Daily Usenet report for csiph.com: Feb 19 04:15:00

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Feb 19 10:25:05 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[3992]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Feb 19 10:25:05 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[3992]: 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]
Feb 19 15:04:23 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[18284]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Feb 19 15:04:23 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[18284]: 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]
Feb 19 20:20:10 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[34711]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Feb 19 20:20:10 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[34711]: 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	35446	49.5%	6.5 MB	53.8%
innd	30575	42.7%	4.6 MB	38.2%
innfeed	3617	5.0%	703.4 KB	5.7%
nnrpd	2015	2.8%	285.3 KB	2.3%
nocem	11	0.0%	1.6 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	71665	100.0%	12.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	298651	49.7%
Negative hits	267177	44.4%
Do not exist	34817	5.8%
Cache misses	762	0.1%
TOTAL: 4	601407	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.295	0.0%	27	0.000	10.926	17.333
article cleanup	00:00:00.186	0.0%	34662	0.000	0.005	0.025
article logging	00:00:01.535	0.0%	35075	0.000	0.044	0.879
article parse	00:00:01.817	0.0%	96987	0.000	0.019	0.181
article write	00:01:08.804	0.1%	14927	0.152	4.609	29.021
artlog/artcncl	00:00:00.002	0.0%	27	0.000	0.074	0.222
artlog/artparse	00:00:00.008	0.0%	379	0.000	0.021	1.000
data move	00:00:00.291	0.0%	588476	0.000	0.000	0.019
hisgrep/artcncl	00:00:00.009	0.0%	26	0.000	0.346	1.000
hishave/artcncl	00:00:00.014	0.0%	27	0.000	0.519	4.000
hishave/artparse	00:00:00.002	0.0%	378	0.000	0.005	0.111
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:08.506	0.1%	604174	0.005	0.113	0.580
history sync	00:00:02.215	0.0%	380	0.000	5.829	94.000
history write	00:02:02.889	0.1%	34635	0.239	3.548	17.086
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:01.027	0.0%	378	0.000	2.717	50.000
idle	23:43:28.724	98.7%	585989	82.754	145.751	429.301
nntp read	00:00:02.544	0.0%	596613	0.002	0.004	0.013
overview write	00:12:08.970	0.8%	14927	0.094	48.836	16226.269
perl filter	00:00:34.309	0.0%	34634	0.772	0.991	1.301
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.275	0.0%	29865	0.000	0.009	0.041
TOTAL: 24:01:36.847	24:00:42.422	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.040	0.0%	15006	0.000	0.003	0.024
article prepare	00:00:00.000	0.0%	5460	0.000	0.000	0.000
article read	00:00:00.258	0.0%	1074	0.000	0.240	24.250
backlog stats	00:00:00.204	0.0%	284341	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.987	0.0%	224034	0.001	0.004	0.012
data write	00:00:01.373	0.0%	211126	0.000	0.007	0.015
idle	23:54:16.779	100.0%	284341	164.352	302.653	949.046
status file	00:00:00.401	0.0%	368	0.000	1.090	32.000
TOTAL: 23:54:30.153	23:54:20.042	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	42:14:10.233	99.5%	1127	0.000	134915.912	600145.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.061	0.0%	2635	0.000	0.023	4.000
readart	00:00:00.248	0.0%	7	22.000	35.429	41.000
TOTAL: 42:27:15.203	42:14:10.542	99.5%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	3	13327	2267	11046	14	17%	22:30:47
2	feeder.eternal-september.org	13	5721	2215	3214	292	38%	28:31:02
3	news.freedyn.net	3	21880	1801	19326	753	8%	24:08:32
4	csiph-out.news.xs4all.nl	0	6264	1443	3245	1576	23%	65:45:17
5	news-out.aioe.org	1	13720	1421	12278	21	10%	23:59:56
6	csiph-out.feeder.erje.net	1440	72921	1053	71235	633	1%	119:07:18
7	csiph-out.news.weretis.net	3	40927	1012	38910	1005	2%	47:53:39
8	news-out.mixmin.net	2	20620	827	19534	259	4%	23:59:28
9	newsfeed.fu-berlin.de	16	31207	772	17711	12724	2%	26:10:14
10	newsfeed.neva.ru	147	25099	637	23864	598	2%	71:23:15
11	csiph-out.feed.uzoreto.com	864	29090	501	25922	2667	1%	71:22:50
12	news-out.neodome.net	2	15865	286	15539	40	1%	23:59:51
13	news.fcku.it	9	1389	179	1204	6	12%	21:43:08
14	news.swapon.de	10	1230	80	1150	0	6%	24:37:35
15	csiph-out.news.tnetconsulting.net	1	14916	79	14826	11	0%	23:59:56
16	nntp.pasdenom.info	2	9577	74	9503	0	0%	23:58:22
17	news.bbs.nz	2	14413	15	14398	0	0%	23:59:51
18	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 18		2544	338166	14662	302905	20599	4%	667:11:01

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	7.4 MB	73.5 KB	3.6 MB	11.1 MB	66%	6.7 KB
2	newsfeeds.xmission.com	6.8 MB	0.0 KB	545.5 KB	7.4 MB	92%	3.3 KB
3	feeder.eternal-september.org	6.6 MB	3.4 KB	12.8 MB	19.4 MB	34%	7.9 KB
4	news.freedyn.net	4.6 MB	22.6 KB	5.1 MB	9.7 MB	47%	3.9 KB
5	csiph-out.news.xs4all.nl	3.6 MB	0.0 KB	5.8 MB	9.5 MB	38%	3.2 KB
6	news-out.aioe.org	3.5 MB	0.0 KB	62.7 KB	3.6 MB	98%	2.5 KB
7	csiph-out.news.weretis.net	2.8 MB	10.1 KB	5.2 MB	8.0 MB	34%	4.1 KB
8	csiph-out.feed.uzoreto.com	2.2 MB	3.3 KB	14.4 MB	16.7 MB	13%	5.4 KB
9	newsfeed.fu-berlin.de	1.9 MB	0.0 KB	29.7 MB	31.5 MB	5%	2.4 KB
10	news-out.mixmin.net	1.8 MB	6.7 KB	634.8 KB	2.5 MB	74%	2.3 KB
11	newsfeed.neva.ru	1.6 MB	10.0 KB	2.0 MB	3.6 MB	44%	3.0 KB
12	news-out.neodome.net	771.8 KB	0.0 KB	1.5 MB	2.3 MB	33%	7.1 KB
13	csiph-out.news.tnetconsulting.net	326.8 KB	0.0 KB	496.2 KB	823.0 KB	39%	9.1 KB
14	news.fcku.it	269.7 KB	0.0 KB	55.0 KB	324.7 KB	83%	1.8 KB
15	news.swapon.de	181.8 KB	0.0 KB	0.0 KB	181.8 KB	100%	2.3 KB
16	nntp.pasdenom.info	180.5 KB	0.0 KB	0.0 KB	180.5 KB	100%	2.4 KB
17	news.bbs.nz	26.1 KB	0.0 KB	0.0 KB	26.1 KB	100%	1.7 KB
18	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		44.5 MB	129.7 KB	82.0 MB	126.7 MB	35%	3.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 19 04:15:00 - 04:59:59	187	1.3%	0.07	521.4 KB	1.1%	0.19
Feb 19 05:00:00 - 05:59:59	370	2.5%	0.10	2.4 MB	5.2%	0.67
Feb 19 06:00:00 - 06:59:59	316	2.1%	0.09	1.2 MB	2.6%	0.33
Feb 19 07:00:00 - 07:59:59	469	3.1%	0.13	1.4 MB	3.0%	0.39
Feb 19 08:00:00 - 08:59:59	530	3.6%	0.15	1.4 MB	3.0%	0.39
Feb 19 09:00:00 - 09:59:59	679	4.6%	0.19	1.6 MB	3.6%	0.46
Feb 19 10:00:00 - 10:59:59	755	5.1%	0.21	2.3 MB	5.0%	0.64
Feb 19 11:00:00 - 11:59:59	856	5.7%	0.24	2.2 MB	4.9%	0.64
Feb 19 12:00:00 - 12:59:59	725	4.9%	0.20	1.8 MB	4.0%	0.52
Feb 19 13:00:00 - 13:59:59	719	4.8%	0.20	1.9 MB	4.1%	0.53
Feb 19 14:00:00 - 14:59:59	943	6.3%	0.26	3.1 MB	6.8%	0.87
Feb 19 15:00:00 - 15:59:59	897	6.0%	0.25	3.6 MB	7.9%	1.01
Feb 19 16:00:00 - 16:59:59	844	5.7%	0.23	2.3 MB	5.1%	0.66
Feb 19 17:00:00 - 17:59:59	893	6.0%	0.25	2.6 MB	5.8%	0.75
Feb 19 18:00:00 - 18:59:59	824	5.5%	0.23	2.5 MB	5.6%	0.72
Feb 19 19:00:00 - 19:59:59	819	5.5%	0.23	2.5 MB	5.4%	0.70
Feb 19 20:00:00 - 20:59:59	751	5.0%	0.21	2.1 MB	4.7%	0.60
Feb 19 21:00:00 - 21:59:59	643	4.3%	0.18	1.8 MB	3.9%	0.50
Feb 19 22:00:00 - 22:59:59	602	4.0%	0.17	1.9 MB	4.1%	0.53
Feb 19 23:00:00 - 23:59:59	548	3.7%	0.15	1.7 MB	3.7%	0.48
Feb 20 00:00:00 - 00:59:59	491	3.3%	0.14	1.5 MB	3.3%	0.43
Feb 20 01:00:00 - 01:59:59	372	2.5%	0.10	1.1 MB	2.3%	0.30
Feb 20 02:00:00 - 02:59:59	286	1.9%	0.08	802.9 KB	1.7%	0.22
Feb 20 03:00:00 - 03:59:59	330	2.2%	0.09	1.2 MB	2.6%	0.33
Feb 20 04:00:00 - 04:15:00	63	0.4%	0.07	214.2 KB	0.5%	0.24
TOTAL: 24:00:00	14912	100.0%	0.17	45.3 MB	100.0%	0.54

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.fu-berlin.de	12932	8	0	0	12924
2	csiph-out.feed.uzoreto.com	2669	18	1	1	2649
3	csiph-out.news.xs4all.nl	1485	27	0	0	1458
4	csiph-out.news.weretis.net	1053	52	3	0	998
5	news.freedyn.net	764	20	4	0	740
6	newsfeed.neva.ru	607	49	3	0	555
7	feeder.eternal-september.org	306	42	1	0	263
8	csiph-out.feeder.erje.net	293	50	14	0	229
9	news-out.mixmin.net	258	6	2	0	250
10	news.swapon.de	37	0	0	0	37
11	news-out.neodome.net	37	0	0	0	37
12	newsfeeds.xmission.com	26	5	0	0	21
13	news-out.aioe.org	25	16	0	0	9
14	csiph-out.news.tnetconsulting.net	9	8	0	0	1
15	news.fcku.it	6	4	0	0	2
TOTAL: 15		20507	305	28	1	20173

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	33
scinet.stats	27
lada.talk	25
alt.bbs.synchronet	22
alt.survival	21
alt.binaries.pictures.purebeauty	21
lada.auto	15
alt.job	14
mozilla.support.seamonkey	12
it-alt.sport.calcio.juventus	11
alt.politics	10
at.jobs	10
cakewalk.audio	9
alt.philosophy	7
alt.windows7.general	6
mozilla.support.thunderbird	5
oesterreich.tratsch	4
alt.politics.usa	4
alt.2600	3
att.jobs	3
TOTAL: 58	305

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
uk.legal.moderated	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
Binary: misplaced binary	12515
EMP (phn path)	4685
EMP (md5)	1809
Too many newsgroups	153
EMP (phl)	127
EMP (phn nph)	46
0	18
Scoring filter	13
HTML Multipart	9
Too many newsgroups (low_xpost)	3
Too many newsgroups (meow)	2
TOTAL: 11	19380

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	feeder.eternal-september.org	125
	news.freedyn.net	67
	csiph-out.feeder.erje.net	59
	csiph-out.feed.uzoreto.com	52
	news-out.neodome.net	24
	csiph-out.news.weretis.net	20
	csiph-out.news.xs4all.nl	19
	newsfeeds.xmission.com	10
	csiph-out.news.tnetconsulting.net	1
	news.fcku.it	1
	TOTAL: 11	379
