Daily Usenet Report: Nov 15 00:01:01

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Nov 15 10:00:09 newsfeed pgpverify[1846006]: pgpverify: verification failed
Nov 15 12:42:51 newsfeed nnrpd[1903338]: 47.186.35.210 auth: program exited with status 8
Nov 16 00:00:01 newsfeed nnrpd[2085169]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	14139	34.8%	1.9 MB	22.6%
inn	11662	28.7%	4.0 MB	48.3%
innd	9524	23.4%	1.4 MB	16.2%
innfeed	4331	10.6%	840.8 KB	9.8%
nocem	555	1.4%	181.5 KB	2.1%
cnfsstat	432	1.1%	65.0 KB	0.8%
controlchan	23	0.1%	5.4 KB	0.1%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 8	40667	100.0%	8.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	130295	64.3%
Negative hits	61902	30.5%
Do not exist	10333	5.1%
Cache misses	191	0.1%
TOTAL: 4	202721	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.888	0.0%	3302	0.000	0.875	18.795
article cleanup	00:00:00.086	0.0%	10366	0.000	0.008	0.065
article logging	00:00:00.470	0.0%	10402	0.000	0.045	0.147
article parse	00:00:00.264	0.0%	18026	0.000	0.015	0.071
article write	00:00:01.817	0.0%	7801	0.000	0.233	1.744
artlog/artcncl	00:00:00.037	0.0%	1279	0.000	0.029	0.333
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:00:00.870	0.0%	198322	0.000	0.004	0.349
hisgrep/artcncl	00:00:02.159	0.0%	3304	0.000	0.653	10.523
hishave/artcncl	00:00:00.467	0.0%	3281	0.000	0.142	8.273
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:58.845	0.2%	200172	0.030	0.893	5.226
history sync	00:00:00.067	0.0%	405	0.000	0.165	1.000
history write	00:02:08.846	0.1%	10366	3.068	12.430	104.850
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.003	0.0%	1	3.000	3.000	3.000
idle	23:48:02.778	99.1%	196571	124.214	435.887	1694.037
nntp read	00:00:03.543	0.0%	198862	0.000	0.018	0.510
overview write	00:06:28.029	0.4%	7801	0.467	49.741	3293.848
perl filter	00:00:27.172	0.0%	10366	1.385	2.621	13.385
python filter	00:00:00.762	0.0%	10366	0.000	0.074	0.231
site send	00:00:01.207	0.0%	15910	0.000	0.076	0.397
TOTAL: 24:01:06.898	24:00:20.310	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.022	0.0%	8560	0.000	0.003	0.077
article prepare	00:00:00.011	0.0%	65552	0.000	0.000	0.005
article read	00:00:01.091	0.0%	5617	0.000	0.194	7.932
backlog stats	00:00:00.420	0.0%	299568	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.395	0.0%	199775	0.000	0.012	0.044
data write	00:00:03.575	0.0%	194382	0.008	0.018	0.037
idle	23:56:35.786	100.0%	299568	134.438	287.734	1111.847
status file	00:00:00.380	0.0%	347	0.800	1.095	3.000
TOTAL: 23:56:56.317	23:56:43.680	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:23:52.321	65.4%	8504	0.250	168.429	10034.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.236	0.2%	95866	0.000	0.044	0.333
readart	00:00:00.067	0.0%	27	2.481	2.481	2.481
TOTAL: 00:36:28.982	00:23:56.624	65.6%	-	-	-	-

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

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	3	15390	2280	12923	187	14%	48:05:09
2	usenet.goja.nl.eu.org	10	8707	1340	6953	414	15%	22:04:23
3	usenet.blueworldhosting.com	718	9911	1042	7847	1022	10%	23:48:30
4	usenet.network	1	6471	452	6011	8	6%	23:59:57
5	i2pn.org	1	8191	400	7675	116	4%	23:59:57
6	news.corradoroberto.it	5	1239	359	880	0	28%	23:12:26
7	glockenspiel.complete.org	4	5106	355	4742	9	6%	20:04:49
8	nntp.comgw.net	2	9232	299	8649	284	3%	23:59:48
9	newsfeed.bofh.team	2	9989	277	9602	110	2%	23:59:47
10	endoftheline-out.news.chmurka.net	1	4384	231	4149	4	5%	23:59:55
11	194.87.241.33	42	348	198	150	0	56%	16:23:50
12	endofthelinebbs.peers.news.panix.com	25	8856	157	8635	64	1%	23:53:42
13	newsfeed.xs3.de	1	6979	155	6778	46	2%	23:59:55
14	news.nntp4.net	2	7158	101	7041	16	1%	23:58:45
15	news.samoylyk.net	2	7433	88	7340	5	1%	23:59:46
16	news.furie.org.uk	1	4183	63	4119	1	1%	23:59:56
17	news.netfront.net	0	2925	36	2725	164	1%	08:33:24
18	news.bbs.nz	1	13321	16	13303	2	0%	25:55:07
19	news.hispagatos.org	6	9269	15	9226	28	0%	24:26:09
20	outgoing.news.lysator.liu.se	2	4666	1	4665	0	0%	23:58:33
21	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:05
22	localhost	27	0	0	0	0	0%	00:00:00
23	news.nk.ca	2	0	0	0	0	0%	00:20:11
TOTAL: 23		859	143758	7865	133413	2480	5%	476:54:04

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	endofthelinebbs-out.news.weretis.net	10.7 MB	308.4 KB	11.0 MB	97%	4.6 KB
2	usenet.network	4.6 MB	60.8 KB	4.7 MB	98%	10.4 KB
3	usenet.goja.nl.eu.org	3.5 MB	588.7 KB	4.1 MB	85%	2.4 KB
4	glockenspiel.complete.org	2.8 MB	10.9 KB	2.8 MB	99%	7.8 KB
5	usenet.blueworldhosting.com	2.5 MB	1.6 MB	4.1 MB	60%	2.0 KB
6	news.corradoroberto.it	1.9 MB	0.0 KB	1.9 MB	100%	5.4 KB
7	i2pn.org	1.1 MB	139.5 KB	1.3 MB	89%	2.5 KB
8	endoftheline-out.news.chmurka.net	1005.5 KB	1.6 MB	2.5 MB	38%	11.1 KB
9	nntp.comgw.net	817.8 KB	1.5 MB	2.3 MB	34%	4.0 KB
10	newsfeed.bofh.team	736.8 KB	1.8 MB	2.6 MB	28%	6.8 KB
11	194.87.241.33	660.2 KB	0.0 KB	660.2 KB	100%	3.3 KB
12	newsfeed.xs3.de	503.2 KB	77.8 KB	581.1 KB	86%	2.9 KB
13	endofthelinebbs.peers.news.panix.com	483.3 KB	73.2 KB	556.6 KB	86%	2.5 KB
14	news.samoylyk.net	341.6 KB	5.8 KB	347.3 KB	98%	3.7 KB
15	news.furie.org.uk	259.7 KB	0.6 KB	260.3 KB	99%	4.1 KB
16	news.nntp4.net	257.7 KB	43.7 KB	301.4 KB	85%	2.6 KB
17	news.netfront.net	62.3 KB	6.8 MB	6.9 MB	0%	35.4 KB
18	news.bbs.nz	59.8 KB	1.8 KB	61.6 KB	97%	3.4 KB
19	news.hispagatos.org	36.3 KB	49.5 KB	85.8 KB	42%	2.0 KB
20	outgoing.news.lysator.liu.se	2.7 KB	0.0 KB	2.7 KB	100%	2.7 KB
21	eotl-out.news.tnetconsulting.net	0.0 KB	0.0 KB	0.0 KB	0%	0
22	localhost	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
TOTAL: 23		32.2 MB	14.7 MB	46.9 MB	68%	4.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 15 00:01:01 - 00:59:59	221	2.8%	0.06	690.3 KB	2.1%	0.20
Nov 15 01:00:00 - 01:59:59	231	3.0%	0.06	679.4 KB	2.1%	0.19
Nov 15 02:00:00 - 02:59:59	368	4.7%	0.10	1.3 MB	4.1%	0.37
Nov 15 03:00:00 - 03:59:59	405	5.2%	0.11	1.2 MB	3.9%	0.35
Nov 15 04:00:00 - 04:59:59	338	4.3%	0.09	1.1 MB	3.5%	0.32
Nov 15 05:00:00 - 05:59:59	303	3.9%	0.08	993.9 KB	3.0%	0.28
Nov 15 06:00:00 - 06:59:59	414	5.3%	0.12	1.3 MB	4.1%	0.37
Nov 15 07:00:00 - 07:59:59	458	5.9%	0.13	1.8 MB	5.5%	0.50
Nov 15 08:00:00 - 08:59:59	543	7.0%	0.15	5.4 MB	16.7%	1.53
Nov 15 09:00:00 - 09:59:59	452	5.8%	0.13	2.0 MB	6.2%	0.56
Nov 15 10:00:00 - 10:59:59	547	7.0%	0.15	2.5 MB	7.7%	0.70
Nov 15 11:00:00 - 11:59:59	466	6.0%	0.13	1.4 MB	4.5%	0.41
Nov 15 12:00:00 - 12:59:59	393	5.0%	0.11	1.3 MB	3.9%	0.36
Nov 15 13:00:00 - 13:59:59	337	4.3%	0.09	1.2 MB	3.9%	0.35
Nov 15 14:00:00 - 14:59:59	433	5.6%	0.12	1.6 MB	5.1%	0.46
Nov 15 15:00:00 - 15:59:59	372	4.8%	0.10	1.9 MB	6.1%	0.55
Nov 15 16:00:00 - 16:59:59	330	4.2%	0.09	1.2 MB	3.7%	0.33
Nov 15 17:00:00 - 17:59:59	270	3.5%	0.07	1.0 MB	3.1%	0.29
Nov 15 18:00:00 - 18:59:59	164	2.1%	0.05	787.0 KB	2.4%	0.22
Nov 15 19:00:00 - 19:59:59	166	2.1%	0.05	608.0 KB	1.9%	0.17
Nov 15 20:00:00 - 20:59:59	189	2.4%	0.05	538.7 KB	1.6%	0.15
Nov 15 21:00:00 - 21:59:59	124	1.6%	0.03	445.2 KB	1.4%	0.12
Nov 15 22:00:00 - 22:59:59	130	1.7%	0.04	786.1 KB	2.4%	0.22
Nov 15 23:00:00 - 23:59:59	124	1.6%	0.03	391.1 KB	1.2%	0.11
Nov 16 00:00:00 - 00:01:01	5	0.1%	0.08	39.5 KB	0.1%	0.65
TOTAL: 24:00:00	7783	100.0%	0.09	32.1 MB	100.0%	0.38

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	99.9 GB	6.5%	0	0.56	33037.40
CYCTXT1	TEXT	5.0 GB	3.9 GB	78.6%	0	0.02	2533.44
TOTAL: 3	-	3077.0 GB	103.9 GB	3.4%	1	0.41	-

