Daily Usenet report for paganini.linuxd.org

Jan 31 00:00:02 -- Feb 1 00:00:02

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Jan 31 00:00:04 paganini nnrpd[3191]: feeder.eternal-september.org noperm ihave without permission
Jan 31 00:00:07 paganini nnrpd[3225]: feeder.eternal-september.org noperm ihave without permission
Jan 31 00:00:07 paganini nnrpd[3226]: feeder.eternal-september.org noperm ihave without permission
Jan 31 00:00:14 paganini nnrpd[3355]: feeder.eternal-september.org noperm ihave without permission
Jan 31 00:00:34 paganini nnrpd[3395]: feeder.eternal-september.org noperm ihave without permission
Jan 31 00:00:37 paganini nnrpd[3397]: feeder.eternal-september.org noperm ihave without permission
Jan 31 00:00:37 paganini nnrpd[3396]: feeder.eternal-september.org noperm ihave without permission
Jan 31 00:00:44 paganini nnrpd[3398]: feeder.eternal-september.org noperm ihave without permission
Jan 31 17:09:11 paganini overchan[26318]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 41979 70.0%8.6 MB 80.1%
innd 16000 26.7%1.9 MB 17.7%
innfeed 1806 3.0%223.9 KB 2.0%
nocem 133 0.2%15.8 KB 0.1%
nnrpd 32 0.1%3.7 KB 0.0%
controlchan 4 0.0%0.7 KB 0.0%
overchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 59955 100.0%10.7 MB100.0%

History cache:

ReasonCount%Count
Positive hits 247711 54.0%
Negative hits 169421 36.9%
Do not exist 41484 9.0%
Cache misses 115 0.0%
TOTAL: 4 458731 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.317 0.0%226 0.000 1.403 26.000
article cleanup00:00:00.220 0.0%41705 0.000 0.005 0.026
article logging00:00:00.580 0.0%41808 0.000 0.014 0.621
article parse00:00:00.919 0.0%97543 0.000 0.009 0.026
article write00:01:09.279 0.1%25476 0.131 2.719 21.690
artlog/artcncl00:00:00.000 0.0%210 0.000 0.000 0.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.173 0.0%496205 0.000 0.000 0.002
hisgrep/artcncl00:00:00.140 0.0%221 0.000 0.633 3.621
hishave/artcncl00:00:00.001 0.0%226 0.000 0.004 0.045
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:22.497 0.0%498593 0.013 0.045 0.355
history sync00:00:00.029 0.0%362 0.000 0.080 6.500
history write00:01:19.688 0.1%41700 0.052 1.911 8.788
hiswrite/artcncl00:00:00.000 0.0%5 0.000 0.000 0.000
idle23:54:25.345 99.6%484095 99.955 177.786 445.311
nntp read00:00:02.584 0.0%495478 0.002 0.005 0.010
overview write00:00:38.827 0.0%25476 0.030 1.524 4.960
perl filter00:01:47.772 0.1%41700 1.187 2.584 8.253
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:06.944 0.0%102118 0.030 0.068 0.116
TOTAL: 24:00:50.16723:59:55.315 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.068 0.0%25922 0.000 0.003 0.016
article prepare00:00:00.008 0.0%72068 0.000 0.000 0.004
article read00:00:13.857 0.0%17362 0.000 0.798 15.722
backlog stats00:00:00.171 0.0%423331 0.000 0.000 0.001
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.169 0.0%278501 0.003 0.008 0.018
data write00:00:03.374 0.0%255934 0.006 0.013 0.023
idle24:02:20.647100.0%423331 118.610 204.428 534.728
status file00:00:05.763 0.0%172 0.000 33.506 141.000
TOTAL: 24:02:56.24824:02:46.057100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:01.391 63.8%24 39.667 57.958 114.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%48 0.000 0.021 0.167
TOTAL: 00:00:02.17900:00:01.392 63.9%----

