Daily Usenet Report: Sep 18 00:01:01

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Sep 18 08:11:53 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Sep 18 13:26:45 newsfeed nnrpd[1670980]: 47.186.47.228 auth: program exited with status 8
Sep 19 00:00:01 newsfeed nnrpd[1830459]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	8549	28.9%	903.2 KB	13.7%
inn	8494	28.7%	3.6 MB	55.6%
innd	7931	26.8%	1.1 MB	17.6%
innfeed	4100	13.9%	793.9 KB	12.0%
cnfsstat	432	1.5%	62.9 KB	1.0%
nocem	74	0.3%	9.6 KB	0.1%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 7	29581	100.0%	6.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	124899	60.5%
Negative hits	73187	35.5%
Do not exist	8327	4.0%
Cache misses	2	0.0%
TOTAL: 4	206415	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.284	0.0%	45	0.000	6.311	22.000
article cleanup	00:00:00.094	0.0%	8404	0.000	0.011	0.100
article logging	00:00:00.428	0.0%	8489	0.000	0.050	0.361
article parse	00:00:00.195	0.0%	12417	0.000	0.016	0.100
article write	00:00:02.530	0.0%	7432	0.000	0.340	6.280
artlog/artcncl	00:00:00.000	0.0%	11	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:02.120	0.0%	210180	0.000	0.010	0.393
hisgrep/artcncl	00:00:00.219	0.0%	47	0.000	4.660	19.000
hishave/artcncl	00:00:00.000	0.0%	11	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:34.175	0.2%	208151	0.087	0.741	4.425
history sync	00:00:00.493	0.0%	399	0.000	1.236	104.250
history write	00:02:08.614	0.1%	8399	3.017	15.313	156.350
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:47:55.757	99.1%	209476	81.290	409.000	1857.938
nntp read	00:00:04.458	0.0%	211006	0.003	0.021	0.418
overview write	00:06:50.619	0.5%	7432	0.840	55.250	5437.529
perl filter	00:00:29.324	0.0%	8374	1.571	3.502	34.136
python filter	00:00:01.002	0.0%	8374	0.000	0.120	1.750
site send	00:00:01.203	0.0%	14931	0.000	0.081	0.241
TOTAL: 24:01:04.301	24:00:11.515	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.020	0.0%	7676	0.000	0.003	0.043
article prepare	00:00:00.007	0.0%	46620	0.000	0.000	0.003
article read	00:00:00.540	0.0%	3862	0.000	0.140	0.444
backlog stats	00:00:00.393	0.0%	269033	0.000	0.001	0.008
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.486	0.0%	187708	0.002	0.013	0.049
data write	00:00:03.366	0.0%	182735	0.009	0.018	0.039
idle	23:58:19.395	100.0%	269033	165.115	320.776	1305.954
status file	00:00:06.732	0.0%	265	0.750	25.404	1607.750
TOTAL: 23:58:45.189	23:58:32.939	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	26:32:52.700	98.8%	10206	0.500	9364.364	50299.195
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.264	0.0%	99211	0.000	0.043	0.333
readart	00:00:01.006	0.0%	28	35.929	35.929	35.929
TOTAL: 26:52:46.325	26:32:57.970	98.8%	-	-	-	-

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	endofthelinebbs-out.news.weretis.net	5	14125	2219	11634	272	15%	47:22:38
2	outgoing.news.lysator.liu.se	1	6070	1719	4330	21	28%	23:59:57
3	usenet.blueworldhosting.com	718	8186	812	6951	423	9%	23:55:02
4	news.hispagatos.org	10	7588	523	7036	29	6%	24:52:36
5	news.corradoroberto.it	6	1496	360	1136	0	24%	22:02:18
6	i2pn.org	1	7513	306	7183	24	4%	23:59:56
7	nntp.comgw.net	2	7799	284	7460	55	3%	23:59:07
8	newsfeed.bofh.team	2	8116	248	7855	13	3%	23:59:48
9	endoftheline-out.news.chmurka.net	1	3912	231	3681	0	5%	23:59:55
10	usenet.goja.nl.eu.org	11	7700	203	7483	14	2%	24:21:27
11	usenet.network	2	5515	153	5359	3	2%	23:58:37
12	endofthelinebbs.peers.news.panix.com	25	7806	129	7662	15	1%	23:53:46
13	news.samoylyk.net	1	7009	97	6911	1	1%	23:59:45
14	news.netfront.net	1	5387	43	5242	102	0%	22:58:35
15	news.nntp4.net	2	6784	29	6755	0	0%	23:59:36
16	news.furie.org.uk	4	7459	15	7442	2	0%	47:57:18
17	glockenspiel.complete.org	2	5676	13	5661	2	0%	23:58:34
18	newsfeed.xs3.de	1	6324	9	6315	0	0%	23:59:55
19	news.bbs.nz	1	6385	8	6377	0	0%	23:59:35
20	photonic.trudheim.com	2	1307	0	1307	0	0%	17:21:02
21	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:05
22	localhost	31	0	0	0	0	0%	00:00:00
23	news.nk.ca	2	0	0	0	0	0%	00:20:18
TOTAL: 23		832	132157	7401	123780	976	5%	519:09:50

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	endofthelinebbs-out.news.weretis.net	6.7 MB	0.0 KB	611.9 KB	7.3 MB	91%	3.0 KB
2	outgoing.news.lysator.liu.se	4.5 MB	5.8 KB	19.9 KB	4.6 MB	99%	2.7 KB
3	news.corradoroberto.it	2.1 MB	0.0 KB	0.0 KB	2.1 MB	100%	6.1 KB
4	usenet.blueworldhosting.com	2.0 MB	0.0 KB	1.2 MB	3.2 MB	63%	2.7 KB
5	usenet.network	1.8 MB	0.0 KB	2.6 KB	1.8 MB	99%	11.9 KB
6	news.samoylyk.net	1.7 MB	0.0 KB	1.2 KB	1.7 MB	99%	17.3 KB
7	news.hispagatos.org	1.6 MB	0.0 KB	52.7 KB	1.7 MB	96%	3.1 KB
8	nntp.comgw.net	1.2 MB	0.0 KB	219.1 KB	1.4 MB	84%	4.2 KB
9	endoftheline-out.news.chmurka.net	947.0 KB	0.0 KB	0.0 KB	947.0 KB	100%	4.1 KB
10	i2pn.org	942.4 KB	0.0 KB	39.1 KB	981.5 KB	96%	3.0 KB
11	newsfeed.bofh.team	613.2 KB	0.0 KB	20.6 KB	633.8 KB	96%	2.4 KB
12	usenet.goja.nl.eu.org	584.0 KB	0.0 KB	19.2 KB	603.2 KB	96%	2.8 KB
13	endofthelinebbs.peers.news.panix.com	298.7 KB	0.0 KB	20.5 KB	319.2 KB	93%	2.2 KB
14	glockenspiel.complete.org	133.8 KB	1.9 KB	0.8 KB	136.5 KB	98%	9.1 KB
15	newsfeed.xs3.de	83.2 KB	0.0 KB	0.0 KB	83.2 KB	100%	9.2 KB
16	news.netfront.net	71.6 KB	6.8 KB	574.6 KB	653.1 KB	10%	4.5 KB
17	news.nntp4.net	71.1 KB	0.0 KB	0.0 KB	71.1 KB	100%	2.5 KB
18	news.furie.org.uk	48.1 KB	0.0 KB	1.3 KB	49.4 KB	97%	2.9 KB
19	news.bbs.nz	15.2 KB	0.0 KB	0.0 KB	15.2 KB	100%	1.9 KB
20	eotl-out.news.tnetconsulting.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
21	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
22	news.nk.ca	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
23	photonic.trudheim.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 23		25.4 MB	14.5 KB	2.7 MB	28.1 MB	90%	3.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 18 00:01:01 - 00:59:59	163	2.2%	0.05	458.0 KB	1.8%	0.13
Sep 18 01:00:00 - 01:59:59	278	3.7%	0.08	962.8 KB	3.7%	0.27
Sep 18 02:00:00 - 02:59:59	308	4.1%	0.09	800.2 KB	3.1%	0.22
Sep 18 03:00:00 - 03:59:59	337	4.5%	0.09	1.1 MB	4.5%	0.32
Sep 18 04:00:00 - 04:59:59	394	5.3%	0.11	1.4 MB	5.6%	0.40
Sep 18 05:00:00 - 05:59:59	363	4.9%	0.10	1017.7 KB	3.9%	0.28
Sep 18 06:00:00 - 06:59:59	292	3.9%	0.08	828.1 KB	3.2%	0.23
Sep 18 07:00:00 - 07:59:59	327	4.4%	0.09	1.1 MB	4.3%	0.31
Sep 18 08:00:00 - 08:59:59	452	6.1%	0.13	1.6 MB	6.2%	0.44
Sep 18 09:00:00 - 09:59:59	448	6.0%	0.12	1.7 MB	6.6%	0.48
Sep 18 10:00:00 - 10:59:59	453	6.1%	0.13	1.7 MB	6.6%	0.48
Sep 18 11:00:00 - 11:59:59	466	6.3%	0.13	1.6 MB	6.3%	0.45
Sep 18 12:00:00 - 12:59:59	448	6.0%	0.12	1.6 MB	6.2%	0.44
Sep 18 13:00:00 - 13:59:59	390	5.3%	0.11	1.4 MB	5.5%	0.40
Sep 18 14:00:00 - 14:59:59	440	5.9%	0.12	1.7 MB	6.6%	0.47
Sep 18 15:00:00 - 15:59:59	397	5.3%	0.11	1.5 MB	6.1%	0.44
Sep 18 16:00:00 - 16:59:59	263	3.5%	0.07	924.7 KB	3.6%	0.26
Sep 18 17:00:00 - 17:59:59	286	3.9%	0.08	1.2 MB	4.8%	0.35
Sep 18 18:00:00 - 18:59:59	199	2.7%	0.06	730.1 KB	2.8%	0.20
Sep 18 19:00:00 - 19:59:59	139	1.9%	0.04	420.9 KB	1.6%	0.12
Sep 18 20:00:00 - 20:59:59	156	2.1%	0.04	576.0 KB	2.2%	0.16
Sep 18 21:00:00 - 21:59:59	105	1.4%	0.03	300.5 KB	1.2%	0.08
Sep 18 22:00:00 - 22:59:59	127	1.7%	0.04	436.0 KB	1.7%	0.12
Sep 18 23:00:00 - 23:59:59	190	2.6%	0.05	527.1 KB	2.0%	0.15
Sep 19 00:00:00 - 00:01:01	4	0.1%	0.07	10.5 KB	0.0%	0.17
TOTAL: 24:00:00	7425	100.0%	0.09	25.3 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.6 GB	6.4%	0	0.47	39755.79
CYCTXT1	TEXT	5.0 GB	3.9 GB	77.3%	0	0.03	1777.78
TOTAL: 3	-	3077.0 GB	101.5 GB	3.3%	1	0.33	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	444	1	0	443
2	endofthelinebbs-out.news.weretis.net	331	0	0	331
3	news.netfront.net	92	0	4	88
4	nntp.comgw.net	57	0	0	57
5	news.hispagatos.org	30	16	0	14
6	i2pn.org	24	0	0	24
7	outgoing.news.lysator.liu.se	22	0	1	21
8	endofthelinebbs.peers.news.panix.com	21	0	0	21
9	newsfeed.bofh.team	15	0	0	15
10	usenet.goja.nl.eu.org	13	0	0	13
11	glockenspiel.complete.org	3	0	0	3
12	usenet.network	3	0	0	3
13	news.furie.org.uk	2	0	0	2
14	news.samoylyk.net	1	0	0	1
TOTAL: 14		1058	17	5	1036

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.hacking.news	8
hispagatos.hacking.ctf	6
hispagatos.hacking.exploits	2
soc.culture.africa	1
TOTAL: 4	17

