(237.8ms) CREATE TABLE "products" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "sku" varchar, "title" varchar, "price" decimal, "active" boolean, "description" text, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)  (114.1ms) CREATE TABLE "users" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "email" varchar, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)  (130.8ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL PRIMARY KEY)  (0.1ms) SELECT version FROM "schema_migrations"  (241.9ms) INSERT INTO "schema_migrations" (version) VALUES (20170507023113)  (0.1ms) select sqlite_version(*)  (156.0ms) INSERT INTO "schema_migrations" (version) VALUES (20170507023018);   (257.5ms) CREATE TABLE "ar_internal_metadata" ("key" varchar NOT NULL PRIMARY KEY, "value" varchar, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL) ActiveRecord::InternalMetadata Load (0.1ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ? [["key", :environment], ["LIMIT", 1]]  (0.0ms) begin transaction SQL (0.2ms) INSERT INTO "ar_internal_metadata" ("key", "value", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["key", "environment"], ["value", "test"], ["created_at", 2017-05-07 18:26:06 UTC], ["updated_at", 2017-05-07 18:26:06 UTC]]  (166.7ms) commit transaction ActiveRecord::InternalMetadata Load (0.0ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ? [["key", :environment], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction  (0.3ms) begin transaction  (0.1ms) rollback transaction  (0.2ms) begin transaction Rendered /home/douglas/code/audited_views/app/views/audited_views/_controls.html.erb (13.0ms)  (0.1ms) rollback transaction  (0.3ms) begin transaction Rendered /home/douglas/code/audited_views/app/views/audited_views/_controls.html.erb (21.2ms)  (0.1ms) rollback transaction  (0.3ms) begin transaction Rendered /home/douglas/code/audited_views/app/views/audited_views/_controls.html.erb (20.4ms)  (0.1ms) rollback transaction  (0.3ms) begin transaction Rendered /home/douglas/code/audited_views/app/views/audited_views/_controls.html.erb (21.1ms)  (0.1ms) rollback transaction  (0.3ms) begin transaction Rendered /home/douglas/code/audited_views/app/views/audited_views/_controls.html.erb (55.6ms)  (0.1ms) rollback transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" Migrating to InstallAudited (20170507191539)  (0.0ms) begin transaction  (0.3ms) CREATE TABLE "audits" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "auditable_id" integer, "auditable_type" varchar, "associated_id" integer, "associated_type" varchar, "user_id" integer, "user_type" varchar, "username" varchar, "action" varchar, "audited_changes" text, "version" integer DEFAULT 0, "comment" varchar, "remote_address" varchar, "request_uuid" varchar, "created_at" datetime)  (0.0ms) select sqlite_version(*)  (0.1ms) CREATE INDEX "auditable_index" ON "audits" ("auditable_id", "auditable_type")  (0.1ms)  SELECT sql FROM sqlite_master WHERE name='auditable_index' AND type='index' UNION ALL SELECT sql FROM sqlite_temp_master WHERE name='auditable_index' AND type='index'   (0.1ms) CREATE INDEX "associated_index" ON "audits" ("associated_id", "associated_type")  (0.0ms)  SELECT sql FROM sqlite_master WHERE name='associated_index' AND type='index' UNION ALL SELECT sql FROM sqlite_temp_master WHERE name='associated_index' AND type='index'   (0.0ms)  SELECT sql FROM sqlite_master WHERE name='auditable_index' AND type='index' UNION ALL SELECT sql FROM sqlite_temp_master WHERE name='auditable_index' AND type='index'   (0.1ms) CREATE INDEX "user_index" ON "audits" ("user_id", "user_type")  (0.1ms)  SELECT sql FROM sqlite_master WHERE name='user_index' AND type='index' UNION ALL SELECT sql FROM sqlite_temp_master WHERE name='user_index' AND type='index'   (0.1ms)  SELECT sql FROM sqlite_master WHERE name='associated_index' AND type='index' UNION ALL SELECT sql FROM sqlite_temp_master WHERE name='associated_index' AND type='index'   (0.0ms)  SELECT sql FROM sqlite_master WHERE name='auditable_index' AND type='index' UNION ALL SELECT sql FROM sqlite_temp_master WHERE name='auditable_index' AND type='index'   (0.1ms) CREATE INDEX "index_audits_on_request_uuid" ON "audits" ("request_uuid")  (0.1ms)  SELECT sql FROM sqlite_master WHERE name='index_audits_on_request_uuid' AND type='index' UNION ALL SELECT sql FROM sqlite_temp_master WHERE name='index_audits_on_request_uuid' AND type='index'   (0.0ms)  SELECT sql FROM sqlite_master WHERE name='user_index' AND type='index' UNION ALL SELECT sql FROM sqlite_temp_master WHERE name='user_index' AND type='index'   (0.0ms)  SELECT sql FROM sqlite_master WHERE name='associated_index' AND type='index' UNION ALL SELECT sql FROM sqlite_temp_master WHERE name='associated_index' AND type='index'   (0.0ms)  SELECT sql FROM sqlite_master WHERE name='auditable_index' AND type='index' UNION ALL SELECT sql FROM sqlite_temp_master WHERE name='auditable_index' AND type='index'   (0.1ms) CREATE INDEX "index_audits_on_created_at" ON "audits" ("created_at") SQL (0.1ms) INSERT INTO "schema_migrations" ("version") VALUES (?) [["version", "20170507191539"]]  (63.5ms) commit transaction ActiveRecord::InternalMetadata Load (0.1ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ? [["key", :environment], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.1ms)  SELECT sql FROM sqlite_master WHERE name='index_audits_on_created_at' AND type='index' UNION ALL SELECT sql FROM sqlite_temp_master WHERE name='index_audits_on_created_at' AND type='index'   (0.1ms)  SELECT sql FROM sqlite_master WHERE name='index_audits_on_request_uuid' AND type='index' UNION ALL SELECT sql FROM sqlite_temp_master WHERE name='index_audits_on_request_uuid' AND type='index'   (0.1ms)  SELECT sql FROM sqlite_master WHERE name='user_index' AND type='index' UNION ALL SELECT sql FROM sqlite_temp_master WHERE name='user_index' AND type='index'   (0.1ms)  SELECT sql FROM sqlite_master WHERE name='associated_index' AND type='index' UNION ALL SELECT sql FROM sqlite_temp_master WHERE name='associated_index' AND type='index'   (0.1ms)  SELECT sql FROM sqlite_master WHERE name='auditable_index' AND type='index' UNION ALL SELECT sql FROM sqlite_temp_master WHERE name='auditable_index' AND type='index'   (0.3ms) begin transaction Rendered /home/douglas/code/audited_views/app/views/audited_views/_controls.html.erb (41.1ms)  (0.1ms) rollback transaction  (0.3ms) begin transaction Rendered /home/douglas/code/audited_views/app/views/audited_views/_controls.html.erb (20.8ms)  (0.1ms) rollback transaction  (0.3ms) begin transaction Rendered /home/douglas/code/audited_views/app/views/audited_views/_controls.html.erb (27.8ms)  (0.1ms) rollback transaction  (0.3ms) begin transaction Rendered /home/douglas/code/audited_views/app/views/audited_views/_controls.html.erb (20.7ms)  (0.1ms) rollback transaction  (0.3ms) begin transaction Rendered /home/douglas/code/audited_views/app/views/audited_views/_controls.html.erb (21.9ms)  (0.1ms) rollback transaction  (0.8ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/douglas/code/audited_views/app/views/audited_views/_controls.html.erb (42.4ms)  (0.2ms) rollback transaction  (0.7ms) begin transaction Started GET "/products" for 127.0.0.1 at 2017-05-07 16:43:51 -0300 Processing by ProductsController#index as HTML Rendering products/index.html.erb within layouts/application Product Load (2.1ms) SELECT "products".* FROM "products" Rendered products/index.html.erb within layouts/application (61.9ms) Completed 200 OK in 244ms (Views: 234.5ms | ActiveRecord: 2.8ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction Rendered /home/douglas/code/audited_views/app/views/audited_views/_controls.html.erb (13.6ms)  (0.1ms) rollback transaction  (0.3ms) begin transaction Rendered /home/douglas/code/audited_views/app/views/audited_views/_controls.html.erb (19.1ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.3ms) begin transaction Rendered /home/douglas/code/audited_views/app/views/audited_views/_controls.html.erb (19.1ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.3ms) begin transaction Rendered /home/douglas/code/audited_views/app/views/audited_views/_controls.html.erb (20.8ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.3ms) begin transaction Rendered /home/douglas/code/audited_views/app/views/audited_views/_controls.html.erb (31.5ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) rollback transaction  (0.3ms) begin transaction  (0.2ms) rollback transaction  (0.1ms) begin transaction Rendered /home/douglas/code/audited_views/app/views/audited_views/_controls.html.erb (28.6ms)  (0.1ms) rollback transaction  (0.3ms) begin transaction Rendered /home/douglas/code/audited_views/app/views/audited_views/_controls.html.erb (24.7ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.2ms) rollback transaction  (0.3ms) begin transaction Rendered /home/douglas/code/audited_views/app/views/audited_views/_controls.html.erb (22.5ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["sku", "001"], ["title", "Smartphone"], ["price", #], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-07 20:05:17 UTC], ["updated_at", 2017-05-07 20:05:17 UTC]] Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" DESC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["LIMIT", 1]] SQL (0.2ms) INSERT INTO "audits" ("auditable_id", "auditable_type", "action", "audited_changes", "version", "request_uuid", "created_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["audited_changes", "---\nsku: '001'\ntitle: Smartphone\nprice: !ruby/object:BigDecimal 18:0.45E3\nactive: true\ndescription: Smartphone ABC\n"], ["version", 1], ["request_uuid", "323fecf6-f855-4ee8-9581-d463c45e81e1"], ["created_at", 2017-05-07 20:05:17 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/products/1" for 127.0.0.1 at 2017-05-07 17:05:17 -0300 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Rendered products/show.html.erb within layouts/application (24.0ms) Completed 500 Internal Server Error in 42ms (ActiveRecord: 0.1ms)  (0.3ms) rollback transaction  (0.3ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["sku", "001"], ["title", "Smartphone"], ["price", #], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-07 20:29:05 UTC], ["updated_at", 2017-05-07 20:29:05 UTC]] Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" DESC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["LIMIT", 1]] SQL (0.2ms) INSERT INTO "audits" ("auditable_id", "auditable_type", "action", "audited_changes", "version", "request_uuid", "created_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["audited_changes", "---\nsku: '001'\ntitle: Smartphone\nprice: !ruby/object:BigDecimal 18:0.45E3\nactive: true\ndescription: Smartphone ABC\n"], ["version", 1], ["request_uuid", "a195c62c-37bd-4909-ac7a-de988528b636"], ["created_at", 2017-05-07 20:29:05 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/products/1" for 127.0.0.1 at 2017-05-07 17:29:05 -0300 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Rendered products/show.html.erb within layouts/application (12.2ms) Completed 500 Internal Server Error in 18ms (ActiveRecord: 0.1ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/douglas/code/audited_views/app/views/audited_views/_controls.html.erb (9.0ms)  (0.1ms) rollback transaction  (0.3ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["sku", "001"], ["title", "Smartphone"], ["price", #], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-07 20:29:39 UTC], ["updated_at", 2017-05-07 20:29:39 UTC]] Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" DESC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["LIMIT", 1]] SQL (0.2ms) INSERT INTO "audits" ("auditable_id", "auditable_type", "action", "audited_changes", "version", "request_uuid", "created_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["audited_changes", "---\nsku: '001'\ntitle: Smartphone\nprice: !ruby/object:BigDecimal 18:0.45E3\nactive: true\ndescription: Smartphone ABC\n"], ["version", 1], ["request_uuid", "7f842ffb-6f82-4091-8c7b-c8eb41e8698d"], ["created_at", 2017-05-07 20:29:39 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/products/1" for 127.0.0.1 at 2017-05-07 17:29:39 -0300 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (8.0ms) Rendered products/show.html.erb within layouts/application (18.4ms) Completed 200 OK in 165ms (Views: 161.7ms | ActiveRecord: 0.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.3ms) begin transaction  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["sku", "001"], ["title", "Smartphone"], ["price", #], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-07 20:30:05 UTC], ["updated_at", 2017-05-07 20:30:05 UTC]] Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" DESC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["LIMIT", 1]] SQL (0.2ms) INSERT INTO "audits" ("auditable_id", "auditable_type", "action", "audited_changes", "version", "request_uuid", "created_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["audited_changes", "---\nsku: '001'\ntitle: Smartphone\nprice: !ruby/object:BigDecimal 18:0.45E3\nactive: true\ndescription: Smartphone ABC\n"], ["version", 1], ["request_uuid", "afa4fa2c-b07c-4251-a19a-5b183326543b"], ["created_at", 2017-05-07 20:30:05 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/products/1" for 127.0.0.1 at 2017-05-07 17:30:05 -0300 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (8.9ms) Rendered products/show.html.erb within layouts/application (19.6ms) Completed 200 OK in 162ms (Views: 159.3ms | ActiveRecord: 0.3ms)  (0.1ms) rollback transaction  (0.3ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["sku", "001"], ["title", "Smartphone"], ["price", #], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-07 20:35:14 UTC], ["updated_at", 2017-05-07 20:35:14 UTC]] Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" DESC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["LIMIT", 1]] SQL (0.2ms) INSERT INTO "audits" ("auditable_id", "auditable_type", "action", "audited_changes", "version", "request_uuid", "created_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["audited_changes", "---\nsku: '001'\ntitle: Smartphone\nprice: !ruby/object:BigDecimal 18:0.45E3\nactive: true\ndescription: Smartphone ABC\n"], ["version", 1], ["request_uuid", "8a562ba5-843e-40d1-b81f-23ad0b81f1fd"], ["created_at", 2017-05-07 20:35:14 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/products/1" for 127.0.0.1 at 2017-05-07 17:35:14 -0300 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (8.5ms) Rendered products/show.html.erb within layouts/application (19.6ms) Completed 200 OK in 165ms (Views: 161.7ms | ActiveRecord: 0.3ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) rollback transaction  (0.3ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["sku", "001"], ["title", "Smartphone"], ["price", #], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-08 00:53:41 UTC], ["updated_at", 2017-05-08 00:53:41 UTC]] Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" DESC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["LIMIT", 1]] SQL (0.2ms) INSERT INTO "audits" ("auditable_id", "auditable_type", "action", "audited_changes", "version", "request_uuid", "created_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["audited_changes", "---\nsku: '001'\ntitle: Smartphone\nprice: !ruby/object:BigDecimal 18:0.45E3\nactive: true\ndescription: Smartphone ABC\n"], ["version", 1], ["request_uuid", "a85b1fe8-23fc-47bc-82c9-fb9ae369149a"], ["created_at", 2017-05-08 00:53:41 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/products/1" for 127.0.0.1 at 2017-05-07 21:53:41 -0300 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (8.3ms) Rendered products/show.html.erb within layouts/application (19.0ms) Completed 200 OK in 168ms (Views: 164.7ms | ActiveRecord: 0.3ms)  (0.1ms) rollback transaction  (0.3ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["sku", "001"], ["title", "Smartphone"], ["price", #], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-08 00:54:26 UTC], ["updated_at", 2017-05-08 00:54:26 UTC]] Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" DESC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["LIMIT", 1]] SQL (0.2ms) INSERT INTO "audits" ("auditable_id", "auditable_type", "action", "audited_changes", "version", "request_uuid", "created_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["audited_changes", "---\nsku: '001'\ntitle: Smartphone\nprice: !ruby/object:BigDecimal 18:0.45E3\nactive: true\ndescription: Smartphone ABC\n"], ["version", 1], ["request_uuid", "64a62eff-5388-42ad-b543-7951978660d4"], ["created_at", 2017-05-08 00:54:26 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/products/1" for 127.0.0.1 at 2017-05-07 21:54:26 -0300 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (8.5ms) Rendered products/show.html.erb within layouts/application (21.1ms) Completed 200 OK in 165ms (Views: 162.1ms | ActiveRecord: 0.3ms)  (0.1ms) rollback transaction  (0.8ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 SQL (0.6ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "user@domain.com"], ["created_at", 2017-05-08 00:56:45 UTC], ["updated_at", 2017-05-08 00:56:45 UTC]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.5ms) INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["sku", "001"], ["title", "Smartphone"], ["price", #], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-08 00:56:45 UTC], ["updated_at", 2017-05-08 00:56:45 UTC]] Audited::Audit Load (0.5ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" DESC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["LIMIT", 1]] SQL (0.5ms) INSERT INTO "audits" ("auditable_id", "auditable_type", "user_id", "user_type", "action", "audited_changes", "version", "request_uuid", "created_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["auditable_id", 1], ["auditable_type", "Product"], ["user_id", 1], ["user_type", "User"], ["action", "create"], ["audited_changes", "---\nsku: '001'\ntitle: Smartphone\nprice: !ruby/object:BigDecimal 18:0.45E3\nactive: true\ndescription: Smartphone ABC\n"], ["version", 1], ["request_uuid", "283cfd86-3fd1-48bd-b5d1-9cd6d5c9e639"], ["created_at", 2017-05-08 00:56:45 UTC]]  (0.2ms) RELEASE SAVEPOINT active_record_1 Started GET "/products/1" for 127.0.0.1 at 2017-05-07 21:56:45 -0300 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.3ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.5ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (30.8ms) Rendered products/show.html.erb within layouts/application (66.7ms) Completed 200 OK in 446ms (Views: 436.6ms | ActiveRecord: 1.1ms)  (0.4ms) rollback transaction  (0.3ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "user@domain.com"], ["created_at", 2017-05-08 00:57:43 UTC], ["updated_at", 2017-05-08 00:57:43 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["sku", "001"], ["title", "Smartphone"], ["price", #], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-08 00:57:43 UTC], ["updated_at", 2017-05-08 00:57:43 UTC]] Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" DESC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["LIMIT", 1]] SQL (0.2ms) INSERT INTO "audits" ("auditable_id", "auditable_type", "user_id", "user_type", "action", "audited_changes", "version", "request_uuid", "created_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["auditable_id", 1], ["auditable_type", "Product"], ["user_id", 1], ["user_type", "User"], ["action", "create"], ["audited_changes", "---\nsku: '001'\ntitle: Smartphone\nprice: !ruby/object:BigDecimal 18:0.45E3\nactive: true\ndescription: Smartphone ABC\n"], ["version", 1], ["request_uuid", "04f3a37d-b748-40b2-85b6-657bb1720ed9"], ["created_at", 2017-05-08 00:57:43 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/products/1" for 127.0.0.1 at 2017-05-07 21:57:43 -0300 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (11.2ms) Rendered products/show.html.erb within layouts/application (23.3ms) Completed 200 OK in 164ms (Views: 161.1ms | ActiveRecord: 0.4ms)  (0.1ms) rollback transaction  (0.3ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "user@domain.com"], ["created_at", 2017-05-08 00:58:32 UTC], ["updated_at", 2017-05-08 00:58:32 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["sku", "001"], ["title", "Smartphone"], ["price", #], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-08 00:58:32 UTC], ["updated_at", 2017-05-08 00:58:32 UTC]] Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" DESC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["LIMIT", 1]] SQL (0.2ms) INSERT INTO "audits" ("auditable_id", "auditable_type", "user_id", "user_type", "action", "audited_changes", "version", "request_uuid", "created_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["auditable_id", 1], ["auditable_type", "Product"], ["user_id", 1], ["user_type", "User"], ["action", "create"], ["audited_changes", "---\nsku: '001'\ntitle: Smartphone\nprice: !ruby/object:BigDecimal 18:0.45E3\nactive: true\ndescription: Smartphone ABC\n"], ["version", 1], ["request_uuid", "78cbd01a-047d-491f-a76e-2db32e0cca7d"], ["created_at", 2017-05-08 00:58:32 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/products/1" for 127.0.0.1 at 2017-05-07 21:58:32 -0300 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (11.8ms) Rendered products/show.html.erb within layouts/application (24.1ms) Completed 200 OK in 172ms (Views: 168.2ms | ActiveRecord: 0.4ms)  (0.2ms) rollback transaction  (0.3ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "user@domain.com"], ["created_at", 2017-05-08 00:59:09 UTC], ["updated_at", 2017-05-08 00:59:09 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["sku", "001"], ["title", "Smartphone"], ["price", #], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-08 00:59:10 UTC], ["updated_at", 2017-05-08 00:59:10 UTC]] Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" DESC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["LIMIT", 1]] SQL (0.2ms) INSERT INTO "audits" ("auditable_id", "auditable_type", "user_id", "user_type", "action", "audited_changes", "version", "request_uuid", "created_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["auditable_id", 1], ["auditable_type", "Product"], ["user_id", 1], ["user_type", "User"], ["action", "create"], ["audited_changes", "---\nsku: '001'\ntitle: Smartphone\nprice: !ruby/object:BigDecimal 18:0.45E3\nactive: true\ndescription: Smartphone ABC\n"], ["version", 1], ["request_uuid", "9af52212-6076-4ca9-8fda-1f9673077814"], ["created_at", 2017-05-08 00:59:10 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/products/1" for 127.0.0.1 at 2017-05-07 21:59:10 -0300 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] User Load (0.4ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (11.8ms) Rendered products/show.html.erb within layouts/application (24.1ms) Completed 200 OK in 172ms (Views: 168.1ms | ActiveRecord: 0.7ms)  (0.1ms) rollback transaction  (0.3ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "user@domain.com"], ["created_at", 2017-05-08 00:59:30 UTC], ["updated_at", 2017-05-08 00:59:30 UTC]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["sku", "001"], ["title", "Smartphone"], ["price", #], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-08 00:59:30 UTC], ["updated_at", 2017-05-08 00:59:30 UTC]] Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" DESC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["LIMIT", 1]] SQL (0.2ms) INSERT INTO "audits" ("auditable_id", "auditable_type", "user_id", "user_type", "action", "audited_changes", "version", "request_uuid", "created_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["auditable_id", 1], ["auditable_type", "Product"], ["user_id", 1], ["user_type", "User"], ["action", "create"], ["audited_changes", "---\nsku: '001'\ntitle: Smartphone\nprice: !ruby/object:BigDecimal 18:0.45E3\nactive: true\ndescription: Smartphone ABC\n"], ["version", 1], ["request_uuid", "4432d727-4179-41a2-b873-00bd14ae32fb"], ["created_at", 2017-05-08 00:59:30 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/products/1" for 127.0.0.1 at 2017-05-07 21:59:30 -0300 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (11.5ms) Rendered products/show.html.erb within layouts/application (24.6ms) Completed 200 OK in 169ms (Views: 165.5ms | ActiveRecord: 0.4ms)  (0.2ms) rollback transaction  (0.3ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "user@domain.com"], ["created_at", 2017-05-08 00:59:33 UTC], ["updated_at", 2017-05-08 00:59:33 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["sku", "001"], ["title", "Smartphone"], ["price", #], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-08 00:59:33 UTC], ["updated_at", 2017-05-08 00:59:33 UTC]] Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" DESC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["LIMIT", 1]] SQL (0.2ms) INSERT INTO "audits" ("auditable_id", "auditable_type", "user_id", "user_type", "action", "audited_changes", "version", "request_uuid", "created_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["auditable_id", 1], ["auditable_type", "Product"], ["user_id", 1], ["user_type", "User"], ["action", "create"], ["audited_changes", "---\nsku: '001'\ntitle: Smartphone\nprice: !ruby/object:BigDecimal 18:0.45E3\nactive: true\ndescription: Smartphone ABC\n"], ["version", 1], ["request_uuid", "9c088518-cb86-44ee-b5e0-9d052189a3e2"], ["created_at", 2017-05-08 00:59:33 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/products/1" for 127.0.0.1 at 2017-05-07 21:59:33 -0300 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.4ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (10.9ms) Rendered products/show.html.erb within layouts/application (23.5ms) Completed 200 OK in 164ms (Views: 161.0ms | ActiveRecord: 0.7ms)  (0.1ms) rollback transaction  (0.3ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "user@domain.com"], ["created_at", 2017-05-08 01:00:36 UTC], ["updated_at", 2017-05-08 01:00:36 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["sku", "001"], ["title", "Smartphone"], ["price", #], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-08 01:00:36 UTC], ["updated_at", 2017-05-08 01:00:36 UTC]] Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" DESC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["LIMIT", 1]] SQL (0.2ms) INSERT INTO "audits" ("auditable_id", "auditable_type", "user_id", "user_type", "action", "audited_changes", "version", "request_uuid", "created_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["auditable_id", 1], ["auditable_type", "Product"], ["user_id", 1], ["user_type", "User"], ["action", "create"], ["audited_changes", "---\nsku: '001'\ntitle: Smartphone\nprice: !ruby/object:BigDecimal 18:0.45E3\nactive: true\ndescription: Smartphone ABC\n"], ["version", 1], ["request_uuid", "1a89bf0d-dc4a-4ff1-aaf0-6c7f4e0e6a94"], ["created_at", 2017-05-08 01:00:36 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/products/1" for 127.0.0.1 at 2017-05-07 22:00:36 -0300 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (11.7ms) Rendered products/show.html.erb within layouts/application (24.0ms) Completed 200 OK in 170ms (Views: 166.2ms | ActiveRecord: 0.5ms) Started GET "/products/1?show_log=true" for 127.0.0.1 at 2017-05-07 22:00:37 -0300 Processing by ProductsController#show as HTML Parameters: {"show_log"=>"true", "id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Audited::Audit Load (0.1ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" ASC [["auditable_id", 1], ["auditable_type", "Product"]] CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Product Load (0.0ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_audit_record.html.erb (6.4ms) Rendered /home/douglas/code/audited_views/app/views/application/_audits.html.erb (8.5ms) Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (12.5ms) Rendered products/show.html.erb within layouts/application (14.0ms) Completed 500 Internal Server Error in 16ms (ActiveRecord: 0.5ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "user@domain.com"], ["created_at", 2017-05-08 01:00:37 UTC], ["updated_at", 2017-05-08 01:00:37 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["sku", "001"], ["title", "Smartphone"], ["price", #], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-08 01:00:37 UTC], ["updated_at", 2017-05-08 01:00:37 UTC]] Audited::Audit Load (0.1ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" DESC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["LIMIT", 1]] SQL (0.2ms) INSERT INTO "audits" ("auditable_id", "auditable_type", "user_id", "user_type", "action", "audited_changes", "version", "request_uuid", "created_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["auditable_id", 1], ["auditable_type", "Product"], ["user_id", 1], ["user_type", "User"], ["action", "create"], ["audited_changes", "---\nsku: '001'\ntitle: Smartphone\nprice: !ruby/object:BigDecimal 18:0.45E3\nactive: true\ndescription: Smartphone ABC\n"], ["version", 1], ["request_uuid", "cb62d38d-c4f5-4a21-aee1-5f1ac4de00bc"], ["created_at", 2017-05-08 01:00:37 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/products/1" for 127.0.0.1 at 2017-05-07 22:00:37 -0300 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (3.7ms) Rendered products/show.html.erb within layouts/application (5.4ms) Completed 200 OK in 9ms (Views: 7.3ms | ActiveRecord: 0.3ms)  (0.2ms) rollback transaction  (0.3ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "user@domain.com"], ["created_at", 2017-05-08 01:01:26 UTC], ["updated_at", 2017-05-08 01:01:26 UTC]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["sku", "001"], ["title", "Smartphone"], ["price", #], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-08 01:01:26 UTC], ["updated_at", 2017-05-08 01:01:26 UTC]] Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" DESC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["LIMIT", 1]] SQL (0.2ms) INSERT INTO "audits" ("auditable_id", "auditable_type", "user_id", "user_type", "action", "audited_changes", "version", "request_uuid", "created_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["auditable_id", 1], ["auditable_type", "Product"], ["user_id", 1], ["user_type", "User"], ["action", "create"], ["audited_changes", "---\nsku: '001'\ntitle: Smartphone\nprice: !ruby/object:BigDecimal 18:0.45E3\nactive: true\ndescription: Smartphone ABC\n"], ["version", 1], ["request_uuid", "01057b85-25c5-4924-9f8d-2d9bf357cdee"], ["created_at", 2017-05-08 01:01:26 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/products/1" for 127.0.0.1 at 2017-05-07 22:01:26 -0300 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (11.1ms) Rendered products/show.html.erb within layouts/application (23.2ms) Completed 200 OK in 171ms (Views: 168.1ms | ActiveRecord: 0.4ms)  (0.2ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "user@domain.com"], ["created_at", 2017-05-08 01:01:26 UTC], ["updated_at", 2017-05-08 01:01:26 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["sku", "001"], ["title", "Smartphone"], ["price", #], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-08 01:01:26 UTC], ["updated_at", 2017-05-08 01:01:26 UTC]] Audited::Audit Load (0.1ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" DESC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["LIMIT", 1]] SQL (0.2ms) INSERT INTO "audits" ("auditable_id", "auditable_type", "user_id", "user_type", "action", "audited_changes", "version", "request_uuid", "created_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["auditable_id", 1], ["auditable_type", "Product"], ["user_id", 1], ["user_type", "User"], ["action", "create"], ["audited_changes", "---\nsku: '001'\ntitle: Smartphone\nprice: !ruby/object:BigDecimal 18:0.45E3\nactive: true\ndescription: Smartphone ABC\n"], ["version", 1], ["request_uuid", "fa0e5f41-e33b-4e77-a8ab-a8346b191c84"], ["created_at", 2017-05-08 01:01:26 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/products/1" for 127.0.0.1 at 2017-05-07 22:01:26 -0300 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (3.2ms) Rendered products/show.html.erb within layouts/application (4.8ms) Completed 200 OK in 8ms (Views: 6.5ms | ActiveRecord: 0.3ms) Started GET "/products/1?show_log=true" for 127.0.0.1 at 2017-05-07 22:01:26 -0300 Processing by ProductsController#show as HTML Parameters: {"show_log"=>"true", "id"=>"1"} Product Load (0.0ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Audited::Audit Load (0.1ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" ASC [["auditable_id", 1], ["auditable_type", "Product"]] CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_audit_record.html.erb (6.3ms) Rendered /home/douglas/code/audited_views/app/views/application/_audits.html.erb (8.3ms) Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (12.1ms) Rendered products/show.html.erb within layouts/application (13.5ms) Completed 200 OK in 17ms (Views: 15.1ms | ActiveRecord: 0.5ms)  (0.2ms) rollback transaction  (0.3ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "user@domain.com"], ["created_at", 2017-05-08 01:01:41 UTC], ["updated_at", 2017-05-08 01:01:41 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["sku", "001"], ["title", "Smartphone"], ["price", #], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-08 01:01:41 UTC], ["updated_at", 2017-05-08 01:01:41 UTC]] Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" DESC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["LIMIT", 1]] SQL (0.2ms) INSERT INTO "audits" ("auditable_id", "auditable_type", "user_id", "user_type", "action", "audited_changes", "version", "request_uuid", "created_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["auditable_id", 1], ["auditable_type", "Product"], ["user_id", 1], ["user_type", "User"], ["action", "create"], ["audited_changes", "---\nsku: '001'\ntitle: Smartphone\nprice: !ruby/object:BigDecimal 18:0.45E3\nactive: true\ndescription: Smartphone ABC\n"], ["version", 1], ["request_uuid", "5cad835d-521a-445f-bbdc-02b1734cb8ae"], ["created_at", 2017-05-08 01:01:41 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/products/1" for 127.0.0.1 at 2017-05-07 22:01:41 -0300 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (11.4ms) Rendered products/show.html.erb within layouts/application (23.7ms) Completed 200 OK in 172ms (Views: 168.9ms | ActiveRecord: 0.4ms) Started GET "/products/1?show_log=true" for 127.0.0.1 at 2017-05-07 22:01:41 -0300 Processing by ProductsController#show as HTML Parameters: {"show_log"=>"true", "id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Audited::Audit Load (0.1ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" ASC [["auditable_id", 1], ["auditable_type", "Product"]] CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Product Load (0.0ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_audit_record.html.erb (5.2ms) Rendered /home/douglas/code/audited_views/app/views/application/_audits.html.erb (7.2ms) Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (11.2ms) Rendered products/show.html.erb within layouts/application (12.7ms) Completed 200 OK in 16ms (Views: 14.2ms | ActiveRecord: 0.5ms)  (0.2ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "user@domain.com"], ["created_at", 2017-05-08 01:01:41 UTC], ["updated_at", 2017-05-08 01:01:41 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["sku", "001"], ["title", "Smartphone"], ["price", #], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-08 01:01:41 UTC], ["updated_at", 2017-05-08 01:01:41 UTC]] Audited::Audit Load (0.1ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" DESC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["LIMIT", 1]] SQL (0.2ms) INSERT INTO "audits" ("auditable_id", "auditable_type", "user_id", "user_type", "action", "audited_changes", "version", "request_uuid", "created_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["auditable_id", 1], ["auditable_type", "Product"], ["user_id", 1], ["user_type", "User"], ["action", "create"], ["audited_changes", "---\nsku: '001'\ntitle: Smartphone\nprice: !ruby/object:BigDecimal 18:0.45E3\nactive: true\ndescription: Smartphone ABC\n"], ["version", 1], ["request_uuid", "91b6358c-a006-4e5c-8f26-ff6de2c3359b"], ["created_at", 2017-05-08 01:01:41 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/products/1" for 127.0.0.1 at 2017-05-07 22:01:41 -0300 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.0ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (3.4ms) Rendered products/show.html.erb within layouts/application (5.0ms) Completed 200 OK in 8ms (Views: 6.7ms | ActiveRecord: 0.2ms)  (0.2ms) rollback transaction  (0.3ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "user@domain.com"], ["created_at", 2017-05-08 01:02:36 UTC], ["updated_at", 2017-05-08 01:02:36 UTC]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["sku", "001"], ["title", "Smartphone"], ["price", #], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-08 01:02:36 UTC], ["updated_at", 2017-05-08 01:02:36 UTC]] Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" DESC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["LIMIT", 1]] SQL (0.2ms) INSERT INTO "audits" ("auditable_id", "auditable_type", "user_id", "user_type", "action", "audited_changes", "version", "request_uuid", "created_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["auditable_id", 1], ["auditable_type", "Product"], ["user_id", 1], ["user_type", "User"], ["action", "create"], ["audited_changes", "---\nsku: '001'\ntitle: Smartphone\nprice: !ruby/object:BigDecimal 18:0.45E3\nactive: true\ndescription: Smartphone ABC\n"], ["version", 1], ["request_uuid", "c1b12a51-43f8-40e2-b9c8-e12737add1ab"], ["created_at", 2017-05-08 01:02:36 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/products/1" for 127.0.0.1 at 2017-05-07 22:02:36 -0300 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (11.5ms) Rendered products/show.html.erb within layouts/application (23.3ms) Completed 200 OK in 174ms (Views: 170.9ms | ActiveRecord: 0.4ms)  (0.2ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "user@domain.com"], ["created_at", 2017-05-08 01:02:36 UTC], ["updated_at", 2017-05-08 01:02:36 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["sku", "001"], ["title", "Smartphone"], ["price", #], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-08 01:02:36 UTC], ["updated_at", 2017-05-08 01:02:36 UTC]] Audited::Audit Load (0.1ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" DESC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["LIMIT", 1]] SQL (0.2ms) INSERT INTO "audits" ("auditable_id", "auditable_type", "user_id", "user_type", "action", "audited_changes", "version", "request_uuid", "created_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["auditable_id", 1], ["auditable_type", "Product"], ["user_id", 1], ["user_type", "User"], ["action", "create"], ["audited_changes", "---\nsku: '001'\ntitle: Smartphone\nprice: !ruby/object:BigDecimal 18:0.45E3\nactive: true\ndescription: Smartphone ABC\n"], ["version", 1], ["request_uuid", "ea17b97b-20a1-4fc0-a0dd-6af327f5eae2"], ["created_at", 2017-05-08 01:02:36 UTC]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/products/1" for 127.0.0.1 at 2017-05-07 22:02:36 -0300 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.0ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (3.7ms) Rendered products/show.html.erb within layouts/application (5.3ms) Completed 200 OK in 9ms (Views: 7.4ms | ActiveRecord: 0.3ms) Started GET "/products/1?show_log=true" for 127.0.0.1 at 2017-05-07 22:02:36 -0300 Processing by ProductsController#show as HTML Parameters: {"show_log"=>"true", "id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" ASC [["auditable_id", 1], ["auditable_type", "Product"]] CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_audit_record.html.erb (5.9ms) Rendered /home/douglas/code/audited_views/app/views/application/_audits.html.erb (8.7ms) Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (14.0ms) Rendered products/show.html.erb within layouts/application (15.6ms) Completed 200 OK in 19ms (Views: 17.1ms | ActiveRecord: 0.6ms)  (0.1ms) rollback transaction  (0.3ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "user@domain.com"], ["created_at", 2017-05-08 01:04:55 UTC], ["updated_at", 2017-05-08 01:04:55 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["sku", "001"], ["title", "Smartphone"], ["price", #], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-08 01:04:55 UTC], ["updated_at", 2017-05-08 01:04:55 UTC]] Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" DESC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["LIMIT", 1]] SQL (0.2ms) INSERT INTO "audits" ("auditable_id", "auditable_type", "user_id", "user_type", "action", "audited_changes", "version", "request_uuid", "created_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["auditable_id", 1], ["auditable_type", "Product"], ["user_id", 1], ["user_type", "User"], ["action", "create"], ["audited_changes", "---\nsku: '001'\ntitle: Smartphone\nprice: !ruby/object:BigDecimal 18:0.45E3\nactive: true\ndescription: Smartphone ABC\n"], ["version", 1], ["request_uuid", "655be2b6-1045-4cb5-b692-e054d03668f4"], ["created_at", 2017-05-08 01:04:55 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/products/1" for 127.0.0.1 at 2017-05-07 22:04:55 -0300 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (11.1ms) Rendered products/show.html.erb within layouts/application (24.3ms) Completed 200 OK in 168ms (Views: 164.7ms | ActiveRecord: 0.4ms) Started GET "/products/1?show_log=true" for 127.0.0.1 at 2017-05-07 22:04:55 -0300 Processing by ProductsController#show as HTML Parameters: {"show_log"=>"true", "id"=>"1"} Product Load (0.0ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Audited::Audit Load (0.1ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" ASC [["auditable_id", 1], ["auditable_type", "Product"]] CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Product Load (0.0ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_audit_record.html.erb (5.1ms) Rendered /home/douglas/code/audited_views/app/views/application/_audits.html.erb (7.1ms) Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (10.9ms) Rendered products/show.html.erb within layouts/application (12.3ms) Completed 200 OK in 15ms (Views: 13.8ms | ActiveRecord: 0.5ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "user@domain.com"], ["created_at", 2017-05-08 01:04:55 UTC], ["updated_at", 2017-05-08 01:04:55 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["sku", "001"], ["title", "Smartphone"], ["price", #], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-08 01:04:55 UTC], ["updated_at", 2017-05-08 01:04:55 UTC]] Audited::Audit Load (0.1ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" DESC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["LIMIT", 1]] SQL (0.2ms) INSERT INTO "audits" ("auditable_id", "auditable_type", "user_id", "user_type", "action", "audited_changes", "version", "request_uuid", "created_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?) [["auditable_id", 1], ["auditable_type", "Product"], ["user_id", 1], ["user_type", "User"], ["action", "create"], ["audited_changes", "---\nsku: '001'\ntitle: Smartphone\nprice: !ruby/object:BigDecimal 18:0.45E3\nactive: true\ndescription: Smartphone ABC\n"], ["version", 1], ["request_uuid", "125432ad-66a1-4633-b66b-bb3f994fac2d"], ["created_at", 2017-05-08 01:04:55 UTC]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/products/1" for 127.0.0.1 at 2017-05-07 22:04:55 -0300 Processing by ProductsController#show as HTML Parameters: {"id"=>"1"} Product Load (0.0ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendering products/show.html.erb within layouts/application Audited::Audit Load (0.2ms) SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? AND "audits"."action" = ? ORDER BY "audits"."version" ASC LIMIT ? [["auditable_id", 1], ["auditable_type", "Product"], ["action", "create"], ["LIMIT", 1]] User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 1], ["LIMIT", 1]] Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (3.2ms) Rendered products/show.html.erb within layouts/application (5.0ms) Completed 200 OK in 8ms (Views: 6.8ms | ActiveRecord: 0.2ms)  (0.1ms) rollback transaction