Daily Usenet Report: Sep 1 00:01:01

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Sep 1 03:45:02 newsfeed pgpverify[883997]: pgpverify: verification failed
Sep 1 08:13:02 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Sep 2 00:00:01 newsfeed nnrpd[1282824]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
innd	8373	28.2%	1.2 MB	19.3%
nnrpd	8370	28.2%	865.0 KB	13.7%
inn	8296	27.9%	3.3 MB	52.9%
innfeed	4126	13.9%	793.7 KB	12.6%
cnfsstat	432	1.5%	75.5 KB	1.2%
nocem	105	0.4%	19.5 KB	0.3%
controlchan	12	0.0%	2.7 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 8	29715	100.0%	6.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	112280	59.7%
Negative hits	67833	36.1%
Do not exist	7942	4.2%
Cache misses	23	0.0%
TOTAL: 4	188078	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.543	0.0%	233	0.000	2.330	13.000
article cleanup	00:00:00.131	0.0%	8021	0.000	0.016	0.297
article logging	00:00:00.395	0.0%	8108	0.000	0.049	0.143
article parse	00:00:00.170	0.0%	12218	0.000	0.014	0.100
article write	00:00:02.085	0.0%	6658	0.000	0.313	6.158
artlog/artcncl	00:00:00.007	0.0%	190	0.000	0.037	0.286
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:00:01.574	0.0%	189239	0.000	0.008	0.497
hisgrep/artcncl	00:00:00.383	0.0%	232	0.000	1.651	9.000
hishave/artcncl	00:00:00.033	0.0%	197	0.000	0.168	3.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:26.573	0.2%	189476	0.099	0.774	6.721
history sync	00:00:00.082	0.0%	399	0.000	0.206	1.667
history write	00:01:58.162	0.1%	8006	3.333	14.759	108.714
hiswrite/artcncl	00:00:00.021	0.0%	7	2.600	3.000	4.000
idle	23:50:53.780	99.1%	189378	104.357	453.346	1856.684
nntp read	00:00:04.994	0.0%	190204	0.000	0.026	0.840
overview write	00:06:47.595	0.5%	6658	0.444	61.219	4038.432
perl filter	00:00:27.266	0.0%	8004	1.679	3.407	15.410
python filter	00:00:01.972	0.0%	8004	0.000	0.246	6.115
site send	00:00:01.092	0.0%	13378	0.000	0.082	0.256
TOTAL: 24:03:38.910	24:02:46.858	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.011	0.0%	7019	0.000	0.002	0.021
article prepare	00:00:00.005	0.0%	37704	0.000	0.000	0.005
article read	00:00:00.514	0.0%	3655	0.000	0.141	0.567
backlog stats	00:00:00.391	0.0%	237302	0.000	0.002	0.009
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.096	0.0%	164651	0.000	0.013	0.053
data write	00:00:02.933	0.0%	161073	0.005	0.018	0.039
idle	23:58:37.414	100.0%	237302	177.103	363.745	1344.666
status file	00:00:00.310	0.0%	275	0.833	1.127	9.000
TOTAL: 23:58:54.990	23:58:43.674	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:23:08.697	54.0%	8339	0.500	166.530	12114.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.682	0.2%	95434	0.000	0.049	0.333
TOTAL: 00:42:53.082	00:23:13.379	54.2%	-	-	-	-

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
moderator@dana.de	0	0	1	1	0	1
TOTAL	0	0	1	1	0	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	outgoing.news.lysator.liu.se	1	6717	1524	4978	215	22%	23:59:58
2	endofthelinebbs-out.news.weretis.net	5	12686	1252	11240	194	9%	45:34:31
3	usenet.blueworldhosting.com	721	7817	789	6586	442	10%	23:58:12
4	usenet.goja.nl.eu.org	12	7695	605	6986	104	7%	25:46:25
5	news.hispagatos.org	5	7323	567	6616	140	7%	24:26:18
6	nntp.comgw.net	3	7471	566	6754	151	7%	23:52:48
7	news.corradoroberto.it	12	1189	348	833	8	29%	23:21:53
8	i2pn.org	1	7090	200	6872	18	2%	23:59:55
9	newsfeed.bofh.team	1	7795	191	7586	18	2%	23:59:56
10	endofthelinebbs.peers.news.panix.com	25	7149	132	7009	8	1%	23:53:06
11	usenet.network	2	5285	105	5163	17	1%	23:59:49
12	endoftheline-out.news.chmurka.net	1	3451	94	3354	3	2%	23:59:56
13	news.samoylyk.net	2	6199	80	6119	0	1%	23:59:41
14	newsfeed.xs3.de	1	5734	73	5658	3	1%	23:59:54
15	news.netfront.net	1	5382	45	5328	9	0%	24:50:16
16	glockenspiel.complete.org	2	5345	12	5330	3	0%	23:58:36
17	news.nntp4.net	2	5992	11	5977	4	0%	23:59:32
18	news.furie.org.uk	2	3319	8	3310	1	0%	23:59:45
19	news.bbs.nz	2	6110	7	6102	1	0%	23:59:38
20	photonic.trudheim.com	3	1533	0	1532	1	0%	21:39:23
21	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:05
22	localhost	28	0	0	0	0	0%	00:00:00
23	news.nk.ca	2	0	0	0	0	0%	00:20:13
TOTAL: 23		835	121282	6609	113333	1340	5%	501:49:50

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	endofthelinebbs-out.news.weretis.net	6.0 MB	9.9 KB	2.1 MB	8.1 MB	73%	5.8 KB
2	outgoing.news.lysator.liu.se	4.8 MB	137.2 KB	733.9 KB	5.7 MB	85%	3.3 KB
3	news.corradoroberto.it	2.2 MB	0.0 KB	13.4 KB	2.2 MB	99%	6.2 KB
4	usenet.goja.nl.eu.org	1.9 MB	5.0 KB	145.4 KB	2.1 MB	92%	3.0 KB
5	nntp.comgw.net	1.9 MB	4.9 KB	292.6 KB	2.1 MB	86%	3.1 KB
6	usenet.blueworldhosting.com	1.8 MB	0.0 KB	1.0 MB	2.8 MB	64%	2.3 KB
7	news.hispagatos.org	1.7 MB	5.0 KB	285.2 KB	2.0 MB	85%	2.9 KB
8	usenet.network	822.4 KB	102.8 KB	11.9 KB	937.1 KB	87%	7.7 KB
9	i2pn.org	721.9 KB	4.9 KB	30.0 KB	756.8 KB	95%	3.5 KB
10	newsfeed.bofh.team	535.3 KB	5.0 KB	24.6 KB	564.9 KB	94%	2.7 KB
11	news.samoylyk.net	344.4 KB	0.0 KB	0.0 KB	344.4 KB	100%	4.3 KB
12	endofthelinebbs.peers.news.panix.com	344.1 KB	4.9 KB	8.6 KB	357.6 KB	96%	2.6 KB
13	newsfeed.xs3.de	244.3 KB	5.0 KB	1.9 KB	251.2 KB	97%	3.3 KB
14	news.netfront.net	238.6 KB	5.0 KB	14.9 KB	258.5 KB	92%	4.8 KB
15	endoftheline-out.news.chmurka.net	217.3 KB	0.0 KB	2.1 KB	219.4 KB	99%	2.3 KB
16	glockenspiel.complete.org	119.0 KB	102.0 KB	0.0 KB	221.0 KB	53%	14.7 KB
17	news.furie.org.uk	54.5 KB	5.0 KB	0.0 KB	59.5 KB	91%	6.6 KB
18	news.nntp4.net	35.7 KB	80.3 KB	3.4 KB	119.4 KB	29%	8.0 KB
19	news.bbs.nz	18.4 KB	5.0 KB	0.0 KB	23.4 KB	78%	2.9 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	photonic.trudheim.com	0.0 KB	5.0 KB	0.0 KB	5.0 KB	0%	5.0 KB
TOTAL: 23		23.9 MB	486.9 KB	4.7 MB	29.0 MB	82%	3.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 1 00:01:01 - 00:59:59	140	2.1%	0.04	690.8 KB	2.8%	0.20
Sep 1 01:00:00 - 01:59:59	263	4.0%	0.07	769.5 KB	3.1%	0.21
Sep 1 02:00:00 - 02:59:59	313	4.7%	0.09	1.1 MB	4.4%	0.30
Sep 1 03:00:00 - 03:59:59	351	5.3%	0.10	1.0 MB	4.3%	0.30
Sep 1 04:00:00 - 04:59:59	330	5.0%	0.09	997.2 KB	4.0%	0.28
Sep 1 05:00:00 - 05:59:59	292	4.4%	0.08	992.0 KB	4.0%	0.28
Sep 1 06:00:00 - 06:59:59	325	4.9%	0.09	1.2 MB	5.1%	0.35
Sep 1 07:00:00 - 07:59:59	363	5.5%	0.10	1.3 MB	5.5%	0.38
Sep 1 08:00:00 - 08:59:59	379	5.7%	0.11	1.2 MB	5.1%	0.36
Sep 1 09:00:00 - 09:59:59	337	5.1%	0.09	834.3 KB	3.3%	0.23
Sep 1 10:00:00 - 10:59:59	437	6.6%	0.12	1.5 MB	6.1%	0.42
Sep 1 11:00:00 - 11:59:59	403	6.1%	0.11	1.4 MB	5.7%	0.39
Sep 1 12:00:00 - 12:59:59	327	4.9%	0.09	1019.8 KB	4.1%	0.28
Sep 1 13:00:00 - 13:59:59	412	6.2%	0.11	3.5 MB	14.4%	1.00
Sep 1 14:00:00 - 14:59:59	358	5.4%	0.10	1.2 MB	4.9%	0.34
Sep 1 15:00:00 - 15:59:59	359	5.4%	0.10	1.2 MB	5.0%	0.35
Sep 1 16:00:00 - 16:59:59	273	4.1%	0.08	1004.3 KB	4.0%	0.28
Sep 1 17:00:00 - 17:59:59	209	3.1%	0.06	704.4 KB	2.8%	0.20
Sep 1 18:00:00 - 18:59:59	171	2.6%	0.05	585.9 KB	2.3%	0.16
Sep 1 19:00:00 - 19:59:59	142	2.1%	0.04	431.5 KB	1.7%	0.12
Sep 1 20:00:00 - 20:59:59	104	1.6%	0.03	442.5 KB	1.8%	0.12
Sep 1 21:00:00 - 21:59:59	113	1.7%	0.03	480.4 KB	1.9%	0.13
Sep 1 22:00:00 - 22:59:59	92	1.4%	0.03	399.7 KB	1.6%	0.11
Sep 1 23:00:00 - 23:59:59	158	2.4%	0.04	477.1 KB	1.9%	0.13
Sep 2 00:00:00 - 00:01:01	4	0.1%	0.07	34.9 KB	0.1%	0.57
TOTAL: 24:00:00	6655	100.0%	0.08	24.4 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	96.9 GB	6.3%	0	0.42	44219.63
CYCTXT1	TEXT	5.0 GB	3.8 GB	76.7%	0	0.09	708.95
TOTAL: 3	-	3077.0 GB	100.8 GB	3.3%	1	0.32	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	467	0	0	467
2	endofthelinebbs-out.news.weretis.net	240	0	2	238
3	outgoing.news.lysator.liu.se	213	0	1	212
4	news.hispagatos.org	157	14	1	142
5	nntp.comgw.net	157	0	1	156
6	usenet.goja.nl.eu.org	119	0	1	118
7	i2pn.org	20	0	1	19
8	newsfeed.bofh.team	20	0	1	19
9	usenet.network	15	0	1	14
10	endofthelinebbs.peers.news.panix.com	11	1	1	9
11	news.netfront.net	9	0	1	8
12	news.corradoroberto.it	8	0	0	8
13	glockenspiel.complete.org	5	0	1	4
14	news.nntp4.net	3	0	1	2
15	newsfeed.xs3.de	3	0	1	2
16	endoftheline-out.news.chmurka.net	1	0	0	1
17	news.bbs.nz	1	0	1	0
18	news.furie.org.uk	1	0	1	0
19	photonic.trudheim.com	1	0	1	0
TOTAL: 19		1451	15	17	1419

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.hacking.ctf	8
hispagatos.hacking.exploits	4
hispagatos.hacking.news	2
matteo.salvini.it	1
TOTAL: 4	15

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	461
EMP (phr path)	347
EMP (md5)	214
User-issued cancel	169
Too many newsgroups	81
EMP (phl)	35
EMP (phr nph)	9
Binary: misplaced binary	5
HTML Multipart	5
Cancel for rejected article	4
TOTAL: 10	1330

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
i2pn2-nocem@i2pn2.org (spam)	42	190	0	190
nocem@chmurka.net (spam)	1	1	0	1
i2pn2-nocem@i2pn2.org (multipost)	9	0	38	38
robot@pasdenom.info (spam2)	6	0	7	7
robot@pasdenom.info (spam3)	3	0	7	7
TOTAL: 5	61	191	52	243

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	endofthelinebbs-out.news.weretis.net	34
	usenet.blueworldhosting.com	25
	usenet.goja.nl.eu.org	13
	endofthelinebbs.peers.news.panix.com	4
	nntp.comgw.net	3
	news.hispagatos.org	3
	i2pn.org	2
	newsfeed.bofh.team	1
	usenet.network	1
	outgoing.news.lysator.liu.se	1
	TOTAL: 10	87
