Daily Usenet report for news.niel.me

Oct 2 04:15:01 -- Oct 3 04:15:02

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Oct 2 17:07:14 rpi-studio innd: filter: No Cancel-Key[sha1:VXE3MUR1ZnMwQTRNa3RJbUdGdkdCYlFvOElvPQ==] matches Cancel-Lock[sha1:DqbvCSg9GZL3cJNEog7ystbpHig=] target=<2020183471.749573726.020176.grimblecrumble870-gmail.com@news.newsdemon.com>
Oct 2 17:07:48 rpi-studio innd: filter: No Cancel-Key[sha1:VXE3MUR1ZnMwQTRNa3RJbUdGdkdCYlFvOElvPQ==] matches Cancel-Lock[sha1:DqbvCSg9GZL3cJNEog7ystbpHig=] target=<2020183471.749573726.020176.grimblecrumble870-gmail.com@news.newsdemon.com>

Log entries by program:

Program nameLines%LinesSize%Size
inn 2236 27.4%410.1 KB 31.6%
innd 2207 27.0%385.3 KB 29.7%
nnrpd 1922 23.5%206.0 KB 15.9%
innfeed 1798 22.0%297.4 KB 22.9%
controlchan 1 0.0%0.1 KB 0.0%
nocem 1 0.0%0.1 KB 0.0%
TOTAL: 6 8165 100.0%1.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 12110 59.8%
Negative hits 5930 29.3%
Do not exist 2216 10.9%
Cache misses 2 0.0%
TOTAL: 4 20258 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.011 0.0%8 1.000 1.375 2.000
article cancel00:00:00.001 0.0%8 0.000 0.125 0.500
article cleanup00:00:00.058 0.0%2221 0.000 0.026 0.250
article logging00:00:00.164 0.0%2223 0.000 0.074 0.667
article parse00:00:00.296 0.0%5034 0.000 0.059 0.250
article write00:00:01.076 0.0%1971 0.000 0.546 1.250
artlog/artcncl00:00:00.000 0.0%8 0.000 0.000 0.000
artlog/artcncl/perl00:00:00.000 0.0%8 0.000 0.000 0.000
artlog/artwrite00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.111 0.0%21255 0.000 0.005 0.030
hisgrep/artcncl00:00:00.000 0.0%8 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.000 0.0%8 0.000 0.000 0.000
hisgrep/perl00:00:00.001 0.0%15 0.000 0.067 0.500
hishave/artcncl00:00:00.000 0.0%8 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.001 0.0%8 0.000 0.125 0.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.298 0.0%20289 0.000 0.015 0.062
history sync00:00:00.055 0.0%401 0.000 0.137 1.000
history write00:00:00.435 0.0%2221 0.000 0.196 0.625
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle24:00:57.639100.0%23398 1239.316 3695.087 25027.375
nntp read00:00:00.643 0.0%22601 0.000 0.028 0.074
overview write00:00:00.687 0.0%1971 0.000 0.349 3.143
perl filter00:00:08.727 0.0%2221 1.667 3.929 7.979
python filter00:00:00.014 0.0%2221 0.000 0.006 0.143
site send00:00:00.817 0.0%3992 0.000 0.205 0.500
TOTAL: 24:01:18.57624:01:11.034100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.025 0.0%2004 0.000 0.012 0.154
article prepare00:00:00.002 0.0%472 0.000 0.004 0.125
article read00:00:00.027 0.0%114 0.000 0.237 7.000
backlog stats00:00:00.177 0.0%30128 0.000 0.006 0.024
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.375 0.0%15628 0.000 0.024 0.088
data write00:00:00.870 0.0%13880 0.019 0.063 0.176
idle23:51:08.603100.0%30128 1222.329 2850.126 7683.622
status file00:00:00.548 0.0%245 1.000 2.237 6.000
TOTAL: 23:51:15.43523:51:10.627100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle01:16:36.704 97.8%17855 0.000 257.446 10051.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:02.490 0.1%67679 0.000 0.037 0.364
readart00:00:02.198 0.0%15791 0.138 0.139 3.000
TOTAL: 01:18:17.88701:16:41.392 97.9%----

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 1904 844 1060 0 44%23:59:54
2news.nntp4.net 2 3839 332 3283 224 8%40:12:58
3news.samoylyk.net 2 2360 264 2095 1 11%28:14:20
4gegeweb-out.news.tnetconsulting.net 3 1784 238 1512 34 13%26:09:39
5i2pn.org 1 1965 180 1785 0 9%23:44:59
6news.glou.org 4 3183 122 2950 111 3%44:06:59
7nntp.terraraq.uk 1 1756 50 1705 1 2%22:55:09
8usenet.pasdenom.info 7 165 7 158 0 4%05:51:21
9localhost 25 0 0 0 0 0%00:00:00
TOTAL: 9 48 16956 2037 14548 371 12%215:15:19
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1feed-out.gegeweb.org2.9 MB0.0 KB0.0 KB2.9 MB100%3.5 KB
2news.samoylyk.net2.2 MB0.0 KB1.3 KB2.2 MB 99%8.4 KB
3news.nntp4.net1.6 MB0.0 KB711.8 KB2.3 MB 70%4.3 KB
4gegeweb-out.news.tnetconsulting.net825.9 KB0.0 KB151.9 KB977.8 KB 84%3.6 KB
5news.glou.org502.7 KB0.0 KB381.2 KB883.8 KB 56%3.8 KB
6i2pn.org495.1 KB0.0 KB0.0 KB495.1 KB100%2.8 KB
7nntp.terraraq.uk116.2 KB0.0 KB1.8 KB118.0 KB 98%2.3 KB
8usenet.pasdenom.info14.2 KB0.0 KB0.0 KB14.2 KB100%2.0 KB
9localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 98.6 MB0.0 KB1.2 MB9.8 MB 87%4.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 2 04:15:01 - 04:59:59 25 1.3% 0.01157.8 KB 1.9% 0.06
Oct 2 05:00:00 - 05:59:59 49 2.5% 0.01162.6 KB 2.0% 0.05
Oct 2 06:00:00 - 06:59:59 51 2.6% 0.01183.9 KB 2.2% 0.05
Oct 2 07:00:00 - 07:59:59 30 1.5% 0.01132.8 KB 1.6% 0.04
Oct 2 08:00:00 - 08:59:59 45 2.3% 0.01142.2 KB 1.7% 0.04
Oct 2 09:00:00 - 09:59:59 72 3.7% 0.02319.5 KB 3.9% 0.09
Oct 2 10:00:00 - 10:59:59 41 2.1% 0.01166.5 KB 2.0% 0.05
Oct 2 11:00:00 - 11:59:59 64 3.3% 0.02209.4 KB 2.5% 0.06
Oct 2 12:00:00 - 12:59:59 81 4.1% 0.02241.9 KB 2.9% 0.07
Oct 2 13:00:00 - 13:59:59 95 4.8% 0.03347.2 KB 4.2% 0.10
Oct 2 14:00:00 - 14:59:59 99 5.0% 0.03661.0 KB 8.0% 0.18
Oct 2 15:00:00 - 15:59:59 91 4.6% 0.03331.2 KB 4.0% 0.09
Oct 2 16:00:00 - 16:59:59 101 5.1% 0.03324.3 KB 3.9% 0.09
Oct 2 17:00:00 - 17:59:59 122 6.2% 0.03402.3 KB 4.9% 0.11
Oct 2 18:00:00 - 18:59:59 94 4.8% 0.03480.2 KB 5.8% 0.13
Oct 2 19:00:00 - 19:59:59 142 7.2% 0.04638.5 KB 7.7% 0.18
Oct 2 20:00:00 - 20:59:59 126 6.4% 0.04536.6 KB 6.5% 0.15
Oct 2 21:00:00 - 21:59:59 120 6.1% 0.03880.1 KB 10.7% 0.24
Oct 2 22:00:00 - 22:59:59 103 5.2% 0.03328.1 KB 4.0% 0.09
Oct 2 23:00:00 - 23:59:59 95 4.8% 0.03448.8 KB 5.4% 0.12
Oct 3 00:00:00 - 00:59:59 103 5.2% 0.03468.5 KB 5.7% 0.13
Oct 3 01:00:00 - 01:59:59 68 3.5% 0.02178.8 KB 2.2% 0.05
Oct 3 02:00:00 - 02:59:59 78 4.0% 0.02245.0 KB 3.0% 0.07
Oct 3 03:00:00 - 03:59:59 56 2.8% 0.02176.8 KB 2.1% 0.05
Oct 3 04:00:00 - 04:15:02 17 0.9% 0.0282.3 KB 1.0% 0.09
TOTAL: 24:00:01 1968 100.0% 0.028.1 MB 100.0% 0.10
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 145 0 0 0 0 0 0 0 145
2news.glou.org 62 0 0 0 0 0 0 0 62
3gegeweb-out.news.tnetconsulting.net 32 0 0 0 0 0 0 0 32
4usenet.pasdenom.info 10 0 0 0 0 0 0 0 10
5feed-out.gegeweb.org 2 0 0 0 0 0 0 0 2
6news.samoylyk.net 1 0 0 0 0 0 0 0 1
TOTAL: 6 252 0 0 0 0 0 0 0 252

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 115
EMP (phn path) 93
Bad path (news.giganews.com.POSTED) 33
No Cancel-Key matches Cancel-Lock. target=<2020183471.749573726.020176.grimblecrumble870-gmail.com@news.newsdemon.com> 2
EMP (md5) 1
Cancel of <323824c9584153920767897125e343dbe52de57c@i2pn2.org> without Cancel-Key 1
Cancel of <fd2ff3bca5a686931de64b3a7c4eb6c61d00574e@i2pn2.org> without Cancel-Key 1
Cancel for rejected article 1
Cancel of <5b9c29393a12de7d9360f408f2136a1a026badd9@i2pn2.org> without Cancel-Key 1
Cancel of <07b34c6d94fee15c79f3d39ba5fd40b3f1a0c3bb@i2pn2.org> without Cancel-Key 1
Cancel of <4b9221054af0ce6605350c9b50215d9d6ad66920@i2pn2.org> without Cancel-Key 1
TOTAL: 11 250

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
feed-out.gegeweb.org 2
TOTAL: 1 2
TOTAL: 1 2

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.gegeweb.org 1181 49 1071 0 0 0 4%23:37:24
2i2pn.org 2992 35 1752 3 0 32 1%23:59:58
3news.tnetconsulting.net 3284 3 1954 4 0 15 0%23:59:58
4news.samoylyk.net 2012 3 1701 20 0 0 0%23:49:17
5nntp.terraraq.uk 1199 1 1189 0 0 0 0%23:39:55
6news.nntp4.net 1750 0 1650 0 0 0 0%23:54:34
7news.aioe.org 0 0 0 0 0 1389 0%24:00:01
8news.pasdenom.info 1672 0 1514 0 0 0 0%23:48:19
TOTAL: 8 14090 91 10831 27 0 1436 0%190:49:26
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.gegeweb.org151.9 KB0.0 KB151.9 KB0.0 KB/s3.1 KB23:37:24
2i2pn.org116.2 KB15.4 KB131.6 KB0.0 KB/s3.5 KB23:59:58
3news.samoylyk.net22.2 KB213.6 KB235.8 KB0.0 KB/s10.3 KB23:49:17
4news.tnetconsulting.net13.6 KB12.0 KB25.6 KB0.0 KB/s3.7 KB23:59:58
5nntp.terraraq.uk2.9 KB0.0 KB2.9 KB0.0 KB/s2.9 KB23:39:55
6news.nntp4.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:54:34
7news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:01
8news.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB/s023:48:19
TOTAL: 8306.8 KB241.0 KB547.8 KB0.0 KB/s4.6 KB190:49:26
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
169.10.48.174 8 1579124.6 MB 10 0 001:15:37
TOTAL: 1 8 1579124.6 MB 10 0 001:18:17

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 20 1579124.6 MB 10 0 001:16:20
2*.survey.inspici.com 1 00.0 KB 0 0 000:00:03
3*.stretchoid.com 1 00.0 KB 0 0 000:00:00
4*.gegeweb.eu 288 00.0 KB 0 0 000:01:36
5*.us-west-2.compute.amazonaws.com 2 00.0 KB 0 0 000:00:01
6*.211.203.35.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
7*.monitoring.internet-measurement.com 5 00.0 KB 0 0 000:00:12
TOTAL: 7 318 1579124.6 MB 10 0 001:18:17

NNRP auth users [Top 20]:

UserConn
mailman 288
TOTAL: 1 288

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
69.10.48.174 2.453 6.328 0.00001:15:37
mail.gegeweb.eu 5.254 4.457 1.72400:01:36
147.185.132.21 0.019 0.027 0.00000:00:10
198.235.24.159 0.017 0.004 0.00000:00:10
104.152.52.135 0.013 0.009 0.00000:00:10
206.168.34.127 0.097 0.062 0.00000:00:08
pleasant.monitoring.internet-measurement.com 0.045 0.030 0.00000:00:06
staunch.monitoring.internet-measurement.com 0.069 0.022 0.00000:00:05
server-0-3.survey.inspici.com 0.011 0.011 0.00000:00:03
91.238.181.71 0.045 0.041 0.00000:00:01
ec2-18-237-81-41.us-west-2.compute.amazonaws.com 0.009 0.014 0.00000:00:01
147.45.112.8 0.030 0.021 0.00000:00:00
249.211.203.35.bc.googleusercontent.com 0.070 0.021 0.00000:00:00
ec2-54-190-56-96.us-west-2.compute.amazonaws.com 0.062 0.016 0.00000:00:00
147.185.132.155 0.068 0.015 0.00000:00:00
azpdegb6.stretchoid.com 0.025 0.021 0.00000:00:00
104.152.52.124 0.010 0.013 0.00000:00:00
glorious.monitoring.internet-measurement.com 0.048 0.016 0.00000:00:00
ingenious.monitoring.internet-measurement.com 0.010 0.017 0.00000:00:00
92.118.39.213 0.028 0.012 0.00000:00:00
TOTAL: 21 8.421 11.183 1.72401:18:17

Curious NNRP server explorers [Top 20]:

SystemConn
mail.gegeweb.eu 288
206.168.34.127 3
91.238.181.71 2
104.152.52.135 1
147.45.112.8 1
glorious.monitoring.internet-measurement.com 1
glamorous.monitoring.internet-measurement.com 1
198.235.24.159 1
staunch.monitoring.internet-measurement.com 1
azpdegb6.stretchoid.com 1
249.211.203.35.bc.googleusercontent.com 1
147.185.132.155 1
92.118.39.213 1
ingenious.monitoring.internet-measurement.com 1
ec2-18-237-81-41.us-west-2.compute.amazonaws.com 1
pleasant.monitoring.internet-measurement.com 1
server-0-3.survey.inspici.com 1
104.152.52.124 1
147.185.132.21 1
ec2-54-190-56-96.us-west-2.compute.amazonaws.com 1
TOTAL: 20 310

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
pleasant.monitoring.internet-measurement.com 6
249.211.203.35.bc.googleusercontent.com 4
147.185.132.155 4
147.45.112.8 1
91.238.181.71 1
206.168.34.127 1
azpdegb6.stretchoid.com 1
TOTAL: 7 18

NNRP unrecognized commands (by command):

CommandCount
GET / HTTP/1.1 3
Accept-Encoding: gzip 3
#003 2
User-Agent: Expanse, a Palo Alto Network... 2
Host: 45.13.105.36:563 2
MGLNDD_45.13.105.36_119 1
Host: [2a0e:e701:1154:0:4e10:5c89:4af0:d... 1
Connection: close 1
Accept: */* 1
EHLO www.censys.io 1
User-Agent: Mozilla/5.0 (compatible; Int... 1
TOTAL: 11 18

NNRP client timeouts [Top 20]:

SystemConnPeer
206.168.34.127 1 2
147.45.112.8 1 1
91.238.181.71 1 1
TOTAL: 3 3 4

Newsgroup request counts (by category):

CategoryCountPct
1grisbi 15789100.0%
2local 2 0.0%
TOTAL: 2 15791100.0%

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

NewsgroupCount
1grisbi.bugsreports 7972
2grisbi.test 5323
3grisbi.user.french 1880
4grisbi.devel 614
5local.test 2
TOTAL: 5 15791
innreport from INN 2.6.4 based on work (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>
Local contact: usenet@niel.me