Daily Usenet report: Dec 7 15:00:02

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Dec 7 15:01:26 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
Dec 7 16:25:40.019Dec 7 16:30:17.072 + hasname-out.news.weretis.net <57802.TQ.20211207.173004@teamquest.pl> 4882 overview! aioe nntp4 inpaths!

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	15864	79.8%	1.9 MB	77.3%
innd	2146	10.8%	310.7 KB	12.4%
innfeed	1282	6.4%	178.1 KB	7.1%
cnfsstat	576	2.9%	79.6 KB	3.2%
nnrpd	12	0.1%	1.2 KB	0.0%
overchan	2	0.0%	0.1 KB	0.0%
controlchan	2	0.0%	0.1 KB	0.0%
TOTAL: 7	19884	100.0%	2.5 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	49066	47.0%
Positive hits	40020	38.3%
Do not exist	15386	14.7%
Cache misses	28	0.0%
TOTAL: 4	104500	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.296	0.0%	55	0.000	41.745	722.000
article cleanup	00:00:00.094	0.0%	15447	0.000	0.006	0.071
article logging	00:00:00.522	0.0%	15747	0.000	0.033	0.260
article parse	00:00:00.243	0.0%	29074	0.000	0.008	0.032
article write	00:00:01.664	0.0%	14867	0.000	0.112	3.066
artlog/artcncl	00:00:00.001	0.0%	54	0.000	0.019	0.167
data move	00:00:00.050	0.0%	113973	0.000	0.000	0.003
hisgrep/artcncl	00:00:00.000	0.0%	55	0.000	0.000	0.000
hishave/artcncl	00:00:00.889	0.0%	55	0.000	16.164	71.167
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:04:55.058	0.3%	104830	0.004	2.815	57.437
history sync	00:00:00.009	0.0%	384	0.000	0.023	0.500
history write	00:00:02.152	0.0%	15442	0.000	0.139	8.984
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:45:01.221	99.6%	113402	293.277	753.966	1905.841
nntp read	00:00:00.882	0.0%	114900	0.000	0.008	0.017
overview write	00:00:00.053	0.0%	14867	0.000	0.004	0.034
perl filter	00:00:30.328	0.0%	15442	0.927	1.964	56.012
python filter	00:00:00.373	0.0%	15442	0.000	0.024	0.085
site send	00:00:02.731	0.0%	44600	0.030	0.061	0.090
TOTAL: 23:50:54.081	23:50:38.566	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.030	0.0%	14815	0.000	0.002	0.041
article prepare	00:00:00.000	0.0%	843	0.000	0.000	0.000
article read	00:00:00.023	0.0%	431	0.000	0.053	1.000
backlog stats	00:00:00.046	0.0%	70297	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.360	0.0%	46336	0.000	0.008	0.027
data write	00:00:00.875	0.0%	31857	0.000	0.027	0.080
idle	23:47:15.824	100.0%	70297	494.791	1218.200	3552.124
status file	00:00:01.268	0.0%	280	0.000	4.529	211.000
TOTAL: 23:47:21.004	23:47:18.426	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.000	0.0%	3	0.000	0.000	0.000
TOTAL: 00:00:01.070	00:00:00.000	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
reserve	2
TOTAL: 10	167

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	hasname-out.news.weretis.net	8	27300	7452	19781	67	27%	48:40:49
2	news-out.aioe.org	3	12941	6309	6628	4	48%	23:07:03
3	news.nntp4.net	2	14315	902	12937	476	6%	22:48:33
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		14	54556	14663	39346	547	26%	94:36:25

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.news.weretis.net	23.5 MB	2.4 KB	101.6 KB	23.6 MB	99%	3.2 KB
2	news-out.aioe.org	16.7 MB	9.8 KB	9.8 KB	16.7 MB	99%	2.7 KB
3	news.nntp4.net	2.5 MB	0.0 KB	2.0 MB	4.5 MB	56%	3.3 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		42.8 MB	12.1 KB	2.1 MB	44.8 MB	95%	3.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 7 15:00:02 - 15:59:59	823	5.5%	0.23	2.3 MB	5.3%	0.65
Dec 7 16:00:00 - 16:59:59	789	5.3%	0.22	2.2 MB	5.0%	0.62
Dec 7 17:00:00 - 17:59:59	863	5.8%	0.24	2.5 MB	5.8%	0.71
Dec 7 18:00:00 - 18:59:59	956	6.4%	0.27	3.0 MB	7.0%	0.87
Dec 7 19:00:00 - 19:59:59	866	5.8%	0.24	2.5 MB	5.8%	0.72
Dec 7 20:00:00 - 20:59:59	879	5.9%	0.24	2.3 MB	5.2%	0.64
Dec 7 21:00:00 - 21:59:59	934	6.3%	0.26	2.6 MB	5.9%	0.73
Dec 7 22:00:00 - 22:59:59	875	5.9%	0.24	2.5 MB	5.7%	0.70
Dec 7 23:00:00 - 23:59:59	422	2.8%	0.12	1.4 MB	3.3%	0.41
Dec 8 00:00:00 - 00:59:59	316	2.1%	0.09	1.1 MB	2.4%	0.30
Dec 8 01:00:00 - 01:59:59	298	2.0%	0.08	993.9 KB	2.2%	0.28
Dec 8 02:00:00 - 02:59:59	285	1.9%	0.08	948.6 KB	2.1%	0.26
Dec 8 03:00:00 - 03:59:59	268	1.8%	0.07	977.1 KB	2.2%	0.27
Dec 8 04:00:00 - 04:59:59	318	2.1%	0.09	1.2 MB	2.8%	0.35
Dec 8 05:00:00 - 05:59:59	330	2.2%	0.09	1.2 MB	2.7%	0.33
Dec 8 06:00:00 - 06:59:59	368	2.5%	0.10	1.1 MB	2.5%	0.31
Dec 8 07:00:00 - 07:59:59	400	2.7%	0.11	1.5 MB	3.4%	0.41
Dec 8 08:00:00 - 08:59:59	554	3.7%	0.15	1.5 MB	3.5%	0.44
Dec 8 09:00:00 - 09:59:59	667	4.5%	0.19	1.7 MB	4.0%	0.49
Dec 8 10:00:00 - 10:59:59	675	4.5%	0.19	1.7 MB	4.0%	0.50
Dec 8 11:00:00 - 11:59:59	740	5.0%	0.21	1.8 MB	4.1%	0.51
Dec 8 12:00:00 - 12:59:59	690	4.6%	0.19	1.9 MB	4.3%	0.53
Dec 8 13:00:00 - 13:59:59	789	5.3%	0.22	2.3 MB	5.2%	0.64
Dec 8 14:00:00 - 15:00:02	816	5.5%	0.23	2.4 MB	5.5%	0.68
TOTAL: 24:00:00	14921	100.0%	0.17	43.4 MB	100.0%	0.51

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
FOUR	DEFAULT	1024.0 MB	383.6 MB	37.5%	4	0.22	55.69
ONE	DEFAULT	1024.0 MB	546.8 MB	53.4%	6	0.22	55.22
THREE	DEFAULT	1024.0 MB	387.2 MB	37.8%	4	0.23	53.62
TWO	DEFAULT	1024.0 MB	381.7 MB	37.3%	4	0.22	55.71
TOTAL: 4	-	4.0 GB	1.7 GB	41.5%	18	0.22	-

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nntp4.net	518	5	0	1	512
2	hasname-out.news.weretis.net	307	2	1	0	304
3	news-out.aioe.org	64	0	4	0	60
TOTAL: 3		889	7	5	1	876

