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

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Dec 7 16:11:44 csiph nnrpd[33033]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 7 16:11:44 csiph nnrpd[33033]: 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	19814	49.7%	5.0 MB	64.7%
innd	15602	39.1%	2.1 MB	26.7%
innfeed	3509	8.8%	585.9 KB	7.4%
nnrpd	883	2.2%	95.2 KB	1.2%
nocem	46	0.1%	5.4 KB	0.1%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	39855	100.0%	7.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	303927	54.4%
Negative hits	235187	42.1%
Do not exist	19250	3.4%
Cache misses	316	0.1%
TOTAL: 4	558680	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.274	0.0%	33	0.000	8.303	18.000
article cleanup	00:00:00.103	0.0%	19137	0.000	0.005	0.025
article logging	00:00:00.287	0.0%	19625	0.000	0.015	0.059
article parse	00:00:00.556	0.0%	57163	0.000	0.010	0.026
article write	00:00:08.369	0.0%	15855	0.133	0.528	25.700
artlog/artcncl	00:00:00.003	0.0%	33	0.000	0.091	0.667
artlog/artparse	00:00:00.002	0.0%	165	0.000	0.012	0.250
data move	00:00:00.166	0.0%	529303	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.015	0.0%	32	0.000	0.469	1.000
hishave/artcncl	00:00:00.006	0.0%	33	0.000	0.182	0.500
hishave/artparse	00:00:00.001	0.0%	151	0.000	0.007	0.250
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:07.001	0.0%	560446	0.001	0.012	0.552
history sync	00:00:00.881	0.0%	368	0.000	2.394	12.000
history write	00:00:12.343	0.0%	19148	0.043	0.645	9.475
hiswrite/artcncl	00:00:00.005	0.0%	1	5.000	5.000	5.000
hiswrite/artparse	00:00:00.048	0.0%	151	0.000	0.318	5.000
idle	23:58:37.620	99.8%	536902	88.055	160.770	508.497
nntp read	00:00:02.065	0.0%	537198	0.001	0.004	0.007
overview write	00:00:44.595	0.1%	15855	0.096	2.813	391.167
perl filter	00:00:28.132	0.0%	19133	1.179	1.470	2.196
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.265	0.0%	31756	0.000	0.008	0.034
TOTAL: 24:00:59.763	24:00:22.737	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.035	0.0%	16317	0.000	0.002	0.021
article prepare	00:00:00.000	0.0%	12712	0.000	0.000	0.000
article read	00:00:00.234	0.0%	1511	0.000	0.155	22.000
backlog stats	00:00:00.212	0.0%	335769	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.240	0.0%	259424	0.002	0.005	0.011
data write	00:00:01.571	0.0%	245908	0.002	0.006	0.015
idle	23:54:30.942	100.0%	335769	144.541	256.340	832.659
status file	00:00:00.234	0.0%	302	0.000	0.775	3.667
TOTAL: 23:54:43.466	23:54:34.468	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	39:58:49.371	99.8%	626	750.214	229919.123	600137.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.009	0.0%	1547	0.000	0.006	0.167
readart	00:00:00.457	0.0%	76	0.000	6.013	42.000
TOTAL: 40:03:26.360	39:58:49.837	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	3	15220	3167	12024	29	20%	24:37:56
2	news-out.mixmin.net	2	16923	2616	14108	199	15%	24:08:58
3	feeder.eternal-september.org	14	6610	2488	3983	139	37%	27:29:31
4	csiph-out.news.xs4all.nl	480	18502	2163	15221	1118	11%	68:24:32
5	news-out.aioe.org	2	15066	1285	13672	109	8%	23:59:24
6	csiph-out.feeder.erje.net	1440	79439	958	78238	243	1%	118:43:16
7	csiph-out.news.weretis.net	3	31690	757	30743	190	2%	47:59:47
8	newsfeed.neva.ru	144	14017	661	13124	232	4%	71:54:26
9	newsfeed.fu-berlin.de	16	12285	601	11532	152	4%	20:22:03
10	csiph-out.feed.uzoreto.com	863	24580	356	23281	943	1%	71:21:34
11	news-out.neodome.net	2	17189	281	16860	48	1%	23:59:55
12	news.fcku.it	8	1830	240	1585	5	13%	21:58:56
13	news.freedyn.net	1	18602	48	18481	73	0%	23:59:55
14	news.swapon.de	6	1349	39	1310	0	2%	23:48:42
15	csiph-out.news.tnetconsulting.net	1	14825	25	14783	17	0%	23:59:55
16	localhost	33	7	7	0	0	100%	00:00:00
17	news.bbs.nz	1	14304	3	14301	0	0%	23:59:53
18	xanadu-bbs.net	17	562	1	561	0	0%	22:16:16
19	news.samoylyk.net	2	15136	1	15134	1	0%	23:50:34
TOTAL: 19		3038	318136	15697	298941	3498	4%	686:55:33

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	10.5 MB	0.0 KB	1.6 MB	12.1 MB	86%	3.9 KB
2	csiph-out.feeder.erje.net	7.2 MB	1.9 KB	2.0 MB	9.2 MB	78%	7.8 KB
3	feeder.eternal-september.org	6.8 MB	0.0 KB	737.6 KB	7.6 MB	90%	2.9 KB
4	news-out.mixmin.net	5.7 MB	0.0 KB	457.8 KB	6.1 MB	92%	2.2 KB
5	csiph-out.news.xs4all.nl	5.6 MB	0.0 KB	5.4 MB	11.0 MB	50%	3.4 KB
6	news-out.aioe.org	4.8 MB	0.0 KB	472.5 KB	5.2 MB	91%	3.8 KB
7	csiph-out.news.weretis.net	2.0 MB	0.6 KB	2.5 MB	4.6 MB	44%	4.9 KB
8	newsfeed.neva.ru	1.8 MB	0.0 KB	647.0 KB	2.5 MB	74%	2.8 KB
9	csiph-out.feed.uzoreto.com	1.7 MB	0.0 KB	8.8 MB	10.6 MB	16%	8.3 KB
10	newsfeed.fu-berlin.de	1.4 MB	0.0 KB	326.0 KB	1.7 MB	81%	2.3 KB
11	news-out.neodome.net	1.0 MB	0.0 KB	173.3 KB	1.2 MB	85%	3.7 KB
12	news.fcku.it	381.3 KB	0.0 KB	11.5 KB	392.8 KB	97%	1.6 KB
13	news.freedyn.net	220.5 KB	0.0 KB	1.3 MB	1.5 MB	14%	12.4 KB
14	csiph-out.news.tnetconsulting.net	159.8 KB	0.0 KB	49.1 KB	208.9 KB	76%	5.0 KB
15	news.swapon.de	99.4 KB	0.0 KB	0.0 KB	99.4 KB	100%	2.5 KB
16	xanadu-bbs.net	19.8 KB	0.0 KB	0.0 KB	19.8 KB	100%	19.8 KB
17	localhost	13.0 KB	0.0 KB	0.0 KB	13.0 KB	100%	1.9 KB
18	news.bbs.nz	5.0 KB	0.0 KB	0.0 KB	5.0 KB	100%	1.7 KB
19	news.samoylyk.net	1.8 KB	0.6 KB	0.6 KB	3.0 KB	58%	1.5 KB
TOTAL: 19		49.4 MB	3.1 KB	24.4 MB	73.9 MB	66%	3.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 7 04:15:00 - 04:59:59	159	1.0%	0.06	489.5 KB	1.0%	0.18
Dec 7 05:00:00 - 05:59:59	366	2.3%	0.10	2.5 MB	5.0%	0.70
Dec 7 06:00:00 - 06:59:59	446	2.8%	0.12	1.4 MB	2.8%	0.39
Dec 7 07:00:00 - 07:59:59	482	3.0%	0.13	1.8 MB	3.6%	0.51
Dec 7 08:00:00 - 08:59:59	694	4.4%	0.19	2.2 MB	4.5%	0.64
Dec 7 09:00:00 - 09:59:59	673	4.2%	0.19	2.2 MB	4.4%	0.62
Dec 7 10:00:00 - 10:59:59	729	4.6%	0.20	2.0 MB	4.0%	0.56
Dec 7 11:00:00 - 11:59:59	834	5.3%	0.23	2.4 MB	4.8%	0.67
Dec 7 12:00:00 - 12:59:59	743	4.7%	0.21	2.0 MB	4.1%	0.58
Dec 7 13:00:00 - 13:59:59	787	5.0%	0.22	2.3 MB	4.6%	0.64
Dec 7 14:00:00 - 14:59:59	817	5.2%	0.23	2.2 MB	4.5%	0.64
Dec 7 15:00:00 - 15:59:59	1030	6.5%	0.29	3.6 MB	7.3%	1.02
Dec 7 16:00:00 - 16:59:59	972	6.1%	0.27	3.3 MB	6.6%	0.93
Dec 7 17:00:00 - 17:59:59	953	6.0%	0.26	2.8 MB	5.7%	0.81
Dec 7 18:00:00 - 18:59:59	908	5.7%	0.25	2.7 MB	5.4%	0.76
Dec 7 19:00:00 - 19:59:59	804	5.1%	0.22	2.2 MB	4.5%	0.64
Dec 7 20:00:00 - 20:59:59	824	5.2%	0.23	2.4 MB	4.9%	0.69
Dec 7 21:00:00 - 21:59:59	684	4.3%	0.19	1.9 MB	3.9%	0.55
Dec 7 22:00:00 - 22:59:59	650	4.1%	0.18	2.1 MB	4.3%	0.61
Dec 7 23:00:00 - 23:59:59	657	4.1%	0.18	2.1 MB	4.2%	0.59
Dec 8 00:00:00 - 00:59:59	477	3.0%	0.13	1.3 MB	2.7%	0.38
Dec 8 01:00:00 - 01:59:59	404	2.5%	0.11	1.2 MB	2.5%	0.35
Dec 8 02:00:00 - 02:59:59	333	2.1%	0.09	1.2 MB	2.3%	0.33
Dec 8 03:00:00 - 03:59:59	348	2.2%	0.10	987.8 KB	2.0%	0.27
Dec 8 04:00:00 - 04:15:00	73	0.5%	0.08	235.6 KB	0.5%	0.26
TOTAL: 24:00:00	15847	100.0%	0.18	49.5 MB	100.0%	0.59

Sites sending bad articles:

Server		Total	Group	Duplic	TooOld	Other
1	csiph-out.news.xs4all.nl	1135	82	0	0	1053
2	csiph-out.feed.uzoreto.com	945	46	0	0	899
3	newsfeed.fu-berlin.de	532	14	0	0	518
4	news-out.mixmin.net	254	10	0	0	244
5	newsfeed.neva.ru	233	75	0	0	158
6	csiph-out.news.weretis.net	197	68	1	0	128
7	csiph-out.feeder.erje.net	165	55	1	0	109
8	feeder.eternal-september.org	134	69	0	1	64
9	news-out.aioe.org	124	83	0	0	41
10	news.freedyn.net	74	0	0	0	74
11	news-out.neodome.net	55	8	0	0	47
12	newsfeeds.xmission.com	35	6	0	0	29
13	csiph-out.news.tnetconsulting.net	26	25	0	0	1
14	news.swapon.de	19	0	0	0	19
15	news.fcku.it	5	5	0	0	0
16	news.samoylyk.net	1	0	1	0	0
TOTAL: 16		3934	546	3	1	3384

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	146
alt.philosophy	68
lada.talk	50
it-alt.sport.calcio.juventus	27
alt.binaries.pictures.purebeauty	26
alt.politics	22
scinet.stats	19
lada.auto	16
spline.eisfair.dev	16
mozilla.support.thunderbird	13
alt.politics.usa	13
mozilla.dev.apps.seamonkey	10
trollnet.cinema	9
att.jobs	8
at.jobs	7
alt.bbs.synchronet	6
alt.survival	6
alt.testing	5
free.it.religioni.scientology	5
lada.movies	5
TOTAL: 65	546

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2017
EMP (phn path)	271
EMP (phl)	121
0	104
EMP (phn nph)	86
Too many newsgroups	73
Binary Payload	23
Scoring filter	15
HTML Multipart	14
Binary: misplaced binary	6
Too many newsgroups (meow)	3
HTML post	1
Too many newsgroups (low_xpost)	1
TOTAL: 13	2735

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	39
	csiph-out.feeder.erje.net	34
	csiph-out.news.xs4all.nl	22
	newsfeeds.xmission.com	22
	csiph-out.news.weretis.net	18
	news.freedyn.net	9
	feeder.eternal-september.org	3
	news-out.mixmin.net	2
	news-out.neodome.net	1
	TOTAL: 9	150
