Daily Usenet report for news.niel.me: Oct 29 04:15:02

Unknown entries from news log file:

First 50 / 13611 lines (0.4%)

Oct 29 10:46:54 rpi-studio nnrpd[1173]: news.nntp4.net noperm ihave without permission
Oct 29 10:46:55 rpi-studio nnrpd[1174]: nntp.terraraq.uk noperm ihave without permission
Oct 29 10:50:02 rpi-studio nnrpd[1188]: news.nntp4.net noperm ihave without permission
Oct 29 10:50:02 rpi-studio nnrpd[1189]: nntp.terraraq.uk noperm ihave without permission
Oct 29 10:50:18 rpi-studio nnrpd[1233]: server.samoylyk.net noperm ihave without permission
Oct 29 10:50:32 rpi-studio nnrpd[1239]: news.nntp4.net noperm ihave without permission
Oct 29 10:50:32 rpi-studio nnrpd[1240]: nntp.terraraq.uk noperm ihave without permission
Oct 29 10:50:32 rpi-studio nnrpd[1238]: i2pn.org noperm ihave without permission
Oct 29 10:50:33 rpi-studio nnrpd[1241]: tncsrv06.tnetconsulting.net noperm ihave without permission
Oct 29 10:50:33 rpi-studio nnrpd[1242]: server.samoylyk.net noperm ihave without permission
Oct 29 10:50:48 rpi-studio nnrpd[1246]: server.samoylyk.net noperm ihave without permission
Oct 29 10:51:02 rpi-studio nnrpd[1253]: i2pn.org noperm ihave without permission
Oct 29 10:51:02 rpi-studio nnrpd[1254]: nntp.terraraq.uk noperm ihave without permission
Oct 29 10:51:02 rpi-studio nnrpd[1262]: news.nntp4.net noperm ihave without permission
Oct 29 10:51:03 rpi-studio nnrpd[1263]: tncsrv06.tnetconsulting.net noperm ihave without permission
Oct 29 10:51:03 rpi-studio nnrpd[1264]: server.samoylyk.net noperm ihave without permission
Oct 29 10:51:32 rpi-studio nnrpd[1274]: news.nntp4.net noperm ihave without permission
Oct 29 10:51:32 rpi-studio nnrpd[1273]: i2pn.org noperm ihave without permission
Oct 29 10:51:32 rpi-studio nnrpd[1275]: nntp.terraraq.uk noperm ihave without permission
Oct 29 10:51:34 rpi-studio nnrpd[1276]: tncsrv06.tnetconsulting.net noperm ihave without permission
Oct 29 10:52:02 rpi-studio nnrpd[1285]: i2pn.org noperm ihave without permission
Oct 29 10:52:02 rpi-studio nnrpd[1286]: nntp.terraraq.uk noperm ihave without permission
Oct 29 10:52:02 rpi-studio nnrpd[1287]: news.nntp4.net noperm ihave without permission
Oct 29 10:52:04 rpi-studio nnrpd[1288]: tncsrv06.tnetconsulting.net noperm ihave without permission
Oct 29 10:52:32 rpi-studio nnrpd[1296]: news.nntp4.net noperm ihave without permission
Oct 29 10:52:32 rpi-studio nnrpd[1297]: i2pn.org noperm ihave without permission
Oct 29 10:52:33 rpi-studio nnrpd[1298]: nntp.terraraq.uk noperm ihave without permission
Oct 29 10:52:34 rpi-studio nnrpd[1299]: tncsrv06.tnetconsulting.net noperm ihave without permission
Oct 29 10:53:02 rpi-studio nnrpd[1313]: i2pn.org noperm ihave without permission
Oct 29 10:53:02 rpi-studio nnrpd[1314]: news.nntp4.net noperm ihave without permission
Oct 29 10:53:03 rpi-studio nnrpd[1315]: nntp.terraraq.uk noperm ihave without permission
Oct 29 10:53:04 rpi-studio nnrpd[1316]: tncsrv06.tnetconsulting.net noperm ihave without permission
Oct 29 10:53:32 rpi-studio nnrpd[1328]: i2pn.org noperm ihave without permission
Oct 29 10:53:32 rpi-studio nnrpd[1329]: news.nntp4.net noperm ihave without permission
Oct 29 10:53:33 rpi-studio nnrpd[1330]: nntp.terraraq.uk noperm ihave without permission
Oct 29 10:53:34 rpi-studio nnrpd[1331]: tncsrv06.tnetconsulting.net noperm ihave without permission
Oct 29 10:54:02 rpi-studio nnrpd[1340]: i2pn.org noperm ihave without permission
Oct 29 10:54:02 rpi-studio nnrpd[1341]: news.nntp4.net noperm ihave without permission
Oct 29 10:54:03 rpi-studio nnrpd[1342]: nntp.terraraq.uk noperm ihave without permission
Oct 29 10:54:04 rpi-studio nnrpd[1343]: tncsrv06.tnetconsulting.net noperm ihave without permission
Oct 29 10:54:32 rpi-studio nnrpd[1350]: news.nntp4.net noperm ihave without permission
Oct 29 10:54:32 rpi-studio nnrpd[1351]: i2pn.org noperm ihave without permission
Oct 29 10:54:33 rpi-studio nnrpd[1352]: nntp.terraraq.uk noperm ihave without permission
Oct 29 10:54:34 rpi-studio nnrpd[1353]: tncsrv06.tnetconsulting.net noperm ihave without permission
Oct 29 10:55:02 rpi-studio nnrpd[1365]: news.nntp4.net noperm ihave without permission
Oct 29 10:55:02 rpi-studio nnrpd[1366]: i2pn.org noperm ihave without permission
Oct 29 10:55:03 rpi-studio nnrpd[1367]: nntp.terraraq.uk noperm ihave without permission
Oct 29 10:55:04 rpi-studio nnrpd[1368]: tncsrv06.tnetconsulting.net noperm ihave without permission
Oct 29 10:55:32 rpi-studio nnrpd[1375]: news.nntp4.net noperm ihave without permission
Oct 29 10:55:33 rpi-studio nnrpd[1376]: i2pn.org noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	64189	92.2%	6.7 MB	88.5%
inn	2108	3.0%	387.1 KB	5.0%
innfeed	1832	2.6%	261.4 KB	3.4%
innd	1513	2.2%	240.7 KB	3.1%
controlchan	2	0.0%	0.1 KB	0.0%
nocem	2	0.0%	0.1 KB	0.0%
TOTAL: 6	69646	100.0%	7.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	10851	72.1%
Do not exist	2084	13.8%
Negative hits	1416	9.4%
Cache misses	704	4.7%
TOTAL: 4	15055	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.009	0.0%	8	1.000	1.125	2.000
article cancel	00:00:00.001	0.0%	8	0.000	0.125	1.000
article cleanup	00:00:00.044	0.0%	2094	0.000	0.021	0.200
article logging	00:00:00.152	0.0%	2095	0.000	0.073	0.500
article parse	00:00:00.327	0.0%	6442	0.000	0.051	0.200
article write	00:00:03.594	0.0%	1953	0.000	1.840	4.400
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
artlog/artwrite	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:00.082	0.0%	11125	0.000	0.007	0.111
hisgrep/artcncl	00:00:00.000	0.0%	8	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.001	0.0%	8	0.000	0.125	1.000
hisgrep/perl	00:00:00.005	0.0%	11	0.000	0.455	5.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:02.488	0.0%	15160	0.000	0.164	3.556
history sync	00:00:00.058	0.0%	372	0.000	0.156	1.000
history write	00:00:00.854	0.0%	2094	0.000	0.408	0.909
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:17:36.800	99.9%	39721	255.320	2201.777	1638824.000
nntp read	00:00:00.314	0.0%	11472	0.000	0.027	0.167
overview write	00:00:00.744	0.0%	1953	0.000	0.381	8.333
perl filter	00:00:08.367	0.0%	2094	1.800	3.996	24.000
python filter	00:00:00.018	0.0%	2094	0.000	0.009	0.500
site send	00:00:00.873	0.0%	3921	0.000	0.223	0.625
TOTAL: 24:18:22.390	24:17:54.732	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.014	0.0%	1993	0.000	0.007	0.250
article prepare	00:00:00.003	0.0%	668	0.000	0.004	0.083
article read	00:00:00.026	0.0%	161	0.000	0.161	3.000
backlog stats	00:00:00.148	0.0%	26698	0.000	0.006	0.019
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.377	0.0%	14052	0.000	0.027	0.105
data write	00:00:00.709	0.0%	12244	0.000	0.058	0.200
idle	24:14:17.650	100.0%	26698	1333.406	3268.322	145657.091
status file	00:00:01.818	0.0%	326	0.000	5.577	33.000
TOTAL: 24:14:33.453	24:14:20.745	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:50:35.993	84.9%	42361	8.500	71.670	90995.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:03.402	0.1%	85122	0.000	0.040	1.000
TOTAL: 00:59:35.238	00:50:39.395	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	1632	1632	0	0	100%	16:09:22
2	i2pn.org	4	2213	168	2045	0	7%	25:49:08
3	news.samoylyk.net	4	1578	167	1411	0	10%	17:36:02
4	gegeweb-out.news.tnetconsulting.net	4	346	161	178	7	46%	07:14:20
5	feed-out.gegeweb.org	2	273	78	195	0	28%	05:53:03
6	news.nntp4.net	4	804	29	740	35	3%	11:09:10
7	usenet.pasdenom.info	11	1133	11	1121	1	0%	07:55:16
8	nntp.terraraq.uk	6	329	10	319	0	3%	07:45:13
9	news.glou.org	8	1447	9	1422	16	0%	07:04:57
10	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 10		72	9755	2265	7431	59	23%	106:36:31

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	2001:41d0:2:abb2::119	8.8 MB	0.0 KB	8.8 MB	100%	5.5 KB
2	news.samoylyk.net	1.7 MB	0.0 KB	1.7 MB	100%	10.3 KB
3	gegeweb-out.news.tnetconsulting.net	528.9 KB	23.9 KB	552.7 KB	95%	3.3 KB
4	i2pn.org	523.8 KB	0.0 KB	523.8 KB	100%	3.1 KB
5	feed-out.gegeweb.org	323.4 KB	0.0 KB	323.4 KB	100%	4.1 KB
6	news.nntp4.net	200.3 KB	139.2 KB	339.5 KB	58%	5.3 KB
7	news.glou.org	56.5 KB	58.7 KB	115.1 KB	49%	4.6 KB
8	nntp.terraraq.uk	46.6 KB	0.0 KB	46.6 KB	100%	4.7 KB
9	usenet.pasdenom.info	29.4 KB	1.2 KB	30.6 KB	95%	2.6 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		12.2 MB	223.0 KB	12.4 MB	98%	5.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 29 04:15:02 - 04:59:59	23	1.2%	0.01	87.1 KB	0.8%	0.03
Oct 29 05:00:00 - 05:59:59	36	1.8%	0.01	165.4 KB	1.6%	0.05
Oct 29 06:00:00 - 06:59:59	39	2.0%	0.01	204.4 KB	2.0%	0.06
Oct 29 07:00:00 - 07:59:59	42	2.2%	0.01	144.6 KB	1.4%	0.04
Oct 29 08:00:00 - 08:59:59	35	1.8%	0.01	128.6 KB	1.2%	0.04
Oct 29 09:00:00 - 09:59:59	55	2.8%	0.02	215.2 KB	2.1%	0.06
Oct 29 10:00:00 - 10:59:59	35	1.8%	0.01	106.5 KB	1.0%	0.03
Oct 29 11:00:00 - 11:59:59	77	4.0%	0.02	303.2 KB	2.9%	0.08
Oct 29 12:00:00 - 12:59:59	78	4.0%	0.02	279.7 KB	2.7%	0.08
Oct 29 13:00:00 - 13:59:59	62	3.2%	0.02	200.5 KB	1.9%	0.06
Oct 29 14:00:00 - 14:59:59	97	5.0%	0.03	307.4 KB	3.0%	0.09
Oct 29 15:00:00 - 15:59:59	78	4.0%	0.02	320.5 KB	3.1%	0.09
Oct 29 16:00:00 - 16:59:59	116	6.0%	0.03	703.7 KB	6.8%	0.20
Oct 29 17:00:00 - 17:59:59	90	4.6%	0.03	719.4 KB	6.9%	0.20
Oct 29 18:00:00 - 18:59:59	150	7.7%	0.04	662.3 KB	6.4%	0.18
Oct 29 19:00:00 - 19:59:59	160	8.2%	0.04	1.7 MB	16.6%	0.48
Oct 29 20:00:00 - 20:59:59	148	7.6%	0.04	1.1 MB	10.8%	0.31
Oct 29 21:00:00 - 21:59:59	162	8.3%	0.04	1.1 MB	11.1%	0.32
Oct 29 22:00:00 - 22:59:59	93	4.8%	0.03	310.9 KB	3.0%	0.09
Oct 29 23:00:00 - 23:59:59	85	4.4%	0.02	275.0 KB	2.6%	0.08
Oct 30 00:00:00 - 00:59:59	94	4.8%	0.03	520.6 KB	5.0%	0.14
Oct 30 01:00:00 - 01:59:59	79	4.1%	0.02	241.7 KB	2.3%	0.07
Oct 30 02:00:00 - 02:59:59	58	3.0%	0.02	162.5 KB	1.6%	0.05
Oct 30 03:00:00 - 03:59:59	48	2.5%	0.01	300.0 KB	2.9%	0.08
Oct 30 04:00:00 - 04:15:01	9	0.5%	0.01	29.4 KB	0.3%	0.03
TOTAL: 23:59:59	1949	100.0%	0.02	10.1 MB	100.0%	0.12