Unwanted newsgroups [Top 20]:

Newsgroup	Count
panix.chat.politics	2
panix.test	1
alt.ads	1
alt.penthouse.sex.first-time	1
alt.books.harry-turtledove	1
comp.infosystems.gemini	1
TOTAL: 6	7

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 (md5)	349
EMP (phn path)	140
EMP (phn nph)	45
Too many newsgroups	30
User-issued spam cancel	5
HTML Multipart	4
EMP (phl)	3
U2 violation - invalid distribution	2
Binary: misplaced binary	2
TOTAL: 9	580

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	hasname-out.news.weretis.net	233
	news-out.aioe.org	60
	news.nntp4.net	6
	TOTAL: 3	299
TOTAL: 1		299

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	bbsgslincom	295	295	0	0	0	100%	17:40:05
2	aioe	8946	8	8114	1	0	0%	23:50:14
3	nntp4	14672	5	13889	0	15	0%	23:50:14
4	weretis	7550	0	7356	123	1	0%	23:50:14
TOTAL: 4		31463	308	29359	124	16	0%	89:10:47

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	bbsgslincom	1.1 MB	0.0 KB	1.1 MB	3.8 KB	17:40:05
2	aioe	27.9 KB	4.1 KB	32.0 KB	3.6 KB	23:50:14
3	nntp4	20.5 KB	0.0 KB	20.5 KB	4.1 KB	23:50:14
4	weretis	0.0 KB	549.3 KB	549.3 KB	4.5 KB	23:50:14
TOTAL: 4		1.1 MB	553.4 KB	1.7 MB	4.0 KB	89:10:47

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
news.nntp4.net	0.002	0.004	00:00:01
scanner-26.ch1.censys-scanner.com	0.002	0.003	00:00:00
TOTAL: 2	0.004	0.007	00:00:01

NNRP no permission clients:

System	Conn
news.nntp4.net	2
scanner-26.ch1.censys-scanner.com	1
TOTAL: 2	3

Daily Usenet report

Dec 7 15:00:02 -- Dec 8 15:00:02