Daily Usenet report for paganini.linuxd.org

Apr 8 00:00:02 -- Apr 9 00:00:06

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Apr 8 04:14:54 paganini overchan[18518]: input line too long, skipping
Apr 8 04:15:16 paganini overchan[18518]: input line too long, skipping
Apr 8 04:16:02 paganini overchan[18518]: input line too long, skipping
Apr 8 07:22:30 paganini overchan[18518]: input line too long, skipping
Apr 8 07:23:40 paganini overchan[18518]: input line too long, skipping
Apr 8 07:25:05 paganini overchan[18518]: input line too long, skipping
Apr 8 15:00:03 paganini controlchan[18516]: Multiple control headers in article <ttY89-5k1-3@gated-at.bofh.it>
Apr 9 00:00:03 paganini overchan[4542]: 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 103907 46.7%12.8 MB 42.7%
nnrpd 63202 28.4%7.3 MB 24.2%
inn 36398 16.3%7.4 MB 24.5%
innd 19025 8.5%2.5 MB 8.4%
nocem 80 0.0%14.3 KB 0.0%
overchan 8 0.0%1.3 KB 0.0%
controlchan 3 0.0%0.3 KB 0.0%
TOTAL: 7 222623 100.0%30.0 MB100.0%

History cache:

ReasonCount%Count
Positive hits 98625 47.0%
Negative hits 75236 35.9%
Do not exist 35906 17.1%
Cache misses 68 0.0%
TOTAL: 4 209835 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.474 0.0%167 0.000 8.826 57.667
article cleanup00:00:00.244 0.0%35969 0.000 0.007 0.026
article logging00:00:00.429 0.0%36249 0.000 0.012 0.212
article parse00:00:01.069 0.0%83852 0.000 0.013 0.040
article write00:01:34.459 0.1%18229 0.121 5.182 43.079
artlog/artcncl00:00:00.001 0.0%167 0.000 0.006 0.333
data move00:00:00.142 0.0%221275 0.000 0.001 0.004
hisgrep/artcncl00:00:00.527 0.0%166 0.000 3.175 29.222
hishave/artcncl00:00:00.095 0.0%167 0.000 0.569 5.222
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:01:54.490 0.1%210142 0.013 0.545 6.322
history sync00:00:00.678 0.0%403 0.000 1.682 299.000
history write00:01:40.012 0.1%35968 0.020 2.781 26.277
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle23:52:54.885 99.4%218823 222.928 392.897 1005.867
nntp read00:00:01.680 0.0%230433 0.002 0.007 0.028
overview write00:00:55.871 0.1%18229 0.011 3.065 27.586
perl filter00:01:26.288 0.1%35966 1.068 2.399 6.724
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.978 0.0%54875 0.000 0.018 0.133
TOTAL: 24:01:06.67024:00:33.322100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.112 0.0%52183 0.000 0.002 0.018
article prepare00:00:00.005 0.0%31420 0.000 0.000 0.004
article read00:00:01.856 0.0%6386 0.000 0.291 5.100
backlog stats00:00:00.246 0.0%316386 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.326 0.0%171216 0.001 0.008 0.015
data write00:00:02.698 0.0%153183 0.007 0.018 0.040
idle23:40:34.086 99.1%316386 28.307 269.399 446.984
status file00:12:51.614 0.9%43446 9.774 17.760 116.384
TOTAL: 23:53:48.99823:53:31.943100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle04:14:19.765 99.9%63890 37.149 238.844600114.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.245 0.0%129646 0.000 0.010 0.125
readart00:00:00.000 0.0%3 0.000 0.000 0.000
TOTAL: 04:14:39.96604:14:21.010 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 39572 15842 22087 1643 40%48:46:14
2newsfeed.neva.ru 146 71745 1513 54558 15674 2%71:28:14
3news.fcku.it 2 6628 1132 5219 277 17%24:58:32
4nntp.pasdenom.info 2 20795 15 20766 14 0%23:57:33
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 179 138740 18502 102630 17608 13%169:10:33
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net49.8 MB0.0 KB12.1 MB61.9 MB 80%3.6 KB
2newsfeed.neva.ru4.2 MB10.1 KB131.6 MB135.8 MB 3%8.1 KB
3news.fcku.it4.1 MB0.0 KB2.8 MB6.9 MB 59%5.0 KB
4nntp.pasdenom.info28.6 KB0.0 KB477.3 KB505.9 KB 5%17.4 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 558.1 MB10.1 KB147.0 MB205.1 MB 28%5.8 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Apr 8 00:00:02 - 00:59:59 793 4.4% 0.223.6 MB 6.3% 1.04
Apr 8 01:00:00 - 01:59:59 726 4.0% 0.202.3 MB 3.9% 0.64
Apr 8 02:00:00 - 02:59:59 501 2.8% 0.141.5 MB 2.6% 0.42
Apr 8 03:00:00 - 03:59:59 528 2.9% 0.151.8 MB 3.2% 0.52
Apr 8 04:00:00 - 04:59:59 501 2.8% 0.142.2 MB 3.8% 0.63
Apr 8 05:00:00 - 05:59:59 429 2.4% 0.121.8 MB 3.1% 0.50
Apr 8 06:00:00 - 06:59:59 379 2.1% 0.111.4 MB 2.4% 0.39
Apr 8 07:00:00 - 07:59:59 456 2.5% 0.131.6 MB 2.8% 0.46
Apr 8 08:00:00 - 08:59:59 600 3.3% 0.172.0 MB 3.4% 0.56
Apr 8 09:00:00 - 09:59:59 687 3.8% 0.192.3 MB 4.0% 0.66
Apr 8 10:00:00 - 10:59:59 883 4.8% 0.252.8 MB 4.9% 0.80
Apr 8 11:00:00 - 11:59:59 867 4.8% 0.242.8 MB 4.9% 0.80
Apr 8 12:00:00 - 12:59:59 710 3.9% 0.202.0 MB 3.4% 0.56
Apr 8 13:00:00 - 13:59:59 820 4.5% 0.232.4 MB 4.2% 0.68
Apr 8 14:00:00 - 14:59:59 849 4.7% 0.242.4 MB 4.2% 0.69
Apr 8 15:00:00 - 15:59:59 1021 5.6% 0.282.7 MB 4.8% 0.78
Apr 8 16:00:00 - 16:59:59 982 5.4% 0.272.7 MB 4.6% 0.76
Apr 8 17:00:00 - 17:59:59 1038 5.7% 0.293.4 MB 5.8% 0.95
Apr 8 18:00:00 - 18:59:59 1000 5.5% 0.282.8 MB 4.9% 0.81
Apr 8 19:00:00 - 19:59:59 919 5.0% 0.262.6 MB 4.5% 0.73
Apr 8 20:00:00 - 20:59:59 923 5.1% 0.262.7 MB 4.6% 0.76
Apr 8 21:00:00 - 21:59:59 1000 5.5% 0.282.8 MB 4.8% 0.79
Apr 8 22:00:00 - 22:59:59 844 4.6% 0.232.5 MB 4.4% 0.71
Apr 8 23:00:00 - 23:59:59 755 4.1% 0.212.5 MB 4.3% 0.71
Apr 9 00:00:00 - 00:00:06 2 0.0% 0.336.2 KB 0.0% 1.03
TOTAL: 24:00:04 18213 100.0% 0.2157.5 MB 100.0% 0.68
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 15830 310 0 1 1 0 0 015518
2linuxd-out.news.weretis.net 1900 407 0 0 0 0 0 0 1493
3news.fcku.it 271 10 0 0 0 0 0 0 261
4nntp.pasdenom.info 17 0 0 0 0 2 0 0 15
TOTAL: 4 18018 727 0 1 1 2 0 017287

