Daily Usenet Report: Dec 28 00:01:02

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Dec 28 08:16:45 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Dec 29 00:00:01 newsfeed nnrpd[701063]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
innd	9527	40.5%	1.4 MB	24.8%
inn	9064	38.5%	3.3 MB	59.1%
innfeed	4377	18.6%	837.6 KB	14.7%
cnfsstat	432	1.8%	65.7 KB	1.2%
nnrpd	97	0.4%	10.1 KB	0.2%
nocem	42	0.2%	5.7 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 7	23540	100.0%	5.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	115805	64.1%
Negative hits	55812	30.9%
Do not exist	8984	5.0%
Cache misses	8	0.0%
TOTAL: 4	180609	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.072	0.0%	18	0.000	4.000	11.000
article cleanup	00:00:00.051	0.0%	9012	0.000	0.006	0.081
article logging	00:00:00.436	0.0%	9069	0.000	0.048	0.505
article parse	00:00:00.203	0.0%	16276	0.000	0.012	0.067
article write	00:00:02.289	0.0%	6471	0.000	0.354	8.000
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.830	0.0%	187228	0.000	0.004	0.260
hisgrep/artcncl	00:00:00.062	0.0%	20	0.000	3.100	8.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:38.910	0.2%	181261	0.087	0.877	6.403
history sync	00:00:00.080	0.0%	405	0.000	0.198	1.000
history write	00:02:17.248	0.2%	9011	3.351	15.231	98.129
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:45:51.291	98.9%	181449	78.111	471.489	2501.417
nntp read	00:00:03.432	0.0%	188653	0.001	0.018	0.245
overview write	00:08:59.449	0.6%	6471	0.529	83.364	17886.048
perl filter	00:00:27.401	0.0%	9011	1.250	3.041	15.524
python filter	00:00:01.373	0.0%	9011	0.000	0.152	3.525
site send	00:00:01.072	0.0%	12983	0.000	0.083	0.235
TOTAL: 24:01:07.806	24:00:24.199	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.013	0.0%	6608	0.000	0.002	0.056
article prepare	00:00:00.002	0.0%	37196	0.000	0.000	0.006
article read	00:00:00.563	0.0%	3934	0.000	0.143	1.889
backlog stats	00:00:00.408	0.0%	235922	0.000	0.002	0.007
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.054	0.0%	158351	0.002	0.013	0.032
data write	00:00:02.738	0.0%	153567	0.003	0.018	0.036
idle	23:57:54.566	100.0%	235922	197.030	365.691	1321.632
status file	00:00:00.452	0.0%	377	0.750	1.199	10.000
TOTAL: 23:58:11.112	23:58:00.796	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:44.019	5.8%	31	0.000	1419.968	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:03.998	0.5%	78959	0.000	0.051	0.500
TOTAL: 00:12:34.582	00:00:48.017	6.4%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	endofthelinebbs-out.news.weretis.net	3	12970	1719	11003	248	13%	46:39:56
2	usenet.blueworldhosting.com	702	8610	976	6515	1119	11%	23:53:19
3	glockenspiel.complete.org	2	4593	783	3809	1	17%	19:25:01
4	nntp.comgw.net	2	8583	563	7502	518	6%	23:59:47
5	usenet.goja.nl.eu.org	11	8566	525	7876	165	6%	25:32:22
6	news.corradoroberto.it	10	891	247	644	0	27%	24:04:53
7	newsfeed.xs3.de	2	5705	243	5417	45	4%	23:58:13
8	194.87.241.33	43	362	242	120	0	66%	17:41:30
9	i2pn.org	1	8069	230	7669	170	2%	23:59:53
10	newsfeed.bofh.team	1	8864	170	8626	68	1%	23:59:57
11	news.samoylyk.net	2	6470	144	6323	3	2%	23:58:35
12	endoftheline-out.news.chmurka.net	1	3428	129	3298	1	3%	23:59:58
13	news.nntp4.net	1	6170	90	6077	3	1%	23:59:54
14	usenet.network	2	5521	88	5427	6	1%	23:59:41
15	news.netfront.net	2	7466	73	7246	147	0%	24:55:08
16	news.hispagatos.org	5	8446	55	8348	43	0%	24:26:23
17	endofthelinebbs.peers.news.panix.com	22	7318	35	7280	3	0%	23:55:37
18	news.furie.org.uk	2	3277	6	3271	0	0%	23:58:42
19	outgoing.news.lysator.liu.se	2	4443	4	4438	1	0%	23:58:53
20	news.bbs.nz	1	6321	3	6317	1	0%	23:59:34
21	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:07
22	localhost	26	0	0	0	0	0%	00:00:02
23	news.nk.ca	2	0	0	0	0	0%	00:20:11
TOTAL: 23		846	126073	6325	117206	2542	5%	494:57:36

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	endofthelinebbs-out.news.weretis.net	5.1 MB	0.0 KB	356.4 KB	5.5 MB	93%	2.8 KB
2	news.corradoroberto.it	3.2 MB	0.0 KB	0.0 KB	3.2 MB	100%	13.3 KB
3	glockenspiel.complete.org	2.4 MB	0.0 KB	0.7 KB	2.4 MB	99%	3.1 KB
4	usenet.blueworldhosting.com	2.2 MB	0.0 KB	2.4 MB	4.5 MB	47%	2.2 KB
5	usenet.network	1.6 MB	0.0 KB	5.7 KB	1.6 MB	99%	17.5 KB
6	usenet.goja.nl.eu.org	1.6 MB	0.0 KB	214.7 KB	1.8 MB	88%	2.7 KB
7	nntp.comgw.net	1.5 MB	2.5 KB	4.8 MB	6.3 MB	23%	6.0 KB
8	news.samoylyk.net	1.4 MB	0.0 KB	4.1 KB	1.4 MB	99%	9.6 KB
9	newsfeed.xs3.de	852.8 KB	0.0 KB	67.3 KB	920.0 KB	92%	3.2 KB
10	194.87.241.33	673.3 KB	0.0 KB	0.0 KB	673.3 KB	100%	2.8 KB
11	newsfeed.bofh.team	600.7 KB	0.0 KB	442.6 KB	1.0 MB	57%	4.4 KB
12	endoftheline-out.news.chmurka.net	595.3 KB	0.0 KB	0.7 KB	596.0 KB	99%	4.6 KB
13	i2pn.org	506.0 KB	0.0 KB	222.5 KB	728.5 KB	69%	1.8 KB
14	news.nntp4.net	300.8 KB	0.0 KB	7.7 KB	308.6 KB	97%	3.3 KB
15	news.hispagatos.org	196.4 KB	0.0 KB	75.8 KB	272.2 KB	72%	2.8 KB
16	news.netfront.net	192.5 KB	0.0 KB	3.0 MB	3.2 MB	5%	15.0 KB
17	endofthelinebbs.peers.news.panix.com	129.9 KB	0.0 KB	2.8 KB	132.7 KB	97%	3.5 KB
18	news.furie.org.uk	14.5 KB	0.0 KB	0.0 KB	14.5 KB	100%	2.4 KB
19	outgoing.news.lysator.liu.se	12.3 KB	0.0 KB	0.7 KB	13.0 KB	94%	2.6 KB
20	news.bbs.nz	8.2 KB	1.5 KB	0.0 KB	9.7 KB	84%	2.4 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		22.9 MB	4.0 KB	11.6 MB	34.5 MB	66%	4.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 28 00:01:02 - 00:59:59	115	1.8%	0.03	398.8 KB	1.7%	0.11
Dec 28 01:00:00 - 01:59:59	252	3.9%	0.07	758.2 KB	3.2%	0.21
Dec 28 02:00:00 - 02:59:59	318	4.9%	0.09	826.8 KB	3.4%	0.23
Dec 28 03:00:00 - 03:59:59	298	4.6%	0.08	1.0 MB	4.4%	0.29
Dec 28 04:00:00 - 04:59:59	360	5.6%	0.10	1.0 MB	4.3%	0.29
Dec 28 05:00:00 - 05:59:59	364	5.6%	0.10	1.0 MB	4.4%	0.30
Dec 28 06:00:00 - 06:59:59	344	5.3%	0.10	1.5 MB	6.2%	0.42
Dec 28 07:00:00 - 07:59:59	424	6.6%	0.12	1.3 MB	5.4%	0.36
Dec 28 08:00:00 - 08:59:59	353	5.5%	0.10	1.1 MB	4.5%	0.30
Dec 28 09:00:00 - 09:59:59	356	5.5%	0.10	912.1 KB	3.8%	0.25
Dec 28 10:00:00 - 10:59:59	344	5.3%	0.10	2.4 MB	10.2%	0.68
Dec 28 11:00:00 - 11:59:59	381	5.9%	0.11	2.4 MB	10.2%	0.68
Dec 28 12:00:00 - 12:59:59	342	5.3%	0.10	965.6 KB	4.0%	0.27
Dec 28 13:00:00 - 13:59:59	357	5.5%	0.10	975.3 KB	4.1%	0.27
Dec 28 14:00:00 - 14:59:59	324	5.0%	0.09	1.5 MB	6.5%	0.44
Dec 28 15:00:00 - 15:59:59	259	4.0%	0.07	859.0 KB	3.6%	0.24
Dec 28 16:00:00 - 16:59:59	275	4.3%	0.08	1.0 MB	4.3%	0.29
Dec 28 17:00:00 - 17:59:59	248	3.8%	0.07	956.5 KB	4.0%	0.27
Dec 28 18:00:00 - 18:59:59	188	2.9%	0.05	653.0 KB	2.7%	0.18
Dec 28 19:00:00 - 19:59:59	162	2.5%	0.04	602.5 KB	2.5%	0.17
Dec 28 20:00:00 - 20:59:59	106	1.6%	0.03	295.9 KB	1.2%	0.08
Dec 28 21:00:00 - 21:59:59	103	1.6%	0.03	632.6 KB	2.6%	0.18
Dec 28 22:00:00 - 22:59:59	85	1.3%	0.02	331.7 KB	1.4%	0.09
Dec 28 23:00:00 - 23:59:59	103	1.6%	0.03	324.4 KB	1.4%	0.09
Dec 29 00:00:00 - 00:01:02	3	0.0%	0.05	5.9 KB	0.0%	0.10
TOTAL: 24:00:00	6464	100.0%	0.07	23.5 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	101.6 GB	6.6%	0	0.44	42693.58
CYCTXT1	TEXT	5.0 GB	4.0 GB	79.8%	0	0.02	2434.96
TOTAL: 3	-	3077.0 GB	105.6 GB	3.4%	1	0.31	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	1137	1	0	1136
2	nntp.comgw.net	528	0	1	527
3	endofthelinebbs-out.news.weretis.net	265	0	0	265
4	i2pn.org	176	0	0	176
5	usenet.goja.nl.eu.org	172	0	0	172
6	news.netfront.net	141	0	0	141
7	newsfeed.bofh.team	67	2	0	65
8	news.hispagatos.org	45	24	0	21
9	newsfeed.xs3.de	45	0	0	45
10	usenet.network	7	0	0	7
11	endofthelinebbs.peers.news.panix.com	5	1	0	4
12	news.nntp4.net	3	0	0	3
13	news.samoylyk.net	3	0	0	3
14	glockenspiel.complete.org	2	0	0	2
15	endoftheline-out.news.chmurka.net	1	0	0	1
16	outgoing.news.lysator.liu.se	1	0	0	1
TOTAL: 16		2598	28	1	2569

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.mastodon.tag.hacking	13
hispagatos.hacking.ctf	10
soc.culture.russia	3
desci.psychologie	1
hispagatos.hacking.news	1
TOTAL: 5	28

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	1566
Too many newsgroups	346
EMP (phr path)	300
EMP (md5)	264
User-issued cancel	27
EMP (fsl)	5
hierarchy violation - crosspost outside sdnet	2
Subject (LSDawSDEwd)	1
Too many newsgroups (meow)	1
TOTAL: 9	2512

