Daily Usenet Report: Oct 16 00:01:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Oct 16 08:16:44 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Oct 17 00:00:01 newsfeed nnrpd[1367032]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
innfeed	9204	33.2%	1.4 MB	21.7%
inn	9176	33.1%	3.6 MB	56.3%
innd	8803	31.8%	1.3 MB	20.8%
cnfsstat	432	1.6%	68.1 KB	1.0%
nnrpd	53	0.2%	6.2 KB	0.1%
nocem	32	0.1%	4.3 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 7	27701	100.0%	6.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	121907	59.2%
Negative hits	75152	36.5%
Do not exist	9004	4.4%
Cache misses	3	0.0%
TOTAL: 4	206066	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.128	0.0%	42	0.000	3.048	6.000
article cleanup	00:00:00.103	0.0%	9059	0.000	0.011	0.261
article logging	00:00:00.581	0.0%	9173	0.000	0.063	1.407
article parse	00:00:00.230	0.0%	14378	0.000	0.016	0.074
article write	00:00:02.595	0.0%	7416	0.000	0.350	11.000
artlog/artcncl	00:00:00.000	0.0%	4	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.940	0.0%	211420	0.000	0.004	0.145
hisgrep/artcncl	00:00:00.103	0.0%	46	0.000	2.239	3.900
hishave/artcncl	00:00:00.000	0.0%	4	0.000	0.000	0.000
hishave/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:48.774	0.2%	207173	0.103	0.815	5.103
history sync	00:00:00.078	0.0%	390	0.000	0.200	2.333
history write	00:02:17.495	0.2%	9060	3.169	15.176	107.431
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:51:51.782	99.3%	204490	83.563	420.127	1612.046
nntp read	00:00:03.587	0.0%	212085	0.000	0.017	0.282
overview write	00:02:50.674	0.2%	7416	0.737	23.014	1600.386
perl filter	00:00:28.637	0.0%	9058	1.253	3.162	28.086
python filter	00:00:02.241	0.0%	9058	0.000	0.247	3.634
site send	00:00:01.067	0.0%	14863	0.000	0.072	0.246
TOTAL: 24:01:16.573	24:00:29.015	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.026	0.0%	10191	0.000	0.003	0.042
article prepare	00:00:00.003	0.0%	57236	0.000	0.000	0.003
article read	00:00:00.739	0.0%	5293	0.000	0.140	0.580
backlog stats	00:00:00.440	0.0%	266627	0.000	0.002	0.006
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.425	0.0%	186522	0.002	0.013	0.042
data write	00:00:03.302	0.0%	180764	0.006	0.018	0.035
idle	23:56:25.169	100.0%	266627	155.316	323.242	1032.437
status file	00:00:03.322	0.0%	2411	1.000	1.378	7.787
TOTAL: 23:56:48.423	23:56:35.426	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:10:43.618	48.7%	18	0.500	35756.556	300103.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:03.829	0.3%	78787	0.000	0.049	0.049
TOTAL: 00:22:01.433	00:10:47.447	49.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	outgoing.news.lysator.liu.se	2	6297	1298	4986	13	20%	23:59:58
2	nntp.comgw.net	1	8202	1003	6971	228	12%	23:59:57
3	usenet.blueworldhosting.com	719	8775	894	7304	577	10%	23:55:46
4	endofthelinebbs-out.news.weretis.net	2	14196	869	13086	241	6%	50:25:48
5	usenet.goja.nl.eu.org	10	8039	590	7276	173	7%	24:00:12
6	news.hispagatos.org	5	8147	494	7612	41	6%	24:26:18
7	newsfeed.xs3.de	1	5930	447	5424	59	7%	23:59:55
8	usenet.network	2	6258	392	5859	7	6%	23:59:50
9	i2pn.org	1	8024	391	7518	115	4%	23:59:55
10	endoftheline-out.news.chmurka.net	1	4031	285	3744	2	7%	23:59:56
11	news.samoylyk.net	2	6777	185	6588	4	2%	23:52:14
12	194.87.241.33	42	300	168	132	0	56%	16:57:05
13	news.corradoroberto.it	3	1106	155	951	0	14%	22:58:21
14	endofthelinebbs.peers.news.panix.com	26	7849	130	7703	16	1%	23:55:05
15	newsfeed.bofh.team	1	8723	82	8637	4	0%	23:59:56
16	news.nntp4.net	1	6611	41	6569	1	0%	23:59:54
17	news.netfront.net	1	4696	25	4522	149	0%	23:57:50
18	glockenspiel.complete.org	2	6251	17	6233	1	0%	23:59:33
19	news.furie.org.uk	2	4031	17	4013	1	0%	24:10:02
20	news.bbs.nz	2	7205	9	7193	3	0%	23:59:32
21	photonic.trudheim.com	3	1601	2	1599	0	0%	22:37:34
22	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:07
23	localhost	26	0	0	0	0	0%	00:00:00
24	news.nk.ca	3	0	0	0	0	0%	00:25:04
TOTAL: 24		859	133049	7494	123920	1635	5%	521:49:52

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	outgoing.news.lysator.liu.se	4.6 MB	0.0 KB	14.1 KB	4.6 MB	99%	3.6 KB
2	usenet.network	3.9 MB	0.0 KB	183.1 KB	4.1 MB	95%	10.5 KB
3	endofthelinebbs-out.news.weretis.net	3.6 MB	0.0 KB	350.7 KB	3.9 MB	91%	3.6 KB
4	nntp.comgw.net	3.0 MB	0.0 KB	419.1 KB	3.4 MB	87%	2.8 KB
5	usenet.goja.nl.eu.org	2.3 MB	0.0 KB	694.5 KB	3.0 MB	77%	4.0 KB
6	usenet.blueworldhosting.com	2.2 MB	0.0 KB	1.2 MB	3.4 MB	64%	2.4 KB
7	news.hispagatos.org	1.8 MB	0.0 KB	78.9 KB	1.9 MB	95%	3.6 KB
8	news.samoylyk.net	1.8 MB	0.0 KB	5.7 KB	1.8 MB	99%	9.7 KB
9	i2pn.org	1.5 MB	0.0 KB	189.2 KB	1.7 MB	89%	3.4 KB
10	endoftheline-out.news.chmurka.net	1.2 MB	0.0 KB	1.3 KB	1.2 MB	99%	4.2 KB
11	newsfeed.xs3.de	1.1 MB	0.0 KB	105.9 KB	1.2 MB	91%	2.5 KB
12	news.corradoroberto.it	698.0 KB	0.0 KB	0.0 KB	698.0 KB	100%	4.5 KB
13	194.87.241.33	509.8 KB	0.0 KB	0.0 KB	509.8 KB	100%	3.0 KB
14	endofthelinebbs.peers.news.panix.com	329.6 KB	0.0 KB	15.3 KB	344.9 KB	95%	2.4 KB
15	newsfeed.bofh.team	229.7 KB	0.0 KB	9.4 KB	239.1 KB	96%	2.8 KB
16	glockenspiel.complete.org	139.6 KB	0.0 KB	0.6 KB	140.2 KB	99%	7.8 KB
17	news.nntp4.net	90.0 KB	0.0 KB	3.3 KB	93.3 KB	96%	2.2 KB
18	news.furie.org.uk	74.0 KB	4.6 KB	0.0 KB	78.5 KB	94%	4.4 KB
19	news.netfront.net	43.1 KB	0.0 KB	1.9 MB	2.0 MB	2%	11.6 KB
20	news.bbs.nz	19.8 KB	5.2 KB	0.0 KB	25.0 KB	79%	2.1 KB
21	photonic.trudheim.com	12.6 KB	0.0 KB	0.0 KB	12.6 KB	100%	6.3 KB
22	eotl-out.news.tnetconsulting.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
23	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
24	news.nk.ca	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 24		29.1 MB	9.8 KB	5.2 MB	34.2 MB	84%	3.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 16 00:01:01 - 00:59:59	163	2.2%	0.05	632.1 KB	2.2%	0.18
Oct 16 01:00:00 - 01:59:59	225	3.0%	0.06	919.3 KB	3.1%	0.26
Oct 16 02:00:00 - 02:59:59	344	4.6%	0.10	1.2 MB	4.0%	0.33
Oct 16 03:00:00 - 03:59:59	373	5.0%	0.10	1.3 MB	4.6%	0.38
Oct 16 04:00:00 - 04:59:59	354	4.8%	0.10	1.2 MB	4.2%	0.34
Oct 16 05:00:00 - 05:59:59	384	5.2%	0.11	1.2 MB	4.3%	0.35
Oct 16 06:00:00 - 06:59:59	336	4.5%	0.09	1.1 MB	3.9%	0.31
Oct 16 07:00:00 - 07:59:59	383	5.2%	0.11	1.4 MB	5.0%	0.40
Oct 16 08:00:00 - 08:59:59	429	5.8%	0.12	1.5 MB	5.3%	0.43
Oct 16 09:00:00 - 09:59:59	489	6.6%	0.14	1.6 MB	5.7%	0.46
Oct 16 10:00:00 - 10:59:59	398	5.4%	0.11	1.4 MB	5.0%	0.41
Oct 16 11:00:00 - 11:59:59	479	6.5%	0.13	1.4 MB	4.9%	0.40
Oct 16 12:00:00 - 12:59:59	431	5.8%	0.12	1.4 MB	4.8%	0.39
Oct 16 13:00:00 - 13:59:59	434	5.8%	0.12	1.7 MB	5.8%	0.47
Oct 16 14:00:00 - 14:59:59	357	4.8%	0.10	1.8 MB	6.2%	0.51
Oct 16 15:00:00 - 15:59:59	324	4.4%	0.09	1.8 MB	6.3%	0.51
Oct 16 16:00:00 - 16:59:59	306	4.1%	0.09	1.5 MB	5.1%	0.41
Oct 16 17:00:00 - 17:59:59	221	3.0%	0.06	1.1 MB	3.8%	0.31
Oct 16 18:00:00 - 18:59:59	211	2.8%	0.06	894.5 KB	3.1%	0.25
Oct 16 19:00:00 - 19:59:59	213	2.9%	0.06	856.1 KB	2.9%	0.24
Oct 16 20:00:00 - 20:59:59	158	2.1%	0.04	1.1 MB	3.9%	0.31
Oct 16 21:00:00 - 21:59:59	137	1.8%	0.04	665.6 KB	2.3%	0.18
Oct 16 22:00:00 - 22:59:59	106	1.4%	0.03	542.3 KB	1.9%	0.15
Oct 16 23:00:00 - 23:59:59	164	2.2%	0.05	481.9 KB	1.6%	0.13
Oct 17 00:00:00 - 00:01:01	3	0.0%	0.05	5.2 KB	0.0%	0.09
TOTAL: 24:00:00	7422	100.0%	0.09	28.6 MB	100.0%	0.34

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
CYCNGS1	NGS	1536.0 GB	48.0 MB	0.0%	1	0.00	0.00
CYCNGS2	NGS	1536.0 GB	98.7 GB	6.4%	0	0.50	37068.06
CYCTXT1	TEXT	5.0 GB	3.9 GB	78.0%	0	0.04	1495.05
TOTAL: 3	-	3077.0 GB	102.6 GB	3.3%	1	0.35	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	599	0	0	599
2	endofthelinebbs-out.news.weretis.net	293	0	0	293
3	nntp.comgw.net	239	0	0	239
4	usenet.goja.nl.eu.org	172	0	0	172
5	news.netfront.net	153	0	0	153
6	i2pn.org	117	0	0	117
7	newsfeed.xs3.de	70	0	0	70
8	news.hispagatos.org	42	17	0	25
9	endofthelinebbs.peers.news.panix.com	23	0	0	23
10	usenet.network	15	0	0	15
11	outgoing.news.lysator.liu.se	13	0	0	13
12	news.samoylyk.net	4	0	0	4
13	newsfeed.bofh.team	4	0	0	4
14	endoftheline-out.news.chmurka.net	2	0	0	2
15	glockenspiel.complete.org	2	0	0	2
16	news.furie.org.uk	1	0	1	0
17	news.nntp4.net	1	0	0	1
TOTAL: 17		1750	17	1	1732

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.hacking.ctf	9
hispagatos.hacking.news	7
hispagatos.hacking.exploits	1
TOTAL: 3	17

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	885
EMP (phr path)	336
Too many newsgroups	164
EMP (md5)	148
User-issued cancel	33
Binary Image: misplaced jpg	24
Binary: misplaced binary	16
EMP (phn nph)	3
Too many newsgroups (meow)	3
HTML Multipart	2
hierarchy violation - crosspost outside sdnet	2
TOTAL: 11	1616

