Daily Usenet report for csiph.com: Nov 16 04:15:00

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Nov 16 12:03:25 csiph nnrpd[62789]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 16 12:03:25 csiph nnrpd[62789]: 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]
Nov 16 12:07:53 csiph nnrpd[63005]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 16 12:07:53 csiph nnrpd[63005]: 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]
Nov 16 13:06:04 csiph nnrpd[65261]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 16 13:06:04 csiph nnrpd[65261]: 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]
Nov 16 17:08:50 csiph nnrpd[74293]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 16 17:08:50 csiph nnrpd[74293]: 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	19367	48.3%	4.9 MB	63.1%
innd	15900	39.7%	2.1 MB	26.8%
innfeed	3485	8.7%	660.9 KB	8.3%
nnrpd	1273	3.2%	138.2 KB	1.7%
nocem	32	0.1%	3.7 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	40058	100.0%	7.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	314793	55.5%
Negative hits	233277	41.1%
Do not exist	18695	3.3%
Cache misses	227	0.0%
TOTAL: 4	566992	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.284	0.0%	21	0.000	13.524	28.000
article cleanup	00:00:00.134	0.0%	18557	0.000	0.007	0.037
article logging	00:00:00.457	0.0%	19092	0.000	0.024	0.915
article parse	00:00:01.449	0.0%	55022	0.000	0.026	0.254
article write	00:00:27.337	0.0%	15260	0.141	1.791	70.514
artlog/artcncl	00:00:00.020	0.0%	21	0.000	0.952	5.000
artlog/artparse	00:00:00.003	0.0%	256	0.000	0.012	1.000
data move	00:00:01.482	0.0%	533162	0.000	0.003	0.240
hisgrep/artcncl	00:00:00.022	0.0%	20	0.000	1.100	16.000
hishave/artcncl	00:00:00.016	0.0%	21	0.000	0.762	16.000
hishave/artparse	00:00:00.000	0.0%	250	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:57.641	0.1%	569528	0.001	0.101	0.711
history sync	00:00:01.308	0.0%	346	0.000	3.780	132.000
history write	00:01:11.937	0.1%	18539	0.500	3.880	26.348
hiswrite/artcncl	00:00:00.012	0.0%	1	12.000	12.000	12.000
hiswrite/artparse	00:00:00.940	0.0%	250	0.000	3.760	30.000
idle	23:52:33.851	99.4%	532303	90.157	161.475	485.617
nntp read	00:00:03.050	0.0%	540788	0.001	0.006	0.096
overview write	00:04:03.174	0.3%	15260	0.071	15.935	4831.906
perl filter	00:00:39.688	0.0%	18533	1.113	2.141	10.910
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.262	0.0%	30551	0.000	0.009	0.032
TOTAL: 24:00:46.347	24:00:03.067	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.033	0.0%	15302	0.000	0.002	0.020
article prepare	00:00:00.001	0.0%	12540	0.000	0.000	0.010
article read	00:00:00.586	0.0%	1229	0.000	0.477	43.000
backlog stats	00:00:00.223	0.0%	354692	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.367	0.0%	279728	0.001	0.005	0.010
data write	00:00:01.723	0.0%	267032	0.001	0.006	0.014
idle	23:55:02.038	100.0%	354692	137.431	242.752	1048.045
status file	00:00:00.174	0.0%	253	0.000	0.688	2.000
TOTAL: 23:55:12.666	23:55:06.145	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	57:16:08.185	99.9%	966	64.000	213424.622	600104.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.013	0.0%	2325	0.000	0.006	0.500
readart	00:00:00.546	0.0%	71	0.000	7.690	42.000
TOTAL: 57:19:55.572	57:16:08.744	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	2	15107	2769	12287	51	18%	25:39:41
2	feeder.eternal-september.org	2	17179	2532	14472	175	14%	24:07:59
3	csiph-out.news.xs4all.nl	499	18043	2111	14623	1309	11%	68:10:01
4	news-out.aioe.org	3	14622	2000	12504	118	13%	24:08:53
5	news-out.mixmin.net	2	16645	1794	14638	213	10%	24:08:56
6	csiph-out.feeder.erje.net	1440	74860	1074	73609	177	1%	118:40:59
7	newsfeed.fu-berlin.de	11	16604	819	15497	288	4%	28:30:51
8	newsfeed.neva.ru	148	13364	630	12458	276	4%	71:37:34
9	csiph-out.news.weretis.net	4	30340	490	29674	176	1%	47:59:04
10	csiph-out.feed.uzoreto.com	865	24408	370	23343	695	1%	71:11:36
11	news.fcku.it	12	1838	310	1527	1	16%	22:14:54
12	news-out.neodome.net	1	16976	146	16812	18	0%	23:59:55
13	nntp.pasdenom.info	2	10263	129	10124	10	1%	23:59:53
14	news.swapon.de	8	1351	70	1281	0	5%	22:52:18
15	csiph-out.news.tnetconsulting.net	1	14056	26	14010	20	0%	23:59:58
16	news.freedyn.net	2	18130	23	18062	45	0%	23:59:50
17	localhost	48	22	22	0	0	100%	00:00:02
18	news.samoylyk.net	5	14847	6	14837	4	0%	24:54:34
19	news.bbs.nz	2	13813	2	13811	0	0%	23:59:36
20	xanadu-bbs.net	38	359	0	358	1	0%	20:44:24
TOTAL: 20		3095	332827	15323	313927	3577	4%	715:00:58

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	8.3 MB	2.1 KB	2.1 MB	10.4 MB	79%	3.8 KB
2	feeder.eternal-september.org	7.2 MB	9.2 KB	1.8 MB	9.0 MB	80%	3.4 KB
3	csiph-out.feeder.erje.net	6.5 MB	12.1 KB	2.4 MB	8.9 MB	73%	7.3 KB
4	news-out.aioe.org	6.0 MB	5.1 KB	346.1 KB	6.3 MB	94%	3.0 KB
5	csiph-out.news.xs4all.nl	5.6 MB	3.0 KB	5.6 MB	11.2 MB	49%	3.4 KB
6	news-out.mixmin.net	4.2 MB	3.0 KB	521.2 KB	4.7 MB	89%	2.4 KB
7	newsfeed.fu-berlin.de	1.9 MB	0.0 KB	645.4 KB	2.5 MB	74%	2.3 KB
8	newsfeed.neva.ru	1.6 MB	3.0 KB	1.6 MB	3.2 MB	49%	3.6 KB
9	csiph-out.feed.uzoreto.com	1.3 MB	6.0 KB	9.8 MB	11.1 MB	12%	10.7 KB
10	csiph-out.news.weretis.net	1.3 MB	7.9 KB	1.8 MB	3.2 MB	41%	4.9 KB
11	news.fcku.it	540.9 KB	0.0 KB	1.3 KB	542.2 KB	99%	1.7 KB
12	news-out.neodome.net	404.6 KB	11.4 KB	330.4 KB	746.5 KB	54%	4.6 KB
13	nntp.pasdenom.info	268.8 KB	0.0 KB	12.9 KB	281.7 KB	95%	2.0 KB
14	news.swapon.de	161.8 KB	0.0 KB	0.0 KB	161.8 KB	100%	2.3 KB
15	news.freedyn.net	107.3 KB	3.1 KB	1.4 MB	1.5 MB	7%	22.0 KB
16	csiph-out.news.tnetconsulting.net	78.4 KB	0.0 KB	172.9 KB	251.3 KB	31%	5.5 KB
17	localhost	35.3 KB	0.0 KB	0.0 KB	35.3 KB	100%	1.6 KB
18	news.samoylyk.net	16.8 KB	3.0 KB	192.1 KB	211.9 KB	7%	21.2 KB
19	news.bbs.nz	2.8 KB	0.0 KB	0.0 KB	2.8 KB	100%	1.4 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	70.7 KB	70.7 KB	0%	70.7 KB
TOTAL: 20		45.5 MB	69.1 KB	28.7 MB	74.2 MB	61%	4.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 16 04:15:00 - 04:59:59	168	1.1%	0.06	573.9 KB	1.2%	0.21
Nov 16 05:00:00 - 05:59:59	324	2.1%	0.09	2.2 MB	4.9%	0.63
Nov 16 06:00:00 - 06:59:59	350	2.3%	0.10	1.1 MB	2.4%	0.32
Nov 16 07:00:00 - 07:59:59	425	2.8%	0.12	1.1 MB	2.5%	0.32
Nov 16 08:00:00 - 08:59:59	605	4.0%	0.17	1.7 MB	3.8%	0.49
Nov 16 09:00:00 - 09:59:59	722	4.7%	0.20	2.1 MB	4.5%	0.59
Nov 16 10:00:00 - 10:59:59	736	4.8%	0.20	1.7 MB	3.8%	0.50
Nov 16 11:00:00 - 11:59:59	821	5.4%	0.23	2.1 MB	4.7%	0.61
Nov 16 12:00:00 - 12:59:59	727	4.8%	0.20	2.0 MB	4.5%	0.58
Nov 16 13:00:00 - 13:59:59	845	5.5%	0.23	2.4 MB	5.3%	0.69
Nov 16 14:00:00 - 14:59:59	913	6.0%	0.25	2.4 MB	5.4%	0.69
Nov 16 15:00:00 - 15:59:59	966	6.3%	0.27	3.4 MB	7.4%	0.96
Nov 16 16:00:00 - 16:59:59	958	6.3%	0.27	2.4 MB	5.4%	0.69
Nov 16 17:00:00 - 17:59:59	898	5.9%	0.25	2.7 MB	6.0%	0.77
Nov 16 18:00:00 - 18:59:59	868	5.7%	0.24	2.7 MB	5.9%	0.76
Nov 16 19:00:00 - 19:59:59	740	4.8%	0.21	2.1 MB	4.6%	0.59
Nov 16 20:00:00 - 20:59:59	823	5.4%	0.23	2.5 MB	5.6%	0.72
Nov 16 21:00:00 - 21:59:59	710	4.7%	0.20	1.9 MB	4.3%	0.55
Nov 16 22:00:00 - 22:59:59	723	4.7%	0.20	2.1 MB	4.5%	0.59
Nov 16 23:00:00 - 23:59:59	466	3.1%	0.13	1.4 MB	3.0%	0.39
Nov 17 00:00:00 - 00:59:59	433	2.8%	0.12	1.4 MB	3.2%	0.41
Nov 17 01:00:00 - 01:59:59	332	2.2%	0.09	1.2 MB	2.5%	0.33
Nov 17 02:00:00 - 02:59:59	327	2.1%	0.09	963.5 KB	2.1%	0.27
Nov 17 03:00:00 - 03:59:59	303	2.0%	0.08	948.1 KB	2.0%	0.26
Nov 17 04:00:00 - 04:15:00	75	0.5%	0.08	208.3 KB	0.4%	0.23
TOTAL: 24:00:00	15258	100.0%	0.18	45.4 MB	100.0%	0.54

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1328	67	1	1260
2	csiph-out.feed.uzoreto.com	696	36	2	658
3	newsfeed.fu-berlin.de	605	12	0	593
4	newsfeed.neva.ru	279	96	1	182
5	news-out.mixmin.net	259	19	1	239
6	csiph-out.news.weretis.net	234	74	2	158
7	feeder.eternal-september.org	178	46	3	129
8	news-out.aioe.org	171	99	2	70
9	csiph-out.feeder.erje.net	151	46	4	101
10	newsfeeds.xmission.com	55	16	1	38
11	news.freedyn.net	44	0	1	43
12	news.swapon.de	29	0	0	29
13	news-out.neodome.net	23	0	4	19
14	csiph-out.news.tnetconsulting.net	20	18	1	1
15	nntp.pasdenom.info	10	0	0	10
16	news.samoylyk.net	4	0	1	3
17	xanadu-bbs.net	1	0	0	1
18	news.fcku.it	1	1	0	0
TOTAL: 18		4088	530	24	3534

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	172
lada.talk	51
alt.politics	33
scinet.stats	27
mozilla.support.thunderbird	19
alt.bbs.synchronet	17
lada.auto	12
at.jobs	12
mozilla.support.firefox	11
alt.survival	10
spline.eisfair	10
alt.binaries.pictures.purebeauty	10
att.jobs	8
mozilla.support.seamonkey	8
mozilla.dev.security.policy	7
mozilla.dev.apps.seamonkey	7
alt.job	7
alt.philosophy	5
alt.math	5
alt.medical.sales.jobs.resumes	5
TOTAL: 80	530

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1969
EMP (phn path)	386
EMP (phl)	211
Too many newsgroups	85
0	51
Scoring filter	15
EMP (phn nph)	12
HTML Multipart	10
Binary: misplaced binary	4
Too many newsgroups (meow)	1
TOTAL: 10	2744

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.feed.uzoreto.com	81
	csiph-out.feeder.erje.net	42
	newsfeeds.xmission.com	31
	csiph-out.news.xs4all.nl	22
	feeder.eternal-september.org	21
	news.freedyn.net	19
	csiph-out.news.weretis.net	15
	news-out.neodome.net	12
	news.samoylyk.net	3
	news-out.mixmin.net	3
	TOTAL: 11	250
