Daily Usenet report for news.niel.me: Oct 19 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Oct 19 11:04:26 rpi-studio innd: filter: No Cancel-Key[sha1:rYPXbxj1LYf0mtJpZiXplg/oxbk=] matches Cancel-Lock[sha1:qVo8uLIKgGDpHYXTnXsISREB7Xo=] target=<634fbd42$0$25801$426a34cc@news.free.fr>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3802	40.1%	715.6 KB	45.1%
innd	2499	26.4%	407.0 KB	25.7%
innfeed	1701	17.9%	314.6 KB	19.8%
nnrpd	1474	15.6%	147.9 KB	9.3%
nocem	1	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	9478	100.0%	1.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	24152	64.9%
Negative hits	9316	25.0%
Do not exist	3733	10.0%
Cache misses	2	0.0%
TOTAL: 4	37203	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.046	0.0%	23	0.500	2.000	10.000
article cancel	00:00:00.001	0.0%	23	0.000	0.043	0.500
article cleanup	00:00:00.085	0.0%	3747	0.000	0.023	0.125
article logging	00:00:00.287	0.0%	3756	0.000	0.076	0.222
article parse	00:00:00.647	0.0%	7673	0.000	0.084	0.308
article write	00:00:02.939	0.0%	3307	0.182	0.889	19.667
artlog/artcncl	00:00:00.001	0.0%	23	0.000	0.043	0.500
artlog/artcncl/perl	00:00:00.000	0.0%	23	0.000	0.000	0.000
data move	00:00:00.104	0.0%	40582	0.000	0.003	0.012
hisgrep/artcncl	00:00:00.000	0.0%	23	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	23	0.000	0.000	0.000
hisgrep/perl	00:00:00.000	0.0%	25	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	23	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	23	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.478	0.0%	37307	0.000	0.013	0.040
history sync	00:00:00.042	0.0%	404	0.000	0.104	1.000
history write	00:00:00.801	0.0%	3747	0.000	0.214	1.622
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:31.069	100.0%	41039	967.321	2106.072	6977.116
nntp read	00:00:01.184	0.0%	40814	0.000	0.029	0.057
overview write	00:00:02.949	0.0%	3307	0.154	0.892	19.909
perl filter	00:00:16.769	0.0%	3747	3.229	4.475	9.667
python filter	00:00:00.009	0.0%	3747	0.000	0.002	0.091
site send	00:00:01.104	0.0%	6675	0.000	0.165	0.500
TOTAL: 24:01:10.389	24:00:58.515	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.031	0.0%	3300	0.000	0.009	0.100
article prepare	00:00:00.001	0.0%	448	0.000	0.002	0.125
article read	00:00:00.016	0.0%	88	0.000	0.182	7.000
backlog stats	00:00:00.111	0.0%	52876	0.000	0.002	0.011
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.646	0.0%	31026	0.000	0.021	0.069
data write	00:00:01.775	0.0%	27772	0.011	0.064	0.109
idle	23:51:12.156	100.0%	52876	819.731	1624.029	4181.778
status file	00:00:00.462	0.0%	172	1.000	2.686	9.000
TOTAL: 23:51:18.507	23:51:15.198	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:46.711	43.8%	1449	11.400	32.237	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.135	0.1%	3182	0.000	0.042	0.500
TOTAL: 00:01:46.728	00:00:46.846	43.9%	-	-	-	-

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-out.aioe.org	2	3515	1273	2199	43	36%	25:00:53
2	i2pn.org	1	3688	966	2523	199	26%	25:36:04
3	news.glou.org	1	3149	653	2415	81	20%	23:59:58
4	feed-out.gegeweb.org	2	2189	256	1933	0	11%	23:58:59
5	news.nntp4.net	2	5118	127	4851	140	2%	32:10:42
6	usenet.pasdenom.info	1	658	51	607	0	7%	07:09:00
7	nntp.terraraq.uk	2	3333	42	3282	9	1%	23:59:50
8	gegeweb-out.news.tnetconsulting.net	2	3478	24	3451	3	0%	23:59:42
9	news.samoylyk.net	1	3507	16	3491	0	0%	23:59:51
10	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 10		39	28635	3408	24752	475	11%	209:54:59

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.glou.org	4.3 MB	498.1 KB	4.8 MB	89%	6.6 KB
2	news-out.aioe.org	4.1 MB	106.2 KB	4.2 MB	97%	3.3 KB
3	i2pn.org	3.1 MB	437.0 KB	3.6 MB	88%	3.1 KB
4	news.nntp4.net	2.8 MB	1.4 MB	4.3 MB	66%	16.4 KB
5	news.samoylyk.net	1.2 MB	0.0 KB	1.2 MB	100%	77.9 KB
6	feed-out.gegeweb.org	649.4 KB	0.0 KB	649.4 KB	100%	2.5 KB
7	gegeweb-out.news.tnetconsulting.net	141.8 KB	6.9 KB	148.7 KB	95%	5.5 KB
8	nntp.terraraq.uk	122.8 KB	16.7 KB	139.5 KB	88%	2.7 KB
9	usenet.pasdenom.info	119.6 KB	0.0 KB	119.6 KB	100%	2.3 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		16.6 MB	2.5 MB	19.0 MB	87%	5.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 19 04:15:01 - 04:59:59	55	1.7%	0.02	299.9 KB	2.2%	0.11
Oct 19 05:00:00 - 05:59:59	113	3.4%	0.03	614.0 KB	4.5%	0.17
Oct 19 06:00:00 - 06:59:59	84	2.5%	0.02	843.1 KB	6.2%	0.23
Oct 19 07:00:00 - 07:59:59	93	2.8%	0.03	606.1 KB	4.5%	0.17
Oct 19 08:00:00 - 08:59:59	128	3.9%	0.04	1.1 MB	7.9%	0.30
Oct 19 09:00:00 - 09:59:59	88	2.7%	0.02	347.4 KB	2.6%	0.10
Oct 19 10:00:00 - 10:59:59	109	3.3%	0.03	565.4 KB	4.2%	0.16
Oct 19 11:00:00 - 11:59:59	146	4.4%	0.04	463.1 KB	3.4%	0.13
Oct 19 12:00:00 - 12:59:59	126	3.8%	0.04	367.6 KB	2.7%	0.10
Oct 19 13:00:00 - 13:59:59	133	4.0%	0.04	376.5 KB	2.8%	0.10
Oct 19 14:00:00 - 14:59:59	166	5.0%	0.05	517.5 KB	3.8%	0.14
Oct 19 15:00:00 - 15:59:59	167	5.1%	0.05	610.4 KB	4.5%	0.17
Oct 19 16:00:00 - 16:59:59	140	4.2%	0.04	470.4 KB	3.5%	0.13
Oct 19 17:00:00 - 17:59:59	218	6.6%	0.06	744.0 KB	5.5%	0.21
Oct 19 18:00:00 - 18:59:59	191	5.8%	0.05	614.8 KB	4.5%	0.17
Oct 19 19:00:00 - 19:59:59	162	4.9%	0.04	489.3 KB	3.6%	0.14
Oct 19 20:00:00 - 20:59:59	141	4.3%	0.04	544.8 KB	4.0%	0.15
Oct 19 21:00:00 - 21:59:59	183	5.5%	0.05	469.3 KB	3.4%	0.13
Oct 19 22:00:00 - 22:59:59	171	5.2%	0.05	471.0 KB	3.5%	0.13
Oct 19 23:00:00 - 23:59:59	177	5.4%	0.05	716.9 KB	5.3%	0.20
Oct 20 00:00:00 - 00:59:59	137	4.1%	0.04	536.6 KB	3.9%	0.15
Oct 20 01:00:00 - 01:59:59	112	3.4%	0.03	508.9 KB	3.7%	0.14
Oct 20 02:00:00 - 02:59:59	141	4.3%	0.04	684.6 KB	5.0%	0.19
Oct 20 03:00:00 - 03:59:59	102	3.1%	0.03	529.2 KB	3.9%	0.15
Oct 20 04:00:00 - 04:15:01	23	0.7%	0.03	145.9 KB	1.1%	0.16
TOTAL: 24:00:00	3306	100.0%	0.04	13.3 MB	100.0%	0.16

Sites sending bad articles:

Server		Total	Group	Other
1	i2pn.org	187	0	187
2	news.nntp4.net	113	0	113
3	news.glou.org	81	0	81
4	news-out.aioe.org	50	8	42
5	usenet.pasdenom.info	12	0	12
6	nntp.terraraq.uk	5	0	5
7	gegeweb-out.news.tnetconsulting.net	2	1	1
TOTAL: 7		450	9	441

Unwanted newsgroups [Top 20]:

Newsgroup	Count
news.test	8
fr.petites-annonces.informatique.materiel	1
TOTAL: 2	9

INND Perl filter [Top 20]:

Reason	Count
EMP (phl)	231
Bad path (news.giganews.com.POSTED)	64
EMP (phn nph)	44
Too many newsgroups	31
EMP (md5)	27
EMP (phn path)	22
HTML Multipart	7
Cancels abusifs	2
Too many newsgroups (meow)	2
No Cancel-Key matches Cancel-Lock. target=<634fbd42$0$25801$426a34cc@news.free.fr>	1
Cancel of non-existing ID <63503717$0$22253$426a74cc@news.free.fr>	1
TOTAL: 11	432

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news-out.aioe.org	8
	news.nntp4.net	1
	TOTAL: 2	9
TOTAL: 1		9

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	4148	47	2345	2	3	1%	23:59:56
2	news.nntp4.net	3710	25	3178	1	0	0%	23:59:56
3	news.tnetconsulting.net	5110	9	3293	3	4	0%	23:58:03
4	news.aioe.org	2000	4	1553	1	0	0%	23:57:17
5	nntp.terraraq.uk	2657	3	2169	10	0	0%	23:59:47
6	news.gegeweb.org	3116	3	3045	3	0	0%	23:59:15
7	news.pasdenom.info	3280	0	2837	0	0	0%	23:59:56
8	news.samoylyk.net	3979	0	3297	2	0	0%	23:59:56
TOTAL: 8		28000	91	21717	22	7	0%	191:54:06

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.nntp4.net	1.0 MB	47.6 KB	1.1 MB	41.9 KB	23:59:56
2	i2pn.org	154.4 KB	2.7 KB	157.1 KB	3.2 KB	23:59:56
3	news.gegeweb.org	53.0 KB	22.1 KB	75.1 KB	12.5 KB	23:59:15
4	news.tnetconsulting.net	49.6 KB	16.2 KB	65.8 KB	5.5 KB	23:58:03
5	news.aioe.org	10.9 KB	1.9 KB	12.8 KB	2.6 KB	23:57:17
6	nntp.terraraq.uk	7.8 KB	734.0 KB	741.8 KB	57.1 KB	23:59:47
7	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:59:56
8	news.samoylyk.net	0.0 KB	3.8 KB	3.8 KB	1.9 KB	23:59:56
TOTAL: 8		1.3 MB	828.3 KB	2.1 MB	19.0 KB	191:54:06

NNRP readership statistics:

System		Conn	Elapsed
1	lstlambert-656-1-48-236.w92-154.abo.wanadoo.fr	1	00:00:10
2	192.241.213.124	1	00:00:09
3	89.248.165.162	1	00:00:01
4	mail.gegeweb.eu	288	00:01:19
5	192.241.195.130	1	00:00:01
6	battery.census.shodan.io	1	00:00:01
7	89.248.163.190	1	00:00:01
TOTAL: 7		294	00:01:46

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.census.shodan.io	1	00:00:01
2	*.gegeweb.eu	288	00:01:19
3	*.w92-154.abo.wanadoo.fr	1	00:00:10
4	unresolved	4	00:00:14
TOTAL: 4		294	00:01: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	8.022	8.222	2.442	00:01:19
lstlambert-656-1-48-236.w92-154.abo.wanadoo.fr	0.028	0.028	0.000	00:00:10
192.241.213.124	0.022	0.033	0.000	00:00:09
89.248.163.190	0.019	0.038	0.000	00:00:01
89.248.165.162	0.022	0.032	0.000	00:00:01
battery.census.shodan.io	0.010	0.039	0.000	00:00:01
192.241.195.130	0.024	0.024	0.000	00:00:01
TOTAL: 7	8.147	8.416	2.442	00:01:46

NNRP unrecognized commands (by host):

System	Conn
192.241.195.130	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
MGLNDD_45.13.105.36_119	1
TOTAL: 1	1

Daily Usenet report for news.niel.me

Oct 19 04:15:01 -- Oct 20 04:15:01