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

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Sep 15 17:00:03 rpi-studio pgpverify[5735]: pgpverify: verification failed
Sep 15 17:00:05 rpi-studio pgpverify[5750]: pgpverify: verification failed
Sep 15 17:58:58 rpi-studio innd: filter: No Cancel-Key[sha1:x7NfdDN3S6y5xeU6TeQa7hzxRgg= sha256:FZnhzFB177ndQYyBYVcD4iCfTe5AcSkmpoe/wNyu2oo=] matches Cancel-Lock[sha1:3mSzCVfhyQOl0OeJjlm2vXM/CTs= sha256:FeUXS7+97NBCpN5snPZbLNNExe5RiIb/dKv3esy5gWg=] target=<1188h5a$1dovk$1@dont-email.me>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2910	31.5%	496.4 KB	35.3%
nnrpd	2462	26.7%	291.2 KB	20.7%
innd	1990	21.6%	341.0 KB	24.3%
innfeed	1855	20.1%	274.2 KB	19.5%
controlchan	13	0.1%	2.3 KB	0.2%
pgpverify	2	0.0%	0.5 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 7	9233	100.0%	1.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	8900	48.7%
Negative hits	6485	35.5%
Do not exist	2891	15.8%
Cache misses	8	0.0%
TOTAL: 4	18284	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.006	0.0%	5	0.500	1.200	2.000
article cancel	00:00:00.001	0.0%	6	0.000	0.167	1.000
article cleanup	00:00:00.057	0.0%	2902	0.000	0.020	0.143
article logging	00:00:00.246	0.0%	2902	0.000	0.085	0.333
article parse	00:00:00.563	0.0%	8020	0.000	0.070	0.286
article write	00:00:01.078	0.0%	2119	0.000	0.509	1.500
artlog/artcncl	00:00:00.000	0.0%	6	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	5	0.000	0.000	0.000
data move	00:00:00.279	0.0%	21996	0.000	0.013	0.067
hisgrep/artcncl	00:00:00.000	0.0%	6	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	5	0.000	0.000	0.000
hisgrep/perl	00:00:00.000	0.0%	6	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	6	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	5	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.321	0.0%	18330	0.000	0.018	0.059
history sync	00:00:00.053	0.0%	344	0.000	0.154	1.000
history write	00:00:00.574	0.0%	2902	0.000	0.198	0.759
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:24.839	100.0%	23051	1074.982	3749.288	23113.500
nntp read	00:00:00.697	0.0%	22288	0.000	0.031	0.116
overview write	00:00:00.695	0.0%	2119	0.000	0.328	2.500
perl filter	00:00:15.049	0.0%	2902	1.381	5.186	12.714
python filter	00:00:00.016	0.0%	2902	0.000	0.006	0.091
site send	00:00:00.905	0.0%	4307	0.000	0.210	0.667
TOTAL: 24:01:04.211	24:00:45.379	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.015	0.0%	2390	0.000	0.006	0.143
article prepare	00:00:00.003	0.0%	688	0.000	0.004	0.250
article read	00:00:00.038	0.0%	171	0.000	0.222	9.000
backlog stats	00:00:00.151	0.0%	26035	0.000	0.006	0.028
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.346	0.0%	12925	0.000	0.027	0.102
data write	00:00:00.685	0.0%	11311	0.000	0.061	0.189
idle	23:58:03.422	100.0%	26035	1000.587	3314.132	8823.817
status file	00:00:06.717	0.0%	270	1.000	24.878	78.000
TOTAL: 23:58:19.539	23:58:11.377	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:43.644	40.5%	1471	10.800	29.670	10020.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.122	0.1%	3490	0.000	0.035	1.000
TOTAL: 00:01:47.837	00:00:43.766	40.6%	-	-	-	-

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	2	2	0	2
TOTAL	0	0	2	2	0	2

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	feed-out.gegeweb.org	2	2050	873	1177	0	42%	23:58:37
2	news.nntp4.net	1	2932	642	1523	767	21%	24:24:09
3	news.glou.org	1	2296	327	1913	56	14%	37:13:46
4	news.samoylyk.net	2	1545	198	1347	0	12%	18:17:14
5	nntp.terraraq.uk	2	1398	96	1300	2	6%	21:21:11
6	gegeweb-out.news.tnetconsulting.net	1	1464	26	1438	0	1%	23:59:32
7	usenet.pasdenom.info	18	864	13	851	0	1%	27:22:17
8	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 8		52	12549	2175	9549	825	17%	176:36:46

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	3.1 MB	7.5 MB	10.7 MB	29%	7.8 KB
2	feed-out.gegeweb.org	3.1 MB	0.0 KB	3.1 MB	100%	3.6 KB
3	news.samoylyk.net	1.3 MB	0.0 KB	1.3 MB	100%	6.6 KB
4	news.glou.org	1.0 MB	180.3 KB	1.2 MB	85%	3.2 KB
5	nntp.terraraq.uk	397.0 KB	6.9 KB	403.9 KB	98%	4.1 KB
6	gegeweb-out.news.tnetconsulting.net	61.2 KB	0.0 KB	61.2 KB	100%	2.4 KB
7	usenet.pasdenom.info	27.9 KB	0.0 KB	27.9 KB	100%	2.1 KB
8	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 8		9.0 MB	7.7 MB	16.7 MB	53%	5.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 15 04:15:01 - 04:59:59	23	1.1%	0.01	74.7 KB	0.8%	0.03
Sep 15 05:00:00 - 05:59:59	44	2.1%	0.01	181.7 KB	1.9%	0.05
Sep 15 06:00:00 - 06:59:59	26	1.2%	0.01	182.2 KB	1.9%	0.05
Sep 15 07:00:00 - 07:59:59	55	2.6%	0.02	171.0 KB	1.8%	0.05
Sep 15 08:00:00 - 08:59:59	76	3.6%	0.02	224.0 KB	2.4%	0.06
Sep 15 09:00:00 - 09:59:59	48	2.3%	0.01	208.9 KB	2.2%	0.06
Sep 15 10:00:00 - 10:59:59	58	2.7%	0.02	639.2 KB	6.7%	0.18
Sep 15 11:00:00 - 11:59:59	105	4.9%	0.03	346.6 KB	3.7%	0.10
Sep 15 12:00:00 - 12:59:59	188	8.9%	0.05	616.0 KB	6.5%	0.17
Sep 15 13:00:00 - 13:59:59	141	6.6%	0.04	540.1 KB	5.7%	0.15
Sep 15 14:00:00 - 14:59:59	104	4.9%	0.03	430.6 KB	4.5%	0.12
Sep 15 15:00:00 - 15:59:59	105	4.9%	0.03	475.8 KB	5.0%	0.13
Sep 15 16:00:00 - 16:59:59	94	4.4%	0.03	299.2 KB	3.2%	0.08
Sep 15 17:00:00 - 17:59:59	116	5.5%	0.03	736.1 KB	7.8%	0.20
Sep 15 18:00:00 - 18:59:59	132	6.2%	0.04	670.9 KB	7.1%	0.19
Sep 15 19:00:00 - 19:59:59	120	5.7%	0.03	461.2 KB	4.9%	0.13
Sep 15 20:00:00 - 20:59:59	82	3.9%	0.02	313.6 KB	3.3%	0.09
Sep 15 21:00:00 - 21:59:59	74	3.5%	0.02	218.6 KB	2.3%	0.06
Sep 15 22:00:00 - 22:59:59	123	5.8%	0.03	424.6 KB	4.5%	0.12
Sep 15 23:00:00 - 23:59:59	76	3.6%	0.02	387.3 KB	4.1%	0.11
Sep 16 00:00:00 - 00:59:59	110	5.2%	0.03	802.5 KB	8.5%	0.22
Sep 16 01:00:00 - 01:59:59	76	3.6%	0.02	325.6 KB	3.4%	0.09
Sep 16 02:00:00 - 02:59:59	42	2.0%	0.01	195.9 KB	2.1%	0.05
Sep 16 03:00:00 - 03:59:59	87	4.1%	0.02	470.5 KB	5.0%	0.13
Sep 16 04:00:00 - 04:15:01	17	0.8%	0.02	72.9 KB	0.8%	0.08
TOTAL: 24:00:00	2122	100.0%	0.02	9.2 MB	100.0%	0.11

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	734	620	114
2	news.glou.org	37	0	37
3	nntp.terraraq.uk	6	0	6
TOTAL: 3		777	620	157