INND Perl filter [Top 20]:

Reason	Count
EMP (phr path)	344
EMP (phn path)	213
EMP (md5)	161
Too many newsgroups	123
Too many newsgroups (meow)	42
User-issued cancel	39
Binary: misplaced binary	3
HTML Multipart	1
TOTAL: 8	926

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
i2pn2-nocem@i2pn2.org (spam)	6	9	0	9
i2pn2-nocem@i2pn2.org (multipost)	42	0	151	151
i2pn2-nocem@i2pn2.org (rmp)	15	0	68	68
robot@pasdenom.info (spam3)	4	0	6	6
TOTAL: 4	67	9	225	234

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	usenet.blueworldhosting.com	1
	TOTAL: 1	1
Including strange strings
	endofthelinebbs-out.news.weretis.net	55
	usenet.blueworldhosting.com	21
	endofthelinebbs.peers.news.panix.com	5
	nntp.comgw.net	2
	outgoing.news.lysator.liu.se	1
	newsfeed.bofh.team	1
	TOTAL: 6	85
TOTAL: 2		86

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.netfront.net	11651	2450	4933	0	16	21%	23:59:34
2	nyheter.lysator.liu.se	5922	1552	3942	209	0	26%	23:59:51
3	news.furie.org.uk	3883	922	2547	21	0	23%	23:59:51
4	photonic.trudheim.com	7790	464	5842	1113	0	5%	23:59:47
5	usenet.network	7990	442	6404	36	0	5%	23:59:46
6	news.hispagatos.org	8012	385	6339	118	0	4%	23:58:17
7	endofthelinebbs.peers.news.panix.com	7666	299	7112	4	0	3%	23:59:51
8	usenet.goja.nl.eu.org	8401	295	6804	81	0	3%	23:59:51
9	news.bbs.nz	13479	259	6787	365	80	1%	23:59:51
10	news.nk.ca	10997	202	6738	414	65	1%	23:59:51
11	newsfeed.bofh.team	8307	178	6943	1	0	2%	23:59:51
12	i2pn.org	8027	154	6905	36	42	1%	23:59:29
13	news.nntp4.net	8384	135	7182	25	0	1%	23:59:51
14	news.tnetconsulting.net	8267	124	7159	131	10	1%	23:59:51
15	news.corradoroberto.it	1276	118	973	2	0	9%	23:04:26
16	news.weretis.net	7669	112	7262	50	8	1%	23:59:51
17	nntp.comgw.net	7961	107	6974	39	0	1%	23:59:51
18	news.quux.org	7847	104	6941	361	3	1%	23:59:42
19	newsfeed.xs3.de	8765	63	7193	129	0	0%	23:58:48
20	news.chmurka.net	4414	42	3484	6	0	0%	23:58:54
21	news.samoylyk.net	8943	23	7219	83	34	0%	23:59:51
22	usenet.blueworldhosting.com	6733	2	6365	0	0	0%	23:59:36
23	feed.opticnetworks.net	0	0	0	0	7425	0%	23:59:51
TOTAL: 23		172384	8432	132048	3224	7683	4%	550:56:22

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.netfront.net	11.0 MB	0.0 KB	11.0 MB	0.1 KB/s	4.6 KB	23:59:34
2	news.furie.org.uk	5.0 MB	267.0 KB	5.2 MB	0.1 KB/s	5.7 KB	23:59:51
3	nyheter.lysator.liu.se	4.9 MB	1.7 MB	6.6 MB	0.1 KB/s	3.8 KB	23:59:51
4	photonic.trudheim.com	2.1 MB	3.2 MB	5.3 MB	0.1 KB/s	3.4 KB	23:59:47
5	news.hispagatos.org	1.6 MB	775.9 KB	2.3 MB	0.0 KB/s	4.8 KB	23:58:17
6	usenet.network	1.4 MB	85.2 KB	1.5 MB	0.0 KB/s	3.2 KB	23:59:46
7	endofthelinebbs.peers.news.panix.com	1.4 MB	51.6 KB	1.4 MB	0.0 KB/s	4.7 KB	23:59:51
8	newsfeed.bofh.team	1.1 MB	2.7 KB	1.1 MB	0.0 KB/s	6.2 KB	23:59:51
9	news.quux.org	1.0 MB	1.5 MB	2.5 MB	0.0 KB/s	5.6 KB	23:59:42
10	news.bbs.nz	886.0 KB	2.2 MB	3.0 MB	0.0 KB/s	5.0 KB	23:59:51
11	usenet.goja.nl.eu.org	744.3 KB	858.8 KB	1.6 MB	0.0 KB/s	4.3 KB	23:59:51
12	news.nntp4.net	648.3 KB	195.9 KB	844.2 KB	0.0 KB/s	5.3 KB	23:59:51
13	news.tnetconsulting.net	619.2 KB	665.6 KB	1.3 MB	0.0 KB/s	5.0 KB	23:59:51
14	nntp.comgw.net	585.7 KB	767.4 KB	1.3 MB	0.0 KB/s	9.3 KB	23:59:51
15	news.weretis.net	571.9 KB	1.9 MB	2.5 MB	0.0 KB/s	15.5 KB	23:59:51
16	news.nk.ca	529.7 KB	2.0 MB	2.6 MB	0.0 KB/s	4.2 KB	23:59:51
17	newsfeed.xs3.de	524.3 KB	932.6 KB	1.4 MB	0.0 KB/s	7.6 KB	23:58:48
18	i2pn.org	512.5 KB	190.9 KB	703.4 KB	0.0 KB/s	3.7 KB	23:59:29
19	news.samoylyk.net	405.7 KB	340.3 KB	746.0 KB	0.0 KB/s	7.0 KB	23:59:51
20	news.corradoroberto.it	305.4 KB	5.8 KB	311.2 KB	0.0 KB/s	2.6 KB	23:04:26
21	news.chmurka.net	154.7 KB	14.0 KB	168.6 KB	0.0 KB/s	3.5 KB	23:58:54
22	usenet.blueworldhosting.com	3.6 KB	0.0 KB	3.6 KB	0.0 KB/s	1.8 KB	23:59:36
23	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:51
TOTAL: 23		35.7 MB	17.6 MB	53.3 MB	0.0 KB/s	4.7 KB	550:56:22