Including strange strings
	newsfeed.fu-berlin.de	361
	news-out.aioe.org	53
	news-out.mixmin.net	50
	news.swapon.de	29
	csiph-out.news.xs4all.nl	19
	newsfeeds.xmission.com	6
	csiph-out.news.weretis.net	5
	csiph-out.feeder.erje.net	4
	newsfeed.neva.ru	3
	feeder.eternal-september.org	3
	TOTAL: 11	534
No colon-space in header
	csiph-out.news.xs4all.nl	5
	csiph-out.feed.uzoreto.com	1
	TOTAL: 2	6
TOTAL: 4		835

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	18497	819	14426	13	0	4%	23:59:56
2	fu-berlin.de	14276	454	13821	0	1	3%	23:59:32
3	news.samoylyk.net	15667	445	14800	7	38	2%	23:59:56
4	news.bbs.nz	28777	326	14457	26	0	1%	23:59:56
5	erje	16232	102	13626	199	0	0%	23:59:56
6	tnet	16064	79	14734	1	0	0%	23:59:52
7	newsfeed.xs4all.nl	13546	77	13026	2	24	0%	23:59:55
8	xmission	14450	34	11992	0	0	0%	23:59:56
9	news.mixmin.net	12957	33	12661	0	0	0%	23:59:43
10	neva.ru	13871	31	13636	94	0	0%	23:59:56
11	news.freedyn.net	14802	31	14421	57	1	0%	23:59:45
12	usenet.pasdenom.info	15249	27	14655	1	0	0%	23:59:56
13	neodome.net	15387	25	14966	0	0	0%	23:59:55
14	eternal-september	12392	25	12041	0	0	0%	23:59:56
15	aioe	11289	23	10803	0	0	0%	23:59:56
16	news.swapon.de	1333	22	1298	0	0	1%	23:24:20
17	weretis.net	14502	21	14041	0	0	0%	23:59:31
18	news.uzoreto.com	14585	20	14283	143	0	0%	23:59:38
19	news.fcku.it	1466	0	1465	0	0	0%	21:37:20
TOTAL: 19		265342	2594	235152	543	64	0%	452:58:55

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	6.0 MB	73.9 KB	6.0 MB	0.1 KB/s	7.4 KB	23:59:56
2	fu-berlin.de	5.0 MB	0.0 KB	5.0 MB	0.1 KB/s	11.4 KB	23:59:32
3	news.samoylyk.net	4.9 MB	120.9 KB	5.0 MB	0.1 KB/s	11.4 KB	23:59:56
4	news.bbs.nz	882.2 KB	165.5 KB	1.0 MB	0.0 KB/s	3.0 KB	23:59:56
5	erje	223.4 KB	702.3 KB	925.7 KB	0.0 KB/s	3.1 KB	23:59:56
6	newsfeed.xs4all.nl	220.1 KB	41.6 KB	261.6 KB	0.0 KB/s	3.3 KB	23:59:55
7	tnet	175.0 KB	4.3 KB	179.4 KB	0.0 KB/s	2.2 KB	23:59:52
8	news.freedyn.net	138.2 KB	1.0 MB	1.1 MB	0.0 KB/s	13.3 KB	23:59:45
9	news.mixmin.net	124.5 KB	0.0 KB	124.5 KB	0.0 KB/s	3.8 KB	23:59:43
10	xmission	78.3 KB	0.0 KB	78.3 KB	0.0 KB/s	2.3 KB	23:59:56
11	neva.ru	61.2 KB	228.0 KB	289.2 KB	0.0 KB/s	2.3 KB	23:59:56
12	eternal-september	44.9 KB	0.0 KB	44.9 KB	0.0 KB/s	1.8 KB	23:59:56
13	usenet.pasdenom.info	43.8 KB	0.7 KB	44.5 KB	0.0 KB/s	1.6 KB	23:59:56
14	neodome.net	40.5 KB	0.0 KB	40.5 KB	0.0 KB/s	1.6 KB	23:59:55
15	aioe	39.3 KB	0.0 KB	39.3 KB	0.0 KB/s	1.7 KB	23:59:56
16	news.uzoreto.com	37.3 KB	447.7 KB	485.0 KB	0.0 KB/s	3.0 KB	23:59:38
17	news.swapon.de	35.4 KB	0.0 KB	35.4 KB	0.0 KB/s	1.6 KB	23:24:20
18	weretis.net	33.8 KB	0.0 KB	33.8 KB	0.0 KB/s	1.6 KB	23:59:31
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:37:20
TOTAL: 19		18.1 MB	2.8 MB	20.8 MB	0.0 KB/s	6.8 KB	452:58:55

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	75	37	87.1 KB	7	0	0	22:09:07
2	024-178-045-162.res.spectrum.com	37	34	80.8 KB	37	22	10	09:24:28
TOTAL: 2		112	71	167.9 KB	44	22	10	57:19:54

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.upc-a.chello.nl	75	37	87.1 KB	7	0	0	22:09:07
2	*.res.spectrum.com	37	34	80.8 KB	37	22	10	09:24:28
3	*.net.upcbroadband.cz	13	0	0.0 KB	0	0	0	03:52:02
4	unresolved	52	0	0.0 KB	0	0	0	16:49:04
5	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:00
6	*.ph.ph.cox.net	10	0	0.0 KB	0	0	0	04:26:54
7	*.range81-135.btcentralplus.com	4	0	0.0 KB	0	0	0	00:38:15
TOTAL: 7		192	71	167.9 KB	44	22	10	57:19:54

