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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Dec 19 20:46:00 rpi-studio innd: filter: No Cancel-Key[sha1:gDhvhcC+2ratp1BLZs1zDl/lLJ4=] matches Cancel-Lock[sha1:cxrCTWLwlF5YA+dtXd2RCZJQisA=] target=<6581f2c2$0$10109$426a74cc@news.free.fr>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	53392	72.0%	9.6 MB	75.7%
innd	16527	22.3%	2.5 MB	19.8%
nnrpd	2237	3.0%	242.5 KB	1.9%
innfeed	2048	2.8%	344.6 KB	2.6%
nocem	1	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	74206	100.0%	12.7 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	56889	35.7%
Positive hits	52816	33.2%
Do not exist	46276	29.0%
Cache misses	3328	2.1%
TOTAL: 4	159309	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:03.560	0.0%	3386	0.000	1.051	6.000
article cancel	00:00:00.527	0.0%	3386	0.000	0.156	1.000
article cleanup	00:00:00.946	0.0%	46732	0.000	0.020	0.042
article logging	00:00:03.538	0.0%	46732	0.037	0.076	0.152
article parse	00:00:10.379	0.0%	195989	0.028	0.053	0.082
article write	00:00:28.214	0.0%	32399	0.374	0.871	4.260
artlog/artcncl	00:00:00.128	0.0%	3386	0.000	0.038	1.000
artlog/artcncl/perl	00:00:00.223	0.0%	3386	0.000	0.066	1.000
data move	00:00:01.841	0.0%	337052	0.002	0.005	0.014
hisgrep/artcncl	00:00:00.094	0.0%	3386	0.000	0.028	1.000
hisgrep/artcncl/perl	00:00:00.083	0.0%	3386	0.000	0.025	0.500
hisgrep/perl	00:00:01.168	0.0%	12603	0.000	0.093	4.000
hishave/artcncl	00:00:00.023	0.0%	3386	0.000	0.007	0.333
hishave/artcncl/perl	00:00:00.066	0.0%	3386	0.000	0.019	0.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:02.776	0.0%	153776	0.006	0.018	0.046
history sync	00:00:00.043	0.0%	393	0.000	0.109	1.000
history write	00:00:09.451	0.0%	46730	0.082	0.202	0.348
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:55:32.636	99.6%	295566	25.912	291.416	701.844
nntp read	00:00:08.828	0.0%	295478	0.017	0.030	0.053
overview write	00:00:18.960	0.0%	32399	0.135	0.585	42.658
perl filter	00:02:57.964	0.2%	46730	3.017	3.808	5.056
python filter	00:00:00.250	0.0%	46730	0.000	0.005	0.026
site send	00:00:07.528	0.0%	66153	0.034	0.114	0.251
TOTAL: 24:01:00.216	24:00:09.226	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.272	0.0%	36747	0.000	0.007	0.034
article prepare	00:00:00.035	0.0%	9968	0.000	0.004	0.083
article read	00:00:00.281	0.0%	1813	0.000	0.155	1.000
backlog stats	00:00:02.257	0.0%	474789	0.001	0.005	0.009
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:07.931	0.0%	326808	0.013	0.024	0.041
data write	00:00:12.860	0.0%	261454	0.035	0.049	0.065
idle	23:52:24.149	99.9%	474789	21.046	181.015	371.357
status file	00:00:00.550	0.0%	233	0.944	2.361	9.000
TOTAL: 23:53:11.787	23:52:48.335	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:44.676	33.2%	1439	11.200	31.047	48.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.098	0.1%	3252	0.000	0.030	1.000
TOTAL: 00:02:14.433	00:00:44.774	33.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	news.nntp4.net	31	45385	17295	23063	5027	38%	25:58:04
2	news.glou.org	4	25667	11630	4835	9202	45%	24:12:26
3	feed-out.gegeweb.org	5	6766	2085	4681	0	30%	24:53:00
4	usenet.pasdenom.info	2	4134	657	3409	68	15%	32:56:21
5	i2pn.org	1	3878	228	3650	0	5%	23:19:59
6	nntp.terraraq.uk	1	3341	208	3109	24	6%	17:52:51
7	gegeweb-out.news.tnetconsulting.net	3	2991	177	2779	35	5%	22:08:07
8	news.samoylyk.net	2	7418	74	7343	1	0%	46:36:40
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		74	99580	32354	52869	14357	32%	217:57:28

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	171.5 MB	4.0 KB	39.2 MB	210.7 MB	81%	9.7 KB
2	news.glou.org	67.5 MB	2.5 KB	18.0 MB	85.5 MB	78%	4.2 KB
3	feed-out.gegeweb.org	10.5 MB	0.0 KB	0.0 KB	10.5 MB	100%	5.2 KB
4	i2pn.org	3.5 MB	0.0 KB	0.0 KB	3.5 MB	100%	15.9 KB
5	usenet.pasdenom.info	2.9 MB	0.0 KB	79.2 KB	3.0 MB	97%	4.2 KB
6	news.samoylyk.net	1.2 MB	0.0 KB	2.6 KB	1.2 MB	99%	16.8 KB
7	nntp.terraraq.uk	1.0 MB	0.0 KB	251.2 KB	1.3 MB	80%	5.5 KB
8	gegeweb-out.news.tnetconsulting.net	496.2 KB	0.0 KB	174.1 KB	670.3 KB	74%	3.2 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		258.7 MB	6.5 KB	57.7 MB	316.3 MB	81%	6.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 19 04:15:01 - 04:59:59	982	3.0%	0.36	8.5 MB	3.3%	3.24
Dec 19 05:00:00 - 05:59:59	1432	4.4%	0.40	13.2 MB	5.1%	3.76
Dec 19 06:00:00 - 06:59:59	1306	4.0%	0.36	13.7 MB	5.3%	3.90
Dec 19 07:00:00 - 07:59:59	4502	13.9%	1.25	21.9 MB	8.5%	6.23
Dec 19 08:00:00 - 08:59:59	1881	5.8%	0.52	19.6 MB	7.6%	5.57
Dec 19 09:00:00 - 09:59:59	1909	5.9%	0.53	19.5 MB	7.5%	5.55
Dec 19 10:00:00 - 10:59:59	1367	4.2%	0.38	11.9 MB	4.6%	3.39
Dec 19 11:00:00 - 11:59:59	1382	4.3%	0.38	12.3 MB	4.7%	3.49
Dec 19 12:00:00 - 12:59:59	1164	3.6%	0.32	10.2 MB	3.9%	2.89
Dec 19 13:00:00 - 13:59:59	1176	3.6%	0.33	9.9 MB	3.8%	2.80
Dec 19 14:00:00 - 14:59:59	1343	4.2%	0.37	10.8 MB	4.2%	3.06
Dec 19 15:00:00 - 15:59:59	1457	4.5%	0.40	9.4 MB	3.6%	2.67
Dec 19 16:00:00 - 16:59:59	1056	3.3%	0.29	9.7 MB	3.7%	2.75
Dec 19 17:00:00 - 17:59:59	1145	3.5%	0.32	9.5 MB	3.7%	2.70
Dec 19 18:00:00 - 18:59:59	1103	3.4%	0.31	9.3 MB	3.6%	2.63
Dec 19 19:00:00 - 19:59:59	1084	3.4%	0.30	8.5 MB	3.3%	2.41
Dec 19 20:00:00 - 20:59:59	868	2.7%	0.24	7.2 MB	2.8%	2.04
Dec 19 21:00:00 - 21:59:59	876	2.7%	0.24	6.0 MB	2.3%	1.70
Dec 19 22:00:00 - 22:59:59	950	2.9%	0.26	6.5 MB	2.5%	1.86
Dec 19 23:00:00 - 23:59:59	883	2.7%	0.25	6.1 MB	2.4%	1.75
Dec 20 00:00:00 - 00:59:59	1002	3.1%	0.28	7.4 MB	2.9%	2.11
Dec 20 01:00:00 - 01:59:59	1077	3.3%	0.30	8.5 MB	3.3%	2.42
Dec 20 02:00:00 - 02:59:59	928	2.9%	0.26	6.8 MB	2.6%	1.93
Dec 20 03:00:00 - 03:59:59	1136	3.5%	0.32	9.4 MB	3.7%	2.69
Dec 20 04:00:00 - 04:15:01	265	0.8%	0.29	2.9 MB	1.1%	3.26
TOTAL: 24:00:00	32274	100.0%	0.37	258.6 MB	100.0%	3.06

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news.glou.org	9202	0	1	9201
2	news.nntp4.net	5024	116	1	4907
3	usenet.pasdenom.info	47	0	0	47
4	nntp.terraraq.uk	38	0	0	38
5	gegeweb-out.news.tnetconsulting.net	34	0	0	34
6	news.samoylyk.net	1	0	0	1
TOTAL: 6		14346	116	2	14228

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.lettres.langue.allemande	68
fr.misc.gestion	48
TOTAL: 2	116

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	4498
Too many newsgroups	258
EMP (phn path)	89
Bad path (news.giganews.com.POSTED)	77
EMP (phl)	43
Cancel for rejected article	32
Cancels abusifs	6
Binary: misplaced binary	5
User-issued spam cancel	2
Cancel of non-existing ID <qt8n0id0f92hl2h9ldm1r3g1c7edi8mb3a@4ax.com>	1
Cancel of non-existing ID <g23n1itp2mekirr05h65kb9vas61sgomhc@4ax.com>	1
Cancel of non-existing ID <knfici5uui1tind74evo7m3i23kh779fq9@4ax.com>	1
Cancel of non-existing ID <l9hlth17skvt6rijgmvf6tgu8fmtc5fb8l@4ax.com>	1
Cancel of non-existing ID <tdlt2i96fsfc1r22c79vva9oalkn2t45f1@4ax.com>	1
Cancel of non-existing ID <fj5obipo6ov5jhtcpqpcumoijlte873fti@4ax.com>	1
Cancel of non-existing ID <hptksh9nkukptag2dhtar65p1av7biilvb@4ax.com>	1
Cancel of non-existing ID <q61d0it1a4pc7cbgu3h6626fto6s7jdrhk@4ax.com>	1
Cancel of non-existing ID <55s15it067f1fipgb1kqcui5ut1c2va80b@4ax.com>	1
Cancel of non-existing ID <krc23i95lmbp759obuk2mi5j3ugiuj4943@4ax.com>	1
Cancel of non-existing ID <up7gdipf2d6ppvcmlnh2jo5e6bblult3c0@4ax.com>	1
TOTAL: 9227	14228

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.nntp4.net	20617	631	14314	22	1959	3%	23:59:55
2	i2pn.org	49879	93	31194	723	143	0%	23:59:52
3	nntp.terraraq.uk	35417	73	30163	483	0	0%	23:59:55
4	news.samoylyk.net	44995	23	32023	180	910	0%	23:59:55
5	news.gegeweb.org	34150	22	29962	0	21	0%	23:59:48
6	news.tnetconsulting.net	45080	4	32034	235	1516	0%	23:59:55
7	news.pasdenom.info	35276	1	31638	8	0	0%	23:59:55
8	news.aioe.org	0	0	0	0	11332	0%	23:59:54
TOTAL: 8		265414	847	201328	1651	15881	0%	191:59:09

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.nntp4.net	3.4 MB	35.9 KB	3.5 MB	0.0 KB/s	5.5 KB	23:59:55
2	nntp.terraraq.uk	624.3 KB	4.4 MB	5.0 MB	0.1 KB/s	9.2 KB	23:59:55
3	i2pn.org	531.6 KB	6.7 MB	7.2 MB	0.1 KB/s	9.0 KB	23:59:52
4	news.gegeweb.org	158.4 KB	0.0 KB	158.4 KB	0.0 KB/s	7.2 KB	23:59:48
5	news.samoylyk.net	104.3 KB	1.4 MB	1.5 MB	0.0 KB/s	7.5 KB	23:59:55
6	news.tnetconsulting.net	11.0 KB	2.2 MB	2.2 MB	0.0 KB/s	9.4 KB	23:59:55
7	news.pasdenom.info	3.8 KB	95.2 KB	98.9 KB	0.0 KB/s	11.0 KB	23:59:55
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:54
TOTAL: 8		4.8 MB	14.7 MB	19.6 MB	0.0 KB/s	8.0 KB	191:59:09

NNRP readership statistics:

System		Conn	Elapsed
1	mail.gegeweb.eu	287	00:01:49
2	sys.gegeweb.eu	1	00:00:09
3	104.156.155.23	85	00:00:15
TOTAL: 3		373	00:02:13

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	85	00:00:15
2	*.gegeweb.eu	288	00:01:58
TOTAL: 2		373	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	4.561	4.323	1.581	00:01:49
104.156.155.23	2.356	0.976	0.000	00:00:15
sys.gegeweb.eu	0.025	0.029	0.005	00:00:09
TOTAL: 3	6.942	5.328	1.586	00:02:13

Daily Usenet report for news.niel.me

Dec 19 04:15:01 -- Dec 20 04:15:01