Daily Usenet Report: Nov 28 00:01:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Nov 29 00:00:01 newsfeed nnrpd[1388885]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	8965	39.6%	3.6 MB	62.2%
innd	8626	38.1%	1.3 MB	21.8%
innfeed	4461	19.7%	872.4 KB	14.7%
cnfsstat	432	1.9%	66.2 KB	1.1%
nnrpd	140	0.6%	14.0 KB	0.2%
nocem	37	0.2%	4.2 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 7	22662	100.0%	5.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	119925	65.8%
Negative hits	53405	29.3%
Do not exist	8916	4.9%
Cache misses	26	0.0%
TOTAL: 4	182272	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.160	0.0%	30	0.000	5.333	11.000
article cleanup	00:00:00.064	0.0%	8939	0.000	0.007	0.077
article logging	00:00:00.472	0.0%	8965	0.000	0.053	1.347
article parse	00:00:00.173	0.0%	13048	0.000	0.013	0.073
article write	00:00:01.752	0.0%	7354	0.000	0.238	2.826
artlog/artcncl	00:00:00.000	0.0%	2	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.238	0.0%	176662	0.000	0.001	0.095
hisgrep/artcncl	00:00:00.130	0.0%	32	0.000	4.062	9.000
hishave/artcncl	00:00:00.000	0.0%	2	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:51.607	0.2%	182497	0.108	0.940	4.950
history sync	00:00:00.058	0.0%	410	0.000	0.141	0.667
history write	00:01:59.221	0.1%	8922	3.235	13.363	97.778
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:15.934	99.0%	170866	208.568	501.188	1986.235
nntp read	00:00:02.428	0.0%	177935	0.000	0.014	0.203
overview write	00:08:06.332	0.6%	7354	0.706	66.132	8726.826
perl filter	00:00:18.503	0.0%	8922	1.230	2.074	6.493
python filter	00:00:00.542	0.0%	8922	0.000	0.061	1.845
site send	00:00:01.146	0.0%	14744	0.000	0.078	0.239
TOTAL: 24:01:09.737	24:00:38.760	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.018	0.0%	7693	0.000	0.002	0.077
article prepare	00:00:00.008	0.0%	59732	0.000	0.000	0.005
article read	00:00:00.690	0.0%	5409	0.000	0.128	0.634
backlog stats	00:00:00.514	0.0%	288942	0.000	0.002	0.121
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.428	0.0%	189150	0.003	0.013	0.051
data write	00:00:03.366	0.0%	184309	0.007	0.018	0.037
idle	23:57:46.758	100.0%	288942	125.329	298.561	1053.695
status file	00:00:00.453	0.0%	416	0.800	1.089	2.000
TOTAL: 23:58:05.075	23:57:54.235	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:31.240	4.4%	27	8.750	1157.037	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:03.849	0.5%	78855	0.000	0.049	0.500
TOTAL: 00:11:44.396	00:00:35.089	5.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	endofthelinebbs-out.news.weretis.net	5	14352	3118	10971	263	21%	48:35:43
2	usenet.blueworldhosting.com	719	8720	903	7111	706	10%	23:48:51
3	glockenspiel.complete.org	2	9510	792	8715	3	8%	36:17:21
4	nntp.comgw.net	2	8278	467	7530	281	5%	23:59:53
5	news.corradoroberto.it	10	1124	460	664	0	40%	23:15:59
6	194.87.241.33	45	397	311	85	1	78%	16:52:24
7	usenet.goja.nl.eu.org	10	8101	271	7762	68	3%	24:19:56
8	newsfeed.bofh.team	1	8596	247	8273	76	2%	23:59:55
9	i2pn.org	1	8029	238	7751	40	2%	23:59:55
10	endoftheline-out.news.chmurka.net	1	3559	194	3365	0	5%	23:59:58
11	usenet.network	1	5773	183	5576	14	3%	23:59:59
12	endofthelinebbs.peers.news.panix.com	27	7815	105	7697	13	1%	23:57:48
13	news.netfront.net	1	6567	57	6461	49	0%	24:29:12
14	news.nntp4.net	2	6734	48	6668	18	0%	23:59:51
15	news.hispagatos.org	6	8288	33	8216	39	0%	24:26:12
16	news.samoylyk.net	3	7069	24	7043	2	0%	23:53:28
17	news.furie.org.uk	2	3658	23	3634	1	0%	23:59:26
18	newsfeed.xs3.de	1	6516	18	6478	20	0%	23:59:56
19	news.bbs.nz	1	7033	7	7025	1	0%	23:59:38
20	outgoing.news.lysator.liu.se	2	3826	0	3825	1	0%	23:59:33
21	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:05
22	localhost	26	0	0	0	0	0%	00:00:00
23	news.nk.ca	2	0	0	0	0	0%	00:20:11
TOTAL: 23		871	133945	7499	124850	1596	5%	510:25:14

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	endofthelinebbs-out.news.weretis.net	8.6 MB	9.6 KB	515.8 KB	9.1 MB	94%	2.8 KB
2	glockenspiel.complete.org	3.7 MB	4.7 KB	1.7 KB	3.7 MB	99%	4.8 KB
3	usenet.blueworldhosting.com	2.7 MB	0.0 KB	1.5 MB	4.2 MB	63%	2.7 KB
4	news.corradoroberto.it	2.5 MB	0.0 KB	0.0 KB	2.5 MB	100%	5.6 KB
5	usenet.network	1.7 MB	4.8 KB	16.5 KB	1.8 MB	98%	9.2 KB
6	nntp.comgw.net	1.2 MB	4.8 KB	1.2 MB	2.4 MB	50%	3.3 KB
7	194.87.241.33	1011.5 KB	0.0 KB	1.9 KB	1013.4 KB	99%	3.2 KB
8	usenet.goja.nl.eu.org	845.9 KB	4.8 KB	84.8 KB	935.5 KB	90%	2.8 KB
9	i2pn.org	744.9 KB	4.8 KB	60.4 KB	810.1 KB	91%	2.9 KB
10	news.samoylyk.net	657.4 KB	4.8 KB	0.6 KB	662.8 KB	99%	25.5 KB
11	endoftheline-out.news.chmurka.net	642.7 KB	0.0 KB	0.0 KB	642.7 KB	100%	3.3 KB
12	newsfeed.bofh.team	566.3 KB	4.8 KB	121.3 KB	692.3 KB	81%	2.1 KB
13	endofthelinebbs.peers.news.panix.com	259.3 KB	4.7 KB	29.9 KB	294.0 KB	88%	2.5 KB
14	news.nntp4.net	152.6 KB	4.8 KB	57.5 KB	214.8 KB	71%	3.3 KB
15	news.netfront.net	91.8 KB	4.8 KB	705.1 KB	801.8 KB	11%	7.6 KB
16	news.hispagatos.org	86.5 KB	4.8 KB	75.3 KB	166.6 KB	51%	2.3 KB
17	news.furie.org.uk	84.4 KB	4.8 KB	0.0 KB	89.2 KB	94%	3.7 KB
18	newsfeed.xs3.de	75.6 KB	4.8 KB	52.0 KB	132.4 KB	57%	3.5 KB
19	news.bbs.nz	11.2 KB	4.8 KB	0.0 KB	15.9 KB	69%	2.0 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	outgoing.news.lysator.liu.se	0.0 KB	4.8 KB	0.0 KB	4.8 KB	0%	4.8 KB
TOTAL: 23		25.6 MB	81.4 KB	4.4 MB	30.1 MB	85%	3.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 28 00:01:01 - 00:59:59	184	2.5%	0.05	571.7 KB	2.3%	0.16
Nov 28 01:00:00 - 01:59:59	260	3.5%	0.07	732.6 KB	2.9%	0.20
Nov 28 02:00:00 - 02:59:59	378	5.1%	0.10	1.2 MB	4.7%	0.33
Nov 28 03:00:00 - 03:59:59	391	5.3%	0.11	1.5 MB	6.2%	0.44
Nov 28 04:00:00 - 04:59:59	418	5.7%	0.12	1.1 MB	4.6%	0.32
Nov 28 05:00:00 - 05:59:59	319	4.3%	0.09	1.0 MB	4.2%	0.29
Nov 28 06:00:00 - 06:59:59	367	5.0%	0.10	1.1 MB	4.5%	0.31
Nov 28 07:00:00 - 07:59:59	394	5.4%	0.11	1.1 MB	4.4%	0.31
Nov 28 08:00:00 - 08:59:59	411	5.6%	0.11	1.2 MB	5.0%	0.35
Nov 28 09:00:00 - 09:59:59	427	5.8%	0.12	1.2 MB	5.0%	0.35
Nov 28 10:00:00 - 10:59:59	430	5.8%	0.12	1.4 MB	5.5%	0.38
Nov 28 11:00:00 - 11:59:59	482	6.6%	0.13	1.5 MB	6.1%	0.42
Nov 28 12:00:00 - 12:59:59	439	6.0%	0.12	1.7 MB	6.8%	0.48
Nov 28 13:00:00 - 13:59:59	366	5.0%	0.10	1.3 MB	5.4%	0.38
Nov 28 14:00:00 - 14:59:59	308	4.2%	0.09	978.0 KB	3.9%	0.27
Nov 28 15:00:00 - 15:59:59	284	3.9%	0.08	1.5 MB	5.9%	0.41
Nov 28 16:00:00 - 16:59:59	295	4.0%	0.08	1022.5 KB	4.1%	0.28
Nov 28 17:00:00 - 17:59:59	276	3.8%	0.08	1.2 MB	4.8%	0.34
Nov 28 18:00:00 - 18:59:59	222	3.0%	0.06	760.1 KB	3.0%	0.21
Nov 28 19:00:00 - 19:59:59	169	2.3%	0.05	707.9 KB	2.8%	0.20
Nov 28 20:00:00 - 20:59:59	150	2.0%	0.04	609.2 KB	2.4%	0.17
Nov 28 21:00:00 - 21:59:59	118	1.6%	0.03	508.7 KB	2.0%	0.14
Nov 28 22:00:00 - 22:59:59	124	1.7%	0.03	376.4 KB	1.5%	0.10
Nov 28 23:00:00 - 23:59:59	136	1.8%	0.04	476.1 KB	1.9%	0.13
Nov 29 00:00:00 - 00:01:02	4	0.1%	0.06	16.8 KB	0.1%	0.27
TOTAL: 24:00:01	7352	100.0%	0.09	24.6 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	100.5 GB	6.5%	0	0.47	39867.70
CYCTXT1	TEXT	5.0 GB	3.9 GB	79.0%	0	0.02	2815.11
TOTAL: 3	-	3077.0 GB	104.5 GB	3.4%	1	0.37	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	709	0	0	709
2	nntp.comgw.net	284	0	1	283
3	endofthelinebbs-out.news.weretis.net	268	0	2	266
4	usenet.goja.nl.eu.org	82	0	1	81
5	newsfeed.bofh.team	75	0	1	74
6	news.netfront.net	50	0	1	49
7	i2pn.org	38	0	1	37
8	news.hispagatos.org	36	27	1	8
9	newsfeed.xs3.de	20	0	1	19
10	news.nntp4.net	19	0	1	18
11	endofthelinebbs.peers.news.panix.com	15	0	1	14
12	usenet.network	7	0	1	6
13	glockenspiel.complete.org	2	0	1	1
14	news.samoylyk.net	2	0	1	1
15	194.87.241.33	1	0	0	1
16	news.bbs.nz	1	0	1	0
17	news.furie.org.uk	1	0	1	0
18	outgoing.news.lysator.liu.se	1	0	1	0
TOTAL: 18		1611	27	17	1567

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.mastodon.tag.hacking	13
hispagatos.hacking.ctf	9
hispagatos.hacking.news	4
hispagatos.mastodon.tag.hackers	1
TOTAL: 4	27

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	809
EMP (phr path)	323
EMP (md5)	185
Too many newsgroups	115
EMP (phn nph)	56
User-issued cancel	41
HTML Multipart	5
EMP (fsl)	4
Too many newsgroups (meow)	2
HTML post	1
TOTAL: 10	1541