NNRP auth users [Top 20]:

User	Conn
ohlandl	10
TOTAL: 1	10

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	5.637	3.054	0.000	22:09:07
91.187.33.87	3.469	2.469	0.000	16:48:54
024-178-045-162.res.spectrum.com	3.257	1.079	0.281	09:24:28
ip72-222-200-117.ph.ph.cox.net	0.967	0.333	0.000	04:26:54
ip-78-102-198-192.net.upcbroadband.cz	1.077	0.836	0.000	03:52:02
host81-135-1-208.range81-135.btcentralplus.com	0.274	0.741	0.000	00:38:15
192.241.237.74	0.055	0.021	0.000	00:00:09
scan-141.security.ipip.net	0.070	0.008	0.000	00:00:00
TOTAL: 8	14.806	8.541	0.281	57:19:54

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	51
ip-78-102-198-192.net.upcbroadband.cz	13
ip72-222-200-117.ph.ph.cox.net	10
host81-135-1-208.range81-135.btcentralplus.com	4
192.241.237.74	1
scan-141.security.ipip.net	1
TOTAL: 6	80

NNRP unrecognized commands (by host):

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

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
024-178-045-162.res.spectrum.com	10	0
ip72-222-200-117.ph.ph.cox.net	8	0
host81-135-1-208.range81-135.btcentralplus.com	1	0
TOTAL: 3	19	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Nov 16 04:15:00 -- Nov 17 04:15:00