Daily Usenet report for paganini.linuxd.org

Mar 25 00:00:02 -- Mar 26 00:00:04

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Mar 25 06:01:33 paganini overchan[13740]: input line too long, skipping
Mar 25 17:53:13 paganini overchan[13740]: input line too long, skipping
Mar 25 17:57:03 paganini overchan[13740]: input line too long, skipping
Mar 25 18:44:20 paganini overchan[13740]: input line too long, skipping
Mar 25 23:02:40 paganini overchan[13740]: input line too long, skipping
Mar 25 23:04:15 paganini overchan[13740]: input line too long, skipping
Mar 25 23:04:38 paganini overchan[13740]: input line too long, skipping
Mar 26 00:00:04 paganini overchan[32500]: 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 115297 67.2%14.3 MB 58.4%
inn 37114 21.6%7.7 MB 31.2%
innd 18560 10.8%2.5 MB 10.0%
nnrpd 525 0.3%75.1 KB 0.3%
nocem 84 0.0%14.4 KB 0.1%
overchan 9 0.0%1.6 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 171590 100.0%24.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 104245 48.3%
Negative hits 75064 34.8%
Do not exist 36364 16.9%
Cache misses 70 0.0%
TOTAL: 4 215743 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.726 0.0%174 0.000 4.172 33.000
article cleanup00:00:00.243 0.0%36531 0.000 0.007 0.025
article logging00:00:00.398 0.0%36969 0.000 0.011 0.029
article parse00:00:01.081 0.0%86702 0.000 0.012 0.033
article write00:02:56.254 0.2%19391 0.091 9.089 89.161
artlog/artcncl00:00:00.002 0.0%174 0.000 0.011 0.091
data move00:00:00.352 0.0%228733 0.000 0.002 0.031
hisgrep/artcncl00:00:00.075 0.0%173 0.000 0.434 3.632
hishave/artcncl00:00:00.035 0.0%174 0.000 0.201 2.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:03:13.615 0.2%216705 0.016 0.893 5.860
history sync00:00:00.105 0.0%402 0.000 0.261 18.333
history write00:03:01.345 0.2%36531 0.014 4.964 29.464
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle23:48:16.342 99.1%220985 215.532 387.793 1348.899
nntp read00:00:01.811 0.0%236899 0.000 0.008 0.042
overview write00:01:25.052 0.1%19391 0.021 4.386 32.492
perl filter00:01:31.036 0.1%36531 1.193 2.492 7.021
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.904 0.0%58344 0.000 0.015 0.044
TOTAL: 24:01:08.65524:00:29.376100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.097 0.0%35489 0.000 0.003 0.019
article prepare00:00:00.002 0.0%22284 0.000 0.000 0.019
article read00:00:01.872 0.0%4903 0.000 0.382 16.909
backlog stats00:00:00.218 0.0%273207 0.000 0.001 0.005
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.159 0.0%147800 0.001 0.008 0.020
data write00:00:02.526 0.0%128640 0.009 0.020 0.037
idle23:35:50.377 98.7%273207 219.970 310.938 495.803
status file00:17:34.139 1.2%48312 9.765 21.819 132.308
TOTAL: 23:53:54.78223:53:30.390100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle04:58:21.451 99.9%453 27.333 39517.552600128.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.014 0.0%1200 0.000 0.012 0.500
readart00:00:00.103 0.0%14 0.000 7.357 32.000
TOTAL: 04:58:36.85104:58:21.568 99.9%----

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 5 41979 16430 23543 2006 39%47:45:15
2newsfeed.neva.ru 144 74163 2322 56878 14963 3%71:32:58
3news.fcku.it 2 7076 520 6397 159 7%26:23:27
4nntp.pasdenom.info 2 23104 52 23046 6 0%23:45:02
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 178 146322 19324 109864 17134 13%169:26:42
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net50.1 MB0.0 KB12.5 MB62.7 MB 80%3.5 KB
2newsfeed.neva.ru6.3 MB0.0 KB134.3 MB140.6 MB 4%8.3 KB
3news.fcku.it2.7 MB0.0 KB3.8 MB6.4 MB 41%9.7 KB
4nntp.pasdenom.info105.8 KB0.0 KB11.0 KB116.7 KB 90%2.0 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 559.2 MB0.0 KB150.5 MB209.8 MB 28%5.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Mar 25 00:00:02 - 00:59:59 701 3.6% 0.192.6 MB 4.4% 0.74
Mar 25 01:00:00 - 01:59:59 682 3.5% 0.192.4 MB 4.1% 0.69
Mar 25 02:00:00 - 02:59:59 528 2.7% 0.151.7 MB 2.8% 0.48
Mar 25 03:00:00 - 03:59:59 433 2.2% 0.121.7 MB 2.8% 0.48
Mar 25 04:00:00 - 04:59:59 385 2.0% 0.111.2 MB 2.0% 0.33
Mar 25 05:00:00 - 05:59:59 380 2.0% 0.111.2 MB 2.0% 0.34
Mar 25 06:00:00 - 06:59:59 391 2.0% 0.112.0 MB 3.3% 0.56
Mar 25 07:00:00 - 07:59:59 397 2.0% 0.111.2 MB 1.9% 0.33
Mar 25 08:00:00 - 08:59:59 625 3.2% 0.171.6 MB 2.8% 0.47
Mar 25 09:00:00 - 09:59:59 919 4.7% 0.262.8 MB 4.7% 0.80
Mar 25 10:00:00 - 10:59:59 939 4.8% 0.262.9 MB 4.9% 0.82
Mar 25 11:00:00 - 11:59:59 879 4.5% 0.242.4 MB 4.1% 0.69
Mar 25 12:00:00 - 12:59:59 840 4.3% 0.232.4 MB 4.0% 0.68
Mar 25 13:00:00 - 13:59:59 852 4.4% 0.242.4 MB 4.0% 0.68
Mar 25 14:00:00 - 14:59:59 960 5.0% 0.272.5 MB 4.2% 0.72
Mar 25 15:00:00 - 15:59:59 949 4.9% 0.262.6 MB 4.3% 0.73
Mar 25 16:00:00 - 16:59:59 1130 5.8% 0.313.5 MB 5.9% 1.00
Mar 25 17:00:00 - 17:59:59 1121 5.8% 0.314.0 MB 6.7% 1.13
Mar 25 18:00:00 - 18:59:59 1079 5.6% 0.303.1 MB 5.2% 0.88
Mar 25 19:00:00 - 19:59:59 1084 5.6% 0.302.9 MB 4.9% 0.83
Mar 25 20:00:00 - 20:59:59 1050 5.4% 0.293.0 MB 5.0% 0.84
Mar 25 21:00:00 - 21:59:59 1032 5.3% 0.293.6 MB 6.1% 1.04
Mar 25 22:00:00 - 22:59:59 1066 5.5% 0.303.1 MB 5.2% 0.88
Mar 25 23:00:00 - 23:59:59 960 5.0% 0.272.8 MB 4.6% 0.78
Mar 26 00:00:00 - 00:00:04 3 0.0% 0.7520.7 KB 0.0% 5.19
TOTAL: 24:00:02 19385 100.0% 0.2259.5 MB 100.0% 0.70
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 14979 244 0 0 0 0 0 014735
2linuxd-out.news.weretis.net 2413 334 0 0 0 0 0 0 2079
3news.fcku.it 153 0 0 0 0 0 0 0 153
4nntp.pasdenom.info 10 0 0 0 0 0 0 0 10
TOTAL: 4 17555 578 0 0 0 0 0 016977

