Daily Usenet report: Oct 25 15:00:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Oct 25 15:00:54 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	13746	74.5%	1.7 MB	72.2%
innd	2793	15.1%	384.7 KB	16.3%
innfeed	1284	7.0%	178.7 KB	7.6%
cnfsstat	580	3.1%	88.6 KB	3.8%
nnrpd	44	0.2%	4.8 KB	0.2%
controlchan	2	0.0%	0.2 KB	0.0%
overchan	2	0.0%	0.3 KB	0.0%
TOTAL: 7	18451	100.0%	2.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	33672	43.1%
Negative hits	31132	39.8%
Do not exist	13280	17.0%
Cache misses	47	0.1%
TOTAL: 4	78131	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.193	0.0%	81	0.000	2.383	8.333
article cleanup	00:00:00.086	0.0%	13336	0.000	0.006	0.051
article logging	00:00:00.361	0.0%	13626	0.000	0.026	0.070
article parse	00:00:00.230	0.0%	22515	0.000	0.010	0.045
article write	00:00:00.548	0.0%	12015	0.000	0.046	0.130
artlog/artcncl	00:00:00.002	0.0%	81	0.000	0.025	0.500
data move	00:00:00.043	0.0%	85061	0.000	0.001	0.004
hisgrep/artcncl	00:00:00.001	0.0%	75	0.000	0.013	0.333
hishave/artcncl	00:00:00.050	0.0%	81	0.000	0.617	3.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:35.228	0.0%	78480	0.000	0.449	4.069
history sync	00:00:00.011	0.0%	361	0.000	0.030	1.000
history write	00:00:01.214	0.0%	13336	0.000	0.091	2.810
hiswrite/artcncl	00:00:00.000	0.0%	6	0.000	0.000	0.000
idle	23:49:53.505	99.9%	85740	556.934	1000.624	4028.805
nntp read	00:00:00.589	0.0%	85786	0.000	0.007	0.020
overview write	00:00:00.034	0.0%	12015	0.000	0.003	0.038
perl filter	00:00:13.101	0.0%	13336	0.774	0.982	3.026
python filter	00:00:00.345	0.0%	13336	0.000	0.026	0.080
site send	00:00:02.099	0.0%	36045	0.026	0.058	0.093
TOTAL: 23:50:55.760	23:50:47.640	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.025	0.0%	11997	0.000	0.002	0.033
article prepare	00:00:00.001	0.0%	1009	0.000	0.001	0.500
article read	00:00:00.250	0.0%	508	0.000	0.492	56.500
backlog stats	00:00:00.022	0.0%	68905	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.293	0.0%	41335	0.000	0.007	0.035
data write	00:00:00.778	0.0%	29398	0.000	0.026	0.074
idle	23:47:58.095	100.0%	68905	647.758	1243.423	3047.487
status file	00:00:00.075	0.0%	277	0.000	0.271	1.667
TOTAL: 23:48:09.796	23:47:59.539	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:50.519	94.7%	28	1846.857	3947.107	4647.190
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.886	1.6%	183586	0.000	0.010	1.000
readart	00:00:00.231	0.2%	8	28.875	28.875	28.875
TOTAL: 00:01:56.681	00:01:52.636	96.5%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	hasname-out.news.weretis.net	6	22120	8000	13627	493	36%	46:04:31
2	news-out.aioe.org	4	10477	2563	7902	12	24%	21:58:40
3	news.nntp4.net	3	13921	1060	12046	815	7%	25:11:02
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		14	46518	11623	33575	1320	24%	93:14:13

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.news.weretis.net	24.4 MB	1.1 MB	25.4 MB	95%	3.1 KB
2	news-out.aioe.org	6.4 MB	21.2 KB	6.5 MB	99%	2.6 KB
3	news.nntp4.net	3.3 MB	2.4 MB	5.8 MB	57%	3.1 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		34.1 MB	3.5 MB	37.6 MB	90%	3.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 25 15:00:02 - 15:59:59	625	5.2%	0.17	1.9 MB	5.3%	0.53
Oct 25 16:00:00 - 16:59:59	663	5.5%	0.18	2.0 MB	5.7%	0.57
Oct 25 17:00:00 - 17:59:59	635	5.3%	0.18	1.8 MB	5.2%	0.52
Oct 25 18:00:00 - 18:59:59	636	5.3%	0.18	1.8 MB	5.1%	0.50
Oct 25 19:00:00 - 19:59:59	617	5.1%	0.17	1.6 MB	4.4%	0.44
Oct 25 20:00:00 - 20:59:59	526	4.4%	0.15	1.6 MB	4.4%	0.44
Oct 25 21:00:00 - 21:59:59	549	4.6%	0.15	2.0 MB	5.8%	0.58
Oct 25 22:00:00 - 22:59:59	417	3.5%	0.12	1.4 MB	4.0%	0.40
Oct 25 23:00:00 - 23:59:59	357	3.0%	0.10	1.3 MB	3.7%	0.37
Oct 26 00:00:00 - 00:59:59	282	2.3%	0.08	1012.9 KB	2.8%	0.28
Oct 26 01:00:00 - 01:59:59	244	2.0%	0.07	896.8 KB	2.5%	0.25
Oct 26 02:00:00 - 02:59:59	290	2.4%	0.08	1017.4 KB	2.8%	0.28
Oct 26 03:00:00 - 03:59:59	226	1.9%	0.06	582.7 KB	1.6%	0.16
Oct 26 04:00:00 - 04:59:59	222	1.8%	0.06	778.5 KB	2.2%	0.22
Oct 26 05:00:00 - 05:59:59	287	2.4%	0.08	941.5 KB	2.6%	0.26
Oct 26 06:00:00 - 06:59:59	371	3.1%	0.10	1.1 MB	3.1%	0.31
Oct 26 07:00:00 - 07:59:59	416	3.5%	0.12	1.2 MB	3.3%	0.33
Oct 26 08:00:00 - 08:59:59	572	4.7%	0.16	1.6 MB	4.4%	0.44
Oct 26 09:00:00 - 09:59:59	648	5.4%	0.18	1.7 MB	5.0%	0.49
Oct 26 10:00:00 - 10:59:59	663	5.5%	0.18	1.7 MB	4.7%	0.47
Oct 26 11:00:00 - 11:59:59	591	4.9%	0.16	1.6 MB	4.6%	0.46
Oct 26 12:00:00 - 12:59:59	672	5.6%	0.19	1.8 MB	5.1%	0.50
Oct 26 13:00:00 - 13:59:59	778	6.5%	0.22	2.0 MB	5.7%	0.57
Oct 26 14:00:00 - 14:59:59	760	6.3%	0.21	2.0 MB	5.8%	0.58
Oct 26 15:00:00 - 15:00:01	2	0.0%	2.00	3.7 KB	0.0%	3.74
TOTAL: 23:59:59	12049	100.0%	0.14	35.0 MB	100.0%	0.42

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
FOUR	DEFAULT	1024.0 MB	275.6 MB	26.9%	9	0.18	66.90
ONE	DEFAULT	1024.0 MB	453.8 MB	44.3%	11	0.18	67.97
THREE	DEFAULT	1024.0 MB	288.6 MB	28.2%	9	0.17	69.36
TWO	DEFAULT	1024.0 MB	282.2 MB	27.6%	9	0.18	68.06
TOTAL: 4	-	4.0 GB	1.3 GB	31.7%	38	0.18	-

