Daily Usenet report for csiph.com: Dec 28 04:15:00

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Dec 28 16:46:49 csiph nnrpd[36891]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 28 16:46:49 csiph nnrpd[36891]: 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	18482	46.4%	4.7 MB	62.1%
innd	15519	39.0%	2.0 MB	26.7%
innfeed	3661	9.2%	631.2 KB	8.1%
nnrpd	2139	5.4%	233.9 KB	3.0%
nocem	30	0.1%	3.5 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	39832	100.0%	7.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	273104	52.2%
Negative hits	231978	44.3%
Do not exist	17889	3.4%
Cache misses	332	0.1%
TOTAL: 4	523303	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.529	0.0%	34	0.000	15.559	36.222
article cleanup	00:00:00.114	0.0%	17569	0.000	0.006	0.038
article logging	00:00:00.493	0.0%	18071	0.000	0.027	0.803
article parse	00:00:01.946	0.0%	58964	0.000	0.033	0.304
article write	00:00:33.795	0.0%	14979	0.106	2.256	89.015
artlog/artcncl	00:00:00.040	0.0%	34	0.000	1.176	6.333
artlog/artparse	00:00:00.006	0.0%	404	0.000	0.015	0.250
data move	00:00:00.177	0.0%	492431	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.027	0.0%	34	0.000	0.794	10.000
hishave/artcncl	00:00:00.019	0.0%	34	0.000	0.559	15.000
hishave/artparse	00:00:00.000	0.0%	391	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:03.574	0.1%	525336	0.002	0.121	0.628
history sync	00:00:01.114	0.0%	371	0.000	3.003	57.667
history write	00:01:07.218	0.1%	17580	0.545	3.824	14.752
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:01.393	0.0%	391	0.000	3.563	17.000
idle	23:53:35.042	99.5%	492266	91.462	174.733	543.378
nntp read	00:00:02.078	0.0%	500593	0.001	0.004	0.010
overview write	00:03:25.911	0.2%	14979	0.133	13.747	3393.032
perl filter	00:00:26.646	0.0%	17567	1.101	1.517	2.831
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.223	0.0%	29988	0.000	0.007	0.029
TOTAL: 24:00:59.486	24:00:20.345	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.036	0.0%	15101	0.000	0.002	0.023
article prepare	00:00:00.001	0.0%	17156	0.000	0.000	0.002
article read	00:00:00.309	0.0%	2064	0.000	0.150	45.000
backlog stats	00:00:00.205	0.0%	321596	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.096	0.0%	246810	0.000	0.004	0.010
data write	00:00:01.422	0.0%	234233	0.001	0.006	0.014
idle	23:54:46.599	100.0%	321596	151.761	267.686	850.399
status file	00:00:00.365	0.0%	351	0.000	1.040	35.000
TOTAL: 23:54:58.316	23:54:50.033	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	55:40:33.133	99.5%	1152	80.000	173987.095	417242.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:02.935	0.0%	2621	0.000	1.120	56.212
readart	00:00:00.545	0.0%	29	0.000	18.793	42.000
TOTAL: 55:56:56.016	55:40:36.613	99.5%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.xs4all.nl	522	17048	3307	12478	1263	19%	67:45:03
2	feeder.eternal-september.org	15	4785	2216	2496	73	46%	26:47:14
3	newsfeeds.xmission.com	3	14326	1824	12473	29	12%	25:21:27
4	news-out.aioe.org	3	14580	1396	13123	61	9%	24:08:49
5	news-out.mixmin.net	1	15961	1231	14463	267	7%	23:59:47
6	csiph-out.news.weretis.net	3	40982	1138	39514	330	2%	62:36:41
7	newsfeed.fu-berlin.de	18	13807	903	12740	164	6%	24:45:24
8	csiph-out.feeder.erje.net	1440	71272	869	70028	375	1%	118:46:04
9	newsfeed.neva.ru	154	11381	702	10475	204	6%	71:29:11
10	csiph-out.feed.uzoreto.com	864	22796	486	21937	373	2%	71:12:06
11	news-out.neodome.net	2	16037	254	15752	31	1%	23:59:52
12	nntp.pasdenom.info	2	9660	229	9421	10	2%	23:58:09
13	news.fcku.it	16	1261	171	1089	1	13%	21:44:29
14	news.swapon.de	8	1440	59	1381	0	4%	24:23:32
15	csiph-out.news.tnetconsulting.net	2	13830	28	13795	7	0%	23:59:44
16	news.freedyn.net	1	17124	17	17071	36	0%	23:59:56
17	news.bbs.nz	2	13441	12	13429	0	0%	23:59:52
18	localhost	34	8	8	0	0	100%	00:00:00
19	xanadu-bbs.net	39	356	1	355	0	0%	20:25:25
TOTAL: 19		3129	300095	14851	282020	3224	4%	703:22:45

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.xs4all.nl	9.5 MB	0.0 KB	13.4 MB	22.9 MB	41%	5.1 KB
2	csiph-out.feeder.erje.net	7.7 MB	0.0 KB	2.3 MB	10.0 MB	76%	8.2 KB
3	feeder.eternal-september.org	6.6 MB	0.0 KB	577.4 KB	7.1 MB	92%	3.2 KB
4	newsfeeds.xmission.com	5.9 MB	0.0 KB	2.2 MB	8.1 MB	72%	4.5 KB
5	news-out.aioe.org	3.7 MB	0.0 KB	134.3 KB	3.9 MB	96%	2.7 KB
6	news-out.mixmin.net	3.7 MB	0.0 KB	600.8 KB	4.3 MB	86%	2.9 KB
7	csiph-out.news.weretis.net	3.3 MB	0.0 KB	3.0 MB	6.3 MB	52%	4.4 KB
8	newsfeed.fu-berlin.de	2.2 MB	0.0 KB	434.1 KB	2.6 MB	83%	2.5 KB
9	newsfeed.neva.ru	2.0 MB	1.4 KB	556.5 KB	2.5 MB	78%	2.8 KB
10	csiph-out.feed.uzoreto.com	1.7 MB	0.0 KB	10.1 MB	11.8 MB	14%	14.1 KB
11	news-out.neodome.net	1.1 MB	0.0 KB	1010.2 KB	2.1 MB	53%	7.5 KB
12	nntp.pasdenom.info	529.8 KB	0.0 KB	14.6 KB	544.5 KB	97%	2.3 KB
13	news.fcku.it	316.3 KB	0.0 KB	3.3 KB	319.7 KB	98%	1.9 KB
14	news.swapon.de	182.4 KB	0.0 KB	0.0 KB	182.4 KB	100%	3.1 KB
15	news.freedyn.net	91.2 KB	0.0 KB	2.1 MB	2.2 MB	4%	41.7 KB
16	csiph-out.news.tnetconsulting.net	80.1 KB	0.0 KB	15.5 KB	95.7 KB	83%	2.7 KB
17	news.bbs.nz	18.8 KB	0.0 KB	0.0 KB	18.8 KB	100%	1.6 KB
18	localhost	12.5 KB	0.0 KB	0.0 KB	12.5 KB	100%	1.6 KB
19	xanadu-bbs.net	2.1 KB	0.0 KB	0.0 KB	2.1 KB	100%	2.1 KB
TOTAL: 19		48.6 MB	1.4 KB	36.4 MB	85.0 MB	57%	4.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 28 04:15:00 - 04:59:59	158	1.1%	0.06	645.4 KB	1.3%	0.24
Dec 28 05:00:00 - 05:59:59	393	2.6%	0.11	1.1 MB	2.1%	0.30
Dec 28 06:00:00 - 06:59:59	419	2.8%	0.12	1.9 MB	3.8%	0.54
Dec 28 07:00:00 - 07:59:59	374	2.5%	0.10	1.1 MB	2.1%	0.30
Dec 28 08:00:00 - 08:59:59	462	3.1%	0.13	1.3 MB	2.7%	0.38
Dec 28 09:00:00 - 09:59:59	799	5.3%	0.22	3.5 MB	7.2%	1.00
Dec 28 10:00:00 - 10:59:59	782	5.2%	0.22	3.1 MB	6.2%	0.87
Dec 28 11:00:00 - 11:59:59	842	5.6%	0.23	2.8 MB	5.7%	0.80
Dec 28 12:00:00 - 12:59:59	764	5.1%	0.21	2.2 MB	4.4%	0.62
Dec 28 13:00:00 - 13:59:59	878	5.9%	0.24	2.5 MB	5.2%	0.72
Dec 28 14:00:00 - 14:59:59	918	6.1%	0.26	2.9 MB	5.9%	0.82
Dec 28 15:00:00 - 15:59:59	937	6.3%	0.26	3.4 MB	6.9%	0.96
Dec 28 16:00:00 - 16:59:59	854	5.7%	0.24	2.6 MB	5.3%	0.74
Dec 28 17:00:00 - 17:59:59	790	5.3%	0.22	2.6 MB	5.3%	0.74
Dec 28 18:00:00 - 18:59:59	761	5.1%	0.21	2.2 MB	4.6%	0.64
Dec 28 19:00:00 - 19:59:59	803	5.4%	0.22	2.5 MB	5.2%	0.72
Dec 28 20:00:00 - 20:59:59	744	5.0%	0.21	2.1 MB	4.2%	0.58
Dec 28 21:00:00 - 21:59:59	600	4.0%	0.17	1.9 MB	3.8%	0.54
Dec 28 22:00:00 - 22:59:59	560	3.7%	0.16	1.7 MB	3.4%	0.48
Dec 28 23:00:00 - 23:59:59	515	3.4%	0.14	1.6 MB	3.3%	0.46
Dec 29 00:00:00 - 00:59:59	439	2.9%	0.12	1.5 MB	3.0%	0.43
Dec 29 01:00:00 - 01:59:59	407	2.7%	0.11	1.5 MB	3.0%	0.42
Dec 29 02:00:00 - 02:59:59	334	2.2%	0.09	1.0 MB	2.1%	0.29
Dec 29 03:00:00 - 03:59:59	372	2.5%	0.10	1.4 MB	2.8%	0.39
Dec 29 04:00:00 - 04:15:00	78	0.5%	0.09	254.6 KB	0.5%	0.28
TOTAL: 24:00:00	14983	100.0%	0.17	49.1 MB	100.0%	0.58

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1289	65	0	1224
2	newsfeed.fu-berlin.de	588	16	0	572
3	csiph-out.feed.uzoreto.com	379	31	0	348
4	csiph-out.news.weretis.net	287	159	2	126
5	news-out.mixmin.net	263	17	0	246
6	newsfeed.neva.ru	208	53	2	153
7	csiph-out.feeder.erje.net	156	53	0	103
8	feeder.eternal-september.org	76	43	0	33
9	news-out.aioe.org	71	30	0	41
10	newsfeeds.xmission.com	41	3	0	38
11	news-out.neodome.net	39	9	1	29
12	news.freedyn.net	38	1	1	36
13	news.swapon.de	19	0	0	19
14	nntp.pasdenom.info	10	0	0	10
15	news.fcku.it	1	1	0	0
TOTAL: 15		3465	481	6	2978

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	166
lada.talk	29
scinet.stats	23
alt.survival	18
mozilla.support.thunderbird	18
alt.binaries.pictures.purebeauty	18
lada.auto	13
mozilla.support.bugzilla	13
alt.bbs.synchronet	13
att.jobs	10
alt.politics	9
alt.math	7
mozilla.support.seamonkey	7
spline.eisfair	7
alt.binaries.ebook	6
alt.philosophy	6
at.jobs	6
scinet.general.chatter	5
aus.jobs	5
cakewalk.audio	5
TOTAL: 79	481

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1468
EMP (phn path)	305
EMP (phl)	141
Too many newsgroups	49
0	43
EMP (phn nph)	38
HTML Multipart	10
Binary: misplaced binary	9
Scoring filter	7
Too many newsgroups (meow)	1
Too many newsgroups (low_xpost)	1
TOTAL: 11	2072

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	133
	csiph-out.feed.uzoreto.com	116
	csiph-out.feeder.erje.net	40
	news.freedyn.net	27
	csiph-out.news.weretis.net	26
	newsfeeds.xmission.com	24
	news-out.neodome.net	18
	feeder.eternal-september.org	4
	newsfeed.fu-berlin.de	2
	news-out.mixmin.net	1
	TOTAL: 10	391
