Daily Usenet report for paganini.linuxd.org

Jan 9 00:00:02 -- Jan 10 00:00:01

Unknown entries from news log file:

First 13 / 13 lines (100.0%)

Jan 9 02:04:41 paganini overchan[15368]: input line too long, skipping
Jan 9 03:55:03 paganini overchan[15368]: input line too long, skipping
Jan 9 03:59:14 paganini overchan[15368]: input line too long, skipping
Jan 9 03:59:53 paganini overchan[15368]: input line too long, skipping
Jan 9 04:00:47 paganini overchan[15368]: input line too long, skipping
Jan 9 04:03:45 paganini overchan[15368]: input line too long, skipping
Jan 9 04:04:58 paganini overchan[15368]: input line too long, skipping
Jan 9 04:06:21 paganini overchan[15368]: input line too long, skipping
Jan 9 04:07:59 paganini overchan[15368]: input line too long, skipping
Jan 9 09:44:16 paganini overchan[15368]: input line too long, skipping
Jan 9 22:10:51 paganini overchan[15368]: input line too long, skipping
Jan 9 22:13:08 paganini overchan[15368]: input line too long, skipping
Jan 10 00:00:01 paganini overchan[5884]: 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 100856 56.9%12.5 MB 48.3%
inn 43472 24.5%9.3 MB 36.1%
innd 19114 10.8%2.5 MB 9.8%
nnrpd 13612 7.7%1.5 MB 5.8%
nocem 73 0.0%12.1 KB 0.0%
overchan 13 0.0%2.4 KB 0.0%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 7 177141 100.0%25.9 MB100.0%

History cache:

ReasonCount%Count
Positive hits 149286 53.7%
Negative hits 85919 30.9%
Do not exist 42599 15.3%
Cache misses 96 0.0%
TOTAL: 4 277900 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.374 0.0%140 0.000 2.671 42.500
article cleanup00:00:00.237 0.0%42690 0.000 0.006 0.019
article logging00:00:00.373 0.0%43349 0.000 0.009 0.026
article parse00:00:01.091 0.0%95691 0.000 0.011 0.036
article write00:00:48.052 0.1%24373 0.107 1.972 25.612
artlog/artcncl00:00:00.000 0.0%140 0.000 0.000 0.000
data move00:00:00.165 0.0%284268 0.000 0.001 0.003
hisgrep/artcncl00:00:00.003 0.0%139 0.000 0.022 1.000
hishave/artcncl00:00:00.005 0.0%140 0.000 0.036 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:16.834 0.0%279109 0.017 0.060 1.498
history sync00:00:00.024 0.0%302 0.000 0.079 1.000
history write00:00:34.535 0.0%42688 0.027 0.809 4.833
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle23:56:01.525 99.7%283642 129.319 303.769 1153.435
nntp read00:00:02.301 0.0%294269 0.002 0.008 0.014
overview write00:00:26.368 0.0%24373 0.024 1.082 8.357
perl filter00:01:31.456 0.1%42688 1.047 2.142 5.543
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.551 0.0%73340 0.000 0.021 0.053
TOTAL: 24:00:23.37623:59:44.894100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.094 0.0%39686 0.000 0.002 0.014
article prepare00:00:00.003 0.0%20336 0.000 0.000 0.004
article read00:00:02.396 0.0%4480 0.000 0.535 15.935
backlog stats00:00:00.235 0.0%377431 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.615 0.0%206034 0.002 0.008 0.018
data write00:00:03.165 0.0%182309 0.004 0.017 0.033
idle23:42:01.141 99.3%377431 155.942 226.058 436.167
status file00:10:03.029 0.7%42142 8.397 14.309 34.428
TOTAL: 23:52:35.74423:52:11.678100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle05:43:25.952 99.6%28567 29.000 721.320600184.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.574 0.0%58071 0.000 0.010 0.333
readart00:00:13.868 0.1%19392 0.000 0.715 28.000
TOTAL: 05:44:46.52505:43:40.394 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 5 54051 21283 30330 2438 39%48:20:17
2newsfeed.neva.ru 149 89270 2752 71377 15141 3%71:15:15
3news.fcku.it 1 6492 192 6180 120 2%18:52:22
4nntp.pasdenom.info 2 38745 66 38116 563 0%24:17:17
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 182 188558 24293 146003 18262 12%162:45:11
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net70.4 MB0.0 KB15.7 MB86.1 MB 81%3.7 KB
2newsfeed.neva.ru10.8 MB3.8 KB130.9 MB141.7 MB 7%8.1 KB
3news.fcku.it459.0 KB0.0 KB2.6 MB3.0 MB 14%9.9 KB
4nntp.pasdenom.info158.3 KB0.0 KB2.9 MB3.1 MB 5%5.0 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 581.7 MB3.8 KB152.1 MB233.9 MB 34%5.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jan 9 00:00:02 - 00:59:59 881 3.6% 0.243.6 MB 4.4% 1.03
Jan 9 01:00:00 - 01:59:59 737 3.0% 0.202.8 MB 3.4% 0.79
Jan 9 02:00:00 - 02:59:59 589 2.4% 0.161.9 MB 2.3% 0.54
Jan 9 03:00:00 - 03:59:59 573 2.4% 0.162.1 MB 2.6% 0.61
Jan 9 04:00:00 - 04:59:59 925 3.8% 0.2610.8 MB 13.2% 3.08
Jan 9 05:00:00 - 05:59:59 451 1.9% 0.132.1 MB 2.5% 0.59
Jan 9 06:00:00 - 06:59:59 541 2.2% 0.151.8 MB 2.2% 0.52
Jan 9 07:00:00 - 07:59:59 553 2.3% 0.151.7 MB 2.1% 0.49
Jan 9 08:00:00 - 08:59:59 650 2.7% 0.182.0 MB 2.4% 0.56
Jan 9 09:00:00 - 09:59:59 984 4.0% 0.273.1 MB 3.8% 0.88
Jan 9 10:00:00 - 10:59:59 1003 4.1% 0.282.8 MB 3.4% 0.79
Jan 9 11:00:00 - 11:59:59 1043 4.3% 0.293.2 MB 3.9% 0.90
Jan 9 12:00:00 - 12:59:59 980 4.0% 0.272.7 MB 3.4% 0.78
Jan 9 13:00:00 - 13:59:59 1078 4.4% 0.302.9 MB 3.6% 0.84
Jan 9 14:00:00 - 14:59:59 1155 4.7% 0.323.1 MB 3.8% 0.90
Jan 9 15:00:00 - 15:59:59 1219 5.0% 0.343.1 MB 3.8% 0.89
Jan 9 16:00:00 - 16:59:59 1406 5.8% 0.394.1 MB 5.0% 1.17
Jan 9 17:00:00 - 17:59:59 1477 6.1% 0.414.4 MB 5.3% 1.24
Jan 9 18:00:00 - 18:59:59 1429 5.9% 0.403.7 MB 4.5% 1.06
Jan 9 19:00:00 - 19:59:59 1384 5.7% 0.383.8 MB 4.6% 1.08
Jan 9 20:00:00 - 20:59:59 1458 6.0% 0.414.2 MB 5.1% 1.19
Jan 9 21:00:00 - 21:59:59 1391 5.7% 0.393.7 MB 4.5% 1.05
Jan 9 22:00:00 - 22:59:59 1209 5.0% 0.343.3 MB 4.1% 0.95
Jan 9 23:00:00 - 23:59:59 1246 5.1% 0.354.8 MB 5.8% 1.36
Jan 10 00:00:00 - 00:00:01 7 0.0% 7.0038.8 KB 0.0% 38.76
TOTAL: 23:59:59 24369 100.0% 0.2881.9 MB 100.0% 0.97
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 15159 546 0 2 0 0 0 014611
2linuxd-out.news.weretis.net 3061 702 0 0 0 0 0 0 2359
3nntp.pasdenom.info 597 0 0 0 0 0 0 0 597
4news.fcku.it 146 0 0 0 0 0 0 0 146
TOTAL: 4 18963 1248 0 2 0 0 0 017713

