Daily Usenet report: Nov 28 04:15:01

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	13070	48.8%	3.9 MB	65.6%
innd	9871	36.8%	1.4 MB	22.7%
innfeed	3859	14.4%	709.9 KB	11.6%
controlchan	1	0.0%	0.2 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 5	26802	100.0%	6.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	127802	59.4%
Negative hits	74248	34.5%
Do not exist	12820	6.0%
Cache misses	214	0.1%
TOTAL: 4	215084	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.002	0.0%	33	0.000	0.061	1.000
article cleanup	00:00:00.074	0.0%	12840	0.000	0.006	0.036
article logging	00:00:00.361	0.0%	13062	0.000	0.028	0.083
article parse	00:00:00.221	0.0%	22790	0.000	0.010	0.034
article write	00:00:00.752	0.0%	11797	0.000	0.064	0.614
artlog/artcncl	00:00:00.000	0.0%	7	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.070	0.0%	219820	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.001	0.0%	33	0.000	0.030	0.100
hishave/artcncl	00:00:00.000	0.0%	33	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.718	0.0%	215352	0.000	0.003	0.010
history sync	00:00:00.014	0.0%	302	0.000	0.046	1.000
history write	00:00:00.699	0.0%	12839	0.000	0.054	0.651
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:59:08.557	99.9%	222095	208.384	388.791	1648.525
nntp read	00:00:01.791	0.0%	221450	0.001	0.008	0.018
overview write	00:00:01.322	0.0%	11797	0.000	0.112	2.594
perl filter	00:00:24.993	0.0%	12838	1.254	1.947	9.105
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:01.367	0.0%	23664	0.000	0.058	0.186
TOTAL: 24:00:22.362	23:59:40.942	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.018	0.0%	12317	0.000	0.001	0.029
article prepare	00:00:00.017	0.0%	181028	0.000	0.000	0.002
article read	00:00:00.633	0.0%	11748	0.000	0.054	0.946
backlog stats	00:00:00.607	0.0%	383704	0.000	0.002	0.005
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.075	0.0%	252772	0.002	0.008	0.018
data write	00:00:02.285	0.0%	242496	0.003	0.009	0.029
idle	23:56:13.309	100.0%	383704	121.064	224.583	794.819
status file	00:00:00.474	0.0%	394	0.000	1.203	5.417
TOTAL: 23:56:31.543	23:56:19.418	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	4	19315	3040	16201	74	15%	45:31:29
2	news-out.aioe.org	4	8978	2869	6077	32	31%	24:09:32
3	usenet.blueworldhosting.com	5	12181	2821	9045	315	23%	24:40:35
4	feeder.eternal-september.org	13	6441	2002	4013	426	31%	25:25:32
5	news.nntp4.net	2	10183	254	9866	63	2%	23:59:44
6	usenet.goja.nl.eu.org	12	11250	209	10982	59	1%	24:11:21
7	paganini.bofh.team	2	10640	159	10480	1	1%	23:38:58
8	news-out.neodome.net	1	9976	113	9856	7	1%	23:59:55
9	news.swapon.de	18	915	88	827	0	9%	21:39:37
10	nntp.alphanet.ch	24	7309	74	7235	0	1%	24:51:28
11	dodin.fr.nf	16	681	58	623	0	8%	20:36:00
12	i2pn2.org	42	250	51	199	0	20%	16:28:02
13	i2pn-out.feed.uzoreto.com	1418	12555	49	12440	66	0%	71:12:05
14	news.samoylyk.net	2	9715	26	9688	1	0%	23:59:47
15	news.niel.me	2	2429	12	2414	3	0%	23:57:35
16	news.bbs.nz	7	8849	3	8846	0	0%	26:49:01
17	kf5zxw.com	1	0	0	0	0	0%	00:10:00
18	novabbs.org	5	3113	0	3113	0	0%	24:38:29
19	news.furie.org.uk	5	7497	0	7497	0	0%	37:31:04
20	localhost	25	0	0	0	0	0%	00:00:00
21	retrobbs.ddns.net	1	0	0	0	0	0%	00:10:00
TOTAL: 21		1609	142277	11828	129402	1047	8%	507:40:14

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	i2pn-out.news.weretis.net	17.8 MB	0.0 KB	383.5 KB	18.1 MB	97%	6.0 KB
2	usenet.blueworldhosting.com	10.0 MB	0.0 KB	1.2 MB	11.3 MB	89%	3.7 KB
3	news-out.aioe.org	7.2 MB	0.0 KB	81.8 KB	7.3 MB	98%	2.6 KB
4	feeder.eternal-september.org	4.7 MB	0.0 KB	3.3 MB	8.0 MB	58%	3.4 KB
5	news.nntp4.net	822.8 KB	0.0 KB	1.0 MB	1.8 MB	44%	5.8 KB
6	paganini.bofh.team	688.4 KB	0.0 KB	25.4 KB	713.8 KB	96%	4.5 KB
7	news-out.neodome.net	563.1 KB	3.5 KB	31.2 KB	597.8 KB	94%	5.0 KB
8	usenet.goja.nl.eu.org	552.6 KB	0.0 KB	201.0 KB	753.5 KB	73%	2.8 KB
9	i2pn2.org	244.1 KB	0.0 KB	0.0 KB	244.1 KB	100%	4.8 KB
10	news.swapon.de	193.4 KB	0.0 KB	0.0 KB	193.4 KB	100%	2.2 KB
11	nntp.alphanet.ch	154.1 KB	0.0 KB	0.0 KB	154.1 KB	100%	2.1 KB
12	i2pn-out.feed.uzoreto.com	116.7 KB	0.0 KB	295.1 KB	411.8 KB	28%	3.6 KB
13	dodin.fr.nf	105.2 KB	0.0 KB	0.0 KB	105.2 KB	100%	1.8 KB
14	news.samoylyk.net	98.1 KB	2.0 KB	2.0 KB	102.1 KB	96%	3.8 KB
15	news.niel.me	41.2 KB	0.0 KB	8.9 KB	50.2 KB	82%	3.3 KB
16	news.bbs.nz	4.0 KB	0.0 KB	0.0 KB	4.0 KB	100%	1.3 KB
17	kf5zxw.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
18	novabbs.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
19	news.furie.org.uk	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
20	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
21	retrobbs.ddns.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 21		43.2 MB	5.4 KB	6.5 MB	49.7 MB	86%	4.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 28 04:15:01 - 04:59:59	125	1.1%	0.05	1.8 MB	4.5%	0.70
Nov 28 05:00:00 - 05:59:59	268	2.3%	0.07	722.1 KB	1.7%	0.20
Nov 28 06:00:00 - 06:59:59	256	2.2%	0.07	677.2 KB	1.6%	0.19
Nov 28 07:00:00 - 07:59:59	365	3.1%	0.10	1.3 MB	3.1%	0.37
Nov 28 08:00:00 - 08:59:59	484	4.1%	0.13	2.4 MB	5.9%	0.69
Nov 28 09:00:00 - 09:59:59	598	5.1%	0.17	2.1 MB	5.1%	0.61
Nov 28 10:00:00 - 10:59:59	685	5.8%	0.19	2.2 MB	5.2%	0.62
Nov 28 11:00:00 - 11:59:59	779	6.6%	0.22	2.2 MB	5.3%	0.62
Nov 28 12:00:00 - 12:59:59	638	5.4%	0.18	1.9 MB	4.6%	0.54
Nov 28 13:00:00 - 13:59:59	606	5.1%	0.17	1.9 MB	4.5%	0.54
Nov 28 14:00:00 - 14:59:59	707	6.0%	0.20	2.1 MB	4.9%	0.58
Nov 28 15:00:00 - 15:59:59	790	6.7%	0.22	2.4 MB	5.7%	0.67
Nov 28 16:00:00 - 16:59:59	713	6.0%	0.20	2.2 MB	5.2%	0.62
Nov 28 17:00:00 - 17:59:59	707	6.0%	0.20	2.1 MB	5.1%	0.60
Nov 28 18:00:00 - 18:59:59	625	5.3%	0.17	2.0 MB	4.8%	0.57
Nov 28 19:00:00 - 19:59:59	573	4.9%	0.16	2.1 MB	5.0%	0.59
Nov 28 20:00:00 - 20:59:59	568	4.8%	0.16	2.0 MB	4.9%	0.58
Nov 28 21:00:00 - 21:59:59	572	4.8%	0.16	2.2 MB	5.3%	0.62
Nov 28 22:00:00 - 22:59:59	456	3.9%	0.13	1.5 MB	3.6%	0.42
Nov 28 23:00:00 - 23:59:59	332	2.8%	0.09	1010.6 KB	2.4%	0.28
Nov 29 00:00:00 - 00:59:59	286	2.4%	0.08	822.1 KB	1.9%	0.23
Nov 29 01:00:00 - 01:59:59	238	2.0%	0.07	2.3 MB	5.6%	0.66
Nov 29 02:00:00 - 02:59:59	184	1.6%	0.05	750.1 KB	1.8%	0.21
Nov 29 03:00:00 - 03:59:59	197	1.7%	0.05	842.3 KB	2.0%	0.23
Nov 29 04:00:00 - 04:15:01	43	0.4%	0.05	155.6 KB	0.4%	0.17
TOTAL: 24:00:00	11795	100.0%	0.14	41.5 MB	100.0%	0.49

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	feeder.eternal-september.org	434	12	0	422
2	usenet.blueworldhosting.com	332	29	0	303
3	i2pn-out.news.weretis.net	202	0	0	202
4	i2pn-out.feed.uzoreto.com	67	3	0	64
5	news.nntp4.net	65	1	0	64
6	usenet.goja.nl.eu.org	60	0	0	60
7	news-out.aioe.org	46	12	0	34
8	news.swapon.de	45	0	0	45
9	news-out.neodome.net	10	0	1	9
10	news.niel.me	3	0	0	3
11	news.samoylyk.net	3	0	1	2
12	paganini.bofh.team	1	0	0	1
TOTAL: 12		1268	57	2	1209

