Daily Usenet Report: Feb 12 00:01:02

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Feb 12 08:19:20 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Feb 13 00:00:01 newsfeed nnrpd[30007]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	9512	42.8%	3.6 MB	63.5%
innd	7770	35.0%	1.1 MB	20.1%
innfeed	4354	19.6%	873.1 KB	15.0%
cnfsstat	432	1.9%	65.0 KB	1.1%
nocem	77	0.3%	9.1 KB	0.2%
nnrpd	65	0.3%	6.9 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 7	22211	100.0%	5.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	132527	65.8%
Negative hits	59353	29.5%
Do not exist	9456	4.7%
Cache misses	113	0.1%
TOTAL: 4	201449	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.183	0.0%	36	0.000	5.083	10.000
article cleanup	00:00:00.080	0.0%	9501	0.000	0.008	0.086
article logging	00:00:00.806	0.0%	9524	0.000	0.085	15.952
article parse	00:00:00.181	0.0%	15441	0.000	0.012	0.057
article write	00:00:02.760	0.0%	7252	0.000	0.381	7.163
artlog/artcncl	00:00:00.000	0.0%	11	0.000	0.000	0.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:00:01.237	0.0%	194961	0.000	0.006	0.377
hisgrep/artcncl	00:00:00.141	0.0%	41	0.000	3.439	6.818
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:36.082	0.2%	202966	0.100	0.769	6.454
history sync	00:00:00.074	0.0%	396	0.000	0.187	1.000
history write	00:02:34.578	0.2%	9498	3.000	16.275	158.568
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:46:07.033	99.0%	186963	93.320	457.668	1947.182
nntp read	00:00:03.917	0.0%	195821	0.003	0.020	0.691
overview write	00:08:27.065	0.6%	7252	0.759	69.921	5668.522
perl filter	00:00:31.218	0.0%	9497	1.281	3.287	31.848
python filter	00:00:01.658	0.0%	9497	0.000	0.175	4.212
site send	00:00:01.311	0.0%	14576	0.000	0.090	3.239
TOTAL: 24:01:09.312	24:00:28.324	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.012	0.0%	7441	0.000	0.002	0.048
article prepare	00:00:00.007	0.0%	49750	0.000	0.000	0.003
article read	00:00:00.655	0.0%	4618	0.000	0.142	1.200
backlog stats	00:00:00.396	0.0%	277903	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.339	0.0%	189040	0.002	0.012	0.044
data write	00:00:03.335	0.0%	183930	0.009	0.018	0.035
idle	23:57:38.375	100.0%	277903	170.143	310.390	1024.494
status file	00:00:00.430	0.0%	373	0.857	1.153	12.000
TOTAL: 23:57:55.890	23:57:45.549	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:42.534	6.0%	24	0.500	1772.250	10033.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:03.748	0.5%	78914	0.000	0.047	0.048
TOTAL: 00:11:47.859	00:00:46.282	6.5%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	endofthelinebbs-out.news.weretis.net	4	15293	2181	12878	234	14%	48:21:21
2	glockenspiel.complete.org	2	8008	894	7043	71	11%	30:37:30
3	usenet.goja.nl.eu.org	10	8518	829	7505	184	9%	23:47:54
4	usenet.blueworldhosting.com	288	9370	796	7609	965	8%	23:44:54
5	nntp.comgw.net	1	8767	499	7989	279	5%	23:59:57
6	usenet.network	2	6160	464	5687	9	7%	23:59:54
7	i2pn.org	2	8285	377	7863	45	4%	23:59:47
8	news.corradoroberto.it	11	1110	349	761	0	31%	23:16:34
9	newsfeed.bofh.team	2	9284	220	9004	60	2%	23:58:59
10	endoftheline-out.news.chmurka.net	1	4254	218	4033	3	5%	23:59:58
11	194.87.241.33	47	740	148	454	138	20%	13:29:13
12	newsfeed.xs3.de	2	6937	126	6693	118	1%	23:59:32
13	news.nntp4.net	1	6898	99	6715	84	1%	23:59:54
14	news.samoylyk.net	4	7285	95	7153	37	1%	24:03:04
15	news.hispagatos.org	5	8873	43	8787	43	0%	24:26:20
16	endofthelinebbs.peers.news.panix.com	23	8041	37	8001	3	0%	23:53:18
17	news.furie.org.uk	1	4516	25	4490	1	0%	23:59:57
18	news.netfront.net	1	7526	9	7498	19	0%	24:36:53
19	outgoing.news.lysator.liu.se	1	5288	5	5283	0	0%	23:59:57
20	news.bbs.nz	1	7232	3	7229	0	0%	23:59:42
21	photonic.trudheim.com	4	1988	1	1987	0	0%	23:51:40
22	localhost	29	0	0	0	0	0%	00:00:00
23	news.nk.ca	2	0	0	0	0	0%	00:20:17
TOTAL: 23		444	144373	7418	134662	2293	5%	524:26:35

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	endofthelinebbs-out.news.weretis.net	6.2 MB	291.6 KB	528.0 KB	7.0 MB	88%	3.0 KB
2	usenet.network	5.2 MB	0.0 KB	10.4 KB	5.2 MB	99%	11.2 KB
3	glockenspiel.complete.org	3.0 MB	0.0 KB	133.1 KB	3.1 MB	95%	3.3 KB
4	usenet.goja.nl.eu.org	2.6 MB	0.0 KB	332.3 KB	2.9 MB	88%	2.9 KB
5	news.corradoroberto.it	2.3 MB	0.0 KB	0.0 KB	2.3 MB	100%	6.7 KB
6	i2pn.org	1.8 MB	0.0 KB	76.5 KB	1.9 MB	95%	4.5 KB
7	usenet.blueworldhosting.com	1.7 MB	0.0 KB	2.1 MB	3.8 MB	45%	2.2 KB
8	nntp.comgw.net	1.5 MB	291.6 KB	548.2 KB	2.3 MB	64%	3.1 KB
9	endoftheline-out.news.chmurka.net	963.1 KB	0.0 KB	1.9 KB	965.0 KB	99%	4.4 KB
10	news.samoylyk.net	818.0 KB	0.0 KB	91.4 KB	909.5 KB	89%	6.9 KB
11	newsfeed.bofh.team	723.8 KB	0.0 KB	104.0 KB	827.8 KB	87%	3.0 KB
12	194.87.241.33	502.1 KB	0.0 KB	367.4 KB	869.5 KB	57%	3.0 KB
13	newsfeed.xs3.de	445.6 KB	291.6 KB	393.6 KB	1.1 MB	39%	4.6 KB
14	news.nntp4.net	268.5 KB	0.0 KB	288.3 KB	556.8 KB	48%	3.0 KB
15	news.hispagatos.org	140.6 KB	0.0 KB	79.7 KB	220.3 KB	63%	2.6 KB
16	news.furie.org.uk	130.3 KB	291.6 KB	0.0 KB	421.9 KB	30%	16.2 KB
17	endofthelinebbs.peers.news.panix.com	115.8 KB	0.0 KB	5.3 KB	121.1 KB	95%	3.0 KB
18	outgoing.news.lysator.liu.se	21.2 KB	0.0 KB	0.0 KB	21.2 KB	100%	4.2 KB
19	photonic.trudheim.com	16.0 KB	0.0 KB	0.0 KB	16.0 KB	100%	16.0 KB
20	news.netfront.net	14.5 KB	0.0 KB	84.9 KB	99.4 KB	14%	3.5 KB
21	news.bbs.nz	5.5 KB	0.0 KB	0.0 KB	5.5 KB	100%	1.8 KB
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		28.3 MB	1.1 MB	5.1 MB	34.5 MB	81%	3.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 12 00:01:02 - 00:59:59	143	2.0%	0.04	618.7 KB	2.2%	0.17
Feb 12 01:00:00 - 01:59:59	211	2.9%	0.06	706.0 KB	2.5%	0.20
Feb 12 02:00:00 - 02:59:59	324	4.5%	0.09	932.0 KB	3.3%	0.26
Feb 12 03:00:00 - 03:59:59	360	5.0%	0.10	1.8 MB	6.4%	0.50
Feb 12 04:00:00 - 04:59:59	314	4.3%	0.09	1010.0 KB	3.6%	0.28
Feb 12 05:00:00 - 05:59:59	298	4.1%	0.08	898.6 KB	3.2%	0.25
Feb 12 06:00:00 - 06:59:59	311	4.3%	0.09	1.1 MB	4.0%	0.31
Feb 12 07:00:00 - 07:59:59	330	4.6%	0.09	1.3 MB	4.7%	0.37
Feb 12 08:00:00 - 08:59:59	361	5.0%	0.10	1.9 MB	7.0%	0.55
Feb 12 09:00:00 - 09:59:59	397	5.5%	0.11	1.7 MB	6.0%	0.47
Feb 12 10:00:00 - 10:59:59	498	6.9%	0.14	1.7 MB	6.3%	0.49
Feb 12 11:00:00 - 11:59:59	498	6.9%	0.14	1.5 MB	5.3%	0.42
Feb 12 12:00:00 - 12:59:59	448	6.2%	0.12	1.6 MB	5.9%	0.46
Feb 12 13:00:00 - 13:59:59	388	5.4%	0.11	1.3 MB	4.7%	0.37
Feb 12 14:00:00 - 14:59:59	380	5.2%	0.11	1.1 MB	4.0%	0.31
Feb 12 15:00:00 - 15:59:59	446	6.2%	0.12	1.9 MB	6.9%	0.54
Feb 12 16:00:00 - 16:59:59	311	4.3%	0.09	1.1 MB	3.9%	0.31
Feb 12 17:00:00 - 17:59:59	236	3.3%	0.07	1.3 MB	4.6%	0.36
Feb 12 18:00:00 - 18:59:59	192	2.6%	0.05	757.4 KB	2.7%	0.21
Feb 12 19:00:00 - 19:59:59	154	2.1%	0.04	859.1 KB	3.0%	0.24
Feb 12 20:00:00 - 20:59:59	174	2.4%	0.05	781.7 KB	2.8%	0.22
Feb 12 21:00:00 - 21:59:59	115	1.6%	0.03	476.4 KB	1.7%	0.13
Feb 12 22:00:00 - 22:59:59	165	2.3%	0.05	842.4 KB	3.0%	0.23
Feb 12 23:00:00 - 23:59:59	191	2.6%	0.05	713.2 KB	2.5%	0.20
Feb 13 00:00:00 - 00:01:02	5	0.1%	0.08	18.2 KB	0.1%	0.29
TOTAL: 24:00:00	7250	100.0%	0.08	27.7 MB	100.0%	0.33

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	103.4 GB	6.7%	0	0.49	38073.93
CYCTXT1	TEXT	5.0 GB	4.0 GB	80.8%	0	0.04	1698.28
TOTAL: 3	-	3077.0 GB	107.5 GB	3.5%	1	0.35	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	969	0	0	969
2	nntp.comgw.net	289	0	1	288
3	usenet.goja.nl.eu.org	192	0	0	192
4	endofthelinebbs-out.news.weretis.net	186	0	1	185
5	194.87.241.33	154	0	0	154
6	newsfeed.xs3.de	116	0	1	115
7	news.nntp4.net	84	0	0	84
8	newsfeed.bofh.team	63	0	0	63
9	i2pn.org	45	0	0	45
10	news.hispagatos.org	42	24	0	18
11	news.samoylyk.net	37	0	0	37
12	glockenspiel.complete.org	34	0	0	34
13	news.netfront.net	21	0	0	21
14	usenet.network	12	0	0	12
15	endoftheline-out.news.chmurka.net	3	0	0	3
16	endofthelinebbs.peers.news.panix.com	3	0	0	3
17	news.furie.org.uk	1	0	1	0
TOTAL: 17		2251	24	4	2223

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.mastodon.tag.hacking	18
hispagatos.hacking.news	5
hispagatos.hacking.ctf	1
TOTAL: 3	24

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	1114
EMP (md5)	325
Too many newsgroups	298
EMP (phr path)	288
EMP (phn nph)	104
User-issued cancel	30
EMP (phl)	16
Too many newsgroups (meow)	15
HTML Multipart	6
Angle-bracket bot	4
HTML post	1
TOTAL: 11	2201

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
robot@pasdenom.info (spam)	4	6	0	6
robot@pasdenom.info (spam3)	8	0	64	64
robot@pasdenom.info (spam2)	22	0	50	50
i2pn2-nocem@i2pn2.org (multipost)	34	0	39	39
i2pn2-nocem@i2pn2.org (rmp)	4	0	8	8
TOTAL: 5	72	6	161	167

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	usenet.goja.nl.eu.org	8
	usenet.blueworldhosting.com	5
	usenet.network	3
	nntp.comgw.net	3
	endofthelinebbs-out.news.weretis.net	1
	newsfeed.xs3.de	1
	i2pn.org	1
	TOTAL: 7	22
