Daily Usenet report for news.niel.me: Feb 28 04:15:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Feb 28 11:10:01 rpi-studio innd: filter: No Cancel-Key[sha1:CVlSNxWyb9C3uQCY1rD+s7yFUMI=] matches Cancel-Lock[sha1:cUsHy5scyb6zxMq1+IX+NkqPxoo=] target=<65df0607$0$3260$426a74cc@news.free.fr>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3958	40.1%	702.7 KB	44.3%
innd	2310	23.4%	413.3 KB	26.0%
nnrpd	1825	18.5%	194.1 KB	12.2%
innfeed	1776	18.0%	277.7 KB	17.5%
nocem	1	0.0%	0.2 KB	0.0%
controlchan	1	0.0%	0.0 KB	0.0%
TOTAL: 6	9871	100.0%	1.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	20160	63.0%
Negative hits	7914	24.7%
Do not exist	3910	12.2%
Cache misses	11	0.0%
TOTAL: 4	31995	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.006	0.0%	8	0.000	0.750	1.000
article cancel	00:00:00.000	0.0%	8	0.000	0.000	0.000
article cleanup	00:00:00.082	0.0%	3914	0.000	0.021	0.167
article logging	00:00:00.279	0.0%	3915	0.000	0.071	0.333
article parse	00:00:00.793	0.0%	17049	0.000	0.047	0.182
article write	00:00:01.904	0.0%	3516	0.091	0.542	1.118
artlog/artcncl	00:00:00.000	0.0%	8	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	8	0.000	0.000	0.000
data move	00:00:00.215	0.0%	41990	0.000	0.005	0.020
hisgrep/artcncl	00:00:00.000	0.0%	8	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	8	0.000	0.000	0.000
hisgrep/perl	00:00:00.001	0.0%	12	0.000	0.083	1.000
hishave/artcncl	00:00:00.000	0.0%	8	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	8	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.471	0.0%	32016	0.000	0.015	0.054
history sync	00:00:00.049	0.0%	400	0.000	0.122	1.000
history write	00:00:00.608	0.0%	3914	0.000	0.155	0.500
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:50:44.798	100.0%	43044	499.730	1994.350	8701.971
nntp read	00:00:01.019	0.0%	42409	0.000	0.024	0.058
overview write	00:00:01.550	0.0%	3516	0.000	0.441	5.625
perl filter	00:00:12.103	0.0%	3914	1.400	3.092	7.667
python filter	00:00:00.017	0.0%	3914	0.000	0.004	0.100
site send	00:00:01.350	0.0%	7224	0.000	0.187	0.417
TOTAL: 23:51:15.050	23:51:05.245	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.027	0.0%	4445	0.000	0.006	0.095
article prepare	00:00:00.009	0.0%	1064	0.000	0.008	0.250
article read	00:00:00.037	0.0%	255	0.000	0.145	3.500
backlog stats	00:00:00.236	0.0%	47808	0.000	0.005	0.022
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.602	0.0%	26981	0.000	0.022	0.059
data write	00:00:01.150	0.0%	23608	0.000	0.049	0.116
idle	23:58:07.648	100.0%	47808	755.684	1804.879	4901.911
status file	00:00:00.406	0.0%	199	1.000	2.040	7.000
TOTAL: 23:58:15.768	23:58:10.115	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:29.037	23.3%	1449	10.400	20.039	10048.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.090	0.1%	3197	0.000	0.028	1.000
TOTAL: 00:02:04.857	00:00:29.127	23.3%	-	-	-	-

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	feed-out.gegeweb.org	2	3434	1838	1596	0	53%	23:58:55
2	news.nntp4.net	1	3973	797	2884	292	20%	25:19:55
3	gegeweb-out.news.tnetconsulting.net	1	3408	286	3059	63	8%	27:07:51
4	i2pn.org	2	3936	243	3693	0	6%	27:59:27
5	usenet.pasdenom.info	3	1425	127	1285	13	8%	23:12:04
6	news.glou.org	2	1880	123	1692	65	6%	22:19:40
7	news.samoylyk.net	1	2442	31	2410	1	1%	20:06:04
8	nntp.terraraq.uk	1	90	3	87	0	3%	01:03:33
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		38	20588	3448	16706	434	16%	171:07:29

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	10.6 MB	1.0 MB	11.6 MB	91%	10.9 KB
2	feed-out.gegeweb.org	8.6 MB	0.0 KB	8.6 MB	100%	4.8 KB
3	gegeweb-out.news.tnetconsulting.net	801.9 KB	220.4 KB	1022.3 KB	78%	2.9 KB
4	i2pn.org	588.6 KB	0.0 KB	588.6 KB	100%	2.4 KB
5	news.samoylyk.net	550.6 KB	1.9 KB	552.5 KB	99%	17.3 KB
6	news.glou.org	547.5 KB	142.0 KB	689.4 KB	79%	3.7 KB
7	usenet.pasdenom.info	307.8 KB	19.6 KB	327.5 KB	94%	2.3 KB
8	nntp.terraraq.uk	8.7 KB	0.0 KB	8.7 KB	100%	2.9 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		21.9 MB	1.4 MB	23.3 MB	93%	6.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 28 04:15:02 - 04:59:59	83	2.3%	0.03	391.1 KB	1.5%	0.14
Feb 28 05:00:00 - 05:59:59	62	1.8%	0.02	251.4 KB	1.0%	0.07
Feb 28 06:00:00 - 06:59:59	69	1.9%	0.02	374.2 KB	1.4%	0.10
Feb 28 07:00:00 - 07:59:59	86	2.4%	0.02	320.1 KB	1.2%	0.09
Feb 28 08:00:00 - 08:59:59	74	2.1%	0.02	379.8 KB	1.5%	0.11
Feb 28 09:00:00 - 09:59:59	102	2.9%	0.03	334.3 KB	1.3%	0.09
Feb 28 10:00:00 - 10:59:59	88	2.5%	0.02	312.6 KB	1.2%	0.09
Feb 28 11:00:00 - 11:59:59	132	3.7%	0.04	855.3 KB	3.3%	0.24
Feb 28 12:00:00 - 12:59:59	138	3.9%	0.04	898.1 KB	3.5%	0.25
Feb 28 13:00:00 - 13:59:59	191	5.4%	0.05	1.3 MB	5.1%	0.37
Feb 28 14:00:00 - 14:59:59	146	4.1%	0.04	990.0 KB	3.8%	0.28
Feb 28 15:00:00 - 15:59:59	118	3.3%	0.03	550.5 KB	2.1%	0.15
Feb 28 16:00:00 - 16:59:59	177	5.0%	0.05	818.0 KB	3.2%	0.23
Feb 28 17:00:00 - 17:59:59	216	6.1%	0.06	1.9 MB	7.5%	0.54
Feb 28 18:00:00 - 18:59:59	224	6.3%	0.06	1.2 MB	4.8%	0.35
Feb 28 19:00:00 - 19:59:59	229	6.5%	0.06	1.7 MB	6.6%	0.48
Feb 28 20:00:00 - 20:59:59	242	6.8%	0.07	1.3 MB	5.0%	0.36
Feb 28 21:00:00 - 21:59:59	143	4.0%	0.04	1.2 MB	4.8%	0.34
Feb 28 22:00:00 - 22:59:59	173	4.9%	0.05	1.8 MB	7.2%	0.52
Feb 28 23:00:00 - 23:59:59	220	6.2%	0.06	1.0 MB	4.1%	0.30
Feb 29 00:00:00 - 00:59:59	138	3.9%	0.04	1.8 MB	6.9%	0.50
Feb 29 01:00:00 - 01:59:59	233	6.6%	0.06	3.7 MB	14.8%	1.07
Feb 29 02:00:00 - 02:59:59	109	3.1%	0.03	885.5 KB	3.4%	0.25
Feb 29 03:00:00 - 03:59:59	111	3.1%	0.03	1.0 MB	4.0%	0.29
Feb 29 04:00:00 - 04:15:01	35	1.0%	0.04	179.9 KB	0.7%	0.20
TOTAL: 23:59:59	3539	100.0%	0.04	25.3 MB	100.0%	0.30

