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

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Jan 1 16:02:09 csiph nnrpd[31441]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 1 16:02:09 csiph nnrpd[31441]: 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 1 20:51:38 csiph nnrpd[46492]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 1 20:51:38 csiph nnrpd[46492]: 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	17133	47.3%	4.4 MB	62.6%
innd	13326	36.8%	1.8 MB	25.3%
innfeed	3627	10.0%	639.3 KB	9.0%
nnrpd	2127	5.9%	226.5 KB	3.2%
nocem	4	0.0%	0.4 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	36218	100.0%	7.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	258731	50.9%
Negative hits	232276	45.7%
Do not exist	16591	3.3%
Cache misses	271	0.1%
TOTAL: 4	507869	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.629	0.0%	40	0.000	15.725	39.800
article cleanup	00:00:00.084	0.0%	16279	0.000	0.005	0.057
article logging	00:00:00.637	0.0%	16740	0.000	0.038	2.887
article parse	00:00:01.355	0.0%	55904	0.000	0.024	0.639
article write	00:00:45.576	0.1%	13834	0.202	3.294	121.379
artlog/artcncl	00:00:00.000	0.0%	40	0.000	0.000	0.000
artlog/artparse	00:00:00.017	0.0%	344	0.000	0.049	1.333
data move	00:00:00.166	0.0%	480586	0.000	0.000	0.003
hisgrep/artcncl	00:00:00.055	0.0%	37	0.000	1.486	15.000
hishave/artcncl	00:00:00.010	0.0%	40	0.000	0.250	1.000
hishave/artparse	00:00:00.000	0.0%	341	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:20.240	0.0%	508803	0.000	0.040	0.226
history sync	00:00:01.042	0.0%	371	0.000	2.809	45.667
history write	00:00:26.228	0.0%	16282	0.196	1.611	16.405
hiswrite/artcncl	00:00:00.003	0.0%	3	1.000	1.000	1.000
hiswrite/artparse	00:00:00.800	0.0%	341	0.000	2.346	58.400
idle	23:56:10.664	99.7%	478784	91.403	179.978	824.975
nntp read	00:00:01.860	0.0%	483195	0.000	0.004	0.009
overview write	00:02:03.735	0.1%	13834	0.139	8.944	805.107
perl filter	00:00:24.062	0.0%	16279	1.106	1.478	3.429
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.225	0.0%	27672	0.000	0.008	0.059
TOTAL: 24:00:55.588	24:00:17.388	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.034	0.0%	13917	0.000	0.002	0.028
article prepare	00:00:00.002	0.0%	13276	0.000	0.000	0.017
article read	00:00:00.277	0.0%	1596	0.000	0.174	30.500
backlog stats	00:00:00.186	0.0%	304571	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.103	0.0%	238724	0.000	0.005	0.013
data write	00:00:01.424	0.0%	227124	0.001	0.006	0.014
idle	23:55:13.112	100.0%	304571	146.937	282.736	951.150
status file	00:00:00.340	0.0%	314	0.000	1.083	23.750
TOTAL: 23:55:24.807	23:55:16.478	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:55.405	0.0%	48853	1.134	1.134	1.134
idle	78:56:24.225	99.3%	106832	92.000	2660.104	600067.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:03.490	0.0%	372703	0.000	0.009	0.167
readart	00:17:37.906	0.4%	105432	0.000	10.034	43.000
TOTAL: 79:31:25.087	79:15:01.026	99.7%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.xs4all.nl	0	6671	3561	1739	1371	53%	69:30:12
2	feeder.eternal-september.org	17	4141	2012	2074	55	48%	26:44:57
3	news-out.mixmin.net	2	14797	1700	12852	245	11%	24:08:57
4	csiph-out.news.weretis.net	6	21129	1328	19632	169	6%	38:58:15
5	newsfeeds.xmission.com	1	12839	1195	11621	23	9%	24:07:41
6	newsfeed.fu-berlin.de	20	12360	840	11368	152	6%	24:48:21
7	newsfeed.neva.ru	140	9938	566	9193	179	5%	71:34:25
8	csiph-out.feeder.erje.net	1440	67342	566	66529	247	0%	118:48:03
9	news-out.aioe.org	1	13055	542	12487	26	4%	23:59:57
10	csiph-out.feed.uzoreto.com	864	21758	427	21093	238	1%	71:17:05
11	news-out.neodome.net	1	14891	300	14581	10	2%	23:59:54
12	news.freedyn.net	2	15909	152	15659	98	0%	23:59:45
13	news.fcku.it	14	1043	125	918	0	11%	21:23:42
14	nntp.pasdenom.info	2	9169	85	9078	6	0%	23:59:29
15	news.swapon.de	7	1439	56	1383	0	3%	24:31:34
16	csiph-out.news.tnetconsulting.net	2	12871	30	12839	2	0%	23:59:40
17	news.samoylyk.net	2	13239	10	13227	2	0%	23:59:35
18	news.bbs.nz	2	12382	7	12375	0	0%	23:59:29
19	localhost	28	2	2	0	0	100%	00:00:01
20	xanadu-bbs.net	32	475	0	474	1	0%	20:20:12
TOTAL: 20		2583	265450	13504	249122	2824	5%	704:11:14

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.xs4all.nl	9.9 MB	13.2 MB	23.1 MB	42%	4.8 KB
2	feeder.eternal-september.org	5.6 MB	1.0 MB	6.6 MB	84%	3.3 KB
3	csiph-out.news.weretis.net	4.4 MB	1.9 MB	6.3 MB	69%	4.3 KB
4	news-out.mixmin.net	4.0 MB	865.6 KB	4.9 MB	82%	2.6 KB
5	csiph-out.feeder.erje.net	4.0 MB	2.8 MB	6.8 MB	59%	8.5 KB
6	newsfeeds.xmission.com	3.9 MB	2.1 MB	6.0 MB	65%	5.1 KB
7	newsfeed.fu-berlin.de	2.1 MB	559.3 KB	2.6 MB	79%	2.7 KB
8	news-out.aioe.org	1.8 MB	67.4 KB	1.9 MB	96%	3.4 KB
9	newsfeed.neva.ru	1.7 MB	641.1 KB	2.4 MB	73%	3.3 KB
10	csiph-out.feed.uzoreto.com	1.5 MB	7.7 MB	9.2 MB	16%	14.2 KB
11	news-out.neodome.net	1.0 MB	59.7 KB	1.1 MB	94%	3.5 KB
12	news.freedyn.net	662.5 KB	2.9 MB	3.6 MB	18%	14.7 KB
13	nntp.pasdenom.info	216.6 KB	7.3 KB	223.9 KB	96%	2.5 KB
14	news.fcku.it	180.2 KB	0.0 KB	180.2 KB	100%	1.4 KB
15	news.swapon.de	156.2 KB	0.0 KB	156.2 KB	100%	2.8 KB
16	csiph-out.news.tnetconsulting.net	124.5 KB	3.5 KB	128.0 KB	97%	4.0 KB
17	news.samoylyk.net	53.7 KB	83.7 KB	137.4 KB	39%	11.5 KB
18	news.bbs.nz	18.1 KB	0.0 KB	18.1 KB	100%	2.6 KB
19	localhost	3.7 KB	0.0 KB	3.7 KB	100%	1.8 KB
20	xanadu-bbs.net	0.0 KB	2.1 KB	2.1 KB	0%	2.1 KB
TOTAL: 20		41.5 MB	33.8 MB	75.2 MB	55%	4.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 1 04:15:00 - 04:59:59	154	1.1%	0.06	516.8 KB	1.2%	0.19
Jan 1 05:00:00 - 05:59:59	266	1.9%	0.07	1.0 MB	2.3%	0.28
Jan 1 06:00:00 - 06:59:59	267	1.9%	0.07	1.1 MB	2.6%	0.31
Jan 1 07:00:00 - 07:59:59	325	2.3%	0.09	1.3 MB	3.1%	0.37
Jan 1 08:00:00 - 08:59:59	497	3.6%	0.14	1.9 MB	4.4%	0.53
Jan 1 09:00:00 - 09:59:59	633	4.6%	0.18	2.6 MB	6.0%	0.73
Jan 1 10:00:00 - 10:59:59	654	4.7%	0.18	1.6 MB	3.8%	0.47
Jan 1 11:00:00 - 11:59:59	666	4.8%	0.18	1.8 MB	4.3%	0.52
Jan 1 12:00:00 - 12:59:59	696	5.0%	0.19	2.0 MB	4.8%	0.58
Jan 1 13:00:00 - 13:59:59	820	5.9%	0.23	2.7 MB	6.3%	0.77
Jan 1 14:00:00 - 14:59:59	830	6.0%	0.23	2.7 MB	6.4%	0.78
Jan 1 15:00:00 - 15:59:59	903	6.5%	0.25	2.6 MB	6.0%	0.73
Jan 1 16:00:00 - 16:59:59	949	6.9%	0.26	2.6 MB	6.1%	0.74
Jan 1 17:00:00 - 17:59:59	815	5.9%	0.23	2.2 MB	5.0%	0.61
Jan 1 18:00:00 - 18:59:59	814	5.9%	0.23	2.2 MB	5.1%	0.62
Jan 1 19:00:00 - 19:59:59	750	5.4%	0.21	2.1 MB	4.9%	0.60
Jan 1 20:00:00 - 20:59:59	588	4.2%	0.16	1.7 MB	4.0%	0.49
Jan 1 21:00:00 - 21:59:59	576	4.2%	0.16	1.8 MB	4.2%	0.50
Jan 1 22:00:00 - 22:59:59	501	3.6%	0.14	1.3 MB	3.2%	0.38
Jan 1 23:00:00 - 23:59:59	536	3.9%	0.15	1.7 MB	4.0%	0.48
Jan 2 00:00:00 - 00:59:59	531	3.8%	0.15	1.8 MB	4.2%	0.51
Jan 2 01:00:00 - 01:59:59	434	3.1%	0.12	1.5 MB	3.5%	0.43
Jan 2 02:00:00 - 02:59:59	261	1.9%	0.07	785.2 KB	1.8%	0.22
Jan 2 03:00:00 - 03:59:59	320	2.3%	0.09	988.8 KB	2.3%	0.27
Jan 2 04:00:00 - 04:15:00	58	0.4%	0.06	178.6 KB	0.4%	0.20
TOTAL: 24:00:00	13844	100.0%	0.16	42.6 MB	100.0%	0.51

