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

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Dec 15 11:44:39 csiph nnrpd[37698]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 15 11:44:39 csiph nnrpd[37698]: 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]
Dec 15 11:48:54 csiph nnrpd[37843]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 15 11:48:54 csiph nnrpd[37843]: 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	18677	47.6%	4.7 MB	62.5%
innd	16120	41.1%	2.1 MB	28.3%
innfeed	3491	8.9%	601.6 KB	7.8%
nnrpd	940	2.4%	103.4 KB	1.3%
nocem	34	0.1%	3.9 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	39263	100.0%	7.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	294213	54.4%
Negative hits	228114	42.2%
Do not exist	18079	3.3%
Cache misses	200	0.0%
TOTAL: 4	540606	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.396	0.0%	36	0.000	11.000	27.250
article cleanup	00:00:00.116	0.0%	17727	0.000	0.007	0.053
article logging	00:00:00.283	0.0%	18221	0.000	0.016	0.238
article parse	00:00:00.693	0.0%	55741	0.000	0.012	0.048
article write	00:00:09.568	0.0%	14551	0.136	0.658	32.059
artlog/artcncl	00:00:00.000	0.0%	36	0.000	0.000	0.000
artlog/artparse	00:00:00.004	0.0%	402	0.000	0.010	0.100
data move	00:00:00.171	0.0%	509868	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.010	0.0%	36	0.000	0.278	1.000
hishave/artcncl	00:00:00.002	0.0%	36	0.000	0.056	1.000
hishave/artparse	00:00:00.001	0.0%	393	0.000	0.003	0.026
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:03.434	0.0%	541556	0.001	0.006	0.028
history sync	00:00:00.828	0.0%	381	0.000	2.173	18.000
history write	00:00:09.103	0.0%	17730	0.016	0.513	12.211
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.182	0.0%	393	0.000	0.463	2.500
idle	23:58:56.757	99.9%	516846	88.467	167.045	524.646
nntp read	00:00:02.052	0.0%	517656	0.001	0.004	0.007
overview write	00:00:31.842	0.0%	14551	0.091	2.188	61.882
perl filter	00:00:25.233	0.0%	17721	0.899	1.424	2.162
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.229	0.0%	29136	0.000	0.008	0.043
TOTAL: 24:01:00.581	24:00:20.904	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.036	0.0%	14672	0.000	0.002	0.032
article prepare	00:00:00.000	0.0%	9884	0.000	0.000	0.000
article read	00:00:00.215	0.0%	1245	0.000	0.173	13.000
backlog stats	00:00:00.202	0.0%	325601	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.127	0.0%	256011	0.001	0.004	0.009
data write	00:00:01.457	0.0%	241915	0.001	0.006	0.013
idle	23:54:55.747	100.0%	325601	134.508	264.421	680.277
status file	00:00:00.202	0.0%	279	0.000	0.724	2.000
TOTAL: 23:55:05.429	23:54:58.986	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	45:51:54.045	99.8%	545	62.000	302961.550	600165.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.007	0.0%	1270	0.000	0.006	0.167
readart	00:00:00.167	0.0%	4	41.000	41.750	42.000
TOTAL: 45:56:45.675	45:51:54.219	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news-out.aioe.org	3	14046	3068	10851	127	21%	24:08:44
2	newsfeeds.xmission.com	3	13694	2474	11157	63	18%	23:02:39
3	feeder.eternal-september.org	14	5870	2261	3490	119	38%	27:29:59
4	csiph-out.news.xs4all.nl	560	17431	1819	14077	1535	10%	67:41:18
5	news-out.mixmin.net	1	16235	852	15057	326	5%	23:59:48
6	newsfeed.fu-berlin.de	13	14770	786	13850	134	5%	24:49:22
7	csiph-out.news.weretis.net	4	30921	783	29937	201	2%	47:59:36
8	csiph-out.feeder.erje.net	1440	72354	776	71338	240	1%	118:39:46
9	newsfeed.neva.ru	151	14178	583	13292	303	4%	71:07:25
10	csiph-out.feed.uzoreto.com	864	23856	362	23072	422	1%	71:17:25
11	news.fcku.it	17	1511	251	1250	10	16%	21:05:34
12	news-out.neodome.net	2	16698	221	16462	15	1%	23:59:51
13	nntp.pasdenom.info	2	9696	113	9573	10	1%	23:58:59
14	news.freedyn.net	1	17540	73	17333	134	0%	23:59:57
15	csiph-out.news.tnetconsulting.net	1	15313	55	15245	13	0%	23:59:56
16	news.swapon.de	3	1595	33	1562	0	2%	25:22:25
17	news.samoylyk.net	3	14470	17	14453	0	0%	24:53:09
18	news.bbs.nz	2	14946	1	14943	2	0%	23:59:52
19	localhost	26	0	0	0	0	0%	00:00:01
20	xanadu-bbs.net	23	516	0	516	0	0%	23:59:26
TOTAL: 20		3133	315640	14528	297458	3654	4%	715:35:12

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	7.5 MB	3.8 KB	2.9 MB	10.4 MB	72%	4.2 KB
2	news-out.aioe.org	7.5 MB	11.8 KB	426.2 KB	7.9 MB	94%	2.5 KB
3	feeder.eternal-september.org	6.1 MB	0.0 KB	1.2 MB	7.3 MB	83%	3.2 KB
4	csiph-out.feeder.erje.net	5.9 MB	0.0 KB	2.3 MB	8.2 MB	72%	8.3 KB
5	csiph-out.news.xs4all.nl	4.7 MB	0.0 KB	8.9 MB	13.6 MB	34%	4.1 KB
6	news-out.mixmin.net	2.2 MB	11.8 KB	510.9 KB	2.7 MB	81%	2.4 KB
7	csiph-out.news.weretis.net	2.2 MB	14.7 KB	2.1 MB	4.3 MB	50%	4.4 KB
8	newsfeed.fu-berlin.de	1.9 MB	0.0 KB	326.2 KB	2.2 MB	85%	2.5 KB
9	csiph-out.feed.uzoreto.com	1.4 MB	0.0 KB	10.1 MB	11.5 MB	12%	15.0 KB
10	newsfeed.neva.ru	1.4 MB	0.0 KB	747.5 KB	2.2 MB	66%	2.5 KB
11	news-out.neodome.net	818.6 KB	0.0 KB	342.1 KB	1.1 MB	70%	4.9 KB
12	news.freedyn.net	465.8 KB	3.9 KB	2.0 MB	2.5 MB	18%	12.2 KB
13	news.fcku.it	410.6 KB	0.0 KB	265.0 KB	675.5 KB	60%	2.6 KB
14	csiph-out.news.tnetconsulting.net	251.6 KB	21.8 KB	128.6 KB	402.0 KB	62%	5.9 KB
15	nntp.pasdenom.info	226.6 KB	0.0 KB	14.8 KB	241.4 KB	93%	2.0 KB
16	news.swapon.de	79.9 KB	0.0 KB	0.0 KB	79.9 KB	100%	2.4 KB
17	news.samoylyk.net	34.2 KB	0.0 KB	0.0 KB	34.2 KB	100%	2.0 KB
18	news.bbs.nz	1.2 KB	0.0 KB	6.0 KB	7.2 KB	16%	2.4 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		43.1 MB	67.7 KB	32.1 MB	75.3 MB	57%	4.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 15 04:15:00 - 04:59:59	186	1.3%	0.07	621.4 KB	1.4%	0.23
Dec 15 05:00:00 - 05:59:59	377	2.6%	0.10	2.3 MB	5.3%	0.65
Dec 15 06:00:00 - 06:59:59	459	3.2%	0.13	1.2 MB	2.8%	0.34
Dec 15 07:00:00 - 07:59:59	422	2.9%	0.12	1.3 MB	3.1%	0.38
Dec 15 08:00:00 - 08:59:59	578	4.0%	0.16	1.5 MB	3.5%	0.44
Dec 15 09:00:00 - 09:59:59	800	5.5%	0.22	2.5 MB	5.8%	0.71
Dec 15 10:00:00 - 10:59:59	741	5.1%	0.21	2.0 MB	4.5%	0.56
Dec 15 11:00:00 - 11:59:59	729	5.0%	0.20	1.9 MB	4.5%	0.55
Dec 15 12:00:00 - 12:59:59	685	4.7%	0.19	1.5 MB	3.5%	0.43
Dec 15 13:00:00 - 13:59:59	734	5.0%	0.20	1.9 MB	4.3%	0.53
Dec 15 14:00:00 - 14:59:59	866	5.9%	0.24	2.7 MB	6.2%	0.76
Dec 15 15:00:00 - 15:59:59	977	6.7%	0.27	3.8 MB	8.7%	1.07
Dec 15 16:00:00 - 16:59:59	952	6.5%	0.26	2.7 MB	6.3%	0.78
Dec 15 17:00:00 - 17:59:59	810	5.6%	0.23	2.1 MB	4.9%	0.60
Dec 15 18:00:00 - 18:59:59	830	5.7%	0.23	2.4 MB	5.5%	0.68
Dec 15 19:00:00 - 19:59:59	717	4.9%	0.20	1.9 MB	4.5%	0.55
Dec 15 20:00:00 - 20:59:59	735	5.0%	0.20	2.2 MB	5.2%	0.64
Dec 15 21:00:00 - 21:59:59	635	4.4%	0.18	1.8 MB	4.1%	0.50
Dec 15 22:00:00 - 22:59:59	542	3.7%	0.15	1.5 MB	3.5%	0.42
Dec 15 23:00:00 - 23:59:59	449	3.1%	0.12	1.3 MB	3.1%	0.38
Dec 16 00:00:00 - 00:59:59	367	2.5%	0.10	1.2 MB	2.7%	0.33
Dec 16 01:00:00 - 01:59:59	352	2.4%	0.10	1.1 MB	2.6%	0.32
Dec 16 02:00:00 - 02:59:59	270	1.9%	0.07	728.2 KB	1.6%	0.20
Dec 16 03:00:00 - 03:59:59	278	1.9%	0.08	819.6 KB	1.9%	0.23
Dec 16 04:00:00 - 04:15:00	77	0.5%	0.09	212.2 KB	0.5%	0.24
TOTAL: 24:00:00	14568	100.0%	0.17	43.2 MB	100.0%	0.51

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.news.xs4all.nl	1558	48	0	0	1510
2	newsfeed.fu-berlin.de	486	10	0	0	476
3	csiph-out.feed.uzoreto.com	427	35	0	1	391
4	news-out.mixmin.net	331	4	1	0	326
5	newsfeed.neva.ru	303	73	0	0	230
6	news-out.aioe.org	219	119	1	0	99
7	csiph-out.news.weretis.net	197	63	2	0	132
8	csiph-out.feeder.erje.net	140	56	0	0	84
9	news.freedyn.net	134	1	1	0	132
10	feeder.eternal-september.org	120	73	0	0	47
11	newsfeeds.xmission.com	73	13	1	0	59
12	csiph-out.news.tnetconsulting.net	30	29	0	0	1
13	news-out.neodome.net	17	3	0	0	14
14	news.swapon.de	17	0	0	0	17
15	news.fcku.it	10	8	0	0	2
16	nntp.pasdenom.info	10	0	0	0	10
17	news.bbs.nz	1	0	0	0	1
TOTAL: 17		4073	535	6	1	3531

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	188
lada.talk	28
alt.binaries.pictures.purebeauty	25
alt.tv.comedy-central.daily-show	25
scinet.stats	24
alt.politics	23
lada.test	22
it-alt.sport.calcio.juventus	21
alt.bbs.synchronet	18
mozilla.support.seamonkey	13
alt.windows7.general	11
trollnet.cinema	11
lada.auto	10
mozilla.support.thunderbird	9
alt.binaries.ebook	7
mozilla.dev.platform	7
oesterreich.tratsch	6
alt.philosophy	6
at.jobs	5
alt.politics.usa	5
TOTAL: 67	535

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)	2060
EMP (phn path)	231
EMP (phl)	106
Too many newsgroups	83
EMP (phn nph)	83
0	55
Binary: misplaced binary	8
HTML Multipart	7
Too many newsgroups (low_xpost)	1
Scoring filter	1
TOTAL: 10	2635

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	131
	csiph-out.news.xs4all.nl	87
	newsfeeds.xmission.com	52
	csiph-out.feeder.erje.net	42
	news.freedyn.net	38
	csiph-out.news.weretis.net	28
	news-out.neodome.net	6
	feeder.eternal-september.org	6
	news.fcku.it	2
	newsfeed.fu-berlin.de	1
	TOTAL: 10	393
