Daily Usenet report for paganini.linuxd.org

May 22 00:00:01 -- May 23 00:00:05

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

May 22 03:05:34 paganini overchan[22303]: input line too long, skipping
May 22 06:34:20 paganini overchan[22303]: input line too long, skipping
May 22 06:40:56 paganini overchan[22303]: input line too long, skipping
May 22 06:43:29 paganini overchan[22303]: input line too long, skipping
May 22 08:23:39 paganini overchan[22303]: input line too long, skipping
May 22 10:16:39 paganini overchan[22303]: input line too long, skipping
May 22 10:17:13 paganini overchan[22303]: input line too long, skipping
May 22 14:02:23 paganini overchan[22303]: input line too long, skipping
May 23 00:00:05 paganini overchan[8149]: 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 101495 48.5%12.6 MB 42.4%
nnrpd 67053 32.1%7.7 MB 26.0%
inn 31100 14.9%7.8 MB 26.2%
innd 9432 4.5%1.6 MB 5.4%
nocem 68 0.0%11.4 KB 0.0%
overchan 10 0.0%1.3 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 209159 100.0%29.7 MB100.0%

History cache:

ReasonCount%Count
Positive hits 116425 57.4%
Negative hits 55869 27.6%
Do not exist 30391 15.0%
Cache misses 57 0.0%
TOTAL: 4 202742 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:02.018 0.0%174 0.000 11.598 68.000
article cleanup00:00:00.214 0.0%30475 0.000 0.007 0.026
article logging00:00:00.243 0.0%30960 0.000 0.008 0.040
article parse00:00:00.615 0.0%45393 0.000 0.014 0.040
article write00:01:37.429 0.1%22024 0.120 4.424 40.168
artlog/artcncl00:00:00.001 0.0%174 0.000 0.006 0.100
data move00:00:00.303 0.0%209436 0.000 0.001 0.115
hisgrep/artcncl00:00:00.362 0.0%174 0.000 2.080 12.111
hishave/artcncl00:00:00.003 0.0%174 0.000 0.017 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:58.383 0.1%203282 0.015 0.287 1.075
history sync00:00:00.056 0.0%403 0.000 0.139 12.333
history write00:04:08.403 0.3%30475 0.050 8.151 31.088
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:51:29.130 99.3%199566 243.211 430.380 1060.804
nntp read00:00:01.664 0.0%211529 0.003 0.008 0.050
overview write00:01:11.284 0.1%22024 0.015 3.237 18.789
perl filter00:00:57.252 0.1%30475 1.051 1.879 13.548
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.316 0.0%66233 0.002 0.020 0.055
TOTAL: 24:01:04.97324:00:28.676100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.100 0.0%36579 0.000 0.003 0.016
article prepare00:00:00.003 0.0%24868 0.000 0.000 0.016
article read00:00:03.255 0.0%4939 0.000 0.659 20.333
backlog stats00:00:00.222 0.0%332098 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.458 0.0%184752 0.002 0.008 0.016
data write00:00:02.964 0.0%163154 0.007 0.018 0.036
idle23:42:06.001 99.2%332098 163.404 256.930 466.244
status file00:11:27.174 0.8%42424 9.152 16.198 80.451
TOTAL: 23:54:02.18023:53:41.177100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle01:58:35.310 99.7%67774 36.946 104.986360153.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.282 0.0%137496 0.000 0.009 0.026
readart00:00:00.010 0.0%3 0.000 3.333 10.000
TOTAL: 01:58:56.02101:58:36.602 99.7%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1linuxd-out.news.weretis.net 7 46822 18508 26777 1537 39%48:04:52
2newsfeed.neva.ru 140 69637 2865 60244 6528 4%72:57:13
3news.fcku.it 2 9532 574 8647 311 6%25:22:45
4nntp.pasdenom.info 3 18503 49 18446 8 0%24:29:38
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 177 144494 21996 114114 8384 15%170:54:28
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net59.9 MB0.0 KB5.7 MB65.6 MB 91%3.4 KB
2newsfeed.neva.ru7.9 MB0.0 KB27.0 MB34.9 MB 22%3.8 KB
3news.fcku.it2.0 MB0.0 KB3.1 MB5.1 MB 39%5.9 KB
4nntp.pasdenom.info129.2 KB0.0 KB22.8 KB152.0 KB 85%2.7 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 569.9 MB0.0 KB35.7 MB105.7 MB 66%3.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 22 00:00:01 - 00:59:59 750 3.4% 0.212.2 MB 3.1% 0.61
May 22 01:00:00 - 01:59:59 601 2.7% 0.171.8 MB 2.6% 0.51
May 22 02:00:00 - 02:59:59 512 2.3% 0.141.5 MB 2.2% 0.43
May 22 03:00:00 - 03:59:59 458 2.1% 0.131.5 MB 2.2% 0.43
May 22 04:00:00 - 04:59:59 412 1.9% 0.111.4 MB 2.0% 0.40
May 22 05:00:00 - 05:59:59 515 2.3% 0.142.3 MB 3.3% 0.65
May 22 06:00:00 - 06:59:59 607 2.8% 0.172.3 MB 3.3% 0.65
May 22 07:00:00 - 07:59:59 540 2.5% 0.152.0 MB 2.8% 0.56
May 22 08:00:00 - 08:59:59 645 2.9% 0.182.0 MB 2.8% 0.56
May 22 09:00:00 - 09:59:59 827 3.8% 0.232.2 MB 3.1% 0.62
May 22 10:00:00 - 10:59:59 881 4.0% 0.242.5 MB 3.6% 0.72
May 22 11:00:00 - 11:59:59 981 4.5% 0.273.7 MB 5.4% 1.07
May 22 12:00:00 - 12:59:59 911 4.1% 0.252.6 MB 3.7% 0.74
May 22 13:00:00 - 13:59:59 959 4.4% 0.273.0 MB 4.3% 0.85
May 22 14:00:00 - 14:59:59 1007 4.6% 0.283.0 MB 4.2% 0.84
May 22 15:00:00 - 15:59:59 1105 5.0% 0.313.8 MB 5.4% 1.07
May 22 16:00:00 - 16:59:59 1462 6.6% 0.414.6 MB 6.5% 1.30
May 22 17:00:00 - 17:59:59 1417 6.4% 0.394.4 MB 6.3% 1.26
May 22 18:00:00 - 18:59:59 1371 6.2% 0.384.9 MB 7.0% 1.40
May 22 19:00:00 - 19:59:59 1292 5.9% 0.364.4 MB 6.2% 1.24
May 22 20:00:00 - 20:59:59 1204 5.5% 0.333.7 MB 5.2% 1.04
May 22 21:00:00 - 21:59:59 1284 5.8% 0.363.7 MB 5.3% 1.06
May 22 22:00:00 - 22:59:59 1268 5.8% 0.353.8 MB 5.4% 1.07
May 22 23:00:00 - 23:59:59 983 4.5% 0.272.8 MB 4.0% 0.80
May 23 00:00:00 - 00:00:05 15 0.1% 3.0083.2 KB 0.1% 16.64
TOTAL: 24:00:04 22007 100.0% 0.2570.0 MB 100.0% 0.83
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 6668 365 0 0 0 0 0 0 6303
2linuxd-out.news.weretis.net 1998 634 0 0 0 0 0 0 1364
3news.fcku.it 255 19 0 0 0 0 0 0 236
4nntp.pasdenom.info 13 1 0 0 0 0 0 0 12
TOTAL: 4 8934 1019 0 0 0 0 0 0 7915

