Daily Usenet Report: Aug 1 00:01:02

Log entries by program:

Program name	Lines	%Lines	Size	%Size
innfeed	23484	55.0%	3.1 MB	36.3%
inn	9970	23.3%	3.9 MB	46.0%
innd	8555	20.0%	1.4 MB	16.3%
cnfsstat	432	1.0%	74.1 KB	0.8%
nocem	181	0.4%	42.7 KB	0.5%
nnrpd	111	0.3%	11.5 KB	0.1%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 7	42734	100.0%	8.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	127904	57.5%
Negative hits	79674	35.8%
Do not exist	9180	4.1%
Cache misses	5744	2.6%
TOTAL: 4	222502	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.827	0.0%	525	0.000	1.575	139.000
article cleanup	00:00:00.064	0.0%	9427	0.000	0.007	0.048
article logging	00:00:00.488	0.0%	9557	0.000	0.051	1.154
article parse	00:00:00.249	0.0%	14892	0.000	0.017	0.063
article write	00:00:02.090	0.0%	7871	0.000	0.266	2.745
artlog/artcncl	00:00:00.009	0.0%	401	0.000	0.022	0.333
data move	00:00:00.637	0.0%	222149	0.000	0.003	0.064
hisgrep/artcncl	00:00:00.422	0.0%	527	0.000	0.801	21.000
hishave/artcncl	00:00:00.051	0.0%	473	0.000	0.108	1.667
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:03:06.005	0.2%	224878	0.091	0.827	5.239
history sync	00:00:00.069	0.0%	390	0.000	0.177	0.667
history write	00:02:13.665	0.2%	9289	3.223	14.390	130.520
hiswrite/artcncl	00:00:00.014	0.0%	4	3.000	3.500	3.667
idle	23:38:49.010	98.8%	214354	66.925	397.142	1466.152
nntp read	00:00:03.891	0.0%	222244	0.000	0.018	1.027
overview write	00:10:17.128	0.7%	7871	0.842	78.405	6422.587
perl filter	00:00:25.825	0.0%	9289	1.274	2.780	12.507
python filter	00:00:00.627	0.0%	9289	0.000	0.067	0.857
site send	00:00:01.183	0.0%	15835	0.000	0.075	0.233
TOTAL: 23:55:40.611	23:55:02.254	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.040	0.0%	17699	0.000	0.002	0.025
article prepare	00:00:00.012	0.0%	57424	0.000	0.000	0.011
article read	00:00:00.925	0.0%	4654	0.000	0.199	1.556
backlog stats	00:00:00.492	0.0%	300893	0.000	0.002	0.008
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.700	0.0%	202839	0.002	0.013	0.039
data write	00:00:03.886	0.0%	197014	0.010	0.020	0.034
idle	23:57:01.640	99.9%	300893	96.088	286.552	891.019
status file	00:00:31.416	0.0%	8404	0.983	3.738	335.288
TOTAL: 23:57:56.383	23:57:41.111	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	01:00:29.612	99.8%	25	12.000	145184.480	300044.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.001	0.0%	59	0.000	0.017	0.062
readart	00:00:00.002	0.0%	1	2.000	2.000	2.000
TOTAL: 01:00:37.609	01:00:29.615	99.8%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	100
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	122

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	endofthelinebbs-out.news.weretis.net	2	14555	2272	11906	377	15%	47:22:49
2	outgoing.news.lysator.liu.se	2	6808	1762	4896	150	25%	23:59:43
3	usenet.blueworldhosting.com	720	9025	766	7835	424	8%	23:55:26
4	usenet.network	1	6223	512	5702	9	8%	23:59:57
5	news.hispagatos.org	5	8004	504	7469	31	6%	24:26:24
6	news.corradoroberto.it	3	1464	315	1144	5	21%	23:52:34
7	i2pn.org	2	7812	262	7534	16	3%	23:59:28
8	usenet.goja.nl.eu.org	11	8492	237	8210	45	2%	25:33:28
9	nntp.comgw.net	1	8289	226	8007	56	2%	23:59:56
10	194.87.241.33	40	330	221	109	0	66%	17:00:36
11	endoftheline-out.news.chmurka.net	1	4172	219	3948	5	5%	23:59:56
12	newsfeed.bofh.team	1	8838	169	8646	23	1%	23:59:53
13	endofthelinebbs.peers.news.panix.com	24	8549	121	8400	28	1%	23:56:07
14	newsfeed.xs3.de	1	6693	119	6484	90	1%	23:59:53
15	news.samoylyk.net	2	7188	117	7062	9	1%	23:59:01
16	news.netfront.net	1	5168	27	5060	81	0%	21:54:30
17	news.nntp4.net	2	6755	21	6729	5	0%	23:59:38
18	glockenspiel.complete.org	2	5920	18	5899	3	0%	23:58:32
19	news.bbs.nz	3	7712	12	7650	50	0%	06:00:58
20	photonic.trudheim.com	5	1861	11	1737	113	0%	19:29:19
21	news.furie.org.uk	2	4524	4	4514	6	0%	23:59:53
22	eotl-out.news.tnetconsulting.net	1	0	0	0	0	0%	00:10:04
23	localhost	6	0	0	0	0	0%	00:00:00
24	news.nk.ca	2	0	0	0	0	0%	00:20:12
TOTAL: 24		840	138382	7915	128941	1526	5%	497:58:17

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	endofthelinebbs-out.news.weretis.net	7.9 MB	33.4 KB	623.6 KB	8.5 MB	92%	3.3 KB
2	outgoing.news.lysator.liu.se	6.5 MB	25.1 KB	419.8 KB	6.9 MB	93%	3.7 KB
3	usenet.network	4.9 MB	14.8 KB	6.5 KB	5.0 MB	99%	9.7 KB
4	usenet.blueworldhosting.com	2.2 MB	11.2 KB	1009.4 KB	3.2 MB	68%	2.8 KB
5	news.hispagatos.org	2.0 MB	19.1 KB	94.1 KB	2.1 MB	94%	4.0 KB
6	news.corradoroberto.it	1.6 MB	20.4 KB	0.0 KB	1.7 MB	98%	5.3 KB
7	nntp.comgw.net	1.3 MB	9.7 KB	468.5 KB	1.7 MB	72%	6.2 KB
8	i2pn.org	1.1 MB	7.7 KB	25.6 KB	1.1 MB	97%	4.1 KB
9	endoftheline-out.news.chmurka.net	817.8 KB	5.8 KB	10.6 KB	834.2 KB	98%	3.7 KB
10	usenet.goja.nl.eu.org	784.2 KB	12.8 KB	46.7 KB	843.7 KB	92%	3.0 KB
11	194.87.241.33	635.4 KB	0.0 KB	0.0 KB	635.4 KB	100%	2.9 KB
12	newsfeed.xs3.de	515.1 KB	17.7 KB	412.8 KB	945.6 KB	54%	4.5 KB
13	news.samoylyk.net	505.7 KB	26.3 KB	1.4 KB	533.4 KB	94%	4.2 KB
14	newsfeed.bofh.team	424.9 KB	24.6 KB	68.6 KB	518.2 KB	82%	2.7 KB
15	endofthelinebbs.peers.news.panix.com	368.9 KB	15.5 KB	38.5 KB	422.8 KB	87%	2.8 KB
16	glockenspiel.complete.org	245.8 KB	3.9 KB	0.7 KB	250.4 KB	98%	11.9 KB
17	news.netfront.net	99.7 KB	100.1 KB	560.3 KB	760.0 KB	13%	7.0 KB
18	photonic.trudheim.com	53.4 KB	27.6 KB	633.4 KB	714.4 KB	7%	5.8 KB
19	news.nntp4.net	50.7 KB	9.1 KB	0.0 KB	59.8 KB	84%	2.3 KB
20	news.bbs.nz	23.7 KB	2.3 KB	44.3 KB	70.3 KB	33%	1.1 KB
21	news.furie.org.uk	14.5 KB	19.7 KB	0.0 KB	34.1 KB	42%	3.4 KB
22	eotl-out.news.tnetconsulting.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
23	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
24	news.nk.ca	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 24		31.9 MB	406.6 KB	4.4 MB	36.7 MB	87%	4.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 1 00:01:02 - 00:59:59	246	3.1%	0.07	844.6 KB	2.6%	0.24
Aug 1 01:00:00 - 01:59:59	334	4.2%	0.09	1.5 MB	4.5%	0.41
Aug 1 02:00:00 - 02:59:59	381	4.8%	0.11	1.6 MB	4.9%	0.45
Aug 1 03:00:00 - 03:59:59	378	4.8%	0.10	1.4 MB	4.4%	0.39
Aug 1 04:00:00 - 04:59:59	434	5.5%	0.12	1.4 MB	4.3%	0.39
Aug 1 05:00:00 - 05:59:59	384	4.9%	0.11	1.2 MB	3.6%	0.33
Aug 1 06:00:00 - 06:59:59	373	4.7%	0.10	1.5 MB	4.8%	0.44
Aug 1 07:00:00 - 07:59:59	358	4.5%	0.10	1.3 MB	4.0%	0.36
Aug 1 08:00:00 - 08:59:59	445	5.6%	0.12	1.7 MB	5.2%	0.47
Aug 1 09:00:00 - 09:59:59	463	5.9%	0.13	2.2 MB	6.8%	0.62
Aug 1 10:00:00 - 10:59:59	431	5.5%	0.12	1.7 MB	5.2%	0.47
Aug 1 11:00:00 - 11:59:59	455	5.8%	0.13	1.9 MB	6.0%	0.55
Aug 1 12:00:00 - 12:59:59	391	5.0%	0.11	1.5 MB	4.8%	0.44
Aug 1 13:00:00 - 13:59:59	322	4.1%	0.09	1.3 MB	4.0%	0.37
Aug 1 14:00:00 - 14:59:59	471	6.0%	0.13	1.9 MB	5.9%	0.53
Aug 1 15:00:00 - 15:59:59	353	4.5%	0.10	1.7 MB	5.4%	0.49
Aug 1 16:00:00 - 16:59:59	375	4.8%	0.10	1.7 MB	5.4%	0.49
Aug 1 17:00:00 - 17:59:59	266	3.4%	0.07	1.3 MB	4.2%	0.38
Aug 1 18:00:00 - 18:59:59	201	2.5%	0.06	828.0 KB	2.5%	0.23
Aug 1 19:00:00 - 19:59:59	174	2.2%	0.05	940.1 KB	2.9%	0.26
Aug 1 20:00:00 - 20:59:59	157	2.0%	0.04	583.1 KB	1.8%	0.16
Aug 1 21:00:00 - 21:59:59	143	1.8%	0.04	536.5 KB	1.6%	0.15
Aug 1 22:00:00 - 22:59:59	126	1.6%	0.04	624.4 KB	1.9%	0.17
Aug 1 23:00:00 - 23:59:59	217	2.8%	0.06	954.1 KB	2.9%	0.27
Aug 2 00:00:00 - 00:01:02	7	0.1%	0.11	60.2 KB	0.2%	0.97
TOTAL: 24:00:00	7885	100.0%	0.09	31.9 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	95.7 GB	6.2%	0	0.56	33171.80
CYCTXT1	TEXT	5.0 GB	3.6 GB	72.3%	0	0.03	2177.87
TOTAL: 3	-	3077.0 GB	99.4 GB	3.2%	1	0.42	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	451	4	5	442
2	endofthelinebbs-out.news.weretis.net	446	2	13	431
3	outgoing.news.lysator.liu.se	151	9	8	134
4	photonic.trudheim.com	113	72	7	34
5	news.netfront.net	101	0	18	83
6	newsfeed.xs3.de	90	0	7	83
7	nntp.comgw.net	59	1	2	56
8	news.bbs.nz	56	1	7	48
9	usenet.goja.nl.eu.org	54	0	7	47
10	news.hispagatos.org	39	8	7	24
11	endofthelinebbs.peers.news.panix.com	32	3	6	23
12	newsfeed.bofh.team	23	3	8	12
13	i2pn.org	17	0	5	12
14	news.samoylyk.net	9	0	8	1
15	usenet.network	9	0	5	4
16	news.furie.org.uk	7	0	6	1
17	glockenspiel.complete.org	6	0	6	0
18	endoftheline-out.news.chmurka.net	5	0	3	2
19	news.corradoroberto.it	5	0	5	0
20	news.nntp4.net	5	0	5	0
TOTAL: 20		1678	103	138	1437

