Daily Usenet report for paganini.linuxd.org

May 5 00:00:02 -- May 6 00:00:02

Unknown entries from news log file:

First 16 / 16 lines (100.0%)

May 5 05:45:15 paganini overchan[30133]: input line too long, skipping
May 5 06:00:01 paganini overchan[30133]: input line too long, skipping
May 5 07:11:45 paganini overchan[30133]: input line too long, skipping
May 5 16:38:07 paganini overchan[30133]: input line too long, skipping
May 5 16:41:26 paganini overchan[30133]: input line too long, skipping
May 5 17:33:33 paganini overchan[30133]: input line too long, skipping
May 5 17:34:32 paganini overchan[30133]: input line too long, skipping
May 5 17:39:58 paganini overchan[30133]: input line too long, skipping
May 5 17:43:19 paganini overchan[30133]: input line too long, skipping
May 5 17:44:44 paganini overchan[30133]: input line too long, skipping
May 5 17:50:05 paganini overchan[30133]: input line too long, skipping
May 5 18:03:08 paganini overchan[30133]: input line too long, skipping
May 5 18:04:13 paganini overchan[30133]: input line too long, skipping
May 5 18:05:40 paganini overchan[30133]: input line too long, skipping
May 5 22:45:02 paganini pgpverify[1142]: pgpverify: verification failed
May 6 00:00:02 paganini overchan[11379]: 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 101445 55.4%12.5 MB 47.1%
nnrpd 40568 22.1%4.7 MB 17.6%
inn 30896 16.9%7.6 MB 28.6%
innd 10229 5.6%1.8 MB 6.6%
nocem 50 0.0%9.5 KB 0.0%
overchan 16 0.0%2.3 KB 0.0%
controlchan 4 0.0%0.5 KB 0.0%
pgpverify 1 0.0%0.2 KB 0.0%
TOTAL: 8 183209 100.0%26.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 113185 57.7%
Negative hits 52677 26.9%
Do not exist 30186 15.4%
Cache misses 130 0.1%
TOTAL: 4 196178 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.751 0.0%184 0.000 4.082 68.500
article cleanup00:00:00.221 0.0%30394 0.000 0.007 0.024
article logging00:00:00.298 0.0%30828 0.000 0.010 0.177
article parse00:00:00.598 0.0%42587 0.000 0.014 0.039
article write00:00:53.012 0.1%21278 0.091 2.491 15.738
artlog/artcncl00:00:00.002 0.0%184 0.000 0.011 0.200
data move00:00:00.114 0.0%204320 0.000 0.001 0.003
hisgrep/artcncl00:00:00.003 0.0%183 0.000 0.016 0.111
hishave/artcncl00:00:00.002 0.0%184 0.000 0.011 0.111
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:10.654 0.0%197472 0.010 0.054 0.529
history sync00:00:00.060 0.0%398 0.000 0.151 14.667
history write00:00:36.859 0.0%30391 0.033 1.213 5.172
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle23:57:31.291 99.7%201632 248.899 427.766 1073.462
nntp read00:00:01.448 0.0%206966 0.002 0.007 0.021
overview write00:00:20.403 0.0%21278 0.010 0.959 4.945
perl filter00:00:52.525 0.1%30390 1.078 1.728 4.981
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.329 0.0%64001 0.004 0.021 0.046
TOTAL: 24:01:10.36824:00:29.570100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.095 0.0%38568 0.000 0.002 0.014
article prepare00:00:00.001 0.0%32168 0.000 0.000 0.010
article read00:00:02.582 0.0%6450 0.000 0.400 11.193
backlog stats00:00:00.190 0.0%347813 0.000 0.001 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.396 0.0%189263 0.002 0.007 0.018
data write00:00:02.942 0.0%168131 0.009 0.017 0.037
idle23:42:21.527 99.2%347813 138.202 245.366 476.937
status file00:10:16.674 0.7%42399 8.938 14.545 24.356
TOTAL: 23:53:14.82323:52:45.407100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle05:28:13.983 99.9%41090 37.322 479.289600339.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.824 0.0%83663 0.000 0.010 0.071
readart00:00:00.033 0.0%12 0.000 2.750 33.000
TOTAL: 05:28:33.30605:28:14.840 99.9%----

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
moderator@dana.de000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1linuxd-out.news.weretis.net 5 45235 18721 25160 1354 41%48:37:28
2newsfeed.neva.ru 147 70597 2195 60808 7594 3%71:40:03
3news.fcku.it 2 8083 409 7486 188 5%24:00:54
4nntp.pasdenom.info 2 21684 51 21624 9 0%23:56:51
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 181 145599 21376 115078 9145 14%168:15:16
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net54.7 MB0.0 KB3.9 MB58.5 MB 93%3.0 KB
2newsfeed.neva.ru5.8 MB8.9 KB29.0 MB34.9 MB 16%3.6 KB
3news.fcku.it1.5 MB0.0 KB1.5 MB3.0 MB 48%5.1 KB
4nntp.pasdenom.info92.8 KB34.8 KB122.2 KB249.8 KB 37%4.2 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 562.0 MB43.7 KB34.6 MB96.7 MB 64%3.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 5 00:00:02 - 00:59:59 753 3.6% 0.212.8 MB 4.6% 0.80
May 5 01:00:00 - 01:59:59 547 2.6% 0.151.6 MB 2.6% 0.46
May 5 02:00:00 - 02:59:59 581 2.7% 0.161.6 MB 2.6% 0.46
May 5 03:00:00 - 03:59:59 527 2.5% 0.151.5 MB 2.4% 0.42
May 5 04:00:00 - 04:59:59 433 2.0% 0.121.3 MB 2.2% 0.38
May 5 05:00:00 - 05:59:59 431 2.0% 0.121.5 MB 2.4% 0.41
May 5 06:00:00 - 06:59:59 520 2.5% 0.141.9 MB 3.2% 0.55
May 5 07:00:00 - 07:59:59 506 2.4% 0.141.4 MB 2.3% 0.41
May 5 08:00:00 - 08:59:59 743 3.5% 0.212.7 MB 4.4% 0.78
May 5 09:00:00 - 09:59:59 937 4.4% 0.262.5 MB 4.0% 0.70
May 5 10:00:00 - 10:59:59 1080 5.1% 0.302.9 MB 4.8% 0.83
May 5 11:00:00 - 11:59:59 1071 5.1% 0.303.2 MB 5.2% 0.90
May 5 12:00:00 - 12:59:59 1070 5.0% 0.302.8 MB 4.6% 0.80
May 5 13:00:00 - 13:59:59 990 4.7% 0.282.5 MB 4.1% 0.72
May 5 14:00:00 - 14:59:59 1065 5.0% 0.302.9 MB 4.7% 0.83
May 5 15:00:00 - 15:59:59 1104 5.2% 0.313.0 MB 4.9% 0.85
May 5 16:00:00 - 16:59:59 1173 5.5% 0.333.0 MB 4.9% 0.86
May 5 17:00:00 - 17:59:59 1201 5.7% 0.333.6 MB 5.8% 1.02
May 5 18:00:00 - 18:59:59 1118 5.3% 0.313.2 MB 5.2% 0.92
May 5 19:00:00 - 19:59:59 1186 5.6% 0.333.1 MB 5.1% 0.89
May 5 20:00:00 - 20:59:59 1284 6.1% 0.363.9 MB 6.3% 1.11
May 5 21:00:00 - 21:59:59 1025 4.8% 0.283.0 MB 4.9% 0.86
May 5 22:00:00 - 22:59:59 981 4.6% 0.272.8 MB 4.6% 0.81
May 5 23:00:00 - 23:59:59 872 4.1% 0.242.5 MB 4.1% 0.72
May 6 00:00:00 - 00:00:02 3 0.0% 1.509.5 KB 0.0% 4.74
TOTAL: 24:00:00 21201 100.0% 0.2561.5 MB 100.0% 0.73
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 7591 383 0 3 0 0 0 0 7205
2linuxd-out.news.weretis.net 1756 487 0 0 0 0 0 0 1269
3news.fcku.it 173 1 0 0 0 0 0 0 172
4nntp.pasdenom.info 9 0 0 0 0 1 0 0 8
TOTAL: 4 9529 871 0 3 0 1 0 0 8654

Unwanted newsgroups [Top 20]:

NewsgroupCount
ut.test 118
lada.talk 82
it-alt.sport.calcio.juventus 59
lada.auto 50
it.comp.os.win.windows10 47
spline.eisfair 45
mpc.lists.freebsd.ports.bugs 43
gmane.comp.gis.openstreetmap 28
mozilla.dev.security.policy 28
it.annunci.varie 26
lada.commerce 26
it.annunci.commerciali 25
mpc.lists.nanog 24
it.annunci.immobiliari 22
alt.windows7.general 20
cakewalk.audio 12
lada.photo 12
mozilla.support.seamonkey 10
spline.fli4l 9
oesterreich.tratsch 9
TOTAL: 103 871

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 7212
EMP (phn path) 773
EMP (phl) 73
EMP (phn nph) 57
User-issued cancel 43
Too many newsgroups 21
Binary Payload 19
Binary: non-binary distribution 11
HTML post 5
Binary Image: misplaced jpg 2
Too many newsgroups (low_xpost) 2
TOTAL: 11 8218

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 414
newsfeed.neva.ru 20
news.fcku.it 2
TOTAL: 3 436
TOTAL: 1 436

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nntp.pasdenom.info 36320 3000 17032 1137 0 339 8%23:57:36
2news.fcku.it 28543 1433 17135 2233 0 58 5%23:59:42
3weretis.net 5404 109 5184 0 0 184 2%23:55:53
4neva.ru 27919 16 18854 130 0 70 0%23:59:43
5eternal-september 23230 14 18218 1 0 7 0%23:59:32
6news.mixmin.net 0 0 0 0 0 0 0%00:10:30
7etla.org 0 0 0 0 0 21201 0%23:59:43
8news.killfile.org 2850 0 0 0 0 24050 0%23:59:43
9vc89.vc.panix.com 2841 0 0 0 0 24041 0%23:59:43
10news.netfront.net 2766 0 0 0 0 23936 0%23:59:42
11news.albasani.net 2862 0 0 0 0 23256 0%23:59:43
12news.stack.nl 2856 0 0 0 0 24051 0%23:59:43
TOTAL: 12 135591 4572 76423 3501 0 141193 3%264:01:13
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nntp.pasdenom.info9.8 MB2.2 MB12.0 MB0.1 KB/s3.0 KB23:57:36
2news.fcku.it3.8 MB6.4 MB10.1 MB0.1 KB/s2.8 KB23:59:42
3weretis.net168.4 KB0.0 KB168.4 KB0.0 KB/s1.5 KB23:55:53
4eternal-september53.1 KB2.0 KB55.1 KB0.0 KB/s3.7 KB23:59:32
5neva.ru21.1 KB308.7 KB329.8 KB0.0 KB/s2.3 KB23:59:43
6news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:30
7etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:43
8news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:43
9vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:43
10news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:42
11news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:43
12news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:43
TOTAL: 1213.8 MB8.9 MB22.7 MB0.0 KB/s2.9 KB264:01:13
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1dslb-188-099-023-198.188.099.pools.vodafone-ip.de 73 825.3 KB 2 0 000:34:12
2challenger.nielsenmedia.com 20 49.4 KB 4 0 004:32:13
TOTAL: 2 93 1234.8 KB 6 0 005:28:32

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.188.099.pools.vodafone-ip.de 73 825.3 KB 2 0 000:34:12
2*.nielsenmedia.com 20 49.4 KB 4 0 004:32:13
3*.drr02.clwr.fl.frontiernet.net 5 00.0 KB 0 0 000:22:06
TOTAL: 3 98 1234.8 KB 6 0 005:28:32

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
challenger.nielsenmedia.com 1.252 0.068 0.00004:32:13
dslb-188-099-023-198.188.099.pools.vodafone-ip.de 8.008 1.876 0.00000:34:12
47-192-187-101.drr02.clwr.fl.frontiernet.net 0.372 0.008 0.00000:22:06
TOTAL: 3 9.632 1.952 0.00005:28:32

Curious NNRP server explorers [Top 20]:

SystemConn
47-192-187-101.drr02.clwr.fl.frontiernet.net 5
TOTAL: 1 5

NNRP client timeouts [Top 20]:

SystemConnPeer
challenger.nielsenmedia.com 8 0
TOTAL: 1 8 0

Newsgroup request counts (by category):

CategoryCountPct
1de 8 66.7%
2comp 4 33.3%
TOTAL: 2 12100.0%

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

NewsgroupCount
1de.talk.tagesgeschehen 7
2comp.lang.awk 4
3de.etc.fahrzeug.auto 1
TOTAL: 3 12