Daily Usenet Report: Sep 7 00:01:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Sep 8 00:00:02 newsfeed nnrpd[685370]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	8317	28.9%	862.4 KB	13.9%
innd	8070	28.0%	1.1 MB	18.8%
inn	7804	27.1%	3.2 MB	53.1%
innfeed	4139	14.4%	812.9 KB	13.1%
cnfsstat	432	1.5%	63.0 KB	1.0%
nocem	65	0.2%	8.2 KB	0.1%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 7	28828	100.0%	6.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	109032	60.5%
Negative hits	63319	35.2%
Do not exist	7733	4.3%
Cache misses	4	0.0%
TOTAL: 4	180088	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.314	0.0%	96	0.000	3.271	21.000
article cleanup	00:00:00.079	0.0%	7759	0.000	0.010	0.097
article logging	00:00:00.510	0.0%	7816	0.000	0.065	2.556
article parse	00:00:00.180	0.0%	11483	0.000	0.016	0.143
article write	00:00:02.156	0.0%	6660	0.000	0.324	4.553
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.968	0.0%	182907	0.000	0.005	0.210
hisgrep/artcncl	00:00:00.267	0.0%	100	0.000	2.670	21.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:20.247	0.2%	180520	0.098	0.777	4.486
history sync	00:00:00.083	0.0%	406	0.000	0.204	1.000
history write	00:01:53.828	0.1%	7755	3.226	14.678	113.474
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:43:40.645	98.8%	182362	65.606	468.413	1566.193
nntp read	00:00:03.893	0.0%	184055	0.002	0.021	0.523
overview write	00:12:05.778	0.8%	6660	0.615	108.976	8282.026
perl filter	00:00:27.742	0.0%	7755	1.459	3.577	32.623
python filter	00:00:02.496	0.0%	7755	0.000	0.322	7.905
site send	00:00:01.108	0.0%	13384	0.000	0.083	0.269
TOTAL: 24:01:30.459	24:00:40.294	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.028	0.0%	6867	0.000	0.004	0.088
article prepare	00:00:00.003	0.0%	39068	0.000	0.000	0.003
article read	00:00:00.442	0.0%	3113	0.000	0.142	0.600
backlog stats	00:00:00.325	0.0%	238356	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.208	0.0%	164828	0.001	0.013	0.055
data write	00:00:02.884	0.0%	161367	0.009	0.018	0.031
idle	23:49:46.257	100.0%	238356	181.401	359.908	1167.330
status file	00:00:00.335	0.0%	285	0.667	1.175	11.000
TOTAL: 23:50:01.289	23:49:52.482	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:22:26.272	53.4%	8251	0.000	163.165	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.455	0.2%	95319	0.000	0.047	2.000
TOTAL: 00:42:02.668	00:22:30.727	53.5%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	endofthelinebbs-out.news.weretis.net	4	13202	1731	11184	287	13%	51:53:17
2	outgoing.news.lysator.liu.se	1	5877	1307	4524	46	22%	23:59:57
3	usenet.blueworldhosting.com	720	7527	976	6032	519	12%	23:56:35
4	news.hispagatos.org	5	6973	608	6321	44	8%	24:26:22
5	usenet.goja.nl.eu.org	10	6733	376	6333	24	5%	22:22:43
6	nntp.comgw.net	2	7084	321	6686	77	4%	23:59:56
7	news.corradoroberto.it	11	1058	295	763	0	27%	23:49:17
8	newsfeed.bofh.team	2	7559	251	7305	3	3%	23:59:45
9	i2pn.org	2	6909	247	6637	25	3%	23:58:59
10	usenet.network	2	5268	194	5073	1	3%	23:59:48
11	endofthelinebbs.peers.news.panix.com	28	7073	184	6872	17	2%	24:24:06
12	endoftheline-out.news.chmurka.net	1	3478	128	3349	1	3%	23:59:55
13	newsfeed.xs3.de	2	5349	90	5257	2	1%	23:59:20
14	news.samoylyk.net	2	6153	70	6081	2	1%	23:59:45
15	news.nntp4.net	1	5922	21	5900	1	0%	23:59:51
16	glockenspiel.complete.org	3	5405	21	5382	2	0%	23:25:13
17	news.netfront.net	1	5336	12	5266	58	0%	19:38:33
18	news.bbs.nz	1	6612	10	6602	0	0%	23:59:33
19	news.furie.org.uk	2	3581	1	3579	1	0%	23:59:44
20	photonic.trudheim.com	3	1660	1	1659	0	0%	23:58:30
21	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:04
22	localhost	26	0	0	0	0	0%	00:00:00
23	news.nk.ca	2	0	0	0	0	0%	00:20:12
TOTAL: 23		832	118759	6844	110805	1110	5%	502:21:25

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	endofthelinebbs-out.news.weretis.net	5.6 MB	8.8 KB	507.2 KB	6.1 MB	91%	3.1 KB
2	outgoing.news.lysator.liu.se	3.8 MB	0.0 KB	65.4 KB	3.9 MB	98%	3.0 KB
3	usenet.blueworldhosting.com	2.5 MB	0.0 KB	1.4 MB	3.9 MB	64%	2.7 KB
4	news.corradoroberto.it	2.2 MB	0.0 KB	0.0 KB	2.2 MB	100%	7.8 KB
5	nntp.comgw.net	2.2 MB	0.0 KB	204.5 KB	2.4 MB	91%	6.1 KB
6	news.hispagatos.org	1.7 MB	0.0 KB	74.7 KB	1.8 MB	95%	2.8 KB
7	usenet.network	1.3 MB	0.0 KB	1.5 KB	1.3 MB	99%	6.7 KB
8	usenet.goja.nl.eu.org	1.1 MB	0.0 KB	25.7 KB	1.1 MB	97%	2.9 KB
9	i2pn.org	882.9 KB	1.9 KB	36.7 KB	921.4 KB	95%	3.4 KB
10	newsfeed.bofh.team	594.6 KB	1.9 KB	6.7 KB	603.2 KB	98%	2.4 KB
11	news.samoylyk.net	556.2 KB	4.1 KB	0.0 KB	560.3 KB	99%	7.8 KB
12	endoftheline-out.news.chmurka.net	531.3 KB	2.1 KB	0.0 KB	533.4 KB	99%	4.1 KB
13	endofthelinebbs.peers.news.panix.com	426.2 KB	0.0 KB	52.9 KB	479.1 KB	88%	2.4 KB
14	newsfeed.xs3.de	282.8 KB	0.0 KB	2.5 KB	285.3 KB	99%	3.1 KB
15	glockenspiel.complete.org	90.1 KB	2.1 KB	0.8 KB	93.0 KB	96%	4.0 KB
16	news.nntp4.net	52.2 KB	2.1 KB	0.0 KB	54.3 KB	96%	2.5 KB
17	news.bbs.nz	25.5 KB	0.0 KB	0.0 KB	25.5 KB	100%	2.6 KB
18	news.netfront.net	23.2 KB	0.0 KB	172.9 KB	196.1 KB	11%	2.8 KB
19	news.furie.org.uk	3.4 KB	0.0 KB	0.8 KB	4.2 KB	81%	2.1 KB
20	photonic.trudheim.com	2.0 KB	0.0 KB	0.0 KB	2.0 KB	100%	2.0 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.9 MB	23.0 KB	2.5 MB	26.4 MB	90%	3.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 7 00:01:01 - 00:59:59	189	2.8%	0.05	473.3 KB	2.0%	0.13
Sep 7 01:00:00 - 01:59:59	215	3.2%	0.06	629.9 KB	2.6%	0.17
Sep 7 02:00:00 - 02:59:59	375	5.6%	0.10	1.7 MB	7.3%	0.48
Sep 7 03:00:00 - 03:59:59	339	5.1%	0.09	1022.3 KB	4.3%	0.28
Sep 7 04:00:00 - 04:59:59	310	4.7%	0.09	864.1 KB	3.6%	0.24
Sep 7 05:00:00 - 05:59:59	349	5.3%	0.10	987.6 KB	4.1%	0.27
Sep 7 06:00:00 - 06:59:59	315	4.7%	0.09	911.0 KB	3.8%	0.25
Sep 7 07:00:00 - 07:59:59	369	5.6%	0.10	1.0 MB	4.4%	0.29
Sep 7 08:00:00 - 08:59:59	391	5.9%	0.11	1.2 MB	5.2%	0.34
Sep 7 09:00:00 - 09:59:59	346	5.2%	0.10	1.0 MB	4.3%	0.29
Sep 7 10:00:00 - 10:59:59	341	5.1%	0.09	1007.6 KB	4.2%	0.28
Sep 7 11:00:00 - 11:59:59	358	5.4%	0.10	1.3 MB	5.6%	0.37
Sep 7 12:00:00 - 12:59:59	323	4.9%	0.09	1.2 MB	5.1%	0.34
Sep 7 13:00:00 - 13:59:59	388	5.8%	0.11	1.3 MB	5.5%	0.37
Sep 7 14:00:00 - 14:59:59	339	5.1%	0.09	1.1 MB	4.7%	0.31
Sep 7 15:00:00 - 15:59:59	322	4.8%	0.09	1.1 MB	4.6%	0.30
Sep 7 16:00:00 - 16:59:59	231	3.5%	0.06	827.6 KB	3.5%	0.23
Sep 7 17:00:00 - 17:59:59	200	3.0%	0.06	700.7 KB	2.9%	0.19
Sep 7 18:00:00 - 18:59:59	210	3.2%	0.06	757.3 KB	3.2%	0.21
Sep 7 19:00:00 - 19:59:59	177	2.7%	0.05	1.6 MB	6.9%	0.45
Sep 7 20:00:00 - 20:59:59	129	1.9%	0.04	709.4 KB	3.0%	0.20
Sep 7 21:00:00 - 21:59:59	175	2.6%	0.05	906.4 KB	3.8%	0.25
Sep 7 22:00:00 - 22:59:59	99	1.5%	0.03	362.2 KB	1.5%	0.10
Sep 7 23:00:00 - 23:59:59	151	2.3%	0.04	863.4 KB	3.6%	0.24
Sep 8 00:00:00 - 00:01:01	4	0.1%	0.07	9.2 KB	0.0%	0.15
TOTAL: 24:00:00	6645	100.0%	0.08	23.3 MB	100.0%	0.28

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.2 GB	6.3%	0	0.43	43852.11
CYCTXT1	TEXT	5.0 GB	3.8 GB	76.9%	0	0.03	2031.79
TOTAL: 3	-	3077.0 GB	101.1 GB	3.3%	1	0.28	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	538	0	0	538
2	endofthelinebbs-out.news.weretis.net	304	0	1	303
3	nntp.comgw.net	80	0	0	80
4	news.netfront.net	60	0	0	60
5	outgoing.news.lysator.liu.se	48	0	0	48
6	news.hispagatos.org	38	16	0	22
7	usenet.goja.nl.eu.org	29	0	0	29
8	i2pn.org	26	0	1	25
9	endofthelinebbs.peers.news.panix.com	21	1	0	20
10	newsfeed.bofh.team	3	0	1	2
11	newsfeed.xs3.de	3	0	0	3
12	glockenspiel.complete.org	2	0	0	2
13	news.furie.org.uk	1	0	0	1
14	news.samoylyk.net	1	0	1	0
15	usenet.network	1	0	0	1
TOTAL: 15		1155	17	4	1134

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.hacking.ctf	15
hispagatos.hacking.news	1
matteo.salvini.it	1
TOTAL: 3	17

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	419
EMP (phr path)	360
EMP (md5)	170
Too many newsgroups	96
User-issued cancel	14
HTML Multipart	6
Binary: misplaced binary	4
Too many newsgroups (meow)	4
EMP (phr nph)	2
EMP (fsl)	1
hierarchy violation - crosspost outside sdnet	1
TOTAL: 11	1077

