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

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Mar 8 11:16:23 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[92932]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Mar 8 11:16:23 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[92932]: 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]
Mar 8 16:39:37 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[15164]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Mar 8 16:39:37 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[15164]: 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]
Mar 8 17:14:47 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[17060]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Mar 8 17:14:47 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[17060]: 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	26377	53.3%	6.3 MB	62.7%
innd	17363	35.1%	2.8 MB	27.5%
innfeed	3721	7.5%	720.6 KB	7.0%
nnrpd	2032	4.1%	284.5 KB	2.8%
nocem	24	0.0%	3.3 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	49518	100.0%	10.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	330247	51.1%
Negative hits	290175	44.9%
Do not exist	25705	4.0%
Cache misses	384	0.1%
TOTAL: 4	646511	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.932	0.0%	48	0.000	19.417	43.667
article cleanup	00:00:00.136	0.0%	25372	0.000	0.005	0.029
article logging	00:00:01.101	0.0%	25861	0.000	0.043	0.612
article parse	00:00:02.583	0.0%	76904	0.000	0.034	0.529
article write	00:01:33.715	0.1%	18909	0.160	4.956	58.778
artlog/artcncl	00:00:00.000	0.0%	48	0.000	0.000	0.000
artlog/artparse	00:00:00.008	0.0%	477	0.000	0.017	1.000
data move	00:00:00.201	0.0%	604902	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.011	0.0%	48	0.000	0.229	1.000
hishave/artcncl	00:00:00.004	0.0%	48	0.000	0.083	1.000
hishave/artparse	00:00:00.001	0.0%	476	0.000	0.002	0.111
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:46.389	0.1%	647891	0.003	0.072	0.338
history sync	00:00:01.510	0.0%	370	0.000	4.081	31.000
history write	00:01:22.516	0.1%	25330	0.179	3.258	12.741
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:02.000	0.0%	472	0.000	4.237	39.333
idle	23:47:21.056	99.1%	599385	61.671	142.882	582.010
nntp read	00:00:02.494	0.0%	610205	0.002	0.004	0.009
overview write	00:08:15.916	0.6%	18909	0.075	26.226	4371.635
perl filter	00:00:31.960	0.0%	25328	0.878	1.262	2.876
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.339	0.0%	37842	0.000	0.009	0.030
TOTAL: 24:00:55.114	24:00:02.872	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.047	0.0%	18995	0.000	0.002	0.017
article prepare	00:00:00.001	0.0%	5064	0.000	0.000	0.042
article read	00:00:01.174	0.0%	1091	0.000	1.076	186.250
backlog stats	00:00:00.187	0.0%	327063	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.172	0.0%	266200	0.000	0.004	0.009
data write	00:00:01.554	0.0%	248938	0.002	0.006	0.014
idle	23:53:21.884	100.0%	327063	124.749	262.952	1038.408
status file	00:00:00.408	0.0%	377	0.000	1.082	12.000
TOTAL: 23:53:37.897	23:53:26.427	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	50:24:01.299	99.4%	1099	0.000	165096.723	600047.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.021	0.0%	2603	0.000	0.008	0.857
readart	00:00:00.091	0.0%	26	3.500	3.500	3.500
TOTAL: 50:40:50.520	50:24:01.411	99.4%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.neva.ru	144	26478	4239	22059	180	16%	71:44:20
2	csiph-out.news.xs4all.nl	0	5784	2436	2295	1053	42%	78:38:57
3	feeder.eternal-september.org	12	4845	2136	2489	220	44%	26:45:57
4	newsfeeds.xmission.com	3	18437	1935	16499	3	10%	26:36:45
5	news-out.aioe.org	3	16605	1682	14895	28	10%	24:08:57
6	news-out.mixmin.net	3	19522	1213	17946	363	6%	24:08:47
7	csiph-out.news.weretis.net	3	30766	1044	29619	103	3%	48:08:53
8	csiph-out.feeder.erje.net	1440	94596	962	93324	310	1%	118:40:38
9	newsfeed.fu-berlin.de	12	21426	845	16795	3786	3%	25:13:15
10	news.freedyn.net	2	20680	781	19517	382	3%	24:09:01
11	csiph-out.feed.uzoreto.com	863	29990	753	28734	503	2%	71:16:15
12	news-out.neodome.net	2	18101	385	17691	25	2%	24:59:56
13	news.fcku.it	12	1785	248	1533	4	13%	21:57:58
14	nntp.pasdenom.info	2	9513	96	9415	2	1%	23:59:54
15	news.swapon.de	8	1230	61	1169	0	4%	23:30:14
16	csiph-out.news.tnetconsulting.net	2	12375	41	12326	8	0%	24:02:56
17	news.bbs.nz	1	17774	8	17766	0	0%	31:16:10
18	localhost	27	1	1	0	0	100%	00:00:01
TOTAL: 18		2539	349908	18866	324072	6970	5%	689:18:54

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.neva.ru	10.0 MB	3.8 KB	841.5 KB	10.8 MB	92%	2.5 KB
2	csiph-out.feeder.erje.net	6.8 MB	64.5 KB	3.9 MB	10.7 MB	63%	8.7 KB
3	csiph-out.news.xs4all.nl	6.8 MB	25.5 KB	8.2 MB	15.0 MB	45%	4.4 KB
4	feeder.eternal-september.org	5.7 MB	0.0 KB	7.1 MB	12.8 MB	44%	5.6 KB
5	newsfeeds.xmission.com	5.7 MB	0.0 KB	283.1 KB	6.0 MB	95%	3.2 KB
6	news-out.aioe.org	4.2 MB	0.0 KB	34.8 KB	4.2 MB	99%	2.5 KB
7	csiph-out.news.weretis.net	2.9 MB	11.4 KB	1.4 MB	4.3 MB	67%	3.8 KB
8	news-out.mixmin.net	2.8 MB	15.3 KB	755.4 KB	3.6 MB	79%	2.3 KB
9	csiph-out.feed.uzoreto.com	2.6 MB	13.9 KB	9.7 MB	12.3 MB	21%	10.0 KB
10	news.freedyn.net	2.0 MB	11.3 KB	2.7 MB	4.7 MB	42%	4.1 KB
11	newsfeed.fu-berlin.de	1.9 MB	0.0 KB	9.5 MB	11.4 MB	16%	2.5 KB
12	news-out.neodome.net	1.2 MB	11.3 KB	426.5 KB	1.6 MB	73%	4.1 KB
13	news.fcku.it	419.7 KB	0.0 KB	5.9 KB	425.7 KB	98%	1.7 KB
14	nntp.pasdenom.info	195.7 KB	0.0 KB	7.3 KB	203.0 KB	96%	2.1 KB
15	news.swapon.de	140.0 KB	0.0 KB	0.0 KB	140.0 KB	100%	2.3 KB
16	csiph-out.news.tnetconsulting.net	129.6 KB	0.0 KB	120.8 KB	250.3 KB	51%	5.1 KB
17	news.bbs.nz	16.9 KB	0.0 KB	0.0 KB	16.9 KB	100%	2.1 KB
18	localhost	2.8 KB	0.0 KB	0.0 KB	2.8 KB	100%	2.8 KB
TOTAL: 18		53.4 MB	157.0 KB	44.8 MB	98.3 MB	54%	3.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 8 04:15:00 - 04:59:59	230	1.2%	0.09	685.4 KB	1.2%	0.25
Mar 8 05:00:00 - 05:59:59	375	2.0%	0.10	2.3 MB	4.3%	0.65
Mar 8 06:00:00 - 06:59:59	304	1.6%	0.08	854.7 KB	1.6%	0.24
Mar 8 07:00:00 - 07:59:59	369	2.0%	0.10	1.3 MB	2.5%	0.38
Mar 8 08:00:00 - 08:59:59	609	3.2%	0.17	1.8 MB	3.4%	0.52
Mar 8 09:00:00 - 09:59:59	658	3.5%	0.18	2.0 MB	3.7%	0.56
Mar 8 10:00:00 - 10:59:59	953	5.0%	0.26	2.6 MB	4.8%	0.73
Mar 8 11:00:00 - 11:59:59	732	3.9%	0.20	1.9 MB	3.5%	0.54
Mar 8 12:00:00 - 12:59:59	684	3.6%	0.19	1.8 MB	3.4%	0.52
Mar 8 13:00:00 - 13:59:59	752	4.0%	0.21	2.0 MB	3.7%	0.57
Mar 8 14:00:00 - 14:59:59	787	4.2%	0.22	2.2 MB	4.2%	0.63
Mar 8 15:00:00 - 15:59:59	921	4.9%	0.26	2.4 MB	4.5%	0.69
Mar 8 16:00:00 - 16:59:59	885	4.7%	0.25	3.5 MB	6.6%	1.00
Mar 8 17:00:00 - 17:59:59	851	4.5%	0.24	2.2 MB	4.1%	0.63
Mar 8 18:00:00 - 18:59:59	752	4.0%	0.21	2.1 MB	3.8%	0.59
Mar 8 19:00:00 - 19:59:59	891	4.7%	0.25	2.6 MB	4.9%	0.74
Mar 8 20:00:00 - 20:59:59	747	3.9%	0.21	2.2 MB	4.1%	0.62
Mar 8 21:00:00 - 21:59:59	1627	8.6%	0.45	3.9 MB	7.3%	1.12
Mar 8 22:00:00 - 22:59:59	1434	7.6%	0.40	3.4 MB	6.3%	0.97
Mar 8 23:00:00 - 23:59:59	1100	5.8%	0.31	2.6 MB	4.8%	0.74
Mar 9 00:00:00 - 00:59:59	782	4.1%	0.22	2.1 MB	3.9%	0.60
Mar 9 01:00:00 - 01:59:59	922	4.9%	0.26	2.7 MB	5.0%	0.76
Mar 9 02:00:00 - 02:59:59	709	3.7%	0.20	2.2 MB	4.1%	0.63
Mar 9 03:00:00 - 03:59:59	686	3.6%	0.19	1.9 MB	3.6%	0.55
Mar 9 04:00:00 - 04:15:00	154	0.8%	0.17	383.0 KB	0.7%	0.43
TOTAL: 24:00:00	18914	100.0%	0.22	53.7 MB	100.0%	0.64

