Daily Usenet Report: Jan 15 00:01:02

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Jan 15 08:19:08 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Jan 15 10:00:10 newsfeed pgpverify[1056482]: pgpverify: verification failed
Jan 16 00:00:01 newsfeed nnrpd[1248243]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	9101	39.7%	3.6 MB	62.1%
innd	8833	38.5%	1.3 MB	22.1%
innfeed	4433	19.3%	853.3 KB	14.3%
cnfsstat	432	1.9%	71.3 KB	1.2%
nocem	88	0.4%	11.9 KB	0.2%
nnrpd	41	0.2%	4.5 KB	0.1%
controlchan	23	0.1%	5.7 KB	0.1%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 8	22952	100.0%	5.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	119044	65.9%
Negative hits	52688	29.1%
Do not exist	9002	5.0%
Cache misses	32	0.0%
TOTAL: 4	180766	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.317	0.0%	74	0.000	4.284	21.000
article cleanup	00:00:00.121	0.0%	9026	0.000	0.013	0.420
article logging	00:00:00.513	0.0%	9078	0.000	0.057	1.913
article parse	00:00:00.230	0.0%	15037	0.000	0.015	0.095
article write	00:00:02.156	0.0%	7332	0.034	0.294	2.704
artlog/artcncl	00:00:00.001	0.0%	31	0.000	0.032	0.200
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:02.237	0.0%	181834	0.000	0.012	0.434
hisgrep/artcncl	00:00:00.200	0.0%	81	0.000	2.469	11.000
hishave/artcncl	00:00:00.015	0.0%	32	0.000	0.469	6.500
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:03:00.585	0.2%	181248	0.104	0.996	10.950
history sync	00:00:00.068	0.0%	393	0.000	0.173	1.250
history write	00:02:13.403	0.2%	9024	3.059	14.783	111.971
hiswrite/artcncl	00:00:00.010	0.0%	2	3.000	5.000	7.000
hiswrite/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:48:41.331	99.1%	174822	80.050	490.335	2230.543
nntp read	00:00:04.035	0.0%	182934	0.004	0.022	1.202
overview write	00:05:34.152	0.4%	7332	0.667	45.574	3973.414
perl filter	00:00:35.583	0.0%	9024	1.039	3.943	47.110
python filter	00:00:02.080	0.0%	9024	0.000	0.230	6.037
site send	00:00:01.176	0.0%	14737	0.000	0.080	0.304
TOTAL: 24:01:05.992	24:00:18.213	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.023	0.0%	8986	0.000	0.003	0.037
article prepare	00:00:00.009	0.0%	56500	0.000	0.000	0.003
article read	00:00:00.712	0.0%	5010	0.000	0.142	0.667
backlog stats	00:00:00.454	0.0%	273617	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.302	0.0%	182529	0.003	0.013	0.065
data write	00:00:03.247	0.0%	177870	0.007	0.018	0.041
idle	23:57:49.629	100.0%	273617	139.232	315.293	1177.461
status file	00:00:00.525	0.0%	412	0.750	1.274	14.000
TOTAL: 23:58:07.516	23:57:56.901	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:41.269	5.4%	13	0.250	3174.538	10052.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:03.892	0.5%	78776	0.000	0.049	0.049
TOTAL: 00:12:47.309	00:00:45.161	5.9%	-	-	-	-

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
group-admin@isc.org	0	0	2	1	1	2
TOTAL	0	0	2	1	1	2

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	endofthelinebbs-out.news.weretis.net	4	14381	2974	11093	314	20%	50:37:18
2	usenet.blueworldhosting.com	716	8823	972	7112	739	11%	23:53:41
3	news.corradoroberto.it	14	1294	470	824	0	36%	23:25:23
4	newsfeed.bofh.team	2	8652	468	8070	114	5%	23:59:19
5	usenet.goja.nl.eu.org	10	7979	436	7427	116	5%	23:44:43
6	usenet.network	1	5940	376	5562	2	6%	23:59:57
7	i2pn.org	1	7793	286	7439	68	3%	23:59:54
8	endoftheline-out.news.chmurka.net	2	3414	224	3187	3	6%	23:52:04
9	nntp.comgw.net	3	8217	217	7847	153	2%	23:52:49
10	newsfeed.xs3.de	2	6154	192	5944	18	3%	23:59:09
11	194.87.241.33	51	292	177	114	1	60%	16:27:09
12	glockenspiel.complete.org	2	2455	168	2287	0	6%	12:31:08
13	news.samoylyk.net	5	6821	160	6656	5	2%	22:27:19
14	news.nntp4.net	2	6477	85	6392	0	1%	23:59:41
15	news.furie.org.uk	2	3879	60	3818	1	1%	23:59:36
16	endofthelinebbs.peers.news.panix.com	23	7974	53	7916	5	0%	23:56:52
17	news.hispagatos.org	5	8203	45	8094	64	0%	24:26:19
18	news.netfront.net	1	5495	31	5419	45	0%	21:52:15
19	outgoing.news.lysator.liu.se	1	4402	11	4390	1	0%	23:59:57
20	news.bbs.nz	1	6485	7	6478	0	0%	23:59:44
21	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:05
22	localhost	31	0	0	0	0	0%	00:00:01
23	news.nk.ca	2	0	0	0	0	0%	00:20:10
TOTAL: 23		882	125130	7412	116069	1649	5%	483:34:33

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	endofthelinebbs-out.news.weretis.net	9.5 MB	0.0 KB	1.1 MB	10.7 MB	89%	3.3 KB
2	usenet.network	4.7 MB	0.0 KB	2.4 KB	4.7 MB	99%	12.8 KB
3	news.corradoroberto.it	3.0 MB	0.0 KB	0.0 KB	3.0 MB	100%	6.4 KB
4	usenet.blueworldhosting.com	2.1 MB	0.0 KB	1.9 MB	4.0 MB	53%	2.4 KB
5	usenet.goja.nl.eu.org	1.5 MB	6.1 KB	222.4 KB	1.7 MB	86%	3.2 KB
6	newsfeed.bofh.team	1.3 MB	0.0 KB	154.3 KB	1.4 MB	89%	2.5 KB
7	endoftheline-out.news.chmurka.net	1.0 MB	0.0 KB	2.0 KB	1.0 MB	99%	4.7 KB
8	newsfeed.xs3.de	822.0 KB	0.0 KB	22.7 KB	844.7 KB	97%	4.0 KB
9	i2pn.org	771.3 KB	0.0 KB	94.6 KB	866.0 KB	89%	2.4 KB
10	glockenspiel.complete.org	730.4 KB	0.0 KB	0.0 KB	730.4 KB	100%	4.3 KB
11	news.samoylyk.net	708.2 KB	0.0 KB	9.0 KB	717.2 KB	98%	4.3 KB
12	194.87.241.33	628.7 KB	0.0 KB	1.7 KB	630.3 KB	99%	3.5 KB
13	nntp.comgw.net	577.4 KB	0.0 KB	352.3 KB	929.6 KB	62%	2.5 KB
14	news.nntp4.net	239.3 KB	0.0 KB	0.0 KB	239.3 KB	100%	2.8 KB
15	news.furie.org.uk	191.1 KB	0.0 KB	0.7 KB	191.8 KB	99%	3.1 KB
16	news.hispagatos.org	186.2 KB	0.0 KB	100.7 KB	286.9 KB	64%	2.6 KB
17	endofthelinebbs.peers.news.panix.com	168.7 KB	0.0 KB	5.0 KB	173.7 KB	97%	3.0 KB
18	news.netfront.net	61.7 KB	0.0 KB	293.8 KB	355.6 KB	17%	4.7 KB
19	outgoing.news.lysator.liu.se	38.4 KB	4.6 KB	0.0 KB	43.0 KB	89%	3.6 KB
20	news.bbs.nz	13.1 KB	0.0 KB	0.0 KB	13.1 KB	100%	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		28.2 MB	10.7 KB	4.3 MB	32.4 MB	86%	3.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 15 00:01:02 - 00:59:59	159	2.2%	0.04	512.1 KB	1.8%	0.14
Jan 15 01:00:00 - 01:59:59	227	3.1%	0.06	789.6 KB	2.8%	0.22
Jan 15 02:00:00 - 02:59:59	282	3.8%	0.08	946.1 KB	3.3%	0.26
Jan 15 03:00:00 - 03:59:59	387	5.3%	0.11	1.4 MB	5.2%	0.41
Jan 15 04:00:00 - 04:59:59	414	5.7%	0.12	1.7 MB	6.0%	0.47
Jan 15 05:00:00 - 05:59:59	345	4.7%	0.10	1.3 MB	4.7%	0.37
Jan 15 06:00:00 - 06:59:59	419	5.7%	0.12	1.2 MB	4.4%	0.34
Jan 15 07:00:00 - 07:59:59	335	4.6%	0.09	1.1 MB	3.8%	0.30
Jan 15 08:00:00 - 08:59:59	389	5.3%	0.11	1.4 MB	5.1%	0.40
Jan 15 09:00:00 - 09:59:59	488	6.7%	0.14	2.0 MB	7.3%	0.58
Jan 15 10:00:00 - 10:59:59	482	6.6%	0.13	2.3 MB	8.3%	0.65
Jan 15 11:00:00 - 11:59:59	451	6.2%	0.13	1.5 MB	5.4%	0.43
Jan 15 12:00:00 - 12:59:59	402	5.5%	0.11	1.3 MB	4.6%	0.36
Jan 15 13:00:00 - 13:59:59	300	4.1%	0.08	1.4 MB	5.2%	0.41
Jan 15 14:00:00 - 14:59:59	316	4.3%	0.09	1.0 MB	3.7%	0.29
Jan 15 15:00:00 - 15:59:59	380	5.2%	0.11	1.2 MB	4.5%	0.36
Jan 15 16:00:00 - 16:59:59	263	3.6%	0.07	932.7 KB	3.3%	0.26
Jan 15 17:00:00 - 17:59:59	339	4.6%	0.09	1.7 MB	6.0%	0.47
Jan 15 18:00:00 - 18:59:59	210	2.9%	0.06	940.8 KB	3.3%	0.26
Jan 15 19:00:00 - 19:59:59	182	2.5%	0.05	762.8 KB	2.7%	0.21
Jan 15 20:00:00 - 20:59:59	171	2.3%	0.05	711.7 KB	2.5%	0.20
Jan 15 21:00:00 - 21:59:59	133	1.8%	0.04	764.0 KB	2.7%	0.21
Jan 15 22:00:00 - 22:59:59	108	1.5%	0.03	413.2 KB	1.5%	0.11
Jan 15 23:00:00 - 23:59:59	137	1.9%	0.04	500.5 KB	1.8%	0.14
Jan 16 00:00:00 - 00:01:01	7	0.1%	0.11	40.6 KB	0.1%	0.67
TOTAL: 23:59:59	7326	100.0%	0.08	27.6 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	102.3 GB	6.7%	0	0.50	37514.22
CYCTXT1	TEXT	5.0 GB	4.0 GB	80.2%	0	0.03	2194.13
TOTAL: 3	-	3077.0 GB	106.4 GB	3.5%	1	0.36	-

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	usenet.blueworldhosting.com	764	2	0	1	761
2	endofthelinebbs-out.news.weretis.net	319	0	0	0	319
3	nntp.comgw.net	144	0	0	0	144
4	news.netfront.net	116	0	0	0	116
5	newsfeed.bofh.team	116	2	0	0	114
6	usenet.goja.nl.eu.org	107	0	1	0	106
7	i2pn.org	70	0	0	0	70
8	news.hispagatos.org	67	58	0	0	9
9	newsfeed.xs3.de	20	0	0	0	20
10	endofthelinebbs.peers.news.panix.com	7	0	0	0	7
11	news.samoylyk.net	5	0	0	0	5
12	endoftheline-out.news.chmurka.net	3	0	0	0	3
13	usenet.network	2	0	0	0	2
14	194.87.241.33	1	0	0	0	1
15	news.bbs.nz	1	0	0	0	1
16	news.furie.org.uk	1	0	0	0	1
17	outgoing.news.lysator.liu.se	1	0	1	0	0
TOTAL: 17		1744	62	2	1	1679

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.hacking.ctf	33
hispagatos.mastodon.tag.hacking	20
hispagatos.hacking.news	4
alt.discuss.test	3
han.misc.test	1
hispagatos.mastodon.tag.hackers	1
TOTAL: 6	62

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
soc.politics	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	775
EMP (phr path)	288
EMP (md5)	248
Too many newsgroups	170
Binary: misplaced binary	71
Too many newsgroups (meow)	42
User-issued cancel	22
HTML Multipart	6
Angle-bracket bot	2
Poison newsgroup	2
EMP (fsl)	1
TOTAL: 11	1627

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
i2pn2-nocem@i2pn2.org (spam)	16	23	0	23
i2pn2-nocem@i2pn2.org (multipost)	34	0	43	43
robot@pasdenom.info (spam3)	8	0	18	18
robot@pasdenom.info (spam2)	11	0	14	14
i2pn2-nocem@i2pn2.org (rmp)	2	0	5	5
TOTAL: 5	71	23	80	103

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	usenet.blueworldhosting.com	2
	TOTAL: 1	2
Including strange strings
	usenet.blueworldhosting.com	25
	endofthelinebbs-out.news.weretis.net	14
	newsfeed.bofh.team	4
	endofthelinebbs.peers.news.panix.com	2
	nntp.comgw.net	2
	newsfeed.xs3.de	2
	news.netfront.net	1
	news.hispagatos.org	1
	i2pn.org	1
	TOTAL: 9	52
TOTAL: 2		54

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	nyheter.lysator.liu.se	7879	3234	3613	468	0	41%	23:59:52
2	news.netfront.net	11333	2605	4676	12	1	22%	23:59:19
3	endofthelinebbs.peers.news.panix.com	7669	1079	6234	13	0	14%	23:59:22
4	news.furie.org.uk	4593	976	2576	13	0	21%	23:58:37
5	usenet.goja.nl.eu.org	8382	336	6263	233	1	4%	23:59:17
6	newsfeed.bofh.team	8156	278	6578	1	0	3%	23:58:58
7	news.corradoroberto.it	1058	254	530	0	0	24%	23:00:58
8	usenet.network	9239	195	6448	31	2	2%	23:59:40
9	news.hispagatos.org	8854	190	6823	270	0	2%	23:59:52
10	news.bbs.nz	13458	146	6669	502	120	1%	23:59:52
11	news.weretis.net	7817	141	6919	265	11	1%	23:59:52
12	i2pn.org	8158	140	6809	72	90	1%	23:59:52
13	news.nntp4.net	8758	135	7067	16	11	1%	23:57:39
14	news.tnetconsulting.net	8416	131	6857	337	14	1%	23:59:33
15	news.quux.org	5965	102	4842	546	3	1%	23:58:19
16	nntp.comgw.net	8020	96	6853	152	49	1%	23:59:52
17	news.nk.ca	10078	89	6491	632	57	0%	23:59:52
18	newsfeed.xs3.de	8842	64	6730	324	1	0%	23:59:52
19	news.samoylyk.net	8931	20	7011	60	1310	0%	23:59:52
20	news.chmurka.net	4213	12	3039	1	0	0%	23:59:30
21	usenet.blueworldhosting.com	6718	2	6063	0	7	0%	23:59:52
22	ddt.demos.su	32	0	32	0	0	0%	04:46:56
23	feed.opticnetworks.net	0	0	0	0	7326	0%	23:59:52
24	photonic.trudheim.com	0	0	0	0	7326	0%	23:59:52
TOTAL: 24		166569	10225	119123	3948	16329	6%	555:36:32

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.netfront.net	11.7 MB	73.9 KB	11.8 MB	0.1 KB/s	4.6 KB	23:59:19
2	nyheter.lysator.liu.se	10.4 MB	4.7 MB	15.1 MB	0.2 KB/s	4.2 KB	23:59:52
3	news.furie.org.uk	6.3 MB	82.2 KB	6.4 MB	0.1 KB/s	6.7 KB	23:58:37
4	endofthelinebbs.peers.news.panix.com	5.5 MB	86.4 KB	5.6 MB	0.1 KB/s	5.2 KB	23:59:22
5	newsfeed.bofh.team	2.0 MB	2.8 KB	2.0 MB	0.0 KB/s	7.5 KB	23:58:58
6	news.hispagatos.org	903.9 KB	2.5 MB	3.4 MB	0.0 KB/s	7.6 KB	23:59:52
7	usenet.network	896.9 KB	61.4 KB	958.3 KB	0.0 KB/s	4.2 KB	23:59:40
8	news.weretis.net	846.7 KB	2.7 MB	3.5 MB	0.0 KB/s	8.8 KB	23:59:52
9	usenet.goja.nl.eu.org	747.2 KB	2.4 MB	3.1 MB	0.0 KB/s	5.6 KB	23:59:17
10	news.tnetconsulting.net	660.2 KB	2.9 MB	3.5 MB	0.0 KB/s	7.7 KB	23:59:33
11	news.corradoroberto.it	644.5 KB	0.0 KB	644.5 KB	0.0 KB/s	2.5 KB	23:00:58
12	news.quux.org	601.5 KB	3.3 MB	3.9 MB	0.0 KB/s	6.1 KB	23:58:19
13	i2pn.org	590.0 KB	332.0 KB	922.0 KB	0.0 KB/s	4.3 KB	23:59:52
14	news.bbs.nz	564.3 KB	3.4 MB	4.0 MB	0.0 KB/s	6.3 KB	23:59:52
15	news.nntp4.net	532.7 KB	273.7 KB	806.4 KB	0.0 KB/s	5.3 KB	23:57:39
16	nntp.comgw.net	339.4 KB	1.5 MB	1.8 MB	0.0 KB/s	7.6 KB	23:59:52
17	newsfeed.xs3.de	284.9 KB	2.4 MB	2.7 MB	0.0 KB/s	7.1 KB	23:59:52
18	news.nk.ca	245.6 KB	3.9 MB	4.1 MB	0.0 KB/s	5.8 KB	23:59:52
19	news.samoylyk.net	109.3 KB	347.1 KB	456.5 KB	0.0 KB/s	5.7 KB	23:59:52
20	news.chmurka.net	44.9 KB	2.6 KB	47.5 KB	0.0 KB/s	3.7 KB	23:59:30
21	usenet.blueworldhosting.com	7.6 KB	0.0 KB	7.6 KB	0.0 KB/s	3.8 KB	23:59:52
22	ddt.demos.su	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	04:46:56
23	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:52
24	photonic.trudheim.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:52
TOTAL: 24		43.9 MB	30.8 MB	74.7 MB	0.0 KB/s	5.4 KB	555:36:32

