Started GET "/" for 127.0.0.1 at 2012-10-12 12:03:19 +0200 Connecting to database specified by database.yml ActionController::RoutingError (No route matches [GET] "/"): actionpack (3.2.8) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.8) lib/rails/rack/logger.rb:26:in `call_app' railties (3.2.8) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.1) lib/rack/methodoverride.rb:21:in `call' rack (1.4.1) lib/rack/runtime.rb:17:in `call' activesupport (3.2.8) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.1) lib/rack/lock.rb:15:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/static.rb:62:in `call' railties (3.2.8) lib/rails/engine.rb:479:in `call' railties (3.2.8) lib/rails/application.rb:223:in `call' rack (1.4.1) lib/rack/content_length.rb:14:in `call' railties (3.2.8) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.1) lib/rack/handler/webrick.rb:59:in `service' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (4.1ms) Connecting to database specified by database.yml Connecting to database specified by database.yml  (0.1ms) select sqlite_version(*)  (2.0ms) CREATE TABLE "schema_migrations" ("version" varchar(255) NOT NULL)  (0.0ms) PRAGMA index_list("schema_migrations")  (33.1ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations"  Migrating to CreateCategories (20121012100526)  (0.0ms) begin transaction  (0.5ms) CREATE TABLE "categories" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar(255), "parent_id" integer, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)   (0.1ms) INSERT INTO "schema_migrations" ("version") VALUES ('20121012100526')  (1.2ms) commit transaction  (0.3ms) select sqlite_version(*)  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations"   (0.0ms) PRAGMA index_list("categories") Started GET "/" for 127.0.0.1 at 2012-10-12 12:15:42 +0200 Connecting to database specified by database.yml Processing by CategoriesController#index as HTML Category Load (0.1ms) SELECT "categories".* FROM "categories"  Rendered categories/index.html.erb within layouts/application (2.2ms) Compiled token-input.css (0ms) (pid 65815) Compiled categories.css (0ms) (pid 65815) Compiled application.css (15ms) (pid 65815) Compiled jquery.js (4ms) (pid 65815) Compiled jquery_ujs.js (0ms) (pid 65815) Compiled jquery.tokeninput.js (0ms) (pid 65815) Compiled categories.js (0ms) (pid 65815) Compiled application.js (109ms) (pid 65815) Completed 200 OK in 360ms (Views: 301.7ms | ActiveRecord: 1.6ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 12:15:43 +0200 Served asset /application.css - 200 OK (4ms) Started GET "/assets/token-input.css?body=1" for 127.0.0.1 at 2012-10-12 12:15:43 +0200 Served asset /token-input.css - 200 OK (2ms) Started GET "/assets/categories.css?body=1" for 127.0.0.1 at 2012-10-12 12:15:43 +0200 Served asset /categories.css - 200 OK (1ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 12:15:43 +0200 Served asset /jquery.js - 304 Not Modified (3ms) Started GET "/assets/categories.js?body=1" for 127.0.0.1 at 2012-10-12 12:15:43 +0200 Served asset /categories.js - 200 OK (1ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 12:15:43 +0200 Served asset /jquery.tokeninput.js - 200 OK (1ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 12:15:43 +0200 Served asset /jquery_ujs.js - 304 Not Modified (2ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 12:15:43 +0200 Served asset /application.js - 200 OK (22ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 12:15:46 +0200 Processing by CategoriesController#new as HTML Rendered categories/new.html.erb within layouts/application (30.8ms) Completed 500 Internal Server Error in 38ms ActionView::Template::Error (undefined method `label_field' for #): 1:

Category#new

2: <%= form_for(@category) do |f| %> 3:

4: <%= f.label_field :name %> 5: <%= f.text_field :name %> 6:

7:

app/views/categories/new.html.erb:4:in `block in _app_views_categories_new_html_erb___57800146728870252_2170212380' app/views/categories/new.html.erb:2:in `_app_views_categories_new_html_erb___57800146728870252_2170212380' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.4ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (0.9ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (9.6ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 12:16:12 +0200 Processing by CategoriesController#new as HTML Rendered categories/new.html.erb within layouts/application (13.9ms) Completed 200 OK in 22ms (Views: 21.8ms | ActiveRecord: 0.0ms) Started GET "/assets/token-input.css?body=1" for 127.0.0.1 at 2012-10-12 12:16:12 +0200 Served asset /token-input.css - 304 Not Modified (0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 12:16:12 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 12:16:12 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/categories.css?body=1" for 127.0.0.1 at 2012-10-12 12:16:12 +0200 Served asset /categories.css - 304 Not Modified (0ms) Started GET "/assets/categories.js?body=1" for 127.0.0.1 at 2012-10-12 12:16:12 +0200 Served asset /categories.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 12:16:12 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 12:16:12 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 12:16:12 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started POST "/categories" for 127.0.0.1 at 2012-10-12 12:16:17 +0200 Processing by CategoriesController#create as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"2av1uH15caF6Mz+lKlJW9cNDGASdZWyvFy3FWznlwS0=", "category"=>{"name"=>"nova", "parent_id"=>""}, "commit"=>"Create Category"}  (0.1ms) begin transaction SQL (44.0ms) INSERT INTO "categories" ("created_at", "name", "parent_id", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Fri, 12 Oct 2012 10:16:17 UTC +00:00], ["name", "nova"], ["parent_id", nil], ["updated_at", Fri, 12 Oct 2012 10:16:17 UTC +00:00]]  (1.1ms) commit transaction Redirected to http://localhost:3000/categories Completed 302 Found in 48ms (ActiveRecord: 45.1ms) Started GET "/categories" for 127.0.0.1 at 2012-10-12 12:16:17 +0200 Processing by CategoriesController#index as HTML Category Load (0.1ms) SELECT "categories".* FROM "categories"  Rendered categories/index.html.erb within layouts/application (1.0ms) Completed 200 OK in 7ms (Views: 5.5ms | ActiveRecord: 0.1ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 12:16:17 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input.css?body=1" for 127.0.0.1 at 2012-10-12 12:16:17 +0200 Served asset /token-input.css - 304 Not Modified (0ms) Started GET "/assets/categories.css?body=1" for 127.0.0.1 at 2012-10-12 12:16:17 +0200 Served asset /categories.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 12:16:17 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 12:16:17 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 12:16:17 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/categories.js?body=1" for 127.0.0.1 at 2012-10-12 12:16:17 +0200 Served asset /categories.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 12:16:17 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/categories" for 127.0.0.1 at 2012-10-12 12:16:41 +0200 Processing by CategoriesController#index as HTML Category Load (0.2ms) SELECT "categories".* FROM "categories" Rendered categories/index.html.erb within layouts/application (1.6ms) Completed 200 OK in 14ms (Views: 12.6ms | ActiveRecord: 0.2ms) Started GET "/assets/token-input.css?body=1" for 127.0.0.1 at 2012-10-12 12:16:41 +0200 Served asset /token-input.css - 304 Not Modified (0ms) Started GET "/assets/categories.css?body=1" for 127.0.0.1 at 2012-10-12 12:16:41 +0200 Served asset /categories.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 12:16:41 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 12:16:41 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/categories.js?body=1" for 127.0.0.1 at 2012-10-12 12:16:41 +0200 Served asset /categories.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 12:16:41 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 12:16:41 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 12:16:41 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/categories" for 127.0.0.1 at 2012-10-12 12:16:54 +0200 Processing by CategoriesController#index as HTML Category Load (0.2ms) SELECT "categories".* FROM "categories"  Rendered categories/index.html.erb within layouts/application (2.5ms) Completed 200 OK in 12ms (Views: 10.1ms | ActiveRecord: 0.2ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 12:16:54 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input.css?body=1" for 127.0.0.1 at 2012-10-12 12:16:54 +0200 Served asset /token-input.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 12:16:54 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 12:16:54 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 12:16:54 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 12:16:54 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/categories.css?body=1" for 127.0.0.1 at 2012-10-12 12:16:54 +0200 Served asset /categories.css - 304 Not Modified (0ms) Started GET "/assets/categories.js?body=1" for 127.0.0.1 at 2012-10-12 12:16:54 +0200 Served asset /categories.js - 304 Not Modified (0ms) Started GET "/categories" for 127.0.0.1 at 2012-10-12 12:17:02 +0200 Processing by CategoriesController#index as HTML Category Load (0.1ms) SELECT "categories".* FROM "categories" Rendered categories/index.html.erb within layouts/application (1.0ms) Completed 200 OK in 7ms (Views: 5.8ms | ActiveRecord: 0.1ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 12:17:02 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input.css?body=1" for 127.0.0.1 at 2012-10-12 12:17:02 +0200 Served asset /token-input.css - 304 Not Modified (0ms) Started GET "/assets/categories.css?body=1" for 127.0.0.1 at 2012-10-12 12:17:02 +0200 Served asset /categories.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 12:17:02 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 12:17:02 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/categories.js?body=1" for 127.0.0.1 at 2012-10-12 12:17:02 +0200 Served asset /categories.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 12:17:02 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 12:17:02 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 12:22:01 +0200 Processing by CategoriesController#new as HTML Rendered categories/new.html.erb within layouts/application (5.1ms) Completed 200 OK in 28ms (Views: 13.4ms | ActiveRecord: 0.6ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 12:22:01 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/categories.js?body=1" for 127.0.0.1 at 2012-10-12 12:22:01 +0200 Served asset /categories.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 12:22:01 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 12:22:01 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/token-input.css?body=1" for 127.0.0.1 at 2012-10-12 12:22:01 +0200 Served asset /token-input.css - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 12:22:01 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/categories.css?body=1" for 127.0.0.1 at 2012-10-12 12:22:01 +0200 Served asset /categories.css - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 12:22:01 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 12:30:35 +0200 ArgumentError (wrong number of arguments (3 for 2)): app/models/category.rb:4:in `' app/models/category.rb:1:in `' app/controllers/categories_controller.rb:1:in `' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (35.8ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.3ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (45.2ms) Connecting to database specified by database.yml Connecting to database specified by database.yml Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 12:31:14 +0200 Connecting to database specified by database.yml Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (10.3ms) Rendered categories/new.html.erb within layouts/application (14.5ms) Completed 500 Internal Server Error in 59ms ActionView::Template::Error (uninitialized constant Parent): 5:

6:

7: <%= f.label :parent_id %> 8: <%= f.token_field :parent_id %> 9:

10: <%= f.submit %> 11: <% end %> app/helpers/token_field_helper.rb:94:in `token_field' app/views/categories/_form.html.erb:8:in `block in _app_views_categories__form_html_erb__2909152794900590978_2158127440' app/views/categories/_form.html.erb:1:in `_app_views_categories__form_html_erb__2909152794900590978_2158127440' app/views/categories/new.html.erb:2:in `_app_views_categories_new_html_erb___2371400964088186688_2157781700' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.4ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.0ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (7.7ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 12:31:52 +0200 Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (31.0ms) Rendered categories/new.html.erb within layouts/application (31.9ms) Completed 500 Internal Server Error in 34ms ActionView::Template::Error (undefined method `parent' for #): 5:

6:

7: <%= f.label :parent_id %> 8: <%= f.token_field :parent_id, :model => :category %> 9:

10: <%= f.submit %> 11: <% end %> app/helpers/token_field_helper.rb:112:in `token_field' app/views/categories/_form.html.erb:8:in `block in _app_views_categories__form_html_erb__2909152794900590978_2165123620' app/views/categories/_form.html.erb:1:in `_app_views_categories__form_html_erb__2909152794900590978_2165123620' app/views/categories/new.html.erb:2:in `_app_views_categories_new_html_erb___2371400964088186688_2157781700' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.9ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.5ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (11.1ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 12:31:53 +0200 Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (2.7ms) Rendered categories/new.html.erb within layouts/application (3.4ms) Completed 500 Internal Server Error in 5ms ActionView::Template::Error (undefined method `parent' for #): 5:

6:

7: <%= f.label :parent_id %> 8: <%= f.token_field :parent_id, :model => :category %> 9:

10: <%= f.submit %> 11: <% end %> app/helpers/token_field_helper.rb:112:in `token_field' app/views/categories/_form.html.erb:8:in `block in _app_views_categories__form_html_erb__2909152794900590978_2165123620' app/views/categories/_form.html.erb:1:in `_app_views_categories__form_html_erb__2909152794900590978_2165123620' app/views/categories/new.html.erb:2:in `_app_views_categories_new_html_erb___2371400964088186688_2157781700' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.2ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (0.9ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (7.3ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 12:32:10 +0200 Connecting to database specified by database.yml Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (16.9ms) Rendered categories/new.html.erb within layouts/application (22.9ms) Completed 500 Internal Server Error in 95ms ActionView::Template::Error (undefined method `parent' for #): 5:

6:

7: <%= f.label :parent_id %> 8: <%= f.token_field :parent_id, :model => :category %> 9:

