(0.4ms)  SELECT sqlite_version(*)
   (1.4ms)  CREATE TABLE "schema_migrations" ("version" varchar NOT NULL PRIMARY KEY)
   (1.1ms)  CREATE TABLE "ar_internal_metadata" ("key" varchar NOT NULL PRIMARY KEY, "value" varchar, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)
   (0.2ms)  SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC
Migrating to CreateApprovalRequests (20170816102906)
   (0.1ms)  begin transaction
   (0.5ms)  CREATE TABLE "approval_requests" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "request_user_id" integer NOT NULL, "respond_user_id" integer, "state" integer(1) DEFAULT 0 NOT NULL, "requested_at" datetime NOT NULL, "cancelled_at" datetime, "approved_at" datetime, "rejected_at" datetime, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)
   (0.1ms)  CREATE  INDEX "index_approval_requests_on_request_user_id" ON "approval_requests" ("request_user_id")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_approval_requests_on_request_user_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_approval_requests_on_request_user_id' AND type='index'

   (0.1ms)  CREATE  INDEX "index_approval_requests_on_respond_user_id" ON "approval_requests" ("respond_user_id")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_approval_requests_on_respond_user_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_approval_requests_on_respond_user_id' AND type='index'

   (0.0ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_approval_requests_on_request_user_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_approval_requests_on_request_user_id' AND type='index'

   (0.1ms)  CREATE  INDEX "index_approval_requests_on_state" ON "approval_requests" ("state")
  SQL (0.2ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20170816102906"]]
   (0.7ms)  commit transaction
Migrating to CreateApprovalComments (20170816102907)
   (0.0ms)  begin transaction
   (0.4ms)  CREATE TABLE "approval_comments" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "request_id" integer NOT NULL, "user_id" integer NOT NULL, "content" text NOT NULL, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)
   (0.1ms)  CREATE  INDEX "index_approval_comments_on_request_id" ON "approval_comments" ("request_id")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_approval_comments_on_request_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_approval_comments_on_request_id' AND type='index'

   (0.2ms)  CREATE  INDEX "index_approval_comments_on_user_id" ON "approval_comments" ("user_id")
  SQL (0.1ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20170816102907"]]
   (0.6ms)  commit transaction
Migrating to CreateApprovalItems (20170816102908)
   (0.0ms)  begin transaction
   (0.5ms)  CREATE TABLE "approval_items" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "request_id" integer NOT NULL, "resource_id" integer, "resource_type" varchar NOT NULL, "event" varchar NOT NULL, "params" text, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)
   (0.1ms)  CREATE  INDEX "index_approval_items_on_request_id" ON "approval_items" ("request_id")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_approval_items_on_request_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_approval_items_on_request_id' AND type='index'

   (0.1ms)  CREATE  INDEX "index_approval_items_on_resource_id_and_resource_type" ON "approval_items" ("resource_id", "resource_type")
  SQL (0.3ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20170816102908"]]
   (1.4ms)  commit transaction
Migrating to CreateUsers (20170816102909)
   (0.1ms)  begin transaction
   (1.2ms)  CREATE TABLE "users" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)
  SQL (0.2ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20170816102909"]]
   (1.0ms)  commit transaction
Migrating to CreateBooks (20170816102910)
   (0.1ms)  begin transaction
   (0.8ms)  CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)
  SQL (0.3ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20170816102910"]]
   (0.7ms)  commit transaction
  ActiveRecord::InternalMetadata Load (0.2ms)  SELECT  "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ?  [["key", "environment"], ["LIMIT", 1]]
   (0.1ms)  begin transaction
  SQL (0.5ms)  INSERT INTO "ar_internal_metadata" ("key", "value", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["key", "environment"], ["value", "test"], ["created_at", "2017-08-16 10:29:11.412092"], ["updated_at", "2017-08-16 10:29:11.412092"]]
   (2.0ms)  commit transaction
   (0.2ms)  SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_approval_comments_on_user_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_approval_comments_on_user_id' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_approval_comments_on_request_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_approval_comments_on_request_id' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_approval_items_on_resource_id_and_resource_type' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_approval_items_on_resource_id_and_resource_type' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_approval_items_on_request_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_approval_items_on_request_id' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_approval_requests_on_state' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_approval_requests_on_state' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_approval_requests_on_respond_user_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_approval_requests_on_respond_user_id' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_approval_requests_on_request_user_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_approval_requests_on_request_user_id' AND type='index'

   (0.0ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.6ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name1"], ["created_at", "2017-08-16 10:29:12.910577"], ["updated_at", "2017-08-16 10:29:12.910577"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (1.2ms)  rollback transaction
   (0.2ms)  begin transaction
   (0.1ms)  SAVEPOINT active_record_1
  SQL (1.1ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name2"], ["created_at", "2017-08-16 10:29:12.925759"], ["updated_at", "2017-08-16 10:29:12.925759"]]
   (0.3ms)  RELEASE SAVEPOINT active_record_1
   (0.6ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.4ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name3"], ["created_at", "2017-08-16 10:29:12.933324"], ["updated_at", "2017-08-16 10:29:12.933324"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.6ms)  rollback transaction
   (0.2ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.4ms)  begin transaction
   (0.7ms)  SAVEPOINT active_record_1
  SQL (2.4ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name4"], ["created_at", "2017-08-16 10:29:12.952313"], ["updated_at", "2017-08-16 10:29:12.952313"]]
   (0.6ms)  RELEASE SAVEPOINT active_record_1
   (1.1ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.2ms)  SAVEPOINT active_record_1
  SQL (2.3ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name5"], ["created_at", "2017-08-16 10:29:13.003357"], ["updated_at", "2017-08-16 10:29:13.003357"]]
   (0.3ms)  RELEASE SAVEPOINT active_record_1
  Approval::Request Load (0.3ms)  SELECT  "approval_requests".* FROM "approval_requests" WHERE "approval_requests"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  Book Load (0.2ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
   (0.7ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.3ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name6"], ["created_at", "2017-08-16 10:29:13.064518"], ["updated_at", "2017-08-16 10:29:13.064518"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.3ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.5ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name7"], ["created_at", "2017-08-16 10:29:13.068217"], ["updated_at", "2017-08-16 10:29:13.068217"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.9ms)  rollback transaction
   (0.5ms)  begin transaction
   (0.2ms)  rollback transaction
   (0.6ms)  begin transaction
   (0.2ms)  SAVEPOINT active_record_1
  SQL (0.4ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name1"], ["created_at", "2017-08-16 10:29:13.131542"], ["updated_at", "2017-08-16 10:29:13.131542"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.3ms)  SAVEPOINT active_record_1
  SQL (0.9ms)  INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["request_user_id", 1], ["requested_at", "2017-08-16 10:29:13.218079"], ["created_at", "2017-08-16 10:29:13.217908"], ["updated_at", "2017-08-16 10:29:13.217908"]]
  SQL (2.1ms)  INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?)  [["request_id", 1], ["user_id", 1], ["content", "content1"], ["created_at", "2017-08-16 10:29:13.221334"], ["updated_at", "2017-08-16 10:29:13.221334"]]
  SQL (12.1ms)  INSERT INTO "approval_items" ("request_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_type", "Book"], ["event", "create"], ["params", "---\n:name: created_name\n"], ["created_at", "2017-08-16 10:29:13.228357"], ["updated_at", "2017-08-16 10:29:13.228357"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.4ms)  SELECT COUNT(*) FROM "books"
   (0.2ms)  SAVEPOINT active_record_1
  SQL (1.6ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "created_name"], ["created_at", "2017-08-16 10:29:13.251467"], ["updated_at", "2017-08-16 10:29:13.251467"]]
   (0.3ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  SAVEPOINT active_record_1
  Book Load (0.2ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  SQL (0.2ms)  UPDATE "approval_items" SET "resource_id" = ?, "updated_at" = ? WHERE "approval_items"."id" = ?  [["resource_id", 1], ["updated_at", "2017-08-16 10:29:13.258767"], ["id", 1]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SELECT COUNT(*) FROM "books"
   (0.5ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.6ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name2"], ["created_at", "2017-08-16 10:29:13.267927"], ["updated_at", "2017-08-16 10:29:13.267927"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  SAVEPOINT active_record_1
  SQL (0.4ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name8"], ["created_at", "2017-08-16 10:29:13.274514"], ["updated_at", "2017-08-16 10:29:13.274514"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  Book Load (0.1ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  Book Load (0.1ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  SQL (0.3ms)  INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["request_user_id", 1], ["requested_at", "2017-08-16 10:29:13.281475"], ["created_at", "2017-08-16 10:29:13.281375"], ["updated_at", "2017-08-16 10:29:13.281375"]]
  SQL (0.6ms)  INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?)  [["request_id", 1], ["user_id", 1], ["content", "content2"], ["created_at", "2017-08-16 10:29:13.283945"], ["updated_at", "2017-08-16 10:29:13.283945"]]
  Book Load (0.1ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  SQL (0.5ms)  INSERT INTO "approval_items" ("request_id", "resource_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_id", 1], ["resource_type", "Book"], ["event", "update"], ["params", "---\n:name: updated_name\n"], ["created_at", "2017-08-16 10:29:13.287386"], ["updated_at", "2017-08-16 10:29:13.287386"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
  Book Load (0.4ms)  SELECT  "books".* FROM "books" ORDER BY "books"."id" ASC LIMIT ?  [["LIMIT", 1]]
  Book Load (0.2ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
   (0.2ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  UPDATE "books" SET "name" = ?, "updated_at" = ? WHERE "books"."id" = ?  [["name", "updated_name"], ["updated_at", "2017-08-16 10:29:13.301735"], ["id", 1]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
  Book Load (0.1ms)  SELECT  "books".* FROM "books" ORDER BY "books"."id" ASC LIMIT ?  [["LIMIT", 1]]
   (0.7ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.5ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name3"], ["created_at", "2017-08-16 10:29:13.314093"], ["updated_at", "2017-08-16 10:29:13.314093"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name9"], ["created_at", "2017-08-16 10:29:13.319423"], ["updated_at", "2017-08-16 10:29:13.319423"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.3ms)  SAVEPOINT active_record_1
  SQL (0.9ms)  INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["request_user_id", 1], ["requested_at", "2017-08-16 10:29:13.328434"], ["created_at", "2017-08-16 10:29:13.328306"], ["updated_at", "2017-08-16 10:29:13.328306"]]
  SQL (0.4ms)  INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?)  [["request_id", 1], ["user_id", 1], ["content", "content3"], ["created_at", "2017-08-16 10:29:13.333989"], ["updated_at", "2017-08-16 10:29:13.333989"]]
  SQL (0.5ms)  INSERT INTO "approval_items" ("request_id", "resource_id", "resource_type", "event", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_id", 1], ["resource_type", "Book"], ["event", "destroy"], ["created_at", "2017-08-16 10:29:13.337231"], ["updated_at", "2017-08-16 10:29:13.337231"]]
   (1.1ms)  RELEASE SAVEPOINT active_record_1
   (0.4ms)  SELECT COUNT(*) FROM "books"
  Book Load (0.7ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
   (0.9ms)  SAVEPOINT active_record_1
  SQL (0.3ms)  DELETE FROM "books" WHERE "books"."id" = ?  [["id", 1]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (0.3ms)  SELECT COUNT(*) FROM "books"
   (4.5ms)  rollback transaction
   (0.2ms)  begin transaction
   (0.2ms)  SELECT COUNT(*) FROM "approval_items"
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.5ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name4"], ["created_at", "2017-08-16 10:29:13.388872"], ["updated_at", "2017-08-16 10:29:13.388872"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name10"], ["created_at", "2017-08-16 10:29:13.392155"], ["updated_at", "2017-08-16 10:29:13.392155"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  Book Load (0.2ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  Book Load (0.6ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  User Load (0.8ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  SQL (1.7ms)  INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["request_user_id", 1], ["requested_at", "2017-08-16 10:29:13.431048"], ["created_at", "2017-08-16 10:29:13.430793"], ["updated_at", "2017-08-16 10:29:13.430793"]]
  SQL (0.4ms)  INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?)  [["request_id", 1], ["user_id", 1], ["content", "reason"], ["created_at", "2017-08-16 10:29:13.445731"], ["updated_at", "2017-08-16 10:29:13.445731"]]
  Book Load (0.2ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  SQL (0.4ms)  INSERT INTO "approval_items" ("request_id", "resource_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_id", 1], ["resource_type", "Book"], ["event", "update"], ["params", "---\nname: changed name\n"], ["created_at", "2017-08-16 10:29:13.451530"], ["updated_at", "2017-08-16 10:29:13.451530"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  SELECT COUNT(*) FROM "approval_items"
   (3.0ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.4ms)  SAVEPOINT active_record_1
  SQL (1.1ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name5"], ["created_at", "2017-08-16 10:29:13.589497"], ["updated_at", "2017-08-16 10:29:13.589497"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (0.5ms)  SAVEPOINT active_record_1
  SQL (0.3ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name11"], ["created_at", "2017-08-16 10:29:13.598261"], ["updated_at", "2017-08-16 10:29:13.598261"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  Book Load (0.1ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  Book Load (0.1ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  User Load (0.1ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  SQL (0.2ms)  INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["request_user_id", 1], ["requested_at", "2017-08-16 10:29:13.617332"], ["created_at", "2017-08-16 10:29:13.617258"], ["updated_at", "2017-08-16 10:29:13.617258"]]
  SQL (0.2ms)  INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?)  [["request_id", 1], ["user_id", 1], ["content", "reason"], ["created_at", "2017-08-16 10:29:13.618645"], ["updated_at", "2017-08-16 10:29:13.618645"]]
  Book Load (0.1ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  SQL (0.5ms)  INSERT INTO "approval_items" ("request_id", "resource_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_id", 1], ["resource_type", "Book"], ["event", "update"], ["params", "---\nname: changed name\n"], ["created_at", "2017-08-16 10:29:13.623364"], ["updated_at", "2017-08-16 10:29:13.623364"]]
   (0.3ms)  RELEASE SAVEPOINT active_record_1
   (2.8ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.2ms)  SELECT COUNT(*) FROM "approval_items"
   (0.1ms)  SAVEPOINT active_record_1
  SQL (1.4ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name6"], ["created_at", "2017-08-16 10:29:13.638158"], ["updated_at", "2017-08-16 10:29:13.638158"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (1.1ms)  SAVEPOINT active_record_1
  SQL (0.6ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name12"], ["created_at", "2017-08-16 10:29:13.649925"], ["updated_at", "2017-08-16 10:29:13.649925"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name13"], ["created_at", "2017-08-16 10:29:13.657319"], ["updated_at", "2017-08-16 10:29:13.657319"]]
   (0.4ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.1ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name14"], ["created_at", "2017-08-16 10:29:13.659627"], ["updated_at", "2017-08-16 10:29:13.659627"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  Book Load (0.1ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  Book Load (0.1ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 2], ["LIMIT", 1]]
  Book Load (0.1ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 3], ["LIMIT", 1]]
  Book Load (0.1ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  Book Load (0.1ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 2], ["LIMIT", 1]]
  Book Load (0.1ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 3], ["LIMIT", 1]]
  User Load (0.1ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  SQL (0.5ms)  INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["request_user_id", 1], ["requested_at", "2017-08-16 10:29:13.682479"], ["created_at", "2017-08-16 10:29:13.682382"], ["updated_at", "2017-08-16 10:29:13.682382"]]
  SQL (0.4ms)  INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?)  [["request_id", 1], ["user_id", 1], ["content", "reason"], ["created_at", "2017-08-16 10:29:13.684853"], ["updated_at", "2017-08-16 10:29:13.684853"]]
  Book Load (0.1ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  SQL (0.8ms)  INSERT INTO "approval_items" ("request_id", "resource_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_id", 1], ["resource_type", "Book"], ["event", "update"], ["params", "---\nname: changed name 0\n"], ["created_at", "2017-08-16 10:29:13.688261"], ["updated_at", "2017-08-16 10:29:13.688261"]]
  Book Load (0.2ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 2], ["LIMIT", 1]]
  SQL (0.2ms)  INSERT INTO "approval_items" ("request_id", "resource_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_id", 2], ["resource_type", "Book"], ["event", "update"], ["params", "---\nname: changed name 1\n"], ["created_at", "2017-08-16 10:29:13.693663"], ["updated_at", "2017-08-16 10:29:13.693663"]]
  Book Load (0.1ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 3], ["LIMIT", 1]]
  SQL (0.2ms)  INSERT INTO "approval_items" ("request_id", "resource_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_id", 3], ["resource_type", "Book"], ["event", "update"], ["params", "---\nname: changed name 2\n"], ["created_at", "2017-08-16 10:29:13.697463"], ["updated_at", "2017-08-16 10:29:13.697463"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SELECT COUNT(*) FROM "approval_items"
   (4.0ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.3ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name7"], ["created_at", "2017-08-16 10:29:13.709461"], ["updated_at", "2017-08-16 10:29:13.709461"]]
   (0.3ms)  RELEASE SAVEPOINT active_record_1
   (0.0ms)  SAVEPOINT active_record_1
  SQL (0.1ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name15"], ["created_at", "2017-08-16 10:29:13.711739"], ["updated_at", "2017-08-16 10:29:13.711739"]]
   (0.3ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.1ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name16"], ["created_at", "2017-08-16 10:29:13.713542"], ["updated_at", "2017-08-16 10:29:13.713542"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.1ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name17"], ["created_at", "2017-08-16 10:29:13.715705"], ["updated_at", "2017-08-16 10:29:13.715705"]]
   (0.0ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  Book Load (0.1ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  Book Load (0.1ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 2], ["LIMIT", 1]]
  Book Load (0.1ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 3], ["LIMIT", 1]]
  Book Load (0.2ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  Book Load (0.4ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 2], ["LIMIT", 1]]
  Book Load (0.2ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 3], ["LIMIT", 1]]
  User Load (0.6ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  SQL (0.5ms)  INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["request_user_id", 1], ["requested_at", "2017-08-16 10:29:13.758420"], ["created_at", "2017-08-16 10:29:13.758143"], ["updated_at", "2017-08-16 10:29:13.758143"]]
  SQL (0.7ms)  INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?)  [["request_id", 1], ["user_id", 1], ["content", "reason"], ["created_at", "2017-08-16 10:29:13.763633"], ["updated_at", "2017-08-16 10:29:13.763633"]]
  Book Load (0.2ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  SQL (0.8ms)  INSERT INTO "approval_items" ("request_id", "resource_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_id", 1], ["resource_type", "Book"], ["event", "update"], ["params", "---\nname: changed name 0\n"], ["created_at", "2017-08-16 10:29:13.769135"], ["updated_at", "2017-08-16 10:29:13.769135"]]
  Book Load (0.2ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 2], ["LIMIT", 1]]
  SQL (0.3ms)  INSERT INTO "approval_items" ("request_id", "resource_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_id", 2], ["resource_type", "Book"], ["event", "update"], ["params", "---\nname: changed name 1\n"], ["created_at", "2017-08-16 10:29:13.783363"], ["updated_at", "2017-08-16 10:29:13.783363"]]
  Book Load (0.3ms)  SELECT  "books".* FROM "books" WHERE "books"."id" = ? LIMIT ?  [["id", 3], ["LIMIT", 1]]
  SQL (0.9ms)  INSERT INTO "approval_items" ("request_id", "resource_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_id", 3], ["resource_type", "Book"], ["event", "update"], ["params", "---\nname: changed name 2\n"], ["created_at", "2017-08-16 10:29:13.791517"], ["updated_at", "2017-08-16 10:29:13.791517"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (1.5ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.4ms)  rollback transaction
   (0.3ms)  begin transaction
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.6ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name8"], ["created_at", "2017-08-16 10:29:13.824603"], ["updated_at", "2017-08-16 10:29:13.824603"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.5ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.8ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name9"], ["created_at", "2017-08-16 10:29:13.832270"], ["updated_at", "2017-08-16 10:29:13.832270"]]
   (0.3ms)  RELEASE SAVEPOINT active_record_1
   (3.9ms)  rollback transaction
   (1.3ms)  begin transaction
   (0.2ms)  SAVEPOINT active_record_1
  SQL (0.6ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name10"], ["created_at", "2017-08-16 10:29:13.865461"], ["updated_at", "2017-08-16 10:29:13.865461"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.5ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.3ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name11"], ["created_at", "2017-08-16 10:29:13.873440"], ["updated_at", "2017-08-16 10:29:13.873440"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.3ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.3ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name12"], ["created_at", "2017-08-16 10:29:13.882784"], ["updated_at", "2017-08-16 10:29:13.882784"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.5ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.3ms)  SAVEPOINT active_record_1
  SQL (0.9ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name13"], ["created_at", "2017-08-16 10:29:13.897855"], ["updated_at", "2017-08-16 10:29:13.897855"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (0.7ms)  rollback transaction
   (0.1ms)  begin transaction
   (1.7ms)  rollback transaction
   (0.7ms)  begin transaction
   (0.8ms)  SELECT COUNT(*) FROM "approval_items"
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.9ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name14"], ["created_at", "2017-08-16 10:29:13.936597"], ["updated_at", "2017-08-16 10:29:13.936597"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (1.5ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name24"], ["created_at", "2017-08-16 10:29:13.942357"], ["updated_at", "2017-08-16 10:29:13.942357"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  User Load (0.7ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  SQL (0.6ms)  INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["request_user_id", 1], ["requested_at", "2017-08-16 10:29:13.955357"], ["created_at", "2017-08-16 10:29:13.955212"], ["updated_at", "2017-08-16 10:29:13.955212"]]
  SQL (2.1ms)  INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?)  [["request_id", 1], ["user_id", 1], ["content", "reason"], ["created_at", "2017-08-16 10:29:13.967243"], ["updated_at", "2017-08-16 10:29:13.967243"]]
  SQL (0.3ms)  INSERT INTO "approval_items" ("request_id", "resource_id", "resource_type", "event", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_id", 1], ["resource_type", "Book"], ["event", "destroy"], ["created_at", "2017-08-16 10:29:13.973919"], ["updated_at", "2017-08-16 10:29:13.973919"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.4ms)  SELECT COUNT(*) FROM "approval_items"
   (2.3ms)  rollback transaction
   (0.7ms)  begin transaction
   (0.6ms)  SAVEPOINT active_record_1
  SQL (1.9ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name15"], ["created_at", "2017-08-16 10:29:14.032836"], ["updated_at", "2017-08-16 10:29:14.032836"]]
   (0.4ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  SAVEPOINT active_record_1
  SQL (0.6ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name25"], ["created_at", "2017-08-16 10:29:14.042281"], ["updated_at", "2017-08-16 10:29:14.042281"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (0.4ms)  SAVEPOINT active_record_1
  User Load (0.3ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  SQL (0.5ms)  INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["request_user_id", 1], ["requested_at", "2017-08-16 10:29:14.078718"], ["created_at", "2017-08-16 10:29:14.078190"], ["updated_at", "2017-08-16 10:29:14.078190"]]
  SQL (0.2ms)  INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?)  [["request_id", 1], ["user_id", 1], ["content", "reason"], ["created_at", "2017-08-16 10:29:14.083360"], ["updated_at", "2017-08-16 10:29:14.083360"]]
  SQL (0.2ms)  INSERT INTO "approval_items" ("request_id", "resource_id", "resource_type", "event", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_id", 1], ["resource_type", "Book"], ["event", "destroy"], ["created_at", "2017-08-16 10:29:14.085003"], ["updated_at", "2017-08-16 10:29:14.085003"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (4.0ms)  rollback transaction
   (0.5ms)  begin transaction
   (0.4ms)  SAVEPOINT active_record_1
  SQL (2.4ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name16"], ["created_at", "2017-08-16 10:29:14.103652"], ["updated_at", "2017-08-16 10:29:14.103652"]]
   (0.4ms)  RELEASE SAVEPOINT active_record_1
   (1.0ms)  SAVEPOINT active_record_1
  SQL (0.6ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name26"], ["created_at", "2017-08-16 10:29:14.123480"], ["updated_at", "2017-08-16 10:29:14.123480"]]
   (0.3ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name27"], ["created_at", "2017-08-16 10:29:14.130138"], ["updated_at", "2017-08-16 10:29:14.130138"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name28"], ["created_at", "2017-08-16 10:29:14.132773"], ["updated_at", "2017-08-16 10:29:14.132773"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (9.2ms)  SAVEPOINT active_record_1
  User Load (0.1ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  SQL (0.3ms)  INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["request_user_id", 1], ["requested_at", "2017-08-16 10:29:14.152683"], ["created_at", "2017-08-16 10:29:14.152615"], ["updated_at", "2017-08-16 10:29:14.152615"]]
  SQL (0.6ms)  INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?)  [["request_id", 1], ["user_id", 1], ["content", "reason"], ["created_at", "2017-08-16 10:29:14.158934"], ["updated_at", "2017-08-16 10:29:14.158934"]]
  SQL (0.7ms)  INSERT INTO "approval_items" ("request_id", "resource_id", "resource_type", "event", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_id", 1], ["resource_type", "Book"], ["event", "destroy"], ["created_at", "2017-08-16 10:29:14.162984"], ["updated_at", "2017-08-16 10:29:14.162984"]]
  SQL (0.4ms)  INSERT INTO "approval_items" ("request_id", "resource_id", "resource_type", "event", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_id", 2], ["resource_type", "Book"], ["event", "destroy"], ["created_at", "2017-08-16 10:29:14.169018"], ["updated_at", "2017-08-16 10:29:14.169018"]]
  SQL (0.5ms)  INSERT INTO "approval_items" ("request_id", "resource_id", "resource_type", "event", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_id", 3], ["resource_type", "Book"], ["event", "destroy"], ["created_at", "2017-08-16 10:29:14.172585"], ["updated_at", "2017-08-16 10:29:14.172585"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (4.7ms)  rollback transaction
   (0.4ms)  begin transaction
   (0.3ms)  SELECT COUNT(*) FROM "approval_items"
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.6ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name17"], ["created_at", "2017-08-16 10:29:14.197808"], ["updated_at", "2017-08-16 10:29:14.197808"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.4ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name29"], ["created_at", "2017-08-16 10:29:14.209287"], ["updated_at", "2017-08-16 10:29:14.209287"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name30"], ["created_at", "2017-08-16 10:29:14.213463"], ["updated_at", "2017-08-16 10:29:14.213463"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (0.0ms)  SAVEPOINT active_record_1
  SQL (0.1ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name31"], ["created_at", "2017-08-16 10:29:14.215825"], ["updated_at", "2017-08-16 10:29:14.215825"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  User Load (0.4ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  SQL (0.9ms)  INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["request_user_id", 1], ["requested_at", "2017-08-16 10:29:14.241882"], ["created_at", "2017-08-16 10:29:14.241698"], ["updated_at", "2017-08-16 10:29:14.241698"]]
  SQL (1.0ms)  INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?)  [["request_id", 1], ["user_id", 1], ["content", "reason"], ["created_at", "2017-08-16 10:29:14.249726"], ["updated_at", "2017-08-16 10:29:14.249726"]]
  SQL (0.4ms)  INSERT INTO "approval_items" ("request_id", "resource_id", "resource_type", "event", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_id", 1], ["resource_type", "Book"], ["event", "destroy"], ["created_at", "2017-08-16 10:29:14.257620"], ["updated_at", "2017-08-16 10:29:14.257620"]]
  SQL (0.2ms)  INSERT INTO "approval_items" ("request_id", "resource_id", "resource_type", "event", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_id", 2], ["resource_type", "Book"], ["event", "destroy"], ["created_at", "2017-08-16 10:29:14.263962"], ["updated_at", "2017-08-16 10:29:14.263962"]]
  SQL (7.0ms)  INSERT INTO "approval_items" ("request_id", "resource_id", "resource_type", "event", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_id", 3], ["resource_type", "Book"], ["event", "destroy"], ["created_at", "2017-08-16 10:29:14.266172"], ["updated_at", "2017-08-16 10:29:14.266172"]]
   (0.3ms)  RELEASE SAVEPOINT active_record_1
   (0.3ms)  SELECT COUNT(*) FROM "approval_items"
   (1.6ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.1ms)  SELECT COUNT(*) FROM "approval_items"
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.5ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name18"], ["created_at", "2017-08-16 10:29:14.286706"], ["updated_at", "2017-08-16 10:29:14.286706"]]
   (0.6ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  User Load (0.2ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  SQL (0.6ms)  INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["request_user_id", 1], ["requested_at", "2017-08-16 10:29:14.338950"], ["created_at", "2017-08-16 10:29:14.338831"], ["updated_at", "2017-08-16 10:29:14.338831"]]
  SQL (1.0ms)  INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?)  [["request_id", 1], ["user_id", 1], ["content", "reason"], ["created_at", "2017-08-16 10:29:14.344724"], ["updated_at", "2017-08-16 10:29:14.344724"]]
  SQL (0.2ms)  INSERT INTO "approval_items" ("request_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_type", "Book"], ["event", "create"], ["params", "---\nname: name32\n"], ["created_at", "2017-08-16 10:29:14.347963"], ["updated_at", "2017-08-16 10:29:14.347963"]]
  SQL (0.1ms)  INSERT INTO "approval_items" ("request_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_type", "Book"], ["event", "create"], ["params", "---\nname: name33\n"], ["created_at", "2017-08-16 10:29:14.349708"], ["updated_at", "2017-08-16 10:29:14.349708"]]
  SQL (0.1ms)  INSERT INTO "approval_items" ("request_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_type", "Book"], ["event", "create"], ["params", "---\nname: name34\n"], ["created_at", "2017-08-16 10:29:14.351140"], ["updated_at", "2017-08-16 10:29:14.351140"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SELECT COUNT(*) FROM "approval_items"
   (1.9ms)  rollback transaction
   (0.3ms)  begin transaction
   (0.3ms)  SAVEPOINT active_record_1
  SQL (2.1ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name19"], ["created_at", "2017-08-16 10:29:14.381566"], ["updated_at", "2017-08-16 10:29:14.381566"]]
   (0.7ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  User Load (0.1ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  SQL (0.2ms)  INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["request_user_id", 1], ["requested_at", "2017-08-16 10:29:14.418989"], ["created_at", "2017-08-16 10:29:14.418893"], ["updated_at", "2017-08-16 10:29:14.418893"]]
  SQL (0.4ms)  INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?)  [["request_id", 1], ["user_id", 1], ["content", "reason"], ["created_at", "2017-08-16 10:29:14.420503"], ["updated_at", "2017-08-16 10:29:14.420503"]]
  SQL (1.0ms)  INSERT INTO "approval_items" ("request_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_type", "Book"], ["event", "create"], ["params", "---\nname: name35\n"], ["created_at", "2017-08-16 10:29:14.428739"], ["updated_at", "2017-08-16 10:29:14.428739"]]
  SQL (0.3ms)  INSERT INTO "approval_items" ("request_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_type", "Book"], ["event", "create"], ["params", "---\nname: name36\n"], ["created_at", "2017-08-16 10:29:14.438139"], ["updated_at", "2017-08-16 10:29:14.438139"]]
  SQL (0.7ms)  INSERT INTO "approval_items" ("request_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_type", "Book"], ["event", "create"], ["params", "---\nname: name37\n"], ["created_at", "2017-08-16 10:29:14.443429"], ["updated_at", "2017-08-16 10:29:14.443429"]]
   (0.6ms)  RELEASE SAVEPOINT active_record_1
   (4.8ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.2ms)  SELECT COUNT(*) FROM "approval_items"
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.7ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name20"], ["created_at", "2017-08-16 10:29:14.468178"], ["updated_at", "2017-08-16 10:29:14.468178"]]
   (0.3ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  User Load (0.4ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  SQL (0.5ms)  INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["request_user_id", 1], ["requested_at", "2017-08-16 10:29:14.498817"], ["created_at", "2017-08-16 10:29:14.498186"], ["updated_at", "2017-08-16 10:29:14.498186"]]
  SQL (0.7ms)  INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?)  [["request_id", 1], ["user_id", 1], ["content", "reason"], ["created_at", "2017-08-16 10:29:14.504274"], ["updated_at", "2017-08-16 10:29:14.504274"]]
  SQL (1.8ms)  INSERT INTO "approval_items" ("request_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_type", "Book"], ["event", "create"], ["params", "---\nname: name38\n"], ["created_at", "2017-08-16 10:29:14.509145"], ["updated_at", "2017-08-16 10:29:14.509145"]]
   (0.5ms)  RELEASE SAVEPOINT active_record_1
   (1.2ms)  SELECT COUNT(*) FROM "approval_items"
   (1.7ms)  rollback transaction
   (0.2ms)  begin transaction
   (0.3ms)  SAVEPOINT active_record_1
  SQL (0.8ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name21"], ["created_at", "2017-08-16 10:29:14.547755"], ["updated_at", "2017-08-16 10:29:14.547755"]]
   (0.4ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  SAVEPOINT active_record_1
  User Load (0.6ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  SQL (0.5ms)  INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["request_user_id", 1], ["requested_at", "2017-08-16 10:29:14.602375"], ["created_at", "2017-08-16 10:29:14.601468"], ["updated_at", "2017-08-16 10:29:14.601468"]]
  SQL (0.3ms)  INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?)  [["request_id", 1], ["user_id", 1], ["content", "reason"], ["created_at", "2017-08-16 10:29:14.610056"], ["updated_at", "2017-08-16 10:29:14.610056"]]
  SQL (0.9ms)  INSERT INTO "approval_items" ("request_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_type", "Book"], ["event", "create"], ["params", "---\nname: name39\n"], ["created_at", "2017-08-16 10:29:14.619509"], ["updated_at", "2017-08-16 10:29:14.619509"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (1.9ms)  rollback transaction
   (0.5ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.2ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.2ms)  begin transaction
   (0.1ms)  SAVEPOINT active_record_1
  SQL (3.1ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name23"], ["created_at", "2017-08-16 10:29:14.646729"], ["updated_at", "2017-08-16 10:29:14.646729"]]
   (0.5ms)  RELEASE SAVEPOINT active_record_1
   (1.4ms)  rollback transaction
   (0.2ms)  begin transaction
   (0.2ms)  SAVEPOINT active_record_1
  SQL (0.6ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name25"], ["created_at", "2017-08-16 10:29:14.670694"], ["updated_at", "2017-08-16 10:29:14.670694"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.7ms)  rollback transaction
   (0.2ms)  begin transaction
   (0.2ms)  SAVEPOINT active_record_1
  SQL (1.8ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name27"], ["created_at", "2017-08-16 10:29:14.689275"], ["updated_at", "2017-08-16 10:29:14.689275"]]
   (0.3ms)  RELEASE SAVEPOINT active_record_1
   (1.5ms)  rollback transaction
   (0.2ms)  begin transaction
   (0.3ms)  rollback transaction
   (0.3ms)  begin transaction
   (0.5ms)  rollback transaction
   (0.2ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.2ms)  rollback transaction
   (0.2ms)  begin transaction
   (0.1ms)  SAVEPOINT active_record_1
  SQL (2.1ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name31"], ["created_at", "2017-08-16 10:29:14.774887"], ["updated_at", "2017-08-16 10:29:14.774887"]]
   (0.4ms)  RELEASE SAVEPOINT active_record_1
   (0.4ms)  SAVEPOINT active_record_1
  SQL (1.1ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name32"], ["created_at", "2017-08-16 10:29:14.785971"], ["updated_at", "2017-08-16 10:29:14.785971"]]
   (0.4ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  SAVEPOINT active_record_1
  SQL (0.4ms)  INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["request_user_id", 2], ["requested_at", "2017-08-16 10:29:14.811881"], ["created_at", "2017-08-16 10:29:14.811687"], ["updated_at", "2017-08-16 10:29:14.811687"]]
  SQL (0.2ms)  INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?)  [["request_id", 1], ["user_id", 2], ["content", "content4"], ["created_at", "2017-08-16 10:29:14.814678"], ["updated_at", "2017-08-16 10:29:14.814678"]]
  SQL (0.2ms)  INSERT INTO "approval_items" ("request_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_type", "Book"], ["event", "create"], ["params", "---\n:name: created_name\n"], ["created_at", "2017-08-16 10:29:14.816222"], ["updated_at", "2017-08-16 10:29:14.816222"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (1.5ms)  rollback transaction
   (0.5ms)  begin transaction
   (0.3ms)  SAVEPOINT active_record_1
  SQL (2.0ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name33"], ["created_at", "2017-08-16 10:29:14.830656"], ["updated_at", "2017-08-16 10:29:14.830656"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  SAVEPOINT active_record_1
  SQL (0.4ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name34"], ["created_at", "2017-08-16 10:29:14.841034"], ["updated_at", "2017-08-16 10:29:14.841034"]]
   (0.8ms)  RELEASE SAVEPOINT active_record_1
   (0.4ms)  SAVEPOINT active_record_1
  SQL (0.3ms)  INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["request_user_id", 2], ["requested_at", "2017-08-16 10:29:14.865376"], ["created_at", "2017-08-16 10:29:14.865235"], ["updated_at", "2017-08-16 10:29:14.865235"]]
  SQL (0.6ms)  INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?)  [["request_id", 1], ["user_id", 2], ["content", "content5"], ["created_at", "2017-08-16 10:29:14.875583"], ["updated_at", "2017-08-16 10:29:14.875583"]]
  SQL (0.2ms)  INSERT INTO "approval_items" ("request_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_type", "Book"], ["event", "create"], ["params", "---\n:name: created_name\n"], ["created_at", "2017-08-16 10:29:14.881197"], ["updated_at", "2017-08-16 10:29:14.881197"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (1.3ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.1ms)  SAVEPOINT active_record_1
  SQL (4.9ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name35"], ["created_at", "2017-08-16 10:29:14.886839"], ["updated_at", "2017-08-16 10:29:14.886839"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (0.3ms)  SAVEPOINT active_record_1
  SQL (0.4ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name36"], ["created_at", "2017-08-16 10:29:14.901757"], ["updated_at", "2017-08-16 10:29:14.901757"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (0.4ms)  SAVEPOINT active_record_1
  SQL (1.1ms)  INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["request_user_id", 2], ["requested_at", "2017-08-16 10:29:14.924809"], ["created_at", "2017-08-16 10:29:14.924198"], ["updated_at", "2017-08-16 10:29:14.924198"]]
  SQL (0.2ms)  INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?)  [["request_id", 1], ["user_id", 2], ["content", "content6"], ["created_at", "2017-08-16 10:29:14.931176"], ["updated_at", "2017-08-16 10:29:14.931176"]]
  SQL (0.2ms)  INSERT INTO "approval_items" ("request_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_type", "Book"], ["event", "create"], ["params", "---\n:name: created_name\n"], ["created_at", "2017-08-16 10:29:14.950169"], ["updated_at", "2017-08-16 10:29:14.950169"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (1.4ms)  rollback transaction
   (0.3ms)  begin transaction
   (0.5ms)  SAVEPOINT active_record_1
  SQL (1.5ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name37"], ["created_at", "2017-08-16 10:29:14.962067"], ["updated_at", "2017-08-16 10:29:14.962067"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (0.3ms)  SAVEPOINT active_record_1
  SQL (0.6ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name38"], ["created_at", "2017-08-16 10:29:14.973549"], ["updated_at", "2017-08-16 10:29:14.973549"]]
   (0.4ms)  RELEASE SAVEPOINT active_record_1
   (0.5ms)  SAVEPOINT active_record_1
  SQL (9.7ms)  INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["request_user_id", 2], ["requested_at", "2017-08-16 10:29:15.000627"], ["created_at", "2017-08-16 10:29:15.000494"], ["updated_at", "2017-08-16 10:29:15.000494"]]
  SQL (0.3ms)  INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?)  [["request_id", 1], ["user_id", 2], ["content", "content7"], ["created_at", "2017-08-16 10:29:15.014436"], ["updated_at", "2017-08-16 10:29:15.014436"]]
  SQL (0.2ms)  INSERT INTO "approval_items" ("request_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_type", "Book"], ["event", "create"], ["params", "---\n:name: created_name\n"], ["created_at", "2017-08-16 10:29:15.016973"], ["updated_at", "2017-08-16 10:29:15.016973"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (1.5ms)  rollback transaction
   (0.5ms)  begin transaction
   (0.2ms)  SAVEPOINT active_record_1
  SQL (1.3ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name39"], ["created_at", "2017-08-16 10:29:15.031929"], ["updated_at", "2017-08-16 10:29:15.031929"]]
   (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 (?, ?, ?)  [["name", "name40"], ["created_at", "2017-08-16 10:29:15.040313"], ["updated_at", "2017-08-16 10:29:15.040313"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (0.5ms)  SAVEPOINT active_record_1
  SQL (0.5ms)  INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["request_user_id", 2], ["requested_at", "2017-08-16 10:29:15.065728"], ["created_at", "2017-08-16 10:29:15.065575"], ["updated_at", "2017-08-16 10:29:15.065575"]]
  SQL (0.5ms)  INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?)  [["request_id", 1], ["user_id", 2], ["content", "content8"], ["created_at", "2017-08-16 10:29:15.076676"], ["updated_at", "2017-08-16 10:29:15.076676"]]
  SQL (0.5ms)  INSERT INTO "approval_items" ("request_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_type", "Book"], ["event", "create"], ["params", "---\n:name: created_name\n"], ["created_at", "2017-08-16 10:29:15.081516"], ["updated_at", "2017-08-16 10:29:15.081516"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.6ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.2ms)  SAVEPOINT active_record_1
  SQL (1.9ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name41"], ["created_at", "2017-08-16 10:29:15.092369"], ["updated_at", "2017-08-16 10:29:15.092369"]]
   (0.4ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  SAVEPOINT active_record_1
  SQL (0.5ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name42"], ["created_at", "2017-08-16 10:29:15.106149"], ["updated_at", "2017-08-16 10:29:15.106149"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (0.8ms)  SAVEPOINT active_record_1
  SQL (0.3ms)  INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["request_user_id", 2], ["requested_at", "2017-08-16 10:29:15.139282"], ["created_at", "2017-08-16 10:29:15.139169"], ["updated_at", "2017-08-16 10:29:15.139169"]]
  SQL (0.6ms)  INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?)  [["request_id", 1], ["user_id", 2], ["content", "content9"], ["created_at", "2017-08-16 10:29:15.141563"], ["updated_at", "2017-08-16 10:29:15.141563"]]
  SQL (0.2ms)  INSERT INTO "approval_items" ("request_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_type", "Book"], ["event", "create"], ["params", "---\n:name: created_name\n"], ["created_at", "2017-08-16 10:29:15.147304"], ["updated_at", "2017-08-16 10:29:15.147304"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (1.3ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.2ms)  SAVEPOINT active_record_1
  SQL (1.1ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name43"], ["created_at", "2017-08-16 10:29:15.154406"], ["updated_at", "2017-08-16 10:29:15.154406"]]
   (0.7ms)  RELEASE SAVEPOINT active_record_1
   (0.4ms)  SAVEPOINT active_record_1
  SQL (0.9ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name44"], ["created_at", "2017-08-16 10:29:15.166224"], ["updated_at", "2017-08-16 10:29:15.166224"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  SAVEPOINT active_record_1
  SQL (0.6ms)  INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["request_user_id", 2], ["requested_at", "2017-08-16 10:29:15.192110"], ["created_at", "2017-08-16 10:29:15.190864"], ["updated_at", "2017-08-16 10:29:15.190864"]]
  SQL (0.2ms)  INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?)  [["request_id", 1], ["user_id", 2], ["content", "content10"], ["created_at", "2017-08-16 10:29:15.204733"], ["updated_at", "2017-08-16 10:29:15.204733"]]
  SQL (0.3ms)  INSERT INTO "approval_items" ("request_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_type", "Book"], ["event", "create"], ["params", "---\n:name: created_name\n"], ["created_at", "2017-08-16 10:29:15.206660"], ["updated_at", "2017-08-16 10:29:15.206660"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (1.7ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.3ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name45"], ["created_at", "2017-08-16 10:29:15.216087"], ["updated_at", "2017-08-16 10:29:15.216087"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.0ms)  SAVEPOINT active_record_1
  SQL (0.1ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name46"], ["created_at", "2017-08-16 10:29:15.218359"], ["updated_at", "2017-08-16 10:29:15.218359"]]
   (0.0ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.8ms)  INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["request_user_id", 2], ["requested_at", "2017-08-16 10:29:15.234810"], ["created_at", "2017-08-16 10:29:15.234491"], ["updated_at", "2017-08-16 10:29:15.234491"]]
  SQL (0.3ms)  INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?)  [["request_id", 1], ["user_id", 2], ["content", "content11"], ["created_at", "2017-08-16 10:29:15.239653"], ["updated_at", "2017-08-16 10:29:15.239653"]]
  SQL (1.3ms)  INSERT INTO "approval_items" ("request_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_type", "Book"], ["event", "create"], ["params", "---\n:name: created_name\n"], ["created_at", "2017-08-16 10:29:15.247279"], ["updated_at", "2017-08-16 10:29:15.247279"]]
   (0.4ms)  RELEASE SAVEPOINT active_record_1
   (1.4ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.2ms)  rollback transaction
   (0.2ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.3ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.2ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.2ms)  SAVEPOINT active_record_1
  SQL (1.5ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name47"], ["created_at", "2017-08-16 10:29:15.311055"], ["updated_at", "2017-08-16 10:29:15.311055"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (1.6ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.2ms)  SAVEPOINT active_record_1
  SQL (0.7ms)  INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name48"], ["created_at", "2017-08-16 10:29:15.345429"], ["updated_at", "2017-08-16 10:29:15.345429"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (2.9ms)  INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["request_user_id", 1], ["requested_at", "2017-08-16 10:29:15.354234"], ["created_at", "2017-08-16 10:29:15.354077"], ["updated_at", "2017-08-16 10:29:15.354077"]]
  SQL (1.8ms)  INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?)  [["request_id", 1], ["user_id", 1], ["content", "content12"], ["created_at", "2017-08-16 10:29:15.363076"], ["updated_at", "2017-08-16 10:29:15.363076"]]
  SQL (1.4ms)  INSERT INTO "approval_items" ("request_id", "resource_type", "event", "params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["request_id", 1], ["resource_type", "Book"], ["event", "create"], ["params", "---\n:name: created_name\n"], ["created_at", "2017-08-16 10:29:15.374784"], ["updated_at", "2017-08-16 10:29:15.374784"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (2.2ms)  rollback transaction
   (0.4ms)  begin transaction
   (0.2ms)  rollback transaction
   (0.3ms)  begin transaction
   (0.2ms)  rollback transaction
   (0.2ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.5ms)  INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?)  [["name", "name49"], ["created_at", "2017-08-16 10:29:15.419066"], ["updated_at", "2017-08-16 10:29:15.419066"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.9ms)  rollback transaction
   (0.3ms)  begin transaction
   (0.4ms)  rollback transaction
   (0.2ms)  begin transaction
   (0.1ms)  rollback transaction