Daily Usenet Report: Aug 28 00:01:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Aug 28 08:16:07 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Aug 29 00:00:01 newsfeed nnrpd[3088484]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	8397	29.0%	883.0 KB	14.1%
innd	8024	27.7%	1.1 MB	18.6%
inn	7995	27.6%	3.3 MB	53.4%
innfeed	4135	14.3%	808.8 KB	12.9%
cnfsstat	432	1.5%	62.8 KB	1.0%
nocem	14	0.0%	1.6 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 7	28998	100.0%	6.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	107524	61.0%
Negative hits	61073	34.6%
Do not exist	7801	4.4%
Cache misses	9	0.0%
TOTAL: 4	176407	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.425	0.0%	38	0.000	11.184	173.000
article cleanup	00:00:00.118	0.0%	7859	0.000	0.015	0.275
article logging	00:00:00.346	0.0%	7935	0.000	0.044	0.286
article parse	00:00:00.210	0.0%	12857	0.000	0.016	0.105
article write	00:00:02.057	0.0%	6747	0.044	0.305	3.621
artlog/artcncl	00:00:00.000	0.0%	4	0.000	0.000	0.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:00.976	0.0%	180599	0.000	0.005	0.174
hisgrep/artcncl	00:00:00.173	0.0%	41	0.000	4.220	19.000
hishave/artcncl	00:00:00.000	0.0%	4	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:22.443	0.2%	177903	0.100	0.801	7.914
history sync	00:00:00.084	0.0%	395	0.000	0.213	1.000
history write	00:01:58.866	0.1%	7859	3.161	15.125	126.481
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:39:45.968	99.2%	179970	84.008	473.334	1475.216
nntp read	00:00:04.052	0.0%	180721	0.000	0.022	0.486
overview write	00:05:48.618	0.4%	6747	0.529	51.670	1528.192
perl filter	00:00:26.547	0.0%	7859	1.596	3.378	21.707
python filter	00:00:01.292	0.0%	7859	0.000	0.164	3.268
site send	00:00:01.096	0.0%	13506	0.000	0.081	0.254
TOTAL: 23:51:24.678	23:50:33.271	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.011	0.0%	7159	0.000	0.002	0.048
article prepare	00:00:00.007	0.0%	57468	0.000	0.000	0.002
article read	00:00:00.614	0.0%	4266	0.000	0.144	0.500
backlog stats	00:00:00.319	0.0%	252648	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.129	0.0%	173972	0.002	0.012	0.051
data write	00:00:03.159	0.0%	170271	0.009	0.019	0.037
idle	23:57:29.940	100.0%	252648	166.679	341.384	1356.104
status file	00:00:00.325	0.0%	281	1.000	1.157	7.000
TOTAL: 23:57:51.375	23:57:36.504	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:23:11.013	55.0%	8311	0.250	167.370	10051.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.408	0.2%	95524	0.000	0.046	3.000
TOTAL: 00:42:07.064	00:23:15.421	55.2%	-	-	-	-

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	1	5526	1561	3939	26	28%	23:59:57
2	usenet.blueworldhosting.com	718	7675	983	6265	427	12%	23:56:25
3	endofthelinebbs-out.news.weretis.net	2	11095	791	10150	154	7%	39:29:06
4	news.hispagatos.org	5	6806	555	6225	26	8%	24:26:22
5	news.corradoroberto.it	6	1335	495	839	1	37%	23:55:56
6	nntp.comgw.net	1	6919	476	6267	176	6%	23:59:56
7	usenet.network	2	5316	406	4903	7	7%	23:59:55
8	usenet.goja.nl.eu.org	11	6864	398	6383	83	5%	24:10:01
9	newsfeed.xs3.de	2	5368	235	5077	56	4%	23:59:25
10	newsfeed.bofh.team	2	7514	203	7283	28	2%	23:58:59
11	i2pn.org	1	6762	193	6551	18	2%	23:59:54
12	endoftheline-out.news.chmurka.net	2	3407	148	3253	6	4%	23:59:43
13	endofthelinebbs.peers.news.panix.com	24	7187	136	7039	12	1%	23:54:40
14	news.nntp4.net	2	5794	24	5769	1	0%	23:59:38
15	glockenspiel.complete.org	2	5821	21	5800	0	0%	24:01:34
16	news.samoylyk.net	2	6083	20	6063	0	0%	23:59:43
17	news.netfront.net	1	4527	20	4489	18	0%	22:29:47
18	news.bbs.nz	1	6354	6	6348	0	0%	23:59:36
19	news.furie.org.uk	2	3781	5	3775	1	0%	23:59:54
20	photonic.trudheim.com	1	1169	1	1168	0	0%	17:15:22
21	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:03
22	localhost	27	0	0	0	0	0%	00:00:01
23	news.nk.ca	2	0	0	0	0	0%	00:20:13
TOTAL: 23		818	115303	6677	107586	1040	5%	488:06:10

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	outgoing.news.lysator.liu.se	5.7 MB	34.2 KB	5.8 MB	99%	3.7 KB
2	usenet.network	4.8 MB	8.3 KB	4.8 MB	99%	11.9 KB
3	usenet.blueworldhosting.com	2.8 MB	1.2 MB	4.0 MB	69%	2.9 KB
4	news.corradoroberto.it	2.5 MB	1.7 KB	2.5 MB	99%	5.1 KB
5	endofthelinebbs-out.news.weretis.net	2.4 MB	411.5 KB	2.8 MB	85%	3.1 KB
6	nntp.comgw.net	2.1 MB	1.5 MB	3.5 MB	58%	5.5 KB
7	news.hispagatos.org	1.6 MB	139.4 KB	1.7 MB	91%	3.0 KB
8	usenet.goja.nl.eu.org	1.1 MB	220.7 KB	1.3 MB	83%	2.8 KB
9	i2pn.org	681.6 KB	27.6 KB	709.2 KB	96%	3.4 KB
10	newsfeed.xs3.de	638.9 KB	264.1 KB	903.0 KB	70%	3.1 KB
11	news.samoylyk.net	569.0 KB	0.0 KB	569.0 KB	100%	28.4 KB
12	endoftheline-out.news.chmurka.net	509.1 KB	4.1 KB	513.2 KB	99%	3.3 KB
13	newsfeed.bofh.team	457.9 KB	415.5 KB	873.4 KB	52%	3.8 KB
14	endofthelinebbs.peers.news.panix.com	314.3 KB	47.4 KB	361.6 KB	86%	2.4 KB
15	glockenspiel.complete.org	228.7 KB	0.0 KB	228.7 KB	100%	10.9 KB
16	news.nntp4.net	69.4 KB	0.6 KB	70.0 KB	99%	2.8 KB
17	news.netfront.net	37.1 KB	75.0 KB	112.2 KB	33%	3.0 KB
18	news.furie.org.uk	29.3 KB	0.7 KB	29.9 KB	97%	5.0 KB
19	news.bbs.nz	10.3 KB	0.0 KB	10.3 KB	100%	1.7 KB
20	photonic.trudheim.com	3.0 KB	0.0 KB	3.0 KB	100%	3.0 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		26.4 MB	4.3 MB	30.7 MB	85%	4.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 28 00:01:01 - 00:59:59	200	2.9%	0.06	597.6 KB	2.2%	0.17
Aug 28 01:00:00 - 01:59:59	247	3.6%	0.07	930.1 KB	3.5%	0.26
Aug 28 02:00:00 - 02:59:59	268	3.9%	0.07	1.0 MB	3.9%	0.29
Aug 28 03:00:00 - 03:59:59	356	5.2%	0.10	1.2 MB	4.5%	0.33
Aug 28 04:00:00 - 04:59:59	379	5.6%	0.11	1.2 MB	4.6%	0.34
Aug 28 05:00:00 - 05:59:59	329	4.8%	0.09	1.2 MB	4.8%	0.35
Aug 28 06:00:00 - 06:59:59	411	6.1%	0.11	1.6 MB	6.2%	0.46
Aug 28 07:00:00 - 07:59:59	333	4.9%	0.09	1.1 MB	4.4%	0.33
Aug 28 08:00:00 - 08:59:59	354	5.2%	0.10	1.4 MB	5.3%	0.39
Aug 28 09:00:00 - 09:59:59	427	6.3%	0.12	1.6 MB	6.2%	0.46
Aug 28 10:00:00 - 10:59:59	365	5.4%	0.10	1.4 MB	5.2%	0.39
Aug 28 11:00:00 - 11:59:59	355	5.2%	0.10	1.2 MB	4.5%	0.34
Aug 28 12:00:00 - 12:59:59	334	4.9%	0.09	1.2 MB	4.5%	0.34
Aug 28 13:00:00 - 13:59:59	375	5.5%	0.10	1.3 MB	4.9%	0.36
Aug 28 14:00:00 - 14:59:59	345	5.1%	0.10	1.1 MB	4.4%	0.33
Aug 28 15:00:00 - 15:59:59	300	4.4%	0.08	1.9 MB	7.1%	0.53
Aug 28 16:00:00 - 16:59:59	221	3.3%	0.06	799.2 KB	3.0%	0.22
Aug 28 17:00:00 - 17:59:59	246	3.6%	0.07	1.3 MB	4.9%	0.36
Aug 28 18:00:00 - 18:59:59	163	2.4%	0.05	949.7 KB	3.5%	0.26
Aug 28 19:00:00 - 19:59:59	180	2.7%	0.05	878.8 KB	3.3%	0.24
Aug 28 20:00:00 - 20:59:59	161	2.4%	0.04	621.5 KB	2.3%	0.17
Aug 28 21:00:00 - 21:59:59	117	1.7%	0.03	425.1 KB	1.6%	0.12
Aug 28 22:00:00 - 22:59:59	131	1.9%	0.04	687.3 KB	2.6%	0.19
Aug 28 23:00:00 - 23:59:59	184	2.7%	0.05	704.4 KB	2.6%	0.20
Aug 29 00:00:00 - 00:01:01	8	0.1%	0.13	29.7 KB	0.1%	0.49
TOTAL: 24:00:00	6789	100.0%	0.08	26.2 MB	100.0%	0.31

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	96.8 GB	6.3%	0	0.47	39918.49
CYCTXT1	TEXT	5.0 GB	3.8 GB	76.6%	0	0.03	2087.83
TOTAL: 3	-	3077.0 GB	100.7 GB	3.3%	1	0.33	-

