Daily Usenet report for news.niel.me: Jun 1 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jun 1 04:35:33 rpi-studio innd: filter: No Cancel-Key[sha1:cy9XlNIirckancfArIcg3iVriH8= sha256:4gdso7mGbYtTFxcg/lefOV6GxCZcXFAdnq8z/ftLQ+c=] matches Cancel-Lock[sha1:U3otNeLm3BQdhUE1nZynXeHeOSg= sha256:dGyKyvb5iKyt9x/lXadJSc3EEgN/WkOIJbl0eiGnKbQ=] target=<t76j0f$36rr$1@bwh01.blueworldhosting.com>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	4612	44.4%	878.2 KB	50.2%
innd	2474	23.8%	410.0 KB	23.4%
innfeed	1717	16.5%	304.8 KB	17.4%
nnrpd	1575	15.2%	157.4 KB	9.0%
nocem	3	0.0%	0.3 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	10382	100.0%	1.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	26027	62.0%
Negative hits	11407	27.2%
Do not exist	4531	10.8%
Cache misses	12	0.0%
TOTAL: 4	41977	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.053	0.0%	25	0.333	2.120	6.000
article cancel	00:00:00.005	0.0%	27	0.000	0.185	1.000
article cleanup	00:00:00.098	0.0%	4538	0.000	0.022	0.133
article logging	00:00:00.357	0.0%	4558	0.000	0.078	0.206
article parse	00:00:00.974	0.0%	8925	0.000	0.109	0.304
article write	00:00:02.445	0.0%	4020	0.143	0.608	1.571
artlog/artcncl	00:00:00.000	0.0%	27	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.002	0.0%	25	0.000	0.080	1.000
data move	00:00:00.110	0.0%	45505	0.000	0.002	0.022
hisgrep/artcncl	00:00:00.001	0.0%	27	0.000	0.037	0.500
hisgrep/artcncl/perl	00:00:00.002	0.0%	25	0.000	0.080	1.000
hisgrep/perl	00:00:00.001	0.0%	26	0.000	0.038	0.500
hishave/artcncl	00:00:00.001	0.0%	27	0.000	0.037	0.500
hishave/artcncl/perl	00:00:00.002	0.0%	25	0.000	0.080	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:00.600	0.0%	41993	0.000	0.014	0.039
history sync	00:00:00.040	0.0%	402	0.000	0.100	0.667
history write	00:00:07.069	0.0%	4538	0.000	1.558	167.583
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:21.347	99.9%	46358	854.588	1864.216	7326.207
nntp read	00:00:01.471	0.0%	45741	0.004	0.032	0.071
overview write	00:00:02.840	0.0%	4020	0.048	0.706	6.000
perl filter	00:00:20.185	0.0%	4538	2.577	4.448	9.652
python filter	00:00:00.007	0.0%	4538	0.000	0.002	0.048
site send	00:00:01.281	0.0%	8125	0.000	0.158	0.400
TOTAL: 24:01:12.180	24:00:58.891	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.048	0.0%	4113	0.000	0.012	0.087
article prepare	00:00:00.000	0.0%	108	0.000	0.000	0.000
article read	00:00:00.011	0.0%	25	0.000	0.440	3.000
backlog stats	00:00:00.144	0.0%	56469	0.000	0.003	0.014
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.765	0.0%	35476	0.000	0.022	0.055
data write	00:00:01.967	0.0%	31574	0.011	0.062	0.102
idle	23:50:01.921	100.0%	56469	626.987	1519.452	4358.848
status file	00:00:00.514	0.0%	184	1.000	2.793	10.000
TOTAL: 23:50:08.261	23:50:05.370	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:52.018	47.4%	1477	0.000	35.219	10014.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.087	0.1%	3336	0.000	0.026	0.273
TOTAL: 00:01:49.721	00:00:52.105	47.5%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	149
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	171

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news-out.aioe.org	1	4445	2135	2251	59	48%	26:48:21
2	i2pn.org	5	4448	1197	3112	139	26%	24:50:42
3	feed-out.gegeweb.org	3	2684	330	2354	0	12%	23:59:02
4	usenet.pasdenom.info	2	3193	320	2848	25	10%	25:31:55
5	news.samoylyk.net	5	6631	130	6445	56	1%	40:39:34
6	gegeweb-out.news.tnetconsulting.net	1	3905	94	3810	1	2%	23:59:54
7	nntp.terraraq.uk	1	3565	66	3457	42	1%	23:59:54
8	news.nntp4.net	1	3980	43	3780	157	1%	21:01:07
9	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 9		45	32851	4315	28057	479	13%	210:50:29

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.aioe.org	7.7 MB	198.2 KB	7.9 MB	97%	3.7 KB
2	news.samoylyk.net	5.0 MB	138.7 KB	5.1 MB	97%	28.1 KB
3	i2pn.org	4.1 MB	461.9 KB	4.6 MB	90%	3.5 KB
4	news.nntp4.net	1.3 MB	511.5 KB	1.8 MB	72%	9.2 KB
5	feed-out.gegeweb.org	927.0 KB	0.0 KB	927.0 KB	100%	2.8 KB
6	usenet.pasdenom.info	922.1 KB	74.6 KB	996.7 KB	92%	2.9 KB
7	gegeweb-out.news.tnetconsulting.net	854.7 KB	1.0 KB	855.7 KB	99%	9.0 KB
8	nntp.terraraq.uk	460.6 KB	125.5 KB	586.1 KB	78%	5.4 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		21.2 MB	1.5 MB	22.7 MB	93%	4.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 1 04:15:01 - 04:59:59	78	1.9%	0.03	200.9 KB	1.1%	0.07
Jun 1 05:00:00 - 05:59:59	147	3.7%	0.04	1.3 MB	7.4%	0.38
Jun 1 06:00:00 - 06:59:59	134	3.3%	0.04	1.6 MB	8.9%	0.46
Jun 1 07:00:00 - 07:59:59	156	3.9%	0.04	1.1 MB	6.0%	0.31
Jun 1 08:00:00 - 08:59:59	148	3.7%	0.04	720.2 KB	3.9%	0.20
Jun 1 09:00:00 - 09:59:59	180	4.5%	0.05	954.8 KB	5.1%	0.27
Jun 1 10:00:00 - 10:59:59	107	2.7%	0.03	350.7 KB	1.9%	0.10
Jun 1 11:00:00 - 11:59:59	139	3.5%	0.04	450.3 KB	2.4%	0.13
Jun 1 12:00:00 - 12:59:59	133	3.3%	0.04	523.3 KB	2.8%	0.15
Jun 1 13:00:00 - 13:59:59	171	4.3%	0.05	574.1 KB	3.1%	0.16
Jun 1 14:00:00 - 14:59:59	136	3.4%	0.04	464.5 KB	2.5%	0.13
Jun 1 15:00:00 - 15:59:59	147	3.7%	0.04	455.6 KB	2.5%	0.13
Jun 1 16:00:00 - 16:59:59	169	4.2%	0.05	522.8 KB	2.8%	0.15
Jun 1 17:00:00 - 17:59:59	229	5.7%	0.06	862.4 KB	4.6%	0.24
Jun 1 18:00:00 - 18:59:59	236	5.9%	0.07	2.1 MB	11.4%	0.59
Jun 1 19:00:00 - 19:59:59	243	6.0%	0.07	836.3 KB	4.5%	0.23
Jun 1 20:00:00 - 20:59:59	192	4.8%	0.05	649.7 KB	3.5%	0.18
Jun 1 21:00:00 - 21:59:59	239	5.9%	0.07	704.5 KB	3.8%	0.20
Jun 1 22:00:00 - 22:59:59	270	6.7%	0.07	807.4 KB	4.4%	0.22
Jun 1 23:00:00 - 23:59:59	190	4.7%	0.05	654.2 KB	3.5%	0.18
Jun 2 00:00:00 - 00:59:59	133	3.3%	0.04	464.1 KB	2.5%	0.13
Jun 2 01:00:00 - 01:59:59	183	4.5%	0.05	1.1 MB	6.2%	0.32
Jun 2 02:00:00 - 02:59:59	103	2.6%	0.03	499.8 KB	2.7%	0.14
Jun 2 03:00:00 - 03:59:59	123	3.1%	0.03	355.6 KB	1.9%	0.10
Jun 2 04:00:00 - 04:15:01	36	0.9%	0.04	100.6 KB	0.5%	0.11
TOTAL: 24:00:00	4022	100.0%	0.05	18.1 MB	100.0%	0.21

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	203	0	203
2	i2pn.org	136	0	136
3	nntp.terraraq.uk	90	0	90
4	news-out.aioe.org	74	1	73
5	usenet.pasdenom.info	20	0	20
6	news.samoylyk.net	13	0	13
7	gegeweb-out.news.tnetconsulting.net	2	0	2
TOTAL: 7		538	1	537

Unwanted newsgroups [Top 20]:

Newsgroup	Count
news.test	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (phn nph)	205
Bad path (news.giganews.com.POSTED)	179
EMP (md5)	70
EMP (phl)	57
Cancels abusifs	4
No Cancel-Key matches Cancel-Lock. target=<t76j0f$36rr$1@bwh01.blueworldhosting.com>	1
HTML Multipart	1
TOTAL: 7	517

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	2	0	2	2
TOTAL: 1	2	0	2	2

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news-out.aioe.org	17
	news.samoylyk.net	2
	i2pn.org	1
	TOTAL: 3	20
TOTAL: 1		20

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.gegeweb.org	3725	10	3679	0	0	0%	23:59:37
2	news.tnetconsulting.net	6599	7	4012	1	12	0%	23:59:29
3	i2pn.org	5301	6	2810	1	107	0%	23:59:57
4	news.nntp4.net	4114	3	3972	0	0	0%	23:59:57
5	news.pasdenom.info	3614	0	3580	0	0	0%	23:59:57
6	news.aioe.org	1643	0	1536	0	0	0%	23:57:12
7	nntp.terraraq.uk	3047	0	2977	0	0	0%	23:59:28
8	news.samoylyk.net	4041	0	3936	0	0	0%	23:59:57
TOTAL: 8		32084	26	26502	2	119	0%	191:55:34

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.gegeweb.org	61.0 KB	0.0 KB	61.0 KB	6.1 KB	23:59:37
2	news.tnetconsulting.net	18.2 KB	65.9 KB	84.1 KB	10.5 KB	23:59:29
3	i2pn.org	15.3 KB	1.7 KB	17.0 KB	2.4 KB	23:59:57
4	news.nntp4.net	7.9 KB	0.0 KB	7.9 KB	2.6 KB	23:59:57
5	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:59:57
6	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	23:57:12
7	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:59:28
8	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0	23:59:57
TOTAL: 8		102.4 KB	67.6 KB	170.0 KB	6.1 KB	191:55:34

