Daily Usenet Report: Sep 2 00:01:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Sep 2 08:12:06 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Sep 3 00:00:01 newsfeed nnrpd[1871551]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	8347	29.0%	887.8 KB	14.1%
inn	7945	27.6%	3.3 MB	53.7%
innd	7878	27.3%	1.1 MB	18.5%
innfeed	4133	14.3%	782.0 KB	12.5%
cnfsstat	432	1.5%	61.6 KB	1.0%
nocem	96	0.3%	11.1 KB	0.2%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 7	28832	100.0%	6.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	109546	59.8%
Negative hits	65852	35.9%
Do not exist	7819	4.3%
Cache misses	10	0.0%
TOTAL: 4	183227	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.163	0.0%	73	0.000	2.233	9.000
article cleanup	00:00:00.250	0.0%	7849	0.000	0.032	2.897
article logging	00:00:00.380	0.0%	7944	0.000	0.048	0.235
article parse	00:00:00.199	0.0%	12455	0.000	0.016	0.083
article write	00:00:03.186	0.0%	6833	0.000	0.466	17.217
artlog/artcncl	00:00:00.000	0.0%	3	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:01.677	0.0%	188971	0.000	0.009	0.574
hisgrep/artcncl	00:00:00.132	0.0%	74	0.000	1.784	6.286
hishave/artcncl	00:00:00.000	0.0%	3	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:19.729	0.2%	183911	0.104	0.760	5.216
history sync	00:00:00.068	0.0%	355	0.000	0.192	1.000
history write	00:01:42.361	0.1%	7850	3.222	13.040	87.762
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:49:06.536	99.2%	188449	90.259	455.012	1609.351
nntp read	00:00:03.118	0.0%	189047	0.003	0.016	0.491
overview write	00:06:06.096	0.4%	6833	0.692	53.578	3402.185
perl filter	00:00:28.964	0.0%	7849	1.581	3.690	51.299
python filter	00:00:00.866	0.0%	7849	0.000	0.110	1.221
site send	00:00:01.027	0.0%	13759	0.000	0.075	0.447
TOTAL: 24:00:45.380	23:59:54.752	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.008	0.0%	6931	0.000	0.001	0.021
article prepare	00:00:00.010	0.0%	48688	0.000	0.000	0.016
article read	00:00:00.560	0.0%	4211	0.000	0.133	0.400
backlog stats	00:00:00.329	0.0%	252018	0.000	0.001	0.011
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.216	0.0%	173898	0.002	0.013	0.049
data write	00:00:03.091	0.0%	169300	0.004	0.018	0.032
idle	23:58:18.838	100.0%	252018	159.524	342.431	1244.085
status file	00:00:00.320	0.0%	276	0.000	1.159	5.000
TOTAL: 23:58:35.123	23:58:25.372	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:22:02.894	52.8%	8285	0.500	159.673	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.665	0.2%	95417	0.000	0.049	1.000
TOTAL: 00:41:46.914	00:22:07.559	53.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	outgoing.news.lysator.liu.se	1	6023	1537	4365	121	25%	23:59:56
2	endofthelinebbs-out.news.weretis.net	5	11779	1518	10004	257	12%	43:07:15
3	usenet.blueworldhosting.com	718	7698	841	6486	371	10%	23:59:08
4	news.hispagatos.org	6	6832	512	6216	104	7%	24:25:56
5	usenet.goja.nl.eu.org	10	6697	375	6285	37	5%	22:09:07
6	news.corradoroberto.it	8	1303	332	971	0	25%	23:25:13
7	usenet.network	2	5133	322	4810	1	6%	23:59:34
8	i2pn.org	3	6983	264	6711	8	3%	23:59:49
9	nntp.comgw.net	1	7093	233	6796	64	3%	23:59:57
10	newsfeed.bofh.team	2	7640	207	7419	14	2%	23:59:51
11	endoftheline-out.news.chmurka.net	2	3489	176	3310	3	5%	23:57:56
12	endofthelinebbs.peers.news.panix.com	24	7225	128	7071	26	1%	23:57:01
13	newsfeed.xs3.de	2	5638	126	5510	2	2%	23:57:48
14	news.samoylyk.net	1	6159	92	6067	0	1%	23:59:49
15	glockenspiel.complete.org	3	8067	25	8037	5	0%	33:00:44
16	news.nntp4.net	2	5955	15	5940	0	0%	23:59:02
17	photonic.trudheim.com	2	1304	11	1293	0	0%	19:58:27
18	news.bbs.nz	1	6025	10	6015	0	0%	23:59:32
19	news.furie.org.uk	1	3632	5	3626	1	0%	23:59:46
20	news.netfront.net	1	4515	2	4512	1	0%	24:50:17
21	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:05
22	localhost	28	0	0	0	0	0%	00:00:00
23	news.nk.ca	2	0	0	0	0	0%	00:20:11
TOTAL: 23		826	119190	6731	111444	1015	5%	503:16:24

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	outgoing.news.lysator.liu.se	5.3 MB	747.3 KB	6.0 MB	87%	3.7 KB
2	endofthelinebbs-out.news.weretis.net	5.0 MB	5.8 MB	10.8 MB	46%	6.2 KB
3	usenet.network	2.7 MB	0.9 KB	2.7 MB	99%	8.5 KB
4	usenet.blueworldhosting.com	2.1 MB	1.2 MB	3.3 MB	64%	2.8 KB
5	news.corradoroberto.it	1.8 MB	0.0 KB	1.8 MB	100%	5.7 KB
6	news.hispagatos.org	1.5 MB	339.6 KB	1.8 MB	81%	3.0 KB
7	i2pn.org	1.2 MB	12.5 KB	1.2 MB	98%	4.5 KB
8	usenet.goja.nl.eu.org	939.2 KB	41.4 KB	980.7 KB	95%	2.4 KB
9	endoftheline-out.news.chmurka.net	774.7 KB	2.0 KB	776.7 KB	99%	4.3 KB
10	nntp.comgw.net	720.1 KB	170.2 KB	890.3 KB	80%	3.0 KB
11	news.samoylyk.net	682.1 KB	0.0 KB	682.1 KB	100%	7.4 KB
12	newsfeed.bofh.team	524.4 KB	46.3 KB	570.7 KB	91%	2.6 KB
13	newsfeed.xs3.de	429.6 KB	4.0 KB	433.6 KB	99%	3.4 KB
14	glockenspiel.complete.org	329.0 KB	405.6 KB	734.6 KB	44%	24.5 KB
15	endofthelinebbs.peers.news.panix.com	319.4 KB	28.0 KB	347.4 KB	91%	2.3 KB
16	photonic.trudheim.com	106.2 KB	0.0 KB	106.2 KB	100%	9.7 KB
17	news.nntp4.net	25.8 KB	0.0 KB	25.8 KB	100%	1.7 KB
18	news.bbs.nz	25.8 KB	0.0 KB	25.8 KB	100%	2.6 KB
19	news.furie.org.uk	13.9 KB	0.8 KB	14.7 KB	94%	2.4 KB
20	news.netfront.net	2.9 KB	2.6 KB	5.5 KB	52%	1.8 KB
21	eotl-out.news.tnetconsulting.net	0.0 KB	0.0 KB	0.0 KB	0%	0
22	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
23	news.nk.ca	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 23		24.4 MB	8.7 MB	33.1 MB	73%	4.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 2 00:01:01 - 00:59:59	136	2.0%	0.04	369.8 KB	1.4%	0.10
Sep 2 01:00:00 - 01:59:59	254	3.7%	0.07	1.7 MB	6.8%	0.48
Sep 2 02:00:00 - 02:59:59	309	4.5%	0.09	961.7 KB	3.7%	0.27
Sep 2 03:00:00 - 03:59:59	339	5.0%	0.09	1.1 MB	4.5%	0.32
Sep 2 04:00:00 - 04:59:59	341	5.0%	0.09	887.8 KB	3.4%	0.25
Sep 2 05:00:00 - 05:59:59	409	6.0%	0.11	1.3 MB	5.3%	0.38
Sep 2 06:00:00 - 06:59:59	300	4.4%	0.08	1.1 MB	4.3%	0.31
Sep 2 07:00:00 - 07:59:59	333	4.9%	0.09	1.2 MB	4.7%	0.34
Sep 2 08:00:00 - 08:59:59	393	5.8%	0.11	1.4 MB	5.4%	0.39
Sep 2 09:00:00 - 09:59:59	411	6.0%	0.11	1.2 MB	4.6%	0.33
Sep 2 10:00:00 - 10:59:59	440	6.4%	0.12	1.6 MB	6.5%	0.47
Sep 2 11:00:00 - 11:59:59	460	6.7%	0.13	1.8 MB	7.2%	0.52
Sep 2 12:00:00 - 12:59:59	347	5.1%	0.10	1.2 MB	4.8%	0.35
Sep 2 13:00:00 - 13:59:59	336	4.9%	0.09	1.1 MB	4.5%	0.32
Sep 2 14:00:00 - 14:59:59	320	4.7%	0.09	1.2 MB	4.7%	0.33
Sep 2 15:00:00 - 15:59:59	269	3.9%	0.07	739.7 KB	2.9%	0.21
Sep 2 16:00:00 - 16:59:59	296	4.3%	0.08	1.0 MB	4.1%	0.29
Sep 2 17:00:00 - 17:59:59	285	4.2%	0.08	1014.0 KB	3.9%	0.28
Sep 2 18:00:00 - 18:59:59	147	2.2%	0.04	458.0 KB	1.8%	0.13
Sep 2 19:00:00 - 19:59:59	155	2.3%	0.04	1.2 MB	4.8%	0.34
Sep 2 20:00:00 - 20:59:59	168	2.5%	0.05	671.4 KB	2.6%	0.19
Sep 2 21:00:00 - 21:59:59	123	1.8%	0.03	450.0 KB	1.7%	0.13
Sep 2 22:00:00 - 22:59:59	110	1.6%	0.03	1.0 MB	4.0%	0.29
Sep 2 23:00:00 - 23:59:59	146	2.1%	0.04	507.9 KB	2.0%	0.14
Sep 3 00:00:00 - 00:01:01	4	0.1%	0.07	47.2 KB	0.2%	0.77
TOTAL: 24:00:00	6831	100.0%	0.08	25.1 MB	100.0%	0.30

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	97.0 GB	6.3%	0	0.45	41628.71
CYCTXT1	TEXT	5.0 GB	3.8 GB	76.7%	0	0.08	753.62
TOTAL: 3	-	3077.0 GB	100.9 GB	3.3%	1	0.37	-