NoCeM on Spool:

Issuer (type)	Notices	Skip	Total
i2pn2-nocem@i2pn2.org (multipost)	18	55	55
i2pn2-nocem@i2pn2.org (rmp)	8	13	13
robot@pasdenom.info (spam3)	4	6	6
robot@pasdenom.info (spam2)	1	1	1
TOTAL: 4	31	75	75

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	endofthelinebbs-out.news.weretis.net	52
	usenet.blueworldhosting.com	26
	usenet.goja.nl.eu.org	12
	newsfeed.xs3.de	11
	endofthelinebbs.peers.news.panix.com	8
	nntp.comgw.net	4
	news.hispagatos.org	1
	TOTAL: 7	114
No colon-space in header field
	usenet.blueworldhosting.com	2
	TOTAL: 1	2
TOTAL: 2		116

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.netfront.net	10140	4089	3319	0	1	40%	23:59:52
2	nyheter.lysator.liu.se	6321	1523	4160	441	0	24%	23:59:52
3	news.furie.org.uk	4298	1048	2684	29	4	24%	23:57:15
4	photonic.trudheim.com	7945	483	5803	1130	0	6%	23:59:32
5	endofthelinebbs.peers.news.panix.com	7707	445	6965	8	1	5%	23:59:52
6	usenet.goja.nl.eu.org	7810	383	6242	213	1	4%	23:58:41
7	news.hispagatos.org	8285	284	6455	189	0	3%	23:59:14
8	usenet.network	7588	247	6351	26	0	3%	23:59:52
9	newsfeed.bofh.team	8219	237	6963	0	0	2%	23:59:52
10	news.bbs.nz	12610	155	6853	401	2627	1%	23:59:52
11	news.nk.ca	10515	145	6616	603	25	1%	23:59:52
12	i2pn.org	8195	126	6838	51	9	1%	23:59:52
13	news.nntp4.net	8342	119	7139	85	1	1%	23:59:52
14	nntp.comgw.net	7319	119	6173	121	0	1%	23:59:52
15	news.corradoroberto.it	1079	83	659	1	0	7%	23:26:35
16	news.weretis.net	7663	74	7196	151	0	0%	23:59:52
17	news.tnetconsulting.net	8173	68	7144	206	0	0%	23:59:52
18	news.quux.org	7751	55	6732	599	0	0%	23:59:52
19	newsfeed.xs3.de	8224	45	6670	250	1	0%	23:59:12
20	news.chmurka.net	4135	30	3480	0	1	0%	23:59:36
21	news.samoylyk.net	8410	23	7106	58	117	0%	23:59:52
22	usenet.blueworldhosting.com	6596	1	6297	0	0	0%	23:59:52
23	ddt.demos.su	68	0	67	0	0	0%	05:40:05
24	feed.opticnetworks.net	0	0	0	0	7422	0%	23:59:52
TOTAL: 24		167393	9782	127912	4562	10210	5%	556:58:02

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.netfront.net	15.6 MB	0.0 KB	15.6 MB	0.2 KB/s	3.9 KB	23:59:52
2	news.furie.org.uk	7.9 MB	243.8 KB	8.1 MB	0.1 KB/s	7.7 KB	23:57:15
3	nyheter.lysator.liu.se	4.8 MB	4.0 MB	8.8 MB	0.1 KB/s	4.6 KB	23:59:52
4	photonic.trudheim.com	3.2 MB	3.5 MB	6.8 MB	0.1 KB/s	4.3 KB	23:59:32
5	endofthelinebbs.peers.news.panix.com	2.5 MB	22.5 KB	2.5 MB	0.0 KB/s	5.7 KB	23:59:52
6	newsfeed.bofh.team	1.6 MB	0.0 KB	1.6 MB	0.0 KB/s	7.0 KB	23:59:52
7	news.hispagatos.org	1010.1 KB	1.7 MB	2.7 MB	0.0 KB/s	5.8 KB	23:59:14
8	usenet.network	788.2 KB	104.7 KB	892.9 KB	0.0 KB/s	3.3 KB	23:59:52
9	usenet.goja.nl.eu.org	737.5 KB	2.2 MB	3.0 MB	0.0 KB/s	5.1 KB	23:58:41
10	news.nk.ca	707.8 KB	3.5 MB	4.2 MB	0.1 KB/s	5.8 KB	23:59:52
11	nntp.comgw.net	664.5 KB	1.3 MB	2.0 MB	0.0 KB/s	8.3 KB	23:59:52
12	news.quux.org	607.6 KB	3.3 MB	3.8 MB	0.0 KB/s	6.0 KB	23:59:52
13	newsfeed.xs3.de	532.7 KB	1.6 MB	2.1 MB	0.0 KB/s	7.4 KB	23:59:12
14	news.bbs.nz	495.8 KB	2.3 MB	2.8 MB	0.0 KB/s	5.1 KB	23:59:52
15	news.samoylyk.net	424.1 KB	247.3 KB	671.4 KB	0.0 KB/s	8.3 KB	23:59:52
16	i2pn.org	381.0 KB	322.2 KB	703.2 KB	0.0 KB/s	4.0 KB	23:59:52
17	news.nntp4.net	306.0 KB	1.0 MB	1.3 MB	0.0 KB/s	6.6 KB	23:59:52
18	news.tnetconsulting.net	304.6 KB	1.6 MB	1.9 MB	0.0 KB/s	7.2 KB	23:59:52
19	news.weretis.net	304.0 KB	2.1 MB	2.4 MB	0.0 KB/s	10.9 KB	23:59:52
20	news.corradoroberto.it	257.2 KB	2.7 KB	260.0 KB	0.0 KB/s	3.1 KB	23:26:35
21	news.chmurka.net	141.6 KB	0.0 KB	141.6 KB	0.0 KB/s	4.7 KB	23:59:36
22	usenet.blueworldhosting.com	4.2 KB	0.0 KB	4.2 KB	0.0 KB/s	4.2 KB	23:59:52
23	ddt.demos.su	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	05:40:05
24	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:52
TOTAL: 24		43.1 MB	29.1 MB	72.2 MB	0.0 KB/s	5.2 KB	556:58:02

NNRP readership statistics:

System		Conn	Elapsed
1	104.152.52.228	1	00:00:10
2	104.152.52.230	1	00:00:00
3	147.45.112.222	1	00:00:01
4	198.235.24.73	1	00:00:10
5	198.235.24.94	1	00:00:10
6	2001:470:28:a8:3ad5:47ff:fe02:c2fd	1	00:10:00
7	45.227.254.49	1	00:00:00
8	azpdsgb12.stretchoid.com	1	00:00:00
9	azpdssb68.stretchoid.com	1	00:00:09
10	localhost	1	00:11:16
11	server-0-3.survey.inspici.com	1	00:00:03
TOTAL: 11		11	00:22:01

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	6	00:00:31
2	*.stretchoid.com	2	00:00:10
3	?	2	00:21:16
4	*.survey.inspici.com	1	00:00:03
TOTAL: 4		11	00:22:01

NNRP auth users [Top 20]:

User	Conn
getlist	1
TOTAL: 1	1

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
localhost	183.064	369.186	0.077	00:11:16
2001:470:28:a8:3ad5:47ff:fe02:c2fd	0.000	0.019	0.000	00:10:00
104.152.52.228	0.008	0.008	0.000	00:00:10
198.235.24.94	0.000	0.026	0.000	00:00:10
198.235.24.73	0.004	0.012	0.000	00:00:10
azpdssb68.stretchoid.com	0.000	0.016	0.000	00:00:09
server-0-3.survey.inspici.com	0.000	0.016	0.000	00:00:03
147.45.112.222	0.006	0.022	0.000	00:00:01
45.227.254.49	0.000	0.016	0.000	00:00:00
104.152.52.230	0.003	0.013	0.000	00:00:00
azpdsgb12.stretchoid.com	0.000	0.023	0.000	00:00:00
TOTAL: 11	183.085	369.357	0.077	00:22:01

NNRP unrecognized commands (by host) [Top 20]:

System	Conn
147.45.112.222	1
45.227.254.49	1
azpdsgb12.stretchoid.com	1
TOTAL: 3	3

NNRP unrecognized commands (by command) [Top 20]:

Command	Count
#003	2
MGLNDD_144.172.126.95_119	1
TOTAL: 2	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
147.45.112.222	1	1
45.227.254.49	1	1
TOTAL: 2	2	2

Daily Usenet Report

Oct 16 00:01:01 -- Oct 17 00:01:01