10: <%= f.submit %> 11: <% end %> app/helpers/token_field_helper.rb:112:in `token_field' app/views/categories/_form.html.erb:8:in `block in _app_views_categories__form_html_erb__2228907538185509431_2155516920' app/views/categories/_form.html.erb:1:in `_app_views_categories__form_html_erb__2228907538185509431_2155516920' app/views/categories/new.html.erb:2:in `_app_views_categories_new_html_erb__838696099899117069_2158445240' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (2.4ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.7ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (13.7ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 12:33:14 +0200 Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (31.1ms) Rendered categories/new.html.erb within layouts/application (31.8ms) Compiled token-input.css (0ms) (pid 76280) Compiled application.css (4ms) (pid 76280) Compiled jquery.js (4ms) (pid 76280) Compiled jquery_ujs.js (0ms) (pid 76280) Compiled jquery.tokeninput.js (0ms) (pid 76280) Compiled application.js (50ms) (pid 76280) Completed 200 OK in 343ms (Views: 334.0ms | ActiveRecord: 0.6ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 12:33:15 +0200 Served asset /application.css - 304 Not Modified (35ms) Started GET "/assets/token-input.css?body=1" for 127.0.0.1 at 2012-10-12 12:33:15 +0200 Served asset /token-input.css - 304 Not Modified (2ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 12:33:15 +0200 Served asset /jquery_ujs.js - 304 Not Modified (2ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 12:33:15 +0200 Served asset /application.js - 304 Not Modified (4ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 12:33:15 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (1ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 12:33:15 +0200 Served asset /jquery.js - 304 Not Modified (2ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 12:33:44 +0200 Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (2.5ms) Rendered categories/new.html.erb within layouts/application (3.1ms) Completed 200 OK in 7ms (Views: 6.9ms | ActiveRecord: 0.0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 12:33:44 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 12:33:44 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 12:33:44 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input.css?body=1" for 127.0.0.1 at 2012-10-12 12:33:44 +0200 Served asset /token-input.css - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 12:33:44 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 12:33:44 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 12:33:45 +0200 Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (2.3ms) Rendered categories/new.html.erb within layouts/application (2.8ms) Completed 200 OK in 7ms (Views: 6.7ms | ActiveRecord: 0.0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 12:33:45 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input.css?body=1" for 127.0.0.1 at 2012-10-12 12:33:45 +0200 Served asset /token-input.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 12:33:45 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 12:33:45 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 12:33:45 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 12:33:45 +0200 Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (2.9ms) Rendered categories/new.html.erb within layouts/application (3.8ms) Completed 200 OK in 8ms (Views: 8.1ms | ActiveRecord: 0.0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 12:33:45 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input.css?body=1" for 127.0.0.1 at 2012-10-12 12:33:45 +0200 Served asset /token-input.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 12:33:45 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 12:33:45 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 12:33:45 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 12:33:45 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 12:33:46 +0200 Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (2.8ms) Rendered categories/new.html.erb within layouts/application (3.5ms) Completed 200 OK in 8ms (Views: 7.8ms | ActiveRecord: 0.0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 12:33:46 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 12:33:46 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 12:33:46 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/token-input.css?body=1" for 127.0.0.1 at 2012-10-12 12:33:46 +0200 Served asset /token-input.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 12:33:46 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 12:33:46 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/admin/categories/token.json?q=d" for 127.0.0.1 at 2012-10-12 12:33:49 +0200 ActionController::RoutingError (No route matches [GET] "/admin/categories/token.json"): actionpack (3.2.8) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.8) lib/rails/rack/logger.rb:26:in `call_app' railties (3.2.8) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.1) lib/rack/methodoverride.rb:21:in `call' rack (1.4.1) lib/rack/runtime.rb:17:in `call' activesupport (3.2.8) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.1) lib/rack/lock.rb:15:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/static.rb:62:in `call' railties (3.2.8) lib/rails/engine.rb:479:in `call' railties (3.2.8) lib/rails/application.rb:223:in `call' rack (1.4.1) lib/rack/content_length.rb:14:in `call' railties (3.2.8) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.1) lib/rack/handler/webrick.rb:59:in `service' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (0.6ms) Started GET "/admin/categories/token.json?q=df" for 127.0.0.1 at 2012-10-12 12:33:50 +0200 ActionController::RoutingError (No route matches [GET] "/admin/categories/token.json"): actionpack (3.2.8) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.8) lib/rails/rack/logger.rb:26:in `call_app' railties (3.2.8) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.1) lib/rack/methodoverride.rb:21:in `call' rack (1.4.1) lib/rack/runtime.rb:17:in `call' activesupport (3.2.8) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.1) lib/rack/lock.rb:15:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/static.rb:62:in `call' railties (3.2.8) lib/rails/engine.rb:479:in `call' railties (3.2.8) lib/rails/application.rb:223:in `call' rack (1.4.1) lib/rack/content_length.rb:14:in `call' railties (3.2.8) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.1) lib/rack/handler/webrick.rb:59:in `service' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (0.6ms) Started GET "/admin/categories/token.json?q=df" for 127.0.0.1 at 2012-10-12 12:33:51 +0200 ActionController::RoutingError (No route matches [GET] "/admin/categories/token.json"): actionpack (3.2.8) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.8) lib/rails/rack/logger.rb:26:in `call_app' railties (3.2.8) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.1) lib/rack/methodoverride.rb:21:in `call' rack (1.4.1) lib/rack/runtime.rb:17:in `call' activesupport (3.2.8) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.1) lib/rack/lock.rb:15:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/static.rb:62:in `call' railties (3.2.8) lib/rails/engine.rb:479:in `call' railties (3.2.8) lib/rails/application.rb:223:in `call' rack (1.4.1) lib/rack/content_length.rb:14:in `call' railties (3.2.8) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.1) lib/rack/handler/webrick.rb:59:in `service' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (0.6ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 12:35:05 +0200 Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (4.8ms) Rendered categories/new.html.erb within layouts/application (5.7ms) Completed 200 OK in 12ms (Views: 11.8ms | ActiveRecord: 0.0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 12:35:05 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input.css?body=1" for 127.0.0.1 at 2012-10-12 12:35:05 +0200 Served asset /token-input.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 12:35:05 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 12:35:05 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 12:35:05 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 12:35:05 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 12:35:07 +0200 Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (2.1ms) Rendered categories/new.html.erb within layouts/application (2.8ms) Completed 200 OK in 8ms (Views: 7.4ms | ActiveRecord: 0.0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 12:35:07 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 12:35:07 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/token-input.css?body=1" for 127.0.0.1 at 2012-10-12 12:35:07 +0200 Served asset /token-input.css - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 12:35:07 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 12:35:07 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 12:35:07 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/categories/token.json?q=t" for 127.0.0.1 at 2012-10-12 12:35:10 +0200 AbstractController::ActionNotFound (The action 'show' could not be found for CategoriesController): actionpack (3.2.8) lib/abstract_controller/base.rb:116:in `process' actionpack (3.2.8) lib/abstract_controller/rendering.rb:45:in `process' actionpack (3.2.8) lib/action_controller/metal.rb:203:in `dispatch' actionpack (3.2.8) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' actionpack (3.2.8) lib/action_controller/metal.rb:246:in `block in action' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:73:in `call' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' actionpack (3.2.8) 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.8) lib/action_dispatch/routing/route_set.rb:600:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' rack (1.4.1) lib/rack/etag.rb:23:in `call' rack (1.4.1) lib/rack/conditionalget.rb:25:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/head.rb:14:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/params_parser.rb:21:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/flash.rb:242:in `call' rack (1.4.1) lib/rack/session/abstract/id.rb:205:in `context' rack (1.4.1) lib/rack/session/abstract/id.rb:200:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/cookies.rb:339:in `call' activerecord (3.2.8) lib/active_record/query_cache.rb:64:in `call' activerecord (3.2.8) lib/active_record/connection_adapters/abstract/connection_pool.rb:473:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' activesupport (3.2.8) lib/active_support/callbacks.rb:405:in `_run__3365942655095098825__call__2216357525263074590__callbacks' activesupport (3.2.8) lib/active_support/callbacks.rb:405:in `__run_callback' activesupport (3.2.8) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' activesupport (3.2.8) lib/active_support/callbacks.rb:81:in `run_callbacks' actionpack (3.2.8) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/reloader.rb:65:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.8) lib/rails/rack/logger.rb:26:in `call_app' railties (3.2.8) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.1) lib/rack/methodoverride.rb:21:in `call' rack (1.4.1) lib/rack/runtime.rb:17:in `call' activesupport (3.2.8) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.1) lib/rack/lock.rb:15:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/static.rb:62:in `call' railties (3.2.8) lib/rails/engine.rb:479:in `call' railties (3.2.8) lib/rails/application.rb:223:in `call' rack (1.4.1) lib/rack/content_length.rb:14:in `call' railties (3.2.8) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.1) lib/rack/handler/webrick.rb:59:in `service' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/unknown_action.erb within rescues/layout (0.4ms) Started GET "/categories/token.json?q=d" for 127.0.0.1 at 2012-10-12 12:35:24 +0200 AbstractController::ActionNotFound (The action 'show' could not be found for CategoriesController): actionpack (3.2.8) lib/abstract_controller/base.rb:116:in `process' actionpack (3.2.8) lib/abstract_controller/rendering.rb:45:in `process' actionpack (3.2.8) lib/action_controller/metal.rb:203:in `dispatch' actionpack (3.2.8) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' actionpack (3.2.8) lib/action_controller/metal.rb:246:in `block in action' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:73:in `call' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' actionpack (3.2.8) 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.8) lib/action_dispatch/routing/route_set.rb:600:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' rack (1.4.1) lib/rack/etag.rb:23:in `call' rack (1.4.1) lib/rack/conditionalget.rb:25:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/head.rb:14:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/params_parser.rb:21:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/flash.rb:242:in `call' rack (1.4.1) lib/rack/session/abstract/id.rb:205:in `context' rack (1.4.1) lib/rack/session/abstract/id.rb:200:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/cookies.rb:339:in `call' activerecord (3.2.8) lib/active_record/query_cache.rb:64:in `call' activerecord (3.2.8) lib/active_record/connection_adapters/abstract/connection_pool.rb:473:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' activesupport (3.2.8) lib/active_support/callbacks.rb:405:in `_run__3365942655095098825__call__2216357525263074590__callbacks' activesupport (3.2.8) lib/active_support/callbacks.rb:405:in `__run_callback' activesupport (3.2.8) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' activesupport (3.2.8) lib/active_support/callbacks.rb:81:in `run_callbacks' actionpack (3.2.8) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/reloader.rb:65:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.8) lib/rails/rack/logger.rb:26:in `call_app' railties (3.2.8) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.1) lib/rack/methodoverride.rb:21:in `call' rack (1.4.1) lib/rack/runtime.rb:17:in `call' activesupport (3.2.8) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.1) lib/rack/lock.rb:15:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/static.rb:62:in `call' railties (3.2.8) lib/rails/engine.rb:479:in `call' railties (3.2.8) lib/rails/application.rb:223:in `call' rack (1.4.1) lib/rack/content_length.rb:14:in `call' railties (3.2.8) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.1) lib/rack/handler/webrick.rb:59:in `service' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/unknown_action.erb within rescues/layout (0.3ms) Started GET "/categories/token.json?q=df" for 127.0.0.1 at 2012-10-12 12:35:25 +0200 AbstractController::ActionNotFound (The action 'show' could not be found for CategoriesController): actionpack (3.2.8) lib/abstract_controller/base.rb:116:in `process' actionpack (3.2.8) lib/abstract_controller/rendering.rb:45:in `process' actionpack (3.2.8) lib/action_controller/metal.rb:203:in `dispatch' actionpack (3.2.8) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' actionpack (3.2.8) lib/action_controller/metal.rb:246:in `block in action' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:73:in `call' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' actionpack (3.2.8) 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.8) lib/action_dispatch/routing/route_set.rb:600:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' rack (1.4.1) lib/rack/etag.rb:23:in `call' rack (1.4.1) lib/rack/conditionalget.rb:25:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/head.rb:14:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/params_parser.rb:21:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/flash.rb:242:in `call' rack (1.4.1) lib/rack/session/abstract/id.rb:205:in `context' rack (1.4.1) lib/rack/session/abstract/id.rb:200:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/cookies.rb:339:in `call' activerecord (3.2.8) lib/active_record/query_cache.rb:64:in `call' activerecord (3.2.8) lib/active_record/connection_adapters/abstract/connection_pool.rb:473:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' activesupport (3.2.8) lib/active_support/callbacks.rb:405:in `_run__3365942655095098825__call__2216357525263074590__callbacks' activesupport (3.2.8) lib/active_support/callbacks.rb:405:in `__run_callback' activesupport (3.2.8) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' activesupport (3.2.8) lib/active_support/callbacks.rb:81:in `run_callbacks' actionpack (3.2.8) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/reloader.rb:65:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.8) lib/rails/rack/logger.rb:26:in `call_app' railties (3.2.8) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.1) lib/rack/methodoverride.rb:21:in `call' rack (1.4.1) lib/rack/runtime.rb:17:in `call' activesupport (3.2.8) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.1) lib/rack/lock.rb:15:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/static.rb:62:in `call' railties (3.2.8) lib/rails/engine.rb:479:in `call' railties (3.2.8) lib/rails/application.rb:223:in `call' rack (1.4.1) lib/rack/content_length.rb:14:in `call' railties (3.2.8) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.1) lib/rack/handler/webrick.rb:59:in `service' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/unknown_action.erb within rescues/layout (0.4ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 12:37:58 +0200 Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (4.8ms) Rendered categories/new.html.erb within layouts/application (5.7ms) Completed 200 OK in 27ms (Views: 17.1ms | ActiveRecord: 0.6ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 12:37:58 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 12:37:58 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 12:37:58 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/token-input.css?body=1" for 127.0.0.1 at 2012-10-12 12:37:58 +0200 Served asset /token-input.css - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 12:37:58 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 12:37:58 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/categories" for 127.0.0.1 at 2012-10-12 12:38:03 +0200 Processing by CategoriesController#index as HTML Category Load (0.1ms) SELECT "categories".* FROM "categories"  Rendered categories/index.html.erb within layouts/application (1.1ms) Completed 200 OK in 8ms (Views: 6.3ms | ActiveRecord: 0.1ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 12:38:03 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input.css?body=1" for 127.0.0.1 at 2012-10-12 12:38:03 +0200 Served asset /token-input.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 12:38:03 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 12:38:03 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 12:38:03 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 12:38:03 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 12:38:04 +0200 Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (2.4ms) Rendered categories/new.html.erb within layouts/application (3.0ms) Completed 200 OK in 8ms (Views: 7.5ms | ActiveRecord: 0.0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 12:38:04 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input.css?body=1" for 127.0.0.1 at 2012-10-12 12:38:04 +0200 Served asset /token-input.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 12:38:04 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 12:38:04 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 12:38:04 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 12:38:04 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/categories/token.json?q=n" for 127.0.0.1 at 2012-10-12 12:38:07 +0200 Processing by CategoriesController#token as JSON Parameters: {"q"=>"n"} Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '%n%') Completed 200 OK in 2ms (Views: 0.2ms | ActiveRecord: 0.2ms) Started POST "/categories" for 127.0.0.1 at 2012-10-12 12:38:16 +0200 Processing by CategoriesController#create as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"2av1uH15caF6Mz+lKlJW9cNDGASdZWyvFy3FWznlwS0=", "category"=>{"name"=>"sub", "parent_id"=>"1"}, "commit"=>"Create Category"}  (0.1ms) begin transaction SQL (5.6ms) INSERT INTO "categories" ("created_at", "name", "parent_id", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Fri, 12 Oct 2012 10:38:16 UTC +00:00], ["name", "sub"], ["parent_id", 1], ["updated_at", Fri, 12 Oct 2012 10:38:16 UTC +00:00]]  (1.2ms) commit transaction Redirected to http://localhost:3000/categories Completed 302 Found in 11ms (ActiveRecord: 6.8ms) Started GET "/categories" for 127.0.0.1 at 2012-10-12 12:38:16 +0200 Processing by CategoriesController#index as HTML Category Load (0.1ms) SELECT "categories".* FROM "categories" Rendered categories/index.html.erb within layouts/application (0.6ms) Completed 200 OK in 5ms (Views: 4.3ms | ActiveRecord: 0.1ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 12:38:16 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input.css?body=1" for 127.0.0.1 at 2012-10-12 12:38:16 +0200 Served asset /token-input.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 12:38:16 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 12:38:16 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 12:38:16 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 12:38:16 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 12:38:19 +0200 Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (3.3ms) Rendered categories/new.html.erb within layouts/application (4.1ms) Completed 200 OK in 9ms (Views: 9.2ms | ActiveRecord: 0.0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 12:38:19 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 12:38:19 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/token-input.css?body=1" for 127.0.0.1 at 2012-10-12 12:38:19 +0200 Served asset /token-input.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 12:38:19 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 12:38:19 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 12:38:19 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/categories/token.json?q=su" for 127.0.0.1 at 2012-10-12 12:38:24 +0200 Processing by CategoriesController#token as JSON Parameters: {"q"=>"su"} Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '%su%') Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) Started POST "/categories" for 127.0.0.1 at 2012-10-12 12:38:28 +0200 Processing by CategoriesController#create as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"2av1uH15caF6Mz+lKlJW9cNDGASdZWyvFy3FWznlwS0=", "category"=>{"name"=>"sub sub", "parent_id"=>"2"}, "commit"=>"Create Category"}  (0.1ms) begin transaction SQL (0.6ms) INSERT INTO "categories" ("created_at", "name", "parent_id", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Fri, 12 Oct 2012 10:38:28 UTC +00:00], ["name", "sub sub"], ["parent_id", 2], ["updated_at", Fri, 12 Oct 2012 10:38:28 UTC +00:00]]  (1.0ms) commit transaction Redirected to http://localhost:3000/categories Completed 302 Found in 4ms (ActiveRecord: 1.7ms) Started GET "/categories" for 127.0.0.1 at 2012-10-12 12:38:28 +0200 Processing by CategoriesController#index as HTML Category Load (0.2ms) SELECT "categories".* FROM "categories"  Rendered categories/index.html.erb within layouts/application (0.6ms) Completed 200 OK in 8ms (Views: 6.7ms | ActiveRecord: 0.2ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 12:38:28 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input.css?body=1" for 127.0.0.1 at 2012-10-12 12:38:28 +0200 Served asset /token-input.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 12:38:28 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 12:38:28 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 12:38:28 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 12:38:28 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 12:43:50 +0200 Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (2.8ms) Rendered categories/new.html.erb within layouts/application (3.4ms) Completed 200 OK in 13ms (Views: 7.1ms | ActiveRecord: 0.4ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 12:43:50 +0200 Served asset /application.css - 304 Not Modified (31ms) Started GET "/assets/token-input.css?body=1" for 127.0.0.1 at 2012-10-12 12:43:50 +0200 Served asset /token-input.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 12:43:50 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 12:43:50 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 12:43:50 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 12:43:50 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 12:44:20 +0200 Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (4.5ms) Rendered categories/new.html.erb within layouts/application (5.7ms) Compiled token-input-facebook.css (0ms) (pid 76280) Compiled application.css (12ms) (pid 76280) Completed 200 OK in 32ms (Views: 32.1ms | ActiveRecord: 0.0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 12:44:20 +0200 Served asset /application.css - 304 Not Modified (3ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-12 12:44:20 +0200 Served asset /token-input-facebook.css - 200 OK (2ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 12:44:20 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 12:44:20 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 12:44:20 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 12:44:20 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/categories/token.json?q=d" for 127.0.0.1 at 2012-10-12 12:44:22 +0200 Processing by CategoriesController#token as JSON Parameters: {"q"=>"d"} Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '%d%') Completed 200 OK in 1ms (Views: 0.1ms | ActiveRecord: 0.2ms) Started GET "/categories/token.json?q=df" for 127.0.0.1 at 2012-10-12 12:44:23 +0200 Processing by CategoriesController#token as JSON Parameters: {"q"=>"df"} Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '%df%') Completed 200 OK in 1ms (Views: 0.1ms | ActiveRecord: 0.2ms) Started GET "/categories/token.json?q=n" for 127.0.0.1 at 2012-10-12 12:44:25 +0200 Processing by CategoriesController#token as JSON Parameters: {"q"=>"n"} Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '%n%') Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.2ms) Started GET "/categories/token.json?q=s" for 127.0.0.1 at 2012-10-12 12:44:27 +0200 Processing by CategoriesController#token as JSON Parameters: {"q"=>"s"} Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '%s%') Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.2ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 12:55:17 +0200 Connecting to database specified by database.yml Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (55.7ms) Rendered categories/new.html.erb within layouts/application (60.6ms) Completed 500 Internal Server Error in 124ms ActionView::Template::Error (undefined method `token_field' for #): 5:

6:

7: <%= f.label :parent_id %> 8: <%= f.token_field :parent_id, :model => :category, :token_url => "/categories/token.json" %> 9:

10: <%= f.submit %> 11: <% end %> app/views/categories/_form.html.erb:8:in `block in _app_views_categories__form_html_erb__4482121404535896327_2186680200' app/views/categories/_form.html.erb:1:in `_app_views_categories__form_html_erb__4482121404535896327_2186680200' app/views/categories/new.html.erb:2:in `_app_views_categories_new_html_erb___569770230832164428_2170274180' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (26.2ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.4ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (35.6ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 12:58:01 +0200 Connecting to database specified by database.yml Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (20.3ms) Rendered categories/new.html.erb within layouts/application (25.3ms) Completed 500 Internal Server Error in 70ms ActionView::Template::Error (undefined method `token_field' for #): 5:

6:

7: <%= f.label :parent_id %> 8: <%= f.token_field :parent_id, :model => :category, :token_url => "/categories/token.json" %> 9:

10: <%= f.submit %> 11: <% end %> app/views/categories/_form.html.erb:8:in `block in _app_views_categories__form_html_erb__3679864089128704092_2159421260' app/views/categories/_form.html.erb:1:in `_app_views_categories__form_html_erb__3679864089128704092_2159421260' app/views/categories/new.html.erb:2:in `_app_views_categories_new_html_erb__1882021031944154533_2167231260' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (18.2ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.0ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (24.5ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 12:58:49 +0200 Connecting to database specified by database.yml Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (35.1ms) Rendered categories/new.html.erb within layouts/application (42.2ms) Completed 500 Internal Server Error in 108ms ActionView::Template::Error (undefined method `token_field' for #): 5:

6:

7: <%= f.label :parent_id %> 8: <%= f.token_field :parent_id, :model => :category, :token_url => "/categories/token.json" %> 9:

10: <%= f.submit %> 11: <% end %> app/views/categories/_form.html.erb:8:in `block in _app_views_categories__form_html_erb__4140744813809962419_2179205160' app/views/categories/_form.html.erb:1:in `_app_views_categories__form_html_erb__4140744813809962419_2179205160' app/views/categories/new.html.erb:2:in `_app_views_categories_new_html_erb___2592824688105459040_2177959320' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (2.2ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.7ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (34.0ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 13:00:15 +0200 Connecting to database specified by database.yml Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (48.3ms) Rendered categories/new.html.erb within layouts/application (54.5ms) Completed 500 Internal Server Error in 112ms ActionView::Template::Error (undefined method `token_field' for #): 5:

6:

7: <%= f.label :parent_id %> 8: <%= f.token_field :parent_id, :model => :category, :token_url => "/categories/token.json" %> 9:

10: <%= f.submit %> 11: <% end %> app/views/categories/_form.html.erb:8:in `block in _app_views_categories__form_html_erb__884084129669588273_2184159260' app/views/categories/_form.html.erb:1:in `_app_views_categories__form_html_erb__884084129669588273_2184159260' app/views/categories/new.html.erb:2:in `_app_views_categories_new_html_erb___1572138950306552606_2159624640' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (20.9ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.0ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (28.7ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 13:02:50 +0200 Connecting to database specified by database.yml Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (11.8ms) Rendered categories/new.html.erb within layouts/application (16.0ms) Compiled token-input-facebook.css (0ms) (pid 94882) Compiled application.css (3ms) (pid 94882) Compiled jquery.js (4ms) (pid 94882) Compiled jquery_ujs.js (0ms) (pid 94882) Compiled jquery.tokeninput.js (0ms) (pid 94882) Compiled application.js (17ms) (pid 94882) Completed 200 OK in 120ms (Views: 85.0ms | ActiveRecord: 1.4ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 13:02:50 +0200 Served asset /application.css - 304 Not Modified (4ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-12 13:02:50 +0200 Served asset /token-input-facebook.css - 304 Not Modified (1ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 13:02:50 +0200 Served asset /jquery_ujs.js - 304 Not Modified (5ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 13:02:50 +0200 Served asset /jquery.js - 304 Not Modified (1ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 13:02:50 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (1ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 13:02:50 +0200 Served asset /application.js - 304 Not Modified (4ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 13:03:08 +0200 Connecting to database specified by database.yml Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (37.2ms) Rendered categories/new.html.erb within layouts/application (41.9ms) Completed 500 Internal Server Error in 92ms ActionView::Template::Error (undefined method `token_field' for #): 5:

6:

7: <%= f.label :parent_id %> 8: <%= f.token_field :parent_id, :model => :category, :token_url => "/categories/token.json" %> 9:

10: <%= f.submit %> 11: <% end %> app/views/categories/_form.html.erb:8:in `block in _app_views_categories__form_html_erb__4160056059248822195_2165481000' app/views/categories/_form.html.erb:1:in `_app_views_categories__form_html_erb__4160056059248822195_2165481000' app/views/categories/new.html.erb:2:in `_app_views_categories_new_html_erb__4270653422052904974_2166756260' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.4ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (0.9ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (8.6ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 13:03:25 +0200 Connecting to database specified by database.yml Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (13.4ms) Rendered categories/new.html.erb within layouts/application (18.8ms) Completed 200 OK in 108ms (Views: 64.6ms | ActiveRecord: 1.6ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 13:03:25 +0200 Served asset /application.css - 304 Not Modified (2ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-12 13:03:25 +0200 Served asset /token-input-facebook.css - 304 Not Modified (2ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 13:03:25 +0200 Served asset /jquery.js - 304 Not Modified (7ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 13:03:25 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (2ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 13:03:25 +0200 Served asset /jquery_ujs.js - 304 Not Modified (2ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 13:03:25 +0200 Served asset /application.js - 304 Not Modified (5ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 13:03:52 +0200 Connecting to database specified by database.yml Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (17.6ms) Rendered categories/new.html.erb within layouts/application (23.5ms) Completed 200 OK in 113ms (Views: 66.5ms | ActiveRecord: 1.8ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 13:03:52 +0200 Served asset /application.css - 304 Not Modified (3ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 13:03:52 +0200 Served asset /jquery_ujs.js - 304 Not Modified (2ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-12 13:03:52 +0200 Served asset /token-input-facebook.css - 304 Not Modified (2ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 13:03:52 +0200 Served asset /jquery.js - 304 Not Modified (3ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 13:03:52 +0200 Served asset /application.js - 304 Not Modified (33ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 13:03:52 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (2ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 13:04:40 +0200 Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (18.4ms) Rendered categories/new.html.erb within layouts/application (19.2ms) Completed 200 OK in 25ms (Views: 24.9ms | ActiveRecord: 0.0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 13:04:40 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 13:04:40 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-12 13:04:40 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 13:04:40 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 13:04:40 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 13:04:40 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 13:05:40 +0200 Connecting to database specified by database.yml Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (11.7ms) Rendered categories/new.html.erb within layouts/application (16.1ms) Completed 200 OK in 74ms (Views: 36.3ms | ActiveRecord: 1.5ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 13:05:40 +0200 Served asset /application.css - 304 Not Modified (6ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-12 13:05:40 +0200 Served asset /token-input-facebook.css - 304 Not Modified (2ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 13:05:40 +0200 Served asset /jquery.js - 304 Not Modified (3ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 13:05:40 +0200 Served asset /jquery_ujs.js - 304 Not Modified (2ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 13:05:40 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (2ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 13:05:40 +0200 Served asset /application.js - 304 Not Modified (6ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 13:06:19 +0200 Connecting to database specified by database.yml Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (11.9ms) Rendered categories/new.html.erb within layouts/application (17.6ms) Completed 200 OK in 78ms (Views: 37.1ms | ActiveRecord: 1.7ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 13:06:19 +0200 Served asset /application.css - 304 Not Modified (6ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-12 13:06:19 +0200 Served asset /token-input-facebook.css - 304 Not Modified (2ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 13:06:19 +0200 Served asset /jquery.js - 304 Not Modified (2ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 13:06:19 +0200 Served asset /jquery_ujs.js - 304 Not Modified (1ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 13:06:19 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (1ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 13:06:19 +0200 Served asset /application.js - 304 Not Modified (7ms) Started GET "/categories/token.json?q=f" for 127.0.0.1 at 2012-10-12 13:06:21 +0200 Processing by CategoriesController#token as JSON Parameters: {"q"=>"f"} Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '%f%') Completed 200 OK in 2ms (Views: 0.2ms | ActiveRecord: 0.2ms) Started GET "/categories/token.json?q=+" for 127.0.0.1 at 2012-10-12 13:06:22 +0200 Processing by CategoriesController#token as JSON Parameters: {"q"=>" "} Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '% %') Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.2ms) Started POST "/categories" for 127.0.0.1 at 2012-10-12 13:06:25 +0200 Processing by CategoriesController#create as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"2av1uH15caF6Mz+lKlJW9cNDGASdZWyvFy3FWznlwS0=", "category"=>{"name"=>"", "parent_id"=>"3"}, "commit"=>"Create Category"}  (0.1ms) begin transaction SQL (5.6ms) INSERT INTO "categories" ("created_at", "name", "parent_id", "updated_at") VALUES (?, ?, ?, ?) [["created_at", Fri, 12 Oct 2012 11:06:25 UTC +00:00], ["name", ""], ["parent_id", 3], ["updated_at", Fri, 12 Oct 2012 11:06:25 UTC +00:00]]  (0.9ms) commit transaction Redirected to http://localhost:3000/categories Completed 302 Found in 11ms (ActiveRecord: 6.6ms) Started GET "/categories" for 127.0.0.1 at 2012-10-12 13:06:25 +0200 Processing by CategoriesController#index as HTML Category Load (0.1ms) SELECT "categories".* FROM "categories" Rendered categories/index.html.erb within layouts/application (1.5ms) Completed 200 OK in 6ms (Views: 5.2ms | ActiveRecord: 0.1ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 13:06:25 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-12 13:06:25 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 13:06:26 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 13:06:26 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 13:06:26 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 13:06:26 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 13:06:27 +0200 Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (3.8ms) Rendered categories/new.html.erb within layouts/application (4.6ms) Completed 200 OK in 11ms (Views: 10.3ms | ActiveRecord: 0.0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 13:06:27 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-12 13:06:27 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 13:06:27 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 13:06:27 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 13:06:27 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 13:06:27 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 13:14:35 +0200 Connecting to database specified by database.yml Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (10.8ms) Rendered categories/new.html.erb within layouts/application (15.4ms) Compiled token-input-facebook.css (0ms) (pid 2185) Compiled application.css (4ms) (pid 2185) Compiled jquery.js (22ms) (pid 2185) Compiled jquery_ujs.js (0ms) (pid 2185) Compiled jquery.tokeninput.js (0ms) (pid 2185) Compiled application.js (36ms) (pid 2185) Completed 200 OK in 121ms (Views: 82.3ms | ActiveRecord: 1.3ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-12 13:14:35 +0200 Served asset /token-input-facebook.css - 304 Not Modified (2ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 13:14:35 +0200 Served asset /application.css - 304 Not Modified (4ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 13:14:35 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (1ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 13:14:35 +0200 Served asset /application.js - 304 Not Modified (4ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 13:14:35 +0200 Served asset /jquery.js - 304 Not Modified (2ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 13:14:36 +0200 Served asset /jquery_ujs.js - 304 Not Modified (2ms) Started GET "/categories/token.json?q=w" for 127.0.0.1 at 2012-10-12 13:14:39 +0200 AbstractController::ActionNotFound (The action 'show' could not be found for CategoriesController): actionpack (3.2.8) lib/abstract_controller/base.rb:116:in `process' actionpack (3.2.8) lib/abstract_controller/rendering.rb:45:in `process' actionpack (3.2.8) lib/action_controller/metal.rb:203:in `dispatch' actionpack (3.2.8) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' actionpack (3.2.8) lib/action_controller/metal.rb:246:in `block in action' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:73:in `call' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' actionpack (3.2.8) 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.8) lib/action_dispatch/routing/route_set.rb:600:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' rack (1.4.1) lib/rack/etag.rb:23:in `call' rack (1.4.1) lib/rack/conditionalget.rb:25:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/head.rb:14:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/params_parser.rb:21:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/flash.rb:242:in `call' rack (1.4.1) lib/rack/session/abstract/id.rb:205:in `context' rack (1.4.1) lib/rack/session/abstract/id.rb:200:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/cookies.rb:339:in `call' activerecord (3.2.8) lib/active_record/query_cache.rb:64:in `call' activerecord (3.2.8) lib/active_record/connection_adapters/abstract/connection_pool.rb:473:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' activesupport (3.2.8) lib/active_support/callbacks.rb:405:in `_run__1991360950574630941__call__2041765774576432645__callbacks' activesupport (3.2.8) lib/active_support/callbacks.rb:405:in `__run_callback' activesupport (3.2.8) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' activesupport (3.2.8) lib/active_support/callbacks.rb:81:in `run_callbacks' actionpack (3.2.8) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/reloader.rb:65:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.8) lib/rails/rack/logger.rb:26:in `call_app' railties (3.2.8) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.1) lib/rack/methodoverride.rb:21:in `call' rack (1.4.1) lib/rack/runtime.rb:17:in `call' activesupport (3.2.8) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.1) lib/rack/lock.rb:15:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/static.rb:62:in `call' railties (3.2.8) lib/rails/engine.rb:479:in `call' railties (3.2.8) lib/rails/application.rb:223:in `call' rack (1.4.1) lib/rack/content_length.rb:14:in `call' railties (3.2.8) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.1) lib/rack/handler/webrick.rb:59:in `service' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/unknown_action.erb within rescues/layout (0.4ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 13:17:06 +0200 Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (4.9ms) Rendered categories/new.html.erb within layouts/application (5.8ms) Completed 200 OK in 31ms (Views: 12.1ms | ActiveRecord: 0.6ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 13:17:07 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-12 13:17:07 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 13:17:07 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 13:17:07 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 13:17:07 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 13:17:07 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/categories/token.json?q=d" for 127.0.0.1 at 2012-10-12 13:17:08 +0200 Processing by CategoriesController#token as JSON Parameters: {"q"=>"d"} Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '%d%') Completed 200 OK in 2ms (Views: 0.2ms | ActiveRecord: 0.2ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 13:19:02 +0200 Connecting to database specified by database.yml Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (22.6ms) Rendered categories/new.html.erb within layouts/application (29.1ms) Compiled token-input-facebook.css (0ms) (pid 4866) Compiled application.css (4ms) (pid 4866) Compiled jquery.js (31ms) (pid 4866) Compiled jquery_ujs.js (0ms) (pid 4866) Compiled jquery.tokeninput.js (0ms) (pid 4866) Compiled application.js (48ms) (pid 4866) Completed 200 OK in 178ms (Views: 115.2ms | ActiveRecord: 3.0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 13:19:02 +0200 Served asset /application.css - 304 Not Modified (7ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-12 13:19:02 +0200 Served asset /token-input-facebook.css - 304 Not Modified (5ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 13:19:02 +0200 Served asset /jquery.js - 304 Not Modified (2ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 13:19:02 +0200 Served asset /jquery_ujs.js - 304 Not Modified (4ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 13:19:02 +0200 Served asset /application.js - 304 Not Modified (4ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 13:19:02 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (1ms) Started GET "/categories/token?q=d" for 127.0.0.1 at 2012-10-12 13:19:04 +0200 Processing by CategoriesController#token as JSON Parameters: {"q"=>"d"} Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '%d%') Completed 200 OK in 3ms (Views: 0.2ms | ActiveRecord: 0.2ms) Started GET "/categories/token?q=n" for 127.0.0.1 at 2012-10-12 13:19:06 +0200 Processing by CategoriesController#token as JSON Parameters: {"q"=>"n"} Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '%n%') Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) Started GET "/admin" for 127.0.0.1 at 2012-10-12 15:53:19 +0200 ActionController::RoutingError (No route matches [GET] "/admin"): actionpack (3.2.8) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.8) lib/rails/rack/logger.rb:26:in `call_app' railties (3.2.8) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.1) lib/rack/methodoverride.rb:21:in `call' rack (1.4.1) lib/rack/runtime.rb:17:in `call' activesupport (3.2.8) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.1) lib/rack/lock.rb:15:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/static.rb:62:in `call' railties (3.2.8) lib/rails/engine.rb:479:in `call' railties (3.2.8) lib/rails/application.rb:223:in `call' rack (1.4.1) lib/rack/content_length.rb:14:in `call' railties (3.2.8) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.1) lib/rack/handler/webrick.rb:59:in `service' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (0.6ms) Started GET "/" for 127.0.0.1 at 2012-10-12 18:34:02 +0200 Connecting to database specified by database.yml Processing by CategoriesController#index as HTML Category Load (22.9ms) SELECT "categories".* FROM "categories"  Rendered categories/index.html.erb within layouts/application (4.5ms) Completed 200 OK in 203ms (Views: 126.0ms | ActiveRecord: 24.9ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 18:34:03 +0200 Served asset /application.css - 304 Not Modified (3ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-12 18:34:03 +0200 Served asset /token-input-facebook.css - 304 Not Modified (2ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 18:34:03 +0200 Served asset /jquery_ujs.js - 304 Not Modified (3ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 18:34:03 +0200 Served asset /jquery.js - 304 Not Modified (6ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 18:34:03 +0200 Served asset /application.js - 304 Not Modified (5ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 18:34:03 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (2ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 18:34:05 +0200 Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (47.0ms) Rendered categories/new.html.erb within layouts/application (91.4ms) Completed 500 Internal Server Error in 176ms ActionView::Template::Error (uninitialized constant Parent): 5:

6:

7: <%= f.label :parent_id %> 8: <%= f.token_field :parent_id, :token_url => token_categories_path %> 9:

10: <%= f.submit %> 11: <% end %> app/views/categories/_form.html.erb:8:in `block in _app_views_categories__form_html_erb__3710308626687115792_2179179080' app/views/categories/_form.html.erb:1:in `_app_views_categories__form_html_erb__3710308626687115792_2179179080' app/views/categories/new.html.erb:2:in `_app_views_categories_new_html_erb___4559839763195524759_2164109640' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.4ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.0ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (9.3ms) Started GET "/categories/new" for 127.0.0.1 at 2012-10-12 18:37:45 +0200 Processing by CategoriesController#new as HTML Rendered categories/_form.html.erb (93.4ms) Rendered categories/new.html.erb within layouts/application (112.4ms) Completed 200 OK in 156ms (Views: 155.7ms | ActiveRecord: 0.0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-12 18:37:45 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-12 18:37:45 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-12 18:37:45 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-12 18:37:45 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-12 18:37:45 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-12 18:37:45 +0200 Served asset /application.js - 304 Not Modified (0ms) Connecting to database specified by database.yml Connecting to database specified by database.yml Started GET "/products" for 127.0.0.1 at 2012-10-13 02:00:36 +0200 Connecting to database specified by database.yml Processing by ProductsController#index as HTML Completed 500 Internal Server Error in 44ms ActiveRecord::StatementInvalid (Could not find table 'products'): app/controllers/products_controller.rb:3:in `index' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.9ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.4ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (14.7ms) Connecting to database specified by database.yml  (0.2ms) SELECT "schema_migrations"."version" FROM "schema_migrations"  Migrating to CreateCategories (20121012100526) Migrating to CreateProducts (20121012235444)  (0.1ms) select sqlite_version(*)  (0.1ms) begin transaction  (0.5ms) CREATE TABLE "products" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar(255), "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)  (22.6ms) INSERT INTO "schema_migrations" ("version") VALUES ('20121012235444')  (22.4ms) commit transaction Migrating to CreateProductHasCategories (20121012235509)  (0.1ms) begin transaction  (0.4ms) CREATE TABLE "product_has_categories" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "product_id" integer, "category_id" integer, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)  (0.2ms) INSERT INTO "schema_migrations" ("version") VALUES ('20121012235509')  (1.0ms) commit transaction  (0.4ms) select sqlite_version(*)  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations"  (0.1ms) PRAGMA index_list("categories")  (0.0ms) PRAGMA index_list("product_has_categories")  (0.0ms) PRAGMA index_list("products") Started GET "/products" for 127.0.0.1 at 2012-10-13 12:08:51 +0200 Connecting to database specified by database.yml Processing by ProductsController#index as HTML Product Load (21.7ms) SELECT "products".* FROM "products"  Rendered products/index.html.erb within layouts/application (2.6ms) Compiled token-input-facebook.css (0ms) (pid 98860) Compiled application.css (6ms) (pid 98860) Compiled jquery.js (5ms) (pid 98860) Compiled jquery_ujs.js (0ms) (pid 98860) Compiled jquery.tokeninput.js (0ms) (pid 98860) Compiled application.js (229ms) (pid 98860) Completed 200 OK in 539ms (Views: 473.1ms | ActiveRecord: 23.5ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 12:08:53 +0200 Served asset /application.css - 304 Not Modified (3ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 12:08:53 +0200 Served asset /jquery.js - 304 Not Modified (7ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 12:08:53 +0200 Served asset /token-input-facebook.css - 304 Not Modified (3ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 12:08:53 +0200 Served asset /jquery_ujs.js - 304 Not Modified (1ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 12:08:53 +0200 Served asset /application.js - 304 Not Modified (5ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 12:08:53 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (1ms) Started GET "/products" for 127.0.0.1 at 2012-10-13 12:08:53 +0200 Processing by ProductsController#index as HTML Product Load (0.1ms) SELECT "products".* FROM "products" Rendered products/index.html.erb within layouts/application (0.3ms) Completed 200 OK in 5ms (Views: 3.9ms | ActiveRecord: 0.1ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 12:08:53 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 12:08:53 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 12:08:53 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 12:08:53 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 12:08:53 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 12:08:53 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/products/new" for 127.0.0.1 at 2012-10-13 12:08:55 +0200 Processing by ProductsController#new as HTML  (10.2ms) SELECT "categories".id FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" IS NULL  (0.2ms) SELECT COUNT(*) FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" IS NULL Rendered products/_form.html.erb (168.4ms) Rendered products/new.html.erb within layouts/application (172.1ms) Completed 200 OK in 223ms (Views: 205.9ms | ActiveRecord: 11.0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 12:08:56 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 12:08:56 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 12:08:56 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 12:08:56 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 12:08:56 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 12:08:56 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/categories/token?q=p" for 127.0.0.1 at 2012-10-13 12:08:59 +0200 Processing by CategoriesController#token as JSON Parameters: {"q"=>"p"} Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '%p%') Completed 200 OK in 2ms (Views: 0.1ms | ActiveRecord: 0.2ms) Started GET "/categories/token?q=n" for 127.0.0.1 at 2012-10-13 12:09:01 +0200 Processing by CategoriesController#token as JSON Parameters: {"q"=>"n"} Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '%n%') Completed 200 OK in 8ms (Views: 0.3ms | ActiveRecord: 0.2ms) Started GET "/categories/token?q=s" for 127.0.0.1 at 2012-10-13 12:09:04 +0200 Processing by CategoriesController#token as JSON Parameters: {"q"=>"s"} Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '%s%') Completed 200 OK in 2ms (Views: 0.2ms | ActiveRecord: 0.2ms) Started POST "/products" for 127.0.0.1 at 2012-10-13 12:09:06 +0200 Processing by ProductsController#create as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"1GQQLhbv/PFJeStXdkdDsMY8Vf3jPqlo8ns0NEg/4t0=", "product"=>{"name"=>"hello", "category_ids"=>"1,2"}, "commit"=>"Create Product"} Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" IN (1, 2)  (0.0ms) begin transaction SQL (30.5ms) INSERT INTO "products" ("created_at", "name", "updated_at") VALUES (?, ?, ?) [["created_at", Sat, 13 Oct 2012 10:09:06 UTC +00:00], ["name", "hello"], ["updated_at", Sat, 13 Oct 2012 10:09:06 UTC +00:00]] SQL (0.4ms) INSERT INTO "product_has_categories" ("category_id", "created_at", "product_id", "updated_at") VALUES (?, ?, ?, ?) [["category_id", 1], ["created_at", Sat, 13 Oct 2012 10:09:06 UTC +00:00], ["product_id", 1], ["updated_at", Sat, 13 Oct 2012 10:09:06 UTC +00:00]] SQL (0.3ms) INSERT INTO "product_has_categories" ("category_id", "created_at", "product_id", "updated_at") VALUES (?, ?, ?, ?) [["category_id", 2], ["created_at", Sat, 13 Oct 2012 10:09:06 UTC +00:00], ["product_id", 1], ["updated_at", Sat, 13 Oct 2012 10:09:06 UTC +00:00]]  (1.5ms) commit transaction Redirected to http://localhost:3000/products Completed 302 Found in 51ms (ActiveRecord: 33.0ms) Started GET "/products" for 127.0.0.1 at 2012-10-13 12:09:06 +0200 Processing by ProductsController#index as HTML Product Load (0.2ms) SELECT "products".* FROM "products" Rendered products/index.html.erb within layouts/application (43.9ms) Completed 500 Internal Server Error in 48ms ActionView::Template::Error (undefined method `parent_ids' for #): 3:
    4: <% @products.each do |product| %> 5: <%= content_tag_for(:li, product) do %> 6: <%= product.name %> - <%= product.parent_ids.inspect %> 7: <% end %> 8: <% end %> 9:
app/views/products/index.html.erb:6:in `block (2 levels) in _app_views_products_index_html_erb__2022835505448971706_2154178040' app/views/products/index.html.erb:5:in `block in _app_views_products_index_html_erb__2022835505448971706_2154178040' app/views/products/index.html.erb:4:in `each' app/views/products/index.html.erb:4:in `_app_views_products_index_html_erb__2022835505448971706_2154178040' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.7ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.0ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (8.8ms) Started GET "/products" for 127.0.0.1 at 2012-10-13 12:09:20 +0200 Processing by ProductsController#index as HTML Product Load (0.2ms) SELECT "products".* FROM "products"   (0.2ms) SELECT "categories".id FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 1 Rendered products/index.html.erb within layouts/application (3.5ms) Completed 200 OK in 11ms (Views: 9.6ms | ActiveRecord: 0.5ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 12:09:20 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 12:09:20 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 12:09:20 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 12:09:20 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 12:09:20 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 12:09:20 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/products/new" for 127.0.0.1 at 2012-10-13 12:09:26 +0200 Processing by ProductsController#new as HTML  (0.2ms) SELECT "categories".id FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" IS NULL  (0.3ms) SELECT COUNT(*) FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" IS NULL Rendered products/_form.html.erb (7.2ms) Rendered products/new.html.erb within layouts/application (8.0ms) Completed 200 OK in 13ms (Views: 11.8ms | ActiveRecord: 0.5ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 12:09:26 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 12:09:26 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 12:09:26 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 12:09:26 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 12:09:26 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 12:09:26 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started POST "/products" for 127.0.0.1 at 2012-10-13 12:09:28 +0200 Processing by ProductsController#create as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"1GQQLhbv/PFJeStXdkdDsMY8Vf3jPqlo8ns0NEg/4t0=", "product"=>{"name"=>"novy", "category_ids"=>""}, "commit"=>"Create Product"}  (0.1ms) begin transaction SQL (0.4ms) INSERT INTO "products" ("created_at", "name", "updated_at") VALUES (?, ?, ?) [["created_at", Sat, 13 Oct 2012 10:09:28 UTC +00:00], ["name", "novy"], ["updated_at", Sat, 13 Oct 2012 10:09:28 UTC +00:00]]  (1.8ms) commit transaction Redirected to http://localhost:3000/products Completed 302 Found in 5ms (ActiveRecord: 2.3ms) Started GET "/products" for 127.0.0.1 at 2012-10-13 12:09:28 +0200 Processing by ProductsController#index as HTML Product Load (0.3ms) SELECT "products".* FROM "products"  (0.2ms) SELECT "categories".id FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 1  (0.2ms) SELECT "categories".id FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 2 Rendered products/index.html.erb within layouts/application (2.9ms) Completed 200 OK in 11ms (Views: 8.3ms | ActiveRecord: 0.7ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 12:09:28 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 12:09:28 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 12:09:28 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 12:09:28 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 12:09:28 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 12:09:28 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/products" for 127.0.0.1 at 2012-10-13 12:24:30 +0200 Processing by ProductsController#index as HTML Product Load (0.1ms) SELECT "products".* FROM "products"   (0.1ms) SELECT "categories".id FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 1  (0.1ms) SELECT "categories".id FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 2 Rendered products/index.html.erb within layouts/application (3.8ms) Completed 200 OK in 9ms (Views: 8.2ms | ActiveRecord: 0.4ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 12:24:30 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 12:24:30 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 12:24:30 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 12:24:30 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 12:24:30 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 12:24:30 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/products/new" for 127.0.0.1 at 2012-10-13 12:24:42 +0200 Processing by ProductsController#new as HTML  (0.1ms) SELECT "categories".id FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" IS NULL  (0.1ms) SELECT COUNT(*) FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" IS NULL Rendered products/_form.html.erb (4.6ms) Rendered products/new.html.erb within layouts/application (5.2ms) Completed 200 OK in 9ms (Views: 8.6ms | ActiveRecord: 0.2ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 12:24:42 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 12:24:42 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 12:24:42 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 12:24:42 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 12:24:42 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 12:24:42 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/products/1/edit" for 127.0.0.1 at 2012-10-13 12:24:45 +0200 AbstractController::ActionNotFound (The action 'edit' could not be found for ProductsController): actionpack (3.2.8) lib/abstract_controller/base.rb:116:in `process' actionpack (3.2.8) lib/abstract_controller/rendering.rb:45:in `process' actionpack (3.2.8) lib/action_controller/metal.rb:203:in `dispatch' actionpack (3.2.8) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' actionpack (3.2.8) lib/action_controller/metal.rb:246:in `block in action' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:73:in `call' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' actionpack (3.2.8) 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.8) lib/action_dispatch/routing/route_set.rb:600:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' rack (1.4.1) lib/rack/etag.rb:23:in `call' rack (1.4.1) lib/rack/conditionalget.rb:25:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/head.rb:14:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/params_parser.rb:21:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/flash.rb:242:in `call' rack (1.4.1) lib/rack/session/abstract/id.rb:205:in `context' rack (1.4.1) lib/rack/session/abstract/id.rb:200:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/cookies.rb:339:in `call' activerecord (3.2.8) lib/active_record/query_cache.rb:64:in `call' activerecord (3.2.8) lib/active_record/connection_adapters/abstract/connection_pool.rb:473:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' activesupport (3.2.8) lib/active_support/callbacks.rb:405:in `_run__1490882092580662470__call__3367597246046703916__callbacks' activesupport (3.2.8) lib/active_support/callbacks.rb:405:in `__run_callback' activesupport (3.2.8) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' activesupport (3.2.8) lib/active_support/callbacks.rb:81:in `run_callbacks' actionpack (3.2.8) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/reloader.rb:65:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.8) lib/rails/rack/logger.rb:26:in `call_app' railties (3.2.8) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.1) lib/rack/methodoverride.rb:21:in `call' rack (1.4.1) lib/rack/runtime.rb:17:in `call' activesupport (3.2.8) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.1) lib/rack/lock.rb:15:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/static.rb:62:in `call' railties (3.2.8) lib/rails/engine.rb:479:in `call' railties (3.2.8) lib/rails/application.rb:223:in `call' rack (1.4.1) lib/rack/content_length.rb:14:in `call' railties (3.2.8) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.1) lib/rack/handler/webrick.rb:59:in `service' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/unknown_action.erb within rescues/layout (0.4ms) Started GET "/products" for 127.0.0.1 at 2012-10-13 12:26:37 +0200 Processing by ProductsController#index as HTML Product Load (0.1ms) SELECT "products".* FROM "products"  (0.1ms) SELECT "categories".id FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 1  (0.2ms) SELECT "categories".id FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 2 Rendered products/index.html.erb within layouts/application (29.7ms) Completed 200 OK in 73ms (Views: 63.9ms | ActiveRecord: 1.7ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 12:26:37 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 12:26:37 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 12:26:37 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 12:26:37 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 12:26:37 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 12:26:37 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/products/1/edit" for 127.0.0.1 at 2012-10-13 12:26:38 +0200 Processing by ProductsController#edit as HTML Parameters: {"id"=>"1"} Product Load (0.3ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT 1 [["id", "1"]]  (0.2ms) SELECT "categories".id FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 1  (0.3ms) SELECT COUNT(*) FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 1 Category Load (0.2ms) SELECT "categories".* FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 1 Rendered products/_form.html.erb (10.6ms) Rendered products/edit.html.erb within layouts/application (12.2ms) Completed 200 OK in 19ms (Views: 16.0ms | ActiveRecord: 1.0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 12:26:39 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 12:26:39 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 12:26:39 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 12:26:39 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 12:26:39 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 12:26:39 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/products/2/edit" for 127.0.0.1 at 2012-10-13 12:26:46 +0200 Processing by ProductsController#edit as HTML Parameters: {"id"=>"2"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT 1 [["id", "2"]]  (0.2ms) SELECT "categories".id FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 2  (0.2ms) SELECT COUNT(*) FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 2 Rendered products/_form.html.erb (6.6ms) Rendered products/edit.html.erb within layouts/application (7.4ms) Completed 200 OK in 14ms (Views: 12.0ms | ActiveRecord: 0.5ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 12:26:46 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 12:26:46 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 12:26:46 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 12:26:46 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 12:26:46 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 12:26:46 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/categories" for 127.0.0.1 at 2012-10-13 12:26:52 +0200 Processing by CategoriesController#index as HTML Category Load (0.2ms) SELECT "categories".* FROM "categories" Rendered categories/index.html.erb within layouts/application (4.1ms) Completed 200 OK in 12ms (Views: 10.4ms | ActiveRecord: 0.2ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 12:26:52 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 12:26:52 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 12:26:52 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 12:26:52 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 12:26:52 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 12:26:52 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/categories/1/edit" for 127.0.0.1 at 2012-10-13 12:26:53 +0200 Processing by CategoriesController#edit as HTML Parameters: {"id"=>"1"} Category Load (0.3ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = ? LIMIT 1 [["id", "1"]] Rendered categories/_form.html.erb (5.9ms) Rendered categories/edit.html.erb within layouts/application (7.4ms) Completed 200 OK in 15ms (Views: 12.5ms | ActiveRecord: 0.3ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 12:26:53 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 12:26:53 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 12:26:53 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 12:26:53 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 12:26:53 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 12:26:53 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/categories/1/edit" for 127.0.0.1 at 2012-10-13 12:27:18 +0200 Processing by CategoriesController#edit as HTML Parameters: {"id"=>"1"} Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = ? LIMIT 1 [["id", "1"]] Rendered categories/_form.html.erb (5.9ms) Rendered categories/edit.html.erb within layouts/application (6.8ms) Completed 200 OK in 14ms (Views: 12.9ms | ActiveRecord: 0.1ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 12:27:19 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 12:27:19 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 12:27:19 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 12:27:19 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 12:27:19 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 12:27:19 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/categories/2/edit" for 127.0.0.1 at 2012-10-13 12:30:18 +0200 Processing by CategoriesController#edit as HTML Parameters: {"id"=>"2"} Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = ? LIMIT 1 [["id", "2"]] Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = 1 LIMIT 1 Rendered categories/_form.html.erb (11.6ms) Rendered categories/edit.html.erb within layouts/application (12.4ms) Completed 200 OK in 21ms (Views: 18.9ms | ActiveRecord: 0.3ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 12:30:18 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 12:30:18 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 12:30:18 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 12:30:18 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 12:30:18 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 12:30:18 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/products" for 127.0.0.1 at 2012-10-13 12:32:03 +0200 Processing by ProductsController#index as HTML Product Load (0.2ms) SELECT "products".* FROM "products"   (0.2ms) SELECT "categories".id FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 1  (0.1ms) SELECT "categories".id FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 2 Rendered products/index.html.erb within layouts/application (4.1ms) Completed 200 OK in 9ms (Views: 7.7ms | ActiveRecord: 0.5ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 12:32:04 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 12:32:04 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 12:32:04 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 12:32:04 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 12:32:04 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 12:32:04 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/products/1/edit" for 127.0.0.1 at 2012-10-13 12:32:07 +0200 Processing by ProductsController#edit as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT 1 [["id", "1"]]  (0.2ms) SELECT "categories".id FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 1  (0.1ms) SELECT COUNT(*) FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 1 Category Load (0.2ms) SELECT "categories".* FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 1 Rendered products/_form.html.erb (8.9ms) Rendered products/edit.html.erb within layouts/application (9.8ms) Completed 200 OK in 15ms (Views: 13.7ms | ActiveRecord: 0.6ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 12:32:07 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 12:32:07 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 12:32:07 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 12:32:07 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 12:32:07 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 12:32:07 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/products/1/edit" for 127.0.0.1 at 2012-10-13 12:35:21 +0200 Processing by ProductsController#edit as HTML Parameters: {"id"=>"1"} Product Load (0.1ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT 1 [["id", "1"]]  (0.1ms) SELECT "categories".id FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 1  (0.1ms) SELECT COUNT(*) FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 1 Category Load (0.1ms) SELECT "categories".* FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 1 Rendered products/_form.html.erb (5.2ms) Rendered products/edit.html.erb within layouts/application (5.9ms) Completed 200 OK in 24ms (Views: 23.1ms | ActiveRecord: 0.5ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 12:35:21 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 12:35:21 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 12:35:21 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 12:35:21 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 12:35:21 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 12:35:21 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/category/1/edit" for 127.0.0.1 at 2012-10-13 12:36:20 +0200 ActionController::RoutingError (No route matches [GET] "/category/1/edit"): actionpack (3.2.8) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.8) lib/rails/rack/logger.rb:26:in `call_app' railties (3.2.8) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.1) lib/rack/methodoverride.rb:21:in `call' rack (1.4.1) lib/rack/runtime.rb:17:in `call' activesupport (3.2.8) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.1) lib/rack/lock.rb:15:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/static.rb:62:in `call' railties (3.2.8) lib/rails/engine.rb:479:in `call' railties (3.2.8) lib/rails/application.rb:223:in `call' rack (1.4.1) lib/rack/content_length.rb:14:in `call' railties (3.2.8) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.1) lib/rack/handler/webrick.rb:59:in `service' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /Users/mirrec/.rvm/rubies/ruby-1.9.3-head/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (0.6ms) Started GET "/categories/1/edit" for 127.0.0.1 at 2012-10-13 12:36:24 +0200 Processing by CategoriesController#edit as HTML Parameters: {"id"=>"1"} Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = ? LIMIT 1 [["id", "1"]] Rendered categories/_form.html.erb (3.6ms) Rendered categories/edit.html.erb within layouts/application (4.3ms) Completed 200 OK in 42ms (Views: 40.9ms | ActiveRecord: 0.1ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 12:36:24 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 12:36:24 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 12:36:24 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 12:36:24 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 12:36:24 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 12:36:24 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/categories" for 127.0.0.1 at 2012-10-13 12:36:29 +0200 Processing by CategoriesController#index as HTML Category Load (0.2ms) SELECT "categories".* FROM "categories"  Rendered categories/index.html.erb within layouts/application (2.9ms) Completed 200 OK in 14ms (Views: 12.8ms | ActiveRecord: 0.2ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 12:36:29 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 12:36:29 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 12:36:29 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 12:36:29 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 12:36:29 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 12:36:29 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/categories/2/edit" for 127.0.0.1 at 2012-10-13 12:36:31 +0200 Processing by CategoriesController#edit as HTML Parameters: {"id"=>"2"} Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = ? LIMIT 1 [["id", "2"]] Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = 1 LIMIT 1 Rendered categories/_form.html.erb (5.8ms) Rendered categories/edit.html.erb within layouts/application (6.7ms) Completed 200 OK in 14ms (Views: 12.5ms | ActiveRecord: 0.3ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 12:36:31 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 12:36:31 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 12:36:31 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 12:36:31 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 12:36:31 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 12:36:31 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Connecting to database specified by database.yml Connecting to database specified by database.yml  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations"  Migrating to CreateCategories (20121012100526) Migrating to CreateProducts (20121012235444) Migrating to CreateProductHasCategories (20121012235509) Migrating to CreateItems (20121013214019)  (0.0ms) select sqlite_version(*)  (0.0ms) begin transaction  (0.4ms) CREATE TABLE "items" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar(255), "category_id" integer, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)  (24.0ms) INSERT INTO "schema_migrations" ("version") VALUES ('20121013214019')  (12.1ms) commit transaction  (0.3ms) select sqlite_version(*)  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations"  (0.0ms) PRAGMA index_list("categories")  (0.0ms) PRAGMA index_list("items")  (0.0ms) PRAGMA index_list("product_has_categories")  (0.0ms) PRAGMA index_list("products") Started GET "/" for 127.0.0.1 at 2012-10-13 23:46:33 +0200 Connecting to database specified by database.yml Processing by CategoriesController#index as HTML Category Load (0.1ms) SELECT "categories".* FROM "categories"  Rendered categories/index.html.erb within layouts/application (5.5ms) Compiled token-input-facebook.css (0ms) (pid 3443) Compiled application.css (49ms) (pid 3443) Compiled jquery.js (4ms) (pid 3443) Compiled jquery_ujs.js (0ms) (pid 3443) Compiled jquery.tokeninput.js (0ms) (pid 3443) Compiled application.js (170ms) (pid 3443) Completed 200 OK in 505ms (Views: 454.5ms | ActiveRecord: 1.7ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 23:46:34 +0200 Served asset /application.css - 200 OK (3ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 23:46:34 +0200 Served asset /token-input-facebook.css - 200 OK (1ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 23:46:34 +0200 Served asset /jquery.js - 200 OK (3ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 23:46:34 +0200 Served asset /jquery_ujs.js - 200 OK (1ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 23:46:34 +0200 Served asset /jquery.tokeninput.js - 200 OK (1ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 23:46:34 +0200 Served asset /application.js - 200 OK (4ms) Started GET "/items" for 127.0.0.1 at 2012-10-13 23:46:39 +0200 Processing by ItemsController#index as HTML Item Load (0.1ms) SELECT "items".* FROM "items" Rendered items/index.html.erb within layouts/application (0.9ms) Completed 200 OK in 7ms (Views: 5.1ms | ActiveRecord: 0.3ms) Started GET "/items/new" for 127.0.0.1 at 2012-10-13 23:46:40 +0200 Processing by ItemsController#new as HTML Rendered items/_form.html.erb (166.2ms) Rendered items/new.html.erb within layouts/application (194.1ms) Completed 500 Internal Server Error in 200ms ActionView::Template::Error (No input found for token): 1: <%= simple_form_for(@item) do |f| %> 2: <%= f.input :name %> 3: <%= f.input :category_ids, :as => :token, :token_url => token_categories_path %> 4: <%= f.submit %> 5: <% end %> 6: <%= link_to "Listing", items_path %> app/views/items/_form.html.erb:3:in `block in _app_views_items__form_html_erb___1811428527127889752_2154073180' app/views/items/_form.html.erb:1:in `_app_views_items__form_html_erb___1811428527127889752_2154073180' app/views/items/new.html.erb:2:in `_app_views_items_new_html_erb__221201119574192906_2163567060' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.3ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (0.9ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (8.8ms) Started GET "/items/new" for 127.0.0.1 at 2012-10-13 23:56:41 +0200 Connecting to database specified by database.yml Processing by ItemsController#new as HTML Rendered items/_form.html.erb (46.2ms) Rendered items/new.html.erb within layouts/application (50.4ms) Completed 500 Internal Server Error in 96ms ActionView::Template::Error (undefined method `category_ids' for #): 1: <%= simple_form_for(@item) do |f| %> 2: <%= f.input :name %> 3: <%= f.input :category_ids, :as => :token, :token_url => token_categories_path %> 4: <%= f.submit %> 5: <% end %> 6: <%= link_to "Listing", items_path %> app/views/items/_form.html.erb:3:in `block in _app_views_items__form_html_erb___3681320351918761277_2156589100' app/views/items/_form.html.erb:1:in `_app_views_items__form_html_erb___3681320351918761277_2156589100' app/views/items/new.html.erb:2:in `_app_views_items_new_html_erb__2608424291861506679_2157462880' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.3ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (0.9ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (7.3ms) Started GET "/items/new" for 127.0.0.1 at 2012-10-13 23:57:48 +0200 Processing by ItemsController#new as HTML Rendered items/_form.html.erb (56.4ms) Rendered items/new.html.erb within layouts/application (57.0ms) Completed 200 OK in 70ms (Views: 69.4ms | ActiveRecord: 0.0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 23:57:48 +0200 Served asset /application.css - 304 Not Modified (3ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 23:57:48 +0200 Served asset /token-input-facebook.css - 304 Not Modified (2ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 23:57:48 +0200 Served asset /jquery.js - 304 Not Modified (5ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 23:57:48 +0200 Served asset /jquery_ujs.js - 304 Not Modified (3ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 23:57:48 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (1ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 23:57:48 +0200 Served asset /application.js - 304 Not Modified (6ms) Started GET "/categories/token?q=n" for 127.0.0.1 at 2012-10-13 23:57:53 +0200 Processing by CategoriesController#token as JSON Parameters: {"q"=>"n"} Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '%n%') Completed 200 OK in 21ms (Views: 0.3ms | ActiveRecord: 0.3ms) Started POST "/items" for 127.0.0.1 at 2012-10-13 23:57:57 +0200 Processing by ItemsController#create as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"lo/p4qjRmxTh+URMUOGsKBCYsotN3l+4gMF97lGTIFQ=", "item"=>{"name"=>"polozka", "category_id"=>"1"}, "commit"=>"Create Item"}  (0.1ms) begin transaction SQL (43.1ms) INSERT INTO "items" ("category_id", "created_at", "name", "updated_at") VALUES (?, ?, ?, ?) [["category_id", 1], ["created_at", Sat, 13 Oct 2012 21:57:57 UTC +00:00], ["name", "polozka"], ["updated_at", Sat, 13 Oct 2012 21:57:57 UTC +00:00]]  (1.0ms) commit transaction Redirected to http://localhost:3000/items Completed 302 Found in 47ms (ActiveRecord: 44.1ms) Started GET "/items" for 127.0.0.1 at 2012-10-13 23:57:57 +0200 Processing by ItemsController#index as HTML Item Load (0.2ms) SELECT "items".* FROM "items"  Rendered items/index.html.erb within layouts/application (1.9ms) Completed 500 Internal Server Error in 5ms ActionView::Template::Error (undefined method `category_ids' for #): 3:
    4: <% @items.each do |item| %> 5: <%= content_tag_for(:li, item) do %> 6: <%= item.name %> - <%= item.category_ids.inspect %> <%= link_to "Edit", edit_item_path(item), :id => dom_id(item) %> 7: <% end %> 8: <% end %> 9:
app/views/items/index.html.erb:6:in `block (2 levels) in _app_views_items_index_html_erb___1933584429465425982_2157446880' app/views/items/index.html.erb:5:in `block in _app_views_items_index_html_erb___1933584429465425982_2157446880' app/views/items/index.html.erb:4:in `each' app/views/items/index.html.erb:4:in `_app_views_items_index_html_erb___1933584429465425982_2157446880' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.1ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (0.8ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (6.6ms) Started GET "/items" for 127.0.0.1 at 2012-10-13 23:58:27 +0200 Processing by ItemsController#index as HTML Item Load (0.1ms) SELECT "items".* FROM "items" Rendered items/index.html.erb within layouts/application (1.4ms) Completed 200 OK in 6ms (Views: 5.4ms | ActiveRecord: 0.1ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 23:58:27 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 23:58:27 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 23:58:27 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 23:58:27 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 23:58:27 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 23:58:27 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/items/1/edit" for 127.0.0.1 at 2012-10-13 23:58:28 +0200 Processing by ItemsController#edit as HTML Parameters: {"id"=>"1"} Item Load (0.2ms) SELECT "items".* FROM "items" WHERE "items"."id" = ? LIMIT 1 [["id", "1"]] Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = 1 LIMIT 1 Rendered items/_form.html.erb (32.3ms) Rendered items/edit.html.erb within layouts/application (33.2ms) Completed 200 OK in 39ms (Views: 36.8ms | ActiveRecord: 0.4ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 23:58:28 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 23:58:28 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 23:58:28 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 23:58:28 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 23:58:28 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 23:58:28 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/items" for 127.0.0.1 at 2012-10-13 23:58:30 +0200 Processing by ItemsController#index as HTML Item Load (0.1ms) SELECT "items".* FROM "items"  Rendered items/index.html.erb within layouts/application (0.7ms) Completed 200 OK in 5ms (Views: 4.4ms | ActiveRecord: 0.1ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2012-10-13 23:58:30 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2012-10-13 23:58:30 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2012-10-13 23:58:30 +0200 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2012-10-13 23:58:30 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2012-10-13 23:58:30 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2012-10-13 23:58:30 +0200 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Connecting to database specified by database.yml Connecting to database specified by database.yml Started GET "/" for 127.0.0.1 at 2015-10-26 17:08:05 +0100 Connecting to database specified by database.yml Processing by CategoriesController#index as HTML Category Load (2.0ms) SELECT "categories".* FROM "categories"  Rendered categories/index.html.erb within layouts/application (4.6ms) Compiled token-input-facebook.css (0ms) (pid 34408) Compiled application.css (23ms) (pid 34408) Compiled jquery.js (3ms) (pid 34408) Compiled jquery_ujs.js (0ms) (pid 34408) Compiled jquery.tokeninput.js (0ms) (pid 34408) Compiled application.js (22ms) (pid 34408) Completed 200 OK in 143ms (Views: 99.5ms | ActiveRecord: 4.5ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2015-10-26 17:08:06 +0100 Served asset /application.css - 200 OK (5ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2015-10-26 17:08:06 +0100 Served asset /token-input-facebook.css - 200 OK (2ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2015-10-26 17:08:06 +0100 Served asset /jquery.js - 200 OK (3ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2015-10-26 17:08:06 +0100 Served asset /jquery_ujs.js - 200 OK (5ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2015-10-26 17:08:06 +0100 Served asset /jquery.tokeninput.js - 200 OK (2ms) Started GET "/categories/1/edit" for 127.0.0.1 at 2015-10-26 17:08:11 +0100 Processing by CategoriesController#edit as HTML Parameters: {"id"=>"1"} Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = ? LIMIT 1 [["id", "1"]] Rendered categories/_form.html.erb (104.8ms) Rendered categories/edit.html.erb within layouts/application (108.3ms) Completed 200 OK in 113ms (Views: 111.6ms | ActiveRecord: 0.2ms) Started GET "/categories/2/edit" for 127.0.0.1 at 2015-10-26 17:08:15 +0100 Processing by CategoriesController#edit as HTML Parameters: {"id"=>"2"} Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = ? LIMIT 1 [["id", "2"]] Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = 1 LIMIT 1 Rendered categories/_form.html.erb (30.3ms) Rendered categories/edit.html.erb within layouts/application (30.9ms) Completed 200 OK in 35ms (Views: 33.9ms | ActiveRecord: 0.3ms) Started GET "/items" for 127.0.0.1 at 2015-10-26 17:08:23 +0100 Processing by ItemsController#index as HTML Item Load (0.5ms) SELECT "items".* FROM "items"  Rendered items/index.html.erb within layouts/application (2.0ms) Completed 200 OK in 13ms (Views: 6.2ms | ActiveRecord: 0.8ms) Started GET "/items/1/edit" for 127.0.0.1 at 2015-10-26 17:08:24 +0100 Processing by ItemsController#edit as HTML Parameters: {"id"=>"1"} Item Load (0.3ms) SELECT "items".* FROM "items" WHERE "items"."id" = ? LIMIT 1 [["id", "1"]] Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = 1 LIMIT 1 Rendered items/_form.html.erb (51.8ms) Rendered items/edit.html.erb within layouts/application (53.4ms) Completed 200 OK in 61ms (Views: 59.6ms | ActiveRecord: 0.4ms) Started GET "/categories/token?q=no" for 127.0.0.1 at 2015-10-26 17:08:31 +0100 Processing by CategoriesController#token as JSON Parameters: {"q"=>"no"} Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '%no%') Completed 200 OK in 2ms (Views: 0.2ms | ActiveRecord: 0.2ms) Started GET "/categories/token?q=nov" for 127.0.0.1 at 2015-10-26 17:08:31 +0100 Processing by CategoriesController#token as JSON Parameters: {"q"=>"nov"} Category Load (0.3ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '%nov%') Completed 200 OK in 1ms (Views: 0.1ms | ActiveRecord: 0.3ms) Started PUT "/items/1" for 127.0.0.1 at 2015-10-26 17:08:34 +0100 Processing by ItemsController#update as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"c/RSjplDoV6tWDKSy/iTKrU5aUpXTufbeq8HXnXhMU0=", "item"=>{"name"=>"polozka", "category_id"=>"1"}, "commit"=>"Update Item", "id"=>"1"} Item Load (0.1ms) SELECT "items".* FROM "items" WHERE "items"."id" = ? LIMIT 1 [["id", "1"]]  (0.1ms) begin transaction  (0.1ms) commit transaction Redirected to http://localhost:3000/items Completed 302 Found in 3ms (ActiveRecord: 0.2ms) Started GET "/items" for 127.0.0.1 at 2015-10-26 17:08:34 +0100 Processing by ItemsController#index as HTML Item Load (0.1ms) SELECT "items".* FROM "items"  Rendered items/index.html.erb within layouts/application (0.8ms) Completed 200 OK in 5ms (Views: 3.9ms | ActiveRecord: 0.1ms) Started GET "/products" for 127.0.0.1 at 2015-10-26 17:08:38 +0100 Processing by ProductsController#index as HTML Product Load (0.4ms) SELECT "products".* FROM "products"  (0.1ms) SELECT "categories".id FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 1  (0.1ms) SELECT "categories".id FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 2 Rendered products/index.html.erb within layouts/application (52.8ms) Completed 200 OK in 63ms (Views: 56.2ms | ActiveRecord: 1.0ms) Started GET "/products/1/edit" for 127.0.0.1 at 2015-10-26 17:08:40 +0100 Processing by ProductsController#edit as HTML Parameters: {"id"=>"1"} Product Load (0.2ms) SELECT "products".* FROM "products" WHERE "products"."id" = ? LIMIT 1 [["id", "1"]]  (0.2ms) SELECT "categories".id FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 1  (0.1ms) SELECT COUNT(*) FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 1 Category Load (0.1ms) SELECT "categories".* FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 1 Rendered products/_form.html.erb (6.3ms) Rendered products/edit.html.erb within layouts/application (7.6ms) Completed 200 OK in 15ms (Views: 13.4ms | ActiveRecord: 0.6ms) Started GET "/categories/token?q=n" for 127.0.0.1 at 2015-10-26 17:10:22 +0100 Processing by CategoriesController#token as JSON Parameters: {"q"=>"n"} Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '%n%') Completed 200 OK in 2ms (Views: 0.3ms | ActiveRecord: 0.2ms) Started GET "/categories/token?q=no" for 127.0.0.1 at 2015-10-26 17:10:22 +0100 Processing by CategoriesController#token as JSON Parameters: {"q"=>"no"} Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '%no%') Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) Started GET "/categories/token?q=s" for 127.0.0.1 at 2015-10-26 17:10:25 +0100 Processing by CategoriesController#token as JSON Parameters: {"q"=>"s"} Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '%s%') Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) Started GET "/categories/token?q=su" for 127.0.0.1 at 2015-10-26 17:10:25 +0100 Processing by CategoriesController#token as JSON Parameters: {"q"=>"su"} Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '%su%') Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) Started GET "/products" for 127.0.0.1 at 2015-10-26 17:10:29 +0100 Processing by ProductsController#index as HTML Product Load (0.2ms) SELECT "products".* FROM "products"   (0.2ms) SELECT "categories".id FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 1  (0.2ms) SELECT "categories".id FROM "categories" INNER JOIN "product_has_categories" ON "categories"."id" = "product_has_categories"."category_id" WHERE "product_has_categories"."product_id" = 2 Rendered products/index.html.erb within layouts/application (3.9ms) Completed 200 OK in 10ms (Views: 8.7ms | ActiveRecord: 0.5ms) Started GET "/items" for 127.0.0.1 at 2015-10-26 17:10:31 +0100 Processing by ItemsController#index as HTML Item Load (0.2ms) SELECT "items".* FROM "items" Rendered items/index.html.erb within layouts/application (1.1ms) Completed 200 OK in 8ms (Views: 6.9ms | ActiveRecord: 0.2ms) Started GET "/items/1/edit" for 127.0.0.1 at 2015-10-26 17:10:33 +0100 Processing by ItemsController#edit as HTML Parameters: {"id"=>"1"} Item Load (0.1ms) SELECT "items".* FROM "items" WHERE "items"."id" = ? LIMIT 1 [["id", "1"]] Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = 1 LIMIT 1 Rendered items/_form.html.erb (6.6ms) Rendered items/edit.html.erb within layouts/application (7.2ms) Completed 200 OK in 11ms (Views: 10.0ms | ActiveRecord: 0.3ms) Started GET "/categories/token?q=su" for 127.0.0.1 at 2015-10-26 17:10:37 +0100 Processing by CategoriesController#token as JSON Parameters: {"q"=>"su"} Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '%su%') Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) Started GET "/categories/token?q=sub" for 127.0.0.1 at 2015-10-26 17:10:37 +0100 Processing by CategoriesController#token as JSON Parameters: {"q"=>"sub"} Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '%sub%') Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.2ms) Started GET "/categories/token?q=s" for 127.0.0.1 at 2015-10-26 17:10:38 +0100 Processing by CategoriesController#token as JSON Parameters: {"q"=>"s"} Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '%s%') Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.2ms) Started GET "/categories" for 127.0.0.1 at 2015-10-26 17:10:42 +0100 Processing by CategoriesController#index as HTML Category Load (0.2ms) SELECT "categories".* FROM "categories" Rendered categories/index.html.erb within layouts/application (2.7ms) Completed 200 OK in 11ms (Views: 10.2ms | ActiveRecord: 0.2ms) Started GET "/categories/2/edit" for 127.0.0.1 at 2015-10-26 17:10:44 +0100 Processing by CategoriesController#edit as HTML Parameters: {"id"=>"2"} Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = ? LIMIT 1 [["id", "2"]] Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = 1 LIMIT 1 Rendered categories/_form.html.erb (3.3ms) Rendered categories/edit.html.erb within layouts/application (3.7ms) Completed 200 OK in 8ms (Views: 6.7ms | ActiveRecord: 0.2ms) Started GET "/categories/token?q=su" for 127.0.0.1 at 2015-10-26 17:10:48 +0100 Processing by CategoriesController#token as JSON Parameters: {"q"=>"su"} Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '%su%') Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.2ms) Started GET "/categories/token?q=sub" for 127.0.0.1 at 2015-10-26 17:10:49 +0100 Processing by CategoriesController#token as JSON Parameters: {"q"=>"sub"} Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE (categories.name like '%sub%') Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.2ms) Started GET "/" for 127.0.0.1 at 2015-10-26 17:43:27 +0100 Processing by CategoriesController#index as HTML Category Load (0.3ms) SELECT "categories".* FROM "categories"  Rendered categories/index.html.erb within layouts/application (2.2ms) Completed 200 OK in 16ms (Views: 7.3ms | ActiveRecord: 0.8ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2015-10-26 17:43:27 +0100 Served asset /token-input-facebook.css - 200 OK (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2015-10-26 17:43:27 +0100 Served asset /jquery.js - 200 OK (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2015-10-26 17:43:27 +0100 Served asset /jquery_ujs.js - 200 OK (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2015-10-26 17:43:27 +0100 Compiled jquery.js (5ms) (pid 34408) Compiled jquery_ujs.js (0ms) (pid 34408) Compiled jquery.tokeninput.js (0ms) (pid 34408) Compiled application.js (61ms) (pid 34408) Served asset /application.js - 200 OK (68ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2015-10-26 17:43:27 +0100 Served asset /jquery.tokeninput.js - 200 OK (0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2015-10-26 17:43:27 +0100 Served asset /application.css - 200 OK (0ms) Started GET "/categories/1/edit" for 127.0.0.1 at 2015-10-26 17:43:29 +0100 Processing by CategoriesController#edit as HTML Parameters: {"id"=>"1"} Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = ? LIMIT 1 [["id", "1"]] Rendered categories/_form.html.erb (3.2ms) Rendered categories/edit.html.erb within layouts/application (4.0ms) Completed 200 OK in 10ms (Views: 8.2ms | ActiveRecord: 0.2ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2015-10-26 17:43:29 +0100 Served asset /application.css - 200 OK (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2015-10-26 17:43:29 +0100 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2015-10-26 17:43:29 +0100 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2015-10-26 17:43:29 +0100 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2015-10-26 17:43:29 +0100 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2015-10-26 17:43:29 +0100 Served asset /application.js - 200 OK (0ms) Started GET "/categories" for 127.0.0.1 at 2015-10-26 17:43:30 +0100 Processing by CategoriesController#index as HTML Category Load (0.2ms) SELECT "categories".* FROM "categories"  Rendered categories/index.html.erb within layouts/application (1.6ms) Completed 200 OK in 6ms (Views: 5.1ms | ActiveRecord: 0.2ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2015-10-26 17:43:30 +0100 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2015-10-26 17:43:30 +0100 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2015-10-26 17:43:30 +0100 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2015-10-26 17:43:30 +0100 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2015-10-26 17:43:30 +0100 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2015-10-26 17:43:31 +0100 Served asset /application.js - 304 Not Modified (0ms) Started GET "/categories/2/edit" for 127.0.0.1 at 2015-10-26 17:43:31 +0100 Processing by CategoriesController#edit as HTML Parameters: {"id"=>"2"} Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = ? LIMIT 1 [["id", "2"]] Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = 1 LIMIT 1 Rendered categories/_form.html.erb (16.0ms) Rendered categories/edit.html.erb within layouts/application (16.9ms) Completed 500 Internal Server Error in 20ms ActionView::Template::Error (undefined method `token_json' for #): 5:

6:

7: <%= f.label :parent_id %> 8: <%= f.token_field :parent_id, :model => :category, :token_url => token_categories_path %> 9:

10: <%= f.submit %> 11: <% end %> app/views/categories/_form.html.erb:8:in `block in _app_views_categories__form_html_erb__1115790777164960122_70364913326800' app/views/categories/_form.html.erb:1:in `_app_views_categories__form_html_erb__1115790777164960122_70364913326800' app/views/categories/edit.html.erb:2:in `_app_views_categories_edit_html_erb__736796483781219419_70364884472540' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.7ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.1ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (11.1ms) Started GET "/categories/2/edit" for 127.0.0.1 at 2015-10-26 17:43:44 +0100 Connecting to database specified by database.yml Processing by CategoriesController#edit as HTML Parameters: {"id"=>"2"} Category Load (0.3ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = ? LIMIT 1 [["id", "2"]] Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = 1 LIMIT 1 Rendered categories/_form.html.erb (132.0ms) Rendered categories/edit.html.erb within layouts/application (138.0ms) Compiled token-input-facebook.css (0ms) (pid 35072) Compiled application.css (4ms) (pid 35072) Completed 200 OK in 249ms (Views: 195.6ms | ActiveRecord: 22.9ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2015-10-26 17:43:45 +0100 Served asset /application.css - 304 Not Modified (4ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2015-10-26 17:43:45 +0100 Served asset /token-input-facebook.css - 304 Not Modified (2ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2015-10-26 17:43:45 +0100 Served asset /jquery.js - 304 Not Modified (2ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2015-10-26 17:43:45 +0100 Served asset /jquery.tokeninput.js - 304 Not Modified (2ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2015-10-26 17:43:45 +0100 Served asset /jquery_ujs.js - 304 Not Modified (1ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2015-10-26 17:43:45 +0100 Served asset /application.js - 304 Not Modified (6ms) Started GET "/categories/2/edit" for 127.0.0.1 at 2015-10-26 17:44:52 +0100 Processing by CategoriesController#edit as HTML Parameters: {"id"=>"2"} Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = ? LIMIT 1 [["id", "2"]] Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = 1 LIMIT 1 Rendered categories/_form.html.erb (9.8ms) Rendered categories/edit.html.erb within layouts/application (10.4ms) Completed 200 OK in 15ms (Views: 14.3ms | ActiveRecord: 0.3ms) Started GET "/categories/2/edit" for 127.0.0.1 at 2015-10-26 17:44:53 +0100 Processing by CategoriesController#edit as HTML Parameters: {"id"=>"2"} Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = ? LIMIT 1 [["id", "2"]] Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = 1 LIMIT 1 Rendered categories/_form.html.erb (4.7ms) Rendered categories/edit.html.erb within layouts/application (5.4ms) Completed 200 OK in 10ms (Views: 9.2ms | ActiveRecord: 0.3ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2015-10-26 17:44:53 +0100 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2015-10-26 17:44:53 +0100 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2015-10-26 17:44:53 +0100 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2015-10-26 17:44:53 +0100 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2015-10-26 17:44:53 +0100 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2015-10-26 17:44:53 +0100 Served asset /application.js - 304 Not Modified (0ms) Started GET "/categories/2/edit" for 127.0.0.1 at 2015-10-26 17:44:59 +0100 Connecting to database specified by database.yml Processing by CategoriesController#edit as HTML Parameters: {"id"=>"2"} Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = ? LIMIT 1 [["id", "2"]] Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = 1 LIMIT 1 Rendered categories/_form.html.erb (139.3ms) Rendered categories/edit.html.erb within layouts/application (144.6ms) Completed 500 Internal Server Error in 201ms ActionView::Template::Error (undefined method `map' for "[{\"id\":1,\"name\":\"nova\"}]":String): 5:

6:

7: <%= f.label :parent_id %> 8: <%= f.token_field :parent_id, :model => :category, :token_url => token_categories_path %> 9:

10: <%= f.submit %> 11: <% end %> app/views/categories/_form.html.erb:8:in `block in _app_views_categories__form_html_erb___3309664555696985273_70226908886980' app/views/categories/_form.html.erb:1:in `_app_views_categories__form_html_erb___3309664555696985273_70226908886980' app/views/categories/edit.html.erb:2:in `_app_views_categories_edit_html_erb___1827947886513484723_70226908630160' Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.7ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.1ms) Rendered /Users/mirrec/.rvm/gems/ruby-1.9.3-head@wowbox/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (8.2ms) Started GET "/categories/2/edit" for 127.0.0.1 at 2015-10-26 17:45:23 +0100 Connecting to database specified by database.yml Processing by CategoriesController#edit as HTML Parameters: {"id"=>"2"} Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = ? LIMIT 1 [["id", "2"]] Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = 1 LIMIT 1 Rendered categories/_form.html.erb (110.7ms) Rendered categories/edit.html.erb within layouts/application (116.3ms) Completed 200 OK in 205ms (Views: 159.0ms | ActiveRecord: 19.3ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2015-10-26 17:45:24 +0100 Served asset /application.css - 304 Not Modified (7ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2015-10-26 17:45:24 +0100 Served asset /token-input-facebook.css - 304 Not Modified (3ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:24 +0100 Served asset /jquery.js - 304 Not Modified (3ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:24 +0100 Served asset /jquery_ujs.js - 304 Not Modified (3ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:24 +0100 Served asset /jquery.tokeninput.js - 304 Not Modified (2ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:24 +0100 Served asset /application.js - 304 Not Modified (42ms) Started PUT "/categories/2" for 127.0.0.1 at 2015-10-26 17:45:26 +0100 Processing by CategoriesController#update as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"qUvOWnNKVcs+tuQzWQx3KoA8LNUX7dIY9OY+CMWvRIg=", "category"=>{"name"=>"sub", "parent_id"=>"1"}, "commit"=>"Update Category", "id"=>"2"} Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = ? LIMIT 1 [["id", "2"]]  (0.1ms) begin transaction  (0.0ms) commit transaction Redirected to http://localhost:3000/categories Completed 302 Found in 4ms (ActiveRecord: 0.2ms) Started GET "/categories" for 127.0.0.1 at 2015-10-26 17:45:26 +0100 Processing by CategoriesController#index as HTML Category Load (0.2ms) SELECT "categories".* FROM "categories" Rendered categories/index.html.erb within layouts/application (2.8ms) Completed 200 OK in 8ms (Views: 6.2ms | ActiveRecord: 0.2ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2015-10-26 17:45:26 +0100 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:26 +0100 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:26 +0100 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:26 +0100 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2015-10-26 17:45:26 +0100 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:26 +0100 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/categories/3/edit" for 127.0.0.1 at 2015-10-26 17:45:28 +0100 Processing by CategoriesController#edit as HTML Parameters: {"id"=>"3"} Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = ? LIMIT 1 [["id", "3"]] Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = 2 LIMIT 1 Rendered categories/_form.html.erb (4.5ms) Rendered categories/edit.html.erb within layouts/application (5.4ms) Completed 200 OK in 11ms (Views: 9.4ms | ActiveRecord: 0.3ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2015-10-26 17:45:28 +0100 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:28 +0100 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2015-10-26 17:45:28 +0100 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:28 +0100 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:28 +0100 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:28 +0100 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started PUT "/categories/3" for 127.0.0.1 at 2015-10-26 17:45:29 +0100 Processing by CategoriesController#update as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"qUvOWnNKVcs+tuQzWQx3KoA8LNUX7dIY9OY+CMWvRIg=", "category"=>{"name"=>"sub sub", "parent_id"=>"2"}, "commit"=>"Update Category", "id"=>"3"} Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = ? LIMIT 1 [["id", "3"]]  (0.1ms) begin transaction  (0.0ms) commit transaction Redirected to http://localhost:3000/categories Completed 302 Found in 3ms (ActiveRecord: 0.2ms) Started GET "/categories" for 127.0.0.1 at 2015-10-26 17:45:29 +0100 Processing by CategoriesController#index as HTML Category Load (0.1ms) SELECT "categories".* FROM "categories" Rendered categories/index.html.erb within layouts/application (1.4ms) Completed 200 OK in 6ms (Views: 4.6ms | ActiveRecord: 0.1ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2015-10-26 17:45:29 +0100 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:29 +0100 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2015-10-26 17:45:29 +0100 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:29 +0100 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:29 +0100 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:29 +0100 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/categories/3/edit" for 127.0.0.1 at 2015-10-26 17:45:30 +0100 Processing by CategoriesController#edit as HTML Parameters: {"id"=>"3"} Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = ? LIMIT 1 [["id", "3"]] Category Load (0.1ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = 2 LIMIT 1 Rendered categories/_form.html.erb (3.4ms) Rendered categories/edit.html.erb within layouts/application (4.1ms) Completed 200 OK in 8ms (Views: 7.3ms | ActiveRecord: 0.3ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2015-10-26 17:45:30 +0100 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2015-10-26 17:45:30 +0100 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:30 +0100 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:30 +0100 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:30 +0100 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:30 +0100 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/categories" for 127.0.0.1 at 2015-10-26 17:45:44 +0100 Processing by CategoriesController#index as HTML Category Load (0.2ms) SELECT "categories".* FROM "categories"  Rendered categories/index.html.erb within layouts/application (2.7ms) Completed 200 OK in 9ms (Views: 7.5ms | ActiveRecord: 0.2ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2015-10-26 17:45:45 +0100 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2015-10-26 17:45:45 +0100 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:45 +0100 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:45 +0100 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:45 +0100 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:45 +0100 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/items" for 127.0.0.1 at 2015-10-26 17:45:48 +0100 Processing by ItemsController#index as HTML Item Load (0.1ms) SELECT "items".* FROM "items" Rendered items/index.html.erb within layouts/application (1.9ms) Completed 200 OK in 13ms (Views: 5.9ms | ActiveRecord: 0.4ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2015-10-26 17:45:48 +0100 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2015-10-26 17:45:48 +0100 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:48 +0100 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:48 +0100 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:48 +0100 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:48 +0100 Served asset /application.js - 304 Not Modified (0ms) Started GET "/items/1/edit" for 127.0.0.1 at 2015-10-26 17:45:50 +0100 Processing by ItemsController#edit as HTML Parameters: {"id"=>"1"} Item Load (0.2ms) SELECT "items".* FROM "items" WHERE "items"."id" = ? LIMIT 1 [["id", "1"]] Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = 1 LIMIT 1 Rendered items/_form.html.erb (65.4ms) Rendered items/edit.html.erb within layouts/application (66.5ms) Completed 200 OK in 72ms (Views: 70.4ms | ActiveRecord: 0.3ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2015-10-26 17:45:50 +0100 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:50 +0100 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2015-10-26 17:45:50 +0100 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:50 +0100 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:50 +0100 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:50 +0100 Served asset /application.js - 304 Not Modified (0ms) Started PUT "/items/1" for 127.0.0.1 at 2015-10-26 17:45:52 +0100 Processing by ItemsController#update as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"qUvOWnNKVcs+tuQzWQx3KoA8LNUX7dIY9OY+CMWvRIg=", "item"=>{"name"=>"polozka", "category_id"=>"1"}, "commit"=>"Update Item", "id"=>"1"} Item Load (0.1ms) SELECT "items".* FROM "items" WHERE "items"."id" = ? LIMIT 1 [["id", "1"]]  (0.1ms) begin transaction  (0.1ms) commit transaction Redirected to http://localhost:3000/items Completed 302 Found in 5ms (ActiveRecord: 0.3ms) Started GET "/items" for 127.0.0.1 at 2015-10-26 17:45:52 +0100 Processing by ItemsController#index as HTML Item Load (0.1ms) SELECT "items".* FROM "items" Rendered items/index.html.erb within layouts/application (0.6ms) Completed 200 OK in 5ms (Views: 4.3ms | ActiveRecord: 0.1ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2015-10-26 17:45:52 +0100 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/token-input-facebook.css?body=1" for 127.0.0.1 at 2015-10-26 17:45:52 +0100 Served asset /token-input-facebook.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:52 +0100 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:52 +0100 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:52 +0100 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.tokeninput.js?body=1" for 127.0.0.1 at 2015-10-26 17:45:52 +0100 Served asset /jquery.tokeninput.js - 304 Not Modified (0ms) DEPRECATION WARNING: You didn't set `secret_key_base`. Read the upgrade documentation to learn more about this new config option. (called from service at /Users/mirrec/.rvm/rubies/ruby-2.2.2/lib/ruby/2.2.0/webrick/httpserver.rb:138) Started GET "/" for ::1 at 2015-10-27 12:52:29 +0100 NoMethodError (undefined method `auto_explain_threshold_in_seconds=' for #): activerecord (4.2.4) lib/active_record/dynamic_matchers.rb:26:in `method_missing' activerecord (4.2.4) lib/active_record/railtie.rb:108:in `block (3 levels) in ' activerecord (4.2.4) lib/active_record/railtie.rb:107:in `each' activerecord (4.2.4) lib/active_record/railtie.rb:107:in `block (2 levels) in ' activesupport (4.2.4) lib/active_support/lazy_load_hooks.rb:38:in `instance_eval' activesupport (4.2.4) lib/active_support/lazy_load_hooks.rb:38:in `execute_hook' activesupport (4.2.4) lib/active_support/lazy_load_hooks.rb:45:in `block in run_load_hooks' activesupport (4.2.4) lib/active_support/lazy_load_hooks.rb:44:in `each' activesupport (4.2.4) lib/active_support/lazy_load_hooks.rb:44:in `run_load_hooks' activerecord (4.2.4) lib/active_record/base.rb:315:in `' activerecord (4.2.4) lib/active_record/base.rb:26:in `' activerecord (4.2.4) lib/active_record/connection_adapters/abstract/connection_pool.rb:660:in `rescue in call' activerecord (4.2.4) lib/active_record/connection_adapters/abstract/connection_pool.rb:651:in `call' actionpack (4.2.4) lib/action_dispatch/middleware/callbacks.rb:29:in `block in call' activesupport (4.2.4) lib/active_support/callbacks.rb:88:in `__run_callbacks__' activesupport (4.2.4) lib/active_support/callbacks.rb:778:in `_run_call_callbacks' activesupport (4.2.4) lib/active_support/callbacks.rb:81:in `run_callbacks' actionpack (4.2.4) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (4.2.4) lib/action_dispatch/middleware/reloader.rb:73:in `call' actionpack (4.2.4) lib/action_dispatch/middleware/remote_ip.rb:78:in `call' actionpack (4.2.4) lib/action_dispatch/middleware/debug_exceptions.rb:17:in `call' actionpack (4.2.4) lib/action_dispatch/middleware/show_exceptions.rb:30:in `call' railties (4.2.4) lib/rails/rack/logger.rb:38:in `call_app' railties (4.2.4) lib/rails/rack/logger.rb:20:in `block in call' activesupport (4.2.4) lib/active_support/tagged_logging.rb:68:in `block in tagged' activesupport (4.2.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (4.2.4) lib/active_support/tagged_logging.rb:68:in `tagged' railties (4.2.4) lib/rails/rack/logger.rb:20:in `call' actionpack (4.2.4) lib/action_dispatch/middleware/request_id.rb:21:in `call' rack (1.6.4) lib/rack/methodoverride.rb:22:in `call' rack (1.6.4) lib/rack/runtime.rb:18:in `call' activesupport (4.2.4) lib/active_support/cache/strategy/local_cache_middleware.rb:28:in `call' rack (1.6.4) lib/rack/lock.rb:17:in `call' actionpack (4.2.4) lib/action_dispatch/middleware/static.rb:116:in `call' rack (1.6.4) lib/rack/sendfile.rb:113:in `call' railties (4.2.4) lib/rails/engine.rb:518:in `call' railties (4.2.4) lib/rails/application.rb:165:in `call' rack (1.6.4) lib/rack/lock.rb:17:in `call' rack (1.6.4) lib/rack/content_length.rb:15:in `call' rack (1.6.4) lib/rack/handler/webrick.rb:88:in `service' /Users/mirrec/.rvm/rubies/ruby-2.2.2/lib/ruby/2.2.0/webrick/httpserver.rb:138:in `service' /Users/mirrec/.rvm/rubies/ruby-2.2.2/lib/ruby/2.2.0/webrick/httpserver.rb:94:in `run' /Users/mirrec/.rvm/rubies/ruby-2.2.2/lib/ruby/2.2.0/webrick/server.rb:294:in `block in start_thread' Rendered /Users/mirrec/.rvm/gems/ruby-2.2.2@token_field/gems/actionpack-4.2.4/lib/action_dispatch/middleware/templates/rescues/_source.erb (4.1ms) Rendered /Users/mirrec/.rvm/gems/ruby-2.2.2@token_field/gems/actionpack-4.2.4/lib/action_dispatch/middleware/templates/rescues/_trace.html.erb (2.2ms) Rendered /Users/mirrec/.rvm/gems/ruby-2.2.2@token_field/gems/actionpack-4.2.4/lib/action_dispatch/middleware/templates/rescues/_request_and_response.html.erb (1.1ms) Rendered /Users/mirrec/.rvm/gems/ruby-2.2.2@token_field/gems/actionpack-4.2.4/lib/action_dispatch/middleware/templates/rescues/diagnostics.html.erb within rescues/layout (54.4ms) Started GET "/" for ::1 at 2015-10-27 12:53:15 +0100 NoMethodError (undefined method `auto_explain_threshold_in_seconds=' for #): activerecord (4.2.4) lib/active_record/dynamic_matchers.rb:26:in `method_missing' activerecord (4.2.4) lib/active_record/railtie.rb:108:in `block (3 levels) in ' activerecord (4.2.4) lib/active_record/railtie.rb:107:in `each' activerecord (4.2.4) lib/active_record/railtie.rb:107:in `block (2 levels) in ' activesupport (4.2.4) lib/active_support/lazy_load_hooks.rb:38:in `instance_eval' activesupport (4.2.4) lib/active_support/lazy_load_hooks.rb:38:in `execute_hook' activesupport (4.2.4) lib/active_support/lazy_load_hooks.rb:45:in `block in run_load_hooks' activesupport (4.2.4) lib/active_support/lazy_load_hooks.rb:44:in `each' activesupport (4.2.4) lib/active_support/lazy_load_hooks.rb:44:in `run_load_hooks' activerecord (4.2.4) lib/active_record/base.rb:315:in `' activerecord (4.2.4) lib/active_record/base.rb:26:in `' activerecord (4.2.4) lib/active_record/connection_adapters/abstract/connection_pool.rb:660:in `rescue in call' activerecord (4.2.4) lib/active_record/connection_adapters/abstract/connection_pool.rb:651:in `call' actionpack (4.2.4) lib/action_dispatch/middleware/callbacks.rb:29:in `block in call' activesupport (4.2.4) lib/active_support/callbacks.rb:88:in `__run_callbacks__' activesupport (4.2.4) lib/active_support/callbacks.rb:778:in `_run_call_callbacks' activesupport (4.2.4) lib/active_support/callbacks.rb:81:in `run_callbacks' actionpack (4.2.4) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (4.2.4) lib/action_dispatch/middleware/reloader.rb:73:in `call' actionpack (4.2.4) lib/action_dispatch/middleware/remote_ip.rb:78:in `call' actionpack (4.2.4) lib/action_dispatch/middleware/debug_exceptions.rb:17:in `call' actionpack (4.2.4) lib/action_dispatch/middleware/show_exceptions.rb:30:in `call' railties (4.2.4) lib/rails/rack/logger.rb:38:in `call_app' railties (4.2.4) lib/rails/rack/logger.rb:20:in `block in call' activesupport (4.2.4) lib/active_support/tagged_logging.rb:68:in `block in tagged' activesupport (4.2.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (4.2.4) lib/active_support/tagged_logging.rb:68:in `tagged' railties (4.2.4) lib/rails/rack/logger.rb:20:in `call' actionpack (4.2.4) lib/action_dispatch/middleware/request_id.rb:21:in `call' rack (1.6.4) lib/rack/methodoverride.rb:22:in `call' rack (1.6.4) lib/rack/runtime.rb:18:in `call' activesupport (4.2.4) lib/active_support/cache/strategy/local_cache_middleware.rb:28:in `call' rack (1.6.4) lib/rack/lock.rb:17:in `call' actionpack (4.2.4) lib/action_dispatch/middleware/static.rb:116:in `call' rack (1.6.4) lib/rack/sendfile.rb:113:in `call' railties (4.2.4) lib/rails/engine.rb:518:in `call' railties (4.2.4) lib/rails/application.rb:165:in `call' rack (1.6.4) lib/rack/lock.rb:17:in `call' rack (1.6.4) lib/rack/content_length.rb:15:in `call' rack (1.6.4) lib/rack/handler/webrick.rb:88:in `service' /Users/mirrec/.rvm/rubies/ruby-2.2.2/lib/ruby/2.2.0/webrick/httpserver.rb:138:in `service' /Users/mirrec/.rvm/rubies/ruby-2.2.2/lib/ruby/2.2.0/webrick/httpserver.rb:94:in `run' /Users/mirrec/.rvm/rubies/ruby-2.2.2/lib/ruby/2.2.0/webrick/server.rb:294:in `block in start_thread' Rendered /Users/mirrec/.rvm/gems/ruby-2.2.2@token_field/gems/actionpack-4.2.4/lib/action_dispatch/middleware/templates/rescues/_source.erb (3.8ms) Rendered /Users/mirrec/.rvm/gems/ruby-2.2.2@token_field/gems/actionpack-4.2.4/lib/action_dispatch/middleware/templates/rescues/_trace.html.erb (2.4ms) Rendered /Users/mirrec/.rvm/gems/ruby-2.2.2@token_field/gems/actionpack-4.2.4/lib/action_dispatch/middleware/templates/rescues/_request_and_response.html.erb (1.0ms) Rendered /Users/mirrec/.rvm/gems/ruby-2.2.2@token_field/gems/actionpack-4.2.4/lib/action_dispatch/middleware/templates/rescues/diagnostics.html.erb within rescues/layout (55.0ms) Started GET "/" for ::1 at 2015-10-27 12:58:03 +0100 Processing by CategoriesController#index as HTML Category Load (2.5ms) SELECT "categories".* FROM "categories" Rendered categories/index.html.erb within layouts/application (36.1ms) Completed 200 OK in 458ms (Views: 419.2ms | ActiveRecord: 2.8ms) Started GET "/assets/application.self.css?body=1" for ::1 at 2015-10-27 12:58:04 +0100 Started GET "/assets/token-input-facebook.self.css?body=1" for ::1 at 2015-10-27 12:58:04 +0100 Started GET "/assets/jquery.self.js?body=1" for ::1 at 2015-10-27 12:58:04 +0100 Started GET "/assets/jquery_ujs.self.js?body=1" for ::1 at 2015-10-27 12:58:04 +0100 Started GET "/assets/jquery.tokeninput.self.js?body=1" for ::1 at 2015-10-27 12:58:04 +0100 Started GET "/assets/application.self.js?body=1" for ::1 at 2015-10-27 12:58:04 +0100 Started GET "/categories/1/edit" for ::1 at 2015-10-27 12:58:06 +0100 Processing by CategoriesController#edit as HTML Parameters: {"id"=>"1"} Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = ? LIMIT 1 [["id", 1]] Rendered categories/_form.html.erb (165.3ms) Rendered categories/edit.html.erb within layouts/application (172.3ms) Completed 200 OK in 209ms (Views: 196.6ms | ActiveRecord: 0.2ms) Started GET "/assets/application.self.css?body=1" for ::1 at 2015-10-27 12:58:06 +0100 Started GET "/assets/token-input-facebook.self.css?body=1" for ::1 at 2015-10-27 12:58:06 +0100 Started GET "/assets/jquery.self.js?body=1" for ::1 at 2015-10-27 12:58:06 +0100 Started GET "/assets/jquery_ujs.self.js?body=1" for ::1 at 2015-10-27 12:58:06 +0100 Started GET "/assets/jquery.tokeninput.self.js?body=1" for ::1 at 2015-10-27 12:58:06 +0100 Started GET "/assets/application.self.js?body=1" for ::1 at 2015-10-27 12:58:06 +0100 Started GET "/items" for 127.0.0.1 at 2015-10-27 12:58:44 +0100 Processing by ItemsController#index as HTML Item Load (1.9ms) SELECT "items".* FROM "items" Rendered items/index.html.erb within layouts/application (28.9ms) Completed 200 OK in 297ms (Views: 260.1ms | ActiveRecord: 2.3ms) Started GET "/assets/token-input-facebook.self.css?body=1" for 127.0.0.1 at 2015-10-27 12:58:45 +0100 Started GET "/assets/application.self.js?body=1" for 127.0.0.1 at 2015-10-27 12:58:45 +0100 Started GET "/assets/jquery.tokeninput.self.js?body=1" for 127.0.0.1 at 2015-10-27 12:58:45 +0100 Started GET "/assets/jquery.self.js?body=1" for 127.0.0.1 at 2015-10-27 12:58:45 +0100 Started GET "/assets/jquery_ujs.self.js?body=1" for 127.0.0.1 at 2015-10-27 12:58:45 +0100 Started GET "/assets/application.self.css?body=1" for 127.0.0.1 at 2015-10-27 12:58:45 +0100 Started GET "/items/1/edit" for 127.0.0.1 at 2015-10-27 12:58:46 +0100 Processing by ItemsController#edit as HTML Parameters: {"id"=>"1"} Item Load (0.2ms) SELECT "items".* FROM "items" WHERE "items"."id" = ? LIMIT 1 [["id", 1]] Category Load (0.5ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = ? LIMIT 1 [["id", 1]] Rendered items/_form.html.erb (33808.3ms) Rendered items/edit.html.erb within layouts/application (33815.1ms) Completed 200 OK in 33853ms (Views: 33840.5ms | ActiveRecord: 0.9ms) Started GET "/assets/application.self.css?body=1" for 127.0.0.1 at 2015-10-27 12:59:20 +0100 Started GET "/assets/token-input-facebook.self.css?body=1" for 127.0.0.1 at 2015-10-27 12:59:20 +0100 Started GET "/assets/jquery.self.js?body=1" for 127.0.0.1 at 2015-10-27 12:59:20 +0100 Started GET "/assets/jquery.tokeninput.self.js?body=1" for 127.0.0.1 at 2015-10-27 12:59:20 +0100 Started GET "/assets/jquery_ujs.self.js?body=1" for 127.0.0.1 at 2015-10-27 12:59:20 +0100 Started GET "/assets/application.self.js?body=1" for 127.0.0.1 at 2015-10-27 12:59:20 +0100 Started GET "/items/1/edit" for 127.0.0.1 at 2015-10-27 13:05:30 +0100 Processing by ItemsController#edit as HTML Parameters: {"id"=>"1"} Item Load (0.1ms) SELECT "items".* FROM "items" WHERE "items"."id" = ? LIMIT 1 [["id", 1]] Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = ? LIMIT 1 [["id", 1]] Rendered items/_form.html.erb (152928.6ms) Rendered items/edit.html.erb within layouts/application (152930.3ms) Completed 200 OK in 152980ms (Views: 152981.5ms | ActiveRecord: 0.3ms) Started GET "/assets/token-input-facebook.self.css?body=1" for 127.0.0.1 at 2015-10-27 13:08:03 +0100 Started GET "/assets/jquery.self.js?body=1" for 127.0.0.1 at 2015-10-27 13:08:03 +0100 Started GET "/assets/jquery_ujs.self.js?body=1" for 127.0.0.1 at 2015-10-27 13:08:03 +0100 Started GET "/assets/jquery.tokeninput.self.js?body=1" for 127.0.0.1 at 2015-10-27 13:08:03 +0100 Started GET "/assets/application.self.js?body=1" for 127.0.0.1 at 2015-10-27 13:08:03 +0100 Started GET "/assets/application.self.css?body=1" for 127.0.0.1 at 2015-10-27 13:08:03 +0100 Started GET "/items/1/edit" for 127.0.0.1 at 2015-10-27 13:08:11 +0100 Processing by ItemsController#edit as HTML Parameters: {"id"=>"1"} Item Load (0.5ms) SELECT "items".* FROM "items" WHERE "items"."id" = ? LIMIT 1 [["id", 1]] Category Load (0.4ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = ? LIMIT 1 [["id", 1]] Rendered items/_form.html.erb (195.1ms) Rendered items/edit.html.erb within layouts/application (202.0ms) Completed 200 OK in 463ms (Views: 393.4ms | ActiveRecord: 4.0ms) Started GET "/assets/application.self.css?body=1" for 127.0.0.1 at 2015-10-27 13:08:12 +0100 Started GET "/assets/jquery.self.js?body=1" for 127.0.0.1 at 2015-10-27 13:08:12 +0100 Started GET "/assets/token-input-facebook.self.css?body=1" for 127.0.0.1 at 2015-10-27 13:08:12 +0100 Started GET "/assets/jquery_ujs.self.js?body=1" for 127.0.0.1 at 2015-10-27 13:08:12 +0100 Started GET "/assets/jquery.tokeninput.self.js?body=1" for 127.0.0.1 at 2015-10-27 13:08:12 +0100 Started GET "/assets/application.self.js?body=1" for 127.0.0.1 at 2015-10-27 13:08:12 +0100 Started GET "/items/1/edit" for 127.0.0.1 at 2015-10-27 13:08:30 +0100 Processing by ItemsController#edit as HTML Parameters: {"id"=>"1"} Item Load (0.2ms) SELECT "items".* FROM "items" WHERE "items"."id" = ? LIMIT 1 [["id", 1]] Category Load (0.2ms) SELECT "categories".* FROM "categories" WHERE "categories"."id" = ? LIMIT 1 [["id", 1]] Rendered items/_form.html.erb (25600.6ms) Rendered items/edit.html.erb within layouts/application (25605.5ms) Completed 200 OK in 25842ms (Views: 25783.5ms | ActiveRecord: 2.7ms) Started GET "/assets/application.self.css?body=1" for 127.0.0.1 at 2015-10-27 13:08:56 +0100 Started GET "/assets/jquery.self.js?body=1" for 127.0.0.1 at 2015-10-27 13:08:56 +0100 Started GET "/assets/token-input-facebook.self.css?body=1" for 127.0.0.1 at 2015-10-27 13:08:56 +0100 Started GET "/assets/jquery_ujs.self.js?body=1" for 127.0.0.1 at 2015-10-27 13:08:56 +0100 Started GET "/assets/jquery.tokeninput.self.js?body=1" for 127.0.0.1 at 2015-10-27 13:08:56 +0100 Started GET "/assets/application.self.js?body=1" for 127.0.0.1 at 2015-10-27 13:08:56 +0100 Connecting to database specified by database.yml