Daily Usenet report for paganini.linuxd.org

Jan 16 00:00:02 -- Jan 17 00:00:01

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Jan 16 14:28:13 paganini overchan[9406]: input line too long, skipping
Jan 16 15:48:53 paganini overchan[9406]: input line too long, skipping
Jan 16 20:11:24 paganini overchan[9406]: input line too long, skipping
Jan 16 22:46:29 paganini overchan[9406]: input line too long, skipping
Jan 17 00:00:01 paganini overchan[32738]: 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 100702 66.0%12.5 MB 53.8%
inn 37345 24.5%8.6 MB 37.3%
innd 13883 9.1%2.0 MB 8.5%
nnrpd 583 0.4%82.1 KB 0.3%
nocem 56 0.0%8.8 KB 0.0%
overchan 6 0.0%0.9 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 152576 100.0%23.1 MB100.0%

History cache:

ReasonCount%Count
Positive hits 128714 55.2%
Negative hits 67677 29.0%
Do not exist 36553 15.7%
Cache misses 80 0.0%
TOTAL: 4 233024 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.700 0.0%148 0.000 4.730 22.533
article cleanup00:00:00.212 0.0%36637 0.000 0.006 0.024
article logging00:00:00.315 0.0%37210 0.000 0.008 0.028
article parse00:00:00.846 0.0%70523 0.000 0.012 0.036
article write00:00:58.420 0.1%23667 0.282 2.468 22.026
artlog/artcncl00:00:00.000 0.0%145 0.000 0.000 0.000
data move00:00:00.138 0.0%244253 0.000 0.001 0.002
hisgrep/artcncl00:00:00.001 0.0%147 0.000 0.007 0.053
hishave/artcncl00:00:00.002 0.0%148 0.000 0.014 0.100
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:12.055 0.0%233615 0.015 0.052 0.550
history sync00:00:00.022 0.0%305 0.000 0.072 1.000
history write00:00:33.947 0.0%36637 0.034 0.927 4.245
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle23:56:13.338 99.7%241004 221.438 357.560 1407.519
nntp read00:00:01.888 0.0%249753 0.002 0.008 0.022
overview write00:00:26.031 0.0%23667 0.032 1.100 13.300
perl filter00:01:13.808 0.1%36637 1.053 2.015 7.939
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.512 0.0%71279 0.000 0.021 0.057
TOTAL: 24:00:22.59923:59:43.235100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.102 0.0%39012 0.000 0.003 0.015
article prepare00:00:00.003 0.0%24436 0.000 0.000 0.011
article read00:00:02.236 0.0%5168 0.000 0.433 20.469
backlog stats00:00:00.231 0.0%369207 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.535 0.0%202467 0.002 0.008 0.014
data write00:00:03.048 0.0%178925 0.008 0.017 0.031
idle23:43:01.764 99.3%369207 152.353 231.257 452.837
status file00:09:43.046 0.7%42082 8.507 13.855 37.558
TOTAL: 23:53:12.91023:52:51.965100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.010 0.0%977 0.000 0.010 0.143
idle02:39:56.847 99.5%1520 29.000 6313.715600157.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:20.297 0.2%140299 0.000 0.145 0.250
TOTAL: 02:40:42.40102:40:17.154 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 6 51032 20609 28582 1841 40%48:29:09
2newsfeed.neva.ru 149 82553 2730 68857 10966 3%72:05:12
3news.fcku.it 1 6987 294 6530 163 4%21:42:53
4nntp.pasdenom.info 4 18792 39 18747 6 0%21:23:46
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 185 159364 23672 122716 12976 14%163:41:00
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net62.6 MB0.0 KB10.2 MB72.8 MB 86%3.3 KB
2newsfeed.neva.ru7.1 MB0.0 KB83.8 MB90.9 MB 7%6.8 KB
3news.fcku.it796.3 KB0.0 KB1.5 MB2.3 MB 33%5.2 KB
4nntp.pasdenom.info187.1 KB0.0 KB85.2 KB272.2 KB 68%6.0 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 570.7 MB0.0 KB95.6 MB166.3 MB 42%4.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jan 16 00:00:02 - 00:59:59 748 3.2% 0.212.3 MB 3.2% 0.64
Jan 16 01:00:00 - 01:59:59 698 3.0% 0.192.8 MB 4.0% 0.79
Jan 16 02:00:00 - 02:59:59 668 2.8% 0.192.1 MB 3.0% 0.60
Jan 16 03:00:00 - 03:59:59 520 2.2% 0.141.6 MB 2.2% 0.44
Jan 16 04:00:00 - 04:59:59 521 2.2% 0.141.7 MB 2.5% 0.50
Jan 16 05:00:00 - 05:59:59 411 1.7% 0.111.3 MB 1.8% 0.37
Jan 16 06:00:00 - 06:59:59 552 2.3% 0.152.3 MB 3.2% 0.65
Jan 16 07:00:00 - 07:59:59 594 2.5% 0.172.9 MB 4.1% 0.82
Jan 16 08:00:00 - 08:59:59 687 2.9% 0.191.8 MB 2.6% 0.52
Jan 16 09:00:00 - 09:59:59 988 4.2% 0.272.8 MB 3.9% 0.78
Jan 16 10:00:00 - 10:59:59 1084 4.6% 0.303.0 MB 4.3% 0.85
Jan 16 11:00:00 - 11:59:59 1085 4.6% 0.302.8 MB 4.0% 0.79
Jan 16 12:00:00 - 12:59:59 1159 4.9% 0.323.2 MB 4.6% 0.92
Jan 16 13:00:00 - 13:59:59 1124 4.8% 0.312.9 MB 4.0% 0.81
Jan 16 14:00:00 - 14:59:59 1126 4.8% 0.313.0 MB 4.3% 0.86
Jan 16 15:00:00 - 15:59:59 1138 4.8% 0.322.9 MB 4.2% 0.84
Jan 16 16:00:00 - 16:59:59 1330 5.6% 0.374.3 MB 6.0% 1.21
Jan 16 17:00:00 - 17:59:59 1503 6.4% 0.423.9 MB 5.6% 1.12
Jan 16 18:00:00 - 18:59:59 1446 6.1% 0.405.2 MB 7.4% 1.49
Jan 16 19:00:00 - 19:59:59 1381 5.8% 0.383.6 MB 5.1% 1.03
Jan 16 20:00:00 - 20:59:59 1303 5.5% 0.364.2 MB 5.9% 1.18
Jan 16 21:00:00 - 21:59:59 1257 5.3% 0.353.4 MB 4.9% 0.98
Jan 16 22:00:00 - 22:59:59 1267 5.4% 0.353.6 MB 5.1% 1.02
Jan 16 23:00:00 - 23:59:59 1061 4.5% 0.293.0 MB 4.2% 0.84
Jan 17 00:00:00 - 00:00:01 4 0.0% 4.0015.3 KB 0.0% 15.27
TOTAL: 23:59:59 23655 100.0% 0.2770.5 MB 100.0% 0.84
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 10968 484 0 0 0 0 0 010484
2linuxd-out.news.weretis.net 2403 655 0 0 0 0 0 0 1748
3news.fcku.it 162 0 0 0 0 0 0 0 162
4nntp.pasdenom.info 12 0 0 0 0 0 0 0 12
TOTAL: 4 13545 1139 0 0 0 0 0 012406