NoCeM on Spool:

Issuer (type)	Notices	Skip	Total
i2pn2-nocem@i2pn2.org (multipost)	26	33	33
robot@pasdenom.info (spam3)	7	12	12
i2pn2-nocem@i2pn2.org (rmp)	5	9	9
robot@pasdenom.info (spam2)	3	3	3
TOTAL: 4	41	57	57

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	usenet.blueworldhosting.com	18
	endofthelinebbs-out.news.weretis.net	15
	i2pn.org	6
	nntp.comgw.net	6
	usenet.goja.nl.eu.org	5
	endofthelinebbs.peers.news.panix.com	2
	newsfeed.xs3.de	2
	newsfeed.bofh.team	1
	usenet.network	1
	glockenspiel.complete.org	1
	TOTAL: 10	57
TOTAL: 1		57

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	nyheter.lysator.liu.se	6937	2802	3519	139	0	40%	23:59:53
2	news.netfront.net	10550	1633	4745	11	17	15%	23:59:53
3	endofthelinebbs.peers.news.panix.com	6630	925	5517	8	0	13%	23:59:53
4	news.furie.org.uk	3894	611	2331	13	0	15%	23:59:37
5	usenet.goja.nl.eu.org	6988	329	5491	38	1	4%	23:59:41
6	news.corradoroberto.it	785	181	407	0	2	23%	22:42:02
7	usenet.network	8224	136	5785	11	2	1%	23:59:50
8	newsfeed.bofh.team	7248	109	6162	1	2	1%	23:59:53
9	news.nntp4.net	7215	108	6229	2	1	1%	23:59:18
10	news.hispagatos.org	7635	97	6214	91	1	1%	23:59:53
11	i2pn.org	6971	90	6085	34	31	1%	23:59:53
12	nntp.comgw.net	6436	87	5807	32	0	1%	23:59:53
13	news.nk.ca	8496	73	5834	431	12	0%	23:59:53
14	news.bbs.nz	12066	65	6060	333	77	0%	23:59:52
15	news.quux.org	5294	50	4567	340	0	0%	23:59:41
16	news.tnetconsulting.net	7219	47	6275	127	8	0%	23:59:53
17	news.weretis.net	6703	46	6367	50	0	0%	23:59:53
18	newsfeed.xs3.de	7654	27	6049	147	2	0%	23:59:53
19	news.chmurka.net	4223	26	3077	2	1	0%	23:59:53
20	news.samoylyk.net	8116	20	6197	35	2	0%	23:59:47
21	ddt.demos.su	55	5	49	0	0	9%	06:09:06
22	usenet.blueworldhosting.com	5565	2	5168	0	5	0%	23:59:53
23	feed.opticnetworks.net	0	0	0	0	6464	0%	23:59:53
24	photonic.trudheim.com	0	0	0	0	6461	0%	23:59:53
TOTAL: 24		144904	7469	107935	1845	13089	5%	556:47:09

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nyheter.lysator.liu.se	9.0 MB	744.4 KB	9.8 MB	0.1 KB/s	3.4 KB	23:59:53
2	news.netfront.net	8.2 MB	60.6 KB	8.3 MB	0.1 KB/s	5.1 KB	23:59:53
3	news.furie.org.uk	2.7 MB	81.6 KB	2.7 MB	0.0 KB/s	4.5 KB	23:59:37
4	endofthelinebbs.peers.news.panix.com	2.3 MB	18.4 KB	2.3 MB	0.0 KB/s	2.5 KB	23:59:53
5	news.quux.org	1.5 MB	658.3 KB	2.1 MB	0.0 KB/s	5.6 KB	23:59:41
6	news.samoylyk.net	1.3 MB	58.7 KB	1.4 MB	0.0 KB/s	25.7 KB	23:59:47
7	usenet.goja.nl.eu.org	620.0 KB	139.3 KB	759.3 KB	0.0 KB/s	2.1 KB	23:59:41
8	ddt.demos.su	603.3 KB	0.0 KB	603.3 KB	0.0 KB/s	120.7 KB	06:09:06
9	news.nntp4.net	553.7 KB	8.8 KB	562.4 KB	0.0 KB/s	5.1 KB	23:59:18
10	newsfeed.bofh.team	478.2 KB	2.0 KB	480.2 KB	0.0 KB/s	4.4 KB	23:59:53
11	news.corradoroberto.it	434.2 KB	0.0 KB	434.2 KB	0.0 KB/s	2.4 KB	22:42:02
12	usenet.network	345.5 KB	22.5 KB	367.9 KB	0.0 KB/s	2.5 KB	23:59:50
13	i2pn.org	278.0 KB	83.6 KB	361.5 KB	0.0 KB/s	2.9 KB	23:59:53
14	news.bbs.nz	268.0 KB	1.6 MB	1.8 MB	0.0 KB/s	4.7 KB	23:59:52
15	nntp.comgw.net	262.1 KB	1.3 MB	1.6 MB	0.0 KB/s	13.5 KB	23:59:53
16	news.hispagatos.org	259.2 KB	571.1 KB	830.3 KB	0.0 KB/s	4.4 KB	23:59:53
17	news.weretis.net	242.8 KB	1.2 MB	1.4 MB	0.0 KB/s	15.3 KB	23:59:53
18	news.tnetconsulting.net	160.2 KB	375.3 KB	535.6 KB	0.0 KB/s	3.1 KB	23:59:53
19	news.nk.ca	146.9 KB	2.0 MB	2.2 MB	0.0 KB/s	4.5 KB	23:59:53
20	newsfeed.xs3.de	140.4 KB	1.5 MB	1.6 MB	0.0 KB/s	9.6 KB	23:59:53
21	news.chmurka.net	87.8 KB	3.2 KB	91.0 KB	0.0 KB/s	3.3 KB	23:59:53
22	usenet.blueworldhosting.com	2.9 KB	0.0 KB	2.9 KB	0.0 KB/s	1.5 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		29.8 MB	10.4 MB	40.1 MB	0.0 KB/s	4.4 KB	556:47:09

