Daily Usenet report for news.niel.me: Aug 10 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Aug 10 22:48:52 rpi-studio innd: filter: No Cancel-Key[sha1:LnKv3Zsc0prelyNEpY6+n3tgc4w=] matches Cancel-Lock[sha1:6fNcKZQnNwMxtfe8Vrnr2xDytQw=] target=<66b7cf75$0$11685$426a34cc@news.free.fr>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2423	28.2%	446.4 KB	32.7%
nnrpd	2166	25.2%	247.3 KB	18.1%
innd	2131	24.8%	365.8 KB	26.8%
innfeed	1880	21.9%	307.1 KB	22.5%
controlchan	1	0.0%	0.2 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	8602	100.0%	1.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	10635	56.9%
Negative hits	5657	30.3%
Do not exist	2402	12.8%
Cache misses	1	0.0%
TOTAL: 4	18695	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.008	0.0%	7	0.000	1.143	2.000
article cancel	00:00:00.001	0.0%	8	0.000	0.125	1.000
article cleanup	00:00:00.050	0.0%	2405	0.000	0.021	0.231
article logging	00:00:00.192	0.0%	2405	0.000	0.080	0.400
article parse	00:00:00.442	0.0%	9709	0.000	0.046	0.286
article write	00:00:01.174	0.0%	2132	0.000	0.551	1.286
artlog/artcncl	00:00:00.001	0.0%	8	0.000	0.125	1.000
artlog/artcncl/perl	00:00:00.000	0.0%	7	0.000	0.000	0.000
artlog/artwrite	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:00.132	0.0%	24319	0.000	0.005	0.036
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.000	0.0%	9	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	8	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.001	0.0%	7	0.000	0.143	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:00.331	0.0%	18706	0.000	0.018	0.055
history sync	00:00:00.056	0.0%	390	0.000	0.144	1.000
history write	00:00:00.408	0.0%	2404	0.000	0.170	0.750
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	24:00:41.226	100.0%	26307	499.010	3285.864	20001.633
nntp read	00:00:00.700	0.0%	25263	0.000	0.028	0.098
overview write	00:00:00.754	0.0%	2132	0.000	0.354	3.250
perl filter	00:00:08.476	0.0%	2404	0.894	3.526	12.667
python filter	00:00:00.009	0.0%	2404	0.000	0.004	0.250
site send	00:00:00.383	0.0%	4312	0.000	0.089	0.857
TOTAL: 24:01:01.868	24:00:54.344	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:26.938	0.0%	3987077	0.002	0.007	0.011
article prepare	00:00:00.001	0.0%	956	0.000	0.001	0.083
article read	00:00:00.040	0.0%	227	0.000	0.176	3.000
backlog stats	00:00:11.278	0.0%	2288846	0.002	0.005	0.009
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:31.622	0.0%	1609986	0.007	0.020	0.041
data write	00:00:33.995	0.0%	1566807	0.010	0.022	0.041
idle	23:48:45.114	99.7%	2288846	26.761	37.453	56.960
status file	00:00:00.699	0.0%	277	0.000	2.523	10.167
TOTAL: 23:53:13.749	23:50:29.687	99.8%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:02.715	31.6%	1561	3.500	40.176	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.126	0.1%	3560	0.000	0.035	1.000
TOTAL: 00:03:18.344	00:01:02.841	31.7%	-	-	-	-

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	2027	1077	950	0	53%	23:56:32
2	news.samoylyk.net	3	3523	601	2922	0	17%	35:33:12
3	gegeweb-out.news.tnetconsulting.net	3	1735	297	1355	83	17%	21:06:52
4	news.glou.org	7	3032	143	2665	224	4%	41:38:29
5	i2pn.org	3	2091	130	1946	15	6%	21:11:52
6	usenet.pasdenom.info	12	1812	115	1653	44	6%	39:49:44
7	nntp.terraraq.uk	4	1812	36	1763	13	1%	21:45:50
8	news.nntp4.net	1	0	0	0	0	0%	00:10:02
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		62	16032	2399	13254	379	14%	205:12:33

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.samoylyk.net	7.5 MB	0.0 KB	7.5 MB	100%	12.8 KB
2	feed-out.gegeweb.org	5.2 MB	0.0 KB	5.2 MB	100%	4.9 KB
3	gegeweb-out.news.tnetconsulting.net	1.3 MB	251.6 KB	1.5 MB	83%	4.1 KB
4	news.glou.org	536.4 KB	1.1 MB	1.6 MB	32%	4.5 KB
5	i2pn.org	398.2 KB	160.4 KB	558.6 KB	71%	3.9 KB
6	nntp.terraraq.uk	276.5 KB	115.3 KB	391.8 KB	70%	8.0 KB
7	usenet.pasdenom.info	260.2 KB	105.1 KB	365.3 KB	71%	2.3 KB
8	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0%	0
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		15.4 MB	1.7 MB	17.1 MB	89%	6.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 10 04:15:01 - 04:59:59	33	1.5%	0.01	140.0 KB	1.0%	0.05
Aug 10 05:00:00 - 05:59:59	58	2.7%	0.02	243.9 KB	1.8%	0.07
Aug 10 06:00:00 - 06:59:59	60	2.8%	0.02	195.9 KB	1.4%	0.05
Aug 10 07:00:00 - 07:59:59	34	1.6%	0.01	118.6 KB	0.9%	0.03
Aug 10 08:00:00 - 08:59:59	85	4.0%	0.02	2.3 MB	17.2%	0.66
Aug 10 09:00:00 - 09:59:59	47	2.2%	0.01	211.3 KB	1.5%	0.06
Aug 10 10:00:00 - 10:59:59	95	4.5%	0.03	292.4 KB	2.1%	0.08
Aug 10 11:00:00 - 11:59:59	91	4.3%	0.03	345.6 KB	2.5%	0.10
Aug 10 12:00:00 - 12:59:59	88	4.1%	0.02	489.4 KB	3.5%	0.14
Aug 10 13:00:00 - 13:59:59	100	4.7%	0.03	554.0 KB	4.0%	0.15
Aug 10 14:00:00 - 14:59:59	152	7.1%	0.04	3.8 MB	28.1%	1.08
Aug 10 15:00:00 - 15:59:59	119	5.6%	0.03	409.8 KB	3.0%	0.11
Aug 10 16:00:00 - 16:59:59	72	3.4%	0.02	327.7 KB	2.4%	0.09
Aug 10 17:00:00 - 17:59:59	85	4.0%	0.02	548.2 KB	3.9%	0.15
Aug 10 18:00:00 - 18:59:59	116	5.4%	0.03	368.1 KB	2.7%	0.10
Aug 10 19:00:00 - 19:59:59	163	7.6%	0.05	688.2 KB	5.0%	0.19
Aug 10 20:00:00 - 20:59:59	148	6.9%	0.04	653.9 KB	4.7%	0.18
Aug 10 21:00:00 - 21:59:59	85	4.0%	0.02	319.4 KB	2.3%	0.09
Aug 10 22:00:00 - 22:59:59	99	4.6%	0.03	345.3 KB	2.5%	0.10
Aug 10 23:00:00 - 23:59:59	83	3.9%	0.02	279.5 KB	2.0%	0.08
Aug 11 00:00:00 - 00:59:59	118	5.5%	0.03	402.9 KB	2.9%	0.11
Aug 11 01:00:00 - 01:59:59	83	3.9%	0.02	284.0 KB	2.0%	0.08
Aug 11 02:00:00 - 02:59:59	65	3.0%	0.02	205.8 KB	1.5%	0.06
Aug 11 03:00:00 - 03:59:59	40	1.9%	0.01	131.2 KB	0.9%	0.04
Aug 11 04:00:00 - 04:15:02	15	0.7%	0.02	36.2 KB	0.3%	0.04
TOTAL: 24:00:01	2134	100.0%	0.02	13.6 MB	100.0%	0.16

