Daily Usenet report for news.niel.me: Aug 7 04:15:01

Unknown entries from news log file:

First 50 / 2589 lines (1.9%)

Aug 7 05:18:52 rpi-studio nnrpd[26806]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:19:22 rpi-studio nnrpd[26822]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:20:02 rpi-studio nnrpd[26829]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:20:33 rpi-studio nnrpd[26844]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:21:03 rpi-studio nnrpd[26855]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:22:01 rpi-studio nnrpd[26864]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:22:31 rpi-studio nnrpd[26887]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:23:02 rpi-studio nnrpd[26896]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:23:33 rpi-studio nnrpd[26911]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:24:03 rpi-studio nnrpd[26925]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:24:33 rpi-studio nnrpd[26939]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:25:03 rpi-studio nnrpd[26953]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:25:33 rpi-studio nnrpd[26965]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:26:04 rpi-studio nnrpd[27116]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:26:34 rpi-studio nnrpd[27123]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:27:04 rpi-studio nnrpd[27134]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:27:35 rpi-studio nnrpd[27142]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:28:05 rpi-studio nnrpd[27152]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:28:35 rpi-studio nnrpd[27167]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:29:06 rpi-studio nnrpd[27176]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:29:36 rpi-studio nnrpd[27183]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:30:06 rpi-studio nnrpd[27201]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:30:36 rpi-studio nnrpd[27209]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:31:07 rpi-studio nnrpd[27218]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:31:37 rpi-studio nnrpd[27225]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:32:07 rpi-studio nnrpd[27236]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:32:37 rpi-studio nnrpd[27243]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:33:08 rpi-studio nnrpd[27255]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:33:38 rpi-studio nnrpd[27264]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:34:08 rpi-studio nnrpd[27273]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:34:39 rpi-studio nnrpd[27293]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:35:09 rpi-studio nnrpd[27311]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:35:39 rpi-studio nnrpd[27326]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:36:09 rpi-studio nnrpd[27483]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:36:40 rpi-studio nnrpd[27495]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:37:10 rpi-studio nnrpd[27506]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:37:40 rpi-studio nnrpd[27513]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:38:10 rpi-studio nnrpd[27523]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:38:41 rpi-studio nnrpd[27531]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:39:11 rpi-studio nnrpd[27547]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:39:41 rpi-studio nnrpd[27554]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:40:11 rpi-studio nnrpd[27565]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:40:41 rpi-studio nnrpd[27572]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:41:11 rpi-studio nnrpd[27585]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:41:42 rpi-studio nnrpd[27600]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:42:12 rpi-studio nnrpd[27609]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:42:42 rpi-studio nnrpd[27617]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:43:12 rpi-studio nnrpd[27628]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:43:42 rpi-studio nnrpd[27636]: nntp.terraraq.uk noperm ihave without permission
Aug 7 05:44:12 rpi-studio nnrpd[27695]: nntp.terraraq.uk noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	14775	70.4%	1.7 MB	62.1%
inn	2305	11.0%	421.2 KB	15.4%
innd	2108	10.0%	346.5 KB	12.7%
innfeed	1796	8.6%	264.7 KB	9.7%
controlchan	1	0.0%	0.0 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	20986	100.0%	2.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	11856	59.8%
Negative hits	5507	27.8%
Do not exist	2276	11.5%
Cache misses	175	0.9%
TOTAL: 4	19814	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.012	0.0%	8	0.000	1.500	3.000
article cancel	00:00:00.000	0.0%	8	0.000	0.000	0.000
article cleanup	00:00:00.062	0.0%	2290	0.000	0.027	0.158
article logging	00:00:00.179	0.0%	2291	0.000	0.078	1.000
article parse	00:00:00.378	0.0%	6649	0.000	0.057	0.375
article write	00:00:01.066	0.0%	2008	0.167	0.531	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
artlog/artwrite	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:00.127	0.0%	20629	0.000	0.006	0.043
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%	10	0.000	0.100	0.500
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.280	0.0%	19924	0.000	0.014	0.068
history sync	00:00:00.052	0.0%	428	0.000	0.121	1.000
history write	00:00:00.404	0.0%	2290	0.000	0.176	0.500
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:39.206	100.0%	28127	1227.573	3073.175	11119.093
nntp read	00:00:00.676	0.0%	22169	0.000	0.030	0.074
overview write	00:00:00.677	0.0%	2008	0.000	0.337	2.636
perl filter	00:00:09.559	0.0%	2290	1.958	4.174	11.000
python filter	00:00:00.008	0.0%	2290	0.000	0.003	0.091
site send	00:00:01.041	0.0%	4074	0.000	0.256	0.600
TOTAL: 24:01:07.066	24:00:53.728	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.024	0.0%	2107	0.000	0.011	0.100
article prepare	00:00:00.004	0.0%	692	0.000	0.006	0.083
article read	00:00:00.026	0.0%	169	0.000	0.154	1.000
backlog stats	00:00:00.173	0.0%	29673	0.000	0.006	0.032
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.406	0.0%	15425	0.000	0.026	0.129
data write	00:00:00.825	0.0%	13885	0.000	0.059	0.125
idle	23:59:37.508	100.0%	29673	1496.232	2910.980	9708.984
status file	00:00:00.497	0.0%	250	1.000	1.988	6.000
TOTAL: 23:59:44.449	23:59:39.463	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:04:51.717	60.3%	9214	0.000	31.660	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.719	0.1%	18750	0.000	0.038	0.364
TOTAL: 00:08:03.844	00:04:52.436	60.4%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	148
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	170

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	feed-out.gegeweb.org	3	1956	982	973	1	50%	23:58:30
2	gegeweb-out.news.tnetconsulting.net	2	2131	324	1723	84	15%	27:07:12
3	news.samoylyk.net	2	1893	218	1675	0	11%	21:09:39
4	news.nntp4.net	2	1303	170	1076	57	13%	15:07:39
5	i2pn.org	2	2321	165	2154	2	7%	23:59:45
6	2a00:1098:0:86:1000:3f:0:2	0	2023	57	1964	2	2%	23:02:05
7	news.glou.org	4	1508	49	1366	93	3%	23:56:55
8	usenet.pasdenom.info	9	300	15	284	1	5%	10:22:10
9	nntp.terraraq.uk	1	0	0	0	0	0%	00:00:00
10	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 10		50	13435	1980	11215	240	14%	168:43:55

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	feed-out.gegeweb.org	3.6 MB	4.7 KB	3.6 MB	99%	3.8 KB
2	news.samoylyk.net	2.0 MB	0.0 KB	2.0 MB	100%	9.3 KB
3	gegeweb-out.news.tnetconsulting.net	923.0 KB	280.8 KB	1.2 MB	76%	3.0 KB
4	news.nntp4.net	669.3 KB	954.6 KB	1.6 MB	41%	7.2 KB
5	i2pn.org	510.3 KB	22.1 KB	532.4 KB	95%	3.2 KB
6	2a00:1098:0:86:1000:3f:0:2	436.3 KB	5.4 KB	441.7 KB	98%	7.5 KB
7	news.glou.org	257.1 KB	371.9 KB	629.0 KB	40%	4.4 KB
8	usenet.pasdenom.info	36.0 KB	0.8 KB	36.8 KB	97%	2.3 KB
9	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0%	0
10	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		8.4 MB	1.6 MB	10.0 MB	83%	4.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 7 04:15:01 - 04:59:59	54	2.7%	0.02	224.4 KB	2.6%	0.08
Aug 7 05:00:00 - 05:59:59	72	3.6%	0.02	303.2 KB	3.5%	0.08
Aug 7 06:00:00 - 06:59:59	72	3.6%	0.02	234.4 KB	2.7%	0.07
Aug 7 07:00:00 - 07:59:59	85	4.2%	0.02	328.1 KB	3.8%	0.09
Aug 7 08:00:00 - 08:59:59	47	2.3%	0.01	170.8 KB	2.0%	0.05
Aug 7 09:00:00 - 09:59:59	96	4.8%	0.03	409.2 KB	4.8%	0.11
Aug 7 10:00:00 - 10:59:59	90	4.5%	0.03	778.5 KB	9.1%	0.22
Aug 7 11:00:00 - 11:59:59	78	3.9%	0.02	307.3 KB	3.6%	0.09
Aug 7 12:00:00 - 12:59:59	64	3.2%	0.02	296.7 KB	3.5%	0.08
Aug 7 13:00:00 - 13:59:59	80	4.0%	0.02	376.4 KB	4.4%	0.10
Aug 7 14:00:00 - 14:59:59	85	4.2%	0.02	423.8 KB	5.0%	0.12
Aug 7 15:00:00 - 15:59:59	137	6.8%	0.04	556.1 KB	6.5%	0.15
Aug 7 16:00:00 - 16:59:59	106	5.3%	0.03	325.2 KB	3.8%	0.09
Aug 7 17:00:00 - 17:59:59	92	4.6%	0.03	390.2 KB	4.6%	0.11
Aug 7 18:00:00 - 18:59:59	65	3.2%	0.02	258.5 KB	3.0%	0.07
Aug 7 19:00:00 - 19:59:59	125	6.2%	0.03	619.8 KB	7.3%	0.17
Aug 7 20:00:00 - 20:59:59	107	5.3%	0.03	341.7 KB	4.0%	0.09
Aug 7 21:00:00 - 21:59:59	82	4.1%	0.02	339.3 KB	4.0%	0.09
Aug 7 22:00:00 - 22:59:59	85	4.2%	0.02	535.1 KB	6.3%	0.15
Aug 7 23:00:00 - 23:59:59	105	5.2%	0.03	300.4 KB	3.5%	0.08
Aug 8 00:00:00 - 00:59:59	81	4.0%	0.02	354.0 KB	4.1%	0.10
Aug 8 01:00:00 - 01:59:59	63	3.1%	0.02	202.0 KB	2.4%	0.06
Aug 8 02:00:00 - 02:59:59	58	2.9%	0.02	214.1 KB	2.5%	0.06
Aug 8 03:00:00 - 03:59:59	58	2.9%	0.02	194.3 KB	2.3%	0.05
Aug 8 04:00:00 - 04:15:01	18	0.9%	0.02	60.5 KB	0.7%	0.07
TOTAL: 24:00:00	2005	100.0%	0.02	8.3 MB	100.0%	0.10

Sites sending bad articles:

Server		Total	Other
1	news.glou.org	93	93
2	news.nntp4.net	85	85
3	gegeweb-out.news.tnetconsulting.net	81	81
4	i2pn.org	13	13
5	usenet.pasdenom.info	9	9
6	feed-out.gegeweb.org	2	2
7	news.samoylyk.net	1	1
TOTAL: 7		284	284

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	110
Bad path (news.giganews.com.POSTED)	82
Too many newsgroups	44
EMP (md5)	42
Cancel for rejected article	2
Cancels abusifs	1
Cancel of <MPG.411dffcd2615090098a4ed@news.individual.net> without Cancel-Key	1
Cancel of <0001HW.2C64215A0005B928700006BD238F@news.eternal-september.org> without Cancel-Key	1
TOTAL: 8	283

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	news.gegeweb.org	1067	61	962	0	0	5%	23:44:14
2	i2pn.org	3164	31	1833	20	42	0%	23:59:46
3	news.samoylyk.net	2232	16	1697	30	29	0%	23:59:46
4	news.nntp4.net	1894	6	1717	2	19	0%	23:58:56
5	news.tnetconsulting.net	3277	6	1997	1	12	0%	23:59:50
6	news.pasdenom.info	1489	0	1369	0	0	0%	23:56:43
7	nntp.terraraq.uk	1226	0	1208	0	0	0%	23:56:03
8	news.aioe.org	0	0	0	0	1593	0%	24:00:00
TOTAL: 8		14349	120	10783	53	1695	0%	191:35:18

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.gegeweb.org	250.2 KB	0.0 KB	250.2 KB	4.1 KB	23:44:14
2	i2pn.org	197.7 KB	165.1 KB	362.8 KB	7.1 KB	23:59:46
3	news.samoylyk.net	68.2 KB	135.4 KB	203.6 KB	4.4 KB	23:59:46
4	news.nntp4.net	26.0 KB	19.4 KB	45.4 KB	5.7 KB	23:58:56
5	news.tnetconsulting.net	12.5 KB	1.9 KB	14.5 KB	2.1 KB	23:59:50
6	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:56:43
7	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:56:03
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
TOTAL: 8		554.5 KB	321.9 KB	876.4 KB	5.1 KB	191:35:18

