Daily Usenet Report: Aug 3 00:01:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Aug 3 14:57:23 newsfeed nnrpd[3552]: 47.189.156.68 auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
innfeed	23938	57.1%	3.2 MB	38.9%
inn	8788	21.0%	3.5 MB	43.0%
innd	8278	19.8%	1.3 MB	16.4%
cnfsstat	432	1.0%	70.9 KB	0.8%
nnrpd	295	0.7%	26.6 KB	0.3%
nocem	168	0.4%	45.7 KB	0.5%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 7	41900	100.0%	8.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	117432	59.8%
Negative hits	70624	36.0%
Do not exist	8087	4.1%
Cache misses	72	0.0%
TOTAL: 4	196215	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.260	0.0%	655	0.000	1.924	34.588
article cleanup	00:00:00.075	0.0%	8188	0.000	0.009	0.074
article logging	00:00:00.369	0.0%	8281	0.000	0.045	0.143
article parse	00:00:00.197	0.0%	12096	0.000	0.016	0.057
article write	00:00:02.036	0.0%	7164	0.000	0.284	2.790
artlog/artcncl	00:00:00.013	0.0%	509	0.000	0.026	0.333
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:00:00.396	0.0%	196273	0.000	0.002	0.066
hisgrep/artcncl	00:00:00.174	0.0%	657	0.000	0.265	10.500
hishave/artcncl	00:00:00.003	0.0%	620	0.000	0.005	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:36.345	0.2%	197162	0.077	0.793	6.893
history sync	00:00:00.080	0.0%	394	0.000	0.203	2.333
history write	00:01:47.512	0.1%	8147	3.152	13.197	223.071
hiswrite/artcncl	00:00:00.325	0.0%	2	3.000	162.500	322.000
idle	23:54:47.669	99.6%	190305	102.752	452.367	2534.186
nntp read	00:00:03.190	0.0%	197223	0.002	0.016	0.216
overview write	00:00:34.148	0.0%	7164	0.500	4.767	30.472
perl filter	00:00:29.376	0.0%	8146	1.167	3.606	44.569
python filter	00:00:01.681	0.0%	8146	0.000	0.206	5.492
site send	00:00:01.157	0.0%	14413	0.000	0.080	0.255
TOTAL: 24:01:10.214	24:00:26.006	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.029	0.0%	10361	0.000	0.003	0.052
article prepare	00:00:00.006	0.0%	44272	0.000	0.000	0.004
article read	00:00:00.585	0.0%	4153	0.000	0.141	1.323
backlog stats	00:00:00.410	0.0%	270604	0.000	0.002	0.006
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.252	0.0%	181106	0.000	0.012	0.041
data write	00:00:03.182	0.0%	176765	0.003	0.018	0.063
idle	23:58:34.816	100.0%	270604	168.410	318.971	1556.093
status file	00:00:09.692	0.0%	8602	1.000	1.127	1.965
TOTAL: 23:59:11.507	23:58:50.972	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	128:59:05.623	100.0%	8514	1.000	54539.068	54670.657
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.268	0.0%	17235	0.000	0.016	0.500
readart	00:00:00.243	0.0%	191	1.272	1.272	1.272
TOTAL: 128:59:11.015	128:59:06.134	100.0%	-	-	-	-

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	3	13186	1762	11209	215	13%	45:51:11
2	outgoing.news.lysator.liu.se	2	6025	1461	4480	84	24%	23:59:54
3	usenet.blueworldhosting.com	718	7922	1025	6468	429	12%	23:56:19
4	news.hispagatos.org	5	7440	631	6783	26	8%	24:26:20
5	nntp.comgw.net	1	7715	477	7175	63	6%	23:59:58
6	news.corradoroberto.it	5	1563	425	1138	0	27%	23:35:30
7	i2pn.org	2	7176	263	6894	19	3%	23:59:45
8	194.87.241.33	44	301	176	124	1	58%	16:47:33
9	news.samoylyk.net	2	6688	159	6524	5	2%	23:59:24
10	usenet.goja.nl.eu.org	10	7575	158	7391	26	2%	23:59:51
11	newsfeed.bofh.team	1	8034	124	7897	13	1%	23:59:54
12	endoftheline-out.news.chmurka.net	1	3534	104	3427	3	2%	23:59:55
13	endofthelinebbs.peers.news.panix.com	25	7536	99	7401	36	1%	23:55:40
14	usenet.network	1	5292	85	5206	1	1%	23:59:57
15	news.nntp4.net	1	6346	46	6298	2	0%	23:59:53
16	newsfeed.xs3.de	1	6206	15	6190	1	0%	23:59:54
17	news.furie.org.uk	2	3817	14	3800	3	0%	23:59:07
18	news.netfront.net	2	6204	11	6094	99	0%	30:26:46
19	glockenspiel.complete.org	2	5594	7	5577	10	0%	24:01:23
20	news.bbs.nz	2	6116	5	6104	7	0%	23:59:26
21	photonic.trudheim.com	3	2222	0	2218	4	0%	29:12:39
22	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:03
23	localhost	6	0	0	0	0	0%	00:00:00
24	news.nk.ca	2	0	0	0	0	0%	00:20:12
TOTAL: 24		842	126492	7047	118398	1047	5%	530:40:34

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	endofthelinebbs-out.news.weretis.net	5.5 MB	53.5 KB	410.6 KB	6.0 MB	92%	3.1 KB
2	outgoing.news.lysator.liu.se	4.9 MB	36.1 KB	358.6 KB	5.3 MB	92%	3.5 KB
3	usenet.blueworldhosting.com	2.8 MB	22.3 KB	922.9 KB	3.7 MB	75%	2.6 KB
4	news.corradoroberto.it	2.6 MB	0.0 KB	0.0 KB	2.6 MB	100%	6.4 KB
5	news.hispagatos.org	2.0 MB	22.7 KB	114.1 KB	2.1 MB	93%	3.3 KB
6	usenet.network	1.7 MB	22.8 KB	0.0 KB	1.8 MB	98%	20.9 KB
7	nntp.comgw.net	1.6 MB	22.7 KB	376.2 KB	2.0 MB	80%	3.7 KB
8	i2pn.org	969.9 KB	22.7 KB	30.6 KB	1023.2 KB	94%	3.6 KB
9	news.samoylyk.net	724.7 KB	22.7 KB	15.8 KB	763.2 KB	94%	4.7 KB
10	usenet.goja.nl.eu.org	519.0 KB	22.7 KB	31.9 KB	573.6 KB	90%	3.1 KB
11	194.87.241.33	495.4 KB	0.0 KB	7.1 KB	502.4 KB	98%	2.8 KB
12	endoftheline-out.news.chmurka.net	399.7 KB	13.3 KB	4.3 KB	417.3 KB	95%	3.9 KB
13	glockenspiel.complete.org	379.8 KB	69.2 KB	0.7 KB	449.7 KB	84%	26.5 KB
14	newsfeed.bofh.team	258.4 KB	22.7 KB	32.9 KB	314.0 KB	82%	2.3 KB
15	endofthelinebbs.peers.news.panix.com	255.2 KB	41.4 KB	71.9 KB	368.5 KB	69%	2.7 KB
16	news.nntp4.net	129.5 KB	24.6 KB	0.0 KB	154.1 KB	84%	3.2 KB
17	news.furie.org.uk	56.1 KB	22.7 KB	1.5 KB	80.3 KB	69%	4.7 KB
18	newsfeed.xs3.de	47.7 KB	22.7 KB	0.0 KB	70.4 KB	67%	4.4 KB
19	news.netfront.net	25.8 KB	27.8 KB	600.6 KB	654.2 KB	3%	5.9 KB
20	news.bbs.nz	8.6 KB	38.8 KB	0.0 KB	47.4 KB	18%	3.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
24	photonic.trudheim.com	0.0 KB	30.7 KB	0.0 KB	30.7 KB	0%	7.7 KB
TOTAL: 24		25.3 MB	562.3 KB	2.9 MB	28.8 MB	87%	3.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 3 00:01:02 - 00:59:59	198	2.8%	0.06	727.1 KB	3.0%	0.21
Aug 3 01:00:00 - 01:59:59	191	2.7%	0.05	608.3 KB	2.5%	0.17
Aug 3 02:00:00 - 02:59:59	264	3.7%	0.07	778.3 KB	3.2%	0.22
Aug 3 03:00:00 - 03:59:59	382	5.3%	0.11	1.2 MB	4.8%	0.33
Aug 3 04:00:00 - 04:59:59	345	4.8%	0.10	871.2 KB	3.6%	0.24
Aug 3 05:00:00 - 05:59:59	304	4.2%	0.08	1.1 MB	4.5%	0.31
Aug 3 06:00:00 - 06:59:59	314	4.4%	0.09	991.9 KB	4.0%	0.28
Aug 3 07:00:00 - 07:59:59	333	4.6%	0.09	982.2 KB	4.0%	0.27
Aug 3 08:00:00 - 08:59:59	374	5.2%	0.10	1.2 MB	5.0%	0.34
Aug 3 09:00:00 - 09:59:59	433	6.0%	0.12	1.5 MB	6.2%	0.42
Aug 3 10:00:00 - 10:59:59	360	5.0%	0.10	1.4 MB	6.0%	0.41
Aug 3 11:00:00 - 11:59:59	444	6.2%	0.12	1.8 MB	7.3%	0.50
Aug 3 12:00:00 - 12:59:59	387	5.4%	0.11	1.6 MB	6.5%	0.45
Aug 3 13:00:00 - 13:59:59	446	6.2%	0.12	1.4 MB	5.7%	0.38
Aug 3 14:00:00 - 14:59:59	341	4.8%	0.09	974.8 KB	4.0%	0.27
Aug 3 15:00:00 - 15:59:59	323	4.5%	0.09	1.1 MB	4.8%	0.32
Aug 3 16:00:00 - 16:59:59	341	4.8%	0.09	1.5 MB	6.3%	0.43
Aug 3 17:00:00 - 17:59:59	268	3.7%	0.07	878.1 KB	3.6%	0.24
Aug 3 18:00:00 - 18:59:59	204	2.8%	0.06	657.5 KB	2.7%	0.18
Aug 3 19:00:00 - 19:59:59	167	2.3%	0.05	641.0 KB	2.6%	0.18
Aug 3 20:00:00 - 20:59:59	190	2.7%	0.05	595.9 KB	2.4%	0.17
Aug 3 21:00:00 - 21:59:59	218	3.0%	0.06	700.6 KB	2.9%	0.19
Aug 3 22:00:00 - 22:59:59	152	2.1%	0.04	438.7 KB	1.8%	0.12
Aug 3 23:00:00 - 23:59:59	175	2.4%	0.05	616.4 KB	2.5%	0.17
Aug 4 00:00:00 - 00:01:02	8	0.1%	0.13	28.3 KB	0.1%	0.46
TOTAL: 24:00:00	7162	100.0%	0.08	23.9 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	95.8 GB	6.2%	0	0.45	41509.86
CYCTXT1	TEXT	5.0 GB	3.6 GB	72.4%	0	0.02	2867.54
TOTAL: 3	-	3077.0 GB	99.4 GB	3.2%	1	0.34	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	453	1	5	447
2	endofthelinebbs-out.news.weretis.net	280	0	4	276
3	outgoing.news.lysator.liu.se	84	2	2	80
4	news.netfront.net	77	0	3	74
5	nntp.comgw.net	68	0	1	67
6	endofthelinebbs.peers.news.panix.com	37	0	5	32
7	usenet.goja.nl.eu.org	31	0	1	30
8	news.hispagatos.org	26	2	1	23
9	i2pn.org	19	0	1	18
10	newsfeed.bofh.team	14	1	1	12
11	news.bbs.nz	6	0	6	0
12	news.samoylyk.net	6	0	1	5
13	photonic.trudheim.com	4	0	4	0
14	endoftheline-out.news.chmurka.net	3	0	1	2
15	news.furie.org.uk	3	0	1	2
16	news.nntp4.net	2	0	2	0
17	194.87.241.33	1	0	0	1
18	glockenspiel.complete.org	1	0	1	0
19	newsfeed.xs3.de	1	0	1	0
20	usenet.network	1	0	1	0
TOTAL: 20		1117	6	42	1069

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.russian.z1	4
soc.culture.russia	1
soc.culture.uk	1
TOTAL: 3	6

