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

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Nov 22 11:13:17 csiph nnrpd[79365]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 22 11:13:17 csiph nnrpd[79365]: 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 22 19:47:50 csiph nnrpd[98427]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 22 19:47:50 csiph nnrpd[98427]: 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	18394	47.7%	4.7 MB	62.4%
innd	15795	40.9%	2.1 MB	27.9%
innfeed	3444	8.9%	632.6 KB	8.3%
nnrpd	910	2.4%	98.8 KB	1.3%
nocem	30	0.1%	3.5 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	38574	100.0%	7.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	299101	54.1%
Negative hits	236003	42.7%
Do not exist	17794	3.2%
Cache misses	319	0.1%
TOTAL: 4	553217	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.676	0.0%	21	0.000	32.190	57.900
article cleanup	00:00:00.101	0.0%	17523	0.000	0.006	0.038
article logging	00:00:00.357	0.0%	18017	0.000	0.020	0.377
article parse	00:00:01.557	0.0%	53898	0.000	0.029	0.402
article write	00:00:28.157	0.0%	14558	0.143	1.934	64.184
artlog/artcncl	00:00:00.000	0.0%	21	0.000	0.000	0.000
artlog/artparse	00:00:00.022	0.0%	366	0.000	0.060	14.000
data move	00:00:01.666	0.0%	525952	0.000	0.003	0.194
hisgrep/artcncl	00:00:00.033	0.0%	21	0.000	1.571	9.000
hishave/artcncl	00:00:00.015	0.0%	21	0.000	0.714	5.000
hishave/artparse	00:00:00.001	0.0%	352	0.000	0.003	0.071
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:38.484	0.0%	555989	0.004	0.069	0.639
history sync	00:00:01.129	0.0%	362	0.000	3.119	47.250
history write	00:00:53.320	0.1%	17535	0.442	3.041	11.724
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:01.021	0.0%	352	0.000	2.901	130.000
idle	23:55:05.631	99.6%	526770	90.043	163.460	841.443
nntp read	00:00:05.340	0.0%	534015	0.001	0.010	0.235
overview write	00:02:11.226	0.2%	14558	0.111	9.014	767.585
perl filter	00:00:37.547	0.0%	17521	1.125	2.143	9.574
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.253	0.0%	29146	0.000	0.009	0.032
TOTAL: 24:00:51.602	24:00:06.536	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.028	0.0%	14543	0.000	0.002	0.031
article prepare	00:00:00.001	0.0%	10496	0.000	0.000	0.006
article read	00:00:01.162	0.0%	1073	0.000	1.083	37.400
backlog stats	00:00:00.255	0.0%	332739	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.263	0.0%	263677	0.001	0.005	0.014
data write	00:00:01.569	0.0%	250273	0.001	0.006	0.014
idle	23:54:13.602	100.0%	332739	141.106	258.622	992.208
status file	00:00:00.202	0.0%	272	0.000	0.743	2.000
TOTAL: 23:54:25.989	23:54:18.082	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	39:13:09.064	99.8%	651	66.000	216880.283	600186.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.018	0.0%	1626	0.000	0.011	0.143
readart	00:00:00.779	0.0%	79	0.000	9.861	42.000
TOTAL: 39:17:47.089	39:13:09.861	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news-out.mixmin.net	2	15897	2714	12932	251	17%	24:08:58
2	newsfeeds.xmission.com	2	13961	2186	11713	62	15%	24:13:11
3	csiph-out.news.xs4all.nl	544	17182	2141	13433	1608	12%	67:29:30
4	feeder.eternal-september.org	2	15609	1912	13583	114	12%	20:49:08
5	csiph-out.feeder.erje.net	1440	73294	1220	71953	121	1%	118:43:08
6	news-out.aioe.org	2	13839	1138	12628	73	8%	23:59:48
7	newsfeed.fu-berlin.de	6	15011	787	14083	141	5%	24:50:50
8	csiph-out.news.weretis.net	3	29163	770	28218	175	2%	47:59:44
9	csiph-out.feed.uzoreto.com	864	23198	425	22353	420	1%	71:13:35
10	newsfeed.neva.ru	135	10280	421	9701	158	4%	71:10:35
11	news-out.neodome.net	2	16474	289	16158	27	1%	23:59:44
12	news.fcku.it	12	1536	171	1363	2	11%	21:20:01
13	nntp.pasdenom.info	1	9513	121	9377	15	1%	23:59:55
14	news.swapon.de	7	2961	118	2841	2	3%	44:37:55
15	csiph-out.news.tnetconsulting.net	2	21641	40	21477	124	0%	32:50:02
16	news.freedyn.net	1	17148	14	17109	25	0%	23:59:57
17	localhost	39	13	13	0	0	100%	00:00:00
18	news.samoylyk.net	2	14057	7	14050	0	0%	23:59:52
19	news.bbs.nz	2	13568	5	13563	0	0%	23:59:51
20	xanadu-bbs.net	24	468	0	468	0	0%	21:11:04
TOTAL: 20		3092	324813	14492	307003	3318	4%	734:36:48

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	6.7 MB	1.6 KB	2.7 MB	9.4 MB	71%	7.2 KB
2	newsfeeds.xmission.com	6.6 MB	0.0 KB	3.7 MB	10.3 MB	63%	4.7 KB
3	news-out.mixmin.net	5.6 MB	0.0 KB	460.9 KB	6.1 MB	92%	2.1 KB
4	csiph-out.news.xs4all.nl	5.6 MB	0.0 KB	8.7 MB	14.3 MB	39%	3.9 KB
5	feeder.eternal-september.org	5.3 MB	0.0 KB	1.6 MB	6.9 MB	76%	3.5 KB
6	news-out.aioe.org	3.9 MB	0.0 KB	210.3 KB	4.1 MB	95%	3.5 KB
7	csiph-out.news.weretis.net	2.0 MB	0.0 KB	1.5 MB	3.6 MB	57%	3.9 KB
8	newsfeed.fu-berlin.de	1.8 MB	0.0 KB	303.2 KB	2.1 MB	85%	2.3 KB
9	csiph-out.feed.uzoreto.com	1.6 MB	0.0 KB	12.2 MB	13.8 MB	11%	16.7 KB
10	newsfeed.neva.ru	1.1 MB	0.0 KB	555.2 KB	1.7 MB	67%	2.9 KB
11	news-out.neodome.net	886.5 KB	0.0 KB	55.1 KB	941.6 KB	94%	3.0 KB
12	news.swapon.de	306.3 KB	0.0 KB	92.6 KB	398.9 KB	76%	3.3 KB
13	news.fcku.it	276.2 KB	0.0 KB	2.3 KB	278.5 KB	99%	1.6 KB
14	nntp.pasdenom.info	261.2 KB	0.0 KB	19.2 KB	280.4 KB	93%	2.1 KB
15	csiph-out.news.tnetconsulting.net	170.1 KB	0.0 KB	478.0 KB	648.1 KB	26%	4.0 KB
16	news.freedyn.net	120.4 KB	0.0 KB	513.3 KB	633.7 KB	19%	16.2 KB
17	news.samoylyk.net	29.8 KB	0.0 KB	0.0 KB	29.8 KB	100%	4.3 KB
18	localhost	17.4 KB	0.0 KB	0.0 KB	17.4 KB	100%	1.3 KB
19	news.bbs.nz	8.6 KB	0.0 KB	0.0 KB	8.6 KB	100%	1.7 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		42.3 MB	1.6 KB	33.1 MB	75.4 MB	56%	4.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 22 04:15:00 - 04:59:59	205	1.4%	0.08	559.0 KB	1.3%	0.21
Nov 22 05:00:00 - 05:59:59	276	1.9%	0.08	776.4 KB	1.8%	0.22
Nov 22 06:00:00 - 06:59:59	272	1.9%	0.08	825.3 KB	1.9%	0.23
Nov 22 07:00:00 - 07:59:59	335	2.3%	0.09	1.2 MB	2.8%	0.34
Nov 22 08:00:00 - 08:59:59	554	3.8%	0.15	2.4 MB	5.5%	0.67
Nov 22 09:00:00 - 09:59:59	741	5.1%	0.21	2.5 MB	5.8%	0.70
Nov 22 10:00:00 - 10:59:59	878	6.0%	0.24	2.7 MB	6.4%	0.78
Nov 22 11:00:00 - 11:59:59	873	6.0%	0.24	2.4 MB	5.5%	0.67
Nov 22 12:00:00 - 12:59:59	776	5.3%	0.22	2.1 MB	4.9%	0.59
Nov 22 13:00:00 - 13:59:59	768	5.3%	0.21	2.5 MB	5.8%	0.70
Nov 22 14:00:00 - 14:59:59	767	5.3%	0.21	2.1 MB	4.9%	0.59
Nov 22 15:00:00 - 15:59:59	902	6.2%	0.25	2.4 MB	5.7%	0.69
Nov 22 16:00:00 - 16:59:59	869	6.0%	0.24	2.4 MB	5.5%	0.67
Nov 22 17:00:00 - 17:59:59	846	5.8%	0.23	2.1 MB	4.9%	0.59
Nov 22 18:00:00 - 18:59:59	809	5.6%	0.22	2.3 MB	5.4%	0.65
Nov 22 19:00:00 - 19:59:59	824	5.7%	0.23	2.4 MB	5.7%	0.69
Nov 22 20:00:00 - 20:59:59	714	4.9%	0.20	2.0 MB	4.7%	0.56
Nov 22 21:00:00 - 21:59:59	669	4.6%	0.19	1.7 MB	4.0%	0.49
Nov 22 22:00:00 - 22:59:59	612	4.2%	0.17	1.6 MB	3.7%	0.45
Nov 22 23:00:00 - 23:59:59	499	3.4%	0.14	1.5 MB	3.5%	0.43
Nov 23 00:00:00 - 00:59:59	377	2.6%	0.10	1.2 MB	2.8%	0.33
Nov 23 01:00:00 - 01:59:59	333	2.3%	0.09	1.1 MB	2.5%	0.30
Nov 23 02:00:00 - 02:59:59	274	1.9%	0.08	861.1 KB	2.0%	0.24
Nov 23 03:00:00 - 03:59:59	302	2.1%	0.08	1.0 MB	2.5%	0.30
Nov 23 04:00:00 - 04:15:00	76	0.5%	0.08	223.9 KB	0.5%	0.25
TOTAL: 24:00:00	14551	100.0%	0.17	42.5 MB	100.0%	0.50

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.news.xs4all.nl	1619	58	0	0	1561
2	newsfeed.fu-berlin.de	493	9	0	0	484
3	csiph-out.feed.uzoreto.com	422	38	0	0	384
4	news-out.mixmin.net	314	8	0	0	306
5	csiph-out.news.weretis.net	198	69	0	0	129
6	newsfeed.neva.ru	158	83	0	0	75
7	feeder.eternal-september.org	136	37	1	0	98
8	csiph-out.feeder.erje.net	123	32	1	1	89
9	csiph-out.news.tnetconsulting.net	113	7	0	0	106
10	news-out.aioe.org	93	60	0	0	33
11	newsfeeds.xmission.com	70	18	0	0	52
12	news.freedyn.net	24	2	0	0	22
13	news-out.neodome.net	21	0	0	0	21
14	news.swapon.de	21	0	0	0	21
15	nntp.pasdenom.info	15	0	0	0	15
16	news.fcku.it	2	1	0	0	1
TOTAL: 16		3822	422	2	1	3397

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	105
lada.talk	62
alt.politics	35
alt.bbs.synchronet	25
scinet.stats	19
alt.windows7.general	17
alt.binaries.pictures.purebeauty	14
lada.auto	11
it-alt.sport.calcio.juventus	8
alt.philosophy	8
spline.eisfair	8
alt.testing	6
mozilla.support.seamonkey	6
free.it.religioni.scientology	6
mozilla.test	5
mozilla.dev.apps.seamonkey	5
alt.binaries.ebooks	4
alt.survival	4
spline.eisfair.dev	4
alt.binaries.ebook	4
TOTAL: 71	422

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)	1818
EMP (phn path)	284
EMP (phl)	133
EMP (phn nph)	129
Too many newsgroups	101
0	52
HTML Multipart	9
Binary: misplaced binary	7
HTML post	1
Too many newsgroups (meow)	1
Scoring filter	1
TOTAL: 11	2536

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	50
	csiph-out.feed.uzoreto.com	5
	csiph-out.news.xs4all.nl	3
	TOTAL: 3	58
