Daily Usenet Report: Sep 17 00:01:01

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Sep 17 07:55:28 newsfeed controlchan[672359]: mod-active returned status 0 (<checkgroups-1726577675@news.novabbs.com>)
Sep 17 08:13:51 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Sep 17 10:16:40 newsfeed nnrpd[1042196]: 47.186.47.228 auth: program exited with status 8
Sep 18 00:00:01 newsfeed nnrpd[1247845]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	8527	28.7%	3.5 MB	54.9%
nnrpd	8500	28.6%	852.4 KB	13.1%
innd	8113	27.3%	1.2 MB	18.2%
innfeed	4046	13.6%	827.1 KB	12.7%
cnfsstat	432	1.5%	63.4 KB	1.0%
nocem	48	0.2%	5.7 KB	0.1%
controlchan	6	0.0%	0.8 KB	0.0%
TOTAL: 7	29672	100.0%	6.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	121616	60.4%
Negative hits	71418	35.5%
Do not exist	8361	4.2%
Cache misses	4	0.0%
TOTAL: 4	201399	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.139	0.0%	53	0.000	2.623	11.000
article cleanup	00:00:00.086	0.0%	8421	0.000	0.010	0.083
article logging	00:00:00.439	0.0%	8501	0.000	0.052	0.859
article parse	00:00:00.197	0.0%	11698	0.000	0.017	0.143
article write	00:00:02.183	0.0%	7231	0.000	0.302	4.418
artlog/artcncl	00:00:00.000	0.0%	16	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.343	0.0%	200591	0.000	0.002	0.028
hisgrep/artcncl	00:00:00.106	0.0%	62	0.000	1.710	8.000
hishave/artcncl	00:00:00.000	0.0%	17	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:23.185	0.2%	201811	0.089	0.710	4.800
history sync	00:00:00.096	0.0%	398	0.000	0.241	1.667
history write	00:01:47.229	0.1%	8377	3.339	12.800	106.962
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:36:37.770	98.3%	200559	73.515	423.804	1924.067
nntp read	00:00:03.914	0.0%	201776	0.003	0.019	0.564
overview write	00:19:12.589	1.3%	7231	1.000	159.396	17594.905
perl filter	00:00:28.834	0.0%	8374	1.371	3.443	32.667
python filter	00:00:01.896	0.0%	8374	0.000	0.226	5.167
site send	00:00:01.051	0.0%	14505	0.000	0.072	0.205
TOTAL: 24:01:42.389	24:00:40.057	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.021	0.0%	7728	0.000	0.003	0.038
article prepare	00:00:00.008	0.0%	47024	0.000	0.000	0.013
article read	00:00:00.578	0.0%	3521	0.000	0.164	4.091
backlog stats	00:00:00.353	0.0%	266403	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.408	0.0%	183240	0.000	0.013	0.054
data write	00:00:03.114	0.0%	178981	0.010	0.017	0.038
idle	23:58:00.699	100.0%	266403	182.355	323.873	1474.302
status file	00:00:00.263	0.0%	229	0.968	1.148	9.000
TOTAL: 23:58:17.319	23:58:07.444	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:21:48.809	53.3%	8232	0.000	158.990	10019.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.371	0.2%	95267	0.000	0.046	0.500
TOTAL: 00:40:57.304	00:21:53.180	53.4%	-	-	-	-