Unwanted newsgroups [Top 20]:

NewsgroupCount
lada.talk 323
lada.auto 73
it-alt.sport.calcio.juventus 64
ut.test 61
it.annunci.varie 58
it.annunci.commerciali 57
it.annunci.immobiliari 56
gmane.comp.gis.openstreetmap.tagging 34
alt.survival 31
alt.windows7.general 28
gmane.linux.alsa.devel 26
lada.photo 19
uk.radio.amateur.moderated 18
gmane.comp.bacula.user 17
eagle.userchat.eng 16
mpc.lists.nanog 15
mozilla.test 14
mozilla.support.thunderbird 12
alt.pl.nieruchomosci.gielda 12
alt.2600 11
TOTAL: 129 1248

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 14522
EMP (phn path) 1394
EMP (phl) 512
EMP (phr path) 293
EMP (phn nph) 178
Too many newsgroups 103
User-issued cancel 21
Binary Payload 12
Binary: misplaced rar 8
Binary Image: misplaced jpg 3
HTML post 2
User-issued spam cancel 2
Binary: non-binary distribution 2
Binary: misplaced ync 1
Binary: misplaced txt 1
Binary: misplaced nzb 1
Too many newsgroups (low_xpost) 1
TOTAL: 17 17056

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 627
newsfeed.neva.ru 30
TOTAL: 2 657
TOTAL: 1 657

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nntp.pasdenom.info 43702 1231 21852 1110 0 772 2%23:53:19
2news.fcku.it 34268 941 21447 1513 0 265 2%23:50:01
3weretis.net 3310 102 3037 0 0 5 3%23:50:32
4neva.ru 30940 44 21341 105 0 118 0%23:56:27
5eternal-september 26924 33 20704 0 0 245 0%23:57:33
6news.albasani.net 2861 0 0 0 0 26110 0%23:59:49
7news.mixmin.net 0 0 0 0 0 0 0%00:10:00
8news.killfile.org 2837 0 0 0 0 27189 0%23:59:29
9news.netfront.net 2715 0 0 0 0 26988 0%23:59:41
10news.stack.nl 2852 0 0 0 0 27162 0%23:59:35
11vc89.vc.panix.com 2832 0 0 0 0 27187 0%23:59:36
12etla.org 0 0 0 0 0 24178 0%23:50:01
TOTAL: 12 153241 2351 88381 2728 0 160219 1%263:26:03
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nntp.pasdenom.info7.7 MB1.9 MB9.6 MB0.1 KB/s4.2 KB23:53:19
2news.fcku.it2.8 MB6.6 MB9.4 MB0.1 KB/s3.9 KB23:50:01
3neva.ru331.0 KB540.4 KB871.4 KB0.0 KB/s5.8 KB23:56:27
4weretis.net175.4 KB0.0 KB175.4 KB0.0 KB/s1.7 KB23:50:32
5eternal-september84.6 KB0.0 KB84.6 KB0.0 KB/s2.6 KB23:57:33
6news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:49
7news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
8news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:29
9news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:41
10news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:35
11vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:36
12etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:01
TOTAL: 1211.1 MB9.0 MB20.1 MB0.0 KB/s4.1 KB263:26:03
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mtrlpq2314w-lp140-05-70-55-56-27.dsl.bell.ca 290 287925.9 KB 167 0 001:03:30
2challenger.nielsenmedia.com 12 48.3 KB 3 0 001:34:01
347-192-187-101.drr02.clwr.fl.frontiernet.net 17 23.8 KB 2 0 003:06:43
491.199.153.3 1 11.3 KB 1 0 000:00:05
TOTAL: 4 320 294939.3 KB 173 0 005:44:44

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.dsl.bell.ca 290 287925.9 KB 167 0 001:03:30
2*.nielsenmedia.com 12 48.3 KB 3 0 001:34:01
3*.drr02.clwr.fl.frontiernet.net 17 23.8 KB 2 0 003:06:43
4unresolved 2 11.3 KB 1 0 000:00:05
5*.094.216.pools.vodafone-ip.de 117 00.0 KB 0 0 000:00:23
TOTAL: 5 438 294939.3 KB 173 0 005:44:44

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
47-192-187-101.drr02.clwr.fl.frontiernet.net 1.172 0.128 0.00003:06:43
challenger.nielsenmedia.com 0.692 0.044 0.00001:34:01
mtrlpq2314w-lp140-05-70-55-56-27.dsl.bell.ca 22.176 2.748 0.00001:03:30
dslb-094-216-025-116.094.216.pools.vodafone-ip.de 6.216 0.456 0.00000:00:23
91.199.153.3 0.444 0.148 0.00000:00:05
89.248.167.131 0.052 0.008 0.00000:00:00
TOTAL: 6 30.752 3.532 0.00005:44:44