Sites sending bad articles:

Server		Total	Group	Other
1	usenet.blueworldhosting.com	1033	68	965
2	usenet.goja.nl.eu.org	424	0	424
3	nntp.comgw.net	275	0	275
4	news.netfront.net	254	0	254
5	endofthelinebbs-out.news.weretis.net	219	0	219
6	i2pn.org	116	0	116
7	newsfeed.bofh.team	108	0	108
8	endofthelinebbs.peers.news.panix.com	65	33	32
9	news.hispagatos.org	33	22	11
10	newsfeed.xs3.de	29	0	29
11	news.nntp4.net	16	0	16
12	glockenspiel.complete.org	8	0	8
13	usenet.network	8	0	8
14	endoftheline-out.news.chmurka.net	5	0	5
15	news.samoylyk.net	5	0	5
16	news.bbs.nz	2	0	2
17	news.furie.org.uk	1	0	1
TOTAL: 17		2601	123	2478

Unwanted newsgroups [Top 20]:

Newsgroup	Count
de.sci.announce	94
hispagatos.hacking.ctf	14
hispagatos.mastodon.tag.hacking	5
hispagatos.hacking.news	2
news.test_messages	2
soc.culture.russia	2
atl.binaries.android	1
hispagatos.hacking.exploits	1
misc.phone.mobile.android	1
rec.autos.vw	1
TOTAL: 10	123

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	1463
EMP (phr path)	327
EMP (md5)	263
Too many newsgroups	234
Binary Image: misplaced jpg	80
EMP (phn nph)	29
User-issued cancel	16
Too many newsgroups (meow)	11
EMP (fsl)	7
HTML Multipart	5
EMP (phr nph)	2
Binary: misplaced binary	1
Subject (lSdk-v)	1
TOTAL: 13	2439

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
i2pn2-nocem@i2pn2.org (spam)	159	2466	0	2466
nocem@eternal-september.org (bot)	90	806	0	806
robot@pasdenom.info (spam)	2	2	0	2
i2pn2-nocem@i2pn2.org (multipost)	37	0	44	44
robot@pasdenom.info (spam2)	9	0	10	10
robot@pasdenom.info (spam3)	5	0	7	7
i2pn2-nocem@i2pn2.org (rmp)	1	0	6	6
TOTAL: 7	303	3274	67	3341

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	newsfeed.bofh.team	1
	endoftheline-out.news.chmurka.net	1
	TOTAL: 2	2
