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

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Nov 17 16:09:58 csiph nnrpd[26690]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 17 16:09:58 csiph nnrpd[26690]: 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 17 17:31:04 csiph nnrpd[29760]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 17 17:31:04 csiph nnrpd[29760]: 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 18 02:20:27 csiph nnrpd[49273]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 18 02:20:27 csiph nnrpd[49273]: 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	18382	48.0%	4.7 MB	62.9%
innd	15469	40.4%	2.0 MB	27.4%
innfeed	3503	9.1%	632.8 KB	8.3%
nnrpd	926	2.4%	99.4 KB	1.3%
nocem	10	0.0%	1.1 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	38291	100.0%	7.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	301596	54.9%
Negative hits	229800	41.8%
Do not exist	17874	3.3%
Cache misses	279	0.1%
TOTAL: 4	549549	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.217	0.0%	21	0.000	10.333	40.600
article cleanup	00:00:00.121	0.0%	17680	0.000	0.007	0.037
article logging	00:00:00.549	0.0%	18138	0.000	0.030	1.544
article parse	00:00:01.594	0.0%	53591	0.000	0.030	0.572
article write	00:00:44.352	0.1%	14784	0.148	3.000	80.528
artlog/artcncl	00:00:00.001	0.0%	21	0.000	0.048	0.200
artlog/artparse	00:00:00.002	0.0%	229	0.000	0.009	0.500
data move	00:00:00.547	0.0%	518398	0.000	0.001	0.090
hisgrep/artcncl	00:00:00.006	0.0%	21	0.000	0.286	1.500
hishave/artcncl	00:00:00.002	0.0%	21	0.000	0.095	1.000
hishave/artparse	00:00:00.000	0.0%	226	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:41.238	0.0%	550141	0.002	0.075	0.562
history sync	00:00:01.540	0.0%	369	0.000	4.173	177.000
history write	00:00:50.322	0.1%	17674	0.708	2.847	11.599
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:01.103	0.0%	226	0.000	4.881	78.000
idle	23:55:39.087	99.6%	521062	88.700	165.314	551.747
nntp read	00:00:03.634	0.0%	527908	0.002	0.007	0.091
overview write	00:01:30.492	0.1%	14784	0.103	6.121	115.944
perl filter	00:00:40.929	0.0%	17671	1.154	2.316	14.556
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.298	0.0%	29578	0.000	0.010	0.039
TOTAL: 24:01:03.215	24:00:16.034	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.027	0.0%	14787	0.000	0.002	0.021
article prepare	00:00:00.001	0.0%	10828	0.000	0.000	0.005
article read	00:00:00.548	0.0%	1315	0.000	0.417	44.000
backlog stats	00:00:00.227	0.0%	341954	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.295	0.0%	269598	0.001	0.005	0.013
data write	00:00:01.654	0.0%	257413	0.002	0.006	0.011
idle	23:54:18.407	100.0%	341954	149.730	251.667	784.852
status file	00:00:00.366	0.0%	290	0.000	1.262	148.000
TOTAL: 23:54:30.531	23:54:22.525	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	37:44:10.685	99.8%	701	166.000	193795.556	600081.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.012	0.0%	1840	0.000	0.007	0.167
readart	00:00:00.521	0.0%	121	0.000	4.306	42.000
TOTAL: 37:48:16.098	37:44:11.218	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news-out.aioe.org	3	14133	3566	10437	130	25%	24:09:12
2	feeder.eternal-september.org	5	16608	2514	13999	95	15%	25:00:42
3	csiph-out.news.xs4all.nl	499	17247	2122	13694	1431	12%	68:25:59
4	newsfeeds.xmission.com	2	13290	2002	11252	36	15%	21:45:28
5	csiph-out.feeder.erje.net	1439	71936	1246	70485	205	1%	118:47:22
6	newsfeed.fu-berlin.de	10	13767	738	12744	285	5%	23:08:58
7	newsfeed.neva.ru	153	10945	543	10235	167	4%	71:42:46
8	csiph-out.feed.uzoreto.com	864	23192	529	22259	404	2%	71:11:48
9	news-out.mixmin.net	1	15858	505	15229	124	3%	23:59:50
10	csiph-out.news.weretis.net	3	31535	382	31002	151	1%	47:59:45
11	news.fcku.it	13	1821	275	1543	3	15%	22:26:54
12	nntp.pasdenom.info	2	10194	112	10062	20	1%	23:58:52
13	news.swapon.de	10	1221	66	1154	1	5%	23:33:29
14	csiph-out.news.tnetconsulting.net	2	15393	43	15333	17	0%	23:59:50
15	news.freedyn.net	3	17226	27	17149	50	0%	23:59:19
16	localhost	33	7	7	0	0	100%	00:00:00
17	news.bbs.nz	1	15055	6	15049	0	0%	23:59:52
18	news-out.neodome.net	2	16176	2	16165	9	0%	23:59:53
19	news.samoylyk.net	2	14342	2	14339	1	0%	23:59:37
20	xanadu-bbs.net	34	342	0	342	0	0%	20:38:11
TOTAL: 20		3081	320288	14687	302472	3129	4%	706:47:47

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.aioe.org	8.8 MB	2.5 KB	386.3 KB	9.1 MB	95%	2.5 KB
2	feeder.eternal-september.org	7.2 MB	4.7 KB	513.2 KB	7.7 MB	93%	3.0 KB
3	csiph-out.feeder.erje.net	6.8 MB	0.0 KB	2.2 MB	9.0 MB	75%	6.3 KB
4	newsfeeds.xmission.com	6.0 MB	0.0 KB	1.8 MB	7.8 MB	76%	3.9 KB
5	csiph-out.news.xs4all.nl	5.5 MB	0.0 KB	11.0 MB	16.5 MB	33%	4.8 KB
6	csiph-out.feed.uzoreto.com	1.9 MB	0.0 KB	6.9 MB	8.8 MB	21%	9.7 KB
7	newsfeed.fu-berlin.de	1.8 MB	0.0 KB	644.3 KB	2.5 MB	74%	2.5 KB
8	news-out.mixmin.net	1.7 MB	3.7 KB	282.3 KB	2.0 MB	86%	3.3 KB
9	newsfeed.neva.ru	1.4 MB	0.0 KB	491.3 KB	1.8 MB	73%	2.7 KB
10	csiph-out.news.weretis.net	1.1 MB	2.1 KB	1.5 MB	2.6 MB	41%	5.0 KB
11	news.fcku.it	482.9 KB	0.0 KB	3.6 KB	486.5 KB	99%	1.8 KB
12	nntp.pasdenom.info	264.5 KB	0.0 KB	25.6 KB	290.1 KB	91%	2.2 KB
13	csiph-out.news.tnetconsulting.net	198.7 KB	3.1 KB	31.3 KB	233.1 KB	85%	3.9 KB
14	news.swapon.de	166.4 KB	0.0 KB	0.9 KB	167.4 KB	99%	2.5 KB
15	news.freedyn.net	109.1 KB	0.0 KB	1.9 MB	2.0 MB	5%	26.2 KB
16	news-out.neodome.net	14.3 KB	4.2 KB	444.3 KB	462.8 KB	3%	42.1 KB
17	news.bbs.nz	11.2 KB	0.0 KB	0.0 KB	11.2 KB	100%	1.9 KB
18	localhost	9.4 KB	0.0 KB	0.0 KB	9.4 KB	100%	1.3 KB
19	news.samoylyk.net	2.8 KB	2.5 KB	2.5 KB	7.9 KB	36%	2.6 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		43.4 MB	22.8 KB	28.0 MB	71.5 MB	60%	4.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 17 04:15:00 - 04:59:59	220	1.5%	0.08	588.1 KB	1.3%	0.22
Nov 17 05:00:00 - 05:59:59	379	2.6%	0.11	2.2 MB	5.0%	0.63
Nov 17 06:00:00 - 06:59:59	342	2.3%	0.10	1.1 MB	2.4%	0.30
Nov 17 07:00:00 - 07:59:59	446	3.0%	0.12	1.3 MB	3.1%	0.38
Nov 17 08:00:00 - 08:59:59	626	4.2%	0.17	1.7 MB	3.9%	0.49
Nov 17 09:00:00 - 09:59:59	732	5.0%	0.20	2.2 MB	5.0%	0.62
Nov 17 10:00:00 - 10:59:59	720	4.9%	0.20	2.0 MB	4.6%	0.57
Nov 17 11:00:00 - 11:59:59	659	4.5%	0.18	2.0 MB	4.5%	0.56
Nov 17 12:00:00 - 12:59:59	735	5.0%	0.20	1.7 MB	3.9%	0.49
Nov 17 13:00:00 - 13:59:59	729	4.9%	0.20	1.9 MB	4.4%	0.55
Nov 17 14:00:00 - 14:59:59	771	5.2%	0.21	1.9 MB	4.4%	0.55
Nov 17 15:00:00 - 15:59:59	940	6.4%	0.26	3.4 MB	7.8%	0.98
Nov 17 16:00:00 - 16:59:59	823	5.6%	0.23	2.3 MB	5.3%	0.65
Nov 17 17:00:00 - 17:59:59	769	5.2%	0.21	1.9 MB	4.4%	0.55
Nov 17 18:00:00 - 18:59:59	880	6.0%	0.24	2.5 MB	5.8%	0.72
Nov 17 19:00:00 - 19:59:59	758	5.1%	0.21	2.1 MB	4.8%	0.59
Nov 17 20:00:00 - 20:59:59	761	5.1%	0.21	2.3 MB	5.3%	0.66
Nov 17 21:00:00 - 21:59:59	772	5.2%	0.21	2.2 MB	5.0%	0.63
Nov 17 22:00:00 - 22:59:59	686	4.6%	0.19	2.0 MB	4.5%	0.57
Nov 17 23:00:00 - 23:59:59	540	3.7%	0.15	1.6 MB	3.7%	0.45
Nov 18 00:00:00 - 00:59:59	434	2.9%	0.12	1.3 MB	2.9%	0.36
Nov 18 01:00:00 - 01:59:59	374	2.5%	0.10	1.2 MB	2.8%	0.34
Nov 18 02:00:00 - 02:59:59	342	2.3%	0.10	1.2 MB	2.6%	0.33
Nov 18 03:00:00 - 03:59:59	273	1.8%	0.08	910.9 KB	2.0%	0.25
Nov 18 04:00:00 - 04:15:00	67	0.5%	0.07	226.7 KB	0.5%	0.25
TOTAL: 24:00:00	14778	100.0%	0.17	43.8 MB	100.0%	0.52

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.news.xs4all.nl	1449	104	0	1	1344
2	newsfeed.fu-berlin.de	646	33	0	0	613
3	csiph-out.feed.uzoreto.com	406	26	0	0	380
4	csiph-out.feeder.erje.net	209	82	0	0	127
5	news-out.aioe.org	205	116	1	0	88
6	newsfeed.neva.ru	169	70	0	0	99
7	news-out.mixmin.net	128	0	2	0	126
8	csiph-out.news.weretis.net	122	32	1	0	89
9	feeder.eternal-september.org	98	41	2	0	55
10	news.freedyn.net	50	1	0	0	49
11	newsfeeds.xmission.com	42	8	0	0	34
12	news.swapon.de	22	0	0	0	22
13	nntp.pasdenom.info	20	0	0	0	20
14	csiph-out.news.tnetconsulting.net	11	11	0	0	0
15	news.fcku.it	3	3	0	0	0
16	news-out.neodome.net	2	0	2	0	0
17	news.samoylyk.net	1	0	1	0	0
TOTAL: 17		3583	527	9	1	3046

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	162
lada.talk	48
spline.eisfair	45
mozilla.support.thunderbird	32
scinet.stats	30
alt.politics	19
alt.bbs.synchronet	17
alt.job	11
clari.news.law.drugs	10
lada.auto	9
alt.religion	9
att.jobs	8
alt.testing	7
mozilla.support.seamonkey	7
it-alt.sport.calcio.juventus	6
alt.politics.usa	6
lada.movies	6
alt.binaries.ebooks	5
mozilla.test	5
alt.hack.nl	5
TOTAL: 67	527

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
uk.legal.moderated	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1590
EMP (phn path)	362
EMP (phl)	212
Too many newsgroups	89
0	66
EMP (phn nph)	16
HTML Multipart	11
Scoring filter	6
Binary: misplaced binary	5
HTML post	1
TOTAL: 10	2358

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	60
	csiph-out.feed.uzoreto.com	4
	csiph-out.news.xs4all.nl	3
	news-out.neodome.net	1
	TOTAL: 4	68
