Daily Usenet report: Aug 31 15:00:02

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Aug 31 15:01:17 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
Sep 1 00:04:02 colo-sc-1 pgpverify[13907]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	13446	76.6%	1.6 MB	73.8%
innd	2269	12.9%	330.4 KB	14.6%
innfeed	1198	6.8%	170.5 KB	7.5%
cnfsstat	576	3.3%	83.4 KB	3.7%
nnrpd	59	0.3%	6.0 KB	0.3%
controlchan	12	0.1%	2.7 KB	0.1%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 7	17561	100.0%	2.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	35152	43.7%
Negative hits	32354	40.2%
Do not exist	12977	16.1%
Cache misses	35	0.0%
TOTAL: 4	80518	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.678	0.0%	83	0.000	32.265	161.556
article cleanup	00:00:00.077	0.0%	13088	0.000	0.006	0.033
article logging	00:00:00.403	0.0%	13367	0.000	0.030	0.132
article parse	00:00:00.203	0.0%	22232	0.000	0.009	0.029
article write	00:00:02.704	0.0%	12247	0.000	0.221	5.679
artlog/artcncl	00:00:00.001	0.0%	83	0.000	0.012	0.111
data move	00:00:00.032	0.0%	89019	0.000	0.000	0.008
hisgrep/artcncl	00:00:00.000	0.0%	79	0.000	0.000	0.000
hishave/artcncl	00:00:00.001	0.0%	83	0.000	0.012	0.167
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:12.475	0.0%	81152	0.000	0.154	12.476
history sync	00:00:00.011	0.0%	359	0.000	0.031	0.667
history write	00:00:03.141	0.0%	13088	0.000	0.240	9.059
hiswrite/artcncl	00:00:00.000	0.0%	4	0.000	0.000	0.000
idle	23:59:52.227	99.9%	89133	578.083	969.251	2844.521
nntp read	00:00:00.622	0.0%	88995	0.000	0.007	0.019
overview write	00:00:00.041	0.0%	12247	0.000	0.003	0.041
perl filter	00:00:14.585	0.0%	13088	0.871	1.114	3.600
python filter	00:00:00.344	0.0%	13088	0.000	0.026	0.098
site send	00:00:01.997	0.0%	36742	0.008	0.054	0.088
TOTAL: 24:00:48.379	24:00:31.542	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.028	0.0%	12249	0.000	0.002	0.029
article prepare	00:00:00.000	0.0%	961	0.000	0.000	0.000
article read	00:00:00.020	0.0%	507	0.000	0.039	0.500
backlog stats	00:00:00.032	0.0%	76593	0.000	0.000	0.004
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.294	0.0%	44289	0.000	0.007	0.027
data write	00:00:00.827	0.0%	32100	0.000	0.026	0.072
idle	23:59:29.428	100.0%	76593	690.883	1127.641	2580.325
status file	00:00:01.012	0.0%	237	0.000	4.270	498.000
TOTAL: 23:59:33.469	23:59:31.641	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%	12	0.000	0.000	0.000
TOTAL: 00:00:03.805	00:00:00.000	0.0%	-	-	-	-

