Daily Usenet report: Sep 3 03:00:03

Unknown entries from news log file:

First 50 / 60 lines (83.3%)

Sep 3 03:01:01 twilightnode innd: message repeated 2 times: [ ctlinnd command s]
Sep 3 09:59:25 twilightnode nnrpd[2684588]: Reading access from /usr/local/news/etc/readers.conf
Sep 3 09:59:25 twilightnode nnrpd[2684588]: Auth strategy 'localhost' does not match client. Removing.
Sep 3 09:59:25 twilightnode nnrpd[2684588]: ec2-54-183-240-95.us-west-1.compute.amazonaws.com res <anonymous>
Sep 3 09:59:25 twilightnode nnrpd[2684588]: ec2-54-183-240-95.us-west-1.compute.amazonaws.com no_match_user <anonymous> <localhost>
Sep 3 09:59:25 twilightnode nnrpd[2684588]: ec2-54-183-240-95.us-west-1.compute.amazonaws.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 3 09:59:25 twilightnode nnrpd[2684588]: ec2-54-183-240-95.us-west-1.compute.amazonaws.com match_user <anonymous> <anonymous>
Sep 3 09:59:25 twilightnode nnrpd[2684588]: ec2-54-183-240-95.us-west-1.compute.amazonaws.com no_post anonymous-read
Sep 3 11:00:24 twilightnode nnrpd[2687363]: Reading access from /usr/local/news/etc/readers.conf
Sep 3 11:00:24 twilightnode nnrpd[2687363]: Auth strategy 'localhost' does not match client. Removing.
Sep 3 11:00:24 twilightnode nnrpd[2687363]: o034.scanner.modat.io res <anonymous>
Sep 3 11:00:24 twilightnode nnrpd[2687363]: o034.scanner.modat.io no_match_user <anonymous> <localhost>
Sep 3 11:00:24 twilightnode nnrpd[2687363]: o034.scanner.modat.io no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 3 11:00:24 twilightnode nnrpd[2687363]: o034.scanner.modat.io match_user <anonymous> <anonymous>
Sep 3 11:00:24 twilightnode nnrpd[2687363]: o034.scanner.modat.io no_post anonymous-read
Sep 3 14:16:43 twilightnode nnrpd[2695620]: Reading access from /usr/local/news/etc/readers.conf
Sep 3 14:16:43 twilightnode nnrpd[2695620]: Auth strategy 'localhost' does not match client. Removing.
Sep 3 14:16:43 twilightnode nnrpd[2695620]: 193-32-209-230.infrawat.ch res <anonymous>
Sep 3 14:16:43 twilightnode nnrpd[2695620]: 193-32-209-230.infrawat.ch no_match_user <anonymous> <localhost>
Sep 3 14:16:43 twilightnode nnrpd[2695620]: 193-32-209-230.infrawat.ch no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 3 14:16:43 twilightnode nnrpd[2695620]: 193-32-209-230.infrawat.ch match_user <anonymous> <anonymous>
Sep 3 14:16:43 twilightnode nnrpd[2695620]: 193-32-209-230.infrawat.ch no_post anonymous-read
Sep 3 14:16:52 twilightnode nnrpd[2695621]: Reading access from /usr/local/news/etc/readers.conf
Sep 3 14:16:52 twilightnode nnrpd[2695621]: Auth strategy 'localhost' does not match client. Removing.
Sep 3 14:16:52 twilightnode nnrpd[2695621]: 31-14-254-84.infrawat.ch res <anonymous>
Sep 3 14:16:52 twilightnode nnrpd[2695621]: 31-14-254-84.infrawat.ch no_match_user <anonymous> <localhost>
Sep 3 14:16:52 twilightnode nnrpd[2695621]: 31-14-254-84.infrawat.ch no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 3 14:16:52 twilightnode nnrpd[2695621]: 31-14-254-84.infrawat.ch match_user <anonymous> <anonymous>
Sep 3 14:16:52 twilightnode nnrpd[2695621]: 31-14-254-84.infrawat.ch no_post anonymous-read
Sep 3 20:31:56 twilightnode nnrpd[2711723]: Reading access from /usr/local/news/etc/readers.conf
Sep 3 20:31:56 twilightnode nnrpd[2711723]: Auth strategy 'localhost' does not match client. Removing.
Sep 3 20:31:56 twilightnode nnrpd[2711723]: 227.224.132.66.censys-scanner.com res <anonymous>
Sep 3 20:31:56 twilightnode nnrpd[2711723]: 227.224.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 3 20:31:56 twilightnode nnrpd[2711723]: 227.224.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 3 20:31:56 twilightnode nnrpd[2711723]: 227.224.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 3 20:31:56 twilightnode nnrpd[2711723]: 227.224.132.66.censys-scanner.com no_post anonymous-read
Sep 3 20:31:58 twilightnode nnrpd[2711723]: 227.224.132.66.censys-scanner.com res <anonymous>
Sep 3 20:31:58 twilightnode nnrpd[2711723]: 227.224.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 3 20:31:58 twilightnode nnrpd[2711723]: 227.224.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 3 20:31:58 twilightnode nnrpd[2711723]: 227.224.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 3 20:31:58 twilightnode nnrpd[2711723]: 227.224.132.66.censys-scanner.com no_post anonymous-read
Sep 3 20:35:07 twilightnode nnrpd[2711981]: Reading access from /usr/local/news/etc/readers.conf
Sep 3 20:35:07 twilightnode nnrpd[2711981]: Auth strategy 'localhost' does not match client. Removing.
Sep 3 20:35:07 twilightnode nnrpd[2711981]: 129.172.132.66.censys-scanner.com res <anonymous>
Sep 3 20:35:07 twilightnode nnrpd[2711981]: 129.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>
Sep 3 20:35:07 twilightnode nnrpd[2711981]: 129.172.132.66.censys-scanner.com no_match_user <anonymous> *,!<anonymous>,!<localhost>
Sep 3 20:35:07 twilightnode nnrpd[2711981]: 129.172.132.66.censys-scanner.com match_user <anonymous> <anonymous>
Sep 3 20:35:07 twilightnode nnrpd[2711981]: 129.172.132.66.censys-scanner.com no_post anonymous-read
Sep 3 20:35:08 twilightnode nnrpd[2711981]: 129.172.132.66.censys-scanner.com res <anonymous>
Sep 3 20:35:08 twilightnode nnrpd[2711981]: 129.172.132.66.censys-scanner.com no_match_user <anonymous> <localhost>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	7332	55.0%	1.3 MB	60.6%
innd	4621	34.6%	655.9 KB	30.4%
innfeed	1297	9.7%	185.9 KB	8.6%
nnrpd	89	0.7%	9.5 KB	0.4%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	13340	100.0%	2.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	20101	52.7%
Negative hits	10751	28.2%
Do not exist	7253	19.0%
Cache misses	4	0.0%
TOTAL: 4	38109	100.0%

