(3.4ms)  begin transaction
   (0.1ms)  rollback transaction
   (1.0ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.2ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.2ms)  begin transaction
   (0.2ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.1ms)  SELECT sqlite_version(*)
   (2.2ms)  CREATE TABLE "schema_migrations" ("version" varchar NOT NULL PRIMARY KEY)
   (2.9ms)  CREATE TABLE "ar_internal_metadata" ("key" varchar NOT NULL PRIMARY KEY, "value" varchar, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)
   (0.2ms)  SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC
Migrating to CreateProducts (20170507023018)
   (0.1ms)  begin transaction
   (0.6ms)  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)
  ActiveRecord::SchemaMigration Create (0.2ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20170507023018"]]
   (0.9ms)  commit transaction
Migrating to CreateUsers (20170507023113)
   (0.1ms)  begin transaction
   (0.4ms)  CREATE TABLE "users" ("id" integer PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "email" varchar, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)
  ActiveRecord::SchemaMigration Create (0.2ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20170507023113"]]
   (0.8ms)  commit transaction
Migrating to InstallAudited (20170507191539)
   (0.1ms)  begin transaction
   (0.2ms)  DROP TABLE IF EXISTS "audits"
   (0.7ms)  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.2ms)  CREATE  INDEX "auditable_index" ON "audits" ("auditable_id", "auditable_type")
   (0.3ms)  CREATE  INDEX "associated_index" ON "audits" ("associated_id", "associated_type")
   (0.2ms)  CREATE  INDEX "user_index" ON "audits" ("user_id", "user_type")
   (0.2ms)  CREATE  INDEX "index_audits_on_request_uuid" ON "audits" ("request_uuid")
   (0.3ms)  CREATE  INDEX "index_audits_on_created_at" ON "audits" ("created_at")
  ActiveRecord::SchemaMigration Create (1.0ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20170507191539"]]
   (1.0ms)  commit transaction
  ActiveRecord::InternalMetadata Load (0.2ms)  SELECT  "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ?  [["key", "environment"], ["LIMIT", 1]]
   (0.1ms)  begin transaction
  ActiveRecord::InternalMetadata Create (0.4ms)  INSERT INTO "ar_internal_metadata" ("key", "value", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["key", "environment"], ["value", "test"], ["created_at", "2018-06-21 02:14:15.101229"], ["updated_at", "2018-06-21 02:14:15.101229"]]
   (1.5ms)  commit transaction
   (0.4ms)  SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC
   (0.1ms)  begin transaction
   (0.1ms)  SAVEPOINT active_record_1
  User Create (0.9ms)  INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?)  [["email", "user@domain.com"], ["created_at", "2018-06-21 02:14:19.259093"], ["updated_at", "2018-06-21 02:14:19.259093"]]
   (0.3ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  Product Create (0.2ms)  INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?)  [["sku", "001"], ["title", "Smartphone"], ["price", 450.0], ["active", "t"], ["description", "Smartphone ABC"], ["created_at", "2018-06-21 02:14:19.285076"], ["updated_at", "2018-06-21 02:14:19.285076"]]
  Audited::Audit Load (0.4ms)  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]]
  Audited::Audit Create (1.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", "98490529-f9fe-450f-a7ba-6b35b694409f"], ["created_at", "2018-06-21 02:14:19.345277"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
Started GET "/products/1" for 127.0.0.1 at 2018-06-20 23:14:19 -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.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 /Users/douglas.lise/code/douglas/lab/audited_views/app/views/application/_controls.html.erb (14.7ms)
  Rendered products/show.html.erb within layouts/application (31.7ms)
Completed 200 OK in 294ms (Views: 284.4ms | ActiveRecord: 0.7ms)
Started GET "/products/1?show_log=true" for 127.0.0.1 at 2018-06-20 23:14:19 -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.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]]
  Audited::Audit Load (0.5ms)  SELECT "audits".* FROM "audits" WHERE "audits"."auditable_id" = ? AND "audits"."auditable_type" = ? ORDER BY "audits"."version" ASC  [["auditable_id", 1], ["auditable_type", "Product"]]
  CACHE User Load (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 /Users/douglas.lise/code/douglas/lab/audited_views/app/views/application/_audit_record.html.erb (12.9ms)
  Rendered /Users/douglas.lise/code/douglas/lab/audited_views/app/views/application/_audits.html.erb (28.5ms)
  Rendered /Users/douglas.lise/code/douglas/lab/audited_views/app/views/application/_controls.html.erb (42.5ms)
  Rendered products/show.html.erb within layouts/application (43.2ms)
Completed 200 OK in 46ms (Views: 43.9ms | ActiveRecord: 1.1ms)
   (1.5ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.1ms)  SAVEPOINT active_record_1
  User Create (0.6ms)  INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?)  [["email", "user@domain.com"], ["created_at", "2018-06-21 02:14:19.727105"], ["updated_at", "2018-06-21 02:14:19.727105"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  Product Create (0.3ms)  INSERT INTO "products" ("sku", "title", "price", "active", "description", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?)  [["sku", "001"], ["title", "Smartphone"], ["price", 450.0], ["active", "t"], ["description", "Smartphone ABC"], ["created_at", "2018-06-21 02:14:19.730526"], ["updated_at", "2018-06-21 02:14:19.730526"]]
  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]]
  Audited::Audit Create (0.7ms)  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", "3d830aea-6f89-4734-9921-93530d71afc7"], ["created_at", "2018-06-21 02:14:19.734682"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
Started GET "/products/1" for 127.0.0.1 at 2018-06-20 23:14:19 -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.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.1ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ? LIMIT ?  [["id", 1], ["LIMIT", 1]]
  Rendered /Users/douglas.lise/code/douglas/lab/audited_views/app/views/application/_controls.html.erb (7.3ms)
  Rendered products/show.html.erb within layouts/application (12.3ms)
Completed 200 OK in 16ms (Views: 13.5ms | ActiveRecord: 0.8ms)
   (1.4ms)  rollback transaction