Daily Usenet report for news.niel.me: Jul 24 04:15:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jul 24 12:17:53 rpi-studio innd: filter: No Cancel-Key[sha1:yWdekz+TX50kx8qG0EROI7+9/4w=] matches Cancel-Lock[sha1:xkmXXy0vQRNmSYALoP1R+L30QKw=] target=<66a0d317$0$18434$426a74cc@news.free.fr>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2606	30.5%	478.2 KB	34.9%
innd	2251	26.3%	388.6 KB	28.3%
innfeed	1868	21.9%	291.0 KB	21.2%
nnrpd	1816	21.3%	213.3 KB	15.6%
controlchan	1	0.0%	0.2 KB	0.0%
nocem	1	0.0%	0.2 KB	0.0%
TOTAL: 6	8543	100.0%	1.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	13421	57.3%
Negative hits	7425	31.7%
Do not exist	2584	11.0%
Cache misses	9	0.0%
TOTAL: 4	23439	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.018	0.0%	6	1.000	3.000	6.000
article cancel	00:00:00.002	0.0%	6	0.000	0.333	1.000
article cleanup	00:00:00.065	0.0%	2595	0.000	0.025	0.300
article logging	00:00:00.188	0.0%	2596	0.000	0.072	0.333
article parse	00:00:00.475	0.0%	8795	0.000	0.054	0.375
article write	00:00:01.335	0.0%	2335	0.000	0.572	1.111
artlog/artcncl	00:00:00.000	0.0%	6	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.001	0.0%	6	0.000	0.167	0.500
artlog/artwrite	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:00.143	0.0%	27263	0.000	0.005	0.053
hisgrep/artcncl	00:00:00.000	0.0%	6	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	6	0.000	0.000	0.000
hisgrep/perl	00:00:00.002	0.0%	12	0.000	0.167	1.000
hishave/artcncl	00:00:00.001	0.0%	6	0.000	0.167	0.500
hishave/artcncl/perl	00:00:00.001	0.0%	6	0.000	0.167	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:00.374	0.0%	23497	0.000	0.016	0.059
history sync	00:00:00.052	0.0%	378	0.000	0.138	1.000
history write	00:00:00.468	0.0%	2591	0.000	0.181	0.500
idle	24:00:43.052	100.0%	29445	454.684	2935.746	25022.667
nntp read	00:00:00.812	0.0%	28735	0.000	0.028	0.098
overview write	00:00:00.831	0.0%	2335	0.000	0.356	2.500
perl filter	00:00:09.942	0.0%	2591	1.510	3.837	9.500
python filter	00:00:00.012	0.0%	2591	0.000	0.005	0.125
site send	00:00:01.129	0.0%	4747	0.000	0.238	0.700
TOTAL: 24:01:07.126	24:00:58.903	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.022	0.0%	2558	0.000	0.009	0.500
article prepare	00:00:00.004	0.0%	880	0.000	0.005	0.250
article read	00:00:00.027	0.0%	212	0.000	0.127	1.000
backlog stats	00:00:00.184	0.0%	32789	0.000	0.006	0.037
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.481	0.0%	17811	0.000	0.027	0.098
data write	00:00:00.914	0.0%	15842	0.000	0.058	0.137
idle	23:50:17.892	100.0%	32789	1043.485	2617.277	8020.197
status file	00:00:00.484	0.0%	268	0.000	1.806	4.000
TOTAL: 23:50:26.220	23:50:20.008	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:54.737	40.8%	1450	3.500	37.750	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.124	0.1%	3226	0.000	0.038	1.000
TOTAL: 00:02:14.018	00:00:54.861	40.9%	-	-	-	-

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	2285	780	1505	0	34%	23:56:43
2	news.samoylyk.net	3	7791	493	7298	0	6%	19:06:21
3	gegeweb-out.news.tnetconsulting.net	3	2270	377	1835	58	16%	25:22:05
4	news.nntp4.net	1	2341	304	1929	108	12%	23:23:21
5	i2pn.org	4	2877	179	2693	5	6%	25:43:48
6	news.glou.org	6	1720	112	1530	78	6%	23:53:09
7	nntp.terraraq.uk	3	1991	50	1941	0	2%	24:44:25
8	usenet.pasdenom.info	9	132	5	126	1	3%	05:29:04
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		58	21407	2300	18857	250	10%	171:38:56

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.samoylyk.net	4.7 MB	0.0 KB	0.0 KB	4.7 MB	100%	9.8 KB
2	feed-out.gegeweb.org	2.6 MB	0.0 KB	0.0 KB	2.6 MB	100%	3.4 KB
3	news.nntp4.net	1.6 MB	3.2 KB	623.0 KB	2.2 MB	72%	5.5 KB
4	gegeweb-out.news.tnetconsulting.net	1.3 MB	1.4 KB	188.3 KB	1.5 MB	87%	3.5 KB
5	i2pn.org	605.8 KB	0.0 KB	44.9 KB	650.8 KB	93%	3.5 KB
6	news.glou.org	326.6 KB	0.0 KB	373.4 KB	700.0 KB	46%	3.7 KB
7	nntp.terraraq.uk	280.9 KB	0.0 KB	0.0 KB	280.9 KB	100%	5.6 KB
8	usenet.pasdenom.info	13.0 KB	0.0 KB	1.0 KB	14.0 KB	92%	2.3 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		11.4 MB	4.5 KB	1.2 MB	12.6 MB	90%	5.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 24 04:15:02 - 04:59:59	91	3.9%	0.03	456.0 KB	3.7%	0.17
Jul 24 05:00:00 - 05:59:59	56	2.4%	0.02	305.0 KB	2.5%	0.08
Jul 24 06:00:00 - 06:59:59	56	2.4%	0.02	205.7 KB	1.7%	0.06
Jul 24 07:00:00 - 07:59:59	44	1.9%	0.01	139.9 KB	1.1%	0.04
Jul 24 08:00:00 - 08:59:59	42	1.8%	0.01	160.0 KB	1.3%	0.04
Jul 24 09:00:00 - 09:59:59	52	2.2%	0.01	174.8 KB	1.4%	0.05
Jul 24 10:00:00 - 10:59:59	94	4.0%	0.03	325.4 KB	2.6%	0.09
Jul 24 11:00:00 - 11:59:59	111	4.8%	0.03	407.4 KB	3.3%	0.11
Jul 24 12:00:00 - 12:59:59	107	4.6%	0.03	1.4 MB	11.5%	0.39
Jul 24 13:00:00 - 13:59:59	160	6.9%	0.04	1.9 MB	15.6%	0.53
Jul 24 14:00:00 - 14:59:59	175	7.5%	0.05	531.6 KB	4.3%	0.15
Jul 24 15:00:00 - 15:59:59	193	8.3%	0.05	631.4 KB	5.1%	0.18
Jul 24 16:00:00 - 16:59:59	122	5.2%	0.03	391.0 KB	3.2%	0.11
Jul 24 17:00:00 - 17:59:59	115	4.9%	0.03	362.2 KB	2.9%	0.10
Jul 24 18:00:00 - 18:59:59	89	3.8%	0.02	245.5 KB	2.0%	0.07
Jul 24 19:00:00 - 19:59:59	89	3.8%	0.02	552.0 KB	4.5%	0.15
Jul 24 20:00:00 - 20:59:59	86	3.7%	0.02	355.3 KB	2.9%	0.10
Jul 24 21:00:00 - 21:59:59	100	4.3%	0.03	1.1 MB	9.1%	0.31
Jul 24 22:00:00 - 22:59:59	123	5.3%	0.03	910.3 KB	7.4%	0.25
Jul 24 23:00:00 - 23:59:59	136	5.8%	0.04	462.9 KB	3.8%	0.13
Jul 25 00:00:00 - 00:59:59	82	3.5%	0.02	356.0 KB	2.9%	0.10
Jul 25 01:00:00 - 01:59:59	100	4.3%	0.03	310.4 KB	2.5%	0.09
Jul 25 02:00:00 - 02:59:59	61	2.6%	0.02	295.7 KB	2.4%	0.08
Jul 25 03:00:00 - 03:59:59	41	1.8%	0.01	201.5 KB	1.6%	0.06
Jul 25 04:00:00 - 04:15:02	8	0.3%	0.01	56.3 KB	0.5%	0.06
TOTAL: 24:00:00	2333	100.0%	0.03	12.0 MB	100.0%	0.14

