Daily Usenet report for csiph.com: Jan 8 04:15:00

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Jan 8 11:42:23 csiph nnrpd[35347]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 8 11:42:23 csiph nnrpd[35347]: 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]
Jan 8 12:48:40 csiph nnrpd[39043]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 8 12:48:40 csiph nnrpd[39043]: 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]
Jan 8 13:38:57 csiph nnrpd[41624]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 8 13:38:57 csiph nnrpd[41624]: 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]
Jan 8 15:20:09 csiph nnrpd[46899]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 8 15:20:09 csiph nnrpd[46899]: 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]
Jan 8 21:00:12 csiph nnrpd[64741]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 8 21:00:12 csiph nnrpd[64741]: 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
inn	20435	50.9%	5.1 MB	65.8%
innd	13888	34.6%	1.8 MB	23.3%
innfeed	3578	8.9%	639.4 KB	8.0%
nnrpd	2251	5.6%	237.5 KB	3.0%
nocem	5	0.0%	0.5 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	40158	100.0%	7.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	303543	49.1%
Negative hits	293875	47.6%
Do not exist	19895	3.2%
Cache misses	321	0.1%
TOTAL: 4	617634	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.276	0.0%	24	0.000	11.500	26.000
article cleanup	00:00:00.124	0.0%	19625	0.000	0.006	0.053
article logging	00:00:00.734	0.0%	20139	0.000	0.036	0.667
article parse	00:00:01.227	0.0%	63659	0.000	0.019	0.390
article write	00:00:52.386	0.1%	16407	0.254	3.193	60.333
artlog/artcncl	00:00:00.031	0.0%	24	0.000	1.292	4.429
artlog/artparse	00:00:00.006	0.0%	277	0.000	0.022	0.500
data move	00:00:00.215	0.0%	571204	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.021	0.0%	23	0.000	0.913	15.000
hishave/artcncl	00:00:00.001	0.0%	24	0.000	0.042	0.143
hishave/artparse	00:00:00.000	0.0%	259	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:31.065	0.0%	617931	0.000	0.050	0.340
history sync	00:00:01.249	0.0%	376	0.000	3.322	53.500
history write	00:00:45.197	0.1%	19637	0.529	2.302	12.023
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.671	0.0%	259	0.000	2.591	33.000
idle	23:55:12.952	99.6%	570110	75.263	151.046	649.504
nntp read	00:00:02.341	0.0%	576473	0.002	0.004	0.010
overview write	00:02:24.491	0.2%	16407	0.153	8.807	550.185
perl filter	00:00:29.083	0.0%	19619	0.982	1.482	2.510
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.276	0.0%	32819	0.000	0.008	0.043
TOTAL: 24:01:01.676	24:00:22.346	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.033	0.0%	16472	0.000	0.002	0.024
article prepare	00:00:00.001	0.0%	13320	0.000	0.000	0.019
article read	00:00:00.544	0.0%	1365	0.000	0.399	22.500
backlog stats	00:00:00.181	0.0%	345752	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.261	0.0%	275948	0.000	0.005	0.012
data write	00:00:01.652	0.0%	263655	0.000	0.006	0.015
idle	23:54:18.961	100.0%	345752	122.357	248.904	842.049
status file	00:00:00.328	0.0%	307	0.000	1.068	71.000
TOTAL: 23:54:29.694	23:54:22.961	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	58:28:43.444	99.6%	1376	729.000	152996.689	600023.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.033	0.0%	3311	0.000	0.010	0.167
readart	00:00:00.652	0.0%	43	0.000	15.163	43.000
TOTAL: 58:41:28.393	58:28:44.129	99.6%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.xs4all.nl	0	8730	4672	2661	1397	53%	76:00:39
2	feeder.eternal-september.org	12	4312	2561	1660	91	59%	27:04:43
3	news-out.mixmin.net	3	17365	2136	14919	310	12%	24:09:35
4	csiph-out.feeder.erje.net	1441	81116	1594	78990	532	1%	118:41:16
5	csiph-out.news.weretis.net	4	34077	1467	32184	426	4%	49:10:54
6	newsfeed.fu-berlin.de	19	17889	1045	16642	202	5%	29:49:34
7	newsfeed.neva.ru	145	9109	789	7998	322	8%	71:52:45
8	newsfeeds.xmission.com	3	16916	605	16308	3	3%	27:48:54
9	csiph-out.feed.uzoreto.com	864	26526	468	25739	319	1%	71:17:11
10	news-out.aioe.org	3	15549	378	15137	34	2%	24:09:49
11	news.fcku.it	11	1692	362	1322	8	21%	22:05:10
12	news-out.neodome.net	2	17775	324	17418	33	1%	23:59:51
13	nntp.pasdenom.info	5	10773	179	10593	1	1%	23:54:04
14	news.freedyn.net	2	18951	91	18717	143	0%	23:59:47
15	news.swapon.de	6	1637	59	1577	1	3%	24:30:42
16	csiph-out.news.tnetconsulting.net	2	16459	48	16404	7	0%	23:59:55
17	localhost	32	6	6	0	0	100%	00:00:00
18	news.bbs.nz	2	16061	4	16057	0	0%	23:59:52
19	news.samoylyk.net	1	15178	3	15173	2	0%	23:26:26
20	xanadu-bbs.net	25	452	2	450	0	0%	21:11:17
TOTAL: 20		2582	330573	16793	309949	3831	5%	731:12:24

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.xs4all.nl	13.4 MB	3.1 KB	9.2 MB	22.6 MB	59%	3.8 KB
2	csiph-out.feeder.erje.net	9.7 MB	13.2 KB	3.7 MB	13.4 MB	72%	6.4 KB
3	feeder.eternal-september.org	6.7 MB	0.0 KB	861.4 KB	7.6 MB	88%	2.9 KB
4	news-out.mixmin.net	4.9 MB	0.0 KB	1.1 MB	6.1 MB	81%	2.5 KB
5	csiph-out.news.weretis.net	4.2 MB	0.0 KB	2.6 MB	6.8 MB	61%	3.7 KB
6	newsfeed.fu-berlin.de	2.7 MB	0.0 KB	650.2 KB	3.3 MB	80%	2.7 KB
7	newsfeed.neva.ru	2.2 MB	0.0 KB	914.1 KB	3.1 MB	70%	2.8 KB
8	csiph-out.feed.uzoreto.com	1.9 MB	0.0 KB	6.1 MB	8.1 MB	23%	10.5 KB
9	newsfeeds.xmission.com	1.5 MB	0.0 KB	81.9 KB	1.6 MB	95%	2.7 KB
10	news-out.aioe.org	1.5 MB	0.0 KB	103.3 KB	1.6 MB	93%	4.0 KB
11	news-out.neodome.net	1023.3 KB	0.0 KB	951.3 KB	1.9 MB	51%	5.5 KB
12	news.fcku.it	668.0 KB	0.0 KB	16.4 KB	684.3 KB	97%	1.8 KB
13	nntp.pasdenom.info	374.6 KB	0.0 KB	1.2 KB	375.8 KB	99%	2.1 KB
14	news.freedyn.net	279.0 KB	0.0 KB	5.6 MB	5.9 MB	4%	25.9 KB
15	csiph-out.news.tnetconsulting.net	187.7 KB	0.0 KB	208.9 KB	396.6 KB	47%	7.2 KB
16	news.swapon.de	142.8 KB	0.0 KB	54.6 KB	197.4 KB	72%	3.3 KB
17	localhost	14.8 KB	0.0 KB	0.0 KB	14.8 KB	100%	2.5 KB
18	news.samoylyk.net	13.6 KB	0.0 KB	121.9 KB	135.5 KB	10%	27.1 KB
19	news.bbs.nz	7.4 KB	0.0 KB	0.0 KB	7.4 KB	100%	1.9 KB
20	xanadu-bbs.net	4.3 KB	0.0 KB	0.0 KB	4.3 KB	100%	2.2 KB
TOTAL: 20		51.4 MB	16.4 KB	32.3 MB	83.6 MB	61%	4.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 8 04:15:00 - 04:59:59	160	1.0%	0.06	461.2 KB	0.9%	0.17
Jan 8 05:00:00 - 05:59:59	339	2.1%	0.09	2.2 MB	4.3%	0.61
Jan 8 06:00:00 - 06:59:59	326	2.0%	0.09	1.2 MB	2.4%	0.34
Jan 8 07:00:00 - 07:59:59	459	2.8%	0.13	1.3 MB	2.6%	0.38
Jan 8 08:00:00 - 08:59:59	648	4.0%	0.18	1.9 MB	3.8%	0.54
Jan 8 09:00:00 - 09:59:59	761	4.6%	0.21	2.2 MB	4.3%	0.62
Jan 8 10:00:00 - 10:59:59	854	5.2%	0.24	2.4 MB	4.9%	0.70
Jan 8 11:00:00 - 11:59:59	835	5.1%	0.23	2.1 MB	4.2%	0.60
Jan 8 12:00:00 - 12:59:59	764	4.7%	0.21	2.1 MB	4.2%	0.60
Jan 8 13:00:00 - 13:59:59	941	5.7%	0.26	2.9 MB	5.7%	0.82
Jan 8 14:00:00 - 14:59:59	1127	6.9%	0.31	3.1 MB	6.2%	0.89
Jan 8 15:00:00 - 15:59:59	1053	6.4%	0.29	3.4 MB	6.7%	0.96
Jan 8 16:00:00 - 16:59:59	900	5.5%	0.25	2.7 MB	5.4%	0.77
Jan 8 17:00:00 - 17:59:59	1135	6.9%	0.32	3.8 MB	7.6%	1.09
Jan 8 18:00:00 - 18:59:59	934	5.7%	0.26	2.7 MB	5.4%	0.77
Jan 8 19:00:00 - 19:59:59	788	4.8%	0.22	2.4 MB	4.8%	0.68
Jan 8 20:00:00 - 20:59:59	733	4.5%	0.20	2.3 MB	4.6%	0.65
Jan 8 21:00:00 - 21:59:59	639	3.9%	0.18	1.8 MB	3.6%	0.52
Jan 8 22:00:00 - 22:59:59	668	4.1%	0.19	1.9 MB	3.8%	0.55
Jan 8 23:00:00 - 23:59:59	604	3.7%	0.17	2.0 MB	4.1%	0.58
Jan 9 00:00:00 - 00:59:59	538	3.3%	0.15	1.6 MB	3.3%	0.47
Jan 9 01:00:00 - 01:59:59	434	2.6%	0.12	1.2 MB	2.4%	0.34
Jan 9 02:00:00 - 02:59:59	339	2.1%	0.09	1.2 MB	2.4%	0.34
Jan 9 03:00:00 - 03:59:59	345	2.1%	0.10	1.1 MB	2.1%	0.31
Jan 9 04:00:00 - 04:15:00	73	0.4%	0.08	193.6 KB	0.4%	0.22
TOTAL: 24:00:00	16397	100.0%	0.19	50.3 MB	100.0%	0.60

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1428	96	1	1331
2	newsfeed.fu-berlin.de	587	25	0	562
3	csiph-out.news.weretis.net	402	106	0	296
4	newsfeed.neva.ru	324	45	0	279
5	csiph-out.feed.uzoreto.com	321	18	0	303
6	news-out.mixmin.net	319	8	0	311
7	csiph-out.feeder.erje.net	266	81	5	180
8	news.freedyn.net	140	0	0	140
9	feeder.eternal-september.org	96	57	0	39
10	news-out.aioe.org	37	26	0	11
11	news-out.neodome.net	36	8	0	28
12	news.swapon.de	32	0	0	32
13	newsfeeds.xmission.com	14	1	0	13
14	news.fcku.it	8	8	0	0
15	news.samoylyk.net	2	0	0	2
16	csiph-out.news.tnetconsulting.net	1	0	0	1
17	nntp.pasdenom.info	1	0	0	1
TOTAL: 17		4014	479	6	3529

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	72
alt.politics	52
alt.tv.comedy-central.daily-show	51
lada.talk	27
alt.binaries.pictures.purebeauty	24
it-alt.sport.calcio.juventus	20
alt.survival	19
mozilla.support.seamonkey	15
scinet.general.chatter	15
scinet.stats	15
spline.eisfair	14
at.jobs	13
alt.job	10
att.jobs	9
mozilla.support.firefox	8
scinet.sysop	8
trollnet.cinema	7
alt.philosophy	7
lada.auto	6
mozilla.general	6
TOTAL: 65	479

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1824
EMP (phn path)	504
EMP (phl)	134
EMP (phn nph)	117
Too many newsgroups	62
Binary Payload	40
Binary: misplaced binary	20
0	18
HTML Multipart	10
Scoring filter	4
TOTAL: 10	2733

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	csiph-out.feeder.erje.net	61
	csiph-out.news.xs4all.nl	60
	news.freedyn.net	50
	csiph-out.feed.uzoreto.com	40
	news-out.neodome.net	15
	csiph-out.news.weretis.net	15
	news-out.mixmin.net	11
	feeder.eternal-september.org	5
	newsfeed.fu-berlin.de	3
	news.samoylyk.net	2
	TOTAL: 12	264