Including strange strings
	endofthelinebbs-out.news.weretis.net	13
	usenet.blueworldhosting.com	11
	usenet.goja.nl.eu.org	11
	endofthelinebbs.peers.news.panix.com	1
	nntp.comgw.net	1
	TOTAL: 5	37
TOTAL: 2		39

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	nyheter.lysator.liu.se	8180	3617	3586	579	1	44%	23:59:04
2	news.netfront.net	12255	2586	5143	1	24	21%	23:59:51
3	photonic.trudheim.com	8927	1029	4765	1988	1	11%	23:59:51
4	news.furie.org.uk	5410	811	3016	11	0	14%	23:59:51
5	endofthelinebbs.peers.news.panix.com	8406	508	7244	16	0	6%	23:59:51
6	usenet.goja.nl.eu.org	8381	378	5762	234	13	4%	23:59:51
7	news.corradoroberto.it	1438	246	713	0	0	17%	23:45:35
8	newsfeed.bofh.team	9246	219	7270	0	0	2%	23:59:51
9	news.nk.ca	10633	203	6709	690	71	1%	23:59:51
10	usenet.network	9703	190	6821	62	1	1%	23:59:51
11	nntp.comgw.net	8734	174	7192	120	14	1%	23:59:36
12	news.hispagatos.org	10133	166	7339	262	0	1%	23:59:51
13	news.nntp4.net	9504	127	7488	62	8	1%	23:59:51
14	news.bbs.nz	3355	92	1582	163	5927	2%	23:59:51
15	i2pn.org	8669	90	7178	77	80	1%	23:59:51
16	news.weretis.net	8119	83	7529	168	0	1%	23:59:39
17	news.tnetconsulting.net	8965	79	7466	222	20	0%	23:59:51
18	news.quux.org	7810	54	5135	658	489	0%	23:59:51
19	news.chmurka.net	5501	47	3770	3	0	0%	23:59:46
20	newsfeed.xs3.de	9810	43	7329	253	24	0%	23:59:49
21	news.samoylyk.net	10395	30	7602	39	27	0%	23:59:51
22	ddt.demos.su	91	21	57	0	0	23%	07:04:39
23	usenet.blueworldhosting.com	6638	0	6410	0	9	0%	23:59:34
24	feed.opticnetworks.net	0	0	0	0	7783	0%	23:59:51
TOTAL: 24		180303	10793	127106	5608	14492	5%	558:45:18

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nyheter.lysator.liu.se	14.3 MB	4.2 MB	18.5 MB	0.2 KB/s	4.5 KB	23:59:04
2	news.netfront.net	13.9 MB	1.3 KB	13.9 MB	0.2 KB/s	5.5 KB	23:59:51
3	photonic.trudheim.com	5.2 MB	7.5 MB	12.7 MB	0.2 KB/s	4.3 KB	23:59:51
4	news.furie.org.uk	4.6 MB	41.9 KB	4.7 MB	0.1 KB/s	5.8 KB	23:59:51
5	newsfeed.bofh.team	2.8 MB	0.0 KB	2.8 MB	0.0 KB/s	13.1 KB	23:59:51
6	news.hispagatos.org	2.7 MB	2.0 MB	4.7 MB	0.1 KB/s	11.3 KB	23:59:51
7	endofthelinebbs.peers.news.panix.com	2.6 MB	52.8 KB	2.6 MB	0.0 KB/s	5.2 KB	23:59:51
8	news.nntp4.net	1.6 MB	550.2 KB	2.1 MB	0.0 KB/s	11.4 KB	23:59:51
9	nntp.comgw.net	1.3 MB	1.8 MB	3.1 MB	0.0 KB/s	10.9 KB	23:59:36
10	news.samoylyk.net	1.3 MB	64.6 KB	1.4 MB	0.0 KB/s	20.0 KB	23:59:51
11	news.quux.org	958.5 KB	3.2 MB	4.1 MB	0.0 KB/s	5.9 KB	23:59:51
12	usenet.network	810.9 KB	144.8 KB	955.7 KB	0.0 KB/s	3.8 KB	23:59:51
13	ddt.demos.su	761.9 KB	0.0 KB	761.9 KB	0.0 KB/s	36.3 KB	07:04:39
14	news.nk.ca	702.0 KB	4.1 MB	4.8 MB	0.1 KB/s	5.5 KB	23:59:51
15	usenet.goja.nl.eu.org	697.4 KB	1.9 MB	2.6 MB	0.0 KB/s	4.4 KB	23:59:51
16	news.corradoroberto.it	626.5 KB	0.0 KB	626.5 KB	0.0 KB/s	2.5 KB	23:45:35
17	newsfeed.xs3.de	371.4 KB	2.0 MB	2.3 MB	0.0 KB/s	8.1 KB	23:59:49
18	news.tnetconsulting.net	347.5 KB	1.6 MB	1.9 MB	0.0 KB/s	6.5 KB	23:59:51
19	news.weretis.net	312.9 KB	2.1 MB	2.4 MB	0.0 KB/s	9.8 KB	23:59:39
20	news.bbs.nz	220.0 KB	592.3 KB	812.3 KB	0.0 KB/s	3.2 KB	23:59:51
21	i2pn.org	215.2 KB	365.3 KB	580.4 KB	0.0 KB/s	3.5 KB	23:59:51
22	news.chmurka.net	135.4 KB	8.9 KB	144.3 KB	0.0 KB/s	2.9 KB	23:59:46
23	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:51
24	usenet.blueworldhosting.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:34
TOTAL: 24		56.4 MB	32.1 MB	88.5 MB	0.0 KB/s	5.5 KB	558:45:18

