Daily Usenet report: Jun 27 06:01:01

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Jun 27 06:02:17 kennel innd: message repeated 2 times: [ ctlinnd command s]
Jun 27 07:15:02 kennel pgpverify[28010]: pgpverify: verification failed
Jun 27 07:15:36 kennel pgpverify[28026]: pgpverify: verification failed
Jun 27 07:22:00 kennel innd: message repeated 2 times: [ 24.182.239.66 rejected 502]
Jun 27 07:30:02 kennel pgpverify[29918]: pgpverify: verification failed
Jun 27 07:30:05 kennel pgpverify[29927]: pgpverify: verification failed
Jun 27 07:30:05 kennel pgpverify[29936]: pgpverify: verification failed
Jun 27 07:30:06 kennel pgpverify[29945]: pgpverify: verification failed
Jun 27 07:30:06 kennel pgpverify[29954]: pgpverify: verification failed
Jun 27 07:30:06 kennel pgpverify[29963]: pgpverify: verification failed
Jun 28 05:30:00 kennel innd: message repeated 2 times: [ 24.182.239.66 rejected 502]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	240712	96.3%	32.0 MB	96.1%
innd	7378	3.0%	1.1 MB	3.2%
innfeed	1597	0.6%	209.8 KB	0.6%
nnrpd	156	0.1%	17.7 KB	0.1%
cnfsstat	144	0.1%	17.7 KB	0.1%
controlchan	9	0.0%	0.6 KB	0.0%
pgpverify	8	0.0%	1.3 KB	0.0%
overchan	1	0.0%	0.1 KB	0.0%
TOTAL: 8	250005	100.0%	33.3 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	430640	46.8%
Positive hits	253554	27.5%
Do not exist	233889	25.4%
Cache misses	2717	0.3%
TOTAL: 4	920800	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.031	0.0%	120	0.000	0.258	1.000
article cleanup	00:00:02.739	0.0%	240082	0.004	0.011	0.019
article logging	00:00:10.502	0.0%	240097	0.017	0.044	0.061
article parse	00:00:14.738	0.0%	655397	0.009	0.022	0.034
article write	00:00:11.239	0.0%	117285	0.044	0.096	0.153
artlog/artparse	00:00:00.034	0.0%	644	0.000	0.053	1.000
data move	00:00:02.938	0.0%	703418	0.000	0.004	0.012
hisgrep/artcncl	00:00:00.004	0.0%	120	0.000	0.033	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:03.811	0.0%	923028	0.001	0.004	0.041
history sync	00:00:00.023	0.0%	334	0.000	0.069	1.000
history write	00:00:15.147	0.0%	234133	0.023	0.065	0.085
idle	23:53:21.066	99.5%	620861	66.793	138.519	225.617
nntp read	00:00:24.995	0.0%	620046	0.017	0.040	0.057
overview write	00:00:00.626	0.0%	117285	0.000	0.005	0.015
perl filter	00:00:12.292	0.0%	233110	0.025	0.053	0.080
python filter	00:00:12.306	0.0%	233110	0.022	0.053	0.082
site send	00:00:05.542	0.0%	308176	0.011	0.018	0.158
TOTAL: 24:00:38.733	23:55:18.033	99.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.277	0.0%	89959	0.000	0.003	0.018
article prepare	00:00:00.002	0.0%	4198	0.000	0.000	0.167
article read	00:00:00.088	0.0%	1303	0.000	0.068	1.000
backlog stats	00:00:00.228	0.0%	213518	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:03.615	0.0%	143413	0.008	0.025	0.048
data write	00:00:03.698	0.0%	76819	0.013	0.048	0.098
idle	23:53:15.688	100.0%	213518	131.534	402.756	1156.040
status file	00:00:00.238	0.0%	353	0.000	0.674	2.250
TOTAL: 23:53:31.852	23:53:23.834	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.003	0.0%	39	0.000	0.077	1.000
TOTAL: 00:00:06.619	00:00:00.003	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
reload	48
reserve	2
TOTAL: 11	215

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
moderator@dana.de	0	0	0	8	0	0
TOTAL	0	0	0	8	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	hasname-out.feeder.erje.net	577	466002	111611	231252	123139	23%	47:26:26
2	hasname-out.news.weretis.net	15	9392	3081	6261	50	32%	36:38:14
3	news.nntp4.net	6	7172	1725	5335	112	24%	25:40:08
4	usenet.blueworldhosting.com	624	6980	801	6047	132	11%	23:34:26
5	24.182.239.66	0	9	0	9	0	0%	04:04:08
6	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 6		1223	489555	117218	248904	123433	23%	137:23:22

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.feeder.erje.net	1.1 GB	68.5 MB	1.2 GB	2.3 GB	47%	10.4 KB
2	hasname-out.news.weretis.net	8.2 MB	22.6 KB	100.9 KB	8.3 MB	98%	2.7 KB
3	news.nntp4.net	7.4 MB	1.6 KB	798.6 KB	8.2 MB	90%	4.6 KB
4	usenet.blueworldhosting.com	1.8 MB	0.0 KB	157.9 KB	2.0 MB	92%	2.2 KB
5	24.182.239.66	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
6	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 6		1.1 GB	68.6 MB	1.2 GB	2.3 GB	47%	10.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 27 06:01:01 - 06:59:59	3728	3.2%	1.05	32.1 MB	2.8%	9.28
Jun 27 07:00:00 - 07:59:59	3767	3.2%	1.05	29.7 MB	2.6%	8.44
Jun 27 08:00:00 - 08:59:59	4208	3.6%	1.17	37.2 MB	3.3%	10.58
Jun 27 09:00:00 - 09:59:59	5635	4.8%	1.57	45.3 MB	4.0%	12.87
Jun 27 10:00:00 - 10:59:59	4868	4.2%	1.35	43.5 MB	3.8%	12.36
Jun 27 11:00:00 - 11:59:59	5360	4.6%	1.49	55.1 MB	4.8%	15.67
Jun 27 12:00:00 - 12:59:59	5811	5.0%	1.61	57.6 MB	5.1%	16.39
Jun 27 13:00:00 - 13:59:59	10336	8.8%	2.87	79.8 MB	7.0%	22.69
Jun 27 14:00:00 - 14:59:59	4732	4.0%	1.31	48.7 MB	4.3%	13.87
Jun 27 15:00:00 - 15:59:59	5080	4.3%	1.41	56.7 MB	5.0%	16.13
Jun 27 16:00:00 - 16:59:59	5778	4.9%	1.60	66.5 MB	5.8%	18.91
Jun 27 17:00:00 - 17:59:59	5399	4.6%	1.50	51.9 MB	4.6%	14.76
Jun 27 18:00:00 - 18:59:59	4680	4.0%	1.30	55.3 MB	4.9%	15.72
Jun 27 19:00:00 - 19:59:59	5335	4.5%	1.48	55.3 MB	4.9%	15.74
Jun 27 20:00:00 - 20:59:59	7720	6.6%	2.14	58.1 MB	5.1%	16.54
Jun 27 21:00:00 - 21:59:59	4499	3.8%	1.25	41.8 MB	3.7%	11.90
Jun 27 22:00:00 - 22:59:59	4599	3.9%	1.28	45.6 MB	4.0%	12.96
Jun 27 23:00:00 - 23:59:59	4028	3.4%	1.12	35.5 MB	3.1%	10.10
Jun 28 00:00:00 - 00:59:59	4669	4.0%	1.30	49.5 MB	4.3%	14.07
Jun 28 01:00:00 - 01:59:59	3885	3.3%	1.08	49.3 MB	4.3%	14.03
Jun 28 02:00:00 - 02:59:59	2557	2.2%	0.71	27.6 MB	2.4%	7.84
Jun 28 03:00:00 - 03:59:59	3151	2.7%	0.88	35.7 MB	3.1%	10.14
Jun 28 04:00:00 - 04:59:59	3932	3.4%	1.09	40.9 MB	3.6%	11.64
Jun 28 05:00:00 - 05:59:59	3433	2.9%	0.95	39.3 MB	3.4%	11.17
Jun 28 06:00:00 - 06:01:01	92	0.1%	1.51	705.3 KB	0.1%	11.56
TOTAL: 24:00:00	117282	100.0%	1.36	1.1 GB	100.0%	13.49

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
C1	DEFAULT	10.0 GB	7.6 GB	76.5%	152	16.18	7.50
TOTAL: 1	-	10.0 GB	7.6 GB	76.5%	152	16.18	-

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	TooOld	Other
1	hasname-out.feeder.erje.net	123121	115500	6575	11	386	649
2	usenet.blueworldhosting.com	132	131	0	1	0	0
3	news.nntp4.net	111	109	2	0	0	0
4	hasname-out.news.weretis.net	66	48	6	1	0	11
TOTAL: 4		123430	115788	6583	13	386	660

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.binaries.friends	38088
alt.binaries.encrypted	16780
alt.binaries.mp3.abooks	3922
alt.binaries.amazing	2232
alt.binaries.u-4all	1818
alt.binaries.wtfnzb.beta	1767
alt.binaries.wtfnzb.echo	1766
alt.binaries.wtfnzb.india	1750
alt.binaries.wtfnzb.juliet	1719
alt.binaries.wtfnzb.bravo	1717
alt.binaries.wtfnzb.alpha	1714
alt.binaries.wtfnzb.kilo	1702
alt.binaries.wtfnzb.lima	1697
alt.binaries.wtfnzb.charlie	1686
alt.binaries.wtfnzb.foxtrot	1686
alt.binaries.wtfnzb.delta	1681
alt.binaries.wtfnzb.mike	1679
alt.binaries.wtfnzb.novemeber	1658
alt.binaries.wtfnzb.hotel	1657
alt.binaries.pwp	1616
TOTAL: 3449	115788

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

