Daily Usenet report for news.niel.me: Oct 19 04:15:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Oct 19 18:09:24 rpi-studio innd: filter: No Cancel-Key[sha1:fPCmB3OgpgdxAyMvjraAVNRX0fI=] matches Cancel-Lock[sha1:uHGe+dq5Mlpvf2zP+IoqLkz5RrU=] target=<65315254$0$6092$426a74cc@news.free.fr>
Oct 19 18:16:16 rpi-studio innd: filter: No Cancel-Key[sha1:fPCmB3OgpgdxAyMvjraAVNRX0fI=] matches Cancel-Lock[sha1:uHGe+dq5Mlpvf2zP+IoqLkz5RrU=] target=<65315254$0$6092$426a74cc@news.free.fr>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	48959	75.5%	9.2 MB	81.7%
innd	11924	18.4%	1.5 MB	13.7%
innfeed	2018	3.1%	312.2 KB	2.7%
nnrpd	1932	3.0%	211.5 KB	1.8%
nocem	2	0.0%	0.3 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	64836	100.0%	11.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	78956	36.3%
Negative hits	59487	27.3%
Do not exist	47973	22.1%
Cache misses	31117	14.3%
TOTAL: 4	217533	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.010	0.0%	8	1.000	1.250	2.000
article cancel	00:00:00.002	0.0%	9	0.000	0.222	1.000
article cleanup	00:00:01.001	0.0%	48710	0.000	0.021	0.052
article logging	00:00:03.565	0.0%	48710	0.031	0.073	0.114
article parse	00:00:10.047	0.0%	183536	0.036	0.055	0.089
article write	00:00:34.136	0.0%	38247	0.489	0.893	1.589
artlog/artcncl	00:00:00.001	0.0%	9	0.000	0.111	1.000
artlog/artcncl/perl	00:00:00.001	0.0%	8	0.000	0.125	1.000
data move	00:00:01.872	0.0%	362340	0.001	0.005	0.010
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%	10	0.000	0.100	0.500
hishave/artcncl	00:00:00.000	0.0%	9	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:03.875	0.0%	217735	0.007	0.018	0.033
history sync	00:00:00.049	0.0%	374	0.000	0.131	0.667
history write	00:00:09.924	0.0%	48029	0.136	0.207	0.299
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	23:45:49.071	99.6%	332010	22.188	257.670	886.771
nntp read	00:00:09.455	0.0%	331532	0.017	0.029	0.049
overview write	00:00:18.076	0.0%	38247	0.153	0.473	30.539
perl filter	00:02:25.626	0.2%	48029	2.460	3.032	3.969
python filter	00:00:00.218	0.0%	48029	0.000	0.005	0.024
site send	00:00:09.689	0.0%	80941	0.048	0.120	0.236
TOTAL: 23:50:56.772	23:49:56.619	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.838	0.0%	111783	0.000	0.007	0.033
article prepare	00:00:00.145	0.0%	40106	0.000	0.004	0.028
article read	00:00:06.565	0.0%	8336	0.000	0.788	1.477
backlog stats	00:00:02.217	0.0%	488308	0.002	0.005	0.012
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:07.509	0.0%	322307	0.013	0.023	0.039
data write	00:00:13.606	0.0%	278055	0.035	0.049	0.064
idle	23:51:56.568	99.9%	488308	18.598	175.947	565.369
status file	00:00:00.534	0.0%	266	0.000	2.008	5.000
TOTAL: 23:52:51.876	23:52:27.982	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:40.539	32.5%	1511	10.400	26.829	9299.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.124	0.1%	3396	0.000	0.037	3.000
TOTAL: 00:02:04.709	00:00:40.663	32.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	news.nntp4.net	6	42990	23294	11628	8068	54%	24:21:33
2	news.glou.org	3	13215	6461	5153	1601	48%	24:09:05
3	gegeweb-out.news.tnetconsulting.net	3	37447	4524	32851	72	12%	24:24:40
4	feed-out.gegeweb.org	3	6277	3109	2985	183	49%	24:09:18
5	news.samoylyk.net	6	34638	431	34202	5	1%	04:09:27
6	nntp.terraraq.uk	3	10536	222	9832	482	2%	22:22:18
7	usenet.pasdenom.info	6	2941	125	2806	10	4%	23:56:53
8	i2pn.org	1	6382	109	6267	6	1%	23:59:57
9	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 9		57	154426	38275	105724	10427	24%	171:33:11

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	165.5 MB	0.0 KB	44.7 MB	210.2 MB	78%	6.9 KB
2	news.glou.org	33.7 MB	1.6 MB	7.1 MB	42.4 MB	79%	5.4 KB
3	gegeweb-out.news.tnetconsulting.net	33.5 MB	0.0 KB	327.7 KB	33.9 MB	99%	7.5 KB
4	feed-out.gegeweb.org	14.6 MB	589.2 KB	589.2 KB	15.8 MB	92%	4.9 KB
5	news.samoylyk.net	3.6 MB	0.0 KB	59.4 KB	3.6 MB	98%	8.5 KB
6	i2pn.org	2.7 MB	86.9 KB	146.1 KB	2.9 MB	92%	25.9 KB
7	nntp.terraraq.uk	1.2 MB	3.2 KB	2.3 MB	3.5 MB	34%	5.1 KB
8	usenet.pasdenom.info	321.6 KB	0.0 KB	18.6 KB	340.2 KB	94%	2.5 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		255.2 MB	2.2 MB	55.2 MB	312.6 MB	81%	6.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 19 04:15:01 - 04:59:59	1014	2.6%	0.38	6.4 MB	2.5%	2.43
Oct 19 05:00:00 - 05:59:59	1422	3.7%	0.40	10.8 MB	4.2%	3.06
Oct 19 06:00:00 - 06:59:59	1459	3.8%	0.41	11.8 MB	4.6%	3.35
Oct 19 07:00:00 - 07:59:59	1468	3.8%	0.41	11.6 MB	4.5%	3.31
Oct 19 08:00:00 - 08:59:59	1627	4.2%	0.45	11.7 MB	4.5%	3.32
Oct 19 09:00:00 - 09:59:59	1597	4.2%	0.44	11.9 MB	4.6%	3.39
Oct 19 10:00:00 - 10:59:59	1451	3.8%	0.40	12.7 MB	4.9%	3.62
Oct 19 11:00:00 - 11:59:59	1823	4.7%	0.51	15.7 MB	6.1%	4.47
Oct 19 12:00:00 - 12:59:59	1715	4.5%	0.48	13.8 MB	5.3%	3.92
Oct 19 13:00:00 - 13:59:59	1569	4.1%	0.44	10.6 MB	4.1%	3.01
Oct 19 14:00:00 - 14:59:59	1355	3.5%	0.38	10.9 MB	4.2%	3.10
Oct 19 15:00:00 - 15:59:59	1600	4.2%	0.44	12.7 MB	4.9%	3.61
Oct 19 16:00:00 - 16:59:59	1631	4.2%	0.45	13.0 MB	5.0%	3.70
Oct 19 17:00:00 - 17:59:59	1479	3.8%	0.41	10.3 MB	4.0%	2.92
Oct 19 18:00:00 - 18:59:59	992	2.6%	0.28	6.2 MB	2.4%	1.77
Oct 19 19:00:00 - 19:59:59	1078	2.8%	0.30	7.3 MB	2.8%	2.08
Oct 19 20:00:00 - 20:59:59	2057	5.4%	0.57	13.2 MB	5.1%	3.75
Oct 19 21:00:00 - 21:59:59	1720	4.5%	0.48	10.5 MB	4.1%	2.99
Oct 19 22:00:00 - 22:59:59	917	2.4%	0.25	5.2 MB	2.0%	1.47
Oct 19 23:00:00 - 23:59:59	5060	13.2%	1.41	18.4 MB	7.1%	5.24
Oct 20 00:00:00 - 00:59:59	1770	4.6%	0.49	10.5 MB	4.1%	3.00
Oct 20 01:00:00 - 01:59:59	1406	3.7%	0.39	8.4 MB	3.3%	2.40
Oct 20 02:00:00 - 02:59:59	926	2.4%	0.26	5.5 MB	2.1%	1.56
Oct 20 03:00:00 - 03:59:59	927	2.4%	0.26	5.9 MB	2.3%	1.68
Oct 20 04:00:00 - 04:15:01	380	1.0%	0.42	2.8 MB	1.1%	3.20
TOTAL: 24:00:00	38443	100.0%	0.44	257.8 MB	100.0%	3.06

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news.nntp4.net	8084	1	0	8083
2	news.glou.org	1626	0	497	1129
3	nntp.terraraq.uk	524	1	1	522
4	feed-out.gegeweb.org	183	0	183	0
5	gegeweb-out.news.tnetconsulting.net	70	3	0	67
6	usenet.pasdenom.info	7	1	0	6
7	news.samoylyk.net	5	0	0	5
TOTAL: 7		10499	6	681	9812

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.petites-annonces.informatique.materiel	4
comp.lang.go	2
TOTAL: 2	6

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	6473
Too many newsgroups	2101
EMP (phr nph)	986
EMP (phl)	112
Bad path (news.giganews.com.POSTED)	76
EMP (phn path)	58
No Cancel-Key matches Cancel-Lock. target=<65315254$0$6092$426a74cc@news.free.fr>	2
HTML Multipart	2
Cancels abusifs	2
TOTAL: 9	9812

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	1	0	1	1
TOTAL: 1	1	0	1	1

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	news.samoylyk.net	53336	4391	41669	712	1	37712	8%	23:59:57
2	nntp.terraraq.uk	39259	1684	35638	161	0	10	4%	23:59:57
3	news.nntp4.net	17382	802	14098	4	0	0	4%	23:59:57
4	news.tnetconsulting.net	63959	550	37767	121	0	2748	0%	23:59:57
5	news.pasdenom.info	55339	271	37724	92	0	19989	0%	23:59:57
6	i2pn.org	62867	190	37113	971	0	4288	0%	23:59:53
7	news.gegeweb.org	37573	19	35248	38	0	1	0%	23:59:57
8	news.aioe.org	0	0	0	0	0	8131	0%	23:59:55
TOTAL: 8		329715	7907	239257	2099	1	72879	2%	191:59:30

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.samoylyk.net	41.0 MB	6.2 MB	47.2 MB	0.6 KB/s	9.5 KB	23:59:57
2	nntp.terraraq.uk	10.3 MB	1.4 MB	11.8 MB	0.1 KB/s	6.5 KB	23:59:57
3	news.nntp4.net	6.1 MB	13.0 KB	6.1 MB	0.1 KB/s	7.7 KB	23:59:57
4	news.tnetconsulting.net	4.4 MB	602.7 KB	5.0 MB	0.1 KB/s	7.6 KB	23:59:57
5	news.pasdenom.info	887.6 KB	643.5 KB	1.5 MB	0.0 KB/s	4.2 KB	23:59:57
6	i2pn.org	783.2 KB	6.4 MB	7.2 MB	0.1 KB/s	6.3 KB	23:59:53
7	news.gegeweb.org	771.9 KB	273.5 KB	1.0 MB	0.0 KB/s	18.3 KB	23:59:57
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:55
TOTAL: 8		64.2 MB	15.6 MB	79.7 MB	0.1 KB/s	8.2 KB	191:59:30

