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

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Dec 8 09:04:16 csiph nnrpd[91946]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 8 09:04:16 csiph nnrpd[91946]: 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]
Dec 8 10:17:34 csiph nnrpd[96022]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 8 10:17:34 csiph nnrpd[96022]: 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]
Dec 8 10:42:01 csiph nnrpd[97322]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 8 10:42:01 csiph nnrpd[97322]: 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]
Dec 8 15:54:21 csiph nnrpd[13833]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 8 15:54:21 csiph nnrpd[13833]: 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	17252	45.2%	4.4 MB	60.6%
innd	16109	42.2%	2.1 MB	29.3%
innfeed	3537	9.3%	607.7 KB	8.2%
nnrpd	1263	3.3%	134.5 KB	1.8%
nocem	16	0.0%	1.7 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	38178	100.0%	7.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	259998	54.5%
Negative hits	200352	42.0%
Do not exist	16679	3.5%
Cache misses	193	0.0%
TOTAL: 4	477222	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.356	0.0%	33	0.000	10.788	19.167
article cleanup	00:00:00.105	0.0%	16520	0.000	0.006	0.040
article logging	00:00:00.231	0.0%	17053	0.000	0.014	0.079
article parse	00:00:00.586	0.0%	50459	0.000	0.012	0.061
article write	00:00:09.211	0.0%	13750	0.111	0.670	14.093
artlog/artcncl	00:00:00.001	0.0%	33	0.000	0.030	0.167
artlog/artparse	00:00:00.003	0.0%	182	0.000	0.016	0.333
data move	00:00:00.156	0.0%	448821	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.012	0.0%	33	0.000	0.364	1.000
hishave/artcncl	00:00:00.003	0.0%	33	0.000	0.091	0.222
hishave/artparse	00:00:00.000	0.0%	176	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:04.238	0.0%	477691	0.001	0.009	0.104
history sync	00:00:00.667	0.0%	379	0.000	1.760	8.500
history write	00:00:08.487	0.0%	16523	0.019	0.514	2.987
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.093	0.0%	176	0.000	0.528	9.500
idle	23:59:17.945	99.9%	457573	108.928	188.730	702.783
nntp read	00:00:01.741	0.0%	457062	0.001	0.004	0.007
overview write	00:00:25.053	0.0%	13750	0.086	1.822	31.953
perl filter	00:00:24.455	0.0%	16516	1.152	1.481	1.975
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.232	0.0%	27516	0.000	0.008	0.029
TOTAL: 24:01:05.321	24:00:33.575	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.034	0.0%	13756	0.000	0.002	0.020
article prepare	00:00:00.004	0.0%	12836	0.000	0.000	0.011
article read	00:00:00.246	0.0%	1434	0.000	0.172	6.429
backlog stats	00:00:00.200	0.0%	297580	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.039	0.0%	227037	0.000	0.005	0.012
data write	00:00:01.399	0.0%	215772	0.000	0.006	0.018
idle	23:54:37.517	100.0%	297580	161.458	289.258	858.888
status file	00:00:00.207	0.0%	306	0.000	0.676	3.375
TOTAL: 23:54:46.630	23:54:40.646	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	43:50:16.155	99.8%	982	0.000	160708.915	600142.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.013	0.0%	2298	0.000	0.006	0.167
readart	00:00:01.086	0.0%	89	0.000	12.202	43.000
TOTAL: 43:56:34.099	43:50:17.254	99.8%	-	-	-	-

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.aioe.org	2	12952	3267	9587	98	25%	24:09:56
2	newsfeeds.xmission.com	3	12601	2676	9899	26	21%	22:59:20
3	csiph-out.news.xs4all.nl	638	15748	1912	12794	1042	12%	67:18:04
4	feeder.eternal-september.org	45	5056	1126	3849	81	22%	33:20:21
5	newsfeed.fu-berlin.de	14	17500	1008	16293	199	5%	32:18:22
6	news-out.mixmin.net	2	14707	885	13646	176	6%	23:59:22
7	newsfeed.neva.ru	145	12681	731	11788	162	5%	72:13:43
8	csiph-out.feeder.erje.net	1440	67400	726	66335	339	1%	118:40:12
9	csiph-out.news.weretis.net	3	29921	588	29200	133	1%	47:59:46
10	csiph-out.feed.uzoreto.com	865	20752	301	19792	659	1%	71:07:13
11	news-out.neodome.net	1	15152	275	14755	122	1%	23:59:53
12	news.fcku.it	16	1194	181	1011	2	15%	21:35:50
13	csiph-out.news.tnetconsulting.net	1	15169	64	15074	31	0%	23:59:56
14	news.freedyn.net	2	16009	60	15857	92	0%	23:59:51
15	news.swapon.de	7	1231	25	1204	2	2%	23:16:07
16	localhost	47	21	21	0	0	100%	00:00:00
17	news.samoylyk.net	2	13155	15	13139	1	0%	23:59:40
18	xanadu-bbs.net	31	432	1	430	1	0%	22:03:39
19	news.bbs.nz	2	14612	0	14612	0	0%	23:59:41
TOTAL: 19		3266	286293	13862	269265	3166	4%	701:00:56

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	9.4 MB	3.5 KB	1.4 MB	10.8 MB	86%	4.1 KB
2	news-out.aioe.org	8.1 MB	0.0 KB	286.2 KB	8.4 MB	96%	2.6 KB
3	csiph-out.feeder.erje.net	6.0 MB	3.4 KB	3.0 MB	9.0 MB	66%	8.7 KB
4	csiph-out.news.xs4all.nl	5.2 MB	0.0 KB	4.4 MB	9.5 MB	54%	3.3 KB
5	feeder.eternal-september.org	3.0 MB	0.0 KB	549.2 KB	3.5 MB	84%	3.0 KB
6	news-out.mixmin.net	2.8 MB	0.0 KB	281.4 KB	3.1 MB	91%	3.0 KB
7	newsfeed.fu-berlin.de	2.3 MB	0.0 KB	407.0 KB	2.7 MB	85%	2.3 KB
8	newsfeed.neva.ru	2.0 MB	0.0 KB	498.3 KB	2.5 MB	80%	2.9 KB
9	csiph-out.news.weretis.net	1.7 MB	0.0 KB	1.6 MB	3.3 MB	51%	4.6 KB
10	csiph-out.feed.uzoreto.com	1.2 MB	0.0 KB	7.6 MB	8.8 MB	14%	9.4 KB
11	news-out.neodome.net	813.3 KB	3.4 KB	953.1 KB	1.7 MB	45%	4.5 KB
12	news.fcku.it	306.7 KB	0.0 KB	2.1 KB	308.8 KB	99%	1.7 KB
13	news.freedyn.net	301.1 KB	0.0 KB	2.1 MB	2.4 MB	12%	15.9 KB
14	csiph-out.news.tnetconsulting.net	243.1 KB	0.0 KB	77.5 KB	320.6 KB	75%	3.4 KB
15	news.samoylyk.net	64.0 KB	0.0 KB	53.3 KB	117.3 KB	54%	7.3 KB
16	localhost	50.1 KB	0.0 KB	0.0 KB	50.1 KB	100%	2.4 KB
17	news.swapon.de	47.6 KB	0.0 KB	25.7 KB	73.3 KB	64%	2.7 KB
18	xanadu-bbs.net	4.5 KB	0.0 KB	1.6 KB	6.2 KB	73%	3.1 KB
19	news.bbs.nz	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 19		43.5 MB	10.3 KB	23.1 MB	66.6 MB	65%	4.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 8 04:15:00 - 04:59:59	189	1.4%	0.07	477.0 KB	1.1%	0.18
Dec 8 05:00:00 - 05:59:59	281	2.0%	0.08	2.3 MB	5.2%	0.64
Dec 8 06:00:00 - 06:59:59	461	3.4%	0.13	1.3 MB	2.9%	0.36
Dec 8 07:00:00 - 07:59:59	381	2.8%	0.11	1.4 MB	3.2%	0.40
Dec 8 08:00:00 - 08:59:59	504	3.7%	0.14	1.3 MB	3.1%	0.38
Dec 8 09:00:00 - 09:59:59	618	4.5%	0.17	1.9 MB	4.3%	0.53
Dec 8 10:00:00 - 10:59:59	626	4.6%	0.17	1.8 MB	4.1%	0.50
Dec 8 11:00:00 - 11:59:59	687	5.0%	0.19	2.2 MB	5.2%	0.64
Dec 8 12:00:00 - 12:59:59	644	4.7%	0.18	2.0 MB	4.6%	0.57
Dec 8 13:00:00 - 13:59:59	661	4.8%	0.18	1.8 MB	4.2%	0.52
Dec 8 14:00:00 - 14:59:59	749	5.5%	0.21	2.3 MB	5.4%	0.67
Dec 8 15:00:00 - 15:59:59	836	6.1%	0.23	3.1 MB	7.3%	0.89
Dec 8 16:00:00 - 16:59:59	783	5.7%	0.22	2.8 MB	6.4%	0.79
Dec 8 17:00:00 - 17:59:59	649	4.7%	0.18	2.0 MB	4.6%	0.56
Dec 8 18:00:00 - 18:59:59	633	4.6%	0.18	1.8 MB	4.2%	0.52
Dec 8 19:00:00 - 19:59:59	728	5.3%	0.20	2.3 MB	5.3%	0.65
Dec 8 20:00:00 - 20:59:59	886	6.4%	0.25	2.5 MB	5.7%	0.71
Dec 8 21:00:00 - 21:59:59	714	5.2%	0.20	2.1 MB	4.9%	0.61
Dec 8 22:00:00 - 22:59:59	662	4.8%	0.18	1.8 MB	4.1%	0.50
Dec 8 23:00:00 - 23:59:59	561	4.1%	0.16	1.7 MB	4.0%	0.50
Dec 9 00:00:00 - 00:59:59	438	3.2%	0.12	1.3 MB	2.9%	0.36
Dec 9 01:00:00 - 01:59:59	377	2.7%	0.10	1.2 MB	2.8%	0.34
Dec 9 02:00:00 - 02:59:59	301	2.2%	0.08	917.1 KB	2.1%	0.25
Dec 9 03:00:00 - 03:59:59	314	2.3%	0.09	876.7 KB	2.0%	0.24
Dec 9 04:00:00 - 04:15:00	57	0.4%	0.06	213.2 KB	0.5%	0.24
TOTAL: 24:00:00	13740	100.0%	0.16	43.3 MB	100.0%	0.51

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.news.xs4all.nl	1071	57	0	0	1014
2	csiph-out.feed.uzoreto.com	664	37	0	1	626
3	newsfeed.fu-berlin.de	571	16	0	0	555
4	news-out.mixmin.net	181	1	0	0	180
5	csiph-out.feeder.erje.net	177	51	1	0	125
6	newsfeed.neva.ru	161	53	0	0	108
7	news-out.aioe.org	155	87	0	0	68
8	csiph-out.news.weretis.net	120	63	0	0	57
9	news-out.neodome.net	113	3	1	0	109
10	news.freedyn.net	92	0	0	0	92
11	feeder.eternal-september.org	82	35	0	0	47
12	newsfeeds.xmission.com	41	7	1	0	33
13	csiph-out.news.tnetconsulting.net	31	31	0	0	0
14	news.swapon.de	16	0	0	0	16
15	news.fcku.it	2	2	0	0	0
16	news.samoylyk.net	1	0	0	0	1
17	xanadu-bbs.net	1	0	0	0	1
TOTAL: 17		3479	443	3	1	3032

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	99
it-alt.sport.calcio.juventus	32
scinet.stats	28
lada.talk	24
alt.politics	22
mozilla.support.firefox	20
alt.binaries.pictures.purebeauty	20
mozilla.support.thunderbird	19
free.it.religioni.scientology	16
alt.windows7.general	12
alt.politics.usa	9
spline.eisfair	8
mozilla.dev.apps.seamonkey	7
lada.auto	7
trollnet.cinema	7
alt.religion	6
att.jobs	6
spline.eisfair.dev	6
at.jobs	5
alt.philosophy	5
TOTAL: 70	443

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)	1613
EMP (phn path)	249
EMP (phl)	171
EMP (phn nph)	121
Too many newsgroups	76
0	56
Binary Payload	18
Binary: misplaced binary	5
HTML Multipart	3
Scoring filter	2
Too many newsgroups (low_xpost)	1
HTML post	1
TOTAL: 12	2316

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	53
	csiph-out.feed.uzoreto.com	43
	news.freedyn.net	26
	newsfeeds.xmission.com	20
	csiph-out.news.weretis.net	14
	news-out.neodome.net	12
	csiph-out.news.xs4all.nl	4
	feeder.eternal-september.org	3
	news.samoylyk.net	1
	TOTAL: 9	176
Including strange strings
	newsfeed.fu-berlin.de	400
	news-out.aioe.org	60
	csiph-out.news.xs4all.nl	31
	news.swapon.de	14
	newsfeeds.xmission.com	13
	csiph-out.feed.uzoreto.com	5
	news-out.mixmin.net	5
	newsfeed.neva.ru	2
	csiph-out.feeder.erje.net	2
	csiph-out.news.weretis.net	1
	TOTAL: 10	533
No colon-space in header
	csiph-out.news.xs4all.nl	4
	csiph-out.feeder.erje.net	2
	TOTAL: 2	6
TOTAL: 4		760

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	17385	873	12867	0	0	5%	23:59:43
2	fu-berlin.de	12668	479	12189	0	0	3%	23:59:55
3	news.samoylyk.net	14072	471	13223	6	0	3%	23:50:05
4	news.bbs.nz	25876	448	12753	32	0	1%	23:50:05
5	newsfeed.xs4all.nl	13389	215	11496	3	16	1%	23:54:32
6	tnet	14675	149	13102	1	0	1%	23:56:35
7	neva.ru	12180	76	11935	74	0	0%	23:59:18
8	xmission	13611	75	10284	4	0	0%	23:58:48
9	news.freedyn.net	13333	30	13008	22	0	0%	23:58:54
10	aioe	9395	26	9009	1	0	0%	23:50:05
11	neodome.net	13768	22	13360	0	0	0%	23:56:53
12	weretis.net	13134	22	12551	1	0	0%	23:50:32
13	news.mixmin.net	12419	22	12098	2	0	0%	23:59:55
14	news.swapon.de	1259	21	1230	0	0	1%	23:29:04
15	news.uzoreto.com	13241	18	12869	21	0	0%	23:50:05
16	erje	12826	16	12667	81	0	0%	23:59:55
17	news.fcku.it	869	0	866	0	0	0%	20:41:04
18	usenet.pasdenom.info	0	0	0	0	13255	0%	23:58:43
19	eternal-september	0	0	0	0	12081	0%	23:59:55
TOTAL: 19		214100	2963	185507	248	25352	1%	451:04:06

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	6.4 MB	0.0 KB	6.4 MB	0.1 KB/s	7.5 KB	23:59:43
2	fu-berlin.de	5.2 MB	0.0 KB	5.2 MB	0.1 KB/s	11.1 KB	23:59:55
3	news.samoylyk.net	5.2 MB	7.3 KB	5.2 MB	0.1 KB/s	11.2 KB	23:50:05
4	news.bbs.nz	1.1 MB	107.4 KB	1.2 MB	0.0 KB/s	2.5 KB	23:50:05
5	newsfeed.xs4all.nl	636.7 KB	72.4 KB	709.1 KB	0.0 KB/s	3.3 KB	23:54:32
6	tnet	350.8 KB	4.4 KB	355.3 KB	0.0 KB/s	2.4 KB	23:56:35
7	xmission	227.9 KB	9.7 KB	237.6 KB	0.0 KB/s	3.0 KB	23:58:48
8	neva.ru	177.2 KB	166.9 KB	344.2 KB	0.0 KB/s	2.3 KB	23:59:18
9	news.freedyn.net	110.4 KB	198.4 KB	308.8 KB	0.0 KB/s	5.9 KB	23:58:54
10	news.mixmin.net	89.5 KB	2.9 KB	92.4 KB	0.0 KB/s	3.8 KB	23:59:55
11	aioe	56.7 KB	1.2 KB	58.0 KB	0.0 KB/s	2.1 KB	23:50:05
12	neodome.net	51.4 KB	0.0 KB	51.4 KB	0.0 KB/s	2.3 KB	23:56:53
13	weretis.net	50.3 KB	1.1 KB	51.4 KB	0.0 KB/s	2.2 KB	23:50:32
14	news.swapon.de	50.2 KB	0.0 KB	50.2 KB	0.0 KB/s	2.4 KB	23:29:04
15	news.uzoreto.com	32.7 KB	76.5 KB	109.2 KB	0.0 KB/s	2.8 KB	23:50:05
16	erje	26.1 KB	266.3 KB	292.4 KB	0.0 KB/s	3.0 KB	23:59:55
17	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:41:04
18	usenet.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:43
19	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:55
TOTAL: 19		19.7 MB	914.7 KB	20.6 MB	0.0 KB/s	6.6 KB	451:04:06

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	071-013-248-064.res.spectrum.com	31	35	84.7 KB	39	15	12	08:14:02
2	91.187.33.87	94	31	78.6 KB	33	6	5	25:47:29
3	a131033.upc-a.chello.nl	32	23	63.4 KB	7	0	0	07:50:38
TOTAL: 3		157	89	226.7 KB	79	21	17	43:56:32

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.res.spectrum.com	31	35	84.7 KB	39	15	12	08:14:02
2	unresolved	95	31	78.6 KB	33	6	5	25:47:30
3	*.upc-a.chello.nl	32	23	63.4 KB	7	0	0	07:50:38
4	*.ph.ph.cox.net	4	0	0.0 KB	0	0	0	02:00:25
5	*.range86-130.btcentralplus.com	4	0	0.0 KB	0	0	0	00:03:54
6	*.w90-20.abo.wanadoo.fr	1	0	0.0 KB	0	0	0	00:00:01
7	*.us-west-1.compute.amazonaws.com	1	0	0.0 KB	0	0	0	00:00:00
TOTAL: 7		168	89	226.7 KB	79	21	17	43:56:32