NNRP readership statistics:

System		Conn	Elapsed
1	azpdesc22.stretchoid.com	1	00:00:09
2	friendly.monitoring.internet-measurement.com	1	00:00:00
3	rapturous.monitoring.internet-measurement.com	1	00:00:00
4	nntp.terraraq.uk	2589	00:06:27
5	efficient.monitoring.internet-measurement.com	1	00:00:00
6	80.66.76.130	1	00:00:01
7	thorough.monitoring.internet-measurement.com	1	00:00:02
8	mail.gegeweb.eu	288	00:00:46
9	186.150.216.162.bc.googleusercontent.com	1	00:00:00
10	205.210.31.43	1	00:00:10
11	176.210.203.35.bc.googleusercontent.com	1	00:00:00
12	awesome.monitoring.internet-measurement.com	1	00:00:05
13	magnanimous.monitoring.internet-measurement.com	1	00:00:00
14	ec2-18-144-35-243.us-west-1.compute.amazonaws.com	1	00:00:01
15	grand.monitoring.internet-measurement.com	2	00:00:00
16	198.235.24.165	1	00:00:10
17	amazing.monitoring.internet-measurement.com	1	00:00:00
18	fantastic.monitoring.internet-measurement.com	1	00:00:00
19	ec2-54-177-240-105.us-west-1.compute.amazonaws.com	1	00:00:00
TOTAL: 19		2895	00:08:00

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.monitoring.internet-measurement.com	10	00:00:09
2	*.150.216.162.bc.googleusercontent.com	1	00:00:00
3	*.210.203.35.bc.googleusercontent.com	1	00:00:00
4	unresolved	3	00:00:22
5	*.gegeweb.eu	288	00:00:46
6	*.stretchoid.com	1	00:00:09
7	*.terraraq.uk	2589	00:06:27
8	*.us-west-1.compute.amazonaws.com	2	00:00:02
TOTAL: 8		2895	00:08:00

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
nntp.terraraq.uk	63.032	47.132	0.000	00:06:27
mail.gegeweb.eu	5.104	4.057	1.669	00:00:46
198.235.24.165	0.026	0.023	0.000	00:00:10
205.210.31.43	0.027	0.019	0.000	00:00:10
azpdesc22.stretchoid.com	0.028	0.020	0.000	00:00:09
awesome.monitoring.internet-measurement.com	0.032	0.008	0.000	00:00:05
thorough.monitoring.internet-measurement.com	0.032	0.016	0.000	00:00:02
80.66.76.130	0.021	0.021	0.000	00:00:01
ec2-18-144-35-243.us-west-1.compute.amazonaws.com	0.035	0.013	0.000	00:00:01
186.150.216.162.bc.googleusercontent.com	0.025	0.014	0.000	00:00:00
ec2-54-177-240-105.us-west-1.compute.amazonaws.com	0.031	0.009	0.000	00:00:00
grand.monitoring.internet-measurement.com	0.057	0.039	0.000	00:00:00
176.210.203.35.bc.googleusercontent.com	0.060	0.017	0.000	00:00:00
rapturous.monitoring.internet-measurement.com	0.032	0.028	0.000	00:00:00
friendly.monitoring.internet-measurement.com	0.038	0.025	0.000	00:00:00
fantastic.monitoring.internet-measurement.com	0.042	0.012	0.000	00:00:00
efficient.monitoring.internet-measurement.com	0.047	0.016	0.000	00:00:00
TOTAL: 17	68.669	51.469	1.669	00:08:00

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
176.210.203.35.bc.googleusercontent.com	4
186.150.216.162.bc.googleusercontent.com	4
80.66.76.130	1
TOTAL: 3	9

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
80.66.76.130	1	1
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

Aug 7 04:15:01 -- Aug 8 04:15:01