Re: Why query plan is different?

From: Andrzej Zawadzki <zawadaa(at)gmail(dot)com>
To: postgres performance list <pgsql-performance(at)postgresql(dot)org>
Subject: Re: Why query plan is different?
Date: 2016-10-10 21:17:09
Message-ID: 8aa28a9c-95c1-a8bb-e542-e7e4fd499c27@gmail.com
Views: Raw Message | Whole Thread | Download mbox | Resend email
Thread:
Lists: pgsql-performance

<html>
<head>
<meta content="text/html; charset=utf-8" http-equiv="Content-Type">
</head>
<body bgcolor="#FFFFFF" text="#000000">
<div class="moz-cite-prefix">On 10.10.2016 17:31, Andrzej Zawadzki
wrote:<br>
</div>
<blockquote cite="mid:bb1d234e-62ee-70e8-44ef-9b0919ec1268(at)wp(dot)pl"
type="cite">
<meta http-equiv="content-type" content="text/html; charset=utf-8">
Hi,<br>
Today, I noticed strange situation:<br>
<br>
The same query run on different servers has very different plan:<br>
<br>
Q: SELECT b.* FROM kredytob b  WHERE pesel = '22222222222'  ORDER
BY b.id DESC LIMIT 1 <br>
<br>
Slow plan:<br>
<br>
"Limit  (cost=0.43..28712.33 rows=1 width=4) (actual
time=2574.041..2574.044 rows=1 loops=1)"<br>
"  Output: id"<br>
"  Buffers: shared hit=316132 read=110001"<br>
"  -&gt;  Index Scan Backward using kredytob_pkey on
public.kredytob b  (cost=0.43..3244444.80 rows=113 width=4)
(actual time=2574.034..2574.034 rows=1 loops=1)"<br>
"        Output: id"<br>
"        Filter: (b.pesel = '22222222222'::bpchar)"<br>
"        Rows Removed by Filter: 433609"<br>
"        Buffers: shared hit=316132 read=110001"<br>
"Planning time: 0.414 ms"<br>
"Execution time: 2574.139 ms"<br>
<br>
<br>
Fast plan:<br>
"Limit  (cost=115240.66..115240.66 rows=1 width=4) (actual
time=463.275..463.276 rows=1 loops=1)"<br>
"  Output: id"<br>
"  Buffers: shared hit=14661 read=4576"<br>
"  -&gt;  Sort  (cost=115240.66..115240.94 rows=112 width=4)
(actual time=463.271..463.271 rows=1 loops=1)"<br>
"        Output: id"<br>
"        Sort Key: b.id DESC"<br>
"        Sort Method: top-N heapsort  Memory: 25kB"<br>
"        Buffers: shared hit=14661 read=4576"<br>
"        -&gt;  Index Scan using
kredytob_pesel_typkred_opclass_idx on public.kredytob b 
(cost=0.43..115240.10 rows=112 width=4) (actual
time=311.347..463.183 rows=5 loops=1)"<br>
"              Output: id"<br>
"              Index Cond: (b.pesel = '22222222222'::bpchar)"<br>
"              Buffers: shared hit=14661 read=4576"<br>
"Planning time: 0.383 ms"<br>
"Execution time: 463.324 ms"<br>
<br>
Data is almost equal - "slow" has a few more rows in table.
("Fast" is a copy from 1 am today).<br>
Why runtime is slower?<br>
</blockquote>
<br>
I made another INDEX, without opclass:<br>
<br>
CREATE INDEX kredytob_pesel_typkred_idx<br>
  ON public.kredytob<br>
  USING btree<br>
  (pesel COLLATE pg_catalog."default", typkred);<br>
<br>
after that: analyze kredytob;<br>
<br>
And now:<br>
"Limit  (cost=333.31..333.31 rows=1 width=4) (actual
time=0.100..0.102 rows=1 loops=1)"<br>
"  Output: id"<br>
"  Buffers: shared hit=8"<br>
"  -&gt;  Sort  (cost=333.31..333.59 rows=114 width=4) (actual
time=0.095..0.095 rows=1 loops=1)"<br>
"        Output: id"<br>
"        Sort Key: b.id DESC"<br>
"        Sort Method: top-N heapsort  Memory: 25kB"<br>
"        Buffers: shared hit=8"<br>
"        -&gt;  Index Scan using kredytob_pesel_typkred_idx on
public.kredytob b  (cost=0.43..332.74 rows=114 width=4) (actual
time=0.046..0.065 rows=5 loops=1)"<br>
"              Output: id"<br>
"              Index Cond: (b.pesel = '22222222222'::bpchar)"<br>
"              Buffers: shared hit=8"<br>
"Planning time: 0.438 ms"<br>
"Execution time: 0.154 ms"<br>
<br>
So, what is a reason that "SLOW" server doesn't like opclass index?<br>
<br>
-- <br>
Andrzej<br>
</body>
</html>

Attachment Content-Type Size
unknown_filename text/html 3.9 KB

In response to

Responses

Browse pgsql-performance by date

  From Date Subject
Next Message Pavel Stehule 2016-10-11 01:47:24 Re: Why query plan is different?
Previous Message Andrzej Zawadzki 2016-10-10 20:51:07 Re: Why query plan is different?