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

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Feb 5 16:51:24 csiph nnrpd[64865]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Feb 5 16:51:24 csiph nnrpd[64865]: 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	35926	49.7%	6.9 MB	59.8%
innd	30545	42.3%	3.8 MB	32.8%
innfeed	3607	5.0%	630.7 KB	5.3%
nnrpd	2135	3.0%	238.9 KB	2.0%
nocem	45	0.1%	5.4 KB	0.0%
controlchan	1	0.0%	0.0 KB	0.0%
TOTAL: 6	72259	100.0%	11.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	292840	49.7%
Negative hits	261004	44.3%
Do not exist	35255	6.0%
Cache misses	386	0.1%
TOTAL: 4	589485	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.135	0.0%	27	0.000	42.037	112.000
article cleanup	00:00:00.197	0.0%	30993	0.000	0.006	0.031
article logging	00:00:03.663	0.0%	31502	0.000	0.116	3.333
article parse	00:00:17.083	0.0%	981126	0.000	0.017	0.466
article write	00:05:03.605	0.4%	15519	0.491	19.563	213.846
artlog/artcncl	00:00:00.000	0.0%	27	0.000	0.000	0.000
artlog/artparse	00:00:00.189	0.0%	4417	0.000	0.043	9.286
data move	00:00:01.626	0.0%	1473157	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.020	0.0%	27	0.000	0.741	6.000
hishave/artcncl	00:00:00.001	0.0%	27	0.000	0.037	0.100
hishave/artparse	00:00:00.008	0.0%	4414	0.000	0.002	0.007
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:58.701	0.2%	588612	0.003	0.304	1.893
history sync	00:00:03.612	0.0%	376	0.000	9.606	89.667
history write	00:03:36.288	0.3%	30988	0.546	6.980	27.596
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:07.925	0.0%	4413	0.000	1.796	88.000
idle	23:35:43.820	98.2%	1420550	2.239	59.796	399.530
nntp read	00:00:06.176	0.0%	1458738	0.002	0.004	0.010
overview write	00:11:25.524	0.8%	15519	0.205	44.173	4318.271
perl filter	00:00:34.571	0.0%	30984	0.828	1.116	2.932
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.296	0.0%	31083	0.000	0.010	0.038
TOTAL: 24:01:01.505	24:00:04.440	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.043	0.0%	15648	0.000	0.003	0.023
article prepare	00:00:00.002	0.0%	10048	0.000	0.000	0.023
article read	00:00:00.844	0.0%	1148	0.000	0.735	87.000
backlog stats	00:00:00.189	0.0%	337684	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.276	0.0%	268459	0.000	0.005	0.010
data write	00:00:01.650	0.0%	254447	0.002	0.006	0.013
idle	23:55:20.404	100.0%	337684	137.320	255.033	834.317
status file	00:00:02.185	0.0%	320	0.000	6.828	278.000
TOTAL: 23:55:34.747	23:55:26.593	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.035	0.0%	6	5.833	5.833	5.833
idle	69:31:01.889	95.7%	202566	0.000	1235.459	600106.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:15.884	0.0%	852742	0.000	0.019	0.333
readart	02:43:30.544	3.8%	197829	0.000	49.591	99.500
TOTAL: 72:37:18.271	72:14:48.352	99.5%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	feeder.eternal-september.org	23	10365	2505	4346	3514	24%	34:22:46
2	newsfeeds.xmission.com	2	14694	2184	12497	13	14%	24:14:26
3	csiph-out.news.xs4all.nl	0	4909	1939	2281	689	39%	65:53:01
4	news.freedyn.net	2	18411	1808	15261	1342	9%	24:09:06
5	news-out.aioe.org	2	14653	1486	13127	40	10%	24:09:56
6	news-out.mixmin.net	2	16247	1126	14941	180	6%	24:08:55
7	csiph-out.news.weretis.net	2	38177	1082	36766	329	2%	56:07:27
8	newsfeed.fu-berlin.de	22	27884	870	13913	13101	3%	27:10:19
9	csiph-out.feeder.erje.net	1444	75004	732	74045	227	0%	118:42:33
10	newsfeed.neva.ru	151	12917	596	12050	271	4%	71:33:31
11	csiph-out.feed.uzoreto.com	864	24127	447	23368	312	1%	71:16:30
12	news.fcku.it	13	1812	274	1538	0	15%	21:58:26
13	news-out.neodome.net	3	16548	172	16339	37	1%	24:08:59
14	nntp.pasdenom.info	2	10100	104	9995	1	1%	23:58:47
15	news.swapon.de	8	1347	80	1267	0	5%	22:34:03
16	csiph-out.news.tnetconsulting.net	1	15847	73	15768	6	0%	23:59:57
17	news.bbs.nz	1	15704	14	15688	2	0%	23:59:53
18	news.samoylyk.net	1	14967	8	14958	1	0%	23:59:55
19	xanadu-bbs.net	34	378	1	377	0	0%	18:35:22
20	localhost	26	0	0	0	0	0%	00:00:01
TOTAL: 20		2603	334091	15501	298525	20065	4%	725:03:53

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	7.4 MB	0.0 KB	2.1 GB	2.1 GB	0%	365.4 KB
2	csiph-out.feeder.erje.net	6.4 MB	0.0 KB	2.1 MB	8.5 MB	75%	9.1 KB
3	newsfeeds.xmission.com	6.3 MB	0.0 KB	400.0 KB	6.7 MB	94%	3.1 KB
4	csiph-out.news.xs4all.nl	5.5 MB	0.0 KB	6.0 MB	11.6 MB	47%	4.5 KB
5	news.freedyn.net	4.6 MB	0.0 KB	94.0 MB	98.6 MB	4%	32.0 KB
6	news-out.aioe.org	3.4 MB	16.6 KB	95.9 KB	3.5 MB	96%	2.4 KB
7	csiph-out.news.weretis.net	3.2 MB	18.2 KB	2.3 MB	5.5 MB	57%	4.0 KB
8	news-out.mixmin.net	2.6 MB	12.9 KB	491.1 KB	3.1 MB	84%	2.5 KB
9	newsfeed.fu-berlin.de	1.9 MB	0.0 KB	30.9 MB	32.8 MB	5%	2.4 KB
10	csiph-out.feed.uzoreto.com	1.7 MB	6.0 KB	5.8 MB	7.5 MB	22%	10.2 KB
11	newsfeed.neva.ru	1.5 MB	0.0 KB	1.1 MB	2.6 MB	58%	3.1 KB
12	news-out.neodome.net	493.0 KB	19.0 KB	1.5 MB	2.0 MB	24%	9.8 KB
13	news.fcku.it	455.1 KB	0.0 KB	0.0 KB	455.1 KB	100%	1.7 KB
14	nntp.pasdenom.info	275.5 KB	6.0 KB	6.0 KB	287.6 KB	95%	2.7 KB
15	csiph-out.news.tnetconsulting.net	252.2 KB	0.0 KB	34.0 KB	286.2 KB	88%	3.6 KB
16	news.swapon.de	151.6 KB	0.0 KB	0.0 KB	151.6 KB	100%	1.9 KB
17	news.bbs.nz	41.8 KB	0.0 KB	2.6 KB	44.3 KB	94%	2.8 KB
18	news.samoylyk.net	41.0 KB	12.9 KB	12.9 KB	66.9 KB	61%	7.4 KB
19	xanadu-bbs.net	6.3 KB	0.0 KB	0.0 KB	6.3 KB	100%	6.3 KB
20	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		46.4 MB	91.7 KB	2.2 GB	2.3 GB	1%	67.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 5 04:15:00 - 04:59:59	225	1.4%	0.08	704.0 KB	1.5%	0.26
Feb 5 05:00:00 - 05:59:59	354	2.3%	0.10	2.2 MB	4.7%	0.62
Feb 5 06:00:00 - 06:59:59	354	2.3%	0.10	1.4 MB	3.1%	0.41
Feb 5 07:00:00 - 07:59:59	457	2.9%	0.13	1.1 MB	2.4%	0.32
Feb 5 08:00:00 - 08:59:59	573	3.7%	0.16	1.4 MB	3.1%	0.41
Feb 5 09:00:00 - 09:59:59	830	5.3%	0.23	2.3 MB	4.9%	0.65
Feb 5 10:00:00 - 10:59:59	769	5.0%	0.21	2.0 MB	4.4%	0.58
Feb 5 11:00:00 - 11:59:59	791	5.1%	0.22	2.1 MB	4.5%	0.59
Feb 5 12:00:00 - 12:59:59	732	4.7%	0.20	2.1 MB	4.6%	0.60
Feb 5 13:00:00 - 13:59:59	835	5.4%	0.23	2.0 MB	4.2%	0.56
Feb 5 14:00:00 - 14:59:59	846	5.4%	0.23	2.5 MB	5.3%	0.70
Feb 5 15:00:00 - 15:59:59	819	5.3%	0.23	2.4 MB	5.1%	0.67
Feb 5 16:00:00 - 16:59:59	1017	6.6%	0.28	3.4 MB	7.4%	0.97
Feb 5 17:00:00 - 17:59:59	1018	6.6%	0.28	3.5 MB	7.6%	1.00
Feb 5 18:00:00 - 18:59:59	859	5.5%	0.24	2.7 MB	5.8%	0.77
Feb 5 19:00:00 - 19:59:59	811	5.2%	0.23	2.1 MB	4.5%	0.60
Feb 5 20:00:00 - 20:59:59	694	4.5%	0.19	1.8 MB	4.0%	0.53
Feb 5 21:00:00 - 21:59:59	802	5.2%	0.22	2.4 MB	5.1%	0.67
Feb 5 22:00:00 - 22:59:59	643	4.1%	0.18	1.6 MB	3.4%	0.45
Feb 5 23:00:00 - 23:59:59	557	3.6%	0.15	1.9 MB	4.0%	0.53
Feb 6 00:00:00 - 00:59:59	421	2.7%	0.12	1.3 MB	2.9%	0.38
Feb 6 01:00:00 - 01:59:59	334	2.2%	0.09	1.1 MB	2.4%	0.31
Feb 6 02:00:00 - 02:59:59	333	2.1%	0.09	1.0 MB	2.2%	0.30
Feb 6 03:00:00 - 03:59:59	381	2.5%	0.11	1.2 MB	2.5%	0.33
Feb 6 04:00:00 - 04:15:00	69	0.4%	0.08	212.1 KB	0.4%	0.24
TOTAL: 24:00:00	15524	100.0%	0.18	46.4 MB	100.0%	0.55

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	TooOld	Other
1	newsfeed.fu-berlin.de	13460	7	0	0	0	13453
2	feeder.eternal-september.org	3513	8	0	0	1	3504
3	news.freedyn.net	1343	5	0	0	0	1338
4	csiph-out.news.xs4all.nl	748	53	0	0	0	695
5	csiph-out.feed.uzoreto.com	314	9	1	1	0	303
6	newsfeed.neva.ru	285	56	0	0	0	229
7	csiph-out.news.weretis.net	250	57	1	0	0	192
8	news-out.mixmin.net	181	8	1	0	0	172
9	csiph-out.feeder.erje.net	123	49	0	1	0	73
10	news-out.aioe.org	42	33	1	0	0	8
11	news-out.neodome.net	40	2	2	0	0	36
12	news.swapon.de	34	0	0	0	0	34
13	newsfeeds.xmission.com	32	8	0	0	0	24
14	csiph-out.news.tnetconsulting.net	6	2	0	0	0	4
15	news.bbs.nz	2	2	0	0	0	0
16	nntp.pasdenom.info	1	0	1	0	0	0
17	news.samoylyk.net	1	0	1	0	0	0
TOTAL: 17		20375	299	8	2	1	20065

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	49
alt.computer.workshop	29
scinet.stats	27
mozilla.support.seamonkey	23
alt.binaries.pictures.purebeauty	17
scinet.sysop	11
mozilla.support.firefox	10
alt.politics	10
lada.auto	8
alt.job	8
alt.bbs.synchronet	8
scinet.general.chatter	7
it-alt.sport.calcio.juventus	6
mozilla.support.thunderbird	6
mozilla.general	5
alt.binaries.ebook	4
it-alt.comp.software.openoffice	4
at.jobs	4
scinet.bbs.mods	4
alt.binaries.ebooks	4
TOTAL: 62	299

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
uk.legal.moderated	2
TOTAL: 1	2

INND Perl filter [Top 20]:

Reason	Count
Binary: misplaced binary	13061
EMP (md5)	1400
EMP (phn path)	225
Too many newsgroups	188
EMP (phl)	163
0	54
Too many newsgroups (meow)	17
HTML Multipart	15
EMP (phn nph)	10
Scoring filter	6
TOTAL: 10	15139

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	feeder.eternal-september.org	3453
	news.freedyn.net	806
	csiph-out.feeder.erje.net	37
	csiph-out.feed.uzoreto.com	35
	csiph-out.news.xs4all.nl	29
	news-out.neodome.net	27
	csiph-out.news.weretis.net	16
	newsfeeds.xmission.com	5
	csiph-out.news.tnetconsulting.net	4
	news-out.mixmin.net	2
	TOTAL: 10	4414
Including strange strings
	newsfeed.fu-berlin.de	366
	csiph-out.news.xs4all.nl	42
	news.swapon.de	34
	newsfeeds.xmission.com	19
	csiph-out.news.weretis.net	14
	newsfeed.neva.ru	14
	csiph-out.feeder.erje.net	8
	csiph-out.feed.uzoreto.com	6
	news-out.mixmin.net	4
	feeder.eternal-september.org	1
	TOTAL: 11	509
No colon-space in header
	csiph-out.feed.uzoreto.com	1
	csiph-out.feeder.erje.net	1
	csiph-out.news.xs4all.nl	1
	TOTAL: 3	3
TOTAL: 4		4971

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	17839	835	14689	0	0	4%	23:59:34
2	news.samoylyk.net	15921	537	14973	5	0	3%	23:59:45
3	fu-berlin.de	14389	533	13856	0	0	3%	23:59:51
4	news.bbs.nz	28092	140	14802	18	0	0%	23:59:50
5	erje	15634	67	14410	109	0	0%	23:59:55
6	neva.ru	14114	54	13888	47	0	0%	23:59:51
7	newsfeed.xs4all.nl	14117	40	13303	0	116	0%	23:59:54
8	xmission	15140	37	12650	7	0	0%	23:59:52
9	tnet	15866	9	14962	0	0	0%	23:59:51
10	news.freedyn.net	13435	6	13174	26	0	0%	23:59:55
11	usenet.pasdenom.info	15193	3	14842	22	11	0%	23:59:55
12	aioe	12780	2	12492	0	0	0%	23:59:55
13	neodome.net	15448	2	15134	0	0	0%	23:51:18
14	news.uzoreto.com	14608	2	14413	14	0	0%	23:59:55
15	news.mixmin.net	13741	1	13545	0	0	0%	23:59:55
16	news.swapon.de	1407	0	1402	0	0	0%	23:12:56
17	news.fcku.it	1433	0	1432	0	0	0%	21:29:23
18	eternal-september	0	0	0	0	12191	0%	23:59:55
19	weretis.net	13983	0	13831	0	0	0%	23:59:55
TOTAL: 19		253140	2268	227798	248	12318	0%	452:31:25

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	6.6 MB	0.0 KB	6.6 MB	0.1 KB/s	8.1 KB	23:59:34
2	news.samoylyk.net	5.8 MB	7.3 KB	5.8 MB	0.1 KB/s	11.0 KB	23:59:45
3	fu-berlin.de	5.8 MB	0.0 KB	5.8 MB	0.1 KB/s	11.1 KB	23:59:51
4	news.bbs.nz	312.3 KB	67.9 KB	380.2 KB	0.0 KB/s	2.4 KB	23:59:50
5	erje	158.0 KB	349.3 KB	507.2 KB	0.0 KB/s	2.9 KB	23:59:55
6	neva.ru	113.8 KB	109.1 KB	223.0 KB	0.0 KB/s	2.2 KB	23:59:51
7	newsfeed.xs4all.nl	87.4 KB	0.0 KB	87.4 KB	0.0 KB/s	2.2 KB	23:59:54
8	xmission	82.8 KB	10.8 KB	93.6 KB	0.0 KB/s	2.1 KB	23:59:52
9	news.uzoreto.com	34.1 KB	54.8 KB	88.8 KB	0.0 KB/s	5.6 KB	23:59:55
10	tnet	20.1 KB	0.0 KB	20.1 KB	0.0 KB/s	2.2 KB	23:59:51
11	news.freedyn.net	19.1 KB	368.2 KB	387.3 KB	0.0 KB/s	12.1 KB	23:59:55
12	usenet.pasdenom.info	7.6 KB	42.4 KB	50.0 KB	0.0 KB/s	2.0 KB	23:59:55
13	aioe	3.1 KB	0.0 KB	3.1 KB	0.0 KB/s	1.5 KB	23:59:55
14	neodome.net	2.2 KB	0.0 KB	2.2 KB	0.0 KB/s	1.1 KB	23:51:18
15	news.mixmin.net	0.6 KB	0.0 KB	0.6 KB	0.0 KB/s	0.6 KB	23:59:55
16	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:12:56
17	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:29:23
18	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:55
19	weretis.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:55
TOTAL: 19		19.0 MB	1009.7 KB	20.0 MB	0.0 KB/s	8.1 KB	452:31:25

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Rej	Elapsed
1	p578e8829.dip0.t-ipconnect.de	6	32	58.8 KB	32	2	01:51:53
2	a131033.upc-a.chello.nl	59	5	7.6 KB	3	0	10:40:17
3	91.187.33.87	288	4	4.8 KB	4	0	31:44:46
TOTAL: 3		353	197643	446.3 MB	40	2	72:37:15

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Rej	Elapsed
1	unresolved	289	197606	446.3 MB	5	0	59:34:21
2	*.dip0.t-ipconnect.de	6	32	58.8 KB	32	2	01:51:53
3	*.upc-a.chello.nl	59	5	7.6 KB	3	0	10:40:17
4	*.starnet.cz	1	0	0.0 KB	0	0	00:30:01
5	*.range31-49.btcentralplus.com	1	0	0.0 KB	0	0	00:00:41
TOTAL: 5		356	197643	446.3 MB	40	2	72:37:15

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
91.187.33.87	18.337	4.393	31:44:46
212.90.61.180	24.013	44.425	27:49:24
a131033.upc-a.chello.nl	3.858	0.826	10:40:17
p578e8829.dip0.t-ipconnect.de	0.566	0.152	01:51:53
nat-147.starnet.cz	0.102	0.017	00:30:01
host31-49-229-214.range31-49.btcentralplus.com	0.069	0.008	00:00:41
192.241.212.24	0.066	0.008	00:00:09
TOTAL: 7	47.011	49.829	72:37:15

Curious NNRP server explorers [Top 20]:

System	Conn
nat-147.starnet.cz	1
host31-49-229-214.range31-49.btcentralplus.com	1
192.241.212.24	1
TOTAL: 3	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
p578e8829.dip0.t-ipconnect.de	3	0
91.187.33.87	2	0
nat-147.starnet.cz	1	0
TOTAL: 3	6	0

Newsgroup request counts (by category):

Category		Count	Pct
1	alt	73889	99.9%
2	comp	42	0.1%
TOTAL: 2		73931	100.0%

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

Newsgroup		Count
1	alt.humor.puns	73889
2	comp.sys.ibm.ps2.hardware	42
TOTAL: 2		73931

Daily Usenet report for csiph.com

Feb 5 04:15:00 -- Feb 6 04:15:00