Daily Usenet report for paganini.linuxd.org

Oct 30 00:00:02 -- Oct 31 00:00:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Oct 31 00:00:02 paganini overchan[27771]: 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 105671 46.2%13.0 MB 43.1%
nnrpd 71923 31.4%7.5 MB 24.8%
inn 34903 15.3%7.5 MB 24.8%
innd 16271 7.1%2.2 MB 7.3%
nocem 40 0.0%6.6 KB 0.0%
overchan 2 0.0%0.4 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 228811 100.0%30.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 117566 52.3%
Negative hits 72664 32.3%
Do not exist 34333 15.3%
Cache misses 96 0.0%
TOTAL: 4 224659 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.447 0.0%153 0.000 2.922 25.000
article cleanup00:00:00.262 0.0%34396 0.000 0.008 0.026
article logging00:00:00.289 0.0%34761 0.000 0.008 0.039
article parse00:00:00.951 0.0%80655 0.000 0.012 0.034
article write00:00:48.234 0.1%19509 0.100 2.472 48.259
artlog/artcncl00:00:00.001 0.0%135 0.000 0.007 0.111
data move00:00:00.161 0.0%244809 0.000 0.001 0.005
hisgrep/artcncl00:00:00.008 0.0%152 0.000 0.053 0.533
hishave/artcncl00:00:00.000 0.0%153 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:13.524 0.0%226053 0.013 0.060 0.874
history sync00:00:00.020 0.0%414 0.000 0.048 0.667
history write00:00:30.353 0.0%34395 0.033 0.882 4.061
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle24:57:16.243 99.7%242371 192.258 370.656 1316.673
nntp read00:00:01.941 0.0%250957 0.003 0.008 0.027
overview write00:00:18.535 0.0%19509 0.000 0.950 4.978
perl filter00:01:23.317 0.1%34395 1.083 2.422 7.483
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.228 0.0%58682 0.000 0.021 0.056
TOTAL: 25:01:14.06025:00:35.514100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.085 0.0%34506 0.000 0.002 0.014
article prepare00:00:00.002 0.0%14264 0.000 0.000 0.013
article read00:00:02.308 0.0%3225 0.000 0.716 19.611
backlog stats00:00:00.196 0.0%321043 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.300 0.0%172733 0.001 0.008 0.016
data write00:00:02.634 0.0%153361 0.008 0.017 0.035
idle24:43:49.691 99.3%321043 171.246 277.314 541.893
status file00:09:58.668 0.7%44174 8.302 13.552 28.685
TOTAL: 24:54:18.34524:53:54.884100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle07:21:55.540 99.8%98704 32.000 268.637602450.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.829 0.0%197753 0.000 0.009 0.250
readart00:00:16.982 0.1%21320 0.000 0.797 7.677
TOTAL: 07:23:00.04907:22:14.351 99.8%----

Control commands to INND:

CommandNumber
L 1
flush 6
flushlogs 2
go 2
logmode 2
mode 154
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 177

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1linuxd-out.news.weretis.net 5 43064 17166 24216 1682 39%49:43:54
2newsfeed.neva.ru 157 70818 1892 56143 12783 2%74:54:28
3news.fcku.it 1 8759 282 8275 202 3%28:16:33
4nntp.pasdenom.info 3 9996 48 9789 159 0%08:27:49
5localhost 26 0 0 0 0 0%00:00:00
TOTAL: 5 192 132637 19388 98423 14826 14%161:22:44
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net52.5 MB0.0 KB11.4 MB63.9 MB 82%3.5 KB
2newsfeed.neva.ru5.5 MB0.0 KB108.2 MB113.6 MB 4%7.9 KB
3news.fcku.it2.2 MB0.0 KB3.7 MB5.9 MB 36%12.5 KB
4nntp.pasdenom.info101.4 KB0.0 KB732.3 KB833.8 KB 12%4.0 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 560.2 MB0.0 KB124.0 MB184.3 MB 32%5.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 30 00:00:02 - 00:59:59 857 4.4% 0.242.5 MB 4.2% 0.73
Oct 30 01:00:00 - 01:59:59 685 3.5% 0.192.4 MB 4.1% 0.69
Oct 30 02:00:00 - 02:59:59 1053 5.4% 0.293.9 MB 6.5% 1.11
Oct 30 03:00:00 - 03:59:59 375 1.9% 0.101.1 MB 1.8% 0.32
Oct 30 04:00:00 - 04:59:59 369 1.9% 0.101.4 MB 2.3% 0.39
Oct 30 05:00:00 - 05:59:59 318 1.6% 0.091.2 MB 2.1% 0.36
Oct 30 06:00:00 - 06:59:59 344 1.8% 0.10997.1 KB 1.6% 0.28
Oct 30 07:00:00 - 07:59:59 384 2.0% 0.111018.9 KB 1.7% 0.28
Oct 30 08:00:00 - 08:59:59 545 2.8% 0.151.5 MB 2.5% 0.42
Oct 30 09:00:00 - 09:59:59 770 3.9% 0.212.1 MB 3.5% 0.60
Oct 30 10:00:00 - 10:59:59 914 4.7% 0.252.4 MB 3.9% 0.67
Oct 30 11:00:00 - 11:59:59 991 5.1% 0.282.7 MB 4.5% 0.77
Oct 30 12:00:00 - 12:59:59 816 4.2% 0.232.2 MB 3.7% 0.64
Oct 30 13:00:00 - 13:59:59 882 4.5% 0.242.3 MB 3.8% 0.66
Oct 30 14:00:00 - 14:59:59 871 4.5% 0.242.7 MB 4.4% 0.76
Oct 30 15:00:00 - 15:59:59 820 4.2% 0.232.2 MB 3.7% 0.63
Oct 30 16:00:00 - 16:59:59 1056 5.4% 0.293.1 MB 5.2% 0.89
Oct 30 17:00:00 - 17:59:59 1134 5.8% 0.323.7 MB 6.2% 1.06
Oct 30 18:00:00 - 18:59:59 1224 6.3% 0.343.8 MB 6.3% 1.08
Oct 30 19:00:00 - 19:59:59 1088 5.6% 0.303.0 MB 5.0% 0.85
Oct 30 20:00:00 - 20:59:59 1110 5.7% 0.316.2 MB 10.3% 1.77
Oct 30 21:00:00 - 21:59:59 986 5.1% 0.272.6 MB 4.2% 0.73
Oct 30 22:00:00 - 22:59:59 989 5.1% 0.272.6 MB 4.3% 0.73
Oct 30 23:00:00 - 23:59:59 913 4.7% 0.252.5 MB 4.2% 0.72
Oct 31 00:00:00 - 00:00:02 3 0.0% 1.5010.0 KB 0.0% 4.99
TOTAL: 24:00:00 19497 100.0% 0.2360.2 MB 100.0% 0.71
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 12850 366 0 0 0 0 0 012484
2linuxd-out.news.weretis.net 2026 366 0 0 0 0 0 0 1660
3nntp.pasdenom.info 205 1 0 1 0 0 0 0 203
4news.fcku.it 190 0 0 0 0 0 0 0 190
TOTAL: 4 15271 733 0 1 0 0 0 014537

Unwanted newsgroups [Top 20]:

