Daily Usenet report for paganini.linuxd.org

Dec 1 00:00:02 -- Dec 2 00:00:02

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Dec 1 07:00:02 paganini pgpverify[7019]: pgpverify: verification failed
Dec 1 07:04:02 paganini pgpverify[8678]: pgpverify: verification failed
Dec 1 07:11:23 paganini pgpverify[9294]: pgpverify: verification failed
Dec 1 09:43:43 paganini overchan[28560]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 54870 66.6%10.5 MB 77.5%
innd 23208 28.1%2.5 MB 18.8%
nnrpd 2379 2.9%261.2 KB 1.9%
innfeed 1830 2.2%225.9 KB 1.6%
nocem 143 0.2%17.7 KB 0.1%
controlchan 11 0.0%1.7 KB 0.0%
pgpverify 3 0.0%0.5 KB 0.0%
overchan 1 0.0%0.1 KB 0.0%
TOTAL: 8 82445 100.0%13.5 MB100.0%

History cache:

ReasonCount%Count
Positive hits 290584 51.2%
Negative hits 222245 39.2%
Do not exist 54313 9.6%
Cache misses 230 0.0%
TOTAL: 4 567372 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.375 0.0%251 0.000 1.494 21.429
article cleanup00:00:00.374 0.0%54499 0.000 0.007 0.023
article logging00:00:00.551 0.0%54586 0.000 0.010 0.179
article parse00:00:01.543 0.0%188867 0.003 0.008 0.026
article write00:01:00.222 0.1%29684 0.100 2.029 14.306
artlog/artcncl00:00:00.002 0.0%246 0.000 0.008 0.500
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.216 0.0%616910 0.000 0.000 0.002
hisgrep/artcncl00:00:00.003 0.0%246 0.000 0.012 0.500
hishave/artcncl00:00:00.001 0.0%251 0.000 0.004 0.067
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:26.744 0.0%569613 0.014 0.047 0.538
history sync00:00:00.011 0.0%300 0.000 0.037 1.000
history write00:01:28.285 0.1%54495 0.156 1.620 6.320
hiswrite/artcncl00:00:00.000 0.0%5 0.000 0.000 0.000
idle23:52:27.096 99.5%613374 23.201 140.122 449.985
nntp read00:00:03.483 0.0%627954 0.003 0.006 0.010
overview write00:00:36.738 0.0%29684 0.021 1.238 3.709
perl filter00:03:04.260 0.2%54495 1.237 3.381 24.524
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:07.591 0.0%118944 0.013 0.064 0.101
TOTAL: 24:00:18.67723:59:17.495 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.075 0.0%33297 0.000 0.002 0.015
article prepare00:00:00.006 0.0%83600 0.000 0.000 0.004
article read00:00:21.026 0.0%20183 0.000 1.042 23.692
backlog stats00:00:00.175 0.0%477395 0.000 0.000 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.552 0.0%312929 0.004 0.008 0.013
data write00:00:03.654 0.0%289927 0.007 0.013 0.025
idle23:51:41.739100.0%477395 78.228 179.938 468.448
status file00:00:06.371 0.0%172 0.000 37.041 140.000
TOTAL: 23:52:23.48123:52:15.598100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:21:00.670 97.3%10716 113.964 117.644 160.492
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.146 0.0%21506 0.000 0.007 0.026
readart00:00:15.118 1.2%7096 0.068 2.130 21.264
TOTAL: 00:21:36.12000:21:15.934 98.4%----

