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

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Nov 8 13:56:34 csiph nnrpd[96160]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 8 13:56:34 csiph nnrpd[96160]: 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 8 22:09:22 csiph nnrpd[17824]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 8 22:09:22 csiph nnrpd[17824]: 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	20074	49.6%	5.0 MB	64.3%
innd	15974	39.4%	2.1 MB	26.3%
innfeed	3422	8.5%	652.5 KB	8.1%
nnrpd	1014	2.5%	105.7 KB	1.3%
nocem	11	0.0%	1.2 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	40496	100.0%	7.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	325707	55.4%
Negative hits	242455	41.2%
Do not exist	19472	3.3%
Cache misses	297	0.1%
TOTAL: 4	587931	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.519	0.0%	33	0.000	15.727	24.667
article cleanup	00:00:00.121	0.0%	19231	0.000	0.006	0.028
article logging	00:00:00.373	0.0%	19724	0.000	0.019	0.436
article parse	00:00:01.352	0.0%	55373	0.000	0.024	0.196
article write	00:00:18.809	0.0%	15790	0.167	1.191	60.854
artlog/artcncl	00:00:00.000	0.0%	33	0.000	0.000	0.000
artlog/artparse	00:00:00.019	0.0%	324	0.000	0.059	16.000
data move	00:00:00.208	0.0%	557654	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.028	0.0%	33	0.000	0.848	10.000
hishave/artcncl	00:00:00.005	0.0%	33	0.000	0.152	0.300
hishave/artparse	00:00:00.000	0.0%	314	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:37.258	0.0%	589357	0.001	0.063	0.394
history sync	00:00:00.965	0.0%	357	0.000	2.703	22.667
history write	00:00:46.745	0.1%	19219	0.444	2.432	12.891
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.821	0.0%	314	0.000	2.615	17.000
idle	23:54:25.559	99.6%	558700	77.877	154.046	506.028
nntp read	00:00:02.238	0.0%	565032	0.001	0.004	0.009
overview write	00:03:22.967	0.2%	15790	0.093	12.854	2689.821
perl filter	00:00:30.970	0.0%	19209	1.104	1.612	5.935
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.273	0.0%	31591	0.000	0.009	0.040
TOTAL: 24:00:47.997	24:00:09.230	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.038	0.0%	15778	0.000	0.002	0.025
article prepare	00:00:00.002	0.0%	11792	0.000	0.000	0.036
article read	00:00:00.331	0.0%	1147	0.000	0.289	43.000
backlog stats	00:00:00.232	0.0%	359520	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.354	0.0%	286104	0.001	0.005	0.013
data write	00:00:01.782	0.0%	271845	0.001	0.007	0.013
idle	23:54:39.886	100.0%	359520	135.807	239.430	765.709
status file	00:00:00.166	0.0%	231	0.000	0.719	2.000
TOTAL: 23:54:50.551	23:54:43.791	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	41:29:28.667	99.8%	666	46.000	224277.278	600130.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:47.887	0.0%	1575	0.000	30.404	664.931
readart	00:00:00.384	0.0%	37	0.000	10.378	43.000
TOTAL: 41:34:12.307	41:30:16.938	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	4	14791	3162	11607	22	21%	23:19:04
2	news-out.mixmin.net	3	17075	2651	14174	250	15%	24:08:36
3	feeder.eternal-september.org	3	18205	2555	15447	203	14%	24:30:19
4	csiph-out.news.xs4all.nl	453	18881	2243	15011	1627	11%	69:14:30
5	news-out.aioe.org	2	15002	1362	13572	68	9%	24:09:58
6	csiph-out.news.weretis.net	3	32095	904	31060	131	2%	47:59:48
7	csiph-out.feeder.erje.net	1440	78870	876	77864	130	1%	118:46:34
8	newsfeed.fu-berlin.de	5	14017	622	13118	277	4%	20:41:35
9	newsfeed.neva.ru	145	13667	421	12757	489	3%	71:24:21
10	csiph-out.feed.uzoreto.com	865	26065	235	25461	369	0%	71:16:33
11	nntp.pasdenom.info	1	10463	229	10224	10	2%	23:59:55
12	news-out.neodome.net	1	17300	183	17084	33	1%	23:59:54
13	news.fcku.it	10	1516	153	1360	3	10%	21:26:00
14	news.swapon.de	5	1409	57	1352	0	4%	22:43:44
15	csiph-out.news.tnetconsulting.net	1	15293	38	15199	56	0%	23:59:54
16	news.freedyn.net	6	19336	10	19252	74	0%	24:54:25
17	localhost	33	7	7	0	0	100%	00:00:02
18	news.samoylyk.net	1	15286	4	15282	0	0%	23:59:51
19	news.bbs.nz	2	14898	2	14895	1	0%	23:59:51
20	xanadu-bbs.net	28	416	0	416	0	0%	21:04:28
TOTAL: 20		3011	344592	15714	325135	3743	4%	705:39:22

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	9.0 MB	0.0 KB	890.7 KB	9.9 MB	91%	3.2 KB
2	feeder.eternal-september.org	6.9 MB	5.8 KB	1015.2 KB	7.9 MB	87%	2.9 KB
3	csiph-out.feeder.erje.net	5.8 MB	5.4 KB	2.9 MB	8.7 MB	66%	8.8 KB
4	csiph-out.news.xs4all.nl	5.7 MB	3.1 KB	8.8 MB	14.5 MB	39%	3.8 KB
5	news-out.mixmin.net	5.2 MB	1.8 KB	411.2 KB	5.6 MB	92%	2.0 KB
6	news-out.aioe.org	4.4 MB	1.8 KB	128.1 KB	4.6 MB	97%	3.3 KB
7	csiph-out.news.weretis.net	2.5 MB	1.8 KB	1.1 MB	3.6 MB	68%	3.6 KB
8	newsfeed.fu-berlin.de	1.4 MB	0.0 KB	603.5 KB	2.0 MB	69%	2.2 KB
9	newsfeed.neva.ru	1.1 MB	5.5 KB	733.0 KB	1.9 MB	61%	2.1 KB
10	csiph-out.feed.uzoreto.com	1.1 MB	3.6 KB	12.3 MB	13.3 MB	7%	22.6 KB
11	nntp.pasdenom.info	528.1 KB	4.0 KB	15.4 KB	547.5 KB	96%	2.3 KB
12	news-out.neodome.net	447.6 KB	4.0 KB	153.6 KB	605.1 KB	73%	2.8 KB
13	news.fcku.it	263.5 KB	0.0 KB	18.7 KB	282.2 KB	93%	1.8 KB
14	csiph-out.news.tnetconsulting.net	183.2 KB	0.0 KB	331.3 KB	514.4 KB	35%	5.5 KB
15	news.swapon.de	121.2 KB	0.0 KB	0.0 KB	121.2 KB	100%	2.1 KB
16	news.freedyn.net	89.9 KB	5.8 KB	825.2 KB	921.0 KB	9%	11.0 KB
17	news.samoylyk.net	13.8 KB	0.0 KB	0.0 KB	13.8 KB	100%	3.4 KB
18	localhost	9.3 KB	0.0 KB	0.0 KB	9.3 KB	100%	1.3 KB
19	news.bbs.nz	3.6 KB	0.0 KB	99.9 KB	103.5 KB	3%	34.5 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		44.6 MB	42.5 KB	30.2 MB	74.9 MB	59%	3.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 8 04:15:00 - 04:59:59	209	1.3%	0.08	628.5 KB	1.4%	0.23
Nov 8 05:00:00 - 05:59:59	264	1.7%	0.07	861.6 KB	1.9%	0.24
Nov 8 06:00:00 - 06:59:59	325	2.1%	0.09	905.4 KB	2.0%	0.25
Nov 8 07:00:00 - 07:59:59	457	2.9%	0.13	1.5 MB	3.3%	0.43
Nov 8 08:00:00 - 08:59:59	660	4.2%	0.18	2.3 MB	5.1%	0.65
Nov 8 09:00:00 - 09:59:59	738	4.7%	0.20	2.2 MB	5.0%	0.63
Nov 8 10:00:00 - 10:59:59	698	4.4%	0.19	2.0 MB	4.4%	0.56
Nov 8 11:00:00 - 11:59:59	710	4.5%	0.20	2.5 MB	5.6%	0.71
Nov 8 12:00:00 - 12:59:59	695	4.4%	0.19	1.8 MB	4.1%	0.52
Nov 8 13:00:00 - 13:59:59	773	4.9%	0.21	2.0 MB	4.4%	0.56
Nov 8 14:00:00 - 14:59:59	864	5.5%	0.24	2.5 MB	5.5%	0.70
Nov 8 15:00:00 - 15:59:59	885	5.6%	0.25	2.2 MB	5.0%	0.64
Nov 8 16:00:00 - 16:59:59	980	6.2%	0.27	2.5 MB	5.5%	0.70
Nov 8 17:00:00 - 17:59:59	964	6.1%	0.27	2.6 MB	5.8%	0.74
Nov 8 18:00:00 - 18:59:59	880	5.6%	0.24	2.4 MB	5.3%	0.67
Nov 8 19:00:00 - 19:59:59	832	5.3%	0.23	2.4 MB	5.3%	0.67
Nov 8 20:00:00 - 20:59:59	852	5.4%	0.24	2.2 MB	5.0%	0.64
Nov 8 21:00:00 - 21:59:59	861	5.5%	0.24	2.5 MB	5.5%	0.70
Nov 8 22:00:00 - 22:59:59	689	4.4%	0.19	1.8 MB	4.0%	0.51
Nov 8 23:00:00 - 23:59:59	611	3.9%	0.17	2.0 MB	4.5%	0.57
Nov 9 00:00:00 - 00:59:59	550	3.5%	0.15	1.6 MB	3.6%	0.46
Nov 9 01:00:00 - 01:59:59	466	3.0%	0.13	1.3 MB	2.9%	0.37
Nov 9 02:00:00 - 02:59:59	399	2.5%	0.11	1.1 MB	2.4%	0.30
Nov 9 03:00:00 - 03:59:59	323	2.0%	0.09	916.7 KB	2.0%	0.25
Nov 9 04:00:00 - 04:15:00	102	0.6%	0.11	281.5 KB	0.6%	0.31
TOTAL: 24:00:00	15787	100.0%	0.18	44.7 MB	100.0%	0.53

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1649	52	2	1595
2	newsfeed.fu-berlin.de	622	17	0	605
3	newsfeed.neva.ru	502	45	3	454
4	csiph-out.feed.uzoreto.com	372	12	2	358
5	news-out.mixmin.net	308	4	1	303
6	feeder.eternal-september.org	201	46	2	153
7	csiph-out.news.weretis.net	170	38	2	130
8	csiph-out.feeder.erje.net	132	45	3	84
9	news-out.aioe.org	109	53	1	55
10	news.freedyn.net	74	0	2	72
11	news-out.neodome.net	28	2	1	25
12	newsfeeds.xmission.com	26	11	0	15
13	csiph-out.news.tnetconsulting.net	25	21	2	2
14	news.swapon.de	23	0	0	23
15	nntp.pasdenom.info	10	0	1	9
16	news.fcku.it	3	2	0	1
TOTAL: 16		4254	348	22	3884

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	52
it-alt.sport.calcio.juventus	48
alt.computer.workshop	27
scinet.stats	23
alt.politics.usa	21
alt.politics	15
spline.eisfair	13
it-alt.test	13
alt.binaries.pictures.purebeauty	11
free.it.religioni.scientology	10
oesterreich.tratsch	7
mozilla.general	7
mozilla.support.seamonkey	6
mozilla.support.thunderbird	5
alt.bbs.synchronet	5
alt.sex.erotica.female	5
alt.sports.hockey.nhl	4
alt.survival	4
alt.binaries.ebook	4
mozilla.support.firefox	3
TOTAL: 68	348

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2226
EMP (phn path)	445
EMP (phn nph)	148
EMP (phl)	135
Too many newsgroups	58
0	42
HTML Multipart	9
Binary: misplaced binary	3
Scoring filter	2
TOTAL: 9	3068

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	50
	csiph-out.news.xs4all.nl	1
	csiph-out.feed.uzoreto.com	1
	TOTAL: 3	52
