Daily Usenet report for news.niel.me: Oct 1 04:15:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Oct 1 20:30:00 rpi-studio innd: filter: No Cancel-Key[sha1:14eSJneopM0vxoGMLIEb3VfmABo= sha256:/CS0ggEDuuZSKKb84f9xfdodedEsNAJFANg5BU2qBaE=] matches Cancel-Lock[sha1:x0RsRw1Q4bUSWkTzguep6IVO+kA= sha256:cDnxCreZTYVb1xUaytIudEWXQE7ZF/bukydjAEwGKGI=] target=<mh5tbl50ohob5hr0270vssauluqdmsu7a7@kgbh.org>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2712	35.4%	454.1 KB	40.3%
innfeed	1819	23.7%	238.3 KB	21.2%
innd	1600	20.9%	269.8 KB	24.0%
nnrpd	1527	19.9%	162.1 KB	14.4%
controlchan	6	0.1%	1.2 KB	0.1%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	7665	100.0%	1.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	7465	54.0%
Negative hits	3478	25.1%
Do not exist	2628	19.0%
Cache misses	261	1.9%
TOTAL: 4	13832	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.050	0.0%	35	1.000	1.429	2.000
article cancel	00:00:00.005	0.0%	36	0.000	0.139	0.500
article cleanup	00:00:00.049	0.0%	2640	0.000	0.019	0.333
article logging	00:00:00.168	0.0%	2641	0.000	0.064	0.250
article parse	00:00:00.439	0.0%	7700	0.000	0.057	0.300
article write	00:00:00.802	0.0%	1880	0.000	0.427	1.714
artlog/artcncl	00:00:00.001	0.0%	36	0.000	0.028	0.036
artlog/artcncl/perl	00:00:00.003	0.0%	35	0.000	0.086	1.000
data move	00:00:00.072	0.0%	12088	0.000	0.006	0.044
hisgrep/artcncl	00:00:00.002	0.0%	35	0.000	0.057	0.071
hisgrep/artcncl/perl	00:00:00.004	0.0%	35	0.000	0.114	0.500
hisgrep/perl	00:00:00.001	0.0%	37	0.000	0.027	0.036
hishave/artcncl	00:00:00.000	0.0%	36	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	35	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.189	0.0%	13765	0.000	0.014	0.059
history sync	00:00:00.029	0.0%	337	0.000	0.086	1.000
history write	00:00:00.378	0.0%	2627	0.000	0.144	0.611
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	24:00:36.499	100.0%	14294	279.668	6047.048	600042.000
nntp read	00:00:00.382	0.0%	13588	0.000	0.028	1.000
overview write	00:00:00.499	0.0%	1880	0.000	0.265	3.500
perl filter	00:00:10.168	0.0%	2627	1.628	3.871	28.000
python filter	00:00:00.008	0.0%	2627	0.000	0.003	0.111
site send	00:00:00.509	0.0%	3793	0.000	0.134	0.583
TOTAL: 24:01:06.489	24:00:50.257	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.023	0.0%	3459	0.000	0.007	0.143
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.105	0.0%	17390	0.000	0.006	0.083
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.187	0.0%	7879	0.000	0.024	0.500
data write	00:00:00.279	0.0%	7119	0.000	0.039	0.174
idle	23:56:01.725	100.0%	17390	176.372	4954.671	19081.939
status file	00:00:08.675	0.0%	358	0.000	24.232	82.000
TOTAL: 23:56:29.851	23:56:11.003	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:26.802	31.2%	983	6.500	27.266	2836.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.076	0.1%	2234	0.000	0.034	0.500
TOTAL: 00:01:26.036	00:00:26.878	31.2%	-	-	-	-

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
control@eternal-september.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	news.samoylyk.net	4	1719	1142	541	36	66%	13:33:25
2	feed-out.gegeweb.org	3	1902	348	1553	1	18%	16:09:55
3	news.nntp4.net	2	2437	155	1657	625	6%	12:56:31
4	nntp.terraraq.uk	5	1680	145	1532	3	8%	21:49:13
5	news.glou.org	5	214	38	165	11	17%	07:48:52
6	gegeweb-out.news.tnetconsulting.net	6	1203	12	1189	2	0%	24:42:27
7	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 7		50	9155	1840	6637	678	20%	97:00:23

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.samoylyk.net	4.7 MB	19.0 KB	134.9 KB	4.8 MB	96%	4.2 KB
2	feed-out.gegeweb.org	1.2 MB	2.2 KB	2.2 KB	1.2 MB	99%	3.4 KB
3	news.nntp4.net	667.9 KB	0.0 KB	5.9 MB	6.6 MB	9%	8.6 KB
4	nntp.terraraq.uk	374.5 KB	0.0 KB	5.2 KB	379.8 KB	98%	2.6 KB
5	news.glou.org	166.1 KB	0.0 KB	39.0 KB	205.1 KB	80%	4.2 KB
6	gegeweb-out.news.tnetconsulting.net	54.0 KB	0.0 KB	4.7 KB	58.8 KB	91%	4.2 KB
7	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 7		7.1 MB	21.2 KB	6.1 MB	13.2 MB	53%	5.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 1 04:15:02 - 04:59:59	24	1.3%	0.01	68.4 KB	0.0%	0.03
Oct 1 05:00:00 - 05:59:59	47	2.5%	0.01	162.0 KB	0.0%	0.04
Oct 1 06:00:00 - 06:59:59	30	1.6%	0.01	120.2 KB	0.0%	0.03
Oct 1 07:00:00 - 07:59:59	34	1.8%	0.01	138.7 KB	0.0%	0.04
Oct 1 08:00:00 - 08:59:59	39	2.1%	0.01	187.1 KB	0.0%	0.05
Oct 1 09:00:00 - 09:59:59	54	2.9%	0.01	233.1 KB	0.0%	0.06
Oct 1 10:00:00 - 10:59:59	44	2.3%	0.01	142.1 KB	0.0%	0.04
Oct 1 11:00:00 - 11:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 1 12:00:00 - 12:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 1 13:00:00 - 13:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 1 14:00:00 - 14:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 1 15:00:00 - 15:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 1 16:00:00 - 16:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 1 17:00:00 - 17:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 1 18:00:00 - 18:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
Oct 1 19:00:00 - 19:59:59	992	52.8%	0.28	4.0 MB	0.0%	1.13
Oct 1 20:00:00 - 20:59:59	106	5.6%	0.03	352.5 KB	0.0%	0.10
Oct 1 21:00:00 - 21:59:59	110	5.9%	0.03	482.6 KB	0.0%	0.13
Oct 1 22:00:00 - 22:59:59	75	4.0%	0.02	306.7 KB	0.0%	0.09
Oct 1 23:00:00 - 23:59:59	64	3.4%	0.02	329.0 KB	0.0%	0.09
Oct 2 00:00:00 - 00:59:59	110	5.9%	0.03	308.8 KB	0.0%	0.09
Oct 2 01:00:00 - 01:59:59	63	3.4%	0.02	330.3 KB	0.0%	0.09
Oct 2 02:00:00 - 02:59:59	40	2.1%	0.01	148.6 KB	0.0%	0.04
Oct 2 03:00:00 - 03:59:59	40	2.1%	0.01	150.5 KB	0.0%	0.04
Oct 2 04:00:00 - 04:15:01	8	0.4%	0.01	23.1 KB	0.0%	0.03
TOTAL: 23:59:59	1880	100.0%	0.02	0.0 KB	100.0%	0.00

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nntp4.net	695	549	0	2	144
2	news.samoylyk.net	37	0	7	0	30
3	news.glou.org	16	0	0	2	14
4	gegeweb-out.news.tnetconsulting.net	8	0	5	0	3
5	nntp.terraraq.uk	4	0	0	0	4
6	feed-out.gegeweb.org	1	0	1	0	0
TOTAL: 6		761	549	13	4	195

Unwanted newsgroups [Top 20]:

Newsgroup	Count
linux.kernel.netdev	492
linux.kernel.git	57
TOTAL: 2	549

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

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

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	138
Bad path (news.giganews.com.POSTED)	54
Binary Image: misplaced jpg	1
No Cancel-Key matches Cancel-Lock. target=<mh5tbl50ohob5hr0270vssauluqdmsu7a7@kgbh.org>	1
TOTAL: 4	194

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news.samoylyk.net	1
	TOTAL: 1	1
TOTAL: 1		1

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.gegeweb.org	1491	3	1453	0	0	0%	15:51:11
2	news.tnetconsulting.net	2489	1	1877	1	1028	0%	23:59:57
3	news.nntp4.net	1714	0	1682	0	5	0%	15:57:02
4	news.samoylyk.net	59	0	54	0	629	0%	23:59:57
5	nntp.terraraq.uk	1002	0	974	0	546	0%	23:51:08
6	i2pn.org	0	0	0	0	1878	0%	23:59:59
7	news.pasdenom.info	1413	0	1354	1	0	0%	15:56:55
8	news.aioe.org	0	0	0	0	1495	0%	23:59:59
TOTAL: 8		8168	4	7394	2	5581	0%	167:36:08

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.gegeweb.org	12.3 KB	0.0 KB	12.3 KB	4.1 KB	15:51:11
2	news.tnetconsulting.net	0.8 KB	10.8 KB	11.6 KB	5.8 KB	23:59:57
3	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0	15:57:02
4	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0	23:59:57
5	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:51:08
6	i2pn.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:59
7	news.pasdenom.info	0.0 KB	1.5 KB	1.5 KB	1.5 KB	15:56:55
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:59
TOTAL: 8		13.1 KB	12.3 KB	25.4 KB	4.2 KB	167:36:08

NNRP readership statistics:

System		Conn	Elapsed
1	71.6.134.230	1	00:00:00
2	104.152.52.205	2	00:00:00
3	mail.gegeweb.eu	180	00:00:15
4	2a01:4020:31:0:102::1e7	1	00:00:00
5	66.61.98.95	6	00:00:03
6	104.152.52.223	1	00:00:01
7	104.152.52.211	5	00:00:03
8	104.152.52.227	1	00:00:00
9	193.24.211.151	3	00:00:00
10	owain.probe.onyphe.net	1	00:00:02
11	2603:6010:6802:4ca5:1a03:73ff:fe28:be0f	4	00:00:02
12	77.195.132.66.censys-scanner.com	1	00:00:03
13	194.165.16.151	6	00:00:00
14	ninja.census.shodan.io	1	00:00:00
15	xanthe.probe.onyphe.net	1	00:00:02
16	198.235.24.179	1	00:00:01
17	104.152.52.209	3	00:00:03
18	104.152.52.221	1	00:00:01
19	2a01:4020:2:b:101::8	1	00:00:00
20	194.165.16.124	6	00:00:00
21	46.161.27.109	6	00:00:00
22	o057.scanner.modat.io	1	00:00:31
23	104.152.52.207	1	00:00:00
24	104.152.52.214	1	00:00:01
25	tamsin.probe.onyphe.net	1	00:00:00
26	193.24.211.138	3	00:00:00
27	194.165.16.127	6	00:00:00
28	jed.probe.onyphe.net	1	00:00:02
29	104.152.52.226	1	00:00:03
30	104.152.52.200	1	00:00:00
TOTAL: 30		248	00:01:25

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.census.shodan.io	1	00:00:00
2	*.scanner.modat.io	1	00:00:31
3	unresolved	55	00:00:23
4	*.probe.onyphe.net	4	00:00:08
5	*.195.132.66.censys-scanner.com	1	00:00:03
6	*.gegeweb.eu	180	00:00:15
7	?	6	00:00:02
TOTAL: 7		248	00:01:25

NNRP auth users [Top 20]:

User	Conn
mailman	180
TOTAL: 1	180

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
o057.scanner.modat.io	0.037	0.025	0.000	00:00:31
mail.gegeweb.eu	3.492	2.883	1.123	00:00:15
104.152.52.226	0.015	0.012	0.000	00:00:03
66.61.98.95	0.102	0.091	0.000	00:00:03
104.152.52.211	0.175	0.062	0.000	00:00:03
104.152.52.209	0.129	0.037	0.000	00:00:03
77.195.132.66.censys-scanner.com	0.019	0.010	0.000	00:00:03
xanthe.probe.onyphe.net	0.036	0.010	0.000	00:00:02
jed.probe.onyphe.net	0.023	0.000	0.000	00:00:02
2603:6010:6802:4ca5:1a03:73ff:fe28:be0f	0.070	0.077	0.000	00:00:02
owain.probe.onyphe.net	0.057	0.020	0.000	00:00:02
104.152.52.214	0.014	0.014	0.000	00:00:01
104.152.52.221	0.014	0.009	0.000	00:00:01
104.152.52.223	0.015	0.008	0.000	00:00:01
198.235.24.179	0.017	0.007	0.000	00:00:01
71.6.134.230	0.013	0.009	0.000	00:00:00
ninja.census.shodan.io	0.024	0.027	0.000	00:00:00
104.152.52.200	0.033	0.015	0.000	00:00:00
tamsin.probe.onyphe.net	0.021	0.029	0.000	00:00:00
104.152.52.227	0.014	0.009	0.000	00:00:00
TOTAL: 31	5.141	3.949	1.123	00:01:25

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
194.165.16.124	6
104.152.52.209	5
104.152.52.221	5
193.24.211.151	3
193.24.211.138	3
194.165.16.151	3
46.161.27.109	3
tamsin.probe.onyphe.net	2
104.152.52.227	2
104.152.52.200	2
104.152.52.226	1
77.195.132.66.censys-scanner.com	1
TOTAL: 12	36

NNRP unrecognized commands (by command):

Command	Count
#003	18
Connection: close	2
EHLO 45.13.105.36	2
User-Agent: curl/7.61.1	2
Accept: /	2
Host: 45.13.105.36	2
MAIL FROM:<relaycheck_please_ignore@prot...	2
GET / HTTP/1.1	2
#026#003#003#002a#001	1
EHLO www.censys.io	1
SSH-2.0-Go	1
À$ÀÀ¯À,ÀrÀsÌ©#023#002#023#001Ì#024À#007À#022À#023À'À/À#024À(À0À`Àa...	1
TOTAL: 12	36

NNRP client timeouts [Top 20]:

System	Conn	Peer
194.165.16.124	6	6
46.161.27.109	3	3
194.165.16.151	3	3
193.24.211.151	3	3
193.24.211.138	3	3
2a01:4020:31:0:102::1e7	1	1
TOTAL: 6	19	21

Daily Usenet report for news.niel.me

Oct 1 04:15:02 -- Oct 2 04:15:01