Connecting to database specified by database.yml  (0.1ms) select sqlite_version(*)  (1.8ms) CREATE TABLE "support_resource" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar(255), "draft" boolean DEFAULT 'f')  (1.9ms) CREATE TABLE "support_another_resource" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar(255), "draft" boolean DEFAULT 'f')   (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (36.5ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 2' LIMIT 1 Refinery::Menus::Menu Exists (0.6ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_2' LIMIT 1 SQL (18.1ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:37 UTC +00:00], ["permatitle", "menu_2"], ["title", "Menu 2"], ["updated_at", Tue, 26 Mar 2013 23:18:37 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.6ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) 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 "support_resource" ("draft", "title") VALUES (?, ?) [["draft", false], ["title", nil]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "support_resource" ("draft", "title") VALUES (?, ?) [["draft", true], ["title", nil]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "support_another_resource" ("draft", "title") VALUES (?, ?) [["draft", false], ["title", nil]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Refinery::Menus::LinkResource Load (0.1ms) SELECT "support_resource".* FROM "support_resource"   (0.6ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "support_resource" ("draft", "title") VALUES (?, ?) [["draft", false], ["title", nil]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "support_resource" ("draft", "title") VALUES (?, ?) [["draft", true], ["title", nil]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "support_another_resource" ("draft", "title") VALUES (?, ?) [["draft", false], ["title", nil]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Refinery::Menus::LinkResource Load (0.1ms) SELECT "support_resource".* FROM "support_resource"   (0.7ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "support_resource" ("draft", "title") VALUES (?, ?) [["draft", false], ["title", nil]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (21.4ms) INSERT INTO "support_resource" ("draft", "title") VALUES (?, ?) [["draft", true], ["title", nil]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "support_another_resource" ("draft", "title") VALUES (?, ?) [["draft", false], ["title", nil]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Refinery::Menus::LinkResource Load (0.1ms) SELECT "support_resource".* FROM "support_resource" WHERE "support_resource"."draft" = 'f' Refinery::Menus::LinkResource Load (0.1ms) SELECT "support_resource".* FROM "support_resource" WHERE "support_resource"."draft" = 'f'  (53.4ms) rollback transaction  (0.1ms) begin transaction  (0.2ms) SAVEPOINT active_record_1 SQL (33.8ms) INSERT INTO "support_resource" ("draft", "title") VALUES (?, ?) [["draft", false], ["title", nil]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (94.0ms) INSERT INTO "support_resource" ("draft", "title") VALUES (?, ?) [["draft", true], ["title", nil]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "support_another_resource" ("draft", "title") VALUES (?, ?) [["draft", false], ["title", nil]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Refinery::Menus::LinkResource Load (0.2ms) SELECT "support_resource".* FROM "support_resource" WHERE "support_resource"."draft" = 'f' Refinery::Menus::LinkResource Load (0.1ms) SELECT "support_resource".* FROM "support_resource" WHERE "support_resource"."draft" = 'f'  (18.7ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 3' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_3' LIMIT 1 SQL (0.4ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:37 UTC +00:00], ["permatitle", "menu_3"], ["title", "Menu 3"], ["updated_at", Tue, 26 Mar 2013 23:18:37 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (288.9ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (181.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 4' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_4' LIMIT 1 SQL (1.0ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:38 UTC +00:00], ["permatitle", "menu_4"], ["title", "Menu 4"], ["updated_at", Tue, 26 Mar 2013 23:18:38 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (116.3ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 5' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_5' LIMIT 1 SQL (0.4ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:38 UTC +00:00], ["permatitle", "menu_5"], ["title", "Menu 5"], ["updated_at", Tue, 26 Mar 2013 23:18:38 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (22.8ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (50.4ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 6' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_6' LIMIT 1 SQL (0.7ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:38 UTC +00:00], ["permatitle", "menu_6"], ["title", "Menu 6"], ["updated_at", Tue, 26 Mar 2013 23:18:38 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (25.0ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (13.7ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 7' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_7' LIMIT 1 SQL (0.5ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:38 UTC +00:00], ["permatitle", "menu_7"], ["title", "Menu 7"], ["updated_at", Tue, 26 Mar 2013 23:18:38 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (25.3ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.2ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 8' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_8' LIMIT 1 SQL (0.5ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:38 UTC +00:00], ["permatitle", "menu_8"], ["title", "Menu 8"], ["updated_at", Tue, 26 Mar 2013 23:18:38 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (36.2ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 9' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_9' LIMIT 1 SQL (0.4ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:38 UTC +00:00], ["permatitle", "menu_9"], ["title", "Menu 9"], ["updated_at", Tue, 26 Mar 2013 23:18:38 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (1.3ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (17.3ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 10' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_10' LIMIT 1 SQL (0.6ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:38 UTC +00:00], ["permatitle", "menu_10"], ["title", "Menu 10"], ["updated_at", Tue, 26 Mar 2013 23:18:38 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (23.7ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (30.2ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 11' LIMIT 1 Refinery::Menus::Menu Exists (0.2ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_11' LIMIT 1 SQL (0.7ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:38 UTC +00:00], ["permatitle", "menu_11"], ["title", "Menu 11"], ["updated_at", Tue, 26 Mar 2013 23:18:38 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (9.7ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (13.2ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 12' LIMIT 1 Refinery::Menus::Menu Exists (0.2ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_12' LIMIT 1 SQL (71.1ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:38 UTC +00:00], ["permatitle", "menu_12"], ["title", "Menu 12"], ["updated_at", Tue, 26 Mar 2013 23:18:38 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (58.7ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 13' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_13' LIMIT 1 SQL (0.4ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:38 UTC +00:00], ["permatitle", "menu_13"], ["title", "Menu 13"], ["updated_at", Tue, 26 Mar 2013 23:18:38 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (19.7ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (14.7ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 14' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_14' LIMIT 1 SQL (74.5ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:38 UTC +00:00], ["permatitle", "menu_14"], ["title", "Menu 14"], ["updated_at", Tue, 26 Mar 2013 23:18:38 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (26.6ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (26.0ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 15' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_15' LIMIT 1 SQL (0.4ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:38 UTC +00:00], ["permatitle", "menu_15"], ["title", "Menu 15"], ["updated_at", Tue, 26 Mar 2013 23:18:38 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (36.9ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (26.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 16' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_16' LIMIT 1 SQL (0.4ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00], ["permatitle", "menu_16"], ["title", "Menu 16"], ["updated_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (16.6ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (17.7ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 17' LIMIT 1 Refinery::Menus::Menu Exists (0.2ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_17' LIMIT 1 SQL (0.6ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00], ["permatitle", "menu_17"], ["title", "Menu 17"], ["updated_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (26.6ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 18' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_18' LIMIT 1 SQL (0.4ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00], ["permatitle", "menu_18"], ["title", "Menu 18"], ["updated_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (29.6ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.2ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 19' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_19' LIMIT 1 SQL (0.5ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00], ["permatitle", "menu_19"], ["title", "Menu 19"], ["updated_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (15.0ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (28.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 20' LIMIT 1 Refinery::Menus::Menu Exists (0.2ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_20' LIMIT 1 SQL (0.7ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00], ["permatitle", "menu_20"], ["title", "Menu 20"], ["updated_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (14.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (28.7ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 21' LIMIT 1 Refinery::Menus::Menu Exists (0.2ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_21' LIMIT 1 SQL (0.7ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00], ["permatitle", "menu_21"], ["title", "Menu 21"], ["updated_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (25.9ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (13.0ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 22' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_22' LIMIT 1 SQL (0.5ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00], ["permatitle", "menu_22"], ["title", "Menu 22"], ["updated_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.7ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 23' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_23' LIMIT 1 SQL (0.3ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00], ["permatitle", "menu_23"], ["title", "Menu 23"], ["updated_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.6ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 24' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_24' LIMIT 1 SQL (0.3ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00], ["permatitle", "menu_24"], ["title", "Menu 24"], ["updated_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.7ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 25' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_25' LIMIT 1 SQL (0.4ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00], ["permatitle", "menu_25"], ["title", "Menu 25"], ["updated_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.5ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 26' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_26' LIMIT 1 SQL (0.3ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00], ["permatitle", "menu_26"], ["title", "Menu 26"], ["updated_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.6ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 27' LIMIT 1 Refinery::Menus::Menu Exists (0.0ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_27' LIMIT 1 SQL (33.2ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00], ["permatitle", "menu_27"], ["title", "Menu 27"], ["updated_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.9ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 28' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_28' LIMIT 1 SQL (0.3ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00], ["permatitle", "menu_28"], ["title", "Menu 28"], ["updated_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.8ms) rollback transaction  (0.1ms) 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  (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.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.0ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 29' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_29' LIMIT 1  (0.0ms) rollback transaction  (0.0ms) begin transaction Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = '' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_30' LIMIT 1  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'First Menu' LIMIT 1 Refinery::Menus::Menu Exists (0.0ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'first-menu' LIMIT 1 SQL (0.4ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00], ["permatitle", "first-menu"], ["title", "First Menu"], ["updated_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'First Menu' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'second-menu' LIMIT 1  (0.5ms) rollback transaction  (0.0ms) begin transaction Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 30' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = '' LIMIT 1  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'First Menu' LIMIT 1 Refinery::Menus::Menu Exists (0.0ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'first-menu' LIMIT 1 SQL (0.4ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00], ["permatitle", "first-menu"], ["title", "First Menu"], ["updated_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Second Menu' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'first-menu' LIMIT 1  (0.6ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 31' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_31' LIMIT 1 SQL (0.3ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00], ["permatitle", "menu_31"], ["title", "Menu 31"], ["updated_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::MenuLink Load (13.2ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" ORDER BY "refinery_menus_links"."rgt" desc LIMIT 1 SQL (16.5ms) INSERT INTO "refinery_menus_links" ("class_attribute", "custom_url", "depth", "id_attribute", "label", "lft", "menu_match", "parent_id", "refinery_menu_id", "resource_id", "resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", nil], ["depth", nil], ["id_attribute", nil], ["label", "label"], ["lft", 1], ["menu_match", nil], ["parent_id", nil], ["refinery_menu_id", 1], ["resource_id", nil], ["resource_type", nil], ["rgt", 2], ["title_attribute", nil]] Refinery::Menus::MenuLink Load (0.2ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 1]] SQL (0.2ms) UPDATE "refinery_menus_links" SET "depth" = 0 WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = 1 ORDER BY "refinery_menus_links"."lft")  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE ("refinery_menus"."title" = 'Menu 31' AND "refinery_menus"."id" != 1) LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE ("refinery_menus"."permatitle" = 'menu_31' AND "refinery_menus"."id" != 1) LIMIT 1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SELECT COUNT(*) FROM "refinery_menus_links" WHERE "refinery_menus_links"."refinery_menu_id" = 1  (0.7ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 32' LIMIT 1 Refinery::Menus::Menu Exists (0.0ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_32' LIMIT 1 SQL (0.4ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00], ["permatitle", "menu_32"], ["title", "Menu 32"], ["updated_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" ORDER BY "refinery_menus_links"."rgt" desc LIMIT 1 SQL (0.3ms) INSERT INTO "refinery_menus_links" ("class_attribute", "custom_url", "depth", "id_attribute", "label", "lft", "menu_match", "parent_id", "refinery_menu_id", "resource_id", "resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", nil], ["depth", nil], ["id_attribute", nil], ["label", "label"], ["lft", 1], ["menu_match", nil], ["parent_id", nil], ["refinery_menu_id", 1], ["resource_id", nil], ["resource_type", nil], ["rgt", 2], ["title_attribute", nil]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 1]] SQL (0.1ms) UPDATE "refinery_menus_links" SET "depth" = 0 WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = 1 ORDER BY "refinery_menus_links"."lft")  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Load (38.6ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = 1 LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE ("refinery_menus"."title" = 'Menu 32' AND "refinery_menus"."id" != 1) LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE ("refinery_menus"."permatitle" = 'menu_32' AND "refinery_menus"."id" != 1) LIMIT 1 Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" ORDER BY "refinery_menus_links"."rgt" desc LIMIT 1 SQL (0.2ms) INSERT INTO "refinery_menus_links" ("class_attribute", "custom_url", "depth", "id_attribute", "label", "lft", "menu_match", "parent_id", "refinery_menu_id", "resource_id", "resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", "/myurl"], ["depth", nil], ["id_attribute", nil], ["label", "/myurl"], ["lft", 3], ["menu_match", nil], ["parent_id", nil], ["refinery_menu_id", 1], ["resource_id", nil], ["resource_type", nil], ["rgt", 4], ["title_attribute", nil]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 2]] SQL (0.1ms) UPDATE "refinery_menus_links" SET "depth" = 0 WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = 2 ORDER BY "refinery_menus_links"."lft")  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SELECT COUNT(*) FROM "refinery_menus_links" WHERE "refinery_menus_links"."refinery_menu_id" = 1  (0.6ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 33' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_33' LIMIT 1 SQL (0.3ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00], ["permatitle", "menu_33"], ["title", "Menu 33"], ["updated_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" ORDER BY "refinery_menus_links"."rgt" desc LIMIT 1 SQL (0.3ms) INSERT INTO "refinery_menus_links" ("class_attribute", "custom_url", "depth", "id_attribute", "label", "lft", "menu_match", "parent_id", "refinery_menu_id", "resource_id", "resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", nil], ["depth", nil], ["id_attribute", nil], ["label", "label"], ["lft", 1], ["menu_match", nil], ["parent_id", nil], ["refinery_menu_id", 1], ["resource_id", nil], ["resource_type", nil], ["rgt", 2], ["title_attribute", nil]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 1]] SQL (0.1ms) UPDATE "refinery_menus_links" SET "depth" = 0 WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = 1 ORDER BY "refinery_menus_links"."lft")  (0.0ms) RELEASE SAVEPOINT active_record_1 Refinery::Menus::Menu Load (0.1ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = ? LIMIT 1 [["id", 1]] Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."refinery_menu_id" = 1 AND "refinery_menus_links"."id" IN (1) ORDER BY lft ASC  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE ("refinery_menus"."title" = 'Menu 33' AND "refinery_menus"."id" != 1) LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE ("refinery_menus"."permatitle" = 'menu_33' AND "refinery_menus"."id" != 1) LIMIT 1 Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."refinery_menu_id" = 1 ORDER BY lft ASC Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links"."lft", "refinery_menus_links"."rgt", "refinery_menus_links"."parent_id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 1]] Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" >= 1 AND "refinery_menus_links"."lft" < 2) AND ("refinery_menus_links".id != 1) ORDER BY "refinery_menus_links"."lft" SQL (0.1ms) UPDATE "refinery_menus_links" SET "lft" = ("lft" - 2) WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" > 2) ORDER BY "refinery_menus_links"."lft") SQL (0.1ms) UPDATE "refinery_menus_links" SET "rgt" = ("rgt" - 2) WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE ("refinery_menus_links"."rgt" > 2) ORDER BY "refinery_menus_links"."lft") SQL (0.1ms) DELETE FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? [["id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SELECT COUNT(*) FROM "refinery_menus_links" WHERE "refinery_menus_links"."refinery_menu_id" = 1  (0.7ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 34' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_34' LIMIT 1 SQL (0.4ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00], ["permatitle", "menu_34"], ["title", "Menu 34"], ["updated_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" ORDER BY "refinery_menus_links"."rgt" desc LIMIT 1 SQL (0.3ms) INSERT INTO "refinery_menus_links" ("class_attribute", "custom_url", "depth", "id_attribute", "label", "lft", "menu_match", "parent_id", "refinery_menu_id", "resource_id", "resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", nil], ["depth", nil], ["id_attribute", nil], ["label", "label"], ["lft", 1], ["menu_match", nil], ["parent_id", nil], ["refinery_menu_id", 1], ["resource_id", nil], ["resource_type", nil], ["rgt", 2], ["title_attribute", nil]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 1]] SQL (0.1ms) UPDATE "refinery_menus_links" SET "depth" = 0 WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = 1 ORDER BY "refinery_menus_links"."lft")  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" ORDER BY "refinery_menus_links"."rgt" desc LIMIT 1 SQL (0.2ms) INSERT INTO "refinery_menus_links" ("class_attribute", "custom_url", "depth", "id_attribute", "label", "lft", "menu_match", "parent_id", "refinery_menu_id", "resource_id", "resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", nil], ["depth", nil], ["id_attribute", nil], ["label", "label"], ["lft", 3], ["menu_match", nil], ["parent_id", 1], ["refinery_menu_id", 1], ["resource_id", nil], ["resource_type", nil], ["rgt", 4], ["title_attribute", nil]] Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? ORDER BY "refinery_menus_links"."lft" LIMIT 1 [["id", 1]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links"."lft", "refinery_menus_links"."rgt", "refinery_menus_links"."parent_id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 2]] SQL (0.1ms) UPDATE "refinery_menus_links" SET "lft" = CASE WHEN "lft" BETWEEN 2 AND 2 THEN "lft" + 4 - 2 WHEN "lft" BETWEEN 3 AND 4 THEN "lft" + 2 - 3 ELSE "lft" END, "rgt" = CASE WHEN "rgt" BETWEEN 2 AND 2 THEN "rgt" + 4 - 2 WHEN "rgt" BETWEEN 3 AND 4 THEN "rgt" + 2 - 3 ELSE "rgt" END, "parent_id" = CASE WHEN id = 2 THEN 1 ELSE "parent_id" END WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" ORDER BY "refinery_menus_links"."lft") Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links"."lft", "refinery_menus_links"."rgt", "refinery_menus_links"."parent_id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 1]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 2]]  (0.1ms) SELECT COUNT(*) FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" <= 2 AND "refinery_menus_links"."rgt" >= 3) AND ("refinery_menus_links".id != 2) SQL (0.1ms) UPDATE "refinery_menus_links" SET "depth" = 1 WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = 2 ORDER BY "refinery_menus_links"."lft")  (0.1ms) SELECT COUNT(*) FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" <= 2 AND "refinery_menus_links"."rgt" >= 3) AND ("refinery_menus_links".id != 2) Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" >= 2 AND "refinery_menus_links"."lft" < 3) AND ("refinery_menus_links".id != 2) ORDER BY "refinery_menus_links"."lft" Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links"."lft", "refinery_menus_links"."rgt", "refinery_menus_links"."parent_id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 2]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 2]]  (0.1ms) SELECT COUNT(*) FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" <= 2 AND "refinery_menus_links"."rgt" >= 3) AND ("refinery_menus_links".id != 2) SQL (0.1ms) UPDATE "refinery_menus_links" SET "depth" = 1 WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = 2 ORDER BY "refinery_menus_links"."lft")  (0.1ms) SELECT COUNT(*) FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" <= 2 AND "refinery_menus_links"."rgt" >= 3) AND ("refinery_menus_links".id != 2)  (0.0ms) RELEASE SAVEPOINT active_record_1 Refinery::Menus::Menu Load (0.0ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = ? LIMIT 1 [["id", 1]] Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."refinery_menu_id" = 1 ORDER BY lft ASC  (0.8ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 35' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_35' LIMIT 1 SQL (0.4ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00], ["permatitle", "menu_35"], ["title", "Menu 35"], ["updated_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" ORDER BY "refinery_menus_links"."rgt" desc LIMIT 1 SQL (0.3ms) INSERT INTO "refinery_menus_links" ("class_attribute", "custom_url", "depth", "id_attribute", "label", "lft", "menu_match", "parent_id", "refinery_menu_id", "resource_id", "resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", nil], ["depth", nil], ["id_attribute", nil], ["label", "label"], ["lft", 1], ["menu_match", nil], ["parent_id", nil], ["refinery_menu_id", 1], ["resource_id", nil], ["resource_type", nil], ["rgt", 2], ["title_attribute", nil]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 1]] SQL (0.1ms) UPDATE "refinery_menus_links" SET "depth" = 0 WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = 1 ORDER BY "refinery_menus_links"."lft")  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" ORDER BY "refinery_menus_links"."rgt" desc LIMIT 1 SQL (0.2ms) INSERT INTO "refinery_menus_links" ("class_attribute", "custom_url", "depth", "id_attribute", "label", "lft", "menu_match", "parent_id", "refinery_menu_id", "resource_id", "resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", nil], ["depth", nil], ["id_attribute", nil], ["label", "label"], ["lft", 3], ["menu_match", nil], ["parent_id", 1], ["refinery_menu_id", 1], ["resource_id", nil], ["resource_type", nil], ["rgt", 4], ["title_attribute", nil]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? ORDER BY "refinery_menus_links"."lft" LIMIT 1 [["id", 1]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links"."lft", "refinery_menus_links"."rgt", "refinery_menus_links"."parent_id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 2]] SQL (0.1ms) UPDATE "refinery_menus_links" SET "lft" = CASE WHEN "lft" BETWEEN 2 AND 2 THEN "lft" + 4 - 2 WHEN "lft" BETWEEN 3 AND 4 THEN "lft" + 2 - 3 ELSE "lft" END, "rgt" = CASE WHEN "rgt" BETWEEN 2 AND 2 THEN "rgt" + 4 - 2 WHEN "rgt" BETWEEN 3 AND 4 THEN "rgt" + 2 - 3 ELSE "rgt" END, "parent_id" = CASE WHEN id = 2 THEN 1 ELSE "parent_id" END WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" ORDER BY "refinery_menus_links"."lft") Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links"."lft", "refinery_menus_links"."rgt", "refinery_menus_links"."parent_id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 1]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 2]]  (0.1ms) SELECT COUNT(*) FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" <= 2 AND "refinery_menus_links"."rgt" >= 3) AND ("refinery_menus_links".id != 2) SQL (0.1ms) UPDATE "refinery_menus_links" SET "depth" = 1 WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = 2 ORDER BY "refinery_menus_links"."lft")  (0.1ms) SELECT COUNT(*) FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" <= 2 AND "refinery_menus_links"."rgt" >= 3) AND ("refinery_menus_links".id != 2) Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" >= 2 AND "refinery_menus_links"."lft" < 3) AND ("refinery_menus_links".id != 2) ORDER BY "refinery_menus_links"."lft" Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links"."lft", "refinery_menus_links"."rgt", "refinery_menus_links"."parent_id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 2]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 2]]  (0.1ms) SELECT COUNT(*) FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" <= 2 AND "refinery_menus_links"."rgt" >= 3) AND ("refinery_menus_links".id != 2) SQL (0.1ms) UPDATE "refinery_menus_links" SET "depth" = 1 WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = 2 ORDER BY "refinery_menus_links"."lft")  (0.1ms) SELECT COUNT(*) FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" <= 2 AND "refinery_menus_links"."rgt" >= 3) AND ("refinery_menus_links".id != 2)  (0.0ms) RELEASE SAVEPOINT active_record_1 Refinery::Menus::Menu Load (0.0ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = ? LIMIT 1 [["id", 1]] Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."refinery_menu_id" = 1 ORDER BY lft ASC  (0.9ms) rollback transaction  (0.1ms) begin transaction Refinery::Role Load (0.3ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1  (0.0ms) SAVEPOINT active_record_1 Refinery::Role Exists (0.1ms) SELECT 1 AS one FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 SQL (18.1ms) INSERT INTO "refinery_roles" ("title") VALUES (?) [["title", "Refinery"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 Refinery::User Exists (17.9ms) SELECT 1 AS one FROM "refinery_users" WHERE "refinery_users"."email" = 'refinerycms@refinerycms.com' LIMIT 1 Refinery::User Exists (0.2ms) SELECT 1 AS one FROM "refinery_users" WHERE "refinery_users"."username" = 'refinerycms' LIMIT 1 Binary data inserted for `string` type on column `encrypted_password` SQL (1.2ms) INSERT INTO "refinery_users" ("created_at", "current_sign_in_at", "current_sign_in_ip", "email", "encrypted_password", "last_sign_in_at", "last_sign_in_ip", "remember_created_at", "reset_password_sent_at", "reset_password_token", "sign_in_count", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00], ["current_sign_in_at", nil], ["current_sign_in_ip", nil], ["email", "refinerycms@refinerycms.com"], ["encrypted_password", "$2a$04$dakXqTZq8M2H5e6kLcZGOeQzP.0EUIY9yn9vt6mH1L1E2G5H4NeKW"], ["last_sign_in_at", nil], ["last_sign_in_ip", nil], ["remember_created_at", nil], ["reset_password_sent_at", nil], ["reset_password_token", nil], ["sign_in_count", nil], ["updated_at", Tue, 26 Mar 2013 23:18:39 UTC +00:00], ["username", "refinerycms"]]  (1.4ms) INSERT INTO "refinery_roles_users" ("user_id", "role_id") VALUES (1, 1)  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (1.2ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_core"], ["position", 0], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_dialogs"], ["position", 1], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_menus"], ["position", 2], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_users"], ["position", 3], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_dashboard"], ["position", 4], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_images"], ["position", 5], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_files"], ["position", 6], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_pages"], ["position", 7], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_i18n"], ["position", 8], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/refinery/login" for 127.0.0.1 at 2013-03-27 00:18:39 +0100 Processing by Refinery::SessionsController#new as HTML Parameters: {"locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_form_actions.html.erb (3.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-authentication-2.0.9/app/views/refinery/sessions/new.html.erb within refinery/layouts/login (72.6ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.8ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (2.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (6.5ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.4ms) Completed 200 OK in 206ms (Views: 171.4ms | ActiveRecord: 0.3ms) Started POST "/refinery/users/login" for 127.0.0.1 at 2013-03-27 00:18:40 +0100 Processing by Refinery::SessionsController#create as HTML Parameters: {"utf8"=>"✓", "refinery_user"=>{"login"=>"refinerycms", "password"=>"[FILTERED]", "remember_me"=>"0"}, "locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE (username = 'refinerycms' OR email = 'refinerycms') LIMIT 1  (0.0ms) SAVEPOINT active_record_1  (0.1ms) UPDATE "refinery_users" SET "last_sign_in_at" = '2013-03-26 23:18:40.548470', "current_sign_in_at" = '2013-03-26 23:18:40.548470', "last_sign_in_ip" = '127.0.0.1', "current_sign_in_ip" = '127.0.0.1', "sign_in_count" = 1, "updated_at" = '2013-03-26 23:18:40.549298' WHERE "refinery_users"."id" = 1  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://www.example.com/refinery Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Completed 302 Found in 57ms (ActiveRecord: 0.1ms) Started GET "/refinery" for 127.0.0.1 at 2013-03-27 00:18:40 +0100 Processing by Refinery::Admin::DashboardController#index as HTML Parameters: {"locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.1ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" ORDER BY updated_at DESC LIMIT 7 Refinery::Image Load (6.3ms) SELECT "refinery_images".* FROM "refinery_images" ORDER BY updated_at DESC LIMIT 7 Refinery::Resource Load (0.9ms) SELECT "refinery_resources".* FROM "refinery_resources" ORDER BY updated_at DESC LIMIT 7 Refinery::Page Load (0.7ms) SELECT "refinery_pages".* FROM "refinery_pages" ORDER BY updated_at DESC LIMIT 7 Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_recent_activity.html.erb (121.9ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_records.html.erb (162.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_additional_dashboard_menu_items.html.erb (0.5ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_actions.html.erb (18.4ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/index.html.erb within layouts/refinery/admin (197.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.5ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (2.9ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_site_bar.html.erb (2.6ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_menu.html.erb (3.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_no_script.html.erb (0.7ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.2ms) Completed 200 OK in 269ms (Views: 251.9ms | ActiveRecord: 8.4ms) Started GET "/menus/refinery/menus" for 127.0.0.1 at 2013-03-27 00:18:40 +0100 Processing by Refinery::Menus::Admin::MenusController#index as HTML Parameters: {"locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.1ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1  (0.1ms) SELECT COUNT(*) FROM "refinery_menus" Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_records.html.erb (4.8ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/index.html.erb within layouts/refinery/admin (20.6ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (2.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (3.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_site_bar.html.erb (1.7ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_menu.html.erb (2.4ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_no_script.html.erb (0.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.1ms) Completed 200 OK in 60ms (Views: 53.0ms | ActiveRecord: 0.6ms)  (0.8ms) rollback transaction  (0.0ms) begin transaction Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1  (0.0ms) SAVEPOINT active_record_1 Refinery::Role Exists (0.0ms) SELECT 1 AS one FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 SQL (0.2ms) INSERT INTO "refinery_roles" ("title") VALUES (?) [["title", "Refinery"]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Refinery::User Exists (0.1ms) SELECT 1 AS one FROM "refinery_users" WHERE "refinery_users"."email" = 'refinerycms@refinerycms.com' LIMIT 1 Refinery::User Exists (0.1ms) SELECT 1 AS one FROM "refinery_users" WHERE "refinery_users"."username" = 'refinerycms' LIMIT 1 Binary data inserted for `string` type on column `encrypted_password` SQL (29.9ms) INSERT INTO "refinery_users" ("created_at", "current_sign_in_at", "current_sign_in_ip", "email", "encrypted_password", "last_sign_in_at", "last_sign_in_ip", "remember_created_at", "reset_password_sent_at", "reset_password_token", "sign_in_count", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:40 UTC +00:00], ["current_sign_in_at", nil], ["current_sign_in_ip", nil], ["email", "refinerycms@refinerycms.com"], ["encrypted_password", "$2a$04$qdO3mNOeZoKsihYENsR8POeqWDqlupf/nQP3wV5xoC0nyhe72oVPG"], ["last_sign_in_at", nil], ["last_sign_in_ip", nil], ["remember_created_at", nil], ["reset_password_sent_at", nil], ["reset_password_token", nil], ["sign_in_count", nil], ["updated_at", Tue, 26 Mar 2013 23:18:40 UTC +00:00], ["username", "refinerycms"]]  (0.2ms) INSERT INTO "refinery_roles_users" ("user_id", "role_id") VALUES (1, 1)  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_core"], ["position", 0], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_dialogs"], ["position", 1], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_menus"], ["position", 2], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_users"], ["position", 3], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_dashboard"], ["position", 4], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_images"], ["position", 5], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_files"], ["position", 6], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_pages"], ["position", 7], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_i18n"], ["position", 8], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/refinery/login" for 127.0.0.1 at 2013-03-27 00:18:41 +0100 Processing by Refinery::SessionsController#new as HTML Parameters: {"locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_form_actions.html.erb (0.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.4ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (2.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.0ms) Completed 200 OK in 10ms (Views: 6.6ms | ActiveRecord: 0.2ms) Started POST "/refinery/users/login" for 127.0.0.1 at 2013-03-27 00:18:41 +0100 Processing by Refinery::SessionsController#create as HTML Parameters: {"utf8"=>"✓", "refinery_user"=>{"login"=>"refinerycms", "password"=>"[FILTERED]", "remember_me"=>"0"}, "locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE (username = 'refinerycms' OR email = 'refinerycms') LIMIT 1  (0.0ms) SAVEPOINT active_record_1  (0.1ms) UPDATE "refinery_users" SET "last_sign_in_at" = '2013-03-26 23:18:41.063458', "current_sign_in_at" = '2013-03-26 23:18:41.063458', "last_sign_in_ip" = '127.0.0.1', "current_sign_in_ip" = '127.0.0.1', "sign_in_count" = 1, "updated_at" = '2013-03-26 23:18:41.064024' WHERE "refinery_users"."id" = 1  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://www.example.com/refinery Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Completed 302 Found in 12ms (ActiveRecord: 0.1ms) Started GET "/refinery" for 127.0.0.1 at 2013-03-27 00:18:41 +0100 Processing by Refinery::Admin::DashboardController#index as HTML Parameters: {"locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.1ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" ORDER BY updated_at DESC LIMIT 7 Refinery::Image Load (0.1ms) SELECT "refinery_images".* FROM "refinery_images" ORDER BY updated_at DESC LIMIT 7 Refinery::Resource Load (0.1ms) SELECT "refinery_resources".* FROM "refinery_resources" ORDER BY updated_at DESC LIMIT 7 Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" ORDER BY updated_at DESC LIMIT 7 Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_recent_activity.html.erb (1.4ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_records.html.erb (1.7ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_additional_dashboard_menu_items.html.erb (0.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_actions.html.erb (7.7ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (1.9ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_site_bar.html.erb (1.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_menu.html.erb (2.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_no_script.html.erb (0.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.2ms) Completed 200 OK in 24ms (Views: 17.3ms | ActiveRecord: 0.9ms)  (0.1ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 36' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_36' LIMIT 1 SQL (14.1ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:41 UTC +00:00], ["permatitle", "menu_36"], ["title", "Menu 36"], ["updated_at", Tue, 26 Mar 2013 23:18:41 UTC +00:00]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/menus/refinery/menus" for 127.0.0.1 at 2013-03-27 00:18:41 +0100 Processing by Refinery::Menus::Admin::MenusController#index as HTML Parameters: {"locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.1ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1  (0.1ms) SELECT COUNT(*) FROM "refinery_menus"  Refinery::Menus::Menu Load (0.2ms) SELECT "refinery_menus".* FROM "refinery_menus" LIMIT 20 OFFSET 0 Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."refinery_menu_id" IN (1) ORDER BY lft ASC Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_menu.html.erb (2.6ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_menus.html.erb (44.3ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_records.html.erb (64.8ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (2.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_site_bar.html.erb (1.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_menu.html.erb (2.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_no_script.html.erb (0.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.0ms) Completed 200 OK in 78ms (Views: 71.8ms | ActiveRecord: 0.8ms)  (0.9ms) rollback transaction  (0.1ms) begin transaction Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1  (0.0ms) SAVEPOINT active_record_1 Refinery::Role Exists (0.0ms) SELECT 1 AS one FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 SQL (0.2ms) INSERT INTO "refinery_roles" ("title") VALUES (?) [["title", "Refinery"]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Refinery::User Exists (0.1ms) SELECT 1 AS one FROM "refinery_users" WHERE "refinery_users"."email" = 'refinerycms@refinerycms.com' LIMIT 1 Refinery::User Exists (0.1ms) SELECT 1 AS one FROM "refinery_users" WHERE "refinery_users"."username" = 'refinerycms' LIMIT 1 Binary data inserted for `string` type on column `encrypted_password` SQL (0.4ms) INSERT INTO "refinery_users" ("created_at", "current_sign_in_at", "current_sign_in_ip", "email", "encrypted_password", "last_sign_in_at", "last_sign_in_ip", "remember_created_at", "reset_password_sent_at", "reset_password_token", "sign_in_count", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:41 UTC +00:00], ["current_sign_in_at", nil], ["current_sign_in_ip", nil], ["email", "refinerycms@refinerycms.com"], ["encrypted_password", "$2a$04$zVJIURq6rDYgiRlo.K1hOuz.NL1CXbyZ.Vyelc1rcMKLXuqd5DQmq"], ["last_sign_in_at", nil], ["last_sign_in_ip", nil], ["remember_created_at", nil], ["reset_password_sent_at", nil], ["reset_password_token", nil], ["sign_in_count", nil], ["updated_at", Tue, 26 Mar 2013 23:18:41 UTC +00:00], ["username", "refinerycms"]]  (0.1ms) INSERT INTO "refinery_roles_users" ("user_id", "role_id") VALUES (1, 1)  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_core"], ["position", 0], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_dialogs"], ["position", 1], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_menus"], ["position", 2], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_users"], ["position", 3], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_dashboard"], ["position", 4], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_images"], ["position", 5], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_files"], ["position", 6], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_pages"], ["position", 7], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_i18n"], ["position", 8], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/refinery/login" for 127.0.0.1 at 2013-03-27 00:18:41 +0100 Processing by Refinery::SessionsController#new as HTML Parameters: {"locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_form_actions.html.erb (0.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (43.8ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.1ms) Completed 200 OK in 51ms (Views: 48.0ms | ActiveRecord: 0.2ms) Started POST "/refinery/users/login" for 127.0.0.1 at 2013-03-27 00:18:41 +0100 Processing by Refinery::SessionsController#create as HTML Parameters: {"utf8"=>"✓", "refinery_user"=>{"login"=>"refinerycms", "password"=>"[FILTERED]", "remember_me"=>"0"}, "locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE (username = 'refinerycms' OR email = 'refinerycms') LIMIT 1  (0.0ms) SAVEPOINT active_record_1  (0.1ms) UPDATE "refinery_users" SET "last_sign_in_at" = '2013-03-26 23:18:41.292481', "current_sign_in_at" = '2013-03-26 23:18:41.292481', "last_sign_in_ip" = '127.0.0.1', "current_sign_in_ip" = '127.0.0.1', "sign_in_count" = 1, "updated_at" = '2013-03-26 23:18:41.293052' WHERE "refinery_users"."id" = 1  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://www.example.com/refinery Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Completed 302 Found in 12ms (ActiveRecord: 0.1ms) Started GET "/refinery" for 127.0.0.1 at 2013-03-27 00:18:41 +0100 Processing by Refinery::Admin::DashboardController#index as HTML Parameters: {"locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.1ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Refinery::User Load (0.2ms) SELECT "refinery_users".* FROM "refinery_users" ORDER BY updated_at DESC LIMIT 7 Refinery::Image Load (0.1ms) SELECT "refinery_images".* FROM "refinery_images" ORDER BY updated_at DESC LIMIT 7 Refinery::Resource Load (0.1ms) SELECT "refinery_resources".* FROM "refinery_resources" ORDER BY updated_at DESC LIMIT 7 Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" ORDER BY updated_at DESC LIMIT 7 Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_recent_activity.html.erb (1.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_records.html.erb (1.5ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_additional_dashboard_menu_items.html.erb (0.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_actions.html.erb (7.9ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (2.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_site_bar.html.erb (1.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_menu.html.erb (2.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_no_script.html.erb (0.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.2ms) Completed 200 OK in 24ms (Views: 17.1ms | ActiveRecord: 0.7ms)  (0.1ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 37' LIMIT 1 Refinery::Menus::Menu Exists (0.0ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_37' LIMIT 1 SQL (0.2ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:41 UTC +00:00], ["permatitle", "menu_37"], ["title", "Menu 37"], ["updated_at", Tue, 26 Mar 2013 23:18:41 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/menus/refinery/menus" for 127.0.0.1 at 2013-03-27 00:18:41 +0100 Processing by Refinery::Menus::Admin::MenusController#index as HTML Parameters: {"locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.1ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1  (0.1ms) SELECT COUNT(*) FROM "refinery_menus" Refinery::Menus::Menu Load (0.1ms) SELECT "refinery_menus".* FROM "refinery_menus" LIMIT 20 OFFSET 0 Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."refinery_menu_id" IN (1) ORDER BY lft ASC Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_menu.html.erb (1.4ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_menus.html.erb (2.8ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_records.html.erb (3.8ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (1.9ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_site_bar.html.erb (1.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_menu.html.erb (2.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_no_script.html.erb (0.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.0ms) Completed 200 OK in 16ms (Views: 11.0ms | ActiveRecord: 0.5ms) Started GET "/menus/refinery/menus/1/edit" for 127.0.0.1 at 2013-03-27 00:18:41 +0100 Processing by Refinery::Menus::Admin::MenusController#edit as HTML Parameters: {"id"=>"1", "locale"=>:en} Refinery::Menus::Menu Load (0.1ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = ? LIMIT 1 [["id", "1"]] Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."refinery_menu_id" IN (1) ORDER BY lft ASC Refinery::Role Load (0.0ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.1ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Refinery::Menus::LinkResource Load (0.2ms) SELECT "support_resource".* FROM "support_resource" Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_resource_link_editor_box.html.erb (6.9ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_form_actions.html.erb (34.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_error_messages.html.erb (1.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_form_actions.html.erb (3.1ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_form.html.erb (23.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_make_sortable.html.erb (3.7ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (1.8ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_site_bar.html.erb (1.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_menu.html.erb (2.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_no_script.html.erb (0.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.1ms) Completed 200 OK in 96ms (Views: 89.6ms | ActiveRecord: 0.7ms) Started PUT "/menus/refinery/menus/1" for 127.0.0.1 at 2013-03-27 00:18:41 +0100 Processing by Refinery::Menus::Admin::MenusController#update as HTML Parameters: {"utf8"=>"✓", "menu"=>{"title"=>"Updated Menu"}, "id"=>"1", "locale"=>:en} Refinery::Menus::Menu Load (0.1ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = ? LIMIT 1 [["id", "1"]] Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."refinery_menu_id" IN (1) ORDER BY lft ASC Refinery::Role Load (0.0ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.1ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1  (0.1ms) SELECT COUNT(*) FROM "refinery_menus_links" LEFT OUTER JOIN "refinery_menus_links" AS parent ON "refinery_menus_links"."parent_id" = parent.id WHERE ("refinery_menus_links"."lft" IS NULL OR "refinery_menus_links"."rgt" IS NULL OR "refinery_menus_links"."lft" >= "refinery_menus_links"."rgt" OR ("refinery_menus_links"."parent_id" IS NOT NULL AND ("refinery_menus_links"."lft" <= parent."lft" OR "refinery_menus_links"."rgt" >= parent."rgt"))) Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links"."lft", COUNT("refinery_menus_links"."lft") FROM "refinery_menus_links" GROUP BY "refinery_menus_links"."lft" HAVING COUNT("refinery_menus_links"."lft") > 1 LIMIT 1 Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links"."rgt", COUNT("refinery_menus_links"."rgt") FROM "refinery_menus_links" GROUP BY "refinery_menus_links"."rgt" HAVING COUNT("refinery_menus_links"."rgt") > 1 LIMIT 1 Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."parent_id" IS NULL ORDER BY "refinery_menus_links"."lft"  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE ("refinery_menus"."title" = 'Updated Menu' AND "refinery_menus"."id" != 1) LIMIT 1 Refinery::Menus::Menu Exists (0.0ms) SELECT 1 AS one FROM "refinery_menus" WHERE ("refinery_menus"."permatitle" = 'menu_37' AND "refinery_menus"."id" != 1) LIMIT 1  (0.1ms) UPDATE "refinery_menus" SET "title" = 'Updated Menu', "updated_at" = '2013-03-26 23:18:41.467342' WHERE "refinery_menus"."id" = 1  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://www.example.com/menus/refinery/menus Completed 302 Found in 13ms (ActiveRecord: 1.0ms) Started GET "/menus/refinery/menus" for 127.0.0.1 at 2013-03-27 00:18:41 +0100 Processing by Refinery::Menus::Admin::MenusController#index as HTML Parameters: {"locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.1ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1  (0.1ms) SELECT COUNT(*) FROM "refinery_menus" Refinery::Menus::Menu Load (0.0ms) SELECT "refinery_menus".* FROM "refinery_menus" LIMIT 20 OFFSET 0 Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."refinery_menu_id" IN (1) ORDER BY lft ASC Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_menu.html.erb (1.6ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_menus.html.erb (2.9ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_records.html.erb (3.9ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.4ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (2.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_site_bar.html.erb (1.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_menu.html.erb (2.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_no_script.html.erb (0.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.2ms) Completed 200 OK in 17ms (Views: 11.5ms | ActiveRecord: 0.5ms)  (1.0ms) rollback transaction Refinery::Role Load (0.2ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1  (0.0ms) begin transaction Refinery::Role Exists (0.1ms) SELECT 1 AS one FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 SQL (0.2ms) INSERT INTO "refinery_roles" ("title") VALUES (?) [["title", "Refinery"]]  (2.2ms) commit transaction  (0.0ms) begin transaction Refinery::User Exists (0.1ms) SELECT 1 AS one FROM "refinery_users" WHERE "refinery_users"."email" = 'refinerycms@refinerycms.com' LIMIT 1 Refinery::User Exists (0.1ms) SELECT 1 AS one FROM "refinery_users" WHERE "refinery_users"."username" = 'refinerycms' LIMIT 1 Binary data inserted for `string` type on column `encrypted_password` SQL (32.3ms) INSERT INTO "refinery_users" ("created_at", "current_sign_in_at", "current_sign_in_ip", "email", "encrypted_password", "last_sign_in_at", "last_sign_in_ip", "remember_created_at", "reset_password_sent_at", "reset_password_token", "sign_in_count", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:41 UTC +00:00], ["current_sign_in_at", nil], ["current_sign_in_ip", nil], ["email", "refinerycms@refinerycms.com"], ["encrypted_password", "$2a$04$ArWz/gMsp5oyA5gEaWaS/uc9p2yL48mLc.B.G3jjYfo2yej4/NI3K"], ["last_sign_in_at", nil], ["last_sign_in_ip", nil], ["remember_created_at", nil], ["reset_password_sent_at", nil], ["reset_password_token", nil], ["sign_in_count", nil], ["updated_at", Tue, 26 Mar 2013 23:18:41 UTC +00:00], ["username", "refinerycms"]]  (0.2ms) INSERT INTO "refinery_roles_users" ("user_id", "role_id") VALUES (1, 1)  (2.1ms) commit transaction  (0.1ms) begin transaction SQL (0.4ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_core"], ["position", 0], ["user_id", 1]]  (1.7ms) commit transaction  (0.0ms) begin transaction SQL (0.3ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_dialogs"], ["position", 1], ["user_id", 1]]  (1.5ms) commit transaction  (0.0ms) begin transaction SQL (0.3ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_menus"], ["position", 2], ["user_id", 1]]  (2.1ms) commit transaction  (0.1ms) begin transaction SQL (0.3ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_users"], ["position", 3], ["user_id", 1]]  (2.0ms) commit transaction  (0.1ms) begin transaction SQL (0.2ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_dashboard"], ["position", 4], ["user_id", 1]]  (1.8ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_images"], ["position", 5], ["user_id", 1]]  (1.7ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_files"], ["position", 6], ["user_id", 1]]  (24.6ms) commit transaction  (0.1ms) begin transaction SQL (0.2ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_pages"], ["position", 7], ["user_id", 1]]  (69.0ms) commit transaction  (0.1ms) begin transaction SQL (0.4ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_i18n"], ["position", 8], ["user_id", 1]]  (30.7ms) commit transaction Started GET "/refinery/login" for 127.0.0.1 at 2013-03-27 00:18:51 +0100 Processing by Refinery::SessionsController#new as HTML Parameters: {"locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_form_actions.html.erb (0.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.4ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (2.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.1ms) Completed 200 OK in 16ms (Views: 8.2ms | ActiveRecord: 1.7ms) Started GET "/assets/refinery/refinery.css" for 127.0.0.1 at 2013-03-27 00:18:51 +0100 Compiled refinery/layout.css (669ms) (pid 32577) Compiled refinery/site_bar.css (29ms) (pid 32577) Compiled refinery/ui.css (26ms) (pid 32577) Compiled refinery/submenu.css (7ms) (pid 32577) Compiled refinery/tooltips.css (18ms) (pid 32577) Compiled wymeditor/skins/refinery/skin.css (137ms) (pid 32577) Compiled refinery/refinery.css (1202ms) (pid 32577) Served asset /refinery/refinery.css - 200 OK (1326ms) Started GET "/assets/admin.js" for 127.0.0.1 at 2013-03-27 00:18:52 +0100 Compiled admin.js (0ms) (pid 32577) Served asset /admin.js - 200 OK (29ms) Started GET "/assets/wymeditor/skins/refinery/skin.js" for 127.0.0.1 at 2013-03-27 00:18:52 +0100 Compiled wymeditor/skins/refinery/skin.js (0ms) (pid 32577) Served asset /wymeditor/skins/refinery/skin.js - 200 OK (159ms) Started GET "/assets/refinery/wymeditor.js" for 127.0.0.1 at 2013-03-27 00:18:52 +0100 Compiled wymeditor/setup.js (58ms) (pid 32577) Compiled wymeditor/functions.js (4ms) (pid 32577) Compiled wymeditor/prototypes.js (16ms) (pid 32577) Compiled wymeditor/classes.js (66ms) (pid 32577) Compiled wymeditor/validators.js (11ms) (pid 32577) Compiled wymeditor/browsers/ie.js (5ms) (pid 32577) Compiled wymeditor/browsers/mozilla.js (45ms) (pid 32577) Compiled wymeditor/browsers/opera.js (2ms) (pid 32577) Compiled wymeditor/browsers/webkit.js (4ms) (pid 32577) Compiled refinery/boot_wym.js (11ms) (pid 32577) Compiled refinery/wymeditor.js (385ms) (pid 32577) Served asset /refinery/wymeditor.js - 200 OK (493ms) Started GET "/assets/refinery/refinery.js" for 127.0.0.1 at 2013-03-27 00:18:53 +0100 Compiled jquery.js (4ms) (pid 32577) Compiled jquery_ujs.js (0ms) (pid 32577) Compiled jquery-ui.js (2ms) (pid 32577) Compiled modernizr-min.js (1ms) (pid 32577) Compiled jquery/jquery.corner.js (0ms) (pid 32577) Compiled jquery/jquery.textTruncate.js (0ms) (pid 32577) Compiled jquery/jquery.html5-placeholder-shim.js (0ms) (pid 32577) Compiled jquery/jquery.timers.js (0ms) (pid 32577) Compiled jquery/jquery.jcarousel.js (0ms) (pid 32577) Compiled refinery/core.js (0ms) (pid 32577) Compiled refinery/site_bar.js (0ms) (pid 32577) Compiled refinery/admin.js (63ms) (pid 32577) Compiled refinery/submenu.js (3ms) (pid 32577) Compiled refinery/interface.js (514ms) (pid 32577) Compiled refinery/sortable_menu.js (133ms) (pid 32577) Compiled refinery/submit_continue.js (77ms) (pid 32577) Compiled refinery/ajaxy_pagination.js (82ms) (pid 32577) Compiled refinery/refinery.js (1379ms) (pid 32577) Served asset /refinery/refinery.js - 200 OK (1468ms) Started GET "/assets/wymeditor/lang/en.js" for 127.0.0.1 at 2013-03-27 00:18:54 +0100 Compiled wymeditor/lang/en.js (0ms) (pid 32577) Served asset /wymeditor/lang/en.js - 200 OK (9ms) Started GET "/assets/refinery/page_bg.png" for 127.0.0.1 at 2013-03-27 00:18:57 +0100 Served asset /refinery/page_bg.png - 200 OK (18ms) Started GET "/assets/refinery/text_field_background.png" for 127.0.0.1 at 2013-03-27 00:18:57 +0100 Served asset /refinery/text_field_background.png - 200 OK (6ms) Started GET "/assets/refinery/ajax-loader.gif" for 127.0.0.1 at 2013-03-27 00:18:57 +0100 Served asset /refinery/ajax-loader.gif - 200 OK (24ms)  (0.1ms) begin transaction Refinery::Menus::Menu Exists (0.2ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 38' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_38' LIMIT 1 SQL (10.1ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:57 UTC +00:00], ["permatitle", "menu_38"], ["title", "Menu 38"], ["updated_at", Tue, 26 Mar 2013 23:18:57 UTC +00:00]]  (2.0ms) commit transaction Started POST "/refinery/users/login" for 127.0.0.1 at 2013-03-27 00:18:57 +0100 Processing by Refinery::SessionsController#create as HTML Parameters: {"utf8"=>"✓", "refinery_user"=>{"login"=>"refinerycms", "password"=>"[FILTERED]", "remember_me"=>"0"}, "locale"=>:en} Refinery::Role Load (0.2ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE (username = 'refinerycms' OR email = 'refinerycms') LIMIT 1  (0.0ms) begin transaction  (0.2ms) UPDATE "refinery_users" SET "last_sign_in_at" = '2013-03-26 23:18:57.906114', "current_sign_in_at" = '2013-03-26 23:18:57.906114', "last_sign_in_ip" = '127.0.0.1', "current_sign_in_ip" = '127.0.0.1', "sign_in_count" = 1, "updated_at" = '2013-03-26 23:18:57.906748' WHERE "refinery_users"."id" = 1  (1.5ms) commit transaction Redirected to http://127.0.0.1:34667/refinery Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Completed 302 Found in 16ms (ActiveRecord: 0.1ms) Started GET "/refinery" for 127.0.0.1 at 2013-03-27 00:18:57 +0100 Processing by Refinery::Admin::DashboardController#index as HTML Parameters: {"locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.1ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" ORDER BY updated_at DESC LIMIT 7 Refinery::Image Load (34.8ms) SELECT "refinery_images".* FROM "refinery_images" ORDER BY updated_at DESC LIMIT 7 Refinery::Resource Load (20.9ms) SELECT "refinery_resources".* FROM "refinery_resources" ORDER BY updated_at DESC LIMIT 7 Refinery::Page Load (19.0ms) SELECT "refinery_pages".* FROM "refinery_pages" ORDER BY updated_at DESC LIMIT 7 Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_recent_activity.html.erb (3.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_records.html.erb (3.7ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_additional_dashboard_menu_items.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_actions.html.erb (54.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (2.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_site_bar.html.erb (1.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_menu.html.erb (2.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_no_script.html.erb (0.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.2ms) Completed 200 OK in 155ms (Views: 68.0ms | ActiveRecord: 76.7ms) Started GET "/assets/refinery/orange_button.png" for 127.0.0.1 at 2013-03-27 00:18:58 +0100 Served asset /refinery/orange_button.png - 200 OK (20ms) Started GET "/assets/refinery/nav_inactive_background.png" for 127.0.0.1 at 2013-03-27 00:18:58 +0100 Served asset /refinery/nav_inactive_background.png - 200 OK (53ms) Started GET "/assets/refinery/icons/arrow_switch.png" for 127.0.0.1 at 2013-03-27 00:18:58 +0100 Served asset /refinery/icons/arrow_switch.png - 200 OK (19ms) Started GET "/assets/refinery/logo-site-bar.png" for 127.0.0.1 at 2013-03-27 00:18:58 +0100 Served asset /refinery/logo-site-bar.png - 200 OK (41ms) Started GET "/assets/refinery/icons/tick.png" for 127.0.0.1 at 2013-03-27 00:18:58 +0100 Served asset /refinery/icons/tick.png - 200 OK (8ms) Started GET "/assets/refinery/icons/accept.png" for 127.0.0.1 at 2013-03-27 00:18:58 +0100 Served asset /refinery/icons/accept.png - 200 OK (62ms) Started GET "/assets/refinery/icons/add.png" for 127.0.0.1 at 2013-03-27 00:18:58 +0100 Served asset /refinery/icons/add.png - 200 OK (5ms) Started GET "/assets/refinery/icons/application_edit.png" for 127.0.0.1 at 2013-03-27 00:18:58 +0100 Served asset /refinery/icons/application_edit.png - 200 OK (5ms) Started GET "/assets/refinery/icons/flags/en.png" for 127.0.0.1 at 2013-03-27 00:18:58 +0100 Served asset /refinery/icons/flags/en.png - 200 OK (24ms) Started GET "/assets/refinery/carousel-right.png" for 127.0.0.1 at 2013-03-27 00:18:58 +0100 Served asset /refinery/carousel-right.png - 200 OK (5ms) Started GET "/assets/refinery/carousel-left.png" for 127.0.0.1 at 2013-03-27 00:18:58 +0100 Served asset /refinery/carousel-left.png - 200 OK (5ms) Started GET "/menus/refinery/menus/1/edit" for 127.0.0.1 at 2013-03-27 00:18:58 +0100 Processing by Refinery::Menus::Admin::MenusController#edit as HTML Parameters: {"id"=>"1", "locale"=>:en} Refinery::Menus::Menu Load (0.1ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = ? LIMIT 1 [["id", "1"]] Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."refinery_menu_id" IN (1) ORDER BY lft ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.1ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Refinery::Menus::LinkResource Load (15.3ms) SELECT "support_resource".* FROM "support_resource" Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_resource_link_editor_box.html.erb (17.8ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_form_actions.html.erb (21.7ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_error_messages.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_form_actions.html.erb (3.2ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_form.html.erb (50.4ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_make_sortable.html.erb (1.4ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (1.8ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_site_bar.html.erb (1.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_menu.html.erb (2.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_no_script.html.erb (0.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.1ms) Completed 200 OK in 90ms (Views: 66.7ms | ActiveRecord: 16.7ms) Started GET "/assets/refinery/select_form.css" for 127.0.0.1 at 2013-03-27 00:18:58 +0100 Compiled refinery/select_form.css (31ms) (pid 32577) Served asset /refinery/select_form.css - 200 OK (54ms) Started GET "/assets/refinery/serializelist.js" for 127.0.0.1 at 2013-03-27 00:18:58 +0100 Compiled refinery/serializelist.js (0ms) (pid 32577) Served asset /refinery/serializelist.js - 200 OK (60ms) Started GET "/assets/refinery/tree.js" for 127.0.0.1 at 2013-03-27 00:18:58 +0100 Compiled refinery/tree.js (0ms) (pid 32577) Served asset /refinery/tree.js - 200 OK (43ms) Started GET "/assets/refinery/nestedsortables.js" for 127.0.0.1 at 2013-03-27 00:18:58 +0100 Compiled refinery/nestedsortables.js (0ms) (pid 32577) Served asset /refinery/nestedsortables.js - 200 OK (54ms) Started GET "/assets/refinery/backend.js" for 127.0.0.1 at 2013-03-27 00:18:58 +0100 Compiled refinery/backend.js (86ms) (pid 32577) Served asset /refinery/backend.js - 200 OK (115ms) Started POST "/menus/refinery/menu_links" for 127.0.0.1 at 2013-03-27 00:18:59 +0100 Processing by Refinery::Menus::Admin::MenuLinksController#create as JS Parameters: {"utf8"=>"✓", "menu_link"=>{"refinery_menu_id"=>"1", "custom_url"=>"http://google.dk", "label"=>"Google"}, "commit"=>"Add Custom Link", "locale"=>:en} Refinery::Role Load (0.2ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.1ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1  (0.1ms) begin transaction Refinery::Menus::Menu Load (0.1ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = 1 LIMIT 1 Refinery::Menus::MenuLink Load (18.8ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" ORDER BY "refinery_menus_links"."rgt" desc LIMIT 1 SQL (1.5ms) INSERT INTO "refinery_menus_links" ("class_attribute", "custom_url", "depth", "id_attribute", "label", "lft", "menu_match", "parent_id", "refinery_menu_id", "resource_id", "resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", "http://google.dk"], ["depth", nil], ["id_attribute", nil], ["label", "Google"], ["lft", 1], ["menu_match", nil], ["parent_id", nil], ["refinery_menu_id", 1], ["resource_id", nil], ["resource_type", nil], ["rgt", 2], ["title_attribute", nil]] Refinery::Menus::MenuLink Load (0.2ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 1]] SQL (0.2ms) UPDATE "refinery_menus_links" SET "depth" = 0 WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = 1 ORDER BY "refinery_menus_links"."lft")  (2.7ms) commit transaction Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_custom_link.html.erb (3.1ms) Refinery::Menus::MenuLink Load (0.2ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."parent_id" = 1 ORDER BY lft Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_menu_link.html.erb (17.0ms) Completed 200 OK in 126ms (Views: 85.2ms | ActiveRecord: 24.4ms) Started GET "/assets/refinery/icons/down.gif" for 127.0.0.1 at 2013-03-27 00:18:59 +0100  (9.0ms) DELETE FROM "refinery_images";  (0.4ms) DELETE FROM sqlite_sequence where name = 'refinery_images';  (2.0ms) DELETE FROM "refinery_menus";  (1.7ms) DELETE FROM sqlite_sequence where name = 'refinery_menus';  (1.5ms) DELETE FROM "refinery_menus_links";  (2.4ms) DELETE FROM sqlite_sequence where name = 'refinery_menus_links';  (26.0ms) DELETE FROM "refinery_page_part_translations";  (0.2ms) DELETE FROM sqlite_sequence where name = 'refinery_page_part_translations'; Served asset /refinery/icons/down.gif - 200 OK (109ms)  (2.7ms) DELETE FROM "refinery_page_parts";  (0.1ms) DELETE FROM sqlite_sequence where name = 'refinery_page_parts';  (2.2ms) DELETE FROM "refinery_page_translations";  (0.1ms) DELETE FROM sqlite_sequence where name = 'refinery_page_translations';  (3.4ms) DELETE FROM "refinery_pages";  (0.1ms) DELETE FROM sqlite_sequence where name = 'refinery_pages';  (1.4ms) DELETE FROM "refinery_resources";  (0.1ms) DELETE FROM sqlite_sequence where name = 'refinery_resources';  (1.7ms) DELETE FROM "refinery_roles";  (1.3ms) DELETE FROM sqlite_sequence where name = 'refinery_roles';  (1.6ms) DELETE FROM "refinery_roles_users";  (0.1ms) DELETE FROM sqlite_sequence where name = 'refinery_roles_users';  (1.6ms) DELETE FROM "refinery_user_plugins";  (1.5ms) DELETE FROM sqlite_sequence where name = 'refinery_user_plugins';  (31.4ms) DELETE FROM "refinery_users";  (2.8ms) DELETE FROM sqlite_sequence where name = 'refinery_users';  (2.9ms) DELETE FROM "seo_meta";  (0.2ms) DELETE FROM sqlite_sequence where name = 'seo_meta';  (1.8ms) DELETE FROM "support_resource";  (0.2ms) DELETE FROM sqlite_sequence where name = 'support_resource';  (2.3ms) DELETE FROM "support_another_resource";  (0.2ms) DELETE FROM sqlite_sequence where name = 'support_another_resource'; Refinery::Role Load (0.2ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1  (0.0ms) begin transaction Refinery::Role Exists (0.1ms) SELECT 1 AS one FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 SQL (0.2ms) INSERT INTO "refinery_roles" ("title") VALUES (?) [["title", "Refinery"]]  (2.5ms) commit transaction  (0.1ms) begin transaction Refinery::User Exists (0.1ms) SELECT 1 AS one FROM "refinery_users" WHERE "refinery_users"."email" = 'refinerycms@refinerycms.com' LIMIT 1 Refinery::User Exists (0.1ms) SELECT 1 AS one FROM "refinery_users" WHERE "refinery_users"."username" = 'refinerycms' LIMIT 1 Binary data inserted for `string` type on column `encrypted_password` SQL (0.5ms) INSERT INTO "refinery_users" ("created_at", "current_sign_in_at", "current_sign_in_ip", "email", "encrypted_password", "last_sign_in_at", "last_sign_in_ip", "remember_created_at", "reset_password_sent_at", "reset_password_token", "sign_in_count", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:59 UTC +00:00], ["current_sign_in_at", nil], ["current_sign_in_ip", nil], ["email", "refinerycms@refinerycms.com"], ["encrypted_password", "$2a$04$6ceslCp1f8BgDrldTkWU/uJTOSxRppG41Eau5mSCfRrMryNv7QK.6"], ["last_sign_in_at", nil], ["last_sign_in_ip", nil], ["remember_created_at", nil], ["reset_password_sent_at", nil], ["reset_password_token", nil], ["sign_in_count", nil], ["updated_at", Tue, 26 Mar 2013 23:18:59 UTC +00:00], ["username", "refinerycms"]]  (0.1ms) INSERT INTO "refinery_roles_users" ("user_id", "role_id") VALUES (1, 1)  (2.8ms) commit transaction  (0.1ms) begin transaction SQL (0.3ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_core"], ["position", 0], ["user_id", 1]]  (2.1ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_dialogs"], ["position", 1], ["user_id", 1]]  (2.3ms) commit transaction  (0.1ms) begin transaction SQL (0.3ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_menus"], ["position", 2], ["user_id", 1]]  (2.5ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_users"], ["position", 3], ["user_id", 1]]  (1.5ms) commit transaction  (0.0ms) begin transaction SQL (0.2ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_dashboard"], ["position", 4], ["user_id", 1]]  (2.4ms) commit transaction  (0.1ms) begin transaction SQL (0.4ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_images"], ["position", 5], ["user_id", 1]]  (1.8ms) commit transaction  (0.1ms) begin transaction SQL (0.4ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_files"], ["position", 6], ["user_id", 1]]  (2.6ms) commit transaction  (0.1ms) begin transaction SQL (0.4ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_pages"], ["position", 7], ["user_id", 1]]  (2.2ms) commit transaction  (0.0ms) begin transaction SQL (0.3ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_i18n"], ["position", 8], ["user_id", 1]]  (2.6ms) commit transaction Started GET "/refinery/login" for 127.0.0.1 at 2013-03-27 00:18:59 +0100 Processing by Refinery::SessionsController#new as HTML Parameters: {"locale"=>:en} Refinery::Role Load (0.2ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_form_actions.html.erb (0.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.4ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (2.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.1ms) Completed 200 OK in 10ms (Views: 6.8ms | ActiveRecord: 0.3ms) Started GET "/assets/refinery/ajax-loader.gif" for 127.0.0.1 at 2013-03-27 00:18:59 +0100  (0.1ms) begin transaction Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 39' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_39' LIMIT 1 SQL (0.4ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:18:59 UTC +00:00], ["permatitle", "menu_39"], ["title", "Menu 39"], ["updated_at", Tue, 26 Mar 2013 23:18:59 UTC +00:00]]  (1.9ms) commit transaction Served asset /refinery/ajax-loader.gif - 304 Not Modified (0ms) Started POST "/refinery/users/login" for 127.0.0.1 at 2013-03-27 00:18:59 +0100 Processing by Refinery::SessionsController#create as HTML Parameters: {"utf8"=>"✓", "refinery_user"=>{"login"=>"refinerycms", "password"=>"[FILTERED]", "remember_me"=>"0"}, "locale"=>:en} Refinery::Role Load (0.2ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE (username = 'refinerycms' OR email = 'refinerycms') LIMIT 1  (0.0ms) begin transaction  (0.3ms) UPDATE "refinery_users" SET "last_sign_in_at" = '2013-03-26 23:18:59.837706', "current_sign_in_at" = '2013-03-26 23:18:59.837706', "last_sign_in_ip" = '127.0.0.1', "current_sign_in_ip" = '127.0.0.1', "sign_in_count" = 1, "updated_at" = '2013-03-26 23:18:59.838338' WHERE "refinery_users"."id" = 1  (1.8ms) commit transaction Redirected to http://127.0.0.1:34667/refinery Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Completed 302 Found in 15ms (ActiveRecord: 0.1ms) Started GET "/refinery" for 127.0.0.1 at 2013-03-27 00:18:59 +0100 Processing by Refinery::Admin::DashboardController#index as HTML Parameters: {"locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.1ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Refinery::User Load (0.2ms) SELECT "refinery_users".* FROM "refinery_users" ORDER BY updated_at DESC LIMIT 7 Refinery::Image Load (0.1ms) SELECT "refinery_images".* FROM "refinery_images" ORDER BY updated_at DESC LIMIT 7 Refinery::Resource Load (0.1ms) SELECT "refinery_resources".* FROM "refinery_resources" ORDER BY updated_at DESC LIMIT 7 Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" ORDER BY updated_at DESC LIMIT 7 Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_recent_activity.html.erb (1.5ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_records.html.erb (1.7ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_additional_dashboard_menu_items.html.erb (0.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_actions.html.erb (8.5ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (2.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_site_bar.html.erb (1.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_menu.html.erb (2.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_no_script.html.erb (0.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.2ms) Completed 200 OK in 25ms (Views: 18.5ms | ActiveRecord: 1.0ms) Started GET "/menus/refinery/menus/1/edit" for 127.0.0.1 at 2013-03-27 00:18:59 +0100 Processing by Refinery::Menus::Admin::MenusController#edit as HTML Parameters: {"id"=>"1", "locale"=>:en} Refinery::Menus::Menu Load (0.1ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = ? LIMIT 1 [["id", "1"]] Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."refinery_menu_id" IN (1) ORDER BY lft ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.1ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Refinery::Menus::LinkResource Load (0.1ms) SELECT "support_resource".* FROM "support_resource" Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_resource_link_editor_box.html.erb (2.0ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_form_actions.html.erb (4.8ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_error_messages.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_form_actions.html.erb (2.7ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_form.html.erb (4.5ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_make_sortable.html.erb (1.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (2.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_site_bar.html.erb (1.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_menu.html.erb (2.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_no_script.html.erb (0.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.1ms) Completed 200 OK in 26ms (Views: 19.2ms | ActiveRecord: 0.9ms) Started POST "/menus/refinery/menu_links" for 127.0.0.1 at 2013-03-27 00:19:00 +0100 Processing by Refinery::Menus::Admin::MenuLinksController#create as JS Parameters: {"utf8"=>"✓", "menu_link"=>{"refinery_menu_id"=>"1", "custom_url"=>"http://google.dk", "label"=>""}, "commit"=>"Add Custom Link", "locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.1ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1  (0.0ms) begin transaction Refinery::Menus::Menu Load (0.1ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = 1 LIMIT 1 Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" ORDER BY "refinery_menus_links"."rgt" desc LIMIT 1 SQL (0.3ms) INSERT INTO "refinery_menus_links" ("class_attribute", "custom_url", "depth", "id_attribute", "label", "lft", "menu_match", "parent_id", "refinery_menu_id", "resource_id", "resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", "http://google.dk"], ["depth", nil], ["id_attribute", nil], ["label", "Google"], ["lft", 1], ["menu_match", nil], ["parent_id", nil], ["refinery_menu_id", 1], ["resource_id", nil], ["resource_type", nil], ["rgt", 2], ["title_attribute", nil]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 1]] SQL (0.1ms) UPDATE "refinery_menus_links" SET "depth" = 0 WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = 1 ORDER BY "refinery_menus_links"."lft")  (1.8ms) commit transaction Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_custom_link.html.erb (2.2ms) Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."parent_id" = 1 ORDER BY lft Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_menu_link.html.erb (4.3ms) Completed 200 OK in 17ms (Views: 5.6ms | ActiveRecord: 3.2ms)  (205.4ms) DELETE FROM "refinery_images";  (0.3ms) DELETE FROM sqlite_sequence where name = 'refinery_images';  (3.0ms) DELETE FROM "refinery_menus";  (3.2ms) DELETE FROM sqlite_sequence where name = 'refinery_menus';  (9.0ms) DELETE FROM "refinery_menus_links";  (2.7ms) DELETE FROM sqlite_sequence where name = 'refinery_menus_links';  (16.4ms) DELETE FROM "refinery_page_part_translations";  (0.2ms) DELETE FROM sqlite_sequence where name = 'refinery_page_part_translations';  (234.7ms) DELETE FROM "refinery_page_parts";  (0.4ms) DELETE FROM sqlite_sequence where name = 'refinery_page_parts';  (3.8ms) DELETE FROM "refinery_page_translations";  (0.2ms) DELETE FROM sqlite_sequence where name = 'refinery_page_translations';  (3.4ms) DELETE FROM "refinery_pages";  (0.2ms) DELETE FROM sqlite_sequence where name = 'refinery_pages';  (1.9ms) DELETE FROM "refinery_resources";  (0.2ms) DELETE FROM sqlite_sequence where name = 'refinery_resources';  (2.0ms) DELETE FROM "refinery_roles";  (2.5ms) DELETE FROM sqlite_sequence where name = 'refinery_roles';  (1.9ms) DELETE FROM "refinery_roles_users";  (0.1ms) DELETE FROM sqlite_sequence where name = 'refinery_roles_users';  (1.8ms) DELETE FROM "refinery_user_plugins";  (1.5ms) DELETE FROM sqlite_sequence where name = 'refinery_user_plugins';  (1.6ms) DELETE FROM "refinery_users";  (2.6ms) DELETE FROM sqlite_sequence where name = 'refinery_users';  (2.0ms) DELETE FROM "seo_meta";  (0.1ms) DELETE FROM sqlite_sequence where name = 'seo_meta';  (2.1ms) DELETE FROM "support_resource";  (0.1ms) DELETE FROM sqlite_sequence where name = 'support_resource';  (1.5ms) DELETE FROM "support_another_resource";  (0.1ms) DELETE FROM sqlite_sequence where name = 'support_another_resource';  (0.1ms) begin transaction Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1  (0.0ms) SAVEPOINT active_record_1 Refinery::Role Exists (0.0ms) SELECT 1 AS one FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 SQL (0.2ms) INSERT INTO "refinery_roles" ("title") VALUES (?) [["title", "Refinery"]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Refinery::User Exists (0.1ms) SELECT 1 AS one FROM "refinery_users" WHERE "refinery_users"."email" = 'refinerycms@refinerycms.com' LIMIT 1 Refinery::User Exists (0.1ms) SELECT 1 AS one FROM "refinery_users" WHERE "refinery_users"."username" = 'refinerycms' LIMIT 1 Binary data inserted for `string` type on column `encrypted_password` SQL (0.4ms) INSERT INTO "refinery_users" ("created_at", "current_sign_in_at", "current_sign_in_ip", "email", "encrypted_password", "last_sign_in_at", "last_sign_in_ip", "remember_created_at", "reset_password_sent_at", "reset_password_token", "sign_in_count", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:19:00 UTC +00:00], ["current_sign_in_at", nil], ["current_sign_in_ip", nil], ["email", "refinerycms@refinerycms.com"], ["encrypted_password", "$2a$04$Gb5eH/og/dcXAW7bD7lSHuDNFKqLJc.F49BNK/oeCuUqQIxAW9Zlu"], ["last_sign_in_at", nil], ["last_sign_in_ip", nil], ["remember_created_at", nil], ["reset_password_sent_at", nil], ["reset_password_token", nil], ["sign_in_count", nil], ["updated_at", Tue, 26 Mar 2013 23:19:00 UTC +00:00], ["username", "refinerycms"]]  (0.1ms) INSERT INTO "refinery_roles_users" ("user_id", "role_id") VALUES (1, 1)  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_core"], ["position", 0], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_dialogs"], ["position", 1], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_menus"], ["position", 2], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_users"], ["position", 3], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_dashboard"], ["position", 4], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_images"], ["position", 5], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_files"], ["position", 6], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_pages"], ["position", 7], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_i18n"], ["position", 8], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/refinery/login" for 127.0.0.1 at 2013-03-27 00:19:00 +0100 Processing by Refinery::SessionsController#new as HTML Parameters: {"locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_form_actions.html.erb (0.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.4ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (2.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.0ms) Completed 200 OK in 10ms (Views: 6.8ms | ActiveRecord: 0.2ms) Started POST "/refinery/users/login" for 127.0.0.1 at 2013-03-27 00:19:00 +0100 Processing by Refinery::SessionsController#create as HTML Parameters: {"utf8"=>"✓", "refinery_user"=>{"login"=>"refinerycms", "password"=>"[FILTERED]", "remember_me"=>"0"}, "locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE (username = 'refinerycms' OR email = 'refinerycms') LIMIT 1  (0.0ms) SAVEPOINT active_record_1  (0.1ms) UPDATE "refinery_users" SET "last_sign_in_at" = '2013-03-26 23:19:00.730853', "current_sign_in_at" = '2013-03-26 23:19:00.730853', "last_sign_in_ip" = '127.0.0.1', "current_sign_in_ip" = '127.0.0.1', "sign_in_count" = 1, "updated_at" = '2013-03-26 23:19:00.731423' WHERE "refinery_users"."id" = 1  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://www.example.com/refinery Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Completed 302 Found in 13ms (ActiveRecord: 0.1ms) Started GET "/refinery" for 127.0.0.1 at 2013-03-27 00:19:00 +0100 Processing by Refinery::Admin::DashboardController#index as HTML Parameters: {"locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.1ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" ORDER BY updated_at DESC LIMIT 7 Refinery::Image Load (0.1ms) SELECT "refinery_images".* FROM "refinery_images" ORDER BY updated_at DESC LIMIT 7 Refinery::Resource Load (0.1ms) SELECT "refinery_resources".* FROM "refinery_resources" ORDER BY updated_at DESC LIMIT 7 Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" ORDER BY updated_at DESC LIMIT 7 Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_recent_activity.html.erb (1.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_records.html.erb (1.5ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_additional_dashboard_menu_items.html.erb (0.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_actions.html.erb (55.5ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (1.9ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_site_bar.html.erb (1.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_menu.html.erb (2.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_no_script.html.erb (0.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.2ms) Completed 200 OK in 71ms (Views: 64.8ms | ActiveRecord: 0.7ms)  (0.1ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 40' LIMIT 1 Refinery::Menus::Menu Exists (0.0ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_40' LIMIT 1 SQL (0.2ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:19:00 UTC +00:00], ["permatitle", "menu_40"], ["title", "Menu 40"], ["updated_at", Tue, 26 Mar 2013 23:19:00 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/menus/refinery/menus/1/edit" for 127.0.0.1 at 2013-03-27 00:19:00 +0100 Processing by Refinery::Menus::Admin::MenusController#edit as HTML Parameters: {"id"=>"1", "locale"=>:en} Refinery::Menus::Menu Load (0.0ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = ? LIMIT 1 [["id", "1"]] Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."refinery_menu_id" IN (1) ORDER BY lft ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.1ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Refinery::Menus::LinkResource Load (0.1ms) SELECT "support_resource".* FROM "support_resource"  Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_resource_link_editor_box.html.erb (1.7ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_form_actions.html.erb (4.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_error_messages.html.erb (0.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_form_actions.html.erb (1.6ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_form.html.erb (3.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_make_sortable.html.erb (1.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (1.7ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_site_bar.html.erb (1.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_menu.html.erb (2.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_no_script.html.erb (0.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.0ms) Completed 200 OK in 23ms (Views: 16.8ms | ActiveRecord: 0.5ms)  (12.5ms) rollback transaction Refinery::Role Load (0.2ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1  (0.0ms) begin transaction Refinery::Role Exists (0.1ms) SELECT 1 AS one FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 SQL (0.2ms) INSERT INTO "refinery_roles" ("title") VALUES (?) [["title", "Refinery"]]  (2.2ms) commit transaction  (0.0ms) begin transaction Refinery::User Exists (0.1ms) SELECT 1 AS one FROM "refinery_users" WHERE "refinery_users"."email" = 'refinerycms@refinerycms.com' LIMIT 1 Refinery::User Exists (0.1ms) SELECT 1 AS one FROM "refinery_users" WHERE "refinery_users"."username" = 'refinerycms' LIMIT 1 Binary data inserted for `string` type on column `encrypted_password` SQL (0.4ms) INSERT INTO "refinery_users" ("created_at", "current_sign_in_at", "current_sign_in_ip", "email", "encrypted_password", "last_sign_in_at", "last_sign_in_ip", "remember_created_at", "reset_password_sent_at", "reset_password_token", "sign_in_count", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:19:00 UTC +00:00], ["current_sign_in_at", nil], ["current_sign_in_ip", nil], ["email", "refinerycms@refinerycms.com"], ["encrypted_password", "$2a$04$Q0r4BW6ZjTxA5xizj8JGrOM4AyT37CfZUkfvaXgoINDx9cAVaZpPe"], ["last_sign_in_at", nil], ["last_sign_in_ip", nil], ["remember_created_at", nil], ["reset_password_sent_at", nil], ["reset_password_token", nil], ["sign_in_count", nil], ["updated_at", Tue, 26 Mar 2013 23:19:00 UTC +00:00], ["username", "refinerycms"]]  (0.1ms) INSERT INTO "refinery_roles_users" ("user_id", "role_id") VALUES (1, 1)  (2.2ms) commit transaction  (0.1ms) begin transaction SQL (0.3ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_core"], ["position", 0], ["user_id", 1]]  (2.0ms) commit transaction  (0.1ms) begin transaction SQL (0.2ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_dialogs"], ["position", 1], ["user_id", 1]]  (2.3ms) commit transaction  (0.1ms) begin transaction SQL (0.3ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_menus"], ["position", 2], ["user_id", 1]]  (1.8ms) commit transaction  (0.1ms) begin transaction SQL (0.3ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_users"], ["position", 3], ["user_id", 1]]  (1.8ms) commit transaction  (0.1ms) begin transaction SQL (0.2ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_dashboard"], ["position", 4], ["user_id", 1]]  (2.1ms) commit transaction  (0.1ms) begin transaction SQL (0.3ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_images"], ["position", 5], ["user_id", 1]]  (2.2ms) commit transaction  (0.1ms) begin transaction SQL (0.2ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_files"], ["position", 6], ["user_id", 1]]  (2.1ms) commit transaction  (0.1ms) begin transaction SQL (0.3ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_pages"], ["position", 7], ["user_id", 1]]  (1.9ms) commit transaction  (0.1ms) begin transaction SQL (0.3ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_i18n"], ["position", 8], ["user_id", 1]]  (1.5ms) commit transaction Started GET "/refinery/login" for 127.0.0.1 at 2013-03-27 00:19:00 +0100 Processing by Refinery::SessionsController#new as HTML Parameters: {"locale"=>:en} Refinery::Role Load (0.2ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_form_actions.html.erb (0.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (2.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.0ms) Completed 200 OK in 9ms (Views: 6.3ms | ActiveRecord: 0.3ms) Started GET "/assets/refinery/ajax-loader.gif" for 127.0.0.1 at 2013-03-27 00:19:00 +0100  (0.1ms) begin transaction Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 41' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_41' LIMIT 1 SQL (0.4ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:19:00 UTC +00:00], ["permatitle", "menu_41"], ["title", "Menu 41"], ["updated_at", Tue, 26 Mar 2013 23:19:00 UTC +00:00]]  (53.5ms) commit transaction Served asset /refinery/ajax-loader.gif - 304 Not Modified (0ms) Started POST "/refinery/users/login" for 127.0.0.1 at 2013-03-27 00:19:01 +0100 Processing by Refinery::SessionsController#create as HTML Parameters: {"utf8"=>"✓", "refinery_user"=>{"login"=>"refinerycms", "password"=>"[FILTERED]", "remember_me"=>"0"}, "locale"=>:en} Refinery::Role Load (0.2ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.2ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.2ms) SELECT "refinery_users".* FROM "refinery_users" WHERE (username = 'refinerycms' OR email = 'refinerycms') LIMIT 1  (0.0ms) begin transaction  (0.3ms) UPDATE "refinery_users" SET "last_sign_in_at" = '2013-03-26 23:19:01.092971', "current_sign_in_at" = '2013-03-26 23:19:01.092971', "last_sign_in_ip" = '127.0.0.1', "current_sign_in_ip" = '127.0.0.1', "sign_in_count" = 1, "updated_at" = '2013-03-26 23:19:01.093561' WHERE "refinery_users"."id" = 1  (55.2ms) commit transaction Redirected to http://127.0.0.1:34667/refinery Refinery::Role Load (0.3ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Completed 302 Found in 75ms (ActiveRecord: 0.3ms) Started GET "/refinery" for 127.0.0.1 at 2013-03-27 00:19:01 +0100 Processing by Refinery::Admin::DashboardController#index as HTML Parameters: {"locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.1ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" ORDER BY updated_at DESC LIMIT 7 Refinery::Image Load (0.1ms) SELECT "refinery_images".* FROM "refinery_images" ORDER BY updated_at DESC LIMIT 7 Refinery::Resource Load (0.1ms) SELECT "refinery_resources".* FROM "refinery_resources" ORDER BY updated_at DESC LIMIT 7 Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" ORDER BY updated_at DESC LIMIT 7 Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_recent_activity.html.erb (1.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_records.html.erb (1.5ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_additional_dashboard_menu_items.html.erb (0.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_actions.html.erb (7.8ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (2.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_site_bar.html.erb (1.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_menu.html.erb (2.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_no_script.html.erb (0.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.2ms) Completed 200 OK in 25ms (Views: 17.5ms | ActiveRecord: 1.1ms) Started GET "/menus/refinery/menus/1/edit" for 127.0.0.1 at 2013-03-27 00:19:01 +0100 Processing by Refinery::Menus::Admin::MenusController#edit as HTML Parameters: {"id"=>"1", "locale"=>:en} Refinery::Menus::Menu Load (0.1ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = ? LIMIT 1 [["id", "1"]] Refinery::Menus::MenuLink Load (0.2ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."refinery_menu_id" IN (1) ORDER BY lft ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.2ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Refinery::Menus::LinkResource Load (0.1ms) SELECT "support_resource".* FROM "support_resource"  Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_resource_link_editor_box.html.erb (1.9ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_form_actions.html.erb (4.8ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_error_messages.html.erb (0.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_form_actions.html.erb (2.9ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_form.html.erb (4.8ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_make_sortable.html.erb (1.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (1.8ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_site_bar.html.erb (1.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_menu.html.erb (2.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_no_script.html.erb (0.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.1ms) Completed 200 OK in 27ms (Views: 19.4ms | ActiveRecord: 1.0ms) Started POST "/menus/refinery/menu_links" for 127.0.0.1 at 2013-03-27 00:19:01 +0100 Processing by Refinery::Menus::Admin::MenuLinksController#create as JS Parameters: {"utf8"=>"✓", "menu_link"=>{"refinery_menu_id"=>"1", "custom_url"=>"http://", "label"=>""}, "commit"=>"Add Custom Link", "locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.1ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1  (0.0ms) begin transaction Refinery::Menus::Menu Load (0.1ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = 1 LIMIT 1 Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" ORDER BY "refinery_menus_links"."rgt" desc LIMIT 1 SQL (0.3ms) INSERT INTO "refinery_menus_links" ("class_attribute", "custom_url", "depth", "id_attribute", "label", "lft", "menu_match", "parent_id", "refinery_menu_id", "resource_id", "resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", "http://"], ["depth", nil], ["id_attribute", nil], ["label", "http://"], ["lft", 1], ["menu_match", nil], ["parent_id", nil], ["refinery_menu_id", 1], ["resource_id", nil], ["resource_type", nil], ["rgt", 2], ["title_attribute", nil]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 1]] SQL (0.2ms) UPDATE "refinery_menus_links" SET "depth" = 0 WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = 1 ORDER BY "refinery_menus_links"."lft")  (7.4ms) commit transaction Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_custom_link.html.erb (3.7ms) Refinery::Menus::MenuLink Load (0.2ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."parent_id" = 1 ORDER BY lft Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_menu_link.html.erb (7.0ms) Completed 200 OK in 26ms (Views: 8.2ms | ActiveRecord: 8.9ms)  (354.5ms) DELETE FROM "refinery_images";  (0.4ms) DELETE FROM sqlite_sequence where name = 'refinery_images';  (3.3ms) DELETE FROM "refinery_menus";  (3.9ms) DELETE FROM sqlite_sequence where name = 'refinery_menus';  (81.0ms) DELETE FROM "refinery_menus_links";  (87.5ms) DELETE FROM sqlite_sequence where name = 'refinery_menus_links';  (60.2ms) DELETE FROM "refinery_page_part_translations";  (0.3ms) DELETE FROM sqlite_sequence where name = 'refinery_page_part_translations';  (28.5ms) DELETE FROM "refinery_page_parts";  (0.3ms) DELETE FROM sqlite_sequence where name = 'refinery_page_parts';  (90.1ms) DELETE FROM "refinery_page_translations";  (0.3ms) DELETE FROM sqlite_sequence where name = 'refinery_page_translations';  (2.7ms) DELETE FROM "refinery_pages";  (0.2ms) DELETE FROM sqlite_sequence where name = 'refinery_pages';  (94.1ms) DELETE FROM "refinery_resources";  (0.2ms) DELETE FROM sqlite_sequence where name = 'refinery_resources';  (104.4ms) DELETE FROM "refinery_roles";  (219.6ms) DELETE FROM sqlite_sequence where name = 'refinery_roles';  (34.6ms) DELETE FROM "refinery_roles_users";  (0.3ms) DELETE FROM sqlite_sequence where name = 'refinery_roles_users';  (82.1ms) DELETE FROM "refinery_user_plugins";  (106.3ms) DELETE FROM sqlite_sequence where name = 'refinery_user_plugins';  (93.7ms) DELETE FROM "refinery_users";  (128.2ms) DELETE FROM sqlite_sequence where name = 'refinery_users';  (65.7ms) DELETE FROM "seo_meta";  (0.2ms) DELETE FROM sqlite_sequence where name = 'seo_meta';  (218.5ms) DELETE FROM "support_resource";  (0.3ms) DELETE FROM sqlite_sequence where name = 'support_resource';  (51.3ms) DELETE FROM "support_another_resource";  (0.2ms) DELETE FROM sqlite_sequence where name = 'support_another_resource';  (0.1ms) begin transaction Refinery::Role Load (0.2ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1  (0.0ms) SAVEPOINT active_record_1 Refinery::Role Exists (0.0ms) SELECT 1 AS one FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 SQL (51.0ms) INSERT INTO "refinery_roles" ("title") VALUES (?) [["title", "Refinery"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Refinery::User Exists (0.1ms) SELECT 1 AS one FROM "refinery_users" WHERE "refinery_users"."email" = 'refinerycms@refinerycms.com' LIMIT 1 Refinery::User Exists (0.1ms) SELECT 1 AS one FROM "refinery_users" WHERE "refinery_users"."username" = 'refinerycms' LIMIT 1 Binary data inserted for `string` type on column `encrypted_password` SQL (67.3ms) INSERT INTO "refinery_users" ("created_at", "current_sign_in_at", "current_sign_in_ip", "email", "encrypted_password", "last_sign_in_at", "last_sign_in_ip", "remember_created_at", "reset_password_sent_at", "reset_password_token", "sign_in_count", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:19:03 UTC +00:00], ["current_sign_in_at", nil], ["current_sign_in_ip", nil], ["email", "refinerycms@refinerycms.com"], ["encrypted_password", "$2a$04$erPffOC7QykFxK8rY9m/6u.WwGO0Wplpez48Nzk2qOS4vcSxL3NWe"], ["last_sign_in_at", nil], ["last_sign_in_ip", nil], ["remember_created_at", nil], ["reset_password_sent_at", nil], ["reset_password_token", nil], ["sign_in_count", nil], ["updated_at", Tue, 26 Mar 2013 23:19:03 UTC +00:00], ["username", "refinerycms"]]  (0.3ms) INSERT INTO "refinery_roles_users" ("user_id", "role_id") VALUES (1, 1)  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_core"], ["position", 0], ["user_id", 1]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_dialogs"], ["position", 1], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_menus"], ["position", 2], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_users"], ["position", 3], ["user_id", 1]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_dashboard"], ["position", 4], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_images"], ["position", 5], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_files"], ["position", 6], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_pages"], ["position", 7], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_i18n"], ["position", 8], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/refinery/login" for 127.0.0.1 at 2013-03-27 00:19:03 +0100 Processing by Refinery::SessionsController#new as HTML Parameters: {"locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.2ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_form_actions.html.erb (0.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (2.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.0ms) Completed 200 OK in 13ms (Views: 8.3ms | ActiveRecord: 0.3ms) Started POST "/refinery/users/login" for 127.0.0.1 at 2013-03-27 00:19:03 +0100 Processing by Refinery::SessionsController#create as HTML Parameters: {"utf8"=>"✓", "refinery_user"=>{"login"=>"refinerycms", "password"=>"[FILTERED]", "remember_me"=>"0"}, "locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE (username = 'refinerycms' OR email = 'refinerycms') LIMIT 1  (0.0ms) SAVEPOINT active_record_1  (0.1ms) UPDATE "refinery_users" SET "last_sign_in_at" = '2013-03-26 23:19:03.662208', "current_sign_in_at" = '2013-03-26 23:19:03.662208', "last_sign_in_ip" = '127.0.0.1', "current_sign_in_ip" = '127.0.0.1', "sign_in_count" = 1, "updated_at" = '2013-03-26 23:19:03.662792' WHERE "refinery_users"."id" = 1  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://www.example.com/refinery Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Completed 302 Found in 13ms (ActiveRecord: 0.1ms) Started GET "/refinery" for 127.0.0.1 at 2013-03-27 00:19:03 +0100 Processing by Refinery::Admin::DashboardController#index as HTML Parameters: {"locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.1ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" ORDER BY updated_at DESC LIMIT 7 Refinery::Image Load (0.1ms) SELECT "refinery_images".* FROM "refinery_images" ORDER BY updated_at DESC LIMIT 7 Refinery::Resource Load (0.1ms) SELECT "refinery_resources".* FROM "refinery_resources" ORDER BY updated_at DESC LIMIT 7 Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" ORDER BY updated_at DESC LIMIT 7 Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_recent_activity.html.erb (1.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_records.html.erb (1.5ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_additional_dashboard_menu_items.html.erb (0.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_actions.html.erb (7.9ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (2.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_site_bar.html.erb (1.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_menu.html.erb (2.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_no_script.html.erb (0.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.2ms) Completed 200 OK in 24ms (Views: 17.2ms | ActiveRecord: 0.8ms)  (0.1ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 42' LIMIT 1 Refinery::Menus::Menu Exists (0.0ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_42' LIMIT 1 SQL (0.2ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:19:03 UTC +00:00], ["permatitle", "menu_42"], ["title", "Menu 42"], ["updated_at", Tue, 26 Mar 2013 23:19:03 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Load (0.1ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = 1 LIMIT 1 Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" ORDER BY "refinery_menus_links"."rgt" desc LIMIT 1 SQL (0.2ms) INSERT INTO "refinery_menus_links" ("class_attribute", "custom_url", "depth", "id_attribute", "label", "lft", "menu_match", "parent_id", "refinery_menu_id", "resource_id", "resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", nil], ["depth", nil], ["id_attribute", nil], ["label", "Link 1"], ["lft", 1], ["menu_match", nil], ["parent_id", nil], ["refinery_menu_id", 1], ["resource_id", nil], ["resource_type", nil], ["rgt", 2], ["title_attribute", nil]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 1]] SQL (0.1ms) UPDATE "refinery_menus_links" SET "depth" = 0 WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = 1 ORDER BY "refinery_menus_links"."lft")  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Load (0.1ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = 1 LIMIT 1 Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" ORDER BY "refinery_menus_links"."rgt" desc LIMIT 1 SQL (0.2ms) INSERT INTO "refinery_menus_links" ("class_attribute", "custom_url", "depth", "id_attribute", "label", "lft", "menu_match", "parent_id", "refinery_menu_id", "resource_id", "resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", nil], ["depth", nil], ["id_attribute", nil], ["label", "Link 2"], ["lft", 3], ["menu_match", nil], ["parent_id", nil], ["refinery_menu_id", 1], ["resource_id", nil], ["resource_type", nil], ["rgt", 4], ["title_attribute", nil]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 2]] SQL (0.1ms) UPDATE "refinery_menus_links" SET "depth" = 0 WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = 2 ORDER BY "refinery_menus_links"."lft")  (0.0ms) RELEASE SAVEPOINT active_record_1 Refinery::Menus::Menu Load (0.0ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = ? LIMIT 1 [["id", 1]] Started GET "/menus/refinery/menus/1/edit" for 127.0.0.1 at 2013-03-27 00:19:03 +0100 Processing by Refinery::Menus::Admin::MenusController#edit as HTML Parameters: {"id"=>"1", "locale"=>:en} Refinery::Menus::Menu Load (0.0ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = ? LIMIT 1 [["id", "1"]] Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."refinery_menu_id" IN (1) ORDER BY lft ASC Refinery::Role Load (0.2ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.2ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.2ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Refinery::Menus::LinkResource Load (0.0ms) SELECT "support_resource".* FROM "support_resource"  Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_resource_link_editor_box.html.erb (1.7ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_form_actions.html.erb (4.8ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_error_messages.html.erb (0.0ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_custom_link.html.erb (2.6ms) Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."parent_id" = 1 ORDER BY lft Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_menu_link.html.erb (7.2ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_custom_link.html.erb (1.8ms) Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."parent_id" = 2 ORDER BY lft Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_menu_link.html.erb (3.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_form_actions.html.erb (1.6ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_form.html.erb (16.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_make_sortable.html.erb (1.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (1.8ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_site_bar.html.erb (1.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_menu.html.erb (2.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_no_script.html.erb (0.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.1ms) Completed 200 OK in 58ms (Views: 30.6ms | ActiveRecord: 1.1ms)  (21.3ms) rollback transaction Refinery::Role Load (0.2ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1  (0.0ms) begin transaction Refinery::Role Exists (0.1ms) SELECT 1 AS one FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 SQL (28.4ms) INSERT INTO "refinery_roles" ("title") VALUES (?) [["title", "Refinery"]]  (90.5ms) commit transaction  (0.1ms) begin transaction Refinery::User Exists (0.2ms) SELECT 1 AS one FROM "refinery_users" WHERE "refinery_users"."email" = 'refinerycms@refinerycms.com' LIMIT 1 Refinery::User Exists (0.1ms) SELECT 1 AS one FROM "refinery_users" WHERE "refinery_users"."username" = 'refinerycms' LIMIT 1 Binary data inserted for `string` type on column `encrypted_password` SQL (42.6ms) INSERT INTO "refinery_users" ("created_at", "current_sign_in_at", "current_sign_in_ip", "email", "encrypted_password", "last_sign_in_at", "last_sign_in_ip", "remember_created_at", "reset_password_sent_at", "reset_password_token", "sign_in_count", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:19:03 UTC +00:00], ["current_sign_in_at", nil], ["current_sign_in_ip", nil], ["email", "refinerycms@refinerycms.com"], ["encrypted_password", "$2a$04$DAYGGW0B35Collx4rePsZeAW0cOAoKQqVbU9PB1fUVKiAsc86BpiC"], ["last_sign_in_at", nil], ["last_sign_in_ip", nil], ["remember_created_at", nil], ["reset_password_sent_at", nil], ["reset_password_token", nil], ["sign_in_count", nil], ["updated_at", Tue, 26 Mar 2013 23:19:03 UTC +00:00], ["username", "refinerycms"]]  (0.2ms) INSERT INTO "refinery_roles_users" ("user_id", "role_id") VALUES (1, 1)  (73.7ms) commit transaction  (0.1ms) begin transaction SQL (64.2ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_core"], ["position", 0], ["user_id", 1]]  (119.9ms) commit transaction  (0.1ms) begin transaction SQL (25.3ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_dialogs"], ["position", 1], ["user_id", 1]]  (119.1ms) commit transaction  (0.1ms) begin transaction SQL (9.0ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_menus"], ["position", 2], ["user_id", 1]]  (2.0ms) commit transaction  (0.0ms) begin transaction SQL (32.3ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_users"], ["position", 3], ["user_id", 1]]  (151.6ms) commit transaction  (0.1ms) begin transaction SQL (35.7ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_dashboard"], ["position", 4], ["user_id", 1]]  (35.0ms) commit transaction  (0.1ms) begin transaction SQL (21.4ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_images"], ["position", 5], ["user_id", 1]]  (67.1ms) commit transaction  (0.1ms) begin transaction SQL (70.9ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_files"], ["position", 6], ["user_id", 1]]  (22.1ms) commit transaction  (0.1ms) begin transaction SQL (0.5ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_pages"], ["position", 7], ["user_id", 1]]  (1.4ms) commit transaction  (0.1ms) begin transaction SQL (0.4ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_i18n"], ["position", 8], ["user_id", 1]]  (132.9ms) commit transaction Started GET "/refinery/login" for 127.0.0.1 at 2013-03-27 00:19:04 +0100 Processing by Refinery::SessionsController#new as HTML Parameters: {"locale"=>:en} Refinery::Role Load (0.2ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.2ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_form_actions.html.erb (0.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (2.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.0ms) Completed 200 OK in 11ms (Views: 6.8ms | ActiveRecord: 0.4ms) Started GET "/assets/refinery/ajax-loader.gif" for 127.0.0.1 at 2013-03-27 00:19:05 +0100  (0.1ms) begin transaction Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 43' LIMIT 1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_43' LIMIT 1 SQL (185.4ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:19:05 UTC +00:00], ["permatitle", "menu_43"], ["title", "Menu 43"], ["updated_at", Tue, 26 Mar 2013 23:19:05 UTC +00:00]] Served asset /refinery/ajax-loader.gif - 304 Not Modified (0ms)  (31.7ms) commit transaction  (0.1ms) begin transaction Refinery::Menus::Menu Load (0.1ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = 1 LIMIT 1 Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" ORDER BY "refinery_menus_links"."rgt" desc LIMIT 1 SQL (0.3ms) INSERT INTO "refinery_menus_links" ("class_attribute", "custom_url", "depth", "id_attribute", "label", "lft", "menu_match", "parent_id", "refinery_menu_id", "resource_id", "resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", nil], ["depth", nil], ["id_attribute", nil], ["label", "Link 1"], ["lft", 1], ["menu_match", nil], ["parent_id", nil], ["refinery_menu_id", 1], ["resource_id", nil], ["resource_type", nil], ["rgt", 2], ["title_attribute", nil]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 1]] SQL (0.1ms) UPDATE "refinery_menus_links" SET "depth" = 0 WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = 1 ORDER BY "refinery_menus_links"."lft")  (2.0ms) commit transaction  (0.0ms) begin transaction Refinery::Menus::Menu Load (0.1ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = 1 LIMIT 1 Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" ORDER BY "refinery_menus_links"."rgt" desc LIMIT 1 SQL (0.3ms) INSERT INTO "refinery_menus_links" ("class_attribute", "custom_url", "depth", "id_attribute", "label", "lft", "menu_match", "parent_id", "refinery_menu_id", "resource_id", "resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", nil], ["depth", nil], ["id_attribute", nil], ["label", "Link 2"], ["lft", 3], ["menu_match", nil], ["parent_id", nil], ["refinery_menu_id", 1], ["resource_id", nil], ["resource_type", nil], ["rgt", 4], ["title_attribute", nil]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 2]] SQL (0.1ms) UPDATE "refinery_menus_links" SET "depth" = 0 WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = 2 ORDER BY "refinery_menus_links"."lft")  (1.9ms) commit transaction Refinery::Menus::Menu Load (0.1ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = ? LIMIT 1 [["id", 1]] Started POST "/refinery/users/login" for 127.0.0.1 at 2013-03-27 00:19:05 +0100 Processing by Refinery::SessionsController#create as HTML Parameters: {"utf8"=>"✓", "refinery_user"=>{"login"=>"refinerycms", "password"=>"[FILTERED]", "remember_me"=>"0"}, "locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE (username = 'refinerycms' OR email = 'refinerycms') LIMIT 1  (0.0ms) begin transaction  (0.3ms) UPDATE "refinery_users" SET "last_sign_in_at" = '2013-03-26 23:19:05.326164', "current_sign_in_at" = '2013-03-26 23:19:05.326164', "last_sign_in_ip" = '127.0.0.1', "current_sign_in_ip" = '127.0.0.1', "sign_in_count" = 1, "updated_at" = '2013-03-26 23:19:05.326726' WHERE "refinery_users"."id" = 1  (1.8ms) commit transaction Redirected to http://127.0.0.1:34667/refinery Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Completed 302 Found in 16ms (ActiveRecord: 0.1ms) Started GET "/refinery" for 127.0.0.1 at 2013-03-27 00:19:05 +0100 Processing by Refinery::Admin::DashboardController#index as HTML Parameters: {"locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.1ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Refinery::User Load (0.2ms) SELECT "refinery_users".* FROM "refinery_users" ORDER BY updated_at DESC LIMIT 7 Refinery::Image Load (0.1ms) SELECT "refinery_images".* FROM "refinery_images" ORDER BY updated_at DESC LIMIT 7 Refinery::Resource Load (0.1ms) SELECT "refinery_resources".* FROM "refinery_resources" ORDER BY updated_at DESC LIMIT 7 Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" ORDER BY updated_at DESC LIMIT 7 Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_recent_activity.html.erb (1.5ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_records.html.erb (1.8ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_additional_dashboard_menu_items.html.erb (0.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_actions.html.erb (56.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (2.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_site_bar.html.erb (1.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_menu.html.erb (2.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_no_script.html.erb (0.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.2ms) Completed 200 OK in 74ms (Views: 66.4ms | ActiveRecord: 1.1ms) Started GET "/menus/refinery/menus/1/edit" for 127.0.0.1 at 2013-03-27 00:19:05 +0100 Processing by Refinery::Menus::Admin::MenusController#edit as HTML Parameters: {"id"=>"1", "locale"=>:en} Refinery::Menus::Menu Load (0.1ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = ? LIMIT 1 [["id", "1"]] Refinery::Menus::MenuLink Load (0.2ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."refinery_menu_id" IN (1) ORDER BY lft ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.2ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.1ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Refinery::Menus::LinkResource Load (0.1ms) SELECT "support_resource".* FROM "support_resource" Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_resource_link_editor_box.html.erb (1.9ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_form_actions.html.erb (4.6ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_error_messages.html.erb (0.0ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_custom_link.html.erb (2.0ms) Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."parent_id" = 1 ORDER BY lft Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_menu_link.html.erb (3.7ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_custom_link.html.erb (2.0ms) Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."parent_id" = 2 ORDER BY lft Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_menu_link.html.erb (3.6ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_form_actions.html.erb (2.8ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_form.html.erb (12.4ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_make_sortable.html.erb (1.4ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (1.9ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_site_bar.html.erb (1.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_menu.html.erb (2.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_no_script.html.erb (0.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.0ms) Completed 200 OK in 34ms (Views: 26.5ms | ActiveRecord: 1.3ms) Started DELETE "/menus/refinery/menu_links/1" for 127.0.0.1 at 2013-03-27 00:19:05 +0100 Processing by Refinery::Menus::Admin::MenuLinksController#destroy as JS Parameters: {"id"=>"1", "locale"=>:en} Refinery::Role Load (0.2ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.1ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", "1"]]  (0.0ms) begin transaction Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links"."lft", "refinery_menus_links"."rgt", "refinery_menus_links"."parent_id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 1]] Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" >= 1 AND "refinery_menus_links"."lft" < 2) AND ("refinery_menus_links".id != 1) ORDER BY "refinery_menus_links"."lft" SQL (0.3ms) UPDATE "refinery_menus_links" SET "lft" = ("lft" - 2) WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" > 2) ORDER BY "refinery_menus_links"."lft") SQL (0.1ms) UPDATE "refinery_menus_links" SET "rgt" = ("rgt" - 2) WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE ("refinery_menus_links"."rgt" > 2) ORDER BY "refinery_menus_links"."lft") SQL (0.1ms) DELETE FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? [["id", 1]]  (2.2ms) commit transaction Completed 200 OK in 54ms (Views: 31.1ms | ActiveRecord: 3.7ms)  (2.0ms) DELETE FROM "refinery_images";  (0.1ms) DELETE FROM sqlite_sequence where name = 'refinery_images';  (1.7ms) DELETE FROM "refinery_menus";  (2.5ms) DELETE FROM sqlite_sequence where name = 'refinery_menus';  (1.6ms) DELETE FROM "refinery_menus_links";  (1.4ms) DELETE FROM sqlite_sequence where name = 'refinery_menus_links';  (1.8ms) DELETE FROM "refinery_page_part_translations";  (0.1ms) DELETE FROM sqlite_sequence where name = 'refinery_page_part_translations';  (2.1ms) DELETE FROM "refinery_page_parts";  (0.1ms) DELETE FROM sqlite_sequence where name = 'refinery_page_parts';  (2.0ms) DELETE FROM "refinery_page_translations";  (0.1ms) DELETE FROM sqlite_sequence where name = 'refinery_page_translations';  (2.0ms) DELETE FROM "refinery_pages";  (0.1ms) DELETE FROM sqlite_sequence where name = 'refinery_pages';  (1.5ms) DELETE FROM "refinery_resources";  (0.1ms) DELETE FROM sqlite_sequence where name = 'refinery_resources';  (1.9ms) DELETE FROM "refinery_roles";  (1.6ms) DELETE FROM sqlite_sequence where name = 'refinery_roles';  (1.6ms) DELETE FROM "refinery_roles_users";  (0.1ms) DELETE FROM sqlite_sequence where name = 'refinery_roles_users';  (1.7ms) DELETE FROM "refinery_user_plugins";  (1.6ms) DELETE FROM sqlite_sequence where name = 'refinery_user_plugins';  (1.4ms) DELETE FROM "refinery_users";  (1.8ms) DELETE FROM sqlite_sequence where name = 'refinery_users';  (1.6ms) DELETE FROM "seo_meta";  (0.1ms) DELETE FROM sqlite_sequence where name = 'seo_meta';  (1.6ms) DELETE FROM "support_resource";  (0.1ms) DELETE FROM sqlite_sequence where name = 'support_resource';  (1.7ms) DELETE FROM "support_another_resource";  (0.1ms) DELETE FROM sqlite_sequence where name = 'support_another_resource';  (0.1ms) begin transaction Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1  (0.0ms) SAVEPOINT active_record_1 Refinery::Role Exists (0.0ms) SELECT 1 AS one FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 SQL (150.0ms) INSERT INTO "refinery_roles" ("title") VALUES (?) [["title", "Refinery"]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Refinery::User Exists (0.1ms) SELECT 1 AS one FROM "refinery_users" WHERE "refinery_users"."email" = 'refinerycms@refinerycms.com' LIMIT 1 Refinery::User Exists (0.1ms) SELECT 1 AS one FROM "refinery_users" WHERE "refinery_users"."username" = 'refinerycms' LIMIT 1 Binary data inserted for `string` type on column `encrypted_password` SQL (0.5ms) INSERT INTO "refinery_users" ("created_at", "current_sign_in_at", "current_sign_in_ip", "email", "encrypted_password", "last_sign_in_at", "last_sign_in_ip", "remember_created_at", "reset_password_sent_at", "reset_password_token", "sign_in_count", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:19:06 UTC +00:00], ["current_sign_in_at", nil], ["current_sign_in_ip", nil], ["email", "refinerycms@refinerycms.com"], ["encrypted_password", "$2a$04$Brt4OIkdfbuKwlpw7ZJ1xe07JK0WHAmC/WNxcKydvdHMoU/ONw7/a"], ["last_sign_in_at", nil], ["last_sign_in_ip", nil], ["remember_created_at", nil], ["reset_password_sent_at", nil], ["reset_password_token", nil], ["sign_in_count", nil], ["updated_at", Tue, 26 Mar 2013 23:19:06 UTC +00:00], ["username", "refinerycms"]]  (0.1ms) INSERT INTO "refinery_roles_users" ("user_id", "role_id") VALUES (1, 1)  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_core"], ["position", 0], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_dialogs"], ["position", 1], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_menus"], ["position", 2], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_users"], ["position", 3], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_dashboard"], ["position", 4], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_images"], ["position", 5], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_files"], ["position", 6], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_pages"], ["position", 7], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_i18n"], ["position", 8], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/refinery/login" for 127.0.0.1 at 2013-03-27 00:19:06 +0100 Processing by Refinery::SessionsController#new as HTML Parameters: {"locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_form_actions.html.erb (0.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.4ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (2.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.0ms) Completed 200 OK in 11ms (Views: 7.8ms | ActiveRecord: 0.2ms) Started POST "/refinery/users/login" for 127.0.0.1 at 2013-03-27 00:19:06 +0100 Processing by Refinery::SessionsController#create as HTML Parameters: {"utf8"=>"✓", "refinery_user"=>{"login"=>"refinerycms", "password"=>"[FILTERED]", "remember_me"=>"0"}, "locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE (username = 'refinerycms' OR email = 'refinerycms') LIMIT 1  (0.0ms) SAVEPOINT active_record_1  (0.1ms) UPDATE "refinery_users" SET "last_sign_in_at" = '2013-03-26 23:19:06.411575', "current_sign_in_at" = '2013-03-26 23:19:06.411575', "last_sign_in_ip" = '127.0.0.1', "current_sign_in_ip" = '127.0.0.1', "sign_in_count" = 1, "updated_at" = '2013-03-26 23:19:06.412160' WHERE "refinery_users"."id" = 1  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://www.example.com/refinery Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Completed 302 Found in 12ms (ActiveRecord: 0.1ms) Started GET "/refinery" for 127.0.0.1 at 2013-03-27 00:19:06 +0100 Processing by Refinery::Admin::DashboardController#index as HTML Parameters: {"locale"=>:en} Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.1ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" ORDER BY updated_at DESC LIMIT 7 Refinery::Image Load (0.1ms) SELECT "refinery_images".* FROM "refinery_images" ORDER BY updated_at DESC LIMIT 7 Refinery::Resource Load (0.1ms) SELECT "refinery_resources".* FROM "refinery_resources" ORDER BY updated_at DESC LIMIT 7 Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" ORDER BY updated_at DESC LIMIT 7 Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_recent_activity.html.erb (1.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_records.html.erb (1.5ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_additional_dashboard_menu_items.html.erb (0.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-dashboard-2.0.9/app/views/refinery/admin/dashboard/_actions.html.erb (7.6ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (1.9ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_site_bar.html.erb (1.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_menu.html.erb (2.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_no_script.html.erb (0.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.2ms) Completed 200 OK in 23ms (Views: 16.6ms | ActiveRecord: 0.7ms)  (0.1ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Exists (0.1ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."title" = 'Menu 44' LIMIT 1 Refinery::Menus::Menu Exists (0.0ms) SELECT 1 AS one FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'menu_44' LIMIT 1 SQL (0.2ms) INSERT INTO "refinery_menus" ("created_at", "permatitle", "title", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Tue, 26 Mar 2013 23:19:06 UTC +00:00], ["permatitle", "menu_44"], ["title", "Menu 44"], ["updated_at", Tue, 26 Mar 2013 23:19:06 UTC +00:00]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Load (0.1ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = 1 LIMIT 1 Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" ORDER BY "refinery_menus_links"."rgt" desc LIMIT 1 SQL (0.2ms) INSERT INTO "refinery_menus_links" ("class_attribute", "custom_url", "depth", "id_attribute", "label", "lft", "menu_match", "parent_id", "refinery_menu_id", "resource_id", "resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", nil], ["depth", nil], ["id_attribute", nil], ["label", "Our Company"], ["lft", 1], ["menu_match", nil], ["parent_id", nil], ["refinery_menu_id", 1], ["resource_id", nil], ["resource_type", nil], ["rgt", 2], ["title_attribute", nil]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 1]] SQL (0.1ms) UPDATE "refinery_menus_links" SET "depth" = 0 WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = 1 ORDER BY "refinery_menus_links"."lft")  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Load (0.1ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = 1 LIMIT 1 Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" ORDER BY "refinery_menus_links"."rgt" desc LIMIT 1 SQL (0.1ms) INSERT INTO "refinery_menus_links" ("class_attribute", "custom_url", "depth", "id_attribute", "label", "lft", "menu_match", "parent_id", "refinery_menu_id", "resource_id", "resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", nil], ["depth", nil], ["id_attribute", nil], ["label", "Our Team"], ["lft", 3], ["menu_match", nil], ["parent_id", 1], ["refinery_menu_id", 1], ["resource_id", nil], ["resource_type", nil], ["rgt", 4], ["title_attribute", nil]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? ORDER BY "refinery_menus_links"."lft" LIMIT 1 [["id", 1]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links"."lft", "refinery_menus_links"."rgt", "refinery_menus_links"."parent_id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 2]] SQL (0.1ms) UPDATE "refinery_menus_links" SET "lft" = CASE WHEN "lft" BETWEEN 2 AND 2 THEN "lft" + 4 - 2 WHEN "lft" BETWEEN 3 AND 4 THEN "lft" + 2 - 3 ELSE "lft" END, "rgt" = CASE WHEN "rgt" BETWEEN 2 AND 2 THEN "rgt" + 4 - 2 WHEN "rgt" BETWEEN 3 AND 4 THEN "rgt" + 2 - 3 ELSE "rgt" END, "parent_id" = CASE WHEN id = 2 THEN 1 ELSE "parent_id" END WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" ORDER BY "refinery_menus_links"."lft") Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links"."lft", "refinery_menus_links"."rgt", "refinery_menus_links"."parent_id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 1]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 2]]  (0.1ms) SELECT COUNT(*) FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" <= 2 AND "refinery_menus_links"."rgt" >= 3) AND ("refinery_menus_links".id != 2) SQL (0.1ms) UPDATE "refinery_menus_links" SET "depth" = 1 WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = 2 ORDER BY "refinery_menus_links"."lft")  (0.1ms) SELECT COUNT(*) FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" <= 2 AND "refinery_menus_links"."rgt" >= 3) AND ("refinery_menus_links".id != 2) Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" >= 2 AND "refinery_menus_links"."lft" < 3) AND ("refinery_menus_links".id != 2) ORDER BY "refinery_menus_links"."lft" Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links"."lft", "refinery_menus_links"."rgt", "refinery_menus_links"."parent_id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 2]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 2]]  (0.1ms) SELECT COUNT(*) FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" <= 2 AND "refinery_menus_links"."rgt" >= 3) AND ("refinery_menus_links".id != 2) SQL (0.1ms) UPDATE "refinery_menus_links" SET "depth" = 1 WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = 2 ORDER BY "refinery_menus_links"."lft")  (0.1ms) SELECT COUNT(*) FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" <= 2 AND "refinery_menus_links"."rgt" >= 3) AND ("refinery_menus_links".id != 2)  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Load (0.1ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = 1 LIMIT 1 Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" ORDER BY "refinery_menus_links"."rgt" desc LIMIT 1 SQL (0.1ms) INSERT INTO "refinery_menus_links" ("class_attribute", "custom_url", "depth", "id_attribute", "label", "lft", "menu_match", "parent_id", "refinery_menu_id", "resource_id", "resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", nil], ["depth", nil], ["id_attribute", nil], ["label", "Our Locations"], ["lft", 5], ["menu_match", nil], ["parent_id", 2], ["refinery_menu_id", 1], ["resource_id", nil], ["resource_type", nil], ["rgt", 6], ["title_attribute", nil]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? ORDER BY "refinery_menus_links"."lft" LIMIT 1 [["id", 2]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links"."lft", "refinery_menus_links"."rgt", "refinery_menus_links"."parent_id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 3]] SQL (0.1ms) UPDATE "refinery_menus_links" SET "lft" = CASE WHEN "lft" BETWEEN 3 AND 4 THEN "lft" + 6 - 4 WHEN "lft" BETWEEN 5 AND 6 THEN "lft" + 3 - 5 ELSE "lft" END, "rgt" = CASE WHEN "rgt" BETWEEN 3 AND 4 THEN "rgt" + 6 - 4 WHEN "rgt" BETWEEN 5 AND 6 THEN "rgt" + 3 - 5 ELSE "rgt" END, "parent_id" = CASE WHEN id = 3 THEN 2 ELSE "parent_id" END WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" ORDER BY "refinery_menus_links"."lft") Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links"."lft", "refinery_menus_links"."rgt", "refinery_menus_links"."parent_id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 2]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 3]]  (0.1ms) SELECT COUNT(*) FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" <= 3 AND "refinery_menus_links"."rgt" >= 4) AND ("refinery_menus_links".id != 3) SQL (0.1ms) UPDATE "refinery_menus_links" SET "depth" = 2 WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = 3 ORDER BY "refinery_menus_links"."lft")  (0.1ms) SELECT COUNT(*) FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" <= 3 AND "refinery_menus_links"."rgt" >= 4) AND ("refinery_menus_links".id != 3) Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" >= 3 AND "refinery_menus_links"."lft" < 4) AND ("refinery_menus_links".id != 3) ORDER BY "refinery_menus_links"."lft" Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links"."lft", "refinery_menus_links"."rgt", "refinery_menus_links"."parent_id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 3]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 3]]  (0.1ms) SELECT COUNT(*) FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" <= 3 AND "refinery_menus_links"."rgt" >= 4) AND ("refinery_menus_links".id != 3) SQL (0.1ms) UPDATE "refinery_menus_links" SET "depth" = 2 WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = 3 ORDER BY "refinery_menus_links"."lft")  (0.1ms) SELECT COUNT(*) FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" <= 3 AND "refinery_menus_links"."rgt" >= 4) AND ("refinery_menus_links".id != 3)  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Load (0.1ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = 1 LIMIT 1 Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" ORDER BY "refinery_menus_links"."rgt" desc LIMIT 1 SQL (0.1ms) INSERT INTO "refinery_menus_links" ("class_attribute", "custom_url", "depth", "id_attribute", "label", "lft", "menu_match", "parent_id", "refinery_menu_id", "resource_id", "resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", nil], ["depth", nil], ["id_attribute", nil], ["label", "New York"], ["lft", 7], ["menu_match", nil], ["parent_id", 3], ["refinery_menu_id", 1], ["resource_id", nil], ["resource_type", nil], ["rgt", 8], ["title_attribute", nil]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? ORDER BY "refinery_menus_links"."lft" LIMIT 1 [["id", 3]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links"."lft", "refinery_menus_links"."rgt", "refinery_menus_links"."parent_id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 4]] SQL (0.1ms) UPDATE "refinery_menus_links" SET "lft" = CASE WHEN "lft" BETWEEN 4 AND 6 THEN "lft" + 8 - 6 WHEN "lft" BETWEEN 7 AND 8 THEN "lft" + 4 - 7 ELSE "lft" END, "rgt" = CASE WHEN "rgt" BETWEEN 4 AND 6 THEN "rgt" + 8 - 6 WHEN "rgt" BETWEEN 7 AND 8 THEN "rgt" + 4 - 7 ELSE "rgt" END, "parent_id" = CASE WHEN id = 4 THEN 3 ELSE "parent_id" END WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" ORDER BY "refinery_menus_links"."lft") Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links"."lft", "refinery_menus_links"."rgt", "refinery_menus_links"."parent_id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 3]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 4]]  (0.1ms) SELECT COUNT(*) FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" <= 4 AND "refinery_menus_links"."rgt" >= 5) AND ("refinery_menus_links".id != 4) SQL (0.1ms) UPDATE "refinery_menus_links" SET "depth" = 3 WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = 4 ORDER BY "refinery_menus_links"."lft")  (0.1ms) SELECT COUNT(*) FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" <= 4 AND "refinery_menus_links"."rgt" >= 5) AND ("refinery_menus_links".id != 4) Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" >= 4 AND "refinery_menus_links"."lft" < 5) AND ("refinery_menus_links".id != 4) ORDER BY "refinery_menus_links"."lft" Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links"."lft", "refinery_menus_links"."rgt", "refinery_menus_links"."parent_id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 4]] Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 4]]  (0.2ms) SELECT COUNT(*) FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" <= 4 AND "refinery_menus_links"."rgt" >= 5) AND ("refinery_menus_links".id != 4) SQL (0.2ms) UPDATE "refinery_menus_links" SET "depth" = 3 WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = 4 ORDER BY "refinery_menus_links"."lft")  (0.1ms) SELECT COUNT(*) FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" <= 4 AND "refinery_menus_links"."rgt" >= 5) AND ("refinery_menus_links".id != 4)  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Refinery::Menus::Menu Load (0.2ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = 1 LIMIT 1 Refinery::Menus::MenuLink Load (0.2ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" ORDER BY "refinery_menus_links"."rgt" desc LIMIT 1 SQL (0.2ms) INSERT INTO "refinery_menus_links" ("class_attribute", "custom_url", "depth", "id_attribute", "label", "lft", "menu_match", "parent_id", "refinery_menu_id", "resource_id", "resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", nil], ["depth", nil], ["id_attribute", nil], ["label", "About"], ["lft", 9], ["menu_match", nil], ["parent_id", nil], ["refinery_menu_id", 1], ["resource_id", nil], ["resource_type", nil], ["rgt", 10], ["title_attribute", nil]] Refinery::Menus::MenuLink Load (0.0ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", 5]] SQL (0.2ms) UPDATE "refinery_menus_links" SET "depth" = 0 WHERE "refinery_menus_links"."id" IN (SELECT "refinery_menus_links"."id" FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = 5 ORDER BY "refinery_menus_links"."lft")  (0.0ms) RELEASE SAVEPOINT active_record_1 Refinery::Menus::Menu Load (0.0ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = ? LIMIT 1 [["id", 1]] Started GET "/menus/refinery/menus/1/edit" for 127.0.0.1 at 2013-03-27 00:19:06 +0100 Processing by Refinery::Menus::Admin::MenusController#edit as HTML Parameters: {"id"=>"1", "locale"=>:en} Refinery::Menus::Menu Load (0.1ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."id" = ? LIMIT 1 [["id", "1"]] Refinery::Menus::MenuLink Load (0.2ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."refinery_menu_id" IN (1) ORDER BY lft ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" INNER JOIN "refinery_roles_users" ON "refinery_users"."id" = "refinery_roles_users"."user_id" WHERE "refinery_roles_users"."role_id" = 1 Refinery::User Load (0.1ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 Refinery::UserPlugin Load (0.1ms) SELECT "refinery_user_plugins".* FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1 ORDER BY position ASC Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" INNER JOIN "refinery_roles_users" ON "refinery_roles"."id" = "refinery_roles_users"."role_id" WHERE "refinery_roles_users"."user_id" = 1 Refinery::Menus::LinkResource Load (0.1ms) SELECT "support_resource".* FROM "support_resource"  Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_resource_link_editor_box.html.erb (2.0ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_form_actions.html.erb (4.9ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_error_messages.html.erb (0.1ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_custom_link.html.erb (2.2ms) Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."parent_id" = 1 ORDER BY lft Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_custom_link.html.erb (2.1ms) Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."parent_id" = 2 ORDER BY lft Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_custom_link.html.erb (2.2ms) Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."parent_id" = 3 ORDER BY lft Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_custom_link.html.erb (2.0ms) Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."parent_id" = 4 ORDER BY lft Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_menu_link.html.erb (4.0ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_menu_link.html.erb (8.5ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_menu_link.html.erb (13.1ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_menu_link.html.erb (70.1ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_custom_link.html.erb (2.3ms) Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."parent_id" = 5 ORDER BY lft Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_menu_link.html.erb (5.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_form_actions.html.erb (2.0ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_form.html.erb (80.7ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_make_sortable.html.erb (1.6ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_html_tag.html.erb (0.1ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_javascripts.html.erb (1.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_head.html.erb (1.9ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_site_bar.html.erb (1.2ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/admin/_menu.html.erb (2.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_no_script.html.erb (0.3ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_message.html.erb (0.1ms) Completed 200 OK in 104ms (Views: 95.6ms | ActiveRecord: 1.4ms)  (66.2ms) rollback transaction