NewsgroupCount
ut.test 148
lada.talk 86
it-alt.sport.calcio.juventus 67
spline.eisfair 39
lada.auto 36
mozilla.support.seamonkey 34
alt.windows7.general 23
alt.survival 21
fido.linux 21
mozilla.dev.security.policy 20
mpc.lists.nanog 17
lada.commerce 15
alt.pub.cafe-bob 12
spline.fli4l.dev 11
mozilla.support.firefox 10
oesterreich.tratsch 10
spline.fli4l 8
hk.politics 8
cakewalk.audio 8
mpc.lists.freebsd.x11 7
TOTAL: 93 733

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 10980
EMP (phn path) 2225
EMP (phl) 643
EMP (phn nph) 184
Too many newsgroups 63
User-issued cancel 25
Binary Payload 24
Binary: non-binary distribution 12
HTML post 9
Binary Image: misplaced jpg 4
Too many newsgroups (meow) 2
User-issued spam cancel 1
TOTAL: 12 14172

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 336
newsfeed.neva.ru 29
TOTAL: 2 365
TOTAL: 1 365

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nntp.pasdenom.info 32638 1939 16361 75 0 1396 5%24:57:27
2news.fcku.it 25458 535 17881 824 0 53 2%24:59:50
3neva.ru 25410 46 17440 99 0 36 0%24:59:43
4weretis.net 2445 43 2192 0 0 18 1%24:59:31
5eternal-september 21378 12 16308 0 0 7 0%24:59:47
6news.stack.nl 2977 0 0 0 0 22426 0%24:59:51
7etla.org 0 0 0 0 0 19497 0%24:59:51
8vc89.vc.panix.com 2950 0 0 0 0 22446 0%24:59:51
9news.mixmin.net 0 0 0 0 0 0 0%00:10:00
10news.killfile.org 2969 0 0 0 0 22465 0%24:59:51
11news.albasani.net 2954 0 0 0 0 21812 0%24:59:51
12news.netfront.net 2798 0 0 0 0 22212 0%24:59:48
TOTAL: 12 121977 2575 70182 998 0 132368 2%275:05:21
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nntp.pasdenom.info5.1 MB127.1 KB5.3 MB0.1 KB/s2.7 KB24:57:27
2news.fcku.it1.5 MB3.9 MB5.4 MB0.1 KB/s4.1 KB24:59:50
3weretis.net355.8 KB0.0 KB355.8 KB0.0 KB/s8.3 KB24:59:31
4neva.ru248.8 KB602.9 KB851.7 KB0.0 KB/s5.9 KB24:59:43
5eternal-september38.5 KB0.0 KB38.5 KB0.0 KB/s3.2 KB24:59:47
6news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s024:59:51
7etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:59:51
8vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s024:59:51
9news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
10news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:59:51
11news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s024:59:51
12news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s024:59:48
TOTAL: 127.3 MB4.6 MB11.9 MB0.0 KB/s3.4 KB275:05:21
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mtrlpq2314w-lp140-01-50-101-182-89.dsl.bell.ca 68 2957.5 KB 16 0 001:33:09
2mtrlpq2314w-lp140-01-845526617.dsl.bell.ca 41 414.3 KB 3 0 000:52:00
350.101.182.89 21 49.5 KB 2 0 000:25:36
4challenger.nielsenmedia.com 2 13.7 KB 1 0 000:30:12
547.192.187.101 10 13.7 KB 1 0 004:01:31
TOTAL: 5 142 3988.6 KB 23 0 007:22:59

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.dsl.bell.ca 109 3371.8 KB 19 0 002:25:10
2unresolved 31 513.2 KB 3 0 004:27:07
3*.nielsenmedia.com 2 13.7 KB 1 0 000:30:12
4*.188.110.pools.vodafone-ip.de 130 00.0 KB 0 0 000:00:28
TOTAL: 4 272 3988.6 KB 23 0 007:22:59

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
47.192.187.101 0.632 0.020 0.00004:01:31
mtrlpq2314w-lp140-01-50-101-182-89.dsl.bell.ca 8.056 3.024 0.00001:33:09
mtrlpq2314w-lp140-01-845526617.dsl.bell.ca 4.800 1.736 0.00000:52:00
challenger.nielsenmedia.com 0.172 0.008 0.00000:30:12
50.101.182.89 2.336 0.792 0.00000:25:36
dslb-188-110-188-212.188.110.pools.vodafone-ip.de 6.884 0.440 0.00000:00:28
TOTAL: 6 22.880 6.020 0.00007:22:59

Curious NNRP server explorers [Top 20]:

SystemConn
dslb-188-110-188-212.188.110.pools.vodafone-ip.de 130
TOTAL: 1 130

NNRP client timeouts [Top 20]:

SystemConnPeer
47.192.187.101 7 0
challenger.nielsenmedia.com 1 0
TOTAL: 2 8 0

Newsgroup request counts (by category):

CategoryCountPct
1uk 10 25.6%
2rec 8 20.5%
3fr 6 15.4%
4alt 5 12.8%
5microsoft 3 7.7%
6comp 3 7.7%
7de 2 5.1%
8it 1 2.6%
9talk 1 2.6%
TOTAL: 9 39100.0%

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

NewsgroupCount
1rec.backcountry 6
2fr.rec.loisirs-creatifs 6
3uk.politics.misc 6
4alt.home.repair 4
5microsoft.public.excel.programming 3
6rec.arts.tv 2
7uk.people.silversurfers 2
8comp.lang.awk 2
9uk.d-i-y 1
10uk.rec.cars.maintenance 1
11alt.fan.rush-limbaugh 1
12talk.politics.guns 1
13de.soc.senioren 1
14it.sport.formula1 1
15comp.lang.python 1
16de.etc.sprache.deutsch 1
TOTAL: 16 39