Daily Usenet report: Oct 4 15:00:01

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Oct 4 15:01:02 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
Oct 4 18:06:03 colo-sc-1 nnrpd[12878]: unable to get certificate from '/etc/dehydrated/certs/usenet.hasname.com/cert.pem'
Oct 4 18:06:03 colo-sc-1 nnrpd[12878]: error initializing TLS: [CA_file: ] [CA_path: /etc/ssl/certs] [cert_file: /etc/dehydrated/certs/usenet.hasname.com/cert.pem] [key_file: /etc/dehydrated/certs/usenet.hasname.com/privkey.pem]
Oct 4 18:19:53 colo-sc-1 innd: message repeated 7 times: [ 94.142.138.104 rejected 502]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	35522	73.5%	5.0 MB	75.0%
innd	10038	20.8%	1.3 MB	18.9%
innfeed	1243	2.6%	198.9 KB	2.9%
cnfsstat	1152	2.4%	174.1 KB	2.6%
nnrpd	340	0.7%	35.7 KB	0.5%
controlchan	1	0.0%	0.2 KB	0.0%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 7	48297	100.0%	6.6 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	79378	41.8%
Positive hits	75000	39.5%
Do not exist	35346	18.6%
Cache misses	59	0.0%
TOTAL: 4	189783	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.475	0.0%	64	0.000	7.422	18.625
article cleanup	00:00:00.248	0.0%	35454	0.000	0.007	0.022
article logging	00:00:00.957	0.0%	35480	0.005	0.027	0.075
article parse	00:00:00.883	0.0%	106626	0.000	0.008	0.024
article write	00:00:02.350	0.0%	27939	0.016	0.084	5.140
artlog/artcncl	00:00:00.001	0.0%	64	0.000	0.016	0.143
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:00:00.202	0.0%	279786	0.000	0.001	0.004
hisgrep/artcncl	00:00:00.001	0.0%	64	0.000	0.016	1.000
hishave/artcncl	00:00:00.004	0.0%	64	0.000	0.062	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:02.243	0.0%	190968	0.001	0.012	0.239
history sync	00:00:00.016	0.0%	385	0.000	0.042	0.500
history write	00:00:03.135	0.0%	35455	0.000	0.088	4.080
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:59:36.033	99.9%	244180	144.247	353.739	933.415
nntp read	00:00:01.817	0.0%	243349	0.002	0.007	0.015
overview write	00:00:00.098	0.0%	27939	0.000	0.004	0.025
perl filter	00:00:43.018	0.0%	35454	0.700	1.213	1.686
python filter	00:00:00.905	0.0%	35454	0.000	0.026	0.059
site send	00:00:04.362	0.0%	83817	0.031	0.052	0.082
TOTAL: 24:00:56.182	24:00:36.748	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.064	0.0%	28693	0.000	0.002	0.019
article prepare	00:00:00.005	0.0%	24127	0.000	0.000	0.011
article read	00:00:00.649	0.0%	10792	0.000	0.060	3.980
backlog stats	00:00:00.100	0.0%	175937	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.871	0.0%	111005	0.001	0.008	0.018
data write	00:00:02.565	0.0%	83965	0.014	0.031	0.055
idle	23:55:24.362	100.0%	175937	174.386	489.518	999.749
status file	00:00:00.043	0.0%	166	0.000	0.259	1.000
TOTAL: 23:55:32.116	23:55:28.659	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:08:15.919	97.2%	166	421.778	2987.464	13396.625
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:06.260	1.2%	185440	0.000	0.034	7.590
readart	00:00:00.207	0.0%	30	0.000	6.900	8.375
TOTAL: 00:08:30.369	00:08:22.386	98.4%	-	-	-	-

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	hasname-out.news.weretis.net	8	58306	20302	34776	3228	34%	48:57:06
2	usenet.blueworldhosting.com	720	25408	5056	16120	4232	19%	23:46:51
3	news.nntp4.net	3	29057	2799	25792	466	9%	24:42:46
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		732	112771	28157	76688	7926	24%	97:26:43

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.news.weretis.net	180.6 MB	24.9 MB	205.5 MB	87%	8.9 KB
2	usenet.blueworldhosting.com	32.3 MB	14.0 MB	46.3 MB	69%	5.1 KB
3	news.nntp4.net	26.6 MB	2.5 MB	29.2 MB	91%	9.1 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		239.5 MB	41.4 MB	281.0 MB	85%	8.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 4 15:00:01 - 15:59:59	1416	5.1%	0.39	9.0 MB	3.8%	2.57
Oct 4 16:00:00 - 16:59:59	1274	4.6%	0.35	8.2 MB	3.4%	2.32
Oct 4 17:00:00 - 17:59:59	1084	3.9%	0.30	8.9 MB	3.8%	2.54
Oct 4 18:00:00 - 18:59:59	681	2.4%	0.19	2.6 MB	1.1%	0.73
Oct 4 19:00:00 - 19:59:59	2813	10.1%	0.78	15.1 MB	6.4%	4.30
Oct 4 20:00:00 - 20:59:59	1543	5.5%	0.43	14.8 MB	6.2%	4.21
Oct 4 21:00:00 - 21:59:59	1324	4.7%	0.37	13.8 MB	5.8%	3.93
Oct 4 22:00:00 - 22:59:59	1659	5.9%	0.46	16.9 MB	7.1%	4.82
Oct 4 23:00:00 - 23:59:59	905	3.2%	0.25	9.3 MB	3.9%	2.65
Oct 5 00:00:00 - 00:59:59	847	3.0%	0.24	10.7 MB	4.5%	3.03
Oct 5 01:00:00 - 01:59:59	958	3.4%	0.27	12.2 MB	5.1%	3.47
Oct 5 02:00:00 - 02:59:59	1082	3.9%	0.30	14.1 MB	5.9%	4.01
Oct 5 03:00:00 - 03:59:59	1004	3.6%	0.28	13.1 MB	5.5%	3.74
Oct 5 04:00:00 - 04:59:59	972	3.5%	0.27	11.4 MB	4.8%	3.23
Oct 5 05:00:00 - 05:59:59	1169	4.2%	0.32	12.5 MB	5.3%	3.56
Oct 5 06:00:00 - 06:59:59	1271	4.6%	0.35	13.9 MB	5.9%	3.96
Oct 5 07:00:00 - 07:59:59	1442	5.2%	0.40	13.8 MB	5.8%	3.93
Oct 5 08:00:00 - 08:59:59	1473	5.3%	0.41	14.9 MB	6.2%	4.23
Oct 5 09:00:00 - 09:59:59	798	2.9%	0.22	4.2 MB	1.8%	1.20
Oct 5 10:00:00 - 10:59:59	775	2.8%	0.22	2.8 MB	1.2%	0.81
Oct 5 11:00:00 - 11:59:59	790	2.8%	0.22	3.1 MB	1.3%	0.89
Oct 5 12:00:00 - 12:59:59	1062	3.8%	0.29	4.3 MB	1.8%	1.22
Oct 5 13:00:00 - 13:59:59	818	2.9%	0.23	4.2 MB	1.8%	1.19
Oct 5 14:00:00 - 14:59:59	762	2.7%	0.21	4.1 MB	1.7%	1.16
Oct 5 15:00:00 - 15:00:02	3	0.0%	1.50	6.7 KB	0.0%	3.37
TOTAL: 24:00:01	27925	100.0%	0.32	238.1 MB	100.0%	2.82

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
EIGHT	BIG	4.0 GB	2.9 GB	72.6%	1	0.00	0.00
FIVE	BIG	4.0 GB	2.9 GB	72.6%	1	0.00	0.00
FOUR	DEFAULT	1024.0 MB	896.6 MB	87.6%	23	0.87	13.96
ONE	DEFAULT	1024.0 MB	34.6 MB	3.4%	26	0.88	13.73
SEVEN	BIG	4.0 GB	2.9 GB	72.6%	1	0.00	0.00
SIX	BIG	4.0 GB	2.9 GB	72.8%	1	0.00	0.00
THREE	DEFAULT	1024.0 MB	903.1 MB	88.2%	23	0.87	13.92
TWO	DEFAULT	1024.0 MB	891.8 MB	87.1%	23	0.87	13.93
TOTAL: 8	-	20.0 GB	14.3 GB	71.4%	99	0.87	-

