(1.5ms) CREATE TABLE "opay_payments" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "payable_id" integer, "payable_type" varchar(255), "session_id" varchar(255) NOT NULL, "provider" varchar(255) NOT NULL, "amount" integer NOT NULL, "finished" boolean DEFAULT 'f' NOT NULL, "status" varchar(255), "created_at" datetime, "updated_at" datetime)   (1.1ms) CREATE INDEX "index_opay_payments_on_payable_id_and_payable_type" ON "opay_payments" ("payable_id", "payable_type")  (1.0ms) CREATE UNIQUE INDEX "index_opay_payments_on_session_id" ON "opay_payments" ("session_id")  (1.0ms) CREATE TABLE "orders" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar(255), "amount" varchar(255), "finished" boolean DEFAULT 'f' NOT NULL, "created_at" datetime, "updated_at" datetime)  (2.0ms) CREATE TABLE "schema_migrations" ("version" varchar(255) NOT NULL)   (1.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (1.0ms) INSERT INTO "schema_migrations" (version) VALUES ('20130326152550') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" Started GET "/" for 127.0.0.1 at 2014-04-24 15:18:45 +0200 Processing by OrdersController#index as HTML Order Load (3.7ms) SELECT "orders".* FROM "orders" Rendered orders/index.html.erb within layouts/application (8.9ms) Completed 200 OK in 281ms (Views: 258.5ms | ActiveRecord: 3.7ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2014-04-24 15:18:47 +0200 Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2014-04-24 15:18:47 +0200 Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2014-04-24 15:18:47 +0200 Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2014-04-24 15:18:47 +0200 Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2014-04-24 15:18:47 +0200 Started GET "/orders/new" for 127.0.0.1 at 2014-04-24 15:18:49 +0200 Processing by OrdersController#new as HTML Rendered orders/_form.html.erb (48.7ms) Rendered orders/new.html.erb within layouts/application (57.0ms) Completed 200 OK in 73ms (Views: 70.0ms | ActiveRecord: 0.5ms) Started POST "/orders" for 127.0.0.1 at 2014-04-24 15:18:55 +0200 Processing by OrdersController#create as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"6UyWqDKrR+zb2JeoY3rG0gbIAKV7yZHCJ2l3g6AiiRE=", "order"=>{"name"=>"name", "amount"=>"10"}, "commit"=>"Create Order"}  (0.2ms) begin transaction SQL (13.6ms) INSERT INTO "orders" ("amount", "created_at", "name", "updated_at") VALUES (?, ?, ?, ?) [["amount", "10"], ["created_at", Thu, 24 Apr 2014 13:18:55 UTC +00:00], ["name", "name"], ["updated_at", Thu, 24 Apr 2014 13:18:55 UTC +00:00]]  (2.4ms) commit transaction Redirected to http://localhost:3000/orders/1 Completed 302 Found in 26ms (ActiveRecord: 16.2ms) Started GET "/orders/1" for 127.0.0.1 at 2014-04-24 15:18:55 +0200 Processing by OrdersController#show as HTML Parameters: {"id"=>"1"} Order Load (0.4ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", "1"]] Opay::Payment Load (0.8ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = ? AND "opay_payments"."payable_type" = ? LIMIT 1 [["payable_id", 1], ["payable_type", "Order"]]  (0.1ms) begin transaction Opay::Payment Load (0.3ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."session_id" = 'wfxL2JXCm2LhuZRGkrMr' ORDER BY "opay_payments"."id" ASC LIMIT 1 SQL (2.9ms) INSERT INTO "opay_payments" ("amount", "created_at", "payable_id", "payable_type", "provider", "session_id", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["amount", 10], ["created_at", Thu, 24 Apr 2014 13:18:56 UTC +00:00], ["payable_id", 1], ["payable_type", "Order"], ["provider", "payu"], ["session_id", "wfxL2JXCm2LhuZRGkrMr"], ["updated_at", Thu, 24 Apr 2014 13:18:56 UTC +00:00]]  (1.2ms) commit transaction Rendered orders/show.html.erb within layouts/application (199.7ms) Completed 500 Internal Server Error in 214ms ActionView::Template::Error (Empty payment provider): 18: 19: 20: <%= payu_form_for(@order) do |f| %> 21: <%= f.payment_info first_name: 'Jan', last_name: 'Kowalski', email: 'kowalski@gmail.com', desc: 'Test payment', client_ip: '127.0.0.1' %> 22: <%= f.submit :pay_with_payu %> 23: <% end -%> 24: app/views/orders/show.html.erb:21:in `block in _app_views_orders_show_html_erb__1764881669874171495_70128889827280' app/views/orders/show.html.erb:20:in `_app_views_orders_show_html_erb__1764881669874171495_70128889827280' Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.9ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.5ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (28.0ms) Started GET "/orders/1" for 127.0.0.1 at 2014-04-24 15:21:15 +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" = ? AND "opay_payments"."payable_type" = ? LIMIT 1 [["payable_id", 1], ["payable_type", "Order"]] Opay::Payment Load (0.3ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."session_id" = 'xsufcfDxp3mbDNz5pdmP' ORDER BY "opay_payments"."id" ASC LIMIT 1  (0.1ms) begin transaction SQL (1.2ms) UPDATE "opay_payments" SET "session_id" = ?, "updated_at" = ? WHERE "opay_payments"."id" = 1 [["session_id", "xsufcfDxp3mbDNz5pdmP"], ["updated_at", Thu, 24 Apr 2014 13:21:15 UTC +00:00]]  (6.1ms) commit transaction Opay::Payment Load (0.4ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."session_id" = 'MZdaqaVdatukLqLKGWmp' ORDER BY "opay_payments"."id" ASC LIMIT 1  (0.1ms) begin transaction SQL (0.7ms) UPDATE "opay_payments" SET "session_id" = ?, "updated_at" = ? WHERE "opay_payments"."id" = 1 [["session_id", "MZdaqaVdatukLqLKGWmp"], ["updated_at", Thu, 24 Apr 2014 13:21:15 UTC +00:00]]  (0.9ms) commit transaction Rendered orders/show.html.erb within layouts/application (37.2ms) Completed 200 OK in 44ms (Views: 32.2ms | ActiveRecord: 10.2ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2014-04-24 15:21:15 +0200 Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2014-04-24 15:21:15 +0200 Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2014-04-24 15:21:15 +0200 Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2014-04-24 15:21:15 +0200 Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2014-04-24 15:21:15 +0200 Started GET "/orders/1" for 127.0.0.1 at 2014-04-24 15:21:55 +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 (3.5ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = ? AND "opay_payments"."payable_type" = ? LIMIT 1 [["payable_id", 1], ["payable_type", "Order"]] Opay::Payment Load (0.3ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."session_id" = 'JpqBqsCgrY6a4MtuP1xQ' ORDER BY "opay_payments"."id" ASC LIMIT 1  (0.1ms) begin transaction SQL (2.7ms) UPDATE "opay_payments" SET "session_id" = ?, "updated_at" = ? WHERE "opay_payments"."id" = 1 [["session_id", "JpqBqsCgrY6a4MtuP1xQ"], ["updated_at", Thu, 24 Apr 2014 13:21:56 UTC +00:00]]  (1.2ms) commit transaction Opay::Payment Load (0.3ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."session_id" = 'jvGJ6FkA72qj2hLgpBCu' ORDER BY "opay_payments"."id" ASC LIMIT 1  (0.1ms) begin transaction SQL (2.5ms) UPDATE "opay_payments" SET "session_id" = ?, "updated_at" = ? WHERE "opay_payments"."id" = 1 [["session_id", "jvGJ6FkA72qj2hLgpBCu"], ["updated_at", Thu, 24 Apr 2014 13:21:56 UTC +00:00]]  (3.1ms) commit transaction Rendered orders/show.html.erb within layouts/application (98.2ms) Completed 200 OK in 282ms (Views: 246.8ms | ActiveRecord: 16.9ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2014-04-24 15:21:56 +0200 Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2014-04-24 15:21:56 +0200 Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2014-04-24 15:21:56 +0200 Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2014-04-24 15:21:56 +0200 Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2014-04-24 15:21:56 +0200 Started PATCH "/opay/payu/paygw/UTF/NewPayment" for 127.0.0.1 at 2014-04-24 15:21:58 +0200 Processing by Opay::PayuController#paygw as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"6UyWqDKrR+zb2JeoY3rG0gbIAKV7yZHCJ2l3g6AiiRE=", "first_name"=>"Jan", "last_name"=>"Kowalski", "email"=>"kowalski@gmail.com", "desc"=>"Test payment", "client_ip"=>"127.0.0.1", "pos_id"=>"123456", "pos_auth_key"=>"DiEKzTD", "session_id"=>"JpqBqsCgrY6a4MtuP1xQ", "amount"=>"10", "js"=>"1", "pay_type"=>"t", "ts"=>"1398345716", "sig"=>"fbf5c774953eda0008cd28fef8a513e3", "commit"=>"pay_with_payu"} Rendered /Users/olownia/dev/gems/opay/app/views/opay/payu/paygw.html.erb within layouts/opay/application (10.8ms) Completed 200 OK in 97ms (Views: 96.8ms | ActiveRecord: 0.0ms) Started GET "/assets/opay/application.css?body=1" for 127.0.0.1 at 2014-04-24 15:21:58 +0200 Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2014-04-24 15:21:58 +0200 Started GET "/assets/opay/application.js?body=1" for 127.0.0.1 at 2014-04-24 15:21:58 +0200 Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2014-04-24 15:21:58 +0200 Started GET "/opay/correct_authorization?session_id=JpqBqsCgrY6a4MtuP1xQ" for 127.0.0.1 at 2014-04-24 15:22:00 +0200 Processing by Opay::PayuController#correct_authorization as HTML Parameters: {"session_id"=>"JpqBqsCgrY6a4MtuP1xQ"} Opay::Payment Load (0.3ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."session_id" = 'JpqBqsCgrY6a4MtuP1xQ' ORDER BY "opay_payments"."id" ASC LIMIT 1 Completed 404 Not Found in 2ms ActiveRecord::RecordNotFound (ActiveRecord::RecordNotFound): activerecord (4.0.4) lib/active_record/relation/finder_methods.rb:98:in `first!' /Users/olownia/dev/gems/opay/app/controllers/opay/application_controller.rb:4:in `correct_authorization' actionpack (4.0.4) lib/action_controller/metal/implicit_render.rb:4:in `send_action' actionpack (4.0.4) lib/abstract_controller/base.rb:189:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rendering.rb:10:in `process_action' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:18:in `block in process_action' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__2900285106230756710__process_action__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:17:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rescue.rb:29:in `process_action' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:31:in `block in process_action' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `block in instrument' activesupport (4.0.4) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `instrument' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:30:in `process_action' actionpack (4.0.4) lib/action_controller/metal/params_wrapper.rb:250:in `process_action' activerecord (4.0.4) lib/active_record/railties/controller_runtime.rb:18:in `process_action' actionpack (4.0.4) lib/abstract_controller/base.rb:136:in `process' actionpack (4.0.4) lib/abstract_controller/rendering.rb:44:in `process' actionpack (4.0.4) lib/action_controller/metal.rb:195:in `dispatch' actionpack (4.0.4) lib/action_controller/metal/rack_delegation.rb:13:in `dispatch' actionpack (4.0.4) lib/action_controller/metal.rb:231:in `block in action' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `dispatch' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:48:in `call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/railtie/configurable.rb:30:in `method_missing' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' rack (1.5.2) lib/rack/etag.rb:23:in `call' rack (1.5.2) lib/rack/conditionalget.rb:25:in `call' rack (1.5.2) lib/rack/head.rb:11:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/params_parser.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/flash.rb:241:in `call' rack (1.5.2) lib/rack/session/abstract/id.rb:225:in `context' rack (1.5.2) lib/rack/session/abstract/id.rb:220:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/cookies.rb:486:in `call' activerecord (4.0.4) lib/active_record/query_cache.rb:36:in `call' activerecord (4.0.4) lib/active_record/connection_adapters/abstract/connection_pool.rb:626:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:29:in `block in call' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__3670105951747852129__call__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/reloader.rb:64:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/remote_ip.rb:76:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/debug_exceptions.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/show_exceptions.rb:30:in `call' railties (4.0.4) lib/rails/rack/logger.rb:38:in `call_app' railties (4.0.4) lib/rails/rack/logger.rb:20:in `block in call' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `block in tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `tagged' railties (4.0.4) lib/rails/rack/logger.rb:20:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/request_id.rb:21:in `call' rack (1.5.2) lib/rack/methodoverride.rb:21:in `call' rack (1.5.2) lib/rack/runtime.rb:17:in `call' activesupport (4.0.4) lib/active_support/cache/strategy/local_cache.rb:83:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/static.rb:64:in `call' rack (1.5.2) lib/rack/sendfile.rb:112:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/application.rb:97:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' rack (1.5.2) lib/rack/content_length.rb:14:in `call' rack (1.5.2) lib/rack/handler/webrick.rb:60:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:138:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:94:in `run' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/server.rb:295:in `block in start_thread' Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_source.erb (1.2ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.6ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.9ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (24.9ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2014-04-24 15:22:35 +0200 Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2014-04-24 15:22:35 +0200 Started PATCH "/opay/payu/paygw/UTF/NewPayment" for 127.0.0.1 at 2014-04-24 15:22:37 +0200 Processing by Opay::PayuController#paygw as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"6UyWqDKrR+zb2JeoY3rG0gbIAKV7yZHCJ2l3g6AiiRE=", "first_name"=>"Jan", "last_name"=>"Kowalski", "email"=>"kowalski@gmail.com", "desc"=>"Test payment", "client_ip"=>"127.0.0.1", "pos_id"=>"123456", "pos_auth_key"=>"DiEKzTD", "session_id"=>"JpqBqsCgrY6a4MtuP1xQ", "amount"=>"10", "js"=>"1", "pay_type"=>"t", "ts"=>"1398345716", "sig"=>"fbf5c774953eda0008cd28fef8a513e3", "commit"=>"pay_with_payu"} Rendered /Users/olownia/dev/gems/opay/app/views/opay/payu/paygw.html.erb within layouts/opay/application (1.4ms) Completed 200 OK in 7ms (Views: 7.2ms | ActiveRecord: 0.0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2014-04-24 15:22:37 +0200 Started GET "/assets/opay/application.js?body=1" for 127.0.0.1 at 2014-04-24 15:22:37 +0200 Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2014-04-24 15:22:37 +0200 Started GET "/assets/opay/application.css?body=1" for 127.0.0.1 at 2014-04-24 15:22:37 +0200 Started GET "/orders/1" for 127.0.0.1 at 2014-04-24 15:22:41 +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" = ? AND "opay_payments"."payable_type" = ? LIMIT 1 [["payable_id", 1], ["payable_type", "Order"]] Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."session_id" = 'Ym7rHuuTLVNpyEwvjj37' ORDER BY "opay_payments"."id" ASC LIMIT 1  (0.1ms) begin transaction SQL (0.6ms) UPDATE "opay_payments" SET "session_id" = ?, "updated_at" = ? WHERE "opay_payments"."id" = 1 [["session_id", "Ym7rHuuTLVNpyEwvjj37"], ["updated_at", Thu, 24 Apr 2014 13:22:41 UTC +00:00]]  (0.9ms) commit transaction Opay::Payment Load (0.3ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."session_id" = 'LHkeq5ZVQTxeEpffRzp4' ORDER BY "opay_payments"."id" ASC LIMIT 1  (0.1ms) begin transaction SQL (0.4ms) UPDATE "opay_payments" SET "session_id" = ?, "updated_at" = ? WHERE "opay_payments"."id" = 1 [["session_id", "LHkeq5ZVQTxeEpffRzp4"], ["updated_at", Thu, 24 Apr 2014 13:22:41 UTC +00:00]]  (0.9ms) commit transaction Rendered orders/show.html.erb within layouts/application (14.9ms) Completed 200 OK in 25ms (Views: 19.5ms | ActiveRecord: 3.8ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2014-04-24 15:22:41 +0200 Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2014-04-24 15:22:41 +0200 Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2014-04-24 15:22:41 +0200 Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2014-04-24 15:22:41 +0200 Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2014-04-24 15:22:41 +0200 Started GET "/orders/new" for 127.0.0.1 at 2014-04-24 15:22:44 +0200 Processing by OrdersController#new as HTML Rendered orders/_form.html.erb (88.8ms) Rendered orders/new.html.erb within layouts/application (96.7ms) Completed 200 OK in 103ms (Views: 102.0ms | ActiveRecord: 0.0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2014-04-24 15:22:44 +0200 Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2014-04-24 15:22:44 +0200 Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2014-04-24 15:22:44 +0200 Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2014-04-24 15:22:44 +0200 Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2014-04-24 15:22:44 +0200 Started GET "/orders" for 127.0.0.1 at 2014-04-24 15:22:48 +0200 Processing by OrdersController#index as HTML Order Load (0.4ms) SELECT "orders".* FROM "orders" Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = ? AND "opay_payments"."payable_type" = ? LIMIT 1 [["payable_id", 1], ["payable_type", "Order"]] Rendered orders/index.html.erb within layouts/application (6.0ms) Completed 200 OK in 15ms (Views: 13.8ms | ActiveRecord: 0.6ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2014-04-24 15:22:48 +0200 Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2014-04-24 15:22:48 +0200 Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2014-04-24 15:22:48 +0200 Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2014-04-24 15:22:48 +0200 Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2014-04-24 15:22:48 +0200 Started GET "/orders/1" for 127.0.0.1 at 2014-04-24 15:22:50 +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" = ? AND "opay_payments"."payable_type" = ? LIMIT 1 [["payable_id", 1], ["payable_type", "Order"]] Opay::Payment Load (0.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."session_id" = 'oysoR4uzDi3JsLW7CTcA' ORDER BY "opay_payments"."id" ASC LIMIT 1  (0.1ms) begin transaction SQL (0.6ms) UPDATE "opay_payments" SET "session_id" = ?, "updated_at" = ? WHERE "opay_payments"."id" = 1 [["session_id", "oysoR4uzDi3JsLW7CTcA"], ["updated_at", Thu, 24 Apr 2014 13:22:50 UTC +00:00]]  (0.9ms) commit transaction Opay::Payment Load (0.3ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."session_id" = 'ecRSsMZT7bz3KBnsZCPR' ORDER BY "opay_payments"."id" ASC LIMIT 1  (0.1ms) begin transaction SQL (0.5ms) UPDATE "opay_payments" SET "session_id" = ?, "updated_at" = ? WHERE "opay_payments"."id" = 1 [["session_id", "ecRSsMZT7bz3KBnsZCPR"], ["updated_at", Thu, 24 Apr 2014 13:22:50 UTC +00:00]]  (1.0ms) commit transaction Rendered orders/show.html.erb within layouts/application (15.1ms) Completed 200 OK in 24ms (Views: 18.9ms | ActiveRecord: 4.0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2014-04-24 15:22:50 +0200 Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2014-04-24 15:22:50 +0200 Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2014-04-24 15:22:50 +0200 Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2014-04-24 15:22:50 +0200 Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2014-04-24 15:22:50 +0200 Started PATCH "/opay/payu/paygw/UTF/NewPayment" for 127.0.0.1 at 2014-04-24 15:22:52 +0200 Processing by Opay::PayuController#paygw as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"6UyWqDKrR+zb2JeoY3rG0gbIAKV7yZHCJ2l3g6AiiRE=", "first_name"=>"Jan", "last_name"=>"Kowalski", "email"=>"kowalski@gmail.com", "desc"=>"Test payment", "client_ip"=>"127.0.0.1", "pos_id"=>"123456", "pos_auth_key"=>"DiEKzTD", "session_id"=>"oysoR4uzDi3JsLW7CTcA", "amount"=>"10", "js"=>"1", "pay_type"=>"t", "ts"=>"1398345770", "sig"=>"8d2eb14c0508b97fae2366ed76e24035", "commit"=>"pay_with_payu"} Rendered /Users/olownia/dev/gems/opay/app/views/opay/payu/paygw.html.erb within layouts/opay/application (1.3ms) Completed 200 OK in 8ms (Views: 7.5ms | ActiveRecord: 0.0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2014-04-24 15:22:52 +0200 Started GET "/assets/opay/application.js?body=1" for 127.0.0.1 at 2014-04-24 15:22:52 +0200 Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2014-04-24 15:22:52 +0200 Started GET "/assets/opay/application.css?body=1" for 127.0.0.1 at 2014-04-24 15:22:52 +0200 Started GET "/opay/wrong_authorizationt" for 127.0.0.1 at 2014-04-24 15:22:53 +0200 Processing by Opay::PayuController#wrong_authorizationt as HTML Completed 500 Internal Server Error in 3ms NoMethodError (undefined method `cancel_payment_path' for #): /Users/olownia/dev/gems/opay/app/controllers/opay/application_controller.rb:10:in `wrong_authorizationt' actionpack (4.0.4) lib/action_controller/metal/implicit_render.rb:4:in `send_action' actionpack (4.0.4) lib/abstract_controller/base.rb:189:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rendering.rb:10:in `process_action' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:18:in `block in process_action' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__2900285106230756710__process_action__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:17:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rescue.rb:29:in `process_action' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:31:in `block in process_action' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `block in instrument' activesupport (4.0.4) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `instrument' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:30:in `process_action' actionpack (4.0.4) lib/action_controller/metal/params_wrapper.rb:250:in `process_action' activerecord (4.0.4) lib/active_record/railties/controller_runtime.rb:18:in `process_action' actionpack (4.0.4) lib/abstract_controller/base.rb:136:in `process' actionpack (4.0.4) lib/abstract_controller/rendering.rb:44:in `process' actionpack (4.0.4) lib/action_controller/metal.rb:195:in `dispatch' actionpack (4.0.4) lib/action_controller/metal/rack_delegation.rb:13:in `dispatch' actionpack (4.0.4) lib/action_controller/metal.rb:231:in `block in action' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `dispatch' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:48:in `call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/railtie/configurable.rb:30:in `method_missing' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' rack (1.5.2) lib/rack/etag.rb:23:in `call' rack (1.5.2) lib/rack/conditionalget.rb:25:in `call' rack (1.5.2) lib/rack/head.rb:11:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/params_parser.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/flash.rb:241:in `call' rack (1.5.2) lib/rack/session/abstract/id.rb:225:in `context' rack (1.5.2) lib/rack/session/abstract/id.rb:220:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/cookies.rb:486:in `call' activerecord (4.0.4) lib/active_record/query_cache.rb:36:in `call' activerecord (4.0.4) lib/active_record/connection_adapters/abstract/connection_pool.rb:626:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:29:in `block in call' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__3670105951747852129__call__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/reloader.rb:64:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/remote_ip.rb:76:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/debug_exceptions.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/show_exceptions.rb:30:in `call' railties (4.0.4) lib/rails/rack/logger.rb:38:in `call_app' railties (4.0.4) lib/rails/rack/logger.rb:20:in `block in call' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `block in tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `tagged' railties (4.0.4) lib/rails/rack/logger.rb:20:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/request_id.rb:21:in `call' rack (1.5.2) lib/rack/methodoverride.rb:21:in `call' rack (1.5.2) lib/rack/runtime.rb:17:in `call' activesupport (4.0.4) lib/active_support/cache/strategy/local_cache.rb:83:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/static.rb:64:in `call' rack (1.5.2) lib/rack/sendfile.rb:112:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/application.rb:97:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' rack (1.5.2) lib/rack/content_length.rb:14:in `call' rack (1.5.2) lib/rack/handler/webrick.rb:60:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:138:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:94:in `run' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/server.rb:295:in `block in start_thread' Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_source.erb (0.8ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_trace.erb (2.3ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.8ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (22.1ms) Started GET "/assets/opay/application.css?body=1" for 127.0.0.1 at 2014-04-24 15:22:58 +0200 Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2014-04-24 15:22:58 +0200 Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2014-04-24 15:22:58 +0200 Started GET "/assets/opay/application.js?body=1" for 127.0.0.1 at 2014-04-24 15:22:58 +0200 Started GET "/opay/correct_authorization?session_id=oysoR4uzDi3JsLW7CTcA" for 127.0.0.1 at 2014-04-24 15:23:00 +0200 Processing by Opay::PayuController#correct_authorization as HTML Parameters: {"session_id"=>"oysoR4uzDi3JsLW7CTcA"} Opay::Payment Load (0.3ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."session_id" = 'oysoR4uzDi3JsLW7CTcA' ORDER BY "opay_payments"."id" ASC LIMIT 1 Completed 404 Not Found in 2ms ActiveRecord::RecordNotFound (ActiveRecord::RecordNotFound): activerecord (4.0.4) lib/active_record/relation/finder_methods.rb:98:in `first!' /Users/olownia/dev/gems/opay/app/controllers/opay/application_controller.rb:4:in `correct_authorization' actionpack (4.0.4) lib/action_controller/metal/implicit_render.rb:4:in `send_action' actionpack (4.0.4) lib/abstract_controller/base.rb:189:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rendering.rb:10:in `process_action' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:18:in `block in process_action' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__2900285106230756710__process_action__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:17:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rescue.rb:29:in `process_action' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:31:in `block in process_action' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `block in instrument' activesupport (4.0.4) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `instrument' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:30:in `process_action' actionpack (4.0.4) lib/action_controller/metal/params_wrapper.rb:250:in `process_action' activerecord (4.0.4) lib/active_record/railties/controller_runtime.rb:18:in `process_action' actionpack (4.0.4) lib/abstract_controller/base.rb:136:in `process' actionpack (4.0.4) lib/abstract_controller/rendering.rb:44:in `process' actionpack (4.0.4) lib/action_controller/metal.rb:195:in `dispatch' actionpack (4.0.4) lib/action_controller/metal/rack_delegation.rb:13:in `dispatch' actionpack (4.0.4) lib/action_controller/metal.rb:231:in `block in action' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `dispatch' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:48:in `call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/railtie/configurable.rb:30:in `method_missing' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' rack (1.5.2) lib/rack/etag.rb:23:in `call' rack (1.5.2) lib/rack/conditionalget.rb:25:in `call' rack (1.5.2) lib/rack/head.rb:11:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/params_parser.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/flash.rb:241:in `call' rack (1.5.2) lib/rack/session/abstract/id.rb:225:in `context' rack (1.5.2) lib/rack/session/abstract/id.rb:220:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/cookies.rb:486:in `call' activerecord (4.0.4) lib/active_record/query_cache.rb:36:in `call' activerecord (4.0.4) lib/active_record/connection_adapters/abstract/connection_pool.rb:626:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:29:in `block in call' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__3670105951747852129__call__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/reloader.rb:64:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/remote_ip.rb:76:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/debug_exceptions.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/show_exceptions.rb:30:in `call' railties (4.0.4) lib/rails/rack/logger.rb:38:in `call_app' railties (4.0.4) lib/rails/rack/logger.rb:20:in `block in call' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `block in tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `tagged' railties (4.0.4) lib/rails/rack/logger.rb:20:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/request_id.rb:21:in `call' rack (1.5.2) lib/rack/methodoverride.rb:21:in `call' rack (1.5.2) lib/rack/runtime.rb:17:in `call' activesupport (4.0.4) lib/active_support/cache/strategy/local_cache.rb:83:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/static.rb:64:in `call' rack (1.5.2) lib/rack/sendfile.rb:112:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/application.rb:97:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' rack (1.5.2) lib/rack/content_length.rb:14:in `call' rack (1.5.2) lib/rack/handler/webrick.rb:60:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:138:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:94:in `run' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/server.rb:295:in `block in start_thread' Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_source.erb (0.9ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.8ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (2.0ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (20.9ms) Started GET "/assets/opay/application.css?body=1" for 127.0.0.1 at 2014-04-24 15:23:24 +0200 Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2014-04-24 15:23:24 +0200 Started GET "/assets/opay/application.js?body=1" for 127.0.0.1 at 2014-04-24 15:23:24 +0200 Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2014-04-24 15:23:24 +0200 Started PATCH "/opay/payu/paygw/UTF/NewPayment" for 127.0.0.1 at 2014-04-24 15:23:26 +0200 Processing by Opay::PayuController#paygw as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"6UyWqDKrR+zb2JeoY3rG0gbIAKV7yZHCJ2l3g6AiiRE=", "first_name"=>"Jan", "last_name"=>"Kowalski", "email"=>"kowalski@gmail.com", "desc"=>"Test payment", "client_ip"=>"127.0.0.1", "pos_id"=>"123456", "pos_auth_key"=>"DiEKzTD", "session_id"=>"oysoR4uzDi3JsLW7CTcA", "amount"=>"10", "js"=>"1", "pay_type"=>"t", "ts"=>"1398345770", "sig"=>"8d2eb14c0508b97fae2366ed76e24035", "commit"=>"pay_with_payu"} Rendered /Users/olownia/dev/gems/opay/app/views/opay/payu/paygw.html.erb within layouts/opay/application (1.4ms) Completed 200 OK in 12ms (Views: 11.4ms | ActiveRecord: 0.0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2014-04-24 15:23:26 +0200 Started GET "/assets/opay/application.js?body=1" for 127.0.0.1 at 2014-04-24 15:23:26 +0200 Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2014-04-24 15:23:26 +0200 Started GET "/assets/opay/application.css?body=1" for 127.0.0.1 at 2014-04-24 15:23:26 +0200 Started GET "/opay/correct_authorization?session_id=oysoR4uzDi3JsLW7CTcA" for 127.0.0.1 at 2014-04-24 15:23:28 +0200 Processing by Opay::PayuController#correct_authorization as HTML Parameters: {"session_id"=>"oysoR4uzDi3JsLW7CTcA"} Opay::Payment Load (0.3ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."session_id" = 'oysoR4uzDi3JsLW7CTcA' ORDER BY "opay_payments"."id" ASC LIMIT 1 Completed 404 Not Found in 2ms ActiveRecord::RecordNotFound (ActiveRecord::RecordNotFound): activerecord (4.0.4) lib/active_record/relation/finder_methods.rb:98:in `first!' /Users/olownia/dev/gems/opay/app/controllers/opay/application_controller.rb:4:in `correct_authorization' actionpack (4.0.4) lib/action_controller/metal/implicit_render.rb:4:in `send_action' actionpack (4.0.4) lib/abstract_controller/base.rb:189:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rendering.rb:10:in `process_action' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:18:in `block in process_action' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__2900285106230756710__process_action__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:17:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rescue.rb:29:in `process_action' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:31:in `block in process_action' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `block in instrument' activesupport (4.0.4) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `instrument' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:30:in `process_action' actionpack (4.0.4) lib/action_controller/metal/params_wrapper.rb:250:in `process_action' activerecord (4.0.4) lib/active_record/railties/controller_runtime.rb:18:in `process_action' actionpack (4.0.4) lib/abstract_controller/base.rb:136:in `process' actionpack (4.0.4) lib/abstract_controller/rendering.rb:44:in `process' actionpack (4.0.4) lib/action_controller/metal.rb:195:in `dispatch' actionpack (4.0.4) lib/action_controller/metal/rack_delegation.rb:13:in `dispatch' actionpack (4.0.4) lib/action_controller/metal.rb:231:in `block in action' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `dispatch' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:48:in `call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/railtie/configurable.rb:30:in `method_missing' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' rack (1.5.2) lib/rack/etag.rb:23:in `call' rack (1.5.2) lib/rack/conditionalget.rb:25:in `call' rack (1.5.2) lib/rack/head.rb:11:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/params_parser.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/flash.rb:241:in `call' rack (1.5.2) lib/rack/session/abstract/id.rb:225:in `context' rack (1.5.2) lib/rack/session/abstract/id.rb:220:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/cookies.rb:486:in `call' activerecord (4.0.4) lib/active_record/query_cache.rb:36:in `call' activerecord (4.0.4) lib/active_record/connection_adapters/abstract/connection_pool.rb:626:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:29:in `block in call' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__3670105951747852129__call__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/reloader.rb:64:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/remote_ip.rb:76:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/debug_exceptions.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/show_exceptions.rb:30:in `call' railties (4.0.4) lib/rails/rack/logger.rb:38:in `call_app' railties (4.0.4) lib/rails/rack/logger.rb:20:in `block in call' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `block in tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `tagged' railties (4.0.4) lib/rails/rack/logger.rb:20:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/request_id.rb:21:in `call' rack (1.5.2) lib/rack/methodoverride.rb:21:in `call' rack (1.5.2) lib/rack/runtime.rb:17:in `call' activesupport (4.0.4) lib/active_support/cache/strategy/local_cache.rb:83:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/static.rb:64:in `call' rack (1.5.2) lib/rack/sendfile.rb:112:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/application.rb:97:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' rack (1.5.2) lib/rack/content_length.rb:14:in `call' rack (1.5.2) lib/rack/handler/webrick.rb:60:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:138:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:94:in `run' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/server.rb:295:in `block in start_thread' Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_source.erb (0.9ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.6ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.9ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (18.6ms) Started GET "/assets/opay/application.css?body=1" for 127.0.0.1 at 2014-04-24 15:23:33 +0200 Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2014-04-24 15:23:33 +0200 Started GET "/assets/opay/application.js?body=1" for 127.0.0.1 at 2014-04-24 15:23:33 +0200 Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2014-04-24 15:23:33 +0200 Started GET "/opay/wrong_authorizationt" for 127.0.0.1 at 2014-04-24 15:23:35 +0200 Processing by Opay::PayuController#wrong_authorizationt as HTML Completed 500 Internal Server Error in 2ms NoMethodError (undefined method `cancel_payment_path' for #): /Users/olownia/dev/gems/opay/app/controllers/opay/application_controller.rb:10:in `wrong_authorizationt' actionpack (4.0.4) lib/action_controller/metal/implicit_render.rb:4:in `send_action' actionpack (4.0.4) lib/abstract_controller/base.rb:189:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rendering.rb:10:in `process_action' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:18:in `block in process_action' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__2900285106230756710__process_action__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:17:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rescue.rb:29:in `process_action' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:31:in `block in process_action' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `block in instrument' activesupport (4.0.4) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `instrument' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:30:in `process_action' actionpack (4.0.4) lib/action_controller/metal/params_wrapper.rb:250:in `process_action' activerecord (4.0.4) lib/active_record/railties/controller_runtime.rb:18:in `process_action' actionpack (4.0.4) lib/abstract_controller/base.rb:136:in `process' actionpack (4.0.4) lib/abstract_controller/rendering.rb:44:in `process' actionpack (4.0.4) lib/action_controller/metal.rb:195:in `dispatch' actionpack (4.0.4) lib/action_controller/metal/rack_delegation.rb:13:in `dispatch' actionpack (4.0.4) lib/action_controller/metal.rb:231:in `block in action' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `dispatch' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:48:in `call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/railtie/configurable.rb:30:in `method_missing' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' rack (1.5.2) lib/rack/etag.rb:23:in `call' rack (1.5.2) lib/rack/conditionalget.rb:25:in `call' rack (1.5.2) lib/rack/head.rb:11:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/params_parser.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/flash.rb:241:in `call' rack (1.5.2) lib/rack/session/abstract/id.rb:225:in `context' rack (1.5.2) lib/rack/session/abstract/id.rb:220:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/cookies.rb:486:in `call' activerecord (4.0.4) lib/active_record/query_cache.rb:36:in `call' activerecord (4.0.4) lib/active_record/connection_adapters/abstract/connection_pool.rb:626:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:29:in `block in call' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__3670105951747852129__call__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/reloader.rb:64:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/remote_ip.rb:76:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/debug_exceptions.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/show_exceptions.rb:30:in `call' railties (4.0.4) lib/rails/rack/logger.rb:38:in `call_app' railties (4.0.4) lib/rails/rack/logger.rb:20:in `block in call' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `block in tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `tagged' railties (4.0.4) lib/rails/rack/logger.rb:20:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/request_id.rb:21:in `call' rack (1.5.2) lib/rack/methodoverride.rb:21:in `call' rack (1.5.2) lib/rack/runtime.rb:17:in `call' activesupport (4.0.4) lib/active_support/cache/strategy/local_cache.rb:83:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/static.rb:64:in `call' rack (1.5.2) lib/rack/sendfile.rb:112:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/application.rb:97:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' rack (1.5.2) lib/rack/content_length.rb:14:in `call' rack (1.5.2) lib/rack/handler/webrick.rb:60:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:138:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:94:in `run' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/server.rb:295:in `block in start_thread' Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_source.erb (0.9ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.7ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.8ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (20.5ms) Started GET "/opay/wrong_authorizationt" for 127.0.0.1 at 2014-04-24 15:23:55 +0200 Processing by Opay::PayuController#wrong_authorizationt as HTML Completed 500 Internal Server Error in 3ms NoMethodError (undefined method `cancel_payment_path' for #): /Users/olownia/dev/gems/opay/app/controllers/opay/application_controller.rb:10:in `wrong_authorizationt' actionpack (4.0.4) lib/action_controller/metal/implicit_render.rb:4:in `send_action' actionpack (4.0.4) lib/abstract_controller/base.rb:189:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rendering.rb:10:in `process_action' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:18:in `block in process_action' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__2900285106230756710__process_action__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:17:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rescue.rb:29:in `process_action' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:31:in `block in process_action' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `block in instrument' activesupport (4.0.4) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `instrument' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:30:in `process_action' actionpack (4.0.4) lib/action_controller/metal/params_wrapper.rb:250:in `process_action' activerecord (4.0.4) lib/active_record/railties/controller_runtime.rb:18:in `process_action' actionpack (4.0.4) lib/abstract_controller/base.rb:136:in `process' actionpack (4.0.4) lib/abstract_controller/rendering.rb:44:in `process' actionpack (4.0.4) lib/action_controller/metal.rb:195:in `dispatch' actionpack (4.0.4) lib/action_controller/metal/rack_delegation.rb:13:in `dispatch' actionpack (4.0.4) lib/action_controller/metal.rb:231:in `block in action' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `dispatch' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:48:in `call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/railtie/configurable.rb:30:in `method_missing' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' rack (1.5.2) lib/rack/etag.rb:23:in `call' rack (1.5.2) lib/rack/conditionalget.rb:25:in `call' rack (1.5.2) lib/rack/head.rb:11:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/params_parser.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/flash.rb:241:in `call' rack (1.5.2) lib/rack/session/abstract/id.rb:225:in `context' rack (1.5.2) lib/rack/session/abstract/id.rb:220:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/cookies.rb:486:in `call' activerecord (4.0.4) lib/active_record/query_cache.rb:36:in `call' activerecord (4.0.4) lib/active_record/connection_adapters/abstract/connection_pool.rb:626:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:29:in `block in call' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__3670105951747852129__call__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/reloader.rb:64:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/remote_ip.rb:76:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/debug_exceptions.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/show_exceptions.rb:30:in `call' railties (4.0.4) lib/rails/rack/logger.rb:38:in `call_app' railties (4.0.4) lib/rails/rack/logger.rb:20:in `block in call' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `block in tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `tagged' railties (4.0.4) lib/rails/rack/logger.rb:20:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/request_id.rb:21:in `call' rack (1.5.2) lib/rack/methodoverride.rb:21:in `call' rack (1.5.2) lib/rack/runtime.rb:17:in `call' activesupport (4.0.4) lib/active_support/cache/strategy/local_cache.rb:83:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/static.rb:64:in `call' rack (1.5.2) lib/rack/sendfile.rb:112:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/application.rb:97:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' rack (1.5.2) lib/rack/content_length.rb:14:in `call' rack (1.5.2) lib/rack/handler/webrick.rb:60:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:138:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:94:in `run' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/server.rb:295:in `block in start_thread' Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_source.erb (0.8ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.6ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.8ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (20.2ms) Started GET "/assets/opay/application.css?body=1" for 127.0.0.1 at 2014-04-24 15:23:57 +0200 Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2014-04-24 15:23:57 +0200 Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2014-04-24 15:23:57 +0200 Started GET "/assets/opay/application.js?body=1" for 127.0.0.1 at 2014-04-24 15:23:57 +0200 Started GET "/opay/correct_authorization?session_id=oysoR4uzDi3JsLW7CTcA" for 127.0.0.1 at 2014-04-24 15:24:10 +0200 Processing by Opay::PayuController#correct_authorization as HTML Parameters: {"session_id"=>"oysoR4uzDi3JsLW7CTcA"} Opay::Payment Load (0.3ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."session_id" = 'oysoR4uzDi3JsLW7CTcA' ORDER BY "opay_payments"."id" ASC LIMIT 1 Completed 404 Not Found in 15ms ActiveRecord::RecordNotFound (ActiveRecord::RecordNotFound): activerecord (4.0.4) lib/active_record/relation/finder_methods.rb:98:in `first!' /Users/olownia/dev/gems/opay/app/controllers/opay/application_controller.rb:4:in `correct_authorization' actionpack (4.0.4) lib/action_controller/metal/implicit_render.rb:4:in `send_action' actionpack (4.0.4) lib/abstract_controller/base.rb:189:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rendering.rb:10:in `process_action' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:18:in `block in process_action' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__1500625184490485662__process_action__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:17:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rescue.rb:29:in `process_action' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:31:in `block in process_action' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `block in instrument' activesupport (4.0.4) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `instrument' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:30:in `process_action' actionpack (4.0.4) lib/action_controller/metal/params_wrapper.rb:250:in `process_action' activerecord (4.0.4) lib/active_record/railties/controller_runtime.rb:18:in `process_action' actionpack (4.0.4) lib/abstract_controller/base.rb:136:in `process' actionpack (4.0.4) lib/abstract_controller/rendering.rb:44:in `process' actionpack (4.0.4) lib/action_controller/metal.rb:195:in `dispatch' actionpack (4.0.4) lib/action_controller/metal/rack_delegation.rb:13:in `dispatch' actionpack (4.0.4) lib/action_controller/metal.rb:231:in `block in action' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `dispatch' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:48:in `call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/railtie/configurable.rb:30:in `method_missing' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' rack (1.5.2) lib/rack/etag.rb:23:in `call' rack (1.5.2) lib/rack/conditionalget.rb:25:in `call' rack (1.5.2) lib/rack/head.rb:11:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/params_parser.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/flash.rb:241:in `call' rack (1.5.2) lib/rack/session/abstract/id.rb:225:in `context' rack (1.5.2) lib/rack/session/abstract/id.rb:220:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/cookies.rb:486:in `call' activerecord (4.0.4) lib/active_record/query_cache.rb:36:in `call' activerecord (4.0.4) lib/active_record/connection_adapters/abstract/connection_pool.rb:626:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:29:in `block in call' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__4332336798211170224__call__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/reloader.rb:64:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/remote_ip.rb:76:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/debug_exceptions.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/show_exceptions.rb:30:in `call' railties (4.0.4) lib/rails/rack/logger.rb:38:in `call_app' railties (4.0.4) lib/rails/rack/logger.rb:20:in `block in call' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `block in tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `tagged' railties (4.0.4) lib/rails/rack/logger.rb:20:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/request_id.rb:21:in `call' rack (1.5.2) lib/rack/methodoverride.rb:21:in `call' rack (1.5.2) lib/rack/runtime.rb:17:in `call' activesupport (4.0.4) lib/active_support/cache/strategy/local_cache.rb:83:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/static.rb:64:in `call' rack (1.5.2) lib/rack/sendfile.rb:112:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/application.rb:97:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' rack (1.5.2) lib/rack/content_length.rb:14:in `call' rack (1.5.2) lib/rack/handler/webrick.rb:60:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:138:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:94:in `run' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/server.rb:295:in `block in start_thread' Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_source.erb (0.9ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.7ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (17.2ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (40.8ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2014-04-24 15:24:14 +0200 Started GET "/assets/opay/application.css?body=1" for 127.0.0.1 at 2014-04-24 15:24:14 +0200 Started GET "/assets/opay/application.js?body=1" for 127.0.0.1 at 2014-04-24 15:24:14 +0200 Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2014-04-24 15:24:14 +0200 Started PATCH "/opay/payu/paygw/UTF/NewPayment" for 127.0.0.1 at 2014-04-24 15:24:16 +0200 Processing by Opay::PayuController#paygw as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"6UyWqDKrR+zb2JeoY3rG0gbIAKV7yZHCJ2l3g6AiiRE=", "first_name"=>"Jan", "last_name"=>"Kowalski", "email"=>"kowalski@gmail.com", "desc"=>"Test payment", "client_ip"=>"127.0.0.1", "pos_id"=>"123456", "pos_auth_key"=>"DiEKzTD", "session_id"=>"oysoR4uzDi3JsLW7CTcA", "amount"=>"10", "js"=>"1", "pay_type"=>"t", "ts"=>"1398345770", "sig"=>"8d2eb14c0508b97fae2366ed76e24035", "commit"=>"pay_with_payu"} Rendered /Users/olownia/dev/gems/opay/app/views/opay/payu/paygw.html.erb within layouts/opay/application (8.8ms) Completed 200 OK in 102ms (Views: 101.6ms | ActiveRecord: 0.0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2014-04-24 15:24:16 +0200 Started GET "/assets/opay/application.js?body=1" for 127.0.0.1 at 2014-04-24 15:24:16 +0200 Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2014-04-24 15:24:16 +0200 Started GET "/assets/opay/application.css?body=1" for 127.0.0.1 at 2014-04-24 15:24:16 +0200 Started GET "/opay/wrong_authorizationt" for 127.0.0.1 at 2014-04-24 15:24:17 +0200 Processing by Opay::PayuController#wrong_authorizationt as HTML Completed 500 Internal Server Error in 4ms NoMethodError (undefined method `root_path' for #): /Users/olownia/dev/gems/opay/app/controllers/opay/application_controller.rb:10:in `wrong_authorizationt' actionpack (4.0.4) lib/action_controller/metal/implicit_render.rb:4:in `send_action' actionpack (4.0.4) lib/abstract_controller/base.rb:189:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rendering.rb:10:in `process_action' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:18:in `block in process_action' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__1500625184490485662__process_action__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:17:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rescue.rb:29:in `process_action' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:31:in `block in process_action' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `block in instrument' activesupport (4.0.4) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `instrument' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:30:in `process_action' actionpack (4.0.4) lib/action_controller/metal/params_wrapper.rb:250:in `process_action' activerecord (4.0.4) lib/active_record/railties/controller_runtime.rb:18:in `process_action' actionpack (4.0.4) lib/abstract_controller/base.rb:136:in `process' actionpack (4.0.4) lib/abstract_controller/rendering.rb:44:in `process' actionpack (4.0.4) lib/action_controller/metal.rb:195:in `dispatch' actionpack (4.0.4) lib/action_controller/metal/rack_delegation.rb:13:in `dispatch' actionpack (4.0.4) lib/action_controller/metal.rb:231:in `block in action' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `dispatch' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:48:in `call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/railtie/configurable.rb:30:in `method_missing' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' rack (1.5.2) lib/rack/etag.rb:23:in `call' rack (1.5.2) lib/rack/conditionalget.rb:25:in `call' rack (1.5.2) lib/rack/head.rb:11:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/params_parser.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/flash.rb:241:in `call' rack (1.5.2) lib/rack/session/abstract/id.rb:225:in `context' rack (1.5.2) lib/rack/session/abstract/id.rb:220:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/cookies.rb:486:in `call' activerecord (4.0.4) lib/active_record/query_cache.rb:36:in `call' activerecord (4.0.4) lib/active_record/connection_adapters/abstract/connection_pool.rb:626:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:29:in `block in call' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__4332336798211170224__call__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/reloader.rb:64:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/remote_ip.rb:76:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/debug_exceptions.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/show_exceptions.rb:30:in `call' railties (4.0.4) lib/rails/rack/logger.rb:38:in `call_app' railties (4.0.4) lib/rails/rack/logger.rb:20:in `block in call' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `block in tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `tagged' railties (4.0.4) lib/rails/rack/logger.rb:20:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/request_id.rb:21:in `call' rack (1.5.2) lib/rack/methodoverride.rb:21:in `call' rack (1.5.2) lib/rack/runtime.rb:17:in `call' activesupport (4.0.4) lib/active_support/cache/strategy/local_cache.rb:83:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/static.rb:64:in `call' rack (1.5.2) lib/rack/sendfile.rb:112:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/application.rb:97:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' rack (1.5.2) lib/rack/content_length.rb:14:in `call' rack (1.5.2) lib/rack/handler/webrick.rb:60:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:138:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:94:in `run' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/server.rb:295:in `block in start_thread' Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_source.erb (1.1ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.9ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.8ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (22.2ms) Started GET "/opay/wrong_authorizationt" for 127.0.0.1 at 2014-04-24 15:25:04 +0200 Processing by Opay::PayuController#wrong_authorizationt as HTML Completed 500 Internal Server Error in 4ms NoMethodError (undefined method `root_path' for #): /Users/olownia/dev/gems/opay/app/controllers/opay/application_controller.rb:10:in `wrong_authorizationt' actionpack (4.0.4) lib/action_controller/metal/implicit_render.rb:4:in `send_action' actionpack (4.0.4) lib/abstract_controller/base.rb:189:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rendering.rb:10:in `process_action' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:18:in `block in process_action' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__1500625184490485662__process_action__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:17:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rescue.rb:29:in `process_action' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:31:in `block in process_action' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `block in instrument' activesupport (4.0.4) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `instrument' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:30:in `process_action' actionpack (4.0.4) lib/action_controller/metal/params_wrapper.rb:250:in `process_action' activerecord (4.0.4) lib/active_record/railties/controller_runtime.rb:18:in `process_action' actionpack (4.0.4) lib/abstract_controller/base.rb:136:in `process' actionpack (4.0.4) lib/abstract_controller/rendering.rb:44:in `process' actionpack (4.0.4) lib/action_controller/metal.rb:195:in `dispatch' actionpack (4.0.4) lib/action_controller/metal/rack_delegation.rb:13:in `dispatch' actionpack (4.0.4) lib/action_controller/metal.rb:231:in `block in action' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `dispatch' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:48:in `call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/railtie/configurable.rb:30:in `method_missing' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' rack (1.5.2) lib/rack/etag.rb:23:in `call' rack (1.5.2) lib/rack/conditionalget.rb:25:in `call' rack (1.5.2) lib/rack/head.rb:11:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/params_parser.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/flash.rb:241:in `call' rack (1.5.2) lib/rack/session/abstract/id.rb:225:in `context' rack (1.5.2) lib/rack/session/abstract/id.rb:220:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/cookies.rb:486:in `call' activerecord (4.0.4) lib/active_record/query_cache.rb:36:in `call' activerecord (4.0.4) lib/active_record/connection_adapters/abstract/connection_pool.rb:626:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:29:in `block in call' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__4332336798211170224__call__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/reloader.rb:64:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/remote_ip.rb:76:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/debug_exceptions.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/show_exceptions.rb:30:in `call' railties (4.0.4) lib/rails/rack/logger.rb:38:in `call_app' railties (4.0.4) lib/rails/rack/logger.rb:20:in `block in call' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `block in tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `tagged' railties (4.0.4) lib/rails/rack/logger.rb:20:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/request_id.rb:21:in `call' rack (1.5.2) lib/rack/methodoverride.rb:21:in `call' rack (1.5.2) lib/rack/runtime.rb:17:in `call' activesupport (4.0.4) lib/active_support/cache/strategy/local_cache.rb:83:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/static.rb:64:in `call' rack (1.5.2) lib/rack/sendfile.rb:112:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/application.rb:97:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' rack (1.5.2) lib/rack/content_length.rb:14:in `call' rack (1.5.2) lib/rack/handler/webrick.rb:60:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:138:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:94:in `run' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/server.rb:295:in `block in start_thread' Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_source.erb (1.2ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.8ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (2.8ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (24.8ms) Started GET "/opay/wrong_authorizationt" for 127.0.0.1 at 2014-04-24 15:25:38 +0200 Processing by Opay::PayuController#wrong_authorizationt as HTML Completed 500 Internal Server Error in 3ms NoMethodError (undefined method `root_path' for #): /Users/olownia/dev/gems/opay/app/controllers/opay/application_controller.rb:10:in `wrong_authorizationt' actionpack (4.0.4) lib/action_controller/metal/implicit_render.rb:4:in `send_action' actionpack (4.0.4) lib/abstract_controller/base.rb:189:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rendering.rb:10:in `process_action' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:18:in `block in process_action' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__1500625184490485662__process_action__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:17:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rescue.rb:29:in `process_action' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:31:in `block in process_action' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `block in instrument' activesupport (4.0.4) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `instrument' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:30:in `process_action' actionpack (4.0.4) lib/action_controller/metal/params_wrapper.rb:250:in `process_action' activerecord (4.0.4) lib/active_record/railties/controller_runtime.rb:18:in `process_action' actionpack (4.0.4) lib/abstract_controller/base.rb:136:in `process' actionpack (4.0.4) lib/abstract_controller/rendering.rb:44:in `process' actionpack (4.0.4) lib/action_controller/metal.rb:195:in `dispatch' actionpack (4.0.4) lib/action_controller/metal/rack_delegation.rb:13:in `dispatch' actionpack (4.0.4) lib/action_controller/metal.rb:231:in `block in action' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `dispatch' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:48:in `call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/railtie/configurable.rb:30:in `method_missing' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' rack (1.5.2) lib/rack/etag.rb:23:in `call' rack (1.5.2) lib/rack/conditionalget.rb:25:in `call' rack (1.5.2) lib/rack/head.rb:11:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/params_parser.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/flash.rb:241:in `call' rack (1.5.2) lib/rack/session/abstract/id.rb:225:in `context' rack (1.5.2) lib/rack/session/abstract/id.rb:220:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/cookies.rb:486:in `call' activerecord (4.0.4) lib/active_record/query_cache.rb:36:in `call' activerecord (4.0.4) lib/active_record/connection_adapters/abstract/connection_pool.rb:626:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:29:in `block in call' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__4332336798211170224__call__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/reloader.rb:64:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/remote_ip.rb:76:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/debug_exceptions.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/show_exceptions.rb:30:in `call' railties (4.0.4) lib/rails/rack/logger.rb:38:in `call_app' railties (4.0.4) lib/rails/rack/logger.rb:20:in `block in call' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `block in tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `tagged' railties (4.0.4) lib/rails/rack/logger.rb:20:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/request_id.rb:21:in `call' rack (1.5.2) lib/rack/methodoverride.rb:21:in `call' rack (1.5.2) lib/rack/runtime.rb:17:in `call' activesupport (4.0.4) lib/active_support/cache/strategy/local_cache.rb:83:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/static.rb:64:in `call' rack (1.5.2) lib/rack/sendfile.rb:112:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/application.rb:97:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' rack (1.5.2) lib/rack/content_length.rb:14:in `call' rack (1.5.2) lib/rack/handler/webrick.rb:60:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:138:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:94:in `run' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/server.rb:295:in `block in start_thread' Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_source.erb (0.8ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.7ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.8ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (21.1ms) Started GET "/opay/wrong_authorizationt" for 127.0.0.1 at 2014-04-24 15:25:51 +0200 Processing by Opay::PayuController#wrong_authorizationt as HTML Redirected to http://localhost:3000/ Completed 302 Found in 7ms (ActiveRecord: 0.0ms) Started GET "/" for 127.0.0.1 at 2014-04-24 15:25:51 +0200 Processing by OrdersController#index as HTML Order Load (4.2ms) SELECT "orders".* FROM "orders" Opay::Payment Load (4.2ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = ? AND "opay_payments"."payable_type" = ? LIMIT 1 [["payable_id", 1], ["payable_type", "Order"]] Rendered orders/index.html.erb within layouts/application (64.4ms) Completed 200 OK in 195ms (Views: 176.1ms | ActiveRecord: 9.3ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2014-04-24 15:25:52 +0200 Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2014-04-24 15:25:52 +0200 Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2014-04-24 15:25:52 +0200 Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2014-04-24 15:25:52 +0200 Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2014-04-24 15:25:52 +0200 Started GET "/orders/1" for 127.0.0.1 at 2014-04-24 15:25:55 +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" = ? AND "opay_payments"."payable_type" = ? LIMIT 1 [["payable_id", 1], ["payable_type", "Order"]] Opay::Payment Load (0.3ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."session_id" = 'YMruHWLUVDTFwjhzBJk3' ORDER BY "opay_payments"."id" ASC LIMIT 1  (0.2ms) begin transaction SQL (3.1ms) UPDATE "opay_payments" SET "session_id" = ?, "updated_at" = ? WHERE "opay_payments"."id" = 1 [["session_id", "YMruHWLUVDTFwjhzBJk3"], ["updated_at", Thu, 24 Apr 2014 13:25:55 UTC +00:00]]  (1.3ms) commit transaction Opay::Payment Load (0.3ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."session_id" = 'Lp3pWAAg7Poxhzva7x2B' ORDER BY "opay_payments"."id" ASC LIMIT 1  (0.1ms) begin transaction SQL (0.5ms) UPDATE "opay_payments" SET "session_id" = ?, "updated_at" = ? WHERE "opay_payments"."id" = 1 [["session_id", "Lp3pWAAg7Poxhzva7x2B"], ["updated_at", Thu, 24 Apr 2014 13:25:55 UTC +00:00]]  (0.9ms) commit transaction Rendered orders/show.html.erb within layouts/application (35.5ms) Completed 200 OK in 45ms (Views: 36.4ms | ActiveRecord: 7.2ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2014-04-24 15:25:56 +0200 Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2014-04-24 15:25:56 +0200 Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2014-04-24 15:25:56 +0200 Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2014-04-24 15:25:56 +0200 Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2014-04-24 15:25:56 +0200 Started PATCH "/opay/paypal/new" for 127.0.0.1 at 2014-04-24 15:25:57 +0200 Processing by Opay::PaypalController#new as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"6UyWqDKrR+zb2JeoY3rG0gbIAKV7yZHCJ2l3g6AiiRE=", "desc"=>"Test payment", "client_ip"=>"127.0.0.1", "confirm_url"=>"http://localhost:3000/opay/paypal/confirm", "cancel_url"=>"/", "session_id"=>"Lp3pWAAg7Poxhzva7x2B", "amount"=>"10", "x"=>"63", "y"=>"14"} Rendered /Users/olownia/dev/gems/opay/app/views/opay/paypal/new.html.erb within layouts/opay/application (8.9ms) Completed 200 OK in 89ms (Views: 88.6ms | ActiveRecord: 0.0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2014-04-24 15:25:57 +0200 Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2014-04-24 15:25:57 +0200 Started GET "/assets/opay/application.js?body=1" for 127.0.0.1 at 2014-04-24 15:25:57 +0200 Started GET "/assets/opay/application.css?body=1" for 127.0.0.1 at 2014-04-24 15:25:57 +0200 Started GET "/opay/correct_authorization?session_id=Lp3pWAAg7Poxhzva7x2B" for 127.0.0.1 at 2014-04-24 15:25:58 +0200 Processing by Opay::PayuController#correct_authorization as HTML Parameters: {"session_id"=>"Lp3pWAAg7Poxhzva7x2B"} Opay::Payment Load (0.3ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."session_id" = 'Lp3pWAAg7Poxhzva7x2B' ORDER BY "opay_payments"."id" ASC LIMIT 1 Order Load (0.2ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", 1]] Opay::Payment Load (0.5ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = ? AND "opay_payments"."payable_type" = ? LIMIT 1 [["payable_id", 1], ["payable_type", "Order"]]  (0.1ms) begin transaction SQL (0.5ms) UPDATE "opay_payments" SET "finished" = ?, "updated_at" = ? WHERE "opay_payments"."id" = 1 [["finished", true], ["updated_at", Thu, 24 Apr 2014 13:25:58 UTC +00:00]]  (0.8ms) commit transaction  (0.1ms) begin transaction SQL (0.6ms) UPDATE "orders" SET "finished" = ?, "updated_at" = ? WHERE "orders"."id" = 1 [["finished", true], ["updated_at", Thu, 24 Apr 2014 13:25:58 UTC +00:00]]  (1.0ms) commit transaction Completed 500 Internal Server Error in 21ms NoMethodError (undefined method `root_path' for #): /Users/olownia/dev/gems/opay/app/controllers/opay/application_controller.rb:6:in `correct_authorization' actionpack (4.0.4) lib/action_controller/metal/implicit_render.rb:4:in `send_action' actionpack (4.0.4) lib/abstract_controller/base.rb:189:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rendering.rb:10:in `process_action' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:18:in `block in process_action' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__1529530369328220172__process_action__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:17:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rescue.rb:29:in `process_action' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:31:in `block in process_action' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `block in instrument' activesupport (4.0.4) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `instrument' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:30:in `process_action' actionpack (4.0.4) lib/action_controller/metal/params_wrapper.rb:250:in `process_action' activerecord (4.0.4) lib/active_record/railties/controller_runtime.rb:18:in `process_action' actionpack (4.0.4) lib/abstract_controller/base.rb:136:in `process' actionpack (4.0.4) lib/abstract_controller/rendering.rb:44:in `process' actionpack (4.0.4) lib/action_controller/metal.rb:195:in `dispatch' actionpack (4.0.4) lib/action_controller/metal/rack_delegation.rb:13:in `dispatch' actionpack (4.0.4) lib/action_controller/metal.rb:231:in `block in action' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `dispatch' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:48:in `call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/railtie/configurable.rb:30:in `method_missing' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' rack (1.5.2) lib/rack/etag.rb:23:in `call' rack (1.5.2) lib/rack/conditionalget.rb:25:in `call' rack (1.5.2) lib/rack/head.rb:11:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/params_parser.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/flash.rb:241:in `call' rack (1.5.2) lib/rack/session/abstract/id.rb:225:in `context' rack (1.5.2) lib/rack/session/abstract/id.rb:220:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/cookies.rb:486:in `call' activerecord (4.0.4) lib/active_record/query_cache.rb:36:in `call' activerecord (4.0.4) lib/active_record/connection_adapters/abstract/connection_pool.rb:626:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:29:in `block in call' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__4065094992399331645__call__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/reloader.rb:64:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/remote_ip.rb:76:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/debug_exceptions.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/show_exceptions.rb:30:in `call' railties (4.0.4) lib/rails/rack/logger.rb:38:in `call_app' railties (4.0.4) lib/rails/rack/logger.rb:20:in `block in call' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `block in tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `tagged' railties (4.0.4) lib/rails/rack/logger.rb:20:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/request_id.rb:21:in `call' rack (1.5.2) lib/rack/methodoverride.rb:21:in `call' rack (1.5.2) lib/rack/runtime.rb:17:in `call' activesupport (4.0.4) lib/active_support/cache/strategy/local_cache.rb:83:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/static.rb:64:in `call' rack (1.5.2) lib/rack/sendfile.rb:112:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/application.rb:97:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' rack (1.5.2) lib/rack/content_length.rb:14:in `call' rack (1.5.2) lib/rack/handler/webrick.rb:60:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:138:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:94:in `run' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/server.rb:295:in `block in start_thread' Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_source.erb (1.0ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.8ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (2.0ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (29.2ms) Started GET "/opay/correct_authorization?session_id=Lp3pWAAg7Poxhzva7x2B" for 127.0.0.1 at 2014-04-24 15:26:06 +0200 Processing by Opay::PayuController#correct_authorization as HTML Parameters: {"session_id"=>"Lp3pWAAg7Poxhzva7x2B"} Opay::Payment Load (0.3ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."session_id" = 'Lp3pWAAg7Poxhzva7x2B' ORDER BY "opay_payments"."id" ASC LIMIT 1 Order Load (0.2ms) 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" = ? AND "opay_payments"."payable_type" = ? LIMIT 1 [["payable_id", 1], ["payable_type", "Order"]]  (0.1ms) begin transaction  (0.1ms) commit transaction  (0.0ms) begin transaction  (0.1ms) commit transaction Completed 500 Internal Server Error in 64ms NoMethodError (undefined method `root_path' for #): /Users/olownia/dev/gems/opay/app/controllers/opay/application_controller.rb:6:in `correct_authorization' actionpack (4.0.4) lib/action_controller/metal/implicit_render.rb:4:in `send_action' actionpack (4.0.4) lib/abstract_controller/base.rb:189:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rendering.rb:10:in `process_action' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:18:in `block in process_action' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__1529530369328220172__process_action__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:17:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rescue.rb:29:in `process_action' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:31:in `block in process_action' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `block in instrument' activesupport (4.0.4) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `instrument' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:30:in `process_action' actionpack (4.0.4) lib/action_controller/metal/params_wrapper.rb:250:in `process_action' activerecord (4.0.4) lib/active_record/railties/controller_runtime.rb:18:in `process_action' actionpack (4.0.4) lib/abstract_controller/base.rb:136:in `process' actionpack (4.0.4) lib/abstract_controller/rendering.rb:44:in `process' actionpack (4.0.4) lib/action_controller/metal.rb:195:in `dispatch' actionpack (4.0.4) lib/action_controller/metal/rack_delegation.rb:13:in `dispatch' actionpack (4.0.4) lib/action_controller/metal.rb:231:in `block in action' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `dispatch' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:48:in `call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/railtie/configurable.rb:30:in `method_missing' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' rack (1.5.2) lib/rack/etag.rb:23:in `call' rack (1.5.2) lib/rack/conditionalget.rb:25:in `call' rack (1.5.2) lib/rack/head.rb:11:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/params_parser.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/flash.rb:241:in `call' rack (1.5.2) lib/rack/session/abstract/id.rb:225:in `context' rack (1.5.2) lib/rack/session/abstract/id.rb:220:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/cookies.rb:486:in `call' activerecord (4.0.4) lib/active_record/query_cache.rb:36:in `call' activerecord (4.0.4) lib/active_record/connection_adapters/abstract/connection_pool.rb:626:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:29:in `block in call' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__4065094992399331645__call__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/reloader.rb:64:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/remote_ip.rb:76:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/debug_exceptions.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/show_exceptions.rb:30:in `call' railties (4.0.4) lib/rails/rack/logger.rb:38:in `call_app' railties (4.0.4) lib/rails/rack/logger.rb:20:in `block in call' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `block in tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `tagged' railties (4.0.4) lib/rails/rack/logger.rb:20:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/request_id.rb:21:in `call' rack (1.5.2) lib/rack/methodoverride.rb:21:in `call' rack (1.5.2) lib/rack/runtime.rb:17:in `call' activesupport (4.0.4) lib/active_support/cache/strategy/local_cache.rb:83:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/static.rb:64:in `call' rack (1.5.2) lib/rack/sendfile.rb:112:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/application.rb:97:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' rack (1.5.2) lib/rack/content_length.rb:14:in `call' rack (1.5.2) lib/rack/handler/webrick.rb:60:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:138:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:94:in `run' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/server.rb:295:in `block in start_thread' Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_source.erb (0.8ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.9ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (4.1ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (26.6ms) Started GET "/opay/correct_authorization?session_id=Lp3pWAAg7Poxhzva7x2B" for 127.0.0.1 at 2014-04-24 15:26:09 +0200 Processing by Opay::PayuController#correct_authorization as HTML Parameters: {"session_id"=>"Lp3pWAAg7Poxhzva7x2B"} Opay::Payment Load (0.3ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."session_id" = 'Lp3pWAAg7Poxhzva7x2B' ORDER BY "opay_payments"."id" ASC LIMIT 1 Order Load (0.2ms) 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" = ? AND "opay_payments"."payable_type" = ? LIMIT 1 [["payable_id", 1], ["payable_type", "Order"]]  (0.1ms) begin transaction  (0.1ms) commit transaction  (0.1ms) begin transaction  (0.1ms) commit transaction Completed 500 Internal Server Error in 10ms NoMethodError (undefined method `root_path' for #): /Users/olownia/dev/gems/opay/app/controllers/opay/application_controller.rb:6:in `correct_authorization' actionpack (4.0.4) lib/action_controller/metal/implicit_render.rb:4:in `send_action' actionpack (4.0.4) lib/abstract_controller/base.rb:189:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rendering.rb:10:in `process_action' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:18:in `block in process_action' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__1529530369328220172__process_action__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/abstract_controller/callbacks.rb:17:in `process_action' actionpack (4.0.4) lib/action_controller/metal/rescue.rb:29:in `process_action' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:31:in `block in process_action' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `block in instrument' activesupport (4.0.4) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (4.0.4) lib/active_support/notifications.rb:159:in `instrument' actionpack (4.0.4) lib/action_controller/metal/instrumentation.rb:30:in `process_action' actionpack (4.0.4) lib/action_controller/metal/params_wrapper.rb:250:in `process_action' activerecord (4.0.4) lib/active_record/railties/controller_runtime.rb:18:in `process_action' actionpack (4.0.4) lib/abstract_controller/base.rb:136:in `process' actionpack (4.0.4) lib/abstract_controller/rendering.rb:44:in `process' actionpack (4.0.4) lib/action_controller/metal.rb:195:in `dispatch' actionpack (4.0.4) lib/action_controller/metal/rack_delegation.rb:13:in `dispatch' actionpack (4.0.4) lib/action_controller/metal.rb:231:in `block in action' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:80:in `dispatch' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:48:in `call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/railtie/configurable.rb:30:in `method_missing' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:71:in `block in call' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `each' actionpack (4.0.4) lib/action_dispatch/journey/router.rb:59:in `call' actionpack (4.0.4) lib/action_dispatch/routing/route_set.rb:674:in `call' rack (1.5.2) lib/rack/etag.rb:23:in `call' rack (1.5.2) lib/rack/conditionalget.rb:25:in `call' rack (1.5.2) lib/rack/head.rb:11:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/params_parser.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/flash.rb:241:in `call' rack (1.5.2) lib/rack/session/abstract/id.rb:225:in `context' rack (1.5.2) lib/rack/session/abstract/id.rb:220:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/cookies.rb:486:in `call' activerecord (4.0.4) lib/active_record/query_cache.rb:36:in `call' activerecord (4.0.4) lib/active_record/connection_adapters/abstract/connection_pool.rb:626:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:29:in `block in call' activesupport (4.0.4) lib/active_support/callbacks.rb:373:in `_run__4065094992399331645__call__callbacks' activesupport (4.0.4) lib/active_support/callbacks.rb:80:in `run_callbacks' actionpack (4.0.4) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/reloader.rb:64:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/remote_ip.rb:76:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/debug_exceptions.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/show_exceptions.rb:30:in `call' railties (4.0.4) lib/rails/rack/logger.rb:38:in `call_app' railties (4.0.4) lib/rails/rack/logger.rb:20:in `block in call' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `block in tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (4.0.4) lib/active_support/tagged_logging.rb:68:in `tagged' railties (4.0.4) lib/rails/rack/logger.rb:20:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/request_id.rb:21:in `call' rack (1.5.2) lib/rack/methodoverride.rb:21:in `call' rack (1.5.2) lib/rack/runtime.rb:17:in `call' activesupport (4.0.4) lib/active_support/cache/strategy/local_cache.rb:83:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' actionpack (4.0.4) lib/action_dispatch/middleware/static.rb:64:in `call' rack (1.5.2) lib/rack/sendfile.rb:112:in `call' railties (4.0.4) lib/rails/engine.rb:511:in `call' railties (4.0.4) lib/rails/application.rb:97:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' rack (1.5.2) lib/rack/content_length.rb:14:in `call' rack (1.5.2) lib/rack/handler/webrick.rb:60:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:138:in `service' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/httpserver.rb:94:in `run' /Users/olownia/.rvm/rubies/ruby-2.0.0-p353/lib/ruby/2.0.0/webrick/server.rb:295:in `block in start_thread' Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_source.erb (0.8ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_trace.erb (2.5ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (2.0ms) Rendered /Users/olownia/.rvm/gems/ruby-2.0.0-p353@opay/gems/actionpack-4.0.4/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (25.2ms) Started GET "/opay/correct_authorization?session_id=Lp3pWAAg7Poxhzva7x2B" for 127.0.0.1 at 2014-04-24 15:26:19 +0200 Processing by Opay::PayuController#correct_authorization as HTML Parameters: {"session_id"=>"Lp3pWAAg7Poxhzva7x2B"} Opay::Payment Load (0.3ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."session_id" = 'Lp3pWAAg7Poxhzva7x2B' ORDER BY "opay_payments"."id" ASC LIMIT 1 Order Load (3.9ms) SELECT "orders".* FROM "orders" WHERE "orders"."id" = ? LIMIT 1 [["id", 1]] Opay::Payment Load (0.4ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = ? AND "opay_payments"."payable_type" = ? LIMIT 1 [["payable_id", 1], ["payable_type", "Order"]]  (0.1ms) begin transaction  (0.1ms) commit transaction  (0.1ms) begin transaction  (0.1ms) commit transaction Redirected to http://localhost:3000/ Completed 302 Found in 87ms (ActiveRecord: 7.7ms) Started GET "/" for 127.0.0.1 at 2014-04-24 15:26:20 +0200 Processing by OrdersController#index as HTML Order Load (0.3ms) SELECT "orders".* FROM "orders" Opay::Payment Load (1.9ms) SELECT "opay_payments".* FROM "opay_payments" WHERE "opay_payments"."payable_id" = ? AND "opay_payments"."payable_type" = ? LIMIT 1 [["payable_id", 1], ["payable_type", "Order"]] Rendered orders/index.html.erb within layouts/application (9.6ms) Completed 200 OK in 145ms (Views: 83.2ms | ActiveRecord: 2.1ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2014-04-24 15:26:20 +0200 Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2014-04-24 15:26:20 +0200 Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2014-04-24 15:26:20 +0200 Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2014-04-24 15:26:20 +0200 Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2014-04-24 15:26:20 +0200