Daily Usenet report for paganini.linuxd.org

Feb 13 00:00:01 -- Feb 14 00:00:02

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Feb 13 05:58:39 paganini overchan[12891]: input line too long, skipping
Feb 13 13:25:58 paganini overchan[12891]: input line too long, skipping
Feb 13 14:29:43 paganini overchan[12891]: input line too long, skipping
Feb 13 20:37:37 paganini overchan[12891]: input line too long, skipping
Feb 13 20:39:09 paganini overchan[12891]: input line too long, skipping
Feb 14 00:00:02 paganini overchan[2764]: 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 93647 59.8%11.6 MB 50.0%
inn 42648 27.2%9.0 MB 38.7%
innd 19826 12.7%2.6 MB 11.1%
nnrpd 379 0.2%54.5 KB 0.2%
nocem 51 0.0%8.7 KB 0.0%
overchan 7 0.0%1.3 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 156559 100.0%23.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 130643 51.0%
Negative hits 83589 32.6%
Do not exist 41859 16.3%
Cache misses 86 0.0%
TOTAL: 4 256177 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.545 0.0%142 0.000 3.838 86.333
article cleanup00:00:00.230 0.0%41931 0.000 0.005 0.030
article logging00:00:00.381 0.0%42526 0.000 0.009 0.054
article parse00:00:01.114 0.0%90806 0.000 0.012 0.037
article write00:00:54.962 0.1%23176 0.152 2.372 15.988
artlog/artcncl00:00:00.001 0.0%138 0.000 0.007 0.091
data move00:00:00.155 0.0%253260 0.000 0.001 0.002
hisgrep/artcncl00:00:00.000 0.0%142 0.000 0.000 0.000
hishave/artcncl00:00:00.004 0.0%142 0.000 0.028 0.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:11.585 0.0%256744 0.019 0.045 0.308
history sync00:00:00.024 0.0%298 0.000 0.081 1.000
history write00:00:36.951 0.0%41931 0.032 0.881 3.736
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:55:59.025 99.7%257720 146.070 334.313 1157.375
nntp read00:00:02.101 0.0%268157 0.003 0.008 0.015
overview write00:00:22.682 0.0%23176 0.033 0.979 5.000
perl filter00:01:29.737 0.1%41931 1.165 2.140 4.495
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.513 0.0%69688 0.004 0.022 0.043
TOTAL: 24:00:23.95223:59:41.010100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.090 0.0%36861 0.000 0.002 0.020
article prepare00:00:00.003 0.0%30068 0.000 0.000 0.006
article read00:00:01.839 0.0%5701 0.000 0.323 20.481
backlog stats00:00:00.207 0.0%352482 0.000 0.001 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.500 0.0%195107 0.001 0.008 0.016
data write00:00:03.035 0.0%172080 0.010 0.018 0.028
idle23:42:24.982 99.3%352482 149.367 242.126 488.886
status file00:10:02.672 0.7%39051 9.361 15.433 33.641
TOTAL: 23:53:04.73323:52:34.328100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle03:16:38.520 99.5%333 29.000 35430.991600128.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.006 0.0%877 0.000 0.007 0.125
readart00:00:00.000 0.0%6 0.000 0.000 0.000
TOTAL: 03:17:34.19203:16:38.526 99.5%----

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 5 50577 19137 29148 2292 37%48:28:14
2newsfeed.neva.ru 149 90448 2712 71594 16142 2%71:45:26
3news.fcku.it 2 8271 1284 6664 323 15%22:42:42
4nntp.pasdenom.info 3 21015 47 20964 4 0%24:06:59
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 184 170311 23180 128370 18761 13%167:03:21
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net57.0 MB0.0 KB13.6 MB70.6 MB 80%3.4 KB
2newsfeed.neva.ru7.2 MB0.0 KB141.7 MB148.9 MB 4%8.1 KB
3news.fcku.it3.0 MB0.0 KB1.5 MB4.5 MB 65%2.8 KB
4nntp.pasdenom.info107.9 KB0.0 KB149.5 KB257.4 KB 41%5.0 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 567.2 MB0.0 KB156.9 MB224.2 MB 29%5.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Feb 13 00:00:01 - 00:59:59 842 3.6% 0.232.5 MB 3.7% 0.70
Feb 13 01:00:00 - 01:59:59 716 3.1% 0.202.2 MB 3.3% 0.64
Feb 13 02:00:00 - 02:59:59 621 2.7% 0.172.0 MB 3.0% 0.57
Feb 13 03:00:00 - 03:59:59 525 2.3% 0.151.8 MB 2.7% 0.51
Feb 13 04:00:00 - 04:59:59 423 1.8% 0.121.4 MB 2.0% 0.39
Feb 13 05:00:00 - 05:59:59 492 2.1% 0.141.5 MB 2.2% 0.43
Feb 13 06:00:00 - 06:59:59 532 2.3% 0.151.9 MB 2.9% 0.55
Feb 13 07:00:00 - 07:59:59 537 2.3% 0.152.1 MB 3.1% 0.59
Feb 13 08:00:00 - 08:59:59 670 2.9% 0.192.0 MB 3.0% 0.58
Feb 13 09:00:00 - 09:59:59 897 3.9% 0.252.5 MB 3.8% 0.72
Feb 13 10:00:00 - 10:59:59 937 4.0% 0.262.6 MB 3.8% 0.73
Feb 13 11:00:00 - 11:59:59 1055 4.6% 0.292.7 MB 4.0% 0.77
Feb 13 12:00:00 - 12:59:59 1057 4.6% 0.292.8 MB 4.2% 0.81
Feb 13 13:00:00 - 13:59:59 1090 4.7% 0.302.8 MB 4.1% 0.79
Feb 13 14:00:00 - 14:59:59 1194 5.2% 0.333.0 MB 4.4% 0.85
Feb 13 15:00:00 - 15:59:59 1120 4.8% 0.313.2 MB 4.8% 0.92
Feb 13 16:00:00 - 16:59:59 1341 5.8% 0.373.9 MB 5.8% 1.11
Feb 13 17:00:00 - 17:59:59 1267 5.5% 0.353.4 MB 5.0% 0.96
Feb 13 18:00:00 - 18:59:59 1471 6.3% 0.414.5 MB 6.7% 1.28
Feb 13 19:00:00 - 19:59:59 1372 5.9% 0.383.8 MB 5.7% 1.09
Feb 13 20:00:00 - 20:59:59 1422 6.1% 0.403.9 MB 5.8% 1.11
Feb 13 21:00:00 - 21:59:59 1249 5.4% 0.353.6 MB 5.3% 1.01
Feb 13 22:00:00 - 22:59:59 1218 5.3% 0.343.8 MB 5.7% 1.08
Feb 13 23:00:00 - 23:59:59 1124 4.8% 0.313.2 MB 4.8% 0.92
Feb 14 00:00:00 - 00:00:02 4 0.0% 2.0014.1 KB 0.0% 7.05
TOTAL: 24:00:01 23176 100.0% 0.2767.1 MB 100.0% 0.80
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 16138 318 0 0 0 0 0 015820
2linuxd-out.news.weretis.net 2841 618 0 0 0 0 0 0 2223
3news.fcku.it 350 25 0 0 0 0 0 0 325
4nntp.pasdenom.info 5 0 0 0 0 0 0 0 5
TOTAL: 4 19334 961 0 0 0 0 0 018373

