Daily Usenet report: Dec 20 03:00:01

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	235900	64.4%	29.8 MB	68.7%
innd	127620	34.8%	13.3 MB	30.6%
innfeed	2415	0.7%	244.7 KB	0.6%
nnrpd	649	0.2%	64.9 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	366585	100.0%	43.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	235696	49.9%
Negative hits	235610	49.9%
Cache misses	500	0.1%
Positive hits	118	0.0%
TOTAL: 4	471924	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.230	0.0%	502	0.000	0.458	12.000
article cleanup	00:00:01.095	0.0%	235805	0.000	0.005	0.123
article logging	00:00:03.324	0.0%	235827	0.000	0.014	0.120
article parse	00:00:02.160	0.0%	720280	0.000	0.003	0.014
article write	00:05:53.571	0.4%	115514	0.000	3.061	74.441
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
artlog/artwrite	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:00.440	0.0%	745270	0.000	0.001	0.010
hisgrep/artcncl	00:00:00.213	0.0%	502	0.000	0.424	12.000
hishave/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
hissync/artwrite	00:00:00.000	0.0%	0	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:18:57.312	1.3%	472225	0.000	2.408	11.956
history sync	00:00:00.009	0.0%	292	0.000	0.031	6.000
history write	00:04:18.885	0.3%	235801	0.018	1.098	6.519
hiswrite/artparse	00:00:00.001	0.0%	1	1.000	1.000	1.000
idle	23:24:01.471	97.5%	721309	50.671	116.790	249.614
nntp read	00:00:06.225	0.0%	720963	0.000	0.009	0.147
overview write	00:00:14.970	0.0%	115514	0.000	0.130	40.361
perl filter	00:05:03.696	0.4%	235801	0.388	1.288	5.079
python filter	00:00:12.674	0.0%	472220	0.000	0.027	0.377
site send	00:00:00.910	0.0%	115514	0.000	0.008	0.070
TOTAL: 23:59:34.162	23:58:57.186	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.179	0.0%	115489	0.000	0.002	0.047
article prepare	00:00:00.024	0.0%	461500	0.000	0.000	0.004
article read	00:01:01.581	0.1%	115375	0.000	0.534	30.807
backlog stats	00:00:00.153	0.0%	582179	0.000	0.000	0.007
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.210	0.0%	346385	0.000	0.006	0.033
data write	00:00:03.561	0.0%	230965	0.000	0.015	0.070
idle	23:57:59.440	99.9%	582179	70.671	148.201	30091.500
status file	00:00:00.054	0.0%	202	0.000	0.267	2.000
TOTAL: 23:59:13.112	23:59:07.202	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:20.232	53.8%	269	49.000	298.260	10024.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.006	0.0%	1251	0.000	0.005	0.333
readart	00:00:00.227	0.2%	72	0.000	3.153	42.000
TOTAL: 00:02:29.103	00:01:20.465	54.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	chimia.se	0	236614	115565	613	120436	48%	24:00:46
2	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 2		25	236614	115565	613	120436	48%	24:00:46

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	chimia.se	328.1 MB	146.1 KB	299.0 MB	627.2 MB	52%	2.7 KB
2	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 2		328.1 MB	146.1 KB	299.0 MB	627.2 MB	52%	2.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 20 03:00:01 - 03:59:59	3508	3.0%	0.97	7.6 MB	2.3%	2.16
Dec 20 04:00:00 - 04:59:59	3654	3.2%	1.01	8.5 MB	2.6%	2.42
Dec 20 05:00:00 - 05:59:59	4029	3.5%	1.12	9.0 MB	2.7%	2.55
Dec 20 06:00:00 - 06:59:59	4029	3.5%	1.12	8.7 MB	2.6%	2.47
Dec 20 07:00:00 - 07:59:59	4260	3.7%	1.18	9.0 MB	2.7%	2.55
Dec 20 08:00:00 - 08:59:59	4068	3.5%	1.13	9.1 MB	2.8%	2.59
Dec 20 09:00:00 - 09:59:59	3734	3.2%	1.04	8.3 MB	2.5%	2.36
Dec 20 10:00:00 - 10:59:59	4291	3.7%	1.19	9.7 MB	3.0%	2.76
Dec 20 11:00:00 - 11:59:59	4082	3.5%	1.13	10.6 MB	3.2%	3.02
Dec 20 12:00:00 - 12:59:59	4315	3.7%	1.20	9.8 MB	3.0%	2.78
Dec 20 13:00:00 - 13:59:59	3744	3.2%	1.04	8.5 MB	2.6%	2.42
Dec 20 14:00:00 - 14:59:59	4180	3.6%	1.16	9.5 MB	2.9%	2.72
Dec 20 15:00:00 - 15:59:59	3985	3.4%	1.11	10.7 MB	3.3%	3.06
Dec 20 16:00:00 - 16:59:59	4179	3.6%	1.16	9.3 MB	2.8%	2.65
Dec 20 17:00:00 - 17:59:59	6959	6.0%	1.93	32.5 MB	9.9%	9.24
Dec 20 18:00:00 - 18:59:59	8331	7.2%	2.31	65.2 MB	19.9%	18.55
Dec 20 19:00:00 - 19:59:59	7745	6.7%	2.15	17.9 MB	5.5%	5.10
Dec 20 20:00:00 - 20:59:59	7006	6.1%	1.95	15.9 MB	4.9%	4.54
Dec 20 21:00:00 - 21:59:59	7236	6.3%	2.01	17.7 MB	5.4%	5.05
Dec 20 22:00:00 - 22:59:59	5417	4.7%	1.50	12.3 MB	3.7%	3.49
Dec 20 23:00:00 - 23:59:59	4712	4.1%	1.31	10.7 MB	3.3%	3.04
Dec 21 00:00:00 - 00:59:59	4076	3.5%	1.13	9.3 MB	2.8%	2.66
Dec 21 01:00:00 - 01:59:59	3860	3.3%	1.07	8.5 MB	2.6%	2.43
Dec 21 02:00:00 - 02:59:59	4117	3.6%	1.14	9.6 MB	2.9%	2.73
Dec 21 03:00:00 - 03:00:01	3	0.0%	3.00	4.2 KB	0.0%	4.17
TOTAL: 24:00:00	115520	100.0%	1.34	328.0 MB	100.0%	3.89

Sites sending bad articles:

Server		Total	Group	Dist	Duplic	Unapp	TooOld	Site	Line	Other
1	chimia.se	120380	0	0	4	0	0	0	0	120376
TOTAL: 1		120380	0	0	4	0	0	0	0	120376

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	97231
EMP (phn nph)	16265
EMP (phl)	4401
EMP (md5)	1440
HTML Multipart	574
Too many newsgroups	117
Too many newsgroups (meow)	61
Too many newsgroups (low_xpost)	55
Subject Rejected	53
Excessive Supersedes (24.102.206.156)	30
MI5 Filter	29
Excessive Supersedes (24.102.192.136)	26
EMP (fsl)	25
Excessive Supersedes (66.57.92.44)	10
Scoring filter	9
HTML post	7
Excessive Supersedes (24.102.206.53)	4
Excessive Supersedes (24.102.134.106)	3
Excessive Supersedes (72.141.204.31)	3
Binary: misplaced binary	2
TOTAL: 26	120353

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	chimia.se	44
	TOTAL: 1	44
Huge articles
	chimia.se	1
	TOTAL: 1	1
Including strange strings
	chimia.se	22
	TOTAL: 1	22
TOTAL: 3		67

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	sklaffkom.se	115517	142	111	115264	0	0%	23:59:58
2	sklaff.dev	0	0	0	0	115517	0%	23:57:42
3	thebunker.nu	0	0	0	0	115517	0%	23:59:58
4	usenet.blueworldhosting.com	0	0	0	0	52684	0%	23:59:57
TOTAL: 4		115517	142	111	115264	283718	0%	95:57:35

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	sklaffkom.se	437.9 KB	327.4 MB	327.9 MB	3.9 KB/s	2.9 KB	23:59:58
2	sklaff.dev	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:42
3	thebunker.nu	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:58
4	usenet.blueworldhosting.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:57
TOTAL: 4		437.9 KB	327.4 MB	327.9 MB	1.0 KB/s	2.9 KB	95:57:35

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	66.61.98.95	72	72	261.8 KB	36	0	0	00:01:10
TOTAL: 1		72	72	261.8 KB	36	0	0	00:02:29

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	88	72	261.8 KB	36	00:02:00
2	*.scanner.modat.io	6	0	0.0 KB	0	00:00:01
3	*.150.216.162.bc.googleusercontent.com	2	0	0.0 KB	0	00:00:00
4	*.210.203.35.bc.googleusercontent.com	2	0	0.0 KB	0	00:00:14
5	*.ip.linodeusercontent.com	2	0	0.0 KB	0	00:00:00
6	*.stretchoid.com	2	0	0.0 KB	0	00:00:10
7	*.211.203.35.bc.googleusercontent.com	1	0	0.0 KB	0	00:00:00
8	*.scan.bufferover.run	1	0	0.0 KB	0	00:00:00
TOTAL: 8		104	72	261.8 KB	36	00:02:29

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
66.61.98.95	0.635	0.369	00:01:10
147.185.133.18	0.085	0.009	00:00:39
215.210.203.35.bc.googleusercontent.com	0.083	0.008	00:00:14
azpdwsx3ote8.stretchoid.com	0.014	0.013	00:00:10
199.45.154.123	0.055	0.015	00:00:04
147.185.132.60	0.007	0.006	00:00:03
o606.scanner.modat.io	0.198	0.025	00:00:01
104.152.52.240	0.041	0.004	00:00:00
104.152.52.239	0.272	0.020	00:00:00
reykjavik.scan.bufferover.run	0.042	0.008	00:00:00
172-236-228-245.ip.linodeusercontent.com	0.085	0.007	00:00:00
43.150.216.162.bc.googleusercontent.com	0.041	0.004	00:00:00
198.235.24.215	0.006	0.007	00:00:00
75.211.203.35.bc.googleusercontent.com	0.045	0.003	00:00:00
212.102.40.218	0.162	0.013	00:00:00
69.150.216.162.bc.googleusercontent.com	0.007	0.004	00:00:00
o302.scanner.modat.io	0.007	0.005	00:00:00
TOTAL: 17	1.785	0.520	00:02:29

Curious NNRP server explorers [Top 20]:

System	Conn
o606.scanner.modat.io	5
104.152.52.239	4
212.102.40.218	4
199.45.154.123	3
147.185.133.18	2
172-236-228-245.ip.linodeusercontent.com	2
215.210.203.35.bc.googleusercontent.com	2
azpdwsx3ote8.stretchoid.com	2
104.152.52.240	1
147.185.132.60	1
198.235.24.215	1
43.150.216.162.bc.googleusercontent.com	1
69.150.216.162.bc.googleusercontent.com	1
75.211.203.35.bc.googleusercontent.com	1
o302.scanner.modat.io	1
reykjavik.scan.bufferover.run	1
TOTAL: 16	32

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	21
TOTAL: 1	21

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

System	Conn
172-236-228-245.ip.linodeusercontent.com	5
147.185.133.18	4
215.210.203.35.bc.googleusercontent.com	4
43.150.216.162.bc.googleusercontent.com	4
69.150.216.162.bc.googleusercontent.com	4
75.211.203.35.bc.googleusercontent.com	4
104.152.52.240	2
199.45.154.123	1
azpdwsx3ote8.stretchoid.com	1
o606.scanner.modat.io	1
TOTAL: 10	30

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

Command	Count
GET / HTTP/1.1	6
Host: 109.238.130.155:563	5
Accept-Encoding: gzip	4
Accept: /	3
User-Agent: Hello from Palo Alto Network...	3
User-Agent: curl/7.68.0	2
EHLO 109.238.130.155	1
EHLO www.censys.io	1
HELO	1
Host: 109.238.130.155:119	1
MAIL FROM:<relaycheck_please_ignore@prot...	1
MGLNDD_109.238.130.155_119	1
User-Agent: Mozilla/5.0 (Macintosh; Inte...	1
TOTAL: 13	30

NNRP client timeouts [Top 20]:

System	Conn	Peer
104.152.52.240	1	1
147.185.133.18	1	2
172-236-228-245.ip.linodeusercontent.com	1	1
199.45.154.123	1	2
215.210.203.35.bc.googleusercontent.com	1	2
43.150.216.162.bc.googleusercontent.com	1	1
75.211.203.35.bc.googleusercontent.com	1	1
o606.scanner.modat.io	1	1
TOTAL: 8	8	13

Newsgroup request counts (by hierarchy):

Hierarchy		Count	Pct
1	rec	72	100.0%
TOTAL: 1		72	100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

Newsgroup		Count
1	rec.collecting	72
TOTAL: 1		72

Daily Usenet report

Dec 20 03:00:01 -- Dec 21 03:00:01