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

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Nov 24 06:49:34 csiph nnrpd[71710]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 24 06:49:34 csiph nnrpd[71710]: 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 25 01:12:53 csiph nnrpd[12878]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 25 01:12:53 csiph nnrpd[12878]: 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	18526	47.7%	4.7 MB	62.8%
innd	15528	40.0%	2.0 MB	27.2%
innfeed	3534	9.1%	637.0 KB	8.2%
nnrpd	1246	3.2%	137.0 KB	1.8%
nocem	31	0.1%	3.6 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	38866	100.0%	7.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	302089	55.3%
Negative hits	226390	41.4%
Do not exist	17810	3.3%
Cache misses	347	0.1%
TOTAL: 4	546636	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.190	0.0%	18	0.000	10.556	21.167
article cleanup	00:00:00.700	0.0%	17565	0.000	0.040	12.532
article logging	00:00:00.346	0.0%	18129	0.000	0.019	0.364
article parse	00:00:01.384	0.0%	56517	0.000	0.024	0.391
article write	00:00:37.404	0.0%	14863	0.211	2.517	64.956
artlog/artcncl	00:00:00.000	0.0%	18	0.000	0.000	0.000
artlog/artparse	00:00:00.015	0.0%	373	0.000	0.040	1.100
data move	00:00:00.543	0.0%	519364	0.000	0.001	0.064
hisgrep/artcncl	00:00:00.006	0.0%	18	0.000	0.333	1.000
hishave/artcncl	00:00:00.001	0.0%	18	0.000	0.056	0.167
hishave/artparse	00:00:00.001	0.0%	372	0.000	0.003	0.250
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:33.769	0.0%	550102	0.004	0.061	0.797
history sync	00:00:02.027	0.0%	372	0.000	5.449	207.000
history write	00:00:44.787	0.1%	17563	0.506	2.550	16.716
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.842	0.0%	372	0.000	2.263	16.200
idle	23:56:01.124	99.7%	521033	85.717	165.366	673.989
nntp read	00:00:04.179	0.0%	527450	0.001	0.008	0.310
overview write	00:01:22.458	0.1%	14863	0.061	5.548	107.914
perl filter	00:00:43.069	0.0%	17562	1.125	2.452	13.105
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.248	0.0%	29757	0.000	0.008	0.025
TOTAL: 24:01:00.166	24:00:13.093	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.039	0.0%	15821	0.000	0.002	0.030
article prepare	00:00:00.001	0.0%	10924	0.000	0.000	0.013
article read	00:00:00.869	0.0%	1158	0.000	0.750	43.000
backlog stats	00:00:00.219	0.0%	338747	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.340	0.0%	268730	0.001	0.005	0.019
data write	00:00:01.603	0.0%	256753	0.000	0.006	0.014
idle	23:54:24.011	100.0%	338747	149.733	254.066	894.171
status file	00:00:00.233	0.0%	286	0.000	0.815	10.000
TOTAL: 23:54:35.064	23:54:28.315	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	52:55:51.721	99.8%	918	0.000	207572.681	900014.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.101	0.0%	2206	0.000	0.046	1.375
readart	00:00:00.547	0.0%	79	0.000	6.924	43.000
TOTAL: 53:00:47.949	52:55:52.369	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news-out.aioe.org	3	14037	3131	10800	106	22%	24:08:59
2	newsfeeds.xmission.com	2	14160	2608	11498	54	18%	24:07:25
3	csiph-out.news.xs4all.nl	523	17124	2454	13386	1284	14%	64:58:31
4	feeder.eternal-september.org	2	16069	2095	13848	126	13%	22:14:11
5	newsfeed.fu-berlin.de	17	14711	818	13647	246	5%	26:35:52
6	csiph-out.feeder.erje.net	1440	72103	816	71163	124	1%	118:41:06
7	news-out.mixmin.net	1	15906	696	15002	208	4%	23:59:48
8	newsfeed.neva.ru	138	11997	618	11248	131	5%	71:51:21
9	csiph-out.news.weretis.net	2	30926	505	30242	179	1%	47:59:47
10	news.fcku.it	17	1584	244	1332	8	15%	21:13:11
11	news-out.neodome.net	3	16265	210	15988	67	1%	23:59:42
12	csiph-out.feed.uzoreto.com	864	22675	197	22127	351	0%	71:11:22
13	nntp.pasdenom.info	12	10333	103	10220	10	0%	23:53:43
14	news.swapon.de	2	1513	61	1452	0	4%	23:36:32
15	csiph-out.news.tnetconsulting.net	1	15472	46	15347	79	0%	23:59:55
16	localhost	40	14	14	0	0	100%	00:00:00
17	news.freedyn.net	1	17220	14	17046	160	0%	23:59:57
18	news.bbs.nz	2	14574	8	14566	0	0%	23:59:45
19	news.samoylyk.net	2	14350	4	14346	0	0%	23:59:48
20	xanadu-bbs.net	23	532	0	532	0	0%	21:30:56
TOTAL: 20		3095	321565	14642	303790	3133	4%	706:01:51

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	8.3 MB	0.0 KB	4.3 MB	12.6 MB	65%	4.8 KB
2	news-out.aioe.org	7.7 MB	0.0 KB	304.3 KB	8.0 MB	96%	2.5 KB
3	csiph-out.news.xs4all.nl	6.5 MB	0.0 KB	13.1 MB	19.6 MB	33%	5.4 KB
4	csiph-out.feeder.erje.net	6.0 MB	0.0 KB	2.4 MB	8.4 MB	70%	9.2 KB
5	feeder.eternal-september.org	6.0 MB	4.2 KB	897.3 KB	6.9 MB	87%	3.2 KB
6	newsfeed.fu-berlin.de	2.0 MB	0.0 KB	469.8 KB	2.4 MB	81%	2.4 KB
7	news-out.mixmin.net	1.9 MB	0.0 KB	456.6 KB	2.4 MB	81%	2.7 KB
8	csiph-out.news.weretis.net	1.5 MB	0.0 KB	1.2 MB	2.7 MB	56%	4.0 KB
9	newsfeed.neva.ru	1.3 MB	0.0 KB	643.0 KB	2.0 MB	67%	2.7 KB
10	csiph-out.feed.uzoreto.com	744.0 KB	0.0 KB	6.6 MB	7.3 MB	9%	13.7 KB
11	news-out.neodome.net	646.9 KB	4.2 KB	278.1 KB	929.2 KB	69%	3.4 KB
12	news.fcku.it	374.3 KB	0.0 KB	8.0 KB	382.3 KB	97%	1.5 KB
13	csiph-out.news.tnetconsulting.net	261.1 KB	0.0 KB	263.9 KB	525.1 KB	49%	4.2 KB
14	nntp.pasdenom.info	219.8 KB	4.2 KB	15.2 KB	239.2 KB	91%	2.1 KB
15	news.swapon.de	171.0 KB	0.0 KB	0.0 KB	171.0 KB	100%	2.8 KB
16	news.freedyn.net	60.8 KB	0.0 KB	6.0 MB	6.0 MB	0%	35.4 KB
17	localhost	31.4 KB	0.0 KB	0.0 KB	31.4 KB	100%	2.2 KB
18	news.bbs.nz	24.8 KB	0.0 KB	0.0 KB	24.8 KB	100%	3.1 KB
19	news.samoylyk.net	11.1 KB	0.0 KB	0.0 KB	11.1 KB	100%	2.8 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		43.7 MB	12.6 KB	36.8 MB	80.5 MB	54%	4.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 24 04:15:00 - 04:59:59	161	1.1%	0.06	525.6 KB	1.2%	0.19
Nov 24 05:00:00 - 05:59:59	355	2.4%	0.10	1.7 MB	3.8%	0.49
Nov 24 06:00:00 - 06:59:59	382	2.6%	0.11	1.3 MB	2.9%	0.37
Nov 24 07:00:00 - 07:59:59	499	3.4%	0.14	1.3 MB	3.0%	0.38
Nov 24 08:00:00 - 08:59:59	591	4.0%	0.16	1.6 MB	3.6%	0.45
Nov 24 09:00:00 - 09:59:59	797	5.4%	0.22	2.4 MB	5.4%	0.68
Nov 24 10:00:00 - 10:59:59	735	4.9%	0.20	2.0 MB	4.5%	0.57
Nov 24 11:00:00 - 11:59:59	749	5.0%	0.21	1.9 MB	4.2%	0.53
Nov 24 12:00:00 - 12:59:59	707	4.8%	0.20	1.9 MB	4.3%	0.55
Nov 24 13:00:00 - 13:59:59	788	5.3%	0.22	1.9 MB	4.2%	0.53
Nov 24 14:00:00 - 14:59:59	764	5.1%	0.21	2.1 MB	4.8%	0.61
Nov 24 15:00:00 - 15:59:59	853	5.7%	0.24	3.4 MB	7.6%	0.97
Nov 24 16:00:00 - 16:59:59	865	5.8%	0.24	2.4 MB	5.4%	0.68
Nov 24 17:00:00 - 17:59:59	857	5.8%	0.24	2.4 MB	5.4%	0.68
Nov 24 18:00:00 - 18:59:59	786	5.3%	0.22	2.3 MB	5.2%	0.66
Nov 24 19:00:00 - 19:59:59	816	5.5%	0.23	2.5 MB	5.5%	0.70
Nov 24 20:00:00 - 20:59:59	829	5.6%	0.23	2.3 MB	5.3%	0.67
Nov 24 21:00:00 - 21:59:59	767	5.2%	0.21	2.3 MB	5.3%	0.67
Nov 24 22:00:00 - 22:59:59	652	4.4%	0.18	1.6 MB	3.7%	0.47
Nov 24 23:00:00 - 23:59:59	503	3.4%	0.14	1.7 MB	3.8%	0.48
Nov 25 00:00:00 - 00:59:59	424	2.9%	0.12	1.4 MB	3.2%	0.41
Nov 25 01:00:00 - 01:59:59	256	1.7%	0.07	799.3 KB	1.8%	0.22
Nov 25 02:00:00 - 02:59:59	277	1.9%	0.08	912.6 KB	2.0%	0.25
Nov 25 03:00:00 - 03:59:59	380	2.6%	0.11	1.3 MB	2.9%	0.37
Nov 25 04:00:00 - 04:15:00	78	0.5%	0.09	431.5 KB	0.9%	0.48
TOTAL: 24:00:00	14871	100.0%	0.17	44.4 MB	100.0%	0.53

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.news.xs4all.nl	1317	88	0	0	1229
2	newsfeed.fu-berlin.de	624	14	0	0	610
3	csiph-out.feed.uzoreto.com	352	24	0	0	328
4	news-out.aioe.org	218	86	0	0	132
5	news-out.mixmin.net	216	5	0	0	211
6	news.freedyn.net	159	0	0	0	159
7	csiph-out.news.weretis.net	150	55	0	0	95
8	feeder.eternal-september.org	143	69	1	0	73
9	newsfeed.neva.ru	133	49	0	0	84
10	csiph-out.feeder.erje.net	127	48	0	1	78
11	news-out.neodome.net	78	3	1	0	74
12	newsfeeds.xmission.com	54	1	0	0	53
13	csiph-out.news.tnetconsulting.net	29	3	0	0	26
14	news.swapon.de	19	0	0	0	19
15	nntp.pasdenom.info	10	0	1	0	9
16	news.fcku.it	8	6	0	0	2
TOTAL: 16		3637	451	3	1	3182

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	129
lada.talk	35
alt.bbs.synchronet	32
scinet.stats	32
it-alt.sport.calcio.juventus	26
mozilla.support.thunderbird	21
alt.politics	14
mozilla.test	13
oesterreich.tratsch	12
alt.survival	12
alt.windows7.general	9
att.jobs	8
mozilla.support.firefox	6
cakewalk.audio	6
alt.binaries.ebook	6
alt.politics.usa	6
alt.binaries.pictures.purebeauty	5
spline.eisfair.dev	4
alt.binaries.ebooks	4
free.it.religioni.scientology	4
TOTAL: 64	451

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)	1484
EMP (phn path)	374
Too many newsgroups	102
Binary Payload	78
EMP (phl)	76
EMP (phn nph)	54
0	48
HTML Multipart	17
Binary: misplaced binary	8
Too many newsgroups (meow)	3
HTML post	1
TOTAL: 11	2245

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	csiph-out.news.xs4all.nl	1
	csiph-out.feed.uzoreto.com	1
	TOTAL: 3	47
