Daily Usenet report for news.niel.me

Aug 29 04:15:01 -- Aug 30 04:15:02

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Aug 29 10:34:15 rpi-studio innd: filter: No Cancel-Key[sha256:O] matches Cancel-Lock[sha256:oANLLtt1HRoz8lB5iVJg4hdx87CVWrpxiEpnSsAa8tw=] target=<6a9298cc$0$28059$426a74cc@news.free.fr>
Aug 29 10:50:38 rpi-studio innd: filter: No Cancel-Key[sha256:L/yk5NsEWVJS6JDCTZ24kf065jA1Ec35FMdy4xcv8WY=] matches Cancel-Lock[sha256:sADd8Any8skzQA6e9FWnMdhkovQNFn403VPcFXiGN5k=] target=<6a929cfa$0$7069$426a74cc@news.free.fr>
Aug 29 10:56:50 rpi-studio innd: filter: No Cancel-Key[sha256:kRVdfWlDllh6WkUK9Z6sjxdNPA6JGlNV98YG7DGE0KQ=] matches Cancel-Lock[sha256:CMsDNZfCIKi+NpmeceQhiaMzqhPxbfHeEFdHmohJXxw=] target=<6a929e0e$0$10314$426a74cc@news.free.fr>
Aug 29 14:03:14 rpi-studio innd: filter: No Cancel-Key[sha256:O#015#012#011sha1:714S7/tlhNS+MLuOaTxNtAKY7Kc= sha256:k7p3KAbGQr0uVF3OUOokXG/+iTULKkzgL8REz/2H3Gk=] matches Cancel-Lock[sha256:oANLLtt1HRoz8lB5iVJg4hdx87CVWrpxiEpnSsAa8tw=] target=<1s0lw6s.koqn4g12xemciN%g4fleurot@free.invalid>
Aug 29 14:06:25 rpi-studio innd: filter: No Cancel-Key[sha1:714S7/tlhNS+MLuOaTxNtAKY7Kc= sha256:k7p3KAbGQr0uVF3OUOokXG/+iTULKkzgL8REz/2H3Gk=] matches Cancel-Lock[sha256:oANLLtt1HRoz8lB5iVJg4hdx87CVWrpxiEpnSsAa8tw=] target=<1s0lw6s.koqn4g12xemciN%g4fleurot@free.invalid>
Aug 29 14:10:56 rpi-studio innd: filter: No Cancel-Key[sha256:O#015#012#011sha1:714S7/tlhNS+MLuOaTxNtAKY7Kc= sha256:k7p3KAbGQr0uVF3OUOokXG/+iTULKkzgL8REz/2H3Gk=] matches Cancel-Lock[sha256:oANLLtt1HRoz8lB5iVJg4hdx87CVWrpxiEpnSsAa8tw=] target=<1s0lw6s.koqn4g12xemciN%g4fleurot@free.invalid>
Aug 29 16:10:19 rpi-studio innd: filter: No Cancel-Key[sha1:dVZGvd9cCCmY4oTB4deMWOAXg0s= sha256:2BbsiTQN8/FdOLgeraKqLcz16yo9Pm3wP3yghGqifZg=] matches Cancel-Lock[sha1:3A7bR5gclV44wYIAKPMatVebDck= sha256:aBQ50a5PeaOcB6lGkf27b9Xg6//agmExLtNa9zmcfiw=] target=<brp59l12km6thu3iejgg1ed73h4g7gqcgd@kgbh.org>
Aug 29 16:10:27 rpi-studio innd: filter: No Cancel-Key[sha1:dVZGvd9cCCmY4oTB4deMWOAXg0s= sha256:2BbsiTQN8/FdOLgeraKqLcz16yo9Pm3wP3yghGqifZg=] matches Cancel-Lock[sha1:3A7bR5gclV44wYIAKPMatVebDck= sha256:aBQ50a5PeaOcB6lGkf27b9Xg6//agmExLtNa9zmcfiw=] target=<brp59l12km6thu3iejgg1ed73h4g7gqcgd@kgbh.org>

Log entries by program:

Program nameLines%LinesSize%Size
innd 2052 26.5%345.6 KB 29.0%
inn 1924 24.8%343.7 KB 28.9%
innfeed 1898 24.5%292.4 KB 24.5%
nnrpd 1869 24.1%209.4 KB 17.6%
nocem 1 0.0%0.1 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 7745 100.0%1.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 7724 51.6%
Negative hits 5353 35.7%
Do not exist 1903 12.7%
Cache misses 1 0.0%
TOTAL: 4 14981 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.016 0.0%9 0.000 1.778 3.500
article cancel00:00:00.003 0.0%9 0.000 0.333 1.000
article cleanup00:00:00.054 0.0%1912 0.000 0.028 0.200
article logging00:00:00.143 0.0%1913 0.000 0.075 0.333
article parse00:00:00.256 0.0%4702 0.000 0.054 0.250
article write00:00:00.833 0.0%1655 0.000 0.503 1.286
artlog/artcncl00:00:00.000 0.0%9 0.000 0.000 0.000
artlog/artcncl/perl00:00:00.002 0.0%9 0.000 0.222 1.000
data move00:00:00.092 0.0%16439 0.000 0.006 0.058
hisgrep/artcncl00:00:00.000 0.0%9 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.000 0.0%9 0.000 0.000 0.000
hisgrep/perl00:00:00.001 0.0%18 0.000 0.056 0.500
hishave/artcncl00:00:00.000 0.0%9 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.000 0.0%9 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.253 0.0%15043 0.000 0.017 0.053
history sync00:00:00.048 0.0%337 0.000 0.142 1.000
history write00:00:00.339 0.0%1912 0.000 0.177 0.591
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle24:00:28.570100.0%18227 1583.396 4741.788 50036.583
nntp read00:00:00.464 0.0%17473 0.000 0.027 0.115
overview write00:00:00.526 0.0%1655 0.000 0.318 2.333
perl filter00:00:11.925 0.0%1912 2.091 6.237 25.667
python filter00:00:00.006 0.0%1912 0.000 0.003 0.167
site send00:00:00.760 0.0%3338 0.000 0.228 0.750
TOTAL: 24:01:05.84624:00:44.291100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.019 0.0%1657 0.000 0.011 1.000
article prepare00:00:00.000 0.0%16 0.000 0.000 0.000
article read00:00:00.008 0.0%4 0.000 2.000 8.000
backlog stats00:00:00.119 0.0%21381 0.000 0.006 0.029
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.294 0.0%10213 0.000 0.029 0.120
data write00:00:00.501 0.0%8619 0.000 0.058 0.150
idle23:59:41.035100.0%21381 1823.647 4040.084 10361.431
status file00:00:08.116 0.0%330 1.000 24.594 84.000
TOTAL: 23:59:54.36523:59:50.092100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:39.739 59.2%1487 10.600 26.724 3911.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.105 0.2%3366 0.000 0.031 0.400
TOTAL: 00:01:07.07800:00:39.844 59.4%----

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
1feed-out.gegeweb.org 5 1687 623 1064 0 36%25:10:59
2news.nntp4.net 3 1903 423 1311 169 22%23:50:20
3news.samoylyk.net 4 1725 421 1303 1 24%23:49:13
4nntp.terraraq.uk 5 1278 100 1165 13 7%24:36:47
5news.glou.org 5 1294 76 1140 78 5%25:12:46
6usenet.pasdenom.info 13 689 22 667 0 3%21:48:11
7gegeweb-out.news.tnetconsulting.net 4 1221 16 1204 1 1%24:10:01
8localhost 25 0 0 0 0 0%00:00:00
TOTAL: 8 64 9797 1681 7854 262 17%168:38:17
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.samoylyk.net2.9 MB0.0 KB2.2 KB2.9 MB 99%7.0 KB
2feed-out.gegeweb.org2.0 MB0.0 KB0.0 KB2.0 MB100%3.3 KB
3news.nntp4.net1.5 MB0.0 KB960.5 KB2.4 MB 61%4.2 KB
4nntp.terraraq.uk286.0 KB0.0 KB28.7 KB314.6 KB 90%2.8 KB
5news.glou.org253.9 KB0.0 KB200.8 KB454.7 KB 55%3.0 KB
6usenet.pasdenom.info61.1 KB0.0 KB0.0 KB61.1 KB100%2.8 KB
7gegeweb-out.news.tnetconsulting.net44.3 KB0.0 KB2.1 KB46.4 KB 95%2.7 KB
8localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 87.0 MB0.0 KB1.2 MB8.2 MB 85%4.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 29 04:15:01 - 04:59:59 38 2.3% 0.01187.0 KB 2.6% 0.07
Aug 29 05:00:00 - 05:59:59 28 1.7% 0.0199.8 KB 1.4% 0.03
Aug 29 06:00:00 - 06:59:59 44 2.7% 0.01138.7 KB 2.0% 0.04
Aug 29 07:00:00 - 07:59:59 31 1.9% 0.0187.3 KB 1.2% 0.02
Aug 29 08:00:00 - 08:59:59 52 3.2% 0.01141.3 KB 2.0% 0.04
Aug 29 09:00:00 - 09:59:59 55 3.3% 0.02184.0 KB 2.6% 0.05
Aug 29 10:00:00 - 10:59:59 70 4.2% 0.02245.5 KB 3.5% 0.07
Aug 29 11:00:00 - 11:59:59 70 4.2% 0.02180.0 KB 2.5% 0.05
Aug 29 12:00:00 - 12:59:59 95 5.8% 0.03455.9 KB 6.5% 0.13
Aug 29 13:00:00 - 13:59:59 87 5.3% 0.02233.6 KB 3.3% 0.06
Aug 29 14:00:00 - 14:59:59 64 3.9% 0.02192.5 KB 2.7% 0.05
Aug 29 15:00:00 - 15:59:59 120 7.3% 0.03612.9 KB 8.7% 0.17
Aug 29 16:00:00 - 16:59:59 117 7.1% 0.03496.8 KB 7.0% 0.14
Aug 29 17:00:00 - 17:59:59 92 5.6% 0.03365.1 KB 5.2% 0.10
Aug 29 18:00:00 - 18:59:59 96 5.8% 0.03579.1 KB 8.2% 0.16
Aug 29 19:00:00 - 19:59:59 58 3.5% 0.02218.0 KB 3.1% 0.06
Aug 29 20:00:00 - 20:59:59 62 3.8% 0.02601.0 KB 8.5% 0.17
Aug 29 21:00:00 - 21:59:59 65 3.9% 0.02234.8 KB 3.3% 0.07
Aug 29 22:00:00 - 22:59:59 81 4.9% 0.02317.3 KB 4.5% 0.09
Aug 29 23:00:00 - 23:59:59 70 4.2% 0.02318.1 KB 4.5% 0.09
Aug 30 00:00:00 - 00:59:59 103 6.2% 0.03427.2 KB 6.0% 0.12
Aug 30 01:00:00 - 01:59:59 60 3.6% 0.02249.1 KB 3.5% 0.07
Aug 30 02:00:00 - 02:59:59 52 3.2% 0.01365.4 KB 5.2% 0.10
Aug 30 03:00:00 - 03:59:59 33 2.0% 0.01123.9 KB 1.8% 0.03
Aug 30 04:00:00 - 04:15:02 5 0.3% 0.0113.2 KB 0.2% 0.01
TOTAL: 24:00:01 1648 100.0% 0.026.9 MB 100.0% 0.08
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 169 63 0 0 1 0 0 0 105
2news.glou.org 74 0 0 0 6 0 0 0 68
3nntp.terraraq.uk 12 0 0 0 0 0 0 0 12
4news.samoylyk.net 1 0 0 0 0 0 0 0 1
5gegeweb-out.news.tnetconsulting.net 1 0 0 0 0 0 0 0 1
6feed-out.gegeweb.org 1 0 0 0 0 0 0 0 1
TOTAL: 6 258 63 0 0 7 0 0 0 188

Unwanted newsgroups [Top 20]:

NewsgroupCount
linux.kernel.netdev 50
linux.kernel.git 13
TOTAL: 2 63

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

GroupsCount
rec.food.recipes 7
TOTAL: 1 7

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 93
Bad path (news.giganews.com.POSTED) 84
No Cancel-Key matches Cancel-Lock. target=<1s0lw6s.koqn4g12xemciN%g4fleurot@free.invalid> 3
No Cancel-Key matches Cancel-Lock. target=<brp59l12km6thu3iejgg1ed73h4g7gqcgd@kgbh.org> 2
No Cancel-Key matches Cancel-Lock. target=<6a929cfa$0$7069$426a74cc@news.free.fr> 1
Cancel for rejected article 1
No Cancel-Key matches Cancel-Lock. target=<6a9298cc$0$28059$426a74cc@news.free.fr> 1
Cancel of <6a91d456$0$31900$426a74cc@news.free.fr> without Cancel-Key 1
No Cancel-Key matches Cancel-Lock. target=<6a929e0e$0$10314$426a74cc@news.free.fr> 1
TOTAL: 9 187

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
1news.tnetconsulting.net 2784 1 1642 0 0 0 0%23:48:38
2news.pasdenom.info 1206 1 1185 0 0 0 0%23:47:15
3news.gegeweb.org 1133 0 1041 0 0 7 0%23:34:16
4news.samoylyk.net 1350 0 1184 2 0 2 0%23:37:22
5news.aioe.org 0 0 0 0 0 1349 0%24:00:01
6i2pn.org 0 0 0 0 0 1641 0%24:00:01
7news.nntp4.net 1253 0 1214 0 0 0 0%23:45:11
8nntp.terraraq.uk 882 0 869 0 0 0 0%23:25:41
TOTAL: 8 8608 2 7135 2 0 2999 0%189:58:25
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tnetconsulting.net19.6 KB0.0 KB19.6 KB0.0 KB/s19.6 KB23:48:38
2news.pasdenom.info3.4 KB0.0 KB3.4 KB0.0 KB/s3.4 KB23:47:15
3news.gegeweb.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:34:16
4news.samoylyk.net0.0 KB3.2 KB3.2 KB0.0 KB/s1.6 KB23:37:22
5news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:01
6i2pn.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:01
7news.nntp4.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:45:11
8nntp.terraraq.uk0.0 KB0.0 KB0.0 KB0.0 KB/s023:25:41
TOTAL: 823.0 KB3.2 KB26.2 KB0.0 KB/s6.5 KB189:58:25
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1o031.scanner.modat.io 1 00.0 KB 0 0 000:00:00
271.6.134.230 1 00.0 KB 0 0 000:00:00
3195-140-214-19.infrawat.ch 1 00.0 KB 0 0 000:00:00
4o018.scanner.modat.io 1 00.0 KB 0 0 000:00:00
5o016.scanner.modat.io 1 00.0 KB 0 0 000:00:00
6mail.gegeweb.eu 287 00.0 KB 0 0 000:00:28
7205.210.31.206 1 00.0 KB 0 0 000:00:00
8185.242.226.126 3 00.0 KB 0 0 000:00:10
92603:6010:6802:4ca5:1a03:73ff:fe28:be0f 8 00.0 KB 0 0 000:00:06
10151.243.11.12 1 00.0 KB 0 0 000:00:04
1189-21-67-184.infrawat.ch 1 00.0 KB 0 0 000:00:00
12151.243.11.16 1 00.0 KB 0 0 000:00:03
1347.172.132.66.censys-scanner.com 5 00.0 KB 0 0 000:00:08
1450.195.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
TOTAL: 14 313 00.0 KB 0 0 000:01:06

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.172.132.66.censys-scanner.com 5 00.0 KB 0 0 000:00:08
2? 8 00.0 KB 0 0 000:00:06
3unresolved 7 00.0 KB 0 0 000:00:19
4*.gegeweb.eu 287 00.0 KB 0 0 000:00:28
5*.infrawat.ch 2 00.0 KB 0 0 000:00:00
6*.195.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
7*.scanner.modat.io 3 00.0 KB 0 0 000:00:00
TOTAL: 7 313 00.0 KB 0 0 000:01:06

NNRP auth users [Top 20]:

UserConn
mailman 287
TOTAL: 1 287

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
mail.gegeweb.eu 4.967 4.630 1.67600:00:28
185.242.226.126 0.118 0.089 0.00000:00:10
47.172.132.66.censys-scanner.com 0.104 0.084 0.00000:00:08
2603:6010:6802:4ca5:1a03:73ff:fe28:be0f 0.181 0.138 0.00000:00:06
151.243.11.12 0.029 0.021 0.00000:00:04
151.243.11.16 0.027 0.023 0.00000:00:03
50.195.132.66.censys-scanner.com 0.048 0.016 0.00000:00:01
71.6.134.230 0.029 0.021 0.00000:00:00
o031.scanner.modat.io 0.065 0.023 0.00000:00:00
205.210.31.206 0.014 0.009 0.00000:00:00
89-21-67-184.infrawat.ch 0.018 0.005 0.00000:00:00
195-140-214-19.infrawat.ch 0.022 0.000 0.00000:00:00
o016.scanner.modat.io 0.030 0.021 0.00000:00:00
o018.scanner.modat.io 0.028 0.024 0.00000:00:00
TOTAL: 14 5.680 5.104 1.67600:01:06

NNRP unrecognized commands (by host):

SystemConn
47.172.132.66.censys-scanner.com 3
50.195.132.66.censys-scanner.com 1
o031.scanner.modat.io 1
TOTAL: 3 5

NNRP unrecognized commands (by command):

CommandCount
EHLO www.censys.io 2
#023#003#023#001#023#002#001 1
HELO 1
#026#003#001 1
TOTAL: 4 5

NNRP client timeouts [Top 20]:

SystemConnPeer
47.172.132.66.censys-scanner.com 1 3
89-21-67-184.infrawat.ch 1 1
TOTAL: 2 2 6
innreport from INN 2.6.4 based on work (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>
Local contact: usenet@niel.me