ActiveRecord::SchemaMigration Load (2.3ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.3ms)  BEGIN
---------------------------
AttributeTest: test_methods
---------------------------
   (0.3ms)  ROLLBACK
   (0.1ms)  BEGIN
-----------------------------
AssociationTest: test_methods
-----------------------------
   (0.2ms)  SAVEPOINT active_record_1
  SQL (18.4ms)  INSERT INTO "shops" ("created_at", "updated_at") VALUES ($1, $2) RETURNING "id"  [["created_at", "2016-11-21 06:16:21.465844"], ["updated_at", "2016-11-21 06:16:21.465844"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  SAVEPOINT active_record_1
  SQL (23.8ms)  INSERT INTO "products" ("name", "shop_id", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id"  [["name", "iPhone"], ["shop_id", 14], ["created_at", "2016-11-21 06:16:21.498203"], ["updated_at", "2016-11-21 06:16:21.498203"]]
   (0.2ms)  RELEASE SAVEPOINT active_record_1
   (0.1ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  INSERT INTO "products" ("name", "shop_id", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id"  [["name", "iPad"], ["shop_id", 14], ["created_at", "2016-11-21 06:16:21.523814"], ["updated_at", "2016-11-21 06:16:21.523814"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
  Product Load (0.3ms)  SELECT "products".* FROM "products" WHERE "products"."shop_id" = $1  [["shop_id", 14]]
   (0.2ms)  SAVEPOINT active_record_1
  SQL (0.2ms)  INSERT INTO "products" ("name", "shop_id", "created_at", "updated_at") VALUES ($1, $2, $3, $4) RETURNING "id"  [["name", "iMac"], ["shop_id", 14], ["created_at", "2016-11-21 06:16:21.528027"], ["updated_at", "2016-11-21 06:16:21.528027"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  ROLLBACK