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

Unknown entries from news log file:

First 50 / 181 lines (27.6%)

Jun 18 19:38:41 rpi-studio innd: filter: No Cancel-Key[sha1:4Dmz/ocS12PhZo+rcR07esXH2dc= sha256:31M9nHejUFFNUBaHmE4Fqu3APUKZrEzUlgdC4yHfR40=] matches Cancel-Lock[sha1:Xhc7nNf8mIs4EPDlUMvelquPch4= sha256:QShUtHqFfIqR+yR+KkJRxanH6a4m1+Fj3jeaPK/pF3o=] target=<4bm35k18lfojrjhpjkmlru8cmqkjimrgau@news.usenet.ovh>
Jun 18 22:00:04 rpi-studio pgpverify[13387]: pgpverify: verification failed
Jun 18 22:00:10 rpi-studio pgpverify[13395]: pgpverify: verification failed
Jun 18 22:01:11 rpi-studio pgpverify[13425]: pgpverify: verification failed
Jun 18 22:01:13 rpi-studio pgpverify[13433]: pgpverify: verification failed
Jun 18 22:02:14 rpi-studio pgpverify[13457]: pgpverify: verification failed
Jun 18 22:02:15 rpi-studio pgpverify[13465]: pgpverify: verification failed
Jun 18 22:03:16 rpi-studio pgpverify[13498]: pgpverify: verification failed
Jun 18 22:03:17 rpi-studio pgpverify[13506]: pgpverify: verification failed
Jun 18 22:04:18 rpi-studio pgpverify[13542]: pgpverify: verification failed
Jun 18 22:04:20 rpi-studio pgpverify[13552]: pgpverify: verification failed
Jun 18 22:05:21 rpi-studio pgpverify[13581]: pgpverify: verification failed
Jun 18 22:05:22 rpi-studio pgpverify[13589]: pgpverify: verification failed
Jun 18 22:06:23 rpi-studio pgpverify[13618]: pgpverify: verification failed
Jun 18 22:06:24 rpi-studio pgpverify[13626]: pgpverify: verification failed
Jun 18 22:07:25 rpi-studio pgpverify[13671]: pgpverify: verification failed
Jun 18 22:07:26 rpi-studio pgpverify[13679]: pgpverify: verification failed
Jun 18 22:08:27 rpi-studio pgpverify[13703]: pgpverify: verification failed
Jun 18 22:08:28 rpi-studio pgpverify[13712]: pgpverify: verification failed
Jun 18 22:09:29 rpi-studio pgpverify[13886]: pgpverify: verification failed
Jun 18 22:09:30 rpi-studio pgpverify[13896]: pgpverify: verification failed
Jun 18 22:10:31 rpi-studio pgpverify[13927]: pgpverify: verification failed
Jun 18 22:10:33 rpi-studio pgpverify[13935]: pgpverify: verification failed
Jun 18 22:11:33 rpi-studio pgpverify[13974]: pgpverify: verification failed
Jun 18 22:11:35 rpi-studio pgpverify[13982]: pgpverify: verification failed
Jun 18 22:12:35 rpi-studio pgpverify[14018]: pgpverify: verification failed
Jun 18 22:12:37 rpi-studio pgpverify[14026]: pgpverify: verification failed
Jun 18 22:13:37 rpi-studio pgpverify[14052]: pgpverify: verification failed
Jun 18 22:13:39 rpi-studio pgpverify[14060]: pgpverify: verification failed
Jun 18 22:14:39 rpi-studio pgpverify[14090]: pgpverify: verification failed
Jun 18 22:14:41 rpi-studio pgpverify[14100]: pgpverify: verification failed
Jun 18 22:15:42 rpi-studio pgpverify[14131]: pgpverify: verification failed
Jun 18 22:15:43 rpi-studio pgpverify[14139]: pgpverify: verification failed
Jun 18 22:16:44 rpi-studio pgpverify[14168]: pgpverify: verification failed
Jun 18 22:16:46 rpi-studio pgpverify[14176]: pgpverify: verification failed
Jun 18 22:17:46 rpi-studio pgpverify[14210]: pgpverify: verification failed
Jun 18 22:17:48 rpi-studio pgpverify[14218]: pgpverify: verification failed
Jun 18 22:18:48 rpi-studio pgpverify[14254]: pgpverify: verification failed
Jun 18 22:18:50 rpi-studio pgpverify[14262]: pgpverify: verification failed
Jun 18 22:19:50 rpi-studio pgpverify[14445]: pgpverify: verification failed
Jun 18 22:19:52 rpi-studio pgpverify[14453]: pgpverify: verification failed
Jun 18 22:20:52 rpi-studio pgpverify[14483]: pgpverify: verification failed
Jun 18 22:20:54 rpi-studio pgpverify[14491]: pgpverify: verification failed
Jun 18 22:21:54 rpi-studio pgpverify[14525]: pgpverify: verification failed
Jun 18 22:21:56 rpi-studio pgpverify[14533]: pgpverify: verification failed
Jun 18 22:22:57 rpi-studio pgpverify[14558]: pgpverify: verification failed
Jun 18 22:22:58 rpi-studio pgpverify[14566]: pgpverify: verification failed
Jun 18 22:23:59 rpi-studio pgpverify[14644]: pgpverify: verification failed
Jun 18 22:24:01 rpi-studio pgpverify[14656]: pgpverify: verification failed
Jun 18 22:25:02 rpi-studio pgpverify[14682]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2845	29.8%	495.7 KB	33.9%
innd	2642	27.7%	444.3 KB	30.4%
nnrpd	1934	20.3%	210.4 KB	14.4%
innfeed	1762	18.5%	272.7 KB	18.7%
controlchan	181	1.9%	13.7 KB	0.9%
pgpverify	180	1.9%	24.7 KB	1.7%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 7	9545	100.0%	1.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	12286	56.6%
Negative hits	6597	30.4%
Do not exist	2823	13.0%
Cache misses	4	0.0%
TOTAL: 4	21710	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.008	0.0%	6	1.000	1.333	2.000
article cancel	00:00:00.001	0.0%	7	0.000	0.143	1.000
article cleanup	00:00:00.059	0.0%	2825	0.000	0.021	0.250
article logging	00:00:00.218	0.0%	2826	0.000	0.077	0.375
article parse	00:00:00.458	0.0%	6890	0.000	0.066	0.200
article write	00:00:01.045	0.0%	2077	0.000	0.503	2.333
artlog/artcncl	00:00:00.000	0.0%	7	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.001	0.0%	6	0.000	0.167	1.000
data move	00:00:00.184	0.0%	25587	0.000	0.007	0.056
hisgrep/artcncl	00:00:00.000	0.0%	7	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	6	0.000	0.000	0.000
hisgrep/perl	00:00:00.001	0.0%	7	0.000	0.143	1.000
hishave/artcncl	00:00:00.000	0.0%	7	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	6	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.331	0.0%	21723	0.000	0.015	0.058
history sync	00:00:00.065	0.0%	401	0.000	0.162	1.000
history write	00:00:00.578	0.0%	2825	0.000	0.205	0.733
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:45.894	100.0%	26646	1053.325	3244.235	12781.532
nntp read	00:00:00.722	0.0%	25831	0.000	0.028	0.136
overview write	00:00:00.648	0.0%	2077	0.000	0.312	3.000
perl filter	00:00:15.080	0.0%	2825	1.688	5.338	19.833
python filter	00:00:00.012	0.0%	2825	0.000	0.004	0.100
site send	00:00:00.659	0.0%	4399	0.000	0.150	0.438
TOTAL: 24:01:16.042	24:01:05.964	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.012	0.0%	2097	0.000	0.006	0.125
article prepare	00:00:00.005	0.0%	764	0.000	0.007	0.125
article read	00:00:00.023	0.0%	153	0.000	0.150	3.000
backlog stats	00:00:00.170	0.0%	30733	0.000	0.006	0.030
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.477	0.0%	16493	0.000	0.029	0.121
data write	00:00:00.775	0.0%	14525	0.000	0.053	0.120
idle	23:59:06.324	100.0%	30733	849.069	2809.564	8954.612
status file	00:00:06.918	0.0%	218	0.000	31.734	92.000
TOTAL: 23:59:17.959	23:59:14.704	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:04:16.754	51.2%	1461	9.200	175.739	95561.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.138	0.0%	3303	0.000	0.042	0.500
TOTAL: 00:08:21.483	00:04:16.892	51.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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
group-admin@isc.org	0	0	0	180	0	0
TOTAL	0	0	0	180	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news.nntp4.net	1	2793	977	1132	684	34%	24:03:14
2	feed-out.gegeweb.org	2	1931	524	1407	0	27%	22:47:39
3	news.glou.org	91	3105	199	2830	76	6%	45:26:20
4	news.samoylyk.net	2	2018	165	1853	0	8%	23:05:46
5	gegeweb-out.news.tnetconsulting.net	2	1659	99	1557	3	5%	21:54:32
6	nntp.terraraq.uk	7	1136	58	1074	4	5%	17:50:56
7	localhost	25	0	0	0	0	0%	00:00:00
8	i2pn.org	1	113	0	113	0	0%	02:03:19
9	usenet.pasdenom.info	6	1041	0	1041	0	0%	21:11:28
TOTAL: 9		137	13796	2022	11007	767	14%	178:23:14

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	4.0 MB	5.0 MB	8.9 MB	44%	5.5 KB
2	feed-out.gegeweb.org	1.6 MB	0.0 KB	1.6 MB	100%	3.2 KB
3	news.samoylyk.net	1.3 MB	0.0 KB	1.3 MB	100%	8.3 KB
4	news.glou.org	646.9 KB	235.1 KB	882.1 KB	73%	3.2 KB
5	gegeweb-out.news.tnetconsulting.net	448.9 KB	12.0 KB	460.9 KB	97%	4.5 KB
6	nntp.terraraq.uk	164.6 KB	9.6 KB	174.2 KB	94%	2.8 KB
7	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
8	i2pn.org	0.0 KB	0.0 KB	0.0 KB	0%	0
9	usenet.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		8.2 MB	5.2 MB	13.4 MB	60%	4.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 18 04:15:01 - 04:59:59	28	1.3%	0.01	103.0 KB	1.2%	0.04
Jun 18 05:00:00 - 05:59:59	57	2.7%	0.02	185.0 KB	2.2%	0.05
Jun 18 06:00:00 - 06:59:59	48	2.3%	0.01	185.0 KB	2.2%	0.05
Jun 18 07:00:00 - 07:59:59	41	2.0%	0.01	233.8 KB	2.8%	0.06
Jun 18 08:00:00 - 08:59:59	51	2.5%	0.01	150.6 KB	1.8%	0.04
Jun 18 09:00:00 - 09:59:59	51	2.5%	0.01	641.0 KB	7.5%	0.18
Jun 18 10:00:00 - 10:59:59	63	3.0%	0.02	262.3 KB	3.1%	0.07
Jun 18 11:00:00 - 11:59:59	120	5.8%	0.03	378.3 KB	4.5%	0.11
Jun 18 12:00:00 - 12:59:59	128	6.2%	0.04	608.9 KB	7.2%	0.17
Jun 18 13:00:00 - 13:59:59	115	5.5%	0.03	351.8 KB	4.1%	0.10
Jun 18 14:00:00 - 14:59:59	101	4.9%	0.03	333.5 KB	3.9%	0.09
Jun 18 15:00:00 - 15:59:59	69	3.3%	0.02	228.8 KB	2.7%	0.06
Jun 18 16:00:00 - 16:59:59	82	3.9%	0.02	281.2 KB	3.3%	0.08
Jun 18 17:00:00 - 17:59:59	104	5.0%	0.03	338.5 KB	4.0%	0.09
Jun 18 18:00:00 - 18:59:59	98	4.7%	0.03	357.4 KB	4.2%	0.10
Jun 18 19:00:00 - 19:59:59	95	4.6%	0.03	690.7 KB	8.1%	0.19
Jun 18 20:00:00 - 20:59:59	64	3.1%	0.02	337.4 KB	4.0%	0.09
Jun 18 21:00:00 - 21:59:59	118	5.7%	0.03	447.1 KB	5.3%	0.12
Jun 18 22:00:00 - 22:59:59	247	11.9%	0.07	855.9 KB	10.1%	0.24
Jun 18 23:00:00 - 23:59:59	148	7.1%	0.04	533.3 KB	6.3%	0.15
Jun 19 00:00:00 - 00:59:59	62	3.0%	0.02	273.5 KB	3.2%	0.08
Jun 19 01:00:00 - 01:59:59	60	2.9%	0.02	241.8 KB	2.8%	0.07
Jun 19 02:00:00 - 02:59:59	34	1.6%	0.01	126.8 KB	1.5%	0.04
Jun 19 03:00:00 - 03:59:59	84	4.0%	0.02	304.8 KB	3.6%	0.08
Jun 19 04:00:00 - 04:15:02	13	0.6%	0.01	46.6 KB	0.5%	0.05
TOTAL: 24:00:01	2081	100.0%	0.02	8.3 MB	100.0%	0.10

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	685	425	260
2	news.glou.org	54	0	54
3	nntp.terraraq.uk	8	0	8
4	gegeweb-out.news.tnetconsulting.net	3	0	3
5	i2pn.org	1	0	1
TOTAL: 5		751	425	326

