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

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Jan 2 15:13:34 csiph nnrpd[2422]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 2 15:13:34 csiph nnrpd[2422]: 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 2 18:54:06 csiph nnrpd[13697]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 2 18:54:06 csiph nnrpd[13697]: 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	19624	49.6%	4.8 MB	64.1%
innd	14348	36.2%	1.9 MB	24.9%
innfeed	3565	9.0%	627.6 KB	8.1%
nnrpd	2046	5.2%	220.5 KB	2.9%
nocem	20	0.1%	2.5 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	39604	100.0%	7.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	280725	50.3%
Negative hits	258225	46.2%
Do not exist	18752	3.4%
Cache misses	662	0.1%
TOTAL: 4	558364	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:10.967	0.0%	312	0.000	35.151	43.000
article cleanup	00:00:00.122	0.0%	18550	0.000	0.007	0.054
article logging	00:00:00.531	0.0%	19000	0.000	0.028	3.479
article parse	00:00:01.342	0.0%	62563	0.000	0.021	0.567
article write	00:00:59.135	0.1%	15068	0.311	3.925	102.455
artlog/artcncl	00:00:00.060	0.0%	312	0.000	0.192	34.000
artlog/artparse	00:00:00.091	0.0%	297	0.000	0.306	14.333
data move	00:00:00.230	0.0%	526555	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.018	0.0%	312	0.000	0.058	7.000
hishave/artcncl	00:00:00.005	0.0%	312	0.000	0.016	0.017
hishave/artparse	00:00:00.001	0.0%	294	0.000	0.003	0.125
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:31.232	0.0%	559964	0.002	0.056	0.346
history sync	00:00:01.005	0.0%	375	0.000	2.680	15.333
history write	00:00:47.900	0.1%	18523	0.531	2.586	35.525
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.687	0.0%	294	0.000	2.337	27.000
idle	23:55:03.778	99.6%	519979	88.566	165.591	729.539
nntp read	00:00:02.099	0.0%	528895	0.001	0.004	0.011
overview write	00:02:19.567	0.2%	15068	0.077	9.262	997.543
perl filter	00:00:27.972	0.0%	18520	0.964	1.510	4.528
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.249	0.0%	30156	0.000	0.008	0.057
TOTAL: 24:01:03.557	24:00:26.991	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.038	0.0%	18698	0.000	0.002	0.029
article prepare	00:00:00.002	0.0%	17764	0.000	0.000	0.010
article read	00:00:01.485	0.0%	2076	0.000	0.715	21.000
backlog stats	00:00:00.210	0.0%	332606	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.231	0.0%	261879	0.001	0.005	0.011
data write	00:00:01.500	0.0%	244544	0.001	0.006	0.017
idle	23:55:06.353	100.0%	332606	129.472	258.884	892.398
status file	00:00:00.395	0.0%	316	0.000	1.250	15.333
TOTAL: 23:55:17.692	23:55:11.214	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	52:05:44.357	99.5%	1057	82.000	177430.801	412302.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.017	0.0%	2468	0.000	0.007	0.167
readart	00:00:00.200	0.0%	11	0.000	18.182	59.000
TOTAL: 52:21:45.150	52:05:44.574	99.5%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.xs4all.nl	0	7597	4036	1739	1822	53%	73:54:52
2	news-out.mixmin.net	2	16402	2134	13837	431	13%	24:08:57
3	feeder.eternal-september.org	14	4368	2055	2238	75	47%	27:39:04
4	csiph-out.news.weretis.net	4	38516	1464	36820	232	3%	60:46:30
5	newsfeeds.xmission.com	1	14615	1402	13163	50	9%	25:57:10
6	newsfeed.fu-berlin.de	23	18259	985	17081	193	5%	34:17:29
7	csiph-out.feeder.erje.net	1440	75433	938	74048	447	1%	118:40:29
8	newsfeed.neva.ru	148	10242	630	9320	292	6%	72:16:47
9	news-out.aioe.org	3	13995	630	13346	19	4%	23:59:53
10	csiph-out.feed.uzoreto.com	864	24067	368	23379	320	1%	71:15:44
11	news-out.neodome.net	1	16431	275	16140	16	1%	23:59:54
12	news.freedyn.net	2	17924	122	17680	122	0%	23:59:57
13	news.fcku.it	19	1148	106	1042	0	9%	21:00:26
14	nntp.pasdenom.info	3	9518	101	9405	12	1%	21:29:55
15	news.swapon.de	7	1349	47	1301	1	3%	23:20:38
16	csiph-out.news.tnetconsulting.net	1	13737	39	13697	1	0%	23:59:57
17	news.samoylyk.net	2	14300	6	14294	0	0%	23:59:54
18	news.bbs.nz	1	13423	4	13419	0	0%	23:59:50
19	localhost	26	0	0	0	0	0%	00:00:01
20	xanadu-bbs.net	28	405	0	405	0	0%	21:26:56
TOTAL: 20		2589	311729	15342	292354	4033	4%	740:14:23

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.xs4all.nl	11.7 MB	2.9 KB	10.6 MB	22.4 MB	52%	3.9 KB
2	csiph-out.feeder.erje.net	8.6 MB	115.6 KB	4.4 MB	13.1 MB	65%	9.7 KB
3	news-out.mixmin.net	6.7 MB	9.0 KB	2.3 MB	9.0 MB	74%	3.6 KB
4	feeder.eternal-september.org	5.7 MB	0.0 KB	934.4 KB	6.6 MB	86%	3.2 KB
5	csiph-out.news.weretis.net	5.1 MB	0.0 KB	2.6 MB	7.8 MB	66%	4.7 KB
6	newsfeeds.xmission.com	4.6 MB	0.0 KB	2.4 MB	7.0 MB	66%	4.9 KB
7	newsfeed.fu-berlin.de	2.4 MB	0.0 KB	559.5 KB	2.9 MB	81%	2.5 KB
8	news-out.aioe.org	2.2 MB	0.0 KB	43.2 KB	2.3 MB	98%	3.6 KB
9	newsfeed.neva.ru	2.2 MB	1.2 KB	797.6 KB	3.0 MB	73%	3.3 KB
10	csiph-out.feed.uzoreto.com	1.2 MB	0.0 KB	4.9 MB	6.1 MB	20%	9.0 KB
11	news-out.neodome.net	909.9 KB	0.0 KB	46.4 KB	956.3 KB	95%	3.3 KB
12	news.freedyn.net	615.1 KB	0.0 KB	3.0 MB	3.6 MB	16%	15.1 KB
13	nntp.pasdenom.info	262.6 KB	0.0 KB	14.7 KB	277.4 KB	94%	2.5 KB
14	news.fcku.it	183.9 KB	0.0 KB	0.0 KB	183.9 KB	100%	1.7 KB
15	csiph-out.news.tnetconsulting.net	150.8 KB	0.0 KB	92.3 KB	243.1 KB	62%	6.1 KB
16	news.swapon.de	112.8 KB	0.0 KB	0.7 KB	113.6 KB	99%	2.4 KB
17	news.samoylyk.net	18.8 KB	0.0 KB	0.0 KB	18.8 KB	100%	3.1 KB
18	news.bbs.nz	9.9 KB	0.0 KB	0.0 KB	9.9 KB	100%	2.5 KB
19	localhost	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		52.7 MB	128.6 KB	32.6 MB	85.4 MB	61%	4.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 2 04:15:00 - 04:59:59	202	1.3%	0.07	714.3 KB	1.3%	0.26
Jan 2 05:00:00 - 05:59:59	582	3.9%	0.16	2.5 MB	4.8%	0.71
Jan 2 06:00:00 - 06:59:59	238	1.6%	0.07	766.8 KB	1.4%	0.21
Jan 2 07:00:00 - 07:59:59	269	1.8%	0.07	801.8 KB	1.5%	0.22
Jan 2 08:00:00 - 08:59:59	545	3.6%	0.15	2.0 MB	3.9%	0.57
Jan 2 09:00:00 - 09:59:59	617	4.1%	0.17	1.6 MB	3.1%	0.47
Jan 2 10:00:00 - 10:59:59	746	4.9%	0.21	2.7 MB	5.3%	0.78
Jan 2 11:00:00 - 11:59:59	784	5.2%	0.22	3.2 MB	6.2%	0.92
Jan 2 12:00:00 - 12:59:59	788	5.2%	0.22	2.6 MB	5.1%	0.75
Jan 2 13:00:00 - 13:59:59	822	5.5%	0.23	2.9 MB	5.6%	0.83
Jan 2 14:00:00 - 14:59:59	819	5.4%	0.23	2.2 MB	4.2%	0.63
Jan 2 15:00:00 - 15:59:59	793	5.3%	0.22	2.7 MB	5.2%	0.77
Jan 2 16:00:00 - 16:59:59	1027	6.8%	0.29	5.0 MB	9.6%	1.42
Jan 2 17:00:00 - 17:59:59	919	6.1%	0.26	3.4 MB	6.5%	0.96
Jan 2 18:00:00 - 18:59:59	860	5.7%	0.24	2.6 MB	5.1%	0.75
Jan 2 19:00:00 - 19:59:59	760	5.0%	0.21	2.0 MB	3.9%	0.58
Jan 2 20:00:00 - 20:59:59	699	4.6%	0.19	2.4 MB	4.6%	0.68
Jan 2 21:00:00 - 21:59:59	635	4.2%	0.18	2.1 MB	4.0%	0.59
Jan 2 22:00:00 - 22:59:59	579	3.8%	0.16	1.8 MB	3.4%	0.51
Jan 2 23:00:00 - 23:59:59	565	3.7%	0.16	1.9 MB	3.7%	0.55
Jan 3 00:00:00 - 00:59:59	458	3.0%	0.13	1.6 MB	3.0%	0.45
Jan 3 01:00:00 - 01:59:59	461	3.1%	0.13	1.4 MB	2.7%	0.41
Jan 3 02:00:00 - 02:59:59	502	3.3%	0.14	1.5 MB	2.9%	0.43
Jan 3 03:00:00 - 03:59:59	329	2.2%	0.09	1.3 MB	2.4%	0.36
Jan 3 04:00:00 - 04:15:00	83	0.6%	0.09	259.3 KB	0.5%	0.29
TOTAL: 24:00:00	15082	100.0%	0.17	52.1 MB	100.0%	0.62

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1916	90	1	1825
2	newsfeed.fu-berlin.de	538	15	0	523
3	news-out.mixmin.net	435	8	3	424
4	csiph-out.feed.uzoreto.com	323	22	0	301
5	newsfeed.neva.ru	294	46	1	247
6	csiph-out.feeder.erje.net	213	41	25	147
7	csiph-out.news.weretis.net	180	55	0	125
8	news.freedyn.net	122	0	0	122
9	feeder.eternal-september.org	76	20	0	56
10	newsfeeds.xmission.com	68	14	0	54
11	news-out.aioe.org	20	15	0	5
12	news.swapon.de	18	0	0	18
13	news-out.neodome.net	15	2	0	13
14	nntp.pasdenom.info	12	0	0	12
TOTAL: 14		4230	328	30	3872

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	56
alt.politics	48
lada.talk	33
scinet.stats	30
alt.binaries.pictures.purebeauty	20
alt.survival	18
alt.tv.comedy-central.daily-show	10
it-alt.comp.software.mozilla	9
spline.eisfair	8
alt.math.undergrad	5
oesterreich.tratsch	5
lada.auto	4
alt.politics.usa	4
mozilla.general	4
mozilla.test	4
mozilla.support.seamonkey	4
rec.travel.resorts.all	3
alt.pl.comp.os.linux.ubuntu	3
lada.commerce	3
alt.philosophy	3
TOTAL: 60	328

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2302
EMP (phn path)	386
EMP (phn nph)	236
Too many newsgroups	73
EMP (phl)	52
0	48
HTML Multipart	11
Binary: misplaced binary	10
Binary Payload	4
Scoring filter	2
HTML post	1
U2 violation - invalid distribution	1
TOTAL: 12	3126

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	50
	csiph-out.feed.uzoreto.com	2
	csiph-out.news.xs4all.nl	2
	TOTAL: 3	54