Sites sending bad articles:

Server		Total	Group	Other
1	usenet.blueworldhosting.com	453	1	452
2	endofthelinebbs-out.news.weretis.net	199	0	199
3	nntp.comgw.net	191	0	191
4	usenet.goja.nl.eu.org	88	0	88
5	news.netfront.net	70	0	70
6	newsfeed.xs3.de	60	0	60
7	newsfeed.bofh.team	31	1	30
8	news.hispagatos.org	28	5	23
9	outgoing.news.lysator.liu.se	28	0	28
10	i2pn.org	18	0	18
11	endofthelinebbs.peers.news.panix.com	14	0	14
12	endoftheline-out.news.chmurka.net	9	0	9
13	usenet.network	6	0	6
14	photonic.trudheim.com	3	0	3
15	glockenspiel.complete.org	1	0	1
16	news.corradoroberto.it	1	0	1
17	news.furie.org.uk	1	0	1
18	news.nntp4.net	1	0	1
TOTAL: 18		1202	7	1195

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.hacking.exploits	3
hispagatos.hacking.news	2
free.freepbx.from.sangoma	1
soc.culture.russia	1
TOTAL: 4	7

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	384
EMP (phr path)	354
Too many newsgroups	153
EMP (md5)	142
EMP (phn nph)	51
Too many newsgroups (meow)	26
User-issued cancel	6
HTML Multipart	2
EMP (phr nph)	1
TOTAL: 9	1119

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
i2pn2-nocem@i2pn2.org (spam)	1	1	0	1
i2pn2-nocem@i2pn2.org (multipost)	8	0	9	9
robot@pasdenom.info (spam3)	2	0	3	3
robot@pasdenom.info (spam2)	1	0	1	1
TOTAL: 4	12	1	13	14

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	endofthelinebbs-out.news.weretis.net	28
	usenet.blueworldhosting.com	26
	usenet.goja.nl.eu.org	9
	newsfeed.xs3.de	4
	newsfeed.bofh.team	4
	outgoing.news.lysator.liu.se	2
	news.hispagatos.org	1
	nntp.comgw.net	1
	endofthelinebbs.peers.news.panix.com	1
	TOTAL: 9	76