Unwanted newsgroups [Top 20]:

Newsgroup	Count
org.kernel.vger.kvm	59
alt.russian.z1	23
org.kernel.vger.linux-kernel	13
org.kernel.vger.linux-block	2
org.kvack.linux-mm	1
org.xenproject.lists.xen-devel	1
soc.culture.africa	1
soc.culture.france	1
soc.culture.germany	1
soc.culutre.china	1
TOTAL: 10	103

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	565
EMP (phr path)	342
EMP (md5)	143
Too many newsgroups	106
EMP (phn nph)	89
EMP (fsl)	21
EMP (phl)	19
User-issued cancel	13
HTML Multipart	5
Binary: misplaced binary	2
Too many newsgroups (meow)	2
TOTAL: 11	1307

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
i2pn2-nocem@i2pn2.org (spam)	80	464	0	464
nocem@eternal-september.org (bot)	6	6	0	6
robot@pasdenom.info (spam3)	6	0	11	11
robot@pasdenom.info (spam2)	2	0	2	2
TOTAL: 4	94	470	13	483

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	endofthelinebbs-out.news.weretis.net	82
	usenet.blueworldhosting.com	27
	usenet.goja.nl.eu.org	11
	endofthelinebbs.peers.news.panix.com	3
	nntp.comgw.net	3
	i2pn.org	1
	news.hispagatos.org	1
	newsfeed.bofh.team	1
	outgoing.news.lysator.liu.se	1
	TOTAL: 9	130
