Daily Usenet report for paganini.linuxd.org

Oct 8 00:00:03 -- Oct 9 00:00:02

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Oct 8 04:05:24 paganini overchan[509]: input line too long, skipping
Oct 8 14:10:37 paganini overchan[509]: input line too long, skipping
Oct 9 00:00:02 paganini overchan[20357]: 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 103601 66.1%12.6 MB 55.2%
inn 36313 23.2%7.8 MB 34.3%
innd 16329 10.4%2.3 MB 10.2%
nnrpd 489 0.3%66.8 KB 0.3%
nocem 56 0.0%8.8 KB 0.0%
overchan 4 0.0%0.7 KB 0.0%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 7 156794 100.0%22.8 MB100.0%

History cache:

ReasonCount%Count
Positive hits 96897 47.2%
Negative hits 72632 35.4%
Do not exist 35798 17.4%
Cache misses 67 0.0%
TOTAL: 4 205394 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.178 0.0%166 0.000 1.072 14.700
article cleanup00:00:00.225 0.0%35888 0.000 0.006 0.019
article logging00:00:00.346 0.0%36230 0.000 0.010 0.160
article parse00:00:00.940 0.0%78901 0.002 0.012 0.030
article write00:00:46.152 0.1%20330 0.123 2.270 16.690
artlog/artcncl00:00:00.000 0.0%165 0.000 0.000 0.000
data move00:00:00.172 0.0%234910 0.000 0.001 0.005
hisgrep/artcncl00:00:00.003 0.0%161 0.000 0.019 1.000
hishave/artcncl00:00:00.001 0.0%166 0.000 0.006 0.056
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:09.253 0.0%206095 0.015 0.045 0.778
history sync00:00:00.026 0.0%400 0.000 0.065 1.000
history write00:00:26.585 0.0%35886 0.026 0.741 2.319
hiswrite/artcncl00:00:00.000 0.0%5 0.000 0.000 0.000
idle23:57:31.066 99.7%232131 232.629 371.562 1147.141
nntp read00:00:01.833 0.0%236771 0.003 0.008 0.018
overview write00:00:19.368 0.0%20330 0.000 0.953 5.545
perl filter00:01:20.183 0.1%35886 1.111 2.234 5.551
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.193 0.0%61140 0.000 0.020 0.075
TOTAL: 24:01:15.24824:00:37.524100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.085 0.0%37296 0.000 0.002 0.017
article prepare00:00:00.005 0.0%19524 0.000 0.000 0.016
article read00:00:00.824 0.0%3827 0.000 0.215 3.870
backlog stats00:00:00.220 0.0%333494 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.380 0.0%179167 0.001 0.008 0.016
data write00:00:02.898 0.0%158931 0.007 0.018 0.037
idle23:42:48.918 99.3%333494 135.520 255.983 461.998
status file00:09:51.889 0.7%43308 7.429 13.667 28.162
TOTAL: 23:53:04.85623:52:46.219100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle03:45:35.544 99.9%428 28.000 31625.103600106.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.168 0.0%1106 0.000 0.152 3.762
readart00:00:00.059 0.0%833 0.000 0.071 1.000
TOTAL: 03:45:53.40203:45:35.771 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 4 46033 16674 27091 2268 36%48:28:26
2newsfeed.neva.ru 138 75063 2166 60106 12791 2%71:12:20
3news.fcku.it 1 7509 1547 5627 335 20%23:50:38
4nntp.pasdenom.info 3 203 0 202 1 0%00:15:07
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 171 128808 20387 93026 15395 15%143:46:31
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net54.7 MB0.0 KB14.3 MB69.0 MB 79%3.7 KB
2newsfeed.neva.ru6.2 MB11.5 KB94.8 MB101.0 MB 6%6.9 KB
3news.fcku.it4.3 MB0.0 KB6.9 MB11.2 MB 38%6.1 KB
4nntp.pasdenom.info0.0 KB0.0 KB5.7 KB5.7 KB 0%5.7 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 565.2 MB11.5 KB116.0 MB181.2 MB 35%5.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 8 00:00:03 - 00:59:59 849 4.2% 0.242.9 MB 4.6% 0.83
Oct 8 01:00:00 - 01:59:59 930 4.6% 0.263.4 MB 5.3% 0.96
Oct 8 02:00:00 - 02:59:59 667 3.3% 0.192.7 MB 4.1% 0.76
Oct 8 03:00:00 - 03:59:59 483 2.4% 0.131.5 MB 2.3% 0.42
Oct 8 04:00:00 - 04:59:59 371 1.8% 0.101.3 MB 2.1% 0.38
Oct 8 05:00:00 - 05:59:59 376 1.9% 0.101.5 MB 2.3% 0.42
Oct 8 06:00:00 - 06:59:59 460 2.3% 0.131.8 MB 2.7% 0.50
Oct 8 07:00:00 - 07:59:59 442 2.2% 0.121.5 MB 2.3% 0.43
Oct 8 08:00:00 - 08:59:59 577 2.8% 0.161.7 MB 2.6% 0.47
Oct 8 09:00:00 - 09:59:59 707 3.5% 0.201.8 MB 2.8% 0.52
Oct 8 10:00:00 - 10:59:59 943 4.7% 0.262.6 MB 4.1% 0.75
Oct 8 11:00:00 - 11:59:59 927 4.6% 0.263.7 MB 5.7% 1.05
Oct 8 12:00:00 - 12:59:59 995 4.9% 0.282.7 MB 4.1% 0.76
Oct 8 13:00:00 - 13:59:59 948 4.7% 0.262.3 MB 3.5% 0.64
Oct 8 14:00:00 - 14:59:59 920 4.5% 0.262.5 MB 3.9% 0.71
Oct 8 15:00:00 - 15:59:59 1026 5.1% 0.282.8 MB 4.3% 0.79
Oct 8 16:00:00 - 16:59:59 1054 5.2% 0.292.9 MB 4.5% 0.83
Oct 8 17:00:00 - 17:59:59 984 4.9% 0.273.0 MB 4.7% 0.86
Oct 8 18:00:00 - 18:59:59 1174 5.8% 0.336.5 MB 10.1% 1.84
Oct 8 19:00:00 - 19:59:59 1233 6.1% 0.343.8 MB 5.9% 1.08
Oct 8 20:00:00 - 20:59:59 1151 5.7% 0.323.2 MB 5.0% 0.91
Oct 8 21:00:00 - 21:59:59 1111 5.5% 0.312.7 MB 4.2% 0.76
Oct 8 22:00:00 - 22:59:59 1016 5.0% 0.283.2 MB 4.9% 0.90
Oct 8 23:00:00 - 23:59:59 933 4.6% 0.262.4 MB 3.7% 0.67
Oct 9 00:00:00 - 00:00:02 1 0.0% 0.504.0 KB 0.0% 2.01
TOTAL: 23:59:59 20278 100.0% 0.2364.2 MB 100.0% 0.76
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 12898 691 0 2 0 0 0 012205
2linuxd-out.news.weretis.net 2585 386 0 0 0 0 0 0 2199
3news.fcku.it 342 3 0 0 1 0 0 0 338
4nntp.pasdenom.info 65 0 0 0 0 0 0 0 65
TOTAL: 4 15890 1080 0 2 1 0 0 014807

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 262
ut.test 122
lada.talk 116
alt.survival 52
lada.auto 48
fa.freebsd.cvs-all 37
lada.photo 28
mozilla.support.seamonkey 25
fa.openbsd.source-changes 22
it-alt.sport.calcio.juventus 22
cakewalk.audio 22
fa.openbsd.tech 20
uk.radio.amateur.moderated 20
alt.windows7.general 19
mozilla.support.thunderbird 16
fa.philos-l 12
it.comp.os.win.windows10 11
fa.freebsd.stable 9
mpc.lists.freebsd.ports.bugs 8
mpc.lists.freebsd.gecko 7
TOTAL: 124 1080

