Daily Usenet report: May 28 15:00:09

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

May 28 15:04:12 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
May 28 16:38:37 colo-sc-1 pgpverify[2591]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	15434	77.1%	1.9 MB	74.7%
innd	2727	13.6%	375.7 KB	14.8%
innfeed	1269	6.3%	179.4 KB	7.1%
cnfsstat	576	2.9%	84.7 KB	3.3%
controlchan	12	0.1%	2.8 KB	0.1%
overchan	2	0.0%	0.1 KB	0.0%
pgpverify	1	0.0%	0.0 KB	0.0%
TOTAL: 7	20021	100.0%	2.5 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	47212	47.9%
Positive hits	36405	37.0%
Do not exist	14834	15.1%
Cache misses	17	0.0%
TOTAL: 4	98468	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.051	0.0%	64	0.000	32.047	185.000
article cleanup	00:00:00.122	0.0%	14998	0.000	0.008	0.056
article logging	00:00:00.505	0.0%	15394	0.000	0.033	0.083
article parse	00:00:00.289	0.0%	28401	0.000	0.010	0.032
article write	00:00:05.458	0.0%	13811	0.000	0.395	12.298
artlog/artcncl	00:00:00.001	0.0%	64	0.000	0.016	1.000
data move	00:00:00.081	0.0%	109641	0.000	0.001	0.004
hisgrep/artcncl	00:00:00.001	0.0%	62	0.000	0.016	1.000
hishave/artcncl	00:00:00.655	0.0%	64	0.000	10.234	74.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:11:30.747	0.8%	99547	0.001	6.939	41.063
history sync	00:00:00.014	0.0%	386	0.000	0.036	0.500
history write	00:00:09.372	0.0%	14998	0.000	0.625	13.444
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
idle	23:47:59.277	99.1%	106023	435.422	808.120	2985.945
nntp read	00:00:00.734	0.0%	108943	0.000	0.007	0.018
overview write	00:00:00.053	0.0%	13811	0.000	0.004	0.037
perl filter	00:00:27.738	0.0%	14998	0.755	1.849	19.427
python filter	00:00:00.416	0.0%	14998	0.000	0.028	0.075
site send	00:00:02.564	0.0%	41431	0.022	0.062	0.143
TOTAL: 24:00:55.405	24:00:20.078	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.031	0.0%	13771	0.000	0.002	0.033
article prepare	00:00:00.000	0.0%	462	0.000	0.000	0.000
article read	00:00:00.017	0.0%	414	0.000	0.041	1.000
backlog stats	00:00:00.047	0.0%	63075	0.000	0.001	0.009
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.329	0.0%	42233	0.000	0.008	0.034
data write	00:00:00.774	0.0%	28616	0.000	0.027	0.089
idle	23:59:43.373	100.0%	63075	770.268	1369.534	4175.882
status file	00:00:01.177	0.0%	269	0.000	4.375	246.333
TOTAL: 23:59:48.208	23:59:45.748	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
control@usenet-fr.news.eu.org	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	hasname-out.news.weretis.net	5	25268	8050	16818	400	31%	47:43:11
2	news-out.aioe.org	3	11707	4038	7616	53	34%	24:27:06
3	news.nntp4.net	3	15442	1891	12801	750	12%	24:59:49
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		12	52417	13979	37235	1203	26%	97:10:06

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.news.weretis.net	26.5 MB	880.8 KB	27.3 MB	96%	3.3 KB
2	news-out.aioe.org	11.2 MB	82.4 KB	11.3 MB	99%	2.8 KB
3	news.nntp4.net	6.0 MB	3.7 MB	9.7 MB	61%	3.8 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		43.7 MB	4.6 MB	48.3 MB	90%	3.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 28 15:00:09 - 15:59:59	814	5.9%	0.23	2.3 MB	5.4%	0.67
May 28 16:00:00 - 16:59:59	787	5.7%	0.22	2.3 MB	5.4%	0.66
May 28 17:00:00 - 17:59:59	700	5.1%	0.19	2.8 MB	6.5%	0.79
May 28 18:00:00 - 18:59:59	764	5.5%	0.21	2.1 MB	4.9%	0.60
May 28 19:00:00 - 19:59:59	720	5.2%	0.20	1.9 MB	4.5%	0.55
May 28 20:00:00 - 20:59:59	686	5.0%	0.19	1.7 MB	4.0%	0.50
May 28 21:00:00 - 21:59:59	589	4.3%	0.16	1.8 MB	4.2%	0.52
May 28 22:00:00 - 22:59:59	572	4.2%	0.16	1.9 MB	4.4%	0.53
May 28 23:00:00 - 23:59:59	476	3.5%	0.13	1.6 MB	3.7%	0.46
May 29 00:00:00 - 00:59:59	395	2.9%	0.11	1.8 MB	4.2%	0.52
May 29 01:00:00 - 01:59:59	339	2.5%	0.09	1.6 MB	3.8%	0.46
May 29 02:00:00 - 02:59:59	203	1.5%	0.06	573.7 KB	1.3%	0.16
May 29 03:00:00 - 03:59:59	319	2.3%	0.09	1.4 MB	3.2%	0.39
May 29 04:00:00 - 04:59:59	193	1.4%	0.05	561.4 KB	1.3%	0.16
May 29 05:00:00 - 05:59:59	352	2.6%	0.10	1.1 MB	2.6%	0.32
May 29 06:00:00 - 06:59:59	538	3.9%	0.15	2.0 MB	4.7%	0.57
May 29 07:00:00 - 07:59:59	566	4.1%	0.16	1.8 MB	4.2%	0.52
May 29 08:00:00 - 08:59:59	660	4.8%	0.18	1.9 MB	4.5%	0.55
May 29 09:00:00 - 09:59:59	687	5.0%	0.19	1.8 MB	4.2%	0.52
May 29 10:00:00 - 10:59:59	694	5.0%	0.19	1.8 MB	4.2%	0.51
May 29 11:00:00 - 11:59:59	594	4.3%	0.17	1.5 MB	3.6%	0.44
May 29 12:00:00 - 12:59:59	731	5.3%	0.20	2.7 MB	6.3%	0.78
May 29 13:00:00 - 13:59:59	787	5.7%	0.22	2.3 MB	5.4%	0.66
May 29 14:00:00 - 14:59:59	603	4.4%	0.17	1.5 MB	3.4%	0.42
May 29 15:00:00 - 15:00:12	11	0.1%	0.92	47.2 KB	0.1%	3.93
TOTAL: 24:00:03	13780	100.0%	0.16	43.1 MB	100.0%	0.51

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
FOUR	DEFAULT	1024.0 MB	158.5 MB	15.5%	1	0.21	56.69
ONE	DEFAULT	1024.0 MB	318.1 MB	31.1%	3	0.21	57.00
THREE	DEFAULT	1024.0 MB	162.8 MB	15.9%	1	0.21	57.35
TWO	DEFAULT	1024.0 MB	160.7 MB	15.7%	1	0.21	57.16
TOTAL: 4	-	4.0 GB	800.0 MB	19.5%	6	0.21	-

