Daily Usenet report for news.niel.me: Jan 13 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jan 13 15:42:11 rpi-studio innd: filter: No Cancel-Key[sha1:Rh6sKCxPzb74aqMyME4qbnuFyRw=] matches Cancel-Lock[sha1:lu3/yAIN9d3WusAo//zVclj9ulU=] target=<65a2a0ed$0$10086$426a34cc@news.free.fr>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	14466	63.0%	2.8 MB	70.2%
innd	4350	19.0%	641.7 KB	16.0%
nnrpd	2307	10.1%	250.0 KB	6.2%
innfeed	1830	8.0%	303.9 KB	7.6%
nocem	1	0.0%	0.2 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	22955	100.0%	3.9 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	19934	36.9%
Positive hits	19746	36.5%
Do not exist	14364	26.6%
Cache misses	3	0.0%
TOTAL: 4	54047	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.011	0.0%	11	0.000	1.000	3.000
article cancel	00:00:00.001	0.0%	11	0.000	0.091	1.000
article cleanup	00:00:00.259	0.0%	14458	0.000	0.018	0.052
article logging	00:00:01.097	0.0%	14458	0.012	0.076	0.149
article parse	00:00:04.331	0.0%	76592	0.019	0.057	0.094
article write	00:00:10.434	0.0%	12073	0.479	0.864	1.722
artlog/artcncl	00:00:00.000	0.0%	11	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	11	0.000	0.000	0.000
data move	00:00:00.647	0.0%	132478	0.000	0.005	0.013
hisgrep/artcncl	00:00:00.000	0.0%	11	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	11	0.000	0.000	0.000
hisgrep/perl	00:00:00.000	0.0%	12	0.000	0.000	0.000
hishave/artcncl	00:00:00.001	0.0%	11	0.000	0.091	1.000
hishave/artcncl/perl	00:00:00.001	0.0%	11	0.000	0.091	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.022	0.0%	54348	0.000	0.019	0.037
history sync	00:00:00.059	0.0%	406	0.000	0.145	0.667
history write	00:00:02.699	0.0%	14458	0.062	0.187	0.331
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:59:25.421	99.9%	116464	473.210	741.563	1252.689
nntp read	00:00:03.475	0.0%	115780	0.016	0.030	0.053
overview write	00:00:04.511	0.0%	12073	0.101	0.374	2.632
perl filter	00:00:50.066	0.1%	14458	2.605	3.463	5.306
python filter	00:00:00.062	0.0%	14458	0.000	0.004	0.027
site send	00:00:03.553	0.0%	25323	0.043	0.140	0.363
TOTAL: 24:01:09.240	24:00:47.650	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.112	0.0%	12059	0.000	0.009	0.044
article prepare	00:00:00.014	0.0%	4048	0.000	0.003	0.083
article read	00:00:00.150	0.0%	895	0.000	0.168	1.000
backlog stats	00:00:00.888	0.0%	187469	0.000	0.005	0.009
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.970	0.0%	123121	0.007	0.024	0.038
data write	00:00:05.330	0.0%	96873	0.033	0.055	0.078
idle	23:55:21.899	100.0%	187469	292.065	459.393	720.391
status file	00:00:00.466	0.0%	198	0.500	2.354	10.000
TOTAL: 23:55:41.724	23:55:31.829	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:58.810	40.6%	1449	19.000	40.587	9504.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.110	0.1%	3280	0.000	0.034	1.000
TOTAL: 00:02:24.708	00:00:58.920	40.7%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news.nntp4.net	2	13858	8608	3025	2225	62%	23:45:09
2	news.glou.org	2	3076	1180	1797	99	38%	21:49:07
3	feed-out.gegeweb.org	2	2869	1038	1831	0	36%	23:58:41
4	usenet.pasdenom.info	1	2689	367	2314	8	13%	28:04:55
5	i2pn.org	1	2939	219	2720	0	7%	21:45:02
6	nntp.terraraq.uk	1	2873	202	2662	9	7%	21:08:42
7	news.samoylyk.net	2	5097	67	5030	0	1%	38:30:30
8	gegeweb-out.news.tnetconsulting.net	2	999	46	944	9	4%	10:41:50
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		38	34400	11727	20323	2350	34%	189:43:56

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	106.2 MB	14.0 MB	120.2 MB	88%	11.4 KB
2	feed-out.gegeweb.org	5.2 MB	0.0 KB	5.2 MB	100%	5.2 KB
3	news.glou.org	5.0 MB	253.3 KB	5.2 MB	95%	4.2 KB
4	i2pn.org	2.0 MB	0.0 KB	2.0 MB	100%	9.1 KB
5	usenet.pasdenom.info	1.6 MB	10.5 KB	1.6 MB	99%	4.4 KB
6	nntp.terraraq.uk	902.6 KB	25.3 KB	927.9 KB	97%	4.4 KB
7	news.samoylyk.net	390.0 KB	0.0 KB	390.0 KB	100%	5.8 KB
8	gegeweb-out.news.tnetconsulting.net	113.7 KB	21.8 KB	135.5 KB	83%	2.5 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		121.4 MB	14.3 MB	135.6 MB	89%	9.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 13 04:15:01 - 04:59:59	429	3.6%	0.16	5.0 MB	4.0%	1.90
Jan 13 05:00:00 - 05:59:59	584	4.8%	0.16	6.0 MB	4.8%	1.70
Jan 13 06:00:00 - 06:59:59	496	4.1%	0.14	5.1 MB	4.2%	1.46
Jan 13 07:00:00 - 07:59:59	599	5.0%	0.17	6.6 MB	5.4%	1.89
Jan 13 08:00:00 - 08:59:59	666	5.5%	0.18	6.8 MB	5.5%	1.92
Jan 13 09:00:00 - 09:59:59	708	5.9%	0.20	7.5 MB	6.1%	2.14
Jan 13 10:00:00 - 10:59:59	555	4.6%	0.15	5.4 MB	4.3%	1.52
Jan 13 11:00:00 - 11:59:59	522	4.3%	0.14	5.5 MB	4.5%	1.57
Jan 13 12:00:00 - 12:59:59	523	4.3%	0.15	5.4 MB	4.3%	1.53
Jan 13 13:00:00 - 13:59:59	436	3.6%	0.12	4.0 MB	3.2%	1.14
Jan 13 14:00:00 - 14:59:59	461	3.8%	0.13	4.4 MB	3.5%	1.25
Jan 13 15:00:00 - 15:59:59	447	3.7%	0.12	4.0 MB	3.3%	1.14
Jan 13 16:00:00 - 16:59:59	574	4.8%	0.16	5.3 MB	4.3%	1.49
Jan 13 17:00:00 - 17:59:59	551	4.6%	0.15	5.9 MB	4.8%	1.68
Jan 13 18:00:00 - 18:59:59	483	4.0%	0.13	4.3 MB	3.5%	1.23
Jan 13 19:00:00 - 19:59:59	480	4.0%	0.13	4.9 MB	3.9%	1.39
Jan 13 20:00:00 - 20:59:59	473	3.9%	0.13	4.9 MB	4.0%	1.39
Jan 13 21:00:00 - 21:59:59	450	3.7%	0.12	4.8 MB	3.9%	1.36
Jan 13 22:00:00 - 22:59:59	439	3.6%	0.12	4.4 MB	3.6%	1.26
Jan 13 23:00:00 - 23:59:59	446	3.7%	0.12	4.4 MB	3.6%	1.25
Jan 14 00:00:00 - 00:59:59	415	3.4%	0.12	4.5 MB	3.6%	1.28
Jan 14 01:00:00 - 01:59:59	389	3.2%	0.11	4.1 MB	3.3%	1.17
Jan 14 02:00:00 - 02:59:59	384	3.2%	0.11	3.9 MB	3.2%	1.11
Jan 14 03:00:00 - 03:59:59	441	3.7%	0.12	5.2 MB	4.2%	1.49
Jan 14 04:00:00 - 04:15:01	117	1.0%	0.13	1.3 MB	1.0%	1.43
TOTAL: 24:00:00	12068	100.0%	0.14	123.6 MB	100.0%	1.46

Sites sending bad articles:

Server		Total	Other
1	news.nntp4.net	2238	2238
2	news.glou.org	103	103
3	gegeweb-out.news.tnetconsulting.net	20	20
4	nntp.terraraq.uk	8	8
5	usenet.pasdenom.info	7	7
TOTAL: 5		2376	2376

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1913
Too many newsgroups	347
EMP (phn path)	74
Bad path (news.giganews.com.POSTED)	33
Binary: misplaced binary	5
Cancels abusifs	2
Too many newsgroups (meow)	1
No Cancel-Key matches Cancel-Lock. target=<65a2a0ed$0$10086$426a34cc@news.free.fr>	1
TOTAL: 8	2376

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	20867	127	11066	507	22	0%	23:59:55
2	news.gegeweb.org	11980	73	10955	0	0	0%	23:59:56
3	news.nntp4.net	3977	49	3285	0	0	1%	23:59:56
4	news.samoylyk.net	15774	46	11923	46	0	0%	23:59:28
5	nntp.terraraq.uk	12126	38	10983	58	0	0%	23:59:56
6	news.tnetconsulting.net	19729	3	12004	60	3	0%	23:59:54
7	news.aioe.org	0	0	0	0	5761	0%	23:59:56
8	news.pasdenom.info	12448	0	11634	4	0	0%	23:59:38
TOTAL: 8		96901	336	71850	675	5786	0%	191:58:39

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.gegeweb.org	732.5 KB	0.0 KB	732.5 KB	0.0 KB/s	10.0 KB	23:59:56
2	i2pn.org	424.1 KB	5.3 MB	5.7 MB	0.1 KB/s	9.2 KB	23:59:55
3	news.nntp4.net	265.4 KB	0.0 KB	265.4 KB	0.0 KB/s	5.4 KB	23:59:56
4	nntp.terraraq.uk	209.0 KB	608.7 KB	817.7 KB	0.0 KB/s	8.5 KB	23:59:56
5	news.samoylyk.net	121.7 KB	224.3 KB	346.0 KB	0.0 KB/s	3.8 KB	23:59:28
6	news.tnetconsulting.net	11.1 KB	404.4 KB	415.4 KB	0.0 KB/s	6.6 KB	23:59:54
7	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:56
8	news.pasdenom.info	0.0 KB	62.1 KB	62.1 KB	0.0 KB/s	15.5 KB	23:59:38
TOTAL: 8		1.7 MB	6.6 MB	8.3 MB	0.0 KB/s	8.4 KB	191:58:39

NNRP readership statistics:

System		Conn	Elapsed
1	172.104.238.162	1	00:00:03
2	mail.gegeweb.eu	288	00:01:42
3	104.156.155.18	85	00:00:16
4	malta.scan.bufferover.run	1	00:00:00
5	107.170.235.10	1	00:00:01
6	57.150.216.162.bc.googleusercontent.com	1	00:00:00
7	battery.census.shodan.io	1	00:00:02
8	194.165.16.72	1	00:00:00
9	supporting.monitoring.internet-measurement.com	1	00:00:02
10	192.241.216.30	1	00:00:09
11	212.102.40.218	3	00:00:03
12	194.165.16.10	1	00:00:00
TOTAL: 12		385	00:02:24

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.150.216.162.bc.googleusercontent.com	1	00:00:00
2	unresolved	93	00:00:35
3	*.monitoring.internet-measurement.com	1	00:00:02
4	*.gegeweb.eu	288	00:01:42
5	*.census.shodan.io	1	00:00:02
6	*.scan.bufferover.run	1	00:00:00
TOTAL: 6		385	00:02:24

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	4.366	4.200	1.517	00:01:42
104.156.155.18	2.318	1.039	0.000	00:00:16
192.241.216.30	0.012	0.012	0.000	00:00:09
172.104.238.162	0.037	0.020	0.000	00:00:03
212.102.40.218	0.042	0.042	0.000	00:00:03
supporting.monitoring.internet-measurement.com	0.020	0.005	0.000	00:00:02
battery.census.shodan.io	0.008	0.017	0.000	00:00:02
107.170.235.10	0.020	0.028	0.000	00:00:01
194.165.16.10	0.030	0.022	0.000	00:00:00
57.150.216.162.bc.googleusercontent.com	0.024	0.024	0.000	00:00:00
194.165.16.72	0.024	0.028	0.000	00:00:00
TOTAL: 11	6.901	5.437	1.517	00:02:24

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
57.150.216.162.bc.googleusercontent.com	3
107.170.235.10	1
194.165.16.72	1
TOTAL: 3	5

NNRP unrecognized commands (by command):

Command	Count
User-Agent: Expanse, a Palo Alto Network...	1
GET / HTTP/1.1	1
Host: 45.13.105.36:119	1
#003	1
MGLNDD_45.13.105.36_119	1
TOTAL: 5	5

NNRP client timeouts [Top 20]:

System	Conn	Peer
194.165.16.72	1	1
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

Jan 13 04:15:01 -- Jan 14 04:15:01