(111.8ms)  DROP DATABASE IF EXISTS "cachers_test"
   (445.2ms)  CREATE DATABASE "cachers_test" ENCODING = 'utf8'
  SQL (1.2ms)  CREATE EXTENSION IF NOT EXISTS "plpgsql"
   (5.1ms)  CREATE TABLE "users" ("id" serial primary key, "name" character varying, "created_at" timestamp NOT NULL, "updated_at" timestamp NOT NULL) 
   (1.4ms)  CREATE TABLE "schema_migrations" ("version" character varying NOT NULL) 
   (1.2ms)  CREATE UNIQUE INDEX  "unique_schema_migrations" ON "schema_migrations"  ("version")
   (0.3ms)  SELECT version FROM "schema_migrations"
   (0.4ms)  INSERT INTO "schema_migrations" (version) VALUES ('20160918232127')
  ActiveRecord::SchemaMigration Load (0.3ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 03:32:43.846092"], ["updated_at", "2016-09-19 03:32:43.846092"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 03:40:11.680241"], ["updated_at", "2016-09-19 03:40:11.680241"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 03:53:34.452635"], ["updated_at", "2016-09-19 03:53:34.452635"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.3ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 03:53:45.003892"], ["updated_at", "2016-09-19 03:53:45.003892"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 03:55:05.594248"], ["updated_at", "2016-09-19 03:55:05.594248"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 03:55:25.999964"], ["updated_at", "2016-09-19 03:55:25.999964"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.3ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 03:56:03.990496"], ["updated_at", "2016-09-19 03:56:03.990496"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:00:12.211999"], ["updated_at", "2016-09-19 04:00:12.211999"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:00:30.371587"], ["updated_at", "2016-09-19 04:00:30.371587"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:00:39.187220"], ["updated_at", "2016-09-19 04:00:39.187220"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:01:47.375081"], ["updated_at", "2016-09-19 04:01:47.375081"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.4ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.5ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:02:05.488095"], ["updated_at", "2016-09-19 04:02:05.488095"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:03:08.616791"], ["updated_at", "2016-09-19 04:03:08.616791"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:04:19.601411"], ["updated_at", "2016-09-19 04:04:19.601411"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:11:08.118905"], ["updated_at", "2016-09-19 04:11:08.118905"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.3ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:11:49.864889"], ["updated_at", "2016-09-19 04:11:49.864889"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:17:16.468134"], ["updated_at", "2016-09-19 04:17:16.468134"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.3ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:20:09.066181"], ["updated_at", "2016-09-19 04:20:09.066181"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.3ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:20:47.945225"], ["updated_at", "2016-09-19 04:20:47.945225"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.3ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:21:53.990821"], ["updated_at", "2016-09-19 04:21:53.990821"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.3ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (0.2ms)  SAVEPOINT active_record_1
   (7.6ms)  ROLLBACK TO SAVEPOINT active_record_1
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.3ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (0.2ms)  SAVEPOINT active_record_1
   (1.3ms)  ROLLBACK TO SAVEPOINT active_record_1
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:22:41.229189"], ["updated_at", "2016-09-19 04:22:41.229189"]]
   (0.6ms)  ROLLBACK TO SAVEPOINT active_record_1
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:23:13.237431"], ["updated_at", "2016-09-19 04:23:13.237431"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.3ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:23:20.608409"], ["updated_at", "2016-09-19 04:23:20.608409"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
  ActiveRecord::SchemaMigration Load (0.3ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:33:55.025900"], ["updated_at", "2016-09-19 04:33:55.025900"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
  ActiveRecord::SchemaMigration Load (0.3ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:34:08.208228"], ["updated_at", "2016-09-19 04:34:08.208228"]]
   (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-09-19 04:34:08.213461"], ["id", 25]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.4ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.3ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:34:22.409935"], ["updated_at", "2016-09-19 04:34:22.409935"]]
   (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-09-19 04:34:22.415869"], ["id", 26]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 26]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:34:36.093119"], ["updated_at", "2016-09-19 04:34:36.093119"]]
   (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-09-19 04:34:36.098857"], ["id", 27]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 27]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.3ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:35:30.409015"], ["updated_at", "2016-09-19 04:35:30.409015"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  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
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:36:34.780507"], ["updated_at", "2016-09-19 04:36:34.780507"]]
   (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-09-19 04:36:34.785088"], ["id", 29]]
   (0.2ms)  ROLLBACK TO SAVEPOINT active_record_1
   (0.3ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:36:56.693647"], ["updated_at", "2016-09-19 04:36:56.693647"]]
   (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-09-19 04:36:56.699391"], ["id", 30]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 30]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.3ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:38:41.003910"], ["updated_at", "2016-09-19 04:38:41.003910"]]
   (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-09-19 04:38:41.009564"], ["id", 31]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 31]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.7ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:40:02.583665"], ["updated_at", "2016-09-19 04:40:02.583665"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.3ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:40:24.636341"], ["updated_at", "2016-09-19 04:40:24.636341"]]
   (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-09-19 04:40:24.642380"], ["id", 33]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 33]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
----------------------
CachersTest: test_crud
----------------------
   (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-09-19 04:41:34.679627"], ["updated_at", "2016-09-19 04:41:34.679627"]]
   (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-09-19 04:41:34.685329"], ["id", 34]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 34]]
   (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.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-09-19 04:43:19.497138"], ["updated_at", "2016-09-19 04:43:19.497138"]]
   (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-09-19 04:43:19.502644"], ["id", 35]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 35]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.6ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
---------------------
TasksTest: test_tasks
---------------------
   (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-09-19 10:30:53.698385"], ["updated_at", "2016-09-19 10:30:53.698385"]]
   (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-09-19 10:30:53.702565"], ["updated_at", "2016-09-19 10:30:53.702565"]]
   (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-09-19 10:30:53.735581"], ["updated_at", "2016-09-19 10:30:53.735581"]]
   (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-09-19 10:30:53.737616"], ["id", 38]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.1ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 38]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
---------------------
TasksTest: test_tasks
---------------------
   (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-09-19 10:31:56.742210"], ["updated_at", "2016-09-19 10:31:56.742210"]]
   (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-09-19 10:31:56.747352"], ["updated_at", "2016-09-19 10:31:56.747352"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  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-09-19 10:31:56.755159"], ["updated_at", "2016-09-19 10:31:56.755159"]]
   (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-09-19 10:31:56.756849"], ["id", 41]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 41]]
   (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.4ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id"  [["name", "mike"], ["created_at", "2016-09-19 10:33:46.475827"], ["updated_at", "2016-09-19 10:33:46.475827"]]
   (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-09-19 10:33:46.481176"], ["id", 42]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 42]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
   (0.2ms)  BEGIN
---------------------
TasksTest: test_tasks
---------------------
   (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-09-19 10:33:46.494057"], ["updated_at", "2016-09-19 10:33:46.494057"]]
   (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-09-19 10:33:46.495377"], ["updated_at", "2016-09-19 10:33:46.495377"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.5ms)  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.3ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id"  [["name", "mike"], ["created_at", "2016-09-19 10:34:09.588618"], ["updated_at", "2016-09-19 10:34:09.588618"]]
   (0.3ms)  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-09-19 10:34:09.593761"], ["id", 45]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 45]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
   (0.1ms)  BEGIN
---------------------
TasksTest: test_tasks
---------------------
   (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-09-19 10:34:09.605874"], ["updated_at", "2016-09-19 10:34:09.605874"]]
   (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-09-19 10:34:09.607483"], ["updated_at", "2016-09-19 10:34:09.607483"]]
   (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
  ActiveRecord::SchemaMigration Load (0.3ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
---------------------
TasksTest: test_tasks
---------------------
   (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-09-19 10:38:40.476745"], ["updated_at", "2016-09-19 10:38:40.476745"]]
   (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-09-19 10:38:40.481059"], ["updated_at", "2016-09-19 10:38:40.481059"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
  User Load (0.5ms)  SELECT  "users".* FROM "users"  ORDER BY "users"."id" ASC LIMIT 1000
   (0.2ms)  ROLLBACK
   (0.2ms)  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-09-19 10:38:40.493288"], ["updated_at", "2016-09-19 10:38:40.493288"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.6ms)  UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3  [["name", "john"], ["updated_at", "2016-09-19 10:38:40.494917"], ["id", 50]]
   (0.5ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 50]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
---------------------
TasksTest: test_tasks
---------------------
   (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-09-19 10:39:15.897495"], ["updated_at", "2016-09-19 10:39:15.897495"]]
   (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-09-19 10:39:15.900619"], ["updated_at", "2016-09-19 10:39:15.900619"]]
   (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-09-19 10:39:15.912940"], ["updated_at", "2016-09-19 10:39:15.912940"]]
   (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-09-19 10:39:15.914494"], ["id", 53]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 53]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.3ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
---------------------
TasksTest: test_tasks
---------------------
   (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-09-19 10:40:45.559918"], ["updated_at", "2016-09-19 10:40:45.559918"]]
   (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-09-19 10:40:45.563055"], ["updated_at", "2016-09-19 10:40:45.563055"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
  User Load (0.6ms)  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-09-19 10:40:45.574354"], ["updated_at", "2016-09-19 10:40:45.574354"]]
   (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-09-19 10:40:45.575989"], ["id", 56]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 56]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.3ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
---------------------
TasksTest: test_tasks
---------------------
   (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-09-19 10:45:18.166029"], ["updated_at", "2016-09-19 10:45:18.166029"]]
   (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-09-19 10:45:18.169470"], ["updated_at", "2016-09-19 10:45:18.169470"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
  User Load (0.5ms)  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-09-19 10:45:18.181500"], ["updated_at", "2016-09-19 10:45:18.181500"]]
   (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-09-19 10:45:18.183135"], ["id", 59]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.1ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 59]]
   (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-09-19 10:48:50.355139"], ["updated_at", "2016-09-19 10:48:50.355139"]]
   (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-09-19 10:48:50.359656"], ["id", 60]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 60]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
   (0.1ms)  BEGIN
---------------------
TasksTest: test_tasks
---------------------
   (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-09-19 10:48:50.371727"], ["updated_at", "2016-09-19 10:48:50.371727"]]
   (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-09-19 10:48:50.372885"], ["updated_at", "2016-09-19 10:48:50.372885"]]
   (0.1ms)  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.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.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-09-19 11:15:10.376287"], ["updated_at", "2016-09-19 11:15:10.376287"]]
   (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-09-19 11:15:10.380757"], ["id", 63]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 63]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
   (0.1ms)  BEGIN
---------------------
TasksTest: test_tasks
---------------------
   (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-09-19 11:15:10.392395"], ["updated_at", "2016-09-19 11:15:10.392395"]]
   (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-09-19 11:15:10.393534"], ["updated_at", "2016-09-19 11:15:10.393534"]]
   (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
  ActiveRecord::SchemaMigration Load (0.3ms)  SELECT "schema_migrations".* FROM "schema_migrations"
  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-09-19 12:16:09.686101"], ["updated_at", "2016-09-19 12:16:09.686101"]]
   (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-09-19 12:16:09.691767"], ["id", 66]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 66]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
   (0.1ms)  BEGIN
---------------------
TasksTest: test_tasks
---------------------
   (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-09-19 12:16:09.723766"], ["updated_at", "2016-09-19 12:16:09.723766"]]
   (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-09-19 12:16:09.725080"], ["updated_at", "2016-09-19 12:16:09.725080"]]
   (0.2ms)  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.3ms)  ROLLBACK
   (0.1ms)  BEGIN
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.3ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
   (0.2ms)  ROLLBACK
   (0.1ms)  BEGIN
---------------------
TasksTest: test_tasks
---------------------
   (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-09-19 12:16:30.054087"], ["updated_at", "2016-09-19 12:16:30.054087"]]
   (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-09-19 12:16:30.057267"], ["updated_at", "2016-09-19 12:16:30.057267"]]
   (0.1ms)  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.2ms)  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-09-19 12:16:30.066117"], ["updated_at", "2016-09-19 12:16:30.066117"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.9ms)  UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3  [["name", "john"], ["updated_at", "2016-09-19 12:16:30.067634"], ["id", 71]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 71]]
   (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_tasks
---------------------
   (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-09-19 12:16:45.921104"], ["updated_at", "2016-09-19 12:16:45.921104"]]
   (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-09-19 12:16:45.925106"], ["updated_at", "2016-09-19 12:16:45.925106"]]
   (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.5ms)  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-09-19 12:16:45.954185"], ["updated_at", "2016-09-19 12:16:45.954185"]]
   (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-09-19 12:16:45.956087"], ["id", 74]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 74]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
   (0.1ms)  BEGIN
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.5ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
   (0.2ms)  ROLLBACK
   (0.1ms)  BEGIN
---------------------
TasksTest: test_tasks
---------------------
   (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-09-19 12:18:21.741142"], ["updated_at", "2016-09-19 12:18:21.741142"]]
   (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-09-19 12:18:21.744972"], ["updated_at", "2016-09-19 12:18:21.744972"]]
   (0.1ms)  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.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-09-19 12:18:21.767140"], ["updated_at", "2016-09-19 12:18:21.767140"]]
   (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-09-19 12:18:21.768716"], ["id", 77]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 77]]
   (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_tasks
---------------------
   (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-09-19 12:19:04.624867"], ["updated_at", "2016-09-19 12:19:04.624867"]]
   (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-09-19 12:19:04.629119"], ["updated_at", "2016-09-19 12:19:04.629119"]]
   (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.5ms)  ROLLBACK
   (0.1ms)  BEGIN
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
   (0.1ms)  ROLLBACK
   (0.1ms)  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-09-19 12:19:04.659953"], ["updated_at", "2016-09-19 12:19:04.659953"]]
   (1.3ms)  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-09-19 12:19:04.663227"], ["id", 80]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 80]]
   (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.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-09-19 12:21:25.556957"], ["updated_at", "2016-09-19 12:21:25.556957"]]
   (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-09-19 12:21:25.562388"], ["id", 81]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 81]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
   (0.4ms)  BEGIN
---------------------
TasksTest: test_tasks
---------------------
   (0.3ms)  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-09-19 12:21:25.588101"], ["updated_at", "2016-09-19 12:21:25.588101"]]
   (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-09-19 12:21:25.589907"], ["updated_at", "2016-09-19 12:21:25.589907"]]
   (0.1ms)  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.2ms)  SELECT  "users".* FROM "users"  ORDER BY "users"."id" ASC LIMIT 1000
   (0.2ms)  ROLLBACK
   (0.1ms)  BEGIN
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (1.5ms)  BEGIN
--------------------------
RecordTest: test_callbacks
--------------------------
   (0.2ms)  SAVEPOINT active_record_1
  SQL (0.6ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id"  [["name", "mike"], ["created_at", "2016-09-19 12:22:15.157316"], ["updated_at", "2016-09-19 12:22:15.157316"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (0.3ms)  SAVEPOINT active_record_1
  SQL (0.4ms)  UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = $3  [["name", "john"], ["updated_at", "2016-09-19 12:22:15.163823"], ["id", 84]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.6ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 84]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (0.3ms)  ROLLBACK
   (0.1ms)  BEGIN
---------------------
TasksTest: test_tasks
---------------------
   (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-09-19 12:22:15.191340"], ["updated_at", "2016-09-19 12:22:15.191340"]]
   (0.4ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  SAVEPOINT active_record_1
  SQL (0.4ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id"  [["name", "john"], ["created_at", "2016-09-19 12:22:15.194465"], ["updated_at", "2016-09-19 12:22:15.194465"]]
   (0.2ms)  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.2ms)  ROLLBACK
   (0.1ms)  BEGIN
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.4ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
   (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-09-19 12:27:38.988822"], ["updated_at", "2016-09-19 12:27:38.988822"]]
   (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-09-19 12:27:38.993333"], ["id", 87]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  SAVEPOINT active_record_1
  SQL (0.3ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 87]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (0.3ms)  ROLLBACK
   (0.1ms)  BEGIN
---------------------
TasksTest: test_tasks
---------------------
   (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-09-19 12:27:39.011620"], ["updated_at", "2016-09-19 12:27:39.011620"]]
   (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-09-19 12:27:39.012975"], ["updated_at", "2016-09-19 12:27:39.012975"]]
   (0.3ms)  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.2ms)  SELECT  "users".* FROM "users"  ORDER BY "users"."id" ASC LIMIT 1000
   (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-09-19 12:29:00.292732"], ["updated_at", "2016-09-19 12:29:00.292732"]]
   (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-09-19 12:29:00.297973"], ["id", 90]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 90]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
   (0.1ms)  BEGIN
---------------------
TasksTest: test_tasks
---------------------
   (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-09-19 12:29:00.306351"], ["updated_at", "2016-09-19 12:29:00.306351"]]
   (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-09-19 12:29:00.307541"], ["updated_at", "2016-09-19 12:29:00.307541"]]
   (0.2ms)  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_file_generation
------------------------------------
   (0.3ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.3ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
---------------------
TasksTest: test_tasks
---------------------
   (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-09-19 12:30:05.417272"], ["updated_at", "2016-09-19 12:30:05.417272"]]
   (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-09-19 12:30:05.420699"], ["updated_at", "2016-09-19 12:30:05.420699"]]
   (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_file_generation
------------------------------------
   (0.2ms)  ROLLBACK
   (0.2ms)  BEGIN
--------------------------
RecordTest: test_callbacks
--------------------------
   (1.1ms)  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-09-19 12:30:05.446005"], ["updated_at", "2016-09-19 12:30:05.446005"]]
   (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-09-19 12:30:05.449777"], ["id", 95]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (1.4ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 95]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.5ms)  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-09-19 12:30:39.169109"], ["updated_at", "2016-09-19 12:30:39.169109"]]
   (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-09-19 12:30:39.174684"], ["id", 96]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 96]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
   (0.1ms)  BEGIN
---------------------
TasksTest: test_tasks
---------------------
   (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-09-19 12:30:39.182524"], ["updated_at", "2016-09-19 12:30:39.182524"]]
   (0.1ms)  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-09-19 12:30:39.183815"], ["updated_at", "2016-09-19 12:30:39.183815"]]
   (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.2ms)  BEGIN
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.3ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
   (0.2ms)  ROLLBACK
   (0.2ms)  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-09-19 12:31:01.640434"], ["updated_at", "2016-09-19 12:31:01.640434"]]
   (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-09-19 12:31:01.644835"], ["id", 99]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 99]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.8ms)  ROLLBACK
   (0.3ms)  BEGIN
---------------------
TasksTest: test_tasks
---------------------
   (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-09-19 12:31:01.657358"], ["updated_at", "2016-09-19 12:31:01.657358"]]
   (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-09-19 12:31:01.658715"], ["updated_at", "2016-09-19 12:31:01.658715"]]
   (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.4ms)  SELECT  "users".* FROM "users"  ORDER BY "users"."id" ASC LIMIT 1000
   (0.2ms)  ROLLBACK
  ActiveRecord::SchemaMigration Load (0.3ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
---------------------
TasksTest: test_tasks
---------------------
   (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-09-19 12:31:48.755309"], ["updated_at", "2016-09-19 12:31:48.755309"]]
   (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-09-19 12:31:48.759186"], ["updated_at", "2016-09-19 12:31:48.759186"]]
   (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.3ms)  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-09-19 12:31:48.773814"], ["updated_at", "2016-09-19 12:31:48.773814"]]
   (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-09-19 12:31:48.775616"], ["id", 104]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 104]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (1.3ms)  ROLLBACK
   (0.3ms)  BEGIN
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
   (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-09-19 15:00:47.191518"], ["updated_at", "2016-09-19 15:00:47.191518"]]
   (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-09-19 15:00:47.196755"], ["id", 105]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 105]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
   (0.1ms)  BEGIN
---------------------
TasksTest: test_tasks
---------------------
   (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-09-19 15:00:47.204941"], ["updated_at", "2016-09-19 15:00:47.204941"]]
   (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-09-19 15:00:47.206203"], ["updated_at", "2016-09-19 15:00:47.206203"]]
   (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.2ms)  ROLLBACK
   (0.2ms)  BEGIN
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
   (0.4ms)  ROLLBACK
   (0.1ms)  BEGIN
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
   (0.2ms)  ROLLBACK
   (0.2ms)  BEGIN
--------------------------
RecordTest: test_callbacks
--------------------------
   (0.2ms)  SAVEPOINT active_record_1
  SQL (3.7ms)  INSERT INTO "users" ("created_at", "name", "updated_at") VALUES ($1, $2, $3) RETURNING "id"  [["created_at", Mon, 19 Sep 2016 16:01:19 UTC +00:00], ["name", "mike"], ["updated_at", Mon, 19 Sep 2016 16:01:19 UTC +00:00]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
   (0.1ms)  BEGIN
---------------------
TasksTest: test_tasks
---------------------
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.3ms)  INSERT INTO "users" ("created_at", "name", "updated_at") VALUES ($1, $2, $3) RETURNING "id"  [["created_at", Mon, 19 Sep 2016 16:01:19 UTC +00:00], ["name", "mike"], ["updated_at", Mon, 19 Sep 2016 16:01:19 UTC +00:00]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  INSERT INTO "users" ("created_at", "name", "updated_at") VALUES ($1, $2, $3) RETURNING "id"  [["created_at", Mon, 19 Sep 2016 16:01:19 UTC +00:00], ["name", "john"], ["updated_at", Mon, 19 Sep 2016 16:01:19 UTC +00:00]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
   (0.1ms)  BEGIN
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
   (0.2ms)  ROLLBACK
   (0.2ms)  BEGIN
--------------------------
RecordTest: test_callbacks
--------------------------
   (0.2ms)  SAVEPOINT active_record_1
  SQL (2.7ms)  INSERT INTO "users" ("created_at", "name", "updated_at") VALUES ($1, $2, $3) RETURNING "id"  [["created_at", Mon, 19 Sep 2016 16:01:46 UTC +00:00], ["name", "mike"], ["updated_at", Mon, 19 Sep 2016 16:01:46 UTC +00:00]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.4ms)  ROLLBACK
   (0.1ms)  BEGIN
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
   (0.2ms)  ROLLBACK
   (0.2ms)  BEGIN
--------------------------
RecordTest: test_callbacks
--------------------------
   (0.2ms)  SAVEPOINT active_record_1
  SQL (3.0ms)  INSERT INTO "users" ("created_at", "name", "updated_at") VALUES ($1, $2, $3) RETURNING "id"  [["created_at", Mon, 19 Sep 2016 16:04:49 UTC +00:00], ["name", "mike"], ["updated_at", Mon, 19 Sep 2016 16:04:49 UTC +00:00]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.5ms)  UPDATE "users" SET "name" = $1, "updated_at" = $2 WHERE "users"."id" = 112  [["name", "john"], ["updated_at", Mon, 19 Sep 2016 16:04:49 UTC +00:00]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 112]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  ROLLBACK
   (0.1ms)  BEGIN
---------------------
TasksTest: test_tasks
---------------------
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  INSERT INTO "users" ("created_at", "name", "updated_at") VALUES ($1, $2, $3) RETURNING "id"  [["created_at", Mon, 19 Sep 2016 16:04:49 UTC +00:00], ["name", "mike"], ["updated_at", Mon, 19 Sep 2016 16:04:49 UTC +00:00]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (1.4ms)  INSERT INTO "users" ("created_at", "name", "updated_at") VALUES ($1, $2, $3) RETURNING "id"  [["created_at", Mon, 19 Sep 2016 16:04:49 UTC +00:00], ["name", "john"], ["updated_at", Mon, 19 Sep 2016 16:04:49 UTC +00:00]]
   (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
  ActiveRecord::SchemaMigration Load (0.5ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  BEGIN
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
   (0.2ms)  ROLLBACK
   (0.2ms)  BEGIN
--------------------------
RecordTest: test_callbacks
--------------------------
   (0.3ms)  SAVEPOINT active_record_1
  SQL (6.3ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id"  [["name", "mike"], ["created_at", "2016-09-19 23:56:52.497038"], ["updated_at", "2016-09-19 23:56:52.497038"]]
   (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-09-19 23:56:52.507525"], ["id", 115]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.3ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  DELETE FROM "users" WHERE "users"."id" = $1  [["id", 115]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK
   (0.2ms)  BEGIN
---------------------
TasksTest: test_tasks
---------------------
   (0.2ms)  SAVEPOINT active_record_1
  SQL (14.3ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id"  [["name", "mike"], ["created_at", "2016-09-19 23:56:52.524641"], ["updated_at", "2016-09-19 23:56:52.524641"]]
   (0.3ms)  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-09-19 23:56:52.540538"], ["updated_at", "2016-09-19 23:56:52.540538"]]
   (0.2ms)  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.3ms)  ROLLBACK
------------------------
CronjobsTest: test_write
------------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------
CronjobsTest: test_write
------------------------
---------------------
TasksTest: test_tasks
---------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------
CronjobsTest: test_write
------------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------
CronjobsTest: test_write
------------------------
---------------------
TasksTest: test_tasks
---------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------
CronjobsTest: test_write
------------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------
CronjobsTest: test_write
------------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------
CronjobsTest: test_write
------------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------
CronjobsTest: test_write
------------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------
CronjobsTest: test_write
------------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------
CronjobsTest: test_write
------------------------
---------------------
TasksTest: test_tasks
---------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------
CronjobsTest: test_write
------------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------
CronjobsTest: test_write
------------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------
CronjobsTest: test_write
------------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------
CronjobsTest: test_write
------------------------
---------------------
TasksTest: test_tasks
---------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------
CronjobsTest: test_write
------------------------
---------------------
TasksTest: test_tasks
---------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
---------------------
TasksTest: test_tasks
---------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
---------------------
TasksTest: test_check
---------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------
CronjobsTest: test_write
------------------------
---------------------
TasksTest: test_check
---------------------
---------------------
TasksTest: test_check
---------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------
CronjobsTest: test_write
------------------------
---------------------
TasksTest: test_check
---------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------
CronjobsTest: test_write
------------------------
---------------------
TasksTest: test_check
---------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------
CronjobsTest: test_write
------------------------
---------------------
TasksTest: test_check
---------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
------------------------
CronjobsTest: test_write
------------------------
---------------------
TasksTest: test_check
---------------------
------------------------
CronjobsTest: test_write
------------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------
---------------------
TasksTest: test_check
---------------------
------------------------
CronjobsTest: test_write
------------------------
---------------------
TasksTest: test_check
---------------------
------------------------------------
GeneratorsTest: test_file_generation
------------------------------------