Daily Usenet report for csiph.com: Sep 25 04:15:00

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Sep 25 22:23:46 csiph nnrpd[73458]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Sep 25 22:23:46 csiph nnrpd[73458]: 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	20110	50.5%	5.2 MB	65.3%
innd	15281	38.4%	2.0 MB	25.2%
innfeed	3497	8.8%	665.4 KB	8.2%
nnrpd	905	2.3%	97.0 KB	1.2%
nocem	5	0.0%	0.5 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	39799	100.0%	7.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	331541	51.5%
Negative hits	264290	41.1%
Cache misses	28267	4.4%
Do not exist	19499	3.0%
TOTAL: 4	643597	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.159	0.0%	23	0.000	6.913	15.875
article cleanup	00:00:00.102	0.0%	19312	0.000	0.005	0.028
article logging	00:00:00.259	0.0%	19871	0.000	0.013	0.059
article parse	00:00:00.687	0.0%	54675	0.000	0.013	0.083
article write	00:00:07.307	0.0%	16360	0.128	0.447	8.306
artlog/artcncl	00:00:00.000	0.0%	23	0.000	0.000	0.000
artlog/artparse	00:00:00.002	0.0%	232	0.000	0.009	0.111
data move	00:00:00.155	0.0%	606232	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.020	0.0%	22	0.000	0.909	1.750
hishave/artcncl	00:00:00.002	0.0%	23	0.000	0.087	0.333
hishave/artparse	00:00:00.000	0.0%	216	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:09.617	0.0%	644505	0.002	0.015	0.148
history sync	00:00:01.060	0.0%	360	0.000	2.944	12.500
history write	00:00:16.319	0.0%	19321	0.068	0.845	2.877
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.215	0.0%	216	0.000	0.995	10.000
idle	23:56:30.731	99.7%	611877	61.478	140.863	429.931
nntp read	00:00:02.224	0.0%	613913	0.001	0.004	0.007
overview write	00:02:42.556	0.2%	16360	0.134	9.936	1807.043
perl filter	00:00:26.654	0.0%	19305	1.164	1.381	1.947
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.295	0.0%	32725	0.000	0.009	0.033
TOTAL: 24:01:00.761	24:00:18.364	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.041	0.0%	16393	0.000	0.003	0.023
article prepare	00:00:00.001	0.0%	15408	0.000	0.000	0.018
article read	00:00:00.249	0.0%	1734	0.000	0.144	2.250
backlog stats	00:00:00.200	0.0%	380368	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.363	0.0%	299172	0.000	0.005	0.010
data write	00:00:01.722	0.0%	285866	0.001	0.006	0.012
idle	23:54:06.534	100.0%	380368	117.211	226.219	705.922
status file	00:00:00.206	0.0%	269	0.000	0.766	4.000
TOTAL: 23:54:18.922	23:54:10.316	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	39:17:50.668	99.8%	672	16.333	210521.827	600146.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.016	0.0%	1739	0.000	0.009	0.167
readart	00:00:00.290	0.0%	126	0.000	2.302	55.000
TOTAL: 39:22:46.880	39:17:50.974	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.xs4all.nl	421	46640	3354	41763	1523	7%	69:30:20
2	news-out.mixmin.net	2	17329	2656	14366	307	15%	24:08:56
3	feeder.eternal-september.org	4	19013	2489	16447	77	13%	26:46:46
4	newsfeeds.xmission.com	2	15378	2388	12954	36	15%	23:08:17
5	news-out.aioe.org	2	15630	1314	14225	91	8%	24:08:58
6	newsfeed.fu-berlin.de	13	15463	1016	14358	89	6%	24:18:28
7	csiph-out.feeder.erje.net	1440	80630	806	79691	133	0%	118:43:14
8	csiph-out.news.weretis.net	2	33211	758	32260	193	2%	46:36:39
9	newsfeed.neva.ru	147	11618	672	10618	328	5%	71:28:38
10	news.fcku.it	13	1746	300	1440	6	17%	21:49:29
11	csiph-out.feed.uzoreto.com	864	25537	268	24980	289	1%	71:16:04
12	news-out.neodome.net	1	17756	193	17556	7	1%	23:59:52
13	nntp.pasdenom.info	2	10601	101	10442	58	0%	23:58:48
14	news.freedyn.net	1	19405	31	19331	43	0%	23:59:52
15	news.swapon.de	6	1551	30	1521	0	1%	23:40:43
16	csiph-out.news.tnetconsulting.net	3	25042	20	25017	5	0%	34:43:26
17	news.samoylyk.net	1	15783	9	15774	0	0%	23:59:54
18	localhost	29	3	3	0	0	100%	00:00:00
19	news.bbs.nz	1	15016	2	15014	0	0%	23:59:52
20	xanadu-bbs.net	38	531	0	531	0	0%	20:56:41
TOTAL: 20		2992	387883	16410	368288	3185	4%	721:14:57

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.xs4all.nl	8.4 MB	0.0 KB	9.3 MB	17.8 MB	47%	3.7 KB
2	newsfeeds.xmission.com	7.2 MB	0.0 KB	2.0 MB	9.2 MB	78%	3.9 KB
3	feeder.eternal-september.org	6.6 MB	1.5 KB	643.9 KB	7.3 MB	91%	2.9 KB
4	news-out.mixmin.net	6.0 MB	0.0 KB	1.6 MB	7.6 MB	78%	2.6 KB
5	news-out.aioe.org	4.7 MB	0.0 KB	262.6 KB	4.9 MB	94%	3.6 KB
6	csiph-out.feeder.erje.net	4.3 MB	0.0 KB	3.1 MB	7.4 MB	58%	8.1 KB
7	newsfeed.fu-berlin.de	2.4 MB	0.0 KB	289.3 KB	2.7 MB	89%	2.5 KB
8	csiph-out.news.weretis.net	2.1 MB	0.0 KB	1.0 MB	3.1 MB	66%	3.3 KB
9	newsfeed.neva.ru	1.7 MB	5.0 KB	838.2 KB	2.6 MB	67%	2.6 KB
10	csiph-out.feed.uzoreto.com	825.9 KB	0.0 KB	4.4 MB	5.2 MB	15%	9.6 KB
11	news.freedyn.net	509.4 KB	1.5 KB	1.2 MB	1.7 MB	28%	24.0 KB
12	news-out.neodome.net	488.7 KB	2.3 KB	65.5 KB	556.5 KB	87%	2.8 KB
13	news.fcku.it	465.2 KB	0.0 KB	8.1 KB	473.2 KB	98%	1.5 KB
14	nntp.pasdenom.info	218.0 KB	0.0 KB	74.2 KB	292.2 KB	74%	1.8 KB
15	news.swapon.de	103.7 KB	0.0 KB	0.0 KB	103.7 KB	100%	3.5 KB
16	csiph-out.news.tnetconsulting.net	54.1 KB	6.5 KB	12.4 KB	73.0 KB	74%	2.9 KB
17	news.samoylyk.net	48.7 KB	0.0 KB	0.0 KB	48.7 KB	100%	5.4 KB
18	localhost	3.7 KB	0.0 KB	0.0 KB	3.7 KB	100%	1.2 KB
19	news.bbs.nz	3.1 KB	0.0 KB	0.0 KB	3.1 KB	100%	1.5 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		46.2 MB	16.8 KB	24.8 MB	71.0 MB	65%	3.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 25 04:15:00 - 04:59:59	254	1.6%	0.09	1.1 MB	2.4%	0.42
Sep 25 05:00:00 - 05:59:59	353	2.2%	0.10	1.0 MB	2.3%	0.29
Sep 25 06:00:00 - 06:59:59	504	3.1%	0.14	1.5 MB	3.3%	0.43
Sep 25 07:00:00 - 07:59:59	772	4.7%	0.21	2.2 MB	4.8%	0.61
Sep 25 08:00:00 - 08:59:59	811	5.0%	0.23	2.2 MB	4.9%	0.62
Sep 25 09:00:00 - 09:59:59	1007	6.2%	0.28	3.2 MB	7.1%	0.91
Sep 25 10:00:00 - 10:59:59	812	5.0%	0.23	2.1 MB	4.6%	0.59
Sep 25 11:00:00 - 11:59:59	647	4.0%	0.18	1.5 MB	3.3%	0.43
Sep 25 12:00:00 - 12:59:59	831	5.1%	0.23	2.0 MB	4.5%	0.58
Sep 25 13:00:00 - 13:59:59	959	5.9%	0.27	2.4 MB	5.4%	0.69
Sep 25 14:00:00 - 14:59:59	969	5.9%	0.27	2.6 MB	5.7%	0.74
Sep 25 15:00:00 - 15:59:59	941	5.8%	0.26	2.4 MB	5.4%	0.69
Sep 25 16:00:00 - 16:59:59	858	5.2%	0.24	2.2 MB	4.9%	0.63
Sep 25 17:00:00 - 17:59:59	932	5.7%	0.26	2.7 MB	6.0%	0.78
Sep 25 18:00:00 - 18:59:59	881	5.4%	0.24	2.3 MB	5.1%	0.66
Sep 25 19:00:00 - 19:59:59	762	4.7%	0.21	2.2 MB	4.9%	0.63
Sep 25 20:00:00 - 20:59:59	756	4.6%	0.21	2.1 MB	4.6%	0.59
Sep 25 21:00:00 - 21:59:59	676	4.1%	0.19	1.9 MB	4.2%	0.54
Sep 25 22:00:00 - 22:59:59	599	3.7%	0.17	1.7 MB	3.9%	0.50
Sep 25 23:00:00 - 23:59:59	555	3.4%	0.15	1.6 MB	3.5%	0.45
Sep 26 00:00:00 - 00:59:59	346	2.1%	0.10	989.3 KB	2.1%	0.27
Sep 26 01:00:00 - 01:59:59	346	2.1%	0.10	1.0 MB	2.2%	0.29
Sep 26 02:00:00 - 02:59:59	292	1.8%	0.08	857.8 KB	1.9%	0.24
Sep 26 03:00:00 - 03:59:59	396	2.4%	0.11	1.2 MB	2.6%	0.33
Sep 26 04:00:00 - 04:15:00	87	0.5%	0.10	238.6 KB	0.5%	0.27
TOTAL: 24:00:00	16346	100.0%	0.19	45.2 MB	100.0%	0.54

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1532	98	0	1434
2	newsfeed.fu-berlin.de	522	27	0	495
3	news-out.mixmin.net	354	1	0	353
4	newsfeed.neva.ru	328	61	2	265
5	csiph-out.feed.uzoreto.com	291	8	0	283
6	csiph-out.news.weretis.net	215	57	0	158
7	csiph-out.feeder.erje.net	130	33	0	97
8	news-out.aioe.org	117	87	0	30
9	feeder.eternal-september.org	69	23	1	45
10	nntp.pasdenom.info	58	0	0	58
11	newsfeeds.xmission.com	52	1	0	51
12	news.freedyn.net	45	0	1	44
13	csiph-out.news.tnetconsulting.net	10	7	2	1
14	news-out.neodome.net	6	1	0	5
15	news.fcku.it	6	6	0	0
16	news.swapon.de	5	0	0	5
17	news.bbs.nz	1	0	1	0
TOTAL: 17		3741	410	7	3324

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.bbs.synchronet	42
it-alt.sport.calcio.juventus	31
alt.survival	28
lada.talk	28
mozilla.support.thunderbird	26
lada.auto	21
scinet.stats	19
alt.computer.workshop	17
mozilla.support.seamonkey	16
spline.eisfair	14
alt.math.undergrad	14
alt.windows7.general	13
alt.tv.comedy-central.daily-show	13
alt.job	9
alt.politics.usa	8
mozilla.general	8
mozilla.dev.apps.seamonkey	8
alt.music.pink-floyd.publius	7
alt.politics	5
at.jobs	5
TOTAL: 67	410

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1764
EMP (phn path)	364
EMP (phl)	194
0	91
Too many newsgroups	51
EMP (phn nph)	42
Scoring filter	13
Binary: misplaced binary	6
HTML Multipart	6
Too many newsgroups (meow)	1
TOTAL: 10	2532

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	55
	csiph-out.news.xs4all.nl	2
	csiph-out.feed.uzoreto.com	2
	TOTAL: 3	59
