Daily Usenet report for news.niel.me: Apr 20 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Apr 20 15:22:56 rpi-studio innd: filter: No Cancel-Key[sha1:uuLP8vIQ6BZ5xLLQNvMFQn0KMXg= sha256:0dF8icNjIJzPnePDjtRHhiBHSWGAEaHhUuoMr9hneoQ=] matches Cancel-Lock[sha1:m/7tdWwaRalbh+++qjt7MzXSBrk= sha256:Brv7peVArqzX9JXXKH5Xc5oWsoXIIa3iugwMQGUsfHM=] target=<v00fi5$oe7$1@rasp.pasdenom.info>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2358	28.5%	441.3 KB	33.3%
innd	2158	26.1%	376.5 KB	28.4%
innfeed	1903	23.0%	298.7 KB	22.5%
nnrpd	1850	22.4%	208.8 KB	15.8%
controlchan	1	0.0%	0.2 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	8271	100.0%	1.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	13196	59.5%
Negative hits	6638	29.9%
Do not exist	2349	10.6%
Cache misses	1	0.0%
TOTAL: 4	22184	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.001	0.0%	2	0.000	0.500	1.000
article cancel	00:00:00.000	0.0%	2	0.000	0.000	0.000
article cleanup	00:00:00.052	0.0%	2353	0.000	0.022	1.000
article logging	00:00:00.165	0.0%	2357	0.000	0.070	0.333
article parse	00:00:00.532	0.0%	9164	0.000	0.058	0.300
article write	00:00:01.150	0.0%	2192	0.000	0.525	1.200
artlog/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:00:00.148	0.0%	27045	0.000	0.005	0.030
hisgrep/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hisgrep/perl	00:00:00.000	0.0%	3	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	2	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:00.337	0.0%	22197	0.000	0.015	0.057
history sync	00:00:00.056	0.0%	400	0.000	0.140	1.000
history write	00:00:00.398	0.0%	2353	0.000	0.169	0.667
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:49.594	100.0%	29006	247.736	2980.404	37513.125
nntp read	00:00:00.779	0.0%	28151	0.000	0.028	0.122
overview write	00:00:01.017	0.0%	2192	0.000	0.464	4.625
perl filter	00:00:09.066	0.0%	2353	1.040	3.853	10.500
python filter	00:00:00.011	0.0%	2353	0.000	0.005	0.100
site send	00:00:00.883	0.0%	4401	0.000	0.201	0.708
TOTAL: 24:01:14.895	24:01:04.189	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.021	0.0%	2512	0.000	0.008	0.200
article prepare	00:00:00.000	0.0%	156	0.000	0.000	0.000
article read	00:00:00.011	0.0%	39	0.000	0.282	7.000
backlog stats	00:00:00.163	0.0%	30548	0.000	0.005	0.044
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.347	0.0%	16448	0.000	0.021	0.100
data write	00:00:00.851	0.0%	14634	0.000	0.058	0.154
idle	23:58:13.485	100.0%	30548	752.311	2824.849	8955.806
status file	00:00:00.599	0.0%	294	0.000	2.037	6.000
TOTAL: 23:58:22.095	23:58:15.477	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:17:15.505	86.6%	1468	0.000	705.385	491372.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.103	0.0%	3296	0.000	0.031	0.500
TOTAL: 00:19:55.357	00:17:15.608	86.6%	-	-	-	-

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	feed-out.gegeweb.org	4	2176	1022	1154	0	46%	23:58:08
2	news.nntp4.net	3	2050	407	1603	40	19%	22:18:08
3	usenet.pasdenom.info	6	2216	313	1888	15	14%	38:31:16
4	news.glou.org	4	3354	243	2958	153	7%	43:25:36
5	i2pn.org	4	2239	119	2119	1	5%	26:12:26
6	gegeweb-out.news.tnetconsulting.net	3	495	54	435	6	10%	10:46:14
7	nntp.terraraq.uk	5	1891	41	1850	0	2%	25:15:00
8	news.samoylyk.net	7	4180	30	4149	1	0%	44:41:49
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		61	18601	2229	16156	216	11%	235:08:37

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	4.3 MB	201.9 KB	4.5 MB	95%	10.4 KB
2	feed-out.gegeweb.org	4.2 MB	0.0 KB	4.2 MB	100%	4.2 KB
3	news.samoylyk.net	3.1 MB	2.9 KB	3.1 MB	99%	101.1 KB
4	news.glou.org	994.5 KB	386.7 KB	1.3 MB	72%	3.5 KB
5	usenet.pasdenom.info	740.0 KB	29.2 KB	769.2 KB	96%	2.3 KB
6	i2pn.org	245.3 KB	7.8 KB	253.1 KB	96%	2.1 KB
7	gegeweb-out.news.tnetconsulting.net	200.8 KB	76.2 KB	277.0 KB	72%	4.6 KB
8	nntp.terraraq.uk	123.7 KB	0.0 KB	123.7 KB	100%	3.0 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		13.8 MB	704.7 KB	14.5 MB	95%	6.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 20 04:15:01 - 04:59:59	28	1.3%	0.01	86.4 KB	0.6%	0.03
Apr 20 05:00:00 - 05:59:59	59	2.7%	0.02	181.7 KB	1.3%	0.05
Apr 20 06:00:00 - 06:59:59	27	1.2%	0.01	183.5 KB	1.3%	0.05
Apr 20 07:00:00 - 07:59:59	31	1.4%	0.01	112.4 KB	0.8%	0.03
Apr 20 08:00:00 - 08:59:59	42	1.9%	0.01	149.5 KB	1.1%	0.04
Apr 20 09:00:00 - 09:59:59	74	3.4%	0.02	357.8 KB	2.5%	0.10
Apr 20 10:00:00 - 10:59:59	57	2.6%	0.02	195.4 KB	1.4%	0.05
Apr 20 11:00:00 - 11:59:59	66	3.0%	0.02	190.6 KB	1.4%	0.05
Apr 20 12:00:00 - 12:59:59	66	3.0%	0.02	501.9 KB	3.6%	0.14
Apr 20 13:00:00 - 13:59:59	53	2.4%	0.01	322.8 KB	2.3%	0.09
Apr 20 14:00:00 - 14:59:59	134	6.1%	0.04	3.8 MB	27.6%	1.08
Apr 20 15:00:00 - 15:59:59	226	10.3%	0.06	3.4 MB	24.8%	0.97
Apr 20 16:00:00 - 16:59:59	81	3.7%	0.02	254.5 KB	1.8%	0.07
Apr 20 17:00:00 - 17:59:59	102	4.7%	0.03	296.5 KB	2.1%	0.08
Apr 20 18:00:00 - 18:59:59	167	7.6%	0.05	524.2 KB	3.7%	0.15
Apr 20 19:00:00 - 19:59:59	108	4.9%	0.03	305.6 KB	2.2%	0.08
Apr 20 20:00:00 - 20:59:59	166	7.6%	0.05	572.8 KB	4.1%	0.16
Apr 20 21:00:00 - 21:59:59	136	6.2%	0.04	427.4 KB	3.0%	0.12
Apr 20 22:00:00 - 22:59:59	94	4.3%	0.03	323.6 KB	2.3%	0.09
Apr 20 23:00:00 - 23:59:59	110	5.0%	0.03	379.1 KB	2.7%	0.11
Apr 21 00:00:00 - 00:59:59	120	5.5%	0.03	366.8 KB	2.6%	0.10
Apr 21 01:00:00 - 01:59:59	96	4.4%	0.03	307.9 KB	2.2%	0.09
Apr 21 02:00:00 - 02:59:59	78	3.6%	0.02	427.5 KB	3.0%	0.12
Apr 21 03:00:00 - 03:59:59	52	2.4%	0.01	164.2 KB	1.2%	0.05
Apr 21 04:00:00 - 04:15:02	19	0.9%	0.02	79.8 KB	0.6%	0.09
TOTAL: 24:00:01	2192	100.0%	0.03	13.8 MB	100.0%	0.16