innd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.083	0.0%	22	0.429	3.773	15.000
article cleanup	00:00:00.060	0.0%	7285	0.000	0.008	0.071
article logging	00:00:00.254	0.0%	7334	0.000	0.035	0.156
article parse	00:00:00.099	0.0%	9894	0.000	0.010	0.049
article write	00:00:06.220	0.0%	6061	0.556	1.026	2.952
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:00.214	0.0%	40617	0.000	0.005	0.041
hisgrep/artcncl	00:00:00.024	0.0%	22	0.000	1.091	2.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:04.281	0.0%	38277	0.059	0.112	0.246
history sync	00:00:00.068	0.0%	418	0.000	0.163	4.000
history write	00:00:03.270	0.0%	7285	0.152	0.449	0.899
idle	24:00:23.993	99.9%	41599	1134.554	2077.550	8000.987
nntp read	00:00:00.824	0.0%	40556	0.000	0.020	0.113
overview write	00:00:04.056	0.0%	6061	0.197	0.669	2.778
perl filter	00:00:13.581	0.0%	7285	0.926	1.864	8.833
python filter	00:00:00.980	0.0%	7285	0.000	0.135	0.562
site send	00:00:00.242	0.0%	12096	0.000	0.020	0.100
TOTAL: 18	24:00:58.249	100.0%	-	-	-	-

innfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.019	0.0%	6905	0.000	0.003	0.091
article prepare	00:00:00.002	0.0%	25210	0.000	0.000	0.006
article read	00:00:00.964	0.0%	4257	0.000	0.226	1.000
backlog stats	00:00:00.040	0.0%	80608	0.000	0.000	0.009
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.612	0.0%	44733	0.000	0.014	0.059
data write	00:00:00.862	0.0%	38735	0.000	0.022	0.083
idle	23:58:23.053	100.0%	80608	428.701	1070.651	4313.357
status file	00:00:00.140	0.0%	173	0.000	0.809	5.000
TOTAL: 9	23:58:25.692	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:08.609	100.0%	10	188.000	860.900	2534.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.001	0.0%	36	0.000	0.028	0.200
TOTAL: 3	00:00:08.610	100.0%	-	-	-	-

Control commands to innd:

Command	Number
flush	4
flushlogs	2
go	2
logmode	2
lowmark	1
mode	147
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	168

Incoming feeds (innd):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	twilightnode-out.news.weretis.net	5	13499	4934	8364	201	36%	57:12:47
2	usenet.blueworldhosting.com	715	7223	1016	5268	939	14%	23:46:23
3	newsfeed.endofthelinebbs.com	1	5104	282	4705	117	5%	23:21:33
4	news.tebibyte.org	1	1796	48	1748	0	2%	12:58:22
5	news.sklaffkom.se	28	50	40	10	0	80%	06:08:27
6	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 6		775	27672	6320	20095	1257	22%	123:27:32

Incoming volume (innd):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	twilightnode-out.news.weretis.net	14.9 MB	579.5 KB	15.4 MB	96%	3.1 KB
2	usenet.blueworldhosting.com	5.5 MB	1.1 MB	6.7 MB	82%	3.5 KB
3	newsfeed.endofthelinebbs.com	3.0 MB	173.2 KB	3.1 MB	94%	8.1 KB
4	news.tebibyte.org	124.7 KB	0.0 KB	124.7 KB	100%	2.6 KB
5	news.sklaffkom.se	97.1 KB	0.0 KB	97.1 KB	100%	2.4 KB
6	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 6		23.6 MB	1.9 MB	25.5 MB	92%	3.4 KB

Incoming articles (innd):

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 3 03:00:03 - 03:59:59	118	1.9%	0.03	572.5 KB	2.3%	0.16
Sep 3 04:00:00 - 04:59:59	199	3.3%	0.06	803.5 KB	3.3%	0.22
Sep 3 05:00:00 - 05:59:59	165	2.7%	0.05	768.5 KB	3.1%	0.21
Sep 3 06:00:00 - 06:59:59	127	2.1%	0.04	437.2 KB	1.8%	0.12
Sep 3 07:00:00 - 07:59:59	116	1.9%	0.03	515.0 KB	2.1%	0.14
Sep 3 08:00:00 - 08:59:59	232	3.8%	0.06	639.0 KB	2.6%	0.18
Sep 3 09:00:00 - 09:59:59	262	4.3%	0.07	866.8 KB	3.5%	0.24
Sep 3 10:00:00 - 10:59:59	275	4.5%	0.08	1.1 MB	4.4%	0.30
Sep 3 11:00:00 - 11:59:59	333	5.5%	0.09	1.3 MB	5.5%	0.38
Sep 3 12:00:00 - 12:59:59	299	4.9%	0.08	966.1 KB	3.9%	0.27
Sep 3 13:00:00 - 13:59:59	341	5.6%	0.09	1.0 MB	4.3%	0.29
Sep 3 14:00:00 - 14:59:59	337	5.6%	0.09	1.0 MB	4.3%	0.29
Sep 3 15:00:00 - 15:59:59	261	4.3%	0.07	754.7 KB	3.1%	0.21
Sep 3 16:00:00 - 16:59:59	287	4.7%	0.08	990.5 KB	4.0%	0.28
Sep 3 17:00:00 - 17:59:59	337	5.6%	0.09	1.1 MB	4.5%	0.31
Sep 3 18:00:00 - 18:59:59	355	5.9%	0.10	1.2 MB	5.1%	0.35
Sep 3 19:00:00 - 19:59:59	342	5.6%	0.10	1.6 MB	6.7%	0.46
Sep 3 20:00:00 - 20:59:59	290	4.8%	0.08	1.4 MB	6.0%	0.41
Sep 3 21:00:00 - 21:59:59	289	4.8%	0.08	1.1 MB	4.7%	0.32
Sep 3 22:00:00 - 22:59:59	290	4.8%	0.08	2.6 MB	10.8%	0.74
Sep 3 23:00:00 - 23:59:59	276	4.6%	0.08	1.1 MB	4.4%	0.30
Sep 4 00:00:00 - 00:59:59	202	3.3%	0.06	899.9 KB	3.7%	0.25
Sep 4 01:00:00 - 01:59:59	177	2.9%	0.05	812.4 KB	3.3%	0.23
Sep 4 02:00:00 - 02:59:59	140	2.3%	0.04	605.1 KB	2.5%	0.17
Sep 4 03:00:00 - 03:00:03	10	0.2%	3.33	18.6 KB	0.1%	6.21
TOTAL: 24:00:00	6060	100.0%	0.07	24.0 MB	100.0%	0.28

Sites sending bad articles:

Server		Total	Group	Other
1	usenet.blueworldhosting.com	939	333	606
2	twilightnode-out.news.weretis.net	214	1	213
3	newsfeed.endofthelinebbs.com	119	5	114
TOTAL: 3		1272	339	933

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.test.ping	288
hispagatos.hacking.ransomware	25
hispagatos.mastodon.tag.hacking	7
fido.golded	3
local.general	3
alt.asshole.binky	2
hispagatos.mastodon.tag.hackers	2
kraft.politics	2
alt.bbs.files-announce	1
alt.sex.pedophilia	1
chmurka.statystyki	1
fido.ru.space	1
fido.su.pol	1
kraft.vehicles	1
ont.test	1
TOTAL: 15	339

Perl filter (innd) [Top 20]:

Reason	Count
EMP (md5)	637
EMP (phn path)	121
Too many newsgroups	79
User-issued cancel	41
HTML Multipart	6
TOTAL: 5	884

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	twilightnode-out.news.weretis.net	49
	TOTAL: 1	49
TOTAL: 1		49

Outgoing feeds (innfeed) by articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	news.tebibyte.org	7981	2247	3690	83	14	44	28%	23:59:48
2	sklaffkom.se	6153	1034	2091	2894	15	586	16%	23:59:54
3	newsfeed.endofthelinebbs.com	10619	21	5702	6	0	208	0%	23:59:53
4	weretis.net	1416	5	1384	0	0	0	0%	23:47:02
5	usenet.blueworldhosting.com	7972	0	4659	1	0	42	0%	23:59:54
TOTAL: 5		34141	3307	17526	2984	29	880	9%	119:46:31

Outgoing feeds (innfeed) by volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.tebibyte.org	5.7 MB	893.8 KB	6.6 MB	0.1 KB/s	2.9 KB	23:59:48
2	sklaffkom.se	1.7 MB	13.7 MB	15.3 MB	0.2 KB/s	4.0 KB	23:59:54
3	newsfeed.endofthelinebbs.com	45.5 KB	15.1 KB	60.6 KB	0.0 KB/s	2.2 KB	23:59:53
4	weretis.net	9.8 KB	0.0 KB	9.8 KB	0.0 KB/s	2.0 KB	23:47:02
5	usenet.blueworldhosting.com	0.0 KB	2.2 KB	2.2 KB	0.0 KB/s	2.2 KB	23:59:54
TOTAL: 5		7.4 MB	14.5 MB	22.0 MB	0.1 KB/s	3.6 KB	119:46:31

NNRP readership statistics [Top 100]:

System		Conn	Elapsed
1	129.172.132.66.censys-scanner.com	1	00:00:00
2	193-32-209-230.infrawat.ch	1	00:00:00
3	205.210.31.26	1	00:00:02
4	227.224.132.66.censys-scanner.com	1	00:00:03
5	31-14-254-84.infrawat.ch	1	00:00:00
6	ec2-54-183-240-95.us-west-1.compute.amazonaws.com	1	00:00:00
7	o034.scanner.modat.io	1	00:00:00
TOTAL: 7		7	00:00:08

NNRP connection statistics (by domain) [Top 100]:

System		Conn	Elapsed
1	*.66.censys-scanner.com	2	00:00:04
2	*.infrawat.ch	2	00:00:00
3	*.compute.amazonaws.com	1	00:00:00
4	*.scanner.modat.io	1	00:00:00
5	unresolved	1	00:00:02
TOTAL: 5		7	00:00:08

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
227.224.132.66.censys-scanner.com	0.025	0.021	00:00:03
205.210.31.26	0.008	0.015	00:00:02
129.172.132.66.censys-scanner.com	0.026	0.012	00:00:00
o034.scanner.modat.io	0.004	0.010	00:00:00
31-14-254-84.infrawat.ch	0.004	0.005	00:00:00
ec2-54-183-240-95.us-west-1.compute.amazonaws.com	0.005	0.013	00:00:00
193-32-209-230.infrawat.ch	0.006	0.013	00:00:00
TOTAL: 7	0.078	0.089	00:00:08

NNRP unrecognized commands (by host) [Top 20]:

System	Conn
129.172.132.66.censys-scanner.com	1
227.224.132.66.censys-scanner.com	1
TOTAL: 2	2

NNRP unrecognized commands (by command) [Top 20]:

Command	Count
EHLO www.censys.io	2
TOTAL: 1	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
31-14-254-84.infrawat.ch	1	1
TOTAL: 1	1	3

Daily Usenet report

Sep 3 03:00:03 -- Sep 4 03:00:03