Huge articles
	csiph-out.news.xs4all.nl	56
	csiph-out.feeder.erje.net	51
	newsfeeds.xmission.com	40
	csiph-out.feed.uzoreto.com	35
	news.freedyn.net	23
	csiph-out.news.weretis.net	5
	feeder.eternal-september.org	5
	news-out.neodome.net	1
	news-out.mixmin.net	1
	TOTAL: 9	217
Including strange strings
	newsfeed.fu-berlin.de	433
	news-out.mixmin.net	52
	news-out.aioe.org	24
	csiph-out.news.xs4all.nl	22
	newsfeeds.xmission.com	10
	csiph-out.news.weretis.net	8
	news.swapon.de	5
	newsfeed.neva.ru	2
	csiph-out.feed.uzoreto.com	2
	feeder.eternal-september.org	1
	TOTAL: 10	559
No colon-space in header
	csiph-out.news.xs4all.nl	13
	csiph-out.feed.uzoreto.com	1
	TOTAL: 2	14
TOTAL: 4		849

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	19367	1060	15257	28	57	5%	23:59:54
2	news.bbs.nz	30650	905	14987	18	1	2%	23:59:54
3	fu-berlin.de	15090	412	14678	0	0	2%	23:59:11
4	news.samoylyk.net	16690	398	15934	5	0	2%	23:59:28
5	tnet	18429	273	15644	1	1	1%	23:59:34
6	neva.ru	14968	127	14661	34	0	0%	23:59:34
7	erje	17579	77	14826	283	1	0%	23:59:52
8	newsfeed.xs4all.nl	13344	35	12903	1	0	0%	23:59:54
9	news.freedyn.net	15998	32	15704	0	0	0%	23:59:54
10	eternal-september	13694	26	13395	0	0	0%	23:59:45
11	aioe	11688	25	11332	2	0	0%	23:59:27
12	usenet.pasdenom.info	16224	20	15793	2	17	0%	23:59:54
13	neodome.net	16421	13	16121	0	0	0%	23:59:50
14	xmission	16495	11	13358	0	0	0%	23:59:54
15	news.mixmin.net	13303	7	13050	0	0	0%	23:59:54
16	weretis.net	15349	6	15070	0	0	0%	23:59:54
17	news.uzoreto.com	15342	6	15084	44	0	0%	23:59:53
18	news.swapon.de	1598	3	1584	0	0	0%	23:35:38
19	news.fcku.it	1394	0	1393	0	0	0%	21:00:34
TOTAL: 19		283623	3436	250774	418	77	1%	452:31:58

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	5.4 MB	105.8 KB	5.5 MB	0.1 KB/s	5.2 KB	23:59:54
2	fu-berlin.de	3.4 MB	0.0 KB	3.4 MB	0.0 KB/s	8.5 KB	23:59:11
3	news.samoylyk.net	3.4 MB	5.7 KB	3.4 MB	0.0 KB/s	8.7 KB	23:59:28
4	news.bbs.nz	2.4 MB	67.2 KB	2.5 MB	0.0 KB/s	2.8 KB	23:59:54
5	tnet	761.4 KB	4.6 KB	766.0 KB	0.0 KB/s	2.8 KB	23:59:34
6	neva.ru	316.6 KB	111.5 KB	428.1 KB	0.0 KB/s	2.7 KB	23:59:34
7	news.freedyn.net	307.8 KB	0.0 KB	307.8 KB	0.0 KB/s	9.6 KB	23:59:54
8	erje	193.7 KB	972.1 KB	1.1 MB	0.0 KB/s	3.2 KB	23:59:52
9	newsfeed.xs4all.nl	71.6 KB	5.9 KB	77.4 KB	0.0 KB/s	2.2 KB	23:59:54
10	eternal-september	68.6 KB	0.0 KB	68.6 KB	0.0 KB/s	2.6 KB	23:59:45
11	aioe	59.4 KB	3.5 KB	62.9 KB	0.0 KB/s	2.3 KB	23:59:27
12	usenet.pasdenom.info	35.4 KB	5.2 KB	40.6 KB	0.0 KB/s	1.8 KB	23:59:54
13	neodome.net	17.8 KB	0.0 KB	17.8 KB	0.0 KB/s	1.4 KB	23:59:50
14	xmission	13.9 KB	0.0 KB	13.9 KB	0.0 KB/s	1.3 KB	23:59:54
15	news.uzoreto.com	13.6 KB	134.9 KB	148.5 KB	0.0 KB/s	3.0 KB	23:59:53
16	weretis.net	11.1 KB	0.0 KB	11.1 KB	0.0 KB/s	1.9 KB	23:59:54
17	news.mixmin.net	7.3 KB	0.0 KB	7.3 KB	0.0 KB/s	1.0 KB	23:59:54
18	news.swapon.de	3.7 KB	0.0 KB	3.7 KB	0.0 KB/s	1.2 KB	23:35:38
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:00:34
TOTAL: 19		16.5 MB	1.4 MB	17.9 MB	0.0 KB/s	4.8 KB	452:31:58

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	ppp-2-87-177-163.home.otenet.gr	1	109	227.3 KB	1	0	0	00:01:08
2	096-042-018-108.res.spectrum.com	22	11	23.6 KB	10	3	2	07:15:15
3	a131033.upc-a.chello.nl	26	3	8.3 KB	3	0	0	05:34:51
4	ip72-222-200-117.ph.ph.cox.net	5	3	9.1 KB	4	0	0	02:30:33
TOTAL: 4		54	126	268.2 KB	18	3	2	39:22:45

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.home.otenet.gr	1	109	227.3 KB	1	0	0	00:01:08
2	*.res.spectrum.com	22	11	23.6 KB	10	3	2	07:15:15
3	*.upc-a.chello.nl	26	3	8.3 KB	3	0	0	05:34:51
4	*.ph.ph.cox.net	5	3	9.1 KB	4	0	0	02:30:33
5	unresolved	73	0	0.0 KB	0	0	0	24:00:54
6	*.internet-census.org	6	0	0.0 KB	0	0	0	00:00:01
7	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:00
TOTAL: 7		134	126	268.2 KB	18	3	2	39:22:45

