Daily Usenet report for csiph.com: Aug 12 04:15:00

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Aug 12 22:31:53 csiph nnrpd[40144]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Aug 12 22:31:53 csiph nnrpd[40144]: 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	18702	47.0%	4.8 MB	62.4%
innd	16735	42.1%	2.2 MB	28.6%
innfeed	3535	8.9%	626.6 KB	7.9%
nnrpd	790	2.0%	85.1 KB	1.1%
nocem	21	0.1%	2.3 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	39784	100.0%	7.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	319196	57.9%
Negative hits	213818	38.8%
Do not exist	18095	3.3%
Cache misses	467	0.1%
TOTAL: 4	551576	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.503	0.0%	46	0.000	10.935	131.500
article cleanup	00:00:00.101	0.0%	17960	0.000	0.006	0.031
article logging	00:00:00.284	0.0%	18411	0.000	0.015	0.101
article parse	00:00:00.683	0.0%	53039	0.000	0.013	0.098
article write	00:00:26.447	0.0%	15219	0.168	1.738	37.377
artlog/artcncl	00:00:00.000	0.0%	46	0.000	0.000	0.000
artlog/artparse	00:00:00.000	0.0%	240	0.000	0.000	0.000
data move	00:00:00.196	0.0%	511334	0.000	0.000	0.004
hisgrep/artcncl	00:00:00.054	0.0%	46	0.000	1.174	19.500
hishave/artcncl	00:00:00.008	0.0%	46	0.000	0.174	1.000
hishave/artparse	00:00:00.001	0.0%	221	0.000	0.005	0.167
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:14.047	0.0%	553724	0.001	0.025	0.246
history sync	00:00:00.765	0.0%	328	0.000	2.332	23.000
history write	00:00:19.385	0.0%	17948	0.209	1.080	5.176
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.192	0.0%	221	0.000	0.869	9.000
idle	23:56:54.107	99.7%	516369	93.537	166.962	484.436
nntp read	00:00:01.989	0.0%	518906	0.002	0.004	0.008
overview write	00:01:37.662	0.1%	15219	0.175	6.417	670.470
perl filter	00:00:24.998	0.0%	17879	1.099	1.398	2.105
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.266	0.0%	30459	0.000	0.009	0.026
TOTAL: 24:00:38.447	24:00:01.688	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.037	0.0%	15616	0.000	0.002	0.030
article prepare	00:00:00.000	0.0%	12760	0.000	0.000	0.000
article read	00:00:00.309	0.0%	1643	0.000	0.188	12.000
backlog stats	00:00:00.214	0.0%	353657	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.357	0.0%	279118	0.001	0.005	0.015
data write	00:00:01.585	0.0%	266724	0.002	0.006	0.013
idle	23:55:14.511	100.0%	353657	143.481	243.497	629.751
status file	00:00:00.194	0.0%	280	0.000	0.693	5.000
TOTAL: 23:55:26.549	23:55:18.207	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	34:16:15.295	99.8%	522	63.000	236351.140	600114.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.010	0.0%	1267	0.000	0.008	0.167
readart	00:00:00.169	0.0%	54	0.000	3.130	42.000
TOTAL: 34:21:05.431	34:16:15.474	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	newsfeeds.xmission.com	2	14807	3759	10993	55	25%	23:54:03
2	news-out.mixmin.net	4	15960	2472	13217	271	15%	24:11:47
3	feeder.eternal-september.org	2	16329	2351	13856	122	14%	23:08:48
4	news-out.aioe.org	3	15021	1377	13525	119	9%	24:08:41
5	newsfeed.neva.ru	151	17356	1192	15723	441	6%	71:45:45
6	csiph-out.feeder.erje.net	1440	75904	962	74462	480	1%	118:42:27
7	csiph-out.news.weretis.net	6	39263	820	38273	170	2%	57:56:01
8	newsfeed.fu-berlin.de	12	15942	815	14804	323	5%	27:12:37
9	csiph-out.news.xs4all.nl	861	16609	593	15519	497	3%	71:28:09
10	news.fcku.it	12	1429	227	1201	1	15%	21:41:30
11	csiph-out.feed.uzoreto.com	863	23739	197	23069	473	0%	71:20:41
12	news-out.neodome.net	2	16189	174	15910	105	1%	23:59:54
13	nntp.pasdenom.info	2	10374	89	10261	24	0%	23:58:44
14	news.swapon.de	5	1344	35	1309	0	2%	23:49:32
15	news.freedyn.net	3	11296	25	11193	78	0%	13:16:29
16	csiph-out.news.tnetconsulting.net	2	14807	18	14773	16	0%	23:59:56
17	news.samoylyk.net	3	15072	13	15058	1	0%	24:53:02
18	news.bbs.nz	2	14223	4	14219	0	0%	23:59:49
19	xanadu-bbs.net	27	411	0	411	0	0%	21:56:48
20	localhost	26	0	0	0	0	0%	00:00:03
TOTAL: 20		3428	336075	15123	317776	3176	4%	715:24:46

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	10.5 MB	0.0 KB	4.0 MB	14.5 MB	72%	3.9 KB
2	feeder.eternal-september.org	6.5 MB	3.4 KB	1.2 MB	7.7 MB	83%	3.2 KB
3	news-out.mixmin.net	5.1 MB	15.8 KB	758.5 KB	5.8 MB	87%	2.2 KB
4	news-out.aioe.org	4.6 MB	15.8 KB	273.9 KB	4.9 MB	94%	3.4 KB
5	csiph-out.feeder.erje.net	4.3 MB	13.7 KB	3.9 MB	8.2 MB	52%	5.8 KB
6	newsfeed.neva.ru	3.5 MB	58.4 KB	1.3 MB	4.8 MB	72%	3.0 KB
7	csiph-out.news.weretis.net	2.4 MB	49.6 KB	1.6 MB	4.0 MB	59%	4.1 KB
8	newsfeed.fu-berlin.de	2.0 MB	0.0 KB	1.1 MB	3.1 MB	64%	2.7 KB
9	csiph-out.news.xs4all.nl	1.7 MB	0.0 KB	4.9 MB	6.6 MB	26%	6.2 KB
10	csiph-out.feed.uzoreto.com	804.3 KB	3.4 KB	11.0 MB	11.8 MB	6%	18.0 KB
11	news-out.neodome.net	542.5 KB	11.3 KB	170.6 KB	724.5 KB	74%	2.6 KB
12	news.fcku.it	352.6 KB	0.0 KB	96.5 KB	449.1 KB	78%	2.0 KB
13	nntp.pasdenom.info	249.7 KB	0.0 KB	43.5 KB	293.2 KB	85%	2.6 KB
14	news.freedyn.net	90.8 KB	12.4 KB	751.1 KB	854.3 KB	10%	8.3 KB
15	news.swapon.de	86.2 KB	0.0 KB	0.0 KB	86.2 KB	100%	2.5 KB
16	csiph-out.news.tnetconsulting.net	51.5 KB	7.8 KB	195.5 KB	254.8 KB	20%	7.5 KB
17	news.samoylyk.net	38.8 KB	3.4 KB	3.4 KB	45.6 KB	84%	3.3 KB
18	news.bbs.nz	9.1 KB	0.0 KB	0.0 KB	9.1 KB	100%	2.3 KB
19	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
20	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		42.7 MB	195.0 KB	31.2 MB	74.1 MB	57%	4.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 12 04:15:00 - 04:59:59	279	1.8%	0.10	797.8 KB	1.8%	0.30
Aug 12 05:00:00 - 05:59:59	368	2.4%	0.10	1.0 MB	2.4%	0.30
Aug 12 06:00:00 - 06:59:59	451	3.0%	0.13	1.3 MB	2.9%	0.36
Aug 12 07:00:00 - 07:59:59	575	3.8%	0.16	1.7 MB	4.0%	0.50
Aug 12 08:00:00 - 08:59:59	667	4.4%	0.19	1.9 MB	4.5%	0.55
Aug 12 09:00:00 - 09:59:59	665	4.4%	0.18	1.9 MB	4.4%	0.54
Aug 12 10:00:00 - 10:59:59	655	4.3%	0.18	1.9 MB	4.3%	0.53
Aug 12 11:00:00 - 11:59:59	663	4.4%	0.18	1.7 MB	4.0%	0.49
Aug 12 12:00:00 - 12:59:59	745	4.9%	0.21	2.0 MB	4.7%	0.57
Aug 12 13:00:00 - 13:59:59	822	5.4%	0.23	2.1 MB	4.8%	0.59
Aug 12 14:00:00 - 14:59:59	911	6.0%	0.25	2.5 MB	5.7%	0.71
Aug 12 15:00:00 - 15:59:59	894	5.9%	0.25	2.4 MB	5.5%	0.68
Aug 12 16:00:00 - 16:59:59	832	5.5%	0.23	2.3 MB	5.4%	0.66
Aug 12 17:00:00 - 17:59:59	815	5.4%	0.23	2.2 MB	5.2%	0.64
Aug 12 18:00:00 - 18:59:59	812	5.3%	0.23	2.4 MB	5.5%	0.68
Aug 12 19:00:00 - 19:59:59	815	5.4%	0.23	2.1 MB	4.8%	0.59
Aug 12 20:00:00 - 20:59:59	738	4.8%	0.20	2.2 MB	5.0%	0.62
Aug 12 21:00:00 - 21:59:59	730	4.8%	0.20	2.0 MB	4.5%	0.56
Aug 12 22:00:00 - 22:59:59	625	4.1%	0.17	1.9 MB	4.5%	0.55
Aug 12 23:00:00 - 23:59:59	491	3.2%	0.14	1.5 MB	3.4%	0.42
Aug 13 00:00:00 - 00:59:59	448	2.9%	0.12	1.3 MB	3.1%	0.38
Aug 13 01:00:00 - 01:59:59	423	2.8%	0.12	1.4 MB	3.2%	0.40
Aug 13 02:00:00 - 02:59:59	410	2.7%	0.11	1.4 MB	3.4%	0.41
Aug 13 03:00:00 - 03:59:59	289	1.9%	0.08	855.5 KB	1.9%	0.24
Aug 13 04:00:00 - 04:15:00	105	0.7%	0.12	383.7 KB	0.9%	0.43
TOTAL: 24:00:00	15228	100.0%	0.18	43.2 MB	100.0%	0.51

