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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jul 24 18:37:37 rpi-studio innd: filter: No Cancel-Key[sha1:tpsHSr/lr6lDws8GvuXeZGz2Wn0= sha256:5GKYrQ964XV6HzR8OxhauQb28nj78Cxtp8NJAFm9/3g=] matches Cancel-Lock[sha1:1vc2qQtcd7cGE5facH37XY9+54s= sha256:PPrmtdunaaD8bTy6+pwcyJympq8/YXwrOH/I2X82TA4=] target=<105tnfs$8br$1@rasp.pasdenom.info>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
innd	2281	27.0%	367.3 KB	29.1%
inn	2243	26.6%	372.7 KB	29.6%
nnrpd	1991	23.6%	238.0 KB	18.9%
innfeed	1928	22.8%	282.1 KB	22.4%
controlchan	1	0.0%	0.2 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	8445	100.0%	1.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	7227	49.5%
Negative hits	5139	35.2%
Do not exist	2204	15.1%
Cache misses	30	0.2%
TOTAL: 4	14600	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.013	0.0%	14	0.000	0.929	2.000
article cancel	00:00:00.003	0.0%	14	0.000	0.214	1.000
article cleanup	00:00:00.057	0.0%	2214	0.000	0.026	0.500
article logging	00:00:00.157	0.0%	2215	0.000	0.071	0.500
article parse	00:00:00.452	0.0%	6985	0.000	0.065	0.250
article write	00:00:00.705	0.0%	1522	0.000	0.463	1.364
artlog/artcncl	00:00:00.001	0.0%	14	0.000	0.071	1.000
artlog/artcncl/perl	00:00:00.002	0.0%	14	0.000	0.143	1.000
data move	00:00:00.100	0.0%	17944	0.000	0.006	0.071
hisgrep/artcncl	00:00:00.001	0.0%	14	0.000	0.071	0.333
hisgrep/artcncl/perl	00:00:00.000	0.0%	14	0.000	0.000	0.000
hisgrep/perl	00:00:00.004	0.0%	19	0.000	0.211	1.000
hishave/artcncl	00:00:00.000	0.0%	14	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	14	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.269	0.0%	14613	0.000	0.018	0.107
history sync	00:00:00.049	0.0%	334	0.000	0.147	1.000
history write	00:00:00.391	0.0%	2214	0.000	0.177	0.667
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:36.909	100.0%	20081	958.543	4304.413	20003.833
nntp read	00:00:00.569	0.0%	19314	0.000	0.029	0.085
overview write	00:00:00.531	0.0%	1522	0.000	0.349	1.800
perl filter	00:00:12.870	0.0%	2214	2.161	5.813	24.000
python filter	00:00:00.012	0.0%	2214	0.000	0.005	0.143
site send	00:00:00.661	0.0%	3083	0.000	0.214	0.583
TOTAL: 24:01:03.455	24:00:53.756	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.014	0.0%	1532	0.000	0.009	0.167
article prepare	00:00:00.002	0.0%	208	0.000	0.010	0.083
article read	00:00:00.013	0.0%	38	0.000	0.342	2.500
backlog stats	00:00:00.124	0.0%	20737	0.000	0.006	0.028
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.300	0.0%	9780	0.000	0.031	0.109
data write	00:00:00.510	0.0%	8271	0.000	0.062	0.190
idle	23:59:42.632	100.0%	20737	2115.423	4165.628	10508.810
status file	00:00:09.168	0.0%	322	1.000	28.472	92.000
TOTAL: 23:59:57.709	23:59:52.763	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:09:09.346	74.3%	1488	9.400	369.184	156716.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.125	0.0%	3381	0.000	0.037	1.000
TOTAL: 00:12:19.563	00:09:09.471	74.3%	-	-	-	-

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	3	2305	738	889	678	32%	25:37:54
2	feed-out.gegeweb.org	4	1442	361	1081	0	25%	23:52:49
3	news.samoylyk.net	5	1733	285	1443	5	16%	27:40:04
4	gegeweb-out.news.tnetconsulting.net	6	2124	154	1967	3	7%	38:42:59
5	news.glou.org	4	1246	94	1114	38	7%	23:51:49
6	nntp.terraraq.uk	6	2108	91	2016	1	4%	39:46:39
7	usenet.pasdenom.info	17	667	0	667	0	0%	21:37:31
8	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 8		70	11625	1723	9177	725	14%	201:09:45

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.samoylyk.net	2.2 MB	23.8 KB	2.2 MB	98%	7.7 KB
2	news.nntp4.net	2.0 MB	8.4 MB	10.4 MB	19%	7.5 KB
3	feed-out.gegeweb.org	1.2 MB	0.0 KB	1.2 MB	100%	3.4 KB
4	gegeweb-out.news.tnetconsulting.net	564.3 KB	27.1 KB	591.4 KB	95%	3.8 KB
5	nntp.terraraq.uk	558.5 KB	2.1 KB	560.6 KB	99%	6.1 KB
6	news.glou.org	314.8 KB	133.0 KB	447.8 KB	70%	3.4 KB
7	usenet.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0%	0
8	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 8		6.8 MB	8.6 MB	15.3 MB	44%	6.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 24 04:15:01 - 04:59:59	58	3.8%	0.02	173.4 KB	3.0%	0.06
Jul 24 05:00:00 - 05:59:59	55	3.6%	0.02	307.0 KB	5.2%	0.09
Jul 24 06:00:00 - 06:59:59	16	1.1%	0.00	103.4 KB	1.8%	0.03
Jul 24 07:00:00 - 07:59:59	30	2.0%	0.01	74.7 KB	1.3%	0.02
Jul 24 08:00:00 - 08:59:59	31	2.0%	0.01	130.6 KB	2.2%	0.04
Jul 24 09:00:00 - 09:59:59	31	2.0%	0.01	164.3 KB	2.8%	0.05
Jul 24 10:00:00 - 10:59:59	61	4.0%	0.02	191.0 KB	3.3%	0.05
Jul 24 11:00:00 - 11:59:59	59	3.9%	0.02	192.8 KB	3.3%	0.05
Jul 24 12:00:00 - 12:59:59	69	4.5%	0.02	193.1 KB	3.3%	0.05
Jul 24 13:00:00 - 13:59:59	89	5.9%	0.02	286.7 KB	4.9%	0.08
Jul 24 14:00:00 - 14:59:59	70	4.6%	0.02	233.0 KB	4.0%	0.06
Jul 24 15:00:00 - 15:59:59	72	4.7%	0.02	258.5 KB	4.4%	0.07
Jul 24 16:00:00 - 16:59:59	87	5.7%	0.02	543.4 KB	9.2%	0.15
Jul 24 17:00:00 - 17:59:59	87	5.7%	0.02	577.0 KB	9.8%	0.16
Jul 24 18:00:00 - 18:59:59	82	5.4%	0.02	421.5 KB	7.2%	0.12
Jul 24 19:00:00 - 19:59:59	74	4.9%	0.02	251.3 KB	4.3%	0.07
Jul 24 20:00:00 - 20:59:59	61	4.0%	0.02	161.7 KB	2.8%	0.04
Jul 24 21:00:00 - 21:59:59	97	6.4%	0.03	278.3 KB	4.7%	0.08
Jul 24 22:00:00 - 22:59:59	78	5.1%	0.02	267.2 KB	4.5%	0.07
Jul 24 23:00:00 - 23:59:59	75	4.9%	0.02	299.0 KB	5.1%	0.08
Jul 25 00:00:00 - 00:59:59	91	6.0%	0.03	260.5 KB	4.4%	0.07
Jul 25 01:00:00 - 01:59:59	56	3.7%	0.02	155.5 KB	2.6%	0.04
Jul 25 02:00:00 - 02:59:59	30	2.0%	0.01	115.5 KB	2.0%	0.03
Jul 25 03:00:00 - 03:59:59	48	3.2%	0.01	194.6 KB	3.3%	0.05
Jul 25 04:00:00 - 04:15:01	14	0.9%	0.02	40.9 KB	0.7%	0.05
TOTAL: 24:00:00	1521	100.0%	0.02	5.7 MB	100.0%	0.07

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	649	302	347
2	news.glou.org	38	0	38
3	news.samoylyk.net	3	0	3
4	nntp.terraraq.uk	2	0	2
5	gegeweb-out.news.tnetconsulting.net	2	0	2
TOTAL: 5		694	302	392

Unwanted newsgroups [Top 20]:

Newsgroup	Count
linux.kernel.netdev	228
linux.kernel.git	70
news.test	3
soc.culture.iran	1
TOTAL: 4	302

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	144
Binary: misplaced binary	101
Too many newsgroups	93
Bad path (news.giganews.com.POSTED)	36
Cancels abusifs	6
Too many newsgroups (meow)	5
Cancel of <105u9vg$c69$11@news.chmurka.net> without Cancel-Key	1
HTML Multipart	1
Cancel of non-existing ID <mef72dFb2f7U1@mid.individual.net>	1
No Cancel-Key matches Cancel-Lock. target=<105tnfs$8br$1@rasp.pasdenom.info>	1
Cancel of <zpzq92b8rrc7$.1jdton1jp4n32.dlg@40tude.net> without Cancel-Key	1
Cancel of <ful53rnoykxz$.u8iennv43ub7$.dlg@40tude.net> without Cancel-Key	1
TOTAL: 12	391

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.nntp4.net	891	5	798	0	1	0%	23:24:13
2	news.tnetconsulting.net	2322	4	1507	7	0	0%	23:52:43
3	news.samoylyk.net	1542	3	1271	2	10	0%	23:52:48
4	news.gegeweb.org	1192	1	1160	0	0	0%	23:47:31
5	news.pasdenom.info	1277	0	1170	25	0	0%	23:51:49
6	news.aioe.org	0	0	0	0	1339	0%	24:00:00
7	nntp.terraraq.uk	935	0	858	5	0	0%	23:10:35
8	i2pn.org	0	0	0	0	1518	0%	23:57:47
TOTAL: 8		8159	13	6764	39	2868	0%	189:57:26

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.tnetconsulting.net	13.9 KB	11.7 KB	25.6 KB	2.3 KB	23:52:43
2	news.samoylyk.net	9.3 KB	2.3 KB	11.6 KB	2.3 KB	23:52:48
3	news.nntp4.net	7.2 KB	0.0 KB	7.2 KB	1.4 KB	23:24:13
4	news.gegeweb.org	3.2 KB	0.0 KB	3.2 KB	3.2 KB	23:47:31
5	news.pasdenom.info	0.0 KB	26.1 KB	26.1 KB	1.0 KB	23:51:49
6	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
7	nntp.terraraq.uk	0.0 KB	6.5 KB	6.5 KB	1.3 KB	23:10:35
8	i2pn.org	0.0 KB	0.0 KB	0.0 KB	0	23:57:47
TOTAL: 8		33.7 KB	46.6 KB	80.3 KB	1.5 KB	189:57:26

NNRP readership statistics:

System		Conn	Elapsed
1	azpdegjz8e93.stretchoid.com	1	00:00:01
2	205.210.31.4	5	00:05:15
3	45.149.216.162.bc.googleusercontent.com	2	00:00:03
4	147.185.132.147	1	00:00:02
5	172-236-228-224.ip.linodeusercontent.com	2	00:00:00
6	147.185.133.236	1	00:00:08
7	212.102.40.218	3	00:00:00
8	2603:6010:6800:36:1a03:73ff:fe28:be0f	8	00:00:06
9	206.168.34.120	1	00:00:33
10	102.149.216.162.bc.googleusercontent.com	1	00:00:00
11	mail.gegeweb.eu	289	00:01:41
12	lstlambert-656-1-48-236.w92-154.abo.wanadoo.fr	1	00:00:10
13	147.185.132.103	1	00:00:07
14	206.168.34.66	1	00:00:49
15	127.211.203.35.bc.googleusercontent.com	2	00:00:02
16	2a06:4882:1000::5	1	00:00:02
17	172-236-228-39.ip.linodeusercontent.com	1	00:00:01
18	194.180.48.168	1	00:00:00
19	205.210.31.160	3	00:03:13
20	194.165.16.167	1	00:00:00
TOTAL: 20		326	00:12:22

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.gegeweb.eu	289	00:01:41
2	?	9	00:00:09
3	*.w92-154.abo.wanadoo.fr	1	00:00:10
4	*.stretchoid.com	1	00:00:01
5	*.211.203.35.bc.googleusercontent.com	2	00:00:02
6	*.149.216.162.bc.googleusercontent.com	3	00:00:04
7	unresolved	18	00:10:10
8	*.ip.linodeusercontent.com	3	00:00:02
TOTAL: 8		326	00:12:22

NNRP auth users [Top 20]:

User	Conn
mailman	289
TOTAL: 1	289

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
205.210.31.4	0.130	0.102	0.000	00:05:15
205.210.31.160	0.063	0.061	0.000	00:03:13
mail.gegeweb.eu	4.776	4.546	1.901	00:01:41
206.168.34.66	0.022	0.010	0.000	00:00:49
206.168.34.120	0.025	0.003	0.000	00:00:33
lstlambert-656-1-48-236.w92-154.abo.wanadoo.fr	0.018	0.005	0.000	00:00:10
147.185.133.236	0.059	0.020	0.000	00:00:08
147.185.132.103	0.019	0.027	0.000	00:00:07
2603:6010:6800:36:1a03:73ff:fe28:be0f	0.179	0.137	0.000	00:00:06
45.149.216.162.bc.googleusercontent.com	0.122	0.038	0.000	00:00:03
2a06:4882:1000::5	0.019	0.005	0.000	00:00:02
147.185.132.147	0.015	0.008	0.000	00:00:02
127.211.203.35.bc.googleusercontent.com	0.150	0.086	0.000	00:00:02
azpdegjz8e93.stretchoid.com	0.019	0.005	0.000	00:00:01
172-236-228-39.ip.linodeusercontent.com	0.056	0.020	0.000	00:00:01
172-236-228-224.ip.linodeusercontent.com	0.077	0.024	0.000	00:00:00
102.149.216.162.bc.googleusercontent.com	0.061	0.017	0.000	00:00:00
212.102.40.218	0.046	0.038	0.000	00:00:00
194.165.16.167	0.042	0.008	0.000	00:00:00
194.180.48.168	0.030	0.022	0.000	00:00:00
TOTAL: 20	5.928	5.182	1.901	00:12:22

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
172-236-228-224.ip.linodeusercontent.com	5
147.185.133.236	4
102.149.216.162.bc.googleusercontent.com	4
45.149.216.162.bc.googleusercontent.com	4
127.211.203.35.bc.googleusercontent.com	4
205.210.31.4	2
205.210.31.160	1
194.180.48.168	1
194.165.16.167	1
azpdegjz8e93.stretchoid.com	1
TOTAL: 10	27

NNRP unrecognized commands (by command):

Command	Count
Host: 45.13.105.36:563	5
GET / HTTP/1.1	5
Accept: /	3
#026#003	3
Accept-Encoding: gzip	3
#003	2
User-Agent: curl/7.68.0	2
User-Agent: Hello from Palo Alto Network...	2
MGLNDD_45.13.105.36_119	1
User-Agent: Mozilla/5.0 (Macintosh; Inte...	1
TOTAL: 10	27

NNRP client timeouts [Top 20]:

System	Conn	Peer
205.210.31.4	3	5
205.210.31.160	2	3
194.165.16.167	1	1
127.211.203.35.bc.googleusercontent.com	1	2
194.180.48.168	1	1
45.149.216.162.bc.googleusercontent.com	1	2
TOTAL: 6	9	16

Daily Usenet report for news.niel.me

Jul 24 04:15:01 -- Jul 25 04:15:01