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

Unknown entries from news log file:

First 50 / 111 lines (45.0%)

Aug 1 08:15:16 rpi-studio nnrpd[13314]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:15:31 rpi-studio nnrpd[13320]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:15:46 rpi-studio nnrpd[13323]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:16:01 rpi-studio nnrpd[13328]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:16:16 rpi-studio nnrpd[13333]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:16:31 rpi-studio nnrpd[13338]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:16:46 rpi-studio nnrpd[13341]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:17:01 rpi-studio nnrpd[13354]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:17:16 rpi-studio nnrpd[13369]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:17:31 rpi-studio nnrpd[13515]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:17:46 rpi-studio nnrpd[13519]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:18:01 rpi-studio nnrpd[13524]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:18:16 rpi-studio nnrpd[13540]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:18:31 rpi-studio nnrpd[13545]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:18:46 rpi-studio nnrpd[13548]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:19:01 rpi-studio nnrpd[13553]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:19:16 rpi-studio nnrpd[13559]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:19:31 rpi-studio nnrpd[13564]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:19:46 rpi-studio nnrpd[13567]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:20:01 rpi-studio nnrpd[13574]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:20:16 rpi-studio nnrpd[13579]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:20:31 rpi-studio nnrpd[13585]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:20:46 rpi-studio nnrpd[13588]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:21:01 rpi-studio nnrpd[13593]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:21:16 rpi-studio nnrpd[13598]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:21:31 rpi-studio nnrpd[13603]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:21:46 rpi-studio nnrpd[13606]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:22:01 rpi-studio nnrpd[13611]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:22:16 rpi-studio nnrpd[13618]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:22:31 rpi-studio nnrpd[13625]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:22:46 rpi-studio nnrpd[13628]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:23:01 rpi-studio nnrpd[13634]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:23:16 rpi-studio nnrpd[13640]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:23:31 rpi-studio nnrpd[13645]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:23:46 rpi-studio nnrpd[13649]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:24:01 rpi-studio nnrpd[13654]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:24:16 rpi-studio nnrpd[13662]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:24:31 rpi-studio nnrpd[13672]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:24:46 rpi-studio nnrpd[13675]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:25:01 rpi-studio nnrpd[13682]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:25:16 rpi-studio nnrpd[13687]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:25:31 rpi-studio nnrpd[13692]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:25:46 rpi-studio nnrpd[13696]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:26:01 rpi-studio nnrpd[13701]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:26:17 rpi-studio nnrpd[13706]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:26:32 rpi-studio nnrpd[13711]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:26:47 rpi-studio nnrpd[13714]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:27:02 rpi-studio nnrpd[13721]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:27:18 rpi-studio nnrpd[13724]: 2a0e:97c0:370::1 noperm ihave without permission
Aug 1 08:27:33 rpi-studio nnrpd[13870]: 2a0e:97c0:370::1 noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	4784	41.7%	926.9 KB	49.4%
nnrpd	2684	23.4%	299.5 KB	16.0%
innd	2296	20.0%	373.0 KB	19.9%
innfeed	1713	14.9%	275.2 KB	14.7%
nocem	1	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.0 KB	0.0%
TOTAL: 6	11479	100.0%	1.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	23284	60.6%
Negative hits	10350	26.9%
Do not exist	4721	12.3%
Cache misses	55	0.1%
TOTAL: 4	38410	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.014	0.0%	14	0.000	1.000	2.000
article cancel	00:00:00.002	0.0%	14	0.000	0.143	1.000
article cleanup	00:00:00.087	0.0%	4737	0.000	0.018	0.103
article logging	00:00:00.360	0.0%	4748	0.000	0.076	0.250
article parse	00:00:00.785	0.0%	16498	0.000	0.048	0.169
article write	00:00:01.977	0.0%	4001	0.250	0.494	1.050
artlog/artcncl	00:00:00.000	0.0%	14	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	14	0.000	0.000	0.000
data move	00:00:00.281	0.0%	45056	0.000	0.006	0.022
hisgrep/artcncl	00:00:00.001	0.0%	14	0.000	0.071	0.500
hisgrep/artcncl/perl	00:00:00.000	0.0%	14	0.000	0.000	0.000
hisgrep/perl	00:00:00.002	0.0%	14	0.000	0.143	1.000
hishave/artcncl	00:00:00.000	0.0%	14	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	14	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.594	0.0%	38382	0.000	0.015	0.039
history sync	00:00:00.032	0.0%	353	0.000	0.091	1.000
history write	00:00:00.791	0.0%	4721	0.000	0.168	0.632
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:24.667	100.0%	47007	434.537	1838.549	600078.000
nntp read	00:00:01.280	0.0%	46196	0.000	0.028	0.062
overview write	00:00:01.110	0.0%	4001	0.000	0.277	1.143
perl filter	00:00:16.950	0.0%	4499	2.222	3.768	8.708
python filter	00:00:00.018	0.0%	4499	0.000	0.004	0.056
site send	00:00:01.548	0.0%	8114	0.000	0.191	0.475
TOTAL: 24:01:01.858	24:00:50.499	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.051	0.0%	6940	0.000	0.007	0.154
article prepare	00:00:00.006	0.0%	1460	0.000	0.004	0.250
article read	00:00:00.044	0.0%	304	0.000	0.145	1.500
backlog stats	00:00:00.286	0.0%	55716	0.000	0.005	0.095
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.804	0.0%	34786	0.000	0.023	0.049
data write	00:00:01.426	0.0%	27202	0.023	0.052	0.096
idle	23:32:03.699	98.5%	55716	411.829	1520.635	25018.750
status file	00:00:00.586	0.0%	261	1.000	2.245	12.000
TOTAL: 23:53:03.847	23:32:06.902	98.5%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:49.767	32.1%	1555	10.600	32.005	10029.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.123	0.1%	3442	0.000	0.036	4.000
TOTAL: 00:02:34.823	00:00:49.890	32.2%	-	-	-	-

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	2	4719	855	3755	109	18%	26:52:49
2	i2pn.org	2	4072	823	3009	240	20%	20:42:03
3	gegeweb-out.news.tnetconsulting.net	3	3300	751	2520	29	22%	17:26:56
4	feed-out.gegeweb.org	3	2169	608	1553	8	28%	20:06:42
5	news.nntp4.net	2	3905	526	3280	99	13%	18:37:09
6	nntp.terraraq.uk	2	4317	344	3946	27	7%	25:04:12
7	usenet.pasdenom.info	1	2099	186	1905	8	8%	22:54:54
8	news.samoylyk.net	2	7721	102	7380	239	1%	42:15:58
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		42	32302	4195	27348	759	12%	194:00:43

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.glou.org	6.1 MB	0.0 KB	351.4 KB	6.5 MB	94%	6.9 KB
2	gegeweb-out.news.tnetconsulting.net	5.2 MB	98.4 KB	228.9 KB	5.6 MB	94%	7.3 KB
3	feed-out.gegeweb.org	2.9 MB	0.0 KB	9.7 KB	3.0 MB	99%	4.9 KB
4	i2pn.org	2.8 MB	73.0 KB	1.0 MB	3.9 MB	71%	3.8 KB
5	news.nntp4.net	2.6 MB	0.0 KB	1020.1 KB	3.6 MB	72%	5.9 KB
6	nntp.terraraq.uk	1.7 MB	0.0 KB	398.7 KB	2.1 MB	81%	5.7 KB
7	news.samoylyk.net	1.0 MB	129.0 KB	909.7 KB	2.0 MB	50%	6.1 KB
8	usenet.pasdenom.info	643.0 KB	0.0 KB	10.1 KB	653.1 KB	98%	3.4 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		23.1 MB	300.4 KB	3.9 MB	27.2 MB	84%	5.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 1 08:15:01 - 08:59:59	495	12.4%	0.18	2.7 MB	12.1%	1.03
Aug 1 09:00:00 - 09:59:59	115	2.9%	0.03	774.0 KB	3.4%	0.22
Aug 1 10:00:00 - 10:59:59	126	3.1%	0.04	1.1 MB	4.8%	0.31
Aug 1 11:00:00 - 11:59:59	159	4.0%	0.04	675.6 KB	2.9%	0.19
Aug 1 12:00:00 - 12:59:59	203	5.1%	0.06	967.3 KB	4.2%	0.27
Aug 1 13:00:00 - 13:59:59	150	3.7%	0.04	433.0 KB	1.9%	0.12
Aug 1 14:00:00 - 14:59:59	203	5.1%	0.06	830.0 KB	3.6%	0.23
Aug 1 15:00:00 - 15:59:59	172	4.3%	0.05	1.4 MB	6.3%	0.40
Aug 1 16:00:00 - 16:59:59	166	4.1%	0.05	969.6 KB	4.2%	0.27
Aug 1 17:00:00 - 17:59:59	197	4.9%	0.05	834.6 KB	3.6%	0.23
Aug 1 18:00:00 - 18:59:59	235	5.9%	0.07	1.3 MB	5.7%	0.36
Aug 1 19:00:00 - 19:59:59	213	5.3%	0.06	1.2 MB	5.2%	0.33
Aug 1 20:00:00 - 20:59:59	210	5.2%	0.06	1.2 MB	5.3%	0.34
Aug 1 21:00:00 - 21:59:59	246	6.1%	0.07	1.8 MB	8.0%	0.51
Aug 1 22:00:00 - 22:59:59	183	4.6%	0.05	754.9 KB	3.3%	0.21
Aug 1 23:00:00 - 23:59:59	228	5.7%	0.06	1.4 MB	6.2%	0.39
Aug 2 00:00:00 - 00:59:59	221	5.5%	0.06	1.9 MB	8.7%	0.55
Aug 2 01:00:00 - 01:59:59	150	3.7%	0.04	839.2 KB	3.6%	0.23
Aug 2 02:00:00 - 02:59:59	124	3.1%	0.03	711.2 KB	3.1%	0.20
Aug 2 03:00:00 - 03:59:59	198	4.9%	0.06	896.6 KB	3.9%	0.25
Aug 2 04:00:00 - 04:15:02	14	0.3%	0.02	41.0 KB	0.2%	0.05
TOTAL: 20:00:01	4008	100.0%	0.06	22.5 MB	100.0%	0.32

Sites sending bad articles:

Server		Total	Group	Duplic	TooOld	Other
1	news.samoylyk.net	226	0	2	222	2
2	i2pn.org	222	0	5	0	217
3	news.nntp4.net	136	0	0	0	136
4	news.glou.org	95	0	0	0	95
5	gegeweb-out.news.tnetconsulting.net	31	1	9	0	21
6	nntp.terraraq.uk	24	0	0	0	24
7	feed-out.gegeweb.org	14	0	0	0	14
TOTAL: 7		748	1	16	222	509

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.petites-annonces.informatique.materiel	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
Bad path (news.giganews.com.POSTED)	139
EMP (md5)	130
EMP (phn nph)	102
EMP (phn path)	54
Too many newsgroups	30
EMP (phr nph)	28
EMP (phl)	14
Cancel for rejected article	1
TOTAL: 8	498

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	feed-out.gegeweb.org	6
	i2pn.org	2
	news.samoylyk.net	2
	news.nntp4.net	1
	TOTAL: 4	11
TOTAL: 1		11

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	nntp.terraraq.uk	3108	186	2617	22	364	5%	24:00:01
2	i2pn.org	4723	53	2982	3	458	1%	24:00:01
3	news.nntp4.net	3686	29	3295	0	414	0%	24:00:01
4	news.gegeweb.org	3583	19	3357	5	413	0%	24:00:01
5	news.samoylyk.net	5038	17	3595	7	797	0%	24:00:01
6	news.tnetconsulting.net	5689	16	3980	5	459	0%	24:00:01
7	news.pasdenom.info	3473	3	3379	1	383	0%	24:00:01
8	news.aioe.org	0	0	0	0	3044	0%	24:00:01
TOTAL: 8		29300	323	23205	43	6332	1%	192:00:08

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	nntp.terraraq.uk	820.0 KB	806.0 KB	1.6 MB	7.8 KB	24:00:01
2	news.nntp4.net	525.4 KB	0.0 KB	525.4 KB	18.1 KB	24:00:01
3	i2pn.org	146.1 KB	16.6 KB	162.7 KB	2.9 KB	24:00:01
4	news.gegeweb.org	86.0 KB	26.2 KB	112.1 KB	4.7 KB	24:00:01
5	news.tnetconsulting.net	68.2 KB	130.3 KB	198.5 KB	9.5 KB	24:00:01
6	news.samoylyk.net	53.5 KB	196.0 KB	249.5 KB	10.4 KB	24:00:01
7	news.pasdenom.info	7.5 KB	2.1 KB	9.6 KB	2.4 KB	24:00:01
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:01
TOTAL: 8		1.7 MB	1.1 MB	2.8 MB	7.9 KB	192:00:08

