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

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Nov 21 10:01:34 csiph nnrpd[24569]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 21 10:01:34 csiph nnrpd[24569]: 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 21 16:32:03 csiph nnrpd[39322]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 21 16:32:03 csiph nnrpd[39322]: 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 21 23:42:28 csiph nnrpd[55092]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 21 23:42:28 csiph nnrpd[55092]: 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	18463	47.6%	4.6 MB	62.2%
innd	15795	40.7%	2.1 MB	27.9%
innfeed	3473	9.0%	633.7 KB	8.3%
nnrpd	1001	2.6%	110.7 KB	1.5%
nocem	36	0.1%	4.2 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	38769	100.0%	7.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	296395	55.4%
Negative hits	220605	41.2%
Do not exist	17796	3.3%
Cache misses	216	0.0%
TOTAL: 4	535012	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.783	0.0%	24	0.000	32.625	92.500
article cleanup	00:00:00.118	0.0%	17455	0.000	0.007	0.039
article logging	00:00:00.498	0.0%	17994	0.000	0.028	1.537
article parse	00:00:02.087	0.0%	54577	0.000	0.038	1.893
article write	00:00:46.625	0.1%	14390	0.202	3.240	118.526
artlog/artcncl	00:00:00.001	0.0%	24	0.000	0.042	1.000
artlog/artparse	00:00:00.030	0.0%	391	0.000	0.077	2.000
data move	00:00:01.184	0.0%	511318	0.000	0.002	0.224
hisgrep/artcncl	00:00:00.010	0.0%	23	0.000	0.435	7.000
hishave/artcncl	00:00:00.002	0.0%	24	0.000	0.083	1.000
hishave/artparse	00:00:00.000	0.0%	390	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:18.980	0.0%	536193	0.001	0.035	0.454
history sync	00:00:01.997	0.0%	380	0.000	5.255	176.500
history write	00:00:26.807	0.0%	17454	0.227	1.536	20.909
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:01.541	0.0%	390	0.000	3.951	74.500
idle	23:46:21.966	99.7%	513301	91.649	166.729	694.061
nntp read	00:00:04.093	0.0%	519235	0.002	0.008	0.141
overview write	00:01:26.428	0.1%	14390	0.096	6.006	197.862
perl filter	00:00:39.988	0.0%	17453	1.197	2.291	13.303
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.237	0.0%	28815	0.000	0.008	0.032
TOTAL: 23:51:03.463	23:50:13.375	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.032	0.0%	14439	0.000	0.002	0.028
article prepare	00:00:00.002	0.0%	13260	0.000	0.000	0.050
article read	00:00:00.667	0.0%	1517	0.000	0.440	12.000
backlog stats	00:00:00.226	0.0%	331700	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.316	0.0%	261284	0.000	0.005	0.013
data write	00:00:01.576	0.0%	248492	0.002	0.006	0.014
idle	23:54:27.707	100.0%	331700	127.995	259.475	873.463
status file	00:00:00.211	0.0%	257	0.000	0.821	4.667
TOTAL: 23:54:40.534	23:54:31.737	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	46:15:29.085	99.9%	705	59.500	236211.468	600108.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.011	0.0%	1783	0.000	0.006	0.143
readart	00:00:00.470	0.0%	51	0.000	9.216	42.000
TOTAL: 46:19:09.705	46:15:29.566	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	2	13019	2543	10413	63	19%	21:56:48
2	feeder.eternal-september.org	4	16678	2329	14192	157	13%	24:48:00
3	csiph-out.news.xs4all.nl	494	17089	2134	13563	1392	12%	68:03:36
4	news-out.mixmin.net	2	15687	2112	13311	264	13%	23:59:20
5	news-out.aioe.org	2	13505	1345	12086	74	9%	23:59:54
6	csiph-out.feeder.erje.net	1439	71903	992	70742	169	1%	119:33:31
7	newsfeed.fu-berlin.de	7	14675	839	13619	217	5%	25:08:58
8	csiph-out.news.weretis.net	2	17496	620	16769	107	3%	31:24:52
9	newsfeed.neva.ru	140	12195	499	11421	275	4%	72:17:04
10	csiph-out.feed.uzoreto.com	864	23722	263	22986	473	1%	71:12:33
11	news-out.neodome.net	1	16101	154	15926	21	0%	23:59:56
12	news.fcku.it	13	1222	126	1094	2	10%	22:13:29
13	nntp.pasdenom.info	2	9204	86	9094	24	0%	23:58:33
14	csiph-out.news.tnetconsulting.net	2	15392	41	15247	104	0%	23:59:43
15	news.freedyn.net	1	17071	18	17020	33	0%	23:59:49
16	localhost	35	9	9	0	0	100%	00:00:03
17	news.samoylyk.net	1	13815	4	13809	2	0%	23:59:50
18	news.bbs.nz	1	14534	2	14532	0	0%	23:59:52
19	news.swapon.de	3	27	0	27	0	0%	01:36:02
20	xanadu-bbs.net	29	367	0	367	0	0%	18:44:10
TOTAL: 20		3044	303711	14116	286218	3377	4%	668:56:03

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	7.6 MB	0.0 KB	4.3 MB	11.9 MB	63%	4.7 KB
2	csiph-out.feeder.erje.net	7.4 MB	0.0 KB	2.8 MB	10.3 MB	72%	9.0 KB
3	feeder.eternal-september.org	6.4 MB	1.6 KB	1.0 MB	7.5 MB	86%	3.1 KB
4	csiph-out.news.xs4all.nl	5.3 MB	0.0 KB	8.6 MB	13.9 MB	37%	4.0 KB
5	news-out.mixmin.net	4.7 MB	0.0 KB	576.5 KB	5.2 MB	89%	2.3 KB
6	news-out.aioe.org	4.4 MB	0.0 KB	300.4 KB	4.7 MB	93%	3.4 KB
7	newsfeed.fu-berlin.de	2.0 MB	0.0 KB	437.1 KB	2.4 MB	82%	2.3 KB
8	csiph-out.news.weretis.net	1.6 MB	0.0 KB	2.3 MB	3.9 MB	40%	5.5 KB
9	newsfeed.neva.ru	1.3 MB	0.0 KB	689.8 KB	2.0 MB	66%	2.6 KB
10	csiph-out.feed.uzoreto.com	974.4 KB	0.0 KB	12.8 MB	13.8 MB	6%	19.2 KB
11	news-out.neodome.net	447.3 KB	1.6 KB	88.7 KB	537.6 KB	83%	3.1 KB
12	news.fcku.it	215.3 KB	0.0 KB	121.5 KB	336.7 KB	63%	2.6 KB
13	nntp.pasdenom.info	205.2 KB	0.0 KB	37.2 KB	242.4 KB	84%	2.2 KB
14	csiph-out.news.tnetconsulting.net	162.4 KB	0.0 KB	311.4 KB	473.8 KB	34%	3.3 KB
15	news.freedyn.net	53.7 KB	0.0 KB	1.1 MB	1.2 MB	4%	24.1 KB
16	news.samoylyk.net	43.8 KB	0.0 KB	192.1 KB	235.9 KB	18%	39.3 KB
17	localhost	12.3 KB	0.0 KB	0.0 KB	12.3 KB	100%	1.4 KB
18	news.bbs.nz	4.4 KB	0.0 KB	0.0 KB	4.4 KB	100%	2.2 KB
19	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		42.8 MB	3.2 KB	35.8 MB	78.6 MB	54%	4.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 21 04:15:00 - 04:59:59	190	1.3%	0.07	568.7 KB	1.3%	0.21
Nov 21 05:00:00 - 05:59:59	263	1.8%	0.07	738.2 KB	1.6%	0.21
Nov 21 06:00:00 - 06:59:59	312	2.2%	0.09	1017.6 KB	2.3%	0.28
Nov 21 07:00:00 - 07:59:59	546	3.8%	0.15	2.7 MB	6.1%	0.76
Nov 21 08:00:00 - 08:59:59	558	3.9%	0.15	1.7 MB	3.9%	0.49
Nov 21 09:00:00 - 09:59:59	802	5.6%	0.22	3.1 MB	7.1%	0.88
Nov 21 10:00:00 - 10:59:59	724	5.0%	0.20	1.9 MB	4.3%	0.54
Nov 21 11:00:00 - 11:59:59	827	5.7%	0.23	2.3 MB	5.3%	0.66
Nov 21 12:00:00 - 12:59:59	623	4.3%	0.17	1.6 MB	3.7%	0.46
Nov 21 13:00:00 - 13:59:59	696	4.8%	0.19	2.0 MB	4.6%	0.58
Nov 21 14:00:00 - 14:59:59	747	5.2%	0.21	1.9 MB	4.4%	0.54
Nov 21 15:00:00 - 15:59:59	927	6.4%	0.26	2.6 MB	6.0%	0.75
Nov 21 16:00:00 - 16:59:59	804	5.6%	0.22	2.4 MB	5.4%	0.67
Nov 21 17:00:00 - 17:59:59	878	6.1%	0.24	3.0 MB	6.9%	0.86
Nov 21 18:00:00 - 18:59:59	779	5.4%	0.22	2.2 MB	4.9%	0.61
Nov 21 19:00:00 - 19:59:59	692	4.8%	0.19	1.7 MB	3.8%	0.48
Nov 21 20:00:00 - 20:59:59	730	5.1%	0.20	2.0 MB	4.5%	0.56
Nov 21 21:00:00 - 21:59:59	673	4.7%	0.19	2.0 MB	4.7%	0.58
Nov 21 22:00:00 - 22:59:59	678	4.7%	0.19	2.1 MB	4.8%	0.60
Nov 21 23:00:00 - 23:59:59	543	3.8%	0.15	1.8 MB	4.2%	0.52
Nov 22 00:00:00 - 00:59:59	406	2.8%	0.11	1.2 MB	2.8%	0.35
Nov 22 01:00:00 - 01:59:59	387	2.7%	0.11	1.3 MB	3.0%	0.37
Nov 22 02:00:00 - 02:59:59	285	2.0%	0.08	819.5 KB	1.8%	0.23
Nov 22 03:00:00 - 03:59:59	284	2.0%	0.08	855.2 KB	1.9%	0.24
Nov 22 04:00:00 - 04:15:00	84	0.6%	0.09	252.5 KB	0.6%	0.28
TOTAL: 24:00:00	14438	100.0%	0.17	43.7 MB	100.0%	0.52

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.news.xs4all.nl	1418	48	0	1	1369
2	newsfeed.fu-berlin.de	610	11	0	0	599
3	csiph-out.feed.uzoreto.com	475	19	0	0	456
4	news-out.mixmin.net	333	7	0	0	326
5	newsfeed.neva.ru	276	39	0	0	237
6	csiph-out.news.weretis.net	187	41	0	0	146
7	feeder.eternal-september.org	158	46	1	0	111
8	csiph-out.feeder.erje.net	152	53	0	0	99
9	csiph-out.news.tnetconsulting.net	120	14	0	0	106
10	news-out.aioe.org	94	63	0	0	31
11	newsfeeds.xmission.com	70	14	0	0	56
12	news.freedyn.net	33	0	0	0	33
13	news-out.neodome.net	27	3	1	0	23
14	nntp.pasdenom.info	24	0	0	0	24
15	news.swapon.de	20	0	0	0	20
16	news.fcku.it	2	0	0	0	2
17	news.samoylyk.net	2	0	0	0	2
TOTAL: 17		4001	358	2	1	3640

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	72
alt.windows7.general	43
lada.talk	26
alt.binaries.pictures.purebeauty	24
scinet.stats	23
alt.politics	21
alt.bbs.synchronet	15
spline.eisfair	9
alt.job	9
it-alt.sport.calcio.juventus	9
att.jobs	8
free.it.religioni.scientology	8
alt.religion	6
mozilla.support.thunderbird	5
alt.philosophy	5
aus.jobs	4
alt.women	3
lada.auto	3
ee.arvutid	3
alt.politics.usa	3
TOTAL: 64	358

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)	1952
EMP (phn path)	360
Too many newsgroups	118
EMP (phl)	103
EMP (phn nph)	91
0	70
HTML Multipart	6
Binary: misplaced binary	4
Scoring filter	3
HTML post	2
Binary Payload	1
TOTAL: 11	2710

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	157
	csiph-out.news.xs4all.nl	79
	csiph-out.feeder.erje.net	51
	newsfeeds.xmission.com	49
	csiph-out.news.weretis.net	23
	news.freedyn.net	16
	feeder.eternal-september.org	8
	news-out.mixmin.net	3
	news.fcku.it	2
	news.samoylyk.net	2
	TOTAL: 11	391
