Daily Usenet report: Mar 5 15:00:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Mar 5 15:00:46 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	13144	75.5%	1.6 MB	74.1%
innd	2285	13.1%	313.0 KB	13.9%
innfeed	1332	7.7%	179.5 KB	8.0%
cnfsstat	576	3.3%	84.0 KB	3.7%
nnrpd	59	0.3%	6.1 KB	0.3%
controlchan	3	0.0%	0.2 KB	0.0%
overchan	2	0.0%	0.2 KB	0.0%
TOTAL: 7	17401	100.0%	2.2 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	29257	45.9%
Positive hits	21656	34.0%
Do not exist	12772	20.0%
Cache misses	17	0.0%
TOTAL: 4	63702	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.251	0.0%	47	0.000	5.340	23.400
article cleanup	00:00:00.090	0.0%	12828	0.000	0.007	0.048
article logging	00:00:00.406	0.0%	13092	0.000	0.031	0.074
article parse	00:00:00.199	0.0%	21954	0.000	0.009	0.049
article write	00:00:00.606	0.0%	11804	0.000	0.051	1.264
artlog/artcncl	00:00:00.002	0.0%	46	0.000	0.043	1.000
data move	00:00:00.039	0.0%	72648	0.000	0.001	0.005
hisgrep/artcncl	00:00:00.000	0.0%	46	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	47	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.147	0.0%	63971	0.000	0.018	0.635
history sync	00:00:00.009	0.0%	346	0.000	0.026	0.500
history write	00:00:00.993	0.0%	12828	0.000	0.077	5.175
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	24:00:12.829	100.0%	72093	570.821	1198.630	4109.644
nntp read	00:00:00.574	0.0%	71886	0.000	0.008	0.017
overview write	00:00:00.040	0.0%	11804	0.000	0.003	0.034
perl filter	00:00:12.209	0.0%	12828	0.768	0.952	1.293
python filter	00:00:00.319	0.0%	12828	0.000	0.025	0.086
site send	00:00:02.084	0.0%	35413	0.020	0.059	0.099
TOTAL: 24:00:38.699	24:00:31.797	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.016	0.0%	11688	0.000	0.001	0.031
article prepare	00:00:00.000	0.0%	411	0.000	0.000	0.000
article read	00:00:00.021	0.0%	408	0.000	0.051	1.000
backlog stats	00:00:00.023	0.0%	46249	0.000	0.000	0.009
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.207	0.0%	25714	0.000	0.008	0.028
data write	00:00:00.495	0.0%	14125	0.000	0.035	0.100
idle	23:49:29.522	100.0%	46249	1085.223	1854.516	5045.631
status file	00:00:00.068	0.0%	285	0.000	0.239	1.000
TOTAL: 23:49:32.279	23:49:30.352	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:25.596	93.1%	4	6399.000	6399.000	6399.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.540	2.0%	46	0.000	11.739	15.400
readart	00:00:00.000	0.0%	1	0.000	0.000	0.000
TOTAL: 00:00:27.502	00:00:26.136	95.0%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	147
name	2
newgroup	1
pause	2
paused	2
reserve	2
TOTAL: 11	168

Newsgroups created:

