Daily Usenet report for csiph.com: Oct 10 04:15:00

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Oct 10 13:11:39 csiph nnrpd[56730]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Oct 10 13:11:39 csiph nnrpd[56730]: 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	17749	47.2%	4.6 MB	62.4%
innd	15318	40.8%	2.0 MB	27.4%
innfeed	3488	9.3%	649.5 KB	8.7%
nnrpd	983	2.6%	108.8 KB	1.5%
nocem	31	0.1%	3.5 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	37570	100.0%	7.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	285857	56.1%
Negative hits	203331	39.9%
Do not exist	17014	3.3%
Cache misses	3045	0.6%
TOTAL: 4	509247	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.864	0.0%	19	1.000	45.474	59.000
article cleanup	00:00:00.121	0.0%	16746	0.000	0.007	0.034
article logging	00:00:00.295	0.0%	17321	0.000	0.017	0.317
article parse	00:00:01.189	0.0%	52323	0.000	0.023	0.568
article write	00:00:13.661	0.0%	14522	0.141	0.941	42.451
artlog/artcncl	00:00:00.002	0.0%	19	0.000	0.105	0.200
artlog/artparse	00:00:00.017	0.0%	390	0.000	0.044	2.167
data move	00:00:00.144	0.0%	484016	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.109	0.0%	18	0.000	6.056	7.800
hishave/artcncl	00:00:00.105	0.0%	19	0.000	5.526	9.200
hishave/artparse	00:00:00.001	0.0%	369	0.000	0.003	0.033
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:13.701	0.0%	511838	0.001	0.027	0.238
history sync	00:00:00.837	0.0%	375	0.000	2.232	10.333
history write	00:00:17.818	0.0%	16763	0.033	1.063	10.204
hiswrite/artcncl	00:00:00.030	0.0%	1	30.000	30.000	30.000
hiswrite/artparse	00:00:00.692	0.0%	369	0.000	1.875	22.062
idle	23:57:58.258	99.8%	490028	84.393	176.068	496.540
nntp read	00:00:01.971	0.0%	492007	0.001	0.004	0.008
overview write	00:01:17.189	0.1%	14522	0.111	5.315	340.068
perl filter	00:00:24.391	0.0%	16742	1.148	1.457	2.294
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.211	0.0%	29074	0.000	0.007	0.032
TOTAL: 24:01:06.085	24:00:31.606	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.042	0.0%	24226	0.000	0.002	0.049
article prepare	00:00:00.002	0.0%	15628	0.000	0.000	0.011
article read	00:00:00.404	0.0%	1970	0.000	0.205	36.333
backlog stats	00:00:00.170	0.0%	326309	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.132	0.0%	254994	0.001	0.004	0.010
data write	00:00:01.472	0.0%	241597	0.001	0.006	0.012
idle	23:54:37.268	100.0%	326309	91.487	263.791	726.412
status file	00:00:00.188	0.0%	271	0.000	0.694	2.000
TOTAL: 23:54:46.826	23:54:40.678	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	50:19:55.918	99.9%	639	42.000	283561.687	418558.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.008	0.0%	1459	0.000	0.005	0.167
readart	00:00:00.212	0.0%	24	0.000	8.833	52.000
TOTAL: 50:23:55.272	50:19:56.138	99.9%	-	-	-	-

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	newsfeeds.xmission.com	2	13054	2992	10019	43	22%	23:59:27
2	csiph-out.news.xs4all.nl	591	16245	2539	12172	1534	15%	67:11:46
3	feeder.eternal-september.org	2	15475	1781	13632	62	11%	23:15:10
4	news-out.mixmin.net	3	14881	1752	12950	179	11%	24:08:19
5	news-out.aioe.org	3	13524	1693	11730	101	12%	24:09:27
6	csiph-out.feeder.erje.net	1440	71076	1017	69916	143	1%	118:46:44
7	newsfeed.fu-berlin.de	4	14059	847	13198	14	6%	24:32:40
8	csiph-out.news.weretis.net	3	30930	841	29999	90	2%	53:27:33
9	newsfeed.neva.ru	146	13345	414	12735	196	3%	72:23:23
10	news-out.neodome.net	1	15592	187	15403	2	1%	23:59:54
11	nntp.pasdenom.info	2	8498	155	8333	10	1%	23:58:47
12	csiph-out.feed.uzoreto.com	864	22938	121	22638	179	0%	71:11:47
13	news.fcku.it	15	1096	97	998	1	8%	21:39:01
14	csiph-out.news.tnetconsulting.net	5	23367	60	23299	8	0%	23:26:53
15	news.swapon.de	2	1298	46	1251	1	3%	23:18:14
16	news.freedyn.net	2	16952	25	16888	39	0%	23:59:42
17	news.samoylyk.net	2	13593	4	13589	0	0%	23:59:52
18	news.bbs.nz	2	14247	3	14244	0	0%	23:59:48
19	localhost	29	3	3	0	0	100%	00:00:04
20	xanadu-bbs.net	27	328	0	328	0	0%	19:25:24
TOTAL: 20		3145	320501	14577	303322	2602	4%	710:53:55

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	8.7 MB	0.0 KB	2.2 MB	10.8 MB	80%	3.7 KB
2	csiph-out.feeder.erje.net	8.4 MB	0.0 KB	2.4 MB	10.8 MB	78%	9.5 KB
3	csiph-out.news.xs4all.nl	6.3 MB	0.0 KB	14.4 MB	20.7 MB	30%	5.2 KB
4	feeder.eternal-september.org	4.9 MB	1.4 KB	585.9 KB	5.4 MB	89%	3.0 KB
5	news-out.aioe.org	4.6 MB	1.4 KB	340.7 KB	5.0 MB	93%	2.8 KB
6	news-out.mixmin.net	3.8 MB	1.3 KB	335.6 KB	4.2 MB	92%	2.2 KB
7	csiph-out.news.weretis.net	2.1 MB	0.0 KB	815.9 KB	2.9 MB	72%	3.2 KB
8	newsfeed.fu-berlin.de	1.9 MB	0.0 KB	47.0 KB	2.0 MB	97%	2.4 KB
9	newsfeed.neva.ru	1.2 MB	1.5 KB	515.5 KB	1.7 MB	69%	2.8 KB
10	csiph-out.feed.uzoreto.com	417.0 KB	0.0 KB	7.1 MB	7.5 MB	5%	25.6 KB
11	news-out.neodome.net	409.9 KB	0.0 KB	2.6 KB	412.5 KB	99%	2.2 KB
12	nntp.pasdenom.info	353.2 KB	0.0 KB	12.8 KB	366.0 KB	96%	2.2 KB
13	csiph-out.news.tnetconsulting.net	182.9 KB	0.0 KB	105.0 KB	287.9 KB	63%	4.2 KB
14	news.fcku.it	161.0 KB	0.0 KB	2.0 KB	163.0 KB	98%	1.7 KB
15	news.freedyn.net	152.3 KB	0.0 KB	557.3 KB	709.6 KB	21%	11.1 KB
16	news.swapon.de	110.3 KB	0.0 KB	80.3 KB	190.6 KB	57%	4.1 KB
17	news.samoylyk.net	47.7 KB	0.0 KB	0.0 KB	47.7 KB	100%	11.9 KB
18	news.bbs.nz	11.8 KB	0.0 KB	0.0 KB	11.8 KB	100%	3.9 KB
19	localhost	3.4 KB	0.0 KB	0.0 KB	3.4 KB	100%	1.1 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		43.7 MB	5.7 KB	29.4 MB	73.1 MB	59%	4.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 10 04:15:00 - 04:59:59	278	1.9%	0.10	854.2 KB	1.9%	0.32
Oct 10 05:00:00 - 05:59:59	320	2.2%	0.09	901.4 KB	2.0%	0.25
Oct 10 06:00:00 - 06:59:59	479	3.3%	0.13	2.4 MB	5.5%	0.68
Oct 10 07:00:00 - 07:59:59	705	4.9%	0.20	3.4 MB	7.7%	0.95
Oct 10 08:00:00 - 08:59:59	642	4.4%	0.18	2.3 MB	5.2%	0.64
Oct 10 09:00:00 - 09:59:59	780	5.4%	0.22	2.0 MB	4.5%	0.56
Oct 10 10:00:00 - 10:59:59	752	5.2%	0.21	1.8 MB	4.1%	0.51
Oct 10 11:00:00 - 11:59:59	783	5.4%	0.22	2.0 MB	4.6%	0.58
Oct 10 12:00:00 - 12:59:59	636	4.4%	0.18	1.7 MB	3.8%	0.48
Oct 10 13:00:00 - 13:59:59	870	6.0%	0.24	2.3 MB	5.4%	0.67
Oct 10 14:00:00 - 14:59:59	802	5.5%	0.22	2.2 MB	5.0%	0.63
Oct 10 15:00:00 - 15:59:59	918	6.3%	0.26	3.5 MB	8.0%	0.99
Oct 10 16:00:00 - 16:59:59	859	5.9%	0.24	2.5 MB	5.8%	0.72
Oct 10 17:00:00 - 17:59:59	817	5.6%	0.23	2.2 MB	5.1%	0.64
Oct 10 18:00:00 - 18:59:59	687	4.7%	0.19	1.7 MB	3.9%	0.48
Oct 10 19:00:00 - 19:59:59	642	4.4%	0.18	1.6 MB	3.6%	0.45
Oct 10 20:00:00 - 20:59:59	711	4.9%	0.20	2.1 MB	4.7%	0.59
Oct 10 21:00:00 - 21:59:59	671	4.6%	0.19	1.9 MB	4.4%	0.55
Oct 10 22:00:00 - 22:59:59	499	3.4%	0.14	1.4 MB	3.1%	0.39
Oct 10 23:00:00 - 23:59:59	364	2.5%	0.10	1012.3 KB	2.3%	0.28
Oct 11 00:00:00 - 00:59:59	284	2.0%	0.08	857.5 KB	1.9%	0.24
Oct 11 01:00:00 - 01:59:59	333	2.3%	0.09	1.1 MB	2.6%	0.32
Oct 11 02:00:00 - 02:59:59	268	1.8%	0.07	658.6 KB	1.5%	0.18
Oct 11 03:00:00 - 03:59:59	334	2.3%	0.09	1.2 MB	2.7%	0.34
Oct 11 04:00:00 - 04:15:00	101	0.7%	0.11	259.9 KB	0.6%	0.29
TOTAL: 24:00:00	14535	100.0%	0.17	43.7 MB	100.0%	0.52

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.news.xs4all.nl	1588	82	0	1	1505
2	newsfeed.fu-berlin.de	410	12	0	0	398
3	news-out.mixmin.net	238	14	1	0	223
4	newsfeed.neva.ru	196	60	1	0	135
5	csiph-out.feed.uzoreto.com	179	7	0	0	172
6	news-out.aioe.org	154	90	1	0	63
7	csiph-out.feeder.erje.net	129	40	0	0	89
8	csiph-out.news.weretis.net	104	39	0	0	65
9	feeder.eternal-september.org	63	35	1	0	27
10	newsfeeds.xmission.com	52	5	0	0	47
11	news.freedyn.net	39	0	0	0	39
12	news.swapon.de	15	0	0	0	15
13	csiph-out.news.tnetconsulting.net	13	12	0	0	1
14	nntp.pasdenom.info	10	0	0	0	10
15	news-out.neodome.net	4	0	0	0	4
16	news.fcku.it	1	1	0	0	0
TOTAL: 16		3195	397	4	1	2793

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	59
alt.bbs.synchronet	52
alt.computer.workshop	47
alt.windows7.general	27
alt.politics	18
mozilla.support.thunderbird	15
alt.binaries.pictures.purebeauty	15
free.it.religioni.scientology	11
scinet.stats	10
spline.eisfair	10
alt.survival	9
alt.job	9
at.jobs	8
alt.philosophy	8
mozilla.support.seamonkey	7
aus.jobs	7
alt.religion	6
alt.hack.nl	6
lada.movies	5
att.jobs	5
TOTAL: 54	397

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)	1483
EMP (phl)	167
Too many newsgroups	89
0	38
HTML Multipart	19
EMP (phn path)	10
Scoring filter	10
Binary: misplaced binary	4
EMP (phn nph)	2
Too many newsgroups (low_xpost)	2
HTML post	1
TOTAL: 11	1825

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	50
	csiph-out.feed.uzoreto.com	1
	csiph-out.news.xs4all.nl	1
	TOTAL: 3	52