TOTAL: 1		130

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.netfront.net	11683	3400	4455	3	18	29%	23:59:53
2	nyheter.lysator.liu.se	6312	1401	4127	593	0	22%	23:59:53
3	photonic.trudheim.com	8267	783	6001	1089	0	9%	23:59:35
4	news.furie.org.uk	5200	614	3588	10	0	11%	23:59:04
5	news.hispagatos.org	8703	376	6704	298	0	4%	23:59:53
6	usenet.network	7687	332	6317	43	0	4%	23:59:48
7	newsfeed.bofh.team	8985	300	7381	1	0	3%	23:59:53
8	news.bbs.nz	20612	219	13492	692	366	1%	23:59:53
9	usenet.goja.nl.eu.org	8930	208	6989	433	0	2%	23:59:53
10	news.nntp4.net	8888	185	7596	51	0	2%	23:59:53
11	news.nk.ca	11236	170	6879	706	82	1%	23:59:53
12	i2pn.org	8640	139	7389	86	61	1%	23:59:50
13	news.corradoroberto.it	1152	107	886	1	0	9%	23:54:31
14	news.weretis.net	8144	76	7534	274	2	0%	23:59:53
15	nntp.comgw.net	8843	59	7349	245	0	0%	23:59:53
16	news.quux.org	8302	45	7171	656	0	0%	23:59:53
17	news.tnetconsulting.net	8779	41	7475	365	7	0%	23:58:49
18	news.samoylyk.net	9156	29	7664	15	1	0%	23:59:53
19	news.chmurka.net	4667	27	3795	1	1	0%	23:59:53
20	newsfeed.xs3.de	9363	26	7455	278	0	0%	23:59:53
21	usenet.blueworldhosting.com	7117	1	6874	0	0	0%	23:59:53
22	ddt.demos.su	2751	0	0	0	2794	0%	23:59:53
23	endofthelinebbs.peers.news.panix.com	0	0	0	0	7882	0%	23:59:53
24	feed.opticnetworks.net	0	0	0	0	7885	0%	23:59:52
TOTAL: 24		183417	8538	137121	5840	19099	4%	575:49:30

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.netfront.net	14.4 MB	22.0 KB	14.4 MB	0.2 KB/s	4.3 KB	23:59:53
2	nyheter.lysator.liu.se	6.0 MB	5.1 MB	11.1 MB	0.1 KB/s	5.7 KB	23:59:53
3	photonic.trudheim.com	4.9 MB	5.0 MB	9.9 MB	0.1 KB/s	5.4 KB	23:59:35
4	news.furie.org.uk	4.4 MB	58.7 KB	4.4 MB	0.1 KB/s	7.2 KB	23:59:04
5	newsfeed.bofh.team	2.5 MB	1.8 KB	2.5 MB	0.0 KB/s	8.5 KB	23:59:53
6	news.hispagatos.org	1.6 MB	2.6 MB	4.1 MB	0.0 KB/s	6.2 KB	23:59:53
7	usenet.network	1.5 MB	173.8 KB	1.6 MB	0.0 KB/s	4.5 KB	23:59:48
8	news.bbs.nz	932.5 KB	4.9 MB	5.8 MB	0.1 KB/s	6.6 KB	23:59:53
9	news.nntp4.net	749.7 KB	500.4 KB	1.2 MB	0.0 KB/s	5.3 KB	23:59:53
10	news.quux.org	568.1 KB	4.5 MB	5.1 MB	0.1 KB/s	7.4 KB	23:59:53
11	i2pn.org	507.5 KB	356.4 KB	863.9 KB	0.0 KB/s	3.8 KB	23:59:50
12	usenet.goja.nl.eu.org	488.7 KB	3.9 MB	4.4 MB	0.1 KB/s	7.0 KB	23:59:53
13	news.samoylyk.net	476.8 KB	21.9 KB	498.7 KB	0.0 KB/s	11.3 KB	23:59:53
14	news.nk.ca	397.4 KB	5.1 MB	5.5 MB	0.1 KB/s	6.4 KB	23:59:53
15	news.corradoroberto.it	352.1 KB	11.3 KB	363.4 KB	0.0 KB/s	3.4 KB	23:54:31
16	news.weretis.net	351.8 KB	2.4 MB	2.8 MB	0.0 KB/s	8.1 KB	23:59:53
17	news.tnetconsulting.net	214.0 KB	2.6 MB	2.8 MB	0.0 KB/s	7.1 KB	23:58:49
18	nntp.comgw.net	204.3 KB	2.2 MB	2.4 MB	0.0 KB/s	8.2 KB	23:59:53
19	newsfeed.xs3.de	146.4 KB	2.3 MB	2.5 MB	0.0 KB/s	8.4 KB	23:59:53
20	news.chmurka.net	93.4 KB	1.8 KB	95.2 KB	0.0 KB/s	3.4 KB	23:59:53
21	usenet.blueworldhosting.com	1.2 KB	0.0 KB	1.2 KB	0.0 KB/s	1.2 KB	23:59:53
22	ddt.demos.su	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:53
23	endofthelinebbs.peers.news.panix.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:53
24	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:52
TOTAL: 24		40.6 MB	41.7 MB	82.3 MB	0.0 KB/s	5.9 KB	575:49:30

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	1512264-v118.1274-static.plfdilaa.metronetinc.net	2	1	4.7 KB	1	0	0	01:00:04
TOTAL: 1		2	1	4.7 KB	1	0	0	01:00:37

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.1274-static.plfdilaa.metronetinc.net	2	1	4.7 KB	1	01:00:04
2	unresolved	5	0	0.0 KB	0	00:00:21
3	*.stretchoid.com	2	0	0.0 KB	0	00:00:09
4	*.monitoring.internet-measurement.com	1	0	0.0 KB	0	00:00:01
TOTAL: 4		10	1	4.7 KB	1	01:00:37

