Daily Usenet report for news.niel.me: Jul 11 04:15:01

Unknown entries from news log file:

First 50 / 132 lines (37.9%)

Jul 12 00:55:13 rpi-studio nnrpd[32531]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 00:55:28 rpi-studio nnrpd[32537]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 00:55:43 rpi-studio nnrpd[32542]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 00:55:58 rpi-studio nnrpd[32545]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 00:56:13 rpi-studio nnrpd[32553]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 00:56:28 rpi-studio nnrpd[32556]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 00:56:43 rpi-studio nnrpd[32561]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 00:56:58 rpi-studio nnrpd[32564]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 00:57:13 rpi-studio nnrpd[32571]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 00:57:28 rpi-studio nnrpd[32574]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 00:57:43 rpi-studio nnrpd[32579]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 00:57:58 rpi-studio nnrpd[32582]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 00:58:14 rpi-studio nnrpd[32590]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 00:58:30 rpi-studio nnrpd[32593]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 00:58:45 rpi-studio nnrpd[32598]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 00:59:00 rpi-studio nnrpd[32610]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 00:59:16 rpi-studio nnrpd[32620]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 00:59:31 rpi-studio nnrpd[32625]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 00:59:46 rpi-studio nnrpd[301]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:00:02 rpi-studio nnrpd[312]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:00:18 rpi-studio nnrpd[316]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:00:33 rpi-studio nnrpd[321]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:00:48 rpi-studio nnrpd[335]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:01:04 rpi-studio nnrpd[352]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:01:19 rpi-studio nnrpd[356]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:01:34 rpi-studio nnrpd[361]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:01:49 rpi-studio nnrpd[364]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:02:04 rpi-studio nnrpd[380]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:02:20 rpi-studio nnrpd[390]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:02:35 rpi-studio nnrpd[399]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:02:51 rpi-studio nnrpd[405]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:03:06 rpi-studio nnrpd[417]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:03:22 rpi-studio nnrpd[428]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:03:38 rpi-studio nnrpd[431]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:03:53 rpi-studio nnrpd[436]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:04:08 rpi-studio nnrpd[441]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:04:23 rpi-studio nnrpd[446]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:04:38 rpi-studio nnrpd[449]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:04:54 rpi-studio nnrpd[454]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:05:09 rpi-studio nnrpd[469]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:05:24 rpi-studio nnrpd[474]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:05:39 rpi-studio nnrpd[481]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:05:55 rpi-studio nnrpd[488]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:06:10 rpi-studio nnrpd[497]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:06:26 rpi-studio nnrpd[505]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:06:41 rpi-studio nnrpd[511]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:06:57 rpi-studio nnrpd[514]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:07:12 rpi-studio nnrpd[522]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:07:28 rpi-studio nnrpd[529]: 2a0e:97c0:370::1 noperm ihave without permission
Jul 12 01:07:44 rpi-studio nnrpd[534]: 2a0e:97c0:370::1 noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	4425	38.3%	823.3 KB	44.6%
innd	2748	23.8%	436.4 KB	23.6%
nnrpd	2519	21.8%	276.6 KB	15.0%
innfeed	1856	16.1%	310.7 KB	16.8%
nocem	1	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	11550	100.0%	1.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	22297	60.9%
Negative hits	9991	27.3%
Do not exist	4329	11.8%
Cache misses	4	0.0%
TOTAL: 4	36621	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.038	0.0%	40	0.500	0.950	2.000
article cancel	00:00:00.002	0.0%	40	0.000	0.050	1.000
article cleanup	00:00:00.073	0.0%	4341	0.000	0.017	0.167
article logging	00:00:00.344	0.0%	4347	0.000	0.079	0.333
article parse	00:00:00.751	0.0%	10990	0.000	0.068	0.222
article write	00:00:03.188	0.0%	3589	0.231	0.888	2.000
artlog/artcncl	00:00:00.000	0.0%	40	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.001	0.0%	40	0.000	0.025	0.111
data move	00:00:00.144	0.0%	39660	0.000	0.004	0.021
hisgrep/artcncl	00:00:00.001	0.0%	40	0.000	0.025	0.111
hisgrep/artcncl/perl	00:00:00.002	0.0%	40	0.000	0.050	1.000
hisgrep/perl	00:00:00.006	0.0%	52	0.000	0.115	1.000
hishave/artcncl	00:00:00.000	0.0%	40	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.001	0.0%	40	0.000	0.025	0.167
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:00.430	0.0%	36637	0.000	0.012	0.049
history sync	00:00:00.052	0.0%	394	0.000	0.132	1.000
history write	00:00:00.996	0.0%	4340	0.000	0.229	0.581
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:21.591	100.0%	42418	718.796	2037.380	300299.000
nntp read	00:00:01.306	0.0%	41585	0.000	0.031	0.075
overview write	00:00:02.241	0.0%	3589	0.000	0.624	6.471
perl filter	00:00:18.734	0.0%	4340	2.614	4.317	12.333
python filter	00:00:00.010	0.0%	4340	0.000	0.002	0.071
site send	00:00:01.340	0.0%	7342	0.000	0.183	0.565
TOTAL: 24:01:03.149	24:00:51.251	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.040	0.0%	4380	0.000	0.009	0.091
article prepare	00:00:00.001	0.0%	1332	0.000	0.001	0.042
article read	00:00:00.034	0.0%	229	0.000	0.148	4.000
backlog stats	00:00:00.141	0.0%	50791	0.000	0.003	0.021
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.709	0.0%	29348	0.000	0.024	0.065
data write	00:00:01.408	0.0%	26089	0.000	0.054	0.118
idle	23:58:41.901	100.0%	50791	645.396	1699.551	9704.016
status file	00:00:00.582	0.0%	243	1.000	2.395	5.000
TOTAL: 23:58:47.618	23:58:44.816	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:56.525	40.4%	1816	10.600	31.126	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.131	0.1%	3927	0.000	0.033	0.500
TOTAL: 00:02:20.063	00:00:56.656	40.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	feed-out.gegeweb.org	4	2358	1001	1356	1	42%	24:21:23
2	news.glou.org	2	3450	596	2723	131	17%	24:24:26
3	i2pn.org	2	3135	593	2436	106	18%	21:43:13
4	news.nntp4.net	3	4428	401	3638	389	9%	25:51:11
5	gegeweb-out.news.tnetconsulting.net	3	6019	300	5635	84	4%	38:01:10
6	nntp.terraraq.uk	3	2906	256	2638	12	8%	22:22:57
7	usenet.pasdenom.info	2	1836	211	1614	11	11%	21:47:18
8	news.samoylyk.net	2	4660	98	4553	9	2%	28:59:34
9	localhost	25	0	0	0	0	0%	00:00:01
TOTAL: 9		46	28792	3456	24593	743	12%	207:31:13

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.glou.org	3.2 MB	0.0 KB	453.2 KB	3.6 MB	87%	5.1 KB
2	feed-out.gegeweb.org	3.2 MB	0.0 KB	1.1 KB	3.2 MB	99%	3.3 KB
3	news.nntp4.net	2.4 MB	0.0 KB	954.3 KB	3.3 MB	72%	4.3 KB
4	gegeweb-out.news.tnetconsulting.net	2.0 MB	104.5 KB	565.6 KB	2.7 MB	75%	7.2 KB
5	i2pn.org	1.9 MB	0.0 KB	387.1 KB	2.3 MB	83%	3.4 KB
6	usenet.pasdenom.info	1.2 MB	0.0 KB	22.4 KB	1.3 MB	98%	5.8 KB
7	news.samoylyk.net	920.3 KB	0.0 KB	97.8 KB	1018.1 KB	90%	9.5 KB
8	nntp.terraraq.uk	904.2 KB	0.0 KB	47.9 KB	952.1 KB	94%	3.6 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		15.8 MB	104.5 KB	2.5 MB	18.3 MB	85%	4.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 11 04:15:01 - 04:59:59	90	2.5%	0.03	566.6 KB	3.4%	0.21
Jul 11 05:00:00 - 05:59:59	75	2.1%	0.02	191.3 KB	1.2%	0.05
Jul 11 06:00:00 - 06:59:59	117	3.3%	0.03	282.5 KB	1.7%	0.08
Jul 11 07:00:00 - 07:59:59	84	2.3%	0.02	271.7 KB	1.7%	0.08
Jul 11 08:00:00 - 08:59:59	90	2.5%	0.03	504.0 KB	3.1%	0.14
Jul 11 09:00:00 - 09:59:59	97	2.7%	0.03	316.0 KB	1.9%	0.09
Jul 11 10:00:00 - 10:59:59	86	2.4%	0.02	353.5 KB	2.1%	0.10
Jul 11 11:00:00 - 11:59:59	124	3.5%	0.03	395.7 KB	2.4%	0.11
Jul 11 12:00:00 - 12:59:59	125	3.5%	0.03	797.3 KB	4.8%	0.22
Jul 11 13:00:00 - 13:59:59	144	4.0%	0.04	472.8 KB	2.9%	0.13
Jul 11 14:00:00 - 14:59:59	121	3.4%	0.03	451.9 KB	2.7%	0.13
Jul 11 15:00:00 - 15:59:59	139	3.9%	0.04	465.5 KB	2.8%	0.13
Jul 11 16:00:00 - 16:59:59	143	4.0%	0.04	619.9 KB	3.8%	0.17
Jul 11 17:00:00 - 17:59:59	259	7.2%	0.07	882.4 KB	5.4%	0.25
Jul 11 18:00:00 - 18:59:59	243	6.8%	0.07	758.9 KB	4.6%	0.21
Jul 11 19:00:00 - 19:59:59	177	4.9%	0.05	1.6 MB	9.7%	0.44
Jul 11 20:00:00 - 20:59:59	255	7.1%	0.07	1.7 MB	10.8%	0.50
Jul 11 21:00:00 - 21:59:59	184	5.1%	0.05	653.5 KB	4.0%	0.18
Jul 11 22:00:00 - 22:59:59	187	5.2%	0.05	1.2 MB	7.3%	0.34
Jul 11 23:00:00 - 23:59:59	179	5.0%	0.05	828.2 KB	5.0%	0.23
Jul 12 00:00:00 - 00:59:59	158	4.4%	0.04	1.1 MB	6.7%	0.31
Jul 12 01:00:00 - 01:59:59	183	5.1%	0.05	830.7 KB	5.0%	0.23
Jul 12 02:00:00 - 02:59:59	192	5.3%	0.05	646.3 KB	3.9%	0.18
Jul 12 03:00:00 - 03:59:59	111	3.1%	0.03	406.1 KB	2.5%	0.11
Jul 12 04:00:00 - 04:15:02	26	0.7%	0.03	71.3 KB	0.4%	0.08
TOTAL: 24:00:01	3589	100.0%	0.04	16.1 MB	100.0%	0.19

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news.nntp4.net	390	0	0	390
2	i2pn.org	156	0	0	156
3	news.glou.org	131	0	0	131
4	gegeweb-out.news.tnetconsulting.net	37	4	1	32
5	news.samoylyk.net	17	0	0	17
6	nntp.terraraq.uk	13	0	0	13
7	usenet.pasdenom.info	10	0	0	10
8	feed-out.gegeweb.org	2	0	0	2
TOTAL: 8		756	4	1	751

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.petites-annonces.informatique.materiel	2
fr.comp.os.os2	1
fr.comp.os.ms-windows.programmation	1
TOTAL: 3	4

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	217
Bad path (news.giganews.com.POSTED)	145
EMP (phl)	107
EMP (phn nph)	100
EMP (md5)	89
EMP (phn path)	69
Cancels abusifs	6
Cancel of non-existing ID <20b55d5c-2781-4959-b6c7-515ba3bda9fcn@googlegroups.com>	1
Cancel of non-existing ID <918c4457-93b5-4f18-980f-ee94ed3e0fa9n@googlegroups.com>	1
Cancel of non-existing ID <18a297aa-2382-47da-8e7a-0cc5d99dac09n@googlegroups.com>	1
Cancel of non-existing ID <a799422a-7130-495d-b94a-f492aff4037en@googlegroups.com>	1
Cancel of non-existing ID <63a9341d-f9a9-486b-a169-06f4b8aefed0n@googlegroups.com>	1
Cancel of non-existing ID <d3e4ec8c-2d80-4add-b89d-a8e518f790d5n@googlegroups.com>	1
Cancel of <dalton-C58E46.01422611072023@news.eternal-september.org> without Cancel-Key	1
Cancel of non-existing ID <eac54e09-f424-45a1-82c1-c781c5d5952cn@googlegroups.com>	1
Cancel of non-existing ID <80b1eb70-324e-41b4-8356-088f070cb855n@googlegroups.com>	1
Cancel of non-existing ID <ce7d75a4-f0fd-4baa-95e9-e648f695e5c3n@googlegroups.com>	1
Cancel of non-existing ID <e818ef9b-2300-43fa-be15-6e5cbbb697a9n@googlegroups.com>	1
Cancel of non-existing ID <bda76470-3478-4526-be44-1edc1464d8b1n@googlegroups.com>	1
TOTAL: 19	745

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	nntp.terraraq.uk	2531	136	2058	20	68	5%	23:59:58
2	i2pn.org	4645	54	2774	7	140	1%	23:59:58
3	news.tnetconsulting.net	5412	36	3535	9	151	0%	23:59:58
4	news.nntp4.net	3420	30	3107	0	115	0%	23:59:58
5	news.samoylyk.net	4753	18	3461	12	125	0%	23:59:57
6	news.gegeweb.org	2716	7	2576	4	101	0%	23:59:58
7	news.aioe.org	0	0	0	0	2947	0%	23:59:55
8	news.pasdenom.info	3053	0	2940	0	95	0%	23:59:58
TOTAL: 8		26530	281	20451	52	3742	1%	191:59:40

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	nntp.terraraq.uk	983.6 KB	81.6 KB	1.0 MB	6.8 KB	23:59:58
2	news.tnetconsulting.net	226.6 KB	83.8 KB	310.5 KB	6.9 KB	23:59:58
3	news.nntp4.net	225.8 KB	0.0 KB	225.8 KB	7.5 KB	23:59:58
4	i2pn.org	191.0 KB	58.0 KB	249.0 KB	4.1 KB	23:59:58
5	news.samoylyk.net	92.7 KB	71.4 KB	164.2 KB	5.5 KB	23:59:57
6	news.gegeweb.org	19.1 KB	10.5 KB	29.5 KB	2.7 KB	23:59:58
7	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:55
8	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:59:58
TOTAL: 8		1.7 MB	305.3 KB	2.0 MB	6.1 KB	191:59:40

NNRP readership statistics:

System		Conn	Elapsed
1	80.66.88.204	1	00:00:01
2	mail.gegeweb.eu	282	00:01:34
3	2a0e:97c0:370::1	132	00:00:32
4	71.6.134.235	3	00:00:01
5	104.152.52.216	1	00:00:00
6	104.152.52.97	1	00:00:10
TOTAL: 6		420	00:02:19

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	?	132	00:00:32
2	*.gegeweb.eu	282	00:01:34
3	unresolved	6	00:00:13
TOTAL: 3		420	00:02:19

NNRP auth users [Top 20]:

User	Conn
mailman	282
TOTAL: 1	282

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mail.gegeweb.eu	4.184	5.027	1.938	00:01:34
2a0e:97c0:370::1	3.104	3.293	0.000	00:00:32
104.152.52.97	0.008	0.041	0.000	00:00:10
71.6.134.235	0.061	0.067	0.000	00:00:01
80.66.88.204	0.000	0.046	0.000	00:00:01
104.152.52.216	0.032	0.021	0.000	00:00:00
TOTAL: 6	7.389	8.495	1.938	00:02:19

NNRP unrecognized commands (by host):

System	Conn
71.6.134.235	7
80.66.88.204	1
TOTAL: 2	8

NNRP unrecognized commands (by command):

Command	Count
#023#003#023#001#023#002#001	1
#026#003#001	1
Host: 45.13.105.36:119	1
Accept: /	1
GET / HTTP/1.1	1
Accept-Encoding: gzip	1
User-Agent: Mozilla/5.0 (Windows NT 10.0...	1
#003	1
TOTAL: 8	8

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

Jul 11 04:15:01 -- Jul 12 04:15:02