Daily Usenet report for news.niel.me: May 24 04:15:01

Unknown entries from news log file:

First 50 / 2708 lines (1.8%)

May 24 04:15:28 rpi-studio nnrpd[7977]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:16:16 rpi-studio nnrpd[8015]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:16:46 rpi-studio nnrpd[8094]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:17:16 rpi-studio nnrpd[8113]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:17:46 rpi-studio nnrpd[8121]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:18:16 rpi-studio nnrpd[8130]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:18:46 rpi-studio nnrpd[8138]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:19:16 rpi-studio nnrpd[8157]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:19:46 rpi-studio nnrpd[8165]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:20:16 rpi-studio nnrpd[8178]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:20:46 rpi-studio nnrpd[8190]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:21:39 rpi-studio nnrpd[8346]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:22:27 rpi-studio nnrpd[8368]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:23:15 rpi-studio nnrpd[8385]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:24:03 rpi-studio nnrpd[8398]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:24:52 rpi-studio nnrpd[8417]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:25:40 rpi-studio nnrpd[8437]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:26:28 rpi-studio nnrpd[8457]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:27:16 rpi-studio nnrpd[8474]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:28:05 rpi-studio nnrpd[8489]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:28:53 rpi-studio nnrpd[8503]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:29:42 rpi-studio nnrpd[8517]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:30:30 rpi-studio nnrpd[8542]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:31:19 rpi-studio nnrpd[8559]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:31:49 rpi-studio nnrpd[8712]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:32:20 rpi-studio nnrpd[8724]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:32:50 rpi-studio nnrpd[8747]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:33:20 rpi-studio nnrpd[8758]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:33:50 rpi-studio nnrpd[8765]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:34:20 rpi-studio nnrpd[8786]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:34:50 rpi-studio nnrpd[8793]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:35:21 rpi-studio nnrpd[8810]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:35:51 rpi-studio nnrpd[8826]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:36:35 rpi-studio nnrpd[8835]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:37:06 rpi-studio nnrpd[8854]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:37:36 rpi-studio nnrpd[8861]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:38:06 rpi-studio nnrpd[8870]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:38:36 rpi-studio nnrpd[8878]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:39:07 rpi-studio nnrpd[8887]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:39:37 rpi-studio nnrpd[8894]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:40:07 rpi-studio nnrpd[8912]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:40:37 rpi-studio nnrpd[8920]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:41:08 rpi-studio nnrpd[8929]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:41:38 rpi-studio nnrpd[9086]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:42:08 rpi-studio nnrpd[9096]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:42:38 rpi-studio nnrpd[9103]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:43:08 rpi-studio nnrpd[9112]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:43:38 rpi-studio nnrpd[9125]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:44:08 rpi-studio nnrpd[9141]: 2001:41d0:a:280::1 noperm ihave without permission
May 24 04:44:38 rpi-studio nnrpd[9149]: 2001:41d0:a:280::1 noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	15773	68.2%	1.7 MB	58.5%
inn	3433	14.8%	658.4 KB	21.6%
innd	2166	9.4%	351.1 KB	11.5%
innfeed	1752	7.6%	255.6 KB	8.4%
controlchan	1	0.0%	0.0 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	23126	100.0%	3.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	17891	59.5%
Negative hits	8284	27.5%
Do not exist	3373	11.2%
Cache misses	526	1.7%
TOTAL: 4	30074	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.038	0.0%	20	1.000	1.900	3.000
article cancel	00:00:00.004	0.0%	20	0.000	0.200	1.000
article cleanup	00:00:00.104	0.0%	3388	0.000	0.031	0.250
article logging	00:00:00.262	0.0%	3401	0.000	0.077	0.286
article parse	00:00:00.599	0.0%	8416	0.000	0.071	0.222
article write	00:00:02.565	0.0%	3013	0.200	0.851	2.000
artlog/artcncl	00:00:00.001	0.0%	20	0.000	0.050	1.000
artlog/artcncl/perl	00:00:00.002	0.0%	20	0.000	0.100	1.000
data move	00:00:00.134	0.0%	30768	0.000	0.004	0.025
hisgrep/artcncl	00:00:00.003	0.0%	20	0.000	0.150	1.000
hisgrep/artcncl/perl	00:00:00.002	0.0%	20	0.000	0.100	1.000
hisgrep/perl	00:00:00.002	0.0%	23	0.000	0.087	1.000
hishave/artcncl	00:00:00.000	0.0%	20	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.397	0.0%	30132	0.000	0.013	0.036
history sync	00:00:00.060	0.0%	417	0.000	0.144	1.000
history write	00:00:00.725	0.0%	3385	0.000	0.214	0.500
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:15.301	99.9%	38181	879.015	2263.306	6740.157
nntp read	00:00:01.215	0.0%	32048	0.000	0.038	0.078
overview write	00:00:02.063	0.0%	3013	0.000	0.685	5.059
perl filter	00:00:20.932	0.0%	3385	3.588	6.184	12.231
python filter	00:00:00.007	0.0%	3385	0.000	0.002	0.083
site send	00:00:01.755	0.0%	6110	0.000	0.287	0.568
TOTAL: 24:01:08.989	24:00:46.171	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.021	0.0%	3135	0.000	0.007	0.200
article prepare	00:00:00.001	0.0%	452	0.000	0.002	0.250
article read	00:00:00.034	0.0%	107	0.000	0.318	8.000
backlog stats	00:00:00.112	0.0%	40742	0.000	0.003	0.023
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.568	0.0%	23892	0.000	0.024	0.091
data write	00:00:01.156	0.0%	21033	0.020	0.055	0.107
idle	23:58:34.745	100.0%	40742	895.557	2118.569	6397.064
status file	00:00:00.465	0.0%	203	0.000	2.291	7.000
TOTAL: 23:59:15.807	23:58:37.102	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	01:03:06.183	87.9%	9671	9.000	391.499	450171.250
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.600	0.0%	19725	0.000	0.030	0.500
TOTAL: 01:11:45.845	01:03:06.783	87.9%	-	-	-	-

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	i2pn.org	2	3110	750	2169	191	24%	23:22:16
2	feed-out.gegeweb.org	5	1938	697	1241	0	35%	23:57:18
3	usenet.pasdenom.info	3	3480	380	3088	12	10%	39:44:04
4	news.glou.org	1	2661	336	2296	29	12%	23:59:58
5	nntp.terraraq.uk	1	2108	250	1831	27	11%	18:52:49
6	news.samoylyk.net	1	1614	210	1397	7	13%	14:45:34
7	gegeweb-out.news.tnetconsulting.net	2	795	41	752	2	5%	08:42:55
8	news.nntp4.net	2	0	0	0	0	0%	00:03:00
9	localhost	25	0	0	0	0	0%	00:00:01
TOTAL: 9		42	15706	2664	12774	268	16%	153:27:55

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	i2pn.org	3.2 MB	0.0 KB	822.5 KB	4.0 MB	79%	4.3 KB
2	feed-out.gegeweb.org	1.9 MB	0.0 KB	0.0 KB	1.9 MB	100%	2.9 KB
3	news.glou.org	1.4 MB	0.0 KB	62.6 KB	1.5 MB	95%	4.1 KB
4	news.samoylyk.net	1.3 MB	0.0 KB	21.1 KB	1.4 MB	98%	6.4 KB
5	nntp.terraraq.uk	1.1 MB	0.0 KB	112.9 KB	1.3 MB	91%	4.7 KB
6	usenet.pasdenom.info	864.4 KB	0.0 KB	16.5 KB	880.9 KB	98%	2.2 KB
7	gegeweb-out.news.tnetconsulting.net	290.3 KB	8.2 KB	10.6 KB	309.1 KB	93%	7.2 KB
8	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		10.1 MB	8.2 KB	1.0 MB	11.2 MB	90%	3.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 24 04:15:01 - 04:59:59	57	1.9%	0.02	218.9 KB	1.7%	0.08
May 24 05:00:00 - 05:59:59	54	1.8%	0.01	308.3 KB	2.4%	0.09
May 24 06:00:00 - 06:59:59	81	2.7%	0.02	291.6 KB	2.3%	0.08
May 24 07:00:00 - 07:59:59	68	2.3%	0.02	363.1 KB	2.8%	0.10
May 24 08:00:00 - 08:59:59	71	2.4%	0.02	499.6 KB	3.9%	0.14
May 24 09:00:00 - 09:59:59	109	3.6%	0.03	344.2 KB	2.7%	0.10
May 24 10:00:00 - 10:59:59	145	4.8%	0.04	529.2 KB	4.1%	0.15
May 24 11:00:00 - 11:59:59	119	4.0%	0.03	535.9 KB	4.1%	0.15
May 24 12:00:00 - 12:59:59	119	4.0%	0.03	430.9 KB	3.3%	0.12
May 24 13:00:00 - 13:59:59	143	4.8%	0.04	702.8 KB	5.4%	0.20
May 24 14:00:00 - 14:59:59	116	3.9%	0.03	470.1 KB	3.6%	0.13
May 24 15:00:00 - 15:59:59	110	3.7%	0.03	584.6 KB	4.5%	0.16
May 24 16:00:00 - 16:59:59	198	6.6%	0.06	646.6 KB	5.0%	0.18
May 24 17:00:00 - 17:59:59	183	6.1%	0.05	668.2 KB	5.2%	0.19
May 24 18:00:00 - 18:59:59	195	6.5%	0.05	623.4 KB	4.8%	0.17
May 24 19:00:00 - 19:59:59	175	5.8%	0.05	728.5 KB	5.6%	0.20
May 24 20:00:00 - 20:59:59	169	5.6%	0.05	792.1 KB	6.1%	0.22
May 24 21:00:00 - 21:59:59	178	5.9%	0.05	747.4 KB	5.8%	0.21
May 24 22:00:00 - 22:59:59	140	4.7%	0.04	588.5 KB	4.5%	0.16
May 24 23:00:00 - 23:59:59	166	5.5%	0.05	879.4 KB	6.8%	0.24
May 25 00:00:00 - 00:59:59	98	3.3%	0.03	690.8 KB	5.3%	0.19
May 25 01:00:00 - 01:59:59	77	2.6%	0.02	561.1 KB	4.3%	0.16
May 25 02:00:00 - 02:59:59	70	2.3%	0.02	213.6 KB	1.7%	0.06
May 25 03:00:00 - 03:59:59	139	4.6%	0.04	463.8 KB	3.6%	0.13
May 25 04:00:00 - 04:15:02	25	0.8%	0.03	62.3 KB	0.5%	0.07
TOTAL: 24:00:01	3005	100.0%	0.03	12.6 MB	100.0%	0.15

