Daily Usenet report for news.niel.me: Jun 16 04:15:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jun 16 13:57:48 rpi-studio innd: filter: No Cancel-Key[sha1:lkngIP6bblEvvz6IfuOdZQE9GtQ=] matches Cancel-Lock[sha1:6OiW6xZ3ANHX/a7u8+vBvHHK9Do=] target=<666ecc16$0$8225$426a74cc@news.free.fr>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2586	31.2%	469.4 KB	35.2%
innd	2138	25.8%	363.8 KB	27.3%
nnrpd	1812	21.8%	198.3 KB	14.9%
innfeed	1758	21.2%	300.0 KB	22.5%
controlchan	1	0.0%	0.2 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	8296	100.0%	1.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	14156	60.6%
Negative hits	6638	28.4%
Do not exist	2558	11.0%
Cache misses	3	0.0%
TOTAL: 4	23355	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.010	0.0%	12	0.000	0.833	2.000
article cancel	00:00:00.003	0.0%	14	0.000	0.214	1.000
article cleanup	00:00:00.052	0.0%	2571	0.000	0.020	0.200
article logging	00:00:00.174	0.0%	2571	0.000	0.068	0.500
article parse	00:00:00.574	0.0%	12885	0.000	0.045	0.241
article write	00:00:01.225	0.0%	2358	0.000	0.520	2.333
artlog/artcncl	00:00:00.000	0.0%	14	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.002	0.0%	12	0.000	0.167	1.000
data move	00:00:00.154	0.0%	31856	0.000	0.005	0.040
hisgrep/artcncl	00:00:00.001	0.0%	13	0.000	0.077	1.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	12	0.000	0.000	0.000
hisgrep/perl	00:00:00.002	0.0%	33	0.000	0.061	1.000
hishave/artcncl	00:00:00.000	0.0%	14	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	12	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.308	0.0%	23420	0.000	0.013	0.062
history sync	00:00:00.051	0.0%	343	0.000	0.149	1.000
history write	00:00:00.373	0.0%	2569	0.000	0.145	0.556
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	24:00:37.422	100.0%	33214	148.333	2602.439	19357.903
nntp read	00:00:00.863	0.0%	32568	0.000	0.026	0.076
overview write	00:00:00.689	0.0%	2358	0.000	0.292	1.900
perl filter	00:00:10.159	0.0%	2569	1.540	3.954	27.000
python filter	00:00:00.006	0.0%	2569	0.000	0.002	0.111
site send	00:00:01.047	0.0%	4804	0.000	0.218	0.714
TOTAL: 24:01:01.520	24:00:53.115	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.008	0.0%	2571	0.000	0.003	0.091
article prepare	00:00:00.003	0.0%	712	0.000	0.004	0.125
article read	00:00:00.030	0.0%	175	0.000	0.171	2.000
backlog stats	00:00:00.157	0.0%	33381	0.000	0.005	0.024
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.401	0.0%	17691	0.000	0.023	0.059
data write	00:00:00.763	0.0%	15885	0.000	0.048	0.133
idle	23:50:04.011	100.0%	33381	763.286	2570.445	7895.829
status file	00:00:01.102	0.0%	213	0.000	5.174	21.000
TOTAL: 23:50:14.003	23:50:06.475	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:40.296	31.5%	1462	5.500	27.562	3912.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.094	0.1%	3285	0.000	0.029	0.500
TOTAL: 00:02:07.732	00:00:40.390	31.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	2	2317	1043	1273	1	45%	23:59:22
2	news.nntp4.net	1	3304	639	2586	79	19%	31:20:06
3	usenet.pasdenom.info	3	2578	260	2268	50	10%	38:47:56
4	i2pn.org	1	2353	252	2101	0	10%	23:34:56
5	gegeweb-out.news.tnetconsulting.net	2	2111	225	1854	32	10%	25:09:55
6	news.samoylyk.net	2	2371	120	2249	2	5%	23:14:30
7	news.glou.org	1	1773	64	1606	103	3%	23:59:58
8	nntp.terraraq.uk	3	3675	59	3615	1	1%	37:36:37
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		40	20482	2662	17552	268	12%	227:43:20

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.samoylyk.net	8.6 MB	0.0 KB	6.6 KB	8.6 MB	99%	72.3 KB
2	news.nntp4.net	4.8 MB	4.7 KB	305.1 KB	5.1 MB	94%	7.2 KB
3	feed-out.gegeweb.org	4.2 MB	0.0 KB	2.0 KB	4.2 MB	99%	4.1 KB
4	gegeweb-out.news.tnetconsulting.net	853.0 KB	0.0 KB	106.6 KB	959.6 KB	88%	3.7 KB
5	i2pn.org	818.1 KB	0.0 KB	0.0 KB	818.1 KB	100%	3.2 KB
6	usenet.pasdenom.info	573.5 KB	0.0 KB	55.1 KB	628.6 KB	91%	2.0 KB
7	nntp.terraraq.uk	527.4 KB	0.0 KB	1.8 KB	529.2 KB	99%	8.8 KB
8	news.glou.org	230.2 KB	0.0 KB	249.8 KB	480.0 KB	47%	2.9 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		20.5 MB	4.7 KB	727.0 KB	21.2 MB	96%	7.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 16 04:15:02 - 04:59:59	38	1.6%	0.01	267.4 KB	1.4%	0.10
Jun 16 05:00:00 - 05:59:59	42	1.8%	0.01	185.8 KB	0.9%	0.05
Jun 16 06:00:00 - 06:59:59	42	1.8%	0.01	145.1 KB	0.7%	0.04
Jun 16 07:00:00 - 07:59:59	57	2.4%	0.02	366.4 KB	1.9%	0.10
Jun 16 08:00:00 - 08:59:59	40	1.7%	0.01	126.6 KB	0.6%	0.04
Jun 16 09:00:00 - 09:59:59	53	2.3%	0.01	186.8 KB	0.9%	0.05
Jun 16 10:00:00 - 10:59:59	66	2.8%	0.02	223.2 KB	1.1%	0.06
Jun 16 11:00:00 - 11:59:59	97	4.1%	0.03	292.6 KB	1.5%	0.08
Jun 16 12:00:00 - 12:59:59	93	4.0%	0.03	363.4 KB	1.8%	0.10
Jun 16 13:00:00 - 13:59:59	73	3.1%	0.02	372.7 KB	1.9%	0.10
Jun 16 14:00:00 - 14:59:59	105	4.5%	0.03	312.5 KB	1.6%	0.09
Jun 16 15:00:00 - 15:59:59	251	10.7%	0.07	7.5 MB	39.0%	2.13
Jun 16 16:00:00 - 16:59:59	140	6.0%	0.04	3.0 MB	15.5%	0.85
Jun 16 17:00:00 - 17:59:59	159	6.8%	0.04	1.6 MB	8.6%	0.47
Jun 16 18:00:00 - 18:59:59	150	6.4%	0.04	750.6 KB	3.8%	0.21
Jun 16 19:00:00 - 19:59:59	121	5.2%	0.03	376.6 KB	1.9%	0.10
Jun 16 20:00:00 - 20:59:59	106	4.5%	0.03	318.0 KB	1.6%	0.09
Jun 16 21:00:00 - 21:59:59	110	4.7%	0.03	335.2 KB	1.7%	0.09
Jun 16 22:00:00 - 22:59:59	165	7.0%	0.05	644.5 KB	3.3%	0.18
Jun 16 23:00:00 - 23:59:59	109	4.6%	0.03	809.2 KB	4.1%	0.22
Jun 17 00:00:00 - 00:59:59	139	5.9%	0.04	618.5 KB	3.1%	0.17
Jun 17 01:00:00 - 01:59:59	88	3.7%	0.02	286.4 KB	1.5%	0.08
Jun 17 02:00:00 - 02:59:59	44	1.9%	0.01	125.8 KB	0.6%	0.03
Jun 17 03:00:00 - 03:59:59	55	2.3%	0.02	142.2 KB	0.7%	0.04
Jun 17 04:00:00 - 04:15:02	4	0.2%	0.00	25.3 KB	0.1%	0.03
TOTAL: 24:00:00	2347	100.0%	0.03	19.2 MB	100.0%	0.23

