(0.2ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/ Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/signed_out Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1 SQLite3::SQLException: no such table: accounts: SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1 Completed 500 Internal Server Error in 3ms  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1 SQLite3::SQLException: no such table: accounts: SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1 Completed 500 Internal Server Error in 0ms  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1 SQLite3::SQLException: no such table: accounts: SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1 Completed 500 Internal Server Error in 0ms  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 4ms (Views: 4.1ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 0ms (Views: 0.4ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.2ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/signed_out Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/ Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1 SQLite3::SQLException: no such table: accounts: SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1 Completed 500 Internal Server Error in 2ms  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1 SQLite3::SQLException: no such table: accounts: SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1 Completed 500 Internal Server Error in 0ms  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1 SQLite3::SQLException: no such table: accounts: SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1 Completed 500 Internal Server Error in 0ms  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 4ms (Views: 4.0ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 0ms (Views: 0.4ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.2ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/signed_out Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/ Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1 SQLite3::SQLException: no such table: accounts: SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1 Completed 500 Internal Server Error in 2ms  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1 SQLite3::SQLException: no such table: accounts: SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1 Completed 500 Internal Server Error in 0ms  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1 SQLite3::SQLException: no such table: accounts: SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1 Completed 500 Internal Server Error in 0ms  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 4ms (Views: 4.0ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 0ms (Views: 0.4ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (2.7ms) CREATE TABLE "schema_migrations" ("version" varchar(255) NOT NULL)   (0.7ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version") ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" Migrating to CreateAccounts (20140522110326)  (0.0ms) begin transaction  (0.2ms) CREATE TABLE "accounts" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "created_at" datetime, "updated_at" datetime)  SQL (0.1ms) INSERT INTO "schema_migrations" ("version") VALUES (?) [["version", "20140522110326"]]  (0.5ms) commit transaction Migrating to AddOAuthFieldsToAccounts (20140522110454)  (0.0ms) begin transaction  (0.2ms) ALTER TABLE "accounts" ADD "provider" varchar(255)  (0.1ms) ALTER TABLE "accounts" ADD "uid" integer  (0.1ms) CREATE INDEX "index_accounts_on_uid" ON "accounts" ("uid")  (0.1ms) ALTER TABLE "accounts" ADD "name" varchar(255)  (0.1ms) ALTER TABLE "accounts" ADD "oauth_access_token" varchar(255)  (0.1ms) ALTER TABLE "accounts" ADD "oauth_refresh_token" varchar(255)  (0.1ms) ALTER TABLE "accounts" ADD "oauth_expires_at" varchar(255) SQL (0.1ms) INSERT INTO "schema_migrations" ("version") VALUES (?) [["version", "20140522110454"]]  (0.5ms) commit transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (0.2ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (2.0ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:49:38 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 13:49:38 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 11ms (ActiveRecord: 2.9ms)  (2.3ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:49:38 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 13:49:38 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 2ms (ActiveRecord: 0.4ms)  (0.3ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:49:38 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 13:49:38 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/admin Completed 302 Found in 1ms (ActiveRecord: 0.3ms)  (0.2ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/ Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/signed_out Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 5ms (Views: 4.6ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 0ms (Views: 0.4ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:49:38 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 13:49:38 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.8ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 13:49:38 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (2.4ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:49:38 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 13:49:38 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 13:49:38 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (2.5ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:49:38 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 13:49:38 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 13:49:38 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.3ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "updated_at") VALUES (?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:49:38 UTC +00:00], ["oauth_access_token", "token"], ["oauth_expires_at", "expires"], ["oauth_refresh_token", "refresh"], ["updated_at", Mon, 26 May 2014 13:49:38 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["oauth_access_token", nil], ["oauth_refresh_token", nil], ["oauth_expires_at", nil], ["updated_at", Mon, 26 May 2014 13:49:38 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.2ms) rollback transaction  (0.2ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (5.9ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:56:41 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 13:56:41 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 15ms (ActiveRecord: 6.7ms)  (0.3ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:56:41 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 13:56:41 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 2ms (ActiveRecord: 0.3ms)  (0.2ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:56:41 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 13:56:41 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/admin Completed 302 Found in 1ms (ActiveRecord: 0.3ms)  (0.2ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/ Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/signed_out Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 4ms (Views: 4.3ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 0ms (Views: 0.4ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:56:41 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 13:56:41 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.5ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 13:56:41 UTC +00:00]]  (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 "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:56:41 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 13:56:41 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 13:56:41 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.2ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:56:41 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 13:56:41 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 13:56:41 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.2ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "updated_at") VALUES (?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:56:41 UTC +00:00], ["oauth_access_token", "token"], ["oauth_expires_at", "expires"], ["oauth_refresh_token", "refresh"], ["updated_at", Mon, 26 May 2014 13:56:41 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["oauth_access_token", nil], ["oauth_refresh_token", nil], ["oauth_expires_at", nil], ["updated_at", Mon, 26 May 2014 13:56:41 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.3ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) rollback transaction  (0.2ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (2.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:57:04 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 13:57:04 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 11ms (ActiveRecord: 3.1ms)  (2.2ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:57:04 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 13:57:04 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 2ms (ActiveRecord: 0.4ms)  (0.3ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:57:04 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 13:57:04 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/admin Completed 302 Found in 1ms (ActiveRecord: 0.4ms)  (0.2ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/ Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/signed_out Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 4ms (Views: 4.2ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 1ms (Views: 0.5ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:57:04 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 13:57:04 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.6ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 13:57:04 UTC +00:00]]  (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 "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:57:04 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 13:57:04 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 13:57:04 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.2ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:57:04 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 13:57:04 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 13:57:04 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.2ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "updated_at") VALUES (?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:57:04 UTC +00:00], ["oauth_access_token", "token"], ["oauth_expires_at", "expires"], ["oauth_refresh_token", "refresh"], ["updated_at", Mon, 26 May 2014 13:57:04 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["oauth_access_token", nil], ["oauth_refresh_token", nil], ["oauth_expires_at", nil], ["updated_at", Mon, 26 May 2014 13:57:04 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.3ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "updated_at") VALUES (?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:57:04 UTC +00:00], ["oauth_access_token", "token"], ["oauth_expires_at", "expires"], ["oauth_refresh_token", "refresh"], ["updated_at", Mon, 26 May 2014 13:57:04 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (2.1ms) rollback transaction  (0.2ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/ Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/signed_out Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (2.0ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:59:28 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 13:59:28 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 11ms (ActiveRecord: 2.9ms)  (2.3ms) rollback transaction  (0.1ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:59:28 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 13:59:28 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 2ms (ActiveRecord: 0.4ms)  (0.3ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:59:28 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 13:59:28 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/admin Completed 302 Found in 1ms (ActiveRecord: 0.4ms)  (0.2ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 5ms (Views: 4.5ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 0ms (Views: 0.4ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "updated_at") VALUES (?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:59:29 UTC +00:00], ["oauth_access_token", "token"], ["oauth_expires_at", "expires"], ["oauth_refresh_token", "refresh"], ["updated_at", Mon, 26 May 2014 13:59:29 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.6ms) UPDATE "accounts" SET "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["oauth_access_token", nil], ["oauth_refresh_token", nil], ["oauth_expires_at", nil], ["updated_at", Mon, 26 May 2014 13:59:29 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.3ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:59:29 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 13:59:29 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 13:59:29 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.2ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:59:29 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 13:59:29 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 13:59:29 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.2ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 13:59:29 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 13:59:29 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 13:59:29 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.3ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.3ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (2.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:00 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:00:00 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 12ms (ActiveRecord: 3.1ms)  (2.2ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:00 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:00:00 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/admin Completed 302 Found in 2ms (ActiveRecord: 0.4ms)  (0.2ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:00 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:00:00 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 2ms (ActiveRecord: 0.4ms)  (0.2ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 5ms (Views: 4.5ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 0ms (Views: 0.4ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/ Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/signed_out Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:00 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:00:00 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.5ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:00:00 UTC +00:00]]  (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 "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:00 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:00:00 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:00:00 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.3ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:00 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:00:00 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:00:00 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.2ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "updated_at") VALUES (?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:00 UTC +00:00], ["oauth_access_token", "token"], ["oauth_expires_at", "expires"], ["oauth_refresh_token", "refresh"], ["updated_at", Mon, 26 May 2014 14:00:00 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["oauth_access_token", nil], ["oauth_refresh_token", nil], ["oauth_expires_at", nil], ["updated_at", Mon, 26 May 2014 14:00:00 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.2ms) rollback transaction  (0.2ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (2.0ms) INSERT INTO "accounts" ("created_at", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "updated_at") VALUES (?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:08 UTC +00:00], ["oauth_access_token", "token"], ["oauth_expires_at", "expires"], ["oauth_refresh_token", "refresh"], ["updated_at", Mon, 26 May 2014 14:00:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.5ms) UPDATE "accounts" SET "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["oauth_access_token", nil], ["oauth_refresh_token", nil], ["oauth_expires_at", nil], ["updated_at", Mon, 26 May 2014 14:00:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (2.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:08 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:00:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:00:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.3ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:08 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:00:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:00:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.3ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:08 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:00:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:00:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Rendered /Users/wijet/projects/bs/bookingsync-engine/app/views/sessions/failure.html.erb within layouts/application (0.2ms) Completed 200 OK in 5ms (Views: 4.4ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 0ms (Views: 0.4ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/signed_out Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/ Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:08 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:00:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/admin Completed 302 Found in 2ms (ActiveRecord: 0.4ms)  (0.3ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:08 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:00:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 1ms (ActiveRecord: 0.3ms)  (0.2ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:08 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:00:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 1ms (ActiveRecord: 0.3ms)  (0.2ms) rollback transaction  (0.2ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (2.0ms) INSERT INTO "accounts" ("created_at", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "updated_at") VALUES (?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:23 UTC +00:00], ["oauth_access_token", "token"], ["oauth_expires_at", "expires"], ["oauth_refresh_token", "refresh"], ["updated_at", Mon, 26 May 2014 14:00:23 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.5ms) UPDATE "accounts" SET "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["oauth_access_token", nil], ["oauth_refresh_token", nil], ["oauth_expires_at", nil], ["updated_at", Mon, 26 May 2014 14:00:23 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (2.1ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:23 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:00:23 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:00:23 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.4ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:23 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:00:23 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:00:23 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.3ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:23 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:00:23 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:00:23 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.2ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Rendered /Users/wijet/projects/bs/bookingsync-engine/app/views/sessions/failure.html.erb within layouts/application (0.2ms) Completed 200 OK in 4ms (Views: 4.2ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 0ms (Views: 0.4ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/signed_out Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/ Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:23 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:00:23 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/admin Completed 302 Found in 2ms (ActiveRecord: 0.4ms)  (0.2ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:23 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:00:23 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 1ms (ActiveRecord: 0.3ms)  (0.2ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:23 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:00:23 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 2ms (ActiveRecord: 0.3ms)  (0.2ms) rollback transaction  (0.2ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (6.0ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:47 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:00:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 15ms (ActiveRecord: 6.8ms)  (2.3ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:47 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:00:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 2ms (ActiveRecord: 0.4ms)  (0.3ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:47 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:00:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/admin Completed 302 Found in 2ms (ActiveRecord: 0.3ms)  (0.2ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/ Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/signed_out Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 5ms (Views: 4.4ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 0ms (Views: 0.4ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:47 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:00:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.5ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:00:47 UTC +00:00]]  (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 "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:47 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:00:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:00:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.3ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:47 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:00:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:00:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.2ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "updated_at") VALUES (?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:00:47 UTC +00:00], ["oauth_access_token", "token"], ["oauth_expires_at", "expires"], ["oauth_refresh_token", "refresh"], ["updated_at", Mon, 26 May 2014 14:00:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["oauth_access_token", nil], ["oauth_refresh_token", nil], ["oauth_expires_at", nil], ["updated_at", Mon, 26 May 2014 14:00:47 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.3ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.2ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (5.9ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:01 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:01 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 15ms (ActiveRecord: 6.7ms)  (2.3ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:01 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:01 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/admin Completed 302 Found in 2ms (ActiveRecord: 0.4ms)  (0.3ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:01 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:01 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 1ms (ActiveRecord: 0.3ms)  (0.2ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 5ms (Views: 4.5ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 0ms (Views: 0.4ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/ Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/signed_out Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:01 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:01 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.5ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:01:01 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:01 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:01 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:01:01 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.3ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:01 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:01 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:01:01 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.2ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "updated_at") VALUES (?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:01 UTC +00:00], ["oauth_access_token", "token"], ["oauth_expires_at", "expires"], ["oauth_refresh_token", "refresh"], ["updated_at", Mon, 26 May 2014 14:01:01 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["oauth_access_token", nil], ["oauth_refresh_token", nil], ["oauth_expires_at", nil], ["updated_at", Mon, 26 May 2014 14:01:01 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.3ms) rollback transaction  (0.2ms) begin transaction  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (2.1ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:11 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:11 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.5ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:01:11 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (2.2ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:11 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:11 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:01:11 UTC +00:00]]  (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 "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:11 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:11 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:01:11 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.3ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "updated_at") VALUES (?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:11 UTC +00:00], ["oauth_access_token", "token"], ["oauth_expires_at", "expires"], ["oauth_refresh_token", "refresh"], ["updated_at", Mon, 26 May 2014 14:01:11 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["oauth_access_token", nil], ["oauth_refresh_token", nil], ["oauth_expires_at", nil], ["updated_at", Mon, 26 May 2014 14:01:11 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.3ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Rendered /Users/wijet/projects/bs/bookingsync-engine/app/views/sessions/failure.html.erb within layouts/application (0.2ms) Completed 200 OK in 4ms (Views: 4.2ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 0ms (Views: 0.4ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:11 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:11 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/admin Completed 302 Found in 2ms (ActiveRecord: 0.4ms)  (0.2ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:11 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:11 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 1ms (ActiveRecord: 0.4ms)  (0.2ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:11 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:11 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 1ms (ActiveRecord: 0.3ms)  (0.2ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/signed_out Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/ Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.2ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (2.3ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:43 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:43 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 11ms (ActiveRecord: 3.2ms)  (0.8ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:43 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:43 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 2ms (ActiveRecord: 0.4ms)  (3.7ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:43 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:43 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/admin Completed 302 Found in 2ms (ActiveRecord: 0.4ms)  (2.3ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/ Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/signed_out Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 5ms (Views: 4.5ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 0ms (Views: 0.4ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:43 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:43 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.5ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:01:43 UTC +00:00]]  (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 "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:43 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:43 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:01:43 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.2ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:43 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:43 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:01:43 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.2ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "updated_at") VALUES (?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:43 UTC +00:00], ["oauth_access_token", "token"], ["oauth_expires_at", "expires"], ["oauth_refresh_token", "refresh"], ["updated_at", Mon, 26 May 2014 14:01:43 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["oauth_access_token", nil], ["oauth_refresh_token", nil], ["oauth_expires_at", nil], ["updated_at", Mon, 26 May 2014 14:01:43 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.2ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.2ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (2.1ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:49 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:49 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 11ms (ActiveRecord: 2.9ms)  (2.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:49 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:49 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 2ms (ActiveRecord: 0.3ms)  (0.3ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:49 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:49 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/admin Completed 302 Found in 1ms (ActiveRecord: 0.3ms)  (0.2ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/ Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/signed_out Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 4ms (Views: 4.3ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 0ms (Views: 0.4ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:49 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:49 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.5ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:01:49 UTC +00:00]]  (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 "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:49 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:49 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:01:49 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.3ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:49 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:49 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:01:49 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.2ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "updated_at") VALUES (?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:49 UTC +00:00], ["oauth_access_token", "token"], ["oauth_expires_at", "expires"], ["oauth_refresh_token", "refresh"], ["updated_at", Mon, 26 May 2014 14:01:49 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["oauth_access_token", nil], ["oauth_refresh_token", nil], ["oauth_expires_at", nil], ["updated_at", Mon, 26 May 2014 14:01:49 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.2ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.2ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (2.0ms) INSERT INTO "accounts" ("created_at", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "updated_at") VALUES (?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:58 UTC +00:00], ["oauth_access_token", "token"], ["oauth_expires_at", "expires"], ["oauth_refresh_token", "refresh"], ["updated_at", Mon, 26 May 2014 14:01:58 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.5ms) UPDATE "accounts" SET "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["oauth_access_token", nil], ["oauth_refresh_token", nil], ["oauth_expires_at", nil], ["updated_at", Mon, 26 May 2014 14:01:58 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (2.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:58 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:58 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:01:58 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.3ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:58 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:58 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:01:58 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.2ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:58 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:58 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:01:58 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.2ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/signed_out Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/ Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 4ms (Views: 4.2ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 0ms (Views: 0.4ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:58 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:58 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 2ms (ActiveRecord: 0.4ms)  (0.3ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:58 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:58 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/admin Completed 302 Found in 1ms (ActiveRecord: 0.3ms)  (0.2ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:01:58 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:01:58 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 1ms (ActiveRecord: 0.3ms)  (0.2ms) rollback transaction  (0.2ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (2.0ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:02:08 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:02:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 11ms (ActiveRecord: 2.8ms)  (0.3ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:02:08 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:02:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 2ms (ActiveRecord: 0.3ms)  (0.4ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:02:08 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:02:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/admin Completed 302 Found in 1ms (ActiveRecord: 0.3ms)  (0.3ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/ Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/signed_out Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 5ms (Views: 4.6ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 1ms (Views: 0.4ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:02:08 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:02:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.5ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:02:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.3ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:02:08 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:02:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:02:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.3ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:02:08 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Mon, 26 May 2014 14:02:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Mon, 26 May 2014 14:02:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.2ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "updated_at") VALUES (?, ?, ?, ?, ?) [["created_at", Mon, 26 May 2014 14:02:08 UTC +00:00], ["oauth_access_token", "token"], ["oauth_expires_at", "expires"], ["oauth_refresh_token", "refresh"], ["updated_at", Mon, 26 May 2014 14:02:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["oauth_access_token", nil], ["oauth_refresh_token", nil], ["oauth_expires_at", nil], ["updated_at", Mon, 26 May 2014 14:02:08 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.3ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.2ms) begin transaction Processing by SessionsController#failure as HTML Rendered /Users/wijet/projects/bs/bookingsync-engine/app/views/sessions/failure.html.erb within layouts/application (0.2ms) Completed 200 OK in 6ms (Views: 5.7ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 1ms (Views: 0.5ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/ Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/signed_out Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (4.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Sat, 09 Aug 2014 11:44:35 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Sat, 09 Aug 2014 11:44:35 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/admin Completed 302 Found in 15ms (ActiveRecord: 5.3ms)  (2.7ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Sat, 09 Aug 2014 11:44:35 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Sat, 09 Aug 2014 11:44:35 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 2ms (ActiveRecord: 0.4ms)  (2.2ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Sat, 09 Aug 2014 11:44:35 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Sat, 09 Aug 2014 11:44:35 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 2ms (ActiveRecord: 0.5ms)  (0.2ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "accounts" ("created_at", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "updated_at") VALUES (?, ?, ?, ?, ?) [["created_at", Sat, 09 Aug 2014 11:44:35 UTC +00:00], ["oauth_access_token", "token"], ["oauth_expires_at", "expires"], ["oauth_refresh_token", "refresh"], ["updated_at", Sat, 09 Aug 2014 11:44:35 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.5ms) UPDATE "accounts" SET "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["oauth_access_token", nil], ["oauth_refresh_token", nil], ["oauth_expires_at", nil], ["updated_at", Sat, 09 Aug 2014 11:44:35 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.4ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Sat, 09 Aug 2014 11:44:35 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Sat, 09 Aug 2014 11:44:35 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Sat, 09 Aug 2014 11:44:35 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.3ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Sat, 09 Aug 2014 11:44:35 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Sat, 09 Aug 2014 11:44:35 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Sat, 09 Aug 2014 11:44:35 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.3ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Sat, 09 Aug 2014 11:44:35 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Sat, 09 Aug 2014 11:44:35 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Sat, 09 Aug 2014 11:44:35 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.2ms) rollback transaction  (0.2ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (2.1ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Sat, 09 Aug 2014 11:45:27 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Sat, 09 Aug 2014 11:45:27 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 12ms (ActiveRecord: 3.0ms)  (2.1ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Sat, 09 Aug 2014 11:45:27 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Sat, 09 Aug 2014 11:45:27 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/ Completed 302 Found in 2ms (ActiveRecord: 0.4ms)  (0.3ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#create as HTML Parameters: {"provider"=>"bookingsync"} Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "name", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Sat, 09 Aug 2014 11:45:27 UTC +00:00], ["name", "business name"], ["oauth_access_token", "token"], ["oauth_expires_at", "expires at"], ["oauth_refresh_token", "refresh token"], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Sat, 09 Aug 2014 11:45:27 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/admin Completed 302 Found in 1ms (ActiveRecord: 0.3ms)  (0.3ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/ Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#destroy as HTML Redirected to http://test.host/signed_out Completed 302 Found in 0ms (ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 4ms (Views: 4.3ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Processing by SessionsController#failure as HTML Completed 200 OK in 0ms (Views: 0.4ms | ActiveRecord: 0.0ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.3ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Sat, 09 Aug 2014 11:45:27 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Sat, 09 Aug 2014 11:45:27 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.5ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Sat, 09 Aug 2014 11:45:27 UTC +00:00]]  (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 "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Sat, 09 Aug 2014 11:45:27 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Sat, 09 Aug 2014 11:45:27 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Sat, 09 Aug 2014 11:45:27 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.2ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "provider", "uid", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Sat, 09 Aug 2014 11:45:27 UTC +00:00], ["provider", "bookingsync"], ["uid", 123], ["updated_at", Sat, 09 Aug 2014 11:45:27 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.1ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."provider" = 'bookingsync' AND "accounts"."uid" = 123 ORDER BY "accounts"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "name" = ?, "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["name", "business name"], ["oauth_access_token", "token"], ["oauth_refresh_token", "refresh token"], ["oauth_expires_at", "expires at"], ["updated_at", Sat, 09 Aug 2014 11:45:27 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.2ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "accounts" ("created_at", "oauth_access_token", "oauth_expires_at", "oauth_refresh_token", "updated_at") VALUES (?, ?, ?, ?, ?) [["created_at", Sat, 09 Aug 2014 11:45:27 UTC +00:00], ["oauth_access_token", "token"], ["oauth_expires_at", "expires"], ["oauth_refresh_token", "refresh"], ["updated_at", Sat, 09 Aug 2014 11:45:27 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) UPDATE "accounts" SET "oauth_access_token" = ?, "oauth_refresh_token" = ?, "oauth_expires_at" = ?, "updated_at" = ? WHERE "accounts"."id" = 1 [["oauth_access_token", nil], ["oauth_refresh_token", nil], ["oauth_expires_at", nil], ["updated_at", Sat, 09 Aug 2014 11:45:27 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Account Load (0.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = ? LIMIT 1 [["id", 1]]  (0.3ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction