Daily Usenet report for paganini.linuxd.org

Mar 10 00:00:03 -- Mar 11 00:00:02

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Mar 10 08:19:57 paganini overchan[5924]: input line too long, skipping
Mar 10 13:30:04 paganini controlchan[5922]: Multiple control headers in article <tjrQf-3VY-47@gated-at.bofh.it>
Mar 10 18:49:40 paganini overchan[5924]: input line too long, skipping
Mar 11 00:00:02 paganini overchan[19224]: 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 115208 66.0%14.3 MB 55.8%
inn 41007 23.5%8.9 MB 34.7%
innd 17797 10.2%2.4 MB 9.2%
nnrpd 518 0.3%72.9 KB 0.3%
nocem 72 0.0%11.4 KB 0.0%
overchan 4 0.0%0.7 KB 0.0%
controlchan 2 0.0%0.3 KB 0.0%
TOTAL: 7 174608 100.0%25.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 128516 52.0%
Negative hits 78488 31.7%
Do not exist 40161 16.2%
Cache misses 97 0.0%
TOTAL: 4 247262 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.857 0.0%226 0.000 8.217 73.500
article cleanup00:00:00.273 0.0%40281 0.000 0.007 0.024
article logging00:00:00.564 0.0%40864 0.000 0.014 0.489
article parse00:00:01.038 0.0%89781 0.000 0.012 0.028
article write00:00:47.297 0.1%23413 0.097 2.020 9.533
artlog/artcncl00:00:00.002 0.0%204 0.000 0.010 1.000
data move00:00:00.161 0.0%264323 0.000 0.001 0.005
hisgrep/artcncl00:00:00.074 0.0%220 0.000 0.336 7.100
hishave/artcncl00:00:00.002 0.0%226 0.000 0.009 0.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:21.027 0.0%248126 0.016 0.085 3.630
history sync00:00:00.060 0.0%405 0.000 0.148 13.333
history write00:00:41.140 0.0%40274 0.058 1.022 3.409
hiswrite/artcncl00:00:00.000 0.0%6 0.000 0.000 0.000
idle23:56:51.208 99.7%260989 210.904 330.325 1164.765
nntp read00:00:01.789 0.0%269762 0.002 0.007 0.014
overview write00:00:20.924 0.0%23413 0.015 0.894 6.257
perl filter00:01:23.620 0.1%40273 0.951 2.076 3.306
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.086 0.0%70451 0.003 0.015 0.049
TOTAL: 24:01:05.69124:00:32.122100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.085 0.0%40399 0.000 0.002 0.010
article prepare00:00:00.003 0.0%27688 0.000 0.000 0.005
article read00:00:03.384 0.0%6529 0.000 0.518 16.218
backlog stats00:00:00.205 0.0%321384 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.295 0.0%173706 0.003 0.007 0.014
data write00:00:02.750 0.0%150688 0.006 0.018 0.030
idle23:40:57.006 99.2%321384 203.066 265.281 429.325
status file00:11:44.428 0.8%48227 9.101 14.607 29.980
TOTAL: 23:53:04.83923:52:49.156100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle05:48:07.639 99.9%441 27.000 47364.261600179.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.014 0.0%1164 0.000 0.012 0.500
readart00:00:00.019 0.0%14 0.000 1.357 3.600
TOTAL: 05:48:21.29405:48:07.672 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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1linuxd-out.news.weretis.net 5 52963 19979 29898 3086 37%47:59:52
2newsfeed.neva.ru 146 85170 3011 68597 13562 3%71:48:17
3news.fcku.it 2 10777 241 10349 187 2%35:08:51
4nntp.pasdenom.info 2 22892 59 22826 7 0%23:41:55
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 180 171802 23290 131670 16842 13%178:38:55
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net58.3 MB1.9 KB22.7 MB80.9 MB 71%3.6 KB
2newsfeed.neva.ru7.8 MB5.7 KB124.5 MB132.3 MB 5%8.2 KB
3news.fcku.it1.2 MB1.6 KB648.3 KB1.9 MB 65%4.4 KB
4nntp.pasdenom.info288.3 KB3.5 KB11.4 KB303.2 KB 95%4.6 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 567.6 MB12.7 KB147.8 MB215.4 MB 31%5.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Mar 10 00:00:03 - 00:59:59 835 3.6% 0.233.2 MB 4.7% 0.90
Mar 10 01:00:00 - 01:59:59 658 2.8% 0.181.8 MB 2.6% 0.51
Mar 10 02:00:00 - 02:59:59 572 2.4% 0.162.1 MB 3.1% 0.59
Mar 10 03:00:00 - 03:59:59 462 2.0% 0.131.4 MB 2.0% 0.39
Mar 10 04:00:00 - 04:59:59 442 1.9% 0.121.9 MB 2.8% 0.53
Mar 10 05:00:00 - 05:59:59 518 2.2% 0.141.5 MB 2.3% 0.44
Mar 10 06:00:00 - 06:59:59 579 2.5% 0.162.2 MB 3.2% 0.62
Mar 10 07:00:00 - 07:59:59 634 2.7% 0.182.2 MB 3.3% 0.64
Mar 10 08:00:00 - 08:59:59 646 2.8% 0.181.6 MB 2.4% 0.47
Mar 10 09:00:00 - 09:59:59 931 4.0% 0.262.7 MB 4.0% 0.76
Mar 10 10:00:00 - 10:59:59 1253 5.4% 0.354.1 MB 6.1% 1.18
Mar 10 11:00:00 - 11:59:59 1157 4.9% 0.323.2 MB 4.7% 0.90
Mar 10 12:00:00 - 12:59:59 1177 5.0% 0.332.8 MB 4.2% 0.80
Mar 10 13:00:00 - 13:59:59 1274 5.5% 0.353.3 MB 4.9% 0.93
Mar 10 14:00:00 - 14:59:59 1376 5.9% 0.383.6 MB 5.3% 1.02
Mar 10 15:00:00 - 15:59:59 1328 5.7% 0.373.5 MB 5.1% 0.99
Mar 10 16:00:00 - 16:59:59 1281 5.5% 0.363.7 MB 5.5% 1.05
Mar 10 17:00:00 - 17:59:59 1315 5.6% 0.373.6 MB 5.3% 1.01
Mar 10 18:00:00 - 18:59:59 1154 4.9% 0.323.1 MB 4.6% 0.88
Mar 10 19:00:00 - 19:59:59 1323 5.7% 0.374.0 MB 6.0% 1.15
Mar 10 20:00:00 - 20:59:59 1098 4.7% 0.302.9 MB 4.3% 0.83
Mar 10 21:00:00 - 21:59:59 1165 5.0% 0.323.2 MB 4.7% 0.91
Mar 10 22:00:00 - 22:59:59 1131 4.8% 0.313.0 MB 4.5% 0.86
Mar 10 23:00:00 - 23:59:59 1061 4.5% 0.292.9 MB 4.2% 0.81
Mar 11 00:00:00 - 00:00:02 6 0.0% 3.0030.8 KB 0.0% 15.41
TOTAL: 23:59:59 23376 100.0% 0.2767.4 MB 100.0% 0.80
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 13602 416 0 3 0 0 0 013183
2linuxd-out.news.weretis.net 3649 680 0 1 0 0 0 0 2968
3news.fcku.it 185 5 0 1 0 0 0 0 179
4nntp.pasdenom.info 9 1 0 2 0 1 0 0 5
TOTAL: 4 17445 1102 0 7 0 1 0 016335

