Daily Usenet report for news.niel.me: Nov 16 04:15:01

Unknown entries from news log file:

First 50 / 2853 lines (1.8%)

Nov 16 04:15:14 rpi-studio nnrpd[26660]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:15:44 rpi-studio nnrpd[26665]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:16:15 rpi-studio nnrpd[26697]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:16:45 rpi-studio nnrpd[26725]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:17:15 rpi-studio nnrpd[26775]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:17:47 rpi-studio nnrpd[26778]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:18:18 rpi-studio nnrpd[26795]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:18:48 rpi-studio nnrpd[26817]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:19:19 rpi-studio nnrpd[26834]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:19:49 rpi-studio nnrpd[26983]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:20:20 rpi-studio nnrpd[26998]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:20:50 rpi-studio nnrpd[27001]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:21:21 rpi-studio nnrpd[27011]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:21:51 rpi-studio nnrpd[27014]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:22:21 rpi-studio nnrpd[27025]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:22:51 rpi-studio nnrpd[27035]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:23:21 rpi-studio nnrpd[27046]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:23:51 rpi-studio nnrpd[27051]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:24:21 rpi-studio nnrpd[27067]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:24:51 rpi-studio nnrpd[27071]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:25:21 rpi-studio nnrpd[27081]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:25:51 rpi-studio nnrpd[27084]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:26:22 rpi-studio nnrpd[27090]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:26:52 rpi-studio nnrpd[27104]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:27:22 rpi-studio nnrpd[27109]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:27:52 rpi-studio nnrpd[27112]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:28:22 rpi-studio nnrpd[27117]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:28:52 rpi-studio nnrpd[27124]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:29:22 rpi-studio nnrpd[27284]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:29:52 rpi-studio nnrpd[27287]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:30:22 rpi-studio nnrpd[27304]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:30:52 rpi-studio nnrpd[27309]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:31:22 rpi-studio nnrpd[27327]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:31:52 rpi-studio nnrpd[27334]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:32:22 rpi-studio nnrpd[27341]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:32:53 rpi-studio nnrpd[27363]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:33:24 rpi-studio nnrpd[27370]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:33:54 rpi-studio nnrpd[27372]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:34:24 rpi-studio nnrpd[27390]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:34:54 rpi-studio nnrpd[27398]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:35:24 rpi-studio nnrpd[27408]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:35:55 rpi-studio nnrpd[27411]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:36:25 rpi-studio nnrpd[27416]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:36:55 rpi-studio nnrpd[27418]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:37:26 rpi-studio nnrpd[27423]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:37:56 rpi-studio nnrpd[27426]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:38:26 rpi-studio nnrpd[27431]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:38:57 rpi-studio nnrpd[27440]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:39:27 rpi-studio nnrpd[27598]: mx.terraraq.uk noperm ihave without permission
Nov 16 04:39:57 rpi-studio nnrpd[27604]: mx.terraraq.uk noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	12962	61.2%	1.3 MB	47.0%
inn	4196	19.8%	796.5 KB	29.1%
innd	2298	10.9%	370.7 KB	13.5%
innfeed	1705	8.1%	284.5 KB	10.4%
controlchan	1	0.0%	0.2 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	21163	100.0%	2.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	23309	60.9%
Negative hits	10904	28.5%
Do not exist	4075	10.6%
Cache misses	2	0.0%
TOTAL: 4	38290	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.042	0.0%	40	0.000	1.050	7.000
article cancel	00:00:00.011	0.0%	40	0.000	0.275	1.000
article cleanup	00:00:00.148	0.0%	4082	0.000	0.036	0.333
article logging	00:00:00.331	0.0%	4109	0.000	0.081	0.333
article parse	00:00:00.751	0.0%	8864	0.000	0.085	0.333
article write	00:00:04.494	0.0%	3708	0.053	1.212	37.000
artlog/artcncl	00:00:00.005	0.0%	40	0.000	0.125	1.000
artlog/artcncl/perl	00:00:00.003	0.0%	40	0.000	0.075	0.333
data move	00:00:00.120	0.0%	41837	0.000	0.003	0.022
hisgrep/artcncl	00:00:00.000	0.0%	40	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.005	0.0%	40	0.000	0.125	1.000
hisgrep/perl	00:00:00.003	0.0%	41	0.000	0.073	0.250
hishave/artcncl	00:00:00.001	0.0%	40	0.000	0.025	0.250
hishave/artcncl/perl	00:00:00.000	0.0%	40	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.561	0.0%	38275	0.000	0.015	0.058
history sync	00:00:00.070	0.0%	416	0.000	0.168	1.000
history write	00:00:01.186	0.0%	4082	0.000	0.291	7.462
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:59:59.854	99.9%	47414	771.256	1822.244	6385.138
nntp read	00:00:01.550	0.0%	42072	0.008	0.037	0.073
overview write	00:00:02.710	0.0%	3708	0.167	0.731	5.786
perl filter	00:00:23.984	0.0%	4082	3.864	5.876	12.615
python filter	00:00:00.004	0.0%	4082	0.000	0.001	0.059
site send	00:00:01.629	0.0%	7499	0.022	0.217	0.500
TOTAL: 24:01:07.227	24:00:37.462	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.048	0.0%	3777	0.000	0.013	0.103
article prepare	00:00:00.001	0.0%	312	0.000	0.003	0.050
article read	00:00:00.015	0.0%	63	0.000	0.238	8.000
backlog stats	00:00:00.129	0.0%	56926	0.000	0.002	0.011
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.802	0.0%	34438	0.000	0.023	0.070
data write	00:00:01.865	0.0%	30472	0.013	0.061	0.122
idle	23:57:56.029	100.0%	56926	776.762	1515.582	5475.191
status file	00:00:00.441	0.0%	173	1.000	2.549	7.000
TOTAL: 23:58:02.272	23:57:59.330	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:04:30.401	47.7%	10002	11.800	27.035	9564.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.574	0.1%	20316	0.000	0.028	1.000
TOTAL: 00:09:27.400	00:04:30.975	47.8%	-	-	-	-

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-out.aioe.org	2	3823	1669	2124	30	43%	25:04:17
2	i2pn.org	1	4149	1016	3002	131	24%	26:38:21
3	news.glou.org	1	3418	639	2722	57	18%	24:05:14
4	usenet.pasdenom.info	2	2675	168	2493	14	6%	23:59:43
5	feed-out.gegeweb.org	2	2489	167	2322	0	6%	23:59:29
6	news.nntp4.net	2	3796	131	3517	148	3%	24:16:25
7	gegeweb-out.news.tnetconsulting.net	1	3764	38	3722	4	1%	23:59:55
8	news.samoylyk.net	2	7010	33	6977	0	0%	45:52:23
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		38	31124	3861	26879	384	12%	217:55:47

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.aioe.org	5.2 MB	95.8 KB	5.3 MB	98%	3.2 KB
2	news.glou.org	4.8 MB	345.1 KB	5.1 MB	93%	7.6 KB
3	i2pn.org	3.2 MB	368.8 KB	3.5 MB	89%	3.2 KB
4	news.nntp4.net	1.1 MB	748.0 KB	1.8 MB	60%	6.7 KB
5	news.samoylyk.net	936.6 KB	0.0 KB	936.6 KB	100%	28.4 KB
6	feed-out.gegeweb.org	555.9 KB	0.0 KB	555.9 KB	100%	3.3 KB
7	usenet.pasdenom.info	416.0 KB	32.5 KB	448.5 KB	92%	2.5 KB
8	gegeweb-out.news.tnetconsulting.net	323.7 KB	13.0 KB	336.7 KB	96%	8.0 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		16.5 MB	1.6 MB	18.0 MB	91%	4.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 16 04:15:01 - 04:59:59	83	2.2%	0.03	243.2 KB	1.5%	0.09
Nov 16 05:00:00 - 05:59:59	52	1.4%	0.01	142.3 KB	0.9%	0.04
Nov 16 06:00:00 - 06:59:59	71	1.9%	0.02	202.8 KB	1.3%	0.06
Nov 16 07:00:00 - 07:59:59	102	2.7%	0.03	339.8 KB	2.1%	0.09
Nov 16 08:00:00 - 08:59:59	126	3.4%	0.04	511.8 KB	3.2%	0.14
Nov 16 09:00:00 - 09:59:59	138	3.7%	0.04	745.0 KB	4.7%	0.21
Nov 16 10:00:00 - 10:59:59	172	4.6%	0.05	490.5 KB	3.1%	0.14
Nov 16 11:00:00 - 11:59:59	144	3.9%	0.04	497.9 KB	3.1%	0.14
Nov 16 12:00:00 - 12:59:59	156	4.2%	0.04	486.6 KB	3.1%	0.14
Nov 16 13:00:00 - 13:59:59	142	3.8%	0.04	430.1 KB	2.7%	0.12
Nov 16 14:00:00 - 14:59:59	168	4.5%	0.05	758.7 KB	4.8%	0.21
Nov 16 15:00:00 - 15:59:59	166	4.5%	0.05	580.2 KB	3.7%	0.16
Nov 16 16:00:00 - 16:59:59	195	5.3%	0.05	1.1 MB	6.9%	0.30
Nov 16 17:00:00 - 17:59:59	224	6.0%	0.06	1.1 MB	7.2%	0.32
Nov 16 18:00:00 - 18:59:59	212	5.7%	0.06	697.3 KB	4.4%	0.19
Nov 16 19:00:00 - 19:59:59	234	6.3%	0.07	780.4 KB	4.9%	0.22
Nov 16 20:00:00 - 20:59:59	203	5.5%	0.06	615.3 KB	3.9%	0.17
Nov 16 21:00:00 - 21:59:59	172	4.6%	0.05	579.3 KB	3.7%	0.16
Nov 16 22:00:00 - 22:59:59	209	5.6%	0.06	1.5 MB	9.6%	0.42
Nov 16 23:00:00 - 23:59:59	171	4.6%	0.05	549.2 KB	3.5%	0.15
Nov 17 00:00:00 - 00:59:59	161	4.3%	0.04	935.1 KB	5.9%	0.26
Nov 17 01:00:00 - 01:59:59	138	3.7%	0.04	485.7 KB	3.1%	0.13
Nov 17 02:00:00 - 02:59:59	121	3.3%	0.03	1.4 MB	8.8%	0.39
Nov 17 03:00:00 - 03:59:59	112	3.0%	0.03	523.7 KB	3.3%	0.15
Nov 17 04:00:00 - 04:15:01	42	1.1%	0.05	114.5 KB	0.7%	0.13
TOTAL: 24:00:00	3714	100.0%	0.04	15.5 MB	100.0%	0.18

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	171	0	171
2	i2pn.org	118	0	118
3	news.glou.org	57	0	57
4	news-out.aioe.org	52	2	50
5	feed-out.gegeweb.org	3	0	3
6	usenet.pasdenom.info	1	0	1
TOTAL: 6		402	2	400

