Daily Usenet report for news.niel.me: Jul 5 04:15:01

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Jul 5 13:59:16 rpi-studio controlchan[17166]: skipping newgroup local.grenzland y lkh%v36407.1blu.de (would be created): Empty description
Jul 5 13:59:16 rpi-studio controlchan[17166]: skipping newgroup local.grenzland y lkh%v36407.1blu.de (would be created): Empty description
Jul 5 22:24:47 rpi-studio innd: filter: No Cancel-Key[sha1:vKvNtKwrFzLk3RQIhWhiCanJJcE=] matches Cancel-Lock[sha1:PFQ4i0KInbEOtuFLbDAcws1lRIg=] target=<686980b7$0$28066$426a34cc@news.free.fr>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
innd	2273	28.5%	372.0 KB	30.4%
nnrpd	1992	25.0%	224.9 KB	18.4%
innfeed	1963	24.6%	317.8 KB	26.0%
inn	1748	21.9%	307.5 KB	25.1%
controlchan	5	0.1%	0.6 KB	0.1%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	7982	100.0%	1.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	8215	57.8%
Negative hits	4261	30.0%
Do not exist	1728	12.2%
Cache misses	4	0.0%
TOTAL: 4	14208	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.009	0.0%	5	0.000	1.800	3.000
article cancel	00:00:00.000	0.0%	5	0.000	0.000	0.000
article cleanup	00:00:00.060	0.0%	1752	0.000	0.034	0.364
article logging	00:00:00.112	0.0%	1752	0.000	0.064	0.500
article parse	00:00:00.265	0.0%	3936	0.000	0.067	0.286
article write	00:00:00.681	0.0%	1395	0.000	0.488	1.455
artlog/artcncl	00:00:00.000	0.0%	5	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.002	0.0%	5	0.000	0.400	0.667
data move	00:00:00.080	0.0%	15393	0.000	0.005	0.039
hisgrep/artcncl	00:00:00.000	0.0%	5	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	5	0.000	0.000	0.000
hisgrep/perl	00:00:00.002	0.0%	9	0.000	0.222	0.500
hishave/artcncl	00:00:00.000	0.0%	5	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.228	0.0%	14333	0.000	0.016	0.054
history sync	00:00:00.040	0.0%	326	0.000	0.123	1.000
history write	00:00:00.308	0.0%	1752	0.000	0.176	0.625
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:41.686	100.0%	17245	1656.992	5012.565	21454.643
nntp read	00:00:00.474	0.0%	16484	0.000	0.029	0.080
overview write	00:00:00.469	0.0%	1395	0.000	0.336	1.833
perl filter	00:00:10.125	0.0%	1752	2.154	5.779	26.500
python filter	00:00:00.008	0.0%	1752	0.000	0.005	0.125
site send	00:00:00.476	0.0%	2846	0.000	0.167	0.500
TOTAL: 24:01:03.819	24:00:55.025	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.011	0.0%	1395	0.000	0.008	0.182
article prepare	00:00:00.001	0.0%	316	0.000	0.003	0.036
article read	00:00:00.022	0.0%	69	0.000	0.319	7.000
backlog stats	00:00:00.111	0.0%	22906	0.000	0.005	0.029
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.305	0.0%	11047	0.000	0.028	0.111
data write	00:00:00.579	0.0%	9667	0.000	0.060	0.273
idle	23:59:40.216	100.0%	22906	1760.639	3771.074	8694.362
status file	00:00:06.904	0.0%	343	0.000	20.128	101.000
TOTAL: 23:59:51.338	23:59:48.149	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:38.099	16.1%	1463	9.400	26.042	6007.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.118	0.0%	3323	0.000	0.036	0.500
TOTAL: 00:03:57.185	00:00:38.217	16.1%	-	-	-	-

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
lkh%v36407.1blu.de	2	0	0	0	0	2
TOTAL	2	0	0	0	0	2

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news.nntp4.net	3	1355	509	617	229	37%	19:01:06
2	feed-out.gegeweb.org	5	1332	345	987	0	25%	23:54:34
3	news.glou.org	6	1184	83	1050	51	7%	23:32:03
4	news.samoylyk.net	5	471	81	390	0	17%	10:36:15
5	i2pn.org	3	1870	78	1792	0	4%	28:12:09
6	gegeweb-out.news.tnetconsulting.net	5	1420	64	1346	10	4%	26:39:08
7	nntp.terraraq.uk	5	377	3	374	0	0%	10:41:54
8	localhost	25	0	0	0	0	0%	00:00:00
9	usenet.pasdenom.info	14	647	0	647	0	0%	23:20:24
TOTAL: 9		71	8656	1163	7203	290	13%	165:57:33

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	1.5 MB	1.2 MB	2.7 MB	55%	3.7 KB
2	feed-out.gegeweb.org	1.2 MB	0.0 KB	1.2 MB	100%	3.6 KB
3	news.samoylyk.net	534.6 KB	0.0 KB	534.6 KB	100%	6.6 KB
4	gegeweb-out.news.tnetconsulting.net	345.2 KB	100.2 KB	445.4 KB	77%	6.0 KB
5	news.glou.org	253.9 KB	134.8 KB	388.7 KB	65%	2.9 KB
6	i2pn.org	238.8 KB	0.0 KB	238.8 KB	100%	3.1 KB
7	nntp.terraraq.uk	6.5 KB	0.0 KB	6.5 KB	100%	2.2 KB
8	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
9	usenet.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		4.0 MB	1.4 MB	5.5 MB	73%	3.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 5 04:15:01 - 04:59:59	40	2.9%	0.01	142.7 KB	2.6%	0.05
Jul 5 05:00:00 - 05:59:59	37	2.6%	0.01	130.7 KB	2.4%	0.04
Jul 5 06:00:00 - 06:59:59	22	1.6%	0.01	136.0 KB	2.5%	0.04
Jul 5 07:00:00 - 07:59:59	33	2.4%	0.01	109.0 KB	2.0%	0.03
Jul 5 08:00:00 - 08:59:59	29	2.1%	0.01	129.6 KB	2.4%	0.04
Jul 5 09:00:00 - 09:59:59	69	4.9%	0.02	272.3 KB	4.9%	0.08
Jul 5 10:00:00 - 10:59:59	38	2.7%	0.01	114.8 KB	2.1%	0.03
Jul 5 11:00:00 - 11:59:59	53	3.8%	0.01	186.5 KB	3.4%	0.05
Jul 5 12:00:00 - 12:59:59	68	4.9%	0.02	184.3 KB	3.3%	0.05
Jul 5 13:00:00 - 13:59:59	49	3.5%	0.01	335.2 KB	6.1%	0.09
Jul 5 14:00:00 - 14:59:59	56	4.0%	0.02	186.5 KB	3.4%	0.05
Jul 5 15:00:00 - 15:59:59	105	7.5%	0.03	347.4 KB	6.3%	0.10
Jul 5 16:00:00 - 16:59:59	85	6.1%	0.02	298.2 KB	5.4%	0.08
Jul 5 17:00:00 - 17:59:59	76	5.4%	0.02	305.7 KB	5.5%	0.08
Jul 5 18:00:00 - 18:59:59	64	4.6%	0.02	266.1 KB	4.8%	0.07
Jul 5 19:00:00 - 19:59:59	61	4.4%	0.02	202.8 KB	3.7%	0.06
Jul 5 20:00:00 - 20:59:59	66	4.7%	0.02	227.9 KB	4.1%	0.06
Jul 5 21:00:00 - 21:59:59	74	5.3%	0.02	400.8 KB	7.3%	0.11
Jul 5 22:00:00 - 22:59:59	71	5.1%	0.02	206.2 KB	3.7%	0.06
Jul 5 23:00:00 - 23:59:59	75	5.4%	0.02	313.5 KB	5.7%	0.09
Jul 6 00:00:00 - 00:59:59	61	4.4%	0.02	170.9 KB	3.1%	0.05
Jul 6 01:00:00 - 01:59:59	39	2.8%	0.01	108.0 KB	2.0%	0.03
Jul 6 02:00:00 - 02:59:59	46	3.3%	0.01	532.8 KB	9.7%	0.15
Jul 6 03:00:00 - 03:59:59	61	4.4%	0.02	164.2 KB	3.0%	0.05
Jul 6 04:00:00 - 04:15:01	19	1.4%	0.02	37.9 KB	0.7%	0.04
TOTAL: 24:00:00	1397	100.0%	0.02	5.4 MB	100.0%	0.06

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	283	104	179
2	news.glou.org	51	0	51
3	news.samoylyk.net	3	0	3
4	gegeweb-out.news.tnetconsulting.net	2	0	2
5	i2pn.org	2	0	2
TOTAL: 5		341	104	237