Unwanted newsgroups [Top 20]:

NewsgroupCount
lada.talk 95
ut.test 67
lada.auto 41
alt.survival 37
spline.eisfair 33
alt.windows7.general 27
alt.politics.scorched-earth 24
alt.politics 24
alt.2600 19
lada.commerce 14
mozilla.support.firefox 12
mozilla.general 11
it-alt.sport.calcio.juventus 9
mpc.lists.freebsd.x11 8
mozilla.dev.platform 7
alt.philosophy 7
mozilla.dev.tech.js-engine 6
alt.tv.star-trek 6
oesterreich.tratsch 5
mozilla.support.seamonkey 5
TOTAL: 95 578

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 14458
EMP (phn path) 1516
EMP (phn nph) 230
EMP (phr path) 178
Too many newsgroups 47
User-issued cancel 44
EMP (phl) 29
Binary Payload 20
Too many newsgroups (low_xpost) 9
HTML post 6
Binary: non-binary distribution 4
User-issued spam cancel 1
Binary: misplaced nzb 1
TOTAL: 13 16543

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 396
newsfeed.neva.ru 36
news.fcku.it 2
TOTAL: 3 434
TOTAL: 1 434

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.fcku.it 22575 1820 14292 2829 0 2 8%23:59:55
2nntp.pasdenom.info 7731 571 2682 98 0 18059 7%23:57:48
3neva.ru 23566 87 16885 73 0 49 0%23:59:53
4weretis.net 2919 57 2738 0 0 9 1%23:58:08
5eternal-september 20789 48 16543 0 0 7 0%23:59:55
6news.netfront.net 2736 0 0 0 0 22054 0%23:59:55
7news.albasani.net 2859 0 0 0 0 21569 0%23:59:56
8news.killfile.org 2848 0 0 0 0 22232 0%23:59:55
9vc89.vc.panix.com 2834 0 0 0 0 22218 0%23:59:55
10news.mixmin.net 0 0 0 0 0 0 0%00:10:00
11etla.org 0 0 0 0 0 19385 0%23:59:56
12news.stack.nl 2843 0 0 0 0 22208 0%23:59:55
TOTAL: 12 91700 2583 53140 3000 0 147792 2%264:05:11
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.fcku.it5.4 MB8.3 MB13.7 MB0.2 KB/s3.0 KB23:59:55
2nntp.pasdenom.info1.9 MB569.8 KB2.4 MB0.0 KB/s3.7 KB23:57:48
3neva.ru307.1 KB627.7 KB934.9 KB0.0 KB/s5.8 KB23:59:53
4eternal-september216.5 KB0.0 KB216.5 KB0.0 KB/s4.5 KB23:59:55
5weretis.net106.7 KB0.0 KB106.7 KB0.0 KB/s1.9 KB23:58:08
6news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
7news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
8news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
9vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
10news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
11etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
12news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
TOTAL: 127.8 MB9.4 MB17.3 MB0.0 KB/s3.2 KB264:05:11
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1challenger.nielsenmedia.com 20 919.0 KB 4 0 003:42:47
247-192-187-101.drr02.clwr.fl.frontiernet.net 11 510.5 KB 2 0 001:15:28
TOTAL: 2 31 1429.5 KB 6 0 004:58:36

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.nielsenmedia.com 20 919.0 KB 4 0 003:42:47
2*.drr02.clwr.fl.frontiernet.net 11 510.5 KB 2 0 001:15:28
3*.092.073.pools.vodafone-ip.de 124 00.0 KB 0 0 000:00:20
TOTAL: 3 155 1429.5 KB 6 0 004:58:36

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
challenger.nielsenmedia.com 1.396 0.100 0.00003:42:47
47-192-187-101.drr02.clwr.fl.frontiernet.net 0.736 0.048 0.00001:15:28
dslb-092-073-008-096.092.073.pools.vodafone-ip.de 6.712 0.440 0.00000:00:20
TOTAL: 3 8.844 0.588 0.00004:58:36

Curious NNRP server explorers [Top 20]:

SystemConn
dslb-092-073-008-096.092.073.pools.vodafone-ip.de 124
TOTAL: 1 124

NNRP client timeouts [Top 20]:

SystemConnPeer
challenger.nielsenmedia.com 7 0
47-192-187-101.drr02.clwr.fl.frontiernet.net 2 1
TOTAL: 2 9 1

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