Daily Usenet report for news.niel.me: May 28 04:15:01

Unknown entries from news log file:

First 50 / 648 lines (7.7%)

May 29 03:10:35 rpi-studio nnrpd[17556]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:10:50 rpi-studio nnrpd[17561]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:11:06 rpi-studio nnrpd[17566]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:11:21 rpi-studio nnrpd[17571]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:11:36 rpi-studio nnrpd[17574]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:11:52 rpi-studio nnrpd[17728]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:12:07 rpi-studio nnrpd[17733]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:12:22 rpi-studio nnrpd[17739]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:12:37 rpi-studio nnrpd[17742]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:12:52 rpi-studio nnrpd[17747]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:13:07 rpi-studio nnrpd[17752]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:13:23 rpi-studio nnrpd[17757]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:13:38 rpi-studio nnrpd[17767]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:13:53 rpi-studio nnrpd[17772]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:14:08 rpi-studio nnrpd[17777]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:14:23 rpi-studio nnrpd[17782]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:14:39 rpi-studio nnrpd[17786]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:14:54 rpi-studio nnrpd[17791]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:15:09 rpi-studio nnrpd[17800]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:15:25 rpi-studio nnrpd[17805]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:15:40 rpi-studio nnrpd[17810]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:15:56 rpi-studio nnrpd[17818]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:16:11 rpi-studio nnrpd[17828]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:16:26 rpi-studio nnrpd[17832]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:16:41 rpi-studio nnrpd[17837]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:16:56 rpi-studio nnrpd[17840]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:17:12 rpi-studio nnrpd[17862]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:17:27 rpi-studio nnrpd[17871]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:17:42 rpi-studio nnrpd[17877]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:17:57 rpi-studio nnrpd[17880]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:18:13 rpi-studio nnrpd[17887]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:18:28 rpi-studio nnrpd[17890]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:18:43 rpi-studio nnrpd[17900]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:18:58 rpi-studio nnrpd[17908]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:19:13 rpi-studio nnrpd[17915]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:19:28 rpi-studio nnrpd[17918]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:19:44 rpi-studio nnrpd[17923]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:19:59 rpi-studio nnrpd[17927]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:20:14 rpi-studio nnrpd[17937]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:20:29 rpi-studio nnrpd[17941]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:20:44 rpi-studio nnrpd[17946]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:20:59 rpi-studio nnrpd[17949]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:21:14 rpi-studio nnrpd[17956]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:21:30 rpi-studio nnrpd[17960]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:21:45 rpi-studio nnrpd[18113]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:22:00 rpi-studio nnrpd[18118]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:22:15 rpi-studio nnrpd[18123]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:22:31 rpi-studio nnrpd[18128]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:22:47 rpi-studio nnrpd[18131]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
May 29 03:23:02 rpi-studio nnrpd[18138]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	4693	42.9%	530.4 KB	33.4%
inn	2351	21.5%	436.4 KB	27.5%
innd	1992	18.2%	342.8 KB	21.6%
innfeed	1901	17.4%	278.5 KB	17.5%
nocem	1	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	10939	100.0%	1.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	11192	58.1%
Negative hits	5747	29.8%
Do not exist	2325	12.1%
Cache misses	1	0.0%
TOTAL: 4	19265	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.005	0.0%	8	0.000	0.625	1.000
article cancel	00:00:00.001	0.0%	8	0.000	0.125	1.000
article cleanup	00:00:00.034	0.0%	2329	0.000	0.015	0.222
article logging	00:00:00.161	0.0%	2330	0.000	0.069	0.500
article parse	00:00:00.267	0.0%	6357	0.000	0.042	0.286
article write	00:00:00.994	0.0%	2168	0.000	0.458	1.200
artlog/artcncl	00:00:00.000	0.0%	8	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	8	0.000	0.000	0.000
data move	00:00:00.096	0.0%	20093	0.000	0.005	0.037
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.001	0.0%	8	0.000	0.125	1.000
hishave/artcncl	00:00:00.000	0.0%	8	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	8	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.288	0.0%	19280	0.000	0.015	0.070
history sync	00:00:00.050	0.0%	385	0.000	0.130	0.667
history write	00:00:00.345	0.0%	2328	0.000	0.148	0.500
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:58:27.422	99.8%	24022	611.674	3592.849	300513.500
nntp read	00:00:00.507	0.0%	22164	0.000	0.023	0.070
overview write	00:00:00.648	0.0%	2168	0.000	0.299	5.000
perl filter	00:00:08.534	0.0%	2328	1.810	3.666	10.667
python filter	00:00:00.009	0.0%	2328	0.000	0.004	0.167
site send	00:00:00.660	0.0%	4355	0.000	0.152	0.500
TOTAL: 24:01:13.227	23:58:40.022	99.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.021	0.0%	3538	0.000	0.006	0.077
article prepare	00:00:00.001	0.0%	544	0.000	0.002	0.083
article read	00:00:00.027	0.0%	129	0.000	0.209	1.000
backlog stats	00:00:00.144	0.0%	29768	0.000	0.005	0.029
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.404	0.0%	15547	0.000	0.026	0.111
data write	00:00:00.700	0.0%	13833	0.000	0.051	0.143
idle	23:58:18.300	99.9%	29768	886.268	2899.029	17648.382
status file	00:00:00.596	0.0%	319	0.500	1.868	7.000
TOTAL: 23:59:37.498	23:58:20.193	99.9%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:52.102	43.6%	3206	10.000	34.966	132.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.231	0.1%	6672	0.000	0.035	1.000
TOTAL: 00:04:16.834	00:01:52.333	43.7%	-	-	-	-

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	feed-out.gegeweb.org	2	1923	993	930	0	51%	22:09:29
2	usenet.pasdenom.info	3	2202	274	1916	12	12%	42:29:48
3	news.nntp4.net	2	2186	273	1849	64	12%	23:10:55
4	gegeweb-out.news.tnetconsulting.net	2	1783	213	1522	48	11%	24:31:04
5	188.165.224.178	2	200	200	0	0	100%	00:03:01
6	news.samoylyk.net	2	4228	192	4013	23	4%	44:54:22
7	news.glou.org	2	3248	177	2980	91	5%	44:54:41
8	nntp.terraraq.uk	2	3346	124	3222	0	3%	42:53:38
9	i2pn.org	2	1871	106	1740	25	5%	21:08:24
10	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 10		44	20987	2552	18172	263	12%	266:15:22

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feed-out.gegeweb.org	3.6 MB	0.0 KB	0.0 KB	3.6 MB	100%	3.7 KB
2	news.samoylyk.net	1.3 MB	0.0 KB	99.2 KB	1.4 MB	93%	6.7 KB
3	188.165.224.178	1.1 MB	0.0 KB	0.0 KB	1.1 MB	100%	5.6 KB
4	news.nntp4.net	1.0 MB	0.0 KB	502.2 KB	1.5 MB	67%	4.7 KB
5	nntp.terraraq.uk	757.6 KB	0.0 KB	0.0 KB	757.6 KB	100%	6.1 KB
6	usenet.pasdenom.info	625.8 KB	0.0 KB	14.5 KB	640.3 KB	97%	2.2 KB
7	news.glou.org	625.3 KB	0.0 KB	398.8 KB	1.0 MB	61%	3.8 KB
8	gegeweb-out.news.tnetconsulting.net	561.6 KB	4.3 KB	178.3 KB	744.2 KB	75%	2.9 KB
9	i2pn.org	280.6 KB	0.0 KB	46.7 KB	327.3 KB	85%	2.5 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		9.8 MB	4.3 KB	1.2 MB	11.0 MB	88%	4.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 28 04:15:01 - 04:59:59	34	1.6%	0.01	85.4 KB	0.0%	0.03
May 28 05:00:00 - 05:59:59	49	2.3%	0.01	155.8 KB	0.0%	0.04
May 28 06:00:00 - 06:59:59	38	1.7%	0.01	190.0 KB	2.2%	0.05
May 28 07:00:00 - 07:59:59	47	2.2%	0.01	137.0 KB	1.6%	0.04
May 28 08:00:00 - 08:59:59	92	4.2%	0.03	268.6 KB	3.1%	0.07
May 28 09:00:00 - 09:59:59	58	2.7%	0.02	182.8 KB	2.1%	0.05
May 28 10:00:00 - 10:59:59	58	2.7%	0.02	184.5 KB	2.2%	0.05
May 28 11:00:00 - 11:59:59	71	3.3%	0.02	287.7 KB	3.4%	0.08
May 28 12:00:00 - 12:59:59	75	3.5%	0.02	240.8 KB	2.8%	0.07
May 28 13:00:00 - 13:59:59	119	5.5%	0.03	350.9 KB	4.1%	0.10
May 28 14:00:00 - 14:59:59	81	3.7%	0.02	264.5 KB	3.1%	0.07
May 28 15:00:00 - 15:59:59	114	5.2%	0.03	396.2 KB	4.6%	0.11
May 28 16:00:00 - 16:59:59	137	6.3%	0.04	451.2 KB	5.3%	0.13
May 28 17:00:00 - 17:59:59	131	6.0%	0.04	954.0 KB	11.1%	0.27
May 28 18:00:00 - 18:59:59	96	4.4%	0.03	494.7 KB	5.8%	0.14
May 28 19:00:00 - 19:59:59	124	5.7%	0.03	411.5 KB	4.8%	0.11
May 28 20:00:00 - 20:59:59	171	7.9%	0.05	520.3 KB	6.1%	0.14
May 28 21:00:00 - 21:59:59	122	5.6%	0.03	392.6 KB	4.6%	0.11
May 28 22:00:00 - 22:59:59	131	6.0%	0.04	482.1 KB	5.6%	0.13
May 28 23:00:00 - 23:59:59	116	5.3%	0.03	442.4 KB	5.2%	0.12
May 29 00:00:00 - 00:59:59	3	0.1%	0.00	14.7 KB	0.2%	0.00
May 29 01:00:00 - 01:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 29 02:00:00 - 02:59:59	0	0.0%	0.00	0.0 KB	0.0%	0.00
May 29 03:00:00 - 03:59:59	282	13.0%	0.08	1.5 MB	18.5%	0.44
May 29 04:00:00 - 04:15:01	24	1.1%	0.03	81.8 KB	1.0%	0.09
TOTAL: 24:00:00	2173	100.0%	0.03	8.4 MB	100.0%	0.10

