Daily Usenet report: Sep 11 06:01:01

Unknown entries from news log file:

First 15 / 15 lines (100.0%)

Sep 11 06:02:19 kennel innd: message repeated 2 times: [ ctlinnd command s]
Sep 11 20:00:04 kennel pgpverify[449129]: pgpverify: verification failed
Sep 11 20:00:08 kennel pgpverify[449146]: pgpverify: verification failed
Sep 12 00:31:12 kennel innd: message repeated 2 times: [ 165.154.6.82 rejected 502]
Sep 12 00:31:33 kennel innd: message repeated 4 times: [ 165.154.6.82 rejected 502]
Sep 12 00:31:52 kennel innd: message repeated 5 times: [ 165.154.6.82 rejected 502]
Sep 12 00:32:00 kennel innd: message repeated 2 times: [ 165.154.6.82 rejected 502]
Sep 12 00:32:23 kennel innd: message repeated 6 times: [ 165.154.6.82 rejected 502]
Sep 12 00:32:31 kennel innd: message repeated 2 times: [ 165.154.6.82 rejected 502]
Sep 12 00:32:47 kennel innd: message repeated 4 times: [ 165.154.6.82 rejected 502]
Sep 12 00:33:00 kennel innd: message repeated 3 times: [ 165.154.6.82 rejected 502]
Sep 12 00:33:16 kennel innd: message repeated 5 times: [ 165.154.6.82 rejected 502]
Sep 12 00:33:24 kennel innd: message repeated 2 times: [ 165.154.6.82 rejected 502]
Sep 12 00:33:40 kennel innd: message repeated 5 times: [ 165.154.6.82 rejected 502]
Sep 12 00:34:00 kennel innd: message repeated 6 times: [ 165.154.6.82 rejected 502]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	526820	98.0%	66.4 MB	97.7%
innd	8825	1.6%	1.3 MB	1.9%
innfeed	1431	0.3%	203.3 KB	0.3%
cnfsstat	144	0.0%	26.4 KB	0.0%
nnrpd	84	0.0%	9.0 KB	0.0%
controlchan	3	0.0%	0.2 KB	0.0%
pgpverify	2	0.0%	0.4 KB	0.0%
overchan	1	0.0%	0.1 KB	0.0%
TOTAL: 8	537310	100.0%	68.0 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	790786	44.0%
Do not exist	512574	28.5%
Positive hits	479225	26.7%
Cache misses	13867	0.8%
TOTAL: 4	1796452	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.000	0.0%	32	0.000	0.000	0.000
article cleanup	00:00:05.255	0.0%	507272	0.006	0.010	0.018
article logging	00:00:19.154	0.0%	507499	0.020	0.038	0.050
article parse	00:00:31.224	0.0%	1441900	0.011	0.022	0.041
article write	00:00:28.495	0.0%	332284	0.061	0.086	0.119
artlog/artparse	00:00:00.804	0.0%	15171	0.000	0.053	0.152
data move	00:00:11.390	0.0%	1498203	0.004	0.008	0.013
hisgrep/artcncl	00:00:00.000	0.0%	32	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:14.864	0.0%	1798444	0.002	0.008	0.220
history sync	00:00:00.023	0.0%	310	0.000	0.074	1.000
history write	00:00:29.813	0.0%	512279	0.044	0.058	0.073
idle	23:40:54.267	99.3%	1168742	35.912	72.945	132.558
nntp read	00:00:51.360	0.1%	1169711	0.024	0.044	0.068
overview write	00:00:01.826	0.0%	332284	0.002	0.005	0.014
perl filter	00:00:23.769	0.0%	497348	0.034	0.048	0.069
python filter	00:00:24.565	0.0%	497348	0.038	0.049	0.067
site send	00:00:13.112	0.0%	807527	0.010	0.016	0.094
TOTAL: 23:50:39.047	23:45:09.921	99.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.509	0.0%	143204	0.000	0.004	0.017
article prepare	00:00:00.003	0.0%	17759	0.000	0.000	0.200
article read	00:00:00.364	0.0%	4567	0.000	0.080	0.500
backlog stats	00:00:00.399	0.0%	417721	0.000	0.001	0.007
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:06.785	0.0%	263940	0.012	0.026	0.043
data write	00:00:05.466	0.0%	163850	0.015	0.033	0.101
idle	23:53:29.193	100.0%	417721	49.698	205.901	1478.885
status file	00:00:00.167	0.0%	268	0.000	0.623	1.000
TOTAL: 23:54:09.726	23:53:42.886	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%	18	0.000	0.056	1.000
TOTAL: 00:00:07.962	00:00:00.001	0.0%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	146
name	2
pause	2
paused	2
reload	48
reserve	2
TOTAL: 11	214

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
group-admin@isc.org	0	0	0	2	0	0
TOTAL	0	0	0	2	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	hasname-out.feeder.erje.net	576	986286	326490	468236	191560	33%	48:04:27
2	hasname-out.news.weretis.net	2	12431	4490	7878	63	36%	53:17:52
3	news.nntp4.net	5	8883	2688	5938	257	30%	24:26:06
4	usenet.blueworldhosting.com	717	7270	923	6131	216	12%	23:44:46
5	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 5		1301	1014870	334591	488183	192096	32%	149:33:11

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.feeder.erje.net	2.9 GB	95.5 MB	1.7 GB	4.7 GB	62%	9.4 KB
2	news.nntp4.net	54.8 MB	2.1 KB	2.4 MB	57.2 MB	95%	19.9 KB
3	hasname-out.news.weretis.net	12.8 MB	2.1 KB	184.4 KB	13.0 MB	98%	2.9 KB
4	usenet.blueworldhosting.com	2.3 MB	0.0 KB	1.4 MB	3.7 MB	62%	3.3 KB
5	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 5		3.0 GB	95.5 MB	1.7 GB	4.7 GB	62%	9.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 11 06:01:01 - 06:59:59	8977	2.7%	2.54	81.3 MB	2.7%	23.51
Sep 11 07:00:00 - 07:59:59	14162	4.2%	3.93	128.9 MB	4.3%	36.67
Sep 11 08:00:00 - 08:59:59	10716	3.2%	2.98	95.6 MB	3.2%	27.20
Sep 11 09:00:00 - 09:59:59	12660	3.8%	3.52	96.4 MB	3.2%	27.43
Sep 11 10:00:00 - 10:59:59	10609	3.2%	2.95	97.0 MB	3.2%	27.60
Sep 11 11:00:00 - 11:59:59	12383	3.7%	3.44	123.2 MB	4.1%	35.04
Sep 11 12:00:00 - 12:59:59	13436	4.0%	3.73	126.7 MB	4.2%	36.04
Sep 11 13:00:00 - 13:59:59	13936	4.2%	3.87	115.3 MB	3.8%	32.80
Sep 11 14:00:00 - 14:59:59	17399	5.2%	4.83	147.3 MB	4.9%	41.89
Sep 11 15:00:00 - 15:59:59	20984	6.3%	5.83	148.5 MB	4.9%	42.24
Sep 11 16:00:00 - 16:59:59	16796	5.0%	4.67	148.5 MB	4.9%	42.23
Sep 11 17:00:00 - 17:59:59	15811	4.7%	4.39	132.3 MB	4.4%	37.64
Sep 11 18:00:00 - 18:59:59	15286	4.6%	4.25	140.6 MB	4.6%	40.00
Sep 11 19:00:00 - 19:59:59	8880	2.7%	2.47	92.6 MB	3.1%	26.33
Sep 11 20:00:00 - 20:59:59	9138	2.7%	2.54	98.0 MB	3.2%	27.88
Sep 11 21:00:00 - 21:59:59	11622	3.5%	3.23	120.3 MB	4.0%	34.21
Sep 11 22:00:00 - 22:59:59	17380	5.2%	4.83	155.1 MB	5.1%	44.12
Sep 11 23:00:00 - 23:59:59	17012	5.1%	4.73	157.9 MB	5.2%	44.91
Sep 12 00:00:00 - 00:59:59	12739	3.8%	3.54	124.4 MB	4.1%	35.39
Sep 12 01:00:00 - 01:59:59	13411	4.0%	3.73	150.2 MB	5.0%	42.73
Sep 12 02:00:00 - 02:59:59	14849	4.4%	4.12	164.1 MB	5.4%	46.69
Sep 12 03:00:00 - 03:59:59	20969	6.3%	5.82	130.2 MB	4.3%	37.03
Sep 12 04:00:00 - 04:59:59	11874	3.5%	3.30	108.0 MB	3.6%	30.71
Sep 12 05:00:00 - 05:59:59	13264	4.0%	3.68	143.9 MB	4.8%	40.93
Sep 12 06:00:00 - 06:01:01	209	0.1%	3.43	2.7 MB	0.1%	45.25
TOTAL: 24:00:00	334502	100.0%	3.87	3.0 GB	100.0%	35.90

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
C1	DEFAULT	10.0 GB	8.8 GB	88.4%	79	43.69	2.78
TOTAL: 1	-	10.0 GB	8.8 GB	88.4%	79	43.69	-

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	hasname-out.feeder.erje.net	191677	166273	10014	55	15335
2	news.nntp4.net	348	263	1	0	84
3	usenet.blueworldhosting.com	223	212	0	0	11
4	hasname-out.news.weretis.net	70	57	1	0	12
TOTAL: 4		192318	166805	10016	55	15442

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.binaries.friends	68110
alt.binaries.encrypted	4118
alt.binaries.encryptnzb.juliet	3846
alt.binaries.encryptnzb.delta	3794
alt.binaries.encryptnzb.novemeber	3793
alt.binaries.encryptnzb.lima	3778
alt.binaries.encryptnzb.hotel	3764
alt.binaries.encryptnzb.mike	3732
alt.binaries.encryptnzb.bravo	3729
alt.binaries.encryptnzb.golf	3725
alt.binaries.encryptnzb.echo	3722
alt.binaries.encryptnzb.india	3710
alt.binaries.encryptnzb.beta	3685
alt.binaries.encryptnzb.charlie	3685
alt.binaries.encryptnzb.foxtrot	3684
alt.binaries.encryptnzb.kilo	3677
alt.binaries.encryptnzb.alpha	3613
alt.binaries.ath	2172
alt.binaries.dc	1373
alt.binaries.solar-xl	1196
TOTAL: 200	166805

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
alt.binaries.whitburn	55
TOTAL: 1	55