Control commands to INND:

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
usenet@film.rlss.okayama-u.ac.jp001011
TOTAL001011

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1linuxd-out.news.weretis.net 2 59088 9670 45956 3462 16%48:00:53
2feeder.eternal-september.org 6 74282 4053 68028 2201 5%24:14:48
3vc89.vc.panix.com 1 7915 4006 3378 531 50%23:22:59
4newsfeed.neva.ru 144 86793 2800 75966 8027 3%71:29:37
5news-out.mixmin.net 6 34092 2475 29721 1896 7%24:34:20
6etla.org 1 24852 2261 22590 1 9%23:58:19
7news.netfront.net 3 43253 88 43076 89 0%24:06:20
8localhost 25 0 0 0 0 0%00:00:00
TOTAL: 8 188 330275 25353 288715 16207 7%239:47:16
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net43.8 MB0.0 KB16.3 MB60.1 MB 72%4.7 KB
2vc89.vc.panix.com14.8 MB140.5 KB5.1 MB20.0 MB 74%4.5 KB
3feeder.eternal-september.org12.3 MB0.0 KB21.1 MB33.5 MB 36%5.5 KB
4newsfeed.neva.ru7.6 MB0.0 KB39.0 MB46.6 MB 16%4.4 KB
5etla.org6.9 MB0.0 KB1.7 KB6.9 MB 99%3.1 KB
6news-out.mixmin.net6.0 MB0.0 KB2.6 MB8.5 MB 70%2.0 KB
7news.netfront.net3.0 MB0.0 KB6.9 MB9.9 MB 30%57.3 KB
8localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 894.5 MB140.5 KB90.9 MB185.5 MB 50%4.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jan 31 00:00:02 - 00:59:59 1031 4.0% 0.296.3 MB 6.7% 1.81
Jan 31 01:00:00 - 01:59:59 801 3.1% 0.225.2 MB 5.5% 1.48
Jan 31 02:00:00 - 02:59:59 618 2.4% 0.171.8 MB 2.0% 0.52
Jan 31 03:00:00 - 03:59:59 744 2.9% 0.212.5 MB 2.6% 0.70
Jan 31 04:00:00 - 04:59:59 516 2.0% 0.141.6 MB 1.7% 0.46
Jan 31 05:00:00 - 05:59:59 621 2.4% 0.172.8 MB 2.9% 0.78
Jan 31 06:00:00 - 06:59:59 594 2.3% 0.173.9 MB 4.1% 1.10
Jan 31 07:00:00 - 07:59:59 582 2.3% 0.168.1 MB 8.6% 2.30
Jan 31 08:00:00 - 08:59:59 812 3.2% 0.232.2 MB 2.3% 0.61
Jan 31 09:00:00 - 09:59:59 1026 4.0% 0.282.9 MB 3.1% 0.82
Jan 31 10:00:00 - 10:59:59 1313 5.2% 0.364.9 MB 5.2% 1.40
Jan 31 11:00:00 - 11:59:59 1070 4.2% 0.303.6 MB 3.8% 1.03
Jan 31 12:00:00 - 12:59:59 1216 4.8% 0.343.7 MB 4.0% 1.06
Jan 31 13:00:00 - 13:59:59 1164 4.6% 0.326.3 MB 6.7% 1.79
Jan 31 14:00:00 - 14:59:59 1295 5.1% 0.364.0 MB 4.2% 1.14
Jan 31 15:00:00 - 15:59:59 1347 5.3% 0.373.6 MB 3.8% 1.02
Jan 31 16:00:00 - 16:59:59 1401 5.5% 0.394.9 MB 5.2% 1.40
Jan 31 17:00:00 - 17:59:59 1366 5.4% 0.383.6 MB 3.8% 1.02
Jan 31 18:00:00 - 18:59:59 1462 5.7% 0.414.1 MB 4.4% 1.18
Jan 31 19:00:00 - 19:59:59 1267 5.0% 0.354.2 MB 4.4% 1.19
Jan 31 20:00:00 - 20:59:59 1421 5.6% 0.393.7 MB 4.0% 1.07
Jan 31 21:00:00 - 21:59:59 1338 5.3% 0.373.8 MB 4.0% 1.07
Jan 31 22:00:00 - 22:59:59 1266 5.0% 0.353.4 MB 3.6% 0.96
Jan 31 23:00:00 - 23:59:59 1187 4.7% 0.333.2 MB 3.4% 0.92
Feb 1 00:00:00 - 00:00:02 1 0.0% 0.5027.6 KB 0.0% 13.79
TOTAL: 24:00:00 25459 100.0% 0.2994.4 MB 100.0% 1.12
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 7955 1211 0 0 0 0 0 0 6744
2linuxd-out.news.weretis.net 3521 1292 0 0 0 0 0 0 2229
3feeder.eternal-september.org 2291 142 0 0 0 0 0 0 2149
4news-out.mixmin.net 1877 44 0 0 0 0 0 0 1833
5vc89.vc.panix.com 572 5 0 5 0 0 0 0 562
6news.netfront.net 86 1 0 0 0 0 0 0 85
7etla.org 7 0 0 0 0 0 0 0 7
TOTAL: 7 16309 2695 0 5 0 0 0 013609

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 282
ut.test 237
mailing.freebsd.pkg-fallout 160
lada.talk 149
alt.politics 118
pld.cvs.commit 98
alt.windows7.general 88
alt.survival 63
it-alt.sport.calcio.juventus 61
lada.auto 52
mailing.freebsd.svn-ports-all 46
mailing.freebsd.svn-src-all 46
fa.freebsd.cvs-all 46
mailing.freebsd.svn-ports-head 46
alt.politics.scorched-earth 45
mailing.freebsd.ports-bugs 39
mailing.freebsd.svn-src-head 38
mpc.lists.freebsd.ports.bugs 37
mozilla.support.seamonkey 32
fa.freebsd.current 29
TOTAL: 239 2695

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 8173
EMP (phn path) 2666
EMP (phl) 2219
PFM bot misc 95
EMP (phn nph) 81
Too many newsgroups 69
User-issued cancel 68
Binary: misplaced rar 44
Binary Image: misplaced jpg 41
Binary Payload 24
Binary: non-binary distribution 12
Binary: misplaced PAR2 7
HTML post 3
Binary: misplaced par2 2
Binary: misplaced 471 1
Binary: misplaced nzb 1
TOTAL: 16 13506

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1vc89.vc.panix.com 30927 796 11953 12709 0 40 2%24:00:07
2news.netfront.net 46300 415 24835 108 2 214 0%24:00:17
3etla.org 31077 409 25012 21 0 81 1%23:58:51
4news.killfile.org 40830 154 21959 3328 0 39 0%23:58:36
5weretis.net 16289 15 15808 0 0 0 0%24:04:15
6neva.ru 25146 6 22565 12 0 5 0%24:06:47
7news.stack.nl 26493 4 25159 7 0 30 0%23:55:28
8eternal-september 22064 1 21269 0 0 0 0%24:01:09
TOTAL: 8 239126 1800 168560 16185 2 409 0%192:05:30
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1etla.org21.9 MB2.1 MB24.0 MB0.3 KB/s57.1 KB23:58:51
2news.netfront.net14.9 MB1.0 MB15.9 MB0.2 KB/s31.2 KB24:00:17
3vc89.vc.panix.com2.7 MB43.1 MB45.8 MB0.5 KB/s3.5 KB24:00:07
4news.killfile.org591.3 KB11.4 MB12.0 MB0.1 KB/s3.5 KB23:58:36
5news.stack.nl66.6 KB21.4 KB88.0 KB0.0 KB/s8.0 KB23:55:28
6weretis.net36.9 KB0.0 KB36.9 KB0.0 KB/s2.5 KB24:04:15
7neva.ru18.0 KB446.1 KB464.1 KB0.0 KB/s25.8 KB24:06:47
8eternal-september1.7 KB0.0 KB1.7 KB0.0 KB/s1.7 KB24:01:09
TOTAL: 840.2 MB58.1 MB98.3 MB0.1 KB/s5.6 KB192:05:30
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1feeder.eternal-september.org 8 00.0 KB 0 0 000:00:02
TOTAL: 1 8 00.0 KB 0 0 000:00:02

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.eternal-september.org 8 00.0 KB 0 0 000:00:02
TOTAL: 1 8 00.0 KB 0 0 000:00:02

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
feeder.eternal-september.org 0.528 0.048 0.00000:00:02
TOTAL: 1 0.528 0.048 0.00000:00:02