Daily Usenet report for news.niel.me

Oct 26 04:15:02 -- Oct 27 04:15:01

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Oct 26 09:10:55 rpi-studio innd: filter: No Cancel-Key[sha1:s59gNQ9bkATLFNoD744q2a/FHKg= sha256:oIixOAL1G9rykSqOxSL2g2D48OsgpT5v0EUPmI5oIG0=] matches Cancel-Lock[sha1:WYPpo9PPtYPldujJ9ZXbb7uH+RU= sha256:oXCEkhWliSFH71f/5SgQetDzQMcwTKTpQTdUjl1lo2o=] target=<11bn1q3$cknj$1@dont-email.me>
Oct 26 13:48:45 rpi-studio innd: filter: No Cancel-Key[sha1:4YgF8Ocb9X3LnzbTT5oNoURE0Lc= sha256:SqWmLnzNI538GBPpRyLWiZZa2xvQlDriJOVVEdm3NDg=] matches Cancel-Lock[sha1:Z3kZBTKhsEX6Xy/gP7D0YYGzp0E= sha256:DJ9NuCRm2MEaHeGsUyqgBIUFAX//VP0wGezmJZTw5Uw=] target=<11bnfl2$i61k$1@dont-email.me>
Oct 26 21:42:23 rpi-studio innd: filter: No Cancel-Key[sha1:ua9+ht+351i5qNj9bxGS5OZg6Kk= sha256:Rehvx92nhWlx+KtcESLWPCE2Z/GWAMQhPI+0ppIC9gI=] matches Cancel-Lock[sha1:r/LtCYRs3G4fIG6kHLkVlNdqs/k= sha256:sxn4aQ2ZeZuoP8Sby3ci114y3BxmTOCLOIsixrzKADQ=] target=<vudvdl91o0dregc87gna2fl8oor95d37rq@kgbh.org>
Oct 26 22:17:57 rpi-studio innd: filter: No Cancel-Key[sha1:vc/n7saMHh8otXTpDw81JHYzx60= sha256:VCGaT/nc3SyRkOlN4ydtANb1fFSzR+60eiW7lL90Sbs=] matches Cancel-Lock[sha1:JGeSFjqKgDGcqp73ZYAzd5PmDNM= sha256:cbYWCNTFTd8GOaPm+BWgYwoC9FsQpcCxB1Lu/GY3+K8=] target=<7agvdldl6p83201aors69rs804fjbtc0e9@kgbh.org>

Log entries by program:

Program nameLines%LinesSize%Size
inn 2785 33.0%465.9 KB 36.3%
innd 2094 24.8%357.3 KB 27.9%
innfeed 1854 22.0%275.1 KB 21.4%
nnrpd 1704 20.2%184.2 KB 14.4%
controlchan 1 0.0%0.2 KB 0.0%
nocem 1 0.0%0.2 KB 0.0%
TOTAL: 6 8439 100.0%1.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 8966 48.7%
Negative hits 6696 36.3%
Do not exist 2763 15.0%
Cache misses 1 0.0%
TOTAL: 4 18426 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.012 0.0%7 0.500 1.714 3.500
article cancel00:00:00.003 0.0%10 0.000 0.300 1.000
article cleanup00:00:00.060 0.0%2766 0.000 0.022 0.125
article logging00:00:00.206 0.0%2768 0.000 0.074 0.500
article parse00:00:00.601 0.0%7818 0.000 0.077 0.500
article write00:00:01.090 0.0%1918 0.000 0.568 1.750
artlog/artcncl00:00:00.000 0.0%10 0.000 0.000 0.000
artlog/artcncl/perl00:00:00.000 0.0%7 0.000 0.000 0.000
data move00:00:00.267 0.0%22848 0.000 0.012 0.051
hisgrep/artcncl00:00:00.000 0.0%10 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.000 0.0%7 0.000 0.000 0.000
hisgrep/perl00:00:00.002 0.0%11 0.000 0.182 0.500
hishave/artcncl00:00:00.000 0.0%10 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.000 0.0%7 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.326 0.0%18431 0.000 0.018 0.065
history sync00:00:00.053 0.0%405 0.000 0.131 1.000
history write00:00:00.568 0.0%2766 0.000 0.205 1.000
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle24:00:31.902100.0%23751 1491.975 3639.085 22240.519
nntp read00:00:00.740 0.0%23033 0.000 0.032 0.093
overview write00:00:00.687 0.0%1918 0.000 0.358 4.600
perl filter00:00:12.450 0.0%2764 1.963 4.504 27.250
python filter00:00:00.014 0.0%2764 0.000 0.005 0.200
site send00:00:00.887 0.0%3897 0.000 0.228 0.625
TOTAL: 24:01:07.41224:00:49.868100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.015 0.0%1940 0.000 0.008 0.167
article prepare00:00:00.000 0.0%32 0.000 0.000 0.000
article read00:00:00.010 0.0%8 0.000 1.250 8.000
backlog stats00:00:00.148 0.0%24169 0.000 0.006 0.026
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.300 0.0%12009 0.000 0.025 0.100
data write00:00:00.594 0.0%10157 0.000 0.058 0.131
idle23:50:17.606100.0%24169 1735.176 3550.731 10025.300
status file00:00:02.900 0.0%295 0.000 9.831 58.000
TOTAL: 23:50:28.75523:50:21.573100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:33.591 31.7%1461 0.000 22.992 9427.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.116 0.1%3269 0.000 0.035 0.500
TOTAL: 00:01:45.96000:00:33.707 31.8%----

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.nntp4.net 1 2510 687 1110 713 27%21:02:57
2news.samoylyk.net 2 1762 447 1305 10 25%20:35:30
3feed-out.gegeweb.org 3 1903 395 1508 0 20%23:59:14
4nntp.terraraq.uk 4 1184 68 1115 1 5%21:13:51
5usenet.pasdenom.info 6 1147 52 1094 1 4%23:29:37
6news.glou.org 4 169 10 147 12 5%05:19:11
7localhost 25 0 0 0 0 0%00:00:00
8gegeweb-out.news.tnetconsulting.net 4 221 0 221 0 0%07:03:06
TOTAL: 8 49 8896 1659 6500 737 18%122:43:26
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.nntp4.net2.7 MB0.0 KB7.7 MB10.4 MB 26%7.6 KB
2news.samoylyk.net2.5 MB0.0 KB26.0 KB2.6 MB 99%5.8 KB
3feed-out.gegeweb.org1.5 MB0.0 KB0.0 KB1.5 MB100%4.0 KB
4nntp.terraraq.uk167.3 KB0.0 KB1.9 KB169.2 KB 98%2.5 KB
5usenet.pasdenom.info132.9 KB0.0 KB1.3 KB134.2 KB 99%2.5 KB
6news.glou.org33.3 KB3.4 KB62.3 KB99.0 KB 33%4.5 KB
7localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
8gegeweb-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 87.1 MB3.4 KB7.8 MB14.9 MB 47%6.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 26 04:15:02 - 04:59:59 27 1.4% 0.0191.7 KB 1.1% 0.03
Oct 26 05:00:00 - 05:59:59 25 1.3% 0.01365.4 KB 4.5% 0.10
Oct 26 06:00:00 - 06:59:59 72 3.8% 0.02420.0 KB 5.1% 0.12
Oct 26 07:00:00 - 07:59:59 44 2.3% 0.01193.4 KB 2.4% 0.05
Oct 26 08:00:00 - 08:59:59 53 2.8% 0.01319.8 KB 3.9% 0.09
Oct 26 09:00:00 - 09:59:59 63 3.3% 0.02317.3 KB 3.9% 0.09
Oct 26 10:00:00 - 10:59:59 87 4.5% 0.02391.6 KB 4.8% 0.11
Oct 26 11:00:00 - 11:59:59 97 5.1% 0.03367.0 KB 4.5% 0.10
Oct 26 12:00:00 - 12:59:59 58 3.0% 0.02210.0 KB 2.6% 0.06
Oct 26 13:00:00 - 13:59:59 49 2.6% 0.01169.3 KB 2.1% 0.05
Oct 26 14:00:00 - 14:59:59 115 6.0% 0.03390.4 KB 4.8% 0.11
Oct 26 15:00:00 - 15:59:59 100 5.2% 0.03391.4 KB 4.8% 0.11
Oct 26 16:00:00 - 16:59:59 107 5.6% 0.03527.3 KB 6.5% 0.15
Oct 26 17:00:00 - 17:59:59 105 5.5% 0.03334.3 KB 4.1% 0.09
Oct 26 18:00:00 - 18:59:59 97 5.1% 0.03383.5 KB 4.7% 0.11
Oct 26 19:00:00 - 19:59:59 106 5.5% 0.03573.6 KB 7.0% 0.16
Oct 26 20:00:00 - 20:59:59 93 4.8% 0.03396.6 KB 4.9% 0.11
Oct 26 21:00:00 - 21:59:59 123 6.4% 0.03485.1 KB 5.9% 0.13
Oct 26 22:00:00 - 22:59:59 107 5.6% 0.03417.9 KB 5.1% 0.12
Oct 26 23:00:00 - 23:59:59 100 5.2% 0.03396.0 KB 4.8% 0.11
Oct 27 00:00:00 - 00:59:59 78 4.1% 0.02292.5 KB 3.6% 0.08
Oct 27 01:00:00 - 01:59:59 73 3.8% 0.02235.3 KB 2.9% 0.07
Oct 27 02:00:00 - 02:59:59 56 2.9% 0.02182.3 KB 2.2% 0.05
Oct 27 03:00:00 - 03:59:59 61 3.2% 0.02222.0 KB 2.7% 0.06
Oct 27 04:00:00 - 04:15:01 22 1.1% 0.0293.9 KB 1.1% 0.10
TOTAL: 23:59:59 1918 100.0% 0.028.0 MB 100.0% 0.09
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 769 565 0 0 0 2 0 0 202
2news.glou.org 66 0 0 0 0 0 0 0 66
3news.samoylyk.net 13 0 0 0 0 0 0 0 13
4nntp.terraraq.uk 1 0 0 0 0 0 0 0 1
5usenet.pasdenom.info 1 0 0 0 0 0 0 0 1
TOTAL: 5 850 565 0 0 0 2 0 0 283

Unwanted newsgroups [Top 20]:

NewsgroupCount
linux.kernel.netdev 438
linux.kernel.git 127
TOTAL: 2 565

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 176
Bad path (news.giganews.com.POSTED) 65
EMP (phn path) 27
Binary: misplaced binary 6
Cancels abusifs 5
No Cancel-Key matches Cancel-Lock. target=<7agvdldl6p83201aors69rs804fjbtc0e9@kgbh.org> 1
HTML Multipart 1
TOTAL: 7 281

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news.nntp4.net 2
TOTAL: 1 2
TOTAL: 1 2

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.tnetconsulting.net 3362 4 1908 3 0 26 0%23:57:06
2nntp.terraraq.uk 1139 1 1074 0 0 6 0%23:43:26
3news.nntp4.net 1202 0 1127 0 0 0 0%23:52:25
4news.samoylyk.net 1626 0 1372 0 0 0 0%23:42:51
5news.gegeweb.org 1593 0 1511 0 0 0 0%23:57:20
6i2pn.org 0 0 0 0 0 1910 0%23:59:59
7news.pasdenom.info 1384 0 1342 0 0 0 0%23:53:31
8news.aioe.org 0 0 0 0 0 1509 0%23:59:59
TOTAL: 8 10306 5 8334 3 0 3451 0%191:06:37
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.tnetconsulting.net32.1 KB20.8 KB52.9 KB0.0 KB/s7.6 KB23:57:06
2nntp.terraraq.uk1.9 KB0.0 KB1.9 KB0.0 KB/s1.9 KB23:43:26
3news.nntp4.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:52:25
4news.samoylyk.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:42:51
5news.gegeweb.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:20
6i2pn.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:59
7news.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB/s023:53:31
8news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:59
TOTAL: 834.0 KB20.8 KB54.8 KB0.0 KB/s6.8 KB191:06:37
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1o325.scanner.modat.io 1 00.0 KB 0 0 000:00:00
277.67.26.202 1 00.0 KB 0 0 000:00:23
3205.210.31.232 1 00.0 KB 0 0 000:00:00
4198.235.24.77 1 00.0 KB 0 0 000:00:00
5104.152.52.233 2 00.0 KB 0 0 000:00:02
6166.186.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:03
7104.152.52.239 1 00.0 KB 0 0 000:00:01
8104.152.52.237 1 00.0 KB 0 0 000:00:01
92a01:4020:1:1:101::695 1 00.0 KB 0 0 000:00:00
10mail.gegeweb.eu 288 00.0 KB 0 0 000:00:26
11104.152.52.241 1 00.0 KB 0 0 000:00:00
12104.152.52.240 3 00.0 KB 0 0 000:00:01
13104.152.52.242 3 00.0 KB 0 0 000:00:00
1420.115.56.91 2 00.0 KB 0 0 000:00:09
15104.152.52.231 1 00.0 KB 0 0 000:00:03
16104.152.52.232 1 00.0 KB 0 0 000:00:01
172a01:4020:1:1:102::40c 1 00.0 KB 0 0 000:00:00
18104.152.52.236 4 00.0 KB 0 0 000:00:03
1977.67.26.227 1 00.0 KB 0 0 000:00:23
2071.6.134.237 1 00.0 KB 0 0 000:00:00
TOTAL: 20 316 00.0 KB 0 0 000:01:45

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.scanner.modat.io 1 00.0 KB 0 0 000:00:00
2*.gegeweb.eu 288 00.0 KB 0 0 000:00:26
3unresolved 24 00.0 KB 0 0 000:01:14
4*.186.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:03
5? 2 00.0 KB 0 0 000:00:00
TOTAL: 5 316 00.0 KB 0 0 000:01:45

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 5.200 4.702 1.73700:00:26
77.67.26.202 0.044 0.020 0.00000:00:23
77.67.26.227 0.048 0.017 0.00000:00:23
20.115.56.91 0.059 0.029 0.00000:00:09
104.152.52.231 0.021 0.025 0.00000:00:03
166.186.132.66.censys-scanner.com 0.033 0.025 0.00000:00:03
104.152.52.236 0.162 0.051 0.00000:00:03
104.152.52.233 0.093 0.025 0.00000:00:02
104.152.52.240 0.093 0.028 0.00000:00:01
104.152.52.237 0.047 0.000 0.00000:00:01
104.152.52.239 0.041 0.012 0.00000:00:01
104.152.52.232 0.025 0.025 0.00000:00:01
104.152.52.241 0.019 0.005 0.00000:00:00
o325.scanner.modat.io 0.016 0.008 0.00000:00:00
71.6.134.237 0.008 0.017 0.00000:00:00
104.152.52.242 0.066 0.034 0.00000:00:00
198.235.24.77 0.027 0.023 0.00000:00:00
205.210.31.232 0.023 0.027 0.00000:00:00
2a01:4020:1:1:102::40c 0.031 0.015 0.00000:00:00
2a01:4020:1:1:101::695 0.024 0.016 0.00000:00:00
TOTAL: 20 6.080 5.104 1.73700:01:45

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
104.152.52.237 5
104.152.52.232 5
104.152.52.233 2
104.152.52.241 2
166.186.132.66.censys-scanner.com 1
104.152.52.231 1
TOTAL: 6 16

NNRP unrecognized commands (by command):

CommandCount
Host: 45.13.105.36 2
EHLO 45.13.105.36 2
User-Agent: curl/7.61.1 2
MAIL FROM:<relaycheck_please_ignore@prot... 2
Accept: */* 2
Connection: close 2
GET / HTTP/1.1 2
SSH-2.0-Go 1
EHLO www.censys.io 1
TOTAL: 9 16

NNRP client timeouts [Top 20]:

SystemConnPeer
2a01:4020:1:1:102::40c 1 1
TOTAL: 1 1 4
innreport from INN 2.6.4 based on work (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>
Local contact: usenet@niel.me