Daily Usenet report for paganini.linuxd.org

Jun 6 00:00:05 -- Jun 7 00:00:04

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Jun 6 00:00:05 paganini overchan[3255]: overchan is running while innd is creating overview data (you can ignore this message if you are running makehistory -F)
Jun 6 07:16:15 paganini overchan[3255]: input line too long, skipping
Jun 6 09:01:34 paganini overchan[3255]: input line too long, skipping
Jun 6 16:38:15 paganini overchan[3255]: input line too long, skipping
Jun 6 22:43:28 paganini overchan[3255]: input line too long, skipping
Jun 6 22:47:38 paganini overchan[3255]: input line too long, skipping
Jun 7 00:00:04 paganini overchan[22604]: 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 101321 60.9%12.4 MB 49.7%
inn 43561 26.2%9.7 MB 39.0%
innd 15863 9.5%2.2 MB 8.8%
nnrpd 5452 3.3%614.9 KB 2.4%
nocem 118 0.1%20.3 KB 0.1%
overchan 9 0.0%1.5 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 166325 100.0%24.9 MB100.0%

History cache:

ReasonCount%Count
Positive hits 119775 49.0%
Negative hits 82139 33.6%
Do not exist 42566 17.4%
Cache misses 89 0.0%
TOTAL: 4 244569 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.140 0.0%196 0.000 5.816 116.500
article cleanup00:00:00.274 0.0%43141 0.000 0.006 0.022
article logging00:00:00.619 0.0%43532 0.000 0.014 0.377
article parse00:00:01.104 0.0%87239 0.000 0.013 0.027
article write00:00:56.494 0.1%25567 0.138 2.210 12.468
artlog/artcncl00:00:00.002 0.0%188 0.000 0.011 1.000
data move00:00:00.188 0.0%272308 0.000 0.001 0.003
hisgrep/artcncl00:00:00.029 0.0%193 0.000 0.150 1.333
hishave/artcncl00:00:00.002 0.0%196 0.000 0.010 0.167
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:10.882 0.0%247747 0.018 0.044 0.305
history sync00:00:00.102 0.0%388 0.000 0.263 16.333
history write00:01:05.421 0.1%43141 0.048 1.516 7.009
hiswrite/artcncl00:00:00.000 0.0%3 0.000 0.000 0.000
idle23:56:01.745 99.6%268136 207.413 321.336 973.961
nntp read00:00:02.007 0.0%272758 0.002 0.007 0.013
overview write00:00:28.865 0.0%25567 0.028 1.129 9.384
perl filter00:01:34.025 0.1%43141 1.000 2.179 5.338
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.657 0.0%76932 0.000 0.022 0.041
TOTAL: 24:01:08.31524:00:24.556 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.103 0.0%40611 0.000 0.003 0.015
article prepare00:00:00.001 0.0%22004 0.000 0.000 0.002
article read00:00:01.742 0.0%4699 0.000 0.371 19.125
backlog stats00:00:00.224 0.0%349158 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.402 0.0%186401 0.000 0.008 0.014
data write00:00:02.408 0.0%161033 0.007 0.015 0.027
idle23:42:45.377 99.3%349158 156.259 244.489 414.845
status file00:09:51.853 0.7%42366 7.440 13.970 30.797
TOTAL: 23:53:09.90923:52:43.110100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle01:34:05.445 99.4%18989 27.667 297.301601297.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.308 0.0%38191 0.000 0.008 0.125
readart00:00:05.853 0.1%11691 0.139 0.501 2.219
TOTAL: 01:34:41.19101:34:11.606 99.5%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1linuxd-out.news.weretis.net 6 61472 21991 35816 3665 35%48:35:03
2newsfeed.neva.ru 143 92357 2716 76048 13593 2%71:22:07
3news.fcku.it 1 8654 761 7726 167 8%21:36:16
4localhost 25 0 0 0 0 0%00:00:00
5nntp.pasdenom.info 2 0 0 0 0 0%00:20:04
TOTAL: 5 177 162483 25468 119590 17425 15%141:53:30
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net69.6 MB0.0 KB30.4 MB99.9 MB 69%4.0 KB
2newsfeed.neva.ru7.5 MB0.0 KB99.5 MB107.0 MB 7%6.7 KB
3news.fcku.it2.4 MB0.0 KB1.2 MB3.6 MB 66%3.9 KB
4localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
5nntp.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 579.4 MB0.0 KB131.0 MB210.5 MB 37%5.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 6 00:00:05 - 00:59:59 1160 4.5% 0.324.0 MB 5.0% 1.14
Jun 6 01:00:00 - 01:59:59 844 3.3% 0.233.2 MB 4.0% 0.90
Jun 6 02:00:00 - 02:59:59 540 2.1% 0.151.7 MB 2.1% 0.48
Jun 6 03:00:00 - 03:59:59 511 2.0% 0.141.8 MB 2.3% 0.52
Jun 6 04:00:00 - 04:59:59 593 2.3% 0.162.6 MB 3.2% 0.73
Jun 6 05:00:00 - 05:59:59 588 2.3% 0.162.4 MB 3.1% 0.69
Jun 6 06:00:00 - 06:59:59 618 2.4% 0.171.9 MB 2.4% 0.54
Jun 6 07:00:00 - 07:59:59 621 2.4% 0.172.1 MB 2.6% 0.59
Jun 6 08:00:00 - 08:59:59 832 3.3% 0.232.3 MB 2.9% 0.66
Jun 6 09:00:00 - 09:59:59 1076 4.2% 0.303.3 MB 4.1% 0.93
Jun 6 10:00:00 - 10:59:59 1081 4.2% 0.303.3 MB 4.1% 0.94
Jun 6 11:00:00 - 11:59:59 1064 4.2% 0.303.0 MB 3.8% 0.85
Jun 6 12:00:00 - 12:59:59 1099 4.3% 0.313.0 MB 3.8% 0.86
Jun 6 13:00:00 - 13:59:59 1127 4.4% 0.312.9 MB 3.6% 0.82
Jun 6 14:00:00 - 14:59:59 1224 4.8% 0.343.4 MB 4.3% 0.97
Jun 6 15:00:00 - 15:59:59 1313 5.1% 0.363.6 MB 4.5% 1.02
Jun 6 16:00:00 - 16:59:59 1571 6.2% 0.444.7 MB 5.9% 1.35
Jun 6 17:00:00 - 17:59:59 1412 5.5% 0.394.1 MB 5.2% 1.18
Jun 6 18:00:00 - 18:59:59 1424 5.6% 0.404.2 MB 5.3% 1.20
Jun 6 19:00:00 - 19:59:59 1381 5.4% 0.384.2 MB 5.3% 1.21
Jun 6 20:00:00 - 20:59:59 1382 5.4% 0.384.2 MB 5.3% 1.20
Jun 6 21:00:00 - 21:59:59 1475 5.8% 0.414.4 MB 5.6% 1.26
Jun 6 22:00:00 - 22:59:59 1208 4.7% 0.344.6 MB 5.8% 1.31
Jun 6 23:00:00 - 23:59:59 1344 5.3% 0.374.5 MB 5.6% 1.28
Jun 7 00:00:00 - 00:00:04 17 0.1% 4.2588.2 KB 0.1% 22.04
TOTAL: 23:59:59 25505 100.0% 0.3079.6 MB 100.0% 0.94
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 13669 1656 0 0 0 0 0 012013
2linuxd-out.news.weretis.net 4005 1870 0 0 0 0 0 0 2135
3news.fcku.it 196 0 0 0 0 0 0 0 196
TOTAL: 3 17870 3526 0 0 0 0 0 014344

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 1218
mailing.freebsd.pkg-fallout 246
ut.test 102
lada.talk 93
mailing.freebsd.svn-src-all 77
fa.freebsd.cvs-all 76
mailing.freebsd.svn-src-head 72
mailing.freebsd.ports-bugs 63
mailing.freebsd.svn-ports-all 58
mailing.freebsd.svn-ports-head 54
it-alt.sport.calcio.juventus 52
alt.windows7.general 51
mailing.gcc.gcc-cvs 45
mailing.openbsd.misc 45
mailing.openssl.cvs 43
mailing.openbsd.source-changes 43
fa.openbsd.source-changes 43
cakewalk.audio 39
mpc.lists.nanog 39
lada.auto 37
TOTAL: 238 3526

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 11300
EMP (phn path) 1905
EMP (phl) 462
PFM bot misc 99
EMP (phn nph) 87
Too many newsgroups 35
Binary Payload 28
User-issued cancel 10
Binary: non-binary distribution 7
Binary: misplaced par2 5
Too many newsgroups (meow) 4
Binary: misplaced srr 2
Binary: misplaced sfv 2
User-issued spam cancel 2
Binary: misplaced nfo 2
Binary Image: misplaced jpg 1
Binary: misplaced r13 1
TOTAL: 17 13952

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 373
newsfeed.neva.ru 17
news.fcku.it 2
TOTAL: 3 392
TOTAL: 1 392

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nntp.pasdenom.info 44860 2015 22639 776 0 772 4%23:57:35
2news.fcku.it 32839 765 22298 1547 0 145 2%23:59:34
3weretis.net 3985 119 3489 0 0 8 2%23:59:42
4neva.ru 34819 55 22483 247 0 185 0%23:59:42
5news.netfront.net 2762 0 0 0 0 28175 0%23:59:41
6news.killfile.org 2844 0 0 0 0 28348 0%23:59:42
7vc89.vc.panix.com 2839 0 0 0 0 28343 0%23:59:42
8news.albasani.net 2857 0 0 0 0 27439 0%23:59:42
9etla.org 0 0 0 0 0 25505 0%23:59:42
10news.stack.nl 2857 0 0 0 0 28297 0%23:59:42
11eternal-september 0 0 0 0 0 21875 0%23:59:42
12news.mixmin.net 0 0 0 0 0 0 0%00:10:31
TOTAL: 12 130662 2954 70909 2570 0 189092 2%264:04:57
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nntp.pasdenom.info7.1 MB1.6 MB8.7 MB0.1 KB/s3.2 KB23:57:35
2news.fcku.it2.4 MB5.6 MB8.0 MB0.1 KB/s3.5 KB23:59:34
3neva.ru713.5 KB930.8 KB1.6 MB0.0 KB/s5.4 KB23:59:42
4weretis.net624.8 KB0.0 KB624.8 KB0.0 KB/s5.3 KB23:59:42
5news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:41
6news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:42
7vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:42
8news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:42
9etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:42
10news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:42
11eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:42
12news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:31
TOTAL: 1210.8 MB8.1 MB18.9 MB0.0 KB/s3.5 KB264:04:57
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mtrlpq2314w-lp130-02-1176034363.dsl.bell.ca 60 818.7 KB 6 0 000:33:51
TOTAL: 1 60 818.7 KB 6 0 001:34:40

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.dsl.bell.ca 60 818.7 KB 6 0 000:33:51
2*.nielsenmedia.com 3 00.0 KB 0 0 000:30:14
3*.188.099.pools.vodafone-ip.de 98 00.0 KB 0 0 000:00:20
4unresolved 2 00.0 KB 0 0 000:30:14
TOTAL: 4 163 818.7 KB 6 0 001:34:40

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
mtrlpq2314w-lp130-02-1176034363.dsl.bell.ca 5.184 1.340 0.00000:33:51
challenger.nielsenmedia.com 0.144 0.008 0.00000:30:14
71.98.160.36 0.152 0.016 0.00000:30:14
dslb-188-099-107-249.188.099.pools.vodafone-ip.de 5.152 0.380 0.00000:00:20
TOTAL: 4 10.632 1.744 0.00001:34:40

Curious NNRP server explorers [Top 20]:

SystemConn
dslb-188-099-107-249.188.099.pools.vodafone-ip.de 98
challenger.nielsenmedia.com 3
71.98.160.36 2
TOTAL: 3 103

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1alt 4 50.0%
2uk 2 25.0%
3rec 2 25.0%
TOTAL: 3 8100.0%

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

NewsgroupCount
1uk.radio.amateur 2
2rec.boats 2
3alt.happy.valley 2
4alt.stupidity 1
5alt.atheism 1
TOTAL: 5 8