NNRP readership statistics:

System		Conn	Elapsed
1	2001:470:28:a8:3ad5:47ff:fe02:c2fd	2801	00:22:09
2	167.94.146.48	3	00:00:03
3	206.168.34.41	3	00:00:03
4	194.165.16.76	1	00:00:00
5	198.235.24.92	1	00:00:07
6	205.210.31.20	1	00:00:10
7	47.186.35.210	1	00:02:15
8	91.238.181.31	1	00:00:00
9	azpdsgb12.stretchoid.com	1	00:00:00
10	localhost	1	00:11:32
11	server-0-3.survey.inspici.com	1	00:00:04
TOTAL: 11		2815	00:36:27

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	?	2802	00:33:42
2	unresolved	11	00:02:41
3	*.stretchoid.com	1	00:00:00
4	*.survey.inspici.com	1	00:00:04
TOTAL: 4		2815	00:36:27

NNRP auth users [Top 20]:

User	Conn
getlist	1
nelgin	1
TOTAL: 2	2

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
2001:470:28:a8:3ad5:47ff:fe02:c2fd	9.796	40.072	0.000	00:22:09
localhost	180.243	363.466	0.075	00:11:32
47.186.35.210	0.004	0.059	0.093	00:02:15
205.210.31.20	0.004	0.013	0.000	00:00:10
198.235.24.92	0.003	0.014	0.000	00:00:07
server-0-3.survey.inspici.com	0.000	0.017	0.000	00:00:04
167.94.146.48	0.020	0.047	0.000	00:00:03
206.168.34.41	0.028	0.037	0.000	00:00:03
194.165.16.76	0.009	0.009	0.000	00:00:00
91.238.181.31	0.000	0.017	0.000	00:00:00
azpdsgb12.stretchoid.com	0.004	0.013	0.000	00:00:00
TOTAL: 11	190.111	403.764	0.168	00:36:27

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

System	Conn
167.94.146.48	1
194.165.16.76	1
206.168.34.41	1
91.238.181.31	1
azpdsgb12.stretchoid.com	1
TOTAL: 5	5

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

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
167.94.146.48	1	2
194.165.16.76	1	1
206.168.34.41	1	2
91.238.181.31	1	1
TOTAL: 4	4	7

Daily Usenet Report

Nov 15 00:01:01 -- Nov 16 00:01:01