ActiveRecord::SchemaMigration Load (27.5ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN ------------------------------- RecordsTest: test_nilify_blanks -------------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (24.5ms) INSERT INTO "shops" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-11-21 19:23:01.287577"], ["updated_at", "2016-11-21 19:23:01.287577"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Shop Load (0.3ms) SELECT "shops".* FROM "shops" WHERE "shops"."id" = $1 LIMIT 1 [["id", 13]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordsTest: test_validate --------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- ValidationsTest: test_methods -----------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------------- ValidationsTest: test_complexity --------------------------------  (0.3ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ValidationsTest: test_count ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- ValidationsTest: test_time --------------------------  (0.3ms) ROLLBACK  (0.1ms) BEGIN --------------------------- HelpersTest: test_extending --------------------------- Rendered layouts/application.html.erb (0.3ms) Rendered layouts/market.html.erb (7.7ms)  (0.3ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ HelpersTest: test_active_trail ------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------------- HelpersTest: test_content_tag_if --------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ControllersTest: test_callbacks -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ControllersTest: test_render ----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- MailersTest: test_normalization ------------------------------- Rendered user_mailer/invite.text.erb (0.2ms) UserMailer#invite: processed outbound mail in 9.0ms  (0.3ms) ROLLBACK ActiveRecord::SchemaMigration Load (6.7ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------------- ValidationsTest: test_complexity --------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- ValidationsTest: test_methods -----------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ValidationsTest: test_count ---------------------------  (0.3ms) ROLLBACK  (0.1ms) BEGIN -------------------------- ValidationsTest: test_time --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- RecordsTest: test_nilify_blanks -------------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.5ms) INSERT INTO "shops" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-11-21 21:26:58.983176"], ["updated_at", "2016-11-21 21:26:58.983176"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Shop Load (0.3ms) SELECT "shops".* FROM "shops" WHERE "shops"."id" = $1 LIMIT 1 [["id", 14]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordsTest: test_validate --------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- MailersTest: test_normalization ------------------------------- Rendered user_mailer/invite.text.erb (0.9ms) UserMailer#invite: processed outbound mail in 14.3ms  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ControllersTest: test_render ----------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ControllersTest: test_callbacks -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------------- HelpersTest: test_content_tag_if --------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- HelpersTest: test_extending --------------------------- Rendered layouts/application.html.erb (0.3ms) Rendered layouts/market.html.erb (8.8ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ HelpersTest: test_active_trail ------------------------------  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN -------------------------- RecordsTest: test_validate --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- RecordsTest: test_nilify_blanks -------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "shops" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-11-21 21:27:09.550135"], ["updated_at", "2016-11-21 21:27:09.550135"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 Shop Load (0.3ms) SELECT "shops".* FROM "shops" WHERE "shops"."id" = $1 LIMIT 1 [["id", 15]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- ValidationsTest: test_time --------------------------  (0.3ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- ValidationsTest: test_methods -----------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------------- ValidationsTest: test_complexity --------------------------------  (0.3ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ValidationsTest: test_count ---------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN -------------------------------- HelpersTest: test_content_tag_if --------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- HelpersTest: test_extending --------------------------- Rendered layouts/application.html.erb (0.2ms) Rendered layouts/market.html.erb (6.1ms)  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------------ HelpersTest: test_active_trail ------------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------------- ControllersTest: test_callbacks -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ControllersTest: test_render ----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- MailersTest: test_normalization ------------------------------- Rendered user_mailer/invite.text.erb (0.3ms) UserMailer#invite: processed outbound mail in 9.3ms  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ------------------------------- MailersTest: test_normalization ------------------------------- Rendered user_mailer/invite.text.erb (0.9ms) UserMailer#invite: processed outbound mail in 17.3ms  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ControllersTest: test_render ----------------------------  (0.1ms) ROLLBACK  (0.2ms) BEGIN ------------------------------- ControllersTest: test_callbacks -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ValidationsTest: test_count ---------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- ValidationsTest: test_time --------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------------- ValidationsTest: test_complexity --------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- ValidationsTest: test_methods -----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- RecordsTest: test_nilify_blanks -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordsTest: test_validate --------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------------- HelpersTest: test_content_tag_if --------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- HelpersTest: test_extending --------------------------- Rendered layouts/application.html.erb (0.2ms) Rendered layouts/market.html.erb (6.6ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ HelpersTest: test_active_trail ------------------------------  (0.1ms) ROLLBACK  (2.4ms) CREATE TABLE "schema_migrations" ("version" character varying NOT NULL)   (0.8ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version") ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations" Migrating to CreateUsers (20161020200126)  (0.2ms) BEGIN  (2.4ms) CREATE TABLE "users" ("id" serial primary key, "name" character varying, "created_at" timestamp NOT NULL, "updated_at" timestamp NOT NULL)  SQL (0.3ms) INSERT INTO "schema_migrations" ("version") VALUES ($1) [["version", "20161020200126"]]  (0.4ms) COMMIT ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.8ms) SELECT t2.oid::regclass::text AS to_table, a1.attname AS column, a2.attname AS primary_key, c.conname AS name, c.confupdtype AS on_update, c.confdeltype AS on_delete FROM pg_constraint c JOIN pg_class t1 ON c.conrelid = t1.oid JOIN pg_class t2 ON c.confrelid = t2.oid JOIN pg_attribute a1 ON a1.attnum = c.conkey[1] AND a1.attrelid = t1.oid JOIN pg_attribute a2 ON a2.attnum = c.confkey[1] AND a2.attrelid = t2.oid JOIN pg_namespace t3 ON c.connamespace = t3.oid WHERE c.contype = 'f' AND t1.relname = 'users' AND t3.nspname = ANY (current_schemas(false)) ORDER BY c.conname  ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN ------------------------------ HelpersTest: test_active_trail ------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------------- HelpersTest: test_content_tag_if --------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- HelpersTest: test_extending --------------------------- Rendered layouts/application.html.erb (0.3ms) Rendered layouts/market.html.erb (8.5ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- ValidationsTest: test_methods -----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- ValidationsTest: test_time --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ValidationsTest: test_count ---------------------------  (0.3ms) ROLLBACK  (0.2ms) BEGIN -------------------------------- ValidationsTest: test_complexity --------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- MailersTest: test_normalization ------------------------------- Rendered user_mailer/invite.text.erb (0.2ms) UserMailer#invite: processed outbound mail in 9.1ms  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordsTest: test_validate --------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- RecordsTest: test_nilify_blanks -------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (6.4ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-11-21 21:59:16.857031"], ["updated_at", "2016-11-21 21:59:16.857031"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 User Load (0.4ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 1]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ControllersTest: test_callbacks -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ControllersTest: test_render ----------------------------  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.6ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ------------------------------- RecordsTest: test_nilify_blanks -------------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.5ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-11-22 13:34:07.765333"], ["updated_at", "2016-11-22 13:34:07.765333"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 2]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- RecordsTest: test_validate --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- MailersTest: test_normalization ------------------------------- Rendered user_mailer/invite.text.erb (1.0ms) UserMailer#invite: processed outbound mail in 19.2ms  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ControllersTest: test_callbacks -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ControllersTest: test_render ----------------------------  (0.1ms) ROLLBACK  (0.2ms) BEGIN -------------------------------- HelpersTest: test_content_tag_if --------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ HelpersTest: test_active_trail ------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- HelpersTest: test_extending --------------------------- Rendered layouts/application.html.erb (0.4ms) Rendered layouts/market.html.erb (7.5ms)  (0.2ms) ROLLBACK  (0.2ms) BEGIN -------------------------------- ValidationsTest: test_complexity --------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- ValidationsTest: test_methods -----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ValidationsTest: test_count ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- ValidationsTest: test_time --------------------------  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (27.9ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.3ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.2ms) Rendered layouts/market.html.erb (7.3ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.3ms) UserMailer#invite: processed outbound mail in 10.7ms  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (25.2ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-08 04:42:27.671953"], ["updated_at", "2016-12-08 04:42:27.671953"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 3]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-08 04:42:36.802936"], ["updated_at", "2016-12-08 04:42:36.802936"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 4]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.1ms) ROLLBACK  (0.2ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.3ms) ROLLBACK  (0.1ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.9ms) UserMailer#invite: processed outbound mail in 12.5ms  (0.2ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.4ms) Rendered layouts/market.html.erb (6.9ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-08 04:44:39.082973"], ["updated_at", "2016-12-08 04:44:39.082973"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 5]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (1.2ms) UserMailer#invite: processed outbound mail in 18.9ms  (0.2ms) ROLLBACK  (0.2ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.3ms) Rendered layouts/market.html.erb (7.2ms)  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (6.7ms) SELECT "schema_migrations".* FROM "schema_migrations"  (6.4ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (6.6ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (18.1ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 14:56:38.303238"], ["updated_at", "2016-12-09 14:56:38.303238"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (6.6ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 6]]  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.3ms) Rendered layouts/market.html.erb (7.7ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.4ms) UserMailer#invite: processed outbound mail in 12.0ms  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (1.0ms) UserMailer#invite: processed outbound mail in 13.3ms  (0.2ms) ROLLBACK  (0.2ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.2ms) Rendered layouts/market.html.erb (6.2ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------- ViewTest: test_handler ---------------------- Started GET "/index.json" for 127.0.0.1 at 2016-12-09 11:58:12 -0300  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 14:58:12.846054"], ["updated_at", "2016-12-09 14:58:12.846054"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 7]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 14:59:16.750761"], ["updated_at", "2016-12-09 14:59:16.750761"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 8]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK  (0.2ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.3ms) ROLLBACK  (0.3ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.4ms) ROLLBACK  (0.2ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (1.0ms) UserMailer#invite: processed outbound mail in 14.3ms  (0.2ms) ROLLBACK  (0.3ms) BEGIN ---------------------- ViewTest: test_handler ---------------------- Started GET "/products.json" for 127.0.0.1 at 2016-12-09 11:59:16 -0300 Processing by ProductsController#index as JSON Completed 500 Internal Server Error in 1ms (ActiveRecord: 0.0ms)  (0.3ms) ROLLBACK  (0.3ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.2ms) Rendered layouts/market.html.erb (6.6ms)  (0.3ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.2ms) Rendered layouts/market.html.erb (6.6ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------- ViewTest: test_handler ---------------------- Started GET "/users.json" for 127.0.0.1 at 2016-12-09 12:01:28 -0300 Processing by UsersController#index as JSON Completed 500 Internal Server Error in 5ms (ActiveRecord: 0.0ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.2ms) UserMailer#invite: processed outbound mail in 13.3ms  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 15:01:28.544663"], ["updated_at", "2016-12-09 15:01:28.544663"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 9]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (1.0ms) UserMailer#invite: processed outbound mail in 18.5ms  (0.3ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.1ms) ROLLBACK  (0.2ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.1ms) ROLLBACK  (0.2ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.1ms) ROLLBACK  (0.2ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.3ms) Rendered layouts/market.html.erb (6.7ms)  (0.3ms) ROLLBACK  (0.1ms) BEGIN ---------------------- ViewTest: test_handler ---------------------- Started GET "/users.json" for 127.0.0.1 at 2016-12-09 12:02:32 -0300 Processing by UsersController#index as JSON User Load (0.4ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (1.0ms) Completed 200 OK in 5ms (Views: 4.7ms | ActiveRecord: 0.4ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 15:02:32.840606"], ["updated_at", "2016-12-09 15:02:32.840606"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 10]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ---------------------- ViewTest: test_handler ---------------------- Started GET "/users.json" for 127.0.0.1 at 2016-12-09 12:03:15 -0300 Processing by UsersController#index as JSON User Load (0.4ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (1.8ms) Completed 200 OK in 10ms (Views: 8.7ms | ActiveRecord: 0.9ms)  (0.3ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 15:03:15.870982"], ["updated_at", "2016-12-09 15:03:15.870982"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 11]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (1.1ms) UserMailer#invite: processed outbound mail in 14.5ms  (0.2ms) ROLLBACK  (0.2ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.1ms) ROLLBACK  (0.2ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.4ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.1ms) ROLLBACK  (0.2ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.4ms) Rendered layouts/market.html.erb (7.3ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.6ms) UserMailer#invite: processed outbound mail in 14.0ms  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------- ViewTest: test_handler ---------------------- Started GET "/users.json" for 127.0.0.1 at 2016-12-09 12:03:30 -0300 Processing by UsersController#index as JSON User Load (0.4ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (1.8ms) Completed 200 OK in 6ms (Views: 4.5ms | ActiveRecord: 0.9ms)  (0.3ms) ROLLBACK  (0.2ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.3ms) ROLLBACK  (0.2ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.1ms) ROLLBACK  (0.2ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.2ms) Rendered layouts/market.html.erb (6.1ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 15:03:30.076472"], ["updated_at", "2016-12-09 15:03:30.076472"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 12]]  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 15:08:09.840493"], ["updated_at", "2016-12-09 15:08:09.840493"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 13]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.3ms) Rendered layouts/market.html.erb (6.5ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.3ms) UserMailer#invite: processed outbound mail in 9.4ms  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-09 15:08:10.053040"], ["updated_at", "2016-12-09 15:08:10.053040"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-09 15:08:10.054744"], ["updated_at", "2016-12-09 15:08:10.054744"]]  (0.3ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-09 12:08:10 -0300 Processing by UsersController#index as JSON User Load (0.3ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (1.0ms) Completed 200 OK in 5ms (Views: 4.6ms | ActiveRecord: 0.3ms)  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 15:09:29.264617"], ["updated_at", "2016-12-09 15:09:29.264617"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 16]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-09 15:09:29.273237"], ["updated_at", "2016-12-09 15:09:29.273237"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-09 15:09:29.274615"], ["updated_at", "2016-12-09 15:09:29.274615"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-09 12:09:29 -0300 Processing by UsersController#index as JSON User Load (0.5ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (1.4ms) Completed 200 OK in 7ms (Views: 6.4ms | ActiveRecord: 0.5ms)  (0.3ms) ROLLBACK  (0.2ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.3ms) Rendered layouts/market.html.erb (6.8ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.2ms) UserMailer#invite: processed outbound mail in 9.5ms  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.2ms) Rendered layouts/market.html.erb (6.5ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.2ms) UserMailer#invite: processed outbound mail in 10.8ms  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 15:09:59.258191"], ["updated_at", "2016-12-09 15:09:59.258191"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 19]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.2ms) ROLLBACK  (0.3ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-09 15:09:59.266810"], ["updated_at", "2016-12-09 15:09:59.266810"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-09 15:09:59.268227"], ["updated_at", "2016-12-09 15:09:59.268227"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-09 12:09:59 -0300 Processing by UsersController#index as JSON User Load (0.3ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (0.9ms) Completed 200 OK in 4ms (Views: 3.8ms | ActiveRecord: 0.3ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.3ms) ROLLBACK  (0.2ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.3ms) Rendered layouts/market.html.erb (6.8ms)  (0.3ms) ROLLBACK  (0.2ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-09 15:10:23.921192"], ["updated_at", "2016-12-09 15:10:23.921192"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-09 15:10:23.924830"], ["updated_at", "2016-12-09 15:10:23.924830"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-09 12:10:23 -0300 Processing by UsersController#index as JSON User Load (0.4ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (1.1ms) Completed 200 OK in 5ms (Views: 4.7ms | ActiveRecord: 0.4ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.3ms) UserMailer#invite: processed outbound mail in 11.1ms  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 15:10:24.079897"], ["updated_at", "2016-12-09 15:10:24.079897"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 24]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.6ms) UserMailer#invite: processed outbound mail in 14.1ms  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.3ms) Rendered layouts/market.html.erb (6.3ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 15:12:38.142138"], ["updated_at", "2016-12-09 15:12:38.142138"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 25]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-09 15:12:38.150575"], ["updated_at", "2016-12-09 15:12:38.150575"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-09 15:12:38.152048"], ["updated_at", "2016-12-09 15:12:38.152048"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-09 12:12:38 -0300 Processing by UsersController#index as JSON User Load (0.3ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (0.9ms) Completed 200 OK in 4ms (Views: 3.8ms | ActiveRecord: 0.3ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (1.0ms) UserMailer#invite: processed outbound mail in 18.7ms  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 15:24:37.119642"], ["updated_at", "2016-12-09 15:24:37.119642"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 28]]  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.1ms) ROLLBACK  (0.2ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-09 15:24:37.132299"], ["updated_at", "2016-12-09 15:24:37.132299"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-09 15:24:37.133767"], ["updated_at", "2016-12-09 15:24:37.133767"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-09 12:24:37 -0300 Processing by UsersController#index as JSON User Load (0.7ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (176930.3ms) Completed 200 OK in 176936ms (Views: 176934.4ms | ActiveRecord: 0.7ms)  (0.6ms) ROLLBACK  (0.3ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.3ms) ROLLBACK  (0.2ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.3ms) ROLLBACK  (0.2ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.3ms) ROLLBACK  (0.2ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.7ms) Rendered layouts/market.html.erb (9.0ms)  (0.5ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 15:27:44.024329"], ["updated_at", "2016-12-09 15:27:44.024329"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 31]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.2ms) Rendered layouts/market.html.erb (6.8ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-09 15:27:44.053653"], ["updated_at", "2016-12-09 15:27:44.053653"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-09 15:27:44.055215"], ["updated_at", "2016-12-09 15:27:44.055215"]]  (0.3ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-09 12:27:44 -0300 Processing by UsersController#index as JSON User Load (0.4ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (2643.9ms) Completed 200 OK in 2648ms (Views: 2647.2ms | ActiveRecord: 0.4ms)  (0.4ms) ROLLBACK  (0.3ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.3ms) ROLLBACK  (0.2ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.3ms) ROLLBACK  (0.2ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.3ms) ROLLBACK  (0.2ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.7ms) UserMailer#invite: processed outbound mail in 13.5ms  (0.3ms) ROLLBACK  (0.2ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.5ms) ROLLBACK  (0.2ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.7ms) UserMailer#invite: processed outbound mail in 13.8ms  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.2ms) Rendered layouts/market.html.erb (6.2ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 15:28:00.805476"], ["updated_at", "2016-12-09 15:28:00.805476"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 34]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-09 15:28:00.814704"], ["updated_at", "2016-12-09 15:28:00.814704"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-09 15:28:00.816007"], ["updated_at", "2016-12-09 15:28:00.816007"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-09 12:28:00 -0300 Processing by UsersController#index as JSON ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.2ms) Rendered layouts/market.html.erb (6.2ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-09 15:32:08.131791"], ["updated_at", "2016-12-09 15:32:08.131791"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-09 15:32:08.134916"], ["updated_at", "2016-12-09 15:32:08.134916"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-09 12:32:08 -0300 Processing by UsersController#index as JSON User Load (0.3ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (18.0ms) Completed 500 Internal Server Error in 22ms (ActiveRecord: 0.3ms)  (0.3ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.2ms) UserMailer#invite: processed outbound mail in 11.6ms  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 15:32:08.309419"], ["updated_at", "2016-12-09 15:32:08.309419"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 39]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-09 15:32:23.692010"], ["updated_at", "2016-12-09 15:32:23.692010"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-09 15:32:23.695112"], ["updated_at", "2016-12-09 15:32:23.695112"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-09 12:32:23 -0300 Processing by UsersController#index as JSON User Load (0.3ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (1.3ms) Completed 200 OK in 7ms (Views: 6.7ms | ActiveRecord: 0.3ms)  (0.3ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.1ms) ROLLBACK  (0.2ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.2ms) Rendered layouts/market.html.erb (6.5ms)  (0.2ms) ROLLBACK  (0.2ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.1ms) ROLLBACK  (0.2ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 15:32:23.779246"], ["updated_at", "2016-12-09 15:32:23.779246"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 42]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.1ms) ROLLBACK  (0.2ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.2ms) UserMailer#invite: processed outbound mail in 10.9ms  (0.3ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-09 15:33:04.089685"], ["updated_at", "2016-12-09 15:33:04.089685"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-09 15:33:04.092834"], ["updated_at", "2016-12-09 15:33:04.092834"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-09 12:33:04 -0300 Processing by UsersController#index as JSON User Load (0.4ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (1.2ms) Completed 200 OK in 7ms (Views: 6.4ms | ActiveRecord: 0.4ms)  (0.3ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.1ms) ROLLBACK  (1.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.7ms) UserMailer#invite: processed outbound mail in 12.1ms  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.3ms) Rendered layouts/market.html.erb (6.2ms)  (0.2ms) ROLLBACK  (0.2ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 15:33:04.320759"], ["updated_at", "2016-12-09 15:33:04.320759"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 45]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.7ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-09 15:33:12.737310"], ["updated_at", "2016-12-09 15:33:12.737310"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-09 15:33:12.742727"], ["updated_at", "2016-12-09 15:33:12.742727"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-09 12:33:12 -0300 Processing by UsersController#index as JSON User Load (0.4ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (1.3ms) Completed 200 OK in 8ms (Views: 7.0ms | ActiveRecord: 0.4ms)  (0.3ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.1ms) ROLLBACK  (0.2ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.1ms) ROLLBACK  (0.2ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.3ms) Rendered layouts/market.html.erb (6.5ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.2ms) UserMailer#invite: processed outbound mail in 9.4ms  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 15:33:12.967177"], ["updated_at", "2016-12-09 15:33:12.967177"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 48]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.3ms) Rendered layouts/market.html.erb (6.4ms)  (0.3ms) ROLLBACK  (0.2ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 15:33:22.472821"], ["updated_at", "2016-12-09 15:33:22.472821"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 49]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-09 15:33:22.512309"], ["updated_at", "2016-12-09 15:33:22.512309"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-09 15:33:22.513748"], ["updated_at", "2016-12-09 15:33:22.513748"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-09 12:33:22 -0300 Processing by UsersController#index as JSON User Load (0.3ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (1.0ms) Completed 200 OK in 4ms (Views: 3.9ms | ActiveRecord: 0.3ms)  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.1ms) ROLLBACK  (0.2ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.2ms) UserMailer#invite: processed outbound mail in 11.1ms  (0.3ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.3ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.2ms) Rendered layouts/market.html.erb (6.4ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.2ms) UserMailer#invite: processed outbound mail in 9.3ms  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-09 15:36:00.489387"], ["updated_at", "2016-12-09 15:36:00.489387"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-09 15:36:00.492779"], ["updated_at", "2016-12-09 15:36:00.492779"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-09 12:36:00 -0300 Processing by UsersController#index as JSON Rendered users/index.json.ruby (357215.4ms) Completed 500 Internal Server Error in 357223ms (ActiveRecord: 0.0ms) ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.3ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.1ms) ROLLBACK  (0.2ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-09 15:42:02.776337"], ["updated_at", "2016-12-09 15:42:02.776337"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-09 15:42:02.780221"], ["updated_at", "2016-12-09 15:42:02.780221"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-09 12:42:02 -0300 Processing by UsersController#index as JSON Rendered users/index.json.ruby (1.5ms) Completed 500 Internal Server Error in 10ms (ActiveRecord: 0.0ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.2ms) Rendered layouts/market.html.erb (6.6ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.2ms) UserMailer#invite: processed outbound mail in 9.5ms  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 15:42:02.970902"], ["updated_at", "2016-12-09 15:42:02.970902"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 56]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 15:42:43.288320"], ["updated_at", "2016-12-09 15:42:43.288320"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 57]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.1ms) ROLLBACK  (0.2ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.4ms) Rendered layouts/market.html.erb (6.9ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.2ms) UserMailer#invite: processed outbound mail in 11.2ms  (0.2ms) ROLLBACK  (0.2ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-09 15:42:43.457416"], ["updated_at", "2016-12-09 15:42:43.457416"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-09 15:42:43.458944"], ["updated_at", "2016-12-09 15:42:43.458944"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-09 12:42:43 -0300 Processing by UsersController#index as JSON User Load (0.3ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (1.0ms) Completed 200 OK in 5ms (Views: 4.2ms | ActiveRecord: 0.3ms)  (0.3ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.2ms) Rendered layouts/market.html.erb (6.5ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.3ms) ROLLBACK  (0.2ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.2ms) UserMailer#invite: processed outbound mail in 9.2ms  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 15:44:49.442105"], ["updated_at", "2016-12-09 15:44:49.442105"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 60]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-09 15:44:49.453219"], ["updated_at", "2016-12-09 15:44:49.453219"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-09 15:44:49.454550"], ["updated_at", "2016-12-09 15:44:49.454550"]]  (0.3ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-09 12:44:49 -0300 Processing by UsersController#index as JSON User Load (0.4ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (1.1ms) Completed 200 OK in 5ms (Views: 4.1ms | ActiveRecord: 0.4ms)  (0.3ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.7ms) UserMailer#invite: processed outbound mail in 14.0ms  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-09 15:50:20.881313"], ["updated_at", "2016-12-09 15:50:20.881313"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-09 15:50:20.884398"], ["updated_at", "2016-12-09 15:50:20.884398"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-09 12:50:20 -0300 Processing by UsersController#index as JSON User Load (0.4ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (1.2ms) Completed 200 OK in 5ms (Views: 4.7ms | ActiveRecord: 0.4ms) Started GET "/users.xml" for 127.0.0.1 at 2016-12-09 12:50:20 -0300 Processing by UsersController#index as XML User Load (0.2ms) SELECT "users".* FROM "users" Rendered users/index.xml.ruby (10.4ms) Completed 200 OK in 13ms (Views: 12.5ms | ActiveRecord: 0.2ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.2ms) Rendered layouts/market.html.erb (6.2ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 15:50:20.936888"], ["updated_at", "2016-12-09 15:50:20.936888"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 65]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 15:50:32.039448"], ["updated_at", "2016-12-09 15:50:32.039448"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 66]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-09 15:50:32.047720"], ["updated_at", "2016-12-09 15:50:32.047720"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-09 15:50:32.049013"], ["updated_at", "2016-12-09 15:50:32.049013"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-09 12:50:32 -0300 Processing by UsersController#index as JSON User Load (0.3ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (1.2ms) Completed 200 OK in 7ms (Views: 6.7ms | ActiveRecord: 0.3ms) Started GET "/users.xml" for 127.0.0.1 at 2016-12-09 12:50:32 -0300 Processing by UsersController#index as XML User Load (0.3ms) SELECT "users".* FROM "users" Rendered users/index.xml.ruby (6.9ms) Completed 200 OK in 10ms (Views: 9.2ms | ActiveRecord: 0.3ms)  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.7ms) UserMailer#invite: processed outbound mail in 11.0ms  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.3ms) Rendered layouts/market.html.erb (6.4ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (1.0ms) UserMailer#invite: processed outbound mail in 18.5ms  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.2ms) Rendered layouts/market.html.erb (6.4ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.2ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 15:51:19.747046"], ["updated_at", "2016-12-09 15:51:19.747046"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 69]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-09 15:51:19.779808"], ["updated_at", "2016-12-09 15:51:19.779808"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-09 15:51:19.781260"], ["updated_at", "2016-12-09 15:51:19.781260"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-09 12:51:19 -0300 Processing by UsersController#index as JSON User Load (0.3ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (1.0ms) Completed 200 OK in 6ms (Views: 5.0ms | ActiveRecord: 0.3ms) Started GET "/users.xml" for 127.0.0.1 at 2016-12-09 12:51:19 -0300 Processing by UsersController#index as XML User Load (0.3ms) SELECT "users".* FROM "users" Rendered users/index.xml.ruby (9.1ms) Completed 200 OK in 12ms (Views: 11.7ms | ActiveRecord: 0.3ms)  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.3ms) Rendered layouts/market.html.erb (7.2ms)  (0.3ms) ROLLBACK  (0.2ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.5ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-09 15:51:29.998583"], ["updated_at", "2016-12-09 15:51:29.998583"]]  (0.2ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-09 15:51:30.006507"], ["updated_at", "2016-12-09 15:51:30.006507"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-09 12:51:30 -0300 Processing by UsersController#index as JSON User Load (0.3ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (1.3ms) Completed 200 OK in 5ms (Views: 4.7ms | ActiveRecord: 0.3ms) Started GET "/users.xml" for 127.0.0.1 at 2016-12-09 12:51:30 -0300 Processing by UsersController#index as XML User Load (0.2ms) SELECT "users".* FROM "users" Rendered users/index.xml.ruby (5.1ms) Completed 200 OK in 8ms (Views: 7.2ms | ActiveRecord: 0.2ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.3ms) UserMailer#invite: processed outbound mail in 9.0ms  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-09 15:51:30.159942"], ["updated_at", "2016-12-09 15:51:30.159942"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 74]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (2.4ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.1ms) SAVEPOINT active_record_1 SQL (17.9ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-13 18:05:30.002952"], ["updated_at", "2016-12-13 18:05:30.002952"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-13 18:05:30.024212"], ["updated_at", "2016-12-13 18:05:30.024212"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-13 15:05:30 -0300 Processing by UsersController#index as JSON User Load (0.3ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (1.1ms) Completed 200 OK in 7ms (Views: 6.6ms | ActiveRecord: 0.3ms) Started GET "/users.xml" for 127.0.0.1 at 2016-12-13 15:05:30 -0300 Processing by UsersController#index as XML User Load (0.3ms) SELECT "users".* FROM "users" Rendered users/index.xml.ruby (10.5ms) Completed 200 OK in 13ms (Views: 12.9ms | ActiveRecord: 0.3ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-13 18:05:30.061752"], ["updated_at", "2016-12-13 18:05:30.061752"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 77]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.7ms) UserMailer#invite: processed outbound mail in 10.2ms  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.2ms) Rendered layouts/market.html.erb (6.7ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-13 18:08:23.127506"], ["updated_at", "2016-12-13 18:08:23.127506"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 78]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.6ms) UserMailer#invite: processed outbound mail in 11.0ms  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-13 18:08:23.275330"], ["updated_at", "2016-12-13 18:08:23.275330"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-13 18:08:23.276754"], ["updated_at", "2016-12-13 18:08:23.276754"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-13 15:08:23 -0300 Processing by UsersController#index as JSON User Load (0.3ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (0.9ms) Completed 200 OK in 4ms (Views: 3.9ms | ActiveRecord: 0.3ms) Started GET "/users.xml" for 127.0.0.1 at 2016-12-13 15:08:23 -0300 Processing by UsersController#index as XML User Load (0.2ms) SELECT "users".* FROM "users" Rendered users/index.xml.ruby (4.9ms) Completed 200 OK in 7ms (Views: 6.8ms | ActiveRecord: 0.2ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.2ms) Rendered layouts/market.html.erb (6.4ms)  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.2ms) Rendered layouts/market.html.erb (6.7ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-13 18:08:56.589545"], ["updated_at", "2016-12-13 18:08:56.589545"]]  (0.2ms) RELEASE SAVEPOINT active_record_1 User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 81]]  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.2ms) UserMailer#invite: processed outbound mail in 8.3ms  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-13 18:08:56.725756"], ["updated_at", "2016-12-13 18:08:56.725756"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-13 18:08:56.727163"], ["updated_at", "2016-12-13 18:08:56.727163"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-13 15:08:56 -0300 Processing by UsersController#index as JSON User Load (0.3ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (0.9ms) Completed 200 OK in 4ms (Views: 3.8ms | ActiveRecord: 0.3ms) Started GET "/users.xml" for 127.0.0.1 at 2016-12-13 15:08:56 -0300 Processing by UsersController#index as XML User Load (0.2ms) SELECT "users".* FROM "users" Rendered users/index.xml.ruby (4.9ms) Completed 200 OK in 7ms (Views: 6.9ms | ActiveRecord: 0.2ms)  (0.2ms) ROLLBACK ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.2ms) ROLLBACK  (0.2ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (0.6ms) UserMailer#invite: processed outbound mail in 11.5ms  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-13 18:09:21.677311"], ["updated_at", "2016-12-13 18:09:21.677311"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-13 18:09:21.680317"], ["updated_at", "2016-12-13 18:09:21.680317"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-13 15:09:21 -0300 Processing by UsersController#index as JSON User Load (0.3ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (1.1ms) Completed 200 OK in 4ms (Views: 3.8ms | ActiveRecord: 0.3ms) Started GET "/users.xml" for 127.0.0.1 at 2016-12-13 15:09:21 -0300 Processing by UsersController#index as XML User Load (0.2ms) SELECT "users".* FROM "users" Rendered users/index.xml.ruby (4.9ms) Completed 200 OK in 7ms (Views: 6.9ms | ActiveRecord: 0.2ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.2ms) Rendered layouts/market.html.erb (6.0ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-13 18:09:21.724440"], ["updated_at", "2016-12-13 18:09:21.724440"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 86]]  (0.1ms) ROLLBACK ActiveRecord::SchemaMigration Load (6.7ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) BEGIN ---------------------- ViewTest: test_handler ----------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.6ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "mario"], ["created_at", "2016-12-13 20:27:03.464116"], ["updated_at", "2016-12-13 20:27:03.464116"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("name", "created_at", "updated_at") VALUES ($1, $2, $3) RETURNING "id" [["name", "juan"], ["created_at", "2016-12-13 20:27:03.468189"], ["updated_at", "2016-12-13 20:27:03.468189"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/users.json" for 127.0.0.1 at 2016-12-13 17:27:03 -0300 Processing by UsersController#index as JSON User Load (0.7ms) SELECT "users".* FROM "users" Rendered users/index.json.ruby (2.0ms) Completed 200 OK in 11ms (Views: 9.7ms | ActiveRecord: 0.7ms) Started GET "/users.xml" for 127.0.0.1 at 2016-12-13 17:27:03 -0300 Processing by UsersController#index as XML User Load (0.3ms) SELECT "users".* FROM "users" Rendered users/index.xml.ruby (12.1ms) Completed 200 OK in 15ms (Views: 14.8ms | ActiveRecord: 0.3ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN --------------------------- ControllerTest: test_render ---------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ ControllerTest: test_callbacks ------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ MailerTest: test_normalization ------------------------------ Rendered user_mailer/invite.text.erb (1.2ms) UserMailer#invite: processed outbound mail in 15.9ms  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- ValidationTest: test_complexity -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- ValidationTest: test_count --------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ---------------------------- ValidationTest: test_methods ----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------- ValidationTest: test_time -------------------------  (0.2ms) ROLLBACK  (0.1ms) BEGIN ----------------------------- HelperTest: test_active_trail -----------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN ------------------------------- HelperTest: test_content_tag_if -------------------------------  (0.1ms) ROLLBACK  (0.1ms) BEGIN -------------------------- HelperTest: test_extending -------------------------- Rendered layouts/application.html.erb (0.3ms) Rendered layouts/market.html.erb (7.4ms)  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------------ RecordTest: test_nilify_blanks ------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "users" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id" [["created_at", "2016-12-13 20:27:03.710582"], ["updated_at", "2016-12-13 20:27:03.710582"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 89]]  (0.2ms) ROLLBACK  (0.1ms) BEGIN ------------------------- RecordTest: test_validate -------------------------  (0.1ms) ROLLBACK  (2.7ms) CREATE TABLE "schema_migrations" ("version" character varying NOT NULL)   (1.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version") ActiveRecord::SchemaMigration Load (0.3ms) SELECT "schema_migrations".* FROM "schema_migrations" Migrating to CreateUsers (20161020200126)  (0.1ms) BEGIN  (2.7ms) CREATE TABLE "users" ("id" serial primary key, "name" character varying, "created_at" timestamp NOT NULL, "updated_at" timestamp NOT NULL)  SQL (0.3ms) INSERT INTO "schema_migrations" ("version") VALUES ($1) [["version", "20161020200126"]]  (0.5ms) COMMIT ActiveRecord::SchemaMigration Load (0.2ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.8ms) SELECT t2.oid::regclass::text AS to_table, a1.attname AS column, a2.attname AS primary_key, c.conname AS name, c.confupdtype AS on_update, c.confdeltype AS on_delete FROM pg_constraint c JOIN pg_class t1 ON c.conrelid = t1.oid JOIN pg_class t2 ON c.confrelid = t2.oid JOIN pg_attribute a1 ON a1.attnum = c.conkey[1] AND a1.attrelid = t1.oid JOIN pg_attribute a2 ON a2.attnum = c.confkey[1] AND a2.attrelid = t2.oid JOIN pg_namespace t3 ON c.connamespace = t3.oid WHERE c.contype = 'f' AND t1.relname = 'users' AND t3.nspname = ANY (current_schemas(false)) ORDER BY c.conname