Sites sending bad articles:

Server		Total	Duplic	Other
1	news.glou.org	118	0	118
2	gegeweb-out.news.tnetconsulting.net	109	0	109
3	usenet.pasdenom.info	27	0	27
4	nntp.terraraq.uk	11	1	10
5	i2pn.org	9	0	9
TOTAL: 5		274	1	273

INND Perl filter [Top 20]:

Reason	Count
Bad path (news.giganews.com.POSTED)	111
EMP (phn path)	107
EMP (md5)	52
Cancels abusifs	2
No Cancel-Key matches Cancel-Lock. target=<66b7cf75$0$11685$426a34cc@news.free.fr>	1
TOTAL: 5	273

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.gegeweb.org	1141	120	952	0	0	10%	23:40:57
2	i2pn.org	3338	74	1872	20	110	2%	23:56:19
3	news.tnetconsulting.net	3470	8	2108	4	40	0%	23:56:52
4	news.samoylyk.net	2047	4	1697	4	70	0%	23:50:58
5	nntp.terraraq.uk	1330	4	1260	0	0	0%	23:50:29
6	news.nntp4.net	4150385	0	0	0	4012266	0%	23:59:55
7	news.aioe.org	0	0	0	0	1710	0%	24:00:01
8	news.pasdenom.info	1589	0	1432	1	0	0%	23:50:59
TOTAL: 8		4163300	210	9321	29	4014196	0%	191:06:30

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.gegeweb.org	516.3 KB	0.0 KB	516.3 KB	4.3 KB	23:40:57
2	i2pn.org	293.7 KB	514.8 KB	808.5 KB	8.6 KB	23:56:19
3	news.tnetconsulting.net	19.6 KB	9.3 KB	29.0 KB	2.4 KB	23:56:52
4	nntp.terraraq.uk	12.3 KB	0.0 KB	12.3 KB	3.1 KB	23:50:29
5	news.samoylyk.net	9.1 KB	22.2 KB	31.3 KB	3.9 KB	23:50:58
6	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0	23:59:55
7	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:01
8	news.pasdenom.info	0.0 KB	2.6 KB	2.6 KB	2.6 KB	23:50:59
TOTAL: 8		851.0 KB	549.0 KB	1.4 MB	5.9 KB	191:06:30

