Daily Usenet report: Apr 25 15:00:10

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Apr 25 15:02:19 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
Apr 25 21:23:42 colo-sc-1 pgpverify[2803]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	17448	81.6%	1.9 MB	77.8%
innd	2352	11.0%	330.6 KB	13.4%
innfeed	970	4.5%	128.5 KB	5.2%
cnfsstat	576	2.7%	87.9 KB	3.6%
nnrpd	26	0.1%	3.0 KB	0.1%
controlchan	2	0.0%	0.2 KB	0.0%
overchan	1	0.0%	0.1 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 8	21376	100.0%	2.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	16355	36.7%
Negative hits	15168	34.0%
Positive hits	13031	29.2%
Cache misses	28	0.1%
TOTAL: 4	44582	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:06.933	0.0%	63	0.000	110.048	434.375
article cleanup	00:00:00.126	0.0%	16991	0.000	0.007	0.036
article logging	00:00:00.637	0.0%	17380	0.000	0.037	0.100
article parse	00:00:00.331	0.0%	39995	0.000	0.008	0.030
article write	00:00:06.052	0.0%	15316	0.000	0.395	21.303
artlog/artcncl	00:00:00.002	0.0%	62	0.000	0.032	0.143
data move	00:00:00.034	0.0%	67735	0.000	0.001	0.005
hisgrep/artcncl	00:00:01.420	0.0%	63	0.000	22.540	161.750
hishave/artcncl	00:00:01.196	0.0%	63	0.000	18.984	203.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:59.652	0.2%	44778	0.000	4.012	47.324
history sync	00:00:00.148	0.0%	345	0.000	0.429	45.667
history write	00:00:09.827	0.0%	16425	0.000	0.598	17.184
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:56:25.078	99.7%	66251	402.554	1300.887	4168.319
nntp read	00:00:00.732	0.0%	66146	0.000	0.011	0.028
overview write	00:00:00.051	0.0%	15316	0.000	0.003	0.031
perl filter	00:00:23.252	0.0%	16425	0.890	1.416	5.915
python filter	00:00:00.564	0.0%	16425	0.000	0.034	0.098
site send	00:00:02.921	0.0%	45828	0.035	0.064	0.105
TOTAL: 24:00:49.933	24:00:18.956	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.032	0.0%	15182	0.000	0.002	0.039
article prepare	00:00:00.000	0.0%	528	0.000	0.000	0.000
article read	00:00:00.034	0.0%	522	0.000	0.065	1.500
backlog stats	00:00:00.032	0.0%	52454	0.000	0.001	0.008
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.274	0.0%	31400	0.000	0.009	0.055
data write	00:00:00.612	0.0%	16345	0.000	0.037	0.149
idle	23:58:46.485	100.0%	52454	935.731	1645.756	4652.109
status file	00:00:00.356	0.0%	191	0.000	1.864	159.000
TOTAL: 23:58:49.301	23:58:47.825	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	0.0%	6	0.000	0.167	1.000
TOTAL: 00:00:03.862	00:00:00.001	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
control@usenet-fr.news.eu.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	news-out.aioe.org	5	13300	12328	683	289	92%	24:28:51
2	news.nntp4.net	3	16355	3078	11891	1386	18%	24:26:18
3	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 3		9	29655	15406	12574	1675	51%	48:55:09

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.aioe.org	33.4 MB	456.7 KB	703.1 KB	34.5 MB	96%	2.8 KB
2	news.nntp4.net	10.0 MB	1.0 MB	11.2 MB	22.2 MB	45%	5.1 KB
3	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 3		43.4 MB	1.5 MB	11.9 MB	56.7 MB	76%	3.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 25 15:00:10 - 15:59:59	722	4.7%	0.20	1.8 MB	4.1%	0.51
Apr 25 16:00:00 - 16:59:59	866	5.7%	0.24	2.6 MB	6.1%	0.75
Apr 25 17:00:00 - 17:59:59	799	5.2%	0.22	2.4 MB	5.6%	0.69
Apr 25 18:00:00 - 18:59:59	833	5.4%	0.23	2.2 MB	5.1%	0.62
Apr 25 19:00:00 - 19:59:59	902	5.9%	0.25	3.1 MB	7.3%	0.90
Apr 25 20:00:00 - 20:59:59	691	4.5%	0.19	1.9 MB	4.3%	0.53
Apr 25 21:00:00 - 21:59:59	744	4.9%	0.21	2.0 MB	4.6%	0.57
Apr 25 22:00:00 - 22:59:59	533	3.5%	0.15	1.6 MB	3.6%	0.44
Apr 25 23:00:00 - 23:59:59	531	3.5%	0.15	2.3 MB	5.3%	0.65
Apr 26 00:00:00 - 00:59:59	395	2.6%	0.11	1.1 MB	2.5%	0.31
Apr 26 01:00:00 - 01:59:59	295	1.9%	0.08	860.8 KB	1.9%	0.24
Apr 26 02:00:00 - 02:59:59	277	1.8%	0.08	1.1 MB	2.6%	0.31
Apr 26 03:00:00 - 03:59:59	282	1.8%	0.08	912.0 KB	2.1%	0.25
Apr 26 04:00:00 - 04:59:59	314	2.0%	0.09	894.1 KB	2.0%	0.25
Apr 26 05:00:00 - 05:59:59	403	2.6%	0.11	1.2 MB	2.7%	0.34
Apr 26 06:00:00 - 06:59:59	519	3.4%	0.14	1.5 MB	3.4%	0.42
Apr 26 07:00:00 - 07:59:59	671	4.4%	0.19	2.0 MB	4.6%	0.57
Apr 26 08:00:00 - 08:59:59	718	4.7%	0.20	1.9 MB	4.3%	0.53
Apr 26 09:00:00 - 09:59:59	819	5.3%	0.23	1.9 MB	4.5%	0.55
Apr 26 10:00:00 - 10:59:59	739	4.8%	0.21	1.7 MB	4.0%	0.49
Apr 26 11:00:00 - 11:59:59	773	5.0%	0.21	1.8 MB	4.3%	0.52
Apr 26 12:00:00 - 12:59:59	779	5.1%	0.22	1.9 MB	4.4%	0.54
Apr 26 13:00:00 - 13:59:59	848	5.5%	0.24	2.2 MB	5.1%	0.63
Apr 26 14:00:00 - 14:59:59	864	5.6%	0.24	2.3 MB	5.3%	0.66
Apr 26 15:00:00 - 15:00:04	5	0.0%	1.25	13.3 KB	0.0%	3.33
TOTAL: 23:59:54	15322	100.0%	0.18	43.1 MB	100.0%	0.51

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
FOUR	DEFAULT	1024.0 MB	560.1 MB	54.7%	0	0.22	53.97
ONE	DEFAULT	1024.0 MB	718.5 MB	70.2%	2	0.22	54.46
THREE	DEFAULT	1024.0 MB	561.8 MB	54.9%	0	0.22	54.76
TWO	DEFAULT	1024.0 MB	563.1 MB	55.0%	0	0.22	54.38
TOTAL: 4	-	4.0 GB	2.3 GB	58.7%	2	0.22	-

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nntp4.net	1409	26	410	1	972
2	news-out.aioe.org	655	0	156	0	499
TOTAL: 2		2064	26	566	1	1471

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.business.career-opportunites	8
gnu.emacs	5
alt.computer.hardware	3
panix.chat.politics	3
harvard.forsale	3
alt.support.chronic.pain	1
nc.charlotte.general	1
aol.neighborhood.ca.fresno.marketplace	1
alt.music.synth.roland	1
TOTAL: 9	26

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)	394
EMP (md5)	363
EMP (phl)	181
Binary: misplaced binary	83
EMP (phn nph)	42
Scoring filter	7
Too many newsgroups	6
HTML Multipart	3
User-issued spam cancel	2
Too many newsgroups (meow)	1
TOTAL: 10	1082

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news-out.aioe.org	364
	news.nntp4.net	25
	TOTAL: 2	389