Including strange strings
	newsfeed.fu-berlin.de	412
	csiph-out.news.xs4all.nl	26
	news.swapon.de	19
	newsfeeds.xmission.com	12
	news-out.aioe.org	10
	csiph-out.news.weretis.net	10
	csiph-out.feed.uzoreto.com	7
	newsfeed.neva.ru	2
	feeder.eternal-september.org	2
	news-out.mixmin.net	2
	TOTAL: 10	502
No colon-space in header
	csiph-out.news.xs4all.nl	8
	csiph-out.feed.uzoreto.com	2
	csiph-out.feeder.erje.net	2
	TOTAL: 3	12
TOTAL: 4		957

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	17982	1249	13717	11	0	6%	23:59:54
2	news.bbs.nz	27747	880	13531	43	0	3%	23:59:54
3	fu-berlin.de	13822	524	13298	0	0	3%	23:59:48
4	tnet	16909	374	14085	6	0	2%	23:59:09
5	xmission	15050	223	12283	12	0	1%	23:59:54
6	erje	14777	212	13400	132	0	1%	23:59:45
7	newsfeed.xs4all.nl	12315	197	11310	0	145	1%	23:54:26
8	usenet.pasdenom.info	14624	19	14129	81	0	0%	23:59:54
9	neva.ru	13579	18	13237	158	0	0%	23:59:33
10	news.mixmin.net	13335	15	12914	0	0	0%	23:59:07
11	neodome.net	15031	14	14608	0	0	0%	23:50:05
12	news.freedyn.net	14291	11	13807	76	0	0%	23:59:52
13	news.uzoreto.com	14131	10	13718	7	0	0%	23:50:06
14	weretis.net	13511	9	13220	0	0	0%	23:59:54
15	aioe	12330	9	11910	0	0	0%	23:59:37
16	news.swapon.de	1386	8	1367	0	0	0%	23:15:41
17	news.fcku.it	1031	0	1031	0	0	0%	21:11:16
18	eternal-september	0	0	0	0	12077	0%	23:59:54
19	news.samoylyk.net	0	0	0	0	14933	0%	23:50:09
TOTAL: 19		231851	3772	201565	526	27155	1%	451:47:58

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	8.7 MB	70.8 KB	8.8 MB	0.1 KB/s	7.1 KB	23:59:54
2	fu-berlin.de	6.6 MB	0.0 KB	6.6 MB	0.1 KB/s	12.9 KB	23:59:48
3	news.bbs.nz	2.3 MB	224.8 KB	2.5 MB	0.0 KB/s	2.8 KB	23:59:54
4	tnet	801.4 KB	18.0 KB	819.4 KB	0.0 KB/s	2.2 KB	23:59:09
5	xmission	501.2 KB	32.7 KB	533.9 KB	0.0 KB/s	2.3 KB	23:59:54
6	newsfeed.xs4all.nl	422.6 KB	0.0 KB	422.6 KB	0.0 KB/s	2.1 KB	23:54:26
7	erje	397.1 KB	607.4 KB	1004.6 KB	0.0 KB/s	2.9 KB	23:59:45
8	usenet.pasdenom.info	33.2 KB	122.2 KB	155.4 KB	0.0 KB/s	1.6 KB	23:59:54
9	neva.ru	32.4 KB	350.7 KB	383.0 KB	0.0 KB/s	2.2 KB	23:59:33
10	neodome.net	30.8 KB	0.0 KB	30.8 KB	0.0 KB/s	2.2 KB	23:50:05
11	news.mixmin.net	26.2 KB	0.0 KB	26.2 KB	0.0 KB/s	1.7 KB	23:59:07
12	news.freedyn.net	20.1 KB	1.4 MB	1.4 MB	0.0 KB/s	17.1 KB	23:59:52
13	news.uzoreto.com	17.5 KB	16.2 KB	33.8 KB	0.0 KB/s	2.0 KB	23:50:06
14	aioe	15.8 KB	0.0 KB	15.8 KB	0.0 KB/s	1.8 KB	23:59:37
15	weretis.net	15.0 KB	0.0 KB	15.0 KB	0.0 KB/s	1.7 KB	23:59:54
16	news.swapon.de	12.5 KB	0.0 KB	12.5 KB	0.0 KB/s	1.6 KB	23:15:41
17	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:11:16
18	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:54
19	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:09
TOTAL: 19		19.9 MB	2.8 MB	22.7 MB	0.0 KB/s	5.4 KB	451:47:58

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	91.187.33.87	299	13	21.8 KB	13	8	8	46:54:56
2	a131033.upc-a.chello.nl	34	12	19.4 KB	7	0	0	09:01:12
3	p3ee23117.dip0.t-ipconnect.de	1	4	12.7 KB	1	0	0	00:00:14
4	212.90.61.180	1	0	0.0 KB	27	0	0	00:00:00
TOTAL: 4		335	29	54.0 KB	48	8	8	55:56:53

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	303	13	21.8 KB	40	8	8	46:55:17
2	*.upc-a.chello.nl	34	12	19.4 KB	7	0	0	09:01:12
3	*.dip0.t-ipconnect.de	1	4	12.7 KB	1	0	0	00:00:14
4	*.range31-50.btcentralplus.com	1	0	0.0 KB	0	0	0	00:00:09
TOTAL: 4		339	29	54.0 KB	48	8	8	55:56:53

