Daily Usenet Report: Aug 24 00:01:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Aug 25 00:00:01 newsfeed nnrpd[587929]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	8801	29.0%	3.3 MB	53.2%
innd	8512	28.1%	1.2 MB	19.3%
nnrpd	8343	27.5%	881.1 KB	13.8%
innfeed	4124	13.6%	788.8 KB	12.3%
cnfsstat	432	1.4%	64.7 KB	1.0%
nocem	125	0.4%	27.2 KB	0.4%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 7	30338	100.0%	6.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	108988	60.5%
Negative hits	62824	34.9%
Do not exist	8221	4.6%
Cache misses	58	0.0%
TOTAL: 4	180091	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.526	0.0%	424	0.000	1.241	8.000
article cleanup	00:00:00.095	0.0%	8278	0.000	0.011	0.076
article logging	00:00:00.428	0.0%	8414	0.000	0.051	1.021
article parse	00:00:00.200	0.0%	11992	0.000	0.017	0.097
article write	00:00:02.901	0.0%	6697	0.000	0.433	9.477
artlog/artcncl	00:00:00.007	0.0%	390	0.000	0.018	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:02.002	0.0%	182892	0.000	0.011	1.409
hisgrep/artcncl	00:00:00.241	0.0%	425	0.000	0.567	7.000
hishave/artcncl	00:00:00.103	0.0%	390	0.000	0.264	2.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:28.931	0.2%	180600	0.101	0.825	5.209
history sync	00:00:00.064	0.0%	393	0.000	0.163	1.000
history write	00:01:58.575	0.1%	8263	3.243	14.350	134.550
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:43:04.264	98.8%	183010	87.425	466.555	1582.757
nntp read	00:00:05.300	0.0%	183707	0.000	0.029	1.174
overview write	00:11:52.205	0.8%	6697	0.800	106.347	6920.478
perl filter	00:00:30.269	0.0%	8263	1.632	3.663	24.151
python filter	00:00:01.841	0.0%	8263	0.000	0.223	5.792
site send	00:00:00.848	0.0%	13460	0.000	0.063	0.170
TOTAL: 24:00:58.884	24:00:08.800	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:04.355	0.0%	4701081	0.000	0.001	0.002
article prepare	00:00:00.005	0.0%	41456	0.000	0.000	0.003
article read	00:00:00.591	0.0%	3999	0.000	0.148	0.639
backlog stats	00:00:01.832	0.0%	1999753	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:11.711	0.0%	1204858	0.005	0.010	0.030
data write	00:00:12.496	0.0%	1201449	0.007	0.010	0.025
idle	23:47:24.143	99.8%	1999753	31.309	42.827	48.042
status file	00:00:06.930	0.0%	268	1.000	25.858	1321.000
TOTAL: 23:50:10.580	23:48:02.063	99.9%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:22:19.937	54.4%	8279	0.000	161.848	10051.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.328	0.2%	95364	0.000	0.045	0.500
TOTAL: 00:41:01.208	00:22:24.265	54.6%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	outgoing.news.lysator.liu.se	2	6511	1286	5089	136	19%	23:59:40
2	usenet.goja.nl.eu.org	11	7933	1274	6393	266	16%	25:46:05
3	endofthelinebbs-out.news.weretis.net	4	13647	859	12646	142	6%	50:38:12
4	usenet.blueworldhosting.com	721	8100	832	6613	655	10%	23:52:06
5	nntp.comgw.net	2	7797	587	7019	191	7%	23:59:48
6	news.hispagatos.org	6	7462	497	6924	41	6%	24:25:43
7	news.corradoroberto.it	6	1258	393	865	0	31%	24:11:08
8	i2pn.org	1	7533	244	7253	36	3%	23:59:51
9	newsfeed.bofh.team	2	8093	203	7870	20	2%	23:58:38
10	usenet.network	1	5631	199	5429	3	3%	23:59:56
11	endoftheline-out.news.chmurka.net	2	3193	158	3031	4	4%	23:59:17
12	endofthelinebbs.peers.news.panix.com	25	7263	154	7087	22	2%	23:54:37
13	news.samoylyk.net	1	6488	73	6414	1	1%	23:59:48
14	newsfeed.xs3.de	2	5613	53	5553	7	0%	23:58:21
15	news.netfront.net	2	8630	32	8499	99	0%	36:33:59
16	glockenspiel.complete.org	2	5539	19	5519	1	0%	23:57:07
17	news.bbs.nz	1	6652	13	6638	1	0%	23:59:21
18	news.furie.org.uk	2	3447	4	3442	1	0%	23:59:43
19	photonic.trudheim.com	1	1311	0	1311	0	0%	19:28:17
20	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:03
21	localhost	34	0	0	0	0	0%	00:00:00
22	news.nk.ca	2	0	0	0	0	0%	00:20:18
23	news.nntp4.net	1	0	0	0	0	0%	00:10:00
TOTAL: 23		832	122101	6880	113595	1626	5%	493:21:58

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	outgoing.news.lysator.liu.se	4.2 MB	5.2 KB	233.6 KB	4.4 MB	94%	3.2 KB
2	usenet.goja.nl.eu.org	3.8 MB	5.1 KB	410.6 KB	4.2 MB	90%	2.8 KB
3	endofthelinebbs-out.news.weretis.net	3.7 MB	10.3 KB	241.8 KB	4.0 MB	93%	4.1 KB
4	usenet.blueworldhosting.com	2.6 MB	0.0 KB	1.2 MB	3.8 MB	67%	2.6 KB
5	nntp.comgw.net	2.3 MB	5.1 KB	430.1 KB	2.7 MB	84%	3.6 KB
6	news.corradoroberto.it	2.1 MB	0.0 KB	0.0 KB	2.1 MB	100%	5.4 KB
7	usenet.network	1.9 MB	5.2 KB	2.1 KB	1.9 MB	99%	9.8 KB
8	news.hispagatos.org	1.5 MB	5.2 KB	69.6 KB	1.5 MB	95%	2.9 KB
9	i2pn.org	896.8 KB	5.1 KB	46.2 KB	948.1 KB	94%	3.4 KB
10	newsfeed.bofh.team	635.0 KB	5.1 KB	32.4 KB	672.5 KB	94%	3.0 KB
11	endoftheline-out.news.chmurka.net	496.1 KB	0.0 KB	3.0 KB	499.1 KB	99%	3.1 KB
12	endofthelinebbs.peers.news.panix.com	493.0 KB	5.1 KB	38.5 KB	536.6 KB	91%	3.0 KB
13	newsfeed.xs3.de	464.8 KB	0.0 KB	25.8 KB	490.5 KB	94%	8.2 KB
14	news.samoylyk.net	333.9 KB	5.1 KB	0.0 KB	339.0 KB	98%	4.6 KB
15	news.netfront.net	97.4 KB	5.2 KB	721.7 KB	824.4 KB	11%	6.3 KB
16	glockenspiel.complete.org	95.4 KB	5.1 KB	0.0 KB	100.5 KB	94%	5.0 KB
17	news.bbs.nz	28.5 KB	5.1 KB	0.0 KB	33.6 KB	84%	2.4 KB
18	news.furie.org.uk	14.3 KB	5.2 KB	0.0 KB	19.5 KB	73%	3.9 KB
19	eotl-out.news.tnetconsulting.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
20	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
21	news.nk.ca	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
22	news.nntp4.net	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.5 MB	77.2 KB	3.4 MB	29.1 MB	87%	3.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 24 00:01:01 - 00:59:59	200	3.0%	0.06	850.6 KB	3.4%	0.24
Aug 24 01:00:00 - 01:59:59	248	3.7%	0.07	935.0 KB	3.7%	0.26
Aug 24 02:00:00 - 02:59:59	317	4.7%	0.09	843.9 KB	3.4%	0.23
Aug 24 03:00:00 - 03:59:59	330	4.9%	0.09	1.2 MB	5.0%	0.35
Aug 24 04:00:00 - 04:59:59	355	5.3%	0.10	967.7 KB	3.9%	0.27
Aug 24 05:00:00 - 05:59:59	390	5.8%	0.11	1.0 MB	4.1%	0.29
Aug 24 06:00:00 - 06:59:59	297	4.4%	0.08	1.0 MB	4.1%	0.29
Aug 24 07:00:00 - 07:59:59	351	5.2%	0.10	1.1 MB	4.5%	0.31
Aug 24 08:00:00 - 08:59:59	388	5.8%	0.11	1.2 MB	5.0%	0.35
Aug 24 09:00:00 - 09:59:59	349	5.2%	0.10	1.2 MB	4.8%	0.33
Aug 24 10:00:00 - 10:59:59	371	5.5%	0.10	1.6 MB	6.5%	0.46
Aug 24 11:00:00 - 11:59:59	394	5.9%	0.11	1.4 MB	5.9%	0.41
Aug 24 12:00:00 - 12:59:59	331	4.9%	0.09	1.7 MB	7.1%	0.49
Aug 24 13:00:00 - 13:59:59	385	5.8%	0.11	1.2 MB	4.9%	0.34
Aug 24 14:00:00 - 14:59:59	315	4.7%	0.09	960.5 KB	3.8%	0.27
Aug 24 15:00:00 - 15:59:59	264	3.9%	0.07	908.7 KB	3.6%	0.25
Aug 24 16:00:00 - 16:59:59	241	3.6%	0.07	881.9 KB	3.5%	0.24
Aug 24 17:00:00 - 17:59:59	230	3.4%	0.06	1.0 MB	4.1%	0.29
Aug 24 18:00:00 - 18:59:59	156	2.3%	0.04	526.8 KB	2.1%	0.15
Aug 24 19:00:00 - 19:59:59	173	2.6%	0.05	686.3 KB	2.7%	0.19
Aug 24 20:00:00 - 20:59:59	144	2.2%	0.04	1.0 MB	4.1%	0.29
Aug 24 21:00:00 - 21:59:59	192	2.9%	0.05	718.4 KB	2.9%	0.20
Aug 24 22:00:00 - 22:59:59	112	1.7%	0.03	1.0 MB	4.1%	0.29
Aug 24 23:00:00 - 23:59:59	151	2.3%	0.04	654.5 KB	2.6%	0.18
Aug 25 00:00:00 - 00:01:01	7	0.1%	0.11	25.9 KB	0.1%	0.42
TOTAL: 24:00:00	6691	100.0%	0.08	24.5 MB	100.0%	0.29

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.6 GB	6.3%	0	0.45	41877.01
CYCTXT1	TEXT	5.0 GB	3.8 GB	76.5%	0	0.03	2243.98
TOTAL: 3	-	3077.0 GB	100.5 GB	3.3%	1	0.31	-