Unwanted newsgroups [Top 20]:

Newsgroup	Count
linux.kernel.netdev	579
linux.kernel.git	41
TOTAL: 2	620

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	106
Bad path (news.giganews.com.POSTED)	49
HTML Multipart	2
TOTAL: 3	157

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.samoylyk.net	2667	47	1713	98	141	1%	23:58:55
2	news.nntp4.net	1549	11	1441	7	0	0%	23:33:40
3	news.pasdenom.info	1365	3	1301	3	0	0%	23:59:57
4	news.tnetconsulting.net	3720	2	2114	1	128	0%	23:59:57
5	news.gegeweb.org	1322	0	1238	0	0	0%	23:36:19
6	nntp.terraraq.uk	1071	0	1024	0	0	0%	23:58:24
7	news.aioe.org	0	0	0	0	1857	0%	24:00:00
8	i2pn.org	0	0	0	0	2115	0%	24:00:00
TOTAL: 8		11694	63	8831	109	4241	0%	191:07:12

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.samoylyk.net	159.9 KB	392.0 KB	551.9 KB	3.8 KB	23:58:55
2	news.nntp4.net	42.9 KB	32.3 KB	75.2 KB	4.2 KB	23:33:40
3	news.pasdenom.info	7.9 KB	10.3 KB	18.2 KB	3.0 KB	23:59:57
4	news.tnetconsulting.net	4.4 KB	14.5 KB	18.9 KB	6.3 KB	23:59:57
5	news.gegeweb.org	0.0 KB	0.0 KB	0.0 KB	0	23:36:19
6	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:58:24
7	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
8	i2pn.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
TOTAL: 8		215.0 KB	449.1 KB	664.1 KB	3.9 KB	191:07:12

