Daily Usenet report for news.niel.me: Apr 9 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Apr 9 13:55:34 rpi-studio innd: filter: No Cancel-Key[sha1:OqJ4A+8IIPB23e0e244Q2Zu/bKM=#015#012#011sha1:oM2kuk16vhO4L5mx0Qj5e1LRt+s=] matches Cancel-Lock[sha1:v5Sg73J/GiIv6/X6eFdmwV7IcKc= sha1:v5Sg73J/GiIv6/X6eFdmwV7IcPc= sha1:v5Sg73J/GiIv6/X6eFdmwV7IcMc=sha1:v5Sg73J/GiIv6/X6eFdmwV7IcKc= sha1:v5Sg73J/GiIv6/X6eFdmwV7IcKc= sha1:v5Sg73J/GiIv6/X6eFdmwV7IcKc= sha1:v5Sg73J/GiIv6/X6eFdmwV7IcKc= sha1:v5Sg73J/GiIv6/X6eFdmwV7IcKc=] target=<1q8xa9j.159jwit1ki74hcN%mv@gmail.invalid>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3135	35.3%	600.3 KB	41.0%
innd	2250	25.3%	377.1 KB	25.7%
nnrpd	1748	19.7%	190.4 KB	13.0%
innfeed	1741	19.6%	297.5 KB	20.3%
nocem	1	0.0%	0.2 KB	0.0%
controlchan	1	0.0%	0.0 KB	0.0%
TOTAL: 6	8876	100.0%	1.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	16825	63.6%
Negative hits	6557	24.8%
Do not exist	3066	11.6%
Cache misses	1	0.0%
TOTAL: 4	26449	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.028	0.0%	20	0.000	1.400	5.000
article cancel	00:00:00.003	0.0%	21	0.000	0.143	1.000
article cleanup	00:00:00.078	0.0%	3078	0.000	0.025	0.182
article logging	00:00:00.250	0.0%	3095	0.000	0.081	0.312
article parse	00:00:00.495	0.0%	6485	0.000	0.076	0.417
article write	00:00:04.349	0.0%	2746	0.333	1.584	3.375
artlog/artcncl	00:00:00.000	0.0%	21	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.002	0.0%	20	0.000	0.100	1.000
data move	00:00:00.085	0.0%	29274	0.000	0.003	0.026
hisgrep/artcncl	00:00:00.002	0.0%	20	0.000	0.100	1.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	20	0.000	0.000	0.000
hisgrep/perl	00:00:00.001	0.0%	22	0.000	0.045	0.500
hishave/artcncl	00:00:00.000	0.0%	21	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	20	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.326	0.0%	26520	0.000	0.012	0.052
history sync	00:00:00.043	0.0%	408	0.000	0.105	0.667
history write	00:00:00.617	0.0%	3078	0.000	0.200	0.800
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	24:00:29.847	99.9%	30464	1294.427	2837.114	16681.083
nntp read	00:00:00.999	0.0%	29771	0.000	0.034	0.079
overview write	00:00:01.668	0.0%	2746	0.083	0.607	12.333
perl filter	00:00:13.823	0.0%	3078	3.048	4.491	9.130
python filter	00:00:00.009	0.0%	3078	0.000	0.003	0.083
site send	00:00:01.020	0.0%	5560	0.000	0.183	0.500
TOTAL: 24:01:16.697	24:00:53.645	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.023	0.0%	2737	0.000	0.008	0.125
article prepare	00:00:00.001	0.0%	848	0.000	0.001	0.083
article read	00:00:00.024	0.0%	163	0.000	0.147	8.000
backlog stats	00:00:00.120	0.0%	42068	0.000	0.003	0.014
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.552	0.0%	23495	0.000	0.023	0.081
data write	00:00:01.190	0.0%	20857	0.000	0.057	0.147
idle	23:51:08.705	100.0%	42068	927.147	2041.188	5624.464
status file	00:00:00.452	0.0%	199	0.000	2.271	7.000
TOTAL: 23:51:19.184	23:51:11.067	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:29.721	26.9%	1451	10.800	20.483	5312.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.084	0.1%	3223	0.000	0.026	0.273
TOTAL: 00:01:50.413	00:00:29.805	27.0%	-	-	-	-

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	3	1845	975	868	2	52%	23:55:40
2	news.glou.org	1	2706	626	2027	53	23%	25:37:35
3	i2pn.org	2	2593	583	1912	98	22%	21:24:03
4	nntp.terraraq.uk	2	2292	179	2075	38	7%	22:15:15
5	usenet.pasdenom.info	2	1836	176	1641	19	9%	18:52:35
6	news.samoylyk.net	2	5101	114	4983	4	2%	36:59:59
7	gegeweb-out.news.tnetconsulting.net	2	3137	55	3078	4	1%	23:57:33
8	news.nntp4.net	1	1353	42	1237	74	3%	08:05:00
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		40	20863	2750	17821	292	13%	181:07:40

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.glou.org	3.5 MB	161.3 KB	3.6 MB	95%	5.5 KB
2	feed-out.gegeweb.org	3.4 MB	2.7 KB	3.4 MB	99%	3.6 KB
3	news.samoylyk.net	2.2 MB	6.5 KB	2.2 MB	99%	19.1 KB
4	i2pn.org	1.9 MB	223.0 KB	2.1 MB	89%	3.1 KB
5	gegeweb-out.news.tnetconsulting.net	706.1 KB	7.5 KB	713.6 KB	98%	12.1 KB
6	nntp.terraraq.uk	599.1 KB	78.9 KB	678.0 KB	88%	3.1 KB
7	usenet.pasdenom.info	389.8 KB	32.3 KB	422.1 KB	92%	2.2 KB
8	news.nntp4.net	159.2 KB	189.2 KB	348.4 KB	45%	3.0 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		12.8 MB	701.3 KB	13.4 MB	94%	4.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 9 04:15:01 - 04:59:59	48	1.7%	0.02	176.3 KB	1.5%	0.07
Apr 9 05:00:00 - 05:59:59	114	4.2%	0.03	435.1 KB	3.7%	0.12
Apr 9 06:00:00 - 06:59:59	107	3.9%	0.03	829.1 KB	7.0%	0.23
Apr 9 07:00:00 - 07:59:59	61	2.2%	0.02	276.7 KB	2.3%	0.08
Apr 9 08:00:00 - 08:59:59	49	1.8%	0.01	180.1 KB	1.5%	0.05
Apr 9 09:00:00 - 09:59:59	71	2.6%	0.02	196.8 KB	1.7%	0.05
Apr 9 10:00:00 - 10:59:59	88	3.2%	0.02	334.7 KB	2.8%	0.09
Apr 9 11:00:00 - 11:59:59	103	3.8%	0.03	523.3 KB	4.4%	0.15
Apr 9 12:00:00 - 12:59:59	88	3.2%	0.02	407.8 KB	3.4%	0.11
Apr 9 13:00:00 - 13:59:59	104	3.8%	0.03	443.7 KB	3.7%	0.12
Apr 9 14:00:00 - 14:59:59	110	4.0%	0.03	299.2 KB	2.5%	0.08
Apr 9 15:00:00 - 15:59:59	107	3.9%	0.03	433.3 KB	3.6%	0.12
Apr 9 16:00:00 - 16:59:59	162	5.9%	0.04	703.5 KB	5.9%	0.20
Apr 9 17:00:00 - 17:59:59	153	5.6%	0.04	500.8 KB	4.2%	0.14
Apr 9 18:00:00 - 18:59:59	142	5.2%	0.04	448.6 KB	3.8%	0.12
Apr 9 19:00:00 - 19:59:59	157	5.7%	0.04	487.8 KB	4.1%	0.14
Apr 9 20:00:00 - 20:59:59	191	7.0%	0.05	911.1 KB	7.7%	0.25
Apr 9 21:00:00 - 21:59:59	146	5.3%	0.04	595.1 KB	5.0%	0.17
Apr 9 22:00:00 - 22:59:59	146	5.3%	0.04	603.2 KB	5.1%	0.17
Apr 9 23:00:00 - 23:59:59	132	4.8%	0.04	550.4 KB	4.6%	0.15
Apr 10 00:00:00 - 00:59:59	138	5.0%	0.04	655.9 KB	5.5%	0.18
Apr 10 01:00:00 - 01:59:59	132	4.8%	0.04	465.1 KB	3.9%	0.13
Apr 10 02:00:00 - 02:59:59	94	3.4%	0.03	576.3 KB	4.8%	0.16
Apr 10 03:00:00 - 03:59:59	79	2.9%	0.02	785.3 KB	6.6%	0.22
Apr 10 04:00:00 - 04:15:01	22	0.8%	0.02	77.8 KB	0.7%	0.09
TOTAL: 24:00:00	2744	100.0%	0.03	11.6 MB	100.0%	0.14

Sites sending bad articles:

Server		Total	Group	Other
1	i2pn.org	112	0	112
2	news.nntp4.net	103	1	102
3	news.glou.org	50	0	50
4	nntp.terraraq.uk	39	0	39
5	news.samoylyk.net	15	0	15
6	usenet.pasdenom.info	12	0	12
7	gegeweb-out.news.tnetconsulting.net	11	7	4
8	feed-out.gegeweb.org	8	0	8
TOTAL: 8		350	8	342

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.petites-annonces.informatique.materiel	4
fr.petites-annonces.divers	4
TOTAL: 2	8

INND Perl filter [Top 20]:

Reason	Count
EMP (phl)	110
Bad path (news.giganews.com.POSTED)	57
Too many newsgroups	49
EMP (phn path)	47
EMP (md5)	43
EMP (phn nph)	9
hierarchy violation - crosspost outside sdnet	6
Cancels abusifs	2
No Cancel-Key matches Cancel-Lock. target=<1q8xa9j.159jwit1ki74hcN%mv@gmail.invalid>	1
Cancel for rejected article	1
TOTAL: 10	325

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news.samoylyk.net	11
	feed-out.gegeweb.org	6
	TOTAL: 2	17
TOTAL: 1		17

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.nntp4.net	3347	67	2618	4	0	2%	23:59:51
2	nntp.terraraq.uk	2235	44	1776	9	1	1%	23:59:51
3	i2pn.org	3402	41	2037	2	0	1%	23:58:53
4	news.gegeweb.org	1863	20	1752	7	0	1%	23:58:51
5	news.tnetconsulting.net	4250	12	2727	2	2	0%	23:59:51
6	news.samoylyk.net	3122	3	2657	1	0	0%	23:58:39
7	news.pasdenom.info	2500	0	2416	0	0	0%	23:59:51
8	news.aioe.org	0	0	0	0	2088	0%	23:59:51
TOTAL: 8		20719	187	15983	25	2091	0%	191:55:38

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.nntp4.net	294.3 KB	10.2 KB	304.5 KB	4.3 KB	23:59:51
2	nntp.terraraq.uk	221.9 KB	170.4 KB	392.2 KB	7.4 KB	23:59:51
3	i2pn.org	135.1 KB	3.3 KB	138.4 KB	3.2 KB	23:58:53
4	news.samoylyk.net	89.0 KB	1.4 KB	90.4 KB	22.6 KB	23:58:39
5	news.gegeweb.org	84.4 KB	16.5 KB	101.0 KB	3.7 KB	23:58:51
6	news.tnetconsulting.net	33.0 KB	4.3 KB	37.3 KB	2.7 KB	23:59:51
7	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:59:51
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:51
TOTAL: 8		857.6 KB	206.1 KB	1.0 MB	5.0 KB	191:55:38

NNRP readership statistics:

System		Conn	Elapsed
1	146.70.97.199	1	00:00:00
2	mail.gegeweb.eu	288	00:01:34
3	zl-lax-us-gp1-wk125.internet-census.org	1	00:00:11
4	88.165.5.23	1	00:00:00
5	192.241.210.44	1	00:00:00
6	equitable.monitoring.internet-measurement.com	1	00:00:01
TOTAL: 6		293	00:01:49

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	3	00:00:01
2	*.internet-census.org	1	00:00:11
3	*.monitoring.internet-measurement.com	1	00:00:01
4	*.gegeweb.eu	288	00:01:34
TOTAL: 4		293	00:01:49

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.126	4.981	1.967	00:01:34
zl-lax-us-gp1-wk125.internet-census.org	0.037	0.012	0.000	00:00:11
equitable.monitoring.internet-measurement.com	0.025	0.000	0.000	00:00:01
146.70.97.199	0.022	0.032	0.000	00:00:00
88.165.5.23	0.032	0.021	0.000	00:00:00
192.241.210.44	0.010	0.042	0.000	00:00:00
TOTAL: 6	4.252	5.088	1.967	00:01:49

NNRP unrecognized commands (by host):

System	Conn
192.241.210.44	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
MGLNDD_45.13.105.36_119	1
TOTAL: 1	1

Daily Usenet report for news.niel.me

Apr 9 04:15:01 -- Apr 10 04:15:01