Daily Usenet report: Jun 9 15:00:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Jun 9 15:01:20 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
Jun 10 07:30:45 colo-sc-1 innd: message repeated 2 times: [ 45.227.254.49 rejected 502]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	13345	75.6%	1.6 MB	72.9%
innd	2469	14.0%	355.3 KB	15.7%
innfeed	1250	7.1%	176.1 KB	7.8%
cnfsstat	576	3.3%	81.8 KB	3.6%
nnrpd	9	0.1%	1.1 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 7	17651	100.0%	2.2 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	32160	41.9%
Positive hits	31631	41.2%
Do not exist	12956	16.9%
Cache misses	21	0.0%
TOTAL: 4	76768	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.053	0.0%	60	0.000	0.883	5.000
article cleanup	00:00:00.102	0.0%	13080	0.000	0.008	0.053
article logging	00:00:00.451	0.0%	13331	0.000	0.034	0.132
article parse	00:00:00.250	0.0%	27487	0.000	0.009	0.050
article write	00:00:00.603	0.0%	12016	0.000	0.050	0.136
artlog/artcncl	00:00:00.000	0.0%	60	0.000	0.000	0.000
data move	00:00:00.044	0.0%	89373	0.000	0.000	0.006
hisgrep/artcncl	00:00:00.000	0.0%	60	0.000	0.000	0.000
hishave/artcncl	00:00:00.015	0.0%	60	0.000	0.250	2.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:27.512	0.1%	77490	0.002	1.129	15.895
history sync	00:00:00.014	0.0%	358	0.000	0.039	0.667
history write	00:00:00.759	0.0%	13080	0.000	0.058	1.952
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:58:52.790	99.9%	89827	469.598	961.101	4028.859
nntp read	00:00:00.727	0.0%	89974	0.000	0.008	0.020
overview write	00:00:00.030	0.0%	12016	0.000	0.002	0.047
perl filter	00:00:15.506	0.0%	13080	0.864	1.185	5.534
python filter	00:00:00.391	0.0%	13080	0.000	0.030	0.129
site send	00:00:02.072	0.0%	36048	0.023	0.057	0.101
TOTAL: 24:00:53.219	24:00:41.319	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.020	0.0%	11970	0.000	0.002	0.027
article prepare	00:00:00.000	0.0%	614	0.000	0.000	0.000
article read	00:00:00.014	0.0%	417	0.000	0.034	1.000
backlog stats	00:00:00.039	0.0%	64948	0.000	0.001	0.011
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.271	0.0%	38042	0.000	0.007	0.024
data write	00:00:00.750	0.0%	26282	0.000	0.029	0.073
idle	23:57:48.175	100.0%	64948	690.511	1328.265	3443.537
status file	00:00:00.597	0.0%	253	0.000	2.360	99.250
TOTAL: 23:58:00.876	23:57:49.866	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.001	1.0%	2	0.000	0.500	1.000
TOTAL: 00:00:00.096	00:00:00.001	1.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	hasname-out.news.weretis.net	6	22487	7675	14497	315	34%	47:48:30
2	news-out.aioe.org	3	12239	4146	8091	2	33%	24:40:08
3	news.nntp4.net	1	10649	139	9713	797	1%	21:26:36
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		11	45375	11960	32301	1114	26%	93:55:14

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.news.weretis.net	25.3 MB	799.2 KB	26.1 MB	97%	3.3 KB
2	news-out.aioe.org	10.4 MB	8.1 KB	10.4 MB	99%	2.6 KB
3	news.nntp4.net	810.7 KB	7.7 MB	8.4 MB	9%	9.2 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		36.6 MB	8.4 MB	45.0 MB	81%	3.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 9 15:00:01 - 15:59:59	727	6.1%	0.20	2.3 MB	6.2%	0.64
Jun 9 16:00:00 - 16:59:59	648	5.4%	0.18	1.8 MB	4.8%	0.50
Jun 9 17:00:00 - 17:59:59	522	4.4%	0.14	1.4 MB	3.8%	0.40
Jun 9 18:00:00 - 18:59:59	592	4.9%	0.16	2.0 MB	5.4%	0.56
Jun 9 19:00:00 - 19:59:59	564	4.7%	0.16	1.5 MB	4.1%	0.42
Jun 9 20:00:00 - 20:59:59	490	4.1%	0.14	1.4 MB	3.8%	0.40
Jun 9 21:00:00 - 21:59:59	580	4.8%	0.16	2.0 MB	5.3%	0.56
Jun 9 22:00:00 - 22:59:59	493	4.1%	0.14	2.4 MB	6.5%	0.68
Jun 9 23:00:00 - 23:59:59	302	2.5%	0.08	1.1 MB	3.1%	0.32
Jun 10 00:00:00 - 00:59:59	296	2.5%	0.08	1.3 MB	3.4%	0.36
Jun 10 01:00:00 - 01:59:59	223	1.9%	0.06	759.7 KB	2.0%	0.21
Jun 10 02:00:00 - 02:59:59	238	2.0%	0.07	844.3 KB	2.2%	0.23
Jun 10 03:00:00 - 03:59:59	304	2.5%	0.08	1.0 MB	2.8%	0.29
Jun 10 04:00:00 - 04:59:59	294	2.5%	0.08	899.4 KB	2.4%	0.25
Jun 10 05:00:00 - 05:59:59	333	2.8%	0.09	861.1 KB	2.3%	0.24
Jun 10 06:00:00 - 06:59:59	387	3.2%	0.11	1.1 MB	2.9%	0.31
Jun 10 07:00:00 - 07:59:59	524	4.4%	0.15	1.8 MB	4.8%	0.50
Jun 10 08:00:00 - 08:59:59	582	4.9%	0.16	1.5 MB	4.1%	0.43
Jun 10 09:00:00 - 09:59:59	605	5.1%	0.17	1.6 MB	4.4%	0.46
Jun 10 10:00:00 - 10:59:59	563	4.7%	0.16	1.5 MB	4.0%	0.42
Jun 10 11:00:00 - 11:59:59	589	4.9%	0.16	1.6 MB	4.3%	0.45
Jun 10 12:00:00 - 12:59:59	623	5.2%	0.17	1.7 MB	4.5%	0.47
Jun 10 13:00:00 - 13:59:59	711	5.9%	0.20	2.2 MB	6.1%	0.64
Jun 10 14:00:00 - 14:59:59	787	6.6%	0.22	2.6 MB	7.1%	0.74
Jun 10 15:00:00 - 15:00:02	1	0.0%	0.50	1.5 KB	0.0%	0.75
TOTAL: 24:00:01	11978	100.0%	0.14	36.8 MB	100.0%	0.44

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
FOUR	DEFAULT	1024.0 MB	325.2 MB	31.8%	7	0.18	65.91
ONE	DEFAULT	1024.0 MB	501.0 MB	48.9%	9	0.18	67.63
THREE	DEFAULT	1024.0 MB	336.9 MB	32.9%	7	0.18	65.66
TWO	DEFAULT	1024.0 MB	329.1 MB	32.1%	7	0.19	65.06
TOTAL: 4	-	4.0 GB	1.5 GB	36.4%	30	0.18	-

