Daily Usenet report for news.niel.me: Dec 10 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Dec 10 10:18:20 rpi-studio innd: filter: No Cancel-Key[sha1:m3mFFIEI/BSo0L5/rNx+xs0F3Fc= sha256:/PLS5DsJ3fU1ZRK02xwZJEC804VQu9jZlH4uCvP2E9k=] matches Cancel-Lock[sha1:6gsFcUZyPDu/qt35FBvN6o3kR/s= sha256:eAB9xHNaUqgaWm4Bw+iqfLKLpzamNqxDqsFe2JDqdNY=] target=<ul3vmr$7ru$1@rasp.pasdenom.info>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	44831	78.4%	8.8 MB	83.9%
innd	8535	14.9%	1.2 MB	11.2%
innfeed	2028	3.5%	329.1 KB	3.1%
nnrpd	1805	3.2%	198.0 KB	1.8%
controlchan	1	0.0%	0.0 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	57201	100.0%	10.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	72082	41.4%
Negative hits	57374	32.9%
Do not exist	43951	25.2%
Cache misses	758	0.4%
TOTAL: 4	174165	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.327	0.0%	241	0.000	1.357	7.000
article cancel	00:00:00.041	0.0%	241	0.000	0.170	1.000
article cleanup	00:00:00.968	0.0%	44520	0.004	0.022	0.044
article logging	00:00:03.319	0.0%	44520	0.032	0.075	0.133
article parse	00:00:12.539	0.0%	246856	0.034	0.051	0.104
article write	00:00:47.796	0.1%	38202	0.857	1.251	3.602
artlog/artcncl	00:00:00.009	0.0%	241	0.000	0.037	0.200
artlog/artcncl/perl	00:00:00.018	0.0%	241	0.000	0.075	1.000
data move	00:00:02.323	0.0%	434604	0.002	0.005	0.009
hisgrep/artcncl	00:00:00.007	0.0%	241	0.000	0.029	0.500
hisgrep/artcncl/perl	00:00:00.007	0.0%	241	0.000	0.029	0.200
hisgrep/perl	00:00:00.080	0.0%	716	0.000	0.112	1.000
hishave/artcncl	00:00:00.003	0.0%	241	0.000	0.012	0.167
hishave/artcncl/perl	00:00:00.002	0.0%	241	0.000	0.008	0.111
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:03.284	0.0%	176262	0.009	0.019	0.031
history sync	00:00:00.052	0.0%	383	0.000	0.136	1.667
history write	00:00:11.262	0.0%	44520	0.112	0.253	0.484
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:54:28.847	99.5%	376304	101.260	228.722	551.760
nntp read	00:00:10.937	0.0%	376301	0.016	0.029	0.041
overview write	00:00:26.285	0.0%	38202	0.167	0.688	47.013
perl filter	00:03:23.480	0.2%	44520	3.453	4.571	6.329
python filter	00:00:00.192	0.0%	44520	0.000	0.004	0.016
site send	00:00:10.564	0.0%	78549	0.043	0.134	0.249
TOTAL: 24:01:00.462	24:00:02.342	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.351	0.0%	38858	0.000	0.009	0.026
article prepare	00:00:00.049	0.0%	14756	0.000	0.003	0.036
article read	00:00:00.394	0.0%	2475	0.000	0.159	1.000
backlog stats	00:00:02.950	0.0%	620534	0.002	0.005	0.009
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:09.703	0.0%	420954	0.013	0.023	0.036
data write	00:00:16.345	0.0%	329860	0.038	0.050	0.068
idle	23:51:14.729	99.9%	620534	81.217	138.388	376.359
status file	00:00:00.400	0.0%	219	0.000	1.826	6.000
TOTAL: 23:52:13.856	23:51:44.921	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:28.752	53.3%	1462	21.500	60.706	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.103	0.1%	3234	0.000	0.032	0.500
TOTAL: 00:02:46.559	00:01:28.855	53.3%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	148
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	170

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news.glou.org	5	28919	20517	7376	1026	70%	24:33:45
2	news.nntp4.net	2	42409	12903	24726	4780	30%	23:52:49
3	nntp.terraraq.uk	5	21659	2308	19083	268	10%	22:54:04
4	feed-out.gegeweb.org	3	4411	1237	3174	0	28%	25:13:57
5	usenet.pasdenom.info	3	3613	346	3119	148	9%	20:45:15
6	gegeweb-out.news.tnetconsulting.net	2	4665	310	4289	66	6%	25:00:45
7	i2pn.org	1	3421	213	3204	4	6%	16:15:01
8	news.samoylyk.net	3	5935	50	5881	4	0%	32:25:05
9	2a01:e0a:21:ea80:ba27:ebff:fe3b:af7a	3	8334	7	8327	0	0%	18:50:13
10	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 10		52	123366	37891	79179	6296	30%	209:50:54

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.glou.org	166.8 MB	5.0 MB	171.8 MB	97%	8.2 KB
2	news.nntp4.net	127.7 MB	43.9 MB	171.6 MB	74%	9.9 KB
3	nntp.terraraq.uk	16.2 MB	1.7 MB	17.9 MB	90%	7.1 KB
4	feed-out.gegeweb.org	13.0 MB	0.0 KB	13.0 MB	100%	10.7 KB
5	i2pn.org	4.5 MB	4.4 KB	4.5 MB	99%	21.1 KB
6	usenet.pasdenom.info	905.0 KB	154.4 KB	1.0 MB	85%	2.1 KB
7	gegeweb-out.news.tnetconsulting.net	847.3 KB	192.1 KB	1.0 MB	81%	2.8 KB
8	news.samoylyk.net	548.5 KB	4.2 KB	552.7 KB	99%	10.2 KB
9	2a01:e0a:21:ea80:ba27:ebff:fe3b:af7a	29.4 KB	0.0 KB	29.4 KB	100%	4.2 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		330.4 MB	51.0 MB	381.3 MB	86%	8.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 10 04:15:01 - 04:59:59	1384	3.6%	0.51	11.8 MB	3.6%	4.48
Dec 10 05:00:00 - 05:59:59	1710	4.5%	0.47	15.0 MB	4.5%	4.27
Dec 10 06:00:00 - 06:59:59	1765	4.6%	0.49	14.8 MB	4.5%	4.21
Dec 10 07:00:00 - 07:59:59	1828	4.8%	0.51	15.7 MB	4.7%	4.46
Dec 10 08:00:00 - 08:59:59	2226	5.8%	0.62	20.6 MB	6.2%	5.85
Dec 10 09:00:00 - 09:59:59	1839	4.8%	0.51	15.8 MB	4.8%	4.51
Dec 10 10:00:00 - 10:59:59	2087	5.5%	0.58	17.9 MB	5.4%	5.10
Dec 10 11:00:00 - 11:59:59	1584	4.2%	0.44	13.2 MB	4.0%	3.75
Dec 10 12:00:00 - 12:59:59	1804	4.7%	0.50	15.2 MB	4.6%	4.31
Dec 10 13:00:00 - 13:59:59	1538	4.0%	0.43	13.3 MB	4.0%	3.77
Dec 10 14:00:00 - 14:59:59	1753	4.6%	0.49	15.3 MB	4.6%	4.35
Dec 10 15:00:00 - 15:59:59	1496	3.9%	0.42	14.2 MB	4.3%	4.04
Dec 10 16:00:00 - 16:59:59	1652	4.3%	0.46	13.9 MB	4.2%	3.94
Dec 10 17:00:00 - 17:59:59	1464	3.8%	0.41	11.4 MB	3.4%	3.24
Dec 10 18:00:00 - 18:59:59	1975	5.2%	0.55	18.5 MB	5.6%	5.25
Dec 10 19:00:00 - 19:59:59	1332	3.5%	0.37	12.1 MB	3.6%	3.43
Dec 10 20:00:00 - 20:59:59	1523	4.0%	0.42	12.4 MB	3.7%	3.54
Dec 10 21:00:00 - 21:59:59	1365	3.6%	0.38	11.7 MB	3.5%	3.32
Dec 10 22:00:00 - 22:59:59	1208	3.2%	0.34	10.3 MB	3.1%	2.92
Dec 10 23:00:00 - 23:59:59	1522	4.0%	0.42	12.5 MB	3.8%	3.56
Dec 11 00:00:00 - 00:59:59	1324	3.5%	0.37	11.2 MB	3.4%	3.18
Dec 11 01:00:00 - 01:59:59	1247	3.3%	0.35	12.4 MB	3.7%	3.51
Dec 11 02:00:00 - 02:59:59	1195	3.1%	0.33	10.7 MB	3.2%	3.06
Dec 11 03:00:00 - 03:59:59	955	2.5%	0.27	9.6 MB	2.9%	2.72
Dec 11 04:00:00 - 04:15:01	287	0.8%	0.32	3.0 MB	0.9%	3.36
TOTAL: 24:00:00	38063	100.0%	0.44	332.2 MB	100.0%	3.94

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	4783	30	4753
2	news.glou.org	1023	0	1023
3	nntp.terraraq.uk	269	0	269
4	usenet.pasdenom.info	144	0	144
5	gegeweb-out.news.tnetconsulting.net	59	0	59
6	news.samoylyk.net	4	0	4
7	i2pn.org	4	0	4
TOTAL: 7		6286	30	6256

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.lettres.langue.allemande	12
fr.misc.gestion	8
fr.education.superieur	4
fr.petites-annonces.informatique.materiel	3
fr.education.entraide.maths	3
TOTAL: 5	30

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	5272
EMP (phl)	193
Too many newsgroups	164
EMP (phn path)	85
Bad path (news.giganews.com.POSTED)	59
Cancel for rejected article	4
User-issued spam cancel	2
Cancels abusifs	2
Cancel of non-existing ID <379b4bd3-29cd-42a9-8642-0a65f8d04295n@googlegroups.com>	1
Cancel of non-existing ID <8ff4c256-3f81-4329-97c8-3a48623b8d4fn@googlegroups.com>	1
Cancel of non-existing ID <96142021-c743-4911-a285-6b14ffece64an@googlegroups.com>	1
Cancel of non-existing ID <8c25af3b-25b7-4af5-b217-9878147e914bn@googlegroups.com>	1
Cancel of non-existing ID <aeec7dbc-b8e8-440f-9cff-860acef07a49n@googlegroups.com>	1
Cancel of non-existing ID <50a2cf49-56e5-42be-bc2e-a44c4a477269n@googlegroups.com>	1
Cancel of non-existing ID <29c34d30-c298-4be0-9a2b-9eb288ff5589n@googlegroups.com>	1
Cancel of non-existing ID <f20042a3-5b43-4e96-81a6-9573d230729dn@googlegroups.com>	1
Cancel of non-existing ID <ecc78f73-9fac-4cec-a26e-8863c1d6f167n@googlegroups.com>	1
Cancel of non-existing ID <2bb35a2c-c543-4dd7-923b-7228f7149efcn@googlegroups.com>	1
Cancel of non-existing ID <1107ec8f-0ac4-4587-aefd-ddb728c30e7cn@googlegroups.com>	1
Cancel of non-existing ID <33433f06-692f-455b-85b0-0c3b35500418n@googlegroups.com>	1
TOTAL: 483	6256

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	nntp.terraraq.uk	44329	861	33104	298	49	1%	23:59:55
2	news.nntp4.net	35797	530	24477	0	25	1%	23:59:55
3	i2pn.org	53666	125	36380	1100	216	0%	23:59:52
4	news.tnetconsulting.net	52493	28	37611	421	81	0%	23:59:55
5	news.samoylyk.net	58994	13	37738	282	265	0%	23:59:55
6	news.pasdenom.info	47200	8	37429	16	308	0%	23:59:55
7	news.gegeweb.org	37738	4	36848	1	0	0%	23:59:55
8	news.aioe.org	0	0	0	0	7888	0%	23:59:55
TOTAL: 8		330217	1569	243587	2118	8832	0%	191:59:17

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nntp.terraraq.uk	7.4 MB	2.9 MB	10.3 MB	0.1 KB/s	9.1 KB	23:59:55
2	news.nntp4.net	4.4 MB	0.0 KB	4.4 MB	0.1 KB/s	8.4 KB	23:59:55
3	i2pn.org	644.8 KB	9.5 MB	10.1 MB	0.1 KB/s	8.5 KB	23:59:52
4	news.gegeweb.org	102.8 KB	21.4 KB	124.2 KB	0.0 KB/s	24.8 KB	23:59:55
5	news.tnetconsulting.net	91.4 KB	4.0 MB	4.1 MB	0.0 KB/s	9.3 KB	23:59:55
6	news.pasdenom.info	80.4 KB	139.5 KB	219.9 KB	0.0 KB/s	9.2 KB	23:59:55
7	news.samoylyk.net	38.8 KB	2.7 MB	2.7 MB	0.0 KB/s	9.4 KB	23:59:55
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:55
TOTAL: 8		12.7 MB	19.2 MB	32.0 MB	0.0 KB/s	8.9 KB	191:59:17

