Daily Usenet report for news.niel.me

Oct 6 04:15:01 -- Oct 7 04:15:01

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Oct 6 07:45:57 rpi-studio innd: filter: No Cancel-Key[sha1:wMIhFhLKR7D0yttPZ+x6PQBm6LY= sha256:1WM0HOQiaw69YLdf8TclhjD/eE/RL4Dq9tKvKIM6IHY=] matches Cancel-Lock[sha1:W04R03IZLMVzlmQ78fv5N//X8wU= sha256:gbShybstNoD5EPLgXVAfKUXnp4DO92i7RYOG/LAeL+I=] target=<11a1caq$13bra$1@gwaiyur.mb-net.net>
Oct 6 11:54:00 rpi-studio innd: filter: No Cancel-Key[sha1:WMIef/r7CHlJkGDcGFq2njZqMpM= sha256:7cHaxKs7uZodeNn5bjGDOU1q8i8a2ZEwkHoNqKldYVo=] matches Cancel-Lock[sha1:W4AVE7YgBN3OuU8ZfTZs9Jr8tnY= sha256:jVpOAB2YK2OVrQk3yyjFzu5DHxhhHCwlmrzSC6LFAv0=] target=<slrn11c9ffu.la7.lhh+news-no_spam@hercule.home.arpa>
Oct 6 18:38:51 rpi-studio innd: filter: No Cancel-Key[sha1:kcdWLuxNzH83nIsZB9yWCIP3R5k= sha256:OMuzveDz/j2DfuiuAQiK/mu2xWtiNEclXohanMb4+OA=] matches Cancel-Lock[sha1:9bhsEaD97zUeoTst1Y+h1i2NkiU= sha256:nmAl/KW0Xp86PwSKsRsGqOPj5WNawSUEX0sQ4+DTJvY=] target=<slrn11ca8s0.tc1.lhh+news-no_spam@hercule.home.arpa>
Oct 6 20:56:37 rpi-studio innd: filter: No Cancel-Key[sha1:hrQyOZbkmugorYWeCEKtnHbExzU= sha256:c7dMFFKzgcey/xY0z3u4X+EG+t68qeCGOFfiDmH3DAs=] matches Cancel-Lock[sha1:SUQbJJy46YrzFrBpfV33TRzw/rQ= sha256:d+7RYzqpZz0MwUFegvcqpUsHZZl6TNtvAXORS6TSl/4=] target=<11a3g5j$24nse$5@dont-email.me>
Oct 6 22:00:03 rpi-studio pgpverify[2571]: pgpverify: verification failed
Oct 6 22:00:06 rpi-studio pgpverify[2720]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
inn 2683 29.8%470.0 KB 34.1%
nnrpd 2503 27.8%305.6 KB 22.1%
innd 1959 21.8%338.2 KB 24.5%
innfeed 1841 20.5%265.6 KB 19.2%
controlchan 3 0.0%0.3 KB 0.0%
pgpverify 2 0.0%0.1 KB 0.0%
nocem 1 0.0%0.1 KB 0.0%
TOTAL: 7 8992 100.0%1.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 9462 50.6%
Negative hits 6584 35.2%
Do not exist 2667 14.3%
Cache misses 2 0.0%
TOTAL: 4 18715 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.005 0.0%3 1.000 1.667 2.000
article cancel00:00:00.004 0.0%6 0.000 0.667 2.000
article cleanup00:00:00.048 0.0%2674 0.000 0.018 0.111
article logging00:00:00.234 0.0%2675 0.000 0.087 0.300
article parse00:00:00.529 0.0%7874 0.000 0.067 0.222
article write00:00:01.093 0.0%2083 0.000 0.525 2.500
artlog/artcncl00:00:00.000 0.0%6 0.000 0.000 0.000
artlog/artcncl/perl00:00:00.001 0.0%3 0.000 0.333 1.000
data move00:00:00.213 0.0%22882 0.000 0.009 0.095
hisgrep/artcncl00:00:00.001 0.0%6 0.000 0.167 1.000
hisgrep/artcncl/perl00:00:00.000 0.0%3 0.000 0.000 0.000
hisgrep/perl00:00:00.000 0.0%7 0.000 0.000 0.000
hishave/artcncl00:00:00.000 0.0%6 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.001 0.0%3 0.000 0.333 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.281 0.0%18747 0.000 0.015 0.083
history sync00:00:00.059 0.0%348 0.000 0.170 1.000
history write00:00:00.547 0.0%2674 0.000 0.205 1.000
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle24:00:27.674100.0%24201 848.573 3571.244 28575.952
nntp read00:00:00.677 0.0%23446 0.000 0.029 0.096
overview write00:00:00.646 0.0%2083 0.000 0.310 2.714
perl filter00:00:11.831 0.0%2674 1.833 4.424 16.333
python filter00:00:00.020 0.0%2674 0.000 0.007 0.167
site send00:00:00.962 0.0%4212 0.000 0.228 0.556
TOTAL: 24:01:03.77624:00:44.826100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.014 0.0%2111 0.000 0.007 0.111
article prepare00:00:00.000 0.0%72 0.000 0.000 0.000
article read00:00:00.005 0.0%18 0.000 0.278 3.000
backlog stats00:00:00.166 0.0%25963 0.000 0.006 0.035
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.339 0.0%13212 0.000 0.026 0.075
data write00:00:00.607 0.0%11152 0.000 0.054 0.152
idle23:58:46.682100.0%25963 953.628 3324.989 10733.875
status file00:00:04.224 0.0%299 1.000 14.127 83.000
TOTAL: 23:58:55.83223:58:52.037100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:46.528 34.3%1504 7.800 30.936 9772.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.136 0.1%3521 0.000 0.039 1.000
readart00:00:00.001 0.0%8 0.125 0.125 0.125
TOTAL: 00:02:15.49400:00:46.665 34.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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org000200
TOTAL000200

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1feed-out.gegeweb.org 4 2131 697 1434 0 32%24:28:34
2news.samoylyk.net 4 2128 573 1554 1 26%23:52:56
3news.nntp4.net 1 2669 520 1603 546 19%23:59:56
4news.glou.org 4 1843 154 1638 51 8%30:10:18
5nntp.terraraq.uk 4 1775 149 1623 3 8%29:28:09
6gegeweb-out.news.tnetconsulting.net 4 2542 89 2446 7 3%40:11:36
7usenet.pasdenom.info 14 885 26 858 1 2%23:32:53
8localhost 25 0 0 0 0 0%00:00:00
TOTAL: 8 60 13973 2208 11156 609 15%195:44:22
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.samoylyk.net3.8 MB0.0 KB149.9 KB3.9 MB 96%7.0 KB
2news.nntp4.net2.4 MB0.0 KB5.7 MB8.1 MB 29%7.7 KB
3feed-out.gegeweb.org2.4 MB0.0 KB0.0 KB2.4 MB100%3.5 KB
4news.glou.org574.5 KB0.0 KB210.1 KB784.7 KB 73%3.8 KB
5nntp.terraraq.uk523.2 KB0.0 KB6.7 KB529.8 KB 98%3.5 KB
6gegeweb-out.news.tnetconsulting.net339.1 KB0.0 KB16.7 KB355.8 KB 95%3.7 KB
7usenet.pasdenom.info57.4 KB0.0 KB1.2 KB58.7 KB 97%2.2 KB
8localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 810.0 MB0.0 KB6.1 MB16.0 MB 62%5.8 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 6 04:15:01 - 04:59:59 41 2.0% 0.02198.5 KB 2.0% 0.07
Oct 6 05:00:00 - 05:59:59 26 1.2% 0.0180.8 KB 0.8% 0.02
Oct 6 06:00:00 - 06:59:59 35 1.7% 0.01102.0 KB 1.0% 0.03
Oct 6 07:00:00 - 07:59:59 55 2.6% 0.02186.4 KB 1.9% 0.05
Oct 6 08:00:00 - 08:59:59 68 3.3% 0.02267.6 KB 2.7% 0.07
Oct 6 09:00:00 - 09:59:59 91 4.4% 0.03407.0 KB 4.2% 0.11
Oct 6 10:00:00 - 10:59:59 55 2.6% 0.02162.5 KB 1.7% 0.05
Oct 6 11:00:00 - 11:59:59 80 3.8% 0.02255.3 KB 2.6% 0.07
Oct 6 12:00:00 - 12:59:59 86 4.1% 0.02437.5 KB 4.5% 0.12
Oct 6 13:00:00 - 13:59:59 93 4.5% 0.03305.3 KB 3.1% 0.08
Oct 6 14:00:00 - 14:59:59 118 5.7% 0.03449.4 KB 4.6% 0.12
Oct 6 15:00:00 - 15:59:59 89 4.3% 0.02369.7 KB 3.8% 0.10
Oct 6 16:00:00 - 16:59:59 221 10.6% 0.06769.8 KB 7.9% 0.21
Oct 6 17:00:00 - 17:59:59 169 8.1% 0.05900.1 KB 9.2% 0.25
Oct 6 18:00:00 - 18:59:59 119 5.7% 0.03955.5 KB 9.8% 0.27
Oct 6 19:00:00 - 19:59:59 142 6.8% 0.04855.9 KB 8.7% 0.24
Oct 6 20:00:00 - 20:59:59 112 5.4% 0.03615.5 KB 6.3% 0.17
Oct 6 21:00:00 - 21:59:59 107 5.1% 0.03542.7 KB 5.5% 0.15
Oct 6 22:00:00 - 22:59:59 91 4.4% 0.03393.3 KB 4.0% 0.11
Oct 6 23:00:00 - 23:59:59 68 3.3% 0.02317.0 KB 3.2% 0.09
Oct 7 00:00:00 - 00:59:59 58 2.8% 0.02226.6 KB 2.3% 0.06
Oct 7 01:00:00 - 01:59:59 75 3.6% 0.02329.8 KB 3.4% 0.09
Oct 7 02:00:00 - 02:59:59 39 1.9% 0.01454.5 KB 4.6% 0.13
Oct 7 03:00:00 - 03:59:59 40 1.9% 0.01176.4 KB 1.8% 0.05
Oct 7 04:00:00 - 04:15:01 6 0.3% 0.0123.3 KB 0.2% 0.03
TOTAL: 24:00:00 2084 100.0% 0.029.6 MB 100.0% 0.11
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 546 493 0 0 0 0 0 0 53
2news.glou.org 33 0 0 0 0 0 0 0 33
3gegeweb-out.news.tnetconsulting.net 6 0 0 0 0 0 0 0 6
4nntp.terraraq.uk 3 0 0 0 0 0 0 0 3
5news.samoylyk.net 1 0 0 0 0 0 0 0 1
6usenet.pasdenom.info 1 0 0 0 0 0 0 0 1
TOTAL: 6 590 493 0 0 0 0 0 0 97

Unwanted newsgroups [Top 20]:

NewsgroupCount
linux.kernel.netdev 405
linux.kernel.git 88
TOTAL: 2 493

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 48
Bad path (news.giganews.com.POSTED) 46
Cancels abusifs 1
No Cancel-Key matches Cancel-Lock. target=<11a3g5j$24nse$5@dont-email.me> 1
TOTAL: 4 96

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
gegeweb-out.news.tnetconsulting.net 1
TOTAL: 1 1
TOTAL: 1 1

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tnetconsulting.net 3657 11 2062 5 0 28 0%23:55:52
2news.gegeweb.org 1634 2 1400 0 0 1 0%23:41:31
3news.samoylyk.net 1683 0 1469 0 0 0 0%23:55:47
4i2pn.org 0 0 0 0 0 2078 0%23:59:59
5news.pasdenom.info 1555 0 1494 0 0 0 0%23:55:15
6news.aioe.org 0 0 0 0 0 1671 0%23:59:59
7news.nntp4.net 1636 0 1554 0 0 0 0%23:44:16
8nntp.terraraq.uk 1127 0 1073 0 0 0 0%23:52:04
TOTAL: 8 11292 13 9052 5 0 3778 0%191:04:43
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tnetconsulting.net34.7 KB39.3 KB74.1 KB0.0 KB/s4.6 KB23:55:52
2news.gegeweb.org25.2 KB0.0 KB25.2 KB0.0 KB/s12.6 KB23:41:31
3news.samoylyk.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:55:47
4i2pn.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:59
5news.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB/s023:55:15
6news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:59
7news.nntp4.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:44:16
8nntp.terraraq.uk0.0 KB0.0 KB0.0 KB0.0 KB/s023:52:04
TOTAL: 859.9 KB39.3 KB99.3 KB0.0 KB/s5.5 KB191:04:43
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
12603:6010:6802:4ca5:1a03:73ff:fe28:be0f 10 827.8 KB 2 0 000:00:06
TOTAL: 1 10 827.8 KB 2 0 000:02:32

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1? 12 827.8 KB 2 0 000:00:07
2*.gegeweb.eu 288 00.0 KB 0 0 000:00:21
3*.monitoring.internet-measurement.com 21 00.0 KB 0 0 000:00:19
4*.210.203.35.bc.googleusercontent.com 2 00.0 KB 0 0 000:00:28
5*.infrawat.ch 2 00.0 KB 0 0 000:00:00
6unresolved 10 00.0 KB 0 0 000:00:18
7*.ip.linodeusercontent.com 94 00.0 KB 0 0 000:00:24
8*.scan.bufferover.run 2 00.0 KB 0 0 000:00:00
9*.scanner.modat.io 2 00.0 KB 0 0 000:00:30
10*.195.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:01
TOTAL: 10 434 827.8 KB 2 0 000:02:32

NNRP auth users [Top 20]:

UserConn
mailman 288
TOTAL: 1 288

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
o013.scanner.modat.io 0.047 0.016 0.00000:00:29
81.210.203.35.bc.googleusercontent.com 0.312 0.058 0.00000:00:28
mail.gegeweb.eu 5.149 4.502 1.69900:00:21
96-126-106-7.ip.linodeusercontent.com 3.079 0.967 0.00000:00:15
20.29.18.118 0.042 0.029 0.00000:00:10
2603:6010:6802:4ca5:1a03:73ff:fe28:be0f 0.289 0.181 0.00000:00:06
45-79-115-59.ip.linodeusercontent.com 0.029 0.021 0.00000:00:06
66.61.98.95 0.119 0.079 0.00000:00:04
62.133.46.13 0.036 0.012 0.00000:00:04
r4-79-4f.monitoring.internet-measurement.com 0.039 0.020 0.00000:00:03
r3-96-60.monitoring.internet-measurement.com 0.052 0.028 0.00000:00:03
r5-120-78.monitoring.internet-measurement.com 0.032 0.012 0.00000:00:02
99.195.132.66.censys-scanner.com 0.036 0.028 0.00000:00:01
r3-44-2c.monitoring.internet-measurement.com 0.082 0.040 0.00000:00:01
r3-45-2d.monitoring.internet-measurement.com 0.137 0.040 0.00000:00:01
r5-63-3f.monitoring.internet-measurement.com 0.070 0.024 0.00000:00:01
r4-64-40.monitoring.internet-measurement.com 0.074 0.022 0.00000:00:01
r4-62-3e.monitoring.internet-measurement.com 0.033 0.015 0.00000:00:01
r4-41-29.monitoring.internet-measurement.com 0.088 0.024 0.00000:00:01
r5-46-2e.monitoring.internet-measurement.com 0.080 0.016 0.00000:00:01
TOTAL: 41 10.702 6.567 1.69900:02:32

Curious NNRP server explorers [Top 20]:

SystemConn
mail.gegeweb.eu 288
96-126-106-7.ip.linodeusercontent.com 85
66.61.98.95 6
45-79-207-129.ip.linodeusercontent.com 3
81.210.203.35.bc.googleusercontent.com 2
r3-44-2c.monitoring.internet-measurement.com 2
reykjavik.scan.bufferover.run 2
172-236-228-39.ip.linodeusercontent.com 2
20.29.18.118 2
45-79-207-111.ip.linodeusercontent.com 1
r3-7-7.monitoring.internet-measurement.com 1
o014.scanner.modat.io 1
45-79-115-59.ip.linodeusercontent.com 1
r4-67-43.monitoring.internet-measurement.com 1
r3-73-49.monitoring.internet-measurement.com 1
r4-50-32.monitoring.internet-measurement.com 1
r3-45-2d.monitoring.internet-measurement.com 1
r4-79-4f.monitoring.internet-measurement.com 1
r4-33-21.monitoring.internet-measurement.com 1
r4-41-29.monitoring.internet-measurement.com 1
TOTAL: 41 424

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
172-236-228-39.ip.linodeusercontent.com 7
reykjavik.scan.bufferover.run 5
81.210.203.35.bc.googleusercontent.com 4
99.195.132.66.censys-scanner.com 1
20.29.18.118 1
TOTAL: 5 18

NNRP unrecognized commands (by command):

CommandCount
Accept: */* 3
GET / HTTP/1.1 3
User-Agent: Mozilla/5.0 (Macintosh; Inte... 2
Accept-Encoding: gzip 2
Host: 45.13.105.36:563 2
User-Agent: curl/7.68.0 1
MGLNDD_45.13.105.36_119 1
Host: 45.13.105.36:119 1
#001 1
EHLO www.censys.io 1
#026#003#001 1
TOTAL: 11 18

NNRP client timeouts [Top 20]:

SystemConnPeer
81.210.203.35.bc.googleusercontent.com 1 2
2a01:4020:31:0:102::54e 1 1
TOTAL: 2 2 8

Newsgroup request counts (by category):

CategoryCountPct
1grisbi 8100.0%
TOTAL: 1 8100.0%

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

NewsgroupCount
1grisbi.bugsreports 7
2grisbi.test 1
TOTAL: 2 8
innreport from INN 2.6.4 based on work (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>
Local contact: usenet@niel.me