(0.2ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (23.1ms)  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.1ms)  select sqlite_version(*)
   (8.1ms)  CREATE  INDEX "associated_index" ON "audits" ("associated_id", "associated_type")
   (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'

   (10.6ms)  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)              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'

   (10.7ms)  CREATE  INDEX "index_audits_on_created_at" ON "audits" ("created_at")
   (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='auditable_index' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='auditable_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'

   (10.3ms)  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.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='auditable_index' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='auditable_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'

   (11.7ms)  CREATE  INDEX "user_index" ON "audits" ("user_id", "user_type")
   (12.3ms)  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)
   (12.0ms)  CREATE TABLE "users" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "email" varchar, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)
   (10.5ms)  CREATE TABLE "schema_migrations" ("version" varchar NOT NULL PRIMARY KEY)
   (0.1ms)  SELECT version FROM "schema_migrations"
   (10.3ms)  INSERT INTO "schema_migrations" (version) VALUES (20170507191539)
   (10.2ms)  INSERT INTO "schema_migrations" (version) VALUES
(20170507023018),
(20170507023113);


   (12.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.2ms)  SELECT  "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ?  [["key", :environment], ["LIMIT", 1]]
   (0.1ms)  begin transaction
  SQL (0.2ms)  INSERT INTO "ar_internal_metadata" ("key", "value", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["key", "environment"], ["value", "test"], ["created_at", 2017-05-08 12:35:48 UTC], ["updated_at", 2017-05-08 12:35:48 UTC]]
   (11.2ms)  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.1ms)  begin transaction
   (0.0ms)  commit transaction
   (0.4ms)  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 12:35:50 UTC], ["updated_at", 2017-05-08 12:35:50 UTC]]
   (0.0ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.6ms)  INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?)  [["sku", "001"], ["title", "Smartphone"], ["price", #<BigDecimal:375b1f8,'0.45E3',9(18)>], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-08 12:35:50 UTC], ["updated_at", 2017-05-08 12:35:50 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", "d9a6a17e-5235-46e2-a037-474ddd818b86"], ["created_at", 2017-05-08 12:35:50 UTC]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
Started GET "/products/1" for 127.0.0.1 at 2017-05-08 09:35:50 -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 (21.6ms)
  Rendered products/show.html.erb within layouts/application (47.4ms)
Completed 200 OK in 233ms (Views: 214.4ms | ActiveRecord: 0.5ms)
   (0.2ms)  rollback transaction
   (0.1ms)  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 12:35:50 UTC], ["updated_at", 2017-05-08 12:35:50 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", #<BigDecimal:6bbcbb8,'0.45E3',9(18)>], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-08 12:35:50 UTC], ["updated_at", 2017-05-08 12:35:50 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", "b4626db5-205e-411d-b256-ff38c2608cec"], ["created_at", 2017-05-08 12:35:50 UTC]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
Started GET "/products/1" for 127.0.0.1 at 2017-05-08 09:35:50 -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.3ms)  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 (5.1ms)
  Rendered products/show.html.erb within layouts/application (7.4ms)
Completed 200 OK in 11ms (Views: 9.5ms | ActiveRecord: 0.4ms)
Started GET "/products/1?show_log=true" for 127.0.0.1 at 2017-05-08 09:35:50 -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 (10.0ms)
  Rendered /home/douglas/code/audited_views/app/views/application/_audits.html.erb (13.0ms)
  Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (20.0ms)
  Rendered products/show.html.erb within layouts/application (22.6ms)
Completed 200 OK in 27ms (Views: 24.5ms | ActiveRecord: 0.7ms)
   (0.2ms)  rollback transaction
  ActiveRecord::SchemaMigration Load (0.1ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  SELECT "ar_internal_metadata"."value" FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ?  [["key", :environment]]
  ActiveRecord::SchemaMigration Load (0.0ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.1ms)  SELECT "ar_internal_metadata"."value" FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ?  [["key", :environment]]
  ActiveRecord::SchemaMigration Load (0.1ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.1ms)  SELECT "ar_internal_metadata"."value" FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ?  [["key", :environment]]
   (14.5ms)  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.1ms)  select sqlite_version(*)
   (9.6ms)  CREATE  INDEX "associated_index" ON "audits" ("associated_id", "associated_type")
   (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'

   (11.6ms)  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)              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'

   (10.4ms)  CREATE  INDEX "index_audits_on_created_at" ON "audits" ("created_at")
   (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='auditable_index' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='auditable_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'

   (10.3ms)  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.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.2ms)              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.4ms)              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'

   (9.6ms)  CREATE  INDEX "user_index" ON "audits" ("user_id", "user_type")
   (10.5ms)  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)
   (10.5ms)  CREATE TABLE "users" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "email" varchar, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)
   (11.8ms)  CREATE TABLE "schema_migrations" ("version" varchar NOT NULL PRIMARY KEY)
   (0.1ms)  SELECT version FROM "schema_migrations"
   (10.4ms)  INSERT INTO "schema_migrations" (version) VALUES (20170507191539)
   (8.8ms)  INSERT INTO "schema_migrations" (version) VALUES