Including strange strings
	newsfeed.fu-berlin.de	343
	news-out.mixmin.net	55
	news.swapon.de	19
	news-out.aioe.org	18
	csiph-out.news.weretis.net	18
	csiph-out.news.xs4all.nl	17
	newsfeeds.xmission.com	7
	csiph-out.feeder.erje.net	3
	feeder.eternal-september.org	3
	csiph-out.feed.uzoreto.com	2
	TOTAL: 10	485
No colon-space in header
	csiph-out.news.xs4all.nl	12
	csiph-out.feed.uzoreto.com	1
	csiph-out.feeder.erje.net	1
	TOTAL: 3	14
TOTAL: 4		694

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	19691	956	14887	2	64	4%	23:59:53
2	news.bbs.nz	29856	550	14708	28	0	1%	23:57:59
3	fu-berlin.de	14929	548	14381	0	0	3%	23:50:09
4	news.samoylyk.net	16440	542	15273	4	405	3%	23:52:55
5	tnet	16885	116	15176	0	0	0%	23:57:46
6	newsfeed.xs4all.nl	14175	58	13501	0	22	0%	23:54:27
7	xmission	15408	53	11998	0	0	0%	23:50:32
8	neva.ru	14222	36	13986	106	0	0%	23:50:09
9	aioe	11246	17	10847	0	0	0%	23:50:57
10	news.freedyn.net	15341	15	15040	34	0	0%	23:55:11
11	news.uzoreto.com	15120	13	14785	8	0	0%	23:50:09
12	neodome.net	15911	12	15496	0	0	0%	23:50:09
13	news.swapon.de	1371	11	1349	0	0	0%	23:23:10
14	erje	14953	9	14745	45	0	0%	23:58:28
15	weretis.net	14984	8	14388	0	0	0%	23:50:09
16	news.mixmin.net	12589	8	12305	0	0	0%	23:50:09
17	news.fcku.it	1472	0	1468	0	0	0%	20:43:07
18	eternal-september	0	0	0	0	12709	0%	23:50:09
19	usenet.pasdenom.info	0	0	0	0	15297	0%	23:58:43
TOTAL: 19		244593	2952	214333	227	28497	1%	450:14:11

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	7.2 MB	9.6 KB	7.2 MB	0.1 KB/s	7.7 KB	23:59:53
2	news.samoylyk.net	6.0 MB	4.3 KB	6.0 MB	0.1 KB/s	11.3 KB	23:52:55
3	fu-berlin.de	6.0 MB	0.0 KB	6.0 MB	0.1 KB/s	11.2 KB	23:50:09
4	news.bbs.nz	1.5 MB	125.0 KB	1.6 MB	0.0 KB/s	2.8 KB	23:57:59
5	tnet	326.8 KB	0.0 KB	326.8 KB	0.0 KB/s	2.8 KB	23:57:46
6	newsfeed.xs4all.nl	189.9 KB	0.0 KB	189.9 KB	0.0 KB/s	3.3 KB	23:54:27
7	xmission	144.5 KB	0.0 KB	144.5 KB	0.0 KB/s	2.7 KB	23:50:32
8	neva.ru	78.4 KB	283.5 KB	362.0 KB	0.0 KB/s	2.5 KB	23:50:09
9	news.freedyn.net	56.9 KB	491.1 KB	547.9 KB	0.0 KB/s	11.2 KB	23:55:11
10	aioe	52.6 KB	0.0 KB	52.6 KB	0.0 KB/s	3.1 KB	23:50:57
11	news.uzoreto.com	33.2 KB	59.3 KB	92.4 KB	0.0 KB/s	4.4 KB	23:50:09
12	erje	21.1 KB	142.1 KB	163.2 KB	0.0 KB/s	3.0 KB	23:58:28
13	neodome.net	20.0 KB	0.0 KB	20.0 KB	0.0 KB/s	1.7 KB	23:50:09
14	news.swapon.de	18.5 KB	0.0 KB	18.5 KB	0.0 KB/s	1.7 KB	23:23:10
15	news.mixmin.net	14.7 KB	0.0 KB	14.7 KB	0.0 KB/s	1.8 KB	23:50:09
16	weretis.net	14.5 KB	0.0 KB	14.5 KB	0.0 KB/s	1.8 KB	23:50:09
17	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:43:07
18	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:09
19	usenet.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:43
TOTAL: 19		21.6 MB	1.1 MB	22.7 MB	0.0 KB/s	7.3 KB	450:14:11

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	31	54	131.9 KB	7	0	0	06:15:14
2	071-013-248-064.res.spectrum.com	26	22	42.6 KB	23	7	6	08:47:20
TOTAL: 2		57	76	174.6 KB	30	7	6	40:03:25

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.upc-a.chello.nl	31	54	131.9 KB	7	0	0	06:15:14
2	*.res.spectrum.com	26	22	42.6 KB	23	7	6	08:47:20
3	*.ph.ph.cox.net	2	0	0.0 KB	0	0	0	01:00:10
4	*.range86-130.btcentralplus.com	1	0	0.0 KB	0	0	0	00:00:13
5	unresolved	73	0	0.0 KB	0	0	0	24:00:25
TOTAL: 5		133	76	174.6 KB	30	7	6	40:03:25

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	5.181	1.085	0.000	24:00:15
071-013-248-064.res.spectrum.com	2.241	0.521	0.015	08:47:20
a131033.upc-a.chello.nl	2.428	0.505	0.000	06:15:14
ip72-222-200-117.ph.ph.cox.net	0.221	0.023	0.000	01:00:10
host86-130-133-6.range86-130.btcentralplus.com	0.064	0.016	0.000	00:00:13
192.241.236.61	0.069	0.008	0.000	00:00:09
TOTAL: 6	10.204	2.158	0.015	40:03:25

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	72
ip72-222-200-117.ph.ph.cox.net	2
192.241.236.61	1
host86-130-133-6.range86-130.btcentralplus.com	1
TOTAL: 4	76

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	2	0
TOTAL: 2	12	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Dec 7 04:15:00 -- Dec 8 04:15:00