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

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Jan 5 19:56:44 csiph nnrpd[89646]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 5 19:56:44 csiph nnrpd[89646]: 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	19036	45.8%	5.0 MB	62.0%
innd	18222	43.8%	2.4 MB	29.4%
innfeed	3502	8.4%	610.9 KB	7.5%
nnrpd	809	1.9%	88.7 KB	1.1%
nocem	16	0.0%	1.7 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	41586	100.0%	8.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	293748	57.5%
Negative hits	198681	38.9%
Do not exist	18469	3.6%
Cache misses	248	0.0%
TOTAL: 4	511146	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.163	0.0%	15	0.000	10.867	24.333
article cleanup	00:00:00.113	0.0%	17989	0.000	0.006	0.039
article logging	00:00:00.237	0.0%	18502	0.000	0.013	0.139
article parse	00:00:00.879	0.0%	59460	0.000	0.015	0.054
article write	00:00:08.854	0.0%	15363	0.130	0.576	17.242
artlog/artcncl	00:00:00.001	0.0%	15	0.000	0.067	0.167
artlog/artparse	00:00:00.007	0.0%	528	0.000	0.013	0.500
data move	00:00:00.163	0.0%	487143	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.005	0.0%	14	0.000	0.357	1.000
hishave/artcncl	00:00:00.001	0.0%	15	0.000	0.067	0.167
hishave/artparse	00:00:00.001	0.0%	527	0.000	0.002	0.045
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:04.085	0.0%	511852	0.001	0.008	0.068
history sync	00:00:00.753	0.0%	342	0.000	2.202	14.500
history write	00:00:09.205	0.0%	17989	0.024	0.512	2.979
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.320	0.0%	527	0.000	0.607	2.000
idle	23:58:55.067	99.9%	495517	100.685	174.232	661.800
nntp read	00:00:01.803	0.0%	495391	0.001	0.004	0.007
overview write	00:00:22.589	0.0%	15363	0.126	1.470	22.467
perl filter	00:00:24.164	0.0%	17985	1.024	1.344	2.038
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.274	0.0%	30742	0.000	0.009	0.030
TOTAL: 24:00:41.657	24:00:08.684	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.026	0.0%	15473	0.000	0.002	0.021
article prepare	00:00:00.000	0.0%	10688	0.000	0.000	0.000
article read	00:00:00.202	0.0%	1102	0.000	0.183	15.333
backlog stats	00:00:00.192	0.0%	349490	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.228	0.0%	270185	0.001	0.005	0.009
data write	00:00:01.552	0.0%	256021	0.002	0.006	0.014
idle	23:54:49.498	100.0%	349490	145.844	246.329	846.592
status file	00:00:00.187	0.0%	277	0.000	0.675	2.000
TOTAL: 23:55:00.712	23:54:52.885	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.016	0.0%	112	0.103	0.143	0.185
idle	31:11:22.862	100.0%	769	0.000	146011.524	600164.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.851	0.0%	1784	0.000	0.477	23.333
readart	00:00:00.662	0.0%	82	0.000	8.073	42.000
TOTAL: 31:11:59.800	31:11:24.391	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news-out.aioe.org	3	14646	3720	10883	43	25%	24:09:02
2	newsfeeds.xmission.com	2	15368	3614	11715	39	23%	25:55:06
3	feeder.eternal-september.org	4	16749	1852	14246	651	11%	24:14:08
4	csiph-out.feeder.erje.net	1440	74959	1299	73184	476	1%	118:41:09
5	newsfeed.fu-berlin.de	4	17072	873	16088	111	5%	28:32:05
6	news-out.mixmin.net	2	15865	823	14823	219	5%	23:59:50
7	csiph-out.news.xs4all.nl	861	16941	752	15386	803	4%	71:27:33
8	csiph-out.news.weretis.net	2	29964	720	29133	111	2%	47:59:48
9	csiph-out.news.tnetconsulting.net	1	26247	579	25655	13	2%	40:09:28
10	csiph-out.feed.uzoreto.com	864	23784	476	22968	340	2%	71:12:08
11	newsfeed.neva.ru	142	16479	428	15755	296	2%	71:45:07
12	news-out.neodome.net	1	15957	151	15797	9	0%	23:59:53
13	nntp.pasdenom.info	1	9502	89	9382	31	0%	23:59:51
14	news.fcku.it	18	1043	78	964	1	7%	22:02:07
15	news.swapon.de	4	1572	27	1544	1	1%	25:35:23
16	localhost	39	13	13	0	0	100%	00:00:05
17	news.bbs.nz	2	14264	8	14256	0	0%	23:59:47
18	news.samoylyk.net	1	14654	4	14650	0	0%	23:59:45
19	xanadu-bbs.net	115	593	0	593	0	0%	109:49:55
TOTAL: 19		3506	325672	15506	307022	3144	4%	801:32:10

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	10.6 MB	1.9 MB	12.5 MB	85%	3.5 KB
2	news-out.aioe.org	9.7 MB	144.7 KB	9.8 MB	98%	2.7 KB
3	csiph-out.feeder.erje.net	8.9 MB	11.3 MB	20.2 MB	44%	11.7 KB
4	feeder.eternal-september.org	5.3 MB	2.1 MB	7.3 MB	71%	3.0 KB
5	news-out.mixmin.net	2.5 MB	628.7 KB	3.1 MB	80%	3.0 KB
6	csiph-out.news.xs4all.nl	2.3 MB	14.1 MB	16.4 MB	14%	10.8 KB
7	csiph-out.news.weretis.net	2.2 MB	1.4 MB	3.6 MB	61%	4.4 KB
8	newsfeed.fu-berlin.de	2.1 MB	256.3 KB	2.4 MB	89%	2.5 KB
9	csiph-out.news.tnetconsulting.net	1.8 MB	151.1 KB	1.9 MB	92%	3.4 KB
10	csiph-out.feed.uzoreto.com	1.7 MB	8.4 MB	10.1 MB	16%	12.6 KB
11	newsfeed.neva.ru	1.2 MB	1.2 MB	2.3 MB	50%	3.3 KB
12	news-out.neodome.net	346.2 KB	17.6 KB	363.9 KB	95%	2.3 KB
13	nntp.pasdenom.info	181.6 KB	45.0 KB	226.7 KB	80%	1.9 KB
14	news.fcku.it	137.8 KB	1.3 KB	139.1 KB	99%	1.8 KB
15	news.swapon.de	74.9 KB	2.9 KB	77.9 KB	96%	2.8 KB
16	localhost	31.0 KB	0.0 KB	31.0 KB	100%	2.4 KB
17	news.samoylyk.net	24.3 KB	0.0 KB	24.3 KB	100%	6.1 KB
18	news.bbs.nz	11.5 KB	0.0 KB	11.5 KB	100%	1.4 KB
19	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 19		49.1 MB	41.5 MB	90.5 MB	54%	5.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 5 04:15:00 - 04:59:59	233	1.5%	0.09	760.3 KB	1.5%	0.28
Jan 5 05:00:00 - 05:59:59	260	1.7%	0.07	816.0 KB	1.6%	0.23
Jan 5 06:00:00 - 06:59:59	247	1.6%	0.07	775.6 KB	1.6%	0.22
Jan 5 07:00:00 - 07:59:59	401	2.6%	0.11	1.9 MB	3.8%	0.53
Jan 5 08:00:00 - 08:59:59	500	3.3%	0.14	1.7 MB	3.5%	0.49
Jan 5 09:00:00 - 09:59:59	656	4.3%	0.18	2.3 MB	4.8%	0.66
Jan 5 10:00:00 - 10:59:59	784	5.1%	0.22	2.7 MB	5.6%	0.77
Jan 5 11:00:00 - 11:59:59	663	4.3%	0.18	2.8 MB	5.8%	0.80
Jan 5 12:00:00 - 12:59:59	736	4.8%	0.20	2.5 MB	5.0%	0.70
Jan 5 13:00:00 - 13:59:59	692	4.5%	0.19	2.0 MB	4.2%	0.58
Jan 5 14:00:00 - 14:59:59	731	4.8%	0.20	1.9 MB	3.9%	0.54
Jan 5 15:00:00 - 15:59:59	895	5.8%	0.25	3.3 MB	6.8%	0.94
Jan 5 16:00:00 - 16:59:59	754	4.9%	0.21	1.9 MB	3.8%	0.53
Jan 5 17:00:00 - 17:59:59	877	5.7%	0.24	2.4 MB	4.9%	0.67
Jan 5 18:00:00 - 18:59:59	927	6.0%	0.26	2.5 MB	5.1%	0.71
Jan 5 19:00:00 - 19:59:59	932	6.1%	0.26	3.0 MB	6.2%	0.86
Jan 5 20:00:00 - 20:59:59	917	6.0%	0.25	2.7 MB	5.5%	0.75
Jan 5 21:00:00 - 21:59:59	836	5.4%	0.23	2.7 MB	5.5%	0.76
Jan 5 22:00:00 - 22:59:59	784	5.1%	0.22	2.2 MB	4.6%	0.63
Jan 5 23:00:00 - 23:59:59	610	4.0%	0.17	2.1 MB	4.4%	0.60
Jan 6 00:00:00 - 00:59:59	483	3.1%	0.13	1.4 MB	2.9%	0.41
Jan 6 01:00:00 - 01:59:59	489	3.2%	0.14	1.4 MB	2.9%	0.41
Jan 6 02:00:00 - 02:59:59	465	3.0%	0.13	1.3 MB	2.6%	0.36
Jan 6 03:00:00 - 03:59:59	394	2.6%	0.11	1.3 MB	2.8%	0.38
Jan 6 04:00:00 - 04:15:00	93	0.6%	0.10	231.5 KB	0.5%	0.26
TOTAL: 24:00:00	15359	100.0%	0.18	48.5 MB	100.0%	0.58

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	803	17	0	786
2	feeder.eternal-september.org	655	25	0	630
3	csiph-out.feeder.erje.net	479	61	0	418
4	newsfeed.fu-berlin.de	477	22	0	455
5	csiph-out.feed.uzoreto.com	342	3	0	339
6	newsfeed.neva.ru	297	46	0	251
7	news-out.mixmin.net	228	1	0	227
8	csiph-out.news.weretis.net	142	39	0	103
9	news-out.aioe.org	137	29	0	108
10	newsfeeds.xmission.com	40	4	0	36
11	nntp.pasdenom.info	31	0	0	31
12	csiph-out.news.tnetconsulting.net	10	10	0	0
13	news-out.neodome.net	9	3	0	6
14	news.swapon.de	8	0	0	8
15	news.bbs.nz	3	2	1	0
16	news.fcku.it	1	1	0	0
TOTAL: 16		3662	263	1	3398

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.binaries.pictures.purebeauty	24
alt.computer.workshop	20
mozilla.dev.platform	17
alt.survival	16
lada.auto	15
lada.talk	11
mozilla.support.thunderbird	11
alt.binaries.ebooks	10
spline.eisfair	10
alt.windows7.general	9
mozilla.support.seamonkey	8
lada.commerce	8
mozilla.support.firefox	6
alt.politics	5
alt.music.pink-floyd.publius	5
oesterreich.tratsch	5
alt.binaries.ebook	5
it-alt.sport.calcio.juventus	4
alt.eunuchs	4
lada.movies	3
TOTAL: 71	263

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1885
EMP (phn path)	166
EMP (phn nph)	150
0	74
EMP (phl)	49
HTML Multipart	12
Too many newsgroups	11
Scoring filter	7
Binary: misplaced binary	5
MID-Bot	1
TOTAL: 10	2360

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	2
	csiph-out.news.xs4all.nl	1
	TOTAL: 2	3
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	csiph-out.feeder.erje.net	202
	csiph-out.news.xs4all.nl	159
	csiph-out.feed.uzoreto.com	99
	newsfeeds.xmission.com	34
	csiph-out.news.weretis.net	16
	feeder.eternal-september.org	7
	news-out.mixmin.net	5
	TOTAL: 7	522