Sites sending bad articles:

Server		Total	Duplic	Other
1	i2pn.org	195	0	195
2	news.nntp4.net	78	0	78
3	nntp.terraraq.uk	40	1	39
4	news.glou.org	29	0	29
5	news.samoylyk.net	19	0	19
6	usenet.pasdenom.info	14	0	14
7	gegeweb-out.news.tnetconsulting.net	11	2	9
8	feed-out.gegeweb.org	2	0	2
TOTAL: 8		388	3	385

INND Perl filter [Top 20]:

Reason	Count
EMP (phn nph)	75
EMP (phl)	69
EMP (md5)	69
Bad path (news.giganews.com.POSTED)	61
Too many newsgroups	37
EMP (phn path)	29
EMP (phr nph)	18
Too many newsgroups (meow)	6
Cancels abusifs	3
HTML Multipart	2
Cancel of non-existing ID <mn.c3ed7e7501524461.149422@po.co>	1
Cancel of <1762145dd25698d7$97492$1044709$a92e2ab9@news.vipernews.com> without Cancel-Key	1
Cancel of <17621f1ad0aaa6d1$97497$1044709$a92e2ab9@news.vipernews.com> without Cancel-Key	1
TOTAL: 13	372

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news.samoylyk.net	9
	i2pn.org	2
	feed-out.gegeweb.org	2
	TOTAL: 3	13
