test/dummy/log/test.log in sidejobs-4.0.0.0 vs test/dummy/log/test.log in sidejobs-4.0.0.1

- old
+ new

@@ -776,5 +776,1685 @@  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SELECT COUNT(*) FROM "sidejobs" WHERE "sidejobs"."status" = $1 [["status", "complete"]]  (0.2ms) SELECT COUNT(*) FROM "sidejobs" WHERE "sidejobs"."status" = $1 [["status", "processing"]]  (0.2ms) SELECT COUNT(*) FROM "sidejobs" WHERE "sidejobs"."status" = $1 [["status", "pending"]]  (0.1ms) ROLLBACK + ActiveRecord::SchemaMigration Load (2.6ms) SELECT "schema_migrations".* FROM "schema_migrations" +  (0.2ms) BEGIN +------------------------ +DaemonTest: test_process +------------------------ + Sidejobs::Job Load (4.4ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-11 05:28:01.075226' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 + Sidejobs::Job Load (1.1ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-11 05:28:05.084421' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 + ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations" +  (0.3ms) BEGIN +------------------------ +DaemonTest: test_process +------------------------ + Sidejobs::Job Load (0.5ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-11 05:28:20.098248' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 + Sidejobs::Job Load (1.2ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-11 05:28:23.313842' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 +  (0.1ms) BEGIN +------------------------ +DaemonTest: test_pulling +------------------------ +  (0.2ms) ROLLBACK +  (0.1ms) BEGIN +----------------------- +QueueTest: test_enqueue +----------------------- +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (22.8ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"9652df1f-ed6f-49de-9df1-3592e561b6aa\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.335531"], ["updated_at", "2016-12-11 05:28:43.335531"]] +[ActiveJob]  (0.2ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 9652df1f-ed6f-49de-9df1-3592e561b6aa) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" + Sidejobs::Job Load (0.5ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +[ActiveJob]  (0.2ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.3ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ChargeSubscriptionsJob\",\"job_id\":\"70fa2f43-afc8-4913-b08e-4d11e2b336dd\",\"queue_name\":\"high_priority\",\"arguments\":[],\"locale\":\"en\"}"], ["queue", "high_priority"], ["priority", 60], ["created_at", "2016-12-11 05:28:43.366191"], ["updated_at", "2016-12-11 05:28:43.366191"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ChargeSubscriptionsJob (Job ID: 70fa2f43-afc8-4913-b08e-4d11e2b336dd) to Sidejobs(high_priority) + Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"SendNewslettersJob\",\"job_id\":\"7523fbe7-f4e0-48d5-90d0-f45e99fe34d9\",\"queue_name\":\"default\",\"arguments\":[],\"locale\":\"en\"}"], ["queue", "default"], ["priority", 40], ["created_at", "2016-12-11 05:28:43.370433"], ["updated_at", "2016-12-11 05:28:43.370433"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued SendNewslettersJob (Job ID: 7523fbe7-f4e0-48d5-90d0-f45e99fe34d9) to Sidejobs(default) + Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +[ActiveJob]  (0.2ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"UpdateExchangesJob\",\"job_id\":\"a43e349e-1c52-474d-8f43-2a6c2c104981\",\"queue_name\":\"low_priority\",\"arguments\":[],\"locale\":\"en\"}"], ["queue", "low_priority"], ["priority", 20], ["created_at", "2016-12-11 05:28:43.374460"], ["updated_at", "2016-12-11 05:28:43.374460"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued UpdateExchangesJob (Job ID: a43e349e-1c52-474d-8f43-2a6c2c104981) to Sidejobs(low_priority) + Sidejobs::Job Load (0.2ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["data", "{\"job_class\":\"ClearGuestUsersJob\",\"job_id\":\"c6990fb9-ee95-4288-9bd6-3c227bcf3269\",\"queue_name\":\"other\",\"arguments\":[],\"locale\":\"en\"}"], ["queue", "other"], ["created_at", "2016-12-11 05:28:43.378107"], ["updated_at", "2016-12-11 05:28:43.378107"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ClearGuestUsersJob (Job ID: c6990fb9-ee95-4288-9bd6-3c227bcf3269) to Sidejobs(other) + Sidejobs::Job Load (0.2ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +[ActiveJob]  (0.2ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "scheduled_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5, $6) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"d9619ad6-264f-4f62-a6d0-3d5babb0ac61\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["scheduled_at", "2016-12-11 15:28:43.386530"], ["created_at", "2016-12-11 05:28:43.387932"], ["updated_at", "2016-12-11 05:28:43.387932"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: d9619ad6-264f-4f62-a6d0-3d5babb0ac61) to Sidejobs(mailers) at 2016-12-11 15:28:43 UTC with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" + Sidejobs::Job Load (0.2ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.3ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "scheduled_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5, $6) RETURNING "id" [["data", "{\"job_class\":\"ChargeSubscriptionsJob\",\"job_id\":\"8d537c48-d522-4342-918c-7013878a446e\",\"queue_name\":\"high_priority\",\"arguments\":[],\"locale\":\"en\"}"], ["queue", "high_priority"], ["priority", 60], ["scheduled_at", "2016-12-11 15:28:43.386530"], ["created_at", "2016-12-11 05:28:43.391224"], ["updated_at", "2016-12-11 05:28:43.391224"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ChargeSubscriptionsJob (Job ID: 8d537c48-d522-4342-918c-7013878a446e) to Sidejobs(high_priority) at 2016-12-11 15:28:43 UTC + Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.3ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "scheduled_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5, $6) RETURNING "id" [["data", "{\"job_class\":\"SendNewslettersJob\",\"job_id\":\"034e09a8-0eec-447a-88f0-2b811dccd934\",\"queue_name\":\"default\",\"arguments\":[],\"locale\":\"en\"}"], ["queue", "default"], ["priority", 40], ["scheduled_at", "2016-12-11 15:28:43.386530"], ["created_at", "2016-12-11 05:28:43.394714"], ["updated_at", "2016-12-11 05:28:43.394714"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued SendNewslettersJob (Job ID: 034e09a8-0eec-447a-88f0-2b811dccd934) to Sidejobs(default) at 2016-12-11 15:28:43 UTC + Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +[ActiveJob]  (0.2ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.3ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "scheduled_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5, $6) RETURNING "id" [["data", "{\"job_class\":\"UpdateExchangesJob\",\"job_id\":\"dc0b46c7-c3d6-47c8-9f4d-8c2e5f02bc4a\",\"queue_name\":\"low_priority\",\"arguments\":[],\"locale\":\"en\"}"], ["queue", "low_priority"], ["priority", 20], ["scheduled_at", "2016-12-11 15:28:43.386530"], ["created_at", "2016-12-11 05:28:43.398431"], ["updated_at", "2016-12-11 05:28:43.398431"]] +[ActiveJob]  (0.2ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued UpdateExchangesJob (Job ID: dc0b46c7-c3d6-47c8-9f4d-8c2e5f02bc4a) to Sidejobs(low_priority) at 2016-12-11 15:28:43 UTC + Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.3ms) INSERT INTO "sidejobs" ("data", "queue", "scheduled_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ClearGuestUsersJob\",\"job_id\":\"8ebf2ce1-db1d-48ac-8b75-749557206d29\",\"queue_name\":\"other\",\"arguments\":[],\"locale\":\"en\"}"], ["queue", "other"], ["scheduled_at", "2016-12-11 15:28:43.386530"], ["created_at", "2016-12-11 05:28:43.402263"], ["updated_at", "2016-12-11 05:28:43.402263"]] +[ActiveJob]  (0.2ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ClearGuestUsersJob (Job ID: 8ebf2ce1-db1d-48ac-8b75-749557206d29) to Sidejobs(other) at 2016-12-11 15:28:43 UTC + Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +  (0.2ms) ROLLBACK +  (0.1ms) BEGIN +------------------------- +ProcessorTest: test_batch +------------------------- +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"d31c0400-23f5-47ac-8e25-9ba4fdaa5bf8\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.407327"], ["updated_at", "2016-12-11 05:28:43.407327"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: d31c0400-23f5-47ac-8e25-9ba4fdaa5bf8) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.3ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"7ad3ec88-b52b-47c8-8255-b21455cbe079\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.409540"], ["updated_at", "2016-12-11 05:28:43.409540"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 7ad3ec88-b52b-47c8-8255-b21455cbe079) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"e982290c-9173-4dd7-ba14-a6aa2e478f7e\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.412223"], ["updated_at", "2016-12-11 05:28:43.412223"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: e982290c-9173-4dd7-ba14-a6aa2e478f7e) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"45046116-7d7f-4210-8ed6-9b689adef59d\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.414529"], ["updated_at", "2016-12-11 05:28:43.414529"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 45046116-7d7f-4210-8ed6-9b689adef59d) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"bcc33a4b-a11b-4df8-a6f0-189003655e2b\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.416611"], ["updated_at", "2016-12-11 05:28:43.416611"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: bcc33a4b-a11b-4df8-a6f0-189003655e2b) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"50fe348e-158e-4a29-a908-2130a0942ff1\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.418631"], ["updated_at", "2016-12-11 05:28:43.418631"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 50fe348e-158e-4a29-a908-2130a0942ff1) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"a26c25f7-9094-4e33-b3fc-2e90f90f0bc2\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.420703"], ["updated_at", "2016-12-11 05:28:43.420703"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: a26c25f7-9094-4e33-b3fc-2e90f90f0bc2) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"ec99cdd6-8c19-42e1-9c25-73c4f59a7542\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.422852"], ["updated_at", "2016-12-11 05:28:43.422852"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: ec99cdd6-8c19-42e1-9c25-73c4f59a7542) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.2ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"9a63e6b9-3676-4bf5-abbb-ecaab6dffffe\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.424926"], ["updated_at", "2016-12-11 05:28:43.424926"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 9a63e6b9-3676-4bf5-abbb-ecaab6dffffe) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"607abc0c-66fc-4ce9-81e8-68e2397c8294\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.426932"], ["updated_at", "2016-12-11 05:28:43.426932"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 607abc0c-66fc-4ce9-81e8-68e2397c8294) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"84369914-8d7f-4dc4-add1-893c5eaf5618\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.429083"], ["updated_at", "2016-12-11 05:28:43.429083"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 84369914-8d7f-4dc4-add1-893c5eaf5618) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"7fee8452-a8af-426f-8de9-ba859305d126\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.431246"], ["updated_at", "2016-12-11 05:28:43.431246"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 7fee8452-a8af-426f-8de9-ba859305d126) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"a1e1fb49-45be-418d-a1f9-eb24df8b3313\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.433404"], ["updated_at", "2016-12-11 05:28:43.433404"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: a1e1fb49-45be-418d-a1f9-eb24df8b3313) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"23f35e75-35bb-4537-8a0e-ac3bf2a71745\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.435506"], ["updated_at", "2016-12-11 05:28:43.435506"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 23f35e75-35bb-4537-8a0e-ac3bf2a71745) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"82ec2978-02ba-4aa6-a733-4f3c5dfe4403\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.437514"], ["updated_at", "2016-12-11 05:28:43.437514"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 82ec2978-02ba-4aa6-a733-4f3c5dfe4403) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"ca0a43b4-809f-46ee-a6a5-113fcf765999\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.439742"], ["updated_at", "2016-12-11 05:28:43.439742"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: ca0a43b4-809f-46ee-a6a5-113fcf765999) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"e5736afa-83a2-4dc5-9695-507dbd7208e2\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.441811"], ["updated_at", "2016-12-11 05:28:43.441811"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: e5736afa-83a2-4dc5-9695-507dbd7208e2) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"f273d577-c349-4926-9b99-598397b95f54\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.443834"], ["updated_at", "2016-12-11 05:28:43.443834"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: f273d577-c349-4926-9b99-598397b95f54) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.3ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"e4fec455-7004-4f6d-90ee-8cce00b964c7\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.445932"], ["updated_at", "2016-12-11 05:28:43.445932"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: e4fec455-7004-4f6d-90ee-8cce00b964c7) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"194de21a-db39-471e-b8df-985d7dd488fa\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.448124"], ["updated_at", "2016-12-11 05:28:43.448124"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 194de21a-db39-471e-b8df-985d7dd488fa) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"652748f1-fb91-44bb-87d0-427b4129a02c\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.450122"], ["updated_at", "2016-12-11 05:28:43.450122"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 652748f1-fb91-44bb-87d0-427b4129a02c) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"b7a74d30-0608-48bd-9f2b-18b0f7342834\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.452107"], ["updated_at", "2016-12-11 05:28:43.452107"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: b7a74d30-0608-48bd-9f2b-18b0f7342834) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"5bebfecc-1d2a-4f47-819d-4dd4cba11a62\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.454127"], ["updated_at", "2016-12-11 05:28:43.454127"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 5bebfecc-1d2a-4f47-819d-4dd4cba11a62) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"3b019d07-3ccc-4c7c-b268-8ce25271891c\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.456123"], ["updated_at", "2016-12-11 05:28:43.456123"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 3b019d07-3ccc-4c7c-b268-8ce25271891c) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" + Sidejobs::Job Load (0.8ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-11 05:28:43.459184' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.460912"], ["attempts", 1], ["updated_at", "2016-12-11 05:28:43.461595"], ["id", 674]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (1.0ms) + +UserMailer#invite: processed outbound mail in 153.9ms + +Sent mail to test@mail.com (7.4ms) +Date: Sun, 11 Dec 2016 02:28:43 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <584ce40b99b38_36633fefbc43f9f4617d7@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.2ms) SAVEPOINT active_record_1 + SQL (0.3ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-11 05:28:43.632262"], ["updated_at", "2016-12-11 05:28:43.633125"], ["id", 674]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.634855"], ["attempts", 1], ["updated_at", "2016-12-11 05:28:43.635457"], ["id", 675]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.8ms + +Sent mail to test@mail.com (1.1ms) +Date: Sun, 11 Dec 2016 02:28:43 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <584ce40b9be5a_36633fefbc43f9f46184e@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-11 05:28:43.639209"], ["updated_at", "2016-12-11 05:28:43.639990"], ["id", 675]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.641563"], ["attempts", 1], ["updated_at", "2016-12-11 05:28:43.642165"], ["id", 676]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.7ms + +Sent mail to test@mail.com (1.3ms) +Date: Sun, 11 Dec 2016 02:28:43 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <584ce40b9d8ca_36633fefbc43f9f4619e0@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-11 05:28:43.646076"], ["updated_at", "2016-12-11 05:28:43.646691"], ["id", 676]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.648154"], ["attempts", 1], ["updated_at", "2016-12-11 05:28:43.648776"], ["id", 677]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.7ms + +Sent mail to test@mail.com (1.2ms) +Date: Sun, 11 Dec 2016 02:28:43 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <584ce40b9f22a_36633fefbc43f9f462087@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-11 05:28:43.652524"], ["updated_at", "2016-12-11 05:28:43.653097"], ["id", 677]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.654456"], ["attempts", 1], ["updated_at", "2016-12-11 05:28:43.655065"], ["id", 678]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.7ms + +Sent mail to test@mail.com (1.3ms) +Date: Sun, 11 Dec 2016 02:28:43 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <584ce40ba0ad4_36633fefbc43f9f46214e@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.2ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-11 05:28:43.658846"], ["updated_at", "2016-12-11 05:28:43.659477"], ["id", 678]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.660820"], ["attempts", 1], ["updated_at", "2016-12-11 05:28:43.661447"], ["id", 679]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.7ms + +Sent mail to test@mail.com (1.1ms) +Date: Sun, 11 Dec 2016 02:28:43 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <584ce40ba2314_36633fefbc43f9f462278@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-11 05:28:43.665021"], ["updated_at", "2016-12-11 05:28:43.665682"], ["id", 679]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.667043"], ["attempts", 1], ["updated_at", "2016-12-11 05:28:43.667644"], ["id", 680]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.7ms + +Sent mail to test@mail.com (1.1ms) +Date: Sun, 11 Dec 2016 02:28:43 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <584ce40ba3b29_36633fefbc43f9f462372@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-11 05:28:43.671203"], ["updated_at", "2016-12-11 05:28:43.671853"], ["id", 680]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.673156"], ["attempts", 1], ["updated_at", "2016-12-11 05:28:43.673795"], ["id", 681]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.1ms) +Date: Sun, 11 Dec 2016 02:28:43 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <584ce40ba530d_36633fefbc43f9f4624d3@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-11 05:28:43.677296"], ["updated_at", "2016-12-11 05:28:43.677867"], ["id", 681]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.679059"], ["attempts", 1], ["updated_at", "2016-12-11 05:28:43.679612"], ["id", 682]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.0ms) +Date: Sun, 11 Dec 2016 02:28:43 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <584ce40ba6911_36633fefbc43f9f462594@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-11 05:28:43.682892"], ["updated_at", "2016-12-11 05:28:43.683508"], ["id", 682]] +  (0.2ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.684797"], ["attempts", 1], ["updated_at", "2016-12-11 05:28:43.685373"], ["id", 683]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.0ms) +Date: Sun, 11 Dec 2016 02:28:43 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <584ce40ba7fa2_36633fefbc43f9f4626fa@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.2ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-11 05:28:43.688661"], ["updated_at", "2016-12-11 05:28:43.689283"], ["id", 683]] +  (0.2ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.690543"], ["attempts", 1], ["updated_at", "2016-12-11 05:28:43.691085"], ["id", 684]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.0ms) +Date: Sun, 11 Dec 2016 02:28:43 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <584ce40ba958f_36633fefbc43f9f462718@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-11 05:28:43.694254"], ["updated_at", "2016-12-11 05:28:43.694844"], ["id", 684]] +  (0.2ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.696039"], ["attempts", 1], ["updated_at", "2016-12-11 05:28:43.696620"], ["id", 685]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.0ms) +Date: Sun, 11 Dec 2016 02:28:43 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <584ce40baabc5_36633fefbc43f9f4628c3@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-11 05:28:43.699992"], ["updated_at", "2016-12-11 05:28:43.700620"], ["id", 685]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.701901"], ["attempts", 1], ["updated_at", "2016-12-11 05:28:43.702448"], ["id", 686]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.0ms) +Date: Sun, 11 Dec 2016 02:28:43 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <584ce40bac225_36633fefbc43f9f4629e9@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-11 05:28:43.705684"], ["updated_at", "2016-12-11 05:28:43.706248"], ["id", 686]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.707425"], ["attempts", 1], ["updated_at", "2016-12-11 05:28:43.707976"], ["id", 687]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.0ms) +Date: Sun, 11 Dec 2016 02:28:43 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <584ce40bad787_36633fefbc43f9f4630c8@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-11 05:28:43.711142"], ["updated_at", "2016-12-11 05:28:43.711671"], ["id", 687]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.712912"], ["attempts", 1], ["updated_at", "2016-12-11 05:28:43.713497"], ["id", 688]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.0ms) +Date: Sun, 11 Dec 2016 02:28:43 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <584ce40baed3c_36633fefbc43f9f463158@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-11 05:28:43.716705"], ["updated_at", "2016-12-11 05:28:43.717233"], ["id", 688]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.718375"], ["attempts", 1], ["updated_at", "2016-12-11 05:28:43.718920"], ["id", 689]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.1ms) +Date: Sun, 11 Dec 2016 02:28:43 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <584ce40bb0216_36633fefbc43f9f46325c@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-11 05:28:43.722063"], ["updated_at", "2016-12-11 05:28:43.722580"], ["id", 689]] +  (0.2ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.723756"], ["attempts", 1], ["updated_at", "2016-12-11 05:28:43.724310"], ["id", 690]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.7ms + +Sent mail to test@mail.com (1.1ms) +Date: Sun, 11 Dec 2016 02:28:43 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <584ce40bb18c6_36633fefbc43f9f4633d0@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.2ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-11 05:28:43.727911"], ["updated_at", "2016-12-11 05:28:43.728552"], ["id", 690]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.729772"], ["attempts", 1], ["updated_at", "2016-12-11 05:28:43.730334"], ["id", 691]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.1ms) +Date: Sun, 11 Dec 2016 02:28:43 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <584ce40bb2f61_36633fefbc43f9f46341d@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-11 05:28:43.733681"], ["updated_at", "2016-12-11 05:28:43.734235"], ["id", 691]] +  (0.2ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.735400"], ["attempts", 1], ["updated_at", "2016-12-11 05:28:43.736004"], ["id", 692]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.1ms) +Date: Sun, 11 Dec 2016 02:28:43 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <584ce40bb453e_36633fefbc43f9f46356d@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-11 05:28:43.739305"], ["updated_at", "2016-12-11 05:28:43.739837"], ["id", 692]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.740986"], ["attempts", 1], ["updated_at", "2016-12-11 05:28:43.741556"], ["id", 693]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.2ms) +Date: Sun, 11 Dec 2016 02:28:43 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <584ce40bb5acb_36633fefbc43f9f4636d7@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-11 05:28:43.744856"], ["updated_at", "2016-12-11 05:28:43.745391"], ["id", 693]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.3ms) SELECT COUNT(*) FROM "sidejobs" WHERE "sidejobs"."status" = $1 [["status", "complete"]] +  (0.2ms) SELECT COUNT(*) FROM "sidejobs" WHERE "sidejobs"."status" = $1 [["status", "processing"]] +  (0.2ms) SELECT COUNT(*) FROM "sidejobs" WHERE "sidejobs"."status" = $1 [["status", "pending"]] + Sidejobs::Job Load (0.2ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.749571"], ["updated_at", "2016-12-11 05:28:43.750414"], ["id", 697]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Sidejobs::Job Load (0.4ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-11 05:28:43.752089' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.752939"], ["attempts", 1], ["updated_at", "2016-12-11 05:28:43.753532"], ["id", 694]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.7ms + +Sent mail to test@mail.com (1.2ms) +Date: Sun, 11 Dec 2016 02:28:43 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <584ce40bb8ae3_36633fefbc43f9f463740@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-11 05:28:43.757139"], ["updated_at", "2016-12-11 05:28:43.757695"], ["id", 694]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.758937"], ["attempts", 1], ["updated_at", "2016-12-11 05:28:43.759531"], ["id", 695]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.1ms) +Date: Sun, 11 Dec 2016 02:28:43 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <584ce40bba127_36633fefbc43f9f4638c6@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-11 05:28:43.762786"], ["updated_at", "2016-12-11 05:28:43.763320"], ["id", 695]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.764549"], ["attempts", 1], ["updated_at", "2016-12-11 05:28:43.765116"], ["id", 696]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.7ms + +Sent mail to test@mail.com (1.1ms) +Date: Sun, 11 Dec 2016 02:28:43 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <584ce40bbb73a_36633fefbc43f9f463921@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-11 05:28:43.768457"], ["updated_at", "2016-12-11 05:28:43.768978"], ["id", 696]] +  (0.2ms) RELEASE SAVEPOINT active_record_1 +  (0.2ms) SELECT COUNT(*) FROM "sidejobs" WHERE "sidejobs"."status" = $1 [["status", "complete"]] +  (0.2ms) SELECT COUNT(*) FROM "sidejobs" WHERE "sidejobs"."status" = $1 [["status", "processing"]] +  (0.2ms) SELECT COUNT(*) FROM "sidejobs" WHERE "sidejobs"."status" = $1 [["status", "pending"]] +  (0.1ms) ROLLBACK +  (0.1ms) BEGIN +--------------------------- +ProcessorTest: test_retries +--------------------------- +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ShareProductsJob\",\"job_id\":\"31245125-e0f6-44d6-a511-9218231e0f24\",\"queue_name\":\"default\",\"arguments\":[],\"locale\":\"en\"}"], ["queue", "default"], ["priority", 40], ["created_at", "2016-12-11 05:28:43.773911"], ["updated_at", "2016-12-11 05:28:43.773911"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ShareProductsJob (Job ID: 31245125-e0f6-44d6-a511-9218231e0f24) to Sidejobs(default) + Sidejobs::Job Load (0.4ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-11 05:28:43.775478' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.776530"], ["attempts", 1], ["updated_at", "2016-12-11 05:28:43.777224"], ["id", 698]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "failed_at" = $2, "error" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "failing"], ["failed_at", "2016-12-11 05:28:43.778860"], ["error", "Social network unavailable"], ["updated_at", "2016-12-11 05:28:43.779424"], ["id", 698]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-11 05:28:43.780785' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.781501"], ["attempts", 2], ["updated_at", "2016-12-11 05:28:43.782143"], ["id", 698]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "failed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "failing"], ["failed_at", "2016-12-11 05:28:43.783408"], ["updated_at", "2016-12-11 05:28:43.783895"], ["id", 698]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-11 05:28:43.785245' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.785983"], ["attempts", 3], ["updated_at", "2016-12-11 05:28:43.786611"], ["id", 698]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "failed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "failing"], ["failed_at", "2016-12-11 05:28:43.788074"], ["updated_at", "2016-12-11 05:28:43.788591"], ["id", 698]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-11 05:28:43.789741' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 + Sidejobs::Job Load (0.2ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +  (0.2ms) ROLLBACK +  (0.1ms) BEGIN +--------------------------- +ProcessorTest: test_process +--------------------------- +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"15ca2775-6ca8-4d14-a470-fa0f3ea99336\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-11 05:28:43.792389"], ["updated_at", "2016-12-11 05:28:43.792389"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 15ca2775-6ca8-4d14-a470-fa0f3ea99336) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" + Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-11 05:28:43.793881' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-11 05:28:43.794619"], ["attempts", 1], ["updated_at", "2016-12-11 05:28:43.795226"], ["id", 699]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.8ms + +Sent mail to test@mail.com (1.2ms) +Date: Sun, 11 Dec 2016 02:28:43 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <584ce40bc2e69_36633fefbc43f9f46404@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-11 05:28:43.799008"], ["updated_at", "2016-12-11 05:28:43.799639"], ["id", 699]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Sidejobs::Job Load (0.2ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +  (0.1ms) ROLLBACK +  (0.1ms) BEGIN +---------------------------- +GeneratorsTest: test_install +---------------------------- +  (0.2ms) ROLLBACK +  (13.9ms) CREATE TABLE "schema_migrations" ("version" character varying NOT NULL)  +  (23.1ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version") + ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations" +Migrating to CreateSidejobs (20161022211612) +  (0.2ms) BEGIN +  (14.2ms) CREATE TABLE "sidejobs" ("id" serial primary key, "data" jsonb, "queue" character varying, "status" character varying DEFAULT 'pending', "priority" integer DEFAULT 0, "attempts" integer DEFAULT 0, "error" text, "failed_at" timestamp, "completed_at" timestamp, "processed_at" timestamp, "scheduled_at" timestamp, "created_at" timestamp NOT NULL, "updated_at" timestamp NOT NULL)  +  (6.8ms) CREATE INDEX "index_sidejobs_on_status_and_scheduled_at" ON "sidejobs" ("status", "scheduled_at") +  (0.7ms) CREATE INDEX "index_sidejobs_on_priority" ON "sidejobs" ("priority") + SQL (0.3ms) INSERT INTO "schema_migrations" ("version") VALUES ($1) [["version", "20161022211612"]] +  (6.4ms) COMMIT + ActiveRecord::SchemaMigration Load (0.2ms) SELECT "schema_migrations".* FROM "schema_migrations" +  (1.8ms) SELECT t2.oid::regclass::text AS to_table, a1.attname AS column, a2.attname AS primary_key, c.conname AS name, c.confupdtype AS on_update, c.confdeltype AS on_delete +FROM pg_constraint c +JOIN pg_class t1 ON c.conrelid = t1.oid +JOIN pg_class t2 ON c.confrelid = t2.oid +JOIN pg_attribute a1 ON a1.attnum = c.conkey[1] AND a1.attrelid = t1.oid +JOIN pg_attribute a2 ON a2.attnum = c.confkey[1] AND a2.attrelid = t2.oid +JOIN pg_namespace t3 ON c.connamespace = t3.oid +WHERE c.contype = 'f' + AND t1.relname = 'sidejobs' + AND t3.nspname = ANY (current_schemas(false)) +ORDER BY c.conname + +  (167.3ms) DROP DATABASE IF EXISTS "sidejobs_test" +  (368.8ms) CREATE DATABASE "sidejobs_test" ENCODING = 'utf8' + SQL (0.4ms) CREATE EXTENSION IF NOT EXISTS "plpgsql" +  (4.4ms) CREATE TABLE "sidejobs" ("id" serial primary key, "data" jsonb, "queue" character varying, "status" character varying DEFAULT 'pending', "priority" integer DEFAULT 0, "attempts" integer DEFAULT 0, "error" text, "failed_at" timestamp, "completed_at" timestamp, "processed_at" timestamp, "scheduled_at" timestamp, "created_at" timestamp NOT NULL, "updated_at" timestamp NOT NULL) +  (1.0ms) CREATE INDEX "index_sidejobs_on_priority" ON "sidejobs" USING btree ("priority") +  (1.1ms) CREATE INDEX "index_sidejobs_on_status_and_scheduled_at" ON "sidejobs" USING btree ("status", "scheduled_at") +  (1.4ms) CREATE TABLE "schema_migrations" ("version" character varying NOT NULL)  +  (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version") +  (0.2ms) SELECT version FROM "schema_migrations" +  (0.4ms) INSERT INTO "schema_migrations" (version) VALUES ('20161022211612') + ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations" +  (0.2ms) BEGIN +------------------------ +DaemonTest: test_process +------------------------ +  (0.2ms) BEGIN +------------------------ +DaemonTest: test_pulling +------------------------ +  (0.2ms) ROLLBACK +  (0.1ms) BEGIN +------------------------- +ProcessorTest: test_batch +------------------------- +  (0.2ms) ROLLBACK +  (0.1ms) BEGIN +--------------------------- +ProcessorTest: test_process +--------------------------- +[ActiveJob] [ActionMailer::DeliveryJob] [9588d0a6-4cbd-4435-82d3-0ce740c3f012] Performing ActionMailer::DeliveryJob from Inline(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob] [ActionMailer::DeliveryJob] [9588d0a6-4cbd-4435-82d3-0ce740c3f012] Rendered user_mailer/invite.text.erb (1.1ms) +[ActiveJob] [ActionMailer::DeliveryJob] [9588d0a6-4cbd-4435-82d3-0ce740c3f012] +UserMailer#invite: processed outbound mail in 159.5ms +[ActiveJob] [ActionMailer::DeliveryJob] [9588d0a6-4cbd-4435-82d3-0ce740c3f012] +Sent mail to test@mail.com (6.9ms) +[ActiveJob] [ActionMailer::DeliveryJob] [9588d0a6-4cbd-4435-82d3-0ce740c3f012] Date: Tue, 13 Dec 2016 17:23:19 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058b7a156a_103173fcbfc43fa14809c2@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +[ActiveJob] [ActionMailer::DeliveryJob] [9588d0a6-4cbd-4435-82d3-0ce740c3f012] Performed ActionMailer::DeliveryJob from Inline(mailers) in 171.6ms +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 9588d0a6-4cbd-4435-82d3-0ce740c3f012) to Inline(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" + Sidejobs::Job Load (1.1ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-13 20:23:19.667757' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 + Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +  (0.1ms) ROLLBACK +  (0.1ms) BEGIN +--------------------------- +ProcessorTest: test_retries +--------------------------- +[ActiveJob] [ShareProductsJob] [d7bcb85f-4984-41ac-83ed-f07bfac02f12] Performing ShareProductsJob from Inline(default) +[ActiveJob] [ShareProductsJob] [d7bcb85f-4984-41ac-83ed-f07bfac02f12] Performed ShareProductsJob from Inline(default) in 0.19ms +  (0.2ms) ROLLBACK +  (0.1ms) BEGIN +---------------------------- +GeneratorsTest: test_install +---------------------------- +  (0.2ms) ROLLBACK +  (0.1ms) BEGIN +----------------------- +QueueTest: test_enqueue +----------------------- +[ActiveJob] [ActionMailer::DeliveryJob] [489f871a-9b19-4d2a-a6ae-ca6e92ac9a13] Performing ActionMailer::DeliveryJob from Inline(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob] [ActionMailer::DeliveryJob] [489f871a-9b19-4d2a-a6ae-ca6e92ac9a13] Rendered user_mailer/invite.text.erb (0.0ms) +[ActiveJob] [ActionMailer::DeliveryJob] [489f871a-9b19-4d2a-a6ae-ca6e92ac9a13] +UserMailer#invite: processed outbound mail in 0.9ms +[ActiveJob] [ActionMailer::DeliveryJob] [489f871a-9b19-4d2a-a6ae-ca6e92ac9a13] +Sent mail to test@mail.com (1.3ms) +[ActiveJob] [ActionMailer::DeliveryJob] [489f871a-9b19-4d2a-a6ae-ca6e92ac9a13] Date: Tue, 13 Dec 2016 17:23:19 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058b7a7b12_103173fcbfc43fa14810c4@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +[ActiveJob] [ActionMailer::DeliveryJob] [489f871a-9b19-4d2a-a6ae-ca6e92ac9a13] Performed ActionMailer::DeliveryJob from Inline(mailers) in 2.47ms +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 489f871a-9b19-4d2a-a6ae-ca6e92ac9a13) to Inline(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" + Sidejobs::Job Load (0.4ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +  (0.1ms) ROLLBACK + ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations" +  (0.2ms) BEGIN +------------------------ +DaemonTest: test_process +------------------------ + Sidejobs::Job Load (1.2ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-13 20:24:04.158159' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 + Sidejobs::Job Load (1.3ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-13 20:24:08.196338' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 +  (0.2ms) BEGIN +------------------------ +DaemonTest: test_pulling +------------------------ +  (0.2ms) ROLLBACK +  (0.1ms) BEGIN +----------------------- +QueueTest: test_enqueue +----------------------- +[ActiveJob]  (0.2ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.7ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"c423a886-5aa8-4c1b-95ae-5b3988443b51\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.197425"], ["updated_at", "2016-12-13 20:24:28.197425"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: c423a886-5aa8-4c1b-95ae-5b3988443b51) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" + Sidejobs::Job Load (0.5ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +[ActiveJob]  (0.2ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.3ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ChargeSubscriptionsJob\",\"job_id\":\"7fe5a591-9a9d-4a5a-8c3b-3606dc599814\",\"queue_name\":\"high_priority\",\"arguments\":[],\"locale\":\"en\"}"], ["queue", "high_priority"], ["priority", 60], ["created_at", "2016-12-13 20:24:28.206063"], ["updated_at", "2016-12-13 20:24:28.206063"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ChargeSubscriptionsJob (Job ID: 7fe5a591-9a9d-4a5a-8c3b-3606dc599814) to Sidejobs(high_priority) + Sidejobs::Job Load (0.2ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"SendNewslettersJob\",\"job_id\":\"c7b95b1d-cf00-4db7-8467-dcada2412b5a\",\"queue_name\":\"default\",\"arguments\":[],\"locale\":\"en\"}"], ["queue", "default"], ["priority", 40], ["created_at", "2016-12-13 20:24:28.209963"], ["updated_at", "2016-12-13 20:24:28.209963"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued SendNewslettersJob (Job ID: c7b95b1d-cf00-4db7-8467-dcada2412b5a) to Sidejobs(default) + Sidejobs::Job Load (0.2ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"UpdateExchangesJob\",\"job_id\":\"51813574-8ae0-459c-becd-c38b544179c8\",\"queue_name\":\"low_priority\",\"arguments\":[],\"locale\":\"en\"}"], ["queue", "low_priority"], ["priority", 20], ["created_at", "2016-12-13 20:24:28.214123"], ["updated_at", "2016-12-13 20:24:28.214123"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued UpdateExchangesJob (Job ID: 51813574-8ae0-459c-becd-c38b544179c8) to Sidejobs(low_priority) + Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id" [["data", "{\"job_class\":\"ClearGuestUsersJob\",\"job_id\":\"7030e029-5966-4159-8724-b656963e7831\",\"queue_name\":\"other\",\"arguments\":[],\"locale\":\"en\"}"], ["queue", "other"], ["created_at", "2016-12-13 20:24:28.223048"], ["updated_at", "2016-12-13 20:24:28.223048"]] +[ActiveJob]  (0.2ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ClearGuestUsersJob (Job ID: 7030e029-5966-4159-8724-b656963e7831) to Sidejobs(other) + Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "scheduled_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5, $6) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"753aba9b-a575-4c1f-9c4d-3162e7fe3564\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["scheduled_at", "2016-12-14 06:24:28.225541"], ["created_at", "2016-12-13 20:24:28.226395"], ["updated_at", "2016-12-13 20:24:28.226395"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 753aba9b-a575-4c1f-9c4d-3162e7fe3564) to Sidejobs(mailers) at 2016-12-14 06:24:28 UTC with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" + Sidejobs::Job Load (0.2ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "scheduled_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5, $6) RETURNING "id" [["data", "{\"job_class\":\"ChargeSubscriptionsJob\",\"job_id\":\"e0e35491-9424-4e6a-bab1-bbd46794c259\",\"queue_name\":\"high_priority\",\"arguments\":[],\"locale\":\"en\"}"], ["queue", "high_priority"], ["priority", 60], ["scheduled_at", "2016-12-14 06:24:28.225541"], ["created_at", "2016-12-13 20:24:28.229297"], ["updated_at", "2016-12-13 20:24:28.229297"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ChargeSubscriptionsJob (Job ID: e0e35491-9424-4e6a-bab1-bbd46794c259) to Sidejobs(high_priority) at 2016-12-14 06:24:28 UTC + Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "scheduled_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5, $6) RETURNING "id" [["data", "{\"job_class\":\"SendNewslettersJob\",\"job_id\":\"c9f1d9b9-34e0-47e6-9c61-166d373b0d4f\",\"queue_name\":\"default\",\"arguments\":[],\"locale\":\"en\"}"], ["queue", "default"], ["priority", 40], ["scheduled_at", "2016-12-14 06:24:28.225541"], ["created_at", "2016-12-13 20:24:28.232092"], ["updated_at", "2016-12-13 20:24:28.232092"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued SendNewslettersJob (Job ID: c9f1d9b9-34e0-47e6-9c61-166d373b0d4f) to Sidejobs(default) at 2016-12-14 06:24:28 UTC + Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "scheduled_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5, $6) RETURNING "id" [["data", "{\"job_class\":\"UpdateExchangesJob\",\"job_id\":\"91804713-9d59-4ab4-9734-8ca8d72711c1\",\"queue_name\":\"low_priority\",\"arguments\":[],\"locale\":\"en\"}"], ["queue", "low_priority"], ["priority", 20], ["scheduled_at", "2016-12-14 06:24:28.225541"], ["created_at", "2016-12-13 20:24:28.234816"], ["updated_at", "2016-12-13 20:24:28.234816"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued UpdateExchangesJob (Job ID: 91804713-9d59-4ab4-9734-8ca8d72711c1) to Sidejobs(low_priority) at 2016-12-14 06:24:28 UTC + Sidejobs::Job Load (0.2ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "scheduled_at", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ClearGuestUsersJob\",\"job_id\":\"68f02b80-31a0-4f83-9336-70bdfba07f09\",\"queue_name\":\"other\",\"arguments\":[],\"locale\":\"en\"}"], ["queue", "other"], ["scheduled_at", "2016-12-14 06:24:28.225541"], ["created_at", "2016-12-13 20:24:28.237507"], ["updated_at", "2016-12-13 20:24:28.237507"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ClearGuestUsersJob (Job ID: 68f02b80-31a0-4f83-9336-70bdfba07f09) to Sidejobs(other) at 2016-12-14 06:24:28 UTC + Sidejobs::Job Load (0.2ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +  (0.2ms) ROLLBACK +  (0.1ms) BEGIN +---------------------------- +GeneratorsTest: test_install +---------------------------- +  (0.2ms) ROLLBACK +  (0.1ms) BEGIN +------------------------- +ProcessorTest: test_batch +------------------------- +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"e36ae247-7725-4c13-9f67-3f2b012de651\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.246258"], ["updated_at", "2016-12-13 20:24:28.246258"]] +[ActiveJob]  (0.2ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: e36ae247-7725-4c13-9f67-3f2b012de651) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"92e643ed-7f63-48be-b77a-0d7a3f36e4a7\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.248630"], ["updated_at", "2016-12-13 20:24:28.248630"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 92e643ed-7f63-48be-b77a-0d7a3f36e4a7) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"76cbf4c5-4991-4b63-b8ed-bf8ce2c8ac45\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.251147"], ["updated_at", "2016-12-13 20:24:28.251147"]] +[ActiveJob]  (0.2ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 76cbf4c5-4991-4b63-b8ed-bf8ce2c8ac45) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"eece3fd9-dfaa-4ad8-9aa1-4e8f5c5e27ec\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.253808"], ["updated_at", "2016-12-13 20:24:28.253808"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: eece3fd9-dfaa-4ad8-9aa1-4e8f5c5e27ec) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"b6c9dbc8-253d-498d-a366-a078ba6fa567\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.256526"], ["updated_at", "2016-12-13 20:24:28.256526"]] +[ActiveJob]  (0.2ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: b6c9dbc8-253d-498d-a366-a078ba6fa567) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.2ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"3e161bb2-e2ee-411b-8889-807d8237aca5\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.259666"], ["updated_at", "2016-12-13 20:24:28.259666"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 3e161bb2-e2ee-411b-8889-807d8237aca5) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.2ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"1bdd02bf-1cb5-4154-b528-26c207dbb52c\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.262444"], ["updated_at", "2016-12-13 20:24:28.262444"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 1bdd02bf-1cb5-4154-b528-26c207dbb52c) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"0c65cfb1-d655-4b24-b1e8-33a88d4ac7a4\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.264782"], ["updated_at", "2016-12-13 20:24:28.264782"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 0c65cfb1-d655-4b24-b1e8-33a88d4ac7a4) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.5ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"3a60b713-4300-4ad7-b403-d48b2c33055d\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.267615"], ["updated_at", "2016-12-13 20:24:28.267615"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 3a60b713-4300-4ad7-b403-d48b2c33055d) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"9d5af0f5-404d-47d8-9db7-0f572a996d58\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.270971"], ["updated_at", "2016-12-13 20:24:28.270971"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 9d5af0f5-404d-47d8-9db7-0f572a996d58) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"98fc1211-3b39-460e-806c-0d6443cf8f8c\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.273492"], ["updated_at", "2016-12-13 20:24:28.273492"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 98fc1211-3b39-460e-806c-0d6443cf8f8c) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"d35f7f3d-8550-49c6-b8e2-40c29233c77f\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.275933"], ["updated_at", "2016-12-13 20:24:28.275933"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: d35f7f3d-8550-49c6-b8e2-40c29233c77f) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.1ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"aa1eb8d3-c4b6-43f9-bf57-ce198d7f6ada\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.278249"], ["updated_at", "2016-12-13 20:24:28.278249"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: aa1eb8d3-c4b6-43f9-bf57-ce198d7f6ada) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"a0a97263-2dd5-4192-8c34-047408403b6a\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.280138"], ["updated_at", "2016-12-13 20:24:28.280138"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: a0a97263-2dd5-4192-8c34-047408403b6a) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"f067ecf3-36aa-4a12-afc3-fb855e905ac5\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.282359"], ["updated_at", "2016-12-13 20:24:28.282359"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: f067ecf3-36aa-4a12-afc3-fb855e905ac5) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"40911424-a348-46f9-91ed-32a907b2aa2b\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.284420"], ["updated_at", "2016-12-13 20:24:28.284420"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 40911424-a348-46f9-91ed-32a907b2aa2b) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"aca17d46-92f9-49de-8d59-58844d3f84d9\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.286689"], ["updated_at", "2016-12-13 20:24:28.286689"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: aca17d46-92f9-49de-8d59-58844d3f84d9) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"daf62c33-01dd-447d-a647-23fc14b3036b\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.289131"], ["updated_at", "2016-12-13 20:24:28.289131"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: daf62c33-01dd-447d-a647-23fc14b3036b) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.4ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"b04c51b8-461e-4098-9403-c3ccf0d67f36\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.291187"], ["updated_at", "2016-12-13 20:24:28.291187"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: b04c51b8-461e-4098-9403-c3ccf0d67f36) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"d71c71c4-f3af-42e2-a620-1dd529de013d\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.293415"], ["updated_at", "2016-12-13 20:24:28.293415"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: d71c71c4-f3af-42e2-a620-1dd529de013d) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"ad4f9879-2920-4318-802d-0dc793a0b6f7\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.295631"], ["updated_at", "2016-12-13 20:24:28.295631"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: ad4f9879-2920-4318-802d-0dc793a0b6f7) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"ba189cb7-c8b6-4b47-97d7-48a3b1da057e\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.297667"], ["updated_at", "2016-12-13 20:24:28.297667"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: ba189cb7-c8b6-4b47-97d7-48a3b1da057e) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"3c3caa41-6c80-4e81-ab18-1ba9ce1173a5\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.299774"], ["updated_at", "2016-12-13 20:24:28.299774"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 3c3caa41-6c80-4e81-ab18-1ba9ce1173a5) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"2ad1b9d2-e2ad-436c-953f-6f8df84c635b\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.301817"], ["updated_at", "2016-12-13 20:24:28.301817"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 2ad1b9d2-e2ad-436c-953f-6f8df84c635b) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" + Sidejobs::Job Load (0.8ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-13 20:24:28.305176' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.306871"], ["attempts", 1], ["updated_at", "2016-12-13 20:24:28.307605"], ["id", 11]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.6ms) + +UserMailer#invite: processed outbound mail in 127.2ms + +Sent mail to test@mail.com (3.5ms) +Date: Tue, 13 Dec 2016 17:24:28 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058fc6c3c9_103433fcc2283f9f428353@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.2ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-13 20:24:28.444877"], ["updated_at", "2016-12-13 20:24:28.445726"], ["id", 11]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.447414"], ["attempts", 1], ["updated_at", "2016-12-13 20:24:28.448006"], ["id", 12]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.7ms + +Sent mail to test@mail.com (1.3ms) +Date: Tue, 13 Dec 2016 17:24:28 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058fc6e1b2_103433fcc2283f9f42843f@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-13 20:24:28.451877"], ["updated_at", "2016-12-13 20:24:28.452549"], ["id", 12]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.453839"], ["attempts", 1], ["updated_at", "2016-12-13 20:24:28.454396"], ["id", 13]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.2ms) +Date: Tue, 13 Dec 2016 17:24:28 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058fc6fa6a_103433fcc2283f9f42852d@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.2ms) SAVEPOINT active_record_1 + SQL (0.5ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-13 20:24:28.458118"], ["updated_at", "2016-12-13 20:24:28.459321"], ["id", 13]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.461429"], ["attempts", 1], ["updated_at", "2016-12-13 20:24:28.462231"], ["id", 14]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.7ms + +Sent mail to test@mail.com (1.1ms) +Date: Tue, 13 Dec 2016 17:24:28 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058fc71a06_103433fcc2283f9f428652@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-13 20:24:28.466108"], ["updated_at", "2016-12-13 20:24:28.466751"], ["id", 14]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.468119"], ["attempts", 1], ["updated_at", "2016-12-13 20:24:28.468692"], ["id", 15]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.1ms) +Date: Tue, 13 Dec 2016 17:24:28 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058fc731f1_103433fcc2283f9f428748@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-13 20:24:28.472249"], ["updated_at", "2016-12-13 20:24:28.472830"], ["id", 15]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.474130"], ["attempts", 1], ["updated_at", "2016-12-13 20:24:28.474735"], ["id", 16]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.3ms) +Date: Tue, 13 Dec 2016 17:24:28 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058fc749fb_103433fcc2283f9f428813@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-13 20:24:28.478448"], ["updated_at", "2016-12-13 20:24:28.479151"], ["id", 16]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.480503"], ["attempts", 1], ["updated_at", "2016-12-13 20:24:28.481112"], ["id", 17]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.7ms + +Sent mail to test@mail.com (1.2ms) +Date: Tue, 13 Dec 2016 17:24:28 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058fc7628b_103433fcc2283f9f4289f3@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-13 20:24:28.484840"], ["updated_at", "2016-12-13 20:24:28.485448"], ["id", 17]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.486696"], ["attempts", 1], ["updated_at", "2016-12-13 20:24:28.487338"], ["id", 18]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.7ms + +Sent mail to test@mail.com (1.1ms) +Date: Tue, 13 Dec 2016 17:24:28 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058fc77a82_103433fcc2283f9f429047@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-13 20:24:28.490765"], ["updated_at", "2016-12-13 20:24:28.491304"], ["id", 18]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.492505"], ["attempts", 1], ["updated_at", "2016-12-13 20:24:28.493084"], ["id", 19]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.1ms) +Date: Tue, 13 Dec 2016 17:24:28 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058fc79069_103433fcc2283f9f4291b0@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-13 20:24:28.496380"], ["updated_at", "2016-12-13 20:24:28.496909"], ["id", 19]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.498088"], ["attempts", 1], ["updated_at", "2016-12-13 20:24:28.498639"], ["id", 20]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.1ms) +Date: Tue, 13 Dec 2016 17:24:28 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058fc7a5ec_103433fcc2283f9f42927@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-13 20:24:28.501894"], ["updated_at", "2016-12-13 20:24:28.502438"], ["id", 20]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.503714"], ["attempts", 1], ["updated_at", "2016-12-13 20:24:28.504337"], ["id", 21]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.1ms) +Date: Tue, 13 Dec 2016 17:24:28 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058fc7bc44_103433fcc2283f9f4293e1@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-13 20:24:28.507632"], ["updated_at", "2016-12-13 20:24:28.508170"], ["id", 21]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.509317"], ["attempts", 1], ["updated_at", "2016-12-13 20:24:28.509989"], ["id", 22]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.1ms) +Date: Tue, 13 Dec 2016 17:24:28 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058fc7d20e_103433fcc2283f9f4294d2@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-13 20:24:28.513204"], ["updated_at", "2016-12-13 20:24:28.513772"], ["id", 22]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.514885"], ["attempts", 1], ["updated_at", "2016-12-13 20:24:28.515443"], ["id", 23]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.5ms + +Sent mail to test@mail.com (1.0ms) +Date: Tue, 13 Dec 2016 17:24:28 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058fc7e772_103433fcc2283f9f4295ef@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-13 20:24:28.518634"], ["updated_at", "2016-12-13 20:24:28.519176"], ["id", 23]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.520325"], ["attempts", 1], ["updated_at", "2016-12-13 20:24:28.520876"], ["id", 24]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.5ms + +Sent mail to test@mail.com (0.9ms) +Date: Tue, 13 Dec 2016 17:24:28 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058fc7fc3d_103433fcc2283f9f4296be@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-13 20:24:28.523921"], ["updated_at", "2016-12-13 20:24:28.524494"], ["id", 24]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.525635"], ["attempts", 1], ["updated_at", "2016-12-13 20:24:28.526177"], ["id", 25]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.0ms) +Date: Tue, 13 Dec 2016 17:24:28 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058fc8114b_103433fcc2283f9f429726@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-13 20:24:28.529317"], ["updated_at", "2016-12-13 20:24:28.530001"], ["id", 25]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.531218"], ["attempts", 1], ["updated_at", "2016-12-13 20:24:28.531761"], ["id", 26]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.0ms) +Date: Tue, 13 Dec 2016 17:24:28 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058fc8276f_103433fcc2283f9f4298aa@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-13 20:24:28.534989"], ["updated_at", "2016-12-13 20:24:28.535526"], ["id", 26]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.536666"], ["attempts", 1], ["updated_at", "2016-12-13 20:24:28.537196"], ["id", 27]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.1ms) +Date: Tue, 13 Dec 2016 17:24:28 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058fc83cb4_103433fcc2283f9f42993f@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-13 20:24:28.540443"], ["updated_at", "2016-12-13 20:24:28.541049"], ["id", 27]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.542262"], ["attempts", 1], ["updated_at", "2016-12-13 20:24:28.542828"], ["id", 28]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.7ms + +Sent mail to test@mail.com (1.0ms) +Date: Tue, 13 Dec 2016 17:24:28 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058fc852f6_103433fcc2283f9f4300dc@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-13 20:24:28.546124"], ["updated_at", "2016-12-13 20:24:28.546657"], ["id", 28]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.547794"], ["attempts", 1], ["updated_at", "2016-12-13 20:24:28.548359"], ["id", 29]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.1ms) +Date: Tue, 13 Dec 2016 17:24:28 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058fc867b6_103433fcc2283f9f430154@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-13 20:24:28.551485"], ["updated_at", "2016-12-13 20:24:28.552011"], ["id", 29]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.553125"], ["attempts", 1], ["updated_at", "2016-12-13 20:24:28.553770"], ["id", 30]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.2ms) +Date: Tue, 13 Dec 2016 17:24:28 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058fc87e0c_103433fcc2283f9f43022e@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-13 20:24:28.557223"], ["updated_at", "2016-12-13 20:24:28.557795"], ["id", 30]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.2ms) SELECT COUNT(*) FROM "sidejobs" WHERE "sidejobs"."status" = $1 [["status", "complete"]] +  (0.2ms) SELECT COUNT(*) FROM "sidejobs" WHERE "sidejobs"."status" = $1 [["status", "processing"]] +  (0.2ms) SELECT COUNT(*) FROM "sidejobs" WHERE "sidejobs"."status" = $1 [["status", "pending"]] + Sidejobs::Job Load (0.2ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.561568"], ["updated_at", "2016-12-13 20:24:28.562207"], ["id", 34]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-13 20:24:28.563704' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.564466"], ["attempts", 1], ["updated_at", "2016-12-13 20:24:28.565101"], ["id", 31]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.2ms) +Date: Tue, 13 Dec 2016 17:24:28 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058fc8aa94_103433fcc2283f9f430360@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-13 20:24:28.568670"], ["updated_at", "2016-12-13 20:24:28.569239"], ["id", 31]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.570373"], ["attempts", 1], ["updated_at", "2016-12-13 20:24:28.570981"], ["id", 32]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.6ms + +Sent mail to test@mail.com (1.1ms) +Date: Tue, 13 Dec 2016 17:24:28 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058fc8c037_103433fcc2283f9f43046f@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-13 20:24:28.574182"], ["updated_at", "2016-12-13 20:24:28.574774"], ["id", 32]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.575927"], ["attempts", 1], ["updated_at", "2016-12-13 20:24:28.576495"], ["id", 33]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.5ms + +Sent mail to test@mail.com (1.1ms) +Date: Tue, 13 Dec 2016 17:24:28 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058fc8d5fb_103433fcc2283f9f4305cd@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-13 20:24:28.579758"], ["updated_at", "2016-12-13 20:24:28.580333"], ["id", 33]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.2ms) SELECT COUNT(*) FROM "sidejobs" WHERE "sidejobs"."status" = $1 [["status", "complete"]] +  (0.2ms) SELECT COUNT(*) FROM "sidejobs" WHERE "sidejobs"."status" = $1 [["status", "processing"]] +  (0.2ms) SELECT COUNT(*) FROM "sidejobs" WHERE "sidejobs"."status" = $1 [["status", "pending"]] +  (0.1ms) ROLLBACK +  (0.1ms) BEGIN +--------------------------- +ProcessorTest: test_process +--------------------------- +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.2ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ActionMailer::DeliveryJob\",\"job_id\":\"77c690e3-d50a-4e70-9987-c900e46fb5d8\",\"queue_name\":\"mailers\",\"arguments\":[\"UserMailer\",\"invite\",\"deliver_now\",\"test@mail.com\"],\"locale\":\"en\"}"], ["queue", "mailers"], ["priority", 80], ["created_at", "2016-12-13 20:24:28.584499"], ["updated_at", "2016-12-13 20:24:28.584499"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ActionMailer::DeliveryJob (Job ID: 77c690e3-d50a-4e70-9987-c900e46fb5d8) to Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" + Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-13 20:24:28.585904' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.586642"], ["attempts", 1], ["updated_at", "2016-12-13 20:24:28.587218"], ["id", 35]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Rendered user_mailer/invite.text.erb (0.0ms) + +UserMailer#invite: processed outbound mail in 0.7ms + +Sent mail to test@mail.com (1.0ms) +Date: Tue, 13 Dec 2016 17:24:28 -0300 +From: test@mail.com +To: test@mail.com +Message-ID: <585058fc9008c_103433fcc2283f9f4306f1@imac-de-matt.mail> +Subject: Invite +Mime-Version: 1.0 +Content-Type: text/plain; + charset=UTF-8 +Content-Transfer-Encoding: 7bit + +Test + +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-13 20:24:28.590585"], ["updated_at", "2016-12-13 20:24:28.591167"], ["id", 35]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Sidejobs::Job Load (0.2ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +  (0.1ms) ROLLBACK +  (0.1ms) BEGIN +--------------------------- +ProcessorTest: test_retries +--------------------------- +[ActiveJob]  (0.1ms) SAVEPOINT active_record_1 +[ActiveJob] SQL (0.3ms) INSERT INTO "sidejobs" ("data", "queue", "priority", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["data", "{\"job_class\":\"ShareProductsJob\",\"job_id\":\"c861417f-88bc-4fa5-9189-bb1471a48f2d\",\"queue_name\":\"default\",\"arguments\":[],\"locale\":\"en\"}"], ["queue", "default"], ["priority", 40], ["created_at", "2016-12-13 20:24:28.594767"], ["updated_at", "2016-12-13 20:24:28.594767"]] +[ActiveJob]  (0.1ms) RELEASE SAVEPOINT active_record_1 +[ActiveJob] Enqueued ShareProductsJob (Job ID: c861417f-88bc-4fa5-9189-bb1471a48f2d) to Sidejobs(default) + Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-13 20:24:28.596466' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.597317"], ["attempts", 1], ["updated_at", "2016-12-13 20:24:28.597920"], ["id", 36]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "failed_at" = $2, "error" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "failing"], ["failed_at", "2016-12-13 20:24:28.599461"], ["error", "Social network unavailable"], ["updated_at", "2016-12-13 20:24:28.600057"], ["id", 36]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-13 20:24:28.601389' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.602073"], ["attempts", 2], ["updated_at", "2016-12-13 20:24:28.602721"], ["id", 36]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "failed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "failing"], ["failed_at", "2016-12-13 20:24:28.604081"], ["updated_at", "2016-12-13 20:24:28.604626"], ["id", 36]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-13 20:24:28.605937' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-13 20:24:28.606589"], ["attempts", 3], ["updated_at", "2016-12-13 20:24:28.607163"], ["id", 36]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "failed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "failing"], ["failed_at", "2016-12-13 20:24:28.608605"], ["updated_at", "2016-12-13 20:24:28.609140"], ["id", 36]] +  (0.1ms) RELEASE SAVEPOINT active_record_1 + Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-13 20:24:28.610305' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 + Sidejobs::Job Load (0.2ms) SELECT "sidejobs".* FROM "sidejobs" ORDER BY "sidejobs"."id" DESC LIMIT 1 +  (0.1ms) ROLLBACK