NNRP auth users [Top 20]:

User	Conn
ohlandl	12
slavotinek	4
TOTAL: 2	16

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	7.174	1.380	0.012	25:47:29
071-013-248-064.res.spectrum.com	3.003	0.448	0.029	08:14:02
a131033.upc-a.chello.nl	2.553	0.439	0.000	07:50:38
ip72-222-200-117.ph.ph.cox.net	0.410	0.080	0.000	02:00:25
host86-130-133-6.range86-130.btcentralplus.com	0.271	0.045	0.000	00:03:54
aorleans-654-1-100-204.w90-20.abo.wanadoo.fr	0.115	0.000	0.000	00:00:01
91.241.19.60	0.066	0.007	0.000	00:00:00
ec2-13-57-254-51.us-west-1.compute.amazonaws.com	0.062	0.015	0.000	00:00:00
TOTAL: 8	13.654	2.414	0.041	43:56:32

Curious NNRP server explorers [Top 20]:

System	Conn
ip72-222-200-117.ph.ph.cox.net	4
host86-130-133-6.range86-130.btcentralplus.com	4
ec2-13-57-254-51.us-west-1.compute.amazonaws.com	1
aorleans-654-1-100-204.w90-20.abo.wanadoo.fr	1
91.241.19.60	1
TOTAL: 5	11

NNRP unrecognized commands (by host):

System	Conn
91.241.19.60	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
^C	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
071-013-248-064.res.spectrum.com	10	0
ip72-222-200-117.ph.ph.cox.net	4	0
91.187.33.87	3	0
91.241.19.60	1	1
TOTAL: 4	18	1

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Dec 8 04:15:00 -- Dec 9 04:15:00