Daily Usenet report for csiph.com: Jan 11 04:15:00

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Jan 11 13:13:59 csiph nnrpd[72986]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 11 13:13:59 csiph nnrpd[72986]: 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]
Jan 11 20:02:30 csiph nnrpd[96229]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 11 20:02:30 csiph nnrpd[96229]: 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	22441	52.3%	5.6 MB	66.9%
innd	14713	34.3%	1.9 MB	23.0%
innfeed	3514	8.2%	613.9 KB	7.2%
nnrpd	2258	5.3%	245.1 KB	2.9%
nocem	6	0.0%	0.7 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	42933	100.0%	8.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	342989	54.2%
Negative hits	267753	42.3%
Do not exist	21768	3.4%
Cache misses	382	0.1%
TOTAL: 4	632892	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.628	0.0%	23	0.000	27.304	58.000
article cleanup	00:00:00.139	0.0%	21342	0.000	0.007	0.037
article logging	00:00:00.476	0.0%	21948	0.000	0.022	0.373
article parse	00:00:01.876	0.0%	73983	0.000	0.025	0.157
article write	00:00:56.310	0.1%	17344	0.155	3.247	87.462
artlog/artcncl	00:00:00.001	0.0%	23	0.000	0.043	0.100
artlog/artparse	00:00:00.051	0.0%	475	0.000	0.107	4.500
data move	00:00:00.245	0.0%	607332	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.024	0.0%	23	0.000	1.043	9.000
hishave/artcncl	00:00:00.004	0.0%	23	0.000	0.174	1.000
hishave/artparse	00:00:00.001	0.0%	459	0.000	0.002	0.028
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:00.965	0.1%	634917	0.001	0.096	0.565
history sync	00:00:01.275	0.0%	362	0.000	3.522	48.667
history write	00:01:05.061	0.1%	21357	0.778	3.046	10.556
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:01.176	0.0%	459	0.000	2.562	19.000
idle	23:54:15.832	99.5%	598955	66.645	143.677	432.088
nntp read	00:00:02.409	0.0%	609758	0.002	0.004	0.008
overview write	00:02:10.481	0.2%	17344	0.186	7.523	426.652
perl filter	00:00:31.576	0.0%	21341	1.186	1.480	2.000
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.275	0.0%	34694	0.000	0.008	0.031
TOTAL: 24:00:54.909	24:00:08.805	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.043	0.0%	17783	0.000	0.002	0.036
article prepare	00:00:00.002	0.0%	14638	0.000	0.000	0.007
article read	00:00:00.391	0.0%	1821	0.000	0.215	44.000
backlog stats	00:00:00.236	0.0%	386638	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.368	0.0%	302084	0.001	0.005	0.010
data write	00:00:01.852	0.0%	287429	0.002	0.006	0.014
idle	23:55:09.516	100.0%	386638	116.692	222.714	669.891
status file	00:00:00.274	0.0%	285	0.000	0.961	13.000
TOTAL: 23:55:21.323	23:55:13.682	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:03.861	0.0%	16754	0.210	0.230	0.241
idle	55:51:32.264	99.5%	18774	141.352	10711.210	600097.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:05.094	0.0%	174836	0.000	0.029	0.167
readart	00:00:03.074	0.0%	262	0.000	11.733	42.000
TOTAL: 56:08:10.871	55:51:44.293	99.5%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.xs4all.nl	0	10229	4328	3858	2043	42%	71:13:08
2	newsfeeds.xmission.com	2	15895	3288	12583	24	20%	23:02:37
3	feeder.eternal-september.org	15	7141	2596	4437	108	36%	27:32:13
4	news-out.mixmin.net	3	19029	1233	17377	419	6%	24:08:43
5	news-out.aioe.org	3	16687	1071	15545	71	6%	24:09:03
6	newsfeed.fu-berlin.de	11	15378	976	14243	159	6%	21:06:16
7	csiph-out.feeder.erje.net	1440	88044	887	86577	580	1%	118:46:51
8	csiph-out.news.weretis.net	5	48641	799	47452	390	1%	63:32:17
9	newsfeed.neva.ru	146	19181	744	18120	317	3%	71:18:06
10	news.fcku.it	16	2201	349	1831	21	15%	22:55:48
11	news-out.neodome.net	2	19231	292	18906	33	1%	23:59:48
12	csiph-out.feed.uzoreto.com	864	28237	266	27683	288	0%	71:06:41
13	nntp.pasdenom.info	3	11717	179	11528	10	1%	23:58:09
14	news.swapon.de	4	1591	67	1524	0	4%	23:58:39
15	news.freedyn.net	2	20816	60	20603	153	0%	24:09:01
16	csiph-out.news.tnetconsulting.net	2	15666	31	15619	16	0%	23:59:53
17	news.samoylyk.net	1	16797	10	16781	6	0%	23:59:53
18	news.bbs.nz	2	15400	6	15394	0	0%	23:59:51
19	localhost	27	1	1	0	0	100%	00:00:02
20	xanadu-bbs.net	28	507	0	507	0	0%	21:56:12
TOTAL: 20		2576	372389	17183	350568	4638	4%	728:53:11

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.xs4all.nl	12.1 MB	1.4 KB	16.7 MB	28.7 MB	41%	4.6 KB
2	newsfeeds.xmission.com	9.7 MB	0.0 KB	656.7 KB	10.4 MB	93%	3.2 KB
3	csiph-out.feeder.erje.net	6.8 MB	0.0 KB	4.3 MB	11.1 MB	61%	7.8 KB
4	feeder.eternal-september.org	6.4 MB	0.0 KB	570.7 KB	7.0 MB	92%	2.6 KB
5	news-out.mixmin.net	3.1 MB	0.0 KB	4.7 MB	7.8 MB	39%	4.8 KB
6	news-out.aioe.org	3.0 MB	0.0 KB	167.0 KB	3.2 MB	94%	2.9 KB
7	csiph-out.news.weretis.net	2.1 MB	0.0 KB	4.2 MB	6.4 MB	33%	5.5 KB
8	newsfeed.fu-berlin.de	2.1 MB	0.0 KB	331.3 KB	2.4 MB	86%	2.2 KB
9	newsfeed.neva.ru	1.9 MB	0.0 KB	1.9 MB	3.8 MB	50%	3.7 KB
10	csiph-out.feed.uzoreto.com	1.1 MB	0.0 KB	5.7 MB	6.7 MB	15%	12.5 KB
11	news-out.neodome.net	868.2 KB	0.0 KB	1.1 MB	2.0 MB	42%	6.2 KB
12	news.fcku.it	591.2 KB	0.0 KB	30.4 KB	621.6 KB	95%	1.7 KB
13	nntp.pasdenom.info	403.1 KB	0.0 KB	15.0 KB	418.1 KB	96%	2.2 KB
14	news.freedyn.net	269.8 KB	0.0 KB	6.7 MB	7.0 MB	3%	33.5 KB
15	news.swapon.de	156.6 KB	0.0 KB	0.0 KB	156.6 KB	100%	2.3 KB
16	csiph-out.news.tnetconsulting.net	139.7 KB	0.0 KB	43.8 KB	183.5 KB	76%	3.9 KB
17	news.samoylyk.net	16.5 KB	0.0 KB	332.3 KB	348.8 KB	4%	21.8 KB
18	news.bbs.nz	8.8 KB	0.0 KB	0.0 KB	8.8 KB	100%	1.5 KB
19	localhost	1.6 KB	0.0 KB	0.0 KB	1.6 KB	100%	1.6 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		50.7 MB	1.4 KB	47.4 MB	98.2 MB	51%	4.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 11 04:15:00 - 04:59:59	262	1.5%	0.10	796.5 KB	1.5%	0.29
Jan 11 05:00:00 - 05:59:59	418	2.4%	0.12	2.1 MB	4.1%	0.60
Jan 11 06:00:00 - 06:59:59	327	1.9%	0.09	1.1 MB	2.2%	0.32
Jan 11 07:00:00 - 07:59:59	405	2.3%	0.11	1.1 MB	2.2%	0.31
Jan 11 08:00:00 - 08:59:59	591	3.4%	0.16	1.9 MB	3.7%	0.53
Jan 11 09:00:00 - 09:59:59	727	4.2%	0.20	2.2 MB	4.4%	0.63
Jan 11 10:00:00 - 10:59:59	829	4.8%	0.23	2.0 MB	4.0%	0.58
Jan 11 11:00:00 - 11:59:59	891	5.1%	0.25	2.4 MB	4.8%	0.69
Jan 11 12:00:00 - 12:59:59	807	4.7%	0.22	2.1 MB	4.1%	0.59
Jan 11 13:00:00 - 13:59:59	843	4.9%	0.23	1.9 MB	3.8%	0.54
Jan 11 14:00:00 - 14:59:59	979	5.6%	0.27	3.2 MB	6.4%	0.92
Jan 11 15:00:00 - 15:59:59	975	5.6%	0.27	3.0 MB	5.9%	0.86
Jan 11 16:00:00 - 16:59:59	1020	5.9%	0.28	2.9 MB	5.6%	0.81
Jan 11 17:00:00 - 17:59:59	1233	7.1%	0.34	3.4 MB	6.6%	0.96
Jan 11 18:00:00 - 18:59:59	1153	6.7%	0.32	3.6 MB	7.1%	1.03
Jan 11 19:00:00 - 19:59:59	998	5.8%	0.28	2.9 MB	5.6%	0.81
Jan 11 20:00:00 - 20:59:59	826	4.8%	0.23	2.7 MB	5.2%	0.75
Jan 11 21:00:00 - 21:59:59	761	4.4%	0.21	2.0 MB	4.0%	0.58
Jan 11 22:00:00 - 22:59:59	745	4.3%	0.21	2.0 MB	3.8%	0.56
Jan 11 23:00:00 - 23:59:59	665	3.8%	0.18	1.9 MB	3.8%	0.55
Jan 12 00:00:00 - 00:59:59	542	3.1%	0.15	1.8 MB	3.6%	0.52
Jan 12 01:00:00 - 01:59:59	436	2.5%	0.12	1.2 MB	2.4%	0.35
Jan 12 02:00:00 - 02:59:59	402	2.3%	0.11	1.1 MB	2.2%	0.31
Jan 12 03:00:00 - 03:59:59	412	2.4%	0.11	1.3 MB	2.6%	0.37
Jan 12 04:00:00 - 04:15:00	82	0.5%	0.09	203.0 KB	0.4%	0.23
TOTAL: 24:00:00	17329	100.0%	0.20	50.8 MB	100.0%	0.60

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	2133	135	1	1997
2	newsfeed.fu-berlin.de	687	30	0	657
3	news-out.mixmin.net	409	4	0	405
4	csiph-out.news.weretis.net	370	88	0	282
5	newsfeed.neva.ru	330	71	0	259
6	csiph-out.feeder.erje.net	310	103	0	207
7	csiph-out.feed.uzoreto.com	288	18	0	270
8	news.freedyn.net	174	0	0	174
9	feeder.eternal-september.org	110	59	0	51
10	news-out.aioe.org	77	67	0	10
11	csiph-out.news.tnetconsulting.net	48	19	0	29
12	newsfeeds.xmission.com	47	17	0	30
13	news.swapon.de	37	0	0	37
14	news-out.neodome.net	32	2	0	30
15	news.fcku.it	21	20	0	1
16	nntp.pasdenom.info	10	3	0	7
17	news.samoylyk.net	6	0	0	6
TOTAL: 17		5089	636	1	4452

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	95
lada.talk	57
it-alt.sport.calcio.juventus	53
scinet.general.chatter	49
alt.binaries.pictures.purebeauty	47
alt.politics	29
spline.eisfair	27
fr.misc.automobile.electrique	25
mozilla.support.thunderbird	24
scinet.stats	22
mozilla.support.seamonkey	21
scinet.sysop	21
mozilla.general	12
alt.bbs.synchronet	11
alt.windows7.general	10
at.jobs	9
mozilla.support.firefox	9
scinet.gaming	7
alt.job	7
att.jobs	6
TOTAL: 79	636

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2223
EMP (phn path)	637
EMP (phn nph)	175
EMP (phl)	121
Too many newsgroups	68
Binary: misplaced binary	40
Binary Payload	37
0	31
Scoring filter	16
HTML Multipart	15
Too many newsgroups (meow)	8
TOTAL: 11	3371

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	50
	csiph-out.feed.uzoreto.com	1
	news-out.neodome.net	1
	csiph-out.news.xs4all.nl	1
	TOTAL: 4	53
