Daily Usenet report: Apr 20 15:00:04

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Apr 20 15:01:02 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
Apr 20 18:30:02 colo-sc-1 pgpverify[25805]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	13759	75.1%	1.7 MB	72.6%
innd	2698	14.7%	375.6 KB	16.1%
innfeed	1233	6.7%	171.3 KB	7.3%
cnfsstat	576	3.1%	88.0 KB	3.8%
nnrpd	55	0.3%	5.5 KB	0.2%
controlchan	2	0.0%	0.2 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
overchan	1	0.0%	0.1 KB	0.0%
TOTAL: 8	18325	100.0%	2.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	35820	42.3%
Negative hits	35330	41.7%
Do not exist	13490	15.9%
Cache misses	34	0.0%
TOTAL: 4	84674	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.535	0.0%	62	0.000	57.016	820.000
article cleanup	00:00:00.067	0.0%	13547	0.000	0.005	0.031
article logging	00:00:00.453	0.0%	13712	0.000	0.033	0.114
article parse	00:00:00.233	0.0%	23806	0.000	0.010	0.045
article write	00:00:03.548	0.0%	12343	0.000	0.287	25.101
artlog/artcncl	00:00:00.001	0.0%	62	0.000	0.016	0.100
data move	00:00:00.059	0.0%	92816	0.000	0.001	0.004
hisgrep/artcncl	00:00:00.000	0.0%	62	0.000	0.000	0.000
hishave/artcncl	00:00:00.164	0.0%	62	0.000	2.645	157.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:34.187	0.0%	84957	0.003	0.402	19.522
history sync	00:00:00.015	0.0%	374	0.000	0.040	0.500
history write	00:00:01.544	0.0%	13547	0.000	0.114	7.333
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:59:33.114	99.9%	93376	576.723	925.003	2857.519
nntp read	00:00:00.701	0.0%	93317	0.000	0.008	0.016
overview write	00:00:00.048	0.0%	12343	0.000	0.004	0.029
perl filter	00:00:17.475	0.0%	13547	0.861	1.290	9.176
python filter	00:00:00.426	0.0%	13547	0.000	0.031	0.109
site send	00:00:02.405	0.0%	37030	0.026	0.065	0.109
TOTAL: 24:00:52.235	24:00:37.975	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.025	0.0%	12324	0.000	0.002	0.021
article prepare	00:00:00.000	0.0%	633	0.000	0.000	0.000
article read	00:00:00.017	0.0%	439	0.000	0.039	1.000
backlog stats	00:00:00.043	0.0%	70599	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.357	0.0%	42492	0.000	0.008	0.029
data write	00:00:00.815	0.0%	30308	0.000	0.027	0.073
idle	23:58:52.913	100.0%	70599	715.156	1222.863	3039.628
status file	00:00:00.073	0.0%	248	0.000	0.294	1.000
TOTAL: 23:59:00.298	23:58:54.243	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%	11	0.000	0.000	0.000
TOTAL: 00:00:00.419	00:00:00.000	0.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
moderator@dana.de	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	9	23691	8158	15098	435	34%	48:22:11
2	news-out.aioe.org	2	12168	4092	8063	13	33%	24:03:35
3	news.nntp4.net	2	11737	118	10838	781	1%	21:12:25
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		14	47596	12368	33999	1229	25%	93:38:11

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.news.weretis.net	26.0 MB	736.4 KB	26.7 MB	97%	3.2 KB
2	news-out.aioe.org	9.8 MB	19.6 KB	9.8 MB	99%	2.5 KB
3	news.nntp4.net	580.5 KB	2.1 MB	2.6 MB	21%	3.0 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		36.4 MB	2.8 MB	39.2 MB	92%	3.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 20 15:00:04 - 15:59:59	721	5.8%	0.20	2.1 MB	5.8%	0.60
Apr 20 16:00:00 - 16:59:59	731	5.9%	0.20	2.0 MB	5.6%	0.58
Apr 20 17:00:00 - 17:59:59	682	5.5%	0.19	1.9 MB	5.3%	0.54
Apr 20 18:00:00 - 18:59:59	732	5.9%	0.20	2.5 MB	6.9%	0.71
Apr 20 19:00:00 - 19:59:59	649	5.3%	0.18	2.0 MB	5.4%	0.56
Apr 20 20:00:00 - 20:59:59	570	4.6%	0.16	1.7 MB	4.6%	0.48
Apr 20 21:00:00 - 21:59:59	542	4.4%	0.15	1.7 MB	4.8%	0.49
Apr 20 22:00:00 - 22:59:59	449	3.6%	0.12	1.7 MB	4.7%	0.48
Apr 20 23:00:00 - 23:59:59	328	2.7%	0.09	1.1 MB	2.9%	0.30
Apr 21 00:00:00 - 00:59:59	328	2.7%	0.09	989.9 KB	2.7%	0.27
Apr 21 01:00:00 - 01:59:59	285	2.3%	0.08	861.1 KB	2.3%	0.24
Apr 21 02:00:00 - 02:59:59	278	2.3%	0.08	1.0 MB	2.9%	0.30
Apr 21 03:00:00 - 03:59:59	239	1.9%	0.07	881.4 KB	2.4%	0.24
Apr 21 04:00:00 - 04:59:59	253	2.1%	0.07	709.0 KB	1.9%	0.20
Apr 21 05:00:00 - 05:59:59	329	2.7%	0.09	1010.1 KB	2.7%	0.28
Apr 21 06:00:00 - 06:59:59	390	3.2%	0.11	889.2 KB	2.4%	0.25
Apr 21 07:00:00 - 07:59:59	568	4.6%	0.16	1.5 MB	4.1%	0.42
Apr 21 08:00:00 - 08:59:59	607	4.9%	0.17	1.7 MB	4.7%	0.48
Apr 21 09:00:00 - 09:59:59	574	4.7%	0.16	1.5 MB	4.2%	0.44
Apr 21 10:00:00 - 10:59:59	602	4.9%	0.17	1.7 MB	4.7%	0.48
Apr 21 11:00:00 - 11:59:59	604	4.9%	0.17	1.7 MB	4.8%	0.49
Apr 21 12:00:00 - 12:59:59	672	5.4%	0.19	1.7 MB	4.8%	0.49
Apr 21 13:00:00 - 13:59:59	627	5.1%	0.17	1.9 MB	5.1%	0.53
Apr 21 14:00:00 - 14:59:59	568	4.6%	0.16	1.6 MB	4.4%	0.45
Apr 21 15:00:00 - 15:00:02	3	0.0%	1.50	5.9 KB	0.0%	2.95
TOTAL: 23:59:58	12331	100.0%	0.14	36.3 MB	100.0%	0.43

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
FOUR	DEFAULT	1024.0 MB	609.1 MB	59.5%	6	0.18	66.90
ONE	DEFAULT	1024.0 MB	781.7 MB	76.3%	8	0.18	66.29
THREE	DEFAULT	1024.0 MB	618.0 MB	60.3%	6	0.19	65.52
TWO	DEFAULT	1024.0 MB	609.6 MB	59.5%	6	0.18	66.08
TOTAL: 4	-	4.0 GB	2.6 GB	63.9%	26	0.18	-

