Daily Usenet report for news.niel.me

Sep 3 04:15:01 -- Sep 4 04:15:01

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Sep 3 08:03:09 rpi-studio innd: filter: No Cancel-Key[sha1:TSmOsIZneH4EhnjDO+fHStqTLN4= sha256:OgpH9fKSir6PRyuD+32Qz22Yx/VrD3OFVk2JmZ6LC20=] matches Cancel-Lock[sha1:lINPPMo6sbNjBDKvZ/ZVS42FwlA= sha256:18ICNzsTlCzLWzPljguHlhdNUn1wH3z3uceLCss2Fas=] target=<aolsfaq-1-1787464981@therockgarden.ca>
Sep 3 11:36:45 rpi-studio innd: filter: No Cancel-Key[sha1:E6A6DSHw1bPiTghev69cs9v7l0c= sha256:+5X2CenWardZhjuq4nPTAIFp4MwPYru+TZaxs/56nL0=] matches Cancel-Lock[sha1:4hUsSve6pvvkjKZHrFFeSo/a6jM= sha256:p7MrlHPGT/tU14KBBxlipSKg5yGHFvrdwRGUuZorkuU=] target=<117be2n$3i7nl$1@dont-email.me>
Sep 3 20:12:07 rpi-studio innd: filter: No Cancel-Key[sha1:NPxP0jpxost4Sjj5mc6mvaVzgIk= sha256:S/4xuQN/ELyYqiRhame9JN1h3xEv641qbxNLGoxriJk=] matches Cancel-Lock[sha1:umeP1HIakBBF1FGsueZqPTvMnj0= sha256:zi+ijmfbZjevcOBYmQOomLePM8A4HYIZN7yKugQz+a8=] target=<117cctd$3u8jc$1@dont-email.me>

Log entries by program:

