Daily Usenet report for news.niel.me: Nov 19 04:15:02

Unknown entries from news log file:

First 50 / 2841 lines (1.8%)

Nov 19 04:15:23 rpi-studio nnrpd[2648]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:15:53 rpi-studio nnrpd[2675]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:16:23 rpi-studio nnrpd[2683]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:16:59 rpi-studio nnrpd[2762]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:17:29 rpi-studio nnrpd[2801]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:18:00 rpi-studio nnrpd[2804]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:18:31 rpi-studio nnrpd[2808]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:19:04 rpi-studio nnrpd[2818]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:19:34 rpi-studio nnrpd[2832]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:20:04 rpi-studio nnrpd[2839]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:20:34 rpi-studio nnrpd[2842]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:21:05 rpi-studio nnrpd[2847]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:21:35 rpi-studio nnrpd[2849]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:22:05 rpi-studio nnrpd[2863]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:22:35 rpi-studio nnrpd[2868]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:23:05 rpi-studio nnrpd[3014]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:23:35 rpi-studio nnrpd[3021]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:24:05 rpi-studio nnrpd[3028]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:24:36 rpi-studio nnrpd[3042]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:25:06 rpi-studio nnrpd[3069]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:25:36 rpi-studio nnrpd[3073]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:26:06 rpi-studio nnrpd[3081]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:26:36 rpi-studio nnrpd[3084]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:27:06 rpi-studio nnrpd[3089]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:27:36 rpi-studio nnrpd[3092]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:28:06 rpi-studio nnrpd[3097]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:28:36 rpi-studio nnrpd[3104]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:29:06 rpi-studio nnrpd[3112]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:29:37 rpi-studio nnrpd[3150]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:30:07 rpi-studio nnrpd[3162]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:30:37 rpi-studio nnrpd[3166]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:31:07 rpi-studio nnrpd[3172]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:31:37 rpi-studio nnrpd[3174]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:32:08 rpi-studio nnrpd[3181]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:32:38 rpi-studio nnrpd[3185]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:33:08 rpi-studio nnrpd[3332]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:33:38 rpi-studio nnrpd[3334]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:34:08 rpi-studio nnrpd[3340]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:34:38 rpi-studio nnrpd[3366]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:35:09 rpi-studio nnrpd[3389]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:35:39 rpi-studio nnrpd[3395]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:36:09 rpi-studio nnrpd[3400]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:36:42 rpi-studio nnrpd[3408]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:37:18 rpi-studio nnrpd[3412]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:37:48 rpi-studio nnrpd[3417]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:38:18 rpi-studio nnrpd[3421]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:38:48 rpi-studio nnrpd[3424]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:39:21 rpi-studio nnrpd[3453]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:39:52 rpi-studio nnrpd[3456]: mx.terraraq.uk noperm ihave without permission
Nov 19 04:40:22 rpi-studio nnrpd[3463]: mx.terraraq.uk noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	13363	61.3%	1.3 MB	48.0%
inn	4239	19.4%	777.1 KB	27.9%
innd	2428	11.1%	382.5 KB	13.7%
innfeed	1767	8.1%	286.6 KB	10.3%
nocem	1	0.0%	0.2 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	21799	100.0%	2.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	23106	60.8%
Negative hits	10722	28.2%
Do not exist	4182	11.0%
Cache misses	7	0.0%
TOTAL: 4	38017	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.029	0.0%	8	1.000	3.625	21.000
article cancel	00:00:00.000	0.0%	8	0.000	0.000	0.000
article cleanup	00:00:00.133	0.0%	4185	0.000	0.032	0.167
article logging	00:00:00.324	0.0%	4224	0.000	0.077	0.238
article parse	00:00:00.773	0.0%	9487	0.000	0.081	0.308
article write	00:00:02.554	0.0%	3677	0.000	0.695	17.250
artlog/artcncl	00:00:00.000	0.0%	8	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.001	0.0%	8	0.000	0.125	1.000
data move	00:00:00.132	0.0%	40767	0.000	0.003	0.024
hisgrep/artcncl	00:00:00.000	0.0%	8	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	8	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%	8	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.001	0.0%	8	0.000	0.125	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:00.534	0.0%	38033	0.000	0.014	0.060
history sync	00:00:00.056	0.0%	421	0.000	0.133	0.667
history write	00:00:00.974	0.0%	4185	0.000	0.233	0.593
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:06.823	99.9%	46568	855.476	1855.498	6458.215
nntp read	00:00:01.465	0.0%	40922	0.000	0.036	0.089
overview write	00:00:02.430	0.0%	3677	0.000	0.661	8.444
perl filter	00:00:23.542	0.0%	4185	2.655	5.625	25.375
python filter	00:00:00.006	0.0%	4185	0.000	0.001	0.043
site send	00:00:01.579	0.0%	7423	0.000	0.213	0.474
TOTAL: 24:01:10.499	24:00:41.356	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.042	0.0%	4197	0.000	0.010	0.167
article prepare	00:00:00.000	0.0%	488	0.000	0.000	0.000
article read	00:00:00.012	0.0%	86	0.000	0.140	2.000
backlog stats	00:00:00.103	0.0%	55268	0.000	0.002	0.013
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.714	0.0%	32799	0.000	0.022	0.067
data write	00:00:01.841	0.0%	29609	0.000	0.062	0.122
idle	23:59:29.826	100.0%	55268	779.548	1562.746	5687.906
status file	00:00:00.450	0.0%	175	1.000	2.571	8.000
TOTAL: 23:59:37.689	23:59:32.988	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:06:32.423	56.5%	9970	11.400	39.360	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.560	0.1%	20322	0.000	0.028	1.000
TOTAL: 00:11:34.860	00:06:32.983	56.6%	-	-	-	-

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	1	3789	1764	1909	116	46%	23:54:35
2	i2pn.org	1	4327	860	3169	298	19%	26:30:01
3	news.glou.org	2	2930	568	2283	79	19%	22:54:44
4	feed-out.gegeweb.org	3	2611	182	2429	0	6%	23:58:52
5	news.nntp4.net	1	3857	141	3657	59	3%	23:18:04
6	usenet.pasdenom.info	2	2647	108	2534	5	4%	23:59:12
7	gegeweb-out.news.tnetconsulting.net	1	3325	36	3288	1	1%	23:59:56
8	news.samoylyk.net	2	3460	8	3452	0	0%	22:36:09
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		38	26946	3667	22721	558	13%	191:11:33

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.aioe.org	6.1 MB	430.0 KB	6.6 MB	93%	3.6 KB
2	news.glou.org	4.9 MB	313.9 KB	5.2 MB	94%	8.3 KB
3	i2pn.org	2.8 MB	737.8 KB	3.5 MB	79%	3.1 KB
4	news.nntp4.net	954.8 KB	268.8 KB	1.2 MB	78%	6.1 KB
5	feed-out.gegeweb.org	695.9 KB	0.0 KB	695.9 KB	100%	3.8 KB
6	news.samoylyk.net	529.9 KB	0.0 KB	529.9 KB	100%	66.2 KB
7	gegeweb-out.news.tnetconsulting.net	393.2 KB	2.6 KB	395.8 KB	99%	10.7 KB
8	usenet.pasdenom.info	281.6 KB	6.6 KB	288.2 KB	97%	2.6 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		16.6 MB	1.7 MB	18.4 MB	90%	4.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 19 04:15:02 - 04:59:59	37	1.0%	0.01	103.8 KB	0.6%	0.04
Nov 19 05:00:00 - 05:59:59	58	1.6%	0.02	177.2 KB	1.0%	0.05
Nov 19 06:00:00 - 06:59:59	66	1.8%	0.02	612.2 KB	3.6%	0.17
Nov 19 07:00:00 - 07:59:59	77	2.1%	0.02	239.6 KB	1.4%	0.07
Nov 19 08:00:00 - 08:59:59	72	2.0%	0.02	572.8 KB	3.3%	0.16
Nov 19 09:00:00 - 09:59:59	94	2.6%	0.03	295.3 KB	1.7%	0.08
Nov 19 10:00:00 - 10:59:59	95	2.6%	0.03	408.0 KB	2.4%	0.11
Nov 19 11:00:00 - 11:59:59	132	3.6%	0.04	516.1 KB	3.0%	0.14
Nov 19 12:00:00 - 12:59:59	163	4.4%	0.05	548.1 KB	3.2%	0.15
Nov 19 13:00:00 - 13:59:59	153	4.2%	0.04	801.7 KB	4.7%	0.22
Nov 19 14:00:00 - 14:59:59	141	3.8%	0.04	532.6 KB	3.1%	0.15
Nov 19 15:00:00 - 15:59:59	199	5.4%	0.06	690.8 KB	4.0%	0.19
Nov 19 16:00:00 - 16:59:59	233	6.3%	0.06	925.4 KB	5.4%	0.26
Nov 19 17:00:00 - 17:59:59	199	5.4%	0.06	707.4 KB	4.1%	0.20
Nov 19 18:00:00 - 18:59:59	198	5.4%	0.06	635.3 KB	3.7%	0.18
Nov 19 19:00:00 - 19:59:59	254	6.9%	0.07	2.0 MB	11.6%	0.56
Nov 19 20:00:00 - 20:59:59	258	7.0%	0.07	926.2 KB	5.4%	0.26
Nov 19 21:00:00 - 21:59:59	200	5.4%	0.06	855.3 KB	5.0%	0.24
Nov 19 22:00:00 - 22:59:59	208	5.7%	0.06	696.0 KB	4.0%	0.19
Nov 19 23:00:00 - 23:59:59	177	4.8%	0.05	868.1 KB	5.0%	0.24
Nov 20 00:00:00 - 00:59:59	149	4.1%	0.04	1.4 MB	8.3%	0.40
Nov 20 01:00:00 - 01:59:59	181	4.9%	0.05	844.6 KB	4.9%	0.23
Nov 20 02:00:00 - 02:59:59	149	4.1%	0.04	891.2 KB	5.2%	0.25
Nov 20 03:00:00 - 03:59:59	142	3.9%	0.04	761.7 KB	4.4%	0.21
Nov 20 04:00:00 - 04:15:01	41	1.1%	0.05	168.4 KB	1.0%	0.19
TOTAL: 23:59:59	3676	100.0%	0.04	16.8 MB	100.0%	0.20