Sites sending bad articles:

Server		Total	Duplic	Other
1	news.nntp4.net	119	2	117
2	news.glou.org	78	0	78
3	gegeweb-out.news.tnetconsulting.net	42	1	41
4	usenet.pasdenom.info	10	0	10
5	i2pn.org	8	0	8
6	nntp.terraraq.uk	2	1	1
7	feed-out.gegeweb.org	1	0	1
8	news.samoylyk.net	1	0	1
TOTAL: 8		261	4	257

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	79
EMP (phn path)	73
Bad path (news.giganews.com.POSTED)	50
EMP (md5)	45
Cancels abusifs	2
Cancel of <75b57e69f6be65a6a871744df62ee4eb073f548f@i2pn2.org> without Cancel-Key	1
Cancel of <f08646a170c432d6ad1afc5e3018b1da2709050c@i2pn2.org> without Cancel-Key	1
Cancel of <lgbqmaFg51pU1@mid.individual.net> without Cancel-Key	1
Cancel for rejected article	1
No Cancel-Key matches Cancel-Lock. target=<66a0d317$0$18434$426a74cc@news.free.fr>	1
Cancel of <92dda4fa2ab239325b4a980213fb395834667515@i2pn2.org> without Cancel-Key	1
Cancel of <e2a349e1aa7aa612ec57071c03184180349fc410@i2pn2.org> without Cancel-Key	1
TOTAL: 12	256

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	3482	111	2042	29	105	3%	23:58:36
2	news.gegeweb.org	1664	59	1500	0	0	3%	23:47:25
3	news.tnetconsulting.net	3850	13	2313	1	106	0%	23:58:36
4	news.samoylyk.net	1941	7	1741	0	18	0%	23:58:26
5	news.pasdenom.info	1739	1	1599	0	0	0%	23:54:50
6	nntp.terraraq.uk	1443	0	1362	0	0	0%	23:49:06
7	news.nntp4.net	2140	0	1998	0	0	0%	23:58:24
8	news.aioe.org	0	0	0	0	1898	0%	24:00:00
TOTAL: 8		16259	191	12555	30	2127	1%	191:25:23

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	525.1 KB	660.0 KB	1.2 MB	8.5 KB	23:58:36
2	news.gegeweb.org	199.2 KB	0.0 KB	199.2 KB	3.4 KB	23:47:25
3	news.tnetconsulting.net	38.4 KB	3.0 KB	41.4 KB	3.0 KB	23:58:36
4	news.samoylyk.net	24.7 KB	0.0 KB	24.7 KB	3.5 KB	23:58:26
5	news.pasdenom.info	2.3 KB	0.0 KB	2.3 KB	2.3 KB	23:54:50
6	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:49:06
7	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0	23:58:24
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
TOTAL: 8		789.7 KB	663.0 KB	1.4 MB	6.6 KB	191:25:23