Groups	Count
alt.binaries.sounds.mp3.jazz.vocals	5
alt.binaries.whitburn	3
soc.culture.hawaii	3
uk.legal.moderated	1
uk.religion.christian	1
TOTAL: 5	13

INND no permission servers:

System	Conn
24.182.239.66	126
103.193.138.76	30
162.216.150.107	2
185.132.176.32	1
66.132.172.43	1
80.66.66.40	1
85.217.149.1	1
88.214.25.125	1
TOTAL: 8	163

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	hasname-out.feeder.erje.net	8
	TOTAL: 1	8
Including strange strings
	hasname-out.news.weretis.net	11
	hasname-out.feeder.erje.net	4
	TOTAL: 2	15
No colon-space in header field
	hasname-out.feeder.erje.net	644
	TOTAL: 1	644
TOTAL: 3		667

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	blueworldhosting	74661	960	71643	1	16526	1%	23:59:12
2	bbsgslincom	339	339	0	0	0	100%	19:32:06
3	nntp4	5018	2	5005	0	58	0%	23:59:01
4	weretis	3285	2	3273	0	1	0%	23:59:53
5	erje	5014	0	5012	0	35	0%	23:57:33
TOTAL: 5		88317	1303	84933	1	16620	1%	115:27:45

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	blueworldhosting	9.4 MB	7.5 KB	9.4 MB	0.1 KB/s	10.0 KB	23:59:12
2	bbsgslincom	1.7 MB	0.0 KB	1.7 MB	0.0 KB/s	5.0 KB	19:32:06
3	weretis	13.0 KB	0.0 KB	13.0 KB	0.0 KB/s	6.5 KB	23:59:53
4	nntp4	2.4 KB	0.0 KB	2.4 KB	0.0 KB/s	1.2 KB	23:59:01
5	erje	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:33
TOTAL: 5		11.0 MB	7.5 KB	11.0 MB	0.0 KB/s	8.7 KB	115:27:45

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
prod53client01.academyforinternetresearch.org	0.022	0.039	00:00:03
prod55client01.academyforinternetresearch.org	0.020	0.045	00:00:02
azpdcszgscea.stretchoid.com	0.003	0.006	00:00:00
139.172.132.66.censys-scanner.com	0.003	0.009	00:00:00
o609.scanner.modat.io	0.002	0.002	00:00:00
o614.scanner.modat.io	0.002	0.000	00:00:00
TOTAL: 6	0.052	0.101	00:00:06

NNRP no permission clients [Top 20]:

System	Conn
prod53client01.academyforinternetresearch.org	16
prod55client01.academyforinternetresearch.org	16
139.172.132.66.censys-scanner.com	3
azpdcszgscea.stretchoid.com	2
o609.scanner.modat.io	1
o614.scanner.modat.io	1
TOTAL: 6	39

Daily Usenet report

Jun 27 06:01:01 -- Jun 28 06:01:01