Sites sending bad articles:

Server		Total	Duplic	Other
1	news.glou.org	103	0	103
2	news.nntp4.net	52	2	50
3	gegeweb-out.news.tnetconsulting.net	30	0	30
4	usenet.pasdenom.info	24	0	24
5	news.samoylyk.net	2	0	2
6	feed-out.gegeweb.org	1	0	1
7	nntp.terraraq.uk	1	0	1
TOTAL: 7		213	2	211

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	132
Bad path (news.giganews.com.POSTED)	35
Too many newsgroups	19
Cancels abusifs	3
HTML Multipart	1
Cancel of <v4nd11$6cvq$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4nh2g$6hvm$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4nfac$6fnd$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4n0hb$5tpp$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4ms3f$5o9u$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4mnfh$5hrh$1@i2pn2.org> without Cancel-Key	1
Cancel for rejected article	1
Cancel of <v4n534$63fd$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4mrru$5o1s$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4md0q$53ob$1@i2pn2.org> without Cancel-Key	1
EMP (md5)	1
Cancel of <v4n2gt$60a6$1@i2pn2.org> without Cancel-Key	1
No Cancel-Key matches Cancel-Lock. target=<666ecc16$0$8225$426a74cc@news.free.fr>	1
Cancel of <v4mllt$5fe0$1@i2pn2.org> without Cancel-Key	1
Cancel of non-existing ID <v4nnse$8hdd$1@dont-email.me>	1
TOTAL: 26	211

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	3314	83	1951	25	74	2%	23:59:56
2	news.gegeweb.org	1387	57	1273	0	0	4%	23:47:43
3	news.tnetconsulting.net	3838	9	2311	2	98	0%	23:58:35
4	news.pasdenom.info	1873	1	1617	1	0	0%	23:59:35
5	news.nntp4.net	1840	0	1770	0	0	0%	23:59:57
6	nntp.terraraq.uk	1308	0	1153	0	0	0%	23:59:35
7	news.samoylyk.net	2814	0	2225	0	53	0%	23:59:57
8	news.aioe.org	0	0	0	0	1952	0%	24:00:00
TOTAL: 8		16374	150	12300	28	2177	0%	191:45:18

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	511.8 KB	335.6 KB	847.3 KB	7.8 KB	23:59:56
2	news.gegeweb.org	219.0 KB	0.0 KB	219.0 KB	3.8 KB	23:47:43
3	news.tnetconsulting.net	20.3 KB	4.9 KB	25.2 KB	2.3 KB	23:58:35
4	news.pasdenom.info	1.7 KB	1.9 KB	3.6 KB	1.8 KB	23:59:35
5	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0	23:59:57
6	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:59:35
7	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0	23:59:57
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
TOTAL: 8		752.9 KB	342.3 KB	1.1 MB	6.2 KB	191:45:18