Including strange strings
	newsfeed.fu-berlin.de	405
	news.swapon.de	31
	csiph-out.news.xs4all.nl	29
	newsfeeds.xmission.com	13
	news-out.mixmin.net	11
	csiph-out.news.weretis.net	10
	csiph-out.feeder.erje.net	5
	feeder.eternal-september.org	5
	csiph-out.feed.uzoreto.com	2
	newsfeed.neva.ru	2
	TOTAL: 11	514
No colon-space in header
	csiph-out.news.xs4all.nl	16
	csiph-out.feeder.erje.net	2
	TOTAL: 2	18
TOTAL: 4		841

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	18349	884	15508	1	0	4%	23:59:56
2	fu-berlin.de	15057	612	14445	0	0	4%	23:59:56
3	news.samoylyk.net	15908	557	14980	11	846	3%	23:59:56
4	news.bbs.nz	30607	475	15267	32	2	1%	23:59:28
5	tnet	17162	121	15658	1	0	0%	23:59:56
6	xmission	18068	97	15079	1	0	0%	23:59:26
7	newsfeed.xs4all.nl	12472	60	11691	0	9	0%	23:59:55
8	usenet.pasdenom.info	16025	22	15514	61	90	0%	23:59:56
9	news.freedyn.net	15121	10	14743	51	0	0%	23:59:56
10	weretis.net	14372	9	14187	0	0	0%	23:59:47
11	news.mixmin.net	13542	9	13227	0	0	0%	23:59:51
12	neodome.net	16317	8	15959	0	0	0%	23:59:24
13	erje	14504	7	14341	85	0	0%	23:59:42
14	aioe	13348	7	13034	0	0	0%	23:59:49
15	neva.ru	14825	6	14543	178	0	0%	23:59:56
16	news.swapon.de	1606	6	1585	0	0	0%	23:43:19
17	news.uzoreto.com	14895	6	14617	18	0	0%	23:59:29
18	eternal-september	0	0	0	0	13125	0%	23:59:56
19	news.fcku.it	1285	0	1285	0	0	0%	22:18:10
TOTAL: 19		263463	2896	235663	439	14072	1%	453:57:48

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	7.5 MB	27.7 KB	7.6 MB	0.1 KB/s	8.8 KB	23:59:56
2	fu-berlin.de	6.9 MB	0.0 KB	6.9 MB	0.1 KB/s	11.5 KB	23:59:56
3	news.samoylyk.net	6.5 MB	62.8 KB	6.5 MB	0.1 KB/s	11.8 KB	23:59:56
4	news.bbs.nz	1.2 MB	110.1 KB	1.3 MB	0.0 KB/s	2.6 KB	23:59:28
5	tnet	301.8 KB	3.8 KB	305.6 KB	0.0 KB/s	2.5 KB	23:59:56
6	xmission	265.0 KB	1.6 KB	266.6 KB	0.0 KB/s	2.7 KB	23:59:26
7	newsfeed.xs4all.nl	185.3 KB	0.0 KB	185.3 KB	0.0 KB/s	3.1 KB	23:59:55
8	usenet.pasdenom.info	55.7 KB	115.7 KB	171.4 KB	0.0 KB/s	2.1 KB	23:59:56
9	news.freedyn.net	29.0 KB	718.9 KB	747.8 KB	0.0 KB/s	12.3 KB	23:59:56
10	weretis.net	26.2 KB	0.0 KB	26.2 KB	0.0 KB/s	2.9 KB	23:59:47
11	news.mixmin.net	19.2 KB	0.0 KB	19.2 KB	0.0 KB/s	2.1 KB	23:59:51
12	aioe	17.4 KB	0.0 KB	17.4 KB	0.0 KB/s	2.5 KB	23:59:49
13	neodome.net	16.6 KB	0.0 KB	16.6 KB	0.0 KB/s	2.1 KB	23:59:24
14	news.swapon.de	14.8 KB	0.0 KB	14.8 KB	0.0 KB/s	2.5 KB	23:43:19
15	neva.ru	14.4 KB	441.7 KB	456.1 KB	0.0 KB/s	2.5 KB	23:59:56
16	erje	14.1 KB	252.8 KB	266.8 KB	0.0 KB/s	2.9 KB	23:59:42
17	news.uzoreto.com	13.7 KB	147.3 KB	161.0 KB	0.0 KB/s	6.7 KB	23:59:29
18	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:56
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:18:10
TOTAL: 19		23.1 MB	1.8 MB	24.9 MB	0.0 KB/s	7.6 KB	453:57:48

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	208.72.53.144	19	15	33.6 KB	16	6	6	07:41:27
2	91.187.33.87	224	11	23.8 KB	13	0	0	36:15:46
3	a131033.upc-a.chello.nl	43	10	20.4 KB	5	0	0	09:02:38
4	172.83.100.194	3	7	13.5 KB	6	0	1	01:05:18
TOTAL: 4		289	43	91.3 KB	40	6	7	58:41:26

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	246	33	70.9 KB	35	6	7	45:02:32
2	*.upc-a.chello.nl	43	10	20.4 KB	5	0	0	09:02:38
3	*.net.upcbroadband.cz	55	0	0.0 KB	0	0	0	04:33:31
4	*.range86-139.btcentralplus.com	5	0	0.0 KB	0	0	0	00:02:43
TOTAL: 4		349	43	91.3 KB	40	6	7	58:41:26

NNRP auth users [Top 20]:

User	Conn
kev009	6
TOTAL: 1	6

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	14.424	3.425	0.000	36:15:46
a131033.upc-a.chello.nl	3.026	0.633	0.000	09:02:38
208.72.53.144	1.794	0.347	0.025	07:41:27
ip-78-102-198-192.net.upcbroadband.cz	3.787	0.762	0.000	04:33:31
172.83.100.194	0.294	0.084	0.000	01:05:18
host86-139-107-71.range86-139.btcentralplus.com	0.306	0.090	0.000	00:02:43
TOTAL: 6	23.631	5.341	0.025	58:41:26

Curious NNRP server explorers [Top 20]:

System	Conn
ip-78-102-198-192.net.upcbroadband.cz	55
host86-139-107-71.range86-139.btcentralplus.com	5
TOTAL: 2	60

NNRP client timeouts [Top 20]:

System	Conn	Peer
208.72.53.144	12	0
172.83.100.194	2	0
TOTAL: 2	14	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	42	91.3%
2	news	4	8.7%
TOTAL: 2		46	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	42
2	news.admin.peering	4
TOTAL: 2		46

Daily Usenet report for csiph.com

Jan 8 04:15:00 -- Jan 9 04:15:00