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

Unknown entries from news log file:

First 50 / 2867 lines (1.7%)

Mar 10 04:15:09 rpi-studio nnrpd[11112]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:15:39 rpi-studio nnrpd[11147]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:16:10 rpi-studio nnrpd[11217]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:16:41 rpi-studio nnrpd[11227]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:17:11 rpi-studio nnrpd[11251]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:17:41 rpi-studio nnrpd[11258]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:18:11 rpi-studio nnrpd[11267]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:18:41 rpi-studio nnrpd[11281]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:19:11 rpi-studio nnrpd[11291]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:19:41 rpi-studio nnrpd[11301]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:20:11 rpi-studio nnrpd[11320]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:20:41 rpi-studio nnrpd[11327]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:21:12 rpi-studio nnrpd[11344]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:21:42 rpi-studio nnrpd[11353]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:22:12 rpi-studio nnrpd[11369]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:22:42 rpi-studio nnrpd[11397]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:23:12 rpi-studio nnrpd[11554]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:23:42 rpi-studio nnrpd[11561]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:24:12 rpi-studio nnrpd[11577]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:24:42 rpi-studio nnrpd[11589]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:25:13 rpi-studio nnrpd[11605]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:25:43 rpi-studio nnrpd[11613]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:26:13 rpi-studio nnrpd[11628]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:26:43 rpi-studio nnrpd[11635]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:27:13 rpi-studio nnrpd[11650]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:27:43 rpi-studio nnrpd[11658]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:28:13 rpi-studio nnrpd[11675]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:28:44 rpi-studio nnrpd[11687]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:29:14 rpi-studio nnrpd[11697]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:29:45 rpi-studio nnrpd[11704]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:30:15 rpi-studio nnrpd[11725]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:30:45 rpi-studio nnrpd[11736]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:31:15 rpi-studio nnrpd[11751]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:31:46 rpi-studio nnrpd[11771]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:32:16 rpi-studio nnrpd[11782]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:32:46 rpi-studio nnrpd[11792]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:33:16 rpi-studio nnrpd[11949]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:33:47 rpi-studio nnrpd[11956]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:34:17 rpi-studio nnrpd[11965]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:34:47 rpi-studio nnrpd[11980]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:35:17 rpi-studio nnrpd[11996]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:35:47 rpi-studio nnrpd[12003]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:36:17 rpi-studio nnrpd[12017]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:36:47 rpi-studio nnrpd[12024]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:37:17 rpi-studio nnrpd[12035]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:37:48 rpi-studio nnrpd[12042]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:38:18 rpi-studio nnrpd[12052]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:38:49 rpi-studio nnrpd[12065]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:39:20 rpi-studio nnrpd[12077]: 2001:41d0:a:280::1 noperm ihave without permission
Mar 10 04:39:50 rpi-studio nnrpd[12085]: 2001:41d0:a:280::1 noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	19224	74.7%	2.2 MB	67.5%
inn	2575	10.0%	469.9 KB	14.0%
innd	2101	8.2%	351.9 KB	10.4%
innfeed	1831	7.1%	271.8 KB	8.1%
nocem	1	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.0 KB	0.0%
TOTAL: 6	25733	100.0%	3.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	11862	55.9%
Negative hits	6798	32.0%
Do not exist	2558	12.1%
Cache misses	1	0.0%
TOTAL: 4	21219	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.006	0.0%	6	0.000	1.000	2.000
article cancel	00:00:00.001	0.0%	6	0.000	0.167	1.000
article cleanup	00:00:00.066	0.0%	2566	0.000	0.026	0.286
article logging	00:00:00.185	0.0%	2566	0.000	0.072	0.286
article parse	00:00:00.451	0.0%	6425	0.000	0.070	0.238
article write	00:00:01.172	0.0%	2127	0.000	0.551	2.000
artlog/artcncl	00:00:00.001	0.0%	6	0.000	0.167	1.000
artlog/artcncl/perl	00:00:00.001	0.0%	6	0.000	0.167	1.000
data move	00:00:00.132	0.0%	24116	0.000	0.005	0.032
hisgrep/artcncl	00:00:00.000	0.0%	6	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	6	0.000	0.000	0.000
hisgrep/perl	00:00:00.000	0.0%	6	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	6	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	6	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.363	0.0%	21270	0.000	0.017	0.062
history sync	00:00:00.064	0.0%	396	0.000	0.162	0.667
history write	00:00:00.505	0.0%	2566	0.000	0.197	0.619
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:26.381	100.0%	31115	1069.656	2777.644	7598.177
nntp read	00:00:00.726	0.0%	24675	0.000	0.029	0.084
overview write	00:00:00.701	0.0%	2127	0.000	0.330	5.000
perl filter	00:00:14.426	0.0%	2566	2.286	5.622	18.000
python filter	00:00:00.021	0.0%	2566	0.000	0.008	0.167
site send	00:00:00.896	0.0%	4329	0.000	0.207	0.529
TOTAL: 24:01:03.294	24:00:46.098	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.021	0.0%	2207	0.000	0.010	0.125
article prepare	00:00:00.002	0.0%	544	0.000	0.004	0.250
article read	00:00:00.031	0.0%	132	0.000	0.235	2.000
backlog stats	00:00:00.145	0.0%	30194	0.000	0.005	0.023
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.501	0.0%	16188	0.000	0.031	0.143
data write	00:00:00.780	0.0%	14512	0.008	0.054	0.143
idle	23:59:20.997	100.0%	30194	1346.148	2860.204	10012.383
status file	00:00:07.275	0.0%	278	1.000	26.169	84.000
TOTAL: 23:59:33.083	23:59:29.752	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:49:04.306	94.4%	10830	9.500	271.866	900049.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.051	0.0%	23609	0.000	0.045	1.000
readart	00:00:00.699	0.0%	12282	0.049	0.057	0.179
TOTAL: 00:51:58.621	00:49:06.056	94.5%	-	-	-	-

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.nntp4.net	3	2532	648	1465	419	25%	23:54:53
2	gegeweb-out.news.tnetconsulting.net	3	1999	560	1417	22	28%	32:00:08
3	feed-out.gegeweb.org	4	2115	520	1595	0	24%	24:32:05
4	i2pn.org	3	4004	295	3705	4	7%	42:42:48
5	news.samoylyk.net	4	3013	279	2734	0	9%	32:07:54
6	usenet.pasdenom.info	7	1246	147	1095	4	11%	24:59:24
7	nntp.terraraq.uk	4	1524	115	1408	1	7%	27:06:13
8	news.glou.org	5	1545	30	1510	5	1%	23:53:18
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		58	17978	2594	14929	455	14%	231:16:43

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.samoylyk.net	3.2 MB	0.0 KB	3.2 MB	100%	11.6 KB
2	news.nntp4.net	3.0 MB	3.9 MB	6.9 MB	43%	6.6 KB
3	gegeweb-out.news.tnetconsulting.net	2.0 MB	68.9 KB	2.0 MB	96%	3.6 KB
4	feed-out.gegeweb.org	1.7 MB	0.0 KB	1.7 MB	100%	3.4 KB
5	i2pn.org	702.1 KB	7.0 KB	709.2 KB	99%	2.4 KB
6	usenet.pasdenom.info	368.5 KB	4.6 KB	373.1 KB	98%	2.5 KB
7	nntp.terraraq.uk	335.5 KB	1.3 KB	336.8 KB	99%	2.9 KB
8	news.glou.org	114.1 KB	14.2 KB	128.3 KB	88%	3.7 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		11.3 MB	4.0 MB	15.3 MB	73%	5.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 10 04:15:01 - 04:59:59	28	1.3%	0.01	127.4 KB	1.4%	0.05
Mar 10 05:00:00 - 05:59:59	34	1.6%	0.01	87.2 KB	1.0%	0.02
Mar 10 06:00:00 - 06:59:59	62	2.9%	0.02	197.1 KB	2.2%	0.05
Mar 10 07:00:00 - 07:59:59	51	2.4%	0.01	157.1 KB	1.8%	0.04
Mar 10 08:00:00 - 08:59:59	70	3.3%	0.02	216.5 KB	2.4%	0.06
Mar 10 09:00:00 - 09:59:59	75	3.5%	0.02	430.3 KB	4.8%	0.12
Mar 10 10:00:00 - 10:59:59	83	3.9%	0.02	311.0 KB	3.5%	0.09
Mar 10 11:00:00 - 11:59:59	65	3.1%	0.02	255.9 KB	2.9%	0.07
Mar 10 12:00:00 - 12:59:59	96	4.5%	0.03	434.9 KB	4.9%	0.12
Mar 10 13:00:00 - 13:59:59	58	2.7%	0.02	217.2 KB	2.4%	0.06
Mar 10 14:00:00 - 14:59:59	101	4.8%	0.03	362.0 KB	4.1%	0.10
Mar 10 15:00:00 - 15:59:59	91	4.3%	0.03	342.9 KB	3.8%	0.10
Mar 10 16:00:00 - 16:59:59	113	5.3%	0.03	421.8 KB	4.7%	0.12
Mar 10 17:00:00 - 17:59:59	106	5.0%	0.03	424.3 KB	4.8%	0.12
Mar 10 18:00:00 - 18:59:59	110	5.2%	0.03	408.5 KB	4.6%	0.11
Mar 10 19:00:00 - 19:59:59	140	6.6%	0.04	637.4 KB	7.2%	0.18
Mar 10 20:00:00 - 20:59:59	137	6.5%	0.04	443.9 KB	5.0%	0.12
Mar 10 21:00:00 - 21:59:59	113	5.3%	0.03	321.4 KB	3.6%	0.09
Mar 10 22:00:00 - 22:59:59	157	7.4%	0.04	943.5 KB	10.6%	0.26
Mar 10 23:00:00 - 23:59:59	149	7.0%	0.04	758.1 KB	8.5%	0.21
Mar 11 00:00:00 - 00:59:59	95	4.5%	0.03	700.9 KB	7.9%	0.19
Mar 11 01:00:00 - 01:59:59	81	3.8%	0.02	265.4 KB	3.0%	0.07
Mar 11 02:00:00 - 02:59:59	55	2.6%	0.02	191.7 KB	2.2%	0.05
Mar 11 03:00:00 - 03:59:59	38	1.8%	0.01	158.3 KB	1.8%	0.04
Mar 11 04:00:00 - 04:15:01	16	0.8%	0.02	93.0 KB	1.0%	0.10
TOTAL: 24:00:00	2124	100.0%	0.02	8.7 MB	100.0%	0.10

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	419	329	90
2	gegeweb-out.news.tnetconsulting.net	9	0	9
3	news.glou.org	5	0	5
4	usenet.pasdenom.info	4	0	4
5	i2pn.org	2	0	2
TOTAL: 5		439	329	110