Huge articles
	csiph-out.news.xs4all.nl	68
	csiph-out.feed.uzoreto.com	45
	csiph-out.feeder.erje.net	36
	newsfeeds.xmission.com	29
	news.freedyn.net	28
	csiph-out.news.weretis.net	10
	feeder.eternal-september.org	5
	news-out.mixmin.net	4
	newsfeed.fu-berlin.de	1
	TOTAL: 9	226
Including strange strings
	newsfeed.fu-berlin.de	331
	news-out.aioe.org	76
	news.swapon.de	21
	csiph-out.news.xs4all.nl	18
	csiph-out.feeder.erje.net	4
	feeder.eternal-september.org	3
	newsfeeds.xmission.com	3
	csiph-out.feed.uzoreto.com	2
	newsfeed.neva.ru	1
	TOTAL: 9	459
No colon-space in header
	csiph-out.news.xs4all.nl	2
	csiph-out.feeder.erje.net	1
	TOTAL: 2	3
TOTAL: 4		756

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	17169	558	14219	1	0	3%	23:59:54
2	fu-berlin.de	13812	486	13326	0	0	3%	23:59:52
3	news.samoylyk.net	15060	475	14262	11	0	3%	23:50:33
4	news.bbs.nz	27760	316	13912	28	0	1%	23:51:41
5	erje	15719	134	12937	289	0	0%	23:50:07
6	tnet	15845	47	14218	0	0	0%	23:55:12
7	aioe	10283	35	9922	1	0	0%	23:50:07
8	newsfeed.xs4all.nl	12752	29	12437	0	20	0%	23:54:26
9	xmission	14138	24	12087	0	0	0%	23:59:54
10	neva.ru	13385	22	13203	55	0	0%	23:59:01
11	usenet.pasdenom.info	14572	20	14149	3	0	0%	23:59:54
12	news.freedyn.net	14386	13	14109	51	11	0%	23:56:30
13	news.uzoreto.com	13807	12	13609	56	0	0%	23:58:33
14	news.mixmin.net	13706	11	13480	0	0	0%	23:59:54
15	news.swapon.de	1194	8	1173	0	0	0%	23:38:00
16	neodome.net	14797	8	14513	0	0	0%	23:49:47
17	eternal-september	11898	7	11662	0	0	0%	23:59:54
18	weretis.net	14053	7	13598	0	0	0%	23:51:43
19	news.fcku.it	1526	0	1522	0	0	0%	22:00:15
TOTAL: 19		255862	2212	228338	495	31	0%	452:25:17

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	5.1 MB	11.8 KB	5.1 MB	0.1 KB/s	9.4 KB	23:59:54
2	fu-berlin.de	5.0 MB	0.0 KB	5.0 MB	0.1 KB/s	10.5 KB	23:59:52
3	news.samoylyk.net	4.9 MB	34.6 KB	5.0 MB	0.1 KB/s	10.5 KB	23:50:33
4	news.bbs.nz	863.9 KB	96.4 KB	960.3 KB	0.0 KB/s	2.8 KB	23:51:41
5	erje	272.5 KB	968.6 KB	1.2 MB	0.0 KB/s	2.9 KB	23:50:07
6	newsfeed.xs4all.nl	125.5 KB	0.0 KB	125.5 KB	0.0 KB/s	4.3 KB	23:54:26
7	xmission	97.3 KB	0.0 KB	97.3 KB	0.0 KB/s	4.1 KB	23:59:54
8	aioe	90.1 KB	3.3 KB	93.4 KB	0.0 KB/s	2.6 KB	23:50:07
9	tnet	89.5 KB	0.0 KB	89.5 KB	0.0 KB/s	1.9 KB	23:55:12
10	usenet.pasdenom.info	71.0 KB	5.3 KB	76.3 KB	0.0 KB/s	3.3 KB	23:59:54
11	news.freedyn.net	43.5 KB	589.9 KB	633.4 KB	0.0 KB/s	9.9 KB	23:56:30
12	news.uzoreto.com	40.2 KB	264.6 KB	304.8 KB	0.0 KB/s	4.5 KB	23:58:33
13	neva.ru	35.4 KB	138.9 KB	174.3 KB	0.0 KB/s	2.3 KB	23:59:01
14	news.mixmin.net	14.0 KB	0.0 KB	14.0 KB	0.0 KB/s	1.3 KB	23:59:54
15	news.swapon.de	10.7 KB	0.0 KB	10.7 KB	0.0 KB/s	1.3 KB	23:38:00
16	neodome.net	10.5 KB	0.0 KB	10.5 KB	0.0 KB/s	1.3 KB	23:49:47
17	eternal-september	9.4 KB	0.0 KB	9.4 KB	0.0 KB/s	1.3 KB	23:59:54
18	weretis.net	9.4 KB	0.0 KB	9.4 KB	0.0 KB/s	1.3 KB	23:51:43
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:00:15
TOTAL: 19		16.8 MB	2.1 MB	18.8 MB	0.0 KB/s	7.1 KB	452:25:17

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	ip72-222-200-117.ph.ph.cox.net	9	51	108.9 KB	64	0	0	02:54:03
2	athedsl-175833.home.otenet.gr	2	47	104.6 KB	2	0	0	00:00:24
3	024-178-045-162.res.spectrum.com	30	12	19.8 KB	12	7	6	07:56:47
4	a131033.upc-a.chello.nl	17	11	17.9 KB	4	0	0	03:54:14
TOTAL: 4		58	121	251.2 KB	82	7	6	37:48:15

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.ph.ph.cox.net	9	51	108.9 KB	64	0	0	02:54:03
2	*.home.otenet.gr	2	47	104.6 KB	2	0	0	00:00:24
3	*.res.spectrum.com	30	12	19.8 KB	12	7	6	07:56:47
4	*.upc-a.chello.nl	17	11	17.9 KB	4	0	0	03:54:14
5	unresolved	61	0	0.0 KB	0	0	0	19:56:54
6	*.net.upcbroadband.cz	10	0	0.0 KB	0	0	0	03:03:06
7	*.range81-153.btcentralplus.com	3	0	0.0 KB	0	0	0	00:02:43
TOTAL: 7		132	121	251.2 KB	82	7	6	37:48:15

