# Logfile created on 2018-11-12 22:46:26 +0800 by logger.rb/56815 D, [2018-11-12T22:46:26.520684 #77412] DEBUG -- :  (2.8ms) CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar)  D, [2018-11-12T22:46:26.524464 #77412] 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, [2018-11-12T22:46:26.524722 #77412] DEBUG -- :  (0.1ms) select sqlite_version(*) D, [2018-11-12T22:46:26.525025 #77412] DEBUG -- :  (0.1ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at") I, [2018-11-12T22:46:26.563925 #77412] INFO -- : Started GET "/" for 127.0.0.1 at 2018-11-12 22:46:26 +0800 I, [2018-11-12T22:46:26.565688 #77412] INFO -- : Processing by DummyController#index as HTML I, [2018-11-12T22:46:26.571767 #77412] INFO -- : Rendered dummy/index.html.erb within layouts/application (1.1ms) I, [2018-11-12T22:46:26.572082 #77412] INFO -- : Completed 200 OK in 6ms (Views: 6.2ms | ActiveRecord: 0.0ms) D, [2018-11-15T01:08:19.556131 #503] DEBUG -- :  (2.0ms) CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar)  D, [2018-11-15T01:08:19.559575 #503] 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, [2018-11-15T01:08:19.559780 #503] DEBUG -- :  (0.0ms) select sqlite_version(*) D, [2018-11-15T01:08:19.560117 #503] DEBUG -- :  (0.1ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at") I, [2018-11-15T01:08:19.600818 #503] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-15 01:08:19 +0800 I, [2018-11-15T01:08:19.610657 #503] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:08:19.610729 #503] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:08:19.611091 #503] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:08:19.616870 #503] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:08:19.624280 #503] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-15 01:08:19 +0800 I, [2018-11-15T01:08:19.624975 #503] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:08:19.625015 #503] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:08:19.625273 #503] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:08:19.630086 #503] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:08:19.635748 #503] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-15 01:08:19 +0800 I, [2018-11-15T01:08:19.636609 #503] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:08:19.636668 #503] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:08:19.637102 #503] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:08:19.643366 #503] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:08:19.650862 #503] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-15 01:08:19 +0800 I, [2018-11-15T01:08:19.651937 #503] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:08:19.652058 #503] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:08:19.652743 #503] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:08:19.664129 #503] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:08:19.674707 #503] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-15 01:08:19 +0800 I, [2018-11-15T01:08:19.675487 #503] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:08:19.675532 #503] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:08:19.675838 #503] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:08:19.680940 #503] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:08:19.799712 #503] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-15 01:08:19 +0800 I, [2018-11-15T01:08:19.800582 #503] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:08:19.800633 #503] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:08:19.800956 #503] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:08:19.810297 #503] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:08:19.812212 #503] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:08:19 +0800 I, [2018-11-15T01:08:19.812919 #503] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-15T01:08:19.812956 #503] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:08:19.832692 #503] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (1.3ms) I, [2018-11-15T01:08:19.833090 #503] INFO -- : Completed 200 OK in 20ms (Views: 10.6ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:08:19.836036 #503] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:08:19 +0800 I, [2018-11-15T01:08:19.836815 #503] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-15T01:08:19.836858 #503] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:08:19.846325 #503] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:08:19.846593 #503] INFO -- : Completed 200 OK in 10ms (Views: 0.9ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:08:19.849050 #503] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:08:19 +0800 I, [2018-11-15T01:08:19.850021 #503] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-15T01:08:19.850096 #503] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:08:19.863258 #503] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:08:19.863550 #503] INFO -- : Completed 200 OK in 13ms (Views: 0.9ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:08:19.865674 #503] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:08:19 +0800 I, [2018-11-15T01:08:19.866448 #503] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-15T01:08:19.866496 #503] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:08:19.875719 #503] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:08:19.875942 #503] INFO -- : Completed 200 OK in 9ms (Views: 0.7ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:08:19.877759 #503] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:08:19 +0800 I, [2018-11-15T01:08:19.878264 #503] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-15T01:08:19.878301 #503] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:08:19.887719 #503] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:08:19.887982 #503] INFO -- : Completed 200 OK in 10ms (Views: 0.8ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:08:19.889854 #503] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:08:19 +0800 I, [2018-11-15T01:08:19.890398 #503] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-15T01:08:19.890438 #503] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:08:19.899270 #503] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:08:19.899549 #503] INFO -- : Completed 200 OK in 9ms (Views: 1.0ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:08:19.901925 #503] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:08:19 +0800 I, [2018-11-15T01:08:19.902498 #503] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-15T01:08:19.902548 #503] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:08:19.912231 #503] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.1ms) I, [2018-11-15T01:08:19.912531 #503] INFO -- : Completed 200 OK in 10ms (Views: 0.9ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:08:19.914487 #503] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:08:19 +0800 I, [2018-11-15T01:08:19.915123 #503] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-15T01:08:19.915166 #503] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:08:19.919886 #503] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:08:19.920354 #503] INFO -- : Completed 200 OK in 5ms (Views: 0.9ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:08:19.928129 #503] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:08:19 +0800 I, [2018-11-15T01:08:19.928715 #503] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-15T01:08:19.928756 #503] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:08:19.933541 #503] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:08:19.938169 #503] INFO -- : Completed 200 OK in 9ms (Views: 5.1ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:08:19.940175 #503] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:08:19 +0800 I, [2018-11-15T01:08:19.940717 #503] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-15T01:08:19.940757 #503] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:08:19.945345 #503] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.1ms) I, [2018-11-15T01:08:19.945579 #503] INFO -- : Completed 200 OK in 5ms (Views: 0.7ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:08:20.053730 #503] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:08:20 +0800 I, [2018-11-15T01:08:20.054885 #503] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-15T01:08:20.054940 #503] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:08:20.066584 #503] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:08:20.066923 #503] INFO -- : Completed 200 OK in 12ms (Views: 6.0ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:08:20.069632 #503] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:08:20 +0800 I, [2018-11-15T01:08:20.070906 #503] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-15T01:08:20.070968 #503] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:08:20.075589 #503] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:08:20.075834 #503] INFO -- : Completed 200 OK in 5ms (Views: 0.8ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:08:20.082576 #503] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:08:20 +0800 I, [2018-11-15T01:08:20.083225 #503] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:08:20.083494 #503] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:08:20.089216 #503] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:08:20.096304 #503] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:08:20 +0800 I, [2018-11-15T01:08:20.097075 #503] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:08:20.097365 #503] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:08:20.102642 #503] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:08:20.110949 #503] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:08:20 +0800 I, [2018-11-15T01:08:20.111401 #503] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:08:20.111623 #503] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:08:20.116633 #503] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:08:20.123029 #503] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:08:20 +0800 I, [2018-11-15T01:08:20.123496 #503] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:08:20.123794 #503] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:08:20.128372 #503] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:08:20.134438 #503] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:08:20 +0800 I, [2018-11-15T01:08:20.134969 #503] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:08:20.135221 #503] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:08:20.140113 #503] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:08:20.145527 #503] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:08:20 +0800 I, [2018-11-15T01:08:20.145948 #503] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:08:20.146233 #503] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:08:20.151051 #503] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:08:20.257946 #503] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:08:20 +0800 I, [2018-11-15T01:08:20.258730 #503] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:08:20.259054 #503] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:08:20.270305 #503] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:08:20.272595 #503] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:08:20 +0800 I, [2018-11-15T01:08:20.273260 #503] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:08:20.273568 #503] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:08:20.278461 #503] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:08:20.383461 #503] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:08:20 +0800 I, [2018-11-15T01:08:20.384523 #503] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:08:20.384853 #503] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:08:20.393915 #503] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:08:20.395992 #503] INFO -- : Started GET "/active_record_after_commit" for 127.0.0.1 at 2018-11-15 01:08:20 +0800 I, [2018-11-15T01:08:20.396561 #503] INFO -- : Processing by DummyController#active_record_after_commit as HTML D, [2018-11-15T01:08:20.403032 #503] DEBUG -- :  (0.1ms) begin transaction D, [2018-11-15T01:08:20.405963 #503] DEBUG -- : SQL (0.1ms) INSERT INTO "books" ("title") VALUES (?) [["title", "Bingo"]] D, [2018-11-15T01:08:20.406190 #503] DEBUG -- :  (0.0ms) commit transaction I, [2018-11-15T01:08:20.406516 #503] INFO -- : Completed 500 Internal Server Error in 10ms (ActiveRecord: 0.4ms) F, [2018-11-15T01:08:20.411577 #503] FATAL -- : AirbrakeTestError (after_commit): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:08:20.516351 #503] INFO -- : Started GET "/active_record_after_rollback" for 127.0.0.1 at 2018-11-15 01:08:20 +0800 I, [2018-11-15T01:08:20.517436 #503] INFO -- : Processing by DummyController#active_record_after_rollback as HTML D, [2018-11-15T01:08:20.517730 #503] DEBUG -- :  (0.1ms) begin transaction D, [2018-11-15T01:08:20.518396 #503] DEBUG -- : SQL (0.1ms) INSERT INTO "books" ("title") VALUES (?) [["title", "Bango"]] D, [2018-11-15T01:08:20.518659 #503] DEBUG -- :  (0.0ms) rollback transaction I, [2018-11-15T01:08:20.519075 #503] INFO -- : Completed 500 Internal Server Error in 2ms (ActiveRecord: 0.2ms) F, [2018-11-15T01:08:20.536761 #503] FATAL -- : AirbrakeTestError (after_rollback): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:08:20.539076 #503] INFO -- : Started GET "/resque" for 127.0.0.1 at 2018-11-15 01:08:20 +0800 I, [2018-11-15T01:08:20.539757 #503] INFO -- : Processing by DummyController#resque as HTML I, [2018-11-15T01:08:20.541322 #503] INFO -- : Rendered dummy/resque.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:08:20.541568 #503] INFO -- : Completed 200 OK in 2ms (Views: 0.8ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:08:20.543106 #503] INFO -- : Started GET "/resque" for 127.0.0.1 at 2018-11-15 01:08:20 +0800 I, [2018-11-15T01:08:20.543468 #503] INFO -- : Processing by DummyController#resque as HTML I, [2018-11-15T01:08:20.553963 #503] INFO -- : Rendered dummy/resque.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:08:20.554289 #503] INFO -- : Completed 200 OK in 11ms (Views: 1.1ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:08:20.558467 #503] INFO -- : Started GET "/active_job" for 127.0.0.1 at 2018-11-15 01:08:20 +0800 I, [2018-11-15T01:08:20.559013 #503] INFO -- : Processing by DummyController#active_job as HTML I, [2018-11-15T01:08:20.572782 #503] INFO -- : Completed 500 Internal Server Error in 14ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:08:20.584576 #503] FATAL -- : AirbrakeTestError (active_job error): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:08:22.597051 #503] INFO -- : Started GET "/active_job" for 127.0.0.1 at 2018-11-15 01:08:22 +0800 I, [2018-11-15T01:08:22.598037 #503] INFO -- : Processing by DummyController#active_job as HTML I, [2018-11-15T01:08:22.606883 #503] INFO -- : Completed 500 Internal Server Error in 9ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:08:22.619732 #503] FATAL -- : AirbrakeTestError (active_job error): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:08:24.634340 #503] INFO -- : Started GET "/active_job" for 127.0.0.1 at 2018-11-15 01:08:24 +0800 I, [2018-11-15T01:08:24.635167 #503] INFO -- : Processing by DummyController#active_job as HTML I, [2018-11-15T01:08:24.636066 #503] INFO -- : Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:08:24.637806 #503] FATAL -- : AirbrakeTestError (active_job error): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:08:26.645025 #503] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:08:26 +0800 I, [2018-11-15T01:08:26.646043 #503] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:08:26.646576 #503] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:08:26.660162 #503] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:08:26.662419 #503] INFO -- : Started GET "/" for 127.0.0.1 at 2018-11-15 01:08:26 +0800 I, [2018-11-15T01:08:26.663125 #503] INFO -- : Processing by DummyController#index as HTML I, [2018-11-15T01:08:26.664087 #503] INFO -- : Rendered dummy/index.html.erb within layouts/application (0.3ms) I, [2018-11-15T01:08:26.664399 #503] INFO -- : Completed 200 OK in 1ms (Views: 1.1ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:08:26.666025 #503] INFO -- : Started GET "/delayed_job" for 127.0.0.1 at 2018-11-15 01:08:26 +0800 I, [2018-11-15T01:08:26.666557 #503] INFO -- : Processing by DummyController#delayed_job as HTML I, [2018-11-15T01:08:26.677634 #503] INFO -- : Completed 500 Internal Server Error in 11ms (ActiveRecord: 0.2ms) F, [2018-11-15T01:08:26.687589 #503] FATAL -- : AirbrakeTestError (delayed_job error): lib/airbrake/delayed_job.rb:10:in `block (2 levels) in ' lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:08:30.702189 #503] INFO -- : Started GET "/delayed_job" for 127.0.0.1 at 2018-11-15 01:08:30 +0800 I, [2018-11-15T01:08:30.703056 #503] INFO -- : Processing by DummyController#delayed_job as HTML I, [2018-11-15T01:08:30.704345 #503] INFO -- : Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:08:30.705855 #503] FATAL -- : AirbrakeTestError (delayed_job error): lib/airbrake/delayed_job.rb:10:in `block (2 levels) in ' lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:08:32.714265 #503] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:08:32 +0800 I, [2018-11-15T01:08:32.715139 #503] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:08:32.715463 #503] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:08:32.721483 #503] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' D, [2018-11-15T01:09:19.311194 #525] DEBUG -- :  (0.5ms) CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar)  D, [2018-11-15T01:09:19.313652 #525] 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, [2018-11-15T01:09:19.313871 #525] DEBUG -- :  (0.0ms) select sqlite_version(*) D, [2018-11-15T01:09:19.314175 #525] DEBUG -- :  (0.1ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at") I, [2018-11-15T01:09:19.347636 #525] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:09:19 +0800 I, [2018-11-15T01:09:19.349421 #525] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:09:19.349729 #525] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:09:19.365144 #525] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:09:21.378221 #525] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-15 01:09:21 +0800 I, [2018-11-15T01:09:21.379208 #525] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:09:21.379266 #525] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:09:21.379654 #525] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:09:21.386049 #525] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:09:21.392271 #525] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-15 01:09:21 +0800 I, [2018-11-15T01:09:21.393103 #525] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:09:21.393155 #525] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:09:21.393449 #525] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:09:21.399244 #525] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:09:21.409179 #525] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-15 01:09:21 +0800 I, [2018-11-15T01:09:21.409947 #525] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:09:21.409987 #525] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:09:21.410235 #525] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:09:21.415720 #525] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:09:21.421352 #525] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-15 01:09:21 +0800 I, [2018-11-15T01:09:21.422075 #525] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:09:21.422113 #525] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:09:21.422363 #525] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:09:21.427920 #525] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:09:21.434403 #525] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-15 01:09:21 +0800 I, [2018-11-15T01:09:21.435106 #525] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:09:21.435145 #525] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:09:21.435405 #525] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:09:21.440496 #525] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:09:21.559394 #525] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-15 01:09:21 +0800 I, [2018-11-15T01:09:21.560635 #525] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:09:21.560689 #525] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:09:21.561058 #525] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:09:21.571968 #525] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:09:21.574034 #525] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:09:21 +0800 I, [2018-11-15T01:09:21.574823 #525] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-15T01:09:21.574869 #525] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:09:21.584400 #525] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.8ms) I, [2018-11-15T01:09:21.588949 #525] INFO -- : Completed 200 OK in 14ms (Views: 9.5ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:09:21.591469 #525] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:09:21 +0800 I, [2018-11-15T01:09:21.592242 #525] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-15T01:09:21.592293 #525] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:09:21.600186 #525] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:09:21.605079 #525] INFO -- : Completed 200 OK in 13ms (Views: 5.4ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:09:21.607703 #525] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:09:21 +0800 I, [2018-11-15T01:09:21.608517 #525] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-15T01:09:21.608574 #525] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:09:21.613930 #525] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:09:21.618467 #525] INFO -- : Completed 200 OK in 10ms (Views: 5.2ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:09:21.620474 #525] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:09:21 +0800 I, [2018-11-15T01:09:21.621101 #525] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-15T01:09:21.621138 #525] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:09:21.626026 #525] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.3ms) I, [2018-11-15T01:09:21.630592 #525] INFO -- : Completed 200 OK in 9ms (Views: 5.2ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:09:21.632911 #525] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:09:21 +0800 I, [2018-11-15T01:09:21.633686 #525] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-15T01:09:21.633727 #525] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:09:21.638715 #525] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:09:21.638986 #525] INFO -- : Completed 200 OK in 5ms (Views: 0.9ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:09:21.745352 #525] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:09:21 +0800 I, [2018-11-15T01:09:21.746598 #525] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-15T01:09:21.746682 #525] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:09:21.759412 #525] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:09:21.759752 #525] INFO -- : Completed 200 OK in 13ms (Views: 6.2ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:09:21.762534 #525] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:09:21 +0800 I, [2018-11-15T01:09:21.763307 #525] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-15T01:09:21.763346 #525] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:09:21.772266 #525] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:09:21.772564 #525] INFO -- : Completed 200 OK in 9ms (Views: 1.0ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:09:21.777046 #525] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:09:21 +0800 I, [2018-11-15T01:09:21.777998 #525] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-15T01:09:21.778065 #525] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:09:21.786500 #525] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:09:21.786781 #525] INFO -- : Completed 200 OK in 9ms (Views: 0.9ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:09:21.789089 #525] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:09:21 +0800 I, [2018-11-15T01:09:21.789744 #525] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-15T01:09:21.789781 #525] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:09:21.798863 #525] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:09:21.799150 #525] INFO -- : Completed 200 OK in 9ms (Views: 0.9ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:09:21.801623 #525] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:09:21 +0800 I, [2018-11-15T01:09:21.802311 #525] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-15T01:09:21.802350 #525] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:09:21.811361 #525] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.3ms) I, [2018-11-15T01:09:21.811828 #525] INFO -- : Completed 200 OK in 9ms (Views: 1.3ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:09:21.814863 #525] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:09:21 +0800 I, [2018-11-15T01:09:21.815622 #525] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-15T01:09:21.815663 #525] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:09:21.824657 #525] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:09:21.824955 #525] INFO -- : Completed 200 OK in 9ms (Views: 0.9ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:09:21.827003 #525] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:09:21 +0800 I, [2018-11-15T01:09:21.827568 #525] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-15T01:09:21.827612 #525] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:09:21.837520 #525] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:09:21.837821 #525] INFO -- : Completed 200 OK in 10ms (Views: 1.0ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:09:21.840984 #525] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:09:21 +0800 I, [2018-11-15T01:09:21.841795 #525] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:09:21.842095 #525] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:09:21.847476 #525] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:09:21.855504 #525] INFO -- : Started GET "/resque" for 127.0.0.1 at 2018-11-15 01:09:21 +0800 I, [2018-11-15T01:09:21.856145 #525] INFO -- : Processing by DummyController#resque as HTML I, [2018-11-15T01:09:21.857715 #525] INFO -- : Rendered dummy/resque.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:09:21.857980 #525] INFO -- : Completed 200 OK in 2ms (Views: 0.8ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:09:21.859256 #525] INFO -- : Started GET "/resque" for 127.0.0.1 at 2018-11-15 01:09:21 +0800 I, [2018-11-15T01:09:21.859654 #525] INFO -- : Processing by DummyController#resque as HTML I, [2018-11-15T01:09:21.869101 #525] INFO -- : Rendered dummy/resque.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:09:21.869416 #525] INFO -- : Completed 200 OK in 10ms (Views: 0.9ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:09:21.871608 #525] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:09:21 +0800 I, [2018-11-15T01:09:21.872151 #525] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:09:21.872422 #525] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:09:21.877012 #525] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:09:21.980704 #525] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:09:21 +0800 I, [2018-11-15T01:09:21.981660 #525] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:09:21.982022 #525] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:09:21.993045 #525] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:09:21.995541 #525] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:09:21 +0800 I, [2018-11-15T01:09:21.996161 #525] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:09:21.996425 #525] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:09:22.000927 #525] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:09:22.108322 #525] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:09:22 +0800 I, [2018-11-15T01:09:22.109127 #525] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:09:22.109659 #525] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:09:22.121776 #525] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:09:22.123457 #525] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:09:22 +0800 I, [2018-11-15T01:09:22.123943 #525] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:09:22.124231 #525] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:09:22.132555 #525] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:09:22.138384 #525] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:09:22 +0800 I, [2018-11-15T01:09:22.138882 #525] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:09:22.139182 #525] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:09:22.144611 #525] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:09:22.150579 #525] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:09:22 +0800 I, [2018-11-15T01:09:22.151141 #525] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:09:22.151410 #525] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:09:22.156185 #525] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:09:22.162631 #525] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:09:22 +0800 I, [2018-11-15T01:09:22.163223 #525] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:09:22.163479 #525] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:09:22.168603 #525] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:09:22.276342 #525] INFO -- : Started GET "/active_record_after_commit" for 127.0.0.1 at 2018-11-15 01:09:22 +0800 I, [2018-11-15T01:09:22.277216 #525] INFO -- : Processing by DummyController#active_record_after_commit as HTML D, [2018-11-15T01:09:22.282701 #525] DEBUG -- :  (0.0ms) begin transaction D, [2018-11-15T01:09:22.284806 #525] DEBUG -- : SQL (0.1ms) INSERT INTO "books" ("title") VALUES (?) [["title", "Bingo"]] D, [2018-11-15T01:09:22.285045 #525] DEBUG -- :  (0.0ms) commit transaction I, [2018-11-15T01:09:22.285389 #525] INFO -- : Completed 500 Internal Server Error in 8ms (ActiveRecord: 0.8ms) F, [2018-11-15T01:09:22.295653 #525] FATAL -- : AirbrakeTestError (after_commit): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:09:22.297626 #525] INFO -- : Started GET "/active_record_after_rollback" for 127.0.0.1 at 2018-11-15 01:09:22 +0800 I, [2018-11-15T01:09:22.298281 #525] INFO -- : Processing by DummyController#active_record_after_rollback as HTML D, [2018-11-15T01:09:22.298483 #525] DEBUG -- :  (0.0ms) begin transaction D, [2018-11-15T01:09:22.299003 #525] DEBUG -- : SQL (0.1ms) INSERT INTO "books" ("title") VALUES (?) [["title", "Bango"]] D, [2018-11-15T01:09:22.299196 #525] DEBUG -- :  (0.0ms) rollback transaction I, [2018-11-15T01:09:22.299519 #525] INFO -- : Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.1ms) F, [2018-11-15T01:09:22.311779 #525] FATAL -- : AirbrakeTestError (after_rollback): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:09:22.321749 #525] INFO -- : Started GET "/delayed_job" for 127.0.0.1 at 2018-11-15 01:09:22 +0800 I, [2018-11-15T01:09:22.322437 #525] INFO -- : Processing by DummyController#delayed_job as HTML I, [2018-11-15T01:09:22.335403 #525] INFO -- : Completed 500 Internal Server Error in 13ms (ActiveRecord: 0.2ms) F, [2018-11-15T01:09:22.344491 #525] FATAL -- : AirbrakeTestError (delayed_job error): lib/airbrake/delayed_job.rb:10:in `block (2 levels) in ' lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:09:26.358717 #525] INFO -- : Started GET "/delayed_job" for 127.0.0.1 at 2018-11-15 01:09:26 +0800 I, [2018-11-15T01:09:26.359869 #525] INFO -- : Processing by DummyController#delayed_job as HTML I, [2018-11-15T01:09:26.361316 #525] INFO -- : Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:09:26.363179 #525] FATAL -- : AirbrakeTestError (delayed_job error): lib/airbrake/delayed_job.rb:10:in `block (2 levels) in ' lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:09:28.369969 #525] INFO -- : Started GET "/active_job" for 127.0.0.1 at 2018-11-15 01:09:28 +0800 I, [2018-11-15T01:09:28.370943 #525] INFO -- : Processing by DummyController#active_job as HTML I, [2018-11-15T01:09:28.384443 #525] INFO -- : Completed 500 Internal Server Error in 13ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:09:28.397830 #525] FATAL -- : AirbrakeTestError (active_job error): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:09:30.409867 #525] INFO -- : Started GET "/active_job" for 127.0.0.1 at 2018-11-15 01:09:30 +0800 I, [2018-11-15T01:09:30.410567 #525] INFO -- : Processing by DummyController#active_job as HTML I, [2018-11-15T01:09:30.419773 #525] INFO -- : Completed 500 Internal Server Error in 9ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:09:30.433692 #525] FATAL -- : AirbrakeTestError (active_job error): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:09:32.444473 #525] INFO -- : Started GET "/active_job" for 127.0.0.1 at 2018-11-15 01:09:32 +0800 I, [2018-11-15T01:09:32.445228 #525] INFO -- : Processing by DummyController#active_job as HTML I, [2018-11-15T01:09:32.446064 #525] INFO -- : Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:09:32.447665 #525] FATAL -- : AirbrakeTestError (active_job error): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:09:34.452104 #525] INFO -- : Started GET "/" for 127.0.0.1 at 2018-11-15 01:09:34 +0800 I, [2018-11-15T01:09:34.453120 #525] INFO -- : Processing by DummyController#index as HTML I, [2018-11-15T01:09:34.454209 #525] INFO -- : Rendered dummy/index.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:09:34.454550 #525] INFO -- : Completed 200 OK in 1ms (Views: 1.2ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:09:34.456191 #525] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:09:34 +0800 I, [2018-11-15T01:09:34.456714 #525] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:09:34.457038 #525] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:09:34.467079 #525] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' D, [2018-11-15T01:10:46.554130 #551] DEBUG -- :  (0.5ms) CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar)  D, [2018-11-15T01:10:46.556866 #551] 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, [2018-11-15T01:10:46.557074 #551] DEBUG -- :  (0.0ms) select sqlite_version(*) D, [2018-11-15T01:10:46.557372 #551] DEBUG -- :  (0.1ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at") I, [2018-11-15T01:10:46.591439 #551] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:10:46 +0800 I, [2018-11-15T01:10:46.593352 #551] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:10:46.593700 #551] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:10:46.607012 #551] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:10:51.624917 #551] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:10:51 +0800 I, [2018-11-15T01:10:51.626024 #551] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:10:51.626695 #551] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:10:51.641357 #551] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:10:51.643204 #551] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:10:51 +0800 I, [2018-11-15T01:10:51.643762 #551] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:10:51.644052 #551] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:10:51.649480 #551] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:10:51.655970 #551] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:10:51 +0800 I, [2018-11-15T01:10:51.656586 #551] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:10:51.656895 #551] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:10:51.662395 #551] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:10:51.669405 #551] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:10:51 +0800 I, [2018-11-15T01:10:51.670136 #551] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:10:51.670521 #551] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:10:51.676700 #551] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:10:51.682971 #551] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:10:51 +0800 I, [2018-11-15T01:10:51.683573 #551] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:10:51.683845 #551] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:10:51.688991 #551] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:10:51.794346 #551] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:10:51 +0800 I, [2018-11-15T01:10:51.795340 #551] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:10:51.795744 #551] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:10:51.806517 #551] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:10:51.808553 #551] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:10:51 +0800 I, [2018-11-15T01:10:51.809296 #551] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:10:51.809582 #551] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:10:51.815500 #551] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:10:51.823952 #551] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:10:51 +0800 I, [2018-11-15T01:10:51.824690 #551] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:10:51.824953 #551] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:10:51.830901 #551] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:10:51.836689 #551] INFO -- : Started GET "/" for 127.0.0.1 at 2018-11-15 01:10:51 +0800 I, [2018-11-15T01:10:51.837257 #551] INFO -- : Processing by DummyController#index as HTML I, [2018-11-15T01:10:51.842054 #551] INFO -- : Rendered dummy/index.html.erb within layouts/application (0.8ms) I, [2018-11-15T01:10:51.842388 #551] INFO -- : Completed 200 OK in 5ms (Views: 5.0ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:10:51.844238 #551] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:10:51 +0800 I, [2018-11-15T01:10:51.844910 #551] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:10:51.845231 #551] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:10:51.849942 #551] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:10:51.855942 #551] INFO -- : Started GET "/active_job" for 127.0.0.1 at 2018-11-15 01:10:51 +0800 I, [2018-11-15T01:10:51.856551 #551] INFO -- : Processing by DummyController#active_job as HTML I, [2018-11-15T01:10:51.866409 #551] INFO -- : Completed 500 Internal Server Error in 10ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:10:51.878777 #551] FATAL -- : AirbrakeTestError (active_job error): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:10:53.890154 #551] INFO -- : Started GET "/active_job" for 127.0.0.1 at 2018-11-15 01:10:53 +0800 I, [2018-11-15T01:10:53.891050 #551] INFO -- : Processing by DummyController#active_job as HTML I, [2018-11-15T01:10:53.899170 #551] INFO -- : Completed 500 Internal Server Error in 8ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:10:53.912983 #551] FATAL -- : AirbrakeTestError (active_job error): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:10:55.923263 #551] INFO -- : Started GET "/active_job" for 127.0.0.1 at 2018-11-15 01:10:55 +0800 I, [2018-11-15T01:10:55.924024 #551] INFO -- : Processing by DummyController#active_job as HTML I, [2018-11-15T01:10:55.924880 #551] INFO -- : Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:10:55.926621 #551] FATAL -- : AirbrakeTestError (active_job error): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:10:57.932721 #551] INFO -- : Started GET "/delayed_job" for 127.0.0.1 at 2018-11-15 01:10:57 +0800 I, [2018-11-15T01:10:57.933744 #551] INFO -- : Processing by DummyController#delayed_job as HTML I, [2018-11-15T01:10:57.955533 #551] INFO -- : Completed 500 Internal Server Error in 22ms (ActiveRecord: 0.4ms) F, [2018-11-15T01:10:57.961292 #551] FATAL -- : AirbrakeTestError (delayed_job error): lib/airbrake/delayed_job.rb:10:in `block (2 levels) in ' lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:01.976691 #551] INFO -- : Started GET "/delayed_job" for 127.0.0.1 at 2018-11-15 01:11:01 +0800 I, [2018-11-15T01:11:01.977985 #551] INFO -- : Processing by DummyController#delayed_job as HTML I, [2018-11-15T01:11:01.979693 #551] INFO -- : Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:01.981449 #551] FATAL -- : AirbrakeTestError (delayed_job error): lib/airbrake/delayed_job.rb:10:in `block (2 levels) in ' lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:03.990668 #551] INFO -- : Started GET "/active_record_after_rollback" for 127.0.0.1 at 2018-11-15 01:11:03 +0800 I, [2018-11-15T01:11:03.991371 #551] INFO -- : Processing by DummyController#active_record_after_rollback as HTML D, [2018-11-15T01:11:03.991637 #551] DEBUG -- :  (0.0ms) begin transaction D, [2018-11-15T01:11:03.995366 #551] DEBUG -- : SQL (0.2ms) INSERT INTO "books" ("title") VALUES (?) [["title", "Bango"]] D, [2018-11-15T01:11:03.996088 #551] DEBUG -- :  (0.1ms) rollback transaction I, [2018-11-15T01:11:03.996472 #551] INFO -- : Completed 500 Internal Server Error in 5ms (ActiveRecord: 0.4ms) F, [2018-11-15T01:11:04.006194 #551] FATAL -- : AirbrakeTestError (after_rollback): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:04.018847 #551] INFO -- : Started GET "/active_record_after_commit" for 127.0.0.1 at 2018-11-15 01:11:04 +0800 I, [2018-11-15T01:11:04.019609 #551] INFO -- : Processing by DummyController#active_record_after_commit as HTML D, [2018-11-15T01:11:04.020075 #551] DEBUG -- :  (0.1ms) begin transaction D, [2018-11-15T01:11:04.020525 #551] DEBUG -- : SQL (0.1ms) INSERT INTO "books" ("title") VALUES (?) [["title", "Bingo"]] D, [2018-11-15T01:11:04.020788 #551] DEBUG -- :  (0.0ms) commit transaction I, [2018-11-15T01:11:04.021165 #551] INFO -- : Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.1ms) F, [2018-11-15T01:11:04.027663 #551] FATAL -- : AirbrakeTestError (after_commit): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:04.034279 #551] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:04 +0800 I, [2018-11-15T01:11:04.035027 #551] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:11:04.035074 #551] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:04.035378 #551] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:04.041316 #551] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:04.047975 #551] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:04 +0800 I, [2018-11-15T01:11:04.048619 #551] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:11:04.048656 #551] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:04.048903 #551] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:04.053481 #551] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:04.059484 #551] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:04 +0800 I, [2018-11-15T01:11:04.060057 #551] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:11:04.060096 #551] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:04.060311 #551] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:04.066137 #551] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:04.072564 #551] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:04 +0800 I, [2018-11-15T01:11:04.073146 #551] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:11:04.073187 #551] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:04.073432 #551] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:04.079018 #551] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:04.086530 #551] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:04 +0800 I, [2018-11-15T01:11:04.087076 #551] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:11:04.087118 #551] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:04.087348 #551] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:04.091998 #551] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:04.197381 #551] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:04 +0800 I, [2018-11-15T01:11:04.198370 #551] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:11:04.198426 #551] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:04.198780 #551] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:04.209703 #551] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:04.212085 #551] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:04 +0800 I, [2018-11-15T01:11:04.213388 #551] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-15T01:11:04.213455 #551] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:04.222982 #551] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:11:04.223251 #551] INFO -- : Completed 200 OK in 10ms (Views: 0.9ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:04.225378 #551] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:04 +0800 I, [2018-11-15T01:11:04.225874 #551] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-15T01:11:04.225910 #551] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:04.235851 #551] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:11:04.236180 #551] INFO -- : Completed 200 OK in 10ms (Views: 1.0ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:04.238572 #551] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:04 +0800 I, [2018-11-15T01:11:04.239310 #551] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-15T01:11:04.239365 #551] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:04.249751 #551] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:11:04.250008 #551] INFO -- : Completed 200 OK in 11ms (Views: 0.8ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:04.252088 #551] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:04 +0800 I, [2018-11-15T01:11:04.252773 #551] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-15T01:11:04.252822 #551] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:04.264483 #551] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:11:04.264783 #551] INFO -- : Completed 200 OK in 12ms (Views: 1.0ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:04.267070 #551] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:04 +0800 I, [2018-11-15T01:11:04.267754 #551] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-15T01:11:04.267793 #551] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:04.279086 #551] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.3ms) I, [2018-11-15T01:11:04.279415 #551] INFO -- : Completed 200 OK in 12ms (Views: 1.1ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:04.281547 #551] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:04 +0800 I, [2018-11-15T01:11:04.282165 #551] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-15T01:11:04.282213 #551] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:04.292270 #551] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:11:04.292556 #551] INFO -- : Completed 200 OK in 10ms (Views: 1.0ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:04.295001 #551] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:04 +0800 I, [2018-11-15T01:11:04.295723 #551] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-15T01:11:04.295768 #551] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:04.300641 #551] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:11:04.305613 #551] INFO -- : Completed 200 OK in 10ms (Views: 5.5ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:04.308023 #551] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:04 +0800 I, [2018-11-15T01:11:04.308796 #551] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-15T01:11:04.308867 #551] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:04.318834 #551] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:11:04.319127 #551] INFO -- : Completed 200 OK in 10ms (Views: 5.6ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:04.321158 #551] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:04 +0800 I, [2018-11-15T01:11:04.321767 #551] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-15T01:11:04.321817 #551] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:04.327544 #551] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:11:04.334518 #551] INFO -- : Completed 200 OK in 13ms (Views: 7.5ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:04.336756 #551] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:04 +0800 I, [2018-11-15T01:11:04.337401 #551] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-15T01:11:04.337455 #551] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:04.343065 #551] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:11:04.348086 #551] INFO -- : Completed 200 OK in 11ms (Views: 5.7ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:04.349989 #551] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:04 +0800 I, [2018-11-15T01:11:04.350551 #551] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-15T01:11:04.350589 #551] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:04.355755 #551] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:11:04.361288 #551] INFO -- : Completed 200 OK in 11ms (Views: 6.1ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:04.363319 #551] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:04 +0800 I, [2018-11-15T01:11:04.363928 #551] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-15T01:11:04.363966 #551] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:04.368711 #551] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:11:04.374466 #551] INFO -- : Completed 200 OK in 10ms (Views: 6.3ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:04.377639 #551] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:11:04 +0800 I, [2018-11-15T01:11:04.378599 #551] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:11:04.378977 #551] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:04.384099 #551] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:04.390339 #551] INFO -- : Started GET "/resque" for 127.0.0.1 at 2018-11-15 01:11:04 +0800 I, [2018-11-15T01:11:04.390920 #551] INFO -- : Processing by DummyController#resque as HTML I, [2018-11-15T01:11:04.393033 #551] INFO -- : Rendered dummy/resque.html.erb within layouts/application (0.3ms) I, [2018-11-15T01:11:04.393430 #551] INFO -- : Completed 200 OK in 2ms (Views: 1.1ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:04.395185 #551] INFO -- : Started GET "/resque" for 127.0.0.1 at 2018-11-15 01:11:04 +0800 I, [2018-11-15T01:11:04.395796 #551] INFO -- : Processing by DummyController#resque as HTML I, [2018-11-15T01:11:04.408161 #551] INFO -- : Rendered dummy/resque.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:11:04.408633 #551] INFO -- : Completed 200 OK in 13ms (Views: 1.1ms | ActiveRecord: 0.0ms) D, [2018-11-15T01:11:37.805530 #570] DEBUG -- :  (0.5ms) CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar)  D, [2018-11-15T01:11:37.808329 #570] 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, [2018-11-15T01:11:37.808575 #570] DEBUG -- :  (0.1ms) select sqlite_version(*) D, [2018-11-15T01:11:37.808992 #570] DEBUG -- :  (0.1ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at") I, [2018-11-15T01:11:37.845425 #570] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:11:37 +0800 I, [2018-11-15T01:11:37.847388 #570] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:11:37.847713 #570] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:37.861984 #570] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:37.869324 #570] INFO -- : Started GET "/resque" for 127.0.0.1 at 2018-11-15 01:11:37 +0800 I, [2018-11-15T01:11:37.870113 #570] INFO -- : Processing by DummyController#resque as HTML I, [2018-11-15T01:11:37.876558 #570] INFO -- : Rendered dummy/resque.html.erb within layouts/application (0.8ms) I, [2018-11-15T01:11:37.876884 #570] INFO -- : Completed 200 OK in 7ms (Views: 5.5ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:37.878514 #570] INFO -- : Started GET "/resque" for 127.0.0.1 at 2018-11-15 01:11:37 +0800 I, [2018-11-15T01:11:37.878971 #570] INFO -- : Processing by DummyController#resque as HTML I, [2018-11-15T01:11:37.894981 #570] INFO -- : Rendered dummy/resque.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:11:37.895341 #570] INFO -- : Completed 200 OK in 16ms (Views: 1.0ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:37.897580 #570] INFO -- : Started GET "/active_job" for 127.0.0.1 at 2018-11-15 01:11:37 +0800 I, [2018-11-15T01:11:37.898193 #570] INFO -- : Processing by DummyController#active_job as HTML I, [2018-11-15T01:11:37.908902 #570] INFO -- : Completed 500 Internal Server Error in 11ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:37.920982 #570] FATAL -- : AirbrakeTestError (active_job error): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:39.932250 #570] INFO -- : Started GET "/active_job" for 127.0.0.1 at 2018-11-15 01:11:39 +0800 I, [2018-11-15T01:11:39.932866 #570] INFO -- : Processing by DummyController#active_job as HTML I, [2018-11-15T01:11:39.939113 #570] INFO -- : Completed 500 Internal Server Error in 6ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:39.951907 #570] FATAL -- : AirbrakeTestError (active_job error): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:41.964325 #570] INFO -- : Started GET "/active_job" for 127.0.0.1 at 2018-11-15 01:11:41 +0800 I, [2018-11-15T01:11:41.965110 #570] INFO -- : Processing by DummyController#active_job as HTML I, [2018-11-15T01:11:41.965973 #570] INFO -- : Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:41.967581 #570] FATAL -- : AirbrakeTestError (active_job error): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:43.970644 #570] INFO -- : Started GET "/active_record_after_rollback" for 127.0.0.1 at 2018-11-15 01:11:43 +0800 I, [2018-11-15T01:11:43.971760 #570] INFO -- : Processing by DummyController#active_record_after_rollback as HTML D, [2018-11-15T01:11:43.972131 #570] DEBUG -- :  (0.1ms) begin transaction D, [2018-11-15T01:11:43.979023 #570] DEBUG -- : SQL (0.1ms) INSERT INTO "books" ("title") VALUES (?) [["title", "Bango"]] D, [2018-11-15T01:11:43.979347 #570] DEBUG -- :  (0.0ms) rollback transaction I, [2018-11-15T01:11:43.979738 #570] INFO -- : Completed 500 Internal Server Error in 8ms (ActiveRecord: 0.4ms) F, [2018-11-15T01:11:43.992239 #570] FATAL -- : AirbrakeTestError (after_rollback): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:44.000621 #570] INFO -- : Started GET "/active_record_after_commit" for 127.0.0.1 at 2018-11-15 01:11:44 +0800 I, [2018-11-15T01:11:44.001279 #570] INFO -- : Processing by DummyController#active_record_after_commit as HTML D, [2018-11-15T01:11:44.001664 #570] DEBUG -- :  (0.0ms) begin transaction D, [2018-11-15T01:11:44.002089 #570] DEBUG -- : SQL (0.1ms) INSERT INTO "books" ("title") VALUES (?) [["title", "Bingo"]] D, [2018-11-15T01:11:44.002324 #570] DEBUG -- :  (0.0ms) commit transaction I, [2018-11-15T01:11:44.002629 #570] INFO -- : Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.1ms) F, [2018-11-15T01:11:44.008263 #570] FATAL -- : AirbrakeTestError (after_commit): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:44.015002 #570] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:11:44 +0800 I, [2018-11-15T01:11:44.015624 #570] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:11:44.015902 #570] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:44.020923 #570] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:46.025311 #570] INFO -- : Started GET "/delayed_job" for 127.0.0.1 at 2018-11-15 01:11:46 +0800 I, [2018-11-15T01:11:46.026138 #570] INFO -- : Processing by DummyController#delayed_job as HTML I, [2018-11-15T01:11:46.038746 #570] INFO -- : Completed 500 Internal Server Error in 13ms (ActiveRecord: 0.2ms) F, [2018-11-15T01:11:46.048253 #570] FATAL -- : AirbrakeTestError (delayed_job error): lib/airbrake/delayed_job.rb:10:in `block (2 levels) in ' lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:50.066181 #570] INFO -- : Started GET "/delayed_job" for 127.0.0.1 at 2018-11-15 01:11:50 +0800 I, [2018-11-15T01:11:50.067129 #570] INFO -- : Processing by DummyController#delayed_job as HTML I, [2018-11-15T01:11:50.068401 #570] INFO -- : Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:50.069926 #570] FATAL -- : AirbrakeTestError (delayed_job error): lib/airbrake/delayed_job.rb:10:in `block (2 levels) in ' lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:52.074649 #570] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.075689 #570] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:11:52.076126 #570] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:52.085708 #570] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:52.087913 #570] INFO -- : Started GET "/" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.088517 #570] INFO -- : Processing by DummyController#index as HTML I, [2018-11-15T01:11:52.089223 #570] INFO -- : Rendered dummy/index.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:11:52.089472 #570] INFO -- : Completed 200 OK in 1ms (Views: 0.8ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:52.091314 #570] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.092000 #570] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:11:52.092269 #570] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:52.097254 #570] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:52.103390 #570] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.104007 #570] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:11:52.104298 #570] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:52.109216 #570] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:52.223852 #570] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.224875 #570] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:11:52.225279 #570] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:52.238184 #570] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:52.239995 #570] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.240570 #570] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:11:52.240859 #570] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:52.247964 #570] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:52.254536 #570] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.255102 #570] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:11:52.255361 #570] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:52.260242 #570] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:52.267085 #570] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.267740 #570] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:11:52.268040 #570] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:52.273610 #570] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:52.279888 #570] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.280502 #570] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:11:52.280778 #570] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:52.285851 #570] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:52.291966 #570] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.292679 #570] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:11:52.292964 #570] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:52.298366 #570] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:52.305348 #570] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.306130 #570] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-15T01:11:52.306172 #570] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:52.317785 #570] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:11:52.318084 #570] INFO -- : Completed 200 OK in 12ms (Views: 1.0ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:52.320754 #570] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.321442 #570] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-15T01:11:52.321481 #570] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:52.330804 #570] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:11:52.331055 #570] INFO -- : Completed 200 OK in 10ms (Views: 0.8ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:52.332907 #570] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.333431 #570] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-15T01:11:52.333471 #570] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:52.343546 #570] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:11:52.343809 #570] INFO -- : Completed 200 OK in 10ms (Views: 0.9ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:52.346186 #570] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.346938 #570] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-15T01:11:52.346977 #570] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:52.358068 #570] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:11:52.358340 #570] INFO -- : Completed 200 OK in 11ms (Views: 0.9ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:52.360480 #570] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.361168 #570] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-15T01:11:52.361210 #570] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:52.370351 #570] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:11:52.370731 #570] INFO -- : Completed 200 OK in 9ms (Views: 0.9ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:52.373268 #570] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.373856 #570] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-15T01:11:52.373896 #570] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:52.385650 #570] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.3ms) I, [2018-11-15T01:11:52.386109 #570] INFO -- : Completed 200 OK in 12ms (Views: 1.1ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:52.388815 #570] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.389573 #570] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:11:52.389614 #570] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:52.389834 #570] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:52.395012 #570] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:52.400710 #570] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.401349 #570] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:11:52.401398 #570] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:52.401661 #570] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:52.408016 #570] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:52.415293 #570] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.415963 #570] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:11:52.416009 #570] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:52.416268 #570] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:52.422533 #570] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:52.430249 #570] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.431057 #570] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:11:52.431110 #570] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:52.431419 #570] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:52.438630 #570] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:52.446139 #570] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.446955 #570] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:11:52.447008 #570] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:52.447291 #570] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:52.460799 #570] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:52.463952 #570] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.464653 #570] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-15T01:11:52.464721 #570] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:52.465002 #570] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-15T01:11:52.471106 #570] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-15T01:11:52.478771 #570] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.479881 #570] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-15T01:11:52.479932 #570] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:52.485612 #570] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.3ms) I, [2018-11-15T01:11:52.490659 #570] INFO -- : Completed 200 OK in 11ms (Views: 5.8ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:52.493459 #570] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.494394 #570] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-15T01:11:52.494527 #570] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:52.500470 #570] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:11:52.506161 #570] INFO -- : Completed 200 OK in 11ms (Views: 6.3ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:52.508952 #570] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.510382 #570] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-15T01:11:52.510464 #570] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:52.520345 #570] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:11:52.526798 #570] INFO -- : Completed 200 OK in 16ms (Views: 7.2ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:52.530760 #570] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.532151 #570] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-15T01:11:52.532210 #570] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:52.539422 #570] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:11:52.547562 #570] INFO -- : Completed 200 OK in 15ms (Views: 8.9ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:52.549791 #570] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.550433 #570] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-15T01:11:52.550477 #570] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:52.560299 #570] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:11:52.560810 #570] INFO -- : Completed 200 OK in 10ms (Views: 5.1ms | ActiveRecord: 0.0ms) I, [2018-11-15T01:11:52.563833 #570] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-15 01:11:52 +0800 I, [2018-11-15T01:11:52.564447 #570] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-15T01:11:52.564491 #570] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-15T01:11:52.569877 #570] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2018-11-15T01:11:52.574533 #570] INFO -- : Completed 200 OK in 10ms (Views: 5.2ms | ActiveRecord: 0.0ms) D, [2018-11-16T20:35:49.951333 #20722] DEBUG -- :  (3.2ms) CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar)  D, [2018-11-16T20:35:49.955561 #20722] 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, [2018-11-16T20:35:49.956001 #20722] DEBUG -- :  (0.1ms) select sqlite_version(*) D, [2018-11-16T20:35:49.956395 #20722] DEBUG -- :  (0.1ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at") I, [2018-11-16T20:35:50.005087 #20722] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.007454 #20722] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-16T20:35:50.007825 #20722] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-16T20:35:50.022384 #20722] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-16T20:35:50.029584 #20722] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.030654 #20722] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-16T20:35:50.030718 #20722] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-16T20:35:50.046165 #20722] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (1.1ms) I, [2018-11-16T20:35:50.046619 #20722] INFO -- : Completed 200 OK in 16ms (Views: 6.3ms | ActiveRecord: 0.0ms) I, [2018-11-16T20:35:50.049297 #20722] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.050029 #20722] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-16T20:35:50.050075 #20722] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-16T20:35:50.059932 #20722] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2018-11-16T20:35:50.060217 #20722] INFO -- : Completed 200 OK in 10ms (Views: 0.8ms | ActiveRecord: 0.0ms) I, [2018-11-16T20:35:50.062217 #20722] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.062954 #20722] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-16T20:35:50.063005 #20722] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-16T20:35:50.074410 #20722] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.3ms) I, [2018-11-16T20:35:50.074986 #20722] INFO -- : Completed 200 OK in 12ms (Views: 1.4ms | ActiveRecord: 0.0ms) I, [2018-11-16T20:35:50.078157 #20722] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.079101 #20722] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-16T20:35:50.079151 #20722] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-16T20:35:50.091155 #20722] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2018-11-16T20:35:50.091404 #20722] INFO -- : Completed 200 OK in 12ms (Views: 0.8ms | ActiveRecord: 0.0ms) I, [2018-11-16T20:35:50.093380 #20722] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.093934 #20722] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-16T20:35:50.093974 #20722] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-16T20:35:50.102596 #20722] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2018-11-16T20:35:50.102858 #20722] INFO -- : Completed 200 OK in 9ms (Views: 0.9ms | ActiveRecord: 0.0ms) I, [2018-11-16T20:35:50.105618 #20722] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.106641 #20722] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2018-11-16T20:35:50.106694 #20722] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-16T20:35:50.116513 #20722] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2018-11-16T20:35:50.116811 #20722] INFO -- : Completed 200 OK in 10ms (Views: 0.9ms | ActiveRecord: 0.0ms) I, [2018-11-16T20:35:50.119071 #20722] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.119626 #20722] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-16T20:35:50.119669 #20722] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-16T20:35:50.119890 #20722] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-16T20:35:50.125546 #20722] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-16T20:35:50.131976 #20722] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.132727 #20722] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-16T20:35:50.132788 #20722] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-16T20:35:50.133068 #20722] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-16T20:35:50.138692 #20722] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-16T20:35:50.144576 #20722] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.145137 #20722] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-16T20:35:50.145183 #20722] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-16T20:35:50.145492 #20722] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-16T20:35:50.152411 #20722] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-16T20:35:50.158230 #20722] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.158805 #20722] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-16T20:35:50.158847 #20722] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-16T20:35:50.159098 #20722] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-16T20:35:50.164238 #20722] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-16T20:35:50.287222 #20722] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.288154 #20722] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-16T20:35:50.288223 #20722] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-16T20:35:50.288568 #20722] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-16T20:35:50.299335 #20722] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-16T20:35:50.301376 #20722] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.302021 #20722] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-16T20:35:50.302061 #20722] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-16T20:35:50.302298 #20722] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-16T20:35:50.307331 #20722] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-16T20:35:50.314226 #20722] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.315091 #20722] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-16T20:35:50.315142 #20722] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-16T20:35:50.321982 #20722] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.3ms) I, [2018-11-16T20:35:50.329212 #20722] INFO -- : Completed 200 OK in 14ms (Views: 8.1ms | ActiveRecord: 0.0ms) I, [2018-11-16T20:35:50.331204 #20722] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.331809 #20722] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-16T20:35:50.331849 #20722] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-16T20:35:50.337262 #20722] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2018-11-16T20:35:50.342796 #20722] INFO -- : Completed 200 OK in 11ms (Views: 6.2ms | ActiveRecord: 0.0ms) I, [2018-11-16T20:35:50.345010 #20722] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.345680 #20722] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-16T20:35:50.345720 #20722] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-16T20:35:50.350869 #20722] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2018-11-16T20:35:50.355038 #20722] INFO -- : Completed 200 OK in 9ms (Views: 4.7ms | ActiveRecord: 0.0ms) I, [2018-11-16T20:35:50.357186 #20722] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.358058 #20722] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-16T20:35:50.358117 #20722] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-16T20:35:50.363604 #20722] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2018-11-16T20:35:50.368290 #20722] INFO -- : Completed 200 OK in 10ms (Views: 5.3ms | ActiveRecord: 0.0ms) I, [2018-11-16T20:35:50.370837 #20722] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.371608 #20722] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-16T20:35:50.371661 #20722] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-16T20:35:50.377537 #20722] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2018-11-16T20:35:50.382135 #20722] INFO -- : Completed 200 OK in 10ms (Views: 5.3ms | ActiveRecord: 0.0ms) I, [2018-11-16T20:35:50.384657 #20722] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.385370 #20722] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2018-11-16T20:35:50.385416 #20722] INFO -- : Parameters: {"foo"=>"bar"} I, [2018-11-16T20:35:50.392503 #20722] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2018-11-16T20:35:50.396530 #20722] INFO -- : Completed 200 OK in 11ms (Views: 4.7ms | ActiveRecord: 0.0ms) I, [2018-11-16T20:35:50.399980 #20722] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.400538 #20722] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-16T20:35:50.400809 #20722] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-16T20:35:50.405494 #20722] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-16T20:35:50.412589 #20722] INFO -- : Started GET "/" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.413217 #20722] INFO -- : Processing by DummyController#index as HTML I, [2018-11-16T20:35:50.413954 #20722] INFO -- : Rendered dummy/index.html.erb within layouts/application (0.2ms) I, [2018-11-16T20:35:50.414196 #20722] INFO -- : Completed 200 OK in 1ms (Views: 0.8ms | ActiveRecord: 0.0ms) I, [2018-11-16T20:35:50.415750 #20722] INFO -- : Started GET "/resque" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.416201 #20722] INFO -- : Processing by DummyController#resque as HTML I, [2018-11-16T20:35:50.427872 #20722] INFO -- : Rendered dummy/resque.html.erb within layouts/application (0.2ms) I, [2018-11-16T20:35:50.428118 #20722] INFO -- : Completed 200 OK in 12ms (Views: 0.8ms | ActiveRecord: 0.0ms) I, [2018-11-16T20:35:50.429806 #20722] INFO -- : Started GET "/resque" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.430218 #20722] INFO -- : Processing by DummyController#resque as HTML I, [2018-11-16T20:35:50.431482 #20722] INFO -- : Rendered dummy/resque.html.erb within layouts/application (0.1ms) I, [2018-11-16T20:35:50.431790 #20722] INFO -- : Completed 200 OK in 2ms (Views: 0.7ms | ActiveRecord: 0.0ms) I, [2018-11-16T20:35:50.433285 #20722] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.433815 #20722] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-16T20:35:50.434049 #20722] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-16T20:35:50.439031 #20722] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-16T20:35:50.445359 #20722] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.445887 #20722] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-16T20:35:50.446117 #20722] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-16T20:35:50.451406 #20722] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-16T20:35:50.461048 #20722] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.461623 #20722] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-16T20:35:50.461882 #20722] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-16T20:35:50.467029 #20722] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-16T20:35:50.573489 #20722] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.574678 #20722] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-16T20:35:50.575085 #20722] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-16T20:35:50.586117 #20722] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-16T20:35:50.587981 #20722] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.588817 #20722] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-16T20:35:50.589196 #20722] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-16T20:35:50.594836 #20722] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-16T20:35:50.601055 #20722] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.601622 #20722] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-16T20:35:50.601894 #20722] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-16T20:35:50.606677 #20722] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-16T20:35:50.613354 #20722] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.614081 #20722] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-16T20:35:50.614443 #20722] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-16T20:35:50.619339 #20722] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-16T20:35:50.725833 #20722] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.726724 #20722] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-16T20:35:50.727172 #20722] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-16T20:35:50.740298 #20722] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-16T20:35:50.742768 #20722] INFO -- : Started GET "/active_job" for 127.0.0.1 at 2018-11-16 20:35:50 +0800 I, [2018-11-16T20:35:50.743461 #20722] INFO -- : Processing by DummyController#active_job as HTML I, [2018-11-16T20:35:50.759907 #20722] INFO -- : Completed 500 Internal Server Error in 16ms (ActiveRecord: 0.0ms) F, [2018-11-16T20:35:50.774019 #20722] FATAL -- : AirbrakeTestError (active_job error): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-16T20:35:52.784564 #20722] INFO -- : Started GET "/active_job" for 127.0.0.1 at 2018-11-16 20:35:52 +0800 I, [2018-11-16T20:35:52.785466 #20722] INFO -- : Processing by DummyController#active_job as HTML I, [2018-11-16T20:35:52.793692 #20722] INFO -- : Completed 500 Internal Server Error in 8ms (ActiveRecord: 0.0ms) F, [2018-11-16T20:35:52.808924 #20722] FATAL -- : AirbrakeTestError (active_job error): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-16T20:35:54.818169 #20722] INFO -- : Started GET "/active_job" for 127.0.0.1 at 2018-11-16 20:35:54 +0800 I, [2018-11-16T20:35:54.819008 #20722] INFO -- : Processing by DummyController#active_job as HTML I, [2018-11-16T20:35:54.820009 #20722] INFO -- : Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms) F, [2018-11-16T20:35:54.821683 #20722] FATAL -- : AirbrakeTestError (active_job error): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-16T20:35:56.830016 #20722] INFO -- : Started GET "/crash" for 127.0.0.1 at 2018-11-16 20:35:56 +0800 I, [2018-11-16T20:35:56.831008 #20722] INFO -- : Processing by DummyController#crash as HTML I, [2018-11-16T20:35:56.831367 #20722] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2018-11-16T20:35:56.836340 #20722] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-16T20:35:58.845918 #20722] INFO -- : Started GET "/delayed_job" for 127.0.0.1 at 2018-11-16 20:35:58 +0800 I, [2018-11-16T20:35:58.848557 #20722] INFO -- : Processing by DummyController#delayed_job as HTML I, [2018-11-16T20:35:58.863975 #20722] INFO -- : Completed 500 Internal Server Error in 15ms (ActiveRecord: 0.3ms) F, [2018-11-16T20:35:58.876212 #20722] FATAL -- : AirbrakeTestError (delayed_job error): lib/airbrake/delayed_job.rb:10:in `block (2 levels) in ' lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-16T20:36:02.891919 #20722] INFO -- : Started GET "/delayed_job" for 127.0.0.1 at 2018-11-16 20:36:02 +0800 I, [2018-11-16T20:36:02.892694 #20722] INFO -- : Processing by DummyController#delayed_job as HTML I, [2018-11-16T20:36:02.893705 #20722] INFO -- : Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms) F, [2018-11-16T20:36:02.895002 #20722] FATAL -- : AirbrakeTestError (delayed_job error): lib/airbrake/delayed_job.rb:10:in `block (2 levels) in ' lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-16T20:36:04.900785 #20722] INFO -- : Started GET "/active_record_after_rollback" for 127.0.0.1 at 2018-11-16 20:36:04 +0800 I, [2018-11-16T20:36:04.901605 #20722] INFO -- : Processing by DummyController#active_record_after_rollback as HTML D, [2018-11-16T20:36:04.901932 #20722] DEBUG -- :  (0.1ms) begin transaction D, [2018-11-16T20:36:04.906586 #20722] DEBUG -- : SQL (0.1ms) INSERT INTO "books" ("title") VALUES (?) [["title", "Bango"]] D, [2018-11-16T20:36:04.906920 #20722] DEBUG -- :  (0.0ms) rollback transaction I, [2018-11-16T20:36:04.907316 #20722] INFO -- : Completed 500 Internal Server Error in 6ms (ActiveRecord: 0.4ms) F, [2018-11-16T20:36:04.923539 #20722] FATAL -- : AirbrakeTestError (after_rollback): lib/airbrake/rack/middleware.rb:54:in `call' I, [2018-11-16T20:36:04.926265 #20722] INFO -- : Started GET "/active_record_after_commit" for 127.0.0.1 at 2018-11-16 20:36:04 +0800 I, [2018-11-16T20:36:04.926987 #20722] INFO -- : Processing by DummyController#active_record_after_commit as HTML D, [2018-11-16T20:36:04.927359 #20722] DEBUG -- :  (0.0ms) begin transaction D, [2018-11-16T20:36:04.927837 #20722] DEBUG -- : SQL (0.1ms) INSERT INTO "books" ("title") VALUES (?) [["title", "Bingo"]] D, [2018-11-16T20:36:04.928034 #20722] DEBUG -- :  (0.0ms) commit transaction I, [2018-11-16T20:36:04.928386 #20722] INFO -- : Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.1ms) F, [2018-11-16T20:36:04.933760 #20722] FATAL -- : AirbrakeTestError (after_commit): lib/airbrake/rack/middleware.rb:54:in `call' D, [2019-02-12T12:15:52.917796 #63362] DEBUG -- :  (0.5ms) CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar)  D, [2019-02-12T12:15:52.922082 #63362] 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, [2019-02-12T12:15:52.922456 #63362] DEBUG -- :  (0.1ms) select sqlite_version(*) D, [2019-02-12T12:15:52.923116 #63362] DEBUG -- :  (0.1ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at") I, [2019-02-12T12:15:52.960801 #63362] INFO -- : Started GET "/crash" for 127.0.0.1 at 2019-02-12 12:15:52 +0200 I, [2019-02-12T12:15:52.963569 #63362] INFO -- : Processing by DummyController#crash as HTML I, [2019-02-12T12:15:52.964037 #63362] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2019-02-12T12:15:52.980815 #63362] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:82:in `call' I, [2019-02-12T12:15:52.987843 #63362] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2019-02-12 12:15:52 +0200 I, [2019-02-12T12:15:52.988638 #63362] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2019-02-12T12:15:52.988691 #63362] INFO -- : Parameters: {"foo"=>"bar"} I, [2019-02-12T12:15:53.004976 #63362] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (1.1ms) I, [2019-02-12T12:15:53.005287 #63362] INFO -- : Completed 200 OK in 16ms (Views: 7.0ms | ActiveRecord: 0.0ms) I, [2019-02-12T12:15:53.007891 #63362] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2019-02-12 12:15:53 +0200 I, [2019-02-12T12:15:53.008553 #63362] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2019-02-12T12:15:53.008597 #63362] INFO -- : Parameters: {"foo"=>"bar"} I, [2019-02-12T12:15:53.018937 #63362] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2019-02-12T12:15:53.019268 #63362] INFO -- : Completed 200 OK in 11ms (Views: 1.0ms | ActiveRecord: 0.0ms) I, [2019-02-12T12:15:53.021721 #63362] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2019-02-12 12:15:53 +0200 I, [2019-02-12T12:15:53.022642 #63362] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2019-02-12T12:15:53.022692 #63362] INFO -- : Parameters: {"foo"=>"bar"} I, [2019-02-12T12:15:53.034913 #63362] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2019-02-12T12:15:53.035302 #63362] INFO -- : Completed 200 OK in 12ms (Views: 0.9ms | ActiveRecord: 0.0ms) I, [2019-02-12T12:15:53.037460 #63362] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2019-02-12 12:15:53 +0200 I, [2019-02-12T12:15:53.038178 #63362] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2019-02-12T12:15:53.038229 #63362] INFO -- : Parameters: {"foo"=>"bar"} I, [2019-02-12T12:15:53.047786 #63362] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2019-02-12T12:15:53.048142 #63362] INFO -- : Completed 200 OK in 10ms (Views: 1.1ms | ActiveRecord: 0.0ms) I, [2019-02-12T12:15:53.050589 #63362] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2019-02-12 12:15:53 +0200 I, [2019-02-12T12:15:53.051266 #63362] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2019-02-12T12:15:53.051305 #63362] INFO -- : Parameters: {"foo"=>"bar"} I, [2019-02-12T12:15:53.061090 #63362] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2019-02-12T12:15:53.061384 #63362] INFO -- : Completed 200 OK in 10ms (Views: 0.9ms | ActiveRecord: 0.0ms) I, [2019-02-12T12:15:53.063529 #63362] INFO -- : Started GET "/notify_airbrake_sync_helper?foo=bar" for 127.0.0.1 at 2019-02-12 12:15:53 +0200 I, [2019-02-12T12:15:53.064141 #63362] INFO -- : Processing by DummyController#notify_airbrake_sync_helper as HTML I, [2019-02-12T12:15:53.064185 #63362] INFO -- : Parameters: {"foo"=>"bar"} I, [2019-02-12T12:15:53.074063 #63362] INFO -- : Rendered dummy/notify_airbrake_sync_helper.html.erb within layouts/application (0.2ms) I, [2019-02-12T12:15:53.074342 #63362] INFO -- : Completed 200 OK in 10ms (Views: 0.9ms | ActiveRecord: 0.0ms) I, [2019-02-12T12:15:53.078046 #63362] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2019-02-12 12:15:53 +0200 I, [2019-02-12T12:15:53.078918 #63362] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2019-02-12T12:15:53.078964 #63362] INFO -- : Parameters: {"foo"=>"bar"} I, [2019-02-12T12:15:53.084666 #63362] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2019-02-12T12:15:53.089575 #63362] INFO -- : Completed 200 OK in 11ms (Views: 5.6ms | ActiveRecord: 0.0ms) I, [2019-02-12T12:15:53.091679 #63362] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2019-02-12 12:15:53 +0200 I, [2019-02-12T12:15:53.092286 #63362] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2019-02-12T12:15:53.092327 #63362] INFO -- : Parameters: {"foo"=>"bar"} I, [2019-02-12T12:15:53.097392 #63362] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2019-02-12T12:15:53.102482 #63362] INFO -- : Completed 200 OK in 10ms (Views: 5.6ms | ActiveRecord: 0.0ms) I, [2019-02-12T12:15:53.104822 #63362] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2019-02-12 12:15:53 +0200 I, [2019-02-12T12:15:53.105586 #63362] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2019-02-12T12:15:53.105640 #63362] INFO -- : Parameters: {"foo"=>"bar"} I, [2019-02-12T12:15:53.111029 #63362] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2019-02-12T12:15:53.116445 #63362] INFO -- : Completed 200 OK in 11ms (Views: 6.1ms | ActiveRecord: 0.0ms) I, [2019-02-12T12:15:53.118823 #63362] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2019-02-12 12:15:53 +0200 I, [2019-02-12T12:15:53.119589 #63362] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2019-02-12T12:15:53.119637 #63362] INFO -- : Parameters: {"foo"=>"bar"} I, [2019-02-12T12:15:53.125199 #63362] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2019-02-12T12:15:53.131569 #63362] INFO -- : Completed 200 OK in 12ms (Views: 7.0ms | ActiveRecord: 0.0ms) I, [2019-02-12T12:15:53.133897 #63362] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2019-02-12 12:15:53 +0200 I, [2019-02-12T12:15:53.134652 #63362] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2019-02-12T12:15:53.134699 #63362] INFO -- : Parameters: {"foo"=>"bar"} I, [2019-02-12T12:15:53.144312 #63362] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.3ms) I, [2019-02-12T12:15:53.144615 #63362] INFO -- : Completed 200 OK in 10ms (Views: 5.2ms | ActiveRecord: 0.0ms) I, [2019-02-12T12:15:53.147126 #63362] INFO -- : Started GET "/notify_airbrake_helper?foo=bar" for 127.0.0.1 at 2019-02-12 12:15:53 +0200 I, [2019-02-12T12:15:53.147930 #63362] INFO -- : Processing by DummyController#notify_airbrake_helper as HTML I, [2019-02-12T12:15:53.147980 #63362] INFO -- : Parameters: {"foo"=>"bar"} I, [2019-02-12T12:15:53.152982 #63362] INFO -- : Rendered dummy/notify_airbrake_helper.html.erb within layouts/application (0.2ms) I, [2019-02-12T12:15:53.157681 #63362] INFO -- : Completed 200 OK in 10ms (Views: 5.4ms | ActiveRecord: 0.0ms) I, [2019-02-12T12:15:53.159843 #63362] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2019-02-12 12:15:53 +0200 I, [2019-02-12T12:15:53.160420 #63362] INFO -- : Processing by DummyController#crash as HTML I, [2019-02-12T12:15:53.160463 #63362] INFO -- : Parameters: {"foo"=>"bar"} I, [2019-02-12T12:15:53.160752 #63362] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2019-02-12T12:15:53.166062 #63362] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:82:in `call' I, [2019-02-12T12:15:53.174173 #63362] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2019-02-12 12:15:53 +0200 I, [2019-02-12T12:15:53.174918 #63362] INFO -- : Processing by DummyController#crash as HTML I, [2019-02-12T12:15:53.174961 #63362] INFO -- : Parameters: {"foo"=>"bar"} I, [2019-02-12T12:15:53.175252 #63362] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2019-02-12T12:15:53.180641 #63362] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:82:in `call' I, [2019-02-12T12:15:53.187047 #63362] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2019-02-12 12:15:53 +0200 I, [2019-02-12T12:15:53.187723 #63362] INFO -- : Processing by DummyController#crash as HTML I, [2019-02-12T12:15:53.187766 #63362] INFO -- : Parameters: {"foo"=>"bar"} I, [2019-02-12T12:15:53.188047 #63362] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2019-02-12T12:15:53.192925 #63362] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:82:in `call' I, [2019-02-12T12:15:53.307855 #63362] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2019-02-12 12:15:53 +0200 I, [2019-02-12T12:15:53.309010 #63362] INFO -- : Processing by DummyController#crash as HTML I, [2019-02-12T12:15:53.309073 #63362] INFO -- : Parameters: {"foo"=>"bar"} I, [2019-02-12T12:15:53.309467 #63362] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2019-02-12T12:15:53.321727 #63362] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:82:in `call' I, [2019-02-12T12:15:53.323557 #63362] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2019-02-12 12:15:53 +0200 I, [2019-02-12T12:15:53.324359 #63362] INFO -- : Processing by DummyController#crash as HTML I, [2019-02-12T12:15:53.324408 #63362] INFO -- : Parameters: {"foo"=>"bar"} I, [2019-02-12T12:15:53.324725 #63362] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2019-02-12T12:15:53.330000 #63362] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:82:in `call' I, [2019-02-12T12:15:53.336262 #63362] INFO -- : Started GET "/crash?foo=bar" for 127.0.0.1 at 2019-02-12 12:15:53 +0200 I, [2019-02-12T12:15:53.336985 #63362] INFO -- : Processing by DummyController#crash as HTML I, [2019-02-12T12:15:53.337027 #63362] INFO -- : Parameters: {"foo"=>"bar"} I, [2019-02-12T12:15:53.337313 #63362] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2019-02-12T12:15:53.342225 #63362] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:82:in `call' I, [2019-02-12T12:15:53.348812 #63362] INFO -- : Started GET "/resque" for 127.0.0.1 at 2019-02-12 12:15:53 +0200 I, [2019-02-12T12:15:53.349506 #63362] INFO -- : Processing by DummyController#resque as HTML I, [2019-02-12T12:15:53.360824 #63362] INFO -- : Rendered dummy/resque.html.erb within layouts/application (0.2ms) I, [2019-02-12T12:15:53.361093 #63362] INFO -- : Completed 200 OK in 11ms (Views: 0.9ms | ActiveRecord: 0.0ms) I, [2019-02-12T12:15:53.363341 #63362] INFO -- : Started GET "/resque" for 127.0.0.1 at 2019-02-12 12:15:53 +0200 I, [2019-02-12T12:15:53.363886 #63362] INFO -- : Processing by DummyController#resque as HTML I, [2019-02-12T12:15:53.365391 #63362] INFO -- : Rendered dummy/resque.html.erb within layouts/application (0.2ms) I, [2019-02-12T12:15:53.365659 #63362] INFO -- : Completed 200 OK in 2ms (Views: 0.9ms | ActiveRecord: 0.0ms) I, [2019-02-12T12:15:53.367472 #63362] INFO -- : Started GET "/active_job" for 127.0.0.1 at 2019-02-12 12:15:53 +0200 I, [2019-02-12T12:15:53.368044 #63362] INFO -- : Processing by DummyController#active_job as HTML I, [2019-02-12T12:15:53.381584 #63362] INFO -- : Completed 500 Internal Server Error in 13ms (ActiveRecord: 0.0ms) F, [2019-02-12T12:15:53.393756 #63362] FATAL -- : AirbrakeTestError (active_job error): lib/airbrake/rack/middleware.rb:82:in `call' I, [2019-02-12T12:15:55.409387 #63362] INFO -- : Started GET "/active_job" for 127.0.0.1 at 2019-02-12 12:15:55 +0200 I, [2019-02-12T12:15:55.410264 #63362] INFO -- : Processing by DummyController#active_job as HTML I, [2019-02-12T12:15:55.418310 #63362] INFO -- : Completed 500 Internal Server Error in 8ms (ActiveRecord: 0.0ms) F, [2019-02-12T12:15:55.430501 #63362] FATAL -- : AirbrakeTestError (active_job error): lib/airbrake/rack/middleware.rb:82:in `call' I, [2019-02-12T12:15:57.435320 #63362] INFO -- : Started GET "/active_job" for 127.0.0.1 at 2019-02-12 12:15:57 +0200 I, [2019-02-12T12:15:57.436151 #63362] INFO -- : Processing by DummyController#active_job as HTML I, [2019-02-12T12:15:57.437091 #63362] INFO -- : Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms) F, [2019-02-12T12:15:57.438817 #63362] FATAL -- : AirbrakeTestError (active_job error): lib/airbrake/rack/middleware.rb:82:in `call' I, [2019-02-12T12:15:59.446829 #63362] INFO -- : Started GET "/active_record_after_commit" for 127.0.0.1 at 2019-02-12 12:15:59 +0200 I, [2019-02-12T12:15:59.447959 #63362] INFO -- : Processing by DummyController#active_record_after_commit as HTML D, [2019-02-12T12:15:59.457388 #63362] DEBUG -- :  (0.1ms) begin transaction D, [2019-02-12T12:15:59.461322 #63362] DEBUG -- : SQL (0.1ms) INSERT INTO "books" ("title") VALUES (?) [["title", "Bingo"]] D, [2019-02-12T12:15:59.461817 #63362] DEBUG -- :  (0.0ms) commit transaction I, [2019-02-12T12:15:59.462363 #63362] INFO -- : Completed 500 Internal Server Error in 14ms (ActiveRecord: 0.6ms) F, [2019-02-12T12:15:59.468794 #63362] FATAL -- : AirbrakeTestError (after_commit): lib/airbrake/rack/middleware.rb:82:in `call' I, [2019-02-12T12:15:59.474835 #63362] INFO -- : Started GET "/active_record_after_rollback" for 127.0.0.1 at 2019-02-12 12:15:59 +0200 I, [2019-02-12T12:15:59.475524 #63362] INFO -- : Processing by DummyController#active_record_after_rollback as HTML D, [2019-02-12T12:15:59.475753 #63362] DEBUG -- :  (0.0ms) begin transaction D, [2019-02-12T12:15:59.476435 #63362] DEBUG -- : SQL (0.1ms) INSERT INTO "books" ("title") VALUES (?) [["title", "Bango"]] D, [2019-02-12T12:15:59.476729 #63362] DEBUG -- :  (0.0ms) rollback transaction I, [2019-02-12T12:15:59.477122 #63362] INFO -- : Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.1ms) F, [2019-02-12T12:15:59.485827 #63362] FATAL -- : AirbrakeTestError (after_rollback): lib/airbrake/rack/middleware.rb:82:in `call' I, [2019-02-12T12:15:59.499119 #63362] INFO -- : Started GET "/" for 127.0.0.1 at 2019-02-12 12:15:59 +0200 I, [2019-02-12T12:15:59.499797 #63362] INFO -- : Processing by DummyController#index as HTML I, [2019-02-12T12:15:59.500549 #63362] INFO -- : Rendered dummy/index.html.erb within layouts/application (0.2ms) I, [2019-02-12T12:15:59.500787 #63362] INFO -- : Completed 200 OK in 1ms (Views: 0.8ms | ActiveRecord: 0.0ms) I, [2019-02-12T12:15:59.502163 #63362] INFO -- : Started GET "/crash" for 127.0.0.1 at 2019-02-12 12:15:59 +0200 I, [2019-02-12T12:15:59.502540 #63362] INFO -- : Processing by DummyController#crash as HTML I, [2019-02-12T12:15:59.502833 #63362] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2019-02-12T12:15:59.508270 #63362] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:82:in `call' I, [2019-02-12T12:15:59.522849 #63362] INFO -- : Started GET "/crash" for 127.0.0.1 at 2019-02-12 12:15:59 +0200 I, [2019-02-12T12:15:59.524061 #63362] INFO -- : Processing by DummyController#crash as HTML I, [2019-02-12T12:15:59.524395 #63362] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2019-02-12T12:15:59.535185 #63362] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:82:in `call' I, [2019-02-12T12:16:01.553791 #63362] INFO -- : Started HEAD "/crash" for 127.0.0.1 at 2019-02-12 12:16:01 +0200 I, [2019-02-12T12:16:01.558589 #63362] INFO -- : Processing by DummyController#crash as HTML I, [2019-02-12T12:16:01.559186 #63362] INFO -- : Completed 0 in 0ms (ActiveRecord: 0.0ms) F, [2019-02-12T12:16:01.564710 #63362] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:82:in `call' I, [2019-02-12T12:16:03.570654 #63362] INFO -- : Started GET "/crash" for 127.0.0.1 at 2019-02-12 12:16:03 +0200 I, [2019-02-12T12:16:03.571542 #63362] INFO -- : Processing by DummyController#crash as HTML I, [2019-02-12T12:16:03.571988 #63362] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2019-02-12T12:16:03.578044 #63362] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:82:in `call' I, [2019-02-12T12:16:03.586133 #63362] INFO -- : Started GET "/crash" for 127.0.0.1 at 2019-02-12 12:16:03 +0200 I, [2019-02-12T12:16:03.586950 #63362] INFO -- : Processing by DummyController#crash as HTML I, [2019-02-12T12:16:03.587295 #63362] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2019-02-12T12:16:03.594118 #63362] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:82:in `call' I, [2019-02-12T12:16:03.603880 #63362] INFO -- : Started GET "/crash" for 127.0.0.1 at 2019-02-12 12:16:03 +0200 I, [2019-02-12T12:16:03.604511 #63362] INFO -- : Processing by DummyController#crash as HTML I, [2019-02-12T12:16:03.604982 #63362] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2019-02-12T12:16:03.610862 #63362] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:82:in `call' I, [2019-02-12T12:16:03.617091 #63362] INFO -- : Started GET "/crash" for 127.0.0.1 at 2019-02-12 12:16:03 +0200 I, [2019-02-12T12:16:03.617784 #63362] INFO -- : Processing by DummyController#crash as HTML I, [2019-02-12T12:16:03.618156 #63362] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2019-02-12T12:16:03.624034 #63362] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:82:in `call' I, [2019-02-12T12:16:03.629754 #63362] INFO -- : Started GET "/crash" for 127.0.0.1 at 2019-02-12 12:16:03 +0200 I, [2019-02-12T12:16:03.630204 #63362] INFO -- : Processing by DummyController#crash as HTML I, [2019-02-12T12:16:03.630491 #63362] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2019-02-12T12:16:03.635549 #63362] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:82:in `call' I, [2019-02-12T12:16:03.643677 #63362] INFO -- : Started GET "/crash" for 127.0.0.1 at 2019-02-12 12:16:03 +0200 I, [2019-02-12T12:16:03.644281 #63362] INFO -- : Processing by DummyController#crash as HTML I, [2019-02-12T12:16:03.644622 #63362] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2019-02-12T12:16:03.649634 #63362] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:82:in `call' I, [2019-02-12T12:16:03.754409 #63362] INFO -- : Started GET "/crash" for 127.0.0.1 at 2019-02-12 12:16:03 +0200 I, [2019-02-12T12:16:03.755421 #63362] INFO -- : Processing by DummyController#crash as HTML I, [2019-02-12T12:16:03.755873 #63362] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2019-02-12T12:16:03.766982 #63362] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:82:in `call' I, [2019-02-12T12:16:03.768763 #63362] INFO -- : Started GET "/crash" for 127.0.0.1 at 2019-02-12 12:16:03 +0200 I, [2019-02-12T12:16:03.769348 #63362] INFO -- : Processing by DummyController#crash as HTML I, [2019-02-12T12:16:03.769650 #63362] INFO -- : Completed 500 Internal Server Error in 0ms (ActiveRecord: 0.0ms) F, [2019-02-12T12:16:03.775101 #63362] FATAL -- : AirbrakeTestError (AirbrakeTestError): lib/airbrake/rack/middleware.rb:82:in `call' I, [2019-02-12T12:16:03.783046 #63362] INFO -- : Started GET "/delayed_job" for 127.0.0.1 at 2019-02-12 12:16:03 +0200 I, [2019-02-12T12:16:03.783703 #63362] INFO -- : Processing by DummyController#delayed_job as HTML I, [2019-02-12T12:16:03.794357 #63362] INFO -- : Completed 500 Internal Server Error in 10ms (ActiveRecord: 0.1ms) F, [2019-02-12T12:16:03.803721 #63362] FATAL -- : AirbrakeTestError (delayed_job error): lib/airbrake/delayed_job.rb:10:in `block (2 levels) in ' lib/airbrake/rack/middleware.rb:82:in `call' I, [2019-02-12T12:16:07.817413 #63362] INFO -- : Started GET "/delayed_job" for 127.0.0.1 at 2019-02-12 12:16:07 +0200 I, [2019-02-12T12:16:07.818486 #63362] INFO -- : Processing by DummyController#delayed_job as HTML I, [2019-02-12T12:16:07.819754 #63362] INFO -- : Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms) F, [2019-02-12T12:16:07.821420 #63362] FATAL -- : AirbrakeTestError (delayed_job error): lib/airbrake/delayed_job.rb:10:in `block (2 levels) in ' lib/airbrake/rack/middleware.rb:82:in `call' D, [2019-02-12T12:17:32.854359 #63382] DEBUG -- :  (0.7ms) CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar)  D, [2019-02-12T12:17:32.857873 #63382] 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, [2019-02-12T12:17:32.858249 #63382] DEBUG -- :  (0.1ms) select sqlite_version(*) D, [2019-02-12T12:17:32.858834 #63382] DEBUG -- :  (0.1ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at") D, [2019-02-12T12:27:50.994489 #63540] DEBUG -- :  (0.5ms) CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar)  D, [2019-02-12T12:27:51.004741 #63540] 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, [2019-02-12T12:27:51.005160 #63540] DEBUG -- :  (0.1ms) select sqlite_version(*) D, [2019-02-12T12:27:51.006415 #63540] DEBUG -- :  (0.1ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at") D, [2019-02-12T12:56:43.697924 #65091] DEBUG -- :  (7.2ms) CREATE TABLE "books" ("id" integer PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar)  D, [2019-02-12T12:56:43.718731 #65091] DEBUG -- :  (1.9ms) SELECT name FROM sqlite_master WHERE type = 'table' AND name = "delayed_jobs" D, [2019-02-12T12:56:43.724222 #65091] DEBUG -- :  (0.4ms) 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, [2019-02-12T12:56:43.729394 #65091] DEBUG -- :  (1.4ms) SELECT sqlite_version(*) D, [2019-02-12T12:56:43.733195 #65091] DEBUG -- :  (0.5ms) SELECT name FROM sqlite_master WHERE type = 'table' AND name = "delayed_jobs" D, [2019-02-12T12:56:43.740007 #65091] DEBUG -- :  (1.3ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at") D, [2019-02-12T13:00:45.777424 #65261] DEBUG -- :  (5.3ms) CREATE TABLE "books" ("id" integer PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar)  D, [2019-02-12T13:00:45.796140 #65261] DEBUG -- :  (1.9ms) SELECT name FROM sqlite_master WHERE type = 'table' AND name = "delayed_jobs" D, [2019-02-12T13:00:45.801466 #65261] DEBUG -- :  (0.4ms) 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, [2019-02-12T13:00:45.805627 #65261] DEBUG -- :  (1.2ms) SELECT sqlite_version(*) D, [2019-02-12T13:00:45.807932 #65261] DEBUG -- :  (0.3ms) SELECT name FROM sqlite_master WHERE type = 'table' AND name = "delayed_jobs" D, [2019-02-12T13:00:45.812706 #65261] DEBUG -- :  (0.8ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at") D, [2019-02-12T13:05:40.286797 #65339] DEBUG -- :  (5.3ms) CREATE TABLE "books" ("id" integer PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar)  D, [2019-02-12T13:05:40.303893 #65339] DEBUG -- :  (1.8ms) SELECT name FROM sqlite_master WHERE type = 'table' AND name = "delayed_jobs" D, [2019-02-12T13:05:40.309054 #65339] DEBUG -- :  (0.4ms) 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, [2019-02-12T13:05:40.313761 #65339] DEBUG -- :  (1.7ms) SELECT sqlite_version(*) D, [2019-02-12T13:05:40.316277 #65339] DEBUG -- :  (0.3ms) SELECT name FROM sqlite_master WHERE type = 'table' AND name = "delayed_jobs" D, [2019-02-12T13:05:40.321181 #65339] DEBUG -- :  (0.7ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at") D, [2019-02-12T13:06:28.637155 #65390] DEBUG -- :  (5.6ms) CREATE TABLE "books" ("id" integer PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar)  D, [2019-02-12T13:06:28.654448 #65390] DEBUG -- :  (1.6ms) SELECT name FROM sqlite_master WHERE type = 'table' AND name = "delayed_jobs" D, [2019-02-12T13:06:28.658863 #65390] DEBUG -- :  (0.4ms) 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, [2019-02-12T13:06:28.663580 #65390] DEBUG -- :  (1.3ms) SELECT sqlite_version(*) D, [2019-02-12T13:06:28.666134 #65390] DEBUG -- :  (0.3ms) SELECT name FROM sqlite_master WHERE type = 'table' AND name = "delayed_jobs" D, [2019-02-12T13:06:28.671859 #65390] DEBUG -- :  (0.9ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at") D, [2019-02-12T13:08:13.536231 #65504] DEBUG -- :  (5.2ms) CREATE TABLE "books" ("id" integer PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar)  D, [2019-02-12T13:08:13.552144 #65504] DEBUG -- :  (1.4ms) SELECT name FROM sqlite_master WHERE type = 'table' AND name = "delayed_jobs" D, [2019-02-12T13:08:13.556227 #65504] DEBUG -- :  (0.4ms) 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, [2019-02-12T13:08:13.561030 #65504] DEBUG -- :  (1.2ms) SELECT sqlite_version(*) D, [2019-02-12T13:08:13.563438 #65504] DEBUG -- :  (0.3ms) SELECT name FROM sqlite_master WHERE type = 'table' AND name = "delayed_jobs" D, [2019-02-12T13:08:13.568211 #65504] DEBUG -- :  (0.4ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at") D, [2019-02-12T13:08:36.794034 #65553] DEBUG -- :  (5.7ms) CREATE TABLE "books" ("id" integer PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar)  D, [2019-02-12T13:08:36.811989 #65553] DEBUG -- :  (1.8ms) SELECT name FROM sqlite_master WHERE type = 'table' AND name = "delayed_jobs" D, [2019-02-12T13:08:36.817097 #65553] DEBUG -- :  (0.4ms) 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, [2019-02-12T13:08:36.821546 #65553] DEBUG -- :  (1.3ms) SELECT sqlite_version(*) D, [2019-02-12T13:08:36.824921 #65553] DEBUG -- :  (0.4ms) SELECT name FROM sqlite_master WHERE type = 'table' AND name = "delayed_jobs" D, [2019-02-12T13:08:36.830938 #65553] DEBUG -- :  (0.7ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")