Sites sending bad articles:

Server		Total	Group	Unapp	Other
1	news.nntp4.net	869	7	1	861
2	hasname-out.news.weretis.net	553	1	0	552
3	news-out.aioe.org	168	0	0	168
TOTAL: 3		1590	8	1	1581

Unwanted newsgroups [Top 20]:

Newsgroup	Count
su.market	2
alt.fan.bert-hubert	1
alt.sex.abstinanca	1
panix.chat.politics	1
0.test	1
alt.sex.buxom	1
fr.comp.os.mac-os.classiques	1
TOTAL: 7	8

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)	587
EMP (md5)	361
EMP (phl)	128
EMP (phn nph)	84
User-issued spam cancel	13
HTML post	6
Too many newsgroups	4
HTML Multipart	2
Binary: misplaced binary	2
Scoring filter	1
TOTAL: 10	1188

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	hasname-out.news.weretis.net	143
	news.nntp4.net	135
	news-out.aioe.org	115
	TOTAL: 3	393
TOTAL: 1		393

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	bbsgslincom	398	398	0	0	0	100%	19:19:26
2	aioe	9763	5	9544	1	5	0%	23:59:58
3	nntp4	12140	1	11981	0	0	0%	23:59:58
4	weretis	5822	0	5670	9	0	0%	23:59:58
TOTAL: 4		28123	404	27195	10	5	1%	91:19:20

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	bbsgslincom	2.0 MB	0.0 KB	2.0 MB	5.1 KB	19:19:26
2	aioe	29.2 KB	2.1 KB	31.3 KB	5.2 KB	23:59:58
3	nntp4	2.4 KB	0.0 KB	2.4 KB	2.4 KB	23:59:58
4	weretis	0.0 KB	42.2 KB	42.2 KB	4.7 KB	23:59:58
TOTAL: 4		2.0 MB	44.3 KB	2.0 MB	5.1 KB	91:19:20

Daily Usenet report

May 28 15:00:09 -- May 29 15:00:12