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

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Aug 31 07:31:25 csiph nnrpd[38233]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Aug 31 07:31:25 csiph nnrpd[38233]: 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	18858	46.9%	4.9 MB	62.2%
innd	17093	42.5%	2.3 MB	28.9%
innfeed	3387	8.4%	612.9 KB	7.7%
nnrpd	871	2.2%	94.2 KB	1.2%
nocem	32	0.1%	4.2 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	40242	100.0%	7.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	313354	57.7%
Negative hits	211528	38.9%
Do not exist	18180	3.3%
Cache misses	186	0.0%
TOTAL: 4	543248	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.570	0.0%	40	0.000	14.250	41.750
article cleanup	00:00:00.111	0.0%	17851	0.000	0.006	0.043
article logging	00:00:00.279	0.0%	18317	0.000	0.015	0.172
article parse	00:00:02.180	0.0%	56394	0.000	0.039	0.432
article write	00:00:14.158	0.0%	15062	0.171	0.940	22.180
artlog/artcncl	00:00:00.000	0.0%	40	0.000	0.000	0.000
artlog/artparse	00:00:00.007	0.0%	487	0.000	0.014	0.167
data move	00:00:00.173	0.0%	514751	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.127	0.0%	40	0.000	3.175	21.000
hishave/artcncl	00:00:00.037	0.0%	40	0.000	0.925	4.750
hishave/artparse	00:00:00.000	0.0%	484	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:21.667	0.0%	546991	0.001	0.040	0.259
history sync	00:00:01.043	0.0%	388	0.000	2.688	12.000
history write	00:00:35.673	0.0%	17839	0.066	2.000	8.669
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:01.597	0.0%	484	0.000	3.300	47.000
idle	23:57:51.464	99.8%	519615	95.213	166.030	433.761
nntp read	00:00:01.926	0.0%	522868	0.001	0.004	0.008
overview write	00:00:47.892	0.1%	15062	0.176	3.180	54.545
perl filter	00:00:24.782	0.0%	17836	1.099	1.389	2.299
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.240	0.0%	30156	0.000	0.008	0.036
TOTAL: 24:01:00.508	24:00:23.926	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.033	0.0%	15246	0.000	0.002	0.017
article prepare	00:00:00.000	0.0%	11768	0.000	0.000	0.000
article read	00:00:00.275	0.0%	1584	0.000	0.174	44.000
backlog stats	00:00:00.173	0.0%	351937	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.348	0.0%	277132	0.002	0.005	0.009
data write	00:00:01.565	0.0%	264133	0.002	0.006	0.014
idle	23:53:34.926	100.0%	351937	140.080	244.404	673.621
status file	00:00:00.261	0.0%	235	0.000	1.111	40.500
TOTAL: 23:53:47.613	23:53:38.581	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	36:06:42.243	99.8%	522	49.333	249046.443	600115.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.004	0.0%	1191	0.000	0.003	0.167
readart	00:00:00.175	0.0%	10	0.000	17.500	48.000
TOTAL: 36:11:26.683	36:06:42.422	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	3	14675	4030	10577	68	27%	24:21:25
2	news-out.mixmin.net	3	15974	2778	12945	251	17%	24:08:54
3	feeder.eternal-september.org	4	15391	1953	13353	85	12%	20:47:41
4	csiph-out.news.xs4all.nl	861	16936	1181	14658	1097	6%	71:28:38
5	news-out.aioe.org	1	14774	1055	13647	72	7%	23:59:55
6	newsfeed.neva.ru	137	16256	950	14954	352	5%	71:08:47
7	newsfeed.fu-berlin.de	5	17710	902	16671	137	5%	31:06:11
8	csiph-out.feeder.erje.net	1440	75865	842	74720	303	1%	118:42:14
9	csiph-out.news.weretis.net	3	30983	667	29938	378	2%	46:53:42
10	news.fcku.it	11	2000	266	1730	4	13%	21:49:25
11	news-out.neodome.net	2	16239	157	16029	53	0%	23:59:49
12	csiph-out.feed.uzoreto.com	864	23543	144	22958	441	0%	71:11:49
13	nntp.pasdenom.info	2	9854	53	9790	11	0%	23:58:58
14	news.swapon.de	3	1586	37	1548	1	2%	23:38:25
15	news.freedyn.net	1	18152	31	18022	99	0%	23:59:51
16	csiph-out.news.tnetconsulting.net	2	13942	29	13880	33	0%	23:59:53
17	news.samoylyk.net	1	14842	12	14828	2	0%	23:59:52
18	news.bbs.nz	1	13572	2	13570	0	0%	23:59:50
19	xanadu-bbs.net	26	555	0	554	1	0%	20:56:39
20	localhost	26	0	0	0	0	0%	00:00:05
TOTAL: 20		3396	332849	15089	314372	3388	4%	714:12:03

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	12.1 MB	0.0 KB	5.0 MB	17.1 MB	70%	4.3 KB
2	news-out.mixmin.net	5.6 MB	4.4 KB	483.1 KB	6.1 MB	92%	2.1 KB
3	feeder.eternal-september.org	5.2 MB	4.4 KB	1.9 MB	7.0 MB	73%	3.5 KB
4	csiph-out.feeder.erje.net	3.8 MB	7.5 KB	2.0 MB	5.8 MB	65%	5.2 KB
5	news-out.aioe.org	3.6 MB	4.4 KB	189.7 KB	3.8 MB	95%	3.4 KB
6	csiph-out.news.xs4all.nl	3.2 MB	0.0 KB	12.5 MB	15.6 MB	20%	7.0 KB
7	newsfeed.neva.ru	2.6 MB	6.9 KB	878.0 KB	3.5 MB	75%	2.8 KB
8	newsfeed.fu-berlin.de	2.2 MB	0.0 KB	697.8 KB	2.8 MB	75%	2.8 KB
9	csiph-out.news.weretis.net	1.9 MB	4.5 KB	3.0 MB	5.0 MB	38%	4.9 KB
10	csiph-out.feed.uzoreto.com	581.1 KB	4.9 KB	12.5 MB	13.1 MB	4%	22.9 KB
11	news.fcku.it	454.9 KB	0.0 KB	6.2 KB	461.1 KB	98%	1.7 KB
12	news-out.neodome.net	437.0 KB	4.5 KB	85.5 KB	526.9 KB	82%	2.5 KB
13	news.freedyn.net	250.7 KB	2.5 KB	2.0 MB	2.3 MB	10%	17.8 KB
14	nntp.pasdenom.info	130.8 KB	0.0 KB	13.9 KB	144.8 KB	90%	2.3 KB
15	csiph-out.news.tnetconsulting.net	102.9 KB	2.0 KB	119.4 KB	224.3 KB	45%	3.6 KB
16	news.swapon.de	87.6 KB	0.0 KB	0.9 KB	88.5 KB	98%	2.3 KB
17	news.samoylyk.net	45.4 KB	4.4 KB	4.4 KB	54.3 KB	83%	3.9 KB
18	news.bbs.nz	2.3 KB	0.0 KB	0.0 KB	2.3 KB	100%	1.1 KB
19	xanadu-bbs.net	0.0 KB	0.0 KB	80.7 KB	80.7 KB	0%	80.7 KB
20	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		42.2 MB	50.4 KB	41.4 MB	83.7 MB	50%	4.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 31 04:15:00 - 04:59:59	256	1.7%	0.09	961.0 KB	2.2%	0.36
Aug 31 05:00:00 - 05:59:59	440	2.9%	0.12	1.2 MB	3.0%	0.35
Aug 31 06:00:00 - 06:59:59	423	2.8%	0.12	1.1 MB	2.6%	0.31
Aug 31 07:00:00 - 07:59:59	485	3.2%	0.13	1.2 MB	2.9%	0.35
Aug 31 08:00:00 - 08:59:59	666	4.4%	0.18	1.8 MB	4.3%	0.52
Aug 31 09:00:00 - 09:59:59	754	5.0%	0.21	1.7 MB	3.9%	0.47
Aug 31 10:00:00 - 10:59:59	711	4.7%	0.20	1.7 MB	4.0%	0.48
Aug 31 11:00:00 - 11:59:59	758	5.0%	0.21	2.0 MB	4.6%	0.56
Aug 31 12:00:00 - 12:59:59	700	4.6%	0.19	1.8 MB	4.3%	0.52
Aug 31 13:00:00 - 13:59:59	743	4.9%	0.21	1.9 MB	4.5%	0.55
Aug 31 14:00:00 - 14:59:59	756	5.0%	0.21	2.3 MB	5.3%	0.64
Aug 31 15:00:00 - 15:59:59	800	5.3%	0.22	2.0 MB	4.8%	0.57
Aug 31 16:00:00 - 16:59:59	940	6.2%	0.26	2.7 MB	6.4%	0.77
Aug 31 17:00:00 - 17:59:59	885	5.9%	0.25	2.4 MB	5.7%	0.69
Aug 31 18:00:00 - 18:59:59	780	5.2%	0.22	2.2 MB	5.2%	0.62
Aug 31 19:00:00 - 19:59:59	849	5.6%	0.24	2.4 MB	5.6%	0.67
Aug 31 20:00:00 - 20:59:59	810	5.4%	0.23	2.2 MB	5.3%	0.63
Aug 31 21:00:00 - 21:59:59	679	4.5%	0.19	2.1 MB	5.0%	0.60
Aug 31 22:00:00 - 22:59:59	585	3.9%	0.16	2.0 MB	4.7%	0.56
Aug 31 23:00:00 - 23:59:59	482	3.2%	0.13	1.6 MB	3.7%	0.45
Sep 1 00:00:00 - 00:59:59	422	2.8%	0.12	1.6 MB	3.9%	0.47
Sep 1 01:00:00 - 01:59:59	361	2.4%	0.10	1002.9 KB	2.3%	0.28
Sep 1 02:00:00 - 02:59:59	420	2.8%	0.12	1.3 MB	3.1%	0.37
Sep 1 03:00:00 - 03:59:59	270	1.8%	0.07	906.0 KB	2.1%	0.25
Sep 1 04:00:00 - 04:15:00	84	0.6%	0.09	263.1 KB	0.6%	0.29
TOTAL: 24:00:00	15059	100.0%	0.17	42.3 MB	100.0%	0.50

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1117	37	0	1080
2	csiph-out.feed.uzoreto.com	445	23	2	420
3	newsfeed.fu-berlin.de	445	32	0	413
4	csiph-out.news.weretis.net	426	67	1	358
5	newsfeed.neva.ru	355	61	2	292
6	news-out.mixmin.net	306	6	1	299
7	csiph-out.feeder.erje.net	155	48	3	104
8	news.freedyn.net	107	0	1	106
9	feeder.eternal-september.org	93	29	1	63
10	newsfeeds.xmission.com	93	7	0	86
11	news-out.aioe.org	91	64	1	26
12	csiph-out.news.tnetconsulting.net	52	20	1	31
13	news-out.neodome.net	50	0	1	49
14	nntp.pasdenom.info	11	0	0	11
15	news.swapon.de	8	0	0	8
16	news.fcku.it	4	3	0	1
17	xanadu-bbs.net	1	0	0	1
18	news.samoylyk.net	1	0	1	0
TOTAL: 18		3760	397	15	3348

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	50
lada.talk	50
scinet.stats	27
it-alt.sport.calcio.juventus	26
alt.politics.usa	21
mozilla.support.thunderbird	16
alt.culture	15
alt.bbs.synchronet	15
alt.tv.comedy-central.daily-show	8
fido7.ru.android	7
mozilla.support.seamonkey	7
alt.philosophy	6
spline.eisfair	6
alt.politics	6
alt.binaries.pictures.purebeauty	6
alt.job	6
alt.slack.goathead	5
trollnet.cinema	5
lada.auto	5
oesterreich.test	4
TOTAL: 92	397

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1535
EMP (phn path)	442
EMP (phl)	187
0	64
EMP (phn nph)	52
Too many newsgroups	49
Scoring filter	24
Binary Payload	10
HTML Multipart	9
Too many newsgroups (meow)	8
Binary: misplaced binary	6
HTML post	2
TOTAL: 12	2388

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	105
	csiph-out.feed.uzoreto.com	13
	csiph-out.news.xs4all.nl	11
	TOTAL: 3	129