Sites sending bad articles:

Server		Total	Duplic	Other
1	usenet.blueworldhosting.com	696	0	696
2	usenet.goja.nl.eu.org	295	1	294
3	nntp.comgw.net	207	1	206
4	endofthelinebbs-out.news.weretis.net	162	2	160
5	outgoing.news.lysator.liu.se	137	1	136
6	news.netfront.net	59	1	58
7	news.hispagatos.org	48	1	47
8	i2pn.org	36	1	35
9	endofthelinebbs.peers.news.panix.com	32	1	31
10	newsfeed.bofh.team	21	1	20
11	newsfeed.xs3.de	7	0	7
12	endoftheline-out.news.chmurka.net	5	0	5
13	usenet.network	3	1	2
14	glockenspiel.complete.org	2	1	1
15	news.samoylyk.net	2	1	1
16	news.bbs.nz	1	1	0
17	news.corradoroberto.it	1	0	1
18	news.furie.org.uk	1	1	0
TOTAL: 18		1715	15	1700

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	1044
EMP (phr path)	341
Too many newsgroups	72
EMP (md5)	71
User-issued cancel	19
EMP (fsl)	7
EMP (phn nph)	6
HTML Multipart	5
TOTAL: 8	1565

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
i2pn2-nocem@i2pn2.org (spam)	57	394	0	394
nocem@usenet.ovh (faqfr)	2	0	18	18
robot@pasdenom.info (spam3)	5	0	8	8
robot@pasdenom.info (spam2)	3	0	3	3
TOTAL: 4	67	394	29	423

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	usenet.blueworldhosting.com	41
	endofthelinebbs-out.news.weretis.net	31
	usenet.goja.nl.eu.org	30
	nntp.comgw.net	16
	endofthelinebbs.peers.news.panix.com	10
	news.netfront.net	2
	newsfeed.bofh.team	1
	outgoing.news.lysator.liu.se	1
	news.corradoroberto.it	1
	i2pn.org	1
	TOTAL: 11	135
