Daily Usenet report for news.niel.me

May 16 04:15:02 -- May 17 04:15:02

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

May 16 16:49:55 rpi-studio innd: filter: No Cancel-Key[sha256:NQ73myu2vdNSGmHcTLcFSSpyKwekwQG96ZsIjnrDpXA=] matches Cancel-Lock[sha1:zgGjkkxz3c9Hvd8zUoTPrxCBw4A= sha1:FTj8VTcKM8XfZ5Uf5fCbiMvaB5M=] target=<1ps23wx.ldzqfm62ohwgN%gd2512@free.fr>
May 16 17:10:18 rpi-studio innd: filter: No Cancel-Key[sha1:Ddg3Kp7pYP2L4ZALXSKnlkzaECc=] matches Cancel-Lock[sha1:zgGjkkxz3c9Hvd8zUoTPrxCBw4A=] target=<1ps24v5.1eyw2ob1de0ikkN%mv@gmail.com.invalid>

Log entries by program:

Program nameLines%LinesSize%Size
inn 4346 44.0%830.8 KB 49.5%
innd 2349 23.8%391.9 KB 23.4%
innfeed 1721 17.4%304.2 KB 18.1%
nnrpd 1462 14.8%151.2 KB 9.0%
nocem 2 0.0%0.1 KB 0.0%
controlchan 1 0.0%0.0 KB 0.0%
TOTAL: 6 9881 100.0%1.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 24829 63.2%
Negative hits 10099 25.7%
Do not exist 4249 10.8%
Cache misses 93 0.2%
TOTAL: 4 39270 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.431 0.0%31 0.000 13.903 193.500
article cancel00:00:00.006 0.0%32 0.000 0.188 1.000
article cleanup00:00:00.078 0.0%4259 0.000 0.018 0.182
article logging00:00:00.343 0.0%4287 0.000 0.080 1.364
article parse00:00:00.811 0.0%8850 0.000 0.092 0.312
article write00:00:03.252 0.0%3856 0.227 0.843 5.971
artlog/artcncl00:00:00.000 0.0%32 0.000 0.000 0.000
artlog/artcncl/perl00:00:00.002 0.0%31 0.000 0.065 1.000
data move00:00:00.110 0.0%43371 0.000 0.003 0.013
hisgrep/artcncl00:00:00.000 0.0%31 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.001 0.0%31 0.000 0.032 0.250
hisgrep/perl00:00:00.002 0.0%38 0.000 0.053 0.400
hishave/artcncl00:00:00.001 0.0%32 0.000 0.031 0.500
hishave/artcncl/perl00:00:00.000 0.0%31 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.530 0.0%39306 0.000 0.013 0.043
history sync00:00:00.048 0.0%407 0.000 0.118 1.667
history write00:00:00.977 0.0%4259 0.000 0.229 0.522
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle24:00:22.289 99.9%43718 807.747 1976.813 8343.167
nntp read00:00:01.337 0.0%43385 0.007 0.031 0.073
overview write00:00:02.791 0.0%3856 0.083 0.724 4.833
perl filter00:00:19.865 0.0%4259 2.870 4.664 18.231
python filter00:00:00.008 0.0%4259 0.000 0.002 0.062
site send00:00:01.371 0.0%7795 0.000 0.176 0.692
TOTAL: 24:01:07.01624:00:54.253100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.044 0.0%3863 0.000 0.011 0.083
article prepare00:00:00.000 0.0%68 0.000 0.000 0.000
article read00:00:00.009 0.0%16 0.000 0.562 7.000
backlog stats00:00:00.103 0.0%54670 0.000 0.002 0.011
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.779 0.0%34075 0.000 0.023 0.083
data write00:00:01.926 0.0%30318 0.020 0.064 0.113
idle23:49:50.978100.0%54670 690.708 1569.251 4730.780
status file00:00:00.425 0.0%175 1.000 2.429 6.000
TOTAL: 23:49:57.91523:49:54.264100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:01:02.457 51.6%1446 16.000 43.193 10046.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.122 0.1%3182 0.000 0.038 0.500
TOTAL: 00:02:00.94900:01:02.579 51.7%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news-out.aioe.org 2 3706 1903 1759 44 51%21:51:56
2i2pn.org 2 4167 1124 2963 80 26%25:01:52
3feed-out.gegeweb.org 2 2560 324 2236 0 12%23:59:49
4usenet.pasdenom.info 3 2791 210 2579 2 7%23:24:25
5news.nntp4.net 2 8196 109 7798 289 1%46:29:38
6gegeweb-out.news.tnetconsulting.net 2 3743 65 3675 3 1%23:59:42
7news.samoylyk.net 2 7544 61 7472 11 0%45:50:15
8nntp.terraraq.uk 1 670 22 628 20 3%05:43:50
9localhost 26 0 0 0 0 0%00:00:00
TOTAL: 9 42 33377 3818 29110 449 11%216:21:27
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news-out.aioe.org6.8 MB0.0 KB165.2 KB7.0 MB 97%3.7 KB
2news.nntp4.net5.1 MB0.0 KB5.8 MB10.9 MB 46%28.2 KB
3i2pn.org3.4 MB0.0 KB308.8 KB3.7 MB 91%3.1 KB
4news.samoylyk.net1.8 MB145.7 KB237.8 KB2.1 MB 82%30.4 KB
5feed-out.gegeweb.org1.3 MB0.0 KB0.0 KB1.3 MB100%4.1 KB
6gegeweb-out.news.tnetconsulting.net918.3 KB141.3 KB143.8 KB1.2 MB 76%17.7 KB
7usenet.pasdenom.info648.9 KB0.0 KB14.2 KB663.1 KB 97%3.1 KB
8nntp.terraraq.uk390.6 KB0.0 KB136.7 KB527.3 KB 74%12.6 KB
9localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 920.3 MB287.0 KB6.8 MB27.4 MB 74%6.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 16 04:15:02 - 04:59:59 79 2.1% 0.03452.9 KB 2.6% 0.17
May 16 05:00:00 - 05:59:59 62 1.6% 0.02346.1 KB 2.0% 0.10
May 16 06:00:00 - 06:59:59 96 2.5% 0.03539.4 KB 3.1% 0.15
May 16 07:00:00 - 07:59:59 124 3.2% 0.03458.9 KB 2.7% 0.13
May 16 08:00:00 - 08:59:59 141 3.7% 0.04380.1 KB 2.2% 0.11
May 16 09:00:00 - 09:59:59 111 2.9% 0.03341.2 KB 2.0% 0.09
May 16 10:00:00 - 10:59:59 110 2.9% 0.03385.5 KB 2.2% 0.11
May 16 11:00:00 - 11:59:59 145 3.8% 0.04438.4 KB 2.6% 0.12
May 16 12:00:00 - 12:59:59 124 3.2% 0.03477.7 KB 2.8% 0.13
May 16 13:00:00 - 13:59:59 151 3.9% 0.04558.8 KB 3.3% 0.16
May 16 14:00:00 - 14:59:59 125 3.2% 0.03482.8 KB 2.8% 0.13
May 16 15:00:00 - 15:59:59 138 3.6% 0.04399.9 KB 2.3% 0.11
May 16 16:00:00 - 16:59:59 197 5.1% 0.05738.2 KB 4.3% 0.21
May 16 17:00:00 - 17:59:59 252 6.5% 0.071.2 MB 7.1% 0.34
May 16 18:00:00 - 18:59:59 200 5.2% 0.06758.6 KB 4.4% 0.21
May 16 19:00:00 - 19:59:59 249 6.5% 0.07912.2 KB 5.3% 0.25
May 16 20:00:00 - 20:59:59 249 6.5% 0.071.7 MB 9.9% 0.47
May 16 21:00:00 - 21:59:59 181 4.7% 0.051.2 MB 6.9% 0.33
May 16 22:00:00 - 22:59:59 223 5.8% 0.06709.5 KB 4.1% 0.20
May 16 23:00:00 - 23:59:59 235 6.1% 0.07765.6 KB 4.5% 0.21
May 17 00:00:00 - 00:59:59 250 6.5% 0.071.9 MB 11.3% 0.54
May 17 01:00:00 - 01:59:59 130 3.4% 0.04530.5 KB 3.1% 0.15
May 17 02:00:00 - 02:59:59 118 3.1% 0.03588.3 KB 3.4% 0.16
May 17 03:00:00 - 03:59:59 129 3.3% 0.04704.3 KB 4.1% 0.20
May 17 04:00:00 - 04:15:02 34 0.9% 0.04135.6 KB 0.8% 0.15
TOTAL: 24:00:00 3853 100.0% 0.0416.7 MB 100.0% 0.20
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 171 1 0 0 0 0 0 0 170
2nntp.terraraq.uk 94 0 0 0 0 0 0 0 94
3i2pn.org 80 0 0 0 0 0 0 0 80
4news-out.aioe.org 74 1 0 0 0 0 0 0 73
5news.samoylyk.net 4 0 0 0 0 0 0 0 4
6gegeweb-out.news.tnetconsulting.net 3 1 0 0 0 0 0 0 2
7usenet.pasdenom.info 3 0 0 0 0 0 0 0 3
8feed-out.gegeweb.org 1 0 0 0 0 0 0 0 1
TOTAL: 8 430 3 0 0 0 0 0 0 427