Control commands to INND:

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
usenet@novabbs.com	0	0	1	0	1	1
TOTAL	0	0	1	0	1	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	outgoing.news.lysator.liu.se	2	5824	1709	4105	10	29%	23:59:52
2	endofthelinebbs-out.news.weretis.net	3	13960	1307	12428	225	9%	49:43:18
3	usenet.blueworldhosting.com	716	8096	993	6592	511	12%	23:59:01
4	usenet.goja.nl.eu.org	10	7506	691	6719	96	9%	23:47:53
5	news.hispagatos.org	5	7400	490	6882	28	6%	24:26:21
6	news.corradoroberto.it	9	1730	355	1373	2	20%	25:48:00
7	nntp.comgw.net	2	7646	314	7257	75	4%	23:59:27
8	i2pn.org	1	7482	305	7138	39	4%	23:59:56
9	endoftheline-out.news.chmurka.net	2	3780	206	3572	2	5%	23:59:29
10	usenet.network	1	5668	197	5454	17	3%	23:59:56
11	newsfeed.xs3.de	2	6093	182	5908	3	2%	23:59:48
12	endofthelinebbs.peers.news.panix.com	25	7659	174	7463	22	2%	23:56:00
13	newsfeed.bofh.team	1	8049	114	7926	9	1%	23:59:55
14	news.samoylyk.net	2	6705	103	6594	8	1%	23:58:39
15	news.netfront.net	1	6057	68	5862	127	1%	22:58:35
16	news.nntp4.net	2	6502	55	6442	5	0%	23:59:19
17	news.furie.org.uk	4	6293	23	6266	4	0%	40:15:55
18	glockenspiel.complete.org	2	6056	8	6046	2	0%	24:00:32
19	news.bbs.nz	1	6784	7	6775	2	0%	23:59:34
20	photonic.trudheim.com	4	1911	4	1904	3	0%	30:23:25
21	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:05
22	localhost	29	0	0	0	0	0%	00:00:01
23	news.nk.ca	2	0	0	0	0	0%	00:20:11
TOTAL: 23		827	131201	7305	122706	1190	5%	529:45:12

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	outgoing.news.lysator.liu.se	4.6 MB	35.2 KB	8.1 KB	4.6 MB	99%	2.7 KB
2	endofthelinebbs-out.news.weretis.net	3.7 MB	43.5 KB	438.2 KB	4.2 MB	88%	2.8 KB
3	usenet.blueworldhosting.com	2.2 MB	0.0 KB	1.1 MB	3.3 MB	66%	2.2 KB
4	news.corradoroberto.it	2.0 MB	35.2 KB	0.0 KB	2.1 MB	98%	6.0 KB
5	usenet.goja.nl.eu.org	1.8 MB	35.2 KB	161.8 KB	2.0 MB	90%	2.6 KB
6	nntp.comgw.net	1.8 MB	37.1 KB	91.9 KB	1.9 MB	93%	5.0 KB
7	usenet.network	1.7 MB	35.3 KB	13.5 KB	1.8 MB	97%	8.5 KB
8	news.hispagatos.org	1.4 MB	35.2 KB	30.3 KB	1.5 MB	95%	2.9 KB
9	i2pn.org	940.5 KB	35.3 KB	62.6 KB	1.0 MB	90%	3.0 KB
10	endoftheline-out.news.chmurka.net	854.5 KB	1.9 KB	0.7 KB	857.1 KB	99%	4.1 KB
11	newsfeed.xs3.de	626.6 KB	35.2 KB	1.4 KB	663.2 KB	94%	3.6 KB
12	endofthelinebbs.peers.news.panix.com	415.3 KB	37.1 KB	46.6 KB	499.0 KB	83%	2.5 KB
13	news.samoylyk.net	377.2 KB	32.6 KB	10.8 KB	420.6 KB	89%	3.8 KB
14	newsfeed.bofh.team	313.3 KB	37.1 KB	6.8 KB	357.1 KB	87%	2.9 KB
15	news.nntp4.net	145.2 KB	37.1 KB	1.4 KB	183.7 KB	79%	3.1 KB
16	news.netfront.net	140.3 KB	35.4 KB	229.2 KB	404.8 KB	34%	2.1 KB
17	news.furie.org.uk	74.1 KB	35.2 KB	1.3 KB	110.6 KB	66%	4.1 KB
18	glockenspiel.complete.org	51.8 KB	35.2 KB	0.0 KB	87.0 KB	59%	8.7 KB
19	photonic.trudheim.com	17.8 KB	37.2 KB	0.0 KB	55.0 KB	32%	7.9 KB
20	news.bbs.nz	13.0 KB	35.2 KB	0.0 KB	48.2 KB	26%	5.4 KB
21	eotl-out.news.tnetconsulting.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
22	localhost	0.0 KB	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 KB	0%	0
TOTAL: 23		23.1 MB	651.1 KB	2.2 MB	26.0 MB	89%	3.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 17 00:01:01 - 00:59:59	201	2.8%	0.06	537.4 KB	2.3%	0.15
Sep 17 01:00:00 - 01:59:59	352	4.9%	0.10	1.1 MB	5.0%	0.32
Sep 17 02:00:00 - 02:59:59	296	4.1%	0.08	901.9 KB	3.9%	0.25
Sep 17 03:00:00 - 03:59:59	371	5.1%	0.10	1.1 MB	4.9%	0.31
Sep 17 04:00:00 - 04:59:59	366	5.1%	0.10	1.3 MB	5.9%	0.38
Sep 17 05:00:00 - 05:59:59	353	4.9%	0.10	1.5 MB	6.6%	0.43
Sep 17 06:00:00 - 06:59:59	389	5.4%	0.11	1.2 MB	5.5%	0.35
Sep 17 07:00:00 - 07:59:59	370	5.1%	0.10	1.1 MB	4.9%	0.32
Sep 17 08:00:00 - 08:59:59	340	4.7%	0.09	911.4 KB	3.9%	0.25
Sep 17 09:00:00 - 09:59:59	372	5.1%	0.10	978.1 KB	4.2%	0.27
Sep 17 10:00:00 - 10:59:59	396	5.5%	0.11	1.2 MB	5.2%	0.33
Sep 17 11:00:00 - 11:59:59	391	5.4%	0.11	1.2 MB	5.4%	0.35
Sep 17 12:00:00 - 12:59:59	396	5.5%	0.11	1.1 MB	4.8%	0.31
Sep 17 13:00:00 - 13:59:59	462	6.4%	0.13	1.5 MB	6.7%	0.43
Sep 17 14:00:00 - 14:59:59	362	5.0%	0.10	1005.7 KB	4.3%	0.28
Sep 17 15:00:00 - 15:59:59	377	5.2%	0.10	1.0 MB	4.6%	0.30
Sep 17 16:00:00 - 16:59:59	398	5.5%	0.11	1.3 MB	5.8%	0.37
Sep 17 17:00:00 - 17:59:59	220	3.0%	0.06	639.3 KB	2.8%	0.18
Sep 17 18:00:00 - 18:59:59	176	2.4%	0.05	629.7 KB	2.7%	0.17
Sep 17 19:00:00 - 19:59:59	136	1.9%	0.04	566.1 KB	2.4%	0.16
Sep 17 20:00:00 - 20:59:59	94	1.3%	0.03	305.1 KB	1.3%	0.08
Sep 17 21:00:00 - 21:59:59	123	1.7%	0.03	452.2 KB	2.0%	0.13
Sep 17 22:00:00 - 22:59:59	127	1.8%	0.04	665.1 KB	2.9%	0.18
Sep 17 23:00:00 - 23:59:59	159	2.2%	0.04	424.7 KB	1.8%	0.12
Sep 18 00:00:00 - 00:01:01	13	0.2%	0.21	41.7 KB	0.2%	0.68
TOTAL: 24:00:00	7240	100.0%	0.08	22.6 MB	100.0%	0.27

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.5 GB	6.4%	0	0.44	42724.16
CYCTXT1	TEXT	5.0 GB	3.9 GB	77.2%	0	0.03	2115.00
TOTAL: 3	-	3077.0 GB	101.5 GB	3.3%	1	0.30	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	537	1	0	536
2	endofthelinebbs-out.news.weretis.net	256	0	5	251
3	news.netfront.net	140	0	2	138
4	usenet.goja.nl.eu.org	97	0	2	95
5	nntp.comgw.net	80	0	3	77
6	i2pn.org	38	0	2	36
7	news.hispagatos.org	28	18	2	8
8	endofthelinebbs.peers.news.panix.com	21	1	3	17
9	usenet.network	17	0	2	15
10	outgoing.news.lysator.liu.se	12	0	2	10
11	newsfeed.bofh.team	9	0	3	6
12	news.samoylyk.net	8	0	2	6
13	news.nntp4.net	6	0	3	3
14	news.furie.org.uk	5	0	2	3
15	newsfeed.xs3.de	5	0	2	3
16	glockenspiel.complete.org	3	0	3	0
17	photonic.trudheim.com	3	0	3	0
18	endoftheline-out.news.chmurka.net	2	0	1	1
19	news.bbs.nz	2	0	2	0
20	news.corradoroberto.it	2	0	2	0
TOTAL: 20		1271	20	46	1205

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.hacking.ctf	10
hispagatos.hacking.news	7
hispagatos.hacking.exploits	1
soc.culture.africa	1
soc.culture.russia	1
TOTAL: 5	20

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	374
EMP (phr path)	356
EMP (md5)	233
Too many newsgroups	110
User-issued cancel	28
Too many newsgroups (meow)	19
HTML Multipart	1
HTML post	1
TOTAL: 8	1122

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
i2pn2-nocem@i2pn2.org (spam)	3	4	0	4
nocem@eternal-september.org (bot)	2	4	0	4
i2pn2-nocem@i2pn2.org (multipost)	22	0	101	101
i2pn2-nocem@i2pn2.org (rmp)	11	0	72	72
robot@pasdenom.info (spam3)	4	0	6	6
TOTAL: 5	42	8	179	187

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	endofthelinebbs-out.news.weretis.net	42
	usenet.blueworldhosting.com	26
	usenet.goja.nl.eu.org	5
	outgoing.news.lysator.liu.se	2
	newsfeed.xs3.de	2
	news.nntp4.net	1
	news.furie.org.uk	1
	nntp.comgw.net	1
	TOTAL: 8	80