Program nameLines%LinesSize%Size
inn 2480 28.3%428.5 KB 32.2%
nnrpd 2464 28.2%293.6 KB 22.1%
innd 1937 22.1%346.2 KB 26.0%
innfeed 1868 21.3%262.5 KB 19.7%
nocem 1 0.0%0.1 KB 0.0%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 6 8751 100.0%1.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 8348 48.0%
Negative hits 6571 37.8%
Do not exist 2465 14.2%
Cache misses 3 0.0%
TOTAL: 4 17387 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.005 0.0%5 0.500 1.000 1.500
article cancel00:00:00.005 0.0%8 0.000 0.625 2.000
article cleanup00:00:00.062 0.0%2468 0.000 0.025 0.200
article logging00:00:00.221 0.0%2468 0.000 0.090 0.400
article parse00:00:00.446 0.0%6623 0.000 0.067 0.235
article write00:00:00.978 0.0%1826 0.000 0.536 1.538
artlog/artcncl00:00:00.000 0.0%8 0.000 0.000 0.000
artlog/artcncl/perl00:00:00.000 0.0%5 0.000 0.000 0.000
data move00:00:00.123 0.0%20624 0.000 0.006 0.073
hisgrep/artcncl00:00:00.003 0.0%8 0.000 0.375 1.000
hisgrep/artcncl/perl00:00:00.000 0.0%5 0.000 0.000 0.000
hisgrep/perl00:00:00.001 0.0%10 0.000 0.100 0.333
hishave/artcncl00:00:00.000 0.0%8 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.000 0.0%5 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.250 0.0%17390 0.000 0.014 0.059
history sync00:00:00.050 0.0%351 0.000 0.142 1.000
history write00:00:00.509 0.0%2468 0.000 0.206 1.200
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle24:00:25.864100.0%21792 1550.574 3965.945 18200.758
nntp read00:00:00.670 0.0%21053 0.000 0.032 0.125
overview write00:00:00.622 0.0%1826 0.000 0.341 1.889
perl filter00:00:14.829 0.0%2468 2.300 6.009 19.800
python filter00:00:00.017 0.0%2468 0.000 0.007 0.125
site send00:00:00.796 0.0%3686 0.000 0.216 0.519
TOTAL: 24:01:04.83024:00:45.451100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.023 0.0%1892 0.000 0.012 0.222
article prepare00:00:00.000 0.0%20 0.000 0.000 0.000
article read00:00:00.008 0.0%5 0.000 1.600 8.000
backlog stats00:00:00.147 0.0%22788 0.000 0.006 0.033
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.295 0.0%11303 0.000 0.026 0.105
data write00:00:00.604 0.0%9509 0.000 0.064 0.182
idle23:59:22.036100.0%22788 1655.793 3789.803 8978.955
status file00:00:08.824 0.0%300 1.000 29.413 85.000
TOTAL: 23:59:36.73823:59:31.937100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:24.573 20.5%1467 0.000 16.751 1987.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.117 0.1%3432 0.000 0.034 1.000
TOTAL: 00:01:59.69600:00:24.690 20.6%----

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 2 1835 578 1257 0 31%23:59:24
2news.nntp4.net 1 2440 422 1431 587 17%23:36:43
3news.samoylyk.net 1 1363 412 949 2 30%17:40:20
4nntp.terraraq.uk 4 1200 109 1089 2 9%19:57:42
5news.glou.org 3 1372 95 1245 32 6%24:47:39
6gegeweb-out.news.tnetconsulting.net 4 1091 7 1084 0 0%18:49:44
7usenet.pasdenom.info 6 105 2 103 0 1%04:54:01
8localhost 25 0 0 0 0 0%00:00:00
TOTAL: 8 46 9406 1625 7158 623 17%133:45:33
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.nntp4.net2.0 MB0.0 KB5.7 MB7.7 MB 25%7.8 KB
2news.samoylyk.net2.0 MB0.0 KB6.1 KB2.0 MB 99%4.9 KB
3feed-out.gegeweb.org1.7 MB0.0 KB0.0 KB1.7 MB100%3.1 KB
4nntp.terraraq.uk452.8 KB0.0 KB6.1 KB458.9 KB 98%4.1 KB
5news.glou.org289.9 KB0.0 KB123.4 KB413.3 KB 70%3.3 KB
6gegeweb-out.news.tnetconsulting.net50.2 KB0.0 KB0.0 KB50.2 KB100%7.2 KB
7usenet.pasdenom.info3.8 KB0.0 KB0.0 KB3.8 KB100%1.9 KB
8localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 86.5 MB0.0 KB5.9 MB12.3 MB 52%5.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 3 04:15:01 - 04:59:59 59 3.2% 0.02265.7 KB 3.5% 0.10
Sep 3 05:00:00 - 05:59:59 71 3.9% 0.02302.2 KB 4.0% 0.08
Sep 3 06:00:00 - 06:59:59 45 2.5% 0.01192.6 KB 2.5% 0.05
Sep 3 07:00:00 - 07:59:59 28 1.5% 0.01101.3 KB 1.3% 0.03
Sep 3 08:00:00 - 08:59:59 61 3.3% 0.02200.5 KB 2.6% 0.06
Sep 3 09:00:00 - 09:59:59 46 2.5% 0.01170.9 KB 2.2% 0.05
Sep 3 10:00:00 - 10:59:59 72 3.9% 0.02397.9 KB 5.2% 0.11
Sep 3 11:00:00 - 11:59:59 88 4.8% 0.02457.1 KB 6.0% 0.13
Sep 3 12:00:00 - 12:59:59 81 4.4% 0.02310.0 KB 4.1% 0.09
Sep 3 13:00:00 - 13:59:59 94 5.1% 0.03281.1 KB 3.7% 0.08
Sep 3 14:00:00 - 14:59:59 94 5.1% 0.03277.1 KB 3.6% 0.08
Sep 3 15:00:00 - 15:59:59 66 3.6% 0.02256.8 KB 3.4% 0.07
Sep 3 16:00:00 - 16:59:59 82 4.5% 0.02297.3 KB 3.9% 0.08
Sep 3 17:00:00 - 17:59:59 87 4.8% 0.02313.2 KB 4.1% 0.09
Sep 3 18:00:00 - 18:59:59 94 5.1% 0.03321.3 KB 4.2% 0.09
Sep 3 19:00:00 - 19:59:59 121 6.6% 0.03415.6 KB 5.4% 0.12
Sep 3 20:00:00 - 20:59:59 71 3.9% 0.02348.5 KB 4.6% 0.10
Sep 3 21:00:00 - 21:59:59 106 5.8% 0.03428.7 KB 5.6% 0.12
Sep 3 22:00:00 - 22:59:59 110 6.0% 0.03410.4 KB 5.4% 0.11
Sep 3 23:00:00 - 23:59:59 120 6.6% 0.03377.2 KB 4.9% 0.10
Sep 4 00:00:00 - 00:59:59 73 4.0% 0.02284.4 KB 3.7% 0.08
Sep 4 01:00:00 - 01:59:59 61 3.3% 0.02322.5 KB 4.2% 0.09
Sep 4 02:00:00 - 02:59:59 43 2.4% 0.01189.8 KB 2.5% 0.05
Sep 4 03:00:00 - 03:59:59 42 2.3% 0.01675.7 KB 8.9% 0.19
Sep 4 04:00:00 - 04:15:01 11 0.6% 0.0134.5 KB 0.5% 0.04
TOTAL: 24:00:00 1826 100.0% 0.027.5 MB 100.0% 0.09
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 600 510 0 0 1 0 0 0 89
2news.glou.org 29 0 0 0 0 0 0 0 29
3nntp.terraraq.uk 9 0 0 0 0 0 0 0 9
4news.samoylyk.net 2 0 0 0 0 0 0 0 2
5gegeweb-out.news.tnetconsulting.net 1 0 0 0 0 0 0 0 1
TOTAL: 5 641 510 0 0 1 0 0 0 130

