Skip to content

Buildpack fails when trying to untar Ruby #26

New issue
New issue
Closed
Closed
Buildpack fails when trying to untar Ruby#26

Description

@pivotal-feedback
pivotal-feedback
opened on Dec 4, 2014

When pushing an app, staging fails on PWS when the buildpack tries to untar Ruby. We see the same behavior for 2.1.4 and 2.1.3 (haven't tried 2.1.2). Below is the output and corresponding logs:

Output from push:

 |2.1.3| montgomery in ~/workspace/feedback
± ek+wr |master ✗| → cf push web
Using manifest file /Users/pivotal/workspace/feedback/manifest.yml

Updating app web in org Pivotal Feedback / space staging as labs-feedback-team@pivotal.io...
OK

Uploading web...
Uploading app files from: /Users/pivotal/workspace/feedback
Uploading 1.5M, 842 files
OK
Binding service redis to app web in org Pivotal Feedback / space staging as labs-feedback-team@pivotal.io...
OK

Stopping app web in org Pivotal Feedback / space staging as labs-feedback-team@pivotal.io...
OK

Starting app web in org Pivotal Feedback / space staging as labs-feedback-team@pivotal.io...
OK
-----> Downloaded app package (812K)

FAILED
Server error, status code: 400, error code: 170004, message: App staging failed in the buildpack compile phase

TIP: use 'cf logs web --recent' for more information

And logs:

± ek+wr |master ✗| → cf logs web --recent
Connected, dumping recent logs for app web in org Pivotal Feedback / space staging as labs-feedback-team@pivotal.io...


2014-12-04T09:48:51.48-0800 [API]     OUT Tried to stop app that never received a start event
2014-12-04T09:48:51.50-0800 [API]     OUT Updated app with guid 8ab75c22-5c8b-411a-94ed-a82a79999ba9 ({"state"=>"STOPPED"})
2014-12-04T09:48:51.56-0800 [App/0]   OUT [37] - Gracefully shutting down workers...
2014-12-04T09:48:51.56-0800 [App/0]   ERR
2014-12-04T09:48:51.57-0800 [App/1]   ERR
2014-12-04T09:48:52.69-0800 [DEA]     OUT Stopping app instance (index 0) with guid 8ab75c22-5c8b-411a-94ed-a82a79999ba9
2014-12-04T09:48:52.69-0800 [DEA]     OUT Stopped app instance (index 0) with guid 8ab75c22-5c8b-411a-94ed-a82a79999ba9
2014-12-04T09:48:52.69-0800 [DEA]     OUT Stopping app instance (index 1) with guid 8ab75c22-5c8b-411a-94ed-a82a79999ba9
2014-12-04T09:48:52.69-0800 [DEA]     OUT Stopped app instance (index 1) with guid 8ab75c22-5c8b-411a-94ed-a82a79999ba9
2014-12-04T09:48:55.13-0800 [API]     OUT Updated app with guid 8ab75c22-5c8b-411a-94ed-a82a79999ba9 ({"name"=>"web", "command"=>"PRIVATE DATA HIDDEN", "instances"=>2, "memory"=>2048, "environment_json"=>"PRIVATE DATA HIDDEN"})
2014-12-04T09:50:23.29-0800 [DEA]     OUT Got staging request for app with id 8ab75c22-5c8b-411a-94ed-a82a79999ba9
2014-12-04T09:50:34.08-0800 [API]     OUT Updated app with guid 8ab75c22-5c8b-411a-94ed-a82a79999ba9 ({"state"=>"STARTED"})
2014-12-04T09:50:39.40-0800 [STG]     OUT -----> Downloaded app package (64M)
2014-12-04T09:50:41.59-0800 [STG]     OUT -----> Downloaded app buildpack cache (58M)
2014-12-04T09:50:45.23-0800 [STG]     ERR Cloning into '/tmp/buildpacks/ruby-buildpack'...
2014-12-04T09:50:46.28-0800 [STG]     ERR Note: checking out 'b476e5a351b69e4c3acfeacdcddd6fc02de5358e'.
2014-12-04T09:50:46.28-0800 [STG]     ERR You are in 'detached HEAD' state. You can look around, make experimental
2014-12-04T09:50:46.28-0800 [STG]     ERR changes and commit them, and you can discard any commits you make in this
2014-12-04T09:50:46.28-0800 [STG]     ERR state without impacting any branches by performing another checkout.
2014-12-04T09:50:46.28-0800 [STG]     ERR If you want to create a new branch to retain commits you create, you may
2014-12-04T09:50:46.28-0800 [STG]     ERR do so (now or later) by using -b with the checkout command again. Example:
2014-12-04T09:50:46.28-0800 [STG]     ERR   git checkout -b new_branch_name
2014-12-04T09:50:46.48-0800 [STG]     OUT Submodule 'buildpack-packager' (https://github.com/cf-buildpacks/buildpack-packager.git) registered for path 'buildpack-packager'
2014-12-04T09:50:46.54-0800 [STG]     ERR Cloning into 'buildpack-packager'...
2014-12-04T09:50:46.82-0800 [STG]     OUT Submodule path 'buildpack-packager': checked out '87560fd0a76ccaaeb71232bf34dc616ea8876338'
2014-12-04T09:50:46.99-0800 [STG]     OUT -------> Buildpack version 1.1.2
2014-12-04T09:50:47.30-0800 [STG]     OUT -----> Compiling Ruby/Rails
2014-12-04T09:50:49.25-0800 [STG]     OUT  !
2014-12-04T09:50:49.25-0800 [STG]     OUT  !     Command: 'set -o pipefail; curl --fail --retry 3 --retry-delay 1 --connect-timeout 10 --max-time 90 https://s3-external-1.amazonaws.com/heroku-buildpack-ruby/ruby-2.1.4.tgz -s -o - | tar zxf - ' failed unexpectedly:
2014-12-04T09:50:49.25-0800 [STG]     OUT  !
2014-12-04T09:50:49.25-0800 [STG]     OUT  !     gzip: stdin: unexpected end of file
2014-12-04T09:50:49.25-0800 [STG]     OUT  !     tar: Child returned status 1
2014-12-04T09:50:49.25-0800 [STG]     OUT  !     tar: Exiting with failure status due to previous errors
2014-12-04T09:50:49.25-0800 [STG]     OUT  !
2014-12-04T09:50:49.27-0800 [STG]     OUT Staging failed: Buildpack compilation step failed
2014-12-04T09:50:49.47-0800 [API]     ERR Encountered error: App staging failed in the buildpack compile phase
2014-12-04T09:52:03.27-0800 [API]     OUT Updated app with guid 8ab75c22-5c8b-411a-94ed-a82a79999ba9 ({"name"=>"web", "command"=>"PRIVATE DATA HIDDEN", "instances"=>2, "memory"=>2048, "environment_json"=>"PRIVATE DATA HIDDEN"})
2014-12-04T09:52:17.25-0800 [API]     OUT Tried to stop app that never received a start event
2014-12-04T09:52:17.27-0800 [API]     OUT Updated app with guid 8ab75c22-5c8b-411a-94ed-a82a79999ba9 ({"state"=>"STOPPED"})
2014-12-04T09:52:20.36-0800 [DEA]     OUT Got staging request for app with id 8ab75c22-5c8b-411a-94ed-a82a79999ba9
2014-12-04T09:52:24.75-0800 [API]     OUT Updated app with guid 8ab75c22-5c8b-411a-94ed-a82a79999ba9 ({"state"=>"STARTED"})
2014-12-04T09:52:25.04-0800 [STG]     OUT -----> Downloaded app package (816K)
2014-12-04T09:52:27.29-0800 [STG]     OUT -----> Downloaded app buildpack cache (58M)
2014-12-04T09:52:27.55-0800 [STG]     ERR Cloning into '/tmp/buildpacks/ruby-buildpack'...
2014-12-04T09:52:28.54-0800 [STG]     ERR Note: checking out 'b476e5a351b69e4c3acfeacdcddd6fc02de5358e'.
2014-12-04T09:52:28.54-0800 [STG]     ERR You are in 'detached HEAD' state. You can look around, make experimental
2014-12-04T09:52:28.54-0800 [STG]     ERR changes and commit them, and you can discard any commits you make in this
2014-12-04T09:52:28.54-0800 [STG]     ERR state without impacting any branches by performing another checkout.
2014-12-04T09:52:28.54-0800 [STG]     ERR If you want to create a new branch to retain commits you create, you may
2014-12-04T09:52:28.54-0800 [STG]     ERR do so (now or later) by using -b with the checkout command again. Example:
2014-12-04T09:52:28.54-0800 [STG]     ERR   git checkout -b new_branch_name
2014-12-04T09:52:28.78-0800 [STG]     OUT Submodule 'buildpack-packager' (https://github.com/cf-buildpacks/buildpack-packager.git) registered for path 'buildpack-packager'
2014-12-04T09:52:28.83-0800 [STG]     ERR Cloning into 'buildpack-packager'...
2014-12-04T09:52:29.13-0800 [STG]     OUT Submodule path 'buildpack-packager': checked out '87560fd0a76ccaaeb71232bf34dc616ea8876338'
2014-12-04T09:52:29.30-0800 [STG]     OUT -------> Buildpack version 1.1.2
2014-12-04T09:52:29.57-0800 [STG]     OUT -----> Compiling Ruby/Rails
2014-12-04T09:52:29.88-0800 [STG]     OUT  !
2014-12-04T09:52:29.88-0800 [STG]     OUT  !     Command: 'set -o pipefail; curl --fail --retry 3 --retry-delay 1 --connect-timeout 10 --max-time 90 https://s3-external-1.amazonaws.com/heroku-buildpack-ruby/ruby-2.1.4.tgz -s -o - | tar zxf - ' failed unexpectedly:
2014-12-04T09:52:29.88-0800 [STG]     OUT  !
2014-12-04T09:52:29.88-0800 [STG]     OUT  !     gzip: stdin: unexpected end of file
2014-12-04T09:52:29.88-0800 [STG]     OUT  !     tar: Child returned status 1
2014-12-04T09:52:29.88-0800 [STG]     OUT  !     tar: Exiting with failure status due to previous errors
2014-12-04T09:52:29.88-0800 [STG]     OUT  !
2014-12-04T09:52:29.90-0800 [STG]     OUT Staging failed: Buildpack compilation step failed
2014-12-04T09:52:30.08-0800 [API]     ERR Encountered error: App staging failed in the buildpack compile phase
2014-12-04T09:54:19.63-0800 [API]     OUT Updated app with guid 8ab75c22-5c8b-411a-94ed-a82a79999ba9 ({"name"=>"web", "command"=>"PRIVATE DATA HIDDEN", "instances"=>2, "memory"=>2048, "environment_json"=>"PRIVATE DATA HIDDEN"})
2014-12-04T09:54:39.63-0800 [API]     OUT Tried to stop app that never received a start event
2014-12-04T09:54:39.66-0800 [API]     OUT Updated app with guid 8ab75c22-5c8b-411a-94ed-a82a79999ba9 ({"state"=>"STOPPED"})
2014-12-04T09:54:42.63-0800 [DEA]     OUT Got staging request for app with id 8ab75c22-5c8b-411a-94ed-a82a79999ba9
2014-12-04T09:54:45.80-0800 [API]     OUT Updated app with guid 8ab75c22-5c8b-411a-94ed-a82a79999ba9 ({"state"=>"STARTED"})
2014-12-04T09:54:46.05-0800 [STG]     OUT -----> Downloaded app package (812K)
2014-12-04T09:54:48.33-0800 [STG]     OUT -----> Downloaded app buildpack cache (58M)
2014-12-04T09:54:48.58-0800 [STG]     ERR Cloning into '/tmp/buildpacks/ruby-buildpack'...
2014-12-04T09:54:49.56-0800 [STG]     ERR Note: checking out 'b476e5a351b69e4c3acfeacdcddd6fc02de5358e'.
2014-12-04T09:54:49.57-0800 [STG]     ERR You are in 'detached HEAD' state. You can look around, make experimental
2014-12-04T09:54:49.57-0800 [STG]     ERR changes and commit them, and you can discard any commits you make in this
2014-12-04T09:54:49.57-0800 [STG]     ERR state without impacting any branches by performing another checkout.
2014-12-04T09:54:49.57-0800 [STG]     ERR If you want to create a new branch to retain commits you create, you may
2014-12-04T09:54:49.57-0800 [STG]     ERR do so (now or later) by using -b with the checkout command again. Example:
2014-12-04T09:54:49.57-0800 [STG]     ERR   git checkout -b new_branch_name
2014-12-04T09:54:49.77-0800 [STG]     OUT Submodule 'buildpack-packager' (https://github.com/cf-buildpacks/buildpack-packager.git) registered for path 'buildpack-packager'
2014-12-04T09:54:49.82-0800 [STG]     ERR Cloning into 'buildpack-packager'...
2014-12-04T09:54:50.11-0800 [STG]     OUT Submodule path 'buildpack-packager': checked out '87560fd0a76ccaaeb71232bf34dc616ea8876338'
2014-12-04T09:54:50.28-0800 [STG]     OUT -------> Buildpack version 1.1.2
2014-12-04T09:54:50.55-0800 [STG]     OUT -----> Compiling Ruby/Rails
2014-12-04T09:54:50.95-0800 [STG]     OUT  !
2014-12-04T09:54:50.96-0800 [STG]     OUT  !     Command: 'set -o pipefail; curl --fail --retry 3 --retry-delay 1 --connect-timeout 10 --max-time 90 https://s3-external-1.amazonaws.com/heroku-buildpack-ruby/ruby-2.1.3.tgz -s -o - | tar zxf - ' failed unexpectedly:
2014-12-04T09:54:50.96-0800 [STG]     OUT  !
2014-12-04T09:54:50.96-0800 [STG]     OUT  !     gzip: stdin: unexpected end of file
2014-12-04T09:54:50.96-0800 [STG]     OUT  !     tar: Child returned status 1
2014-12-04T09:54:50.96-0800 [STG]     OUT  !     tar: Exiting with failure status due to previous errors
2014-12-04T09:54:50.96-0800 [STG]     OUT  !
2014-12-04T09:54:50.97-0800 [STG]     OUT Staging failed: Buildpack compilation step failed
2014-12-04T09:54:51.16-0800 [API]     ERR Encountered error: App staging failed in the buildpack compile phase

We then tried curling the buildpack ourselves, and got a 403:

curl --fail --retry 3 --retry-delay 1 --connect-timeout 10 --max-time 90 https://s3-external-1.amazonaws.com/heroku-buildpack-ruby/ruby-2.1.3.tgz
curl: (22) The requested URL returned error: 403 Forbidden

Activity

  1. cf-gitbot commented on Dec 4, 2014

    @cf-gitbot
    cf-gitbot
    on Dec 4, 2014

    We have created an issue in Pivotal Tracker to manage this. You can view the current status of your issue at: https://www.pivotaltracker.com/story/show/83951350.

  2. jchesterpivotal commented on Dec 4, 2014

    @jchesterpivotal
    jchesterpivotal
    on Dec 4, 2014
    Contributor

    Hey guys, is there any reason you're using Ruby BP 1.1.2?

    BP 1.1.2 is broken for Ruby 2.1.3 and didn't include Ruby 2.1.4 at all. We added an error message, starting in 1.1.3, to say when a resource is not available in a buildpack.

    Can you check whether behaviour is the same for 1.1.3 or 1.1.4?

    Cheers,

    JC.

  3. jchesterpivotal commented on Dec 4, 2014

    @jchesterpivotal
    jchesterpivotal
    on Dec 4, 2014
    Contributor

    Oh, another note. Starting with Ruby 2.1.3, Heroku changed their URL structure -- which is why Ruby BP 1.1.2 is broken for Ruby 2.1.3. If you do this:

    curl -I https://s3-external-1.amazonaws.com/heroku-buildpack-ruby/cedar/ruby-2.1.3.tgz

    You should see where they put the binaries now.

  4. pivotal-feedback commented on Dec 4, 2014

    @pivotal-feedback
    pivotal-feedback
    on Dec 4, 2014
    Author

    We could force use of the new buildpack, but surprised it's not just always using the latest and greatest.

    Here's our current manifest.yml, note no buildpack is being specified:

    ---
env:
  REDIS_PROVIDER: REDISCLOUD_URL
  QUEUE_ALERT_SIZE: 50
services:
- redis
applications:
- name: web
  memory: 2G
  instances: 2
  command: bundle exec rake cf:on_first_instance db:migrate && bundle exec puma -C config/puma.rb

- name: worker
  no-route: true
  memory: 2G
  instances: 1
  command: bundle exec sidekiq

- name: clock
  no-route: true
  memory: 2G
  instances: 1
  command: bundle exec clockwork config/schedule.rb

  5. jchesterpivotal commented on Dec 4, 2014

    @jchesterpivotal
    jchesterpivotal
    on Dec 4, 2014
    Contributor

    I'm surprised too. Ruby BP 1.1.3 was in cf-release v192 and 1.1.4 was in v194.

    Looks like v194 has not yet been rolled out to PWS, but I would've thought you'd get 1.1.3 and a more helpful error message in your logs.

    I'm going to see if ops have an idea about it.

  6. pivotal-feedback commented on Dec 4, 2014

    @pivotal-feedback
    pivotal-feedback
    on Dec 4, 2014
    Author

    Forcing the buildpack to 1.1.3 worked. Between that AND giving ourselves more memory during deploy we now have our app running on Ruby 2.1.4!!!!!!!!!!!!!!

  7. jchesterpivotal commented on Dec 4, 2014

    @jchesterpivotal
    jchesterpivotal
    on Dec 4, 2014
    Contributor

    In the log the staging process is pulling Ruby BP 1.1.2 from github instead of using the PWS buildpack. My understanding is that this only happens if you specify a buildpack URL at push time.

    Is there a script or rake task or something?

  8. pivotal-feedback commented on Dec 4, 2014

    @pivotal-feedback
    pivotal-feedback
    on Dec 4, 2014
    Author

    Nope, nothing fancy. It seemed like there was some sort of buildpack cache. Maybe it cached 1.1.2 when we were specifying it trying to work through issue #24

  9. jchesterpivotal commented on Dec 5, 2014

    @jchesterpivotal
    jchesterpivotal
    on Dec 5, 2014
    Contributor

    How am I supposed to blame the user if you're not at fault? Sheesh :)

    My understanding is that re-pushing an app after a buildpack update should cause the new buildpack to be applied. I'll check with runtime if the CC decides to remember -b options even when a buildpack is updated,

  10. jchesterpivotal commented on Dec 5, 2014

    @jchesterpivotal
    jchesterpivotal
    on Dec 5, 2014
    Contributor

    Another question: do you have the session and log from pinning the buildpack to 1.1.3? It'd be interesting to compare to the previous output.

    And did you use -b or change the manifest?

  11. pivotal-feedback commented on Dec 5, 2014

    @pivotal-feedback
    pivotal-feedback
    on Dec 5, 2014
    Author

    We changed the manifest. Here's our new manifest and logs from a successful push of one app:
    Manifest:

    ---
env:
  REDIS_PROVIDER: REDISCLOUD_URL
  QUEUE_ALERT_SIZE: 50
services:
- redis
buildpack:
  https://github.com/cloudfoundry/ruby-buildpack#v1.1.3
applications:
- name: web
  memory: 2G
  instances: 2
  command: bundle exec rake cf:on_first_instance db:migrate && bundle exec puma -C config/puma.rb

- name: worker
  no-route: true
  memory: 2G
  instances: 1
  command: bundle exec sidekiq

- name: clock
  no-route: true
  memory: 2G
  instances: 1
  command: bundle exec clockwork config/schedule.rb

    Deploy logs of pushing the web app:

    $ cf push web
Using manifest file /home/ubuntu/pivotal-feedback/manifest.yml

Updating app web in org Pivotal Feedback / space staging as labs-feedback-team@pivotal.io...
OK

Uploading web...
Uploading app files from: /home/ubuntu/pivotal-feedback
Uploading 43.8M, 16036 files

Done uploading
OK
Binding service redis to app web in org Pivotal Feedback / space staging as labs-feedback-team@pivotal.io...
OK

Starting app web in org Pivotal Feedback / space staging as labs-feedback-team@pivotal.io...
-----> Downloaded app package (64M)
-----> Downloaded app buildpack cache (58M)
����Cloning into '/tmp/buildpacks/ruby-buildpack'...
Note: checking out '48a89f7ed6814cfce869bbaf9acb945baa10bd2f'.
You are in 'detached HEAD' state. You can look around, make experimental
changes and commit them, and you can discard any commits you make in this
state without impacting any branches by performing another checkout.
If you want to create a new branch to retain commits you create, you may
do so (now or later) by using -b with the checkout command again. Example:
  git checkout -b new_branch_name
����Submodule 'buildpack-packager' (https://github.com/cf-buildpacks/buildpack-packager.git) registered for path 'buildpack-packager'
Cloning into 'buildpack-packager'...
Submodule path 'buildpack-packager': checked out 'fec9c4598a6496fb9fa02c288076dc120754b0b5'
-------> Buildpack version 1.1.3
-----> Compiling Ruby/Rails
-----> Using Ruby version: ruby-2.1.4
-----> Installing dependencies using 1.6.3
       Running: bundle install --without development:test --path vendor/bundle --binstubs vendor/bundle/bin -j4 --deployment
       Using rake 10.3.2
       Using i18n 0.6.11
       Using json 1.8.1
       Using minitest 5.4.3
       Using thread_safe 0.3.4
       Using builder 3.2.2
       Using erubis 2.7.0
       Using rack 1.5.2
       Using mime-types 2.4.3
       Using arel 5.0.1.20140414130214
       Using pg 0.17.1
       Using addressable 2.3.6
       Using thor 0.19.1
       Using hike 1.2.3
       Using multi_json 1.10.1
       Using tilt 1.4.1
       Using angular-ui-bootstrap-rails 0.11.1
       Using angularjs-rails 1.2.25
       Using canonix 0.1.1
       Using timers 1.1.0
       Using sass 3.2.19
       Using orm_adapter 0.5.0
       Using chunky_png 1.3.1
       Using fssm 0.2.10
       Using connection_pool 2.0.0
       Using request_store 1.1.0
       Using execjs 2.2.1
       Using multipart-post 2.0.0
       Using hashie 3.3.1
       Using htmlentities 4.3.2
       Using httpclient 2.4.0
       Using jwt 1.0.0
       Using systemu 2.6.4
       Using mini_portile 0.6.0
       Using mixpanel-ruby 1.5.0
       Using multi_xml 0.5.5
       Using net-ldap 0.8.0
       Using newrelic_rpm 3.9.5.251
       Using bundler 1.6.3
       Using rails-assets-angular 1.2.26
       Using rails-assets-spin.js 2.0.1
       Using rails-assets-jquery 2.1.1
       Using rails-assets-underscore 1.7.0
       Using rails_serve_static_assets 0.0.2
       Using rails_stdout_logging 0.0.3
       Using redis 3.1.0
       Using mail 2.6.3
       Using tzinfo 1.2.2
       Using css_parser 1.3.5
       Using rack-test 0.6.2
       Using rack-protection 1.5.3
       Using puma 2.9.1
       Using select2-rails 3.5.9.1
       Using sprockets 2.11.0
       Using haml 4.0.5
       Using celluloid 0.15.2
       Using ckeditor 4.1.0
       Using bootstrap-sass 3.2.0.2
       Using compass 0.12.7
       Using uglifier 2.5.3
       Using faraday 0.9.0
       Using macaddr 1.7.1
       Using nokogiri 1.6.3.1
       Using rails-assets-bouil--angular-google-chart 0.0.11
       Using rails-assets-ng-table 0.3.3
       Using rails-assets-angular-spinner 0.5.1
       Using omniauth 1.2.2
       Using rails-assets-select2 3.5.1
       Using redis-namespace 1.5.1
       Using activesupport 4.1.8
       Using premailer 1.8.2
       Using sinatra 1.4.5
       Using rails_12factor 0.0.2
       Using compass-rails 2.0.0
       Using oauth2 1.0.0
       Using uuid 2.3.7
       Using sidekiq 3.2.5
       Using rails-assets-angular-ui-select2 0.0.5
       Using actionview 4.1.8
       Using activemodel 4.1.8
       Using pundit 0.3.0
       Using clockwork 1.0.0
       Using omniauth-oauth2 1.2.0
       Using ruby-saml 0.7.2
       Using actionpack 4.1.8
       Using active_model_serializers 0.8.0
       Using activerecord 4.1.8
       Using also_validates 0.0.2
       Using omniauth-google-oauth2 0.2.5
       Using actionmailer 4.1.8
       Using omniauth-saml 1.1.0
       Using draper 1.4.0
       Using railties 4.1.8
       Using sprockets-rails 2.2.0
       Using activerecord-postgresql-citext 0.1.0
       Using simple_form 3.0.2
       Using angular-rails-templates 0.1.3
       Using jquery-rails 3.1.2
       Using font-awesome-rails 4.2.0.0
       Using haml-rails 0.5.3
       Using momentjs-rails 2.8.3
       Using responders 1.1.1
       Using sass-rails 4.0.3
       Using rails 4.1.8
       Using elementaljs-rails 0.0.11
       Using premailer-rails 1.8.0
       Using pivotal-sass 0.0.3
       Using ngannotate-rails 0.10.1
       Using pivotal_auth 0.0.1 from git://github.com/pivotal-feedback/pivotal_auth.git (at dev)
       Your bundle is complete!
       Gems in the groups development and test were not installed.
       It was installed into ./vendor/bundle
       Bundle completed (0.79s)
       Cleaning up the bundler cache.
-----> Preparing app for Rails asset pipeline
       Running: rake assets:precompile
       Asset precompilation completed (7.53s)
       Cleaning assets
       Running: rake assets:clean
###### WARNING:
       Removing `vendor/bundle`.
       Checking in `vendor/bundle` is not supported. Please remove this directory
       and add it to your .gitignore. To vendor your gems with Bundler, use
       `bundle pack` instead.
-----> Uploading droplet (56M)

0 of 2 instances running, 2 starting
0 of 2 instances running, 2 starting
2 of 2 instances running

App started


OK
Showing health and status for app web in org Pivotal Feedback / space staging as labs-feedback-team@pivotal.io...
OK

requested state: started
instances: 2/2
usage: 2G x 2 instances
urls: feedback-staging.cfapps.io
last uploaded: Fri Dec 5 00:06:29 +0000 2014

     state     since                    cpu    memory         disk   
#0   running   2014-12-04 04:08:13 PM   0.0%   194.9M of 2G   208.4M of 1G   
#1 running 2014-12-04 04:08:09 PM 0.0% 191M of 2G 208.4M of 1G

  12. jchesterpivotal commented on Dec 9, 2014

    @jchesterpivotal
    jchesterpivotal
    on Dec 9, 2014
    Contributor

    OK, we spent some time today and we're able to recreate your problem.

    First things first. Here's a complete transcript of two pushes to PWS, the first with a -b, the second without. We'll call out what we think is interesting in the next comment.

    ruby-2.1.1| cortelyou in ~/workspace/ruby-issue-26
○ → CF_TRACE=true cf push sinatra1234567 -b https://github.com/cloudfoundry/ruby-buildpack#v1.1.2

VERSION:
6.6.2-0c953cf


REQUEST: [2014-12-09T11:44:21-05:00]
POST /oauth/token HTTP/1.1
Host: login.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/x-www-form-urlencoded
User-Agent: go-cli 6.6.2-0c953cf / darwin

grant_type=refresh_token&refresh_token=eyJhbGciOiJSUzI1NiJ9.eyJqdGkiOiJiZTdiZTQ3Ni0wMWRkLTRmOGUtOTYzMy02YWMwNWZjMDQ0ZTEiLCJzdWIiOiIzNDlkZWIwOS1hNTI2LTQwYzEtYWFmMy0xNzRmZGQ5ZWQ5NjAiLCJzY29wZSI6WyJzY2ltLnVzZXJpZHMiLCJjbG91ZF9jb250cm9sbGVyLndyaXRlIiwicGFzc3dvcmQud3JpdGUiLCJvcGVuaWQiLCJjbG91ZF9jb250cm9sbGVyLnJlYWQiXSwiaWF0IjoxNDE4MTQzMjMxLCJleHAiOjE0MjA3MzUyMzEsImNpZCI6ImNmIiwiaXNzIjoiaHR0cHM6Ly91YWEucnVuLnBpdm90YWwuaW8vb2F1dGgvdG9rZW4iLCJncmFudF90eXBlIjoicGFzc3dvcmQiLCJ1c2VyX25hbWUiOiJjZi1idWlsZHBhY2tzLWVuZ0BwaXZvdGFsLmlvIiwidXNlcl9pZCI6IjM0OWRlYjA5LWE1MjYtNDBjMS1hYWYzLTE3NGZkZDllZDk2MCIsImF1ZCI6WyJzY2ltIiwiY2xvdWRfY29udHJvbGxlciIsInBhc3N3b3JkIiwib3BlbmlkIl19.NKsOPgfEgLz7BYPHo_GKBozC01pBQYsjF8pDTRN6bK8XaXsWTUcl7PiS7knn9W-YLuhRR3RX6pbilOh2MliA5sJMecXXD5saf6buW9Su5UlDUz5o2SzqQUQGLEezA23sgZBIaKZ0TAcR8Ip4oeItaLR-27wLpNX2Y93LagMluwh9VZNDCzdXHJxcNoCoUfX9Gz3XjNkkDlldFL5DTr_NlPMqpX4nXLbMHDqBfr7B7kMPXNA0IY1mvo9dQ0XMFoB2CeECd1MWKMhleByGatCQBV5kMflGRTCdbrAusCNqet4Iwje3F45wL22HsozAF1uq6_LVazvN3Lny4IN3RzC9yw&scope=

RESPONSE: [2014-12-09T11:44:22-05:00]
HTTP/1.1 200 OK
Content-Length: 2223
Cache-Control: no-cache
Cache-Control: no-store
Cache-Control: no-store
Connection: keep-alive
Content-Type: application/json;charset=UTF-8
Date: Tue, 09 Dec 2014 16:44:45 GMT
Expires: Thu, 01 Jan 1970 00:00:00 GMT
Pragma: no-cache
Pragma: no-cache
Server: Apache-Coyote/1.1
X-Cf-Requestid: 5736d538-59d4-47dd-6990-d3a856095ed5
X-Cf-Requestid: d7b4c4b2-43ef-4b15-7c5d-07f4dc0cd601
X-Frame-Options: DENY

{"access_token":"[PRIVATE DATA HIDDEN]","token_type":"bearer","refresh_token":"[PRIVATE DATA HIDDEN]","expires_in":599,"scope":"scim.userids password.write openid cloud_controller.write cloud_controller.read","jti":"51805053-f7dd-41dc-9c61-dc018df3ff13"}

REQUEST: [2014-12-09T11:44:22-05:00]
GET /v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/apps?q=name%3Asinatra1234567&inline-relations-depth=1 HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/json
User-Agent: go-cli 6.6.2-0c953cf / darwin



RESPONSE: [2014-12-09T11:44:22-05:00]
HTTP/1.1 200 OK
Content-Length: 107
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:44:46 GMT
Server: nginx
X-Cf-Requestid: ce452d4d-cb42-40ac-6621-6edfd85b5c14
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: bf3e1af5-50fc-4930-6574-e6ad37024379::55a3c5da-857d-46b7-9d5c-d79afa6d3589

{
  "total_results": 0,
  "total_pages": 1,
  "prev_url": null,
  "next_url": null,
  "resources": [

  ]
}
Creating app sinatra1234567 in org cf-buildpacks / space development as cf-buildpacks-eng@pivotal.io...

REQUEST: [2014-12-09T11:44:22-05:00]
POST /v2/apps?async=true HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/json
User-Agent: go-cli 6.6.2-0c953cf / darwin

{"name":"sinatra1234567","space_guid":"1cb7bacc-8739-4811-8129-cc136d4a6763","buildpack":"https://github.com/cloudfoundry/ruby-buildpack#v1.1.2"}

RESPONSE: [2014-12-09T11:44:22-05:00]
HTTP/1.1 201 Created
Content-Length: 1339
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:44:46 GMT
Location: /v2/apps/e117d817-a114-435e-816f-0273fb4976a6
Server: nginx
X-Cf-Requestid: ebc8557b-f46b-4cb9-4ef2-bf0a1e73cc8b
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: 00649fe3-0d87-450f-4163-1a1bd458c18a::fe906dca-4adc-4099-93e4-792d7eb7b3b5

{
  "metadata": {
    "guid": "e117d817-a114-435e-816f-0273fb4976a6",
    "url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6",
    "created_at": "2014-12-09T16:44:46+00:00",
    "updated_at": null
  },
  "entity": {
    "name": "sinatra1234567",
    "production": false,
    "space_guid": "1cb7bacc-8739-4811-8129-cc136d4a6763",
    "stack_guid": "50688ae5-9bfc-4bf6-a4bf-caadb21a32c6",
    "buildpack": "https://github.com/cloudfoundry/ruby-buildpack#v1.1.2",
    "detected_buildpack": null,
    "environment_json": {

    },
    "memory": 1024,
    "instances": 1,
    "disk_quota": 1024,
    "state": "STOPPED",
    "version": "5cd9b598-9062-4ac9-899f-0db973e426a6",
    "command": null,
    "console": false,
    "debug": null,
    "staging_task_id": null,
    "package_state": "PENDING",
    "health_check_timeout": null,
    "staging_failed_reason": null,
    "docker_image": null,
    "package_updated_at": null,
    "detected_start_command": "",
    "space_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763",
    "stack_url": "/v2/stacks/50688ae5-9bfc-4bf6-a4bf-caadb21a32c6",
    "events_url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6/events",
    "service_bindings_url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6/service_bindings",
    "routes_url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6/routes"
  }
}
OK


REQUEST: [2014-12-09T11:44:22-05:00]
GET /v2/organizations/d43a652b-2e2e-49bc-a630-8d4c02ea9a5d/private_domains HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/json
User-Agent: go-cli 6.6.2-0c953cf / darwin



RESPONSE: [2014-12-09T11:44:22-05:00]
HTTP/1.1 200 OK
Content-Length: 107
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:44:46 GMT
Server: nginx
X-Cf-Requestid: c90f28ce-3c41-463a-76db-26ea8d5d8292
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: 244447e5-3f2f-4e83-4f98-5008573df37c::63473c7f-a1fb-471c-bb3f-10c43c96a2d9

{
  "total_results": 0,
  "total_pages": 1,
  "prev_url": null,
  "next_url": null,
  "resources": [

  ]
}

REQUEST: [2014-12-09T11:44:22-05:00]
GET /v2/shared_domains HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/json
User-Agent: go-cli 6.6.2-0c953cf / darwin



RESPONSE: [2014-12-09T11:44:22-05:00]
HTTP/1.1 200 OK
Content-Length: 409
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:44:46 GMT
Server: nginx
X-Cf-Requestid: 98ed8cc6-8e39-4cf9-7cd4-4dea8c7f8c17
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: e3994382-9dda-4ebe-653f-2d4526401e55::af5b9f9b-c36f-45a5-8320-cecd3eeaf51f

{
  "total_results": 1,
  "total_pages": 1,
  "prev_url": null,
  "next_url": null,
  "resources": [
    {
      "metadata": {
        "guid": "fb6bd89f-2ed9-49d4-9ad1-97951a573135",
        "url": "/v2/shared_domains/fb6bd89f-2ed9-49d4-9ad1-97951a573135",
        "created_at": "2013-05-04T00:29:46+00:00",
        "updated_at": null
      },
      "entity": {
        "name": "cfapps.io"
      }
    }
  ]
}

REQUEST: [2014-12-09T11:44:22-05:00]
GET /v2/routes?inline-relations-depth=1&q=host%3Asinatra1234567%3Bdomain_guid%3Afb6bd89f-2ed9-49d4-9ad1-97951a573135 HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/json
User-Agent: go-cli 6.6.2-0c953cf / darwin



RESPONSE: [2014-12-09T11:44:22-05:00]
HTTP/1.1 200 OK
Content-Length: 107
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:44:46 GMT
Server: nginx
X-Cf-Requestid: 5fd16c1e-9583-4367-704d-374412dea8bb
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: 5aa78fe0-ad06-4e0b-5eba-697d01c20f4d::0cf4b668-a079-415d-b03f-bfdcd0e10fa0

{
  "total_results": 0,
  "total_pages": 1,
  "prev_url": null,
  "next_url": null,
  "resources": [

  ]
}
Creating route sinatra1234567.cfapps.io...

REQUEST: [2014-12-09T11:44:22-05:00]
POST /v2/routes?async=true&inline-relations-depth=1 HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/json
User-Agent: go-cli 6.6.2-0c953cf / darwin

{"host":"sinatra1234567","domain_guid":"fb6bd89f-2ed9-49d4-9ad1-97951a573135","space_guid":"1cb7bacc-8739-4811-8129-cc136d4a6763"}

RESPONSE: [2014-12-09T11:44:23-05:00]
HTTP/1.1 201 Created
Content-Length: 2280
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:44:46 GMT
Location: /v2/routes/436c57cd-8acf-4322-a31c-ed0f022e4530
Server: nginx
X-Cf-Requestid: 02e6a2be-ec0d-4c20-479a-019e16756466
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: 9371966d-a601-4569-4e5c-67a3619d1097::a90d04dd-a29d-4a09-86b7-c26985a1f8bb

{
  "metadata": {
    "guid": "436c57cd-8acf-4322-a31c-ed0f022e4530",
    "url": "/v2/routes/436c57cd-8acf-4322-a31c-ed0f022e4530",
    "created_at": "2014-12-09T16:44:46+00:00",
    "updated_at": null
  },
  "entity": {
    "host": "sinatra1234567",
    "domain_guid": "fb6bd89f-2ed9-49d4-9ad1-97951a573135",
    "space_guid": "1cb7bacc-8739-4811-8129-cc136d4a6763",
    "domain_url": "/v2/domains/fb6bd89f-2ed9-49d4-9ad1-97951a573135",
    "domain": {
      "metadata": {
        "guid": "fb6bd89f-2ed9-49d4-9ad1-97951a573135",
        "url": "/v2/domains/fb6bd89f-2ed9-49d4-9ad1-97951a573135",
        "created_at": "2013-05-04T00:29:46+00:00",
        "updated_at": null
      },
      "entity": {
        "name": "cfapps.io"
      }
    },
    "space_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763",
    "space": {
      "metadata": {
        "guid": "1cb7bacc-8739-4811-8129-cc136d4a6763",
        "url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763",
        "created_at": "2014-09-24T14:55:47+00:00",
        "updated_at": null
      },
      "entity": {
        "name": "development",
        "organization_guid": "d43a652b-2e2e-49bc-a630-8d4c02ea9a5d",
        "space_quota_definition_guid": null,
        "organization_url": "/v2/organizations/d43a652b-2e2e-49bc-a630-8d4c02ea9a5d",
        "developers_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/developers",
        "managers_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/managers",
        "auditors_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/auditors",
        "apps_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/apps",
        "routes_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/routes",
        "domains_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/domains",
        "service_instances_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/service_instances",
        "app_events_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/app_events",
        "events_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/events",
        "security_groups_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/security_groups"
      }
    },
    "apps_url": "/v2/routes/436c57cd-8acf-4322-a31c-ed0f022e4530/apps",
    "apps": [

    ]
  }
}
OK

Binding sinatra1234567.cfapps.io to sinatra1234567...

REQUEST: [2014-12-09T11:44:23-05:00]
PUT /v2/apps/e117d817-a114-435e-816f-0273fb4976a6/routes/436c57cd-8acf-4322-a31c-ed0f022e4530 HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/json
User-Agent: go-cli 6.6.2-0c953cf / darwin



RESPONSE: [2014-12-09T11:44:23-05:00]
HTTP/1.1 201 Created
Content-Length: 1362
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:44:46 GMT
Server: nginx
X-Cf-Requestid: 7bde233d-2a4f-48c2-73a0-fb5720676976
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: e8cc9d90-cac5-43eb-66de-141eb27730c7::5ae645c7-b718-4234-9f78-285229588ef7

{
  "metadata": {
    "guid": "e117d817-a114-435e-816f-0273fb4976a6",
    "url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6",
    "created_at": "2014-12-09T16:44:46+00:00",
    "updated_at": "2014-12-09T16:44:47+00:00"
  },
  "entity": {
    "name": "sinatra1234567",
    "production": false,
    "space_guid": "1cb7bacc-8739-4811-8129-cc136d4a6763",
    "stack_guid": "50688ae5-9bfc-4bf6-a4bf-caadb21a32c6",
    "buildpack": "https://github.com/cloudfoundry/ruby-buildpack#v1.1.2",
    "detected_buildpack": null,
    "environment_json": {

    },
    "memory": 1024,
    "instances": 1,
    "disk_quota": 1024,
    "state": "STOPPED",
    "version": "51ef5afa-6691-4836-8ab2-ca8fd1a8e04d",
    "command": null,
    "console": false,
    "debug": null,
    "staging_task_id": null,
    "package_state": "PENDING",
    "health_check_timeout": null,
    "staging_failed_reason": null,
    "docker_image": null,
    "package_updated_at": null,
    "detected_start_command": "",
    "space_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763",
    "stack_url": "/v2/stacks/50688ae5-9bfc-4bf6-a4bf-caadb21a32c6",
    "events_url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6/events",
    "service_bindings_url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6/service_bindings",
    "routes_url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6/routes"
  }
}
OK

Uploading sinatra1234567...

REQUEST: [2014-12-09T11:44:23-05:00]
PUT /v2/resource_match HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/json
User-Agent: go-cli 6.6.2-0c953cf / darwin

[{"fn":"Gemfile","sha1":"c9df5781e7a5f5f7abe8e6ac61547d4579f39b6c","size":59},{"fn":"Gemfile.lock","sha1":"a268134cf6917e8f53d2402fbb0fdb46bfa02e15","size":261},{"fn":"README.md","sha1":"b3443027a0e467e0d244599b2e587813c2b8ad02","size":80},{"fn":"app.rb","sha1":"3f47d5b2d40e55ab170ee215b7d172fcc97b7964","size":50},{"fn":"config.ru","sha1":"c912ce784170d36b1cc8d49ddec94d83a977e6eb","size":40},{"fn":"vendor","sha1":"0","size":0},{"fn":"vendor/cache","sha1":"0","size":0},{"fn":"vendor/cache/rack-1.5.2.gem","sha1":"a17f40c9beb03b458f537f42cf36dd90d8230625","size":216576},{"fn":"vendor/cache/rack-protection-1.5.2.gem","sha1":"7e8002b0b5fdb40ec93f6cde4a4a19cc0f45a158","size":17920},{"fn":"vendor/cache/sinatra-1.4.4.gem","sha1":"9d5fc505c53027a0746ce1e29d897ee8aaaaffa8","size":326656},{"fn":"vendor/cache/tilt-1.4.1.gem","sha1":"a63b374ab06aeb6f567bd24e61434e386cce0811","size":42496}]

RESPONSE: [2014-12-09T11:44:23-05:00]
HTTP/1.1 200 OK
Content-Length: 206
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:44:47 GMT
Server: nginx
X-Cf-Requestid: dbae6343-49ec-432e-587d-715807db844f
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: 1a83b20f-ca27-4fd4-6b33-afa1613caa55::23bad950-1ac9-4cc5-8841-df82133a4c62

[{"fn":"vendor/cache/rack-1.5.2.gem","sha1":"a17f40c9beb03b458f537f42cf36dd90d8230625","size":216576},{"fn":"vendor/cache/sinatra-1.4.4.gem","sha1":"9d5fc505c53027a0746ce1e29d897ee8aaaaffa8","size":326656}]
Uploading app files from: /Users/pivotal/workspace/ruby-issue-26
Uploading 60.6K, 9 files

REQUEST: [2014-12-09T11:44:23-05:00]
PUT /v2/apps/e117d817-a114-435e-816f-0273fb4976a6/bits?async=true HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: multipart/form-data; boundary=71342316c6cce324e893d4a8230faa15ff2a74bd951364befe22c73ed58e
User-Agent: go-cli 6.6.2-0c953cf / darwin


[MULTIPART/FORM-DATA CONTENT HIDDEN]

RESPONSE: [2014-12-09T11:44:24-05:00]
HTTP/1.1 201 Created
Content-Length: 275
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:44:47 GMT
Server: nginx
X-Cf-Requestid: 4653e6f0-8310-48c1-4ef4-7a598286ab03
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: 99d4c6ce-361d-4a05-4ff8-3389deb1423a::dd98605c-322d-4f55-9f85-7ebc5b806d67

{
  "metadata": {
    "guid": "5e381097-170f-4e07-92ef-51a37bcbd75a",
    "created_at": "2014-12-09T16:44:47+00:00",
    "url": "/v2/jobs/5e381097-170f-4e07-92ef-51a37bcbd75a"
  },
  "entity": {
    "guid": "5e381097-170f-4e07-92ef-51a37bcbd75a",
    "status": "queued"
  }
}

REQUEST: [2014-12-09T11:44:24-05:00]
GET /v2/jobs/5e381097-170f-4e07-92ef-51a37bcbd75a HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/json
User-Agent: go-cli 6.6.2-0c953cf / darwin



RESPONSE: [2014-12-09T11:44:24-05:00]
HTTP/1.1 200 OK
Content-Length: 275
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:44:47 GMT
Server: nginx
X-Cf-Requestid: 674dbe12-b11d-4f43-65dc-bdbb251c9c22
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: 4d8afcc8-fd9a-4915-5cea-dbc8a5da4d09::cc5e6cb0-4787-4354-8292-bf600402b8e2

{
  "metadata": {
    "guid": "5e381097-170f-4e07-92ef-51a37bcbd75a",
    "created_at": "2014-12-09T16:44:47+00:00",
    "url": "/v2/jobs/5e381097-170f-4e07-92ef-51a37bcbd75a"
  },
  "entity": {
    "guid": "5e381097-170f-4e07-92ef-51a37bcbd75a",
    "status": "queued"
  }
}

REQUEST: [2014-12-09T11:44:29-05:00]
GET /v2/jobs/5e381097-170f-4e07-92ef-51a37bcbd75a HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/json
User-Agent: go-cli 6.6.2-0c953cf / darwin



RESPONSE: [2014-12-09T11:44:29-05:00]
HTTP/1.1 200 OK
Content-Length: 172
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:44:52 GMT
Server: nginx
X-Cf-Requestid: a0fc0042-47ab-4ec6-53df-0a542b6accc9
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: 99b935d5-ba16-4452-5b64-5e5fce1790c8::9fc90df9-ea15-4f1f-9bd9-6e297e728359

{
  "metadata": {
    "guid": "0",
    "created_at": "1970-01-01T00:00:00+00:00",
    "url": "/v2/jobs/0"
  },
  "entity": {
    "guid": "0",
    "status": "finished"
  }
}
OK


WEBSOCKET REQUEST: [2014-12-09T11:44:29-05:00]
GET /tail/?app=e117d817-a114-435e-816f-0273fb4976a6 HTTP/1.1
Host: wss://loggregator.run.pivotal.io:4443
Upgrade: websocket
Connection: Upgrade
Sec-WebSocket-Version: 13
Sec-WebSocket-Key: [HIDDEN]
Origin: http://localhost
Authorization: [PRIVATE DATA HIDDEN]


WEBSOCKET RESPONSE: [2014-12-09T11:44:29-05:00]
HTTP/1.1 101 Switching Protocols
Upgrade: websocket
Connection: Upgrade
Sec-Websocket-Accept: 5XzQk8jMIigCbFlNT9Hao5mOfzo=

Starting app sinatra1234567 in org cf-buildpacks / space development as cf-buildpacks-eng@pivotal.io...

REQUEST: [2014-12-09T11:44:29-05:00]
PUT /v2/apps/e117d817-a114-435e-816f-0273fb4976a6?async=true&inline-relations-depth=1 HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/json
User-Agent: go-cli 6.6.2-0c953cf / darwin

{"state":"STARTED"}

RESPONSE: [2014-12-09T11:44:32-05:00]
HTTP/1.1 201 Created
Content-Length: 3885
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:44:56 GMT
Server: nginx
X-App-Staging-Log: https://cbb140a74e3d46f5b7a4dfef80f083a8.run.pivotal.io/staging_tasks/cd8346c6dac44d62b055d73956cabc34/file_path?hmac=b6715ce85aeac9cfe254649e08ad1320faf635966c48a01066042318b4e27663e877321fb221a09982d3b8ccd1867835582d61b1072abe97b9b8df54ecaa0168&path=%2Ftmp%2Fstaged%2Flogs%2Fstaging_task.log&timestamp=1418143496
X-Cf-Requestid: 474631e2-a9bf-4edb-6fea-7bc5af21ea65
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: 8c537f1b-bf3c-416f-42a5-11c3eb3e424b::c9479226-94d9-4306-86e6-2bbdde374d1a

{
  "metadata": {
    "guid": "e117d817-a114-435e-816f-0273fb4976a6",
    "url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6",
    "created_at": "2014-12-09T16:44:53+00:00",
    "updated_at": "2014-12-09T16:44:53+00:00"
  },
  "entity": {
    "name": "sinatra1234567",
    "production": false,
    "space_guid": "1cb7bacc-8739-4811-8129-cc136d4a6763",
    "stack_guid": "50688ae5-9bfc-4bf6-a4bf-caadb21a32c6",
    "buildpack": "https://github.com/cloudfoundry/ruby-buildpack#v1.1.2",
    "detected_buildpack": null,
    "environment_json": {

    },
    "memory": 1024,
    "instances": 1,
    "disk_quota": 1024,
    "state": "STARTED",
    "version": "56fa4b55-a033-486c-aa17-6e345464b789",
    "command": null,
    "console": false,
    "debug": null,
    "staging_task_id": "cd8346c6dac44d62b055d73956cabc34",
    "package_state": "PENDING",
    "health_check_timeout": null,
    "staging_failed_reason": null,
    "docker_image": null,
    "package_updated_at": "2014-12-09T16:44:50+00:00",
    "detected_start_command": "",
    "space_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763",
    "space": {
      "metadata": {
        "guid": "1cb7bacc-8739-4811-8129-cc136d4a6763",
        "url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763",
        "created_at": "2014-09-24T14:55:47+00:00",
        "updated_at": null
      },
      "entity": {
        "name": "development",
        "organization_guid": "d43a652b-2e2e-49bc-a630-8d4c02ea9a5d",
        "space_quota_definition_guid": null,
        "organization_url": "/v2/organizations/d43a652b-2e2e-49bc-a630-8d4c02ea9a5d",
        "developers_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/developers",
        "managers_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/managers",
        "auditors_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/auditors",
        "apps_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/apps",
        "routes_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/routes",
        "domains_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/domains",
        "service_instances_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/service_instances",
        "app_events_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/app_events",
        "events_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/events",
        "security_groups_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/security_groups"
      }
    },
    "stack_url": "/v2/stacks/50688ae5-9bfc-4bf6-a4bf-caadb21a32c6",
    "stack": {
      "metadata": {
        "guid": "50688ae5-9bfc-4bf6-a4bf-caadb21a32c6",
        "url": "/v2/stacks/50688ae5-9bfc-4bf6-a4bf-caadb21a32c6",
        "created_at": "2013-11-19T19:34:57+00:00",
        "updated_at": "2013-11-19T19:34:57+00:00"
      },
      "entity": {
        "name": "lucid64",
        "description": "Ubuntu 10.04"
      }
    },
    "events_url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6/events",
    "service_bindings_url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6/service_bindings",
    "service_bindings": [

    ],
    "routes_url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6/routes",
    "routes": [
      {
        "metadata": {
          "guid": "436c57cd-8acf-4322-a31c-ed0f022e4530",
          "url": "/v2/routes/436c57cd-8acf-4322-a31c-ed0f022e4530",
          "created_at": "2014-12-09T16:44:46+00:00",
          "updated_at": null
        },
        "entity": {
          "host": "sinatra1234567",
          "domain_guid": "fb6bd89f-2ed9-49d4-9ad1-97951a573135",
          "space_guid": "1cb7bacc-8739-4811-8129-cc136d4a6763",
          "domain_url": "/v2/domains/fb6bd89f-2ed9-49d4-9ad1-97951a573135",
          "space_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763",
          "apps_url": "/v2/routes/436c57cd-8acf-4322-a31c-ed0f022e4530/apps"
        }
      }
    ]
  }
}
OK

REQUEST: [2014-12-09T11:44:33-05:00]
GET /v2/apps/e117d817-a114-435e-816f-0273fb4976a6/instances HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/json
User-Agent: go-cli 6.6.2-0c953cf / darwin



RESPONSE: [2014-12-09T11:44:33-05:00]
HTTP/1.1 400 Bad Request
Content-Length: 102
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:44:56 GMT
Server: nginx
X-Cf-Requestid: f9455d84-2126-443c-7831-c8224c53e726
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: b06837dc-c23e-43d3-59b2-1e10f5547f3d::c1f9641d-1793-4ed9-9898-b236f4ec20ad

{
  "code": 170002,
  "description": "App has not finished staging",
  "error_code": "CF-NotStaged"
}


REQUEST: [2014-12-09T11:44:38-05:00]
GET /v2/apps/e117d817-a114-435e-816f-0273fb4976a6/instances HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/json
User-Agent: go-cli 6.6.2-0c953cf / darwin


-----> Downloaded app package (584K)

RESPONSE: [2014-12-09T11:44:38-05:00]
HTTP/1.1 400 Bad Request
Content-Length: 136
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:45:01 GMT
Server: nginx
X-Cf-Requestid: c99c0e32-3d89-46ce-4174-bae5fb7cc1fd
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: 0c1bf1c8-45c9-4633-4b58-b1fa0f3c62a3::4c62c856-d6c7-42db-961b-ec2130fb9790

{
  "code": 170004,
  "description": "App staging failed in the buildpack compile phase",
  "error_code": "CF-BuildpackCompileFailed"
}


FAILED
Server error, status code: 400, error code: 170004, message: App staging failed in the buildpack compile phase

TIP: use 'cf logs sinatra1234567 --recent' for more information
FAILED
Server error, status code: 400, error code: 170004, message: App staging failed in the buildpack compile phase

TIP: use 'cf logs sinatra1234567 --recent' for more information

|ruby-2.1.1| cortelyou in ~/workspace/ruby-issue-26
○ → cf logs sinatra1234567 --recent
Connected, dumping recent logs for app sinatra1234567 in org cf-buildpacks / space development as cf-buildpacks-eng@pivotal.io...

2014-12-09T11:44:46.14-0500 [API]     OUT Created app with guid e117d817-a114-435e-816f-0273fb4976a6
2014-12-09T11:44:46.93-0500 [API]     OUT Updated app with guid e117d817-a114-435e-816f-0273fb4976a6 ({"route"=>"436c57cd-8acf-4322-a31c-ed0f022e4530"})
2014-12-09T11:44:54.92-0500 [DEA]     OUT Got staging request for app with id e117d817-a114-435e-816f-0273fb4976a6
2014-12-09T11:44:56.53-0500 [API]     OUT Updated app with guid e117d817-a114-435e-816f-0273fb4976a6 ({"state"=>"STARTED"})
2014-12-09T11:44:56.67-0500 [STG]     OUT -----> Downloaded app package (584K)
2014-12-09T11:44:56.79-0500 [STG]     ERR Cloning into '/tmp/buildpacks/ruby-buildpack'...
2014-12-09T11:44:57.62-0500 [STG]     ERR Note: checking out 'b476e5a351b69e4c3acfeacdcddd6fc02de5358e'.
2014-12-09T11:44:57.62-0500 [STG]     ERR You are in 'detached HEAD' state. You can look around, make experimental
2014-12-09T11:44:57.62-0500 [STG]     ERR changes and commit them, and you can discard any commits you make in this
2014-12-09T11:44:57.62-0500 [STG]     ERR state without impacting any branches by performing another checkout.
2014-12-09T11:44:57.62-0500 [STG]     ERR If you want to create a new branch to retain commits you create, you may
2014-12-09T11:44:57.62-0500 [STG]     ERR do so (now or later) by using -b with the checkout command again. Example:
2014-12-09T11:44:57.62-0500 [STG]     ERR   git checkout -b new_branch_name
2014-12-09T11:44:57.85-0500 [STG]     OUT Submodule 'buildpack-packager' (https://github.com/cf-buildpacks/buildpack-packager.git) registered for path 'buildpack-packager'
2014-12-09T11:44:57.90-0500 [STG]     ERR Cloning into 'buildpack-packager'...
2014-12-09T11:44:58.18-0500 [STG]     OUT Submodule path 'buildpack-packager': checked out '87560fd0a76ccaaeb71232bf34dc616ea8876338'
2014-12-09T11:44:58.34-0500 [STG]     OUT -------> Buildpack version 1.1.2
2014-12-09T11:44:58.83-0500 [STG]     OUT -----> Compiling Ruby/Rack
2014-12-09T11:44:59.12-0500 [STG]     OUT  !
2014-12-09T11:44:59.12-0500 [STG]     OUT  !     Command: 'set -o pipefail; curl --fail --retry 3 --retry-delay 1 --connect-timeout 10 --max-time 90 https://s3-external-1.amazonaws.com/heroku-buildpack-ruby/ruby-2.1.3.tgz -s -o - | tar zxf - ' failed unexpectedly:
2014-12-09T11:44:59.12-0500 [STG]     OUT  !
2014-12-09T11:44:59.12-0500 [STG]     OUT  !     gzip: stdin: unexpected end of file
2014-12-09T11:44:59.12-0500 [STG]     OUT  !     tar: Child returned status 1
2014-12-09T11:44:59.12-0500 [STG]     OUT  !     tar: Exiting with failure status due to previous errors
2014-12-09T11:44:59.12-0500 [STG]     OUT  !
2014-12-09T11:44:59.14-0500 [STG]     OUT Staging failed: Buildpack compilation step failed
2014-12-09T11:44:59.33-0500 [API]     ERR Encountered error: App staging failed in the buildpack compile phase


# ------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------

|ruby-2.1.1| cortelyou in ~/workspace/ruby-issue-26
○ → # now, we repush **without** specifying the buildpack

|ruby-2.1.1| cortelyou in ~/workspace/ruby-issue-26
○ → CF_TRACE=true cf push sinatra1234567

VERSION:
6.6.2-0c953cf


REQUEST: [2014-12-09T11:45:14-05:00]
POST /oauth/token HTTP/1.1
Host: login.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/x-www-form-urlencoded
User-Agent: go-cli 6.6.2-0c953cf / darwin

grant_type=refresh_token&refresh_token=eyJhbGciOiJSUzI1NiJ9.eyJqdGkiOiJiZTdiZTQ3Ni0wMWRkLTRmOGUtOTYzMy02YWMwNWZjMDQ0ZTEiLCJzdWIiOiIzNDlkZWIwOS1hNTI2LTQwYzEtYWFmMy0xNzRmZGQ5ZWQ5NjAiLCJzY29wZSI6WyJzY2ltLnVzZXJpZHMiLCJjbG91ZF9jb250cm9sbGVyLndyaXRlIiwicGFzc3dvcmQud3JpdGUiLCJvcGVuaWQiLCJjbG91ZF9jb250cm9sbGVyLnJlYWQiXSwiaWF0IjoxNDE4MTQzMjMxLCJleHAiOjE0MjA3MzUyMzEsImNpZCI6ImNmIiwiaXNzIjoiaHR0cHM6Ly91YWEucnVuLnBpdm90YWwuaW8vb2F1dGgvdG9rZW4iLCJncmFudF90eXBlIjoicGFzc3dvcmQiLCJ1c2VyX25hbWUiOiJjZi1idWlsZHBhY2tzLWVuZ0BwaXZvdGFsLmlvIiwidXNlcl9pZCI6IjM0OWRlYjA5LWE1MjYtNDBjMS1hYWYzLTE3NGZkZDllZDk2MCIsImF1ZCI6WyJzY2ltIiwiY2xvdWRfY29udHJvbGxlciIsInBhc3N3b3JkIiwib3BlbmlkIl19.NKsOPgfEgLz7BYPHo_GKBozC01pBQYsjF8pDTRN6bK8XaXsWTUcl7PiS7knn9W-YLuhRR3RX6pbilOh2MliA5sJMecXXD5saf6buW9Su5UlDUz5o2SzqQUQGLEezA23sgZBIaKZ0TAcR8Ip4oeItaLR-27wLpNX2Y93LagMluwh9VZNDCzdXHJxcNoCoUfX9Gz3XjNkkDlldFL5DTr_NlPMqpX4nXLbMHDqBfr7B7kMPXNA0IY1mvo9dQ0XMFoB2CeECd1MWKMhleByGatCQBV5kMflGRTCdbrAusCNqet4Iwje3F45wL22HsozAF1uq6_LVazvN3Lny4IN3RzC9yw&scope=

RESPONSE: [2014-12-09T11:45:14-05:00]
HTTP/1.1 200 OK
Content-Length: 2223
Cache-Control: no-cache
Cache-Control: no-store
Cache-Control: no-store
Connection: keep-alive
Content-Type: application/json;charset=UTF-8
Date: Tue, 09 Dec 2014 16:45:38 GMT
Expires: Thu, 01 Jan 1970 00:00:00 GMT
Pragma: no-cache
Pragma: no-cache
Server: Apache-Coyote/1.1
X-Cf-Requestid: 7495b755-d726-4cb6-66e6-511c2d2fbf8a
X-Cf-Requestid: 556acd1b-4f04-41bf-6653-e0ef65bc3ed8
X-Frame-Options: DENY

{"access_token":"[PRIVATE DATA HIDDEN]","token_type":"bearer","refresh_token":"[PRIVATE DATA HIDDEN]","expires_in":599,"scope":"scim.userids password.write openid cloud_controller.write cloud_controller.read","jti":"d49a0d8a-27c3-4c28-81c9-5e7db2421fd1"}

REQUEST: [2014-12-09T11:45:14-05:00]
GET /v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/apps?q=name%3Asinatra1234567&inline-relations-depth=1 HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/json
User-Agent: go-cli 6.6.2-0c953cf / darwin



RESPONSE: [2014-12-09T11:45:14-05:00]
HTTP/1.1 200 OK
Content-Length: 4387
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:45:38 GMT
Server: nginx
X-Cf-Requestid: a958d74f-7535-487b-4bf7-4f1dcda0f1bb
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: 9eccee05-1d7e-40ad-7f76-315ec16add09::32dda01c-3980-4c32-8a3e-c8484a3ea8be

{
  "total_results": 1,
  "total_pages": 1,
  "prev_url": null,
  "next_url": null,
  "resources": [
    {
      "metadata": {
        "guid": "e117d817-a114-435e-816f-0273fb4976a6",
        "url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6",
        "created_at": "2014-12-09T16:44:53+00:00",
        "updated_at": "2014-12-09T16:44:59+00:00"
      },
      "entity": {
        "name": "sinatra1234567",
        "production": false,
        "space_guid": "1cb7bacc-8739-4811-8129-cc136d4a6763",
        "stack_guid": "50688ae5-9bfc-4bf6-a4bf-caadb21a32c6",
        "buildpack": "https://github.com/cloudfoundry/ruby-buildpack#v1.1.2",
        "detected_buildpack": null,
        "environment_json": {

        },
        "memory": 1024,
        "instances": 1,
        "disk_quota": 1024,
        "state": "STARTED",
        "version": "56fa4b55-a033-486c-aa17-6e345464b789",
        "command": null,
        "console": false,
        "debug": null,
        "staging_task_id": "cd8346c6dac44d62b055d73956cabc34",
        "package_state": "FAILED",
        "health_check_timeout": null,
        "staging_failed_reason": "BuildpackCompileFailed",
        "docker_image": null,
        "package_updated_at": "2014-12-09T16:44:50+00:00",
        "detected_start_command": "",
        "space_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763",
        "space": {
          "metadata": {
            "guid": "1cb7bacc-8739-4811-8129-cc136d4a6763",
            "url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763",
            "created_at": "2014-09-24T14:55:47+00:00",
            "updated_at": null
          },
          "entity": {
            "name": "development",
            "organization_guid": "d43a652b-2e2e-49bc-a630-8d4c02ea9a5d",
            "space_quota_definition_guid": null,
            "organization_url": "/v2/organizations/d43a652b-2e2e-49bc-a630-8d4c02ea9a5d",
            "developers_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/developers",
            "managers_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/managers",
            "auditors_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/auditors",
            "apps_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/apps",
            "routes_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/routes",
            "domains_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/domains",
            "service_instances_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/service_instances",
            "app_events_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/app_events",
            "events_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/events",
            "security_groups_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/security_groups"
          }
        },
        "stack_url": "/v2/stacks/50688ae5-9bfc-4bf6-a4bf-caadb21a32c6",
        "stack": {
          "metadata": {
            "guid": "50688ae5-9bfc-4bf6-a4bf-caadb21a32c6",
            "url": "/v2/stacks/50688ae5-9bfc-4bf6-a4bf-caadb21a32c6",
            "created_at": "2013-11-19T19:34:57+00:00",
            "updated_at": "2013-11-19T19:34:57+00:00"
          },
          "entity": {
            "name": "lucid64",
            "description": "Ubuntu 10.04"
          }
        },
        "events_url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6/events",
        "service_bindings_url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6/service_bindings",
        "service_bindings": [

        ],
        "routes_url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6/routes",
        "routes": [
          {
            "metadata": {
              "guid": "436c57cd-8acf-4322-a31c-ed0f022e4530",
              "url": "/v2/routes/436c57cd-8acf-4322-a31c-ed0f022e4530",
              "created_at": "2014-12-09T16:44:46+00:00",
              "updated_at": null
            },
            "entity": {
              "host": "sinatra1234567",
              "domain_guid": "fb6bd89f-2ed9-49d4-9ad1-97951a573135",
              "space_guid": "1cb7bacc-8739-4811-8129-cc136d4a6763",
              "domain_url": "/v2/domains/fb6bd89f-2ed9-49d4-9ad1-97951a573135",
              "space_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763",
              "apps_url": "/v2/routes/436c57cd-8acf-4322-a31c-ed0f022e4530/apps"
            }
          }
        ]
      }
    }
  ]
}
Updating app sinatra1234567 in org cf-buildpacks / space development as cf-buildpacks-eng@pivotal.io...

REQUEST: [2014-12-09T11:45:14-05:00]
PUT /v2/apps/e117d817-a114-435e-816f-0273fb4976a6?async=true&inline-relations-depth=1 HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/json
User-Agent: go-cli 6.6.2-0c953cf / darwin

{"name":"sinatra1234567"}

RESPONSE: [2014-12-09T11:45:14-05:00]
HTTP/1.1 201 Created
Content-Length: 3904
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:45:38 GMT
Server: nginx
X-Cf-Requestid: b231af17-5f1a-4f72-569e-919ae28e5a38
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: 142b3f86-6171-402d-5517-975cb7f6e7b3::9d0cb73c-c54f-4c52-bbd4-6ae6cef611d7

{
  "metadata": {
    "guid": "e117d817-a114-435e-816f-0273fb4976a6",
    "url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6",
    "created_at": "2014-12-09T16:44:53+00:00",
    "updated_at": "2014-12-09T16:45:38+00:00"
  },
  "entity": {
    "name": "sinatra1234567",
    "production": false,
    "space_guid": "1cb7bacc-8739-4811-8129-cc136d4a6763",
    "stack_guid": "50688ae5-9bfc-4bf6-a4bf-caadb21a32c6",
    "buildpack": "https://github.com/cloudfoundry/ruby-buildpack#v1.1.2",
    "detected_buildpack": null,
    "environment_json": {

    },
    "memory": 1024,
    "instances": 1,
    "disk_quota": 1024,
    "state": "STARTED",
    "version": "56fa4b55-a033-486c-aa17-6e345464b789",
    "command": null,
    "console": false,
    "debug": null,
    "staging_task_id": "cd8346c6dac44d62b055d73956cabc34",
    "package_state": "FAILED",
    "health_check_timeout": null,
    "staging_failed_reason": "BuildpackCompileFailed",
    "docker_image": null,
    "package_updated_at": "2014-12-09T16:44:50+00:00",
    "detected_start_command": "",
    "space_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763",
    "space": {
      "metadata": {
        "guid": "1cb7bacc-8739-4811-8129-cc136d4a6763",
        "url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763",
        "created_at": "2014-09-24T14:55:47+00:00",
        "updated_at": null
      },
      "entity": {
        "name": "development",
        "organization_guid": "d43a652b-2e2e-49bc-a630-8d4c02ea9a5d",
        "space_quota_definition_guid": null,
        "organization_url": "/v2/organizations/d43a652b-2e2e-49bc-a630-8d4c02ea9a5d",
        "developers_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/developers",
        "managers_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/managers",
        "auditors_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/auditors",
        "apps_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/apps",
        "routes_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/routes",
        "domains_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/domains",
        "service_instances_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/service_instances",
        "app_events_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/app_events",
        "events_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/events",
        "security_groups_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/security_groups"
      }
    },
    "stack_url": "/v2/stacks/50688ae5-9bfc-4bf6-a4bf-caadb21a32c6",
    "stack": {
      "metadata": {
        "guid": "50688ae5-9bfc-4bf6-a4bf-caadb21a32c6",
        "url": "/v2/stacks/50688ae5-9bfc-4bf6-a4bf-caadb21a32c6",
        "created_at": "2013-11-19T19:34:57+00:00",
        "updated_at": "2013-11-19T19:34:57+00:00"
      },
      "entity": {
        "name": "lucid64",
        "description": "Ubuntu 10.04"
      }
    },
    "events_url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6/events",
    "service_bindings_url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6/service_bindings",
    "service_bindings": [

    ],
    "routes_url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6/routes",
    "routes": [
      {
        "metadata": {
          "guid": "436c57cd-8acf-4322-a31c-ed0f022e4530",
          "url": "/v2/routes/436c57cd-8acf-4322-a31c-ed0f022e4530",
          "created_at": "2014-12-09T16:44:46+00:00",
          "updated_at": null
        },
        "entity": {
          "host": "sinatra1234567",
          "domain_guid": "fb6bd89f-2ed9-49d4-9ad1-97951a573135",
          "space_guid": "1cb7bacc-8739-4811-8129-cc136d4a6763",
          "domain_url": "/v2/domains/fb6bd89f-2ed9-49d4-9ad1-97951a573135",
          "space_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763",
          "apps_url": "/v2/routes/436c57cd-8acf-4322-a31c-ed0f022e4530/apps"
        }
      }
    ]
  }
}
OK


REQUEST: [2014-12-09T11:45:14-05:00]
GET /v2/organizations/d43a652b-2e2e-49bc-a630-8d4c02ea9a5d/private_domains HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/json
User-Agent: go-cli 6.6.2-0c953cf / darwin



RESPONSE: [2014-12-09T11:45:14-05:00]
HTTP/1.1 200 OK
Content-Length: 107
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:45:38 GMT
Server: nginx
X-Cf-Requestid: ef8e3b7e-102e-4e5a-5113-3aa0114f194c
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: 554b7cf3-2a31-4166-4c06-5bd6d009e36e::8f149e93-1fe0-4edc-8a6e-2a4cb274c452

{
  "total_results": 0,
  "total_pages": 1,
  "prev_url": null,
  "next_url": null,
  "resources": [

  ]
}

REQUEST: [2014-12-09T11:45:14-05:00]
GET /v2/shared_domains HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/json
User-Agent: go-cli 6.6.2-0c953cf / darwin



RESPONSE: [2014-12-09T11:45:15-05:00]
HTTP/1.1 200 OK
Content-Length: 409
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:45:38 GMT
Server: nginx
X-Cf-Requestid: 67f9b8d3-5407-468b-414c-ae4fbd238e89
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: cbe41c60-95d9-492a-77e0-7dbc59850d41::2b00b038-f56e-4fd3-be67-cbe8a382c595

{
  "total_results": 1,
  "total_pages": 1,
  "prev_url": null,
  "next_url": null,
  "resources": [
    {
      "metadata": {
        "guid": "fb6bd89f-2ed9-49d4-9ad1-97951a573135",
        "url": "/v2/shared_domains/fb6bd89f-2ed9-49d4-9ad1-97951a573135",
        "created_at": "2013-05-04T00:29:46+00:00",
        "updated_at": null
      },
      "entity": {
        "name": "cfapps.io"
      }
    }
  ]
}
Uploading sinatra1234567...

REQUEST: [2014-12-09T11:45:15-05:00]
PUT /v2/resource_match HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/json
User-Agent: go-cli 6.6.2-0c953cf / darwin

[{"fn":"Gemfile","sha1":"c9df5781e7a5f5f7abe8e6ac61547d4579f39b6c","size":59},{"fn":"Gemfile.lock","sha1":"a268134cf6917e8f53d2402fbb0fdb46bfa02e15","size":261},{"fn":"README.md","sha1":"b3443027a0e467e0d244599b2e587813c2b8ad02","size":80},{"fn":"app.rb","sha1":"3f47d5b2d40e55ab170ee215b7d172fcc97b7964","size":50},{"fn":"config.ru","sha1":"c912ce784170d36b1cc8d49ddec94d83a977e6eb","size":40},{"fn":"vendor","sha1":"0","size":0},{"fn":"vendor/cache","sha1":"0","size":0},{"fn":"vendor/cache/rack-1.5.2.gem","sha1":"a17f40c9beb03b458f537f42cf36dd90d8230625","size":216576},{"fn":"vendor/cache/rack-protection-1.5.2.gem","sha1":"7e8002b0b5fdb40ec93f6cde4a4a19cc0f45a158","size":17920},{"fn":"vendor/cache/sinatra-1.4.4.gem","sha1":"9d5fc505c53027a0746ce1e29d897ee8aaaaffa8","size":326656},{"fn":"vendor/cache/tilt-1.4.1.gem","sha1":"a63b374ab06aeb6f567bd24e61434e386cce0811","size":42496}]

RESPONSE: [2014-12-09T11:45:15-05:00]
HTTP/1.1 200 OK
Content-Length: 206
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:45:38 GMT
Server: nginx
X-Cf-Requestid: 4c864c11-a15f-4d17-5ced-f506f43c0cb6
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: d77c43a2-fa9d-4e1c-437a-f21d061e05fb::7ad1ba71-38f2-4931-b8ff-9fdf2412ef1a

[{"fn":"vendor/cache/rack-1.5.2.gem","sha1":"a17f40c9beb03b458f537f42cf36dd90d8230625","size":216576},{"fn":"vendor/cache/sinatra-1.4.4.gem","sha1":"9d5fc505c53027a0746ce1e29d897ee8aaaaffa8","size":326656}]
Uploading app files from: /Users/pivotal/workspace/ruby-issue-26
Uploading 60.6K, 9 files

REQUEST: [2014-12-09T11:45:15-05:00]
PUT /v2/apps/e117d817-a114-435e-816f-0273fb4976a6/bits?async=true HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: multipart/form-data; boundary=9ec47c148bbcb738bac374040b34a69697b3945bf345b29d72b3ea082513
User-Agent: go-cli 6.6.2-0c953cf / darwin


[MULTIPART/FORM-DATA CONTENT HIDDEN]

RESPONSE: [2014-12-09T11:45:15-05:00]
HTTP/1.1 201 Created
Content-Length: 275
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:45:38 GMT
Server: nginx
X-Cf-Requestid: 682998b2-864e-438d-491b-d8bef1cd5a8b
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: a7e093e7-9f54-42a7-7c22-2e6b05c9bb97::6d5f6515-5e1b-4b32-b738-fc050e6b6169

{
  "metadata": {
    "guid": "4b667625-e607-49ae-a946-f46699950453",
    "created_at": "2014-12-09T16:45:39+00:00",
    "url": "/v2/jobs/4b667625-e607-49ae-a946-f46699950453"
  },
  "entity": {
    "guid": "4b667625-e607-49ae-a946-f46699950453",
    "status": "queued"
  }
}

REQUEST: [2014-12-09T11:45:15-05:00]
GET /v2/jobs/4b667625-e607-49ae-a946-f46699950453 HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/json
User-Agent: go-cli 6.6.2-0c953cf / darwin



RESPONSE: [2014-12-09T11:45:15-05:00]
HTTP/1.1 200 OK
Content-Length: 275
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:45:39 GMT
Server: nginx
X-Cf-Requestid: 3c6d182e-2be3-4643-5444-5cfc301b8640
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: 1cc5ddc1-3dda-492f-6ca7-a5dda3e229d8::6c39f95d-54f5-4b3e-9202-f5b8fb0e378a

{
  "metadata": {
    "guid": "4b667625-e607-49ae-a946-f46699950453",
    "created_at": "2014-12-09T16:45:39+00:00",
    "url": "/v2/jobs/4b667625-e607-49ae-a946-f46699950453"
  },
  "entity": {
    "guid": "4b667625-e607-49ae-a946-f46699950453",
    "status": "queued"
  }
}

REQUEST: [2014-12-09T11:45:20-05:00]
GET /v2/jobs/4b667625-e607-49ae-a946-f46699950453 HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/json
User-Agent: go-cli 6.6.2-0c953cf / darwin



RESPONSE: [2014-12-09T11:45:20-05:00]
HTTP/1.1 200 OK
Content-Length: 172
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:45:44 GMT
Server: nginx
X-Cf-Requestid: 2878a1a5-69a8-4364-518f-1359f835f644
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: 63aa2337-2850-4b99-5353-7e77441d6ccd::8f32ee3b-fbd9-4fc3-a64a-b7372281e945

{
  "metadata": {
    "guid": "0",
    "created_at": "1970-01-01T00:00:00+00:00",
    "url": "/v2/jobs/0"
  },
  "entity": {
    "guid": "0",
    "status": "finished"
  }
}
OK

Stopping app sinatra1234567 in org cf-buildpacks / space development as cf-buildpacks-eng@pivotal.io...

REQUEST: [2014-12-09T11:45:20-05:00]
PUT /v2/apps/e117d817-a114-435e-816f-0273fb4976a6?async=true&inline-relations-depth=1 HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/json
User-Agent: go-cli 6.6.2-0c953cf / darwin

{"state":"STOPPED"}

RESPONSE: [2014-12-09T11:45:20-05:00]
HTTP/1.1 201 Created
Content-Length: 3885
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:45:44 GMT
Server: nginx
X-Cf-Requestid: 65869631-fd19-4194-56e5-ddee1d109280
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: 1f232bd1-a767-4671-7a22-765847337b78::ff84567c-76db-4c16-a3d8-e3d55708ac90

{
  "metadata": {
    "guid": "e117d817-a114-435e-816f-0273fb4976a6",
    "url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6",
    "created_at": "2014-12-09T16:44:53+00:00",
    "updated_at": "2014-12-09T16:45:44+00:00"
  },
  "entity": {
    "name": "sinatra1234567",
    "production": false,
    "space_guid": "1cb7bacc-8739-4811-8129-cc136d4a6763",
    "stack_guid": "50688ae5-9bfc-4bf6-a4bf-caadb21a32c6",
    "buildpack": "https://github.com/cloudfoundry/ruby-buildpack#v1.1.2",
    "detected_buildpack": null,
    "environment_json": {

    },
    "memory": 1024,
    "instances": 1,
    "disk_quota": 1024,
    "state": "STOPPED",
    "version": "56fa4b55-a033-486c-aa17-6e345464b789",
    "command": null,
    "console": false,
    "debug": null,
    "staging_task_id": "cd8346c6dac44d62b055d73956cabc34",
    "package_state": "PENDING",
    "health_check_timeout": null,
    "staging_failed_reason": null,
    "docker_image": null,
    "package_updated_at": "2014-12-09T16:45:41+00:00",
    "detected_start_command": "",
    "space_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763",
    "space": {
      "metadata": {
        "guid": "1cb7bacc-8739-4811-8129-cc136d4a6763",
        "url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763",
        "created_at": "2014-09-24T14:55:47+00:00",
        "updated_at": null
      },
      "entity": {
        "name": "development",
        "organization_guid": "d43a652b-2e2e-49bc-a630-8d4c02ea9a5d",
        "space_quota_definition_guid": null,
        "organization_url": "/v2/organizations/d43a652b-2e2e-49bc-a630-8d4c02ea9a5d",
        "developers_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/developers",
        "managers_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/managers",
        "auditors_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/auditors",
        "apps_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/apps",
        "routes_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/routes",
        "domains_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/domains",
        "service_instances_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/service_instances",
        "app_events_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/app_events",
        "events_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/events",
        "security_groups_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/security_groups"
      }
    },
    "stack_url": "/v2/stacks/50688ae5-9bfc-4bf6-a4bf-caadb21a32c6",
    "stack": {
      "metadata": {
        "guid": "50688ae5-9bfc-4bf6-a4bf-caadb21a32c6",
        "url": "/v2/stacks/50688ae5-9bfc-4bf6-a4bf-caadb21a32c6",
        "created_at": "2013-11-19T19:34:57+00:00",
        "updated_at": "2013-11-19T19:34:57+00:00"
      },
      "entity": {
        "name": "lucid64",
        "description": "Ubuntu 10.04"
      }
    },
    "events_url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6/events",
    "service_bindings_url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6/service_bindings",
    "service_bindings": [

    ],
    "routes_url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6/routes",
    "routes": [
      {
        "metadata": {
          "guid": "436c57cd-8acf-4322-a31c-ed0f022e4530",
          "url": "/v2/routes/436c57cd-8acf-4322-a31c-ed0f022e4530",
          "created_at": "2014-12-09T16:44:46+00:00",
          "updated_at": null
        },
        "entity": {
          "host": "sinatra1234567",
          "domain_guid": "fb6bd89f-2ed9-49d4-9ad1-97951a573135",
          "space_guid": "1cb7bacc-8739-4811-8129-cc136d4a6763",
          "domain_url": "/v2/domains/fb6bd89f-2ed9-49d4-9ad1-97951a573135",
          "space_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763",
          "apps_url": "/v2/routes/436c57cd-8acf-4322-a31c-ed0f022e4530/apps"
        }
      }
    ]
  }
}
OK


WEBSOCKET REQUEST: [2014-12-09T11:45:20-05:00]
GET /tail/?app=e117d817-a114-435e-816f-0273fb4976a6 HTTP/1.1
Host: wss://loggregator.run.pivotal.io:4443
Upgrade: websocket
Connection: Upgrade
Sec-WebSocket-Version: 13
Sec-WebSocket-Key: [HIDDEN]
Origin: http://localhost
Authorization: [PRIVATE DATA HIDDEN]


WEBSOCKET RESPONSE: [2014-12-09T11:45:20-05:00]
HTTP/1.1 101 Switching Protocols
Upgrade: websocket
Connection: Upgrade
Sec-Websocket-Accept: 1qlTYLR1cuJ1msZsP7EkNH85l/Y=

Starting app sinatra1234567 in org cf-buildpacks / space development as cf-buildpacks-eng@pivotal.io...

REQUEST: [2014-12-09T11:45:20-05:00]
PUT /v2/apps/e117d817-a114-435e-816f-0273fb4976a6?async=true&inline-relations-depth=1 HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/json
User-Agent: go-cli 6.6.2-0c953cf / darwin

{"state":"STARTED"}

RESPONSE: [2014-12-09T11:45:24-05:00]
HTTP/1.1 201 Created
Content-Length: 3885
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:45:47 GMT
Server: nginx
X-App-Staging-Log: https://cbb140a74e3d46f5b7a4dfef80f083a8.run.pivotal.io/staging_tasks/2e5655d2fee548a8904db2d99f8569a4/file_path?hmac=67bb5510babe815f5720a3ff372063b7761bc3f89a34bf0852a43ec3953ec1cc25481ad4523f3644776c115a32f4532bb14075f9ace054de6b88b82bdbc33c03&path=%2Ftmp%2Fstaged%2Flogs%2Fstaging_task.log&timestamp=1418143547
X-Cf-Requestid: 3741d518-438e-427c-6b67-507667f82e5b
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: e00566ce-631d-4367-4248-607b614d8078::a89378ad-6010-40a5-aaa0-4745d9770ecc

{
  "metadata": {
    "guid": "e117d817-a114-435e-816f-0273fb4976a6",
    "url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6",
    "created_at": "2014-12-09T16:45:44+00:00",
    "updated_at": "2014-12-09T16:45:44+00:00"
  },
  "entity": {
    "name": "sinatra1234567",
    "production": false,
    "space_guid": "1cb7bacc-8739-4811-8129-cc136d4a6763",
    "stack_guid": "50688ae5-9bfc-4bf6-a4bf-caadb21a32c6",
    "buildpack": "https://github.com/cloudfoundry/ruby-buildpack#v1.1.2",
    "detected_buildpack": null,
    "environment_json": {

    },
    "memory": 1024,
    "instances": 1,
    "disk_quota": 1024,
    "state": "STARTED",
    "version": "b098f52d-b98a-4c02-b132-37e7987fa0a4",
    "command": null,
    "console": false,
    "debug": null,
    "staging_task_id": "2e5655d2fee548a8904db2d99f8569a4",
    "package_state": "PENDING",
    "health_check_timeout": null,
    "staging_failed_reason": null,
    "docker_image": null,
    "package_updated_at": "2014-12-09T16:45:41+00:00",
    "detected_start_command": "",
    "space_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763",
    "space": {
      "metadata": {
        "guid": "1cb7bacc-8739-4811-8129-cc136d4a6763",
        "url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763",
        "created_at": "2014-09-24T14:55:47+00:00",
        "updated_at": null
      },
      "entity": {
        "name": "development",
        "organization_guid": "d43a652b-2e2e-49bc-a630-8d4c02ea9a5d",
        "space_quota_definition_guid": null,
        "organization_url": "/v2/organizations/d43a652b-2e2e-49bc-a630-8d4c02ea9a5d",
        "developers_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/developers",
        "managers_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/managers",
        "auditors_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/auditors",
        "apps_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/apps",
        "routes_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/routes",
        "domains_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/domains",
        "service_instances_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/service_instances",
        "app_events_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/app_events",
        "events_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/events",
        "security_groups_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/security_groups"
      }
    },
    "stack_url": "/v2/stacks/50688ae5-9bfc-4bf6-a4bf-caadb21a32c6",
    "stack": {
      "metadata": {
        "guid": "50688ae5-9bfc-4bf6-a4bf-caadb21a32c6",
        "url": "/v2/stacks/50688ae5-9bfc-4bf6-a4bf-caadb21a32c6",
        "created_at": "2013-11-19T19:34:57+00:00",
        "updated_at": "2013-11-19T19:34:57+00:00"
      },
      "entity": {
        "name": "lucid64",
        "description": "Ubuntu 10.04"
      }
    },
    "events_url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6/events",
    "service_bindings_url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6/service_bindings",
    "service_bindings": [

    ],
    "routes_url": "/v2/apps/e117d817-a114-435e-816f-0273fb4976a6/routes",
    "routes": [
      {
        "metadata": {
          "guid": "436c57cd-8acf-4322-a31c-ed0f022e4530",
          "url": "/v2/routes/436c57cd-8acf-4322-a31c-ed0f022e4530",
          "created_at": "2014-12-09T16:44:46+00:00",
          "updated_at": null
        },
        "entity": {
          "host": "sinatra1234567",
          "domain_guid": "fb6bd89f-2ed9-49d4-9ad1-97951a573135",
          "space_guid": "1cb7bacc-8739-4811-8129-cc136d4a6763",
          "domain_url": "/v2/domains/fb6bd89f-2ed9-49d4-9ad1-97951a573135",
          "space_url": "/v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763",
          "apps_url": "/v2/routes/436c57cd-8acf-4322-a31c-ed0f022e4530/apps"
        }
      }
    ]
  }
}
OK

REQUEST: [2014-12-09T11:45:24-05:00]
GET /v2/apps/e117d817-a114-435e-816f-0273fb4976a6/instances HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/json
User-Agent: go-cli 6.6.2-0c953cf / darwin



RESPONSE: [2014-12-09T11:45:24-05:00]
HTTP/1.1 400 Bad Request
Content-Length: 102
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:45:47 GMT
Server: nginx
X-Cf-Requestid: 914796e6-5b98-4499-718e-0d28446b8672
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: 8db6fc9f-01f8-4e7f-569b-541eb8315526::058e9f22-159c-457c-a64a-5d17b3b03995

{
  "code": 170002,
  "description": "App has not finished staging",
  "error_code": "CF-NotStaged"
}


REQUEST: [2014-12-09T11:45:29-05:00]
GET /v2/apps/e117d817-a114-435e-816f-0273fb4976a6/instances HTTP/1.1
Host: api.run.pivotal.io
Accept: application/json
Authorization: [PRIVATE DATA HIDDEN]
Content-Type: application/json
User-Agent: go-cli 6.6.2-0c953cf / darwin


-----> Downloaded app package (584K)

RESPONSE: [2014-12-09T11:45:29-05:00]
HTTP/1.1 400 Bad Request
Content-Length: 136
Connection: keep-alive
Content-Type: application/json;charset=utf-8
Date: Tue, 09 Dec 2014 16:45:52 GMT
Server: nginx
X-Cf-Requestid: 628006e5-b3ba-421d-77d3-38e6ad66548f
X-Content-Type-Options: nosniff
X-Vcap-Request-Id: 6502e966-96ff-4664-5a09-6b6eabac2928::b4f2e23d-27d3-49a9-8d30-277648ad0c4c

{
  "code": 170004,
  "description": "App staging failed in the buildpack compile phase",
  "error_code": "CF-BuildpackCompileFailed"
}


FAILED
Server error, status code: 400, error code: 170004, message: App staging failed in the buildpack compile phase

TIP: use 'cf logs sinatra1234567 --recent' for more information
FAILED
Server error, status code: 400, error code: 170004, message: App staging failed in the buildpack compile phase

TIP: use 'cf logs sinatra1234567 --recent' for more information

|ruby-2.1.1| cortelyou in ~/workspace/ruby-issue-26
○ → cf logs sinatra1234567 --recent
Connected, dumping recent logs for app sinatra1234567 in org cf-buildpacks / space development as cf-buildpacks-eng@pivotal.io...

2014-12-09T11:44:46.14-0500 [API]     OUT Created app with guid e117d817-a114-435e-816f-0273fb4976a6
2014-12-09T11:44:46.93-0500 [API]     OUT Updated app with guid e117d817-a114-435e-816f-0273fb4976a6 ({"route"=>"436c57cd-8acf-4322-a31c-ed0f022e4530"})
2014-12-09T11:44:54.92-0500 [DEA]     OUT Got staging request for app with id e117d817-a114-435e-816f-0273fb4976a6
2014-12-09T11:44:56.53-0500 [API]     OUT Updated app with guid e117d817-a114-435e-816f-0273fb4976a6 ({"state"=>"STARTED"})
2014-12-09T11:44:56.67-0500 [STG]     OUT -----> Downloaded app package (584K)
2014-12-09T11:44:56.79-0500 [STG]     ERR Cloning into '/tmp/buildpacks/ruby-buildpack'...
2014-12-09T11:44:57.62-0500 [STG]     ERR Note: checking out 'b476e5a351b69e4c3acfeacdcddd6fc02de5358e'.
2014-12-09T11:44:57.62-0500 [STG]     ERR You are in 'detached HEAD' state. You can look around, make experimental
2014-12-09T11:44:57.62-0500 [STG]     ERR changes and commit them, and you can discard any commits you make in this
2014-12-09T11:44:57.62-0500 [STG]     ERR state without impacting any branches by performing another checkout.
2014-12-09T11:44:57.62-0500 [STG]     ERR If you want to create a new branch to retain commits you create, you may
2014-12-09T11:44:57.62-0500 [STG]     ERR do so (now or later) by using -b with the checkout command again. Example:
2014-12-09T11:44:57.62-0500 [STG]     ERR   git checkout -b new_branch_name
2014-12-09T11:44:57.85-0500 [STG]     OUT Submodule 'buildpack-packager' (https://github.com/cf-buildpacks/buildpack-packager.git) registered for path 'buildpack-packager'
2014-12-09T11:44:57.90-0500 [STG]     ERR Cloning into 'buildpack-packager'...
2014-12-09T11:44:58.18-0500 [STG]     OUT Submodule path 'buildpack-packager': checked out '87560fd0a76ccaaeb71232bf34dc616ea8876338'
2014-12-09T11:44:58.34-0500 [STG]     OUT -------> Buildpack version 1.1.2
2014-12-09T11:44:58.83-0500 [STG]     OUT -----> Compiling Ruby/Rack
2014-12-09T11:44:59.12-0500 [STG]     OUT  !
2014-12-09T11:44:59.12-0500 [STG]     OUT  !     Command: 'set -o pipefail; curl --fail --retry 3 --retry-delay 1 --connect-timeout 10 --max-time 90 https://s3-external-1.amazonaws.com/heroku-buildpack-ruby/ruby-2.1.3.tgz -s -o - | tar zxf - ' failed unexpectedly:
2014-12-09T11:44:59.12-0500 [STG]     OUT  !
2014-12-09T11:44:59.12-0500 [STG]     OUT  !     gzip: stdin: unexpected end of file
2014-12-09T11:44:59.12-0500 [STG]     OUT  !     tar: Child returned status 1
2014-12-09T11:44:59.12-0500 [STG]     OUT  !     tar: Exiting with failure status due to previous errors
2014-12-09T11:44:59.12-0500 [STG]     OUT  !
2014-12-09T11:44:59.14-0500 [STG]     OUT Staging failed: Buildpack compilation step failed
2014-12-09T11:44:59.33-0500 [API]     ERR Encountered error: App staging failed in the buildpack compile phase
2014-12-09T11:45:38.43-0500 [API]     OUT Updated app with guid e117d817-a114-435e-816f-0273fb4976a6 ({"name"=>"sinatra1234567"})
2014-12-09T11:45:44.15-0500 [API]     OUT Tried to stop app that never received a start event
2014-12-09T11:45:44.18-0500 [API]     OUT Updated app with guid e117d817-a114-435e-816f-0273fb4976a6 ({"state"=>"STOPPED"})
2014-12-09T11:45:46.19-0500 [DEA]     OUT Got staging request for app with id e117d817-a114-435e-816f-0273fb4976a6
2014-12-09T11:45:47.76-0500 [API]     OUT Updated app with guid e117d817-a114-435e-816f-0273fb4976a6 ({"state"=>"STARTED"})
2014-12-09T11:45:47.90-0500 [STG]     OUT -----> Downloaded app package (584K)
2014-12-09T11:45:48.01-0500 [STG]     ERR Cloning into '/tmp/buildpacks/ruby-buildpack'...
2014-12-09T11:45:48.91-0500 [STG]     ERR Note: checking out 'b476e5a351b69e4c3acfeacdcddd6fc02de5358e'.
2014-12-09T11:45:48.91-0500 [STG]     ERR You are in 'detached HEAD' state. You can look around, make experimental
2014-12-09T11:45:48.91-0500 [STG]     ERR changes and commit them, and you can discard any commits you make in this
2014-12-09T11:45:48.91-0500 [STG]     ERR state without impacting any branches by performing another checkout.
2014-12-09T11:45:48.91-0500 [STG]     ERR If you want to create a new branch to retain commits you create, you may
2014-12-09T11:45:48.91-0500 [STG]     ERR do so (now or later) by using -b with the checkout command again. Example:
2014-12-09T11:45:48.91-0500 [STG]     ERR   git checkout -b new_branch_name
2014-12-09T11:45:49.11-0500 [STG]     OUT Submodule 'buildpack-packager' (https://github.com/cf-buildpacks/buildpack-packager.git) registered for path 'buildpack-packager'
2014-12-09T11:45:49.16-0500 [STG]     ERR Cloning into 'buildpack-packager'...
2014-12-09T11:45:49.44-0500 [STG]     OUT Submodule path 'buildpack-packager': checked out '87560fd0a76ccaaeb71232bf34dc616ea8876338'
2014-12-09T11:45:49.60-0500 [STG]     OUT -------> Buildpack version 1.1.2
2014-12-09T11:45:50.06-0500 [STG]     OUT -----> Compiling Ruby/Rack
2014-12-09T11:45:50.37-0500 [STG]     OUT  !
2014-12-09T11:45:50.37-0500 [STG]     OUT  !     Command: 'set -o pipefail; curl --fail --retry 3 --retry-delay 1 --connect-timeout 10 --max-time 90 https://s3-external-1.amazonaws.com/heroku-buildpack-ruby/ruby-2.1.3.tgz -s -o - | tar zxf - ' failed unexpectedly:
2014-12-09T11:45:50.37-0500 [STG]     OUT  !
2014-12-09T11:45:50.37-0500 [STG]     OUT  !     gzip: stdin: unexpected end of file
2014-12-09T11:45:50.37-0500 [STG]     OUT  !     tar: Child returned status 1
2014-12-09T11:45:50.37-0500 [STG]     OUT  !     tar: Exiting with failure status due to previous errors
2014-12-09T11:45:50.37-0500 [STG]     OUT  !
2014-12-09T11:45:50.39-0500 [STG]     OUT Staging failed: Buildpack compilation step failed
2014-12-09T11:45:50.58-0500 [API]     ERR Encountered error: App staging failed in the buildpack compile phase

  13. jchesterpivotal commented on Dec 9, 2014

    @jchesterpivotal
    jchesterpivotal
    on Dec 9, 2014
    Contributor

    So we think this may be a Cloud Controller issue. Here's the interesting bit:

    REQUEST: [2014-12-09T11:45:14-05:00]
GET /v2/spaces/1cb7bacc-8739-4811-8129-cc136d4a6763/apps?q=name%3Asinatra1234567&inline-relations-depth=1 HTTP/1.1
Host: api.run.pivotal.io

# [snip]

RESPONSE: [2014-12-09T11:45:14-05:00]
HTTP/1.1 200 OK

# [snip]

{
# [snip]
      "entity": {
        "name": "sinatra1234567",
# [snip]
--->        "buildpack": "https://github.com/cloudfoundry/ruby-buildpack#v1.1.2",  <---
        "detected_buildpack": null,

    So the sequence is:

    1. I use cf push some_app -b https://example.org/some/broken/buildpack
    2. It fails.
    3. I use cf push some_app
    4. During the push, the Cloud Controller reports that the buildpack is https://example.org/some/broken/buildpack.
    5. Apparently, this value is used instead of going back to using the admin buildpacks installed in the system.

    So at a guess it seems as though a failed staging event causes CC to not clear the associated buildpack upon a re-push.

    Thanks for the report and your patience, I think we're going to refer this to Runtime for further investigation. As with #24, I will leave this issue open until Runtime advises that they have resolved the issue.

  14. jchesterpivotal commented on Dec 9, 2014

    @jchesterpivotal
    jchesterpivotal
    on Dec 9, 2014
    Contributor

    OK, after discussion in Pivotal Tracker with Runtime and with the CLI PM, looks like this is actually the expected behaviour for CC -- to recall buildpack settings between pushes.

    We now think that this requires a UI change to help users understand that if they are pinned to a remote buildpack, they remain pinned to it upon subsequent pushes. That was the root of the behaviour you and we observed.

    I'm going to open an issue with CLI, and then close this one, because it looks like a clean handoff.

  15. jchesterpivotal commented on Dec 9, 2014

    @jchesterpivotal
    jchesterpivotal
    on Dec 9, 2014
    Contributor

    We've logged our request here. Feel free to hop in.

    Closing.

  16. jchesterpivotal
    closed this as completedon Dec 9, 2014

  17. jchesterpivotal commented on Dec 10, 2014

    @jchesterpivotal
    jchesterpivotal
    on Dec 10, 2014
    Contributor

    Looks like CLI are on top of it and 6.8.0 will report the buildpack being used.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment

Metadata

Metadata

Assignees

No one assigned

    Labels

    No labels
    No labels

    Type

    No type

    Projects

    No projects

      Milestone

      No milestone

      Relationships

      None yet

      Development

      No branches or pull requests

      Issue actions