Skip to content

Instantly share code, notes, and snippets.

@vlledo
Created September 3, 2013 08:18
Show Gist options
  • Save vlledo/6421040 to your computer and use it in GitHub Desktop.
Save vlledo/6421040 to your computer and use it in GitHub Desktop.
Started GET "/" for 127.0.0.1 at 2013-09-03 10:15:08 +0200
Spree::Page Load (0.3ms) SELECT "spree_pages".* FROM "spree_pages" WHERE "spree_pages"."visible" = 't' AND (("spree_pages"."slug" IS NULL OR "spree_pages"."slug" = '/')) ORDER BY position ASC LIMIT 1
CACHE (0.0ms) SELECT "spree_pages".* FROM "spree_pages" WHERE "spree_pages"."visible" = 't' AND (("spree_pages"."slug" IS NULL OR "spree_pages"."slug" = '/')) ORDER BY position ASC LIMIT 1
Processing by Spree::HomeController#index as HTML
Spree::User Load (0.2ms) SELECT "spree_users".* FROM "spree_users" WHERE "spree_users"."id" = 1 LIMIT 1
Spree::Order Load (0.3ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."user_id" = 1 AND "spree_orders"."completed_at" IS NULL AND "spree_orders"."created_by_id" = 1 ORDER BY created_at DESC LIMIT 1
Spree::Order Load (0.2ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 AND "spree_orders"."currency" = 'EUR' LIMIT 1
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."adjustable_id" IN (20) ORDER BY created_at ASC
Spree::Taxonomy Load (0.2ms) SELECT "spree_taxonomies".* FROM "spree_taxonomies" ORDER BY spree_taxonomies.position
Spree::Taxon Load (0.2ms) SELECT "spree_taxons".* FROM "spree_taxons" WHERE "spree_taxons"."parent_id" IS NULL AND "spree_taxons"."taxonomy_id" IN (1, 2)
Spree::Taxon Load (0.2ms) SELECT "spree_taxons".* FROM "spree_taxons" WHERE "spree_taxons"."parent_id" IN (1, 2) ORDER BY lft
Spree::Taxonomy::Translation Load (0.1ms) SELECT "spree_taxonomy_translations".* FROM "spree_taxonomy_translations" WHERE "spree_taxonomy_translations"."spree_taxonomy_id" = 1
Spree::Taxon::Translation Load (0.2ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 4
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 5
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 6
Spree::Taxonomy::Translation Load (0.1ms) SELECT "spree_taxonomy_translations".* FROM "spree_taxonomy_translations" WHERE "spree_taxonomy_translations"."spree_taxonomy_id" = 2
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 10
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 11
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 12
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 13
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_taxonomies.html.erb (47.5ms)
SQL (10.0ms) SELECT DISTINCT "spree_products".id FROM "spree_products" INNER JOIN "spree_variants" ON "spree_variants"."product_id" = "spree_products"."id" AND "spree_variants"."is_master" = 't' INNER JOIN "spree_prices" ON "spree_prices"."variant_id" = "spree_variants"."id" WHERE "spree_products"."deleted_at" IS NULL AND "spree_prices"."currency" = 'EUR' AND ("spree_products".deleted_at IS NULL or "spree_products".deleted_at >= '2013-09-03 08:15:08.266564') AND ("spree_products".available_on <= '2013-09-03 08:15:08.266876') AND (spree_prices.amount IS NOT NULL) LIMIT 12 OFFSET 0
 (0.4ms) SELECT COUNT(*) FROM "spree_products" INNER JOIN "spree_variants" ON "spree_variants"."product_id" = "spree_products"."id" AND "spree_variants"."is_master" = 't' INNER JOIN "spree_prices" ON "spree_prices"."variant_id" = "spree_variants"."id" WHERE "spree_products"."deleted_at" IS NULL AND "spree_prices"."currency" = 'EUR' AND "spree_products"."id" IN (1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12) AND ("spree_products".deleted_at IS NULL or "spree_products".deleted_at >= '2013-09-03 08:15:08.266564') AND ("spree_products".available_on <= '2013-09-03 08:15:08.266876') AND (spree_prices.amount IS NOT NULL)
CACHE (0.0ms) SELECT DISTINCT "spree_products".id FROM "spree_products" INNER JOIN "spree_variants" ON "spree_variants"."product_id" = "spree_products"."id" AND "spree_variants"."is_master" = 't' INNER JOIN "spree_prices" ON "spree_prices"."variant_id" = "spree_variants"."id" WHERE "spree_products"."deleted_at" IS NULL AND "spree_prices"."currency" = 'EUR' AND ("spree_products".deleted_at IS NULL or "spree_products".deleted_at >= '2013-09-03 08:15:08.266564') AND ("spree_products".available_on <= '2013-09-03 08:15:08.266876') AND (spree_prices.amount IS NOT NULL) LIMIT 12 OFFSET 0
CACHE (0.0ms) SELECT COUNT(*) FROM "spree_products" INNER JOIN "spree_variants" ON "spree_variants"."product_id" = "spree_products"."id" AND "spree_variants"."is_master" = 't' INNER JOIN "spree_prices" ON "spree_prices"."variant_id" = "spree_variants"."id" WHERE "spree_products"."deleted_at" IS NULL AND "spree_prices"."currency" = 'EUR' AND "spree_products"."id" IN (1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12) AND ("spree_products".deleted_at IS NULL or "spree_products".deleted_at >= '2013-09-03 08:15:08.266564') AND ("spree_products".available_on <= '2013-09-03 08:15:08.266876') AND (spree_prices.amount IS NOT NULL)
CACHE (0.0ms) SELECT DISTINCT "spree_products".id FROM "spree_products" INNER JOIN "spree_variants" ON "spree_variants"."product_id" = "spree_products"."id" AND "spree_variants"."is_master" = 't' INNER JOIN "spree_prices" ON "spree_prices"."variant_id" = "spree_variants"."id" WHERE "spree_products"."deleted_at" IS NULL AND "spree_prices"."currency" = 'EUR' AND ("spree_products".deleted_at IS NULL or "spree_products".deleted_at >= '2013-09-03 08:15:08.266564') AND ("spree_products".available_on <= '2013-09-03 08:15:08.266876') AND (spree_prices.amount IS NOT NULL) LIMIT 12 OFFSET 0
SQL (0.6ms) SELECT "spree_products"."id" AS t0_r0, "spree_products"."name" AS t0_r1, "spree_products"."description" AS t0_r2, "spree_products"."available_on" AS t0_r3, "spree_products"."deleted_at" AS t0_r4, "spree_products"."permalink" AS t0_r5, "spree_products"."meta_description" AS t0_r6, "spree_products"."meta_keywords" AS t0_r7, "spree_products"."tax_category_id" AS t0_r8, "spree_products"."shipping_category_id" AS t0_r9, "spree_products"."created_at" AS t0_r10, "spree_products"."updated_at" AS t0_r11, "spree_variants"."id" AS t1_r0, "spree_variants"."sku" AS t1_r1, "spree_variants"."weight" AS t1_r2, "spree_variants"."height" AS t1_r3, "spree_variants"."width" AS t1_r4, "spree_variants"."depth" AS t1_r5, "spree_variants"."deleted_at" AS t1_r6, "spree_variants"."is_master" AS t1_r7, "spree_variants"."product_id" AS t1_r8, "spree_variants"."cost_price" AS t1_r9, "spree_variants"."position" AS t1_r10, "spree_variants"."cost_currency" AS t1_r11, "spree_prices"."id" AS t2_r0, "spree_prices"."variant_id" AS t2_r1, "spree_prices"."amount" AS t2_r2, "spree_prices"."currency" AS t2_r3 FROM "spree_products" INNER JOIN "spree_variants" ON "spree_variants"."product_id" = "spree_products"."id" AND "spree_variants"."is_master" = 't' INNER JOIN "spree_prices" ON "spree_prices"."variant_id" = "spree_variants"."id" WHERE "spree_products"."deleted_at" IS NULL AND "spree_prices"."currency" = 'EUR' AND "spree_products"."id" IN (1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12) AND ("spree_products".deleted_at IS NULL or "spree_products".deleted_at >= '2013-09-03 08:15:08.266564') AND ("spree_products".available_on <= '2013-09-03 08:15:08.266876') AND (spree_prices.amount IS NOT NULL)
 (0.6ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 1 AND "spree_assets"."viewable_type" = 'Spree::Variant'
Spree::Image Load (0.3ms) SELECT "spree_assets".* FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 1 AND "spree_assets"."viewable_type" = 'Spree::Variant' ORDER BY position LIMIT 1
Spree::Product::Translation Load (0.1ms) SELECT "spree_product_translations".* FROM "spree_product_translations" WHERE "spree_product_translations"."spree_product_id" = 1
 (0.3ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 2 AND "spree_assets"."viewable_type" = 'Spree::Variant'
Spree::Image Load (0.2ms) SELECT "spree_assets".* FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 2 AND "spree_assets"."viewable_type" = 'Spree::Variant' ORDER BY position LIMIT 1
Spree::Product::Translation Load (0.1ms) SELECT "spree_product_translations".* FROM "spree_product_translations" WHERE "spree_product_translations"."spree_product_id" = 2
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 3 AND "spree_assets"."viewable_type" = 'Spree::Variant'
Spree::Image Load (0.2ms) SELECT "spree_assets".* FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 3 AND "spree_assets"."viewable_type" = 'Spree::Variant' ORDER BY position LIMIT 1
Spree::Product::Translation Load (0.1ms) SELECT "spree_product_translations".* FROM "spree_product_translations" WHERE "spree_product_translations"."spree_product_id" = 3
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 4 AND "spree_assets"."viewable_type" = 'Spree::Variant'
Spree::Image Load (0.2ms) SELECT "spree_assets".* FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 4 AND "spree_assets"."viewable_type" = 'Spree::Variant' ORDER BY position LIMIT 1
Spree::Product::Translation Load (0.1ms) SELECT "spree_product_translations".* FROM "spree_product_translations" WHERE "spree_product_translations"."spree_product_id" = 4
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 5 AND "spree_assets"."viewable_type" = 'Spree::Variant'
Spree::Image Load (0.2ms) SELECT "spree_assets".* FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 5 AND "spree_assets"."viewable_type" = 'Spree::Variant' ORDER BY position LIMIT 1
Spree::Product::Translation Load (0.1ms) SELECT "spree_product_translations".* FROM "spree_product_translations" WHERE "spree_product_translations"."spree_product_id" = 5
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 6 AND "spree_assets"."viewable_type" = 'Spree::Variant'
Spree::Image Load (0.2ms) SELECT "spree_assets".* FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 6 AND "spree_assets"."viewable_type" = 'Spree::Variant' ORDER BY position LIMIT 1
Spree::Product::Translation Load (0.1ms) SELECT "spree_product_translations".* FROM "spree_product_translations" WHERE "spree_product_translations"."spree_product_id" = 6
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 7 AND "spree_assets"."viewable_type" = 'Spree::Variant'
Spree::Image Load (0.2ms) SELECT "spree_assets".* FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 7 AND "spree_assets"."viewable_type" = 'Spree::Variant' ORDER BY position LIMIT 1
Spree::Product::Translation Load (0.1ms) SELECT "spree_product_translations".* FROM "spree_product_translations" WHERE "spree_product_translations"."spree_product_id" = 7
 (0.3ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 8 AND "spree_assets"."viewable_type" = 'Spree::Variant'
Spree::Image Load (0.3ms) SELECT "spree_assets".* FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 8 AND "spree_assets"."viewable_type" = 'Spree::Variant' ORDER BY position LIMIT 1
Spree::Product::Translation Load (0.1ms) SELECT "spree_product_translations".* FROM "spree_product_translations" WHERE "spree_product_translations"."spree_product_id" = 8
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 9 AND "spree_assets"."viewable_type" = 'Spree::Variant'
Spree::Image Load (0.2ms) SELECT "spree_assets".* FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 9 AND "spree_assets"."viewable_type" = 'Spree::Variant' ORDER BY position LIMIT 1
Spree::Product::Translation Load (0.2ms) SELECT "spree_product_translations".* FROM "spree_product_translations" WHERE "spree_product_translations"."spree_product_id" = 9
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 10 AND "spree_assets"."viewable_type" = 'Spree::Variant'
Spree::Image Load (0.2ms) SELECT "spree_assets".* FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 10 AND "spree_assets"."viewable_type" = 'Spree::Variant' ORDER BY position LIMIT 1
Spree::Product::Translation Load (0.1ms) SELECT "spree_product_translations".* FROM "spree_product_translations" WHERE "spree_product_translations"."spree_product_id" = 10
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 11 AND "spree_assets"."viewable_type" = 'Spree::Variant'
Spree::Image Load (0.2ms) SELECT "spree_assets".* FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 11 AND "spree_assets"."viewable_type" = 'Spree::Variant' ORDER BY position LIMIT 1
Spree::Product::Translation Load (0.1ms) SELECT "spree_product_translations".* FROM "spree_product_translations" WHERE "spree_product_translations"."spree_product_id" = 11
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 12 AND "spree_assets"."viewable_type" = 'Spree::Variant'
Spree::Image Load (0.2ms) SELECT "spree_assets".* FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 12 AND "spree_assets"."viewable_type" = 'Spree::Variant' ORDER BY position LIMIT 1
Spree::Product::Translation Load (0.1ms) SELECT "spree_product_translations".* FROM "spree_product_translations" WHERE "spree_product_translations"."spree_product_id" = 12
 (0.2ms) SELECT COUNT(*) FROM "spree_products" INNER JOIN "spree_variants" ON "spree_variants"."product_id" = "spree_products"."id" AND "spree_variants"."is_master" = 't' INNER JOIN "spree_prices" ON "spree_prices"."variant_id" = "spree_variants"."id" WHERE "spree_products"."deleted_at" IS NULL AND "spree_prices"."currency" = 'EUR' AND ("spree_products".deleted_at IS NULL or "spree_products".deleted_at >= '2013-09-03 08:15:08.266564') AND ("spree_products".available_on <= '2013-09-03 08:15:08.266876') AND (spree_prices.amount IS NOT NULL)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_products.html.erb (404.2ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/home/index.html.erb within spree/layouts/spree_application (492.3ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/core/app/views/spree/shared/_routes.html.erb (0.5ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_head.html.erb (29.8ms)
Deface: 1 overrides found for 'spree/shared/_nav_bar'
Deface: 'auth_shared_login_bar' matched 1 times with 'li#search-bar'
Spree::Taxon Load (0.3ms) SELECT "spree_taxons".* FROM "spree_taxons" WHERE "spree_taxons"."parent_id" IS NULL ORDER BY "spree_taxons"."lft"
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 1
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 2
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 3
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 9
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_search.html.erb (6.3ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_nav_bar.html.erb (11.9ms)
Deface: 2 overrides found for 'spree/shared/_main_nav_bar'
Deface: 'locale_selector' matched 1 times with '#main-nav-bar'
Deface: [WARNING] No :original defined for 'locale_selector', you should change its definition to include:
:original => 'db2835f20e2ab66e854a74f6ae2a05cfb86f20fd'
Deface: 'pages_in_header' matched 1 times with '#main-nav-bar'
Deface: [WARNING] No :original defined for 'pages_in_header', you should change its definition to include:
:original => '69e748b409fc9557847596ce97b153d0be34385d'
 (0.1ms) SELECT COUNT(*) FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20
Spree::LineItem Load (0.2ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20 ORDER BY created_at ASC
Spree::Page Load (0.2ms) SELECT "spree_pages".* FROM "spree_pages" WHERE "spree_pages"."show_in_header" = 't' AND "spree_pages"."visible" = 't' ORDER BY position ASC
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree_static_content-a9d17d7fe22b/app/views/spree/static_content/_static_content_list.html.erb (0.2ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_main_nav_bar.html.erb (25.9ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_header.html.erb (168.3ms)
Deface: 1 overrides found for 'spree/shared/_sidebar'
Deface: 'pages_in_sidebar' matched 1 times with '#sidebar'
Deface: [WARNING] No :original defined for 'pages_in_sidebar', you should change its definition to include:
:original => 'd1fc4156cbab1ac52f706094ef49c096c6cc99c6'
 (0.2ms) SELECT COUNT(*) FROM "spree_pages" WHERE "spree_pages"."show_in_sidebar" = 't' AND "spree_pages"."visible" = 't'
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_sidebar.html.erb (1.7ms)
Deface: 1 overrides found for 'spree/shared/_footer'
Deface: 'pages_in_footer' matched 1 times with '#footer-right'
Deface: [WARNING] No :original defined for 'pages_in_footer', you should change its definition to include:
:original => 'd9b8e77ea5ae5135f238b2c9fa4caa345b1c8412'
Spree::Page Load (0.3ms) SELECT "spree_pages".* FROM "spree_pages" WHERE "spree_pages"."show_in_footer" = 't' AND "spree_pages"."visible" = 't' ORDER BY position ASC
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree_static_content-a9d17d7fe22b/app/views/spree/static_content/_static_content_list.html.erb (0.5ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-yoexportoaceite-theme-0c7e2325fed5/app/views/spree/shared/_footer.html.erb (5.1ms)
Spree::Tracker Load (0.1ms) SELECT "spree_trackers".* FROM "spree_trackers" WHERE "spree_trackers"."active" = 't' AND "spree_trackers"."environment" = 'development' LIMIT 1
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_google_analytics.html.erb (0.8ms)
Completed 200 OK in 1041ms (Views: 929.5ms | ActiveRecord: 22.8ms)
Started GET "/assets/normalize.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:09 +0200
Served asset /normalize.css - 304 Not Modified (0ms)
Started GET "/assets/skeleton.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:09 +0200
Served asset /skeleton.css - 304 Not Modified (0ms)
Started GET "/assets/store/screen.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:09 +0200
Served asset /store/screen.css - 304 Not Modified (0ms)
Started GET "/assets/store/spree_paypal_express.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:09 +0200
Served asset /store/spree_paypal_express.css - 304 Not Modified (0ms)
Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:09 +0200
Served asset /jquery_ujs.js - 304 Not Modified (0ms)
Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:09 +0200
Served asset /jquery.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_yoexporto.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:09 +0200
Served asset /store/spree_yoexporto.css - 304 Not Modified (0ms)
Started GET "/assets/jquery.validate/jquery.validate.min.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:09 +0200
Served asset /jquery.validate/jquery.validate.min.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_frontend.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:09 +0200
Served asset /store/spree_frontend.css - 304 Not Modified (0ms)
Started GET "/assets/jsuri.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:09 +0200
Served asset /jsuri.js - 304 Not Modified (0ms)
Started GET "/assets/spree.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:09 +0200
Served asset /spree.js - 304 Not Modified (0ms)
Started GET "/assets/store/checkout.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:09 +0200
Served asset /store/checkout.js - 304 Not Modified (0ms)
Started GET "/assets/store/cart.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:09 +0200
Served asset /store/cart.js - 304 Not Modified (0ms)
Started GET "/assets/store/product.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:09 +0200
Served asset /store/product.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_frontend.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:09 +0200
Served asset /store/spree_frontend.js - 304 Not Modified (0ms)
Started GET "/assets/store/locale.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:09 +0200
Served asset /store/locale.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_i18n.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:09 +0200
Served asset /store/spree_i18n.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_yoexporto.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:09 +0200
Served asset /store/spree_yoexporto.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_paypal_express.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:09 +0200
Served asset /store/spree_paypal_express.js - 304 Not Modified (0ms)
Started GET "/cart" for 127.0.0.1 at 2013-09-03 10:15:13 +0200
Spree::Page Exists (0.3ms) SELECT 1 AS one FROM "spree_pages" WHERE "spree_pages"."visible" = 't' AND (("spree_pages"."slug" = 'cart' OR "spree_pages"."slug" = '/cart')) ORDER BY position ASC LIMIT 1
CACHE (0.0ms) SELECT 1 AS one FROM "spree_pages" WHERE "spree_pages"."visible" = 't' AND (("spree_pages"."slug" = 'cart' OR "spree_pages"."slug" = '/cart')) ORDER BY position ASC LIMIT 1
Processing by Spree::OrdersController#edit as HTML
Spree::User Load (0.2ms) SELECT "spree_users".* FROM "spree_users" WHERE "spree_users"."id" = 1 LIMIT 1
Spree::Order Load (0.2ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."user_id" = 1 AND "spree_orders"."completed_at" IS NULL AND "spree_orders"."created_by_id" = 1 ORDER BY created_at DESC LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 AND "spree_orders"."currency" = 'EUR' LIMIT 1
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."adjustable_id" IN (20) ORDER BY created_at ASC
Spree::Order Load (7.4ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."number" IS NULL LIMIT 1
 (0.1ms) SELECT COUNT(*) FROM "spree_roles" INNER JOIN "spree_roles_users" ON "spree_roles"."id" = "spree_roles_users"."role_id" WHERE "spree_roles_users"."user_id" = 1 AND "spree_roles"."name" = 'admin'
CACHE (0.0ms) SELECT "spree_users".* FROM "spree_users" WHERE "spree_users"."id" = 1 LIMIT 1
 (0.1ms) SELECT COUNT(*) FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/core/app/views/spree/shared/_error_messages.html.erb (0.1ms)
Spree::LineItem Load (0.2ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20 ORDER BY created_at ASC
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."id" = 2 LIMIT 1
Spree::Image Load (0.3ms) SELECT "spree_assets".* FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 2 AND "spree_assets"."viewable_type" = 'Spree::Variant' ORDER BY position
Spree::Product Load (0.1ms) SELECT "spree_products".* FROM "spree_products" WHERE "spree_products"."id" = 2 LIMIT 1
Spree::Product::Translation Load (0.1ms) SELECT "spree_product_translations".* FROM "spree_product_translations" WHERE "spree_product_translations"."spree_product_id" = 2
Spree::OptionValue Load (0.2ms) SELECT "spree_option_values".* FROM "spree_option_values" INNER JOIN "spree_option_types" ON "spree_option_types"."id" = "spree_option_values"."option_type_id" INNER JOIN "spree_option_values_variants" ON "spree_option_values"."id" = "spree_option_values_variants"."option_value_id" WHERE "spree_option_values_variants"."variant_id" = 2 ORDER BY spree_option_types.position asc
Spree::StockItem Load (0.2ms) SELECT "spree_stock_items".* FROM "spree_stock_items" INNER JOIN "spree_stock_locations" ON "spree_stock_locations"."id" = "spree_stock_items"."stock_location_id" WHERE "spree_stock_items"."variant_id" = 2 AND "spree_stock_locations"."active" = 't'
Spree::StockItem Load (0.1ms) SELECT "spree_stock_items".* FROM "spree_stock_items" INNER JOIN "spree_stock_locations" ON "spree_stock_locations"."id" = "spree_stock_items"."stock_location_id" WHERE "spree_stock_items"."variant_id" = 1 AND "spree_stock_locations"."active" = 't'
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."id" = 1 LIMIT 1
Spree::Image Load (0.3ms) SELECT "spree_assets".* FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 1 AND "spree_assets"."viewable_type" = 'Spree::Variant' ORDER BY position
Spree::Product Load (0.1ms) SELECT "spree_products".* FROM "spree_products" WHERE "spree_products"."id" = 1 LIMIT 1
Spree::Product::Translation Load (0.1ms) SELECT "spree_product_translations".* FROM "spree_product_translations" WHERE "spree_product_translations"."spree_product_id" = 1
Spree::OptionValue Load (0.2ms) SELECT "spree_option_values".* FROM "spree_option_values" INNER JOIN "spree_option_types" ON "spree_option_types"."id" = "spree_option_values"."option_type_id" INNER JOIN "spree_option_values_variants" ON "spree_option_values"."id" = "spree_option_values_variants"."option_value_id" WHERE "spree_option_values_variants"."variant_id" = 1 ORDER BY spree_option_types.position asc
CACHE (0.0ms) SELECT "spree_stock_items".* FROM "spree_stock_items" INNER JOIN "spree_stock_locations" ON "spree_stock_locations"."id" = "spree_stock_items"."stock_location_id" WHERE "spree_stock_items"."variant_id" = 2 AND "spree_stock_locations"."active" = 't'
CACHE (0.0ms) SELECT "spree_stock_items".* FROM "spree_stock_items" INNER JOIN "spree_stock_locations" ON "spree_stock_locations"."id" = "spree_stock_items"."stock_location_id" WHERE "spree_stock_items"."variant_id" = 1 AND "spree_stock_locations"."active" = 't'
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/orders/_line_item.html.erb (88.3ms)
 (0.2ms) SELECT COUNT(*) FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't'
Spree::Adjustment Load (0.3ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/orders/_adjustments.html.erb (6.2ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/orders/_form.html.erb (136.1ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/orders/edit.html.erb within spree/layouts/spree_application (263.3ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/core/app/views/spree/shared/_routes.html.erb (0.4ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_head.html.erb (28.8ms)
Deface: 1 overrides found for 'spree/shared/_nav_bar'
Deface: 'auth_shared_login_bar' matched 1 times with 'li#search-bar'
Spree::Taxon Load (0.3ms) SELECT "spree_taxons".* FROM "spree_taxons" WHERE "spree_taxons"."parent_id" IS NULL ORDER BY "spree_taxons"."lft"
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 1
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 2
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 3
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 9
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_search.html.erb (5.8ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_nav_bar.html.erb (10.9ms)
Deface: 2 overrides found for 'spree/shared/_main_nav_bar'
Deface: 'locale_selector' matched 1 times with '#main-nav-bar'
Deface: [WARNING] No :original defined for 'locale_selector', you should change its definition to include:
:original => 'db2835f20e2ab66e854a74f6ae2a05cfb86f20fd'
Deface: 'pages_in_header' matched 1 times with '#main-nav-bar'
Deface: [WARNING] No :original defined for 'pages_in_header', you should change its definition to include:
:original => '69e748b409fc9557847596ce97b153d0be34385d'
Spree::Page Load (0.3ms) SELECT "spree_pages".* FROM "spree_pages" WHERE "spree_pages"."show_in_header" = 't' AND "spree_pages"."visible" = 't' ORDER BY position ASC
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree_static_content-a9d17d7fe22b/app/views/spree/static_content/_static_content_list.html.erb (0.2ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_main_nav_bar.html.erb (5.8ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_header.html.erb (35.1ms)
Deface: 1 overrides found for 'spree/shared/_footer'
Deface: 'pages_in_footer' matched 1 times with '#footer-right'
Deface: [WARNING] No :original defined for 'pages_in_footer', you should change its definition to include:
:original => 'd9b8e77ea5ae5135f238b2c9fa4caa345b1c8412'
Spree::Page Load (0.3ms) SELECT "spree_pages".* FROM "spree_pages" WHERE "spree_pages"."show_in_footer" = 't' AND "spree_pages"."visible" = 't' ORDER BY position ASC
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree_static_content-a9d17d7fe22b/app/views/spree/static_content/_static_content_list.html.erb (0.4ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-yoexportoaceite-theme-0c7e2325fed5/app/views/spree/shared/_footer.html.erb (4.3ms)
Spree::Tracker Load (0.1ms) SELECT "spree_trackers".* FROM "spree_trackers" WHERE "spree_trackers"."active" = 't' AND "spree_trackers"."environment" = 'development' LIMIT 1
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_google_analytics.html.erb (0.6ms)
Completed 200 OK in 416ms (Views: 373.7ms | ActiveRecord: 12.2ms)
Started GET "/assets/normalize.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:14 +0200
Served asset /normalize.css - 304 Not Modified (0ms)
Started GET "/assets/skeleton.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:14 +0200
Served asset /skeleton.css - 304 Not Modified (0ms)
Started GET "/assets/store/spree_paypal_express.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:14 +0200
Served asset /store/spree_paypal_express.css - 304 Not Modified (0ms)
Started GET "/assets/store/spree_frontend.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:14 +0200
Served asset /store/spree_frontend.css - 304 Not Modified (0ms)
Started GET "/assets/store/screen.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:14 +0200
Served asset /store/screen.css - 304 Not Modified (0ms)
Started GET "/assets/store/spree_yoexporto.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:14 +0200
Served asset /store/spree_yoexporto.css - 304 Not Modified (0ms)
Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:14 +0200
Served asset /jquery.js - 304 Not Modified (0ms)
Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:14 +0200
Served asset /jquery_ujs.js - 304 Not Modified (0ms)
Started GET "/assets/jquery.validate/jquery.validate.min.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:14 +0200
Served asset /jquery.validate/jquery.validate.min.js - 304 Not Modified (0ms)
Started GET "/assets/jsuri.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:14 +0200
Served asset /jsuri.js - 304 Not Modified (0ms)
Started GET "/assets/spree.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:14 +0200
Served asset /spree.js - 304 Not Modified (0ms)
Started GET "/assets/store/checkout.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:14 +0200
Served asset /store/checkout.js - 304 Not Modified (0ms)
Started GET "/assets/store/product.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:14 +0200
Served asset /store/product.js - 304 Not Modified (0ms)
Started GET "/assets/store/cart.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:14 +0200
Served asset /store/cart.js - 304 Not Modified (0ms)
Started GET "/assets/store/locale.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:14 +0200
Served asset /store/locale.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_frontend.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:14 +0200
Served asset /store/spree_frontend.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_i18n.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:14 +0200
Served asset /store/spree_i18n.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_paypal_express.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:14 +0200
Served asset /store/spree_paypal_express.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_yoexporto.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:14 +0200
Served asset /store/spree_yoexporto.js - 304 Not Modified (0ms)
Started GET "/assets/store/locale.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:14 +0200
Served asset /store/locale.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_i18n.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:14 +0200
Served asset /store/spree_i18n.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_yoexporto.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:14 +0200
Served asset /store/spree_yoexporto.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_paypal_express.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:15 +0200
Served asset /store/spree_paypal_express.js - 304 Not Modified (0ms)
Started PUT "/cart" for 127.0.0.1 at 2013-09-03 10:15:18 +0200
Processing by Spree::OrdersController#update as HTML
Parameters: {"utf8"=>"✓", "authenticity_token"=>"Z4VAnyBl3LnxFHfiUVATVLOTAeezNyNAZ/HitL3KxWo=", "order"=>{"line_items_attributes"=>{"0"=>{"quantity"=>"1", "id"=>"28"}, "1"=>{"quantity"=>"1", "id"=>"29"}}, "coupon_code"=>""}, "checkout"=>""}
Spree::User Load (0.2ms) SELECT "spree_users".* FROM "spree_users" WHERE "spree_users"."id" = 1 LIMIT 1
Spree::Order Load (0.2ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."user_id" = 1 AND "spree_orders"."completed_at" IS NULL AND "spree_orders"."created_by_id" = 1 ORDER BY created_at DESC LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 AND "spree_orders"."currency" = 'EUR' LIMIT 1
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."adjustable_id" IN (20) ORDER BY created_at ASC
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."number" IS NULL LIMIT 1
 (0.1ms) SELECT COUNT(*) FROM "spree_roles" INNER JOIN "spree_roles_users" ON "spree_roles"."id" = "spree_roles_users"."role_id" WHERE "spree_roles_users"."user_id" = 1 AND "spree_roles"."name" = 'admin'
 (0.0ms) begin transaction
Spree::LineItem Load (0.2ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20 AND "spree_line_items"."id" IN (28, 29) ORDER BY created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20 ORDER BY created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20
Spree::PaymentMethod Load (0.1ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
 (0.1ms) commit transaction
 (0.0ms) begin transaction
 (0.0ms) commit transaction
 (0.1ms) SELECT COUNT(*) FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 20
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 20
 (0.0ms) begin transaction
Spree::ShippingRate Load (0.1ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 43
SQL (0.3ms) DELETE FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."id" = ? [["id", 91]]
SQL (0.0ms) DELETE FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."id" = ? [["id", 92]]
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 43
SQL (0.1ms) DELETE FROM "spree_inventory_units" WHERE "spree_inventory_units"."id" = ? [["id", 65]]
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."source_id" = 43 AND "spree_adjustments"."source_type" = 'Spree::Shipment' LIMIT 1
SQL (7.6ms) DELETE FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? [["id", 47]]
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20 ORDER BY created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY created_at ASC
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 48]]
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 44 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 44
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (1)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.2ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 48
SQL (0.1ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 48
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."originator_type" = 'Spree::PromotionAction' AND "spree_adjustments"."eligible" = 't' ORDER BY amount ASC, created_at DESC LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
SQL (0.1ms) UPDATE "spree_orders" SET "payment_state" = 'balance_due', "shipment_state" = NULL, "item_total" = 33.0, "adjustment_total" = 11.93, "payment_total" = 0.0, "total" = 44.93 WHERE "spree_orders"."id" = 20
SQL (16.1ms) DELETE FROM "spree_shipments" WHERE "spree_shipments"."id" = ? [["id", 43]]
Spree::ShippingRate Load (0.1ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 44
SQL (0.0ms) DELETE FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."id" = ? [["id", 93]]
SQL (0.0ms) DELETE FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."id" = ? [["id", 94]]
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 44
SQL (0.0ms) DELETE FROM "spree_inventory_units" WHERE "spree_inventory_units"."id" = ? [["id", 66]]
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."source_id" = 44 AND "spree_adjustments"."source_type" = 'Spree::Shipment' LIMIT 1
SQL (0.0ms) DELETE FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? [["id", 48]]
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20 ORDER BY created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."originator_type" = 'Spree::PromotionAction' AND "spree_adjustments"."eligible" = 't' ORDER BY amount ASC, created_at DESC LIMIT 1
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
SQL (0.1ms) UPDATE "spree_orders" SET "payment_state" = 'balance_due', "shipment_state" = NULL, "item_total" = 33.0, "adjustment_total" = 6.93, "payment_total" = 0.0, "total" = 39.93 WHERE "spree_orders"."id" = 20
SQL (0.0ms) DELETE FROM "spree_shipments" WHERE "spree_shipments"."id" = ? [["id", 44]]
 (2.4ms) commit transaction
SQL (1.2ms) UPDATE "spree_orders" SET "state" = 'address' WHERE "spree_orders"."id" = 20
Spree::Activator Load (0.1ms) SELECT "spree_activators".* FROM "spree_activators" WHERE (starts_at IS NULL OR starts_at < '2013-09-03 08:15:18.532673') AND (expires_at IS NULL OR expires_at > '2013-09-03 08:15:18.532821') AND (event_name LIKE 'spree.order.contents_changed%')
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Redirected to http://localhost:3000/checkout/address
Completed 302 Found in 459ms (ActiveRecord: 33.9ms)
Started GET "/checkout/address" for 127.0.0.1 at 2013-09-03 10:15:18 +0200
Spree::Page Exists (0.2ms) SELECT 1 AS one FROM "spree_pages" WHERE "spree_pages"."visible" = 't' AND (("spree_pages"."slug" = 'checkout/address' OR "spree_pages"."slug" = '/checkout/address')) ORDER BY position ASC LIMIT 1
CACHE (0.0ms) SELECT 1 AS one FROM "spree_pages" WHERE "spree_pages"."visible" = 't' AND (("spree_pages"."slug" = 'checkout/address' OR "spree_pages"."slug" = '/checkout/address')) ORDER BY position ASC LIMIT 1
Processing by Spree::CheckoutController#edit as HTML
Parameters: {"state"=>"address"}
Spree::User Load (0.2ms) SELECT "spree_users".* FROM "spree_users" WHERE "spree_users"."id" = 1 LIMIT 1
Spree::Order Load (0.2ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."user_id" = 1 AND "spree_orders"."completed_at" IS NULL AND "spree_orders"."created_by_id" = 1 ORDER BY created_at DESC LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 AND "spree_orders"."currency" = 'EUR' LIMIT 1
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."adjustable_id" IN (20) ORDER BY created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20 ORDER BY created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20
Spree::PaymentMethod Load (0.1ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
 (0.1ms) SELECT COUNT(*) FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20
Spree::StockItem Load (0.1ms) SELECT "spree_stock_items".* FROM "spree_stock_items" INNER JOIN "spree_stock_locations" ON "spree_stock_locations"."id" = "spree_stock_items"."stock_location_id" WHERE "spree_stock_items"."variant_id" = 2 AND "spree_stock_locations"."active" = 't'
Spree::StockItem Load (0.1ms) SELECT "spree_stock_items".* FROM "spree_stock_items" INNER JOIN "spree_stock_locations" ON "spree_stock_locations"."id" = "spree_stock_items"."stock_location_id" WHERE "spree_stock_items"."variant_id" = 1 AND "spree_stock_locations"."active" = 't'
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
CACHE (0.0ms) SELECT "spree_users".* FROM "spree_users" WHERE "spree_users"."id" = 1 LIMIT 1
Spree::Address Load (0.1ms) SELECT "spree_addresses".* FROM "spree_addresses" WHERE "spree_addresses"."id" = 48 LIMIT 1
Spree::Address Load (0.1ms) SELECT "spree_addresses".* FROM "spree_addresses" WHERE "spree_addresses"."id" = 49 LIMIT 1
 (0.1ms) SELECT COUNT(*) FROM "spree_roles" INNER JOIN "spree_roles_users" ON "spree_roles"."id" = "spree_roles_users"."role_id" WHERE "spree_roles_users"."user_id" = 1 AND "spree_roles"."name" = 'admin'
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/core/app/views/spree/shared/_error_messages.html.erb (0.1ms)
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Preference Load (0.2ms) SELECT "spree_preferences".* FROM "spree_preferences" WHERE "spree_preferences"."key" = 'spree/app_configuration/checkout_zone' LIMIT 1
Spree::Zone Load (14.4ms) SELECT "spree_zones".* FROM "spree_zones" WHERE "spree_zones"."name" IS NULL LIMIT 1
Spree::Country Load (0.8ms) SELECT "spree_countries".* FROM "spree_countries"
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 175 LIMIT 1
 (0.1ms) SELECT COUNT(*) FROM "spree_states" WHERE "spree_states"."country_id" = 175
Spree::State Load (0.2ms) SELECT "spree_states".* FROM "spree_states" WHERE "spree_states"."country_id" = 175 ORDER BY name ASC
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/address/_form.html.erb (115.0ms)
CACHE (0.0ms) SELECT "spree_preferences".* FROM "spree_preferences" WHERE "spree_preferences"."key" = 'spree/app_configuration/checkout_zone' LIMIT 1
CACHE (0.0ms) SELECT "spree_zones".* FROM "spree_zones" WHERE "spree_zones"."name" IS NULL LIMIT 1
CACHE (0.0ms) SELECT "spree_countries".* FROM "spree_countries"
CACHE (0.0ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 175 LIMIT 1
CACHE (0.0ms) SELECT COUNT(*) FROM "spree_states" WHERE "spree_states"."country_id" = 175
CACHE (0.0ms) SELECT "spree_states".* FROM "spree_states" WHERE "spree_states"."country_id" = 175 ORDER BY name ASC
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/address/_form.html.erb (123.1ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/checkout/_address.html.erb (279.6ms)
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Order Load (0.2ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" INNER JOIN "spree_line_items" ON "spree_adjustments"."adjustable_id" = "spree_line_items"."id" AND "spree_adjustments"."adjustable_type" = 'Spree::LineItem' WHERE "spree_line_items"."order_id" = 20 AND "spree_adjustments"."eligible" = 't'
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/checkout/_summary.html.erb (6.2ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/checkout/edit.html.erb within spree/layouts/spree_application (317.3ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/core/app/views/spree/shared/_routes.html.erb (0.4ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_head.html.erb (12.0ms)
Deface: 1 overrides found for 'spree/shared/_nav_bar'
Deface: 'auth_shared_login_bar' matched 1 times with 'li#search-bar'
Spree::Taxon Load (0.3ms) SELECT "spree_taxons".* FROM "spree_taxons" WHERE "spree_taxons"."parent_id" IS NULL ORDER BY "spree_taxons"."lft"
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 1
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 2
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 3
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 9
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_search.html.erb (6.3ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_nav_bar.html.erb (11.3ms)
Deface: 2 overrides found for 'spree/shared/_main_nav_bar'
Deface: 'locale_selector' matched 1 times with '#main-nav-bar'
Deface: [WARNING] No :original defined for 'locale_selector', you should change its definition to include:
:original => 'db2835f20e2ab66e854a74f6ae2a05cfb86f20fd'
Deface: 'pages_in_header' matched 1 times with '#main-nav-bar'
Deface: [WARNING] No :original defined for 'pages_in_header', you should change its definition to include:
:original => '69e748b409fc9557847596ce97b153d0be34385d'
Spree::Page Load (0.3ms) SELECT "spree_pages".* FROM "spree_pages" WHERE "spree_pages"."show_in_header" = 't' AND "spree_pages"."visible" = 't' ORDER BY position ASC
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree_static_content-a9d17d7fe22b/app/views/spree/static_content/_static_content_list.html.erb (0.2ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_main_nav_bar.html.erb (7.0ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_header.html.erb (48.8ms)
Deface: 1 overrides found for 'spree/shared/_footer'
Deface: 'pages_in_footer' matched 1 times with '#footer-right'
Deface: [WARNING] No :original defined for 'pages_in_footer', you should change its definition to include:
:original => 'd9b8e77ea5ae5135f238b2c9fa4caa345b1c8412'
Spree::Page Load (0.4ms) SELECT "spree_pages".* FROM "spree_pages" WHERE "spree_pages"."show_in_footer" = 't' AND "spree_pages"."visible" = 't' ORDER BY position ASC
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree_static_content-a9d17d7fe22b/app/views/spree/static_content/_static_content_list.html.erb (0.5ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-yoexportoaceite-theme-0c7e2325fed5/app/views/spree/shared/_footer.html.erb (4.8ms)
Spree::Tracker Load (0.1ms) SELECT "spree_trackers".* FROM "spree_trackers" WHERE "spree_trackers"."active" = 't' AND "spree_trackers"."environment" = 'development' LIMIT 1
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_google_analytics.html.erb (0.6ms)
Completed 200 OK in 454ms (Views: 403.6ms | ActiveRecord: 19.8ms)
Started GET "/assets/store/screen.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:19 +0200
Served asset /store/screen.css - 304 Not Modified (0ms)
Started GET "/assets/store/spree_frontend.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:19 +0200
Served asset /store/spree_frontend.css - 304 Not Modified (0ms)
Started GET "/assets/normalize.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:19 +0200
Served asset /normalize.css - 304 Not Modified (0ms)
Started GET "/assets/skeleton.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:19 +0200
Served asset /skeleton.css - 304 Not Modified (0ms)
Started GET "/assets/store/spree_yoexporto.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:19 +0200
Served asset /store/spree_yoexporto.css - 304 Not Modified (0ms)
Started GET "/assets/store/spree_paypal_express.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:19 +0200
Served asset /store/spree_paypal_express.css - 304 Not Modified (0ms)
Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:19 +0200
Served asset /jquery.js - 304 Not Modified (0ms)
Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:19 +0200
Served asset /jquery_ujs.js - 304 Not Modified (0ms)
Started GET "/assets/jquery.validate/jquery.validate.min.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:19 +0200
Served asset /jquery.validate/jquery.validate.min.js - 304 Not Modified (0ms)
Started GET "/assets/jsuri.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:19 +0200
Served asset /jsuri.js - 304 Not Modified (0ms)
Started GET "/assets/spree.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:19 +0200
Served asset /spree.js - 304 Not Modified (0ms)
Started GET "/assets/store/checkout.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:19 +0200
Served asset /store/checkout.js - 304 Not Modified (0ms)
Started GET "/assets/store/product.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:19 +0200
Served asset /store/product.js - 304 Not Modified (0ms)
Started GET "/assets/store/cart.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:19 +0200
Served asset /store/cart.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_frontend.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:19 +0200
Served asset /store/spree_frontend.js - 304 Not Modified (0ms)
Started GET "/assets/store/locale.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:19 +0200
Served asset /store/locale.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_i18n.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:19 +0200
Served asset /store/spree_i18n.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_paypal_express.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:19 +0200
Served asset /store/spree_paypal_express.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_yoexporto.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:19 +0200
Served asset /store/spree_yoexporto.js - 304 Not Modified (0ms)
Started GET "/api/states?country_id=175" for 127.0.0.1 at 2013-09-03 10:15:19 +0200
Spree::Page Exists (0.3ms) SELECT 1 AS one FROM "spree_pages" WHERE "spree_pages"."visible" = 't' AND (("spree_pages"."slug" = 'api/states' OR "spree_pages"."slug" = '/api/states')) ORDER BY position ASC LIMIT 1
CACHE (0.0ms) SELECT 1 AS one FROM "spree_pages" WHERE "spree_pages"."visible" = 't' AND (("spree_pages"."slug" = 'api/states' OR "spree_pages"."slug" = '/api/states')) ORDER BY position ASC LIMIT 1
Spree::User Load (0.2ms) SELECT "spree_users".* FROM "spree_users" WHERE "spree_users"."id" = 1 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = ? LIMIT 1 [["id", "175"]]
Spree::State Load (0.3ms) SELECT "spree_states".* FROM "spree_states" WHERE "spree_states"."country_id" = 175 ORDER BY name ASC LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" IN (175)
Spree::State Load (0.2ms) SELECT "spree_states".* FROM "spree_states" WHERE "spree_states"."country_id" = 175 ORDER BY name ASC
CACHE (0.0ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" IN (175)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/api/app/views/spree/api/states/index.v1.rabl (51.7ms)
Started PUT "/checkout/update/address" for 127.0.0.1 at 2013-09-03 10:15:23 +0200
Processing by Spree::CheckoutController#update as HTML
Parameters: {"utf8"=>"✓", "authenticity_token"=>"Z4VAnyBl3LnxFHfiUVATVLOTAeezNyNAZ/HitL3KxWo=", "order"=>{"bill_address_attributes"=>{"firstname"=>"Víctor", "lastname"=>"Lledó", "address1"=>"Avenida de las cruces 18,4B", "address2"=>"", "city"=>"Jaen", "country_id"=>"175", "state_id"=>"55", "zipcode"=>"23003", "phone"=>"653368046", "id"=>"48"}, "use_billing"=>"1", "ship_address_attributes"=>{"id"=>"49"}}, "commit"=>"Guardar y continuar", "state"=>"address"}
Spree::User Load (0.2ms) SELECT "spree_users".* FROM "spree_users" WHERE "spree_users"."id" = 1 LIMIT 1
Spree::Order Load (0.2ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."user_id" = 1 AND "spree_orders"."completed_at" IS NULL AND "spree_orders"."created_by_id" = 1 ORDER BY created_at DESC LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 AND "spree_orders"."currency" = 'EUR' LIMIT 1
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."adjustable_id" IN (20) ORDER BY created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20 ORDER BY created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Payment Load (0.2ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20
Spree::PaymentMethod Load (0.1ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
 (0.1ms) SELECT COUNT(*) FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20
Spree::StockItem Load (0.1ms) SELECT "spree_stock_items".* FROM "spree_stock_items" INNER JOIN "spree_stock_locations" ON "spree_stock_locations"."id" = "spree_stock_items"."stock_location_id" WHERE "spree_stock_items"."variant_id" = 2 AND "spree_stock_locations"."active" = 't'
Spree::StockItem Load (0.1ms) SELECT "spree_stock_items".* FROM "spree_stock_items" INNER JOIN "spree_stock_locations" ON "spree_stock_locations"."id" = "spree_stock_items"."stock_location_id" WHERE "spree_stock_items"."variant_id" = 1 AND "spree_stock_locations"."active" = 't'
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
CACHE (0.0ms) SELECT "spree_users".* FROM "spree_users" WHERE "spree_users"."id" = 1 LIMIT 1
Spree::Address Load (0.1ms) SELECT "spree_addresses".* FROM "spree_addresses" WHERE "spree_addresses"."id" = 48 LIMIT 1
Spree::Address Load (0.1ms) SELECT "spree_addresses".* FROM "spree_addresses" WHERE "spree_addresses"."id" = 49 LIMIT 1
 (0.1ms) SELECT COUNT(*) FROM "spree_roles" INNER JOIN "spree_roles_users" ON "spree_roles"."id" = "spree_roles_users"."role_id" WHERE "spree_roles_users"."user_id" = 1 AND "spree_roles"."name" = 'admin'
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
 (0.0ms) begin transaction
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 175 LIMIT 1
Spree::State Load (0.0ms) SELECT "spree_states".* FROM "spree_states" WHERE "spree_states"."id" = 55 LIMIT 1
CACHE (0.0ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 175 LIMIT 1
CACHE (0.0ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 175 LIMIT 1
CACHE (0.0ms) SELECT "spree_states".* FROM "spree_states" WHERE "spree_states"."id" = 55 LIMIT 1
CACHE (0.0ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 175 LIMIT 1
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
 (0.0ms) commit transaction
Spree::Activator Load (0.1ms) SELECT "spree_activators".* FROM "spree_activators" WHERE (starts_at IS NULL OR starts_at < '2013-09-03 08:15:23.120717') AND (expires_at IS NULL OR expires_at > '2013-09-03 08:15:23.120856') AND (event_name LIKE 'spree.checkout.update%')
 (0.0ms) begin transaction
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 20
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."active" = 't'
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."id" = 2 LIMIT 1
Spree::StockItem Load (11.5ms) SELECT "spree_stock_items".* FROM "spree_stock_items" WHERE "spree_stock_items"."stock_location_id" = 1 AND "spree_stock_items"."variant_id" = 2 ORDER BY id LIMIT 1
CACHE (0.0ms) SELECT "spree_stock_items".* FROM "spree_stock_items" WHERE "spree_stock_items"."stock_location_id" = 1 AND "spree_stock_items"."variant_id" = 2 ORDER BY id LIMIT 1
CACHE (0.0ms) SELECT "spree_stock_items".* FROM "spree_stock_items" WHERE "spree_stock_items"."stock_location_id" = 1 AND "spree_stock_items"."variant_id" = 2 ORDER BY id LIMIT 1
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."id" = 1 LIMIT 1
Spree::StockItem Load (0.1ms) SELECT "spree_stock_items".* FROM "spree_stock_items" WHERE "spree_stock_items"."stock_location_id" = 1 AND "spree_stock_items"."variant_id" = 1 ORDER BY id LIMIT 1
CACHE (0.0ms) SELECT "spree_stock_items".* FROM "spree_stock_items" WHERE "spree_stock_items"."stock_location_id" = 1 AND "spree_stock_items"."variant_id" = 1 ORDER BY id LIMIT 1
Spree::Product Load (0.1ms) SELECT "spree_products".* FROM "spree_products" WHERE "spree_products"."id" = 2 LIMIT 1
Spree::Product Load (0.1ms) SELECT "spree_products".* FROM "spree_products" WHERE "spree_products"."id" = 1 LIMIT 1
Spree::ShippingCategory Load (0.1ms) SELECT "spree_shipping_categories".* FROM "spree_shipping_categories" WHERE "spree_shipping_categories"."id" = 1 LIMIT 1
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" INNER JOIN "spree_shipping_method_categories" ON "spree_shipping_methods"."id" = "spree_shipping_method_categories"."shipping_method_id" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_method_categories"."shipping_category_id" = 1
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 1 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 3 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 2 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 6 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
Spree::Zone Load (0.1ms) SELECT "spree_zones".* FROM "spree_zones" INNER JOIN "spree_shipping_methods_zones" ON "spree_zones"."id" = "spree_shipping_methods_zones"."zone_id" WHERE "spree_shipping_methods_zones"."shipping_method_id" = 1
Spree::ZoneMember Load (0.1ms) SELECT "spree_zone_members".* FROM "spree_zone_members" WHERE "spree_zone_members"."zone_id" = 2
Spree::Zone Load (0.1ms) SELECT "spree_zones".* FROM "spree_zones" INNER JOIN "spree_shipping_methods_zones" ON "spree_zones"."id" = "spree_shipping_methods_zones"."zone_id" WHERE "spree_shipping_methods_zones"."shipping_method_id" = 4
Spree::ZoneMember Load (0.1ms) SELECT "spree_zone_members".* FROM "spree_zone_members" WHERE "spree_zone_members"."zone_id" = 1
Spree::Zone Load (0.1ms) SELECT "spree_zones".* FROM "spree_zones" INNER JOIN "spree_shipping_methods_zones" ON "spree_zones"."id" = "spree_shipping_methods_zones"."zone_id" WHERE "spree_shipping_methods_zones"."shipping_method_id" = 3
CACHE (0.0ms) SELECT "spree_zone_members".* FROM "spree_zone_members" WHERE "spree_zone_members"."zone_id" = 2
Spree::Zone Load (0.1ms) SELECT "spree_zones".* FROM "spree_zones" INNER JOIN "spree_shipping_methods_zones" ON "spree_zones"."id" = "spree_shipping_methods_zones"."zone_id" WHERE "spree_shipping_methods_zones"."shipping_method_id" = 2
CACHE (0.0ms) SELECT "spree_zone_members".* FROM "spree_zone_members" WHERE "spree_zone_members"."zone_id" = 2
Spree::Zone Load (0.1ms) SELECT "spree_zones".* FROM "spree_zones" INNER JOIN "spree_shipping_methods_zones" ON "spree_zones"."id" = "spree_shipping_methods_zones"."zone_id" WHERE "spree_shipping_methods_zones"."shipping_method_id" = 6
CACHE (0.0ms) SELECT "spree_zone_members".* FROM "spree_zone_members" WHERE "spree_zone_members"."zone_id" = 1
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
CACHE (0.0ms) SELECT "spree_shipping_categories".* FROM "spree_shipping_categories" WHERE "spree_shipping_categories"."id" = 1 LIMIT 1
CACHE (0.0ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" INNER JOIN "spree_shipping_method_categories" ON "spree_shipping_methods"."id" = "spree_shipping_method_categories"."shipping_method_id" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_method_categories"."shipping_category_id" = 1
CACHE (0.0ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 1 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
CACHE (0.0ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
CACHE (0.0ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 3 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
CACHE (0.0ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 2 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
CACHE (0.0ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 6 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
CACHE (0.0ms) SELECT "spree_zones".* FROM "spree_zones" INNER JOIN "spree_shipping_methods_zones" ON "spree_zones"."id" = "spree_shipping_methods_zones"."zone_id" WHERE "spree_shipping_methods_zones"."shipping_method_id" = 1
CACHE (0.0ms) SELECT "spree_zone_members".* FROM "spree_zone_members" WHERE "spree_zone_members"."zone_id" = 2
CACHE (0.0ms) SELECT "spree_zones".* FROM "spree_zones" INNER JOIN "spree_shipping_methods_zones" ON "spree_zones"."id" = "spree_shipping_methods_zones"."zone_id" WHERE "spree_shipping_methods_zones"."shipping_method_id" = 4
CACHE (0.0ms) SELECT "spree_zone_members".* FROM "spree_zone_members" WHERE "spree_zone_members"."zone_id" = 1
CACHE (0.0ms) SELECT "spree_zones".* FROM "spree_zones" INNER JOIN "spree_shipping_methods_zones" ON "spree_zones"."id" = "spree_shipping_methods_zones"."zone_id" WHERE "spree_shipping_methods_zones"."shipping_method_id" = 3
CACHE (0.0ms) SELECT "spree_zone_members".* FROM "spree_zone_members" WHERE "spree_zone_members"."zone_id" = 2
CACHE (0.0ms) SELECT "spree_zones".* FROM "spree_zones" INNER JOIN "spree_shipping_methods_zones" ON "spree_zones"."id" = "spree_shipping_methods_zones"."zone_id" WHERE "spree_shipping_methods_zones"."shipping_method_id" = 2
CACHE (0.0ms) SELECT "spree_zone_members".* FROM "spree_zone_members" WHERE "spree_zone_members"."zone_id" = 2
CACHE (0.0ms) SELECT "spree_zones".* FROM "spree_zones" INNER JOIN "spree_shipping_methods_zones" ON "spree_zones"."id" = "spree_shipping_methods_zones"."zone_id" WHERE "spree_shipping_methods_zones"."shipping_method_id" = 6
CACHE (0.0ms) SELECT "spree_zone_members".* FROM "spree_zone_members" WHERE "spree_zone_members"."zone_id" = 1
CACHE (0.0ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."number" = 'H24446867065' LIMIT 1
 (0.1ms) SELECT COUNT(*) FROM "spree_shipments" WHERE (spree_shipments.number LIKE 'H24446867065%')
SQL (4.2ms) INSERT INTO "spree_shipments" ("address_id", "cost", "created_at", "number", "order_id", "shipped_at", "state", "stock_location_id", "tracking", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["address_id", nil], ["cost", nil], ["created_at", Tue, 03 Sep 2013 08:15:23 UTC +00:00], ["number", "H24446867065"], ["order_id", 20], ["shipped_at", nil], ["state", "pending"], ["stock_location_id", 1], ["tracking", nil], ["updated_at", Tue, 03 Sep 2013 08:15:23 UTC +00:00]]
SQL (0.6ms) INSERT INTO "spree_shipping_rates" ("cost", "created_at", "selected", "shipment_id", "shipping_method_id", "updated_at") VALUES (?, ?, ?, ?, ?, ?) [["cost", #<BigDecimal:7f99740c8770,'0.5E1',9(27)>], ["created_at", Tue, 03 Sep 2013 08:15:23 UTC +00:00], ["selected", true], ["shipment_id", 45], ["shipping_method_id", 4], ["updated_at", Tue, 03 Sep 2013 08:15:23 UTC +00:00]]
SQL (0.1ms) INSERT INTO "spree_shipping_rates" ("cost", "created_at", "selected", "shipment_id", "shipping_method_id", "updated_at") VALUES (?, ?, ?, ?, ?, ?) [["cost", #<BigDecimal:7f99740dad30,'0.1E2',9(27)>], ["created_at", Tue, 03 Sep 2013 08:15:23 UTC +00:00], ["selected", false], ["shipment_id", 45], ["shipping_method_id", 6], ["updated_at", Tue, 03 Sep 2013 08:15:23 UTC +00:00]]
SQL (0.2ms) INSERT INTO "spree_inventory_units" ("created_at", "order_id", "pending", "return_authorization_id", "shipment_id", "state", "updated_at", "variant_id") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Tue, 03 Sep 2013 08:15:23 UTC +00:00], ["order_id", 20], ["pending", true], ["return_authorization_id", nil], ["shipment_id", 45], ["state", "on_hand"], ["updated_at", Tue, 03 Sep 2013 08:15:23 UTC +00:00], ["variant_id", 2]]
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."source_id" = 45 AND "spree_adjustments"."source_type" = 'Spree::Shipment' LIMIT 1
Spree::ShippingRate Load (0.1ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 45 AND "spree_shipping_rates"."selected" = 't' LIMIT 1
CACHE (0.0ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 45 AND "spree_shipping_rates"."selected" = 't' LIMIT 1
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
CACHE (0.0ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 45 AND "spree_shipping_rates"."selected" = 't' LIMIT 1
CACHE (0.0ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 45
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (2)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.3ms) INSERT INTO "spree_adjustments" ("adjustable_id", "adjustable_type", "amount", "created_at", "eligible", "label", "mandatory", "originator_id", "originator_type", "source_id", "source_type", "state", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["adjustable_id", 20], ["adjustable_type", "Spree::Order"], ["amount", #<BigDecimal:7f9974335cb0,'0.5E1',9(27)>], ["created_at", Tue, 03 Sep 2013 08:15:23 UTC +00:00], ["eligible", true], ["label", "Envío"], ["mandatory", true], ["originator_id", 4], ["originator_type", "Spree::ShippingMethod"], ["source_id", 45], ["source_type", "Spree::Shipment"], ["state", "open"], ["updated_at", Tue, 03 Sep 2013 08:15:23 UTC +00:00]]
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20 ORDER BY created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY created_at ASC
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 50]]
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 45 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 45
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (2)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.2ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 50
SQL (0.0ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 50
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."originator_type" = 'Spree::PromotionAction' AND "spree_adjustments"."eligible" = 't' ORDER BY amount ASC, created_at DESC LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
SQL (0.1ms) UPDATE "spree_orders" SET "payment_state" = 'balance_due', "shipment_state" = NULL, "item_total" = 33.0, "adjustment_total" = 11.93, "payment_total" = 0.0, "total" = 44.93 WHERE "spree_orders"."id" = 20
Spree::Shipment Load (0.0ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = ? LIMIT 1 [["id", 45]]
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20 ORDER BY created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY created_at ASC
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 50]]
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 45 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 45
Spree::Variant Load (0.2ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (2)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 50
SQL (0.1ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 50
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."originator_type" = 'Spree::PromotionAction' AND "spree_adjustments"."eligible" = 't' ORDER BY amount ASC, created_at DESC LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
SQL (0.1ms) UPDATE "spree_orders" SET "payment_state" = 'balance_due', "shipment_state" = NULL, "item_total" = 33.0, "adjustment_total" = 11.93, "payment_total" = 0.0, "total" = 44.93 WHERE "spree_orders"."id" = 20
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."number" = 'H84061225451' LIMIT 1
 (0.1ms) SELECT COUNT(*) FROM "spree_shipments" WHERE (spree_shipments.number LIKE 'H84061225451%')
SQL (0.2ms) INSERT INTO "spree_shipments" ("address_id", "cost", "created_at", "number", "order_id", "shipped_at", "state", "stock_location_id", "tracking", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["address_id", nil], ["cost", nil], ["created_at", Tue, 03 Sep 2013 08:15:23 UTC +00:00], ["number", "H84061225451"], ["order_id", 20], ["shipped_at", nil], ["state", "pending"], ["stock_location_id", 1], ["tracking", nil], ["updated_at", Tue, 03 Sep 2013 08:15:23 UTC +00:00]]
SQL (0.1ms) INSERT INTO "spree_shipping_rates" ("cost", "created_at", "selected", "shipment_id", "shipping_method_id", "updated_at") VALUES (?, ?, ?, ?, ?, ?) [["cost", #<BigDecimal:7f9974238ab0,'0.5E1',9(27)>], ["created_at", Tue, 03 Sep 2013 08:15:23 UTC +00:00], ["selected", true], ["shipment_id", 46], ["shipping_method_id", 4], ["updated_at", Tue, 03 Sep 2013 08:15:23 UTC +00:00]]
SQL (0.1ms) INSERT INTO "spree_shipping_rates" ("cost", "created_at", "selected", "shipment_id", "shipping_method_id", "updated_at") VALUES (?, ?, ?, ?, ?, ?) [["cost", #<BigDecimal:7f997424b7f0,'0.1E2',9(27)>], ["created_at", Tue, 03 Sep 2013 08:15:23 UTC +00:00], ["selected", false], ["shipment_id", 46], ["shipping_method_id", 6], ["updated_at", Tue, 03 Sep 2013 08:15:23 UTC +00:00]]
SQL (0.1ms) INSERT INTO "spree_inventory_units" ("created_at", "order_id", "pending", "return_authorization_id", "shipment_id", "state", "updated_at", "variant_id") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Tue, 03 Sep 2013 08:15:23 UTC +00:00], ["order_id", 20], ["pending", true], ["return_authorization_id", nil], ["shipment_id", 46], ["state", "backordered"], ["updated_at", Tue, 03 Sep 2013 08:15:23 UTC +00:00], ["variant_id", 1]]
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."source_id" = 46 AND "spree_adjustments"."source_type" = 'Spree::Shipment' LIMIT 1
Spree::ShippingRate Load (0.1ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 46 AND "spree_shipping_rates"."selected" = 't' LIMIT 1
CACHE (0.0ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 46 AND "spree_shipping_rates"."selected" = 't' LIMIT 1
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
CACHE (0.0ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 46 AND "spree_shipping_rates"."selected" = 't' LIMIT 1
CACHE (0.0ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 46
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (1)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.2ms) INSERT INTO "spree_adjustments" ("adjustable_id", "adjustable_type", "amount", "created_at", "eligible", "label", "mandatory", "originator_id", "originator_type", "source_id", "source_type", "state", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["adjustable_id", 20], ["adjustable_type", "Spree::Order"], ["amount", #<BigDecimal:7f996e525eb8,'0.5E1',9(27)>], ["created_at", Tue, 03 Sep 2013 08:15:23 UTC +00:00], ["eligible", true], ["label", "Envío"], ["mandatory", true], ["originator_id", 4], ["originator_type", "Spree::ShippingMethod"], ["source_id", 46], ["source_type", "Spree::Shipment"], ["state", "open"], ["updated_at", Tue, 03 Sep 2013 08:15:23 UTC +00:00]]
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20 ORDER BY created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY created_at ASC
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 50]]
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 45 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 45
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (2)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 50
SQL (0.0ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 50
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 51]]
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 46 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 46
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (1)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.0ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 51
SQL (0.0ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 51
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."originator_type" = 'Spree::PromotionAction' AND "spree_adjustments"."eligible" = 't' ORDER BY amount ASC, created_at DESC LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
SQL (0.1ms) UPDATE "spree_orders" SET "payment_state" = 'balance_due', "shipment_state" = NULL, "item_total" = 33.0, "adjustment_total" = 16.93, "payment_total" = 0.0, "total" = 49.93 WHERE "spree_orders"."id" = 20
Spree::Shipment Load (0.0ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = ? LIMIT 1 [["id", 46]]
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20 ORDER BY created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY created_at ASC
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 50]]
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 45 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 45
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (2)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 50
SQL (0.0ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 50
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 51]]
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 46 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 46
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (1)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.0ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 51
SQL (0.0ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 51
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."originator_type" = 'Spree::PromotionAction' AND "spree_adjustments"."eligible" = 't' ORDER BY amount ASC, created_at DESC LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
SQL (0.1ms) UPDATE "spree_orders" SET "payment_state" = 'balance_due', "shipment_state" = NULL, "item_total" = 33.0, "adjustment_total" = 16.93, "payment_total" = 0.0, "total" = 49.93 WHERE "spree_orders"."id" = 20
Spree::ShippingRate Load (0.1ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 45
Spree::ShippingRate Load (0.1ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 46
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
 (0.1ms) UPDATE "spree_orders" SET "state" = 'delivery', "adjustment_total" = 16.93, "total" = 49.93, "updated_at" = '2013-09-03 08:15:23.404608' WHERE "spree_orders"."id" = 20
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Adjustment Load (7.3ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."originator_type" = 'Spree::TaxRate' AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY created_at ASC
SQL (0.0ms) DELETE FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? [["id", 49]]
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20 ORDER BY created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY created_at ASC
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 50]]
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 45 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 45
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (2)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 50
SQL (0.0ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 50
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 51]]
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 46 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 46
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (1)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 51
SQL (0.0ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 51
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."originator_type" = 'Spree::PromotionAction' AND "spree_adjustments"."eligible" = 't' ORDER BY amount ASC, created_at DESC LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
SQL (0.1ms) UPDATE "spree_orders" SET "payment_state" = 'balance_due', "shipment_state" = NULL, "item_total" = 33.0, "adjustment_total" = 10.0, "payment_total" = 0.0, "total" = 43.0 WHERE "spree_orders"."id" = 20
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" INNER JOIN "spree_line_items" ON "spree_adjustments"."adjustable_id" = "spree_line_items"."id" AND "spree_adjustments"."adjustable_type" = 'Spree::LineItem' WHERE "spree_line_items"."order_id" = 20 AND "spree_adjustments"."originator_type" = 'Spree::TaxRate'
Spree::Zone Load (0.1ms) SELECT "spree_zones".* FROM "spree_zones" ORDER BY zone_members_count, created_at
Spree::ZoneMember Load (8.9ms) SELECT "spree_zone_members".* FROM "spree_zone_members" WHERE "spree_zone_members"."zone_id" IN (2, 1)
Spree::TaxRate Load (8.8ms) SELECT "spree_tax_rates".* FROM "spree_tax_rates" WHERE "spree_tax_rates"."deleted_at" IS NULL
Spree::Zone Load (0.1ms) SELECT "spree_zones".* FROM "spree_zones" WHERE "spree_zones"."id" = 2 LIMIT 1
CACHE (0.0ms) SELECT "spree_zones".* FROM "spree_zones" ORDER BY zone_members_count, created_at
CACHE (0.0ms) SELECT "spree_zone_members".* FROM "spree_zone_members" WHERE "spree_zone_members"."zone_id" IN (2, 1)
CACHE (0.0ms) SELECT "spree_zones".* FROM "spree_zones" ORDER BY zone_members_count, created_at
CACHE (0.0ms) SELECT "spree_zone_members".* FROM "spree_zone_members" WHERE "spree_zone_members"."zone_id" IN (2, 1)
Spree::ZoneMember Load (0.1ms) SELECT "spree_zone_members".* FROM "spree_zone_members" WHERE "spree_zone_members"."zone_id" = 2
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 72 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 10 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 76 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 94 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 155 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 13 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 164 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 217 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 167 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 20 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 111 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 175 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 24 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 29 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 98 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 180 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 182 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 44 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 206 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 46 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 211 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 135 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 56 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 207 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 210 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 49 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 204 LIMIT 1
Spree::Zone Load (0.1ms) SELECT "spree_zones".* FROM "spree_zones" WHERE "spree_zones"."id" = 1 LIMIT 1
CACHE (0.0ms) SELECT "spree_zones".* FROM "spree_zones" ORDER BY zone_members_count, created_at
CACHE (0.0ms) SELECT "spree_zone_members".* FROM "spree_zone_members" WHERE "spree_zone_members"."zone_id" IN (2, 1)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 2 AND "spree_calculators"."calculable_type" = 'Spree::TaxRate' LIMIT 1
Spree::TaxCategory Load (0.1ms) SELECT "spree_tax_categories".* FROM "spree_tax_categories" WHERE "spree_tax_categories"."deleted_at" IS NULL AND "spree_tax_categories"."id" = 1 LIMIT 1
Spree::TaxRate Load (0.1ms) SELECT "spree_tax_rates".* FROM "spree_tax_rates" WHERE "spree_tax_rates"."deleted_at" IS NULL AND "spree_tax_rates"."id" = 2 LIMIT 1
CACHE (0.0ms) SELECT "spree_tax_categories".* FROM "spree_tax_categories" WHERE "spree_tax_categories"."deleted_at" IS NULL AND "spree_tax_categories"."id" = 1 LIMIT 1
CACHE (0.0ms) SELECT "spree_tax_categories".* FROM "spree_tax_categories" WHERE "spree_tax_categories"."deleted_at" IS NULL AND "spree_tax_categories"."id" = 1 LIMIT 1
SQL (0.2ms) INSERT INTO "spree_adjustments" ("adjustable_id", "adjustable_type", "amount", "created_at", "eligible", "label", "mandatory", "originator_id", "originator_type", "source_id", "source_type", "state", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["adjustable_id", 20], ["adjustable_type", "Spree::Order"], ["amount", #<BigDecimal:7f9972f255b8,'0.693E1',18(36)>], ["created_at", Tue, 03 Sep 2013 08:15:23 UTC +00:00], ["eligible", true], ["label", "IVA 21.0%"], ["mandatory", false], ["originator_id", 2], ["originator_type", "Spree::TaxRate"], ["source_id", 20], ["source_type", "Spree::Order"], ["state", "closed"], ["updated_at", Tue, 03 Sep 2013 08:15:23 UTC +00:00]]
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::Payment Load (0.2ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.2ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20 ORDER BY created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY created_at ASC
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 50]]
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 45 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 45
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (2)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 50
SQL (0.0ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 50
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 51]]
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 46 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 46
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (1)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 51
SQL (0.0ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 51
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."originator_type" = 'Spree::PromotionAction' AND "spree_adjustments"."eligible" = 't' ORDER BY amount ASC, created_at DESC LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
SQL (0.1ms) UPDATE "spree_orders" SET "payment_state" = 'balance_due', "shipment_state" = NULL, "item_total" = 33.0, "adjustment_total" = 16.93, "payment_total" = 0.0, "total" = 49.93 WHERE "spree_orders"."id" = 20
 (2.7ms) commit transaction
Redirected to http://localhost:3000/checkout/delivery
Completed 302 Found in 611ms (ActiveRecord: 67.3ms)
Started GET "/checkout/delivery" for 127.0.0.1 at 2013-09-03 10:15:23 +0200
Spree::Page Exists (0.2ms) SELECT 1 AS one FROM "spree_pages" WHERE "spree_pages"."visible" = 't' AND (("spree_pages"."slug" = 'checkout/delivery' OR "spree_pages"."slug" = '/checkout/delivery')) ORDER BY position ASC LIMIT 1
CACHE (0.0ms) SELECT 1 AS one FROM "spree_pages" WHERE "spree_pages"."visible" = 't' AND (("spree_pages"."slug" = 'checkout/delivery' OR "spree_pages"."slug" = '/checkout/delivery')) ORDER BY position ASC LIMIT 1
Processing by Spree::CheckoutController#edit as HTML
Parameters: {"state"=>"delivery"}
Spree::User Load (0.2ms) SELECT "spree_users".* FROM "spree_users" WHERE "spree_users"."id" = 1 LIMIT 1
Spree::Order Load (0.3ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."user_id" = 1 AND "spree_orders"."completed_at" IS NULL AND "spree_orders"."created_by_id" = 1 ORDER BY created_at DESC LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 AND "spree_orders"."currency" = 'EUR' LIMIT 1
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."adjustable_id" IN (20) ORDER BY created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20 ORDER BY created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20
Spree::PaymentMethod Load (0.1ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
 (0.1ms) SELECT COUNT(*) FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20
Spree::StockItem Load (0.1ms) SELECT "spree_stock_items".* FROM "spree_stock_items" INNER JOIN "spree_stock_locations" ON "spree_stock_locations"."id" = "spree_stock_items"."stock_location_id" WHERE "spree_stock_items"."variant_id" = 2 AND "spree_stock_locations"."active" = 't'
Spree::StockItem Load (0.1ms) SELECT "spree_stock_items".* FROM "spree_stock_items" INNER JOIN "spree_stock_locations" ON "spree_stock_locations"."id" = "spree_stock_items"."stock_location_id" WHERE "spree_stock_items"."variant_id" = 1 AND "spree_stock_locations"."active" = 't'
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
CACHE (0.0ms) SELECT "spree_users".* FROM "spree_users" WHERE "spree_users"."id" = 1 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 20
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 45
Spree::Variant Load (0.2ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (2)
CACHE (0.0ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 46
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (1)
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."id" = 2 LIMIT 1
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."id" = 1 LIMIT 1
 (0.1ms) SELECT COUNT(*) FROM "spree_roles" INNER JOIN "spree_roles_users" ON "spree_roles"."id" = "spree_roles_users"."role_id" WHERE "spree_roles_users"."user_id" = 1 AND "spree_roles"."name" = 'admin'
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/core/app/views/spree/shared/_error_messages.html.erb (0.1ms)
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
CACHE (0.0ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 45
CACHE (0.0ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (2)
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 2 AND "spree_assets"."viewable_type" = 'Spree::Variant'
Spree::Image Load (0.2ms) SELECT "spree_assets".* FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 2 AND "spree_assets"."viewable_type" = 'Spree::Variant' ORDER BY position LIMIT 1
Spree::Product Load (0.1ms) SELECT "spree_products".* FROM "spree_products" WHERE "spree_products"."id" = 2 LIMIT 1
Spree::Product::Translation Load (0.1ms) SELECT "spree_product_translations".* FROM "spree_product_translations" WHERE "spree_product_translations"."spree_product_id" = 2
Spree::Price Load (0.1ms) SELECT "spree_prices".* FROM "spree_prices" WHERE "spree_prices"."variant_id" = 2
SQL (0.2ms) SELECT "spree_shipping_rates"."id" AS t0_r0, "spree_shipping_rates"."shipment_id" AS t0_r1, "spree_shipping_rates"."shipping_method_id" AS t0_r2, "spree_shipping_rates"."selected" AS t0_r3, "spree_shipping_rates"."cost" AS t0_r4, "spree_shipping_rates"."created_at" AS t0_r5, "spree_shipping_rates"."updated_at" AS t0_r6, "spree_shipping_methods"."id" AS t1_r0, "spree_shipping_methods"."name" AS t1_r1, "spree_shipping_methods"."display_on" AS t1_r2, "spree_shipping_methods"."deleted_at" AS t1_r3, "spree_shipping_methods"."created_at" AS t1_r4, "spree_shipping_methods"."updated_at" AS t1_r5, "spree_shipping_methods"."tracking_url" AS t1_r6, "spree_shipping_methods"."admin_name" AS t1_r7 FROM "spree_shipping_rates" LEFT OUTER JOIN "spree_shipping_methods" ON "spree_shipping_methods"."id" = "spree_shipping_rates"."shipping_method_id" WHERE "spree_shipping_rates"."shipment_id" = 45 AND (spree_shipping_methods.display_on != 'back_end' OR spree_shipping_methods.display_on IS NULL)
Spree::ShippingRate Load (0.1ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 45 AND "spree_shipping_rates"."selected" = 't' LIMIT 1
Spree::TaxCategory Load (0.1ms) SELECT "spree_tax_categories".* FROM "spree_tax_categories" WHERE "spree_tax_categories"."deleted_at" IS NULL AND "spree_tax_categories"."is_default" = 't' LIMIT 1
Spree::TaxRate Load (0.2ms) SELECT "spree_tax_rates".* FROM "spree_tax_rates" WHERE "spree_tax_rates"."deleted_at" IS NULL AND "spree_tax_rates"."tax_category_id" IN (1)
Spree::Zone Load (0.1ms) SELECT "spree_zones".* FROM "spree_zones" WHERE "spree_zones"."id" = 2 LIMIT 1
Spree::ZoneMember Load (0.1ms) SELECT "spree_zone_members".* FROM "spree_zone_members" WHERE "spree_zone_members"."zone_id" = 2
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 45 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
CACHE (0.0ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 45 AND "spree_shipping_rates"."selected" = 't' LIMIT 1
CACHE (0.0ms) SELECT "spree_tax_categories".* FROM "spree_tax_categories" WHERE "spree_tax_categories"."deleted_at" IS NULL AND "spree_tax_categories"."is_default" = 't' LIMIT 1
CACHE (0.0ms) SELECT "spree_tax_rates".* FROM "spree_tax_rates" WHERE "spree_tax_rates"."deleted_at" IS NULL AND "spree_tax_rates"."tax_category_id" IN (1)
CACHE (0.0ms) SELECT "spree_zones".* FROM "spree_zones" WHERE "spree_zones"."id" = 2 LIMIT 1
CACHE (0.0ms) SELECT "spree_zone_members".* FROM "spree_zone_members" WHERE "spree_zone_members"."zone_id" = 2
CACHE (0.0ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 45 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
CACHE (0.0ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 46
CACHE (0.0ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (1)
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 1 AND "spree_assets"."viewable_type" = 'Spree::Variant'
Spree::Image Load (0.2ms) SELECT "spree_assets".* FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 1 AND "spree_assets"."viewable_type" = 'Spree::Variant' ORDER BY position LIMIT 1
Spree::Product Load (0.1ms) SELECT "spree_products".* FROM "spree_products" WHERE "spree_products"."id" = 1 LIMIT 1
Spree::Product::Translation Load (0.1ms) SELECT "spree_product_translations".* FROM "spree_product_translations" WHERE "spree_product_translations"."spree_product_id" = 1
Spree::Price Load (0.1ms) SELECT "spree_prices".* FROM "spree_prices" WHERE "spree_prices"."variant_id" = 1
SQL (0.2ms) SELECT "spree_shipping_rates"."id" AS t0_r0, "spree_shipping_rates"."shipment_id" AS t0_r1, "spree_shipping_rates"."shipping_method_id" AS t0_r2, "spree_shipping_rates"."selected" AS t0_r3, "spree_shipping_rates"."cost" AS t0_r4, "spree_shipping_rates"."created_at" AS t0_r5, "spree_shipping_rates"."updated_at" AS t0_r6, "spree_shipping_methods"."id" AS t1_r0, "spree_shipping_methods"."name" AS t1_r1, "spree_shipping_methods"."display_on" AS t1_r2, "spree_shipping_methods"."deleted_at" AS t1_r3, "spree_shipping_methods"."created_at" AS t1_r4, "spree_shipping_methods"."updated_at" AS t1_r5, "spree_shipping_methods"."tracking_url" AS t1_r6, "spree_shipping_methods"."admin_name" AS t1_r7 FROM "spree_shipping_rates" LEFT OUTER JOIN "spree_shipping_methods" ON "spree_shipping_methods"."id" = "spree_shipping_rates"."shipping_method_id" WHERE "spree_shipping_rates"."shipment_id" = 46 AND (spree_shipping_methods.display_on != 'back_end' OR spree_shipping_methods.display_on IS NULL)
Spree::ShippingRate Load (0.1ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 46 AND "spree_shipping_rates"."selected" = 't' LIMIT 1
CACHE (0.0ms) SELECT "spree_tax_categories".* FROM "spree_tax_categories" WHERE "spree_tax_categories"."deleted_at" IS NULL AND "spree_tax_categories"."is_default" = 't' LIMIT 1
CACHE (0.0ms) SELECT "spree_tax_rates".* FROM "spree_tax_rates" WHERE "spree_tax_rates"."deleted_at" IS NULL AND "spree_tax_rates"."tax_category_id" IN (1)
CACHE (0.0ms) SELECT "spree_zones".* FROM "spree_zones" WHERE "spree_zones"."id" = 2 LIMIT 1
CACHE (0.0ms) SELECT "spree_zone_members".* FROM "spree_zone_members" WHERE "spree_zone_members"."zone_id" = 2
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 46 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
CACHE (0.0ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 46 AND "spree_shipping_rates"."selected" = 't' LIMIT 1
CACHE (0.0ms) SELECT "spree_tax_categories".* FROM "spree_tax_categories" WHERE "spree_tax_categories"."deleted_at" IS NULL AND "spree_tax_categories"."is_default" = 't' LIMIT 1
CACHE (0.0ms) SELECT "spree_tax_rates".* FROM "spree_tax_rates" WHERE "spree_tax_rates"."deleted_at" IS NULL AND "spree_tax_rates"."tax_category_id" IN (1)
CACHE (0.0ms) SELECT "spree_zones".* FROM "spree_zones" WHERE "spree_zones"."id" = 2 LIMIT 1
CACHE (0.0ms) SELECT "spree_zone_members".* FROM "spree_zone_members" WHERE "spree_zone_members"."zone_id" = 2
CACHE (0.0ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 46 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/checkout/_delivery.html.erb (77.5ms)
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" INNER JOIN "spree_line_items" ON "spree_adjustments"."adjustable_id" = "spree_line_items"."id" AND "spree_adjustments"."adjustable_type" = 'Spree::LineItem' WHERE "spree_line_items"."order_id" = 20 AND "spree_adjustments"."eligible" = 't'
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/checkout/_summary.html.erb (8.2ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/checkout/edit.html.erb within spree/layouts/spree_application (109.7ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/core/app/views/spree/shared/_routes.html.erb (0.4ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_head.html.erb (13.0ms)
Deface: 1 overrides found for 'spree/shared/_nav_bar'
Deface: 'auth_shared_login_bar' matched 1 times with 'li#search-bar'
Spree::Taxon Load (0.3ms) SELECT "spree_taxons".* FROM "spree_taxons" WHERE "spree_taxons"."parent_id" IS NULL ORDER BY "spree_taxons"."lft"
Spree::Taxon::Translation Load (0.2ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 1
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 2
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 3
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 9
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_search.html.erb (22.4ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_nav_bar.html.erb (28.2ms)
Deface: 2 overrides found for 'spree/shared/_main_nav_bar'
Deface: 'locale_selector' matched 1 times with '#main-nav-bar'
Deface: [WARNING] No :original defined for 'locale_selector', you should change its definition to include:
:original => 'db2835f20e2ab66e854a74f6ae2a05cfb86f20fd'
Deface: 'pages_in_header' matched 1 times with '#main-nav-bar'
Deface: [WARNING] No :original defined for 'pages_in_header', you should change its definition to include:
:original => '69e748b409fc9557847596ce97b153d0be34385d'
Spree::Page Load (0.3ms) SELECT "spree_pages".* FROM "spree_pages" WHERE "spree_pages"."show_in_header" = 't' AND "spree_pages"."visible" = 't' ORDER BY position ASC
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree_static_content-a9d17d7fe22b/app/views/spree/static_content/_static_content_list.html.erb (0.2ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_main_nav_bar.html.erb (6.7ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_header.html.erb (53.3ms)
Deface: 1 overrides found for 'spree/shared/_footer'
Deface: 'pages_in_footer' matched 1 times with '#footer-right'
Deface: [WARNING] No :original defined for 'pages_in_footer', you should change its definition to include:
:original => 'd9b8e77ea5ae5135f238b2c9fa4caa345b1c8412'
Spree::Page Load (0.3ms) SELECT "spree_pages".* FROM "spree_pages" WHERE "spree_pages"."show_in_footer" = 't' AND "spree_pages"."visible" = 't' ORDER BY position ASC
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree_static_content-a9d17d7fe22b/app/views/spree/static_content/_static_content_list.html.erb (0.4ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-yoexportoaceite-theme-0c7e2325fed5/app/views/spree/shared/_footer.html.erb (4.0ms)
Spree::Tracker Load (0.1ms) SELECT "spree_trackers".* FROM "spree_trackers" WHERE "spree_trackers"."active" = 't' AND "spree_trackers"."environment" = 'development' LIMIT 1
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_google_analytics.html.erb (0.6ms)
Completed 200 OK in 245ms (Views: 208.1ms | ActiveRecord: 7.2ms)
Started GET "/assets/normalize.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:24 +0200
Served asset /normalize.css - 304 Not Modified (0ms)
Started GET "/assets/skeleton.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:24 +0200
Served asset /skeleton.css - 304 Not Modified (0ms)
Started GET "/assets/store/screen.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:24 +0200
Served asset /store/screen.css - 304 Not Modified (0ms)
Started GET "/assets/store/spree_paypal_express.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:24 +0200
Served asset /store/spree_paypal_express.css - 304 Not Modified (0ms)
Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:24 +0200
Served asset /jquery.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_yoexporto.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:24 +0200
Served asset /store/spree_yoexporto.css - 304 Not Modified (0ms)
Started GET "/assets/store/spree_frontend.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:24 +0200
Served asset /store/spree_frontend.css - 304 Not Modified (0ms)
Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:24 +0200
Served asset /jquery_ujs.js - 304 Not Modified (0ms)
Started GET "/assets/jsuri.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:24 +0200
Served asset /jsuri.js - 304 Not Modified (0ms)
Started GET "/assets/jquery.validate/jquery.validate.min.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:24 +0200
Served asset /jquery.validate/jquery.validate.min.js - 304 Not Modified (0ms)
Started GET "/assets/spree.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:24 +0200
Served asset /spree.js - 304 Not Modified (0ms)
Started GET "/assets/store/checkout.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:24 +0200
Served asset /store/checkout.js - 304 Not Modified (0ms)
Started GET "/assets/store/product.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:24 +0200
Served asset /store/product.js - 304 Not Modified (0ms)
Started GET "/assets/store/cart.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:24 +0200
Served asset /store/cart.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_frontend.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:24 +0200
Served asset /store/spree_frontend.js - 304 Not Modified (0ms)
Started GET "/assets/store/locale.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:24 +0200
Served asset /store/locale.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_yoexporto.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:24 +0200
Served asset /store/spree_yoexporto.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_i18n.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:24 +0200
Served asset /store/spree_i18n.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_paypal_express.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:24 +0200
Served asset /store/spree_paypal_express.js - 304 Not Modified (0ms)
Started PUT "/checkout/update/delivery" for 127.0.0.1 at 2013-09-03 10:15:42 +0200
Processing by Spree::CheckoutController#update as HTML
Parameters: {"utf8"=>"✓", "authenticity_token"=>"Z4VAnyBl3LnxFHfiUVATVLOTAeezNyNAZ/HitL3KxWo=", "order"=>{"shipments_attributes"=>{"0"=>{"selected_shipping_rate_id"=>"95", "id"=>"45"}, "1"=>{"selected_shipping_rate_id"=>"97", "id"=>"46"}}}, "commit"=>"Guardar y continuar", "state"=>"delivery"}
Spree::User Load (0.2ms) SELECT "spree_users".* FROM "spree_users" WHERE "spree_users"."id" = 1 LIMIT 1
Spree::Order Load (0.2ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."user_id" = 1 AND "spree_orders"."completed_at" IS NULL AND "spree_orders"."created_by_id" = 1 ORDER BY created_at DESC LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 AND "spree_orders"."currency" = 'EUR' LIMIT 1
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."adjustable_id" IN (20) ORDER BY created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20 ORDER BY created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20
Spree::PaymentMethod Load (0.1ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
 (0.1ms) SELECT COUNT(*) FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20
Spree::StockItem Load (0.1ms) SELECT "spree_stock_items".* FROM "spree_stock_items" INNER JOIN "spree_stock_locations" ON "spree_stock_locations"."id" = "spree_stock_items"."stock_location_id" WHERE "spree_stock_items"."variant_id" = 2 AND "spree_stock_locations"."active" = 't'
Spree::StockItem Load (0.1ms) SELECT "spree_stock_items".* FROM "spree_stock_items" INNER JOIN "spree_stock_locations" ON "spree_stock_locations"."id" = "spree_stock_items"."stock_location_id" WHERE "spree_stock_items"."variant_id" = 1 AND "spree_stock_locations"."active" = 't'
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
CACHE (0.0ms) SELECT "spree_users".* FROM "spree_users" WHERE "spree_users"."id" = 1 LIMIT 1
 (0.1ms) SELECT COUNT(*) FROM "spree_roles" INNER JOIN "spree_roles_users" ON "spree_roles"."id" = "spree_roles_users"."role_id" WHERE "spree_roles_users"."user_id" = 1 AND "spree_roles"."name" = 'admin'
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
 (0.0ms) begin transaction
Spree::Shipment Load (0.2ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 20 AND "spree_shipments"."id" IN (45, 46)
SQL (0.2ms) UPDATE "spree_shipping_rates" SET "selected" = 'f' WHERE "spree_shipping_rates"."shipment_id" = 45
Spree::ShippingRate Load (0.1ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 45 AND "spree_shipping_rates"."id" = ? LIMIT 1 [["id", "95"]]
 (0.2ms) UPDATE "spree_shipping_rates" SET "selected" = 't', "updated_at" = '2013-09-03 08:15:42.264467' WHERE "spree_shipping_rates"."id" = 95
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."source_id" = 45 AND "spree_adjustments"."source_type" = 'Spree::Shipment' LIMIT 1
Spree::ShippingRate Load (0.1ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 45 AND "spree_shipping_rates"."selected" = 't' LIMIT 1
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
CACHE (0.0ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 45 AND "spree_shipping_rates"."selected" = 't' LIMIT 1
CACHE (0.0ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
CACHE (0.0ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 45 AND "spree_shipping_rates"."selected" = 't' LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.2ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20 ORDER BY created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY created_at ASC
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 50]]
CACHE (0.0ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 45 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 45
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (2)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 50
SQL (0.0ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 50
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 51]]
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 46 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 46
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (1)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 51
SQL (0.0ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 51
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."originator_type" = 'Spree::PromotionAction' AND "spree_adjustments"."eligible" = 't' ORDER BY amount ASC, created_at DESC LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
SQL (0.1ms) UPDATE "spree_orders" SET "payment_state" = 'balance_due', "shipment_state" = NULL, "item_total" = 33.0, "adjustment_total" = 16.93, "payment_total" = 0.0, "total" = 49.93 WHERE "spree_orders"."id" = 20
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 50]]
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20 ORDER BY created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY created_at ASC
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 50]]
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 45 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 45
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (2)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 50
SQL (0.0ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 50
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 51]]
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 46 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 46
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (1)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 51
SQL (0.0ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 51
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."originator_type" = 'Spree::PromotionAction' AND "spree_adjustments"."eligible" = 't' ORDER BY amount ASC, created_at DESC LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
SQL (0.1ms) UPDATE "spree_orders" SET "payment_state" = 'balance_due', "shipment_state" = NULL, "item_total" = 33.0, "adjustment_total" = 16.93, "payment_total" = 0.0, "total" = 49.93 WHERE "spree_orders"."id" = 20
SQL (0.1ms) UPDATE "spree_shipping_rates" SET "selected" = 'f' WHERE "spree_shipping_rates"."shipment_id" = 46
Spree::ShippingRate Load (0.1ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 46 AND "spree_shipping_rates"."id" = ? LIMIT 1 [["id", "97"]]
 (0.1ms) UPDATE "spree_shipping_rates" SET "selected" = 't', "updated_at" = '2013-09-03 08:15:42.330718' WHERE "spree_shipping_rates"."id" = 97
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."source_id" = 46 AND "spree_adjustments"."source_type" = 'Spree::Shipment' LIMIT 1
Spree::ShippingRate Load (0.1ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 46 AND "spree_shipping_rates"."selected" = 't' LIMIT 1
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
CACHE (0.0ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 46 AND "spree_shipping_rates"."selected" = 't' LIMIT 1
CACHE (0.0ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
CACHE (0.0ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 46 AND "spree_shipping_rates"."selected" = 't' LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20 ORDER BY created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY created_at ASC
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 50]]
CACHE (0.0ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 45 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 45
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (2)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 50
SQL (0.0ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 50
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 51]]
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 46 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 46
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (1)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 51
SQL (0.0ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 51
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."originator_type" = 'Spree::PromotionAction' AND "spree_adjustments"."eligible" = 't' ORDER BY amount ASC, created_at DESC LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
SQL (0.1ms) UPDATE "spree_orders" SET "payment_state" = 'balance_due', "shipment_state" = NULL, "item_total" = 33.0, "adjustment_total" = 16.93, "payment_total" = 0.0, "total" = 49.93 WHERE "spree_orders"."id" = 20
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 51]]
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20 ORDER BY created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY created_at ASC
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 50]]
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 45 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 45
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (2)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 50
SQL (0.1ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 50
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 51]]
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 46 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 46
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (1)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.0ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 51
SQL (0.0ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 51
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."originator_type" = 'Spree::PromotionAction' AND "spree_adjustments"."eligible" = 't' ORDER BY amount ASC, created_at DESC LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
SQL (0.1ms) UPDATE "spree_orders" SET "payment_state" = 'balance_due', "shipment_state" = NULL, "item_total" = 33.0, "adjustment_total" = 16.93, "payment_total" = 0.0, "total" = 49.93 WHERE "spree_orders"."id" = 20
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
 (1.6ms) commit transaction
Spree::Activator Load (0.3ms) SELECT "spree_activators".* FROM "spree_activators" WHERE (starts_at IS NULL OR starts_at < '2013-09-03 08:15:42.395118') AND (expires_at IS NULL OR expires_at > '2013-09-03 08:15:42.395306') AND (event_name LIKE 'spree.checkout.update%')
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
 (0.0ms) begin transaction
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
 (0.2ms) UPDATE "spree_orders" SET "state" = 'payment', "updated_at" = '2013-09-03 08:15:42.420812' WHERE "spree_orders"."id" = 20
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
 (11.8ms) commit transaction
Redirected to http://localhost:3000/checkout/payment
Completed 302 Found in 204ms (ActiveRecord: 27.5ms)
Started GET "/checkout/payment" for 127.0.0.1 at 2013-09-03 10:15:42 +0200
Spree::Page Exists (0.3ms) SELECT 1 AS one FROM "spree_pages" WHERE "spree_pages"."visible" = 't' AND (("spree_pages"."slug" = 'checkout/payment' OR "spree_pages"."slug" = '/checkout/payment')) ORDER BY position ASC LIMIT 1
CACHE (0.0ms) SELECT 1 AS one FROM "spree_pages" WHERE "spree_pages"."visible" = 't' AND (("spree_pages"."slug" = 'checkout/payment' OR "spree_pages"."slug" = '/checkout/payment')) ORDER BY position ASC LIMIT 1
Processing by Spree::CheckoutController#edit as HTML
Parameters: {"state"=>"payment"}
Spree::User Load (0.2ms) SELECT "spree_users".* FROM "spree_users" WHERE "spree_users"."id" = 1 LIMIT 1
Spree::Order Load (0.2ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."user_id" = 1 AND "spree_orders"."completed_at" IS NULL AND "spree_orders"."created_by_id" = 1 ORDER BY created_at DESC LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 AND "spree_orders"."currency" = 'EUR' LIMIT 1
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."adjustable_id" IN (20) ORDER BY created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.2ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20 ORDER BY created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20
Spree::PaymentMethod Load (0.1ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
 (0.1ms) SELECT COUNT(*) FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20
Spree::StockItem Load (0.1ms) SELECT "spree_stock_items".* FROM "spree_stock_items" INNER JOIN "spree_stock_locations" ON "spree_stock_locations"."id" = "spree_stock_items"."stock_location_id" WHERE "spree_stock_items"."variant_id" = 2 AND "spree_stock_locations"."active" = 't'
Spree::StockItem Load (0.1ms) SELECT "spree_stock_items".* FROM "spree_stock_items" INNER JOIN "spree_stock_locations" ON "spree_stock_locations"."id" = "spree_stock_items"."stock_location_id" WHERE "spree_stock_items"."variant_id" = 1 AND "spree_stock_locations"."active" = 't'
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
CACHE (0.0ms) SELECT "spree_users".* FROM "spree_users" WHERE "spree_users"."id" = 1 LIMIT 1
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 20
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 45
Spree::Variant Load (0.2ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (2)
CACHE (0.0ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 46
Spree::Variant Load (0.2ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (1)
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."id" = 2 LIMIT 1
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."id" = 1 LIMIT 1
 (0.1ms) SELECT COUNT(*) FROM "spree_roles" INNER JOIN "spree_roles_users" ON "spree_roles"."id" = "spree_roles_users"."role_id" WHERE "spree_roles_users"."user_id" = 1 AND "spree_roles"."name" = 'admin'
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/core/app/views/spree/shared/_error_messages.html.erb (0.1ms)
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::PaymentMethod Load (0.2ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL
Spree::Address Load (0.1ms) SELECT "spree_addresses".* FROM "spree_addresses" WHERE "spree_addresses"."id" = 48 LIMIT 1
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/checkout/payment/_gateway.html.erb (2.8ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/checkout/payment/_check.html.erb (0.1ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/better_spree_paypal_express-1f02a2d1484a/app/views/spree/checkout/payment/_paypal.html.erb (0.5ms)
 (0.1ms) SELECT COUNT(*) FROM "spree_activators" WHERE "spree_activators"."type" IN ('Spree::Promotion') AND "spree_activators"."event_name" = 'spree.checkout.coupon_code_added'
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/checkout/_payment.html.erb (24.9ms)
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" INNER JOIN "spree_line_items" ON "spree_adjustments"."adjustable_id" = "spree_line_items"."id" AND "spree_adjustments"."adjustable_type" = 'Spree::LineItem' WHERE "spree_line_items"."order_id" = 20 AND "spree_adjustments"."eligible" = 't'
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/checkout/_summary.html.erb (8.0ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/checkout/edit.html.erb within spree/layouts/spree_application (63.0ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/core/app/views/spree/shared/_routes.html.erb (0.3ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_head.html.erb (11.5ms)
Deface: 1 overrides found for 'spree/shared/_nav_bar'
Deface: 'auth_shared_login_bar' matched 1 times with 'li#search-bar'
Spree::Taxon Load (0.3ms) SELECT "spree_taxons".* FROM "spree_taxons" WHERE "spree_taxons"."parent_id" IS NULL ORDER BY "spree_taxons"."lft"
Spree::Taxon::Translation Load (0.2ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 1
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 2
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 3
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 9
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_search.html.erb (6.9ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_nav_bar.html.erb (11.8ms)
Deface: 2 overrides found for 'spree/shared/_main_nav_bar'
Deface: 'locale_selector' matched 1 times with '#main-nav-bar'
Deface: [WARNING] No :original defined for 'locale_selector', you should change its definition to include:
:original => 'db2835f20e2ab66e854a74f6ae2a05cfb86f20fd'
Deface: 'pages_in_header' matched 1 times with '#main-nav-bar'
Deface: [WARNING] No :original defined for 'pages_in_header', you should change its definition to include:
:original => '69e748b409fc9557847596ce97b153d0be34385d'
Spree::Page Load (0.3ms) SELECT "spree_pages".* FROM "spree_pages" WHERE "spree_pages"."show_in_header" = 't' AND "spree_pages"."visible" = 't' ORDER BY position ASC
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree_static_content-a9d17d7fe22b/app/views/spree/static_content/_static_content_list.html.erb (0.2ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_main_nav_bar.html.erb (6.7ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_header.html.erb (35.2ms)
Deface: 1 overrides found for 'spree/shared/_footer'
Deface: 'pages_in_footer' matched 1 times with '#footer-right'
Deface: [WARNING] No :original defined for 'pages_in_footer', you should change its definition to include:
:original => 'd9b8e77ea5ae5135f238b2c9fa4caa345b1c8412'
Spree::Page Load (0.3ms) SELECT "spree_pages".* FROM "spree_pages" WHERE "spree_pages"."show_in_footer" = 't' AND "spree_pages"."visible" = 't' ORDER BY position ASC
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree_static_content-a9d17d7fe22b/app/views/spree/static_content/_static_content_list.html.erb (0.4ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-yoexportoaceite-theme-0c7e2325fed5/app/views/spree/shared/_footer.html.erb (4.3ms)
Spree::Tracker Load (0.1ms) SELECT "spree_trackers".* FROM "spree_trackers" WHERE "spree_trackers"."active" = 't' AND "spree_trackers"."environment" = 'development' LIMIT 1
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_google_analytics.html.erb (0.6ms)
Completed 200 OK in 180ms (Views: 144.7ms | ActiveRecord: 4.9ms)
Started GET "/assets/store/screen.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:42 +0200
Served asset /store/screen.css - 304 Not Modified (0ms)
Started GET "/assets/normalize.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:42 +0200
Served asset /normalize.css - 304 Not Modified (0ms)
Started GET "/assets/store/spree_frontend.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:42 +0200
Served asset /store/spree_frontend.css - 304 Not Modified (0ms)
Started GET "/assets/skeleton.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:42 +0200
Served asset /skeleton.css - 304 Not Modified (0ms)
Started GET "/assets/store/spree_paypal_express.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:42 +0200
Served asset /store/spree_paypal_express.css - 304 Not Modified (0ms)
Started GET "/assets/store/spree_yoexporto.css?body=1" for 127.0.0.1 at 2013-09-03 10:15:42 +0200
Served asset /store/spree_yoexporto.css - 304 Not Modified (0ms)
Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:42 +0200
Served asset /jquery.js - 304 Not Modified (0ms)
Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:42 +0200
Served asset /jquery_ujs.js - 304 Not Modified (0ms)
Started GET "/assets/jquery.validate/jquery.validate.min.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:42 +0200
Served asset /jquery.validate/jquery.validate.min.js - 304 Not Modified (0ms)
Started GET "/assets/spree.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:43 +0200
Served asset /spree.js - 304 Not Modified (0ms)
Started GET "/assets/jsuri.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:43 +0200
Served asset /jsuri.js - 304 Not Modified (0ms)
Started GET "/assets/store/cart.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:43 +0200
Served asset /store/cart.js - 304 Not Modified (0ms)
Started GET "/assets/store/product.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:43 +0200
Served asset /store/product.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_frontend.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:43 +0200
Served asset /store/spree_frontend.js - 304 Not Modified (0ms)
Started GET "/assets/store/checkout.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:43 +0200
Served asset /store/checkout.js - 304 Not Modified (0ms)
Started GET "/assets/store/locale.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:43 +0200
Served asset /store/locale.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_i18n.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:43 +0200
Served asset /store/spree_i18n.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_yoexporto.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:43 +0200
Served asset /store/spree_yoexporto.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_paypal_express.js?body=1" for 127.0.0.1 at 2013-09-03 10:15:43 +0200
Served asset /store/spree_paypal_express.js - 304 Not Modified (0ms)
Started POST "/paypal?payment_method_id=7" for 127.0.0.1 at 2013-09-03 10:15:47 +0200
Processing by Spree::PaypalController#express as HTML
Parameters: {"authenticity_token"=>"Z4VAnyBl3LnxFHfiUVATVLOTAeezNyNAZ/HitL3KxWo=", "payment_method_id"=>"7"}
Spree::User Load (0.2ms) SELECT "spree_users".* FROM "spree_users" WHERE "spree_users"."id" = 1 LIMIT 1
Spree::Order Load (0.2ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."user_id" = 1 AND "spree_orders"."completed_at" IS NULL AND "spree_orders"."created_by_id" = 1 ORDER BY created_at DESC LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 AND "spree_orders"."currency" = 'EUR' LIMIT 1
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."adjustable_id" IN (20) ORDER BY created_at ASC
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20 ORDER BY created_at ASC
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."id" = 2 LIMIT 1
Spree::Product Load (0.1ms) SELECT "spree_products".* FROM "spree_products" WHERE "spree_products"."id" = 2 LIMIT 1
Spree::Product::Translation Load (0.1ms) SELECT "spree_product_translations".* FROM "spree_product_translations" WHERE "spree_product_translations"."spree_product_id" = 2
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."id" = 1 LIMIT 1
Spree::Product Load (0.1ms) SELECT "spree_products".* FROM "spree_products" WHERE "spree_products"."id" = 1 LIMIT 1
Spree::Product::Translation Load (0.1ms) SELECT "spree_product_translations".* FROM "spree_product_translations" WHERE "spree_product_translations"."spree_product_id" = 1
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."originator_type" = 'Spree::TaxRate' AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."originator_type" = 'Spree::ShippingMethod' ORDER BY created_at ASC
Spree::PaymentMethod Load (0.1ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = ? LIMIT 1 [["id", "7"]]
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."originator_type" = 'Spree::ShippingMethod' ORDER BY created_at ASC
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."originator_type" = 'Spree::TaxRate' AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY created_at ASC
Spree::Address Load (0.1ms) SELECT "spree_addresses".* FROM "spree_addresses" WHERE "spree_addresses"."id" = 48 LIMIT 1
Spree::State Load (0.1ms) SELECT "spree_states".* FROM "spree_states" WHERE "spree_states"."id" = 55 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 175 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = ? LIMIT 1 [["id", "7"]]
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = ? LIMIT 1 [["id", "7"]]
Redirected to https://www.sandbox.paypal.com/webscr?cmd=_express-checkout&token=EC-4C457159SK9393546
Completed 302 Found in 1883ms (ActiveRecord: 2.4ms)
Started GET "/paypal/confirm?payment_method_id=7&token=EC-4C457159SK9393546&PayerID=B4ESFV9SMX2DL" for 127.0.0.1 at 2013-09-03 10:16:10 +0200
Spree::Page Exists (0.2ms) SELECT 1 AS one FROM "spree_pages" WHERE "spree_pages"."visible" = 't' AND (("spree_pages"."slug" = 'paypal/confirm' OR "spree_pages"."slug" = '/paypal/confirm')) ORDER BY position ASC LIMIT 1
CACHE (0.0ms) SELECT 1 AS one FROM "spree_pages" WHERE "spree_pages"."visible" = 't' AND (("spree_pages"."slug" = 'paypal/confirm' OR "spree_pages"."slug" = '/paypal/confirm')) ORDER BY position ASC LIMIT 1
Processing by Spree::PaypalController#confirm as HTML
Parameters: {"payment_method_id"=>"7", "token"=>"EC-4C457159SK9393546", "PayerID"=>"B4ESFV9SMX2DL"}
Spree::User Load (0.2ms) SELECT "spree_users".* FROM "spree_users" WHERE "spree_users"."id" = 1 LIMIT 1
Spree::Order Load (0.2ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."user_id" = 1 AND "spree_orders"."completed_at" IS NULL AND "spree_orders"."created_by_id" = 1 ORDER BY created_at DESC LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 AND "spree_orders"."currency" = 'EUR' LIMIT 1
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."adjustable_id" IN (20) ORDER BY created_at ASC
 (0.0ms) begin transaction
SQL (0.3ms) INSERT INTO "spree_paypal_express_checkouts" ("created_at", "payer_id", "refund_transaction_id", "refund_type", "refunded_at", "state", "token", "transaction_id") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Tue, 03 Sep 2013 08:16:10 UTC +00:00], ["payer_id", "B4ESFV9SMX2DL"], ["refund_transaction_id", nil], ["refund_type", nil], ["refunded_at", nil], ["state", "complete"], ["token", "EC-4C457159SK9393546"], ["transaction_id", nil]]
 (3.8ms) commit transaction
Spree::PaymentMethod Load (0.1ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = ? LIMIT 1 [["id", "7"]]
 (0.0ms) begin transaction
Spree::Payment Exists (0.1ms) SELECT 1 AS one FROM "spree_payments" WHERE "spree_payments"."identifier" = 'UGXF4NU7' LIMIT 1
SQL (0.3ms) INSERT INTO "spree_payments" ("amount", "avs_response", "created_at", "cvv_response_code", "cvv_response_message", "identifier", "order_id", "payment_method_id", "response_code", "source_id", "source_type", "state", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["amount", #<BigDecimal:7f99740d3b48,'0.4993E2',18(45)>], ["avs_response", nil], ["created_at", Tue, 03 Sep 2013 08:16:11 UTC +00:00], ["cvv_response_code", nil], ["cvv_response_message", nil], ["identifier", "UGXF4NU7"], ["order_id", 20], ["payment_method_id", 7], ["response_code", nil], ["source_id", 6], ["source_type", "Spree::PaypalExpressCheckout"], ["state", "checkout"], ["updated_at", Tue, 03 Sep 2013 08:16:11 UTC +00:00]]
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'checkout' AND (id != 43)
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.2ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20 ORDER BY created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY created_at ASC
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 50]]
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 45 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 45
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (2)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 50
SQL (0.2ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 50
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 51]]
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 46 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 46
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (1)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 51
SQL (0.0ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 51
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."originator_type" = 'Spree::PromotionAction' AND "spree_adjustments"."eligible" = 't' ORDER BY amount ASC, created_at DESC LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
SQL (0.1ms) UPDATE "spree_orders" SET "payment_state" = 'balance_due', "shipment_state" = NULL, "item_total" = 33.0, "adjustment_total" = 16.93, "payment_total" = 0.0, "total" = 49.93 WHERE "spree_orders"."id" = 20
 (1.8ms) commit transaction
Spree::Payment Load (0.2ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20
Spree::PaymentMethod Load (0.1ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
 (0.0ms) begin transaction
Spree::Payment Exists (0.1ms) SELECT 1 AS one FROM "spree_payments" WHERE "spree_payments"."identifier" = 'WCTTQ7JL' LIMIT 1
 (0.2ms) UPDATE "spree_payments" SET "state" = 'processing', "identifier" = 'WCTTQ7JL', "updated_at" = '2013-09-03 08:16:11.041148' WHERE "spree_payments"."id" = 43
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY created_at ASC
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 50]]
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 45 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 45
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (2)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 50
SQL (0.2ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 50
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 51]]
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 46 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 46
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (1)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 51
SQL (0.0ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 51
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."originator_type" = 'Spree::PromotionAction' AND "spree_adjustments"."eligible" = 't' ORDER BY amount ASC, created_at DESC LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
SQL (0.1ms) UPDATE "spree_orders" SET "payment_state" = 'balance_due', "shipment_state" = NULL, "item_total" = 33.0, "adjustment_total" = 16.93, "payment_total" = 0.0, "total" = 49.93 WHERE "spree_orders"."id" = 20
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."originator_type" = 'Spree::ShippingMethod' ORDER BY created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."originator_type" = 'Spree::TaxRate' AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' AND "spree_adjustments"."originator_type" = 'Spree::PromotionAction' ORDER BY created_at ASC
Spree::Address Load (0.1ms) SELECT "spree_addresses".* FROM "spree_addresses" WHERE "spree_addresses"."id" = 48 LIMIT 1
Spree::State Load (0.0ms) SELECT "spree_states".* FROM "spree_states" WHERE "spree_states"."id" = 55 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 175 LIMIT 1
Spree::Address Load (0.1ms) SELECT "spree_addresses".* FROM "spree_addresses" WHERE "spree_addresses"."id" = 49 LIMIT 1
CACHE (0.0ms) SELECT "spree_states".* FROM "spree_states" WHERE "spree_states"."id" = 55 LIMIT 1
CACHE (0.0ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 175 LIMIT 1
SQL (0.3ms) INSERT INTO "spree_log_entries" ("created_at", "details", "source_id", "source_type", "updated_at") VALUES (?, ?, ?, ?, ?) [["created_at", Tue, 03 Sep 2013 08:16:12 UTC +00:00], ["details", "--- !ruby/object:PayPal::SDK::Merchant::DataTypes::DoExpressCheckoutPaymentResponseType\nTimestamp: !ruby/object:DateTime 2013-09-03 08:16:10.000000000 Z\nAck: !ruby/string:PayPal::SDK::Merchant::DataTypes::AckCodeType |-\n RmFpbHVyZQ==\nCorrelationID: !ruby/string:PayPal::SDK::Core::API::DataTypes::SimpleTypes::String |-\n NjUyOWRkYTliYzExZg==\nErrors: !ruby/array:PayPal::SDK::Core::API::DataTypes::ArrayWithBlock\n internal:\n - !ruby/object:PayPal::SDK::Merchant::DataTypes::ErrorType\n ShortMessage: !ruby/string:PayPal::SDK::Core::API::DataTypes::SimpleTypes::String |-\n VHJhbnNhY3Rpb24gcmVmdXNlZCBiZWNhdXNlIG9mIGFuIGludmFsaWQgYXJn\n dW1lbnQuIFNlZSBhZGRpdGlvbmFsIGVycm9yIG1lc3NhZ2VzIGZvciBkZXRh\n aWxzLg==\n LongMessage: !ruby/string:PayPal::SDK::Core::API::DataTypes::SimpleTypes::String |-\n T3JkZXIgdG90YWwgaXMgaW52YWxpZC4=\n ErrorCode: !ruby/string:PayPal::SDK::Core::API::DataTypes::SimpleTypes::String |-\n MTA0MDE=\n SeverityCode: !ruby/string:PayPal::SDK::Merchant::DataTypes::SeverityCodeType |-\n RXJyb3I=\n ivars:\n :@block: !ruby/object:Proc {}\nVersion: !ruby/string:PayPal::SDK::Core::API::DataTypes::SimpleTypes::String |-\n OTguMA==\nBuild: !ruby/string:PayPal::SDK::Core::API::DataTypes::SimpleTypes::String |-\n NzUzOTE5MQ==\nDoExpressCheckoutPaymentResponseDetails: \n"], ["source_id", 43], ["source_type", "Spree::Payment"], ["updated_at", Tue, 03 Sep 2013 08:16:12 UTC +00:00]]
Spree::Payment Exists (0.1ms) SELECT 1 AS one FROM "spree_payments" WHERE "spree_payments"."identifier" = 'P2YLE9JC' LIMIT 1
 (0.1ms) UPDATE "spree_payments" SET "state" = 'failed', "identifier" = 'P2YLE9JC', "updated_at" = '2013-09-03 08:16:12.557813' WHERE "spree_payments"."id" = 43
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY created_at ASC
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 50]]
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 45 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 45
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (2)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 50
SQL (0.1ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 50
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 51]]
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 46 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 46
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (1)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.0ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 51
SQL (0.0ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 51
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."originator_type" = 'Spree::PromotionAction' AND "spree_adjustments"."eligible" = 't' ORDER BY amount ASC, created_at DESC LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
SQL (0.1ms) UPDATE "spree_orders" SET "payment_state" = 'balance_due', "shipment_state" = NULL, "item_total" = 33.0, "adjustment_total" = 16.93, "payment_total" = 0.0, "total" = 49.93 WHERE "spree_orders"."id" = 20
Error en pasarela
--- !ruby/object:PayPal::SDK::Merchant::DataTypes::DoExpressCheckoutPaymentResponseType
Timestamp: !ruby/object:DateTime 2013-09-03 08:16:10.000000000 Z
Ack: !ruby/string:PayPal::SDK::Merchant::DataTypes::AckCodeType |-
RmFpbHVyZQ==
CorrelationID: !ruby/string:PayPal::SDK::Core::API::DataTypes::SimpleTypes::String |-
NjUyOWRkYTliYzExZg==
Errors: !ruby/array:PayPal::SDK::Core::API::DataTypes::ArrayWithBlock
internal:
- !ruby/object:PayPal::SDK::Merchant::DataTypes::ErrorType
ShortMessage: !ruby/string:PayPal::SDK::Core::API::DataTypes::SimpleTypes::String |-
VHJhbnNhY3Rpb24gcmVmdXNlZCBiZWNhdXNlIG9mIGFuIGludmFsaWQgYXJn
dW1lbnQuIFNlZSBhZGRpdGlvbmFsIGVycm9yIG1lc3NhZ2VzIGZvciBkZXRh
aWxzLg==
LongMessage: !ruby/string:PayPal::SDK::Core::API::DataTypes::SimpleTypes::String |-
T3JkZXIgdG90YWwgaXMgaW52YWxpZC4=
ErrorCode: !ruby/string:PayPal::SDK::Core::API::DataTypes::SimpleTypes::String |-
MTA0MDE=
SeverityCode: !ruby/string:PayPal::SDK::Merchant::DataTypes::SeverityCodeType |-
RXJyb3I=
ivars:
:@block: !ruby/object:Proc {}
Version: !ruby/string:PayPal::SDK::Core::API::DataTypes::SimpleTypes::String |-
OTguMA==
Build: !ruby/string:PayPal::SDK::Core::API::DataTypes::SimpleTypes::String |-
NzUzOTE5MQ==
DoExpressCheckoutPaymentResponseDetails:
 (0.6ms) rollback transaction
 (0.0ms) begin transaction
Spree::Payment Exists (0.1ms) SELECT 1 AS one FROM "spree_payments" WHERE "spree_payments"."identifier" = '943LCRYK' LIMIT 1
 (0.1ms) UPDATE "spree_payments" SET "state" = 'failed', "identifier" = '943LCRYK', "updated_at" = '2013-09-03 08:16:12.595310' WHERE "spree_payments"."id" = 43
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY created_at ASC
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 50]]
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 45 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 45
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (2)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 50
SQL (0.2ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 50
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 51]]
Spree::ShippingMethod Load (0.1ms) SELECT "spree_shipping_methods".* FROM "spree_shipping_methods" WHERE "spree_shipping_methods"."deleted_at" IS NULL AND "spree_shipping_methods"."id" = 4 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 46 LIMIT 1
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 46
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (1)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 4 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 51
SQL (0.1ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 51
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."originator_type" = 'Spree::PromotionAction' AND "spree_adjustments"."eligible" = 't' ORDER BY amount ASC, created_at DESC LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
SQL (0.1ms) UPDATE "spree_orders" SET "payment_state" = 'balance_due', "shipment_state" = NULL, "item_total" = 33.0, "adjustment_total" = 16.93, "payment_total" = 0.0, "total" = 49.93 WHERE "spree_orders"."id" = 20
 (5.4ms) commit transaction
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."id" = 43 LIMIT 1
 (0.0ms) begin transaction
SQL (0.3ms) INSERT INTO "spree_log_entries" ("created_at", "details", "source_id", "source_type", "updated_at") VALUES (?, ?, ?, ?, ?) [["created_at", Tue, 03 Sep 2013 08:16:12 UTC +00:00], ["details", "--- !ruby/object:PayPal::SDK::Merchant::DataTypes::DoExpressCheckoutPaymentResponseType\nTimestamp: !ruby/object:DateTime 2013-09-03 08:16:10.000000000 Z\nAck: !ruby/string:PayPal::SDK::Merchant::DataTypes::AckCodeType |-\n RmFpbHVyZQ==\nCorrelationID: !ruby/string:PayPal::SDK::Core::API::DataTypes::SimpleTypes::String |-\n NjUyOWRkYTliYzExZg==\nErrors: !ruby/array:PayPal::SDK::Core::API::DataTypes::ArrayWithBlock\n internal:\n - !ruby/object:PayPal::SDK::Merchant::DataTypes::ErrorType\n ShortMessage: !ruby/string:PayPal::SDK::Core::API::DataTypes::SimpleTypes::String |-\n VHJhbnNhY3Rpb24gcmVmdXNlZCBiZWNhdXNlIG9mIGFuIGludmFsaWQgYXJn\n dW1lbnQuIFNlZSBhZGRpdGlvbmFsIGVycm9yIG1lc3NhZ2VzIGZvciBkZXRh\n aWxzLg==\n LongMessage: !ruby/string:PayPal::SDK::Core::API::DataTypes::SimpleTypes::String |-\n T3JkZXIgdG90YWwgaXMgaW52YWxpZC4=\n ErrorCode: !ruby/string:PayPal::SDK::Core::API::DataTypes::SimpleTypes::String |-\n MTA0MDE=\n SeverityCode: !ruby/string:PayPal::SDK::Merchant::DataTypes::SeverityCodeType |-\n RXJyb3I=\n ivars:\n :@block: !ruby/object:Proc {}\nVersion: !ruby/string:PayPal::SDK::Core::API::DataTypes::SimpleTypes::String |-\n OTguMA==\nBuild: !ruby/string:PayPal::SDK::Core::API::DataTypes::SimpleTypes::String |-\n NzUzOTE5MQ==\nDoExpressCheckoutPaymentResponseDetails: \n"], ["source_id", 43], ["source_type", "Spree::Payment"], ["updated_at", Tue, 03 Sep 2013 08:16:12 UTC +00:00]]
 (2.0ms) commit transaction
Redirected to http://localhost:3000/checkout/payment
Completed 302 Found in 1634ms (ActiveRecord: 27.8ms)
Started GET "/checkout/payment" for 127.0.0.1 at 2013-09-03 10:16:12 +0200
Spree::Page Exists (0.2ms) SELECT 1 AS one FROM "spree_pages" WHERE "spree_pages"."visible" = 't' AND (("spree_pages"."slug" = 'checkout/payment' OR "spree_pages"."slug" = '/checkout/payment')) ORDER BY position ASC LIMIT 1
CACHE (0.0ms) SELECT 1 AS one FROM "spree_pages" WHERE "spree_pages"."visible" = 't' AND (("spree_pages"."slug" = 'checkout/payment' OR "spree_pages"."slug" = '/checkout/payment')) ORDER BY position ASC LIMIT 1
Processing by Spree::CheckoutController#edit as HTML
Parameters: {"state"=>"payment"}
Spree::User Load (0.2ms) SELECT "spree_users".* FROM "spree_users" WHERE "spree_users"."id" = 1 LIMIT 1
Spree::Order Load (0.2ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."user_id" = 1 AND "spree_orders"."completed_at" IS NULL AND "spree_orders"."created_by_id" = 1 ORDER BY created_at DESC LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 AND "spree_orders"."currency" = 'EUR' LIMIT 1
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."adjustable_id" IN (20) ORDER BY created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20 ORDER BY created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20
Spree::PaymentMethod Load (0.1ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL AND "spree_payment_methods"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
 (0.1ms) SELECT COUNT(*) FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 20
Spree::StockItem Load (0.1ms) SELECT "spree_stock_items".* FROM "spree_stock_items" INNER JOIN "spree_stock_locations" ON "spree_stock_locations"."id" = "spree_stock_items"."stock_location_id" WHERE "spree_stock_items"."variant_id" = 2 AND "spree_stock_locations"."active" = 't'
Spree::StockItem Load (0.1ms) SELECT "spree_stock_items".* FROM "spree_stock_items" INNER JOIN "spree_stock_locations" ON "spree_stock_locations"."id" = "spree_stock_items"."stock_location_id" WHERE "spree_stock_items"."variant_id" = 1 AND "spree_stock_locations"."active" = 't'
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
CACHE (0.0ms) SELECT "spree_users".* FROM "spree_users" WHERE "spree_users"."id" = 1 LIMIT 1
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::Shipment Load (0.2ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 20
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 45
Spree::Variant Load (0.2ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (2)
CACHE (0.0ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 46
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (1)
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."id" = 2 LIMIT 1
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."id" = 1 LIMIT 1
 (0.1ms) SELECT COUNT(*) FROM "spree_roles" INNER JOIN "spree_roles_users" ON "spree_roles"."id" = "spree_roles_users"."role_id" WHERE "spree_roles_users"."user_id" = 1 AND "spree_roles"."name" = 'admin'
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/core/app/views/spree/shared/_error_messages.html.erb (0.2ms)
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 20 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
Spree::PaymentMethod Load (0.2ms) SELECT "spree_payment_methods".* FROM "spree_payment_methods" WHERE "spree_payment_methods"."deleted_at" IS NULL
Spree::Address Load (0.1ms) SELECT "spree_addresses".* FROM "spree_addresses" WHERE "spree_addresses"."id" = 48 LIMIT 1
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/checkout/payment/_gateway.html.erb (2.7ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/checkout/payment/_check.html.erb (0.1ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/better_spree_paypal_express-1f02a2d1484a/app/views/spree/checkout/payment/_paypal.html.erb (0.4ms)
 (0.1ms) SELECT COUNT(*) FROM "spree_activators" WHERE "spree_activators"."type" IN ('Spree::Promotion') AND "spree_activators"."event_name" = 'spree.checkout.coupon_code_added'
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/checkout/_payment.html.erb (15.4ms)
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 20 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY created_at ASC
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 20 LIMIT 1
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" INNER JOIN "spree_line_items" ON "spree_adjustments"."adjustable_id" = "spree_line_items"."id" AND "spree_adjustments"."adjustable_type" = 'Spree::LineItem' WHERE "spree_line_items"."order_id" = 20 AND "spree_adjustments"."eligible" = 't'
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/checkout/_summary.html.erb (8.2ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/checkout/edit.html.erb within spree/layouts/spree_application (41.4ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/core/app/views/spree/shared/_routes.html.erb (0.4ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_head.html.erb (11.9ms)
Deface: 1 overrides found for 'spree/shared/_nav_bar'
Deface: 'auth_shared_login_bar' matched 1 times with 'li#search-bar'
Spree::Taxon Load (0.3ms) SELECT "spree_taxons".* FROM "spree_taxons" WHERE "spree_taxons"."parent_id" IS NULL ORDER BY "spree_taxons"."lft"
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 1
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 2
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 3
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 9
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_search.html.erb (6.4ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_nav_bar.html.erb (11.8ms)
Deface: 2 overrides found for 'spree/shared/_main_nav_bar'
Deface: 'locale_selector' matched 1 times with '#main-nav-bar'
Deface: [WARNING] No :original defined for 'locale_selector', you should change its definition to include:
:original => 'db2835f20e2ab66e854a74f6ae2a05cfb86f20fd'
Deface: 'pages_in_header' matched 1 times with '#main-nav-bar'
Deface: [WARNING] No :original defined for 'pages_in_header', you should change its definition to include:
:original => '69e748b409fc9557847596ce97b153d0be34385d'
Spree::Page Load (0.3ms) SELECT "spree_pages".* FROM "spree_pages" WHERE "spree_pages"."show_in_header" = 't' AND "spree_pages"."visible" = 't' ORDER BY position ASC
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree_static_content-a9d17d7fe22b/app/views/spree/static_content/_static_content_list.html.erb (0.2ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_main_nav_bar.html.erb (7.0ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_header.html.erb (35.6ms)
Deface: 1 overrides found for 'spree/shared/_footer'
Deface: 'pages_in_footer' matched 1 times with '#footer-right'
Deface: [WARNING] No :original defined for 'pages_in_footer', you should change its definition to include:
:original => 'd9b8e77ea5ae5135f238b2c9fa4caa345b1c8412'
Spree::Page Load (0.3ms) SELECT "spree_pages".* FROM "spree_pages" WHERE "spree_pages"."show_in_footer" = 't' AND "spree_pages"."visible" = 't' ORDER BY position ASC
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree_static_content-a9d17d7fe22b/app/views/spree/static_content/_static_content_list.html.erb (0.4ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-yoexportoaceite-theme-0c7e2325fed5/app/views/spree/shared/_footer.html.erb (4.3ms)
Spree::Tracker Load (0.1ms) SELECT "spree_trackers".* FROM "spree_trackers" WHERE "spree_trackers"."active" = 't' AND "spree_trackers"."environment" = 'development' LIMIT 1
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-8208b4bd0871/frontend/app/views/spree/shared/_google_analytics.html.erb (0.6ms)
Completed 200 OK in 255ms (Views: 123.4ms | ActiveRecord: 4.9ms)
Started GET "/assets/normalize.css?body=1" for 127.0.0.1 at 2013-09-03 10:16:13 +0200
Served asset /normalize.css - 304 Not Modified (0ms)
Started GET "/assets/store/spree_frontend.css?body=1" for 127.0.0.1 at 2013-09-03 10:16:13 +0200
Served asset /store/spree_frontend.css - 304 Not Modified (0ms)
Started GET "/assets/skeleton.css?body=1" for 127.0.0.1 at 2013-09-03 10:16:13 +0200
Served asset /skeleton.css - 304 Not Modified (0ms)
Started GET "/assets/store/screen.css?body=1" for 127.0.0.1 at 2013-09-03 10:16:13 +0200
Served asset /store/screen.css - 304 Not Modified (0ms)
Started GET "/assets/store/spree_paypal_express.css?body=1" for 127.0.0.1 at 2013-09-03 10:16:13 +0200
Served asset /store/spree_paypal_express.css - 304 Not Modified (0ms)
Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-09-03 10:16:13 +0200
Served asset /jquery.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_yoexporto.css?body=1" for 127.0.0.1 at 2013-09-03 10:16:13 +0200
Served asset /store/spree_yoexporto.css - 304 Not Modified (0ms)
Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-09-03 10:16:13 +0200
Served asset /jquery_ujs.js - 304 Not Modified (0ms)
Started GET "/assets/jquery.validate/jquery.validate.min.js?body=1" for 127.0.0.1 at 2013-09-03 10:16:13 +0200
Served asset /jquery.validate/jquery.validate.min.js - 304 Not Modified (0ms)
Started GET "/assets/jsuri.js?body=1" for 127.0.0.1 at 2013-09-03 10:16:13 +0200
Served asset /jsuri.js - 304 Not Modified (0ms)
Started GET "/assets/store/checkout.js?body=1" for 127.0.0.1 at 2013-09-03 10:16:13 +0200
Served asset /store/checkout.js - 304 Not Modified (0ms)
Started GET "/assets/spree.js?body=1" for 127.0.0.1 at 2013-09-03 10:16:13 +0200
Served asset /spree.js - 304 Not Modified (0ms)
Started GET "/assets/store/product.js?body=1" for 127.0.0.1 at 2013-09-03 10:16:13 +0200
Served asset /store/product.js - 304 Not Modified (0ms)
Started GET "/assets/store/cart.js?body=1" for 127.0.0.1 at 2013-09-03 10:16:13 +0200
Served asset /store/cart.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_frontend.js?body=1" for 127.0.0.1 at 2013-09-03 10:16:13 +0200
Served asset /store/spree_frontend.js - 304 Not Modified (0ms)
Started GET "/assets/store/locale.js?body=1" for 127.0.0.1 at 2013-09-03 10:16:13 +0200
Served asset /store/locale.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_i18n.js?body=1" for 127.0.0.1 at 2013-09-03 10:16:13 +0200
Served asset /store/spree_i18n.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_yoexporto.js?body=1" for 127.0.0.1 at 2013-09-03 10:16:13 +0200
Served asset /store/spree_yoexporto.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_paypal_express.js?body=1" for 127.0.0.1 at 2013-09-03 10:16:13 +0200
Served asset /store/spree_paypal_express.js - 304 Not Modified (0ms)
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment