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

Unknown entries from news log file:

First 50 / 2859 lines (1.7%)

Nov 21 04:15:30 rpi-studio nnrpd[23249]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:16:01 rpi-studio nnrpd[23278]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:16:31 rpi-studio nnrpd[23304]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:17:01 rpi-studio nnrpd[23360]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:17:31 rpi-studio nnrpd[23376]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:18:02 rpi-studio nnrpd[23380]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:18:32 rpi-studio nnrpd[23396]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:19:02 rpi-studio nnrpd[23400]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:19:32 rpi-studio nnrpd[23415]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:20:02 rpi-studio nnrpd[23421]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:20:33 rpi-studio nnrpd[23424]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:21:03 rpi-studio nnrpd[23432]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:21:33 rpi-studio nnrpd[23435]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:22:03 rpi-studio nnrpd[23449]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:22:33 rpi-studio nnrpd[23464]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:23:03 rpi-studio nnrpd[23469]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:23:33 rpi-studio nnrpd[23472]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:24:03 rpi-studio nnrpd[23477]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:24:33 rpi-studio nnrpd[23492]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:25:04 rpi-studio nnrpd[23502]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:25:35 rpi-studio nnrpd[23646]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:26:06 rpi-studio nnrpd[23651]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:26:36 rpi-studio nnrpd[23654]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:27:06 rpi-studio nnrpd[23660]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:27:36 rpi-studio nnrpd[23663]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:28:06 rpi-studio nnrpd[23680]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:28:36 rpi-studio nnrpd[23684]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:29:06 rpi-studio nnrpd[23688]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:29:37 rpi-studio nnrpd[23704]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:30:08 rpi-studio nnrpd[23716]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:30:38 rpi-studio nnrpd[23718]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:31:08 rpi-studio nnrpd[23723]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:31:39 rpi-studio nnrpd[23730]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:32:11 rpi-studio nnrpd[23751]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:32:41 rpi-studio nnrpd[23754]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:33:11 rpi-studio nnrpd[23759]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:33:41 rpi-studio nnrpd[23761]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:34:11 rpi-studio nnrpd[23768]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:34:41 rpi-studio nnrpd[23784]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:35:11 rpi-studio nnrpd[23935]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:35:41 rpi-studio nnrpd[23938]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:36:11 rpi-studio nnrpd[23944]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:36:41 rpi-studio nnrpd[23946]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:37:11 rpi-studio nnrpd[23953]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:37:41 rpi-studio nnrpd[23955]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:38:11 rpi-studio nnrpd[23961]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:38:42 rpi-studio nnrpd[23964]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:39:12 rpi-studio nnrpd[23981]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:39:42 rpi-studio nnrpd[23983]: mx.terraraq.uk noperm ihave without permission
Nov 21 04:40:13 rpi-studio nnrpd[24002]: mx.terraraq.uk noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	12892	61.2%	1.2 MB	46.8%
inn	4187	19.9%	790.1 KB	29.0%
innd	2256	10.7%	372.2 KB	13.6%
innfeed	1743	8.3%	288.8 KB	10.6%
controlchan	1	0.0%	0.2 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	21080	100.0%	2.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	23492	60.7%
Negative hits	11070	28.6%
Do not exist	4112	10.6%
Cache misses	3	0.0%
TOTAL: 4	38677	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.024	0.0%	16	1.000	1.500	4.000
article cancel	00:00:00.002	0.0%	16	0.000	0.125	1.000
article cleanup	00:00:00.125	0.0%	4117	0.000	0.030	0.139
article logging	00:00:00.313	0.0%	4138	0.000	0.076	0.208
article parse	00:00:00.767	0.0%	8805	0.000	0.087	0.400
article write	00:00:02.345	0.0%	3779	0.227	0.621	1.486
artlog/artcncl	00:00:00.000	0.0%	16	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.001	0.0%	16	0.000	0.062	1.000
data move	00:00:00.143	0.0%	41379	0.000	0.003	0.016
hisgrep/artcncl	00:00:00.001	0.0%	16	0.000	0.062	1.000
hisgrep/artcncl/perl	00:00:00.001	0.0%	16	0.000	0.062	1.000
hisgrep/perl	00:00:00.002	0.0%	18	0.000	0.111	1.000
hishave/artcncl	00:00:00.000	0.0%	16	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	16	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.529	0.0%	38691	0.000	0.014	0.042
history sync	00:00:00.051	0.0%	417	0.000	0.122	1.000
history write	00:00:00.973	0.0%	4117	0.000	0.236	2.286
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:50:06.301	99.9%	47034	941.487	1824.346	4803.536
nntp read	00:00:01.393	0.0%	41400	0.000	0.034	0.065
overview write	00:00:02.345	0.0%	3779	0.091	0.621	5.625
perl filter	00:00:23.076	0.0%	4117	3.125	5.605	14.667
python filter	00:00:00.005	0.0%	4117	0.000	0.001	0.028
site send	00:00:01.296	0.0%	7640	0.000	0.170	0.500
TOTAL: 23:51:10.443	23:50:39.693	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.039	0.0%	4070	0.000	0.010	0.095
article prepare	00:00:00.000	0.0%	304	0.000	0.000	0.000
article read	00:00:00.015	0.0%	42	0.000	0.357	9.000
backlog stats	00:00:00.128	0.0%	55996	0.000	0.002	0.026
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.734	0.0%	33745	0.000	0.022	0.058
data write	00:00:01.923	0.0%	30317	0.000	0.063	0.123
idle	23:57:57.538	100.0%	55996	786.481	1540.780	4175.847
status file	00:00:00.493	0.0%	182	1.000	2.709	8.000
TOTAL: 23:58:03.855	23:58:00.870	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:04:36.691	48.5%	10018	11.400	27.619	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.573	0.1%	20325	0.000	0.028	0.500
TOTAL: 00:09:30.815	00:04:37.264	48.6%	-	-	-	-

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	2	3736	1848	1850	38	49%	23:33:32
2	i2pn.org	1	3556	741	2645	170	20%	21:26:39
3	news.glou.org	1	2519	404	2098	17	16%	20:02:30
4	feed-out.gegeweb.org	3	2708	213	2495	0	7%	23:59:02
5	news.nntp4.net	2	7412	118	7060	234	1%	46:19:41
6	usenet.pasdenom.info	2	2705	115	2573	17	4%	23:59:17
7	gegeweb-out.news.tnetconsulting.net	1	3355	58	3291	6	1%	23:59:56
8	news.samoylyk.net	2	3677	5	3672	0	0%	23:18:43
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		39	29668	3502	25684	482	11%	206:39:20

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.aioe.org	6.7 MB	145.3 KB	6.8 MB	97%	3.7 KB
2	news.glou.org	2.7 MB	207.1 KB	2.9 MB	92%	7.0 KB
3	i2pn.org	2.4 MB	352.0 KB	2.7 MB	87%	3.1 KB
4	news.nntp4.net	1.6 MB	1011.7 KB	2.6 MB	61%	7.5 KB
5	feed-out.gegeweb.org	744.5 KB	0.0 KB	744.5 KB	100%	3.5 KB
6	gegeweb-out.news.tnetconsulting.net	491.7 KB	43.2 KB	534.8 KB	91%	8.4 KB
7	news.samoylyk.net	437.5 KB	0.0 KB	437.5 KB	100%	87.5 KB
8	usenet.pasdenom.info	358.6 KB	25.5 KB	384.0 KB	93%	2.9 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		15.3 MB	1.7 MB	17.0 MB	89%	4.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 21 04:15:01 - 04:59:59	86	2.3%	0.03	276.6 KB	1.7%	0.10
Nov 21 05:00:00 - 05:59:59	73	1.9%	0.02	450.6 KB	2.7%	0.13
Nov 21 06:00:00 - 06:59:59	65	1.7%	0.02	333.0 KB	2.0%	0.09
Nov 21 07:00:00 - 07:59:59	61	1.6%	0.02	770.1 KB	4.6%	0.21
Nov 21 08:00:00 - 08:59:59	100	2.6%	0.03	462.7 KB	2.8%	0.13
Nov 21 09:00:00 - 09:59:59	102	2.7%	0.03	418.4 KB	2.5%	0.12
Nov 21 10:00:00 - 10:59:59	129	3.4%	0.04	479.4 KB	2.9%	0.13
Nov 21 11:00:00 - 11:59:59	138	3.6%	0.04	492.0 KB	3.0%	0.14
Nov 21 12:00:00 - 12:59:59	172	4.5%	0.05	716.9 KB	4.3%	0.20
Nov 21 13:00:00 - 13:59:59	150	4.0%	0.04	597.3 KB	3.6%	0.17
Nov 21 14:00:00 - 14:59:59	216	5.7%	0.06	737.1 KB	4.4%	0.20
Nov 21 15:00:00 - 15:59:59	205	5.4%	0.06	781.5 KB	4.7%	0.22
Nov 21 16:00:00 - 16:59:59	189	5.0%	0.05	1.0 MB	6.2%	0.29
Nov 21 17:00:00 - 17:59:59	210	5.5%	0.06	1.0 MB	6.3%	0.29
Nov 21 18:00:00 - 18:59:59	228	6.0%	0.06	935.3 KB	5.6%	0.26
Nov 21 19:00:00 - 19:59:59	217	5.7%	0.06	902.9 KB	5.4%	0.25
Nov 21 20:00:00 - 20:59:59	214	5.6%	0.06	714.5 KB	4.3%	0.20
Nov 21 21:00:00 - 21:59:59	197	5.2%	0.05	904.3 KB	5.4%	0.25
Nov 21 22:00:00 - 22:59:59	262	6.9%	0.07	1.1 MB	7.0%	0.32
Nov 21 23:00:00 - 23:59:59	198	5.2%	0.06	684.7 KB	4.1%	0.19
Nov 22 00:00:00 - 00:59:59	184	4.8%	0.05	691.4 KB	4.2%	0.19
Nov 22 01:00:00 - 01:59:59	148	3.9%	0.04	672.3 KB	4.0%	0.19
Nov 22 02:00:00 - 02:59:59	112	3.0%	0.03	463.3 KB	2.8%	0.13
Nov 22 03:00:00 - 03:59:59	113	3.0%	0.03	620.7 KB	3.7%	0.17
Nov 22 04:00:00 - 04:15:01	27	0.7%	0.03	277.0 KB	1.7%	0.31
TOTAL: 24:00:00	3796	100.0%	0.04	16.2 MB	100.0%	0.19