Unwanted newsgroups [Top 20]:

NewsgroupCount
soc.culture.ukraine 2
news.test 1
TOTAL: 2 3

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 163
EMP (phn nph) 102
Bad path (news.giganews.com.POSTED) 87
EMP (phl) 32
Too many newsgroups 8
Cancel of non-existing ID <5fe046b8-9b41-4420-b6e5-69a6b013ccfen@googlegroups.com> 1
Cancel of non-existing ID <62822f2a$0$22060$426a74cc@news.free.fr> 1
Cancel of non-existing ID <4ec39cb2-427e-4cde-acc3-a2233efa476bn@googlegroups.com> 1
No Cancel-Key matches Cancel-Lock. target=<1ps24v5.1eyw2ob1de0ikkN%mv@gmail.com.invalid> 1
No Cancel-Key matches Cancel-Lock. target=<1ps23wx.ldzqfm62ohwgN%gd2512@free.fr> 1
Cancel of non-existing ID <2bfecaf0-2218-482c-ac0d-751a1c770d3cn@googlegroups.com> 1
Cancel of non-existing ID <ce4960e1-0e0c-41a5-8f12-7f2ca2d1492bn@googlegroups.com> 1
TOTAL: 12 399

NoCeM on Spool:

IdGoodBadUniqueTotal
bleachbot@httrack.com1011
TOTAL: 11011

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news-out.aioe.org 27
feed-out.gegeweb.org 1
TOTAL: 2 28
TOTAL: 1 28

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tnetconsulting.net 6343 7 3846 0 0 11 0%23:58:38
2i2pn.org 5224 6 2721 0 0 17 0%23:58:13
3news.gegeweb.org 3576 2 3532 2 0 0 0%23:59:57
4nntp.terraraq.uk 2783 0 2732 0 0 0 0%23:59:41
5news.aioe.org 1428 0 1372 0 0 0 0%23:59:07
6news.samoylyk.net 3930 0 3825 0 0 0 0%23:59:56
7news.nntp4.net 3936 0 3790 0 0 0 0%23:59:50
8news.pasdenom.info 3503 0 3431 0 0 2 0%23:59:57
TOTAL: 8 30723 15 25249 2 0 30 0%191:55:19
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1i2pn.org26.9 KB0.0 KB26.9 KB0.0 KB/s4.5 KB23:58:13
2news.tnetconsulting.net19.7 KB0.0 KB19.7 KB0.0 KB/s2.8 KB23:58:38
3news.gegeweb.org5.3 KB8.1 KB13.4 KB0.0 KB/s3.4 KB23:59:57
4nntp.terraraq.uk0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:41
5news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:07
6news.samoylyk.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
7news.nntp4.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:50
8news.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
TOTAL: 851.9 KB8.1 KB60.0 KB0.0 KB/s3.5 KB191:55:19
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mail.gegeweb.eu 288 00.0 KB 0 0 000:01:26
2159.65.179.88 1 00.0 KB 0 0 000:00:10
3192.241.223.27 1 00.0 KB 0 0 000:00:09
423.94.212.6 1 00.0 KB 0 0 000:00:13
588.165.5.23 1 00.0 KB 0 0 000:00:00
TOTAL: 5 292 00.0 KB 0 0 000:02:00

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 4 00.0 KB 0 0 000:00:34
2*.gegeweb.eu 288 00.0 KB 0 0 000:01:26
TOTAL: 2 292 00.0 KB 0 0 000:02:00

NNRP auth users [Top 20]:

UserConn
mailman 288
TOTAL: 1 288

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
mail.gegeweb.eu 7.617 8.405 2.31300:01:26
23.94.212.6 0.028 0.000 0.00000:00:13
159.65.179.88 0.018 0.036 0.00000:00:10
192.241.223.27 0.033 0.022 0.00000:00:09
88.165.5.23 0.031 0.021 0.00000:00:00
TOTAL: 5 7.727 8.484 2.31300:02:00