Sites sending bad articles:

Server		Total	Group	Duplic	TooOld	Other
1	newsfeed.fu-berlin.de	4237	6	0	0	4231
2	csiph-out.news.xs4all.nl	1068	52	9	1	1006
3	csiph-out.feed.uzoreto.com	514	32	6	0	476
4	news.freedyn.net	388	7	1	0	380
5	news-out.mixmin.net	372	7	2	0	363
6	feeder.eternal-september.org	223	15	0	0	208
7	csiph-out.feeder.erje.net	182	45	19	0	118
8	newsfeed.neva.ru	180	32	1	0	147
9	csiph-out.news.weretis.net	114	44	2	0	68
10	news-out.neodome.net	46	7	1	0	38
11	news.swapon.de	33	0	0	0	33
12	news-out.aioe.org	32	16	0	0	16
13	newsfeeds.xmission.com	13	0	0	0	13
14	csiph-out.news.tnetconsulting.net	6	3	2	0	1
15	news.fcku.it	4	4	0	0	0
16	nntp.pasdenom.info	3	0	0	0	3
TOTAL: 16		7415	270	43	1	7101

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	50
it-alt.sport.calcio.juventus	31
alt.bbs.synchronet	21
mozilla.support.thunderbird	17
mozilla.support.firefox	14
lada.talk	10
alt.binaries.by-hash.shv1	10
alt.binaries.pictures.purebeauty	9
alt.job	8
at.jobs	8
mozilla.dev.security.policy	6
oesterreich.tratsch	4
alt.christian.bible	3
alt.politics	3
alt.philosophy	3
att.jobs	3
rocksolid.test.test	3
mozilla.dev.tech.js-engine	3
alt.religion	3
alt.math.undergrad	3
TOTAL: 69	270