Sites sending bad articles:

Server		Total	Group	Other
1	i2pn.org	174	0	174
2	news.nntp4.net	91	0	91
3	news-out.aioe.org	55	4	51
4	news.glou.org	28	0	28
5	usenet.pasdenom.info	9	0	9
6	feed-out.gegeweb.org	2	0	2
TOTAL: 6		359	4	355

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.petites-annonces.divers	1
talk.politics	1
news.test	1
fr.petites-annonces.informatique.materiel	1
TOTAL: 4	4

INND Perl filter [Top 20]:

Reason	Count
EMP (phn nph)	107
EMP (phl)	93
Bad path (news.giganews.com.POSTED)	56
EMP (md5)	46
Too many newsgroups	26
Cancels abusifs	4
No Cancel-Key matches Cancel-Lock. target=<637b84f5$0$31523$426a74cc@news.free.fr>	1
No Cancel-Key matches Cancel-Lock. target=<637b3345$0$7652$426a74cc@news.free.fr>	1
TOTAL: 8	334

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.nntp4.net	4102	18	3700	0	0	0%	23:59:55
2	i2pn.org	5227	11	2920	6	146	0%	23:59:56
3	news.tnetconsulting.net	6051	8	3779	6	134	0%	23:59:56
4	nntp.terraraq.uk	2815	5	2315	9	0	0%	23:58:23
5	news.gegeweb.org	3637	3	3575	0	0	0%	23:59:49
6	news.aioe.org	1496	3	1359	0	0	0%	23:51:33
7	news.samoylyk.net	4500	1	3782	6	0	0%	23:59:22
8	news.pasdenom.info	3342	0	2913	0	0	0%	23:59:36
TOTAL: 8		31170	49	24343	27	280	0%	191:48:30

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.nntp4.net	489.4 KB	0.0 KB	489.4 KB	27.2 KB	23:59:55
2	nntp.terraraq.uk	152.7 KB	398.0 KB	550.8 KB	39.3 KB	23:58:23
3	news.gegeweb.org	41.9 KB	0.0 KB	41.9 KB	14.0 KB	23:59:49
4	news.aioe.org	36.0 KB	0.0 KB	36.0 KB	12.0 KB	23:51:33
5	news.tnetconsulting.net	34.7 KB	86.0 KB	120.7 KB	8.6 KB	23:59:56
6	i2pn.org	29.5 KB	87.0 KB	116.5 KB	6.9 KB	23:59:56
7	news.samoylyk.net	2.8 KB	86.0 KB	88.8 KB	12.7 KB	23:59:22
8	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:59:36
TOTAL: 8		787.0 KB	657.0 KB	1.4 MB	19.0 KB	191:48:30

