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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jan 3 11:37:44 rpi-studio innd: filter: No Cancel-Key[sha1:/Ssm3dZNZzoL7rfsNpUpxd694lE= sha256:aU1pFmqdtkp+fN2aaV+v6XareKOV2mE71V7RwZ4Bz44=#015#012#011sha1:jLI9+arZ6PNzndSIGfcKiVTFoe4= sha256:cVVLaE3bMjc4ndwxMsGudVhRk1fjrjiCoK6q/Tm3hN0=] matches Cancel-Lock[sha1:MoHcy6LZVL+o7qpjvhEjnpkpHHk= sha256:Bx2k6uXcfJW8Ip4NXStiRCmVZOCI6fmEchSR94EoVZ4=] target=<un3bn6$nv3$1@rasp.pasdenom.info>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	13803	67.0%	2.8 MB	74.1%
innd	2609	12.7%	424.2 KB	10.9%
nnrpd	2302	11.2%	252.6 KB	6.5%
innfeed	1890	9.2%	325.1 KB	8.4%
controlchan	1	0.0%	0.2 KB	0.0%
nocem	1	0.0%	0.2 KB	0.0%
TOTAL: 6	20606	100.0%	3.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	28604	45.5%
Negative hits	20733	33.0%
Do not exist	13498	21.5%
Cache misses	5	0.0%
TOTAL: 4	62840	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.157	0.0%	116	0.000	1.353	3.500
article cancel	00:00:00.028	0.0%	117	0.000	0.239	1.000
article cleanup	00:00:00.273	0.0%	13576	0.000	0.020	0.062
article logging	00:00:00.930	0.0%	13576	0.022	0.069	0.150
article parse	00:00:03.262	0.0%	62865	0.026	0.052	0.094
article write	00:00:10.967	0.0%	12886	0.446	0.851	1.615
artlog/artcncl	00:00:00.009	0.0%	117	0.000	0.077	1.000
artlog/artcncl/perl	00:00:00.008	0.0%	116	0.000	0.069	1.000
data move	00:00:00.641	0.0%	124459	0.000	0.005	0.014
hisgrep/artcncl	00:00:00.004	0.0%	117	0.000	0.034	1.000
hisgrep/artcncl/perl	00:00:00.004	0.0%	116	0.000	0.034	1.000
hisgrep/perl	00:00:00.003	0.0%	126	0.000	0.024	0.500
hishave/artcncl	00:00:00.001	0.0%	117	0.000	0.009	1.000
hishave/artcncl/perl	00:00:00.005	0.0%	116	0.000	0.043	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.198	0.0%	62951	0.005	0.019	0.055
history sync	00:00:00.039	0.0%	417	0.000	0.094	1.000
history write	00:00:02.323	0.0%	13576	0.074	0.171	0.320
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:59:28.619	99.9%	112084	460.002	770.570	1323.777
nntp read	00:00:03.184	0.0%	111545	0.013	0.029	0.047
overview write	00:00:04.941	0.0%	12886	0.128	0.383	2.165
perl filter	00:00:53.991	0.1%	13576	2.877	3.977	5.413
python filter	00:00:00.069	0.0%	13576	0.000	0.005	0.028
site send	00:00:03.683	0.0%	27110	0.038	0.136	0.249
TOTAL: 24:01:15.896	24:00:54.339	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.137	0.0%	12870	0.000	0.011	0.053
article prepare	00:00:00.015	0.0%	4892	0.000	0.003	0.062
article read	00:00:00.145	0.0%	907	0.000	0.160	2.250
backlog stats	00:00:00.970	0.0%	209104	0.000	0.005	0.011
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:03.192	0.0%	138067	0.006	0.023	0.044
data write	00:00:05.661	0.0%	107762	0.037	0.053	0.069
idle	23:55:12.851	100.0%	209104	291.839	411.818	738.505
status file	00:00:00.439	0.0%	200	0.500	2.195	9.000
TOTAL: 23:55:34.297	23:55:23.410	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:11.676	38.3%	1458	14.500	49.160	11716.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.130	0.1%	3338	0.000	0.039	1.000
TOTAL: 00:03:07.161	00:01:11.806	38.4%	-	-	-	-

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	3	12879	5679	6748	452	44%	24:05:46
2	news.glou.org	2	7583	4774	2640	169	62%	24:35:51
3	feed-out.gegeweb.org	3	3652	1130	2517	5	30%	24:57:07
4	usenet.pasdenom.info	1	2730	479	2236	15	17%	21:17:40
5	nntp.terraraq.uk	1	5988	340	5641	7	5%	34:46:33
6	i2pn.org	1	4107	279	3828	0	6%	25:14:58
7	gegeweb-out.news.tnetconsulting.net	1	3198	218	2938	42	6%	22:49:39
8	news.samoylyk.net	1	3809	36	3773	0	0%	23:59:50
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		38	43946	12935	30321	690	29%	201:47:24

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	57.7 MB	2.6 MB	60.3 MB	95%	10.1 KB
2	news.glou.org	29.8 MB	486.3 KB	30.2 MB	98%	6.3 KB
3	feed-out.gegeweb.org	5.4 MB	7.3 KB	5.4 MB	99%	4.8 KB
4	i2pn.org	2.4 MB	0.0 KB	2.4 MB	100%	8.7 KB
5	nntp.terraraq.uk	1.5 MB	32.2 KB	1.5 MB	97%	4.4 KB
6	usenet.pasdenom.info	1.2 MB	18.5 KB	1.3 MB	98%	2.6 KB
7	gegeweb-out.news.tnetconsulting.net	724.2 KB	137.4 KB	861.6 KB	84%	3.3 KB
8	news.samoylyk.net	225.2 KB	0.0 KB	225.2 KB	100%	6.3 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		98.8 MB	3.2 MB	102.0 MB	96%	7.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 3 04:15:01 - 04:59:59	442	3.4%	0.16	3.3 MB	3.3%	1.24
Jan 3 05:00:00 - 05:59:59	641	5.0%	0.18	5.0 MB	5.0%	1.41
Jan 3 06:00:00 - 06:59:59	571	4.4%	0.16	4.6 MB	4.7%	1.30
Jan 3 07:00:00 - 07:59:59	620	4.8%	0.17	5.2 MB	5.3%	1.48
Jan 3 08:00:00 - 08:59:59	627	4.9%	0.17	5.3 MB	5.4%	1.50
Jan 3 09:00:00 - 09:59:59	686	5.3%	0.19	4.8 MB	4.9%	1.38
Jan 3 10:00:00 - 10:59:59	591	4.6%	0.16	4.0 MB	4.1%	1.14
Jan 3 11:00:00 - 11:59:59	580	4.5%	0.16	4.1 MB	4.1%	1.16
Jan 3 12:00:00 - 12:59:59	580	4.5%	0.16	5.6 MB	5.7%	1.59
Jan 3 13:00:00 - 13:59:59	610	4.7%	0.17	4.8 MB	4.9%	1.36
Jan 3 14:00:00 - 14:59:59	575	4.5%	0.16	4.6 MB	4.6%	1.30
Jan 3 15:00:00 - 15:59:59	553	4.3%	0.15	4.6 MB	4.7%	1.32
Jan 3 16:00:00 - 16:59:59	514	4.0%	0.14	3.6 MB	3.7%	1.03
Jan 3 17:00:00 - 17:59:59	467	3.6%	0.13	3.1 MB	3.1%	0.88
Jan 3 18:00:00 - 18:59:59	446	3.5%	0.12	3.1 MB	3.1%	0.87
Jan 3 19:00:00 - 19:59:59	434	3.4%	0.12	3.1 MB	3.2%	0.90
Jan 3 20:00:00 - 20:59:59	406	3.2%	0.11	2.7 MB	2.8%	0.78
Jan 3 21:00:00 - 21:59:59	406	3.2%	0.11	2.8 MB	2.8%	0.78
Jan 3 22:00:00 - 22:59:59	432	3.4%	0.12	3.1 MB	3.1%	0.88
Jan 3 23:00:00 - 23:59:59	382	3.0%	0.11	3.1 MB	3.1%	0.87
Jan 4 00:00:00 - 00:59:59	506	3.9%	0.14	3.5 MB	3.6%	1.00
Jan 4 01:00:00 - 01:59:59	557	4.3%	0.15	4.2 MB	4.2%	1.18
Jan 4 02:00:00 - 02:59:59	542	4.2%	0.15	4.2 MB	4.3%	1.20
Jan 4 03:00:00 - 03:59:59	580	4.5%	0.16	5.2 MB	5.3%	1.48
Jan 4 04:00:00 - 04:15:01	133	1.0%	0.15	1.1 MB	1.2%	1.31
TOTAL: 24:00:00	12881	100.0%	0.15	98.5 MB	100.0%	1.17

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	451	59	392
2	news.glou.org	165	0	165
3	gegeweb-out.news.tnetconsulting.net	42	0	42
4	usenet.pasdenom.info	17	0	17
5	nntp.terraraq.uk	9	0	9
6	feed-out.gegeweb.org	5	0	5
TOTAL: 6		689	59	630

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.lettres.langue.allemande	33
fr.misc.gestion	26
TOTAL: 2	59

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	229
Too many newsgroups	206
EMP (phn path)	101
Bad path (news.giganews.com.POSTED)	68
Too many newsgroups (low_xpost)	5
Binary: misplaced binary	5
User-issued spam cancel	3
Cancels abusifs	2
Cancel for rejected article	2
Cancel of <mn.1a937e81c02bb6f2.145333@Capistrano.ru.invalid> without Cancel-Key	1
Cancel of <mn.1c057e8136b8ca58.145333@Capistrano.ru.invalid> without Cancel-Key	1
Cancel of <mn.1aa77e8166478e32.145333@Capistrano.ru.invalid> without Cancel-Key	1
Cancel of <mn.1a977e8148205eb1.145333@Capistrano.ru.invalid> without Cancel-Key	1
Cancel of non-existing ID <un4m53$3bnmh$1@dont-email.me>	1
Cancel of <mn.1a8b7e810419b0d0.145333@Capistrano.ru.invalid> without Cancel-Key	1
Cancel of <mn.1a987e812fbdc427.145333@Capistrano.ru.invalid> without Cancel-Key	1
Cancel of <mn.1ad17e81ae007fb1.145333@Capistrano.ru.invalid> without Cancel-Key	1
Cancel of <mn.1c027e81c0a8dc7d.145333@Capistrano.ru.invalid> without Cancel-Key	1
TOTAL: 18	630

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	20410	177	11998	415	10	0%	23:59:55
2	news.nntp4.net	9162	122	7052	2	1	1%	23:59:56
3	news.gegeweb.org	13521	33	11710	2	0	0%	23:59:52
4	nntp.terraraq.uk	13381	23	11021	175	0	0%	23:59:52
5	news.samoylyk.net	18465	21	12709	97	3	0%	23:59:52
6	news.tnetconsulting.net	18268	17	12718	135	10	0%	23:59:56
7	news.pasdenom.info	14164	1	12298	3	0	0%	23:59:53
8	news.aioe.org	0	0	0	0	6212	0%	23:59:56
TOTAL: 8		107371	394	79506	829	6236	0%	191:59:12

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.nntp4.net	1.1 MB	6.1 KB	1.1 MB	8.8 KB	23:59:56
2	i2pn.org	585.1 KB	3.4 MB	4.0 MB	6.9 KB	23:59:55
3	news.gegeweb.org	322.7 KB	14.3 KB	337.0 KB	9.6 KB	23:59:52
4	nntp.terraraq.uk	137.5 KB	1.6 MB	1.8 MB	9.2 KB	23:59:52
5	news.tnetconsulting.net	60.4 KB	1.1 MB	1.1 MB	7.5 KB	23:59:56
6	news.samoylyk.net	54.7 KB	777.6 KB	832.3 KB	7.1 KB	23:59:52
7	news.pasdenom.info	5.8 KB	27.9 KB	33.7 KB	8.4 KB	23:59:53
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:56
TOTAL: 8		2.2 MB	6.9 MB	9.1 MB	7.6 KB	191:59:12

NNRP readership statistics:

System		Conn	Elapsed
1	2607:ff10:c8:594::6	3	00:00:07
2	118.194.251.112	1	00:00:01
3	172.104.238.162	1	00:00:02
4	mail.gegeweb.eu	288	00:02:08
5	222.186.13.132	1	00:00:24
6	91.240.118.73	1	00:00:00
7	delightful.monitoring.internet-measurement.com	1	00:00:01
8	80.66.88.215	1	00:00:00
9	88-165-5-23.subs.proxad.net	2	00:00:01
10	194.165.16.73	1	00:00:00
11	104.156.155.21	85	00:00:15
TOTAL: 11		385	00:03:06

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.subs.proxad.net	2	00:00:01
2	unresolved	91	00:00:46
3	*.monitoring.internet-measurement.com	1	00:00:01
4	?	3	00:00:07
5	*.gegeweb.eu	288	00:02:08
TOTAL: 5		385	00:03:06

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.620	4.286	1.603	00:02:08
222.186.13.132	0.031	0.024	0.000	00:00:24
104.156.155.21	2.255	0.980	0.000	00:00:15
2607:ff10:c8:594::6	0.060	0.073	0.000	00:00:07
172.104.238.162	0.016	0.012	0.000	00:00:02
delightful.monitoring.internet-measurement.com	0.030	0.023	0.000	00:00:01
118.194.251.112	0.033	0.020	0.000	00:00:01
88-165-5-23.subs.proxad.net	0.039	0.039	0.000	00:00:01
194.165.16.73	0.035	0.022	0.000	00:00:00
80.66.88.215	0.012	0.012	0.000	00:00:00
91.240.118.73	0.037	0.025	0.000	00:00:00
TOTAL: 11	7.168	5.516	1.603	00:03:06

NNRP unrecognized commands (by host):

System	Conn
2607:ff10:c8:594::6	7
80.66.88.215	1
TOTAL: 2	8

NNRP unrecognized commands (by command):

Command	Count
#003	1
Accept-Encoding: gzip	1
User-Agent: Mozilla/5.0 (Windows NT 10.0...	1
Host: [2a0e:e701:1154:0:4e10:5c89:4af0:d...	1
Accept: /	1
#026#003#001	1
#023#003#023#001#023#002#001	1
GET / HTTP/1.1	1
TOTAL: 8	8

NNRP client timeouts [Top 20]:

System	Conn	Peer
80.66.88.215	1	1
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

Jan 3 04:15:01 -- Jan 4 04:15:01