Daily Usenet report: Apr 22 06:01:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Apr 22 06:02:22 kennel innd: message repeated 2 times: [ ctlinnd command s]
Apr 22 21:56:15 kennel pgpverify[1486791]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	682626	98.6%	88.1 MB	98.3%
innd	7947	1.1%	1.3 MB	1.4%
innfeed	1462	0.2%	219.8 KB	0.2%
cnfsstat	144	0.0%	24.9 KB	0.0%
nnrpd	124	0.0%	14.1 KB	0.0%
controlchan	12	0.0%	2.7 KB	0.0%
overchan	1	0.0%	0.1 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 8	692317	100.0%	89.6 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	1073443	44.2%
Positive hits	675037	27.8%
Do not exist	667244	27.5%
Cache misses	10614	0.4%
TOTAL: 4	2426338	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.018	0.0%	47	0.000	0.383	1.000
article cleanup	00:00:05.978	0.0%	681422	0.005	0.009	0.015
article logging	00:00:23.923	0.0%	681502	0.015	0.035	0.049
article parse	00:00:23.951	0.0%	1324913	0.007	0.018	0.025
article write	00:00:34.020	0.0%	476156	0.038	0.071	0.105
artlog/artparse	00:00:00.045	0.0%	724	0.000	0.062	1.000
data move	00:00:01.866	0.0%	1263744	0.000	0.001	0.009
hisgrep/artcncl	00:00:00.012	0.0%	47	0.000	0.255	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:56.559	0.1%	2430452	0.002	0.023	0.423
history sync	00:00:00.030	0.0%	294	0.000	0.102	1.000
history write	00:00:37.204	0.0%	667261	0.036	0.056	0.076
idle	23:50:26.789	99.3%	1044083	44.509	82.203	125.895
nntp read	00:00:39.373	0.0%	1045015	0.017	0.038	0.051
overview write	00:00:02.423	0.0%	476156	0.002	0.005	0.010
perl filter	00:00:25.429	0.0%	666546	0.014	0.038	0.049
python filter	00:00:27.949	0.0%	666546	0.017	0.042	0.053
site send	00:00:20.526	0.0%	1428469	0.010	0.014	0.091
TOTAL: 24:00:19.962	23:55:26.095	99.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:01.546	0.0%	473029	0.001	0.003	0.007
article prepare	00:00:00.001	0.0%	980	0.000	0.001	0.053
article read	00:00:00.063	0.0%	508	0.000	0.124	1.000
backlog stats	00:00:01.062	0.0%	1272009	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:19.766	0.0%	784737	0.017	0.025	0.043
data write	00:00:13.166	0.0%	490527	0.015	0.027	0.040
idle	23:49:29.076	99.9%	1272009	38.680	67.428	143.813
status file	00:00:00.189	0.0%	289	0.000	0.654	2.000
TOTAL: 23:50:31.402	23:50:04.869	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.004	0.1%	29	0.000	0.138	1.000
TOTAL: 00:00:06.825	00:00:00.004	0.1%	-	-	-	-

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
nl-admin@nic.surfnet.nl	0	0	1	1	0	1
TOTAL	0	0	1	1	0	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	hasname-out.feeder.erje.net	576	1327516	469552	652119	205845	35%	47:45:48
2	hasname-out.news.weretis.net	3	12775	4174	8538	63	32%	50:09:22
3	usenet.blueworldhosting.com	288	7701	1467	6131	103	19%	23:43:38
4	news.nntp4.net	2	8176	1319	6555	302	16%	27:25:20
5	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 5		870	1356168	476512	673343	206313	35%	149:04:08

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.feeder.erje.net	1.7 GB	78.8 MB	1.7 GB	3.4 GB	48%	5.3 KB
2	hasname-out.news.weretis.net	11.0 MB	0.8 KB	123.0 KB	11.1 MB	98%	2.7 KB
3	news.nntp4.net	4.5 MB	2.1 KB	2.9 MB	7.4 MB	60%	4.7 KB
4	usenet.blueworldhosting.com	3.0 MB	0.0 KB	358.9 KB	3.4 MB	89%	2.2 KB
5	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 5		1.7 GB	78.8 MB	1.7 GB	3.5 GB	49%	5.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 22 06:01:01 - 06:59:59	19547	4.1%	5.52	71.2 MB	4.1%	20.59
Apr 22 07:00:00 - 07:59:59	17585	3.7%	4.88	82.4 MB	4.7%	23.43
Apr 22 08:00:00 - 08:59:59	20271	4.3%	5.63	84.5 MB	4.9%	24.05
Apr 22 09:00:00 - 09:59:59	20469	4.3%	5.69	77.4 MB	4.5%	22.03
Apr 22 10:00:00 - 10:59:59	24647	5.2%	6.85	94.4 MB	5.4%	26.84
Apr 22 11:00:00 - 11:59:59	22624	4.8%	6.28	74.5 MB	4.3%	21.19
Apr 22 12:00:00 - 12:59:59	19742	4.1%	5.48	68.5 MB	3.9%	19.47
Apr 22 13:00:00 - 13:59:59	20112	4.2%	5.59	71.3 MB	4.1%	20.28
Apr 22 14:00:00 - 14:59:59	20154	4.2%	5.60	72.8 MB	4.2%	20.70
Apr 22 15:00:00 - 15:59:59	19360	4.1%	5.38	77.6 MB	4.5%	22.08
Apr 22 16:00:00 - 16:59:59	19945	4.2%	5.54	84.4 MB	4.9%	24.00
Apr 22 17:00:00 - 17:59:59	18670	3.9%	5.19	76.7 MB	4.4%	21.83
Apr 22 18:00:00 - 18:59:59	18406	3.9%	5.11	72.0 MB	4.1%	20.48
Apr 22 19:00:00 - 19:59:59	19002	4.0%	5.28	73.6 MB	4.2%	20.94
Apr 22 20:00:00 - 20:59:59	22562	4.7%	6.27	71.6 MB	4.1%	20.37
Apr 22 21:00:00 - 21:59:59	18623	3.9%	5.17	65.8 MB	3.8%	18.71
Apr 22 22:00:00 - 22:59:59	19146	4.0%	5.32	61.9 MB	3.6%	17.61
Apr 22 23:00:00 - 23:59:59	18842	4.0%	5.23	67.4 MB	3.9%	19.16
Apr 23 00:00:00 - 00:59:59	19510	4.1%	5.42	78.0 MB	4.5%	22.19
Apr 23 01:00:00 - 01:59:59	18765	3.9%	5.21	59.8 MB	3.4%	17.01
Apr 23 02:00:00 - 02:59:59	19480	4.1%	5.41	63.7 MB	3.7%	18.12
Apr 23 03:00:00 - 03:59:59	19912	4.2%	5.53	63.2 MB	3.6%	17.97
Apr 23 04:00:00 - 04:59:59	18773	3.9%	5.21	59.3 MB	3.4%	16.87
Apr 23 05:00:00 - 05:59:59	19653	4.1%	5.46	62.9 MB	3.6%	17.89
Apr 23 06:00:00 - 06:01:01	357	0.1%	5.85	993.6 KB	0.1%	16.29
TOTAL: 24:00:00	476157	100.0%	5.51	1.7 GB	100.0%	20.57

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
C1	DEFAULT	10.0 GB	9.8 GB	97.6%	140	34.17	3.55
TOTAL: 1	-	10.0 GB	9.8 GB	97.6%	140	34.17	-

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	hasname-out.feeder.erje.net	205993	190348	14881	1	763
2	news.nntp4.net	276	274	1	0	1
3	usenet.blueworldhosting.com	123	103	0	0	20
4	hasname-out.news.weretis.net	77	57	1	0	19
TOTAL: 4		206469	190782	14883	1	803

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.binaries.friends	126669
alt.binaries.pwp	5149
alt.binaries.encrypted	4013
alt.binaries.font	3370
alt.binaries.ath	2279
alt.binaries.wtfnzb.beta	1658
alt.binaries.wtfnzb.lima	1653
alt.binaries.wtfnzb.delta	1647
alt.binaries.wtfnzb.echo	1636
alt.binaries.bungabunga	1633
alt.binaries.wtfnzb.hotel	1621
alt.binaries.wtfnzb.golf	1614
alt.binaries.wtfnzb.bravo	1606
alt.binaries.wtfnzb.foxtrot	1605
alt.binaries.wtfnzb.mike	1598
alt.binaries.wtfnzb.alpha	1597
alt.binaries.wtfnzb.juliet	1597
alt.binaries.wtfnzb.novemeber	1586
alt.binaries.wtfnzb.kilo	1569
alt.binaries.wtfnzb.charlie	1544
TOTAL: 191	190782

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