NNRP readership statistics:

System		Conn	Elapsed
1	91.238.181.35	1	00:00:00
2	172-232-195-152.ip.linodeusercontent.com	1	00:00:00
3	179.60.149.8	1	00:00:01
4	80.66.76.121	1	00:00:00
5	172-232-195-123.ip.linodeusercontent.com	1	00:00:00
6	148.150.216.162.bc.googleusercontent.com	1	00:00:01
7	mail.gegeweb.eu	288	00:01:40
8	205.210.31.138	1	00:00:10
9	rapturous.monitoring.internet-measurement.com	1	00:00:02
10	147.185.132.174	1	00:00:10
11	71.6.134.234	1	00:00:01
12	146.150.216.162.bc.googleusercontent.com	1	00:00:01
13	172-232-220-33.ip.linodeusercontent.com	1	00:00:00
14	250.211.203.35.bc.googleusercontent.com	1	00:00:01
15	45.227.254.49	1	00:00:00
TOTAL: 15		302	00:02:13

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.211.203.35.bc.googleusercontent.com	1	00:00:01
2	*.150.216.162.bc.googleusercontent.com	2	00:00:02
3	*.gegeweb.eu	288	00:01:40
4	*.monitoring.internet-measurement.com	1	00:00:02
5	unresolved	7	00:00:24
6	*.ip.linodeusercontent.com	3	00:00:01
TOTAL: 6		302	00:02:13

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	5.143	3.881	1.629	00:01:40
205.210.31.138	0.013	0.009	0.000	00:00:10
147.185.132.174	0.024	0.020	0.000	00:00:10
rapturous.monitoring.internet-measurement.com	0.031	0.019	0.000	00:00:02
179.60.149.8	0.014	0.014	0.000	00:00:01
71.6.134.234	0.007	0.015	0.000	00:00:01
250.211.203.35.bc.googleusercontent.com	0.067	0.020	0.000	00:00:01
146.150.216.162.bc.googleusercontent.com	0.071	0.016	0.000	00:00:01
148.150.216.162.bc.googleusercontent.com	0.066	0.012	0.000	00:00:01
172-232-195-152.ip.linodeusercontent.com	0.011	0.011	0.000	00:00:00
91.238.181.35	0.027	0.022	0.000	00:00:00
172-232-195-123.ip.linodeusercontent.com	0.028	0.016	0.000	00:00:00
45.227.254.49	0.051	0.008	0.000	00:00:00
172-232-220-33.ip.linodeusercontent.com	0.015	0.007	0.000	00:00:00
80.66.76.121	0.041	0.022	0.000	00:00:00
?	0.036	0.018	0.000	00:00:00
TOTAL: 16	5.645	4.110	1.629	00:02:13

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
146.150.216.162.bc.googleusercontent.com	4
148.150.216.162.bc.googleusercontent.com	4
250.211.203.35.bc.googleusercontent.com	4
91.238.181.35	1
TOTAL: 4	13

NNRP unrecognized commands (by command):

Command	Count
Accept-Encoding: gzip	3
GET / HTTP/1.1	3
Host: 45.13.105.36:563	3
User-Agent: Expanse, a Palo Alto Network...	3
#003	1
TOTAL: 5	13

NNRP client timeouts [Top 20]:

System	Conn	Peer
91.238.181.35	1	1
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

Jul 24 04:15:02 -- Jul 25 04:15:02