Connecting to database specified by database.yml  (0.1ms) select sqlite_version(*)  (2.4ms) CREATE TABLE "schema_migrations" ("version" varchar(255) NOT NULL)  (1.4ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (3.2ms) SELECT "schema_migrations"."version" FROM "schema_migrations" Migrating to CreatePageMenus (20130204144343)  (0.0ms) begin transaction  (0.4ms) CREATE TABLE "refinery_page_positions" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "parent_id" integer, "lft" integer, "rgt" integer, "depth" integer, "refinery_page_id" integer, "refinery_menu_id" integer)  (0.1ms) CREATE INDEX "index_refinery_page_positions_on_depth" ON "refinery_page_positions" ("depth")  (0.1ms) CREATE INDEX "index_refinery_page_positions_on_id" ON "refinery_page_positions" ("id")  (0.1ms) CREATE INDEX "index_refinery_page_positions_on_lft" ON "refinery_page_positions" ("lft")  (0.1ms) CREATE INDEX "index_refinery_page_positions_on_parent_id" ON "refinery_page_positions" ("parent_id")  (0.1ms) CREATE INDEX "index_refinery_page_positions_on_rgt" ON "refinery_page_positions" ("rgt")  (0.1ms) CREATE TABLE "refinery_page_menus" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar(255), "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)  (0.1ms) INSERT INTO "schema_migrations" ("version") VALUES ('20130204144343')  (2.2ms) commit transaction Migrating to AddPermatitleToPageMenus (20130204144344)  (0.1ms) begin transaction  (0.3ms) ALTER TABLE "refinery_page_menus" ADD "permatitle" varchar(255)  (0.2ms) CREATE UNIQUE INDEX "index_refinery_page_menus_on_permatitle" ON "refinery_page_menus" ("permatitle")  (0.1ms) INSERT INTO "schema_migrations" ("version") VALUES ('20130204144344')  (1.5ms) commit transaction Migrating to AddMenuMatchToPagePositions (20130204144345)  (0.0ms) begin transaction  (0.3ms) ALTER TABLE "refinery_page_positions" ADD "menu_match" varchar(255)  (0.1ms) INSERT INTO "schema_migrations" ("version") VALUES ('20130204144345')  (1.7ms) commit transaction Migrating to AddFlexFieldsToPagePositions (20130204144346)  (0.1ms) begin transaction  (0.4ms) ALTER TABLE "refinery_page_positions" ADD "refinery_resource_id" integer  (0.1ms) ALTER TABLE "refinery_page_positions" ADD "refinery_resource_type" varchar(255)  (0.1ms) ALTER TABLE "refinery_page_positions" ADD "title_attribute" varchar(255)  (0.1ms) ALTER TABLE "refinery_page_positions" ADD "custom_url" varchar(255)  (0.1ms) ALTER TABLE "refinery_page_positions" ADD "label" varchar(255)  (0.1ms) INSERT INTO "schema_migrations" ("version") VALUES ('20130204144346')  (1.5ms) commit transaction Migrating to RemoveRefineryPageIdFromRefineryPagePositions (20130204144347)  (0.0ms) begin transaction  (0.4ms) CREATE TEMPORARY TABLE "altered_refinery_page_positions" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "parent_id" integer, "lft" integer, "rgt" integer, "depth" integer, "refinery_page_id" integer, "refinery_menu_id" integer, "menu_match" varchar(255), "refinery_resource_id" integer, "refinery_resource_type" varchar(255), "title_attribute" varchar(255), "custom_url" varchar(255), "label" varchar(255))   (0.1ms) CREATE INDEX "temp_index_altered_refinery_page_positions_on_rgt" ON "altered_refinery_page_positions" ("rgt")  (0.1ms) CREATE INDEX "temp_index_altered_refinery_page_positions_on_parent_id" ON "altered_refinery_page_positions" ("parent_id")  (0.1ms) CREATE INDEX "temp_index_altered_refinery_page_positions_on_lft" ON "altered_refinery_page_positions" ("lft")  (0.1ms) CREATE INDEX "temp_index_altered_refinery_page_positions_on_id" ON "altered_refinery_page_positions" ("id")  (0.1ms) CREATE INDEX "temp_index_altered_refinery_page_positions_on_depth" ON "altered_refinery_page_positions" ("depth")  (0.1ms) SELECT * FROM "refinery_page_positions"  (0.4ms) DROP TABLE "refinery_page_positions"  (0.1ms) CREATE TABLE "refinery_page_positions" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "parent_id" integer, "lft" integer, "rgt" integer, "depth" integer, "refinery_menu_id" integer, "menu_match" varchar(255), "refinery_resource_id" integer, "refinery_resource_type" varchar(255), "title_attribute" varchar(255), "custom_url" varchar(255), "label" varchar(255))   (0.1ms) CREATE INDEX "index_refinery_page_positions_on_depth" ON "refinery_page_positions" ("depth")  (0.1ms) CREATE INDEX "index_refinery_page_positions_on_id" ON "refinery_page_positions" ("id")  (0.1ms) CREATE INDEX "index_refinery_page_positions_on_lft" ON "refinery_page_positions" ("lft")  (0.1ms) CREATE INDEX "index_refinery_page_positions_on_parent_id" ON "refinery_page_positions" ("parent_id")  (0.1ms) CREATE INDEX "index_refinery_page_positions_on_rgt" ON "refinery_page_positions" ("rgt")  (0.1ms) SELECT * FROM "altered_refinery_page_positions"  (0.5ms) DROP TABLE "altered_refinery_page_positions"  (0.1ms) INSERT INTO "schema_migrations" ("version") VALUES ('20130204144347')  (1.7ms) commit transaction Migrating to RenamePagePositionsToMenuLinks (20130204144348)  (0.0ms) begin transaction  (0.4ms) ALTER TABLE "refinery_page_positions" RENAME TO "refinery_menu_links"  (0.1ms) INSERT INTO "schema_migrations" ("version") VALUES ('20130204144348')  (1.0ms) commit transaction Migrating to AddClassAndIdAttributeToMenuLinks (20130204144349)  (0.0ms) begin transaction  (0.4ms) ALTER TABLE "refinery_menu_links" ADD "id_attribute" varchar(255)  (0.2ms) ALTER TABLE "refinery_menu_links" ADD "class_attribute" varchar(255)  (0.1ms) INSERT INTO "schema_migrations" ("version") VALUES ('20130204144349')  (1.1ms) commit transaction Migrating to RenamePageMenusToMenus (20130204144350)  (0.0ms) begin transaction  (0.3ms) ALTER TABLE "refinery_page_menus" RENAME TO "refinery_menus"  (0.1ms) INSERT INTO "schema_migrations" ("version") VALUES ('20130204144350')  (1.0ms) commit transaction Migrating to AddNamespaceToTables (20130204144351)  (0.0ms) begin transaction  (0.4ms) ALTER TABLE "refinery_menu_links" RENAME TO "refinery_menus_links"  (0.1ms) INSERT INTO "schema_migrations" ("version") VALUES ('20130204144351')  (1.0ms) commit transaction Migrating to CreateRefinerycmsAuthenticationSchema (20130204144352)  (0.0ms) begin transaction  (0.3ms) CREATE TABLE "refinery_roles_users" ("user_id" integer, "role_id" integer)   (0.1ms) CREATE INDEX "index_refinery_roles_users_on_role_id_and_user_id" ON "refinery_roles_users" ("role_id", "user_id")  (0.1ms) CREATE INDEX "index_refinery_roles_users_on_user_id_and_role_id" ON "refinery_roles_users" ("user_id", "role_id")  (0.1ms) CREATE TABLE "refinery_roles" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar(255))  (0.1ms) CREATE TABLE "refinery_user_plugins" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "user_id" integer, "name" varchar(255), "position" integer)   (0.1ms) CREATE INDEX "index_refinery_user_plugins_on_name" ON "refinery_user_plugins" ("name")  (0.3ms) CREATE UNIQUE INDEX "index_refinery_user_plugins_on_user_id_and_name" ON "refinery_user_plugins" ("user_id", "name")  (0.2ms) CREATE TABLE "refinery_users" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "username" varchar(255) NOT NULL, "email" varchar(255) NOT NULL, "encrypted_password" varchar(255) NOT NULL, "current_sign_in_at" datetime, "last_sign_in_at" datetime, "current_sign_in_ip" varchar(255), "last_sign_in_ip" varchar(255), "sign_in_count" integer, "remember_created_at" datetime, "reset_password_token" varchar(255), "reset_password_sent_at" datetime, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)  (0.1ms) CREATE INDEX "index_refinery_users_on_id" ON "refinery_users" ("id")  (0.1ms) INSERT INTO "schema_migrations" ("version") VALUES ('20130204144352')  (1.4ms) commit transaction Migrating to CreateRefinerycmsImagesSchema (20130204144353)  (0.0ms) begin transaction  (0.4ms) CREATE TABLE "refinery_images" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "image_mime_type" varchar(255), "image_name" varchar(255), "image_size" integer, "image_width" integer, "image_height" integer, "image_uid" varchar(255), "image_ext" varchar(255), "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)   (0.1ms) INSERT INTO "schema_migrations" ("version") VALUES ('20130204144353')  (1.4ms) commit transaction Migrating to CreateRefinerycmsResourcesSchema (20130204144354)  (0.1ms) begin transaction  (0.3ms) CREATE TABLE "refinery_resources" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "file_mime_type" varchar(255), "file_name" varchar(255), "file_size" integer, "file_uid" varchar(255), "file_ext" varchar(255), "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)   (0.1ms) INSERT INTO "schema_migrations" ("version") VALUES ('20130204144354')  (1.5ms) commit transaction Migrating to CreateRefinerycmsPagesSchema (20130204144355)  (0.1ms) begin transaction  (0.5ms) CREATE TABLE "refinery_page_parts" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "refinery_page_id" integer, "title" varchar(255), "body" text, "position" integer, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)   (0.3ms) CREATE INDEX "index_refinery_page_parts_on_id" ON "refinery_page_parts" ("id")  (0.1ms) CREATE INDEX "index_refinery_page_parts_on_refinery_page_id" ON "refinery_page_parts" ("refinery_page_id")  (0.2ms) CREATE TABLE "refinery_pages" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "parent_id" integer, "path" varchar(255), "slug" varchar(255), "show_in_menu" boolean DEFAULT 't', "link_url" varchar(255), "menu_match" varchar(255), "deletable" boolean DEFAULT 't', "draft" boolean DEFAULT 'f', "skip_to_first_child" boolean DEFAULT 'f', "lft" integer, "rgt" integer, "depth" integer, "view_template" varchar(255), "layout_template" varchar(255), "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)  (0.1ms) CREATE INDEX "index_refinery_pages_on_depth" ON "refinery_pages" ("depth")  (0.1ms) CREATE INDEX "index_refinery_pages_on_id" ON "refinery_pages" ("id")  (0.1ms) CREATE INDEX "index_refinery_pages_on_lft" ON "refinery_pages" ("lft")  (0.2ms) CREATE INDEX "index_refinery_pages_on_parent_id" ON "refinery_pages" ("parent_id")  (0.1ms) CREATE INDEX "index_refinery_pages_on_rgt" ON "refinery_pages" ("rgt")  (0.1ms) CREATE TABLE "refinery_page_part_translations" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "refinery_page_part_id" integer, "locale" varchar(255), "body" text, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)  (0.1ms) CREATE INDEX "index_f9716c4215584edbca2557e32706a5ae084a15ef" ON "refinery_page_part_translations" ("refinery_page_part_id")  (0.1ms) CREATE INDEX "index_refinery_page_part_translations_on_locale" ON "refinery_page_part_translations" ("locale")  (0.1ms) CREATE TABLE "refinery_page_translations" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "refinery_page_id" integer, "locale" varchar(255), "title" varchar(255), "custom_slug" varchar(255), "menu_title" varchar(255), "slug" varchar(255), "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)   (0.1ms) CREATE INDEX "index_d079468f88bff1c6ea81573a0d019ba8bf5c2902" ON "refinery_page_translations" ("refinery_page_id")  (0.1ms) CREATE INDEX "index_refinery_page_translations_on_locale" ON "refinery_page_translations" ("locale")  (0.1ms) INSERT INTO "schema_migrations" ("version") VALUES ('20130204144355')  (2.3ms) commit transaction Migrating to CreateSeoMeta (20130204144356)  (0.0ms) begin transaction  (0.4ms) CREATE TABLE "seo_meta" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "seo_meta_id" integer, "seo_meta_type" varchar(255), "browser_title" varchar(255), "meta_keywords" varchar(255), "meta_description" text, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)   (0.1ms) CREATE INDEX "index_seo_meta_on_id" ON "seo_meta" ("id")  (0.1ms) CREATE INDEX "index_seo_meta_on_seo_meta_id_and_seo_meta_type" ON "seo_meta" ("seo_meta_id", "seo_meta_type")  (0.1ms) INSERT INTO "schema_migrations" ("version") VALUES ('20130204144356')  (1.9ms) commit transaction  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations" Connecting to database specified by database.yml  (3.6ms) SELECT "schema_migrations"."version" FROM "schema_migrations"   (0.1ms) SELECT COUNT(*) FROM "refinery_pages" WHERE "refinery_pages"."menu_match" = '^/$' Refinery::Page::Translation Load (0.2ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" IS NULL AND "refinery_page_translations"."locale" = 'en' LIMIT 1  (0.1ms) begin transaction Refinery::Page Load (0.3ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."parent_id" IS NULL AND ("slug" = 'home' OR "slug" LIKE 'home--%'ESCAPE '\') ORDER BY LENGTH("slug") DESC, "slug" DESC LIMIT 1 SQL (3.2ms) INSERT INTO "refinery_page_translations" ("created_at", "custom_slug", "locale", "menu_title", "refinery_page_id", "slug", "title", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 04 Feb 2013 14:44:00 UTC +00:00], ["custom_slug", nil], ["locale", "en"], ["menu_title", nil], ["refinery_page_id", nil], ["slug", nil], ["title", nil], ["updated_at", Mon, 04 Feb 2013 14:44:00 UTC +00:00]] SeoMetum Load (0.1ms) SELECT "seo_meta".* FROM "seo_meta" WHERE "seo_meta"."seo_meta_type" = 'Refinery::Page::Translation' AND "seo_meta"."seo_meta_id" = 1 LIMIT 1 SQL (0.5ms) INSERT INTO "seo_meta" ("browser_title", "created_at", "meta_description", "meta_keywords", "seo_meta_id", "seo_meta_type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["browser_title", nil], ["created_at", Mon, 04 Feb 2013 14:44:00 UTC +00:00], ["meta_description", nil], ["meta_keywords", nil], ["seo_meta_id", 1], ["seo_meta_type", "Refinery::Page::Translation"], ["updated_at", Mon, 04 Feb 2013 14:44:00 UTC +00:00]] Refinery::Page Load (0.2ms) SELECT "refinery_pages".* FROM "refinery_pages" ORDER BY "refinery_pages"."rgt" desc LIMIT 1 SQL (0.5ms) INSERT INTO "refinery_pages" ("created_at", "deletable", "depth", "draft", "layout_template", "lft", "link_url", "menu_match", "parent_id", "path", "rgt", "show_in_menu", "skip_to_first_child", "slug", "updated_at", "view_template") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 04 Feb 2013 14:44:00 UTC +00:00], ["deletable", false], ["depth", nil], ["draft", false], ["layout_template", nil], ["lft", 1], ["link_url", "/"], ["menu_match", "^/$"], ["parent_id", nil], ["path", nil], ["rgt", 2], ["show_in_menu", true], ["skip_to_first_child", false], ["slug", "home"], ["updated_at", Mon, 04 Feb 2013 14:44:00 UTC +00:00], ["view_template", nil]]  (0.4ms) UPDATE "refinery_page_translations" SET "refinery_page_id" = 1, "updated_at" = '2013-02-04 14:44:00.955204' WHERE "refinery_page_translations"."id" = 1 Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 1 AND "refinery_page_translations"."locale" = 'en' LIMIT 1 SeoMetum Load (0.1ms) SELECT "seo_meta".* FROM "seo_meta" WHERE "seo_meta"."seo_meta_type" = 'Refinery::Page::Translation' AND "seo_meta"."seo_meta_id" = 1 LIMIT 1  (0.1ms) UPDATE "refinery_page_translations" SET "title" = 'Home', "slug" = 'home', "updated_at" = '2013-02-04 14:44:00.960202' WHERE "refinery_page_translations"."id" = 1 Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."id" = ? LIMIT 1 [["id", 1]] Refinery::Page Load (0.3ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE ("refinery_pages"."id" >= 0) ORDER BY "refinery_pages"."id" ASC LIMIT 500 Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 1 SeoMetum Load (0.1ms) SELECT "seo_meta".* FROM "seo_meta" WHERE "seo_meta"."seo_meta_type" = 'Refinery::Page::Translation' AND "seo_meta"."seo_meta_id" = 1 LIMIT 1 Refinery::PagePart Load (0.1ms) SELECT "refinery_page_parts".* FROM "refinery_page_parts" WHERE "refinery_page_parts"."refinery_page_id" = 1 ORDER BY position ASC Refinery::PagePart Load (0.1ms) SELECT "refinery_page_parts".* FROM "refinery_page_parts" WHERE "refinery_page_parts"."refinery_page_id" = 1 ORDER BY position ASC Refinery::Page Load (0.2ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 1]] SQL (0.2ms) UPDATE "refinery_pages" SET "depth" = 0 WHERE "refinery_pages"."id" IN (SELECT "refinery_pages"."id" FROM "refinery_pages" WHERE "refinery_pages"."id" = 1 ORDER BY "refinery_pages"."lft") Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 1]] Refinery::PagePart Load (0.1ms) SELECT "refinery_page_parts".* FROM "refinery_page_parts" WHERE "refinery_page_parts"."refinery_page_id" = 1 ORDER BY position ASC  (2.2ms) commit transaction  (0.1ms) begin transaction Refinery::PagePart::Translation Load (0.1ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."refinery_page_part_id" IS NULL AND "refinery_page_part_translations"."locale" = 'en' LIMIT 1 SQL (0.6ms) INSERT INTO "refinery_page_parts" ("body", "created_at", "position", "refinery_page_id", "title", "updated_at") VALUES (?, ?, ?, ?, ?, ?) [["body", "

Welcome to our site. This is just a place holder page while we gather our content.

"], ["created_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00], ["position", 0], ["refinery_page_id", 1], ["title", "Body"], ["updated_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00]] SQL (0.4ms) INSERT INTO "refinery_page_part_translations" ("body", "created_at", "locale", "refinery_page_part_id", "updated_at") VALUES (?, ?, ?, ?, ?) [["body", nil], ["created_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00], ["locale", "en"], ["refinery_page_part_id", 1], ["updated_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00]] Refinery::PagePart::Translation Load (0.1ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."refinery_page_part_id" = 1 AND "refinery_page_part_translations"."locale" = 'en' LIMIT 1  (0.3ms) UPDATE "refinery_page_part_translations" SET "body" = '

Welcome to our site. This is just a place holder page while we gather our content.

', "updated_at" = '2013-02-04 14:44:01.028291' WHERE "refinery_page_part_translations"."id" = 1 Refinery::PagePart::Translation Load (0.1ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."id" = ? LIMIT 1 [["id", 1]]  (2.2ms) commit transaction  (0.1ms) begin transaction Refinery::PagePart::Translation Load (0.2ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."refinery_page_part_id" IS NULL AND "refinery_page_part_translations"."locale" = 'en' LIMIT 1 SQL (0.4ms) INSERT INTO "refinery_page_parts" ("body", "created_at", "position", "refinery_page_id", "title", "updated_at") VALUES (?, ?, ?, ?, ?, ?) [["body", "

This is another block of content over here.

"], ["created_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00], ["position", 1], ["refinery_page_id", 1], ["title", "Side Body"], ["updated_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00]] SQL (0.3ms) INSERT INTO "refinery_page_part_translations" ("body", "created_at", "locale", "refinery_page_part_id", "updated_at") VALUES (?, ?, ?, ?, ?) [["body", nil], ["created_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00], ["locale", "en"], ["refinery_page_part_id", 2], ["updated_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00]] Refinery::PagePart::Translation Load (0.1ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."refinery_page_part_id" = 2 AND "refinery_page_part_translations"."locale" = 'en' LIMIT 1  (0.4ms) UPDATE "refinery_page_part_translations" SET "body" = '

This is another block of content over here.

', "updated_at" = '2013-02-04 14:44:01.050653' WHERE "refinery_page_part_translations"."id" = 2 Refinery::PagePart::Translation Load (0.1ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."id" = ? LIMIT 1 [["id", 2]]  (2.3ms) commit transaction  (0.1ms) begin transaction Refinery::Page::Translation Load (0.2ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" IS NULL AND "refinery_page_translations"."locale" = 'en' LIMIT 1 Refinery::Page Load (0.2ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."parent_id" = 1 AND ("slug" = 'page-not-found' OR "slug" LIKE 'page-not-found--%'ESCAPE '\') ORDER BY LENGTH("slug") DESC, "slug" DESC LIMIT 1 SQL (0.5ms) INSERT INTO "refinery_page_translations" ("created_at", "custom_slug", "locale", "menu_title", "refinery_page_id", "slug", "title", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00], ["custom_slug", nil], ["locale", "en"], ["menu_title", nil], ["refinery_page_id", nil], ["slug", nil], ["title", nil], ["updated_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00]] SeoMetum Load (48.2ms) SELECT "seo_meta".* FROM "seo_meta" WHERE "seo_meta"."seo_meta_type" = 'Refinery::Page::Translation' AND "seo_meta"."seo_meta_id" = 2 LIMIT 1 SQL (0.3ms) INSERT INTO "seo_meta" ("browser_title", "created_at", "meta_description", "meta_keywords", "seo_meta_id", "seo_meta_type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["browser_title", nil], ["created_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00], ["meta_description", nil], ["meta_keywords", nil], ["seo_meta_id", 2], ["seo_meta_type", "Refinery::Page::Translation"], ["updated_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00]] Refinery::Page Load (0.2ms) SELECT "refinery_pages".* FROM "refinery_pages" ORDER BY "refinery_pages"."rgt" desc LIMIT 1 SQL (0.4ms) INSERT INTO "refinery_pages" ("created_at", "deletable", "depth", "draft", "layout_template", "lft", "link_url", "menu_match", "parent_id", "path", "rgt", "show_in_menu", "skip_to_first_child", "slug", "updated_at", "view_template") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00], ["deletable", false], ["depth", nil], ["draft", false], ["layout_template", nil], ["lft", 3], ["link_url", nil], ["menu_match", "^/404$"], ["parent_id", 1], ["path", nil], ["rgt", 4], ["show_in_menu", false], ["skip_to_first_child", false], ["slug", "page-not-found"], ["updated_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00], ["view_template", nil]]  (0.3ms) UPDATE "refinery_page_translations" SET "refinery_page_id" = 2, "updated_at" = '2013-02-04 14:44:01.123050' WHERE "refinery_page_translations"."id" = 2 Refinery::Page::Translation Load (0.2ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 2 AND "refinery_page_translations"."locale" = 'en' LIMIT 1 SeoMetum Load (0.1ms) SELECT "seo_meta".* FROM "seo_meta" WHERE "seo_meta"."seo_meta_type" = 'Refinery::Page::Translation' AND "seo_meta"."seo_meta_id" = 2 LIMIT 1  (0.1ms) UPDATE "refinery_page_translations" SET "title" = 'Page not found', "slug" = 'page-not-found', "updated_at" = '2013-02-04 14:44:01.126886' WHERE "refinery_page_translations"."id" = 2 Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."id" = ? LIMIT 1 [["id", 2]] Refinery::PagePart Load (0.1ms) SELECT "refinery_page_parts".* FROM "refinery_page_parts" WHERE "refinery_page_parts"."refinery_page_id" = 2 ORDER BY position ASC Refinery::Page Load (0.2ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? ORDER BY "refinery_pages"."lft" LIMIT 1 [["id", 1]] Refinery::Page Load (0.1ms) SELECT "refinery_pages"."lft", "refinery_pages"."rgt", "refinery_pages"."parent_id" FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 2]] SQL (0.2ms) UPDATE "refinery_pages" 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_pages"."id" IN (SELECT "refinery_pages"."id" FROM "refinery_pages" ORDER BY "refinery_pages"."lft") Refinery::Page Load (0.0ms) SELECT "refinery_pages"."lft", "refinery_pages"."rgt", "refinery_pages"."parent_id" FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 1]] Refinery::Page Load (0.0ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 2]]  (0.1ms) SELECT COUNT(*) FROM "refinery_pages" WHERE ("refinery_pages"."lft" <= 2 AND "refinery_pages"."rgt" >= 3) AND ("refinery_pages".id != 2) SQL (0.1ms) UPDATE "refinery_pages" SET "depth" = 1 WHERE "refinery_pages"."id" IN (SELECT "refinery_pages"."id" FROM "refinery_pages" WHERE "refinery_pages"."id" = 2 ORDER BY "refinery_pages"."lft")  (0.1ms) SELECT COUNT(*) FROM "refinery_pages" WHERE ("refinery_pages"."lft" <= 2 AND "refinery_pages"."rgt" >= 3) AND ("refinery_pages".id != 2) Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE ("refinery_pages"."lft" >= 2 AND "refinery_pages"."lft" < 3) AND ("refinery_pages".id != 2) ORDER BY "refinery_pages"."lft" Refinery::Page Load (0.0ms) SELECT "refinery_pages"."lft", "refinery_pages"."rgt", "refinery_pages"."parent_id" FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 2]] Refinery::Page Load (0.0ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 2]]  (0.1ms) SELECT COUNT(*) FROM "refinery_pages" WHERE ("refinery_pages"."lft" <= 2 AND "refinery_pages"."rgt" >= 3) AND ("refinery_pages".id != 2) SQL (0.1ms) UPDATE "refinery_pages" SET "depth" = 1 WHERE "refinery_pages"."id" IN (SELECT "refinery_pages"."id" FROM "refinery_pages" WHERE "refinery_pages"."id" = 2 ORDER BY "refinery_pages"."lft")  (0.1ms) SELECT COUNT(*) FROM "refinery_pages" WHERE ("refinery_pages"."lft" <= 2 AND "refinery_pages"."rgt" >= 3) AND ("refinery_pages".id != 2) Refinery::Page Load (0.0ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 2]] Refinery::PagePart Load (0.2ms) SELECT "refinery_page_parts".* FROM "refinery_page_parts" WHERE "refinery_page_parts"."refinery_page_id" = 2 ORDER BY position ASC  (2.6ms) commit transaction  (0.1ms) begin transaction Refinery::PagePart::Translation Load (0.4ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."refinery_page_part_id" IS NULL AND "refinery_page_part_translations"."locale" = 'en' LIMIT 1 SQL (0.5ms) INSERT INTO "refinery_page_parts" ("body", "created_at", "position", "refinery_page_id", "title", "updated_at") VALUES (?, ?, ?, ?, ?, ?) [["body", "

Sorry, there was a problem...

The page you requested was not found.

Return to the home page

"], ["created_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00], ["position", 0], ["refinery_page_id", 2], ["title", "Body"], ["updated_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00]] SQL (0.2ms) INSERT INTO "refinery_page_part_translations" ("body", "created_at", "locale", "refinery_page_part_id", "updated_at") VALUES (?, ?, ?, ?, ?) [["body", nil], ["created_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00], ["locale", "en"], ["refinery_page_part_id", 3], ["updated_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00]] Refinery::PagePart::Translation Load (0.2ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."refinery_page_part_id" = 3 AND "refinery_page_part_translations"."locale" = 'en' LIMIT 1  (0.3ms) UPDATE "refinery_page_part_translations" SET "body" = '

Sorry, there was a problem...

The page you requested was not found.

Return to the home page

', "updated_at" = '2013-02-04 14:44:01.168034' WHERE "refinery_page_part_translations"."id" = 3 Refinery::PagePart::Translation Load (0.1ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."id" = ? LIMIT 1 [["id", 3]]  (2.4ms) commit transaction  (0.3ms) SELECT COUNT(*) FROM "refinery_pages" INNER JOIN "refinery_page_translations" ON "refinery_page_translations"."refinery_page_id" = "refinery_pages"."id" WHERE "refinery_page_translations"."locale" = 'en' AND "refinery_page_translations"."title" = 'About' Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" IS NULL AND "refinery_page_translations"."locale" = 'en' LIMIT 1  (0.1ms) begin transaction Refinery::Page Load (0.2ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."parent_id" IS NULL AND ("slug" = 'about' OR "slug" LIKE 'about--%'ESCAPE '\') ORDER BY LENGTH("slug") DESC, "slug" DESC LIMIT 1 SQL (0.5ms) INSERT INTO "refinery_page_translations" ("created_at", "custom_slug", "locale", "menu_title", "refinery_page_id", "slug", "title", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00], ["custom_slug", nil], ["locale", "en"], ["menu_title", nil], ["refinery_page_id", nil], ["slug", nil], ["title", nil], ["updated_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00]] SeoMetum Load (0.1ms) SELECT "seo_meta".* FROM "seo_meta" WHERE "seo_meta"."seo_meta_type" = 'Refinery::Page::Translation' AND "seo_meta"."seo_meta_id" = 3 LIMIT 1 SQL (0.3ms) INSERT INTO "seo_meta" ("browser_title", "created_at", "meta_description", "meta_keywords", "seo_meta_id", "seo_meta_type", "updated_at") VALUES (?, ?, ?, ?, ?, ?, ?) [["browser_title", nil], ["created_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00], ["meta_description", nil], ["meta_keywords", nil], ["seo_meta_id", 3], ["seo_meta_type", "Refinery::Page::Translation"], ["updated_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00]] Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" ORDER BY "refinery_pages"."rgt" desc LIMIT 1 SQL (0.5ms) INSERT INTO "refinery_pages" ("created_at", "deletable", "depth", "draft", "layout_template", "lft", "link_url", "menu_match", "parent_id", "path", "rgt", "show_in_menu", "skip_to_first_child", "slug", "updated_at", "view_template") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["created_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00], ["deletable", true], ["depth", nil], ["draft", false], ["layout_template", nil], ["lft", 5], ["link_url", nil], ["menu_match", nil], ["parent_id", nil], ["path", nil], ["rgt", 6], ["show_in_menu", true], ["skip_to_first_child", false], ["slug", "about"], ["updated_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00], ["view_template", nil]]  (0.4ms) UPDATE "refinery_page_translations" SET "refinery_page_id" = 3, "updated_at" = '2013-02-04 14:44:01.209812' WHERE "refinery_page_translations"."id" = 3 Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 3 AND "refinery_page_translations"."locale" = 'en' LIMIT 1 SeoMetum Load (0.1ms) SELECT "seo_meta".* FROM "seo_meta" WHERE "seo_meta"."seo_meta_type" = 'Refinery::Page::Translation' AND "seo_meta"."seo_meta_id" = 3 LIMIT 1  (0.1ms) UPDATE "refinery_page_translations" SET "title" = 'About', "slug" = 'about', "updated_at" = '2013-02-04 14:44:01.213439' WHERE "refinery_page_translations"."id" = 3 Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."id" = ? LIMIT 1 [["id", 3]] Refinery::PagePart Load (0.1ms) SELECT "refinery_page_parts".* FROM "refinery_page_parts" WHERE "refinery_page_parts"."refinery_page_id" = 3 ORDER BY position ASC Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 3]] SQL (0.2ms) UPDATE "refinery_pages" SET "depth" = 0 WHERE "refinery_pages"."id" IN (SELECT "refinery_pages"."id" FROM "refinery_pages" WHERE "refinery_pages"."id" = 3 ORDER BY "refinery_pages"."lft") Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 3]] Refinery::PagePart Load (0.1ms) SELECT "refinery_page_parts".* FROM "refinery_page_parts" WHERE "refinery_page_parts"."refinery_page_id" = 3 ORDER BY position ASC  (2.4ms) commit transaction  (0.1ms) begin transaction Refinery::PagePart::Translation Load (0.3ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."refinery_page_part_id" IS NULL AND "refinery_page_part_translations"."locale" = 'en' LIMIT 1 SQL (0.5ms) INSERT INTO "refinery_page_parts" ("body", "created_at", "position", "refinery_page_id", "title", "updated_at") VALUES (?, ?, ?, ?, ?, ?) [["body", "

This is just a standard text page example. Lorem ipsum dolor sit amet, consectetur adipiscing elit. Proin metus dolor, hendrerit sit amet, aliquet nec, posuere sed, purus. Nullam et velit iaculis odio sagittis placerat. Duis metus tellus, pellentesque ut, luctus id, egestas a, lorem. Praesent vitae mauris. Aliquam sed nulla. Sed id nunc vitae leo suscipit viverra. Proin at leo ut lacus consequat rhoncus. In hac habitasse platea dictumst. Nunc quis tortor sed libero hendrerit dapibus.\n\nInteger interdum purus id erat. Duis nec velit vitae dolor mattis euismod. Class aptent taciti sociosqu ad litora torquent per conubia nostra, per inceptos himenaeos. Suspendisse pellentesque dignissim lacus. Nulla semper euismod arcu. Suspendisse egestas, erat a consectetur dapibus, felis orci cursus eros, et sollicitudin purus urna et metus. Integer eget est sed nunc euismod vestibulum. Integer nulla dui, tristique in, euismod et, interdum imperdiet, enim. Mauris at lectus. Sed egestas tortor nec mi.

"], ["created_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00], ["position", 0], ["refinery_page_id", 3], ["title", "Body"], ["updated_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00]] SQL (0.2ms) INSERT INTO "refinery_page_part_translations" ("body", "created_at", "locale", "refinery_page_part_id", "updated_at") VALUES (?, ?, ?, ?, ?) [["body", nil], ["created_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00], ["locale", "en"], ["refinery_page_part_id", 4], ["updated_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00]] Refinery::PagePart::Translation Load (0.1ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."refinery_page_part_id" = 4 AND "refinery_page_part_translations"."locale" = 'en' LIMIT 1  (0.4ms) UPDATE "refinery_page_part_translations" SET "body" = '

This is just a standard text page example. Lorem ipsum dolor sit amet, consectetur adipiscing elit. Proin metus dolor, hendrerit sit amet, aliquet nec, posuere sed, purus. Nullam et velit iaculis odio sagittis placerat. Duis metus tellus, pellentesque ut, luctus id, egestas a, lorem. Praesent vitae mauris. Aliquam sed nulla. Sed id nunc vitae leo suscipit viverra. Proin at leo ut lacus consequat rhoncus. In hac habitasse platea dictumst. Nunc quis tortor sed libero hendrerit dapibus. Integer interdum purus id erat. Duis nec velit vitae dolor mattis euismod. Class aptent taciti sociosqu ad litora torquent per conubia nostra, per inceptos himenaeos. Suspendisse pellentesque dignissim lacus. Nulla semper euismod arcu. Suspendisse egestas, erat a consectetur dapibus, felis orci cursus eros, et sollicitudin purus urna et metus. Integer eget est sed nunc euismod vestibulum. Integer nulla dui, tristique in, euismod et, interdum imperdiet, enim. Mauris at lectus. Sed egestas tortor nec mi.

', "updated_at" = '2013-02-04 14:44:01.235237' WHERE "refinery_page_part_translations"."id" = 4 Refinery::PagePart::Translation Load (0.1ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."id" = ? LIMIT 1 [["id", 4]]  (2.2ms) commit transaction  (0.1ms) begin transaction Refinery::PagePart::Translation Load (0.2ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."refinery_page_part_id" IS NULL AND "refinery_page_part_translations"."locale" = 'en' LIMIT 1 SQL (0.4ms) INSERT INTO "refinery_page_parts" ("body", "created_at", "position", "refinery_page_id", "title", "updated_at") VALUES (?, ?, ?, ?, ?, ?) [["body", "

Lorem ipsum dolor sit amet, consectetur adipiscing elit. Vivamus fringilla nisi a elit. Duis ultricies orci ut arcu. Ut ac nibh. Duis blandit rhoncus magna. Pellentesque semper risus ut magna. Etiam pulvinar tellus eget diam. Morbi blandit. Donec pulvinar mauris at ligula. Sed pellentesque, ipsum id congue molestie, lectus risus egestas pede, ac viverra diam lacus ac urna. Aenean elit.

"], ["created_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00], ["position", 1], ["refinery_page_id", 3], ["title", "Side Body"], ["updated_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00]] SQL (0.2ms) INSERT INTO "refinery_page_part_translations" ("body", "created_at", "locale", "refinery_page_part_id", "updated_at") VALUES (?, ?, ?, ?, ?) [["body", nil], ["created_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00], ["locale", "en"], ["refinery_page_part_id", 5], ["updated_at", Mon, 04 Feb 2013 14:44:01 UTC +00:00]] Refinery::PagePart::Translation Load (0.1ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."refinery_page_part_id" = 5 AND "refinery_page_part_translations"."locale" = 'en' LIMIT 1  (0.3ms) UPDATE "refinery_page_part_translations" SET "body" = '

Lorem ipsum dolor sit amet, consectetur adipiscing elit. Vivamus fringilla nisi a elit. Duis ultricies orci ut arcu. Ut ac nibh. Duis blandit rhoncus magna. Pellentesque semper risus ut magna. Etiam pulvinar tellus eget diam. Morbi blandit. Donec pulvinar mauris at ligula. Sed pellentesque, ipsum id congue molestie, lectus risus egestas pede, ac viverra diam lacus ac urna. Aenean elit.

', "updated_at" = '2013-02-04 14:44:01.248192' WHERE "refinery_page_part_translations"."id" = 5 Refinery::PagePart::Translation Load (0.1ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."id" = ? LIMIT 1 [["id", 5]]  (2.3ms) commit transaction Refinery::Page Load (0.2ms) SELECT "refinery_pages".* FROM "refinery_pages" INNER JOIN "refinery_page_translations" ON "refinery_page_translations"."refinery_page_id" = "refinery_pages"."id" WHERE "refinery_page_translations"."locale" = 'en' AND "refinery_page_translations"."title" = 'Home'  (0.0ms) begin transaction Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 1 SeoMetum Load (0.1ms) SELECT "seo_meta".* FROM "seo_meta" WHERE "seo_meta"."seo_meta_type" = 'Refinery::Page::Translation' AND "seo_meta"."seo_meta_id" = 1 LIMIT 1 Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 1]] Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 1 SeoMetum Load (0.1ms) SELECT "seo_meta".* FROM "seo_meta" WHERE "seo_meta"."seo_meta_type" = 'Refinery::Page::Translation' AND "seo_meta"."seo_meta_id" = 1 LIMIT 1 Refinery::PagePart Load (0.1ms) SELECT "refinery_page_parts".* FROM "refinery_page_parts" WHERE "refinery_page_parts"."refinery_page_id" = 1 ORDER BY position ASC Refinery::PagePart::Translation Load (0.2ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."refinery_page_part_id" IN (1, 2) Refinery::PagePart Load (0.2ms) SELECT "refinery_page_parts".* FROM "refinery_page_parts" WHERE "refinery_page_parts"."refinery_page_id" = 1 ORDER BY position ASC Refinery::PagePart::Translation Load (0.2ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."refinery_page_part_id" IN (1, 2)  (0.3ms) UPDATE "refinery_pages" SET "slug" = 'home', "updated_at" = '2013-02-04 14:44:01.354880' WHERE "refinery_pages"."id" = 1 Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 1 AND "refinery_page_translations"."locale" = 'en' LIMIT 1 SeoMetum Load (0.2ms) SELECT "seo_meta".* FROM "seo_meta" WHERE "seo_meta"."seo_meta_type" = 'Refinery::Page::Translation' AND "seo_meta"."seo_meta_id" = 1 LIMIT 1 Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."id" = ? LIMIT 1 [["id", 1]] Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 1]] SQL (0.4ms) UPDATE "refinery_pages" SET "depth" = 0 WHERE "refinery_pages"."id" IN (SELECT "refinery_pages"."id" FROM "refinery_pages" WHERE "refinery_pages"."id" = 1 ORDER BY "refinery_pages"."lft") Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 1]] Refinery::PagePart Load (0.2ms) SELECT "refinery_page_parts".* FROM "refinery_page_parts" WHERE "refinery_page_parts"."refinery_page_id" = 1 ORDER BY position ASC Refinery::PagePart::Translation Load (0.2ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."refinery_page_part_id" IN (1, 2) Refinery::PagePart::Translation Load (0.1ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."id" = ? LIMIT 1 [["id", 1]] Refinery::PagePart::Translation Load (0.1ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."id" = ? LIMIT 1 [["id", 2]]  (2.2ms) commit transaction Refinery::Page Load (0.2ms) SELECT "refinery_pages".* FROM "refinery_pages" INNER JOIN "refinery_page_translations" ON "refinery_page_translations"."refinery_page_id" = "refinery_pages"."id" WHERE "refinery_page_translations"."locale" = 'en' AND "refinery_page_translations"."title" = 'Page not found'  (0.0ms) begin transaction Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 2 SeoMetum Load (0.1ms) SELECT "seo_meta".* FROM "seo_meta" WHERE "seo_meta"."seo_meta_type" = 'Refinery::Page::Translation' AND "seo_meta"."seo_meta_id" = 2 LIMIT 1 Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 2]] Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 2 SeoMetum Load (0.1ms) SELECT "seo_meta".* FROM "seo_meta" WHERE "seo_meta"."seo_meta_type" = 'Refinery::Page::Translation' AND "seo_meta"."seo_meta_id" = 2 LIMIT 1 Refinery::PagePart Load (0.1ms) SELECT "refinery_page_parts".* FROM "refinery_page_parts" WHERE "refinery_page_parts"."refinery_page_id" = 2 ORDER BY position ASC Refinery::PagePart::Translation Load (0.1ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."refinery_page_part_id" IN (3) Refinery::PagePart Load (0.2ms) SELECT "refinery_page_parts".* FROM "refinery_page_parts" WHERE "refinery_page_parts"."refinery_page_id" = 2 ORDER BY position ASC Refinery::PagePart::Translation Load (0.2ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."refinery_page_part_id" IN (3)  (0.2ms) UPDATE "refinery_pages" SET "slug" = 'page-not-found', "updated_at" = '2013-02-04 14:44:01.419292' WHERE "refinery_pages"."id" = 2 Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 2 AND "refinery_page_translations"."locale" = 'en' LIMIT 1 SeoMetum Load (0.1ms) SELECT "seo_meta".* FROM "seo_meta" WHERE "seo_meta"."seo_meta_type" = 'Refinery::Page::Translation' AND "seo_meta"."seo_meta_id" = 2 LIMIT 1 Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."id" = ? LIMIT 1 [["id", 2]] Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 2]]  (0.1ms) SELECT COUNT(*) FROM "refinery_pages" WHERE ("refinery_pages"."lft" <= 2 AND "refinery_pages"."rgt" >= 3) AND ("refinery_pages".id != 2) SQL (0.3ms) UPDATE "refinery_pages" SET "depth" = 1 WHERE "refinery_pages"."id" IN (SELECT "refinery_pages"."id" FROM "refinery_pages" WHERE "refinery_pages"."id" = 2 ORDER BY "refinery_pages"."lft")  (0.1ms) SELECT COUNT(*) FROM "refinery_pages" WHERE ("refinery_pages"."lft" <= 2 AND "refinery_pages"."rgt" >= 3) AND ("refinery_pages".id != 2) Refinery::Page Load (0.0ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 2]] Refinery::PagePart Load (0.1ms) SELECT "refinery_page_parts".* FROM "refinery_page_parts" WHERE "refinery_page_parts"."refinery_page_id" = 2 ORDER BY position ASC Refinery::PagePart::Translation Load (0.1ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."refinery_page_part_id" IN (3) Refinery::PagePart::Translation Load (0.1ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."id" = ? LIMIT 1 [["id", 3]]  (2.3ms) commit transaction Refinery::Page Load (0.2ms) SELECT "refinery_pages".* FROM "refinery_pages" INNER JOIN "refinery_page_translations" ON "refinery_page_translations"."refinery_page_id" = "refinery_pages"."id" WHERE "refinery_page_translations"."locale" = 'en' AND "refinery_page_translations"."title" = 'About'  (0.0ms) begin transaction Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 3 SeoMetum Load (0.1ms) SELECT "seo_meta".* FROM "seo_meta" WHERE "seo_meta"."seo_meta_type" = 'Refinery::Page::Translation' AND "seo_meta"."seo_meta_id" = 3 LIMIT 1 Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 3]] Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 3 SeoMetum Load (0.1ms) SELECT "seo_meta".* FROM "seo_meta" WHERE "seo_meta"."seo_meta_type" = 'Refinery::Page::Translation' AND "seo_meta"."seo_meta_id" = 3 LIMIT 1 Refinery::PagePart Load (0.1ms) SELECT "refinery_page_parts".* FROM "refinery_page_parts" WHERE "refinery_page_parts"."refinery_page_id" = 3 ORDER BY position ASC Refinery::PagePart::Translation Load (0.1ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."refinery_page_part_id" IN (4, 5) Refinery::PagePart Load (0.2ms) SELECT "refinery_page_parts".* FROM "refinery_page_parts" WHERE "refinery_page_parts"."refinery_page_id" = 3 ORDER BY position ASC Refinery::PagePart::Translation Load (0.2ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."refinery_page_part_id" IN (4, 5)  (0.2ms) UPDATE "refinery_pages" SET "slug" = 'about', "updated_at" = '2013-02-04 14:44:01.610937' WHERE "refinery_pages"."id" = 3 Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 3 AND "refinery_page_translations"."locale" = 'en' LIMIT 1 SeoMetum Load (0.1ms) SELECT "seo_meta".* FROM "seo_meta" WHERE "seo_meta"."seo_meta_type" = 'Refinery::Page::Translation' AND "seo_meta"."seo_meta_id" = 3 LIMIT 1 Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."id" = ? LIMIT 1 [["id", 3]] Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 3]] SQL (0.4ms) UPDATE "refinery_pages" SET "depth" = 0 WHERE "refinery_pages"."id" IN (SELECT "refinery_pages"."id" FROM "refinery_pages" WHERE "refinery_pages"."id" = 3 ORDER BY "refinery_pages"."lft") Refinery::Page Load (0.0ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 3]] Refinery::PagePart Load (0.1ms) SELECT "refinery_page_parts".* FROM "refinery_page_parts" WHERE "refinery_page_parts"."refinery_page_id" = 3 ORDER BY position ASC Refinery::PagePart::Translation Load (0.1ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."refinery_page_part_id" IN (4, 5) Refinery::PagePart::Translation Load (0.1ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."id" = ? LIMIT 1 [["id", 4]] Refinery::PagePart::Translation Load (0.0ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."id" = ? LIMIT 1 [["id", 5]]  (2.0ms) commit transaction Connecting to database specified by database.yml  (3.8ms) SELECT "schema_migrations"."version" FROM "schema_migrations"   (0.2ms) select sqlite_version(*)  (1.3ms) CREATE TABLE "refinery_images" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "image_mime_type" varchar(255), "image_name" varchar(255), "image_size" integer, "image_width" integer, "image_height" integer, "image_uid" varchar(255), "image_ext" varchar(255), "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)   (1.2ms) CREATE TABLE "refinery_menus" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar(255), "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL, "permatitle" varchar(255))  (1.3ms) CREATE UNIQUE INDEX "index_refinery_page_menus_on_permatitle" ON "refinery_menus" ("permatitle")  (1.4ms) CREATE TABLE "refinery_menus_links" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "parent_id" integer, "lft" integer, "rgt" integer, "depth" integer, "refinery_menu_id" integer, "menu_match" varchar(255), "refinery_resource_id" integer, "refinery_resource_type" varchar(255), "title_attribute" varchar(255), "custom_url" varchar(255), "label" varchar(255), "id_attribute" varchar(255), "class_attribute" varchar(255))  (1.2ms) CREATE INDEX "index_refinery_page_positions_on_depth" ON "refinery_menus_links" ("depth")  (1.4ms) CREATE INDEX "index_refinery_page_positions_on_id" ON "refinery_menus_links" ("id")  (1.4ms) CREATE INDEX "index_refinery_page_positions_on_lft" ON "refinery_menus_links" ("lft")  (1.8ms) CREATE INDEX "index_refinery_page_positions_on_parent_id" ON "refinery_menus_links" ("parent_id")  (1.3ms) CREATE INDEX "index_refinery_page_positions_on_rgt" ON "refinery_menus_links" ("rgt")  (1.4ms) CREATE TABLE "refinery_page_part_translations" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "refinery_page_part_id" integer, "locale" varchar(255), "body" text, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)  (1.3ms) CREATE INDEX "index_refinery_page_part_translations_on_locale" ON "refinery_page_part_translations" ("locale")  (1.5ms) CREATE INDEX "index_f9716c4215584edbca2557e32706a5ae084a15ef" ON "refinery_page_part_translations" ("refinery_page_part_id")  (1.4ms) CREATE TABLE "refinery_page_parts" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "refinery_page_id" integer, "title" varchar(255), "body" text, "position" integer, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)   (1.5ms) CREATE INDEX "index_refinery_page_parts_on_id" ON "refinery_page_parts" ("id")  (2.0ms) CREATE INDEX "index_refinery_page_parts_on_refinery_page_id" ON "refinery_page_parts" ("refinery_page_id")  (1.5ms) CREATE TABLE "refinery_page_translations" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "refinery_page_id" integer, "locale" varchar(255), "title" varchar(255), "custom_slug" varchar(255), "menu_title" varchar(255), "slug" varchar(255), "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)  (1.9ms) CREATE INDEX "index_refinery_page_translations_on_locale" ON "refinery_page_translations" ("locale")  (1.5ms) CREATE INDEX "index_d079468f88bff1c6ea81573a0d019ba8bf5c2902" ON "refinery_page_translations" ("refinery_page_id")  (1.6ms) CREATE TABLE "refinery_pages" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "parent_id" integer, "path" varchar(255), "slug" varchar(255), "show_in_menu" boolean DEFAULT 't', "link_url" varchar(255), "menu_match" varchar(255), "deletable" boolean DEFAULT 't', "draft" boolean DEFAULT 'f', "skip_to_first_child" boolean DEFAULT 'f', "lft" integer, "rgt" integer, "depth" integer, "view_template" varchar(255), "layout_template" varchar(255), "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)   (1.7ms) CREATE INDEX "index_refinery_pages_on_depth" ON "refinery_pages" ("depth")  (1.5ms) CREATE INDEX "index_refinery_pages_on_id" ON "refinery_pages" ("id")  (1.6ms) CREATE INDEX "index_refinery_pages_on_lft" ON "refinery_pages" ("lft")  (1.7ms) CREATE INDEX "index_refinery_pages_on_parent_id" ON "refinery_pages" ("parent_id")  (1.7ms) CREATE INDEX "index_refinery_pages_on_rgt" ON "refinery_pages" ("rgt")  (1.5ms) CREATE TABLE "refinery_resources" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "file_mime_type" varchar(255), "file_name" varchar(255), "file_size" integer, "file_uid" varchar(255), "file_ext" varchar(255), "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)   (1.6ms) CREATE TABLE "refinery_roles" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "title" varchar(255))  (2.3ms) CREATE TABLE "refinery_roles_users" ("user_id" integer, "role_id" integer)   (1.7ms) CREATE INDEX "index_refinery_roles_users_on_role_id_and_user_id" ON "refinery_roles_users" ("role_id", "user_id")  (6.6ms) CREATE INDEX "index_refinery_roles_users_on_user_id_and_role_id" ON "refinery_roles_users" ("user_id", "role_id")  (9.8ms) CREATE TABLE "refinery_user_plugins" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "user_id" integer, "name" varchar(255), "position" integer)  (10.5ms) CREATE INDEX "index_refinery_user_plugins_on_name" ON "refinery_user_plugins" ("name")  (9.8ms) CREATE UNIQUE INDEX "index_refinery_user_plugins_on_user_id_and_name" ON "refinery_user_plugins" ("user_id", "name")  (2.0ms) CREATE TABLE "refinery_users" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "username" varchar(255) NOT NULL, "email" varchar(255) NOT NULL, "encrypted_password" varchar(255) NOT NULL, "current_sign_in_at" datetime, "last_sign_in_at" datetime, "current_sign_in_ip" varchar(255), "last_sign_in_ip" varchar(255), "sign_in_count" integer, "remember_created_at" datetime, "reset_password_token" varchar(255), "reset_password_sent_at" datetime, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)   (7.6ms) CREATE INDEX "index_refinery_users_on_id" ON "refinery_users" ("id")  (2.3ms) CREATE TABLE "seo_meta" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "seo_meta_id" integer, "seo_meta_type" varchar(255), "browser_title" varchar(255), "meta_keywords" varchar(255), "meta_description" text, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)   (6.9ms) CREATE INDEX "index_seo_meta_on_id" ON "seo_meta" ("id")  (9.0ms) CREATE INDEX "index_seo_meta_on_seo_meta_id_and_seo_meta_type" ON "seo_meta" ("seo_meta_id", "seo_meta_type")  (2.3ms) CREATE TABLE "schema_migrations" ("version" varchar(255) NOT NULL)  (1.7ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (2.2ms) INSERT INTO "schema_migrations" (version) VALUES ('20130204144356')  (3.9ms) INSERT INTO "schema_migrations" (version) VALUES ('20130204144343')  (1.7ms) INSERT INTO "schema_migrations" (version) VALUES ('20130204144344')  (1.6ms) INSERT INTO "schema_migrations" (version) VALUES ('20130204144345')  (1.8ms) INSERT INTO "schema_migrations" (version) VALUES ('20130204144346')  (2.0ms) INSERT INTO "schema_migrations" (version) VALUES ('20130204144347')  (3.5ms) INSERT INTO "schema_migrations" (version) VALUES ('20130204144348')  (1.8ms) INSERT INTO "schema_migrations" (version) VALUES ('20130204144349')  (1.6ms) INSERT INTO "schema_migrations" (version) VALUES ('20130204144350')  (7.9ms) INSERT INTO "schema_migrations" (version) VALUES ('20130204144351')  (2.2ms) INSERT INTO "schema_migrations" (version) VALUES ('20130204144352')  (8.2ms) INSERT INTO "schema_migrations" (version) VALUES ('20130204144353')  (2.0ms) INSERT INTO "schema_migrations" (version) VALUES ('20130204144354')  (69.4ms) INSERT INTO "schema_migrations" (version) VALUES ('20130204144355') Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Started GET "/menus/refinery/menus" for 127.0.0.1 at 2013-02-04 15:45: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  (0.1ms) begin transaction Refinery::Role Exists (0.1ms) SELECT 1 AS one FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 SQL (4.3ms) INSERT INTO "refinery_roles" ("title") VALUES (?) [["title", "Refinery"]]  (2.0ms) commit transaction 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 Redirected to http://localhost:3000/refinery/users/register Filter chain halted as :refinery_user_required? rendered or redirected Completed 302 Found in 163ms (ActiveRecord: 7.5ms) Started GET "/refinery/users/register" for 127.0.0.1 at 2013-02-04 15:45:41 +0100 Processing by Refinery::UsersController#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 CACHE (0.0ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 CACHE (0.0ms) 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/_error_messages.html.erb (0.9ms) 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/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-authentication-2.0.9/app/views/refinery/users/new.html.erb within refinery/layouts/login (82.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.7ms) 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.8ms) 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 (8.4ms) 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.7ms) Completed 200 OK in 153ms (Views: 127.2ms | ActiveRecord: 0.3ms) Started GET "/assets/admin.js" for 127.0.0.1 at 2013-02-04 15:45:42 +0100 Compiled admin.js (0ms) (pid 21342) Served asset /admin.js - 304 Not Modified (14ms) Started GET "/assets/refinery/refinery.css" for 127.0.0.1 at 2013-02-04 15:45:42 +0100 Compiled refinery/layout.css (717ms) (pid 21342) Compiled refinery/site_bar.css (30ms) (pid 21342) Compiled refinery/ui.css (34ms) (pid 21342) Compiled refinery/submenu.css (12ms) (pid 21342) Compiled refinery/tooltips.css (23ms) (pid 21342) Compiled wymeditor/skins/refinery/skin.css (171ms) (pid 21342) Compiled refinery/refinery.css (1103ms) (pid 21342) Served asset /refinery/refinery.css - 200 OK (1164ms) Started GET "/assets/refinery/page_bg.png" for 127.0.0.1 at 2013-02-04 15:45:44 +0100 Served asset /refinery/page_bg.png - 304 Not Modified (9ms) Started GET "/assets/refinery/text_field_background.png" for 127.0.0.1 at 2013-02-04 15:45:44 +0100 Served asset /refinery/text_field_background.png - 304 Not Modified (8ms) Started GET "/assets/refinery/ajax-loader.gif" for 127.0.0.1 at 2013-02-04 15:45:48 +0100 Served asset /refinery/ajax-loader.gif - 200 OK (10ms) Started POST "/refinery/users/register" for 127.0.0.1 at 2013-02-04 15:45:48 +0100 Processing by Refinery::UsersController#create as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"ihxHCHWM5sv6XycM3Y+7pAdH0yCfYPjrr7WS03giNBk=", "user"=>{"username"=>"support", "email"=>"pylon@pylonweb.dk", "password"=>"[FILTERED]", "password_confirmation"=>"[FILTERED]"}, "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 CACHE (0.0ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1 CACHE (0.0ms) 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 Exists (0.2ms) SELECT 1 AS one FROM "refinery_users" WHERE "refinery_users"."email" = 'pylon@pylonweb.dk' LIMIT 1 Refinery::User Exists (0.1ms) SELECT 1 AS one FROM "refinery_users" WHERE "refinery_users"."username" = 'support' LIMIT 1  (0.0ms) begin transaction CACHE (0.0ms) SELECT 1 AS one FROM "refinery_users" WHERE "refinery_users"."email" = 'pylon@pylonweb.dk' LIMIT 1 CACHE (0.0ms) SELECT 1 AS one FROM "refinery_users" WHERE "refinery_users"."username" = 'support' LIMIT 1 Binary data inserted for `string` type on column `encrypted_password` SQL (2.7ms) 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", Mon, 04 Feb 2013 14:45:49 UTC +00:00], ["current_sign_in_at", nil], ["current_sign_in_ip", nil], ["email", "pylon@pylonweb.dk"], ["encrypted_password", "$2a$10$7OJsF75opvnS9eCPb4DyiORjzMEUfJ9A5jaVQkJ8taVPZGyRWcNDO"], ["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", Mon, 04 Feb 2013 14:45:49 UTC +00:00], ["username", "support"]]  (22.8ms) commit transaction Refinery::Role Load (0.2ms) 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::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1  (0.0ms) begin transaction  (0.2ms) INSERT INTO "refinery_roles_users" ("user_id", "role_id") VALUES (1, 1)  (1.8ms) commit transaction Refinery::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Refinery' LIMIT 1  (0.1ms) SELECT COUNT(*) 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::Role Load (0.1ms) SELECT "refinery_roles".* FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Superuser' LIMIT 1  (0.0ms) begin transaction Refinery::Role Exists (0.1ms) SELECT 1 AS one FROM "refinery_roles" WHERE "refinery_roles"."title" = 'Superuser' LIMIT 1 SQL (0.2ms) INSERT INTO "refinery_roles" ("title") VALUES (?) [["title", "Superuser"]]  (1.5ms) commit transaction  (0.0ms) begin transaction  (0.2ms) INSERT INTO "refinery_roles_users" ("user_id", "role_id") VALUES (1, 2)  (1.3ms) commit transaction SQL (0.1ms) DELETE FROM "refinery_user_plugins" WHERE "refinery_user_plugins"."user_id" = 1  (0.0ms) begin transaction SQL (0.5ms) INSERT INTO "refinery_user_plugins" ("name", "position", "user_id") VALUES (?, ?, ?) [["name", "refinery_menus"], ["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_users"], ["position", 1], ["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", 2], ["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_images"], ["position", 3], ["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_files"], ["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_pages"], ["position", 5], ["user_id", 1]]  (2.5ms) commit transaction Refinery::User Exists (0.1ms) SELECT 1 AS one FROM "refinery_users" WHERE ("refinery_users"."username" = 'support' AND "refinery_users"."id" != 1) LIMIT 1  (0.0ms) begin transaction  (0.3ms) UPDATE "refinery_users" SET "last_sign_in_at" = '2013-02-04 14:45:49.204055', "current_sign_in_at" = '2013-02-04 14:45:49.204055', "last_sign_in_ip" = '127.0.0.1', "current_sign_in_ip" = '127.0.0.1', "sign_in_count" = 1, "updated_at" = '2013-02-04 14:45:49.205090' WHERE "refinery_users"."id" = 1  (1.8ms) commit transaction Redirected to http://localhost:3000/refinery Completed 302 Found in 379ms (ActiveRecord: 47.4ms) Started GET "/refinery" for 127.0.0.1 at 2013-02-04 15:45:49 +0100 Processing by Refinery::Admin::DashboardController#index 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 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.2ms) SELECT "refinery_pages".* FROM "refinery_pages" ORDER BY updated_at DESC LIMIT 7 Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 3 Refinery::Page::Translation Load (0.2ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 2 Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = 1 LIMIT 1 Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 1 CACHE (0.0ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 1 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 (181.2ms) 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 (184.0ms) 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.4ms) 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 (16.9ms) 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 (206.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.9ms) 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.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 (2.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 (3.8ms) 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 (1.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.2ms) Completed 200 OK in 330ms (Views: 248.8ms | ActiveRecord: 2.8ms) Started GET "/assets/admin.js" for 127.0.0.1 at 2013-02-04 15:45:49 +0100 Served asset /admin.js - 304 Not Modified (0ms) Started GET "/assets/refinery/orange_button.png" for 127.0.0.1 at 2013-02-04 15:45:51 +0100 Served asset /refinery/orange_button.png - 304 Not Modified (77ms) Started GET "/assets/refinery/logo-site-bar.png" for 127.0.0.1 at 2013-02-04 15:45:51 +0100 Served asset /refinery/logo-site-bar.png - 304 Not Modified (71ms) Started GET "/assets/refinery/icons/arrow_switch.png" for 127.0.0.1 at 2013-02-04 15:45:51 +0100 Served asset /refinery/icons/arrow_switch.png - 304 Not Modified (12ms) Started GET "/assets/refinery/nav_inactive_background.png" for 127.0.0.1 at 2013-02-04 15:45:52 +0100 Served asset /refinery/nav_inactive_background.png - 304 Not Modified (8ms) Started GET "/assets/refinery/icons/add.png" for 127.0.0.1 at 2013-02-04 15:45:52 +0100 Served asset /refinery/icons/add.png - 304 Not Modified (8ms) Started GET "/assets/refinery/icons/tick.png" for 127.0.0.1 at 2013-02-04 15:45:52 +0100 Served asset /refinery/icons/tick.png - 304 Not Modified (14ms) Started GET "/assets/refinery/icons/application_edit.png" for 127.0.0.1 at 2013-02-04 15:45:52 +0100 Served asset /refinery/icons/application_edit.png - 304 Not Modified (56ms) Started GET "/assets/refinery/icons/flags/en.png" for 127.0.0.1 at 2013-02-04 15:45:52 +0100 Served asset /refinery/icons/flags/en.png - 304 Not Modified (9ms) Started GET "/assets/refinery/carousel-right.png" for 127.0.0.1 at 2013-02-04 15:45:52 +0100 Served asset /refinery/carousel-right.png - 304 Not Modified (7ms) Started GET "/assets/refinery/carousel-left.png" for 127.0.0.1 at 2013-02-04 15:45:52 +0100 Served asset /refinery/carousel-left.png - 304 Not Modified (7ms) Started GET "/menus/refinery/menus" for 127.0.0.1 at 2013-02-04 15:45:52 +0100 Processing by Refinery::Menus::Admin::MenusController#index 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 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 (3.2ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_menus.html.erb (80.5ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_records.html.erb (86.0ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/index.html.erb within layouts/refinery/admin (89.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.9ms) 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.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.4ms) 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.8ms) 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.4ms) 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 136ms (Views: 128.5ms | ActiveRecord: 1.4ms) Started GET "/assets/admin.js" for 127.0.0.1 at 2013-02-04 15:45:52 +0100 Served asset /admin.js - 304 Not Modified (0ms) Started GET "/assets/refinery/logo-site-bar.png" for 127.0.0.1 at 2013-02-04 15:45:53 +0100 Served asset /refinery/logo-site-bar.png - 304 Not Modified (0ms) Started GET "/assets/refinery/icons/application_edit.png" for 127.0.0.1 at 2013-02-04 15:45:53 +0100 Served asset /refinery/icons/application_edit.png - 304 Not Modified (0ms) Started GET "/assets/refinery/icons/arrow_switch.png" for 127.0.0.1 at 2013-02-04 15:45:53 +0100 Served asset /refinery/icons/arrow_switch.png - 304 Not Modified (0ms) Started GET "/assets/refinery/icons/delete.png" for 127.0.0.1 at 2013-02-04 15:45:53 +0100 Served asset /refinery/icons/delete.png - 304 Not Modified (5ms) Started GET "/assets/refinery/icons/tick.png" for 127.0.0.1 at 2013-02-04 15:45:53 +0100 Served asset /refinery/icons/tick.png - 304 Not Modified (0ms) Started GET "/assets/refinery/carousel-right.png" for 127.0.0.1 at 2013-02-04 15:45:53 +0100 Served asset /refinery/carousel-right.png - 304 Not Modified (0ms) Started GET "/assets/refinery/carousel-left.png" for 127.0.0.1 at 2013-02-04 15:45:53 +0100 Served asset /refinery/carousel-left.png - 304 Not Modified (0ms) Started GET "/about" for 127.0.0.1 at 2013-02-04 15:46:00 +0100 Processing by Refinery::PagesController#show as HTML Parameters: {"path"=>"about", "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::Page Load (0.2ms) SELECT "refinery_pages".* FROM "refinery_pages" INNER JOIN "refinery_page_translations" ON "refinery_page_translations"."refinery_page_id" = "refinery_pages"."id" WHERE "refinery_page_translations"."locale" IN ('en') AND "refinery_page_translations"."slug" = 'about' AND "refinery_pages"."parent_id" IS NULL LIMIT 1 Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 3 Refinery::Menus::Menu Load (0.2ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'sidebar_menu' 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 Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_menu.html.erb (0.8ms) Refinery::PagePart Load (0.2ms) SELECT "refinery_page_parts".* FROM "refinery_page_parts" WHERE "refinery_page_parts"."refinery_page_id" = 3 ORDER BY position ASC Refinery::PagePart::Translation Load (0.2ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."refinery_page_part_id" IN (4, 5) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_content_page.html.erb (44.3ms) Rendered refinery/pages/show.html.erb within layouts/application (69.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) Refinery::User Load (0.2ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 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 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 (5.8ms) SeoMetum Load (0.1ms) SELECT "seo_meta".* FROM "seo_meta" WHERE "seo_meta"."seo_meta_type" = 'Refinery::Page::Translation' AND "seo_meta"."seo_meta_id" = 3 LIMIT 1 Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_google_analytics.html.erb (0.6ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_head.html.erb (18.9ms) SQL (0.3ms) SELECT "refinery_pages"."id" AS t0_r0, "refinery_pages"."parent_id" AS t0_r1, "refinery_pages"."path" AS t0_r2, "refinery_pages"."slug" AS t0_r3, "refinery_pages"."show_in_menu" AS t0_r4, "refinery_pages"."link_url" AS t0_r5, "refinery_pages"."menu_match" AS t0_r6, "refinery_pages"."deletable" AS t0_r7, "refinery_pages"."draft" AS t0_r8, "refinery_pages"."skip_to_first_child" AS t0_r9, "refinery_pages"."lft" AS t0_r10, "refinery_pages"."rgt" AS t0_r11, "refinery_pages"."depth" AS t0_r12, "refinery_pages"."view_template" AS t0_r13, "refinery_pages"."layout_template" AS t0_r14, "refinery_pages"."created_at" AS t0_r15, "refinery_pages"."updated_at" AS t0_r16, "refinery_page_translations"."id" AS t1_r0, "refinery_page_translations"."refinery_page_id" AS t1_r1, "refinery_page_translations"."locale" AS t1_r2, "refinery_page_translations"."title" AS t1_r3, "refinery_page_translations"."custom_slug" AS t1_r4, "refinery_page_translations"."menu_title" AS t1_r5, "refinery_page_translations"."slug" AS t1_r6, "refinery_page_translations"."created_at" AS t1_r7, "refinery_page_translations"."updated_at" AS t1_r8 FROM "refinery_pages" INNER JOIN "refinery_page_translations" ON "refinery_page_translations"."refinery_page_id" = "refinery_pages"."id" WHERE "refinery_pages"."draft" = 'f' AND "refinery_pages"."show_in_menu" = 't' AND "refinery_page_translations"."locale" = 'en' ORDER BY lft ASC Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_menu_branch.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/_menu.html.erb (11.6ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_header.html.erb (15.9ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_footer.html.erb (0.9ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_javascripts.html.erb (1.2ms) Completed 200 OK in 207ms (Views: 190.5ms | ActiveRecord: 2.7ms) Started GET "/assets/application.css" for 127.0.0.1 at 2013-02-04 15:46:00 +0100 Compiled application.css (6ms) (pid 21342) Served asset /application.css - 304 Not Modified (26ms) Started GET "/assets/theme.css" for 127.0.0.1 at 2013-02-04 15:46:00 +0100 Compiled theme.css (9ms) (pid 21342) Served asset /theme.css - 200 OK (23ms) Started GET "/assets/formatting.css" for 127.0.0.1 at 2013-02-04 15:46:00 +0100 Compiled formatting.css (17ms) (pid 21342) Served asset /formatting.css - 200 OK (27ms) Started GET "/assets/refinery/site_bar.css" for 127.0.0.1 at 2013-02-04 15:46:00 +0100 Served asset /refinery/site_bar.css - 200 OK (8ms) Started GET "/assets/application.js" for 127.0.0.1 at 2013-02-04 15:46:00 +0100 Compiled jquery.js (56ms) (pid 21342) Compiled jquery_ujs.js (0ms) (pid 21342) Compiled application.js (76ms) (pid 21342) Served asset /application.js - 304 Not Modified (143ms) Started GET "/assets/modernizr-min.js" for 127.0.0.1 at 2013-02-04 15:46:01 +0100 Compiled modernizr-min.js (0ms) (pid 21342) Served asset /modernizr-min.js - 304 Not Modified (13ms) Started GET "/assets/refinery/orange_button.png" for 127.0.0.1 at 2013-02-04 15:46:01 +0100 Served asset /refinery/orange_button.png - 304 Not Modified (0ms) Started GET "/assets/refinery/logo-site-bar.png" for 127.0.0.1 at 2013-02-04 15:46:01 +0100 Served asset /refinery/logo-site-bar.png - 304 Not Modified (0ms) Started GET "/menus/refinery/menus/1/edit" for 127.0.0.1 at 2013-02-04 15:46:03 +0100 Processing by Refinery::Menus::Admin::MenusController#edit as HTML Parameters: {"id"=>"1", "locale"=>:en} Refinery::Menus::Menu Load (0.2ms) 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::Page Load (0.2ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."draft" = 'f' ORDER BY lft ASC Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 1 Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 2 Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 3 Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_resource_link_editor_box.html.erb (9.5ms) Refinery::Resource Load (0.1ms) SELECT "refinery_resources".* FROM "refinery_resources"  Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_resource_link_editor_box.html.erb (3.0ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_form_actions.html.erb (39.4ms) 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 (4.1ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_form.html.erb (14.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 (2.7ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/edit.html.erb within layouts/refinery/admin (66.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.8ms) 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 (5.4ms) 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.6ms) 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 112ms (Views: 102.7ms | ActiveRecord: 1.5ms) Started GET "/assets/refinery/select_form.css" for 127.0.0.1 at 2013-02-04 15:46:03 +0100 Compiled refinery/select_form.css (46ms) (pid 21342) Served asset /refinery/select_form.css - 304 Not Modified (114ms) Started GET "/assets/refinery/serializelist.js" for 127.0.0.1 at 2013-02-04 15:46:03 +0100 Compiled refinery/serializelist.js (0ms) (pid 21342) Served asset /refinery/serializelist.js - 304 Not Modified (11ms) Started GET "/assets/admin.js" for 127.0.0.1 at 2013-02-04 15:46:03 +0100 Served asset /admin.js - 304 Not Modified (0ms) Started GET "/assets/refinery/backend.js" for 127.0.0.1 at 2013-02-04 15:46:03 +0100 Compiled refinery/backend.js (88ms) (pid 21342) Served asset /refinery/backend.js - 304 Not Modified (175ms) Started GET "/assets/refinery/nestedsortables.js" for 127.0.0.1 at 2013-02-04 15:46:03 +0100 Compiled refinery/nestedsortables.js (0ms) (pid 21342) Served asset /refinery/nestedsortables.js - 304 Not Modified (11ms) Started GET "/assets/refinery/tree.js" for 127.0.0.1 at 2013-02-04 15:46:04 +0100 Compiled refinery/tree.js (0ms) (pid 21342) Served asset /refinery/tree.js - 304 Not Modified (13ms) Started GET "/assets/refinery/icons/tick.png" for 127.0.0.1 at 2013-02-04 15:46:04 +0100 Served asset /refinery/icons/tick.png - 304 Not Modified (0ms) Started GET "/assets/refinery/logo-site-bar.png" for 127.0.0.1 at 2013-02-04 15:46:04 +0100 Served asset /refinery/logo-site-bar.png - 304 Not Modified (0ms) Started GET "/assets/refinery/icons/arrow_switch.png" for 127.0.0.1 at 2013-02-04 15:46:04 +0100 Served asset /refinery/icons/arrow_switch.png - 304 Not Modified (0ms) Started GET "/assets/refinery/carousel-right.png" for 127.0.0.1 at 2013-02-04 15:46:04 +0100 Served asset /refinery/carousel-right.png - 304 Not Modified (0ms) Started GET "/assets/refinery/carousel-left.png" for 127.0.0.1 at 2013-02-04 15:46:04 +0100 Served asset /refinery/carousel-left.png - 304 Not Modified (0ms) Started POST "/menus/refinery/menu_links" for 127.0.0.1 at 2013-02-04 15:46:08 +0100 Processing by Refinery::Menus::Admin::MenuLinksController#create as JS Parameters: {"utf8"=>"✓", "authenticity_token"=>"ihxHCHWM5sv6XycM3Y+7pAdH0yCfYPjrr7WS03giNBk=", "menu_link"=>{"refinery_menu_id"=>"1", "refinery_resource_type"=>"refinery_page"}, "refinery_resource_ids"=>["1", "2", "3"], "commit"=>"Add Refinery Page", "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.0ms) begin transaction Refinery::Page Load (0.2ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 1]] Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 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.4ms) INSERT INTO "refinery_menus_links" ("class_attribute", "custom_url", "depth", "id_attribute", "label", "lft", "menu_match", "parent_id", "refinery_menu_id", "refinery_resource_id", "refinery_resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", nil], ["depth", nil], ["id_attribute", nil], ["label", "Home"], ["lft", 1], ["menu_match", nil], ["parent_id", nil], ["refinery_menu_id", 1], ["refinery_resource_id", 1], ["refinery_resource_type", "refinery_page"], ["rgt", 2], ["title_attribute", nil]] Refinery::Menus::MenuLink Load (0.1ms) 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.4ms) commit transaction  (0.1ms) begin transaction Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 2]] Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 2 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", "refinery_resource_id", "refinery_resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", nil], ["depth", nil], ["id_attribute", nil], ["label", "Page not found"], ["lft", 3], ["menu_match", nil], ["parent_id", nil], ["refinery_menu_id", 1], ["refinery_resource_id", 2], ["refinery_resource_type", "refinery_page"], ["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  (0.1ms) begin transaction Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 3]] Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 3 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", "refinery_resource_id", "refinery_resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", nil], ["depth", nil], ["id_attribute", nil], ["label", "About"], ["lft", 5], ["menu_match", nil], ["parent_id", nil], ["refinery_menu_id", 1], ["refinery_resource_id", 3], ["refinery_resource_type", "refinery_page"], ["rgt", 6], ["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", 3]] 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" = 3 ORDER BY "refinery_menus_links"."lft")  (2.1ms) commit transaction Refinery::Page Load (0.2ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 1]] Refinery::Page::Translation Load (0.2ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 1 Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_resource_link.html.erb (5.3ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_menu_link.html.erb (48.8ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/create.js.erb (53.9ms) Completed 500 Internal Server Error in 192ms ActionView::Template::Error (undefined method `menus_admin_menu_link_path' for #<#:0x007ffd0582d958>): 13: <%= render partial: "refinery/menus/admin/menu_links/resource_link", object: menu_link, locals: {f: f} %> 14: <% end %> 15: 16: <%= link_to t('remove', :scope => 'refinery.menus.admin.menu_links'), menus_admin_menu_link_path(menu_link), 17: method: :delete, 18: remote: true, 19: class: 'remove' %> /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_menu_link.html.erb:16:in `block in ___sers_johanfrolich__ails_refinerycms_menus_app_views_refinery_menus_admin_menu_links__menu_link_html_erb___3065049461527326993_70362272491420' actionpack (3.2.11) lib/action_view/helpers/capture_helper.rb:40:in `block in capture' actionpack (3.2.11) lib/action_view/helpers/capture_helper.rb:187:in `with_output_buffer' actionpack (3.2.11) lib/action_view/helpers/capture_helper.rb:40:in `capture' actionpack (3.2.11) lib/action_view/helpers/form_helper.rb:607:in `fields_for' /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_menu_link.html.erb:1:in `___sers_johanfrolich__ails_refinerycms_menus_app_views_refinery_menus_admin_menu_links__menu_link_html_erb___3065049461527326993_70362272491420' actionpack (3.2.11) lib/action_view/template.rb:145:in `block in render' activesupport (3.2.11) lib/active_support/notifications.rb:123:in `block in instrument' activesupport (3.2.11) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (3.2.11) lib/active_support/notifications.rb:123:in `instrument' actionpack (3.2.11) lib/action_view/template.rb:143:in `render' actionpack (3.2.11) lib/action_view/renderer/partial_renderer.rb:265:in `render_partial' actionpack (3.2.11) lib/action_view/renderer/partial_renderer.rb:238:in `block in render' actionpack (3.2.11) lib/action_view/renderer/abstract_renderer.rb:38:in `block in instrument' activesupport (3.2.11) lib/active_support/notifications.rb:123:in `block in instrument' activesupport (3.2.11) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (3.2.11) lib/active_support/notifications.rb:123:in `instrument' actionpack (3.2.11) lib/action_view/renderer/abstract_renderer.rb:38:in `instrument' actionpack (3.2.11) lib/action_view/renderer/partial_renderer.rb:237:in `render' actionpack (3.2.11) lib/action_view/renderer/renderer.rb:41:in `render_partial' actionpack (3.2.11) lib/action_view/renderer/renderer.rb:15:in `render' actionpack (3.2.11) lib/action_view/helpers/rendering_helper.rb:24:in `render' /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/create.js.erb:4:in `block in ___sers_johanfrolich__ails_refinerycms_menus_app_views_refinery_menus_admin_menu_links_create_js_erb___2904344486748774495_70362290783060' /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/create.js.erb:3:in `each' /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/create.js.erb:3:in `___sers_johanfrolich__ails_refinerycms_menus_app_views_refinery_menus_admin_menu_links_create_js_erb___2904344486748774495_70362290783060' actionpack (3.2.11) lib/action_view/template.rb:145:in `block in render' activesupport (3.2.11) lib/active_support/notifications.rb:123:in `block in instrument' activesupport (3.2.11) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (3.2.11) lib/active_support/notifications.rb:123:in `instrument' actionpack (3.2.11) lib/action_view/template.rb:143:in `render' actionpack (3.2.11) lib/action_view/renderer/template_renderer.rb:47:in `block (2 levels) in render_template' actionpack (3.2.11) lib/action_view/renderer/abstract_renderer.rb:38:in `block in instrument' activesupport (3.2.11) lib/active_support/notifications.rb:123:in `block in instrument' activesupport (3.2.11) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (3.2.11) lib/active_support/notifications.rb:123:in `instrument' actionpack (3.2.11) lib/action_view/renderer/abstract_renderer.rb:38:in `instrument' actionpack (3.2.11) lib/action_view/renderer/template_renderer.rb:46:in `block in render_template' actionpack (3.2.11) lib/action_view/renderer/template_renderer.rb:54:in `render_with_layout' actionpack (3.2.11) lib/action_view/renderer/template_renderer.rb:45:in `render_template' actionpack (3.2.11) lib/action_view/renderer/template_renderer.rb:18:in `render' actionpack (3.2.11) lib/action_view/renderer/renderer.rb:36:in `render_template' actionpack (3.2.11) lib/action_view/renderer/renderer.rb:17:in `render' actionpack (3.2.11) lib/abstract_controller/rendering.rb:110:in `_render_template' actionpack (3.2.11) lib/action_controller/metal/streaming.rb:225:in `_render_template' actionpack (3.2.11) lib/abstract_controller/rendering.rb:103:in `render_to_body' actionpack (3.2.11) lib/action_controller/metal/renderers.rb:28:in `render_to_body' actionpack (3.2.11) lib/action_controller/metal/compatibility.rb:50:in `render_to_body' actionpack (3.2.11) lib/abstract_controller/rendering.rb:88:in `render' actionpack (3.2.11) lib/action_controller/metal/rendering.rb:16:in `render' actionpack (3.2.11) lib/action_controller/metal/instrumentation.rb:40:in `block (2 levels) in render' activesupport (3.2.11) lib/active_support/core_ext/benchmark.rb:5:in `block in ms' /Users/johanfrolich/.rvm/rubies/ruby-1.9.3-p327/lib/ruby/1.9.1/benchmark.rb:295:in `realtime' activesupport (3.2.11) lib/active_support/core_ext/benchmark.rb:5:in `ms' actionpack (3.2.11) lib/action_controller/metal/instrumentation.rb:40:in `block in render' actionpack (3.2.11) lib/action_controller/metal/instrumentation.rb:83:in `cleanup_view_runtime' activerecord (3.2.11) lib/active_record/railties/controller_runtime.rb:24:in `cleanup_view_runtime' actionpack (3.2.11) lib/action_controller/metal/instrumentation.rb:39:in `render' actionpack (3.2.11) lib/action_controller/metal/implicit_render.rb:10:in `default_render' actionpack (3.2.11) lib/action_controller/metal/implicit_render.rb:5:in `send_action' actionpack (3.2.11) lib/abstract_controller/base.rb:167:in `process_action' actionpack (3.2.11) lib/action_controller/metal/rendering.rb:10:in `process_action' actionpack (3.2.11) lib/abstract_controller/callbacks.rb:18:in `block in process_action' activesupport (3.2.11) lib/active_support/callbacks.rb:491:in `_run__2189183261198825773__process_action__1856637526189967613__callbacks' activesupport (3.2.11) lib/active_support/callbacks.rb:405:in `__run_callback' activesupport (3.2.11) lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks' activesupport (3.2.11) lib/active_support/callbacks.rb:81:in `run_callbacks' actionpack (3.2.11) lib/abstract_controller/callbacks.rb:17:in `process_action' actionpack (3.2.11) lib/action_controller/metal/rescue.rb:29:in `process_action' actionpack (3.2.11) lib/action_controller/metal/instrumentation.rb:30:in `block in process_action' activesupport (3.2.11) lib/active_support/notifications.rb:123:in `block in instrument' activesupport (3.2.11) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (3.2.11) lib/active_support/notifications.rb:123:in `instrument' actionpack (3.2.11) lib/action_controller/metal/instrumentation.rb:29:in `process_action' actionpack (3.2.11) lib/action_controller/metal/params_wrapper.rb:207:in `process_action' activerecord (3.2.11) lib/active_record/railties/controller_runtime.rb:18:in `process_action' actionpack (3.2.11) lib/abstract_controller/base.rb:121:in `process' actionpack (3.2.11) lib/abstract_controller/rendering.rb:45:in `process' actionpack (3.2.11) lib/action_controller/metal.rb:203:in `dispatch' actionpack (3.2.11) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' actionpack (3.2.11) lib/action_controller/metal.rb:246:in `block in action' actionpack (3.2.11) lib/action_dispatch/routing/route_set.rb:73:in `call' actionpack (3.2.11) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' actionpack (3.2.11) lib/action_dispatch/routing/route_set.rb:36:in `call' journey (1.0.4) lib/journey/router.rb:68:in `block in call' journey (1.0.4) lib/journey/router.rb:56:in `each' journey (1.0.4) lib/journey/router.rb:56:in `call' actionpack (3.2.11) lib/action_dispatch/routing/route_set.rb:601:in `call' railties (3.2.11) lib/rails/engine.rb:479:in `call' railties (3.2.11) lib/rails/railtie/configurable.rb:30:in `method_missing' journey (1.0.4) lib/journey/router.rb:68:in `block in call' journey (1.0.4) lib/journey/router.rb:56:in `each' journey (1.0.4) lib/journey/router.rb:56:in `call' actionpack (3.2.11) lib/action_dispatch/routing/route_set.rb:601:in `call' meta_request (0.2.1) lib/meta_request/middlewares/app_request_handler.rb:11:in `call' rack-contrib (1.1.0) lib/rack/contrib/response_headers.rb:17:in `call' meta_request (0.2.1) lib/meta_request/middlewares/headers.rb:16:in `call' meta_request (0.2.1) lib/meta_request/middlewares/meta_request_handler.rb:13:in `call' warden (1.1.1) lib/warden/manager.rb:35:in `block in call' warden (1.1.1) lib/warden/manager.rb:34:in `catch' warden (1.1.1) lib/warden/manager.rb:34:in `call' actionpack (3.2.11) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' rack (1.4.4) lib/rack/etag.rb:23:in `call' rack (1.4.4) lib/rack/conditionalget.rb:35:in `call' actionpack (3.2.11) lib/action_dispatch/middleware/head.rb:14:in `call' actionpack (3.2.11) lib/action_dispatch/middleware/params_parser.rb:21:in `call' actionpack (3.2.11) lib/action_dispatch/middleware/flash.rb:242:in `call' rack (1.4.4) lib/rack/session/abstract/id.rb:210:in `context' rack (1.4.4) lib/rack/session/abstract/id.rb:205:in `call' actionpack (3.2.11) lib/action_dispatch/middleware/cookies.rb:341:in `call' dragonfly (0.9.12) lib/dragonfly/cookie_monster.rb:9:in `call' activerecord (3.2.11) lib/active_record/query_cache.rb:64:in `call' activerecord (3.2.11) lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call' actionpack (3.2.11) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' activesupport (3.2.11) lib/active_support/callbacks.rb:405:in `_run__2014893813759175431__call__2733281080647183211__callbacks' activesupport (3.2.11) lib/active_support/callbacks.rb:405:in `__run_callback' activesupport (3.2.11) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' activesupport (3.2.11) lib/active_support/callbacks.rb:81:in `run_callbacks' actionpack (3.2.11) lib/action_dispatch/middleware/callbacks.rb:27:in `call' dragonfly (0.9.12) lib/dragonfly/middleware.rb:13:in `call' dragonfly (0.9.12) lib/dragonfly/middleware.rb:13:in `call' rack-cache (1.2) lib/rack/cache/context.rb:136:in `forward' rack-cache (1.2) lib/rack/cache/context.rb:143:in `pass' rack-cache (1.2) lib/rack/cache/context.rb:155:in `invalidate' rack-cache (1.2) lib/rack/cache/context.rb:71:in `call!' rack-cache (1.2) lib/rack/cache/context.rb:51:in `call' rack-cache (1.2) lib/rack/cache/context.rb:136:in `forward' rack-cache (1.2) lib/rack/cache/context.rb:143:in `pass' rack-cache (1.2) lib/rack/cache/context.rb:155:in `invalidate' rack-cache (1.2) lib/rack/cache/context.rb:71:in `call!' rack-cache (1.2) lib/rack/cache/context.rb:51:in `call' actionpack (3.2.11) lib/action_dispatch/middleware/reloader.rb:65:in `call' actionpack (3.2.11) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' actionpack (3.2.11) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' actionpack (3.2.11) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.11) lib/rails/rack/logger.rb:32:in `call_app' railties (3.2.11) lib/rails/rack/logger.rb:16:in `block in call' activesupport (3.2.11) lib/active_support/tagged_logging.rb:22:in `tagged' railties (3.2.11) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.11) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.4) lib/rack/methodoverride.rb:21:in `call' rack (1.4.4) lib/rack/runtime.rb:17:in `call' activesupport (3.2.11) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.4) lib/rack/lock.rb:15:in `call' actionpack (3.2.11) lib/action_dispatch/middleware/static.rb:62:in `call' railties (3.2.11) lib/rails/engine.rb:479:in `call' railties (3.2.11) lib/rails/application.rb:223:in `call' rack (1.4.4) lib/rack/content_length.rb:14:in `call' railties (3.2.11) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.4) lib/rack/handler/webrick.rb:59:in `service' /Users/johanfrolich/.rvm/rubies/ruby-1.9.3-p327/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /Users/johanfrolich/.rvm/rubies/ruby-1.9.3-p327/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /Users/johanfrolich/.rvm/rubies/ruby-1.9.3-p327/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/actionpack-3.2.11/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.9ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/actionpack-3.2.11/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.5ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/actionpack-3.2.11/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (12.2ms) Started POST "/menus/refinery/menu_links" for 127.0.0.1 at 2013-02-04 15:46:20 +0100 Processing by Refinery::Menus::Admin::MenuLinksController#create as JS Parameters: {"utf8"=>"✓", "authenticity_token"=>"ihxHCHWM5sv6XycM3Y+7pAdH0yCfYPjrr7WS03giNBk=", "menu_link"=>{"refinery_menu_id"=>"1", "refinery_resource_type"=>"refinery_page"}, "refinery_resource_ids"=>["1", "2", "3"], "commit"=>"Add Refinery Page", "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::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 1]] Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 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.3ms) INSERT INTO "refinery_menus_links" ("class_attribute", "custom_url", "depth", "id_attribute", "label", "lft", "menu_match", "parent_id", "refinery_menu_id", "refinery_resource_id", "refinery_resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", nil], ["depth", nil], ["id_attribute", nil], ["label", "Home"], ["lft", 7], ["menu_match", nil], ["parent_id", nil], ["refinery_menu_id", 1], ["refinery_resource_id", 1], ["refinery_resource_type", "refinery_page"], ["rgt", 8], ["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", 4]] 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" = 4 ORDER BY "refinery_menus_links"."lft")  (2.3ms) commit transaction  (0.0ms) begin transaction Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 2]] Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 2 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", "refinery_resource_id", "refinery_resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", nil], ["depth", nil], ["id_attribute", nil], ["label", "Page not found"], ["lft", 9], ["menu_match", nil], ["parent_id", nil], ["refinery_menu_id", 1], ["refinery_resource_id", 2], ["refinery_resource_type", "refinery_page"], ["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.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" = 5 ORDER BY "refinery_menus_links"."lft")  (1.3ms) commit transaction  (0.1ms) begin transaction Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 3]] Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 3 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.4ms) INSERT INTO "refinery_menus_links" ("class_attribute", "custom_url", "depth", "id_attribute", "label", "lft", "menu_match", "parent_id", "refinery_menu_id", "refinery_resource_id", "refinery_resource_type", "rgt", "title_attribute") VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [["class_attribute", nil], ["custom_url", nil], ["depth", nil], ["id_attribute", nil], ["label", "About"], ["lft", 11], ["menu_match", nil], ["parent_id", nil], ["refinery_menu_id", 1], ["refinery_resource_id", 3], ["refinery_resource_type", "refinery_page"], ["rgt", 12], ["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", 6]] 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" = 6 ORDER BY "refinery_menus_links"."lft")  (1.6ms) commit transaction Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 1]] Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 1 Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_resource_link.html.erb (3.8ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_menu_link.html.erb (44.8ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/create.js.erb (49.0ms) Completed 500 Internal Server Error in 117ms ActionView::Template::Error (undefined method `menus_admin_menu_link_path' for #<#:0x007ffcfef66898>): 13: <%= render partial: "refinery/menus/admin/menu_links/resource_link", object: menu_link, locals: {f: f} %> 14: <% end %> 15: 16: <%= link_to t('remove', :scope => 'refinery.menus.admin.menu_links'), menus_admin_menu_link_path(menu_link), 17: method: :delete, 18: remote: true, 19: class: 'remove' %> /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_menu_link.html.erb:16:in `block in ___sers_johanfrolich__ails_refinerycms_menus_app_views_refinery_menus_admin_menu_links__menu_link_html_erb___3065049461527326993_70362272491420' actionpack (3.2.11) lib/action_view/helpers/capture_helper.rb:40:in `block in capture' actionpack (3.2.11) lib/action_view/helpers/capture_helper.rb:187:in `with_output_buffer' actionpack (3.2.11) lib/action_view/helpers/capture_helper.rb:40:in `capture' actionpack (3.2.11) lib/action_view/helpers/form_helper.rb:607:in `fields_for' /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_menu_link.html.erb:1:in `___sers_johanfrolich__ails_refinerycms_menus_app_views_refinery_menus_admin_menu_links__menu_link_html_erb___3065049461527326993_70362272491420' actionpack (3.2.11) lib/action_view/template.rb:145:in `block in render' activesupport (3.2.11) lib/active_support/notifications.rb:123:in `block in instrument' activesupport (3.2.11) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (3.2.11) lib/active_support/notifications.rb:123:in `instrument' actionpack (3.2.11) lib/action_view/template.rb:143:in `render' actionpack (3.2.11) lib/action_view/renderer/partial_renderer.rb:265:in `render_partial' actionpack (3.2.11) lib/action_view/renderer/partial_renderer.rb:238:in `block in render' actionpack (3.2.11) lib/action_view/renderer/abstract_renderer.rb:38:in `block in instrument' activesupport (3.2.11) lib/active_support/notifications.rb:123:in `block in instrument' activesupport (3.2.11) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (3.2.11) lib/active_support/notifications.rb:123:in `instrument' actionpack (3.2.11) lib/action_view/renderer/abstract_renderer.rb:38:in `instrument' actionpack (3.2.11) lib/action_view/renderer/partial_renderer.rb:237:in `render' actionpack (3.2.11) lib/action_view/renderer/renderer.rb:41:in `render_partial' actionpack (3.2.11) lib/action_view/renderer/renderer.rb:15:in `render' actionpack (3.2.11) lib/action_view/helpers/rendering_helper.rb:24:in `render' /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/create.js.erb:4:in `block in ___sers_johanfrolich__ails_refinerycms_menus_app_views_refinery_menus_admin_menu_links_create_js_erb___2904344486748774495_70362290783060' /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/create.js.erb:3:in `each' /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/create.js.erb:3:in `___sers_johanfrolich__ails_refinerycms_menus_app_views_refinery_menus_admin_menu_links_create_js_erb___2904344486748774495_70362290783060' actionpack (3.2.11) lib/action_view/template.rb:145:in `block in render' activesupport (3.2.11) lib/active_support/notifications.rb:123:in `block in instrument' activesupport (3.2.11) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (3.2.11) lib/active_support/notifications.rb:123:in `instrument' actionpack (3.2.11) lib/action_view/template.rb:143:in `render' actionpack (3.2.11) lib/action_view/renderer/template_renderer.rb:47:in `block (2 levels) in render_template' actionpack (3.2.11) lib/action_view/renderer/abstract_renderer.rb:38:in `block in instrument' activesupport (3.2.11) lib/active_support/notifications.rb:123:in `block in instrument' activesupport (3.2.11) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (3.2.11) lib/active_support/notifications.rb:123:in `instrument' actionpack (3.2.11) lib/action_view/renderer/abstract_renderer.rb:38:in `instrument' actionpack (3.2.11) lib/action_view/renderer/template_renderer.rb:46:in `block in render_template' actionpack (3.2.11) lib/action_view/renderer/template_renderer.rb:54:in `render_with_layout' actionpack (3.2.11) lib/action_view/renderer/template_renderer.rb:45:in `render_template' actionpack (3.2.11) lib/action_view/renderer/template_renderer.rb:18:in `render' actionpack (3.2.11) lib/action_view/renderer/renderer.rb:36:in `render_template' actionpack (3.2.11) lib/action_view/renderer/renderer.rb:17:in `render' actionpack (3.2.11) lib/abstract_controller/rendering.rb:110:in `_render_template' actionpack (3.2.11) lib/action_controller/metal/streaming.rb:225:in `_render_template' actionpack (3.2.11) lib/abstract_controller/rendering.rb:103:in `render_to_body' actionpack (3.2.11) lib/action_controller/metal/renderers.rb:28:in `render_to_body' actionpack (3.2.11) lib/action_controller/metal/compatibility.rb:50:in `render_to_body' actionpack (3.2.11) lib/abstract_controller/rendering.rb:88:in `render' actionpack (3.2.11) lib/action_controller/metal/rendering.rb:16:in `render' actionpack (3.2.11) lib/action_controller/metal/instrumentation.rb:40:in `block (2 levels) in render' activesupport (3.2.11) lib/active_support/core_ext/benchmark.rb:5:in `block in ms' /Users/johanfrolich/.rvm/rubies/ruby-1.9.3-p327/lib/ruby/1.9.1/benchmark.rb:295:in `realtime' activesupport (3.2.11) lib/active_support/core_ext/benchmark.rb:5:in `ms' actionpack (3.2.11) lib/action_controller/metal/instrumentation.rb:40:in `block in render' actionpack (3.2.11) lib/action_controller/metal/instrumentation.rb:83:in `cleanup_view_runtime' activerecord (3.2.11) lib/active_record/railties/controller_runtime.rb:24:in `cleanup_view_runtime' actionpack (3.2.11) lib/action_controller/metal/instrumentation.rb:39:in `render' actionpack (3.2.11) lib/action_controller/metal/implicit_render.rb:10:in `default_render' actionpack (3.2.11) lib/action_controller/metal/implicit_render.rb:5:in `send_action' actionpack (3.2.11) lib/abstract_controller/base.rb:167:in `process_action' actionpack (3.2.11) lib/action_controller/metal/rendering.rb:10:in `process_action' actionpack (3.2.11) lib/abstract_controller/callbacks.rb:18:in `block in process_action' activesupport (3.2.11) lib/active_support/callbacks.rb:491:in `_run__2189183261198825773__process_action__1856637526189967613__callbacks' activesupport (3.2.11) lib/active_support/callbacks.rb:405:in `__run_callback' activesupport (3.2.11) lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks' activesupport (3.2.11) lib/active_support/callbacks.rb:81:in `run_callbacks' actionpack (3.2.11) lib/abstract_controller/callbacks.rb:17:in `process_action' actionpack (3.2.11) lib/action_controller/metal/rescue.rb:29:in `process_action' actionpack (3.2.11) lib/action_controller/metal/instrumentation.rb:30:in `block in process_action' activesupport (3.2.11) lib/active_support/notifications.rb:123:in `block in instrument' activesupport (3.2.11) lib/active_support/notifications/instrumenter.rb:20:in `instrument' activesupport (3.2.11) lib/active_support/notifications.rb:123:in `instrument' actionpack (3.2.11) lib/action_controller/metal/instrumentation.rb:29:in `process_action' actionpack (3.2.11) lib/action_controller/metal/params_wrapper.rb:207:in `process_action' activerecord (3.2.11) lib/active_record/railties/controller_runtime.rb:18:in `process_action' actionpack (3.2.11) lib/abstract_controller/base.rb:121:in `process' actionpack (3.2.11) lib/abstract_controller/rendering.rb:45:in `process' actionpack (3.2.11) lib/action_controller/metal.rb:203:in `dispatch' actionpack (3.2.11) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' actionpack (3.2.11) lib/action_controller/metal.rb:246:in `block in action' actionpack (3.2.11) lib/action_dispatch/routing/route_set.rb:73:in `call' actionpack (3.2.11) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' actionpack (3.2.11) lib/action_dispatch/routing/route_set.rb:36:in `call' journey (1.0.4) lib/journey/router.rb:68:in `block in call' journey (1.0.4) lib/journey/router.rb:56:in `each' journey (1.0.4) lib/journey/router.rb:56:in `call' actionpack (3.2.11) lib/action_dispatch/routing/route_set.rb:601:in `call' railties (3.2.11) lib/rails/engine.rb:479:in `call' railties (3.2.11) lib/rails/railtie/configurable.rb:30:in `method_missing' journey (1.0.4) lib/journey/router.rb:68:in `block in call' journey (1.0.4) lib/journey/router.rb:56:in `each' journey (1.0.4) lib/journey/router.rb:56:in `call' actionpack (3.2.11) lib/action_dispatch/routing/route_set.rb:601:in `call' meta_request (0.2.1) lib/meta_request/middlewares/app_request_handler.rb:11:in `call' rack-contrib (1.1.0) lib/rack/contrib/response_headers.rb:17:in `call' meta_request (0.2.1) lib/meta_request/middlewares/headers.rb:16:in `call' meta_request (0.2.1) lib/meta_request/middlewares/meta_request_handler.rb:13:in `call' warden (1.1.1) lib/warden/manager.rb:35:in `block in call' warden (1.1.1) lib/warden/manager.rb:34:in `catch' warden (1.1.1) lib/warden/manager.rb:34:in `call' actionpack (3.2.11) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' rack (1.4.4) lib/rack/etag.rb:23:in `call' rack (1.4.4) lib/rack/conditionalget.rb:35:in `call' actionpack (3.2.11) lib/action_dispatch/middleware/head.rb:14:in `call' actionpack (3.2.11) lib/action_dispatch/middleware/params_parser.rb:21:in `call' actionpack (3.2.11) lib/action_dispatch/middleware/flash.rb:242:in `call' rack (1.4.4) lib/rack/session/abstract/id.rb:210:in `context' rack (1.4.4) lib/rack/session/abstract/id.rb:205:in `call' actionpack (3.2.11) lib/action_dispatch/middleware/cookies.rb:341:in `call' dragonfly (0.9.12) lib/dragonfly/cookie_monster.rb:9:in `call' activerecord (3.2.11) lib/active_record/query_cache.rb:64:in `call' activerecord (3.2.11) lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call' actionpack (3.2.11) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' activesupport (3.2.11) lib/active_support/callbacks.rb:405:in `_run__2014893813759175431__call__2733281080647183211__callbacks' activesupport (3.2.11) lib/active_support/callbacks.rb:405:in `__run_callback' activesupport (3.2.11) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' activesupport (3.2.11) lib/active_support/callbacks.rb:81:in `run_callbacks' actionpack (3.2.11) lib/action_dispatch/middleware/callbacks.rb:27:in `call' dragonfly (0.9.12) lib/dragonfly/middleware.rb:13:in `call' dragonfly (0.9.12) lib/dragonfly/middleware.rb:13:in `call' rack-cache (1.2) lib/rack/cache/context.rb:136:in `forward' rack-cache (1.2) lib/rack/cache/context.rb:143:in `pass' rack-cache (1.2) lib/rack/cache/context.rb:155:in `invalidate' rack-cache (1.2) lib/rack/cache/context.rb:71:in `call!' rack-cache (1.2) lib/rack/cache/context.rb:51:in `call' rack-cache (1.2) lib/rack/cache/context.rb:136:in `forward' rack-cache (1.2) lib/rack/cache/context.rb:143:in `pass' rack-cache (1.2) lib/rack/cache/context.rb:155:in `invalidate' rack-cache (1.2) lib/rack/cache/context.rb:71:in `call!' rack-cache (1.2) lib/rack/cache/context.rb:51:in `call' actionpack (3.2.11) lib/action_dispatch/middleware/reloader.rb:65:in `call' actionpack (3.2.11) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' actionpack (3.2.11) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' actionpack (3.2.11) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.11) lib/rails/rack/logger.rb:32:in `call_app' railties (3.2.11) lib/rails/rack/logger.rb:16:in `block in call' activesupport (3.2.11) lib/active_support/tagged_logging.rb:22:in `tagged' railties (3.2.11) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.11) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.4) lib/rack/methodoverride.rb:21:in `call' rack (1.4.4) lib/rack/runtime.rb:17:in `call' activesupport (3.2.11) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.4) lib/rack/lock.rb:15:in `call' actionpack (3.2.11) lib/action_dispatch/middleware/static.rb:62:in `call' railties (3.2.11) lib/rails/engine.rb:479:in `call' railties (3.2.11) lib/rails/application.rb:223:in `call' rack (1.4.4) lib/rack/content_length.rb:14:in `call' railties (3.2.11) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.4) lib/rack/handler/webrick.rb:59:in `service' /Users/johanfrolich/.rvm/rubies/ruby-1.9.3-p327/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /Users/johanfrolich/.rvm/rubies/ruby-1.9.3-p327/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /Users/johanfrolich/.rvm/rubies/ruby-1.9.3-p327/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/actionpack-3.2.11/lib/action_dispatch/middleware/templates/rescues/_trace.erb (2.0ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/actionpack-3.2.11/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.4ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/actionpack-3.2.11/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (11.3ms) Connecting to database specified by database.yml Started GET "/menus/refinery/menus/1/edit" for 127.0.0.1 at 2013-02-04 15:46:57 +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::Page Load (0.2ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."draft" = 'f' ORDER BY lft ASC Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 1 Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 2 Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 3 Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_resource_link_editor_box.html.erb (7.7ms) Refinery::Resource Load (0.1ms) SELECT "refinery_resources".* FROM "refinery_resources"  Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_resource_link_editor_box.html.erb (2.9ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_form_actions.html.erb (21.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.1ms) Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 1]] CACHE (0.0ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 1 Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_resource_link.html.erb (4.5ms) 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.2ms) Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 2]] CACHE (0.0ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 2 Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_resource_link.html.erb (3.7ms) 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 (9.1ms) Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 3]] CACHE (0.0ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 3 Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_resource_link.html.erb (3.6ms) Refinery::Menus::MenuLink Load (0.2ms) 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/_menu_link.html.erb (9.0ms) CACHE (0.0ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 1]] CACHE (0.0ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 1 Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_resource_link.html.erb (3.3ms) Refinery::Menus::MenuLink Load (0.2ms) 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 (8.5ms) CACHE (0.0ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 2]] CACHE (0.0ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 2 Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_resource_link.html.erb (3.5ms) Refinery::Menus::MenuLink Load (0.2ms) 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 (9.0ms) CACHE (0.0ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 3]] CACHE (0.0ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 3 Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_resource_link.html.erb (3.2ms) Refinery::Menus::MenuLink Load (0.2ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."parent_id" = 6 ORDER BY lft Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/_menu_link.html.erb (8.4ms) 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.6ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/_form.html.erb (91.6ms) 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 (2.0ms) Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menus/edit.html.erb within layouts/refinery/admin (125.0ms) 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.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 (6.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.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.6ms) 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 173ms (Views: 162.9ms | ActiveRecord: 2.9ms) Started GET "/assets/refinery/refinery.css" for 127.0.0.1 at 2013-02-04 15:46:58 +0100 Served asset /refinery/refinery.css - 304 Not Modified (0ms) Started GET "/assets/refinery/select_form.css" for 127.0.0.1 at 2013-02-04 15:46:58 +0100 Served asset /refinery/select_form.css - 304 Not Modified (0ms) Started GET "/assets/admin.js" for 127.0.0.1 at 2013-02-04 15:46:58 +0100 Served asset /admin.js - 304 Not Modified (0ms) Started GET "/assets/refinery/nestedsortables.js" for 127.0.0.1 at 2013-02-04 15:46:58 +0100 Served asset /refinery/nestedsortables.js - 304 Not Modified (0ms) Started GET "/assets/refinery/serializelist.js" for 127.0.0.1 at 2013-02-04 15:46:58 +0100 Served asset /refinery/serializelist.js - 304 Not Modified (0ms) Started GET "/assets/refinery/tree.js" for 127.0.0.1 at 2013-02-04 15:46:58 +0100 Served asset /refinery/tree.js - 304 Not Modified (0ms) Started GET "/assets/refinery/backend.js" for 127.0.0.1 at 2013-02-04 15:46:58 +0100 Served asset /refinery/backend.js - 304 Not Modified (0ms) Started GET "/assets/refinery/logo-site-bar.png" for 127.0.0.1 at 2013-02-04 15:46:59 +0100 Served asset /refinery/logo-site-bar.png - 304 Not Modified (0ms) Started GET "/assets/refinery/icons/arrow_switch.png" for 127.0.0.1 at 2013-02-04 15:46:59 +0100 Served asset /refinery/icons/arrow_switch.png - 304 Not Modified (0ms) Started GET "/assets/refinery/carousel-left.png" for 127.0.0.1 at 2013-02-04 15:46:59 +0100 Served asset /refinery/carousel-left.png - 304 Not Modified (0ms) Started GET "/assets/refinery/icons/tick.png" for 127.0.0.1 at 2013-02-04 15:46:59 +0100 Served asset /refinery/icons/tick.png - 304 Not Modified (0ms) Started GET "/assets/refinery/carousel-right.png" for 127.0.0.1 at 2013-02-04 15:46:59 +0100 Served asset /refinery/carousel-right.png - 304 Not Modified (0ms) Started GET "/assets/refinery/icons/down.gif" for 127.0.0.1 at 2013-02-04 15:46:59 +0100 Served asset /refinery/icons/down.gif - 304 Not Modified (9ms) Started DELETE "/menus/refinery/menu_links/6" for 127.0.0.1 at 2013-02-04 15:47:02 +0100 Processing by Refinery::Menus::Admin::MenuLinksController#destroy as JS Parameters: {"id"=>"6", "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 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", "6"]]  (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", 6]] Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" >= 11 AND "refinery_menus_links"."lft" < 12) AND ("refinery_menus_links".id != 6) 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" > 12) 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" > 12) ORDER BY "refinery_menus_links"."lft") SQL (0.3ms) DELETE FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? [["id", 6]]  (5.4ms) commit transaction Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/destroy.js.erb (0.6ms) Completed 200 OK in 52ms (Views: 33.8ms | ActiveRecord: 6.9ms) Started DELETE "/menus/refinery/menu_links/5" for 127.0.0.1 at 2013-02-04 15:47:04 +0100 Processing by Refinery::Menus::Admin::MenuLinksController#destroy as JS Parameters: {"id"=>"5", "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", "5"]]  (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", 5]] Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" >= 9 AND "refinery_menus_links"."lft" < 10) AND ("refinery_menus_links".id != 5) 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" > 10) 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" > 10) ORDER BY "refinery_menus_links"."lft") SQL (0.2ms) DELETE FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? [["id", 5]]  (6.0ms) commit transaction Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/destroy.js.erb (0.1ms) Completed 200 OK in 50ms (Views: 32.9ms | ActiveRecord: 7.4ms) Started DELETE "/menus/refinery/menu_links/4" for 127.0.0.1 at 2013-02-04 15:47:06 +0100 Processing by Refinery::Menus::Admin::MenuLinksController#destroy as JS Parameters: {"id"=>"4", "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::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? LIMIT 1 [["id", "4"]]  (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", 4]] Refinery::Menus::MenuLink Load (0.1ms) SELECT "refinery_menus_links".* FROM "refinery_menus_links" WHERE ("refinery_menus_links"."lft" >= 7 AND "refinery_menus_links"."lft" < 8) AND ("refinery_menus_links".id != 4) 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" > 8) 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" > 8) ORDER BY "refinery_menus_links"."lft") SQL (0.2ms) DELETE FROM "refinery_menus_links" WHERE "refinery_menus_links"."id" = ? [["id", 4]]  (6.3ms) commit transaction Rendered /Users/johanfrolich/Rails/refinerycms-menus/app/views/refinery/menus/admin/menu_links/destroy.js.erb (0.1ms) Completed 200 OK in 53ms (Views: 36.0ms | ActiveRecord: 7.6ms) Started GET "/about" for 127.0.0.1 at 2013-02-04 15:47:20 +0100 Processing by Refinery::PagesController#show as HTML Parameters: {"path"=>"about", "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::Page Load (0.2ms) SELECT "refinery_pages".* FROM "refinery_pages" INNER JOIN "refinery_page_translations" ON "refinery_page_translations"."refinery_page_id" = "refinery_pages"."id" WHERE "refinery_page_translations"."locale" IN ('en') AND "refinery_page_translations"."slug" = 'about' AND "refinery_pages"."parent_id" IS NULL LIMIT 1 Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 3 Refinery::Menus::Menu Load (0.1ms) SELECT "refinery_menus".* FROM "refinery_menus" WHERE "refinery_menus"."permatitle" = 'sidebar_menu' 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::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 1]] CACHE (0.0ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 1]] Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 2]] CACHE (0.0ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 2]] Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 2 Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = 1 LIMIT 1 Refinery::Page::Translation Load (0.1ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 1 Refinery::Page Load (0.1ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 3]] CACHE (0.0ms) SELECT "refinery_pages".* FROM "refinery_pages" WHERE "refinery_pages"."id" = ? LIMIT 1 [["id", 3]] CACHE (0.0ms) SELECT "refinery_page_translations".* FROM "refinery_page_translations" WHERE "refinery_page_translations"."refinery_page_id" = 3 Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_menu_branch.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/_menu.html.erb (5.8ms) Refinery::PagePart Load (0.2ms) SELECT "refinery_page_parts".* FROM "refinery_page_parts" WHERE "refinery_page_parts"."refinery_page_id" = 3 ORDER BY position ASC Refinery::PagePart::Translation Load (0.2ms) SELECT "refinery_page_part_translations".* FROM "refinery_page_part_translations" WHERE "refinery_page_part_translations"."refinery_page_part_id" IN (4, 5) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_content_page.html.erb (4.7ms) Rendered refinery/pages/show.html.erb within layouts/application (29.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) Refinery::User Load (0.2ms) SELECT "refinery_users".* FROM "refinery_users" WHERE "refinery_users"."id" = 1 LIMIT 1 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 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 (4.8ms) SeoMetum Load (0.2ms) SELECT "seo_meta".* FROM "seo_meta" WHERE "seo_meta"."seo_meta_type" = 'Refinery::Page::Translation' AND "seo_meta"."seo_meta_id" = 3 LIMIT 1 Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_google_analytics.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/_head.html.erb (8.1ms) SQL (0.3ms) SELECT "refinery_pages"."id" AS t0_r0, "refinery_pages"."parent_id" AS t0_r1, "refinery_pages"."path" AS t0_r2, "refinery_pages"."slug" AS t0_r3, "refinery_pages"."show_in_menu" AS t0_r4, "refinery_pages"."link_url" AS t0_r5, "refinery_pages"."menu_match" AS t0_r6, "refinery_pages"."deletable" AS t0_r7, "refinery_pages"."draft" AS t0_r8, "refinery_pages"."skip_to_first_child" AS t0_r9, "refinery_pages"."lft" AS t0_r10, "refinery_pages"."rgt" AS t0_r11, "refinery_pages"."depth" AS t0_r12, "refinery_pages"."view_template" AS t0_r13, "refinery_pages"."layout_template" AS t0_r14, "refinery_pages"."created_at" AS t0_r15, "refinery_pages"."updated_at" AS t0_r16, "refinery_page_translations"."id" AS t1_r0, "refinery_page_translations"."refinery_page_id" AS t1_r1, "refinery_page_translations"."locale" AS t1_r2, "refinery_page_translations"."title" AS t1_r3, "refinery_page_translations"."custom_slug" AS t1_r4, "refinery_page_translations"."menu_title" AS t1_r5, "refinery_page_translations"."slug" AS t1_r6, "refinery_page_translations"."created_at" AS t1_r7, "refinery_page_translations"."updated_at" AS t1_r8 FROM "refinery_pages" INNER JOIN "refinery_page_translations" ON "refinery_page_translations"."refinery_page_id" = "refinery_pages"."id" WHERE "refinery_pages"."draft" = 'f' AND "refinery_pages"."show_in_menu" = 't' AND "refinery_page_translations"."locale" = 'en' ORDER BY lft ASC Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_menu_branch.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/_menu.html.erb (71.9ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_header.html.erb (75.6ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_footer.html.erb (0.4ms) Rendered /Users/johanfrolich/.rvm/gems/ruby-1.9.3-p327@refinerycms-menus/gems/refinerycms-core-2.0.9/app/views/refinery/_javascripts.html.erb (0.7ms) Completed 200 OK in 155ms (Views: 145.9ms | ActiveRecord: 2.7ms) Started GET "/assets/application.css" for 127.0.0.1 at 2013-02-04 15:47:20 +0100 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/formatting.css" for 127.0.0.1 at 2013-02-04 15:47:20 +0100 Served asset /formatting.css - 304 Not Modified (0ms) Started GET "/assets/refinery/site_bar.css" for 127.0.0.1 at 2013-02-04 15:47:20 +0100 Served asset /refinery/site_bar.css - 304 Not Modified (0ms) Started GET "/assets/modernizr-min.js" for 127.0.0.1 at 2013-02-04 15:47:20 +0100 Served asset /modernizr-min.js - 304 Not Modified (0ms) Started GET "/assets/application.js" for 127.0.0.1 at 2013-02-04 15:47:20 +0100 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/theme.css" for 127.0.0.1 at 2013-02-04 15:47:20 +0100 Served asset /theme.css - 304 Not Modified (0ms) Started GET "/assets/refinery/logo-site-bar.png" for 127.0.0.1 at 2013-02-04 15:47:21 +0100 Served asset /refinery/logo-site-bar.png - 304 Not Modified (0ms)