Huge articles
	csiph-out.news.xs4all.nl	120
	news.freedyn.net	70
	csiph-out.feed.uzoreto.com	65
	newsfeeds.xmission.com	49
	csiph-out.feeder.erje.net	44
	news-out.neodome.net	11
	feeder.eternal-september.org	8
	csiph-out.news.weretis.net	6
	csiph-out.news.tnetconsulting.net	1
	news-out.mixmin.net	1
	TOTAL: 10	375
Including strange strings
	newsfeed.fu-berlin.de	377
	news-out.aioe.org	110
	csiph-out.news.xs4all.nl	33
	news.swapon.de	19
	csiph-out.news.weretis.net	10
	newsfeeds.xmission.com	4
	news-out.mixmin.net	4
	csiph-out.feeder.erje.net	3
	newsfeed.neva.ru	2
	csiph-out.feed.uzoreto.com	1
	TOTAL: 11	564
No colon-space in header
	csiph-out.news.xs4all.nl	1
	TOTAL: 1	1
TOTAL: 4		987

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	18349	741	14125	1	7	4%	23:59:56
2	fu-berlin.de	13833	484	13349	0	0	3%	23:59:55
3	news.samoylyk.net	15164	482	14382	3	0	3%	23:59:55
4	news.bbs.nz	27956	400	13953	25	3	1%	23:59:55
5	neva.ru	13378	45	13200	64	0	0%	23:59:55
6	tnet	16055	42	14338	0	3	0%	23:59:55
7	newsfeed.xs4all.nl	12528	41	12231	0	965	0%	23:59:55
8	aioe	10823	35	10430	1	0	0%	23:59:55
9	usenet.pasdenom.info	14685	32	14236	12	7	0%	23:59:55
10	xmission	13147	31	11633	0	2	0%	23:59:56
11	erje	14304	29	13582	73	1	0%	23:59:56
12	news.freedyn.net	14376	29	14148	27	0	0%	23:59:55
13	neodome.net	14891	23	14610	0	0	0%	23:59:55
14	news.mixmin.net	13604	18	13407	0	0	0%	23:59:14
15	eternal-september	12232	16	12010	0	0	0%	23:59:55
16	weretis.net	14194	15	13604	0	1	0%	23:59:52
17	news.swapon.de	1546	15	1524	0	0	0%	23:55:08
18	news.uzoreto.com	14137	12	13925	36	0	0%	23:59:55
19	news.fcku.it	1219	0	1218	0	0	0%	20:40:14
TOTAL: 19		256421	2490	229905	242	989	0%	452:33:16

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	5.8 MB	13.4 KB	5.8 MB	0.1 KB/s	8.0 KB	23:59:56
2	fu-berlin.de	5.0 MB	0.0 KB	5.0 MB	0.1 KB/s	10.5 KB	23:59:55
3	news.samoylyk.net	5.0 MB	4.3 KB	5.0 MB	0.1 KB/s	10.5 KB	23:59:55
4	news.bbs.nz	1.1 MB	85.3 KB	1.2 MB	0.0 KB/s	2.8 KB	23:59:55
5	newsfeed.xs4all.nl	141.0 KB	0.0 KB	141.0 KB	0.0 KB/s	3.4 KB	23:59:55
6	neva.ru	128.6 KB	190.0 KB	318.6 KB	0.0 KB/s	2.9 KB	23:59:55
7	tnet	100.8 KB	0.0 KB	100.8 KB	0.0 KB/s	2.4 KB	23:59:55
8	xmission	99.1 KB	0.0 KB	99.1 KB	0.0 KB/s	3.2 KB	23:59:56
9	usenet.pasdenom.info	88.0 KB	38.4 KB	126.4 KB	0.0 KB/s	2.9 KB	23:59:55
10	news.freedyn.net	83.3 KB	431.2 KB	514.5 KB	0.0 KB/s	9.2 KB	23:59:55
11	aioe	81.7 KB	0.8 KB	82.5 KB	0.0 KB/s	2.3 KB	23:59:55
12	erje	73.4 KB	172.2 KB	245.5 KB	0.0 KB/s	2.4 KB	23:59:56
13	neodome.net	72.7 KB	0.0 KB	72.7 KB	0.0 KB/s	3.2 KB	23:59:55
14	news.mixmin.net	38.4 KB	0.0 KB	38.4 KB	0.0 KB/s	2.1 KB	23:59:14
15	eternal-september	36.0 KB	0.0 KB	36.0 KB	0.0 KB/s	2.3 KB	23:59:55
16	weretis.net	34.7 KB	0.0 KB	34.7 KB	0.0 KB/s	2.3 KB	23:59:52
17	news.swapon.de	33.3 KB	0.0 KB	33.3 KB	0.0 KB/s	2.2 KB	23:55:08
18	news.uzoreto.com	22.8 KB	110.8 KB	133.5 KB	0.0 KB/s	2.8 KB	23:59:55
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:40:14
TOTAL: 19		17.8 MB	1.0 MB	18.8 MB	0.0 KB/s	7.1 KB	452:33:16

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	athedsl-175833.home.otenet.gr	2	34	71.9 KB	2	0	0	00:00:20
2	066-188-083-201.res.spectrum.com	40	30	59.8 KB	31	13	10	11:46:40
3	a131033.upc-a.chello.nl	49	14	34.3 KB	6	0	0	11:01:15
4	aorleans-654-1-104-233.w90-20.abo.wanadoo.fr	1	1	0.8 KB	0	0	0	00:00:02
5	ip72-222-200-117.ph.ph.cox.net	9	0	0.0 KB	0	1	1	03:50:32
TOTAL: 5		101	79	166.8 KB	39	14	11	53:00:46

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.home.otenet.gr	2	34	71.9 KB	2	0	0	00:00:20
2	*.res.spectrum.com	40	30	59.8 KB	31	13	10	11:46:40
3	*.upc-a.chello.nl	49	14	34.3 KB	6	0	0	11:01:15
4	*.w90-20.abo.wanadoo.fr	1	1	0.8 KB	0	0	0	00:00:02
5	unresolved	77	0	0.0 KB	0	0	0	26:07:55
6	*.range5-81.btcentralplus.com	2	0	0.0 KB	0	0	0	00:14:00
7	*.ph.ph.cox.net	9	0	0.0 KB	0	1	1	03:50:32
TOTAL: 7		180	79	166.8 KB	39	14	11	53:00:46