Sites sending bad articles:

Server		Total	Group	Other
1	i2pn.org	258	0	258
2	news-out.aioe.org	109	1	108
3	news.glou.org	97	0	97
4	news.nntp4.net	70	0	70
5	gegeweb-out.news.tnetconsulting.net	6	1	5
6	news.samoylyk.net	4	0	4
7	feed-out.gegeweb.org	2	0	2
8	usenet.pasdenom.info	1	0	1
TOTAL: 8		547	2	545

Unwanted newsgroups [Top 20]:

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

INND Perl filter [Top 20]:

Reason	Count
EMP (phn nph)	168
EMP (md5)	133
EMP (phl)	103
Bad path (news.giganews.com.POSTED)	72
Too many newsgroups	29
Cancel of non-existing ID <tlae2r$19vl$1@cabale.usenet-fr.net>	1
TOTAL: 6	506

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news-out.aioe.org	26
	news.samoylyk.net	4
	i2pn.org	4
	news.nntp4.net	3
	feed-out.gegeweb.org	2
	TOTAL: 5	39
TOTAL: 1		39

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.nntp4.net	4011	34	3498	0	0	0%	23:59:55
2	i2pn.org	5062	12	2846	7	257	0%	23:59:12
3	nntp.terraraq.uk	2874	10	2279	27	0	0%	23:59:55
4	news.tnetconsulting.net	5942	9	3660	5	260	0%	23:59:55
5	news.aioe.org	1416	6	1196	1	0	0%	23:51:39
6	news.gegeweb.org	3595	4	3483	2	0	0%	23:59:55
7	news.pasdenom.info	3194	1	2740	0	0	0%	23:59:55
8	news.samoylyk.net	4437	0	3663	4	4	0%	23:59:55
TOTAL: 8		30531	76	23365	46	521	0%	191:50:21

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.nntp4.net	1.7 MB	0.0 KB	1.7 MB	52.7 KB	23:59:55
2	nntp.terraraq.uk	195.9 KB	2.2 MB	2.4 MB	66.1 KB	23:59:55
3	news.aioe.org	78.7 KB	1.3 KB	80.0 KB	11.4 KB	23:51:39
4	i2pn.org	49.3 KB	46.1 KB	95.5 KB	5.0 KB	23:59:12
5	news.tnetconsulting.net	26.0 KB	58.4 KB	84.4 KB	6.0 KB	23:59:55
6	news.gegeweb.org	19.5 KB	11.7 KB	31.2 KB	5.2 KB	23:59:55
7	news.pasdenom.info	4.0 KB	0.0 KB	4.0 KB	4.0 KB	23:59:55
8	news.samoylyk.net	0.0 KB	40.9 KB	40.9 KB	10.2 KB	23:59:55
TOTAL: 8		2.1 MB	2.4 MB	4.5 MB	37.5 KB	191:50:21