Sites sending bad articles:

Server		Total	Group	Unapp	Other
1	usenet.blueworldhosting.com	395	1	1	393
2	endofthelinebbs-out.news.weretis.net	310	0	0	310
3	outgoing.news.lysator.liu.se	126	0	0	126
4	news.hispagatos.org	106	14	0	92
5	nntp.comgw.net	69	0	0	69
6	usenet.goja.nl.eu.org	40	0	0	40
7	endofthelinebbs.peers.news.panix.com	35	0	0	35
8	newsfeed.bofh.team	13	0	0	13
9	i2pn.org	8	0	0	8
10	endoftheline-out.news.chmurka.net	3	0	0	3
11	newsfeed.xs3.de	2	0	0	2
12	glockenspiel.complete.org	1	0	0	1
13	news.furie.org.uk	1	0	0	1
14	news.netfront.net	1	0	0	1
15	usenet.network	1	0	0	1
TOTAL: 15		1111	15	1	1095

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.hacking.ctf	8
hispagatos.hacking.news	5
alt.idiot	1
hispagatos.hacking.exploits	1
TOTAL: 4	15

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
misc.legal.moderated	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (phr path)	351
EMP (phn path)	316
EMP (md5)	150
Too many newsgroups	109
EMP (phl)	52
User-issued cancel	17
HTML Multipart	3
EMP (phn nph)	1
TOTAL: 8	999

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
i2pn2-nocem@i2pn2.org (spam)	2	2	0	2
i2pn2-nocem@i2pn2.org (multipost)	52	0	107	107
i2pn2-nocem@i2pn2.org (rmp)	32	0	99	99
nocem@usenet.ovh (faqfr)	1	0	14	14
robot@pasdenom.info (spam2)	4	0	4	4
robot@pasdenom.info (spam3)	2	0	2	2
TOTAL: 6	93	2	226	228

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	endofthelinebbs-out.news.weretis.net	46
	usenet.blueworldhosting.com	24
	endofthelinebbs.peers.news.panix.com	9
	nntp.comgw.net	7
	usenet.goja.nl.eu.org	5
	outgoing.news.lysator.liu.se	4
	TOTAL: 6	95