Unwanted newsgroups [Top 20]:

NewsgroupCount
ut.test 118
lada.talk 77
mozilla.support.thunderbird 67
lada.kitchen 37
alt.survival 33
lada.auto 33
alt.politics.scorched-earth 28
mpc.lists.freebsd.ports.bugs 25
it-alt.sport.calcio.juventus 25
it.annunci.varie 24
alt.windows7.general 20
it.comp.os.win.windows10 17
alt.2600 14
mozilla.support.seamonkey 14
lada.commerce 11
alt.tv.star-trek 10
gmane.comp.gis.openstreetmap.tagging 9
cakewalk.audio 9
mozilla.general 9
spline.eisfair 8
TOTAL: 85 727

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

GroupsCount
it.scienza.astronomia 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 15570
EMP (phn path) 1198
EMP (phn nph) 80
Too many newsgroups 63
User-issued cancel 43
Binary: non-binary distribution 27
Binary Payload 12
Too many newsgroups (low_xpost) 9
Binary Image: misplaced jpg 2
Binary: misplaced nfo 1
Binary: misplaced sfv 1
Too many newsgroups (meow) 1
TOTAL: 12 17007

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 264
newsfeed.neva.ru 10
news.fcku.it 6
TOTAL: 3 280
TOTAL: 1 280

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nntp.pasdenom.info 46110 2728 31766 1091 0 2502 5%23:58:11
2news.fcku.it 20558 1590 13517 2176 0 4 7%24:09:49
3weretis.net 2699 124 2473 0 0 8 4%24:09:21
4neva.ru 24170 27 16499 160 0 11 0%24:00:29
5eternal-september 19805 15 15554 0 0 10 0%24:05:51
6vc89.vc.panix.com 2839 0 0 0 0 21061 0%24:00:06
7news.netfront.net 2773 0 0 0 0 20922 0%24:00:06
8news.stack.nl 2859 0 0 0 0 21051 0%24:00:20
9news.albasani.net 2865 0 0 0 0 20286 0%24:00:28
10etla.org 0 0 0 0 0 18341 0%24:09:52
11news.mixmin.net 0 0 0 0 0 0 0%00:10:00
12news.killfile.org 2835 0 0 0 0 21059 0%24:00:28
TOTAL: 12 127513 4484 79809 3427 0 125255 3%264:45:01
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nntp.pasdenom.info9.4 MB2.1 MB11.6 MB0.1 KB/s3.1 KB23:58:11
2news.fcku.it4.3 MB6.3 MB10.6 MB0.1 KB/s2.9 KB24:09:49
3weretis.net222.3 KB0.0 KB222.3 KB0.0 KB/s1.8 KB24:09:21
4neva.ru119.6 KB581.3 KB700.9 KB0.0 KB/s3.7 KB24:00:29
5eternal-september65.2 KB0.0 KB65.2 KB0.0 KB/s4.3 KB24:05:51
6vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:06
7news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:06
8news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:20
9news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:28
10etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:09:52
11news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
12news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:28
TOTAL: 1214.2 MB9.0 MB23.2 MB0.0 KB/s3.0 KB264:45:01
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1dslb-092-073-008-096.092.073.pools.vodafone-ip.de 114 38.6 KB 2 0 000:54:00
TOTAL: 1 114 38.6 KB 2 0 004:14:39

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.092.073.pools.vodafone-ip.de 114 38.6 KB 2 0 000:54:00
2*.nielsenmedia.com 6 00.0 KB 0 0 002:50:37
3*.drr02.clwr.fl.frontiernet.net 1 00.0 KB 0 0 000:30:01
TOTAL: 3 121 38.6 KB 2 0 004:14:39

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
challenger.nielsenmedia.com 0.312 0.036 0.00002:50:37
dslb-092-073-008-096.092.073.pools.vodafone-ip.de 12.296 3.140 0.00000:54:00
47-192-187-101.drr02.clwr.fl.frontiernet.net 0.048 0.012 0.00000:30:01
TOTAL: 3 12.656 3.188 0.00004:14:39

Curious NNRP server explorers [Top 20]:

SystemConn
challenger.nielsenmedia.com 6
47-192-187-101.drr02.clwr.fl.frontiernet.net 1
TOTAL: 2 7

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1de 3100.0%
TOTAL: 1 3100.0%

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

NewsgroupCount
1de.talk.jokes 2
2de.rec.heimwerken 1
TOTAL: 2 3