Daily Usenet report for news.niel.me: Aug 20 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Aug 20 21:44:14 rpi-studio innd: filter: No Cancel-Key[sha1:f53bShl3xQtpal84E1kVoiazdP8= sha256:mIrNAz3CafGOMgMVaUnmYsvv2JKeH0g8eS833QvxTys=] matches Cancel-Lock[sha1:a4wfQZvP4uC5KaxR66YgPxUk13M= sha256:AX9zST2hhLgfy7gIdWqng5DP8Fkza5wNmSHYJ1Ev5/w=] target=<1167l6j$20te$1@nnrp.usenet.blueworldhosting.com>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2280	27.5%	392.3 KB	31.2%
innd	2092	25.3%	346.7 KB	27.5%
nnrpd	1983	24.0%	217.7 KB	17.3%
innfeed	1922	23.2%	302.2 KB	24.0%
controlchan	1	0.0%	0.2 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	8279	100.0%	1.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	8083	49.5%
Negative hits	5974	36.6%
Do not exist	2264	13.9%
Cache misses	2	0.0%
TOTAL: 4	16323	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.006	0.0%	5	1.000	1.200	1.500
article cancel	00:00:00.003	0.0%	6	0.000	0.500	1.000
article cleanup	00:00:00.050	0.0%	2269	0.000	0.022	0.333
article logging	00:00:00.173	0.0%	2271	0.000	0.076	0.429
article parse	00:00:00.409	0.0%	5728	0.000	0.071	0.222
article write	00:00:00.900	0.0%	1692	0.000	0.532	1.333
artlog/artcncl	00:00:00.001	0.0%	6	0.000	0.167	0.500
artlog/artcncl/perl	00:00:00.000	0.0%	5	0.000	0.000	0.000
data move	00:00:00.110	0.0%	18021	0.000	0.006	0.100
hisgrep/artcncl	00:00:00.001	0.0%	6	0.000	0.167	1.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	5	0.000	0.000	0.000
hisgrep/perl	00:00:00.000	0.0%	6	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	6	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	5	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.302	0.0%	16351	0.000	0.018	0.069
history sync	00:00:00.045	0.0%	350	0.000	0.129	1.000
history write	00:00:00.460	0.0%	2269	0.000	0.203	0.600
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:26.083	100.0%	20249	1812.042	4268.165	25016.667
nntp read	00:00:00.579	0.0%	19493	0.000	0.030	0.091
overview write	00:00:00.625	0.0%	1692	0.000	0.369	4.167
perl filter	00:00:15.756	0.0%	2269	2.429	6.944	29.333
python filter	00:00:00.011	0.0%	2269	0.000	0.005	0.200
site send	00:00:00.699	0.0%	3448	0.000	0.203	0.600
TOTAL: 24:01:04.643	24:00:46.213	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.011	0.0%	1687	0.000	0.007	0.250
article prepare	00:00:00.000	0.0%	8	0.000	0.000	0.000
article read	00:00:00.003	0.0%	2	0.000	1.500	3.000
backlog stats	00:00:00.132	0.0%	21732	0.000	0.006	0.031
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.284	0.0%	10669	0.000	0.027	0.125
data write	00:00:00.528	0.0%	9015	0.000	0.059	0.167
idle	23:49:58.444	100.0%	21732	1465.318	3948.023	10676.136
status file	00:00:09.806	0.0%	330	0.000	29.715	81.000
TOTAL: 23:50:13.831	23:50:09.208	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:44.919	65.5%	1502	11.000	29.906	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.133	0.2%	3383	0.000	0.039	0.364
TOTAL: 00:01:08.565	00:00:45.052	65.7%	-	-	-	-

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	4	1754	542	1209	3	30%	25:00:29
2	news.nntp4.net	2	2439	405	1510	524	16%	27:17:32
3	news.samoylyk.net	3	1511	398	1096	17	26%	21:51:56
4	news.glou.org	6	1327	150	1131	46	11%	24:52:01
5	nntp.terraraq.uk	5	1492	122	1367	3	8%	26:56:46
6	usenet.pasdenom.info	16	736	32	699	5	4%	22:45:42
7	gegeweb-out.news.tnetconsulting.net	2	1315	14	1300	1	1%	23:59:27
8	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 8		63	10574	1663	8312	599	15%	172:43:53

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	1.8 MB	4.4 MB	6.2 MB	29%	6.9 KB
2	news.samoylyk.net	1.8 MB	42.1 KB	1.8 MB	97%	4.5 KB
3	feed-out.gegeweb.org	1.6 MB	6.2 KB	1.6 MB	99%	3.0 KB
4	news.glou.org	579.1 KB	134.8 KB	713.8 KB	81%	3.6 KB
5	nntp.terraraq.uk	348.4 KB	7.3 KB	355.7 KB	97%	2.8 KB
6	gegeweb-out.news.tnetconsulting.net	116.0 KB	1.5 KB	117.5 KB	98%	7.8 KB
7	usenet.pasdenom.info	86.0 KB	6.2 KB	92.2 KB	93%	2.5 KB
8	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 8		6.3 MB	4.6 MB	10.9 MB	57%	5.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 20 04:15:01 - 04:59:59	64	3.8%	0.02	195.6 KB	3.0%	0.07
Aug 20 05:00:00 - 05:59:59	45	2.7%	0.01	208.5 KB	3.2%	0.06
Aug 20 06:00:00 - 06:59:59	45	2.7%	0.01	188.0 KB	2.9%	0.05
Aug 20 07:00:00 - 07:59:59	16	0.9%	0.00	72.8 KB	1.1%	0.02
Aug 20 08:00:00 - 08:59:59	26	1.5%	0.01	92.6 KB	1.4%	0.03
Aug 20 09:00:00 - 09:59:59	75	4.4%	0.02	333.7 KB	5.1%	0.09
Aug 20 10:00:00 - 10:59:59	72	4.3%	0.02	247.0 KB	3.7%	0.07
Aug 20 11:00:00 - 11:59:59	74	4.4%	0.02	254.0 KB	3.9%	0.07
Aug 20 12:00:00 - 12:59:59	83	4.9%	0.02	266.9 KB	4.1%	0.07
Aug 20 13:00:00 - 13:59:59	72	4.3%	0.02	246.9 KB	3.7%	0.07
Aug 20 14:00:00 - 14:59:59	63	3.7%	0.02	238.6 KB	3.6%	0.07
Aug 20 15:00:00 - 15:59:59	80	4.7%	0.02	242.3 KB	3.7%	0.07
Aug 20 16:00:00 - 16:59:59	77	4.6%	0.02	274.6 KB	4.2%	0.08
Aug 20 17:00:00 - 17:59:59	104	6.2%	0.03	361.5 KB	5.5%	0.10
Aug 20 18:00:00 - 18:59:59	91	5.4%	0.03	279.5 KB	4.2%	0.08
Aug 20 19:00:00 - 19:59:59	120	7.1%	0.03	450.1 KB	6.8%	0.13
Aug 20 20:00:00 - 20:59:59	90	5.3%	0.03	323.1 KB	4.9%	0.09
Aug 20 21:00:00 - 21:59:59	93	5.5%	0.03	366.9 KB	5.6%	0.10
Aug 20 22:00:00 - 22:59:59	77	4.6%	0.02	295.6 KB	4.5%	0.08
Aug 20 23:00:00 - 23:59:59	73	4.3%	0.02	565.6 KB	8.6%	0.16
Aug 21 00:00:00 - 00:59:59	96	5.7%	0.03	443.0 KB	6.7%	0.12
Aug 21 01:00:00 - 01:59:59	43	2.5%	0.01	143.3 KB	2.2%	0.04
Aug 21 02:00:00 - 02:59:59	75	4.4%	0.02	253.4 KB	3.8%	0.07
Aug 21 03:00:00 - 03:59:59	32	1.9%	0.01	232.8 KB	3.5%	0.06
Aug 21 04:00:00 - 04:15:02	4	0.2%	0.00	13.2 KB	0.2%	0.01
TOTAL: 24:00:01	1690	100.0%	0.02	6.4 MB	100.0%	0.08

Sites sending bad articles:

Server		Total	Group	Unapp	Other
1	news.nntp4.net	501	346	5	150
2	news.glou.org	46	0	2	44
3	news.samoylyk.net	17	0	0	17
4	usenet.pasdenom.info	5	0	0	5
5	feed-out.gegeweb.org	5	0	0	5
6	gegeweb-out.news.tnetconsulting.net	4	0	0	4
7	nntp.terraraq.uk	1	0	0	1
TOTAL: 7		579	346	7	226

Unwanted newsgroups [Top 20]:

Newsgroup	Count
linux.kernel.netdev	262
linux.kernel.git	84
TOTAL: 2	346

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

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

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	109
EMP (phn path)	74
Bad path (news.giganews.com.POSTED)	34
Cancels abusifs	5
Cancel for rejected article	1
Too many newsgroups (meow)	1
TOTAL: 6	224

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	feed-out.gegeweb.org	2
	TOTAL: 1	2
TOTAL: 1		2

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Spool	%Took	Elapsed
1	news.tnetconsulting.net	2809	2	1686	0	0%	23:56:10
2	news.samoylyk.net	1277	0	1181	0	0%	23:50:32
3	news.gegeweb.org	1200	0	1161	0	0%	23:32:31
4	news.pasdenom.info	1312	0	1265	0	0%	23:54:15
5	news.nntp4.net	1379	0	1317	0	0%	23:50:42
6	i2pn.org	0	0	0	1687	0%	24:00:00
7	nntp.terraraq.uk	1017	0	986	0	0%	23:48:33
8	news.aioe.org	0	0	0	1322	0%	24:00:00
TOTAL: 8		8994	2	7596	3009	0%	190:52:43

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	TotalVol	Vol/Art	Elapsed
1	news.tnetconsulting.net	6.2 KB	6.2 KB	3.1 KB	23:56:10
2	news.samoylyk.net	0.0 KB	0.0 KB	0	23:50:32
3	news.gegeweb.org	0.0 KB	0.0 KB	0	23:32:31
4	news.pasdenom.info	0.0 KB	0.0 KB	0	23:54:15
5	news.nntp4.net	0.0 KB	0.0 KB	0	23:50:42
6	i2pn.org	0.0 KB	0.0 KB	0	24:00:00
7	nntp.terraraq.uk	0.0 KB	0.0 KB	0	23:48:33
8	news.aioe.org	0.0 KB	0.0 KB	0	24:00:00
TOTAL: 8		6.2 KB	6.2 KB	3.1 KB	190:52:43

NNRP readership statistics:

System		Conn	Elapsed
1	o033.scanner.modat.io	2	00:00:00
2	69.71.178.34.bc.googleusercontent.com	1	00:00:04
3	215.172.132.66.censys-scanner.com	1	00:00:01
4	2603:6010:6802:4ca5:1a03:73ff:fe28:be0f	6	00:00:05
5	104.152.52.127	2	00:00:00
6	172.210.203.35.bc.googleusercontent.com	1	00:00:00
7	104.152.52.144	1	00:00:00
8	104.152.52.142	4	00:00:00
9	104.152.52.126	2	00:00:03
10	240e:41:8800:5:1::100	3	00:00:01
11	173-255-221-189.ip.linodeusercontent.com	1	00:00:00
12	104.152.52.136	1	00:00:00
13	mail.gegeweb.eu	288	00:00:24
14	66.61.98.95	4	00:00:07
15	45-33-14-197.ip.linodeusercontent.com	1	00:00:00
16	104.152.52.130	1	00:00:00
17	r4-113-71.monitoring.internet-measurement.com	1	00:00:01
18	azpdes1vckj9.stretchoid.com	2	00:00:10
19	o037.scanner.modat.io	2	00:00:00
20	45-79-207-71.ip.linodeusercontent.com	2	00:00:00
21	104.152.52.143	1	00:00:00
22	104.152.52.149	1	00:00:01
TOTAL: 22		328	00:01:08

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.monitoring.internet-measurement.com	1	00:00:01
2	*.scanner.modat.io	4	00:00:01
3	*.210.203.35.bc.googleusercontent.com	1	00:00:00
4	*.gegeweb.eu	288	00:00:24
5	?	9	00:00:07
6	*.172.132.66.censys-scanner.com	1	00:00:01
7	*.ip.linodeusercontent.com	4	00:00:01
8	*.stretchoid.com	2	00:00:10
9	*.71.178.34.bc.googleusercontent.com	1	00:00:04
10	unresolved	17	00:00:14
TOTAL: 10		328	00:01:08

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.399	4.515	1.792	00:00:24
azpdes1vckj9.stretchoid.com	0.052	0.048	0.000	00:00:10
66.61.98.95	0.054	0.049	0.000	00:00:07
2603:6010:6802:4ca5:1a03:73ff:fe28:be0f	0.147	0.135	0.000	00:00:05
69.71.178.34.bc.googleusercontent.com	0.019	0.031	0.000	00:00:04
104.152.52.126	0.031	0.024	0.000	00:00:03
r4-113-71.monitoring.internet-measurement.com	0.022	0.030	0.000	00:00:01
240e:41:8800:5:1::100	0.118	0.081	0.000	00:00:01
104.152.52.149	0.041	0.009	0.000	00:00:01
215.172.132.66.censys-scanner.com	0.025	0.030	0.000	00:00:01
45-33-14-197.ip.linodeusercontent.com	0.034	0.019	0.000	00:00:00
o037.scanner.modat.io	0.052	0.020	0.000	00:00:00
104.152.52.143	0.038	0.011	0.000	00:00:00
o033.scanner.modat.io	0.044	0.057	0.000	00:00:00
45-79-207-71.ip.linodeusercontent.com	0.045	0.045	0.000	00:00:00
172.210.203.35.bc.googleusercontent.com	0.077	0.031	0.000	00:00:00
104.152.52.142	0.104	0.053	0.000	00:00:00
104.152.52.130	0.031	0.016	0.000	00:00:00
104.152.52.144	0.040	0.007	0.000	00:00:00
173-255-221-189.ip.linodeusercontent.com	0.023	0.004	0.000	00:00:00
TOTAL: 22	6.457	5.235	1.792	00:01:08

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
104.152.52.149	5
172.210.203.35.bc.googleusercontent.com	4
104.152.52.143	2
215.172.132.66.censys-scanner.com	1
azpdes1vckj9.stretchoid.com	1
o033.scanner.modat.io	1
o037.scanner.modat.io	1
TOTAL: 7	15

NNRP unrecognized commands (by command):

Command	Count
HELO	2
GET / HTTP/1.1	2
User-Agent: curl/7.61.1	1
User-Agent: Hello from Palo Alto Network...	1
Connection: close	1
Accept: /	1
EHLO www.censys.io	1
Accept-Encoding: gzip	1
EHLO 45.13.105.36	1
Host: 45.13.105.36:563	1
Host: 45.13.105.36	1
MGLNDD_45.13.105.36_119	1
MAIL FROM:<relaycheck_please_ignore@prot...	1
TOTAL: 13	15

Daily Usenet report for news.niel.me

Aug 20 04:15:01 -- Aug 21 04:15:02