Unwanted newsgroups [Top 20]:

Newsgroup	Count
linux.kernel.netdev	378
linux.kernel.git	45
news.test	1
soc.culture.iran	1
TOTAL: 4	425

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	169
Bad path (news.giganews.com.POSTED)	85
Too many newsgroups	70
Cancels abusifs	1
TOTAL: 4	325

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news.nntp4.net	1
	TOTAL: 1	1
TOTAL: 1		1

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.pasdenom.info	1817	61	1637	17	0	3%	23:59:59
2	news.gegeweb.org	1615	11	1524	0	0	0%	23:48:45
3	news.tnetconsulting.net	3035	10	2048	19	6	0%	23:59:58
4	news.samoylyk.net	2437	8	1860	38	2	0%	23:59:58
5	news.nntp4.net	1194	8	1091	0	0	0%	23:49:21
6	i2pn.org	2911	2	1957	10	5	0%	23:59:58
7	news.aioe.org	0	0	0	0	1706	0%	24:00:01
8	nntp.terraraq.uk	1509	0	1393	7	0	0%	23:53:18
TOTAL: 8		14518	100	11510	91	1719	0%	191:31:18

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.pasdenom.info	256.6 KB	25.5 KB	282.1 KB	3.6 KB	23:59:59
2	news.tnetconsulting.net	83.1 KB	46.8 KB	129.9 KB	4.5 KB	23:59:58
3	news.gegeweb.org	26.0 KB	0.0 KB	26.0 KB	2.4 KB	23:48:45
4	news.samoylyk.net	21.6 KB	411.8 KB	433.4 KB	9.4 KB	23:59:58
5	news.nntp4.net	10.0 KB	0.0 KB	10.0 KB	1.3 KB	23:49:21
6	i2pn.org	4.8 KB	15.7 KB	20.4 KB	1.7 KB	23:59:58
7	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:01
8	nntp.terraraq.uk	0.0 KB	9.0 KB	9.0 KB	1.3 KB	23:53:18
TOTAL: 8		402.0 KB	508.7 KB	910.7 KB	4.8 KB	191:31:18