Control commands to INND:

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

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	4	22889	8710	13928	251	38%	47:21:04
2	news-out.aioe.org	3	11998	3173	8824	1	26%	23:47:52
3	news.nntp4.net	1	12318	104	11620	594	0%	22:47:24
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		9	47205	11987	34372	846	25%	93:56:20

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.news.weretis.net	27.2 MB	686.1 KB	27.8 MB	97%	3.2 KB
2	news-out.aioe.org	7.7 MB	1.2 KB	7.7 MB	99%	2.5 KB
3	news.nntp4.net	606.1 KB	1.5 MB	2.1 MB	28%	3.0 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		35.5 MB	2.2 MB	37.6 MB	94%	3.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 31 15:00:02 - 15:59:59	611	5.0%	0.17	1.7 MB	4.8%	0.49
Aug 31 16:00:00 - 16:59:59	645	5.3%	0.18	2.1 MB	5.7%	0.59
Aug 31 17:00:00 - 17:59:59	637	5.2%	0.18	1.6 MB	4.5%	0.47
Aug 31 18:00:00 - 18:59:59	668	5.5%	0.19	1.8 MB	4.9%	0.50
Aug 31 19:00:00 - 19:59:59	579	4.7%	0.16	1.6 MB	4.4%	0.45
Aug 31 20:00:00 - 20:59:59	578	4.7%	0.16	1.5 MB	4.1%	0.42
Aug 31 21:00:00 - 21:59:59	541	4.4%	0.15	1.6 MB	4.3%	0.45
Aug 31 22:00:00 - 22:59:59	436	3.6%	0.12	1.5 MB	4.1%	0.42
Aug 31 23:00:00 - 23:59:59	378	3.1%	0.10	1.1 MB	2.9%	0.30
Sep 1 00:00:00 - 00:59:59	274	2.2%	0.08	1.1 MB	2.9%	0.30
Sep 1 01:00:00 - 01:59:59	277	2.3%	0.08	898.3 KB	2.4%	0.25
Sep 1 02:00:00 - 02:59:59	231	1.9%	0.06	747.4 KB	2.0%	0.21
Sep 1 03:00:00 - 03:59:59	229	1.9%	0.06	708.1 KB	1.9%	0.20
Sep 1 04:00:00 - 04:59:59	265	2.2%	0.07	933.2 KB	2.5%	0.26
Sep 1 05:00:00 - 05:59:59	310	2.5%	0.09	895.6 KB	2.4%	0.25
Sep 1 06:00:00 - 06:59:59	502	4.1%	0.14	2.1 MB	5.8%	0.60
Sep 1 07:00:00 - 07:59:59	669	5.5%	0.19	2.3 MB	6.4%	0.67
Sep 1 08:00:00 - 08:59:59	654	5.3%	0.18	1.8 MB	4.9%	0.50
Sep 1 09:00:00 - 09:59:59	617	5.0%	0.17	1.6 MB	4.5%	0.46
Sep 1 10:00:00 - 10:59:59	619	5.1%	0.17	1.8 MB	4.9%	0.51
Sep 1 11:00:00 - 11:59:59	545	4.4%	0.15	1.4 MB	3.9%	0.41
Sep 1 12:00:00 - 12:59:59	553	4.5%	0.15	1.6 MB	4.3%	0.45
Sep 1 13:00:00 - 13:59:59	720	5.9%	0.20	1.9 MB	5.2%	0.54
Sep 1 14:00:00 - 15:00:02	710	5.8%	0.20	2.3 MB	6.3%	0.65
TOTAL: 24:00:00	12248	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	511.6 MB	50.0%	8	0.18	66.21
ONE	DEFAULT	1024.0 MB	690.4 MB	67.4%	10	0.18	65.95
THREE	DEFAULT	1024.0 MB	524.8 MB	51.3%	8	0.18	66.60
TWO	DEFAULT	1024.0 MB	520.0 MB	50.8%	8	0.18	66.16
TOTAL: 4	-	4.0 GB	2.2 GB	54.9%	34	0.18	-

Sites sending bad articles:

Server		Total	Group	Unapp	Other
1	news.nntp4.net	590	4	1	585
2	hasname-out.news.weretis.net	491	1	0	490
3	news-out.aioe.org	34	0	0	34
TOTAL: 3		1115	5	1	1109

Unwanted newsgroups [Top 20]:

Newsgroup	Count
braunschweig.test	1
alt.fan.robert	1
alt.culture	1
nyx.test	1
it.tlc.telefonia.isdn	1
TOTAL: 5	5

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 (md5)	312
EMP (phn path)	249
EMP (phl)	203
EMP (phn nph)	58
HTML Multipart	6
Scoring filter	4
Too many newsgroups	1
TOTAL: 7	833

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	hasname-out.news.weretis.net	240
	news-out.aioe.org	33
	news.nntp4.net	3
	TOTAL: 3	276
TOTAL: 1		276

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	bbsgslincom	354	354	0	0	0	100%	20:02:51
2	aioe	12016	129	8573	3	1	1%	23:59:41
3	nntp4	15545	7	12039	1	0	0%	23:55:34
4	weretis	3510	1	3280	9	4	0%	23:50:00
TOTAL: 4		31425	491	23892	13	5	1%	91:48:06

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	bbsgslincom	1.5 MB	0.0 KB	1.5 MB	4.2 KB	20:02:51
2	aioe	415.3 KB	5.1 KB	420.4 KB	3.2 KB	23:59:41
3	nntp4	20.1 KB	1.8 KB	21.8 KB	2.7 KB	23:55:34
4	weretis	6.8 KB	27.4 KB	34.3 KB	3.4 KB	23:50:00
TOTAL: 4		1.9 MB	34.3 KB	1.9 MB	3.9 KB	91:48:06

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
27.124.32.176	0.000	0.005	00:00:03
167.71.167.22	0.007	0.011	00:00:00
192.241.209.108	0.002	0.000	00:00:00
scanner-25.ch1.censys-scanner.com	0.001	0.002	00:00:00
TOTAL: 4	0.010	0.018	00:00:03

NNRP no permission clients:

System	Conn
167.71.167.22	8
27.124.32.176	2
scanner-25.ch1.censys-scanner.com	1
192.241.209.108	1
TOTAL: 4	12

Daily Usenet report

Aug 31 15:00:02 -- Sep 1 15:00:02