Daily Usenet report for paganini.linuxd.org

Apr 29 00:00:02 -- Apr 30 00:00:07

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Apr 29 00:44:08 paganini overchan[13337]: input line too long, skipping
Apr 29 11:33:29 paganini overchan[13337]: input line too long, skipping
Apr 30 00:00:06 paganini overchan[30761]: 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 118109 55.0%14.7 MB 49.9%
nnrpd 39389 18.3%4.5 MB 15.5%
inn 37458 17.4%7.6 MB 25.8%
innd 19661 9.2%2.6 MB 8.8%
nocem 46 0.0%7.3 KB 0.0%
overchan 4 0.0%0.5 KB 0.0%
controlchan 1 0.0%0.0 KB 0.0%
TOTAL: 7 214668 100.0%29.4 MB100.0%

History cache:

ReasonCount%Count
Positive hits 102587 47.5%
Negative hits 76505 35.4%
Do not exist 36879 17.1%
Cache misses 62 0.0%
TOTAL: 4 216033 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.355 0.0%153 0.000 8.856 55.000
article cleanup00:00:00.261 0.0%37021 0.000 0.007 0.030
article logging00:00:00.361 0.0%37373 0.000 0.010 0.031
article parse00:00:01.040 0.0%89456 0.000 0.012 0.049
article write00:00:54.945 0.1%18720 0.169 2.935 47.517
artlog/artcncl00:00:00.000 0.0%153 0.000 0.000 0.000
data move00:00:00.178 0.0%236489 0.000 0.001 0.003
hisgrep/artcncl00:00:00.447 0.0%153 0.000 2.922 23.421
hishave/artcncl00:00:00.081 0.0%153 0.000 0.529 4.158
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:23.353 0.0%216874 0.012 0.108 5.149
history sync00:00:00.126 0.0%398 0.000 0.317 15.833
history write00:00:38.248 0.0%37020 0.038 1.033 30.838
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:56:16.978 99.7%234504 198.813 367.486 1222.342
nntp read00:00:01.944 0.0%243494 0.001 0.008 0.024
overview write00:00:26.313 0.0%18720 0.000 1.406 26.803
perl filter00:01:28.497 0.1%37019 1.091 2.391 7.571
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.111 0.0%56301 0.003 0.020 0.073
TOTAL: 24:01:02.83424:00:15.238 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.124 0.0%55589 0.000 0.002 0.017
article prepare00:00:00.004 0.0%15096 0.000 0.000 0.010
article read00:00:01.751 0.0%3559 0.000 0.492 23.458
backlog stats00:00:00.223 0.0%292467 0.000 0.001 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.304 0.0%154845 0.002 0.008 0.022
data write00:00:02.731 0.0%136293 0.012 0.020 0.035
idle23:40:29.865 99.1%292467 26.056 291.417 501.207
status file00:13:00.642 0.9%49492 8.296 15.773 99.054
TOTAL: 23:53:53.67623:53:36.644100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle03:36:53.392 99.9%39907 37.852 326.093600161.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.842 0.0%81191 0.000 0.010 0.167
readart00:00:00.035 0.0%7 0.000 5.000 9.000
TOTAL: 03:37:08.65403:36:54.269 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 8 40954 16013 22486 2455 39%47:46:47
2newsfeed.neva.ru 144 74346 1958 56775 15613 2%71:44:49
3news.fcku.it 2 7039 372 6403 264 5%25:00:50
4nntp.pasdenom.info 2 21774 62 21685 27 0%23:44:42
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 181 144113 18405 107349 18359 12%168:17:08
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net46.9 MB1.2 KB19.9 MB66.7 MB 70%3.7 KB
2newsfeed.neva.ru5.3 MB0.0 KB133.7 MB139.0 MB 3%8.1 KB
3news.fcku.it2.3 MB0.0 KB5.7 MB8.0 MB 29%12.9 KB
4nntp.pasdenom.info167.3 KB0.0 KB1.0 MB1.2 MB 13%13.8 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 554.6 MB1.2 KB160.3 MB214.9 MB 25%6.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Apr 29 00:00:02 - 00:59:59 821 4.4% 0.232.8 MB 5.1% 0.80
Apr 29 01:00:00 - 01:59:59 624 3.3% 0.171.5 MB 2.8% 0.44
Apr 29 02:00:00 - 02:59:59 501 2.7% 0.141.5 MB 2.7% 0.42
Apr 29 03:00:00 - 03:59:59 445 2.4% 0.121.7 MB 3.0% 0.47
Apr 29 04:00:00 - 04:59:59 442 2.4% 0.121.2 MB 2.1% 0.33
Apr 29 05:00:00 - 05:59:59 417 2.2% 0.121.2 MB 2.1% 0.33
Apr 29 06:00:00 - 06:59:59 337 1.8% 0.091.0 MB 1.9% 0.30
Apr 29 07:00:00 - 07:59:59 406 2.2% 0.111.3 MB 2.3% 0.36
Apr 29 08:00:00 - 08:59:59 636 3.4% 0.182.1 MB 3.9% 0.61
Apr 29 09:00:00 - 09:59:59 786 4.2% 0.223.3 MB 5.9% 0.94
Apr 29 10:00:00 - 10:59:59 738 4.0% 0.202.0 MB 3.7% 0.58
Apr 29 11:00:00 - 11:59:59 911 4.9% 0.252.7 MB 4.8% 0.75
Apr 29 12:00:00 - 12:59:59 677 3.6% 0.192.0 MB 3.5% 0.55
Apr 29 13:00:00 - 13:59:59 859 4.6% 0.242.3 MB 4.1% 0.64
Apr 29 14:00:00 - 14:59:59 957 5.1% 0.272.6 MB 4.8% 0.75
Apr 29 15:00:00 - 15:59:59 1032 5.5% 0.293.5 MB 6.3% 0.99
Apr 29 16:00:00 - 16:59:59 1029 5.5% 0.293.1 MB 5.5% 0.87
Apr 29 17:00:00 - 17:59:59 1051 5.6% 0.292.8 MB 5.1% 0.80
Apr 29 18:00:00 - 18:59:59 1119 6.0% 0.313.1 MB 5.6% 0.88
Apr 29 19:00:00 - 19:59:59 1108 5.9% 0.313.2 MB 5.9% 0.92
Apr 29 20:00:00 - 20:59:59 1065 5.7% 0.303.0 MB 5.3% 0.84
Apr 29 21:00:00 - 21:59:59 969 5.2% 0.272.6 MB 4.7% 0.74
Apr 29 22:00:00 - 22:59:59 924 4.9% 0.262.6 MB 4.6% 0.73
Apr 29 23:00:00 - 23:59:59 817 4.4% 0.232.5 MB 4.6% 0.72
Apr 30 00:00:00 - 00:00:07 7 0.0% 1.0020.0 KB 0.0% 2.86
TOTAL: 24:00:05 18678 100.0% 0.2255.4 MB 100.0% 0.66
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 15620 335 0 0 0 0 0 015285
2linuxd-out.news.weretis.net 2791 310 0 1 0 0 0 0 2480
3news.fcku.it 218 6 0 0 0 0 0 0 212
4nntp.pasdenom.info 14 0 0 0 0 1 0 0 13
TOTAL: 4 18643 651 0 1 0 1 0 017990