Unwanted newsgroups [Top 20]:

Newsgroup	Count
news.test	1
soc.culture.iran	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
EMP (phn nph)	146
EMP (phl)	64
EMP (phn path)	59
EMP (md5)	41
Bad path (news.giganews.com.POSTED)	38
Too many newsgroups	23
Cancel of <mn.82637e6b3488dfd1.145333@gmail.com> without Cancel-Key	1
Cancels abusifs	1
TOTAL: 8	373

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news-out.aioe.org	22
	feed-out.gegeweb.org	3
	i2pn.org	1
	news.nntp4.net	1
	TOTAL: 4	27
TOTAL: 1		27

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.nntp4.net	4015	13	3563	0	0	0%	23:59:57
2	news.gegeweb.org	3601	9	3532	2	0	0%	23:59:57
3	nntp.terraraq.uk	2954	9	2455	24	0	0%	23:59:22
4	news.tnetconsulting.net	5840	7	3704	2	33	0%	23:59:32
5	i2pn.org	4749	4	2731	2	36	0%	23:59:57
6	news.aioe.org	1764	3	1526	0	0	0%	23:55:27
7	news.samoylyk.net	4403	1	3691	2	0	0%	23:59:57
8	news.pasdenom.info	3547	0	3123	0	0	0%	23:59:29
TOTAL: 8		30873	46	24325	32	69	0%	191:53:38

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.nntp4.net	521.3 KB	0.0 KB	521.3 KB	40.1 KB	23:59:57
2	news.aioe.org	54.6 KB	0.0 KB	54.6 KB	18.2 KB	23:55:27
3	nntp.terraraq.uk	44.6 KB	2.1 MB	2.2 MB	67.9 KB	23:59:22
4	news.tnetconsulting.net	25.7 KB	8.3 KB	34.0 KB	3.8 KB	23:59:32
5	news.gegeweb.org	18.3 KB	8.5 KB	26.8 KB	2.4 KB	23:59:57
6	i2pn.org	12.0 KB	8.3 KB	20.3 KB	3.4 KB	23:59:57
7	news.samoylyk.net	2.1 KB	8.3 KB	10.4 KB	3.5 KB	23:59:57
8	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:59:29
TOTAL: 8		678.6 KB	2.2 MB	2.8 MB	37.3 KB	191:53:38

