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

Unknown entries from news log file:

First 50 / 2858 lines (1.7%)

Nov 14 04:15:04 rpi-studio nnrpd[31962]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:15:34 rpi-studio nnrpd[32090]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:16:05 rpi-studio nnrpd[32120]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:16:35 rpi-studio nnrpd[32131]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:17:05 rpi-studio nnrpd[32340]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:17:35 rpi-studio nnrpd[32345]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:18:06 rpi-studio nnrpd[32349]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:18:36 rpi-studio nnrpd[32353]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:19:07 rpi-studio nnrpd[32370]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:19:38 rpi-studio nnrpd[32373]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:20:08 rpi-studio nnrpd[32392]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:20:38 rpi-studio nnrpd[32396]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:21:09 rpi-studio nnrpd[32405]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:21:39 rpi-studio nnrpd[32409]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:22:10 rpi-studio nnrpd[32423]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:22:40 rpi-studio nnrpd[32431]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:23:10 rpi-studio nnrpd[32436]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:23:41 rpi-studio nnrpd[32440]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:24:11 rpi-studio nnrpd[32461]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:24:41 rpi-studio nnrpd[32465]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:25:11 rpi-studio nnrpd[32475]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:25:41 rpi-studio nnrpd[32483]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:26:11 rpi-studio nnrpd[32488]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:26:41 rpi-studio nnrpd[32490]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:27:11 rpi-studio nnrpd[32635]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:27:41 rpi-studio nnrpd[32638]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:28:11 rpi-studio nnrpd[32655]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:28:41 rpi-studio nnrpd[32659]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:29:11 rpi-studio nnrpd[32676]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:29:41 rpi-studio nnrpd[32679]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:30:11 rpi-studio nnrpd[32691]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:30:41 rpi-studio nnrpd[32694]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:31:11 rpi-studio nnrpd[32698]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:31:42 rpi-studio nnrpd[32701]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:32:12 rpi-studio nnrpd[32708]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:32:42 rpi-studio nnrpd[32715]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:33:12 rpi-studio nnrpd[32734]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:33:42 rpi-studio nnrpd[32743]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:34:13 rpi-studio nnrpd[32760]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:34:43 rpi-studio nnrpd[32763]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:35:13 rpi-studio nnrpd[305]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:35:43 rpi-studio nnrpd[312]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:36:13 rpi-studio nnrpd[319]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:36:44 rpi-studio nnrpd[321]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:37:15 rpi-studio nnrpd[493]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:37:45 rpi-studio nnrpd[495]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:38:15 rpi-studio nnrpd[500]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:38:47 rpi-studio nnrpd[504]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:39:17 rpi-studio nnrpd[533]: mx.terraraq.uk noperm ihave without permission
Nov 14 04:39:48 rpi-studio nnrpd[535]: mx.terraraq.uk noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	12891	62.7%	1.3 MB	49.4%
inn	3681	17.9%	692.0 KB	26.4%
innd	2282	11.1%	370.7 KB	14.1%
innfeed	1709	8.3%	265.5 KB	10.1%
nocem	1	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.0 KB	0.0%
TOTAL: 6	20565	100.0%	2.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	20614	62.2%
Negative hits	8910	26.9%
Do not exist	3599	10.9%
Cache misses	1	0.0%
TOTAL: 4	33124	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.011	0.0%	9	1.000	1.222	3.000
article cancel	00:00:00.002	0.0%	9	0.000	0.222	1.000
article cleanup	00:00:00.116	0.0%	3611	0.000	0.032	0.200
article logging	00:00:00.288	0.0%	3668	0.000	0.079	0.286
article parse	00:00:00.582	0.0%	6808	0.000	0.085	0.250
article write	00:00:01.985	0.0%	3245	0.000	0.612	2.412
artlog/artcncl	00:00:00.000	0.0%	9	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.001	0.0%	9	0.000	0.111	1.000
data move	00:00:00.104	0.0%	35262	0.000	0.003	0.018
hisgrep/artcncl	00:00:00.000	0.0%	9	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	9	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%	9	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	9	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.440	0.0%	33191	0.000	0.013	0.048
history sync	00:00:00.080	0.0%	425	0.000	0.188	0.667
history write	00:00:00.866	0.0%	3610	0.000	0.240	0.695
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:17.338	99.9%	41238	1119.414	2095.575	5360.634
nntp read	00:00:01.259	0.0%	35474	0.008	0.035	0.067
overview write	00:00:02.302	0.0%	3245	0.050	0.709	7.000
perl filter	00:00:20.663	0.0%	3610	3.519	5.724	12.036
python filter	00:00:00.006	0.0%	3610	0.000	0.002	0.125
site send	00:00:01.591	0.0%	6557	0.000	0.243	0.571
TOTAL: 24:01:18.013	24:00:47.634	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.036	0.0%	3316	0.000	0.011	0.167
article prepare	00:00:00.000	0.0%	320	0.000	0.000	0.000
article read	00:00:00.019	0.0%	51	0.000	0.373	8.000
backlog stats	00:00:00.098	0.0%	49865	0.000	0.002	0.014
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.717	0.0%	29515	0.000	0.024	0.065
data write	00:00:01.629	0.0%	26363	0.025	0.062	0.109
idle	23:59:36.648	100.0%	49865	890.622	1732.210	4951.333
status file	00:00:01.409	0.0%	178	1.000	7.916	929.000
TOTAL: 23:59:45.787	23:59:40.556	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:04:47.691	50.0%	10019	12.000	28.715	10023.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.590	0.1%	20326	0.000	0.029	0.333
TOTAL: 00:09:35.021	00:04:48.281	50.1%	-	-	-	-

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	3435	1659	1746	30	48%	24:49:53
2	i2pn.org	1	3489	766	2562	161	21%	24:23:00
3	news.glou.org	2	2881	430	2394	57	14%	23:33:33
4	feed-out.gegeweb.org	3	2294	254	2034	6	11%	23:58:35
5	gegeweb-out.news.tnetconsulting.net	2	3468	35	3424	9	1%	23:59:42
6	news.samoylyk.net	2	3041	12	3029	0	0%	21:59:00
7	usenet.pasdenom.info	1	240	6	234	0	2%	04:14:58
8	localhost	25	0	0	0	0	0%	00:00:00
9	news.nntp4.net	1	97	0	96	1	0%	00:56:38
TOTAL: 9		39	18945	3162	15519	264	16%	147:55:19

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.aioe.org	5.7 MB	212.1 KB	5.9 MB	96%	3.6 KB
2	i2pn.org	2.5 MB	386.5 KB	2.9 MB	86%	3.1 KB
3	news.glou.org	2.1 MB	209.9 KB	2.3 MB	91%	4.8 KB
4	feed-out.gegeweb.org	858.0 KB	12.0 KB	870.0 KB	98%	3.3 KB
5	gegeweb-out.news.tnetconsulting.net	572.5 KB	19.8 KB	592.4 KB	96%	13.5 KB
6	news.samoylyk.net	342.3 KB	0.0 KB	342.3 KB	100%	28.5 KB
7	usenet.pasdenom.info	28.3 KB	0.0 KB	28.3 KB	100%	4.7 KB
8	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
9	news.nntp4.net	0.0 KB	1.7 KB	1.7 KB	0%	1.7 KB
TOTAL: 9		12.0 MB	842.0 KB	12.8 MB	93%	3.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 14 04:15:01 - 04:59:59	46	1.4%	0.02	149.6 KB	1.1%	0.06
Nov 14 05:00:00 - 05:59:59	64	2.0%	0.02	342.1 KB	2.6%	0.10
Nov 14 06:00:00 - 06:59:59	92	2.8%	0.03	370.5 KB	2.8%	0.10
Nov 14 07:00:00 - 07:59:59	72	2.2%	0.02	998.3 KB	7.7%	0.28
Nov 14 08:00:00 - 08:59:59	55	1.7%	0.02	163.5 KB	1.3%	0.05
Nov 14 09:00:00 - 09:59:59	76	2.3%	0.02	339.9 KB	2.6%	0.09
Nov 14 10:00:00 - 10:59:59	118	3.6%	0.03	442.1 KB	3.4%	0.12
Nov 14 11:00:00 - 11:59:59	145	4.5%	0.04	534.1 KB	4.1%	0.15
Nov 14 12:00:00 - 12:59:59	123	3.8%	0.03	623.4 KB	4.8%	0.17
Nov 14 13:00:00 - 13:59:59	123	3.8%	0.03	371.4 KB	2.8%	0.10
Nov 14 14:00:00 - 14:59:59	133	4.1%	0.04	445.5 KB	3.4%	0.12
Nov 14 15:00:00 - 15:59:59	154	4.7%	0.04	507.3 KB	3.9%	0.14
Nov 14 16:00:00 - 16:59:59	200	6.2%	0.06	701.7 KB	5.4%	0.19
Nov 14 17:00:00 - 17:59:59	176	5.4%	0.05	567.5 KB	4.4%	0.16
Nov 14 18:00:00 - 18:59:59	241	7.4%	0.07	884.2 KB	6.8%	0.25
Nov 14 19:00:00 - 19:59:59	221	6.8%	0.06	677.9 KB	5.2%	0.19
Nov 14 20:00:00 - 20:59:59	149	4.6%	0.04	874.1 KB	6.7%	0.24
Nov 14 21:00:00 - 21:59:59	174	5.4%	0.05	528.7 KB	4.1%	0.15
Nov 14 22:00:00 - 22:59:59	187	5.8%	0.05	836.1 KB	6.4%	0.23
Nov 14 23:00:00 - 23:59:59	198	6.1%	0.06	673.2 KB	5.2%	0.19
Nov 15 00:00:00 - 00:59:59	138	4.3%	0.04	441.7 KB	3.4%	0.12
Nov 15 01:00:00 - 01:59:59	152	4.7%	0.04	623.9 KB	4.8%	0.17
Nov 15 02:00:00 - 02:59:59	105	3.2%	0.03	581.9 KB	4.5%	0.16
Nov 15 03:00:00 - 03:59:59	81	2.5%	0.02	299.9 KB	2.3%	0.08
Nov 15 04:00:00 - 04:15:01	20	0.6%	0.02	63.9 KB	0.5%	0.07
TOTAL: 24:00:00	3243	100.0%	0.04	12.7 MB	100.0%	0.15

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	i2pn.org	158	0	0	158
2	news.nntp4.net	99	0	0	99
3	news-out.aioe.org	85	1	0	84
4	news.glou.org	57	0	0	57
5	gegeweb-out.news.tnetconsulting.net	10	0	1	9
6	feed-out.gegeweb.org	8	0	0	8
7	usenet.pasdenom.info	3	0	0	3
TOTAL: 7		420	1	1	418