NNRP auth users [Top 20]:

User	Conn
ohlandl	6
TOTAL: 1	6

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	4.204	2.956	0.000	19:56:54
024-178-045-162.res.spectrum.com	2.404	2.698	0.015	07:56:47
a131033.upc-a.chello.nl	1.354	1.052	0.000	03:54:14
ip-78-102-198-192.net.upcbroadband.cz	0.691	0.113	0.000	03:03:06
ip72-222-200-117.ph.ph.cox.net	0.871	0.643	0.000	02:54:03
host81-153-177-33.range81-153.btcentralplus.com	0.203	0.041	0.000	00:02:43
athedsl-175833.home.otenet.gr	0.224	0.016	0.000	00:00:24
185.181.102.18	0.053	0.023	0.000	00:00:00
TOTAL: 8	10.004	7.542	0.015	37:48:15

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	60
ip-78-102-198-192.net.upcbroadband.cz	10
host81-153-177-33.range81-153.btcentralplus.com	3
185.181.102.18	1
TOTAL: 4	74

NNRP client timeouts [Top 20]:

System	Conn	Peer
024-178-045-162.res.spectrum.com	8	0
ip72-222-200-117.ph.ph.cox.net	5	0
TOTAL: 2	13	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Nov 17 04:15:00 -- Nov 18 04:15:00