Including strange strings
	newsfeed.fu-berlin.de	377
	news-out.aioe.org	95
	csiph-out.news.weretis.net	14
	news-out.mixmin.net	9
	news.swapon.de	7
	csiph-out.feed.uzoreto.com	4
	csiph-out.feeder.erje.net	3
	newsfeeds.xmission.com	2
	csiph-out.news.xs4all.nl	1
	TOTAL: 9	512
No colon-space in header
	csiph-out.news.xs4all.nl	1
	TOTAL: 1	1
TOTAL: 5		1083

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	20266	780	14576	3	5	3%	23:59:54
2	news.samoylyk.net	15744	604	14749	0	0	3%	23:59:54
3	fu-berlin.de	14470	519	13951	0	0	3%	23:59:53
4	news.bbs.nz	28850	271	14508	3	0	0%	23:59:42
5	usenet.pasdenom.info	15124	80	14569	50	0	0%	23:59:54
6	neva.ru	14411	55	14152	69	0	0%	23:59:54
7	tnet	15889	32	14759	0	0	0%	23:59:40
8	newsfeed.xs4all.nl	14838	31	14409	0	137	0%	23:59:53
9	neodome.net	15602	25	15133	0	0	0%	23:59:54
10	erje	14936	23	13882	4	0	0%	23:59:51
11	news.mixmin.net	14178	22	13725	0	0	0%	23:59:54
12	xmission	14404	20	11218	1	0	0%	23:59:21
13	weretis.net	14055	15	13889	5	0	0%	23:59:54
14	aioe	10660	15	10321	0	0	0%	23:59:31
15	news.swapon.de	1720	13	1698	0	0	0%	23:57:29
16	eternal-september	13179	13	12751	0	0	0%	23:59:54
17	news.uzoreto.com	14782	9	14482	16	0	0%	23:59:54
18	news.freedyn.net	0	0	0	0	15355	0%	23:58:41
19	news.fcku.it	896	0	893	0	0	0%	22:00:08
TOTAL: 19		254004	2527	223665	151	15497	0%	453:53:15

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	7.2 MB	11.0 KB	7.2 MB	0.1 KB/s	9.4 KB	23:59:54
2	news.samoylyk.net	6.6 MB	0.0 KB	6.6 MB	0.1 KB/s	11.2 KB	23:59:54
3	fu-berlin.de	6.4 MB	0.0 KB	6.4 MB	0.1 KB/s	12.7 KB	23:59:53
4	news.bbs.nz	716.4 KB	12.0 KB	728.3 KB	0.0 KB/s	2.7 KB	23:59:42
5	usenet.pasdenom.info	224.6 KB	152.0 KB	376.6 KB	0.0 KB/s	2.9 KB	23:59:54
6	neva.ru	127.4 KB	173.1 KB	300.6 KB	0.0 KB/s	2.4 KB	23:59:54
7	tnet	98.1 KB	0.0 KB	98.1 KB	0.0 KB/s	3.1 KB	23:59:40
8	newsfeed.xs4all.nl	75.2 KB	0.0 KB	75.2 KB	0.0 KB/s	2.4 KB	23:59:53
9	erje	54.1 KB	15.9 KB	70.0 KB	0.0 KB/s	2.6 KB	23:59:51
10	neodome.net	51.4 KB	0.0 KB	51.4 KB	0.0 KB/s	2.1 KB	23:59:54
11	weretis.net	44.4 KB	11.7 KB	56.1 KB	0.0 KB/s	2.8 KB	23:59:54
12	news.mixmin.net	44.4 KB	0.0 KB	44.4 KB	0.0 KB/s	2.0 KB	23:59:54
13	xmission	43.8 KB	1.1 KB	44.9 KB	0.0 KB/s	2.1 KB	23:59:21
14	aioe	35.8 KB	0.0 KB	35.8 KB	0.0 KB/s	2.4 KB	23:59:31
15	news.swapon.de	31.1 KB	0.0 KB	31.1 KB	0.0 KB/s	2.4 KB	23:57:29
16	eternal-september	31.1 KB	0.0 KB	31.1 KB	0.0 KB/s	2.4 KB	23:59:54
17	news.uzoreto.com	20.1 KB	41.3 KB	61.4 KB	0.0 KB/s	2.5 KB	23:59:54
18	news.freedyn.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:41
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:00:08
TOTAL: 19		21.8 MB	418.1 KB	22.2 MB	0.0 KB/s	8.5 KB	453:53:15

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	71-90-73-253.dhcp.ftbg.wi.charter.com	36	27	71.3 KB	18	12	12	08:39:00
2	ip72-222-200-117.ph.ph.cox.net	13	25	43.6 KB	4	0	0	03:57:12
3	a131033.upc-a.chello.nl	63	20	39.7 KB	7	0	0	17:59:54
4	208-71-239-109.cust.centrio.cz	1	9	17.6 KB	1	0	0	00:30:46
5	p2e5b5216.dip0.t-ipconnect.de	1	1	1.2 KB	1	0	0	00:00:05
TOTAL: 5		114	82	173.3 KB	31	12	12	31:11:59

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dhcp.ftbg.wi.charter.com	36	27	71.3 KB	18	12	12	08:39:00
2	*.ph.ph.cox.net	13	25	43.6 KB	4	0	0	03:57:12
3	*.upc-a.chello.nl	63	20	39.7 KB	7	0	0	17:59:54
4	*.cust.centrio.cz	1	9	17.6 KB	1	0	0	00:30:46
5	*.dip0.t-ipconnect.de	1	1	1.2 KB	1	0	0	00:00:05
6	unresolved	4	0	0.0 KB	0	0	0	00:00:15
7	*.res.spectrum.com	2	0	0.0 KB	0	0	0	00:04:44
TOTAL: 7		120	82	173.3 KB	31	12	12	31:11:59

