Daily Usenet Report: Jan 3 00:01:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jan 4 00:00:01 newsfeed nnrpd[4117177]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
innd	11173	44.1%	1.6 MB	26.7%
inn	9134	36.1%	3.6 MB	58.5%
innfeed	4482	17.7%	840.7 KB	13.5%
cnfsstat	432	1.7%	67.2 KB	1.1%
nocem	59	0.2%	6.4 KB	0.1%
nnrpd	47	0.2%	4.9 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 7	25328	100.0%	6.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	120911	64.7%
Negative hits	56978	30.5%
Do not exist	8988	4.8%
Cache misses	20	0.0%
TOTAL: 4	186897	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.195	0.0%	42	0.000	4.643	18.000
article cleanup	00:00:00.065	0.0%	9071	0.000	0.007	0.053
article logging	00:00:00.393	0.0%	9126	0.000	0.043	0.146
article parse	00:00:00.231	0.0%	15540	0.000	0.015	0.089
article write	00:00:02.111	0.0%	7200	0.000	0.293	3.228
artlog/artcncl	00:00:00.000	0.0%	11	0.000	0.000	0.000
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:00:01.903	0.0%	193520	0.000	0.010	0.276
hisgrep/artcncl	00:00:00.143	0.0%	51	0.000	2.804	10.000
hishave/artcncl	00:00:00.001	0.0%	11	0.000	0.091	1.000
hishave/artparse	00:00:00.000	0.0%	2	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:50.298	0.2%	188577	0.114	0.903	5.337
history sync	00:00:00.077	0.0%	381	0.000	0.202	2.667
history write	00:02:09.118	0.1%	9056	3.421	14.258	133.125
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.005	0.0%	2	2.000	2.500	3.000
idle	23:50:12.605	99.1%	189691	74.587	452.381	1629.514
nntp read	00:00:03.810	0.0%	196362	0.002	0.019	0.493
overview write	00:06:11.188	0.4%	7200	0.556	51.554	8377.000
perl filter	00:00:31.889	0.0%	9056	1.264	3.521	56.210
python filter	00:00:01.838	0.0%	9056	0.000	0.203	12.519
site send	00:00:01.169	0.0%	14456	0.000	0.081	0.202
TOTAL: 24:02:46.370	24:02:07.039	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.017	0.0%	7401	0.000	0.002	0.037
article prepare	00:00:00.006	0.0%	44976	0.000	0.000	0.003
article read	00:00:00.600	0.0%	4492	0.000	0.134	0.400
backlog stats	00:00:00.426	0.0%	257945	0.000	0.002	0.005
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.293	0.0%	175358	0.003	0.013	0.039
data write	00:00:03.080	0.0%	169840	0.006	0.018	0.048
idle	23:57:57.076	100.0%	257945	150.457	334.479	1042.436
status file	00:00:00.516	0.0%	435	0.667	1.186	9.000
TOTAL: 23:58:14.089	23:58:04.014	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:30:22.192	70.7%	14	0.250	130156.571	900049.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.369	0.2%	78776	0.000	0.055	0.055
TOTAL: 00:42:56.792	00:30:26.561	70.9%	-	-	-	-

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	endofthelinebbs-out.news.weretis.net	2	14009	2298	11490	221	16%	48:53:52
2	usenet.blueworldhosting.com	1289	8927	900	7384	643	10%	24:12:36
3	nntp.comgw.net	1	8334	647	7290	397	7%	23:59:57
4	usenet.goja.nl.eu.org	10	8216	552	7477	187	6%	23:37:32
5	newsfeed.bofh.team	1	8769	338	8334	97	3%	23:59:55
6	newsfeed.xs3.de	1	6063	301	5711	51	4%	23:59:56
7	i2pn.org	1	8041	300	7593	148	3%	23:59:53
8	glockenspiel.complete.org	2	1553	295	1258	0	18%	07:40:59
9	news.corradoroberto.it	11	1155	249	906	0	21%	23:27:12
10	endoftheline-out.news.chmurka.net	1	3598	247	3350	1	6%	23:59:54
11	usenet.network	1	6085	243	5837	5	3%	23:59:57
12	news.samoylyk.net	4	6828	151	6672	5	2%	25:16:27
13	news.nntp4.net	1	6600	122	6472	6	1%	23:59:54
14	194.87.241.33	41	259	108	151	0	41%	15:04:44
15	endofthelinebbs.peers.news.panix.com	22	7833	95	7726	12	1%	23:56:24
16	news.hispagatos.org	10	8413	90	8265	58	1%	24:51:06
17	news.furie.org.uk	1	3673	50	3622	1	1%	23:59:57
18	news.netfront.net	1	6849	44	6697	108	0%	25:14:43
19	news.bbs.nz	2	6808	8	6799	1	0%	23:59:37
20	outgoing.news.lysator.liu.se	1	4523	3	4519	1	0%	23:59:57
21	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:08
22	localhost	28	0	0	0	0	0%	00:00:00
23	news.nk.ca	2	0	0	0	0	0%	00:20:12
TOTAL: 23		1434	126536	7041	117553	1942	5%	482:44:52

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	endofthelinebbs-out.news.weretis.net	6.7 MB	10.4 KB	350.0 KB	7.1 MB	95%	2.9 KB
2	usenet.network	2.3 MB	5.2 KB	1.4 MB	3.7 MB	61%	15.4 KB
3	usenet.blueworldhosting.com	2.2 MB	0.0 KB	1.3 MB	3.5 MB	63%	2.3 KB
4	news.corradoroberto.it	1.6 MB	0.0 KB	0.0 KB	1.6 MB	100%	6.7 KB
5	nntp.comgw.net	1.6 MB	5.2 KB	1.7 MB	3.3 MB	48%	3.3 KB
6	usenet.goja.nl.eu.org	1.5 MB	5.1 KB	226.0 KB	1.7 MB	86%	2.4 KB
7	newsfeed.bofh.team	1.1 MB	5.1 KB	183.0 KB	1.3 MB	85%	3.0 KB
8	endoftheline-out.news.chmurka.net	1003.9 KB	0.0 KB	0.7 KB	1004.6 KB	99%	4.1 KB
9	newsfeed.xs3.de	994.8 KB	0.0 KB	77.9 KB	1.0 MB	92%	3.0 KB
10	glockenspiel.complete.org	874.6 KB	0.0 KB	0.0 KB	874.6 KB	100%	3.0 KB
11	i2pn.org	722.0 KB	5.1 KB	187.8 KB	914.9 KB	78%	2.0 KB
12	news.samoylyk.net	691.6 KB	5.1 KB	4.7 KB	701.4 KB	98%	4.5 KB
13	news.hispagatos.org	382.5 KB	5.2 KB	146.6 KB	534.3 KB	71%	3.6 KB
14	endofthelinebbs.peers.news.panix.com	356.4 KB	5.2 KB	8.2 KB	369.7 KB	96%	3.5 KB
15	news.nntp4.net	355.3 KB	5.2 KB	6.8 KB	367.3 KB	96%	2.9 KB
16	194.87.241.33	218.2 KB	0.0 KB	0.0 KB	218.2 KB	100%	2.0 KB
17	news.furie.org.uk	194.4 KB	5.2 KB	0.0 KB	199.6 KB	97%	3.9 KB
18	news.netfront.net	89.2 KB	0.0 KB	1.1 MB	1.2 MB	7%	8.2 KB
19	outgoing.news.lysator.liu.se	15.0 KB	5.2 KB	0.0 KB	20.1 KB	74%	5.0 KB
20	news.bbs.nz	12.0 KB	5.2 KB	0.0 KB	17.2 KB	69%	1.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
TOTAL: 23		22.8 MB	72.3 KB	6.7 MB	29.6 MB	77%	3.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 3 00:01:01 - 00:59:59	120	1.7%	0.03	670.9 KB	2.8%	0.19
Jan 3 01:00:00 - 01:59:59	177	2.5%	0.05	575.5 KB	2.4%	0.16
Jan 3 02:00:00 - 02:59:59	275	3.8%	0.08	811.7 KB	3.3%	0.23
Jan 3 03:00:00 - 03:59:59	381	5.3%	0.11	1023.5 KB	4.2%	0.28
Jan 3 04:00:00 - 04:59:59	385	5.4%	0.11	1.1 MB	4.7%	0.31
Jan 3 05:00:00 - 05:59:59	389	5.4%	0.11	1.4 MB	5.8%	0.39
Jan 3 06:00:00 - 06:59:59	395	5.5%	0.11	1.2 MB	5.0%	0.34
Jan 3 07:00:00 - 07:59:59	337	4.7%	0.09	955.5 KB	3.9%	0.27
Jan 3 08:00:00 - 08:59:59	424	5.9%	0.12	1.3 MB	5.4%	0.36
Jan 3 09:00:00 - 09:59:59	508	7.1%	0.14	1.9 MB	8.1%	0.54
Jan 3 10:00:00 - 10:59:59	454	6.3%	0.13	1.6 MB	6.9%	0.47
Jan 3 11:00:00 - 11:59:59	416	5.8%	0.12	1.3 MB	5.3%	0.36
Jan 3 12:00:00 - 12:59:59	455	6.3%	0.13	1.3 MB	5.7%	0.38
Jan 3 13:00:00 - 13:59:59	337	4.7%	0.09	1.0 MB	4.3%	0.29
Jan 3 14:00:00 - 14:59:59	336	4.7%	0.09	930.9 KB	3.8%	0.26
Jan 3 15:00:00 - 15:59:59	362	5.0%	0.10	1.5 MB	6.2%	0.42
Jan 3 16:00:00 - 16:59:59	280	3.9%	0.08	928.7 KB	3.8%	0.26
Jan 3 17:00:00 - 17:59:59	260	3.6%	0.07	847.0 KB	3.5%	0.24
Jan 3 18:00:00 - 18:59:59	197	2.7%	0.05	931.6 KB	3.8%	0.26
Jan 3 19:00:00 - 19:59:59	163	2.3%	0.05	561.4 KB	2.3%	0.16
Jan 3 20:00:00 - 20:59:59	143	2.0%	0.04	511.6 KB	2.1%	0.14
Jan 3 21:00:00 - 21:59:59	133	1.8%	0.04	620.1 KB	2.6%	0.17
Jan 3 22:00:00 - 22:59:59	124	1.7%	0.03	476.5 KB	2.0%	0.13
Jan 3 23:00:00 - 23:59:59	142	2.0%	0.04	513.3 KB	2.1%	0.14
Jan 4 00:00:00 - 00:01:01	3	0.0%	0.05	6.1 KB	0.0%	0.10
TOTAL: 24:00:00	7196	100.0%	0.08	23.7 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.8 GB	6.6%	0	0.46	40499.48
CYCTXT1	TEXT	5.0 GB	4.0 GB	79.9%	0	0.03	2259.04
TOTAL: 3	-	3077.0 GB	105.9 GB	3.4%	1	0.34	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	655	0	0	655
2	nntp.comgw.net	401	0	1	400
3	endofthelinebbs-out.news.weretis.net	253	0	2	251
4	usenet.goja.nl.eu.org	175	0	1	174
5	i2pn.org	148	0	1	147
6	newsfeed.bofh.team	101	0	1	100
7	news.hispagatos.org	58	21	1	36
8	newsfeed.xs3.de	54	0	0	54
9	news.netfront.net	48	0	1	47
10	endofthelinebbs.peers.news.panix.com	14	1	1	12
11	news.nntp4.net	6	0	1	5
12	usenet.network	5	0	1	4
13	news.samoylyk.net	4	0	1	3
14	endoftheline-out.news.chmurka.net	1	0	0	1
15	glockenspiel.complete.org	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		1927	22	15	1890

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.mastodon.tag.hacking	12
hispagatos.hacking.news	5
hispagatos.hacking.ctf	4
soc.culture.russia	1
TOTAL: 4	22

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	1072
EMP (phr path)	341
Too many newsgroups	178
EMP (md5)	153
Too many newsgroups (meow)	33
EMP (fsl)	31
User-issued cancel	20
HTML Multipart	5
TOTAL: 8	1833

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
nocem@usenet.ovh (faqfr)	2	2	0	2
i2pn2-nocem@i2pn2.org (multipost)	35	0	42	42
robot@pasdenom.info (spam2)	13	0	15	15
robot@pasdenom.info (spam3)	4	0	9	9
i2pn2-nocem@i2pn2.org (rmp)	2	0	7	7
TOTAL: 5	56	2	73	75

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	usenet.network	2
	TOTAL: 1	2