Including strange strings
	newsfeed.fu-berlin.de	381
	news-out.mixmin.net	69
	csiph-out.news.xs4all.nl	25
	news-out.aioe.org	21
	news.swapon.de	18
	csiph-out.news.weretis.net	11
	newsfeeds.xmission.com	5
	csiph-out.feed.uzoreto.com	4
	feeder.eternal-september.org	2
	news.freedyn.net	2
	TOTAL: 11	539
No colon-space in header
	csiph-out.feed.uzoreto.com	1
	TOTAL: 1	1
TOTAL: 4		976

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	16918	748	13687	3	0	4%	23:59:42
2	fu-berlin.de	13379	600	12779	0	1	4%	23:59:39
3	news.samoylyk.net	14774	593	13836	5	0	4%	23:59:53
4	news.bbs.nz	26859	402	13404	31	0	1%	23:59:53
5	erje	14186	66	12779	368	0	0%	23:59:13
6	tnet	14978	54	13786	0	4	0%	23:59:54
7	newsfeed.xs4all.nl	12730	51	12144	0	11	0%	23:59:53
8	xmission	13044	34	10946	0	0	0%	23:59:54
9	news.freedyn.net	13902	24	13542	76	0	0%	23:59:43
10	neva.ru	13219	23	13015	111	0	0%	23:59:53
11	aioe	10505	20	10093	0	0	0%	23:59:53
12	usenet.pasdenom.info	14161	17	13734	3	11	0%	23:59:53
13	news.mixmin.net	11630	14	11358	0	0	0%	23:59:29
14	neodome.net	14548	12	14190	0	0	0%	23:59:53
15	eternal-september	11630	11	11374	0	0	0%	23:59:53
16	weretis.net	13317	11	13076	0	0	0%	23:59:54
17	news.swapon.de	1458	9	1442	0	0	0%	23:57:29
18	news.uzoreto.com	13817	9	13567	21	0	0%	23:59:53
19	news.fcku.it	1040	0	1038	0	0	0%	21:27:09
TOTAL: 19		246095	2698	219790	618	27	1%	453:21:03

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	6.8 MB	28.6 KB	6.8 MB	0.1 KB/s	9.2 KB	23:59:42
2	fu-berlin.de	6.4 MB	0.0 KB	6.4 MB	0.1 KB/s	10.9 KB	23:59:39
3	news.samoylyk.net	6.3 MB	64.9 KB	6.4 MB	0.1 KB/s	10.9 KB	23:59:53
4	news.bbs.nz	1.0 MB	113.7 KB	1.1 MB	0.0 KB/s	2.7 KB	23:59:53
5	newsfeed.xs4all.nl	142.1 KB	0.0 KB	142.1 KB	0.0 KB/s	2.8 KB	23:59:53
6	erje	134.5 KB	1.1 MB	1.2 MB	0.0 KB/s	2.8 KB	23:59:13
7	xmission	123.3 KB	0.0 KB	123.3 KB	0.0 KB/s	3.6 KB	23:59:54
8	tnet	100.8 KB	0.0 KB	100.8 KB	0.0 KB/s	1.9 KB	23:59:54
9	news.freedyn.net	40.8 KB	1.1 MB	1.1 MB	0.0 KB/s	11.7 KB	23:59:43
10	aioe	40.1 KB	0.0 KB	40.1 KB	0.0 KB/s	2.0 KB	23:59:53
11	neva.ru	38.9 KB	243.7 KB	282.6 KB	0.0 KB/s	2.1 KB	23:59:53
12	usenet.pasdenom.info	30.9 KB	6.1 KB	37.0 KB	0.0 KB/s	1.9 KB	23:59:53
13	weretis.net	24.5 KB	0.0 KB	24.5 KB	0.0 KB/s	2.2 KB	23:59:54
14	news.mixmin.net	23.7 KB	0.0 KB	23.7 KB	0.0 KB/s	1.7 KB	23:59:29
15	neodome.net	17.1 KB	0.0 KB	17.1 KB	0.0 KB/s	1.4 KB	23:59:53
16	eternal-september	14.8 KB	0.0 KB	14.8 KB	0.0 KB/s	1.3 KB	23:59:53
17	news.uzoreto.com	12.6 KB	73.1 KB	85.7 KB	0.0 KB/s	2.9 KB	23:59:53
18	news.swapon.de	12.3 KB	0.0 KB	12.3 KB	0.0 KB/s	1.4 KB	23:57:29
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:27:09
TOTAL: 19		21.2 MB	2.7 MB	23.9 MB	0.0 KB/s	7.4 KB	453:21:03

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	066-188-083-201.res.spectrum.com	29	20	39.3 KB	21	9	6	09:18:31
2	a131033.upc-a.chello.nl	41	19	36.0 KB	6	0	0	11:21:20
3	athedsl-175833.home.otenet.gr	1	12	20.1 KB	1	0	0	00:00:07
TOTAL: 3		71	51	95.4 KB	28	9	6	46:19:08

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.res.spectrum.com	29	20	39.3 KB	21	9	6	09:18:31
2	*.upc-a.chello.nl	41	19	36.0 KB	6	0	0	11:21:20
3	*.home.otenet.gr	1	12	20.1 KB	1	0	0	00:00:07
4	*.range5-81.btcentralplus.com	3	0	0.0 KB	0	0	0	00:27:37
5	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:00
6	*.net.upcbroadband.cz	16	0	0.0 KB	0	0	0	05:12:01
7	unresolved	52	0	0.0 KB	0	0	0	17:13:51
8	*.ph.ph.cox.net	8	0	0.0 KB	0	0	0	02:45:25
9	*.20.97.83.ro.ovo.sc	1	0	0.0 KB	0	0	0	00:00:12
TOTAL: 9		152	51	95.4 KB	28	9	6	46:19:08