NNRP readership statistics:

System		Conn	Elapsed
1	62.233.50.179	1	00:00:07
2	mx.terraraq.uk	2844	00:10:01
3	104.156.155.24	81	00:00:16
4	chivalrous.monitoring.internet-measurement.com	1	00:00:00
5	wondrous.monitoring.internet-measurement.com	1	00:00:00
6	refined.monitoring.internet-measurement.com	1	00:00:06
7	awesome.monitoring.internet-measurement.com	1	00:00:00
8	mail.gegeweb.eu	288	00:00:36
9	equitable.monitoring.internet-measurement.com	2	00:00:00
10	192.241.198.117	1	00:00:09
11	acclaimed.monitoring.internet-measurement.com	1	00:00:00
12	splendid.monitoring.internet-measurement.com	1	00:00:00
13	beaming.monitoring.internet-measurement.com	1	00:00:00
14	bubbly.monitoring.internet-measurement.com	1	00:00:00
15	generous.monitoring.internet-measurement.com	1	00:00:00
16	104.152.52.142	1	00:00:10
TOTAL: 16		3227	00:11:30

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	84	00:00:43
2	*.terraraq.uk	2844	00:10:01
3	*.gegeweb.eu	288	00:00:36
4	*.monitoring.internet-measurement.com	11	00:00:09
TOTAL: 4		3227	00:11: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	77.340	75.112	0.000	00:10:01
mail.gegeweb.eu	8.212	8.034	2.377	00:00:36
104.156.155.24	2.523	0.622	0.000	00:00:16
104.152.52.142	0.031	0.021	0.000	00:00:10
192.241.198.117	0.033	0.022	0.000	00:00:09
62.233.50.179	0.020	0.030	0.000	00:00:07
refined.monitoring.internet-measurement.com	0.068	0.023	0.000	00:00:06
beaming.monitoring.internet-measurement.com	0.055	0.028	0.000	00:00:00
wondrous.monitoring.internet-measurement.com	0.062	0.010	0.000	00:00:00
awesome.monitoring.internet-measurement.com	0.035	0.000	0.000	00:00:00
equitable.monitoring.internet-measurement.com	0.056	0.019	0.000	00:00:00
bubbly.monitoring.internet-measurement.com	0.068	0.019	0.000	00:00:00
splendid.monitoring.internet-measurement.com	0.019	0.019	0.000	00:00:00
generous.monitoring.internet-measurement.com	0.018	0.000	0.000	00:00:00
acclaimed.monitoring.internet-measurement.com	0.017	0.009	0.000	00:00:00
chivalrous.monitoring.internet-measurement.com	0.018	0.018	0.000	00:00:00
TOTAL: 16	88.575	83.986	2.377	00:11:30

NNRP unrecognized commands (by host):

System	Conn
62.233.50.179	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
#003	1
TOTAL: 1	1

Daily Usenet report for news.niel.me

Nov 19 04:15:02 -- Nov 20 04:15:01