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

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Nov 13 13:15:53 csiph nnrpd[98625]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 13 13:15:53 csiph nnrpd[98625]: 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 13 14:41:22 csiph nnrpd[2004]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 13 14:41:22 csiph nnrpd[2004]: 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 13 19:12:13 csiph nnrpd[12654]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 13 19:12:13 csiph nnrpd[12654]: 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 13 19:18:00 csiph nnrpd[12809]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 13 19:18:00 csiph nnrpd[12809]: 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	18874	48.8%	4.9 MB	63.8%
innd	15357	39.7%	2.0 MB	26.7%
innfeed	3460	8.9%	634.2 KB	8.1%
nnrpd	977	2.5%	107.6 KB	1.4%
nocem	17	0.0%	2.0 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	38686	100.0%	7.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	311391	55.0%
Negative hits	235796	41.7%
Do not exist	18247	3.2%
Cache misses	224	0.0%
TOTAL: 4	565658	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.429	0.0%	31	0.000	13.839	24.778
article cleanup	00:00:00.134	0.0%	18059	0.000	0.007	0.233
article logging	00:00:00.339	0.0%	18571	0.000	0.018	0.250
article parse	00:00:00.816	0.0%	52924	0.000	0.015	0.126
article write	00:00:27.274	0.0%	15295	0.177	1.783	56.245
artlog/artcncl	00:00:00.000	0.0%	31	0.000	0.000	0.000
artlog/artparse	00:00:00.018	0.0%	264	0.000	0.068	12.000
data move	00:00:01.829	0.0%	527757	0.000	0.003	0.124
hisgrep/artcncl	00:00:00.029	0.0%	31	0.000	0.935	17.000
hishave/artcncl	00:00:00.005	0.0%	31	0.000	0.161	0.444
hishave/artparse	00:00:00.000	0.0%	248	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:22.030	0.0%	567718	0.001	0.039	0.443
history sync	00:00:01.188	0.0%	378	0.000	3.143	95.667
history write	00:00:28.281	0.0%	18071	0.082	1.565	10.848
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.314	0.0%	248	0.000	1.266	13.333
idle	23:55:30.783	99.6%	532783	95.548	161.662	528.286
nntp read	00:00:02.487	0.0%	536113	0.001	0.005	0.024
overview write	00:02:39.186	0.2%	15295	0.131	10.408	2340.409
perl filter	00:00:35.094	0.0%	18055	1.135	1.944	7.268
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.272	0.0%	30607	0.000	0.009	0.035
TOTAL: 24:00:58.586	24:00:10.508	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.031	0.0%	15302	0.000	0.002	0.026
article prepare	00:00:00.000	0.0%	10828	0.000	0.000	0.000
article read	00:00:00.589	0.0%	1024	0.000	0.575	175.500
backlog stats	00:00:00.216	0.0%	344834	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.277	0.0%	276153	0.001	0.005	0.010
data write	00:00:01.632	0.0%	263899	0.001	0.006	0.013
idle	23:53:58.078	100.0%	344834	148.040	249.506	669.428
status file	00:00:00.176	0.0%	256	0.000	0.688	2.000
TOTAL: 23:54:10.879	23:54:01.999	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	41:32:21.841	99.8%	716	4110.800	208857.320	600162.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.016	0.0%	1732	0.000	0.009	0.167
readart	00:00:00.376	0.0%	42	0.000	8.952	42.000
TOTAL: 41:36:09.148	41:32:22.233	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news-out.aioe.org	3	14685	3572	10989	124	24%	24:09:43
2	newsfeeds.xmission.com	2	14541	2569	11942	30	17%	24:05:23
3	feeder.eternal-september.org	5	17151	2465	14571	115	14%	24:35:05
4	csiph-out.news.xs4all.nl	504	17606	2060	14245	1301	11%	68:18:42
5	csiph-out.feeder.erje.net	1440	74607	845	73672	90	1%	118:41:53
6	newsfeed.fu-berlin.de	12	15070	822	14040	208	5%	25:22:12
7	news-out.mixmin.net	1	16345	685	15447	213	4%	23:59:34
8	newsfeed.neva.ru	143	12642	623	11852	167	4%	71:24:18
9	csiph-out.news.weretis.net	2	31241	577	30323	341	1%	47:08:42
10	csiph-out.feed.uzoreto.com	864	23974	406	23201	367	1%	71:11:15
11	news.fcku.it	12	1680	255	1423	2	15%	22:21:27
12	news-out.neodome.net	1	16918	146	16717	55	0%	23:59:56
13	nntp.pasdenom.info	2	10184	84	10092	8	0%	23:58:54
14	news.swapon.de	4	1448	64	1383	1	4%	23:34:29
15	csiph-out.news.tnetconsulting.net	2	15753	33	15693	27	0%	23:59:56
16	localhost	41	15	15	0	0	100%	00:00:01
17	news.freedyn.net	2	17660	13	17613	34	0%	23:59:55
18	news.samoylyk.net	2	14670	3	14667	0	0%	23:59:49
19	news.bbs.nz	1	15340	2	15338	0	0%	23:59:52
20	xanadu-bbs.net	22	549	0	549	0	0%	21:48:15
TOTAL: 20		3065	332079	15239	313757	3083	4%	710:39:21

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.aioe.org	8.2 MB	1.2 KB	320.1 KB	8.5 MB	96%	2.3 KB
2	newsfeeds.xmission.com	7.5 MB	1.2 KB	723.4 KB	8.2 MB	91%	3.2 KB
3	feeder.eternal-september.org	6.8 MB	0.0 KB	403.0 KB	7.2 MB	94%	2.9 KB
4	csiph-out.feeder.erje.net	6.6 MB	0.0 KB	2.1 MB	8.7 MB	75%	9.5 KB
5	csiph-out.news.xs4all.nl	5.3 MB	2.1 KB	8.6 MB	13.9 MB	38%	4.2 KB
6	news-out.mixmin.net	1.9 MB	0.0 KB	432.5 KB	2.4 MB	82%	2.7 KB
7	newsfeed.fu-berlin.de	1.9 MB	0.0 KB	453.9 KB	2.3 MB	80%	2.3 KB
8	csiph-out.news.weretis.net	1.7 MB	0.0 KB	1.8 MB	3.5 MB	47%	3.9 KB
9	newsfeed.neva.ru	1.6 MB	0.0 KB	491.1 KB	2.1 MB	76%	2.7 KB
10	csiph-out.feed.uzoreto.com	1.4 MB	0.0 KB	8.0 MB	9.4 MB	15%	12.4 KB
11	news.fcku.it	425.5 KB	0.0 KB	5.9 KB	431.4 KB	98%	1.7 KB
12	news-out.neodome.net	405.4 KB	1.2 KB	88.4 KB	495.0 KB	81%	2.5 KB
13	nntp.pasdenom.info	192.6 KB	0.0 KB	10.2 KB	202.8 KB	94%	2.2 KB
14	news.swapon.de	136.6 KB	0.0 KB	49.9 KB	186.5 KB	73%	2.9 KB
15	csiph-out.news.tnetconsulting.net	107.9 KB	2.4 KB	77.1 KB	187.4 KB	57%	3.1 KB
16	news.freedyn.net	49.8 KB	0.0 KB	1.9 MB	2.0 MB	2%	42.5 KB
17	localhost	23.4 KB	0.0 KB	0.0 KB	23.4 KB	100%	1.6 KB
18	news.samoylyk.net	5.2 KB	0.0 KB	0.0 KB	5.2 KB	100%	1.7 KB
19	news.bbs.nz	2.4 KB	0.0 KB	0.0 KB	2.4 KB	100%	1.2 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		44.2 MB	8.2 KB	25.3 MB	69.5 MB	63%	3.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 13 04:15:00 - 04:59:59	176	1.2%	0.07	511.4 KB	1.1%	0.19
Nov 13 05:00:00 - 05:59:59	355	2.3%	0.10	2.0 MB	4.6%	0.58
Nov 13 06:00:00 - 06:59:59	345	2.3%	0.10	1.0 MB	2.3%	0.29
Nov 13 07:00:00 - 07:59:59	492	3.2%	0.14	1.3 MB	3.0%	0.38
Nov 13 08:00:00 - 08:59:59	746	4.9%	0.21	2.0 MB	4.5%	0.57
Nov 13 09:00:00 - 09:59:59	793	5.2%	0.22	2.2 MB	5.0%	0.63
Nov 13 10:00:00 - 10:59:59	752	4.9%	0.21	2.3 MB	5.2%	0.66
Nov 13 11:00:00 - 11:59:59	788	5.2%	0.22	2.3 MB	5.1%	0.64
Nov 13 12:00:00 - 12:59:59	721	4.7%	0.20	1.7 MB	3.9%	0.49
Nov 13 13:00:00 - 13:59:59	844	5.5%	0.23	2.0 MB	4.6%	0.58
Nov 13 14:00:00 - 14:59:59	875	5.7%	0.24	2.2 MB	5.0%	0.63
Nov 13 15:00:00 - 15:59:59	954	6.2%	0.27	2.8 MB	6.4%	0.81
Nov 13 16:00:00 - 16:59:59	908	5.9%	0.25	2.7 MB	6.2%	0.78
Nov 13 17:00:00 - 17:59:59	875	5.7%	0.24	2.3 MB	5.2%	0.65
Nov 13 18:00:00 - 18:59:59	832	5.4%	0.23	2.9 MB	6.6%	0.83
Nov 13 19:00:00 - 19:59:59	828	5.4%	0.23	2.1 MB	4.7%	0.59
Nov 13 20:00:00 - 20:59:59	744	4.9%	0.21	2.1 MB	4.6%	0.59
Nov 13 21:00:00 - 21:59:59	610	4.0%	0.17	1.8 MB	4.0%	0.50
Nov 13 22:00:00 - 22:59:59	624	4.1%	0.17	1.7 MB	3.9%	0.49
Nov 13 23:00:00 - 23:59:59	562	3.7%	0.16	1.8 MB	4.0%	0.51
Nov 14 00:00:00 - 00:59:59	473	3.1%	0.13	1.3 MB	2.9%	0.37
Nov 14 01:00:00 - 01:59:59	311	2.0%	0.09	859.3 KB	1.9%	0.24
Nov 14 02:00:00 - 02:59:59	313	2.0%	0.09	1.1 MB	2.5%	0.32
Nov 14 03:00:00 - 03:59:59	307	2.0%	0.09	1022.8 KB	2.2%	0.28
Nov 14 04:00:00 - 04:15:00	71	0.5%	0.08	267.0 KB	0.6%	0.30
TOTAL: 24:00:00	15299	100.0%	0.18	44.4 MB	100.0%	0.53

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.news.xs4all.nl	1324	31	1	0	1292
2	newsfeed.fu-berlin.de	582	17	0	0	565
3	csiph-out.feed.uzoreto.com	369	20	0	1	348
4	csiph-out.news.weretis.net	304	91	0	0	213
5	news-out.mixmin.net	212	3	0	0	209
6	news-out.aioe.org	201	92	1	0	108
7	newsfeed.neva.ru	169	81	0	0	88
8	feeder.eternal-september.org	109	48	0	0	61
9	csiph-out.feeder.erje.net	92	23	0	0	69
10	news-out.neodome.net	54	3	1	0	50
11	news.freedyn.net	41	0	0	0	41
12	newsfeeds.xmission.com	30	18	1	0	11
13	news.swapon.de	29	0	0	0	29
14	csiph-out.news.tnetconsulting.net	18	18	0	0	0
15	nntp.pasdenom.info	8	0	0	0	8
16	news.fcku.it	2	2	0	0	0
TOTAL: 16		3544	447	4	1	3092

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	93
lada.talk	46
alt.politics	40
mozilla.support.firefox	27
scinet.stats	18
alt.politics.usa	18
alt.binaries.pictures.purebeauty	14
mozilla.support.thunderbird	12
att.jobs	12
lada.auto	12
mozilla.dev.security.policy	11
alt.bbs.synchronet	9
mozilla.support.seamonkey	8
alt.testing	8
mozilla.general	7
spline.eisfair	7
alt.survival	7
at.jobs	6
alt.job	5
free.it.religioni.scientology	5
TOTAL: 75	447

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)	1434
EMP (phn path)	482
EMP (phl)	168
Too many newsgroups	88
EMP (phn nph)	78
0	39
Binary: misplaced binary	12
HTML Multipart	5
HTML post	4
Binary Payload	4
Too many newsgroups (meow)	1
TOTAL: 11	2315

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	92
	csiph-out.news.xs4all.nl	70
	csiph-out.feeder.erje.net	39
	news.freedyn.net	21
	csiph-out.news.weretis.net	11
	newsfeeds.xmission.com	9
	feeder.eternal-september.org	3
	news-out.mixmin.net	2
	news.swapon.de	1
	TOTAL: 9	248
Including strange strings
	newsfeed.fu-berlin.de	367
	news-out.aioe.org	81
	news.swapon.de	28
	csiph-out.news.xs4all.nl	23
	csiph-out.news.weretis.net	6
	news-out.mixmin.net	2
	csiph-out.feeder.erje.net	2
	csiph-out.feed.uzoreto.com	2
	feeder.eternal-september.org	1
	newsfeeds.xmission.com	1
	TOTAL: 11	514
No colon-space in header
	csiph-out.news.xs4all.nl	13
	csiph-out.feeder.erje.net	1
	csiph-out.feed.uzoreto.com	1
	TOTAL: 3	15
TOTAL: 4		822

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	17980	694	14600	3	0	3%	23:59:49
2	fu-berlin.de	14261	513	13748	0	0	3%	23:59:50
3	news.samoylyk.net	15645	504	14788	4	0	3%	23:59:53
4	news.bbs.nz	28836	343	14415	28	1	1%	23:59:53
5	tnet	16070	72	14685	0	0	0%	23:53:49
6	newsfeed.xs4all.nl	13498	44	13059	3	21	0%	23:54:27
7	neva.ru	13790	39	13562	91	0	0%	23:59:53
8	usenet.pasdenom.info	15189	22	14677	3	14	0%	23:59:53
9	xmission	13727	22	12126	0	0	0%	23:59:43
10	news.mixmin.net	14039	20	13757	2	0	0%	23:59:50
11	news.freedyn.net	14885	20	14496	106	0	0%	23:59:45
12	eternal-september	12417	17	12117	0	0	0%	23:59:53
13	neodome.net	15391	17	15049	0	0	0%	23:50:08
14	aioe	10801	17	10384	0	0	0%	23:59:53
15	weretis.net	14531	16	14114	0	0	0%	23:59:53
16	news.swapon.de	1455	16	1431	0	0	1%	23:20:22
17	news.uzoreto.com	14751	9	14495	11	0	0%	23:59:53
18	erje	14221	3	14073	71	0	0%	23:59:52
19	news.fcku.it	1356	0	1351	0	0	0%	22:00:11
TOTAL: 19		262843	2388	236927	322	36	0%	452:56:50

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	5.9 MB	6.9 KB	5.9 MB	0.1 KB/s	8.7 KB	23:59:49
2	fu-berlin.de	5.4 MB	0.0 KB	5.4 MB	0.1 KB/s	10.7 KB	23:59:50
3	news.samoylyk.net	5.4 MB	3.7 KB	5.4 MB	0.1 KB/s	10.8 KB	23:59:53
4	news.bbs.nz	847.7 KB	83.5 KB	931.2 KB	0.0 KB/s	2.5 KB	23:59:53
5	tnet	143.2 KB	0.0 KB	143.2 KB	0.0 KB/s	2.0 KB	23:53:49
6	newsfeed.xs4all.nl	102.9 KB	103.7 KB	206.6 KB	0.0 KB/s	4.4 KB	23:54:27
7	news.freedyn.net	87.3 KB	1.7 MB	1.8 MB	0.0 KB/s	14.8 KB	23:59:45
8	neva.ru	82.4 KB	188.0 KB	270.4 KB	0.0 KB/s	2.1 KB	23:59:53
9	news.mixmin.net	72.7 KB	2.2 KB	74.9 KB	0.0 KB/s	3.4 KB	23:59:50
10	usenet.pasdenom.info	68.8 KB	4.0 KB	72.8 KB	0.0 KB/s	2.9 KB	23:59:53
11	xmission	54.1 KB	0.0 KB	54.1 KB	0.0 KB/s	2.5 KB	23:59:43
12	eternal-september	33.5 KB	0.0 KB	33.5 KB	0.0 KB/s	2.0 KB	23:59:53
13	aioe	29.0 KB	0.0 KB	29.0 KB	0.0 KB/s	1.7 KB	23:59:53
14	neodome.net	27.9 KB	0.0 KB	27.9 KB	0.0 KB/s	1.6 KB	23:50:08
15	weretis.net	27.7 KB	0.0 KB	27.7 KB	0.0 KB/s	1.7 KB	23:59:53
16	news.swapon.de	24.9 KB	0.0 KB	24.9 KB	0.0 KB/s	1.6 KB	23:20:22
17	news.uzoreto.com	11.3 KB	54.1 KB	65.4 KB	0.0 KB/s	3.3 KB	23:59:53
18	erje	3.8 KB	280.4 KB	284.2 KB	0.0 KB/s	3.8 KB	23:59:52
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:00:11
TOTAL: 19		18.2 MB	2.4 MB	20.7 MB	0.0 KB/s	7.8 KB	452:56:50

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	24	22	32.9 KB	5	0	0	06:01:13
2	071-013-248-095.res.spectrum.com	37	20	31.2 KB	21	15	11	09:57:05
TOTAL: 2		61	42	64.1 KB	26	15	11	41:36:08

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.upc-a.chello.nl	24	22	32.9 KB	5	0	0	06:01:13
2	*.res.spectrum.com	37	20	31.2 KB	21	15	11	09:57:05
3	*.range81-135.btcentralplus.com	2	0	0.0 KB	0	0	0	00:05:37
4	*.range86-138.btcentralplus.com	2	0	0.0 KB	0	0	0	00:10:56
5	unresolved	55	0	0.0 KB	0	0	0	17:50:17
6	*.ph.ph.cox.net	5	0	0.0 KB	0	0	0	02:30:18
7	*.net.upcbroadband.cz	16	0	0.0 KB	0	0	0	05:00:38
TOTAL: 7		141	42	64.1 KB	26	15	11	41:36:08

NNRP auth users [Top 20]:

User	Conn
ohlandl	11
TOTAL: 1	11

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	3.637	2.723	0.000	17:50:07
071-013-248-095.res.spectrum.com	3.086	2.580	0.369	09:57:05
a131033.upc-a.chello.nl	1.801	0.674	0.000	06:01:13
ip-78-102-198-192.net.upcbroadband.cz	1.138	1.246	0.000	05:00:38
ip72-222-200-117.ph.ph.cox.net	0.542	0.071	0.000	02:30:18
host86-138-214-15.range86-138.btcentralplus.com	0.125	0.033	0.000	00:10:56
host81-135-1-208.range81-135.btcentralplus.com	0.138	0.024	0.000	00:05:37
192.241.237.52	0.059	0.017	0.000	00:00:09
TOTAL: 8	10.526	7.368	0.369	41:36:08

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	54
ip-78-102-198-192.net.upcbroadband.cz	16
ip72-222-200-117.ph.ph.cox.net	5
host81-135-1-208.range81-135.btcentralplus.com	2
host86-138-214-15.range86-138.btcentralplus.com	2
192.241.237.52	1
TOTAL: 6	80

NNRP client timeouts [Top 20]:

System	Conn	Peer
071-013-248-095.res.spectrum.com	10	0
ip72-222-200-117.ph.ph.cox.net	5	0
TOTAL: 2	15	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Nov 13 04:15:00 -- Nov 14 04:15:00