Daily Usenet report: Sep 1 15:00:03

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Sep 1 15:01:13 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
Sep 1 21:00:02 colo-sc-1 pgpverify[8388]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	16613	79.7%	2.1 MB	77.6%
innd	2407	11.5%	348.4 KB	12.7%
innfeed	1221	5.9%	179.2 KB	6.6%
cnfsstat	576	2.8%	81.5 KB	3.0%
controlchan	12	0.1%	2.5 KB	0.1%
nnrpd	10	0.0%	1.0 KB	0.0%
overchan	2	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 8	20842	100.0%	2.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	39645	42.8%
Negative hits	36814	39.7%
Do not exist	15751	17.0%
Cache misses	416	0.4%
TOTAL: 4	92626	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.200	0.0%	1481	0.000	0.810	51.667
article cleanup	00:00:00.102	0.0%	14826	0.000	0.007	0.043
article logging	00:00:00.478	0.0%	15138	0.000	0.032	0.093
article parse	00:00:00.241	0.0%	25089	0.000	0.010	0.053
article write	00:00:04.707	0.0%	13812	0.000	0.341	21.677
artlog/artcncl	00:00:00.010	0.0%	1477	0.000	0.007	0.167
data move	00:00:00.043	0.0%	98237	0.000	0.000	0.009
hisgrep/artcncl	00:00:00.006	0.0%	436	0.000	0.014	0.125
hishave/artcncl	00:00:00.419	0.0%	1481	0.000	0.283	51.667
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:02.950	0.0%	91868	0.000	0.032	1.657
history sync	00:00:00.018	0.0%	348	0.000	0.052	1.000
history write	00:00:03.152	0.0%	14826	0.000	0.213	6.707
hiswrite/artcncl	00:00:00.010	0.0%	1045	0.010	0.010	0.010
idle	24:00:04.179	99.9%	98706	106.543	875.369	4514.805
nntp read	00:00:00.705	0.0%	98566	0.000	0.007	0.027
overview write	00:00:00.049	0.0%	13812	0.000	0.004	0.032
perl filter	00:00:15.285	0.0%	14826	0.373	1.031	1.674
python filter	00:00:00.433	0.0%	14826	0.000	0.029	0.094
site send	00:00:02.100	0.0%	41437	0.008	0.051	0.094
TOTAL: 24:00:49.933	24:00:36.087	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.027	0.0%	14173	0.000	0.002	0.031
article prepare	00:00:00.000	0.0%	935	0.000	0.000	0.000
article read	00:00:00.023	0.0%	526	0.000	0.044	1.000
backlog stats	00:00:00.059	0.0%	80807	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.340	0.0%	48158	0.000	0.007	0.026
data write	00:00:00.869	0.0%	34725	0.000	0.025	0.096
idle	23:58:19.027	100.0%	80807	128.574	1067.965	4323.194
status file	00:00:00.123	0.0%	234	0.000	0.526	16.500
TOTAL: 23:58:22.785	23:58:20.468	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:00.000	0.0%	0	0.000	0.000	0.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.000	0.0%	2	0.000	0.000	0.000
TOTAL: 00:00:00.098	00:00:00.000	0.0%	-	-	-	-