Huge articles
	csiph-out.feed.uzoreto.com	139
	csiph-out.news.xs4all.nl	81
	csiph-out.feeder.erje.net	54
	newsfeeds.xmission.com	12
	news.freedyn.net	9
	csiph-out.news.weretis.net	9
	feeder.eternal-september.org	8
	csiph-out.news.tnetconsulting.net	2
	news-out.neodome.net	1
	TOTAL: 9	315
Including strange strings
	newsfeed.fu-berlin.de	336
	news-out.mixmin.net	58
	news-out.aioe.org	40
	news.swapon.de	23
	csiph-out.news.xs4all.nl	22
	csiph-out.news.weretis.net	7
	newsfeeds.xmission.com	3
	csiph-out.feed.uzoreto.com	2
	csiph-out.feeder.erje.net	1
	TOTAL: 9	492
No colon-space in header
	csiph-out.news.xs4all.nl	9
	csiph-out.feeder.erje.net	1
	TOTAL: 2	10
TOTAL: 4		869

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	18708	793	14984	10	0	4%	23:59:55
2	news.samoylyk.net	16181	546	15230	7	0	3%	23:59:55
3	fu-berlin.de	14900	517	14383	0	0	3%	23:59:51
4	news.bbs.nz	29719	412	14829	18	1	1%	23:59:55
5	tnet	16468	161	15107	0	0	0%	23:59:39
6	neva.ru	14783	104	14501	74	0	0%	23:59:55
7	newsfeed.xs4all.nl	13905	45	13435	1	14	0%	23:59:55
8	erje	14530	15	14135	109	0	0%	23:59:37
9	news.freedyn.net	15493	15	15144	24	0	0%	23:59:55
10	xmission	13850	14	11956	0	0	0%	23:59:55
11	aioe	11801	12	11262	0	0	0%	23:59:55
12	news.mixmin.net	12574	10	12269	1	0	0%	23:59:32
13	weretis.net	14566	10	14295	0	0	0%	23:59:55
14	neodome.net	15891	10	15491	0	0	0%	23:59:55
15	usenet.pasdenom.info	15575	9	15020	1	0	0%	23:59:55
16	eternal-september	12941	9	12605	0	0	0%	23:59:55
17	news.swapon.de	1405	7	1392	0	0	0%	23:41:26
18	news.uzoreto.com	15329	2	15005	12	0	0%	23:59:55
19	news.fcku.it	1281	0	1281	0	0	0%	21:02:53
TOTAL: 19		269900	2691	242324	257	15	0%	452:41:53

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	5.4 MB	22.5 KB	5.4 MB	0.1 KB/s	6.9 KB	23:59:55
2	fu-berlin.de	4.7 MB	0.0 KB	4.7 MB	0.1 KB/s	9.3 KB	23:59:51
3	news.samoylyk.net	4.7 MB	79.9 KB	4.8 MB	0.1 KB/s	8.9 KB	23:59:55
4	news.bbs.nz	1.1 MB	49.7 KB	1.1 MB	0.0 KB/s	2.6 KB	23:59:55
5	tnet	398.3 KB	0.0 KB	398.3 KB	0.0 KB/s	2.5 KB	23:59:39
6	neva.ru	258.8 KB	182.4 KB	441.2 KB	0.0 KB/s	2.5 KB	23:59:55
7	newsfeed.xs4all.nl	108.8 KB	5.6 KB	114.4 KB	0.0 KB/s	2.5 KB	23:59:55
8	news.freedyn.net	30.4 KB	286.2 KB	316.5 KB	0.0 KB/s	8.1 KB	23:59:55
9	erje	29.2 KB	297.5 KB	326.7 KB	0.0 KB/s	2.6 KB	23:59:37
10	aioe	29.2 KB	0.0 KB	29.2 KB	0.0 KB/s	2.4 KB	23:59:55
11	xmission	24.1 KB	0.0 KB	24.1 KB	0.0 KB/s	1.7 KB	23:59:55
12	usenet.pasdenom.info	17.9 KB	2.0 KB	19.9 KB	0.0 KB/s	2.0 KB	23:59:55
13	weretis.net	15.6 KB	0.0 KB	15.6 KB	0.0 KB/s	1.6 KB	23:59:55
14	news.mixmin.net	14.1 KB	4.3 KB	18.4 KB	0.0 KB/s	1.7 KB	23:59:32
15	neodome.net	13.8 KB	0.0 KB	13.8 KB	0.0 KB/s	1.4 KB	23:59:55
16	eternal-september	12.8 KB	0.0 KB	12.8 KB	0.0 KB/s	1.4 KB	23:59:55
17	news.swapon.de	9.3 KB	0.0 KB	9.3 KB	0.0 KB/s	1.3 KB	23:41:26
18	news.uzoreto.com	2.0 KB	41.3 KB	43.4 KB	0.0 KB/s	3.1 KB	23:59:55
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:02:53
TOTAL: 19		16.8 MB	971.3 KB	17.8 MB	0.0 KB/s	6.2 KB	452:41:53

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	ppp-2-84-162-19.home.otenet.gr	1	10	13.2 KB	1	0	0	00:00:06
2	p2e5b5c10.dip0.t-ipconnect.de	1	9	15.9 KB	2	0	0	00:01:03
3	a131033.upc-a.chello.nl	24	9	12.4 KB	7	0	0	05:14:20
4	131.93.176.49	39	9	12.4 KB	9	7	7	10:35:07
TOTAL: 4		65	37	53.9 KB	19	7	7	41:34:11

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.home.otenet.gr	1	10	13.2 KB	1	0	0	00:00:06
2	*.upc-a.chello.nl	24	9	12.4 KB	7	0	0	05:14:20
3	unresolved	94	9	12.4 KB	9	7	7	28:41:32
4	*.dip0.t-ipconnect.de	1	9	15.9 KB	2	0	0	00:01:03
5	*.ch1.censys-scanner.com	1	0	0.0 KB	0	0	0	00:00:00
6	*.net.upcbroadband.cz	16	0	0.0 KB	0	0	0	05:06:46
7	*.ph.ph.cox.net	4	0	0.0 KB	0	0	0	02:00:18
8	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:01
9	*.range86-138.btcentralplus.com	1	0	0.0 KB	0	0	0	00:30:02
TOTAL: 9		143	37	53.9 KB	19	7	7	41:34:11