No colon-space in header field
	usenet.blueworldhosting.com	2
	TOTAL: 1	2
TOTAL: 2		82

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.netfront.net	11663	1946	5225	0	30	16%	23:59:52
2	nyheter.lysator.liu.se	5744	1793	3467	266	9	31%	23:59:49
3	news.furie.org.uk	3919	706	2643	15	10	18%	23:59:53
4	news.hispagatos.org	8051	489	6130	133	6	6%	23:59:53
5	photonic.trudheim.com	7640	445	5511	1272	0	5%	23:59:53
6	usenet.network	7924	402	6218	47	16	5%	23:58:08
7	usenet.goja.nl.eu.org	7840	344	6138	81	5	4%	23:59:53
8	endofthelinebbs.peers.news.panix.com	7433	333	6900	3	1	4%	23:59:53
9	news.bbs.nz	13330	261	6573	399	116	1%	23:59:53
10	news.nk.ca	10855	220	6479	464	70	2%	23:59:53
11	newsfeed.bofh.team	8315	201	6831	0	12	2%	23:57:01
12	news.nntp4.net	8344	175	6966	21	10	2%	23:59:53
13	nntp.comgw.net	8002	139	6740	49	5	1%	23:59:53
14	i2pn.org	7849	128	6753	43	58	1%	23:59:53
15	news.tnetconsulting.net	8143	126	6928	181	52	1%	23:59:47
16	news.weretis.net	7473	118	7045	75	12	1%	23:59:46
17	news.quux.org	7662	83	6719	429	16	1%	23:59:53
18	news.corradoroberto.it	1335	74	1092	10	0	5%	23:29:21
19	newsfeed.xs3.de	8571	55	6815	178	11	0%	23:59:32
20	news.chmurka.net	4042	46	3285	4	0	1%	23:59:53
21	news.samoylyk.net	8772	24	7004	71	58	0%	23:59:53
22	usenet.blueworldhosting.com	6154	8	5907	0	5	0%	23:59:29
23	feed.opticnetworks.net	0	0	0	0	7240	0%	23:59:53
TOTAL: 23		169061	8116	127369	3741	7742	4%	551:21:07

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.netfront.net	7.8 MB	0.0 KB	7.8 MB	0.1 KB/s	4.1 KB	23:59:52
2	nyheter.lysator.liu.se	4.9 MB	2.0 MB	6.8 MB	0.1 KB/s	3.4 KB	23:59:49
3	news.furie.org.uk	3.8 MB	79.4 KB	3.9 MB	0.0 KB/s	5.5 KB	23:59:53
4	photonic.trudheim.com	2.1 MB	3.2 MB	5.3 MB	0.1 KB/s	3.2 KB	23:59:53
5	news.hispagatos.org	1.6 MB	761.4 KB	2.3 MB	0.0 KB/s	3.8 KB	23:59:53
6	endofthelinebbs.peers.news.panix.com	1.5 MB	11.3 KB	1.5 MB	0.0 KB/s	4.5 KB	23:59:53
7	usenet.network	1.3 MB	98.0 KB	1.4 MB	0.0 KB/s	3.2 KB	23:58:08
8	newsfeed.bofh.team	960.3 KB	0.0 KB	960.3 KB	0.0 KB/s	4.8 KB	23:57:01
9	news.bbs.nz	826.3 KB	1.6 MB	2.4 MB	0.0 KB/s	3.7 KB	23:59:53
10	usenet.goja.nl.eu.org	773.9 KB	744.6 KB	1.5 MB	0.0 KB/s	3.6 KB	23:59:53
11	news.tnetconsulting.net	617.7 KB	962.4 KB	1.5 MB	0.0 KB/s	5.1 KB	23:59:47
12	news.weretis.net	595.0 KB	1.2 MB	1.8 MB	0.0 KB/s	9.4 KB	23:59:46
13	news.nntp4.net	531.2 KB	260.8 KB	792.0 KB	0.0 KB/s	4.0 KB	23:59:53
14	news.nk.ca	477.3 KB	2.0 MB	2.5 MB	0.0 KB/s	3.7 KB	23:59:53
15	i2pn.org	431.8 KB	221.4 KB	653.1 KB	0.0 KB/s	3.8 KB	23:59:53
16	news.quux.org	431.3 KB	1.8 MB	2.2 MB	0.0 KB/s	4.4 KB	23:59:53
17	nntp.comgw.net	391.9 KB	742.3 KB	1.1 MB	0.0 KB/s	6.0 KB	23:59:53
18	newsfeed.xs3.de	336.1 KB	1.0 MB	1.4 MB	0.0 KB/s	6.1 KB	23:59:32
19	news.corradoroberto.it	193.5 KB	20.3 KB	213.8 KB	0.0 KB/s	2.5 KB	23:29:21
20	news.chmurka.net	104.8 KB	9.2 KB	114.0 KB	0.0 KB/s	2.3 KB	23:59:53
21	news.samoylyk.net	47.5 KB	499.2 KB	546.7 KB	0.0 KB/s	5.8 KB	23:59:53
22	usenet.blueworldhosting.com	25.6 KB	0.0 KB	25.6 KB	0.0 KB/s	3.2 KB	23:59:29
23	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:53
TOTAL: 23		29.5 MB	17.2 MB	46.7 MB	0.0 KB/s	4.0 KB	551:21:07

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	47.186.47.228	1	0	0.0 KB	3	0	0	00:00:00
TOTAL: 1		1	0	0.0 KB	3	0	0	00:40:55