Unwanted newsgroups [Top 20]:

NewsgroupCount
linux.kernel.netdev 444
linux.kernel.git 66
TOTAL: 2 510

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

GroupsCount
soc.culture.hawaii 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 84
Bad path (news.giganews.com.POSTED) 43
Cancel of non-existing ID <117be2n$3i7nl$1@dont-email.me> 2
HTML Multipart 1
TOTAL: 4 130

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.samoylyk.net 1343 2 1232 0 0 0 0%23:58:49
2news.tnetconsulting.net 3094 1 1817 1 0 64 0%23:59:57
3news.gegeweb.org 1331 0 1249 0 0 0 0%23:56:46
4news.pasdenom.info 1405 0 1375 0 0 0 0%23:58:54
5news.nntp4.net 1433 0 1386 0 0 2 0%23:45:02
6nntp.terraraq.uk 965 0 930 1 0 0 0%23:32:16
7i2pn.org 0 0 0 0 0 1819 0%24:00:00
8news.aioe.org 0 0 0 0 0 1512 0%24:00:00
TOTAL: 8 9571 3 7989 2 0 3397 0%191:11:44
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.samoylyk.net5.9 KB0.0 KB5.9 KB0.0 KB/s2.9 KB23:58:49
2news.tnetconsulting.net2.0 KB1.7 KB3.7 KB0.0 KB/s1.8 KB23:59:57
3news.gegeweb.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:56:46
4news.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:54
5news.nntp4.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:45:02
6nntp.terraraq.uk0.0 KB1.7 KB1.7 KB0.0 KB/s1.7 KB23:32:16
7i2pn.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:00
8news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:00
TOTAL: 87.9 KB3.4 KB11.3 KB0.0 KB/s2.3 KB191:11:44
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1104.152.52.218 1 00.0 KB 0 0 000:00:00
2r3-244-f4.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
369-5-169-93.infrawat.ch 1 00.0 KB 0 0 000:00:00
4r4-145-91.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
5r4-90-5a.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
6172-236-239-55.ip.linodeusercontent.com 2 00.0 KB 0 0 000:00:01
7104.152.52.226 2 00.0 KB 0 0 000:00:00
8r4-106-6a.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
9r4-231-e7.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
10104.152.52.228 2 00.0 KB 0 0 000:00:01
11r3-135-87.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
12r3-236-ec.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
1345-79-207-129.ip.linodeusercontent.com 1 00.0 KB 0 0 000:00:01
14104.152.52.222 4 00.0 KB 0 0 000:00:00
15r5-178-b2.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:03
16r3-234-ea.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
17r4-243-f3.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
18r5-234-ea.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
19r4-246-f6.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
20104.152.52.227 1 00.0 KB 0 0 000:00:00
21104.152.52.220 2 00.0 KB 0 0 000:00:00
22104.152.52.225 3 00.0 KB 0 0 000:00:06
23r3-148-94.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:03
24104.152.52.229 1 00.0 KB 0 0 000:00:00
25r5-229-e5.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
2666-228-34-203.ip.linodeusercontent.com 85 00.0 KB 0 0 000:00:16
2745-33-14-5.ip.linodeusercontent.com 1 00.0 KB 0 0 000:00:00
28r5-82-52.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
29r4-131-83.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
30147.185.133.82 1 00.0 KB 0 0 000:00:03
31r3-114-72.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
3269-5-169-3.infrawat.ch 1 00.0 KB 0 0 000:00:00
33104.152.52.224 1 00.0 KB 0 0 000:00:01
34r5-250-fa.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
35r5-230-e6.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
36r3-241-f1.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
37137.172.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:00
38147.185.133.246 2 00.0 KB 0 0 000:01:05
39172.172.236.85 2 00.0 KB 0 0 000:00:00
40mail.gegeweb.eu 288 00.0 KB 0 0 000:00:36
41ec2-44-250-197-111.us-west-2.compute.amazonaws.com 1 00.0 KB 0 0 000:00:00
TOTAL: 41 423 00.0 KB 0 0 000:02:33

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.infrawat.ch 2 00.0 KB 0 0 000:00:00
2*.172.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:00
3*.ip.linodeusercontent.com 89 00.0 KB 0 0 000:00:18
4*.gegeweb.eu 288 00.0 KB 0 0 000:00:36
5*.monitoring.internet-measurement.com 20 00.0 KB 0 0 000:00:14
6unresolved 22 00.0 KB 0 0 000:01:22
7*.us-west-2.compute.amazonaws.com 1 00.0 KB 0 0 000:00:00
TOTAL: 7 423 00.0 KB 0 0 000:02:33

