Daily Usenet report for csiph.com: Apr 13 04:15:00

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Apr 13 06:04:19 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[99542]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Apr 13 06:04:19 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[99542]: error initializing TLS: [CA_file: ] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/key.pem]
Apr 13 10:46:31 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[14130]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Apr 13 10:46:31 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[14130]: error initializing TLS: [CA_file: ] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/key.pem]
Apr 13 11:00:34 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[14862]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Apr 13 11:00:34 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[14862]: error initializing TLS: [CA_file: ] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/key.pem]
Apr 13 13:50:09 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[24492]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Apr 13 13:50:09 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[24492]: error initializing TLS: [CA_file: ] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/key.pem]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
innd	22865	39.5%	3.8 MB	34.7%
inn	18663	32.3%	4.7 MB	43.0%
innfeed	14109	24.4%	2.1 MB	19.5%
nnrpd	2225	3.8%	316.8 KB	2.8%
nocem	2	0.0%	0.3 KB	0.0%
TOTAL: 5	57864	100.0%	10.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	292163	54.3%
Negative hits	227633	42.3%
Do not exist	18149	3.4%
Cache misses	299	0.1%
TOTAL: 4	538244	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.676	0.0%	26	0.000	26.000	49.000
article cleanup	00:00:00.120	0.0%	18084	0.000	0.007	0.033
article logging	00:00:00.955	0.0%	18518	0.000	0.052	0.828
article parse	00:00:01.716	0.0%	56993	0.000	0.030	0.313
article write	00:01:43.686	0.1%	14674	0.271	7.066	48.686
artlog/artcncl	00:00:00.003	0.0%	26	0.000	0.115	0.333
artlog/artparse	00:00:00.018	0.0%	307	0.000	0.059	2.250
data move	00:00:00.188	0.0%	502751	0.000	0.000	0.003
hisgrep/artcncl	00:00:00.105	0.0%	25	0.000	4.200	13.000
hishave/artcncl	00:00:00.015	0.0%	26	0.000	0.577	1.000
hishave/artparse	00:00:00.001	0.0%	304	0.000	0.003	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:06.017	0.1%	541786	0.002	0.122	0.413
history sync	00:00:01.450	0.0%	368	0.000	3.940	54.000
history write	00:01:29.142	0.1%	18078	0.712	4.931	14.070
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:01.225	0.0%	304	0.000	4.030	34.000
idle	23:46:09.233	98.5%	500189	102.323	171.074	559.662
nntp read	00:00:02.223	0.0%	513177	0.002	0.004	0.007
overview write	00:15:36.668	1.1%	14674	0.104	63.832	10565.891
perl filter	00:00:25.177	0.0%	18074	0.912	1.393	4.346
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.299	0.0%	29350	0.000	0.010	0.045
TOTAL: 24:07:37.772	24:06:38.917	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.031	0.0%	16173	0.000	0.002	0.024
article prepare	00:00:00.000	0.0%	3560	0.000	0.000	0.000
article read	00:00:03.309	0.0%	783	0.000	4.226	116.000
backlog stats	00:00:00.200	0.0%	293050	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.996	0.0%	225424	0.001	0.004	0.011
data write	00:00:01.280	0.0%	212713	0.001	0.006	0.020
idle	23:54:32.782	100.0%	293050	170.099	293.714	881.734
status file	00:00:03.206	0.0%	4795	0.350	0.669	4.462
TOTAL: 23:54:59.894	23:54:41.804	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.001	0.0%	1	1.000	1.000	1.000
idle	38:17:48.390	99.9%	1199	0.000	114986.147	600215.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.037	0.0%	3570	0.000	0.010	0.500
readart	00:00:00.041	0.0%	1	41.000	41.000	41.000
TOTAL: 38:19:50.021	38:17:48.469	99.9%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	126
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	146

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.xs4all.nl	2231	17147	2332	13540	1275	13%	125:39:29
2	feeder.eternal-september.org	15	5800	2144	3525	131	36%	27:46:43
3	newsfeeds.xmission.com	3	13962	2036	11918	8	14%	24:31:58
4	news-out.aioe.org	2	13700	1442	12230	28	10%	23:59:51
5	csiph-out.feeder.erje.net	1440	72821	1145	71069	607	1%	119:07:08
6	news-out.mixmin.net	2	15893	977	14598	318	6%	23:59:03
7	newsfeed.fu-berlin.de	19	14352	858	13071	423	5%	25:04:26
8	csiph-out.news.weretis.net	2	29871	851	28942	78	2%	47:59:44
9	news.freedyn.net	2	17190	620	16254	316	3%	23:59:51
10	newsfeed.neva.ru	144	20630	607	19886	137	2%	71:28:42
11	csiph-out.feed.uzoreto.com	864	24469	554	23383	532	2%	71:22:17
12	nntp.pasdenom.info	5	26389	373	26010	6	1%	61:36:04
13	news.fcku.it	7	1745	269	1464	12	15%	20:56:49
14	csiph-out.news.tnetconsulting.net	2	14709	258	14441	10	1%	23:59:54
15	news-out.neodome.net	2	16093	232	15830	31	1%	23:59:26
16	news.swapon.de	5	1460	36	1421	3	2%	26:07:49
17	news.bbs.nz	2	14616	5	14611	0	0%	23:59:32
18	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 18		4773	320847	14739	302193	3915	4%	765:38:46

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	7.8 MB	29.3 KB	4.4 MB	12.2 MB	64%	7.2 KB
2	feeder.eternal-september.org	6.3 MB	0.0 KB	5.3 MB	11.6 MB	54%	5.2 KB
3	csiph-out.news.xs4all.nl	6.1 MB	0.0 KB	8.5 MB	14.6 MB	41%	4.1 KB
4	newsfeeds.xmission.com	6.0 MB	0.0 KB	409.3 KB	6.4 MB	93%	3.2 KB
5	news-out.aioe.org	3.6 MB	0.0 KB	60.4 KB	3.6 MB	98%	2.5 KB
6	news-out.mixmin.net	2.4 MB	0.0 KB	626.7 KB	3.1 MB	79%	2.4 KB
7	csiph-out.news.weretis.net	2.3 MB	23.4 KB	1.3 MB	3.6 MB	62%	4.0 KB
8	newsfeed.fu-berlin.de	2.0 MB	0.0 KB	4.7 MB	6.6 MB	29%	5.3 KB
9	csiph-out.feed.uzoreto.com	1.8 MB	0.0 KB	8.5 MB	10.3 MB	17%	9.7 KB
10	news.freedyn.net	1.7 MB	0.0 KB	2.4 MB	4.1 MB	41%	4.5 KB
11	newsfeed.neva.ru	1.5 MB	0.0 KB	341.3 KB	1.8 MB	81%	2.5 KB
12	csiph-out.news.tnetconsulting.net	899.3 KB	0.0 KB	34.4 KB	933.7 KB	96%	3.5 KB
13	nntp.pasdenom.info	773.8 KB	0.0 KB	8.5 KB	782.2 KB	98%	2.1 KB
14	news-out.neodome.net	522.2 KB	0.0 KB	245.9 KB	768.1 KB	67%	2.9 KB
15	news.fcku.it	467.2 KB	0.0 KB	18.1 KB	485.3 KB	96%	1.7 KB
16	news.swapon.de	79.4 KB	0.0 KB	83.7 KB	163.2 KB	48%	4.2 KB
17	news.bbs.nz	19.1 KB	0.0 KB	0.0 KB	19.1 KB	100%	3.8 KB
18	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		44.2 MB	52.8 KB	36.8 MB	81.0 MB	54%	4.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 13 04:15:00 - 04:59:59	295	2.0%	0.11	1.8 MB	4.3%	0.70
Apr 13 05:00:00 - 05:59:59	374	2.6%	0.10	1.3 MB	3.0%	0.37
Apr 13 06:00:00 - 06:59:59	449	3.1%	0.12	1.3 MB	3.0%	0.37
Apr 13 07:00:00 - 07:59:59	525	3.6%	0.15	1.7 MB	3.9%	0.48
Apr 13 08:00:00 - 08:59:59	731	5.0%	0.20	1.8 MB	4.2%	0.52
Apr 13 09:00:00 - 09:59:59	806	5.5%	0.22	1.9 MB	4.5%	0.55
Apr 13 10:00:00 - 10:59:59	691	4.7%	0.19	1.6 MB	3.7%	0.46
Apr 13 11:00:00 - 11:59:59	740	5.1%	0.21	1.7 MB	4.0%	0.50
Apr 13 12:00:00 - 12:59:59	838	5.7%	0.23	2.3 MB	5.3%	0.65
Apr 13 13:00:00 - 13:59:59	749	5.1%	0.21	2.2 MB	5.2%	0.64
Apr 13 14:00:00 - 14:59:59	804	5.5%	0.22	2.6 MB	6.1%	0.75
Apr 13 15:00:00 - 15:59:59	889	6.1%	0.25	3.2 MB	7.3%	0.91
Apr 13 16:00:00 - 16:59:59	923	6.3%	0.26	2.5 MB	5.8%	0.72
Apr 13 17:00:00 - 17:59:59	749	5.1%	0.21	2.2 MB	5.1%	0.64
Apr 13 18:00:00 - 18:59:59	764	5.2%	0.21	2.2 MB	5.0%	0.62
Apr 13 19:00:00 - 19:59:59	747	5.1%	0.21	2.0 MB	4.5%	0.56
Apr 13 20:00:00 - 20:59:59	690	4.7%	0.19	2.1 MB	4.9%	0.61
Apr 13 21:00:00 - 21:59:59	608	4.2%	0.17	1.6 MB	3.6%	0.45
Apr 13 22:00:00 - 22:59:59	491	3.4%	0.14	1.5 MB	3.5%	0.43
Apr 13 23:00:00 - 23:59:59	411	2.8%	0.11	1.2 MB	2.8%	0.35
Apr 14 00:00:00 - 00:59:59	354	2.4%	0.10	1.0 MB	2.4%	0.30
Apr 14 01:00:00 - 01:59:59	326	2.2%	0.09	1.1 MB	2.4%	0.30
Apr 14 02:00:00 - 02:59:59	281	1.9%	0.08	887.8 KB	2.0%	0.25
Apr 14 03:00:00 - 03:59:59	262	1.8%	0.07	791.7 KB	1.8%	0.22
Apr 14 04:00:00 - 04:15:00	110	0.8%	0.12	730.0 KB	1.6%	0.81
TOTAL: 24:00:00	14607	100.0%	0.17	43.5 MB	100.0%	0.52

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1316	81	0	1235
2	newsfeed.fu-berlin.de	747	23	0	724
3	csiph-out.feed.uzoreto.com	538	52	0	486
4	news.freedyn.net	322	2	0	320
5	news-out.mixmin.net	318	4	0	314
6	csiph-out.feeder.erje.net	302	46	9	247
7	feeder.eternal-september.org	154	46	0	108
8	newsfeed.neva.ru	139	56	0	83
9	csiph-out.news.weretis.net	81	7	0	74
10	news-out.neodome.net	32	2	0	30
11	news-out.aioe.org	30	19	0	11
12	news.swapon.de	17	0	0	17
13	csiph-out.news.tnetconsulting.net	16	15	0	1
14	newsfeeds.xmission.com	14	1	0	13
15	news.fcku.it	12	12	0	0
TOTAL: 15		4038	366	9	3663

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	80
lada.talk	51
alt.news.misc	42
it-alt.sport.calcio.juventus	27
spline.eisfair	20
alt.slack.goathead	13
alt.computer.hardware	13
alt.binaries.pictures.purebeauty	12
lada.auto	9
alt.job	8
alt.bbs.synchronet	8
alt.finance	7
alt.politics.usa	7
alt.survival	7
alt.politics	5
cakewalk.audio	5
at.jobs	5
al.christnet.bible	3
alt.comp.lang.raku	3
alt.christian.bible	3
TOTAL: 52	366

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1863
EMP (phn path)	313
Binary: misplaced binary	237
EMP (phl)	204
Too many newsgroups	180
Binary Payload	58
EMP (phn nph)	47
0	18
Scoring filter	6
HTML Multipart	1
TOTAL: 10	2927

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.news.xs4all.nl	1
	TOTAL: 1	1
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	feeder.eternal-september.org	89
	csiph-out.feeder.erje.net	63
	csiph-out.feed.uzoreto.com	60
	csiph-out.news.xs4all.nl	43
	news.freedyn.net	19
	csiph-out.news.weretis.net	11
	newsfeeds.xmission.com	7
	news-out.mixmin.net	3
	news-out.neodome.net	3
	news.swapon.de	1
	TOTAL: 11	300
