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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Nov 20 10:00:19 rpi-studio innd: filter: No Cancel-Key[sha1:iWzxRNiTs8RFRzpVnIAnEA2q/vY= sha256:Hl8FnQZ7gSUW5aUmtMt7fhdFhxpC0YUYPNp3eIp5dts=] matches Cancel-Lock[sha1:OtwTM/SFML5HWtqhvJAxI9cy3S4= sha256:GEoG+2LFUjudauzYy8PDS/wn8nxfhKFpPsZhu0quioE=] target=<ujf75v$as$1@rasp.pasdenom.info>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	33124	69.8%	6.0 MB	76.5%
innd	10527	22.2%	1.3 MB	17.0%
innfeed	1948	4.1%	330.9 KB	4.1%
nnrpd	1827	3.9%	198.1 KB	2.4%
nocem	1	0.0%	0.2 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	47428	100.0%	7.9 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	42949	37.1%
Positive hits	40012	34.5%
Do not exist	32871	28.4%
Cache misses	10	0.0%
TOTAL: 4	115842	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.019	0.0%	7	1.000	2.714	7.000
article cancel	00:00:00.001	0.0%	7	0.000	0.143	1.000
article cleanup	00:00:00.716	0.0%	33066	0.000	0.022	0.059
article logging	00:00:02.525	0.0%	33066	0.038	0.076	0.128
article parse	00:00:09.562	0.0%	178773	0.023	0.053	0.082
article write	00:00:27.122	0.0%	24645	0.552	1.101	2.952
artlog/artcncl	00:00:00.001	0.0%	7	0.000	0.143	1.000
artlog/artcncl/perl	00:00:00.001	0.0%	7	0.000	0.143	1.000
data move	00:00:01.708	0.0%	307493	0.001	0.006	0.012
hisgrep/artcncl	00:00:00.000	0.0%	7	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	7	0.000	0.000	0.000
hisgrep/perl	00:00:00.001	0.0%	9	0.000	0.111	1.000
hishave/artcncl	00:00:00.000	0.0%	7	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	7	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:02.282	0.0%	116528	0.005	0.020	0.038
history sync	00:00:00.061	0.0%	393	0.000	0.155	1.000
history write	00:00:07.548	0.0%	33066	0.123	0.228	0.372
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:56:51.058	99.7%	261629	202.396	329.516	875.095
nntp read	00:00:07.748	0.0%	261248	0.016	0.030	0.048
overview write	00:00:09.926	0.0%	24645	0.141	0.403	19.825
perl filter	00:02:14.945	0.2%	33066	3.175	4.081	6.890
python filter	00:00:00.151	0.0%	33066	0.000	0.005	0.019
site send	00:00:07.101	0.0%	50777	0.031	0.140	0.273
TOTAL: 24:01:05.304	24:00:22.476	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.203	0.0%	24593	0.000	0.008	0.031
article prepare	00:00:00.033	0.0%	10412	0.000	0.003	0.042
article read	00:00:00.316	0.0%	1806	0.000	0.175	1.000
backlog stats	00:00:01.922	0.0%	411833	0.001	0.005	0.009
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:06.423	0.0%	274385	0.012	0.023	0.039
data write	00:00:10.900	0.0%	213593	0.032	0.051	0.071
idle	23:52:32.071	100.0%	411833	115.666	208.706	527.360
status file	00:00:00.456	0.0%	202	1.000	2.257	9.000
TOTAL: 23:53:11.436	23:52:52.324	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:07.265	39.2%	1452	8.500	46.326	10023.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.104	0.1%	3227	0.000	0.032	0.273
TOTAL: 00:02:51.784	00:01:07.369	39.2%	-	-	-	-

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	5	32135	11862	13347	6926	36%	24:26:47
2	news.glou.org	4	15082	9906	3860	1316	65%	24:21:08
3	feed-out.gegeweb.org	3	3272	1028	2243	1	31%	22:44:22
4	gegeweb-out.news.tnetconsulting.net	1	6727	959	5671	97	14%	26:01:13
5	nntp.terraraq.uk	1	6828	590	6122	116	8%	27:21:37
6	i2pn.org	2	3912	312	3600	0	7%	22:59:59
7	usenet.pasdenom.info	1	1067	93	968	6	8%	09:39:14
8	news.samoylyk.net	1	797	6	791	0	0%	06:00:28
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		43	69820	24756	36602	8462	35%	163:34:48

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	121.9 MB	68.8 MB	190.7 MB	63%	10.4 KB
2	news.glou.org	76.3 MB	7.4 MB	83.8 MB	91%	7.6 KB
3	feed-out.gegeweb.org	11.3 MB	1.3 KB	11.3 MB	99%	11.2 KB
4	gegeweb-out.news.tnetconsulting.net	5.1 MB	508.2 KB	5.6 MB	91%	5.4 KB
5	i2pn.org	4.6 MB	0.0 KB	4.6 MB	100%	15.1 KB
6	nntp.terraraq.uk	3.5 MB	919.2 KB	4.4 MB	79%	6.4 KB
7	usenet.pasdenom.info	267.1 KB	8.4 KB	275.4 KB	96%	2.8 KB
8	news.samoylyk.net	76.9 KB	0.0 KB	76.9 KB	100%	12.8 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		222.9 MB	77.7 MB	300.6 MB	74%	9.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 20 04:15:01 - 04:59:59	690	2.8%	0.26	5.7 MB	2.5%	2.15
Nov 20 05:00:00 - 05:59:59	1168	4.7%	0.32	10.7 MB	4.8%	3.03
Nov 20 06:00:00 - 06:59:59	1208	4.9%	0.34	11.6 MB	5.2%	3.31
Nov 20 07:00:00 - 07:59:59	1183	4.8%	0.33	12.1 MB	5.4%	3.43
Nov 20 08:00:00 - 08:59:59	1384	5.6%	0.38	12.6 MB	5.7%	3.60
Nov 20 09:00:00 - 09:59:59	1532	6.2%	0.43	14.9 MB	6.7%	4.25
Nov 20 10:00:00 - 10:59:59	1373	5.6%	0.38	13.1 MB	5.9%	3.73
Nov 20 11:00:00 - 11:59:59	1398	5.7%	0.39	12.9 MB	5.8%	3.66
Nov 20 12:00:00 - 12:59:59	1210	4.9%	0.34	13.1 MB	5.9%	3.74
Nov 20 13:00:00 - 13:59:59	1303	5.3%	0.36	12.6 MB	5.7%	3.59
Nov 20 14:00:00 - 14:59:59	993	4.0%	0.28	9.4 MB	4.2%	2.69
Nov 20 15:00:00 - 15:59:59	1011	4.1%	0.28	10.1 MB	4.5%	2.87
Nov 20 16:00:00 - 16:59:59	914	3.7%	0.25	8.8 MB	4.0%	2.51
Nov 20 17:00:00 - 17:59:59	884	3.6%	0.25	8.9 MB	4.0%	2.54
Nov 20 18:00:00 - 18:59:59	793	3.2%	0.22	7.3 MB	3.3%	2.08
Nov 20 19:00:00 - 19:59:59	862	3.5%	0.24	5.4 MB	2.4%	1.54
Nov 20 20:00:00 - 20:59:59	800	3.2%	0.22	6.8 MB	3.0%	1.92
Nov 20 21:00:00 - 21:59:59	922	3.7%	0.26	8.0 MB	3.6%	2.29
Nov 20 22:00:00 - 22:59:59	755	3.1%	0.21	5.8 MB	2.6%	1.64
Nov 20 23:00:00 - 23:59:59	696	2.8%	0.19	6.3 MB	2.8%	1.78
Nov 21 00:00:00 - 00:59:59	804	3.3%	0.22	5.0 MB	2.2%	1.41
Nov 21 01:00:00 - 01:59:59	589	2.4%	0.16	3.4 MB	1.5%	0.96
Nov 21 02:00:00 - 02:59:59	784	3.2%	0.22	5.9 MB	2.7%	1.69
Nov 21 03:00:00 - 03:59:59	1105	4.5%	0.31	9.5 MB	4.3%	2.70
Nov 21 04:00:00 - 04:15:01	302	1.2%	0.34	2.9 MB	1.3%	3.28
TOTAL: 24:00:00	24663	100.0%	0.29	222.9 MB	100.0%	2.64

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	6936	0	6936
2	news.glou.org	1316	0	1316
3	nntp.terraraq.uk	113	0	113
4	gegeweb-out.news.tnetconsulting.net	76	1	75
5	usenet.pasdenom.info	5	0	5
6	feed-out.gegeweb.org	1	0	1
TOTAL: 6		8447	1	8446

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.petites-annonces.informatique.materiel	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	7854
EMP (phl)	364
Bad path (news.giganews.com.POSTED)	105
Too many newsgroups	83
EMP (phn path)	36
No Cancel-Key matches Cancel-Lock. target=<ujf75v$as$1@rasp.pasdenom.info>	1
Too many newsgroups (meow)	1
Cancels abusifs	1
Cancel of non-existing ID <ujg8ut$e0eg$1@dont-email.me>	1
TOTAL: 9	8446

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.nntp4.net	17646	328	12515	0	0	1%	23:59:56
2	nntp.terraraq.uk	29060	239	22439	631	1	0%	23:59:56
3	i2pn.org	38052	99	23297	903	35	0%	23:59:53
4	news.tnetconsulting.net	38148	48	24452	163	9	0%	23:59:55
5	news.samoylyk.net	37961	11	24396	245	22	0%	23:59:56
6	news.gegeweb.org	23796	1	23581	2	0	0%	23:59:55
7	news.pasdenom.info	28671	0	24251	3	0	0%	23:59:56
8	news.aioe.org	0	0	0	0	5218	0%	23:59:54
TOTAL: 8		213334	726	154931	1947	5285	0%	191:59:21

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.nntp4.net	2.4 MB	0.0 KB	2.4 MB	0.0 KB/s	7.5 KB	23:59:56
2	nntp.terraraq.uk	1.7 MB	5.7 MB	7.4 MB	0.1 KB/s	8.8 KB	23:59:56
3	i2pn.org	934.8 KB	8.1 MB	9.0 MB	0.1 KB/s	9.2 KB	23:59:53
4	news.tnetconsulting.net	579.4 KB	1.5 MB	2.1 MB	0.0 KB/s	10.2 KB	23:59:55
5	news.samoylyk.net	66.0 KB	2.4 MB	2.5 MB	0.0 KB/s	9.9 KB	23:59:56
6	news.gegeweb.org	8.1 KB	17.6 KB	25.7 KB	0.0 KB/s	8.6 KB	23:59:55
7	news.pasdenom.info	0.0 KB	17.1 KB	17.1 KB	0.0 KB/s	5.7 KB	23:59:56
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:54
TOTAL: 8		5.7 MB	17.8 MB	23.5 MB	0.0 KB/s	9.0 KB	191:59:21