NNRP auth users [Top 20]:

User	Conn
ohlandl	6
TOTAL: 1	6

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	3.509	1.075	0.000	17:13:51
a131033.upc-a.chello.nl	2.914	1.773	0.000	11:21:20
066-188-083-201.res.spectrum.com	2.586	1.308	0.015	09:18:31
ip-78-102-198-192.net.upcbroadband.cz	1.260	0.968	0.000	05:12:01
ip72-222-200-117.ph.ph.cox.net	0.701	0.264	0.000	02:45:25
host5-81-182-240.range5-81.btcentralplus.com	0.214	0.024	0.000	00:27:37
34.20.97.83.ro.ovo.sc	0.063	0.376	0.000	00:00:12
athedsl-175833.home.otenet.gr	0.089	0.032	0.000	00:00:07
scan-141.security.ipip.net	0.069	0.008	0.000	00:00:00
TOTAL: 9	11.405	5.828	0.015	46:19:08

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	52
ip-78-102-198-192.net.upcbroadband.cz	16
ip72-222-200-117.ph.ph.cox.net	8
host5-81-182-240.range5-81.btcentralplus.com	3
scan-141.security.ipip.net	1
34.20.97.83.ro.ovo.sc	1
TOTAL: 6	81

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
Connection: close	1
Host: 69.160.37.58:119	1
GET / HTTP/1.1	1
TOTAL: 4	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
066-188-083-201.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	52	100.0%
TOTAL: 1		52	100.0%

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

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

Daily Usenet report for csiph.com

Nov 21 04:15:00 -- Nov 22 04:15:00