NNRP auth users [Top 20]:

User	Conn
ohlandl	12
ctomczyk	1
TOTAL: 2	13

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	4.489	0.927	0.000	17:59:54
71-90-73-253.dhcp.ftbg.wi.charter.com	2.930	0.719	0.026	08:39:00
ip72-222-200-117.ph.ph.cox.net	1.152	0.229	0.000	03:57:12
208-71-239-109.cust.centrio.cz	0.102	0.022	0.003	00:30:46
172-221-041-102.res.spectrum.com	0.105	0.070	0.000	00:04:44
164.52.24.176	0.189	0.047	0.000	00:00:13
p2e5b5216.dip0.t-ipconnect.de	0.112	0.037	0.000	00:00:05
94.167.82.209	0.059	0.017	0.000	00:00:01
TOTAL: 8	9.138	2.068	0.029	31:11:59

Curious NNRP server explorers [Top 20]:

System	Conn
164.52.24.176	3
172-221-041-102.res.spectrum.com	2
94.167.82.209	1
TOTAL: 3	6

NNRP unrecognized commands (by host):

System	Conn
164.52.24.176	3
TOTAL: 1	3

NNRP unrecognized commands (by command):

Command	Count
À/À+À'À#À^SÀ\x09	1
^TnM-^CÄM-^R^A¨«ÒÐ¿Ý^L?(T	1
^V^C^A^A"^A	1
TOTAL: 3	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
71-90-73-253.dhcp.ftbg.wi.charter.com	10	0
ip72-222-200-117.ph.ph.cox.net	5	0
208-71-239-109.cust.centrio.cz	1	0
164.52.24.176	1	1
TOTAL: 4	17	1

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	81	98.8%
2	alt	1	1.2%
TOTAL: 2		82	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	72
2	comp.lang.javascript	9
3	alt.free.newsservers	1
TOTAL: 3		82

Daily Usenet report for csiph.com

Jan 5 04:15:00 -- Jan 6 04:15:00