Daily Usenet report for news.niel.me: Sep 30 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Sep 30 12:13:03 rpi-studio innd: filter: No Cancel-Key[sha1:WZy8pg7jUQGX2TKIfh5Jv6cHnNA= sha256:qgQr0+t0W7y5zCosmPgH3zZUk0S5WuATvZN9P1DwgY0=] matches Cancel-Lock[sha1:Y/P4ppYhrYPTdUQBV2/Rj5t/hVo= sha256:EyLb1b8sKc2QLD47731W26O8qpPffOuH0Rv5GiHeh9Y=] target=<82opbl15njlcl31dunu7eug3dd7lubjkb3@kgbh.org>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2720	31.5%	459.1 KB	35.0%
nnrpd	2057	23.9%	244.0 KB	18.6%
innd	1924	22.3%	331.3 KB	25.3%
innfeed	1914	22.2%	276.5 KB	21.1%
controlchan	6	0.1%	1.2 KB	0.1%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	8622	100.0%	1.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	7903	45.4%
Negative hits	6789	39.0%
Do not exist	2713	15.6%
Cache misses	6	0.0%
TOTAL: 4	17411	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.008	0.0%	2	4.000	4.000	4.000
article cancel	00:00:00.001	0.0%	2	0.500	0.500	0.500
article cleanup	00:00:00.060	0.0%	2715	0.000	0.022	0.154
article logging	00:00:00.229	0.0%	2718	0.000	0.084	0.333
article parse	00:00:00.564	0.0%	8215	0.000	0.069	0.231
article write	00:00:01.016	0.0%	1923	0.000	0.528	1.316
artlog/artcncl	00:00:00.001	0.0%	2	0.500	0.500	0.500
artlog/artcncl/perl	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:00:00.144	0.0%	21843	0.000	0.007	0.038
hisgrep/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hisgrep/perl	00:00:00.000	0.0%	4	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	2	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.303	0.0%	17431	0.000	0.017	0.100
history sync	00:00:00.052	0.0%	364	0.000	0.143	1.000
history write	00:00:00.565	0.0%	2715	0.000	0.208	1.000
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:32.057	100.0%	23048	1370.345	3750.089	40009.933
nntp read	00:00:00.651	0.0%	22219	0.000	0.029	0.125
overview write	00:00:00.677	0.0%	1923	0.000	0.352	8.000
perl filter	00:00:12.375	0.0%	2715	2.091	4.558	19.375
python filter	00:00:00.014	0.0%	2715	0.000	0.005	0.100
site send	00:00:00.916	0.0%	3878	0.000	0.236	0.500
TOTAL: 24:01:08.005	24:00:49.633	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.013	0.0%	1935	0.000	0.007	0.143
article prepare	00:00:00.000	0.0%	44	0.000	0.000	0.000
article read	00:00:00.004	0.0%	11	0.000	0.364	3.000
backlog stats	00:00:00.129	0.0%	23466	0.000	0.005	0.031
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.302	0.0%	11708	0.000	0.026	0.083
data write	00:00:00.553	0.0%	9863	0.000	0.056	0.176
idle	23:49:55.965	100.0%	23466	1464.310	3656.182	10328.083
status file	00:00:03.718	0.0%	322	0.000	11.547	83.000
TOTAL: 23:50:05.868	23:50:00.684	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:40.986	38.4%	1515	10.600	27.053	9687.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.122	0.1%	3480	0.000	0.035	0.500
TOTAL: 00:01:46.696	00:00:41.108	38.5%	-	-	-	-

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
us-control@lists.killfile.org	0	0	1	0	0	1
TOTAL	0	0	1	0	0	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	feed-out.gegeweb.org	4	1892	603	1289	0	31%	23:48:15
2	news.samoylyk.net	4	2602	588	2013	1	22%	29:30:30
3	news.nntp4.net	2	3053	568	1606	879	18%	26:58:18
4	news.glou.org	3	1338	140	1172	26	10%	24:35:03
5	nntp.terraraq.uk	4	774	96	676	2	12%	15:22:07
6	usenet.pasdenom.info	9	274	13	261	0	4%	11:02:37
7	gegeweb-out.news.tnetconsulting.net	6	268	3	265	0	1%	07:31:33
8	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 8		57	10201	2011	7282	908	19%	138:48:23

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.samoylyk.net	3.3 MB	0.8 KB	3.3 MB	99%	5.7 KB
2	news.nntp4.net	3.3 MB	9.4 MB	12.7 MB	25%	9.0 KB
3	feed-out.gegeweb.org	2.0 MB	0.0 KB	2.0 MB	100%	3.4 KB
4	news.glou.org	519.5 KB	86.8 KB	606.3 KB	85%	3.7 KB
5	nntp.terraraq.uk	419.5 KB	4.2 KB	423.7 KB	99%	4.3 KB
6	usenet.pasdenom.info	30.5 KB	0.0 KB	30.5 KB	100%	2.3 KB
7	gegeweb-out.news.tnetconsulting.net	5.2 KB	0.0 KB	5.2 KB	100%	1.7 KB
8	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 8		9.5 MB	9.5 MB	19.0 MB	50%	6.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 30 04:15:01 - 04:59:59	29	1.5%	0.01	74.1 KB	0.9%	0.03
Sep 30 05:00:00 - 05:59:59	42	2.2%	0.01	144.1 KB	1.7%	0.04
Sep 30 06:00:00 - 06:59:59	28	1.5%	0.01	102.1 KB	1.2%	0.03
Sep 30 07:00:00 - 07:59:59	57	3.0%	0.02	204.6 KB	2.3%	0.06
Sep 30 08:00:00 - 08:59:59	39	2.0%	0.01	197.0 KB	2.3%	0.05
Sep 30 09:00:00 - 09:59:59	85	4.4%	0.02	309.3 KB	3.5%	0.09
Sep 30 10:00:00 - 10:59:59	97	5.0%	0.03	580.2 KB	6.7%	0.16
Sep 30 11:00:00 - 11:59:59	69	3.6%	0.02	393.2 KB	4.5%	0.11
Sep 30 12:00:00 - 12:59:59	69	3.6%	0.02	272.4 KB	3.1%	0.08
Sep 30 13:00:00 - 13:59:59	91	4.7%	0.03	529.1 KB	6.1%	0.15
Sep 30 14:00:00 - 14:59:59	85	4.4%	0.02	304.8 KB	3.5%	0.08
Sep 30 15:00:00 - 15:59:59	105	5.5%	0.03	345.3 KB	4.0%	0.10
Sep 30 16:00:00 - 16:59:59	102	5.3%	0.03	415.1 KB	4.8%	0.12
Sep 30 17:00:00 - 17:59:59	141	7.3%	0.04	819.1 KB	9.4%	0.23
Sep 30 18:00:00 - 18:59:59	103	5.4%	0.03	504.9 KB	5.8%	0.14
Sep 30 19:00:00 - 19:59:59	136	7.1%	0.04	639.2 KB	7.3%	0.18
Sep 30 20:00:00 - 20:59:59	86	4.5%	0.02	245.8 KB	2.8%	0.07
Sep 30 21:00:00 - 21:59:59	80	4.2%	0.02	297.0 KB	3.4%	0.08
Sep 30 22:00:00 - 22:59:59	100	5.2%	0.03	539.5 KB	6.2%	0.15
Sep 30 23:00:00 - 23:59:59	104	5.4%	0.03	444.3 KB	5.1%	0.12
Oct 1 00:00:00 - 00:59:59	128	6.7%	0.04	622.6 KB	7.1%	0.17
Oct 1 01:00:00 - 01:59:59	36	1.9%	0.01	181.6 KB	2.1%	0.05
Oct 1 02:00:00 - 02:59:59	37	1.9%	0.01	318.1 KB	3.7%	0.09
Oct 1 03:00:00 - 03:59:59	67	3.5%	0.02	209.5 KB	2.4%	0.06
Oct 1 04:00:00 - 04:15:02	6	0.3%	0.01	20.5 KB	0.2%	0.02
TOTAL: 24:00:01	1922	100.0%	0.02	8.5 MB	100.0%	0.10

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	762	644	118
2	news.glou.org	26	0	26
3	feed-out.gegeweb.org	2	0	2
4	nntp.terraraq.uk	2	0	2
5	news.samoylyk.net	1	0	1
6	gegeweb-out.news.tnetconsulting.net	1	0	1
TOTAL: 6		794	644	150

Unwanted newsgroups [Top 20]:

Newsgroup	Count
linux.kernel.netdev	561
linux.kernel.git	83
TOTAL: 2	644

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	115
Bad path (news.giganews.com.POSTED)	30
Cancel of <ni3qcaFc7daU1@mid.individual.net> without Cancel-Key	1
No Cancel-Key matches Cancel-Lock. target=<82opbl15njlcl31dunu7eug3dd7lubjkb3@kgbh.org>	1
TOTAL: 4	147

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.tnetconsulting.net	3324	2	1907	8	18	0%	23:48:57
2	news.samoylyk.net	1538	1	1345	0	0	0%	23:48:22
3	i2pn.org	0	0	0	0	1914	0%	24:00:01
4	nntp.terraraq.uk	922	0	889	0	0	0%	23:20:15
5	news.nntp4.net	1445	0	1413	0	0	0%	23:33:45
6	news.gegeweb.org	1370	0	1314	0	0	0%	23:44:23
7	news.aioe.org	0	0	0	0	1561	0%	24:00:01
8	news.pasdenom.info	1357	0	1332	0	0	0%	23:45:29
TOTAL: 8		9956	3	8200	8	3493	0%	190:01:13

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.tnetconsulting.net	5.6 KB	37.7 KB	43.3 KB	4.3 KB	23:48:57
2	news.samoylyk.net	2.9 KB	0.0 KB	2.9 KB	2.9 KB	23:48:22
3	i2pn.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:01
4	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:20:15
5	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0	23:33:45
6	news.gegeweb.org	0.0 KB	0.0 KB	0.0 KB	0	23:44:23
7	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:01
8	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:45:29
TOTAL: 8		8.5 KB	37.7 KB	46.2 KB	4.2 KB	190:01:13

NNRP readership statistics:

System		Conn	Elapsed
1	r5-146-92.monitoring.internet-measurement.com	1	00:00:00
2	r5-35-23.monitoring.internet-measurement.com	1	00:00:00
3	2603:6010:6802:4ca5:1a03:73ff:fe28:be0f	10	00:00:09
4	39.210.203.35.bc.googleusercontent.com	2	00:00:01
5	20.65.169.214	2	00:00:09
6	o013.scanner.modat.io	1	00:00:00
7	r3-191-bf.monitoring.internet-measurement.com	1	00:00:00
8	r3-75-4b.monitoring.internet-measurement.com	1	00:00:03
9	81-19-219-238.infrawat.ch	1	00:00:00
10	r5-79-4f.monitoring.internet-measurement.com	1	00:00:06
11	205.210.31.90	1	00:00:00
12	2a01:4020:1:1:102::1d9	1	00:00:00
13	r4-136-88.monitoring.internet-measurement.com	1	00:00:01
14	r3-198-c6.monitoring.internet-measurement.com	1	00:00:00
15	r4-178-b2.monitoring.internet-measurement.com	1	00:00:00
16	69-5-169-219.infrawat.ch	1	00:00:00
17	r3-155-9b.monitoring.internet-measurement.com	1	00:00:00
18	r5-37-25.monitoring.internet-measurement.com	1	00:00:00
19	89-21-67-169.infrawat.ch	1	00:00:00
20	r3-214-d6.monitoring.internet-measurement.com	1	00:00:00
21	185-223-235-42.infrawat.ch	1	00:00:00
22	r4-13-d.monitoring.internet-measurement.com	1	00:00:00
23	r4-17-11.monitoring.internet-measurement.com	1	00:00:01
24	2a01:4020:2:b:101::6	1	00:00:00
25	r3-2-2.monitoring.internet-measurement.com	1	00:00:00
26	187.186.132.66.censys-scanner.com	1	00:00:01
27	194-88-98-122.infrawat.ch	1	00:00:00
28	r3-25-19.monitoring.internet-measurement.com	1	00:00:00
29	r3-27-1b.monitoring.internet-measurement.com	1	00:00:00
30	r5-28-1c.monitoring.internet-measurement.com	1	00:00:00
31	r3-18-12.monitoring.internet-measurement.com	1	00:00:00
32	66.61.98.95	6	00:00:06
33	r3-178-b2.monitoring.internet-measurement.com	1	00:00:00
34	r3-205-cd.monitoring.internet-measurement.com	1	00:00:00
35	222.210.203.35.bc.googleusercontent.com	1	00:00:00
36	193-176-31-200.infrawat.ch	1	00:00:00
37	r4-7-7.monitoring.internet-measurement.com	2	00:00:02
38	o033.scanner.modat.io	1	00:00:30
39	mail.gegeweb.eu	288	00:00:28
TOTAL: 39		343	00:01:51

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.210.203.35.bc.googleusercontent.com	3	00:00:02
2	*.monitoring.internet-measurement.com	22	00:00:20
3	*.infrawat.ch	6	00:00:02
4	*.gegeweb.eu	288	00:00:28
5	unresolved	9	00:00:16
6	?	12	00:00:09
7	*.scanner.modat.io	2	00:00:30
8	*.186.132.66.censys-scanner.com	1	00:00:01
TOTAL: 8		343	00:01:51

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
o033.scanner.modat.io	0.012	0.015	0.000	00:00:30
mail.gegeweb.eu	5.306	4.695	1.691	00:00:28
20.65.169.214	0.045	0.049	0.000	00:00:09
2603:6010:6802:4ca5:1a03:73ff:fe28:be0f	0.263	0.215	0.000	00:00:09
66.61.98.95	0.176	0.091	0.000	00:00:06
r5-79-4f.monitoring.internet-measurement.com	0.126	0.034	0.000	00:00:06
r3-75-4b.monitoring.internet-measurement.com	0.043	0.016	0.000	00:00:03
r4-7-7.monitoring.internet-measurement.com	0.188	0.074	0.000	00:00:02
r4-136-88.monitoring.internet-measurement.com	0.024	0.024	0.000	00:00:01
39.210.203.35.bc.googleusercontent.com	0.138	0.050	0.000	00:00:01
187.186.132.66.censys-scanner.com	0.038	0.023	0.000	00:00:01
r4-17-11.monitoring.internet-measurement.com	0.060	0.034	0.000	00:00:01
194-88-98-122.infrawat.ch	0.045	0.016	0.000	00:00:00
r5-146-92.monitoring.internet-measurement.com	0.037	0.029	0.000	00:00:00
r3-27-1b.monitoring.internet-measurement.com	0.075	0.030	0.000	00:00:00
r5-37-25.monitoring.internet-measurement.com	0.096	0.008	0.000	00:00:00
r3-2-2.monitoring.internet-measurement.com	0.067	0.028	0.000	00:00:00
r3-18-12.monitoring.internet-measurement.com	0.037	0.009	0.000	00:00:00
r5-28-1c.monitoring.internet-measurement.com	0.034	0.014	0.000	00:00:00
r5-35-23.monitoring.internet-measurement.com	0.066	0.021	0.000	00:00:00
TOTAL: 38	7.397	5.771	1.691	00:01:51

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
39.210.203.35.bc.googleusercontent.com	4
222.210.203.35.bc.googleusercontent.com	4
20.65.169.214	1
187.186.132.66.censys-scanner.com	1
TOTAL: 4	10

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	2
Host: 45.13.105.36:563	2
MGLNDD_45.13.105.36_119	1
User-Agent: Hello from Palo Alto Network...	1
User-Agent: curl/7.68.0	1
Accept: /	1
EHLO www.censys.io	1
Accept-Encoding: gzip	1
TOTAL: 8	10

NNRP client timeouts [Top 20]:

System	Conn	Peer
193-176-31-200.infrawat.ch	1	1
39.210.203.35.bc.googleusercontent.com	1	2
2a01:4020:1:1:102::1d9	1	1
185-223-235-42.infrawat.ch	1	1
TOTAL: 4	4	9

Daily Usenet report for news.niel.me

Sep 30 04:15:01 -- Oct 1 04:15:02