NNRP readership statistics (by domain):

System		Conn	Groups	Elapsed
1	*.demos.su	2744	0	00:28:38
2	unresolved	7	3	00:00:19
3	*.stretchoid.com	1	0	00:00:00
4	*.survey.inspici.com	1	0	00:00:03
5	?	1	0	00:11:54
TOTAL: 5		2754	3	00:40:55

NNRP auth users [Top 20]:

User	Conn
getlist	1
nelgin	1
TOTAL: 2	2

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
ddt.demos.su	9.313	36.547	0.000	00:28:38
localhost	177.338	371.993	0.069	00:11:54
147.185.132.18	0.000	0.016	0.000	00:00:10
167.94.138.48	0.021	0.049	0.000	00:00:09
server-0-2.survey.inspici.com	0.004	0.012	0.000	00:00:03
205.210.31.219	0.008	0.008	0.000	00:00:00
91.238.181.35	0.008	0.008	0.000	00:00:00
azpdwg42.stretchoid.com	0.004	0.013	0.000	00:00:00
TOTAL: 8	186.696	408.646	0.069	00:40:55

Curious NNRP server explorers [Top 20]:

System	Conn
ddt.demos.su	2744
167.94.138.48	3
147.185.132.18	1
205.210.31.219	1
91.238.181.35	1
azpdwg42.stretchoid.com	1
localhost	1
server-0-2.survey.inspici.com	1
TOTAL: 8	2753

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

System	Conn
167.94.138.48	1
91.238.181.35	1
azpdwg42.stretchoid.com	1
TOTAL: 3	3

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

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
167.94.138.48	1	2
91.238.181.35	1	1
TOTAL: 2	2	4

Newsgroup request counts (by hierarchy):

Hierarchy		Count	Pct
1	alt	2	50.0%
2	news	2	50.0%
TOTAL: 2		4	100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

Newsgroup		Count
1	alt.bbs.doors	2
2	news.admin.peering	2
TOTAL: 2		4

Daily Usenet Report

Sep 17 00:01:01 -- Sep 18 00:01:01