Unwanted newsgroups [Top 20]:

NewsgroupCount
lada.talk 172
lada.auto 127
ut.test 70
it-alt.sport.calcio.juventus 67
it.annunci.immobiliari 63
it.annunci.varie 62
it.annunci.commerciali 61
spline.eisfair.dev 32
alt.windows7.general 30
mozilla.support.seamonkey 28
gmane.linux.alsa.devel 27
mpc.lists.nanog 23
mpc.lists.freebsd.ports.bugs 22
lada.commerce 22
alt.survival 18
gmane.comp.gis.openstreetmap.tagging 16
alt.politics 15
mozilla.support.firefox 15
mozilla.dev.platform 14
mozilla.support.thunderbird 13
TOTAL: 103 1139

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 9935
EMP (phn path) 1147
EMP (phl) 498
EMP (phn nph) 103
Too many newsgroups 70
HTML post 21
Binary Payload 20
User-issued cancel 15
Binary: non-binary distribution 10
User-issued spam cancel 7
Too many newsgroups (low_xpost) 2
Binary Image: misplaced jpg 1
Binary: misplaced par2 1
TOTAL: 13 11830

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 548
newsfeed.neva.ru 26
news.fcku.it 2
TOTAL: 3 576
TOTAL: 1 576

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.fcku.it 32366 1873 19115 2416 0 134 5%23:59:45
2nntp.pasdenom.info 42078 1320 21889 319 0 758 3%23:57:39
3neva.ru 30873 64 20776 67 0 122 0%23:59:43
4eternal-september 25692 43 20088 0 0 241 0%23:59:45
5weretis.net 3266 40 3021 0 0 218 1%23:59:46
6vc89.vc.panix.com 2807 0 0 0 0 26461 0%23:59:45
7etla.org 0 0 0 0 0 23655 0%23:59:46
8news.mixmin.net 0 0 0 0 0 0 0%00:10:00
9news.albasani.net 2845 0 0 0 0 25505 0%23:59:45
10news.killfile.org 2849 0 0 0 0 26503 0%23:59:45
11news.netfront.net 2733 0 0 0 0 26296 0%23:59:44
12news.stack.nl 2831 0 0 0 0 26450 0%23:59:45
TOTAL: 12 148340 3340 84889 2802 0 156343 2%264:05:08
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.fcku.it5.2 MB7.6 MB12.8 MB0.2 KB/s3.1 KB23:59:45
2nntp.pasdenom.info4.9 MB736.8 KB5.7 MB0.1 KB/s3.5 KB23:57:39
3neva.ru305.9 KB460.6 KB766.5 KB0.0 KB/s5.9 KB23:59:43
4eternal-september181.9 KB0.0 KB181.9 KB0.0 KB/s4.2 KB23:59:45
5weretis.net52.2 KB0.0 KB52.2 KB0.0 KB/s1.3 KB23:59:46
6vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:45
7etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:46
8news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
9news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:45
10news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:45
11news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:44
12news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:45
TOTAL: 1210.7 MB8.8 MB19.5 MB0.0 KB/s3.2 KB264:05:08
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1240.132.151.153.ap.dti.ne.jp 12 00.0 KB 0 0 000:09:32
2dslb-094-216-025-116.094.216.pools.vodafone-ip.de 123 00.0 KB 0 0 000:00:25
347-192-187-101.drr02.clwr.fl.frontiernet.net 9 00.0 KB 0 0 002:30:43
TOTAL: 3 144 00.0 KB 0 0 002:40:41

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.094.216.pools.vodafone-ip.de 123 00.0 KB 0 0 000:00:25
2*.132.151.153.ap.dti.ne.jp 12 00.0 KB 0 0 000:09:32
3*.drr02.clwr.fl.frontiernet.net 9 00.0 KB 0 0 002:30:43
TOTAL: 3 144 00.0 KB 0 0 002:40:41

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
47-192-187-101.drr02.clwr.fl.frontiernet.net 0.656 0.032 0.00002:30:43
240.132.151.153.ap.dti.ne.jp 0.784 0.136 0.00000:09:32
dslb-094-216-025-116.094.216.pools.vodafone-ip.de 6.592 0.480 0.00000:00:25
TOTAL: 3 8.032 0.648 0.00002:40:41

NNRP client timeouts [Top 20]:

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