Sites sending bad articles:

Server		Total	Duplic	Other
1	news.nntp4.net	48	0	48
2	gegeweb-out.news.tnetconsulting.net	43	1	42
3	news.glou.org	33	0	33
4	i2pn.org	25	0	25
5	usenet.pasdenom.info	7	0	7
6	news.samoylyk.net	5	0	5
7	feed-out.gegeweb.org	1	0	1
TOTAL: 7		162	1	161

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	61
Bad path (news.giganews.com.POSTED)	43
EMP (md5)	27
Too many newsgroups	21
Cancels abusifs	7
hierarchy violation - crosspost outside sdnet	1
TOTAL: 6	160

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	feed-out.gegeweb.org	1
	TOTAL: 1	1
TOTAL: 1		1

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	3228	80	1945	11	256	2%	23:59:37
2	news.gegeweb.org	956	20	897	0	0	2%	23:00:34
3	news.tnetconsulting.net	3385	11	2151	8	266	0%	23:59:37
4	news.nntp4.net	1946	5	1856	0	218	0%	23:59:36
5	news.samoylyk.net	2418	1	2087	0	244	0%	23:59:37
6	nntp.terraraq.uk	1254	0	1183	0	186	0%	23:54:24
7	news.pasdenom.info	1730	0	1627	0	199	0%	23:59:37
8	news.aioe.org	0	0	0	0	1762	0%	24:00:00
TOTAL: 8		14917	117	11746	19	3131	0%	190:53:02

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	202.4 KB	124.7 KB	327.1 KB	3.6 KB	23:59:37
2	news.nntp4.net	63.4 KB	0.0 KB	63.4 KB	12.7 KB	23:59:36
3	news.gegeweb.org	58.7 KB	0.0 KB	58.7 KB	2.9 KB	23:00:34
4	news.tnetconsulting.net	36.6 KB	21.0 KB	57.6 KB	3.0 KB	23:59:37
5	news.samoylyk.net	2.0 KB	0.0 KB	2.0 KB	2.0 KB	23:59:37
6	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:54:24
7	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:59:37
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
TOTAL: 8		363.1 KB	145.7 KB	508.8 KB	3.7 KB	190:53:02

