Daily Usenet report for paganini.linuxd.org

Jan 6 00:00:03 -- Jan 7 00:00:02

Unknown entries from news log file:

First 14 / 14 lines (100.0%)

Jan 6 02:45:46 paganini overchan[17035]: input line too long, skipping
Jan 6 02:56:50 paganini overchan[17035]: input line too long, skipping
Jan 6 06:08:08 paganini overchan[17035]: input line too long, skipping
Jan 6 09:45:09 paganini overchan[17035]: input line too long, skipping
Jan 6 19:10:22 paganini overchan[17035]: input line too long, skipping
Jan 6 19:10:50 paganini overchan[17035]: input line too long, skipping
Jan 6 19:11:14 paganini overchan[17035]: input line too long, skipping
Jan 6 23:13:33 paganini overchan[17035]: input line too long, skipping
Jan 6 23:25:47 paganini overchan[17035]: input line too long, skipping
Jan 6 23:26:46 paganini overchan[17035]: input line too long, skipping
Jan 6 23:27:39 paganini overchan[17035]: input line too long, skipping
Jan 6 23:31:22 paganini overchan[17035]: input line too long, skipping
Jan 6 23:32:04 paganini overchan[17035]: input line too long, skipping
Jan 7 00:00:02 paganini overchan[6510]: 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 101277 58.0%12.6 MB 49.9%
inn 40768 23.3%8.8 MB 34.7%
innd 17937 10.3%2.3 MB 9.1%
nnrpd 14617 8.4%1.6 MB 6.3%
nocem 75 0.0%11.1 KB 0.0%
overchan 15 0.0%2.5 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 174690 100.0%25.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 139886 54.3%
Negative hits 77949 30.2%
Do not exist 39810 15.4%
Cache misses 106 0.0%
TOTAL: 4 257751 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.558 0.0%191 0.000 2.921 46.000
article cleanup00:00:00.243 0.0%39944 0.000 0.006 0.026
article logging00:00:00.603 0.0%40632 0.000 0.015 0.521
article parse00:00:01.102 0.0%86619 0.000 0.013 0.047
article write00:00:56.709 0.1%23106 0.097 2.454 23.508
artlog/artcncl00:00:00.002 0.0%170 0.000 0.012 0.125
data move00:00:00.172 0.0%251172 0.000 0.001 0.004
hisgrep/artcncl00:00:00.057 0.0%189 0.000 0.302 7.714
hishave/artcncl00:00:00.003 0.0%191 0.000 0.016 0.067
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:13.904 0.0%259371 0.016 0.054 0.440
history sync00:00:00.021 0.0%306 0.000 0.069 1.000
history write00:00:34.620 0.0%39942 0.031 0.867 3.520
hiswrite/artcncl00:00:00.000 0.0%2 0.000 0.000 0.000
idle23:56:02.075 99.7%252830 168.953 340.791 1252.000
nntp read00:00:02.102 0.0%263234 0.003 0.008 0.019
overview write00:00:22.969 0.0%23106 0.011 0.994 9.543
perl filter00:01:25.434 0.1%39942 1.174 2.139 5.351
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.516 0.0%69559 0.004 0.022 0.061
TOTAL: 24:00:21.62123:59:42.090100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.112 0.0%39103 0.000 0.003 0.012
article prepare00:00:00.001 0.0%20396 0.000 0.000 0.005
article read00:00:03.509 0.0%4547 0.000 0.772 22.871
backlog stats00:00:00.209 0.0%359887 0.000 0.001 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.457 0.0%196335 0.003 0.007 0.016
data write00:00:03.117 0.0%173822 0.009 0.018 0.030
idle23:43:01.919 99.3%359887 153.107 237.246 456.917
status file00:09:53.488 0.7%42292 8.273 14.033 25.349
TOTAL: 23:53:18.68723:53:03.812100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle08:01:43.939 99.7%29292 29.333 986.752600133.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.659 0.0%59540 0.000 0.011 0.982
readart00:00:23.082 0.1%18914 0.000 1.220 33.000
TOTAL: 08:03:22.72308:02:07.680 99.7%----

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 50189 20378 27819 1992 40%48:06:31
2newsfeed.neva.ru 148 86003 2341 69375 14287 2%72:10:56
3news.fcku.it 2 6621 300 6254 67 4%20:24:12
4nntp.pasdenom.info 3 31209 33 30779 397 0%21:00:19
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 182 174022 23052 134227 16743 13%161:41:58
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net66.7 MB0.0 KB13.9 MB80.6 MB 82%3.7 KB
2newsfeed.neva.ru6.4 MB17.4 KB129.2 MB135.6 MB 4%8.3 KB
3news.fcku.it1021.9 KB0.0 KB255.8 KB1.2 MB 79%3.5 KB
4nntp.pasdenom.info83.2 KB6.3 KB1.6 MB1.7 MB 4%4.0 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 574.2 MB23.6 KB144.9 MB219.1 MB 33%5.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jan 6 00:00:03 - 00:59:59 912 4.0% 0.253.4 MB 4.5% 0.95
Jan 6 01:00:00 - 01:59:59 787 3.4% 0.222.4 MB 3.2% 0.68
Jan 6 02:00:00 - 02:59:59 624 2.7% 0.171.9 MB 2.6% 0.55
Jan 6 03:00:00 - 03:59:59 524 2.3% 0.151.6 MB 2.1% 0.44
Jan 6 04:00:00 - 04:59:59 428 1.9% 0.121.4 MB 1.8% 0.39
Jan 6 05:00:00 - 05:59:59 566 2.5% 0.162.1 MB 2.9% 0.61
Jan 6 06:00:00 - 06:59:59 442 1.9% 0.121.6 MB 2.1% 0.45
Jan 6 07:00:00 - 07:59:59 553 2.4% 0.151.6 MB 2.2% 0.46
Jan 6 08:00:00 - 08:59:59 512 2.2% 0.141.7 MB 2.3% 0.48
Jan 6 09:00:00 - 09:59:59 751 3.3% 0.212.2 MB 3.0% 0.63
Jan 6 10:00:00 - 10:59:59 913 4.0% 0.252.6 MB 3.5% 0.73
Jan 6 11:00:00 - 11:59:59 1098 4.8% 0.303.1 MB 4.2% 0.88
Jan 6 12:00:00 - 12:59:59 1065 4.6% 0.303.4 MB 4.6% 0.96
Jan 6 13:00:00 - 13:59:59 1075 4.7% 0.303.4 MB 4.6% 0.96
Jan 6 14:00:00 - 14:59:59 1102 4.8% 0.313.0 MB 4.0% 0.85
Jan 6 15:00:00 - 15:59:59 1201 5.2% 0.333.4 MB 4.5% 0.95
Jan 6 16:00:00 - 16:59:59 1330 5.8% 0.374.2 MB 5.6% 1.18
Jan 6 17:00:00 - 17:59:59 1362 5.9% 0.384.0 MB 5.4% 1.14
Jan 6 18:00:00 - 18:59:59 1352 5.9% 0.385.3 MB 7.2% 1.51
Jan 6 19:00:00 - 19:59:59 1372 5.9% 0.384.0 MB 5.5% 1.15
Jan 6 20:00:00 - 20:59:59 1337 5.8% 0.374.3 MB 5.9% 1.23
Jan 6 21:00:00 - 21:59:59 1282 5.6% 0.364.4 MB 5.9% 1.24
Jan 6 22:00:00 - 22:59:59 1244 5.4% 0.355.2 MB 7.1% 1.49
Jan 6 23:00:00 - 23:59:59 1226 5.3% 0.343.9 MB 5.3% 1.11
Jan 7 00:00:00 - 00:00:02 17 0.1% 8.5060.2 KB 0.1% 30.11
TOTAL: 23:59:59 23075 100.0% 0.2774.0 MB 100.0% 0.88
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 14390 478 0 1 0 0 0 013911
2linuxd-out.news.weretis.net 2633 491 0 0 0 0 0 0 2142
3nntp.pasdenom.info 434 2 0 1 0 0 0 0 431
4news.fcku.it 83 0 0 0 0 0 0 0 83
TOTAL: 4 17540 971 0 2 0 0 0 016567

Unwanted newsgroups [Top 20]:

NewsgroupCount
lada.talk 271
lada.auto 63
ut.test 55
it-alt.sport.calcio.juventus 45
mpc.lists.freebsd.ports.bugs 32
gmane.linux.alsa.devel 27
lada.movies 24
uk.radio.amateur.moderated 21
mozilla.support.seamonkey 18
oesterreich.tratsch 17
alt.survival 17
gmane.comp.gis.openstreetmap 16
test.post 15
eagle.support.eng 15
gmane.comp.freedesktop.xorg.drivers.intel 14
mozilla.dev.platform 14
alt.windows7.general 13
alt.computer.workshop 12
mozilla.support.thunderbird 12
cakewalk.audio 11
TOTAL: 123 971

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 13819
EMP (phn path) 1400
EMP (phl) 441
EMP (phn nph) 86
Too many newsgroups 66
EMP (phr path) 34
User-issued cancel 13
Binary Payload 11
HTML post 8
Binary: non-binary distribution 6
Binary Image: misplaced jpg 1
TOTAL: 11 15885

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 647
newsfeed.neva.ru 34
news.fcku.it 1
TOTAL: 3 682
TOTAL: 1 682

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nntp.pasdenom.info 41471 1096 20867 1152 0 1315 2%24:03:07
2news.fcku.it 31981 1050 20130 1660 0 312 3%24:07:45
3weretis.net 2810 87 2614 0 0 145 3%24:07:17
4neva.ru 29884 46 20652 45 0 174 0%24:00:41
5eternal-september 25068 18 19706 0 0 158 0%24:00:27
6vc89.vc.panix.com 2837 0 0 0 0 25899 0%23:58:22
7news.killfile.org 2841 0 0 0 0 25904 0%23:58:29
8etla.org 0 0 0 0 0 23225 0%24:07:45
9news.netfront.net 2753 0 0 0 0 25742 0%23:58:21
10news.stack.nl 2854 0 0 0 0 25875 0%23:58:29
11news.mixmin.net 0 0 0 0 0 0 0%00:10:00
12news.albasani.net 2860 0 0 0 0 24836 0%23:58:13
TOTAL: 12 145359 2297 83969 2857 0 153585 1%264:28:56
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nntp.pasdenom.info5.5 MB2.2 MB7.7 MB0.1 KB/s3.5 KB24:03:07
2news.fcku.it3.1 MB6.0 MB9.2 MB0.1 KB/s3.5 KB24:07:45
3weretis.net145.6 KB0.0 KB145.6 KB0.0 KB/s1.7 KB24:07:17
4neva.ru144.6 KB172.3 KB316.9 KB0.0 KB/s3.5 KB24:00:41
5eternal-september79.5 KB0.0 KB79.5 KB0.0 KB/s4.4 KB24:00:27
6vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:22
7news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:29
8etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:07:45
9news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:21
10news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:29
11news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
12news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:13
TOTAL: 129.0 MB8.4 MB17.4 MB0.0 KB/s3.5 KB264:28:56
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mtrlpq2314w-lp140-05-70-55-56-27.dsl.bell.ca 310 263954.5 KB 163 0 001:01:52
2challenger.nielsenmedia.com 26 1128.2 KB 5 0 004:45:52
347-192-187-101.drr02.clwr.fl.frontiernet.net 20 823.0 KB 3 0 002:14:54
491.199.153.3 1 11.3 KB 1 0 000:00:09
TOTAL: 4 357 2831007.0 KB 172 0 008:03:20

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.dsl.bell.ca 310 263954.5 KB 163 0 001:01:52
2*.nielsenmedia.com 26 1128.2 KB 5 0 004:45:52
3*.drr02.clwr.fl.frontiernet.net 20 823.0 KB 3 0 002:14:54
4unresolved 2 11.3 KB 1 0 000:00:14
5*.094.216.pools.vodafone-ip.de 136 00.0 KB 0 0 000:00:26
TOTAL: 5 494 2831007.0 KB 172 0 008:03:20

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
challenger.nielsenmedia.com 1.812 0.104 0.00004:45:52
47-192-187-101.drr02.clwr.fl.frontiernet.net 1.432 0.072 0.00002:14:54
mtrlpq2314w-lp140-05-70-55-56-27.dsl.bell.ca 26.676 3.036 0.00001:01:52
dslb-094-216-025-116.094.216.pools.vodafone-ip.de 7.116 0.508 0.00000:00:26
91.199.153.3 0.456 0.244 0.00000:00:09
94.216.25.116 0.056 0.000 0.00000:00:05
TOTAL: 6 37.548 3.964 0.00008:03:20

