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

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Nov 3 08:24:00 csiph nnrpd[43134]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 3 08:24:00 csiph nnrpd[43134]: 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 3 16:30:37 csiph nnrpd[66160]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 3 16:30:37 csiph nnrpd[66160]: 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	18982	48.9%	4.9 MB	63.8%
innd	15442	39.7%	2.1 MB	27.1%
innfeed	3532	9.1%	615.7 KB	7.9%
nnrpd	883	2.3%	99.7 KB	1.3%
nocem	8	0.0%	0.9 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	38848	100.0%	7.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	307490	53.4%
Negative hits	249621	43.3%
Do not exist	18390	3.2%
Cache misses	352	0.1%
TOTAL: 4	575853	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.208	0.0%	18	0.000	11.556	29.000
article cleanup	00:00:00.118	0.0%	18205	0.000	0.006	0.038
article logging	00:00:00.354	0.0%	18701	0.000	0.019	0.661
article parse	00:00:00.732	0.0%	59454	0.000	0.012	0.081
article write	00:00:18.173	0.0%	15304	0.179	1.187	45.306
artlog/artcncl	00:00:00.000	0.0%	18	0.000	0.000	0.000
artlog/artparse	00:00:00.006	0.0%	288	0.000	0.021	0.500
data move	00:00:00.287	0.0%	544559	0.000	0.001	0.015
hisgrep/artcncl	00:00:00.012	0.0%	18	0.000	0.667	2.000
hishave/artcncl	00:00:00.005	0.0%	18	0.000	0.278	1.000
hishave/artparse	00:00:00.002	0.0%	284	0.000	0.007	0.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:08.581	0.0%	578708	0.001	0.015	0.206
history sync	00:00:01.019	0.0%	370	0.000	2.754	47.667
history write	00:00:15.004	0.0%	18209	0.225	0.824	3.944
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.194	0.0%	284	0.000	0.683	3.000
idle	23:58:07.261	99.8%	551390	94.490	156.490	510.953
nntp read	00:00:02.357	0.0%	553762	0.001	0.004	0.035
overview write	00:00:59.808	0.1%	15304	0.154	3.908	298.500
perl filter	00:00:27.943	0.0%	18205	1.211	1.535	3.068
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.233	0.0%	30616	0.000	0.008	0.028
TOTAL: 24:01:03.228	24:00:22.297	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.024	0.0%	15503	0.000	0.002	0.027
article prepare	00:00:00.000	0.0%	10012	0.000	0.000	0.000
article read	00:00:00.308	0.0%	1207	0.000	0.255	44.000
backlog stats	00:00:00.224	0.0%	347585	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.240	0.0%	277693	0.000	0.004	0.009
data write	00:00:01.560	0.0%	265109	0.002	0.006	0.015
idle	23:54:17.548	100.0%	347585	145.160	247.587	1005.136
status file	00:00:00.199	0.0%	283	0.000	0.703	2.500
TOTAL: 23:54:30.456	23:54:21.103	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	39:26:06.506	99.8%	560	292.000	253511.618	600092.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.108	0.0%	33234	0.000	0.003	0.167
readart	00:00:00.169	0.0%	20	0.000	8.450	42.000
TOTAL: 39:31:00.269	39:26:06.783	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news-out.aioe.org	2	14611	3462	11015	134	23%	24:09:14
2	csiph-out.news.xs4all.nl	475	17582	3016	13201	1365	17%	68:25:21
3	feeder.eternal-september.org	5	17242	2348	14749	145	13%	25:27:25
4	newsfeeds.xmission.com	2	13885	1774	12077	34	12%	21:41:05
5	csiph-out.feeder.erje.net	1440	74875	1089	73288	498	1%	118:41:33
6	newsfeed.fu-berlin.de	10	14788	782	13775	231	5%	25:32:43
7	news-out.mixmin.net	1	16048	666	15297	85	4%	23:59:47
8	newsfeed.neva.ru	141	9460	666	8399	395	7%	71:37:15
9	csiph-out.news.weretis.net	5	33415	589	32694	132	1%	49:45:13
10	news.fcku.it	9	2013	278	1733	2	13%	21:16:45
11	csiph-out.feed.uzoreto.com	864	23996	213	23423	360	0%	71:13:20
12	news-out.neodome.net	2	16382	183	16190	9	1%	23:59:53
13	nntp.pasdenom.info	2	10307	127	10152	28	1%	23:58:35
14	csiph-out.news.tnetconsulting.net	2	15337	70	15247	20	0%	23:59:43
15	news.swapon.de	7	1316	49	1267	0	3%	23:10:48
16	news.freedyn.net	11	18051	16	17919	116	0%	24:38:36
17	news.bbs.nz	2	15023	6	15017	0	0%	23:59:52
18	news.samoylyk.net	3	15075	5	15065	5	0%	25:46:42
19	localhost	30	4	4	0	0	100%	00:00:01
20	xanadu-bbs.net	31	431	0	431	0	0%	20:35:29
TOTAL: 20		3044	329841	15343	310939	3559	4%	711:59:20

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.aioe.org	8.3 MB	0.0 KB	454.3 KB	8.8 MB	94%	2.5 KB
2	csiph-out.news.xs4all.nl	7.8 MB	0.0 KB	12.5 MB	20.3 MB	38%	4.8 KB
3	feeder.eternal-september.org	6.3 MB	0.0 KB	1.7 MB	8.1 MB	78%	3.3 KB
4	csiph-out.feeder.erje.net	6.3 MB	0.0 KB	3.2 MB	9.5 MB	66%	6.1 KB
5	newsfeeds.xmission.com	5.7 MB	0.0 KB	2.7 MB	8.4 MB	67%	4.8 KB
6	newsfeed.fu-berlin.de	1.8 MB	0.0 KB	510.3 KB	2.3 MB	78%	2.3 KB
7	news-out.mixmin.net	1.7 MB	0.0 KB	159.4 KB	1.9 MB	91%	2.5 KB
8	newsfeed.neva.ru	1.7 MB	0.0 KB	2.1 MB	3.8 MB	44%	3.6 KB
9	csiph-out.news.weretis.net	1.6 MB	0.0 KB	2.4 MB	4.0 MB	39%	5.6 KB
10	csiph-out.feed.uzoreto.com	817.5 KB	0.0 KB	11.6 MB	12.4 MB	6%	22.1 KB
11	news-out.neodome.net	525.1 KB	0.0 KB	109.1 KB	634.2 KB	82%	3.3 KB
12	news.fcku.it	469.5 KB	0.0 KB	1.7 KB	471.2 KB	99%	1.7 KB
13	nntp.pasdenom.info	294.1 KB	0.0 KB	34.9 KB	329.1 KB	89%	2.1 KB
14	csiph-out.news.tnetconsulting.net	267.4 KB	15.7 KB	245.3 KB	528.4 KB	50%	5.9 KB
15	news.swapon.de	125.8 KB	0.0 KB	0.0 KB	125.8 KB	100%	2.6 KB
16	news.freedyn.net	77.5 KB	0.0 KB	3.7 MB	3.7 MB	2%	28.9 KB
17	news.bbs.nz	12.2 KB	0.0 KB	0.0 KB	12.2 KB	100%	2.0 KB
18	news.samoylyk.net	10.2 KB	0.0 KB	11.2 KB	21.4 KB	47%	2.1 KB
19	localhost	5.6 KB	0.0 KB	0.0 KB	5.6 KB	100%	1.4 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		43.7 MB	15.7 KB	41.4 MB	85.1 MB	51%	4.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 3 04:15:00 - 04:59:59	170	1.1%	0.06	494.9 KB	1.1%	0.18
Nov 3 05:00:00 - 05:59:59	356	2.3%	0.10	1.7 MB	4.0%	0.49
Nov 3 06:00:00 - 06:59:59	419	2.7%	0.12	1.4 MB	3.3%	0.40
Nov 3 07:00:00 - 07:59:59	527	3.4%	0.15	1.3 MB	3.0%	0.37
Nov 3 08:00:00 - 08:59:59	626	4.1%	0.17	1.7 MB	3.9%	0.48
Nov 3 09:00:00 - 09:59:59	844	5.5%	0.23	2.7 MB	6.3%	0.78
Nov 3 10:00:00 - 10:59:59	804	5.3%	0.22	2.7 MB	6.3%	0.77
Nov 3 11:00:00 - 11:59:59	778	5.1%	0.22	2.1 MB	4.9%	0.60
Nov 3 12:00:00 - 12:59:59	814	5.3%	0.23	1.9 MB	4.3%	0.53
Nov 3 13:00:00 - 13:59:59	768	5.0%	0.21	1.9 MB	4.3%	0.53
Nov 3 14:00:00 - 14:59:59	833	5.4%	0.23	2.3 MB	5.4%	0.66
Nov 3 15:00:00 - 15:59:59	871	5.7%	0.24	2.8 MB	6.4%	0.79
Nov 3 16:00:00 - 16:59:59	919	6.0%	0.26	2.3 MB	5.4%	0.66
Nov 3 17:00:00 - 17:59:59	886	5.8%	0.25	2.5 MB	5.8%	0.72
Nov 3 18:00:00 - 18:59:59	824	5.4%	0.23	2.3 MB	5.3%	0.66
Nov 3 19:00:00 - 19:59:59	833	5.4%	0.23	2.3 MB	5.2%	0.64
Nov 3 20:00:00 - 20:59:59	809	5.3%	0.22	2.2 MB	5.1%	0.63
Nov 3 21:00:00 - 21:59:59	727	4.8%	0.20	2.0 MB	4.5%	0.56
Nov 3 22:00:00 - 22:59:59	709	4.6%	0.20	2.0 MB	4.7%	0.57
Nov 3 23:00:00 - 23:59:59	495	3.2%	0.14	1.3 MB	3.1%	0.38
Nov 4 00:00:00 - 00:59:59	382	2.5%	0.11	1010.3 KB	2.3%	0.28
Nov 4 01:00:00 - 01:59:59	299	2.0%	0.08	740.8 KB	1.7%	0.21
Nov 4 02:00:00 - 02:59:59	256	1.7%	0.07	762.4 KB	1.7%	0.21
Nov 4 03:00:00 - 03:59:59	273	1.8%	0.08	796.2 KB	1.8%	0.22
Nov 4 04:00:00 - 04:15:00	70	0.5%	0.08	205.8 KB	0.5%	0.23
TOTAL: 24:00:00	15292	100.0%	0.18	43.4 MB	100.0%	0.51

