Connecting to database specified by database.yml Connecting to database specified by database.yml  (0.1ms) select sqlite_version(*)  (107.8ms) CREATE TABLE "schema_migrations" ("version" varchar(255) NOT NULL)  (71.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.3ms) SELECT "schema_migrations"."version" FROM "schema_migrations" Migrating to CreateProducts (20130320132244)  (0.0ms) begin transaction  (0.4ms) CREATE TABLE "products" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar(255), "price" integer, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)  (0.1ms) INSERT INTO "schema_migrations" ("version") VALUES ('20130320132244')  (25.5ms) commit transaction  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations"  Connecting to database specified by database.yml Started GET "/" for 127.0.0.1 at 2013-03-20 14:24:58 +0100 ActionController::RoutingError (No route matches [GET] "/"): actionpack (3.2.13) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.13) lib/rails/rack/logger.rb:32:in `call_app' railties (3.2.13) lib/rails/rack/logger.rb:16:in `block in call' activesupport (3.2.13) lib/active_support/tagged_logging.rb:22:in `tagged' railties (3.2.13) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' rack (1.4.5) lib/rack/runtime.rb:17:in `call' activesupport (3.2.13) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.5) lib/rack/lock.rb:15:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/static.rb:63:in `call' railties (3.2.13) lib/rails/engine.rb:479:in `call' railties (3.2.13) lib/rails/application.rb:223:in `call' rack (1.4.5) lib/rack/content_length.rb:14:in `call' railties (3.2.13) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (31.5ms) Started GET "/products" for 127.0.0.1 at 2013-03-20 14:25:03 +0100 Processing by ProductsController#index as HTML Product Load (0.1ms) SELECT "products".* FROM "products"  Rendered products/index.html.erb within layouts/application (1.5ms) Compiled products.css (0ms) (pid 17351) Compiled scaffold.css (0ms) (pid 17351) Compiled application.css (18ms) (pid 17351) Compiled jquery.js (2ms) (pid 17351) Compiled jquery_ujs.js (3ms) (pid 17351) Compiled products.js (0ms) (pid 17351) Compiled application.js (78ms) (pid 17351) Completed 200 OK in 258ms (Views: 188.1ms | ActiveRecord: 1.9ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-20 14:25:03 +0100 Served asset /application.css - 200 OK (25ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-20 14:25:03 +0100 Served asset /jquery_ujs.js - 200 OK (4ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-20 14:25:03 +0100 Served asset /scaffold.css - 200 OK (1ms) Started GET "/assets/products.js?body=1" for 127.0.0.1 at 2013-03-20 14:25:03 +0100 Served asset /products.js - 200 OK (1ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-20 14:25:03 +0100 Served asset /jquery.js - 200 OK (1ms) Started GET "/assets/products.css?body=1" for 127.0.0.1 at 2013-03-20 14:25:03 +0100 Served asset /products.css - 200 OK (1ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-20 14:25:03 +0100 Served asset /application.js - 200 OK (4ms) Started GET "/products/new" for 127.0.0.1 at 2013-03-20 14:25:10 +0100 Processing by ProductsController#new as HTML Rendered products/_form.html.erb (56.1ms) Rendered products/new.html.erb within layouts/application (67.7ms) Completed 200 OK in 77ms (Views: 71.9ms | ActiveRecord: 0.0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-20 14:25:10 +0100 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/products.css?body=1" for 127.0.0.1 at 2013-03-20 14:25:10 +0100 Served asset /products.css - 304 Not Modified (0ms) Started GET "/assets/products.js?body=1" for 127.0.0.1 at 2013-03-20 14:25:10 +0100 Served asset /products.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-20 14:25:10 +0100 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-20 14:25:10 +0100 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-20 14:25:10 +0100 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-20 14:25:10 +0100 Served asset /application.js - 304 Not Modified (0ms) Started POST "/products" for 127.0.0.1 at 2013-03-20 14:25:18 +0100 Processing by ProductsController#create as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"rqOJCmDCDnImQxVFjsrfbItIR5pDloV7KUV0wsEKCNU=", "product"=>{"name"=>"product 1", "price"=>"100"}, "commit"=>"Create Product"}  (0.1ms) begin transaction SQL (203.8ms) INSERT INTO "products" ("created_at", "name", "price", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Wed, 20 Mar 2013 13:25:18 UTC +00:00], ["name", "product 1"], ["price", 100], ["updated_at", Wed, 20 Mar 2013 13:25:18 UTC +00:00]]  (16.6ms) commit transaction Redirected to http://localhost:3000/products/1 Completed 302 Found in 225ms (ActiveRecord: 220.4ms) Started GET "/products/1" for 127.0.0.1 at 2013-03-20 14:25:18 +0100 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.2ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT 1 [["id", "1"]] Rendered products/show.html.erb within layouts/application (1.2ms) Completed 200 OK in 11ms (Views: 8.7ms | ActiveRecord: 0.2ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-20 14:25:18 +0100 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/products.css?body=1" for 127.0.0.1 at 2013-03-20 14:25:18 +0100 Served asset /products.css - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-20 14:25:18 +0100 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-20 14:25:18 +0100 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-20 14:25:18 +0100 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-20 14:25:18 +0100 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/products.js?body=1" for 127.0.0.1 at 2013-03-20 14:25:18 +0100 Served asset /products.js - 304 Not Modified (0ms) Started GET "/products" for 127.0.0.1 at 2013-03-20 14:25:21 +0100 Processing by ProductsController#index as HTML Product Load (0.2ms) SELECT "products".* FROM "products" Rendered products/index.html.erb within layouts/application (1.1ms) Completed 200 OK in 7ms (Views: 5.7ms | ActiveRecord: 0.2ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-20 14:25:21 +0100 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-20 14:25:21 +0100 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-20 14:25:21 +0100 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/products.css?body=1" for 127.0.0.1 at 2013-03-20 14:25:21 +0100 Served asset /products.css - 304 Not Modified (0ms) Started GET "/assets/products.js?body=1" for 127.0.0.1 at 2013-03-20 14:25:21 +0100 Served asset /products.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-20 14:25:21 +0100 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-20 14:25:21 +0100 Served asset /application.js - 304 Not Modified (0ms) Started GET "/opay" for 127.0.0.1 at 2013-03-20 14:25:59 +0100 ActionController::RoutingError (No route matches [GET] "/opay"): actionpack (3.2.13) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.13) lib/rails/rack/logger.rb:32:in `call_app' railties (3.2.13) lib/rails/rack/logger.rb:16:in `block in call' activesupport (3.2.13) lib/active_support/tagged_logging.rb:22:in `tagged' railties (3.2.13) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' rack (1.4.5) lib/rack/runtime.rb:17:in `call' activesupport (3.2.13) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.5) lib/rack/lock.rb:15:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/static.rb:63:in `call' railties (3.2.13) lib/rails/engine.rb:479:in `call' railties (3.2.13) lib/rails/application.rb:223:in `call' rack (1.4.5) lib/rack/content_length.rb:14:in `call' railties (3.2.13) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (1.0ms) Started GET "/opay" for 127.0.0.1 at 2013-03-20 14:26:25 +0100 ActionController::RoutingError (No route matches [GET] "/opay"): actionpack (3.2.13) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.13) lib/rails/rack/logger.rb:32:in `call_app' railties (3.2.13) lib/rails/rack/logger.rb:16:in `block in call' activesupport (3.2.13) lib/active_support/tagged_logging.rb:22:in `tagged' railties (3.2.13) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' rack (1.4.5) lib/rack/runtime.rb:17:in `call' activesupport (3.2.13) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.5) lib/rack/lock.rb:15:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/static.rb:63:in `call' railties (3.2.13) lib/rails/engine.rb:479:in `call' railties (3.2.13) lib/rails/application.rb:223:in `call' rack (1.4.5) lib/rack/content_length.rb:14:in `call' railties (3.2.13) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (0.6ms) Started GET "/opay" for 127.0.0.1 at 2013-03-20 14:27:13 +0100 ActionController::RoutingError (uninitialized constant Opay::HomeController): activesupport (3.2.13) lib/active_support/inflector/methods.rb:230:in `block in constantize' activesupport (3.2.13) lib/active_support/inflector/methods.rb:229:in `each' activesupport (3.2.13) lib/active_support/inflector/methods.rb:229:in `constantize' actionpack (3.2.13) lib/action_dispatch/routing/route_set.rb:69:in `controller_reference' actionpack (3.2.13) lib/action_dispatch/routing/route_set.rb:54:in `controller' actionpack (3.2.13) lib/action_dispatch/routing/route_set.rb:32:in `call' journey (1.0.4) lib/journey/router.rb:68:in `block in call' journey (1.0.4) lib/journey/router.rb:56:in `each' journey (1.0.4) lib/journey/router.rb:56:in `call' actionpack (3.2.13) lib/action_dispatch/routing/route_set.rb:612:in `call' railties (3.2.13) lib/rails/engine.rb:479:in `call' railties (3.2.13) lib/rails/railtie/configurable.rb:30:in `method_missing' journey (1.0.4) lib/journey/router.rb:68:in `block in call' journey (1.0.4) lib/journey/router.rb:56:in `each' journey (1.0.4) lib/journey/router.rb:56:in `call' actionpack (3.2.13) lib/action_dispatch/routing/route_set.rb:612:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' rack (1.4.5) lib/rack/etag.rb:23:in `call' rack (1.4.5) lib/rack/conditionalget.rb:25:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/head.rb:14:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/params_parser.rb:21:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/flash.rb:242:in `call' rack (1.4.5) lib/rack/session/abstract/id.rb:210:in `context' rack (1.4.5) lib/rack/session/abstract/id.rb:205:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/cookies.rb:341:in `call' activerecord (3.2.13) lib/active_record/query_cache.rb:64:in `call' activerecord (3.2.13) lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' activesupport (3.2.13) lib/active_support/callbacks.rb:405:in `_run__4433136916292117459__call__3875846806398412552__callbacks' activesupport (3.2.13) lib/active_support/callbacks.rb:405:in `__run_callback' activesupport (3.2.13) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' activesupport (3.2.13) lib/active_support/callbacks.rb:81:in `run_callbacks' actionpack (3.2.13) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/reloader.rb:65:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.13) lib/rails/rack/logger.rb:32:in `call_app' railties (3.2.13) lib/rails/rack/logger.rb:16:in `block in call' activesupport (3.2.13) lib/active_support/tagged_logging.rb:22:in `tagged' railties (3.2.13) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' rack (1.4.5) lib/rack/runtime.rb:17:in `call' activesupport (3.2.13) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.5) lib/rack/lock.rb:15:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/static.rb:63:in `call' railties (3.2.13) lib/rails/engine.rb:479:in `call' railties (3.2.13) lib/rails/application.rb:223:in `call' rack (1.4.5) lib/rack/content_length.rb:14:in `call' railties (3.2.13) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (0.5ms) Started GET "/opay" for 127.0.0.1 at 2013-03-20 14:30:05 +0100 ActionController::RoutingError (uninitialized constant Opay::HomeController): activesupport (3.2.13) lib/active_support/inflector/methods.rb:230:in `block in constantize' activesupport (3.2.13) lib/active_support/inflector/methods.rb:229:in `each' activesupport (3.2.13) lib/active_support/inflector/methods.rb:229:in `constantize' actionpack (3.2.13) lib/action_dispatch/routing/route_set.rb:69:in `controller_reference' actionpack (3.2.13) lib/action_dispatch/routing/route_set.rb:54:in `controller' actionpack (3.2.13) lib/action_dispatch/routing/route_set.rb:32:in `call' journey (1.0.4) lib/journey/router.rb:68:in `block in call' journey (1.0.4) lib/journey/router.rb:56:in `each' journey (1.0.4) lib/journey/router.rb:56:in `call' actionpack (3.2.13) lib/action_dispatch/routing/route_set.rb:612:in `call' railties (3.2.13) lib/rails/engine.rb:479:in `call' railties (3.2.13) lib/rails/railtie/configurable.rb:30:in `method_missing' journey (1.0.4) lib/journey/router.rb:68:in `block in call' journey (1.0.4) lib/journey/router.rb:56:in `each' journey (1.0.4) lib/journey/router.rb:56:in `call' actionpack (3.2.13) lib/action_dispatch/routing/route_set.rb:612:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' rack (1.4.5) lib/rack/etag.rb:23:in `call' rack (1.4.5) lib/rack/conditionalget.rb:25:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/head.rb:14:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/params_parser.rb:21:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/flash.rb:242:in `call' rack (1.4.5) lib/rack/session/abstract/id.rb:210:in `context' rack (1.4.5) lib/rack/session/abstract/id.rb:205:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/cookies.rb:341:in `call' activerecord (3.2.13) lib/active_record/query_cache.rb:64:in `call' activerecord (3.2.13) lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' activesupport (3.2.13) lib/active_support/callbacks.rb:405:in `_run__4433136916292117459__call__3875846806398412552__callbacks' activesupport (3.2.13) lib/active_support/callbacks.rb:405:in `__run_callback' activesupport (3.2.13) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' activesupport (3.2.13) lib/active_support/callbacks.rb:81:in `run_callbacks' actionpack (3.2.13) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/reloader.rb:65:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.13) lib/rails/rack/logger.rb:32:in `call_app' railties (3.2.13) lib/rails/rack/logger.rb:16:in `block in call' activesupport (3.2.13) lib/active_support/tagged_logging.rb:22:in `tagged' railties (3.2.13) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' rack (1.4.5) lib/rack/runtime.rb:17:in `call' activesupport (3.2.13) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.5) lib/rack/lock.rb:15:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/static.rb:63:in `call' railties (3.2.13) lib/rails/engine.rb:479:in `call' railties (3.2.13) lib/rails/application.rb:223:in `call' rack (1.4.5) lib/rack/content_length.rb:14:in `call' railties (3.2.13) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (0.6ms) Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Started GET "/opay" for 127.0.0.1 at 2013-03-20 15:55:48 +0100 Processing by Opay::PayuController#online as HTML Completed 500 Internal Server Error in 34ms ArgumentError ('Opay::Configuration' is not an ActiveModel-compatible object that returns a valid partial path.): actionpack (3.2.13) lib/action_view/renderer/partial_renderer.rb:385:in `partial_path' actionpack (3.2.13) lib/action_view/renderer/partial_renderer.rb:295:in `setup' actionpack (3.2.13) lib/action_view/renderer/partial_renderer.rb:221:in `render' actionpack (3.2.13) lib/action_view/renderer/renderer.rb:41:in `render_partial' actionpack (3.2.13) lib/action_view/renderer/renderer.rb:15:in `render' actionpack (3.2.13) lib/abstract_controller/rendering.rb:110:in `_render_template' actionpack (3.2.13) lib/action_controller/metal/streaming.rb:225:in `_render_template' actionpack (3.2.13) lib/abstract_controller/rendering.rb:103:in `render_to_body' actionpack (3.2.13) lib/action_controller/metal/renderers.rb:28:in `render_to_body' actionpack (3.2.13) lib/action_controller/metal/compatibility.rb:50:in `render_to_body' actionpack (3.2.13) lib/abstract_controller/rendering.rb:88:in `render' actionpack (3.2.13) lib/action_controller/metal/rendering.rb:16:in `render' actionpack (3.2.13) lib/action_controller/metal/instrumentation.rb:40:in `block (2 levels) in render' activesupport (3.2.13) lib/active_support/core_ext/benchmark.rb:5:in `block in ms' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/benchmark.rb:295:in `realtime' activesupport (3.2.13) lib/active_support/core_ext/benchmark.rb:5:in `ms' actionpack (3.2.13) lib/action_controller/metal/instrumentation.rb:40:in `block in render' actionpack (3.2.13) lib/action_controller/metal/instrumentation.rb:83:in `cleanup_view_runtime' activerecord (3.2.13) lib/active_record/railties/controller_runtime.rb:24:in `cleanup_view_runtime' actionpack (3.2.13) lib/action_controller/metal/instrumentation.rb:39:in `render' /Users/mariusz/projekty/rails/opay/app/controllers/opay/payu_controller.rb:6:in `online' actionpack (3.2.13) lib/action_controller/metal/implicit_render.rb:4:in `send_action' actionpack (3.2.13) lib/abstract_controller/base.rb:167:in `process_action' actionpack (3.2.13) lib/action_controller/metal/rendering.rb:10:in `process_action' actionpack (3.2.13) lib/abstract_controller/callbacks.rb:18:in `block in process_action' activesupport (3.2.13) lib/active_support/callbacks.rb:403:in `_run__3236530856422423603__process_action__3703542402287087438__callbacks' activesupport (3.2.13) lib/active_support/callbacks.rb:405:in `__run_callback' activesupport (3.2.13) lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks' activesupport (3.2.13) lib/active_support/callbacks.rb:81:in `run_callbacks' actionpack (3.2.13) lib/abstract_controller/callbacks.rb:17:in `process_action' actionpack (3.2.13) lib/action_controller/metal/rescue.rb:29:in `process_action' actionpack (3.2.13) lib/action_controller/metal/instrumentation.rb:30:in `block in process_action' activesupport (3.2.13) lib/active_support/notifications.rb:123:in `block in instrument' activesupport (3.2.13) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (3.2.13) lib/active_support/notifications.rb:123:in `instrument' actionpack (3.2.13) lib/action_controller/metal/instrumentation.rb:29:in `process_action' actionpack (3.2.13) lib/action_controller/metal/params_wrapper.rb:207:in `process_action' activerecord (3.2.13) lib/active_record/railties/controller_runtime.rb:18:in `process_action' actionpack (3.2.13) lib/abstract_controller/base.rb:121:in `process' actionpack (3.2.13) lib/abstract_controller/rendering.rb:45:in `process' actionpack (3.2.13) lib/action_controller/metal.rb:203:in `dispatch' actionpack (3.2.13) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' actionpack (3.2.13) lib/action_controller/metal.rb:246:in `block in action' actionpack (3.2.13) lib/action_dispatch/routing/route_set.rb:73:in `call' actionpack (3.2.13) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' actionpack (3.2.13) lib/action_dispatch/routing/route_set.rb:36:in `call' journey (1.0.4) lib/journey/router.rb:68:in `block in call' journey (1.0.4) lib/journey/router.rb:56:in `each' journey (1.0.4) lib/journey/router.rb:56:in `call' actionpack (3.2.13) lib/action_dispatch/routing/route_set.rb:612:in `call' railties (3.2.13) lib/rails/engine.rb:479:in `call' railties (3.2.13) lib/rails/railtie/configurable.rb:30:in `method_missing' journey (1.0.4) lib/journey/router.rb:68:in `block in call' journey (1.0.4) lib/journey/router.rb:56:in `each' journey (1.0.4) lib/journey/router.rb:56:in `call' actionpack (3.2.13) lib/action_dispatch/routing/route_set.rb:612:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' rack (1.4.5) lib/rack/etag.rb:23:in `call' rack (1.4.5) lib/rack/conditionalget.rb:25:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/head.rb:14:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/params_parser.rb:21:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/flash.rb:242:in `call' rack (1.4.5) lib/rack/session/abstract/id.rb:210:in `context' rack (1.4.5) lib/rack/session/abstract/id.rb:205:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/cookies.rb:341:in `call' activerecord (3.2.13) lib/active_record/query_cache.rb:64:in `call' activerecord (3.2.13) lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' activesupport (3.2.13) lib/active_support/callbacks.rb:405:in `_run__515505277320657570__call__4558016531051876299__callbacks' activesupport (3.2.13) lib/active_support/callbacks.rb:405:in `__run_callback' activesupport (3.2.13) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' activesupport (3.2.13) lib/active_support/callbacks.rb:81:in `run_callbacks' actionpack (3.2.13) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/reloader.rb:65:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.13) lib/rails/rack/logger.rb:32:in `call_app' railties (3.2.13) lib/rails/rack/logger.rb:16:in `block in call' activesupport (3.2.13) lib/active_support/tagged_logging.rb:22:in `tagged' railties (3.2.13) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' rack (1.4.5) lib/rack/runtime.rb:17:in `call' activesupport (3.2.13) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.5) lib/rack/lock.rb:15:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/static.rb:63:in `call' railties (3.2.13) lib/rails/engine.rb:479:in `call' railties (3.2.13) lib/rails/application.rb:223:in `call' rack (1.4.5) lib/rack/content_length.rb:14:in `call' railties (3.2.13) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.3ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (0.9ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (14.2ms) Started GET "/opay" for 127.0.0.1 at 2013-03-20 15:56:09 +0100 Processing by Opay::PayuController#online as HTML Rendered text template (0.0ms) Completed 200 OK in 11ms (Views: 10.3ms | ActiveRecord: 0.0ms) Started GET "/opay" for 127.0.0.1 at 2013-03-20 15:56:20 +0100 Processing by Opay::PayuController#online as HTML Completed 500 Internal Server Error in 0ms NoMethodError (undefined method `provider' for Opay::Configuration:Module): /Users/mariusz/projekty/rails/opay/app/controllers/opay/payu_controller.rb:6:in `online' actionpack (3.2.13) lib/action_controller/metal/implicit_render.rb:4:in `send_action' actionpack (3.2.13) lib/abstract_controller/base.rb:167:in `process_action' actionpack (3.2.13) lib/action_controller/metal/rendering.rb:10:in `process_action' actionpack (3.2.13) lib/abstract_controller/callbacks.rb:18:in `block in process_action' activesupport (3.2.13) lib/active_support/callbacks.rb:403:in `_run__3236530856422423603__process_action__3703542402287087438__callbacks' activesupport (3.2.13) lib/active_support/callbacks.rb:405:in `__run_callback' activesupport (3.2.13) lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks' activesupport (3.2.13) lib/active_support/callbacks.rb:81:in `run_callbacks' actionpack (3.2.13) lib/abstract_controller/callbacks.rb:17:in `process_action' actionpack (3.2.13) lib/action_controller/metal/rescue.rb:29:in `process_action' actionpack (3.2.13) lib/action_controller/metal/instrumentation.rb:30:in `block in process_action' activesupport (3.2.13) lib/active_support/notifications.rb:123:in `block in instrument' activesupport (3.2.13) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (3.2.13) lib/active_support/notifications.rb:123:in `instrument' actionpack (3.2.13) lib/action_controller/metal/instrumentation.rb:29:in `process_action' actionpack (3.2.13) lib/action_controller/metal/params_wrapper.rb:207:in `process_action' activerecord (3.2.13) lib/active_record/railties/controller_runtime.rb:18:in `process_action' actionpack (3.2.13) lib/abstract_controller/base.rb:121:in `process' actionpack (3.2.13) lib/abstract_controller/rendering.rb:45:in `process' actionpack (3.2.13) lib/action_controller/metal.rb:203:in `dispatch' actionpack (3.2.13) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' actionpack (3.2.13) lib/action_controller/metal.rb:246:in `block in action' actionpack (3.2.13) lib/action_dispatch/routing/route_set.rb:73:in `call' actionpack (3.2.13) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' actionpack (3.2.13) lib/action_dispatch/routing/route_set.rb:36:in `call' journey (1.0.4) lib/journey/router.rb:68:in `block in call' journey (1.0.4) lib/journey/router.rb:56:in `each' journey (1.0.4) lib/journey/router.rb:56:in `call' actionpack (3.2.13) lib/action_dispatch/routing/route_set.rb:612:in `call' railties (3.2.13) lib/rails/engine.rb:479:in `call' railties (3.2.13) lib/rails/railtie/configurable.rb:30:in `method_missing' journey (1.0.4) lib/journey/router.rb:68:in `block in call' journey (1.0.4) lib/journey/router.rb:56:in `each' journey (1.0.4) lib/journey/router.rb:56:in `call' actionpack (3.2.13) lib/action_dispatch/routing/route_set.rb:612:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' rack (1.4.5) lib/rack/etag.rb:23:in `call' rack (1.4.5) lib/rack/conditionalget.rb:25:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/head.rb:14:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/params_parser.rb:21:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/flash.rb:242:in `call' rack (1.4.5) lib/rack/session/abstract/id.rb:210:in `context' rack (1.4.5) lib/rack/session/abstract/id.rb:205:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/cookies.rb:341:in `call' activerecord (3.2.13) lib/active_record/query_cache.rb:64:in `call' activerecord (3.2.13) lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' activesupport (3.2.13) lib/active_support/callbacks.rb:405:in `_run__515505277320657570__call__4558016531051876299__callbacks' activesupport (3.2.13) lib/active_support/callbacks.rb:405:in `__run_callback' activesupport (3.2.13) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' activesupport (3.2.13) lib/active_support/callbacks.rb:81:in `run_callbacks' actionpack (3.2.13) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/reloader.rb:65:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.13) lib/rails/rack/logger.rb:32:in `call_app' railties (3.2.13) lib/rails/rack/logger.rb:16:in `block in call' activesupport (3.2.13) lib/active_support/tagged_logging.rb:22:in `tagged' railties (3.2.13) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' rack (1.4.5) lib/rack/runtime.rb:17:in `call' activesupport (3.2.13) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.5) lib/rack/lock.rb:15:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/static.rb:63:in `call' railties (3.2.13) lib/rails/engine.rb:479:in `call' railties (3.2.13) lib/rails/application.rb:223:in `call' rack (1.4.5) lib/rack/content_length.rb:14:in `call' railties (3.2.13) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.1ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (0.8ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (6.9ms) Started GET "/opay" for 127.0.0.1 at 2013-03-20 15:56:30 +0100 Processing by Opay::PayuController#online as HTML Completed 500 Internal Server Error in 0ms NoMethodError (undefined method `config' for Opay::Configuration:Module): /Users/mariusz/projekty/rails/opay/app/controllers/opay/payu_controller.rb:6:in `online' actionpack (3.2.13) lib/action_controller/metal/implicit_render.rb:4:in `send_action' actionpack (3.2.13) lib/abstract_controller/base.rb:167:in `process_action' actionpack (3.2.13) lib/action_controller/metal/rendering.rb:10:in `process_action' actionpack (3.2.13) lib/abstract_controller/callbacks.rb:18:in `block in process_action' activesupport (3.2.13) lib/active_support/callbacks.rb:403:in `_run__3236530856422423603__process_action__3703542402287087438__callbacks' activesupport (3.2.13) lib/active_support/callbacks.rb:405:in `__run_callback' activesupport (3.2.13) lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks' activesupport (3.2.13) lib/active_support/callbacks.rb:81:in `run_callbacks' actionpack (3.2.13) lib/abstract_controller/callbacks.rb:17:in `process_action' actionpack (3.2.13) lib/action_controller/metal/rescue.rb:29:in `process_action' actionpack (3.2.13) lib/action_controller/metal/instrumentation.rb:30:in `block in process_action' activesupport (3.2.13) lib/active_support/notifications.rb:123:in `block in instrument' activesupport (3.2.13) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (3.2.13) lib/active_support/notifications.rb:123:in `instrument' actionpack (3.2.13) lib/action_controller/metal/instrumentation.rb:29:in `process_action' actionpack (3.2.13) lib/action_controller/metal/params_wrapper.rb:207:in `process_action' activerecord (3.2.13) lib/active_record/railties/controller_runtime.rb:18:in `process_action' actionpack (3.2.13) lib/abstract_controller/base.rb:121:in `process' actionpack (3.2.13) lib/abstract_controller/rendering.rb:45:in `process' actionpack (3.2.13) lib/action_controller/metal.rb:203:in `dispatch' actionpack (3.2.13) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' actionpack (3.2.13) lib/action_controller/metal.rb:246:in `block in action' actionpack (3.2.13) lib/action_dispatch/routing/route_set.rb:73:in `call' actionpack (3.2.13) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' actionpack (3.2.13) lib/action_dispatch/routing/route_set.rb:36:in `call' journey (1.0.4) lib/journey/router.rb:68:in `block in call' journey (1.0.4) lib/journey/router.rb:56:in `each' journey (1.0.4) lib/journey/router.rb:56:in `call' actionpack (3.2.13) lib/action_dispatch/routing/route_set.rb:612:in `call' railties (3.2.13) lib/rails/engine.rb:479:in `call' railties (3.2.13) lib/rails/railtie/configurable.rb:30:in `method_missing' journey (1.0.4) lib/journey/router.rb:68:in `block in call' journey (1.0.4) lib/journey/router.rb:56:in `each' journey (1.0.4) lib/journey/router.rb:56:in `call' actionpack (3.2.13) lib/action_dispatch/routing/route_set.rb:612:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' rack (1.4.5) lib/rack/etag.rb:23:in `call' rack (1.4.5) lib/rack/conditionalget.rb:25:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/head.rb:14:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/params_parser.rb:21:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/flash.rb:242:in `call' rack (1.4.5) lib/rack/session/abstract/id.rb:210:in `context' rack (1.4.5) lib/rack/session/abstract/id.rb:205:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/cookies.rb:341:in `call' activerecord (3.2.13) lib/active_record/query_cache.rb:64:in `call' activerecord (3.2.13) lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' activesupport (3.2.13) lib/active_support/callbacks.rb:405:in `_run__515505277320657570__call__4558016531051876299__callbacks' activesupport (3.2.13) lib/active_support/callbacks.rb:405:in `__run_callback' activesupport (3.2.13) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' activesupport (3.2.13) lib/active_support/callbacks.rb:81:in `run_callbacks' actionpack (3.2.13) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/reloader.rb:65:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.13) lib/rails/rack/logger.rb:32:in `call_app' railties (3.2.13) lib/rails/rack/logger.rb:16:in `block in call' activesupport (3.2.13) lib/active_support/tagged_logging.rb:22:in `tagged' railties (3.2.13) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' rack (1.4.5) lib/rack/runtime.rb:17:in `call' activesupport (3.2.13) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.5) lib/rack/lock.rb:15:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/static.rb:63:in `call' railties (3.2.13) lib/rails/engine.rb:479:in `call' railties (3.2.13) lib/rails/application.rb:223:in `call' rack (1.4.5) lib/rack/content_length.rb:14:in `call' railties (3.2.13) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.2ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (0.8ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (7.2ms) Started GET "/opay" for 127.0.0.1 at 2013-03-20 15:57:00 +0100 Processing by Opay::PayuController#online as HTML Rendered text template (0.0ms) Completed 200 OK in 1ms (Views: 0.7ms | ActiveRecord: 0.0ms) Started GET "/opay" for 127.0.0.1 at 2013-03-20 15:57:07 +0100 Processing by Opay::PayuController#online as HTML Rendered text template (0.0ms) Completed 200 OK in 2ms (Views: 1.3ms | ActiveRecord: 0.0ms) Started GET "/opay" for 127.0.0.1 at 2013-03-20 15:57:17 +0100 Processing by Opay::PayuController#online as HTML Rendered text template (0.0ms) Completed 200 OK in 1ms (Views: 0.7ms | ActiveRecord: 0.0ms) Connecting to database specified by database.yml Started GET "/" for 127.0.0.1 at 2013-03-21 16:12:52 +0100 ActionController::RoutingError (No route matches [GET] "/"): actionpack (3.2.13) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.13) lib/rails/rack/logger.rb:32:in `call_app' railties (3.2.13) lib/rails/rack/logger.rb:16:in `block in call' activesupport (3.2.13) lib/active_support/tagged_logging.rb:22:in `tagged' railties (3.2.13) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' rack (1.4.5) lib/rack/runtime.rb:17:in `call' activesupport (3.2.13) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.5) lib/rack/lock.rb:15:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/static.rb:63:in `call' railties (3.2.13) lib/rails/engine.rb:479:in `call' railties (3.2.13) lib/rails/application.rb:223:in `call' rack (1.4.5) lib/rack/content_length.rb:14:in `call' railties (3.2.13) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (5.2ms) Started GET "/products" for 127.0.0.1 at 2013-03-21 16:12:59 +0100 ArgumentError (Unknown key: aa): app/models/product.rb:2:in `' app/models/product.rb:1:in `' app/controllers/products_controller.rb:1:in `' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.3ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (2.8ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (11.6ms) Started GET "/products" for 127.0.0.1 at 2013-03-21 16:13:14 +0100 Processing by ProductsController#index as HTML Product Load (33.7ms) SELECT "products".* FROM "products"  Rendered products/index.html.erb within layouts/application (2.8ms) Completed 200 OK in 1161ms (Views: 800.0ms | ActiveRecord: 53.8ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-21 16:13:16 +0100 Served asset /application.css - 200 OK (5ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-21 16:13:16 +0100 Served asset /jquery.js - 200 OK (76ms) Started GET "/assets/products.css?body=1" for 127.0.0.1 at 2013-03-21 16:13:16 +0100 Served asset /products.css - 304 Not Modified (62ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-21 16:13:16 +0100 Served asset /jquery_ujs.js - 200 OK (2ms) Started GET "/assets/products.js?body=1" for 127.0.0.1 at 2013-03-21 16:13:16 +0100 Served asset /products.js - 304 Not Modified (29ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-21 16:13:16 +0100 Served asset /scaffold.css - 304 Not Modified (27ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-21 16:13:16 +0100 Served asset /application.js - 200 OK (71ms) Started GET "/products/1" for 127.0.0.1 at 2013-03-21 16:13:19 +0100 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.3ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT 1 [["id", "1"]] Rendered products/show.html.erb within layouts/application (1.3ms) Completed 200 OK in 132ms (Views: 129.6ms | ActiveRecord: 0.3ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-21 16:13:19 +0100 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/products.css?body=1" for 127.0.0.1 at 2013-03-21 16:13:19 +0100 Served asset /products.css - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-21 16:13:20 +0100 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-21 16:13:20 +0100 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-21 16:13:20 +0100 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/products.js?body=1" for 127.0.0.1 at 2013-03-21 16:13:21 +0100 Served asset /products.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-21 16:13:21 +0100 Served asset /application.js - 304 Not Modified (0ms) Started GET "/products/1" for 127.0.0.1 at 2013-03-21 16:14:09 +0100 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT 1 [["id", "1"]] Rendered products/show.html.erb within layouts/application (119.4ms) Completed 500 Internal Server Error in 139ms ActionView::Template::Error (undefined method `opay_form_for' for #<#:0x007fc664429028>): 10: <%= @product.price %> 11:

