# Logfile created on 2018-04-14 02:18:33 +0300 by logger.rb/56815
D, [2018-04-14T02:18:33.891780 #75096] DEBUG -- :    (0.6ms)  CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar) 
D, [2018-04-14T02:18:33.896679 #75096] DEBUG -- :    (0.2ms)  CREATE TABLE "delayed_jobs" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" datetime, "locked_at" datetime, "failed_at" datetime, "locked_by" varchar, "queue" varchar, "created_at" datetime, "updated_at" datetime) 
D, [2018-04-14T02:18:33.896950 #75096] DEBUG -- :    (0.1ms)  select sqlite_version(*)
D, [2018-04-14T02:18:33.897276 #75096] DEBUG -- :    (0.1ms)  CREATE  INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")
I, [2018-04-14T02:18:33.940168 #75096]  INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-04-14 02:18:33 +0300
I, [2018-04-14T02:18:33.950249 #75096]  INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML
I, [2018-04-14T02:18:33.950313 #75096]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2018-04-14T02:18:33.974433 #75096]  INFO -- :   Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (1.7ms)
I, [2018-04-14T02:18:33.974859 #75096]  INFO -- : Completed 200 OK in 24ms (Views: 9.9ms | ActiveRecord: 0.0ms)
I, [2018-04-14T02:18:33.979467 #75096]  INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-04-14 02:18:33 +0300
I, [2018-04-14T02:18:33.980062 #75096]  INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML
I, [2018-04-14T02:18:33.980100 #75096]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2018-04-14T02:18:34.002553 #75096]  INFO -- :   Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms)
I, [2018-04-14T02:18:34.002841 #75096]  INFO -- : Completed 200 OK in 23ms (Views: 0.9ms | ActiveRecord: 0.0ms)
I, [2018-04-14T02:18:34.004776 #75096]  INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.005376 #75096]  INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML
I, [2018-04-14T02:18:34.005413 #75096]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2018-04-14T02:18:34.012922 #75096]  INFO -- :   Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms)
I, [2018-04-14T02:18:34.013166 #75096]  INFO -- : Completed 200 OK in 8ms (Views: 0.8ms | ActiveRecord: 0.0ms)
I, [2018-04-14T02:18:34.014881 #75096]  INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.015388 #75096]  INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML
I, [2018-04-14T02:18:34.015426 #75096]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2018-04-14T02:18:34.023225 #75096]  INFO -- :   Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms)
I, [2018-04-14T02:18:34.023600 #75096]  INFO -- : Completed 200 OK in 8ms (Views: 1.0ms | ActiveRecord: 0.0ms)
I, [2018-04-14T02:18:34.026126 #75096]  INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.026972 #75096]  INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML
I, [2018-04-14T02:18:34.027025 #75096]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2018-04-14T02:18:34.035078 #75096]  INFO -- :   Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms)
I, [2018-04-14T02:18:34.035366 #75096]  INFO -- : Completed 200 OK in 8ms (Views: 0.9ms | ActiveRecord: 0.0ms)
I, [2018-04-14T02:18:34.037279 #75096]  INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.037882 #75096]  INFO -- : Processing by DummyController#notify_airbrake_helper as HTML
I, [2018-04-14T02:18:34.037958 #75096]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2018-04-14T02:18:34.042475 #75096]  INFO -- :   Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms)
I, [2018-04-14T02:18:34.047113 #75096]  INFO -- : Completed 200 OK in 9ms (Views: 5.2ms | ActiveRecord: 0.0ms)
I, [2018-04-14T02:18:34.049446 #75096]  INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.050131 #75096]  INFO -- : Processing by DummyController#notify_airbrake_helper as HTML
I, [2018-04-14T02:18:34.050169 #75096]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2018-04-14T02:18:34.054562 #75096]  INFO -- :   Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms)
I, [2018-04-14T02:18:34.058723 #75096]  INFO -- : Completed 200 OK in 8ms (Views: 4.7ms | ActiveRecord: 0.0ms)
I, [2018-04-14T02:18:34.060589 #75096]  INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.061120 #75096]  INFO -- : Processing by DummyController#notify_airbrake_helper as HTML
I, [2018-04-14T02:18:34.061164 #75096]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2018-04-14T02:18:34.066090 #75096]  INFO -- :   Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms)
I, [2018-04-14T02:18:34.070644 #75096]  INFO -- : Completed 200 OK in 9ms (Views: 5.3ms | ActiveRecord: 0.0ms)
I, [2018-04-14T02:18:34.073227 #75096]  INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.073915 #75096]  INFO -- : Processing by DummyController#notify_airbrake_helper as HTML
I, [2018-04-14T02:18:34.073955 #75096]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2018-04-14T02:18:34.078423 #75096]  INFO -- :   Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms)
I, [2018-04-14T02:18:34.079603 #75096]  INFO -- : Completed 200 OK in 6ms (Views: 1.7ms | ActiveRecord: 0.0ms)
I, [2018-04-14T02:18:34.197149 #75096]  INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.197944 #75096]  INFO -- : Processing by DummyController#notify_airbrake_helper as HTML
I, [2018-04-14T02:18:34.197995 #75096]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2018-04-14T02:18:34.205732 #75096]  INFO -- :   Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms)
I, [2018-04-14T02:18:34.206008 #75096]  INFO -- : Completed 200 OK in 8ms (Views: 4.4ms | ActiveRecord: 0.0ms)
I, [2018-04-14T02:18:34.208175 #75096]  INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.208949 #75096]  INFO -- : Processing by DummyController#crash as HTML
I, [2018-04-14T02:18:34.208988 #75096]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2018-04-14T02:18:34.209229 #75096]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2018-04-14T02:18:34.213557 #75096] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:52:in `call'


I, [2018-04-14T02:18:34.219550 #75096]  INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.220219 #75096]  INFO -- : Processing by DummyController#crash as HTML
I, [2018-04-14T02:18:34.220261 #75096]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2018-04-14T02:18:34.220475 #75096]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2018-04-14T02:18:34.224538 #75096] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:52:in `call'


I, [2018-04-14T02:18:34.327759 #75096]  INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.328491 #75096]  INFO -- : Processing by DummyController#crash as HTML
I, [2018-04-14T02:18:34.328534 #75096]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2018-04-14T02:18:34.328777 #75096]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2018-04-14T02:18:34.336963 #75096] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:52:in `call'


I, [2018-04-14T02:18:34.338521 #75096]  INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.339060 #75096]  INFO -- : Processing by DummyController#crash as HTML
I, [2018-04-14T02:18:34.339097 #75096]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2018-04-14T02:18:34.339287 #75096]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2018-04-14T02:18:34.343271 #75096] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:52:in `call'


I, [2018-04-14T02:18:34.349088 #75096]  INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.349824 #75096]  INFO -- : Processing by DummyController#crash as HTML
I, [2018-04-14T02:18:34.349863 #75096]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2018-04-14T02:18:34.350088 #75096]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2018-04-14T02:18:34.354432 #75096] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:52:in `call'


I, [2018-04-14T02:18:34.360070 #75096]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.360630 #75096]  INFO -- : Processing by DummyController#crash as HTML
I, [2018-04-14T02:18:34.360862 #75096]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2018-04-14T02:18:34.365802 #75096] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:52:in `call'


I, [2018-04-14T02:18:34.372572 #75096]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.373445 #75096]  INFO -- : Processing by DummyController#crash as HTML
I, [2018-04-14T02:18:34.373830 #75096]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2018-04-14T02:18:34.378889 #75096] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:52:in `call'


I, [2018-04-14T02:18:34.386905 #75096]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.387405 #75096]  INFO -- : Processing by DummyController#crash as HTML
I, [2018-04-14T02:18:34.387679 #75096]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2018-04-14T02:18:34.391640 #75096] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:52:in `call'


I, [2018-04-14T02:18:34.396691 #75096]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.397226 #75096]  INFO -- : Processing by DummyController#crash as HTML
I, [2018-04-14T02:18:34.397545 #75096]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2018-04-14T02:18:34.401812 #75096] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:52:in `call'


I, [2018-04-14T02:18:34.407590 #75096]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.408199 #75096]  INFO -- : Processing by DummyController#crash as HTML
I, [2018-04-14T02:18:34.408458 #75096]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2018-04-14T02:18:34.413035 #75096] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:52:in `call'


I, [2018-04-14T02:18:34.418521 #75096]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.419142 #75096]  INFO -- : Processing by DummyController#crash as HTML
I, [2018-04-14T02:18:34.419368 #75096]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2018-04-14T02:18:34.423729 #75096] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:52:in `call'


I, [2018-04-14T02:18:34.429460 #75096]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.430245 #75096]  INFO -- : Processing by DummyController#crash as HTML
I, [2018-04-14T02:18:34.430541 #75096]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2018-04-14T02:18:34.439339 #75096] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:52:in `call'


I, [2018-04-14T02:18:34.441246 #75096]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.441831 #75096]  INFO -- : Processing by DummyController#crash as HTML
I, [2018-04-14T02:18:34.442122 #75096]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2018-04-14T02:18:34.446507 #75096] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:52:in `call'


I, [2018-04-14T02:18:34.553430 #75096]  INFO -- : Started GET "/active_record_after_commit" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.554164 #75096]  INFO -- : Processing by DummyController#active_record_after_commit as HTML
D, [2018-04-14T02:18:34.558669 #75096] DEBUG -- :    (0.0ms)  begin transaction
D, [2018-04-14T02:18:34.561179 #75096] DEBUG -- :   SQL (0.1ms)  INSERT INTO "books" ("title") VALUES (?)  [["title", "Bingo"]]
D, [2018-04-14T02:18:34.561470 #75096] DEBUG -- :    (0.0ms)  commit transaction
I, [2018-04-14T02:18:34.561850 #75096]  INFO -- : Completed 500 Internal Server Error in 8ms (ActiveRecord: 0.4ms)
F, [2018-04-14T02:18:34.572797 #75096] FATAL -- : 
AirbrakeTestError (after_commit):
  lib/airbrake/rack/middleware.rb:52:in `call'


I, [2018-04-14T02:18:34.574607 #75096]  INFO -- : Started GET "/active_record_after_rollback" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.575354 #75096]  INFO -- : Processing by DummyController#active_record_after_rollback as HTML
D, [2018-04-14T02:18:34.575574 #75096] DEBUG -- :    (0.0ms)  begin transaction
D, [2018-04-14T02:18:34.576140 #75096] DEBUG -- :   SQL (0.1ms)  INSERT INTO "books" ("title") VALUES (?)  [["title", "Bango"]]
D, [2018-04-14T02:18:34.576388 #75096] DEBUG -- :    (0.0ms)  rollback transaction
I, [2018-04-14T02:18:34.576742 #75096]  INFO -- : Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.1ms)
F, [2018-04-14T02:18:34.585405 #75096] FATAL -- : 
AirbrakeTestError (after_rollback):
  lib/airbrake/rack/middleware.rb:52:in `call'


I, [2018-04-14T02:18:34.593742 #75096]  INFO -- : Started GET "/" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.594385 #75096]  INFO -- : Processing by DummyController#index as HTML
I, [2018-04-14T02:18:34.595173 #75096]  INFO -- :   Rendered dummy/index.html.erb within layouts/application (0.2ms)
I, [2018-04-14T02:18:34.595446 #75096]  INFO -- : Completed 200 OK in 1ms (Views: 0.9ms | ActiveRecord: 0.0ms)
I, [2018-04-14T02:18:34.596688 #75096]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.597156 #75096]  INFO -- : Processing by DummyController#crash as HTML
I, [2018-04-14T02:18:34.597380 #75096]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2018-04-14T02:18:34.601141 #75096] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:52:in `call'


I, [2018-04-14T02:18:34.608594 #75096]  INFO -- : Started GET "/active_job" for 127.0.0.1 at 2018-04-14 02:18:34 +0300
I, [2018-04-14T02:18:34.609561 #75096]  INFO -- : Processing by DummyController#active_job as HTML
I, [2018-04-14T02:18:34.620569 #75096]  INFO -- : Completed 500 Internal Server Error in 11ms (ActiveRecord: 0.0ms)
F, [2018-04-14T02:18:34.632158 #75096] FATAL -- : 
AirbrakeTestError (active_job error):
  lib/airbrake/rack/middleware.rb:52:in `call'