test/dummy/log/test.log in dirty_associations-0.1.0 vs test/dummy/log/test.log in dirty_associations-0.2.0
- old
+ new
@@ -2482,5 +2482,186 @@
[1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m
[1m[35mSQL (0.1ms)[0m UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (2) [["bar_id", 1]]
[1m[36mSQL (0.1ms)[0m [1mUPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1[0m [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 04:43:39 UTC +00:00]]
[1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
[1m[36m (0.3ms)[0m [1mrollback transaction[0m
+ [1m[36m (0.3ms)[0m [1mbegin transaction[0m
+-------------------------------------------------
+DirtyAssociationsTest: test_changes_reset_by_save
+-------------------------------------------------
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36mSQL (5.0ms)[0m [1mINSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?)[0m [["created_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00]]
+ [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1
+ [1m[36mFoo Load (0.1ms)[0m [1mSELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1[0m [["id", 1]]
+ [1m[35m (0.1ms)[0m SAVEPOINT active_record_1
+ [1m[36mSQL (1.2ms)[0m [1mINSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?)[0m [["created_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00]]
+ [1m[35mSQL (0.2ms)[0m 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]]
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36mSQL (0.1ms)[0m [1mINSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?)[0m [["created_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00]]
+ [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
+ [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35mSQL (0.2ms)[0m UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = ? AND "foos"."id" IN (1) [["bar_id", 1]]
+ [1m[36mSQL (0.1ms)[0m [1mUPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2[0m [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00]]
+ [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
+ [1m[36m (0.4ms)[0m [1mrollback transaction[0m
+ [1m[35m (0.1ms)[0m begin transaction
+---------------------------------------------------------------------------------------
+DirtyAssociationsTest: test_has_many_association_appears_in_previous_changes_after_save
+---------------------------------------------------------------------------------------
+ [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35mSQL (0.3ms)[0m 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]]
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+ [1m[35mFoo Load (0.0ms)[0m SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]
+ [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35mSQL (0.3ms)[0m 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]]
+ [1m[36mSQL (0.1ms)[0m [1mUPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 1[0m [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00]]
+ [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
+ [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
+ [1m[36m (0.3ms)[0m [1mrollback transaction[0m
+ [1m[35m (0.0ms)[0m begin transaction
+-------------------------------------------------------------------------------
+DirtyAssociationsTest: test_setting_has_many_association_adds_object_to_changes
+-------------------------------------------------------------------------------
+ [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35mSQL (0.2ms)[0m 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]]
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36mSQL (0.2ms)[0m [1mINSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?)[0m [["created_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00]]
+ [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
+ [1m[36mFoo Load (0.0ms)[0m [1mSELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1[0m [["id", 2]]
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36mSQL (0.1ms)[0m [1mINSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?)[0m [["created_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00]]
+ [1m[35mSQL (0.1ms)[0m 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]]
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+ [1m[35m (0.4ms)[0m rollback transaction
+ [1m[36m (0.0ms)[0m [1mbegin transaction[0m
+----------------------------------------------------------------------------------------
+DirtyAssociationsTest: test_setting_has_many_association_ids_adds_association_to_changes
+----------------------------------------------------------------------------------------
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36mSQL (0.2ms)[0m [1mINSERT INTO "foos" ("created_at", "updated_at") VALUES (?, ?)[0m [["created_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00], ["updated_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00]]
+ [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
+ [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35mSQL (0.2ms)[0m 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]]
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+ [1m[35mFoo Load (0.0ms)[0m SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]
+ [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35mSQL (0.1ms)[0m 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]]
+ [1m[36mSQL (0.1ms)[0m [1mUPDATE "foos" SET "bar_id" = ?, "updated_at" = ? WHERE "foos"."id" = 2[0m [["bar_id", 1], ["updated_at", Wed, 13 Nov 2013 13:04:26 UTC +00:00]]
+ [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
+ [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
+ [1m[36m (0.5ms)[0m [1mrollback transaction[0m
+ [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35mSQL (3.3ms)[0m 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]]
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+ [1m[35mFoo Load (0.0ms)[0m SELECT "foos".id FROM "foos" WHERE "foos"."bar_id" IS NULL
+ [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35mSQL (0.3ms)[0m 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]]
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+ [1m[35mFoo Load (0.0ms)[0m SELECT "foos".id FROM "foos" WHERE "foos"."bar_id" IS NULL
+ [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35mSQL (0.2ms)[0m 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]]
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36mSQL (0.8ms)[0m [1mINSERT INTO "foos" ("bar_id", "created_at", "updated_at") VALUES (?, ?, ?)[0m [["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]]
+ [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
+ [1m[36mFoo Load (0.1ms)[0m [1mSELECT "foos".id FROM "foos" WHERE "foos"."bar_id" IS NULL[0m
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36mSQL (0.2ms)[0m [1mINSERT INTO "foos" ("bar_id", "created_at", "updated_at") VALUES (?, ?, ?)[0m [["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]]
+ [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
+ [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35mSQL (0.2ms)[0m 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]]
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+ [1m[35mFoo Load (0.1ms)[0m SELECT "foos".id FROM "foos" WHERE "foos"."bar_id" IS NULL
+Connecting to database specified by database.yml
+ [1m[36m (0.2ms)[0m [1mbegin transaction[0m
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36mSQL (2.7ms)[0m [1mINSERT INTO "foos" ("bar_id", "created_at", "updated_at") VALUES (?, ?, ?)[0m [["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]]
+ [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1
+ [1m[36mFoo Load (0.1ms)[0m [1mSELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1[0m [["id", 1]]
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36mSQL (1.0ms)[0m [1mINSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?)[0m [["created_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00], ["updated_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00]]
+ [1m[35m (0.1ms)[0m UPDATE "foos" SET "bar_id" = 1, "updated_at" = '2013-11-14 02:31:05.499028' WHERE "foos"."id" = 1
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36mSQL (0.2ms)[0m [1mINSERT INTO "foos" ("bar_id", "created_at", "updated_at") VALUES (?, ?, ?)[0m [["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]]
+ [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
+ [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35mSQL (0.1ms)[0m UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = 1 AND "foos"."id" IN (1)
+ [1m[36m (0.0ms)[0m [1mUPDATE "foos" SET "bar_id" = 1, "updated_at" = '2013-11-14 02:31:05.506536' WHERE "foos"."id" = 2[0m
+ [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
+ [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35m (0.0ms)[0m UPDATE "bars" SET "updated_at" = '2013-11-14 02:31:05.507186' WHERE "bars"."id" = 1
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+ [1m[35m (6.6ms)[0m rollback transaction
+ [1m[36m (0.1ms)[0m [1mbegin transaction[0m
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36mSQL (0.5ms)[0m [1mINSERT INTO "foos" ("bar_id", "created_at", "updated_at") VALUES (?, ?, ?)[0m [["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]]
+ [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
+ [1m[36mFoo Load (0.0ms)[0m [1mSELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1[0m [["id", 1]]
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36mSQL (0.3ms)[0m [1mINSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?)[0m [["created_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00], ["updated_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00]]
+ [1m[35m (0.1ms)[0m UPDATE "foos" SET "bar_id" = 1, "updated_at" = '2013-11-14 02:31:05.519762' WHERE "foos"."id" = 1
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36mSQL (0.1ms)[0m [1mINSERT INTO "foos" ("bar_id", "created_at", "updated_at") VALUES (?, ?, ?)[0m [["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]]
+ [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
+ [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35mSQL (0.1ms)[0m UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = 1 AND "foos"."id" IN (1)
+ [1m[36m (0.0ms)[0m [1mUPDATE "foos" SET "bar_id" = 1, "updated_at" = '2013-11-14 02:31:05.522621' WHERE "foos"."id" = 2[0m
+ [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
+ [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35m (0.0ms)[0m UPDATE "bars" SET "updated_at" = '2013-11-14 02:31:05.523272' WHERE "bars"."id" = 1
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+ [1m[35m (0.3ms)[0m rollback transaction
+ [1m[36m (0.0ms)[0m [1mbegin transaction[0m
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36mSQL (0.2ms)[0m [1mINSERT INTO "foos" ("bar_id", "created_at", "updated_at") VALUES (?, ?, ?)[0m [["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]]
+ [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
+ [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35mSQL (0.3ms)[0m 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]]
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+ [1m[35mFoo Load (0.0ms)[0m SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 2]]
+ [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35mSQL (0.1ms)[0m 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]]
+ [1m[36m (0.1ms)[0m [1mUPDATE "foos" SET "bar_id" = 1, "updated_at" = '2013-11-14 02:31:05.528803' WHERE "foos"."id" = 2[0m
+ [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
+ [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
+ [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35mSQL (0.1ms)[0m UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = 1 AND "foos"."id" IN (2)
+ [1m[36m (0.0ms)[0m [1mUPDATE "foos" SET "bar_id" = 1, "updated_at" = '2013-11-14 02:31:05.530679' WHERE "foos"."id" = 1[0m
+ [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
+ [1m[36m (0.4ms)[0m [1mrollback transaction[0m
+ [1m[35m (0.0ms)[0m begin transaction
+ [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35mSQL (0.5ms)[0m 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]]
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36mSQL (0.2ms)[0m [1mINSERT INTO "foos" ("bar_id", "created_at", "updated_at") VALUES (?, ?, ?)[0m [["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]]
+ [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
+ [1m[36mFoo Load (0.0ms)[0m [1mSELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1[0m [["id", 2]]
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36mSQL (0.1ms)[0m [1mINSERT INTO "bars" ("created_at", "updated_at") VALUES (?, ?)[0m [["created_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00], ["updated_at", Thu, 14 Nov 2013 02:31:05 UTC +00:00]]
+ [1m[35m (0.1ms)[0m UPDATE "foos" SET "bar_id" = 1, "updated_at" = '2013-11-14 02:31:05.535536' WHERE "foos"."id" = 2
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+ [1m[35mFoo Load (0.0ms)[0m SELECT "foos".* FROM "foos" WHERE "foos"."id" = ? LIMIT 1 [["id", 1]]
+ [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35mSQL (0.1ms)[0m UPDATE "foos" SET "bar_id" = NULL WHERE "foos"."bar_id" = 1 AND "foos"."id" IN (2)
+ [1m[36m (0.0ms)[0m [1mUPDATE "foos" SET "bar_id" = 1, "updated_at" = '2013-11-14 02:31:05.537246' WHERE "foos"."id" = 1[0m
+ [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
+ [1m[36m (7.1ms)[0m [1mrollback transaction[0m