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

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Nov 27 11:02:02 csiph nnrpd[48897]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 27 11:02:02 csiph nnrpd[48897]: 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 27 13:16:34 csiph nnrpd[55783]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 27 13:16:34 csiph nnrpd[55783]: 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 27 17:55:45 csiph nnrpd[70103]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 27 17:55:45 csiph nnrpd[70103]: 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 28 01:38:27 csiph nnrpd[94007]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 28 01:38:27 csiph nnrpd[94007]: 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	18261	46.6%	4.6 MB	61.6%
innd	15536	39.6%	2.1 MB	27.5%
innfeed	3578	9.1%	627.8 KB	8.2%
nnrpd	1799	4.6%	199.5 KB	2.6%
nocem	31	0.1%	3.6 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	39206	100.0%	7.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	282017	54.9%
Negative hits	213802	41.6%
Do not exist	17687	3.4%
Cache misses	284	0.1%
TOTAL: 4	513790	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.279	0.0%	32	0.000	8.719	18.100
article cleanup	00:00:00.114	0.0%	17423	0.000	0.007	0.051
article logging	00:00:00.268	0.0%	17916	0.000	0.015	0.069
article parse	00:00:00.686	0.0%	54410	0.000	0.013	0.143
article write	00:00:05.282	0.0%	14796	0.102	0.357	6.754
artlog/artcncl	00:00:00.001	0.0%	32	0.000	0.031	1.000
artlog/artparse	00:00:00.004	0.0%	300	0.000	0.013	0.333
data move	00:00:00.178	0.0%	482361	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.008	0.0%	32	0.000	0.250	0.700
hishave/artcncl	00:00:00.001	0.0%	32	0.000	0.031	0.100
hishave/artparse	00:00:00.001	0.0%	292	0.000	0.003	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:05.527	0.0%	514703	0.001	0.011	0.347
history sync	00:00:00.727	0.0%	362	0.000	2.008	10.500
history write	00:00:10.125	0.0%	17430	0.060	0.581	9.671
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.157	0.0%	292	0.000	0.538	9.400
idle	23:59:05.943	99.9%	490727	110.585	175.955	603.216
nntp read	00:00:01.900	0.0%	490836	0.002	0.004	0.007
overview write	00:00:22.425	0.0%	14796	0.080	1.516	34.977
perl filter	00:00:25.863	0.0%	17422	1.185	1.485	2.133
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.208	0.0%	29623	0.000	0.007	0.027
TOTAL: 24:00:55.994	24:00:19.697	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.030	0.0%	15724	0.000	0.002	0.024
article prepare	00:00:00.001	0.0%	15380	0.000	0.000	0.004
article read	00:00:00.259	0.0%	1851	0.000	0.140	11.000
backlog stats	00:00:00.190	0.0%	329712	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.141	0.0%	254987	0.000	0.004	0.010
data write	00:00:01.572	0.0%	240729	0.001	0.007	0.014
idle	23:54:52.493	100.0%	329712	154.939	261.114	716.676
status file	00:00:00.205	0.0%	298	0.000	0.688	3.800
TOTAL: 23:55:03.453	23:54:55.891	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	64:15:02.114	99.5%	44298	382.875	5221.502	450075.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:02:03.627	0.1%	2921803	0.000	0.042	0.167
readart	00:09:48.716	0.3%	43181	0.000	13.634	43.000
TOTAL: 64:32:42.978	64:26:54.457	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news-out.mixmin.net	2	16151	4021	11905	225	24%	24:08:53
2	csiph-out.news.xs4all.nl	533	16884	3191	12236	1457	18%	67:56:56
3	newsfeeds.xmission.com	3	13873	2511	11339	23	18%	24:17:34
4	news-out.aioe.org	2	13963	1048	12839	76	7%	23:59:52
5	csiph-out.feeder.erje.net	1440	72025	959	70952	114	1%	118:47:16
6	newsfeed.fu-berlin.de	12	15738	870	14703	165	5%	28:38:16
7	csiph-out.news.weretis.net	3	18141	780	17224	137	4%	29:47:08
8	newsfeed.neva.ru	148	11131	582	10436	113	5%	71:04:27
9	news.fcku.it	9	1678	270	1406	2	16%	22:22:57
10	news-out.neodome.net	1	16114	224	15804	86	1%	23:59:55
11	csiph-out.feed.uzoreto.com	864	23668	198	23075	395	0%	71:16:58
12	nntp.pasdenom.info	8	10265	129	10129	7	1%	23:23:12
13	csiph-out.news.tnetconsulting.net	2	12968	41	12895	32	0%	23:59:50
14	news.swapon.de	1	1285	32	1253	0	2%	21:34:27
15	localhost	51	25	25	0	0	100%	00:00:02
16	news.freedyn.net	2	17301	13	17266	22	0%	23:59:53
17	news.samoylyk.net	4	14379	1	14377	1	0%	24:41:03
18	xanadu-bbs.net	26	423	1	422	0	0%	21:39:27
19	news.bbs.nz	2	12535	0	12535	0	0%	23:59:52
TOTAL: 19		3113	288547	14896	270796	2855	5%	669:37:58

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.mixmin.net	9.8 MB	0.0 KB	559.1 KB	10.3 MB	94%	2.5 KB
2	csiph-out.news.xs4all.nl	8.6 MB	0.0 KB	12.8 MB	21.4 MB	39%	4.7 KB
3	newsfeeds.xmission.com	8.1 MB	0.0 KB	1.5 MB	9.7 MB	83%	3.9 KB
4	csiph-out.feeder.erje.net	7.5 MB	0.0 KB	1.9 MB	9.4 MB	79%	8.9 KB
5	news-out.aioe.org	3.7 MB	0.0 KB	369.5 KB	4.1 MB	91%	3.7 KB
6	csiph-out.news.weretis.net	2.2 MB	14.1 KB	1.6 MB	3.8 MB	58%	4.3 KB
7	newsfeed.fu-berlin.de	2.2 MB	0.0 KB	518.0 KB	2.7 MB	81%	2.7 KB
8	newsfeed.neva.ru	1.5 MB	2.0 KB	472.3 KB	2.0 MB	76%	2.9 KB
9	csiph-out.feed.uzoreto.com	1.0 MB	0.0 KB	8.2 MB	9.2 MB	11%	15.9 KB
10	news-out.neodome.net	735.5 KB	0.0 KB	190.3 KB	925.8 KB	79%	3.0 KB
11	news.fcku.it	411.5 KB	0.0 KB	2.0 KB	413.5 KB	99%	1.5 KB
12	nntp.pasdenom.info	275.7 KB	0.0 KB	8.7 KB	284.4 KB	96%	2.1 KB
13	csiph-out.news.tnetconsulting.net	174.2 KB	0.0 KB	411.6 KB	585.8 KB	29%	8.0 KB
14	news.swapon.de	117.2 KB	0.0 KB	0.0 KB	117.2 KB	100%	3.7 KB
15	news.freedyn.net	75.2 KB	0.0 KB	730.3 KB	805.5 KB	9%	23.0 KB
16	localhost	39.7 KB	0.0 KB	0.0 KB	39.7 KB	100%	1.6 KB
17	news.samoylyk.net	4.4 KB	0.0 KB	2.8 KB	7.3 KB	61%	3.6 KB
18	xanadu-bbs.net	2.4 KB	0.0 KB	0.0 KB	2.4 KB	100%	2.4 KB
19	news.bbs.nz	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 19		46.4 MB	16.2 KB	29.3 MB	75.6 MB	61%	4.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 27 04:15:00 - 04:59:59	186	1.3%	0.07	626.7 KB	1.3%	0.23
Nov 27 05:00:00 - 05:59:59	412	2.8%	0.11	2.1 MB	4.7%	0.61
Nov 27 06:00:00 - 06:59:59	352	2.4%	0.10	1.3 MB	2.9%	0.38
Nov 27 07:00:00 - 07:59:59	534	3.6%	0.15	1.4 MB	3.1%	0.40
Nov 27 08:00:00 - 08:59:59	659	4.4%	0.18	1.8 MB	3.9%	0.50
Nov 27 09:00:00 - 09:59:59	827	5.6%	0.23	2.2 MB	4.8%	0.63
Nov 27 10:00:00 - 10:59:59	739	5.0%	0.21	2.0 MB	4.4%	0.57
Nov 27 11:00:00 - 11:59:59	746	5.0%	0.21	2.1 MB	4.5%	0.59
Nov 27 12:00:00 - 12:59:59	671	4.5%	0.19	1.8 MB	4.0%	0.52
Nov 27 13:00:00 - 13:59:59	711	4.8%	0.20	2.0 MB	4.3%	0.57
Nov 27 14:00:00 - 14:59:59	780	5.3%	0.22	2.4 MB	5.2%	0.68
Nov 27 15:00:00 - 15:59:59	854	5.8%	0.24	3.5 MB	7.6%	0.99
Nov 27 16:00:00 - 16:59:59	954	6.4%	0.27	3.0 MB	6.5%	0.86
Nov 27 17:00:00 - 17:59:59	854	5.8%	0.24	2.1 MB	4.7%	0.61
Nov 27 18:00:00 - 18:59:59	740	5.0%	0.21	2.2 MB	4.8%	0.62
Nov 27 19:00:00 - 19:59:59	787	5.3%	0.22	2.3 MB	4.9%	0.65
Nov 27 20:00:00 - 20:59:59	686	4.6%	0.19	2.3 MB	5.0%	0.65
Nov 27 21:00:00 - 21:59:59	728	4.9%	0.20	2.6 MB	5.7%	0.74
Nov 27 22:00:00 - 22:59:59	544	3.7%	0.15	1.5 MB	3.3%	0.43
Nov 27 23:00:00 - 23:59:59	521	3.5%	0.14	1.7 MB	3.8%	0.50
Nov 28 00:00:00 - 00:59:59	362	2.4%	0.10	1.2 MB	2.6%	0.34
Nov 28 01:00:00 - 01:59:59	301	2.0%	0.08	1005.1 KB	2.1%	0.28
Nov 28 02:00:00 - 02:59:59	357	2.4%	0.10	1.2 MB	2.6%	0.34
Nov 28 03:00:00 - 03:59:59	371	2.5%	0.10	1.2 MB	2.6%	0.34
Nov 28 04:00:00 - 04:15:00	138	0.9%	0.15	359.0 KB	0.8%	0.40
TOTAL: 24:00:00	14814	100.0%	0.17	46.0 MB	100.0%	0.54

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1480	68	0	1412
2	newsfeed.fu-berlin.de	482	19	0	463
3	csiph-out.feed.uzoreto.com	400	15	0	385
4	news-out.mixmin.net	299	17	0	282
5	csiph-out.news.weretis.net	236	67	0	169
6	csiph-out.feeder.erje.net	117	42	0	75
7	newsfeed.neva.ru	112	33	1	78
8	news-out.aioe.org	100	67	0	33
9	news-out.neodome.net	90	10	0	80
10	newsfeeds.xmission.com	30	2	0	28
11	csiph-out.news.tnetconsulting.net	22	22	0	0
12	news.freedyn.net	22	0	0	22
13	news.swapon.de	15	0	0	15
14	nntp.pasdenom.info	7	0	0	7
15	news.fcku.it	2	2	0	0
16	news.samoylyk.net	1	0	0	1
TOTAL: 16		3415	364	1	3050

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	94
scinet.stats	32
alt.politics.usa	25
alt.binaries.pictures.purebeauty	24
lada.talk	22
mozilla.support.seamonkey	17
alt.windows7.general	16
alt.survival	12
alt.politics	10
spline.eisfair	9
mozilla.support.thunderbird	9
at.jobs	8
alt.job	6
lada.auto	5
it-alt.sport.calcio.juventus	5
aus.jobs	4
free.it.religioni.scientology	4
alt.philosophy	4
oesterreich.tratsch	3
alt.binaries.ebook	3
TOTAL: 62	364

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1635
EMP (phn path)	301
Too many newsgroups	88
EMP (phl)	83
EMP (phn nph)	66
0	47
Binary: misplaced binary	22
HTML Multipart	7
Scoring filter	6
Too many newsgroups (meow)	1
U2 violation - invalid distribution	1
TOTAL: 11	2257

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.news.xs4all.nl	118
	csiph-out.feed.uzoreto.com	87
	csiph-out.feeder.erje.net	37
	newsfeeds.xmission.com	20
	csiph-out.news.weretis.net	17
	news.freedyn.net	8
	newsfeed.fu-berlin.de	3
	news-out.mixmin.net	2
	TOTAL: 8	292