NNRP readership statistics:

System		Conn	Elapsed
1	104.152.52.219	1	00:00:00
2	104.152.52.64	1	00:00:00
3	172-236-254-181.ip.linodeusercontent.com	1	00:00:00
4	172-104-210-105.ip.linodeusercontent.com	1	00:00:07
5	104.152.52.65	4	00:00:00
6	104.152.52.225	2	00:00:04
7	raymond.probe.onyphe.net	2	00:00:12
8	66-228-40-100.ip.linodeusercontent.com	3	00:00:00
9	104.152.52.61	1	00:00:00
10	45-79-115-59.ip.linodeusercontent.com	1	00:00:00
11	104.152.52.73	3	00:00:01
12	104.152.52.69	1	00:00:01
13	97-107-131-17.ip.linodeusercontent.com	85	00:00:16
14	o006.scanner.modat.io	1	00:00:00
15	104.152.52.202	1	00:00:01
16	104.152.52.55	2	00:00:01
17	69-5-169-104.infrawat.ch	1	00:00:12
18	69-5-169-124.infrawat.ch	1	00:00:00
19	104-237-145-228.ip.linodeusercontent.com	2	00:00:00
20	69-5-169-116.infrawat.ch	1	00:00:00
21	45-79-207-71.ip.linodeusercontent.com	1	00:00:02
22	104.152.52.224	1	00:00:01
23	2a01:4020:1:1:103::61f	1	00:00:00
24	2a01:4020:1:1:101::12e	1	00:00:00
25	212.102.40.218	7	00:00:00
26	193-176-31-246.infrawat.ch	1	00:00:00
27	mail.gegeweb.eu	288	00:00:36
28	104.152.52.67	1	00:00:00
29	o012.scanner.modat.io	1	00:00:00
TOTAL: 29		417	00:01:47

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.ip.linodeusercontent.com	94	00:00:29
2	*.scanner.modat.io	2	00:00:00
3	?	2	00:00:00
4	*.probe.onyphe.net	2	00:00:12
5	*.infrawat.ch	4	00:00:13
6	unresolved	25	00:00:14
7	*.gegeweb.eu	288	00:00:36
TOTAL: 7		417	00:01:47

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
mail.gegeweb.eu	5.039	4.590	1.720	00:00:36
97-107-131-17.ip.linodeusercontent.com	3.094	1.063	0.000	00:00:16
69-5-169-104.infrawat.ch	0.029	0.019	0.000	00:00:12
raymond.probe.onyphe.net	0.162	0.017	0.000	00:00:12
172-104-210-105.ip.linodeusercontent.com	0.029	0.015	0.000	00:00:07
104.152.52.225	0.020	0.028	0.000	00:00:04
45-79-207-71.ip.linodeusercontent.com	0.022	0.026	0.000	00:00:02
104.152.52.55	0.051	0.024	0.000	00:00:01
104.152.52.73	0.056	0.025	0.000	00:00:01
104.152.52.202	0.065	0.016	0.000	00:00:01
104.152.52.69	0.018	0.009	0.000	00:00:01
104.152.52.224	0.014	0.011	0.000	00:00:01
104-237-145-228.ip.linodeusercontent.com	0.082	0.027	0.000	00:00:00
45-79-115-59.ip.linodeusercontent.com	0.029	0.033	0.000	00:00:00
66-228-40-100.ip.linodeusercontent.com	0.100	0.049	0.000	00:00:00
o012.scanner.modat.io	0.043	0.005	0.000	00:00:00
104.152.52.67	0.032	0.016	0.000	00:00:00
172-236-254-181.ip.linodeusercontent.com	0.033	0.015	0.000	00:00:00
104.152.52.65	0.100	0.061	0.000	00:00:00
69-5-169-124.infrawat.ch	0.015	0.038	0.000	00:00:00
TOTAL: 29	9.355	6.272	1.720	00:01:47

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
66-228-40-100.ip.linodeusercontent.com	12
104.152.52.219	6
104.152.52.55	5
104.152.52.202	5
172-236-254-181.ip.linodeusercontent.com	5
104-237-145-228.ip.linodeusercontent.com	5
104.152.52.224	5
104.152.52.225	3
o012.scanner.modat.io	2
104.152.52.67	2
o006.scanner.modat.io	2
TOTAL: 11	52