Unwanted newsgroups [Top 20]:

Newsgroup	Count
linux.kernel.netdev	246
linux.kernel.git	82
news.test	1
TOTAL: 3	329

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	73
Bad path (news.giganews.com.POSTED)	32
Cancels abusifs	4
Angle-bracket bot	1
TOTAL: 4	110

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	2917	36	1911	6	23	1%	23:54:54
2	news.gegeweb.org	1728	26	1583	0	0	1%	23:47:09
3	news.tnetconsulting.net	2947	5	2107	7	10	0%	23:54:51
4	news.pasdenom.info	1643	3	1524	0	0	0%	23:53:12
5	news.samoylyk.net	2848	0	1936	53	51	0%	23:54:43
6	news.aioe.org	0	0	0	0	1712	0%	24:00:00
7	news.nntp4.net	1579	0	1458	0	0	0%	23:43:39
8	nntp.terraraq.uk	1224	0	1170	0	0	0%	23:42:01
TOTAL: 8		14886	70	11689	66	1796	0%	190:50:29

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	159.4 KB	23.4 KB	182.8 KB	4.4 KB	23:54:54
2	news.gegeweb.org	58.8 KB	0.0 KB	58.8 KB	2.3 KB	23:47:09
3	news.tnetconsulting.net	10.9 KB	39.4 KB	50.3 KB	4.2 KB	23:54:51
4	news.pasdenom.info	8.3 KB	0.0 KB	8.3 KB	2.8 KB	23:53:12
5	news.samoylyk.net	0.0 KB	245.3 KB	245.3 KB	4.6 KB	23:54:43
6	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
7	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0	23:43:39
8	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:42:01
TOTAL: 8		237.5 KB	308.2 KB	545.6 KB	4.0 KB	190:50:29

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	syn-066-061-096-239.res.spectrum.com	2	709	1.3 MB	1	0	0	00:15:38
TOTAL: 1		2	709	1.3 MB	1	0	0	00:51:54

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.res.spectrum.com	2	709	1.3 MB	1	00:15:38
2	*.ip.linodeusercontent.com	38	0	0.0 KB	0	00:00:17
3	*.stretchoid.com	1	0	0.0 KB	0	00:00:00
4	*.gegeweb.eu	288	0	0.0 KB	0	00:01:23
5	*.monitoring.internet-measurement.com	1	0	0.0 KB	0	00:00:01
6	unresolved	7	0	0.0 KB	0	00:30:36
7	*.us-east-2.compute.amazonaws.com	5	0	0.0 KB	0	00:00:01
8	*.probe.onyphe.net	2	0	0.0 KB	0	00:00:08
9	?	2874	0	0.0 KB	0	00:03:31
10	*.150.216.162.bc.googleusercontent.com	2	0	0.0 KB	0	00:00:01
11	*.nycmny.fios.verizon.net	1	0	0.0 KB	0	00:00:12
TOTAL: 11		3221	709	1.3 MB	1	00:51:54

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
60.190.226.187	0.008	0.015	0.000	00:30:00
syn-066-061-096-239.res.spectrum.com	0.212	0.343	0.000	00:15:38
2001:41d0:a:280::1	62.947	50.633	0.000	00:03:18
mail.gegeweb.eu	4.957	4.331	1.914	00:01:23
172-105-246-139.ip.linodeusercontent.com	0.890	0.479	0.000	00:00:17
pool-100-38-239-226.nycmny.fios.verizon.net	0.285	0.759	0.000	00:00:12
206.168.34.124	0.018	0.014	0.000	00:00:11
205.210.31.184	0.012	0.012	0.000	00:00:10
198.235.24.112	0.024	0.024	0.000	00:00:10
hari.probe.onyphe.net	0.027	0.023	0.000	00:00:04
foley.probe.onyphe.net	0.039	0.019	0.000	00:00:04
2a06:4882:1000::5	0.022	0.017	0.000	00:00:04
2a06:4883:1000::23	0.031	0.031	0.000	00:00:03
2a06:4883:3000::3d	0.017	0.030	0.000	00:00:03
2600:3c02::f03c:95ff:fe9c:737c	0.029	0.021	0.000	00:00:02
pleasing.monitoring.internet-measurement.com	0.041	0.012	0.000	00:00:01
ec2-3-133-93-182.us-east-2.compute.amazonaws.com	0.208	0.102	0.000	00:00:01
147.45.112.188	0.043	0.016	0.000	00:00:01
23.150.216.162.bc.googleusercontent.com	0.067	0.013	0.000	00:00:01
78.150.216.162.bc.googleusercontent.com	0.049	0.033	0.000	00:00:00
TOTAL: 26	70.114	57.053	1.914	00:51:54