Unwanted newsgroups [Top 20]:

Newsgroup	Count
winnipeg.general	10
att.jobs	8
alt.info-science	6
alt.college.camp-all-stars	5
gun.general	4
rec.travel.resorts.all	4
aus.jobs	2
soc.culture.germany	1
alt.ads	1
tor.forsale	1
aol.neighborhood.ca.fresno.marketplace	1
alt.soc.germans	1
alt.adsl.free.montpellier	1
eternal-september.info	1
atl.forsale	1
alt.religion.jewish	1
alt.exploding.barney	1
soc.culture.mexico	1
alt.entrepreneurs	1
alt.forsale.seattle	1
TOTAL: 25	57

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	509
Too many newsgroups	166
Poison newsgroup	153
EMP (fsl)	73
Binary: misplaced par2	63
User-issued cancel	14
Scoring filter (10)	2
Scoring filter (8)	2
U2 violation - invalid distribution	2
HTML Multipart	1
Scoring filter (9)	1
TOTAL: 11	986

INND no permission servers:

System	Conn
50.31.21.4	31
192.241.204.132	1
192.241.203.171	1
2a06:4880:3000::33	1
167.248.133.47	1
TOTAL: 5	35

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	i2pn-out.news.weretis.net	134
	news.swapon.de	45
	news-out.aioe.org	15
	usenet.blueworldhosting.com	10
	feeder.eternal-september.org	9
	news-out.neodome.net	3
	news.nntp4.net	2
	news.samoylyk.net	2
	usenet.goja.nl.eu.org	1
	i2pn-out.feed.uzoreto.com	1
	TOTAL: 10	222
