Daily Usenet report for news.niel.me: Sep 7 04:15:02

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Sep 7 09:38:16 rpi-studio innd: filter: No Cancel-Key[sha1:hVsEo89G53N1FNFT/j4P2CLOUCM= sha256:Yl3bj6b/CwYgKVM7TedugpzARDocmbK4b7fNrpEXLE8=] matches Cancel-Lock[sha1:2drpWguNpgzUpLIZ7jNkNlQp92o= sha256:MNwR8cqcjtIFHTr7gVhJzunoW1Op2n2uPJXnaWLxyx0=] target=<117lpkb$3201i$1@dont-email.me>
Sep 7 09:39:04 rpi-studio innd: filter: No Cancel-Key[sha1:u6DUtKQ010VrIPPW0ut/ZvUNG8U= sha256:+bGgWfOZTbvRGRmzwG0ilGg++ZfgM34z8HWI/wMJQfo=] matches Cancel-Lock[sha1:rnutAjYdvf0+Vc1kPUPf/50g2qw= sha256:SOISjg5ezqzQ2eGZ/ulEtUfwVNIXgEd0o6OuxCNxxGo=] target=<117lpm0$320ka$1@dont-email.me>
Sep 7 10:22:05 rpi-studio innd: filter: No Cancel-Key[sha1:yQY+6DcjX9zttR/9h3eGNU+msHc= sha256:BXiWOJ/As0UANvAUyhChfenysnGmdjDDWxw1ESJ+tJU=] matches Cancel-Lock[sha1:b/gtu5n6Dz0P1WsZmcpNOM4YO2I= sha256:rRO2IzERKJmBx10Lkj3RaXuS5Ap49VHk4VNM5o3mo9w=] target=<117ls6h$32tih$1@dont-email.me>
Sep 7 10:57:10 rpi-studio innd: filter: No Cancel-Key[sha1:ybe/sMvxRUbaXyh8BsfrsXpCR5w= sha256:aIWHzF3iSKzy9j8fsw7AjhZt8fMRHpx8E07HFMtdjDA=] matches Cancel-Lock[sha1:AMe/coi7tZvDz4xF1CoAbwdIN7U= sha256:tCmIzEgOY2wFZQ0pym+nJ0dDzekCsHRplSV8p00rKg0=] target=<117lu1b$33ilu$1@dont-email.me>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2635	31.5%	456.6 KB	35.4%
innd	1965	23.5%	330.5 KB	25.6%
nnrpd	1958	23.4%	227.2 KB	17.6%
innfeed	1794	21.5%	276.7 KB	21.4%
controlchan	1	0.0%	0.1 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	8354	100.0%	1.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	9115	51.3%
Negative hits	6038	34.0%
Do not exist	2609	14.7%
Cache misses	8	0.0%
TOTAL: 4	17770	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.014	0.0%	10	1.000	1.400	2.000
article cancel	00:00:00.002	0.0%	12	0.000	0.167	1.000
article cleanup	00:00:00.065	0.0%	2618	0.000	0.025	0.333
article logging	00:00:00.201	0.0%	2618	0.000	0.077	0.333
article parse	00:00:00.474	0.0%	7096	0.000	0.067	0.214
article write	00:00:01.071	0.0%	1992	0.000	0.538	2.000
artlog/artcncl	00:00:00.000	0.0%	12	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.001	0.0%	10	0.000	0.100	0.500
data move	00:00:00.130	0.0%	20865	0.000	0.006	0.034
hisgrep/artcncl	00:00:00.000	0.0%	12	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.001	0.0%	10	0.000	0.100	0.500
hisgrep/perl	00:00:00.000	0.0%	16	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	12	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	10	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:00.287	0.0%	17830	0.000	0.016	0.064
history sync	00:00:00.062	0.0%	329	0.000	0.188	2.000
history write	00:00:00.528	0.0%	2618	0.000	0.202	1.750
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:26.299	100.0%	22307	1575.911	3874.403	18198.212
nntp read	00:00:00.677	0.0%	21692	0.000	0.031	0.093
overview write	00:00:00.645	0.0%	1992	0.000	0.324	2.667
perl filter	00:00:15.175	0.0%	2618	1.773	5.796	20.571
python filter	00:00:00.013	0.0%	2618	0.000	0.005	0.083
site send	00:00:00.850	0.0%	4025	0.000	0.211	0.562
TOTAL: 24:01:06.486	24:00:46.495	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.018	0.0%	2120	0.000	0.008	0.167
article prepare	00:00:00.000	0.0%	24	0.000	0.000	0.000
article read	00:00:00.009	0.0%	6	0.000	1.500	8.000
backlog stats	00:00:00.134	0.0%	24158	0.000	0.006	0.028
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.317	0.0%	12136	0.000	0.026	0.083
data write	00:00:00.689	0.0%	10366	0.000	0.066	0.163
idle	23:58:24.029	100.0%	24158	1653.493	3572.482	9091.545
status file	00:00:09.572	0.0%	259	0.000	36.958	89.000
TOTAL: 23:58:39.339	23:58:34.768	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:32.286	33.5%	1458	0.000	22.144	10010.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.127	0.1%	3326	0.000	0.038	1.000
TOTAL: 00:01:36.349	00:00:32.413	33.6%	-	-	-	-

Control commands to INND:

Command	Number
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):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	feed-out.gegeweb.org	3	1954	1000	953	1	51%	23:58:49
2	news.samoylyk.net	2	2668	514	2154	0	19%	32:33:35
3	news.nntp4.net	1	2611	282	1763	566	10%	23:59:57
4	news.glou.org	2	1358	136	1177	45	10%	22:41:45
5	usenet.pasdenom.info	9	728	26	702	0	3%	18:10:36
6	nntp.terraraq.uk	2	227	19	208	0	8%	06:08:02
7	gegeweb-out.news.tnetconsulting.net	2	1336	8	1326	2	0%	23:56:24
8	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 8		46	10882	1985	8283	614	18%	151:29:08

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	feed-out.gegeweb.org	3.3 MB	1.3 KB	3.3 MB	99%	3.3 KB
2	news.samoylyk.net	2.7 MB	0.0 KB	2.7 MB	100%	5.4 KB
3	news.nntp4.net	1.6 MB	5.7 MB	7.3 MB	21%	8.8 KB
4	news.glou.org	471.0 KB	173.2 KB	644.2 KB	73%	3.6 KB
5	usenet.pasdenom.info	58.9 KB	0.0 KB	58.9 KB	100%	2.3 KB
6	gegeweb-out.news.tnetconsulting.net	56.2 KB	9.0 KB	65.3 KB	86%	6.5 KB
7	nntp.terraraq.uk	54.2 KB	0.0 KB	54.2 KB	100%	2.9 KB
8	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 8		8.2 MB	5.9 MB	14.1 MB	58%	5.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 7 04:15:02 - 04:59:59	61	3.1%	0.02	353.2 KB	4.3%	0.13
Sep 7 05:00:00 - 05:59:59	37	1.9%	0.01	136.3 KB	1.7%	0.04
Sep 7 06:00:00 - 06:59:59	40	2.0%	0.01	122.1 KB	1.5%	0.03
Sep 7 07:00:00 - 07:59:59	38	1.9%	0.01	145.5 KB	1.8%	0.04
Sep 7 08:00:00 - 08:59:59	73	3.7%	0.02	243.4 KB	3.0%	0.07
Sep 7 09:00:00 - 09:59:59	225	11.3%	0.06	1.0 MB	12.6%	0.29
Sep 7 10:00:00 - 10:59:59	71	3.6%	0.02	196.5 KB	2.4%	0.05
Sep 7 11:00:00 - 11:59:59	75	3.8%	0.02	225.7 KB	2.8%	0.06
Sep 7 12:00:00 - 12:59:59	79	4.0%	0.02	253.3 KB	3.1%	0.07
Sep 7 13:00:00 - 13:59:59	88	4.4%	0.02	295.1 KB	3.6%	0.08
Sep 7 14:00:00 - 14:59:59	67	3.4%	0.02	368.2 KB	4.5%	0.10
Sep 7 15:00:00 - 15:59:59	108	5.4%	0.03	318.8 KB	3.9%	0.09
Sep 7 16:00:00 - 16:59:59	96	4.8%	0.03	346.4 KB	4.3%	0.10
Sep 7 17:00:00 - 17:59:59	90	4.5%	0.03	719.6 KB	8.9%	0.20
Sep 7 18:00:00 - 18:59:59	106	5.3%	0.03	441.3 KB	5.4%	0.12
Sep 7 19:00:00 - 19:59:59	93	4.7%	0.03	391.4 KB	4.8%	0.11
Sep 7 20:00:00 - 20:59:59	105	5.3%	0.03	314.1 KB	3.9%	0.09
Sep 7 21:00:00 - 21:59:59	87	4.4%	0.02	302.0 KB	3.7%	0.08
Sep 7 22:00:00 - 22:59:59	86	4.3%	0.02	340.1 KB	4.2%	0.09
Sep 7 23:00:00 - 23:59:59	73	3.7%	0.02	355.0 KB	4.4%	0.10
Sep 8 00:00:00 - 00:59:59	55	2.8%	0.02	221.5 KB	2.7%	0.06
Sep 8 01:00:00 - 01:59:59	94	4.7%	0.03	385.7 KB	4.7%	0.11
Sep 8 02:00:00 - 02:59:59	68	3.4%	0.02	293.9 KB	3.6%	0.08
Sep 8 03:00:00 - 03:59:59	54	2.7%	0.01	267.0 KB	3.3%	0.07
Sep 8 04:00:00 - 04:15:01	20	1.0%	0.02	64.5 KB	0.8%	0.07
TOTAL: 23:59:59	1989	100.0%	0.02	7.9 MB	100.0%	0.09

Sites sending bad articles:

Server		Total	Group	Unapp	Other
1	news.nntp4.net	566	461	1	104
2	news.glou.org	50	0	0	50
3	nntp.terraraq.uk	4	0	0	4
4	gegeweb-out.news.tnetconsulting.net	2	0	0	2
5	usenet.pasdenom.info	1	0	0	1
6	feed-out.gegeweb.org	1	1	0	0
TOTAL: 6		624	462	1	161

Unwanted newsgroups [Top 20]:

Newsgroup	Count
linux.kernel.netdev	425
linux.kernel.git	36
france.basse-normandie	1
TOTAL: 3	462

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
soc.culture.hawaii	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	100
Bad path (news.giganews.com.POSTED)	56
Cancel of non-existing ID <117lu1b$33ilu$1@dont-email.me>	2
No Cancel-Key matches Cancel-Lock. target=<117ls6h$32tih$1@dont-email.me>	1
No Cancel-Key matches Cancel-Lock. target=<117lpm0$320ka$1@dont-email.me>	1
Cancels abusifs	1
TOTAL: 6	161

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.samoylyk.net	1917	1	1567	3	77	0%	23:58:24
2	news.aioe.org	0	0	0	0	1622	0%	23:59:59
3	nntp.terraraq.uk	1032	0	1004	1	0	0%	23:55:01
4	i2pn.org	0	0	0	0	1985	0%	23:59:59
5	news.gegeweb.org	1041	0	976	0	0	0%	23:22:44
6	news.pasdenom.info	1449	0	1406	0	0	0%	23:58:48
7	news.nntp4.net	1727	0	1685	0	0	0%	23:59:57
8	news.tnetconsulting.net	3450	0	1986	1	55	0%	23:59:56
TOTAL: 8		10616	1	8624	5	3739	0%	191:14:48

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.samoylyk.net	1.7 KB	16.9 KB	18.6 KB	4.7 KB	23:58:24
2	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:59
3	nntp.terraraq.uk	0.0 KB	1.2 KB	1.2 KB	1.2 KB	23:55:01
4	i2pn.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:59
5	news.gegeweb.org	0.0 KB	0.0 KB	0.0 KB	0	23:22:44
6	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:58:48
7	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0	23:59:57
8	news.tnetconsulting.net	0.0 KB	13.1 KB	13.1 KB	13.1 KB	23:59:56
TOTAL: 8		1.7 KB	31.2 KB	33.0 KB	5.5 KB	191:14:48

NNRP readership statistics:

System		Conn	Elapsed
1	104.152.52.128	1	00:00:04
2	104.152.52.125	1	00:00:01
3	104.152.52.139	1	00:00:01
4	mail.gegeweb.eu	288	00:00:32
5	104.152.52.126	2	00:00:02
6	o018.scanner.modat.io	1	00:00:00
7	36.255.97.197	4	00:00:23
8	104.152.52.135	2	00:00:00
9	69-5-169-20.infrawat.ch	1	00:00:00
10	104.152.52.138	3	00:00:03
11	o041.scanner.modat.io	1	00:00:00
12	104.152.52.132	1	00:00:03
13	104.152.52.136	1	00:00:00
14	173-255-221-189.ip.linodeusercontent.com	1	00:00:00
15	45-79-207-181.ip.linodeusercontent.com	1	00:00:00
16	104.152.52.127	1	00:00:01
17	16.5.0.254	1	00:00:03
18	104.152.52.149	1	00:00:01
19	23-239-11-64.ip.linodeusercontent.com	2	00:00:00
20	104.152.52.143	5	00:00:00
21	123.58.196.28	2	00:00:10
22	104.152.52.140	1	00:00:00
23	193-32-209-250.infrawat.ch	1	00:00:00
TOTAL: 23		323	00:01:35

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.gegeweb.eu	288	00:00:32
2	*.infrawat.ch	2	00:00:00
3	*.ip.linodeusercontent.com	4	00:00:01
4	*.scanner.modat.io	2	00:00:01
5	unresolved	27	00:00:59
TOTAL: 5		323	00:01:35

NNRP auth users [Top 20]:

User	Conn
mailman	288
TOTAL: 1	288

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mail.gegeweb.eu	5.172	4.622	1.758	00:00:32
36.255.97.197	0.063	0.080	0.000	00:00:23
123.58.196.28	0.058	0.043	0.000	00:00:10
104.152.52.128	0.015	0.015	0.000	00:00:04
16.5.0.254	0.031	0.022	0.000	00:00:03
104.152.52.138	0.127	0.037	0.000	00:00:03
104.152.52.132	0.039	0.012	0.000	00:00:03
104.152.52.126	0.105	0.034	0.000	00:00:02
104.152.52.139	0.039	0.008	0.000	00:00:01
104.152.52.125	0.034	0.015	0.000	00:00:01
104.152.52.127	0.036	0.012	0.000	00:00:01
104.152.52.149	0.019	0.008	0.000	00:00:01
o041.scanner.modat.io	0.100	0.008	0.000	00:00:00
o018.scanner.modat.io	0.079	0.025	0.000	00:00:00
104.152.52.143	0.126	0.064	0.000	00:00:00
173-255-221-189.ip.linodeusercontent.com	0.022	0.031	0.000	00:00:00
23-239-11-64.ip.linodeusercontent.com	0.060	0.036	0.000	00:00:00
45-79-207-181.ip.linodeusercontent.com	0.034	0.017	0.000	00:00:00
104.152.52.140	0.030	0.017	0.000	00:00:00
104.152.52.136	0.042	0.004	0.000	00:00:00
TOTAL: 23	6.320	5.169	1.758	00:01:35

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	7
TOTAL: 1	7

NNRP unrecognized commands (by host):

System	Conn
104.152.52.138	8
23-239-11-64.ip.linodeusercontent.com	7
104.152.52.139	5
104.152.52.127	5
104.152.52.125	3
o018.scanner.modat.io	2
o041.scanner.modat.io	2
123.58.196.28	2
TOTAL: 8	34

NNRP unrecognized commands (by command):

Command	Count
HELO	2
#026#003#001	2
Accept: /	2
Connection: close	2
GET / HTTP/1.1	2
NOOP	2
Host: 45.13.105.36	2
À#024	1
SSTP_DUPLEX_POST /sra_{BA195980-CD49-458...	1
INFO	1
$4	1
Content-Length: 8	1
Content-Length: 18446744073709551615	1
User-Agent: Microsoft WinRM Client	1
CSeq: 1	1
User-Agent: VLC media player/1.3.0	1
Host: 45.13.105.36:119	1
Accept-Encoding: gzip	1
*1	1
User-Agent: Mozilla/5.0 (Macintosh; Inte...	1
User-Agent: curl/7.61.1	1
#001	1
SSTPCORRELATIONID: {7c360794-ab24-11f1-9...	1
Accept: application/sdp	1
OPTIONS rtsp://45.13.105.36/ RTSP/1.0	1
24B9E864	1
Host: 45.13.105.36:563	1
TOTAL: 27	34

NNRP client timeouts [Top 20]:

System	Conn	Peer
69-5-169-20.infrawat.ch	1	1
TOTAL: 1	1	5

Daily Usenet report for news.niel.me

Sep 7 04:15:02 -- Sep 8 04:15:01