Daily Usenet report for paganini.linuxd.org

May 11 00:00:06 -- May 12 00:00:05

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

May 11 02:18:45 paganini overchan[3768]: input line too long, skipping
May 11 04:13:00 paganini overchan[3768]: input line too long, skipping
May 11 04:15:06 paganini overchan[3768]: input line too long, skipping
May 11 04:25:24 paganini overchan[3768]: input line too long, skipping
May 12 00:00:05 paganini overchan[28959]: 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 101318 46.5%12.5 MB 41.6%
nnrpd 59340 27.2%6.8 MB 22.8%
inn 38936 17.9%8.3 MB 27.6%
innd 18101 8.3%2.4 MB 7.9%
nocem 99 0.0%16.5 KB 0.1%
overchan 6 0.0%0.9 KB 0.0%
controlchan 1 0.0%0.0 KB 0.0%
TOTAL: 7 217801 100.0%29.9 MB100.0%

History cache:

ReasonCount%Count
Positive hits 114833 48.4%
Negative hits 84193 35.5%
Do not exist 38316 16.1%
Cache misses 85 0.0%
TOTAL: 4 237427 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:02.698 0.0%223 0.000 12.099 167.000
article cleanup00:00:00.249 0.0%38455 0.000 0.006 0.027
article logging00:00:00.357 0.0%38763 0.000 0.009 0.065
article parse00:00:01.048 0.0%82764 0.000 0.013 0.041
article write00:03:21.842 0.2%21480 0.133 9.397 38.319
artlog/artcncl00:00:00.004 0.0%223 0.000 0.018 0.333
data move00:00:00.961 0.0%235183 0.000 0.004 0.157
hisgrep/artcncl00:00:00.456 0.0%223 0.000 2.045 16.000
hishave/artcncl00:00:00.170 0.0%223 0.000 0.762 7.889
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:03:53.829 0.3%238220 0.018 0.982 5.506
history sync00:00:00.029 0.0%396 0.000 0.073 3.000
history write00:03:53.823 0.3%38455 0.040 6.080 36.496
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:45:47.961 98.9%228244 194.048 374.809 829.822
nntp read00:00:02.691 0.0%246724 0.001 0.011 0.062
overview write00:01:33.748 0.1%21480 0.020 4.364 33.054
perl filter00:01:26.150 0.1%38455 1.200 2.240 5.174
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.473 0.0%64761 0.003 0.023 0.188
TOTAL: 24:00:59.34724:00:07.489 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.091 0.0%35938 0.000 0.003 0.013
article prepare00:00:00.001 0.0%19868 0.000 0.000 0.007
article read00:00:01.001 0.0%3971 0.000 0.252 10.250
backlog stats00:00:00.210 0.0%308421 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.368 0.0%172254 0.003 0.008 0.020
data write00:00:02.798 0.0%151144 0.008 0.019 0.042
idle23:37:22.631 98.9%308421 178.958 275.736 427.949
status file00:15:55.504 1.1%42377 7.441 22.548 156.310
TOTAL: 23:53:46.33423:53:23.604100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle03:55:28.371 99.8%59987 37.317 235.524600345.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.204 0.0%121764 0.000 0.010 0.019
readart00:00:00.087 0.0%11 0.000 7.909 20.500
TOTAL: 03:56:01.49303:55:29.662 99.8%----

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 46829 17459 27554 1816 37%47:56:39
2newsfeed.neva.ru 149 76487 2868 58698 14921 3%71:47:27
3news.fcku.it 1 8880 1071 7584 225 12%24:40:57
4nntp.pasdenom.info 2 21654 107 21493 54 0%24:02:02
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 181 153850 21505 115329 17016 13%168:27:05
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net56.2 MB0.0 KB11.9 MB68.1 MB 82%3.6 KB
2newsfeed.neva.ru7.7 MB0.0 KB132.0 MB139.6 MB 5%8.0 KB
3news.fcku.it3.4 MB0.0 KB1.2 MB4.6 MB 74%3.6 KB
4nntp.pasdenom.info243.9 KB0.0 KB659.5 KB903.4 KB 26%5.6 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 567.4 MB0.0 KB145.7 MB213.1 MB 31%5.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 11 00:00:06 - 00:59:59 768 3.6% 0.212.4 MB 3.6% 0.68
May 11 01:00:00 - 01:59:59 575 2.7% 0.161.7 MB 2.6% 0.49
May 11 02:00:00 - 02:59:59 549 2.6% 0.151.5 MB 2.3% 0.44
May 11 03:00:00 - 03:59:59 432 2.0% 0.121.3 MB 2.0% 0.38
May 11 04:00:00 - 04:59:59 518 2.4% 0.143.3 MB 4.9% 0.95
May 11 05:00:00 - 05:59:59 490 2.3% 0.141.6 MB 2.4% 0.45
May 11 06:00:00 - 06:59:59 469 2.2% 0.131.8 MB 2.7% 0.51
May 11 07:00:00 - 07:59:59 545 2.5% 0.151.7 MB 2.5% 0.48
May 11 08:00:00 - 08:59:59 695 3.2% 0.191.9 MB 2.8% 0.54
May 11 09:00:00 - 09:59:59 956 4.5% 0.272.6 MB 3.9% 0.74
May 11 10:00:00 - 10:59:59 1075 5.0% 0.303.2 MB 4.8% 0.91
May 11 11:00:00 - 11:59:59 1105 5.1% 0.313.0 MB 4.4% 0.85
May 11 12:00:00 - 12:59:59 1101 5.1% 0.314.3 MB 6.4% 1.22
May 11 13:00:00 - 13:59:59 885 4.1% 0.252.3 MB 3.4% 0.65
May 11 14:00:00 - 14:59:59 1129 5.3% 0.312.7 MB 4.1% 0.78
May 11 15:00:00 - 15:59:59 1214 5.7% 0.343.1 MB 4.7% 0.90
May 11 16:00:00 - 16:59:59 1118 5.2% 0.313.5 MB 5.1% 0.98
May 11 17:00:00 - 17:59:59 1200 5.6% 0.334.2 MB 6.3% 1.20
May 11 18:00:00 - 18:59:59 1245 5.8% 0.353.8 MB 5.6% 1.07
May 11 19:00:00 - 19:59:59 1114 5.2% 0.315.9 MB 8.7% 1.66
May 11 20:00:00 - 20:59:59 1172 5.5% 0.333.2 MB 4.7% 0.90
May 11 21:00:00 - 21:59:59 1012 4.7% 0.282.7 MB 4.0% 0.76
May 11 22:00:00 - 22:59:59 1095 5.1% 0.302.7 MB 4.1% 0.78
May 11 23:00:00 - 23:59:59 985 4.6% 0.272.8 MB 4.2% 0.81
May 12 00:00:00 - 00:00:05 11 0.1% 2.2048.5 KB 0.1% 9.71
TOTAL: 23:59:59 21458 100.0% 0.2567.3 MB 100.0% 0.80
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 14935 363 0 0 0 0 0 014572
2linuxd-out.news.weretis.net 2085 513 0 0 0 0 0 0 1572
3news.fcku.it 223 27 0 0 0 0 0 0 196
4nntp.pasdenom.info 32 1 0 0 0 0 0 0 31
TOTAL: 4 17275 904 0 0 0 0 0 016371

Unwanted newsgroups [Top 20]:

NewsgroupCount
ut.test 115
lada.talk 89
it-alt.sport.calcio.juventus 89
gmane.linux.alsa.devel 49
lada.auto 48
mpc.lists.freebsd.ports.bugs 38
it.annunci.commerciali 32
it.annunci.varie 31
mozilla.dev.security.policy 26
it.annunci.immobiliari 25
lada.commerce 24
it.comp.os.win.windows10 23
mozilla.support.firefox 22
alt.windows7.general 21
spline.eisfair 18
mozilla.dev.platform 17
cakewalk.audio 14
gmane.comp.gis.openstreetmap.tagging 14
gmane.comp.gis.openstreetmap.imports 10
alt.survival 10
TOTAL: 95 904

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 14544
EMP (phn path) 1341
EMP (phl) 74
Too many newsgroups 33
User-issued cancel 23
Binary Payload 20
EMP (phn nph) 17
Binary: non-binary distribution 5
HTML post 4
Too many test groups in crosspost 1
Angle-bracket bot 1
TOTAL: 11 16063

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 278
newsfeed.neva.ru 26
news.fcku.it 4
TOTAL: 3 308
TOTAL: 1 308

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nntp.pasdenom.info 35006 1970 18827 605 0 427 5%23:57:38
2news.fcku.it 24212 854 18244 1376 0 9 3%23:59:45
3neva.ru 24121 19 18405 138 0 7 0%23:59:44
4weretis.net 279 13 237 0 0 3621 4%23:58:05
5eternal-september 22091 12 18228 0 0 21 0%23:59:44
6etla.org 0 0 0 0 0 21456 0%23:59:45
7news.albasani.net 2855 0 0 0 0 23604 0%23:59:44
8vc89.vc.panix.com 2840 0 0 0 0 24295 0%23:59:44
9news.killfile.org 2849 0 0 0 0 24304 0%23:59:44
10news.netfront.net 2761 0 0 0 0 24185 0%23:59:44
11news.mixmin.net 0 0 0 0 0 0 0%00:10:00
12news.stack.nl 2853 0 0 0 0 24285 0%23:59:44
TOTAL: 12 119867 2868 73941 2119 0 146214 2%264:03:21
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nntp.pasdenom.info6.8 MB1.3 MB8.1 MB0.1 KB/s3.2 KB23:57:38
2news.fcku.it2.9 MB4.1 MB7.0 MB0.1 KB/s3.2 KB23:59:45
3neva.ru72.5 KB277.8 KB350.3 KB0.0 KB/s2.2 KB23:59:44
4eternal-september58.3 KB0.0 KB58.3 KB0.0 KB/s4.9 KB23:59:44
5weretis.net29.8 KB0.0 KB29.8 KB0.0 KB/s2.3 KB23:58:05
6etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:45
7news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:44
8vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:44
9news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:44
10news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:44
11news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
12news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:44
TOTAL: 129.8 MB5.7 MB15.5 MB0.0 KB/s3.2 KB264:03:21
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1challenger.nielsenmedia.com 7 616.1 KB 3 0 002:40:06
2dslb-088-067-084-037.088.067.pools.vodafone-ip.de 107 38.7 KB 3 0 000:53:08
347-192-187-101.drr02.clwr.fl.frontiernet.net 1 28.1 KB 1 0 000:22:41
TOTAL: 3 115 1133.0 KB 7 0 003:56:01

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.nielsenmedia.com 7 616.1 KB 3 0 002:40:06
2*.088.067.pools.vodafone-ip.de 107 38.7 KB 3 0 000:53:08
3*.drr02.clwr.fl.frontiernet.net 1 28.1 KB 1 0 000:22:41
4unresolved 1 00.0 KB 0 0 000:00:03
TOTAL: 4 116 1133.0 KB 7 0 003:56:01

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
challenger.nielsenmedia.com 0.440 0.036 0.00002:40:06
dslb-088-067-084-037.088.067.pools.vodafone-ip.de 11.736 2.764 0.00000:53:08
47-192-187-101.drr02.clwr.fl.frontiernet.net 0.076 0.012 0.00000:22:41
106.75.99.191 0.056 0.000 0.00000:00:03
TOTAL: 4 12.308 2.812 0.00003:56:01

Curious NNRP server explorers [Top 20]:

SystemConn
106.75.99.191 1
TOTAL: 1 1

NNRP client timeouts [Top 20]:

SystemConnPeer
challenger.nielsenmedia.com 5 0
TOTAL: 1 5 0

Newsgroup request counts (by category):

CategoryCountPct
1comp 8 72.7%
2control 1 9.1%
3alt 1 9.1%
4de 1 9.1%
TOTAL: 4 11100.0%

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

NewsgroupCount
1comp.lang.awk 8
2control.cancel 1
3de.rec.geocaching 1
4alt.test 1
TOTAL: 4 11