NNRP auth users [Top 20]:

UserConn
mailman 288
TOTAL: 1 288

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
147.185.133.246 0.140 0.069 0.00000:01:05
mail.gegeweb.eu 4.948 4.437 1.67300:00:36
66-228-34-203.ip.linodeusercontent.com 3.082 1.015 0.00000:00:16
104.152.52.225 0.062 0.040 0.00000:00:06
147.185.133.82 0.062 0.021 0.00000:00:03
r3-148-94.monitoring.internet-measurement.com 0.033 0.026 0.00000:00:03
r5-178-b2.monitoring.internet-measurement.com 0.061 0.020 0.00000:00:03
104.152.52.228 0.064 0.030 0.00000:00:01
104.152.52.224 0.013 0.018 0.00000:00:01
r5-229-e5.monitoring.internet-measurement.com 0.146 0.056 0.00000:00:01
r4-243-f3.monitoring.internet-measurement.com 0.072 0.024 0.00000:00:01
45-79-207-129.ip.linodeusercontent.com 0.036 0.016 0.00000:00:01
r4-231-e7.monitoring.internet-measurement.com 0.072 0.024 0.00000:00:01
172-236-239-55.ip.linodeusercontent.com 0.048 0.048 0.00000:00:01
172.172.236.85 0.029 0.016 0.00000:00:00
r5-230-e6.monitoring.internet-measurement.com 0.083 0.021 0.00000:00:00
r3-244-f4.monitoring.internet-measurement.com 0.061 0.027 0.00000:00:00
104.152.52.229 0.078 0.016 0.00000:00:00
104.152.52.222 0.117 0.050 0.00000:00:00
r3-236-ec.monitoring.internet-measurement.com 0.037 0.011 0.00000:00:00
TOTAL: 40 9.807 6.290 1.67300:02:33

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
172-236-239-55.ip.linodeusercontent.com 7
104.152.52.225 6
104.152.52.228 5
147.185.133.246 4
147.185.133.82 4
104.152.52.227 2
104.152.52.218 2
104.152.52.229 2
172.172.236.85 1
137.172.132.66.censys-scanner.com 1
TOTAL: 10 34

NNRP unrecognized commands (by command):

CommandCount
GET / HTTP/1.1 5
Accept: */* 4
MAIL FROM:<relaycheck_please_ignore@prot... 2
Connection: close 2
EHLO 45.13.105.36 2
Accept-Encoding: gzip 2
Host: 45.13.105.36 2
Host: 45.13.105.36:563 2
User-Agent: curl/7.61.1 2
#026#003#001 1
À#024#023#001#023#002#023#003#001 1
User-Agent: Hello from Palo Alto Network... 1
User-Agent: Mozilla/5.0 (Macintosh; Inte... 1
#001 1
SSH-2.0-Go 1
Host: 45.13.105.36:119 1
#026#003#001#005Ê#001 1
User-Agent: curl/7.68.0 1
MGLNDD_45.13.105.36_119 1
EHLO www.censys.io 1
TOTAL: 20 34

NNRP client timeouts [Top 20]:

SystemConnPeer
147.185.133.246 1 2
69-5-169-93.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