Including strange strings
	endofthelinebbs-out.news.weretis.net	26
	usenet.blueworldhosting.com	12
	nntp.comgw.net	4
	newsfeed.bofh.team	3
	newsfeed.xs3.de	3
	usenet.goja.nl.eu.org	3
	endofthelinebbs.peers.news.panix.com	2
	i2pn.org	1
	news.hispagatos.org	1
	TOTAL: 9	55
TOTAL: 2		57

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	nyheter.lysator.liu.se	7693	3035	3881	277	0	39%	23:59:53
2	news.netfront.net	11331	2017	5125	0	12	17%	23:59:53
3	endofthelinebbs.peers.news.panix.com	7406	965	6212	18	0	13%	23:59:53
4	news.furie.org.uk	4172	953	2420	21	0	22%	23:57:20
5	news.corradoroberto.it	1207	336	580	0	0	27%	23:04:51
6	usenet.goja.nl.eu.org	8020	268	6244	66	8	3%	23:59:50
7	newsfeed.bofh.team	7889	171	6688	0	2	2%	23:59:30
8	usenet.network	8921	157	6497	48	5	1%	23:58:34
9	news.nntp4.net	8161	126	6910	14	1	1%	23:59:53
10	news.hispagatos.org	8302	120	6803	175	20	1%	23:59:53
11	news.nk.ca	8993	111	6499	419	12	1%	23:59:53
12	i2pn.org	7677	106	6743	42	43	1%	23:59:53
13	nntp.comgw.net	7150	101	6377	61	0	1%	23:59:53
14	news.bbs.nz	13184	89	6721	380	57	0%	23:59:44
15	news.weretis.net	7450	52	7007	135	0	0%	23:59:40
16	ddt.demos.su	146	40	96	0	0	27%	11:28:34
17	news.quux.org	5721	32	4992	378	0	0%	23:59:53
18	newsfeed.xs3.de	8506	29	6650	202	2	0%	23:59:46
19	news.tnetconsulting.net	7992	28	6977	188	3	0%	23:58:43
20	news.samoylyk.net	8648	19	6835	51	44	0%	23:59:53
21	news.chmurka.net	4312	18	3236	0	2	0%	23:59:53
22	usenet.blueworldhosting.com	6419	1	5842	0	0	0%	23:58:27
23	feed.opticnetworks.net	0	0	0	0	7196	0%	23:59:53
24	photonic.trudheim.com	0	0	0	0	7196	0%	23:57:43
TOTAL: 24		159300	8774	119335	2475	14603	5%	562:21:18

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nyheter.lysator.liu.se	8.8 MB	2.6 MB	11.4 MB	0.1 KB/s	3.5 KB	23:59:53
2	news.netfront.net	6.6 MB	0.0 KB	6.6 MB	0.1 KB/s	3.4 KB	23:59:53
3	news.furie.org.uk	4.5 MB	112.3 KB	4.6 MB	0.1 KB/s	4.8 KB	23:57:20
4	endofthelinebbs.peers.news.panix.com	3.1 MB	32.0 KB	3.1 MB	0.0 KB/s	3.3 KB	23:59:53
5	news.corradoroberto.it	946.7 KB	0.0 KB	946.7 KB	0.0 KB/s	2.8 KB	23:04:51
6	newsfeed.bofh.team	904.3 KB	0.0 KB	904.3 KB	0.0 KB/s	5.3 KB	23:59:30
7	usenet.goja.nl.eu.org	545.0 KB	744.6 KB	1.3 MB	0.0 KB/s	3.9 KB	23:59:50
8	usenet.network	390.1 KB	99.5 KB	489.6 KB	0.0 KB/s	2.4 KB	23:58:34
9	news.bbs.nz	382.5 KB	1.5 MB	1.8 MB	0.0 KB/s	4.0 KB	23:59:44
10	news.nntp4.net	318.5 KB	208.5 KB	527.0 KB	0.0 KB/s	3.8 KB	23:59:53
11	news.nk.ca	255.3 KB	1.6 MB	1.8 MB	0.0 KB/s	3.5 KB	23:59:53
12	news.hispagatos.org	233.4 KB	1.4 MB	1.6 MB	0.0 KB/s	5.7 KB	23:59:53
13	i2pn.org	227.1 KB	306.1 KB	533.2 KB	0.0 KB/s	3.6 KB	23:59:53
14	news.weretis.net	182.9 KB	1008.2 KB	1.2 MB	0.0 KB/s	6.4 KB	23:59:40
15	nntp.comgw.net	163.6 KB	698.7 KB	862.3 KB	0.0 KB/s	5.3 KB	23:59:53
16	news.quux.org	113.6 KB	1.6 MB	1.7 MB	0.0 KB/s	4.2 KB	23:59:53
17	ddt.demos.su	111.5 KB	0.0 KB	111.5 KB	0.0 KB/s	2.8 KB	11:28:34
18	news.tnetconsulting.net	100.1 KB	1.1 MB	1.2 MB	0.0 KB/s	5.8 KB	23:58:43
19	newsfeed.xs3.de	92.4 KB	1.0 MB	1.1 MB	0.0 KB/s	4.9 KB	23:59:46
20	news.samoylyk.net	44.0 KB	150.9 KB	194.9 KB	0.0 KB/s	2.8 KB	23:59:53
21	news.chmurka.net	40.3 KB	0.0 KB	40.3 KB	0.0 KB/s	2.2 KB	23:59:53
22	usenet.blueworldhosting.com	1.2 KB	0.0 KB	1.2 KB	0.0 KB/s	1.2 KB	23:58:27
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:57:43
TOTAL: 24		28.0 MB	14.0 MB	42.0 MB	0.0 KB/s	3.8 KB	562:21:18

NNRP readership statistics:

System		Conn	Elapsed
1	147.185.132.63	1	00:00:10
2	147.185.133.22	1	00:00:03
3	194.165.16.76	1	00:00:00
4	198.235.24.78	1	00:00:10
5	202.107.226.2	1	00:30:00
6	205.210.31.64	1	00:00:00
7	2a06:4883:9000::ae	1	00:00:01
8	localhost	1	00:12:29
TOTAL: 8		8	00:42:56

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	6	00:30:24
2	?	2	00:12:31
TOTAL: 2		8	00:42:56

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
202.107.226.2	0.004	0.023	0.000	00:30:00
localhost	179.549	370.287	0.074	00:12:29
198.235.24.78	0.000	0.017	0.000	00:00:10
147.185.132.63	0.003	0.035	0.000	00:00:10
147.185.133.22	0.004	0.022	0.000	00:00:03
2a06:4883:9000::ae	0.006	0.027	0.000	00:00:01
194.165.16.76	0.004	0.023	0.000	00:00:00
205.210.31.64	0.004	0.013	0.000	00:00:00
TOTAL: 8	179.574	370.447	0.074	00:42:56

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

System	Conn
147.185.133.22	4
194.165.16.76	1
202.107.226.2	1
TOTAL: 3	6

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

Command	Count
#003	1
Accept-Encoding: gzip	1
GET / HTTP/1.0	1
GET / HTTP/1.1	1
Host: 144.172.126.95:119	1
User-Agent: Expanse, a Palo Alto Network...	1
TOTAL: 6	6

NNRP client timeouts [Top 20]:

System	Conn	Peer
194.165.16.76	1	1
202.107.226.2	1	0
TOTAL: 2	2	2

Daily Usenet Report

Jan 3 00:01:01 -- Jan 4 00:01:01