NNRP auth users [Top 20]:

User	Conn
ohlandl	10
kev009	1
TOTAL: 2	11

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	5.493	2.827	0.000	25:00:09
066-188-083-201.res.spectrum.com	3.569	4.205	0.032	11:46:40
a131033.upc-a.chello.nl	3.481	2.329	0.000	11:01:15
ip72-222-200-117.ph.ph.cox.net	0.795	0.167	0.002	03:50:32
111.206.250.198	0.112	0.040	0.000	00:37:14
111.206.250.229	0.072	0.008	0.000	00:30:00
host5-81-182-240.range5-81.btcentralplus.com	0.138	0.261	0.000	00:14:00
111.206.250.230	0.135	0.025	0.000	00:00:31
athedsl-175833.home.otenet.gr	0.221	0.430	0.000	00:00:20
aorleans-654-1-104-233.w90-20.abo.wanadoo.fr	0.101	0.671	0.000	00:00:02
TOTAL: 10	14.117	10.963	0.034	53:00:46

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	72
111.206.250.198	2
host5-81-182-240.range5-81.btcentralplus.com	2
111.206.250.230	2
111.206.250.229	1
TOTAL: 5	79

NNRP unrecognized commands (by host):

System	Conn
111.206.250.198	5
TOTAL: 1	5

NNRP unrecognized commands (by command):

Command	Count
^A	1
^V^C^A	1
Host: 69.160.37.58	1
»ºÑ0íWö²^WZm	1
GET / HTTP/1.0	1
TOTAL: 5	5

NNRP client timeouts [Top 20]:

System	Conn	Peer
066-188-083-201.res.spectrum.com	12	0
ip72-222-200-117.ph.ph.cox.net	6	0
91.187.33.87	6	0
111.206.250.198	3	0
111.206.250.229	1	0
TOTAL: 5	28	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Nov 24 04:15:00 -- Nov 25 04:15:00