TOTAL: 1		13

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	3518	31	2191	0	0	0%	23:58:31
2	news.gegeweb.org	2521	24	2242	41	0	0%	23:59:40
3	news.tnetconsulting.net	4538	13	2989	0	0	0%	23:59:40
4	news.pasdenom.info	2638	1	2582	1	0	0%	23:58:56
5	nntp.terraraq.uk	1906	1	1821	1	0	0%	23:59:40
6	news.nntp4.net	3011	0	2938	0	130	0%	24:00:01
7	news.samoylyk.net	2906	0	2625	0	0	0%	23:59:40
8	news.aioe.org	0	0	0	0	2342	0%	23:59:40
TOTAL: 8		21038	70	17388	43	2472	0%	191:55:48

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	89.0 KB	0.0 KB	89.0 KB	2.9 KB	23:58:31
2	news.gegeweb.org	78.4 KB	134.0 KB	212.4 KB	3.3 KB	23:59:40
3	news.tnetconsulting.net	40.0 KB	0.0 KB	40.0 KB	3.1 KB	23:59:40
4	news.pasdenom.info	4.0 KB	1.7 KB	5.7 KB	2.8 KB	23:58:56
5	nntp.terraraq.uk	2.1 KB	3.3 KB	5.4 KB	2.7 KB	23:59:40
6	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0	24:00:01
7	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0	23:59:40
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:40
TOTAL: 8		213.6 KB	139.1 KB	352.6 KB	3.1 KB	191:55:48