Huge articles
	csiph-out.feed.uzoreto.com	167
	csiph-out.news.xs4all.nl	140
	newsfeeds.xmission.com	58
	news.freedyn.net	38
	csiph-out.feeder.erje.net	37
	feeder.eternal-september.org	26
	csiph-out.news.weretis.net	20
	newsfeed.fu-berlin.de	3
	csiph-out.news.tnetconsulting.net	1
	news-out.mixmin.net	1
	TOTAL: 11	492
Including strange strings
	newsfeed.fu-berlin.de	339
	news-out.mixmin.net	56
	newsfeeds.xmission.com	27
	news-out.aioe.org	15
	csiph-out.news.xs4all.nl	8
	news.swapon.de	7
	csiph-out.news.weretis.net	5
	csiph-out.feeder.erje.net	5
	csiph-out.feed.uzoreto.com	4
	newsfeed.neva.ru	1
	TOTAL: 10	467
No colon-space in header
	csiph-out.news.xs4all.nl	3
	TOTAL: 1	3
TOTAL: 4		1091

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	19044	1024	13970	42	102	5%	23:55:35
2	news.bbs.nz	28454	602	14038	21	0	2%	23:50:13
3	fu-berlin.de	14049	317	13732	0	0	2%	23:50:13
4	news.samoylyk.net	15560	307	14697	4	0	1%	23:53:07
5	tnet	16453	184	14482	2	0	1%	23:50:13
6	xmission	12914	70	10575	3	0	0%	23:50:06
7	neva.ru	13606	55	13377	90	0	0%	23:50:13
8	newsfeed.xs4all.nl	14159	34	13753	5	116	0%	23:56:22
9	news.freedyn.net	15123	30	14822	0	0	0%	23:54:28
10	eternal-september	13091	14	12382	7	0	0%	23:50:13
11	usenet.pasdenom.info	15198	14	14648	3	5	0%	23:58:59
12	news.mixmin.net	12069	8	11753	0	0	0%	23:56:58
13	news.uzoreto.com	14547	5	14232	19	0	0%	23:50:00
14	neodome.net	15263	5	14817	0	0	0%	23:50:12
15	aioe	11212	4	10711	0	0	0%	23:55:14
16	erje	14110	4	13926	69	0	0%	23:50:13
17	weretis.net	14013	1	13901	0	0	0%	23:57:29
18	news.swapon.de	1581	0	1571	0	0	0%	23:53:28
19	news.fcku.it	1615	0	1605	0	0	0%	21:10:15
TOTAL: 19		262061	2678	232992	265	223	1%	451:03:31

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	xanadu-bbs	4.0 MB	185.4 KB	4.2 MB	4.0 KB	23:55:35
2	fu-berlin.de	2.0 MB	0.0 KB	2.0 MB	6.5 KB	23:50:13
3	news.samoylyk.net	2.0 MB	4.3 KB	2.0 MB	6.6 KB	23:53:07
4	news.bbs.nz	1.8 MB	91.5 KB	1.8 MB	3.0 KB	23:50:13
5	tnet	542.5 KB	5.2 KB	547.7 KB	2.9 KB	23:50:13
6	news.freedyn.net	300.5 KB	0.0 KB	300.5 KB	10.0 KB	23:54:28
7	xmission	185.0 KB	8.2 KB	193.2 KB	2.6 KB	23:50:06
8	neva.ru	124.0 KB	283.4 KB	407.4 KB	2.8 KB	23:50:13
9	newsfeed.xs4all.nl	85.8 KB	40.9 KB	126.7 KB	3.2 KB	23:56:22
10	eternal-september	41.7 KB	14.8 KB	56.6 KB	2.7 KB	23:50:13
11	usenet.pasdenom.info	34.9 KB	15.2 KB	50.0 KB	2.9 KB	23:58:59
12	news.mixmin.net	19.1 KB	0.0 KB	19.1 KB	2.4 KB	23:56:58
13	aioe	9.8 KB	0.0 KB	9.8 KB	2.4 KB	23:55:14
14	news.uzoreto.com	8.6 KB	55.5 KB	64.1 KB	2.7 KB	23:50:00
15	neodome.net	7.2 KB	0.0 KB	7.2 KB	1.4 KB	23:50:12
16	erje	6.0 KB	183.9 KB	189.8 KB	2.6 KB	23:50:13
17	weretis.net	0.6 KB	0.0 KB	0.6 KB	0.6 KB	23:57:29
18	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0	23:53:28
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0	21:10:15
TOTAL: 19		11.1 MB	888.3 KB	12.0 MB	4.2 KB	451:03:31

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	8.44.58.66	1	4	8.9 KB	1	00:00:04
2	a131033.upc-a.chello.nl	44	3	6.8 KB	3	07:46:44
3	096-042-018-019.res.spectrum.com	7	3	6.8 KB	2	02:24:25
TOTAL: 3		52	10	22.6 KB	6	36:11:25

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	73	4	8.9 KB	1	23:59:51
2	*.res.spectrum.com	7	3	6.8 KB	2	02:24:25
3	*.upc-a.chello.nl	44	3	6.8 KB	3	07:46:44
4	*.security.ipip.net	1	0	0.0 KB	0	00:00:00
5	*.home.otenet.gr	1	0	0.0 KB	0	00:00:01
6	*.ph.ph.cox.net	4	0	0.0 KB	0	02:00:19
7	*.internet-census.org	6	0	0.0 KB	0	00:00:02
TOTAL: 7		136	10	22.6 KB	6	36:11:25

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
91.187.33.87	4.618	1.123	23:59:47
a131033.upc-a.chello.nl	3.019	0.610	07:46:44
096-042-018-019.res.spectrum.com	0.491	0.142	02:24:25
ip72-222-200-117.ph.ph.cox.net	0.430	0.066	02:00:19
8.44.58.66	0.096	0.022	00:00:04
sh-ams-nl-gp1-wk107.internet-census.org	0.400	0.046	00:00:02
ppp-2-87-179-175.home.otenet.gr	0.076	0.000	00:00:01
scan-141.security.ipip.net	0.068	0.008	00:00:00
TOTAL: 8	9.198	2.017	36:11:25

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	72
sh-ams-nl-gp1-wk107.internet-census.org	6
ip72-222-200-117.ph.ph.cox.net	4
scan-141.security.ipip.net	1
ppp-2-87-179-175.home.otenet.gr	1
TOTAL: 5	84

NNRP unrecognized commands (by host):

System	Conn
sh-ams-nl-gp1-wk107.internet-census.org	10
scan-141.security.ipip.net	4
TOTAL: 2	14

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
096-042-018-019.res.spectrum.com	4	0
ip72-222-200-117.ph.ph.cox.net	4	0
TOTAL: 2	8	1

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Aug 31 04:15:00 -- Sep 1 04:15:00