Daily Usenet report: Jan 1 14:50:26

Unknown entries from news log file:

First 50 / 99 lines (50.5%)

Jan 1 14:50:42 spool1 nnrpd[34303]: unable to get private key from '/usr/local/news/etc/cert.pem'
Jan 1 14:50:42 spool1 nnrpd[34303]: error initializing TLS: [CA_file: /usr/local/news/etc/cert.pem] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/cert.pem]
Jan 1 14:50:42 spool1 nnrpd[34303]: time 82 nntpwrite 0(1)
Jan 1 14:50:43 spool1 nnrpd[34361]: unable to get private key from '/usr/local/news/etc/cert.pem'
Jan 1 14:50:43 spool1 nnrpd[34361]: error initializing TLS: [CA_file: /usr/local/news/etc/cert.pem] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/cert.pem]
Jan 1 14:50:43 spool1 nnrpd[34361]: time 79 nntpwrite 0(1)
Jan 1 14:50:45 spool1 nnrpd[34393]: unable to get private key from '/usr/local/news/etc/cert.pem'
Jan 1 14:50:45 spool1 nnrpd[34393]: error initializing TLS: [CA_file: /usr/local/news/etc/cert.pem] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/cert.pem]
Jan 1 14:50:45 spool1 nnrpd[34393]: time 80 nntpwrite 0(1)
Jan 1 14:50:48 spool1 nnrpd[34585]: unable to get private key from '/usr/local/news/etc/cert.pem'
Jan 1 14:50:48 spool1 nnrpd[34585]: error initializing TLS: [CA_file: /usr/local/news/etc/cert.pem] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/cert.pem]
Jan 1 14:50:48 spool1 nnrpd[34585]: time 82 nntpwrite 0(1)
Jan 1 14:50:59 spool1 nnrpd[34597]: unable to get private key from '/usr/local/news/etc/cert.pem'
Jan 1 14:50:59 spool1 nnrpd[34597]: error initializing TLS: [CA_file: /usr/local/news/etc/cert.pem] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/cert.pem]
Jan 1 14:50:59 spool1 nnrpd[34597]: time 81 nntpwrite 0(1)
Jan 1 14:51:00 spool1 nnrpd[34898]: unable to get private key from '/usr/local/news/etc/cert.pem'
Jan 1 14:51:00 spool1 nnrpd[34898]: error initializing TLS: [CA_file: /usr/local/news/etc/cert.pem] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/cert.pem]
Jan 1 14:51:00 spool1 nnrpd[34898]: time 82 nntpwrite 0(1)
Jan 1 14:51:02 spool1 nnrpd[35019]: unable to get private key from '/usr/local/news/etc/cert.pem'
Jan 1 14:51:02 spool1 nnrpd[35019]: error initializing TLS: [CA_file: /usr/local/news/etc/cert.pem] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/cert.pem]
Jan 1 14:51:02 spool1 nnrpd[35019]: time 79 nntpwrite 0(1)
Jan 1 14:51:16 spool1 nnrpd[35286]: unable to get private key from '/usr/local/news/etc/cert.pem'
Jan 1 14:51:16 spool1 nnrpd[35286]: error initializing TLS: [CA_file: /usr/local/news/etc/cert.pem] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/cert.pem]
Jan 1 14:51:16 spool1 nnrpd[35286]: time 83 nntpwrite 0(1)
Jan 1 14:51:17 spool1 nnrpd[35288]: unable to get private key from '/usr/local/news/etc/cert.pem'
Jan 1 14:51:17 spool1 nnrpd[35288]: error initializing TLS: [CA_file: /usr/local/news/etc/cert.pem] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/cert.pem]
Jan 1 14:51:17 spool1 nnrpd[35288]: time 80 nntpwrite 0(1)
Jan 1 14:51:19 spool1 nnrpd[35553]: unable to get private key from '/usr/local/news/etc/cert.pem'
Jan 1 14:51:19 spool1 nnrpd[35553]: error initializing TLS: [CA_file: /usr/local/news/etc/cert.pem] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/cert.pem]
Jan 1 14:51:19 spool1 nnrpd[35553]: time 82 nntpwrite 0(1)
Jan 1 14:51:33 spool1 nnrpd[35760]: unable to get private key from '/usr/local/news/etc/cert.pem'
Jan 1 14:51:33 spool1 nnrpd[35760]: error initializing TLS: [CA_file: /usr/local/news/etc/cert.pem] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/cert.pem]
Jan 1 14:51:33 spool1 nnrpd[35760]: time 80 nntpwrite 0(1)
Jan 1 14:51:34 spool1 nnrpd[36154]: unable to get private key from '/usr/local/news/etc/cert.pem'
Jan 1 14:51:34 spool1 nnrpd[36154]: error initializing TLS: [CA_file: /usr/local/news/etc/cert.pem] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/cert.pem]
Jan 1 14:51:34 spool1 nnrpd[36154]: time 81 nntpwrite 0(1)
Jan 1 14:51:35 spool1 nnrpd[36264]: unable to get private key from '/usr/local/news/etc/cert.pem'
Jan 1 14:51:35 spool1 nnrpd[36264]: error initializing TLS: [CA_file: /usr/local/news/etc/cert.pem] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/cert.pem]
Jan 1 14:51:35 spool1 nnrpd[36264]: time 80 nntpwrite 0(1)
Jan 1 14:51:37 spool1 nnrpd[36372]: unable to get private key from '/usr/local/news/etc/cert.pem'
Jan 1 14:51:37 spool1 nnrpd[36372]: error initializing TLS: [CA_file: /usr/local/news/etc/cert.pem] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/cert.pem]
Jan 1 14:51:37 spool1 nnrpd[36372]: time 81 nntpwrite 0(1)
Jan 1 14:51:50 spool1 nnrpd[36469]: unable to get private key from '/usr/local/news/etc/cert.pem'
Jan 1 14:51:50 spool1 nnrpd[36469]: error initializing TLS: [CA_file: /usr/local/news/etc/cert.pem] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/cert.pem]
Jan 1 14:51:50 spool1 nnrpd[36469]: time 83 nntpwrite 0(1)
Jan 1 14:51:51 spool1 nnrpd[36619]: unable to get private key from '/usr/local/news/etc/cert.pem'
Jan 1 14:51:51 spool1 nnrpd[36619]: error initializing TLS: [CA_file: /usr/local/news/etc/cert.pem] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/cert.pem]
Jan 1 14:51:51 spool1 nnrpd[36619]: time 80 nntpwrite 0(1)
Jan 1 14:51:51 spool1 nnrpd[36909]: unable to get private key from '/usr/local/news/etc/cert.pem'
Jan 1 14:51:51 spool1 nnrpd[36909]: error initializing TLS: [CA_file: /usr/local/news/etc/cert.pem] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/cert.pem]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	26575	66.7%	2.0 MB	57.7%
inn	12676	31.8%	1.4 MB	40.1%
innd	471	1.2%	68.0 KB	1.9%
innfeed	97	0.2%	8.7 KB	0.2%
controlchan	2	0.0%	0.1 KB	0.0%
nocem	2	0.0%	0.2 KB	0.0%
TOTAL: 6	39823	100.0%	3.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	12549	96.0%
Negative hits	517	4.0%
Positive hits	2	0.0%
Cache misses	1	0.0%
TOTAL: 4	13069	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.222	0.0%	42	0.000	5.286	35.000
article cleanup	00:00:00.086	0.0%	12622	0.000	0.007	0.065
article logging	00:00:00.225	0.0%	12698	0.000	0.018	0.135
article parse	00:00:08.198	0.0%	684599	0.000	0.012	0.051
article write	00:02:53.508	0.4%	12517	0.325	13.862	41.776
artlog/artcncl	00:00:00.001	0.0%	3	0.000	0.333	0.500
data move	00:00:00.969	0.0%	754844	0.000	0.001	0.020
hisgrep/artcncl	00:00:00.015	0.0%	45	0.000	0.333	1.500
hishave/artcncl	00:00:00.000	0.0%	3	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.336	0.0%	13139	0.000	0.026	0.187
history sync	00:00:20.114	0.0%	200	0.000	100.570	187.000
history write	00:02:23.165	0.3%	12622	0.233	11.342	22.256
idle	11:25:39.717	97.9%	682687	1.544	60.261	9674.871
nntp read	00:00:43.782	0.1%	682589	0.000	0.064	0.116
overview write	00:05:02.975	0.7%	12517	0.147	24.205	1537.776
perl filter	00:00:01.366	0.0%	12598	0.000	0.108	0.152
python filter	00:03:21.367	0.5%	12622	0.748	15.954	22.512
site send	00:00:00.413	0.0%	12541	0.000	0.033	0.152
TOTAL: 11:40:41.982	11:40:36.459	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.000	0.0%	0	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.002	0.0%	1821	0.000	0.001	0.077
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.000	0.0%	6	0.000	0.000	0.000
data write	00:00:00.001	0.0%	6	0.000	0.167	0.500
idle	11:33:35.706	100.0%	1821	17170.200	22853.216	24004.160
status file	00:00:00.003	0.0%	12	0.000	0.250	0.500
TOTAL: 11:33:35.742	11:33:35.712	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.046	0.0%	5	9.200	9.200	9.200
idle	00:00:00.000	0.0%	0	0.000	0.000	0.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:15:43.876	2.3%	7301755	0.000	0.129	67.421
readart	00:00:00.097	0.0%	5	19.400	19.400	19.400
TOTAL: 11:24:54.811	00:15:44.019	2.3%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	12
name	2
pause	2
paused	2
reload	2
reserve	2
rmgroup	1
TOTAL: 12	35