NNRP readership statistics:

System		Conn	Elapsed
1	mail.gegeweb.eu	289	00:02:02
2	vivacious.monitoring.internet-measurement.com	1	00:00:00
3	admirable.monitoring.internet-measurement.com	1	00:00:00
4	willing.monitoring.internet-measurement.com	1	00:00:08
5	generous.monitoring.internet-measurement.com	1	00:00:01
6	efficient.monitoring.internet-measurement.com	1	00:00:00
7	reassuring.monitoring.internet-measurement.com	1	00:00:00
8	cheerful.monitoring.internet-measurement.com	1	00:00:00
9	107.170.252.63	1	00:00:09
10	steadfast.monitoring.internet-measurement.com	1	00:00:00
11	handsome.monitoring.internet-measurement.com	1	00:00:05
12	fresh.monitoring.internet-measurement.com	1	00:00:05
13	adroit.monitoring.internet-measurement.com	1	00:00:00
14	104.152.52.127	1	00:00:14
15	94.102.61.41	1	00:00:01
16	192.241.194.104	1	00:00:00
TOTAL: 16		304	00:02:51

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.gegeweb.eu	289	00:02:02
2	*.monitoring.internet-measurement.com	11	00:00:22
3	unresolved	4	00:00:26
TOTAL: 3		304	00:02:51