Sites sending bad articles:

Server		Total	Other
1	news.nntp4.net	74	74
2	gegeweb-out.news.tnetconsulting.net	24	24
3	news.samoylyk.net	22	22
4	news.glou.org	16	16
5	nntp.terraraq.uk	4	4
6	usenet.pasdenom.info	1	1
7	i2pn.org	1	1
8	2001:41d0:2:abb2::119	1	1
TOTAL: 8		143	143

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	71
Bad path (news.giganews.com.POSTED)	47
EMP (phn path)	13
Binary: misplaced binary	4
Too many newsgroups (meow)	2
Cancels abusifs	2
Cancel of <6555902713cc8b4a124a9405a88545e7e596133e@i2pn2.org> without Cancel-Key	1
Cancel of non-existing ID <vfrcf7$1mev$1@cabale.usenet-fr.net>	1
Cancel of non-existing ID <vfrhml$1ob3$1@cabale.usenet-fr.net>	1
TOTAL: 9	142

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	2001:41d0:2:abb2::119	1
	TOTAL: 1	1
TOTAL: 1		1

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	2649	82	1743	11	17	3%	24:14:35
2	news.tnetconsulting.net	2814	17	1926	10	26	0%	24:20:38
3	news.nntp4.net	2035	12	1840	0	4	0%	24:16:08
4	news.gegeweb.org	226	12	203	0	0	5%	08:00:22
5	news.samoylyk.net	2076	4	1594	18	0	0%	24:15:12
6	news.pasdenom.info	1549	2	1472	0	0	0%	24:19:42
7	nntp.terraraq.uk	1088	0	1061	0	0	0%	24:05:54
8	news.aioe.org	0	0	0	0	1516	0%	24:25:35
TOTAL: 8		12437	129	9839	39	1563	1%	177:58:06

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	430.2 KB	127.7 KB	557.9 KB	6.0 KB	24:14:35
2	news.nntp4.net	59.9 KB	0.0 KB	59.9 KB	5.0 KB	24:16:08
3	news.tnetconsulting.net	46.8 KB	25.0 KB	71.9 KB	2.7 KB	24:20:38
4	news.gegeweb.org	36.9 KB	0.0 KB	36.9 KB	3.1 KB	08:00:22
5	news.samoylyk.net	19.3 KB	562.8 KB	582.1 KB	26.5 KB	24:15:12
6	news.pasdenom.info	4.7 KB	0.0 KB	4.7 KB	2.4 KB	24:19:42
7	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	24:05:54
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:25:35
TOTAL: 8		597.9 KB	715.5 KB	1.3 MB	7.8 KB	177:58:06