TOTAL: 1		389

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	%Took	Elapsed
1	bbsgslincom	520	520	0	0	100%	22:00:29
2	aioe	2976	1	2927	1	0%	23:59:49
3	nntp4	12171	0	12138	0	0%	23:50:01
TOTAL: 3		15667	521	15065	1	3%	69:50:19

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	bbsgslincom	1.9 MB	0.0 KB	1.9 MB	3.7 KB	22:00:29
2	aioe	4.8 KB	1.2 KB	5.9 KB	3.0 KB	23:59:49
3	nntp4	0.0 KB	0.0 KB	0.0 KB	0	23:50:01
TOTAL: 3		1.9 MB	1.2 KB	1.9 MB	3.7 KB	69:50:19

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
71.6.146.186	0.000	0.005	00:00:02
news.nntp4.net	0.000	0.004	00:00:01
scanner-09.ch1.censys-scanner.com	0.001	0.007	00:00:00
scanner-06.ch1.censys-scanner.com	0.003	0.000	00:00:00
TOTAL: 4	0.004	0.016	00:00:03

NNRP no permission clients:

System	Conn
71.6.146.186	2
scanner-09.ch1.censys-scanner.com	2
news.nntp4.net	1
scanner-06.ch1.censys-scanner.com	1
TOTAL: 4	6

Daily Usenet report

Apr 25 15:00:10 -- Apr 26 15:00:04