NNRP readership statistics:

System		Conn	Elapsed
1	104.152.52.199	1	00:00:00
2	104.152.52.242	1	00:00:10
3	167.94.138.63	1	00:00:02
4	185.242.226.41	1	00:00:00
5	198.235.24.105	1	00:00:10
6	198.235.24.214	1	00:00:10
7	azpdssb14.stretchoid.com	1	00:00:09
8	localhost	1	00:12:04
TOTAL: 8		8	00:12:47

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	6	00:00:32
2	*.stretchoid.com	1	00:00:09
3	?	1	00:12:04
TOTAL: 3		8	00:12: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	178.948	370.012	0.075	00:12:04
104.152.52.242	0.005	0.011	0.000	00:00:10
198.235.24.105	0.017	0.128	0.000	00:00:10
198.235.24.214	0.006	0.011	0.000	00:00:10
azpdssb14.stretchoid.com	0.003	0.022	0.000	00:00:09
167.94.138.63	0.008	0.023	0.000	00:00:02
185.242.226.41	0.006	0.019	0.000	00:00:00
104.152.52.199	0.008	0.008	0.000	00:00:00
TOTAL: 8	179.001	370.234	0.075	00:12:47

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

System	Conn
185.242.226.41	5
167.94.138.63	1
TOTAL: 2	6

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

Command	Count
Accept-Encoding: gzip	1
Accept: /	1
EHLO www.censys.io	1
GET / HTTP/1.1	1
Host: 144.172.126.95:119	1
User-Agent: Mozilla/5.0 (Windows NT 10.0...	1
TOTAL: 6	6

Daily Usenet Report

Jan 15 00:01:02 -- Jan 16 00:01:01