Curious NNRP server explorers [Top 20]:

SystemConn
dslb-094-216-025-116.094.216.pools.vodafone-ip.de 117
89.248.167.131 1
TOTAL: 2 118

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1alt 145 49.3%
2uk 64 21.8%
3comp 35 11.9%
4rec 27 9.2%
5soc 9 3.1%
6talk 5 1.7%
7misc 4 1.4%
8fr 4 1.4%
9can 1 0.3%
TOTAL: 9 294100.0%

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

NewsgroupCount
1alt.politics.economics 29
2comp.lang.python 26
3alt.christnet.christianlife 24
4alt.home.repair 23
5alt.fan.rush-limbaugh 22
6alt.society.liberalism 15
7uk.media.radio.archers 15
8uk.legal 14
9uk.d-i-y 12
10rec.boats 12
11comp.lang.awk 6
12alt.folklore.computers 6
13alt.atheism 6
14uk.rec.cycling 5
15rec.arts.tv 5
16uk.politics.misc 5
17alt.happy.valley 4
18rec.arts.sf.written 4
19soc.culture.usa 4
20misc.survivalism 4
21alt.california 3
22rec.crafts.metalworking 3
23fr.rec.photo 3
24uk.games.video.misc 3
25talk.politics.misc 3
26soc.culture.polish 3
27uk.rec.sheds 2
28alt.sports.football.pro.sd-chargers 2
29talk.politics.guns 2
30comp.sys.mac.system 2
31uk.net.news.moderation 1
32alt.russian.z1 1
33rec.arts.comics.strips 1
34comp.os.vms 1
35rec.arts.tv.uk.coronation-st 1
36uk.rec.motorcycles 1
37alt.usage.english 1
38alt.conspiracy 1
39can.politics 1
40alt.astronomy 1
41alt.sex.phone 1
42alt.autos.toyota 1
43uk.people.silversurfers 1
44uk.sport.cricket 1
45alt.usenet.kooks 1
46alt.religion.christian 1
47uk.radio.amateur 1
48alt.stupidity 1
49soc.retirement 1
50rec.pets.cats.community 1
51uk.sci.weather 1
52fr.soc.politique 1
53alt.comp.freeware 1
54uk.comp.sys.mac 1
55soc.genealogy.britain 1
56alt.os.linux 1
57uk.rec.driving 1
TOTAL: 57 294