Daily Usenet report for news.niel.me: Nov 18 04:15:01

Unknown entries from news log file:

First 50 / 2861 lines (1.7%)

Nov 18 04:15:04 rpi-studio nnrpd[21123]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:15:34 rpi-studio nnrpd[21250]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:16:04 rpi-studio nnrpd[21282]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:16:34 rpi-studio nnrpd[21299]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:17:04 rpi-studio nnrpd[21366]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:17:34 rpi-studio nnrpd[21370]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:18:04 rpi-studio nnrpd[21375]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:18:34 rpi-studio nnrpd[21378]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:19:04 rpi-studio nnrpd[21382]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:19:34 rpi-studio nnrpd[21409]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:20:04 rpi-studio nnrpd[21427]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:20:34 rpi-studio nnrpd[21430]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:21:05 rpi-studio nnrpd[21435]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:21:35 rpi-studio nnrpd[21438]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:22:05 rpi-studio nnrpd[21596]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:22:35 rpi-studio nnrpd[21610]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:23:05 rpi-studio nnrpd[21617]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:23:35 rpi-studio nnrpd[21621]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:24:05 rpi-studio nnrpd[21636]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:24:35 rpi-studio nnrpd[21651]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:25:05 rpi-studio nnrpd[21668]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:25:35 rpi-studio nnrpd[21683]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:26:05 rpi-studio nnrpd[21687]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:26:36 rpi-studio nnrpd[21696]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:27:06 rpi-studio nnrpd[21702]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:27:36 rpi-studio nnrpd[21706]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:28:06 rpi-studio nnrpd[21710]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:28:36 rpi-studio nnrpd[21714]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:29:06 rpi-studio nnrpd[21718]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:29:36 rpi-studio nnrpd[21733]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:30:06 rpi-studio nnrpd[21744]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:30:36 rpi-studio nnrpd[21748]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:31:09 rpi-studio nnrpd[21754]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:31:39 rpi-studio nnrpd[21757]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:32:09 rpi-studio nnrpd[21909]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:32:39 rpi-studio nnrpd[21916]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:33:09 rpi-studio nnrpd[21921]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:33:39 rpi-studio nnrpd[21924]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:34:09 rpi-studio nnrpd[21963]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:34:39 rpi-studio nnrpd[21979]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:35:09 rpi-studio nnrpd[21994]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:35:40 rpi-studio nnrpd[22003]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:36:10 rpi-studio nnrpd[22008]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:36:40 rpi-studio nnrpd[22016]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:37:10 rpi-studio nnrpd[22020]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:37:40 rpi-studio nnrpd[22030]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:38:11 rpi-studio nnrpd[22039]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:38:41 rpi-studio nnrpd[22055]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:39:11 rpi-studio nnrpd[22072]: mx.terraraq.uk noperm ihave without permission
Nov 18 04:39:41 rpi-studio nnrpd[22075]: mx.terraraq.uk noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	12899	60.9%	1.3 MB	47.1%
inn	4137	19.5%	774.0 KB	28.4%
innd	2444	11.5%	386.1 KB	14.2%
innfeed	1712	8.1%	283.8 KB	10.4%
nocem	1	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	21194	100.0%	2.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	22876	61.6%
Negative hits	10186	27.4%
Do not exist	4078	11.0%
Cache misses	7	0.0%
TOTAL: 4	37147	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.301	0.0%	20	1.000	15.050	281.000
article cancel	00:00:00.005	0.0%	21	0.000	0.238	1.000
article cleanup	00:00:00.118	0.0%	4095	0.000	0.029	0.150
article logging	00:00:00.332	0.0%	4108	0.000	0.081	0.429
article parse	00:00:00.803	0.0%	10125	0.000	0.079	0.250
article write	00:00:02.520	0.0%	3569	0.158	0.706	9.652
artlog/artcncl	00:00:00.001	0.0%	21	0.000	0.048	0.500
artlog/artcncl/perl	00:00:00.004	0.0%	20	0.000	0.200	1.000
data move	00:00:00.122	0.0%	42548	0.000	0.003	0.016
hisgrep/artcncl	00:00:00.001	0.0%	21	0.000	0.048	0.333
hisgrep/artcncl/perl	00:00:00.000	0.0%	20	0.000	0.000	0.000
hisgrep/perl	00:00:00.003	0.0%	24	0.000	0.125	1.000
hishave/artcncl	00:00:00.000	0.0%	21	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	20	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.515	0.0%	37258	0.000	0.014	0.034
history sync	00:00:00.050	0.0%	416	0.000	0.120	0.667
history write	00:00:01.383	0.0%	4095	0.056	0.338	11.184
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:05.862	99.9%	48208	487.836	1792.355	5312.726
nntp read	00:00:01.653	0.0%	42489	0.000	0.039	0.130
overview write	00:00:02.556	0.0%	3569	0.071	0.716	8.600
perl filter	00:00:26.251	0.0%	4095	3.969	6.411	37.395
python filter	00:00:00.008	0.0%	4095	0.000	0.002	0.100
site send	00:00:01.608	0.0%	7197	0.000	0.223	0.550
TOTAL: 24:01:14.147	24:00:44.096	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.046	0.0%	3648	0.000	0.013	0.250
article prepare	00:00:00.000	0.0%	556	0.000	0.000	0.000
article read	00:00:00.015	0.0%	112	0.000	0.134	5.000
backlog stats	00:00:00.142	0.0%	56975	0.000	0.002	0.021
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.801	0.0%	33616	0.000	0.024	0.097
data write	00:00:01.964	0.0%	30216	0.015	0.065	0.119
idle	23:57:28.053	100.0%	56975	650.207	1513.788	4052.913
status file	00:00:00.472	0.0%	179	0.000	2.637	7.000
TOTAL: 23:57:34.640	23:57:31.493	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:34:22.544	87.5%	10023	11.400	205.781	900006.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.580	0.0%	20363	0.000	0.028	0.364
TOTAL: 00:39:18.309	00:34:23.124	87.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-out.aioe.org	1	3551	1552	1930	69	43%	23:07:22
2	news.glou.org	2	3444	730	2640	74	21%	23:59:48
3	i2pn.org	1	3591	719	2691	181	20%	22:06:21
4	feed-out.gegeweb.org	3	2345	273	2072	0	11%	23:55:53
5	usenet.pasdenom.info	1	2560	149	2405	6	5%	19:15:18
6	news.nntp4.net	2	3918	57	3732	129	1%	24:17:05
7	gegeweb-out.news.tnetconsulting.net	1	3255	25	3228	2	0%	23:59:56
8	news.samoylyk.net	2	6816	21	6795	0	0%	47:36:26
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		38	29480	3526	25493	461	11%	208:18:09

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.glou.org	7.8 MB	299.4 KB	8.0 MB	96%	10.2 KB
2	news-out.aioe.org	5.1 MB	245.3 KB	5.4 MB	95%	3.4 KB
3	i2pn.org	2.3 MB	446.5 KB	2.7 MB	83%	3.1 KB
4	feed-out.gegeweb.org	922.4 KB	0.0 KB	922.4 KB	100%	3.4 KB
5	news.samoylyk.net	589.0 KB	0.0 KB	589.0 KB	100%	28.0 KB
6	news.nntp4.net	556.2 KB	439.0 KB	995.2 KB	55%	5.4 KB
7	usenet.pasdenom.info	368.9 KB	10.1 KB	378.9 KB	97%	2.4 KB
8	gegeweb-out.news.tnetconsulting.net	66.3 KB	3.8 KB	70.1 KB	94%	2.6 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		17.6 MB	1.4 MB	19.0 MB	92%	4.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 18 04:15:01 - 04:59:59	71	2.0%	0.03	250.3 KB	1.4%	0.09
Nov 18 05:00:00 - 05:59:59	88	2.5%	0.02	349.0 KB	1.9%	0.10
Nov 18 06:00:00 - 06:59:59	73	2.0%	0.02	291.5 KB	1.6%	0.08
Nov 18 07:00:00 - 07:59:59	81	2.3%	0.02	230.1 KB	1.3%	0.06
Nov 18 08:00:00 - 08:59:59	73	2.0%	0.02	254.2 KB	1.4%	0.07
Nov 18 09:00:00 - 09:59:59	111	3.1%	0.03	804.9 KB	4.4%	0.22
Nov 18 10:00:00 - 10:59:59	124	3.5%	0.03	1001.9 KB	5.5%	0.28
Nov 18 11:00:00 - 11:59:59	160	4.5%	0.04	535.7 KB	2.9%	0.15
Nov 18 12:00:00 - 12:59:59	160	4.5%	0.04	750.5 KB	4.1%	0.21
Nov 18 13:00:00 - 13:59:59	163	4.6%	0.05	558.9 KB	3.1%	0.16
Nov 18 14:00:00 - 14:59:59	149	4.2%	0.04	2.0 MB	11.1%	0.56
Nov 18 15:00:00 - 15:59:59	158	4.4%	0.04	556.7 KB	3.0%	0.15
Nov 18 16:00:00 - 16:59:59	157	4.4%	0.04	545.1 KB	3.0%	0.15
Nov 18 17:00:00 - 17:59:59	221	6.2%	0.06	682.5 KB	3.7%	0.19
Nov 18 18:00:00 - 18:59:59	241	6.8%	0.07	2.2 MB	12.1%	0.61
Nov 18 19:00:00 - 19:59:59	257	7.2%	0.07	972.1 KB	5.3%	0.27
Nov 18 20:00:00 - 20:59:59	230	6.5%	0.06	959.1 KB	5.2%	0.27
Nov 18 21:00:00 - 21:59:59	188	5.3%	0.05	695.4 KB	3.8%	0.19
Nov 18 22:00:00 - 22:59:59	169	4.7%	0.05	627.8 KB	3.4%	0.17
Nov 18 23:00:00 - 23:59:59	156	4.4%	0.04	562.4 KB	3.1%	0.16
Nov 19 00:00:00 - 00:59:59	150	4.2%	0.04	508.6 KB	2.8%	0.14
Nov 19 01:00:00 - 01:59:59	148	4.2%	0.04	509.3 KB	2.8%	0.14
Nov 19 02:00:00 - 02:59:59	115	3.2%	0.03	1.8 MB	10.2%	0.52
Nov 19 03:00:00 - 03:59:59	104	2.9%	0.03	492.7 KB	2.7%	0.14
Nov 19 04:00:00 - 04:15:01	14	0.4%	0.02	40.6 KB	0.2%	0.05
TOTAL: 24:00:00	3561	100.0%	0.04	17.9 MB	100.0%	0.21