NNRP readership statistics:

System		Conn	Arts	Size	Elapsed
1	47.186.47.228	1	4	13.7 KB	26:10:14
2	ddt.demos.su	2776	0	0.0 KB	00:29:41
3	141.98.11.195	1	0	0.0 KB	00:00:00
4	162.142.125.216	1	0	0.0 KB	00:00:00
5	162.142.125.221	1	0	0.0 KB	00:00:00
6	198.235.24.109	1	0	0.0 KB	00:00:10
7	205.210.31.13	1	0	0.0 KB	00:00:10
8	206.168.34.44	1	0	0.0 KB	00:00:10
9	amicable.monitoring.internet-measurement.com	1	0	0.0 KB	00:00:02
10	azpdegc29.stretchoid.com	1	0	0.0 KB	00:00:00
11	localhost	1	0	0.0 KB	00:12:09
12	server-0-2.survey.inspici.com	1	0	0.0 KB	00:00:03
TOTAL: 12		2787	4	13.7 KB	26:52:44

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Elapsed
1	unresolved	7	4	13.7 KB	26:10:46
2	*.demos.su	2776	0	0.0 KB	00:29:41
3	*.monitoring.internet-measurement.com	1	0	0.0 KB	00:00:02
4	*.stretchoid.com	1	0	0.0 KB	00:00:00
5	*.survey.inspici.com	1	0	0.0 KB	00:00:03
6	?	1	0	0.0 KB	00:12:09
TOTAL: 6		2787	4	13.7 KB	26:52:44

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
47.186.47.228	0.050	0.258	0.715	26:10:14
ddt.demos.su	9.894	36.451	0.000	00:29:41
localhost	184.181	372.069	0.069	00:12:09
206.168.34.44	0.019	0.009	0.000	00:00:10
198.235.24.109	0.000	0.016	0.000	00:00:10
205.210.31.13	0.000	0.016	0.000	00:00:10
server-0-2.survey.inspici.com	0.004	0.013	0.000	00:00:03
amicable.monitoring.internet-measurement.com	0.006	0.011	0.000	00:00:02
141.98.11.195	0.004	0.013	0.000	00:00:00
162.142.125.221	0.016	0.012	0.000	00:00:00
162.142.125.216	0.016	0.012	0.000	00:00:00
azpdegc29.stretchoid.com	0.004	0.012	0.000	00:00:00
TOTAL: 12	194.194	408.892	0.784	26:52:44

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

System	Conn
141.98.11.195	1
162.142.125.216	1
162.142.125.221	1
206.168.34.44	1
azpdegc29.stretchoid.com	1
TOTAL: 5	5

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

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
141.98.11.195	1	1
TOTAL: 1	1	1

Daily Usenet Report

Sep 18 00:01:01 -- Sep 19 00:01:01