Group	Mode
free.lyin.biden	y
TOTAL	1

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
mchaney@nyc.com	1	0	0	0	1	1
TOTAL	1	0	0	0	1	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	hasname-out.news.weretis.net	8	22019	10243	11431	345	46%	48:20:45
2	news.nntp4.net	3	13097	1689	10709	699	12%	24:45:36
3	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 3		12	35116	11932	22140	1044	33%	73:06:21

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.news.weretis.net	32.1 MB	797.2 KB	32.9 MB	97%	3.2 KB
2	news.nntp4.net	4.8 MB	2.6 MB	7.4 MB	65%	3.2 KB
3	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 3		37.0 MB	3.4 MB	40.3 MB	91%	3.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 5 15:00:02 - 15:59:59	598	5.1%	0.17	1.6 MB	4.4%	0.45
Mar 5 16:00:00 - 16:59:59	585	5.0%	0.16	1.7 MB	4.8%	0.49
Mar 5 17:00:00 - 17:59:59	634	5.4%	0.18	2.0 MB	5.5%	0.57
Mar 5 18:00:00 - 18:59:59	783	6.6%	0.22	2.2 MB	5.9%	0.61
Mar 5 19:00:00 - 19:59:59	665	5.6%	0.18	1.7 MB	4.6%	0.48
Mar 5 20:00:00 - 20:59:59	481	4.1%	0.13	1.3 MB	3.7%	0.38
Mar 5 21:00:00 - 21:59:59	518	4.4%	0.14	1.6 MB	4.5%	0.46
Mar 5 22:00:00 - 22:59:59	485	4.1%	0.13	1.7 MB	4.6%	0.48
Mar 5 23:00:00 - 23:59:59	420	3.6%	0.12	1.4 MB	3.9%	0.41
Mar 6 00:00:00 - 00:59:59	382	3.2%	0.11	1.6 MB	4.4%	0.45
Mar 6 01:00:00 - 01:59:59	269	2.3%	0.07	1.1 MB	3.0%	0.31
Mar 6 02:00:00 - 02:59:59	230	1.9%	0.06	726.1 KB	2.0%	0.20
Mar 6 03:00:00 - 03:59:59	306	2.6%	0.09	1.5 MB	4.2%	0.43
Mar 6 04:00:00 - 04:59:59	199	1.7%	0.06	778.0 KB	2.1%	0.22
Mar 6 05:00:00 - 05:59:59	293	2.5%	0.08	922.3 KB	2.5%	0.26
Mar 6 06:00:00 - 06:59:59	313	2.7%	0.09	949.9 KB	2.6%	0.26
Mar 6 07:00:00 - 07:59:59	367	3.1%	0.10	1.2 MB	3.2%	0.33
Mar 6 08:00:00 - 08:59:59	587	5.0%	0.16	1.4 MB	3.8%	0.40
Mar 6 09:00:00 - 09:59:59	605	5.1%	0.17	1.5 MB	4.2%	0.43
Mar 6 10:00:00 - 10:59:59	630	5.3%	0.17	2.0 MB	5.6%	0.58
Mar 6 11:00:00 - 11:59:59	612	5.2%	0.17	1.7 MB	4.6%	0.47
Mar 6 12:00:00 - 12:59:59	550	4.7%	0.15	1.6 MB	4.4%	0.46
Mar 6 13:00:00 - 13:59:59	608	5.1%	0.17	2.1 MB	5.7%	0.59
Mar 6 14:00:00 - 14:59:59	687	5.8%	0.19	2.1 MB	5.9%	0.61
Mar 6 15:00:00 - 15:00:02	1	0.0%	0.50	2.6 KB	0.0%	1.30
TOTAL: 24:00:00	11808	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	96.3 MB	9.4%	11	0.18	67.97
ONE	DEFAULT	1024.0 MB	270.2 MB	26.4%	13	0.18	66.73
THREE	DEFAULT	1024.0 MB	107.6 MB	10.5%	11	0.18	68.20
TWO	DEFAULT	1024.0 MB	92.9 MB	9.1%	11	0.18	68.34
TOTAL: 4	-	4.0 GB	566.9 MB	13.8%	46	0.18	-

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	706	1	705
2	hasname-out.news.weretis.net	584	4	580
TOTAL: 2		1290	5	1285

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.religion.buddhism	4
bburg.forsale	1
TOTAL: 2	5

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	509
EMP (phn path)	382
EMP (phn nph)	81
EMP (phl)	30
HTML Multipart	7
Binary: misplaced binary	6
HTML post	3
Scoring filter	3
TOTAL: 8	1021

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	hasname-out.news.weretis.net	241
	news.nntp4.net	23
	TOTAL: 2	264
TOTAL: 1		264

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	bbsgslincom	407	407	0	0	0	100%	20:05:36
2	aioe	0	0	0	0	11735	0%	23:59:54
3	nntp4	11741	0	10205	0	1	0%	23:59:54
4	weretis	1567	0	1536	1	0	0%	23:30:49
TOTAL: 4		13715	407	11741	1	11736	2%	91:36:13

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	bbsgslincom	1.4 MB	0.0 KB	1.4 MB	3.5 KB	20:05:36
2	aioe	0.0 KB	0.0 KB	0.0 KB	0	23:59:54
3	nntp4	0.0 KB	0.0 KB	0.0 KB	0	23:59:54
4	weretis	0.0 KB	1.8 KB	1.8 KB	1.8 KB	23:30:49
TOTAL: 4		1.4 MB	1.8 KB	1.4 MB	3.5 KB	91:36:13

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	114-34-121-114.hinet-ip.hinet.net	1	1	1.7 KB	1	0	0	00:00:26
TOTAL: 1		1	1	1.7 KB	1	0	0	00:00:27

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.hinet-ip.hinet.net	1	1	1.7 KB	1	0	0	00:00:26
TOTAL: 1		1	1	1.7 KB	1	0	0	00:00:26

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
114-34-121-114.hinet-ip.hinet.net	0.004	0.006	00:00:26
164.92.106.15	0.000	0.004	00:00:00
willing.monitoring.internet-measurement.com	0.000	0.002	00:00:00
164.92.114.247	0.001	0.003	00:00:00
147.182.225.86	0.001	0.003	00:00:00
192.241.218.22	0.000	0.002	00:00:00
45.227.254.49	0.001	0.003	00:00:00
?	0.001	0.000	00:00:00
TOTAL: 8	0.008	0.023	00:00:27

NNRP no permission clients:

System	Conn
164.92.106.15	2
164.92.114.247	2
45.227.254.49	2
147.182.225.86	2
192.241.218.22	1
willing.monitoring.internet-measurement.com	1
TOTAL: 6	10

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	1
TOTAL: 1	1

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	1	100.0%
TOTAL: 1		1	100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

Newsgroup		Count
1	comp.lang.c	1
TOTAL: 1		1

Daily Usenet report

Mar 5 15:00:02 -- Mar 6 15:00:02