INND Perl filter [Top 20]:

Reason	Count
EMP (phr path)	340
EMP (phn path)	231
EMP (md5)	205
Too many newsgroups	84
User-issued cancel	49
EMP (phl)	37
EMP (fsl)	13
EMP (phn nph)	7
HTML Multipart	7
Binary: misplaced binary	1
Subject (LSDQstGB0ZE)	1
TOTAL: 11	975

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
i2pn2-nocem@i2pn2.org (spam)	82	616	0	616
robot@pasdenom.info (spam3)	2	0	4	4
robot@pasdenom.info (spam2)	1	0	1	1
TOTAL: 3	85	616	5	621

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	endofthelinebbs-out.news.weretis.net	55
	usenet.blueworldhosting.com	24
	nntp.comgw.net	6
	usenet.goja.nl.eu.org	6
	endofthelinebbs.peers.news.panix.com	1
	newsfeed.bofh.team	1
	TOTAL: 6	93
No colon-space in header field
	usenet.blueworldhosting.com	1
	TOTAL: 1	1
TOTAL: 2		94

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.netfront.net	10526	3176	3975	1	8	30%	23:59:34
2	nyheter.lysator.liu.se	5935	1479	4047	165	7	24%	23:59:47
3	photonic.trudheim.com	7499	502	5492	1168	2	6%	23:59:47
4	news.furie.org.uk	4134	394	3000	4	0	9%	23:59:47
5	news.hispagatos.org	7636	392	5952	105	3	5%	23:59:43
6	usenet.network	7897	376	6263	31	9	4%	23:59:47
7	news.bbs.nz	13204	286	6589	281	127	2%	23:58:48
8	usenet.goja.nl.eu.org	8330	286	6595	57	1	3%	23:59:47
9	news.nntp4.net	8261	246	6851	8	16	2%	23:59:47
10	news.nk.ca	10331	229	6526	322	37	2%	23:59:47
11	i2pn.org	7802	191	6624	66	99	2%	23:59:47
12	newsfeed.bofh.team	8252	135	6821	0	9	1%	23:59:47
13	news.weretis.net	7494	116	6989	57	1	1%	23:58:04
14	nntp.comgw.net	7834	92	6572	47	10	1%	23:59:47
15	news.corradoroberto.it	1065	82	828	4	7	7%	23:23:36
16	news.tnetconsulting.net	7981	77	6952	130	22	0%	23:59:29
17	news.quux.org	7607	65	6800	284	3	0%	23:59:21
18	newsfeed.xs3.de	8673	52	7005	77	4	0%	23:59:47
19	news.samoylyk.net	8441	38	6887	18	16	0%	23:59:47
20	news.chmurka.net	3947	32	3184	0	8	0%	23:59:47
21	usenet.blueworldhosting.com	6160	0	5835	0	0	0%	23:59:47
22	ddt.demos.su	2814	0	0	0	2878	0%	23:59:47
23	endofthelinebbs.peers.news.panix.com	0	0	0	0	7159	0%	23:59:47
24	feed.opticnetworks.net	0	0	0	0	7162	0%	23:57:37
TOTAL: 24		161823	8246	119787	2825	17588	5%	575:12:44

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.netfront.net	10.3 MB	4.8 KB	10.3 MB	0.1 KB/s	3.3 KB	23:59:34
2	nyheter.lysator.liu.se	4.9 MB	819.6 KB	5.7 MB	0.1 KB/s	3.5 KB	23:59:47
3	photonic.trudheim.com	2.3 MB	3.4 MB	5.7 MB	0.1 KB/s	3.5 KB	23:59:47
4	news.furie.org.uk	1.8 MB	80.0 KB	1.9 MB	0.0 KB/s	4.9 KB	23:59:47
5	news.hispagatos.org	1.3 MB	616.5 KB	1.9 MB	0.0 KB/s	3.9 KB	23:59:43
6	usenet.network	1.2 MB	57.1 KB	1.3 MB	0.0 KB/s	3.2 KB	23:59:47
7	news.bbs.nz	885.1 KB	930.9 KB	1.8 MB	0.0 KB/s	3.2 KB	23:58:48
8	news.nntp4.net	859.1 KB	40.5 KB	899.6 KB	0.0 KB/s	3.5 KB	23:59:47
9	i2pn.org	715.1 KB	180.5 KB	895.6 KB	0.0 KB/s	3.5 KB	23:59:47
10	newsfeed.bofh.team	695.3 KB	0.0 KB	695.3 KB	0.0 KB/s	5.2 KB	23:59:47
11	usenet.goja.nl.eu.org	674.3 KB	380.4 KB	1.0 MB	0.0 KB/s	3.1 KB	23:59:47
12	news.quux.org	653.8 KB	659.1 KB	1.3 MB	0.0 KB/s	3.8 KB	23:59:21
13	news.weretis.net	613.0 KB	675.4 KB	1.3 MB	0.0 KB/s	7.4 KB	23:58:04
14	news.nk.ca	577.8 KB	1.1 MB	1.6 MB	0.0 KB/s	3.0 KB	23:59:47
15	news.tnetconsulting.net	362.9 KB	521.6 KB	884.5 KB	0.0 KB/s	4.3 KB	23:59:29
16	news.samoylyk.net	356.8 KB	24.1 KB	380.9 KB	0.0 KB/s	6.8 KB	23:59:47
17	news.corradoroberto.it	284.4 KB	9.3 KB	293.7 KB	0.0 KB/s	3.4 KB	23:23:36
18	nntp.comgw.net	283.2 KB	426.4 KB	709.6 KB	0.0 KB/s	5.1 KB	23:59:47
19	newsfeed.xs3.de	181.8 KB	529.2 KB	711.0 KB	0.0 KB/s	5.5 KB	23:59:47
20	news.chmurka.net	112.1 KB	0.0 KB	112.1 KB	0.0 KB/s	3.5 KB	23:59:47
21	ddt.demos.su	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:47
22	endofthelinebbs.peers.news.panix.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:47
23	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:37
24	usenet.blueworldhosting.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:47
TOTAL: 24		29.0 MB	10.3 MB	39.3 MB	0.0 KB/s	3.6 KB	575:12:44