Huge articles
	csiph-out.feed.uzoreto.com	135
	csiph-out.news.xs4all.nl	75
	csiph-out.feeder.erje.net	52
	newsfeeds.xmission.com	47
	feeder.eternal-september.org	26
	csiph-out.news.weretis.net	12
	news.freedyn.net	4
	csiph-out.news.tnetconsulting.net	2
	TOTAL: 8	353
Including strange strings
	newsfeed.fu-berlin.de	364
	news-out.mixmin.net	63
	news.swapon.de	21
	news-out.aioe.org	20
	csiph-out.news.xs4all.nl	11
	csiph-out.news.weretis.net	9
	newsfeeds.xmission.com	3
	csiph-out.feeder.erje.net	2
	csiph-out.feed.uzoreto.com	1
	TOTAL: 9	494
No colon-space in header
	csiph-out.news.xs4all.nl	13
	TOTAL: 1	13
TOTAL: 4		918

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	16935	684	13854	13	0	4%	23:59:53
2	fu-berlin.de	13598	513	13085	0	0	3%	23:59:39
3	news.samoylyk.net	14898	506	14032	6	0	3%	23:59:54
4	news.bbs.nz	27244	377	13628	30	0	1%	23:59:41
5	neva.ru	13485	70	13253	100	0	0%	23:59:41
6	newsfeed.xs4all.nl	12828	46	12292	0	13	0%	23:59:53
7	aioe	10510	29	10066	0	0	0%	23:59:49
8	tnet	15564	25	14015	0	6	0%	23:59:54
9	erje	14016	23	13124	18	0	0%	23:59:54
10	xmission	13301	17	11761	0	0	0%	23:59:47
11	news.freedyn.net	14047	17	13698	43	0	0%	23:59:50
12	neodome.net	14557	16	14197	0	0	0%	23:59:53
13	news.mixmin.net	11218	16	10945	0	0	0%	23:59:36
14	weretis.net	13383	14	13168	0	0	0%	23:59:54
15	eternal-september	12116	13	11837	0	0	0%	23:59:49
16	news.uzoreto.com	13885	12	13595	12	0	0%	23:59:53
17	usenet.pasdenom.info	14259	11	13905	1	0	0%	23:59:54
18	news.swapon.de	1495	10	1476	3	0	0%	23:42:19
19	news.fcku.it	1293	0	1293	0	0	0%	20:58:54
TOTAL: 19		248632	2399	223224	226	19	0%	452:38:07

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	5.2 MB	107.1 KB	5.3 MB	0.1 KB/s	7.8 KB	23:59:53
2	fu-berlin.de	4.8 MB	0.0 KB	4.8 MB	0.1 KB/s	9.5 KB	23:59:39
3	news.samoylyk.net	4.7 MB	31.7 KB	4.8 MB	0.1 KB/s	9.5 KB	23:59:54
4	news.bbs.nz	956.1 KB	104.3 KB	1.0 MB	0.0 KB/s	2.6 KB	23:59:41
5	neva.ru	128.8 KB	243.0 KB	371.7 KB	0.0 KB/s	2.2 KB	23:59:41
6	newsfeed.xs4all.nl	90.6 KB	0.0 KB	90.6 KB	0.0 KB/s	2.0 KB	23:59:53
7	aioe	70.6 KB	0.0 KB	70.6 KB	0.0 KB/s	2.4 KB	23:59:49
8	erje	45.3 KB	109.3 KB	154.6 KB	0.0 KB/s	3.8 KB	23:59:54
9	tnet	44.8 KB	0.0 KB	44.8 KB	0.0 KB/s	1.8 KB	23:59:54
10	news.freedyn.net	31.3 KB	513.5 KB	544.7 KB	0.0 KB/s	9.1 KB	23:59:50
11	xmission	31.2 KB	0.0 KB	31.2 KB	0.0 KB/s	1.8 KB	23:59:47
12	neodome.net	30.0 KB	0.0 KB	30.0 KB	0.0 KB/s	1.9 KB	23:59:53
13	news.mixmin.net	25.3 KB	0.0 KB	25.3 KB	0.0 KB/s	1.6 KB	23:59:36
14	news.uzoreto.com	20.9 KB	42.3 KB	63.2 KB	0.0 KB/s	2.6 KB	23:59:53
15	weretis.net	20.2 KB	0.0 KB	20.2 KB	0.0 KB/s	1.4 KB	23:59:54
16	eternal-september	17.4 KB	0.0 KB	17.4 KB	0.0 KB/s	1.3 KB	23:59:49
17	usenet.pasdenom.info	16.2 KB	1.2 KB	17.4 KB	0.0 KB/s	1.5 KB	23:59:54
18	news.swapon.de	13.4 KB	4.0 KB	17.4 KB	0.0 KB/s	1.3 KB	23:42:19
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:58:54
TOTAL: 19		16.2 MB	1.1 MB	17.4 MB	0.0 KB/s	6.8 KB	452:38:07

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	athedsl-175833.home.otenet.gr	3	37	84.7 KB	1	0	0	00:00:28
2	a131033.upc-a.chello.nl	17	22	53.9 KB	4	0	0	03:57:24
3	066-188-083-201.res.spectrum.com	37	20	50.5 KB	22	13	10	10:12:20
TOTAL: 3		57	79	189.1 KB	27	13	10	39:17:46

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.home.otenet.gr	3	37	84.7 KB	1	0	0	00:00:28
2	*.upc-a.chello.nl	17	22	53.9 KB	4	0	0	03:57:24
3	*.res.spectrum.com	37	20	50.5 KB	22	13	10	10:12:20
4	*.range5-81.btcentralplus.com	2	0	0.0 KB	0	0	0	00:07:03
5	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:00
6	unresolved	73	0	0.0 KB	0	0	0	24:00:24
7	*.ph.ph.cox.net	2	0	0.0 KB	0	0	0	01:00:04
TOTAL: 7		135	79	189.1 KB	27	13	10	39:17:46

