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

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Nov 7 10:10:17 csiph nnrpd[21546]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 7 10:10:17 csiph nnrpd[21546]: 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 7 10:55:15 csiph nnrpd[24059]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 7 10:55:15 csiph nnrpd[24059]: 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 7 16:23:36 csiph nnrpd[39236]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 7 16:23:36 csiph nnrpd[39236]: 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	19312	49.2%	5.0 MB	64.3%
innd	15360	39.1%	2.0 MB	26.5%
innfeed	3711	9.5%	639.5 KB	8.1%
nnrpd	831	2.1%	90.0 KB	1.1%
nocem	27	0.1%	3.3 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	39242	100.0%	7.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	320970	56.2%
Negative hits	231719	40.5%
Do not exist	18658	3.3%
Cache misses	200	0.0%
TOTAL: 4	571547	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.414	0.0%	25	0.500	16.560	32.800
article cleanup	00:00:00.101	0.0%	18347	0.000	0.006	0.037
article logging	00:00:00.335	0.0%	18909	0.000	0.018	0.229
article parse	00:00:01.573	0.0%	55077	0.000	0.029	1.161
article write	00:00:26.669	0.0%	15680	0.158	1.701	65.549
artlog/artcncl	00:00:00.000	0.0%	25	0.000	0.000	0.000
artlog/artparse	00:00:00.007	0.0%	389	0.000	0.018	0.167
data move	00:00:00.230	0.0%	545840	0.000	0.000	0.021
hisgrep/artcncl	00:00:00.020	0.0%	25	0.000	0.800	13.000
hishave/artcncl	00:00:00.002	0.0%	25	0.000	0.080	1.000
hishave/artparse	00:00:00.002	0.0%	373	0.000	0.005	0.200
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:20.282	0.0%	573373	0.002	0.035	0.278
history sync	00:00:01.161	0.0%	364	0.000	3.190	56.667
history write	00:00:36.969	0.0%	18360	0.508	2.014	13.071
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:01.008	0.0%	368	0.000	2.739	178.000
idle	23:57:11.928	99.7%	548436	78.027	157.232	545.959
nntp read	00:00:02.794	0.0%	553572	0.001	0.005	0.058
overview write	00:01:02.558	0.1%	15680	0.067	3.990	87.510
perl filter	00:00:29.632	0.0%	18344	1.183	1.615	9.200
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.239	0.0%	31386	0.000	0.008	0.032
TOTAL: 24:00:58.786	24:00:15.924	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.023	0.0%	16706	0.000	0.001	0.025
article prepare	00:00:00.001	0.0%	11016	0.000	0.000	0.003
article read	00:00:00.513	0.0%	1205	0.000	0.426	13.750
backlog stats	00:00:00.230	0.0%	352947	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.305	0.0%	284034	0.001	0.005	0.009
data write	00:00:01.645	0.0%	268354	0.001	0.006	0.014
idle	23:54:03.879	100.0%	352947	123.287	243.787	780.078
status file	00:00:00.306	0.0%	352	0.000	0.869	21.000
TOTAL: 23:54:16.206	23:54:07.902	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	39:40:01.119	99.8%	505	540.208	282774.493	600139.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.006	0.0%	1259	0.000	0.005	0.167
readart	00:00:00.167	0.0%	31	0.000	5.387	42.000
TOTAL: 39:44:57.181	39:40:01.292	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	newsfeeds.xmission.com	2	15298	3531	11735	32	23%	25:21:15
2	news-out.mixmin.net	2	16599	2741	13636	222	16%	24:08:55
3	feeder.eternal-september.org	5	17427	2519	14769	139	14%	24:38:30
4	csiph-out.news.xs4all.nl	435	17982	1729	15093	1160	9%	68:46:00
5	news-out.aioe.org	3	14761	1411	13297	53	9%	24:08:46
6	csiph-out.news.weretis.net	3	32205	929	31146	130	2%	47:59:25
7	newsfeed.fu-berlin.de	2	16762	878	15547	337	5%	27:30:32
8	csiph-out.feeder.erje.net	1440	78123	732	77272	119	0%	118:43:24
9	newsfeed.neva.ru	150	14038	452	13319	267	3%	72:02:06
10	csiph-out.feed.uzoreto.com	874	25633	305	24852	476	1%	71:14:15
11	news.fcku.it	17	1202	184	1013	5	15%	21:15:57
12	news-out.neodome.net	1	16853	175	16653	25	1%	23:59:55
13	nntp.pasdenom.info	8	10096	91	9998	7	0%	23:48:15
14	news.swapon.de	10	1576	72	1503	1	4%	22:55:16
15	csiph-out.news.tnetconsulting.net	1	15520	42	15463	15	0%	23:59:55
16	news.freedyn.net	8	17985	9	17955	21	0%	23:57:05
17	news.bbs.nz	1	15149	4	15142	3	0%	23:59:52
18	news.samoylyk.net	1	15048	3	15044	1	0%	23:59:54
19	localhost	28	2	2	0	0	100%	00:00:02
20	xanadu-bbs.net	36	441	0	441	0	0%	20:03:20
TOTAL: 20		3027	342700	15809	323878	3013	4%	712:32:39

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	9.6 MB	0.0 KB	1.6 MB	11.1 MB	86%	3.2 KB
2	feeder.eternal-september.org	6.3 MB	0.0 KB	1.6 MB	7.9 MB	79%	3.0 KB
3	csiph-out.feeder.erje.net	6.1 MB	0.0 KB	3.2 MB	9.3 MB	65%	11.2 KB
4	news-out.mixmin.net	5.4 MB	1.6 KB	400.0 KB	5.8 MB	93%	2.0 KB
5	news-out.aioe.org	4.7 MB	0.0 KB	172.2 KB	4.9 MB	96%	3.4 KB
6	csiph-out.news.xs4all.nl	4.3 MB	0.0 KB	6.2 MB	10.5 MB	41%	3.7 KB
7	csiph-out.news.weretis.net	2.4 MB	23.1 KB	1.5 MB	3.9 MB	61%	3.8 KB
8	newsfeed.fu-berlin.de	2.1 MB	0.0 KB	776.1 KB	2.9 MB	73%	2.4 KB
9	csiph-out.feed.uzoreto.com	1.2 MB	0.0 KB	15.9 MB	17.1 MB	6%	22.4 KB
10	newsfeed.neva.ru	1.2 MB	4.3 KB	592.7 KB	1.8 MB	66%	2.5 KB
11	news-out.neodome.net	526.2 KB	1.2 KB	103.6 KB	631.0 KB	83%	3.2 KB
12	news.fcku.it	342.9 KB	0.0 KB	93.2 KB	436.1 KB	78%	2.3 KB
13	news.swapon.de	207.3 KB	0.0 KB	0.9 KB	208.2 KB	99%	2.9 KB
14	nntp.pasdenom.info	200.6 KB	0.0 KB	9.1 KB	209.7 KB	95%	2.1 KB
15	csiph-out.news.tnetconsulting.net	194.6 KB	3.3 KB	136.0 KB	334.0 KB	58%	5.9 KB
16	news.freedyn.net	79.0 KB	0.0 KB	819.2 KB	898.2 KB	8%	29.9 KB
17	news.bbs.nz	11.2 KB	3.9 KB	3.9 KB	19.1 KB	58%	2.7 KB
18	news.samoylyk.net	5.1 KB	0.0 KB	99.8 KB	104.9 KB	4%	26.2 KB
19	localhost	1.9 KB	0.0 KB	0.0 KB	1.9 KB	100%	1.0 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		44.8 MB	37.5 KB	33.1 MB	77.9 MB	57%	4.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 7 04:15:00 - 04:59:59	262	1.7%	0.10	674.2 KB	1.5%	0.25
Nov 7 05:00:00 - 05:59:59	261	1.7%	0.07	801.4 KB	1.8%	0.22
Nov 7 06:00:00 - 06:59:59	302	1.9%	0.08	884.7 KB	1.9%	0.25
Nov 7 07:00:00 - 07:59:59	447	2.9%	0.12	1.3 MB	3.0%	0.38
Nov 7 08:00:00 - 08:59:59	589	3.8%	0.16	2.4 MB	5.5%	0.69
Nov 7 09:00:00 - 09:59:59	727	4.6%	0.20	2.1 MB	4.7%	0.59
Nov 7 10:00:00 - 10:59:59	734	4.7%	0.20	2.2 MB	4.9%	0.62
Nov 7 11:00:00 - 11:59:59	712	4.5%	0.20	2.2 MB	5.0%	0.63
Nov 7 12:00:00 - 12:59:59	739	4.7%	0.21	2.1 MB	4.7%	0.59
Nov 7 13:00:00 - 13:59:59	662	4.2%	0.18	2.0 MB	4.5%	0.57
Nov 7 14:00:00 - 14:59:59	816	5.2%	0.23	2.3 MB	5.1%	0.65
Nov 7 15:00:00 - 15:59:59	816	5.2%	0.23	2.2 MB	5.0%	0.63
Nov 7 16:00:00 - 16:59:59	884	5.6%	0.25	3.0 MB	6.7%	0.85
Nov 7 17:00:00 - 17:59:59	1025	6.5%	0.28	3.3 MB	7.3%	0.93
Nov 7 18:00:00 - 18:59:59	1103	7.0%	0.31	2.7 MB	6.1%	0.77
Nov 7 19:00:00 - 19:59:59	904	5.8%	0.25	2.2 MB	4.9%	0.61
Nov 7 20:00:00 - 20:59:59	769	4.9%	0.21	1.9 MB	4.2%	0.53
Nov 7 21:00:00 - 21:59:59	817	5.2%	0.23	2.0 MB	4.6%	0.58
Nov 7 22:00:00 - 22:59:59	728	4.6%	0.20	1.9 MB	4.2%	0.53
Nov 7 23:00:00 - 23:59:59	611	3.9%	0.17	1.6 MB	3.6%	0.45
Nov 8 00:00:00 - 00:59:59	457	2.9%	0.13	1.4 MB	3.0%	0.38
Nov 8 01:00:00 - 01:59:59	419	2.7%	0.12	1.2 MB	2.6%	0.33
Nov 8 02:00:00 - 02:59:59	391	2.5%	0.11	1.0 MB	2.3%	0.29
Nov 8 03:00:00 - 03:59:59	352	2.2%	0.10	960.9 KB	2.1%	0.27
Nov 8 04:00:00 - 04:15:00	141	0.9%	0.16	416.9 KB	0.9%	0.46
TOTAL: 24:00:00	15668	100.0%	0.18	44.4 MB	100.0%	0.53

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1172	75	0	1097
2	newsfeed.fu-berlin.de	716	11	0	705
3	csiph-out.feed.uzoreto.com	476	18	0	458
4	news-out.mixmin.net	289	8	1	280
5	newsfeed.neva.ru	268	59	1	208
6	csiph-out.news.weretis.net	162	41	1	120
7	feeder.eternal-september.org	139	29	0	110
8	csiph-out.feeder.erje.net	118	39	0	79
9	news-out.aioe.org	104	42	0	62
10	csiph-out.news.tnetconsulting.net	54	51	0	3
11	news.swapon.de	34	0	0	34
12	newsfeeds.xmission.com	29	10	0	19
13	news-out.neodome.net	24	1	0	23
14	news.freedyn.net	20	4	0	16
15	nntp.pasdenom.info	7	0	0	7
16	news.fcku.it	5	2	0	3
17	news.bbs.nz	1	0	0	1
18	news.samoylyk.net	1	0	0	1
TOTAL: 18		3619	390	3	3226

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	50
alt.computer.workshop	42
nntp4net.test	41
alt.politics.usa	28
alt.tv.comedy-central.daily-show	24
scinet.stats	16
mozilla.general	15
alt.politics	13
alt.binaries.pictures.purebeauty	12
alt.philosophy	11
spline.eisfair	9
alt.job	8
free.it.religioni.scientology	7
mozilla.support.thunderbird	7
aus.jobs	7
alt.windows7.general	6
it-alt.sport.calcio.juventus	6
oesterreich.tratsch	5
alt.binaries.ebook	5
att.jobs	5
TOTAL: 67	390

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1450
EMP (phn path)	427
EMP (phl)	177
EMP (phn nph)	100
Too many newsgroups	73
0	41
Binary: misplaced binary	3
HTML Multipart	2
Binary Payload	2
TOTAL: 9	2275

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	85
	csiph-out.news.xs4all.nl	8
	csiph-out.feed.uzoreto.com	8
	TOTAL: 3	101
