Daily Usenet report for news.niel.me

Dec 9 04:15:01 -- Dec 10 04:15:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Dec 9 22:19:22 rpi-studio innd: filter: No Cancel-Key[sha1:ccGguRuzwLzoaHQIiDX3ZeLKRgM= sha256:dvDMakW8byZQ85l9f7XzW7ZIOiLjwhB7YCBLh7IUDHs=] matches Cancel-Lock[sha1:guKuWCfzk1bqKIm2tEW62cVAm0Y= sha256:Ou3yYITxfqu27spxObih1B43NAqtTGgbvkcnGg2BUnM=] target=<ul2lhs$1hsb$1@nnrp.usenet.blueworldhosting.com>
Dec 9 23:06:40 rpi-studio innd: filter: No Cancel-Key[sha1:0FOOko4AkkGdzhq9GMA5n3a/Xn8= sha256:8gfgXILZJxz3z+b4/LtNntCOs5MY9f3078UPhKwTX64=] matches Cancel-Lock[sha1:DSFX1Be9Ib15xpOD7m0rqB0wQ7c= sha256:fcXfR1pEWnWVysAFMRStqTpw1qVk7PFq+hY1mZfRv+Y=] target=<ul2oc9$1hsb$4@nnrp.usenet.blueworldhosting.com>

Log entries by program:

Program nameLines%LinesSize%Size
inn 55686 79.8%10.8 MB 85.5%
innd 9807 14.1%1.3 MB 9.9%
nnrpd 2139 3.1%235.8 KB 1.8%
innfeed 2101 3.0%358.7 KB 2.8%
nocem 11 0.0%1.3 KB 0.0%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 6 69745 100.0%12.7 MB100.0%

History cache:

ReasonCount%Count
Positive hits 107397 46.4%
Negative hits 67597 29.2%
Do not exist 54827 23.7%
Cache misses 1568 0.7%
TOTAL: 4 231389 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.221 0.0%197 0.000 1.122 6.000
article cancel00:00:00.032 0.0%207 0.000 0.155 1.000
article cleanup00:00:01.131 0.0%54940 0.004 0.021 0.037
article logging00:00:04.188 0.0%54941 0.021 0.076 0.111
article parse00:00:16.632 0.0%313756 0.034 0.053 0.072
article write00:00:56.146 0.1%47434 0.747 1.184 2.589
artlog/artcncl00:00:00.006 0.0%207 0.000 0.029 1.000
artlog/artcncl/perl00:00:00.017 0.0%197 0.000 0.086 1.000
data move00:00:03.074 0.0%572693 0.001 0.005 0.008
hisgrep/artcncl00:00:00.003 0.0%207 0.000 0.014 0.500
hisgrep/artcncl/perl00:00:00.009 0.0%197 0.000 0.046 1.000
hisgrep/perl00:00:00.008 0.0%200 0.000 0.040 1.000
hishave/artcncl00:00:00.001 0.0%207 0.000 0.005 0.500
hishave/artcncl/perl00:00:00.004 0.0%197 0.000 0.020 0.250
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.197 0.0%231786 0.010 0.018 0.030
history sync00:00:00.055 0.0%360 0.000 0.153 1.333
history write00:00:12.183 0.0%54940 0.139 0.222 0.321
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:43:18.072 99.5%488696 99.122 174.747 291.423
nntp read00:00:14.264 0.0%488588 0.020 0.029 0.046
overview write00:00:15.387 0.0%47434 0.203 0.324 2.124
perl filter00:03:55.403 0.3%54940 3.152 4.285 5.656
python filter00:00:00.273 0.0%54940 0.000 0.005 0.016
site send00:00:12.617 0.0%96493 0.035 0.131 0.240
TOTAL: 23:50:49.87823:49:33.923 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.457 0.0%48320 0.000 0.009 0.027
article prepare00:00:00.103 0.0%24424 0.000 0.004 0.036
article read00:00:00.685 0.0%4635 0.000 0.148 1.000
backlog stats00:00:03.389 0.0%745040 0.002 0.005 0.008
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:12.085 0.0%508044 0.013 0.024 0.042
data write00:00:19.878 0.0%400599 0.034 0.050 0.063
idle23:50:19.224 99.9%745040 67.386 115.187 199.944
status file00:00:00.516 0.0%219 0.500 2.356 7.000
TOTAL: 23:51:31.42523:50:56.337100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.028 0.0%845 0.018 0.033 0.049
idle00:00:52.363 5.5%1550 26.000 33.783 1081.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.212 0.0%5217 0.000 0.041 1.000
TOTAL: 00:15:57.65500:00:52.603 5.5%----