NNRP auth users [Top 20]:

User	Conn
ohlandl	10
TOTAL: 1	10

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	4.903	3.654	0.000	24:00:14
066-188-083-201.res.spectrum.com	3.046	3.750	0.023	10:12:20
a131033.upc-a.chello.nl	1.320	0.234	0.000	03:57:24
ip72-222-200-117.ph.ph.cox.net	0.187	0.058	0.000	01:00:04
host5-81-182-240.range5-81.btcentralplus.com	0.118	0.042	0.000	00:07:03
athedsl-175833.home.otenet.gr	0.217	0.058	0.000	00:00:28
192.241.222.113	0.070	0.210	0.000	00:00:09
scan-141.security.ipip.net	0.063	0.014	0.000	00:00:00
TOTAL: 8	9.924	8.020	0.023	39:17:46

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	72
ip72-222-200-117.ph.ph.cox.net	2
host5-81-182-240.range5-81.btcentralplus.com	2
scan-141.security.ipip.net	1
192.241.222.113	1
TOTAL: 5	78

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
TOTAL: 1	4

NNRP unrecognized commands (by command):

Command	Count
User-Agent: HTTP Banner Detection (https...	1
GET / HTTP/1.1	1
Host: 69.160.37.58:119	1
Connection: close	1
TOTAL: 4	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
066-188-083-201.res.spectrum.com	11	0
ip72-222-200-117.ph.ph.cox.net	2	0
TOTAL: 2	13	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Nov 22 04:15:00 -- Nov 23 04:15:00