Sites sending bad articles:

Server		Total	Other
1	news.nntp4.net	274	274
2	news.glou.org	70	70
3	gegeweb-out.news.tnetconsulting.net	39	39
4	usenet.pasdenom.info	13	13
5	news.samoylyk.net	4	4
6	nntp.terraraq.uk	2	2
7	feed-out.gegeweb.org	1	1
TOTAL: 7		403	403

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	266
EMP (phn path)	68
Bad path (news.giganews.com.POSTED)	57
Cancels abusifs	5
EMP (md5)	2
Cancel of <uro661$2k76$1@dont-email.me> without Cancel-Key	1
No Cancel-Key matches Cancel-Lock. target=<65df0607$0$3260$426a74cc@news.free.fr>	1
Cancel of <uro4mm$2i6m$1@dont-email.me> without Cancel-Key	1
Cancel of <urob5a$3te8$1@dont-email.me> without Cancel-Key	1
TOTAL: 9	402

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	feed-out.gegeweb.org	1
	TOTAL: 1	1
TOTAL: 1		1

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	6037	67	3131	80	459	1%	23:59:54
2	news.gegeweb.org	1771	54	1657	0	0	3%	23:59:28
3	news.tnetconsulting.net	5634	19	3473	13	252	0%	23:59:54
4	news.samoylyk.net	5231	17	3426	0	180	0%	23:57:10
5	news.pasdenom.info	2286	12	1928	1	0	0%	23:59:54
6	news.nntp4.net	2866	2	2628	0	13	0%	23:59:54
7	nntp.terraraq.uk	1640	1	1433	0	0	0%	23:54:00
8	news.aioe.org	0	0	0	0	3076	0%	23:59:54
TOTAL: 8		25465	172	17676	94	3980	0%	191:50:08

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	291.9 KB	672.6 KB	964.5 KB	6.6 KB	23:59:54
2	news.gegeweb.org	200.1 KB	0.0 KB	200.1 KB	3.7 KB	23:59:28
3	news.tnetconsulting.net	47.5 KB	38.1 KB	85.7 KB	2.7 KB	23:59:54
4	news.samoylyk.net	39.9 KB	0.0 KB	39.9 KB	2.3 KB	23:57:10
5	news.pasdenom.info	17.0 KB	1.5 KB	18.5 KB	1.4 KB	23:59:54
6	news.nntp4.net	9.2 KB	0.0 KB	9.2 KB	4.6 KB	23:59:54
7	nntp.terraraq.uk	2.2 KB	0.0 KB	2.2 KB	2.2 KB	23:54:00
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:54
TOTAL: 8		607.8 KB	712.2 KB	1.3 MB	5.0 KB	191:50:08

