Daily Usenet report: Apr 29 15:00:02

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Apr 29 15:01:02 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
Apr 29 20:00:03 colo-sc-1 pgpverify[24619]: pgpverify: verification failed
Apr 29 20:00:04 colo-sc-1 pgpverify[24632]: pgpverify: verification failed
Apr 29 20:01:05 colo-sc-1 pgpverify[24748]: pgpverify: verification failed
Apr 29 20:01:06 colo-sc-1 pgpverify[24758]: pgpverify: verification failed
Apr 30 07:00:02 colo-sc-1 pgpverify[11949]: pgpverify: verification failed
Apr 30 07:00:02 colo-sc-1 pgpverify[11957]: pgpverify: verification failed
Apr 30 07:00:07 colo-sc-1 pgpverify[11975]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	12698	72.5%	1.5 MB	69.8%
innd	2968	17.0%	411.4 KB	18.3%
innfeed	1240	7.1%	176.3 KB	7.9%
cnfsstat	576	3.3%	87.1 KB	3.9%
controlchan	8	0.0%	0.6 KB	0.0%
nnrpd	8	0.0%	0.9 KB	0.0%
pgpverify	7	0.0%	1.2 KB	0.1%
overchan	2	0.0%	0.1 KB	0.0%
TOTAL: 8	17507	100.0%	2.2 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	32902	42.7%
Positive hits	31807	41.3%
Do not exist	12325	16.0%
Cache misses	21	0.0%
TOTAL: 4	77055	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.089	0.0%	55	0.000	1.618	7.000
article cleanup	00:00:00.078	0.0%	12429	0.000	0.006	0.071
article logging	00:00:00.743	0.0%	12667	0.000	0.059	2.871
article parse	00:00:00.204	0.0%	21406	0.000	0.010	0.064
article write	00:00:00.727	0.0%	10919	0.000	0.067	1.477
artlog/artcncl	00:00:00.000	0.0%	54	0.000	0.000	0.000
data move	00:00:00.059	0.0%	84644	0.000	0.001	0.008
hisgrep/artcncl	00:00:00.002	0.0%	54	0.000	0.037	1.000
hishave/artcncl	00:00:00.009	0.0%	55	0.000	0.164	3.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:08.141	0.1%	77710	0.001	1.649	58.138
history sync	00:00:00.013	0.0%	363	0.000	0.036	1.000
history write	00:00:03.933	0.0%	12426	0.000	0.317	13.171
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	23:58:07.265	99.8%	84925	485.311	1016.041	6453.097
nntp read	00:00:00.594	0.0%	85215	0.000	0.007	0.022
overview write	00:00:00.040	0.0%	10919	0.000	0.004	0.042
perl filter	00:00:16.182	0.0%	12426	0.853	1.302	10.619
python filter	00:00:00.400	0.0%	12426	0.000	0.032	0.107
site send	00:00:02.038	0.0%	32764	0.000	0.062	0.133
TOTAL: 24:00:51.566	24:00:40.517	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.027	0.0%	10899	0.000	0.002	0.036
article prepare	00:00:00.001	0.0%	556	0.000	0.002	0.091
article read	00:00:00.020	0.0%	392	0.000	0.051	1.000
backlog stats	00:00:00.035	0.0%	62836	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.304	0.0%	37703	0.000	0.008	0.029
data write	00:00:00.704	0.0%	26893	0.000	0.026	0.120
idle	23:59:34.362	100.0%	62836	704.314	1374.600	4850.575
status file	00:00:00.104	0.0%	237	0.000	0.439	17.000
TOTAL: 23:59:41.875	23:59:35.557	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.055	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
group-admin@isc.org	0	0	0	4	0	0
moderator@dana.de	0	0	0	3	0	0
TOTAL	0	0	0	7	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	hasname-out.news.weretis.net	10	21686	6620	14532	534	30%	49:02:25
2	news-out.aioe.org	2	11105	4351	6674	80	39%	24:18:59
3	news.nntp4.net	2	11966	126	11030	810	1%	23:25:44
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		15	44757	11097	32236	1424	24%	96:47:08

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.news.weretis.net	20.2 MB	1.3 KB	858.7 KB	21.0 MB	95%	3.0 KB
2	news-out.aioe.org	9.8 MB	1.3 KB	127.1 KB	9.9 MB	98%	2.3 KB
3	news.nntp4.net	352.7 KB	1.3 KB	3.4 MB	3.7 MB	9%	4.1 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		30.3 MB	3.9 KB	4.4 MB	34.6 MB	87%	2.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 29 15:00:02 - 15:59:59	679	6.2%	0.19	1.8 MB	6.2%	0.52
Apr 29 16:00:00 - 16:59:59	770	7.1%	0.21	1.9 MB	6.5%	0.55
Apr 29 17:00:00 - 17:59:59	582	5.3%	0.16	1.4 MB	4.7%	0.40
Apr 29 18:00:00 - 18:59:59	626	5.7%	0.17	1.6 MB	5.3%	0.45
Apr 29 19:00:00 - 19:59:59	592	5.4%	0.16	1.6 MB	5.2%	0.44
Apr 29 20:00:00 - 20:59:59	464	4.3%	0.13	1.3 MB	4.4%	0.38
Apr 29 21:00:00 - 21:59:59	471	4.3%	0.13	1.3 MB	4.5%	0.38
Apr 29 22:00:00 - 22:59:59	328	3.0%	0.09	1.1 MB	3.8%	0.32
Apr 29 23:00:00 - 23:59:59	303	2.8%	0.08	1.2 MB	4.0%	0.34
Apr 30 00:00:00 - 00:59:59	215	2.0%	0.06	699.8 KB	2.3%	0.19
Apr 30 01:00:00 - 01:59:59	246	2.3%	0.07	787.7 KB	2.6%	0.22
Apr 30 02:00:00 - 02:59:59	224	2.1%	0.06	602.4 KB	2.0%	0.17
Apr 30 03:00:00 - 03:59:59	200	1.8%	0.06	662.4 KB	2.2%	0.18
Apr 30 04:00:00 - 04:59:59	192	1.8%	0.05	702.6 KB	2.3%	0.20
Apr 30 05:00:00 - 05:59:59	273	2.5%	0.08	762.8 KB	2.5%	0.21
Apr 30 06:00:00 - 06:59:59	362	3.3%	0.10	959.3 KB	3.1%	0.27
Apr 30 07:00:00 - 07:59:59	454	4.2%	0.13	1.2 MB	3.9%	0.33
Apr 30 08:00:00 - 08:59:59	537	4.9%	0.15	1.3 MB	4.5%	0.38
Apr 30 09:00:00 - 09:59:59	526	4.8%	0.15	1.3 MB	4.5%	0.38
Apr 30 10:00:00 - 10:59:59	557	5.1%	0.15	1.4 MB	4.9%	0.41
Apr 30 11:00:00 - 11:59:59	519	4.8%	0.14	1.5 MB	5.1%	0.43
Apr 30 12:00:00 - 12:59:59	570	5.2%	0.16	1.6 MB	5.3%	0.45
Apr 30 13:00:00 - 13:59:59	581	5.3%	0.16	1.5 MB	5.1%	0.43
Apr 30 14:00:00 - 15:00:02	630	5.8%	0.17	1.6 MB	5.2%	0.44
TOTAL: 24:00:00	10901	100.0%	0.13	29.8 MB	100.0%	0.35

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
FOUR	DEFAULT	1024.0 MB	740.0 MB	72.3%	6	0.15	79.44
ONE	DEFAULT	1024.0 MB	914.2 MB	89.3%	8	0.16	77.74
THREE	DEFAULT	1024.0 MB	750.3 MB	73.3%	6	0.16	78.10
TWO	DEFAULT	1024.0 MB	741.8 MB	72.4%	6	0.16	77.45
TOTAL: 4	-	4.0 GB	3.1 GB	76.8%	26	0.16	-

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nntp4.net	890	5	1	1	883
2	hasname-out.news.weretis.net	706	1	1	0	704
3	news-out.aioe.org	146	0	1	0	145
TOTAL: 3		1742	6	3	1	1732

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.health.policy.drug-approval	5
alt.math	1
TOTAL: 2	6

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)	952
EMP (md5)	233
EMP (phl)	157
EMP (phn nph)	143
Too many newsgroups	3
HTML Multipart	2
Scoring filter	2
TOTAL: 7	1492

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	%Took	Elapsed
1	bbsgslincom	344	344	0	0	100%	19:35:41
2	aioe	8684	28	6580	2	0%	24:09:42
3	nntp4	13056	7	10886	0	0%	24:09:41
4	weretis	4624	0	4419	17	0%	24:06:11
TOTAL: 4		26708	379	21885	19	1%	92:01:15

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	bbsgslincom	1.1 MB	0.0 KB	1.1 MB	3.4 KB	19:35:41
2	aioe	93.3 KB	5.1 KB	98.4 KB	3.3 KB	24:09:42
3	nntp4	36.0 KB	0.0 KB	36.0 KB	5.1 KB	24:09:41
4	weretis	0.0 KB	64.9 KB	64.9 KB	3.8 KB	24:06:11
TOTAL: 4		1.3 MB	70.0 KB	1.3 MB	3.4 KB	92:01:15

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
scanner-25.ch1.censys-scanner.com	0.004	0.002	0.000	00:00:00
TOTAL: 1	0.004	0.002	0.000	00:00:00

NNRP no permission clients:

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

Daily Usenet report

Apr 29 15:00:02 -- Apr 30 15:00:02