NNRP readership statistics:

System		Conn	Elapsed
1	172-105-246-139.ip.linodeusercontent.com	60	00:00:14
2	192.241.200.4	1	00:00:00
3	88.165.5.23	1	00:00:00
4	mail.gegeweb.eu	288	00:01:26
5	194.165.16.73	1	00:00:00
6	zl-ams-nl-gp1-wk130.internet-census.org	1	00:00:11
7	107.170.234.30	1	00:00:09
TOTAL: 7		353	00:02:04

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.ip.linodeusercontent.com	60	00:00:14
2	unresolved	4	00:00:12
3	*.internet-census.org	1	00:00:11
4	*.gegeweb.eu	288	00:01:26
TOTAL: 4		353	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.108	4.350	1.584	00:01:26
172-105-246-139.ip.linodeusercontent.com	1.471	0.965	0.000	00:00:14
zl-ams-nl-gp1-wk130.internet-census.org	0.024	0.032	0.000	00:00:11
107.170.234.30	0.012	0.012	0.000	00:00:09
88.165.5.23	0.029	0.021	0.000	00:00:00
192.241.200.4	0.044	0.010	0.000	00:00:00
194.165.16.73	0.027	0.027	0.000	00:00:00
TOTAL: 7	5.715	5.417	1.584	00:02:04

NNRP unrecognized commands (by host):

System	Conn
194.165.16.73	1
192.241.200.4	1
TOTAL: 2	2

NNRP unrecognized commands (by command):

Command	Count
MGLNDD_45.13.105.36_119	1
#003	1
TOTAL: 2	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
194.165.16.73	1	1
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

Oct 19 04:15:01 -- Oct 20 04:15:01