NNRP readership statistics:

System		Conn	Elapsed
1	208.100.26.230	2	00:00:03
2	ns31410048.ip-145-239-154.eu	2	00:00:00
3	229.210.203.35.bc.googleusercontent.com	1	00:00:01
4	172-233-14-36.ip.linodeusercontent.com	1	00:00:00
5	unwavering.monitoring.internet-measurement.com	1	00:00:03
6	88-165-5-23.subs.proxad.net	1	00:00:00
7	azpdwgb47.stretchoid.com	1	00:00:01
8	199.45.154.60	3	00:00:06
9	mail.gegeweb.eu	288	00:01:43
10	39.150.216.162.bc.googleusercontent.com	1	00:00:00
11	194.165.16.72	1	00:00:01
12	172-233-17-205.ip.linodeusercontent.com	1	00:00:01
13	206.168.34.60	1	00:00:02
TOTAL: 13		304	00:02:07

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.monitoring.internet-measurement.com	1	00:00:03
2	unresolved	7	00:00:14
3	*.stretchoid.com	1	00:00:01
4	*.gegeweb.eu	288	00:01:43
5	*.ip.linodeusercontent.com	2	00:00:01
6	*.subs.proxad.net	1	00:00:00
7	*.150.216.162.bc.googleusercontent.com	1	00:00:00
8	*.210.203.35.bc.googleusercontent.com	1	00:00:01
9	*.ip-145-239-154.eu	2	00:00:00
TOTAL: 9		304	00:02:07

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.436	4.317	1.541	00:01:43
199.45.154.60	0.083	0.068	0.000	00:00:06
208.100.26.230	0.049	0.028	0.000	00:00:03
unwavering.monitoring.internet-measurement.com	0.013	0.013	0.000	00:00:03
206.168.34.60	0.029	0.023	0.000	00:00:02
azpdwgb47.stretchoid.com	0.032	0.020	0.000	00:00:01
172-233-17-205.ip.linodeusercontent.com	0.016	0.008	0.000	00:00:01
194.165.16.72	0.012	0.012	0.000	00:00:01
229.210.203.35.bc.googleusercontent.com	0.066	0.023	0.000	00:00:01
ns31410048.ip-145-239-154.eu	0.088	0.037	0.000	00:00:00
39.150.216.162.bc.googleusercontent.com	0.064	0.025	0.000	00:00:00
172-233-14-36.ip.linodeusercontent.com	0.008	0.016	0.000	00:00:00
88-165-5-23.subs.proxad.net	0.013	0.013	0.000	00:00:00
?	0.023	0.020	0.000	00:00:00
TOTAL: 14	4.932	4.623	1.541	00:02:07

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
229.210.203.35.bc.googleusercontent.com	3
39.150.216.162.bc.googleusercontent.com	3
206.168.34.60	1
194.165.16.72	1
ns31410048.ip-145-239-154.eu	1
199.45.154.60	1
azpdwgb47.stretchoid.com	1
TOTAL: 7	11

NNRP unrecognized commands (by command):

Command	Count
EHLO www.censys.io	2
GET / HTTP/1.1	2
Host: 45.13.105.36:563	2
User-Agent: Expanse, a Palo Alto Network...	2
#026#003	1
MGLNDD_45.13.105.36_119	1
#003	1
TOTAL: 7	11

NNRP client timeouts [Top 20]:

System	Conn	Peer
199.45.154.60	1	2
194.165.16.72	1	1
TOTAL: 2	2	3

Daily Usenet report for news.niel.me

Jun 16 04:15:02 -- Jun 17 04:15:02