Supposedly-moderated groups with unmoderated postings [Top 20]:

GroupsCount
alt.culture.china 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 11217
EMP (phn path) 2233
EMP (phl) 814
EMP (phn nph) 105
Too many newsgroups 45
User-issued cancel 18
Binary Payload 18
Binary: non-binary distribution 11
HTML post 3
Binary Image: misplaced png 1
TOTAL: 10 14465

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 321
newsfeed.neva.ru 15
news.fcku.it 6
TOTAL: 3 342
TOTAL: 1 342

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nntp.pasdenom.info 35123 915 18577 745 0 3006 2%23:57:41
2news.fcku.it 23715 905 16364 1448 0 5 3%23:59:47
3weretis.net 4070 397 3343 0 0 5 9%23:56:42
4neva.ru 27683 105 17603 379 0 11 0%23:59:45
5eternal-september 23144 5 17040 0 0 9 0%23:59:34
6news.mixmin.net 0 0 0 0 0 0 0%00:10:00
7etla.org 0 0 0 0 0 20278 0%23:59:48
8news.stack.nl 2829 0 0 0 0 22994 0%23:59:48
9vc89.vc.panix.com 2838 0 0 0 0 23115 0%23:59:48
10news.killfile.org 2847 0 0 0 0 23124 0%23:59:48
11news.netfront.net 2757 0 0 0 0 22972 0%23:59:47
12news.albasani.net 2860 0 0 0 0 22421 0%23:59:48
TOTAL: 12 127866 2327 72927 2572 0 137940 1%264:02:16
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nntp.pasdenom.info4.4 MB1.1 MB5.5 MB0.1 KB/s3.4 KB23:57:41
2news.fcku.it3.5 MB5.4 MB8.9 MB0.1 KB/s3.9 KB23:59:47
3weretis.net1.1 MB0.0 KB1.1 MB0.0 KB/s3.0 KB23:56:42
4neva.ru460.9 KB1.5 MB1.9 MB0.0 KB/s4.1 KB23:59:45
5eternal-september11.6 KB0.0 KB11.6 KB0.0 KB/s2.3 KB23:59:34
6news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
7etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:48
8news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:48
9vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:48
10news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:48
11news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:47
12news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:48
TOTAL: 129.4 MB8.0 MB17.4 MB0.0 KB/s3.6 KB264:02:16
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
147.192.187.101 13 34.5 KB 2 0 001:37:47
2challenger.nielsenmedia.com 11 22.7 KB 1 0 002:07:34
TOTAL: 2 24 57.2 KB 3 0 003:45:52

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 14 34.5 KB 2 0 001:37:53
2*.nielsenmedia.com 11 22.7 KB 1 0 002:07:34
3*.188.110.pools.vodafone-ip.de 115 00.0 KB 0 0 000:00:25
TOTAL: 3 140 57.2 KB 3 0 003:45:52

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
challenger.nielsenmedia.com 0.840 0.056 0.00002:07:34
47.192.187.101 0.848 0.088 0.00001:37:47
dslb-188-110-188-212.188.110.pools.vodafone-ip.de 6.016 0.408 0.00000:00:25
196.52.16.7 0.112 0.008 0.00000:00:05
TOTAL: 4 7.816 0.560 0.00003:45:52

Curious NNRP server explorers [Top 20]:

SystemConn
dslb-188-110-188-212.188.110.pools.vodafone-ip.de 115
196.52.16.7 1
TOTAL: 2 116

NNRP client timeouts [Top 20]:

SystemConnPeer
challenger.nielsenmedia.com 4 1
47.192.187.101 2 1
TOTAL: 2 6 2

Newsgroup request counts (by category):

CategoryCountPct
1comp 5100.0%
TOTAL: 1 5100.0%

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

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