NNRP auth users [Top 20]:

User	Conn
the ripper	2
TOTAL: 1	2

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
1512264-v118.1274-static.plfdilaa.metronetinc.net	0.020	0.019	3.241	01:00:04
205.210.31.32	0.000	0.017	0.000	00:00:10
azpdes15.stretchoid.com	0.003	0.024	0.000	00:00:09
147.185.132.69	0.004	0.021	0.000	00:00:06
147.185.133.1	0.011	0.011	0.000	00:00:04
champion.monitoring.internet-measurement.com	0.000	0.017	0.000	00:00:01
2.57.122.206	0.000	0.016	0.000	00:00:00
45.227.254.49	0.000	0.016	0.000	00:00:00
azpdcgc65.stretchoid.com	0.000	0.022	0.000	00:00:00
TOTAL: 9	0.038	0.163	3.241	01:00:37

Curious NNRP server explorers [Top 20]:

System	Conn
147.185.132.69	1
147.185.133.1	1
2.57.122.206	1
205.210.31.32	1
45.227.254.49	1
azpdcgc65.stretchoid.com	1
azpdes15.stretchoid.com	1
champion.monitoring.internet-measurement.com	1
TOTAL: 8	8

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

System	Conn
147.185.133.1	4
45.227.254.49	1
azpdcgc65.stretchoid.com	1
TOTAL: 3	6

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

Command	Count
#003	1
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: 6	6

NNRP client timeouts [Top 20]:

System	Conn	Peer
1512264-v118.1274-static.plfdilaa.metronetinc.net	2	0
45.227.254.49	1	1
TOTAL: 2	3	2

Newsgroup request counts (by hierarchy):

Hierarchy		Count	Pct
1	rec	1	100.0%
TOTAL: 1		1	100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

Newsgroup		Count
1	rec.games.video.classic	1
TOTAL: 1		1

Daily Usenet Report

Aug 1 00:01:02 -- Aug 2 00:01:02