Control commands to INND:

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
bofh-control@killfile.org001001
control@usenet.org.uk000100
grisbi-control@grisbi.org000100
us-control@lists.killfile.org000100
TOTAL001301

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1linuxd-out.news.weretis.net 3 68485 10157 55043 3285 14%47:50:13
2feeder.eternal-september.org 6 49134 5788 39589 3757 11%24:25:16
3vc89.vc.panix.com 1 9324 4095 4881 348 43%23:59:18
4newsfeed.neva.ru 144 100695 3587 80605 16503 3%71:14:11
5news-out.mixmin.net 4 34508 3429 30228 851 9%23:33:32
6ceres.etla.org 1 28663 2145 26518 0 7%23:50:23
7news.netfront.net 3 27065 25 27021 19 0%14:49:15
8localhost 25 0 0 0 0 0%00:00:00
TOTAL: 8 187 317874 29226 263885 24763 9%229:42:08
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net53.2 MB0.0 KB11.4 MB64.6 MB 82%4.9 KB
2feeder.eternal-september.org42.0 MB0.0 KB70.1 MB112.0 MB 37%12.0 KB
3vc89.vc.panix.com15.5 MB49.6 KB2.8 MB18.4 MB 84%4.2 KB
4newsfeed.neva.ru10.9 MB25.5 KB139.3 MB150.2 MB 7%7.7 KB
5news-out.mixmin.net8.7 MB0.0 KB2.3 MB11.0 MB 79%2.6 KB
6ceres.etla.org6.0 MB0.0 KB0.0 KB6.0 MB100%2.8 KB
7news.netfront.net51.8 KB0.0 KB1.1 MB1.2 MB 4%27.9 KB
8localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 8136.4 MB75.1 KB227.0 MB363.4 MB 37%6.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Dec 1 00:00:02 - 00:59:59 1048 3.5% 0.293.2 MB 2.2% 0.92
Dec 1 01:00:00 - 01:59:59 828 2.8% 0.233.0 MB 2.1% 0.87
Dec 1 02:00:00 - 02:59:59 685 2.3% 0.192.1 MB 1.4% 0.60
Dec 1 03:00:00 - 03:59:59 694 2.3% 0.192.7 MB 1.8% 0.76
Dec 1 04:00:00 - 04:59:59 593 2.0% 0.162.1 MB 1.5% 0.60
Dec 1 05:00:00 - 05:59:59 627 2.1% 0.173.5 MB 2.4% 0.99
Dec 1 06:00:00 - 06:59:59 632 2.1% 0.182.8 MB 1.9% 0.79
Dec 1 07:00:00 - 07:59:59 698 2.4% 0.194.8 MB 3.3% 1.35
Dec 1 08:00:00 - 08:59:59 845 2.8% 0.232.8 MB 1.9% 0.80
Dec 1 09:00:00 - 09:59:59 1003 3.4% 0.283.0 MB 2.1% 0.85
Dec 1 10:00:00 - 10:59:59 1193 4.0% 0.333.2 MB 2.2% 0.92
Dec 1 11:00:00 - 11:59:59 1440 4.9% 0.405.9 MB 4.0% 1.67
Dec 1 12:00:00 - 12:59:59 1310 4.4% 0.363.9 MB 2.7% 1.10
Dec 1 13:00:00 - 13:59:59 1495 5.0% 0.424.7 MB 3.2% 1.34
Dec 1 14:00:00 - 14:59:59 1439 4.8% 0.404.1 MB 2.9% 1.18
Dec 1 15:00:00 - 15:59:59 1588 5.4% 0.444.4 MB 3.1% 1.26
Dec 1 16:00:00 - 16:59:59 1732 5.8% 0.4840.9 MB 28.2% 11.64
Dec 1 17:00:00 - 17:59:59 1930 6.5% 0.546.4 MB 4.4% 1.81
Dec 1 18:00:00 - 18:59:59 1734 5.8% 0.486.5 MB 4.4% 1.83
Dec 1 19:00:00 - 19:59:59 1748 5.9% 0.495.1 MB 3.5% 1.45
Dec 1 20:00:00 - 20:59:59 1643 5.5% 0.464.9 MB 3.4% 1.39
Dec 1 21:00:00 - 21:59:59 1494 5.0% 0.415.2 MB 3.6% 1.48
Dec 1 22:00:00 - 22:59:59 1778 6.0% 0.4915.2 MB 10.4% 4.31
Dec 1 23:00:00 - 23:59:59 1497 5.0% 0.424.9 MB 3.4% 1.39
Dec 2 00:00:00 - 00:00:02 2 0.0% 1.005.0 KB 0.0% 2.52
TOTAL: 24:00:00 29676 100.0% 0.34145.2 MB 100.0% 1.72
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 16499 2704 0 2 0 0 0 013793
2feeder.eternal-september.org 3814 228 0 0 0 0 0 0 3586
3linuxd-out.news.weretis.net 3347 1251 0 0 0 0 0 0 2096
4news-out.mixmin.net 910 12 0 0 0 0 0 0 898
5vc89.vc.panix.com 350 1 0 2 0 0 0 0 347
6news.netfront.net 25 0 0 0 0 0 0 0 25
7ceres.etla.org 4 0 0 0 0 0 0 0 4
TOTAL: 7 24949 4196 0 4 0 0 0 020749

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 835
lada.talk 272
alt.politics 178
it-alt.sport.calcio.juventus 177
ut.test 173
lada.auto 138
pld.cvs.commit 65
mailing.unix.samba 64
mailing.freebsd.svn-src-all 64
fa.freebsd.cvs-all 63
mailing.gcc.gcc-cvs 59
mailing.freebsd.svn-src-head 56
mailing.unix.spamassassin-talk 53
mailing.freebsd.ports-bugs 52
alt.survival 49
yahoo.tekscopes 48
mpc.lists.freebsd.ports.bugs 48
24hoursupport.helpdesk 46
mailing.openbsd.ports-changes 42
alt.windows7.general 39
TOTAL: 415 4196

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 16090
EMP (phn path) 3261
EMP (phl) 972
EMP (phn nph) 140
Too many newsgroups 109
Binary: non-binary distribution 34
User-issued cancel 20
Binary Payload 13
HTML post 8
Too many newsgroups (low_xpost) 3
Too many newsgroups (meow) 2
Binary Image: misplaced jpg 2
Binary: misplaced rar 2
Binary: misplaced cmd 1
Binary: misplaced par2 1
Binary: misplaced nfo 1
Binary: misplaced nzb 1
TOTAL: 17 20660

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1vc89.vc.panix.com 37663 546 14956 14323 5 417 1%24:08:11
2etla.org 34772 460 29269 2 0 529 1%24:02:18
3news.netfront.net 52954 374 29119 49 0 2120 0%23:57:04
4news.killfile.org 47095 101 24458 5107 1 382 0%23:59:10
5news.stack.nl 30325 19 29153 18 0 225 0%24:02:03
6eternal-september 24867 17 23729 7 0 9 0%24:04:21
7weretis.net 19965 5 19241 0 0 0 0%23:55:57
8neva.ru 27360 2 25642 5 0 61 0%23:58:04
TOTAL: 8 275001 1524 195567 19511 6 3743 0%192:07:08
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1etla.org60.5 MB6.6 KB60.5 MB0.7 KB/s134.2 KB24:02:18
2news.netfront.net16.8 MB620.1 KB17.4 MB0.2 KB/s42.1 KB23:57:04
3vc89.vc.panix.com2.0 MB71.4 MB73.4 MB0.9 KB/s5.1 KB24:08:11
4news.killfile.org339.0 KB37.3 MB37.7 MB0.4 KB/s7.4 KB23:59:10
5neva.ru258.8 KB180.0 KB438.7 KB0.0 KB/s62.7 KB23:58:04
6news.stack.nl123.5 KB39.3 KB162.7 KB0.0 KB/s4.4 KB24:02:03
7eternal-september36.0 KB11.9 KB47.9 KB0.0 KB/s2.0 KB24:04:21
8weretis.net24.3 KB0.0 KB24.3 KB0.0 KB/s4.9 KB23:55:57
TOTAL: 880.1 MB109.6 MB189.6 MB0.3 KB/s9.2 KB192:07:08
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mtrlpq2314w-lp140-04-1177941446.dsl.bell.ca 104 37115.8 KB 20 0 000:21:35
TOTAL: 1 104 37115.8 KB 20 0 000:21:35

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.dsl.bell.ca 104 37115.8 KB 20 0 000:21:35
TOTAL: 1 104 37115.8 KB 20 0 000:21:35

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
mtrlpq2314w-lp140-04-1177941446.dsl.bell.ca 8.592 0.928 0.00000:21:35
TOTAL: 1 8.592 0.928 0.00000:21:35

Newsgroup request counts (by category):

CategoryCountPct
1alt 20 54.1%
2uk 9 24.3%
3talk 6 16.2%
4rec 2 5.4%
TOTAL: 4 37100.0%

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

NewsgroupCount
1alt.atheism 19
2talk.origins 6
3uk.railway 5
4uk.rec.motorcycles 4
5rec.crafts.metalworking 1
6alt.gossip.celebrities 1
7rec.sport.football.college 1
TOTAL: 7 37