Sites sending bad articles:

Server		Total	Group	Unapp	Other
1	news.nntp4.net	782	9	1	772
2	hasname-out.news.weretis.net	542	2	0	540
3	news-out.aioe.org	43	1	0	42
TOTAL: 3		1367	12	1	1354

Unwanted newsgroups [Top 20]:

Newsgroup	Count
panix.chat.politics	7
comp.infosystems.gemini	2
alt.sys.alpha-micro	1
clari.web.local.missouri	1
alt.music.home-recording	1
TOTAL: 5	12

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)	635
EMP (md5)	380
EMP (phn nph)	110
EMP (phl)	48
Scoring filter	6
User-issued spam cancel	5
HTML Multipart	3
Binary: misplaced binary	1
Too many newsgroups	1
TOTAL: 9	1189

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	hasname-out.news.weretis.net	134
	news-out.aioe.org	31
	TOTAL: 2	165
TOTAL: 1		165

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	%Took	Elapsed
1	bbsgslincom	377	377	0	0	100%	19:25:33
2	aioe	10493	41	8091	2	0%	23:58:56
3	nntp4	14597	1	12194	1	0%	23:59:50
4	weretis	4340	0	4152	19	0%	23:59:19
TOTAL: 4		29807	419	24437	22	1%	91:23:38

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	bbsgslincom	1.2 MB	0.0 KB	1.2 MB	3.4 KB	19:25:33
2	aioe	185.9 KB	7.3 KB	193.2 KB	4.5 KB	23:58:56
3	nntp4	2.0 KB	12.6 KB	14.6 KB	7.3 KB	23:59:50
4	weretis	0.0 KB	89.1 KB	89.1 KB	4.7 KB	23:59:19
TOTAL: 4		1.4 MB	109.0 KB	1.5 MB	3.5 KB	91:23:38

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
221.154.57.175	0.001	0.004	00:00:00
134.209.159.153	0.004	0.015	00:00:00
79.124.62.106	0.000	0.002	00:00:00
TOTAL: 3	0.005	0.021	00:00:00

NNRP no permission clients:

System	Conn
134.209.159.153	8
221.154.57.175	2
79.124.62.106	1
TOTAL: 3	11

Daily Usenet report

Apr 20 15:00:04 -- Apr 21 15:00:02