NNRP readership statistics:

System		Conn	Elapsed
1	2a0e:97c0:370::1	111	00:00:27
2	generous.monitoring.internet-measurement.com	1	00:00:00
3	ninja.census.shodan.io	1	00:00:11
4	cheerful.monitoring.internet-measurement.com	1	00:00:00
5	104.156.155.23	85	00:00:20
6	152.89.198.113	2	00:00:01
7	mail.gegeweb.eu	243	00:01:26
8	198.199.102.69	1	00:00:00
9	dependable.monitoring.internet-measurement.com	1	00:00:00
10	sweet.monitoring.internet-measurement.com	1	00:00:05
TOTAL: 10		447	00:02:34

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.monitoring.internet-measurement.com	4	00:00:06
2	*.census.shodan.io	1	00:00:11
3	unresolved	88	00:00:22
4	*.gegeweb.eu	243	00:01:26
5	?	111	00:00:27
TOTAL: 5		447	00:02:34

NNRP auth users [Top 20]:

User	Conn
mailman	243
TOTAL: 1	243

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mail.gegeweb.eu	3.885	3.182	1.312	00:01:26
2a0e:97c0:370::1	2.469	1.667	0.000	00:00:27
104.156.155.23	2.302	0.973	0.000	00:00:20
ninja.census.shodan.io	0.015	0.007	0.000	00:00:11
sweet.monitoring.internet-measurement.com	0.069	0.017	0.000	00:00:05
152.89.198.113	0.059	0.036	0.000	00:00:01
198.199.102.69	0.034	0.017	0.000	00:00:00
generous.monitoring.internet-measurement.com	0.048	0.009	0.000	00:00:00
cheerful.monitoring.internet-measurement.com	0.037	0.026	0.000	00:00:00
dependable.monitoring.internet-measurement.com	0.033	0.029	0.000	00:00:00
TOTAL: 10	8.951	5.963	1.312	00:02:34

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
152.89.198.113	2
198.199.102.69	1
TOTAL: 2	3

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
152.89.198.113	2	2
TOTAL: 1	2	2

Daily Usenet report for news.niel.me

Aug 1 04:15:01 -- Aug 2 04:15:02