Sites sending bad articles:

Server		Total	Group	Other
1	i2pn.org	210	0	210
2	news.nntp4.net	130	0	130
3	news-out.aioe.org	112	2	110
4	news.glou.org	74	0	74
5	usenet.pasdenom.info	5	0	5
6	news.samoylyk.net	3	0	3
7	gegeweb-out.news.tnetconsulting.net	1	0	1
TOTAL: 7		535	2	533

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.petites-annonces.divers	1
news.test	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
EMP (phn nph)	182
EMP (md5)	149
EMP (phl)	80
Bad path (news.giganews.com.POSTED)	79
Too many newsgroups	25
Cancels abusifs	2
No Cancel-Key matches Cancel-Lock. target=<63777d62$0$3013$426a74cc@news.free.fr>	1
Cancel of <mn.92597e6b8307cc7d.145333@ozorro.eu.invalid> without Cancel-Key	1
Cancel of <mn.92587e6b504a8ed5.145333@ozorro.eu.invalid> without Cancel-Key	1
TOTAL: 9	520

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news-out.aioe.org	9
	news.samoylyk.net	3
	news.nntp4.net	1
	TOTAL: 3	13
TOTAL: 1		13

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.nntp4.net	4045	27	3464	0	0	0%	23:59:56
2	news.samoylyk.net	4626	14	3511	28	8	0%	23:59:39
3	nntp.terraraq.uk	3079	7	2422	45	0	0%	23:59:56
4	news.tnetconsulting.net	5614	6	3550	2	41	0%	23:59:49
5	i2pn.org	4676	5	2715	2	40	0%	23:59:08
6	news.aioe.org	1660	2	1451	0	0	0%	23:58:58
7	news.gegeweb.org	3353	1	3288	0	0	0%	23:59:56
8	news.pasdenom.info	3534	0	3029	0	0	0%	23:59:39
TOTAL: 8		30587	62	23430	77	89	0%	191:57:01

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.nntp4.net	1.7 MB	0.0 KB	1.7 MB	0.0 KB/s	64.8 KB	23:59:56
2	nntp.terraraq.uk	139.3 KB	4.1 MB	4.3 MB	0.1 KB/s	83.9 KB	23:59:56
3	news.samoylyk.net	32.5 KB	195.4 KB	227.8 KB	0.0 KB/s	5.4 KB	23:59:39
4	news.aioe.org	28.0 KB	0.0 KB	28.0 KB	0.0 KB/s	14.0 KB	23:58:58
5	news.tnetconsulting.net	16.0 KB	4.9 KB	20.9 KB	0.0 KB/s	2.6 KB	23:59:49
6	i2pn.org	15.5 KB	4.9 KB	20.4 KB	0.0 KB/s	2.9 KB	23:59:08
7	news.gegeweb.org	2.6 KB	0.0 KB	2.6 KB	0.0 KB/s	2.6 KB	23:59:56
8	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:39
TOTAL: 8		1.9 MB	4.3 MB	6.3 MB	0.0 KB/s	46.1 KB	191:57:01

