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

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Nov 5 10:24:27 csiph nnrpd[87887]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 5 10:24:27 csiph nnrpd[87887]: 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 5 13:03:29 csiph nnrpd[96351]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 5 13:03:29 csiph nnrpd[96351]: 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 5 14:38:15 csiph nnrpd[1081]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 5 14:38:15 csiph nnrpd[1081]: 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 5 21:09:52 csiph nnrpd[18724]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 5 21:09:52 csiph nnrpd[18724]: 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	20017	49.6%	5.1 MB	64.5%
innd	15810	39.2%	2.1 MB	26.7%
innfeed	3613	9.0%	616.9 KB	7.6%
nnrpd	871	2.2%	94.9 KB	1.2%
nocem	25	0.1%	3.0 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	40337	100.0%	7.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	325412	56.6%
Negative hits	229817	40.0%
Do not exist	19365	3.4%
Cache misses	317	0.1%
TOTAL: 4	574911	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.451	0.0%	51	0.000	8.843	27.300
article cleanup	00:00:00.110	0.0%	18599	0.000	0.006	0.029
article logging	00:00:00.352	0.0%	19148	0.000	0.018	0.449
article parse	00:00:01.806	0.0%	78099	0.000	0.023	0.099
article write	00:00:28.676	0.0%	15902	0.160	1.803	37.792
artlog/artcncl	00:00:00.000	0.0%	51	0.000	0.000	0.000
artlog/artparse	00:00:00.013	0.0%	811	0.000	0.016	0.500
data move	00:00:00.679	0.0%	566775	0.000	0.001	0.026
hisgrep/artcncl	00:00:00.015	0.0%	50	0.000	0.300	1.333
hishave/artcncl	00:00:00.011	0.0%	51	0.000	0.216	1.000
hishave/artparse	00:00:00.001	0.0%	808	0.000	0.001	0.038
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:15.945	0.0%	575407	0.003	0.028	0.204
history sync	00:00:01.187	0.0%	381	0.000	3.115	40.000
history write	00:00:22.710	0.0%	18592	0.176	1.221	8.772
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:01.006	0.0%	808	0.000	1.245	6.500
idle	23:57:30.360	99.8%	569901	82.877	151.343	672.502
nntp read	00:00:02.830	0.0%	574245	0.001	0.005	0.059
overview write	00:01:00.741	0.1%	15902	0.156	3.820	134.745
perl filter	00:00:28.635	0.0%	18589	1.176	1.540	3.188
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.248	0.0%	31829	0.000	0.008	0.032
TOTAL: 24:00:57.845	24:00:15.776	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.036	0.0%	16506	0.000	0.002	0.017
article prepare	00:00:00.001	0.0%	15984	0.000	0.000	0.008
article read	00:00:00.428	0.0%	2302	0.000	0.186	16.500
backlog stats	00:00:00.257	0.0%	371868	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.436	0.0%	292662	0.001	0.005	0.010
data write	00:00:01.771	0.0%	279571	0.002	0.006	0.012
idle	23:54:47.911	100.0%	371868	129.718	231.501	875.195
status file	00:00:00.222	0.0%	313	0.000	0.709	2.000
TOTAL: 23:55:00.805	23:54:52.062	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	38:51:52.847	99.8%	545	53.500	256720.820	600171.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.008	0.0%	1365	0.000	0.006	0.167
readart	00:00:00.167	0.0%	16	0.000	10.438	43.000
TOTAL: 38:56:43.875	38:51:53.022	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	3	15232	3532	11660	40	23%	24:27:20
2	news-out.aioe.org	3	15134	3433	11546	155	22%	24:09:55
3	feeder.eternal-september.org	5	17702	2420	15186	96	13%	24:45:31
4	csiph-out.news.xs4all.nl	444	18726	2173	14971	1582	11%	68:50:10
5	newsfeed.fu-berlin.de	14	15787	840	14724	223	5%	26:03:34
6	csiph-out.feeder.erje.net	1440	76965	709	76143	113	0%	118:41:47
7	news-out.mixmin.net	1	16815	681	15956	178	4%	23:59:39
8	csiph-out.news.weretis.net	3	32049	615	31331	103	1%	47:59:47
9	newsfeed.neva.ru	152	14439	595	13584	260	4%	72:03:47
10	news.fcku.it	12	2297	406	1877	14	17%	22:20:11
11	news-out.neodome.net	1	16998	207	16765	26	1%	23:59:55
12	csiph-out.feed.uzoreto.com	867	25373	127	24596	650	0%	71:11:49
13	nntp.pasdenom.info	2	11013	97	10906	10	0%	23:58:52
14	news.swapon.de	13	1095	60	1035	0	5%	23:37:17
15	csiph-out.news.tnetconsulting.net	2	15289	56	15196	37	0%	23:59:55
16	news.freedyn.net	1	18084	6	18054	24	0%	23:59:58
17	news.bbs.nz	1	14910	4	14906	0	0%	23:59:52
18	news.samoylyk.net	10	15430	1	15428	1	0%	24:39:06
19	localhost	27	1	1	0	0	100%	00:00:03
20	xanadu-bbs.net	41	263	0	263	0	0%	17:35:35
TOTAL: 20		3042	343602	15963	324127	3512	4%	710:24:03

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	9.8 MB	2.9 KB	2.7 MB	12.6 MB	78%	3.6 KB
2	news-out.aioe.org	8.3 MB	5.7 KB	474.3 KB	8.8 MB	94%	2.5 KB
3	feeder.eternal-september.org	6.2 MB	4.6 KB	837.5 KB	7.0 MB	88%	2.9 KB
4	csiph-out.news.xs4all.nl	5.3 MB	0.0 KB	37.2 MB	42.6 MB	12%	11.6 KB
5	csiph-out.feeder.erje.net	5.1 MB	0.0 KB	1.9 MB	7.0 MB	73%	8.7 KB
6	newsfeed.fu-berlin.de	1.8 MB	0.0 KB	561.7 KB	2.4 MB	77%	2.3 KB
7	csiph-out.news.weretis.net	1.8 MB	0.0 KB	1.7 MB	3.4 MB	51%	4.9 KB
8	news-out.mixmin.net	1.7 MB	0.0 KB	313.5 KB	2.0 MB	85%	2.4 KB
9	newsfeed.neva.ru	1.4 MB	2.8 KB	671.3 KB	2.0 MB	67%	2.4 KB
10	news.fcku.it	676.4 KB	2.7 KB	19.2 KB	698.3 KB	96%	1.7 KB
11	news-out.neodome.net	595.3 KB	2.0 KB	289.4 KB	886.7 KB	67%	3.8 KB
12	csiph-out.feed.uzoreto.com	422.7 KB	0.0 KB	45.7 MB	46.1 MB	0%	60.8 KB
13	csiph-out.news.tnetconsulting.net	203.8 KB	0.0 KB	253.2 KB	457.0 KB	44%	4.9 KB
14	nntp.pasdenom.info	195.2 KB	0.0 KB	12.6 KB	207.8 KB	93%	1.9 KB
15	news.swapon.de	128.6 KB	0.0 KB	0.0 KB	128.6 KB	100%	2.1 KB
16	news.freedyn.net	27.9 KB	0.0 KB	788.1 KB	815.9 KB	3%	27.2 KB
17	news.samoylyk.net	11.6 KB	2.7 KB	2.7 KB	17.0 KB	68%	8.5 KB
18	news.bbs.nz	4.4 KB	0.0 KB	0.0 KB	4.4 KB	100%	1.1 KB
19	localhost	1.6 KB	0.0 KB	0.0 KB	1.6 KB	100%	1.6 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		43.7 MB	23.3 KB	93.3 MB	137.0 MB	31%	7.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 5 04:15:00 - 04:59:59	188	1.2%	0.07	580.7 KB	1.3%	0.22
Nov 5 05:00:00 - 05:59:59	374	2.4%	0.10	1.8 MB	4.2%	0.52
Nov 5 06:00:00 - 06:59:59	396	2.5%	0.11	1005.7 KB	2.3%	0.28
Nov 5 07:00:00 - 07:59:59	523	3.3%	0.15	1.3 MB	3.1%	0.38
Nov 5 08:00:00 - 08:59:59	741	4.7%	0.21	1.8 MB	4.2%	0.52
Nov 5 09:00:00 - 09:59:59	810	5.1%	0.23	2.2 MB	5.0%	0.62
Nov 5 10:00:00 - 10:59:59	783	4.9%	0.22	2.0 MB	4.5%	0.56
Nov 5 11:00:00 - 11:59:59	808	5.1%	0.22	2.0 MB	4.7%	0.58
Nov 5 12:00:00 - 12:59:59	691	4.3%	0.19	2.0 MB	4.6%	0.57
Nov 5 13:00:00 - 13:59:59	840	5.3%	0.23	2.1 MB	4.7%	0.59
Nov 5 14:00:00 - 14:59:59	844	5.3%	0.23	2.1 MB	4.7%	0.59
Nov 5 15:00:00 - 15:59:59	1001	6.3%	0.28	2.8 MB	6.3%	0.78
Nov 5 16:00:00 - 16:59:59	925	5.8%	0.26	2.6 MB	6.0%	0.75
Nov 5 17:00:00 - 17:59:59	861	5.4%	0.24	2.2 MB	5.0%	0.62
Nov 5 18:00:00 - 18:59:59	821	5.2%	0.23	2.2 MB	5.0%	0.62
Nov 5 19:00:00 - 19:59:59	838	5.3%	0.23	2.5 MB	5.8%	0.72
Nov 5 20:00:00 - 20:59:59	822	5.2%	0.23	2.1 MB	4.9%	0.61
Nov 5 21:00:00 - 21:59:59	740	4.7%	0.21	2.0 MB	4.5%	0.56
Nov 5 22:00:00 - 22:59:59	671	4.2%	0.19	1.9 MB	4.3%	0.53
Nov 5 23:00:00 - 23:59:59	569	3.6%	0.16	1.7 MB	3.9%	0.48
Nov 6 00:00:00 - 00:59:59	469	2.9%	0.13	1.3 MB	2.9%	0.36
Nov 6 01:00:00 - 01:59:59	398	2.5%	0.11	1.2 MB	2.8%	0.34
Nov 6 02:00:00 - 02:59:59	383	2.4%	0.11	1.1 MB	2.5%	0.31
Nov 6 03:00:00 - 03:59:59	334	2.1%	0.09	985.3 KB	2.2%	0.27
Nov 6 04:00:00 - 04:15:00	69	0.4%	0.08	162.7 KB	0.4%	0.18
TOTAL: 24:00:00	15899	100.0%	0.18	43.5 MB	100.0%	0.52

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1613	41	0	1572
2	csiph-out.feed.uzoreto.com	650	10	0	640
3	newsfeed.fu-berlin.de	613	27	0	586
4	newsfeed.neva.ru	273	91	1	181
5	news-out.aioe.org	241	130	2	109
6	news-out.mixmin.net	179	1	0	178
7	csiph-out.feeder.erje.net	113	37	0	76
8	csiph-out.news.weretis.net	109	46	0	63
9	feeder.eternal-september.org	100	53	2	45
10	newsfeeds.xmission.com	48	6	1	41
11	news-out.neodome.net	31	9	1	21
12	csiph-out.news.tnetconsulting.net	28	22	1	5
13	news.freedyn.net	24	0	0	24
14	news.swapon.de	20	0	0	20
15	news.fcku.it	14	13	1	0
16	nntp.pasdenom.info	10	1	0	9
17	news.samoylyk.net	1	0	1	0
TOTAL: 17		4067	487	10	3570

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	108
lada.talk	84
scinet.stats	31
spline.eisfair	27
it-alt.sport.calcio.juventus	25
alt.bbs.synchronet	13
alt.politics	11
alt.tv.comedy-central.daily-show	11
free.it.religioni.scientology	11
mozilla.support.thunderbird	10
alt.binaries.pictures.purebeauty	10
mozilla.dev.security.policy	10
at.jobs	9
alt.job	8
oesterreich.tratsch	8
alt.philosophy	8
alt.slack.goathead	7
alt.politics.usa	7
lada.auto	6
mozilla.general	5
TOTAL: 71	487

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1633
EMP (phn path)	238
EMP (phl)	147
Too many newsgroups	74
EMP (phn nph)	48
0	37
Binary Payload	11
Binary: misplaced binary	7
HTML post	6
Scoring filter	5
HTML Multipart	3
TOTAL: 11	2209

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	85
	csiph-out.feed.uzoreto.com	10
	csiph-out.news.xs4all.nl	8
	TOTAL: 3	103