NNRP readership statistics:

System		Conn	Elapsed
1	45.142.193.185	2	00:00:39
2	147.185.132.169	2	00:00:02
3	poignant.monitoring.internet-measurement.com	1	00:00:02
4	198.235.24.16	1	00:00:03
5	205.210.31.7	3	00:03:12
6	splendid.monitoring.internet-measurement.com	1	00:00:00
7	hari.probe.onyphe.net	1	00:00:13
8	206.168.34.78	1	00:00:31
9	185.210.203.35.bc.googleusercontent.com	1	00:00:00
10	oconnor.probe.onyphe.net	1	00:00:05
11	206.168.34.61	1	00:00:32
12	kind.monitoring.internet-measurement.com	1	00:00:03
13	136.149.216.162.bc.googleusercontent.com	1	00:00:00
14	198.235.24.88	1	00:00:03
15	51.159.234.118	1	00:00:01
16	206.168.34.208	1	00:00:31
17	ravishing.monitoring.internet-measurement.com	1	00:00:00
18	laudable.monitoring.internet-measurement.com	1	00:00:00
19	198.235.24.157	3	00:00:10
20	uplifting.monitoring.internet-measurement.com	1	00:00:03
21	206.168.34.85	1	00:00:07
22	mail.gegeweb.eu	288	00:01:53
23	93.149.216.162.bc.googleusercontent.com	2	00:00:03
TOTAL: 23		317	00:08:24

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	17	00:05:56
2	*.149.216.162.bc.googleusercontent.com	3	00:00:04
3	*.probe.onyphe.net	2	00:00:19
4	*.210.203.35.bc.googleusercontent.com	1	00:00:00
5	*.gegeweb.eu	288	00:01:53
6	*.monitoring.internet-measurement.com	6	00:00:09
TOTAL: 6		317	00:08:24

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
205.210.31.7	0.073	0.039	0.000	00:03:12
mail.gegeweb.eu	4.863	4.258	1.852	00:01:53
45.142.193.185	0.064	0.028	0.000	00:00:39
206.168.34.61	0.029	0.025	0.000	00:00:32
206.168.34.208	0.045	0.021	0.000	00:00:31
206.168.34.78	0.040	0.022	0.000	00:00:31
hari.probe.onyphe.net	0.014	0.011	0.000	00:00:13
198.235.24.157	0.061	0.036	0.000	00:00:10
206.168.34.85	0.022	0.009	0.000	00:00:07
oconnor.probe.onyphe.net	0.033	0.013	0.000	00:00:05
198.235.24.16	0.033	0.014	0.000	00:00:03
93.149.216.162.bc.googleusercontent.com	0.177	0.058	0.000	00:00:03
kind.monitoring.internet-measurement.com	0.014	0.018	0.000	00:00:03
198.235.24.88	0.012	0.012	0.000	00:00:03
uplifting.monitoring.internet-measurement.com	0.063	0.009	0.000	00:00:03
poignant.monitoring.internet-measurement.com	0.036	0.012	0.000	00:00:02
147.185.132.169	0.134	0.026	0.000	00:00:02
51.159.234.118	0.104	0.028	0.000	00:00:01
136.149.216.162.bc.googleusercontent.com	0.054	0.025	0.000	00:00:00
185.210.203.35.bc.googleusercontent.com	0.026	0.015	0.000	00:00:00
TOTAL: 21	5.911	4.693	1.852	00:08:24

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
45.142.193.185	7
hari.probe.onyphe.net	6
147.185.132.169	4
185.210.203.35.bc.googleusercontent.com	4
136.149.216.162.bc.googleusercontent.com	4
93.149.216.162.bc.googleusercontent.com	4
oconnor.probe.onyphe.net	3
206.168.34.85	1
205.210.31.7	1
198.235.24.157	1
TOTAL: 10	35

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	5
Host: 45.13.105.36:563	5
Accept: /	3
User-Agent: Expanse, a Palo Alto Network...	2
#026#003	2
User-Agent: curl/7.68.0	2
Accept-Encoding: gzip	2
À+Ì©À#011À0À#024À/Ì¨À#023	1
User-Agent: Mozilla/5.0 (X11; Ubuntu; Li...	1
#026#003#003#001½#001	1
À$ÀÀ¯À,ÀrÀsÌ©#023#002#023#001Ì#024À#007À#022À#023À'À/À#024À(À0À`Àa...	1
EHLO www.censys.io	1
#026#003#001	1
Host: [2a0e:e701:1154:0:4e10:5c89:4af0:d...	1
Connection: close	1
Accept-Encoding: deflate, gzip	1
Accept: text/html,application/xhtml+xml,...	1
Accept-Language: en-US,en;q=0.5	1
?Ã“ #025#033ôÀ¹kè#002Ž(#030#037î>–r¦“†¦oš#003#016ÄJm#020Ø#022	1
#001	1
User-Agent: Mozilla/5.0 (Windows NT 6.1;...	1
TOTAL: 21	35

NNRP client timeouts [Top 20]:

System	Conn	Peer
198.235.24.157	2	3
205.210.31.7	2	3
93.149.216.162.bc.googleusercontent.com	1	2
147.185.132.169	1	2
TOTAL: 4	6	12

Daily Usenet report for news.niel.me

Jun 18 04:15:01 -- Jun 19 04:15:02