Daily Usenet report for paganini.linuxd.org

May 25 00:00:07 -- May 26 00:00:03

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

May 25 01:22:11 paganini overchan[3929]: input line too long, skipping
May 25 05:00:59 paganini overchan[3929]: input line too long, skipping
May 25 05:13:47 paganini overchan[3929]: input line too long, skipping
May 25 06:36:56 paganini overchan[3929]: input line too long, skipping
May 25 12:31:24 paganini overchan[3929]: input line too long, skipping
May 25 17:43:36 paganini overchan[3929]: input line too long, skipping
May 25 19:26:12 paganini overchan[3929]: input line too long, skipping
May 25 22:55:55 paganini overchan[3929]: input line too long, skipping
May 26 00:00:03 paganini overchan[15679]: 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 101413 53.8%12.6 MB 46.2%
nnrpd 49394 26.2%5.7 MB 20.9%
inn 29346 15.6%7.5 MB 27.7%
innd 8403 4.5%1.4 MB 5.2%
nocem 88 0.0%15.5 KB 0.1%
overchan 10 0.0%1.6 KB 0.0%
TOTAL: 6 188654 100.0%27.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 112434 59.2%
Negative hits 48899 25.8%
Do not exist 28478 15.0%
Cache misses 68 0.0%
TOTAL: 4 189879 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.635 0.0%172 0.000 3.692 28.600
article cleanup00:00:00.191 0.0%28786 0.000 0.007 0.026
article logging00:00:00.257 0.0%29196 0.000 0.009 0.035
article parse00:00:00.625 0.0%43194 0.000 0.014 0.031
article write00:00:48.573 0.1%21714 0.115 2.237 11.710
artlog/artcncl00:00:00.000 0.0%172 0.000 0.000 0.000
data move00:00:00.091 0.0%200083 0.000 0.000 0.003
hisgrep/artcncl00:00:00.005 0.0%167 0.000 0.030 1.000
hishave/artcncl00:00:00.003 0.0%172 0.000 0.017 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:10.628 0.0%191759 0.017 0.055 0.472
history sync00:00:00.256 0.0%400 0.000 0.640 37.000
history write00:00:32.687 0.0%28784 0.040 1.136 4.861
hiswrite/artcncl00:00:00.000 0.0%5 0.000 0.000 0.000
idle23:57:45.570 99.8%194789 172.349 442.867 1052.242
nntp read00:00:01.591 0.0%200882 0.001 0.008 0.017
overview write00:00:22.739 0.0%21714 0.040 1.047 4.401
perl filter00:00:53.536 0.1%28784 1.053 1.860 3.813
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.378 0.0%65329 0.005 0.021 0.052
TOTAL: 24:01:12.51224:00:38.765100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.101 0.0%39587 0.000 0.003 0.016
article prepare00:00:00.004 0.0%35312 0.000 0.000 0.005
article read00:00:03.500 0.0%6913 0.000 0.506 11.114
backlog stats00:00:00.270 0.0%348601 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.504 0.0%191524 0.003 0.008 0.015
data write00:00:03.070 0.0%169945 0.007 0.018 0.032
idle23:43:08.598 99.3%348601 128.817 244.947 431.160
status file00:09:48.159 0.7%42402 8.751 13.871 29.965
TOTAL: 23:53:27.94923:53:05.206100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle06:18:36.471 99.9%49953 37.048 454.757600109.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.896 0.0%101471 0.000 0.009 0.038
readart00:00:00.101 0.0%20 0.000 5.050 16.500
TOTAL: 06:18:54.03906:18:37.468 99.9%----

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 4 45918 18934 25958 1026 41%48:12:56
2newsfeed.neva.ru 150 67945 2410 59663 5872 3%71:51:30
3news.fcku.it 1 8040 363 7518 159 4%24:51:21
4nntp.pasdenom.info 2 22589 85 22485 19 0%24:14:31
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 182 144492 21792 115624 7076 15%169:10:18
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net65.2 MB0.0 KB7.4 MB72.6 MB 89%3.7 KB
2newsfeed.neva.ru6.1 MB22.2 KB26.7 MB32.9 MB 18%4.1 KB
3news.fcku.it1.6 MB0.0 KB644.1 KB2.2 MB 71%4.3 KB
4nntp.pasdenom.info249.1 KB0.0 KB586.8 KB835.9 KB 29%8.0 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 573.1 MB22.2 KB35.3 MB108.4 MB 67%3.8 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 25 00:00:07 - 00:59:59 808 3.7% 0.222.7 MB 3.7% 0.77
May 25 01:00:00 - 01:59:59 641 3.0% 0.181.9 MB 2.7% 0.55
May 25 02:00:00 - 02:59:59 675 3.1% 0.192.6 MB 3.6% 0.75
May 25 03:00:00 - 03:59:59 484 2.2% 0.131.8 MB 2.4% 0.50
May 25 04:00:00 - 04:59:59 503 2.3% 0.142.1 MB 2.9% 0.59
May 25 05:00:00 - 05:59:59 536 2.5% 0.152.4 MB 3.3% 0.69
May 25 06:00:00 - 06:59:59 519 2.4% 0.141.6 MB 2.2% 0.46
May 25 07:00:00 - 07:59:59 528 2.4% 0.152.8 MB 3.9% 0.80
May 25 08:00:00 - 08:59:59 684 3.2% 0.192.8 MB 3.9% 0.81
May 25 09:00:00 - 09:59:59 1023 4.7% 0.283.7 MB 5.1% 1.05
May 25 10:00:00 - 10:59:59 1031 4.8% 0.293.0 MB 4.2% 0.87
May 25 11:00:00 - 11:59:59 1180 5.4% 0.334.1 MB 5.6% 1.16
May 25 12:00:00 - 12:59:59 1013 4.7% 0.283.5 MB 4.9% 1.00
May 25 13:00:00 - 13:59:59 1103 5.1% 0.314.3 MB 5.9% 1.21
May 25 14:00:00 - 14:59:59 989 4.6% 0.272.9 MB 4.0% 0.83
May 25 15:00:00 - 15:59:59 1106 5.1% 0.313.3 MB 4.5% 0.93
May 25 16:00:00 - 16:59:59 1106 5.1% 0.313.4 MB 4.7% 0.98
May 25 17:00:00 - 17:59:59 1248 5.8% 0.353.7 MB 5.1% 1.05
May 25 18:00:00 - 18:59:59 1207 5.6% 0.344.3 MB 6.0% 1.24
May 25 19:00:00 - 19:59:59 1104 5.1% 0.313.2 MB 4.4% 0.90
May 25 20:00:00 - 20:59:59 1041 4.8% 0.293.2 MB 4.4% 0.90
May 25 21:00:00 - 21:59:59 1021 4.7% 0.282.9 MB 4.0% 0.82
May 25 22:00:00 - 22:59:59 1157 5.3% 0.323.3 MB 4.5% 0.94
May 25 23:00:00 - 23:59:59 963 4.4% 0.272.9 MB 3.9% 0.82
May 26 00:00:00 - 00:00:03 30 0.1% 10.00132.3 KB 0.2% 44.12
TOTAL: 23:59:56 21700 100.0% 0.2572.6 MB 100.0% 0.86
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 5869 240 0 2 1 0 0 0 5626
2linuxd-out.news.weretis.net 1409 410 0 0 0 0 0 0 999
3news.fcku.it 169 3 0 0 0 0 0 0 166
4nntp.pasdenom.info 10 1 0 0 0 0 0 0 9
TOTAL: 4 7457 654 0 2 1 0 0 0 6800

