spec/rails/rails-5.1.2/log/test.log in approval-0.3.1 vs spec/rails/rails-5.1.2/log/test.log in approval-0.3.2

- old
+ new

@@ -1,12 +1,12 @@ -  (0.2ms) SELECT sqlite_version(*) -  (1.1ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL PRIMARY KEY) -  (2.9ms) CREATE TABLE "ar_internal_metadata" ("key" varchar NOT NULL PRIMARY KEY, "value" varchar, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL) +  (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 (20170816024438) +Migrating to CreateApprovalRequests (20170816102906)  (0.1ms) begin transaction -  (0.4ms) 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.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 @@ -30,58 +30,58 @@ 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", "20170816024438"]] -  (0.6ms) commit transaction -Migrating to CreateApprovalComments (20170816024439) -  (0.1ms) begin transaction -  (0.6ms) 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) + 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.1ms) CREATE INDEX "index_approval_comments_on_user_id" ON "approval_comments" ("user_id") - SQL (0.1ms) INSERT INTO "schema_migrations" ("version") VALUES (?) [["version", "20170816024439"]] -  (0.9ms) commit transaction -Migrating to CreateApprovalItems (20170816024440) -  (0.1ms) begin transaction -  (0.6ms) 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.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.2ms) INSERT INTO "schema_migrations" ("version") VALUES (?) [["version", "20170816024440"]] + 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 -Migrating to CreateUsers (20170816024441) + 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 -  (0.4ms) 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", "20170816024441"]] -  (0.6ms) commit transaction -Migrating to CreateBooks (20170816024442) -  (0.0ms) begin transaction -  (0.4ms) CREATE TABLE "books" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL) - SQL (0.1ms) INSERT INTO "schema_migrations" ("version") VALUES (?) [["version", "20170816024442"]] -  (0.8ms) commit transaction - ActiveRecord::InternalMetadata Load (0.3ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ? [["key", "environment"], ["LIMIT", 1]] -  (0.0ms) begin transaction - SQL (0.3ms) INSERT INTO "ar_internal_metadata" ("key", "value", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["key", "environment"], ["value", "test"], ["created_at", "2017-08-16 02:44:44.978496"], ["updated_at", "2017-08-16 02:44:44.978496"]] -  (0.8ms) commit transaction -  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC -  (0.1ms)  SELECT sql + 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 @@ -140,1085 +140,556 @@  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) 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 02:44:46.495832"], ["updated_at", "2017-08-16 02:44:46.495832"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 - Approval::Request Load (0.2ms) SELECT "approval_requests".* FROM "approval_requests" WHERE "approval_requests"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] -  (0.9ms) 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", "name2"], ["created_at", "2017-08-16 02:44:46.531823"], ["updated_at", "2017-08-16 02:44:46.531823"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.5ms) rollback transaction -  (0.1ms) begin transaction -  (0.0ms) 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", "name3"], ["created_at", "2017-08-16 02:44:46.538212"], ["updated_at", "2017-08-16 02:44:46.538212"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.4ms) 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.6ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name4"], ["created_at", "2017-08-16 02:44:46.545991"], ["updated_at", "2017-08-16 02:44:46.545991"]] -  (0.1ms) 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", "name5"], ["created_at", "2017-08-16 02:44:46.553280"], ["updated_at", "2017-08-16 02:44:46.553280"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.6ms) rollback transaction -  (0.1ms) begin transaction  (0.1ms) rollback transaction -  (0.1ms) begin transaction +  (0.0ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 - SQL (0.4ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name6"], ["created_at", "2017-08-16 02:44:46.561419"], ["updated_at", "2017-08-16 02:44:46.561419"]] + 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 -  (3.8ms) rollback transaction -  (0.1ms) begin transaction -  (0.1ms) rollback transaction -  (0.1ms) begin transaction -  (0.2ms) SAVEPOINT active_record_1 - SQL (0.8ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name7"], ["created_at", "2017-08-16 02:44:46.573787"], ["updated_at", "2017-08-16 02:44:46.573787"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.8ms) rollback transaction -  (0.1ms) begin transaction -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name1"], ["created_at", "2017-08-16 02:44:46.592092"], ["updated_at", "2017-08-16 02:44:46.592092"]] -  (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", "name8"], ["created_at", "2017-08-16 02:44:46.630043"], ["updated_at", "2017-08-16 02:44:46.630043"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) SAVEPOINT active_record_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 02:44:46.633231"], ["created_at", "2017-08-16 02:44:46.633117"], ["updated_at", "2017-08-16 02:44:46.633117"]] - SQL (0.2ms) 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 02:44:46.634557"], ["updated_at", "2017-08-16 02:44:46.634557"]] - 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 02:44:46.635763"], ["updated_at", "2017-08-16 02:44:46.635763"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.3ms) SELECT COUNT(*) FROM "books" - Book Load (0.1ms) SELECT "books".* FROM "books" WHERE "books"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.1ms) DELETE FROM "books" WHERE "books"."id" = ? [["id", 1]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) SELECT COUNT(*) FROM "books" -  (0.4ms) 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", "name2"], ["created_at", "2017-08-16 02:44:46.651864"], ["updated_at", "2017-08-16 02:44:46.651864"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) SAVEPOINT active_record_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 02:44:46.657186"], ["created_at", "2017-08-16 02:44:46.657120"], ["updated_at", "2017-08-16 02:44:46.657120"]] - SQL (0.1ms) 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 02:44:46.658430"], ["updated_at", "2017-08-16 02:44:46.658430"]] - 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 02:44:46.659730"], ["updated_at", "2017-08-16 02:44:46.659730"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.2ms) SELECT COUNT(*) FROM "books" -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.2ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "created_name"], ["created_at", "2017-08-16 02:44:46.663890"], ["updated_at", "2017-08-16 02:44:46.663890"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.2ms) SAVEPOINT active_record_1 - SQL (0.6ms) UPDATE "approval_items" SET "resource_id" = ?, "updated_at" = ? WHERE "approval_items"."id" = ? [["resource_id", 1], ["updated_at", "2017-08-16 02:44:46.667801"], ["id", 1]] -  (0.3ms) RELEASE SAVEPOINT active_record_1 -  (0.5ms) SELECT COUNT(*) FROM "books"  (1.2ms) rollback transaction -  (0.1ms) begin transaction +  (0.2ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 - SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name3"], ["created_at", "2017-08-16 02:44:46.683841"], ["updated_at", "2017-08-16 02:44:46.683841"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.1ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name9"], ["created_at", "2017-08-16 02:44:46.687685"], ["updated_at", "2017-08-16 02:44:46.687685"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.0ms) SAVEPOINT active_record_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 02:44:46.690870"], ["created_at", "2017-08-16 02:44:46.690808"], ["updated_at", "2017-08-16 02:44:46.690808"]] - SQL (0.1ms) 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 02:44:46.692368"], ["updated_at", "2017-08-16 02:44:46.692368"]] - 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", 1], ["resource_type", "Book"], ["event", "update"], ["params", "---\n:name: updated_name\n"], ["created_at", "2017-08-16 02:44:46.693944"], ["updated_at", "2017-08-16 02:44:46.693944"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 - Book Load (0.3ms) SELECT "books".* FROM "books" ORDER BY "books"."id" ASC LIMIT ? [["LIMIT", 1]] - Book Load (0.1ms) SELECT "books".* FROM "books" WHERE "books"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.2ms) UPDATE "books" SET "name" = ?, "updated_at" = ? WHERE "books"."id" = ? [["name", "updated_name"], ["updated_at", "2017-08-16 02:44:46.700776"], ["id", 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 - Book Load (0.1ms) SELECT "books".* FROM "books" ORDER BY "books"."id" ASC LIMIT ? [["LIMIT", 1]]  (0.6ms) rollback transaction  (0.1ms) begin transaction -  (0.1ms) rollback transaction -  (0.1ms) begin transaction -  (0.1ms) rollback transaction -  (0.1ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 - SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name5"], ["created_at", "2017-08-16 02:44:46.712593"], ["updated_at", "2017-08-16 02:44:46.712593"]] + 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.4ms) 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", "name7"], ["created_at", "2017-08-16 02:44:46.725279"], ["updated_at", "2017-08-16 02:44:46.725279"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.4ms) 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", "name9"], ["created_at", "2017-08-16 02:44:46.730732"], ["updated_at", "2017-08-16 02:44:46.730732"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.3ms) rollback transaction -  (0.1ms) begin transaction -  (0.0ms) rollback transaction -  (0.1ms) begin transaction -  (0.0ms) rollback transaction -  (0.1ms) begin transaction -  (0.0ms) rollback transaction -  (0.1ms) begin transaction -  (0.0ms) rollback transaction -  (0.1ms) begin transaction +  (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.1ms) begin transaction +  (0.0ms) begin transaction  (0.1ms) rollback transaction -  (0.1ms) begin transaction +  (0.0ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name13"], ["created_at", "2017-08-16 02:44:46.751871"], ["updated_at", "2017-08-16 02:44:46.751871"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) SAVEPOINT active_record_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 02:44:46.759424"], ["created_at", "2017-08-16 02:44:46.759358"], ["updated_at", "2017-08-16 02:44:46.759358"]] - SQL (0.2ms) INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?) [["request_id", 1], ["user_id", 1], ["content", "content4"], ["created_at", "2017-08-16 02:44:46.760997"], ["updated_at", "2017-08-16 02:44:46.760997"]] - 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 02:44:46.766586"], ["updated_at", "2017-08-16 02:44:46.766586"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.4ms) 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.1ms) begin transaction -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.5ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name14"], ["created_at", "2017-08-16 02:44:46.787700"], ["updated_at", "2017-08-16 02:44:46.787700"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.4ms) 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", "name15"], ["created_at", "2017-08-16 02:44:46.796419"], ["updated_at", "2017-08-16 02:44:46.796419"]] -  (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 (?, ?, ?) [["name", "name16"], ["created_at", "2017-08-16 02:44:46.800912"], ["updated_at", "2017-08-16 02:44:46.800912"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.2ms) INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["request_user_id", 2], ["requested_at", "2017-08-16 02:44:46.814974"], ["created_at", "2017-08-16 02:44:46.814902"], ["updated_at", "2017-08-16 02:44:46.814902"]] - SQL (0.2ms) 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 02:44:46.820022"], ["updated_at", "2017-08-16 02:44:46.820022"]] - 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 02:44:46.821486"], ["updated_at", "2017-08-16 02:44:46.821486"]] -  (0.3ms) RELEASE SAVEPOINT active_record_1 -  (0.4ms) 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", "name17"], ["created_at", "2017-08-16 02:44:46.828576"], ["updated_at", "2017-08-16 02:44:46.828576"]] -  (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 (?, ?, ?) [["name", "name18"], ["created_at", "2017-08-16 02:44:46.831317"], ["updated_at", "2017-08-16 02:44:46.831317"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) 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 02:44:46.839015"], ["created_at", "2017-08-16 02:44:46.838787"], ["updated_at", "2017-08-16 02:44:46.838787"]] - SQL (1.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 02:44:46.842677"], ["updated_at", "2017-08-16 02:44:46.842677"]] - SQL (0.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 02:44:46.847009"], ["updated_at", "2017-08-16 02:44:46.847009"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.6ms) 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", "name19"], ["created_at", "2017-08-16 02:44:46.858819"], ["updated_at", "2017-08-16 02:44:46.858819"]] -  (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 (?, ?, ?) [["name", "name20"], ["created_at", "2017-08-16 02:44:46.863567"], ["updated_at", "2017-08-16 02:44:46.863567"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.2ms) SAVEPOINT active_record_1 - SQL (0.2ms) INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["request_user_id", 2], ["requested_at", "2017-08-16 02:44:46.877831"], ["created_at", "2017-08-16 02:44:46.877755"], ["updated_at", "2017-08-16 02:44:46.877755"]] - SQL (0.2ms) 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 02:44:46.879343"], ["updated_at", "2017-08-16 02:44:46.879343"]] - 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 02:44:46.880913"], ["updated_at", "2017-08-16 02:44:46.880913"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.4ms) 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", "name21"], ["created_at", "2017-08-16 02:44:46.886003"], ["updated_at", "2017-08-16 02:44:46.886003"]] -  (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 (?, ?, ?) [["name", "name22"], ["created_at", "2017-08-16 02:44:46.888754"], ["updated_at", "2017-08-16 02:44:46.888754"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.7ms) INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["request_user_id", 2], ["requested_at", "2017-08-16 02:44:46.894039"], ["created_at", "2017-08-16 02:44:46.893969"], ["updated_at", "2017-08-16 02:44:46.893969"]] - SQL (0.3ms) 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 02:44:46.896484"], ["updated_at", "2017-08-16 02:44:46.896484"]] - SQL (0.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 02:44:46.899046"], ["updated_at", "2017-08-16 02:44:46.899046"]] -  (0.1ms) RELEASE 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 "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name23"], ["created_at", "2017-08-16 02:44:46.910245"], ["updated_at", "2017-08-16 02:44:46.910245"]] + 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.1ms) SAVEPOINT active_record_1 - SQL (0.1ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name24"], ["created_at", "2017-08-16 02:44:46.917075"], ["updated_at", "2017-08-16 02:44:46.917075"]] -  (0.2ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) 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 02:44:46.925569"], ["created_at", "2017-08-16 02:44:46.925256"], ["updated_at", "2017-08-16 02:44:46.925256"]] - SQL (0.2ms) 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 02:44:46.927426"], ["updated_at", "2017-08-16 02:44:46.927426"]] - 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 02:44:46.928887"], ["updated_at", "2017-08-16 02:44:46.928887"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.4ms) rollback transaction +  (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", "name25"], ["created_at", "2017-08-16 02:44:46.933541"], ["updated_at", "2017-08-16 02:44:46.933541"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.3ms) SAVEPOINT active_record_1 - SQL (0.1ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name26"], ["created_at", "2017-08-16 02:44:46.936166"], ["updated_at", "2017-08-16 02:44:46.936166"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.2ms) INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["request_user_id", 2], ["requested_at", "2017-08-16 02:44:46.941395"], ["created_at", "2017-08-16 02:44:46.941330"], ["updated_at", "2017-08-16 02:44:46.941330"]] - 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 02:44:46.943156"], ["updated_at", "2017-08-16 02:44:46.943156"]] - SQL (0.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 02:44:46.944869"], ["updated_at", "2017-08-16 02:44:46.944869"]] + 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.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", "name27"], ["created_at", "2017-08-16 02:44:46.953513"], ["updated_at", "2017-08-16 02:44:46.953513"]] -  (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 (?, ?, ?) [["name", "name28"], ["created_at", "2017-08-16 02:44:46.957539"], ["updated_at", "2017-08-16 02:44:46.957539"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) 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 02:44:46.967707"], ["created_at", "2017-08-16 02:44:46.967640"], ["updated_at", "2017-08-16 02:44:46.967640"]] - 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 02:44:46.969075"], ["updated_at", "2017-08-16 02:44:46.969075"]] - 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 02:44:46.970923"], ["updated_at", "2017-08-16 02:44:46.970923"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.8ms) 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", "name29"], ["created_at", "2017-08-16 02:44:46.979435"], ["updated_at", "2017-08-16 02:44:46.979435"]] -  (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 (?, ?, ?) [["name", "name30"], ["created_at", "2017-08-16 02:44:46.981999"], ["updated_at", "2017-08-16 02:44:46.981999"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.2ms) INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["request_user_id", 2], ["requested_at", "2017-08-16 02:44:46.987716"], ["created_at", "2017-08-16 02:44:46.987643"], ["updated_at", "2017-08-16 02:44:46.987643"]] - SQL (0.2ms) INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?) [["request_id", 1], ["user_id", 2], ["content", "content12"], ["created_at", "2017-08-16 02:44:46.989320"], ["updated_at", "2017-08-16 02:44:46.989320"]] - 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 02:44:46.990660"], ["updated_at", "2017-08-16 02:44:46.990660"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.4ms) rollback transaction -  (0.1ms) begin transaction -  (0.1ms) 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", "name31"], ["created_at", "2017-08-16 02:44:47.001607"], ["updated_at", "2017-08-16 02:44:47.001607"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.9ms) 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", "name32"], ["created_at", "2017-08-16 02:44:47.009397"], ["updated_at", "2017-08-16 02:44:47.009397"]] +  (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.4ms) rollback transaction -  (0.1ms) begin transaction -  (0.1ms) rollback transaction -  (0.1ms) begin transaction -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name33"], ["created_at", "2017-08-16 02:44:47.033199"], ["updated_at", "2017-08-16 02:44:47.033199"]] +  (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) rollback transaction -  (0.1ms) begin transaction -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name34"], ["created_at", "2017-08-16 02:44:47.038813"], ["updated_at", "2017-08-16 02:44:47.038813"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.5ms) rollback transaction -  (0.1ms) begin transaction +  (0.4ms) SELECT COUNT(*) FROM "books"  (0.2ms) SAVEPOINT active_record_1 - SQL (0.7ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name35"], ["created_at", "2017-08-16 02:44:47.045986"], ["updated_at", "2017-08-16 02:44:47.045986"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.6ms) rollback transaction -  (0.1ms) begin transaction + 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 - SQL (0.6ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name36"], ["created_at", "2017-08-16 02:44:47.056075"], ["updated_at", "2017-08-16 02:44:47.056075"]] + 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 -  (3.3ms) SELECT COUNT(*) FROM "approval_items"  (0.1ms) SAVEPOINT active_record_1 - SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name37"], ["created_at", "2017-08-16 02:44:47.069481"], ["updated_at", "2017-08-16 02:44:47.069481"]] + 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 - SQL (0.2ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name25"], ["created_at", "2017-08-16 02:44:47.071493"], ["updated_at", "2017-08-16 02:44:47.071493"]] + 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 -  (0.3ms) 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 02:44:47.084989"], ["created_at", "2017-08-16 02:44:47.084923"], ["updated_at", "2017-08-16 02:44:47.084923"]] - 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 02:44:47.086397"], ["updated_at", "2017-08-16 02:44:47.086397"]] - 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 02:44:47.087885"], ["updated_at", "2017-08-16 02:44:47.087885"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) SELECT COUNT(*) FROM "approval_items" -  (0.8ms) rollback transaction -  (0.1ms) begin transaction + 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.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name38"], ["created_at", "2017-08-16 02:44:47.127547"], ["updated_at", "2017-08-16 02:44:47.127547"]] + 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 -  (0.0ms) SAVEPOINT active_record_1 - SQL (0.1ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name26"], ["created_at", "2017-08-16 02:44:47.129427"], ["updated_at", "2017-08-16 02:44:47.129427"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.0ms) 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 02:44:47.135099"], ["created_at", "2017-08-16 02:44:47.135032"], ["updated_at", "2017-08-16 02:44:47.135032"]] - SQL (0.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 02:44:47.136261"], ["updated_at", "2017-08-16 02:44:47.136261"]] - SQL (0.1ms) 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 02:44:47.137261"], ["updated_at", "2017-08-16 02:44:47.137261"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.6ms) rollback transaction + 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) SELECT COUNT(*) FROM "approval_items" -  (0.0ms) SAVEPOINT active_record_1 - SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name39"], ["created_at", "2017-08-16 02:44:47.141495"], ["updated_at", "2017-08-16 02:44:47.141495"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.0ms) SAVEPOINT active_record_1 - SQL (0.3ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name27"], ["created_at", "2017-08-16 02:44:47.143075"], ["updated_at", "2017-08-16 02:44:47.143075"]] -  (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", "name28"], ["created_at", "2017-08-16 02:44:47.145256"], ["updated_at", "2017-08-16 02:44:47.145256"]] + 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", "name29"], ["created_at", "2017-08-16 02:44:47.147774"], ["updated_at", "2017-08-16 02:44:47.147774"]] + 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.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 02:44:47.159128"], ["created_at", "2017-08-16 02:44:47.159061"], ["updated_at", "2017-08-16 02:44:47.159061"]] - 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 02:44:47.160447"], ["updated_at", "2017-08-16 02:44:47.160447"]] - 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 02:44:47.161871"], ["updated_at", "2017-08-16 02:44:47.161871"]] - 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 02:44:47.166687"], ["updated_at", "2017-08-16 02:44:47.166687"]] - SQL (0.1ms) 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 02:44:47.167954"], ["updated_at", "2017-08-16 02:44:47.167954"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) SELECT COUNT(*) FROM "approval_items" -  (0.5ms) rollback transaction -  (0.4ms) begin transaction -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.7ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name40"], ["created_at", "2017-08-16 02:44:47.174386"], ["updated_at", "2017-08-16 02:44:47.174386"]] -  (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", "name30"], ["created_at", "2017-08-16 02:44:47.177530"], ["updated_at", "2017-08-16 02:44:47.177530"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.1ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name31"], ["created_at", "2017-08-16 02:44:47.179094"], ["updated_at", "2017-08-16 02:44:47.179094"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.0ms) SAVEPOINT active_record_1 - SQL (0.1ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name32"], ["created_at", "2017-08-16 02:44:47.180398"], ["updated_at", "2017-08-16 02:44:47.180398"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.0ms) 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 02:44:47.187197"], ["created_at", "2017-08-16 02:44:47.187136"], ["updated_at", "2017-08-16 02:44:47.187136"]] - SQL (0.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 02:44:47.188314"], ["updated_at", "2017-08-16 02:44:47.188314"]] - 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 02:44:47.189477"], ["updated_at", "2017-08-16 02:44:47.189477"]] - SQL (0.1ms) 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 02:44:47.190744"], ["updated_at", "2017-08-16 02:44:47.190744"]] - SQL (0.1ms) 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 02:44:47.191900"], ["updated_at", "2017-08-16 02:44:47.191900"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.5ms) rollback transaction -  (0.2ms) begin transaction  (0.3ms) SAVEPOINT active_record_1 - SQL (0.7ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name41"], ["created_at", "2017-08-16 02:44:47.202114"], ["updated_at", "2017-08-16 02:44:47.202114"]] -  (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", "name33"], ["created_at", "2017-08-16 02:44:47.206681"], ["updated_at", "2017-08-16 02:44:47.206681"]] -  (0.1ms) 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 02:44:47.219348"], ["created_at", "2017-08-16 02:44:47.219227"], ["updated_at", "2017-08-16 02:44:47.219227"]] - 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 02:44:47.220895"], ["updated_at", "2017-08-16 02:44:47.220895"]] - 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 02:44:47.223442"], ["updated_at", "2017-08-16 02:44:47.223442"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.6ms) rollback transaction -  (0.1ms) begin transaction -  (0.2ms) SELECT COUNT(*) FROM "approval_items" -  (0.0ms) SAVEPOINT active_record_1 - SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name42"], ["created_at", "2017-08-16 02:44:47.229982"], ["updated_at", "2017-08-16 02:44:47.229982"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.0ms) SAVEPOINT active_record_1 - SQL (0.1ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name34"], ["created_at", "2017-08-16 02:44:47.232148"], ["updated_at", "2017-08-16 02:44:47.232148"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.0ms) 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 02:44:47.239958"], ["created_at", "2017-08-16 02:44:47.239889"], ["updated_at", "2017-08-16 02:44:47.239889"]] - SQL (0.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 02:44:47.241146"], ["updated_at", "2017-08-16 02:44:47.241146"]] - 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", 1], ["resource_type", "Book"], ["event", "update"], ["params", "---\nname: changed name\n"], ["created_at", "2017-08-16 02:44:47.242331"], ["updated_at", "2017-08-16 02:44:47.242331"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) SELECT COUNT(*) FROM "approval_items" -  (0.5ms) rollback transaction -  (0.1ms) begin transaction -  (0.3ms) SELECT COUNT(*) FROM "approval_items" -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name43"], ["created_at", "2017-08-16 02:44:47.256982"], ["updated_at", "2017-08-16 02:44:47.256982"]] -  (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", "name35"], ["created_at", "2017-08-16 02:44:47.259139"], ["updated_at", "2017-08-16 02:44:47.259139"]] -  (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", "name36"], ["created_at", "2017-08-16 02:44:47.260816"], ["updated_at", "2017-08-16 02:44:47.260816"]] -  (3.7ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.1ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name37"], ["created_at", "2017-08-16 02:44:47.266510"], ["updated_at", "2017-08-16 02:44:47.266510"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.0ms) 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 02:44:47.279071"], ["created_at", "2017-08-16 02:44:47.279002"], ["updated_at", "2017-08-16 02:44:47.279002"]] - SQL (0.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 02:44:47.280314"], ["updated_at", "2017-08-16 02:44:47.280314"]] - 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", 1], ["resource_type", "Book"], ["event", "update"], ["params", "---\nname: changed name 0\n"], ["created_at", "2017-08-16 02:44:47.281501"], ["updated_at", "2017-08-16 02:44:47.281501"]] - SQL (0.1ms) 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 02:44:47.282946"], ["updated_at", "2017-08-16 02:44:47.282946"]] - 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 02:44:47.284601"], ["updated_at", "2017-08-16 02:44:47.284601"]] + 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.4ms) SELECT COUNT(*) FROM "approval_items" -  (0.6ms) rollback transaction -  (0.1ms) begin transaction -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name44"], ["created_at", "2017-08-16 02:44:47.292129"], ["updated_at", "2017-08-16 02:44:47.292129"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.3ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name38"], ["created_at", "2017-08-16 02:44:47.295640"], ["updated_at", "2017-08-16 02:44:47.295640"]] -  (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", "name39"], ["created_at", "2017-08-16 02:44:47.298338"], ["updated_at", "2017-08-16 02:44:47.298338"]] -  (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", "name40"], ["created_at", "2017-08-16 02:44:47.301538"], ["updated_at", "2017-08-16 02:44:47.301538"]] -  (7.4ms) RELEASE SAVEPOINT active_record_1 -  (1.0ms) 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 02:44:47.329312"], ["created_at", "2017-08-16 02:44:47.329237"], ["updated_at", "2017-08-16 02:44:47.329237"]] - 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 02:44:47.330983"], ["updated_at", "2017-08-16 02:44:47.330983"]] - 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", 1], ["resource_type", "Book"], ["event", "update"], ["params", "---\nname: changed name 0\n"], ["created_at", "2017-08-16 02:44:47.332708"], ["updated_at", "2017-08-16 02:44:47.332708"]] - SQL (0.1ms) 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 02:44:47.334418"], ["updated_at", "2017-08-16 02:44:47.334418"]] - SQL (0.1ms) 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 02:44:47.336082"], ["updated_at", "2017-08-16 02:44:47.336082"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (1.5ms) rollback transaction +  (0.3ms) SELECT COUNT(*) FROM "books" +  (4.5ms) rollback transaction  (0.2ms) begin transaction -  (0.1ms) rollback transaction -  (0.1ms) begin transaction -  (0.3ms) SELECT COUNT(*) FROM "approval_items" -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.7ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name45"], ["created_at", "2017-08-16 02:44:47.348095"], ["updated_at", "2017-08-16 02:44:47.348095"]] -  (0.1ms) 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 02:44:47.368975"], ["created_at", "2017-08-16 02:44:47.368914"], ["updated_at", "2017-08-16 02:44:47.368914"]] - 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 02:44:47.370344"], ["updated_at", "2017-08-16 02:44:47.370344"]] - 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", "---\nname: name41\n"], ["created_at", "2017-08-16 02:44:47.373232"], ["updated_at", "2017-08-16 02:44:47.373232"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SELECT COUNT(*) FROM "approval_items" -  (1.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", "name46"], ["created_at", "2017-08-16 02:44:47.384267"], ["updated_at", "2017-08-16 02:44:47.384267"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.0ms) 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 02:44:47.391260"], ["created_at", "2017-08-16 02:44:47.391195"], ["updated_at", "2017-08-16 02:44:47.391195"]] - 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 02:44:47.392420"], ["updated_at", "2017-08-16 02:44:47.392420"]] - 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: name42\n"], ["created_at", "2017-08-16 02:44:47.394438"], ["updated_at", "2017-08-16 02:44:47.394438"]] + 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.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", "name47"], ["created_at", "2017-08-16 02:44:47.400930"], ["updated_at", "2017-08-16 02:44:47.400930"]] + 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 - 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 02:44:47.417450"], ["created_at", "2017-08-16 02:44:47.417385"], ["updated_at", "2017-08-16 02:44:47.417385"]] - 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 02:44:47.418620"], ["updated_at", "2017-08-16 02:44:47.418620"]] - 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: name43\n"], ["created_at", "2017-08-16 02:44:47.419824"], ["updated_at", "2017-08-16 02:44:47.419824"]] - 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: name44\n"], ["created_at", "2017-08-16 02:44:47.422267"], ["updated_at", "2017-08-16 02:44:47.422267"]] - 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: name45\n"], ["created_at", "2017-08-16 02:44:47.426287"], ["updated_at", "2017-08-16 02:44:47.426287"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.4ms) rollback transaction -  (0.1ms) begin transaction -  (0.1ms) SELECT COUNT(*) FROM "approval_items" -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name48"], ["created_at", "2017-08-16 02:44:47.431303"], ["updated_at", "2017-08-16 02:44:47.431303"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.0ms) 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 02:44:47.440487"], ["created_at", "2017-08-16 02:44:47.440411"], ["updated_at", "2017-08-16 02:44:47.440411"]] - 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 02:44:47.441712"], ["updated_at", "2017-08-16 02:44:47.441712"]] - SQL (0.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", "---\nname: name46\n"], ["created_at", "2017-08-16 02:44:47.442918"], ["updated_at", "2017-08-16 02:44:47.442918"]] - 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: name47\n"], ["created_at", "2017-08-16 02:44:47.445747"], ["updated_at", "2017-08-16 02:44:47.445747"]] - 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: name48\n"], ["created_at", "2017-08-16 02:44:47.448509"], ["updated_at", "2017-08-16 02:44:47.448509"]] -  (0.1ms) RELEASE 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" -  (0.6ms) rollback transaction +  (3.0ms) rollback transaction  (0.1ms) begin transaction -  (0.1ms) 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", "name49"], ["created_at", "2017-08-16 02:44:47.458829"], ["updated_at", "2017-08-16 02:44:47.458829"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.4ms) 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.1ms) rollback transaction -  (0.1ms) begin transaction -  (0.1ms) rollback transaction -  (0.1ms) begin transaction -  (0.0ms) rollback transaction -  (0.1ms) begin transaction -  (0.0ms) rollback transaction -  (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.1ms) begin transaction -  (0.1ms) SAVEPOINT active_record_1 - SQL (1.0ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name1"], ["created_at", "2017-08-16 03:34:53.473271"], ["updated_at", "2017-08-16 03:34:53.473271"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.5ms) rollback transaction -  (0.2ms) begin transaction -  (0.0ms) 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.1ms) begin transaction -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name2"], ["created_at", "2017-08-16 03:34:53.492196"], ["updated_at", "2017-08-16 03:34:53.492196"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.2ms) 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 03:34:53.553334"], ["created_at", "2017-08-16 03:34:53.552944"], ["updated_at", "2017-08-16 03:34:53.552944"]] - SQL (0.7ms) 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 03:34:53.556356"], ["updated_at", "2017-08-16 03:34:53.556356"]] - SQL (0.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", "---\n:name: created_name\n"], ["created_at", "2017-08-16 03:34:53.558454"], ["updated_at", "2017-08-16 03:34:53.558454"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.6ms) rollback transaction -  (0.1ms) begin transaction -  (0.2ms) SELECT COUNT(*) FROM "approval_items" -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name3"], ["created_at", "2017-08-16 03:34:53.573343"], ["updated_at", "2017-08-16 03:34:53.573343"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.0ms) SAVEPOINT active_record_1 - SQL (0.4ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name1"], ["created_at", "2017-08-16 03:34:53.575527"], ["updated_at", "2017-08-16 03:34:53.575527"]] +  (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.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.3ms) 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 03:34:53.594033"], ["created_at", "2017-08-16 03:34:53.593899"], ["updated_at", "2017-08-16 03:34:53.593899"]] - SQL (0.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 03:34:53.596216"], ["updated_at", "2017-08-16 03:34:53.596216"]] - Book Load (0.0ms) SELECT "books".* FROM "books" WHERE "books"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] - SQL (0.1ms) 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 03:34:53.597730"], ["updated_at", "2017-08-16 03:34:53.597730"]] +  (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) SELECT COUNT(*) FROM "approval_items" -  (0.6ms) 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", "name4"], ["created_at", "2017-08-16 03:34:53.632821"], ["updated_at", "2017-08-16 03:34:53.632821"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.0ms) SAVEPOINT active_record_1 - SQL (0.2ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name2"], ["created_at", "2017-08-16 03:34:53.634991"], ["updated_at", "2017-08-16 03:34:53.634991"]] -  (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.0ms) 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.3ms) INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["request_user_id", 1], ["requested_at", "2017-08-16 03:34:53.644889"], ["created_at", "2017-08-16 03:34:53.644803"], ["updated_at", "2017-08-16 03:34:53.644803"]] - 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 03:34:53.646652"], ["updated_at", "2017-08-16 03:34:53.646652"]] + 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.2ms) 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 03:34:53.648658"], ["updated_at", "2017-08-16 03:34:53.648658"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.6ms) rollback transaction + 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 (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name5"], ["created_at", "2017-08-16 03:34:53.653647"], ["updated_at", "2017-08-16 03:34:53.653647"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.0ms) SAVEPOINT active_record_1 - SQL (0.1ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name3"], ["created_at", "2017-08-16 03:34:53.656085"], ["updated_at", "2017-08-16 03:34:53.656085"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.0ms) SAVEPOINT active_record_1 - SQL (0.1ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name4"], ["created_at", "2017-08-16 03:34:53.657655"], ["updated_at", "2017-08-16 03:34:53.657655"]] -  (0.0ms) RELEASE 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.1ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name5"], ["created_at", "2017-08-16 03:34:53.659786"], ["updated_at", "2017-08-16 03:34:53.659786"]] -  (0.1ms) RELEASE 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 - Book Load (0.6ms) 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.2ms) 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 (1.6ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] - SQL (0.8ms) INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["request_user_id", 1], ["requested_at", "2017-08-16 03:34:53.690357"], ["created_at", "2017-08-16 03:34:53.689870"], ["updated_at", "2017-08-16 03:34:53.689870"]] - SQL (0.5ms) 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 03:34:53.697852"], ["updated_at", "2017-08-16 03:34:53.697852"]] - 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 0\n"], ["created_at", "2017-08-16 03:34:53.702359"], ["updated_at", "2017-08-16 03:34:53.702359"]] - Book Load (0.5ms) 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 03:34:53.707921"], ["updated_at", "2017-08-16 03:34:53.707921"]] - Book Load (0.3ms) SELECT "books".* FROM "books" WHERE "books"."id" = ? LIMIT ? [["id", 3], ["LIMIT", 1]] - SQL (0.1ms) 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 03:34:53.714662"], ["updated_at", "2017-08-16 03:34:53.714662"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (1.2ms) rollback transaction -  (0.1ms) begin transaction -  (0.1ms) SELECT COUNT(*) FROM "approval_items" -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name6"], ["created_at", "2017-08-16 03:34:53.720701"], ["updated_at", "2017-08-16 03:34:53.720701"]] + 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 - SQL (0.1ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name6"], ["created_at", "2017-08-16 03:34:53.722829"], ["updated_at", "2017-08-16 03:34:53.722829"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.0ms) SAVEPOINT active_record_1 - SQL (0.1ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name7"], ["created_at", "2017-08-16 03:34:53.724433"], ["updated_at", "2017-08-16 03:34:53.724433"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.0ms) SAVEPOINT active_record_1 - SQL (0.1ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name8"], ["created_at", "2017-08-16 03:34:53.726079"], ["updated_at", "2017-08-16 03:34:53.726079"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.0ms) 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.2ms) INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["request_user_id", 1], ["requested_at", "2017-08-16 03:34:53.740210"], ["created_at", "2017-08-16 03:34:53.740125"], ["updated_at", "2017-08-16 03:34:53.740125"]] - SQL (0.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 03:34:53.741761"], ["updated_at", "2017-08-16 03:34:53.741761"]] + 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.2ms) 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 03:34:53.743444"], ["updated_at", "2017-08-16 03:34:53.743444"]] - Book Load (0.5ms) SELECT "books".* FROM "books" WHERE "books"."id" = ? LIMIT ? [["id", 2], ["LIMIT", 1]] - SQL (0.1ms) 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 03:34:53.746692"], ["updated_at", "2017-08-16 03:34:53.746692"]] + 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.1ms) 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 03:34:53.748887"], ["updated_at", "2017-08-16 03:34:53.748887"]] + 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" -  (1.0ms) rollback transaction +  (4.0ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 - SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name7"], ["created_at", "2017-08-16 03:34:53.756451"], ["updated_at", "2017-08-16 03:34:53.756451"]] -  (0.4ms) RELEASE 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 - User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] - SQL (0.4ms) INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["request_user_id", 1], ["requested_at", "2017-08-16 03:34:53.768545"], ["created_at", "2017-08-16 03:34:53.768475"], ["updated_at", "2017-08-16 03:34:53.768475"]] - 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 03:34:53.770947"], ["updated_at", "2017-08-16 03:34:53.770947"]] - 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: name9\n"], ["created_at", "2017-08-16 03:34:53.772674"], ["updated_at", "2017-08-16 03:34:53.772674"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.6ms) rollback transaction -  (0.1ms) begin transaction -  (0.2ms) SELECT COUNT(*) FROM "approval_items" + 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.8ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name8"], ["created_at", "2017-08-16 03:34:53.778794"], ["updated_at", "2017-08-16 03:34:53.778794"]] + 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.0ms) 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 03:34:53.790154"], ["created_at", "2017-08-16 03:34:53.790083"], ["updated_at", "2017-08-16 03:34:53.790083"]] - 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 03:34:53.791567"], ["updated_at", "2017-08-16 03:34:53.791567"]] - 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: name10\n"], ["created_at", "2017-08-16 03:34:53.793323"], ["updated_at", "2017-08-16 03:34:53.793323"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) SELECT COUNT(*) FROM "approval_items" -  (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", "name9"], ["created_at", "2017-08-16 03:34:53.805517"], ["updated_at", "2017-08-16 03:34:53.805517"]] + 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 - 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 03:34:53.822006"], ["created_at", "2017-08-16 03:34:53.821917"], ["updated_at", "2017-08-16 03:34:53.821917"]] - SQL (0.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 03:34:53.823634"], ["updated_at", "2017-08-16 03:34:53.823634"]] - 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: name11\n"], ["created_at", "2017-08-16 03:34:53.824988"], ["updated_at", "2017-08-16 03:34:53.824988"]] - 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: name12\n"], ["created_at", "2017-08-16 03:34:53.839324"], ["updated_at", "2017-08-16 03:34:53.839324"]] - 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: name13\n"], ["created_at", "2017-08-16 03:34:53.841038"], ["updated_at", "2017-08-16 03:34:53.841038"]] + 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.3ms) rollback transaction +  (1.5ms) rollback transaction  (0.1ms) begin transaction -  (0.1ms) SELECT COUNT(*) FROM "approval_items" -  (0.0ms) SAVEPOINT active_record_1 - SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name10"], ["created_at", "2017-08-16 03:34:53.846946"], ["updated_at", "2017-08-16 03:34:53.846946"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.0ms) SAVEPOINT active_record_1 - User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] - SQL (0.4ms) INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["request_user_id", 1], ["requested_at", "2017-08-16 03:34:53.857236"], ["created_at", "2017-08-16 03:34:53.857164"], ["updated_at", "2017-08-16 03:34:53.857164"]] - 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 03:34:53.859898"], ["updated_at", "2017-08-16 03:34:53.859898"]] - 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: name14\n"], ["created_at", "2017-08-16 03:34:53.862581"], ["updated_at", "2017-08-16 03:34:53.862581"]] - 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: name15\n"], ["created_at", "2017-08-16 03:34:53.864423"], ["updated_at", "2017-08-16 03:34:53.864423"]] - 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: name16\n"], ["created_at", "2017-08-16 03:34:53.866716"], ["updated_at", "2017-08-16 03:34:53.866716"]] +  (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.1ms) SELECT COUNT(*) FROM "approval_items"  (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 03:34:53.872259"], ["updated_at", "2017-08-16 03:34:53.872259"]] + 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.1ms) SAVEPOINT active_record_1 - SQL (0.1ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name12"], ["created_at", "2017-08-16 03:34:53.875405"], ["updated_at", "2017-08-16 03:34:53.875405"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.2ms) INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["request_user_id", 2], ["requested_at", "2017-08-16 03:34:53.881784"], ["created_at", "2017-08-16 03:34:53.881645"], ["updated_at", "2017-08-16 03:34:53.881645"]] - SQL (0.3ms) INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?) [["request_id", 1], ["user_id", 2], ["content", "content2"], ["created_at", "2017-08-16 03:34:53.883678"], ["updated_at", "2017-08-16 03:34:53.883678"]] - 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 03:34:53.885500"], ["updated_at", "2017-08-16 03:34:53.885500"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.6ms) rollback transaction +  (0.5ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 - SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name13"], ["created_at", "2017-08-16 03:34:53.892186"], ["updated_at", "2017-08-16 03:34:53.892186"]] + 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.1ms) SAVEPOINT active_record_1 - SQL (0.1ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name14"], ["created_at", "2017-08-16 03:34:53.894506"], ["updated_at", "2017-08-16 03:34:53.894506"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.7ms) 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 03:34:53.904961"], ["created_at", "2017-08-16 03:34:53.904864"], ["updated_at", "2017-08-16 03:34:53.904864"]] - SQL (0.2ms) INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?) [["request_id", 1], ["user_id", 2], ["content", "content3"], ["created_at", "2017-08-16 03:34:53.907079"], ["updated_at", "2017-08-16 03:34:53.907079"]] - 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 03:34:53.908694"], ["updated_at", "2017-08-16 03:34:53.908694"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (1.0ms) rollback transaction +  (0.3ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 - SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name15"], ["created_at", "2017-08-16 03:34:53.916451"], ["updated_at", "2017-08-16 03:34:53.916451"]] + 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.1ms) SAVEPOINT active_record_1 - SQL (0.1ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name16"], ["created_at", "2017-08-16 03:34:53.918943"], ["updated_at", "2017-08-16 03:34:53.918943"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.2ms) INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["request_user_id", 2], ["requested_at", "2017-08-16 03:34:53.926631"], ["created_at", "2017-08-16 03:34:53.926552"], ["updated_at", "2017-08-16 03:34:53.926552"]] - 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 03:34:53.928050"], ["updated_at", "2017-08-16 03:34:53.928050"]] - 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 03:34:53.930092"], ["updated_at", "2017-08-16 03:34:53.930092"]] -  (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.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name17"], ["created_at", "2017-08-16 03:34:53.936149"], ["updated_at", "2017-08-16 03:34:53.936149"]] + 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 (0.1ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name18"], ["created_at", "2017-08-16 03:34:53.939559"], ["updated_at", "2017-08-16 03:34:53.939559"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) 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 03:34:53.945019"], ["created_at", "2017-08-16 03:34:53.944853"], ["updated_at", "2017-08-16 03:34:53.944853"]] - SQL (0.2ms) 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 03:34:53.947491"], ["updated_at", "2017-08-16 03:34:53.947491"]] - 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 03:34:53.948927"], ["updated_at", "2017-08-16 03:34:53.948927"]] + 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.4ms) rollback transaction -  (0.1ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 - SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name19"], ["created_at", "2017-08-16 03:34:53.955247"], ["updated_at", "2017-08-16 03:34:53.955247"]] + 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.1ms) SAVEPOINT active_record_1 - SQL (0.1ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name20"], ["created_at", "2017-08-16 03:34:53.957690"], ["updated_at", "2017-08-16 03:34:53.957690"]] -  (0.0ms) 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.2ms) INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["request_user_id", 2], ["requested_at", "2017-08-16 03:34:53.965611"], ["created_at", "2017-08-16 03:34:53.965523"], ["updated_at", "2017-08-16 03:34:53.965523"]] - 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 03:34:53.967376"], ["updated_at", "2017-08-16 03:34:53.967376"]] - SQL (0.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 03:34:53.968686"], ["updated_at", "2017-08-16 03:34:53.968686"]] + 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 -  (0.6ms) rollback transaction -  (0.1ms) begin transaction +  (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.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name21"], ["created_at", "2017-08-16 03:34:53.974392"], ["updated_at", "2017-08-16 03:34:53.974392"]] + 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.0ms) SAVEPOINT active_record_1 - SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name22"], ["created_at", "2017-08-16 03:34:53.976736"], ["updated_at", "2017-08-16 03:34:53.976736"]] -  (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.3ms) INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["request_user_id", 2], ["requested_at", "2017-08-16 03:34:53.984516"], ["created_at", "2017-08-16 03:34:53.984444"], ["updated_at", "2017-08-16 03:34:53.984444"]] - SQL (0.2ms) 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 03:34:53.986896"], ["updated_at", "2017-08-16 03:34:53.986896"]] - 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 03:34:53.988536"], ["updated_at", "2017-08-16 03:34:53.988536"]] + 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.5ms) rollback transaction -  (0.1ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 - SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name23"], ["created_at", "2017-08-16 03:34:53.994568"], ["updated_at", "2017-08-16 03:34:53.994568"]] + 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.1ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name24"], ["created_at", "2017-08-16 03:34:53.997572"], ["updated_at", "2017-08-16 03:34:53.997572"]] + 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 - SQL (0.2ms) INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["request_user_id", 2], ["requested_at", "2017-08-16 03:34:54.004295"], ["created_at", "2017-08-16 03:34:54.004214"], ["updated_at", "2017-08-16 03:34:54.004214"]] - SQL (0.2ms) 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 03:34:54.005772"], ["updated_at", "2017-08-16 03:34:54.005772"]] - 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 03:34:54.007309"], ["updated_at", "2017-08-16 03:34:54.007309"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.7ms) rollback transaction + 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.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name25"], ["created_at", "2017-08-16 03:34:54.016013"], ["updated_at", "2017-08-16 03:34:54.016013"]] -  (0.0ms) RELEASE 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 - SQL (0.1ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name26"], ["created_at", "2017-08-16 03:34:54.019156"], ["updated_at", "2017-08-16 03:34:54.019156"]] + 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 - 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 03:34:54.024450"], ["created_at", "2017-08-16 03:34:54.024311"], ["updated_at", "2017-08-16 03:34:54.024311"]] - SQL (0.3ms) 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 03:34:54.026743"], ["updated_at", "2017-08-16 03:34:54.026743"]] - 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 03:34:54.029040"], ["updated_at", "2017-08-16 03:34:54.029040"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.6ms) rollback transaction + 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.1ms) rollback transaction -  (0.1ms) begin transaction -  (0.0ms) rollback transaction -  (0.1ms) begin transaction +  (0.2ms) SELECT COUNT(*) FROM "approval_items"  (0.1ms) SAVEPOINT active_record_1 - SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name28"], ["created_at", "2017-08-16 03:34:54.040141"], ["updated_at", "2017-08-16 03:34:54.040141"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.4ms) rollback transaction -  (0.1ms) begin transaction + 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 - SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name30"], ["created_at", "2017-08-16 03:34:54.046876"], ["updated_at", "2017-08-16 03:34:54.046876"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.3ms) rollback transaction + 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 (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name32"], ["created_at", "2017-08-16 03:34:54.051897"], ["updated_at", "2017-08-16 03:34:54.051897"]] + 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.1ms) begin transaction -  (0.0ms) rollback transaction  (0.2ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction -  (0.2ms) rollback transaction -  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction -  (0.1ms) rollback transaction +  (0.0ms) 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.1ms) begin transaction -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.3ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name26"], ["created_at", "2017-08-16 03:34:54.093634"], ["updated_at", "2017-08-16 03:34:54.093634"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (1.2ms) rollback transaction -  (0.3ms) begin transaction -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.8ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name27"], ["created_at", "2017-08-16 03:34:54.100621"], ["updated_at", "2017-08-16 03:34:54.100621"]] -  (0.2ms) RELEASE SAVEPOINT active_record_1 -  (2.1ms) rollback transaction -  (0.1ms) begin transaction  (0.2ms) rollback transaction -  (0.3ms) begin transaction -  (0.1ms) rollback transaction  (0.2ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 - SQL (0.5ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name28"], ["created_at", "2017-08-16 03:34:54.124951"], ["updated_at", "2017-08-16 03:34:54.124951"]] + 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 -  (0.5ms) rollback transaction -  (0.1ms) begin transaction +  (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 "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name29"], ["created_at", "2017-08-16 03:34:54.131795"], ["updated_at", "2017-08-16 03:34:54.131795"]] + 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 -  (0.4ms) rollback transaction +  (1.3ms) rollback transaction  (0.1ms) begin transaction -  (4.1ms) 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", "name30"], ["created_at", "2017-08-16 03:34:54.143622"], ["updated_at", "2017-08-16 03:34:54.143622"]] + 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 -  (0.8ms) rollback transaction +  (1.4ms) rollback transaction  (0.3ms) begin transaction -  (0.1ms) rollback transaction -  (0.2ms) begin transaction -  (0.3ms) SAVEPOINT active_record_1 - SQL (0.7ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name31"], ["created_at", "2017-08-16 03:34:54.160916"], ["updated_at", "2017-08-16 03:34:54.160916"]] +  (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.9ms) rollback transaction -  (0.2ms) begin transaction -  (0.1ms) rollback transaction -  (0.2ms) begin transaction -  (0.1ms) rollback transaction -  (0.1ms) begin transaction -  (0.1ms) rollback transaction -  (0.1ms) begin transaction +  (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.2ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name32"], ["created_at", "2017-08-16 03:34:54.216038"], ["updated_at", "2017-08-16 03:34:54.216038"]] + 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 - Approval::Request Load (0.2ms) SELECT "approval_requests".* FROM "approval_requests" WHERE "approval_requests"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] - Book Load (0.1ms) SELECT "books".* FROM "books" WHERE "books"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] -  (1.5ms) rollback transaction -  (0.1ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 - SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name36"], ["created_at", "2017-08-16 03:34:54.238920"], ["updated_at", "2017-08-16 03:34:54.238920"]] + 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.1ms) SAVEPOINT active_record_1 - SQL (0.2ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name33"], ["created_at", "2017-08-16 03:34:54.243399"], ["updated_at", "2017-08-16 03:34:54.243399"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) SAVEPOINT active_record_1 - Book Load (0.3ms) 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.6ms) INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["request_user_id", 1], ["requested_at", "2017-08-16 03:34:54.250851"], ["created_at", "2017-08-16 03:34:54.250597"], ["updated_at", "2017-08-16 03:34:54.250597"]] - SQL (0.6ms) INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?) [["request_id", 1], ["user_id", 1], ["content", "content10"], ["created_at", "2017-08-16 03:34:54.255560"], ["updated_at", "2017-08-16 03:34:54.255560"]] - 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", "---\n:name: updated_name\n"], ["created_at", "2017-08-16 03:34:54.262904"], ["updated_at", "2017-08-16 03:34:54.262904"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 - Book Load (0.2ms) SELECT "books".* FROM "books" ORDER BY "books"."id" ASC LIMIT ? [["LIMIT", 1]] - Book Load (0.1ms) SELECT "books".* FROM "books" WHERE "books"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.2ms) UPDATE "books" SET "name" = ?, "updated_at" = ? WHERE "books"."id" = ? [["name", "updated_name"], ["updated_at", "2017-08-16 03:34:54.276807"], ["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.8ms) rollback transaction +  (0.6ms) 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", "name37"], ["created_at", "2017-08-16 03:34:54.288094"], ["updated_at", "2017-08-16 03:34:54.288094"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) SAVEPOINT active_record_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 03:34:54.293463"], ["created_at", "2017-08-16 03:34:54.293397"], ["updated_at", "2017-08-16 03:34:54.293397"]] - SQL (0.1ms) INSERT INTO "approval_comments" ("request_id", "user_id", "content", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?) [["request_id", 1], ["user_id", 1], ["content", "content11"], ["created_at", "2017-08-16 03:34:54.294727"], ["updated_at", "2017-08-16 03:34:54.294727"]] - 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 03:34:54.295921"], ["updated_at", "2017-08-16 03:34:54.295921"]] -  (0.3ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) SELECT COUNT(*) FROM "books" -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.2ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "created_name"], ["created_at", "2017-08-16 03:34:54.300530"], ["updated_at", "2017-08-16 03:34:54.300530"]] +  (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.1ms) 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 03:34:54.304943"], ["id", 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 -  (0.3ms) SELECT COUNT(*) FROM "books" -  (0.9ms) rollback transaction -  (0.2ms) begin transaction -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.5ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name38"], ["created_at", "2017-08-16 03:34:54.315377"], ["updated_at", "2017-08-16 03:34:54.315377"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.3ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name34"], ["created_at", "2017-08-16 03:34:54.321893"], ["updated_at", "2017-08-16 03:34:54.321893"]] +  (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.2ms) INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["request_user_id", 1], ["requested_at", "2017-08-16 03:34:54.326694"], ["created_at", "2017-08-16 03:34:54.326612"], ["updated_at", "2017-08-16 03:34:54.326612"]] - SQL (0.2ms) 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 03:34:54.327979"], ["updated_at", "2017-08-16 03:34:54.327979"]] - 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 03:34:54.329052"], ["updated_at", "2017-08-16 03:34:54.329052"]] + 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 -  (3.2ms) SELECT COUNT(*) FROM "books" - Book Load (0.1ms) SELECT "books".* FROM "books" WHERE "books"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]]  (0.0ms) SAVEPOINT active_record_1 - SQL (0.1ms) DELETE FROM "books" WHERE "books"."id" = ? [["id", 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) SELECT COUNT(*) FROM "books" -  (0.4ms) 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.4ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name36"], ["created_at", "2017-08-16 03:34:54.343276"], ["updated_at", "2017-08-16 03:34:54.343276"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.5ms) rollback transaction + 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.1ms) SAVEPOINT active_record_1 - SQL (0.5ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name39"], ["created_at", "2017-08-16 03:34:54.360690"], ["updated_at", "2017-08-16 03:34:54.360690"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.6ms) rollback 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.1ms) 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", "name40"], ["created_at", "2017-08-16 03:34:54.376459"], ["updated_at", "2017-08-16 03:34:54.376459"]] +  (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.4ms) rollback transaction -  (0.1ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 - SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name41"], ["created_at", "2017-08-16 03:34:54.386043"], ["updated_at", "2017-08-16 03:34:54.386043"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.5ms) rollback transaction -  (0.1ms) begin transaction -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name42"], ["created_at", "2017-08-16 03:34:54.391754"], ["updated_at", "2017-08-16 03:34:54.391754"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.5ms) rollback transaction -  (0.1ms) begin transaction -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.7ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name43"], ["created_at", "2017-08-16 03:34:54.398299"], ["updated_at", "2017-08-16 03:34:54.398299"]] -  (0.3ms) RELEASE SAVEPOINT active_record_1 -  (1.1ms) rollback transaction -  (0.1ms) begin transaction + 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.1ms) SAVEPOINT active_record_1 - SQL (0.6ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name44"], ["created_at", "2017-08-16 03:34:54.418259"], ["updated_at", "2017-08-16 03:34:54.418259"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.6ms) rollback transaction +  (0.0ms) rollback transaction  (0.1ms) begin transaction -  (0.1ms) rollback transaction +  (0.0ms) rollback transaction  (0.1ms) begin transaction -  (0.2ms) SELECT COUNT(*) FROM "approval_items"  (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 03:34:54.428469"], ["updated_at", "2017-08-16 03:34:54.428469"]] + 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 -  (3.2ms) SAVEPOINT active_record_1 - SQL (0.2ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name43"], ["created_at", "2017-08-16 03:34:54.434275"], ["updated_at", "2017-08-16 03:34:54.434275"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.0ms) 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 03:34:54.440867"], ["created_at", "2017-08-16 03:34:54.440804"], ["updated_at", "2017-08-16 03:34:54.440804"]] - 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 03:34:54.441999"], ["updated_at", "2017-08-16 03:34:54.441999"]] - 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 03:34:54.443164"], ["updated_at", "2017-08-16 03:34:54.443164"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) SELECT COUNT(*) FROM "approval_items"  (0.9ms) rollback transaction +  (0.3ms) begin transaction +  (0.4ms) rollback transaction  (0.2ms) begin transaction -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.9ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name46"], ["created_at", "2017-08-16 03:34:54.456267"], ["updated_at", "2017-08-16 03:34:54.456267"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) SAVEPOINT active_record_1 - SQL (0.3ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name44"], ["created_at", "2017-08-16 03:34:54.461362"], ["updated_at", "2017-08-16 03:34:54.461362"]] -  (0.1ms) 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.2ms) INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["request_user_id", 1], ["requested_at", "2017-08-16 03:34:54.474616"], ["created_at", "2017-08-16 03:34:54.474422"], ["updated_at", "2017-08-16 03:34:54.474422"]] - 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 03:34:54.476453"], ["updated_at", "2017-08-16 03:34:54.476453"]] - 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 03:34:54.478157"], ["updated_at", "2017-08-16 03:34:54.478157"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.6ms) rollback transaction -  (0.1ms) 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", "name47"], ["created_at", "2017-08-16 03:34:54.488097"], ["updated_at", "2017-08-16 03:34:54.488097"]] -  (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", "name45"], ["created_at", "2017-08-16 03:34:54.490846"], ["updated_at", "2017-08-16 03:34:54.490846"]] -  (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", "name46"], ["created_at", "2017-08-16 03:34:54.492648"], ["updated_at", "2017-08-16 03:34:54.492648"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.0ms) SAVEPOINT active_record_1 - SQL (0.1ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name47"], ["created_at", "2017-08-16 03:34:54.493982"], ["updated_at", "2017-08-16 03:34:54.493982"]] -  (0.1ms) 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.4ms) INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["request_user_id", 1], ["requested_at", "2017-08-16 03:34:54.512274"], ["created_at", "2017-08-16 03:34:54.512109"], ["updated_at", "2017-08-16 03:34:54.512109"]] - 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 03:34:54.515405"], ["updated_at", "2017-08-16 03:34:54.515405"]] - 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 03:34:54.518170"], ["updated_at", "2017-08-16 03:34:54.518170"]] - SQL (0.3ms) 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 03:34:54.520509"], ["updated_at", "2017-08-16 03:34:54.520509"]] - SQL (0.3ms) 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 03:34:54.522705"], ["updated_at", "2017-08-16 03:34:54.522705"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.1ms) SELECT COUNT(*) FROM "approval_items" -  (1.4ms) rollback transaction -  (0.1ms) begin transaction -  (0.3ms) SAVEPOINT active_record_1 - SQL (0.7ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name48"], ["created_at", "2017-08-16 03:34:54.535293"], ["updated_at", "2017-08-16 03:34:54.535293"]] -  (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", "name48"], ["created_at", "2017-08-16 03:34:54.539103"], ["updated_at", "2017-08-16 03:34:54.539103"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.0ms) SAVEPOINT active_record_1 - SQL (0.1ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name49"], ["created_at", "2017-08-16 03:34:54.540446"], ["updated_at", "2017-08-16 03:34:54.540446"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (0.0ms) SAVEPOINT active_record_1 - SQL (0.1ms) INSERT INTO "books" ("name", "created_at", "updated_at") VALUES (?, ?, ?) [["name", "name50"], ["created_at", "2017-08-16 03:34:54.541930"], ["updated_at", "2017-08-16 03:34:54.541930"]] -  (0.0ms) RELEASE SAVEPOINT active_record_1 -  (0.0ms) SAVEPOINT active_record_1 - User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] - SQL (0.8ms) INSERT INTO "approval_requests" ("request_user_id", "requested_at", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["request_user_id", 1], ["requested_at", "2017-08-16 03:34:54.554956"], ["created_at", "2017-08-16 03:34:54.554570"], ["updated_at", "2017-08-16 03:34:54.554570"]] - 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 03:34:54.559899"], ["updated_at", "2017-08-16 03:34:54.559899"]] - 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 03:34:54.561618"], ["updated_at", "2017-08-16 03:34:54.561618"]] - 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 03:34:54.563514"], ["updated_at", "2017-08-16 03:34:54.563514"]] - SQL (0.3ms) 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 03:34:54.566174"], ["updated_at", "2017-08-16 03:34:54.566174"]] -  (0.1ms) RELEASE SAVEPOINT active_record_1 -  (1.5ms) rollback transaction +  (0.1ms) rollback transaction