Sites sending bad articles:

Server		Total	Group	Other
1	news.glou.org	64	0	64
2	gegeweb-out.news.tnetconsulting.net	50	0	50
3	news.nntp4.net	37	2	35
4	usenet.pasdenom.info	4	0	4
5	i2pn.org	2	0	2
6	nntp.terraraq.uk	2	0	2
7	feed-out.gegeweb.org	2	0	2
8	news.samoylyk.net	1	0	1
TOTAL: 8		162	2	160

Unwanted newsgroups [Top 20]:

Newsgroup	Count
news.test	2
TOTAL: 1	2

INND Perl filter [Top 20]:

Reason	Count
Bad path (news.giganews.com.POSTED)	60
EMP (phn path)	54
Too many newsgroups	25
Too many newsgroups (meow)	8
EMP (md5)	4
EMP (phl)	3
No Cancel-Key matches Cancel-Lock. target=<v00fi5$oe7$1@rasp.pasdenom.info>	1
Cancels abusifs	1
TOTAL: 8	156

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	feed-out.gegeweb.org	2
	nntp.terraraq.uk	1
	gegeweb-out.news.tnetconsulting.net	1
	TOTAL: 3	4
TOTAL: 1		4

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	3069	17	2007	14	136	0%	23:52:34
2	news.tnetconsulting.net	3600	4	2171	1	121	0%	23:58:50
3	nntp.terraraq.uk	1218	3	1192	0	0	0%	23:38:37
4	news.aioe.org	0	0	0	0	1859	0%	24:00:00
5	news.samoylyk.net	2615	0	2177	0	64	0%	23:56:35
6	news.nntp4.net	1749	0	1716	0	0	0%	23:42:02
7	news.gegeweb.org	1168	0	1159	0	0	0%	23:27:44
8	news.pasdenom.info	1620	0	1611	0	0	0%	23:49:52
TOTAL: 8		15039	24	12033	15	2180	0%	190:26:14

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	66.6 KB	276.8 KB	343.4 KB	11.1 KB	23:52:34
2	news.tnetconsulting.net	13.7 KB	3.3 KB	17.0 KB	3.4 KB	23:58:50
3	nntp.terraraq.uk	9.2 KB	0.0 KB	9.2 KB	3.1 KB	23:38:37
4	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
5	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0	23:56:35
6	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0	23:42:02
7	news.gegeweb.org	0.0 KB	0.0 KB	0.0 KB	0	23:27:44
8	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:49:52
TOTAL: 8		89.4 KB	280.1 KB	369.5 KB	9.5 KB	190:26:14