Sites sending bad articles:

Server		Total	Group	Unapp	Other
1	csiph-out.news.xs4all.nl	1396	89	1	1306
2	newsfeed.fu-berlin.de	563	21	0	542
3	newsfeed.neva.ru	396	140	0	256
4	csiph-out.feed.uzoreto.com	362	15	0	347
5	news-out.aioe.org	228	123	0	105
6	csiph-out.feeder.erje.net	175	77	0	98
7	feeder.eternal-september.org	147	75	0	72
8	news.freedyn.net	117	10	0	107
9	news-out.mixmin.net	93	0	0	93
10	csiph-out.news.weretis.net	75	32	0	43
11	newsfeeds.xmission.com	36	4	0	32
12	nntp.pasdenom.info	28	0	0	28
13	news.swapon.de	26	0	0	26
14	news-out.neodome.net	11	8	0	3
15	csiph-out.news.tnetconsulting.net	11	7	0	4
16	news.samoylyk.net	5	0	0	5
17	news.fcku.it	2	1	0	1
18	news.bbs.nz	1	1	0	0
TOTAL: 18		3672	603	1	3068

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	149
lada.talk	106
scinet.stats	31
nntp4net.test	28
alt.binaries.pictures.purebeauty	25
mozilla.support.thunderbird	24
lada.auto	22
alt.bbs.synchronet	15
alt.tv.comedy-central.daily-show	13
it-alt.comp.software.mozilla	12
spline.eisfair	12
alt.politics	11
mozilla.support.seamonkey	10
free.it.religioni.scientology	8
alt.windows7.general	7
at.jobs	6
alt.philosophy	6
alt.binaries.ebooks	6
alt.language.telugu.literature	5
alt.binaries.pictures.models.petite	5
TOTAL: 66	603

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)	1636
EMP (phn path)	230
EMP (phl)	157
EMP (phn nph)	65
Binary: misplaced binary	55
0	47
Too many newsgroups	40
Binary Payload	38
HTML Multipart	11
Too many newsgroups (meow)	4
Scoring filter	4
TOTAL: 11	2287

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	145
	csiph-out.feed.uzoreto.com	23
	csiph-out.news.xs4all.nl	20
	TOTAL: 3	188