NNRP readership statistics:

System		Conn	Elapsed
1	123.149.216.162.bc.googleusercontent.com	1	00:00:00
2	usenet.pasdenom.info	193	00:00:13
3	news.glou.org	1913	00:07:19
4	80.66.76.121	1	00:00:00
5	server-0-2.survey.inspici.com	1	00:00:03
6	server.samoylyk.net	3799	00:17:01
7	104.152.52.199	1	00:00:03
8	tncsrv06.tnetconsulting.net	1912	00:12:23
9	azpdwsb39.stretchoid.com	1	00:00:09
10	news.nntp4.net	1938	00:05:01
11	mail.gegeweb.eu	288	00:00:55
12	166.211.203.35.bc.googleusercontent.com	1	00:00:01
13	147.185.132.28	1	00:00:02
14	147.45.112.8	1	00:00:00
15	104.152.52.109	1	00:00:11
16	198.235.24.167	1	00:00:04
17	194.165.16.10	1	00:00:00
18	205.210.31.26	1	00:00:10
19	nntp.terraraq.uk	1934	00:06:29
20	147.185.133.224	1	00:00:11
21	172-104-137-47.ip.linodeusercontent.com	79	00:00:24
22	i2pn.org	1922	00:08:22
TOTAL: 22		13991	00:59:14

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	9	00:00:46
2	*.ip.linodeusercontent.com	79	00:00:24
3	*.glou.org	1913	00:07:19
4	*.org	1922	00:08:22
5	*.nntp4.net	1938	00:05:01
6	*.gegeweb.eu	288	00:00:55
7	*.survey.inspici.com	1	00:00:03
8	*.stretchoid.com	1	00:00:09
9	*.pasdenom.info	193	00:00:13
10	*.samoylyk.net	3799	00:17:01
11	*.149.216.162.bc.googleusercontent.com	1	00:00:00
12	*.terraraq.uk	1934	00:06:29
13	*.tnetconsulting.net	1912	00:12:23
14	*.211.203.35.bc.googleusercontent.com	1	00:00:01
TOTAL: 14		13991	00:59:14

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
server.samoylyk.net	81.554	57.111	0.000	00:17:01
tncsrv06.tnetconsulting.net	41.770	29.134	0.000	00:12:23
i2pn.org	40.978	28.756	0.000	00:08:22
news.glou.org	42.631	29.373	0.000	00:07:19
nntp.terraraq.uk	42.027	29.241	0.000	00:06:29
news.nntp4.net	41.515	28.908	0.000	00:05:01
mail.gegeweb.eu	4.566	3.637	1.528	00:00:55
172-104-137-47.ip.linodeusercontent.com	1.818	0.974	0.000	00:00:24
usenet.pasdenom.info	4.321	2.949	0.000	00:00:13
104.152.52.109	0.016	0.023	0.000	00:00:11
147.185.133.224	0.058	0.022	0.000	00:00:11
205.210.31.26	0.030	0.017	0.000	00:00:10
azpdwsb39.stretchoid.com	0.032	0.016	0.000	00:00:09
198.235.24.167	0.018	0.026	0.000	00:00:04
server-0-2.survey.inspici.com	0.013	0.008	0.000	00:00:03
104.152.52.199	0.031	0.016	0.000	00:00:03
147.185.132.28	0.017	0.004	0.000	00:00:02
166.211.203.35.bc.googleusercontent.com	0.081	0.009	0.000	00:00:01
123.149.216.162.bc.googleusercontent.com	0.063	0.025	0.000	00:00:00
147.45.112.8	0.007	0.014	0.000	00:00:00
TOTAL: 22	301.608	210.283	1.528	00:59:14

NNRP unrecognized commands (by host):

System	Conn
166.211.203.35.bc.googleusercontent.com	4
123.149.216.162.bc.googleusercontent.com	4
147.185.133.224	4
147.45.112.8	1
TOTAL: 4	13

NNRP unrecognized commands (by command):

Command	Count
Accept-Encoding: gzip	3
GET / HTTP/1.1	3
User-Agent: Expanse, a Palo Alto Network...	3
Host: 45.13.105.36:563	3
#003	1
TOTAL: 5	13

NNRP client timeouts [Top 20]:

System	Conn	Peer
147.45.112.8	1	1
TOTAL: 1	1	4

Daily Usenet report for news.niel.me

Oct 29 04:15:02 -- Oct 30 04:15:01