Unwanted newsgroups [Top 20]:

NewsgroupCount
it-alt.sport.calcio.juventus 161
ut.test 123
lada.talk 90
spline.eisfair 48
lada.auto 44
mpc.lists.freebsd.ports.bugs 40
it.annunci.immobiliari 32
it.annunci.commerciali 31
mozilla.support.firefox 30
gmane.linux.alsa.devel 29
it.annunci.varie 25
mozilla.dev.security.policy 25
mozilla.test 24
it.comp.os.win.windows10 20
alt.survival 18
mozilla.support.seamonkey 14
alt.windows7.general 14
alt.pl.nieruchomosci.gielda 12
alt.politics 11
alt.philosophy 11
TOTAL: 112 1019

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 6294
EMP (phn path) 832
EMP (phl) 132
Too many newsgroups 52
EMP (phn nph) 49
Binary Payload 29
User-issued cancel 27
Binary: non-binary distribution 9
HTML post 4
User-issued spam cancel 1
Too many newsgroups (low_xpost) 1
TOTAL: 11 7430

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 452
newsfeed.neva.ru 30
news.fcku.it 3
TOTAL: 3 485
TOTAL: 1 485

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nntp.pasdenom.info 36617 2205 18908 834 0 369 6%23:57:48
2news.fcku.it 27001 1244 18552 1708 0 111 4%23:59:55
3weretis.net 3563 116 3269 0 0 8 3%23:59:51
4neva.ru 25822 31 18970 113 0 48 0%23:59:55
5eternal-september 23824 3 18925 0 0 2 0%23:59:55
6news.mixmin.net 0 0 0 0 0 0 0%00:10:00
7etla.org 0 0 0 0 0 22007 0%23:59:55
8news.stack.nl 2859 0 0 0 0 24812 0%23:59:55
9news.albasani.net 2865 0 0 0 0 24190 0%23:59:55
10news.killfile.org 2851 0 0 0 0 24857 0%23:59:55
11vc89.vc.panix.com 2842 0 0 0 0 24848 0%23:59:55
12news.netfront.net 2765 0 0 0 0 24715 0%23:59:55
TOTAL: 12 131009 3599 78624 2655 0 145967 2%264:06:54
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nntp.pasdenom.info7.9 MB1.6 MB9.6 MB0.1 KB/s3.2 KB23:57:48
2news.fcku.it3.5 MB5.9 MB9.4 MB0.1 KB/s3.3 KB23:59:55
3weretis.net142.9 KB0.0 KB142.9 KB0.0 KB/s1.2 KB23:59:51
4neva.ru105.6 KB380.4 KB486.1 KB0.0 KB/s3.4 KB23:59:55
5eternal-september14.0 KB0.0 KB14.0 KB0.0 KB/s4.7 KB23:59:55
6news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
7etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
8news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
9news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
10news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
11vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
12news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
TOTAL: 1211.7 MB7.8 MB19.5 MB0.0 KB/s3.2 KB264:06:54
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1challenger.nielsenmedia.com 1 12.7 KB 1 0 000:30:01
247-192-187-101.drr02.clwr.fl.frontiernet.net 1 12.7 KB 1 0 000:31:21
3dslb-088-067-084-037.088.067.pools.vodafone-ip.de 121 11.8 KB 1 0 000:57:32
TOTAL: 3 123 37.2 KB 3 0 001:58:55

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.088.067.pools.vodafone-ip.de 121 11.8 KB 1 0 000:57:32
2*.drr02.clwr.fl.frontiernet.net 1 12.7 KB 1 0 000:31:21
3*.nielsenmedia.com 1 12.7 KB 1 0 000:30:01
TOTAL: 3 123 37.2 KB 3 0 001:58:55

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
dslb-088-067-084-037.088.067.pools.vodafone-ip.de 13.140 3.160 0.00000:57:32
47-192-187-101.drr02.clwr.fl.frontiernet.net 0.088 0.000 0.00000:31:21
challenger.nielsenmedia.com 0.088 0.004 0.00000:30:01
TOTAL: 3 13.316 3.164 0.00001:58:55

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1comp 2 66.7%
2de 1 33.3%
TOTAL: 2 3100.0%

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

NewsgroupCount
1comp.lang.awk 2
2de.sci.physik 1
TOTAL: 2 3