Daily Usenet report for news.niel.me: May 14 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

May 14 10:06:17 rpi-studio innd: filter: No Cancel-Key[sha1:JvPD+fnosyLpS9QFdXDiMiXtr0k=] matches Cancel-Lock[sha1:o2ek4VxsyeNZHTm+cshk0aad6wM= sha256:DcxLmPOKoX/wKErxft0hSIa0cmJtTn1BTu1EbPXQtII=] target=<10u3vob$3r6dp$1@dont-email.me>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2272	27.4%	388.1 KB	30.7%
innfeed	2108	25.4%	324.2 KB	25.6%
innd	1993	24.1%	346.8 KB	27.4%
nnrpd	1911	23.1%	204.7 KB	16.2%
controlchan	1	0.0%	0.1 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	8286	100.0%	1.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	8017	51.9%
Negative hits	5185	33.5%
Do not exist	2257	14.6%
Cache misses	2	0.0%
TOTAL: 4	15461	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.005	0.0%	4	1.000	1.250	2.000
article cancel	00:00:00.002	0.0%	5	0.000	0.400	1.000
article cleanup	00:00:00.049	0.0%	2262	0.000	0.022	0.200
article logging	00:00:00.165	0.0%	2265	0.000	0.073	0.375
article parse	00:00:00.412	0.0%	5630	0.000	0.073	0.286
article write	00:00:00.879	0.0%	1785	0.000	0.492	3.000
artlog/artcncl	00:00:00.000	0.0%	5	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	4	0.000	0.000	0.000
data move	00:00:00.089	0.0%	18170	0.000	0.005	0.041
hisgrep/artcncl	00:00:00.000	0.0%	5	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	4	0.000	0.000	0.000
hisgrep/perl	00:00:00.000	0.0%	7	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	5	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	4	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.246	0.0%	15482	0.000	0.016	0.085
history sync	00:00:00.056	0.0%	400	0.000	0.140	0.667
history write	00:00:00.458	0.0%	2262	0.000	0.202	0.625
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:40.016	100.0%	19130	1600.664	4518.558	27293.318
nntp read	00:00:00.575	0.0%	18453	0.000	0.031	0.149
overview write	00:00:00.562	0.0%	1785	0.000	0.315	2.625
perl filter	00:00:10.742	0.0%	2262	1.870	4.749	17.000
python filter	00:00:00.014	0.0%	2262	0.000	0.006	0.125
site send	00:00:00.628	0.0%	3630	0.000	0.173	0.750
TOTAL: 24:01:04.065	24:00:54.898	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.007	0.0%	1809	0.000	0.004	0.100
article prepare	00:00:00.001	0.0%	104	0.000	0.010	0.250
article read	00:00:00.006	0.0%	26	0.000	0.231	1.500
backlog stats	00:00:00.128	0.0%	22721	0.000	0.006	0.040
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.264	0.0%	10997	0.000	0.024	0.111
data write	00:00:00.539	0.0%	9225	0.000	0.058	0.200
idle	23:58:20.868	100.0%	22721	1869.779	3798.287	9536.937
status file	00:00:06.460	0.0%	377	1.000	17.135	80.000
TOTAL: 23:58:32.434	23:58:28.273	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:33.577	24.1%	1457	11.600	23.045	3207.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.115	0.1%	3348	0.000	0.034	1.000
TOTAL: 00:02:19.510	00:00:33.692	24.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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news.nntp4.net	2	2344	1080	796	468	46%	24:49:07
2	feed-out.gegeweb.org	4	1801	237	1564	0	13%	23:58:07
3	news.samoylyk.net	4	1264	232	1030	2	18%	17:47:58
4	news.glou.org	7	1375	85	1267	23	6%	24:57:31
5	nntp.terraraq.uk	6	502	15	485	2	2%	11:23:07
6	usenet.pasdenom.info	12	519	11	508	0	2%	15:45:06
7	gegeweb-out.news.tnetconsulting.net	6	481	5	476	0	1%	11:20:24
8	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 8		66	8286	1665	6126	495	20%	130:01:20

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	4.0 MB	4.5 MB	8.4 MB	47%	5.6 KB
2	news.samoylyk.net	1.8 MB	8.2 KB	1.8 MB	99%	7.9 KB
3	feed-out.gegeweb.org	595.9 KB	0.0 KB	595.9 KB	100%	2.5 KB
4	news.glou.org	277.8 KB	84.3 KB	362.2 KB	76%	3.4 KB
5	nntp.terraraq.uk	38.0 KB	8.6 KB	46.5 KB	81%	2.7 KB
6	usenet.pasdenom.info	22.8 KB	0.0 KB	22.8 KB	100%	2.1 KB
7	gegeweb-out.news.tnetconsulting.net	17.0 KB	0.0 KB	17.0 KB	100%	3.4 KB
8	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 8		6.7 MB	4.6 MB	11.3 MB	59%	5.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 14 04:15:01 - 04:59:59	37	2.1%	0.01	135.2 KB	1.8%	0.05
May 14 05:00:00 - 05:59:59	29	1.6%	0.01	107.9 KB	1.5%	0.03
May 14 06:00:00 - 06:59:59	20	1.1%	0.01	115.4 KB	1.6%	0.03
May 14 07:00:00 - 07:59:59	65	3.6%	0.02	235.6 KB	3.2%	0.07
May 14 08:00:00 - 08:59:59	56	3.1%	0.02	220.7 KB	3.0%	0.06
May 14 09:00:00 - 09:59:59	76	4.3%	0.02	691.5 KB	9.5%	0.19
May 14 10:00:00 - 10:59:59	98	5.5%	0.03	331.6 KB	4.5%	0.09
May 14 11:00:00 - 11:59:59	54	3.0%	0.01	228.0 KB	3.1%	0.06
May 14 12:00:00 - 12:59:59	76	4.3%	0.02	234.1 KB	3.2%	0.07
May 14 13:00:00 - 13:59:59	78	4.4%	0.02	251.5 KB	3.4%	0.07
May 14 14:00:00 - 14:59:59	71	4.0%	0.02	238.3 KB	3.3%	0.07
May 14 15:00:00 - 15:59:59	83	4.7%	0.02	282.0 KB	3.9%	0.08
May 14 16:00:00 - 16:59:59	93	5.2%	0.03	338.5 KB	4.6%	0.09
May 14 17:00:00 - 17:59:59	79	4.4%	0.02	288.0 KB	3.9%	0.08
May 14 18:00:00 - 18:59:59	70	3.9%	0.02	275.3 KB	3.8%	0.08
May 14 19:00:00 - 19:59:59	120	6.7%	0.03	801.7 KB	11.0%	0.22
May 14 20:00:00 - 20:59:59	92	5.2%	0.03	342.4 KB	4.7%	0.10
May 14 21:00:00 - 21:59:59	127	7.1%	0.04	552.6 KB	7.6%	0.15
May 14 22:00:00 - 22:59:59	90	5.0%	0.03	327.0 KB	4.5%	0.09
May 14 23:00:00 - 23:59:59	83	4.7%	0.02	337.4 KB	4.6%	0.09
May 15 00:00:00 - 00:59:59	87	4.9%	0.02	287.8 KB	3.9%	0.08
May 15 01:00:00 - 01:59:59	71	4.0%	0.02	241.0 KB	3.3%	0.07
May 15 02:00:00 - 02:59:59	60	3.4%	0.02	206.3 KB	2.8%	0.06
May 15 03:00:00 - 03:59:59	53	3.0%	0.01	203.6 KB	2.8%	0.06
May 15 04:00:00 - 04:15:01	16	0.9%	0.02	39.9 KB	0.5%	0.04
TOTAL: 24:00:00	1784	100.0%	0.02	7.1 MB	100.0%	0.08

Sites sending bad articles:

Server		Total	Group	Unapp	Other
1	news.nntp4.net	456	358	0	98
2	news.glou.org	14	0	2	12
3	news.samoylyk.net	4	0	0	4
4	nntp.terraraq.uk	3	0	0	3
5	feed-out.gegeweb.org	1	0	0	1
6	usenet.pasdenom.info	1	0	0	1
TOTAL: 6		479	358	2	119

Unwanted newsgroups [Top 20]:

Newsgroup	Count
linux.kernel.netdev	282
linux.kernel.git	75
news.test	1
TOTAL: 3	358

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

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

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	62
Bad path (news.giganews.com.POSTED)	30
EMP (phn path)	16
HTML Multipart	3
Cancels abusifs	2
Cancel of non-existing ID <10u3vob$3r6dp$1@dont-email.me>	2
Cancel for rejected article	1
TOTAL: 7	116

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.tnetconsulting.net	3208	8	1755	18	26	0%	23:59:16
2	news.gegeweb.org	1681	0	1546	0	0	0%	23:59:04
3	news.aioe.org	0	0	0	0	1495	0%	24:00:00
4	news.pasdenom.info	1168	0	1139	0	0	0%	23:23:55
5	i2pn.org	0	0	0	0	1782	0%	24:00:00
6	nntp.terraraq.uk	914	0	873	0	0	0%	23:13:38
7	news.nntp4.net	786	0	738	0	0	0%	23:09:11
8	news.samoylyk.net	1561	0	1393	0	0	0%	23:45:07
TOTAL: 8		9318	8	7444	18	3303	0%	189:30:11

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.tnetconsulting.net	37.6 KB	87.0 KB	124.7 KB	4.8 KB	23:59:16
2	news.gegeweb.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:04
3	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
4	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:23:55
5	i2pn.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
6	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:13:38
7	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0	23:09:11
8	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0	23:45:07
TOTAL: 8		37.6 KB	87.0 KB	124.7 KB	4.8 KB	189:30:11

NNRP readership statistics:

System		Conn	Elapsed
1	r4-217-d9.monitoring.internet-measurement.com	1	00:00:01
2	173.186.132.66.censys-scanner.com	3	00:00:05
3	69.5.169.45	1	00:00:00
4	r5-182-b6.monitoring.internet-measurement.com	1	00:00:00
5	31.14.254.77	1	00:00:00
6	r3-120-78.monitoring.internet-measurement.com	1	00:00:01
7	93.123.109.191	1	00:00:04
8	161.150.216.162.bc.googleusercontent.com	2	00:01:00
9	r5-129-81.monitoring.internet-measurement.com	1	00:00:03
10	r3-214-d6.monitoring.internet-measurement.com	2	00:00:07
11	r5-213-d5.monitoring.internet-measurement.com	1	00:00:00
12	147.185.133.73	2	00:00:19
13	r4-131-83.monitoring.internet-measurement.com	1	00:00:03
14	r5-79-4f.monitoring.internet-measurement.com	1	00:00:00
15	r4-193-c1.monitoring.internet-measurement.com	1	00:00:01
16	47.254.213.90	1	00:00:03
17	r4-186-ba.monitoring.internet-measurement.com	1	00:00:00
18	r4-198-c6.monitoring.internet-measurement.com	1	00:00:01
19	r5-200-c8.monitoring.internet-measurement.com	1	00:00:03
20	prod-bromine-us-southeast-24.li.binaryedge.ninja	1	00:00:02
21	r3-198-c6.monitoring.internet-measurement.com	1	00:00:02
22	r4-109-6d.monitoring.internet-measurement.com	1	00:00:00
23	r3-201-c9.monitoring.internet-measurement.com	1	00:00:03
24	r3-89-59.monitoring.internet-measurement.com	1	00:00:00
25	mail.gegeweb.eu	288	00:00:32
26	45.227.254.152	1	00:00:19
TOTAL: 26		318	00:02:59

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	7	00:00:47
2	*.150.216.162.bc.googleusercontent.com	2	00:01:00
3	*.186.132.66.censys-scanner.com	3	00:00:05
4	*.li.binaryedge.ninja	1	00:00:02
5	*.monitoring.internet-measurement.com	17	00:00:31
6	*.gegeweb.eu	288	00:00:32
TOTAL: 6		318	00:02:59

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
161.150.216.162.bc.googleusercontent.com	0.331	0.096	0.000	00:01:00
mail.gegeweb.eu	5.304	4.353	1.745	00:00:32
147.185.133.73	0.247	0.070	0.000	00:00:19
45.227.254.152	0.010	0.014	0.000	00:00:19
r3-214-d6.monitoring.internet-measurement.com	0.086	0.032	0.000	00:00:07
173.186.132.66.censys-scanner.com	0.099	0.067	0.000	00:00:05
93.123.109.191	0.027	0.019	0.000	00:00:04
r5-200-c8.monitoring.internet-measurement.com	0.041	0.012	0.000	00:00:03
r3-201-c9.monitoring.internet-measurement.com	0.079	0.025	0.000	00:00:03
47.254.213.90	0.030	0.022	0.000	00:00:03
r5-129-81.monitoring.internet-measurement.com	0.034	0.019	0.000	00:00:03
r4-131-83.monitoring.internet-measurement.com	0.080	0.030	0.000	00:00:03
prod-bromine-us-southeast-24.li.binaryedge.ninja	0.013	0.010	0.000	00:00:02
r3-198-c6.monitoring.internet-measurement.com	0.081	0.027	0.000	00:00:02
r3-120-78.monitoring.internet-measurement.com	0.018	0.005	0.000	00:00:01
r4-198-c6.monitoring.internet-measurement.com	0.077	0.037	0.000	00:00:01
r4-193-c1.monitoring.internet-measurement.com	0.073	0.008	0.000	00:00:01
r4-217-d9.monitoring.internet-measurement.com	0.087	0.017	0.000	00:00:01
r5-182-b6.monitoring.internet-measurement.com	0.085	0.020	0.000	00:00:00
r5-213-d5.monitoring.internet-measurement.com	0.033	0.025	0.000	00:00:00
TOTAL: 24	7.011	4.992	1.745	00:02:59

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
161.150.216.162.bc.googleusercontent.com	4
147.185.133.73	4
93.123.109.191	2
173.186.132.66.censys-scanner.com	1
45.227.254.152	1
TOTAL: 5	12

NNRP unrecognized commands (by command):

Command	Count
User-Agent: curl/7.68.0	2
Host: 45.13.105.36:563	2
GET / HTTP/1.1	2
Accept: /	2
#003	1
#001	1
EHLO www.censys.io	1
#026#003#001	1
TOTAL: 8	12

NNRP client timeouts [Top 20]:

System	Conn	Peer
45.227.254.152	1	1
31.14.254.77	1	1
173.186.132.66.censys-scanner.com	1	2
161.150.216.162.bc.googleusercontent.com	1	2
93.123.109.191	1	1
47.254.213.90	1	1
147.185.133.73	1	2
TOTAL: 7	7	11

Daily Usenet report for news.niel.me

May 14 04:15:01 -- May 15 04:15:01