Unwanted newsgroups [Top 20]:

NewsgroupCount
lada.talk 103
lada.auto 60
it-alt.sport.calcio.juventus 46
gmane.linux.alsa.devel 30
it.annunci.immobiliari 27
it.annunci.varie 24
it.annunci.commerciali 24
lada.commerce 21
mozilla.dev.platform 19
spline.eisfair 18
alt.survival 14
alt.windows7.general 14
mozilla.support.seamonkey 13
it.comp.os.win.windows10 12
oesterreich.tratsch 11
gmane.comp.gis.openstreetmap 10
mpc.lists.freebsd.ports.bugs 10
mpc.lists.nanog 9
free.it.religioni.scientology 9
ut.test 8
TOTAL: 105 654

Supposedly-moderated groups with unmoderated postings [Top 20]:

GroupsCount
uk.legal.moderated 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 5320
EMP (phn path) 763
EMP (phl) 132
EMP (phn nph) 67
Too many newsgroups 46
Binary Payload 23
User-issued cancel 14
Binary: non-binary distribution 11
HTML post 11
User-issued spam cancel 1
Binary Image: misplaced jpg 1
TOTAL: 11 6389

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 382
newsfeed.neva.ru 27
news.fcku.it 2
TOTAL: 3 411
TOTAL: 1 411

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nntp.pasdenom.info 37294 3023 17674 887 0 456 8%23:55:35
2news.fcku.it 27629 2111 16626 2551 0 95 7%23:57:14
3weretis.net 6066 85 5727 0 0 188 1%23:51:01
4eternal-september 24425 34 18410 0 0 9 0%23:54:18
5neva.ru 27392 26 19030 155 0 107 0%23:56:00
6news.stack.nl 2860 0 0 0 0 24402 0%23:59:25
7etla.org 0 0 0 0 0 21540 0%23:50:02
8news.killfile.org 2843 0 0 0 0 24508 0%23:59:29
9news.albasani.net 2866 0 0 0 0 23861 0%23:59:29
10news.netfront.net 2765 0 0 0 0 24389 0%23:59:34
11news.mixmin.net 0 0 0 0 0 0 0%00:10:00
12vc89.vc.panix.com 2841 0 0 0 0 24506 0%23:59:29
TOTAL: 12 136981 5279 77467 3593 0 144061 3%263:31:36
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nntp.pasdenom.info9.7 MB1.9 MB11.6 MB0.1 KB/s3.0 KB23:55:35
2news.fcku.it6.3 MB8.0 MB14.3 MB0.2 KB/s3.1 KB23:57:14
3eternal-september133.3 KB0.0 KB133.3 KB0.0 KB/s3.9 KB23:54:18
4weretis.net130.3 KB0.0 KB130.3 KB0.0 KB/s1.5 KB23:51:01
5neva.ru66.7 KB450.0 KB516.8 KB0.0 KB/s2.9 KB23:56:00
6news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:25
7etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:02
8news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:29
9news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:29
10news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:34
11news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
12vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:29
TOTAL: 1216.3 MB10.3 MB26.7 MB0.0 KB/s3.1 KB263:31:36
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1challenger.nielsenmedia.com 10 1742.2 KB 6 0 004:35:32
247-192-187-101.drr02.clwr.fl.frontiernet.net 2 36.6 KB 2 0 001:01:37
TOTAL: 2 12 2048.8 KB 8 0 006:18:53

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.nielsenmedia.com 10 1742.2 KB 6 0 004:35:32
2*.drr02.clwr.fl.frontiernet.net 2 36.6 KB 2 0 001:01:37
3*.088.067.pools.vodafone-ip.de 89 00.0 KB 0 0 000:41:43
TOTAL: 3 101 2048.8 KB 8 0 006:18:53

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
challenger.nielsenmedia.com 0.848 0.040 0.00004:35:32
47-192-187-101.drr02.clwr.fl.frontiernet.net 0.176 0.008 0.00001:01:37
dslb-088-067-084-037.088.067.pools.vodafone-ip.de 9.468 2.392 0.00000:41:43
TOTAL: 3 10.492 2.440 0.00006:18:53

Curious NNRP server explorers [Top 20]:

SystemConn
dslb-088-067-084-037.088.067.pools.vodafone-ip.de 89
TOTAL: 1 89

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1comp 20100.0%
TOTAL: 1 20100.0%

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

NewsgroupCount
1comp.lang.awk 20
TOTAL: 1 20