Daily Usenet Report: Feb 4 00:01:02

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Feb 4 15:00:07 newsfeed pgpverify[640521]: pgpverify: verification failed
Feb 4 15:01:14 newsfeed pgpverify[641031]: pgpverify: verification failed
Feb 4 15:02:17 newsfeed pgpverify[641376]: pgpverify: verification failed
Feb 5 00:00:01 newsfeed nnrpd[807054]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	14888	34.2%	1.9 MB	22.8%
innfeed	11298	25.9%	1.7 MB	19.7%
inn	9560	21.9%	3.7 MB	43.7%
innd	7378	16.9%	1.1 MB	12.9%
cnfsstat	432	1.0%	67.1 KB	0.8%
nocem	23	0.1%	3.5 KB	0.0%
controlchan	10	0.0%	1.0 KB	0.0%
pgpverify	3	0.0%	0.4 KB	0.0%
TOTAL: 8	43592	100.0%	8.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	128232	64.9%
Negative hits	60057	30.4%
Do not exist	9296	4.7%
Cache misses	16	0.0%
TOTAL: 4	197601	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.087	0.0%	26	0.000	3.346	9.000
article cleanup	00:00:00.216	0.0%	9538	0.000	0.023	0.542
article logging	00:00:00.500	0.0%	9574	0.000	0.052	0.479
article parse	00:00:00.275	0.0%	14313	0.000	0.019	0.121
article write	00:00:02.439	0.0%	7430	0.030	0.328	2.366
artlog/artcncl	00:00:00.001	0.0%	3	0.000	0.333	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:01.074	0.0%	192162	0.000	0.006	0.084
hisgrep/artcncl	00:00:00.074	0.0%	29	0.000	2.552	7.000
hishave/artcncl	00:00:00.000	0.0%	3	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:47.415	0.2%	199843	0.102	0.838	8.051
history sync	00:00:00.089	0.0%	391	0.000	0.228	1.667
history write	00:02:20.539	0.2%	9404	3.167	14.945	107.636
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:43:31.057	98.7%	189749	84.829	450.127	1420.464
nntp read	00:00:04.247	0.0%	193001	0.002	0.022	0.458
overview write	00:10:04.873	0.7%	7430	0.944	81.410	3098.400
perl filter	00:01:29.788	0.1%	9404	1.367	9.548	267.778
python filter	00:00:06.481	0.0%	9404	0.000	0.689	38.889
site send	00:00:01.191	0.0%	14885	0.000	0.080	0.254
TOTAL: 24:01:56.793	24:00:30.346	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.046	0.0%	12867	0.000	0.004	0.080
article prepare	00:00:00.011	0.0%	60140	0.000	0.000	0.004
article read	00:00:00.854	0.0%	6183	0.000	0.138	0.938
backlog stats	00:00:00.471	0.0%	288631	0.000	0.002	0.006
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:02.401	0.0%	192580	0.002	0.012	0.041
data write	00:00:03.512	0.0%	186869	0.007	0.019	0.038
idle	23:48:12.593	100.0%	288631	153.037	296.893	823.313
status file	00:00:03.351	0.0%	3085	0.984	1.086	6.800
TOTAL: 23:48:34.187	23:48:23.239	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:19:15.108	59.6%	8907	0.000	129.685	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.442	0.2%	96561	0.000	0.046	1.000
TOTAL: 00:32:18.140	00:19:19.550	59.8%	-	-	-	-

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
rmgroup	3
TOTAL: 12	123

Newsgroups removed:

Group
rec.arts.comics.info
rec.arts.comics.reviews
rec.radio.broadcasting
TOTAL: 3

Control Channel:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	endofthelinebbs-out.news.weretis.net	10	14293	2314	11785	194	16%	43:20:39
2	usenet.blueworldhosting.com	288	9307	1034	7438	835	11%	23:45:12
3	nntp.comgw.net	2	8695	607	7939	149	6%	23:59:33
4	usenet.goja.nl.eu.org	11	8472	512	7826	134	6%	24:17:59
5	usenet.network	4	6170	480	5669	21	7%	23:59:55
6	newsfeed.bofh.team	2	9161	373	8642	146	4%	23:59:18
7	newsfeed.xs3.de	4	7067	351	6556	160	4%	23:59:44
8	news.corradoroberto.it	9	1295	288	1005	2	22%	23:00:07
9	i2pn.org	2	8404	275	8093	36	3%	23:59:21
10	glockenspiel.complete.org	2	6705	250	6444	11	3%	24:03:37
11	news.samoylyk.net	1	7479	190	7243	46	2%	23:59:51
12	194.87.241.33	49	761	138	416	207	18%	16:28:09
13	endoftheline-out.news.chmurka.net	2	3771	124	3638	9	3%	23:59:50
14	2001:41d0:a:280::1	0	7018	95	6887	36	1%	21:36:29
15	news.hispagatos.org	10	8782	84	8650	48	0%	24:52:46
16	news.furie.org.uk	4	3920	46	3867	7	1%	23:59:43
17	endofthelinebbs.peers.news.panix.com	23	8114	36	8068	10	0%	23:56:29
18	news.bbs.nz	1	7366	12	7221	133	0%	06:22:40
19	outgoing.news.lysator.liu.se	4	5045	4	5034	7	0%	23:59:55
20	localhost	26	0	0	0	0	0%	00:00:00
21	news.nk.ca	1	0	0	0	0	0%	00:10:13
TOTAL: 21		455	131825	7213	122421	2191	5%	447:51:30

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	endofthelinebbs-out.news.weretis.net	7.1 MB	40.7 KB	383.1 KB	7.5 MB	94%	3.1 KB
2	usenet.network	4.7 MB	10.9 KB	17.2 KB	4.7 MB	99%	9.6 KB
3	usenet.blueworldhosting.com	3.0 MB	0.0 KB	1.8 MB	4.8 MB	61%	2.7 KB
4	nntp.comgw.net	1.6 MB	15.3 KB	249.0 KB	1.9 MB	86%	2.6 KB
5	news.corradoroberto.it	1.4 MB	1.6 KB	1.1 KB	1.4 MB	99%	5.1 KB
6	glockenspiel.complete.org	1.4 MB	11.0 KB	16.6 KB	1.4 MB	98%	5.5 KB
7	usenet.goja.nl.eu.org	1.3 MB	9.3 KB	213.5 KB	1.5 MB	85%	2.4 KB
8	news.samoylyk.net	1.2 MB	13.5 KB	104.9 KB	1.3 MB	91%	5.6 KB
9	newsfeed.xs3.de	1.0 MB	12.4 KB	418.3 KB	1.5 MB	71%	2.9 KB
10	i2pn.org	1.0 MB	18.0 KB	35.0 KB	1.1 MB	95%	3.5 KB
11	newsfeed.bofh.team	1003.1 KB	8.5 KB	246.9 KB	1.2 MB	79%	2.4 KB
12	194.87.241.33	416.6 KB	0.0 KB	542.2 KB	958.8 KB	43%	2.8 KB
13	endoftheline-out.news.chmurka.net	392.2 KB	13.1 KB	1.5 KB	406.8 KB	96%	3.1 KB
14	news.hispagatos.org	315.2 KB	24.6 KB	74.6 KB	414.4 KB	76%	3.1 KB
15	2001:41d0:a:280::1	259.6 KB	15.5 KB	95.5 KB	370.6 KB	70%	2.8 KB
16	news.furie.org.uk	201.8 KB	10.9 KB	1.2 KB	213.9 KB	94%	4.0 KB
17	endofthelinebbs.peers.news.panix.com	119.5 KB	19.5 KB	6.3 KB	145.3 KB	82%	3.2 KB
18	news.bbs.nz	29.9 KB	1.6 KB	259.9 KB	291.4 KB	10%	2.0 KB
19	outgoing.news.lysator.liu.se	9.6 KB	19.7 KB	0.0 KB	29.3 KB	32%	2.7 KB
20	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
21	news.nk.ca	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 21		26.4 MB	246.1 KB	4.5 MB	31.1 MB	84%	3.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 4 00:01:02 - 00:59:59	152	2.0%	0.04	616.3 KB	2.2%	0.17
Feb 4 01:00:00 - 01:59:59	187	2.5%	0.05	676.5 KB	2.5%	0.19
Feb 4 02:00:00 - 02:59:59	317	4.3%	0.09	1.2 MB	4.4%	0.33
Feb 4 03:00:00 - 03:59:59	379	5.1%	0.11	1.1 MB	4.0%	0.30
Feb 4 04:00:00 - 04:59:59	347	4.7%	0.10	1.2 MB	4.3%	0.33
Feb 4 05:00:00 - 05:59:59	380	5.1%	0.11	1.1 MB	4.2%	0.32
Feb 4 06:00:00 - 06:59:59	366	4.9%	0.10	1.1 MB	4.3%	0.32
Feb 4 07:00:00 - 07:59:59	392	5.3%	0.11	1.3 MB	4.9%	0.37
Feb 4 08:00:00 - 08:59:59	434	5.9%	0.12	1.6 MB	5.9%	0.45
Feb 4 09:00:00 - 09:59:59	449	6.1%	0.12	1.4 MB	5.2%	0.40
Feb 4 10:00:00 - 10:59:59	401	5.4%	0.11	1.4 MB	5.3%	0.40
Feb 4 11:00:00 - 11:59:59	386	5.2%	0.11	1.2 MB	4.4%	0.34
Feb 4 12:00:00 - 12:59:59	467	6.3%	0.13	1.6 MB	6.0%	0.45
Feb 4 13:00:00 - 13:59:59	411	5.5%	0.11	1.7 MB	6.5%	0.50
Feb 4 14:00:00 - 14:59:59	330	4.4%	0.09	1.4 MB	5.4%	0.41
Feb 4 15:00:00 - 15:59:59	371	5.0%	0.10	1.5 MB	5.7%	0.43
Feb 4 16:00:00 - 16:59:59	391	5.3%	0.11	1.5 MB	5.6%	0.42
Feb 4 17:00:00 - 17:59:59	247	3.3%	0.07	1.1 MB	4.0%	0.31
Feb 4 18:00:00 - 18:59:59	209	2.8%	0.06	716.5 KB	2.6%	0.20
Feb 4 19:00:00 - 19:59:59	191	2.6%	0.05	699.8 KB	2.6%	0.19
Feb 4 20:00:00 - 20:59:59	157	2.1%	0.04	835.8 KB	3.1%	0.23
Feb 4 21:00:00 - 21:59:59	136	1.8%	0.04	610.8 KB	2.2%	0.17
Feb 4 22:00:00 - 22:59:59	129	1.7%	0.04	670.8 KB	2.4%	0.19
Feb 4 23:00:00 - 23:59:59	185	2.5%	0.05	655.2 KB	2.4%	0.18
Feb 5 00:00:00 - 00:01:01	2	0.0%	0.03	5.8 KB	0.0%	0.09
TOTAL: 23:59:59	7416	100.0%	0.09	26.8 MB	100.0%	0.32

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	103.1 GB	6.7%	0	0.49	38104.83
CYCTXT1	TEXT	5.0 GB	4.0 GB	80.6%	0	0.03	2302.79
TOTAL: 3	-	3077.0 GB	107.2 GB	3.5%	1	0.34	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	850	0	0	850
2	endofthelinebbs-out.news.weretis.net	212	0	18	194
3	194.87.241.33	189	0	0	189
4	newsfeed.xs3.de	161	0	5	156
5	nntp.comgw.net	157	0	5	152
6	newsfeed.bofh.team	150	0	5	145
7	usenet.goja.nl.eu.org	136	0	4	132
8	news.hispagatos.org	46	16	10	20
9	news.samoylyk.net	46	0	4	42
10	i2pn.org	37	0	10	27
11	news.netfront.net	37	0	14	23
12	2001:41d0:a:280::1	36	0	5	31
13	usenet.network	23	0	5	18
14	glockenspiel.complete.org	15	0	9	6
15	news.bbs.nz	11	0	11	0
16	endofthelinebbs.peers.news.panix.com	10	1	8	1
17	endoftheline-out.news.chmurka.net	9	0	7	2
18	news.furie.org.uk	7	1	6	0
19	outgoing.news.lysator.liu.se	7	0	7	0
20	news.corradoroberto.it	2	0	1	1
TOTAL: 20		2141	18	134	1989

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hispagatos.mastodon.tag.hacking	10
hispagatos.hacking.news	6
01.alt.test	1
comp.os.linux.hardawe	1
TOTAL: 4	18

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	1252
EMP (phr path)	288
Too many newsgroups	164
EMP (md5)	153
EMP (phn nph)	57
User-issued cancel	22
Too many newsgroups (meow)	8
Angle-bracket bot	3
HTML Multipart	3
HTML post	2
Too many newsgroups (low_xpost)	1
TOTAL: 11	1953