NNRP readership statistics:

System		Conn	Elapsed
1	favourable.monitoring.internet-measurement.com	1	00:00:00
2	vps-558d23ae.vps.ovh.net	1	00:00:00
3	104.152.52.94	1	00:00:11
4	198.199.110.61	1	00:00:01
5	mail.gegeweb.eu	288	00:01:32
6	vps-5a4e3251.vps.ovh.ca	1	00:00:01
7	innovative.monitoring.internet-measurement.com	1	00:00:02
8	ip16.ip-54-37-99.eu	1	00:00:00
9	212.102.40.218	3	00:00:01
10	45-79-168-172.ip.linodeusercontent.com	1	00:00:00
11	terrific.monitoring.internet-measurement.com	1	00:00:00
12	monaco.scan.bufferover.run	1	00:00:00
13	80.66.88.204	1	00:00:00
14	fond.monitoring.internet-measurement.com	1	00:00:05
15	vps-a0b69465.vps.ovh.ca	1	00:00:00
16	effective.monitoring.internet-measurement.com	1	00:00:00
17	vps-23764f54.vps.ovh.ca	1	00:00:01
TOTAL: 17		306	00:02:04

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.vps.ovh.net	1	00:00:00
2	*.vps.ovh.ca	3	00:00:03
3	*.ip.linodeusercontent.com	1	00:00:00
4	*.ip-54-37-99.eu	1	00:00:00
5	*.scan.bufferover.run	1	00:00:00
6	*.monitoring.internet-measurement.com	5	00:00:08
7	*.gegeweb.eu	288	00:01:32
8	unresolved	6	00:00:16
TOTAL: 8		306	00:02:04

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.417	4.022	1.469	00:01:32
104.152.52.94	0.020	0.005	0.000	00:00:11
fond.monitoring.internet-measurement.com	0.029	0.013	0.000	00:00:05
innovative.monitoring.internet-measurement.com	0.019	0.031	0.000	00:00:02
212.102.40.218	0.047	0.036	0.000	00:00:01
vps-23764f54.vps.ovh.ca	0.034	0.021	0.000	00:00:01
198.199.110.61	0.031	0.023	0.000	00:00:01
vps-5a4e3251.vps.ovh.ca	0.010	0.016	0.000	00:00:01
80.66.88.204	0.039	0.023	0.000	00:00:00
ip16.ip-54-37-99.eu	0.033	0.020	0.000	00:00:00
monaco.scan.bufferover.run	0.026	0.011	0.000	00:00:00
vps-558d23ae.vps.ovh.net	0.029	0.017	0.000	00:00:00
45-79-168-172.ip.linodeusercontent.com	0.040	0.022	0.000	00:00:00
vps-a0b69465.vps.ovh.ca	0.021	0.029	0.000	00:00:00
terrific.monitoring.internet-measurement.com	0.013	0.017	0.000	00:00:00
effective.monitoring.internet-measurement.com	0.043	0.013	0.000	00:00:00
favourable.monitoring.internet-measurement.com	0.020	0.008	0.000	00:00:00
TOTAL: 17	4.871	4.327	1.469	00:02:04

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
198.199.110.61	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
MGLNDD_45.13.105.36_119	1
TOTAL: 1	1

Daily Usenet report for news.niel.me

Feb 28 04:15:02 -- Feb 29 04:15:01