Unwanted newsgroups [Top 20]:

Newsgroup	Count
linux.kernel.netdev	91
linux.kernel.git	7
soc.culture.iran	3
soc.culture.russia	2
news.test	1
TOTAL: 5	104

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	89
Too many newsgroups	79
Bad path (news.giganews.com.POSTED)	60
Cancels abusifs	4
Cancel of <mcti02FucrfU2@mid.individual.net> without Cancel-Key	1
U2 violation - invalid distribution	1
Cancel of <104btr2$1ljcd$1@dont-email.me> without Cancel-Key	1
No Cancel-Key matches Cancel-Lock. target=<686980b7$0$28066$426a34cc@news.free.fr>	1
Cancel of <mcti43FucrfU6@mid.individual.net> without Cancel-Key	1
TOTAL: 9	237

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.gegeweb.org	1088	30	1013	0	0	2%	23:50:10
2	i2pn.org	2011	10	1307	18	1	0%	23:55:00
3	news.tnetconsulting.net	2095	7	1380	3	0	0%	23:56:31
4	news.nntp4.net	816	3	742	0	0	0%	23:13:46
5	nntp.terraraq.uk	1036	0	951	2	0	0%	23:16:22
6	news.aioe.org	0	0	0	0	1141	0%	24:00:00
7	news.samoylyk.net	1343	0	1193	1	0	0%	23:39:55
8	news.pasdenom.info	1176	0	1107	5	0	0%	23:37:35
TOTAL: 8		9565	50	7693	29	1142	0%	189:29:19

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.tnetconsulting.net	78.1 KB	8.3 KB	86.4 KB	8.6 KB	23:56:31
2	news.gegeweb.org	74.1 KB	0.0 KB	74.1 KB	2.5 KB	23:50:10
3	i2pn.org	18.6 KB	78.2 KB	96.8 KB	3.5 KB	23:55:00
4	news.nntp4.net	3.0 KB	0.0 KB	3.0 KB	1.0 KB	23:13:46
5	nntp.terraraq.uk	0.0 KB	2.0 KB	2.0 KB	1.0 KB	23:16:22
6	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
7	news.samoylyk.net	0.0 KB	1.0 KB	1.0 KB	1.0 KB	23:39:55
8	news.pasdenom.info	0.0 KB	5.1 KB	5.1 KB	1.0 KB	23:37:35
TOTAL: 8		173.8 KB	94.6 KB	268.4 KB	3.4 KB	189:29:19

NNRP readership statistics:

System		Conn	Elapsed
1	2a06:4883:3000::44	1	00:00:03
2	206.168.34.54	1	00:00:05
3	198.235.24.157	3	00:00:05
4	71.6.134.232	1	00:00:00
5	172-236-228-38.ip.linodeusercontent.com	2	00:00:01
6	206.150.216.162.bc.googleusercontent.com	1	00:00:00
7	dedicated.monitoring.internet-measurement.com	1	00:00:00
8	205.210.31.154	1	00:00:02
9	147.185.132.71	2	00:00:02
10	198.235.24.20	1	00:00:03
11	66-228-53-78.ip.linodeusercontent.com	2	00:00:00
12	147.185.133.92	1	00:00:08
13	azpdwg7weyrp.stretchoid.com	1	00:00:00
14	2a06:4882:5000::51	1	00:00:00
15	198.235.24.160	3	00:00:12
16	21.210.203.35.bc.googleusercontent.com	1	00:00:00
17	thriving.monitoring.internet-measurement.com	1	00:00:03
18	206.168.34.67	1	00:00:32
19	2a06:4883:5000::6a	1	00:00:00
20	2a06:4882:3000::48	1	00:00:04
21	mail.gegeweb.eu	288	00:01:53
22	147.185.133.135	4	00:01:03
23	cheerful.monitoring.internet-measurement.com	1	00:00:03
24	2a06:4883:5000::5f	1	00:00:00
25	stunning.monitoring.internet-measurement.com	1	00:00:00
26	sunlit.monitoring.internet-measurement.com	1	00:00:00
27	2a06:4883:5000::56	1	00:00:00
TOTAL: 27		324	00:04:29

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.monitoring.internet-measurement.com	5	00:00:07
2	*.stretchoid.com	1	00:00:00
3	*.gegeweb.eu	288	00:01:53
4	unresolved	18	00:02:17
5	*.150.216.162.bc.googleusercontent.com	1	00:00:00
6	?	6	00:00:07
7	*.210.203.35.bc.googleusercontent.com	1	00:00:00
8	*.ip.linodeusercontent.com	4	00:00:01
TOTAL: 8		324	00:04:29

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	4.805	4.327	1.866	00:01:53
147.185.133.135	0.333	0.144	0.000	00:01:03
206.168.34.67	0.044	0.012	0.000	00:00:32
198.235.24.160	0.027	0.042	0.000	00:00:12
147.185.133.92	0.068	0.024	0.000	00:00:08
206.168.34.54	0.032	0.008	0.000	00:00:05
198.235.24.157	0.087	0.037	0.000	00:00:05
2a06:4882:3000::48	0.036	0.004	0.000	00:00:04
198.235.24.20	0.018	0.005	0.000	00:00:03
cheerful.monitoring.internet-measurement.com	0.048	0.016	0.000	00:00:03
2a06:4883:3000::44	0.033	0.022	0.000	00:00:03
thriving.monitoring.internet-measurement.com	0.070	0.019	0.000	00:00:03
205.210.31.154	0.015	0.008	0.000	00:00:02
147.185.132.71	0.112	0.046	0.000	00:00:02
172-236-228-38.ip.linodeusercontent.com	0.023	0.024	0.000	00:00:01
66-228-53-78.ip.linodeusercontent.com	0.051	0.016	0.000	00:00:00
azpdwg7weyrp.stretchoid.com	0.044	0.008	0.000	00:00:00
71.6.134.232	0.011	0.011	0.000	00:00:00
206.150.216.162.bc.googleusercontent.com	0.055	0.029	0.000	00:00:00
sunlit.monitoring.internet-measurement.com	0.024	0.004	0.000	00:00:00
TOTAL: 25	6.107	4.864	1.866	00:04:29

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
147.185.133.135	8
172-236-228-38.ip.linodeusercontent.com	7
66-228-53-78.ip.linodeusercontent.com	5
147.185.132.71	4
206.150.216.162.bc.googleusercontent.com	4
147.185.133.92	4
21.210.203.35.bc.googleusercontent.com	4
198.235.24.160	1
206.168.34.54	1
198.235.24.157	1
azpdwg7weyrp.stretchoid.com	1
TOTAL: 11	40

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	8
Host: 45.13.105.36:563	7
Accept-Encoding: gzip	5
Accept: /	5
User-Agent: Expanse, a Palo Alto Network...	3
User-Agent: curl/7.68.0	3
User-Agent: Mozilla/5.0 (Macintosh; Inte...	2
#026#003	2
MGLNDD_45.13.105.36_119	1
#026#003#001	1
Host: 45.13.105.36:119	1
#001	1
EHLO www.censys.io	1
TOTAL: 13	40

NNRP client timeouts [Top 20]:

System	Conn	Peer
147.185.133.135	2	4
198.235.24.160	2	3
198.235.24.157	2	3
147.185.132.71	1	2
TOTAL: 4	7	14

Daily Usenet report for news.niel.me

Jul 5 04:15:01 -- Jul 6 04:15:01