Daily Usenet report for paganini.linuxd.org

Jun 15 00:00:02 -- Jun 16 00:00:02

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Jun 15 08:34:33 paganini overchan[10283]: input line too long, skipping
Jun 15 13:52:28 paganini overchan[10283]: input line too long, skipping
Jun 15 17:00:04 paganini pgpverify[21105]: pgpverify: verification failed
Jun 15 17:24:21 paganini overchan[10283]: input line too long, skipping
Jun 15 23:02:09 paganini nnrpd[23458]: unable to get certificate from '/etc/news/cert.pem'
Jun 15 23:02:09 paganini nnrpd[23458]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
Jun 16 00:00:02 paganini overchan[30372]: overchan is running while innd is creating overview data (you can ignore this message if you are running makehistory -F)

Log entries by program:

Program nameLines%LinesSize%Size
innfeed 101269 58.6%12.4 MB 47.9%
inn 44435 25.7%10.0 MB 38.6%
innd 15876 9.2%2.3 MB 8.8%
nnrpd 11041 6.4%1.2 MB 4.6%
nocem 74 0.0%11.4 KB 0.0%
overchan 5 0.0%0.6 KB 0.0%
controlchan 4 0.0%0.5 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 8 172705 100.0%25.9 MB100.0%

History cache:

ReasonCount%Count
Positive hits 125169 49.8%
Negative hits 82888 32.9%
Do not exist 43445 17.3%
Cache misses 68 0.0%
TOTAL: 4 251570 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.676 0.0%144 0.000 11.639 517.000
article cleanup00:00:00.299 0.0%43782 0.000 0.007 0.020
article logging00:00:00.431 0.0%44242 0.000 0.010 0.082
article parse00:00:01.076 0.0%86113 0.002 0.012 0.039
article write00:01:05.645 0.1%26475 0.194 2.480 19.794
artlog/artcncl00:00:00.003 0.0%140 0.000 0.021 1.000
data move00:00:00.155 0.0%282211 0.000 0.001 0.003
hisgrep/artcncl00:00:00.002 0.0%141 0.000 0.014 0.200
hishave/artcncl00:00:00.002 0.0%144 0.000 0.014 0.333
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:11.365 0.0%253395 0.016 0.045 0.247
history sync00:00:00.158 0.0%398 0.000 0.397 54.500
history write00:01:06.595 0.1%43779 0.148 1.521 4.106
hiswrite/artcncl00:00:00.000 0.0%3 0.000 0.000 0.000
idle23:55:42.408 99.6%276328 193.679 311.740 971.942
nntp read00:00:02.051 0.0%281897 0.003 0.007 0.013
overview write00:00:29.689 0.0%26475 0.025 1.121 7.254
perl filter00:01:35.971 0.1%43779 1.068 2.192 4.407
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.718 0.0%79656 0.003 0.022 0.053
TOTAL: 24:01:07.77024:00:19.244 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.114 0.0%41561 0.000 0.003 0.018
article prepare00:00:00.002 0.0%21640 0.000 0.000 0.007
article read00:00:02.718 0.0%4521 0.000 0.601 22.824
backlog stats00:00:00.232 0.0%354784 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.552 0.0%190617 0.001 0.008 0.017
data write00:00:02.471 0.0%164341 0.008 0.015 0.025
idle23:43:00.204 99.3%354784 159.407 240.654 446.303
status file00:09:41.709 0.7%42345 8.218 13.737 30.538
TOTAL: 23:53:12.41623:52:49.002100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle08:01:17.050 98.2%26498 0.000 1089.782450395.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.475 0.0%53423 0.000 0.009 0.667
readart00:00:08.917 0.0%12837 0.000 0.695 33.000
TOTAL: 08:10:16.10208:01:26.442 98.2%----

Control commands to INND:

CommandNumber
L 1
flush 6
flushlogs 2
go 2
logmode 2
mode 149
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 172

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1linuxd-out.news.weretis.net 4 62205 22099 36544 3562 35%47:55:31
2newsfeed.neva.ru 144 95063 3105 78789 13169 3%71:16:52
3news.fcku.it 1 11836 1278 10144 414 10%24:59:02
4nntp.pasdenom.info 5 0 0 0 0 0%00:30:56
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 179 169104 26482 125477 17145 15%144:42:21
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net77.9 MB37.6 KB26.1 MB104.0 MB 74%4.1 KB
2newsfeed.neva.ru9.2 MB59.9 KB85.5 MB94.8 MB 9%6.0 KB
3news.fcku.it3.9 MB0.0 KB2.6 MB6.5 MB 59%4.0 KB
4nntp.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB 0%0
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 591.0 MB97.5 KB114.2 MB205.2 MB 44%4.8 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 15 00:00:02 - 00:59:59 1003 3.8% 0.284.3 MB 4.7% 1.23
Jun 15 01:00:00 - 01:59:59 851 3.2% 0.246.0 MB 6.6% 1.72
Jun 15 02:00:00 - 02:59:59 846 3.2% 0.233.0 MB 3.3% 0.85
Jun 15 03:00:00 - 03:59:59 593 2.2% 0.161.9 MB 2.0% 0.53
Jun 15 04:00:00 - 04:59:59 546 2.1% 0.152.2 MB 2.4% 0.62
Jun 15 05:00:00 - 05:59:59 528 2.0% 0.151.8 MB 2.0% 0.51
Jun 15 06:00:00 - 06:59:59 626 2.4% 0.171.9 MB 2.1% 0.55
Jun 15 07:00:00 - 07:59:59 641 2.4% 0.182.2 MB 2.4% 0.62
Jun 15 08:00:00 - 08:59:59 760 2.9% 0.212.6 MB 2.8% 0.74
Jun 15 09:00:00 - 09:59:59 1130 4.3% 0.314.1 MB 4.5% 1.17
Jun 15 10:00:00 - 10:59:59 1346 5.1% 0.374.2 MB 4.6% 1.20
Jun 15 11:00:00 - 11:59:59 1207 4.6% 0.343.3 MB 3.7% 0.95
Jun 15 12:00:00 - 12:59:59 1216 4.6% 0.343.2 MB 3.6% 0.92
Jun 15 13:00:00 - 13:59:59 1208 4.6% 0.343.2 MB 3.5% 0.90
Jun 15 14:00:00 - 14:59:59 1258 4.7% 0.353.4 MB 3.7% 0.97
Jun 15 15:00:00 - 15:59:59 1270 4.8% 0.353.5 MB 3.8% 0.99
Jun 15 16:00:00 - 16:59:59 1427 5.4% 0.404.4 MB 4.8% 1.25
Jun 15 17:00:00 - 17:59:59 1602 6.0% 0.456.8 MB 7.5% 1.94
Jun 15 18:00:00 - 18:59:59 1494 5.6% 0.415.0 MB 5.5% 1.42
Jun 15 19:00:00 - 19:59:59 1519 5.7% 0.424.8 MB 5.3% 1.38
Jun 15 20:00:00 - 20:59:59 1502 5.7% 0.424.4 MB 4.8% 1.25
Jun 15 21:00:00 - 21:59:59 1188 4.5% 0.334.6 MB 5.0% 1.30
Jun 15 22:00:00 - 22:59:59 1456 5.5% 0.406.5 MB 7.1% 1.84
Jun 15 23:00:00 - 23:59:59 1265 4.8% 0.353.8 MB 4.2% 1.08
Jun 16 00:00:00 - 00:00:02 28 0.1% 14.00119.1 KB 0.1% 59.57
TOTAL: 24:00:00 26510 100.0% 0.3191.2 MB 100.0% 1.08
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 13360 1732 0 2 0 0 0 011626
2linuxd-out.news.weretis.net 4001 1621 0 1 0 0 0 0 2379
3news.fcku.it 406 1 0 0 0 0 0 0 405
TOTAL: 3 17767 3354 0 3 0 0 0 014410

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 1098
lada.talk 202
mailing.freebsd.pkg-fallout 148
ut.test 95
lada.auto 79
mailing.freebsd.ports-bugs 73
it-alt.sport.calcio.juventus 58
gmane.linux.alsa.devel 51
lada.photo 51
mailing.database.pgsql-general 46
mailing.openbsd.ports-changes 38
spline.eisfair 38
fa.freebsd.cvs-all 37
mailing.freebsd.svn-src-all 37
mailing.gcc.gcc-cvs 37
mpc.lists.nanog 35
mailing.openssl.cvs 33
mailing.freebsd.svn-ports-all 32
mailing.openssl.dev 30
alt.politics.scorched-earth 28
TOTAL: 228 3354

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 10804
EMP (phn path) 1980
EMP (phl) 670
PFM bot misc 217
EMP (phn nph) 134
Too many newsgroups 58
Binary Payload 33
User-issued cancel 27
HTML post 9
Binary: non-binary distribution 6
Binary Image: misplaced jpg 3
Binary: misplaced txt 1
Binary: misplaced nzb 1
Binary: misplaced zip 1
TOTAL: 14 13944

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 436
newsfeed.neva.ru 23
news.fcku.it 7
TOTAL: 3 466
TOTAL: 1 466

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nntp.pasdenom.info 47011 1816 23743 785 0 861 3%23:57:42
2news.fcku.it 32295 781 23283 1182 0 135 2%23:59:49
3weretis.net 4880 221 4132 1 0 6 4%23:58:57
4neva.ru 35739 209 22681 450 0 203 0%23:59:47
5vc89.vc.panix.com 2841 0 0 0 0 29350 0%23:59:49
6news.netfront.net 2760 0 0 0 0 29211 0%23:59:49
7news.albasani.net 2836 0 0 0 0 28636 0%23:59:49
8news.killfile.org 2850 0 0 0 0 29359 0%23:59:49
9news.stack.nl 2860 0 0 0 0 29256 0%23:59:49
10news.mixmin.net 0 0 0 0 0 0 0%00:10:00
11eternal-september 0 0 0 0 0 22161 0%23:59:49
12etla.org 0 0 0 0 0 26510 0%23:59:49
TOTAL: 12 134072 3027 73839 2418 0 195688 2%264:04:58
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nntp.pasdenom.info7.8 MB1.9 MB9.7 MB0.1 KB/s3.8 KB23:57:42
2news.fcku.it2.6 MB5.9 MB8.5 MB0.1 KB/s4.4 KB23:59:49
3neva.ru1.2 MB1.9 MB3.1 MB0.0 KB/s4.8 KB23:59:47
4weretis.net646.9 KB1.8 KB648.7 KB0.0 KB/s2.9 KB23:58:57
5vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:49
6news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:49
7news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:49
8news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:49
9news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:49
10news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
11eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:49
12etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:49
TOTAL: 1212.2 MB9.7 MB21.9 MB0.0 KB/s4.1 KB264:04:58
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1challenger.nielsenmedia.com 35 3273.1 KB 5 0 005:24:21
2mtrlpq2314w-lp130-02-1176034363.dsl.bell.ca 120 2049.9 KB 16 0 000:46:25
371.98.160.36 9 814.2 KB 4 0 001:50:56
TOTAL: 3 164 60137.3 KB 25 0 008:10:14

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.nielsenmedia.com 35 3273.1 KB 5 0 005:24:21
2*.dsl.bell.ca 120 2049.9 KB 16 0 000:46:25
3unresolved 40 814.2 KB 4 0 001:58:58
4*.188.099.pools.vodafone-ip.de 130 00.0 KB 0 0 000:00:29
TOTAL: 4 325 60137.3 KB 25 0 008:10:14

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
challenger.nielsenmedia.com 2.428 0.148 0.00005:24:21
71.98.160.36 0.756 0.028 0.00001:50:56
mtrlpq2314w-lp130-02-1176034363.dsl.bell.ca 11.460 1.928 0.00000:46:25
185.103.109.81 1.636 0.148 0.00000:08:02
dslb-188-099-106-132.188.099.pools.vodafone-ip.de 6.592 0.388 0.00000:00:27
dslb-188-099-107-249.188.099.pools.vodafone-ip.de 0.416 0.040 0.00000:00:01
TOTAL: 6 23.288 2.680 0.00008:10:14

Curious NNRP server explorers [Top 20]:

SystemConn
dslb-188-099-106-132.188.099.pools.vodafone-ip.de 122
185.103.109.81 31
dslb-188-099-107-249.188.099.pools.vodafone-ip.de 8
TOTAL: 3 161

NNRP unrecognized commands (by host):

SystemConn
185.103.109.81 17
TOTAL: 1 17

NNRP unrecognized commands (by command):

CommandCount
#026#003 2
To: <sip:nm2@nm2> 1
Call-ID: 50000 1
Accept: application/sdp 1
Max-Forwards: 70 1
OPTIONS / HTTP/1.0 1
GET / HTTP/1.0 1
OPTIONS / RTSP/1.0 1
OPTIONS sip:nm SIP/2.0 1
#001default 1
Contact: <sip:nm@nm> 1
From: <sip:nm@nm>;tag=root 1
GET /nice%20ports%2C/Tri%6Eity.txt%2ebak... 1
CSeq: 42 OPTIONS 1
Content-Length: 0 1
Via: SIP/2.0/TCP nm;branch=foo 1
TOTAL: 16 17

NNRP client timeouts [Top 20]:

SystemConnPeer
challenger.nielsenmedia.com 10 1
71.98.160.36 2 0
TOTAL: 2 12 1

Newsgroup request counts (by category):

CategoryCountPct
1comp 44 73.3%
2alt 12 20.0%
3rec 4 6.7%
TOTAL: 3 60100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

NewsgroupCount
1comp.lang.awk 40
2alt.happy.valley 5
3rec.arts.tv 2
4alt.sixtyplus 2
5alt.atheism 2
6comp.lang.python 2
7rec.arts.tv.uk.coronation-st 1
8comp.sys.raspberry-pi 1
9alt.folklore.computers 1
10alt.sports.football.pro.sd-chargers 1
11comp.sys.mac.system 1
12alt.os.linux.ubuntu 1
13rec.pets.cats.community 1
TOTAL: 13 60