NNRP readership statistics:

System		Conn	Elapsed
1	2a03:f80:ed31:8c5a::1	110	00:00:16
2	i2pn.org	73	00:00:20
3	nntp.terraraq.uk	75	00:00:08
4	2001:41d0:2:abb2::25	1	00:00:13
5	tncsrv06.tnetconsulting.net	71	00:00:27
6	news.nntp4.net	75	00:00:10
7	adored.monitoring.internet-measurement.com	1	00:00:00
8	heavenly.monitoring.internet-measurement.com	1	00:00:00
9	elegant.monitoring.internet-measurement.com	1	00:00:05
10	mail.gegeweb.eu	251	00:01:42
11	usenet.pasdenom.info	7	00:00:01
12	198.199.104.60	1	00:00:01
13	news.glou.org	94	00:00:16
14	motivating.monitoring.internet-measurement.com	1	00:00:00
15	upbeat.monitoring.internet-measurement.com	1	00:00:00
16	masterful.monitoring.internet-measurement.com	1	00:00:00
17	hearty.monitoring.internet-measurement.com	1	00:00:00
18	adroit.monitoring.internet-measurement.com	1	00:00:06
19	server.samoylyk.net	143	00:00:24
TOTAL: 19		909	00:04:15

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.org	73	00:00:20
2	*.tnetconsulting.net	71	00:00:27
3	*.glou.org	94	00:00:16
4	*.nntp4.net	75	00:00:10
5	unresolved	1	00:00:01
6	?	111	00:00:30
7	*.terraraq.uk	75	00:00:08
8	*.gegeweb.eu	251	00:01:42
9	*.samoylyk.net	143	00:00:24
10	*.pasdenom.info	7	00:00:01
11	*.monitoring.internet-measurement.com	8	00:00:12
TOTAL: 11		909	00:04:15