Sites sending bad articles:

Server		Total	Group	Other
1	usenet.blueworldhosting.com	3872	1953	1919
2	hasname-out.news.weretis.net	3205	16	3189
3	news.nntp4.net	456	2	454
TOTAL: 3		7533	1971	5562

Unwanted newsgroups [Top 20]:

Newsgroup	Count
de.sci.announce	507
de.sci.medizin	327
kraft.politics	160
linux.kernel.netdev	159
fido7.fidonet.online	150
fido7.pravda.news	87
kraft.talks	84
fido7.pushkin.local	79
fido7.ukrnews	64
kraft.competent	57
kraft.vehicles	50
linux.kernel.git	36
fido.fidonet.online	36
linux.redhat.rhsa.announce	17
odessa.news	17
kraft.food	16
adobe.illustrator.macintosh	15
fido7.lorapvt.horo	12
kraft.marketplace	12
eternal-september.moderated	12
TOTAL: 52	1971

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	2405
EMP (md5)	1498
Reject Google Groups posting to comp.lang.c	1286
Too many newsgroups	231
Scoring filter	81
EMP (phl)	31
HTML Multipart	3
TOTAL: 7	5535

INND no permission servers:

System	Conn
94.142.138.104	1
80.66.88.19	1
194.165.16.37	1
80.66.76.32	1
TOTAL: 4	4

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	hasname-out.news.weretis.net	16
	usenet.blueworldhosting.com	10
	TOTAL: 2	26
