Daily Usenet report for news.niel.me: Mar 27 04:15:01

Unknown entries from news log file:

First 50 / 14112 lines (0.4%)

Mar 27 07:11:51 rpi-studio nnrpd[14873]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:12:09 rpi-studio nnrpd[14883]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:12:24 rpi-studio nnrpd[14888]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:12:39 rpi-studio nnrpd[14894]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:12:55 rpi-studio nnrpd[14901]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:13:10 rpi-studio nnrpd[14915]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:13:26 rpi-studio nnrpd[14918]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:13:41 rpi-studio nnrpd[14923]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:13:57 rpi-studio nnrpd[14927]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:14:12 rpi-studio nnrpd[14934]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:14:28 rpi-studio nnrpd[14937]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:14:43 rpi-studio nnrpd[14942]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:14:59 rpi-studio nnrpd[14945]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:16:23 rpi-studio nnrpd[15018]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:16:38 rpi-studio nnrpd[15021]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:16:53 rpi-studio nnrpd[15028]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:17:16 rpi-studio nnrpd[15046]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:17:31 rpi-studio nnrpd[15051]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:17:46 rpi-studio nnrpd[15054]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:18:01 rpi-studio nnrpd[15062]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:18:16 rpi-studio nnrpd[15066]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:18:31 rpi-studio nnrpd[15071]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:18:47 rpi-studio nnrpd[15074]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:19:02 rpi-studio nnrpd[15082]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:19:18 rpi-studio nnrpd[15085]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:19:33 rpi-studio nnrpd[15091]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:19:48 rpi-studio nnrpd[15096]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:20:03 rpi-studio nnrpd[15106]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:20:18 rpi-studio nnrpd[15109]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:20:34 rpi-studio nnrpd[15115]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:20:50 rpi-studio nnrpd[15121]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:21:05 rpi-studio nnrpd[15134]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:21:20 rpi-studio nnrpd[15280]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:21:35 rpi-studio nnrpd[15283]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:21:51 rpi-studio nnrpd[15295]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:22:06 rpi-studio nnrpd[15306]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:22:21 rpi-studio nnrpd[15312]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:22:36 rpi-studio nnrpd[15327]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:22:51 rpi-studio nnrpd[15334]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:23:06 rpi-studio nnrpd[15344]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:23:21 rpi-studio nnrpd[15349]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:23:36 rpi-studio nnrpd[15357]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:23:51 rpi-studio nnrpd[15362]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:24:07 rpi-studio nnrpd[15367]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:24:22 rpi-studio nnrpd[15372]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:24:37 rpi-studio nnrpd[15375]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:24:52 rpi-studio nnrpd[15380]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:25:07 rpi-studio nnrpd[15387]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:25:22 rpi-studio nnrpd[15392]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission
Mar 27 07:25:37 rpi-studio nnrpd[15400]: 2a03:f80:ed31:8c5a::1 noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	60918	90.4%	5.9 MB	85.4%
inn	3317	4.9%	554.7 KB	7.9%
innfeed	1739	2.6%	242.8 KB	3.5%
innd	1379	2.0%	226.4 KB	3.2%
controlchan	1	0.0%	0.0 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	67355	100.0%	6.9 MB	100.0%

History cache:

Reason	Count	%Count
Cache misses	28359	60.4%
Positive hits	12872	27.4%
Do not exist	3192	6.8%
Negative hits	2502	5.3%
TOTAL: 4	46925	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.007	0.0%	4	0.500	1.750	3.000
article cancel	00:00:00.001	0.0%	4	0.000	0.250	0.500
article cleanup	00:00:00.071	0.0%	3308	0.000	0.021	0.188
article logging	00:00:00.204	0.0%	3308	0.000	0.062	0.333
article parse	00:00:00.439	0.0%	7617	0.000	0.058	0.231
article write	00:00:01.376	0.0%	2274	0.080	0.605	1.706
artlog/artcncl	00:00:00.000	0.0%	4	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.002	0.0%	4	0.500	0.500	0.500
data move	00:00:00.074	0.0%	15971	0.000	0.005	0.074
hisgrep/artcncl	00:00:00.000	0.0%	4	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	4	0.000	0.000	0.000
hisgrep/perl	00:00:00.000	0.0%	4	0.000	0.000	0.000
hishave/artcncl	00:00:00.001	0.0%	4	0.000	0.250	0.500
hishave/artcncl/perl	00:00:00.000	0.0%	4	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.631	0.0%	46917	0.000	0.013	0.105
history sync	00:00:00.052	0.0%	390	0.000	0.133	1.000
history write	00:00:00.456	0.0%	3187	0.000	0.143	0.545
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:09.962	99.9%	46018	132.670	1877.743	300370.000
nntp read	00:00:00.506	0.0%	16982	0.000	0.030	0.115
overview write	00:00:00.638	0.0%	2274	0.000	0.281	0.778
perl filter	00:00:12.102	0.0%	2645	1.539	4.575	15.625
python filter	00:00:00.018	0.0%	2645	0.000	0.007	0.250
site send	00:00:00.853	0.0%	4623	0.000	0.185	0.571
TOTAL: 24:01:10.042	24:00:27.393	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.023	0.0%	4144	0.000	0.006	0.200
article prepare	00:00:00.000	0.0%	116	0.000	0.000	0.000
article read	00:00:00.015	0.0%	29	0.000	0.517	8.000
backlog stats	00:00:00.140	0.0%	26384	0.000	0.005	0.021
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.357	0.0%	14159	0.000	0.025	0.091
data write	00:00:00.596	0.0%	11936	0.000	0.050	0.127
idle	23:42:42.619	99.4%	26384	665.647	3235.393	21451.857
status file	00:00:05.672	0.0%	312	0.000	18.179	87.000
TOTAL: 23:51:18.649	23:42:49.422	99.4%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:41:16.674	84.8%	43852	8.667	56.478	24478.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:06.903	0.2%	88178	0.000	0.078	32.737
readart	00:00:00.000	0.0%	2	0.000	0.000	0.000
TOTAL: 00:48:40.804	00:41:23.577	85.0%	-	-	-	-

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	2001:41d0:2:abb2::119	4	2186	2093	89	4	95%	19:44:39
2	gegeweb-out.news.tnetconsulting.net	2	1380	51	1159	170	3%	15:01:30
3	37.187.2.128	4	28229	1	27680	548	0%	14:06:10
4	usenet.pasdenom.info	1	0	0	0	0	0%	00:00:00
5	nntp.terraraq.uk	2	0	0	0	0	0%	00:03:00
6	news.samoylyk.net	2	0	0	0	0	0%	00:06:00
7	news.glou.org	2	0	0	0	0	0%	00:03:00
8	news.nntp4.net	2	1839	0	1639	200	0%	00:01:05
9	localhost	25	0	0	0	0	0%	00:00:00
10	i2pn.org	1	0	0	0	0	0%	00:00:00
TOTAL: 10		45	33634	2145	30567	922	6%	49:05:24

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	2001:41d0:2:abb2::119	8.4 MB	7.5 KB	7.5 KB	8.4 MB	99%	4.1 KB
2	gegeweb-out.news.tnetconsulting.net	186.1 KB	398.2 KB	564.8 KB	1.1 MB	16%	5.2 KB
3	37.187.2.128	1.5 KB	0.0 KB	1.7 MB	1.7 MB	0%	3.2 KB
4	usenet.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
5	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
6	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
7	news.glou.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
8	news.nntp4.net	0.0 KB	0.0 KB	1.8 MB	1.8 MB	0%	9.0 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
10	i2pn.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		8.5 MB	405.6 KB	4.0 MB	12.9 MB	65%	4.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 27 07:15:01 - 07:59:59	392	17.2%	0.15	1.4 MB	15.9%	0.54
Mar 27 08:00:00 - 08:59:59	55	2.4%	0.02	173.1 KB	1.9%	0.05
Mar 27 09:00:00 - 09:59:59	84	3.7%	0.02	433.0 KB	4.7%	0.12
Mar 27 10:00:00 - 10:59:59	70	3.1%	0.02	341.0 KB	3.7%	0.09
Mar 27 11:00:00 - 11:59:59	83	3.6%	0.02	326.0 KB	3.5%	0.09
Mar 27 12:00:00 - 12:59:59	137	6.0%	0.04	444.3 KB	4.8%	0.12
Mar 27 13:00:00 - 13:59:59	131	5.8%	0.04	461.0 KB	5.0%	0.13
Mar 27 14:00:00 - 14:59:59	81	3.6%	0.02	266.5 KB	2.9%	0.07
Mar 27 15:00:00 - 15:59:59	74	3.3%	0.02	207.8 KB	2.3%	0.06
Mar 27 16:00:00 - 16:59:59	118	5.2%	0.03	374.1 KB	4.1%	0.10
Mar 27 17:00:00 - 17:59:59	114	5.0%	0.03	362.4 KB	3.9%	0.10
Mar 27 18:00:00 - 18:59:59	79	3.5%	0.02	358.7 KB	3.9%	0.10
Mar 27 19:00:00 - 19:59:59	87	3.8%	0.02	358.0 KB	3.9%	0.10
Mar 27 20:00:00 - 20:59:59	103	4.5%	0.03	411.8 KB	4.5%	0.11
Mar 27 21:00:00 - 21:59:59	101	4.4%	0.03	369.7 KB	4.0%	0.10
Mar 27 22:00:00 - 22:59:59	123	5.4%	0.03	370.1 KB	4.0%	0.10
Mar 27 23:00:00 - 23:59:59	108	4.7%	0.03	847.2 KB	9.2%	0.24
Mar 28 00:00:00 - 00:59:59	81	3.6%	0.02	713.1 KB	7.7%	0.20
Mar 28 01:00:00 - 01:59:59	82	3.6%	0.02	287.0 KB	3.1%	0.08
Mar 28 02:00:00 - 02:59:59	91	4.0%	0.03	373.6 KB	4.1%	0.10
Mar 28 03:00:00 - 03:59:59	72	3.2%	0.02	255.1 KB	2.8%	0.07
Mar 28 04:00:00 - 04:15:01	9	0.4%	0.01	18.0 KB	0.2%	0.02
TOTAL: 21:00:00	2275	100.0%	0.03	9.0 MB	100.0%	0.12