No colon-space in header field
	usenet.blueworldhosting.com	1
	TOTAL: 1	1
TOTAL: 2		88

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.netfront.net	10061	2624	3984	2	34	26%	23:58:13
2	nyheter.lysator.liu.se	5324	1138	3767	227	0	21%	23:59:52
3	photonic.trudheim.com	6944	489	5311	844	0	7%	23:59:52
4	endofthelinebbs.peers.news.panix.com	6843	365	6266	12	0	5%	23:59:52
5	news.hispagatos.org	7025	364	5645	96	0	5%	23:58:37
6	usenet.network	7055	299	5787	37	0	4%	23:59:18
7	news.furie.org.uk	3548	288	2801	16	0	8%	23:59:52
8	news.bbs.nz	12236	232	6196	210	112	1%	23:59:52
9	usenet.goja.nl.eu.org	7038	211	5770	46	0	2%	23:59:49
10	news.nk.ca	9930	177	6040	308	44	1%	23:59:52
11	news.tnetconsulting.net	7402	109	6424	104	3	1%	23:59:52
12	newsfeed.bofh.team	7488	107	6299	0	0	1%	23:58:32
13	news.weretis.net	6910	102	6497	47	0	1%	23:58:44
14	news.nntp4.net	7825	94	6523	15	9	1%	23:59:45
15	i2pn.org	7340	93	6298	44	54	1%	23:59:52
16	news.quux.org	7068	92	6309	234	0	1%	23:59:00
17	nntp.comgw.net	6965	75	5992	37	75	1%	23:59:52
18	news.corradoroberto.it	788	55	604	3	4	6%	22:42:50
19	news.chmurka.net	3640	48	3061	0	0	1%	23:59:52
20	newsfeed.xs3.de	7733	47	6443	63	0	0%	23:59:52
21	news.samoylyk.net	8037	24	6463	59	28	0%	23:59:32
22	usenet.blueworldhosting.com	6057	3	5587	0	5	0%	23:58:40
23	feed.opticnetworks.net	0	0	0	0	6655	0%	23:59:52
TOTAL: 23		153257	7036	118067	2404	7023	4%	550:31:24

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.netfront.net	10.8 MB	6.2 KB	10.8 MB	0.1 KB/s	4.2 KB	23:58:13
2	nyheter.lysator.liu.se	4.9 MB	1.4 MB	6.3 MB	0.1 KB/s	4.7 KB	23:59:52
3	news.furie.org.uk	2.7 MB	201.7 KB	2.9 MB	0.0 KB/s	9.9 KB	23:59:52
4	photonic.trudheim.com	2.3 MB	3.5 MB	5.8 MB	0.1 KB/s	4.5 KB	23:59:52
5	news.hispagatos.org	2.0 MB	656.6 KB	2.6 MB	0.0 KB/s	5.8 KB	23:58:37
6	endofthelinebbs.peers.news.panix.com	1.8 MB	25.7 KB	1.8 MB	0.0 KB/s	4.9 KB	23:59:52
7	usenet.network	1.2 MB	98.4 KB	1.3 MB	0.0 KB/s	4.1 KB	23:59:18
8	newsfeed.bofh.team	934.2 KB	0.0 KB	934.2 KB	0.0 KB/s	8.7 KB	23:58:32
9	news.bbs.nz	911.9 KB	807.9 KB	1.7 MB	0.0 KB/s	3.9 KB	23:59:52
10	news.nntp4.net	597.1 KB	88.5 KB	685.6 KB	0.0 KB/s	6.3 KB	23:59:45
11	news.tnetconsulting.net	591.8 KB	627.5 KB	1.2 MB	0.0 KB/s	5.7 KB	23:59:52
12	usenet.goja.nl.eu.org	568.4 KB	455.3 KB	1023.7 KB	0.0 KB/s	4.0 KB	23:59:49
13	news.quux.org	485.0 KB	878.2 KB	1.3 MB	0.0 KB/s	4.2 KB	23:59:00
14	news.weretis.net	456.0 KB	551.9 KB	1007.9 KB	0.0 KB/s	6.8 KB	23:58:44
15	news.chmurka.net	423.7 KB	0.0 KB	423.7 KB	0.0 KB/s	8.8 KB	23:59:52
16	news.nk.ca	384.1 KB	1.1 MB	1.4 MB	0.0 KB/s	3.0 KB	23:59:52
17	i2pn.org	363.1 KB	196.3 KB	559.4 KB	0.0 KB/s	4.1 KB	23:59:52
18	news.samoylyk.net	279.1 KB	221.6 KB	500.7 KB	0.0 KB/s	6.0 KB	23:59:32
19	nntp.comgw.net	247.6 KB	380.1 KB	627.7 KB	0.0 KB/s	5.6 KB	23:59:52
20	newsfeed.xs3.de	227.2 KB	448.5 KB	675.7 KB	0.0 KB/s	6.1 KB	23:59:52
21	news.corradoroberto.it	192.9 KB	26.0 KB	219.0 KB	0.0 KB/s	3.8 KB	22:42:50
22	usenet.blueworldhosting.com	10.5 KB	0.0 KB	10.5 KB	0.0 KB/s	3.5 KB	23:58:40
23	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:52
TOTAL: 23		32.2 MB	11.5 MB	43.7 MB	0.0 KB/s	4.7 KB	550:31:24

