Daily Usenet Report: Sep 4 00:01:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Sep 4 08:13:30 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Sep 5 00:00:01 newsfeed nnrpd[3067491]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	8714	28.4%	3.6 MB	54.8%
innd	8484	27.6%	1.2 MB	18.0%
nnrpd	8309	27.0%	862.1 KB	12.8%
innfeed	4701	15.3%	896.6 KB	13.3%
cnfsstat	432	1.4%	62.3 KB	0.9%
nocem	85	0.3%	10.0 KB	0.1%
controlchan	9	0.0%	1.4 KB	0.0%
TOTAL: 7	30734	100.0%	6.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	117984	59.2%
Negative hits	72876	36.5%
Do not exist	8563	4.3%
Cache misses	35	0.0%
TOTAL: 4	199458	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.145	0.0%	38	0.000	3.816	10.000
article cleanup	00:00:00.427	0.0%	8604	0.000	0.050	2.474
article logging	00:00:00.467	0.0%	8685	0.000	0.054	0.190
article parse	00:00:00.208	0.0%	12702	0.000	0.016	0.069
article write	00:00:02.709	0.0%	7454	0.000	0.363	10.440
artlog/artcncl	00:00:00.001	0.0%	3	0.000	0.333	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:01.195	0.0%	204688	0.000	0.006	0.434
hisgrep/artcncl	00:00:00.117	0.0%	40	0.000	2.925	10.000
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:38.077	0.2%	200283	0.095	0.789	6.230
history sync	00:00:00.082	0.0%	387	0.000	0.212	1.000
history write	00:02:03.439	0.1%	8602	3.176	14.350	108.652
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:47:06.114	99.0%	203610	61.950	420.540	2607.230
nntp read	00:00:04.949	0.0%	205070	0.002	0.024	1.455
overview write	00:07:17.967	0.5%	7454	0.625	58.756	9837.875
perl filter	00:00:35.224	0.0%	8602	1.548	4.095	55.458
python filter	00:00:02.216	0.0%	8602	0.000	0.258	6.882
site send	00:00:01.012	0.0%	14991	0.000	0.068	0.212
TOTAL: 24:01:01.204	23:59:54.349	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.025	0.0%	9076	0.000	0.003	0.067
article prepare	00:00:00.012	0.0%	55048	0.000	0.000	0.003
article read	00:00:00.633	0.0%	4717	0.000	0.134	0.412
backlog stats	00:00:00.351	0.0%	267255	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.377	0.0%	185875	0.003	0.013	0.043
data write	00:00:03.241	0.0%	180304	0.003	0.018	0.035
idle	23:37:58.952	100.0%	267255	95.701	318.344	1467.194
status file	00:00:00.683	0.0%	261	1.000	2.617	163.500
TOTAL: 23:38:16.843	23:38:06.274	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:22:14.011	54.0%	8263	0.500	161.444	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.866	0.2%	95355	0.000	0.051	2.000
TOTAL: 00:41:12.426	00:22:18.877	54.2%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	98
name	2
newgroup	4
pause	2
paused	2
reload	2
reserve	2
TOTAL: 12	124

Newsgroups created:

Group	Mode
free.tampon.tim.walz	y
free.tim.walz.chicken-diaries	y
free.tim.walz.deployment.run.run.run.away	y
free.tim.walz.tranny.factory	y
TOTAL	4

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
rcanoza@gmail.com	4	0	0	0	4	4
TOTAL	4	0	0	0	4	4

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	outgoing.news.lysator.liu.se	5	6728	1829	4718	181	27%	23:59:52
2	endofthelinebbs-out.news.weretis.net	13	12539	1439	10908	192	11%	45:18:45
3	usenet.blueworldhosting.com	720	8289	832	7068	389	10%	23:54:21
4	usenet.network	5	5906	533	5369	4	9%	23:59:56
5	news.hispagatos.org	25	7466	504	6815	147	6%	25:35:55
6	usenet.goja.nl.eu.org	14	7264	453	6748	63	6%	21:56:00
7	nntp.comgw.net	5	7601	451	7041	109	5%	23:59:45
8	news.corradoroberto.it	11	1422	303	1119	0	21%	24:06:41
9	i2pn.org	5	7388	270	7109	9	3%	23:59:33
10	endoftheline-out.news.chmurka.net	5	3851	220	3629	2	5%	23:59:46
11	newsfeed.bofh.team	5	8344	151	8176	17	1%	23:59:39
12	endofthelinebbs.peers.news.panix.com	27	7823	129	7675	19	1%	24:13:44
13	newsfeed.xs3.de	5	6069	113	5956	0	1%	23:59:36
14	news.samoylyk.net	5	6545	68	6476	1	1%	23:59:10
15	news.nntp4.net	5	6317	27	6289	1	0%	23:59:40
16	news.bbs.nz	5	5894	25	5869	0	0%	23:58:54
17	glockenspiel.complete.org	6	5265	19	5246	0	0%	24:02:05
18	news.furie.org.uk	2	4000	9	3990	1	0%	23:58:58
19	photonic.trudheim.com	7	2149	1	2147	1	0%	29:14:48
20	news.netfront.net	4	676	1	675	0	0%	05:36:46
21	eotl-out.news.tnetconsulting.net	5	0	0	0	0	0%	00:32:54
22	localhost	27	0	0	0	0	0%	00:00:00
23	news.nk.ca	2	0	0	0	0	0%	00:20:10
TOTAL: 23		913	121536	7377	113023	1136	6%	488:46:58

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	outgoing.news.lysator.liu.se	6.6 MB	0.0 KB	1.1 MB	7.6 MB	86%	3.9 KB
2	usenet.network	5.2 MB	0.0 KB	3.6 KB	5.2 MB	99%	9.9 KB
3	endofthelinebbs-out.news.weretis.net	4.2 MB	0.0 KB	286.6 KB	4.5 MB	93%	2.8 KB
4	usenet.blueworldhosting.com	2.1 MB	0.0 KB	820.9 KB	2.9 MB	72%	2.4 KB
5	news.corradoroberto.it	1.9 MB	0.0 KB	0.0 KB	1.9 MB	100%	6.4 KB
6	news.hispagatos.org	1.4 MB	0.0 KB	526.4 KB	2.0 MB	73%	3.1 KB
7	nntp.comgw.net	1.3 MB	3.5 KB	631.2 KB	1.9 MB	67%	3.5 KB
8	usenet.goja.nl.eu.org	1.1 MB	0.0 KB	86.8 KB	1.2 MB	93%	2.4 KB
9	i2pn.org	907.3 KB	0.0 KB	9.8 KB	917.1 KB	98%	3.3 KB
10	endoftheline-out.news.chmurka.net	883.6 KB	0.0 KB	1.6 KB	885.2 KB	99%	4.0 KB
11	newsfeed.bofh.team	519.5 KB	0.0 KB	74.4 KB	594.0 KB	87%	3.5 KB
12	newsfeed.xs3.de	427.8 KB	0.0 KB	0.0 KB	427.8 KB	100%	3.8 KB
13	endofthelinebbs.peers.news.panix.com	331.9 KB	0.0 KB	30.7 KB	362.6 KB	91%	2.4 KB
14	news.samoylyk.net	325.0 KB	0.0 KB	0.6 KB	325.6 KB	99%	4.7 KB
15	news.nntp4.net	127.3 KB	0.0 KB	4.9 KB	132.2 KB	96%	4.7 KB
16	glockenspiel.complete.org	126.1 KB	0.0 KB	0.0 KB	126.1 KB	100%	6.6 KB
17	news.bbs.nz	70.6 KB	0.0 KB	0.0 KB	70.6 KB	100%	2.8 KB
18	news.furie.org.uk	33.7 KB	0.0 KB	0.8 KB	34.5 KB	97%	3.5 KB
19	photonic.trudheim.com	11.3 KB	0.0 KB	1.4 KB	12.7 KB	88%	6.4 KB
20	news.netfront.net	0.9 KB	0.0 KB	0.0 KB	0.9 KB	100%	0.9 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		27.4 MB	3.5 KB	3.5 MB	30.9 MB	88%	3.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 4 00:01:01 - 00:59:59	205	2.7%	0.06	634.6 KB	2.3%	0.18
Sep 4 01:00:00 - 01:59:59	206	2.8%	0.06	701.3 KB	2.5%	0.19
Sep 4 02:00:00 - 02:59:59	293	3.9%	0.08	933.1 KB	3.3%	0.26
Sep 4 03:00:00 - 03:59:59	362	4.8%	0.10	1.3 MB	4.7%	0.37
Sep 4 04:00:00 - 04:59:59	351	4.7%	0.10	1.5 MB	5.6%	0.43
Sep 4 05:00:00 - 05:59:59	330	4.4%	0.09	1.0 MB	3.8%	0.29
Sep 4 06:00:00 - 06:59:59	336	4.5%	0.09	1.1 MB	3.9%	0.30
Sep 4 07:00:00 - 07:59:59	410	5.5%	0.11	1.4 MB	5.0%	0.39
Sep 4 08:00:00 - 08:59:59	423	5.7%	0.12	1.9 MB	6.8%	0.53
Sep 4 09:00:00 - 09:59:59	407	5.5%	0.11	1.6 MB	5.9%	0.46
Sep 4 10:00:00 - 10:59:59	436	5.8%	0.12	1.7 MB	6.3%	0.49
Sep 4 11:00:00 - 11:59:59	536	7.2%	0.15	1.8 MB	6.4%	0.50
Sep 4 12:00:00 - 12:59:59	399	5.3%	0.11	1.3 MB	4.8%	0.38
Sep 4 13:00:00 - 13:59:59	354	4.7%	0.10	1.1 MB	4.1%	0.32
Sep 4 14:00:00 - 14:59:59	386	5.2%	0.11	1.5 MB	5.4%	0.42
Sep 4 15:00:00 - 15:59:59	394	5.3%	0.11	1.4 MB	5.0%	0.39
Sep 4 16:00:00 - 16:59:59	431	5.8%	0.12	1.4 MB	5.2%	0.41
Sep 4 17:00:00 - 17:59:59	306	4.1%	0.09	1.5 MB	5.5%	0.43
Sep 4 18:00:00 - 18:59:59	221	3.0%	0.06	920.3 KB	3.3%	0.26
Sep 4 19:00:00 - 19:59:59	179	2.4%	0.05	887.6 KB	3.2%	0.25
Sep 4 20:00:00 - 20:59:59	116	1.6%	0.03	445.0 KB	1.6%	0.12
Sep 4 21:00:00 - 21:59:59	126	1.7%	0.04	460.9 KB	1.6%	0.13
Sep 4 22:00:00 - 22:59:59	79	1.1%	0.02	341.5 KB	1.2%	0.09
Sep 4 23:00:00 - 23:59:59	171	2.3%	0.05	638.6 KB	2.3%	0.18
Sep 5 00:00:00 - 00:01:01	7	0.1%	0.11	63.8 KB	0.2%	1.05
TOTAL: 24:00:00	7464	100.0%	0.09	27.3 MB	100.0%	0.32

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.1 GB	6.3%	0	0.49	37766.84
CYCTXT1	TEXT	5.0 GB	3.8 GB	76.8%	0	0.03	1866.80
TOTAL: 3	-	3077.0 GB	100.9 GB	3.3%	1	0.35	-

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	usenet.blueworldhosting.com	425	0	0	1	424
2	endofthelinebbs-out.news.weretis.net	247	0	1	0	246
3	outgoing.news.lysator.liu.se	183	0	0	0	183
4	news.hispagatos.org	140	11	0	0	129
5	nntp.comgw.net	103	0	1	0	102
6	usenet.goja.nl.eu.org	68	0	0	0	68
7	endofthelinebbs.peers.news.panix.com	24	0	0	0	24
8	news.netfront.net	20	0	0	0	20
9	newsfeed.bofh.team	19	0	0	0	19
10	i2pn.org	10	1	0	0	9
11	usenet.network	4	0	0	0	4
12	news.furie.org.uk	1	0	0	0	1
13	news.nntp4.net	1	0	0	0	1
14	news.samoylyk.net	1	0	0	0	1
15	photonic.trudheim.com	1	0	0	0	1
TOTAL: 15		1247	12	2	1	1232

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.hacking.news	9
hispagatos.hacking.ctf	2
hispagatos.hacking.exploits	1
TOTAL: 3	12

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 (phn path)	320
EMP (phr path)	300
EMP (md5)	297
Too many newsgroups	145
EMP (phl)	64
User-issued cancel	15
HTML Multipart	10
Subject (LSD "flyin)	1
TOTAL: 8	1152

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
i2pn2-nocem@i2pn2.org (spam)	1	1	0	1
i2pn2-nocem@i2pn2.org (multipost)	43	0	123	123
i2pn2-nocem@i2pn2.org (rmp)	31	0	94	94
nocem@usenet.ovh (faqfr)	2	0	9	9
robot@pasdenom.info (spam2)	2	0	2	2
TOTAL: 5	79	1	228	229

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	usenet.blueworldhosting.com	34
	endofthelinebbs-out.news.weretis.net	31
	usenet.goja.nl.eu.org	5
	endofthelinebbs.peers.news.panix.com	5
	nntp.comgw.net	1
	i2pn.org	1
	outgoing.news.lysator.liu.se	1
	news.hispagatos.org	1
	newsfeed.bofh.team	1
	TOTAL: 9	80
TOTAL: 1		80

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.netfront.net	10472	3824	3624	12	5	36%	23:59:38
2	nyheter.lysator.liu.se	5806	1235	3755	643	2	21%	23:59:34
3	photonic.trudheim.com	7920	617	5913	928	0	7%	23:59:38
4	news.furie.org.uk	4648	613	3135	19	0	13%	23:59:38
5	endofthelinebbs.peers.news.panix.com	7797	476	6978	10	37	6%	23:59:38
6	news.hispagatos.org	8287	343	6359	276	5	4%	23:59:38
7	usenet.network	7200	335	5839	31	2	4%	23:59:38
8	news.bbs.nz	13449	261	6579	601	18	1%	23:59:38
9	usenet.goja.nl.eu.org	8120	250	6493	256	0	3%	23:59:38
10	newsfeed.bofh.team	8384	219	7012	1	2	2%	23:59:35
11	news.nk.ca	11216	210	6605	582	275	1%	23:59:38
12	news.corradoroberto.it	1323	128	934	14	0	9%	22:30:02
13	i2pn.org	8047	120	7032	27	16	1%	23:59:38
14	news.nntp4.net	8505	119	7253	52	0	1%	23:59:36
15	news.weretis.net	7735	108	7169	181	3	1%	23:59:38
16	news.tnetconsulting.net	8247	94	7093	273	1	1%	23:59:39
17	nntp.comgw.net	8125	93	6811	124	0	1%	23:59:38
18	news.quux.org	7854	76	6824	556	1	0%	23:59:38
19	newsfeed.xs3.de	8757	58	7121	166	0	0%	23:59:38
20	news.chmurka.net	4127	38	3445	1	0	0%	23:52:36
21	news.samoylyk.net	8601	22	7339	36	1351	0%	23:45:53
22	usenet.blueworldhosting.com	6734	0	6355	0	1	0%	23:59:38
23	feed.opticnetworks.net	0	0	0	0	7464	0%	23:59:38
TOTAL: 23		171354	9239	129668	4789	9183	5%	550:01:03

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.netfront.net	14.6 MB	33.4 KB	14.6 MB	0.2 KB/s	3.9 KB	23:59:38
2	photonic.trudheim.com	4.4 MB	2.7 MB	7.1 MB	0.1 KB/s	4.7 KB	23:59:38
3	news.furie.org.uk	4.3 MB	169.7 KB	4.5 MB	0.1 KB/s	7.3 KB	23:59:38
4	nyheter.lysator.liu.se	3.7 MB	5.7 MB	9.4 MB	0.1 KB/s	5.1 KB	23:59:34
5	endofthelinebbs.peers.news.panix.com	3.1 MB	26.3 KB	3.1 MB	0.0 KB/s	6.5 KB	23:59:38
6	newsfeed.bofh.team	1.7 MB	2.6 KB	1.7 MB	0.0 KB/s	8.1 KB	23:59:35
7	news.hispagatos.org	1.2 MB	2.3 MB	3.5 MB	0.0 KB/s	5.8 KB	23:59:38
8	usenet.network	1.2 MB	55.3 KB	1.3 MB	0.0 KB/s	3.6 KB	23:59:38
9	news.bbs.nz	718.6 KB	4.4 MB	5.1 MB	0.1 KB/s	6.0 KB	23:59:38
10	usenet.goja.nl.eu.org	641.1 KB	2.6 MB	3.3 MB	0.0 KB/s	6.6 KB	23:59:38
11	news.tnetconsulting.net	526.9 KB	2.3 MB	2.8 MB	0.0 KB/s	7.8 KB	23:59:39
12	news.quux.org	461.2 KB	4.0 MB	4.5 MB	0.1 KB/s	7.2 KB	23:59:38
13	i2pn.org	444.7 KB	128.9 KB	573.7 KB	0.0 KB/s	3.9 KB	23:59:38
14	news.nntp4.net	440.0 KB	615.9 KB	1.0 MB	0.0 KB/s	6.2 KB	23:59:36
15	news.nk.ca	433.1 KB	4.4 MB	4.9 MB	0.1 KB/s	6.3 KB	23:59:38
16	news.weretis.net	411.4 KB	2.0 MB	2.4 MB	0.0 KB/s	8.5 KB	23:59:38
17	nntp.comgw.net	405.5 KB	1.3 MB	1.7 MB	0.0 KB/s	8.2 KB	23:59:38
18	news.corradoroberto.it	349.6 KB	162.6 KB	512.2 KB	0.0 KB/s	3.6 KB	22:30:02
19	newsfeed.xs3.de	339.3 KB	1.5 MB	1.9 MB	0.0 KB/s	8.5 KB	23:59:38
20	news.chmurka.net	102.5 KB	1.8 KB	104.3 KB	0.0 KB/s	2.7 KB	23:52:36
21	news.samoylyk.net	42.5 KB	106.6 KB	149.1 KB	0.0 KB/s	2.6 KB	23:45:53
22	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:38
23	usenet.blueworldhosting.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:38
TOTAL: 23		39.5 MB	34.5 MB	74.0 MB	0.0 KB/s	5.4 KB	550:01:03

NNRP readership statistics:

System		Conn	Elapsed
1	ddt.demos.su	2753	00:29:12
2	167.94.146.53	3	00:00:03
3	198.235.24.13	1	00:00:10
4	205.210.31.137	1	00:00:10
5	azpdeg11.stretchoid.com	1	00:00:00
6	ec2-18-237-198-114.us-west-2.compute.amazonaws.com	1	00:00:00
7	energetic.monitoring.internet-measurement.com	1	00:00:01
8	localhost	1	00:11:22
9	sh-phx-us-gp1-wk110.internet-census.org	1	00:00:10
TOTAL: 9		2763	00:41:11

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.demos.su	2753	00:29:12
2	unresolved	5	00:00:23
3	*.internet-census.org	1	00:00:10
4	*.monitoring.internet-measurement.com	1	00:00:01
5	*.stretchoid.com	1	00:00:00
6	*.us-west-2.compute.amazonaws.com	1	00:00:00
7	?	1	00:11:22
TOTAL: 7		2763	00:41:11

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.848	36.344	0.000	00:29:12
localhost	186.210	370.339	0.076	00:11:22
sh-phx-us-gp1-wk110.internet-census.org	0.003	0.013	0.000	00:00:10
205.210.31.137	0.005	0.010	0.000	00:00:10
198.235.24.13	0.000	0.016	0.000	00:00:10
167.94.146.53	0.018	0.044	0.000	00:00:03
energetic.monitoring.internet-measurement.com	0.004	0.012	0.000	00:00:01
azpdeg11.stretchoid.com	0.000	0.020	0.000	00:00:00
ec2-18-237-198-114.us-west-2.compute.amazonaws.com	0.000	0.021	0.000	00:00:00
TOTAL: 9	196.088	406.819	0.076	00:41:11

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

System	Conn
167.94.146.53	1
azpdeg11.stretchoid.com	1
TOTAL: 2	2

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

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
167.94.146.53	1	2
TOTAL: 1	1	2

Daily Usenet Report

Sep 4 00:01:01 -- Sep 5 00:01:01