(0.2ms) begin transaction --------------------------------------------------- DirtyRelationsTest: test_changed_flag_reset_by_save ---------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction -------------------------------------------------------------------------- DirtyRelationsTest: test_previous_changes_set_to_last_changed_flag_by_save --------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction ----------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relation_ids_flags_object_as_changed -----------------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction -------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relations_flags_object_as_changed --------------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.2ms) begin transaction --------------------------------------------------- DirtyRelationsTest: test_changed_flag_reset_by_save ---------------------------------------------------  (0.0ms) rollback transaction  (0.1ms) begin transaction -------------------------------------------------------------------------- DirtyRelationsTest: test_previous_changes_set_to_last_changed_flag_by_save --------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction ----------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relation_ids_flags_object_as_changed -----------------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction -------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relations_flags_object_as_changed --------------------------------------------------------------------------------  (0.0ms) rollback transaction  (7.3ms) CREATE TABLE "bars" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "created_at" datetime, "updated_at" datetime)   (0.8ms) CREATE TABLE "foos" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "bar_id" integer, "created_at" datetime, "updated_at" datetime)  (0.7ms) CREATE INDEX "index_foos_on_bar_id" ON "foos" ("bar_id")  (0.8ms) CREATE TABLE "schema_migrations" ("version" varchar(255) NOT NULL)  (0.7ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (0.6ms) INSERT INTO "schema_migrations" (version) VALUES ('20131113012331')  (0.5ms) INSERT INTO "schema_migrations" (version) VALUES ('20131113012248') ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) begin transaction --------------------------------------------------- DirtyRelationsTest: test_changed_flag_reset_by_save ---------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction -------------------------------------------------------------------------- DirtyRelationsTest: test_previous_changes_set_to_last_changed_flag_by_save --------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction ----------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relation_ids_flags_object_as_changed -----------------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction -------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relations_flags_object_as_changed --------------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.2ms) begin transaction --------------------------------------------------- DirtyRelationsTest: test_changed_flag_reset_by_save ---------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction -------------------------------------------------------------------------- DirtyRelationsTest: test_previous_changes_set_to_last_changed_flag_by_save --------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction ----------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relation_ids_flags_object_as_changed -----------------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction -------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relations_flags_object_as_changed --------------------------------------------------------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (4.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 01:55:52 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 01:55:52 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.8ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 01:55:52 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 01:55:52 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.2ms) begin transaction --------------------------------------------------- DirtyRelationsTest: test_changed_flag_reset_by_save ---------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction -------------------------------------------------------------------------- DirtyRelationsTest: test_previous_changes_set_to_last_changed_flag_by_save --------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction ----------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relation_ids_flags_object_as_changed -----------------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction -------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relations_flags_object_as_changed --------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (2.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 01:56:27 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 01:56:27 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.9ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 01:56:27 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 01:56:27 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (6.7ms) rollback transaction  (0.3ms) begin transaction --------------------------------------------------- DirtyRelationsTest: test_changed_flag_reset_by_save ---------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction -------------------------------------------------------------------------- DirtyRelationsTest: test_previous_changes_set_to_last_changed_flag_by_save --------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction ----------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relation_ids_flags_object_as_changed -----------------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction -------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relations_flags_object_as_changed --------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (1.9ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 01:58:16 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 01:58:16 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.9ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 01:58:16 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 01:58:16 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.5ms) rollback transaction  (0.2ms) begin transaction --------------------------------------------------- DirtyRelationsTest: test_changed_flag_reset_by_save ---------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction -------------------------------------------------------------------------- DirtyRelationsTest: test_previous_changes_set_to_last_changed_flag_by_save --------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction ----------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relation_ids_flags_object_as_changed -----------------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction -------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relations_flags_object_as_changed --------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (2.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 01:59:00 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 01:59:00 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (1.5ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 01:59:00 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 01:59:00 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.2ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 01:59:00 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 01:59:00 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 01:59:00 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 01:59:00 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.2ms) begin transaction --------------------------------------------------- DirtyRelationsTest: test_changed_flag_reset_by_save ---------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction -------------------------------------------------------------------------- DirtyRelationsTest: test_previous_changes_set_to_last_changed_flag_by_save --------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction ----------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relation_ids_flags_object_as_changed -----------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (1.9ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:02:39 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:02:39 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.9ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:02:39 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:02:39 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:02:39 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:02:39 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:02:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:02:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (6.7ms) rollback transaction  (0.1ms) begin transaction -------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relations_flags_object_as_changed --------------------------------------------------------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:02:39 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:02:39 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:02:39 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:02:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:02:39 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:02:39 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:02:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:02:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.2ms) begin transaction --------------------------------------------------- DirtyRelationsTest: test_changed_flag_reset_by_save ---------------------------------------------------  (0.0ms) rollback transaction  (0.1ms) begin transaction -------------------------------------------------------------------------- DirtyRelationsTest: test_previous_changes_set_to_last_changed_flag_by_save --------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction ----------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relation_ids_flags_object_as_changed -----------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (1.9ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:04:51 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:04:51 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.8ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:04:51 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:04:51 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:04:51 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:04:51 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:04:51 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:04:51 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (6.5ms) rollback transaction  (0.1ms) begin transaction -------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relations_flags_object_as_changed --------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:04:51 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:04:51 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:04:51 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:04:51 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.2ms) begin transaction --------------------------------------------------- DirtyRelationsTest: test_changed_flag_reset_by_save ---------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction -------------------------------------------------------------------------- DirtyRelationsTest: test_previous_changes_set_to_last_changed_flag_by_save --------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction ----------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relation_ids_flags_object_as_changed -----------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (2.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:05:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:05:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.8ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:05:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:05:08 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:05:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:05:08 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:05:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:05:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (6.6ms) rollback transaction  (0.1ms) begin transaction -------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relations_flags_object_as_changed --------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:05:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:05:08 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:05:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:05:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:05:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:05:08 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:05:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:05:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:05:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:05:08 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:05:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:05:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:05:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:05:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.5ms) rollback transaction  (0.2ms) begin transaction --------------------------------------------------- DirtyRelationsTest: test_changed_flag_reset_by_save ---------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (2.0ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.9ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (6.4ms) rollback transaction  (0.1ms) begin transaction -------------------------------------------------------------------------- DirtyRelationsTest: test_previous_changes_set_to_last_changed_flag_by_save --------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction ----------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relation_ids_flags_object_as_changed -----------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.7ms) rollback transaction  (0.0ms) begin transaction -------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relations_flags_object_as_changed --------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:02 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.5ms) rollback transaction  (0.2ms) begin transaction --------------------------------------------------- DirtyRelationsTest: test_changed_flag_reset_by_save ---------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (2.5ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.2ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (1.0ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.0ms) begin transaction -------------------------------------------------------------------------- DirtyRelationsTest: test_previous_changes_set_to_last_changed_flag_by_save --------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction ----------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relation_ids_flags_object_as_changed -----------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.0ms) begin transaction -------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relations_flags_object_as_changed --------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:11:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.2ms) begin transaction --------------------------------------------------- DirtyRelationsTest: test_changed_flag_reset_by_save ---------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (2.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (1.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (6.6ms) rollback transaction  (0.1ms) begin transaction -------------------------------------------------------------------------- DirtyRelationsTest: test_previous_changes_set_to_last_changed_flag_by_save --------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.0ms) begin transaction ----------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relation_ids_flags_object_as_changed -----------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.1ms) begin transaction -------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relations_flags_object_as_changed --------------------------------------------------------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.2ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:13:28 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.2ms) begin transaction --------------------------------------------------- DirtyRelationsTest: test_changed_flag_reset_by_save ---------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (1.8ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.1ms) SAVEPOINT active_record_1 SQL (1.2ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (6.5ms) rollback transaction  (0.1ms) begin transaction -------------------------------------------------------------------------- DirtyRelationsTest: test_previous_changes_set_to_last_changed_flag_by_save --------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.0ms) begin transaction ----------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relation_ids_flags_object_as_changed -----------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.0ms) begin transaction -------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relations_flags_object_as_changed --------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:16:44 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.2ms) begin transaction -------------------------------------------------------------------------- DirtyRelationsTest: test_previous_changes_set_to_last_changed_flag_by_save --------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (1.8ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:17:43 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:17:43 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (1.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:17:43 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:17:43 UTC +00:00]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:17:43 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (6.7ms) rollback transaction  (0.2ms) begin transaction -------------------------------------------------------------------------- DirtyRelationsTest: test_previous_changes_set_to_last_changed_flag_by_save --------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (1.9ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:18:53 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:18:53 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (1.2ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:18:53 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:18:53 UTC +00:00]] SQL (0.3ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:18:53 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (6.6ms) rollback transaction  (0.2ms) begin transaction -------------------------------------------------------------------------- DirtyRelationsTest: test_previous_changes_set_to_last_changed_flag_by_save --------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (1.9ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:19:21 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:19:21 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.1ms) SAVEPOINT active_record_1 SQL (1.0ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:19:21 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:19:21 UTC +00:00]] SQL (0.3ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:19:21 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (6.8ms) rollback transaction  (0.2ms) begin transaction -------------------------------------------------------------------------- DirtyRelationsTest: test_previous_changes_set_to_last_changed_flag_by_save --------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (1.8ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:20:44 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:20:44 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.2ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (1.0ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:20:44 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:20:44 UTC +00:00]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:20:44 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.2ms) begin transaction -------------------------------------------------------------------------- DirtyRelationsTest: test_previous_changes_set_to_last_changed_flag_by_save --------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (1.9ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:21:37 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:21:37 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (1.3ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:21:37 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:21:37 UTC +00:00]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:21:37 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (6.8ms) rollback transaction  (0.3ms) begin transaction -------------------------------------------------------------------------- DirtyRelationsTest: test_previous_changes_set_to_last_changed_flag_by_save --------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (1.8ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:22:42 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:22:42 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (1.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:22:42 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:22:42 UTC +00:00]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:22:42 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (6.6ms) rollback transaction  (0.3ms) begin transaction -------------------------------------------------------------------------- DirtyRelationsTest: test_previous_changes_set_to_last_changed_flag_by_save --------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (2.0ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:23:06 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:23:06 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.9ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:23:06 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:23:06 UTC +00:00]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:23:06 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:23:06 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:23:06 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:23:06 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 02:23:06 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (6.4ms) rollback transaction  (0.2ms) begin transaction -------------------------------------------------------------------------- DirtyRelationsTest: test_previous_changes_set_to_last_changed_flag_by_save --------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (1.9ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:24:07 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:24:07 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (1.4ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:24:07 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:24:07 UTC +00:00]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:24:07 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:24:07 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:24:07 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:24:07 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 02:24:07 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (6.8ms) rollback transaction  (0.2ms) begin transaction --------------------------------------------------- DirtyRelationsTest: test_changed_flag_reset_by_save ---------------------------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (1.9ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (1.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (6.6ms) rollback transaction  (0.1ms) begin transaction -------------------------------------------------------------------------- DirtyRelationsTest: test_previous_changes_set_to_last_changed_flag_by_save --------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.0ms) begin transaction ----------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relation_ids_flags_object_as_changed -----------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.0ms) begin transaction -------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_the_monitored_relations_flags_object_as_changed --------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:24:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.2ms) begin transaction --------------------------------------------------------------------------- DirtyRelationsTest: test_association_appears_in_previous_changes_after_save ---------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (1.9ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.1ms) SAVEPOINT active_record_1 SQL (1.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (6.6ms) rollback transaction  (0.1ms) begin transaction ---------------------------------------------- DirtyRelationsTest: test_changes_reset_by_save ----------------------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.0ms) begin transaction ---------------------------------------------------------------------------- DirtyRelationsTest: test_setting_has_many_association_adds_object_to_changes ----------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.0ms) begin transaction ------------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_has_many_association_ids_adds_association_to_changes -------------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:28:52 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.2ms) begin transaction ---------------------------------------------- DirtyRelationsTest: test_changes_reset_by_save ----------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (1.9ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (1.2ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (6.5ms) rollback transaction  (0.1ms) begin transaction ------------------------------------------------------------------------------------ DirtyRelationsTest: test_has_many_association_appears_in_previous_changes_after_save ------------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.5ms) rollback transaction  (0.0ms) begin transaction ---------------------------------------------------------------------------- DirtyRelationsTest: test_setting_has_many_association_adds_object_to_changes ----------------------------------------------------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.0ms) begin transaction ------------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_has_many_association_ids_adds_association_to_changes -------------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:36:19 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.2ms) begin transaction ---------------------------------------------- DirtyRelationsTest: test_changes_reset_by_save ----------------------------------------------  (0.1ms) rollback transaction  (0.1ms) begin transaction ------------------------------------------------------------------------------------ DirtyRelationsTest: test_has_many_association_appears_in_previous_changes_after_save ------------------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.1ms) begin transaction ---------------------------------------------------------------------------- DirtyRelationsTest: test_setting_has_many_association_adds_object_to_changes ----------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction ------------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_has_many_association_ids_adds_association_to_changes -------------------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.2ms) begin transaction ---------------------------------------------- DirtyRelationsTest: test_changes_reset_by_save ----------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction ------------------------------------------------------------------------------------ DirtyRelationsTest: test_has_many_association_appears_in_previous_changes_after_save ------------------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction ---------------------------------------------------------------------------- DirtyRelationsTest: test_setting_has_many_association_adds_object_to_changes ----------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.0ms) begin transaction ------------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_has_many_association_ids_adds_association_to_changes -------------------------------------------------------------------------------------  (0.0ms) rollback transaction  (0.2ms) begin transaction ---------------------------------------------- DirtyRelationsTest: test_changes_reset_by_save ----------------------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (2.5ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (1.0ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.5ms) rollback transaction  (0.1ms) begin transaction ------------------------------------------------------------------------------------ DirtyRelationsTest: test_has_many_association_appears_in_previous_changes_after_save ------------------------------------------------------------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.0ms) begin transaction ---------------------------------------------------------------------------- DirtyRelationsTest: test_setting_has_many_association_adds_object_to_changes ----------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.0ms) begin transaction ------------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_has_many_association_ids_adds_association_to_changes -------------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 02:52:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.2ms) begin transaction ---------------------------------------------- DirtyRelationsTest: test_changes_reset_by_save ----------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (2.4ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.1ms) SAVEPOINT active_record_1 SQL (0.9ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (6.6ms) rollback transaction  (0.1ms) begin transaction ------------------------------------------------------------------------------------ DirtyRelationsTest: test_has_many_association_appears_in_previous_changes_after_save ------------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.0ms) begin transaction ---------------------------------------------------------------------------- DirtyRelationsTest: test_setting_has_many_association_adds_object_to_changes ----------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.7ms) rollback transaction  (0.0ms) begin transaction ------------------------------------------------------------------------------------- DirtyRelationsTest: test_setting_has_many_association_ids_adds_association_to_changes -------------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:00:17 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (1.7ms) rollback transaction  (0.2ms) begin transaction ------------------------------------------------- DirtyAssociationsTest: test_changes_reset_by_save -------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (1.9ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (1.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (6.5ms) rollback transaction  (0.1ms) begin transaction --------------------------------------------------------------------------------------- DirtyAssociationsTest: test_has_many_association_appears_in_previous_changes_after_save ---------------------------------------------------------------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.0ms) begin transaction ------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_adds_object_to_changes -------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.0ms) begin transaction ---------------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_ids_adds_association_to_changes ----------------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:09:09 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.5ms) begin transaction -------------------------------- DirtyAssociationsTest: test_blah --------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (2.0ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:26:53 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:26:53 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.9ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:26:53 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:26:53 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (6.7ms) rollback transaction  (0.2ms) begin transaction -------------------------------- DirtyAssociationsTest: test_blah --------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (2.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.9ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.5ms) rollback transaction  (0.0ms) begin transaction ------------------------------------------------- DirtyAssociationsTest: test_changes_reset_by_save -------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.0ms) begin transaction --------------------------------------------------------------------------------------- DirtyAssociationsTest: test_has_many_association_appears_in_previous_changes_after_save ---------------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.0ms) begin transaction ------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_adds_object_to_changes -------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.0ms) begin transaction ---------------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_ids_adds_association_to_changes ----------------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:32:31 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.8ms) rollback transaction  (0.2ms) begin transaction ------------------------------------------------- DirtyAssociationsTest: test_changes_reset_by_save -------------------------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (2.4ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:53:36 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:53:36 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.0ms) begin transaction --------------------------------------------------------------------------------------- DirtyAssociationsTest: test_has_many_association_appears_in_previous_changes_after_save ---------------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:53:36 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:53:36 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.1ms) SAVEPOINT active_record_1 SQL (1.2ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:53:37 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:53:37 UTC +00:00]] SQL (0.3ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:53:37 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.5ms) rollback transaction  (0.0ms) begin transaction ------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_adds_object_to_changes -------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:53:37 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:53:37 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:53:37 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:53:37 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:53:37 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:53:37 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:53:37 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.1ms) begin transaction ---------------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_ids_adds_association_to_changes ----------------------------------------------------------------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:53:37 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:53:37 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:53:37 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:53:37 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:53:37 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:53:37 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:53:37 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.2ms) begin transaction ------------------------------------------------- DirtyAssociationsTest: test_changes_reset_by_save -------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (1.9ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:55:58 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:55:58 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (6.7ms) rollback transaction  (0.1ms) begin transaction --------------------------------------------------------------------------------------- DirtyAssociationsTest: test_has_many_association_appears_in_previous_changes_after_save ---------------------------------------------------------------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.5ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:55:58 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:55:58 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.1ms) SAVEPOINT active_record_1 SQL (0.9ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:55:58 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:55:58 UTC +00:00]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:55:58 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.0ms) begin transaction ------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_adds_object_to_changes -------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:55:58 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:55:58 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:55:58 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:55:58 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:55:58 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:55:58 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:55:58 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.0ms) begin transaction ---------------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_ids_adds_association_to_changes ----------------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:55:58 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:55:58 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:55:58 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:55:58 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:55:58 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:55:58 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:55:58 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.2ms) begin transaction ------------------------------------------------- DirtyAssociationsTest: test_changes_reset_by_save -------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (1.9ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:56:14 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:56:14 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (6.5ms) rollback transaction  (0.1ms) begin transaction --------------------------------------------------------------------------------------- DirtyAssociationsTest: test_has_many_association_appears_in_previous_changes_after_save ---------------------------------------------------------------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.5ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:56:14 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:56:14 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.2ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.1ms) SAVEPOINT active_record_1 SQL (1.0ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:56:14 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:56:14 UTC +00:00]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:56:14 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.0ms) begin transaction ------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_adds_object_to_changes -------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:56:14 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:56:14 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:56:14 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:56:14 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:56:14 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:56:14 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:56:14 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.1ms) begin transaction ---------------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_ids_adds_association_to_changes ----------------------------------------------------------------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:56:14 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:56:14 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.5ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:56:14 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:56:14 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:56:14 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:56:14 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:56:14 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.5ms) rollback transaction  (0.2ms) begin transaction ------------------------------------------------- DirtyAssociationsTest: test_changes_reset_by_save -------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (1.9ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.3ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.1ms) SAVEPOINT active_record_1 SQL (1.0ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00]] SQL (0.3ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.1ms) begin transaction --------------------------------------------------------------------------------------- DirtyAssociationsTest: test_has_many_association_appears_in_previous_changes_after_save ---------------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.1ms) SAVEPOINT active_record_1 SQL (0.5ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.5ms) rollback transaction  (0.0ms) begin transaction ------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_adds_object_to_changes -------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.1ms) begin transaction ---------------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_ids_adds_association_to_changes ----------------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:56:57 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.2ms) begin transaction ------------------------------------------------- DirtyAssociationsTest: test_changes_reset_by_save -------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (1.8ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (1.2ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (6.6ms) rollback transaction  (0.1ms) begin transaction --------------------------------------------------------------------------------------- DirtyAssociationsTest: test_has_many_association_appears_in_previous_changes_after_save ---------------------------------------------------------------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.0ms) begin transaction ------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_adds_object_to_changes -------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.0ms) begin transaction ---------------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_ids_adds_association_to_changes ----------------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 03:57:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.2ms) begin transaction -------------------------------- DirtyAssociationsTest: test_blah --------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (1.9ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (1.2ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.5ms) rollback transaction  (0.1ms) begin transaction ------------------------------------------------- DirtyAssociationsTest: test_changes_reset_by_save -------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.0ms) begin transaction --------------------------------------------------------------------------------------- DirtyAssociationsTest: test_has_many_association_appears_in_previous_changes_after_save ---------------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.1ms) begin transaction ------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_adds_object_to_changes -------------------------------------------------------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.1ms) begin transaction ---------------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_ids_adds_association_to_changes ----------------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:04:59 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.5ms) rollback transaction  (0.2ms) begin transaction -------------------------------- DirtyAssociationsTest: test_blah --------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (1.8ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.8ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (6.4ms) rollback transaction  (0.1ms) begin transaction ------------------------------------------------- DirtyAssociationsTest: test_changes_reset_by_save -------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.2ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.0ms) begin transaction --------------------------------------------------------------------------------------- DirtyAssociationsTest: test_has_many_association_appears_in_previous_changes_after_save ---------------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.0ms) begin transaction ------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_adds_object_to_changes -------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.5ms) rollback transaction  (0.0ms) begin transaction ---------------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_ids_adds_association_to_changes ----------------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:05:10 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.2ms) begin transaction ------------------------------------------------- DirtyAssociationsTest: test_changes_reset_by_save -------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (2.0ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (1.2ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (6.5ms) rollback transaction  (0.1ms) begin transaction --------------------------------------------------------------------------------------- DirtyAssociationsTest: test_has_many_association_appears_in_previous_changes_after_save ---------------------------------------------------------------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.4ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.1ms) begin transaction ------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_adds_object_to_changes -------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.0ms) begin transaction ---------------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_ids_adds_association_to_changes ----------------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:07:29 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.2ms) begin transaction ------------------------------------------------- DirtyAssociationsTest: test_changes_reset_by_save -------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (2.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.9ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.5ms) rollback transaction  (0.0ms) begin transaction --------------------------------------------------------------------------------------- DirtyAssociationsTest: test_has_many_association_appears_in_previous_changes_after_save ---------------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.0ms) begin transaction ------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_adds_object_to_changes -------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.0ms) begin transaction ---------------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_ids_adds_association_to_changes ----------------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:42:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.2ms) begin transaction ------------------------------------------------- DirtyAssociationsTest: test_changes_reset_by_save -------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (1.9ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (1.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (6.6ms) rollback transaction  (0.1ms) begin transaction --------------------------------------------------------------------------------------- DirtyAssociationsTest: test_has_many_association_appears_in_previous_changes_after_save ---------------------------------------------------------------------------------------  (0.1ms) SAVEPOINT active_record_1 SQL (0.5ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "bars" SET "updated_at" = ? WHERE "bars"."id" = 1 [["updated_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.0ms) begin transaction ------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_adds_object_to_changes -------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (1.4ms) rollback transaction  (0.1ms) begin transaction ---------------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_ids_adds_association_to_changes ----------------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.3ms) begin transaction ------------------------------------------------- DirtyAssociationsTest: test_changes_reset_by_save -------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (5.0ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.1ms) SAVEPOINT active_record_1 SQL (1.2ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00]] SQL (0.2ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.1ms) begin transaction --------------------------------------------------------------------------------------- DirtyAssociationsTest: test_has_many_association_appears_in_previous_changes_after_save ---------------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.0ms) begin transaction ------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_adds_object_to_changes -------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.0ms) begin transaction ---------------------------------------------------------------------------------------- DirtyAssociationsTest: test_setting_has_many_association_ids_adds_association_to_changes ----------------------------------------------------------------------------------------  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00]] SQL (0.1ms) UPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2 [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.5ms) rollback transaction  (0.0ms) SAVEPOINT active_record_1 SQL (3.3ms) INSERT INTO "foos" ("bar_id", "created_at", "updated_at") VALUES (?, ?, ?) [["bar_id", nil], ["created_at", Thu, 14 Nov 2013 02:24:47 UTC +00:00], ["updated_at", Thu, 14 Nov 2013 02:24:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".id FROM "foos" WHERE "foos"."bar_id" IS NULL  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("bar_id", "created_at", "updated_at") VALUES (?, ?, ?) [["bar_id", nil], ["created_at", Thu, 14 Nov 2013 02:24:47 UTC +00:00], ["updated_at", Thu, 14 Nov 2013 02:24:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".id FROM "foos" WHERE "foos"."bar_id" IS NULL  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("bar_id", "created_at", "updated_at") VALUES (?, ?, ?) [["bar_id", nil], ["created_at", Thu, 14 Nov 2013 02:24:47 UTC +00:00], ["updated_at", Thu, 14 Nov 2013 02:24:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.8ms) INSERT INTO "foos" ("bar_id", "created_at", "updated_at") VALUES (?, ?, ?) [["bar_id", nil], ["created_at", Thu, 14 Nov 2013 02:24:47 UTC +00:00], ["updated_at", Thu, 14 Nov 2013 02:24:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".id FROM "foos" WHERE "foos"."bar_id" IS NULL  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("bar_id", "created_at", "updated_at") VALUES (?, ?, ?) [["bar_id", nil], ["created_at", Thu, 14 Nov 2013 02:24:47 UTC +00:00], ["updated_at", Thu, 14 Nov 2013 02:24:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("bar_id", "created_at", "updated_at") VALUES (?, ?, ?) [["bar_id", nil], ["created_at", Thu, 14 Nov 2013 02:24:47 UTC +00:00], ["updated_at", Thu, 14 Nov 2013 02:24:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".id FROM "foos" WHERE "foos"."bar_id" IS NULL Connecting to database specified by database.yml  (0.2ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (2.7ms) INSERT INTO "foos" ("bar_id", "created_at", "updated_at") VALUES (?, ?, ?) [["bar_id", nil], ["created_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00], ["updated_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.1ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (1.0ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00], ["updated_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00]]  (0.1ms) UPDATE "foos" SET "bar_id" = 1, "updated_at" = '2013-11-14 02:31:05.499028' WHERE "foos"."id" = 1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("bar_id", "created_at", "updated_at") VALUES (?, ?, ?) [["bar_id", nil], ["created_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00], ["updated_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = 1 AND "foos"."id" IN (1)  (0.0ms) UPDATE "foos" SET "bar_id" = 1, "updated_at" = '2013-11-14 02:31:05.506536' WHERE "foos"."id" = 2  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) UPDATE "bars" SET "updated_at" = '2013-11-14 02:31:05.507186' WHERE "bars"."id" = 1  (0.0ms) RELEASE SAVEPOINT active_record_1  (6.6ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.5ms) INSERT INTO "foos" ("bar_id", "created_at", "updated_at") VALUES (?, ?, ?) [["bar_id", nil], ["created_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00], ["updated_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00], ["updated_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00]]  (0.1ms) UPDATE "foos" SET "bar_id" = 1, "updated_at" = '2013-11-14 02:31:05.519762' WHERE "foos"."id" = 1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "foos" ("bar_id", "created_at", "updated_at") VALUES (?, ?, ?) [["bar_id", nil], ["created_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00], ["updated_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = 1 AND "foos"."id" IN (1)  (0.0ms) UPDATE "foos" SET "bar_id" = 1, "updated_at" = '2013-11-14 02:31:05.522621' WHERE "foos"."id" = 2  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) UPDATE "bars" SET "updated_at" = '2013-11-14 02:31:05.523272' WHERE "bars"."id" = 1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("bar_id", "created_at", "updated_at") VALUES (?, ?, ?) [["bar_id", nil], ["created_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00], ["updated_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "foos" ("bar_id", "created_at", "updated_at") VALUES (?, ?, ?) [["bar_id", nil], ["created_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00], ["updated_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00], ["updated_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00]]  (0.1ms) UPDATE "foos" SET "bar_id" = 1, "updated_at" = '2013-11-14 02:31:05.528803' WHERE "foos"."id" = 2  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = 1 AND "foos"."id" IN (2)  (0.0ms) UPDATE "foos" SET "bar_id" = 1, "updated_at" = '2013-11-14 02:31:05.530679' WHERE "foos"."id" = 1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.5ms) INSERT INTO "foos" ("bar_id", "created_at", "updated_at") VALUES (?, ?, ?) [["bar_id", nil], ["created_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00], ["updated_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "foos" ("bar_id", "created_at", "updated_at") VALUES (?, ?, ?) [["bar_id", nil], ["created_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00], ["updated_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?) [["created_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00], ["updated_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00]]  (0.1ms) UPDATE "foos" SET "bar_id" = 1, "updated_at" = '2013-11-14 02:31:05.535536' WHERE "foos"."id" = 2  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Foo Load (0.0ms) SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = 1 AND "foos"."id" IN (2)  (0.0ms) UPDATE "foos" SET "bar_id" = 1, "updated_at" = '2013-11-14 02:31:05.537246' WHERE "foos"."id" = 1  (0.0ms) RELEASE SAVEPOINT active_record_1  (7.1ms) rollback transaction