TOTAL: 1		22

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	nyheter.lysator.liu.se	8124	2828	4000	417	0	34%	23:58:41
2	news.netfront.net	11895	1941	5299	1	0	16%	23:58:00
3	endofthelinebbs.peers.news.panix.com	7556	880	6349	16	0	11%	23:59:54
4	news.furie.org.uk	5876	717	3373	23	0	12%	23:59:00
5	photonic.trudheim.com	9087	647	5344	1256	0	7%	23:59:54
6	news.nntp4.net	7771	331	6800	11	15	4%	23:59:54
7	usenet.goja.nl.eu.org	7424	304	5909	164	0	4%	23:58:41
8	newsfeed.bofh.team	7966	126	6876	0	0	1%	23:59:26
9	news.corradoroberto.it	1075	124	569	0	0	11%	22:09:58
10	news.bbs.nz	13405	99	6610	531	69	0%	23:58:08
11	news.nk.ca	9331	97	6544	474	23	1%	23:59:54
12	news.weretis.net	7790	68	7039	140	0	0%	23:59:43
13	news.hispagatos.org	8339	65	6958	176	2	0%	23:59:54
14	news.quux.org	5362	56	4456	481	0	1%	23:59:54
15	usenet.network	8348	48	6060	10	0	0%	23:59:54
16	nntp.comgw.net	7407	46	6619	76	0	0%	23:59:38
17	i2pn.org	7777	44	6781	30	25	0%	23:59:54
18	news.tnetconsulting.net	8833	28	7018	198	6	0%	23:59:54
19	newsfeed.xs3.de	8603	28	6905	173	0	0%	23:59:34
20	news.chmurka.net	4766	19	3736	0	0	0%	23:59:54
21	usenet.blueworldhosting.com	6775	10	6348	0	0	0%	23:59:54
22	news.samoylyk.net	8841	5	7033	25	57	0%	23:59:54
23	ddt.demos.su	35	1	34	0	0	2%	04:38:19
24	feed.opticnetworks.net	0	0	0	0	7250	0%	23:59:54
TOTAL: 24		172386	8512	126660	4202	7447	4%	554:37:50

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.netfront.net	11.1 MB	2.4 KB	11.1 MB	0.1 KB/s	5.8 KB	23:58:00
2	nyheter.lysator.liu.se	9.0 MB	4.9 MB	13.9 MB	0.2 KB/s	4.4 KB	23:58:41
3	news.furie.org.uk	5.2 MB	109.5 KB	5.3 MB	0.1 KB/s	7.4 KB	23:59:00
4	endofthelinebbs.peers.news.panix.com	4.1 MB	54.5 KB	4.2 MB	0.0 KB/s	4.8 KB	23:59:54
5	photonic.trudheim.com	3.0 MB	3.7 MB	6.7 MB	0.1 KB/s	3.6 KB	23:59:54
6	news.quux.org	1.0 MB	3.3 MB	4.4 MB	0.1 KB/s	8.3 KB	23:59:54
7	newsfeed.bofh.team	1019.6 KB	0.0 KB	1019.6 KB	0.0 KB/s	8.1 KB	23:59:26
8	news.nntp4.net	842.2 KB	41.0 KB	883.2 KB	0.0 KB/s	2.6 KB	23:59:54
9	usenet.goja.nl.eu.org	640.8 KB	1.8 MB	2.4 MB	0.0 KB/s	5.3 KB	23:58:41
10	news.bbs.nz	388.4 KB	4.6 MB	5.0 MB	0.1 KB/s	8.1 KB	23:58:08
11	news.corradoroberto.it	329.6 KB	0.0 KB	329.6 KB	0.0 KB/s	2.7 KB	22:09:58
12	news.weretis.net	267.4 KB	2.9 MB	3.2 MB	0.0 KB/s	15.8 KB	23:59:43
13	news.nk.ca	246.6 KB	3.7 MB	3.9 MB	0.0 KB/s	7.1 KB	23:59:54
14	news.hispagatos.org	200.6 KB	1.7 MB	1.9 MB	0.0 KB/s	7.9 KB	23:59:54
15	i2pn.org	173.7 KB	150.2 KB	323.9 KB	0.0 KB/s	4.4 KB	23:59:54
16	usenet.network	144.5 KB	24.3 KB	168.8 KB	0.0 KB/s	2.9 KB	23:59:54
17	nntp.comgw.net	138.6 KB	1.5 MB	1.7 MB	0.0 KB/s	14.0 KB	23:59:38
18	news.samoylyk.net	132.5 KB	771.4 KB	903.9 KB	0.0 KB/s	30.1 KB	23:59:54
19	newsfeed.xs3.de	114.4 KB	1.5 MB	1.6 MB	0.0 KB/s	8.2 KB	23:59:34
20	ddt.demos.su	111.1 KB	0.0 KB	111.1 KB	0.0 KB/s	111.1 KB	04:38:19
21	news.tnetconsulting.net	93.6 KB	1.9 MB	1.9 MB	0.0 KB/s	8.8 KB	23:59:54
22	news.chmurka.net	53.5 KB	0.0 KB	53.5 KB	0.0 KB/s	2.8 KB	23:59:54
23	usenet.blueworldhosting.com	30.7 KB	0.0 KB	30.7 KB	0.0 KB/s	3.1 KB	23:59:54
24	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:54
TOTAL: 24		38.3 MB	32.6 MB	71.0 MB	0.0 KB/s	5.7 KB	554:37:50

NNRP readership statistics:

System		Conn	Elapsed
1	167.94.138.171	3	00:00:04
2	167.94.146.57	3	00:00:03
3	prod-barium-nyc1-6.do.binaryedge.ninja	3	00:00:04
4	147.185.132.36	1	00:00:10
5	205.210.31.135	1	00:00:10
6	azpdcsb94.stretchoid.com	1	00:00:09
7	azpdegb75.stretchoid.com	1	00:00:00
8	localhost	1	00:11:04
TOTAL: 8		14	00:11:47

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	8	00:00:28
2	*.do.binaryedge.ninja	3	00:00:04
3	*.stretchoid.com	2	00:00:09
4	?	1	00:11:04
TOTAL: 4		14	00:11:47

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
localhost	182.466	371.163	0.077	00:11:04
147.185.132.36	0.000	0.017	0.000	00:00:10
205.210.31.135	0.004	0.019	0.000	00:00:10
azpdcsb94.stretchoid.com	0.010	0.015	0.000	00:00:09
167.94.138.171	0.016	0.049	0.000	00:00:04
prod-barium-nyc1-6.do.binaryedge.ninja	0.003	0.063	0.000	00:00:04
167.94.146.57	0.023	0.037	0.000	00:00:03
azpdegb75.stretchoid.com	0.007	0.011	0.000	00:00:00
TOTAL: 8	182.529	371.374	0.077	00:11:47

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

System	Conn
167.94.138.171	1
167.94.146.57	1
azpdegb75.stretchoid.com	1
TOTAL: 3	3

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

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
167.94.138.171	1	2
167.94.146.57	1	2
TOTAL: 2	2	4

Daily Usenet Report

Feb 12 00:01:02 -- Feb 13 00:01:02