Daily Usenet report: Jun 15 03:00:02

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Jun 15 03:14:08 news innd: message repeated 2 times: [ ctlinnd command s]
Jun 15 17:00:05 news pgpverify[2032656]: pgpverify: verification failed
Jun 15 17:00:10 news pgpverify[2032671]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
innd	5996	48.0%	1.0 MB	52.0%
inn	4266	34.2%	767.9 KB	38.5%
innfeed	2109	16.9%	173.5 KB	8.7%
nnrpd	95	0.8%	9.1 KB	0.5%
controlchan	23	0.2%	4.8 KB	0.2%
pgpverify	2	0.0%	0.2 KB	0.0%
TOTAL: 6	12491	100.0%	1.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	4200	91.3%
Negative hits	357	7.8%
Cache misses	41	0.9%
Positive hits	0	0.0%
TOTAL: 4	4598	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.069	0.0%	7	0.000	9.857	15.000
article cleanup	00:00:00.013	0.0%	4232	0.000	0.003	1.000
article logging	00:00:00.046	0.0%	4267	0.000	0.011	0.500
article parse	00:00:00.042	0.0%	4792	0.000	0.009	1.000
article write	00:00:03.489	0.0%	4202	0.000	0.830	42.000
data move	00:00:00.000	0.0%	5715	0.000	0.000	0.000
hisgrep/artcncl	00:00:00.003	0.0%	7	0.000	0.429	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:23.819	0.0%	4600	0.000	5.178	39.000
history sync	00:00:00.078	0.0%	1682	0.000	0.046	17.500
history write	00:00:12.009	0.0%	4202	0.000	2.858	30.000
idle	23:59:10.939	99.9%	6790	829.329	12717.370	60061.000
nntp read	00:00:00.048	0.0%	5186	0.000	0.009	1.000
overview write	00:00:02.637	0.0%	4202	0.000	0.628	74.182
perl filter	00:00:09.702	0.0%	4202	0.000	2.309	54.000
python filter	00:00:00.184	0.0%	4202	0.000	0.044	15.000
site send	00:00:00.025	0.0%	4198	0.000	0.006	1.000
TOTAL: 24:00:05.038	24:00:03.103	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.000	0.0%	4194	0.000	0.000	0.000
article prepare	00:00:00.000	0.0%	0	0.000	0.000	0.000
article read	00:00:00.000	0.0%	0	0.000	0.000	0.000
backlog stats	00:00:00.004	0.0%	8733	0.000	0.000	0.250
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.030	0.0%	4251	0.000	0.007	1.000
data write	00:00:00.000	0.0%	92	0.000	0.000	0.000
idle	23:59:49.021	100.0%	8733	1406.209	9892.250	30100.000
status file	00:00:00.214	0.0%	196	0.000	1.092	67.500
TOTAL: 23:59:49.596	23:59:49.269	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:17.030	67.6%	17	11.500	1001.765	10043.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.000	0.0%	115	0.000	0.000	0.000
TOTAL: 00:00:25.177	00:00:17.030	67.6%	-	-	-	-

Control commands to INND:

Command	Number
flush	2
flushlogs	2
go	2
logmode	2
lowmark	1
mode	146
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	165

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	news.twilightnode.org	2	4100	4059	11	30	99%	23:08:10
2	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 2		27	4100	4059	11	30	99%	23:08:10

Incoming Volume (INN):

Server		AcceptVol	DupVol	TotalVol	%Acc	Vol/Art
1	news.twilightnode.org	12.1 MB	131.3 KB	12.2 MB	98%	3.1 KB
2	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 2		12.1 MB	131.3 KB	12.2 MB	98%	3.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 15 03:00:02 - 03:59:59	76	1.8%	0.02	182.3 KB	1.4%	0.05
Jun 15 04:00:00 - 04:59:59	82	2.0%	0.02	325.7 KB	2.5%	0.09
Jun 15 05:00:00 - 05:59:59	135	3.2%	0.04	441.8 KB	3.5%	0.12
Jun 15 06:00:00 - 06:59:59	102	2.4%	0.03	334.0 KB	2.6%	0.09
Jun 15 07:00:00 - 07:59:59	160	3.8%	0.04	553.6 KB	4.3%	0.15
Jun 15 08:00:00 - 08:59:59	124	3.0%	0.03	332.5 KB	2.6%	0.09
Jun 15 09:00:00 - 09:59:59	182	4.3%	0.05	557.9 KB	4.4%	0.15
Jun 15 10:00:00 - 10:59:59	191	4.5%	0.05	554.8 KB	4.3%	0.15
Jun 15 11:00:00 - 11:59:59	169	4.0%	0.05	373.1 KB	2.9%	0.10
Jun 15 12:00:00 - 12:59:59	172	4.1%	0.05	461.9 KB	3.6%	0.13
Jun 15 13:00:00 - 13:59:59	182	4.3%	0.05	460.6 KB	3.6%	0.13
Jun 15 14:00:00 - 14:59:59	204	4.9%	0.06	540.4 KB	4.2%	0.15
Jun 15 15:00:00 - 15:59:59	205	4.9%	0.06	551.1 KB	4.3%	0.15
Jun 15 16:00:00 - 16:59:59	223	5.3%	0.06	718.7 KB	5.6%	0.20
Jun 15 17:00:00 - 17:59:59	241	5.7%	0.07	1.1 MB	8.8%	0.31
Jun 15 18:00:00 - 18:59:59	231	5.5%	0.06	620.4 KB	4.9%	0.17
Jun 15 19:00:00 - 19:59:59	229	5.5%	0.06	691.2 KB	5.4%	0.19
Jun 15 20:00:00 - 20:59:59	189	4.5%	0.05	523.2 KB	4.1%	0.15
Jun 15 21:00:00 - 21:59:59	207	4.9%	0.06	635.7 KB	5.0%	0.18
Jun 15 22:00:00 - 22:59:59	189	4.5%	0.05	492.6 KB	3.9%	0.14
Jun 15 23:00:00 - 23:59:59	186	4.4%	0.05	567.4 KB	4.4%	0.16
Jun 16 00:00:00 - 00:59:59	185	4.4%	0.05	615.3 KB	4.8%	0.17
Jun 16 01:00:00 - 01:59:59	205	4.9%	0.06	746.8 KB	5.8%	0.21
Jun 16 02:00:00 - 02:59:59	131	3.1%	0.04	378.5 KB	3.0%	0.11
Jun 16 03:00:00 - 03:00:01	1	0.0%	1.00	0.8 KB	0.0%	0.80
TOTAL: 23:59:59	4201	100.0%	0.05	12.5 MB	100.0%	0.15