NoCeM on Spool:

Issuer (type)	Notices	Skip	Total
i2pn2-nocem@i2pn2.org (multipost)	17	19	19
robot@pasdenom.info (spam3)	6	15	15
i2pn2-nocem@i2pn2.org (rmp)	7	11	11
robot@pasdenom.info (spam2)	6	6	6
TOTAL: 4	36	51	51

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	endofthelinebbs-out.news.weretis.net	20
	usenet.blueworldhosting.com	3
	news.nntp4.net	1
	endofthelinebbs.peers.news.panix.com	1
	nntp.comgw.net	1
	TOTAL: 5	26
TOTAL: 1		26

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	nyheter.lysator.liu.se	7736	3951	3150	251	0	51%	23:59:53
2	news.netfront.net	11668	2353	4931	0	4	20%	23:59:53
3	news.furie.org.uk	4687	731	2570	11	0	15%	23:59:53
4	usenet.goja.nl.eu.org	8779	515	6210	184	1	5%	23:59:47
5	endofthelinebbs.peers.news.panix.com	7591	508	6834	5	0	6%	23:59:21
6	usenet.network	11353	468	6386	21	0	4%	23:59:53
7	news.nntp4.net	8750	468	6602	217	9	5%	23:59:50
8	newsfeed.bofh.team	8677	305	6813	0	1	3%	23:59:53
9	news.bbs.nz	13539	300	6584	459	45	2%	23:59:50
10	news.hispagatos.org	9375	249	6952	115	0	2%	23:59:11
11	news.corradoroberto.it	1108	234	542	0	0	21%	22:32:37
12	news.nk.ca	9907	227	6354	605	5	2%	23:59:53
13	news.tnetconsulting.net	8252	196	6889	261	0	2%	23:58:27
14	nntp.comgw.net	7829	196	6579	112	0	2%	23:59:53
15	i2pn.org	8081	178	6846	66	32	2%	23:59:43
16	news.weretis.net	7630	161	7045	145	0	2%	23:58:19
17	news.quux.org	5583	149	4508	596	1	2%	23:59:53
18	newsfeed.xs3.de	9433	99	7001	221	2	1%	23:59:53
19	news.samoylyk.net	10272	84	7004	226	246	0%	23:59:53
20	ddt.demos.su	80	27	49	0	0	33%	08:30:14
21	usenet.blueworldhosting.com	6335	25	6175	0	0	0%	23:59:53
22	news.chmurka.net	4639	23	3151	0	0	0%	23:59:53
23	feed.opticnetworks.net	0	0	0	0	7352	0%	23:59:53
24	photonic.trudheim.com	0	0	0	0	7349	0%	23:59:53
TOTAL: 24		171304	11447	119175	3495	15047	6%	558:55:41

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nyheter.lysator.liu.se	12.6 MB	1.8 MB	14.4 MB	0.2 KB/s	3.5 KB	23:59:53
2	news.netfront.net	9.6 MB	0.0 KB	9.6 MB	0.1 KB/s	4.2 KB	23:59:53
3	news.furie.org.uk	3.2 MB	45.2 KB	3.3 MB	0.0 KB/s	4.5 KB	23:59:53
4	endofthelinebbs.peers.news.panix.com	2.4 MB	15.2 KB	2.4 MB	0.0 KB/s	4.9 KB	23:59:21
5	news.nntp4.net	1.8 MB	1.6 MB	3.4 MB	0.0 KB/s	5.2 KB	23:59:50
6	usenet.network	1.7 MB	90.2 KB	1.8 MB	0.0 KB/s	3.8 KB	23:59:53
7	newsfeed.bofh.team	1.6 MB	0.0 KB	1.6 MB	0.0 KB/s	5.5 KB	23:59:53
8	usenet.goja.nl.eu.org	1.3 MB	1.5 MB	2.8 MB	0.0 KB/s	4.1 KB	23:59:47
9	news.bbs.nz	1.2 MB	2.0 MB	3.2 MB	0.0 KB/s	4.3 KB	23:59:50
10	news.quux.org	1.1 MB	2.3 MB	3.4 MB	0.0 KB/s	4.6 KB	23:59:53
11	news.tnetconsulting.net	905.8 KB	1.1 MB	2.0 MB	0.0 KB/s	4.4 KB	23:58:27
12	news.hispagatos.org	880.4 KB	1.0 MB	1.9 MB	0.0 KB/s	5.3 KB	23:59:11
13	news.weretis.net	738.2 KB	1.2 MB	1.9 MB	0.0 KB/s	6.3 KB	23:58:19
14	news.samoylyk.net	612.9 KB	1.0 MB	1.6 MB	0.0 KB/s	5.3 KB	23:59:53
15	i2pn.org	601.9 KB	212.2 KB	814.1 KB	0.0 KB/s	3.3 KB	23:59:43
16	news.nk.ca	592.6 KB	2.8 MB	3.4 MB	0.0 KB/s	4.2 KB	23:59:53
17	news.corradoroberto.it	591.6 KB	0.0 KB	591.6 KB	0.0 KB/s	2.5 KB	22:32:37
18	nntp.comgw.net	585.0 KB	1.0 MB	1.6 MB	0.0 KB/s	5.4 KB	23:59:53
19	newsfeed.xs3.de	466.8 KB	1.2 MB	1.6 MB	0.0 KB/s	5.2 KB	23:59:53
20	ddt.demos.su	177.1 KB	0.0 KB	177.1 KB	0.0 KB/s	6.6 KB	08:30:14
21	news.chmurka.net	79.6 KB	0.0 KB	79.6 KB	0.0 KB/s	3.5 KB	23:59:53
22	usenet.blueworldhosting.com	56.7 KB	0.0 KB	56.7 KB	0.0 KB/s	2.3 KB	23:59:53
23	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:53
24	photonic.trudheim.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:53
TOTAL: 24		42.7 MB	18.9 MB	61.6 MB	0.0 KB/s	4.2 KB	558:55:41

