Daily Usenet report for news.niel.me

Sep 11 04:15:01 -- Sep 12 04:15:01

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Sep 11 08:54:25 rpi-studio innd: filter: No Cancel-Key[sha256:JeofuC3zpnupxqqjJsASDv5YNBQ6ALEb8y4zt4DzMIc=] matches Cancel-Lock[sha256:KOA5N3BHTbyMCdwSZtGerTT2lTTpFCf+/8LapvlWvIM=] target=<tfk0l8$a31$1@rasp.pasdenom.info>
Sep 11 10:20:42 rpi-studio innd: filter: No Cancel-Key[sha256:X34wDoGbKfVVLu7vLEEf0mlH2Hl0uKSFgV/CATVe1GI=] matches Cancel-Lock[sha256:omFY06uH1Ln0YUXuQRjmrrAiJSPubE2POpt/e7B8efs=] target=<tfk5n0$mkb$1@rasp.pasdenom.info>
Sep 11 10:27:10 rpi-studio innd: filter: No Cancel-Key[sha256:HH5AQIZkv4TlziEYNgsFgN8kITHL2VSmMIv3jtH6kGw=] matches Cancel-Lock[sha256:+i0ivGNDVEzqBxtCpqBhdsAwfxkz1VmOpW+71Fk05Es=] target=<tfk63s$rbv$1@rasp.pasdenom.info>
Sep 11 10:55:52 rpi-studio innd: filter: No Cancel-Key[sha256:vwHfy8BefJX+ovPzatNd28C2qFznCeHpYKZkL4ReIIA=] matches Cancel-Lock[sha256:UEaBPOcYbmZQ8wWnY6xE7rNPPtvq0jspmF5d7BTtPi4=] target=<tfk69p$rbv$2@rasp.pasdenom.info>

Log entries by program:

Program nameLines%LinesSize%Size
inn 3906 40.4%749.0 KB 46.0%
innd 2526 26.1%434.7 KB 26.7%
innfeed 1719 17.8%293.6 KB 18.0%
nnrpd 1517 15.7%151.5 KB 9.3%
nocem 9 0.1%0.8 KB 0.1%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 6 9678 100.0%1.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 26173 66.7%
Negative hits 9187 23.4%
Do not exist 3866 9.9%
Cache misses 4 0.0%
TOTAL: 4 39230 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.014 0.0%10 0.000 1.400 5.000
article cancel00:00:00.005 0.0%20 0.000 0.250 1.000
article cleanup00:00:00.067 0.0%3886 0.000 0.017 0.167
article logging00:00:00.274 0.0%3893 0.000 0.070 0.333
article parse00:00:00.732 0.0%6946 0.000 0.105 0.333
article write00:00:02.245 0.0%3528 0.083 0.636 4.611
artlog/artcncl00:00:00.000 0.0%20 0.000 0.000 0.000
artlog/artcncl/perl00:00:00.000 0.0%10 0.000 0.000 0.000
data move00:00:00.115 0.0%42153 0.000 0.003 0.019
hisgrep/artcncl00:00:00.000 0.0%15 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.001 0.0%10 0.000 0.100 1.000
hisgrep/perl00:00:00.001 0.0%17 0.000 0.059 1.000
hishave/artcncl00:00:00.000 0.0%20 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.000 0.0%10 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.514 0.0%39449 0.000 0.013 0.034
history sync00:00:00.054 0.0%411 0.000 0.131 0.667
history write00:00:01.140 0.0%3886 0.000 0.293 13.143
hiswrite/artcncl00:00:00.002 0.0%5 0.000 0.400 1.000
idle24:00:32.691100.0%42737 786.425 2022.432 8577.686
nntp read00:00:01.266 0.0%42228 0.000 0.030 0.064
overview write00:00:02.455 0.0%3528 0.000 0.696 5.550
perl filter00:00:17.609 0.0%3886 2.839 4.531 29.833
python filter00:00:00.003 0.0%3886 0.000 0.001 0.028
site send00:00:01.118 0.0%7103 0.000 0.157 0.485
TOTAL: 24:01:12.90324:01:00.306100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.037 0.0%3550 0.000 0.010 0.364
article prepare00:00:00.001 0.0%988 0.000 0.001 0.250
article read00:00:00.047 0.0%206 0.000 0.228 8.000
backlog stats00:00:00.136 0.0%52345 0.000 0.003 0.013
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.798 0.0%31811 0.000 0.025 0.094
data write00:00:01.846 0.0%28348 0.021 0.065 0.118
idle23:59:04.038100.0%52345 733.306 1649.518 4325.050
status file00:00:00.619 0.0%176 1.000 3.517 52.000
TOTAL: 23:59:10.68823:59:07.522100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:28.466 28.0%1441 11.600 19.754 5619.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.098 0.1%3183 0.000 0.031 0.273
TOTAL: 00:01:41.71400:00:28.564 28.1%----

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-out.aioe.org 2 3818 1530 2233 55 40%25:43:12
2i2pn.org 1 3814 1527 2044 243 40%26:00:52
3news.glou.org 2 2420 206 2199 15 8%18:13:20
4feed-out.gegeweb.org 2 2277 190 2087 0 8%23:59:42
5usenet.pasdenom.info 5 2761 178 2567 16 6%21:48:17
6nntp.terraraq.uk 4 6337 103 6222 12 1%41:03:42
7gegeweb-out.news.tnetconsulting.net 2 3622 72 3550 0 1%23:59:20
8news.nntp4.net 1 2159 5 2122 32 0%11:50:35
9news.samoylyk.net 2 2249 4 2245 0 0%16:52:55
10localhost 29 0 0 0 0 0%00:00:00
TOTAL: 10 50 29457 3815 25269 373 12%209:31:55
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news-out.aioe.org5.3 MB0.0 KB188.8 KB5.5 MB 96%3.5 KB
2i2pn.org5.0 MB0.0 KB606.2 KB5.6 MB 89%3.2 KB
3news.glou.org3.2 MB0.0 KB35.5 KB3.2 MB 98%15.0 KB
4feed-out.gegeweb.org588.3 KB0.0 KB0.0 KB588.3 KB100%3.1 KB
5nntp.terraraq.uk578.1 KB0.0 KB21.1 KB599.2 KB 96%5.2 KB
6gegeweb-out.news.tnetconsulting.net447.3 KB0.0 KB0.0 KB447.3 KB100%6.2 KB
7usenet.pasdenom.info397.2 KB0.0 KB26.1 KB423.3 KB 93%2.2 KB
8news.samoylyk.net214.4 KB0.0 KB0.0 KB214.4 KB100%53.6 KB
9news.nntp4.net89.5 KB0.0 KB84.3 KB173.8 KB 51%4.7 KB
10localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1015.8 MB0.0 KB962.1 KB16.7 MB 94%4.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 11 04:15:01 - 04:59:59 71 2.0% 0.03289.4 KB 2.0% 0.11
Sep 11 05:00:00 - 05:59:59 84 2.4% 0.02357.3 KB 2.5% 0.10
Sep 11 06:00:00 - 06:59:59 80 2.3% 0.02347.7 KB 2.4% 0.10
Sep 11 07:00:00 - 07:59:59 87 2.5% 0.02308.4 KB 2.1% 0.09
Sep 11 08:00:00 - 08:59:59 115 3.3% 0.03494.0 KB 3.4% 0.14
Sep 11 09:00:00 - 09:59:59 73 2.1% 0.02279.3 KB 1.9% 0.08
Sep 11 10:00:00 - 10:59:59 137 3.9% 0.04458.7 KB 3.2% 0.13
Sep 11 11:00:00 - 11:59:59 107 3.0% 0.03373.8 KB 2.6% 0.10
Sep 11 12:00:00 - 12:59:59 101 2.9% 0.03323.9 KB 2.2% 0.09
Sep 11 13:00:00 - 13:59:59 134 3.8% 0.04455.1 KB 3.2% 0.13
Sep 11 14:00:00 - 14:59:59 202 5.7% 0.061.4 MB 10.1% 0.41
Sep 11 15:00:00 - 15:59:59 182 5.2% 0.05659.4 KB 4.6% 0.18
Sep 11 16:00:00 - 16:59:59 141 4.0% 0.04441.9 KB 3.1% 0.12
Sep 11 17:00:00 - 17:59:59 187 5.3% 0.05782.1 KB 5.4% 0.22
Sep 11 18:00:00 - 18:59:59 217 6.2% 0.06985.1 KB 6.8% 0.27
Sep 11 19:00:00 - 19:59:59 181 5.1% 0.05687.4 KB 4.8% 0.19
Sep 11 20:00:00 - 20:59:59 222 6.3% 0.06812.7 KB 5.6% 0.23
Sep 11 21:00:00 - 21:59:59 242 6.9% 0.071.3 MB 9.5% 0.38
Sep 11 22:00:00 - 22:59:59 238 6.8% 0.07914.9 KB 6.3% 0.25
Sep 11 23:00:00 - 23:59:59 170 4.8% 0.05632.8 KB 4.4% 0.18
Sep 12 00:00:00 - 00:59:59 153 4.4% 0.04571.2 KB 4.0% 0.16
Sep 12 01:00:00 - 01:59:59 144 4.1% 0.04508.1 KB 3.5% 0.14
Sep 12 02:00:00 - 02:59:59 124 3.5% 0.03499.3 KB 3.5% 0.14
Sep 12 03:00:00 - 03:59:59 106 3.0% 0.03369.9 KB 2.6% 0.10
Sep 12 04:00:00 - 04:15:01 17 0.5% 0.0258.9 KB 0.4% 0.07
TOTAL: 24:00:00 3515 100.0% 0.0414.1 MB 100.0% 0.17
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1i2pn.org 230 0 0 0 0 0 0 0 230
2news-out.aioe.org 58 1 0 0 0 0 0 0 57
3news.nntp4.net 44 0 0 0 0 0 0 0 44
4usenet.pasdenom.info 9 0 0 0 0 0 0 0 9
5gegeweb-out.news.tnetconsulting.net 7 0 0 0 0 0 0 0 7
6nntp.terraraq.uk 7 0 0 0 0 0 0 0 7
7news.glou.org 6 0 0 0 0 0 0 0 6
TOTAL: 7 361 1 0 0 0 0 0 0 360

Unwanted newsgroups [Top 20]:

NewsgroupCount
news.test 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (phl) 121
Bad path (news.giganews.com.POSTED) 89
EMP (phn nph) 64
Too many newsgroups 50
EMP (md5) 27
Cancel of non-existing ID <tfk63s$rbv$1@rasp.pasdenom.info> 1
Cancel of <tfk9ss$4d4$1@rasp.pasdenom.info> without Cancel-Key 1
TOTAL: 7 353

NoCeM on Spool:

IdGoodBadUniqueTotal
bleachbot@httrack.com5055
TOTAL: 15055

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
i2pn.org 4
news-out.aioe.org 3
TOTAL: 2 7
TOTAL: 1 7

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1i2pn.org 3865 95 1987 8 0 5 2%23:59:56
2news.nntp4.net 4156 23 3486 0 0 2 0%23:59:56
3news.gegeweb.org 3468 18 3257 50 0 0 0%23:59:56
4nntp.terraraq.uk 2619 16 2384 11 0 4 0%23:59:56
5news.tnetconsulting.net 5322 11 3498 5 0 7 0%23:59:28
6news.aioe.org 1750 3 1591 2 0 0 0%23:59:56
7news.pasdenom.info 3218 1 3100 0 0 3 0%23:59:56
8news.samoylyk.net 3959 0 3504 4 0 12 0%23:59:56
TOTAL: 8 28357 167 22807 80 0 33 0%191:59:00
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.nntp4.net802.7 KB0.0 KB802.7 KB0.0 KB/s34.9 KB23:59:56
2nntp.terraraq.uk327.8 KB900.2 KB1.2 MB0.0 KB/s45.5 KB23:59:56
3i2pn.org259.9 KB19.4 KB279.3 KB0.0 KB/s2.7 KB23:59:56
4news.gegeweb.org85.2 KB173.4 KB258.6 KB0.0 KB/s3.8 KB23:59:56
5news.tnetconsulting.net58.9 KB11.3 KB70.1 KB0.0 KB/s4.4 KB23:59:28
6news.aioe.org29.1 KB5.0 KB34.1 KB0.0 KB/s6.8 KB23:59:56
7news.pasdenom.info3.9 KB0.0 KB3.9 KB0.0 KB/s3.9 KB23:59:56
8news.samoylyk.net0.0 KB9.3 KB9.3 KB0.0 KB/s2.3 KB23:59:56
TOTAL: 81.5 MB1.1 MB2.6 MB0.0 KB/s10.9 KB191:59:00
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1179.60.150.88 1 00.0 KB 0 0 000:00:00
2185.220.101.159 4 00.0 KB 0 0 000:00:03
3185.254.75.55 2 00.0 KB 0 0 000:00:01
4185.220.101.179 1 00.0 KB 0 0 000:00:01
5mail.gegeweb.eu 288 00.0 KB 0 0 000:01:06
6pirate.census.shodan.io 1 00.0 KB 0 0 000:00:06
7107.189.31.134 1 00.0 KB 0 0 000:00:03
8185.220.101.174 1 00.0 KB 0 0 000:00:05
9185.220.101.189 2 00.0 KB 0 0 000:00:10
TOTAL: 9 301 00.0 KB 0 0 000:01:41

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.census.shodan.io 1 00.0 KB 0 0 000:00:06
2*.gegeweb.eu 288 00.0 KB 0 0 000:01:06
3unresolved 12 00.0 KB 0 0 000:00:27
TOTAL: 3 301 00.0 KB 0 0 000:01:41

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 7.938 8.249 2.37000:01:06
185.220.101.189 0.105 0.011 0.00000:00:10
pirate.census.shodan.io 0.028 0.028 0.00000:00:06
185.220.101.174 0.087 0.000 0.00000:00:05
107.189.31.134 0.061 0.017 0.00000:00:03
185.220.101.159 0.309 0.009 0.00000:00:03
185.254.75.55 0.071 0.017 0.00000:00:01
185.220.101.179 0.059 0.020 0.00000:00:01
179.60.150.88 0.038 0.000 0.00000:00:00
TOTAL: 9 8.696 8.351 2.37000:01:41

NNRP gethostbyaddr failures [Top 20]:

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