ActiveRecord::SchemaMigration Load (26.8ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------- TasksTest: test_all -------------------  (0.2ms) SAVEPOINT active_record_1 SQL (24.6ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-21 23:37:45.305099"], ["updated_at", "2016-11-21 23:37:45.305099"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-11-21 23:37:45.333154"], ["updated_at", "2016-11-21 23:37:45.333154"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.7ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.3ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.2ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-21 23:37:45.379367"], ["updated_at", "2016-11-21 23:37:45.379367"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-21 23:37:45.381498"], ["id", 129]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 129]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-21 23:39:38.343738"], ["updated_at", "2016-11-21 23:39:38.343738"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-21 23:39:38.349483"], ["id", 130]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 130]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------- TasksTest: test_all -------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-21 23:39:38.361035"], ["updated_at", "2016-11-21 23:39:38.361035"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-11-21 23:39:38.362245"], ["updated_at", "2016-11-21 23:39:38.362245"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.3ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.4ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.3ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-21 23:40:59.697877"], ["updated_at", "2016-11-21 23:40:59.697877"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-21 23:40:59.703733"], ["id", 133]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 133]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.3ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-21 23:41:12.579170"], ["updated_at", "2016-11-21 23:41:12.579170"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-21 23:41:12.584483"], ["id", 134]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 134]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.4ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-21 23:46:12.926685"], ["updated_at", "2016-11-21 23:46:12.926685"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-21 23:46:12.933083"], ["id", 135]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 135]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.3ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-21 23:47:02.820026"], ["updated_at", "2016-11-21 23:47:02.820026"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-21 23:47:02.825878"], ["id", 136]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 136]]  (0.1ms) RELEASE SAVEPOINT active_record_1 ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-21 23:49:48.460181"], ["updated_at", "2016-11-21 23:49:48.460181"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-21 23:49:48.465952"], ["id", 137]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 137]]  (0.1ms) RELEASE SAVEPOINT active_record_1 ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-21 23:50:18.893466"], ["updated_at", "2016-11-21 23:50:18.893466"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-21 23:50:18.898622"], ["id", 138]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 138]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.3ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-21 23:51:12.408206"], ["updated_at", "2016-11-21 23:51:12.408206"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-21 23:51:12.418440"], ["id", 139]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 139]]  (0.1ms) RELEASE SAVEPOINT active_record_1 ActiveRecord::SchemaMigration Load (0.6ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-21 23:51:59.372065"], ["updated_at", "2016-11-21 23:51:59.372065"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-21 23:51:59.377410"], ["id", 140]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 140]]  (0.1ms) RELEASE SAVEPOINT active_record_1 ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-21 23:52:45.648517"], ["updated_at", "2016-11-21 23:52:45.648517"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-21 23:52:48.089139"], ["id", 141]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 141]]  (0.1ms) RELEASE SAVEPOINT active_record_1 ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-21 23:53:39.062956"], ["updated_at", "2016-11-21 23:53:39.062956"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-21 23:53:41.025901"], ["id", 142]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 142]]  (0.1ms) RELEASE SAVEPOINT active_record_1 ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-21 23:57:28.969082"], ["updated_at", "2016-11-21 23:57:28.969082"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.4ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-21 23:57:47.446870"], ["updated_at", "2016-11-21 23:57:47.446870"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.3ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.5ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-21 23:59:07.003959"], ["updated_at", "2016-11-21 23:59:07.003959"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.3ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-21 23:59:24.044279"], ["updated_at", "2016-11-21 23:59:24.044279"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-21 23:59:31.276528"], ["id", 146]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 146]]  (0.1ms) RELEASE SAVEPOINT active_record_1 ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:02:01.530432"], ["updated_at", "2016-11-22 00:02:01.530432"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-22 00:02:01.535954"], ["id", 147]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 147]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:02:19.314031"], ["updated_at", "2016-11-22 00:02:19.314031"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-22 00:02:27.698301"], ["id", 148]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 148]]  (0.1ms) RELEASE SAVEPOINT active_record_1 ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:03:42.543762"], ["updated_at", "2016-11-22 00:03:42.543762"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-22 00:03:46.560535"], ["id", 149]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 149]]  (0.1ms) RELEASE SAVEPOINT active_record_1 ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:06:58.988468"], ["updated_at", "2016-11-22 00:06:58.988468"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-22 00:07:07.677416"], ["id", 150]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 150]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.3ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:09:29.868739"], ["updated_at", "2016-11-22 00:09:29.868739"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-22 00:09:33.803445"], ["id", 151]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 151]]  (0.1ms) RELEASE SAVEPOINT active_record_1 ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:09:56.312834"], ["updated_at", "2016-11-22 00:09:56.312834"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-22 00:09:59.881428"], ["id", 152]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 152]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.3ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:10:37.784068"], ["updated_at", "2016-11-22 00:10:37.784068"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-22 00:10:43.891252"], ["id", 153]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 153]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.3ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:11:44.523662"], ["updated_at", "2016-11-22 00:11:44.523662"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-22 00:11:47.802533"], ["id", 154]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 154]]  (0.1ms) RELEASE SAVEPOINT active_record_1 ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.5ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:14:35.685174"], ["updated_at", "2016-11-22 00:14:35.685174"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:15:15.077225"], ["updated_at", "2016-11-22 00:15:15.077225"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-22 00:15:17.648443"], ["id", 156]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 156]]  (0.1ms) RELEASE SAVEPOINT active_record_1 ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.5ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:15:35.278143"], ["updated_at", "2016-11-22 00:15:35.278143"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-22 00:15:37.898495"], ["id", 157]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 157]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.3ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:17:39.185655"], ["updated_at", "2016-11-22 00:17:39.185655"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-22 00:17:42.706545"], ["id", 158]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 158]]  (0.1ms) RELEASE SAVEPOINT active_record_1 ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:20:53.185113"], ["updated_at", "2016-11-22 00:20:53.185113"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-22 00:21:08.379630"], ["id", 159]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 159]]  (0.1ms) RELEASE SAVEPOINT active_record_1 ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:22:54.718145"], ["updated_at", "2016-11-22 00:22:54.718145"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-22 00:22:58.652088"], ["id", 160]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 160]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.3ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:25:12.646356"], ["updated_at", "2016-11-22 00:25:12.646356"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:28:17.710759"], ["updated_at", "2016-11-22 00:28:17.710759"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:30:52.366841"], ["updated_at", "2016-11-22 00:30:52.366841"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-22 00:30:58.139785"], ["id", 163]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 163]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN ------------------- TasksTest: test_all -------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.5ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:32:37.989284"], ["updated_at", "2016-11-22 00:32:37.989284"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-11-22 00:32:37.993727"], ["updated_at", "2016-11-22 00:32:37.993727"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.4ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.4ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.3ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000  (0.2ms) ROLLBACK  (0.2ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:32:38.021371"], ["updated_at", "2016-11-22 00:32:38.021371"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-22 00:32:38.023126"], ["id", 166]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 166]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ------------------- TasksTest: test_all -------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:47:44.753877"], ["updated_at", "2016-11-22 00:47:44.753877"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-11-22 00:47:44.757357"], ["updated_at", "2016-11-22 00:47:44.757357"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.4ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.3ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.2ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:47:44.779257"], ["updated_at", "2016-11-22 00:47:44.779257"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-22 00:47:44.780860"], ["id", 169]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 169]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.2ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:49:07.788022"], ["updated_at", "2016-11-22 00:49:07.788022"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-22 00:49:07.793619"], ["id", 170]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 170]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------- TasksTest: test_all -------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:49:07.805710"], ["updated_at", "2016-11-22 00:49:07.805710"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-11-22 00:49:07.807063"], ["updated_at", "2016-11-22 00:49:07.807063"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.4ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.4ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.2ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:49:18.583623"], ["updated_at", "2016-11-22 00:49:18.583623"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-22 00:49:18.589113"], ["id", 173]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 173]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.2ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------- TasksTest: test_all -------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:49:18.600385"], ["updated_at", "2016-11-22 00:49:18.600385"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-11-22 00:49:18.601767"], ["updated_at", "2016-11-22 00:49:18.601767"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.3ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.3ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.3ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:50:33.197146"], ["updated_at", "2016-11-22 00:50:33.197146"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-22 00:50:33.202556"], ["id", 176]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 176]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.2ms) BEGIN ------------------- TasksTest: test_all -------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 00:50:33.209231"], ["updated_at", "2016-11-22 00:50:33.209231"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-11-22 00:50:33.210491"], ["updated_at", "2016-11-22 00:50:33.210491"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.4ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.2ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.2ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN ------------------- TasksTest: test_all -------------------  (0.2ms) SAVEPOINT active_record_1 SQL (6.8ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 13:34:36.183600"], ["updated_at", "2016-11-22 13:34:36.183600"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-11-22 13:34:36.194048"], ["updated_at", "2016-11-22 13:34:36.194048"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.4ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 13:34:36.201722"], ["updated_at", "2016-11-22 13:34:36.201722"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 13:34:44.591790"], ["updated_at", "2016-11-22 13:34:44.591790"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------- TasksTest: test_all -------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 13:34:44.603483"], ["updated_at", "2016-11-22 13:34:44.603483"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-11-22 13:34:44.605281"], ["updated_at", "2016-11-22 13:34:44.605281"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.5ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN ------------------- TasksTest: test_all -------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 13:35:02.394624"], ["updated_at", "2016-11-22 13:35:02.394624"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-11-22 13:35:02.398032"], ["updated_at", "2016-11-22 13:35:02.398032"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.4ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 13:35:02.408869"], ["updated_at", "2016-11-22 13:35:02.408869"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-22 13:35:02.410795"], ["id", 187]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN ------------------- TasksTest: test_all -------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 13:36:29.701855"], ["updated_at", "2016-11-22 13:36:29.701855"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-11-22 13:36:29.705407"], ["updated_at", "2016-11-22 13:36:29.705407"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.4ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 13:36:29.716471"], ["updated_at", "2016-11-22 13:36:29.716471"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-22 13:36:29.718171"], ["id", 190]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 190]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 13:36:36.399524"], ["updated_at", "2016-11-22 13:36:36.399524"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-22 13:36:36.404863"], ["id", 191]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 191]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.2ms) BEGIN ------------------- TasksTest: test_all -------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 13:36:36.412203"], ["updated_at", "2016-11-22 13:36:36.412203"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-11-22 13:36:36.413453"], ["updated_at", "2016-11-22 13:36:36.413453"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.4ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN ------------------- TasksTest: test_all -------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 13:37:34.042772"], ["updated_at", "2016-11-22 13:37:34.042772"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-11-22 13:37:34.046186"], ["updated_at", "2016-11-22 13:37:34.046186"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.4ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.2ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.2ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 13:37:34.059321"], ["updated_at", "2016-11-22 13:37:34.059321"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-22 13:37:34.061114"], ["id", 196]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 196]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 13:37:57.076860"], ["updated_at", "2016-11-22 13:37:57.076860"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-22 13:37:57.081994"], ["id", 197]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------- TasksTest: test_all -------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 13:37:57.091345"], ["updated_at", "2016-11-22 13:37:57.091345"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-11-22 13:37:57.092642"], ["updated_at", "2016-11-22 13:37:57.092642"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.4ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.3ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.5ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 13:38:12.866885"], ["updated_at", "2016-11-22 13:38:12.866885"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-11-22 13:38:12.871768"], ["id", 200]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 200]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.2ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------- TasksTest: test_all -------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-11-22 13:38:12.883070"], ["updated_at", "2016-11-22 13:38:12.883070"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-11-22 13:38:12.884350"], ["updated_at", "2016-11-22 13:38:12.884350"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.4ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.4ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.6ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (26.6ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.3ms) SAVEPOINT active_record_1 SQL (32.0ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-12 23:33:17.780838"], ["updated_at", "2016-12-12 23:33:17.780838"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-12 23:33:17.820306"], ["updated_at", "2016-12-12 23:33:17.820306"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-12 23:33:17.821367"], ["updated_at", "2016-12-12 23:33:17.821367"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.3ms) SAVEPOINT active_record_1 SQL (0.5ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-12 23:34:21.435223"], ["updated_at", "2016-12-12 23:34:21.435223"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-12 23:34:21.439441"], ["updated_at", "2016-12-12 23:34:21.439441"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-12 23:34:21.442125"], ["updated_at", "2016-12-12 23:34:21.442125"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-12 23:36:01.338429"], ["updated_at", "2016-12-12 23:36:01.338429"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-12 23:36:01.341708"], ["updated_at", "2016-12-12 23:36:01.341708"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-12 23:36:01.348945"], ["updated_at", "2016-12-12 23:36:01.348945"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-12 23:37:48.638640"], ["updated_at", "2016-12-12 23:37:48.638640"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-12 23:37:48.647824"], ["updated_at", "2016-12-12 23:37:48.647824"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-12 23:37:48.649440"], ["updated_at", "2016-12-12 23:37:48.649440"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-12 23:47:36.634003"], ["updated_at", "2016-12-12 23:47:36.634003"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-12 23:47:36.638308"], ["updated_at", "2016-12-12 23:47:36.638308"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-12 23:47:36.641010"], ["updated_at", "2016-12-12 23:47:36.641010"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-12 23:48:47.109338"], ["updated_at", "2016-12-12 23:48:47.109338"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-12 23:48:47.113766"], ["updated_at", "2016-12-12 23:48:47.113766"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-12 23:48:47.114824"], ["updated_at", "2016-12-12 23:48:47.114824"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-12 23:49:32.766206"], ["updated_at", "2016-12-12 23:49:32.766206"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-12 23:49:32.769265"], ["updated_at", "2016-12-12 23:49:32.769265"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-12 23:49:32.776394"], ["updated_at", "2016-12-12 23:49:32.776394"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-12 23:49:57.597451"], ["updated_at", "2016-12-12 23:49:57.597451"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.3ms) ROLLBACK  (0.1ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-12 23:49:57.622004"], ["updated_at", "2016-12-12 23:49:57.622004"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-12 23:49:57.623239"], ["updated_at", "2016-12-12 23:49:57.623239"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.3ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-12 23:50:32.527586"], ["updated_at", "2016-12-12 23:50:32.527586"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.4ms) ROLLBACK  (0.1ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-12 23:50:32.548549"], ["updated_at", "2016-12-12 23:50:32.548549"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-12 23:50:32.549792"], ["updated_at", "2016-12-12 23:50:32.549792"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.5ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:08:06.302722"], ["updated_at", "2016-12-13 00:08:06.302722"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-13 00:08:06.307774"], ["updated_at", "2016-12-13 00:08:06.307774"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:08:06.328401"], ["updated_at", "2016-12-13 00:08:06.328401"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-12-13 00:08:06.329961"], ["id", 232]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 232]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:08:11.840136"], ["updated_at", "2016-12-13 00:08:11.840136"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-12-13 00:08:11.856174"], ["id", 233]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 233]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:08:11.870451"], ["updated_at", "2016-12-13 00:08:11.870451"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (1.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-13 00:08:11.871970"], ["updated_at", "2016-12-13 00:08:11.871970"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:08:15.513040"], ["updated_at", "2016-12-13 00:08:15.513040"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-12-13 00:08:15.529318"], ["id", 236]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 236]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:08:15.536164"], ["updated_at", "2016-12-13 00:08:15.536164"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-13 00:08:15.537215"], ["updated_at", "2016-12-13 00:08:15.537215"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.2ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:08:18.120875"], ["updated_at", "2016-12-13 00:08:18.120875"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-12-13 00:08:18.137117"], ["id", 239]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 239]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:08:18.143855"], ["updated_at", "2016-12-13 00:08:18.143855"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-13 00:08:18.144909"], ["updated_at", "2016-12-13 00:08:18.144909"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:10:00.510127"], ["updated_at", "2016-12-13 00:10:00.510127"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-13 00:10:00.513953"], ["updated_at", "2016-12-13 00:10:00.513953"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.3ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:12:10.972983"], ["updated_at", "2016-12-13 00:12:10.972983"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-13 00:12:10.977733"], ["updated_at", "2016-12-13 00:12:10.977733"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- RecordTest: test_callbacks -------------------------- ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.3ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:15:36.639669"], ["updated_at", "2016-12-13 00:15:36.639669"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-13 00:15:36.643384"], ["updated_at", "2016-12-13 00:15:36.643384"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:16:36.346285"], ["updated_at", "2016-12-13 00:16:36.346285"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-12-13 00:16:36.365120"], ["id", 248]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 248]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:16:36.373755"], ["updated_at", "2016-12-13 00:16:36.373755"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-13 00:16:36.374877"], ["updated_at", "2016-12-13 00:16:36.374877"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (1.2ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:18:41.471328"], ["updated_at", "2016-12-13 00:18:41.471328"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-13 00:18:41.474703"], ["updated_at", "2016-12-13 00:18:41.474703"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.4ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.3ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.2ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:18:41.501263"], ["updated_at", "2016-12-13 00:18:41.501263"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-12-13 00:18:41.503272"], ["id", 253]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 253]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (11.7ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.5ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:44:07.323233"], ["updated_at", "2016-12-13 00:44:07.323233"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:44:07.333421"], ["updated_at", "2016-12-13 00:44:07.333421"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-13 00:44:07.334825"], ["updated_at", "2016-12-13 00:44:07.334825"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 ActiveRecord::SchemaMigration Load (12.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ORDER BY "schema_migrations"."" ASC LIMIT 1000  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.5ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:44:32.224090"], ["updated_at", "2016-12-13 00:44:32.224090"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-13 00:44:32.228371"], ["updated_at", "2016-12-13 00:44:32.228371"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.7ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:44:32.234776"], ["updated_at", "2016-12-13 00:44:32.234776"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (5.9ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:45:16.034550"], ["updated_at", "2016-12-13 00:45:16.034550"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 ActiveRecord::SchemaMigration Load (0.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:46:22.517077"], ["updated_at", "2016-12-13 00:46:22.517077"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:46:22.523040"], ["updated_at", "2016-12-13 00:46:22.523040"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-13 00:46:22.524293"], ["updated_at", "2016-12-13 00:46:22.524293"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.4ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000  (0.1ms) ROLLBACK  (0.2ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.5ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:46:35.524490"], ["updated_at", "2016-12-13 00:46:35.524490"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-13 00:46:35.527743"], ["updated_at", "2016-12-13 00:46:35.527743"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 User Load (0.5ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.3ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.3ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:46:35.588476"], ["updated_at", "2016-12-13 00:46:35.588476"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-12-13 00:46:35.590041"], ["id", 266]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 266]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:48:07.027428"], ["updated_at", "2016-12-13 00:48:07.027428"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-12-13 00:48:07.047922"], ["id", 267]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 267]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:48:07.056492"], ["updated_at", "2016-12-13 00:48:07.056492"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-13 00:48:07.057515"], ["updated_at", "2016-12-13 00:48:07.057515"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.4ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.2ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.3ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:50:59.551979"], ["updated_at", "2016-12-13 00:50:59.551979"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.3ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-12-13 00:50:59.568604"], ["id", 270]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 270]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 00:50:59.576725"], ["updated_at", "2016-12-13 00:50:59.576725"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-13 00:50:59.577914"], ["updated_at", "2016-12-13 00:50:59.577914"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (1.4ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.2ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.2ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.7ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.3ms) ROLLBACK  (32.4ms) BEGIN ------------------ TaskTest: test_all ------------------  (1.3ms) SAVEPOINT active_record_1 SQL (0.7ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 01:05:12.847206"], ["updated_at", "2016-12-13 01:05:12.847206"]]  (3.6ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (1.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-13 01:05:12.880257"], ["updated_at", "2016-12-13 01:05:12.880257"]]  (1.2ms) RELEASE SAVEPOINT active_record_1 User Load (0.5ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.4ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.3ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 01:05:12.943568"], ["updated_at", "2016-12-13 01:05:12.943568"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-12-13 01:05:12.945518"], ["id", 275]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 275]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ---------------------------- GeneratorsTest: test_install ----------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 01:06:24.985350"], ["updated_at", "2016-12-13 01:06:24.985350"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-12-13 01:06:25.002951"], ["id", 276]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 276]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 01:06:25.011154"], ["updated_at", "2016-12-13 01:06:25.011154"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-13 01:06:25.012392"], ["updated_at", "2016-12-13 01:06:25.012392"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.4ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.3ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.3ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- GeneratorsTest: test_install ----------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 01:06:42.853164"], ["updated_at", "2016-12-13 01:06:42.853164"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-12-13 01:06:42.870759"], ["id", 279]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 279]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-13 01:06:42.880137"], ["updated_at", "2016-12-13 01:06:42.880137"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-13 01:06:42.881553"], ["updated_at", "2016-12-13 01:06:42.881553"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 User Load (0.6ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.3ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.3ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000  (0.1ms) ROLLBACK  (5.6ms) CREATE TABLE "schema_migrations" ("version" character varying NOT NULL)   (11.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version") ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations" Migrating to CreateUsers (20160918232127)  (0.1ms) BEGIN  (8.8ms) CREATE TABLE "users" ("id" serial primary key, "name" character varying, "created_at" timestamp NOT NULL, "updated_at" timestamp NOT NULL)  SQL (0.3ms) INSERT INTO "schema_migrations" ("version") VALUES ($1) [["version", "20160918232127"]]  (0.5ms) COMMIT ActiveRecord::SchemaMigration Load (0.2ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.7ms) 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 = 'users' AND t3.nspname = ANY (current_schemas(false)) ORDER BY c.conname  ActiveRecord::SchemaMigration Load (6.9ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ------------------ TaskTest: test_all ------------------  (0.1ms) SAVEPOINT active_record_1 SQL (6.9ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-14 05:28:29.909537"], ["updated_at", "2016-12-14 05:28:29.909537"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "john"], ["created_at", "2016-12-14 05:28:29.920107"], ["updated_at", "2016-12-14 05:28:29.920107"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.4ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.3ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000 User Load (0.2ms) SELECT "users".* FROM "users" ORDER BY "users"."id" ASC LIMIT 1000  (0.1ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- GeneratorsTest: test_install ----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- GeneratorsTest: test_cacher ---------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordTest: test_callbacks --------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mike"], ["created_at", "2016-12-14 05:28:29.972542"], ["updated_at", "2016-12-14 05:28:29.972542"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3 [["name", "john"], ["updated_at", "2016-12-14 05:28:29.974143"], ["id", 3]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) DELETE FROM "users" WHERE "users"."id" = $1 [["id", 3]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) ROLLBACK