Curious NNRP server explorers [Top 20]:

System	Conn
2001:41d0:a:280::1	2867
mail.gegeweb.eu	288
172-105-246-139.ip.linodeusercontent.com	38
ec2-3-133-93-182.us-east-2.compute.amazonaws.com	5
azpdegc14.stretchoid.com	1
2a06:4882:1000::5	1
23.150.216.162.bc.googleusercontent.com	1
147.185.132.151	1
2a06:4883:3000::3d	1
198.235.24.112	1
2a06:4883:1000::23	1
pleasing.monitoring.internet-measurement.com	1
2a06:4883:d000::db	1
2a06:4883:d000::f0	1
pool-100-38-239-226.nycmny.fios.verizon.net	1
206.168.34.124	1
78.150.216.162.bc.googleusercontent.com	1
88.214.25.123	1
205.210.31.184	1
foley.probe.onyphe.net	1
TOTAL: 25	3219

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
ec2-3-133-93-182.us-east-2.compute.amazonaws.com	5
78.150.216.162.bc.googleusercontent.com	4
147.185.132.151	4
23.150.216.162.bc.googleusercontent.com	4
88.214.25.123	1
206.168.34.124	1
60.190.226.187	1
azpdegc14.stretchoid.com	1
TOTAL: 8	21

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	4
Accept-Encoding: gzip	4
Host: 45.13.105.36:563	4
User-Agent: Expanse, a Palo Alto Network...	3
Accept: /	1
EHLO www.censys.io	1
MGLNDD_45.13.105.36_119	1
User-Agent: Mozilla/5.0 (Macintosh; Inte...	1
GET / HTTP/1.0	1
#003	1
TOTAL: 10	21

NNRP client timeouts [Top 20]:

System	Conn	Peer
88.214.25.123	1	1
60.190.226.187	1	0
TOTAL: 2	2	1

Newsgroup request counts (by category):

Category		Count	Pct
1	grisbi	709	100.0%
TOTAL: 1		709	100.0%

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

Newsgroup		Count
1	grisbi.bugsreports	709
TOTAL: 1		709

Daily Usenet report for news.niel.me

Mar 10 04:15:01 -- Mar 11 04:15:01