INND no permission servers:

System	Conn
103.193.138.76	30
165.154.6.82	25
167.94.146.49	2
178.159.37.70	1
206.168.34.122	1
TOTAL: 5	59

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	hasname-out.feeder.erje.net	4
	TOTAL: 1	4
Including strange strings
	hasname-out.feeder.erje.net	130
	news.nntp4.net	84
	hasname-out.news.weretis.net	12
	usenet.blueworldhosting.com	7
	TOTAL: 4	233
No colon-space in header field
	hasname-out.feeder.erje.net	2047
	TOTAL: 1	2047
TOTAL: 3		2284

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	blueworldhosting	143219	2275	139807	2	128	1%	23:59:39
2	bbsgslincom	316	316	0	0	0	100%	20:10:17
3	erje	7522	0	7283	108	0	0%	23:59:40
4	nntp4	5839	0	5838	0	0	0%	23:59:40
5	weretis	4293	0	2309	1980	2	0%	23:58:13
TOTAL: 5		161189	2591	155237	2090	130	1%	116:07:29

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	blueworldhosting	15.6 MB	51.0 KB	15.7 MB	0.2 KB/s	7.0 KB	23:59:39
2	bbsgslincom	972.9 KB	0.0 KB	972.9 KB	0.0 KB/s	3.1 KB	20:10:17
3	erje	0.0 KB	2.9 MB	2.9 MB	0.0 KB/s	27.8 KB	23:59:40
4	nntp4	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:40
5	weretis	0.0 KB	54.8 MB	54.8 MB	0.6 KB/s	28.3 KB	23:58:13
TOTAL: 5		16.6 MB	57.7 MB	74.3 MB	0.2 KB/s	16.3 KB	116:07:29

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
206.168.34.94	0.001	0.012	00:00:04
45.82.78.106	0.004	0.012	00:00:01
165.154.238.112	0.001	0.003	00:00:01
194.164.107.4	0.000	0.004	00:00:00
o42.scanner.modat.io	0.000	0.004	00:00:00
azpdesbsk5g6.stretchoid.com	0.001	0.007	00:00:00
205.210.31.206	0.000	0.004	00:00:00
azpdsswec9a4.stretchoid.com	0.004	0.004	00:00:00
148.210.203.35.bc.googleusercontent.com	0.001	0.003	00:00:00
205.210.31.243	0.001	0.003	00:00:00
198.235.24.32	0.001	0.002	00:00:00
TOTAL: 11	0.014	0.058	00:00:07

NNRP no permission clients [Top 20]:

System	Conn
45.82.78.106	4
206.168.34.94	3
azpdesbsk5g6.stretchoid.com	2
azpdsswec9a4.stretchoid.com	2
148.210.203.35.bc.googleusercontent.com	1
165.154.238.112	1
194.164.107.4	1
198.235.24.32	1
205.210.31.206	1
205.210.31.243	1
o42.scanner.modat.io	1
TOTAL: 11	18

Daily Usenet report

Sep 11 06:01:01 -- Sep 12 06:01:01