NNRP readership statistics:

System		Conn	Elapsed
1	ec2-35-88-8-145.us-west-2.compute.amazonaws.com	1	00:00:00
2	167.99.178.52	8	00:00:01
3	mail.gegeweb.eu	288	00:01:26
4	23.225.180.203	2	00:00:14
5	ec2-13-52-74-147.us-west-1.compute.amazonaws.com	1	00:00:00
6	tor38.quintex.com	1	00:00:03
7	88.165.5.23	5	00:00:01
TOTAL: 7		306	00:01:49

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.gegeweb.eu	288	00:01:26
2	unresolved	15	00:00:17
3	*.quintex.com	1	00:00:03
4	*.us-west-2.compute.amazonaws.com	1	00:00:00
5	*.us-west-1.compute.amazonaws.com	1	00:00:00
TOTAL: 5		306	00:01:49

NNRP auth users [Top 20]:

User	Conn
mailman	288
TOTAL: 1	288

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mail.gegeweb.eu	7.720	8.166	2.390	00:01:26
23.225.180.203	0.058	0.051	0.000	00:00:14
tor38.quintex.com	0.022	0.033	0.000	00:00:03
167.99.178.52	0.216	0.233	0.000	00:00:01
88.165.5.23	0.139	0.132	0.000	00:00:01
ec2-35-88-8-145.us-west-2.compute.amazonaws.com	0.064	0.011	0.000	00:00:00
ec2-13-52-74-147.us-west-1.compute.amazonaws.com	0.025	0.025	0.000	00:00:00
TOTAL: 7	8.244	8.651	2.390	00:01:49

NNRP unrecognized commands (by host):

System	Conn
167.99.178.52	42
23.225.180.203	2
TOTAL: 2	44

NNRP unrecognized commands (by command):

Command	Count
Connection: keep-alive	7
Host: 45.13.105.36:119	7
Accept: /	7
Accept-Encoding: gzip, deflate	7
User-Agent: Mozilla/5.0 (Windows NT 10.0...	6
GET /stalker_portal/c/version.js HTTP/1....	1
User-Agent: Roku/DVP-9.10 (289.10E04111A...	1
GET /system_api.php HTTP/1.1	1
#001	1
GET /streaming/clients_live.php HTTP/1.1	1
GET /c/version.js HTTP/1.1	1
GET /flu/403.html HTTP/1.1	1
#026#003#001	1
GET / HTTP/1.1	1
GET /stream/live.php HTTP/1.1	1
TOTAL: 15	44

NNRP client timeouts [Top 20]:

System	Conn	Peer
23.225.180.203	1	1
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

Jun 1 04:15:01 -- Jun 2 04:15:01