Huge articles
	csiph-out.feed.uzoreto.com	70
	csiph-out.news.xs4all.nl	62
	csiph-out.feeder.erje.net	53
	news.freedyn.net	44
	newsfeeds.xmission.com	29
	csiph-out.news.weretis.net	14
	feeder.eternal-september.org	9
	news-out.neodome.net	1
	csiph-out.news.tnetconsulting.net	1
	newsfeed.fu-berlin.de	1
	TOTAL: 10	284
Including strange strings
	newsfeed.fu-berlin.de	324
	news-out.aioe.org	94
	csiph-out.news.xs4all.nl	31
	news.swapon.de	26
	csiph-out.news.weretis.net	4
	news-out.mixmin.net	4
	feeder.eternal-september.org	3
	newsfeeds.xmission.com	2
	csiph-out.feed.uzoreto.com	2
	newsfeed.neva.ru	1
	TOTAL: 12	493
No colon-space in header
	csiph-out.feeder.erje.net	2
	csiph-out.feed.uzoreto.com	1
	csiph-out.news.xs4all.nl	1
	TOTAL: 3	4
TOTAL: 4		969

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	17724	640	14640	11	0	3%	23:59:53
2	fu-berlin.de	14335	424	13911	0	0	2%	23:59:46
3	news.samoylyk.net	15757	416	14865	4	158	2%	23:59:53
4	news.bbs.nz	29005	351	14456	33	8	1%	23:59:53
5	newsfeed.xs4all.nl	13787	159	12040	0	15	1%	23:54:28
6	tnet	16272	94	14751	0	0	0%	23:57:32
7	xmission	14893	81	12925	6	0	0%	23:59:52
8	neva.ru	13812	45	13647	56	0	0%	23:59:53
9	usenet.pasdenom.info	15077	14	14704	1	13	0%	23:59:53
10	neodome.net	15246	11	14974	0	0	0%	23:50:06
11	aioe	11076	11	10675	0	0	0%	23:59:53
12	news.mixmin.net	14072	9	13875	0	0	0%	23:59:38
13	weretis.net	14374	8	14053	0	0	0%	23:59:53
14	eternal-september	12658	7	12403	0	0	0%	23:59:53
15	news.uzoreto.com	14499	7	14305	17	0	0%	23:59:49
16	news.freedyn.net	16336	6	14384	32	48	0%	23:59:53
17	news.swapon.de	1337	4	1326	0	0	0%	23:42:03
18	erje	13486	4	13331	52	10	0%	23:59:53
19	news.fcku.it	1664	0	1663	0	0	0%	21:11:03
TOTAL: 19		265410	2291	236928	212	252	0%	452:33:07

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	4.9 MB	28.4 KB	4.9 MB	0.1 KB/s	7.7 KB	23:59:53
2	fu-berlin.de	4.4 MB	0.0 KB	4.4 MB	0.1 KB/s	10.5 KB	23:59:46
3	news.samoylyk.net	4.3 MB	5.5 KB	4.4 MB	0.1 KB/s	10.6 KB	23:59:53
4	news.bbs.nz	892.8 KB	113.9 KB	1006.7 KB	0.0 KB/s	2.6 KB	23:59:53
5	newsfeed.xs4all.nl	468.5 KB	0.0 KB	468.5 KB	0.0 KB/s	2.9 KB	23:54:28
6	tnet	208.0 KB	0.0 KB	208.0 KB	0.0 KB/s	2.2 KB	23:57:32
7	xmission	180.5 KB	10.8 KB	191.3 KB	0.0 KB/s	2.2 KB	23:59:52
8	neva.ru	98.4 KB	115.8 KB	214.2 KB	0.0 KB/s	2.1 KB	23:59:53
9	aioe	31.3 KB	0.0 KB	31.3 KB	0.0 KB/s	2.8 KB	23:59:53
10	usenet.pasdenom.info	29.0 KB	1.3 KB	30.3 KB	0.0 KB/s	2.0 KB	23:59:53
11	weretis.net	20.8 KB	0.0 KB	20.8 KB	0.0 KB/s	2.6 KB	23:59:53
12	neodome.net	16.9 KB	0.0 KB	16.9 KB	0.0 KB/s	1.5 KB	23:50:06
13	news.mixmin.net	15.9 KB	0.0 KB	15.9 KB	0.0 KB/s	1.8 KB	23:59:38
14	eternal-september	12.7 KB	0.0 KB	12.7 KB	0.0 KB/s	1.8 KB	23:59:53
15	news.freedyn.net	11.2 KB	618.7 KB	630.0 KB	0.0 KB/s	16.6 KB	23:59:53
16	news.uzoreto.com	10.8 KB	52.5 KB	63.3 KB	0.0 KB/s	2.6 KB	23:59:49
17	news.swapon.de	5.6 KB	0.0 KB	5.6 KB	0.0 KB/s	1.4 KB	23:42:03
18	erje	5.3 KB	214.2 KB	219.5 KB	0.0 KB/s	3.9 KB	23:59:53
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:11:03
TOTAL: 19		15.5 MB	1.1 MB	16.7 MB	0.0 KB/s	6.8 KB	452:33:07

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	ppp-2-87-178-169.home.otenet.gr	2	8	9.8 KB	2	0	0	00:00:06
2	071-013-251-224.res.spectrum.com	20	4	5.6 KB	4	4	3	05:24:04
3	ip72-222-200-117.ph.ph.cox.net	9	4	6.1 KB	5	0	0	04:00:33
4	a131033.upc-a.chello.nl	24	4	6.1 KB	3	0	0	06:00:50
TOTAL: 4		55	20	27.6 KB	14	4	3	39:30:59

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.home.otenet.gr	2	8	9.8 KB	2	0	0	00:00:06
2	*.ph.ph.cox.net	9	4	6.1 KB	5	0	0	04:00:33
3	*.upc-a.chello.nl	24	4	6.1 KB	3	0	0	06:00:50
4	*.res.spectrum.com	20	4	5.6 KB	4	4	3	05:24:04
5	*.range81-153.btcentralplus.com	2	0	0.0 KB	0	0	0	00:05:25
6	*.w82-125.abo.wanadoo.fr	2	0	0.0 KB	0	0	0	00:00:03
7	unresolved	73	0	0.0 KB	0	0	0	23:59:54
TOTAL: 7		132	20	27.6 KB	14	4	3	39:30:59