NNRP readership statistics:

System		Conn	Elapsed
1	dojo.census.shodan.io	1	00:00:03
2	mail.gegeweb.eu	288	00:00:34
3	62.233.50.179	1	00:00:08
4	118.123.105.89	1	00:30:00
5	192.241.208.43	1	00:00:00
6	mx.terraraq.uk	2859	00:08:25
TOTAL: 6		3151	00:39:13

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	3	00:30:09
2	*.terraraq.uk	2859	00:08:25
3	*.gegeweb.eu	288	00:00:34
4	*.census.shodan.io	1	00:00:03
TOTAL: 4		3151	00:39:13

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
118.123.105.89	0.022	0.033	0.000	00:30:00
mx.terraraq.uk	77.127	76.220	0.000	00:08:25
mail.gegeweb.eu	7.935	7.978	2.328	00:00:34
62.233.50.179	0.043	0.011	0.000	00:00:08
dojo.census.shodan.io	0.033	0.022	0.000	00:00:03
192.241.208.43	0.041	0.010	0.000	00:00:00
TOTAL: 6	85.201	84.274	2.328	00:39:13

NNRP unrecognized commands (by host):

System	Conn
192.241.208.43	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
MGLNDD_45.13.105.36_119	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
118.123.105.89	1	0
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

Nov 18 04:15:01 -- Nov 19 04:15:01