Daily Usenet report: Mar 4 04:15:01

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	25852	52.6%	5.0 MB	62.7%
innd	20065	40.9%	2.5 MB	30.9%
innfeed	3182	6.5%	525.7 KB	6.4%
nocem	5	0.0%	0.5 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 5	49105	100.0%	8.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	144553	51.3%
Negative hits	111994	39.7%
Do not exist	25385	9.0%
Cache misses	36	0.0%
TOTAL: 4	281968	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.057	0.0%	63	0.000	0.905	21.000
article cleanup	00:00:00.143	0.0%	25488	0.000	0.006	0.026
article logging	00:00:00.743	0.0%	25809	0.000	0.029	0.063
article parse	00:00:00.943	0.0%	78636	0.000	0.012	0.030
article write	00:00:03.520	0.0%	15642	0.059	0.225	2.469
artlog/artcncl	00:00:00.000	0.0%	62	0.000	0.000	0.000
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:00:00.153	0.0%	332182	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.000	0.0%	62	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	63	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:01.131	0.0%	282581	0.000	0.004	0.009
history sync	00:00:00.016	0.0%	375	0.000	0.043	1.333
history write	00:00:01.799	0.0%	25488	0.015	0.071	0.830
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	23:59:31.623	99.9%	329473	56.710	262.151	798.803
nntp read	00:00:03.331	0.0%	328750	0.005	0.010	0.019
overview write	00:00:03.053	0.0%	15642	0.007	0.195	4.538
perl filter	00:00:26.077	0.0%	25486	0.545	1.023	4.747
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:01.509	0.0%	31332	0.000	0.048	0.123
TOTAL: 24:00:57.157	24:00:14.098	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.029	0.0%	15877	0.000	0.002	0.019
article prepare	00:00:00.008	0.0%	129028	0.000	0.000	0.001
article read	00:00:00.814	0.0%	15386	0.000	0.053	0.400
backlog stats	00:00:00.435	0.0%	374894	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.179	0.0%	253497	0.003	0.009	0.016
data write	00:00:02.058	0.0%	239282	0.003	0.009	0.017
idle	23:55:02.607	100.0%	374894	121.972	229.672	562.152
status file	00:00:00.174	0.0%	318	0.000	0.547	3.000
TOTAL: 23:55:23.783	23:55:08.304	100.0%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	5
flushlogs	2
go	2
logmode	2
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	i2pn-out.news.weretis.net	5	28252	5665	22450	137	20%	48:07:43
2	news-out.aioe.org	3	12625	3498	9070	57	27%	24:08:43
3	feeder.eternal-september.org	31	6114	2974	2687	453	48%	32:37:07
4	usenet.goja.nl.eu.org	11	13191	1408	11720	63	10%	21:20:19
5	paganini.bofh.team	2	24998	1055	15212	8731	4%	23:53:07
6	news.swapon.de	8	1295	336	958	1	25%	22:19:34
7	news.nntp4.net	1	16377	184	16031	162	1%	23:59:54
8	news-out.neodome.net	1	14778	166	14566	46	1%	23:59:56
9	i2pn2.org	48	363	73	290	0	20%	19:45:07
10	i2pn-out.feed.uzoreto.com	1903	16344	68	16090	186	0%	70:57:16
11	2a01:4f8:c17:a9e2::8e	0	786	38	746	2	4%	02:15:12
12	news.niel.me	1	3746	37	3707	2	0%	23:59:57
13	news.samoylyk.net	1	12361	26	12333	2	0%	23:59:53
14	dodin.fr.nf	16	862	17	845	0	1%	21:13:35
15	news.bbs.nz	2	12651	6	12645	0	0%	24:50:15
16	novabbs.org	7	4379	3	4376	0	0%	23:47:40
17	localhost	27	0	0	0	0	0%	00:00:00
18	kf5zxw.com	1	0	0	0	0	0%	00:10:00
TOTAL: 18		2068	169122	15554	143726	9842	9%	431:25:18

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	i2pn-out.news.weretis.net	28.1 MB	0.0 KB	558.2 KB	28.6 MB	98%	5.1 KB
2	feeder.eternal-september.org	10.8 MB	0.0 KB	6.0 MB	16.7 MB	64%	5.0 KB
3	news-out.aioe.org	8.4 MB	0.0 KB	191.2 KB	8.6 MB	97%	2.5 KB
4	usenet.goja.nl.eu.org	3.5 MB	0.0 KB	166.8 KB	3.7 MB	95%	2.6 KB
5	paganini.bofh.team	3.2 MB	0.0 KB	278.0 MB	281.1 MB	1%	29.4 KB
6	news.swapon.de	966.6 KB	0.0 KB	0.8 KB	967.4 KB	99%	2.9 KB
7	news.nntp4.net	747.4 KB	0.0 KB	8.5 MB	9.2 MB	7%	27.3 KB
8	news-out.neodome.net	605.6 KB	2.2 KB	366.4 KB	974.2 KB	62%	4.6 KB
9	i2pn-out.feed.uzoreto.com	174.6 KB	0.0 KB	591.7 KB	766.3 KB	22%	3.0 KB
10	i2pn2.org	162.6 KB	0.0 KB	0.0 KB	162.6 KB	100%	2.2 KB
11	2a01:4f8:c17:a9e2::8e	94.8 KB	0.0 KB	3.9 KB	98.7 KB	96%	2.5 KB
12	news.samoylyk.net	83.5 KB	4.4 KB	4.4 KB	92.4 KB	90%	3.3 KB
13	news.niel.me	81.5 KB	0.0 KB	10.8 KB	92.3 KB	88%	2.4 KB
14	novabbs.org	30.3 KB	0.0 KB	0.0 KB	30.3 KB	100%	10.1 KB
15	dodin.fr.nf	27.7 KB	0.0 KB	0.0 KB	27.7 KB	100%	1.6 KB
16	news.bbs.nz	8.6 KB	0.0 KB	0.0 KB	8.6 KB	100%	1.4 KB
17	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
18	kf5zxw.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		56.8 MB	6.6 KB	294.3 MB	351.1 MB	16%	14.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 4 04:15:01 - 04:59:59	271	1.7%	0.10	2.4 MB	4.1%	0.89
Mar 4 05:00:00 - 05:59:59	293	1.9%	0.08	874.5 KB	1.5%	0.24
Mar 4 06:00:00 - 06:59:59	318	2.0%	0.09	1.1 MB	2.0%	0.33
Mar 4 07:00:00 - 07:59:59	518	3.3%	0.14	2.2 MB	3.8%	0.62
Mar 4 08:00:00 - 08:59:59	632	4.0%	0.18	1.6 MB	2.9%	0.47
Mar 4 09:00:00 - 09:59:59	736	4.7%	0.20	2.2 MB	3.9%	0.64
Mar 4 10:00:00 - 10:59:59	845	5.4%	0.23	4.0 MB	7.0%	1.14
Mar 4 11:00:00 - 11:59:59	723	4.6%	0.20	2.4 MB	4.2%	0.68
Mar 4 12:00:00 - 12:59:59	828	5.3%	0.23	2.6 MB	4.5%	0.74
Mar 4 13:00:00 - 13:59:59	726	4.6%	0.20	2.7 MB	4.8%	0.78
Mar 4 14:00:00 - 14:59:59	953	6.1%	0.26	2.8 MB	4.8%	0.78
Mar 4 15:00:00 - 15:59:59	1072	6.9%	0.30	3.6 MB	6.3%	1.03
Mar 4 16:00:00 - 16:59:59	900	5.8%	0.25	2.9 MB	5.1%	0.83
Mar 4 17:00:00 - 17:59:59	937	6.0%	0.26	3.6 MB	6.3%	1.02
Mar 4 18:00:00 - 18:59:59	782	5.0%	0.22	3.4 MB	5.9%	0.96
Mar 4 19:00:00 - 19:59:59	680	4.3%	0.19	2.1 MB	3.7%	0.61
Mar 4 20:00:00 - 20:59:59	758	4.8%	0.21	3.1 MB	5.4%	0.87
Mar 4 21:00:00 - 21:59:59	716	4.6%	0.20	2.0 MB	3.5%	0.57
Mar 4 22:00:00 - 22:59:59	736	4.7%	0.20	2.5 MB	4.3%	0.70
Mar 4 23:00:00 - 23:59:59	567	3.6%	0.16	1.9 MB	3.4%	0.55
Mar 5 00:00:00 - 00:59:59	524	3.3%	0.15	1.8 MB	3.2%	0.53
Mar 5 01:00:00 - 01:59:59	370	2.4%	0.10	1.3 MB	2.3%	0.38
Mar 5 02:00:00 - 02:59:59	305	1.9%	0.08	1.2 MB	2.0%	0.33
Mar 5 03:00:00 - 03:59:59	376	2.4%	0.10	2.7 MB	4.7%	0.77
Mar 5 04:00:00 - 04:15:01	79	0.5%	0.09	252.3 KB	0.4%	0.28
TOTAL: 24:00:00	15645	100.0%	0.18	57.3 MB	100.0%	0.68

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	paganini.bofh.team	8693	4	0	8689
2	feeder.eternal-september.org	474	11	0	463
3	i2pn-out.news.weretis.net	227	0	0	227
4	i2pn-out.feed.uzoreto.com	187	0	0	187
5	news.swapon.de	178	0	0	178
6	news.nntp4.net	162	2	0	160
7	news-out.aioe.org	79	21	0	58
8	usenet.goja.nl.eu.org	78	0	0	78
9	news-out.neodome.net	61	0	0	61
10	news.samoylyk.net	3	0	2	1
11	news.niel.me	2	0	0	2
12	2a01:4f8:c17:a9e2::8e	1	0	0	1
TOTAL: 12		10145	38	2	10105