Sites sending bad articles:

Server		Total	Group	Unapp	Other
1	csiph-out.news.xs4all.nl	1460	96	0	1364
2	newsfeed.fu-berlin.de	507	13	0	494
3	news-out.mixmin.net	252	13	0	239
4	csiph-out.feed.uzoreto.com	241	12	0	229
5	csiph-out.news.weretis.net	210	62	0	148
6	newsfeed.neva.ru	182	43	0	139
7	csiph-out.feeder.erje.net	130	48	1	81
8	news.freedyn.net	98	2	0	96
9	feeder.eternal-september.org	59	19	0	40
10	newsfeeds.xmission.com	45	0	0	45
11	news-out.aioe.org	26	24	0	2
12	news.swapon.de	18	0	0	18
13	news-out.neodome.net	11	0	0	11
14	nntp.pasdenom.info	6	0	0	6
15	news.samoylyk.net	2	0	0	2
16	xanadu-bbs.net	1	0	0	1
17	csiph-out.news.tnetconsulting.net	1	0	0	1
TOTAL: 17		3249	332	1	2916

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	58
scinet.stats	31
alt.binaries.pictures.purebeauty	30
lada.talk	30
it-alt.test	10
at.jobs	10
alt.philosophy	10
alt.politics	9
alt.job	7
spline.eisfair	7
mozilla.support.seamonkey	7
att.jobs	7
trollnet.cinema	7
alt.8178287618	6
alt.survival	5
alt.politics.usa	5
mozilla.support.thunderbird	5
alt.bbs.synchronet	5
oesterreich.tratsch	4
alt.christian.bible	4
TOTAL: 73	332

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)	1438
EMP (phn path)	322
EMP (phl)	121
EMP (phn nph)	120
Too many newsgroups	44
0	40
Scoring filter	9
HTML Multipart	6
Binary: misplaced binary	6
Binary Payload	3
U2 violation - invalid distribution	1
TOTAL: 11	2110

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.news.xs4all.nl	130
	csiph-out.feed.uzoreto.com	70
	csiph-out.feeder.erje.net	51
	news.freedyn.net	27
	newsfeeds.xmission.com	23
	csiph-out.news.weretis.net	18
	feeder.eternal-september.org	9
	news-out.mixmin.net	8
	newsfeed.fu-berlin.de	2
	news-out.neodome.net	1
	TOTAL: 12	341