Huge articles
	csiph-out.news.xs4all.nl	164
	csiph-out.feed.uzoreto.com	97
	newsfeeds.xmission.com	44
	csiph-out.feeder.erje.net	44
	news.freedyn.net	9
	feeder.eternal-september.org	8
	csiph-out.news.weretis.net	6
	news.swapon.de	1
	TOTAL: 8	373
Including strange strings
	newsfeed.fu-berlin.de	396
	news-out.mixmin.net	59
	news-out.aioe.org	48
	csiph-out.news.xs4all.nl	44
	news.swapon.de	14
	csiph-out.news.weretis.net	5
	newsfeeds.xmission.com	3
	news-out.neodome.net	2
	feeder.eternal-september.org	2
	csiph-out.feeder.erje.net	2
	TOTAL: 11	576
No colon-space in header
	csiph-out.news.xs4all.nl	20
	csiph-out.feeder.erje.net	1
	TOTAL: 2	21
TOTAL: 4		1022

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	17149	1729	12762	10	1	10%	23:59:56
2	fu-berlin.de	13496	688	12808	0	0	5%	23:59:55
3	news.samoylyk.net	14839	671	13852	7	1	4%	23:59:53
4	news.bbs.nz	26994	315	13479	30	1	1%	23:59:54
5	neva.ru	13422	87	13125	39	0	0%	23:58:58
6	news.freedyn.net	14303	83	13982	0	0	0%	23:59:55
7	tnet	14203	52	13781	0	9728	0%	23:59:56
8	newsfeed.xs4all.nl	12274	34	11821	0	0	0%	23:59:55
9	xmission	13525	22	10692	10	0	0%	23:59:54
10	news.mixmin.net	12071	10	11850	0	0	0%	23:59:55
11	usenet.pasdenom.info	14097	9	13666	1	6	0%	23:59:55
12	neodome.net	14698	8	14316	0	0	0%	23:59:45
13	erje	13429	5	13184	69	0	0%	23:59:55
14	news.uzoreto.com	13503	5	13306	8	0	0%	23:59:51
15	aioe	9909	5	9591	0	0	0%	23:59:47
16	eternal-september	12148	5	11926	0	0	0%	23:59:55
17	weretis.net	13038	5	12857	0	0	0%	23:59:55
18	news.swapon.de	1272	4	1257	0	0	0%	23:49:48
19	news.fcku.it	952	0	952	0	0	0%	21:08:06
TOTAL: 19		245322	3737	219207	174	9737	1%	452:55:08

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	10.4 MB	40.6 KB	10.5 MB	0.1 KB/s	6.2 KB	23:59:56
2	fu-berlin.de	7.6 MB	0.0 KB	7.6 MB	0.1 KB/s	11.3 KB	23:59:55
3	news.samoylyk.net	7.5 MB	55.3 KB	7.6 MB	0.1 KB/s	11.4 KB	23:59:53
4	news.freedyn.net	1.3 MB	0.0 KB	1.3 MB	0.0 KB/s	16.1 KB	23:59:55
5	news.bbs.nz	796.5 KB	67.1 KB	863.5 KB	0.0 KB/s	2.5 KB	23:59:54
6	neva.ru	164.7 KB	86.0 KB	250.7 KB	0.0 KB/s	2.0 KB	23:58:58
7	tnet	110.2 KB	0.0 KB	110.2 KB	0.0 KB/s	2.1 KB	23:59:56
8	newsfeed.xs4all.nl	102.4 KB	0.0 KB	102.4 KB	0.0 KB/s	3.0 KB	23:59:55
9	xmission	84.1 KB	21.8 KB	106.0 KB	0.0 KB/s	3.3 KB	23:59:54
10	news.mixmin.net	45.8 KB	0.0 KB	45.8 KB	0.0 KB/s	4.6 KB	23:59:55
11	weretis.net	14.0 KB	0.0 KB	14.0 KB	0.0 KB/s	2.8 KB	23:59:55
12	neodome.net	12.6 KB	0.0 KB	12.6 KB	0.0 KB/s	1.6 KB	23:59:45
13	usenet.pasdenom.info	9.9 KB	2.2 KB	12.2 KB	0.0 KB/s	1.2 KB	23:59:55
14	eternal-september	8.3 KB	0.0 KB	8.3 KB	0.0 KB/s	1.7 KB	23:59:55
15	erje	8.2 KB	173.9 KB	182.1 KB	0.0 KB/s	2.5 KB	23:59:55
16	aioe	6.8 KB	0.0 KB	6.8 KB	0.0 KB/s	1.4 KB	23:59:47
17	news.swapon.de	5.3 KB	0.0 KB	5.3 KB	0.0 KB/s	1.3 KB	23:49:48
18	news.uzoreto.com	5.0 KB	76.4 KB	81.4 KB	0.0 KB/s	6.3 KB	23:59:51
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:08:06
TOTAL: 19		28.2 MB	523.4 KB	28.7 MB	0.0 KB/s	7.5 KB	452:55:08

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	024-178-046-028.res.spectrum.com	9	16	29.6 KB	7	3	3	01:43:22
2	a131033.upc-a.chello.nl	73	8	12.2 KB	3	0	0	23:30:26
TOTAL: 2		82	24	41.8 KB	10	3	3	50:23:54

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.res.spectrum.com	9	16	29.6 KB	7	3	3	01:43:22
2	*.upc-a.chello.nl	73	8	12.2 KB	3	0	0	23:30:26
3	*.net.upcbroadband.cz	13	0	0.0 KB	0	0	0	04:15:50
4	*.ch1.censys-scanner.com	2	0	0.0 KB	0	0	0	00:00:00
5	*.ph.ph.cox.net	4	0	0.0 KB	0	0	0	02:00:14
6	unresolved	58	0	0.0 KB	0	0	0	18:53:58
TOTAL: 6		159	24	41.8 KB	10	3	3	50:23:54

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
a131033.upc-a.chello.nl	4.929	1.016	0.000	23:30:26
91.187.33.87	3.921	0.837	0.000	18:53:48
ip-78-102-198-192.net.upcbroadband.cz	0.848	0.243	0.000	04:15:50
ip72-222-200-117.ph.ph.cox.net	0.422	0.080	0.000	02:00:14
024-178-046-028.res.spectrum.com	0.884	0.169	0.021	01:43:22
192.241.217.150	0.048	0.032	0.000	00:00:09
scanner-04.ch1.censys-scanner.com	0.131	0.026	0.000	00:00:00
TOTAL: 7	11.183	2.403	0.021	50:23:54

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	57
ip-78-102-198-192.net.upcbroadband.cz	13
ip72-222-200-117.ph.ph.cox.net	4
scanner-04.ch1.censys-scanner.com	2
192.241.217.150	1
TOTAL: 5	77

NNRP unrecognized commands (by host):

System	Conn
scanner-04.ch1.censys-scanner.com	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
EHLO www.censys.io	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip72-222-200-117.ph.ph.cox.net	4	0
024-178-046-028.res.spectrum.com	3	2
TOTAL: 2	7	3

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Oct 10 04:15:00 -- Oct 11 04:15:00