TOTAL: 1		76

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.netfront.net	10118	2928	3839	1	10	28%	23:59:18
2	nyheter.lysator.liu.se	5502	1434	3219	575	0	26%	23:59:49
3	photonic.trudheim.com	7142	751	5050	979	0	10%	23:59:49
4	news.furie.org.uk	4333	638	2836	6	2	14%	23:59:29
5	endofthelinebbs.peers.news.panix.com	7077	472	6306	8	0	6%	23:59:37
6	usenet.network	7068	371	5542	38	8	5%	23:59:49
7	news.hispagatos.org	7497	352	5542	330	0	4%	23:59:24
8	usenet.goja.nl.eu.org	7587	321	5887	160	8	4%	23:59:49
9	news.bbs.nz	10805	308	5944	523	62	2%	23:59:37
10	newsfeed.bofh.team	7824	277	6271	0	4	3%	23:59:46
11	news.corradoroberto.it	1318	266	679	2	0	20%	23:28:39
12	news.tnetconsulting.net	7418	241	6309	234	0	3%	23:59:39
13	news.weretis.net	7044	209	6444	134	0	2%	23:59:47
14	news.nk.ca	10532	196	6026	525	40	1%	23:59:49
15	news.quux.org	7218	181	6112	476	0	2%	23:59:49
16	news.nntp4.net	7935	166	6517	58	11	2%	23:59:24
17	nntp.comgw.net	7482	159	6032	92	0	2%	23:59:03
18	i2pn.org	7425	128	6393	58	47	1%	23:59:22
19	newsfeed.xs3.de	7987	119	6288	131	6	1%	23:58:50
20	news.samoylyk.net	10307	37	6249	470	181	0%	23:59:22
21	news.chmurka.net	3517	23	2942	4	1	0%	23:57:37
22	usenet.blueworldhosting.com	5742	0	5486	0	0	0%	23:57:19
23	feed.opticnetworks.net	0	0	0	0	6789	0%	23:59:49
TOTAL: 23		158878	9577	115913	4804	7169	6%	551:14:56

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.netfront.net	12.4 MB	2.5 KB	12.4 MB	0.1 KB/s	4.3 KB	23:59:18
2	nyheter.lysator.liu.se	5.5 MB	4.5 MB	10.0 MB	0.1 KB/s	5.1 KB	23:59:49
3	photonic.trudheim.com	4.8 MB	2.8 MB	7.6 MB	0.1 KB/s	4.5 KB	23:59:49
4	news.furie.org.uk	4.5 MB	30.9 KB	4.6 MB	0.1 KB/s	7.3 KB	23:59:29
5	endofthelinebbs.peers.news.panix.com	2.7 MB	17.1 KB	2.7 MB	0.0 KB/s	5.7 KB	23:59:37
6	newsfeed.bofh.team	1.8 MB	0.0 KB	1.8 MB	0.0 KB/s	6.7 KB	23:59:46
7	news.hispagatos.org	1.5 MB	2.0 MB	3.5 MB	0.0 KB/s	5.2 KB	23:59:24
8	usenet.network	1.4 MB	89.1 KB	1.5 MB	0.0 KB/s	3.7 KB	23:59:49
9	news.quux.org	1.4 MB	3.2 MB	4.6 MB	0.1 KB/s	7.1 KB	23:59:49
10	news.bbs.nz	1.2 MB	3.4 MB	4.6 MB	0.1 KB/s	5.7 KB	23:59:37
11	news.tnetconsulting.net	1.1 MB	1.7 MB	2.9 MB	0.0 KB/s	6.1 KB	23:59:39
12	usenet.goja.nl.eu.org	1.1 MB	1.7 MB	2.8 MB	0.0 KB/s	5.9 KB	23:59:49
13	news.weretis.net	936.0 KB	2.1 MB	3.0 MB	0.0 KB/s	8.9 KB	23:59:47
14	news.corradoroberto.it	875.9 KB	7.0 KB	882.9 KB	0.0 KB/s	3.3 KB	23:28:39
15	news.nntp4.net	787.5 KB	657.4 KB	1.4 MB	0.0 KB/s	6.5 KB	23:59:24
16	nntp.comgw.net	776.3 KB	1.2 MB	2.0 MB	0.0 KB/s	8.2 KB	23:59:03
17	news.nk.ca	703.8 KB	3.8 MB	4.5 MB	0.1 KB/s	6.4 KB	23:59:49
18	newsfeed.xs3.de	702.5 KB	1.4 MB	2.1 MB	0.0 KB/s	8.7 KB	23:58:50
19	i2pn.org	498.8 KB	134.0 KB	632.8 KB	0.0 KB/s	3.4 KB	23:59:22
20	news.samoylyk.net	407.0 KB	2.5 MB	2.9 MB	0.0 KB/s	5.8 KB	23:59:22
21	news.chmurka.net	106.7 KB	16.2 KB	122.9 KB	0.0 KB/s	4.6 KB	23:57:37
22	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:49
23	usenet.blueworldhosting.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:19
TOTAL: 23		45.1 MB	31.3 MB	76.4 MB	0.0 KB/s	5.4 KB	551:14:56