No colon-space in header field
	usenet.blueworldhosting.com	1
	TOTAL: 1	1
TOTAL: 2		96

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.netfront.net	9896	3239	3589	1	3	32%	23:59:04
2	nyheter.lysator.liu.se	5446	1303	3554	434	0	23%	23:59:53
3	photonic.trudheim.com	7130	634	5252	904	0	8%	23:59:53
4	news.furie.org.uk	3944	429	2858	12	0	10%	23:58:23
5	news.hispagatos.org	7443	421	5706	173	0	5%	23:59:53
6	endofthelinebbs.peers.news.panix.com	7041	412	6408	3	0	5%	23:59:53
7	usenet.network	7090	336	5698	39	5	4%	23:59:52
8	news.bbs.nz	12533	307	6153	362	23	2%	23:59:26
9	usenet.goja.nl.eu.org	7564	218	6056	150	3	2%	23:59:53
10	news.nk.ca	10551	189	6032	510	24	1%	23:59:53
11	newsfeed.bofh.team	7764	186	6362	0	1	2%	23:59:53
12	news.nntp4.net	8108	128	6625	31	5	1%	23:58:30
13	news.tnetconsulting.net	7570	120	6466	236	0	1%	23:59:26
14	news.weretis.net	7089	118	6587	125	0	1%	23:59:53
15	news.quux.org	7176	102	6297	413	15	1%	23:59:53
16	nntp.comgw.net	7596	95	6386	90	1	1%	23:59:09
17	i2pn.org	7400	87	6422	36	19	1%	23:59:38
18	news.corradoroberto.it	1039	86	802	0	0	8%	23:29:43
19	newsfeed.xs3.de	8092	60	6507	133	6	0%	23:59:53
20	news.chmurka.net	3793	35	3081	0	0	0%	23:58:24
21	news.samoylyk.net	8175	26	6672	35	0	0%	23:59:53
22	usenet.blueworldhosting.com	6019	1	5726	0	0	0%	23:59:01
23	feed.opticnetworks.net	0	0	0	0	6831	0%	23:59:53
TOTAL: 23		158459	8532	119239	3687	6936	5%	551:19:12

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.netfront.net	13.1 MB	1.9 KB	13.1 MB	0.2 KB/s	4.1 KB	23:59:04
2	nyheter.lysator.liu.se	4.5 MB	3.4 MB	7.8 MB	0.1 KB/s	4.6 KB	23:59:53
3	photonic.trudheim.com	3.8 MB	2.9 MB	6.7 MB	0.1 KB/s	4.4 KB	23:59:53
4	news.furie.org.uk	3.1 MB	121.7 KB	3.2 MB	0.0 KB/s	7.5 KB	23:58:23
5	endofthelinebbs.peers.news.panix.com	2.0 MB	7.4 KB	2.0 MB	0.0 KB/s	4.9 KB	23:59:53
6	news.hispagatos.org	1.8 MB	1.0 MB	2.9 MB	0.0 KB/s	5.0 KB	23:59:53
7	usenet.network	1.0 MB	92.0 KB	1.1 MB	0.0 KB/s	3.1 KB	23:59:52
8	newsfeed.bofh.team	1.0 MB	0.0 KB	1.0 MB	0.0 KB/s	5.6 KB	23:59:53
9	news.bbs.nz	862.7 KB	2.0 MB	2.9 MB	0.0 KB/s	4.4 KB	23:59:26
10	news.nntp4.net	525.5 KB	460.1 KB	985.6 KB	0.0 KB/s	6.2 KB	23:58:30
11	news.tnetconsulting.net	525.0 KB	1.2 MB	1.7 MB	0.0 KB/s	5.0 KB	23:59:26
12	news.weretis.net	485.2 KB	1.3 MB	1.8 MB	0.0 KB/s	7.6 KB	23:59:53
13	usenet.goja.nl.eu.org	435.9 KB	1.3 MB	1.7 MB	0.0 KB/s	4.7 KB	23:59:53
14	news.quux.org	424.6 KB	2.5 MB	3.0 MB	0.0 KB/s	5.9 KB	23:59:53
15	news.nk.ca	382.3 KB	3.2 MB	3.6 MB	0.0 KB/s	5.3 KB	23:59:53
16	nntp.comgw.net	295.6 KB	732.8 KB	1.0 MB	0.0 KB/s	5.6 KB	23:59:09
17	news.corradoroberto.it	265.5 KB	0.0 KB	265.5 KB	0.0 KB/s	3.1 KB	23:29:43
18	i2pn.org	233.9 KB	153.1 KB	387.0 KB	0.0 KB/s	3.1 KB	23:59:38
19	news.chmurka.net	229.0 KB	0.0 KB	229.0 KB	0.0 KB/s	6.5 KB	23:58:24
20	newsfeed.xs3.de	190.7 KB	873.9 KB	1.0 MB	0.0 KB/s	5.5 KB	23:59:53
21	news.samoylyk.net	71.1 KB	122.0 KB	193.1 KB	0.0 KB/s	3.2 KB	23:59:53
22	usenet.blueworldhosting.com	1.1 KB	0.0 KB	1.1 KB	0.0 KB/s	1.1 KB	23:59:01
23	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:53
TOTAL: 23		35.1 MB	21.4 MB	56.6 MB	0.0 KB/s	4.7 KB	551:19:12