Groups	Count
uk.legal.moderated	1
TOTAL: 1	1

INND no permission servers:

System	Conn
103.193.138.76	29
147.185.132.235	2
35.203.211.55	2
165.22.22.224	1
62.60.232.62	1
66.132.186.174	1
TOTAL: 6	36

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	hasname-out.feeder.erje.net	36
	TOTAL: 1	36
Including strange strings
	hasname-out.feeder.erje.net	39
	usenet.blueworldhosting.com	20
	hasname-out.news.weretis.net	19
	news.nntp4.net	1
	TOTAL: 4	79
No colon-space in header field
	hasname-out.feeder.erje.net	724
	TOTAL: 1	724
TOTAL: 3		839

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	bbsgslincom	353	353	0	0	0	100%	20:19:24
2	weretis	3085	1	3080	0	0	0%	23:58:47
3	blueworldhosting	480394	0	474426	161	62	0%	23:59:56
4	erje	5935	0	5935	0	75	0%	23:59:56
5	nntp4	5886	0	5884	0	22	0%	23:59:56
TOTAL: 5		495653	354	489325	161	159	0%	116:17:59

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	bbsgslincom	1.1 MB	0.0 KB	1.1 MB	3.1 KB	20:19:24
2	weretis	4.6 KB	0.0 KB	4.6 KB	4.6 KB	23:58:47
3	blueworldhosting	0.0 KB	404.9 KB	404.9 KB	2.5 KB	23:59:56
4	erje	0.0 KB	0.0 KB	0.0 KB	0	23:59:56
5	nntp4	0.0 KB	0.0 KB	0.0 KB	0	23:59:56
TOTAL: 5		1.1 MB	404.9 KB	1.5 MB	2.9 KB	116:17:59

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
prod57client01.academyforinternetresearch.org	0.021	0.045	00:00:03
195.140.214.21	0.001	0.004	00:00:00
185.242.226.72	0.000	0.004	00:00:00
intelligent.monitoring.internet-measurement.com	0.000	0.005	00:00:00
195.140.214.20	0.000	0.005	00:00:00
195.140.214.23	0.001	0.004	00:00:00
195.140.214.25	0.000	0.004	00:00:00
37.10.113.220	0.002	0.003	00:00:00
42.240.141.242	0.000	0.004	00:00:00
azpdcgewr0bm.stretchoid.com	0.001	0.007	00:00:00
o045.scanner.modat.io	0.000	0.004	00:00:00
231.224.132.66.censys-scanner.com	0.004	0.001	00:00:00
198.235.24.26	0.000	0.004	00:00:00
TOTAL: 13	0.030	0.094	00:00:06

NNRP no permission clients [Top 20]:

System	Conn
prod57client01.academyforinternetresearch.org	16
azpdcgewr0bm.stretchoid.com	2
185.242.226.72	1
195.140.214.20	1
195.140.214.21	1
195.140.214.23	1
195.140.214.25	1
198.235.24.26	1
231.224.132.66.censys-scanner.com	1
37.10.113.220	1
42.240.141.242	1
intelligent.monitoring.internet-measurement.com	1
o045.scanner.modat.io	1
TOTAL: 13	29

Daily Usenet report

Apr 22 06:01:01 -- Apr 23 06:01:01