NNRP readership statistics:

System		Conn	Elapsed
1	mail.gegeweb.eu	288	00:00:30
2	45.227.254.49	1	00:00:00
3	prod-jerry-se-scanners-lon1-62.do.binaryedge.ninja	1	00:00:02
4	172-104-137-47.ip.linodeusercontent.com	94	00:00:27
5	88.214.25.4	1	00:00:00
6	2a02-8428-0e98-6001-0b95-6e6a-230c-89bf.rev.sfr.net	2	01:00:02
7	94.102.61.39	1	00:00:00
8	2001:41d0:a:280::1	183	00:06:08
9	news.nntp4.net	2525	00:04:26
10	thorough.monitoring.internet-measurement.com	1	00:00:02
TOTAL: 10		3097	01:11:41

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.ip.linodeusercontent.com	94	00:00:27
2	unresolved	3	00:00:01
3	*.do.binaryedge.ninja	1	00:00:02
4	*.monitoring.internet-measurement.com	1	00:00:02
5	*.rev.sfr.net	2	01:00:02
6	*.nntp4.net	2525	00:04:26
7	*.gegeweb.eu	288	00:00:30
8	?	183	00:06:08
TOTAL: 8		3097	01:11:41

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
2a02-8428-0e98-6001-0b95-6e6a-230c-89bf.rev.sfr.net	0.063	0.085	0.000	01:00:02
2001:41d0:a:280::1	4.451	4.954	0.000	00:06:08
news.nntp4.net	62.775	64.819	0.000	00:04:26
mail.gegeweb.eu	4.503	4.729	1.891	00:00:30
172-104-137-47.ip.linodeusercontent.com	2.421	1.539	0.000	00:00:27
thorough.monitoring.internet-measurement.com	0.036	0.018	0.000	00:00:02
prod-jerry-se-scanners-lon1-62.do.binaryedge.ninja	0.027	0.027	0.000	00:00:02
94.102.61.39	0.025	0.033	0.000	00:00:00
88.214.25.4	0.021	0.032	0.000	00:00:00
45.227.254.49	0.034	0.027	0.000	00:00:00
TOTAL: 10	74.356	76.263	1.891	01:11:41

NNRP unrecognized commands (by host):

System	Conn
88.214.25.4	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
#003	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
2a02-8428-0e98-6001-0b95-6e6a-230c-89bf.rev.sfr.net	2	0
88.214.25.4	1	1
TOTAL: 2	3	1

Daily Usenet report for news.niel.me

May 24 04:15:01 -- May 25 04:15:02