NNRP auth users [Top 20]:

User	Conn
ohlandl	7
TOTAL: 1	7

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	3.641	1.339	0.000	18:06:25
131.93.176.49	2.902	0.820	0.019	10:35:07
a131033.upc-a.chello.nl	1.970	0.577	0.000	05:14:20
ip-78-102-198-192.net.upcbroadband.cz	1.034	0.308	0.000	05:06:46
ip72-222-200-117.ph.ph.cox.net	0.421	0.072	0.000	02:00:18
host86-138-214-15.range86-138.btcentralplus.com	0.059	0.020	0.000	00:30:02
p2e5b5c10.dip0.t-ipconnect.de	0.135	0.067	0.000	00:01:03
ppp-2-84-162-19.home.otenet.gr	0.104	0.016	0.000	00:00:06
scan-141.security.ipip.net	0.040	0.032	0.000	00:00:01
scanner-07.ch1.censys-scanner.com	0.065	0.016	0.000	00:00:00
TOTAL: 10	10.371	3.267	0.019	41:34:11

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	55
ip-78-102-198-192.net.upcbroadband.cz	16
ip72-222-200-117.ph.ph.cox.net	4
host86-138-214-15.range86-138.btcentralplus.com	1
scan-141.security.ipip.net	1
scanner-07.ch1.censys-scanner.com	1
TOTAL: 6	78

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
scanner-07.ch1.censys-scanner.com	1
TOTAL: 2	5

NNRP unrecognized commands (by command):

Command	Count
User-Agent: HTTP Banner Detection (https...	1
Connection: close	1
GET / HTTP/1.1	1
EHLO www.censys.io	1
Host: 69.160.37.58:119	1
TOTAL: 5	5

NNRP client timeouts [Top 20]:

System	Conn	Peer
131.93.176.49	12	0
ip72-222-200-117.ph.ph.cox.net	4	0
host86-138-214-15.range86-138.btcentralplus.com	1	0
TOTAL: 3	17	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	28	75.7%
2	de	8	21.6%
3	alt	1	2.7%
TOTAL: 3		37	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	28
2	de.sci.electronics	8
3	alt.free.newsservers	1
TOTAL: 3		37

Daily Usenet report for csiph.com

Nov 8 04:15:00 -- Nov 9 04:15:00