Sites sending bad articles:

Server		Total	Group	Unapp	Other
1	news.nntp4.net	738	7	1	730
2	hasname-out.news.weretis.net	532	5	0	527
3	news-out.aioe.org	39	0	0	39
TOTAL: 3		1309	12	1	1296

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.fetish	2
es.test	2
comp.infosystems.gemini	2
alt.ads.forsale	1
fr.misc.domotique	1
mailing.postfix.users	1
clari.local.texas.briefs	1
alt.os.nachos	1
alt.fetish.feet	1
TOTAL: 9	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)	483
EMP (md5)	414
EMP (phn nph)	77
EMP (phl)	55
HTML Multipart	8
Scoring filter	4
HTML post	1
Binary: misplaced binary	1
U2 violation - invalid distribution	1
TOTAL: 9	1044

INND no permission servers:

System	Conn
45.227.254.49	1
TOTAL: 1	1

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	hasname-out.news.weretis.net	215
	news-out.aioe.org	37
	TOTAL: 2	252
TOTAL: 1		252

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	bbsgslincom	354	354	0	0	0	100%	18:55:55
2	aioe	10127	54	7650	7	10	0%	23:59:48
3	nntp4	11118	1	9043	0	2824	0%	23:59:49
4	weretis	4253	0	4098	3	0	0%	23:59:43
TOTAL: 4		25852	409	20791	10	2834	1%	90:55:15

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	bbsgslincom	2.6 MB	0.0 KB	2.6 MB	7.6 KB	18:55:55
2	aioe	183.7 KB	16.4 KB	200.1 KB	3.3 KB	23:59:48
3	nntp4	2.0 KB	0.0 KB	2.0 KB	2.0 KB	23:59:49
4	weretis	0.0 KB	15.1 KB	15.1 KB	5.0 KB	23:59:43
TOTAL: 4		2.8 MB	31.5 KB	2.8 MB	6.9 KB	90:55:15

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
192.241.213.188	0.001	0.002	00:00:00
scanner-27.ch1.censys-scanner.com	0.003	0.000	00:00:00
TOTAL: 2	0.004	0.002	00:00:00

NNRP no permission clients:

System	Conn
192.241.213.188	1
scanner-27.ch1.censys-scanner.com	1
TOTAL: 2	2

Daily Usenet report

Jun 9 15:00:01 -- Jun 10 15:00:02