NNRP readership statistics:

System		Conn	Elapsed
1	206.168.34.42	5	00:00:47
2	162.142.125.201	3	00:00:08
3	167.94.146.57	3	00:00:03
4	194.165.16.73	1	00:00:00
5	198.235.24.109	1	00:00:10
6	198.235.24.205	1	00:00:10
7	223.149.216.162.bc.googleusercontent.com	1	00:00:00
8	azpdegc63.stretchoid.com	1	00:00:00
9	azpdwsb37.stretchoid.com	1	00:00:09
10	localhost	1	00:11:04
TOTAL: 10		18	00:12:34

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	14	00:01:20
2	*.stretchoid.com	2	00:00:09
3	*.149.216.162.bc.googleusercontent.com	1	00:00:00
4	?	1	00:11:04
TOTAL: 4		18	00:12:34

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	177.003	372.856	0.074	00:11:04
206.168.34.42	0.019	0.080	0.000	00:00:47
198.235.24.205	0.000	0.024	0.000	00:00:10
198.235.24.109	0.004	0.012	0.000	00:00:10
azpdwsb37.stretchoid.com	0.000	0.027	0.000	00:00:09
162.142.125.201	0.018	0.043	0.000	00:00:08
167.94.146.57	0.021	0.054	0.000	00:00:03
194.165.16.73	0.006	0.011	0.000	00:00:00
azpdegc63.stretchoid.com	0.008	0.008	0.000	00:00:00
223.149.216.162.bc.googleusercontent.com	0.000	0.017	0.000	00:00:00
TOTAL: 10	177.079	373.132	0.074	00:12:34

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

System	Conn
223.149.216.162.bc.googleusercontent.com	4
206.168.34.42	3
162.142.125.201	1
167.94.146.57	1
194.165.16.73	1
azpdegc63.stretchoid.com	1
TOTAL: 6	11

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

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
206.168.34.42	2	2
162.142.125.201	1	2
167.94.146.57	1	2
194.165.16.73	1	1
TOTAL: 4	5	7

Daily Usenet Report

Dec 28 00:01:02 -- Dec 29 00:01:02