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

Unknown entries from news log file:

First 50 / 2840 lines (1.8%)

Nov 12 04:15:17 rpi-studio nnrpd[5745]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:15:47 rpi-studio nnrpd[5748]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:16:18 rpi-studio nnrpd[5778]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:16:48 rpi-studio nnrpd[5832]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:17:18 rpi-studio nnrpd[5859]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:17:48 rpi-studio nnrpd[5874]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:18:18 rpi-studio nnrpd[5879]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:18:49 rpi-studio nnrpd[5882]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:19:19 rpi-studio nnrpd[5905]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:19:49 rpi-studio nnrpd[5915]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:20:19 rpi-studio nnrpd[5924]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:20:50 rpi-studio nnrpd[5932]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:21:28 rpi-studio nnrpd[5942]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:21:58 rpi-studio nnrpd[5953]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:22:28 rpi-studio nnrpd[5972]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:22:58 rpi-studio nnrpd[5974]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:23:28 rpi-studio nnrpd[5979]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:24:01 rpi-studio nnrpd[5993]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:24:32 rpi-studio nnrpd[5999]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:25:02 rpi-studio nnrpd[6150]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:25:32 rpi-studio nnrpd[6153]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:26:02 rpi-studio nnrpd[6170]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:26:33 rpi-studio nnrpd[6172]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:27:03 rpi-studio nnrpd[6177]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:27:33 rpi-studio nnrpd[6181]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:28:04 rpi-studio nnrpd[6187]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:28:34 rpi-studio nnrpd[6190]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:29:05 rpi-studio nnrpd[6207]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:29:35 rpi-studio nnrpd[6211]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:30:05 rpi-studio nnrpd[6234]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:30:35 rpi-studio nnrpd[6236]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:31:06 rpi-studio nnrpd[6254]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:31:36 rpi-studio nnrpd[6257]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:32:06 rpi-studio nnrpd[6267]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:32:36 rpi-studio nnrpd[6276]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:33:06 rpi-studio nnrpd[6286]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:33:36 rpi-studio nnrpd[6288]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:34:10 rpi-studio nnrpd[6306]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:34:40 rpi-studio nnrpd[6309]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:35:10 rpi-studio nnrpd[6461]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:35:41 rpi-studio nnrpd[6463]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:36:12 rpi-studio nnrpd[6468]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:36:42 rpi-studio nnrpd[6471]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:37:15 rpi-studio nnrpd[6476]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:37:45 rpi-studio nnrpd[6480]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:38:15 rpi-studio nnrpd[6485]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:38:46 rpi-studio nnrpd[6488]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:39:16 rpi-studio nnrpd[6528]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:39:48 rpi-studio nnrpd[6531]: mx.terraraq.uk noperm ihave without permission
Nov 12 04:40:18 rpi-studio nnrpd[6537]: mx.terraraq.uk noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	13151	62.6%	1.3 MB	48.3%
inn	3800	18.1%	715.9 KB	26.6%
innd	2354	11.2%	374.1 KB	13.9%
innfeed	1696	8.1%	301.8 KB	11.2%
nocem	1	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	21003	100.0%	2.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	21107	62.0%
Negative hits	9215	27.1%
Do not exist	3727	10.9%
Cache misses	1	0.0%
TOTAL: 4	34050	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.028	0.0%	12	0.000	2.333	19.000
article cancel	00:00:00.002	0.0%	12	0.000	0.167	1.000
article cleanup	00:00:00.144	0.0%	3732	0.000	0.039	0.258
article logging	00:00:00.326	0.0%	3754	0.000	0.087	0.969
article parse	00:00:00.652	0.0%	7509	0.000	0.087	0.267
article write	00:00:01.922	0.0%	3285	0.083	0.585	1.600
artlog/artcncl	00:00:00.001	0.0%	12	0.000	0.083	1.000
artlog/artcncl/perl	00:00:00.000	0.0%	12	0.000	0.000	0.000
data move	00:00:00.128	0.0%	37240	0.000	0.003	0.031
hisgrep/artcncl	00:00:00.000	0.0%	12	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	12	0.000	0.000	0.000
hisgrep/perl	00:00:00.000	0.0%	12	0.000	0.000	0.000
hishave/artcncl	00:00:00.001	0.0%	12	0.000	0.083	1.000
hishave/artcncl/perl	00:00:00.000	0.0%	12	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.460	0.0%	34053	0.000	0.014	0.047
history sync	00:00:00.064	0.0%	411	0.000	0.156	1.000
history write	00:00:00.860	0.0%	3732	0.000	0.230	0.679
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:50:03.114	99.9%	43078	832.269	1991.808	6251.469
nntp read	00:00:01.350	0.0%	37195	0.000	0.036	0.067
overview write	00:00:02.340	0.0%	3285	0.000	0.712	4.588
perl filter	00:00:27.207	0.0%	3732	4.217	7.290	123.526
python filter	00:00:00.007	0.0%	3732	0.000	0.002	0.077
site send	00:00:01.479	0.0%	6658	0.033	0.222	0.696
TOTAL: 23:51:08.943	23:50:40.085	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.031	0.0%	3319	0.000	0.009	0.118
article prepare	00:00:00.000	0.0%	316	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.120	0.0%	52049	0.000	0.002	0.023
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.674	0.0%	30937	0.000	0.022	0.075
data write	00:00:01.736	0.0%	27637	0.028	0.063	0.109
idle	23:59:34.873	100.0%	52049	709.924	1659.491	5055.588
status file	00:00:00.430	0.0%	171	1.000	2.515	8.000
TOTAL: 23:59:40.796	23:59:37.883	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:07:05.291	58.8%	10047	11.800	42.330	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.622	0.1%	20457	0.000	0.030	0.500
TOTAL: 00:12:03.604	00:07:05.913	58.9%	-	-	-	-

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-out.aioe.org	1	3569	1583	1942	44	44%	25:12:27
2	i2pn.org	1	3407	787	2435	185	23%	22:50:01
3	news.glou.org	1	3740	661	2984	95	17%	28:27:52
4	usenet.pasdenom.info	3	3011	193	2801	17	6%	29:20:16
5	feed-out.gegeweb.org	3	2418	140	2278	0	5%	23:59:53
6	gegeweb-out.news.tnetconsulting.net	1	3656	58	3593	5	1%	23:59:56
7	news.samoylyk.net	2	6857	25	6832	0	0%	47:14:07
8	news.nntp4.net	1	40	1	38	1	2%	00:18:34
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		38	26698	3448	22903	347	12%	201:23:06

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.aioe.org	5.7 MB	153.0 KB	5.9 MB	97%	3.7 KB
2	news.glou.org	3.2 MB	401.9 KB	3.6 MB	89%	4.9 KB
3	i2pn.org	2.7 MB	434.9 KB	3.1 MB	86%	3.2 KB
4	news.samoylyk.net	1.3 MB	0.0 KB	1.3 MB	100%	52.5 KB
5	feed-out.gegeweb.org	514.0 KB	0.0 KB	514.0 KB	100%	3.7 KB
6	usenet.pasdenom.info	448.2 KB	42.2 KB	490.4 KB	91%	2.3 KB
7	gegeweb-out.news.tnetconsulting.net	163.0 KB	14.4 KB	177.4 KB	91%	2.8 KB
8	news.nntp4.net	4.3 KB	1.3 KB	5.6 KB	77%	2.8 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		14.0 MB	1.0 MB	15.1 MB	93%	4.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 12 04:15:01 - 04:59:59	61	1.8%	0.02	190.7 KB	1.4%	0.07
Nov 12 05:00:00 - 05:59:59	66	2.0%	0.02	185.9 KB	1.4%	0.05
Nov 12 06:00:00 - 06:59:59	82	2.5%	0.02	338.0 KB	2.5%	0.09
Nov 12 07:00:00 - 07:59:59	64	1.9%	0.02	672.3 KB	5.0%	0.19
Nov 12 08:00:00 - 08:59:59	93	2.8%	0.03	402.7 KB	3.0%	0.11
Nov 12 09:00:00 - 09:59:59	117	3.5%	0.03	612.3 KB	4.5%	0.17
Nov 12 10:00:00 - 10:59:59	120	3.6%	0.03	433.8 KB	3.2%	0.12
Nov 12 11:00:00 - 11:59:59	139	4.2%	0.04	423.3 KB	3.1%	0.12
Nov 12 12:00:00 - 12:59:59	137	4.1%	0.04	441.9 KB	3.3%	0.12
Nov 12 13:00:00 - 13:59:59	139	4.2%	0.04	474.7 KB	3.5%	0.13
Nov 12 14:00:00 - 14:59:59	121	3.7%	0.03	397.1 KB	2.9%	0.11
Nov 12 15:00:00 - 15:59:59	173	5.2%	0.05	566.8 KB	4.2%	0.16
Nov 12 16:00:00 - 16:59:59	260	7.9%	0.07	920.2 KB	6.8%	0.26
Nov 12 17:00:00 - 17:59:59	204	6.2%	0.06	1.1 MB	8.0%	0.30
Nov 12 18:00:00 - 18:59:59	139	4.2%	0.04	889.6 KB	6.6%	0.25
Nov 12 19:00:00 - 19:59:59	200	6.0%	0.06	809.9 KB	6.0%	0.22
Nov 12 20:00:00 - 20:59:59	183	5.5%	0.05	666.3 KB	4.9%	0.19
Nov 12 21:00:00 - 21:59:59	179	5.4%	0.05	649.0 KB	4.8%	0.18
Nov 12 22:00:00 - 22:59:59	183	5.5%	0.05	682.8 KB	5.1%	0.19
Nov 12 23:00:00 - 23:59:59	192	5.8%	0.05	559.2 KB	4.1%	0.16
Nov 13 00:00:00 - 00:59:59	128	3.9%	0.04	553.7 KB	4.1%	0.15
Nov 13 01:00:00 - 01:59:59	94	2.8%	0.03	436.5 KB	3.2%	0.12
Nov 13 02:00:00 - 02:59:59	99	3.0%	0.03	501.7 KB	3.7%	0.14
Nov 13 03:00:00 - 03:59:59	103	3.1%	0.03	532.7 KB	3.9%	0.15
Nov 13 04:00:00 - 04:15:01	30	0.9%	0.03	74.9 KB	0.6%	0.08
TOTAL: 24:00:00	3306	100.0%	0.04	13.2 MB	100.0%	0.16