Control commands to INND:

CommandNumber
flush 5
flushlogs 2
go 2
logmode 2
lowmark 1
mode 148
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 170

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news.nntp4.net 13 54180 25319 22714 6147 46%25:31:56
2news.glou.org 7 27296 16980 9369 947 62%24:55:17
3nntp.terraraq.uk 3 19153 2948 15836 369 15%24:15:46
4feed-out.gegeweb.org 3 3721 1059 2662 0 28%23:57:53
5usenet.pasdenom.info 3 46429 643 45769 17 1%45:41:41
6i2pn.org 3 8138 480 7658 0 5%46:42:59
7gegeweb-out.news.tnetconsulting.net 1 3406 376 2956 74 11%24:29:49
8news.samoylyk.net 1 24 1 23 0 4%00:10:19
9localhost 26 0 0 0 0 0%00:00:00
TOTAL: 9 60 162347 47806 106987 7554 29%215:45:40
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.nntp4.net269.3 MB0.0 KB55.5 MB324.8 MB 82%10.6 KB
2news.glou.org149.7 MB0.0 KB6.0 MB155.7 MB 96%8.9 KB
3nntp.terraraq.uk22.1 MB0.0 KB2.4 MB24.6 MB 90%7.6 KB
4feed-out.gegeweb.org13.5 MB0.0 KB0.0 KB13.5 MB100%13.0 KB
5i2pn.org10.6 MB0.0 KB0.0 KB10.6 MB100%22.6 KB
6usenet.pasdenom.info3.0 MB0.0 KB23.6 KB3.0 MB 99%4.7 KB
7gegeweb-out.news.tnetconsulting.net1.0 MB0.0 KB233.1 KB1.2 MB 81%2.8 KB
8news.samoylyk.net14.7 KB0.0 KB0.0 KB14.7 KB100%14.7 KB
9localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 9469.2 MB0.0 KB64.2 MB533.4 MB 87%9.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Dec 9 04:15:01 - 04:59:59 1112 2.3% 0.4111.4 MB 2.4% 4.33
Dec 9 05:00:00 - 05:59:59 1535 3.2% 0.4315.4 MB 3.3% 4.38
Dec 9 06:00:00 - 06:59:59 1916 4.0% 0.5321.3 MB 4.6% 6.06
Dec 9 07:00:00 - 07:59:59 2287 4.8% 0.6425.2 MB 5.4% 7.18
Dec 9 08:00:00 - 08:59:59 2208 4.6% 0.6123.0 MB 4.9% 6.53
Dec 9 09:00:00 - 09:59:59 2143 4.5% 0.6022.3 MB 4.8% 6.33
Dec 9 10:00:00 - 10:59:59 2508 5.3% 0.7025.1 MB 5.4% 7.13
Dec 9 11:00:00 - 11:59:59 2786 5.8% 0.7728.8 MB 6.2% 8.18
Dec 9 12:00:00 - 12:59:59 2166 4.5% 0.6021.4 MB 4.6% 6.08
Dec 9 13:00:00 - 13:59:59 2329 4.9% 0.6523.6 MB 5.1% 6.72
Dec 9 14:00:00 - 14:59:59 2531 5.3% 0.7026.9 MB 5.8% 7.65
Dec 9 15:00:00 - 15:59:59 2250 4.7% 0.6224.1 MB 5.2% 6.85
Dec 9 16:00:00 - 16:59:59 1912 4.0% 0.5319.6 MB 4.2% 5.58
Dec 9 17:00:00 - 17:59:59 1976 4.1% 0.5519.3 MB 4.1% 5.49
Dec 9 18:00:00 - 18:59:59 1639 3.4% 0.4615.9 MB 3.4% 4.52
Dec 9 19:00:00 - 19:59:59 2001 4.2% 0.5619.8 MB 4.3% 5.63
Dec 9 20:00:00 - 20:59:59 1483 3.1% 0.4113.4 MB 2.9% 3.81
Dec 9 21:00:00 - 21:59:59 1835 3.8% 0.5118.0 MB 3.9% 5.11
Dec 9 22:00:00 - 22:59:59 1889 4.0% 0.5216.2 MB 3.5% 4.60
Dec 9 23:00:00 - 23:59:59 1899 4.0% 0.5316.2 MB 3.5% 4.62
Dec 10 00:00:00 - 00:59:59 1911 4.0% 0.5315.8 MB 3.4% 4.48
Dec 10 01:00:00 - 01:59:59 1648 3.5% 0.4612.8 MB 2.7% 3.64
Dec 10 02:00:00 - 02:59:59 1610 3.4% 0.4513.0 MB 2.8% 3.70
Dec 10 03:00:00 - 03:59:59 1655 3.5% 0.4613.7 MB 2.9% 3.89
Dec 10 04:00:00 - 04:15:01 498 1.0% 0.554.2 MB 0.9% 4.72
TOTAL: 24:00:00 47727 100.0% 0.55466.1 MB 100.0% 5.52
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 6145 31 0 0 0 0 0 0 6114
2news.glou.org 950 0 0 0 0 0 0 0 950
3nntp.terraraq.uk 369 0 0 0 0 0 0 0 369
4gegeweb-out.news.tnetconsulting.net 68 0 0 0 0 0 0 0 68
5usenet.pasdenom.info 22 1 0 0 0 0 0 0 21
6feed-out.gegeweb.org 1 0 0 0 0 0 0 0 1
TOTAL: 6 7555 32 0 0 0 0 0 0 7523

