Daily Usenet report: Apr 30 15:00:02

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Apr 30 15:01:05 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
May 1 00:04:03 colo-sc-1 pgpverify[10766]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	12776	73.4%	1.5 MB	70.5%
innd	2821	16.2%	399.0 KB	17.9%
innfeed	1208	6.9%	170.0 KB	7.6%
cnfsstat	576	3.3%	86.6 KB	3.9%
controlchan	12	0.1%	2.6 KB	0.1%
pgpverify	1	0.0%	0.2 KB	0.0%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 7	17395	100.0%	2.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	32199	42.1%
Negative hits	31936	41.7%
Do not exist	12367	16.2%
Cache misses	32	0.0%
TOTAL: 4	76534	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.628	0.0%	56	0.000	29.071	256.000
article cleanup	00:00:00.073	0.0%	12435	0.000	0.006	0.045
article logging	00:00:00.416	0.0%	12732	0.000	0.033	0.098
article parse	00:00:00.198	0.0%	21640	0.000	0.009	0.043
article write	00:00:00.923	0.0%	10963	0.000	0.084	3.010
artlog/artcncl	00:00:00.000	0.0%	56	0.000	0.000	0.000
data move	00:00:00.041	0.0%	83953	0.000	0.000	0.005
hisgrep/artcncl	00:00:00.003	0.0%	54	0.000	0.056	1.000
hishave/artcncl	00:00:00.393	0.0%	56	0.000	7.018	111.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:37.177	0.0%	76936	0.000	0.483	10.161
history sync	00:00:00.012	0.0%	340	0.000	0.035	1.000
history write	00:00:00.709	0.0%	12435	0.000	0.057	1.673
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
idle	23:59:39.019	99.9%	84183	619.816	1026.086	2728.036
nntp read	00:00:00.587	0.0%	84136	0.000	0.007	0.020
overview write	00:00:00.043	0.0%	10963	0.000	0.004	0.032
perl filter	00:00:17.041	0.0%	12435	0.836	1.370	16.990
python filter	00:00:00.389	0.0%	12435	0.000	0.031	0.120
site send	00:00:01.704	0.0%	32889	0.008	0.052	0.096
TOTAL: 24:00:52.264	24:00:40.356	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.024	0.0%	11102	0.000	0.002	0.037
article prepare	00:00:00.000	0.0%	727	0.000	0.000	0.000
article read	00:00:00.028	0.0%	477	0.000	0.059	1.000
backlog stats	00:00:00.032	0.0%	64998	0.000	0.000	0.008
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.317	0.0%	38219	0.000	0.008	0.024
data write	00:00:00.753	0.0%	27592	0.000	0.027	0.094
idle	23:59:01.989	100.0%	64998	722.965	1328.379	3926.597
status file	00:00:00.851	0.0%	238	0.000	3.576	196.500
TOTAL: 23:59:10.401	23:59:03.994	100.0%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	147
name	2
pause	2
paused	2
reserve	2
TOTAL: 10	167

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
us-control@lists.killfile.org	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	hasname-out.news.weretis.net	3	20972	6590	13862	520	31%	47:25:29
2	news-out.aioe.org	2	10733	4000	6627	106	37%	23:30:39
3	news.nntp4.net	1	11202	114	10253	835	1%	21:59:24
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		7	42907	10704	30742	1461	24%	92:55:32

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.news.weretis.net	20.4 MB	885.4 KB	21.3 MB	95%	3.1 KB
2	news-out.aioe.org	9.6 MB	153.1 KB	9.7 MB	98%	2.4 KB
3	news.nntp4.net	321.7 KB	1.9 MB	2.2 MB	14%	2.4 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		30.3 MB	2.9 MB	33.2 MB	91%	2.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 30 15:00:02 - 15:59:59	525	4.8%	0.15	1.5 MB	4.9%	0.43
Apr 30 16:00:00 - 16:59:59	542	4.9%	0.15	1.5 MB	4.8%	0.42
Apr 30 17:00:00 - 17:59:59	558	5.1%	0.15	1.3 MB	4.3%	0.38
Apr 30 18:00:00 - 18:59:59	543	5.0%	0.15	1.5 MB	4.8%	0.43
Apr 30 19:00:00 - 19:59:59	545	5.0%	0.15	1.4 MB	4.7%	0.41
Apr 30 20:00:00 - 20:59:59	463	4.2%	0.13	1.2 MB	3.8%	0.34
Apr 30 21:00:00 - 21:59:59	424	3.9%	0.12	1.2 MB	4.0%	0.35
Apr 30 22:00:00 - 22:59:59	463	4.2%	0.13	1.5 MB	4.8%	0.42
Apr 30 23:00:00 - 23:59:59	388	3.5%	0.11	1.2 MB	4.0%	0.35
May 1 00:00:00 - 00:59:59	341	3.1%	0.09	1.1 MB	3.5%	0.31
May 1 01:00:00 - 01:59:59	457	4.2%	0.13	1.3 MB	4.2%	0.37
May 1 02:00:00 - 02:59:59	308	2.8%	0.09	987.8 KB	3.1%	0.27
May 1 03:00:00 - 03:59:59	234	2.1%	0.07	1004.8 KB	3.2%	0.28
May 1 04:00:00 - 04:59:59	243	2.2%	0.07	832.9 KB	2.6%	0.23
May 1 05:00:00 - 05:59:59	298	2.7%	0.08	901.5 KB	2.9%	0.25
May 1 06:00:00 - 06:59:59	373	3.4%	0.10	1.1 MB	3.7%	0.33
May 1 07:00:00 - 07:59:59	430	3.9%	0.12	1.1 MB	3.7%	0.32
May 1 08:00:00 - 08:59:59	409	3.7%	0.11	1.1 MB	3.6%	0.32
May 1 09:00:00 - 09:59:59	556	5.1%	0.15	1.4 MB	4.4%	0.39
May 1 10:00:00 - 10:59:59	576	5.3%	0.16	1.5 MB	4.7%	0.42
May 1 11:00:00 - 11:59:59	522	4.8%	0.14	1.5 MB	4.9%	0.43
May 1 12:00:00 - 12:59:59	564	5.1%	0.16	1.8 MB	5.7%	0.50
May 1 13:00:00 - 13:59:59	577	5.3%	0.16	1.5 MB	4.8%	0.42
May 1 14:00:00 - 14:59:59	615	5.6%	0.17	1.5 MB	4.8%	0.42
May 1 15:00:00 - 15:00:01	1	0.0%	1.00	1.6 KB	0.0%	1.56
TOTAL: 23:59:59	10955	100.0%	0.13	30.9 MB	100.0%	0.37

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
FOUR	DEFAULT	1024.0 MB	753.4 MB	73.6%	6	0.16	76.57
ONE	DEFAULT	1024.0 MB	927.5 MB	90.6%	8	0.16	77.63
THREE	DEFAULT	1024.0 MB	763.7 MB	74.6%	6	0.16	76.92
TWO	DEFAULT	1024.0 MB	755.1 MB	73.7%	6	0.16	77.27
TOTAL: 4	-	4.0 GB	3.1 GB	78.1%	26	0.16	-