Unwanted newsgroups [Top 20]:

NewsgroupCount
ut.test 135
lada.talk 109
alt.windows7.general 82
spline.eisfair 41
mpc.lists.freebsd.ports.bugs 32
it-alt.sport.calcio.juventus 27
lada.auto 26
mozilla.support.thunderbird 24
mozilla.test 11
alt.survival 10
gmane.comp.gis.openstreetmap.tagging 10
oesterreich.tratsch 9
uk.radio.amateur.moderated 8
it.comp.os.win.windows10 7
alt.politics.scorched-earth 7
lada.commerce 6
mozilla.support.firefox 5
alt.english.usage 5
alt.personals.ad 5
mozilla.dev.ui-alerts 5
TOTAL: 75 651

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 15930
EMP (phn path) 1466
EMP (phn nph) 91
EMP (phl) 43
Too many newsgroups 32
Binary Payload 26
User-issued cancel 17
Binary: non-binary distribution 15
User-issued spam cancel 4
HTML post 4
Binary: misplaced par2 3
Too many newsgroups (low_xpost) 2
Binary: misplaced sfv 2
U2 violation - invalid distribution 1
Malformed article 1
Binary: misplaced nfo 1
TOTAL: 16 17638

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 317
newsfeed.neva.ru 33
news.fcku.it 2
TOTAL: 3 352
TOTAL: 1 352

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.fcku.it 23799 1292 15040 2085 0 35 5%24:07:49
2nntp.pasdenom.info 25317 192 21677 28 0 19499 0%23:56:12
3weretis.net 2639 48 2374 0 0 11 1%24:00:58
4eternal-september 20708 25 16192 0 0 4 0%24:04:20
5neva.ru 23916 9 16694 116 0 14 0%24:07:49
6news.albasani.net 2858 0 0 0 0 20797 0%23:58:18
7etla.org 0 0 0 0 0 18788 0%24:07:48
8news.killfile.org 2846 0 0 0 0 21493 0%23:57:59
9news.netfront.net 2768 0 0 0 0 21378 0%23:58:18
10news.stack.nl 2853 0 0 0 0 21511 0%23:58:22
11news.mixmin.net 0 0 0 0 0 0 0%00:10:00
12vc89.vc.panix.com 2838 0 0 0 0 21511 0%23:58:14
TOTAL: 12 110542 1566 71977 2229 0 145041 1%264:26:07
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.fcku.it3.8 MB5.5 MB9.3 MB0.1 KB/s2.8 KB24:07:49
2nntp.pasdenom.info579.8 KB76.3 KB656.1 KB0.0 KB/s3.0 KB23:56:12
3eternal-september99.2 KB0.0 KB99.2 KB0.0 KB/s4.0 KB24:04:20
4weretis.net68.4 KB0.0 KB68.4 KB0.0 KB/s1.4 KB24:00:58
5neva.ru31.7 KB476.8 KB508.5 KB0.0 KB/s4.1 KB24:07:49
6news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:18
7etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:07:48
8news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:59
9news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:18
10news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:22
11news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
12vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:14
TOTAL: 124.6 MB6.0 MB10.6 MB0.0 KB/s2.9 KB264:26:07
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1challenger.nielsenmedia.com 8 410.3 KB 2 0 002:24:33
2dslb-188-099-023-198.188.099.pools.vodafone-ip.de 71 36.2 KB 2 0 000:34:25
TOTAL: 2 79 716.4 KB 4 0 003:37:08

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.nielsenmedia.com 8 410.3 KB 2 0 002:24:33
2*.188.099.pools.vodafone-ip.de 71 36.2 KB 2 0 000:34:25
3*.drr02.clwr.fl.frontiernet.net 3 00.0 KB 0 0 000:38:10
TOTAL: 3 82 716.4 KB 4 0 003:37:08

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
challenger.nielsenmedia.com 0.424 0.036 0.00002:24:33
47-192-187-101.drr02.clwr.fl.frontiernet.net 0.140 0.012 0.00000:38:10
dslb-188-099-023-198.188.099.pools.vodafone-ip.de 7.716 1.932 0.00000:34:25
TOTAL: 3 8.280 1.980 0.00003:37:08

Curious NNRP server explorers [Top 20]:

SystemConn
47-192-187-101.drr02.clwr.fl.frontiernet.net 3
TOTAL: 1 3

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1comp 4 57.1%
2de 2 28.6%
3control 1 14.3%
TOTAL: 3 7100.0%

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

NewsgroupCount
1comp.lang.awk 4
2de.soc.politik.misc 2
3control.cancel 1
TOTAL: 3 7