NNRP readership statistics:

System		Conn	Elapsed
1	113.206.130.123	1	00:16:23
2	101.36.102.26	3	00:00:29
3	grand.monitoring.internet-measurement.com	1	00:00:01
4	112.94.252.148	1	00:00:02
5	poised.monitoring.internet-measurement.com	1	00:00:00
6	clever.monitoring.internet-measurement.com	1	00:00:00
7	profound.monitoring.internet-measurement.com	1	00:00:00
8	brilliant.monitoring.internet-measurement.com	1	00:00:06
9	light.monitoring.internet-measurement.com	2	00:00:07
10	1.85.216.138	1	00:00:02
11	2607:ff10:c8:594::5	3	00:00:16
12	successful.monitoring.internet-measurement.com	1	00:00:01
13	124.31.105.9	1	00:00:01
14	awesome.monitoring.internet-measurement.com	1	00:00:00
15	180.95.231.31	1	00:00:01
16	mail.gegeweb.eu	288	00:02:16
17	145.210.203.35.bc.googleusercontent.com	1	00:00:00
TOTAL: 17		309	00:19:54

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	?	3	00:00:16
2	*.210.203.35.bc.googleusercontent.com	1	00:00:00
3	*.gegeweb.eu	288	00:02:16
4	*.monitoring.internet-measurement.com	9	00:00:19
5	unresolved	8	00:17:01
TOTAL: 5		309	00:19:54

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
113.206.130.123	0.015	0.011	0.000	00:16:23
mail.gegeweb.eu	4.422	4.162	1.538	00:02:16
101.36.102.26	0.053	0.050	0.000	00:00:29
2607:ff10:c8:594::5	0.063	0.066	0.000	00:00:16
light.monitoring.internet-measurement.com	0.037	0.041	0.000	00:00:07
brilliant.monitoring.internet-measurement.com	0.073	0.021	0.000	00:00:06
1.85.216.138	0.015	0.010	0.000	00:00:02
112.94.252.148	0.030	0.017	0.000	00:00:02
124.31.105.9	0.028	0.020	0.000	00:00:01
successful.monitoring.internet-measurement.com	0.028	0.024	0.000	00:00:01
grand.monitoring.internet-measurement.com	0.036	0.016	0.000	00:00:01
180.95.231.31	0.025	0.021	0.000	00:00:01
145.210.203.35.bc.googleusercontent.com	0.027	0.027	0.000	00:00:00
clever.monitoring.internet-measurement.com	0.046	0.017	0.000	00:00:00
poised.monitoring.internet-measurement.com	0.041	0.024	0.000	00:00:00
profound.monitoring.internet-measurement.com	0.016	0.012	0.000	00:00:00
TOTAL: 16	4.955	4.539	1.538	00:19:54

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
2607:ff10:c8:594::5	7
113.206.130.123	3
1.85.216.138	3
145.210.203.35.bc.googleusercontent.com	3
101.36.102.26	3
180.95.231.31	3
124.31.105.9	1
TOTAL: 7	23

NNRP unrecognized commands (by command):

Command	Count
#026#003#001	3
À#024	3
GET / HTTP/1.1	3
Host: 45.13.105.36:119	2
#023#001#023#002#023#003#001	2
Accept: /	2
Accept-Encoding: gzip	1
}Nê¼¥‰û*9ïÆ;ß #013â\|û8ÉÍõ#004Ö}%wÕ#017#014æ ®bé7¾JFA...	1
#023#003#023#001#023#002#001	1
User-Agent: Mozilla/5.0 (Windows NT 10.0...	1
Host: [2a0e:e701:1154:0:4e10:5c89:4af0:d...	1
#026#003#001#001#027#001	1
USER anonymous	1
User-Agent: Expanse, a Palo Alto Network...	1
TOTAL: 14	23

NNRP client timeouts [Top 20]:

System	Conn	Peer
113.206.130.123	2	0
124.31.105.9	1	1
TOTAL: 2	3	1

Daily Usenet report for news.niel.me

Apr 20 04:15:01 -- Apr 21 04:15:02