Huge articles
	csiph-out.news.xs4all.nl	143
	news.freedyn.net	87
	csiph-out.feeder.erje.net	76
	csiph-out.feed.uzoreto.com	62
	csiph-out.news.tnetconsulting.net	29
	news-out.neodome.net	19
	csiph-out.news.weretis.net	18
	newsfeeds.xmission.com	8
	news-out.mixmin.net	7
	news.samoylyk.net	5
	TOTAL: 12	457
Including strange strings
	newsfeed.fu-berlin.de	493
	news.swapon.de	37
	csiph-out.news.xs4all.nl	23
	newsfeeds.xmission.com	22
	csiph-out.news.weretis.net	14
	newsfeed.neva.ru	12
	news-out.aioe.org	5
	news-out.mixmin.net	1
	csiph-out.feeder.erje.net	1
	TOTAL: 9	608
No colon-space in header
	csiph-out.news.xs4all.nl	15
	csiph-out.feeder.erje.net	1
	TOTAL: 2	16
TOTAL: 4		1134

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	xanadu-bbs	20276	1099	16227	1	1	441	5%	23:59:55
2	fu-berlin.de	15979	516	15463	0	0	0	3%	23:50:04
3	news.samoylyk.net	17872	502	16753	14	0	0	2%	23:50:04
4	news.bbs.nz	32502	409	16297	34	0	0	1%	23:50:04
5	xmission	17560	248	12929	5	0	0	1%	23:50:04
6	tnet	18093	83	16663	1	0	0	0%	23:50:04
7	newsfeed.xs4all.nl	13608	68	12713	4	0	16	0%	23:54:29
8	neva.ru	15715	22	15326	255	0	0	0%	23:50:04
9	news.freedyn.net	15926	20	15420	62	0	0	0%	23:50:04
10	news.mixmin.net	15745	15	15281	3	0	0	0%	23:52:18
11	usenet.pasdenom.info	17170	15	16548	45	0	19	0%	23:59:55
12	erje	16248	13	15809	195	0	0	0%	23:50:04
13	neodome.net	17601	6	16993	0	0	0	0%	23:50:03
14	news.uzoreto.com	16470	5	16051	11	0	0	0%	23:53:45
15	weretis.net	16178	3	15892	0	0	0	0%	23:50:04
16	news.swapon.de	1592	3	1578	0	0	0	0%	23:47:33
17	aioe	14656	1	14051	0	0	0	0%	23:54:00
18	news.fcku.it	1759	0	1754	0	0	0	0%	22:14:38
19	eternal-september	0	0	0	0	0	14042	0%	23:50:04
TOTAL: 19		284950	3028	251748	630	1	14518	1%	451:47:16

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	7.2 MB	12.4 KB	7.2 MB	0.1 KB/s	6.7 KB	23:59:55
2	fu-berlin.de	5.7 MB	0.0 KB	5.7 MB	0.1 KB/s	11.3 KB	23:50:04
3	news.samoylyk.net	5.6 MB	82.3 KB	5.7 MB	0.1 KB/s	11.4 KB	23:50:04
4	news.bbs.nz	1.0 MB	132.8 KB	1.2 MB	0.0 KB/s	2.7 KB	23:50:04
5	xmission	530.5 KB	7.4 KB	537.9 KB	0.0 KB/s	2.1 KB	23:50:04
6	tnet	169.5 KB	1.4 KB	170.9 KB	0.0 KB/s	2.0 KB	23:50:04
7	newsfeed.xs4all.nl	147.1 KB	16.4 KB	163.5 KB	0.0 KB/s	2.3 KB	23:54:29
8	news.freedyn.net	101.9 KB	974.5 KB	1.1 MB	0.0 KB/s	13.1 KB	23:50:04
9	usenet.pasdenom.info	70.1 KB	74.7 KB	144.9 KB	0.0 KB/s	2.4 KB	23:59:55
10	news.mixmin.net	59.8 KB	8.0 KB	67.8 KB	0.0 KB/s	3.8 KB	23:52:18
11	neva.ru	38.0 KB	715.2 KB	753.2 KB	0.0 KB/s	2.7 KB	23:50:04
12	erje	23.3 KB	639.9 KB	663.1 KB	0.0 KB/s	3.2 KB	23:50:04
13	news.uzoreto.com	10.1 KB	89.1 KB	99.2 KB	0.0 KB/s	6.2 KB	23:53:45
14	neodome.net	8.3 KB	0.0 KB	8.3 KB	0.0 KB/s	1.4 KB	23:50:03
15	news.swapon.de	4.4 KB	0.0 KB	4.4 KB	0.0 KB/s	1.5 KB	23:47:33
16	weretis.net	3.5 KB	0.0 KB	3.5 KB	0.0 KB/s	1.2 KB	23:50:04
17	aioe	1.6 KB	0.0 KB	1.6 KB	0.0 KB/s	1.6 KB	23:54:00
18	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:14:38
19	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:04
TOTAL: 19		20.7 MB	2.7 MB	23.4 MB	0.0 KB/s	6.6 KB	451:47:16

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	172.83.100.205	6	104	204.2 KB	129	0	0	02:00:56
2	91-165-244-119.subs.proxad.net	5	72	79.6 KB	0	0	0	01:11:02
3	aorleans-654-1-55-137.w90-20.abo.wanadoo.fr	9	69	143.8 KB	1	0	0	00:01:21
4	208.72.53.154	9	10	15.0 KB	13	0	0	04:05:23
5	91.187.33.87	293	5	8.4 KB	5	1	1	47:25:16
6	a131033.upc-a.chello.nl	6	2	3.7 KB	2	0	0	01:23:13
TOTAL: 6		328	262	454.7 KB	150	1	1	56:08:08

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	309	119	227.6 KB	147	1	1	53:31:51
2	*.subs.proxad.net	5	72	79.6 KB	0	0	0	01:11:02
3	*.w90-20.abo.wanadoo.fr	9	69	143.8 KB	1	0	0	00:01:21
4	*.upc-a.chello.nl	6	2	3.7 KB	2	0	0	01:23:13
5	*.range86-139.btcentralplus.com	2	0	0.0 KB	0	0	0	00:00:40
TOTAL: 5		331	262	454.7 KB	150	1	1	56:08:08