Including strange strings
	newsfeed.fu-berlin.de	341
	csiph-out.news.xs4all.nl	43
	news.swapon.de	14
	csiph-out.feed.uzoreto.com	9
	csiph-out.news.weretis.net	8
	newsfeeds.xmission.com	6
	news-out.aioe.org	4
	csiph-out.feeder.erje.net	3
	newsfeed.neva.ru	2
	news-out.mixmin.net	2
	TOTAL: 10	432
No colon-space in header
	csiph-out.news.xs4all.nl	1
	csiph-out.feed.uzoreto.com	1
	csiph-out.feeder.erje.net	1
	TOTAL: 3	3
TOTAL: 5		781

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	fu-berlin.de	13528	407	13121	0	0	3%	23:59:50
2	news.bbs.nz	27169	139	13956	13	0	0%	23:59:50
3	xmission	15136	70	11992	5	0	0%	23:59:50
4	newsfeed.xs4all.nl	12998	59	11956	0	104	0%	23:54:17
5	erje	14281	42	13073	36	7	0%	23:59:50
6	tnet	14933	22	14065	2	0	0%	23:55:44
7	weretis.net	13400	16	12997	4	0	0%	23:59:50
8	news.uzoreto.com	13232	5	13096	17	0	0%	23:50:07
9	news.freedyn.net	14002	5	13806	48	0	0%	23:59:50
10	aioe	12275	4	11984	0	0	0%	23:59:00
11	neodome.net	14610	2	14353	0	0	0%	23:58:19
12	usenet.pasdenom.info	13943	1	13744	3	3	0%	23:59:37
13	news.swapon.de	1427	1	1417	0	0	0%	23:50:55
14	news.mixmin.net	12975	1	12826	1	0	0%	23:59:50
15	news.samoylyk.net	0	0	0	0	14541	0%	23:50:11
16	news.fcku.it	1409	0	1407	0	0	0%	20:52:52
17	eternal-september	0	0	0	0	11878	0%	23:59:50
18	neva.ru	13158	0	13069	3	0	0%	23:59:36
19	xanadu-bbs	1503	0	0	0	16109	0%	23:59:37
TOTAL: 19		209979	774	186862	132	42642	0%	452:08:55

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	fu-berlin.de	4.9 MB	0.0 KB	4.9 MB	0.1 KB/s	12.5 KB	23:59:50
2	news.bbs.nz	324.2 KB	74.5 KB	398.7 KB	0.0 KB/s	2.6 KB	23:59:50
3	newsfeed.xs4all.nl	225.0 KB	0.0 KB	225.0 KB	0.0 KB/s	3.8 KB	23:54:17
4	xmission	184.7 KB	10.6 KB	195.2 KB	0.0 KB/s	2.6 KB	23:59:50
5	erje	123.6 KB	152.0 KB	275.6 KB	0.0 KB/s	3.5 KB	23:59:50
6	tnet	51.5 KB	2.8 KB	54.2 KB	0.0 KB/s	2.3 KB	23:55:44
7	weretis.net	35.8 KB	4.6 KB	40.4 KB	0.0 KB/s	2.0 KB	23:59:50
8	news.uzoreto.com	31.7 KB	79.9 KB	111.7 KB	0.0 KB/s	5.1 KB	23:50:07
9	news.freedyn.net	23.8 KB	898.4 KB	922.2 KB	0.0 KB/s	17.4 KB	23:59:50
10	neodome.net	19.3 KB	0.0 KB	19.3 KB	0.0 KB/s	9.7 KB	23:58:19
11	aioe	14.1 KB	0.0 KB	14.1 KB	0.0 KB/s	3.5 KB	23:59:00
12	news.swapon.de	3.3 KB	0.0 KB	3.3 KB	0.0 KB/s	3.3 KB	23:50:55
13	usenet.pasdenom.info	1.7 KB	7.2 KB	8.8 KB	0.0 KB/s	2.2 KB	23:59:37
14	news.mixmin.net	1.7 KB	1.9 KB	3.5 KB	0.0 KB/s	1.8 KB	23:59:50
15	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:11
16	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:52:52
17	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:50
18	neva.ru	0.0 KB	8.5 KB	8.5 KB	0.0 KB/s	2.8 KB	23:59:36
19	xanadu-bbs	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:37
TOTAL: 19		6.0 MB	1.2 MB	7.2 MB	0.0 KB/s	8.1 KB	452:08:55

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	208.72.53.129	12	1	1.4 KB	1	0	0	04:47:12
TOTAL: 1		12	1	1.4 KB	1	0	0	38:19:31

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	309	1	1.4 KB	1	30:28:20
2	*.upc-a.chello.nl	45	0	0.0 KB	0	07:24:42
3	*.range86-139.btcentralplus.com	4	0	0.0 KB	0	00:26:21
4	*.security.ipip.net	1	0	0.0 KB	0	00:00:01
5	*.dynamic.chello.pl	2	0	0.0 KB	0	00:00:03
6	*.50-31-21.static.steadfastdns.net	2	0	0.0 KB	0	00:00:02
TOTAL: 6		363	1	1.4 KB	1	38:19:31

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
91.187.33.87	17.221	3.832	23:54:05
a131033.upc-a.chello.nl	2.720	0.536	07:24:42
208.72.53.129	0.872	0.186	04:47:12
172.83.100.218	0.269	0.043	01:30:12
host86-139-107-9.range86-139.btcentralplus.com	0.259	0.047	00:26:21
87.140.194.0	0.227	0.034	00:16:27
192.241.206.14	0.059	0.015	00:00:09
123.160.221.42	0.057	0.016	00:00:05
123.160.221.10	0.058	0.015	00:00:05
87-206-178-190.dynamic.chello.pl	0.124	0.022	00:00:03
ip10.50-31-21.static.steadfastdns.net	0.125	0.022	00:00:02
scan-141.security.ipip.net	0.058	0.014	00:00:01
TOTAL: 12	22.049	4.782	38:19:31

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	288
a131033.upc-a.chello.nl	45
host86-139-107-9.range86-139.btcentralplus.com	4
87.140.194.0	3
172.83.100.218	3
ip10.50-31-21.static.steadfastdns.net	2
87-206-178-190.dynamic.chello.pl	2
123.160.221.10	1
scan-141.security.ipip.net	1
192.241.206.14	1
123.160.221.42	1
TOTAL: 11	351

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
TOTAL: 1	4

NNRP unrecognized commands (by command):

Command	Count
Connection: close	1
Host: 69.160.37.58:119	1
User-Agent: HTTP Banner Detection (https...	1
GET / HTTP/1.1	1
TOTAL: 4	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
208.72.53.129	7	0
172.83.100.218	3	0
TOTAL: 2	10	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	1	100.0%
TOTAL: 1		1	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	1
TOTAL: 1		1

Daily Usenet report for csiph.com

Apr 13 04:15:00 -- Apr 14 04:15:00