Unwanted newsgroups [Top 20]:

NewsgroupCount
fr.education.entraide.maths 16
fr.misc.gestion 9
fr.education.superieur 4
fr.lettres.langue.allemande 1
fr.petites-annonces.informatique.materiel 1
comp.lang.go 1
TOTAL: 6 32

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 6931
Too many newsgroups 275
EMP (phl) 163
Bad path (news.giganews.com.POSTED) 74
EMP (phn path) 63
User-issued spam cancel 10
Cancel for rejected article 2
No Cancel-Key matches Cancel-Lock. target=<ul2oc9$1hsb$4@nnrp.usenet.blueworldhosting.com> 1
Cancels abusifs 1
No Cancel-Key matches Cancel-Lock. target=<ul2lhs$1hsb$1@nnrp.usenet.blueworldhosting.com> 1
Cancel of non-existing ID <dd744fb7adfc77bab55ad1ecb401df82@msgid.frell.theremailer.net> 1
TOTAL: 11 7522

NoCeM on Spool:

IdGoodBadUniqueTotal
bleachbot@httrack.com1001010
TOTAL: 11001010

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
feed-out.gegeweb.org 1
TOTAL: 1 1
TOTAL: 1 1

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nntp.terraraq.uk 53261 816 42103 490 0 174 1%23:59:54
2news.nntp4.net 31606 544 21923 1 0 0 1%23:59:54
3i2pn.org 71929 142 44080 2942 0 586 0%23:59:53
4news.gegeweb.org 47963 32 46617 2 0 0 0%23:59:54
5news.tnetconsulting.net 71641 25 47233 467 0 57 0%23:59:52
6news.samoylyk.net 70704 15 47153 519 0 27 0%23:59:54
7news.pasdenom.info 55140 1 46979 124 0 8 0%23:59:54
8news.aioe.org 0 0 0 0 0 9830 0%23:59:54
TOTAL: 8 402244 1575 296088 4545 0 10682 0%191:59:09
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nntp.terraraq.uk7.4 MB5.2 MB12.6 MB0.1 KB/s9.9 KB23:59:54
2news.nntp4.net5.5 MB1.9 KB5.5 MB0.1 KB/s10.3 KB23:59:54
3news.gegeweb.org955.3 KB8.3 KB963.7 KB0.0 KB/s28.3 KB23:59:54
4i2pn.org896.8 KB27.7 MB28.6 MB0.3 KB/s9.5 KB23:59:53
5news.tnetconsulting.net125.7 KB4.4 MB4.5 MB0.1 KB/s9.4 KB23:59:52
6news.samoylyk.net47.9 KB5.0 MB5.1 MB0.1 KB/s9.7 KB23:59:54
7news.pasdenom.info2.2 KB5.1 MB5.1 MB0.1 KB/s41.8 KB23:59:54
8news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
TOTAL: 814.9 MB47.4 MB62.3 MB0.1 KB/s10.4 KB191:59:09
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1172-105-246-139.ip.linodeusercontent.com 103 00.0 KB 0 0 000:00:23
2valiant.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
3luminous.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
4kind.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:06
5eloquent.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
6mail.gegeweb.eu 288 00.0 KB 0 0 000:01:49
72607:ff10:c8:594::e 3 00.0 KB 0 0 000:00:08
8gracious.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
9happy.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
1089.149.23.89 8 00.0 KB 0 0 000:13:29
TOTAL: 10 408 00.0 KB 0 0 000:15:57

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1? 3 00.0 KB 0 0 000:00:08
2unresolved 8 00.0 KB 0 0 000:13:29
3*.monitoring.internet-measurement.com 6 00.0 KB 0 0 000:00:06
4*.gegeweb.eu 288 00.0 KB 0 0 000:01:49
5*.ip.linodeusercontent.com 103 00.0 KB 0 0 000:00:23
TOTAL: 5 408 00.0 KB 0 0 000:15:57