NNRP auth users [Top 20]:

User	Conn
slavotinek	1
TOTAL: 1	1

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	18.661	4.378	0.016	47:25:16
208.72.53.154	0.743	0.149	0.000	04:05:23
172.83.100.205	0.662	0.122	0.000	02:00:56
a131033.upc-a.chello.nl	0.412	0.145	0.000	01:23:13
91-165-244-119.subs.proxad.net	0.911	1.000	0.000	01:11:02
aorleans-654-1-55-137.w90-20.abo.wanadoo.fr	0.802	0.314	0.000	00:01:21
host86-139-107-71.range86-139.btcentralplus.com	0.130	0.031	0.000	00:00:40
183.136.225.45	0.070	0.008	0.000	00:00:14
TOTAL: 8	22.391	6.147	0.016	56:08:08

Curious NNRP server explorers [Top 20]:

System	Conn
host86-139-107-71.range86-139.btcentralplus.com	2
183.136.225.45	1
TOTAL: 2	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
208.72.53.154	7	0
172.83.100.205	2	0
91-165-244-119.subs.proxad.net	1	0
TOTAL: 3	10	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	143	65.6%
2	alt	69	31.7%
3	news	6	2.8%
TOTAL: 3		218	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	143
2	alt.e-smith.fr	69
3	news.admin.peering	6
TOTAL: 3		218

Daily Usenet report for csiph.com

Jan 11 04:15:00 -- Jan 12 04:15:00