TOTAL: 1		135

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.netfront.net	9934	2815	3851	0	2	28%	23:59:53
2	nyheter.lysator.liu.se	5596	1256	3930	216	0	22%	23:59:07
3	news.furie.org.uk	3639	442	2625	4	0	12%	23:59:53
4	news.hispagatos.org	7195	441	5610	99	3	6%	23:59:14
5	photonic.trudheim.com	7243	425	5576	677	0	5%	23:59:33
6	endofthelinebbs.peers.news.panix.com	6918	367	6303	13	0	5%	23:58:58
7	usenet.network	7194	365	5820	24	3	5%	23:59:35
8	usenet.goja.nl.eu.org	6268	254	5094	59	3	4%	23:55:52
9	news.bbs.nz	11828	230	6217	235	75	1%	23:59:45
10	news.nk.ca	9818	184	5939	460	34	1%	23:59:53
11	i2pn.org	7359	161	6227	45	46	2%	23:59:10
12	news.tnetconsulting.net	7359	159	6404	119	0	2%	23:59:53
13	newsfeed.bofh.team	7178	146	6275	0	0	2%	23:59:53
14	news.weretis.net	6970	131	6507	48	0	1%	23:59:53
15	news.corradoroberto.it	995	125	667	0	2	12%	23:13:37
16	news.quux.org	7056	114	6312	245	0	1%	23:59:36
17	nntp.comgw.net	6857	112	5973	31	0	1%	23:59:53
18	newsfeed.xs3.de	7625	81	6472	58	3	1%	23:59:53
19	news.chmurka.net	3388	34	2877	4	2	1%	23:59:53
20	news.samoylyk.net	8947	19	6373	213	51	0%	23:59:53
21	usenet.blueworldhosting.com	6063	4	5582	0	0	0%	23:59:53
22	news.nntp4.net	4733891	0	0	0	4733858	0%	23:59:53
23	feed.opticnetworks.net	0	0	0	0	6691	0%	23:59:53
TOTAL: 23		4879321	7865	110634	2550	4740773	0%	551:02:56

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.netfront.net	11.4 MB	0.0 KB	11.4 MB	0.1 KB/s	4.1 KB	23:59:53
2	nyheter.lysator.liu.se	5.0 MB	1.0 MB	6.0 MB	0.1 KB/s	4.2 KB	23:59:07
3	news.furie.org.uk	2.9 MB	28.4 KB	2.9 MB	0.0 KB/s	6.7 KB	23:59:53
4	photonic.trudheim.com	2.5 MB	2.2 MB	4.7 MB	0.1 KB/s	4.3 KB	23:59:33
5	news.hispagatos.org	1.7 MB	577.9 KB	2.3 MB	0.0 KB/s	4.3 KB	23:59:14
6	endofthelinebbs.peers.news.panix.com	1.4 MB	30.9 KB	1.5 MB	0.0 KB/s	3.9 KB	23:58:58
7	usenet.network	1.3 MB	59.5 KB	1.4 MB	0.0 KB/s	3.7 KB	23:59:35
8	newsfeed.bofh.team	836.0 KB	0.0 KB	836.0 KB	0.0 KB/s	5.7 KB	23:59:53
9	news.quux.org	804.2 KB	721.1 KB	1.5 MB	0.0 KB/s	4.2 KB	23:59:36
10	news.bbs.nz	776.1 KB	1.3 MB	2.0 MB	0.0 KB/s	4.5 KB	23:59:45
11	news.tnetconsulting.net	753.4 KB	491.7 KB	1.2 MB	0.0 KB/s	4.5 KB	23:59:53
12	news.weretis.net	684.4 KB	504.9 KB	1.2 MB	0.0 KB/s	6.6 KB	23:59:53
13	i2pn.org	627.0 KB	145.6 KB	772.6 KB	0.0 KB/s	3.8 KB	23:59:10
14	usenet.goja.nl.eu.org	626.9 KB	350.9 KB	977.8 KB	0.0 KB/s	3.1 KB	23:55:52
15	news.nk.ca	525.1 KB	1.4 MB	1.9 MB	0.0 KB/s	3.0 KB	23:59:53
16	news.corradoroberto.it	456.0 KB	0.0 KB	456.0 KB	0.0 KB/s	3.6 KB	23:13:37
17	nntp.comgw.net	396.8 KB	417.3 KB	814.1 KB	0.0 KB/s	5.7 KB	23:59:53
18	newsfeed.xs3.de	312.8 KB	468.3 KB	781.1 KB	0.0 KB/s	5.6 KB	23:59:53
19	news.samoylyk.net	301.4 KB	1.4 MB	1.7 MB	0.0 KB/s	7.6 KB	23:59:53
20	news.chmurka.net	128.3 KB	18.7 KB	146.9 KB	0.0 KB/s	3.9 KB	23:59:53
21	usenet.blueworldhosting.com	8.2 KB	0.0 KB	8.2 KB	0.0 KB/s	2.1 KB	23:59:53
22	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:53
23	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:53
TOTAL: 23		33.2 MB	11.0 MB	44.3 MB	0.0 KB/s	4.4 KB	551:02:56