Including strange strings
	newsfeed.fu-berlin.de	304
	news.swapon.de	37
	csiph-out.news.weretis.net	30
	csiph-out.news.xs4all.nl	17
	newsfeeds.xmission.com	11
	newsfeed.neva.ru	6
	csiph-out.feeder.erje.net	4
	news-out.aioe.org	3
	csiph-out.feed.uzoreto.com	2
	TOTAL: 9	414
No colon-space in header
	csiph-out.feed.uzoreto.com	1
	TOTAL: 1	1
TOTAL: 4		839

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	fu-berlin.de	13813	529	13284	0	0	3%	23:56:55
2	news.bbs.nz	27823	212	14068	22	0	0%	23:51:06
3	newsfeed.xs4all.nl	14296	138	13094	5	105	0%	23:54:18
4	tnet	15331	79	14232	3	0	0%	23:50:05
5	usenet.pasdenom.info	14645	58	14203	17	10	0%	23:59:52
6	xmission	13922	57	11835	4	0	0%	23:56:54
7	neva.ru	13421	37	13141	86	0	0%	23:50:05
8	news.freedyn.net	12915	12	12688	14	0	0%	23:53:04
9	news.mixmin.net	13340	11	13077	0	0	0%	23:50:05
10	aioe	12609	10	12202	1	0	0%	23:50:05
11	neodome.net	14840	4	14509	0	0	0%	23:50:04
12	erje	13720	3	13546	41	4	0%	23:55:24
13	news.uzoreto.com	14063	2	13822	18	0	0%	23:58:00
14	weretis.net	13525	1	13216	1	0	0%	23:57:59
15	xanadu-bbs	0	0	0	0	14877	0%	23:50:11
16	eternal-september	0	0	0	0	11983	0%	23:50:05
17	news.fcku.it	1162	0	1159	0	0	0%	21:18:02
18	news.swapon.de	1209	0	1203	0	0	0%	23:29:34
19	news.samoylyk.net	0	0	0	0	14877	0%	23:50:06
TOTAL: 19		210634	1153	189279	212	41856	0%	450:51:54

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	fu-berlin.de	5.8 MB	0.0 KB	5.8 MB	0.1 KB/s	11.2 KB	23:56:55
2	news.bbs.nz	530.8 KB	64.8 KB	595.7 KB	0.0 KB/s	2.5 KB	23:51:06
3	newsfeed.xs4all.nl	434.9 KB	86.0 KB	520.9 KB	0.0 KB/s	3.6 KB	23:54:18
4	usenet.pasdenom.info	191.5 KB	41.6 KB	233.1 KB	0.0 KB/s	3.1 KB	23:59:52
5	tnet	182.6 KB	8.3 KB	190.9 KB	0.0 KB/s	2.3 KB	23:50:05
6	xmission	149.2 KB	4.8 KB	154.0 KB	0.0 KB/s	2.5 KB	23:56:54
7	news.freedyn.net	121.9 KB	173.0 KB	294.9 KB	0.0 KB/s	11.3 KB	23:53:04
8	neva.ru	103.6 KB	220.1 KB	323.8 KB	0.0 KB/s	2.6 KB	23:50:05
9	news.mixmin.net	57.7 KB	0.0 KB	57.7 KB	0.0 KB/s	5.2 KB	23:50:05
10	erje	25.0 KB	143.9 KB	169.0 KB	0.0 KB/s	3.8 KB	23:55:24
11	aioe	19.8 KB	4.7 KB	24.5 KB	0.0 KB/s	2.2 KB	23:50:05
12	neodome.net	6.0 KB	0.0 KB	6.0 KB	0.0 KB/s	1.5 KB	23:50:04
13	news.uzoreto.com	4.9 KB	75.9 KB	80.9 KB	0.0 KB/s	4.0 KB	23:58:00
14	weretis.net	1.9 KB	1.8 KB	3.8 KB	0.0 KB/s	1.9 KB	23:57:59
15	xanadu-bbs	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:11
16	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:05
17	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:18:02
18	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:29:34
19	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:06
TOTAL: 19		7.6 MB	825.0 KB	8.4 MB	0.0 KB/s	6.3 KB	450:51:54

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	91.187.33.87	218	2	5.4 KB	2	21:10:08
2	172.83.100.250	17	2	3.9 KB	2	05:29:26
3	ip-78-102-198-192.net.upcbroadband.cz	53	2	3.2 KB	2	08:15:53
4	a131033.upc-a.chello.nl	39	1	1.7 KB	1	06:19:37
TOTAL: 4		327	7	14.2 KB	7	42:26:58

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	235	4	9.3 KB	4	26:39:35
2	*.net.upcbroadband.cz	53	2	3.2 KB	2	08:15:53
3	*.upc-a.chello.nl	39	1	1.7 KB	1	06:19:37
4	*.163.24.10.ip.us.as30241.net	2	0	0.0 KB	0	01:00:18
5	*.range31-49.btcentralplus.com	3	0	0.0 KB	0	00:11:33
TOTAL: 5		332	7	14.2 KB	7	42:26:58

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
91.187.33.87	13.633	3.372	21:10:08
ip-78-102-198-192.net.upcbroadband.cz	3.299	0.823	08:15:53
a131033.upc-a.chello.nl	2.450	0.586	06:19:37
172.83.100.250	1.230	0.312	05:29:26
192.163.24.10.ip.us.as30241.net	0.209	0.024	01:00:18
host31-49-229-214.range31-49.btcentralplus.com	0.186	0.061	00:11:33
TOTAL: 6	21.007	5.178	42:26:58

Curious NNRP server explorers [Top 20]:

System	Conn
host31-49-229-214.range31-49.btcentralplus.com	3
192.163.24.10.ip.us.as30241.net	2
TOTAL: 2	5

NNRP client timeouts [Top 20]:

System	Conn	Peer
172.83.100.250	8	0
192.163.24.10.ip.us.as30241.net	2	0
TOTAL: 2	10	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Feb 19 04:15:00 -- Feb 20 04:15:00