Daily Usenet report for news.niel.me

Sep 25 04:15:02 -- Sep 26 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Sep 25 16:40:28 rpi-studio innd: filter: No Cancel-Key[sha1:vMqY6DQ4p9+zjJtBBDu2mCOuZ1E= sha256:Jxg2SdDGsCcTbMJuGBiaRfxfcabpZrZvKYUvDdE174w=] matches Cancel-Lock[sha1:HKY+vXC5mNdf7uQB0HfehNrCVN8= sha256:j7jQjHOBCqz49n5+zEqmHPD1ar15gQmcV9MrIFZjo5k=] target=<1r0fjkd.1n315ae1c5sfazN%g4fleurot@free.fr.invalid>

Log entries by program:

Program nameLines%LinesSize%Size
inn 2401 29.6%453.7 KB 34.5%
innd 2025 24.9%369.2 KB 28.1%
innfeed 1847 22.8%265.5 KB 20.2%
nnrpd 1842 22.7%225.7 KB 17.2%
controlchan 1 0.0%0.1 KB 0.0%
nocem 1 0.0%0.2 KB 0.0%
TOTAL: 6 8117 100.0%1.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 11314 55.8%
Negative hits 6571 32.4%
Do not exist 2377 11.7%
Cache misses 2 0.0%
TOTAL: 4 20264 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.008 0.0%6 1.000 1.333 1.500
article cancel00:00:00.002 0.0%6 0.000 0.333 1.000
article cleanup00:00:00.056 0.0%2392 0.000 0.023 0.286
article logging00:00:00.184 0.0%2393 0.000 0.077 0.333
article parse00:00:00.348 0.0%5449 0.000 0.064 0.286
article write00:00:01.186 0.0%2199 0.000 0.539 1.222
artlog/artcncl00:00:00.001 0.0%6 0.000 0.167 0.500
artlog/artcncl/perl00:00:00.000 0.0%6 0.000 0.000 0.000
artlog/artwrite00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.116 0.0%21546 0.000 0.005 0.029
hisgrep/artcncl00:00:00.000 0.0%6 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.000 0.0%6 0.000 0.000 0.000
hisgrep/perl00:00:00.002 0.0%16 0.000 0.125 1.000
hishave/artcncl00:00:00.000 0.0%6 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.333 0.0%20359 0.000 0.016 0.071
history sync00:00:00.061 0.0%401 0.000 0.152 1.000
history write00:00:00.394 0.0%2389 0.000 0.165 0.750
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle24:00:49.807100.0%23700 1497.332 3647.671 18769.906
nntp read00:00:00.647 0.0%22977 0.000 0.028 0.078
overview write00:00:00.786 0.0%2199 0.000 0.357 5.000
perl filter00:00:09.413 0.0%2389 2.000 3.940 10.333
python filter00:00:00.011 0.0%2389 0.000 0.005 0.154
site send00:00:00.881 0.0%4443 0.000 0.198 0.667
TOTAL: 24:01:11.45824:01:04.236100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.020 0.0%2208 0.000 0.009 0.125
article prepare00:00:00.002 0.0%400 0.000 0.005 0.083
article read00:00:00.027 0.0%95 0.000 0.284 4.000
backlog stats00:00:00.165 0.0%32586 0.000 0.005 0.023
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.408 0.0%17369 0.000 0.023 0.073
data write00:00:00.896 0.0%15385 0.017 0.058 0.158
idle23:58:25.106100.0%32586 1049.393 2648.533 8039.987
status file00:00:00.540 0.0%264 0.000 2.045 6.000
TOTAL: 23:58:31.84823:58:27.164100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:01:01.258 37.9%1452 3.000 42.189 10047.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.111 0.1%3324 0.000 0.033 0.500
TOTAL: 00:02:41.47200:01:01.369 38.0%----

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 2153 1257 896 0 58%23:26:30
2gegeweb-out.news.tnetconsulting.net 1 1541 242 1273 26 15%19:32:56
3news.nntp4.net 2 2301 198 2007 96 8%24:23:01
4news.samoylyk.net 2 2174 186 1985 3 8%23:03:04
5usenet.pasdenom.info 4 1305 94 1198 13 7%21:53:27
6news.glou.org 2 1805 79 1682 44 4%23:58:14
7nntp.terraraq.uk 2 1864 35 1828 1 1%22:07:18
8i2pn.org 11 0 0 0 0 0%01:13:12
9localhost 25 0 0 0 0 0%00:00:00
TOTAL: 9 52 13143 2091 10869 183 15%159:37:42
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1feed-out.gegeweb.org3.9 MB0.0 KB0.0 KB3.9 MB100%3.2 KB
2news.samoylyk.net1.7 MB0.0 KB12.4 KB1.7 MB 99%9.2 KB
3gegeweb-out.news.tnetconsulting.net873.3 KB4.0 KB94.9 KB972.3 KB 89%3.6 KB
4news.nntp4.net769.0 KB3.4 KB266.0 KB1.0 MB 74%3.5 KB
5news.glou.org472.2 KB0.0 KB136.3 KB608.5 KB 77%4.9 KB
6nntp.terraraq.uk237.9 KB3.4 KB3.4 KB244.6 KB 97%6.8 KB
7usenet.pasdenom.info212.7 KB0.0 KB18.4 KB231.1 KB 92%2.2 KB
8i2pn.org0.0 KB0.0 KB0.0 KB0.0 KB 0%0
9localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 98.1 MB10.8 KB531.5 KB8.7 MB 93%3.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 25 04:15:02 - 04:59:59 30 1.4% 0.0186.0 KB 1.0% 0.03
Sep 25 05:00:00 - 05:59:59 37 1.7% 0.01119.8 KB 1.4% 0.03
Sep 25 06:00:00 - 06:59:59 32 1.5% 0.01109.5 KB 1.3% 0.03
Sep 25 07:00:00 - 07:59:59 58 2.6% 0.02195.1 KB 2.3% 0.05
Sep 25 08:00:00 - 08:59:59 47 2.1% 0.01438.0 KB 5.1% 0.12
Sep 25 09:00:00 - 09:59:59 66 3.0% 0.02229.9 KB 2.7% 0.06
Sep 25 10:00:00 - 10:59:59 72 3.3% 0.02265.5 KB 3.1% 0.07
Sep 25 11:00:00 - 11:59:59 69 3.1% 0.02245.4 KB 2.9% 0.07
Sep 25 12:00:00 - 12:59:59 65 3.0% 0.02554.9 KB 6.5% 0.15
Sep 25 13:00:00 - 13:59:59 128 5.8% 0.04442.6 KB 5.2% 0.12
Sep 25 14:00:00 - 14:59:59 113 5.1% 0.03412.9 KB 4.8% 0.11
Sep 25 15:00:00 - 15:59:59 101 4.6% 0.03312.8 KB 3.7% 0.09
Sep 25 16:00:00 - 16:59:59 105 4.8% 0.03379.6 KB 4.5% 0.11
Sep 25 17:00:00 - 17:59:59 147 6.7% 0.04460.4 KB 5.4% 0.13
Sep 25 18:00:00 - 18:59:59 141 6.4% 0.04479.0 KB 5.6% 0.13
Sep 25 19:00:00 - 19:59:59 88 4.0% 0.02313.5 KB 3.7% 0.09
Sep 25 20:00:00 - 20:59:59 142 6.5% 0.04475.8 KB 5.6% 0.13
Sep 25 21:00:00 - 21:59:59 173 7.9% 0.05580.3 KB 6.8% 0.16
Sep 25 22:00:00 - 22:59:59 143 6.5% 0.04687.1 KB 8.1% 0.19
Sep 25 23:00:00 - 23:59:59 152 6.9% 0.04659.3 KB 7.7% 0.18
Sep 26 00:00:00 - 00:59:59 102 4.6% 0.03437.2 KB 5.1% 0.12
Sep 26 01:00:00 - 01:59:59 57 2.6% 0.02208.6 KB 2.4% 0.06
Sep 26 02:00:00 - 02:59:59 53 2.4% 0.01170.5 KB 2.0% 0.05
Sep 26 03:00:00 - 03:59:59 60 2.7% 0.02209.6 KB 2.5% 0.06
Sep 26 04:00:00 - 04:15:01 14 0.6% 0.0248.5 KB 0.6% 0.05
TOTAL: 23:59:59 2195 100.0% 0.038.3 MB 100.0% 0.10
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 96 0 0 1 0 0 0 0 95
2news.glou.org 44 0 0 0 0 0 0 0 44
3gegeweb-out.news.tnetconsulting.net 37 0 0 1 0 0 0 0 36
4usenet.pasdenom.info 13 0 0 0 0 0 0 0 13
5news.samoylyk.net 3 0 0 0 0 0 0 0 3
6nntp.terraraq.uk 1 0 0 1 0 0 0 0 0
TOTAL: 6 194 0 0 3 0 0 0 0 191

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 88
EMP (phn path) 53
Bad path (news.giganews.com.POSTED) 38
Cancel of <vd19f8$2m77p$1@news.usenet.ovh> without Cancel-Key 1
Cancel of <a3203162555bdf76294463ceac11335b3e58c72e@i2pn2.org> without Cancel-Key 1
Cancel of <11206844e490ad0fe8aa4df37135b3a15bb74ea1@i2pn2.org> without Cancel-Key 1
Cancel of <ee1ee4a4c2d9418274bc5a94de80534b198eafd6@i2pn2.org> without Cancel-Key 1
Cancel of <e3ec687d7c866cf7fa9d65083756158027b56565@i2pn2.org> without Cancel-Key 1
No Cancel-Key matches Cancel-Lock. target=<1r0fjkd.1n315ae1c5sfazN%g4fleurot@free.fr.invalid> 1
EMP (md5) 1
Cancel of <6ea711d02a8ab5dcf5f7b8defed283bbee9dc900@i2pn2.org> without Cancel-Key 1
Cancel of <f0f2b20a9801a23f844476b760ec752429b7569b@i2pn2.org> without Cancel-Key 1
Cancel of <27e8afbfa8e8c8d7db47702669a99497b3acaab6@i2pn2.org> without Cancel-Key 1
Cancel of <189a7b69f12c3bdf32ccc80b5e03064283104ab9@i2pn2.org> without Cancel-Key 1
TOTAL: 14 190

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
1i2pn.org 3394 48 1962 5 0 13 1%23:59:57
2news.samoylyk.net 2262 8 1966 28 0 0 0%23:59:56
3news.tnetconsulting.net 3715 6 2183 3 0 0 0%23:59:57
4news.gegeweb.org 937 1 905 0 0 0 0%23:16:40
5news.pasdenom.info 1803 0 1787 0 0 0 0%23:59:57
6news.aioe.org 0 0 0 0 0 1684 0%23:59:59
7nntp.terraraq.uk 1293 0 1282 0 0 0 0%23:57:37
8news.nntp4.net 2092 0 1987 1 0 0 0%23:59:57
TOTAL: 8 15496 63 12072 37 0 1697 0%191:14:00
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1i2pn.org122.1 KB36.2 KB158.3 KB0.0 KB/s3.0 KB23:59:57
2news.samoylyk.net24.3 KB134.2 KB158.4 KB0.0 KB/s4.4 KB23:59:56
3news.tnetconsulting.net17.2 KB6.3 KB23.5 KB0.0 KB/s2.6 KB23:59:57
4news.gegeweb.org2.3 KB0.0 KB2.3 KB0.0 KB/s2.3 KB23:16:40
5news.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
6news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:59
7nntp.terraraq.uk0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:37
8news.nntp4.net0.0 KB5.0 KB5.0 KB0.0 KB/s5.0 KB23:59:57
TOTAL: 8165.8 KB181.7 KB347.5 KB0.0 KB/s3.5 KB191:14:00
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1adored.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
2mail.gegeweb.eu 288 00.0 KB 0 0 000:01:49
3198.235.24.167 1 00.0 KB 0 0 000:00:10
4199.45.154.147 3 00.0 KB 0 0 000:00:07
591.238.181.20 1 00.0 KB 0 0 000:00:00
6server-0-3.survey.inspici.com 1 00.0 KB 0 0 000:00:04
7nurturing.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:04
8199.45.154.136 7 00.0 KB 0 0 000:00:07
9147.185.132.63 1 00.0 KB 0 0 000:00:10
1082.211.203.35.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
1191.238.181.31 1 00.0 KB 0 0 000:00:03
12147.185.132.157 1 00.0 KB 0 0 000:00:00
TOTAL: 12 307 00.0 KB 0 0 000:02:40

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.monitoring.internet-measurement.com 2 00.0 KB 0 0 000:00:06
2unresolved 15 00.0 KB 0 0 000:00:40
3*.survey.inspici.com 1 00.0 KB 0 0 000:00:04
4*.211.203.35.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
5*.gegeweb.eu 288 00.0 KB 0 0 000:01:49
TOTAL: 5 307 00.0 KB 0 0 000:02:40

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 4.928 4.265 1.64800:01:49
198.235.24.167 0.036 0.013 0.00000:00:10
147.185.132.63 0.025 0.025 0.00000:00:10
199.45.154.136 0.294 0.083 0.00000:00:07
199.45.154.147 0.104 0.059 0.00000:00:07
server-0-3.survey.inspici.com 0.025 0.025 0.00000:00:04
nurturing.monitoring.internet-measurement.com 0.028 0.020 0.00000:00:04
91.238.181.31 0.031 0.015 0.00000:00:03
adored.monitoring.internet-measurement.com 0.026 0.026 0.00000:00:01
147.185.132.157 0.050 0.029 0.00000:00:00
82.211.203.35.bc.googleusercontent.com 0.058 0.019 0.00000:00:00
91.238.181.20 0.014 0.014 0.00000:00:00
TOTAL: 12 5.619 4.593 1.64800:02:40

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
147.185.132.157 4
82.211.203.35.bc.googleusercontent.com 4
199.45.154.147 1
91.238.181.31 1
199.45.154.136 1
TOTAL: 5 11

NNRP unrecognized commands (by command):

CommandCount
User-Agent: Expanse, a Palo Alto Network... 2
Accept-Encoding: gzip 2
GET / HTTP/1.1 2
EHLO www.censys.io 2
Host: 45.13.105.36:563 2
#003 1
TOTAL: 6 11

NNRP client timeouts [Top 20]:

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