NNRP auth users [Top 20]:

UserConn
mailman 288
TOTAL: 1 288

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
89.149.23.89 0.464 0.292 0.00000:13:29
mail.gegeweb.eu 4.533 4.186 1.55500:01:49
172-105-246-139.ip.linodeusercontent.com 2.591 1.465 0.00000:00:23
2607:ff10:c8:594::e 0.048 0.071 0.00000:00:08
kind.monitoring.internet-measurement.com 0.044 0.020 0.00000:00:06
luminous.monitoring.internet-measurement.com 0.012 0.016 0.00000:00:00
gracious.monitoring.internet-measurement.com 0.024 0.005 0.00000:00:00
happy.monitoring.internet-measurement.com 0.036 0.020 0.00000:00:00
TOTAL: 8 7.752 6.075 1.55500:15:57

NNRP gethostbyaddr failures [Top 20]:

SystemConn
? (can't getpeername) 5
TOTAL: 1 5

NNRP unrecognized commands (by host):

SystemConn
2607:ff10:c8:594::e 7
TOTAL: 1 7

NNRP unrecognized commands (by command):

CommandCount
Accept: */* 1
#026#003#001 1
Accept-Encoding: gzip 1
#023#003#023#001#023#002#001 1
Host: [2a0e:e701:1154:0:4e10:5c89:4af0:d... 1
GET / HTTP/1.1 1
User-Agent: Mozilla/5.0 (Windows NT 10.0... 1
TOTAL: 7 7
innreport from INN 2.6.4 based on work (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>
Local contact: usenet@niel.me