Unwanted newsgroups [Top 20]:

Newsgroup	Count
att.jobs	10
aus.jobs	8
newsreader.test	5
trollnet.cinema	4
alt.startrek	3
alt.music.pink-floyd.publius	2
alt.testing	2
lucky.freebsd.jobs	1
alt.penthouse.sex.femdom	1
nntp4.test	1
ga.jobs	1
TOTAL: 11	38

INND Perl filter [Top 20]:

Reason	Count
Binary: misplaced par2	7797
Binary: misplaced rar	641
EMP (md5)	637
Too many newsgroups	452
Poison newsgroup	135
Binary: misplaced PAR2	72
User-issued spam cancel	19
User-issued cancel	16
HTML Multipart	7
Scoring filter (8)	2
U2 violation - invalid distribution	2
Binary: misplaced nzb	1
TOTAL: 12	9781

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	3	0	3	3
TOTAL: 1	3	0	3	3

INND no permission servers:

System	Conn
192.241.221.137	1
2a01:4f8:c17:a9e2::8e	1
167.94.138.61	1
TOTAL: 3	3

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	i2pn-out.feed.uzoreto.com	1
	TOTAL: 1	1
Including strange strings
	news.swapon.de	177
	i2pn-out.news.weretis.net	93
	news-out.aioe.org	22
	usenet.goja.nl.eu.org	15
	feeder.eternal-september.org	10
	paganini.bofh.team	2
	news.samoylyk.net	1
	news-out.neodome.net	1
	i2pn-out.feed.uzoreto.com	1
	TOTAL: 9	322
No colon-space in header
	i2pn-out.feed.uzoreto.com	2
	TOTAL: 1	2
TOTAL: 3		325

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn2	15443	13949	139	1128	0	90%	23:59:48
2	novabbs	19913	9867	4461	1095	23	49%	23:59:48
3	aioe	11929	1510	10135	27	2	12%	23:59:48
4	news.samoylyk.net	16505	1254	14212	7	0	7%	23:59:48
5	news.bbs.nz	30111	856	14685	94	136	2%	23:59:47
6	news.niel.me	4913	785	3932	79	47	15%	23:59:48
7	neodome	17453	179	15164	0	0	1%	23:59:38
8	paganini.bofh.team	16574	99	14485	0	0	0%	23:59:36
9	weretis.net	16728	91	8733	950	16	0%	23:59:46
10	news.uzoreto.com	16339	89	14023	0	0	0%	23:59:48
11	eternal-september	14687	81	12474	4	8	0%	23:59:48
12	usenet.goja.nl.eu.org	16252	41	13938	54	29	0%	23:59:48
13	news.nntp4.net	17629	37	15417	2	0	0%	23:59:48
14	dodin.fr.nf	1426	34	937	1	0	2%	21:48:43
15	swapon.de	1253	4	1087	0	0	0%	23:41:29
16	kf5zxw.com	0	0	0	0	15643	0%	23:57:42
TOTAL: 16		217155	28876	143822	3441	15904	13%	381:24:53

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	i2pn2	38.6 MB	4.3 MB	42.9 MB	0.5 KB/s	2.9 KB	23:59:48
2	novabbs	38.2 MB	5.5 MB	43.6 MB	0.5 KB/s	4.1 KB	23:59:48
3	aioe	5.5 MB	87.2 KB	5.6 MB	0.1 KB/s	3.7 KB	23:59:48
4	news.bbs.nz	3.9 MB	609.9 KB	4.5 MB	0.1 KB/s	4.9 KB	23:59:47
5	news.samoylyk.net	3.8 MB	46.2 KB	3.8 MB	0.0 KB/s	3.1 KB	23:59:48
6	news.niel.me	3.7 MB	371.5 KB	4.1 MB	0.0 KB/s	4.8 KB	23:59:48
7	neodome	502.9 KB	0.0 KB	502.9 KB	0.0 KB/s	2.8 KB	23:59:38
8	paganini.bofh.team	255.6 KB	0.0 KB	255.6 KB	0.0 KB/s	2.6 KB	23:59:36
9	news.uzoreto.com	238.5 KB	0.0 KB	238.5 KB	0.0 KB/s	2.7 KB	23:59:48
10	weretis.net	197.2 KB	1.6 MB	1.8 MB	0.0 KB/s	1.8 KB	23:59:46
11	eternal-september	178.8 KB	30.5 KB	209.4 KB	0.0 KB/s	2.5 KB	23:59:48
12	news.nntp4.net	177.5 KB	28.4 KB	205.8 KB	0.0 KB/s	5.3 KB	23:59:48
13	usenet.goja.nl.eu.org	128.3 KB	105.2 KB	233.5 KB	0.0 KB/s	2.5 KB	23:59:48
14	dodin.fr.nf	125.5 KB	2.4 KB	127.9 KB	0.0 KB/s	3.7 KB	21:48:43
15	swapon.de	10.9 KB	0.0 KB	10.9 KB	0.0 KB/s	2.7 KB	23:41:29
16	kf5zxw.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:42
TOTAL: 16		95.5 MB	12.6 MB	108.1 MB	0.1 KB/s	3.4 KB	381:24:53

Daily Usenet report

Mar 4 04:15:01 -- Mar 5 04:15:01