test/dummy/log/test.log in cachers-4.0.0.0 vs test/dummy/log/test.log in cachers-4.1.0.0

- old
+ new

@@ -895,5 +895,803 @@  (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 +