No colon-space in header
	i2pn-out.feed.uzoreto.com	1
	TOTAL: 1	1
TOTAL: 2		223

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	retrobbs.ddns.net	11654	11327	0	325	0	97%	23:59:34
2	i2pn2	11783	10929	178	495	10	92%	23:59:52
3	novabbs	14831	7939	3231	482	17	53%	23:59:52
4	news.nntp4.net	12274	1989	9486	56	0	16%	23:58:27
5	paganini.bofh.team	12535	1923	9706	1	0	15%	23:59:50
6	aioe	9008	1865	6532	83	1	20%	23:59:52
7	neodome	12883	1507	10070	0	5	11%	23:59:38
8	usenet.goja.nl.eu.org	13165	1083	10368	23	25	8%	23:59:52
9	news.furie.org.uk	8176	888	4559	112	349	10%	23:59:52
10	news.niel.me	3660	690	2531	172	0	18%	23:59:14
11	news.bbs.nz	22617	637	10873	270	71	2%	23:59:52
12	nntp.alphanet.ch	13286	502	10785	337	1	3%	23:59:33
13	news.uzoreto.com	13197	145	10394	0	1	1%	23:59:50
14	usenet.blueworldhosting.com	16305	144	8781	2	34	0%	23:59:47
15	eternal-september	12710	142	9576	40	20	1%	23:59:39
16	news.samoylyk.net	14441	120	11513	35	6	0%	23:59:51
17	weretis.net	12818	104	7690	895	6	0%	23:59:50
18	dodin.fr.nf	1292	54	673	1	0	4%	19:31:41
19	swapon.de	1035	3	882	0	0	0%	22:29:05
20	kf5zxw.com	0	0	0	0	11792	0%	23:57:43
TOTAL: 20		217670	41991	127828	3329	12338	19%	473:52:54

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	retrobbs.ddns.net	39.5 MB	1.3 MB	40.8 MB	0.5 KB/s	3.6 KB	23:59:34
2	i2pn2	37.5 MB	2.2 MB	39.7 MB	0.5 KB/s	3.6 KB	23:59:52
3	novabbs	29.2 MB	1.9 MB	31.2 MB	0.4 KB/s	3.8 KB	23:59:52
4	aioe	6.4 MB	217.2 KB	6.6 MB	0.1 KB/s	3.5 KB	23:59:52
5	news.nntp4.net	6.2 MB	242.4 KB	6.5 MB	0.1 KB/s	3.2 KB	23:58:27
6	paganini.bofh.team	6.0 MB	3.5 KB	6.0 MB	0.1 KB/s	3.2 KB	23:59:50
7	neodome	4.6 MB	0.0 KB	4.6 MB	0.1 KB/s	3.2 KB	23:59:38
8	usenet.goja.nl.eu.org	3.4 MB	226.9 KB	3.6 MB	0.0 KB/s	3.3 KB	23:59:52
9	news.furie.org.uk	3.1 MB	511.8 KB	3.6 MB	0.0 KB/s	3.7 KB	23:59:52
10	news.bbs.nz	2.3 MB	1.0 MB	3.3 MB	0.0 KB/s	3.7 KB	23:59:52
11	news.niel.me	2.1 MB	426.9 KB	2.5 MB	0.0 KB/s	3.0 KB	23:59:14
12	nntp.alphanet.ch	1.9 MB	1.4 MB	3.3 MB	0.0 KB/s	4.1 KB	23:59:33
13	usenet.blueworldhosting.com	487.5 KB	8.2 KB	495.7 KB	0.0 KB/s	3.4 KB	23:59:47
14	news.uzoreto.com	454.4 KB	0.0 KB	454.4 KB	0.0 KB/s	3.1 KB	23:59:50
15	eternal-september	345.9 KB	362.0 KB	708.0 KB	0.0 KB/s	3.9 KB	23:59:39
16	dodin.fr.nf	335.4 KB	3.3 KB	338.7 KB	0.0 KB/s	6.2 KB	19:31:41
17	news.samoylyk.net	275.4 KB	211.2 KB	486.6 KB	0.0 KB/s	3.1 KB	23:59:51
18	weretis.net	240.2 KB	1.6 MB	1.8 MB	0.0 KB/s	1.9 KB	23:59:50
19	swapon.de	88.9 KB	0.0 KB	88.9 KB	0.0 KB/s	29.6 KB	22:29:05
20	kf5zxw.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:43
TOTAL: 20		144.6 MB	11.5 MB	156.1 MB	0.1 KB/s	3.5 KB	473:52:54

Daily Usenet report

Nov 28 04:15:01 -- Nov 29 04:15:01