Sites sending bad articles:

Server		Total	Group	Duplic	TooOld	Other
1	37.187.2.128	548	0	0	542	6
2	news.nntp4.net	302	185	0	0	117
3	gegeweb-out.news.tnetconsulting.net	178	1	117	0	60
4	2001:41d0:2:abb2::119	4	0	4	0	0
5	nntp.terraraq.uk	1	0	0	0	1
6	news.glou.org	1	0	0	0	1
TOTAL: 6		1034	186	121	542	185

Unwanted newsgroups [Top 20]:

Newsgroup	Count
linux.kernel.netdev	139
linux.kernel.git	45
soc.culture.iran	1
news.test	1
TOTAL: 4	186

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	116
Bad path (news.giganews.com.POSTED)	68
hierarchy violation - crosspost outside sdnet	1
TOTAL: 3	185

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	2984	11	2155	1	360	0%	24:00:00
2	news.tnetconsulting.net	3131	6	2260	7	373	0%	24:00:00
3	news.gegeweb.org	165	2	160	0	0	1%	11:10:47
4	news.pasdenom.info	1828	1	1750	0	313	0%	24:00:00
5	news.samoylyk.net	2250	1	1987	0	324	0%	24:00:00
6	news.aioe.org	0	0	0	0	1797	0%	24:00:00
7	nntp.terraraq.uk	1452	0	1423	0	285	0%	24:00:00
8	news.nntp4.net	1691	0	1586	0	221	0%	24:00:00
TOTAL: 8		13501	21	11321	8	3673	0%	179:10:47

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	116.9 KB	1.8 KB	118.6 KB	9.9 KB	24:00:00
2	news.tnetconsulting.net	9.3 KB	25.0 KB	34.3 KB	2.6 KB	24:00:00
3	news.pasdenom.info	7.4 KB	0.0 KB	7.4 KB	7.4 KB	24:00:00
4	news.gegeweb.org	4.8 KB	0.0 KB	4.8 KB	2.4 KB	11:10:47
5	news.samoylyk.net	2.1 KB	0.0 KB	2.1 KB	2.1 KB	24:00:00
6	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
7	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
8	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
TOTAL: 8		140.4 KB	26.7 KB	167.2 KB	5.8 KB	179:10:47

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	syn-066-061-096-239.res.spectrum.com	18	2	1.0 KB	1	0	0	00:01:14
TOTAL: 1		18	2	1.0 KB	1	0	0	00:48:18

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.res.spectrum.com	18	2	1.0 KB	1	00:01:14
2	*.glou.org	2315	0	0.0 KB	0	00:02:54
3	*.org	2301	0	0.0 KB	0	00:12:07
4	*.150.216.162.bc.googleusercontent.com	1	0	0.0 KB	0	00:00:00
5	*.ip.linodeusercontent.com	10	0	0.0 KB	0	00:00:02
6	*.pasdenom.info	233	0	0.0 KB	0	00:00:21
7	*.samoylyk.net	4534	0	0.0 KB	0	00:16:36
8	*.terraraq.uk	2316	0	0.0 KB	0	00:04:37
9	*.149.216.162.bc.googleusercontent.com	1	0	0.0 KB	0	00:00:00
10	unresolved	31	0	0.0 KB	0	00:01:57
11	*.probe.onyphe.net	2	0	0.0 KB	0	00:00:03
12	*.nntp4.net	2312	0	0.0 KB	0	00:06:03
13	?	112	0	0.0 KB	0	00:00:43
14	*.gegeweb.eu	251	0	0.0 KB	0	00:01:24
15	*.monitoring.internet-measurement.com	5	0	0.0 KB	0	00:00:09
TOTAL: 15		14442	2	1.0 KB	1	00:48:18