Huge articles
	csiph-out.feed.uzoreto.com	415
	csiph-out.news.xs4all.nl	297
	csiph-out.feeder.erje.net	35
	newsfeeds.xmission.com	32
	csiph-out.news.weretis.net	13
	news.freedyn.net	7
	feeder.eternal-september.org	5
	news-out.neodome.net	4
	csiph-out.news.tnetconsulting.net	2
	newsfeed.fu-berlin.de	1
	TOTAL: 10	811
Including strange strings
	newsfeed.fu-berlin.de	382
	news-out.aioe.org	96
	csiph-out.news.xs4all.nl	31
	news.swapon.de	20
	newsfeeds.xmission.com	9
	csiph-out.news.weretis.net	6
	feeder.eternal-september.org	3
	newsfeed.neva.ru	1
	news-out.mixmin.net	1
	news-out.neodome.net	1
	TOTAL: 10	550
No colon-space in header
	csiph-out.news.xs4all.nl	2
	csiph-out.feed.uzoreto.com	1
	TOTAL: 2	3
TOTAL: 4		1467

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	19094	1855	14032	10	5	9%	23:59:53
2	news.bbs.nz	30083	435	14972	23	0	1%	23:59:53
3	fu-berlin.de	14804	424	14380	0	0	2%	23:59:53
4	news.samoylyk.net	16457	409	15480	9	585	2%	23:59:53
5	erje	16302	172	14531	292	0	1%	23:59:44
6	tnet	18047	103	15337	0	0	0%	23:59:50
7	newsfeed.xs4all.nl	13993	61	13588	1	19	0%	23:59:53
8	neva.ru	14506	51	14304	49	0	0%	23:59:52
9	xmission	13850	14	11903	0	0	0%	23:59:35
10	usenet.pasdenom.info	15915	9	15328	2	20	0%	23:59:53
11	neodome.net	16060	8	15642	0	0	0%	23:59:30
12	weretis.net	15275	5	14779	0	0	0%	23:59:49
13	news.mixmin.net	14808	5	14547	0	0	0%	23:59:43
14	news.freedyn.net	15381	5	15087	23	0	0%	23:59:51
15	news.uzoreto.com	15479	3	15156	16	0	0%	23:59:50
16	news.swapon.de	1127	3	1113	0	0	0%	23:12:44
17	eternal-september	13252	3	12878	0	0	0%	23:59:53
18	aioe	11764	1	11219	1	0	0%	23:59:53
19	news.fcku.it	1822	0	1817	0	0	0%	22:06:56
TOTAL: 19		278019	3566	246093	426	629	1%	453:16:28

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	7.8 MB	32.6 KB	7.9 MB	0.1 KB/s	4.3 KB	23:59:53
2	fu-berlin.de	4.1 MB	0.0 KB	4.1 MB	0.0 KB/s	9.9 KB	23:59:53
3	news.samoylyk.net	4.0 MB	90.0 KB	4.1 MB	0.0 KB/s	10.0 KB	23:59:53
4	news.bbs.nz	1.1 MB	70.6 KB	1.2 MB	0.0 KB/s	2.6 KB	23:59:53
5	erje	436.5 KB	999.1 KB	1.4 MB	0.0 KB/s	3.1 KB	23:59:44
6	tnet	231.5 KB	0.0 KB	231.5 KB	0.0 KB/s	2.2 KB	23:59:50
7	newsfeed.xs4all.nl	192.4 KB	6.6 KB	199.1 KB	0.0 KB/s	3.2 KB	23:59:53
8	neva.ru	97.0 KB	116.3 KB	213.3 KB	0.0 KB/s	2.1 KB	23:59:52
9	xmission	54.6 KB	0.0 KB	54.6 KB	0.0 KB/s	3.9 KB	23:59:35
10	usenet.pasdenom.info	30.3 KB	3.0 KB	33.3 KB	0.0 KB/s	3.0 KB	23:59:53
11	neodome.net	27.7 KB	0.0 KB	27.7 KB	0.0 KB/s	3.5 KB	23:59:30
12	news.mixmin.net	11.7 KB	0.0 KB	11.7 KB	0.0 KB/s	2.3 KB	23:59:43
13	news.freedyn.net	10.2 KB	218.3 KB	228.5 KB	0.0 KB/s	8.2 KB	23:59:51
14	news.uzoreto.com	7.0 KB	61.0 KB	68.0 KB	0.0 KB/s	3.6 KB	23:59:50
15	weretis.net	6.0 KB	0.0 KB	6.0 KB	0.0 KB/s	1.2 KB	23:59:49
16	eternal-september	4.9 KB	0.0 KB	4.9 KB	0.0 KB/s	1.6 KB	23:59:53
17	news.swapon.de	4.5 KB	0.0 KB	4.5 KB	0.0 KB/s	1.5 KB	23:12:44
18	aioe	1.6 KB	0.7 KB	2.3 KB	0.0 KB/s	1.1 KB	23:59:53
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:06:56
TOTAL: 19		18.1 MB	1.6 MB	19.7 MB	0.0 KB/s	5.0 KB	453:16:28

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	ppp-2-84-162-19.home.otenet.gr	1	8	10.1 KB	1	0	0	00:00:05
2	ip72-222-200-117.ph.ph.cox.net	15	3	3.6 KB	3	0	0	04:47:33
3	a131033.upc-a.chello.nl	27	3	3.6 KB	3	0	0	05:33:40
4	066-188-082-215.res.spectrum.com	13	2	2.7 KB	2	1	1	03:24:08
TOTAL: 4		56	16	20.0 KB	9	1	1	38:56:42

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.home.otenet.gr	1	8	10.1 KB	1	0	0	00:00:05
2	*.ph.ph.cox.net	15	3	3.6 KB	3	0	0	04:47:33
3	*.upc-a.chello.nl	27	3	3.6 KB	3	0	0	05:33:40
4	*.res.spectrum.com	13	2	2.7 KB	2	1	1	03:24:08
5	*.range81-135.btcentralplus.com	4	0	0.0 KB	0	0	0	01:10:32
6	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:01
7	unresolved	72	0	0.0 KB	0	0	0	24:00:41
TOTAL: 7		133	16	20.0 KB	9	1	1	38:56:42

NNRP auth users [Top 20]:

User	Conn
ohlandl	1
TOTAL: 1	1

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	4.604	1.330	0.000	24:00:41
a131033.upc-a.chello.nl	1.821	0.750	0.000	05:33:40
ip72-222-200-117.ph.ph.cox.net	1.158	0.219	0.000	04:47:33
066-188-082-215.res.spectrum.com	0.956	0.170	0.003	03:24:08
host81-135-1-153.range81-135.btcentralplus.com	0.251	0.069	0.000	01:10:32
ppp-2-84-162-19.home.otenet.gr	0.106	0.008	0.000	00:00:05
scan-141.security.ipip.net	0.057	0.016	0.000	00:00:01
TOTAL: 7	8.953	2.562	0.003	38:56:42

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	72
host81-135-1-153.range81-135.btcentralplus.com	4
scan-141.security.ipip.net	1
TOTAL: 3	77

NNRP unrecognized commands (by host):

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

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip72-222-200-117.ph.ph.cox.net	7	0
066-188-082-215.res.spectrum.com	5	0
host81-135-1-153.range81-135.btcentralplus.com	2	0
TOTAL: 3	14	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Nov 5 04:15:00 -- Nov 6 04:15:00