Daily Usenet report for paganini.linuxd.org

May 16 00:00:02 -- May 17 00:00:02

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

May 16 08:00:50 paganini overchan[7954]: input line too long, skipping
May 16 09:43:23 paganini overchan[7954]: input line too long, skipping
May 16 11:53:24 paganini overchan[7954]: input line too long, skipping
May 17 00:00:02 paganini overchan[22414]: 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 101534 48.2%12.5 MB 41.5%
nnrpd 66022 31.3%7.6 MB 25.3%
inn 33052 15.7%8.3 MB 27.6%
innd 10059 4.8%1.7 MB 5.6%
nocem 98 0.0%15.8 KB 0.1%
overchan 5 0.0%0.7 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 210771 100.0%30.1 MB100.0%

History cache:

ReasonCount%Count
Positive hits 124393 57.6%
Negative hits 59425 27.5%
Do not exist 32238 14.9%
Cache misses 75 0.0%
TOTAL: 4 216131 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.906 0.0%213 0.000 4.254 32.933
article cleanup00:00:00.219 0.0%32453 0.000 0.007 0.023
article logging00:00:00.279 0.0%32798 0.000 0.009 0.031
article parse00:00:00.636 0.0%47983 0.000 0.013 0.032
article write00:00:58.465 0.1%23601 0.120 2.477 30.355
artlog/artcncl00:00:00.002 0.0%197 0.000 0.010 0.500
data move00:00:00.131 0.0%226413 0.000 0.001 0.002
hisgrep/artcncl00:00:00.004 0.0%212 0.000 0.019 0.333
hishave/artcncl00:00:00.003 0.0%213 0.000 0.014 0.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:19.225 0.0%217371 0.015 0.088 5.720
history sync00:00:00.417 0.0%396 0.000 1.053 105.333
history write00:00:38.622 0.0%32447 0.034 1.190 4.394
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle23:56:43.038 99.7%222558 205.318 387.328 1071.934
nntp read00:00:01.808 0.0%229166 0.002 0.008 0.065
overview write00:00:26.019 0.0%23601 0.029 1.102 4.617
perl filter00:01:00.017 0.1%32447 0.971 1.850 5.567
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.683 0.0%71131 0.002 0.024 0.130
TOTAL: 24:01:01.42624:00:11.474 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.084 0.0%38248 0.000 0.002 0.010
article prepare00:00:00.004 0.0%33144 0.000 0.000 0.008
article read00:00:02.099 0.0%6412 0.000 0.327 20.061
backlog stats00:00:00.248 0.0%363931 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.670 0.0%201328 0.002 0.008 0.016
data write00:00:03.328 0.0%177835 0.010 0.019 0.042
idle23:42:28.772 99.2%363931 141.138 234.519 425.967
status file00:10:34.932 0.7%42414 8.889 14.970 57.338
TOTAL: 23:53:34.06323:53:11.137100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle05:40:39.510 99.9%66878 36.568 305.624600186.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.143 0.0%136041 0.000 0.008 0.500
readart00:00:00.027 0.0%14 0.000 1.929 8.000
TOTAL: 05:41:06.48005:40:40.680 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 49280 19620 28381 1279 39%48:21:37
2newsfeed.neva.ru 147 75482 2627 65694 7161 3%72:02:43
3news.fcku.it 3 10097 1444 8230 423 14%24:57:34
4nntp.pasdenom.info 3 23042 28 22994 20 0%23:52:17
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 182 157901 23719 125299 8883 15%169:14:11
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net64.5 MB2.0 KB4.9 MB69.4 MB 92%3.4 KB
2newsfeed.neva.ru7.7 MB3.9 KB30.1 MB37.7 MB 20%3.9 KB
3news.fcku.it4.1 MB0.0 KB2.6 MB6.7 MB 60%3.7 KB
4nntp.pasdenom.info97.0 KB8.2 KB244.6 KB349.8 KB 27%7.3 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 576.4 MB14.1 KB37.8 MB114.2 MB 66%3.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 16 00:00:02 - 00:59:59 866 3.7% 0.242.7 MB 3.6% 0.77
May 16 01:00:00 - 01:59:59 789 3.3% 0.223.1 MB 4.1% 0.88
May 16 02:00:00 - 02:59:59 607 2.6% 0.172.8 MB 3.7% 0.79
May 16 03:00:00 - 03:59:59 556 2.4% 0.152.2 MB 2.8% 0.61
May 16 04:00:00 - 04:59:59 510 2.2% 0.141.9 MB 2.5% 0.54
May 16 05:00:00 - 05:59:59 389 1.6% 0.111.4 MB 1.8% 0.39
May 16 06:00:00 - 06:59:59 695 2.9% 0.192.9 MB 3.9% 0.84
May 16 07:00:00 - 07:59:59 677 2.9% 0.192.5 MB 3.3% 0.70
May 16 08:00:00 - 08:59:59 770 3.3% 0.212.3 MB 3.0% 0.65
May 16 09:00:00 - 09:59:59 1026 4.3% 0.283.3 MB 4.4% 0.94
May 16 10:00:00 - 10:59:59 960 4.1% 0.272.7 MB 3.6% 0.78
May 16 11:00:00 - 11:59:59 1064 4.5% 0.302.8 MB 3.7% 0.80
May 16 12:00:00 - 12:59:59 920 3.9% 0.262.5 MB 3.3% 0.71
May 16 13:00:00 - 13:59:59 986 4.2% 0.273.2 MB 4.3% 0.92
May 16 14:00:00 - 14:59:59 1239 5.2% 0.344.0 MB 5.3% 1.15
May 16 15:00:00 - 15:59:59 1099 4.7% 0.313.0 MB 4.0% 0.85
May 16 16:00:00 - 16:59:59 1306 5.5% 0.363.5 MB 4.6% 1.00
May 16 17:00:00 - 17:59:59 1512 6.4% 0.424.8 MB 6.3% 1.36
May 16 18:00:00 - 18:59:59 1316 5.6% 0.374.4 MB 5.8% 1.24
May 16 19:00:00 - 19:59:59 1337 5.7% 0.374.1 MB 5.5% 1.18
May 16 20:00:00 - 20:59:59 1271 5.4% 0.353.6 MB 4.7% 1.02
May 16 21:00:00 - 21:59:59 1349 5.7% 0.374.2 MB 5.5% 1.19
May 16 22:00:00 - 22:59:59 1252 5.3% 0.354.6 MB 6.1% 1.30
May 16 23:00:00 - 23:59:59 1072 4.5% 0.303.0 MB 4.0% 0.85
May 17 00:00:00 - 00:00:02 33 0.1% 16.50139.7 KB 0.2% 69.83
TOTAL: 24:00:00 23601 100.0% 0.2775.6 MB 100.0% 0.90
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 7206 318 0 2 0 0 0 0 6886
2linuxd-out.news.weretis.net 1598 502 0 1 0 0 0 0 1095
3news.fcku.it 414 28 0 0 0 0 0 0 386
4nntp.pasdenom.info 18 2 0 3 0 0 0 0 13
TOTAL: 4 9236 850 0 6 0 0 0 0 8380

