Daily Usenet report for news.niel.me: Dec 6 04:15:02

Unknown entries from news log file:

First 50 / 266 lines (18.8%)

Dec 6 06:15:07 rpi-studio nnrpd[21210]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 06:20:07 rpi-studio nnrpd[21392]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 06:25:07 rpi-studio nnrpd[21695]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 06:30:07 rpi-studio nnrpd[21799]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 06:35:07 rpi-studio nnrpd[22063]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 06:40:07 rpi-studio nnrpd[22152]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 06:45:07 rpi-studio nnrpd[22401]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 06:50:07 rpi-studio nnrpd[22501]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 06:55:07 rpi-studio nnrpd[22741]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 07:00:11 rpi-studio nnrpd[22852]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 07:05:11 rpi-studio nnrpd[23106]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 07:10:11 rpi-studio nnrpd[23207]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 07:15:12 rpi-studio nnrpd[23440]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 07:20:12 rpi-studio nnrpd[23554]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 07:25:13 rpi-studio nnrpd[23792]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 07:30:13 rpi-studio nnrpd[23884]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 07:35:13 rpi-studio nnrpd[24139]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 07:40:13 rpi-studio nnrpd[24244]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 07:45:13 rpi-studio nnrpd[24484]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 07:50:13 rpi-studio nnrpd[24593]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 07:55:13 rpi-studio nnrpd[24829]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 08:00:15 rpi-studio nnrpd[24939]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 08:05:15 rpi-studio nnrpd[25186]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 08:10:15 rpi-studio nnrpd[25291]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 08:15:18 rpi-studio nnrpd[25540]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 08:20:18 rpi-studio nnrpd[25662]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 08:25:18 rpi-studio nnrpd[25909]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 08:30:18 rpi-studio nnrpd[26014]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 08:35:18 rpi-studio nnrpd[26254]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 08:40:18 rpi-studio nnrpd[26349]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 08:45:18 rpi-studio nnrpd[26597]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 08:50:18 rpi-studio nnrpd[26705]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 08:55:18 rpi-studio nnrpd[26940]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 09:00:19 rpi-studio nnrpd[27046]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 09:05:19 rpi-studio nnrpd[27289]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 09:10:19 rpi-studio nnrpd[27394]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 09:15:21 rpi-studio nnrpd[27635]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 09:20:21 rpi-studio nnrpd[27754]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 09:25:21 rpi-studio nnrpd[27986]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 09:30:21 rpi-studio nnrpd[28093]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 09:35:21 rpi-studio nnrpd[28340]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 09:40:21 rpi-studio nnrpd[28433]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 09:45:21 rpi-studio nnrpd[28680]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 09:50:22 rpi-studio nnrpd[28776]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 09:55:22 rpi-studio nnrpd[29031]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 10:00:24 rpi-studio nnrpd[29116]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 10:05:24 rpi-studio nnrpd[29374]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 10:10:24 rpi-studio nnrpd[29477]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 10:15:26 rpi-studio nnrpd[29714]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 6 10:20:26 rpi-studio nnrpd[29833]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	49747	74.1%	9.4 MB	80.7%
innd	12022	17.9%	1.5 MB	13.1%
nnrpd	3362	5.0%	408.5 KB	3.4%
innfeed	2001	3.0%	325.9 KB	2.7%
nocem	1	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	67134	100.0%	11.6 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	60191	36.5%
Positive hits	55673	33.7%
Do not exist	49186	29.8%
Cache misses	31	0.0%
TOTAL: 4	165081	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.223	0.0%	171	0.500	1.304	6.000
article cancel	00:00:00.036	0.0%	171	0.000	0.211	1.000
article cleanup	00:00:01.079	0.0%	49430	0.006	0.022	0.053
article logging	00:00:03.785	0.0%	49430	0.035	0.077	0.122
article parse	00:00:14.654	0.0%	281680	0.032	0.052	0.078
article write	00:00:52.481	0.1%	39455	0.855	1.330	6.389
artlog/artcncl	00:00:00.013	0.0%	171	0.000	0.076	1.000
artlog/artcncl/perl	00:00:00.010	0.0%	171	0.000	0.058	1.000
data move	00:00:02.588	0.0%	467278	0.002	0.006	0.010
hisgrep/artcncl	00:00:00.006	0.0%	171	0.000	0.035	1.000
hisgrep/artcncl/perl	00:00:00.003	0.0%	171	0.000	0.018	0.333
hisgrep/perl	00:00:00.026	0.0%	174	0.000	0.149	3.000
hishave/artcncl	00:00:00.000	0.0%	171	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.001	0.0%	171	0.000	0.006	0.111
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:03.363	0.0%	165495	0.009	0.020	0.036
history sync	00:00:00.068	0.0%	374	0.000	0.182	1.000
history write	00:00:11.941	0.0%	49430	0.123	0.242	0.751
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:53:59.419	99.5%	396813	122.215	216.826	547.968
nntp read	00:00:11.714	0.0%	396023	0.018	0.030	0.045
overview write	00:00:33.420	0.0%	39455	0.206	0.847	79.684
perl filter	00:03:31.510	0.2%	49430	3.208	4.279	5.321
python filter	00:00:00.254	0.0%	49430	0.000	0.005	0.019
site send	00:00:10.424	0.0%	81164	0.037	0.128	0.223
TOTAL: 24:00:57.234	23:59:57.018	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.344	0.0%	41903	0.000	0.008	0.028
article prepare	00:00:00.107	0.0%	29404	0.000	0.004	0.021
article read	00:00:00.721	0.0%	4557	0.000	0.158	0.500
backlog stats	00:00:02.988	0.0%	636301	0.001	0.005	0.008
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:10.314	0.0%	427382	0.013	0.024	0.036
data write	00:00:17.417	0.0%	339931	0.041	0.051	0.066
idle	23:51:20.640	99.9%	636301	78.695	134.969	309.788
status file	00:00:00.583	0.0%	242	1.000	2.409	10.000
TOTAL: 23:52:22.087	23:51:53.114	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:58.123	33.2%	2236	12.667	25.994	91.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.172	0.1%	4769	0.000	0.036	1.000
TOTAL: 00:02:55.271	00:00:58.295	33.3%	-	-	-	-

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.glou.org	6	24733	17954	5240	1539	72%	24:36:18
2	news.nntp4.net	4	46690	17561	21081	8048	37%	24:11:23
3	nntp.terraraq.uk	2	15862	1836	13731	295	11%	24:30:14
4	feed-out.gegeweb.org	4	4277	1327	2950	0	31%	24:02:37
5	i2pn.org	3	8713	287	8426	0	3%	46:52:09
6	gegeweb-out.news.tnetconsulting.net	2	3230	233	2953	44	7%	21:36:08
7	2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4	0	1344	211	1132	1	15%	09:44:49
8	news.samoylyk.net	2	8638	57	8581	0	0%	46:56:23
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		48	113487	39466	64094	9927	34%	222:30:01

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	179.4 MB	66.7 MB	246.2 MB	72%	9.8 KB
2	news.glou.org	157.6 MB	9.7 MB	167.3 MB	94%	8.8 KB
3	feed-out.gegeweb.org	17.5 MB	0.0 KB	17.5 MB	100%	13.5 KB
4	nntp.terraraq.uk	10.7 MB	2.1 MB	12.8 MB	83%	6.2 KB
5	i2pn.org	5.9 MB	0.0 KB	5.9 MB	100%	21.2 KB
6	news.samoylyk.net	1.6 MB	0.0 KB	1.6 MB	100%	28.4 KB
7	gegeweb-out.news.tnetconsulting.net	631.0 KB	140.0 KB	771.1 KB	81%	2.8 KB
8	2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4	603.2 KB	1.2 KB	604.4 KB	99%	2.9 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		374.0 MB	78.7 MB	452.7 MB	82%	9.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 6 04:15:02 - 04:59:59	1239	3.1%	0.46	11.8 MB	3.2%	4.50
Dec 6 05:00:00 - 05:59:59	1750	4.4%	0.49	17.5 MB	4.7%	4.97
Dec 6 06:00:00 - 06:59:59	1621	4.1%	0.45	15.9 MB	4.3%	4.53
Dec 6 07:00:00 - 07:59:59	1670	4.2%	0.46	16.8 MB	4.5%	4.79
Dec 6 08:00:00 - 08:59:59	2062	5.2%	0.57	19.3 MB	5.2%	5.49
Dec 6 09:00:00 - 09:59:59	1952	5.0%	0.54	16.9 MB	4.5%	4.81
Dec 6 10:00:00 - 10:59:59	2286	5.8%	0.64	22.2 MB	6.0%	6.33
Dec 6 11:00:00 - 11:59:59	2377	6.0%	0.66	22.4 MB	6.0%	6.37
Dec 6 12:00:00 - 12:59:59	2199	5.6%	0.61	26.5 MB	7.1%	7.53
Dec 6 13:00:00 - 13:59:59	2230	5.7%	0.62	21.0 MB	5.6%	5.97
Dec 6 14:00:00 - 14:59:59	1961	5.0%	0.54	19.8 MB	5.3%	5.62
Dec 6 15:00:00 - 15:59:59	1777	4.5%	0.49	18.0 MB	4.8%	5.11
Dec 6 16:00:00 - 16:59:59	1764	4.5%	0.49	19.4 MB	5.2%	5.52
Dec 6 17:00:00 - 17:59:59	1649	4.2%	0.46	17.4 MB	4.7%	4.95
Dec 6 18:00:00 - 18:59:59	1583	4.0%	0.44	14.9 MB	4.0%	4.23
Dec 6 19:00:00 - 19:59:59	1561	4.0%	0.43	13.7 MB	3.7%	3.90
Dec 6 20:00:00 - 20:59:59	1222	3.1%	0.34	11.7 MB	3.1%	3.32
Dec 6 21:00:00 - 21:59:59	1182	3.0%	0.33	9.6 MB	2.6%	2.73
Dec 6 22:00:00 - 22:59:59	1113	2.8%	0.31	8.3 MB	2.2%	2.35
Dec 6 23:00:00 - 23:59:59	1131	2.9%	0.31	9.9 MB	2.7%	2.82
Dec 7 00:00:00 - 00:59:59	889	2.3%	0.25	7.3 MB	2.0%	2.08
Dec 7 01:00:00 - 01:59:59	964	2.4%	0.27	6.7 MB	1.8%	1.90
Dec 7 02:00:00 - 02:59:59	1379	3.5%	0.38	9.8 MB	2.6%	2.78
Dec 7 03:00:00 - 03:59:59	1465	3.7%	0.41	11.4 MB	3.1%	3.23
Dec 7 04:00:00 - 04:15:02	407	1.0%	0.45	3.7 MB	1.0%	4.22
TOTAL: 24:00:00	39433	100.0%	0.46	371.8 MB	100.0%	4.41

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	8073	21	8052
2	news.glou.org	1539	0	1539
3	nntp.terraraq.uk	292	0	292
4	gegeweb-out.news.tnetconsulting.net	68	0	68
TOTAL: 4		9972	21	9951

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.lettres.langue.allemande	11
fr.education.superieur	7
fr.misc.gestion	1
fr.comp.objet	1
fr.education.entraide.maths	1
TOTAL: 5	21

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	9384
EMP (phl)	265
Bad path (news.giganews.com.POSTED)	86
Too many newsgroups	82
EMP (phn path)	70
EMP (phn nph)	39
User-issued spam cancel	14
Cancel for rejected article	3
hierarchy violation - crosspost outside local	3
Cancels abusifs	2
No Cancel-Key matches Cancel-Lock. target=<6570ac97$0$10084$426a74cc@news.free.fr>	1
No Cancel-Key matches Cancel-Lock. target=<6570b3e8$0$2591$426a74cc@news.free.fr>	1
Cancel of non-existing ID <ukpeo7$ukj$1@rasp.pasdenom.info>	1
TOTAL: 13	9951

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.nntp4.net	30954	1270	20399	2	1	4%	23:59:27
2	nntp.terraraq.uk	45286	944	34775	563	88	2%	23:59:56
3	i2pn.org	62691	189	36428	2535	655	0%	23:59:53
4	news.tnetconsulting.net	56400	37	38673	722	80	0%	23:59:57
5	news.gegeweb.org	38834	21	38054	0	1	0%	23:59:55
6	news.samoylyk.net	58771	16	38711	675	1718	0%	23:57:03
7	news.pasdenom.info	47360	13	38505	357	122	0%	23:59:54
8	news.aioe.org	0	0	0	0	7438	0%	23:59:57
TOTAL: 8		340296	2490	245545	4854	10103	0%	191:56:02

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.nntp4.net	13.0 MB	3.9 KB	13.0 MB	0.2 KB/s	10.5 KB	23:59:27
2	nntp.terraraq.uk	9.7 MB	8.2 MB	17.9 MB	0.2 KB/s	12.2 KB	23:59:56
3	i2pn.org	1.2 MB	25.6 MB	26.8 MB	0.3 KB/s	10.1 KB	23:59:53
4	news.gegeweb.org	736.2 KB	0.0 KB	736.2 KB	0.0 KB/s	35.1 KB	23:59:55
5	news.tnetconsulting.net	205.7 KB	7.9 MB	8.1 MB	0.1 KB/s	10.9 KB	23:59:57
6	news.pasdenom.info	105.5 KB	5.7 MB	5.8 MB	0.1 KB/s	16.2 KB	23:59:54
7	news.samoylyk.net	57.9 KB	7.6 MB	7.6 MB	0.1 KB/s	11.3 KB	23:57:03
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:57
TOTAL: 8		25.0 MB	55.0 MB	80.0 MB	0.1 KB/s	11.2 KB	191:56:02