Unwanted newsgroups [Top 20]:

NewsgroupCount
ut.test 118
it-alt.sport.calcio.juventus 60
lada.talk 54
it.annunci.immobiliari 42
lada.auto 42
it.annunci.commerciali 37
it.annunci.varie 36
gmane.comp.gis.openstreetmap.tagging 35
spline.eisfair 30
mozilla.dev.security.policy 29
gmane.linux.alsa.devel 23
it.comp.os.win.windows10 22
mpc.lists.freebsd.x11 21
alt.survival 20
alt.windows7.general 19
gmane.linux.drivers.devicetree 18
lada.commerce 17
mozilla.dev.platform 15
mozilla.support.firefox 15
mpc.lists.nanog 15
TOTAL: 136 961

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 15189
EMP (phn path) 1857
EMP (phl) 468
EMP (phn nph) 131
Too many newsgroups 66
Binary Payload 24
User-issued cancel 15
Binary: non-binary distribution 7
Binary Image: misplaced jpg 5
Too many newsgroups (meow) 5
Binary: misplaced par2 4
HTML post 3
User-issued spam cancel 2
HTML file attachment 1
TOTAL: 14 17777

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 554
newsfeed.neva.ru 33
news.fcku.it 9
TOTAL: 3 596
TOTAL: 1 596

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nntp.pasdenom.info 40936 2194 20544 362 0 623 5%23:57:38
2news.fcku.it 26633 1817 17744 2259 0 20 6%23:59:41
3weretis.net 4430 388 3697 1 0 14 8%23:59:45
4neva.ru 30251 221 20002 248 0 107 0%23:59:44
5eternal-september 25961 61 19735 1 0 34 0%23:59:32
6news.stack.nl 2849 0 0 0 0 26014 0%23:59:44
7news.albasani.net 1784 0 0 0 0 24210 0%23:59:44
8vc89.vc.panix.com 2838 0 0 0 0 26013 0%23:59:44
9news.netfront.net 2738 0 0 0 0 25836 0%23:59:43
10etla.org 0 0 0 0 0 23176 0%23:59:45
11news.mixmin.net 0 0 0 0 0 0 0%00:10:30
12news.killfile.org 2848 0 0 0 0 26023 0%23:59:44
TOTAL: 12 141268 4681 81722 2871 0 152070 3%264:05:14
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nntp.pasdenom.info6.9 MB921.4 KB7.8 MB0.1 KB/s3.1 KB23:57:38
2news.fcku.it5.2 MB6.8 MB11.9 MB0.1 KB/s3.0 KB23:59:41
3weretis.net721.1 KB5.0 KB726.2 KB0.0 KB/s1.9 KB23:59:45
4neva.ru560.7 KB569.5 KB1.1 MB0.0 KB/s2.4 KB23:59:44
5eternal-september275.8 KB1.5 KB277.3 KB0.0 KB/s4.5 KB23:59:32
6news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:44
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.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:43
10etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:45
11news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:30
12news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:44
TOTAL: 1213.5 MB8.2 MB21.8 MB0.0 KB/s3.0 KB264:05:14
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1challenger.nielsenmedia.com 4 36.0 KB 1 0 001:00:24
247-192-187-101.drr02.clwr.fl.frontiernet.net 15 36.0 KB 1 0 002:16:11
TOTAL: 2 19 611.9 KB 2 0 003:17:33

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.nielsenmedia.com 4 36.0 KB 1 0 001:00:24
2*.drr02.clwr.fl.frontiernet.net 15 36.0 KB 1 0 002:16:11
3*.188.110.pools.vodafone-ip.de 94 00.0 KB 0 0 000:00:54
4? 1 00.0 KB 0 0 000:00:03
TOTAL: 4 114 611.9 KB 2 0 003:17:33

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
47-192-187-101.drr02.clwr.fl.frontiernet.net 0.952 0.052 0.00002:16:11
challenger.nielsenmedia.com 0.280 0.016 0.00001:00:24
dslb-188-110-184-251.188.110.pools.vodafone-ip.de 5.012 0.364 0.00000:00:54
2607:ff10::c0:1:1:10d 0.048 0.008 0.00000:00:03
TOTAL: 4 6.292 0.440 0.00003:17:33

Curious NNRP server explorers [Top 20]:

SystemConn
dslb-188-110-184-251.188.110.pools.vodafone-ip.de 94
2607:ff10::c0:1:1:10d 1
TOTAL: 2 95

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1comp 6100.0%
TOTAL: 1 6100.0%

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

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