INND Perl filter [Top 20]:

Reason	Count
Binary: misplaced binary	3820
EMP (md5)	1817
Too many newsgroups	202
EMP (phl)	155
0	46
EMP (phn nph)	29
EMP (phn path)	26
Scoring filter	14
hierarchy violation - crosspost outside sdnet	13
HTML Multipart	8
Too many newsgroups (meow)	3
Binary Payload	1
TOTAL: 12	6134

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
	csiph-out.news.xs4all.nl	1
	TOTAL: 3	52
Huge articles
	csiph-out.feed.uzoreto.com	129
	feeder.eternal-september.org	96
	csiph-out.news.xs4all.nl	94
	csiph-out.feeder.erje.net	68
	news.freedyn.net	32
	news-out.neodome.net	27
	csiph-out.news.weretis.net	14
	newsfeed.fu-berlin.de	11
	newsfeeds.xmission.com	3
	news-out.mixmin.net	1
	TOTAL: 11	476
Including strange strings
	newsfeed.fu-berlin.de	364
	news.swapon.de	33
	csiph-out.news.xs4all.nl	30
	csiph-out.feed.uzoreto.com	17
	csiph-out.news.weretis.net	11
	news-out.mixmin.net	11
	newsfeeds.xmission.com	10
	csiph-out.feeder.erje.net	7
	news-out.aioe.org	4
	feeder.eternal-september.org	2
	TOTAL: 11	490
No colon-space in header
	csiph-out.feeder.erje.net	1
	TOTAL: 1	1