NNRP readership statistics:

System		Conn	Elapsed
1	ddt.demos.su	2779	00:30:18
2	147.185.133.152	1	00:00:04
3	198.235.24.115	1	00:00:10
4	198.235.24.98	1	00:00:10
5	azpdsgb24.stretchoid.com	1	00:00:00
6	heartfelt.monitoring.internet-measurement.com	1	00:00:01
7	localhost	1	00:12:07
TOTAL: 7		2785	00:42:51

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.demos.su	2779	00:30:18
2	unresolved	3	00:00:24
3	*.monitoring.internet-measurement.com	1	00:00:01
4	*.stretchoid.com	1	00:00:00
5	?	1	00:12:07
TOTAL: 5		2785	00:42:51

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
ddt.demos.su	9.666	37.246	0.000	00:30:18
localhost	179.063	368.160	0.074	00:12:07
198.235.24.98	0.006	0.009	0.000	00:00:10
198.235.24.115	0.004	0.012	0.000	00:00:10
147.185.133.152	0.003	0.013	0.000	00:00:04
heartfelt.monitoring.internet-measurement.com	0.003	0.013	0.000	00:00:01
azpdsgb24.stretchoid.com	0.004	0.013	0.000	00:00:00
TOTAL: 7	188.749	405.466	0.074	00:42:51

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

System	Conn
147.185.133.152	4
azpdsgb24.stretchoid.com	1
TOTAL: 2	5

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

Command	Count
Accept-Encoding: gzip	1
GET / HTTP/1.1	1
Host: 144.172.126.95:119	1
MGLNDD_144.172.126.95_119	1
User-Agent: Expanse, a Palo Alto Network...	1
TOTAL: 5	5

Daily Usenet Report

Sep 1 00:01:01 -- Sep 2 00:01:01