Newsgroups removed:

Group
01alt.test
TOTAL: 1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	feed1.usenet.blueworldhosting.com	4	12735	12629	0	106	99%	11:55:12
TOTAL: 1		4	12735	12629	0	106	99%	11:55:12

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feed1.usenet.blueworldhosting.com	3.6 GB	0.0 KB	797.1 KB	3.6 GB	99%	298.3 KB
TOTAL: 1		3.6 GB	0.0 KB	797.1 KB	3.6 GB	99%	298.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 1 14:50:26 - 14:59:59	2912	23.3%	5.07	1.2 GB	33.5%	2215.62
Jan 1 15:00:00 - 15:59:59	5867	47.0%	1.63	2.3 GB	62.8%	661.71
Jan 1 16:00:00 - 16:59:59	551	4.4%	0.15	2.3 MB	0.1%	0.65
Jan 1 17:00:00 - 17:59:59	391	3.1%	0.11	2.0 MB	0.1%	0.56
Jan 1 18:00:00 - 18:59:59	428	3.4%	0.12	2.2 MB	0.1%	0.63
Jan 1 19:00:00 - 19:59:59	276	2.2%	0.08	1.2 MB	0.0%	0.35
Jan 1 20:00:00 - 20:59:59	203	1.6%	0.06	2.2 MB	0.1%	0.62
Jan 1 21:00:00 - 21:59:59	391	3.1%	0.11	76.6 MB	2.1%	21.78
Jan 1 22:00:00 - 22:59:59	240	1.9%	0.07	1.7 MB	0.0%	0.48
Jan 1 23:00:00 - 23:59:59	303	2.4%	0.08	1.4 MB	0.0%	0.40
Jan 2 00:00:00 - 00:59:59	368	2.9%	0.10	46.8 MB	1.3%	13.32
Jan 2 01:00:00 - 01:59:59	346	2.8%	0.10	1.2 MB	0.0%	0.33
Jan 2 02:00:00 - 02:30:00	216	1.7%	0.12	1018.7 KB	0.0%	0.57
TOTAL: 11:39:34	12492	100.0%	0.30	3.6 GB	100.0%	90.43