NoCeM on Spool:

Issuer (type)	Notices	Skip	Total
i2pn2-nocem@i2pn2.org (rmp)	22	112	112
i2pn2-nocem@i2pn2.org (multipost)	34	107	107
nocem@usenet.ovh (faqfr)	6	6	6
robot@pasdenom.info (spam2)	1	1	1
robot@pasdenom.info (spam3)	1	1	1
TOTAL: 5	64	227	227

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	endofthelinebbs-out.news.weretis.net	23
	usenet.blueworldhosting.com	19
	usenet.goja.nl.eu.org	7
	nntp.comgw.net	2
	endofthelinebbs.peers.news.panix.com	2
	outgoing.news.lysator.liu.se	2
	i2pn.org	1
	newsfeed.xs3.de	1
	TOTAL: 8	57
TOTAL: 1		57

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.netfront.net	10837	1688	4941	4	26	15%	23:59:54
2	nyheter.lysator.liu.se	5583	1253	3680	405	0	22%	23:59:54
3	news.furie.org.uk	3836	429	2750	9	3	11%	23:59:54
4	photonic.trudheim.com	7110	380	5267	975	0	5%	23:58:27
5	usenet.network	7149	355	5609	51	0	4%	23:59:54
6	endofthelinebbs.peers.news.panix.com	6838	353	6281	3	0	5%	23:59:51
7	news.hispagatos.org	7250	345	5490	217	1	4%	23:58:59
8	news.bbs.nz	12043	334	5959	304	80	2%	23:59:54
9	usenet.goja.nl.eu.org	7226	267	5904	76	3	3%	23:59:14
10	news.nk.ca	9973	194	5946	416	37	1%	23:59:54
11	news.nntp4.net	7509	120	6429	49	0	1%	23:59:54
12	newsfeed.bofh.team	7238	116	6177	0	1	1%	23:57:58
13	nntp.comgw.net	7195	102	6218	22	0	1%	23:59:40
14	i2pn.org	7383	99	6221	57	76	1%	23:59:54
15	news.tnetconsulting.net	7264	98	6354	144	13	1%	23:59:38
16	news.weretis.net	6845	92	6467	45	0	1%	23:59:54
17	news.quux.org	6973	68	6166	392	1	0%	23:59:54
18	news.corradoroberto.it	707	62	566	0	1	8%	22:23:47
19	newsfeed.xs3.de	7645	59	6401	60	0	0%	23:59:18
20	news.samoylyk.net	7973	41	6434	92	7	0%	23:59:54
21	news.chmurka.net	3594	34	3107	1	0	0%	23:59:01
22	usenet.blueworldhosting.com	5616	3	5362	0	0	0%	23:59:54
23	feed.opticnetworks.net	0	0	0	0	6645	0%	23:59:54
TOTAL: 23		153787	6492	117729	3322	6894	4%	550:14:35

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.netfront.net	7.6 MB	11.8 KB	7.6 MB	0.1 KB/s	4.6 KB	23:59:54
2	nyheter.lysator.liu.se	4.2 MB	1.6 MB	5.8 MB	0.1 KB/s	3.6 KB	23:59:54
3	news.furie.org.uk	2.5 MB	472.8 KB	3.0 MB	0.0 KB/s	6.9 KB	23:59:54
4	photonic.trudheim.com	2.0 MB	2.8 MB	4.8 MB	0.1 KB/s	3.6 KB	23:58:27
5	usenet.network	1.4 MB	105.3 KB	1.5 MB	0.0 KB/s	3.8 KB	23:59:54
6	news.hispagatos.org	1.4 MB	688.1 KB	2.0 MB	0.0 KB/s	3.7 KB	23:58:59
7	endofthelinebbs.peers.news.panix.com	1.2 MB	2.9 KB	1.2 MB	0.0 KB/s	3.4 KB	23:59:51
8	news.bbs.nz	1.1 MB	1.1 MB	2.2 MB	0.0 KB/s	3.5 KB	23:59:54
9	usenet.goja.nl.eu.org	767.4 KB	306.6 KB	1.0 MB	0.0 KB/s	3.1 KB	23:59:14
10	news.tnetconsulting.net	723.7 KB	432.7 KB	1.1 MB	0.0 KB/s	4.8 KB	23:59:38
11	newsfeed.bofh.team	619.2 KB	0.0 KB	619.2 KB	0.0 KB/s	5.3 KB	23:57:58
12	news.weretis.net	598.5 KB	258.9 KB	857.4 KB	0.0 KB/s	6.3 KB	23:59:54
13	news.nntp4.net	533.4 KB	79.5 KB	612.9 KB	0.0 KB/s	3.6 KB	23:59:54
14	news.quux.org	514.5 KB	971.8 KB	1.5 MB	0.0 KB/s	3.2 KB	23:59:54
15	newsfeed.xs3.de	464.9 KB	194.7 KB	659.5 KB	0.0 KB/s	5.5 KB	23:59:18
16	nntp.comgw.net	461.3 KB	124.8 KB	586.1 KB	0.0 KB/s	4.7 KB	23:59:40
17	news.nk.ca	424.8 KB	1.2 MB	1.6 MB	0.0 KB/s	2.7 KB	23:59:54
18	i2pn.org	329.4 KB	255.2 KB	584.6 KB	0.0 KB/s	3.7 KB	23:59:54
19	news.corradoroberto.it	205.1 KB	0.0 KB	205.1 KB	0.0 KB/s	3.3 KB	22:23:47
20	news.chmurka.net	137.3 KB	9.4 KB	146.6 KB	0.0 KB/s	4.2 KB	23:59:01
21	news.samoylyk.net	122.8 KB	125.9 KB	248.7 KB	0.0 KB/s	1.9 KB	23:59:54
22	usenet.blueworldhosting.com	14.4 KB	0.0 KB	14.4 KB	0.0 KB/s	4.8 KB	23:59:54
23	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:54
TOTAL: 23		27.1 MB	10.6 MB	37.7 MB	0.0 KB/s	3.9 KB	550:14:35