Huge articles
	csiph-out.feed.uzoreto.com	194
	csiph-out.feeder.erje.net	60
	csiph-out.news.xs4all.nl	54
	newsfeeds.xmission.com	18
	feeder.eternal-september.org	16
	csiph-out.news.weretis.net	14
	news.freedyn.net	8
	csiph-out.news.tnetconsulting.net	3
	news.bbs.nz	1
	news.fcku.it	1
	TOTAL: 14	373
Including strange strings
	newsfeed.fu-berlin.de	389
	news-out.mixmin.net	67
	news-out.aioe.org	53
	news.swapon.de	33
	csiph-out.news.xs4all.nl	11
	csiph-out.news.weretis.net	7
	newsfeeds.xmission.com	1
	newsfeed.neva.ru	1
	TOTAL: 8	562
No colon-space in header
	csiph-out.news.xs4all.nl	15
	TOTAL: 1	15
TOTAL: 4		1051

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	18562	837	14827	4	804	4%	23:59:54
2	fu-berlin.de	14610	504	14106	0	0	3%	23:59:37
3	news.samoylyk.net	16103	489	15168	8	0	3%	23:59:54
4	news.bbs.nz	29527	378	14738	30	1	1%	23:59:52
5	tnet	16130	67	15081	3	0	0%	23:59:52
6	neva.ru	14605	50	14345	116	0	0%	23:59:52
7	newsfeed.xs4all.nl	14322	43	13835	1	26	0%	23:59:53
8	xmission	12747	17	11655	0	0	0%	23:59:54
9	news.freedyn.net	15226	12	14877	51	27	0%	23:59:54
10	usenet.pasdenom.info	15717	12	15046	1	218	0%	23:59:54
11	neodome.net	15858	9	15438	0	0	0%	23:59:17
12	news.mixmin.net	12485	9	12169	0	0	0%	23:59:19
13	erje	15013	7	14770	78	0	0%	23:59:38
14	eternal-september	12813	6	12461	0	0	0%	23:59:35
15	weretis.net	14417	5	14145	0	0	0%	23:59:54
16	aioe	11641	4	11145	0	0	0%	23:59:51
17	news.swapon.de	1512	2	1509	0	0	0%	23:13:37
18	news.uzoreto.com	15274	1	14946	8	0	0%	23:59:36
19	news.fcku.it	965	0	963	0	1	0%	20:39:11
TOTAL: 19		267527	2452	241224	300	1077	0%	451:48:34

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	6.3 MB	8.5 KB	6.3 MB	0.1 KB/s	7.6 KB	23:59:54
2	fu-berlin.de	5.4 MB	0.0 KB	5.4 MB	0.1 KB/s	11.1 KB	23:59:37
3	news.samoylyk.net	5.3 MB	101.7 KB	5.4 MB	0.1 KB/s	11.2 KB	23:59:54
4	news.bbs.nz	930.8 KB	87.9 KB	1018.7 KB	0.0 KB/s	2.5 KB	23:59:52
5	tnet	149.5 KB	5.2 KB	154.8 KB	0.0 KB/s	2.2 KB	23:59:52
6	neva.ru	116.3 KB	318.2 KB	434.5 KB	0.0 KB/s	2.6 KB	23:59:52
7	newsfeed.xs4all.nl	114.3 KB	1.4 KB	115.7 KB	0.0 KB/s	2.6 KB	23:59:53
8	xmission	65.3 KB	0.0 KB	65.3 KB	0.0 KB/s	3.8 KB	23:59:54
9	news.freedyn.net	42.0 KB	820.0 KB	862.0 KB	0.0 KB/s	13.7 KB	23:59:54
10	usenet.pasdenom.info	33.1 KB	1.6 KB	34.6 KB	0.0 KB/s	2.7 KB	23:59:54
11	neodome.net	21.2 KB	0.0 KB	21.2 KB	0.0 KB/s	2.4 KB	23:59:17
12	news.mixmin.net	19.0 KB	0.0 KB	19.0 KB	0.0 KB/s	2.1 KB	23:59:19
13	erje	16.9 KB	250.0 KB	266.9 KB	0.0 KB/s	3.1 KB	23:59:38
14	eternal-september	9.4 KB	0.0 KB	9.4 KB	0.0 KB/s	1.6 KB	23:59:35
15	aioe	8.1 KB	0.0 KB	8.1 KB	0.0 KB/s	2.0 KB	23:59:51
16	weretis.net	7.7 KB	0.0 KB	7.7 KB	0.0 KB/s	1.5 KB	23:59:54
17	news.swapon.de	1.9 KB	0.0 KB	1.9 KB	0.0 KB/s	1.0 KB	23:13:37
18	news.uzoreto.com	0.9 KB	21.5 KB	22.5 KB	0.0 KB/s	2.5 KB	23:59:36
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:39:11
TOTAL: 19		18.6 MB	1.6 MB	20.1 MB	0.0 KB/s	7.5 KB	451:48:34

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	ppp-2-84-162-19.home.otenet.gr	1	21	26.7 KB	1	0	0	00:00:13
2	a131033.upc-a.chello.nl	26	6	7.5 KB	3	0	0	06:01:05
3	131.93.176.49	19	2	2.5 KB	2	2	2	06:54:15
4	ip72-222-200-117.ph.ph.cox.net	7	2	2.5 KB	2	0	0	02:29:22
TOTAL: 4		53	31	39.2 KB	8	2	2	39:44:56

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.home.otenet.gr	1	21	26.7 KB	1	0	0	00:00:13
2	*.upc-a.chello.nl	26	6	7.5 KB	3	0	0	06:01:05
3	unresolved	92	2	2.5 KB	2	2	2	31:04:21
4	*.ph.ph.cox.net	7	2	2.5 KB	2	0	0	02:29:22
5	*.range81-135.btcentralplus.com	3	0	0.0 KB	0	0	0	00:09:53
TOTAL: 5		129	31	39.2 KB	8	2	2	39:44:56

NNRP auth users [Top 20]:

User	Conn
ohlandl	2
TOTAL: 1	2

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	4.500	1.739	0.000	24:09:55
131.93.176.49	1.371	0.309	0.006	06:54:15
a131033.upc-a.chello.nl	1.826	0.585	0.000	06:01:05
ip72-222-200-117.ph.ph.cox.net	0.547	0.284	0.000	02:29:22
host81-135-1-153.range81-135.btcentralplus.com	0.215	0.024	0.000	00:09:53
ppp-2-84-162-19.home.otenet.gr	0.111	0.008	0.000	00:00:13
162.243.128.226	0.076	0.000	0.000	00:00:09
TOTAL: 7	8.646	2.949	0.006	39:44:56

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	72
host81-135-1-153.range81-135.btcentralplus.com	3
162.243.128.226	1
TOTAL: 3	76

NNRP client timeouts [Top 20]:

System	Conn	Peer
131.93.176.49	8	0
ip72-222-200-117.ph.ph.cox.net	4	0
91.187.33.87	1	0
TOTAL: 3	13	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Nov 7 04:15:00 -- Nov 8 04:15:00