spec/internal/log/test.log in crono-2.0.0 vs spec/internal/log/test.log in crono-2.0.1

- old
+ new

@@ -3864,5 +3864,2422 @@ Completed 200 OK in 1ms (Views: 0.5ms | ActiveRecord: 0.0ms | Allocations: 1975) TRANSACTION (0.0ms) SAVEPOINT active_record_1 Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (0.2ms) rollback transaction +  (0.6ms) SELECT sqlite_version(*) +  (0.0ms) SELECT sqlite_version(*) +  (0.4ms) CREATE TABLE "crono_jobs" ("id" integer PRIMARY KEY AUTOINCREMENT NOT NULL, "job_id" varchar NOT NULL, "log" text(1073741823), "last_performed_at" datetime(6), "healthy" boolean, "created_at" datetime(6) NOT NULL, "updated_at" datetime(6) NOT NULL) +  (0.3ms) CREATE UNIQUE INDEX "index_crono_jobs_on_job_id" ON "crono_jobs" ("job_id") +  (0.4ms) CREATE TABLE "ar_internal_metadata" ("key" varchar NOT NULL PRIMARY KEY, "value" varchar, "created_at" datetime(6) NOT NULL, "updated_at" datetime(6) NOT NULL) + ActiveRecord::InternalMetadata Load (0.0ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ? [["key", "environment"], ["LIMIT", 1]] + TRANSACTION (0.0ms) begin transaction + ActiveRecord::InternalMetadata Create (0.1ms) INSERT INTO "ar_internal_metadata" ("key", "value", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["key", "environment"], ["value", "test"], ["created_at", "2022-07-23 19:51:38.224431"], ["updated_at", "2022-07-23 19:51:38.224431"]] + TRANSACTION (0.2ms) commit transaction +  (0.3ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL PRIMARY KEY) + ActiveRecord::InternalMetadata Load (0.0ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ? [["key", "environment"], ["LIMIT", 1]] +  (0.0ms) SELECT sqlite_version(*) + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.1ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.2ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-23 19:51:38.445148"], ["updated_at", "2022-07-23 19:51:38.445148"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["created_at", "2022-07-23 19:51:38.447166"], ["updated_at", "2022-07-23 19:51:38.447166"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-07-23T22:51:38.446646 #14041] INFO -- : Perform TestFailingJob\nE, [2022-07-23T22:51:38.446722 #14041] ERROR -- : Finished TestFailingJob in 0.00 seconds with error: wrong number of arguments (given 0, expected 1)\nE, [2022-07-23T22:51:38.446736 #14041] ERROR -- : /Users/dima/dev/crono/spec/job_spec.rb:10:in `perform'\n/Users/dima/dev/crono/lib/crono/job.rb:82:in `perform_job'\n/Users/dima/dev/crono/lib/crono/job.rb:43:in `block in perform'\n"], ["last_performed_at", "2022-07-23 19:51:38.446651"], ["healthy", 0], ["updated_at", "2022-07-23 19:51:38.447972"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.0ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-23 19:51:38.459891"], ["updated_at", "2022-07-23 19:51:38.459891"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 2]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["created_at", "2022-07-23 19:51:38.461423"], ["updated_at", "2022-07-23 19:51:38.461423"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-07-23T22:51:38.460927 #14041] INFO -- : Perform TestFailingJob\nE, [2022-07-23T22:51:38.461016 #14041] ERROR -- : Finished TestFailingJob in 0.00 seconds with error: wrong number of arguments (given 0, expected 1)\nE, [2022-07-23T22:51:38.461030 #14041] ERROR -- : /Users/dima/dev/crono/spec/job_spec.rb:10:in `perform'\n/Users/dima/dev/crono/lib/crono/job.rb:82:in `perform_job'\n/Users/dima/dev/crono/lib/crono/job.rb:43:in `block in perform'\n"], ["last_performed_at", "2022-07-23 19:51:38.460932"], ["healthy", 0], ["updated_at", "2022-07-23 19:51:38.462133"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.0ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-23 19:51:38.462751"], ["updated_at", "2022-07-23 19:51:38.462751"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 2]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-23 19:51:38.463979"], ["updated_at", "2022-07-23 19:51:38.463979"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? ORDER BY "crono_jobs"."id" ASC LIMIT ?  [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "updated_at" = ? WHERE "crono_jobs"."id" = ? [["updated_at", "2022-07-23 19:51:38.464605"], ["id", 1]] + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-23 19:51:38.466266"], ["updated_at", "2022-07-23 19:51:38.466266"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-23 19:51:38.467632"], ["updated_at", "2022-07-23 19:51:38.467632"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-23 19:51:38.469922"], ["updated_at", "2022-07-23 19:51:38.469922"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-07-23T22:51:38.468556 #14041] INFO -- : Perform TestJob\nE, [2022-07-23T22:51:38.469569 #14041] ERROR -- : Finished TestJob in 0.00 seconds with error: undefined method `perform' for nil:NilClass\n\n performer.new.perform\n ^^^^^^^^\nE, [2022-07-23T22:51:38.469583 #14041] ERROR -- : /Users/dima/dev/crono/lib/crono/job.rb:82:in `perform_job'\n/Users/dima/dev/crono/lib/crono/job.rb:43:in `block in perform'\n"], ["last_performed_at", "2022-07-23 19:51:38.468560"], ["healthy", 0], ["updated_at", "2022-07-23 19:51:38.470567"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-23 19:51:38.471963"], ["updated_at", "2022-07-23 19:51:38.471963"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (5.2ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-07-23T22:51:38.471539 #14041] INFO -- : Perform TestJob\nI, [2022-07-23T22:51:38.471622 #14041] INFO -- : Finished TestJob in 0.00 seconds\n"], ["last_performed_at", "2022-07-23 19:51:38.471544"], ["healthy", 1], ["updated_at", "2022-07-23 19:51:38.477863"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Exists? (0.1ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.2ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-23 19:51:38.481329"], ["updated_at", "2022-07-23 19:51:38.481329"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", ""], ["updated_at", "2022-07-23 19:51:38.482329"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-23 19:51:38.483655"], ["updated_at", "2022-07-23 19:51:38.483655"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", ""], ["last_performed_at", "2022-07-23 19:51:38.483336"], ["healthy", 1], ["updated_at", "2022-07-23 19:51:38.484375"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-23 19:51:38.485565"], ["updated_at", "2022-07-23 19:51:38.485565"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-07-23T22:51:38.485169 #14041] INFO -- : test message\n"], ["updated_at", "2022-07-23 19:51:38.486139"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-23 19:51:38.487479"], ["updated_at", "2022-07-23 19:51:38.487479"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-07-23T22:51:38.487155 #14041] INFO -- : line 1\nline 2\nline 3\nline 4\nline 5\nline 6\nline 7\nline 8\nline 9\nline 10\nline 11\nline 12\nline 13\nline 14\nline 15\nline 16\nline 17\nline 18\nline 19\nline 20\nline 21\nline 22\nline 23\nline 24\nline 25\nline 26\nline 27\nline 28\nline 29\nline 30\nline 31\nline 32\nline 33\nline 34\nline 35\nline 36\nline 37\nline 38\nline 39\nline 40\nline 41\nline 42\nline 43\nline 44\nline 45\nline 46\nline 47\nline 48\nline 49\nline 50\nline 51\nline 52\nline 53\nline 54\nline 55\nline 56\nline 57\nline 58\nline 59\nline 60\nline 61\nline 62\nline 63\nline 64\nline 65\nline 66\nline 67\nline 68\nline 69\nline 70\nline 71\nline 72\nline 73\nline 74\nline 75\nline 76\nline 77\nline 78\nline 79\nline 80\nline 81\nline 82\nline 83\nline 84\nline 85\nline 86\nline 87\nline 88\nline 89\nline 90\nline 91\nline 92\nline 93\nline 94\nline 95\nline 96\nline 97\nline 98\nline 99\nline 100\n"], ["updated_at", "2022-07-23 19:51:38.488078"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-23 19:51:38.489360"], ["updated_at", "2022-07-23 19:51:38.489360"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "line 51\nline 52\nline 53\nline 54\nline 55\nline 56\nline 57\nline 58\nline 59\nline 60\nline 61\nline 62\nline 63\nline 64\nline 65\nline 66\nline 67\nline 68\nline 69\nline 70\nline 71\nline 72\nline 73\nline 74\nline 75\nline 76\nline 77\nline 78\nline 79\nline 80\nline 81\nline 82\nline 83\nline 84\nline 85\nline 86\nline 87\nline 88\nline 89\nline 90\nline 91\nline 92\nline 93\nline 94\nline 95\nline 96\nline 97\nline 98\nline 99\nline 100\n"], ["updated_at", "2022-07-23 19:51:38.490047"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-23 19:51:38.491165"], ["updated_at", "2022-07-23 19:51:38.491165"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", ""], ["last_performed_at", "2022-07-23 19:51:38.490869"], ["updated_at", "2022-07-23 19:51:38.491836"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" IS NULL LIMIT ? [["LIMIT", 1]] + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "TestJob every 2 days"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "TestJob every 2 days"], ["created_at", "2022-07-23 19:51:38.494511"], ["updated_at", "2022-07-23 19:51:38.494511"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "TestJob every 2 days"], ["LIMIT", 1]] + TRANSACTION (0.0ms) ROLLBACK TO SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "TestJob every 2 days"], ["LIMIT", 1]] + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 3 days"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 3 days"], ["created_at", "2022-07-23 19:51:38.495782"], ["updated_at", "2022-07-23 19:51:38.495782"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 3 days"], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:30"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:30"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:30"], ["created_at", "2022-07-23 19:51:38.497650"], ["updated_at", "2022-07-23 19:51:38.497650"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "used_job"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "used_job"], ["created_at", "2022-07-23 19:51:42.506670"], ["updated_at", "2022-07-23 19:51:42.506670"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE 1=1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "used_job"], ["LIMIT", 1]] + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-07-23 19:51:42.508841"], ["updated_at", "2022-07-23 19:51:42.508841"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#index as HTML + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + Rendered /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application (Duration: 0.8ms | Allocations: 843) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 7.7ms | Allocations: 12135) +Completed 200 OK in 13ms (Views: 9.5ms | ActiveRecord: 0.0ms | Allocations: 15843) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-07-23 19:51:42.532044"], ["updated_at", "2022-07-23 19:51:42.532044"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["last_performed_at", "2022-07-23 19:41:42.532514"], ["healthy", 0], ["updated_at", "2022-07-23 19:51:42.532835"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#index as HTML + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + Rendered /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application (Duration: 0.2ms | Allocations: 216) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 0.6ms | Allocations: 1651) +Completed 200 OK in 1ms (Views: 0.7ms | ActiveRecord: 0.0ms | Allocations: 2020) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-07-23 19:51:42.535211"], ["updated_at", "2022-07-23 19:51:42.535211"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["last_performed_at", "2022-07-23 19:41:42.535623"], ["healthy", 1], ["updated_at", "2022-07-23 19:51:42.535875"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#index as HTML + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + Rendered /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application (Duration: 0.1ms | Allocations: 204) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 0.5ms | Allocations: 1639) +Completed 200 OK in 1ms (Views: 0.7ms | ActiveRecord: 0.0ms | Allocations: 2007) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-07-23 19:51:42.538109"], ["updated_at", "2022-07-23 19:51:42.538109"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#index as HTML + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + Rendered /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application (Duration: 0.1ms | Allocations: 136) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 0.4ms | Allocations: 1584) +Completed 200 OK in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms | Allocations: 1952) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-07-23 19:51:42.540539"], ["updated_at", "2022-07-23 19:51:42.540539"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#show as HTML + Parameters: {"id"=>"1"} + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/show.html.erb within layouts/crono/application + Rendered /Users/dima/dev/crono/app/views/crono/jobs/show.html.erb within layouts/crono/application (Duration: 0.3ms | Allocations: 242) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 0.7ms | Allocations: 1667) +Completed 200 OK in 1ms (Views: 0.8ms | ActiveRecord: 0.0ms | Allocations: 2421) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-07-23 19:51:42.543371"], ["updated_at", "2022-07-23 19:51:42.543371"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["healthy", 0], ["updated_at", "2022-07-23 19:51:42.544047"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#show as HTML + Parameters: {"id"=>"1"} + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/show.html.erb within layouts/crono/application + Rendered /Users/dima/dev/crono/app/views/crono/jobs/show.html.erb within layouts/crono/application (Duration: 0.1ms | Allocations: 46) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 0.4ms | Allocations: 1471) +Completed 200 OK in 1ms (Views: 0.5ms | ActiveRecord: 0.0ms | Allocations: 1960) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction +  (0.6ms) SELECT sqlite_version(*) +  (0.0ms) SELECT sqlite_version(*) +  (0.5ms) CREATE TABLE "crono_jobs" ("id" integer PRIMARY KEY AUTOINCREMENT NOT NULL, "job_id" varchar NOT NULL, "log" text(1073741823), "last_performed_at" datetime(6), "healthy" boolean, "created_at" datetime(6) NOT NULL, "updated_at" datetime(6) NOT NULL) +  (0.2ms) CREATE UNIQUE INDEX "index_crono_jobs_on_job_id" ON "crono_jobs" ("job_id") +  (0.4ms) CREATE TABLE "ar_internal_metadata" ("key" varchar NOT NULL PRIMARY KEY, "value" varchar, "created_at" datetime(6) NOT NULL, "updated_at" datetime(6) NOT NULL) + ActiveRecord::InternalMetadata Load (0.0ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ? [["key", "environment"], ["LIMIT", 1]] + TRANSACTION (0.0ms) begin transaction + ActiveRecord::InternalMetadata Create (0.1ms) INSERT INTO "ar_internal_metadata" ("key", "value", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["key", "environment"], ["value", "test"], ["created_at", "2022-07-24 19:36:19.815739"], ["updated_at", "2022-07-24 19:36:19.815739"]] + TRANSACTION (0.2ms) commit transaction +  (0.3ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL PRIMARY KEY) + ActiveRecord::InternalMetadata Load (0.0ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ? [["key", "environment"], ["LIMIT", 1]] +  (0.0ms) SELECT sqlite_version(*) + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.2ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:20.028791"], ["updated_at", "2022-07-24 19:36:20.028791"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:20.030486"], ["updated_at", "2022-07-24 19:36:20.030486"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-07-24T22:36:20.029987 #17502] INFO -- : Perform TestFailingJob\nE, [2022-07-24T22:36:20.030058 #17502] ERROR -- : Finished TestFailingJob in 0.00 seconds with error: Some error\nE, [2022-07-24T22:36:20.030075 #17502] ERROR -- : /Users/dima/dev/crono/spec/job_spec.rb:11:in `perform'\n/Users/dima/dev/crono/lib/crono/job.rb:82:in `perform_job'\n/Users/dima/dev/crono/lib/crono/job.rb:43:in `block in perform'\n"], ["last_performed_at", "2022-07-24 19:36:20.029993"], ["healthy", 0], ["updated_at", "2022-07-24 19:36:20.031237"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.0ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:20.033161"], ["updated_at", "2022-07-24 19:36:20.033161"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 2]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:20.034411"], ["updated_at", "2022-07-24 19:36:20.034411"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-07-24T22:36:20.033969 #17502] INFO -- : Perform TestFailingJob\nE, [2022-07-24T22:36:20.034045 #17502] ERROR -- : Finished TestFailingJob in 0.00 seconds with error: Some error\nE, [2022-07-24T22:36:20.034060 #17502] ERROR -- : /Users/dima/dev/crono/spec/job_spec.rb:11:in `perform'\n/Users/dima/dev/crono/lib/crono/job.rb:82:in `perform_job'\n/Users/dima/dev/crono/lib/crono/job.rb:43:in `block in perform'\n"], ["last_performed_at", "2022-07-24 19:36:20.033974"], ["healthy", 0], ["updated_at", "2022-07-24 19:36:20.035043"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:20.035753"], ["updated_at", "2022-07-24 19:36:20.035753"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 2]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.1ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.2ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:20.037694"], ["updated_at", "2022-07-24 19:36:20.037694"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? ORDER BY "crono_jobs"."id" ASC LIMIT ?  [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "updated_at" = ? WHERE "crono_jobs"."id" = ? [["updated_at", "2022-07-24 19:36:20.038530"], ["id", 1]] + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:20.040536"], ["updated_at", "2022-07-24 19:36:20.040536"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:20.041962"], ["updated_at", "2022-07-24 19:36:20.041962"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:20.044264"], ["updated_at", "2022-07-24 19:36:20.044264"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-07-24T22:36:20.042912 #17502] INFO -- : Perform TestJob\nE, [2022-07-24T22:36:20.043890 #17502] ERROR -- : Finished TestJob in 0.00 seconds with error: undefined method `perform' for nil:NilClass\n\n performer.new.perform\n ^^^^^^^^\nE, [2022-07-24T22:36:20.043906 #17502] ERROR -- : /Users/dima/dev/crono/lib/crono/job.rb:82:in `perform_job'\n/Users/dima/dev/crono/lib/crono/job.rb:43:in `block in perform'\n"], ["last_performed_at", "2022-07-24 19:36:20.042916"], ["healthy", 0], ["updated_at", "2022-07-24 19:36:20.044917"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:20.046424"], ["updated_at", "2022-07-24 19:36:20.046424"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-07-24T22:36:20.045941 #17502] INFO -- : Perform TestJob\nI, [2022-07-24T22:36:20.046062 #17502] INFO -- : Finished TestJob in 0.00 seconds\n"], ["last_performed_at", "2022-07-24 19:36:20.045946"], ["healthy", 1], ["updated_at", "2022-07-24 19:36:20.047039"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:20.049385"], ["updated_at", "2022-07-24 19:36:20.049385"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", ""], ["updated_at", "2022-07-24 19:36:20.049999"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.1ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.2ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:20.056928"], ["updated_at", "2022-07-24 19:36:20.056928"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", ""], ["last_performed_at", "2022-07-24 19:36:20.056336"], ["healthy", 1], ["updated_at", "2022-07-24 19:36:20.057672"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:20.059093"], ["updated_at", "2022-07-24 19:36:20.059093"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-07-24T22:36:20.058767 #17502] INFO -- : test message\n"], ["updated_at", "2022-07-24 19:36:20.059686"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:20.060896"], ["updated_at", "2022-07-24 19:36:20.060896"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-07-24T22:36:20.060577 #17502] INFO -- : line 1\nline 2\nline 3\nline 4\nline 5\nline 6\nline 7\nline 8\nline 9\nline 10\nline 11\nline 12\nline 13\nline 14\nline 15\nline 16\nline 17\nline 18\nline 19\nline 20\nline 21\nline 22\nline 23\nline 24\nline 25\nline 26\nline 27\nline 28\nline 29\nline 30\nline 31\nline 32\nline 33\nline 34\nline 35\nline 36\nline 37\nline 38\nline 39\nline 40\nline 41\nline 42\nline 43\nline 44\nline 45\nline 46\nline 47\nline 48\nline 49\nline 50\nline 51\nline 52\nline 53\nline 54\nline 55\nline 56\nline 57\nline 58\nline 59\nline 60\nline 61\nline 62\nline 63\nline 64\nline 65\nline 66\nline 67\nline 68\nline 69\nline 70\nline 71\nline 72\nline 73\nline 74\nline 75\nline 76\nline 77\nline 78\nline 79\nline 80\nline 81\nline 82\nline 83\nline 84\nline 85\nline 86\nline 87\nline 88\nline 89\nline 90\nline 91\nline 92\nline 93\nline 94\nline 95\nline 96\nline 97\nline 98\nline 99\nline 100\n"], ["updated_at", "2022-07-24 19:36:20.061568"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:20.062761"], ["updated_at", "2022-07-24 19:36:20.062761"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "line 51\nline 52\nline 53\nline 54\nline 55\nline 56\nline 57\nline 58\nline 59\nline 60\nline 61\nline 62\nline 63\nline 64\nline 65\nline 66\nline 67\nline 68\nline 69\nline 70\nline 71\nline 72\nline 73\nline 74\nline 75\nline 76\nline 77\nline 78\nline 79\nline 80\nline 81\nline 82\nline 83\nline 84\nline 85\nline 86\nline 87\nline 88\nline 89\nline 90\nline 91\nline 92\nline 93\nline 94\nline 95\nline 96\nline 97\nline 98\nline 99\nline 100\n"], ["updated_at", "2022-07-24 19:36:20.063395"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:20.064623"], ["updated_at", "2022-07-24 19:36:20.064623"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", ""], ["last_performed_at", "2022-07-24 19:36:20.064325"], ["updated_at", "2022-07-24 19:36:20.065186"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" IS NULL LIMIT ? [["LIMIT", 1]] + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "TestJob every 2 days"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "TestJob every 2 days"], ["created_at", "2022-07-24 19:36:20.068036"], ["updated_at", "2022-07-24 19:36:20.068036"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "TestJob every 2 days"], ["LIMIT", 1]] + TRANSACTION (0.0ms) ROLLBACK TO SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "TestJob every 2 days"], ["LIMIT", 1]] + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 3 days"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 3 days"], ["created_at", "2022-07-24 19:36:20.069361"], ["updated_at", "2022-07-24 19:36:20.069361"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 3 days"], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:30"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:30"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:30"], ["created_at", "2022-07-24 19:36:20.071205"], ["updated_at", "2022-07-24 19:36:20.071205"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.1ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "used_job"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "used_job"], ["created_at", "2022-07-24 19:36:24.080926"], ["updated_at", "2022-07-24 19:36:24.080926"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE 1=1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "used_job"], ["LIMIT", 1]] + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-07-24 19:36:24.083319"], ["updated_at", "2022-07-24 19:36:24.083319"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#index as HTML + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + Rendered /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application (Duration: 0.8ms | Allocations: 843) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 7.4ms | Allocations: 12133) +Completed 200 OK in 12ms (Views: 9.1ms | ActiveRecord: 0.0ms | Allocations: 15842) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.1ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-07-24 19:36:24.105657"], ["updated_at", "2022-07-24 19:36:24.105657"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["last_performed_at", "2022-07-24 19:26:24.106079"], ["healthy", 0], ["updated_at", "2022-07-24 19:36:24.106361"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#index as HTML + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + Rendered /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application (Duration: 0.2ms | Allocations: 216) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 0.6ms | Allocations: 1651) +Completed 200 OK in 1ms (Views: 0.7ms | ActiveRecord: 0.0ms | Allocations: 2020) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.2ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-07-24 19:36:24.108786"], ["updated_at", "2022-07-24 19:36:24.108786"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["last_performed_at", "2022-07-24 19:26:24.109349"], ["healthy", 1], ["updated_at", "2022-07-24 19:36:24.109682"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#index as HTML + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + Rendered /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application (Duration: 0.3ms | Allocations: 204) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 0.8ms | Allocations: 1639) +Completed 200 OK in 1ms (Views: 0.9ms | ActiveRecord: 0.0ms | Allocations: 2007) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-07-24 19:36:24.112839"], ["updated_at", "2022-07-24 19:36:24.112839"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#index as HTML + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + Rendered /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application (Duration: 0.1ms | Allocations: 136) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 0.5ms | Allocations: 1584) +Completed 200 OK in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms | Allocations: 1952) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-07-24 19:36:24.115679"], ["updated_at", "2022-07-24 19:36:24.115679"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#show as HTML + Parameters: {"id"=>"1"} + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/show.html.erb within layouts/crono/application + Rendered /Users/dima/dev/crono/app/views/crono/jobs/show.html.erb within layouts/crono/application (Duration: 0.3ms | Allocations: 242) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 0.6ms | Allocations: 1667) +Completed 200 OK in 1ms (Views: 0.7ms | ActiveRecord: 0.0ms | Allocations: 2421) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.1ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-07-24 19:36:24.118624"], ["updated_at", "2022-07-24 19:36:24.118624"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.1ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["healthy", 0], ["updated_at", "2022-07-24 19:36:24.119226"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#show as HTML + Parameters: {"id"=>"1"} + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/show.html.erb within layouts/crono/application + Rendered /Users/dima/dev/crono/app/views/crono/jobs/show.html.erb within layouts/crono/application (Duration: 0.0ms | Allocations: 46) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 0.4ms | Allocations: 1471) +Completed 200 OK in 1ms (Views: 0.5ms | ActiveRecord: 0.0ms | Allocations: 1960) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction +  (0.6ms) SELECT sqlite_version(*) +  (0.0ms) SELECT sqlite_version(*) +  (0.4ms) CREATE TABLE "crono_jobs" ("id" integer PRIMARY KEY AUTOINCREMENT NOT NULL, "job_id" varchar NOT NULL, "log" text(1073741823), "last_performed_at" datetime(6), "healthy" boolean, "created_at" datetime(6) NOT NULL, "updated_at" datetime(6) NOT NULL) +  (0.3ms) CREATE UNIQUE INDEX "index_crono_jobs_on_job_id" ON "crono_jobs" ("job_id") +  (0.4ms) CREATE TABLE "ar_internal_metadata" ("key" varchar NOT NULL PRIMARY KEY, "value" varchar, "created_at" datetime(6) NOT NULL, "updated_at" datetime(6) NOT NULL) + ActiveRecord::InternalMetadata Load (0.1ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ? [["key", "environment"], ["LIMIT", 1]] + TRANSACTION (0.0ms) begin transaction + ActiveRecord::InternalMetadata Create (0.1ms) INSERT INTO "ar_internal_metadata" ("key", "value", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["key", "environment"], ["value", "test"], ["created_at", "2022-07-24 19:36:58.177466"], ["updated_at", "2022-07-24 19:36:58.177466"]] + TRANSACTION (0.2ms) commit transaction +  (0.3ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL PRIMARY KEY) + ActiveRecord::InternalMetadata Load (0.0ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ? [["key", "environment"], ["LIMIT", 1]] +  (0.0ms) SELECT sqlite_version(*) + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.2ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:58.379236"], ["updated_at", "2022-07-24 19:36:58.379236"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:58.380853"], ["updated_at", "2022-07-24 19:36:58.380853"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-07-24T22:36:58.380371 #17573] INFO -- : Perform TestFailingJob\nE, [2022-07-24T22:36:58.380436 #17573] ERROR -- : Finished TestFailingJob in 0.00 seconds with error: Some error\nE, [2022-07-24T22:36:58.380450 #17573] ERROR -- : /Users/dima/dev/crono/spec/job_spec.rb:11:in `perform'\n/Users/dima/dev/crono/lib/crono/job.rb:82:in `perform_job'\n/Users/dima/dev/crono/lib/crono/job.rb:43:in `block in perform'\n"], ["last_performed_at", "2022-07-24 19:36:58.380376"], ["healthy", 0], ["updated_at", "2022-07-24 19:36:58.381602"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.0ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:58.383495"], ["updated_at", "2022-07-24 19:36:58.383495"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 2]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:58.384755"], ["updated_at", "2022-07-24 19:36:58.384755"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-07-24T22:36:58.384298 #17573] INFO -- : Perform TestFailingJob\nE, [2022-07-24T22:36:58.384380 #17573] ERROR -- : Finished TestFailingJob in 0.00 seconds with error: Some error\nE, [2022-07-24T22:36:58.384394 #17573] ERROR -- : /Users/dima/dev/crono/spec/job_spec.rb:11:in `perform'\n/Users/dima/dev/crono/lib/crono/job.rb:82:in `perform_job'\n/Users/dima/dev/crono/lib/crono/job.rb:43:in `block in perform'\n"], ["last_performed_at", "2022-07-24 19:36:58.384303"], ["healthy", 0], ["updated_at", "2022-07-24 19:36:58.385415"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.0ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:58.385989"], ["updated_at", "2022-07-24 19:36:58.385989"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 2]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:58.387146"], ["updated_at", "2022-07-24 19:36:58.387146"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? ORDER BY "crono_jobs"."id" ASC LIMIT ?  [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "updated_at" = ? WHERE "crono_jobs"."id" = ? [["updated_at", "2022-07-24 19:36:58.387747"], ["id", 1]] + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:58.389357"], ["updated_at", "2022-07-24 19:36:58.389357"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.2ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:58.391332"], ["updated_at", "2022-07-24 19:36:58.391332"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:58.394090"], ["updated_at", "2022-07-24 19:36:58.394090"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-07-24T22:36:58.392645 #17573] INFO -- : Perform TestJob\nE, [2022-07-24T22:36:58.393662 #17573] ERROR -- : Finished TestJob in 0.00 seconds with error: undefined method `perform' for nil:NilClass\n\n performer.new.perform\n ^^^^^^^^\nE, [2022-07-24T22:36:58.393678 #17573] ERROR -- : /Users/dima/dev/crono/lib/crono/job.rb:82:in `perform_job'\n/Users/dima/dev/crono/lib/crono/job.rb:43:in `block in perform'\n"], ["last_performed_at", "2022-07-24 19:36:58.392649"], ["healthy", 0], ["updated_at", "2022-07-24 19:36:58.394825"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:58.396317"], ["updated_at", "2022-07-24 19:36:58.396317"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-07-24T22:36:58.395869 #17573] INFO -- : Perform TestJob\nI, [2022-07-24T22:36:58.395973 #17573] INFO -- : Finished TestJob in 0.00 seconds\n"], ["last_performed_at", "2022-07-24 19:36:58.395874"], ["healthy", 1], ["updated_at", "2022-07-24 19:36:58.396943"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:58.399223"], ["updated_at", "2022-07-24 19:36:58.399223"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", ""], ["updated_at", "2022-07-24 19:36:58.399855"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.1ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.2ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:58.406714"], ["updated_at", "2022-07-24 19:36:58.406714"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", ""], ["last_performed_at", "2022-07-24 19:36:58.406134"], ["healthy", 1], ["updated_at", "2022-07-24 19:36:58.407479"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.3ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:58.408914"], ["updated_at", "2022-07-24 19:36:58.408914"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-07-24T22:36:58.408576 #17573] INFO -- : test message\n"], ["updated_at", "2022-07-24 19:36:58.409515"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:58.410716"], ["updated_at", "2022-07-24 19:36:58.410716"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-07-24T22:36:58.410406 #17573] INFO -- : line 1\nline 2\nline 3\nline 4\nline 5\nline 6\nline 7\nline 8\nline 9\nline 10\nline 11\nline 12\nline 13\nline 14\nline 15\nline 16\nline 17\nline 18\nline 19\nline 20\nline 21\nline 22\nline 23\nline 24\nline 25\nline 26\nline 27\nline 28\nline 29\nline 30\nline 31\nline 32\nline 33\nline 34\nline 35\nline 36\nline 37\nline 38\nline 39\nline 40\nline 41\nline 42\nline 43\nline 44\nline 45\nline 46\nline 47\nline 48\nline 49\nline 50\nline 51\nline 52\nline 53\nline 54\nline 55\nline 56\nline 57\nline 58\nline 59\nline 60\nline 61\nline 62\nline 63\nline 64\nline 65\nline 66\nline 67\nline 68\nline 69\nline 70\nline 71\nline 72\nline 73\nline 74\nline 75\nline 76\nline 77\nline 78\nline 79\nline 80\nline 81\nline 82\nline 83\nline 84\nline 85\nline 86\nline 87\nline 88\nline 89\nline 90\nline 91\nline 92\nline 93\nline 94\nline 95\nline 96\nline 97\nline 98\nline 99\nline 100\n"], ["updated_at", "2022-07-24 19:36:58.411402"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:58.412570"], ["updated_at", "2022-07-24 19:36:58.412570"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "line 51\nline 52\nline 53\nline 54\nline 55\nline 56\nline 57\nline 58\nline 59\nline 60\nline 61\nline 62\nline 63\nline 64\nline 65\nline 66\nline 67\nline 68\nline 69\nline 70\nline 71\nline 72\nline 73\nline 74\nline 75\nline 76\nline 77\nline 78\nline 79\nline 80\nline 81\nline 82\nline 83\nline 84\nline 85\nline 86\nline 87\nline 88\nline 89\nline 90\nline 91\nline 92\nline 93\nline 94\nline 95\nline 96\nline 97\nline 98\nline 99\nline 100\n"], ["updated_at", "2022-07-24 19:36:58.413158"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:36:58.414347"], ["updated_at", "2022-07-24 19:36:58.414347"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", ""], ["last_performed_at", "2022-07-24 19:36:58.414041"], ["updated_at", "2022-07-24 19:36:58.414920"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" IS NULL LIMIT ? [["LIMIT", 1]] + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "TestJob every 2 days"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "TestJob every 2 days"], ["created_at", "2022-07-24 19:36:58.417718"], ["updated_at", "2022-07-24 19:36:58.417718"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "TestJob every 2 days"], ["LIMIT", 1]] + TRANSACTION (0.0ms) ROLLBACK TO SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "TestJob every 2 days"], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 3 days"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 3 days"], ["created_at", "2022-07-24 19:36:58.419030"], ["updated_at", "2022-07-24 19:36:58.419030"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 3 days"], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:30"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:30"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:30"], ["created_at", "2022-07-24 19:36:58.420878"], ["updated_at", "2022-07-24 19:36:58.420878"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.1ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "used_job"], ["LIMIT", 1]] + Crono::CronoJob Create (0.2ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "used_job"], ["created_at", "2022-07-24 19:37:02.430529"], ["updated_at", "2022-07-24 19:37:02.430529"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE 1=1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "used_job"], ["LIMIT", 1]] + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-07-24 19:37:02.432926"], ["updated_at", "2022-07-24 19:37:02.432926"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#index as HTML + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + Rendered /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application (Duration: 0.8ms | Allocations: 843) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 7.1ms | Allocations: 12131) +Completed 200 OK in 12ms (Views: 8.8ms | ActiveRecord: 0.0ms | Allocations: 15840) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.2ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-07-24 19:37:02.454419"], ["updated_at", "2022-07-24 19:37:02.454419"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["last_performed_at", "2022-07-24 19:27:02.454991"], ["healthy", 0], ["updated_at", "2022-07-24 19:37:02.455276"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#index as HTML + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + Rendered /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application (Duration: 0.2ms | Allocations: 216) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 0.6ms | Allocations: 1651) +Completed 200 OK in 1ms (Views: 0.7ms | ActiveRecord: 0.0ms | Allocations: 2020) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-07-24 19:37:02.457588"], ["updated_at", "2022-07-24 19:37:02.457588"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["last_performed_at", "2022-07-24 19:27:02.457933"], ["healthy", 1], ["updated_at", "2022-07-24 19:37:02.458177"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#index as HTML + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + Rendered /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application (Duration: 0.1ms | Allocations: 204) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 0.5ms | Allocations: 1639) +Completed 200 OK in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms | Allocations: 2007) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-07-24 19:37:02.460157"], ["updated_at", "2022-07-24 19:37:02.460157"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#index as HTML + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + Rendered /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application (Duration: 0.1ms | Allocations: 136) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 0.5ms | Allocations: 1584) +Completed 200 OK in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms | Allocations: 1952) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.1ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-07-24 19:37:02.462616"], ["updated_at", "2022-07-24 19:37:02.462616"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#show as HTML + Parameters: {"id"=>"1"} + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/show.html.erb within layouts/crono/application + Rendered /Users/dima/dev/crono/app/views/crono/jobs/show.html.erb within layouts/crono/application (Duration: 0.4ms | Allocations: 242) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 0.8ms | Allocations: 1667) +Completed 200 OK in 2ms (Views: 1.0ms | ActiveRecord: 0.0ms | Allocations: 2422) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-07-24 19:37:02.466429"], ["updated_at", "2022-07-24 19:37:02.466429"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["healthy", 0], ["updated_at", "2022-07-24 19:37:02.467116"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#show as HTML + Parameters: {"id"=>"1"} + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/show.html.erb within layouts/crono/application + Rendered /Users/dima/dev/crono/app/views/crono/jobs/show.html.erb within layouts/crono/application (Duration: 0.1ms | Allocations: 46) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 0.5ms | Allocations: 1471) +Completed 200 OK in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms | Allocations: 1960) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction +  (0.6ms) SELECT sqlite_version(*) +  (0.0ms) SELECT sqlite_version(*) +  (0.4ms) CREATE TABLE "crono_jobs" ("id" integer PRIMARY KEY AUTOINCREMENT NOT NULL, "job_id" varchar NOT NULL, "log" text(1073741823), "last_performed_at" datetime(6), "healthy" boolean, "created_at" datetime(6) NOT NULL, "updated_at" datetime(6) NOT NULL) +  (0.3ms) CREATE UNIQUE INDEX "index_crono_jobs_on_job_id" ON "crono_jobs" ("job_id") +  (0.4ms) CREATE TABLE "ar_internal_metadata" ("key" varchar NOT NULL PRIMARY KEY, "value" varchar, "created_at" datetime(6) NOT NULL, "updated_at" datetime(6) NOT NULL) + ActiveRecord::InternalMetadata Load (0.0ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ? [["key", "environment"], ["LIMIT", 1]] + TRANSACTION (0.0ms) begin transaction + ActiveRecord::InternalMetadata Create (0.1ms) INSERT INTO "ar_internal_metadata" ("key", "value", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["key", "environment"], ["value", "test"], ["created_at", "2022-07-24 19:49:44.789898"], ["updated_at", "2022-07-24 19:49:44.789898"]] + TRANSACTION (0.2ms) commit transaction +  (0.3ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL PRIMARY KEY) + ActiveRecord::InternalMetadata Load (0.0ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ? [["key", "environment"], ["LIMIT", 1]] +  (0.0ms) SELECT sqlite_version(*) + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.2ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:49:44.989503"], ["updated_at", "2022-07-24 19:49:44.989503"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:49:44.991171"], ["updated_at", "2022-07-24 19:49:44.991171"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-07-24T22:49:44.990668 #18325] INFO -- : Perform TestFailingJob\nE, [2022-07-24T22:49:44.990735 #18325] ERROR -- : Finished TestFailingJob in 0.00 seconds with error: Some error\nE, [2022-07-24T22:49:44.990747 #18325] ERROR -- : /Users/dima/dev/crono/spec/job_spec.rb:11:in `perform'\n/Users/dima/dev/crono/lib/crono/job.rb:82:in `perform_job'\n/Users/dima/dev/crono/lib/crono/job.rb:43:in `block in perform'\n"], ["last_performed_at", "2022-07-24 19:49:44.990673"], ["healthy", 0], ["updated_at", "2022-07-24 19:49:44.991922"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.0ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:49:44.993840"], ["updated_at", "2022-07-24 19:49:44.993840"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 2]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:49:44.995133"], ["updated_at", "2022-07-24 19:49:44.995133"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-07-24T22:49:44.994692 #18325] INFO -- : Perform TestFailingJob\nE, [2022-07-24T22:49:44.994754 #18325] ERROR -- : Finished TestFailingJob in 0.00 seconds with error: Some error\nE, [2022-07-24T22:49:44.994766 #18325] ERROR -- : /Users/dima/dev/crono/spec/job_spec.rb:11:in `perform'\n/Users/dima/dev/crono/lib/crono/job.rb:82:in `perform_job'\n/Users/dima/dev/crono/lib/crono/job.rb:43:in `block in perform'\n"], ["last_performed_at", "2022-07-24 19:49:44.994697"], ["healthy", 0], ["updated_at", "2022-07-24 19:49:44.995775"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.0ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:49:44.996366"], ["updated_at", "2022-07-24 19:49:44.996366"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 2]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:49:44.997515"], ["updated_at", "2022-07-24 19:49:44.997515"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? ORDER BY "crono_jobs"."id" ASC LIMIT ?  [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "updated_at" = ? WHERE "crono_jobs"."id" = ? [["updated_at", "2022-07-24 19:49:44.998094"], ["id", 1]] + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:49:44.999794"], ["updated_at", "2022-07-24 19:49:44.999794"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.1ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.2ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:49:45.001992"], ["updated_at", "2022-07-24 19:49:45.001992"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:49:45.004890"], ["updated_at", "2022-07-24 19:49:45.004890"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-07-24T22:49:45.003419 #18325] INFO -- : Perform TestJob\nE, [2022-07-24T22:49:45.004424 #18325] ERROR -- : Finished TestJob in 0.00 seconds with error: undefined method `perform' for nil:NilClass\n\n performer.new.perform\n ^^^^^^^^\nE, [2022-07-24T22:49:45.004437 #18325] ERROR -- : /Users/dima/dev/crono/lib/crono/job.rb:82:in `perform_job'\n/Users/dima/dev/crono/lib/crono/job.rb:43:in `block in perform'\n"], ["last_performed_at", "2022-07-24 19:49:45.003424"], ["healthy", 0], ["updated_at", "2022-07-24 19:49:45.005563"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.2ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:49:45.007067"], ["updated_at", "2022-07-24 19:49:45.007067"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-07-24T22:49:45.006618 #18325] INFO -- : Perform TestJob\nI, [2022-07-24T22:49:45.006721 #18325] INFO -- : Finished TestJob in 0.00 seconds\n"], ["last_performed_at", "2022-07-24 19:49:45.006623"], ["healthy", 1], ["updated_at", "2022-07-24 19:49:45.007799"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:49:45.010191"], ["updated_at", "2022-07-24 19:49:45.010191"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", ""], ["updated_at", "2022-07-24 19:49:45.010817"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (5.0ms) begin transaction + Crono::CronoJob Load (0.1ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.2ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:49:45.017194"], ["updated_at", "2022-07-24 19:49:45.017194"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", ""], ["last_performed_at", "2022-07-24 19:49:45.016642"], ["healthy", 1], ["updated_at", "2022-07-24 19:49:45.017953"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:49:45.019362"], ["updated_at", "2022-07-24 19:49:45.019362"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-07-24T22:49:45.019020 #18325] INFO -- : test message\n"], ["updated_at", "2022-07-24 19:49:45.019965"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:49:45.021186"], ["updated_at", "2022-07-24 19:49:45.021186"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-07-24T22:49:45.020872 #18325] INFO -- : line 1\nline 2\nline 3\nline 4\nline 5\nline 6\nline 7\nline 8\nline 9\nline 10\nline 11\nline 12\nline 13\nline 14\nline 15\nline 16\nline 17\nline 18\nline 19\nline 20\nline 21\nline 22\nline 23\nline 24\nline 25\nline 26\nline 27\nline 28\nline 29\nline 30\nline 31\nline 32\nline 33\nline 34\nline 35\nline 36\nline 37\nline 38\nline 39\nline 40\nline 41\nline 42\nline 43\nline 44\nline 45\nline 46\nline 47\nline 48\nline 49\nline 50\nline 51\nline 52\nline 53\nline 54\nline 55\nline 56\nline 57\nline 58\nline 59\nline 60\nline 61\nline 62\nline 63\nline 64\nline 65\nline 66\nline 67\nline 68\nline 69\nline 70\nline 71\nline 72\nline 73\nline 74\nline 75\nline 76\nline 77\nline 78\nline 79\nline 80\nline 81\nline 82\nline 83\nline 84\nline 85\nline 86\nline 87\nline 88\nline 89\nline 90\nline 91\nline 92\nline 93\nline 94\nline 95\nline 96\nline 97\nline 98\nline 99\nline 100\n"], ["updated_at", "2022-07-24 19:49:45.021860"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:49:45.023055"], ["updated_at", "2022-07-24 19:49:45.023055"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "line 51\nline 52\nline 53\nline 54\nline 55\nline 56\nline 57\nline 58\nline 59\nline 60\nline 61\nline 62\nline 63\nline 64\nline 65\nline 66\nline 67\nline 68\nline 69\nline 70\nline 71\nline 72\nline 73\nline 74\nline 75\nline 76\nline 77\nline 78\nline 79\nline 80\nline 81\nline 82\nline 83\nline 84\nline 85\nline 86\nline 87\nline 88\nline 89\nline 90\nline 91\nline 92\nline 93\nline 94\nline 95\nline 96\nline 97\nline 98\nline 99\nline 100\n"], ["updated_at", "2022-07-24 19:49:45.023667"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-07-24 19:49:45.024854"], ["updated_at", "2022-07-24 19:49:45.024854"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", ""], ["last_performed_at", "2022-07-24 19:49:45.024562"], ["updated_at", "2022-07-24 19:49:45.025425"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" IS NULL LIMIT ? [["LIMIT", 1]] + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "TestJob every 2 days"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "TestJob every 2 days"], ["created_at", "2022-07-24 19:49:45.028275"], ["updated_at", "2022-07-24 19:49:45.028275"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "TestJob every 2 days"], ["LIMIT", 1]] + TRANSACTION (0.0ms) ROLLBACK TO SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "TestJob every 2 days"], ["LIMIT", 1]] + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 3 days"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 3 days"], ["created_at", "2022-07-24 19:49:45.029575"], ["updated_at", "2022-07-24 19:49:45.029575"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 3 days"], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:30"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:30"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:30"], ["created_at", "2022-07-24 19:49:45.031413"], ["updated_at", "2022-07-24 19:49:45.031413"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.1ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "used_job"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "used_job"], ["created_at", "2022-07-24 19:49:49.041018"], ["updated_at", "2022-07-24 19:49:49.041018"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE 1=1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "used_job"], ["LIMIT", 1]] + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-07-24 19:49:49.043469"], ["updated_at", "2022-07-24 19:49:49.043469"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#index as HTML + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + Rendered /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application (Duration: 0.8ms | Allocations: 843) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 7.2ms | Allocations: 12129) +Completed 200 OK in 12ms (Views: 8.9ms | ActiveRecord: 0.0ms | Allocations: 15838) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-07-24 19:49:49.065006"], ["updated_at", "2022-07-24 19:49:49.065006"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["last_performed_at", "2022-07-24 19:39:49.065525"], ["healthy", 0], ["updated_at", "2022-07-24 19:49:49.065814"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#index as HTML + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + Rendered /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application (Duration: 0.2ms | Allocations: 216) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 0.6ms | Allocations: 1651) +Completed 200 OK in 1ms (Views: 0.7ms | ActiveRecord: 0.0ms | Allocations: 2020) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-07-24 19:49:49.068185"], ["updated_at", "2022-07-24 19:49:49.068185"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["last_performed_at", "2022-07-24 19:39:49.068522"], ["healthy", 1], ["updated_at", "2022-07-24 19:49:49.068820"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#index as HTML + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + Rendered /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application (Duration: 0.1ms | Allocations: 204) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 0.5ms | Allocations: 1639) +Completed 200 OK in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms | Allocations: 2007) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.1ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-07-24 19:49:49.070883"], ["updated_at", "2022-07-24 19:49:49.070883"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#index as HTML + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + Rendered /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application (Duration: 0.1ms | Allocations: 136) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 0.5ms | Allocations: 1584) +Completed 200 OK in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms | Allocations: 1952) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-07-24 19:49:49.073393"], ["updated_at", "2022-07-24 19:49:49.073393"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#show as HTML + Parameters: {"id"=>"1"} + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/show.html.erb within layouts/crono/application + Rendered /Users/dima/dev/crono/app/views/crono/jobs/show.html.erb within layouts/crono/application (Duration: 0.3ms | Allocations: 242) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 0.8ms | Allocations: 1667) +Completed 200 OK in 2ms (Views: 1.1ms | ActiveRecord: 0.0ms | Allocations: 2421) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-07-24 19:49:49.077523"], ["updated_at", "2022-07-24 19:49:49.077523"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["healthy", 0], ["updated_at", "2022-07-24 19:49:49.078210"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#show as HTML + Parameters: {"id"=>"1"} + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/show.html.erb within layouts/crono/application + Rendered /Users/dima/dev/crono/app/views/crono/jobs/show.html.erb within layouts/crono/application (Duration: 0.1ms | Allocations: 46) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 0.5ms | Allocations: 1471) +Completed 200 OK in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms | Allocations: 1960) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction +  (0.4ms) SELECT sqlite_version(*) +  (0.0ms) SELECT sqlite_version(*) +  (0.4ms) CREATE TABLE "crono_jobs" ("id" integer PRIMARY KEY AUTOINCREMENT NOT NULL, "job_id" varchar NOT NULL, "log" text(1073741823), "last_performed_at" datetime(6), "healthy" boolean, "created_at" datetime(6) NOT NULL, "updated_at" datetime(6) NOT NULL) +  (0.3ms) CREATE UNIQUE INDEX "index_crono_jobs_on_job_id" ON "crono_jobs" ("job_id") +  (0.4ms) CREATE TABLE "ar_internal_metadata" ("key" varchar NOT NULL PRIMARY KEY, "value" varchar, "created_at" datetime(6) NOT NULL, "updated_at" datetime(6) NOT NULL) + ActiveRecord::InternalMetadata Load (0.0ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ? [["key", "environment"], ["LIMIT", 1]] + TRANSACTION (0.0ms) begin transaction + ActiveRecord::InternalMetadata Create (0.1ms) INSERT INTO "ar_internal_metadata" ("key", "value", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["key", "environment"], ["value", "test"], ["created_at", "2022-09-03 12:24:12.235790"], ["updated_at", "2022-09-03 12:24:12.235790"]] + TRANSACTION (0.2ms) commit transaction +  (0.3ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL PRIMARY KEY) + ActiveRecord::InternalMetadata Load (0.0ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ? [["key", "environment"], ["LIMIT", 1]] +  (0.0ms) SELECT sqlite_version(*) + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.1ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.2ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-09-03 12:24:12.405631"], ["updated_at", "2022-09-03 12:24:12.405631"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.2ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["created_at", "2022-09-03 12:24:12.411279"], ["updated_at", "2022-09-03 12:24:12.411279"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.1ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-09-03T15:24:12.406695 #80042] INFO -- : Perform TestFailingJob\nE, [2022-09-03T15:24:12.406764 #80042] ERROR -- : Finished TestFailingJob in 0.00 seconds with error: Some error\nE, [2022-09-03T15:24:12.406777 #80042] ERROR -- : /Users/dima/dev/crono/spec/job_spec.rb:11:in `perform'\n/Users/dima/dev/crono/lib/crono/job.rb:82:in `perform_job'\n/Users/dima/dev/crono/lib/crono/job.rb:43:in `block in perform'\n"], ["last_performed_at", "2022-09-03 12:24:12.406699"], ["healthy", 0], ["updated_at", "2022-09-03 12:24:12.414742"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (2.3ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.0ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-09-03 12:24:12.431127"], ["updated_at", "2022-09-03 12:24:12.431127"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 2]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.1ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.2ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["created_at", "2022-09-03 12:24:12.441542"], ["updated_at", "2022-09-03 12:24:12.441542"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestFailingJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-09-03T15:24:12.440797 #80042] INFO -- : Perform TestFailingJob\nE, [2022-09-03T15:24:12.440888 #80042] ERROR -- : Finished TestFailingJob in 0.00 seconds with error: Some error\nE, [2022-09-03T15:24:12.440901 #80042] ERROR -- : /Users/dima/dev/crono/spec/job_spec.rb:11:in `perform'\n/Users/dima/dev/crono/lib/crono/job.rb:82:in `perform_job'\n/Users/dima/dev/crono/lib/crono/job.rb:43:in `block in perform'\n"], ["last_performed_at", "2022-09-03 12:24:12.440803"], ["healthy", 0], ["updated_at", "2022-09-03 12:24:12.442324"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.0ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-09-03 12:24:12.443065"], ["updated_at", "2022-09-03 12:24:12.443065"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 2]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-09-03 12:24:12.444356"], ["updated_at", "2022-09-03 12:24:12.444356"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? ORDER BY "crono_jobs"."id" ASC LIMIT ?  [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "updated_at" = ? WHERE "crono_jobs"."id" = ? [["updated_at", "2022-09-03 12:24:12.445107"], ["id", 1]] + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-09-03 12:24:12.446786"], ["updated_at", "2022-09-03 12:24:12.446786"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-09-03 12:24:12.448233"], ["updated_at", "2022-09-03 12:24:12.448233"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-09-03 12:24:12.449679"], ["updated_at", "2022-09-03 12:24:12.449679"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-09-03 12:24:12.452092"], ["updated_at", "2022-09-03 12:24:12.452092"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-09-03T15:24:12.450643 #80042] INFO -- : Perform TestJob\nE, [2022-09-03T15:24:12.451608 #80042] ERROR -- : Finished TestJob in 0.00 seconds with error: undefined method `perform' for nil:NilClass\n\n performer.new.perform\n ^^^^^^^^\nE, [2022-09-03T15:24:12.451619 #80042] ERROR -- : /Users/dima/dev/crono/lib/crono/job.rb:82:in `perform_job'\n/Users/dima/dev/crono/lib/crono/job.rb:43:in `block in perform'\n"], ["last_performed_at", "2022-09-03 12:24:12.450647"], ["healthy", 0], ["updated_at", "2022-09-03 12:24:12.452766"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-09-03 12:24:12.454281"], ["updated_at", "2022-09-03 12:24:12.454281"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-09-03T15:24:12.453851 #80042] INFO -- : Perform TestJob\nI, [2022-09-03T15:24:12.453947 #80042] INFO -- : Finished TestJob in 0.00 seconds\n"], ["last_performed_at", "2022-09-03 12:24:12.453856"], ["healthy", 1], ["updated_at", "2022-09-03 12:24:12.454874"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-09-03 12:24:12.457282"], ["updated_at", "2022-09-03 12:24:12.457282"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", ""], ["updated_at", "2022-09-03 12:24:12.457877"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-09-03 12:24:12.458976"], ["updated_at", "2022-09-03 12:24:12.458976"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", ""], ["last_performed_at", "2022-09-03 12:24:12.458675"], ["healthy", 1], ["updated_at", "2022-09-03 12:24:12.459625"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-09-03 12:24:12.460604"], ["updated_at", "2022-09-03 12:24:12.460604"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-09-03T15:24:12.460296 #80042] INFO -- : test message\n"], ["updated_at", "2022-09-03 12:24:12.461238"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-09-03 12:24:12.462335"], ["updated_at", "2022-09-03 12:24:12.462335"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "I, [2022-09-03T15:24:12.461954 #80042] INFO -- : line 1\nline 2\nline 3\nline 4\nline 5\nline 6\nline 7\nline 8\nline 9\nline 10\nline 11\nline 12\nline 13\nline 14\nline 15\nline 16\nline 17\nline 18\nline 19\nline 20\nline 21\nline 22\nline 23\nline 24\nline 25\nline 26\nline 27\nline 28\nline 29\nline 30\nline 31\nline 32\nline 33\nline 34\nline 35\nline 36\nline 37\nline 38\nline 39\nline 40\nline 41\nline 42\nline 43\nline 44\nline 45\nline 46\nline 47\nline 48\nline 49\nline 50\nline 51\nline 52\nline 53\nline 54\nline 55\nline 56\nline 57\nline 58\nline 59\nline 60\nline 61\nline 62\nline 63\nline 64\nline 65\nline 66\nline 67\nline 68\nline 69\nline 70\nline 71\nline 72\nline 73\nline 74\nline 75\nline 76\nline 77\nline 78\nline 79\nline 80\nline 81\nline 82\nline 83\nline 84\nline 85\nline 86\nline 87\nline 88\nline 89\nline 90\nline 91\nline 92\nline 93\nline 94\nline 95\nline 96\nline 97\nline 98\nline 99\nline 100\n"], ["updated_at", "2022-09-03 12:24:12.462901"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-09-03 12:24:12.463970"], ["updated_at", "2022-09-03 12:24:12.463970"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", "line 51\nline 52\nline 53\nline 54\nline 55\nline 56\nline 57\nline 58\nline 59\nline 60\nline 61\nline 62\nline 63\nline 64\nline 65\nline 66\nline 67\nline 68\nline 69\nline 70\nline 71\nline 72\nline 73\nline 74\nline 75\nline 76\nline 77\nline 78\nline 79\nline 80\nline 81\nline 82\nline 83\nline 84\nline 85\nline 86\nline 87\nline 88\nline 89\nline 90\nline 91\nline 92\nline 93\nline 94\nline 95\nline 96\nline 97\nline 98\nline 99\nline 100\n"], ["updated_at", "2022-09-03 12:24:12.464529"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:00"], ["created_at", "2022-09-03 12:24:12.465589"], ["updated_at", "2022-09-03 12:24:12.465589"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "log" = ?, "last_performed_at" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["log", ""], ["last_performed_at", "2022-09-03 12:24:12.465293"], ["updated_at", "2022-09-03 12:24:12.466157"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:00"], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" IS NULL LIMIT ? [["LIMIT", 1]] + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.1ms) begin transaction + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "TestJob every 2 days"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "TestJob every 2 days"], ["created_at", "2022-09-03 12:24:12.468673"], ["updated_at", "2022-09-03 12:24:12.468673"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "TestJob every 2 days"], ["LIMIT", 1]] + TRANSACTION (0.0ms) ROLLBACK TO SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "TestJob every 2 days"], ["LIMIT", 1]] + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 3 days"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 3 days"], ["created_at", "2022-09-03 12:24:12.470123"], ["updated_at", "2022-09-03 12:24:12.470123"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 3 days"], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:30"], ["LIMIT", 1]] + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 2 days at 15:30"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "Perform TestJob every 2 days at 15:30"], ["created_at", "2022-09-03 12:24:12.472230"], ["updated_at", "2022-09-03 12:24:12.472230"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "used_job"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "created_at", "updated_at") VALUES (?, ?, ?) [["job_id", "used_job"], ["created_at", "2022-09-03 12:24:16.481463"], ["updated_at", "2022-09-03 12:24:16.481463"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE 1=1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "used_job"], ["LIMIT", 1]] + TRANSACTION (0.1ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + TRANSACTION (0.0ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-09-03 12:24:16.483625"], ["updated_at", "2022-09-03 12:24:16.483625"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#index as HTML + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + Rendered /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application (Duration: 0.7ms | Allocations: 843) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 580.2ms | Allocations: 33039) +Completed 200 OK in 584ms (Views: 581.5ms | ActiveRecord: 0.0ms | Allocations: 36746) + TRANSACTION (0.1ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.1ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.4ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.1ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.2ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-09-03 12:24:17.077604"], ["updated_at", "2022-09-03 12:24:17.077604"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["last_performed_at", "2022-09-03 12:14:17.078330"], ["healthy", 0], ["updated_at", "2022-09-03 12:24:17.078798"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#index as HTML + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + Rendered /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application (Duration: 0.3ms | Allocations: 216) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 1.0ms | Allocations: 1651) +Completed 200 OK in 1ms (Views: 1.2ms | ActiveRecord: 0.0ms | Allocations: 2020) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.2ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-09-03 12:24:17.082745"], ["updated_at", "2022-09-03 12:24:17.082745"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "last_performed_at" = ?, "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["last_performed_at", "2022-09-03 12:14:17.083327"], ["healthy", 1], ["updated_at", "2022-09-03 12:24:17.083736"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#index as HTML + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + Rendered /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application (Duration: 0.2ms | Allocations: 204) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 0.8ms | Allocations: 1639) +Completed 200 OK in 1ms (Views: 1.0ms | ActiveRecord: 0.0ms | Allocations: 2007) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-09-03 12:24:17.086781"], ["updated_at", "2022-09-03 12:24:17.086781"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["id", 1], ["LIMIT", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#index as HTML + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + Rendered /Users/dima/dev/crono/app/views/crono/jobs/index.html.erb within layouts/crono/application (Duration: 0.1ms | Allocations: 136) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 0.7ms | Allocations: 1584) +Completed 200 OK in 1ms (Views: 0.8ms | ActiveRecord: 0.0ms | Allocations: 1952) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-09-03 12:24:17.090143"], ["updated_at", "2022-09-03 12:24:17.090143"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#show as HTML + Parameters: {"id"=>"1"} + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/show.html.erb within layouts/crono/application + Rendered /Users/dima/dev/crono/app/views/crono/jobs/show.html.erb within layouts/crono/application (Duration: 0.4ms | Allocations: 242) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 0.9ms | Allocations: 1667) +Completed 200 OK in 2ms (Views: 1.1ms | ActiveRecord: 0.0ms | Allocations: 2420) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction + TRANSACTION (0.0ms) begin transaction + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["LIMIT", 1]] + Crono::CronoJob Create (0.1ms) INSERT INTO "crono_jobs" ("job_id", "log", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["job_id", "Perform TestJob every 5 seconds"], ["log", "All runs ok"], ["created_at", "2022-09-03 12:24:17.093887"], ["updated_at", "2022-09-03 12:24:17.093887"]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Exists? (0.0ms) SELECT 1 AS one FROM "crono_jobs" WHERE "crono_jobs"."job_id" = ? AND "crono_jobs"."id" != ? LIMIT ? [["job_id", "Perform TestJob every 5 seconds"], ["id", 1], ["LIMIT", 1]] + Crono::CronoJob Update (0.0ms) UPDATE "crono_jobs" SET "healthy" = ?, "updated_at" = ? WHERE "crono_jobs"."id" = ? [["healthy", 0], ["updated_at", "2022-09-03 12:24:17.094648"], ["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by Crono::JobsController#show as HTML + Parameters: {"id"=>"1"} + Crono::CronoJob Load (0.0ms) SELECT "crono_jobs".* FROM "crono_jobs" WHERE "crono_jobs"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] + Rendering layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb + Rendering /Users/dima/dev/crono/app/views/crono/jobs/show.html.erb within layouts/crono/application + Rendered /Users/dima/dev/crono/app/views/crono/jobs/show.html.erb within layouts/crono/application (Duration: 0.0ms | Allocations: 46) + Rendered layout /Users/dima/dev/crono/app/views/layouts/crono/application.html.erb (Duration: 0.5ms | Allocations: 1471) +Completed 200 OK in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms | Allocations: 1960) + TRANSACTION (0.0ms) SAVEPOINT active_record_1 + Crono::CronoJob Destroy (0.0ms) DELETE FROM "crono_jobs" WHERE "crono_jobs"."id" = ? [["id", 1]] + TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 + TRANSACTION (0.2ms) rollback transaction