Including strange strings
	newsfeed.fu-berlin.de	364
	csiph-out.news.xs4all.nl	33
	newsfeeds.xmission.com	22
	news.swapon.de	18
	csiph-out.news.weretis.net	11
	csiph-out.feed.uzoreto.com	4
	news-out.mixmin.net	4
	csiph-out.feeder.erje.net	2
	feeder.eternal-september.org	2
	newsfeed.neva.ru	2
	TOTAL: 10	462
No colon-space in header
	csiph-out.feed.uzoreto.com	2
	csiph-out.news.xs4all.nl	1
	TOTAL: 2	3
TOTAL: 4		851

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	16390	817	13023	3	8	4%	23:59:52
2	news.bbs.nz	25928	604	12799	22	0	2%	23:59:53
3	fu-berlin.de	12770	368	12402	0	0	2%	23:59:41
4	news.samoylyk.net	14169	362	13467	5	0	2%	23:59:53
5	tnet	15460	322	13106	2	0	2%	23:59:53
6	xmission	13919	256	11880	10	0	1%	23:59:52
7	newsfeed.xs4all.nl	10543	43	9944	0	75	0%	23:59:53
8	erje	13139	25	12890	144	0	0%	23:59:53
9	neva.ru	12752	13	12448	168	0	0%	23:59:53
10	aioe	11343	13	10956	1	0	0%	23:59:44
11	news.freedyn.net	12290	7	12006	31	0	0%	23:59:53
12	usenet.pasdenom.info	13610	7	13257	80	0	0%	23:59:53
13	news.mixmin.net	11679	4	11405	0	0	0%	23:59:53
14	news.uzoreto.com	13204	3	12936	5	0	0%	23:59:53
15	neodome.net	13771	3	13446	0	0	0%	23:59:53
16	news.swapon.de	1369	2	1365	0	0	0%	23:30:32
17	weretis.net	12158	2	11847	1	0	0%	23:59:29
18	news.fcku.it	892	0	891	0	0	0%	20:45:03
19	eternal-september	0	0	0	0	11336	0%	23:59:53
TOTAL: 19		225386	2851	200068	472	11419	1%	452:12:49

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	4.7 MB	15.8 KB	4.8 MB	0.1 KB/s	5.9 KB	23:59:52
2	news.samoylyk.net	3.4 MB	6.2 KB	3.4 MB	0.0 KB/s	9.6 KB	23:59:53
3	fu-berlin.de	3.4 MB	0.0 KB	3.4 MB	0.0 KB/s	9.6 KB	23:59:41
4	news.bbs.nz	1.6 MB	77.2 KB	1.7 MB	0.0 KB/s	2.7 KB	23:59:53
5	tnet	783.4 KB	2.9 KB	786.4 KB	0.0 KB/s	2.4 KB	23:59:53
6	xmission	566.1 KB	24.3 KB	590.4 KB	0.0 KB/s	2.2 KB	23:59:52
7	newsfeed.xs4all.nl	97.8 KB	0.0 KB	97.8 KB	0.0 KB/s	2.3 KB	23:59:53
8	erje	62.8 KB	528.8 KB	591.6 KB	0.0 KB/s	3.5 KB	23:59:53
9	neva.ru	31.7 KB	370.2 KB	402.0 KB	0.0 KB/s	2.2 KB	23:59:53
10	news.freedyn.net	26.3 KB	424.3 KB	450.6 KB	0.0 KB/s	11.9 KB	23:59:53
11	aioe	24.9 KB	0.7 KB	25.6 KB	0.0 KB/s	1.8 KB	23:59:44
12	usenet.pasdenom.info	15.4 KB	177.3 KB	192.7 KB	0.0 KB/s	2.2 KB	23:59:53
13	news.mixmin.net	6.7 KB	0.0 KB	6.7 KB	0.0 KB/s	1.7 KB	23:59:53
14	neodome.net	5.7 KB	0.0 KB	5.7 KB	0.0 KB/s	1.9 KB	23:59:53
15	news.uzoreto.com	5.3 KB	8.8 KB	14.1 KB	0.0 KB/s	1.8 KB	23:59:53
16	news.swapon.de	3.7 KB	0.0 KB	3.7 KB	0.0 KB/s	1.8 KB	23:30:32
17	weretis.net	3.7 KB	1.2 KB	4.9 KB	0.0 KB/s	1.6 KB	23:59:29
18	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:45:03
19	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:53
TOTAL: 19		14.8 MB	1.6 MB	16.4 MB	0.0 KB/s	5.1 KB	452:12:49

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	59	7	13.7 KB	4	0	0	18:37:59
2	91.187.33.87	295	5	9.0 KB	5	2	2	47:12:33
TOTAL: 2		354	56493	137.6 MB	10	2	2	79:31:22

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	295	56486	137.6 MB	6	2	2	60:21:24
2	*.upc-a.chello.nl	59	7	13.7 KB	4	0	0	18:37:59
3	*.hsd1.wa.comcast.net	1	0	0.0 KB	0	0	0	00:30:00
4	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:00
5	*.range31-50.btcentralplus.com	2	0	0.0 KB	0	0	0	00:01:57
TOTAL: 5		358	56493	137.6 MB	10	2	2	79:31:22