NNRP auth users [Top 20]:

User	Conn
ohlandl	3
TOTAL: 1	3

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	4.648	1.398	0.000	23:59:44
a131033.upc-a.chello.nl	1.583	0.590	0.000	06:00:50
071-013-251-224.res.spectrum.com	1.463	0.312	0.008	05:24:04
ip72-222-200-117.ph.ph.cox.net	0.795	0.166	0.000	04:00:33
host81-153-177-82.range81-153.btcentralplus.com	0.132	0.025	0.000	00:05:25
45.227.255.233	0.062	0.156	0.000	00:00:10
ppp-2-87-178-169.home.otenet.gr	0.195	0.040	0.000	00:00:06
lfbn-poi-1-714-63.w82-125.abo.wanadoo.fr	0.288	1.461	0.000	00:00:03
TOTAL: 8	9.166	4.148	0.008	39:30:59

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	72
host81-153-177-82.range81-153.btcentralplus.com	2
lfbn-poi-1-714-63.w82-125.abo.wanadoo.fr	2
45.227.255.233	1
TOTAL: 4	77

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip72-222-200-117.ph.ph.cox.net	7	0
071-013-251-224.res.spectrum.com	5	0
TOTAL: 2	12	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Nov 3 04:15:00 -- Nov 4 04:15:00