NNRP readership statistics:

System		Conn	Elapsed
1	ddt.demos.su	2764	00:30:03
2	152.32.207.88	3	00:00:10
3	167.94.138.123	3	00:00:05
4	167.94.145.97	3	00:00:03
5	167.94.146.62	3	00:00:03
6	104.152.52.133	1	00:00:00
7	104.152.52.218	1	00:00:10
8	147.185.132.108	1	00:00:10
9	147.185.132.72	1	00:00:10
10	179.60.149.54	1	00:00:00
11	adventuresome.monitoring.internet-measurement.com	1	00:00:02
12	localhost	1	00:11:05
TOTAL: 12		2783	00:42:05

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.demos.su	2764	00:30:03
2	unresolved	17	00:00:53
3	*.monitoring.internet-measurement.com	1	00:00:02
4	?	1	00:11:05
TOTAL: 4		2783	00:42:05

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.618	36.850	0.000	00:30:03
localhost	180.313	369.330	0.075	00:11:05
152.32.207.88	0.012	0.039	0.000	00:00:10
147.185.132.72	0.008	0.008	0.000	00:00:10
147.185.132.108	0.008	0.008	0.000	00:00:10
104.152.52.218	0.004	0.012	0.000	00:00:10
167.94.138.123	0.013	0.049	0.000	00:00:05
167.94.145.97	0.019	0.047	0.000	00:00:03
167.94.146.62	0.035	0.024	0.000	00:00:03
adventuresome.monitoring.internet-measurement.com	0.004	0.013	0.000	00:00:02
104.152.52.133	0.008	0.008	0.000	00:00:00
179.60.149.54	0.008	0.008	0.000	00:00:00
TOTAL: 12	190.050	406.396	0.075	00:42:05

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

System	Conn
152.32.207.88	2
167.94.138.123	1
167.94.145.97	1
167.94.146.62	1
179.60.149.54	1
TOTAL: 5	6

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

Command	Count
EHLO www.censys.io	3
#003	1
#026#003#001#001#027#001	1
À#024	1
TOTAL: 4	6

NNRP client timeouts [Top 20]:

System	Conn	Peer
167.94.138.123	1	2
167.94.145.97	1	2
167.94.146.62	1	2
179.60.149.54	1	1
TOTAL: 4	4	7

Daily Usenet Report

Aug 28 00:01:01 -- Aug 29 00:01:01