(806.1ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (1.1ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (0.2ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (6.9ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (1.8ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (3.2ms) CREATE TABLE "schema_migrations" ("version" character varying NOT NULL PRIMARY KEY)  (0.5ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC  (0.7ms) INSERT INTO "schema_migrations" (version) VALUES (20180606205114)  (2.6ms) CREATE TABLE "ar_internal_metadata" ("key" character varying NOT NULL PRIMARY KEY, "value" character varying, "created_at" timestamp NOT NULL, "updated_at" timestamp NOT NULL) ActiveRecord::InternalMetadata Load (0.5ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.3ms) BEGIN ActiveRecord::InternalMetadata Create (0.4ms) INSERT INTO "ar_internal_metadata" ("key", "value", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "key" [["key", "environment"], ["value", "test"], ["created_at", "2019-07-10 17:49:37.159358"], ["updated_at", "2019-07-10 17:49:37.159358"]]  (5.2ms) COMMIT ActiveRecord::InternalMetadata Load (0.8ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.2ms) BEGIN  (0.1ms) COMMIT  (0.8ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC  (0.4ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.2ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (8.0ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (4.5ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (1.6ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.6ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.4ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (3.1ms) BEGIN  (0.4ms) COMMIT  (0.3ms) BEGIN  Load (0.6ms) SELECT "delayed_jobs".* FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 LIMIT $2 [["id", 1], ["LIMIT", 1]]  (0.4ms) ROLLBACK  (1.0ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.4ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (5.3ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (64.2ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (1.5ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (1.3ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.5ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.2ms) BEGIN  (0.2ms) COMMIT  (0.3ms) BEGIN  (0.2ms) SAVEPOINT active_record_1 # Create (1.0ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 17:50:54.877813"], ["created_at", "2019-07-10 17:50:54.877878"], ["updated_at", "2019-07-10 17:50:54.877878"]] Delayed::Backend::ActiveRecord::Job Create (0.5ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"2564544c-92e8-406a-9a6e-0a36cb0fec07\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T17:50:55.002Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"1\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 17:50:55.016767"], ["created_at", "2019-07-10 17:50:55.016814"], ["updated_at", "2019-07-10 17:50:55.016814"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 # Destroy (0.3ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 1]] Delayed::Backend::ActiveRecord::Job Create (0.4ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"e935381a-7e1e-41f7-9140-0d2ca9741a48\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T17:50:55.021Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"1\",\"database_operation\":\"delete\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 17:50:55.024375"], ["created_at", "2019-07-10 17:50:55.024402"], ["updated_at", "2019-07-10 17:50:55.024402"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK  (0.9ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.2ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (4.0ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (6.3ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (1.7ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.6ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.3ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.4ms) BEGIN  (0.2ms) COMMIT  (0.6ms) BEGIN  (0.3ms) SAVEPOINT active_record_1 SQL (0.8ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 17:52:44.250467"], ["created_at", "2019-07-10 17:52:44.250556"], ["updated_at", "2019-07-10 17:52:44.250556"]] SQL (0.4ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"8356072a-4cdb-42d8-aca6-40fb34786ddf\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T17:52:44.357Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"1\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 17:52:44.368830"], ["created_at", "2019-07-10 17:52:44.368867"], ["updated_at", "2019-07-10 17:52:44.368867"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1  (0.8ms) ROLLBACK TO SAVEPOINT active_record_1  (0.4ms) ROLLBACK  (0.8ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.9ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (7.5ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (6.7ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (3.5ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (1.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.5ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.3ms) BEGIN  (0.1ms) COMMIT  (0.3ms) BEGIN  (0.6ms) ROLLBACK  (0.4ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (2.6ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.4ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (1.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.8ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.4ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.6ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (1.5ms) ROLLBACK  (0.3ms) BEGIN  (0.2ms) ROLLBACK  (0.3ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.3ms) BEGIN  (0.2ms) ROLLBACK  (1.4ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (4.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.5ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.4ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.3ms) BEGIN  (0.5ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.8ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.6ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') Delayed::Backend::ActiveRecord::Job Load (4.4ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:09:50.174212', locked_by = 'host:Sam-Moore-MBP13 pid:94471' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:09:50.173637' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:09:50.173648') OR locked_by = 'host:Sam-Moore-MBP13 pid:94471') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.4ms) BEGIN  (0.4ms) SAVEPOINT active_record_1 SQL (0.7ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-07-10T19:09:50.182Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-07-10 19:09:50.198839"], ["created_at", "2019-07-10 19:09:50.198889"], ["updated_at", "2019-07-10 19:09:50.198889"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.6ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:09:50.201584', locked_by = 'host:Sam-Moore-MBP13 pid:94471' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:09:50.201325' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:09:50.201334') OR locked_by = 'host:Sam-Moore-MBP13 pid:94471') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 1]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.6ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:09:50.204953', locked_by = 'host:Sam-Moore-MBP13 pid:94471' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:09:50.204654' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:09:50.204666') OR locked_by = 'host:Sam-Moore-MBP13 pid:94471') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.5ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 20]]  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-07-10T19:09:50.217Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-07-10 19:09:50.219508"], ["created_at", "2019-07-10 19:09:50.219548"], ["updated_at", "2019-07-10 19:09:50.219548"]]  (0.3ms) RELEASE SAVEPOINT active_record_1  (0.7ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 20]] Delayed::Backend::ActiveRecord::Job Load (1.4ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:09:50.222934', locked_by = 'host:Sam-Moore-MBP13 pid:94471' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:09:50.222529' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:09:50.222540') OR locked_by = 'host:Sam-Moore-MBP13 pid:94471') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.6ms) SAVEPOINT active_record_1 SQL (0.3ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 2]]  (0.2ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.6ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:09:50.229748', locked_by = 'host:Sam-Moore-MBP13 pid:94471' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:09:50.229484' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:09:50.229498') OR locked_by = 'host:Sam-Moore-MBP13 pid:94471') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 0]]  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-07-10T19:09:50.233Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-07-10 19:09:50.234825"], ["created_at", "2019-07-10 19:09:50.234864"], ["updated_at", "2019-07-10 19:09:50.234864"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.3ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 0]] Delayed::Backend::ActiveRecord::Job Load (0.5ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:09:50.236757', locked_by = 'host:Sam-Moore-MBP13 pid:94471' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:09:50.236542' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:09:50.236550') OR locked_by = 'host:Sam-Moore-MBP13 pid:94471') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 3]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.5ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:09:50.239127', locked_by = 'host:Sam-Moore-MBP13 pid:94471' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:09:50.238916' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:09:50.238925') OR locked_by = 'host:Sam-Moore-MBP13 pid:94471') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') Delayed::Backend::ActiveRecord::Job Load (0.5ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:09:50.243788', locked_by = 'host:Sam-Moore-MBP13 pid:94471' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:09:50.243525' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:09:50.243538') OR locked_by = 'host:Sam-Moore-MBP13 pid:94471') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.3ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:09:50.267016"], ["created_at", "2019-07-10 19:09:50.267068"], ["updated_at", "2019-07-10 19:09:50.267068"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 SQL (0.3ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 4]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:09:50.287348"], ["created_at", "2019-07-10 19:09:50.287387"], ["updated_at", "2019-07-10 19:09:50.287387"]] SQL (1.0ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"b79357d0-0bb9-43f3-8a0c-be4de93547c7\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:09:50.288Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"5\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:09:50.291839"], ["created_at", "2019-07-10 19:09:50.291892"], ["updated_at", "2019-07-10 19:09:50.291892"]]  (0.8ms) RELEASE SAVEPOINT active_record_1  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:09:50.324615"], ["created_at", "2019-07-10 19:09:50.324677"], ["updated_at", "2019-07-10 19:09:50.324677"]] SQL (6.0ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"5b305d28-b151-483e-bc1c-8abc45e66c21\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:09:50.326Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"7\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:09:50.346507"], ["created_at", "2019-07-10 19:09:50.346547"], ["updated_at", "2019-07-10 19:09:50.346547"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1  (0.1ms) ROLLBACK TO SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:09:50.368185"], ["created_at", "2019-07-10 19:09:50.368225"], ["updated_at", "2019-07-10 19:09:50.368225"]] SQL (0.3ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"0fd21302-e354-4f3a-9473-4a5346244510\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:09:50.369Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"9\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:09:50.371054"], ["created_at", "2019-07-10 19:09:50.371080"], ["updated_at", "2019-07-10 19:09:50.371080"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "delayed_jobs" SET "handler" = '' WHERE "delayed_jobs"."id" = $1 [["id", 9]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:09:50.388971"], ["created_at", "2019-07-10 19:09:50.389010"], ["updated_at", "2019-07-10 19:09:50.389010"]] SQL (1.7ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"3256527e-e9ac-45de-bec4-71a8573d7015\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:09:50.390Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"11\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:09:50.392151"], ["created_at", "2019-07-10 19:09:50.392189"], ["updated_at", "2019-07-10 19:09:50.392189"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:09:50.407758"], ["created_at", "2019-07-10 19:09:50.407802"], ["updated_at", "2019-07-10 19:09:50.407802"]] SQL (0.5ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"ab5af778-b40a-4c2b-b607-b0aaaa10f743\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:09:50.409Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"13\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:09:50.411401"], ["created_at", "2019-07-10 19:09:50.411432"], ["updated_at", "2019-07-10 19:09:50.411432"]]  (0.3ms) RELEASE SAVEPOINT active_record_1  (0.3ms) ROLLBACK  (0.1ms) BEGIN SQL (0.3ms) DELETE FROM "delayed_jobs"  (0.1ms) ROLLBACK  (0.2ms) BEGIN SQL (0.3ms) DELETE FROM "delayed_jobs"  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.5ms) ROLLBACK  (0.1ms) BEGIN SQL (0.3ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 1]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN SQL (0.3ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 1]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN SQL (0.4ms) UPDATE "delayed_jobs" SET "handler" = ''  (0.2ms) ROLLBACK  (0.2ms) BEGIN SQL (0.3ms) UPDATE "delayed_jobs" SET "locked_at" = NULL  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.5ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.5ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (9.7ms) BEGIN  (1.4ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.5ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.3ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (9.0ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (5.0ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (1.2ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.5ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.4ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.2ms) BEGIN  (0.1ms) COMMIT  (0.3ms) BEGIN  (0.7ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.7ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') Delayed::Backend::ActiveRecord::Job Load (0.9ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:15:23.958485', locked_by = 'host:Sam-Moore-MBP13 pid:94859' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:15:23.957915' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:15:23.957928') OR locked_by = 'host:Sam-Moore-MBP13 pid:94859') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SAVEPOINT active_record_1 SQL (0.5ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-07-10T19:15:23.961Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-07-10 19:15:23.983135"], ["created_at", "2019-07-10 19:15:23.983179"], ["updated_at", "2019-07-10 19:15:23.983179"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.5ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:15:23.988989', locked_by = 'host:Sam-Moore-MBP13 pid:94859' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:15:23.987957' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:15:23.987967') OR locked_by = 'host:Sam-Moore-MBP13 pid:94859') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 1]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.5ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:15:23.993119', locked_by = 'host:Sam-Moore-MBP13 pid:94859' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:15:23.992333' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:15:23.992896') OR locked_by = 'host:Sam-Moore-MBP13 pid:94859') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (1.1ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 20]]  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-07-10T19:15:24.002Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-07-10 19:15:24.007720"], ["created_at", "2019-07-10 19:15:24.007750"], ["updated_at", "2019-07-10 19:15:24.007750"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.3ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 20]] Delayed::Backend::ActiveRecord::Job Load (0.6ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:15:24.010570', locked_by = 'host:Sam-Moore-MBP13 pid:94859' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:15:24.010359' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:15:24.010368') OR locked_by = 'host:Sam-Moore-MBP13 pid:94859') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.5ms) SAVEPOINT active_record_1 SQL (0.4ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 2]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.6ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:15:24.017795', locked_by = 'host:Sam-Moore-MBP13 pid:94859' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:15:24.016311' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:15:24.016324') OR locked_by = 'host:Sam-Moore-MBP13 pid:94859') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 0]]  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-07-10T19:15:24.021Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-07-10 19:15:24.025412"], ["created_at", "2019-07-10 19:15:24.025444"], ["updated_at", "2019-07-10 19:15:24.025444"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.3ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 0]] Delayed::Backend::ActiveRecord::Job Load (1.2ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:15:24.028193', locked_by = 'host:Sam-Moore-MBP13 pid:94859' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:15:24.027959' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:15:24.027969') OR locked_by = 'host:Sam-Moore-MBP13 pid:94859') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 3]]  (0.2ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.5ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:15:24.032206', locked_by = 'host:Sam-Moore-MBP13 pid:94859' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:15:24.031985' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:15:24.031995') OR locked_by = 'host:Sam-Moore-MBP13 pid:94859') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.5ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') Delayed::Backend::ActiveRecord::Job Load (0.6ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:15:24.040376', locked_by = 'host:Sam-Moore-MBP13 pid:94859' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:15:24.040117' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:15:24.040130') OR locked_by = 'host:Sam-Moore-MBP13 pid:94859') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.7ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.6ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (4.4ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.3ms) BEGIN  (0.4ms) ROLLBACK  (0.3ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:15:24.289044"], ["created_at", "2019-07-10 19:15:24.289087"], ["updated_at", "2019-07-10 19:15:24.289087"]] SQL (0.3ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"33d62680-77e5-4832-9df6-d85e0946a390\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:15:24.291Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"4\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:15:24.293276"], ["created_at", "2019-07-10 19:15:24.293305"], ["updated_at", "2019-07-10 19:15:24.293305"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1  (0.7ms) ROLLBACK TO SAVEPOINT active_record_1  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SAVEPOINT active_record_1 SQL (0.8ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:15:24.311503"], ["created_at", "2019-07-10 19:15:24.311557"], ["updated_at", "2019-07-10 19:15:24.311557"]] SQL (0.4ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"36782d4b-e481-4ff0-bc98-90803dff3111\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:15:24.315Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"6\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:15:24.316853"], ["created_at", "2019-07-10 19:15:24.316881"], ["updated_at", "2019-07-10 19:15:24.316881"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:15:24.330993"], ["created_at", "2019-07-10 19:15:24.331026"], ["updated_at", "2019-07-10 19:15:24.331026"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 SQL (0.3ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 8]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:15:24.369701"], ["created_at", "2019-07-10 19:15:24.369741"], ["updated_at", "2019-07-10 19:15:24.369741"]] SQL (0.3ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"526f4ab4-0bbd-40ac-bd77-193a2ce2900e\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:15:24.370Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"9\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:15:24.372501"], ["created_at", "2019-07-10 19:15:24.372526"], ["updated_at", "2019-07-10 19:15:24.372526"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:15:24.385520"], ["created_at", "2019-07-10 19:15:24.385570"], ["updated_at", "2019-07-10 19:15:24.385570"]] SQL (0.3ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"f39c8ce5-e66b-4d4c-b4e3-556f0841c206\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:15:24.386Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"11\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:15:24.389075"], ["created_at", "2019-07-10 19:15:24.389105"], ["updated_at", "2019-07-10 19:15:24.389105"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.4ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:15:24.405535"], ["created_at", "2019-07-10 19:15:24.405597"], ["updated_at", "2019-07-10 19:15:24.405597"]] SQL (0.4ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"20522fff-a6af-472a-a3b6-130ddfecfe56\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:15:24.407Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"13\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:15:24.408596"], ["created_at", "2019-07-10 19:15:24.408621"], ["updated_at", "2019-07-10 19:15:24.408621"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "delayed_jobs" SET "handler" = '' WHERE "delayed_jobs"."id" = $1 [["id", 13]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN SQL (0.4ms) UPDATE "delayed_jobs" SET "handler" = ''  (0.2ms) ROLLBACK  (0.2ms) BEGIN SQL (0.5ms) UPDATE "delayed_jobs" SET "locked_at" = NULL  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN SQL (0.3ms) DELETE FROM "delayed_jobs"  (0.3ms) ROLLBACK  (0.1ms) BEGIN SQL (0.3ms) DELETE FROM "delayed_jobs"  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN SQL (0.4ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 1]]  (0.2ms) ROLLBACK  (0.2ms) BEGIN SQL (0.4ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 1]]  (0.2ms) ROLLBACK  (0.3ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.8ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.4ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.5ms) BEGIN  (0.8ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.3ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.4ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.3ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.5ms) ROLLBACK  (0.4ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.3ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (4.2ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (8.1ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (3.2ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.8ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.4ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.2ms) BEGIN  (0.1ms) COMMIT  (0.3ms) BEGIN  (0.4ms) ROLLBACK  (0.1ms) BEGIN  (0.5ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SAVEPOINT active_record_1 SQL (0.8ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:17:35.494430"], ["created_at", "2019-07-10 19:17:35.494474"], ["updated_at", "2019-07-10 19:17:35.494474"]] SQL (0.4ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"2ce5a2b4-b36e-419e-9771-f3f812bcbf6c\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:17:35.563Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"1\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:17:35.565684"], ["created_at", "2019-07-10 19:17:35.565717"], ["updated_at", "2019-07-10 19:17:35.565717"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:17:35.580718"], ["created_at", "2019-07-10 19:17:35.580755"], ["updated_at", "2019-07-10 19:17:35.580755"]] SQL (0.3ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"98e3093f-4b9d-41e6-92ab-3187e64d4bed\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:17:35.582Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"3\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:17:35.583613"], ["created_at", "2019-07-10 19:17:35.583644"], ["updated_at", "2019-07-10 19:17:35.583644"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1  (0.1ms) ROLLBACK TO SAVEPOINT active_record_1  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SAVEPOINT active_record_1 SQL (0.6ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:17:35.601992"], ["created_at", "2019-07-10 19:17:35.602065"], ["updated_at", "2019-07-10 19:17:35.602065"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 SQL (0.6ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 5]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:17:35.623309"], ["created_at", "2019-07-10 19:17:35.623346"], ["updated_at", "2019-07-10 19:17:35.623346"]] SQL (0.3ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"1425d3a2-58f2-4abb-9f8f-be699b6c53ad\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:17:35.624Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"6\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:17:35.626349"], ["created_at", "2019-07-10 19:17:35.626383"], ["updated_at", "2019-07-10 19:17:35.626383"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:17:35.643179"], ["created_at", "2019-07-10 19:17:35.643220"], ["updated_at", "2019-07-10 19:17:35.643220"]] SQL (0.3ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"dde6ea3b-cb52-43d4-bf69-5f8f5a32de1b\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:17:35.644Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"8\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:17:35.646998"], ["created_at", "2019-07-10 19:17:35.647070"], ["updated_at", "2019-07-10 19:17:35.647070"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.4ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:17:35.663656"], ["created_at", "2019-07-10 19:17:35.663708"], ["updated_at", "2019-07-10 19:17:35.663708"]] SQL (0.4ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"5d10b878-9b4f-4c25-858b-f14b0ea927b4\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:17:35.665Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"10\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:17:35.666971"], ["created_at", "2019-07-10 19:17:35.667002"], ["updated_at", "2019-07-10 19:17:35.667002"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "delayed_jobs" SET "handler" = '' WHERE "delayed_jobs"."id" = $1 [["id", 10]]  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.6ms) ROLLBACK  (0.4ms) BEGIN SQL (0.3ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 1]]  (0.2ms) ROLLBACK  (0.3ms) BEGIN SQL (0.3ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 1]]  (0.2ms) ROLLBACK  (0.2ms) BEGIN SQL (0.2ms) DELETE FROM "delayed_jobs"  (0.2ms) ROLLBACK  (0.1ms) BEGIN SQL (0.4ms) DELETE FROM "delayed_jobs"  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN SQL (1.0ms) UPDATE "delayed_jobs" SET "handler" = ''  (0.3ms) ROLLBACK  (0.2ms) BEGIN SQL (0.4ms) UPDATE "delayed_jobs" SET "locked_at" = NULL  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.4ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.5ms) ROLLBACK  (0.3ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.3ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.6ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.6ms) BEGIN  (0.4ms) ROLLBACK  (0.1ms) BEGIN  (0.4ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') Delayed::Backend::ActiveRecord::Job Load (1.1ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:17:35.861638', locked_by = 'host:Sam-Moore-MBP13 pid:95384' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:17:35.860939' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:17:35.860952') OR locked_by = 'host:Sam-Moore-MBP13 pid:95384') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.4ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 20]]  (0.2ms) SAVEPOINT active_record_1 SQL (1.0ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-07-10T19:17:35.868Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-07-10 19:17:35.871612"], ["created_at", "2019-07-10 19:17:35.871648"], ["updated_at", "2019-07-10 19:17:35.871648"]]  (0.4ms) RELEASE SAVEPOINT active_record_1  (0.4ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 20]] Delayed::Backend::ActiveRecord::Job Load (0.8ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:17:35.876072', locked_by = 'host:Sam-Moore-MBP13 pid:95384' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:17:35.875605' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:17:35.875621') OR locked_by = 'host:Sam-Moore-MBP13 pid:95384') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 12]]  (0.2ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.7ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:17:35.880257', locked_by = 'host:Sam-Moore-MBP13 pid:95384' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:17:35.879920' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:17:35.879934') OR locked_by = 'host:Sam-Moore-MBP13 pid:95384') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-07-10T19:17:35.883Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-07-10 19:17:35.884693"], ["created_at", "2019-07-10 19:17:35.884719"], ["updated_at", "2019-07-10 19:17:35.884719"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.5ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:17:35.886149', locked_by = 'host:Sam-Moore-MBP13 pid:95384' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:17:35.885850' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:17:35.885856') OR locked_by = 'host:Sam-Moore-MBP13 pid:95384') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 13]]  (0.5ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (5.8ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:17:35.889895', locked_by = 'host:Sam-Moore-MBP13 pid:95384' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:17:35.889434' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:17:35.889456') OR locked_by = 'host:Sam-Moore-MBP13 pid:95384') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.4ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 0]]  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-07-10T19:17:35.899Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-07-10 19:17:35.901521"], ["created_at", "2019-07-10 19:17:35.901549"], ["updated_at", "2019-07-10 19:17:35.901549"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.4ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 0]] Delayed::Backend::ActiveRecord::Job Load (0.5ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:17:35.903715', locked_by = 'host:Sam-Moore-MBP13 pid:95384' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:17:35.903496' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:17:35.903505') OR locked_by = 'host:Sam-Moore-MBP13 pid:95384') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) SAVEPOINT active_record_1 SQL (1.2ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 14]]  (0.4ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (1.0ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:17:35.908181', locked_by = 'host:Sam-Moore-MBP13 pid:95384' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:17:35.907763' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:17:35.907788') OR locked_by = 'host:Sam-Moore-MBP13 pid:95384') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.5ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') Delayed::Backend::ActiveRecord::Job Load (0.5ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:17:35.914294', locked_by = 'host:Sam-Moore-MBP13 pid:95384' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:17:35.913936' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:17:35.913948') OR locked_by = 'host:Sam-Moore-MBP13 pid:95384') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.5ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.4ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.2ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (6.3ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (5.5ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (8.1ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.5ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.3ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.2ms) BEGIN  (0.2ms) COMMIT  (0.2ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.7ms) ROLLBACK  (0.2ms) BEGIN  (0.3ms) ROLLBACK  (0.3ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.5ms) ROLLBACK  (0.2ms) BEGIN SQL (0.6ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 1]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN SQL (0.4ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 1]]  (0.2ms) ROLLBACK  (1.4ms) BEGIN SQL (0.3ms) DELETE FROM "delayed_jobs"  (0.2ms) ROLLBACK  (0.2ms) BEGIN SQL (0.3ms) DELETE FROM "delayed_jobs"  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN SQL (0.4ms) UPDATE "delayed_jobs" SET "handler" = ''  (0.2ms) ROLLBACK  (0.2ms) BEGIN SQL (0.5ms) UPDATE "delayed_jobs" SET "locked_at" = NULL  (0.2ms) ROLLBACK  (0.3ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) SAVEPOINT active_record_1 SQL (0.6ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:22:15.774265"], ["created_at", "2019-07-10 19:22:15.774317"], ["updated_at", "2019-07-10 19:22:15.774317"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 SQL (0.4ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 1]]  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:22:15.791803"], ["created_at", "2019-07-10 19:22:15.791844"], ["updated_at", "2019-07-10 19:22:15.791844"]] SQL (0.5ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"77ae21cf-2b05-490b-87b8-d55101f2ac14\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:22:15.849Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"2\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:22:15.852540"], ["created_at", "2019-07-10 19:22:15.852590"], ["updated_at", "2019-07-10 19:22:15.852590"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:22:15.866497"], ["created_at", "2019-07-10 19:22:15.866534"], ["updated_at", "2019-07-10 19:22:15.866534"]] SQL (0.3ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"4a869add-580e-405a-baad-39fa903b9d49\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:22:15.867Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"4\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:22:15.870452"], ["created_at", "2019-07-10 19:22:15.870501"], ["updated_at", "2019-07-10 19:22:15.870501"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 SQL (0.4ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 4]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (2.0ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:22:15.892265"], ["created_at", "2019-07-10 19:22:15.892409"], ["updated_at", "2019-07-10 19:22:15.892409"]] SQL (0.3ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"70cc8c64-822e-4765-87ef-910666730301\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:22:15.894Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"6\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:22:15.896250"], ["created_at", "2019-07-10 19:22:15.896280"], ["updated_at", "2019-07-10 19:22:15.896280"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "delayed_jobs" SET "handler" = '' WHERE "delayed_jobs"."id" = $1 [["id", 6]]  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.3ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:22:15.913246"], ["created_at", "2019-07-10 19:22:15.913283"], ["updated_at", "2019-07-10 19:22:15.913283"]] SQL (0.3ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"94ef764a-433f-4064-9f11-1c688fa5ec64\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:22:15.914Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"8\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:22:15.916010"], ["created_at", "2019-07-10 19:22:15.916034"], ["updated_at", "2019-07-10 19:22:15.916034"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.3ms) BEGIN  (0.3ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:22:15.933482"], ["created_at", "2019-07-10 19:22:15.933521"], ["updated_at", "2019-07-10 19:22:15.933521"]] SQL (0.7ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"5ded2d36-5167-4989-89b6-2d6afacd7389\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:22:15.934Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"10\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:22:15.937481"], ["created_at", "2019-07-10 19:22:15.937547"], ["updated_at", "2019-07-10 19:22:15.937547"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "delayed_jobs" SET "locked_at" = NULL WHERE "delayed_jobs"."id" = $1 [["id", 10]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-07-10T19:22:15.942Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-07-10 19:22:15.944876"], ["created_at", "2019-07-10 19:22:15.944908"], ["updated_at", "2019-07-10 19:22:15.944908"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.9ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:22:15.947914', locked_by = 'host:Sam-Moore-MBP13 pid:95946' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:22:15.947295' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:22:15.947305') OR locked_by = 'host:Sam-Moore-MBP13 pid:95946') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 12]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.4ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:22:15.951336', locked_by = 'host:Sam-Moore-MBP13 pid:95946' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:22:15.950994' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:22:15.951003') OR locked_by = 'host:Sam-Moore-MBP13 pid:95946') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.4ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 20]]  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-07-10T19:22:15.958Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-07-10 19:22:15.960546"], ["created_at", "2019-07-10 19:22:15.960576"], ["updated_at", "2019-07-10 19:22:15.960576"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 20]] Delayed::Backend::ActiveRecord::Job Load (0.5ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:22:15.962503', locked_by = 'host:Sam-Moore-MBP13 pid:95946' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:22:15.962291' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:22:15.962299') OR locked_by = 'host:Sam-Moore-MBP13 pid:95946') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 13]]  (0.2ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.4ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:22:15.964974', locked_by = 'host:Sam-Moore-MBP13 pid:95946' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:22:15.964769' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:22:15.964779') OR locked_by = 'host:Sam-Moore-MBP13 pid:95946') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.4ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 0]]  (0.2ms) SAVEPOINT active_record_1 SQL (1.4ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-07-10T19:22:15.968Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-07-10 19:22:15.970170"], ["created_at", "2019-07-10 19:22:15.970220"], ["updated_at", "2019-07-10 19:22:15.970220"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.4ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 0]] Delayed::Backend::ActiveRecord::Job Load (0.9ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:22:15.975185', locked_by = 'host:Sam-Moore-MBP13 pid:95946' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:22:15.974832' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:22:15.974845') OR locked_by = 'host:Sam-Moore-MBP13 pid:95946') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 14]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.4ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:22:15.978500', locked_by = 'host:Sam-Moore-MBP13 pid:95946' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:22:15.978293' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:22:15.978303') OR locked_by = 'host:Sam-Moore-MBP13 pid:95946') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') Delayed::Backend::ActiveRecord::Job Load (0.5ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:22:15.982896', locked_by = 'host:Sam-Moore-MBP13 pid:95946' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:22:15.982684' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:22:15.982693') OR locked_by = 'host:Sam-Moore-MBP13 pid:95946') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') Delayed::Backend::ActiveRecord::Job Load (1.1ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:22:15.988239', locked_by = 'host:Sam-Moore-MBP13 pid:95946' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:22:15.987570' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:22:15.987607') OR locked_by = 'host:Sam-Moore-MBP13 pid:95946') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.9ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.8ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.4ms) BEGIN  (0.3ms) ROLLBACK  (0.4ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.5ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (1.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.5ms) ROLLBACK  (0.4ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.4ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.4ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.3ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.4ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.2ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (3.0ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (6.5ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (1.6ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (5.5ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.6ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.3ms) BEGIN  (0.3ms) COMMIT  (0.3ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.3ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.3ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.5ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') Delayed::Backend::ActiveRecord::Job Load (0.8ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:23:14.218262', locked_by = 'host:Sam-Moore-MBP13 pid:96208' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:23:14.217651' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:23:14.217674') OR locked_by = 'host:Sam-Moore-MBP13 pid:96208') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.3ms) SAVEPOINT active_record_1 SQL (0.5ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-07-10T19:23:14.221Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-07-10 19:23:14.255603"], ["created_at", "2019-07-10 19:23:14.255639"], ["updated_at", "2019-07-10 19:23:14.255639"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.6ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:23:14.257855', locked_by = 'host:Sam-Moore-MBP13 pid:96208' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:23:14.257606' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:23:14.257614') OR locked_by = 'host:Sam-Moore-MBP13 pid:96208') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 1]]  (0.2ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.5ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:23:14.261913', locked_by = 'host:Sam-Moore-MBP13 pid:96208' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:23:14.261683' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:23:14.261694') OR locked_by = 'host:Sam-Moore-MBP13 pid:96208') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.4ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 20]]  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-07-10T19:23:14.268Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-07-10 19:23:14.272450"], ["created_at", "2019-07-10 19:23:14.272480"], ["updated_at", "2019-07-10 19:23:14.272480"]]  (0.3ms) RELEASE SAVEPOINT active_record_1  (0.3ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 20]] Delayed::Backend::ActiveRecord::Job Load (0.6ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:23:14.276912', locked_by = 'host:Sam-Moore-MBP13 pid:96208' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:23:14.275873' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:23:14.275884') OR locked_by = 'host:Sam-Moore-MBP13 pid:96208') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) SAVEPOINT active_record_1 SQL (0.6ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 2]]  (0.3ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.6ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:23:14.282097', locked_by = 'host:Sam-Moore-MBP13 pid:96208' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:23:14.281832' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:23:14.281845') OR locked_by = 'host:Sam-Moore-MBP13 pid:96208') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 0]]  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-07-10T19:23:14.287Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-07-10 19:23:14.292154"], ["created_at", "2019-07-10 19:23:14.292186"], ["updated_at", "2019-07-10 19:23:14.292186"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.3ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 0]] Delayed::Backend::ActiveRecord::Job Load (0.8ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:23:14.297440', locked_by = 'host:Sam-Moore-MBP13 pid:96208' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:23:14.295395' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:23:14.297004') OR locked_by = 'host:Sam-Moore-MBP13 pid:96208') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 3]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.8ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:23:14.302441', locked_by = 'host:Sam-Moore-MBP13 pid:96208' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:23:14.301318' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:23:14.301328') OR locked_by = 'host:Sam-Moore-MBP13 pid:96208') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') Delayed::Backend::ActiveRecord::Job Load (0.6ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:23:14.311250', locked_by = 'host:Sam-Moore-MBP13 pid:96208' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:23:14.310978' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:23:14.310988') OR locked_by = 'host:Sam-Moore-MBP13 pid:96208') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (6.4ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.5ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.4ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.4ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.3ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.3ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (1.4ms) BEGIN  (0.2ms) ROLLBACK  (0.3ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.5ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.6ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (1.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.9ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (1.3ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (9.0ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (10.9ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (1.6ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.6ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.4ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.2ms) BEGIN  (0.1ms) COMMIT  (0.2ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.4ms) ROLLBACK  (0.7ms) BEGIN  (2.4ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN # Update All (1.5ms) UPDATE "delayed_jobs" SET "handler" = ''  (0.5ms) ROLLBACK  (0.2ms) BEGIN # Update All (0.5ms) UPDATE "delayed_jobs" SET "locked_at" = NULL  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN # Destroy (0.8ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 1]]  (0.3ms) ROLLBACK  (0.3ms) BEGIN # Destroy (1.2ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 1]]  (0.6ms) ROLLBACK  (0.2ms) BEGIN # Destroy (0.3ms) DELETE FROM "delayed_jobs"  (0.1ms) ROLLBACK  (0.1ms) BEGIN # Destroy (0.3ms) DELETE FROM "delayed_jobs"  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.3ms) SAVEPOINT active_record_1 # Create (3.0ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:24:43.507041"], ["created_at", "2019-07-10 19:24:43.507083"], ["updated_at", "2019-07-10 19:24:43.507083"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 # Destroy (0.3ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 1]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) SAVEPOINT active_record_1 # Create (0.5ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:24:43.529704"], ["created_at", "2019-07-10 19:24:43.529766"], ["updated_at", "2019-07-10 19:24:43.529766"]] Delayed::Backend::ActiveRecord::Job Create (0.4ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"e0dcc1a1-b349-49e0-97b8-c1d9a5e8e639\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:24:43.533Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"2\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:24:43.537082"], ["created_at", "2019-07-10 19:24:43.537114"], ["updated_at", "2019-07-10 19:24:43.537114"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) SAVEPOINT active_record_1 # Create (0.6ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:24:43.555371"], ["created_at", "2019-07-10 19:24:43.555407"], ["updated_at", "2019-07-10 19:24:43.555407"]] Delayed::Backend::ActiveRecord::Job Create (0.5ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"1ca5f90c-5e07-449a-bcdb-c98c44737770\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:24:43.558Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"4\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:24:43.562671"], ["created_at", "2019-07-10 19:24:43.562709"], ["updated_at", "2019-07-10 19:24:43.562709"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 # Destroy (0.3ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 4]]  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.3ms) SAVEPOINT active_record_1 # Create (0.6ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:24:43.588156"], ["created_at", "2019-07-10 19:24:43.588211"], ["updated_at", "2019-07-10 19:24:43.588211"]] Delayed::Backend::ActiveRecord::Job Create (0.5ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"45d09c04-50f6-4bff-afd5-47252288cca2\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:24:43.591Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"6\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:24:43.595581"], ["created_at", "2019-07-10 19:24:43.595618"], ["updated_at", "2019-07-10 19:24:43.595618"]]  (0.3ms) RELEASE SAVEPOINT active_record_1 # Update (0.4ms) UPDATE "delayed_jobs" SET "locked_at" = $1 WHERE "delayed_jobs"."id" = $2 [["locked_at", nil], ["id", 6]]  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) SAVEPOINT active_record_1 # Create (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:24:43.612650"], ["created_at", "2019-07-10 19:24:43.612697"], ["updated_at", "2019-07-10 19:24:43.612697"]] Delayed::Backend::ActiveRecord::Job Create (0.4ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"be5fc01a-348f-47db-8ff5-8cd523a5e234\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:24:43.615Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"8\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:24:43.617525"], ["created_at", "2019-07-10 19:24:43.617576"], ["updated_at", "2019-07-10 19:24:43.617576"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) SAVEPOINT active_record_1 # Create (0.4ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:24:43.634695"], ["created_at", "2019-07-10 19:24:43.634752"], ["updated_at", "2019-07-10 19:24:43.634752"]] Delayed::Backend::ActiveRecord::Job Create (0.3ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"49364f9b-59b1-49dd-814f-87320562582a\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:24:43.637Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"10\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:24:43.638931"], ["created_at", "2019-07-10 19:24:43.638956"], ["updated_at", "2019-07-10 19:24:43.638956"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 # Update (0.9ms) UPDATE "delayed_jobs" SET "handler" = $1 WHERE "delayed_jobs"."id" = $2 [["handler", ""], ["id", 10]]  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.4ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') Delayed::Backend::ActiveRecord::Job Load (0.7ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:24:43.649718', locked_by = 'host:Sam-Moore-MBP13 pid:96769' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:24:43.649301' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:24:43.649310') OR locked_by = 'host:Sam-Moore-MBP13 pid:96769') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Create (0.3ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-07-10T19:24:43.652Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-07-10 19:24:43.654247"], ["created_at", "2019-07-10 19:24:43.654274"], ["updated_at", "2019-07-10 19:24:43.654274"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.6ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:24:43.655629', locked_by = 'host:Sam-Moore-MBP13 pid:96769' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:24:43.655400' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:24:43.655406') OR locked_by = 'host:Sam-Moore-MBP13 pid:96769') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Destroy (0.2ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 12]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.4ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:24:43.658456', locked_by = 'host:Sam-Moore-MBP13 pid:96769' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:24:43.658229' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:24:43.658237') OR locked_by = 'host:Sam-Moore-MBP13 pid:96769') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.4ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 20]]  (0.2ms) SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Create (0.5ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-07-10T19:24:43.663Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-07-10 19:24:43.664904"], ["created_at", "2019-07-10 19:24:43.664932"], ["updated_at", "2019-07-10 19:24:43.664932"]]  (0.3ms) RELEASE SAVEPOINT active_record_1  (0.3ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 20]] Delayed::Backend::ActiveRecord::Job Load (0.5ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:24:43.667827', locked_by = 'host:Sam-Moore-MBP13 pid:96769' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:24:43.667548' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:24:43.667557') OR locked_by = 'host:Sam-Moore-MBP13 pid:96769') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.1ms) SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Destroy (0.2ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 13]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.4ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:24:43.670241', locked_by = 'host:Sam-Moore-MBP13 pid:96769' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:24:43.670023' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:24:43.670032') OR locked_by = 'host:Sam-Moore-MBP13 pid:96769') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 0]]  (0.2ms) SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Create (0.6ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-07-10T19:24:43.673Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-07-10 19:24:43.675203"], ["created_at", "2019-07-10 19:24:43.675230"], ["updated_at", "2019-07-10 19:24:43.675230"]]  (0.3ms) RELEASE SAVEPOINT active_record_1  (0.5ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 0]] Delayed::Backend::ActiveRecord::Job Load (0.6ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:24:43.679122', locked_by = 'host:Sam-Moore-MBP13 pid:96769' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:24:43.678814' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:24:43.678828') OR locked_by = 'host:Sam-Moore-MBP13 pid:96769') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.1ms) SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Destroy (0.2ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 14]]  (0.2ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.7ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:24:43.681929', locked_by = 'host:Sam-Moore-MBP13 pid:96769' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:24:43.681635' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:24:43.681658') OR locked_by = 'host:Sam-Moore-MBP13 pid:96769') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') Delayed::Backend::ActiveRecord::Job Load (0.4ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:24:43.686784', locked_by = 'host:Sam-Moore-MBP13 pid:96769' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:24:43.686543' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:24:43.686552') OR locked_by = 'host:Sam-Moore-MBP13 pid:96769') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.3ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.5ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.3ms) BEGIN  (0.5ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.4ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.5ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.5ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (1.1ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.7ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.2ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (3.4ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (3.8ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (1.1ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.7ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.4ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.2ms) BEGIN  (0.2ms) COMMIT  (0.4ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.3ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.5ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.3ms) BEGIN  (0.2ms) ROLLBACK  (0.3ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.8ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.9ms) ROLLBACK  (0.3ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.3ms) BEGIN  (3.7ms) ROLLBACK  (0.2ms) BEGIN  (1.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.7ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.6ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.3ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.5ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') Delayed::Backend::ActiveRecord::Job Load (0.8ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:27:37.086907', locked_by = 'host:Sam-Moore-MBP13 pid:98556' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:27:37.086189' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:27:37.086202') OR locked_by = 'host:Sam-Moore-MBP13 pid:98556') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') Delayed::Backend::ActiveRecord::Job Load (0.4ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:27:37.092599', locked_by = 'host:Sam-Moore-MBP13 pid:98556' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:27:37.092395' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:27:37.092404') OR locked_by = 'host:Sam-Moore-MBP13 pid:98556') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.7ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 20]]  (0.3ms) SAVEPOINT active_record_1 SQL (1.5ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-07-10T19:27:37.104Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-07-10 19:27:37.119525"], ["created_at", "2019-07-10 19:27:37.119582"], ["updated_at", "2019-07-10 19:27:37.119582"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.4ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 20]] Delayed::Backend::ActiveRecord::Job Load (0.6ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:27:37.124153', locked_by = 'host:Sam-Moore-MBP13 pid:98556' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:27:37.123828' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:27:37.123840') OR locked_by = 'host:Sam-Moore-MBP13 pid:98556') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 1]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.5ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:27:37.128055', locked_by = 'host:Sam-Moore-MBP13 pid:98556' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:27:37.127735' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:27:37.127748') OR locked_by = 'host:Sam-Moore-MBP13 pid:98556') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-07-10T19:27:37.131Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-07-10 19:27:37.134790"], ["created_at", "2019-07-10 19:27:37.134824"], ["updated_at", "2019-07-10 19:27:37.134824"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.6ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:27:37.136746', locked_by = 'host:Sam-Moore-MBP13 pid:98556' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:27:37.136468' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:27:37.136477') OR locked_by = 'host:Sam-Moore-MBP13 pid:98556') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 2]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.4ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:27:37.139365', locked_by = 'host:Sam-Moore-MBP13 pid:98556' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:27:37.139167' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:27:37.139177') OR locked_by = 'host:Sam-Moore-MBP13 pid:98556') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 0]]  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-07-10T19:27:37.142Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-07-10 19:27:37.144074"], ["created_at", "2019-07-10 19:27:37.144104"], ["updated_at", "2019-07-10 19:27:37.144104"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.3ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 0]] Delayed::Backend::ActiveRecord::Job Load (0.8ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:27:37.146460', locked_by = 'host:Sam-Moore-MBP13 pid:98556' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:27:37.146214' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:27:37.146224') OR locked_by = 'host:Sam-Moore-MBP13 pid:98556') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.3ms) SAVEPOINT active_record_1 SQL (0.3ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 3]]  (0.2ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.5ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:27:37.150256', locked_by = 'host:Sam-Moore-MBP13 pid:98556' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:27:37.149927' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:27:37.149941') OR locked_by = 'host:Sam-Moore-MBP13 pid:98556') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.9ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.2ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (3.6ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (4.2ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (4.9ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.7ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.4ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.2ms) BEGIN  (0.1ms) COMMIT  (0.2ms) BEGIN  (0.5ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.3ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.6ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.9ms) ROLLBACK  (0.2ms) BEGIN  (0.3ms) ROLLBACK  (0.9ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.5ms) BEGIN # Update All (0.5ms) UPDATE "delayed_jobs" SET "locked_at" = NULL  (0.1ms) ROLLBACK  (0.1ms) BEGIN # Update All (0.3ms) UPDATE "delayed_jobs" SET "handler" = ''  (0.1ms) ROLLBACK  (0.1ms) BEGIN # Destroy (0.2ms) DELETE FROM "delayed_jobs"  (0.1ms) ROLLBACK  (0.1ms) BEGIN # Destroy (0.3ms) DELETE FROM "delayed_jobs"  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.4ms) BEGIN # Destroy (0.5ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 1]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN # Destroy (0.3ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 1]]  (0.5ms) ROLLBACK  (0.2ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) SAVEPOINT active_record_1 # Create (0.8ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:27:54.216387"], ["created_at", "2019-07-10 19:27:54.216448"], ["updated_at", "2019-07-10 19:27:54.216448"]] Delayed::Backend::ActiveRecord::Job Create (0.3ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"71c53ffa-27d8-499a-b640-711acbdeace3\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:27:54.220Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"1\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:27:54.223752"], ["created_at", "2019-07-10 19:27:54.223784"], ["updated_at", "2019-07-10 19:27:54.223784"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 # Update (0.4ms) UPDATE "delayed_jobs" SET "locked_at" = $1 WHERE "delayed_jobs"."id" = $2 [["locked_at", nil], ["id", 1]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.8ms) SAVEPOINT active_record_1 # Create (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:27:54.245352"], ["created_at", "2019-07-10 19:27:54.245490"], ["updated_at", "2019-07-10 19:27:54.245490"]] Delayed::Backend::ActiveRecord::Job Create (0.6ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"d7e4d3f6-b046-469b-8a68-991f52a4b007\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:27:54.249Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"3\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:27:54.251538"], ["created_at", "2019-07-10 19:27:54.251583"], ["updated_at", "2019-07-10 19:27:54.251583"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 # Update (0.3ms) UPDATE "delayed_jobs" SET "handler" = $1 WHERE "delayed_jobs"."id" = $2 [["handler", ""], ["id", 3]]  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (1.7ms) SAVEPOINT active_record_1 # Create (0.4ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:27:54.278316"], ["created_at", "2019-07-10 19:27:54.278443"], ["updated_at", "2019-07-10 19:27:54.278443"]] Delayed::Backend::ActiveRecord::Job Create (0.4ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"70ce0441-59c2-40fb-9870-925bdae8fee2\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:27:54.283Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"5\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:27:54.286253"], ["created_at", "2019-07-10 19:27:54.286286"], ["updated_at", "2019-07-10 19:27:54.286286"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) SAVEPOINT active_record_1 # Create (0.5ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:27:54.301750"], ["created_at", "2019-07-10 19:27:54.301788"], ["updated_at", "2019-07-10 19:27:54.301788"]] Delayed::Backend::ActiveRecord::Job Create (0.7ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"6b3ba46b-ddf9-4179-8045-b464305a2687\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:27:54.305Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"7\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:27:54.307630"], ["created_at", "2019-07-10 19:27:54.307724"], ["updated_at", "2019-07-10 19:27:54.307724"]]  (1.0ms) RELEASE SAVEPOINT active_record_1 # Destroy (0.4ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 7]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) SAVEPOINT active_record_1 # Create (0.4ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:27:54.337370"], ["created_at", "2019-07-10 19:27:54.337416"], ["updated_at", "2019-07-10 19:27:54.337416"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 # Destroy (0.4ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 9]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.8ms) SAVEPOINT active_record_1 # Create (0.4ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-07-10 19:27:54.361963"], ["created_at", "2019-07-10 19:27:54.362117"], ["updated_at", "2019-07-10 19:27:54.362117"]] Delayed::Backend::ActiveRecord::Job Create (0.3ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"05e32c2f-b10b-4e43-8d9c-a177c8ce3a9c\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-07-10T19:27:54.367Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"10\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-07-10 19:27:54.368889"], ["created_at", "2019-07-10 19:27:54.368947"], ["updated_at", "2019-07-10 19:27:54.368947"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.3ms) BEGIN  (0.2ms) ROLLBACK  (0.5ms) BEGIN  (0.5ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (1.0ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.5ms) ROLLBACK  (0.4ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.7ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.3ms) BEGIN  (2.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.3ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (1.4ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (1.1ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.4ms) ROLLBACK  (0.1ms) BEGIN  (1.5ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.4ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') Delayed::Backend::ActiveRecord::Job Load (1.0ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:27:54.622530', locked_by = 'host:Sam-Moore-MBP13 pid:99305' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:27:54.621952' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:27:54.621967') OR locked_by = 'host:Sam-Moore-MBP13 pid:99305') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Create (0.3ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-07-10T19:27:54.626Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-07-10 19:27:54.631183"], ["created_at", "2019-07-10 19:27:54.631220"], ["updated_at", "2019-07-10 19:27:54.631220"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.7ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:27:54.632991', locked_by = 'host:Sam-Moore-MBP13 pid:99305' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:27:54.632722' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:27:54.632729') OR locked_by = 'host:Sam-Moore-MBP13 pid:99305') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Destroy (0.3ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 12]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.5ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:27:54.636087', locked_by = 'host:Sam-Moore-MBP13 pid:99305' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:27:54.635832' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:27:54.635841') OR locked_by = 'host:Sam-Moore-MBP13 pid:99305') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.7ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 20]]  (0.2ms) SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Create (0.4ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-07-10T19:27:54.643Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-07-10 19:27:54.672668"], ["created_at", "2019-07-10 19:27:54.672698"], ["updated_at", "2019-07-10 19:27:54.672698"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.4ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 20]] Delayed::Backend::ActiveRecord::Job Load (0.6ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:27:54.675106', locked_by = 'host:Sam-Moore-MBP13 pid:99305' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:27:54.674843' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:27:54.674851') OR locked_by = 'host:Sam-Moore-MBP13 pid:99305') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Destroy (0.3ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 13]]  (0.2ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.5ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:27:54.678262', locked_by = 'host:Sam-Moore-MBP13 pid:99305' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:27:54.678027' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:27:54.678036') OR locked_by = 'host:Sam-Moore-MBP13 pid:99305') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 0]]  (0.2ms) SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Create (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-07-10T19:27:54.681Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-07-10 19:27:54.683987"], ["created_at", "2019-07-10 19:27:54.684018"], ["updated_at", "2019-07-10 19:27:54.684018"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.3ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 0]] Delayed::Backend::ActiveRecord::Job Load (1.9ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:27:54.686029', locked_by = 'host:Sam-Moore-MBP13 pid:99305' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:27:54.685785' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:27:54.685793') OR locked_by = 'host:Sam-Moore-MBP13 pid:99305') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.1ms) SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Destroy (0.2ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 14]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.8ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:27:54.689996', locked_by = 'host:Sam-Moore-MBP13 pid:99305' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:27:54.689733' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:27:54.689742') OR locked_by = 'host:Sam-Moore-MBP13 pid:99305') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.6ms) BEGIN  (0.5ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') Delayed::Backend::ActiveRecord::Job Load (0.5ms) UPDATE "delayed_jobs" SET locked_at = '2019-07-10 19:27:54.698264', locked_by = 'host:Sam-Moore-MBP13 pid:99305' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-07-10 19:27:54.698018' AND (locked_at IS NULL OR locked_at < '2019-07-10 15:27:54.698029') OR locked_by = 'host:Sam-Moore-MBP13 pid:99305') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (1.1ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (2.5ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (24.8ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (44.5ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (1.9ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (2.0ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (1.4ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.2ms) BEGIN  (0.2ms) COMMIT  (0.4ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.3ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (3.3ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (5.7ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (3.2ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.7ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.4ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.2ms) BEGIN  (0.2ms) COMMIT  (0.4ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.4ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (4.6ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (9.7ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (1.8ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.6ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.3ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.2ms) BEGIN  (0.1ms) COMMIT  (0.2ms) BEGIN # Update All (1.1ms) UPDATE "delayed_jobs" SET locked_at is null  (0.3ms) ROLLBACK  (0.4ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.5ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (6.9ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (5.2ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (1.6ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.5ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.4ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.9ms) BEGIN  (0.2ms) COMMIT  (0.3ms) BEGIN # Update All (0.5ms) UPDATE "delayed_jobs" SET locked_at = null  (1.2ms) ROLLBACK  (0.4ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.3ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (3.8ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (4.0ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (7.4ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.6ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.6ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.2ms) BEGIN  (0.2ms) COMMIT  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.4ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.3ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (4.1ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (4.8ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (2.2ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.7ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.4ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.6ms) BEGIN  (0.4ms) COMMIT  (0.4ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.3ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (4.9ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (5.4ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (2.9ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.6ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.4ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.5ms) BEGIN  (0.1ms) COMMIT  (0.3ms) BEGIN  (0.3ms) ROLLBACK  (0.4ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.8ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (3.3ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (4.3ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (1.5ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.7ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.4ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.2ms) BEGIN  (0.1ms) COMMIT  (0.3ms) BEGIN  (0.3ms) ROLLBACK  (0.4ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.3ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (3.2ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (4.3ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (4.0ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.6ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.4ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.2ms) BEGIN  (0.1ms) COMMIT  (0.4ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.2ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (6.5ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (4.6ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (3.2ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.7ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.4ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.2ms) BEGIN  (0.2ms) COMMIT  (0.2ms) BEGIN  (0.3ms) ROLLBACK  (0.4ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.2ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (4.9ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (6.5ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (1.6ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.6ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.7ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.2ms) BEGIN  (0.2ms) COMMIT  (0.5ms) BEGIN # Update All (0.5ms) UPDATE "delayed_jobs" SET \"locked_at\" = NULL  (0.3ms) ROLLBACK  (0.4ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.3ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (4.4ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (5.1ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (1.4ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.5ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.3ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.2ms) BEGIN  (0.2ms) COMMIT  (0.4ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.3ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (4.6ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (6.0ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (2.2ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.9ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.5ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.2ms) BEGIN  (0.1ms) COMMIT  (0.2ms) BEGIN # Update All (0.5ms) UPDATE "delayed_jobs" SET locked_at = null  (0.3ms) ROLLBACK  (0.4ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.3ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (7.4ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (5.0ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (1.9ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.5ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.4ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.2ms) BEGIN  (0.2ms) COMMIT  (0.2ms) BEGIN # Update All (0.6ms) UPDATE "delayed_jobs" SET locked_at = null  (0.3ms) ROLLBACK  (0.4ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.2ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (4.2ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (4.5ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (1.2ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.5ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.3ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.2ms) BEGIN  (0.1ms) COMMIT  (0.4ms) BEGIN # Update All (0.6ms) UPDATE "delayed_jobs" SET locked_at = null  (0.4ms) ROLLBACK  (0.4ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.5ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (7.2ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (28.0ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (1.6ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.6ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.3ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.3ms) BEGIN  (0.2ms) COMMIT  (4.6ms) BEGIN # Update All (0.5ms) UPDATE "delayed_jobs" SET last_error = 'locked_at'  (1.2ms) ROLLBACK  (0.3ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.3ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (3.5ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (4.8ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (1.4ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.7ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.4ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.3ms) BEGIN  (0.3ms) COMMIT  (0.3ms) BEGIN # Update All (0.6ms) UPDATE "delayed_jobs" SET last_error = 'locked_at'  (0.2ms) ROLLBACK  (0.4ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.4ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (5.0ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (4.2ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (1.8ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.5ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.5ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.6ms) BEGIN  (0.3ms) COMMIT  (0.2ms) BEGIN # Update All (0.6ms) UPDATE "delayed_jobs" SET last_error = 'locked_at'  (0.3ms) ROLLBACK  (3.1ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.9ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (8.1ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (18.8ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (1.3ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.6ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.4ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.2ms) BEGIN  (0.1ms) COMMIT  (0.6ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.2ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (5.2ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (7.3ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (1.3ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.5ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.4ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.2ms) BEGIN  (0.1ms) COMMIT  (0.2ms) BEGIN # Update All (0.8ms) UPDATE "delayed_jobs" SET last_error = 'locked_at'  (0.2ms) ROLLBACK  (0.6ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.2ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (4.4ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (7.1ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (1.5ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (1.0ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.4ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.2ms) BEGIN  (0.1ms) COMMIT  (0.2ms) BEGIN # Update All (0.5ms) UPDATE "delayed_jobs" SET last_error = 'locked_at'  (0.3ms) ROLLBACK  (9.7ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (2.2ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (9.6ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (16.7ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (1.4ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (1.2ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.7ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.3ms) BEGIN  (0.1ms) COMMIT  (0.3ms) BEGIN # Update All (2.6ms) UPDATE "delayed_jobs" SET last_error = 'locked_at'  (0.5ms) ROLLBACK  (0.4ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.2ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (4.5ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (5.6ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (1.4ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (0.5ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.3ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.2ms) BEGIN  (0.1ms) COMMIT  (0.3ms) BEGIN # Update All (1.1ms) UPDATE "delayed_jobs" SET last_error = 'locked_at'  (0.3ms) ROLLBACK  (1.5ms) CREATE DATABASE "journaled_test" ENCODING = 'utf8' SQL (0.6ms) CREATE EXTENSION IF NOT EXISTS "plpgsql"  (6.6ms) DROP TABLE IF EXISTS "delayed_jobs" CASCADE  (13.5ms) CREATE TABLE "delayed_jobs" ("id" bigserial primary key, "priority" integer DEFAULT 0 NOT NULL, "attempts" integer DEFAULT 0 NOT NULL, "handler" text NOT NULL, "last_error" text, "run_at" timestamp, "locked_at" timestamp, "failed_at" timestamp, "locked_by" character varying, "queue" character varying, "created_at" timestamp, "updated_at" timestamp)  (3.2ms) CREATE INDEX "delayed_jobs_priority" ON "delayed_jobs" ("priority", "run_at")  (1.4ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC ActiveRecord::InternalMetadata Load (0.5ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = $1 LIMIT $2 [["key", "environment"], ["LIMIT", 1]]  (0.5ms) BEGIN  (0.4ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.3ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.5ms) ROLLBACK  (0.3ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.3ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.5ms) ROLLBACK  (0.3ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.3ms) BEGIN  (0.8ms) ROLLBACK  (0.3ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Create (0.7ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-09-13T16:08:04.407Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-09-13 16:08:04.426521"], ["created_at", "2019-09-13 16:08:04.426575"], ["updated_at", "2019-09-13 16:08:04.426575"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (2.3ms) UPDATE "delayed_jobs" SET locked_at = '2019-09-13 16:08:04.429596', locked_by = 'host:Sam-Moore-MBP13 pid:46133' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-09-13 16:08:04.429073' AND (locked_at IS NULL OR locked_at < '2019-09-13 12:08:04.429082') OR locked_by = 'host:Sam-Moore-MBP13 pid:46133') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.3ms) SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Destroy (0.3ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 1]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.5ms) UPDATE "delayed_jobs" SET locked_at = '2019-09-13 16:08:04.434967', locked_by = 'host:Sam-Moore-MBP13 pid:46133' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-09-13 16:08:04.434616' AND (locked_at IS NULL OR locked_at < '2019-09-13 12:08:04.434629') OR locked_by = 'host:Sam-Moore-MBP13 pid:46133') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.5ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 20]]  (0.2ms) SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Create (0.2ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-09-13T16:08:04.442Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-09-13 16:08:04.443861"], ["created_at", "2019-09-13 16:08:04.443892"], ["updated_at", "2019-09-13 16:08:04.443892"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (1.0ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 20]] Delayed::Backend::ActiveRecord::Job Load (0.6ms) UPDATE "delayed_jobs" SET locked_at = '2019-09-13 16:08:04.447239', locked_by = 'host:Sam-Moore-MBP13 pid:46133' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-09-13 16:08:04.446824' AND (locked_at IS NULL OR locked_at < '2019-09-13 12:08:04.446841') OR locked_by = 'host:Sam-Moore-MBP13 pid:46133') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Destroy (0.3ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 2]]  (0.2ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.5ms) UPDATE "delayed_jobs" SET locked_at = '2019-09-13 16:08:04.450631', locked_by = 'host:Sam-Moore-MBP13 pid:46133' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-09-13 16:08:04.450321' AND (locked_at IS NULL OR locked_at < '2019-09-13 12:08:04.450333') OR locked_by = 'host:Sam-Moore-MBP13 pid:46133') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 0]]  (0.2ms) SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Create (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"FAKE_UUID\",\"event_type\":\"fake_event\",\"created_at\":\"2019-09-13T16:08:04.454Z\",\"foo\":\"bar\"}'\npartition_key: fake_partition_key\napp_name: my_app\n"], ["run_at", "2019-09-13 16:08:04.455893"], ["created_at", "2019-09-13 16:08:04.455921"], ["updated_at", "2019-09-13 16:08:04.455921"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.3ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') AND "delayed_jobs"."priority" = $1 [["priority", 0]] Delayed::Backend::ActiveRecord::Job Load (0.6ms) UPDATE "delayed_jobs" SET locked_at = '2019-09-13 16:08:04.458541', locked_by = 'host:Sam-Moore-MBP13 pid:46133' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-09-13 16:08:04.458170' AND (locked_at IS NULL OR locked_at < '2019-09-13 12:08:04.458179') OR locked_by = 'host:Sam-Moore-MBP13 pid:46133') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Destroy (0.2ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 3]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Delayed::Backend::ActiveRecord::Job Load (0.4ms) UPDATE "delayed_jobs" SET locked_at = '2019-09-13 16:08:04.461295', locked_by = 'host:Sam-Moore-MBP13 pid:46133' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-09-13 16:08:04.460995' AND (locked_at IS NULL OR locked_at < '2019-09-13 12:08:04.461005') OR locked_by = 'host:Sam-Moore-MBP13 pid:46133') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.5ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') Delayed::Backend::ActiveRecord::Job Load (0.9ms) UPDATE "delayed_jobs" SET locked_at = '2019-09-13 16:08:04.467404', locked_by = 'host:Sam-Moore-MBP13 pid:46133' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-09-13 16:08:04.466452' AND (locked_at IS NULL OR locked_at < '2019-09-13 12:08:04.466490') OR locked_by = 'host:Sam-Moore-MBP13 pid:46133') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.5ms) SELECT COUNT(*) FROM "delayed_jobs" WHERE (handler like '%Journaled::Delivery%') Delayed::Backend::ActiveRecord::Job Load (0.5ms) UPDATE "delayed_jobs" SET locked_at = '2019-09-13 16:08:04.473654', locked_by = 'host:Sam-Moore-MBP13 pid:46133' WHERE id IN (SELECT "delayed_jobs"."id" FROM "delayed_jobs" WHERE ((run_at <= '2019-09-13 16:08:04.473318' AND (locked_at IS NULL OR locked_at < '2019-09-13 12:08:04.473330') OR locked_by = 'host:Sam-Moore-MBP13 pid:46133') AND failed_at IS NULL) ORDER BY priority ASC, run_at ASC LIMIT 1 FOR UPDATE) RETURNING *  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.2ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SAVEPOINT active_record_1 # Create (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-09-13 16:08:04.529164"], ["created_at", "2019-09-13 16:08:04.529206"], ["updated_at", "2019-09-13 16:08:04.529206"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 # Destroy (0.6ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 4]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) SAVEPOINT active_record_1 # Create (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-09-13 16:08:04.555858"], ["created_at", "2019-09-13 16:08:04.555895"], ["updated_at", "2019-09-13 16:08:04.555895"]] Delayed::Backend::ActiveRecord::Job Create (0.4ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"3277d88b-04bf-43fa-9a87-84919973801e\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-09-13T16:08:04.558Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"5\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-09-13 16:08:04.562411"], ["created_at", "2019-09-13 16:08:04.562444"], ["updated_at", "2019-09-13 16:08:04.562444"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 # Destroy (0.4ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 5]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SAVEPOINT active_record_1 # Create (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-09-13 16:08:04.579633"], ["created_at", "2019-09-13 16:08:04.579688"], ["updated_at", "2019-09-13 16:08:04.579688"]] Delayed::Backend::ActiveRecord::Job Create (0.4ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"b9b84453-7493-4e7a-aed4-c26a0240f95f\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-09-13T16:08:04.583Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"7\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-09-13 16:08:04.585117"], ["created_at", "2019-09-13 16:08:04.585152"], ["updated_at", "2019-09-13 16:08:04.585152"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.3ms) SAVEPOINT active_record_1 # Create (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-09-13 16:08:04.598271"], ["created_at", "2019-09-13 16:08:04.598310"], ["updated_at", "2019-09-13 16:08:04.598310"]] Delayed::Backend::ActiveRecord::Job Create (0.4ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"53d8c08d-077d-4892-a225-3a3fd1e1fc75\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-09-13T16:08:04.602Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"9\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-09-13 16:08:04.623125"], ["created_at", "2019-09-13 16:08:04.623162"], ["updated_at", "2019-09-13 16:08:04.623162"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 # Update (0.3ms) UPDATE "delayed_jobs" SET "locked_at" = $1 WHERE "delayed_jobs"."id" = $2 [["locked_at", nil], ["id", 9]]  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) SAVEPOINT active_record_1 # Create (0.3ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-09-13 16:08:04.638487"], ["created_at", "2019-09-13 16:08:04.638522"], ["updated_at", "2019-09-13 16:08:04.638522"]] Delayed::Backend::ActiveRecord::Job Create (0.3ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"9e974ebc-7f48-438e-a743-00fafe6fff16\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-09-13T16:08:04.640Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"11\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-09-13 16:08:04.642295"], ["created_at", "2019-09-13 16:08:04.642319"], ["updated_at", "2019-09-13 16:08:04.642319"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 # Update (0.3ms) UPDATE "delayed_jobs" SET "handler" = $1 WHERE "delayed_jobs"."id" = $2 [["handler", ""], ["id", 11]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) SAVEPOINT active_record_1 # Create (0.2ms) INSERT INTO "delayed_jobs" ("handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["handler", "--- !ruby/module 'TestJob'\n"], ["run_at", "2019-09-13 16:08:04.656927"], ["created_at", "2019-09-13 16:08:04.656963"], ["updated_at", "2019-09-13 16:08:04.656963"]] Delayed::Backend::ActiveRecord::Job Create (0.3ms) INSERT INTO "delayed_jobs" ("priority", "handler", "run_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["priority", 20], ["handler", "--- !ruby/object:Journaled::Delivery\nserialized_event: '{\"id\":\"6280fd1a-693f-4929-8357-5a01ed7c70c2\",\"event_type\":\"journaled_change\",\"created_at\":\"2019-09-13T16:08:04.659Z\",\"table_name\":\"delayed_jobs\",\"record_id\":\"13\",\"database_operation\":\"create\",\"logical_operation\":\"attempt\",\"changes\":\"{\\\"locked_at\\\":null}\",\"actor\":\"gid://dummy\"}'\npartition_key: journaled_change\napp_name: \n"], ["run_at", "2019-09-13 16:08:04.660811"], ["created_at", "2019-09-13 16:08:04.660834"], ["updated_at", "2019-09-13 16:08:04.660834"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN # Update All (0.4ms) UPDATE "delayed_jobs" SET "locked_at" = $1 [["locked_at", nil]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN # Update All (0.3ms) UPDATE "delayed_jobs" SET last_error = 'locked_at'  (0.1ms) ROLLBACK  (0.1ms) BEGIN # Update All (0.3ms) UPDATE "delayed_jobs" SET "handler" = $1 [["handler", ""]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN # Destroy (0.3ms) DELETE FROM "delayed_jobs"  (0.1ms) ROLLBACK  (0.1ms) BEGIN # Destroy (0.3ms) DELETE FROM "delayed_jobs"  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.2ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN # Destroy (0.4ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 1]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN # Destroy (0.3ms) DELETE FROM "delayed_jobs" WHERE "delayed_jobs"."id" = $1 [["id", 1]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK  (0.1ms) BEGIN  (0.2ms) ROLLBACK  (0.1ms) BEGIN  (0.1ms) ROLLBACK