Skip to content

Instantly share code, notes, and snippets.

@vlledo
Created July 31, 2013 08:07
Show Gist options
  • Save vlledo/6120216 to your computer and use it in GitHub Desktop.
Save vlledo/6120216 to your computer and use it in GitHub Desktop.
Server Output
Started GET "/" for 127.0.0.1 at 2013-07-31 09:57:44 +0200
Spree::Page Load (0.2ms) 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.1ms) 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" = 12 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 (12) ORDER BY spree_adjustments.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 (3, 4)
Spree::Taxon Load (0.3ms) SELECT "spree_taxons".* FROM "spree_taxons" WHERE "spree_taxons"."parent_id" IN (12, 13) ORDER BY lft
Spree::Taxonomy::Translation Load (0.1ms) SELECT "spree_taxonomy_translations".* FROM "spree_taxonomy_translations" WHERE "spree_taxonomy_translations"."spree_taxonomy_id" = 3
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 14
Spree::Taxon::Translation Load (0.2ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 15
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 16
Spree::Taxonomy::Translation Load (0.1ms) SELECT "spree_taxonomy_translations".* FROM "spree_taxonomy_translations" WHERE "spree_taxonomy_translations"."spree_taxonomy_id" = 4
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 19
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 20
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 21
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 22
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_taxonomies.html.erb (2235.5ms)
SQL (0.4ms) 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-07-31 07:57:44.714980') AND ("spree_products".available_on <= '2013-07-31 07:57:44.719062') 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 (17, 19, 20, 21, 22, 23, 24, 25, 26, 27, 28, 29) AND ("spree_products".deleted_at IS NULL or "spree_products".deleted_at >= '2013-07-31 07:57:44.714980') AND ("spree_products".available_on <= '2013-07-31 07:57:44.719062') 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-07-31 07:57:44.714980') AND ("spree_products".available_on <= '2013-07-31 07:57:44.719062') 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 (17, 19, 20, 21, 22, 23, 24, 25, 26, 27, 28, 29) AND ("spree_products".deleted_at IS NULL or "spree_products".deleted_at >= '2013-07-31 07:57:44.714980') AND ("spree_products".available_on <= '2013-07-31 07:57:44.719062') 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-07-31 07:57:44.714980') AND ("spree_products".available_on <= '2013-07-31 07:57:44.719062') 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 (17, 19, 20, 21, 22, 23, 24, 25, 26, 27, 28, 29) AND ("spree_products".deleted_at IS NULL or "spree_products".deleted_at >= '2013-07-31 07:57:44.714980') AND ("spree_products".available_on <= '2013-07-31 07:57:44.719062') AND (spree_prices.amount IS NOT NULL)
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 27 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" = 27 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" = 17
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 29 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" = 29 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" = 19
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 30 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" = 30 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" = 20
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 31 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" = 31 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" = 21
 (0.3ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 32 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" = 32 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" = 22
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 33 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" = 33 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" = 23
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 34 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" = 34 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" = 24
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 35 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" = 35 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" = 25
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 36 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" = 36 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" = 26
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 37 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" = 37 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" = 27
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 38 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" = 38 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" = 28
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 39 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" = 39 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" = 29
 (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-07-31 07:57:44.714980') AND ("spree_products".available_on <= '2013-07-31 07:57:44.719062') AND (spree_prices.amount IS NOT NULL)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_products.html.erb (256.9ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/home/index.html.erb within spree/layouts/spree_application (2503.5ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/core/app/views/spree/shared/_routes.html.erb (0.8ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_head.html.erb (93.1ms)
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.2ms) 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" = 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-fcb429304620/frontend/app/views/spree/shared/_search.html.erb (4.5ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_nav_bar.html.erb (9.5ms)
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" = 12
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.0ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_main_nav_bar.html.erb (9.5ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_header.html.erb (37.2ms)
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.1ms) 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-fcb429304620/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 => '0e70b64f6d6eb3161e2de39b8253df9c0a201c63'
Spree::Page Load (0.2ms) 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.0ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_footer.html.erb (3.6ms)
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-fcb429304620/frontend/app/views/spree/shared/_google_analytics.html.erb (4.5ms)
Completed 200 OK in 2811ms (Views: 2678.9ms | ActiveRecord: 14.8ms)
Started GET "/assets/normalize.css?body=1" for 127.0.0.1 at 2013-07-31 09:57:47 +0200
Served asset /normalize.css - 304 Not Modified (8ms)
Started GET "/assets/store/spree_frontend.css?body=1" for 127.0.0.1 at 2013-07-31 09:57:47 +0200
Served asset /store/spree_frontend.css - 304 Not Modified (6ms)
Started GET "/assets/store/screen.css?body=1" for 127.0.0.1 at 2013-07-31 09:57:47 +0200
Served asset /store/screen.css - 304 Not Modified (4ms)
Started GET "/assets/skeleton.css?body=1" for 127.0.0.1 at 2013-07-31 09:57:47 +0200
Served asset /skeleton.css - 304 Not Modified (4ms)
Started GET "/assets/store/spree_paypal_express.css?body=1" for 127.0.0.1 at 2013-07-31 09:57:47 +0200
Served asset /store/spree_paypal_express.css - 304 Not Modified (6ms)
Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-07-31 09:57:47 +0200
Served asset /jquery.js - 304 Not Modified (6ms)
Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-07-31 09:57:47 +0200
Served asset /jquery_ujs.js - 304 Not Modified (5ms)
Started GET "/assets/jquery.validate/jquery.validate.min.js?body=1" for 127.0.0.1 at 2013-07-31 09:57:47 +0200
Served asset /jquery.validate/jquery.validate.min.js - 304 Not Modified (2ms)
Started GET "/assets/jsuri.js?body=1" for 127.0.0.1 at 2013-07-31 09:57:47 +0200
Served asset /jsuri.js - 304 Not Modified (5ms)
Started GET "/assets/spree.js?body=1" for 127.0.0.1 at 2013-07-31 09:57:47 +0200
Served asset /spree.js - 304 Not Modified (5ms)
Started GET "/assets/store/checkout.js?body=1" for 127.0.0.1 at 2013-07-31 09:57:47 +0200
Served asset /store/checkout.js - 304 Not Modified (4ms)
Started GET "/assets/store/product.js?body=1" for 127.0.0.1 at 2013-07-31 09:57:47 +0200
Served asset /store/product.js - 304 Not Modified (2ms)
Started GET "/assets/store/cart.js?body=1" for 127.0.0.1 at 2013-07-31 09:57:47 +0200
Served asset /store/cart.js - 304 Not Modified (2ms)
Started GET "/assets/store/spree_frontend.js?body=1" for 127.0.0.1 at 2013-07-31 09:57:47 +0200
Served asset /store/spree_frontend.js - 304 Not Modified (6ms)
Started GET "/assets/store/spree_paypal_express.js?body=1" for 127.0.0.1 at 2013-07-31 09:57:47 +0200
Served asset /store/spree_paypal_express.js - 304 Not Modified (6ms)
Started GET "/assets/store/locale.js?body=1" for 127.0.0.1 at 2013-07-31 09:57:47 +0200
Served asset /store/locale.js - 304 Not Modified (2ms)
Started GET "/assets/store/spree_i18n.js?body=1" for 127.0.0.1 at 2013-07-31 09:57:47 +0200
Served asset /store/spree_i18n.js - 304 Not Modified (8ms)
Started GET "/assets/logo/spree_50.png" for 127.0.0.1 at 2013-07-31 09:57:47 +0200
Served asset /logo/spree_50.png - 304 Not Modified (3ms)
Started GET "/products/ruby-on-rails-tote" for 127.0.0.1 at 2013-07-31 09:58:05 +0200
Spree::Page Exists (0.2ms) SELECT 1 AS one FROM "spree_pages" WHERE "spree_pages"."visible" = 't' AND (("spree_pages"."slug" = 'products/ruby-on-rails-tote' OR "spree_pages"."slug" = '/products/ruby-on-rails-tote')) 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" = 'products/ruby-on-rails-tote' OR "spree_pages"."slug" = '/products/ruby-on-rails-tote')) ORDER BY position ASC LIMIT 1
Processing by Spree::ProductsController#show as HTML
Parameters: {"id"=>"ruby-on-rails-tote"}
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" = 12 AND "spree_orders"."currency" = 'EUR' LIMIT 1
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."adjustable_id" IN (12) ORDER BY spree_adjustments.created_at ASC
 (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'
Spree::Product::Translation Load (0.1ms) SELECT DISTINCT locale FROM "spree_product_translations" 
SQL (0.2ms) SELECT DISTINCT "spree_products".id FROM "spree_products" LEFT OUTER JOIN "spree_product_translations" ON "spree_product_translations"."spree_product_id" = "spree_products"."id" WHERE "spree_products"."deleted_at" IS NULL AND "spree_product_translations"."permalink" = 'ruby-on-rails-tote' AND "spree_product_translations"."locale" IN ('en', 'es') AND (spree_product_translations.name IS NOT NULL) AND (spree_product_translations.permalink IS NOT NULL) LIMIT 1
SQL (0.2ms) 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_product_translations"."id" AS t1_r0, "spree_product_translations"."spree_product_id" AS t1_r1, "spree_product_translations"."locale" AS t1_r2, "spree_product_translations"."created_at" AS t1_r3, "spree_product_translations"."updated_at" AS t1_r4, "spree_product_translations"."name" AS t1_r5, "spree_product_translations"."description" AS t1_r6, "spree_product_translations"."meta_description" AS t1_r7, "spree_product_translations"."meta_keywords" AS t1_r8, "spree_product_translations"."permalink" AS t1_r9 FROM "spree_products" LEFT OUTER JOIN "spree_product_translations" ON "spree_product_translations"."spree_product_id" = "spree_products"."id" WHERE "spree_products"."deleted_at" IS NULL AND "spree_product_translations"."permalink" = 'ruby-on-rails-tote' AND "spree_product_translations"."locale" IN ('en', 'es') AND "spree_products"."id" IN (17) AND (spree_product_translations.name IS NOT NULL) AND (spree_product_translations.permalink IS NOT NULL)
Spree::Product::Translation Load (0.1ms) SELECT "spree_product_translations".* FROM "spree_product_translations" WHERE "spree_product_translations"."spree_product_id" = 17
Spree::Variant Load (0.2ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."product_id" = 17 AND "spree_variants"."is_master" = 't' LIMIT 1
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 27 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" = 27 AND "spree_assets"."viewable_type" = 'Spree::Variant' ORDER BY position LIMIT 1
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/products/_image.html.erb (4.0ms)
Spree::Image Load (0.3ms) SELECT "spree_assets".* FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 27 AND "spree_assets"."viewable_type" = 'Spree::Variant' ORDER BY position
Spree::Image Load (0.3ms) SELECT "spree_assets".* FROM "spree_assets" INNER JOIN "spree_variants" ON "spree_assets"."viewable_id" = "spree_variants"."id" AND "spree_assets"."viewable_type" = 'Spree::Variant' WHERE "spree_variants"."deleted_at" IS NULL AND "spree_assets"."type" IN ('Spree::Image') AND "spree_variants"."product_id" = 17 ORDER BY position
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" = 27 LIMIT 1
CACHE (0.0ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" = 27 LIMIT 1
 (0.1ms) SELECT COUNT(*) FROM "spree_variants" WHERE "spree_variants"."product_id" = 17 AND "spree_variants"."is_master" = 'f' AND "spree_variants"."deleted_at" IS NULL
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/products/_thumbnails.html.erb (15.9ms)
 (0.1ms) SELECT COUNT(*) FROM "spree_product_properties" WHERE "spree_product_properties"."product_id" = 17
Spree::ProductProperty Load (0.2ms) SELECT "spree_product_properties".* FROM "spree_product_properties" WHERE "spree_product_properties"."product_id" = 17 ORDER BY spree_product_properties.position
Spree::Property Load (0.2ms) SELECT "spree_properties".* FROM "spree_properties" WHERE "spree_properties"."id" IN (20, 21, 22)
Spree::Property::Translation Load (0.1ms) SELECT "spree_property_translations".* FROM "spree_property_translations" WHERE "spree_property_translations"."spree_property_id" = 20
Spree::Property::Translation Load (0.1ms) SELECT "spree_property_translations".* FROM "spree_property_translations" WHERE "spree_property_translations"."spree_property_id" = 21
Spree::Property::Translation Load (0.1ms) SELECT "spree_property_translations".* FROM "spree_property_translations" WHERE "spree_property_translations"."spree_property_id" = 22
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/products/_properties.html.erb (33.4ms)
Spree::PromotionRule Load (0.1ms) SELECT "spree_promotion_rules".* FROM "spree_promotion_rules" INNER JOIN "spree_products_promotion_rules" ON "spree_promotion_rules"."id" = "spree_products_promotion_rules"."promotion_rule_id" WHERE "spree_products_promotion_rules"."product_id" = 17
Spree::Promotion Load (0.1ms) SELECT "spree_activators".* FROM "spree_activators" WHERE "spree_activators"."type" IN ('Spree::Promotion') AND "spree_activators"."advertise" = 't' AND "spree_activators"."id" IN (NULL)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/products/_promotions.html.erb (4.8ms)
Spree::Variant Load (0.3ms) SELECT "spree_variants".* FROM "spree_variants" INNER JOIN "spree_prices" ON "spree_prices"."variant_id" = "spree_variants"."id" WHERE "spree_variants"."product_id" = 17 AND "spree_variants"."is_master" = 'f' AND "spree_variants"."deleted_at" IS NULL AND "spree_prices"."currency" = 'EUR' AND (spree_prices.amount IS NOT NULL) ORDER BY "spree_variants".position ASC
Spree::Price Load (0.1ms) SELECT "spree_prices".* FROM "spree_prices" WHERE "spree_prices"."variant_id" = 27
Spree::Price Load (0.1ms) SELECT "spree_prices".* FROM "spree_prices" WHERE "spree_prices"."variant_id" = 27 AND "spree_prices"."currency" = 'EUR' LIMIT 1
CACHE (0.0ms) SELECT "spree_variants".* FROM "spree_variants" INNER JOIN "spree_prices" ON "spree_prices"."variant_id" = "spree_variants"."id" WHERE "spree_variants"."product_id" = 17 AND "spree_variants"."is_master" = 'f' AND "spree_variants"."deleted_at" IS NULL AND "spree_prices"."currency" = 'EUR' AND (spree_prices.amount IS NOT NULL) ORDER BY "spree_variants".position ASC
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/products/_cart_form.html.erb (10.2ms)
Spree::Taxon Load (0.1ms) SELECT "spree_taxons".* FROM "spree_taxons" INNER JOIN "spree_products_taxons" ON "spree_taxons"."id" = "spree_products_taxons"."taxon_id" WHERE "spree_products_taxons"."product_id" = 17
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 14
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/products/_taxons.html.erb (71.4ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/products/show.html.erb within spree/layouts/spree_application (162.6ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/core/app/views/spree/shared/_routes.html.erb (0.4ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_head.html.erb (15.7ms)
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.2ms) 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" = 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-fcb429304620/frontend/app/views/spree/shared/_search.html.erb (3.8ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_nav_bar.html.erb (8.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'
 (0.1ms) SELECT COUNT(*) FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 12
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.0ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/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-fcb429304620/frontend/app/views/spree/shared/_header.html.erb (31.4ms)
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 => '0e70b64f6d6eb3161e2de39b8253df9c0a201c63'
Spree::Page Load (0.2ms) 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.0ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_footer.html.erb (3.5ms)
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-fcb429304620/frontend/app/views/spree/shared/_google_analytics.html.erb (0.7ms)
Completed 200 OK in 270ms (Views: 243.1ms | ActiveRecord: 7.3ms)
Started GET "/assets/normalize.css?body=1" for 127.0.0.1 at 2013-07-31 09:58:06 +0200
Served asset /normalize.css - 304 Not Modified (0ms)
Started GET "/assets/skeleton.css?body=1" for 127.0.0.1 at 2013-07-31 09:58:06 +0200
Served asset /skeleton.css - 304 Not Modified (0ms)
Started GET "/assets/store/spree_frontend.css?body=1" for 127.0.0.1 at 2013-07-31 09:58:06 +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-07-31 09:58:06 +0200
Served asset /jquery_ujs.js - 304 Not Modified (0ms)
Started GET "/assets/store/screen.css?body=1" for 127.0.0.1 at 2013-07-31 09:58:06 +0200
Served asset /store/screen.css - 304 Not Modified (0ms)
Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-07-31 09:58:06 +0200
Served asset /jquery.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_paypal_express.css?body=1" for 127.0.0.1 at 2013-07-31 09:58:06 +0200
Served asset /store/spree_paypal_express.css - 304 Not Modified (0ms)
Started GET "/assets/jsuri.js?body=1" for 127.0.0.1 at 2013-07-31 09:58:06 +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-07-31 09:58:06 +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-07-31 09:58:06 +0200
Served asset /spree.js - 304 Not Modified (0ms)
Started GET "/assets/store/checkout.js?body=1" for 127.0.0.1 at 2013-07-31 09:58:06 +0200
Served asset /store/checkout.js - 304 Not Modified (3ms)
Started GET "/assets/store/product.js?body=1" for 127.0.0.1 at 2013-07-31 09:58:06 +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-07-31 09:58:06 +0200
Served asset /store/spree_frontend.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_paypal_express.js?body=1" for 127.0.0.1 at 2013-07-31 09:58:06 +0200
Served asset /store/spree_paypal_express.js - 304 Not Modified (0ms)
Started GET "/assets/store/cart.js?body=1" for 127.0.0.1 at 2013-07-31 09:58:06 +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-07-31 09:58:06 +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-07-31 09:58:06 +0200
Served asset /store/spree_i18n.js - 304 Not Modified (0ms)
Started GET "/assets/logo/spree_50.png" for 127.0.0.1 at 2013-07-31 09:58:06 +0200
Served asset /logo/spree_50.png - 304 Not Modified (0ms)
Started POST "/orders/populate" for 127.0.0.1 at 2013-07-31 09:58:08 +0200
Processing by Spree::OrdersController#populate as HTML
Parameters: {"utf8"=>"✓", "authenticity_token"=>"x7qYFREW2RI08ppuesUlw0uKvdi7Egk3vjlp648CeVo=", "variants"=>{"27"=>"1"}}
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" = 12 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 (12) ORDER BY spree_adjustments.created_at ASC
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."number" IS NULL LIMIT 1
 (0.2ms) 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'
Spree::Variant Load (0.2ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" = ? LIMIT 1 [["id", "27"]]
Spree::LineItem Load (0.2ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 12 ORDER BY spree_line_items.created_at ASC
Spree::Price Load (0.1ms) SELECT "spree_prices".* FROM "spree_prices" WHERE "spree_prices"."variant_id" = 27
 (0.0ms) begin transaction
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" = 27 AND "spree_stock_locations"."active" = 't'
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 12 LIMIT 1
SQL (0.5ms) INSERT INTO "spree_line_items" ("cost_price", "created_at", "currency", "order_id", "price", "quantity", "updated_at", "variant_id") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["cost_price", #<BigDecimal:7fb3d956cf68,'0.17E2',9(36)>], ["created_at", Wed, 31 Jul 2013 07:58:08 UTC +00:00], ["currency", "EUR"], ["order_id", 12], ["price", #<BigDecimal:7fb3d953e848,'0.14E2',9(36)>], ["quantity", 1], ["updated_at", Wed, 31 Jul 2013 07:58:08 UTC +00:00], ["variant_id", 27]]
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."originator_type" = 'Spree::TaxRate' AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY spree_adjustments.created_at ASC
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 12 ORDER BY spree_line_items.created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 10 AND "spree_adjustments"."adjustable_type" = 'Spree::LineItem'
Spree::Zone Load (0.1ms) SELECT "spree_zones".* FROM "spree_zones" ORDER BY zone_members_count, created_at
Spree::ZoneMember Load (0.2ms) SELECT "spree_zone_members".* FROM "spree_zone_members" WHERE "spree_zone_members"."zone_id" IN (2, 1)
Spree::Zone Load (0.1ms) SELECT "spree_zones".* FROM "spree_zones" WHERE "spree_zones"."default_tax" = 't' LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12
SQL (0.3ms) INSERT INTO "spree_state_changes" ("created_at", "name", "next_state", "previous_state", "stateful_id", "stateful_type", "updated_at", "user_id") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Wed, 31 Jul 2013 07:58:08 UTC +00:00], ["name", "payment"], ["next_state", "balance_due"], ["previous_state", nil], ["stateful_id", 12], ["stateful_type", "Spree::Order"], ["updated_at", Wed, 31 Jul 2013 07:58:08 UTC +00:00], ["user_id", 1]]
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 12
 (0.1ms) SELECT "spree_shipments"."state" FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 12
SQL (0.2ms) INSERT INTO "spree_state_changes" ("created_at", "name", "next_state", "previous_state", "stateful_id", "stateful_type", "updated_at", "user_id") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Wed, 31 Jul 2013 07:58:08 UTC +00:00], ["name", "shipment"], ["next_state", nil], ["previous_state", nil], ["stateful_id", 12], ["stateful_type", "Spree::Order"], ["updated_at", Wed, 31 Jul 2013 07:58:08 UTC +00:00], ["user_id", 1]]
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY spree_adjustments.created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 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" = 12 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
SQL (0.1ms) UPDATE "spree_orders" SET "payment_state" = 'balance_due', "shipment_state" = NULL, "item_total" = 14.0, "adjustment_total" = 0.0, "payment_total" = 0.0, "total" = 14.0 WHERE "spree_orders"."id" = 12
 (1.8ms) commit transaction
Spree::Order Load (0.2ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = ? LIMIT 1 [["id", 12]]
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" = 27 AND "spree_stock_locations"."active" = 't'
 (0.1ms) SELECT COUNT(*) FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 12
Spree::Activator Load (0.1ms) SELECT "spree_activators".* FROM "spree_activators" WHERE (starts_at IS NULL OR starts_at < '2013-07-31 07:58:08.760762') AND (expires_at IS NULL OR expires_at > '2013-07-31 07:58:08.760918') AND (event_name LIKE 'spree.cart.add%')
Spree::Activator Load (0.1ms) SELECT "spree_activators".* FROM "spree_activators" WHERE (starts_at IS NULL OR starts_at < '2013-07-31 07:58:08.761668') AND (expires_at IS NULL OR expires_at > '2013-07-31 07:58:08.761755') AND (event_name LIKE 'spree.order.contents_changed%')
Redirected to http://localhost:3000/cart
Completed 302 Found in 241ms (ActiveRecord: 8.3ms)
Started GET "/cart" for 127.0.0.1 at 2013-07-31 09:58:08 +0200
Spree::Page Exists (0.2ms) 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.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" = 12 AND "spree_orders"."currency" = 'EUR' LIMIT 1
Spree::Adjustment Load (0.4ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."adjustable_id" IN (12) ORDER BY spree_adjustments.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'
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" = 12
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/core/app/views/spree/shared/_error_messages.html.erb (0.4ms)
Spree::LineItem Load (0.2ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 12 ORDER BY spree_line_items.created_at ASC
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" = 27 LIMIT 1
Spree::Image Load (0.2ms) SELECT "spree_assets".* FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 27 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" = 17 LIMIT 1
Spree::Product::Translation Load (0.1ms) SELECT "spree_product_translations".* FROM "spree_product_translations" WHERE "spree_product_translations"."spree_product_id" = 17
Spree::OptionValue Load (0.1ms) 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" = 27 ORDER BY spree_option_types.position asc
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" = 27 AND "spree_stock_locations"."active" = 't'
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/orders/_line_item.html.erb (17.1ms)
 (0.1ms) SELECT COUNT(*) FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't'
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/orders/_form.html.erb (29.9ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/orders/edit.html.erb within spree/layouts/spree_application (38.8ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/core/app/views/spree/shared/_routes.html.erb (0.3ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_head.html.erb (11.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.2ms) 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" = 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-fcb429304620/frontend/app/views/spree/shared/_search.html.erb (3.8ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_nav_bar.html.erb (8.4ms)
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.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.0ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_main_nav_bar.html.erb (4.9ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_header.html.erb (28.9ms)
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 => '0e70b64f6d6eb3161e2de39b8253df9c0a201c63'
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.0ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_footer.html.erb (3.4ms)
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-fcb429304620/frontend/app/views/spree/shared/_google_analytics.html.erb (0.6ms)
Completed 200 OK in 192ms (Views: 113.5ms | ActiveRecord: 3.9ms)
Started GET "/assets/normalize.css?body=1" for 127.0.0.1 at 2013-07-31 09:58:08 +0200
Served asset /normalize.css - 304 Not Modified (0ms)
Started GET "/assets/store/screen.css?body=1" for 127.0.0.1 at 2013-07-31 09:58:09 +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-07-31 09:58:09 +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-07-31 09:58:09 +0200
Served asset /skeleton.css - 304 Not Modified (0ms)
Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-07-31 09:58:09 +0200
Served asset /jquery.js - 304 Not Modified (0ms)
Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-07-31 09:58:09 +0200
Served asset /jquery_ujs.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_paypal_express.css?body=1" for 127.0.0.1 at 2013-07-31 09:58:09 +0200
Served asset /store/spree_paypal_express.css - 304 Not Modified (0ms)
Started GET "/assets/jsuri.js?body=1" for 127.0.0.1 at 2013-07-31 09:58:09 +0200
Served asset /jsuri.js - 304 Not Modified (0ms)
Started GET "/assets/store/checkout.js?body=1" for 127.0.0.1 at 2013-07-31 09:58:09 +0200
Served asset /store/checkout.js - 304 Not Modified (0ms)
Started GET "/assets/jquery.validate/jquery.validate.min.js?body=1" for 127.0.0.1 at 2013-07-31 09:58:09 +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-07-31 09:58:09 +0200
Served asset /spree.js - 304 Not Modified (0ms)
Started GET "/assets/store/product.js?body=1" for 127.0.0.1 at 2013-07-31 09:58:09 +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-07-31 09:58:09 +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-07-31 09:58:09 +0200
Served asset /store/spree_frontend.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_paypal_express.js?body=1" for 127.0.0.1 at 2013-07-31 09:58:09 +0200
Served asset /store/spree_paypal_express.js - 304 Not Modified (0ms)
Started GET "/assets/store/locale.js?body=1" for 127.0.0.1 at 2013-07-31 09:58: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-07-31 09:58:09 +0200
Served asset /store/spree_i18n.js - 304 Not Modified (0ms)
Started GET "/assets/logo/spree_50.png" for 127.0.0.1 at 2013-07-31 09:58:09 +0200
Served asset /logo/spree_50.png - 304 Not Modified (0ms)
Started PUT "/cart" for 127.0.0.1 at 2013-07-31 09:58:12 +0200
Processing by Spree::OrdersController#update as HTML
Parameters: {"utf8"=>"✓", "authenticity_token"=>"x7qYFREW2RI08ppuesUlw0uKvdi7Egk3vjlp648CeVo=", "order"=>{"line_items_attributes"=>{"0"=>{"quantity"=>"1", "id"=>"10"}}, "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" = 12 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 (12) ORDER BY spree_adjustments.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" = 12 AND "spree_line_items"."id" IN (10) ORDER BY spree_line_items.created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 12 ORDER BY spree_line_items.created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12
 (0.2ms) UPDATE "spree_orders" SET "last_ip_address" = '127.0.0.1', "updated_at" = '2013-07-31 07:58:12.761876' WHERE "spree_orders"."id" = 12
 (0.9ms) commit transaction
 (0.0ms) begin transaction
 (0.0ms) commit transaction
 (0.1ms) SELECT COUNT(*) FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 12
Spree::Activator Load (0.1ms) SELECT "spree_activators".* FROM "spree_activators" WHERE (starts_at IS NULL OR starts_at < '2013-07-31 07:58:12.765932') AND (expires_at IS NULL OR expires_at > '2013-07-31 07:58:12.766099') AND (event_name LIKE 'spree.order.contents_changed%')
 (0.0ms) begin transaction
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
 (0.0ms) commit transaction
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
Redirected to http://localhost:3000/checkout/address
Completed 302 Found in 24ms (ActiveRecord: 3.1ms)
Started GET "/checkout/address" for 127.0.0.1 at 2013-07-31 09:58:12 +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" = 12 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 (12) ORDER BY spree_adjustments.created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 12 ORDER BY spree_line_items.created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = ? LIMIT 1 [["id", 49]]
CACHE (0.0ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = ? LIMIT 1 [["id", 49]]
 (0.2ms) SELECT COUNT(*) FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 12
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" = 27 AND "spree_stock_locations"."active" = 't'
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
CACHE (0.0ms) SELECT "spree_users".* FROM "spree_users" WHERE "spree_users"."id" = 1 LIMIT 1
 (0.2ms) 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-fcb429304620/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" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
Spree::Preference Load (0.1ms) SELECT "spree_preferences".* FROM "spree_preferences" WHERE "spree_preferences"."key" = 'spree/app_configuration/checkout_zone' LIMIT 1
Spree::Zone Load (0.1ms) 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" 
 (0.1ms) SELECT COUNT(*) FROM "spree_states" WHERE "spree_states"."country_id" = 49
Spree::State Load (0.4ms) SELECT "spree_states".* FROM "spree_states" WHERE "spree_states"."country_id" = 49 ORDER BY name ASC
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/address/_form.html.erb (120.9ms)
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 COUNT(*) FROM "spree_states" WHERE "spree_states"."country_id" = 49
CACHE (0.0ms) SELECT "spree_states".* FROM "spree_states" WHERE "spree_states"."country_id" = 49 ORDER BY name ASC
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/address/_form.html.erb (35.8ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/checkout/_address.html.erb (165.5ms)
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 10 AND "spree_adjustments"."adjustable_type" = 'Spree::LineItem'
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/checkout/_summary.html.erb (4.5ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/checkout/edit.html.erb within spree/layouts/spree_application (187.2ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/core/app/views/spree/shared/_routes.html.erb (0.3ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_head.html.erb (11.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.2ms) 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" = 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-fcb429304620/frontend/app/views/spree/shared/_search.html.erb (3.6ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_nav_bar.html.erb (8.1ms)
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.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.0ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_main_nav_bar.html.erb (5.9ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_header.html.erb (29.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 => '0e70b64f6d6eb3161e2de39b8253df9c0a201c63'
Spree::Page Load (0.2ms) 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.0ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_footer.html.erb (3.5ms)
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-fcb429304620/frontend/app/views/spree/shared/_google_analytics.html.erb (0.6ms)
Completed 200 OK in 321ms (Views: 262.5ms | ActiveRecord: 5.2ms)
Started GET "/assets/skeleton.css?body=1" for 127.0.0.1 at 2013-07-31 09:58:13 +0200
Served asset /skeleton.css - 304 Not Modified (0ms)
Started GET "/assets/store/spree_frontend.css?body=1" for 127.0.0.1 at 2013-07-31 09:58:13 +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-07-31 09:58:13 +0200
Served asset /store/screen.css - 304 Not Modified (0ms)
Started GET "/assets/normalize.css?body=1" for 127.0.0.1 at 2013-07-31 09:58:13 +0200
Served asset /normalize.css - 304 Not Modified (0ms)
Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-07-31 09:58:13 +0200
Served asset /jquery.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_paypal_express.css?body=1" for 127.0.0.1 at 2013-07-31 09:58:13 +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-07-31 09:58: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-07-31 09:58:13 +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-07-31 09:58:13 +0200
Served asset /spree.js - 304 Not Modified (0ms)
Started GET "/assets/jsuri.js?body=1" for 127.0.0.1 at 2013-07-31 09:58: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-07-31 09:58:13 +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-07-31 09:58:13 +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-07-31 09:58:13 +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-07-31 09:58:13 +0200
Served asset /store/spree_frontend.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_paypal_express.js?body=1" for 127.0.0.1 at 2013-07-31 09:58:13 +0200
Served asset /store/spree_paypal_express.js - 304 Not Modified (0ms)
Started GET "/assets/store/locale.js?body=1" for 127.0.0.1 at 2013-07-31 09:58: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-07-31 09:58:13 +0200
Served asset /store/spree_i18n.js - 304 Not Modified (0ms)
Started GET "/assets/logo/spree_50.png" for 127.0.0.1 at 2013-07-31 09:58:13 +0200
Served asset /logo/spree_50.png - 304 Not Modified (0ms)
Started GET "/api/states?country_id=49" for 127.0.0.1 at 2013-07-31 09:58:13 +0200
Spree::Page Exists (0.4ms) 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", "49"]]
Spree::State Load (0.4ms) SELECT "spree_states".* FROM "spree_states" WHERE "spree_states"."country_id" = 49 ORDER BY name ASC LIMIT 1
Spree::Country Load (0.2ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" IN (49)
Spree::State Load (0.5ms) SELECT "spree_states".* FROM "spree_states" WHERE "spree_states"."country_id" = 49 ORDER BY name ASC
CACHE (0.0ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" IN (49)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/api/app/views/spree/api/states/index.v1.rabl (13.4ms)
Started GET "/api/states?country_id=175" for 127.0.0.1 at 2013-07-31 09:58:24 +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.2ms) 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-fcb429304620/api/app/views/spree/api/states/index.v1.rabl (3.4ms)
Started PUT "/checkout/update/address" for 127.0.0.1 at 2013-07-31 09:58:48 +0200
Processing by Spree::CheckoutController#update as HTML
Parameters: {"utf8"=>"✓", "authenticity_token"=>"x7qYFREW2RI08ppuesUlw0uKvdi7Egk3vjlp648CeVo=", "order"=>{"bill_address_attributes"=>{"firstname"=>"Víctor", "lastname"=>"Lledó", "address1"=>"Avenida de las cruces 18,4B", "address2"=>"", "city"=>"Jaén", "country_id"=>"175", "state_id"=>"56", "zipcode"=>"23003", "phone"=>"653368046"}, "use_billing"=>"1"}, "commit"=>"Save and Continue", "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" = 12 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 (12) ORDER BY spree_adjustments.created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 12 ORDER BY spree_line_items.created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = ? LIMIT 1 [["id", 49]]
CACHE (0.0ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = ? LIMIT 1 [["id", 49]]
 (0.1ms) SELECT COUNT(*) FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 12
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" = 27 AND "spree_stock_locations"."active" = 't'
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.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" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.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
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" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
SQL (0.5ms) INSERT INTO "spree_addresses" ("address1", "address2", "alternative_phone", "city", "company", "country_id", "created_at", "firstname", "lastname", "phone", "state_id", "state_name", "updated_at", "zipcode") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["address1", "Avenida de las cruces 18,4B"], ["address2", ""], ["alternative_phone", nil], ["city", "Jaén"], ["company", nil], ["country_id", 175], ["created_at", Wed, 31 Jul 2013 07:58:48 UTC +00:00], ["firstname", "Víctor"], ["lastname", "Lledó"], ["phone", "653368046"], ["state_id", 56], ["state_name", nil], ["updated_at", Wed, 31 Jul 2013 07:58:48 UTC +00:00], ["zipcode", "23003"]]
SQL (0.1ms) INSERT INTO "spree_addresses" ("address1", "address2", "alternative_phone", "city", "company", "country_id", "created_at", "firstname", "lastname", "phone", "state_id", "state_name", "updated_at", "zipcode") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["address1", "Avenida de las cruces 18,4B"], ["address2", ""], ["alternative_phone", nil], ["city", "Jaén"], ["company", nil], ["country_id", 175], ["created_at", Wed, 31 Jul 2013 07:58:48 UTC +00:00], ["firstname", "Víctor"], ["lastname", "Lledó"], ["phone", "653368046"], ["state_id", 56], ["state_name", nil], ["updated_at", Wed, 31 Jul 2013 07:58:48 UTC +00:00], ["zipcode", "23003"]]
 (0.1ms) UPDATE "spree_orders" SET "state" = 'address', "bill_address_id" = 11, "ship_address_id" = 12, "updated_at" = '2013-07-31 07:58:48.678907' WHERE "spree_orders"."id" = 12
 (1.3ms) commit transaction
Spree::Activator Load (0.1ms) SELECT "spree_activators".* FROM "spree_activators" WHERE (starts_at IS NULL OR starts_at < '2013-07-31 07:58:48.681545') AND (expires_at IS NULL OR expires_at > '2013-07-31 07:58:48.681713') 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" = 12
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"."deleted_at" IS NULL AND "spree_variants"."id" = 27 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" = 27 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" = 27 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" = 27 ORDER BY id LIMIT 1
Spree::Product Load (0.1ms) SELECT "spree_products".* FROM "spree_products" WHERE "spree_products"."id" = 17 LIMIT 1
Spree::ShippingCategory Load (0.1ms) SELECT "spree_shipping_categories".* FROM "spree_shipping_categories" WHERE "spree_shipping_categories"."id" = 4 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" = 4
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 9 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" = 12 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" = 11 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" = 10 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" = 9
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" = 12
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" = 11
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" = 10
CACHE (0.0ms) SELECT "spree_zone_members".* FROM "spree_zone_members" WHERE "spree_zone_members"."zone_id" = 2
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" = 12 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."number" = 'H83877523324' LIMIT 1
 (0.1ms) SELECT COUNT(*) FROM "spree_shipments" WHERE (spree_shipments.number LIKE 'H83877523324%')
SQL (0.4ms) 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", Wed, 31 Jul 2013 07:58:48 UTC +00:00], ["number", "H83877523324"], ["order_id", 12], ["shipped_at", nil], ["state", "pending"], ["stock_location_id", 1], ["tracking", nil], ["updated_at", Wed, 31 Jul 2013 07:58:48 UTC +00:00]]
SQL (0.2ms) INSERT INTO "spree_shipping_rates" ("cost", "created_at", "selected", "shipment_id", "shipping_method_id", "updated_at") VALUES (?, ?, ?, ?, ?, ?) [["cost", #<BigDecimal:7fb3d90f9c88,'0.5E1',9(27)>], ["created_at", Wed, 31 Jul 2013 07:58:48 UTC +00:00], ["selected", true], ["shipment_id", 7], ["shipping_method_id", 12], ["updated_at", Wed, 31 Jul 2013 07:58:48 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", Wed, 31 Jul 2013 07:58:48 UTC +00:00], ["order_id", 12], ["pending", true], ["return_authorization_id", nil], ["shipment_id", 7], ["state", "on_hand"], ["updated_at", Wed, 31 Jul 2013 07:58:48 UTC +00:00], ["variant_id", 27]]
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."source_id" = 7 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" = 7 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" = 7 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" = 12 LIMIT 1
CACHE (0.0ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 7 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" = 12 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 7
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (27)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 12 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.4ms) 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", 12], ["adjustable_type", "Spree::Order"], ["amount", #<BigDecimal:7fb3da2dd530,'0.5E1',9(27)>], ["created_at", Wed, 31 Jul 2013 07:58:48 UTC +00:00], ["eligible", true], ["label", "Shipping"], ["mandatory", true], ["originator_id", 12], ["originator_type", "Spree::ShippingMethod"], ["source_id", 7], ["source_type", "Spree::Shipment"], ["state", "open"], ["updated_at", Wed, 31 Jul 2013 07:58:48 UTC +00:00]]
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 12 LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 12 ORDER BY spree_line_items.created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12
SQL (0.2ms) INSERT INTO "spree_state_changes" ("created_at", "name", "next_state", "previous_state", "stateful_id", "stateful_type", "updated_at", "user_id") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Wed, 31 Jul 2013 07:58:48 UTC +00:00], ["name", "payment"], ["next_state", "balance_due"], ["previous_state", "balance_due"], ["stateful_id", 12], ["stateful_type", "Spree::Order"], ["updated_at", Wed, 31 Jul 2013 07:58:48 UTC +00:00], ["user_id", 1]]
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 12
SQL (0.2ms) UPDATE "spree_shipments" SET "state" = 'pending' WHERE "spree_shipments"."id" = 7
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 7
 (0.0ms) SELECT "spree_shipments"."state" FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 12
SQL (0.1ms) INSERT INTO "spree_state_changes" ("created_at", "name", "next_state", "previous_state", "stateful_id", "stateful_type", "updated_at", "user_id") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Wed, 31 Jul 2013 07:58:48 UTC +00:00], ["name", "shipment"], ["next_state", "pending"], ["previous_state", nil], ["stateful_id", 12], ["stateful_type", "Spree::Order"], ["updated_at", Wed, 31 Jul 2013 07:58:48 UTC +00:00], ["user_id", 1]]
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY spree_adjustments.created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 19]]
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" = 12 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 7 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" = 12 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 7
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (27)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 12 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 19
SQL (0.0ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 19
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 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" = 12 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
SQL (0.1ms) UPDATE "spree_orders" SET "payment_state" = 'balance_due', "shipment_state" = 'pending', "item_total" = 14.0, "adjustment_total" = 5.0, "payment_total" = 0.0, "total" = 19.0 WHERE "spree_orders"."id" = 12
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = ? LIMIT 1 [["id", 7]]
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 12 LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 12 ORDER BY spree_line_items.created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12
SQL (0.2ms) INSERT INTO "spree_state_changes" ("created_at", "name", "next_state", "previous_state", "stateful_id", "stateful_type", "updated_at", "user_id") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Wed, 31 Jul 2013 07:58:49 UTC +00:00], ["name", "payment"], ["next_state", "balance_due"], ["previous_state", "balance_due"], ["stateful_id", 12], ["stateful_type", "Spree::Order"], ["updated_at", Wed, 31 Jul 2013 07:58:49 UTC +00:00], ["user_id", 1]]
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 12
SQL (0.1ms) UPDATE "spree_shipments" SET "state" = 'pending' WHERE "spree_shipments"."id" = 7
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 7
 (0.1ms) SELECT "spree_shipments"."state" FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 12
SQL (0.2ms) INSERT INTO "spree_state_changes" ("created_at", "name", "next_state", "previous_state", "stateful_id", "stateful_type", "updated_at", "user_id") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Wed, 31 Jul 2013 07:58:49 UTC +00:00], ["name", "shipment"], ["next_state", "pending"], ["previous_state", "pending"], ["stateful_id", 12], ["stateful_type", "Spree::Order"], ["updated_at", Wed, 31 Jul 2013 07:58:49 UTC +00:00], ["user_id", 1]]
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY spree_adjustments.created_at ASC
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 19]]
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" = 12 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 7 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" = 12 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 7
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (27)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 12 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 19
SQL (0.0ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 19
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 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" = 12 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
SQL (0.1ms) UPDATE "spree_orders" SET "payment_state" = 'balance_due', "shipment_state" = 'pending', "item_total" = 14.0, "adjustment_total" = 5.0, "payment_total" = 0.0, "total" = 19.0 WHERE "spree_orders"."id" = 12
Spree::ShippingRate Load (0.1ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 7
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
 (0.1ms) UPDATE "spree_orders" SET "state" = 'delivery', "adjustment_total" = 5.0, "total" = 19.0, "updated_at" = '2013-07-31 07:58:49.065890' WHERE "spree_orders"."id" = 12
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."originator_type" = 'Spree::TaxRate' AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY spree_adjustments.created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 10 AND "spree_adjustments"."adjustable_type" = 'Spree::LineItem'
Spree::Zone Load (0.1ms) SELECT "spree_zones".* FROM "spree_zones" ORDER BY zone_members_count, created_at
Spree::ZoneMember Load (0.2ms) SELECT "spree_zone_members".* FROM "spree_zone_members" WHERE "spree_zone_members"."zone_id" IN (2, 1)
Spree::TaxRate Load (0.1ms) 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.0ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 94 LIMIT 1
Spree::Country Load (0.0ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 155 LIMIT 1
Spree::Country Load (0.0ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 13 LIMIT 1
Spree::Country Load (0.0ms) 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.0ms) 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.0ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 206 LIMIT 1
Spree::Country Load (0.0ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 46 LIMIT 1
Spree::Country Load (0.0ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 211 LIMIT 1
Spree::Country Load (0.0ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 135 LIMIT 1
Spree::Country Load (0.0ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 56 LIMIT 1
Spree::Country Load (0.0ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 207 LIMIT 1
Spree::Country Load (0.0ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 210 LIMIT 1
Spree::Country Load (0.0ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 49 LIMIT 1
Spree::Country Load (0.0ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 204 LIMIT 1
 (2.7ms) commit transaction
Redirected to http://localhost:3000/checkout/delivery
Completed 302 Found in 560ms (ActiveRecord: 19.0ms)
Started GET "/checkout/delivery" for 127.0.0.1 at 2013-07-31 09:58:49 +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.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" = 12 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 (12) ORDER BY spree_adjustments.created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 12 ORDER BY spree_line_items.created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 12
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" = 12 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 7
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (27)
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" = 27 LIMIT 1
 (0.1ms) SELECT COUNT(*) FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 12
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" = 27 AND "spree_stock_locations"."active" = 't'
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.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'
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/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" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
CACHE (0.0ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 7
CACHE (0.0ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (27)
 (0.3ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 27 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" = 27 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" = 17 LIMIT 1
Spree::Product::Translation Load (0.1ms) SELECT "spree_product_translations".* FROM "spree_product_translations" WHERE "spree_product_translations"."spree_product_id" = 17
Spree::Price Load (0.1ms) SELECT "spree_prices".* FROM "spree_prices" WHERE "spree_prices"."variant_id" = 27
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 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" = 7 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" = 7 AND "spree_shipping_rates"."selected" = 't' LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 7 LIMIT 1
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 12 LIMIT 1
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/checkout/_delivery.html.erb (18.9ms)
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 12 LIMIT 1
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 10 AND "spree_adjustments"."adjustable_type" = 'Spree::LineItem'
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/checkout/_summary.html.erb (5.5ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/checkout/edit.html.erb within spree/layouts/spree_application (48.1ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/core/app/views/spree/shared/_routes.html.erb (0.3ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_head.html.erb (10.7ms)
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.2ms) 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" = 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-fcb429304620/frontend/app/views/spree/shared/_search.html.erb (3.6ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_nav_bar.html.erb (8.0ms)
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.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.0ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_main_nav_bar.html.erb (5.9ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_header.html.erb (33.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 => '0e70b64f6d6eb3161e2de39b8253df9c0a201c63'
Spree::Page Load (0.2ms) 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.0ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_footer.html.erb (3.7ms)
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-fcb429304620/frontend/app/views/spree/shared/_google_analytics.html.erb (73.8ms)
Completed 200 OK in 242ms (Views: 214.5ms | ActiveRecord: 4.5ms)
Started GET "/assets/normalize.css?body=1" for 127.0.0.1 at 2013-07-31 09:58:49 +0200
Served asset /normalize.css - 304 Not Modified (0ms)
Started GET "/assets/skeleton.css?body=1" for 127.0.0.1 at 2013-07-31 09:58:49 +0200
Served asset /skeleton.css - 304 Not Modified (0ms)
Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-07-31 09:58:49 +0200
Served asset /jquery_ujs.js - 304 Not Modified (0ms)
Started GET "/assets/store/screen.css?body=1" for 127.0.0.1 at 2013-07-31 09:58:49 +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-07-31 09:58:49 +0200
Served asset /store/spree_frontend.css - 304 Not Modified (0ms)
Started GET "/assets/store/spree_paypal_express.css?body=1" for 127.0.0.1 at 2013-07-31 09:58:49 +0200
Served asset /store/spree_paypal_express.css - 304 Not Modified (0ms)
Started GET "/assets/jsuri.js?body=1" for 127.0.0.1 at 2013-07-31 09:58:49 +0200
Served asset /jsuri.js - 304 Not Modified (0ms)
Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-07-31 09:58:49 +0200
Served asset /jquery.js - 304 Not Modified (0ms)
Started GET "/assets/spree.js?body=1" for 127.0.0.1 at 2013-07-31 09:58:49 +0200
Served asset /spree.js - 304 Not Modified (0ms)
Started GET "/assets/jquery.validate/jquery.validate.min.js?body=1" for 127.0.0.1 at 2013-07-31 09:58:49 +0200
Served asset /jquery.validate/jquery.validate.min.js - 304 Not Modified (0ms)
Started GET "/assets/store/checkout.js?body=1" for 127.0.0.1 at 2013-07-31 09:58:49 +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-07-31 09:58:49 +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-07-31 09:58:49 +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-07-31 09:58:49 +0200
Served asset /store/spree_frontend.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_paypal_express.js?body=1" for 127.0.0.1 at 2013-07-31 09:58:49 +0200
Served asset /store/spree_paypal_express.js - 304 Not Modified (0ms)
Started GET "/assets/store/locale.js?body=1" for 127.0.0.1 at 2013-07-31 09:58:49 +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-07-31 09:58:49 +0200
Served asset /store/spree_i18n.js - 304 Not Modified (0ms)
Started GET "/assets/logo/spree_50.png" for 127.0.0.1 at 2013-07-31 09:58:49 +0200
Served asset /logo/spree_50.png - 304 Not Modified (0ms)
Started PUT "/checkout/update/delivery" for 127.0.0.1 at 2013-07-31 09:59:18 +0200
Processing by Spree::CheckoutController#update as HTML
Parameters: {"utf8"=>"✓", "authenticity_token"=>"x7qYFREW2RI08ppuesUlw0uKvdi7Egk3vjlp648CeVo=", "order"=>{"shipments_attributes"=>{"0"=>{"selected_shipping_rate_id"=>"16", "id"=>"7"}}}, "commit"=>"Save and Continue", "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" = 12 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 (12) ORDER BY spree_adjustments.created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 12 ORDER BY spree_line_items.created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
 (0.1ms) SELECT COUNT(*) FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 12
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" = 27 AND "spree_stock_locations"."active" = 't'
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.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" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
 (0.0ms) begin transaction
Spree::Shipment Load (0.2ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 12 AND "spree_shipments"."id" IN (7)
SQL (0.2ms) UPDATE "spree_shipping_rates" SET "selected" = 'f' WHERE "spree_shipping_rates"."shipment_id" = 7
Spree::ShippingRate Load (0.1ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 7 AND "spree_shipping_rates"."id" = ? LIMIT 1 [["id", "16"]]
 (0.2ms) UPDATE "spree_shipping_rates" SET "selected" = 't', "updated_at" = '2013-07-31 07:59:18.070214' WHERE "spree_shipping_rates"."id" = 16
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."source_id" = 7 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" = 7 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" = 12 LIMIT 1
CACHE (0.0ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 7 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" = 12 LIMIT 1
CACHE (0.0ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 7 AND "spree_shipping_rates"."selected" = 't' LIMIT 1
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 12 LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 12 ORDER BY spree_line_items.created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12
SQL (0.2ms) INSERT INTO "spree_state_changes" ("created_at", "name", "next_state", "previous_state", "stateful_id", "stateful_type", "updated_at", "user_id") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Wed, 31 Jul 2013 07:59:18 UTC +00:00], ["name", "payment"], ["next_state", "balance_due"], ["previous_state", "balance_due"], ["stateful_id", 12], ["stateful_type", "Spree::Order"], ["updated_at", Wed, 31 Jul 2013 07:59:18 UTC +00:00], ["user_id", 1]]
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 12
SQL (0.1ms) UPDATE "spree_shipments" SET "state" = 'pending' WHERE "spree_shipments"."id" = 7
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 7
 (0.0ms) SELECT "spree_shipments"."state" FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 12
SQL (0.1ms) INSERT INTO "spree_state_changes" ("created_at", "name", "next_state", "previous_state", "stateful_id", "stateful_type", "updated_at", "user_id") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Wed, 31 Jul 2013 07:59:18 UTC +00:00], ["name", "shipment"], ["next_state", "pending"], ["previous_state", "pending"], ["stateful_id", 12], ["stateful_type", "Spree::Order"], ["updated_at", Wed, 31 Jul 2013 07:59:18 UTC +00:00], ["user_id", 1]]
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY spree_adjustments.created_at ASC
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 19]]
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" = 12 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 7 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" = 12 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 7
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (27)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 12 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 19
SQL (0.0ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 19
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 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" = 12 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
SQL (0.1ms) UPDATE "spree_orders" SET "payment_state" = 'balance_due', "shipment_state" = 'pending', "item_total" = 14.0, "adjustment_total" = 5.0, "payment_total" = 0.0, "total" = 19.0 WHERE "spree_orders"."id" = 12
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 19]]
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 12 LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 12 ORDER BY spree_line_items.created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12
SQL (0.2ms) INSERT INTO "spree_state_changes" ("created_at", "name", "next_state", "previous_state", "stateful_id", "stateful_type", "updated_at", "user_id") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Wed, 31 Jul 2013 07:59:18 UTC +00:00], ["name", "payment"], ["next_state", "balance_due"], ["previous_state", "balance_due"], ["stateful_id", 12], ["stateful_type", "Spree::Order"], ["updated_at", Wed, 31 Jul 2013 07:59:18 UTC +00:00], ["user_id", 1]]
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 12
SQL (0.1ms) UPDATE "spree_shipments" SET "state" = 'pending' WHERE "spree_shipments"."id" = 7
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 7
 (0.1ms) SELECT "spree_shipments"."state" FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 12
SQL (0.2ms) INSERT INTO "spree_state_changes" ("created_at", "name", "next_state", "previous_state", "stateful_id", "stateful_type", "updated_at", "user_id") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Wed, 31 Jul 2013 07:59:18 UTC +00:00], ["name", "shipment"], ["next_state", "pending"], ["previous_state", "pending"], ["stateful_id", 12], ["stateful_type", "Spree::Order"], ["updated_at", Wed, 31 Jul 2013 07:59:18 UTC +00:00], ["user_id", 1]]
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY spree_adjustments.created_at ASC
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 19]]
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" = 12 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 7 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" = 12 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 7
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (27)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 12 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 19
SQL (0.1ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 19
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 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" = 12 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
SQL (0.1ms) UPDATE "spree_orders" SET "payment_state" = 'balance_due', "shipment_state" = 'pending', "item_total" = 14.0, "adjustment_total" = 5.0, "payment_total" = 0.0, "total" = 19.0 WHERE "spree_orders"."id" = 12
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
 (2.0ms) commit transaction
Spree::Activator Load (0.1ms) SELECT "spree_activators".* FROM "spree_activators" WHERE (starts_at IS NULL OR starts_at < '2013-07-31 07:59:18.124977') AND (expires_at IS NULL OR expires_at > '2013-07-31 07:59:18.125162') AND (event_name LIKE 'spree.checkout.update%')
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
 (0.0ms) begin transaction
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
 (0.2ms) UPDATE "spree_orders" SET "state" = 'payment', "updated_at" = '2013-07-31 07:59:18.131244' WHERE "spree_orders"."id" = 12
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
 (22.0ms) commit transaction
Redirected to http://localhost:3000/checkout/payment
Completed 302 Found in 113ms (ActiveRecord: 31.9ms)
Started GET "/checkout/payment" for 127.0.0.1 at 2013-07-31 09:59:18 +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" = 12 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 (12) ORDER BY spree_adjustments.created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 12 ORDER BY spree_line_items.created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
Spree::Shipment Load (0.2ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 12
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" = 12 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 7
Spree::Variant Load (0.2ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (27)
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" = 27 LIMIT 1
 (0.1ms) SELECT COUNT(*) FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 12
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" = 27 AND "spree_stock_locations"."active" = 't'
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.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'
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/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" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
Spree::PaymentMethod Load (0.1ms) 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" = 11 LIMIT 1
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/checkout/payment/_gateway.html.erb (4.2ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/checkout/payment/_check.html.erb (0.3ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/better_spree_paypal_express-6e4752d0899b/app/views/spree/checkout/payment/_paypal.html.erb (0.9ms)
 (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-fcb429304620/frontend/app/views/spree/checkout/_payment.html.erb (61.5ms)
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
CACHE (0.0ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 12 LIMIT 1
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 10 AND "spree_adjustments"."adjustable_type" = 'Spree::LineItem'
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/checkout/_summary.html.erb (5.3ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/checkout/edit.html.erb within spree/layouts/spree_application (83.7ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/core/app/views/spree/shared/_routes.html.erb (0.3ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_head.html.erb (10.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" = 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-fcb429304620/frontend/app/views/spree/shared/_search.html.erb (4.1ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_nav_bar.html.erb (8.7ms)
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.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.0ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_main_nav_bar.html.erb (6.5ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_header.html.erb (30.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 => '0e70b64f6d6eb3161e2de39b8253df9c0a201c63'
Spree::Page Load (0.2ms) 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.0ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_footer.html.erb (3.4ms)
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-fcb429304620/frontend/app/views/spree/shared/_google_analytics.html.erb (0.8ms)
Completed 200 OK in 268ms (Views: 161.8ms | ActiveRecord: 4.1ms)
Started GET "/assets/normalize.css?body=1" for 127.0.0.1 at 2013-07-31 09:59:18 +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-07-31 09:59:18 +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-07-31 09:59:18 +0200
Served asset /store/screen.css - 304 Not Modified (0ms)
Started GET "/assets/skeleton.css?body=1" for 127.0.0.1 at 2013-07-31 09:59:18 +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-07-31 09:59:18 +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-07-31 09:59:18 +0200
Served asset /jquery.js - 304 Not Modified (0ms)
Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-07-31 09:59:18 +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-07-31 09:59:18 +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-07-31 09:59:18 +0200
Served asset /jsuri.js - 304 Not Modified (0ms)
Started GET "/assets/spree.js?body=1" for 127.0.0.1 at 2013-07-31 09:59:18 +0200
Served asset /spree.js - 304 Not Modified (0ms)
Started GET "/assets/store/checkout.js?body=1" for 127.0.0.1 at 2013-07-31 09:59:18 +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-07-31 09:59:18 +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-07-31 09:59:18 +0200
Served asset /store/cart.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_paypal_express.js?body=1" for 127.0.0.1 at 2013-07-31 09:59:18 +0200
Served asset /store/spree_paypal_express.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_frontend.js?body=1" for 127.0.0.1 at 2013-07-31 09:59:18 +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-07-31 09:59:18 +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-07-31 09:59:18 +0200
Served asset /store/spree_i18n.js - 304 Not Modified (0ms)
Started GET "/assets/logo/spree_50.png" for 127.0.0.1 at 2013-07-31 09:59:18 +0200
Served asset /logo/spree_50.png - 304 Not Modified (0ms)
Started GET "/assets/credit_cards/credit_card.gif" for 127.0.0.1 at 2013-07-31 09:59:18 +0200
Served asset /credit_cards/credit_card.gif - 304 Not Modified (6ms)
Started POST "/paypal?payment_method_id=17" for 127.0.0.1 at 2013-07-31 09:59:22 +0200
Processing by Spree::PaypalController#express as HTML
Parameters: {"authenticity_token"=>"x7qYFREW2RI08ppuesUlw0uKvdi7Egk3vjlp648CeVo=", "payment_method_id"=>"17"}
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" = 12 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 (12) ORDER BY spree_adjustments.created_at ASC
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 12 ORDER BY spree_line_items.created_at ASC
Spree::Product Load (0.2ms) SELECT "spree_products".* FROM "spree_products" INNER JOIN "spree_variants" ON "spree_products"."id" = "spree_variants"."product_id" WHERE "spree_products"."deleted_at" IS NULL AND "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" = 27 LIMIT 1
Spree::Product::Translation Load (0.1ms) SELECT "spree_product_translations".* FROM "spree_product_translations" WHERE "spree_product_translations"."spree_product_id" = 17
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."originator_type" = 'Spree::TaxRate' AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY spree_adjustments.created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."originator_type" = 'Spree::ShippingMethod' ORDER BY spree_adjustments.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", "17"]]
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."originator_type" = 'Spree::ShippingMethod' ORDER BY spree_adjustments.created_at ASC
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."originator_type" = 'Spree::TaxRate' AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY spree_adjustments.created_at ASC
Spree::Address Load (0.1ms) SELECT "spree_addresses".* FROM "spree_addresses" WHERE "spree_addresses"."id" = 11 LIMIT 1
Spree::State Load (0.1ms) SELECT "spree_states".* FROM "spree_states" WHERE "spree_states"."id" = 56 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", "17"]]
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", "17"]]
Redirected to https://www.sandbox.paypal.com/webscr?cmd=_express-checkout&token=EC-3UJ14429W6731031F
Completed 302 Found in 1442ms (ActiveRecord: 2.1ms)
Started GET "/paypal/confirm?payment_method_id=17&token=EC-3UJ14429W6731031F&PayerID=B4ESFV9SMX2DL" for 127.0.0.1 at 2013-07-31 09:59:54 +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"=>"17", "token"=>"EC-3UJ14429W6731031F", "PayerID"=>"B4ESFV9SMX2DL"}
Spree::User Load (0.1ms) 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" = 12 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 (12) ORDER BY spree_adjustments.created_at ASC
 (0.0ms) begin transaction
SQL (0.2ms) INSERT INTO "spree_paypal_express_checkouts" ("payer_id", "token") VALUES (?, ?) [["payer_id", "B4ESFV9SMX2DL"], ["token", "EC-3UJ14429W6731031F"]]
 (1.2ms) 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", "17"]]
 (0.0ms) begin transaction
Spree::Payment Exists (0.1ms) SELECT 1 AS one FROM "spree_payments" WHERE "spree_payments"."identifier" = '9KCP3MQA' LIMIT 1
SQL (0.5ms) 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:7fb3d717ef20,'0.19E2',9(36)>], ["avs_response", nil], ["created_at", Wed, 31 Jul 2013 07:59:54 UTC +00:00], ["cvv_response_code", nil], ["cvv_response_message", nil], ["identifier", "9KCP3MQA"], ["order_id", 12], ["payment_method_id", 17], ["response_code", nil], ["source_id", 3], ["source_type", "Spree::PaypalExpressCheckout"], ["state", "checkout"], ["updated_at", Wed, 31 Jul 2013 07:59:54 UTC +00:00]]
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 12 LIMIT 1
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'checkout' AND (id != 8)
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 12 ORDER BY spree_line_items.created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
SQL (0.2ms) INSERT INTO "spree_state_changes" ("created_at", "name", "next_state", "previous_state", "stateful_id", "stateful_type", "updated_at", "user_id") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Wed, 31 Jul 2013 07:59:54 UTC +00:00], ["name", "payment"], ["next_state", "balance_due"], ["previous_state", "balance_due"], ["stateful_id", 12], ["stateful_type", "Spree::Order"], ["updated_at", Wed, 31 Jul 2013 07:59:54 UTC +00:00], ["user_id", 1]]
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 12
SQL (0.1ms) UPDATE "spree_shipments" SET "state" = 'pending' WHERE "spree_shipments"."id" = 7
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 7
 (0.0ms) SELECT "spree_shipments"."state" FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 12
SQL (0.1ms) INSERT INTO "spree_state_changes" ("created_at", "name", "next_state", "previous_state", "stateful_id", "stateful_type", "updated_at", "user_id") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Wed, 31 Jul 2013 07:59:54 UTC +00:00], ["name", "shipment"], ["next_state", "pending"], ["previous_state", "pending"], ["stateful_id", 12], ["stateful_type", "Spree::Order"], ["updated_at", Wed, 31 Jul 2013 07:59:54 UTC +00:00], ["user_id", 1]]
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY spree_adjustments.created_at ASC
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 19]]
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" = 12 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 7 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" = 12 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 7
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (27)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 12 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 19
SQL (0.3ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 19
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 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" = 12 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
SQL (0.1ms) UPDATE "spree_orders" SET "payment_state" = 'balance_due', "shipment_state" = 'pending', "item_total" = 14.0, "adjustment_total" = 5.0, "payment_total" = 0.0, "total" = 19.0 WHERE "spree_orders"."id" = 12
 (2.2ms) commit transaction
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12
 (0.0ms) begin transaction
Spree::Payment Exists (0.1ms) SELECT 1 AS one FROM "spree_payments" WHERE "spree_payments"."identifier" = 'EMFE5JTX' LIMIT 1
 (0.2ms) UPDATE "spree_payments" SET "state" = 'processing', "identifier" = 'EMFE5JTX', "updated_at" = '2013-07-31 07:59:54.461284' WHERE "spree_payments"."id" = 8
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
SQL (0.2ms) INSERT INTO "spree_state_changes" ("created_at", "name", "next_state", "previous_state", "stateful_id", "stateful_type", "updated_at", "user_id") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Wed, 31 Jul 2013 07:59:54 UTC +00:00], ["name", "payment"], ["next_state", "balance_due"], ["previous_state", "balance_due"], ["stateful_id", 12], ["stateful_type", "Spree::Order"], ["updated_at", Wed, 31 Jul 2013 07:59:54 UTC +00:00], ["user_id", 1]]
SQL (0.1ms) UPDATE "spree_shipments" SET "state" = 'pending' WHERE "spree_shipments"."id" = 7
 (0.1ms) SELECT "spree_shipments"."state" FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 12
SQL (0.1ms) INSERT INTO "spree_state_changes" ("created_at", "name", "next_state", "previous_state", "stateful_id", "stateful_type", "updated_at", "user_id") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Wed, 31 Jul 2013 07:59:54 UTC +00:00], ["name", "shipment"], ["next_state", "pending"], ["previous_state", "pending"], ["stateful_id", 12], ["stateful_type", "Spree::Order"], ["updated_at", Wed, 31 Jul 2013 07:59:54 UTC +00:00], ["user_id", 1]]
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY spree_adjustments.created_at ASC
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 19]]
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" = 12 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 7 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" = 12 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 7
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (27)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 12 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 19
SQL (0.2ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 19
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 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" = 12 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
SQL (0.1ms) UPDATE "spree_orders" SET "payment_state" = 'balance_due', "shipment_state" = 'pending', "item_total" = 14.0, "adjustment_total" = 5.0, "payment_total" = 0.0, "total" = 19.0 WHERE "spree_orders"."id" = 12
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."originator_type" = 'Spree::ShippingMethod' ORDER BY spree_adjustments.created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."originator_type" = 'Spree::TaxRate' AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY spree_adjustments.created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' AND "spree_adjustments"."originator_type" = 'Spree::PromotionAction' ORDER BY spree_adjustments.created_at ASC
Spree::Address Load (0.1ms) SELECT "spree_addresses".* FROM "spree_addresses" WHERE "spree_addresses"."id" = 11 LIMIT 1
Spree::State Load (0.0ms) SELECT "spree_states".* FROM "spree_states" WHERE "spree_states"."id" = 56 LIMIT 1
Spree::Country Load (0.0ms) 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" = 12 LIMIT 1
CACHE (0.0ms) SELECT "spree_states".* FROM "spree_states" WHERE "spree_states"."id" = 56 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", Wed, 31 Jul 2013 07:59:57 UTC +00:00], ["details", "--- !ruby/object {}\n"], ["source_id", 8], ["source_type", "Spree::Payment"], ["updated_at", Wed, 31 Jul 2013 07:59:57 UTC +00:00]]
Spree::Payment Exists (0.1ms) SELECT 1 AS one FROM "spree_payments" WHERE "spree_payments"."identifier" = '8AEMRVF5' LIMIT 1
 (0.1ms) UPDATE "spree_payments" SET "state" = 'completed', "identifier" = '8AEMRVF5', "updated_at" = '2013-07-31 07:59:57.979989' WHERE "spree_payments"."id" = 8
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
SQL (0.2ms) INSERT INTO "spree_state_changes" ("created_at", "name", "next_state", "previous_state", "stateful_id", "stateful_type", "updated_at", "user_id") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Wed, 31 Jul 2013 07:59:57 UTC +00:00], ["name", "payment"], ["next_state", "paid"], ["previous_state", "balance_due"], ["stateful_id", 12], ["stateful_type", "Spree::Order"], ["updated_at", Wed, 31 Jul 2013 07:59:57 UTC +00:00], ["user_id", 1]]
SQL (0.1ms) UPDATE "spree_shipments" SET "state" = 'pending' WHERE "spree_shipments"."id" = 7
 (0.1ms) SELECT "spree_shipments"."state" FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 12
SQL (0.2ms) INSERT INTO "spree_state_changes" ("created_at", "name", "next_state", "previous_state", "stateful_id", "stateful_type", "updated_at", "user_id") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Wed, 31 Jul 2013 07:59:57 UTC +00:00], ["name", "shipment"], ["next_state", "pending"], ["previous_state", "pending"], ["stateful_id", 12], ["stateful_type", "Spree::Order"], ["updated_at", Wed, 31 Jul 2013 07:59:57 UTC +00:00], ["user_id", 1]]
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY spree_adjustments.created_at ASC
Spree::Adjustment Load (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."id" = ? LIMIT 1 [["id", 19]]
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" = 12 LIMIT 1
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."id" = 7 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" = 12 LIMIT 1
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 7
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (27)
Spree::Calculator Load (0.1ms) SELECT "spree_calculators".* FROM "spree_calculators" WHERE "spree_calculators"."calculable_id" = 12 AND "spree_calculators"."calculable_type" = 'Spree::ShippingMethod' LIMIT 1
SQL (0.1ms) UPDATE "spree_adjustments" SET "amount" = 5.0 WHERE "spree_adjustments"."id" = 19
SQL (0.1ms) UPDATE "spree_adjustments" SET "eligible" = 't' WHERE "spree_adjustments"."id" = 19
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 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" = 12 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
SQL (0.1ms) UPDATE "spree_orders" SET "payment_state" = 'paid', "shipment_state" = 'pending', "item_total" = 14.0, "adjustment_total" = 5.0, "payment_total" = 19.0, "total" = 19.0 WHERE "spree_orders"."id" = 12
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 12 ORDER BY spree_line_items.created_at ASC
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
 (0.1ms) UPDATE "spree_orders" SET "payment_total" = 19.0, "state" = 'complete', "updated_at" = '2013-07-31 07:59:58.003450' WHERE "spree_orders"."id" = 12
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
SQL (0.1ms) UPDATE "spree_orders" SET "updated_at" = '2013-07-31 07:59:58.008321', "completed_at" = '2013-07-31 07:59:58.008321' WHERE "spree_orders"."id" = 12
SQL (0.0ms) UPDATE "spree_adjustments" SET "state" = 'closed' WHERE "spree_adjustments"."id" = 19
SQL (0.1ms) INSERT INTO "spree_state_changes" ("created_at", "name", "next_state", "previous_state", "stateful_id", "stateful_type", "updated_at", "user_id") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Wed, 31 Jul 2013 07:59:58 UTC +00:00], ["name", "payment"], ["next_state", "paid"], ["previous_state", "balance_due"], ["stateful_id", 12], ["stateful_type", "Spree::Order"], ["updated_at", Wed, 31 Jul 2013 07:59:58 UTC +00:00], ["user_id", 1]]
Spree::Shipment Load (0.1ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 12
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 7
SQL (0.0ms) UPDATE "spree_shipments" SET "state" = 'ready' WHERE "spree_shipments"."id" = 7
SQL (0.1ms) UPDATE "spree_inventory_units" SET "pending" = 'f' WHERE "spree_inventory_units"."id" = 7
Spree::InventoryUnit Load (0.1ms) SELECT "spree_inventory_units".* FROM "spree_inventory_units" WHERE "spree_inventory_units"."shipment_id" = 7
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" IN (27)
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."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" = 27 ORDER BY id LIMIT 1
Spree::StockItem Load (0.1ms) SELECT "spree_stock_items".* FROM "spree_stock_items" WHERE "spree_stock_items"."id" = 27 LIMIT 1
SQL (0.3ms) INSERT INTO "spree_stock_movements" ("action", "created_at", "originator_id", "originator_type", "quantity", "stock_item_id", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["action", nil], ["created_at", Wed, 31 Jul 2013 07:59:58 UTC +00:00], ["originator_id", 7], ["originator_type", "Spree::Shipment"], ["quantity", -1], ["stock_item_id", 27], ["updated_at", Wed, 31 Jul 2013 07:59:58 UTC +00:00]]
Spree::StockItem Load (0.1ms) SELECT "spree_stock_items".* FROM "spree_stock_items" WHERE "spree_stock_items"."id" = ? LIMIT 1 [["id", 27]]
SQL (0.2ms) SELECT "spree_inventory_units"."id" AS t0_r0, "spree_inventory_units"."state" AS t0_r1, "spree_inventory_units"."variant_id" AS t0_r2, "spree_inventory_units"."order_id" AS t0_r3, "spree_inventory_units"."shipment_id" AS t0_r4, "spree_inventory_units"."return_authorization_id" AS t0_r5, "spree_inventory_units"."created_at" AS t0_r6, "spree_inventory_units"."updated_at" AS t0_r7, "spree_inventory_units"."pending" AS t0_r8, "spree_shipments"."id" AS t1_r0, "spree_shipments"."tracking" AS t1_r1, "spree_shipments"."number" AS t1_r2, "spree_shipments"."cost" AS t1_r3, "spree_shipments"."shipped_at" AS t1_r4, "spree_shipments"."order_id" AS t1_r5, "spree_shipments"."address_id" AS t1_r6, "spree_shipments"."state" AS t1_r7, "spree_shipments"."created_at" AS t1_r8, "spree_shipments"."updated_at" AS t1_r9, "spree_shipments"."stock_location_id" AS t1_r10 FROM "spree_inventory_units" LEFT OUTER JOIN "spree_shipments" ON "spree_shipments"."id" = "spree_inventory_units"."shipment_id" WHERE "spree_inventory_units"."variant_id" = 27 AND "spree_inventory_units"."state" = 'backordered' AND (spree_shipments.state != 'canceled') ORDER BY spree_inventory_units.created_at ASC
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" = 27 LIMIT 1
Spree::StockItem Exists (0.0ms) SELECT 1 AS one FROM "spree_stock_items" WHERE ("spree_stock_items"."variant_id" = 27 AND "spree_stock_items"."id" != 27 AND "spree_stock_items"."stock_location_id" = 1) LIMIT 1
 (0.1ms) UPDATE "spree_stock_items" SET "count_on_hand" = 8, "updated_at" = '2013-07-31 07:59:58.044918' WHERE "spree_stock_items"."id" = 27
 (0.0ms) SELECT "spree_shipments"."state" FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 12
SQL (0.1ms) INSERT INTO "spree_state_changes" ("created_at", "name", "next_state", "previous_state", "stateful_id", "stateful_type", "updated_at", "user_id") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Wed, 31 Jul 2013 07:59:58 UTC +00:00], ["name", "shipment"], ["next_state", "ready"], ["previous_state", "pending"], ["stateful_id", 12], ["stateful_type", "Spree::Order"], ["updated_at", Wed, 31 Jul 2013 07:59:58 UTC +00:00], ["user_id", 1]]
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
 (0.1ms) UPDATE "spree_orders" SET "payment_state" = 'paid', "shipment_state" = 'ready', "updated_at" = '2013-07-31 07:59:58.051622' WHERE "spree_orders"."id" = 12
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = ? LIMIT 1 [["id", 12]]
Spree::LineItem Load (0.2ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 12 ORDER BY spree_line_items.created_at ASC
Spree::Variant Load (0.1ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" = 27 LIMIT 1
Spree::Product Load (0.1ms) SELECT "spree_products".* FROM "spree_products" WHERE "spree_products"."id" = 17 LIMIT 1
Spree::Product::Translation Load (0.1ms) SELECT "spree_product_translations".* FROM "spree_product_translations" WHERE "spree_product_translations"."spree_product_id" = 17
Spree::OptionValue Load (0.1ms) 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" = 27 ORDER BY spree_option_types.position asc
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 12 LIMIT 1
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/core/app/views/spree/order_mailer/confirm_email.text.erb (13.1ms)
Spree::Preference Load (0.1ms) SELECT "spree_preferences".* FROM "spree_preferences" WHERE "spree_preferences"."key" = 'spree/app_configuration/intercept_email' LIMIT 1
Sent mail to spree@example.com (5ms)
Date: Wed, 31 Jul 2013 09:59:58 +0200
From: spree@example.com
To: spree@example.com
Message-ID: <51f8c3fe4fb1b_cf873fd9ed296a888713@p097-114.ujaen.es.mail>
Subject: Spree Demo Site Order Confirmation #R812676113
Mime-Version: 1.0
Content-Type: text/plain;
charset=UTF-8
Content-Transfer-Encoding: base64
RGVhciBDdXN0b21lciwKClBsZWFzZSByZXZpZXcgYW5kIHJldGFpbiB0aGUg
Zm9sbG93aW5nIG9yZGVyIGluZm9ybWF0aW9uIGZvciB5b3VyIHJlY29yZHMu
Cgo9PT09PT09PT09PT09PT09PT09PT09PT09PT09PT09PT09PT09PT09PT09
PT09PT09PT09PT09PT09PT0KT3JkZXIgU3VtbWFyeQo9PT09PT09PT09PT09
PT09PT09PT09PT09PT09PT09PT09PT09PT09PT09PT09PT09PT09PT09PT09
PT0KICBST1ItMDAwMTEgUnVieSBvbiBSYWlscyBUb3RlICAoMSkgQCAxNC4w
MCDigqwgPSAxNC4wMCDigqwKPT09PT09PT09PT09PT09PT09PT09PT09PT09
PT09PT09PT09PT09PT09PT09PT09PT09PT09PT09PT09ClN1YnRvdGFsOiAx
NC4wMCDigqwKCiAgU2hpcHBpbmcgNS4wMCDigqwKCk9yZGVyIFRvdGFsOiAx
OS4wMCDigqwKClRoYW5rIHlvdSBmb3IgeW91ciBidXNpbmVzcy4K
SQL (0.2ms) INSERT INTO "spree_state_changes" ("created_at", "name", "next_state", "previous_state", "stateful_id", "stateful_type", "updated_at", "user_id") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Wed, 31 Jul 2013 07:59:58 UTC +00:00], ["name", "order"], ["next_state", "complete"], ["previous_state", "cart"], ["stateful_id", 12], ["stateful_type", "Spree::Order"], ["updated_at", Wed, 31 Jul 2013 07:59:58 UTC +00:00], ["user_id", 1]]
 (2.7ms) commit transaction
Spree::TokenizedPermission Load (0.1ms) SELECT "spree_tokenized_permissions".* FROM "spree_tokenized_permissions" WHERE "spree_tokenized_permissions"."permissable_id" = 12 AND "spree_tokenized_permissions"."permissable_type" = 'Spree::Order' LIMIT 1
Redirected to http://localhost:3000/orders/R812676113?token=af13563596af912a
Completed 302 Found in 4029ms (ActiveRecord: 21.3ms)
Started GET "/orders/R812676113?token=af13563596af912a" for 127.0.0.1 at 2013-07-31 09:59:58 +0200
Spree::Page Exists (0.2ms) SELECT 1 AS one FROM "spree_pages" WHERE "spree_pages"."visible" = 't' AND (("spree_pages"."slug" = 'orders/R812676113' OR "spree_pages"."slug" = '/orders/R812676113')) 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" = 'orders/R812676113' OR "spree_pages"."slug" = '/orders/R812676113')) ORDER BY position ASC LIMIT 1
Processing by Spree::OrdersController#show as HTML
Parameters: {"token"=>"af13563596af912a", "id"=>"R812676113"}
Spree::User Load (0.1ms) 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" = 12 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 (12) ORDER BY spree_adjustments.created_at ASC
 (0.0ms) begin transaction
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."number" = 'R708200244' LIMIT 1
 (0.1ms) SELECT COUNT(*) FROM "spree_orders" WHERE (spree_orders.number LIKE 'R708200244%')
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" IS NULL AND "spree_payments"."state" = 'completed'
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" IS NULL AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
SQL (0.4ms) INSERT INTO "spree_orders" ("adjustment_total", "bill_address_id", "completed_at", "created_at", "created_by_id", "currency", "email", "item_total", "last_ip_address", "number", "payment_state", "payment_total", "ship_address_id", "shipment_state", "shipping_method_id", "special_instructions", "state", "total", "updated_at", "user_id") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["adjustment_total", #<BigDecimal:7fb3da7c7c08,'0.0',9(36)>], ["bill_address_id", nil], ["completed_at", nil], ["created_at", Wed, 31 Jul 2013 07:59:58 UTC +00:00], ["created_by_id", 1], ["currency", "EUR"], ["email", "spree@example.com"], ["item_total", #<BigDecimal:7fb3da7c7ed8,'0.0',9(36)>], ["last_ip_address", nil], ["number", "R708200244"], ["payment_state", nil], ["payment_total", #<BigDecimal:7fb3da7c7960,'0.0',9(36)>], ["ship_address_id", nil], ["shipment_state", nil], ["shipping_method_id", nil], ["special_instructions", nil], ["state", "cart"], ["total", #<BigDecimal:7fb3da7ae2f8,'0.0',9(27)>], ["updated_at", Wed, 31 Jul 2013 07:59:58 UTC +00:00], ["user_id", 1]]
Spree::TokenizedPermission Load (0.1ms) SELECT "spree_tokenized_permissions".* FROM "spree_tokenized_permissions" WHERE "spree_tokenized_permissions"."permissable_id" = 13 AND "spree_tokenized_permissions"."permissable_type" = 'Spree::Order' LIMIT 1
SQL (0.2ms) INSERT INTO "spree_tokenized_permissions" ("created_at", "permissable_id", "permissable_type", "token", "updated_at") VALUES (?, ?, ?, ?, ?) [["created_at", Wed, 31 Jul 2013 07:59:58 UTC +00:00], ["permissable_id", 13], ["permissable_type", "Spree::Order"], ["token", "295d40b8fd11d2f5"], ["updated_at", Wed, 31 Jul 2013 07:59:58 UTC +00:00]]
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 13 AND "spree_adjustments"."originator_type" = 'Spree::TaxRate' AND "spree_adjustments"."adjustable_type" = 'Spree::Order' ORDER BY spree_adjustments.created_at ASC
Spree::Zone Load (0.1ms) SELECT "spree_zones".* FROM "spree_zones" ORDER BY zone_members_count, created_at
Spree::ZoneMember Load (0.2ms) SELECT "spree_zone_members".* FROM "spree_zone_members" WHERE "spree_zone_members"."zone_id" IN (2, 1)
Spree::Zone Load (0.1ms) SELECT "spree_zones".* FROM "spree_zones" WHERE "spree_zones"."default_tax" = 't' LIMIT 1
 (1.7ms) commit transaction
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."number" = 'R812676113' 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_orders".* FROM "spree_orders" WHERE "spree_orders"."number" = 'R812676113' LIMIT 1
Spree::Payment Load (0.2ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
Spree::LineItem Load (0.1ms) SELECT "spree_line_items".* FROM "spree_line_items" WHERE "spree_line_items"."order_id" = 12 ORDER BY spree_line_items.created_at ASC
Spree::Adjustment Load (0.2ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
Spree::Payment Load (0.1ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12
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" = 17 LIMIT 1
Spree::Address Load (0.1ms) SELECT "spree_addresses".* FROM "spree_addresses" WHERE "spree_addresses"."id" = 12 LIMIT 1
Spree::State Load (0.1ms) SELECT "spree_states".* FROM "spree_states" WHERE "spree_states"."id" = 56 LIMIT 1
Spree::Country Load (0.1ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 175 LIMIT 1
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/core/app/views/spree/shared/_address.html.erb (2.3ms)
Spree::Address Load (0.1ms) SELECT "spree_addresses".* FROM "spree_addresses" WHERE "spree_addresses"."id" = 11 LIMIT 1
CACHE (0.0ms) SELECT "spree_states".* FROM "spree_states" WHERE "spree_states"."id" = 56 LIMIT 1
CACHE (0.0ms) SELECT "spree_countries".* FROM "spree_countries" WHERE "spree_countries"."id" = 175 LIMIT 1
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/core/app/views/spree/shared/_address.html.erb (1.1ms)
CACHE (0.0ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND "spree_payments"."state" = 'completed'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
Spree::Shipment Load (0.2ms) SELECT "spree_shipments".* FROM "spree_shipments" WHERE "spree_shipments"."order_id" = 12
Spree::StockLocation Load (0.1ms) SELECT "spree_stock_locations".* FROM "spree_stock_locations" WHERE "spree_stock_locations"."id" = 1 LIMIT 1
Spree::ShippingRate Load (0.1ms) SELECT "spree_shipping_rates".* FROM "spree_shipping_rates" WHERE "spree_shipping_rates"."shipment_id" = 7 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" = 12 LIMIT 1
Spree::Payment Load (0.2ms) SELECT "spree_payments".* FROM "spree_payments" WHERE "spree_payments"."order_id" = 12 AND (state NOT IN ('failed','invalid'))
Spree::PaypalExpressCheckout Load (0.1ms) SELECT "spree_paypal_express_checkouts".* FROM "spree_paypal_express_checkouts" WHERE "spree_paypal_express_checkouts"."id" = 3 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" = 17 LIMIT 1
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/core/app/views/spree/payments/_payment.html.erb (9.0ms)
Spree::Variant Load (0.2ms) SELECT "spree_variants".* FROM "spree_variants" WHERE "spree_variants"."deleted_at" IS NULL AND "spree_variants"."id" = 27 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" = 27 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" = 17 LIMIT 1
Spree::Product::Translation Load (0.1ms) SELECT "spree_product_translations".* FROM "spree_product_translations" WHERE "spree_product_translations"."spree_product_id" = 17
Spree::OptionValue Load (0.1ms) SELECT "spree_option_values".* FROM "spree_option_values" 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" = 27
Spree::Adjustment Load (0.1ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 10 AND "spree_adjustments"."adjustable_type" = 'Spree::LineItem'
CACHE (0.0ms) SELECT "spree_adjustments".* FROM "spree_adjustments" WHERE "spree_adjustments"."adjustable_id" = 12 AND "spree_adjustments"."adjustable_type" = 'Spree::Order' AND "spree_adjustments"."eligible" = 't' ORDER BY spree_adjustments.created_at ASC
Spree::Order Load (0.1ms) SELECT "spree_orders".* FROM "spree_orders" WHERE "spree_orders"."id" = 12 LIMIT 1
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_order_details.html.erb (55.6ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/orders/show.html.erb within spree/layouts/spree_application (61.4ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/core/app/views/spree/shared/_routes.html.erb (0.3ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_head.html.erb (12.3ms)
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.2ms) 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" = 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-fcb429304620/frontend/app/views/spree/shared/_search.html.erb (3.9ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_nav_bar.html.erb (8.6ms)
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.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.0ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_main_nav_bar.html.erb (5.6ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_header.html.erb (30.5ms)
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 => '0e70b64f6d6eb3161e2de39b8253df9c0a201c63'
Spree::Page Load (0.2ms) 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.0ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_footer.html.erb (3.6ms)
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-fcb429304620/frontend/app/views/spree/shared/_google_analytics.html.erb (0.6ms)
Completed 200 OK in 169ms (Views: 140.3ms | ActiveRecord: 8.0ms)
Started GET "/assets/normalize.css?body=1" for 127.0.0.1 at 2013-07-31 09:59:58 +0200
Served asset /normalize.css - 304 Not Modified (0ms)
Started GET "/assets/skeleton.css?body=1" for 127.0.0.1 at 2013-07-31 09:59:58 +0200
Served asset /skeleton.css - 304 Not Modified (0ms)
Started GET "/assets/store/screen.css?body=1" for 127.0.0.1 at 2013-07-31 09:59:58 +0200
Served asset /store/screen.css - 304 Not Modified (0ms)
Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-07-31 09:59:58 +0200
Served asset /jquery_ujs.js - 304 Not Modified (0ms)
Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-07-31 09:59:58 +0200
Served asset /jquery.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_frontend.css?body=1" for 127.0.0.1 at 2013-07-31 09:59:58 +0200
Served asset /store/spree_frontend.css - 304 Not Modified (0ms)
Started GET "/assets/store/spree_paypal_express.css?body=1" for 127.0.0.1 at 2013-07-31 09:59:58 +0200
Served asset /store/spree_paypal_express.css - 304 Not Modified (0ms)
Started GET "/assets/jquery.validate/jquery.validate.min.js?body=1" for 127.0.0.1 at 2013-07-31 09:59:58 +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-07-31 09:59:58 +0200
Served asset /jsuri.js - 304 Not Modified (0ms)
Started GET "/assets/spree.js?body=1" for 127.0.0.1 at 2013-07-31 09:59:58 +0200
Served asset /spree.js - 304 Not Modified (0ms)
Started GET "/assets/store/checkout.js?body=1" for 127.0.0.1 at 2013-07-31 09:59:58 +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-07-31 09:59:59 +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-07-31 09:59:59 +0200
Served asset /store/cart.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_paypal_express.js?body=1" for 127.0.0.1 at 2013-07-31 09:59:59 +0200
Served asset /store/spree_paypal_express.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_frontend.js?body=1" for 127.0.0.1 at 2013-07-31 09:59:59 +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-07-31 09:59:59 +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-07-31 09:59:59 +0200
Served asset /store/spree_i18n.js - 304 Not Modified (0ms)
Started GET "/assets/logo/spree_50.png" for 127.0.0.1 at 2013-07-31 09:59:59 +0200
Served asset /logo/spree_50.png - 304 Not Modified (0ms)
Started GET "/" for 127.0.0.1 at 2013-07-31 10:00:16 +0200
Spree::Page Load (0.2ms) 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.1ms) 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" = 13 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 (13) ORDER BY spree_adjustments.created_at ASC
Spree::Taxonomy Load (0.2ms) SELECT "spree_taxonomies".* FROM "spree_taxonomies" ORDER BY spree_taxonomies.position
Spree::Taxon Load (0.1ms) SELECT "spree_taxons".* FROM "spree_taxons" WHERE "spree_taxons"."parent_id" IS NULL AND "spree_taxons"."taxonomy_id" IN (3, 4)
Spree::Taxon Load (0.2ms) SELECT "spree_taxons".* FROM "spree_taxons" WHERE "spree_taxons"."parent_id" IN (12, 13) ORDER BY lft
Spree::Taxonomy::Translation Load (0.1ms) SELECT "spree_taxonomy_translations".* FROM "spree_taxonomy_translations" WHERE "spree_taxonomy_translations"."spree_taxonomy_id" = 3
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 14
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 15
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 16
Spree::Taxonomy::Translation Load (0.1ms) SELECT "spree_taxonomy_translations".* FROM "spree_taxonomy_translations" WHERE "spree_taxonomy_translations"."spree_taxonomy_id" = 4
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 19
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 20
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 21
Spree::Taxon::Translation Load (0.1ms) SELECT "spree_taxon_translations".* FROM "spree_taxon_translations" WHERE "spree_taxon_translations"."spree_taxon_id" = 22
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_taxonomies.html.erb (14.3ms)
SQL (0.3ms) 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-07-31 08:00:16.775384') AND ("spree_products".available_on <= '2013-07-31 08:00:16.775625') 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 (17, 19, 20, 21, 22, 23, 24, 25, 26, 27, 28, 29) AND ("spree_products".deleted_at IS NULL or "spree_products".deleted_at >= '2013-07-31 08:00:16.775384') AND ("spree_products".available_on <= '2013-07-31 08:00:16.775625') 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-07-31 08:00:16.775384') AND ("spree_products".available_on <= '2013-07-31 08:00:16.775625') 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 (17, 19, 20, 21, 22, 23, 24, 25, 26, 27, 28, 29) AND ("spree_products".deleted_at IS NULL or "spree_products".deleted_at >= '2013-07-31 08:00:16.775384') AND ("spree_products".available_on <= '2013-07-31 08:00:16.775625') 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-07-31 08:00:16.775384') AND ("spree_products".available_on <= '2013-07-31 08:00:16.775625') 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 (17, 19, 20, 21, 22, 23, 24, 25, 26, 27, 28, 29) AND ("spree_products".deleted_at IS NULL or "spree_products".deleted_at >= '2013-07-31 08:00:16.775384') AND ("spree_products".available_on <= '2013-07-31 08:00:16.775625') AND (spree_prices.amount IS NOT NULL)
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 27 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" = 27 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" = 17
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 29 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" = 29 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" = 19
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 30 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" = 30 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" = 20
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 31 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" = 31 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" = 21
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 32 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" = 32 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" = 22
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 33 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" = 33 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" = 23
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 34 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" = 34 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" = 24
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 35 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" = 35 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" = 25
 (0.3ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 36 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" = 36 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" = 26
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 37 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" = 37 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" = 27
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 38 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" = 38 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" = 28
 (0.2ms) SELECT COUNT(*) FROM "spree_assets" WHERE "spree_assets"."type" IN ('Spree::Image') AND "spree_assets"."viewable_id" = 39 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" = 39 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" = 29
 (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-07-31 08:00:16.775384') AND ("spree_products".available_on <= '2013-07-31 08:00:16.775625') AND (spree_prices.amount IS NOT NULL)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_products.html.erb (188.1ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/home/index.html.erb within spree/layouts/spree_application (209.9ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/core/app/views/spree/shared/_routes.html.erb (0.4ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_head.html.erb (11.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" = 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-fcb429304620/frontend/app/views/spree/shared/_search.html.erb (4.5ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_nav_bar.html.erb (9.1ms)
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" = 13
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.0ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_main_nav_bar.html.erb (6.9ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_header.html.erb (32.8ms)
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.1ms) 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-fcb429304620/frontend/app/views/spree/shared/_sidebar.html.erb (1.4ms)
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 => '0e70b64f6d6eb3161e2de39b8253df9c0a201c63'
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.0ms)
Rendered /Users/vlledo/.rvm/gems/ruby-2.0.0-p195/bundler/gems/spree-fcb429304620/frontend/app/views/spree/shared/_footer.html.erb (4.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-fcb429304620/frontend/app/views/spree/shared/_google_analytics.html.erb (0.8ms)
Completed 200 OK in 308ms (Views: 292.2ms | ActiveRecord: 10.9ms)
Started GET "/assets/normalize.css?body=1" for 127.0.0.1 at 2013-07-31 10:00:17 +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-07-31 10:00:17 +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-07-31 10:00:17 +0200
Served asset /skeleton.css - 304 Not Modified (0ms)
Started GET "/assets/store/screen.css?body=1" for 127.0.0.1 at 2013-07-31 10:00:17 +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-07-31 10:00:17 +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-07-31 10:00:17 +0200
Served asset /jquery.js - 304 Not Modified (0ms)
Started GET "/assets/store/checkout.js?body=1" for 127.0.0.1 at 2013-07-31 10:00:17 +0200
Served asset /store/checkout.js - 304 Not Modified (0ms)
Started GET "/assets/spree.js?body=1" for 127.0.0.1 at 2013-07-31 10:00:17 +0200
Served asset /spree.js - 304 Not Modified (0ms)
Started GET "/assets/jquery.validate/jquery.validate.min.js?body=1" for 127.0.0.1 at 2013-07-31 10:00:17 +0200
Served asset /jquery.validate/jquery.validate.min.js - 304 Not Modified (0ms)
Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-07-31 10:00:17 +0200
Served asset /jquery_ujs.js - 304 Not Modified (0ms)
Started GET "/assets/jsuri.js?body=1" for 127.0.0.1 at 2013-07-31 10:00:17 +0200
Served asset /jsuri.js - 304 Not Modified (0ms)
Started GET "/assets/store/product.js?body=1" for 127.0.0.1 at 2013-07-31 10:00:17 +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-07-31 10:00:17 +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-07-31 10:00:17 +0200
Served asset /store/spree_frontend.js - 304 Not Modified (0ms)
Started GET "/assets/store/spree_paypal_express.js?body=1" for 127.0.0.1 at 2013-07-31 10:00:17 +0200
Served asset /store/spree_paypal_express.js - 304 Not Modified (0ms)
Started GET "/assets/store/locale.js?body=1" for 127.0.0.1 at 2013-07-31 10:00:17 +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-07-31 10:00:17 +0200
Served asset /store/spree_i18n.js - 304 Not Modified (0ms)
Started GET "/assets/logo/spree_50.png" for 127.0.0.1 at 2013-07-31 10:00:17 +0200
Served asset /logo/spree_50.png - 304 Not Modified (0ms)
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment