Daily Usenet report: Sep 16 04:15:02

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	14114	50.8%	4.1 MB	67.2%
innd	9841	35.4%	1.4 MB	22.3%
innfeed	3819	13.7%	663.0 KB	10.5%
nocem	2	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	27777	100.0%	6.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	128880	58.4%
Negative hits	77940	35.3%
Do not exist	13742	6.2%
Cache misses	185	0.1%
TOTAL: 4	220747	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.015	0.0%	28	0.000	0.536	3.000
article cleanup	00:00:00.074	0.0%	13793	0.000	0.005	0.040
article logging	00:00:00.470	0.0%	14108	0.000	0.033	0.093
article parse	00:00:00.276	0.0%	23490	0.000	0.012	0.052
article write	00:00:06.938	0.0%	12700	0.059	0.546	35.137
artlog/artcncl	00:00:00.000	0.0%	28	0.000	0.000	0.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:00:00.105	0.0%	228237	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.000	0.0%	27	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	28	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.765	0.0%	221248	0.000	0.003	0.008
history sync	00:00:00.013	0.0%	388	0.000	0.034	0.500
history write	00:00:04.602	0.0%	13788	0.000	0.334	76.038
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	23:59:38.748	99.9%	229338	198.791	376.644	1380.009
nntp read	00:00:02.121	0.0%	229071	0.004	0.009	0.019
overview write	00:00:01.112	0.0%	12700	0.000	0.088	1.277
perl filter	00:00:31.441	0.0%	13785	1.312	2.281	77.698
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:01.500	0.0%	25442	0.000	0.059	0.171
TOTAL: 24:01:03.854	24:00:28.180	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.036	0.0%	12915	0.000	0.003	0.045
article prepare	00:00:00.010	0.0%	162516	0.000	0.000	0.002
article read	00:00:00.778	0.0%	12288	0.000	0.063	2.583
backlog stats	00:00:00.604	0.0%	379494	0.000	0.002	0.004
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.467	0.0%	250439	0.003	0.010	0.017
data write	00:00:02.091	0.0%	238027	0.003	0.009	0.019
idle	23:55:28.770	100.0%	379494	102.850	226.957	721.939
status file	00:00:08.730	0.0%	363	0.000	24.050	501.750
TOTAL: 23:55:57.279	23:55:43.486	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	usenet.blueworldhosting.com	4	12911	3528	8743	640	27%	23:30:18
2	news-out.aioe.org	3	8544	3201	5312	31	37%	24:08:39
3	i2pn-out.news.weretis.net	4	26836	2435	24355	46	9%	53:45:24
4	feeder.eternal-september.org	15	6321	1602	4533	186	25%	26:29:59
5	paganini.bofh.team	1	9627	789	8827	11	8%	20:37:23
6	news.swapon.de	8	1105	293	810	2	26%	22:40:21
7	news-out.neodome.net	1	11004	256	10735	13	2%	23:59:56
8	usenet.goja.nl.eu.org	11	11717	172	11475	70	1%	23:42:29
9	news.nntp4.net	1	10021	83	9921	17	0%	23:59:56
10	news.niel.me	2	2029	73	1953	3	3%	23:59:01
11	i2pn-out.feed.uzoreto.com	1577	13541	61	13430	50	0%	71:18:51
12	nntp.alphanet.ch	16	4741	51	4690	0	1%	17:12:11
13	i2pn2.org	22	41	40	1	0	97%	04:46:00
14	dodin.fr.nf	20	535	17	518	0	3%	20:30:02
15	news.samoylyk.net	1	10733	15	10715	3	0%	23:59:53
16	news.bbs.nz	6	10105	6	10098	1	0%	26:10:37
17	news.furie.org.uk	2	5357	4	5353	0	0%	23:59:52
18	novabbs.org	21	40	1	39	0	2%	04:36:15
19	retrobbs.ddns.net	1	0	0	0	0	0%	00:10:00
20	kf5zxw.com	1	0	0	0	0	0%	00:10:00
21	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 21		1743	145208	12627	131508	1073	8%	459:47:07

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	i2pn-out.news.weretis.net	19.2 MB	0.0 KB	270.1 KB	19.4 MB	98%	8.0 KB
2	usenet.blueworldhosting.com	13.0 MB	1.8 KB	1.7 MB	14.7 MB	88%	3.6 KB
3	news-out.aioe.org	7.2 MB	0.0 KB	65.1 KB	7.3 MB	99%	2.3 KB
4	feeder.eternal-september.org	4.9 MB	0.0 KB	757.8 KB	5.6 MB	86%	3.2 KB
5	paganini.bofh.team	2.1 MB	0.0 KB	27.5 KB	2.1 MB	98%	2.7 KB
6	news-out.neodome.net	962.0 KB	0.0 KB	52.2 KB	1014.2 KB	94%	3.8 KB
7	news.swapon.de	708.1 KB	2.2 KB	2.9 KB	713.2 KB	99%	2.4 KB
8	usenet.goja.nl.eu.org	619.3 KB	0.0 KB	200.4 KB	819.7 KB	75%	3.4 KB
9	news.nntp4.net	362.0 KB	0.0 KB	53.3 KB	415.3 KB	87%	4.2 KB
10	news.niel.me	221.4 KB	0.0 KB	4.2 KB	225.6 KB	98%	3.0 KB
11	i2pn2.org	176.2 KB	0.0 KB	0.0 KB	176.2 KB	100%	4.4 KB
12	i2pn-out.feed.uzoreto.com	108.9 KB	0.0 KB	167.8 KB	276.7 KB	39%	2.5 KB
13	nntp.alphanet.ch	91.6 KB	0.0 KB	0.0 KB	91.6 KB	100%	1.8 KB
14	news.samoylyk.net	55.8 KB	5.9 KB	5.9 KB	67.6 KB	82%	3.8 KB
15	dodin.fr.nf	32.7 KB	0.0 KB	0.0 KB	32.7 KB	100%	1.9 KB
16	news.furie.org.uk	11.6 KB	0.0 KB	0.0 KB	11.6 KB	100%	2.9 KB
17	news.bbs.nz	9.1 KB	1.3 KB	1.3 KB	11.8 KB	77%	1.7 KB
18	novabbs.org	1.8 KB	0.0 KB	0.0 KB	1.8 KB	100%	1.8 KB
19	retrobbs.ddns.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
20	kf5zxw.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
21	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 21		49.7 MB	11.3 KB	3.2 MB	52.9 MB	93%	4.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 16 04:15:02 - 04:59:59	186	1.5%	0.07	747.4 KB	1.4%	0.28
Sep 16 05:00:00 - 05:59:59	326	2.6%	0.09	2.2 MB	4.3%	0.64
Sep 16 06:00:00 - 06:59:59	441	3.5%	0.12	1.2 MB	2.3%	0.34
Sep 16 07:00:00 - 07:59:59	632	5.0%	0.18	1.5 MB	2.9%	0.43
Sep 16 08:00:00 - 08:59:59	644	5.1%	0.18	1.8 MB	3.4%	0.50
Sep 16 09:00:00 - 09:59:59	643	5.1%	0.18	2.0 MB	3.9%	0.57
Sep 16 10:00:00 - 10:59:59	650	5.1%	0.18	2.1 MB	4.1%	0.60
Sep 16 11:00:00 - 11:59:59	622	4.9%	0.17	2.7 MB	5.3%	0.78
Sep 16 12:00:00 - 12:59:59	665	5.2%	0.18	3.2 MB	6.1%	0.90
Sep 16 13:00:00 - 13:59:59	769	6.1%	0.21	2.9 MB	5.6%	0.82
Sep 16 14:00:00 - 14:59:59	857	6.8%	0.24	2.7 MB	5.2%	0.76
Sep 16 15:00:00 - 15:59:59	690	5.4%	0.19	2.2 MB	4.3%	0.63
Sep 16 16:00:00 - 16:59:59	801	6.3%	0.22	3.5 MB	6.8%	1.00
Sep 16 17:00:00 - 17:59:59	653	5.1%	0.18	2.8 MB	5.4%	0.80
Sep 16 18:00:00 - 18:59:59	612	4.8%	0.17	2.1 MB	4.1%	0.60
Sep 16 19:00:00 - 19:59:59	686	5.4%	0.19	5.0 MB	9.8%	1.44
Sep 16 20:00:00 - 20:59:59	530	4.2%	0.15	1.9 MB	3.7%	0.54
Sep 16 21:00:00 - 21:59:59	495	3.9%	0.14	1.6 MB	3.0%	0.44
Sep 16 22:00:00 - 22:59:59	434	3.4%	0.12	1.9 MB	3.6%	0.54
Sep 16 23:00:00 - 23:59:59	294	2.3%	0.08	1.4 MB	2.6%	0.39
Sep 17 00:00:00 - 00:59:59	249	2.0%	0.07	2.1 MB	4.1%	0.61
Sep 17 01:00:00 - 01:59:59	276	2.2%	0.08	897.5 KB	1.7%	0.25
Sep 17 02:00:00 - 02:59:59	211	1.7%	0.06	866.1 KB	1.6%	0.24
Sep 17 03:00:00 - 03:59:59	242	1.9%	0.07	1.2 MB	2.4%	0.35
Sep 17 04:00:00 - 04:15:01	73	0.6%	0.08	1.2 MB	2.3%	1.36
TOTAL: 23:59:59	12681	100.0%	0.15	51.8 MB	100.0%	0.61

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	661	368	1	292
2	feeder.eternal-september.org	191	0	0	191
3	news.swapon.de	159	0	1	158
4	i2pn-out.news.weretis.net	108	2	0	106
5	usenet.goja.nl.eu.org	72	0	0	72
6	news-out.aioe.org	58	13	0	45
7	news-out.neodome.net	57	0	0	57
8	i2pn-out.feed.uzoreto.com	50	22	0	28
9	paganini.bofh.team	25	0	0	25
10	news.nntp4.net	17	0	0	17
11	news.niel.me	3	0	0	3
12	news.samoylyk.net	3	0	3	0
13	news.bbs.nz	1	0	1	0
TOTAL: 13		1405	405	6	994

Unwanted newsgroups [Top 20]:

Newsgroup	Count
talk.ukrainian.tg	300
talk.english.tg	51
talk.generic.ua.ws	12
att.jobs	7
alt.info-science	6
talk.english.tw	5
sybase.public.powerdesigner.general	5
aus.jobs	4
biz.marketplace	3
fr.petites-annonces.divers	3
3com.totalservice.test	2
trollnet.cinema	2
alt.culture	2
talk.from.war.diary	1
1.test	1
talk.from.diary	1
TOTAL: 16	405

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	302
EMP (md5)	263
Poison newsgroup	76
User-issued cancel	15
EMP (fsl)	10
Scoring filter (9)	3
HTML Multipart	3
MID-Bot	2
Scoring filter (8)	1
hierarchy violation - crosspost outside netscape	1
TOTAL: 10	676

NoCeM on Spool:

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

INND no permission servers:

System	Conn
109.74.204.123	18
167.248.133.118	1
192.241.196.30	1
TOTAL: 3	20

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	i2pn-out.feed.uzoreto.com	2
	TOTAL: 1	2
Including strange strings
	news.swapon.de	157
	i2pn-out.news.weretis.net	60
	news-out.neodome.net	41
	news-out.aioe.org	24
	usenet.blueworldhosting.com	15
	paganini.bofh.team	13
	feeder.eternal-september.org	4
	news.nntp4.net	1
	TOTAL: 8	315
No colon-space in header
	i2pn-out.feed.uzoreto.com	1
	TOTAL: 1	1
TOTAL: 3		318

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn2	12352	11547	142	507	0	93%	23:59:50
2	novabbs	12418	11491	188	556	0	92%	23:59:46
3	aioe	8930	3004	5526	130	0	33%	23:59:50
4	news.nntp4.net	13111	2796	9535	10	0	21%	23:59:50
5	paganini.bofh.team	12387	2733	8873	2	0	22%	23:59:47
6	nntp.alphanet.ch	14381	1908	9651	647	212	13%	23:59:50
7	news.niel.me	3472	1292	1956	150	0	37%	23:59:50
8	neodome	14831	959	11288	0	0	6%	23:59:50
9	usenet.goja.nl.eu.org	14513	952	11093	21	6	6%	23:59:50
10	news.bbs.nz	24589	443	12015	205	39	1%	23:59:31
11	news.furie.org.uk	8491	344	5443	104	0	4%	23:59:50
12	news.uzoreto.com	14503	100	10955	0	0	0%	23:59:50
13	eternal-september	14233	73	10487	13	0	0%	23:59:20
14	usenet.blueworldhosting.com	13730	70	8858	0	0	0%	23:59:37
15	news.samoylyk.net	15764	57	12157	27	0	0%	23:59:21
16	weretis.net	15268	53	9576	460	0	0%	23:59:50
17	dodin.fr.nf	702	33	520	0	0	4%	20:37:13
18	swapon.de	1138	1	888	0	0	0%	23:08:12
19	kf5zxw.com	0	0	0	0	12433	0%	23:57:40
20	retrobbs.ddns.net	0	0	0	0	12236	0%	23:57:46
TOTAL: 20		214813	37856	129151	2832	24926	17%	475:36:33

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	novabbs	44.5 MB	3.9 MB	48.4 MB	0.6 KB/s	4.1 KB	23:59:46
2	i2pn2	44.4 MB	3.9 MB	48.3 MB	0.6 KB/s	4.1 KB	23:59:50
3	aioe	9.5 MB	369.0 KB	9.9 MB	0.1 KB/s	3.2 KB	23:59:50
4	news.nntp4.net	8.6 MB	90.3 KB	8.7 MB	0.1 KB/s	3.2 KB	23:59:50
5	paganini.bofh.team	8.2 MB	23.9 KB	8.2 MB	0.1 KB/s	3.1 KB	23:59:47
6	nntp.alphanet.ch	6.2 MB	2.3 MB	8.5 MB	0.1 KB/s	3.4 KB	23:59:50
7	news.niel.me	4.1 MB	422.4 KB	4.5 MB	0.1 KB/s	3.2 KB	23:59:50
8	neodome	2.9 MB	0.0 KB	2.9 MB	0.0 KB/s	3.1 KB	23:59:50
9	usenet.goja.nl.eu.org	2.7 MB	157.2 KB	2.9 MB	0.0 KB/s	3.0 KB	23:59:50
10	news.bbs.nz	1.8 MB	925.7 KB	2.7 MB	0.0 KB/s	4.3 KB	23:59:31
11	news.furie.org.uk	1.4 MB	455.8 KB	1.8 MB	0.0 KB/s	4.2 KB	23:59:50
12	news.uzoreto.com	346.6 KB	0.0 KB	346.6 KB	0.0 KB/s	3.5 KB	23:59:50
13	usenet.blueworldhosting.com	251.9 KB	0.0 KB	251.9 KB	0.0 KB/s	3.6 KB	23:59:37
14	eternal-september	177.7 KB	135.2 KB	312.9 KB	0.0 KB/s	3.6 KB	23:59:20
15	news.samoylyk.net	168.5 KB	139.1 KB	307.6 KB	0.0 KB/s	3.7 KB	23:59:21
16	weretis.net	137.5 KB	865.1 KB	1002.5 KB	0.0 KB/s	2.0 KB	23:59:50
17	dodin.fr.nf	99.8 KB	0.0 KB	99.8 KB	0.0 KB/s	3.0 KB	20:37:13
18	swapon.de	2.2 KB	0.0 KB	2.2 KB	0.0 KB/s	2.2 KB	23:08:12
19	kf5zxw.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:40
20	retrobbs.ddns.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:46
TOTAL: 20		135.5 MB	13.6 MB	149.1 MB	0.1 KB/s	3.8 KB	475:36:33

Daily Usenet report

Sep 16 04:15:02 -- Sep 17 04:15:01