NNRP unrecognized commands (by command):

Command	Count
Accept: /	7
GET / HTTP/1.1	7
Accept-Encoding: gzip	4
User-Agent: Mozilla/5.0 (Macintosh; Inte...	4
Host: 45.13.105.36:563	3
Host: 45.13.105.36	3
User-Agent: curl/7.61.1	3
Connection: close	3
MAIL FROM:<relaycheck_please_ignore@prot...	2
EHLO 45.13.105.36	2
À#024#023#001#023#002#023#003#001	1
#026#003#001#005Ê#001	1
SYST	1
g9c6T#027…Êú"~#013Q“cÇV‹’A	1
íxÅ™BíÖ>ørÏv:P"‹XICž“´P#033„<\|“#007Y5 ]d¢ÕäµÜ...	1
#026#003#001	1
NOOP	1
FEAT	1
Host: 45.13.105.36:119	1
„#003#032'»àvÁ#003pê8#033uÊ—Û¥^Ôƒ#011#031#002#023õ+¿¡·'°Ÿ/[¨@Š\x7f...	1
HELO	1
#010iÐ<BVgEêÛ/#YÏuÜ?#026ÑÊ˜#014_Vy#002€µz‚yË:¬…å"Ž#005...	1
SSH-2.0-Go	1
#001	1
TOTAL: 24	52

NNRP client timeouts [Top 20]:

System	Conn	Peer
2a01:4020:1:1:101::12e	1	1
69-5-169-116.infrawat.ch	1	1
69-5-169-124.infrawat.ch	1	1
TOTAL: 3	3	7

Daily Usenet report for news.niel.me

Sep 15 04:15:01 -- Sep 16 04:15:01