NNRP readership statistics:

System		Conn	Arts	Size	Elapsed
1	47.189.156.68	1	2	2.6 KB	128:58:41
2	167.94.145.101	3	0	0.0 KB	00:00:03
3	199.45.154.153	3	0	0.0 KB	00:00:04
4	80.66.76.134	2	0	0.0 KB	00:00:00
5	147.185.132.37	1	0	0.0 KB	00:00:10
6	185.165.191.27	1	0	0.0 KB	00:00:00
7	205.210.31.209	1	0	0.0 KB	00:00:10
8	207.90.244.14	1	0	0.0 KB	00:00:00
9	207.90.244.4	1	0	0.0 KB	00:00:00
10	azpdeg4.stretchoid.com	1	0	0.0 KB	00:00:00
TOTAL: 10		15	2	2.6 KB	128:59:11

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Elapsed
1	unresolved	14	2	2.6 KB	128:59:10
2	*.stretchoid.com	1	0	0.0 KB	00:00:00
TOTAL: 2		15	2	2.6 KB	128:59:11

NNRP auth users [Top 20]:

User	Conn
nelgin	1
TOTAL: 1	1

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
47.189.156.68	0.425	0.958	0.093	128:58:41
147.185.132.37	0.000	0.017	0.000	00:00:10
205.210.31.209	0.004	0.012	0.000	00:00:10
199.45.154.153	0.015	0.049	0.000	00:00:04
167.94.145.101	0.017	0.042	0.000	00:00:03
80.66.76.134	0.000	0.036	0.000	00:00:00
azpdeg4.stretchoid.com	0.008	0.008	0.000	00:00:00
185.165.191.27	0.003	0.013	0.000	00:00:00
207.90.244.4	0.009	0.018	0.000	00:00:00
207.90.244.14	0.000	0.019	0.000	00:00:00
TOTAL: 10	0.481	1.172	0.093	128:59:11

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

System	Conn
80.66.76.134	2
167.94.145.101	1
199.45.154.153	1
azpdeg4.stretchoid.com	1
TOTAL: 4	5

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

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
80.66.76.134	2	2
167.94.145.101	1	2
199.45.154.153	1	2
47.189.156.68	1	0
TOTAL: 4	5	6

Daily Usenet Report

Aug 3 00:01:02 -- Aug 4 00:01:02