NNRP readership statistics:

System		Conn	Elapsed
1	ddt.demos.su	2757	00:29:19
2	167.94.146.59	3	00:00:04
3	67.207.94.139	2	00:00:00
4	179.60.149.8	1	00:00:00
5	198.235.24.102	1	00:00:10
6	205.210.31.33	1	00:00:10
7	5.150.216.162.bc.googleusercontent.com	1	00:00:00
8	91.238.181.21	1	00:00:00
9	azpdcs82.stretchoid.com	1	00:00:09
10	azpdeg56.stretchoid.com	1	00:00:00
11	localhost	1	00:11:05
TOTAL: 11		2770	00:40:59

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.demos.su	2757	00:29:19
2	unresolved	9	00:00:25
3	*.stretchoid.com	2	00:00:10
4	*.150.216.162.bc.googleusercontent.com	1	00:00:00
5	?	1	00:11:05
TOTAL: 5		2770	00:40:59

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.538	37.036	0.000	00:29:19
localhost	182.069	368.204	0.073	00:11:05
198.235.24.102	0.009	0.007	0.000	00:00:10
205.210.31.33	0.005	0.011	0.000	00:00:10
azpdcs82.stretchoid.com	0.004	0.012	0.000	00:00:09
167.94.146.59	0.024	0.036	0.000	00:00:04
67.207.94.139	0.003	0.029	0.000	00:00:00
91.238.181.21	0.003	0.013	0.000	00:00:00
179.60.149.8	0.004	0.013	0.000	00:00:00
azpdeg56.stretchoid.com	0.000	0.017	0.000	00:00:00
5.150.216.162.bc.googleusercontent.com	0.004	0.013	0.000	00:00:00
TOTAL: 11	191.663	405.391	0.073	00:40:59

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

System	Conn
5.150.216.162.bc.googleusercontent.com	4
167.94.146.59	1
179.60.149.8	1
91.238.181.21	1
azpdeg56.stretchoid.com	1
TOTAL: 5	8

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

Command	Count
#003	2
Accept-Encoding: gzip	1
EHLO www.censys.io	1
GET / HTTP/1.1	1
Host: 144.172.126.95:119	1
MGLNDD_144.172.126.95_119	1
User-Agent: Expanse, a Palo Alto Network...	1
TOTAL: 7	8

NNRP client timeouts [Top 20]:

System	Conn	Peer
167.94.146.59	1	2
179.60.149.8	1	1
91.238.181.21	1	1
TOTAL: 3	3	4

Daily Usenet Report

Aug 24 00:01:01 -- Aug 25 00:01:01