Including strange strings
	newsfeed.fu-berlin.de	332
	news-out.mixmin.net	74
	news-out.aioe.org	27
	csiph-out.news.xs4all.nl	23
	news.swapon.de	15
	newsfeeds.xmission.com	7
	csiph-out.news.weretis.net	6
	csiph-out.feed.uzoreto.com	6
	csiph-out.feeder.erje.net	3
	TOTAL: 9	493
No colon-space in header
	csiph-out.feeder.erje.net	5
	csiph-out.news.xs4all.nl	2
	csiph-out.feed.uzoreto.com	1
	TOTAL: 3	8
TOTAL: 4		838

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	17667	802	14012	0	0	4%	23:59:54
2	fu-berlin.de	13824	591	13233	0	0	4%	23:59:37
3	news.samoylyk.net	15190	589	14221	2	144	3%	23:59:54
4	news.bbs.nz	27653	442	13738	27	0	1%	23:59:54
5	erje	15551	211	12373	646	0	1%	23:59:54
6	tnet	15742	61	14075	1	0	0%	23:51:29
7	newsfeed.xs4all.nl	11759	42	11371	1	16	0%	23:54:24
8	xmission	14341	40	11559	0	0	0%	23:59:52
9	usenet.pasdenom.info	14682	35	14036	2	790	0%	23:59:54
10	weretis.net	13787	31	13385	0	0	0%	23:59:40
11	news.freedyn.net	14132	31	13818	27	0	0%	23:59:54
12	neva.ru	13299	30	13081	125	0	0%	23:59:51
13	aioe	10927	29	10442	0	0	0%	23:59:54
14	neodome.net	14815	26	14450	0	0	0%	23:51:26
15	news.swapon.de	1365	25	1325	0	0	1%	23:54:25
16	news.mixmin.net	10060	25	9807	0	0	0%	23:59:54
17	news.uzoreto.com	13854	19	13608	9	0	0%	23:59:48
18	eternal-september	0	0	0	0	11969	0%	23:58:43
19	news.fcku.it	1291	0	1291	0	0	0%	21:11:43
TOTAL: 19		239939	3029	209825	840	12919	1%	452:40:10

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	6.8 MB	0.0 KB	6.8 MB	0.1 KB/s	8.7 KB	23:59:54
2	fu-berlin.de	6.3 MB	0.0 KB	6.3 MB	0.1 KB/s	11.0 KB	23:59:37
3	news.samoylyk.net	6.3 MB	18.8 KB	6.3 MB	0.1 KB/s	11.0 KB	23:59:54
4	news.bbs.nz	1.1 MB	108.5 KB	1.2 MB	0.0 KB/s	2.6 KB	23:59:54
5	erje	490.6 KB	2.2 MB	2.7 MB	0.0 KB/s	3.2 KB	23:59:54
6	tnet	118.5 KB	3.7 KB	122.2 KB	0.0 KB/s	2.0 KB	23:51:29
7	newsfeed.xs4all.nl	104.1 KB	4.3 KB	108.4 KB	0.0 KB/s	2.5 KB	23:54:24
8	xmission	103.8 KB	0.0 KB	103.8 KB	0.0 KB/s	2.6 KB	23:59:52
9	news.freedyn.net	95.8 KB	513.9 KB	609.7 KB	0.0 KB/s	10.5 KB	23:59:54
10	usenet.pasdenom.info	73.3 KB	6.7 KB	80.0 KB	0.0 KB/s	2.2 KB	23:59:54
11	neva.ru	56.5 KB	272.9 KB	329.5 KB	0.0 KB/s	2.1 KB	23:59:51
12	weretis.net	56.3 KB	0.0 KB	56.3 KB	0.0 KB/s	1.8 KB	23:59:40
13	aioe	56.2 KB	0.0 KB	56.2 KB	0.0 KB/s	1.9 KB	23:59:54
14	neodome.net	42.5 KB	0.0 KB	42.5 KB	0.0 KB/s	1.6 KB	23:51:26
15	news.swapon.de	39.8 KB	0.0 KB	39.8 KB	0.0 KB/s	1.6 KB	23:54:25
16	news.mixmin.net	39.8 KB	0.0 KB	39.8 KB	0.0 KB/s	1.6 KB	23:59:54
17	news.uzoreto.com	30.1 KB	16.1 KB	46.1 KB	0.0 KB/s	1.6 KB	23:59:48
18	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:43
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:11:43
TOTAL: 19		21.8 MB	3.1 MB	25.0 MB	0.0 KB/s	6.6 KB	452:40:10

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	lfbn-ren-1-681-66.w81-53.abo.wanadoo.fr	56	43075	85.9 MB	56	0	0	13:37:42
2	071-082-226-242.res.spectrum.com	51	51	99.0 KB	55	25	22	09:03:54
3	athedsl-175833.home.otenet.gr	1	32	48.1 KB	1	0	0	00:00:22
4	a131033.upc-a.chello.nl	57	23	33.4 KB	7	0	0	16:05:00
TOTAL: 4		165	43181	86.1 MB	119	25	22	64:32:41

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.w81-53.abo.wanadoo.fr	56	43075	85.9 MB	56	0	0	13:37:42
2	*.res.spectrum.com	51	51	99.0 KB	55	25	22	09:03:54
3	*.home.otenet.gr	1	32	48.1 KB	1	0	0	00:00:22
4	*.upc-a.chello.nl	57	23	33.4 KB	7	0	0	16:05:00
5	*.range5-81.btcentralplus.com	4	0	0.0 KB	0	0	0	00:04:57
6	unresolved	72	0	0.0 KB	0	0	0	23:40:31
7	*.ph.ph.cox.net	4	0	0.0 KB	0	0	0	02:00:11
TOTAL: 7		245	43181	86.1 MB	119	25	22	64:32:41