Unwanted newsgroups [Top 20]:

NewsgroupCount
lada.talk 139
ut.test 114
it-alt.sport.calcio.juventus 101
alt.courts.pretrial-services 71
lada.auto 64
mozilla.support.thunderbird 63
gmane.comp.gis.openstreetmap.tagging 35
mozilla.dev.platform 30
spline.eisfair.dev 29
mozilla.dev.planning 23
mpc.lists.freebsd.x11 21
mozilla.support.firefox 21
lada.commerce 17
mozilla.dev.security.policy 16
gmane.comp.bacula.user 16
cakewalk.audio 16
alt.survival 13
mozilla.support.seamonkey 13
fido7.ru.android 12
mozilla.general 12
TOTAL: 141 1102

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 13512
EMP (phn path) 1544
EMP (phr path) 251
Too many newsgroups (meow) 133
EMP (phn nph) 126
Too many newsgroups 66
EMP (phl) 40
Binary Payload 27
User-issued cancel 25
Binary: non-binary distribution 8
Binary: misplaced pdf 5
Binary Image: misplaced jpg 5
Too many newsgroups (low_xpost) 4
HTML post 4
Angle-bracket bot 1
Binary: misplaced nzb 1
TOTAL: 16 15752

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 554
newsfeed.neva.ru 29
TOTAL: 2 583
TOTAL: 1 583

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.fcku.it 31175 2573 16927 3809 0 463 8%24:09:45
2nntp.pasdenom.info 8788 312 3500 52 0 21705 3%23:58:42
3weretis.net 3605 96 3216 0 0 23 2%24:05:00
4eternal-september 26616 45 20234 0 0 107 0%24:09:43
5neva.ru 30310 28 20291 119 0 304 0%24:05:29
6news.stack.nl 2820 0 0 0 0 26202 0%24:00:22
7etla.org 0 0 0 0 0 23543 0%24:09:45
8news.albasani.net 2848 0 0 0 0 25149 0%24:00:22
9news.killfile.org 2849 0 0 0 0 26236 0%23:59:56
10news.netfront.net 2747 0 0 0 0 26050 0%23:59:53
11vc89.vc.panix.com 2821 0 0 0 0 26227 0%24:00:22
12news.mixmin.net 0 0 0 0 0 0 0%00:10:00
TOTAL: 12 114579 3054 64168 3980 0 176009 2%264:49:19
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.fcku.it7.1 MB10.5 MB17.7 MB0.2 KB/s2.8 KB24:09:45
2nntp.pasdenom.info1.1 MB165.8 KB1.2 MB0.0 KB/s3.4 KB23:58:42
3eternal-september165.9 KB0.0 KB165.9 KB0.0 KB/s3.7 KB24:09:43
4weretis.net114.2 KB0.0 KB114.2 KB0.0 KB/s1.2 KB24:05:00
5neva.ru94.9 KB469.1 KB564.0 KB0.0 KB/s3.8 KB24:05:29
6news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:22
7etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:09:45
8news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:22
9news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
10news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
11vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:22
12news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
TOTAL: 128.6 MB11.1 MB19.7 MB0.0 KB/s2.9 KB264:49:19
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1challenger.nielsenmedia.com 26 1246.1 KB 4 0 004:25:10
247-192-187-101.drr02.clwr.fl.frontiernet.net 9 24.8 KB 2 0 001:22:51
TOTAL: 2 35 1450.8 KB 6 0 005:48:20

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.nielsenmedia.com 26 1246.1 KB 4 0 004:25:10
2*.drr02.clwr.fl.frontiernet.net 9 24.8 KB 2 0 001:22:51
3*.188.104.pools.vodafone-ip.de 117 00.0 KB 0 0 000:00:18
TOTAL: 3 152 1450.8 KB 6 0 005:48:20

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
challenger.nielsenmedia.com 1.564 0.084 0.00004:25:10
47-192-187-101.drr02.clwr.fl.frontiernet.net 0.632 0.024 0.00001:22:51
dslb-188-104-034-195.188.104.pools.vodafone-ip.de 6.132 0.320 0.00000:00:18
TOTAL: 3 8.328 0.428 0.00005:48:20

Curious NNRP server explorers [Top 20]:

SystemConn
dslb-188-104-034-195.188.104.pools.vodafone-ip.de 117
TOTAL: 1 117

NNRP client timeouts [Top 20]:

SystemConnPeer
challenger.nielsenmedia.com 8 0
47-192-187-101.drr02.clwr.fl.frontiernet.net 2 0
TOTAL: 2 10 0

Newsgroup request counts (by category):

CategoryCountPct
1comp 14100.0%
TOTAL: 1 14100.0%

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

NewsgroupCount
1comp.lang.awk 14
TOTAL: 1 14