12: 13: <%= opay_form_for @product do |f| %> 14: cd 15: <% end %> 16: app/views/products/show.html.erb:13:in `_app_views_products_show_html_erb___3152130214115647815_70245031165260' app/controllers/products_controller.rb:18:in `show' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.6ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.2ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (20.2ms) Started GET "/products/1" for 127.0.0.1 at 2013-03-21 16:17:10 +0100 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT 1 [["id", "1"]] Rendered products/show.html.erb within layouts/application (5.0ms) Completed 500 Internal Server Error in 8ms ActionView::Template::Error (undefined method `opay_form_for' for #<#:0x007fc6642cf498>): 10: <%= @product.price %> 11:

12: 13: <%= opay_form_for @product do |f| %> 14: cd 15: <% end %> 16: app/views/products/show.html.erb:13:in `_app_views_products_show_html_erb___3152130214115647815_70245031165260' app/controllers/products_controller.rb:18:in `show' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.5ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.0ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (8.1ms) Connecting to database specified by database.yml Connecting to database specified by database.yml Started GET "/products/1" for 127.0.0.1 at 2013-03-21 16:18:19 +0100 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (5.0ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT 1 [["id", "1"]] Rendered products/show.html.erb within layouts/application (4.8ms) Completed 200 OK in 173ms (Views: 88.9ms | ActiveRecord: 7.4ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-21 16:18:19 +0100 Served asset /application.css - 304 Not Modified (3ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-21 16:18:20 +0100 Served asset /scaffold.css - 304 Not Modified (1ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-21 16:18:20 +0100 Served asset /jquery.js - 304 Not Modified (2ms) Started GET "/assets/products.css?body=1" for 127.0.0.1 at 2013-03-21 16:18:20 +0100 Served asset /products.css - 304 Not Modified (1ms) Started GET "/assets/products.js?body=1" for 127.0.0.1 at 2013-03-21 16:18:20 +0100 Served asset /products.js - 304 Not Modified (1ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-21 16:18:20 +0100 Served asset /jquery_ujs.js - 304 Not Modified (1ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-21 16:18:20 +0100 Served asset /application.js - 304 Not Modified (5ms) Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Started GET "/products/1" for 127.0.0.1 at 2013-03-21 16:20:11 +0100 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.2ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT 1 [["id", "1"]] Rendered products/show.html.erb within layouts/application (4.9ms) Completed 200 OK in 79ms (Views: 31.4ms | ActiveRecord: 2.2ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-21 16:20:11 +0100 Served asset /application.css - 304 Not Modified (31ms) Started GET "/assets/products.js?body=1" for 127.0.0.1 at 2013-03-21 16:20:11 +0100 Served asset /products.js - 304 Not Modified (1ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-21 16:20:11 +0100 Served asset /jquery_ujs.js - 304 Not Modified (4ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-21 16:20:11 +0100 Served asset /jquery.js - 304 Not Modified (4ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-21 16:20:11 +0100 Served asset /scaffold.css - 304 Not Modified (5ms) Started GET "/assets/products.css?body=1" for 127.0.0.1 at 2013-03-21 16:20:11 +0100 Served asset /products.css - 304 Not Modified (1ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-21 16:20:11 +0100 Served asset /application.js - 304 Not Modified (39ms) Connecting to database specified by database.yml Started GET "/products/1" for 127.0.0.1 at 2013-03-21 16:25:01 +0100 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.2ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT 1 [["id", "1"]] Rendered products/show.html.erb within layouts/application (10.4ms) Completed 500 Internal Server Error in 67ms ActionView::Template::Error (undefined method `opay_form_for' for #<#:0x007f8ccbd12ef0>): 10: <%= @product.price %> 11:

12: 13: <%= opay_form_for @product do |f| %> 14: cd 15: <% end %> 16: app/views/products/show.html.erb:13:in `_app_views_products_show_html_erb__3056843916972674487_70121353760140' app/controllers/products_controller.rb:18:in `show' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.4ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.0ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (9.8ms) Connecting to database specified by database.yml Started GET "/products/1" for 127.0.0.1 at 2013-03-21 16:36:39 +0100 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.2ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT 1 [["id", "1"]] Rendered products/show.html.erb within layouts/application (11.8ms) Completed 500 Internal Server Error in 69ms ActionView::Template::Error (undefined method `payu' for #): 11:

12: 13: <%= opay_form_for @product do |f| %> 14: <%= f.payu %> 15: <% end %> 16: 17: app/views/products/show.html.erb:14:in `block in _app_views_products_show_html_erb__3066636752553584687_70118463091240' app/views/products/show.html.erb:13:in `_app_views_products_show_html_erb__3066636752553584687_70118463091240' app/controllers/products_controller.rb:18:in `show' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.4ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (0.9ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (9.5ms) Started GET "/products/1" for 127.0.0.1 at 2013-03-21 16:38:19 +0100 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT 1 [["id", "1"]] Rendered products/show.html.erb within layouts/application (35.4ms) Completed 500 Internal Server Error in 38ms ActionView::Template::Error (undefined method `payu' for #): 11:

12: 13: <%= opay_form_for @product do |f| %> 14: <%= f.payu %> 15: <% end %> 16: 17: app/views/products/show.html.erb:14:in `block in _app_views_products_show_html_erb__3066636752553584687_70118463091240' app/views/products/show.html.erb:13:in `_app_views_products_show_html_erb__3066636752553584687_70118463091240' app/controllers/products_controller.rb:18:in `show' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.3ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.2ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (7.9ms) Connecting to database specified by database.yml Started GET "/products/1" for 127.0.0.1 at 2013-03-21 16:43:43 +0100 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.2ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT 1 [["id", "1"]] Rendered products/show.html.erb within layouts/application (12.3ms) Completed 500 Internal Server Error in 141ms ActionView::Template::Error (undefined method `payu' for #): 11:

12: 13: <%= opay_form_for @product do |f| %> 14: <%= f.payu %> 15: <% end %> 16: 17: app/views/products/show.html.erb:14:in `block in _app_views_products_show_html_erb__1277346441317852476_70218910525100' app/views/products/show.html.erb:13:in `_app_views_products_show_html_erb__1277346441317852476_70218910525100' app/controllers/products_controller.rb:18:in `show' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.3ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.1ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (9.7ms) Connecting to database specified by database.yml Started GET "/products/1" for 127.0.0.1 at 2013-03-21 16:48:38 +0100 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.2ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT 1 [["id", "1"]] Rendered products/show.html.erb within layouts/application (24.6ms) Completed 200 OK in 207ms (Views: 127.2ms | ActiveRecord: 2.4ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-21 16:48:38 +0100 Served asset /application.css - 304 Not Modified (7ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-21 16:48:38 +0100 Served asset /jquery_ujs.js - 304 Not Modified (1ms) Started GET "/assets/products.css?body=1" for 127.0.0.1 at 2013-03-21 16:48:38 +0100 Served asset /products.css - 304 Not Modified (3ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-21 16:48:38 +0100 Served asset /scaffold.css - 304 Not Modified (2ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-21 16:48:38 +0100 Served asset /jquery.js - 304 Not Modified (2ms) Started GET "/assets/products.js?body=1" for 127.0.0.1 at 2013-03-21 16:48:38 +0100 Served asset /products.js - 304 Not Modified (27ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-21 16:48:38 +0100 Served asset /application.js - 304 Not Modified (4ms) Started GET "/products/1" for 127.0.0.1 at 2013-03-21 16:55:36 +0100 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT 1 [["id", "1"]] Rendered products/show.html.erb within layouts/application (1.4ms) Completed 200 OK in 57ms (Views: 55.1ms | ActiveRecord: 0.1ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-21 16:55:36 +0100 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/products.css?body=1" for 127.0.0.1 at 2013-03-21 16:55:36 +0100 Served asset /products.css - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-21 16:55:36 +0100 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-21 16:55:36 +0100 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-21 16:55:36 +0100 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/products.js?body=1" for 127.0.0.1 at 2013-03-21 16:55:36 +0100 Served asset /products.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-21 16:55:36 +0100 Served asset /application.js - 304 Not Modified (0ms) Connecting to database specified by database.yml Started GET "/products/1" for 127.0.0.1 at 2013-03-21 16:55:58 +0100 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.2ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT 1 [["id", "1"]] Rendered products/show.html.erb within layouts/application (34.6ms) Completed 500 Internal Server Error in 147ms ActionView::Template::Error (undefined method `content_tag' for #): 11:

12: 13: <%= opay_form_for @product do |f| %> 14: <%= f.payu %> 15: <% end %> 16: 17: app/views/products/show.html.erb:14:in `block in _app_views_products_show_html_erb___3641402905806552961_70195370918940' app/views/products/show.html.erb:13:in `_app_views_products_show_html_erb___3641402905806552961_70195370918940' app/controllers/products_controller.rb:18:in `show' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.8ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.2ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (11.1ms) Started GET "/products/1" for 127.0.0.1 at 2013-03-21 16:57:02 +0100 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT 1 [["id", "1"]] Rendered products/show.html.erb within layouts/application (6.8ms) Completed 500 Internal Server Error in 36ms ActionView::Template::Error (undefined method `content_tag' for #): 11:

12: 13: <%= opay_form_for @product do |f| %> 14: <%= f.payu %> 15: <% end %> 16: 17: app/views/products/show.html.erb:14:in `block in _app_views_products_show_html_erb___3641402905806552961_70195370918940' app/views/products/show.html.erb:13:in `_app_views_products_show_html_erb___3641402905806552961_70195370918940' app/controllers/products_controller.rb:18:in `show' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.4ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (0.9ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (8.3ms) Connecting to database specified by database.yml Started GET "/products/1" for 127.0.0.1 at 2013-03-21 16:59:39 +0100 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.2ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT 1 [["id", "1"]] Rendered products/show.html.erb within layouts/application (18.3ms) Completed 200 OK in 215ms (Views: 137.0ms | ActiveRecord: 2.4ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-21 16:59:40 +0100 Served asset /application.css - 304 Not Modified (3ms) Started GET "/assets/products.css?body=1" for 127.0.0.1 at 2013-03-21 16:59:40 +0100 Served asset /products.css - 304 Not Modified (1ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-21 16:59:40 +0100 Served asset /jquery.js - 304 Not Modified (3ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-21 16:59:40 +0100 Served asset /jquery_ujs.js - 304 Not Modified (1ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-21 16:59:40 +0100 Served asset /scaffold.css - 304 Not Modified (1ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-21 16:59:40 +0100 Served asset /application.js - 304 Not Modified (9ms) Started GET "/assets/products.js?body=1" for 127.0.0.1 at 2013-03-21 16:59:40 +0100 Served asset /products.js - 304 Not Modified (1ms) Connecting to database specified by database.yml Started GET "/products/1" for 127.0.0.1 at 2013-03-21 17:01:47 +0100 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.3ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT 1 [["id", "1"]] Rendered products/show.html.erb within layouts/application (37.2ms) Completed 500 Internal Server Error in 96ms ActionView::Template::Error (undefined method `jkjk' for #): 11:

12: 13: <%= opay_form_for @product do |f| %> 14: <%= f.payu %> 15: <% end %> 16: 17: app/views/products/show.html.erb:14:in `block in _app_views_products_show_html_erb___3787525202601926261_70315224150200' app/views/products/show.html.erb:13:in `_app_views_products_show_html_erb___3787525202601926261_70315224150200' app/controllers/products_controller.rb:18:in `show' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.5ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.0ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (11.1ms) Started GET "/products/1" for 127.0.0.1 at 2013-03-21 17:02:26 +0100 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT 1 [["id", "1"]] Rendered products/show.html.erb within layouts/application (1.6ms) Completed 500 Internal Server Error in 4ms ActionView::Template::Error (undefined method `jkjk' for #): 11:

12: 13: <%= opay_form_for @product do |f| %> 14: <%= f.payu %> 15: <% end %> 16: 17: app/views/products/show.html.erb:14:in `block in _app_views_products_show_html_erb___3787525202601926261_70315224150200' app/views/products/show.html.erb:13:in `_app_views_products_show_html_erb___3787525202601926261_70315224150200' app/controllers/products_controller.rb:18:in `show' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.6ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (23.5ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (30.6ms) Started GET "/products/1" for 127.0.0.1 at 2013-03-21 17:02:28 +0100 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT 1 [["id", "1"]] Rendered products/show.html.erb within layouts/application (1.4ms) Completed 500 Internal Server Error in 4ms ActionView::Template::Error (undefined method `jkjk' for #): 11:

12: 13: <%= opay_form_for @product do |f| %> 14: <%= f.payu %> 15: <% end %> 16: 17: app/views/products/show.html.erb:14:in `block in _app_views_products_show_html_erb___3787525202601926261_70315224150200' app/views/products/show.html.erb:13:in `_app_views_products_show_html_erb___3787525202601926261_70315224150200' app/controllers/products_controller.rb:18:in `show' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.3ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.1ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (8.0ms) Connecting to database specified by database.yml  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations"  Migrating to CreateProducts (20130320132244)  (0.1ms) select sqlite_version(*)  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations"  Connecting to database specified by database.yml  (11.6ms) SELECT "schema_migrations"."version" FROM "schema_migrations"  Migrating to CreateProducts (20130320132244) Migrating to CreateOpayPayments (20130326141947)  (0.1ms) select sqlite_version(*)  (0.0ms) begin transaction  (0.5ms) CREATE TABLE "opay_payments" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "payable_id" integer, "payable_type" varchar(255), "session" varchar(255), "amount" float, "finished" boolean, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)  (0.1ms) CREATE INDEX "index_opay_payments_on_item_id" ON "opay_payments" ("item_id") SQLite3::SQLException: table opay_payments has no column named item_id: CREATE INDEX "index_opay_payments_on_item_id" ON "opay_payments" ("item_id")  (35.3ms) rollback transaction Connecting to database specified by database.yml  (11.3ms) SELECT "schema_migrations"."version" FROM "schema_migrations"  Migrating to CreateProducts (20130320132244) Migrating to CreateOpayPayments (20130326142236)  (0.1ms) select sqlite_version(*)  (0.1ms) begin transaction  (0.6ms) CREATE TABLE "opay_payments" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "payable_id" integer, "payable_type" varchar(255), "session" varchar(255), "amount" float, "finished" boolean, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)  (0.1ms) CREATE INDEX "index_opay_payments_on_payable_id_and_payable_type" ON "opay_payments" ("payable_id", "payable_type")  (0.2ms) INSERT INTO "schema_migrations" ("version") VALUES ('20130326142236')  (1.8ms) commit transaction  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations" Connecting to database specified by database.yml Connecting to database specified by database.yml  (2.2ms) select sqlite_version(*)  (1.5ms) CREATE TABLE "opay_payments" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "payable_id" integer, "payable_type" varchar(255), "session" varchar(255), "amount" float, "finished" boolean, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)  (1.4ms) CREATE INDEX "index_opay_payments_on_payable_id_and_payable_type" ON "opay_payments" ("payable_id", "payable_type")  (1.4ms) CREATE TABLE "products" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar(255), "price" integer, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)  (1.3ms) CREATE TABLE "schema_migrations" ("version" varchar(255) NOT NULL)   (8.6ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (1.5ms) INSERT INTO "schema_migrations" (version) VALUES ('20130326142236')  (1.2ms) INSERT INTO "schema_migrations" (version) VALUES ('20130320132244')  (1.2ms) INSERT INTO "schema_migrations" (version) VALUES ('20130321114023')  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations"  Connecting to database specified by database.yml Connecting to database specified by database.yml  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations"  Migrating to CreateProducts (20130320132244) Migrating to CreateOpayPayments (20130321114023)  (0.1ms) select sqlite_version(*)  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations"  Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml  (16.3ms) SELECT "schema_migrations"."version" FROM "schema_migrations"  Connecting to database specified by database.yml  (1.7ms) select sqlite_version(*)  (2.0ms) CREATE TABLE "opay_payments" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "payable_id" integer, "payable_type" varchar(255), "session" varchar(255), "amount" float, "finished" boolean, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)  (1.7ms) CREATE INDEX "index_opay_payments_on_payable_id_and_payable_type" ON "opay_payments" ("payable_id", "payable_type")  (1.6ms) CREATE TABLE "orders" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar(255), "amount" varchar(255), "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)  (1.5ms) CREATE TABLE "products" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar(255), "price" integer, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)   (2.5ms) CREATE TABLE "schema_migrations" ("version" varchar(255) NOT NULL)  (1.9ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.2ms) SELECT version FROM "schema_migrations"  (1.8ms) INSERT INTO "schema_migrations" (version) VALUES ('20130326152550')  (1.8ms) INSERT INTO "schema_migrations" (version) VALUES ('20130321114023')  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations"  Connecting to database specified by database.yml  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations"   (0.5ms) select sqlite_version(*)  (17.7ms) DROP TABLE "opay_payments"  (1.7ms) CREATE TABLE "opay_payments" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "payable_id" integer, "payable_type" varchar(255), "session" varchar(255), "amount" float, "finished" boolean, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)  (5.9ms) CREATE INDEX "index_opay_payments_on_payable_id_and_payable_type" ON "opay_payments" ("payable_id", "payable_type")  (2.0ms) DROP TABLE "orders"  (1.7ms) CREATE TABLE "orders" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar(255), "amount" varchar(255), "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)   (1.7ms) DROP TABLE "products"  (1.5ms) CREATE TABLE "products" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar(255), "price" integer, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)   (0.1ms) SELECT version FROM "schema_migrations" Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml  (29.9ms) DELETE FROM "opay_payments";  (0.3ms) DELETE FROM sqlite_sequence where name = 'opay_payments';  (1.6ms) DELETE FROM "orders";  (0.1ms) DELETE FROM sqlite_sequence where name = 'orders';  (1.5ms) DELETE FROM "products";  (0.1ms) DELETE FROM sqlite_sequence where name = 'products';  (1.9ms) DELETE FROM "opay_payments";  (0.1ms) DELETE FROM sqlite_sequence where name = 'opay_payments';  (2.2ms) DELETE FROM "orders";  (0.1ms) DELETE FROM sqlite_sequence where name = 'orders';  (1.5ms) DELETE FROM "products";  (0.1ms) DELETE FROM sqlite_sequence where name = 'products';  (1.7ms) DELETE FROM "opay_payments";  (0.1ms) DELETE FROM sqlite_sequence where name = 'opay_payments';  (1.5ms) DELETE FROM "orders";  (0.1ms) DELETE FROM sqlite_sequence where name = 'orders';  (1.3ms) DELETE FROM "products";  (0.1ms) DELETE FROM sqlite_sequence where name = 'products';  (2.3ms) DELETE FROM "opay_payments";  (0.1ms) DELETE FROM sqlite_sequence where name = 'opay_payments';  (1.9ms) DELETE FROM "orders";  (0.1ms) DELETE FROM sqlite_sequence where name = 'orders';  (1.5ms) DELETE FROM "products";  (0.1ms) DELETE FROM sqlite_sequence where name = 'products';  (2.1ms) DELETE FROM "opay_payments";  (0.1ms) DELETE FROM sqlite_sequence where name = 'opay_payments';  (1.3ms) DELETE FROM "orders";  (0.1ms) DELETE FROM sqlite_sequence where name = 'orders';  (1.4ms) DELETE FROM "products";  (0.1ms) DELETE FROM sqlite_sequence where name = 'products';  (1.8ms) DELETE FROM "opay_payments";  (0.1ms) DELETE FROM sqlite_sequence where name = 'opay_payments';  (1.5ms) DELETE FROM "orders";  (0.1ms) DELETE FROM sqlite_sequence where name = 'orders';  (1.5ms) DELETE FROM "products";  (0.3ms) DELETE FROM sqlite_sequence where name = 'products';  (78.0ms) DELETE FROM "opay_payments";  (0.2ms) DELETE FROM sqlite_sequence where name = 'opay_payments';  (12.1ms) DELETE FROM "orders";  (0.1ms) DELETE FROM sqlite_sequence where name = 'orders';  (12.3ms) DELETE FROM "products";  (0.2ms) DELETE FROM sqlite_sequence where name = 'products';  (20.5ms) DELETE FROM "opay_payments";  (0.1ms) DELETE FROM sqlite_sequence where name = 'opay_payments';  (2.4ms) DELETE FROM "orders";  (0.1ms) DELETE FROM sqlite_sequence where name = 'orders';  (5.7ms) DELETE FROM "products";  (0.1ms) DELETE FROM sqlite_sequence where name = 'products';  (9.3ms) DELETE FROM "opay_payments";  (0.1ms) DELETE FROM sqlite_sequence where name = 'opay_payments';  (3.5ms) DELETE FROM "orders";  (0.1ms) DELETE FROM sqlite_sequence where name = 'orders';  (19.1ms) DELETE FROM "products";  (0.2ms) DELETE FROM sqlite_sequence where name = 'products';  (4.1ms) DELETE FROM "opay_payments";  (0.1ms) DELETE FROM sqlite_sequence where name = 'opay_payments';  (12.3ms) DELETE FROM "orders";  (0.1ms) DELETE FROM sqlite_sequence where name = 'orders';  (13.7ms) DELETE FROM "products";  (0.2ms) DELETE FROM sqlite_sequence where name = 'products';  (13.5ms) DELETE FROM "opay_payments";  (0.1ms) DELETE FROM sqlite_sequence where name = 'opay_payments';  (5.9ms) DELETE FROM "orders";  (0.1ms) DELETE FROM sqlite_sequence where name = 'orders';  (12.3ms) DELETE FROM "products";  (0.2ms) DELETE FROM sqlite_sequence where name = 'products';  (6.2ms) DELETE FROM "opay_payments";  (0.1ms) DELETE FROM sqlite_sequence where name = 'opay_payments';  (23.7ms) DELETE FROM "orders";  (0.2ms) DELETE FROM sqlite_sequence where name = 'orders';  (2.4ms) DELETE FROM "products";  (0.1ms) DELETE FROM sqlite_sequence where name = 'products';  (2.1ms) DELETE FROM "opay_payments";  (0.1ms) DELETE FROM sqlite_sequence where name = 'opay_payments';  (1.6ms) DELETE FROM "orders";  (0.1ms) DELETE FROM sqlite_sequence where name = 'orders';  (1.7ms) DELETE FROM "products";  (0.1ms) DELETE FROM sqlite_sequence where name = 'products';  (2.2ms) DELETE FROM "opay_payments";  (0.1ms) DELETE FROM sqlite_sequence where name = 'opay_payments';  (1.3ms) DELETE FROM "orders";  (0.1ms) DELETE FROM sqlite_sequence where name = 'orders';  (1.3ms) DELETE FROM "products";  (0.1ms) DELETE FROM sqlite_sequence where name = 'products';  (1.7ms) DELETE FROM "opay_payments";  (0.1ms) DELETE FROM sqlite_sequence where name = 'opay_payments';  (1.4ms) DELETE FROM "orders";  (0.1ms) DELETE FROM sqlite_sequence where name = 'orders';  (1.4ms) DELETE FROM "products";  (0.1ms) DELETE FROM sqlite_sequence where name = 'products';  (1.7ms) DELETE FROM "opay_payments";  (0.1ms) DELETE FROM sqlite_sequence where name = 'opay_payments';  (1.7ms) DELETE FROM "orders";  (0.1ms) DELETE FROM sqlite_sequence where name = 'orders';  (1.5ms) DELETE FROM "products";  (0.1ms) DELETE FROM sqlite_sequence where name = 'products';  (1.6ms) DELETE FROM "opay_payments";  (0.2ms) DELETE FROM sqlite_sequence where name = 'opay_payments';  (1.7ms) DELETE FROM "orders";  (0.1ms) DELETE FROM sqlite_sequence where name = 'orders';  (1.2ms) DELETE FROM "products";  (0.1ms) DELETE FROM sqlite_sequence where name = 'products';  (1.6ms) DELETE FROM "opay_payments";  (0.2ms) DELETE FROM sqlite_sequence where name = 'opay_payments';  (1.5ms) DELETE FROM "orders";  (0.1ms) DELETE FROM sqlite_sequence where name = 'orders';  (1.5ms) DELETE FROM "products";  (0.1ms) DELETE FROM sqlite_sequence where name = 'products';  (8.1ms) DELETE FROM "opay_payments";  (0.2ms) DELETE FROM sqlite_sequence where name = 'opay_payments';  (1.4ms) DELETE FROM "orders";  (0.1ms) DELETE FROM sqlite_sequence where name = 'orders';  (1.4ms) DELETE FROM "products";  (0.1ms) DELETE FROM sqlite_sequence where name = 'products';  (1.7ms) DELETE FROM "opay_payments";  (0.1ms) DELETE FROM sqlite_sequence where name = 'opay_payments';  (1.6ms) DELETE FROM "orders";  (0.2ms) DELETE FROM sqlite_sequence where name = 'orders';  (1.5ms) DELETE FROM "products";  (0.1ms) DELETE FROM sqlite_sequence where name = 'products';  (1.5ms) DELETE FROM "opay_payments";  (0.1ms) DELETE FROM sqlite_sequence where name = 'opay_payments';  (1.3ms) DELETE FROM "orders";  (0.1ms) DELETE FROM sqlite_sequence where name = 'orders';  (1.3ms) DELETE FROM "products";  (0.1ms) DELETE FROM sqlite_sequence where name = 'products';  (1.5ms) DELETE FROM "opay_payments";  (0.2ms) DELETE FROM sqlite_sequence where name = 'opay_payments';  (1.3ms) DELETE FROM "orders";  (0.1ms) DELETE FROM sqlite_sequence where name = 'orders';  (1.3ms) DELETE FROM "products";  (0.1ms) DELETE FROM sqlite_sequence where name = 'products';  (1.5ms) DELETE FROM "opay_payments";  (0.1ms) DELETE FROM sqlite_sequence where name = 'opay_payments';  (2.1ms) DELETE FROM "orders";  (0.1ms) DELETE FROM sqlite_sequence where name = 'orders';  (1.4ms) DELETE FROM "products";  (0.2ms) DELETE FROM sqlite_sequence where name = 'products';  (55.0ms) DELETE FROM "opay_payments";  (0.2ms) DELETE FROM sqlite_sequence where name = 'opay_payments';  (1.5ms) DELETE FROM "orders";  (0.1ms) DELETE FROM sqlite_sequence where name = 'orders';  (1.4ms) DELETE FROM "products";  (0.1ms) DELETE FROM sqlite_sequence where name = 'products'; Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Started GET "/" for 127.0.0.1 at 2013-04-24 13:51:05 +0200 ActionController::RoutingError (No route matches [GET] "/"): actionpack (3.2.13) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.13) lib/rails/rack/logger.rb:32:in `call_app' railties (3.2.13) lib/rails/rack/logger.rb:16:in `block in call' activesupport (3.2.13) lib/active_support/tagged_logging.rb:22:in `tagged' railties (3.2.13) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' rack (1.4.5) lib/rack/runtime.rb:17:in `call' activesupport (3.2.13) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.5) lib/rack/lock.rb:15:in `call' actionpack (3.2.13) lib/action_dispatch/middleware/static.rb:63:in `call' railties (3.2.13) lib/rails/engine.rb:479:in `call' railties (3.2.13) lib/rails/application.rb:223:in `call' rack (1.4.5) lib/rack/content_length.rb:14:in `call' railties (3.2.13) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /Users/mariusz/.rvm/rubies/ruby-1.9.3-p0/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (34.6ms) Started GET "/" for 127.0.0.1 at 2013-04-24 13:51:35 +0200 Processing by OrdersController#index as HTML Order Load (39.1ms) SELECT "orders".* FROM "orders"  Rendered orders/index.html.erb within layouts/application (1.1ms) Compiled application.css (1ms) (pid 46805) Completed 500 Internal Server Error in 1144ms ActionView::Template::Error (couldn't find file 'jquery' (in /Users/mariusz/projekty/rails/opay/spec/dummy/app/assets/javascripts/application.js:13)): 3: 4: Dummy 5: <%= stylesheet_link_tag "application", :media => "all" %> 6: <%= javascript_include_tag "application" %> 7: <%= csrf_meta_tags %> 8: 9: app/views/layouts/application.html.erb:6:in `_app_views_layouts_application_html_erb__2834171036792740793_70302624147620' app/controllers/orders_controller.rb:7:in `index' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.4ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.0ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (11.0ms) Connecting to database specified by database.yml Started GET "/" for 127.0.0.1 at 2013-04-24 13:52:51 +0200 Processing by OrdersController#index as HTML Order Load (31.6ms) SELECT "orders".* FROM "orders"  Rendered orders/index.html.erb within layouts/application (5.8ms) Compiled application.js (2ms) (pid 47443) Completed 200 OK in 408ms (Views: 266.0ms | ActiveRecord: 58.3ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 13:52:52 +0200 Served asset /scaffold.css - 200 OK (5ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 13:52:52 +0200 Served asset /jquery.js - 304 Not Modified (40ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 13:52:52 +0200 Served asset /jquery_ujs.js - 304 Not Modified (1ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 13:52:52 +0200 Served asset /application.js - 200 OK (3ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 13:52:52 +0200 Served asset /application.css - 200 OK (3ms) Started GET "/orders/new" for 127.0.0.1 at 2013-04-24 13:52:55 +0200 Processing by OrdersController#new as HTML Rendered orders/_form.html.erb (201.4ms) Rendered orders/new.html.erb within layouts/application (282.2ms) Completed 200 OK in 311ms (Views: 305.0ms | ActiveRecord: 0.0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 13:52:55 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 13:52:55 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 13:52:55 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 13:52:55 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 13:52:55 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started POST "/orders" for 127.0.0.1 at 2013-04-24 13:53:08 +0200 Processing by OrdersController#create as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"sYtpdAkBiSPH0VVmuJaonVtw4WeS/h2Atq0ssPS25Qg=", "order"=>{"name"=>"order 1", "amount"=>"10"}, "commit"=>"Create Order"}  (0.1ms) begin transaction SQL (134.0ms) INSERT INTO "orders" ("amount", "created_at", "name", "updated_at") VALUES (?, ?, ?, ?) [["amount", "10"], ["created_at", Wed, 24 Apr 2013 11:53:08 UTC +00:00], ["name", "order 1"], ["updated_at", Wed, 24 Apr 2013 11:53:08 UTC +00:00]]  (4.7ms) commit transaction Redirected to http://0.0.0.0:3000/orders/1 Completed 302 Found in 144ms (ActiveRecord: 138.8ms) Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 13:53:08 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (0.3ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Rendered orders/show.html.erb within layouts/application (2.5ms) Completed 200 OK in 45ms (Views: 42.3ms | ActiveRecord: 0.3ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 13:53:08 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 13:53:08 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 13:53:08 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 13:53:08 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 13:53:08 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/orders" for 127.0.0.1 at 2013-04-24 13:53:11 +0200 Processing by OrdersController#index as HTML Order Load (0.2ms) SELECT "orders".* FROM "orders" Rendered orders/index.html.erb within layouts/application (1.6ms) Completed 200 OK in 18ms (Views: 16.2ms | ActiveRecord: 0.2ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 13:53:11 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 13:53:11 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 13:53:11 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 13:53:11 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 13:53:11 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 13:53:19 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Rendered orders/show.html.erb within layouts/application (0.6ms) Completed 200 OK in 6ms (Views: 4.7ms | ActiveRecord: 0.1ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 13:53:19 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 13:53:19 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 13:53:19 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 13:53:19 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 13:53:19 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 13:54:36 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Rendered orders/show.html.erb within layouts/application (28.9ms) Completed 500 Internal Server Error in 58ms ActionView::Template::Error (undefined method `payu_form_for' for #<#:0x007f7f82773a18>): 10: <%= @order.amount %> 11:

12: 13: <%= payu_form_for(@order) do |f| %> 14: 15: <% end %> 16: app/views/orders/show.html.erb:13:in `_app_views_orders_show_html_erb__3540414464078662255_70092813207540' app/controllers/orders_controller.rb:18:in `show' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.4ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (0.9ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (9.6ms) Connecting to database specified by database.yml Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 13:59:32 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (30.9ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Rendered orders/show.html.erb within layouts/application (158.2ms) Completed 500 Internal Server Error in 370ms ActionView::Template::Error (unknown attribute: session_id): 10: <%= @order.amount %> 11:

12: 13: <%= payu_form_for(@order) do |f| %> 14: 15: <% end %> 16: app/views/orders/show.html.erb:13:in `_app_views_orders_show_html_erb__2842286153136912271_70185199437880' app/controllers/orders_controller.rb:18:in `show' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.3ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (0.9ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (10.0ms) Connecting to database specified by database.yml Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 14:02:36 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Completed 500 Internal Server Error in 54ms ActiveRecord::StatementInvalid (Could not find table 'orders'): app/controllers/orders_controller.rb:16:in `show' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.3ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (0.9ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (12.7ms) Connecting to database specified by database.yml  (3.1ms) select sqlite_version(*)  (2.4ms) CREATE TABLE "opay_payments" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "payable_id" integer, "payable_type" varchar(255), "session_id" varchar(255), "provider" varchar(255), "amount" float, "finished" boolean DEFAULT 'f' NOT NULL, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)  (1.6ms) CREATE INDEX "index_opay_payments_on_payable_id_and_payable_type" ON "opay_payments" ("payable_id", "payable_type")  (1.5ms) CREATE TABLE "orders" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar(255), "amount" varchar(255), "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)  (1.4ms) CREATE TABLE "schema_migrations" ("version" varchar(255) NOT NULL)   (38.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (69.0ms) INSERT INTO "schema_migrations" (version) VALUES ('20130326152550')  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations"  Connecting to database specified by database.yml Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 14:03:01 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (0.2ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Completed 404 Not Found in 64ms ActiveRecord::RecordNotFound (Couldn't find Order with id=1): app/controllers/orders_controller.rb:16:in `show' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.4ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.1ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (25.1ms) Started GET "/orders" for 127.0.0.1 at 2013-04-24 14:03:06 +0200 Processing by OrdersController#index as HTML Order Load (0.2ms) SELECT "orders".* FROM "orders" Rendered orders/index.html.erb within layouts/application (1.4ms) Completed 200 OK in 137ms (Views: 135.1ms | ActiveRecord: 0.2ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 14:03:07 +0200 Served asset /application.css - 304 Not Modified (2ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 14:03:07 +0200 Served asset /jquery.js - 304 Not Modified (3ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 14:03:07 +0200 Served asset /scaffold.css - 304 Not Modified (1ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 14:03:07 +0200 Served asset /application.js - 304 Not Modified (4ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 14:03:07 +0200 Served asset /jquery_ujs.js - 304 Not Modified (2ms) Started GET "/orders/new" for 127.0.0.1 at 2013-04-24 14:03:08 +0200 Processing by OrdersController#new as HTML Rendered orders/_form.html.erb (8.1ms) Rendered orders/new.html.erb within layouts/application (25.4ms) Completed 200 OK in 47ms (Views: 40.5ms | ActiveRecord: 0.0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 14:03:08 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 14:03:08 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 14:03:08 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 14:03:08 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 14:03:08 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started POST "/orders" for 127.0.0.1 at 2013-04-24 14:03:13 +0200 Processing by OrdersController#create as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"sYtpdAkBiSPH0VVmuJaonVtw4WeS/h2Atq0ssPS25Qg=", "order"=>{"name"=>"order 1", "amount"=>"10"}, "commit"=>"Create Order"}  (0.1ms) begin transaction SQL (33.9ms) INSERT INTO "orders" ("amount", "created_at", "name", "updated_at") VALUES (?, ?, ?, ?) [["amount", "10"], ["created_at", Wed, 24 Apr 2013 12:03:13 UTC +00:00], ["name", "order 1"], ["updated_at", Wed, 24 Apr 2013 12:03:13 UTC +00:00]]  (4.4ms) commit transaction Redirected to http://0.0.0.0:3000/orders/1 Completed 302 Found in 43ms (ActiveRecord: 38.4ms) Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 14:03:13 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]]  (0.1ms) begin transaction Order Load (0.2ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = 1 LIMIT 1 Binary data inserted for `string` type on column `session_id` SQL (7.3ms) INSERT INTO "opay_payments" ("amount", "created_at", "finished", "payable_id", "payable_type", "provider", "session_id", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["amount", 10.0], ["created_at", Wed, 24 Apr 2013 12:03:13 UTC +00:00], ["finished", false], ["payable_id", 1], ["payable_type", "Order"], ["provider", "payu"], ["session_id", "a7deb9a6840e764b4dac3ce9469a788c"], ["updated_at", Wed, 24 Apr 2013 12:03:13 UTC +00:00]]  (4.2ms) commit transaction Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 1 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (103.8ms) Completed 200 OK in 125ms (Views: 111.9ms | ActiveRecord: 12.4ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 14:03:13 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 14:03:13 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 14:03:13 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 14:03:13 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 14:03:13 +0200 Served asset /application.css - 304 Not Modified (8ms) Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 14:04:07 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]]  (0.1ms) begin transaction Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = 1 LIMIT 1 Binary data inserted for `string` type on column `session_id` SQL (0.5ms) INSERT INTO "opay_payments" ("amount", "created_at", "finished", "payable_id", "payable_type", "provider", "session_id", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["amount", 10.0], ["created_at", Wed, 24 Apr 2013 12:04:07 UTC +00:00], ["finished", false], ["payable_id", 1], ["payable_type", "Order"], ["provider", "payu"], ["session_id", "a7deb9a6840e764b4dac3ce9469a788c"], ["updated_at", Wed, 24 Apr 2013 12:04:07 UTC +00:00]]  (2.7ms) commit transaction Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 1 AND "opay_payments"."payable_type" = 'Order' LIMIT 1  (0.0ms) begin transaction  (0.0ms) rollback transaction Rendered orders/show.html.erb within layouts/application (39.9ms) Completed 500 Internal Server Error in 43ms ActiveRecord::RecordNotSaved (Failed to remove the existing associated payment. The record failed to save when after its foreign key was set to nil.): app/views/orders/show.html.erb:13:in `_app_views_orders_show_html_erb___3780241057777541332_70269272291260' app/controllers/orders_controller.rb:18:in `show' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (2.8ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.1ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (49.2ms) Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 14:04:19 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]]  (0.1ms) begin transaction Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = 1 LIMIT 1 Binary data inserted for `string` type on column `session_id` SQL (0.5ms) INSERT INTO "opay_payments" ("amount", "created_at", "finished", "payable_id", "payable_type", "provider", "session_id", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["amount", 10.0], ["created_at", Wed, 24 Apr 2013 12:04:19 UTC +00:00], ["finished", false], ["payable_id", 1], ["payable_type", "Order"], ["provider", "payu"], ["session_id", "a7deb9a6840e764b4dac3ce9469a788c"], ["updated_at", Wed, 24 Apr 2013 12:04:19 UTC +00:00]]  (7.6ms) commit transaction Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 1 AND "opay_payments"."payable_type" = 'Order' LIMIT 1  (0.0ms) begin transaction  (0.1ms) rollback transaction Rendered orders/show.html.erb within layouts/application (23.9ms) Completed 500 Internal Server Error in 26ms ActiveRecord::RecordNotSaved (Failed to remove the existing associated payment. The record failed to save when after its foreign key was set to nil.): app/views/orders/show.html.erb:13:in `_app_views_orders_show_html_erb___3780241057777541332_70269272291260' app/controllers/orders_controller.rb:18:in `show' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.5ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.0ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (8.7ms) Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 14:04:57 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]]  (0.1ms) begin transaction Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = 1 LIMIT 1 Binary data inserted for `string` type on column `session_id` SQL (0.7ms) INSERT INTO "opay_payments" ("amount", "created_at", "finished", "payable_id", "payable_type", "provider", "session_id", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["amount", 10.0], ["created_at", Wed, 24 Apr 2013 12:04:57 UTC +00:00], ["finished", false], ["payable_id", 1], ["payable_type", "Order"], ["provider", "payu"], ["session_id", "a7deb9a6840e764b4dac3ce9469a788c"], ["updated_at", Wed, 24 Apr 2013 12:04:57 UTC +00:00]]  (1.8ms) commit transaction Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 1 AND "opay_payments"."payable_type" = 'Order' LIMIT 1  (0.0ms) begin transaction  (0.0ms) rollback transaction Rendered orders/show.html.erb within layouts/application (10.1ms) Completed 500 Internal Server Error in 13ms ActiveRecord::RecordNotSaved (Failed to remove the existing associated payment. The record failed to save when after its foreign key was set to nil.): app/views/orders/show.html.erb:13:in `_app_views_orders_show_html_erb___3780241057777541332_70269272291260' app/controllers/orders_controller.rb:18:in `show' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.3ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (0.9ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (7.3ms) Connecting to database specified by database.yml Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 14:06:24 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (0.3ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 1 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (167.1ms) Completed 200 OK in 430ms (Views: 308.7ms | ActiveRecord: 28.0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 14:06:25 +0200 Served asset /application.css - 304 Not Modified (6ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 14:06:25 +0200 Served asset /jquery_ujs.js - 304 Not Modified (1ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 14:06:25 +0200 Served asset /jquery.js - 304 Not Modified (2ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 14:06:25 +0200 Served asset /application.js - 304 Not Modified (3ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 14:06:25 +0200 Served asset /scaffold.css - 304 Not Modified (1ms) Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 14:06:28 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 1 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (4.1ms) Completed 200 OK in 10ms (Views: 8.2ms | ActiveRecord: 0.4ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 14:06:28 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 14:06:28 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 14:06:28 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 14:06:28 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 14:06:28 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 14:07:00 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 1 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (4.8ms) Completed 200 OK in 10ms (Views: 8.5ms | ActiveRecord: 0.3ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 14:07:00 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 14:07:00 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 14:07:00 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 14:07:00 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 14:07:00 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 14:07:15 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 1 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (318.8ms) Completed 200 OK in 577ms (Views: 576.0ms | ActiveRecord: 0.3ms) Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 14:07:16 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (0.3ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Opay::Payment Load (0.3ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 1 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (6.5ms) Completed 200 OK in 27ms (Views: 23.3ms | ActiveRecord: 0.6ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 14:07:17 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 14:07:17 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 14:07:17 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 14:07:17 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 14:07:17 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 14:07:35 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 1 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (4.9ms) Completed 200 OK in 11ms (Views: 9.7ms | ActiveRecord: 0.3ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 14:07:35 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 14:07:35 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 14:07:35 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 14:07:35 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 14:07:35 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 14:07:42 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 1 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (2.5ms) Completed 200 OK in 8ms (Views: 6.9ms | ActiveRecord: 0.3ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 14:07:42 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 14:07:42 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 14:07:42 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 14:07:42 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 14:07:42 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 14:08:49 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 1 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (2.7ms) Completed 200 OK in 37ms (Views: 36.1ms | ActiveRecord: 0.3ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 14:08:49 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 14:08:49 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 14:08:49 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 14:08:49 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 14:08:49 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 14:09:49 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (0.2ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 1 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (4.5ms) Completed 200 OK in 27ms (Views: 26.2ms | ActiveRecord: 0.4ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 14:09:49 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 14:09:49 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 14:09:49 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 14:09:49 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 14:09:49 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 14:09:52 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Opay::Payment Load (0.1ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 1 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (2.4ms) Completed 200 OK in 7ms (Views: 6.0ms | ActiveRecord: 0.3ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 14:09:52 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 14:09:52 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 14:09:52 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 14:09:52 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 14:09:52 +0200 Served asset /application.js - 304 Not Modified (0ms) Connecting to database specified by database.yml Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 14:11:45 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (23.3ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Opay::Payment Load (0.6ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 1 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (165.2ms) Completed 200 OK in 447ms (Views: 306.4ms | ActiveRecord: 50.9ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 14:11:46 +0200 Served asset /application.css - 304 Not Modified (41ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 14:11:46 +0200 Served asset /jquery.js - 304 Not Modified (5ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 14:11:46 +0200 Served asset /jquery_ujs.js - 304 Not Modified (1ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 14:11:46 +0200 Served asset /application.js - 304 Not Modified (5ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 14:11:46 +0200 Served asset /scaffold.css - 304 Not Modified (1ms) Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 14:12:43 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 1 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (3.1ms) Completed 200 OK in 34ms (Views: 32.8ms | ActiveRecord: 0.3ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 14:12:43 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 14:12:43 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 14:12:43 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 14:12:43 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 14:12:43 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 14:13:36 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 1 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (74.3ms) Completed 500 Internal Server Error in 89ms ActionView::Template::Error (undefined local variable or method `request' for #): 16:

17: 18: <%= opay_form_for(@order) do |f| %> 19: <%= f.payment_info first_name: 'Jan', last_name: 'Kowalski', email: 'kowalski@gmail.com', desc: 'Test payment' %> 20: <%= f.submit 'pay with payu' %> 21: <% end %> 22: app/views/orders/show.html.erb:19:in `block in _app_views_orders_show_html_erb__1732424407509850341_70302188118440' app/views/orders/show.html.erb:18:in `_app_views_orders_show_html_erb__1732424407509850341_70302188118440' app/controllers/orders_controller.rb:18:in `show' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.4ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (0.9ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (10.1ms) Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 14:15:16 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 1 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (5.4ms) Completed 500 Internal Server Error in 29ms ActionView::Template::Error (undefined method `client_ip' for #): 16:

17: 18: <%= opay_form_for(@order) do |f| %> 19: <%= f.payment_info first_name: 'Jan', last_name: 'Kowalski', email: 'kowalski@gmail.com', desc: 'Test payment', client_ip: request.client_ip %> 20: <%= f.submit 'pay with payu' %> 21: <% end %> 22: app/views/orders/show.html.erb:19:in `block in _app_views_orders_show_html_erb__1732424407509850341_70302199623420' app/views/orders/show.html.erb:18:in `_app_views_orders_show_html_erb__1732424407509850341_70302199623420' app/controllers/orders_controller.rb:18:in `show' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.2ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.5ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (8.8ms) Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 14:15:42 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 1 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (4.6ms) Completed 200 OK in 9ms (Views: 7.9ms | ActiveRecord: 0.4ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 14:15:42 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 14:15:42 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 14:15:42 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 14:15:42 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 14:15:42 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Connecting to database specified by database.yml Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 14:19:18 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (21.8ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Opay::Payment Load (0.7ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 1 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (214.7ms) Completed 500 Internal Server Error in 440ms ActionView::Template::Error (undefined local variable or method `view' for #): 16:

17: 18: <%= opay_form_for(@order) do |f| %> 19: <%= f.payment_info first_name: 'Jan', last_name: 'Kowalski', email: 'kowalski@gmail.com', desc: 'Test payment' %> 20: <%= f.submit 'pay with payu' %> 21: <% end %> 22: app/views/orders/show.html.erb:19:in `block in _app_views_orders_show_html_erb__3919135364871191408_70288639727100' app/views/orders/show.html.erb:18:in `_app_views_orders_show_html_erb__3919135364871191408_70288639727100' app/controllers/orders_controller.rb:18:in `show' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.3ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.1ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (10.9ms) Connecting to database specified by database.yml Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 14:23:07 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (23.3ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Opay::Payment Load (0.6ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 1 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (278.7ms) Completed 500 Internal Server Error in 541ms ActionView::Template::Error (undefined local variable or method `request' for #): 16:

17: 18: <%= opay_form_for(@order) do |f| %> 19: <%= f.payment_info first_name: 'Jan', last_name: 'Kowalski', email: 'kowalski@gmail.com', desc: 'Test payment' %> 20: <%= f.submit 'pay with payu' %> 21: <% end %> 22: app/views/orders/show.html.erb:19:in `block in _app_views_orders_show_html_erb___1594893664330200545_70165726232560' app/views/orders/show.html.erb:18:in `_app_views_orders_show_html_erb___1594893664330200545_70165726232560' app/controllers/orders_controller.rb:18:in `show' Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.3ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (0.9ms) Rendered /Users/mariusz/.rvm/gems/ruby-1.9.3-p0@payments_engine/gems/actionpack-3.2.13/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (9.8ms) Connecting to database specified by database.yml Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 14:28:09 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (23.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Opay::Payment Load (0.6ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 1 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (181.0ms) Completed 200 OK in 447ms (Views: 305.6ms | ActiveRecord: 51.0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 14:28:10 +0200 Served asset /application.css - 304 Not Modified (5ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 14:28:10 +0200 Served asset /application.js - 304 Not Modified (5ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 14:28:10 +0200 Served asset /jquery.js - 304 Not Modified (2ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 14:28:10 +0200 Served asset /jquery_ujs.js - 304 Not Modified (1ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 14:28:10 +0200 Served asset /scaffold.css - 304 Not Modified (1ms) Connecting to database specified by database.yml Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 16:23:53 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (23.7ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Opay::Payment Load (0.7ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 1 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (186.6ms) Completed 200 OK in 447ms (Views: 339.7ms | ActiveRecord: 51.9ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 16:23:53 +0200 Served asset /scaffold.css - 304 Not Modified (1ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 16:23:53 +0200 Served asset /jquery.js - 304 Not Modified (5ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 16:23:53 +0200 Served asset /jquery_ujs.js - 304 Not Modified (2ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 16:23:53 +0200 Served asset /application.js - 304 Not Modified (44ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 16:23:53 +0200 Served asset /application.css - 304 Not Modified (2ms) Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 16:24:39 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 1 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (3.8ms) Completed 200 OK in 33ms (Views: 32.0ms | ActiveRecord: 0.4ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 16:24:39 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 16:24:39 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 16:24:39 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 16:24:39 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 16:24:39 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/orders/1" for 127.0.0.1 at 2013-04-24 16:24:44 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 1 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (3.9ms) Completed 200 OK in 11ms (Views: 10.1ms | ActiveRecord: 0.3ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 16:24:44 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 16:24:44 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 16:24:44 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 16:24:44 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 16:24:44 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/orders" for 127.0.0.1 at 2013-04-24 16:25:27 +0200 Processing by OrdersController#index as HTML Order Load (0.4ms) SELECT "orders".* FROM "orders"  Rendered orders/index.html.erb within layouts/application (2.2ms) Completed 200 OK in 37ms (Views: 35.3ms | ActiveRecord: 0.4ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 16:25:27 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 16:25:27 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 16:25:27 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 16:25:27 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 16:25:27 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/orders/new" for 127.0.0.1 at 2013-04-24 16:25:29 +0200 Processing by OrdersController#new as HTML Rendered orders/_form.html.erb (68.9ms) Rendered orders/new.html.erb within layouts/application (131.1ms) Completed 200 OK in 164ms (Views: 160.8ms | ActiveRecord: 0.0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 16:25:29 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 16:25:29 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 16:25:29 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 16:25:29 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 16:25:29 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started POST "/orders" for 127.0.0.1 at 2013-04-24 16:25:35 +0200 Processing by OrdersController#create as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"sYtpdAkBiSPH0VVmuJaonVtw4WeS/h2Atq0ssPS25Qg=", "order"=>{"name"=>"order 2", "amount"=>"1000"}, "commit"=>"Create Order"}  (0.1ms) begin transaction SQL (273.3ms) INSERT INTO "orders" ("amount", "created_at", "name", "updated_at") VALUES (?, ?, ?, ?) [["amount", "1000"], ["created_at", Wed, 24 Apr 2013 14:25:35 UTC +00:00], ["name", "order 2"], ["updated_at", Wed, 24 Apr 2013 14:25:35 UTC +00:00]]  (4.5ms) commit transaction Redirected to http://0.0.0.0:3000/orders/2 Completed 302 Found in 282ms (ActiveRecord: 277.8ms) Started GET "/orders/2" for 127.0.0.1 at 2013-04-24 16:25:36 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"2"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "2"]] Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 2 AND "opay_payments"."payable_type" = 'Order' LIMIT 1  (0.0ms) begin transaction Order Load (0.2ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = 2 LIMIT 1 Binary data inserted for `string` type on column `session_id` SQL (0.7ms) INSERT INTO "opay_payments" ("amount", "created_at", "finished", "payable_id", "payable_type", "provider", "session_id", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["amount", 1000.0], ["created_at", Wed, 24 Apr 2013 14:25:36 UTC +00:00], ["finished", false], ["payable_id", 2], ["payable_type", "Order"], ["provider", "payu"], ["session_id", "d8bf541acd87654f3d815aca7140383d"], ["updated_at", Wed, 24 Apr 2013 14:25:36 UTC +00:00]]  (1.7ms) commit transaction Rendered orders/show.html.erb within layouts/application (83.4ms) Completed 200 OK in 124ms (Views: 119.9ms | ActiveRecord: 3.0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 16:25:36 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 16:25:36 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 16:25:36 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 16:25:36 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 16:25:36 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Connecting to database specified by database.yml Started GET "/orders/2" for 127.0.0.1 at 2013-04-24 16:28:34 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"2"} Order Load (22.8ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "2"]] Opay::Payment Load (0.5ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 2 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (191.4ms) Completed 200 OK in 588ms (Views: 429.9ms | ActiveRecord: 50.9ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 16:28:34 +0200 Served asset /scaffold.css - 304 Not Modified (2ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 16:28:34 +0200 Served asset /jquery_ujs.js - 304 Not Modified (4ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 16:28:34 +0200 Served asset /jquery.js - 304 Not Modified (4ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 16:28:34 +0200 Served asset /application.js - 304 Not Modified (5ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 16:28:34 +0200 Served asset /application.css - 304 Not Modified (2ms) Started GET "/orders/2" for 127.0.0.1 at 2013-04-24 16:29:27 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"2"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "2"]] Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 2 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (3.5ms) Completed 200 OK in 19ms (Views: 17.4ms | ActiveRecord: 0.3ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 16:29:27 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 16:29:27 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 16:29:27 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 16:29:27 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 16:29:27 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/orders/2" for 127.0.0.1 at 2013-04-24 16:29:52 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"2"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "2"]] Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 2 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (3.3ms) Completed 200 OK in 22ms (Views: 20.8ms | ActiveRecord: 0.3ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 16:29:52 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 16:29:52 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 16:29:52 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 16:29:52 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 16:29:52 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/orders/2" for 127.0.0.1 at 2013-04-24 16:30:16 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"2"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "2"]] Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 2 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (3.4ms) Completed 200 OK in 10ms (Views: 8.2ms | ActiveRecord: 0.4ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 16:30:17 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 16:30:17 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 16:30:17 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 16:30:17 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 16:30:17 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/orders/2" for 127.0.0.1 at 2013-04-24 16:30:54 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"2"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "2"]] Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 2 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (1.9ms) Completed 200 OK in 66ms (Views: 63.9ms | ActiveRecord: 0.3ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 16:30:55 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 16:30:55 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 16:30:55 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 16:30:55 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 16:30:55 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/orders/2" for 127.0.0.1 at 2013-04-24 16:31:16 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"2"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "2"]] Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 2 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (2.9ms) Completed 200 OK in 69ms (Views: 67.8ms | ActiveRecord: 0.3ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 16:31:16 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 16:31:16 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 16:31:16 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 16:31:16 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 16:31:16 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/orders/2" for 127.0.0.1 at 2013-04-24 16:31:28 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"2"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "2"]] Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 2 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (1.5ms) Completed 200 OK in 11ms (Views: 10.0ms | ActiveRecord: 0.3ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 16:31:28 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 16:31:28 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 16:31:28 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 16:31:28 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 16:31:28 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/orders/2" for 127.0.0.1 at 2013-04-24 16:34:38 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"2"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "2"]] Opay::Payment Load (1.0ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 2 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (4.6ms) Completed 200 OK in 15ms (Views: 13.2ms | ActiveRecord: 1.1ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 16:34:38 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 16:34:38 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 16:34:38 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 16:34:38 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 16:34:38 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/orders/2" for 127.0.0.1 at 2013-04-24 16:40:46 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"2"} Order Load (0.1ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "2"]] Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = 2 AND "opay_payments"."payable_type" = 'Order' LIMIT 1 Rendered orders/show.html.erb within layouts/application (2.8ms) Completed 200 OK in 34ms (Views: 32.1ms | ActiveRecord: 0.3ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-04-24 16:40:46 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-04-24 16:40:46 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-04-24 16:40:46 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-04-24 16:40:46 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-04-24 16:40:46 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms)