Daily Usenet report for news.niel.me

Feb 7 04:15:01 -- Feb 8 04:15:01

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Feb 8 00:28:06 rpi-studio innd: filter: No Cancel-Key[sha1:mmuOXSYzrqt4uHhRvGE56J4sXlU= sha256:WjeOQ6AXXByGj0nqhCZ3pmuTrWiLSl1ixZwdVxH1+OE=] matches Cancel-Lock[sha1:th8lvpoe1K3OD4kvgujG3eOihHA= sha256:zCQQ+2qReKduWTpdiA26hdhyx6J02yVB64Jmfp3deN0=] target=<ln3dqj9kqddi5sk0rk3jfje8v5lhdn90be@news.usenet.ovh>
Feb 8 00:29:39 rpi-studio innd: filter: No Cancel-Key[sha1:gNf2MnjN3/chYyxbHiGugnOTmCk= sha256:mN6lfgJZbN9PBGAodjs7fkxAFz/tQYStuI+daAHGxUg=] matches Cancel-Lock[sha1:PVacgIAH9WyfB3W7JMftD7yVq34= sha256:nVugnzBi0N1mxg+JnOrWoRNq018C3K98Mi5lNtxuRxk=] target=<3k4dqj92p5c6msd349c87qvgiq01m4vhkm@news.usenet.ovh>
Feb 8 01:30:02 rpi-studio innd: filter: No Cancel-Key[sha1:BZdiS/0KZI8J3YpHM3GtJEeTYYA=] matches Cancel-Lock[sha1:7NZSaUANqwv75Ob/y1+unl58Q+o=] target=<de-gruppen-finden-01.02.2025@dnqmail.priggish.de>

Log entries by program:

Program nameLines%LinesSize%Size
inn 2665 31.5%500.6 KB 36.5%
innd 2119 25.0%375.3 KB 27.4%
nnrpd 1900 22.5%230.8 KB 16.8%
innfeed 1774 21.0%263.0 KB 19.2%
controlchan 1 0.0%0.1 KB 0.0%
nocem 1 0.0%0.2 KB 0.0%
TOTAL: 6 8460 100.0%1.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 13525 56.2%
Negative hits 7890 32.8%
Do not exist 2630 10.9%
Cache misses 5 0.0%
TOTAL: 4 24050 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.016 0.0%15 0.000 1.067 2.000
article cancel00:00:00.004 0.0%18 0.000 0.222 1.000
article cleanup00:00:00.059 0.0%2637 0.000 0.022 0.250
article logging00:00:00.193 0.0%2637 0.000 0.073 0.286
article parse00:00:00.391 0.0%7439 0.000 0.053 0.222
article write00:00:01.256 0.0%2474 0.000 0.508 1.750
artlog/artcncl00:00:00.000 0.0%18 0.000 0.000 0.000
artlog/artcncl/perl00:00:00.001 0.0%15 0.000 0.067 0.500
data move00:00:00.144 0.0%26471 0.000 0.005 0.037
hisgrep/artcncl00:00:00.001 0.0%18 0.000 0.056 1.000
hisgrep/artcncl/perl00:00:00.001 0.0%15 0.000 0.067 1.000
hisgrep/perl00:00:00.001 0.0%20 0.000 0.050 0.500
hishave/artcncl00:00:00.000 0.0%18 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.000 0.0%15 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.358 0.0%24089 0.000 0.015 0.083
history sync00:00:00.061 0.0%398 0.000 0.153 1.000
history write00:00:00.425 0.0%2637 0.000 0.161 0.667
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle24:00:47.566100.0%28923 452.990 2988.887 21454.250
nntp read00:00:00.718 0.0%28194 0.000 0.025 0.116
overview write00:00:00.744 0.0%2474 0.000 0.301 2.429
perl filter00:00:10.066 0.0%2637 1.453 3.817 11.667
python filter00:00:00.009 0.0%2637 0.000 0.003 0.067
site send00:00:00.894 0.0%5002 0.000 0.179 0.545
TOTAL: 24:01:10.52724:01:02.908100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.020 0.0%2729 0.000 0.007 0.095
article prepare00:00:00.000 0.0%444 0.000 0.000 0.000
article read00:00:00.029 0.0%105 0.000 0.276 7.000
backlog stats00:00:00.179 0.0%33347 0.000 0.005 0.029
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.448 0.0%18590 0.000 0.024 0.125
data write00:00:00.901 0.0%16421 0.015 0.055 0.141
idle23:59:21.090100.0%33347 716.346 2589.771 8073.526
status file00:00:01.811 0.0%226 0.500 8.013 76.000
TOTAL: 23:59:30.12823:59:24.478100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:44.906 18.8%1452 9.600 30.927 10036.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.097 0.0%3276 0.000 0.030 0.500
TOTAL: 00:03:58.29600:00:45.003 18.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 2419 765 1654 0 31%23:58:18
2news.samoylyk.net 3 2931 664 2267 0 22%28:25:37
3gegeweb-out.news.tnetconsulting.net 1 1840 479 1338 23 26%26:36:25
4i2pn.org 2 2464 206 2255 3 8%24:25:37
5news.nntp4.net 2 1461 157 1250 54 10%14:43:29
6usenet.pasdenom.info 5 1206 117 1084 5 9%22:23:10
7news.glou.org 2 253 28 211 14 11%05:16:52
8nntp.terraraq.uk 3 94 8 84 2 8%03:34:51
9localhost 25 0 0 0 0 0%00:00:00
TOTAL: 9 46 12668 2424 10143 101 19%149:24:19
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.samoylyk.net5.8 MB0.0 KB0.0 KB5.8 MB100%8.9 KB
2feed-out.gegeweb.org2.6 MB0.0 KB0.0 KB2.6 MB100%3.5 KB
3gegeweb-out.news.tnetconsulting.net1.5 MB0.0 KB101.7 KB1.6 MB 93%3.2 KB
4news.nntp4.net807.5 KB0.0 KB87.9 KB895.4 KB 90%4.2 KB
5i2pn.org535.5 KB0.0 KB6.6 KB542.1 KB 98%2.6 KB
6usenet.pasdenom.info293.4 KB0.0 KB5.8 KB299.2 KB 98%2.5 KB
7news.glou.org151.9 KB0.0 KB87.7 KB239.6 KB 63%5.7 KB
8nntp.terraraq.uk36.5 KB0.0 KB3.9 KB40.4 KB 90%4.0 KB
9localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 911.7 MB0.0 KB293.6 KB12.0 MB 97%4.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Feb 7 04:15:01 - 04:59:59 38 1.5% 0.01170.5 KB 1.5% 0.06
Feb 7 05:00:00 - 05:59:59 27 1.1% 0.01100.6 KB 0.9% 0.03
Feb 7 06:00:00 - 06:59:59 30 1.2% 0.01103.2 KB 0.9% 0.03
Feb 7 07:00:00 - 07:59:59 45 1.8% 0.01533.6 KB 4.5% 0.15
Feb 7 08:00:00 - 08:59:59 83 3.4% 0.02350.4 KB 3.0% 0.10
Feb 7 09:00:00 - 09:59:59 72 2.9% 0.02296.5 KB 2.5% 0.08
Feb 7 10:00:00 - 10:59:59 91 3.7% 0.03301.0 KB 2.6% 0.08
Feb 7 11:00:00 - 11:59:59 77 3.1% 0.02240.9 KB 2.1% 0.07
Feb 7 12:00:00 - 12:59:59 116 4.7% 0.03443.8 KB 3.8% 0.12
Feb 7 13:00:00 - 13:59:59 206 8.3% 0.062.5 MB 22.2% 0.72
Feb 7 14:00:00 - 14:59:59 129 5.2% 0.04430.5 KB 3.7% 0.12
Feb 7 15:00:00 - 15:59:59 140 5.7% 0.04508.1 KB 4.3% 0.14
Feb 7 16:00:00 - 16:59:59 97 3.9% 0.03368.0 KB 3.1% 0.10
Feb 7 17:00:00 - 17:59:59 200 8.1% 0.06844.6 KB 7.2% 0.23
Feb 7 18:00:00 - 18:59:59 155 6.3% 0.04486.0 KB 4.1% 0.14
Feb 7 19:00:00 - 19:59:59 123 5.0% 0.03463.2 KB 3.9% 0.13
Feb 7 20:00:00 - 20:59:59 101 4.1% 0.03494.8 KB 4.2% 0.14
Feb 7 21:00:00 - 21:59:59 99 4.0% 0.03347.2 KB 3.0% 0.10
Feb 7 22:00:00 - 22:59:59 167 6.8% 0.05573.9 KB 4.9% 0.16
Feb 7 23:00:00 - 23:59:59 111 4.5% 0.03853.0 KB 7.3% 0.24
Feb 8 00:00:00 - 00:59:59 102 4.1% 0.03398.3 KB 3.4% 0.11
Feb 8 01:00:00 - 01:59:59 98 4.0% 0.03351.3 KB 3.0% 0.10
Feb 8 02:00:00 - 02:59:59 73 3.0% 0.02233.7 KB 2.0% 0.06
Feb 8 03:00:00 - 03:59:59 83 3.4% 0.02211.1 KB 1.8% 0.06
Feb 8 04:00:00 - 04:15:01 7 0.3% 0.0123.7 KB 0.2% 0.03
TOTAL: 24:00:00 2470 100.0% 0.0311.5 MB 100.0% 0.14
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 107 0 0 0 0 0 0 0 107
2news.glou.org 24 0 0 0 0 0 0 0 24
3gegeweb-out.news.tnetconsulting.net 20 1 0 0 0 0 0 0 19
4usenet.pasdenom.info 5 0 0 0 0 0 0 0 5
5i2pn.org 3 0 0 0 0 0 0 0 3
6nntp.terraraq.uk 3 0 0 0 0 0 0 0 3
TOTAL: 6 162 1 0 0 0 0 0 0 161

Unwanted newsgroups [Top 20]:

NewsgroupCount
soc.culture.iran 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 99
Bad path (news.giganews.com.POSTED) 52
Cancels abusifs 5
hierarchy violation - crosspost outside sdnet 1
Cancel of non-existing ID <vo5985$3iaap$1@dont-email.me> 1
HTML Multipart 1
Cancel of non-existing ID <vo4kgs$3emgp$1@dont-email.me> 1
EMP (md5) 1
TOTAL: 8 161

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1i2pn.org 3422 60 2184 13 0 129 1%23:58:46
2news.gegeweb.org 1881 19 1700 0 0 0 1%23:51:07
3news.tnetconsulting.net 3719 6 2451 9 0 102 0%23:58:46
4news.nntp4.net 2513 2 2235 0 0 1 0%23:58:51
5news.samoylyk.net 2204 1 1827 1 0 27 0%23:58:46
6news.pasdenom.info 1926 0 1757 0 0 0 0%23:58:46
7news.aioe.org 0 0 0 0 0 2158 0%24:00:00
8nntp.terraraq.uk 1325 0 1251 0 0 0 0%23:42:08
TOTAL: 8 16990 88 13405 23 0 2417 0%191:27:10
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1i2pn.org248.3 KB154.2 KB402.5 KB0.0 KB/s5.5 KB23:58:46
2news.gegeweb.org60.9 KB0.0 KB60.9 KB0.0 KB/s3.2 KB23:51:07
3news.tnetconsulting.net12.5 KB39.3 KB51.8 KB0.0 KB/s3.5 KB23:58:46
4news.nntp4.net5.6 KB0.0 KB5.6 KB0.0 KB/s2.8 KB23:58:51
5news.samoylyk.net1.8 KB2.2 KB4.0 KB0.0 KB/s2.0 KB23:58:46
6news.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:46
7news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:00
8nntp.terraraq.uk0.0 KB0.0 KB0.0 KB0.0 KB/s023:42:08
TOTAL: 8329.0 KB195.6 KB524.7 KB0.0 KB/s4.7 KB191:27:10
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
12a06:4882:1000::10 1 00.0 KB 0 0 000:00:04
2205.210.31.86 1 00.0 KB 0 0 000:00:01
32a06:4882:5000::4f 1 00.0 KB 0 0 000:00:00
4nurturing.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
5104.152.52.124 1 00.0 KB 0 0 000:00:00
62a06:4882:5000::52 1 00.0 KB 0 0 000:00:00
780.66.76.121 1 00.0 KB 0 0 000:00:00
8magical.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
9147.185.132.244 1 00.0 KB 0 0 000:00:00
10104.152.52.133 1 00.0 KB 0 0 000:00:10
11206.168.34.71 7 00.0 KB 0 0 000:01:09
12fascinating.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
132a06:4882:5000::60 1 00.0 KB 0 0 000:00:00
14wonderful.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
15azpdsgc50.stretchoid.com 1 00.0 KB 0 0 000:00:00
16205.210.31.245 1 00.0 KB 0 0 000:00:10
17imperative.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:03
18pleasant.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
19199.45.154.135 3 00.0 KB 0 0 000:00:04
202a06:4882:5000::55 1 00.0 KB 0 0 000:00:00
21mail.gegeweb.eu 288 00.0 KB 0 0 000:02:05
22198.235.24.151 1 00.0 KB 0 0 000:00:05
TOTAL: 22 317 00.0 KB 0 0 000:03:57

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.gegeweb.eu 288 00.0 KB 0 0 000:02:05
2? 5 00.0 KB 0 0 000:00:05
3unresolved 17 00.0 KB 0 0 000:01:42
4*.monitoring.internet-measurement.com 6 00.0 KB 0 0 000:00:03
5*.stretchoid.com 1 00.0 KB 0 0 000:00:00
TOTAL: 5 317 00.0 KB 0 0 000:03:57

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.309 3.845 1.59700:02:05
206.168.34.71 0.312 0.126 0.00000:01:09
205.210.31.245 0.009 0.013 0.00000:00:10
104.152.52.133 0.028 0.024 0.00000:00:10
198.235.24.151 0.026 0.026 0.00000:00:05
199.45.154.135 0.107 0.055 0.00000:00:04
2a06:4882:1000::10 0.044 0.028 0.00000:00:04
imperative.monitoring.internet-measurement.com 0.043 0.017 0.00000:00:03
205.210.31.86 0.029 0.021 0.00000:00:01
2a06:4882:5000::55 0.044 0.020 0.00000:00:00
147.185.132.244 0.060 0.016 0.00000:00:00
104.152.52.124 0.014 0.009 0.00000:00:00
wonderful.monitoring.internet-measurement.com 0.040 0.024 0.00000:00:00
azpdsgc50.stretchoid.com 0.016 0.036 0.00000:00:00
2a06:4882:5000::52 0.038 0.023 0.00000:00:00
2a06:4882:5000::4f 0.042 0.021 0.00000:00:00
80.66.76.121 0.018 0.009 0.00000:00:00
nurturing.monitoring.internet-measurement.com 0.008 0.020 0.00000:00:00
pleasant.monitoring.internet-measurement.com 0.012 0.016 0.00000:00:00
TOTAL: 19 5.199 4.349 1.59700:03:57

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
147.185.132.244 4
azpdsgc50.stretchoid.com 1
199.45.154.135 1
206.168.34.71 1
TOTAL: 4 7

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

SystemConnPeer
199.45.154.135 1 2
TOTAL: 1 1 4