NNRP readership statistics:

System		Conn	Elapsed
1	mail.gegeweb.eu	288	00:02:03
2	147.185.132.30	1	00:00:10
3	lively.monitoring.internet-measurement.com	2	00:00:00
4	147.185.132.206	1	00:00:00
5	208.100.26.230	2	00:00:02
6	91.238.181.22	2	00:00:00
7	91.238.181.71	1	00:00:00
8	147.185.133.149	1	00:00:12
9	polished.monitoring.internet-measurement.com	1	00:00:00
10	172-104-137-47.ip.linodeusercontent.com	109	00:00:25
11	lovely.monitoring.internet-measurement.com	1	00:00:05
12	205.210.31.146	1	00:00:10
13	117.150.216.162.bc.googleusercontent.com	1	00:00:00
14	223.149.216.162.bc.googleusercontent.com	1	00:00:01
15	194.165.16.72	1	00:00:00
16	fond.monitoring.internet-measurement.com	1	00:00:01
TOTAL: 16		414	00:03:17

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.monitoring.internet-measurement.com	5	00:00:07
2	unresolved	10	00:00:38
3	*.gegeweb.eu	288	00:02:03
4	*.150.216.162.bc.googleusercontent.com	1	00:00:00
5	*.ip.linodeusercontent.com	109	00:00:25
6	*.149.216.162.bc.googleusercontent.com	1	00:00:01
TOTAL: 6		414	00:03:17

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.105	4.159	1.725	00:02:03
172-104-137-47.ip.linodeusercontent.com	2.722	1.404	0.000	00:00:25
147.185.133.149	0.027	0.016	0.000	00:00:12
147.185.132.30	0.011	0.011	0.000	00:00:10
205.210.31.146	0.018	0.013	0.000	00:00:10
lovely.monitoring.internet-measurement.com	0.045	0.015	0.000	00:00:05
208.100.26.230	0.029	0.039	0.000	00:00:02
fond.monitoring.internet-measurement.com	0.027	0.023	0.000	00:00:01
223.149.216.162.bc.googleusercontent.com	0.064	0.024	0.000	00:00:01
91.238.181.22	0.039	0.043	0.000	00:00:00
117.150.216.162.bc.googleusercontent.com	0.009	0.013	0.000	00:00:00
147.185.132.206	0.062	0.027	0.000	00:00:00
194.165.16.72	0.011	0.011	0.000	00:00:00
91.238.181.71	0.013	0.009	0.000	00:00:00
lively.monitoring.internet-measurement.com	0.088	0.040	0.000	00:00:00
polished.monitoring.internet-measurement.com	0.042	0.023	0.000	00:00:00
TOTAL: 16	8.312	5.870	1.725	00:03:17

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
147.185.132.206	4
117.150.216.162.bc.googleusercontent.com	4
223.149.216.162.bc.googleusercontent.com	4
147.185.133.149	4
194.165.16.72	1
91.238.181.71	1
TOTAL: 6	18

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
91.238.181.71	1	1
194.165.16.72	1	1
TOTAL: 2	2	2

Daily Usenet report for news.niel.me

Aug 10 04:15:01 -- Aug 11 04:15:02