NNRP readership statistics:

System		Conn	Elapsed
1	mail.gegeweb.eu	288	00:00:36
2	139.162.190.203	1	00:00:10
3	mx.terraraq.uk	2857	00:08:25
4	142.93.222.180	1	00:00:10
5	183.136.225.42	1	00:00:03
6	192.241.205.217	1	00:00:00
TOTAL: 6		3149	00:09:26

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.terraraq.uk	2857	00:08:25
2	*.gegeweb.eu	288	00:00:36
3	unresolved	4	00:00:24
TOTAL: 3		3149	00:09:26

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	78.428	75.604	0.000	00:08:25
mail.gegeweb.eu	8.035	8.043	2.365	00:00:36
142.93.222.180	0.038	0.019	0.000	00:00:10
139.162.190.203	0.034	0.022	0.000	00:00:10
183.136.225.42	0.029	0.029	0.000	00:00:03
192.241.205.217	0.017	0.033	0.000	00:00:00
TOTAL: 6	86.581	83.750	2.365	00:09:26

NNRP unrecognized commands (by host):

System	Conn
192.241.205.217	1
183.136.225.42	1
TOTAL: 2	2

NNRP unrecognized commands (by command):

Command	Count
MGLNDD_45.13.105.36_119	1
#026#003#001#002	1
TOTAL: 2	2

Daily Usenet report for news.niel.me

Nov 21 04:15:01 -- Nov 22 04:15:01