NNRP auth users [Top 20]:

User	Conn
mailman	254
TOTAL: 1	254

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
server.samoylyk.net	97.521	77.083	0.000	00:16:36
i2pn.org	47.379	38.225	0.000	00:12:07
news.nntp4.net	46.732	37.745	0.000	00:06:03
nntp.terraraq.uk	46.745	38.103	0.000	00:04:37
pousse.glou.org	48.337	38.589	0.000	00:02:54
mail.gegeweb.eu	4.038	3.779	1.588	00:01:24
syn-066-061-096-239.res.spectrum.com	0.476	0.358	0.000	00:01:14
104.219.236.22	0.118	0.118	0.000	00:00:53
2a03:f80:ed31:8c5a::1	2.291	1.831	0.000	00:00:23
usenet.pasdenom.info	5.026	3.845	0.000	00:00:21
172.81.132.30	0.166	0.157	0.000	00:00:13
2001:41d0:2:abb2::25	0.066	0.042	0.015	00:00:10
104.219.236.154	0.096	0.051	0.000	00:00:10
172.81.133.214	0.174	0.136	0.000	00:00:10
104.152.52.158	0.011	0.011	0.000	00:00:10
240e:204:4:6::3	0.191	0.102	0.000	00:00:09
199.45.154.115	0.274	0.123	0.000	00:00:07
172.81.133.216	0.088	0.092	0.000	00:00:06
outstanding.monitoring.internet-measurement.com	0.035	0.004	0.000	00:00:04
lucid.monitoring.internet-measurement.com	0.011	0.020	0.000	00:00:03
TOTAL: 32	300.257	240.695	1.603	00:48:18

Curious NNRP server explorers [Top 20]:

System	Conn
server.samoylyk.net	4534
nntp.terraraq.uk	2316
pousse.glou.org	2315
news.nntp4.net	2312
i2pn.org	2301
mail.gegeweb.eu	251
usenet.pasdenom.info	233
2a03:f80:ed31:8c5a::1	104
194-195-215-162.ip.linodeusercontent.com	10
199.45.154.115	8
172.81.133.214	6
240e:204:4:6::3	5
104.219.236.154	4
172.81.132.30	4
2001:41d0:2:abb2::25	3
104.219.236.22	2
172.81.133.216	2
228.149.216.162.bc.googleusercontent.com	1
productive.monitoring.internet-measurement.com	1
lucid.monitoring.internet-measurement.com	1
TOTAL: 31	14424

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
193.150.216.162.bc.googleusercontent.com	4
228.149.216.162.bc.googleusercontent.com	4
rocha.probe.onyphe.net	2
TOTAL: 3	10

NNRP unrecognized commands (by command):

Command	Count
User-Agent: Expanse, a Palo Alto Network...	2
Host: 45.13.105.36:563	2
Accept-Encoding: gzip	2
GET / HTTP/1.1	2
#026#003#003#001¥#001	1
À$ÀÀ¯À,ÀrÀsÌ©#023#002#023#001Ì#024À#007À#022À#023À'À/À#024À(À0À`Àa...	1
TOTAL: 6	10

Newsgroup request counts (by category):

Category		Count	Pct
1	local	2	100.0%
TOTAL: 1		2	100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

Newsgroup		Count
1	local.test	2
TOTAL: 1		2

Daily Usenet report for news.niel.me

Mar 27 04:15:01 -- Mar 28 04:15:01