NNRP auth users [Top 20]:

User	Conn
mailman	252
TOTAL: 1	252

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mail.gegeweb.eu	3.690	3.731	1.340	00:01:42
tncsrv06.tnetconsulting.net	1.219	1.204	0.000	00:00:27
server.samoylyk.net	2.752	2.648	0.000	00:00:24
i2pn.org	1.659	1.473	0.000	00:00:20
2a03:f80:ed31:8c5a::1	2.233	2.116	0.000	00:00:16
news.glou.org	1.792	1.754	0.000	00:00:16
2001:41d0:2:abb2::25	0.012	0.016	0.005	00:00:13
news.nntp4.net	1.314	1.357	0.000	00:00:10
nntp.terraraq.uk	1.728	1.532	0.000	00:00:08
adroit.monitoring.internet-measurement.com	0.038	0.038	0.000	00:00:06
elegant.monitoring.internet-measurement.com	0.049	0.012	0.000	00:00:05
usenet.pasdenom.info	0.162	0.096	0.000	00:00:01
198.199.104.60	0.025	0.029	0.000	00:00:01
masterful.monitoring.internet-measurement.com	0.017	0.012	0.000	00:00:00
adored.monitoring.internet-measurement.com	0.031	0.026	0.000	00:00:00
upbeat.monitoring.internet-measurement.com	0.038	0.026	0.000	00:00:00
hearty.monitoring.internet-measurement.com	0.020	0.008	0.000	00:00:00
TOTAL: 17	16.779	16.078	1.345	00:04:15

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	12
TOTAL: 1	12

NNRP unrecognized commands (by host):

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

May 28 04:15:01 -- May 29 04:15:01