Sites sending bad articles:

Server		Total	Group	Dist	Duplic	Unapp	TooOld	Site	Line	Other
1	news.twilightnode.org	65	0	0	30	0	0	0	0	35
TOTAL: 1		65	0	0	30	0	0	0	0	35

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news.twilightnode.org	35
	TOTAL: 1	35
TOTAL: 1		35

Outgoing Feeds (innfeed) by Articles:

Server		Spool	%Took	Elapsed
1	sklaffkom.se	4195	0%	23:59:57
2	thebunker.nu	4195	0%	23:57:42
3	twilightnode.org	0	0%	00:10:00
4	usenet.blueworldhosting.com	3876	0%	23:59:57
TOTAL: 4		12266	0%	72:07:36

Outgoing Feeds (innfeed) by Volume:

Server		Elapsed
1	sklaffkom.se	23:59:57
2	thebunker.nu	23:57:42
3	twilightnode.org	00:10:00
4	usenet.blueworldhosting.com	23:59:57
TOTAL: 4		72:07:36

NNRP readership statistics:

System		Conn	Elapsed
1	o316.scanner.modat.io	5	00:00:00
2	azpdesq2p3jd.stretchoid.com	2	00:00:10
3	168.186.132.66.censys-scanner.com	1	00:00:02
4	188.240.59.27	1	00:00:00
5	47.251.13.174	1	00:00:03
6	62.164.177.41	1	00:00:00
7	69.5.169.20	1	00:00:00
8	69.5.169.200	1	00:00:00
9	69.5.169.77	1	00:00:00
10	maggie.probe.onyphe.net	1	00:00:03
11	mark.probe.onyphe.net	1	00:00:03
12	r3-148-94.monitoring.internet-measurement.com	1	00:00:01
TOTAL: 12		17	00:00:25

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	6	00:00:04
2	*.scanner.modat.io	5	00:00:00
3	*.probe.onyphe.net	2	00:00:06
4	*.stretchoid.com	2	00:00:10
5	*.186.132.66.censys-scanner.com	1	00:00:02
6	*.monitoring.internet-measurement.com	1	00:00:01
TOTAL: 6		17	00:00:25

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
azpdesq2p3jd.stretchoid.com	0.015	0.009	00:00:10
47.251.13.174	0.008	0.003	00:00:03
mark.probe.onyphe.net	0.043	0.001	00:00:03
maggie.probe.onyphe.net	0.039	0.005	00:00:03
168.186.132.66.censys-scanner.com	0.037	0.011	00:00:02
r3-148-94.monitoring.internet-measurement.com	0.007	0.005	00:00:01
o316.scanner.modat.io	0.196	0.017	00:00:00
62.164.177.41	0.005	0.006	00:00:00
69.5.169.20	0.007	0.004	00:00:00
69.5.169.200	0.005	0.007	00:00:00
69.5.169.77	0.008	0.005	00:00:00
188.240.59.27	0.009	0.003	00:00:00
TOTAL: 12	0.379	0.076	00:00:25

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host) [Top 20]:

System	Conn
168.186.132.66.censys-scanner.com	1
62.164.177.41	1
azpdesq2p3jd.stretchoid.com	1
o316.scanner.modat.io	1
TOTAL: 4	4

NNRP unrecognized commands (by command) [Top 20]:

Command	Count
#003	1
EHLO www.censys.io	1
HELO	1
MGLNDD_109.238.130.155_119	1
TOTAL: 4	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
47.251.13.174	1	1
62.164.177.41	1	1
69.5.169.20	1	1
69.5.169.200	1	1
69.5.169.77	1	1
o316.scanner.modat.io	1	1
TOTAL: 6	6	9

Daily Usenet report

Jun 15 03:00:02 -- Jun 16 03:00:01