(20170507023018),
(20170507023113);


   (12.2ms)  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.2ms)  SELECT  "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ?  [["key", :environment], ["LIMIT", 1]]
   (0.1ms)  begin transaction
  SQL (0.2ms)  INSERT INTO "ar_internal_metadata" ("key", "value", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["key", "environment"], ["value", "test"], ["created_at", 2017-05-08 12:35:56 UTC], ["updated_at", 2017-05-08 12:35:56 UTC]]
   (9.1ms)  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.1ms)  begin transaction
   (0.0ms)  commit transaction
   (0.5ms)  begin transaction
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.5ms)  INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?)  [["email", "user@domain.com"], ["created_at", 2017-05-08 12:35:57 UTC], ["updated_at", 2017-05-08 12:35:57 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", #<BigDecimal:2d76750,'0.45E3',9(18)>], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-08 12:35:57 UTC], ["updated_at", 2017-05-08 12:35:57 UTC]]
  Audited::Audit Load (0.3ms)  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.3ms)  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", "41835f55-1b36-43a4-ad3e-575de5255d6f"], ["created_at", 2017-05-08 12:35:57 UTC]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
Started GET "/products/1" for 127.0.0.1 at 2017-05-08 09:35:57 -0300
Processing by ProductsController#show as HTML
  Parameters: {"id"=>"1"}
  Product Load (0.2ms)  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 (18.4ms)
  Rendered products/show.html.erb within layouts/application (36.1ms)
Completed 200 OK in 197ms (Views: 190.6ms | ActiveRecord: 0.6ms)
Started GET "/products/1?show_log=true" for 127.0.0.1 at 2017-05-08 09:35:58 -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 (6.3ms)
  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 (13.8ms)
  Rendered products/show.html.erb within layouts/application (15.5ms)
Completed 200 OK in 19ms (Views: 17.2ms | ActiveRecord: 0.6ms)
   (0.2ms)  rollback transaction
   (0.1ms)  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 12:35:58 UTC], ["updated_at", 2017-05-08 12:35:58 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", #<BigDecimal:61256c8,'0.45E3',9(18)>], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-08 12:35:58 UTC], ["updated_at", 2017-05-08 12:35:58 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", "73f94d51-41f9-43c8-a48c-1f9fdf8a7bb5"], ["created_at", 2017-05-08 12:35:58 UTC]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
Started GET "/products/1" for 127.0.0.1 at 2017-05-08 09:35:58 -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.3ms)  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 (4.9ms)
  Rendered products/show.html.erb within layouts/application (7.2ms)
Completed 200 OK in 11ms (Views: 9.4ms | ActiveRecord: 0.4ms)
   (0.3ms)  rollback transaction
   (0.4ms)  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 12:36:01 UTC], ["updated_at", 2017-05-08 12:36:01 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", #<BigDecimal:382d8d8,'0.45E3',9(18)>], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-08 12:36:01 UTC], ["updated_at", 2017-05-08 12:36:01 UTC]]
  Audited::Audit Load (0.3ms)  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.3ms)  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", "eee48361-37f1-4160-b26e-1f375731ed9d"], ["created_at", 2017-05-08 12:36:01 UTC]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
Started GET "/products/1" for 127.0.0.1 at 2017-05-08 09:36:01 -0300
Processing by ProductsController#show as HTML
  Parameters: {"id"=>"1"}
  Product Load (0.2ms)  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.6ms)
  Rendered products/show.html.erb within layouts/application (26.6ms)
Completed 200 OK in 211ms (Views: 203.8ms | ActiveRecord: 0.5ms)
   (0.2ms)  rollback transaction
   (0.1ms)  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 12:36:01 UTC], ["updated_at", 2017-05-08 12:36:01 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", #<BigDecimal:5ec7250,'0.45E3',9(18)>], ["active", true], ["description", "Smartphone ABC"], ["created_at", 2017-05-08 12:36:01 UTC], ["updated_at", 2017-05-08 12:36:01 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", "f6da4da1-a6c6-46e8-a08b-c45a3f626f6f"], ["created_at", 2017-05-08 12:36:01 UTC]]
   (0.0ms)  RELEASE SAVEPOINT active_record_1
Started GET "/products/1" for 127.0.0.1 at 2017-05-08 09:36:01 -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 (4.4ms)
  Rendered products/show.html.erb within layouts/application (6.6ms)
Completed 200 OK in 10ms (Views: 8.7ms | ActiveRecord: 0.3ms)
Started GET "/products/1?show_log=true" for 127.0.0.1 at 2017-05-08 09:36:01 -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.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]]
  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 (6.7ms)
  Rendered /home/douglas/code/audited_views/app/views/application/_audits.html.erb (9.5ms)
  Rendered /home/douglas/code/audited_views/app/views/application/_controls.html.erb (15.7ms)
  Rendered products/show.html.erb within layouts/application (17.2ms)
Completed 200 OK in 20ms (Views: 18.5ms | ActiveRecord: 0.8ms)
   (0.2ms)  rollback transaction