NoCeM on Spool:

Issuer (type)	Notices	Skip	Total
robot@pasdenom.info (spam3)	8	18	18
i2pn2-nocem@i2pn2.org (multipost)	6	6	6
i2pn2-nocem@i2pn2.org (rmp)	1	5	5
robot@pasdenom.info (spam2)	4	4	4
TOTAL: 4	19	33	33

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	usenet.blueworldhosting.com	15
	endofthelinebbs-out.news.weretis.net	6
	nntp.comgw.net	6
	newsfeed.bofh.team	4
	newsfeed.xs3.de	2
	usenet.network	1
	i2pn.org	1
	usenet.goja.nl.eu.org	1
	TOTAL: 8	36
TOTAL: 1		36

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	nyheter.lysator.liu.se	7841	3077	3847	488	0	0	39%	23:59:41
2	news.netfront.net	11890	2202	5193	0	0	7	18%	23:59:40
3	endofthelinebbs.peers.news.panix.com	7721	1039	6364	10	0	0	13%	23:59:41
4	news.furie.org.uk	4980	710	2856	1	0	0	14%	23:59:41
5	photonic.trudheim.com	9454	666	5059	1686	2	5369	7%	23:56:31
6	news.nntp4.net	8670	404	6894	15	0	11	4%	23:59:41
7	usenet.goja.nl.eu.org	8185	359	6244	205	0	0	4%	23:59:40
8	newsfeed.bofh.team	8106	187	6836	0	0	0	2%	23:59:40
9	news.bbs.nz	13623	161	6608	636	0	53	1%	23:59:39
10	news.corradoroberto.it	1253	120	722	0	0	0	9%	22:23:34
11	news.nk.ca	9497	114	6556	619	0	6	1%	23:59:41
12	i2pn.org	8118	64	6990	60	0	35	0%	23:59:41
13	news.hispagatos.org	8587	60	6917	353	0	16	0%	23:59:40
14	news.quux.org	5706	59	4708	597	0	0	1%	23:59:41
15	news.weretis.net	7928	58	7109	246	0	4	0%	23:59:41
16	usenet.network	8367	43	6483	7	0	0	0%	23:59:40
17	nntp.comgw.net	7614	33	6621	138	0	0	0%	23:59:40
18	news.tnetconsulting.net	9265	22	7069	321	0	14	0%	23:59:41
19	news.chmurka.net	4487	18	3415	0	0	0	0%	23:59:40
20	newsfeed.xs3.de	8986	15	6773	254	0	0	0%	23:59:41
21	news.samoylyk.net	9125	13	7073	11	0	1	0%	23:58:47
22	ddt.demos.su	59	9	47	0	0	0	15%	07:13:34
23	usenet.blueworldhosting.com	6777	1	6284	0	0	0	0%	23:59:41
24	feed.opticnetworks.net	0	0	0	0	0	7416	0%	23:59:41
TOTAL: 24		176239	9434	126668	5647	2	12932	5%	557:25:57

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.netfront.net	10.1 MB	0.0 KB	10.1 MB	0.1 KB/s	4.7 KB	23:59:40
2	nyheter.lysator.liu.se	9.9 MB	4.6 MB	14.5 MB	0.2 KB/s	4.2 KB	23:59:41
3	news.furie.org.uk	5.2 MB	4.4 KB	5.2 MB	0.1 KB/s	7.4 KB	23:59:41
4	endofthelinebbs.peers.news.panix.com	5.0 MB	36.7 KB	5.0 MB	0.1 KB/s	4.9 KB	23:59:41
5	photonic.trudheim.com	3.9 MB	4.7 MB	8.5 MB	0.1 KB/s	3.7 KB	23:56:31
6	newsfeed.bofh.team	1.7 MB	0.0 KB	1.7 MB	0.0 KB/s	9.3 KB	23:59:40
7	news.nntp4.net	1005.7 KB	212.5 KB	1.2 MB	0.0 KB/s	2.9 KB	23:59:41
8	usenet.goja.nl.eu.org	873.0 KB	2.2 MB	3.0 MB	0.0 KB/s	5.5 KB	23:59:40
9	news.bbs.nz	787.4 KB	3.7 MB	4.5 MB	0.1 KB/s	5.8 KB	23:59:39
10	news.quux.org	483.8 KB	3.4 MB	3.9 MB	0.0 KB/s	6.0 KB	23:59:41
11	news.nk.ca	450.6 KB	3.9 MB	4.4 MB	0.1 KB/s	6.1 KB	23:59:41
12	newsfeed.xs3.de	295.7 KB	2.3 MB	2.6 MB	0.0 KB/s	9.8 KB	23:59:41
13	news.weretis.net	290.7 KB	2.3 MB	2.6 MB	0.0 KB/s	8.7 KB	23:59:41
14	nntp.comgw.net	273.8 KB	1.4 MB	1.6 MB	0.0 KB/s	9.9 KB	23:59:40
15	news.corradoroberto.it	271.1 KB	0.0 KB	271.1 KB	0.0 KB/s	2.3 KB	22:23:34
16	i2pn.org	239.1 KB	273.4 KB	512.6 KB	0.0 KB/s	4.1 KB	23:59:41
17	news.hispagatos.org	179.5 KB	3.1 MB	3.3 MB	0.0 KB/s	8.2 KB	23:59:40
18	usenet.network	166.5 KB	16.4 KB	183.0 KB	0.0 KB/s	3.7 KB	23:59:40
19	news.tnetconsulting.net	136.1 KB	2.9 MB	3.0 MB	0.0 KB/s	9.0 KB	23:59:41
20	news.samoylyk.net	55.4 KB	24.7 KB	80.0 KB	0.0 KB/s	3.3 KB	23:58:47
21	news.chmurka.net	40.7 KB	0.0 KB	40.7 KB	0.0 KB/s	2.3 KB	23:59:40
22	ddt.demos.su	20.0 KB	0.0 KB	20.0 KB	0.0 KB/s	2.2 KB	07:13:34
23	usenet.blueworldhosting.com	1.6 KB	0.0 KB	1.6 KB	0.0 KB/s	1.6 KB	23:59:41
24	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:41
TOTAL: 24		41.1 MB	35.0 MB	76.2 MB	0.0 KB/s	5.2 KB	557:25:57

