Daily Usenet report for news.niel.me

Oct 19 04:15:01 -- Oct 20 04:15:01

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Oct 19 08:20:43 rpi-studio innd: filter: No Cancel-Key[sha1:ETOAUg9z1om7F+SbMXiK/H1/Bpc= sha256:7M0SvCleNCYbfX00rJB1tkgrLQ0ZiHs4Mc/nmDh+suk=] matches Cancel-Lock[sha1:+AOxnGcZXSZ5+0c0y3vTI57z6X0= sha256:UkNr5xjSKbwE+/qZx8jwh9p0txqV3qUmUeb3xHB6Svg=] target=<11b4c94$17jt$1@nnrp.usenet.blueworldhosting.com>
Oct 19 12:16:50 rpi-studio innd: filter: No Cancel-Key[sha1:lleStDdarFPGlCC/lYud5JkHNKs= sha256:SP1gR0qHv72xqcMVmpYKB+qKBThR0gLfuraGu/WSfWc=] matches Cancel-Lock[sha1:uzWRtTg9NBfZ1xcM0kOOuQXUxtk= sha256:BAPY8tLFZr2fk51wX6zpV/3noN9p0unbTcgKyQ4Gs/U=] target=<11b4qgj$1v2n4$1@dont-email.me>
Oct 19 19:41:19 rpi-studio innd: filter: No Cancel-Key[sha1:IeTcd4EVxvovAI+Jeh1rn3iDUOA= sha256:RYFaxapj72Vx3rFcid1Reb1cjyUbnWfGsooaPLpSkN4=] matches Cancel-Lock[sha1:j1aNnW9SXuR5s0Dpd5xKv4R76+w= sha256:0z41UyXHtvUVR29bF8xNQ18UFm3oz4fjTOJG84TkSXw=] target=<86lcdl5iv4le7dk39obhtolptkk6j4c944@kgbh.org>
Oct 20 01:01:35 rpi-studio innd: filter: No Cancel-Key[sha1:ITBHUftPdGrtgcTZFJt0X9KFMAw= sha256:sY8j7HFhpyVluElDzqNUZ2XWT+LMp3SNzqzoD4fUlGM=] matches Cancel-Lock[sha1:m1gfyYOxddoRSzGRoIc6vss0fCY= sha256:bytDly1pH9nyQbj/Pc6tUxqjDl/L/9/prYZ2QbnnfiY=] target=<u58ddl5ros56pi0krfg65hooamgrhp2hjn@kgbh.org>
Oct 20 01:03:09 rpi-studio innd: filter: No Cancel-Key[sha1:j/EkAbtY/kCncmC26LAkk428gGE= sha256:jHCYpsTf1+XzqXEUKvSxqmjuUiJPi5pWlHqRyMKlhPc=] matches Cancel-Lock[sha1:WX4n0C62TZrSIk8dTwUYqjWUzo4= sha256:e/RIz01w1qxDO3oIEf88Ct7JlIebCP1PwvgAxUIwchw=] target=<ta8ddlhsmn203hc1a2tls42bj1pn8kpjvj@kgbh.org>

Log entries by program:

Program nameLines%LinesSize%Size
inn 2742 32.5%465.0 KB 36.8%
nnrpd 2056 24.4%219.2 KB 17.3%
innd 1847 21.9%326.4 KB 25.8%
innfeed 1786 21.2%254.0 KB 20.1%
nocem 1 0.0%0.1 KB 0.0%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 6 8433 100.0%1.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 7835 47.0%
Negative hits 6114 36.7%
Do not exist 2722 16.3%
Cache misses 1 0.0%
TOTAL: 4 16672 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.007 0.0%6 0.000 1.167 2.000
article cancel00:00:00.002 0.0%8 0.000 0.250 1.000
article cleanup00:00:00.065 0.0%2724 0.000 0.024 0.143
article logging00:00:00.227 0.0%2729 0.000 0.083 0.333
article parse00:00:00.659 0.0%8287 0.000 0.080 0.222
article write00:00:01.105 0.0%1926 0.125 0.574 1.667
artlog/artcncl00:00:00.000 0.0%8 0.000 0.000 0.000
artlog/artcncl/perl00:00:00.001 0.0%6 0.000 0.167 1.000
data move00:00:00.157 0.0%22532 0.000 0.007 0.030
hisgrep/artcncl00:00:00.001 0.0%8 0.000 0.125 1.000
hisgrep/artcncl/perl00:00:00.000 0.0%6 0.000 0.000 0.000
hisgrep/perl00:00:00.000 0.0%13 0.000 0.000 0.000
hishave/artcncl00:00:00.000 0.0%8 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.000 0.0%6 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.298 0.0%16676 0.000 0.018 0.083
history sync00:00:00.047 0.0%364 0.000 0.129 1.000
history write00:00:00.567 0.0%2724 0.000 0.208 0.625
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle24:00:29.964100.0%22779 1001.275 3794.283 18754.250
nntp read00:00:00.662 0.0%21998 0.000 0.030 0.115
overview write00:00:00.669 0.0%1926 0.000 0.347 2.857
perl filter00:00:12.880 0.0%2724 1.789 4.728 13.286
python filter00:00:00.014 0.0%2724 0.000 0.005 0.083
site send00:00:00.979 0.0%3898 0.000 0.251 0.750
TOTAL: 24:01:06.20224:00:48.304100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.021 0.0%1929 0.000 0.011 0.143
article prepare00:00:00.001 0.0%72 0.000 0.014 0.083
article read00:00:00.011 0.0%18 0.000 0.611 2.667
backlog stats00:00:00.095 0.0%18503 0.000 0.005 0.041
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.230 0.0%8890 0.000 0.026 0.125
data write00:00:00.426 0.0%7095 0.000 0.060 0.185
idle23:58:01.073100.0%18503 1764.579 4663.086 10939.691
status file00:00:04.399 0.0%266 0.000 16.538 82.000
TOTAL: 23:58:09.79023:58:06.256100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:42.327 38.7%1539 0.000 27.503 9236.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.126 0.1%3480 0.000 0.036 1.000
readart00:00:00.002 0.0%6 0.333 0.333 0.333
TOTAL: 00:01:49.28500:00:42.455 38.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
1feed-out.gegeweb.org 3 1896 855 1041 0 45%23:58:13
2news.nntp4.net 2 2515 441 1402 672 17%21:43:38
3news.samoylyk.net 2 2159 332 1826 1 15%26:59:56
4news.glou.org 3 1243 85 1118 40 6%21:06:07
5nntp.terraraq.uk 3 282 33 249 0 11%08:06:52
6usenet.pasdenom.info 9 633 32 598 3 5%14:39:55
7localhost 25 0 0 0 0 0%00:00:00
TOTAL: 7 47 8728 1778 6234 716 20%116:34:41
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1feed-out.gegeweb.org3.1 MB0.0 KB0.0 KB3.1 MB100%3.7 KB
2news.nntp4.net2.8 MB0.0 KB7.7 MB10.5 MB 26%9.6 KB
3news.samoylyk.net2.4 MB0.0 KB2.8 KB2.4 MB 99%7.3 KB
4news.glou.org356.5 KB0.0 KB200.8 KB557.3 KB 63%4.5 KB
5nntp.terraraq.uk87.5 KB0.0 KB0.0 KB87.5 KB100%2.7 KB
6usenet.pasdenom.info85.8 KB0.0 KB3.7 KB89.4 KB 95%2.6 KB
7localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 78.8 MB0.0 KB7.9 MB16.7 MB 52%6.8 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 19 04:15:01 - 04:59:59 32 1.7% 0.01224.2 KB 2.4% 0.08
Oct 19 05:00:00 - 05:59:59 30 1.6% 0.01234.0 KB 2.5% 0.07
Oct 19 06:00:00 - 06:59:59 24 1.2% 0.01134.3 KB 1.5% 0.04
Oct 19 07:00:00 - 07:59:59 51 2.6% 0.01232.3 KB 2.5% 0.06
Oct 19 08:00:00 - 08:59:59 46 2.4% 0.01205.3 KB 2.2% 0.06
Oct 19 09:00:00 - 09:59:59 50 2.6% 0.01293.1 KB 3.2% 0.08
Oct 19 10:00:00 - 10:59:59 77 4.0% 0.02819.8 KB 8.9% 0.23
Oct 19 11:00:00 - 11:59:59 80 4.2% 0.02256.7 KB 2.8% 0.07
Oct 19 12:00:00 - 12:59:59 152 7.9% 0.04649.1 KB 7.0% 0.18
Oct 19 13:00:00 - 13:59:59 129 6.7% 0.04542.2 KB 5.9% 0.15
Oct 19 14:00:00 - 14:59:59 108 5.6% 0.031.1 MB 12.5% 0.32
Oct 19 15:00:00 - 15:59:59 79 4.1% 0.02329.1 KB 3.6% 0.09
Oct 19 16:00:00 - 16:59:59 125 6.5% 0.03554.9 KB 6.0% 0.15
Oct 19 17:00:00 - 17:59:59 102 5.3% 0.03397.7 KB 4.3% 0.11
Oct 19 18:00:00 - 18:59:59 83 4.3% 0.02320.6 KB 3.5% 0.09
Oct 19 19:00:00 - 19:59:59 87 4.5% 0.02433.6 KB 4.7% 0.12
Oct 19 20:00:00 - 20:59:59 74 3.8% 0.02287.3 KB 3.1% 0.08
Oct 19 21:00:00 - 21:59:59 103 5.4% 0.03362.1 KB 3.9% 0.10
Oct 19 22:00:00 - 22:59:59 83 4.3% 0.02314.6 KB 3.4% 0.09
Oct 19 23:00:00 - 23:59:59 116 6.0% 0.03502.1 KB 5.4% 0.14
Oct 20 00:00:00 - 00:59:59 77 4.0% 0.02243.9 KB 2.6% 0.07
Oct 20 01:00:00 - 01:59:59 75 3.9% 0.02266.1 KB 2.9% 0.07
Oct 20 02:00:00 - 02:59:59 73 3.8% 0.02263.6 KB 2.9% 0.07
Oct 20 03:00:00 - 03:59:59 57 3.0% 0.02178.1 KB 1.9% 0.05
Oct 20 04:00:00 - 04:15:01 12 0.6% 0.0126.9 KB 0.3% 0.03
TOTAL: 24:00:00 1925 100.0% 0.029.0 MB 100.0% 0.11
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 731 622 0 0 0 0 0 0 109
2news.glou.org 56 0 0 0 0 0 0 0 56
3nntp.terraraq.uk 8 0 0 0 0 0 0 0 8
4usenet.pasdenom.info 4 0 0 0 0 0 0 0 4
5feed-out.gegeweb.org 3 0 0 0 0 0 0 0 3
6news.samoylyk.net 1 0 0 0 0 0 0 0 1
TOTAL: 6 803 622 0 0 0 0 0 0 181

Unwanted newsgroups [Top 20]:

NewsgroupCount
linux.kernel.netdev 544
linux.kernel.git 78
TOTAL: 2 622

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 96
Bad path (news.giganews.com.POSTED) 71
Cancels abusifs 4
Cancel of non-existing ID <11b4qgj$1v2n4$1@dont-email.me> 2
No Cancel-Key matches Cancel-Lock. target=<u58ddl5ros56pi0krfg65hooamgrhp2hjn@kgbh.org> 1
No Cancel-Key matches Cancel-Lock. target=<ta8ddlhsmn203hc1a2tls42bj1pn8kpjvj@kgbh.org> 1
No Cancel-Key matches Cancel-Lock. target=<86lcdl5iv4le7dk39obhtolptkk6j4c944@kgbh.org> 1
TOTAL: 7 176

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
feed-out.gegeweb.org 3
news.nntp4.net 1
news.glou.org 1
TOTAL: 3 5
TOTAL: 1 5

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.samoylyk.net 2064 5 1614 10 0 6 0%23:59:57
2nntp.terraraq.uk 979 2 930 0 0 0 0%23:43:08
3news.aioe.org 0 0 0 0 0 1511 0%24:00:00
4i2pn.org 0 0 0 0 0 1919 0%24:00:00
5news.pasdenom.info 1439 0 1403 0 0 0 0%23:59:03
6news.nntp4.net 1481 0 1418 1 0 0 0%23:48:13
7news.tnetconsulting.net 0 0 0 0 0 1920 0%23:57:47
8news.gegeweb.org 1134 0 1069 0 0 0 0%23:57:55
TOTAL: 8 7097 7 6434 11 0 5356 0%191:26:03
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.samoylyk.net15.5 KB24.7 KB40.2 KB0.0 KB/s2.7 KB23:59:57
2nntp.terraraq.uk5.8 KB0.0 KB5.8 KB0.0 KB/s2.9 KB23:43:08
3news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:00
4i2pn.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:00
5news.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:03
6news.nntp4.net0.0 KB2.4 KB2.4 KB0.0 KB/s2.4 KB23:48:13
7news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:47
8news.gegeweb.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:55
TOTAL: 821.2 KB27.1 KB48.3 KB0.0 KB/s2.7 KB191:26:03
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1174.96.78.81 30 638.7 KB 1 0 000:00:17
TOTAL: 1 30 638.7 KB 1 0 000:01:48

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 42 638.7 KB 1 0 000:00:46
2*.monitoring.internet-measurement.com 10 00.0 KB 0 0 000:00:08
3*.scanner.modat.io 2 00.0 KB 0 0 000:00:30
4*.gegeweb.eu 288 00.0 KB 0 0 000:00:19
5? 2 00.0 KB 0 0 000:00:00
6*.infrawat.ch 2 00.0 KB 0 0 000:00:01
7*.172.132.66.censys-scanner.com 1 00.0 KB 0 0 000:00:02
TOTAL: 7 347 638.7 KB 1 0 000:01:48

NNRP auth users [Top 20]:

UserConn
mailman 288
TOTAL: 1 288

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
o031.scanner.modat.io 0.023 0.005 0.00000:00:30
mail.gegeweb.eu 5.275 4.673 1.77400:00:19
174.96.78.81 0.739 0.464 0.00000:00:17
77.67.26.240 0.026 0.002 0.00000:00:14
52.186.183.216 0.069 0.032 0.00000:00:09
47.251.14.178 0.029 0.021 0.00000:00:03
r4-182-b6.monitoring.internet-measurement.com 0.063 0.017 0.00000:00:03
r3-208-d0.monitoring.internet-measurement.com 0.037 0.020 0.00000:00:03
108.172.132.66.censys-scanner.com 0.025 0.034 0.00000:00:02
r3-142-8e.monitoring.internet-measurement.com 0.029 0.008 0.00000:00:01
193.24.211.152 0.153 0.110 0.00000:00:01
89-37-172-138.infrawat.ch 0.014 0.009 0.00000:00:00
147.185.132.177 0.025 0.025 0.00000:00:00
2a01:4020:1:1:102::de 0.023 0.031 0.00000:00:00
69-5-169-133.infrawat.ch 0.019 0.016 0.00000:00:00
o039.scanner.modat.io 0.009 0.014 0.00000:00:00
r3-110-6e.monitoring.internet-measurement.com 0.016 0.012 0.00000:00:00
r3-126-7e.monitoring.internet-measurement.com 0.038 0.025 0.00000:00:00
r3-158-9e.monitoring.internet-measurement.com 0.041 0.025 0.00000:00:00
r4-180-b4.monitoring.internet-measurement.com 0.043 0.021 0.00000:00:00
TOTAL: 23 6.787 5.625 1.77400:01:48

Curious NNRP server explorers [Top 20]:

SystemConn
mail.gegeweb.eu 288
193.24.211.152 6
52.186.183.216 2
47.251.14.178 1
r3-126-7e.monitoring.internet-measurement.com 1
147.185.132.177 1
2a01:4020:1:1:102::de 1
64.205.72.196 1
r4-180-b4.monitoring.internet-measurement.com 1
r3-146-92.monitoring.internet-measurement.com 1
89-37-172-138.infrawat.ch 1
r3-142-8e.monitoring.internet-measurement.com 1
r4-144-90.monitoring.internet-measurement.com 1
108.172.132.66.censys-scanner.com 1
o031.scanner.modat.io 1
69-5-169-133.infrawat.ch 1
r3-158-9e.monitoring.internet-measurement.com 1
2a01:4020:1:1:103::47d 1
r3-208-d0.monitoring.internet-measurement.com 1
r4-158-9e.monitoring.internet-measurement.com 1
TOTAL: 24 317

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
193.24.211.152 12
47.251.14.178 3
52.186.183.216 1
108.172.132.66.censys-scanner.com 1
TOTAL: 4 17

NNRP unrecognized commands (by command):

CommandCount
#026#003#001 7
À#011À#024À#023 6
À#024 1
#023#001#023#002#023#003#001 1
MGLNDD_45.13.105.36_119 1
EHLO www.censys.io 1
TOTAL: 6 17

NNRP client timeouts [Top 20]:

SystemConnPeer
193.24.211.152 6 6
2a01:4020:1:1:102::de 1 1
47.251.14.178 1 1
69-5-169-133.infrawat.ch 1 1
TOTAL: 4 9 12

Newsgroup request counts (by category):

CategoryCountPct
1grisbi 6100.0%
TOTAL: 1 6100.0%

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

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