Huge articles
	csiph-out.feeder.erje.net	80
	csiph-out.news.xs4all.nl	55
	csiph-out.feed.uzoreto.com	38
	news.freedyn.net	37
	newsfeeds.xmission.com	36
	csiph-out.news.weretis.net	27
	feeder.eternal-september.org	9
	news-out.mixmin.net	8
	newsfeed.fu-berlin.de	4
	TOTAL: 9	294
Including strange strings
	newsfeed.fu-berlin.de	360
	csiph-out.news.xs4all.nl	31
	news.swapon.de	17
	newsfeeds.xmission.com	17
	csiph-out.news.weretis.net	8
	news-out.mixmin.net	5
	newsfeed.neva.ru	3
	csiph-out.feed.uzoreto.com	3
	csiph-out.feeder.erje.net	2
	news.freedyn.net	1
	TOTAL: 12	449
No colon-space in header
	csiph-out.feeder.erje.net	2
	csiph-out.news.xs4all.nl	1
	TOTAL: 2	3
TOTAL: 4		800

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	17452	1256	13819	7	0	7%	23:59:51
2	news.bbs.nz	27265	819	13473	33	0	3%	23:50:07
3	news.samoylyk.net	15450	655	14363	7	0	4%	23:50:07
4	fu-berlin.de	14012	653	13358	0	0	4%	23:50:07
5	tnet	16271	194	14134	4	0	1%	23:50:07
6	usenet.pasdenom.info	25083	161	13986	125	3619	0%	23:59:55
7	xmission	14601	109	12812	5	0	0%	23:59:42
8	newsfeed.xs4all.nl	11376	51	10736	0	13	0%	23:54:33
9	neva.ru	13583	12	13300	213	0	0%	23:59:55
10	erje	14028	12	13853	20	0	0%	23:54:47
11	news.freedyn.net	14024	5	13575	63	0	0%	23:57:24
12	aioe	12040	4	11705	0	0	0%	23:55:38
13	neodome.net	15003	3	14635	0	0	0%	23:50:07
14	weretis.net	12892	2	12730	1	0	0%	23:50:07
15	news.mixmin.net	12121	2	11798	1	0	0%	23:50:07
16	news.uzoreto.com	13832	2	13573	10	0	0%	23:50:07
17	eternal-september	0	0	0	0	12251	0%	23:59:55
18	news.fcku.it	995	0	995	0	0	0%	20:14:55
19	news.swapon.de	1311	0	1305	0	0	0%	23:14:13
TOTAL: 19		251339	3940	214150	489	15883	1%	449:51:44

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	9.3 MB	47.3 KB	9.3 MB	0.1 KB/s	7.6 KB	23:59:51
2	fu-berlin.de	7.7 MB	0.0 KB	7.7 MB	0.1 KB/s	12.0 KB	23:50:07
3	news.samoylyk.net	7.6 MB	95.2 KB	7.7 MB	0.1 KB/s	11.9 KB	23:50:07
4	news.bbs.nz	2.4 MB	227.8 KB	2.6 MB	0.0 KB/s	3.1 KB	23:50:07
5	usenet.pasdenom.info	562.0 KB	262.5 KB	824.5 KB	0.0 KB/s	2.9 KB	23:59:55
6	tnet	526.8 KB	5.4 KB	532.2 KB	0.0 KB/s	2.7 KB	23:50:07
7	xmission	247.4 KB	9.8 KB	257.2 KB	0.0 KB/s	2.3 KB	23:59:42
8	newsfeed.xs4all.nl	118.5 KB	0.0 KB	118.5 KB	0.0 KB/s	2.3 KB	23:54:33
9	neva.ru	18.7 KB	493.9 KB	512.7 KB	0.0 KB/s	2.3 KB	23:59:55
10	erje	16.6 KB	38.9 KB	55.6 KB	0.0 KB/s	1.7 KB	23:54:47
11	aioe	11.9 KB	0.0 KB	11.9 KB	0.0 KB/s	3.0 KB	23:55:38
12	news.freedyn.net	11.6 KB	821.7 KB	833.3 KB	0.0 KB/s	12.3 KB	23:57:24
13	neodome.net	6.3 KB	0.0 KB	6.3 KB	0.0 KB/s	2.1 KB	23:50:07
14	weretis.net	3.6 KB	1.1 KB	4.8 KB	0.0 KB/s	1.6 KB	23:50:07
15	news.mixmin.net	2.5 KB	1.4 KB	3.9 KB	0.0 KB/s	1.3 KB	23:50:07
16	news.uzoreto.com	2.4 KB	100.3 KB	102.7 KB	0.0 KB/s	8.6 KB	23:50:07
17	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:55
18	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:14:55
19	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:14:13
TOTAL: 19		28.4 MB	2.1 MB	30.5 MB	0.0 KB/s	7.0 KB	449:51:44

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	aorleans-654-1-138-187.w90-20.abo.wanadoo.fr	1	5	33.1 KB	1	00:00:05
2	91.187.33.87	285	3	5.0 KB	3	42:10:42
3	a131033.upc-a.chello.nl	55	3	5.0 KB	2	10:09:20
4	212.90.61.180	1	0	0.0 KB	11	00:00:00
TOTAL: 4		342	11	43.0 KB	17	52:21:42

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.w90-20.abo.wanadoo.fr	1	5	33.1 KB	1	00:00:05
2	unresolved	286	3	5.0 KB	14	42:10:42
3	*.upc-a.chello.nl	55	3	5.0 KB	2	10:09:20
4	*.security.ipip.net	1	0	0.0 KB	0	00:00:00
5	*.range31-50.btcentralplus.com	2	0	0.0 KB	0	00:01:33
TOTAL: 5		345	11	43.0 KB	17	52:21:42

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
91.187.33.87	17.890	4.390	42:10:42
a131033.upc-a.chello.nl	3.489	0.849	10:09:20
host31-50-220-99.range31-50.btcentralplus.com	0.124	0.036	00:01:33
aorleans-654-1-138-187.w90-20.abo.wanadoo.fr	0.089	0.032	00:00:05
scan-141.security.ipip.net	0.059	0.017	00:00:00
TOTAL: 5	21.651	5.324	52:21:42

Curious NNRP server explorers [Top 20]:

System	Conn
host31-50-220-99.range31-50.btcentralplus.com	2
scan-141.security.ipip.net	1
TOTAL: 2	3

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

Newsgroup request counts (by category):

Category		Count	Pct
1	soc	29135	94.1%
2	alt	1749	5.7%
3	comp	55	0.2%
4	fr	5	0.0%
5	no	2	0.0%
TOTAL: 5		30946	100.0%

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

Newsgroup		Count
1	soc.culture.vietnamese	29135
2	alt.tv.star-trek.voyager	611
3	alt.fan.tolkien	478
4	alt.uk.edinburgh.misc	395
5	alt.comp.test	160
6	alt.fan.countries.libya	53
7	comp.sys.palmtops.pilot	49
8	alt.fan.sailor-moon	27
9	alt.games.abandonware	24
10	comp.sys.ibm.ps2.hardware	6
11	fr.usenet.forums.annonces	5
12	no.fritid.slektsforsking.it	2
13	alt.drugs.banana-peels	1
TOTAL: 13		30946

Daily Usenet report for csiph.com

Jan 2 04:15:00 -- Jan 3 04:15:00