Sites sending bad articles:

Server		Total	Group	Other
1	i2pn.org	185	0	185
2	news.nntp4.net	131	0	131
3	news.glou.org	76	0	76
4	news-out.aioe.org	62	1	61
5	usenet.pasdenom.info	12	0	12
6	gegeweb-out.news.tnetconsulting.net	1	1	0
7	feed-out.gegeweb.org	1	0	1
TOTAL: 7		468	2	466

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.petites-annonces.informatique.materiel	1
news.test	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
EMP (phl)	119
EMP (phn nph)	109
Bad path (news.giganews.com.POSTED)	97
EMP (phn path)	55
EMP (md5)	32
Too many newsgroups	31
Cancels abusifs	1
TOTAL: 7	444

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.tnetconsulting.net	5281	15	3286	4	6	0%	23:59:52
2	news.nntp4.net	3562	15	3220	0	0	0%	23:59:56
3	i2pn.org	4437	9	2493	4	6	0%	23:57:09
4	news.gegeweb.org	3258	8	3154	1	0	0%	23:59:05
5	nntp.terraraq.uk	2749	5	2304	7	0	0%	23:59:56
6	news.aioe.org	1555	5	1354	0	0	0%	23:59:56
7	news.samoylyk.net	3966	1	3290	5	0	0%	23:59:47
8	news.pasdenom.info	3133	0	2782	0	2	0%	23:59:56
TOTAL: 8		27941	58	21883	21	14	0%	191:55:37

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.nntp4.net	218.8 KB	0.0 KB	218.8 KB	14.6 KB	23:59:56
2	news.tnetconsulting.net	94.1 KB	14.9 KB	109.0 KB	5.7 KB	23:59:52
3	news.aioe.org	61.1 KB	0.0 KB	61.1 KB	12.2 KB	23:59:56
4	i2pn.org	57.9 KB	14.9 KB	72.7 KB	5.6 KB	23:57:09
5	nntp.terraraq.uk	41.3 KB	366.5 KB	407.8 KB	34.0 KB	23:59:56
6	news.samoylyk.net	24.8 KB	19.0 KB	43.9 KB	7.3 KB	23:59:47
7	news.gegeweb.org	18.8 KB	1.4 KB	20.2 KB	2.2 KB	23:59:05
8	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:59:56
TOTAL: 8		516.8 KB	416.7 KB	933.5 KB	11.8 KB	191:55:37

NNRP readership statistics:

System		Conn	Elapsed
1	mx.terraraq.uk	2844	00:10:47
2	192.241.207.142	1	00:00:00
3	mail.gegeweb.eu	288	00:00:37
4	89.248.165.202	1	00:00:00
5	139-162-229-202.ip.linodeusercontent.com	80	00:00:32
TOTAL: 5		3214	00:11:58

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.terraraq.uk	2844	00:10:47
2	*.ip.linodeusercontent.com	80	00:00:32
3	unresolved	2	00:00:01
4	*.gegeweb.eu	288	00:00:37
TOTAL: 4		3214	00:11:58

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	75.805	76.768	0.000	00:10:47
mail.gegeweb.eu	7.747	8.214	2.362	00:00:37
139-162-229-202.ip.linodeusercontent.com	2.433	1.231	0.000	00:00:32
192.241.207.142	0.018	0.037	0.000	00:00:00
89.248.165.202	0.038	0.000	0.000	00:00:00
TOTAL: 5	86.041	86.250	2.362	00:11:58

NNRP unrecognized commands (by host):

System	Conn
192.241.207.142	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 12 04:15:01 -- Nov 13 04:15:01