NNRP readership statistics:

System		Conn	Elapsed
1	2a0e:73c2:8000:802c::2	2361	00:16:16
2	2001:41d0:a:280::1	609	00:03:38
3	106.75.20.12	1	00:00:00
4	198.235.24.67	1	00:00:10
5	198.235.24.72	1	00:00:05
6	91.238.181.31	1	00:00:00
7	admired.monitoring.internet-measurement.com	1	00:00:01
8	ec2-54-241-206-163.us-west-1.compute.amazonaws.com	1	00:00:00
9	localhost	1	00:12:04
TOTAL: 9		2977	00:32:17

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	?	2971	00:31:59
2	unresolved	4	00:00:15
3	*.monitoring.internet-measurement.com	1	00:00:01
4	*.us-west-1.compute.amazonaws.com	1	00:00:00
TOTAL: 4		2977	00:32:17

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
2a0e:73c2:8000:802c::2	8.835	34.123	0.000	00:16:16
localhost	179.950	370.612	0.074	00:12:04
2001:41d0:a:280::1	2.161	8.622	0.000	00:03:38
198.235.24.67	0.003	0.019	0.000	00:00:10
198.235.24.72	0.000	0.017	0.000	00:00:05
admired.monitoring.internet-measurement.com	0.004	0.013	0.000	00:00:01
106.75.20.12	0.010	0.010	0.000	00:00:00
91.238.181.31	0.004	0.012	0.000	00:00:00
ec2-54-241-206-163.us-west-1.compute.amazonaws.com	0.004	0.013	0.000	00:00:00
TOTAL: 9	190.971	413.441	0.074	00:32:17

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

System	Conn
91.238.181.31	1
TOTAL: 1	1

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

Command	Count
#003	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
91.238.181.31	1	1
TOTAL: 1	1	2

Daily Usenet Report

Feb 4 00:01:02 -- Feb 5 00:01:01