Sites sending bad articles:

Server		Total	Group	Dist	Duplic	Unapp	TooOld	Site	Line	Other
1	feed1.usenet.blueworldhosting.com	181	81	0	0	0	0	0	0	100
TOTAL: 1		181	81	0	0	0	0	0	0	100

Unwanted newsgroups [Top 20]:

Newsgroup	Count
finet.binaries.keskustelu	18
alt.binaries.pictures.wallpaper	12
alt.binaries.pictures.purebeauty	10
alt.religion.buddhism	5
alt.binaries.sounds.lossless.classical	3
alt.binaries.clip-art	3
alt.binaries.erotica.collections.rars	3
alt.binaries.sounds.lossless	3
alt.binaries.sounds.mp3.christmas	2
alt.binaries.pictures.tall-ships	2
alt.binaries.pictures.aviation	2
alt.binaries.emulators.mame	1
alt.binaries.pictures.scenic	1
alt.binaries.pictures.teen-starlets	1
alt.binaries.comics.dcp	1
alt.binaries.multimedia.cooking	1
alt.binaries.test.yenc	1
alt.binaries.pictures.sierra	1
alt.binaries.classic.tv.shows	1
alt.binaries.multimedia.vintage-tv	1
TOTAL: 29	81

INND Python filter [Top 20]:

Reason	Count
Bad From (FBInCIAnNSATerroristSlayer@yahoo.com)	14
EMP Body Reject	10
TOTAL: 2	24

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	feed1.usenet.blueworldhosting.com	76
	TOTAL: 1	76
TOTAL: 1		76

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	feed1.usenet.blueworldhosting.com	0	0	0	0	0	0	0%	00:20:01
TOTAL: 1		0	0	0	0	0	0	0%	00:20:01

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	feed1.usenet.blueworldhosting.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	00:20:01
TOTAL: 1		0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	00:20:01

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	023-084-030-207.res.spectrum.com	37	5	5.1 KB	2	0	0	00:16:13
TOTAL: 1		37	5	5.1 KB	2	0	0	11:24:51

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.res.spectrum.com	37	5	5.1 KB	2	00:16:13
2	unresolved	1	0	0.0 KB	0	00:00:00
3	*.stehlik.co.uk	513	0	0.0 KB	0	11:08:37
TOTAL: 3		551	5	5.1 KB	2	11:24:51

NNRP auth users [Top 20]:

User	Conn
jesse	31
0.069 system 0.008 idle 0.000 elapsed 0.076	3
0.077 system 0.000 idle 0.000 elapsed 0.077	3
0.075 system 0.000 idle 0.000 elapsed 0.075	2
0.061 system 0.015 idle 0.000 elapsed 0.076	2
0.071 system 0.008 idle 0.000 elapsed 0.079	2
0.082 system 0.000 idle 0.000 elapsed 0.082	2
0.070 system 0.008 idle 0.000 elapsed 0.078	2
0.080 system 0.000 idle 0.000 elapsed 0.079	2
0.069 system 0.008 idle 0.000 elapsed 0.077	2
0.061 system 0.015 idle 0.000 elapsed 0.075	1
0.068 system 0.008 idle 0.000 elapsed 0.075	1
0.067 system 0.008 idle 0.000 elapsed 0.076	1
0.078 system 0.000 idle 0.000 elapsed 0.077	1
0.067 system 0.007 idle 0.000 elapsed 0.075	1
0.067 system 0.008 idle 0.000 elapsed 0.075	1
0.073 system 0.008 idle 0.000 elapsed 0.081	1
0.080 system 0.000 idle 0.000 elapsed 0.080	1
0.062 system 0.015 idle 0.000 elapsed 0.077	1
0.079 system 0.000 idle 0.000 elapsed 0.079	1
TOTAL: 23	64

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
toma-03.stehlik.co.uk	102.535	37.927	0.000	11:08:37
023-084-030-207.res.spectrum.com	3.264	0.658	0.186	00:16:13
94.102.61.39	0.074	0.008	0.000	00:00:00
TOTAL: 3	105.873	38.593	0.186	11:24:51

Curious NNRP server explorers [Top 20]:

System	Conn
toma-03.stehlik.co.uk	513
94.102.61.39	1
TOTAL: 2	514

NNRP client timeouts [Top 20]:

System	Conn	Peer
023-084-030-207.res.spectrum.com	33	33
toma-03.stehlik.co.uk	7	7
TOTAL: 2	40	40

Newsgroup request counts (by hierarchy):

Hierarchy		Count	Pct
1	1	5	100.0%
TOTAL: 1		5	100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

Newsgroup		Count
1	1.test	5
TOTAL: 1		5

Daily Usenet report

Jan 1 14:50:26 -- Jan 2 02:30:00