Sites sending bad articles:

Server		Total	Group	Unapp	Other
1	news.nntp4.net	828	0	1	827
2	hasname-out.news.weretis.net	741	5	0	736
3	news-out.aioe.org	47	1	0	46
TOTAL: 3		1616	6	1	1609

Unwanted newsgroups [Top 20]:

Newsgroup	Count
sybase.public.powerdesigner.general	2
alt.business.home	2
alt.exploding.barney	1
alt.business.business	1
TOTAL: 4	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)	630
EMP (md5)	422
EMP (phl)	150
EMP (phn nph)	102
Too many newsgroups	5
Scoring filter	4
HTML Multipart	2
TOTAL: 7	1315

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	hasname-out.news.weretis.net	258
	news-out.aioe.org	35
	news.nntp4.net	3
	TOTAL: 3	296
TOTAL: 1		296

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	bbsgslincom	345	345	0	0	0	100%	18:37:22
2	aioe	10634	128	8860	9	2	1%	23:56:24
3	nntp4	13215	1	10998	0	0	0%	23:56:24
4	weretis	4774	0	3778	28	0	0%	23:56:24
TOTAL: 4		28968	474	23636	37	2	1%	90:26:34

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	bbsgslincom	1.2 MB	0.0 KB	1.2 MB	3.6 KB	18:37:22
2	aioe	428.9 KB	34.0 KB	462.9 KB	3.4 KB	23:56:24
3	nntp4	2.3 KB	0.0 KB	2.3 KB	2.3 KB	23:56:24
4	weretis	0.0 KB	101.1 KB	101.1 KB	3.6 KB	23:56:24
TOTAL: 4		1.6 MB	135.1 KB	1.8 MB	3.5 KB	90:26:34

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	114-34-121-114.hinet-ip.hinet.net	2	8	18.5 KB	2	0	0	00:01:56
TOTAL: 1		2	8	18.5 KB	2	0	0	00:01:56

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.hinet-ip.hinet.net	2	8	18.5 KB	2	0	0	00:01:56
TOTAL: 1		2	8	18.5 KB	2	0	0	00:01:56

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
114-34-121-114.hinet-ip.hinet.net	0.374	1.199	00:01:56
192.3.255.115	0.001	0.001	00:00:00
164.92.106.15	0.001	0.005	00:00:00
purple.census.shodan.io	0.000	0.004	00:00:00
198.199.94.158	0.001	0.002	00:00:00
scanner-08.ch1.censys-scanner.com	0.002	0.001	00:00:00
TOTAL: 6	0.379	1.212	00:01:56

NNRP no permission clients:

System	Conn
164.92.106.15	2
purple.census.shodan.io	2
198.199.94.158	1
192.3.255.115	1
scanner-08.ch1.censys-scanner.com	1
TOTAL: 5	7

Newsgroup request counts (by category):

Category		Count	Pct
1	tw	8	100.0%
TOTAL: 1		8	100.0%

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

Newsgroup		Count
1	tw.bbs.talk	4
2	tw.bbs.comp.lang.perl	4
TOTAL: 2		8

Daily Usenet report

Oct 25 15:00:02 -- Oct 26 15:00:01