No colon-space in header
	usenet.blueworldhosting.com	1
	TOTAL: 1	1
TOTAL: 2		27

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	bbsgslincom	8516	8513	0	3	433	99%	23:59:56
2	nntp4	28455	3129	21982	110	138	10%	23:59:58
3	weretis	11386	425	6721	255	290	3%	23:59:58
4	aioe	0	0	0	0	27226	0%	23:59:57
5	blueworldhosting	24175	0	21608	0	0	0%	23:59:58
TOTAL: 5		72532	12067	50311	368	28087	16%	119:59:47

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	bbsgslincom	87.9 MB	23.6 KB	87.9 MB	1.0 KB/s	10.6 KB	23:59:56
2	nntp4	18.5 MB	1.5 MB	20.0 MB	0.2 KB/s	6.3 KB	23:59:58
3	weretis	3.1 MB	2.6 MB	5.7 MB	0.1 KB/s	8.5 KB	23:59:58
4	aioe	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:57
5	blueworldhosting	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:58
TOTAL: 5		109.4 MB	4.1 MB	113.5 MB	0.3 KB/s	9.3 KB	119:59:47

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	114-34-121-114.hinet-ip.hinet.net	17	27	265.5 KB	5	00:06:05
2	1-162-95-50.dynamic-ip.hinet.net	3	3	7.7 KB	1	00:02:23
TOTAL: 2		20	30	273.2 KB	6	00:08:30

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.hinet-ip.hinet.net	17	27	265.5 KB	5	0	0	00:06:05
TOTAL: 1		17	30	273.2 KB	6	0	0	00:06:05

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
114-34-121-114.hinet-ip.hinet.net	0.113	0.183	00:06:05
1-162-95-50.dynamic-ip.hinet.net	0.053	0.127	00:02:23
152.32.149.246	0.001	0.004	00:00:00
zl-ams-nl-gp1-wk117b.internet-census.org	0.004	0.012	00:00:00
exhilarating.monitoring.internet-measurement.com	0.001	0.001	00:00:00
zl-ams-nl-gp1-wk117.internet-census.org	0.001	0.002	00:00:00
zl-ams-nl-gp1-wk117d.internet-census.org	0.001	0.001	00:00:00
107.170.241.22	0.001	0.001	00:00:00
71.6.134.234	0.001	0.005	00:00:00
94.102.61.41	0.002	0.000	00:00:00
TOTAL: 10	0.178	0.336	00:08:30

NNRP no permission clients:

System	Conn
zl-ams-nl-gp1-wk117b.internet-census.org	8
71.6.134.234	3
152.32.149.246	3
zl-ams-nl-gp1-wk117d.internet-census.org	1
exhilarating.monitoring.internet-measurement.com	1
1-162-95-50.dynamic-ip.hinet.net	1
107.170.241.22	1
94.102.61.41	1
zl-ams-nl-gp1-wk117.internet-census.org	1
TOTAL: 9	20

Newsgroup request counts (by category):

Category		Count	Pct
1	news	24	80.0%
2	tw	3	10.0%
3	comp	3	10.0%
TOTAL: 3		30	100.0%

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

Newsgroup		Count
1	news.answers	21
2	news.announce.newgroups	3
3	comp.lang.c	3
4	tw.bbs.comp.lang.perl	2
5	tw.bbs.talk	1
TOTAL: 5		30

Daily Usenet report

Oct 4 15:00:01 -- Oct 5 15:00:02