Control commands to INND:

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

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	hasname-out.news.weretis.net	6	26521	10294	15947	280	38%	48:43:37
2	news-out.aioe.org	3	13771	3461	10308	2	25%	24:09:50
3	news.nntp4.net	2	16275	224	15283	768	1%	26:50:41
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		12	56567	13979	41538	1050	24%	99:44:08

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.news.weretis.net	30.3 MB	530.5 KB	30.8 MB	98%	3.0 KB
2	news-out.aioe.org	8.5 MB	3.6 KB	8.5 MB	99%	2.5 KB
3	news.nntp4.net	876.5 KB	2.1 MB	2.9 MB	29%	3.0 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		39.6 MB	2.6 MB	42.2 MB	93%	2.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 1 15:00:03 - 15:59:59	712	5.2%	0.20	2.0 MB	5.1%	0.57
Sep 1 16:00:00 - 16:59:59	785	5.7%	0.22	2.6 MB	6.6%	0.73
Sep 1 17:00:00 - 17:59:59	662	4.8%	0.18	1.7 MB	4.4%	0.48
Sep 1 18:00:00 - 18:59:59	600	4.3%	0.17	1.6 MB	4.0%	0.44
Sep 1 19:00:00 - 19:59:59	596	4.3%	0.17	1.6 MB	4.0%	0.44
Sep 1 20:00:00 - 20:59:59	580	4.2%	0.16	1.7 MB	4.3%	0.48
Sep 1 21:00:00 - 21:59:59	476	3.4%	0.13	1.4 MB	3.6%	0.40
Sep 1 22:00:00 - 22:59:59	457	3.3%	0.13	1.7 MB	4.3%	0.48
Sep 1 23:00:00 - 23:59:59	366	2.7%	0.10	1.5 MB	3.9%	0.43
Sep 2 00:00:00 - 00:59:59	231	1.7%	0.06	963.0 KB	2.4%	0.27
Sep 2 01:00:00 - 01:59:59	260	1.9%	0.07	900.7 KB	2.3%	0.25
Sep 2 02:00:00 - 02:59:59	174	1.3%	0.05	620.9 KB	1.6%	0.17
Sep 2 03:00:00 - 03:59:59	203	1.5%	0.06	639.5 KB	1.6%	0.18
Sep 2 04:00:00 - 04:59:59	545	3.9%	0.15	2.4 MB	6.1%	0.68
Sep 2 05:00:00 - 05:59:59	290	2.1%	0.08	850.7 KB	2.1%	0.24
Sep 2 06:00:00 - 06:59:59	1520	11.0%	0.42	2.5 MB	6.5%	0.72
Sep 2 07:00:00 - 07:59:59	555	4.0%	0.15	1.6 MB	4.2%	0.47
Sep 2 08:00:00 - 08:59:59	621	4.5%	0.17	1.6 MB	4.1%	0.45
Sep 2 09:00:00 - 09:59:59	626	4.5%	0.17	1.6 MB	4.1%	0.46
Sep 2 10:00:00 - 10:59:59	689	5.0%	0.19	1.7 MB	4.4%	0.49
Sep 2 11:00:00 - 11:59:59	691	5.0%	0.19	1.8 MB	4.6%	0.51
Sep 2 12:00:00 - 12:59:59	618	4.5%	0.17	1.7 MB	4.4%	0.49
Sep 2 13:00:00 - 13:59:59	665	4.8%	0.18	2.0 MB	5.0%	0.56
Sep 2 14:00:00 - 14:59:59	887	6.4%	0.25	2.5 MB	6.5%	0.72
Sep 2 15:00:00 - 15:00:02	2	0.0%	1.00	9.1 KB	0.0%	4.54
TOTAL: 23:59:59	13811	100.0%	0.16	39.0 MB	100.0%	0.46

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
FOUR	DEFAULT	1024.0 MB	528.9 MB	51.7%	8	0.20	59.26
ONE	DEFAULT	1024.0 MB	707.5 MB	69.1%	10	0.20	59.85
THREE	DEFAULT	1024.0 MB	541.8 MB	52.9%	8	0.20	60.46
TWO	DEFAULT	1024.0 MB	536.9 MB	52.4%	8	0.20	60.75
TOTAL: 4	-	4.0 GB	2.3 GB	56.5%	34	0.20	-

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	735	5	730
2	hasname-out.news.weretis.net	561	0	561
3	news-out.aioe.org	29	0	29
TOTAL: 3		1325	5	1320

Unwanted newsgroups [Top 20]:

Newsgroup	Count
list.postfix.users	1
alt.math	1
alt.personals.where-are-you-now	1
alt.culture	1
alt.windows	1
TOTAL: 5	5

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	423
EMP (phn path)	337
EMP (phl)	149
EMP (phn nph)	87
Scoring filter	12
HTML Multipart	1
TOTAL: 6	1009

INND no permission servers:

System	Conn
152.89.196.25	1
TOTAL: 1	1

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	hasname-out.news.weretis.net	282
	news-out.aioe.org	27
	news.nntp4.net	2
	TOTAL: 3	311
TOTAL: 1		311

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	bbsgslincom	404	404	0	0	0	100%	20:26:40
2	aioe	13370	75	10142	4	27	0%	23:59:52
3	nntp4	17083	37	13637	8	357	0%	24:04:13
4	weretis	3768	0	3581	11	0	0%	24:09:49
TOTAL: 4		34625	516	27360	23	384	1%	92:40:34

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	bbsgslincom	1.4 MB	0.0 KB	1.4 MB	3.6 KB	20:26:40
2	aioe	220.0 KB	10.9 KB	231.0 KB	2.9 KB	23:59:52
3	nntp4	135.2 KB	63.4 KB	198.6 KB	4.4 KB	24:04:13
4	weretis	0.0 KB	46.3 KB	46.3 KB	4.2 KB	24:09:49
TOTAL: 4		1.8 MB	120.6 KB	1.9 MB	3.6 KB	92:40:34

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
192.241.209.58	0.001	0.001	00:00:00
192.241.195.58	0.001	0.002	00:00:00
TOTAL: 2	0.002	0.003	00:00:00

NNRP no permission clients:

System	Conn
192.241.195.58	1
192.241.209.58	1
TOTAL: 2	2

Daily Usenet report

Sep 1 15:00:03 -- Sep 2 15:00:02