Unwanted newsgroups [Top 20]:

Newsgroup	Count
news.test	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (phl)	137
EMP (phn path)	67
Bad path (news.giganews.com.POSTED)	61
EMP (phn nph)	59
EMP (md5)	26
Too many newsgroups	11
TOTAL: 6	361

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news-out.aioe.org	55
	feed-out.gegeweb.org	2
	TOTAL: 2	57
TOTAL: 1		57

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.nntp4.net	3479	18	3156	0	1	0%	23:59:00
2	i2pn.org	4409	15	2452	6	35	0%	23:58:20
3	news.tnetconsulting.net	5205	12	3224	4	37	0%	23:59:54
4	nntp.terraraq.uk	2434	5	2076	9	0	0%	23:59:54
5	news.aioe.org	1319	3	1192	0	0	0%	23:53:30
6	news.gegeweb.org	3072	3	2995	0	0	0%	23:58:54
7	news.samoylyk.net	3817	2	3227	2	0	0%	23:59:54
8	news.pasdenom.info	2960	0	2642	1	0	0%	23:59:54
TOTAL: 8		26695	58	20964	22	73	0%	191:49:20

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.nntp4.net	250.1 KB	0.0 KB	250.1 KB	13.9 KB	23:59:00
2	news.tnetconsulting.net	87.0 KB	18.5 KB	105.5 KB	6.6 KB	23:59:54
3	i2pn.org	53.6 KB	23.2 KB	76.8 KB	3.7 KB	23:58:20
4	news.aioe.org	21.8 KB	0.0 KB	21.8 KB	7.3 KB	23:53:30
5	nntp.terraraq.uk	17.8 KB	304.7 KB	322.5 KB	23.0 KB	23:59:54
6	news.samoylyk.net	15.2 KB	7.7 KB	22.8 KB	5.7 KB	23:59:54
7	news.gegeweb.org	5.9 KB	0.0 KB	5.9 KB	2.0 KB	23:58:54
8	news.pasdenom.info	0.0 KB	2.2 KB	2.2 KB	2.2 KB	23:59:54
TOTAL: 8		451.3 KB	356.2 KB	807.6 KB	10.1 KB	191:49:20