Sites sending bad articles:

Server		Total	Group	Duplic	TooOld	Other
1	newsfeed.fu-berlin.de	608	48	0	0	560
2	csiph-out.news.xs4all.nl	499	19	0	0	480
3	csiph-out.feed.uzoreto.com	474	18	1	0	455
4	newsfeed.neva.ru	443	85	10	0	348
5	news-out.mixmin.net	354	7	2	0	345
6	csiph-out.feeder.erje.net	290	60	4	0	226
7	csiph-out.news.weretis.net	194	43	6	0	145
8	news-out.aioe.org	141	101	2	0	38
9	feeder.eternal-september.org	125	24	2	0	99
10	news-out.neodome.net	108	2	2	0	104
11	news.freedyn.net	79	1	1	50	27
12	newsfeeds.xmission.com	62	3	0	0	59
13	nntp.pasdenom.info	23	0	0	0	23
14	csiph-out.news.tnetconsulting.net	22	20	0	0	2
15	news.swapon.de	4	0	0	0	4
16	news.fcku.it	2	1	0	0	1
17	news.samoylyk.net	1	0	1	0	0
TOTAL: 17		3429	432	31	50	2916

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	83
alt.computer.workshop	48
alt.bbs.synchronet	34
it-alt.sport.calcio.juventus	30
alt.politics.usa	19
trollnet.cinema	17
mozilla.general	16
scinet.stats	16
spline.fli4l.dev	11
mozilla.support.seamonkey	10
alt.hack.nl	8
spline.eisfair	7
alt.paranormal.spells	7
oesterreich.tratsch	6
alt.english.usage	6
alt.survival	6
mozilla.dev.l10n	6
alt.politics	6
mozilla.support.thunderbird	6
alt.philosophy	5
TOTAL: 74	432

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1350
EMP (phn path)	388
EMP (phl)	200
EMP (phn nph)	94
0	89
Too many newsgroups	60
HTML Multipart	25
Scoring filter	6
Too many newsgroups (meow)	4
Binary Payload	4
Binary: misplaced binary	4
Too many newsgroups (low_xpost)	1
TOTAL: 12	2225

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	60
	csiph-out.news.xs4all.nl	3
	csiph-out.feed.uzoreto.com	3
	TOTAL: 3	66