NNRP readership statistics:

System		Conn	Elapsed
1	ddt.demos.su	2759	00:29:13
2	162.142.125.221	3	00:00:05
3	167.94.145.108	3	00:00:03
4	147.185.132.87	1	00:00:02
5	198.235.24.65	1	00:00:10
6	45.84.89.3	1	00:00:03
7	71.6.134.235	1	00:00:00
8	azpdes93.stretchoid.com	1	00:00:09
9	azpdsg0.stretchoid.com	1	00:00:00
10	localhost	1	00:11:55
TOTAL: 10		2772	00:41:45

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.demos.su	2759	00:29:13
2	unresolved	10	00:00:25
3	*.stretchoid.com	2	00:00:09
4	?	1	00:11:55
TOTAL: 4		2772	00:41:45

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
ddt.demos.su	9.836	37.004	0.000	00:29:13
localhost	180.555	371.156	0.083	00:11:55
198.235.24.65	0.004	0.012	0.000	00:00:10
azpdes93.stretchoid.com	0.004	0.012	0.000	00:00:09
162.142.125.221	0.021	0.049	0.000	00:00:05
167.94.145.108	0.013	0.048	0.000	00:00:03
45.84.89.3	0.000	0.016	0.000	00:00:03
147.185.132.87	0.004	0.012	0.000	00:00:02
71.6.134.235	0.004	0.013	0.000	00:00:00
azpdsg0.stretchoid.com	0.003	0.013	0.000	00:00:00
TOTAL: 10	190.444	408.335	0.083	00:41:45

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

System	Conn
162.142.125.221	1
167.94.145.108	1
azpdsg0.stretchoid.com	1
TOTAL: 3	3

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

Command	Count
EHLO www.censys.io	2
MGLNDD_144.172.126.95_119	1
TOTAL: 2	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
162.142.125.221	1	2
167.94.145.108	1	2
TOTAL: 2	2	5

Daily Usenet Report

Sep 2 00:01:01 -- Sep 3 00:01:01