NNRP auth users [Top 20]:

User	Conn
ohlandl	2
TOTAL: 1	2

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	4.774	1.287	0.000	24:00:44
096-042-018-108.res.spectrum.com	1.914	0.387	0.005	07:15:15
a131033.upc-a.chello.nl	1.782	0.429	0.000	05:34:51
ip72-222-200-117.ph.ph.cox.net	0.542	0.076	0.000	02:30:33
ppp-2-87-177-163.home.otenet.gr	0.114	0.023	0.000	00:01:08
192.241.237.194	0.067	0.017	0.000	00:00:09
sh-chi-us-gp1-wk111.internet-census.org	0.365	0.129	0.000	00:00:01
scan-141.security.ipip.net	0.061	0.015	0.000	00:00:00
TOTAL: 8	9.619	2.363	0.005	39:22:45

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	72
sh-chi-us-gp1-wk111.internet-census.org	6
scan-141.security.ipip.net	1
192.241.237.194	1
TOTAL: 4	80

NNRP unrecognized commands (by host):

System	Conn
sh-chi-us-gp1-wk111.internet-census.org	10
scan-141.security.ipip.net	4
TOTAL: 2	14

NNRP unrecognized commands (by command):

Command	Count
EHLO sh-chi-us-gp1-wk111.internet-census...	2
Host: 69.160.37.58:119	2
GET / HTTP/1.1	2
Connection: close	1
User-Agent: Mozilla/5.0 (Windows NT 10.0...	1
User-Agent: HTTP Banner Detection (https...	1
^V^C^A	1
Accept: /	1
SSH-2.0-Go	1
Accept-Encoding: gzip	1
^A	1
TOTAL: 11	14

NNRP client timeouts [Top 20]:

System	Conn	Peer
096-042-018-108.res.spectrum.com	9	0
ip72-222-200-117.ph.ph.cox.net	5	0
TOTAL: 2	14	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Sep 25 04:15:00 -- Sep 26 04:15:00