NNRP readership statistics:

System		Conn	Elapsed
1	supportive.monitoring.internet-measurement.com	1	00:00:00
2	lively.monitoring.internet-measurement.com	1	00:00:00
3	mail.gegeweb.eu	288	00:00:59
4	194.165.16.73	1	00:00:01
5	ec2-54-218-112-77.us-west-2.compute.amazonaws.com	1	00:00:01
6	celebrated.monitoring.internet-measurement.com	1	00:00:05
7	2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4	264	00:01:44
8	thrilling.monitoring.internet-measurement.com	1	00:00:00
9	45.227.254.8	1	00:00:00
10	107.170.245.35	1	00:00:00
TOTAL: 10		560	00:02:54

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.monitoring.internet-measurement.com	4	00:00:05
2	unresolved	3	00:00:03
3	?	264	00:01:44
4	*.us-west-2.compute.amazonaws.com	1	00:00:01
5	*.gegeweb.eu	288	00:00:59
TOTAL: 5		560	00:02: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
2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4	5.605	4.634	0.000	00:01:44
mail.gegeweb.eu	4.618	4.369	1.592	00:00:59
celebrated.monitoring.internet-measurement.com	0.035	0.031	0.000	00:00:05
194.165.16.73	0.023	0.005	0.000	00:00:01
ec2-54-218-112-77.us-west-2.compute.amazonaws.com	0.028	0.014	0.000	00:00:01
107.170.245.35	0.019	0.034	0.000	00:00:00
supportive.monitoring.internet-measurement.com	0.016	0.012	0.000	00:00:00
45.227.254.8	0.033	0.020	0.000	00:00:00
thrilling.monitoring.internet-measurement.com	0.020	0.008	0.000	00:00:00
lively.monitoring.internet-measurement.com	0.029	0.033	0.000	00:00:00
TOTAL: 10	10.426	9.160	1.592	00:02:54

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
107.170.245.35	1
45.227.254.8	1
TOTAL: 2	2

NNRP unrecognized commands (by command):

Command	Count
#003	1
MGLNDD_45.13.105.36_119	1
TOTAL: 2	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
45.227.254.8	1	1
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

Dec 6 04:15:02 -- Dec 7 04:15:02