NNRP readership statistics (by domain):

System		Conn	Groups	Elapsed
1	unresolved	9	1	00:00:30
2	*.us-east-2.compute.amazonaws.com	4	0	00:00:00
3	*.monitoring.internet-measurement.com	1	0	00:00:02
4	*.stretchoid.com	1	0	00:00:00
5	?	1	0	00:11:10
TOTAL: 5		16	1	00:11:44

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.899	370.695	0.153	00:11:10
198.235.24.150	0.007	0.010	0.000	00:00:10
104.152.52.133	0.003	0.014	0.000	00:00:10
167.94.138.120	0.022	0.054	0.000	00:00:07
joyful.monitoring.internet-measurement.com	0.003	0.014	0.000	00:00:02
147.185.132.90	0.010	0.021	0.000	00:00:02
ec2-13-58-97-162.us-east-2.compute.amazonaws.com	0.014	0.052	0.000	00:00:00
2.57.122.236	0.004	0.012	0.000	00:00:00
88.214.25.63	0.000	0.017	0.000	00:00:00
azpdsg4.stretchoid.com	0.006	0.011	0.000	00:00:00
104.152.52.124	0.000	0.017	0.000	00:00:00
TOTAL: 11	182.968	370.917	0.153	00:11:44

Curious NNRP server explorers [Top 20]:

System	Conn
ec2-13-58-97-162.us-east-2.compute.amazonaws.com	4
167.94.138.120	3
104.152.52.124	1
104.152.52.133	1
147.185.132.90	1
198.235.24.150	1
2.57.122.236	1
88.214.25.63	1
azpdsg4.stretchoid.com	1
joyful.monitoring.internet-measurement.com	1
localhost	1
TOTAL: 11	16

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

System	Conn
ec2-13-58-97-162.us-east-2.compute.amazonaws.com	7
167.94.138.120	1
88.214.25.63	1
azpdsg4.stretchoid.com	1
TOTAL: 4	10

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

Command	Count
#001	1
#003	1
#026#003#001	1
Accept-Encoding: gzip	1
Accept: /	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: Mozilla/5.0 (Macintosh; Inte...	1
TOTAL: 10	10

NNRP client timeouts [Top 20]:

System	Conn	Peer
167.94.138.120	1	2
88.214.25.63	1	1
TOTAL: 2	2	4

Newsgroup request counts (by hierarchy):

Hierarchy		Count	Pct
1	news	1	100.0%
TOTAL: 1		1	100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

Newsgroup		Count
1	news.software.nntp	1
TOTAL: 1		1

Daily Usenet Report

Nov 28 00:01:01 -- Nov 29 00:01:02