# Logfile created on 2016-08-04 18:05:14 +0300 by logger.rb/54362
D, [2016-08-04T18:05:15.367841 #31533] DEBUG -- :    (0.3ms)  CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar) 
D, [2016-08-04T18:05:15.372457 #31533] DEBUG -- :    (0.1ms)  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, [2016-08-04T18:05:15.372663 #31533] DEBUG -- :    (0.0ms)  select sqlite_version(*)
D, [2016-08-04T18:05:15.372949 #31533] DEBUG -- :    (0.1ms)  CREATE  INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")
I, [2016-08-04T18:05:15.401626 #31533]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:05:15 +0300
I, [2016-08-04T18:05:15.403571 #31533]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:05:15.404656 #31533]  INFO -- : Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:05:15.417580 #31533] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:05:15.539986 #31533]  INFO -- : Started GET "/" for 127.0.0.1 at 2016-08-04 18:05:15 +0300
I, [2016-08-04T18:05:15.540780 #31533]  INFO -- : Processing by DummyController#index as HTML
I, [2016-08-04T18:05:15.549595 #31533]  INFO -- :   Rendered dummy/index.html.erb within layouts/application (1.1ms)
I, [2016-08-04T18:05:15.550011 #31533]  INFO -- : Completed 200 OK in 9ms (Views: 9.0ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:05:15.551490 #31533]  INFO -- : Started GET "/active_job" for 127.0.0.1 at 2016-08-04 18:05:15 +0300
I, [2016-08-04T18:05:15.552095 #31533]  INFO -- : Processing by DummyController#active_job as HTML
I, [2016-08-04T18:05:15.573970 #31533]  INFO -- : Completed 500 Internal Server Error in 22ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:05:15.578133 #31533] FATAL -- : 
AirbrakeTestError (active_job error):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:05:17.588989 #31533]  INFO -- : Started GET "/active_job" for 127.0.0.1 at 2016-08-04 18:05:17 +0300
I, [2016-08-04T18:05:17.589609 #31533]  INFO -- : Processing by DummyController#active_job as HTML
I, [2016-08-04T18:05:17.590373 #31533]  INFO -- : Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:05:17.591745 #31533] FATAL -- : 
AirbrakeTestError (active_job error):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:05:19.600788 #31533]  INFO -- : Started GET "/delayed_job" for 127.0.0.1 at 2016-08-04 18:05:19 +0300
I, [2016-08-04T18:05:19.601647 #31533]  INFO -- : Processing by DummyController#delayed_job as HTML
I, [2016-08-04T18:05:19.614216 #31533]  INFO -- : Completed 500 Internal Server Error in 12ms (ActiveRecord: 0.3ms)
F, [2016-08-04T18:05:19.618266 #31533] FATAL -- : 
AirbrakeTestError (delayed_job error):
  lib/airbrake/delayed_job/plugin.rb:11:in `block (2 levels) in <class:Airbrake>'
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:05:23.628103 #31533]  INFO -- : Started GET "/resque" for 127.0.0.1 at 2016-08-04 18:05:23 +0300
I, [2016-08-04T18:05:23.628840 #31533]  INFO -- : Processing by DummyController#resque as HTML
I, [2016-08-04T18:05:23.642093 #31533]  INFO -- :   Rendered dummy/resque.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:05:23.642328 #31533]  INFO -- : Completed 200 OK in 13ms (Views: 1.2ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:05:23.645151 #31533]  INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2016-08-04 18:05:23 +0300
I, [2016-08-04T18:05:23.645985 #31533]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:05:23.646012 #31533]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:05:23.646223 #31533]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:05:23.650010 #31533] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:05:23.757115 #31533]  INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2016-08-04 18:05:23 +0300
I, [2016-08-04T18:05:23.758415 #31533]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:05:23.758455 #31533]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:05:23.758857 #31533]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:05:23.775294 #31533] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:05:23.777056 #31533]  INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2016-08-04 18:05:23 +0300
I, [2016-08-04T18:05:23.777723 #31533]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:05:23.777750 #31533]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:05:23.777958 #31533]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:05:23.780343 #31533] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:05:23.886807 #31533]  INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2016-08-04 18:05:23 +0300
I, [2016-08-04T18:05:23.887734 #31533]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:05:23.887770 #31533]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:05:23.888095 #31533]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:05:23.895285 #31533] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:05:23.896971 #31533]  INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2016-08-04 18:05:23 +0300
I, [2016-08-04T18:05:23.897691 #31533]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:05:23.897719 #31533]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:05:23.897958 #31533]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:05:23.900833 #31533] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:05:24.006953 #31533]  INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:05:24 +0300
I, [2016-08-04T18:05:24.008159 #31533]  INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML
I, [2016-08-04T18:05:24.008203 #31533]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:05:24.016575 #31533]  INFO -- :   Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:05:24.016895 #31533]  INFO -- : Completed 200 OK in 9ms (Views: 1.0ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:05:24.019322 #31533]  INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:05:24 +0300
I, [2016-08-04T18:05:24.020048 #31533]  INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML
I, [2016-08-04T18:05:24.020076 #31533]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:05:24.027308 #31533]  INFO -- :   Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:05:24.027563 #31533]  INFO -- : Completed 200 OK in 7ms (Views: 0.9ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:05:24.029660 #31533]  INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:05:24 +0300
I, [2016-08-04T18:05:24.030317 #31533]  INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML
I, [2016-08-04T18:05:24.030364 #31533]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:05:24.037559 #31533]  INFO -- :   Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:05:24.037815 #31533]  INFO -- : Completed 200 OK in 7ms (Views: 0.9ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:05:24.039856 #31533]  INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:05:24 +0300
I, [2016-08-04T18:05:24.040487 #31533]  INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML
I, [2016-08-04T18:05:24.040514 #31533]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:05:24.050927 #31533]  INFO -- :   Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:05:24.051204 #31533]  INFO -- : Completed 200 OK in 11ms (Views: 1.0ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:05:24.053493 #31533]  INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:05:24 +0300
I, [2016-08-04T18:05:24.054228 #31533]  INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML
I, [2016-08-04T18:05:24.054257 #31533]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:05:24.061642 #31533]  INFO -- :   Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:05:24.061883 #31533]  INFO -- : Completed 200 OK in 8ms (Views: 0.9ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:05:24.064013 #31533]  INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:05:24 +0300
I, [2016-08-04T18:05:24.064744 #31533]  INFO -- : Processing by DummyController#notify_airbrake_helper as HTML
I, [2016-08-04T18:05:24.064776 #31533]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:05:24.067674 #31533]  INFO -- :   Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:05:24.067904 #31533]  INFO -- : Completed 200 OK in 3ms (Views: 0.9ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:05:24.176226 #31533]  INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:05:24 +0300
I, [2016-08-04T18:05:24.177262 #31533]  INFO -- : Processing by DummyController#notify_airbrake_helper as HTML
I, [2016-08-04T18:05:24.177302 #31533]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:05:24.186005 #31533]  INFO -- :   Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:05:24.186314 #31533]  INFO -- : Completed 200 OK in 9ms (Views: 5.4ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:05:24.188414 #31533]  INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:05:24 +0300
I, [2016-08-04T18:05:24.189051 #31533]  INFO -- : Processing by DummyController#notify_airbrake_helper as HTML
I, [2016-08-04T18:05:24.189078 #31533]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:05:24.192392 #31533]  INFO -- :   Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:05:24.192667 #31533]  INFO -- : Completed 200 OK in 4ms (Views: 0.9ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:05:24.299416 #31533]  INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:05:24 +0300
I, [2016-08-04T18:05:24.300394 #31533]  INFO -- : Processing by DummyController#notify_airbrake_helper as HTML
I, [2016-08-04T18:05:24.300433 #31533]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:05:24.308423 #31533]  INFO -- :   Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:05:24.308660 #31533]  INFO -- : Completed 200 OK in 8ms (Views: 5.2ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:05:24.310701 #31533]  INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:05:24 +0300
I, [2016-08-04T18:05:24.311308 #31533]  INFO -- : Processing by DummyController#notify_airbrake_helper as HTML
I, [2016-08-04T18:05:24.311334 #31533]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:05:24.314603 #31533]  INFO -- :   Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.1ms)
I, [2016-08-04T18:05:24.314844 #31533]  INFO -- : Completed 200 OK in 3ms (Views: 0.9ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:05:24.321587 #31533]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:05:24 +0300
I, [2016-08-04T18:05:24.322626 #31533]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:05:24.322915 #31533]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:05:24.326834 #31533] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:05:24.434261 #31533]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:05:24 +0300
I, [2016-08-04T18:05:24.435044 #31533]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:05:24.435398 #31533]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:05:24.443511 #31533] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:05:24.445227 #31533]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:05:24 +0300
I, [2016-08-04T18:05:24.445738 #31533]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:05:24.445959 #31533]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:05:24.448714 #31533] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:05:24.556207 #31533]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:05:24 +0300
I, [2016-08-04T18:05:24.557073 #31533]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:05:24.557416 #31533]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:05:24.565919 #31533] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:05:24.567848 #31533]  INFO -- : Started GET "/active_record_after_commit" for 127.0.0.1 at 2016-08-04 18:05:24 +0300
I, [2016-08-04T18:05:24.568544 #31533]  INFO -- : Processing by DummyController#active_record_after_commit as HTML
D, [2016-08-04T18:05:24.570397 #31533] DEBUG -- :    (0.0ms)  begin transaction
D, [2016-08-04T18:05:24.575183 #31533] DEBUG -- :   SQL (0.1ms)  INSERT INTO "books" ("title") VALUES (?)  [["title", "Bingo"]]
D, [2016-08-04T18:05:24.575518 #31533] DEBUG -- :    (0.0ms)  commit transaction
I, [2016-08-04T18:05:24.575867 #31533]  INFO -- : Completed 500 Internal Server Error in 7ms (ActiveRecord: 0.3ms)
F, [2016-08-04T18:05:24.579077 #31533] FATAL -- : 
AirbrakeTestError (after_commit):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:05:24.686586 #31533]  INFO -- : Started GET "/active_record_after_rollback" for 127.0.0.1 at 2016-08-04 18:05:24 +0300
I, [2016-08-04T18:05:24.687487 #31533]  INFO -- : Processing by DummyController#active_record_after_rollback as HTML
D, [2016-08-04T18:05:24.687741 #31533] DEBUG -- :    (0.1ms)  begin transaction
D, [2016-08-04T18:05:24.688451 #31533] DEBUG -- :   SQL (0.1ms)  INSERT INTO "books" ("title") VALUES (?)  [["title", "Bango"]]
D, [2016-08-04T18:05:24.688686 #31533] DEBUG -- :    (0.0ms)  rollback transaction
I, [2016-08-04T18:05:24.689138 #31533]  INFO -- : Completed 500 Internal Server Error in 2ms (ActiveRecord: 0.2ms)
F, [2016-08-04T18:05:24.700774 #31533] FATAL -- : 
AirbrakeTestError (after_rollback):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:05:24.702740 #31533]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:05:24 +0300
I, [2016-08-04T18:05:24.703444 #31533]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:05:24.703684 #31533]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:05:24.706416 #31533] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:05:24.811405 #31533]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:05:24 +0300
I, [2016-08-04T18:05:24.812328 #31533]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:05:24.812650 #31533]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:05:24.816095 #31533] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:05:24.919088 #31533]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:05:24 +0300
I, [2016-08-04T18:05:24.919836 #31533]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:05:24.920160 #31533]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:05:24.927861 #31533] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:05:24.929476 #31533]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:05:24 +0300
I, [2016-08-04T18:05:24.930024 #31533]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:05:24.930268 #31533]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:05:24.933339 #31533] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:05:25.038154 #31533]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:05:25 +0300
I, [2016-08-04T18:05:25.038844 #31533]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:05:25.039193 #31533]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:05:25.047257 #31533] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


D, [2016-08-04T18:06:36.854810 #31634] DEBUG -- :    (0.3ms)  CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar) 
D, [2016-08-04T18:06:36.860037 #31634] 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, [2016-08-04T18:06:36.860409 #31634] DEBUG -- :    (0.1ms)  select sqlite_version(*)
D, [2016-08-04T18:06:36.860961 #31634] DEBUG -- :    (0.2ms)  CREATE  INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")
I, [2016-08-04T18:06:36.888527 #31634]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:06:36 +0300
I, [2016-08-04T18:06:36.897887 #31634]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:06:36.898990 #31634]  INFO -- : Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:06:36.902328 #31634] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:06:37.022593 #31634]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:06:37 +0300
I, [2016-08-04T18:06:37.023475 #31634]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:06:37.023809 #31634]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:06:37.031514 #31634] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:06:37.033200 #31634]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:06:37 +0300
I, [2016-08-04T18:06:37.033720 #31634]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:06:37.033992 #31634]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:06:37.036972 #31634] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:06:37.143030 #31634]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:06:37 +0300
I, [2016-08-04T18:06:37.143771 #31634]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:06:37.144125 #31634]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:06:37.152991 #31634] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:06:37.159160 #31634]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:06:37 +0300
I, [2016-08-04T18:06:37.159734 #31634]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:06:37.159995 #31634]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:06:37.162965 #31634] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:06:37.271207 #31634]  INFO -- : Started GET "/active_record_after_rollback" for 127.0.0.1 at 2016-08-04 18:06:37 +0300
I, [2016-08-04T18:06:37.272278 #31634]  INFO -- : Processing by DummyController#active_record_after_rollback as HTML
D, [2016-08-04T18:06:37.272678 #31634] DEBUG -- :    (0.1ms)  begin transaction
D, [2016-08-04T18:06:37.279510 #31634] DEBUG -- :   SQL (0.1ms)  INSERT INTO "books" ("title") VALUES (?)  [["title", "Bango"]]
D, [2016-08-04T18:06:37.279868 #31634] DEBUG -- :    (0.0ms)  rollback transaction
I, [2016-08-04T18:06:37.280242 #31634]  INFO -- : Completed 500 Internal Server Error in 8ms (ActiveRecord: 0.5ms)
F, [2016-08-04T18:06:37.292896 #31634] FATAL -- : 
AirbrakeTestError (after_rollback):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:06:37.294707 #31634]  INFO -- : Started GET "/active_record_after_commit" for 127.0.0.1 at 2016-08-04 18:06:37 +0300
I, [2016-08-04T18:06:37.295389 #31634]  INFO -- : Processing by DummyController#active_record_after_commit as HTML
D, [2016-08-04T18:06:37.295722 #31634] DEBUG -- :    (0.1ms)  begin transaction
D, [2016-08-04T18:06:37.296098 #31634] DEBUG -- :   SQL (0.0ms)  INSERT INTO "books" ("title") VALUES (?)  [["title", "Bingo"]]
D, [2016-08-04T18:06:37.296278 #31634] DEBUG -- :    (0.0ms)  commit transaction
I, [2016-08-04T18:06:37.296585 #31634]  INFO -- : Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.1ms)
F, [2016-08-04T18:06:37.299720 #31634] FATAL -- : 
AirbrakeTestError (after_commit):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:06:37.404618 #31634]  INFO -- : Started GET "/" for 127.0.0.1 at 2016-08-04 18:06:37 +0300
I, [2016-08-04T18:06:37.405575 #31634]  INFO -- : Processing by DummyController#index as HTML
I, [2016-08-04T18:06:37.410769 #31634]  INFO -- :   Rendered dummy/index.html.erb within layouts/application (0.8ms)
I, [2016-08-04T18:06:37.411122 #31634]  INFO -- : Completed 200 OK in 5ms (Views: 5.3ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:06:37.412569 #31634]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:06:37 +0300
I, [2016-08-04T18:06:37.413094 #31634]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:06:37.413352 #31634]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:06:37.421417 #31634] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:06:37.423456 #31634]  INFO -- : Started GET "/resque" for 127.0.0.1 at 2016-08-04 18:06:37 +0300
I, [2016-08-04T18:06:37.424087 #31634]  INFO -- : Processing by DummyController#resque as HTML
I, [2016-08-04T18:06:37.432252 #31634]  INFO -- :   Rendered dummy/resque.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:06:37.432528 #31634]  INFO -- : Completed 200 OK in 8ms (Views: 1.0ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:06:37.434723 #31634]  INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:06:37 +0300
I, [2016-08-04T18:06:37.435468 #31634]  INFO -- : Processing by DummyController#notify_airbrake_helper as HTML
I, [2016-08-04T18:06:37.435508 #31634]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:06:37.438787 #31634]  INFO -- :   Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:06:37.439095 #31634]  INFO -- : Completed 200 OK in 4ms (Views: 0.9ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:06:37.544885 #31634]  INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:06:37 +0300
I, [2016-08-04T18:06:37.546046 #31634]  INFO -- : Processing by DummyController#notify_airbrake_helper as HTML
I, [2016-08-04T18:06:37.546105 #31634]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:06:37.555859 #31634]  INFO -- :   Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:06:37.556202 #31634]  INFO -- : Completed 200 OK in 10ms (Views: 6.8ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:06:37.558904 #31634]  INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:06:37 +0300
I, [2016-08-04T18:06:37.559624 #31634]  INFO -- : Processing by DummyController#notify_airbrake_helper as HTML
I, [2016-08-04T18:06:37.559651 #31634]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:06:37.562520 #31634]  INFO -- :   Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:06:37.562774 #31634]  INFO -- : Completed 200 OK in 3ms (Views: 0.8ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:06:37.669990 #31634]  INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:06:37 +0300
I, [2016-08-04T18:06:37.671157 #31634]  INFO -- : Processing by DummyController#notify_airbrake_helper as HTML
I, [2016-08-04T18:06:37.671216 #31634]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:06:37.680174 #31634]  INFO -- :   Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:06:37.680521 #31634]  INFO -- : Completed 200 OK in 9ms (Views: 5.6ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:06:37.682575 #31634]  INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:06:37 +0300
I, [2016-08-04T18:06:37.683217 #31634]  INFO -- : Processing by DummyController#notify_airbrake_helper as HTML
I, [2016-08-04T18:06:37.683245 #31634]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:06:37.686206 #31634]  INFO -- :   Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:06:37.686614 #31634]  INFO -- : Completed 200 OK in 3ms (Views: 1.1ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:06:37.794246 #31634]  INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2016-08-04 18:06:37 +0300
I, [2016-08-04T18:06:37.795150 #31634]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:06:37.795191 #31634]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:06:37.795506 #31634]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:06:37.799314 #31634] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:06:37.904072 #31634]  INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2016-08-04 18:06:37 +0300
I, [2016-08-04T18:06:37.904869 #31634]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:06:37.904941 #31634]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:06:37.905330 #31634]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:06:37.918401 #31634] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:06:37.920671 #31634]  INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2016-08-04 18:06:37 +0300
I, [2016-08-04T18:06:37.921313 #31634]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:06:37.921343 #31634]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:06:37.921608 #31634]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:06:37.924423 #31634] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:06:38.030868 #31634]  INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2016-08-04 18:06:38 +0300
I, [2016-08-04T18:06:38.031971 #31634]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:06:38.032011 #31634]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:06:38.032338 #31634]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:06:38.041938 #31634] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:06:38.043615 #31634]  INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2016-08-04 18:06:38 +0300
I, [2016-08-04T18:06:38.044222 #31634]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:06:38.044249 #31634]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:06:38.044496 #31634]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:06:38.047678 #31634] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:06:38.153687 #31634]  INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:06:38 +0300
I, [2016-08-04T18:06:38.154589 #31634]  INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML
I, [2016-08-04T18:06:38.154625 #31634]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:06:38.163104 #31634]  INFO -- :   Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:06:38.163334 #31634]  INFO -- : Completed 200 OK in 9ms (Views: 0.9ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:06:38.165394 #31634]  INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:06:38 +0300
I, [2016-08-04T18:06:38.166026 #31634]  INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML
I, [2016-08-04T18:06:38.166053 #31634]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:06:38.173118 #31634]  INFO -- :   Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:06:38.173362 #31634]  INFO -- : Completed 200 OK in 7ms (Views: 0.9ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:06:38.175339 #31634]  INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:06:38 +0300
I, [2016-08-04T18:06:38.175960 #31634]  INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML
I, [2016-08-04T18:06:38.175989 #31634]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:06:38.183994 #31634]  INFO -- :   Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:06:38.184262 #31634]  INFO -- : Completed 200 OK in 8ms (Views: 0.9ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:06:38.186428 #31634]  INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:06:38 +0300
I, [2016-08-04T18:06:38.187105 #31634]  INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML
I, [2016-08-04T18:06:38.187139 #31634]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:06:38.198145 #31634]  INFO -- :   Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:06:38.198409 #31634]  INFO -- : Completed 200 OK in 11ms (Views: 0.9ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:06:38.200284 #31634]  INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:06:38 +0300
I, [2016-08-04T18:06:38.200954 #31634]  INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML
I, [2016-08-04T18:06:38.200991 #31634]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:06:38.208175 #31634]  INFO -- :   Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:06:38.208407 #31634]  INFO -- : Completed 200 OK in 7ms (Views: 0.8ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:06:38.210450 #31634]  INFO -- : Started GET "/delayed_job" for 127.0.0.1 at 2016-08-04 18:06:38 +0300
I, [2016-08-04T18:06:38.210989 #31634]  INFO -- : Processing by DummyController#delayed_job as HTML
I, [2016-08-04T18:06:38.220340 #31634]  INFO -- : Completed 500 Internal Server Error in 9ms (ActiveRecord: 0.1ms)
F, [2016-08-04T18:06:38.223302 #31634] FATAL -- : 
AirbrakeTestError (delayed_job error):
  lib/airbrake/delayed_job/plugin.rb:11:in `block (2 levels) in <class:Airbrake>'
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:06:42.232287 #31634]  INFO -- : Started GET "/active_job" for 127.0.0.1 at 2016-08-04 18:06:42 +0300
I, [2016-08-04T18:06:42.233118 #31634]  INFO -- : Processing by DummyController#active_job as HTML
I, [2016-08-04T18:06:42.247344 #31634]  INFO -- : Completed 500 Internal Server Error in 14ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:06:42.251641 #31634] FATAL -- : 
AirbrakeTestError (active_job error):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:06:46.268441 #31634]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:06:46 +0300
I, [2016-08-04T18:06:46.269454 #31634]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:06:46.269839 #31634]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:06:46.273230 #31634] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:06:46.380594 #31634]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:06:46 +0300
I, [2016-08-04T18:06:46.381539 #31634]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:06:46.381882 #31634]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:06:46.391298 #31634] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:06:46.393037 #31634]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:06:46 +0300
I, [2016-08-04T18:06:46.393591 #31634]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:06:46.393817 #31634]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:06:46.396526 #31634] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:06:46.502733 #31634]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:06:46 +0300
I, [2016-08-04T18:06:46.503429 #31634]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:06:46.503742 #31634]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:06:46.512046 #31634] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


D, [2016-08-04T18:08:04.943020 #31772] DEBUG -- :    (0.3ms)  CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar) 
D, [2016-08-04T18:08:04.946600 #31772] DEBUG -- :    (0.1ms)  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, [2016-08-04T18:08:04.946767 #31772] DEBUG -- :    (0.0ms)  select sqlite_version(*)
D, [2016-08-04T18:08:04.947050 #31772] DEBUG -- :    (0.1ms)  CREATE  INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")
I, [2016-08-04T18:08:04.971744 #31772]  INFO -- : Started GET "/active_record_after_rollback" for 127.0.0.1 at 2016-08-04 18:08:04 +0300
I, [2016-08-04T18:08:04.973569 #31772]  INFO -- : Processing by DummyController#active_record_after_rollback as HTML
D, [2016-08-04T18:08:04.973825 #31772] DEBUG -- :    (0.1ms)  begin transaction
D, [2016-08-04T18:08:04.979652 #31772] DEBUG -- :   SQL (0.1ms)  INSERT INTO "books" ("title") VALUES (?)  [["title", "Bango"]]
D, [2016-08-04T18:08:04.980053 #31772] DEBUG -- :    (0.1ms)  rollback transaction
I, [2016-08-04T18:08:04.982046 #31772]  INFO -- : Completed 500 Internal Server Error in 8ms (ActiveRecord: 0.4ms)
F, [2016-08-04T18:08:04.997711 #31772] FATAL -- : 
AirbrakeTestError (after_rollback):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:08:05.012472 #31772]  INFO -- : Started GET "/active_record_after_commit" for 127.0.0.1 at 2016-08-04 18:08:05 +0300
I, [2016-08-04T18:08:05.013118 #31772]  INFO -- : Processing by DummyController#active_record_after_commit as HTML
D, [2016-08-04T18:08:05.013451 #31772] DEBUG -- :    (0.1ms)  begin transaction
D, [2016-08-04T18:08:05.013821 #31772] DEBUG -- :   SQL (0.0ms)  INSERT INTO "books" ("title") VALUES (?)  [["title", "Bingo"]]
D, [2016-08-04T18:08:05.013970 #31772] DEBUG -- :    (0.0ms)  commit transaction
I, [2016-08-04T18:08:05.014238 #31772]  INFO -- : Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.1ms)
F, [2016-08-04T18:08:05.017059 #31772] FATAL -- : 
AirbrakeTestError (after_commit):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:08:05.139516 #31772]  INFO -- : Started GET "/delayed_job" for 127.0.0.1 at 2016-08-04 18:08:05 +0300
I, [2016-08-04T18:08:05.140514 #31772]  INFO -- : Processing by DummyController#delayed_job as HTML
I, [2016-08-04T18:08:05.161002 #31772]  INFO -- : Completed 500 Internal Server Error in 20ms (ActiveRecord: 0.2ms)
F, [2016-08-04T18:08:05.166379 #31772] FATAL -- : 
AirbrakeTestError (delayed_job error):
  lib/airbrake/delayed_job/plugin.rb:11:in `block (2 levels) in <class:Airbrake>'
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:08:09.183611 #31772]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:08:09 +0300
I, [2016-08-04T18:08:09.184711 #31772]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:08:09.184988 #31772]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:08:09.192873 #31772] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:08:09.195083 #31772]  INFO -- : Started GET "/" for 127.0.0.1 at 2016-08-04 18:08:09 +0300
I, [2016-08-04T18:08:09.195763 #31772]  INFO -- : Processing by DummyController#index as HTML
I, [2016-08-04T18:08:09.200176 #31772]  INFO -- :   Rendered dummy/index.html.erb within layouts/application (0.7ms)
I, [2016-08-04T18:08:09.200509 #31772]  INFO -- : Completed 200 OK in 5ms (Views: 4.5ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:08:09.201989 #31772]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:08:09 +0300
I, [2016-08-04T18:08:09.202486 #31772]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:08:09.202711 #31772]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:08:09.205475 #31772] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:08:09.308188 #31772]  INFO -- : Started GET "/active_job" for 127.0.0.1 at 2016-08-04 18:08:09 +0300
I, [2016-08-04T18:08:09.308956 #31772]  INFO -- : Processing by DummyController#active_job as HTML
I, [2016-08-04T18:08:09.321603 #31772]  INFO -- : Completed 500 Internal Server Error in 13ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:08:09.325739 #31772] FATAL -- : 
AirbrakeTestError (active_job error):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:08:11.329108 #31772]  INFO -- : Started GET "/active_job" for 127.0.0.1 at 2016-08-04 18:08:11 +0300
I, [2016-08-04T18:08:11.329778 #31772]  INFO -- : Processing by DummyController#active_job as HTML
I, [2016-08-04T18:08:11.330579 #31772]  INFO -- : Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:08:11.332072 #31772] FATAL -- : 
AirbrakeTestError (active_job error):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:08:13.343525 #31772]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:08:13 +0300
I, [2016-08-04T18:08:13.344186 #31772]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:08:13.344485 #31772]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:08:13.347904 #31772] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:08:13.455290 #31772]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:08:13 +0300
I, [2016-08-04T18:08:13.455976 #31772]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:08:13.456282 #31772]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:08:13.464082 #31772] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:08:13.465639 #31772]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:08:13 +0300
I, [2016-08-04T18:08:13.466158 #31772]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:08:13.466403 #31772]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:08:13.469102 #31772] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:08:13.576646 #31772]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:08:13 +0300
I, [2016-08-04T18:08:13.577418 #31772]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:08:13.577780 #31772]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:08:13.586041 #31772] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:08:13.587892 #31772]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:08:13 +0300
I, [2016-08-04T18:08:13.588439 #31772]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:08:13.588692 #31772]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:08:13.591490 #31772] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:08:13.698078 #31772]  INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:08:13 +0300
I, [2016-08-04T18:08:13.699161 #31772]  INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML
I, [2016-08-04T18:08:13.699204 #31772]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:08:13.708530 #31772]  INFO -- :   Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:08:13.708791 #31772]  INFO -- : Completed 200 OK in 10ms (Views: 1.0ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:08:13.710968 #31772]  INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:08:13 +0300
I, [2016-08-04T18:08:13.711615 #31772]  INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML
I, [2016-08-04T18:08:13.711642 #31772]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:08:13.723439 #31772]  INFO -- :   Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.3ms)
I, [2016-08-04T18:08:13.723869 #31772]  INFO -- : Completed 200 OK in 12ms (Views: 1.6ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:08:13.726270 #31772]  INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:08:13 +0300
I, [2016-08-04T18:08:13.727067 #31772]  INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML
I, [2016-08-04T18:08:13.727105 #31772]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:08:13.735825 #31772]  INFO -- :   Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:08:13.736096 #31772]  INFO -- : Completed 200 OK in 9ms (Views: 1.0ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:08:13.738181 #31772]  INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:08:13 +0300
I, [2016-08-04T18:08:13.738828 #31772]  INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML
I, [2016-08-04T18:08:13.738866 #31772]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:08:13.746070 #31772]  INFO -- :   Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:08:13.746295 #31772]  INFO -- : Completed 200 OK in 7ms (Views: 0.9ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:08:13.748059 #31772]  INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:08:13 +0300
I, [2016-08-04T18:08:13.748643 #31772]  INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML
I, [2016-08-04T18:08:13.748670 #31772]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:08:13.756175 #31772]  INFO -- :   Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:08:13.756442 #31772]  INFO -- : Completed 200 OK in 8ms (Views: 0.9ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:08:13.758561 #31772]  INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2016-08-04 18:08:13 +0300
I, [2016-08-04T18:08:13.759250 #31772]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:08:13.759278 #31772]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:08:13.759558 #31772]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:08:13.762590 #31772] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:08:13.867476 #31772]  INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2016-08-04 18:08:13 +0300
I, [2016-08-04T18:08:13.868302 #31772]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:08:13.868338 #31772]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:08:13.868635 #31772]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:08:13.876473 #31772] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:08:13.878314 #31772]  INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2016-08-04 18:08:13 +0300
I, [2016-08-04T18:08:13.878957 #31772]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:08:13.878985 #31772]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:08:13.879240 #31772]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:08:13.882350 #31772] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:08:13.987688 #31772]  INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2016-08-04 18:08:13 +0300
I, [2016-08-04T18:08:13.988581 #31772]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:08:13.988621 #31772]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:08:13.988942 #31772]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:08:13.997668 #31772] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:08:13.999267 #31772]  INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2016-08-04 18:08:13 +0300
I, [2016-08-04T18:08:13.999885 #31772]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:08:13.999913 #31772]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:08:14.000144 #31772]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:08:14.002987 #31772] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:08:14.107080 #31772]  INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:08:14 +0300
I, [2016-08-04T18:08:14.108032 #31772]  INFO -- : Processing by DummyController#notify_airbrake_helper as HTML
I, [2016-08-04T18:08:14.108072 #31772]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:08:14.111859 #31772]  INFO -- :   Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:08:14.112168 #31772]  INFO -- : Completed 200 OK in 4ms (Views: 1.1ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:08:14.216517 #31772]  INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:08:14 +0300
I, [2016-08-04T18:08:14.217369 #31772]  INFO -- : Processing by DummyController#notify_airbrake_helper as HTML
I, [2016-08-04T18:08:14.217408 #31772]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:08:14.225792 #31772]  INFO -- :   Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:08:14.226062 #31772]  INFO -- : Completed 200 OK in 9ms (Views: 5.8ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:08:14.227954 #31772]  INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:08:14 +0300
I, [2016-08-04T18:08:14.228554 #31772]  INFO -- : Processing by DummyController#notify_airbrake_helper as HTML
I, [2016-08-04T18:08:14.228581 #31772]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:08:14.232080 #31772]  INFO -- :   Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:08:14.232369 #31772]  INFO -- : Completed 200 OK in 4ms (Views: 1.0ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:08:14.338241 #31772]  INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:08:14 +0300
I, [2016-08-04T18:08:14.339122 #31772]  INFO -- : Processing by DummyController#notify_airbrake_helper as HTML
I, [2016-08-04T18:08:14.339160 #31772]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:08:14.351320 #31772]  INFO -- :   Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:08:14.351559 #31772]  INFO -- : Completed 200 OK in 12ms (Views: 8.9ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:08:14.353471 #31772]  INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2016-08-04 18:08:14 +0300
I, [2016-08-04T18:08:14.354285 #31772]  INFO -- : Processing by DummyController#notify_airbrake_helper as HTML
I, [2016-08-04T18:08:14.354324 #31772]  INFO -- :   Parameters: {"foo"=>"bar"}
I, [2016-08-04T18:08:14.357864 #31772]  INFO -- :   Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:08:14.358165 #31772]  INFO -- : Completed 200 OK in 4ms (Views: 0.9ms | ActiveRecord: 0.0ms)
I, [2016-08-04T18:08:14.462949 #31772]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:08:14 +0300
I, [2016-08-04T18:08:14.463904 #31772]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:08:14.464334 #31772]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:08:14.473226 #31772] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:08:14.474692 #31772]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:08:14 +0300
I, [2016-08-04T18:08:14.475179 #31772]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:08:14.475422 #31772]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:08:14.478473 #31772] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:08:14.585152 #31772]  INFO -- : Started GET "/crash" for 127.0.0.1 at 2016-08-04 18:08:14 +0300
I, [2016-08-04T18:08:14.585923 #31772]  INFO -- : Processing by DummyController#crash as HTML
I, [2016-08-04T18:08:14.586239 #31772]  INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:08:14.594034 #31772] FATAL -- : 
AirbrakeTestError (AirbrakeTestError):
  lib/airbrake/rack/middleware.rb:22:in `call'


I, [2016-08-04T18:08:14.595823 #31772]  INFO -- : Started GET "/resque" for 127.0.0.1 at 2016-08-04 18:08:14 +0300
I, [2016-08-04T18:08:14.596567 #31772]  INFO -- : Processing by DummyController#resque as HTML
I, [2016-08-04T18:08:14.604913 #31772]  INFO -- :   Rendered dummy/resque.html.erb within layouts/application (0.2ms)
I, [2016-08-04T18:08:14.605182 #31772]  INFO -- : Completed 200 OK in 9ms (Views: 1.0ms | ActiveRecord: 0.0ms)
D, [2016-08-04T18:08:19.624127 #31819] DEBUG -- :    (0.2ms)  CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar) 
D, [2016-08-04T18:08:19.627778 #31819] DEBUG -- :    (0.1ms)  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, [2016-08-04T18:08:19.627949 #31819] DEBUG -- :    (0.0ms)  select sqlite_version(*)
D, [2016-08-04T18:08:19.628238 #31819] DEBUG -- :    (0.1ms)  CREATE  INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")
I, [2016-08-04T18:08:19.660649 #31819]  INFO -- : Started GET "/active_job" for 127.0.0.1 at 2016-08-04 18:08:19 +0300
I, [2016-08-04T18:08:19.662857 #31819]  INFO -- : Processing by DummyController#active_job as HTML
I, [2016-08-04T18:08:19.670390 #31819]  INFO -- : Completed 500 Internal Server Error in 7ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:08:19.671699 #31819] FATAL -- : 
AirbrakeTestError (active_job error):
  lib/airbrake/rack/middleware.rb:22:in `call'


D, [2016-08-04T18:08:35.285166 #31865] DEBUG -- :    (0.3ms)  CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar) 
D, [2016-08-04T18:08:35.289550 #31865] DEBUG -- :    (0.1ms)  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, [2016-08-04T18:08:35.289750 #31865] DEBUG -- :    (0.0ms)  select sqlite_version(*)
D, [2016-08-04T18:08:35.290074 #31865] DEBUG -- :    (0.1ms)  CREATE  INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")
I, [2016-08-04T18:08:35.328299 #31865]  INFO -- : Started GET "/active_job" for 127.0.0.1 at 2016-08-04 18:08:35 +0300
I, [2016-08-04T18:08:35.332272 #31865]  INFO -- : Processing by DummyController#active_job as HTML
I, [2016-08-04T18:08:37.457743 #31865]  INFO -- : Completed 500 Internal Server Error in 2125ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:08:37.459927 #31865] FATAL -- : 
AirbrakeTestError (active_job error):
  lib/airbrake/rack/middleware.rb:22:in `call'


D, [2016-08-04T18:08:43.984512 #31894] DEBUG -- :    (0.3ms)  CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar) 
D, [2016-08-04T18:08:43.988348 #31894] DEBUG -- :    (0.1ms)  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, [2016-08-04T18:08:43.988546 #31894] DEBUG -- :    (0.0ms)  select sqlite_version(*)
D, [2016-08-04T18:08:43.988836 #31894] DEBUG -- :    (0.1ms)  CREATE  INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")
I, [2016-08-04T18:08:44.019090 #31894]  INFO -- : Started GET "/active_job" for 127.0.0.1 at 2016-08-04 18:08:44 +0300
I, [2016-08-04T18:08:44.021305 #31894]  INFO -- : Processing by DummyController#active_job as HTML
I, [2016-08-04T18:08:45.768611 #31894]  INFO -- :   Rendered dummy/active_job.html.erb within layouts/application (1.1ms)
I, [2016-08-04T18:08:45.769124 #31894]  INFO -- : Completed 200 OK in 1748ms (Views: 7.9ms | ActiveRecord: 0.0ms)
D, [2016-08-04T18:09:18.303642 #32007] DEBUG -- :    (0.3ms)  CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar) 
D, [2016-08-04T18:09:18.307646 #32007] DEBUG -- :    (0.1ms)  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, [2016-08-04T18:09:18.307859 #32007] DEBUG -- :    (0.0ms)  select sqlite_version(*)
D, [2016-08-04T18:09:18.308160 #32007] DEBUG -- :    (0.1ms)  CREATE  INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")
I, [2016-08-04T18:09:18.351679 #32007]  INFO -- : Started GET "/active_job" for 127.0.0.1 at 2016-08-04 18:09:18 +0300
I, [2016-08-04T18:09:18.356237 #32007]  INFO -- : Processing by DummyController#active_job as HTML
I, [2016-08-04T18:09:18.365618 #32007]  INFO -- : Completed 500 Internal Server Error in 9ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:09:18.367628 #32007] FATAL -- : 
AirbrakeTestError (active_job error):
  lib/airbrake/rack/middleware.rb:22:in `call'


D, [2016-08-04T18:09:53.070158 #32124] DEBUG -- :    (0.3ms)  CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar) 
D, [2016-08-04T18:09:53.073748 #32124] DEBUG -- :    (0.1ms)  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, [2016-08-04T18:09:53.073933 #32124] DEBUG -- :    (0.0ms)  select sqlite_version(*)
D, [2016-08-04T18:09:53.074202 #32124] DEBUG -- :    (0.1ms)  CREATE  INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")
I, [2016-08-04T18:09:53.103502 #32124]  INFO -- : Started GET "/active_job" for 127.0.0.1 at 2016-08-04 18:09:53 +0300
I, [2016-08-04T18:09:53.107040 #32124]  INFO -- : Processing by DummyController#active_job as HTML
I, [2016-08-04T18:09:53.113126 #32124]  INFO -- : Completed 500 Internal Server Error in 6ms (ActiveRecord: 0.0ms)
F, [2016-08-04T18:09:53.114626 #32124] FATAL -- : 
AirbrakeTestError (active_job error):
  lib/airbrake/rack/middleware.rb:22:in `call'


D, [2016-08-04T18:10:28.389003 #32237] DEBUG -- :    (0.3ms)  CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar) 
D, [2016-08-04T18:10:28.392695 #32237] DEBUG -- :    (0.1ms)  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, [2016-08-04T18:10:28.392868 #32237] DEBUG -- :    (0.0ms)  select sqlite_version(*)
D, [2016-08-04T18:10:28.393141 #32237] DEBUG -- :    (0.1ms)  CREATE  INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")
I, [2016-08-04T18:10:28.423620 #32237]  INFO -- : Started GET "/active_job" for 127.0.0.1 at 2016-08-04 18:10:28 +0300
I, [2016-08-04T18:10:28.425616 #32237]  INFO -- : Processing by DummyController#active_job as HTML
I, [2016-08-04T18:10:28.434828 #32237]  INFO -- :   Rendered dummy/active_job.html.erb within layouts/application (0.8ms)
I, [2016-08-04T18:10:28.435172 #32237]  INFO -- : Completed 200 OK in 9ms (Views: 4.2ms | ActiveRecord: 0.0ms)
D, [2016-08-10T12:34:48.565801 #4032] DEBUG -- :    (0.3ms)  CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar) 
D, [2016-08-10T12:34:48.571622 #4032] 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, [2016-08-10T12:34:48.572185 #4032] DEBUG -- :    (0.3ms)  select sqlite_version(*)
D, [2016-08-10T12:34:48.572593 #4032] DEBUG -- :    (0.1ms)  CREATE  INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")
I, [2016-08-10T12:34:48.618749 #4032]  INFO -- : Started GET "/active_job" for 127.0.0.1 at 2016-08-10 12:34:48 +0300
I, [2016-08-10T12:34:48.621823 #4032]  INFO -- : Processing by DummyController#active_job as HTML
I, [2016-08-10T12:34:48.634827 #4032]  INFO -- :   Rendered dummy/active_job.html.erb within layouts/application (1.2ms)
I, [2016-08-10T12:34:48.635215 #4032]  INFO -- : Completed 200 OK in 13ms (Views: 6.3ms | ActiveRecord: 0.0ms)
D, [2016-08-10T12:35:33.787875 #4190] DEBUG -- :    (0.3ms)  CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar) 
D, [2016-08-10T12:35:33.791566 #4190] DEBUG -- :    (0.1ms)  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, [2016-08-10T12:35:33.791759 #4190] DEBUG -- :    (0.0ms)  select sqlite_version(*)
D, [2016-08-10T12:35:33.792052 #4190] DEBUG -- :    (0.1ms)  CREATE  INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")
I, [2016-08-10T12:35:33.820914 #4190]  INFO -- : Started GET "/active_job" for 127.0.0.1 at 2016-08-10 12:35:33 +0300
I, [2016-08-10T12:35:33.822867 #4190]  INFO -- : Processing by DummyController#active_job as HTML
I, [2016-08-10T12:35:33.829437 #4190]  INFO -- : Completed 500 Internal Server Error in 6ms (ActiveRecord: 0.0ms)
F, [2016-08-10T12:35:33.831023 #4190] FATAL -- : 
AirbrakeTestError (active_job error):
  lib/airbrake/rack/middleware.rb:22:in `call'


D, [2016-08-10T12:36:34.456947 #4319] DEBUG -- :    (0.3ms)  CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar) 
D, [2016-08-10T12:36:34.460872 #4319] DEBUG -- :    (0.1ms)  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, [2016-08-10T12:36:34.461102 #4319] DEBUG -- :    (0.0ms)  select sqlite_version(*)
D, [2016-08-10T12:36:34.461417 #4319] DEBUG -- :    (0.1ms)  CREATE  INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")
I, [2016-08-10T12:36:38.060797 #4319]  INFO -- : Started GET "/active_job" for 127.0.0.1 at 2016-08-10 12:36:38 +0300
I, [2016-08-10T12:36:38.063621 #4319]  INFO -- : Processing by DummyController#active_job as HTML
I, [2016-08-10T12:36:38.069214 #4319]  INFO -- : Completed 500 Internal Server Error in 5ms (ActiveRecord: 0.0ms)
F, [2016-08-10T12:36:38.070352 #4319] FATAL -- : 
AirbrakeTestError (active_job error):
  lib/airbrake/rack/middleware.rb:22:in `call'


D, [2016-08-10T12:38:29.212407 #4454] DEBUG -- :    (0.3ms)  CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar) 
D, [2016-08-10T12:38:29.216050 #4454] DEBUG -- :    (0.1ms)  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, [2016-08-10T12:38:29.216238 #4454] DEBUG -- :    (0.0ms)  select sqlite_version(*)
D, [2016-08-10T12:38:29.216591 #4454] DEBUG -- :    (0.1ms)  CREATE  INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")
I, [2016-08-10T12:38:29.248027 #4454]  INFO -- : Started GET "/active_job" for 127.0.0.1 at 2016-08-10 12:38:29 +0300
I, [2016-08-10T12:38:29.250007 #4454]  INFO -- : Processing by DummyController#active_job as HTML
I, [2016-08-10T12:38:34.315033 #4454]  INFO -- : Completed 500 Internal Server Error in 5065ms (ActiveRecord: 0.0ms)
F, [2016-08-10T12:38:34.316637 #4454] FATAL -- : 
AirbrakeTestError (active_job error):
  lib/airbrake/rack/middleware.rb:22:in `call'


D, [2016-08-10T12:38:56.227813 #4524] DEBUG -- :    (0.4ms)  CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar) 
D, [2016-08-10T12:38:56.232310 #4524] DEBUG -- :    (0.1ms)  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, [2016-08-10T12:38:56.232533 #4524] DEBUG -- :    (0.0ms)  select sqlite_version(*)
D, [2016-08-10T12:38:56.232875 #4524] DEBUG -- :    (0.1ms)  CREATE  INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")
I, [2016-08-10T12:38:56.263559 #4524]  INFO -- : Started GET "/active_job" for 127.0.0.1 at 2016-08-10 12:38:56 +0300
I, [2016-08-10T12:38:56.266188 #4524]  INFO -- : Processing by DummyController#active_job as HTML
I, [2016-08-10T12:38:56.271707 #4524]  INFO -- : Completed 500 Internal Server Error in 5ms (ActiveRecord: 0.0ms)
F, [2016-08-10T12:38:56.272893 #4524] FATAL -- : 
AirbrakeTestError (active_job error):
  lib/airbrake/rack/middleware.rb:22:in `call'


D, [2016-08-10T12:39:31.780943 #4625] DEBUG -- :    (0.3ms)  CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar) 
D, [2016-08-10T12:39:31.784503 #4625] DEBUG -- :    (0.1ms)  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, [2016-08-10T12:39:31.784674 #4625] DEBUG -- :    (0.0ms)  select sqlite_version(*)
D, [2016-08-10T12:39:31.784968 #4625] DEBUG -- :    (0.1ms)  CREATE  INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")
I, [2016-08-10T12:39:31.812941 #4625]  INFO -- : Started GET "/active_job" for 127.0.0.1 at 2016-08-10 12:39:31 +0300
I, [2016-08-10T12:39:31.814955 #4625]  INFO -- : Processing by DummyController#active_job as HTML
I, [2016-08-10T12:39:31.820405 #4625]  INFO -- : Completed 500 Internal Server Error in 5ms (ActiveRecord: 0.0ms)
F, [2016-08-10T12:39:31.821682 #4625] FATAL -- : 
AirbrakeTestError (active_job error):
  lib/airbrake/rack/middleware.rb:22:in `call'


D, [2016-08-10T12:42:13.866982 #5124] DEBUG -- :    (0.3ms)  CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar) 
D, [2016-08-10T12:42:13.870807 #5124] DEBUG -- :    (0.1ms)  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, [2016-08-10T12:42:13.871007 #5124] DEBUG -- :    (0.0ms)  select sqlite_version(*)
D, [2016-08-10T12:42:13.871309 #5124] DEBUG -- :    (0.1ms)  CREATE  INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")
I, [2016-08-10T12:42:13.900596 #5124]  INFO -- : Started GET "/active_job" for 127.0.0.1 at 2016-08-10 12:42:13 +0300
I, [2016-08-10T12:42:13.902820 #5124]  INFO -- : Processing by DummyController#active_job as HTML
I, [2016-08-10T12:42:13.908462 #5124]  INFO -- : Completed 500 Internal Server Error in 6ms (ActiveRecord: 0.0ms)
F, [2016-08-10T12:42:13.909529 #5124] FATAL -- : 
AirbrakeTestError (active_job error):
  lib/airbrake/rack/middleware.rb:22:in `call'


D, [2016-08-10T12:42:45.646052 #5212] DEBUG -- :    (0.3ms)  CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar) 
D, [2016-08-10T12:42:45.649824 #5212] DEBUG -- :    (0.1ms)  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, [2016-08-10T12:42:45.650023 #5212] DEBUG -- :    (0.0ms)  select sqlite_version(*)
D, [2016-08-10T12:42:45.650373 #5212] DEBUG -- :    (0.1ms)  CREATE  INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")
I, [2016-08-10T12:42:45.684535 #5212]  INFO -- : Started GET "/active_job" for 127.0.0.1 at 2016-08-10 12:42:45 +0300
I, [2016-08-10T12:42:45.686631 #5212]  INFO -- : Processing by DummyController#active_job as HTML
I, [2016-08-10T12:42:45.691849 #5212]  INFO -- : Completed 500 Internal Server Error in 5ms (ActiveRecord: 0.0ms)
F, [2016-08-10T12:42:48.171986 #5212] FATAL -- : 
AirbrakeTestError (active_job error):
  lib/airbrake/rack/middleware.rb:22:in `call'


D, [2016-08-10T12:51:52.339480 #6026] DEBUG -- :    (0.3ms)  CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar) 
D, [2016-08-10T12:51:52.343437 #6026] DEBUG -- :    (0.1ms)  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, [2016-08-10T12:51:52.343644 #6026] DEBUG -- :    (0.0ms)  select sqlite_version(*)
D, [2016-08-10T12:51:52.348798 #6026] DEBUG -- :    (0.1ms)  CREATE  INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")
I, [2016-08-10T12:51:52.379375 #6026]  INFO -- : Started GET "/active_job" for 127.0.0.1 at 2016-08-10 12:51:52 +0300
I, [2016-08-10T12:51:52.381425 #6026]  INFO -- : Processing by DummyController#active_job as HTML
I, [2016-08-10T12:51:52.387063 #6026]  INFO -- : Completed 500 Internal Server Error in 6ms (ActiveRecord: 0.0ms)
F, [2016-08-10T12:51:52.387888 #6026] FATAL -- : 
ArgumentError (wrong number of arguments (given 0, expected 1+)):
  lib/airbrake/rack/middleware.rb:24:in `rescue in call'
  lib/airbrake/rack/middleware.rb:21:in `call'