NNRP auth users [Top 20]:

User	Conn
slavotinek	8
TOTAL: 1	8

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	19.466	4.494	0.020	46:54:56
a131033.upc-a.chello.nl	2.514	0.471	0.000	09:01:12
p3ee23117.dip0.t-ipconnect.de	0.119	0.022	0.000	00:00:14
134.122.14.132	0.068	0.008	0.000	00:00:10
192.241.221.196	0.069	0.008	0.000	00:00:09
host31-50-220-99.range31-50.btcentralplus.com	0.051	0.029	0.000	00:00:09
45.146.165.32	0.053	0.023	0.000	00:00:00
TOTAL: 7	22.340	5.055	0.020	55:56:53

Curious NNRP server explorers [Top 20]:

System	Conn
192.241.221.196	1
host31-50-220-99.range31-50.btcentralplus.com	1
45.146.165.32	1
134.122.14.132	1
TOTAL: 4	4

NNRP unrecognized commands (by host):

System	Conn
45.146.165.32	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
^C	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
45.146.165.32	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

Category		Count	Pct
1	alt	19528	48.1%
2	pl	15869	39.1%
3	comp	1961	4.8%
4	ukr	1293	3.2%
5	de	1178	2.9%
6	rec	430	1.1%
7	italia	181	0.4%
8	chile	64	0.2%
9	ca	43	0.1%
10	dc	10	0.0%
11	perl	5	0.0%
12	memphis	2	0.0%
TOTAL: 12		40564	100.0%

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

Newsgroup		Count
1	alt.sex.wanted	19139
2	pl.misc.telefonia.gsm	15763
3	comp.graphics.api.opengl	1698
4	ukr.nodes	1293
5	de.comp.lang.php	1178
6	rec.music.makers.squeezebox	430
7	comp.sys.sun.admin	238
8	italia.bologna.barzellette	181
9	alt.comp.lang.c	172
10	alt.union.natl-writers	125
11	pl.comp.os.linux.debian	106
12	chile.test	64
13	ca.unix	43
14	alt.christnet.philosophy	39
15	comp.sys.ibm.ps2.hardware	25
16	alt.sex.fetish.trent-reznor	12
17	dc.housing	10
18	alt.fan.kim-bach.slut.slut.slut	10
19	alt.nosebeeping	6
20	alt.fan.dave_barry	5
21	perl.perl4lib	5
22	alt.sport.wrestling.amateur.gay	5
23	alt.free.newsservers	4
24	alt.games.video.sony-playstation3	3
25	alt.movies.grease	3
26	alt.games.video.shooters	2
27	alt.support.jaw-disorders	2
28	memphis.newsgroups	2
29	alt.sex.uncut	1
TOTAL: 29		40564

Daily Usenet report for csiph.com

Dec 28 04:15:00 -- Dec 29 04:15:00