NNRP readership statistics:

System		Conn	Elapsed
1	mx.terraraq.uk	2858	00:08:38
2	natural.monitoring.internet-measurement.com	1	00:00:01
3	143.198.1.79	1	00:00:10
4	192.241.199.73	1	00:00:00
5	mail.gegeweb.eu	288	00:00:36
6	thrifty.monitoring.internet-measurement.com	1	00:00:02
TOTAL: 6		3150	00:09:30

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.gegeweb.eu	288	00:00:36
2	*.monitoring.internet-measurement.com	2	00:00:04
3	unresolved	2	00:00:11
4	*.terraraq.uk	2858	00:08:38
TOTAL: 4		3150	00:09:30

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.895	76.065	0.000	00:08:38
mail.gegeweb.eu	7.785	8.296	2.238	00:00:36
143.198.1.79	0.023	0.034	0.000	00:00:10
thrifty.monitoring.internet-measurement.com	0.027	0.027	0.000	00:00:02
natural.monitoring.internet-measurement.com	0.024	0.024	0.000	00:00:01
192.241.199.73	0.027	0.027	0.000	00:00:00
TOTAL: 6	84.781	84.473	2.238	00:09:30

NNRP unrecognized commands (by host):

System	Conn
192.241.199.73	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 14 04:15:01 -- Nov 15 04:15:01