NNRP auth users [Top 20]:

User	Conn
ohlandl	22
TOTAL: 1	22

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	5.708	0.951	0.000	23:40:31
a131033.upc-a.chello.nl	4.146	0.844	0.000	16:05:00
lfbn-ren-1-681-66.w81-53.abo.wanadoo.fr	15.890	35.743	0.000	13:37:42
071-082-226-242.res.spectrum.com	4.742	0.871	0.053	09:03:54
ip72-222-200-117.ph.ph.cox.net	0.423	0.062	0.000	02:00:11
host5-81-219-132.range5-81.btcentralplus.com	0.245	0.070	0.000	00:04:57
athedsl-175833.home.otenet.gr	0.095	0.027	0.000	00:00:22
TOTAL: 7	31.249	38.568	0.053	64:32:41

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	72
ip72-222-200-117.ph.ph.cox.net	4
host5-81-219-132.range5-81.btcentralplus.com	4
TOTAL: 3	80

NNRP client timeouts [Top 20]:

System	Conn	Peer
071-082-226-242.res.spectrum.com	5	0
ip72-222-200-117.ph.ph.cox.net	4	0
TOTAL: 2	9	0

Newsgroup request counts (by category):

Category		Count	Pct
1	fr	43075	99.7%
2	comp	110	0.3%
TOTAL: 2		43185	100.0%

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

Newsgroup		Count
1	fr.rec.photo	43066
2	comp.sys.ibm.ps2.hardware	110
3	fr.comp.os.mac-os.x	8
4	fr.comp.sys.mac.materiel	1
TOTAL: 4		43185

Daily Usenet report for csiph.com

Nov 27 04:15:00 -- Nov 28 04:15:00