NNRP auth users [Top 20]:

User	Conn
mailman	289
TOTAL: 1	289

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mail.gegeweb.eu	4.483	4.274	1.627	00:02:02
104.152.52.127	0.012	0.012	0.000	00:00:14
107.170.252.63	0.019	0.005	0.000	00:00:09
willing.monitoring.internet-measurement.com	0.068	0.017	0.000	00:00:08
handsome.monitoring.internet-measurement.com	0.070	0.016	0.000	00:00:05
fresh.monitoring.internet-measurement.com	0.059	0.024	0.000	00:00:05
generous.monitoring.internet-measurement.com	0.004	0.020	0.000	00:00:01
94.102.61.41	0.024	0.020	0.000	00:00:01
192.241.194.104	0.019	0.005	0.000	00:00:00
steadfast.monitoring.internet-measurement.com	0.041	0.020	0.000	00:00:00
cheerful.monitoring.internet-measurement.com	0.025	0.037	0.000	00:00:00
efficient.monitoring.internet-measurement.com	0.021	0.008	0.000	00:00:00
adroit.monitoring.internet-measurement.com	0.030	0.026	0.000	00:00:00
vivacious.monitoring.internet-measurement.com	0.046	0.011	0.000	00:00:00
reassuring.monitoring.internet-measurement.com	0.033	0.023	0.000	00:00:00
TOTAL: 15	4.954	4.518	1.627	00:02:51

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
willing.monitoring.internet-measurement.com	6
94.102.61.41	5
192.241.194.104	1
TOTAL: 3	12

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	2
Accept: /	2
Accept-Encoding: gzip	2
MGLNDD_45.13.105.36_119	1
Host: [2a0e:e701:1154:0:4e10:5c89:4af0:d...	1
User-Agent: Mozilla/5.0 (compatible; Int...	1
Connection: close	1
Host: 45.13.105.36:119	1
User-Agent: Mozilla/5.0 (Windows NT 10.0...	1
TOTAL: 9	12

Daily Usenet report for news.niel.me

Nov 20 04:15:01 -- Nov 21 04:15:01