Unwanted newsgroups [Top 20]:

NewsgroupCount
ut.test 115
it-alt.sport.calcio.juventus 57
mozilla.dev.security.policy 51
gmane.linux.alsa.devel 51
mpc.lists.freebsd.ports.bugs 40
lada.auto 39
lada.talk 35
alt.windows7.general 32
it.annunci.varie 31
it.annunci.immobiliari 30
it.annunci.commerciali 30
lada.photo 28
mpc.lists.nanog 26
gmane.comp.gis.openstreetmap.tagging 21
mozilla.support.seamonkey 16
mozilla.support.thunderbird 15
mozilla.dev.platform 14
alt.survival 11
lada.commerce 11
eagle.support.eng 10
TOTAL: 110 850

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 7001
EMP (phn path) 753
EMP (phl) 71
EMP (phn nph) 69
Too many newsgroups 56
User-issued cancel 26
Binary Payload 24
MID-Bot 21
Binary: non-binary distribution 3
Too many newsgroups (meow) 3
Binary Image: misplaced jpg 3
Binary: misplaced par2 2
HTML post 2
User-issued spam cancel 1
Binary: misplaced sfv 1
TOTAL: 15 8036

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nntp.pasdenom.info 40611 3229 19465 896 0 495 7%24:01:48
2news.fcku.it 27990 1611 18735 1985 0 97 5%24:05:43
3weretis.net 4371 281 3747 0 0 25 6%24:06:27
4neva.ru 30312 84 20646 266 0 67 0%24:02:59
5eternal-september 26668 15 20338 1 0 29 0%24:00:27
6news.netfront.net 2764 0 0 0 0 26344 0%24:00:13
7etla.org 0 0 0 0 0 23761 0%24:09:33
8vc89.vc.panix.com 2842 0 0 0 0 26454 0%23:59:58
9news.killfile.org 2848 0 0 0 0 26465 0%24:00:11
10news.mixmin.net 0 0 0 0 0 0 0%00:10:00
11news.albasani.net 2866 0 0 0 0 25747 0%23:59:48
12news.stack.nl 2860 0 0 0 0 26416 0%23:59:47
TOTAL: 12 144132 5220 82931 3148 0 155900 3%264:36:54
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nntp.pasdenom.info11.2 MB2.0 MB13.2 MB0.2 KB/s3.3 KB24:01:48
2news.fcku.it4.5 MB6.4 MB10.9 MB0.1 KB/s3.1 KB24:05:43
3weretis.net556.9 KB0.0 KB556.9 KB0.0 KB/s2.0 KB24:06:27
4neva.ru148.2 KB811.3 KB959.5 KB0.0 KB/s2.7 KB24:02:59
5eternal-september67.7 KB2.7 KB70.4 KB0.0 KB/s4.4 KB24:00:27
6news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:13
7etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:09:33
8vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:58
9news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:11
10news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
11news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:48
12news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:47
TOTAL: 1216.5 MB9.2 MB25.7 MB0.0 KB/s3.1 KB264:36:54
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1challenger.nielsenmedia.com 13 1021.9 KB 4 0 004:14:26
2dslb-088-067-084-037.088.067.pools.vodafone-ip.de 119 415.5 KB 4 0 000:56:37
TOTAL: 2 132 1437.3 KB 8 0 005:41:06

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.nielsenmedia.com 13 1021.9 KB 4 0 004:14:26
2*.088.067.pools.vodafone-ip.de 119 415.5 KB 4 0 000:56:37
3*.drr02.clwr.fl.frontiernet.net 1 00.0 KB 0 0 000:30:01
TOTAL: 3 133 1437.3 KB 8 0 005:41:06

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
challenger.nielsenmedia.com 0.908 0.068 0.00004:14:26
dslb-088-067-084-037.088.067.pools.vodafone-ip.de 12.624 3.164 0.00000:56:37
47-192-187-101.drr02.clwr.fl.frontiernet.net 0.048 0.008 0.00000:30:01
TOTAL: 3 13.580 3.240 0.00005:41:06

Curious NNRP server explorers [Top 20]:

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

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1comp 10 71.4%
2de 2 14.3%
3control 2 14.3%
TOTAL: 3 14100.0%

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

NewsgroupCount
1comp.lang.awk 10
2control.cancel 2
3de.rec.geocaching 1
4de.talk.tagesgeschehen 1
TOTAL: 4 14