NNRP readership statistics:

System		Conn	Elapsed
1	bubbly.monitoring.internet-measurement.com	1	00:00:00
2	unforgettable.monitoring.internet-measurement.com	1	00:00:00
3	192.241.206.94	1	00:00:09
4	mail.gegeweb.eu	288	00:00:36
5	merciful.monitoring.internet-measurement.com	1	00:00:00
6	mx.terraraq.uk	2853	00:08:17
7	sensational.monitoring.internet-measurement.com	1	00:00:00
8	astounding.monitoring.internet-measurement.com	2	00:00:00
9	genius.monitoring.internet-measurement.com	1	00:00:00
10	delightful.monitoring.internet-measurement.com	1	00:00:00
11	prepared.monitoring.internet-measurement.com	1	00:00:00
12	sensitive.monitoring.internet-measurement.com	1	00:00:06
13	great.monitoring.internet-measurement.com	1	00:00:00
14	renowned.monitoring.internet-measurement.com	1	00:00:00
15	supporting.monitoring.internet-measurement.com	1	00:00:00
16	192.241.206.233	1	00:00:00
17	proud.monitoring.internet-measurement.com	2	00:00:00
18	happy.monitoring.internet-measurement.com	1	00:00:00
19	meritorious.monitoring.internet-measurement.com	1	00:00:06
20	sagacious.monitoring.internet-measurement.com	1	00:00:00
21	pleasant.monitoring.internet-measurement.com	1	00:00:00
22	celebrated.monitoring.internet-measurement.com	1	00:00:00
23	brilliant.monitoring.internet-measurement.com	1	00:00:00
24	refined.monitoring.internet-measurement.com	1	00:00:00
TOTAL: 24		3165	00:09:22

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.gegeweb.eu	288	00:00:36
2	unresolved	2	00:00:10
3	*.terraraq.uk	2853	00:08:17
4	*.monitoring.internet-measurement.com	22	00:00:19
TOTAL: 4		3165	00:09:22

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
mx.terraraq.uk	76.417	76.415	0.000	00:08:17
mail.gegeweb.eu	7.882	8.028	2.315	00:00:36
192.241.206.94	0.014	0.042	0.000	00:00:09
meritorious.monitoring.internet-measurement.com	0.067	0.022	0.000	00:00:06
sensitive.monitoring.internet-measurement.com	0.055	0.037	0.000	00:00:06
proud.monitoring.internet-measurement.com	0.051	0.037	0.000	00:00:00
sensational.monitoring.internet-measurement.com	0.063	0.024	0.000	00:00:00
genius.monitoring.internet-measurement.com	0.025	0.008	0.000	00:00:00
192.241.206.233	0.033	0.022	0.000	00:00:00
celebrated.monitoring.internet-measurement.com	0.045	0.027	0.000	00:00:00
astounding.monitoring.internet-measurement.com	0.045	0.009	0.000	00:00:00
merciful.monitoring.internet-measurement.com	0.066	0.019	0.000	00:00:00
pleasant.monitoring.internet-measurement.com	0.019	0.019	0.000	00:00:00
bubbly.monitoring.internet-measurement.com	0.058	0.019	0.000	00:00:00
brilliant.monitoring.internet-measurement.com	0.038	0.000	0.000	00:00:00
happy.monitoring.internet-measurement.com	0.045	0.009	0.000	00:00:00
supporting.monitoring.internet-measurement.com	0.012	0.024	0.000	00:00:00
sagacious.monitoring.internet-measurement.com	0.086	0.000	0.000	00:00:00
refined.monitoring.internet-measurement.com	0.009	0.009	0.000	00:00:00
unforgettable.monitoring.internet-measurement.com	0.027	0.009	0.000	00:00:00
TOTAL: 24	85.119	84.806	2.315	00:09:22

NNRP unrecognized commands (by host):

System	Conn
192.241.206.233	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

Nov 16 04:15:01 -- Nov 17 04:15:01