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

Unknown entries from news log file:

First 50 / 86 lines (58.1%)

Dec 7 04:17:07 rpi-studio nnrpd[3917]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 04:22:07 rpi-studio nnrpd[4266]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 04:27:08 rpi-studio nnrpd[4364]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 04:32:08 rpi-studio nnrpd[4637]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 04:37:08 rpi-studio nnrpd[4745]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 04:42:08 rpi-studio nnrpd[5004]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 04:47:08 rpi-studio nnrpd[5117]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 04:52:08 rpi-studio nnrpd[5364]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 04:57:09 rpi-studio nnrpd[5486]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 05:02:13 rpi-studio nnrpd[5735]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 05:07:13 rpi-studio nnrpd[5855]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 05:12:13 rpi-studio nnrpd[6110]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 05:17:14 rpi-studio nnrpd[6221]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 05:22:14 rpi-studio nnrpd[6476]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 05:27:14 rpi-studio nnrpd[6598]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 05:32:14 rpi-studio nnrpd[6851]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 05:37:14 rpi-studio nnrpd[6954]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 05:42:14 rpi-studio nnrpd[7235]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 05:47:14 rpi-studio nnrpd[7330]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 05:52:14 rpi-studio nnrpd[7591]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 05:57:14 rpi-studio nnrpd[7695]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 06:02:17 rpi-studio nnrpd[7948]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 06:07:17 rpi-studio nnrpd[8061]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 06:12:18 rpi-studio nnrpd[8303]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 06:15:08 rpi-studio nnrpd[8376]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 06:20:10 rpi-studio nnrpd[8483]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 06:25:11 rpi-studio nnrpd[8756]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 06:30:11 rpi-studio nnrpd[8856]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 06:35:11 rpi-studio nnrpd[9119]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 06:40:11 rpi-studio nnrpd[9274]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 06:45:11 rpi-studio nnrpd[9509]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 06:50:11 rpi-studio nnrpd[9650]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 06:55:11 rpi-studio nnrpd[9893]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 07:00:11 rpi-studio nnrpd[10012]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 07:05:11 rpi-studio nnrpd[10274]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 07:10:11 rpi-studio nnrpd[10375]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 07:15:11 rpi-studio nnrpd[10630]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 07:20:13 rpi-studio nnrpd[10739]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 07:25:13 rpi-studio nnrpd[11009]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 07:30:13 rpi-studio nnrpd[11112]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 07:35:14 rpi-studio nnrpd[11387]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 07:40:14 rpi-studio nnrpd[11512]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 07:45:14 rpi-studio nnrpd[11758]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 07:50:14 rpi-studio nnrpd[11881]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 07:55:14 rpi-studio nnrpd[12111]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 08:00:14 rpi-studio nnrpd[12246]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 08:05:15 rpi-studio nnrpd[12516]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 08:10:15 rpi-studio nnrpd[12642]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 08:15:15 rpi-studio nnrpd[12893]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission
Dec 7 08:20:18 rpi-studio nnrpd[13015]: 2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4 noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	55723	76.1%	10.5 MB	82.4%
innd	12942	17.7%	1.6 MB	12.8%
nnrpd	2399	3.3%	276.8 KB	2.1%
innfeed	2171	3.0%	341.8 KB	2.6%
controlchan	1	0.0%	0.2 KB	0.0%
nocem	1	0.0%	0.2 KB	0.0%
TOTAL: 6	73237	100.0%	12.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	85459	40.7%
Negative hits	66339	31.6%
Do not exist	54897	26.1%
Cache misses	3412	1.6%
TOTAL: 4	210107	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.392	0.0%	316	0.000	1.241	7.000
article cancel	00:00:00.068	0.0%	316	0.000	0.215	1.000
article cleanup	00:00:01.181	0.0%	55103	0.004	0.021	0.039
article logging	00:00:04.258	0.0%	55103	0.045	0.077	0.124
article parse	00:00:15.823	0.0%	291870	0.026	0.054	0.093
article write	00:00:50.995	0.1%	44323	0.479	1.151	3.513
artlog/artcncl	00:00:00.019	0.0%	316	0.000	0.060	0.500
artlog/artcncl/perl	00:00:00.023	0.0%	316	0.000	0.073	1.000
data move	00:00:02.740	0.0%	509655	0.002	0.005	0.012
hisgrep/artcncl	00:00:00.010	0.0%	316	0.000	0.032	1.000
hisgrep/artcncl/perl	00:00:00.004	0.0%	316	0.000	0.013	1.000
hisgrep/perl	00:00:00.039	0.0%	317	0.000	0.123	5.000
hishave/artcncl	00:00:00.005	0.0%	316	0.000	0.016	1.000
hishave/artcncl/perl	00:00:00.003	0.0%	316	0.000	0.009	0.091
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:03.843	0.0%	210561	0.009	0.018	0.037
history sync	00:00:00.034	0.0%	373	0.000	0.091	1.000
history write	00:00:13.492	0.0%	55073	0.077	0.245	0.873
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:53:48.952	99.5%	435399	62.620	197.586	300254.000
nntp read	00:00:12.732	0.0%	435330	0.000	0.029	0.051
overview write	00:00:20.086	0.0%	44323	0.077	0.453	10.360
perl filter	00:03:39.687	0.3%	55073	2.458	3.989	5.188
python filter	00:00:00.252	0.0%	55073	0.000	0.005	0.015
site send	00:00:12.017	0.0%	90834	0.052	0.132	0.213
TOTAL: 24:00:57.896	23:59:46.655	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.513	0.0%	56839	0.000	0.009	0.028
article prepare	00:00:00.098	0.0%	23726	0.000	0.004	0.042
article read	00:00:00.758	0.0%	4996	0.000	0.152	1.000
backlog stats	00:00:03.236	0.0%	669300	0.000	0.005	0.026
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:11.333	0.0%	458169	0.013	0.025	0.041
data write	00:00:17.520	0.0%	358084	0.000	0.049	0.063
idle	23:51:24.109	99.9%	669300	74.463	128.319	17179.229
status file	00:00:00.682	0.0%	296	1.000	2.304	7.000
TOTAL: 23:52:30.022	23:51:58.249	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:02.855	0.0%	57598	0.033	0.050	0.069
idle	00:00:57.364	0.3%	1616	12.667	35.498	9374.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:05.712	0.0%	118820	0.000	0.048	0.333
TOTAL: 04:38:25.229	00:01:05.931	0.4%	-	-	-	-

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	7	53428	22251	22089	9088	41%	23:42:10
2	news.glou.org	5	25391	15739	8435	1217	61%	23:09:54
3	nntp.terraraq.uk	4	16439	3332	12777	330	20%	24:05:17
4	feed-out.gegeweb.org	4	4145	1192	2953	0	28%	23:13:06
5	usenet.pasdenom.info	5	28848	1003	27794	51	3%	30:04:09
6	gegeweb-out.news.tnetconsulting.net	2	4765	381	4292	92	7%	28:18:27
7	i2pn.org	2	3791	118	3673	0	3%	21:50:33
8	news.samoylyk.net	3	4269	29	4240	0	0%	23:55:00
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		57	141076	44045	86253	10778	31%	198:18:36

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	228.4 MB	0.0 KB	83.0 MB	311.4 MB	73%	10.2 KB
2	news.glou.org	124.3 MB	0.0 KB	6.6 MB	130.9 MB	94%	7.9 KB
3	nntp.terraraq.uk	21.2 MB	85.3 KB	2.2 MB	23.5 MB	90%	6.6 KB
4	feed-out.gegeweb.org	16.1 MB	0.0 KB	0.0 KB	16.1 MB	100%	13.9 KB
5	usenet.pasdenom.info	7.2 MB	4.4 KB	143.8 KB	7.4 MB	98%	7.2 KB
6	i2pn.org	2.8 MB	0.0 KB	0.0 KB	2.8 MB	100%	24.5 KB
7	gegeweb-out.news.tnetconsulting.net	1.0 MB	0.0 KB	296.3 KB	1.3 MB	78%	2.9 KB
8	news.samoylyk.net	241.0 KB	0.0 KB	0.0 KB	241.0 KB	100%	8.3 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		401.4 MB	89.7 KB	92.2 MB	493.7 MB	81%	9.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 7 04:15:02 - 04:59:59	1275	2.9%	0.47	11.2 MB	2.8%	4.26
Dec 7 05:00:00 - 05:59:59	2274	5.1%	0.63	22.9 MB	5.7%	6.50
Dec 7 06:00:00 - 06:59:59	1903	4.3%	0.53	17.9 MB	4.4%	5.09
Dec 7 07:00:00 - 07:59:59	2305	5.2%	0.64	22.3 MB	5.5%	6.35
Dec 7 08:00:00 - 08:59:59	2627	5.9%	0.73	25.9 MB	6.4%	7.37
Dec 7 09:00:00 - 09:59:59	2234	5.0%	0.62	20.8 MB	5.2%	5.91
Dec 7 10:00:00 - 10:59:59	2255	5.1%	0.63	19.8 MB	4.9%	5.63
Dec 7 11:00:00 - 11:59:59	2399	5.4%	0.67	22.1 MB	5.5%	6.29
Dec 7 12:00:00 - 12:59:59	2210	5.0%	0.61	19.2 MB	4.8%	5.47
Dec 7 13:00:00 - 13:59:59	2335	5.3%	0.65	20.2 MB	5.0%	5.75
Dec 7 14:00:00 - 14:59:59	2340	5.3%	0.65	19.8 MB	4.9%	5.63
Dec 7 15:00:00 - 15:59:59	2394	5.4%	0.67	20.8 MB	5.2%	5.91
Dec 7 16:00:00 - 16:59:59	2227	5.0%	0.62	19.0 MB	4.7%	5.39
Dec 7 17:00:00 - 17:59:59	1731	3.9%	0.48	16.8 MB	4.2%	4.79
Dec 7 18:00:00 - 18:59:59	1357	3.1%	0.38	12.2 MB	3.0%	3.47
Dec 7 19:00:00 - 19:59:59	1622	3.7%	0.45	14.9 MB	3.7%	4.25
Dec 7 20:00:00 - 20:59:59	1547	3.5%	0.43	15.0 MB	3.7%	4.28
Dec 7 21:00:00 - 21:59:59	1286	2.9%	0.36	12.1 MB	3.0%	3.43
Dec 7 22:00:00 - 22:59:59	1440	3.3%	0.40	13.0 MB	3.2%	3.71
Dec 7 23:00:00 - 23:59:59	1035	2.3%	0.29	9.0 MB	2.2%	2.55
Dec 8 00:00:00 - 00:59:59	724	1.6%	0.20	7.7 MB	1.9%	2.20
Dec 8 01:00:00 - 01:59:59	733	1.7%	0.20	4.1 MB	1.0%	1.17
Dec 8 02:00:00 - 02:59:59	2239	5.1%	0.62	20.9 MB	5.2%	5.96
Dec 8 03:00:00 - 03:59:59	1400	3.2%	0.39	11.9 MB	2.9%	3.38
Dec 8 04:00:00 - 04:15:01	410	0.9%	0.46	3.6 MB	0.9%	4.09
TOTAL: 23:59:59	44302	100.0%	0.51	403.2 MB	100.0%	4.78

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news.nntp4.net	9065	36	0	9029
2	news.glou.org	1246	0	0	1246
3	nntp.terraraq.uk	332	1	23	308
4	gegeweb-out.news.tnetconsulting.net	78	0	3	75
5	usenet.pasdenom.info	55	1	4	50
6	news.samoylyk.net	1	0	0	1
TOTAL: 6		10777	38	30	10709

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.lettres.langue.allemande	26
fr.education.entraide.maths	8
fr.education.superieur	2
fr.misc.gestion	1
comp.lang.go	1
TOTAL: 5	38

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	10185
EMP (phl)	221
Bad path (news.giganews.com.POSTED)	94
EMP (phn path)	63
Too many newsgroups	60
EMP (phn nph)	50
User-issued spam cancel	31
Cancel for rejected article	2
Cancels abusifs	2
Cancel of non-existing ID <3cc56be2-5c44-4d87-9ce4-083dc1973923n@googlegroups.com>	1
TOTAL: 10	10709

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	news.nntp4.net	29788	519	21070	2	0	1423	1%	23:59:55
2	nntp.terraraq.uk	47770	380	38791	292	0	782	0%	23:59:55
3	i2pn.org	69137	204	40010	3732	1	3110	0%	23:59:51
4	news.tnetconsulting.net	63631	36	44041	223	0	1944	0%	23:59:55
5	news.samoylyk.net	64005	33	43893	327	0	1814	0%	23:59:55
6	news.pasdenom.info	51004	30	43467	158	0	1862	0%	23:59:55
7	news.gegeweb.org	43715	12	43056	0	0	1806	0%	23:59:55
8	news.aioe.org	0	0	0	0	0	7964	0%	23:59:55
TOTAL: 8		369050	1214	274328	4734	1	20705	0%	191:59:16

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.nntp4.net	5.0 MB	21.8 KB	5.0 MB	0.1 KB/s	9.8 KB	23:59:55
2	nntp.terraraq.uk	3.7 MB	3.4 MB	7.1 MB	0.1 KB/s	10.8 KB	23:59:55
3	i2pn.org	1.2 MB	36.4 MB	37.6 MB	0.4 KB/s	9.8 KB	23:59:51
4	news.gegeweb.org	401.4 KB	0.0 KB	401.4 KB	0.0 KB/s	33.5 KB	23:59:55
5	news.tnetconsulting.net	120.8 KB	2.2 MB	2.3 MB	0.0 KB/s	9.1 KB	23:59:55
6	news.pasdenom.info	115.4 KB	3.3 MB	3.4 MB	0.0 KB/s	18.6 KB	23:59:55
7	news.samoylyk.net	115.4 KB	3.2 MB	3.3 MB	0.0 KB/s	9.3 KB	23:59:55
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:55
TOTAL: 8		10.6 MB	48.5 MB	59.0 MB	0.1 KB/s	10.2 KB	191:59:16

NNRP readership statistics:

System		Conn	Elapsed
1	80.66.88.204	1	00:00:00
2	89.149.23.89	40	04:36:05
3	2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4	86	00:00:34
4	mail.gegeweb.eu	271	00:01:30
5	192.241.198.23	1	00:00:09
6	172.104.238.162	1	00:00:03
TOTAL: 6		400	04:38:24

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.gegeweb.eu	271	00:01:30
2	?	86	00:00:34
3	unresolved	43	04:36:19
TOTAL: 3		400	04:38:24

NNRP auth users [Top 20]:

User	Conn
mailman	271
TOTAL: 1	271

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
89.149.23.89	8.837	11.125	0.000	04:36:05
mail.gegeweb.eu	4.368	4.087	1.511	00:01:30
2a01:e0a:21:ea80:e65f:1ff:fe14:e7a4	1.973	1.498	0.000	00:00:34
192.241.198.23	0.031	0.023	0.000	00:00:09
172.104.238.162	0.012	0.016	0.000	00:00:03
80.66.88.204	0.004	0.021	0.000	00:00:00
TOTAL: 6	15.225	16.770	1.511	04:38:24

NNRP unrecognized commands (by host):

System	Conn
80.66.88.204	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
#003	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
80.66.88.204	1	1
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

Dec 7 04:15:02 -- Dec 8 04:15:01