Huge articles
	csiph-out.feeder.erje.net	66
	csiph-out.feed.uzoreto.com	55
	newsfeeds.xmission.com	52
	csiph-out.news.weretis.net	12
	csiph-out.news.xs4all.nl	10
	feeder.eternal-september.org	9
	news.freedyn.net	6
	news-out.mixmin.net	5
	newsfeed.fu-berlin.de	3
	csiph-out.news.tnetconsulting.net	2
	TOTAL: 11	221
Including strange strings
	newsfeed.fu-berlin.de	322
	news-out.mixmin.net	85
	news-out.aioe.org	19
	csiph-out.news.weretis.net	10
	news.swapon.de	4
	csiph-out.news.xs4all.nl	4
	newsfeeds.xmission.com	4
	csiph-out.feeder.erje.net	1
	csiph-out.feed.uzoreto.com	1
	newsfeed.neva.ru	1
	TOTAL: 10	451
No colon-space in header
	csiph-out.news.xs4all.nl	14
	csiph-out.feed.uzoreto.com	4
	csiph-out.feeder.erje.net	1
	TOTAL: 3	19
TOTAL: 4		757

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	18809	1237	13968	23	61	6%	23:59:54
2	news.bbs.nz	29013	404	14472	51	0	1%	23:59:53
3	fu-berlin.de	14344	283	14061	0	0	1%	23:59:52
4	news.samoylyk.net	15727	279	14931	4	116	1%	23:59:53
5	tnet	17003	182	14746	2	0	1%	23:59:54
6	newsfeed.xs4all.nl	14910	121	14413	11	152	0%	23:59:53
7	news.freedyn.net	15450	60	15093	0	21	0%	23:59:53
8	neva.ru	13678	54	13355	109	66	0%	23:59:53
9	xmission	13027	28	11092	3	0	0%	23:59:43
10	news.uzoreto.com	14814	14	14523	128	0	0%	23:59:53
11	usenet.pasdenom.info	15446	11	14825	8	6	0%	23:59:53
12	erje	14215	6	14000	148	0	0%	23:59:31
13	eternal-september	13108	5	12346	6	0	0%	23:59:53
14	aioe	11502	2	11074	0	0	0%	23:59:53
15	news.swapon.de	1378	1	1365	0	0	0%	23:54:32
16	neodome.net	15480	1	15047	0	0	0%	23:59:53
17	news.mixmin.net	12561	1	12233	0	0	0%	23:59:46
18	weretis.net	14184	0	13979	0	0	0%	23:59:54
19	news.fcku.it	1035	0	1032	0	0	0%	21:26:25
TOTAL: 19		265684	2689	236555	493	422	1%	453:18:21

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	4.8 MB	114.9 KB	5.0 MB	0.1 KB/s	4.0 KB	23:59:54
2	fu-berlin.de	2.4 MB	0.0 KB	2.4 MB	0.0 KB/s	8.5 KB	23:59:52
3	news.samoylyk.net	2.4 MB	3.9 KB	2.4 MB	0.0 KB/s	8.5 KB	23:59:53
4	news.bbs.nz	1.0 MB	133.5 KB	1.1 MB	0.0 KB/s	2.6 KB	23:59:53
5	news.freedyn.net	498.9 KB	0.0 KB	498.9 KB	0.0 KB/s	8.3 KB	23:59:53
6	tnet	465.2 KB	5.3 KB	470.4 KB	0.0 KB/s	2.6 KB	23:59:54
7	newsfeed.xs4all.nl	304.0 KB	62.3 KB	366.3 KB	0.0 KB/s	2.8 KB	23:59:53
8	neva.ru	127.4 KB	236.5 KB	363.9 KB	0.0 KB/s	2.2 KB	23:59:53
9	xmission	91.8 KB	6.1 KB	97.9 KB	0.0 KB/s	3.2 KB	23:59:43
10	news.mixmin.net	36.4 KB	0.0 KB	36.4 KB	0.0 KB/s	36.4 KB	23:59:46
11	news.uzoreto.com	34.3 KB	286.2 KB	320.5 KB	0.0 KB/s	2.3 KB	23:59:53
12	usenet.pasdenom.info	33.7 KB	17.6 KB	51.3 KB	0.0 KB/s	2.7 KB	23:59:53
13	eternal-september	22.1 KB	31.8 KB	53.8 KB	0.0 KB/s	4.9 KB	23:59:53
14	erje	17.2 KB	440.1 KB	457.4 KB	0.0 KB/s	3.0 KB	23:59:31
15	aioe	4.4 KB	0.0 KB	4.4 KB	0.0 KB/s	2.2 KB	23:59:53
16	neodome.net	2.6 KB	0.0 KB	2.6 KB	0.0 KB/s	2.6 KB	23:59:53
17	news.swapon.de	1.8 KB	0.0 KB	1.8 KB	0.0 KB/s	1.8 KB	23:54:32
18	weretis.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:54
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:26:25
TOTAL: 19		12.2 MB	1.3 MB	13.5 MB	0.0 KB/s	4.3 KB	453:18:21

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	ppp-94-68-97-189.home.otenet.gr	1	27	56.2 KB	1	00:00:21
2	a131033.upc-a.chello.nl	12	18	35.2 KB	2	01:57:47
3	047-035-184-231.res.spectrum.com	22	9	21.9 KB	2	06:14:03
TOTAL: 3		35	54	113.3 KB	5	34:21:04

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.home.otenet.gr	2	27	56.2 KB	1	00:00:24
2	*.upc-a.chello.nl	12	18	35.2 KB	2	01:57:47
3	*.res.spectrum.com	22	9	21.9 KB	2	06:14:03
4	*.ph.ph.cox.net	4	0	0.0 KB	0	02:00:18
5	unresolved	76	0	0.0 KB	0	24:08:28
6	*.static.otenet.gr	2	0	0.0 KB	0	00:00:02
TOTAL: 6		118	54	113.3 KB	5	34:21:04

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
91.187.33.87	4.651	1.213	24:08:25
047-035-184-231.res.spectrum.com	1.457	0.363	06:14:03
ip72-222-200-117.ph.ph.cox.net	0.452	0.048	02:00:18
a131033.upc-a.chello.nl	0.877	0.164	01:57:47
ppp-94-68-97-189.home.otenet.gr	0.106	0.016	00:00:21
o04n9v.static.otenet.gr	0.139	0.016	00:00:02
athedsl-152558.home.otenet.gr	0.056	0.024	00:00:02
185.100.87.246	0.134	0.024	00:00:02
192.35.168.220	0.137	0.024	00:00:01
TOTAL: 9	8.009	1.892	34:21:04

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	72
ip72-222-200-117.ph.ph.cox.net	4
185.100.87.246	2
o04n9v.static.otenet.gr	2
192.35.168.220	2
athedsl-152558.home.otenet.gr	1
TOTAL: 6	83

NNRP unrecognized commands (by host):

System	Conn
192.35.168.220	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
047-035-184-231.res.spectrum.com	9	1
ip72-222-200-117.ph.ph.cox.net	4	0
91.187.33.87	1	0
TOTAL: 3	14	2

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Aug 12 04:15:00 -- Aug 13 04:15:00