Including strange strings
	newsfeed.fu-berlin.de	337
	news-out.aioe.org	90
	csiph-out.news.xs4all.nl	23
	news.swapon.de	17
	newsfeeds.xmission.com	7
	news-out.mixmin.net	6
	csiph-out.news.weretis.net	6
	csiph-out.feed.uzoreto.com	5
	csiph-out.feeder.erje.net	1
	newsfeed.neva.ru	1
	TOTAL: 11	494
No colon-space in header
	csiph-out.news.xs4all.nl	9
	TOTAL: 1	9
TOTAL: 4		941

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	17648	798	13763	7	6	4%	23:59:55
2	fu-berlin.de	13550	487	13063	0	0	3%	23:57:45
3	news.samoylyk.net	14900	485	14014	3	73	3%	23:51:26
4	news.bbs.nz	27501	308	13676	17	0	1%	23:49:48
5	newsfeed.xs4all.nl	13385	57	12597	0	19	0%	23:54:30
6	xmission	14085	42	11335	1	0	0%	23:54:05
7	neva.ru	13197	35	13024	14	0	0%	23:57:56
8	tnet	15231	23	14024	2	0	0%	23:56:26
9	usenet.pasdenom.info	14333	19	13907	13	27	0%	23:59:55
10	erje	14764	17	13540	59	0	0%	23:52:08
11	aioe	10323	9	9996	0	0	0%	23:50:11
12	news.freedyn.net	13864	4	13654	41	0	0%	23:50:11
13	news.uzoreto.com	13923	3	13774	22	0	0%	23:50:11
14	neodome.net	14524	2	14215	0	0	0%	23:53:17
15	news.swapon.de	1591	1	1581	0	0	0%	23:57:41
16	news.mixmin.net	13096	1	12908	0	0	0%	23:50:11
17	eternal-september	0	0	0	0	11749	0%	23:59:55
18	weretis.net	13544	0	13149	0	0	0%	23:50:11
19	news.fcku.it	1180	0	1173	0	0	0%	20:28:53
TOTAL: 19		240639	2291	213393	179	11874	0%	450:44:35

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	6.1 MB	29.7 KB	6.1 MB	0.1 KB/s	7.8 KB	23:59:55
2	fu-berlin.de	5.1 MB	0.0 KB	5.1 MB	0.1 KB/s	10.8 KB	23:57:45
3	news.samoylyk.net	5.1 MB	22.3 KB	5.1 MB	0.1 KB/s	10.8 KB	23:51:26
4	news.bbs.nz	876.2 KB	71.4 KB	947.6 KB	0.0 KB/s	2.9 KB	23:49:48
5	newsfeed.xs4all.nl	204.3 KB	0.0 KB	204.3 KB	0.0 KB/s	3.6 KB	23:54:30
6	xmission	128.8 KB	2.9 KB	131.8 KB	0.0 KB/s	3.1 KB	23:54:05
7	neva.ru	93.6 KB	28.9 KB	122.5 KB	0.0 KB/s	2.5 KB	23:57:56
8	usenet.pasdenom.info	59.4 KB	23.0 KB	82.4 KB	0.0 KB/s	2.6 KB	23:59:55
9	tnet	56.5 KB	4.0 KB	60.6 KB	0.0 KB/s	2.4 KB	23:56:26
10	erje	33.5 KB	323.2 KB	356.6 KB	0.0 KB/s	4.7 KB	23:52:08
11	aioe	24.7 KB	0.0 KB	24.7 KB	0.0 KB/s	2.7 KB	23:50:11
12	neodome.net	22.5 KB	0.0 KB	22.5 KB	0.0 KB/s	11.3 KB	23:53:17
13	news.freedyn.net	15.5 KB	662.1 KB	677.6 KB	0.0 KB/s	15.1 KB	23:50:11
14	news.uzoreto.com	8.6 KB	66.4 KB	75.1 KB	0.0 KB/s	3.0 KB	23:50:11
15	news.swapon.de	6.9 KB	0.0 KB	6.9 KB	0.0 KB/s	6.9 KB	23:57:41
16	news.mixmin.net	6.9 KB	0.0 KB	6.9 KB	0.0 KB/s	6.9 KB	23:50:11
17	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:55
18	weretis.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:11
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:28:53
TOTAL: 19		17.8 MB	1.2 MB	19.0 MB	0.0 KB/s	7.9 KB	450:44:35

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	91.187.33.87	77	2	4.0 KB	2	25:12:45
2	a131033.upc-a.chello.nl	77	2	4.0 KB	2	18:49:37
TOTAL: 2		154	4	8.0 KB	4	45:56:44

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.upc-a.chello.nl	77	2	4.0 KB	2	18:49:37
2	unresolved	77	2	4.0 KB	2	25:12:45
3	*.range31-50.btcentralplus.com	2	0	0.0 KB	0	00:24:10
4	*.ph.ph.cox.net	3	0	0.0 KB	0	01:30:10
5	*.security.ipip.net	1	0	0.0 KB	0	00:00:00
TOTAL: 5		160	4	8.0 KB	4	45:56:44

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
91.187.33.87	4.964	1.197	25:12:45
a131033.upc-a.chello.nl	5.054	0.962	18:49:37
ip72-222-200-117.ph.ph.cox.net	0.254	0.064	01:30:10
host31-50-220-99.range31-50.btcentralplus.com	0.119	0.037	00:24:10
scan-141.security.ipip.net	0.066	0.007	00:00:00
TOTAL: 5	10.457	2.267	45:56:44

Curious NNRP server explorers [Top 20]:

System	Conn
ip72-222-200-117.ph.ph.cox.net	3
host31-50-220-99.range31-50.btcentralplus.com	2
scan-141.security.ipip.net	1
TOTAL: 3	6

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
91.187.33.87	4	0
ip72-222-200-117.ph.ph.cox.net	3	0
TOTAL: 2	7	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Dec 15 04:15:00 -- Dec 16 04:15:00