Curious NNRP server explorers [Top 20]:

SystemConn
dslb-094-216-025-116.094.216.pools.vodafone-ip.de 136
94.216.25.116 1
TOTAL: 2 137

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1alt 90 31.8%
2uk 70 24.7%
3rec 48 17.0%
4comp 36 12.7%
5soc 17 6.0%
6misc 12 4.2%
7talk 6 2.1%
8sci 2 0.7%
9us 1 0.4%
10can 1 0.4%
TOTAL: 10 283100.0%

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

NewsgroupCount
1alt.christnet.christianlife 32
2rec.arts.tv 23
3uk.media.radio.archers 22
4comp.lang.awk 19
5soc.culture.usa 14
6uk.legal 14
7alt.home.repair 14
8alt.fan.rush-limbaugh 13
9comp.lang.python 13
10misc.survivalism 12
11rec.boats 12
12alt.society.liberalism 8
13talk.politics.misc 6
14alt.happy.valley 6
15uk.politics.misc 5
16rec.sport.tennis 5
17alt.global-warming 5
18uk.rec.motorcycles 5
19uk.d-i-y 4
20alt.politics.economics 4
21soc.culture.polish 3
22uk.people.silversurfers 3
23alt.atheism 3
24uk.rec.sheds 3
25uk.games.video.misc 3
26uk.net.news.moderation 2
27comp.lang.c++ 2
28uk.rec.cycling 2
29uk.sport.cricket 2
30rec.sport.golf 2
31rec.aviation.soaring 2
32uk.radio.amateur 1
33comp.lang.javascript 1
34can.politics 1
35alt.sports.football.pro.sd-chargers 1
36uk.tech.broadcast 1
37uk.media.tv.misc 1
38comp.sys.raspberry-pi 1
39us.military.army 1
40alt.comp.os.windows-10 1
41alt.abortion 1
42rec.arts.sf.written 1
43uk.sci.weather 1
44rec.crafts.metalworking 1
45alt.sex.telephone 1
46sci.military.naval 1
47rec.autos.sport.f1 1
48rec.outdoors.rv-travel 1
49sci.physics.relativity 1
50uk.comp.sys.mac 1
51alt.os.linux 1
TOTAL: 51 283