NNRP readership statistics:

System		Conn	Elapsed
1	prod-boron-us-central-87.li.binaryedge.ninja	1	00:00:02
2	45.227.254.49	1	00:00:00
3	104.152.52.212	1	00:00:10
4	152.32.159.79	3	00:00:12
5	192.241.223.60	1	00:00:09
6	45.227.254.8	1	00:00:00
7	71.6.134.233	3	00:00:01
8	einstein.census.shodan.io	1	00:00:01
9	111.149.216.162.bc.googleusercontent.com	1	00:00:00
10	mail.gegeweb.eu	288	00:01:54
11	lstlambert-656-1-48-236.w92-154.abo.wanadoo.fr	1	00:00:10
12	162.243.140.52	1	00:00:01
TOTAL: 12		303	00:02:46

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.gegeweb.eu	288	00:01:54
2	*.w92-154.abo.wanadoo.fr	1	00:00:10
3	unresolved	11	00:00:36
4	*.census.shodan.io	1	00:00:01
5	*.li.binaryedge.ninja	1	00:00:02
6	*.149.216.162.bc.googleusercontent.com	1	00:00:00
TOTAL: 6		303	00:02:46

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.488	4.155	1.543	00:01:54
152.32.159.79	0.070	0.058	0.000	00:00:12
lstlambert-656-1-48-236.w92-154.abo.wanadoo.fr	0.020	0.028	0.000	00:00:10
104.152.52.212	0.009	0.014	0.000	00:00:10
192.241.223.60	0.015	0.008	0.000	00:00:09
prod-boron-us-central-87.li.binaryedge.ninja	0.025	0.025	0.000	00:00:02
71.6.134.233	0.064	0.062	0.000	00:00:01
162.243.140.52	0.026	0.018	0.000	00:00:01
einstein.census.shodan.io	0.034	0.017	0.000	00:00:01
111.149.216.162.bc.googleusercontent.com	0.031	0.016	0.000	00:00:00
45.227.254.8	0.025	0.033	0.000	00:00:00
45.227.254.49	0.038	0.019	0.000	00:00:00
TOTAL: 12	4.845	4.453	1.543	00:02:46

NNRP unrecognized commands (by host):

System	Conn
71.6.134.233	7
111.149.216.162.bc.googleusercontent.com	3
152.32.159.79	2
162.243.140.52	1
45.227.254.8	1
TOTAL: 5	14

NNRP unrecognized commands (by command):

Command	Count
Host: 45.13.105.36:119	2
GET / HTTP/1.1	2
À#024	1
#026#003#001#001#027#001	1
#003	1
#026#003#001	1
User-Agent: Expanse, a Palo Alto Network...	1
User-Agent: Mozilla/5.0 (Windows NT 10.0...	1
MGLNDD_45.13.105.36_119	1
Accept-Encoding: gzip	1
Accept: /	1
#023#003#023#001#023#002#001	1
TOTAL: 12	14

NNRP client timeouts [Top 20]:

System	Conn	Peer
45.227.254.8	1	1
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

Dec 10 04:15:01 -- Dec 11 04:15:01