NNRP auth users [Top 20]:

User	Conn
slavotinek	2
TOTAL: 1	2

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	18.705	4.377	0.005	47:12:33
a131033.upc-a.chello.nl	3.956	0.822	0.000	18:37:59
212.90.61.180	8.000	22.470	0.000	13:08:50
c-73-140-175-55.hsd1.wa.comcast.net	0.066	0.008	0.000	00:30:00
host31-50-220-99.range31-50.btcentralplus.com	0.144	0.016	0.000	00:01:57
scan-141.security.ipip.net	0.053	0.023	0.000	00:00:00
TOTAL: 6	30.924	27.716	0.005	79:31:22

Curious NNRP server explorers [Top 20]:

System	Conn
host31-50-220-99.range31-50.btcentralplus.com	2
c-73-140-175-55.hsd1.wa.comcast.net	1
scan-141.security.ipip.net	1
TOTAL: 3	4

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
TOTAL: 1	4

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	1
Connection: close	1
User-Agent: HTTP Banner Detection (https...	1
Host: 69.160.37.58:119	1
TOTAL: 4	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
c-73-140-175-55.hsd1.wa.comcast.net	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	rec	49042	100.0%
2	comp	12	0.0%
TOTAL: 2		49054	100.0%

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

Newsgroup		Count
1	rec.food.cooking	49042
2	comp.sys.ibm.ps2.hardware	12
TOTAL: 2		49054

Daily Usenet report for csiph.com

Jan 1 04:15:00 -- Jan 2 04:15:00