TOTAL: 4		1019

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	fu-berlin.de	17682	563	17117	0	1	3%	23:59:55
2	newsfeed.xs4all.nl	17347	96	16358	3	135	0%	23:58:36
3	erje	17773	87	17062	137	0	0%	24:03:46
4	news.bbs.nz	17164	82	8787	11	9495	0%	24:08:38
5	xmission	20328	80	16322	5	0	0%	24:00:56
6	tnet	19464	57	18205	3	0	0%	23:54:51
7	usenet.pasdenom.info	18774	18	18226	2	0	0%	23:59:51
8	news.mixmin.net	17078	16	16790	0	1	0%	24:03:05
9	news.uzoreto.com	17384	14	17244	32	1	0%	23:59:55
10	news.freedyn.net	17988	6	17743	45	1	0%	23:50:37
11	neodome.net	18592	3	18298	0	1	0%	23:59:54
12	aioe	16198	2	15689	0	0	0%	23:57:11
13	news.swapon.de	1261	2	1241	0	0	0%	23:38:05
14	weretis.net	17220	1	16901	0	0	0%	23:59:55
15	eternal-september	0	0	0	0	16049	0%	23:59:55
16	news.fcku.it	1445	0	1441	0	0	0%	21:27:06
17	neva.ru	13435	0	13385	4	1	0%	23:59:55
18	news.samoylyk.net	0	0	0	0	18868	0%	23:58:34
19	xanadu-bbs	0	0	0	0	18868	0%	23:58:42
TOTAL: 19		249133	1027	230809	242	63421	0%	452:59:27

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	fu-berlin.de	5.7 MB	0.0 KB	5.7 MB	0.1 KB/s	10.4 KB	23:59:55
2	newsfeed.xs4all.nl	421.5 KB	68.6 KB	490.1 KB	0.0 KB/s	5.0 KB	23:58:36
3	erje	287.4 KB	452.9 KB	740.3 KB	0.0 KB/s	3.3 KB	24:03:46
4	news.bbs.nz	257.7 KB	40.0 KB	297.6 KB	0.0 KB/s	3.2 KB	24:08:38
5	xmission	232.1 KB	15.6 KB	247.7 KB	0.0 KB/s	2.9 KB	24:00:56
6	tnet	103.1 KB	8.2 KB	111.3 KB	0.0 KB/s	1.9 KB	23:54:51
7	news.uzoreto.com	96.6 KB	249.1 KB	345.7 KB	0.0 KB/s	7.5 KB	23:59:55
8	news.mixmin.net	95.2 KB	0.0 KB	95.2 KB	0.0 KB/s	6.0 KB	24:03:05
9	news.freedyn.net	82.4 KB	912.1 KB	994.5 KB	0.0 KB/s	19.5 KB	23:50:37
10	usenet.pasdenom.info	50.3 KB	5.6 KB	55.9 KB	0.0 KB/s	2.8 KB	23:59:51
11	aioe	4.3 KB	0.0 KB	4.3 KB	0.0 KB/s	2.2 KB	23:57:11
12	news.swapon.de	4.0 KB	0.0 KB	4.0 KB	0.0 KB/s	2.0 KB	23:38:05
13	neodome.net	3.7 KB	0.0 KB	3.7 KB	0.0 KB/s	1.2 KB	23:59:54
14	weretis.net	2.8 KB	0.0 KB	2.8 KB	0.0 KB/s	2.8 KB	23:59:55
15	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:55
16	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:27:06
17	neva.ru	0.0 KB	10.9 KB	10.9 KB	0.0 KB/s	2.7 KB	23:59:55
18	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:34
19	xanadu-bbs	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
TOTAL: 19		7.3 MB	1.7 MB	9.0 MB	0.0 KB/s	7.3 KB	452:59:27

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	208.72.53.130	17	26	59.0 KB	30	1	1	06:42:45
TOTAL: 1		17	26	59.0 KB	30	1	1	50:40:22

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	305	26	59.0 KB	30	1	1	47:11:40
2	*.upc-a.chello.nl	18	0	0.0 KB	0	0	0	02:54:21
3	*.range31-49.btcentralplus.com	3	0	0.0 KB	0	0	0	00:34:20
TOTAL: 3		326	26	59.0 KB	30	1	1	50:40:22

NNRP auth users [Top 20]:

User	Conn
kev009	1
TOTAL: 1	1

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	17.426	3.790	0.000	40:28:54
208.72.53.130	1.153	0.300	0.003	06:42:45
a131033.upc-a.chello.nl	1.089	0.240	0.000	02:54:21
host31-49-229-214.range31-49.btcentralplus.com	0.213	0.015	0.000	00:34:20
TOTAL: 4	19.881	4.345	0.003	50:40:22

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	288
a131033.upc-a.chello.nl	18
host31-49-229-214.range31-49.btcentralplus.com	3
TOTAL: 3	309

NNRP client timeouts [Top 20]:

System	Conn	Peer
208.72.53.130	11	0
host31-49-229-214.range31-49.btcentralplus.com	1	0
TOTAL: 2	12	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.arch	30
TOTAL: 1		30

Daily Usenet report for csiph.com

Mar 8 04:15:00 -- Mar 9 04:15:00