NNRP readership statistics:

System		Conn	Elapsed
1	ddt.demos.su	2749	00:29:31
2	167.94.138.45	3	00:00:04
3	ns31410048.ip-145-239-154.eu	2	00:00:00
4	194.165.16.72	1	00:00:01
5	205.210.31.14	1	00:00:04
6	205.210.31.41	1	00:00:10
7	240e:d9:d800:200::d4	1	00:00:14
8	80.66.76.121	1	00:00:00
9	azpdegb58.stretchoid.com	1	00:00:00
10	localhost	1	00:11:52
TOTAL: 10		2761	00:42:01

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.demos.su	2749	00:29:31
2	unresolved	7	00:00:21
3	*.ip-145-239-154.eu	2	00:00:00
4	?	2	00:12:07
5	*.stretchoid.com	1	00:00:00
TOTAL: 5		2761	00:42:01

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.401	36.493	0.000	00:29:31
localhost	179.581	367.618	0.076	00:11:52
240e:d9:d800:200::d4	0.000	0.016	0.000	00:00:14
205.210.31.41	0.004	0.012	0.000	00:00:10
205.210.31.14	0.000	0.016	0.000	00:00:04
167.94.138.45	0.013	0.047	0.000	00:00:04
194.165.16.72	0.003	0.014	0.000	00:00:01
ns31410048.ip-145-239-154.eu	0.028	0.024	0.000	00:00:00
80.66.76.121	0.007	0.010	0.000	00:00:00
azpdegb58.stretchoid.com	0.000	0.018	0.000	00:00:00
TOTAL: 10	189.037	404.268	0.076	00:42:01

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

System	Conn
167.94.138.45	1
194.165.16.72	1
80.66.76.121	1
azpdegb58.stretchoid.com	1
ns31410048.ip-145-239-154.eu	1
TOTAL: 5	5

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

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
194.165.16.72	1	1
80.66.76.121	1	1
ns31410048.ip-145-239-154.eu	1	1
TOTAL: 3	3	5

Daily Usenet Report

Sep 7 00:01:01 -- Sep 8 00:01:01