Sites sending bad articles:

Server		Total	Group	Unapp	Other
1	news.nntp4.net	839	2	1	836
2	hasname-out.news.weretis.net	782	1	0	781
3	news-out.aioe.org	145	0	0	145
TOTAL: 3		1766	3	1	1762

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.math	1
alt.fetish.feet	1
afj.test	1
TOTAL: 3	3

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

Groups	Count
bit.listserv.catholic	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	988
EMP (md5)	203
EMP (phn nph)	155
EMP (phl)	105
Scoring filter	9
Too many newsgroups	3
HTML Multipart	2
TOTAL: 7	1465

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	hasname-out.news.weretis.net	259
	news-out.aioe.org	38
	TOTAL: 2	297
TOTAL: 1		297

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	bbsgslincom	395	395	0	0	0	100%	20:12:41
2	aioe	9099	62	6724	2	0	0%	23:59:51
3	nntp4	13357	9	10822	0	156	0%	23:59:51
4	weretis	4379	0	4189	10	0	0%	23:59:51
TOTAL: 4		27230	466	21735	12	156	1%	92:12:14

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	bbsgslincom	1.3 MB	0.0 KB	1.3 MB	3.4 KB	20:12:41
2	aioe	198.1 KB	5.2 KB	203.3 KB	3.2 KB	23:59:51
3	nntp4	37.7 KB	0.0 KB	37.7 KB	4.2 KB	23:59:51
4	weretis	0.0 KB	38.4 KB	38.4 KB	3.8 KB	23:59:51
TOTAL: 4		1.6 MB	43.6 KB	1.6 MB	3.4 KB	92:12:14

Daily Usenet report

Apr 30 15:00:02 -- May 1 15:00:01