test/dummy/log/development.log in kblog-0.0.2 vs test/dummy/log/development.log in kblog-0.0.3

- old
+ new

@@ -5559,5 +5559,3983 @@ Served asset /kblog/application.js - 304 Not Modified (1ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-06 10:54:59 +0100 Served asset /jquery.js - 304 Not Modified (0ms) + + +Started GET "/users" for 127.0.0.1 at 2013-03-06 16:50:07 +0100 +Connecting to database specified by database.yml +Processing by UsersController#index as HTML + User Load (0.1ms) SELECT "users".* FROM "users"  + Rendered users/index.html.erb within layouts/application (35.2ms) +Compiled scaffold.css (0ms) (pid 21665) +Compiled users.css (0ms) (pid 21665) +Compiled application.css (13ms) (pid 21665) +Compiled kblog/articles.css (90ms) (pid 21665) +Compiled jquery.js (35ms) (pid 21665) +Compiled jquery_ujs.js (0ms) (pid 21665) +Compiled users.js (0ms) (pid 21665) +Compiled application.js (62ms) (pid 21665) + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 1 + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (50.7ms) +Completed 200 OK in 388ms (Views: 348.9ms | ActiveRecord: 32.0ms) + + +Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-06 16:50:08 +0100 +Served asset /application.css - 200 OK (7ms) + + +Started GET "/assets/users.css?body=1" for 127.0.0.1 at 2013-03-06 16:50:08 +0100 +Served asset /users.css - 200 OK (4ms) + + +Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-06 16:50:08 +0100 +Served asset /scaffold.css - 304 Not Modified (1ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-06 16:50:08 +0100 +Served asset /kblog/articles.css - 200 OK (2ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-06 16:50:08 +0100 +Served asset /jquery.js - 200 OK (2ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-06 16:50:08 +0100 +Served asset /jquery_ujs.js - 200 OK (1ms) + + +Started GET "/assets/users.js?body=1" for 127.0.0.1 at 2013-03-06 16:50:08 +0100 +Served asset /users.js - 200 OK (2ms) + + +Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-06 16:50:08 +0100 +Served asset /application.js - 200 OK (35ms) + + +Started GET "/kblog/articles" for 127.0.0.1 at 2013-03-06 16:51:29 +0100 +Connecting to database specified by database.yml +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Kblog::Article Load (0.2ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 3 OFFSET 0 +  (0.1ms) SELECT COUNT(*) FROM "kblog_articles" + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (7.8ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/index.html.erb within layouts/kblog/application (52.8ms) +Compiled kblog/application.css (2ms) (pid 21732) +Compiled kblog/articles.js (0ms) (pid 21732) +Compiled kblog/application.js (10ms) (pid 21732) +Completed 200 OK in 173ms (Views: 169.1ms | ActiveRecord: 2.3ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-06 16:51:30 +0100 +Served asset /kblog/application.css - 200 OK (3ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-06 16:51:30 +0100 +Served asset /kblog/articles.css - 304 Not Modified (2ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-06 16:51:30 +0100 +Served asset /jquery.js - 304 Not Modified (3ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-06 16:51:30 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (1ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-06 16:51:30 +0100 +Served asset /kblog/articles.js - 200 OK (2ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-06 16:51:30 +0100 +Served asset /kblog/application.js - 200 OK (5ms) +Connecting to database specified by database.yml + +foobar + + +Started GET "/kblog/articles" for 127.0.0.1 at 2013-03-06 17:01:24 +0100 +Connecting to database specified by database.yml +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Kblog::Article Load (0.2ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 3 OFFSET 0 +  (0.1ms) SELECT COUNT(*) FROM "kblog_articles" + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (7.9ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/index.html.erb within layouts/kblog/application (55.7ms) +Completed 200 OK in 120ms (Views: 116.5ms | ActiveRecord: 2.2ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-06 17:01:25 +0100 +Served asset /kblog/application.css - 304 Not Modified (3ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-06 17:01:25 +0100 +Served asset /kblog/articles.css - 304 Not Modified (2ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-06 17:01:25 +0100 +Served asset /jquery.js - 304 Not Modified (5ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-06 17:01:25 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (10ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-06 17:01:25 +0100 +Served asset /kblog/articles.js - 304 Not Modified (2ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-06 17:01:25 +0100 +Served asset /kblog/application.js - 304 Not Modified (9ms) + + +Started GET "/" for 127.0.0.1 at 2013-03-06 17:01:31 +0100 + +ActionController::RoutingError (No route matches [GET] "/"): + actionpack (3.2.12) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' + railties (3.2.12) lib/rails/rack/logger.rb:32:in `call_app' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `block in call' + activesupport (3.2.12) lib/active_support/tagged_logging.rb:22:in `tagged' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/request_id.rb:22:in `call' + rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' + rack (1.4.5) lib/rack/runtime.rb:17:in `call' + activesupport (3.2.12) lib/active_support/cache/strategy/local_cache.rb:72:in `call' + rack (1.4.5) lib/rack/lock.rb:15:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/static.rb:62:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/application.rb:223:in `call' + rack (1.4.5) lib/rack/content_length.rb:14:in `call' + railties (3.2.12) lib/rails/rack/log_tailer.rb:17:in `call' + rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' + /usr/local/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (0.5ms) + + +Started GET "/" for 127.0.0.1 at 2013-03-06 17:01:39 +0100 + +ActionController::RoutingError (No route matches [GET] "/"): + actionpack (3.2.12) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' + railties (3.2.12) lib/rails/rack/logger.rb:32:in `call_app' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `block in call' + activesupport (3.2.12) lib/active_support/tagged_logging.rb:22:in `tagged' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/request_id.rb:22:in `call' + rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' + rack (1.4.5) lib/rack/runtime.rb:17:in `call' + activesupport (3.2.12) lib/active_support/cache/strategy/local_cache.rb:72:in `call' + rack (1.4.5) lib/rack/lock.rb:15:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/static.rb:62:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/application.rb:223:in `call' + rack (1.4.5) lib/rack/content_length.rb:14:in `call' + railties (3.2.12) lib/rails/rack/log_tailer.rb:17:in `call' + rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' + /usr/local/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (0.6ms) + + +Started GET "/users" for 127.0.0.1 at 2013-03-06 17:01:41 +0100 +Processing by UsersController#index as HTML + User Load (0.1ms) SELECT "users".* FROM "users"  + Rendered users/index.html.erb within layouts/application (4.0ms) + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 1 + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (0.5ms) +Completed 200 OK in 66ms (Views: 58.1ms | ActiveRecord: 0.7ms) + + +Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-06 17:01:41 +0100 +Served asset /application.css - 304 Not Modified (5ms) + + +Started GET "/assets/users.css?body=1" for 127.0.0.1 at 2013-03-06 17:01:41 +0100 +Served asset /users.css - 304 Not Modified (1ms) + + +Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-06 17:01:41 +0100 +Served asset /scaffold.css - 304 Not Modified (1ms) + + +Started GET "/assets/users.js?body=1" for 127.0.0.1 at 2013-03-06 17:01:41 +0100 +Served asset /users.js - 304 Not Modified (1ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-06 17:01:41 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-06 17:01:41 +0100 +Served asset /jquery.js - 304 Not Modified (0ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-06 17:01:41 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-06 17:01:41 +0100 +Served asset /application.js - 304 Not Modified (5ms) + + +Started GET "/users" for 127.0.0.1 at 2013-03-06 17:04:27 +0100 +Connecting to database specified by database.yml +Processing by UsersController#index as HTML + User Load (0.1ms) SELECT "users".* FROM "users"  + Rendered users/index.html.erb within layouts/application (32.6ms) + Kblog::Article Load (0.2ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 1 + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (78.4ms) +Completed 500 Internal Server Error in 187ms + +ActionView::Template::Error (undefined local variable or method `current_user' for #<#<Class:0x00000003c16b48>:0x007f98381b3ff0>): + 7: <div class="kblog-content"> + 8: <%= textilize(article.content).html_safe %> + 9: </div> + 10: <% if current_user && Kblog::Article.user_rights(current_user) %> + 11: <div class="kblog-links"> + 12: <%= link_to 'Edit', kblog.edit_article_path(article) %> | + 13: <%= link_to 'Destroy', kblog.article_path(article), :confirm => 'Are you sure?', :method => :delete %> + app/views/layouts/application.html.erb:15:in `_app_views_layouts_application_html_erb__653315328870129980_70145879047920' + app/controllers/users_controller.rb:7:in `index' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.2ms) + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (0.9ms) + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/template_error.erb within rescues/layout (6.6ms) +Connecting to database specified by database.yml + + +Started GET "/users" for 127.0.0.1 at 2013-03-06 17:07:05 +0100 +Connecting to database specified by database.yml +Processing by UsersController#index as HTML + User Load (0.1ms) SELECT "users".* FROM "users"  + Rendered users/index.html.erb within layouts/application (32.3ms) + Kblog::Article Load (0.2ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 1 + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (5.9ms) +Completed 200 OK in 113ms (Views: 75.8ms | ActiveRecord: 30.5ms) + + +Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-06 17:07:06 +0100 +Served asset /application.css - 304 Not Modified (3ms) + + +Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-06 17:07:06 +0100 +Served asset /scaffold.css - 304 Not Modified (4ms) + + +Started GET "/assets/users.css?body=1" for 127.0.0.1 at 2013-03-06 17:07:06 +0100 +Served asset /users.css - 304 Not Modified (1ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-06 17:07:06 +0100 +Served asset /kblog/articles.css - 304 Not Modified (4ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-06 17:07:06 +0100 +Served asset /jquery.js - 304 Not Modified (5ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-06 17:07:06 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (1ms) + + +Started GET "/assets/users.js?body=1" for 127.0.0.1 at 2013-03-06 17:07:06 +0100 +Served asset /users.js - 304 Not Modified (1ms) + + +Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-06 17:07:06 +0100 +Served asset /application.js - 304 Not Modified (6ms) + + +Started GET "/kblog/articles/new" for 127.0.0.1 at 2013-03-06 17:34:44 +0100 +Connecting to database specified by database.yml +Processing by Kblog::ArticlesController#new as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_form.html.erb (7.5ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/new.html.erb within layouts/kblog/application (41.0ms) +Compiled kblog/articles.css (6ms) (pid 23147) +Compiled kblog/application.css (13ms) (pid 23147) +Compiled jquery.js (33ms) (pid 23147) +Compiled jquery_ujs.js (0ms) (pid 23147) +Compiled kblog/articles.js (0ms) (pid 23147) +Compiled kblog/application.js (61ms) (pid 23147) +Completed 200 OK in 229ms (Views: 192.1ms | ActiveRecord: 29.8ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-06 17:34:45 +0100 +Served asset /kblog/application.css - 200 OK (3ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-06 17:34:45 +0100 +Served asset /kblog/articles.css - 200 OK (3ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-06 17:34:45 +0100 +Served asset /jquery.js - 200 OK (3ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-06 17:34:45 +0100 +Served asset /jquery_ujs.js - 200 OK (2ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-06 17:34:45 +0100 +Served asset /kblog/articles.js - 200 OK (1ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-06 17:34:45 +0100 +Served asset /kblog/application.js - 200 OK (6ms) + + +Started POST "/kblog/articles" for 127.0.0.1 at 2013-03-06 17:34:52 +0100 +Processing by Kblog::ArticlesController#create as HTML + Parameters: {"utf8"=>"✓", "authenticity_token"=>"Vyg2n46QgdWuOPtVHZU0zFHfT39Qm6lDSv19bunCnc8=", "article"=>{"title"=>"adafsdf", "content"=>"Lorem ipsum dolor sit amet, consectetur adipiscing elit. Donec pharetra felis vitae est fringilla sit amet volutpat eros accumsan. Class aptent taciti sociosqu ad litora torquent per conubia nostra, per inceptos himenaeos. Curabitur sed elit non urna posuere convallis. Suspendisse porta lectus at augue molestie tincidunt. In odio nisi, suscipit at porta sed.\r\n"}, "commit"=>"Create Article"} +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: +Completed 500 Internal Server Error in 41ms + +ActionView::MissingTemplate (Missing template kblog/articles/create, kblog/application/create with {:locale=>[:en], :formats=>[:html, :text, :js, :css, :ics, :csv, :png, :jpeg, :gif, :bmp, :tiff, :mpeg, :xml, :rss, :atom, :yaml, :multipart_form, :url_encoded_form, :json, :pdf, :zip], :handlers=>[:erb, :builder]}. Searched in: + * "/home/erpe/devel/kblog/test/dummy/app/views" + * "/home/erpe/devel/kblog/app/views" +): + actionpack (3.2.12) lib/action_view/path_set.rb:58:in `find' + actionpack (3.2.12) lib/action_view/lookup_context.rb:109:in `find' + actionpack (3.2.12) lib/action_view/renderer/abstract_renderer.rb:3:in `find_template' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:34:in `determine_template' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:10:in `render' + actionpack (3.2.12) lib/action_view/renderer/renderer.rb:36:in `render_template' + actionpack (3.2.12) lib/action_view/renderer/renderer.rb:17:in `render' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:110:in `_render_template' + actionpack (3.2.12) lib/action_controller/metal/streaming.rb:225:in `_render_template' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:103:in `render_to_body' + actionpack (3.2.12) lib/action_controller/metal/renderers.rb:28:in `render_to_body' + actionpack (3.2.12) lib/action_controller/metal/compatibility.rb:50:in `render_to_body' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:88:in `render' + actionpack (3.2.12) lib/action_controller/metal/rendering.rb:16:in `render' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:40:in `block (2 levels) in render' + activesupport (3.2.12) lib/active_support/core_ext/benchmark.rb:5:in `block in ms' + /usr/local/lib/ruby/1.9.1/benchmark.rb:295:in `realtime' + activesupport (3.2.12) lib/active_support/core_ext/benchmark.rb:5:in `ms' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:40:in `block in render' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:83:in `cleanup_view_runtime' + activerecord (3.2.12) lib/active_record/railties/controller_runtime.rb:24:in `cleanup_view_runtime' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:39:in `render' + /home/erpe/devel/kblog/app/controllers/kblog/articles_controller.rb:78:in `authenticate' + activesupport (3.2.12) lib/active_support/callbacks.rb:418:in `_run__241488573084885455__process_action__3563710475870684462__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/abstract_controller/callbacks.rb:17:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/rescue.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:30:in `block in process_action' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `block in instrument' + activesupport (3.2.12) lib/active_support/notifications/instrumenter.rb:20:in `instrument' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `instrument' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/params_wrapper.rb:207:in `process_action' + activerecord (3.2.12) lib/active_record/railties/controller_runtime.rb:18:in `process_action' + actionpack (3.2.12) lib/abstract_controller/base.rb:121:in `process' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:45:in `process' + actionpack (3.2.12) lib/action_controller/metal.rb:203:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal.rb:246:in `block in action' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' + actionpack (3.2.12) 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.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/railtie/configurable.rb:30:in `method_missing' + journey (1.0.4) lib/journey/router.rb:68:in `block in call' + journey (1.0.4) lib/journey/router.rb:56:in `each' + journey (1.0.4) lib/journey/router.rb:56:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' + rack (1.4.5) lib/rack/etag.rb:23:in `call' + rack (1.4.5) lib/rack/conditionalget.rb:35:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/head.rb:14:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/params_parser.rb:21:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/flash.rb:242:in `call' + rack (1.4.5) lib/rack/session/abstract/id.rb:210:in `context' + rack (1.4.5) lib/rack/session/abstract/id.rb:205:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/cookies.rb:341:in `call' + activerecord (3.2.12) lib/active_record/query_cache.rb:64:in `call' + activerecord (3.2.12) lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `_run__2498969859756494126__call__2444857881217746440__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:27:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/reloader.rb:65:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' + railties (3.2.12) lib/rails/rack/logger.rb:32:in `call_app' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `block in call' + activesupport (3.2.12) lib/active_support/tagged_logging.rb:22:in `tagged' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/request_id.rb:22:in `call' + rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' + rack (1.4.5) lib/rack/runtime.rb:17:in `call' + activesupport (3.2.12) lib/active_support/cache/strategy/local_cache.rb:72:in `call' + rack (1.4.5) lib/rack/lock.rb:15:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/static.rb:62:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/application.rb:223:in `call' + rack (1.4.5) lib/rack/content_length.rb:14:in `call' + railties (3.2.12) lib/rails/rack/log_tailer.rb:17:in `call' + rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' + /usr/local/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/missing_template.erb within rescues/layout (0.4ms) + + +Started GET "/kblog/articles" for 127.0.0.1 at 2013-03-06 17:36:43 +0100 +Connecting to database specified by database.yml +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Kblog::Article Load (0.2ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 3 OFFSET 0 +  (0.1ms) SELECT COUNT(*) FROM "kblog_articles" + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (7.9ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/index.html.erb within layouts/kblog/application (51.4ms) +Completed 200 OK in 103ms (Views: 99.4ms | ActiveRecord: 2.3ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-06 17:36:44 +0100 +Served asset /kblog/application.css - 304 Not Modified (3ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-06 17:36:44 +0100 +Served asset /kblog/articles.css - 304 Not Modified (2ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-06 17:36:44 +0100 +Served asset /jquery.js - 304 Not Modified (3ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-06 17:36:44 +0100 +Served asset /kblog/application.js - 304 Not Modified (9ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-06 17:36:44 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (1ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-06 17:36:44 +0100 +Served asset /kblog/articles.js - 304 Not Modified (2ms) + + +Started GET "/kblog/articles/new" for 127.0.0.1 at 2013-03-06 17:36:48 +0100 +Processing by Kblog::ArticlesController#new as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_form.html.erb (5.6ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/new.html.erb within layouts/kblog/application (7.5ms) +Completed 200 OK in 14ms (Views: 13.3ms | ActiveRecord: 0.0ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-06 17:36:48 +0100 +Served asset /kblog/application.css - 304 Not Modified (2ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-06 17:36:48 +0100 +Served asset /jquery.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-06 17:36:48 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-06 17:36:48 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-06 17:36:48 +0100 +Served asset /kblog/articles.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-06 17:36:48 +0100 +Served asset /kblog/application.js - 304 Not Modified (1ms) + + +Started POST "/kblog/articles" for 127.0.0.1 at 2013-03-06 17:36:55 +0100 +Processing by Kblog::ArticlesController#create as HTML + Parameters: {"utf8"=>"✓", "authenticity_token"=>"Vyg2n46QgdWuOPtVHZU0zFHfT39Qm6lDSv19bunCnc8=", "article"=>{"title"=>"foobar", "content"=>"Suspendisse felis nisl, blandit eget mollis id; facilisis vel justo. Nulla porttitor elementum est eget dictum? Curabitur scelerisque ligula eu tortor accumsan viverra. Mauris laoreet scelerisque enim vel hendrerit. Vivamus a urna ut turpis condimentum semper in eu mauris. Curabitur vitae neque ac nisi laoreet laoreet. Etiam neque nisl, dapibus non volutpat.\r\n"}, "commit"=>"Create Article"} +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: +Completed 500 Internal Server Error in 13ms + +ActionView::MissingTemplate (Missing template kblog/articles/create, kblog/application/create with {:locale=>[:en], :formats=>[:html, :text, :js, :css, :ics, :csv, :png, :jpeg, :gif, :bmp, :tiff, :mpeg, :xml, :rss, :atom, :yaml, :multipart_form, :url_encoded_form, :json, :pdf, :zip], :handlers=>[:erb, :builder]}. Searched in: + * "/home/erpe/devel/kblog/test/dummy/app/views" + * "/home/erpe/devel/kblog/app/views" +): + actionpack (3.2.12) lib/action_view/path_set.rb:58:in `find' + actionpack (3.2.12) lib/action_view/lookup_context.rb:109:in `find' + actionpack (3.2.12) lib/action_view/renderer/abstract_renderer.rb:3:in `find_template' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:34:in `determine_template' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:10:in `render' + actionpack (3.2.12) lib/action_view/renderer/renderer.rb:36:in `render_template' + actionpack (3.2.12) lib/action_view/renderer/renderer.rb:17:in `render' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:110:in `_render_template' + actionpack (3.2.12) lib/action_controller/metal/streaming.rb:225:in `_render_template' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:103:in `render_to_body' + actionpack (3.2.12) lib/action_controller/metal/renderers.rb:28:in `render_to_body' + actionpack (3.2.12) lib/action_controller/metal/compatibility.rb:50:in `render_to_body' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:88:in `render' + actionpack (3.2.12) lib/action_controller/metal/rendering.rb:16:in `render' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:40:in `block (2 levels) in render' + activesupport (3.2.12) lib/active_support/core_ext/benchmark.rb:5:in `block in ms' + /usr/local/lib/ruby/1.9.1/benchmark.rb:295:in `realtime' + activesupport (3.2.12) lib/active_support/core_ext/benchmark.rb:5:in `ms' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:40:in `block in render' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:83:in `cleanup_view_runtime' + activerecord (3.2.12) lib/active_record/railties/controller_runtime.rb:24:in `cleanup_view_runtime' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:39:in `render' + /home/erpe/devel/kblog/app/controllers/kblog/articles_controller.rb:78:in `authenticate' + activesupport (3.2.12) lib/active_support/callbacks.rb:418:in `_run__844021587095060203__process_action__4254871983231633084__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/abstract_controller/callbacks.rb:17:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/rescue.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:30:in `block in process_action' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `block in instrument' + activesupport (3.2.12) lib/active_support/notifications/instrumenter.rb:20:in `instrument' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `instrument' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/params_wrapper.rb:207:in `process_action' + activerecord (3.2.12) lib/active_record/railties/controller_runtime.rb:18:in `process_action' + actionpack (3.2.12) lib/abstract_controller/base.rb:121:in `process' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:45:in `process' + actionpack (3.2.12) lib/action_controller/metal.rb:203:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal.rb:246:in `block in action' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' + actionpack (3.2.12) 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.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/railtie/configurable.rb:30:in `method_missing' + journey (1.0.4) lib/journey/router.rb:68:in `block in call' + journey (1.0.4) lib/journey/router.rb:56:in `each' + journey (1.0.4) lib/journey/router.rb:56:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' + rack (1.4.5) lib/rack/etag.rb:23:in `call' + rack (1.4.5) lib/rack/conditionalget.rb:35:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/head.rb:14:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/params_parser.rb:21:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/flash.rb:242:in `call' + rack (1.4.5) lib/rack/session/abstract/id.rb:210:in `context' + rack (1.4.5) lib/rack/session/abstract/id.rb:205:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/cookies.rb:341:in `call' + activerecord (3.2.12) lib/active_record/query_cache.rb:64:in `call' + activerecord (3.2.12) lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `_run__1989890823962898859__call__3048104571436203686__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:27:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/reloader.rb:65:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' + railties (3.2.12) lib/rails/rack/logger.rb:32:in `call_app' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `block in call' + activesupport (3.2.12) lib/active_support/tagged_logging.rb:22:in `tagged' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/request_id.rb:22:in `call' + rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' + rack (1.4.5) lib/rack/runtime.rb:17:in `call' + activesupport (3.2.12) lib/active_support/cache/strategy/local_cache.rb:72:in `call' + rack (1.4.5) lib/rack/lock.rb:15:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/static.rb:62:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/application.rb:223:in `call' + rack (1.4.5) lib/rack/content_length.rb:14:in `call' + railties (3.2.12) lib/rails/rack/log_tailer.rb:17:in `call' + rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' + /usr/local/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/missing_template.erb within rescues/layout (0.4ms) + + +Started GET "/kblog/articles" for 127.0.0.1 at 2013-03-06 17:39:24 +0100 +Connecting to database specified by database.yml +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 3 OFFSET 0 +  (0.2ms) SELECT COUNT(*) FROM "kblog_articles" + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (7.9ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/index.html.erb within layouts/kblog/application (52.4ms) +Completed 200 OK in 104ms (Views: 100.4ms | ActiveRecord: 2.5ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-06 17:39:25 +0100 +Served asset /kblog/application.css - 304 Not Modified (3ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-06 17:39:25 +0100 +Served asset /kblog/articles.css - 304 Not Modified (2ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-06 17:39:25 +0100 +Served asset /jquery.js - 304 Not Modified (4ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-06 17:39:25 +0100 +Served asset /kblog/application.js - 304 Not Modified (11ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-06 17:39:25 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (1ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-06 17:39:25 +0100 +Served asset /kblog/articles.js - 304 Not Modified (2ms) + + +Started GET "/kblog/articles/new" for 127.0.0.1 at 2013-03-06 17:39:30 +0100 +Processing by Kblog::ArticlesController#new as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_form.html.erb (4.8ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/new.html.erb within layouts/kblog/application (6.5ms) +Completed 200 OK in 13ms (Views: 12.7ms | ActiveRecord: 0.0ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-06 17:39:30 +0100 +Served asset /kblog/application.css - 304 Not Modified (5ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-06 17:39:30 +0100 +Served asset /kblog/articles.css - 304 Not Modified (2ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-06 17:39:30 +0100 +Served asset /jquery.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-06 17:39:30 +0100 +Served asset /kblog/application.js - 304 Not Modified (3ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-06 17:39:30 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-06 17:39:30 +0100 +Served asset /kblog/articles.js - 304 Not Modified (0ms) + + +Started POST "/kblog/articles" for 127.0.0.1 at 2013-03-06 17:39:36 +0100 +Processing by Kblog::ArticlesController#create as HTML + Parameters: {"utf8"=>"✓", "authenticity_token"=>"Vyg2n46QgdWuOPtVHZU0zFHfT39Qm6lDSv19bunCnc8=", "article"=>{"title"=>"foobar", "content"=>"foobar"}, "commit"=>"Create Article"} +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: +Completed 500 Internal Server Error in 13ms + +ActionView::MissingTemplate (Missing template kblog/articles/create, kblog/application/create with {:locale=>[:en], :formats=>[:html, :text, :js, :css, :ics, :csv, :png, :jpeg, :gif, :bmp, :tiff, :mpeg, :xml, :rss, :atom, :yaml, :multipart_form, :url_encoded_form, :json, :pdf, :zip], :handlers=>[:erb, :builder]}. Searched in: + * "/home/erpe/devel/kblog/test/dummy/app/views" + * "/home/erpe/devel/kblog/app/views" +): + actionpack (3.2.12) lib/action_view/path_set.rb:58:in `find' + actionpack (3.2.12) lib/action_view/lookup_context.rb:109:in `find' + actionpack (3.2.12) lib/action_view/renderer/abstract_renderer.rb:3:in `find_template' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:34:in `determine_template' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:10:in `render' + actionpack (3.2.12) lib/action_view/renderer/renderer.rb:36:in `render_template' + actionpack (3.2.12) lib/action_view/renderer/renderer.rb:17:in `render' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:110:in `_render_template' + actionpack (3.2.12) lib/action_controller/metal/streaming.rb:225:in `_render_template' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:103:in `render_to_body' + actionpack (3.2.12) lib/action_controller/metal/renderers.rb:28:in `render_to_body' + actionpack (3.2.12) lib/action_controller/metal/compatibility.rb:50:in `render_to_body' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:88:in `render' + actionpack (3.2.12) lib/action_controller/metal/rendering.rb:16:in `render' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:40:in `block (2 levels) in render' + activesupport (3.2.12) lib/active_support/core_ext/benchmark.rb:5:in `block in ms' + /usr/local/lib/ruby/1.9.1/benchmark.rb:295:in `realtime' + activesupport (3.2.12) lib/active_support/core_ext/benchmark.rb:5:in `ms' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:40:in `block in render' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:83:in `cleanup_view_runtime' + activerecord (3.2.12) lib/active_record/railties/controller_runtime.rb:24:in `cleanup_view_runtime' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:39:in `render' + /home/erpe/devel/kblog/app/controllers/kblog/articles_controller.rb:78:in `authenticate' + activesupport (3.2.12) lib/active_support/callbacks.rb:418:in `_run__3844626742844615791__process_action__2924615651833782927__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/abstract_controller/callbacks.rb:17:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/rescue.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:30:in `block in process_action' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `block in instrument' + activesupport (3.2.12) lib/active_support/notifications/instrumenter.rb:20:in `instrument' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `instrument' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/params_wrapper.rb:207:in `process_action' + activerecord (3.2.12) lib/active_record/railties/controller_runtime.rb:18:in `process_action' + actionpack (3.2.12) lib/abstract_controller/base.rb:121:in `process' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:45:in `process' + actionpack (3.2.12) lib/action_controller/metal.rb:203:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal.rb:246:in `block in action' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' + actionpack (3.2.12) 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.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/railtie/configurable.rb:30:in `method_missing' + journey (1.0.4) lib/journey/router.rb:68:in `block in call' + journey (1.0.4) lib/journey/router.rb:56:in `each' + journey (1.0.4) lib/journey/router.rb:56:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' + rack (1.4.5) lib/rack/etag.rb:23:in `call' + rack (1.4.5) lib/rack/conditionalget.rb:35:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/head.rb:14:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/params_parser.rb:21:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/flash.rb:242:in `call' + rack (1.4.5) lib/rack/session/abstract/id.rb:210:in `context' + rack (1.4.5) lib/rack/session/abstract/id.rb:205:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/cookies.rb:341:in `call' + activerecord (3.2.12) lib/active_record/query_cache.rb:64:in `call' + activerecord (3.2.12) lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `_run__4042438081108192768__call__4418625189216720305__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:27:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/reloader.rb:65:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' + railties (3.2.12) lib/rails/rack/logger.rb:32:in `call_app' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `block in call' + activesupport (3.2.12) lib/active_support/tagged_logging.rb:22:in `tagged' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/request_id.rb:22:in `call' + rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' + rack (1.4.5) lib/rack/runtime.rb:17:in `call' + activesupport (3.2.12) lib/active_support/cache/strategy/local_cache.rb:72:in `call' + rack (1.4.5) lib/rack/lock.rb:15:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/static.rb:62:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/application.rb:223:in `call' + rack (1.4.5) lib/rack/content_length.rb:14:in `call' + railties (3.2.12) lib/rails/rack/log_tailer.rb:17:in `call' + rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' + /usr/local/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/missing_template.erb within rescues/layout (0.4ms) + + +Started GET "/" for 127.0.0.1 at 2013-03-07 16:53:08 +0100 +Connecting to database specified by database.yml + +ActionController::RoutingError (No route matches [GET] "/"): + actionpack (3.2.12) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' + railties (3.2.12) lib/rails/rack/logger.rb:32:in `call_app' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `block in call' + activesupport (3.2.12) lib/active_support/tagged_logging.rb:22:in `tagged' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/request_id.rb:22:in `call' + rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' + rack (1.4.5) lib/rack/runtime.rb:17:in `call' + activesupport (3.2.12) lib/active_support/cache/strategy/local_cache.rb:72:in `call' + rack (1.4.5) lib/rack/lock.rb:15:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/static.rb:62:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/application.rb:223:in `call' + rack (1.4.5) lib/rack/content_length.rb:14:in `call' + railties (3.2.12) lib/rails/rack/log_tailer.rb:17:in `call' + rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' + /usr/local/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (2.8ms) + + +Started GET "/kblog/articles" for 127.0.0.1 at 2013-03-07 16:53:18 +0100 +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 3 OFFSET 0 +  (0.1ms) SELECT COUNT(*) FROM "kblog_articles" + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (98.4ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/index.html.erb within layouts/kblog/application (143.9ms) +Completed 200 OK in 251ms (Views: 243.3ms | ActiveRecord: 7.0ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 16:53:18 +0100 +Served asset /kblog/application.css - 304 Not Modified (35ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 16:53:18 +0100 +Served asset /kblog/articles.css - 304 Not Modified (2ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 16:53:18 +0100 +Served asset /jquery.js - 304 Not Modified (4ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 16:53:18 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (1ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 16:53:18 +0100 +Served asset /kblog/application.js - 304 Not Modified (6ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 16:53:18 +0100 +Served asset /kblog/articles.js - 304 Not Modified (2ms) + + +Started GET "/kblog/articles" for 127.0.0.1 at 2013-03-07 16:54:27 +0100 +Connecting to database specified by database.yml +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Kblog::Article Load (0.2ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 3 OFFSET 0 +  (0.1ms) SELECT COUNT(*) FROM "kblog_articles" + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (8.0ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/index.html.erb within layouts/kblog/application (49.3ms) +Completed 200 OK in 99ms (Views: 95.3ms | ActiveRecord: 2.2ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 16:54:28 +0100 +Served asset /kblog/application.css - 304 Not Modified (5ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 16:54:28 +0100 +Served asset /kblog/articles.css - 304 Not Modified (3ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 16:54:28 +0100 +Served asset /jquery.js - 304 Not Modified (3ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 16:54:28 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (1ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 16:54:28 +0100 +Served asset /kblog/articles.js - 304 Not Modified (2ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 16:54:28 +0100 +Served asset /kblog/application.js - 304 Not Modified (5ms) + + +Started GET "/kblog/articles/new" for 127.0.0.1 at 2013-03-07 16:54:34 +0100 +Processing by Kblog::ArticlesController#new as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_form.html.erb (7.0ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/new.html.erb within layouts/kblog/application (8.9ms) +Completed 200 OK in 18ms (Views: 17.5ms | ActiveRecord: 0.0ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 16:54:34 +0100 +Served asset /kblog/application.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 16:54:34 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 16:54:34 +0100 +Served asset /jquery.js - 304 Not Modified (4ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 16:54:34 +0100 +Served asset /kblog/articles.js - 304 Not Modified (0ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 16:54:34 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 16:54:34 +0100 +Served asset /kblog/application.js - 304 Not Modified (1ms) + + +Started POST "/kblog/articles" for 127.0.0.1 at 2013-03-07 16:54:43 +0100 +Processing by Kblog::ArticlesController#create as HTML + Parameters: {"utf8"=>"✓", "authenticity_token"=>"hpOEghe52ExY/R6STy7MBrxlmBF/38o/IzHyiFCSfTM=", "article"=>{"title"=>"Pellentesque varius iaculis tortor eu dignissim. Cras cras amet.", "content"=>"Sed ultricies massa at nisi tristique mollis. Suspendisse cursus convallis nisl eget aliquam? Vestibulum aliquam elit et ipsum lobortis vel vulputate ante pharetra. Ut feugiat, ligula sit amet rutrum fermentum, ligula elit accumsan tellus, mollis vulputate orci velit eget massa. Proin rutrum tortor eget metus iaculis luctus! Duis condimentum, tortor ut amet.\r\n"}, "commit"=>"Create Article"} +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: +Completed 500 Internal Server Error in 27ms + +ActionView::MissingTemplate (Missing template kblog/articles/create, kblog/application/create with {:locale=>[:en], :formats=>[:html, :text, :js, :css, :ics, :csv, :png, :jpeg, :gif, :bmp, :tiff, :mpeg, :xml, :rss, :atom, :yaml, :multipart_form, :url_encoded_form, :json, :pdf, :zip], :handlers=>[:erb, :builder]}. Searched in: + * "/home/erpe/devel/kblog/test/dummy/app/views" + * "/home/erpe/devel/kblog/app/views" +): + actionpack (3.2.12) lib/action_view/path_set.rb:58:in `find' + actionpack (3.2.12) lib/action_view/lookup_context.rb:109:in `find' + actionpack (3.2.12) lib/action_view/renderer/abstract_renderer.rb:3:in `find_template' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:34:in `determine_template' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:10:in `render' + actionpack (3.2.12) lib/action_view/renderer/renderer.rb:36:in `render_template' + actionpack (3.2.12) lib/action_view/renderer/renderer.rb:17:in `render' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:110:in `_render_template' + actionpack (3.2.12) lib/action_controller/metal/streaming.rb:225:in `_render_template' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:103:in `render_to_body' + actionpack (3.2.12) lib/action_controller/metal/renderers.rb:28:in `render_to_body' + actionpack (3.2.12) lib/action_controller/metal/compatibility.rb:50:in `render_to_body' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:88:in `render' + actionpack (3.2.12) lib/action_controller/metal/rendering.rb:16:in `render' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:40:in `block (2 levels) in render' + activesupport (3.2.12) lib/active_support/core_ext/benchmark.rb:5:in `block in ms' + /usr/local/lib/ruby/1.9.1/benchmark.rb:295:in `realtime' + activesupport (3.2.12) lib/active_support/core_ext/benchmark.rb:5:in `ms' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:40:in `block in render' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:83:in `cleanup_view_runtime' + activerecord (3.2.12) lib/active_record/railties/controller_runtime.rb:24:in `cleanup_view_runtime' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:39:in `render' + /home/erpe/devel/kblog/app/controllers/kblog/articles_controller.rb:78:in `authenticate' + activesupport (3.2.12) lib/active_support/callbacks.rb:418:in `_run__2193433531367141375__process_action__1592199748791760400__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/abstract_controller/callbacks.rb:17:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/rescue.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:30:in `block in process_action' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `block in instrument' + activesupport (3.2.12) lib/active_support/notifications/instrumenter.rb:20:in `instrument' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `instrument' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/params_wrapper.rb:207:in `process_action' + activerecord (3.2.12) lib/active_record/railties/controller_runtime.rb:18:in `process_action' + actionpack (3.2.12) lib/abstract_controller/base.rb:121:in `process' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:45:in `process' + actionpack (3.2.12) lib/action_controller/metal.rb:203:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal.rb:246:in `block in action' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' + actionpack (3.2.12) 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.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/railtie/configurable.rb:30:in `method_missing' + journey (1.0.4) lib/journey/router.rb:68:in `block in call' + journey (1.0.4) lib/journey/router.rb:56:in `each' + journey (1.0.4) lib/journey/router.rb:56:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' + rack (1.4.5) lib/rack/etag.rb:23:in `call' + rack (1.4.5) lib/rack/conditionalget.rb:35:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/head.rb:14:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/params_parser.rb:21:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/flash.rb:242:in `call' + rack (1.4.5) lib/rack/session/abstract/id.rb:210:in `context' + rack (1.4.5) lib/rack/session/abstract/id.rb:205:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/cookies.rb:341:in `call' + activerecord (3.2.12) lib/active_record/query_cache.rb:64:in `call' + activerecord (3.2.12) lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `_run__2842342180502850940__call__570187528911297125__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:27:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/reloader.rb:65:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' + railties (3.2.12) lib/rails/rack/logger.rb:32:in `call_app' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `block in call' + activesupport (3.2.12) lib/active_support/tagged_logging.rb:22:in `tagged' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/request_id.rb:22:in `call' + rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' + rack (1.4.5) lib/rack/runtime.rb:17:in `call' + activesupport (3.2.12) lib/active_support/cache/strategy/local_cache.rb:72:in `call' + rack (1.4.5) lib/rack/lock.rb:15:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/static.rb:62:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/application.rb:223:in `call' + rack (1.4.5) lib/rack/content_length.rb:14:in `call' + railties (3.2.12) lib/rails/rack/log_tailer.rb:17:in `call' + rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' + /usr/local/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/missing_template.erb within rescues/layout (0.4ms) + + +Started GET "/kblog/articles" for 127.0.0.1 at 2013-03-07 16:55:55 +0100 +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Kblog::Article Load (0.2ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 3 OFFSET 0 +  (0.2ms) SELECT COUNT(*) FROM "kblog_articles" + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (2.4ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/index.html.erb within layouts/kblog/application (11.3ms) +Completed 200 OK in 18ms (Views: 16.2ms | ActiveRecord: 0.8ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 16:55:56 +0100 +Served asset /kblog/application.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 16:55:56 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 16:55:56 +0100 +Served asset /kblog/application.js - 304 Not Modified (1ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 16:55:56 +0100 +Served asset /kblog/articles.js - 304 Not Modified (0ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 16:55:56 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 16:55:56 +0100 +Served asset /jquery.js - 304 Not Modified (31ms) + + +Started GET "/kblog/articles/new" for 127.0.0.1 at 2013-03-07 16:55:59 +0100 +Processing by Kblog::ArticlesController#new as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_form.html.erb (6.2ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/new.html.erb within layouts/kblog/application (7.2ms) +Completed 200 OK in 13ms (Views: 12.7ms | ActiveRecord: 0.0ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 16:55:59 +0100 +Served asset /kblog/application.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 16:55:59 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 16:55:59 +0100 +Served asset /jquery.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 16:55:59 +0100 +Served asset /kblog/articles.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 16:55:59 +0100 +Served asset /kblog/application.js - 304 Not Modified (1ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 16:55:59 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started POST "/kblog/articles" for 127.0.0.1 at 2013-03-07 16:56:05 +0100 +Processing by Kblog::ArticlesController#create as HTML + Parameters: {"utf8"=>"✓", "authenticity_token"=>"hpOEghe52ExY/R6STy7MBrxlmBF/38o/IzHyiFCSfTM=", "article"=>{"title"=>"Vestibulum ullamcorper euismod est eu blandit? Nulla massa nunc.", "content"=>"Nullam vestibulum facilisis malesuada. Phasellus eu dui mauris. Praesent ut dolor ut ligula feugiat luctus eu at leo. Donec dui nunc, mollis et molestie nec, sagittis ac odio. Praesent sit amet neque at erat lobortis elementum at et tellus. Aenean lacinia purus at eros placerat a ultricies felis cursus. Vivamus metus elit, auctor et condimentum in cras amet.\r\n"}, "commit"=>"Create Article"} +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: +  (0.1ms) begin transaction + SQL (43.0ms) INSERT INTO "kblog_articles" ("content", "created_at", "title", "updated_at") VALUES (?, ?, ?, ?) [["content", "Nullam vestibulum facilisis malesuada. Phasellus eu dui mauris. Praesent ut dolor ut ligula feugiat luctus eu at leo. Donec dui nunc, mollis et molestie nec, sagittis ac odio. Praesent sit amet neque at erat lobortis elementum at et tellus. Aenean lacinia purus at eros placerat a ultricies felis cursus. Vivamus metus elit, auctor et condimentum in cras amet.\r\n"], ["created_at", Thu, 07 Mar 2013 15:56:05 UTC +00:00], ["title", "Vestibulum ullamcorper euismod est eu blandit? Nulla massa nunc."], ["updated_at", Thu, 07 Mar 2013 15:56:05 UTC +00:00]] +  (182.6ms) commit transaction +Redirected to http://localhost:3000/kblog/articles/5 +Completed 302 Found in 231ms (ActiveRecord: 225.7ms) + + +Started GET "/kblog/articles/5" for 127.0.0.1 at 2013-03-07 16:56:05 +0100 +Processing by Kblog::ArticlesController#show as HTML + Parameters: {"id"=>"5"} +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" WHERE "kblog_articles"."id" = ? LIMIT 1 [["id", "5"]] + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (1.6ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/show.html.erb within layouts/kblog/application (6.8ms) +Completed 200 OK in 15ms (Views: 13.2ms | ActiveRecord: 0.3ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 16:56:06 +0100 +Served asset /kblog/application.css - 304 Not Modified (0ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 16:56:06 +0100 +Served asset /jquery.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 16:56:06 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 16:56:06 +0100 +Served asset /kblog/articles.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 16:56:06 +0100 +Served asset /kblog/application.js - 304 Not Modified (9ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 16:56:06 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/kblog/articles/" for 127.0.0.1 at 2013-03-07 16:58:36 +0100 +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Kblog::Article Load (0.2ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 3 OFFSET 0 +  (0.1ms) SELECT COUNT(*) FROM "kblog_articles" + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (2.4ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/index.html.erb within layouts/kblog/application (11.8ms) +Completed 200 OK in 19ms (Views: 16.7ms | ActiveRecord: 0.8ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 16:58:37 +0100 +Served asset /kblog/application.css - 304 Not Modified (0ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 16:58:37 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 16:58:37 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 16:58:37 +0100 +Served asset /kblog/articles.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 16:58:37 +0100 +Served asset /kblog/application.js - 304 Not Modified (1ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 16:58:37 +0100 +Served asset /jquery.js - 304 Not Modified (0ms) + + +Started GET "/kblog/articles/new" for 127.0.0.1 at 2013-03-07 16:58:41 +0100 +Processing by Kblog::ArticlesController#new as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_form.html.erb (2.6ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/new.html.erb within layouts/kblog/application (3.5ms) +Completed 200 OK in 9ms (Views: 8.4ms | ActiveRecord: 0.0ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 16:58:41 +0100 +Served asset /kblog/application.css - 304 Not Modified (4ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 16:58:41 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 16:58:41 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 16:58:41 +0100 +Served asset /kblog/articles.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 16:58:41 +0100 +Served asset /kblog/application.js - 304 Not Modified (1ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 16:58:41 +0100 +Served asset /jquery.js - 304 Not Modified (0ms) + + +Started POST "/kblog/articles" for 127.0.0.1 at 2013-03-07 16:58:48 +0100 +Processing by Kblog::ArticlesController#create as HTML + Parameters: {"utf8"=>"✓", "authenticity_token"=>"hpOEghe52ExY/R6STy7MBrxlmBF/38o/IzHyiFCSfTM=", "article"=>{"title"=>"In porttitor nulla felis. Proin ut sapien vitae justo cras amet.", "content"=>"Pellentesque habitant morbi tristique senectus et netus et malesuada fames ac turpis egestas. Duis fermentum diam sed augue euismod et accumsan neque congue? Praesent dignissim mattis ante id convallis. Aliquam erat volutpat. Maecenas vel diam diam. Suspendisse consectetur, orci tempus vestibulum viverra, lectus justo rutrum velit; nec sollicitudin volutpat.\r\n"}, "commit"=>"Create Article"} +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: +  (0.1ms) begin transaction + SQL (0.8ms) INSERT INTO "kblog_articles" ("content", "created_at", "title", "updated_at") VALUES (?, ?, ?, ?) [["content", "Pellentesque habitant morbi tristique senectus et netus et malesuada fames ac turpis egestas. Duis fermentum diam sed augue euismod et accumsan neque congue? Praesent dignissim mattis ante id convallis. Aliquam erat volutpat. Maecenas vel diam diam. Suspendisse consectetur, orci tempus vestibulum viverra, lectus justo rutrum velit; nec sollicitudin volutpat.\r\n"], ["created_at", Thu, 07 Mar 2013 15:58:48 UTC +00:00], ["title", "In porttitor nulla felis. Proin ut sapien vitae justo cras amet."], ["updated_at", Thu, 07 Mar 2013 15:58:48 UTC +00:00]] +  (168.1ms) commit transaction +Redirected to http://localhost:3000/kblog/articles/6 +Completed 302 Found in 174ms (ActiveRecord: 169.1ms) + + +Started GET "/kblog/articles/6" for 127.0.0.1 at 2013-03-07 16:58:48 +0100 +Processing by Kblog::ArticlesController#show as HTML + Parameters: {"id"=>"6"} +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Kblog::Article Load (0.4ms) SELECT "kblog_articles".* FROM "kblog_articles" WHERE "kblog_articles"."id" = ? LIMIT 1 [["id", "6"]] + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (0.8ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/show.html.erb within layouts/kblog/application (2.6ms) +Completed 200 OK in 12ms (Views: 9.3ms | ActiveRecord: 0.4ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 16:58:48 +0100 +Served asset /kblog/application.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 16:58:48 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 16:58:48 +0100 +Served asset /jquery.js - 304 Not Modified (1ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 16:58:48 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 16:58:48 +0100 +Served asset /kblog/articles.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 16:58:48 +0100 +Served asset /kblog/application.js - 304 Not Modified (1ms) + + +Started GET "/kblog/articles/" for 127.0.0.1 at 2013-03-07 16:59:00 +0100 +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 3 OFFSET 0 +  (0.1ms) SELECT COUNT(*) FROM "kblog_articles" + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (1.7ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/index.html.erb within layouts/kblog/application (5.4ms) +Completed 200 OK in 12ms (Views: 11.1ms | ActiveRecord: 0.4ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 16:59:00 +0100 +Served asset /kblog/application.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 16:59:00 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 16:59:00 +0100 +Served asset /kblog/articles.js - 304 Not Modified (0ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 16:59:00 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 16:59:00 +0100 +Served asset /jquery.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 16:59:00 +0100 +Served asset /kblog/application.js - 304 Not Modified (48ms) + + +Started GET "/kblog/articles/" for 127.0.0.1 at 2013-03-07 16:59:49 +0100 +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 3 OFFSET 0 +  (0.2ms) SELECT COUNT(*) FROM "kblog_articles" + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (2.0ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/index.html.erb within layouts/kblog/application (6.8ms) +Completed 200 OK in 13ms (Views: 11.8ms | ActiveRecord: 0.5ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 16:59:49 +0100 +Served asset /kblog/application.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 16:59:49 +0100 +Served asset /kblog/articles.js - 304 Not Modified (0ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 16:59:49 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 16:59:49 +0100 +Served asset /jquery.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 16:59:49 +0100 +Served asset /kblog/application.js - 304 Not Modified (1ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 16:59:49 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/kblog/articles/new" for 127.0.0.1 at 2013-03-07 16:59:51 +0100 +Processing by Kblog::ArticlesController#new as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_form.html.erb (1.8ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/new.html.erb within layouts/kblog/application (2.6ms) +Completed 200 OK in 8ms (Views: 7.5ms | ActiveRecord: 0.0ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 16:59:51 +0100 +Served asset /kblog/application.css - 304 Not Modified (0ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 16:59:51 +0100 +Served asset /jquery.js - 304 Not Modified (0ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 16:59:51 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 16:59:51 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 16:59:51 +0100 +Served asset /kblog/articles.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 16:59:51 +0100 +Served asset /kblog/application.js - 304 Not Modified (1ms) + + +Started POST "/kblog/articles" for 127.0.0.1 at 2013-03-07 16:59:57 +0100 +Processing by Kblog::ArticlesController#create as HTML + Parameters: {"utf8"=>"✓", "authenticity_token"=>"hpOEghe52ExY/R6STy7MBrxlmBF/38o/IzHyiFCSfTM=", "article"=>{"title"=>"Phasellus malesuada; mauris non ultricies mattis, elit volutpat.", "content"=>"Curabitur iaculis vehicula tellus at feugiat. Etiam massa dui, scelerisque sit amet consectetur venenatis; ullamcorper sit amet odio? Ut vitae ipsum neque. Aenean aliquam; metus sit amet vulputate volutpat, odio metus aliquet nisl, quis ultrices erat erat id lorem. Mauris vulputate, erat euismod pretium hendrerit, orci libero blandit velit, sit amet posuere.\r\n"}, "commit"=>"Create Article"} +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: +  (0.1ms) begin transaction + SQL (0.7ms) INSERT INTO "kblog_articles" ("content", "created_at", "title", "updated_at") VALUES (?, ?, ?, ?) [["content", "Curabitur iaculis vehicula tellus at feugiat. Etiam massa dui, scelerisque sit amet consectetur venenatis; ullamcorper sit amet odio? Ut vitae ipsum neque. Aenean aliquam; metus sit amet vulputate volutpat, odio metus aliquet nisl, quis ultrices erat erat id lorem. Mauris vulputate, erat euismod pretium hendrerit, orci libero blandit velit, sit amet posuere.\r\n"], ["created_at", Thu, 07 Mar 2013 15:59:57 UTC +00:00], ["title", "Phasellus malesuada; mauris non ultricies mattis, elit volutpat."], ["updated_at", Thu, 07 Mar 2013 15:59:57 UTC +00:00]] +  (149.0ms) commit transaction +Redirected to http://localhost:3000/kblog/articles/7 +Completed 302 Found in 152ms (ActiveRecord: 149.8ms) + + +Started GET "/kblog/articles/7" for 127.0.0.1 at 2013-03-07 16:59:57 +0100 +Processing by Kblog::ArticlesController#show as HTML + Parameters: {"id"=>"7"} +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Kblog::Article Load (0.2ms) SELECT "kblog_articles".* FROM "kblog_articles" WHERE "kblog_articles"."id" = ? LIMIT 1 [["id", "7"]] + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (0.9ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/show.html.erb within layouts/kblog/application (1.8ms) +Completed 200 OK in 10ms (Views: 8.0ms | ActiveRecord: 0.2ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 16:59:58 +0100 +Served asset /kblog/application.css - 304 Not Modified (2ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 16:59:58 +0100 +Served asset /kblog/articles.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 16:59:58 +0100 +Served asset /kblog/application.js - 304 Not Modified (1ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 16:59:58 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 16:59:58 +0100 +Served asset /jquery.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 16:59:58 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/kblog/articles/" for 127.0.0.1 at 2013-03-07 17:00:00 +0100 +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 3 OFFSET 0 +  (0.1ms) SELECT COUNT(*) FROM "kblog_articles" + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (1.9ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/index.html.erb within layouts/kblog/application (5.9ms) +Completed 200 OK in 13ms (Views: 12.3ms | ActiveRecord: 0.4ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 17:00:01 +0100 +Served asset /kblog/application.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 17:00:01 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 17:00:01 +0100 +Served asset /kblog/application.js - 304 Not Modified (1ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 17:00:01 +0100 +Served asset /jquery.js - 304 Not Modified (1ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 17:00:01 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 17:00:01 +0100 +Served asset /kblog/articles.js - 304 Not Modified (0ms) + + +Started GET "/kblog/?page=2" for 127.0.0.1 at 2013-03-07 17:00:03 +0100 +Processing by Kblog::ArticlesController#index as HTML + Parameters: {"page"=>"2"} +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 3 OFFSET 3 +  (0.1ms) SELECT COUNT(*) FROM "kblog_articles" + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (2.5ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/index.html.erb within layouts/kblog/application (6.2ms) +Completed 200 OK in 12ms (Views: 11.3ms | ActiveRecord: 0.4ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 17:00:03 +0100 +Served asset /kblog/application.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 17:00:03 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 17:00:03 +0100 +Served asset /kblog/application.js - 304 Not Modified (4ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 17:00:03 +0100 +Served asset /kblog/articles.js - 304 Not Modified (1ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 17:00:03 +0100 +Served asset /jquery.js - 304 Not Modified (0ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 17:00:03 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/kblog/?page=1" for 127.0.0.1 at 2013-03-07 17:00:08 +0100 +Processing by Kblog::ArticlesController#index as HTML + Parameters: {"page"=>"1"} +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 3 OFFSET 0 +  (0.1ms) SELECT COUNT(*) FROM "kblog_articles" + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (2.6ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/index.html.erb within layouts/kblog/application (7.1ms) +Completed 200 OK in 13ms (Views: 12.3ms | ActiveRecord: 0.4ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 17:00:08 +0100 +Served asset /kblog/application.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 17:00:08 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 17:00:08 +0100 +Served asset /kblog/application.js - 304 Not Modified (1ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 17:00:08 +0100 +Served asset /jquery.js - 304 Not Modified (1ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 17:00:08 +0100 +Served asset /kblog/articles.js - 304 Not Modified (0ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 17:00:08 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/kblog/articles" for 127.0.0.1 at 2013-03-07 17:00:28 +0100 +Connecting to database specified by database.yml +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 3 OFFSET 0 +  (0.1ms) SELECT COUNT(*) FROM "kblog_articles" + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (6.9ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/index.html.erb within layouts/kblog/application (49.3ms) +Completed 200 OK in 99ms (Views: 95.5ms | ActiveRecord: 2.3ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 17:00:29 +0100 +Served asset /kblog/application.css - 304 Not Modified (5ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 17:00:29 +0100 +Served asset /kblog/articles.css - 304 Not Modified (2ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 17:00:29 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (1ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 17:00:29 +0100 +Served asset /kblog/articles.js - 304 Not Modified (2ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 17:00:29 +0100 +Served asset /jquery.js - 304 Not Modified (3ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 17:00:29 +0100 +Served asset /kblog/application.js - 304 Not Modified (5ms) + + +Started GET "/kblog/articles/new" for 127.0.0.1 at 2013-03-07 17:00:31 +0100 +Processing by Kblog::ArticlesController#new as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_form.html.erb (4.4ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/new.html.erb within layouts/kblog/application (6.1ms) +Completed 200 OK in 13ms (Views: 12.0ms | ActiveRecord: 0.0ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 17:00:31 +0100 +Served asset /kblog/application.css - 304 Not Modified (2ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 17:00:31 +0100 +Served asset /jquery.js - 304 Not Modified (3ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 17:00:31 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 17:00:31 +0100 +Served asset /kblog/articles.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 17:00:31 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 17:00:31 +0100 +Served asset /kblog/application.js - 304 Not Modified (1ms) + + +Started POST "/kblog/articles" for 127.0.0.1 at 2013-03-07 17:00:39 +0100 +Processing by Kblog::ArticlesController#create as HTML + Parameters: {"utf8"=>"✓", "authenticity_token"=>"hpOEghe52ExY/R6STy7MBrxlmBF/38o/IzHyiFCSfTM=", "article"=>{"title"=>"Donec porta tempus sem non posuere. Etiam mattis nisl in nullam.", "content"=>"Nullam et rhoncus neque. Cras tincidunt, dui non molestie elementum, nisi turpis lobortis nulla, nec aliquam nulla nisl ut tortor. Sed odio leo, placerat vel porta quis, varius non metus. Phasellus gravida accumsan euismod. Vestibulum lacus nulla, consectetur a mollis eu, hendrerit scelerisque felis! Proin enim lectus, fermentum sit amet rhoncus at volutpat.\r\n"}, "commit"=>"Create Article"} +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: +  (0.1ms) begin transaction + SQL (6.6ms) INSERT INTO "kblog_articles" ("content", "created_at", "title", "updated_at") VALUES (?, ?, ?, ?) [["content", "Nullam et rhoncus neque. Cras tincidunt, dui non molestie elementum, nisi turpis lobortis nulla, nec aliquam nulla nisl ut tortor. Sed odio leo, placerat vel porta quis, varius non metus. Phasellus gravida accumsan euismod. Vestibulum lacus nulla, consectetur a mollis eu, hendrerit scelerisque felis! Proin enim lectus, fermentum sit amet rhoncus at volutpat.\r\n"], ["created_at", Thu, 07 Mar 2013 16:00:39 UTC +00:00], ["title", "Donec porta tempus sem non posuere. Etiam mattis nisl in nullam."], ["updated_at", Thu, 07 Mar 2013 16:00:39 UTC +00:00]] +  (161.8ms) commit transaction +Redirected to http://localhost:3000/kblog/articles/8 +Completed 302 Found in 173ms (ActiveRecord: 168.4ms) + + +Started GET "/kblog/articles/8" for 127.0.0.1 at 2013-03-07 17:00:39 +0100 +Processing by Kblog::ArticlesController#show as HTML + Parameters: {"id"=>"8"} +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Kblog::Article Load (0.4ms) SELECT "kblog_articles".* FROM "kblog_articles" WHERE "kblog_articles"."id" = ? LIMIT 1 [["id", "8"]] + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (1.4ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/show.html.erb within layouts/kblog/application (2.7ms) +Completed 200 OK in 12ms (Views: 9.6ms | ActiveRecord: 0.4ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 17:00:39 +0100 +Served asset /kblog/application.css - 304 Not Modified (1ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 17:00:39 +0100 +Served asset /kblog/articles.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 17:00:39 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 17:00:39 +0100 +Served asset /kblog/application.js - 304 Not Modified (1ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 17:00:39 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 17:00:39 +0100 +Served asset /jquery.js - 304 Not Modified (0ms) + + +Started GET "/kblog/articles/8" for 127.0.0.1 at 2013-03-07 17:01:36 +0100 +Connecting to database specified by database.yml +Processing by Kblog::ArticlesController#show as HTML + Parameters: {"id"=>"8"} +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Kblog::Article Load (0.2ms) SELECT "kblog_articles".* FROM "kblog_articles" WHERE "kblog_articles"."id" = ? LIMIT 1 [["id", "8"]] + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (24.4ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/show.html.erb within layouts/kblog/application (55.1ms) +Completed 200 OK in 114ms (Views: 77.6ms | ActiveRecord: 28.2ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 17:01:37 +0100 +Served asset /kblog/application.css - 304 Not Modified (3ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 17:01:37 +0100 +Served asset /kblog/articles.css - 304 Not Modified (2ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 17:01:37 +0100 +Served asset /jquery.js - 304 Not Modified (7ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 17:01:37 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (1ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 17:01:37 +0100 +Served asset /kblog/articles.js - 304 Not Modified (2ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 17:01:37 +0100 +Served asset /kblog/application.js - 304 Not Modified (6ms) + + +Started GET "/kblog/articles/" for 127.0.0.1 at 2013-03-07 17:01:39 +0100 +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 3 OFFSET 0 +  (0.2ms) SELECT COUNT(*) FROM "kblog_articles"  + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (5.2ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/index.html.erb within layouts/kblog/application (11.0ms) +Completed 200 OK in 17ms (Views: 15.8ms | ActiveRecord: 0.5ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 17:01:39 +0100 +Served asset /kblog/application.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 17:01:39 +0100 +Served asset /kblog/application.js - 304 Not Modified (41ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 17:01:39 +0100 +Served asset /kblog/articles.js - 304 Not Modified (0ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 17:01:39 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 17:01:39 +0100 +Served asset /jquery.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 17:01:39 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/kblog/articles/new" for 127.0.0.1 at 2013-03-07 17:01:41 +0100 +Processing by Kblog::ArticlesController#new as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: +Filter chain halted as #<Proc:0x007f0fa0d1d718@/usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_controller/metal/http_authentication.rb:112> rendered or redirected +Completed 401 Unauthorized in 1ms (ActiveRecord: 0.0ms) + + +Started GET "/kblog/articles/" for 127.0.0.1 at 2013-03-07 17:02:35 +0100 +Connecting to database specified by database.yml +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 3 OFFSET 0 +  (0.1ms) SELECT COUNT(*) FROM "kblog_articles" + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (7.0ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/index.html.erb within layouts/kblog/application (49.7ms) +Completed 200 OK in 100ms (Views: 96.2ms | ActiveRecord: 2.3ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 17:02:35 +0100 +Served asset /kblog/application.css - 304 Not Modified (3ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 17:02:35 +0100 +Served asset /kblog/articles.css - 304 Not Modified (2ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 17:02:35 +0100 +Served asset /jquery.js - 304 Not Modified (2ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 17:02:35 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (1ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 17:02:35 +0100 +Served asset /kblog/articles.js - 304 Not Modified (1ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 17:02:35 +0100 +Served asset /kblog/application.js - 304 Not Modified (14ms) + + +Started GET "/kblog/articles/new" for 127.0.0.1 at 2013-03-07 17:02:38 +0100 +Processing by Kblog::ArticlesController#new as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_form.html.erb (3.2ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/new.html.erb within layouts/kblog/application (4.4ms) +Completed 200 OK in 11ms (Views: 9.9ms | ActiveRecord: 0.0ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 17:02:38 +0100 +Served asset /kblog/application.css - 304 Not Modified (1ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 17:02:38 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 17:02:38 +0100 +Served asset /kblog/articles.js - 304 Not Modified (0ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 17:02:38 +0100 +Served asset /jquery.js - 304 Not Modified (0ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 17:02:38 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 17:02:38 +0100 +Served asset /kblog/application.js - 304 Not Modified (1ms) + + +Started POST "/kblog/articles" for 127.0.0.1 at 2013-03-07 17:02:46 +0100 +Processing by Kblog::ArticlesController#create as HTML + Parameters: {"utf8"=>"✓", "authenticity_token"=>"hpOEghe52ExY/R6STy7MBrxlmBF/38o/IzHyiFCSfTM=", "article"=>{"title"=>"Maecenas mattis neque condimentum magna vehicula ac turpis duis.", "content"=>"Sed id lacus sed diam posuere ultrices a at dui. Donec erat ligula, placerat eget volutpat a, hendrerit vitae quam. In hac habitasse platea dictumst. Cum sociis natoque penatibus et magnis dis parturient montes, nascetur ridiculus mus. Mauris dignissim, est sed pellentesque imperdiet, nibh dui ullamcorper dolor; eu molestie turpis purus a libero turpis duis.\r\n"}, "commit"=>"Create Article"} +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: +Completed 500 Internal Server Error in 12ms + +ActionView::MissingTemplate (Missing template kblog/articles/create, kblog/application/create with {:locale=>[:en], :formats=>[:html, :text, :js, :css, :ics, :csv, :png, :jpeg, :gif, :bmp, :tiff, :mpeg, :xml, :rss, :atom, :yaml, :multipart_form, :url_encoded_form, :json, :pdf, :zip], :handlers=>[:erb, :builder]}. Searched in: + * "/home/erpe/devel/kblog/test/dummy/app/views" + * "/home/erpe/devel/kblog/app/views" +): + actionpack (3.2.12) lib/action_view/path_set.rb:58:in `find' + actionpack (3.2.12) lib/action_view/lookup_context.rb:109:in `find' + actionpack (3.2.12) lib/action_view/renderer/abstract_renderer.rb:3:in `find_template' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:34:in `determine_template' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:10:in `render' + actionpack (3.2.12) lib/action_view/renderer/renderer.rb:36:in `render_template' + actionpack (3.2.12) lib/action_view/renderer/renderer.rb:17:in `render' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:110:in `_render_template' + actionpack (3.2.12) lib/action_controller/metal/streaming.rb:225:in `_render_template' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:103:in `render_to_body' + actionpack (3.2.12) lib/action_controller/metal/renderers.rb:28:in `render_to_body' + actionpack (3.2.12) lib/action_controller/metal/compatibility.rb:50:in `render_to_body' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:88:in `render' + actionpack (3.2.12) lib/action_controller/metal/rendering.rb:16:in `render' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:40:in `block (2 levels) in render' + activesupport (3.2.12) lib/active_support/core_ext/benchmark.rb:5:in `block in ms' + /usr/local/lib/ruby/1.9.1/benchmark.rb:295:in `realtime' + activesupport (3.2.12) lib/active_support/core_ext/benchmark.rb:5:in `ms' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:40:in `block in render' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:83:in `cleanup_view_runtime' + activerecord (3.2.12) lib/active_record/railties/controller_runtime.rb:24:in `cleanup_view_runtime' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:39:in `render' + /home/erpe/devel/kblog/app/controllers/kblog/articles_controller.rb:82:in `authenticate' + activesupport (3.2.12) lib/active_support/callbacks.rb:418:in `_run__483044901279044810__process_action__4373632998908216725__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/abstract_controller/callbacks.rb:17:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/rescue.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:30:in `block in process_action' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `block in instrument' + activesupport (3.2.12) lib/active_support/notifications/instrumenter.rb:20:in `instrument' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `instrument' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/params_wrapper.rb:207:in `process_action' + activerecord (3.2.12) lib/active_record/railties/controller_runtime.rb:18:in `process_action' + actionpack (3.2.12) lib/abstract_controller/base.rb:121:in `process' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:45:in `process' + actionpack (3.2.12) lib/action_controller/metal.rb:203:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal.rb:246:in `block in action' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' + actionpack (3.2.12) 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.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/railtie/configurable.rb:30:in `method_missing' + journey (1.0.4) lib/journey/router.rb:68:in `block in call' + journey (1.0.4) lib/journey/router.rb:56:in `each' + journey (1.0.4) lib/journey/router.rb:56:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' + rack (1.4.5) lib/rack/etag.rb:23:in `call' + rack (1.4.5) lib/rack/conditionalget.rb:35:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/head.rb:14:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/params_parser.rb:21:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/flash.rb:242:in `call' + rack (1.4.5) lib/rack/session/abstract/id.rb:210:in `context' + rack (1.4.5) lib/rack/session/abstract/id.rb:205:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/cookies.rb:341:in `call' + activerecord (3.2.12) lib/active_record/query_cache.rb:64:in `call' + activerecord (3.2.12) lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `_run__3176685302267864909__call__518594377563964172__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:27:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/reloader.rb:65:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' + railties (3.2.12) lib/rails/rack/logger.rb:32:in `call_app' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `block in call' + activesupport (3.2.12) lib/active_support/tagged_logging.rb:22:in `tagged' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/request_id.rb:22:in `call' + rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' + rack (1.4.5) lib/rack/runtime.rb:17:in `call' + activesupport (3.2.12) lib/active_support/cache/strategy/local_cache.rb:72:in `call' + rack (1.4.5) lib/rack/lock.rb:15:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/static.rb:62:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/application.rb:223:in `call' + rack (1.4.5) lib/rack/content_length.rb:14:in `call' + railties (3.2.12) lib/rails/rack/log_tailer.rb:17:in `call' + rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' + /usr/local/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/missing_template.erb within rescues/layout (0.4ms) + + +Started GET "/kblog/articles" for 127.0.0.1 at 2013-03-07 17:04:17 +0100 +Connecting to database specified by database.yml +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 3 OFFSET 0 +  (0.1ms) SELECT COUNT(*) FROM "kblog_articles" + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (7.3ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/index.html.erb within layouts/kblog/application (49.9ms) +Completed 200 OK in 100ms (Views: 96.6ms | ActiveRecord: 2.2ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 17:04:17 +0100 +Served asset /kblog/application.css - 304 Not Modified (3ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 17:04:18 +0100 +Served asset /kblog/articles.css - 304 Not Modified (5ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 17:04:18 +0100 +Served asset /jquery.js - 304 Not Modified (5ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 17:04:18 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (3ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 17:04:18 +0100 +Served asset /kblog/articles.js - 304 Not Modified (2ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 17:04:18 +0100 +Served asset /kblog/application.js - 304 Not Modified (6ms) + + +Started GET "/kblog/articles/new" for 127.0.0.1 at 2013-03-07 17:04:19 +0100 +Processing by Kblog::ArticlesController#new as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_form.html.erb (4.0ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/new.html.erb within layouts/kblog/application (5.6ms) +Completed 200 OK in 12ms (Views: 11.0ms | ActiveRecord: 0.0ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 17:04:19 +0100 +Served asset /kblog/application.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 17:04:19 +0100 +Served asset /kblog/articles.js - 304 Not Modified (0ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 17:04:19 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 17:04:19 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 17:04:19 +0100 +Served asset /kblog/application.js - 304 Not Modified (1ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 17:04:19 +0100 +Served asset /jquery.js - 304 Not Modified (1ms) + + +Started POST "/kblog/articles" for 127.0.0.1 at 2013-03-07 17:04:27 +0100 +Processing by Kblog::ArticlesController#create as HTML + Parameters: {"utf8"=>"✓", "authenticity_token"=>"hpOEghe52ExY/R6STy7MBrxlmBF/38o/IzHyiFCSfTM=", "article"=>{"title"=>"Sed sapien nibh, ullamcorper at vehicula sed, tempor sit nullam.", "content"=>"Maecenas venenatis mi quis lectus condimentum egestas? Integer lorem dui; ullamcorper vitae feugiat sed, elementum pulvinar leo. Cum sociis natoque penatibus et magnis dis parturient montes, nascetur ridiculus mus. Vivamus magna nisi; venenatis vel euismod a, gravida et urna. In aliquet ultrices pharetra. Nam laoreet, nunc sit amet laoreet tincidunt posuere.\r\n"}, "commit"=>"Create Article"} +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: +Redirected to http://localhost:3000/kblog/articles/new +Filter chain halted as :authenticate rendered or redirected +Completed 302 Found in 1ms (ActiveRecord: 0.0ms) + + +Started GET "/kblog/articles/new" for 127.0.0.1 at 2013-03-07 17:04:28 +0100 +Processing by Kblog::ArticlesController#new as HTML +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#set_blog_user - current_user: + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_form.html.erb (2.9ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/new.html.erb within layouts/kblog/application (4.0ms) +Completed 200 OK in 11ms (Views: 10.6ms | ActiveRecord: 0.0ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 17:04:28 +0100 +Served asset /kblog/application.css - 304 Not Modified (0ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 17:04:28 +0100 +Served asset /jquery.js - 304 Not Modified (0ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 17:04:28 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 17:04:28 +0100 +Served asset /kblog/articles.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 17:04:28 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 17:04:28 +0100 +Served asset /kblog/application.js - 304 Not Modified (10ms) + + +Started GET "/?locale=de" for 127.0.0.1 at 2013-03-07 17:29:51 +0100 +Connecting to database specified by database.yml + +ActionController::RoutingError (No route matches [GET] "/"): + actionpack (3.2.12) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' + railties (3.2.12) lib/rails/rack/logger.rb:32:in `call_app' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `block in call' + activesupport (3.2.12) lib/active_support/tagged_logging.rb:22:in `tagged' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/request_id.rb:22:in `call' + rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' + rack (1.4.5) lib/rack/runtime.rb:17:in `call' + activesupport (3.2.12) lib/active_support/cache/strategy/local_cache.rb:72:in `call' + rack (1.4.5) lib/rack/lock.rb:15:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/static.rb:62:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/application.rb:223:in `call' + rack (1.4.5) lib/rack/content_length.rb:14:in `call' + railties (3.2.12) lib/rails/rack/log_tailer.rb:17:in `call' + rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' + /usr/local/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (2.5ms) + + +Started GET "/kblog/articles" for 127.0.0.1 at 2013-03-07 17:29:58 +0100 + +LoadError (No such file to load -- kblog/application_controller): + activesupport (3.2.12) lib/active_support/dependencies.rb:317:in `rescue in depend_on' + activesupport (3.2.12) lib/active_support/dependencies.rb:312:in `depend_on' + activesupport (3.2.12) lib/active_support/dependencies.rb:225:in `require_dependency' + /home/erpe/devel/kblog/app/controllers/kblog/articles_controller.rb:1:in `<top (required)>' + activesupport (3.2.12) lib/active_support/dependencies.rb:469:in `load' + activesupport (3.2.12) lib/active_support/dependencies.rb:469:in `block in load_file' + activesupport (3.2.12) lib/active_support/dependencies.rb:639:in `new_constants_in' + activesupport (3.2.12) lib/active_support/dependencies.rb:468:in `load_file' + activesupport (3.2.12) lib/active_support/dependencies.rb:353:in `require_or_load' + activesupport (3.2.12) lib/active_support/dependencies.rb:502:in `load_missing_constant' + activesupport (3.2.12) lib/active_support/dependencies.rb:192:in `block in const_missing' + activesupport (3.2.12) lib/active_support/dependencies.rb:190:in `each' + activesupport (3.2.12) lib/active_support/dependencies.rb:190:in `const_missing' + activesupport (3.2.12) lib/active_support/inflector/methods.rb:230:in `block in constantize' + activesupport (3.2.12) lib/active_support/inflector/methods.rb:229:in `each' + activesupport (3.2.12) lib/active_support/inflector/methods.rb:229:in `constantize' + activesupport (3.2.12) lib/active_support/dependencies.rb:554:in `get' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:69:in `controller_reference' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:54:in `controller' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:32: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.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/railtie/configurable.rb:30:in `method_missing' + journey (1.0.4) lib/journey/router.rb:68:in `block in call' + journey (1.0.4) lib/journey/router.rb:56:in `each' + journey (1.0.4) lib/journey/router.rb:56:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' + rack (1.4.5) lib/rack/etag.rb:23:in `call' + rack (1.4.5) lib/rack/conditionalget.rb:25:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/head.rb:14:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/params_parser.rb:21:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/flash.rb:242:in `call' + rack (1.4.5) lib/rack/session/abstract/id.rb:210:in `context' + rack (1.4.5) lib/rack/session/abstract/id.rb:205:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/cookies.rb:341:in `call' + activerecord (3.2.12) lib/active_record/query_cache.rb:64:in `call' + activerecord (3.2.12) lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `_run__3613979613058399932__call__1799227182409900727__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:27:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/reloader.rb:65:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' + railties (3.2.12) lib/rails/rack/logger.rb:32:in `call_app' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `block in call' + activesupport (3.2.12) lib/active_support/tagged_logging.rb:22:in `tagged' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/request_id.rb:22:in `call' + rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' + rack (1.4.5) lib/rack/runtime.rb:17:in `call' + activesupport (3.2.12) lib/active_support/cache/strategy/local_cache.rb:72:in `call' + rack (1.4.5) lib/rack/lock.rb:15:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/static.rb:62:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/application.rb:223:in `call' + rack (1.4.5) lib/rack/content_length.rb:14:in `call' + railties (3.2.12) lib/rails/rack/log_tailer.rb:17:in `call' + rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' + /usr/local/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.1ms) + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (0.9ms) + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (7.2ms) + + +Started GET "/kblog/articles" for 127.0.0.1 at 2013-03-07 17:31:02 +0100 +Connecting to database specified by database.yml +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start +Completed 500 Internal Server Error in 2ms + +NameError (undefined local variable or method `current_user' for #<Kblog::ArticlesController:0x007f925cceee38>): + /home/erpe/devel/kblog/app/controllers/kblog/articles_controller.rb:65:in `set_blog_user' + activesupport (3.2.12) lib/active_support/callbacks.rb:418:in `_run__1881970651038010329__process_action__2425746614651328776__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/abstract_controller/callbacks.rb:17:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/rescue.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:30:in `block in process_action' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `block in instrument' + activesupport (3.2.12) lib/active_support/notifications/instrumenter.rb:20:in `instrument' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `instrument' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/params_wrapper.rb:207:in `process_action' + activerecord (3.2.12) lib/active_record/railties/controller_runtime.rb:18:in `process_action' + actionpack (3.2.12) lib/abstract_controller/base.rb:121:in `process' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:45:in `process' + actionpack (3.2.12) lib/action_controller/metal.rb:203:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal.rb:246:in `block in action' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' + actionpack (3.2.12) 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.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/railtie/configurable.rb:30:in `method_missing' + journey (1.0.4) lib/journey/router.rb:68:in `block in call' + journey (1.0.4) lib/journey/router.rb:56:in `each' + journey (1.0.4) lib/journey/router.rb:56:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' + rack (1.4.5) lib/rack/etag.rb:23:in `call' + rack (1.4.5) lib/rack/conditionalget.rb:25:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/head.rb:14:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/params_parser.rb:21:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/flash.rb:242:in `call' + rack (1.4.5) lib/rack/session/abstract/id.rb:210:in `context' + rack (1.4.5) lib/rack/session/abstract/id.rb:205:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/cookies.rb:341:in `call' + activerecord (3.2.12) lib/active_record/query_cache.rb:64:in `call' + activerecord (3.2.12) lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `_run__3616515490580497985__call__2885681611294726745__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:27:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/reloader.rb:65:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' + railties (3.2.12) lib/rails/rack/logger.rb:32:in `call_app' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `block in call' + activesupport (3.2.12) lib/active_support/tagged_logging.rb:22:in `tagged' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/request_id.rb:22:in `call' + rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' + rack (1.4.5) lib/rack/runtime.rb:17:in `call' + activesupport (3.2.12) lib/active_support/cache/strategy/local_cache.rb:72:in `call' + rack (1.4.5) lib/rack/lock.rb:15:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/static.rb:62:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/application.rb:223:in `call' + rack (1.4.5) lib/rack/content_length.rb:14:in `call' + railties (3.2.12) lib/rails/rack/log_tailer.rb:17:in `call' + rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' + /usr/local/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.2ms) + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (0.8ms) + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (10.0ms) + + +Started GET "/kblog/articles" for 127.0.0.1 at 2013-03-07 17:33:47 +0100 +Connecting to database specified by database.yml +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start +Completed 500 Internal Server Error in 2ms + +NameError (undefined local variable or method `current_user' for #<Kblog::ArticlesController:0x007f6cbc9288c8>): + /home/erpe/devel/kblog/app/controllers/kblog/articles_controller.rb:65:in `set_blog_user' + activesupport (3.2.12) lib/active_support/callbacks.rb:418:in `_run__1618667733515774426__process_action__426894716345873807__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/abstract_controller/callbacks.rb:17:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/rescue.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:30:in `block in process_action' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `block in instrument' + activesupport (3.2.12) lib/active_support/notifications/instrumenter.rb:20:in `instrument' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `instrument' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/params_wrapper.rb:207:in `process_action' + activerecord (3.2.12) lib/active_record/railties/controller_runtime.rb:18:in `process_action' + actionpack (3.2.12) lib/abstract_controller/base.rb:121:in `process' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:45:in `process' + actionpack (3.2.12) lib/action_controller/metal.rb:203:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal.rb:246:in `block in action' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' + actionpack (3.2.12) 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.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/railtie/configurable.rb:30:in `method_missing' + journey (1.0.4) lib/journey/router.rb:68:in `block in call' + journey (1.0.4) lib/journey/router.rb:56:in `each' + journey (1.0.4) lib/journey/router.rb:56:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' + rack (1.4.5) lib/rack/etag.rb:23:in `call' + rack (1.4.5) lib/rack/conditionalget.rb:25:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/head.rb:14:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/params_parser.rb:21:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/flash.rb:242:in `call' + rack (1.4.5) lib/rack/session/abstract/id.rb:210:in `context' + rack (1.4.5) lib/rack/session/abstract/id.rb:205:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/cookies.rb:341:in `call' + activerecord (3.2.12) lib/active_record/query_cache.rb:64:in `call' + activerecord (3.2.12) lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `_run__1406634743418440215__call__2041462277902100961__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:27:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/reloader.rb:65:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' + railties (3.2.12) lib/rails/rack/logger.rb:32:in `call_app' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `block in call' + activesupport (3.2.12) lib/active_support/tagged_logging.rb:22:in `tagged' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/request_id.rb:22:in `call' + rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' + rack (1.4.5) lib/rack/runtime.rb:17:in `call' + activesupport (3.2.12) lib/active_support/cache/strategy/local_cache.rb:72:in `call' + rack (1.4.5) lib/rack/lock.rb:15:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/static.rb:62:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/application.rb:223:in `call' + rack (1.4.5) lib/rack/content_length.rb:14:in `call' + railties (3.2.12) lib/rails/rack/log_tailer.rb:17:in `call' + rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' + /usr/local/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.2ms) + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (0.9ms) + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (9.8ms) + + +Started GET "/kblog/articles" for 127.0.0.1 at 2013-03-07 17:35:41 +0100 +Connecting to database specified by database.yml +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start +Completed 500 Internal Server Error in 2ms + +NameError (undefined local variable or method `current_user' for #<Kblog::ArticlesController:0x007f5ff4bd8958>): + /home/erpe/devel/kblog/app/controllers/kblog/articles_controller.rb:65:in `set_blog_user' + activesupport (3.2.12) lib/active_support/callbacks.rb:418:in `_run__1099818644059037180__process_action__1061034940305078763__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/abstract_controller/callbacks.rb:17:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/rescue.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:30:in `block in process_action' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `block in instrument' + activesupport (3.2.12) lib/active_support/notifications/instrumenter.rb:20:in `instrument' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `instrument' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/params_wrapper.rb:207:in `process_action' + activerecord (3.2.12) lib/active_record/railties/controller_runtime.rb:18:in `process_action' + actionpack (3.2.12) lib/abstract_controller/base.rb:121:in `process' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:45:in `process' + actionpack (3.2.12) lib/action_controller/metal.rb:203:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal.rb:246:in `block in action' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' + actionpack (3.2.12) 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.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/railtie/configurable.rb:30:in `method_missing' + journey (1.0.4) lib/journey/router.rb:68:in `block in call' + journey (1.0.4) lib/journey/router.rb:56:in `each' + journey (1.0.4) lib/journey/router.rb:56:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' + rack (1.4.5) lib/rack/etag.rb:23:in `call' + rack (1.4.5) lib/rack/conditionalget.rb:25:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/head.rb:14:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/params_parser.rb:21:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/flash.rb:242:in `call' + rack (1.4.5) lib/rack/session/abstract/id.rb:210:in `context' + rack (1.4.5) lib/rack/session/abstract/id.rb:205:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/cookies.rb:341:in `call' + activerecord (3.2.12) lib/active_record/query_cache.rb:64:in `call' + activerecord (3.2.12) lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `_run__426147494644860608__call__4552665452713301372__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:27:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/reloader.rb:65:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' + railties (3.2.12) lib/rails/rack/logger.rb:32:in `call_app' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `block in call' + activesupport (3.2.12) lib/active_support/tagged_logging.rb:22:in `tagged' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/request_id.rb:22:in `call' + rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' + rack (1.4.5) lib/rack/runtime.rb:17:in `call' + activesupport (3.2.12) lib/active_support/cache/strategy/local_cache.rb:72:in `call' + rack (1.4.5) lib/rack/lock.rb:15:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/static.rb:62:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/application.rb:223:in `call' + rack (1.4.5) lib/rack/content_length.rb:14:in `call' + railties (3.2.12) lib/rails/rack/log_tailer.rb:17:in `call' + rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' + /usr/local/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.2ms) + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (0.9ms) + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (10.0ms) + + +Started GET "/kblog/articles" for 127.0.0.1 at 2013-03-07 17:36:11 +0100 +Connecting to database specified by database.yml +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start +Completed 500 Internal Server Error in 2ms + +NameError (undefined local variable or method `current_user' for #<Kblog::ArticlesController:0x007fa6481fea50>): + /home/erpe/devel/kblog/app/controllers/kblog/articles_controller.rb:65:in `set_blog_user' + activesupport (3.2.12) lib/active_support/callbacks.rb:418:in `_run__3945929107002426202__process_action__1898965138587692781__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/abstract_controller/callbacks.rb:17:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/rescue.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:30:in `block in process_action' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `block in instrument' + activesupport (3.2.12) lib/active_support/notifications/instrumenter.rb:20:in `instrument' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `instrument' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/params_wrapper.rb:207:in `process_action' + activerecord (3.2.12) lib/active_record/railties/controller_runtime.rb:18:in `process_action' + actionpack (3.2.12) lib/abstract_controller/base.rb:121:in `process' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:45:in `process' + actionpack (3.2.12) lib/action_controller/metal.rb:203:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal.rb:246:in `block in action' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' + actionpack (3.2.12) 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.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/railtie/configurable.rb:30:in `method_missing' + journey (1.0.4) lib/journey/router.rb:68:in `block in call' + journey (1.0.4) lib/journey/router.rb:56:in `each' + journey (1.0.4) lib/journey/router.rb:56:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' + rack (1.4.5) lib/rack/etag.rb:23:in `call' + rack (1.4.5) lib/rack/conditionalget.rb:25:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/head.rb:14:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/params_parser.rb:21:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/flash.rb:242:in `call' + rack (1.4.5) lib/rack/session/abstract/id.rb:210:in `context' + rack (1.4.5) lib/rack/session/abstract/id.rb:205:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/cookies.rb:341:in `call' + activerecord (3.2.12) lib/active_record/query_cache.rb:64:in `call' + activerecord (3.2.12) lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `_run__3165999709594408912__call__2944486760057773361__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:27:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/reloader.rb:65:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' + railties (3.2.12) lib/rails/rack/logger.rb:32:in `call_app' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `block in call' + activesupport (3.2.12) lib/active_support/tagged_logging.rb:22:in `tagged' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/request_id.rb:22:in `call' + rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' + rack (1.4.5) lib/rack/runtime.rb:17:in `call' + activesupport (3.2.12) lib/active_support/cache/strategy/local_cache.rb:72:in `call' + rack (1.4.5) lib/rack/lock.rb:15:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/static.rb:62:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/application.rb:223:in `call' + rack (1.4.5) lib/rack/content_length.rb:14:in `call' + railties (3.2.12) lib/rails/rack/log_tailer.rb:17:in `call' + rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' + /usr/local/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.4ms) + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (0.9ms) + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (35.5ms) + + +Started GET "/kblog/articles" for 127.0.0.1 at 2013-03-07 17:36:16 +0100 +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start +Completed 500 Internal Server Error in 1ms + +NameError (undefined local variable or method `current_user' for #<Kblog::ArticlesController:0x007fa6487ee2a8>): + /home/erpe/devel/kblog/app/controllers/kblog/articles_controller.rb:65:in `set_blog_user' + activesupport (3.2.12) lib/active_support/callbacks.rb:418:in `_run__3945929107002426202__process_action__1898965138587692781__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/abstract_controller/callbacks.rb:17:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/rescue.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:30:in `block in process_action' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `block in instrument' + activesupport (3.2.12) lib/active_support/notifications/instrumenter.rb:20:in `instrument' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `instrument' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/params_wrapper.rb:207:in `process_action' + activerecord (3.2.12) lib/active_record/railties/controller_runtime.rb:18:in `process_action' + actionpack (3.2.12) lib/abstract_controller/base.rb:121:in `process' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:45:in `process' + actionpack (3.2.12) lib/action_controller/metal.rb:203:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal.rb:246:in `block in action' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' + actionpack (3.2.12) 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.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/railtie/configurable.rb:30:in `method_missing' + journey (1.0.4) lib/journey/router.rb:68:in `block in call' + journey (1.0.4) lib/journey/router.rb:56:in `each' + journey (1.0.4) lib/journey/router.rb:56:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' + rack (1.4.5) lib/rack/etag.rb:23:in `call' + rack (1.4.5) lib/rack/conditionalget.rb:25:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/head.rb:14:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/params_parser.rb:21:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/flash.rb:242:in `call' + rack (1.4.5) lib/rack/session/abstract/id.rb:210:in `context' + rack (1.4.5) lib/rack/session/abstract/id.rb:205:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/cookies.rb:341:in `call' + activerecord (3.2.12) lib/active_record/query_cache.rb:64:in `call' + activerecord (3.2.12) lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `_run__3165999709594408912__call__2944486760057773361__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:27:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/reloader.rb:65:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' + railties (3.2.12) lib/rails/rack/logger.rb:32:in `call_app' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `block in call' + activesupport (3.2.12) lib/active_support/tagged_logging.rb:22:in `tagged' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/request_id.rb:22:in `call' + rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' + rack (1.4.5) lib/rack/runtime.rb:17:in `call' + activesupport (3.2.12) lib/active_support/cache/strategy/local_cache.rb:72:in `call' + rack (1.4.5) lib/rack/lock.rb:15:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/static.rb:62:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/application.rb:223:in `call' + rack (1.4.5) lib/rack/content_length.rb:14:in `call' + railties (3.2.12) lib/rails/rack/log_tailer.rb:17:in `call' + rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' + /usr/local/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.1ms) + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (0.8ms) + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (7.4ms) + + +Started GET "/kblog/articles" for 127.0.0.1 at 2013-03-07 17:37:59 +0100 +Connecting to database specified by database.yml +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 3 OFFSET 0 +  (0.1ms) SELECT COUNT(*) FROM "kblog_articles" + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (7.3ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/index.html.erb within layouts/application (50.2ms) +Compiled scaffold.css (0ms) (pid 5241) +Compiled users.css (0ms) (pid 5241) +Compiled application.css (12ms) (pid 5241) +Compiled kblog/articles.css (94ms) (pid 5241) +Compiled jquery.js (2ms) (pid 5241) +Compiled jquery_ujs.js (0ms) (pid 5241) +Compiled users.js (0ms) (pid 5241) +Compiled application.js (56ms) (pid 5241) + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 1 + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (0.7ms) +Completed 200 OK in 293ms (Views: 289.2ms | ActiveRecord: 2.5ms) + + +Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-07 17:38:00 +0100 +Served asset /application.css - 200 OK (41ms) + + +Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-07 17:38:00 +0100 +Served asset /scaffold.css - 304 Not Modified (1ms) + + +Started GET "/assets/users.css?body=1" for 127.0.0.1 at 2013-03-07 17:38:00 +0100 +Served asset /users.css - 200 OK (1ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 17:38:00 +0100 +Served asset /jquery_ujs.js - 200 OK (2ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 17:38:00 +0100 +Served asset /kblog/articles.css - 200 OK (2ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 17:38:00 +0100 +Served asset /jquery.js - 200 OK (2ms) + + +Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-07 17:38:00 +0100 +Served asset /application.js - 200 OK (5ms) + + +Started GET "/assets/users.js?body=1" for 127.0.0.1 at 2013-03-07 17:38:00 +0100 +Served asset /users.js - 200 OK (1ms) + + +Started GET "/kblog/?page=2" for 127.0.0.1 at 2013-03-07 17:38:08 +0100 +Processing by Kblog::ArticlesController#index as HTML + Parameters: {"page"=>"2"} +Kblog::ArticlesController#set_blog_user - start + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 3 OFFSET 3 +  (0.1ms) SELECT COUNT(*) FROM "kblog_articles"  + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (2.7ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/index.html.erb within layouts/application (34.1ms) + Kblog::Article Load (0.2ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 1 + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (0.7ms) +Completed 200 OK in 42ms (Views: 40.8ms | ActiveRecord: 0.7ms) + + +Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-07 17:38:09 +0100 +Served asset /application.css - 304 Not Modified (9ms) + + +Started GET "/assets/users.js?body=1" for 127.0.0.1 at 2013-03-07 17:38:09 +0100 +Served asset /users.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 17:38:09 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/users.css?body=1" for 127.0.0.1 at 2013-03-07 17:38:09 +0100 +Served asset /users.css - 304 Not Modified (0ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 17:38:09 +0100 +Served asset /jquery.js - 304 Not Modified (0ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 17:38:09 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-07 17:38:09 +0100 +Served asset /scaffold.css - 304 Not Modified (0ms) + + +Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-07 17:38:09 +0100 +Served asset /application.js - 304 Not Modified (1ms) + + +Started GET "/kblog/articles/new" for 127.0.0.1 at 2013-03-07 17:38:16 +0100 +Processing by Kblog::ArticlesController#new as HTML +Kblog::ArticlesController#set_blog_user - start + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_form.html.erb (3.5ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/new.html.erb within layouts/application (4.8ms) + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 1 + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (0.7ms) +Completed 200 OK in 13ms (Views: 11.8ms | ActiveRecord: 0.3ms) + + +Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-07 17:38:16 +0100 +Served asset /application.css - 304 Not Modified (0ms) + + +Started GET "/assets/users.css?body=1" for 127.0.0.1 at 2013-03-07 17:38:16 +0100 +Served asset /users.css - 304 Not Modified (2ms) + + +Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-07 17:38:16 +0100 +Served asset /scaffold.css - 304 Not Modified (1ms) + + +Started GET "/assets/users.js?body=1" for 127.0.0.1 at 2013-03-07 17:38:16 +0100 +Served asset /users.js - 304 Not Modified (0ms) + + +Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-07 17:38:16 +0100 +Served asset /application.js - 304 Not Modified (1ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 17:38:16 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 17:38:16 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 17:38:16 +0100 +Served asset /jquery.js - 304 Not Modified (0ms) + + +Started POST "/kblog/articles" for 127.0.0.1 at 2013-03-07 17:38:23 +0100 +Processing by Kblog::ArticlesController#create as HTML + Parameters: {"utf8"=>"✓", "authenticity_token"=>"hpOEghe52ExY/R6STy7MBrxlmBF/38o/IzHyiFCSfTM=", "article"=>{"title"=>"this is a test", "content"=>"testing"}, "commit"=>"Create Article"} +Kblog::ArticlesController#set_blog_user - start +Completed 500 Internal Server Error in 2ms + +NameError (undefined local variable or method `current_user' for #<Kblog::ArticlesController:0x007f4540203f00>): + /home/erpe/devel/kblog/app/controllers/kblog/articles_controller.rb:81:in `authenticate' + activesupport (3.2.12) lib/active_support/callbacks.rb:429:in `_run__3542859978084738867__process_action__291995377537302863__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/abstract_controller/callbacks.rb:17:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/rescue.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:30:in `block in process_action' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `block in instrument' + activesupport (3.2.12) lib/active_support/notifications/instrumenter.rb:20:in `instrument' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `instrument' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/params_wrapper.rb:207:in `process_action' + activerecord (3.2.12) lib/active_record/railties/controller_runtime.rb:18:in `process_action' + actionpack (3.2.12) lib/abstract_controller/base.rb:121:in `process' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:45:in `process' + actionpack (3.2.12) lib/action_controller/metal.rb:203:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal.rb:246:in `block in action' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' + actionpack (3.2.12) 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.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/railtie/configurable.rb:30:in `method_missing' + journey (1.0.4) lib/journey/router.rb:68:in `block in call' + journey (1.0.4) lib/journey/router.rb:56:in `each' + journey (1.0.4) lib/journey/router.rb:56:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' + rack (1.4.5) lib/rack/etag.rb:23:in `call' + rack (1.4.5) lib/rack/conditionalget.rb:35:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/head.rb:14:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/params_parser.rb:21:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/flash.rb:242:in `call' + rack (1.4.5) lib/rack/session/abstract/id.rb:210:in `context' + rack (1.4.5) lib/rack/session/abstract/id.rb:205:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/cookies.rb:341:in `call' + activerecord (3.2.12) lib/active_record/query_cache.rb:64:in `call' + activerecord (3.2.12) lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `_run__2555718356526222205__call__4448809886370788369__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:27:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/reloader.rb:65:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' + railties (3.2.12) lib/rails/rack/logger.rb:32:in `call_app' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `block in call' + activesupport (3.2.12) lib/active_support/tagged_logging.rb:22:in `tagged' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/request_id.rb:22:in `call' + rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' + rack (1.4.5) lib/rack/runtime.rb:17:in `call' + activesupport (3.2.12) lib/active_support/cache/strategy/local_cache.rb:72:in `call' + rack (1.4.5) lib/rack/lock.rb:15:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/static.rb:62:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/application.rb:223:in `call' + rack (1.4.5) lib/rack/content_length.rb:14:in `call' + railties (3.2.12) lib/rails/rack/log_tailer.rb:17:in `call' + rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' + /usr/local/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/_trace.erb (1.7ms) + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.0ms) + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (8.4ms) + + +Started GET "/kblog/articles" for 127.0.0.1 at 2013-03-07 17:40:16 +0100 +Connecting to database specified by database.yml +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 3 OFFSET 0 +  (0.1ms) SELECT COUNT(*) FROM "kblog_articles" + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (7.2ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/index.html.erb within layouts/application (50.8ms) + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 1 + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (0.7ms) +Completed 200 OK in 78ms (Views: 74.7ms | ActiveRecord: 2.5ms) + + +Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-07 17:40:17 +0100 +Served asset /application.css - 304 Not Modified (37ms) + + +Started GET "/assets/users.css?body=1" for 127.0.0.1 at 2013-03-07 17:40:17 +0100 +Served asset /users.css - 304 Not Modified (1ms) + + +Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-07 17:40:17 +0100 +Served asset /scaffold.css - 304 Not Modified (1ms) + + +Started GET "/assets/users.js?body=1" for 127.0.0.1 at 2013-03-07 17:40:17 +0100 +Served asset /users.js - 304 Not Modified (1ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 17:40:17 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (1ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 17:40:17 +0100 +Served asset /jquery.js - 304 Not Modified (3ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 17:40:17 +0100 +Served asset /kblog/articles.css - 304 Not Modified (2ms) + + +Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-07 17:40:17 +0100 +Served asset /application.js - 304 Not Modified (37ms) + + +Started GET "/kblog/?page=2" for 127.0.0.1 at 2013-03-07 17:40:23 +0100 +Processing by Kblog::ArticlesController#index as HTML + Parameters: {"page"=>"2"} +Kblog::ArticlesController#set_blog_user - start + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 3 OFFSET 3 +  (0.2ms) SELECT COUNT(*) FROM "kblog_articles"  + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (2.6ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/index.html.erb within layouts/application (7.3ms) + Kblog::Article Load (0.2ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 1 + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (0.7ms) +Completed 200 OK in 17ms (Views: 15.3ms | ActiveRecord: 0.7ms) + + +Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-07 17:40:23 +0100 +Served asset /application.css - 304 Not Modified (0ms) + + +Started GET "/assets/users.css?body=1" for 127.0.0.1 at 2013-03-07 17:40:23 +0100 +Served asset /users.css - 304 Not Modified (2ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 17:40:23 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 17:40:23 +0100 +Served asset /jquery.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 17:40:23 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/users.js?body=1" for 127.0.0.1 at 2013-03-07 17:40:23 +0100 +Served asset /users.js - 304 Not Modified (0ms) + + +Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-07 17:40:23 +0100 +Served asset /scaffold.css - 304 Not Modified (0ms) + + +Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-07 17:40:23 +0100 +Served asset /application.js - 304 Not Modified (2ms) + + +Started GET "/kblog/articles/new" for 127.0.0.1 at 2013-03-07 17:40:25 +0100 +Processing by Kblog::ArticlesController#new as HTML +Kblog::ArticlesController#set_blog_user - start + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_form.html.erb (3.7ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/new.html.erb within layouts/application (5.1ms) + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 1 + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (0.7ms) +Completed 200 OK in 15ms (Views: 13.6ms | ActiveRecord: 0.3ms) + + +Started GET "/assets/users.css?body=1" for 127.0.0.1 at 2013-03-07 17:40:25 +0100 +Served asset /users.css - 304 Not Modified (0ms) + + +Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-07 17:40:25 +0100 +Served asset /application.css - 304 Not Modified (2ms) + + +Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-07 17:40:25 +0100 +Served asset /scaffold.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 17:40:25 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 17:40:25 +0100 +Served asset /jquery.js - 304 Not Modified (0ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 17:40:25 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/users.js?body=1" for 127.0.0.1 at 2013-03-07 17:40:25 +0100 +Served asset /users.js - 304 Not Modified (0ms) + + +Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-07 17:40:25 +0100 +Served asset /application.js - 304 Not Modified (1ms) + + +Started POST "/kblog/articles" for 127.0.0.1 at 2013-03-07 17:40:32 +0100 +Processing by Kblog::ArticlesController#create as HTML + Parameters: {"utf8"=>"✓", "authenticity_token"=>"hpOEghe52ExY/R6STy7MBrxlmBF/38o/IzHyiFCSfTM=", "article"=>{"title"=>"Nam felis metus, gravida vel suscipit sed, euismod vitae nullam.", "content"=>"Aenean sit amet vulputate tellus? Pellentesque habitant morbi tristique senectus et netus et malesuada fames ac turpis egestas. Vestibulum volutpat tempor leo a tincidunt. Ut pretium, tellus a malesuada consectetur, ipsum sem blandit mi, in ultrices sem quam eu nisi! Cras et arcu eget velit condimentum sollicitudin. Phasellus mollis quam et magna massa nunc.\r\n"}, "commit"=>"Create Article"} +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#authenticate - insufficient rights: user: +Redirected to http://localhost:3000/kblog/articles/new +Filter chain halted as :authenticate rendered or redirected +Completed 302 Found in 1ms (ActiveRecord: 0.0ms) + + +Started GET "/kblog/articles/new" for 127.0.0.1 at 2013-03-07 17:40:33 +0100 +Processing by Kblog::ArticlesController#new as HTML +Kblog::ArticlesController#set_blog_user - start + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_form.html.erb (2.6ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/new.html.erb within layouts/application (3.9ms) + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 1 + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (0.9ms) +Completed 200 OK in 16ms (Views: 14.9ms | ActiveRecord: 0.3ms) + + +Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-07 17:40:33 +0100 +Served asset /application.css - 304 Not Modified (0ms) + + +Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-07 17:40:33 +0100 +Served asset /scaffold.css - 304 Not Modified (0ms) + + +Started GET "/assets/users.js?body=1" for 127.0.0.1 at 2013-03-07 17:40:33 +0100 +Served asset /users.js - 304 Not Modified (0ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 17:40:33 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (3ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 17:40:33 +0100 +Served asset /jquery.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 17:40:33 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/users.css?body=1" for 127.0.0.1 at 2013-03-07 17:40:33 +0100 +Served asset /users.css - 304 Not Modified (0ms) + + +Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-07 17:40:33 +0100 +Served asset /application.js - 304 Not Modified (2ms) + + +Started GET "/kblog/articles/" for 127.0.0.1 at 2013-03-07 17:56:27 +0100 +Connecting to database specified by database.yml +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 3 OFFSET 0 +  (0.1ms) SELECT COUNT(*) FROM "kblog_articles" + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (7.8ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/index.html.erb within layouts/application (55.1ms) +Compiled scaffold.css (0ms) (pid 5666) +Compiled users.css (0ms) (pid 5666) +Compiled application.css (13ms) (pid 5666) +Compiled kblog/articles.css (7ms) (pid 5666) +Compiled jquery.js (1ms) (pid 5666) +Compiled jquery_ujs.js (0ms) (pid 5666) +Compiled users.js (0ms) (pid 5666) +Compiled application.js (57ms) (pid 5666) + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 1 + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (0.7ms) +Completed 200 OK in 246ms (Views: 242.4ms | ActiveRecord: 2.7ms) + + +Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-07 17:56:28 +0100 +Served asset /application.css - 200 OK (46ms) + + +Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-07 17:56:28 +0100 +Served asset /scaffold.css - 304 Not Modified (1ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 17:56:28 +0100 +Served asset /jquery_ujs.js - 200 OK (2ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 17:56:28 +0100 +Served asset /jquery.js - 200 OK (3ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 17:56:28 +0100 +Served asset /kblog/articles.css - 200 OK (2ms) + + +Started GET "/assets/users.css?body=1" for 127.0.0.1 at 2013-03-07 17:56:28 +0100 +Served asset /users.css - 200 OK (1ms) + + +Started GET "/assets/users.js?body=1" for 127.0.0.1 at 2013-03-07 17:56:28 +0100 +Served asset /users.js - 200 OK (1ms) + + +Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-07 17:56:28 +0100 +Served asset /application.js - 200 OK (36ms) + + +Started GET "/kblog/articles/new" for 127.0.0.1 at 2013-03-07 17:56:31 +0100 +Processing by Kblog::ArticlesController#new as HTML +Kblog::ArticlesController#set_blog_user - start + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_form.html.erb (3.7ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/new.html.erb within layouts/application (5.2ms) + Kblog::Article Load (0.2ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 1 + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (0.7ms) +Completed 200 OK in 13ms (Views: 12.0ms | ActiveRecord: 0.2ms) + + +Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-07 17:56:31 +0100 +Served asset /application.css - 304 Not Modified (0ms) + + +Started GET "/assets/users.css?body=1" for 127.0.0.1 at 2013-03-07 17:56:31 +0100 +Served asset /users.css - 304 Not Modified (0ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 17:56:31 +0100 +Served asset /jquery.js - 304 Not Modified (1ms) + + +Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-07 17:56:31 +0100 +Served asset /scaffold.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 17:56:31 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 17:56:31 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/users.js?body=1" for 127.0.0.1 at 2013-03-07 17:56:31 +0100 +Served asset /users.js - 304 Not Modified (0ms) + + +Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-07 17:56:31 +0100 +Served asset /application.js - 304 Not Modified (1ms) + + +Started POST "/kblog/articles" for 127.0.0.1 at 2013-03-07 17:56:38 +0100 +Processing by Kblog::ArticlesController#create as HTML + Parameters: {"utf8"=>"✓", "authenticity_token"=>"hpOEghe52ExY/R6STy7MBrxlmBF/38o/IzHyiFCSfTM=", "article"=>{"title"=>"foobar", "content"=>"adsfalsdfasdfasfd"}, "commit"=>"Create Article"} +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#authenticate - insufficient rights: user: +Redirected to http://localhost:3000/kblog/articles/new +Filter chain halted as :authenticate rendered or redirected +Completed 302 Found in 1ms (ActiveRecord: 0.0ms) + + +Started GET "/kblog/articles/new" for 127.0.0.1 at 2013-03-07 17:56:38 +0100 +Processing by Kblog::ArticlesController#new as HTML +Kblog::ArticlesController#set_blog_user - start + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_form.html.erb (2.2ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/new.html.erb within layouts/application (3.1ms) + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 1 + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (0.8ms) +Completed 200 OK in 11ms (Views: 10.5ms | ActiveRecord: 0.3ms) + + +Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-07 17:56:38 +0100 +Served asset /application.css - 304 Not Modified (0ms) + + +Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-07 17:56:38 +0100 +Served asset /scaffold.css - 304 Not Modified (0ms) + + +Started GET "/assets/users.css?body=1" for 127.0.0.1 at 2013-03-07 17:56:38 +0100 +Served asset /users.css - 304 Not Modified (5ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 17:56:38 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 17:56:38 +0100 +Served asset /jquery.js - 304 Not Modified (0ms) + + +Started GET "/assets/users.js?body=1" for 127.0.0.1 at 2013-03-07 17:56:38 +0100 +Served asset /users.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 17:56:38 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-07 17:56:38 +0100 +Served asset /application.js - 304 Not Modified (1ms) + + +Started GET "/kblog/articles" for 127.0.0.1 at 2013-03-07 17:56:41 +0100 +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 3 OFFSET 0 +  (0.1ms) SELECT COUNT(*) FROM "kblog_articles"  + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (2.1ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/index.html.erb within layouts/application (6.8ms) + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 1 + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (0.8ms) +Completed 200 OK in 15ms (Views: 13.8ms | ActiveRecord: 0.8ms) + + +Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-07 17:56:42 +0100 +Served asset /application.css - 304 Not Modified (39ms) + + +Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-07 17:56:42 +0100 +Served asset /scaffold.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 17:56:42 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 17:56:42 +0100 +Served asset /jquery.js - 304 Not Modified (0ms) + + +Started GET "/assets/users.css?body=1" for 127.0.0.1 at 2013-03-07 17:56:42 +0100 +Served asset /users.css - 304 Not Modified (0ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 17:56:42 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/users.js?body=1" for 127.0.0.1 at 2013-03-07 17:56:42 +0100 +Served asset /users.js - 304 Not Modified (0ms) + + +Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-07 17:56:42 +0100 +Served asset /application.js - 304 Not Modified (1ms) + + +Started GET "/kblog/articles" for 127.0.0.1 at 2013-03-07 18:13:44 +0100 +Connecting to database specified by database.yml + +ActionController::RoutingError (No route matches [GET] "/kblog/articles"): + actionpack (3.2.12) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' + railties (3.2.12) lib/rails/rack/logger.rb:32:in `call_app' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `block in call' + activesupport (3.2.12) lib/active_support/tagged_logging.rb:22:in `tagged' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/request_id.rb:22:in `call' + rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' + rack (1.4.5) lib/rack/runtime.rb:17:in `call' + activesupport (3.2.12) lib/active_support/cache/strategy/local_cache.rb:72:in `call' + rack (1.4.5) lib/rack/lock.rb:15:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/static.rb:62:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/application.rb:223:in `call' + rack (1.4.5) lib/rack/content_length.rb:14:in `call' + railties (3.2.12) lib/rails/rack/log_tailer.rb:17:in `call' + rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' + /usr/local/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (2.3ms) + + +Started GET "/" for 127.0.0.1 at 2013-03-07 18:13:48 +0100 + +ActionController::RoutingError (uninitialized constant ArticlesController): + activesupport (3.2.12) lib/active_support/inflector/methods.rb:230:in `block in constantize' + activesupport (3.2.12) lib/active_support/inflector/methods.rb:229:in `each' + activesupport (3.2.12) lib/active_support/inflector/methods.rb:229:in `constantize' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:69:in `controller_reference' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:54:in `controller' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:32: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.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' + rack (1.4.5) lib/rack/etag.rb:23:in `call' + rack (1.4.5) lib/rack/conditionalget.rb:25:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/head.rb:14:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/params_parser.rb:21:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/flash.rb:242:in `call' + rack (1.4.5) lib/rack/session/abstract/id.rb:210:in `context' + rack (1.4.5) lib/rack/session/abstract/id.rb:205:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/cookies.rb:341:in `call' + activerecord (3.2.12) lib/active_record/query_cache.rb:64:in `call' + activerecord (3.2.12) lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `_run__1690496309857359941__call__4023228510723103448__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:27:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/reloader.rb:65:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' + railties (3.2.12) lib/rails/rack/logger.rb:32:in `call_app' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `block in call' + activesupport (3.2.12) lib/active_support/tagged_logging.rb:22:in `tagged' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/request_id.rb:22:in `call' + rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' + rack (1.4.5) lib/rack/runtime.rb:17:in `call' + activesupport (3.2.12) lib/active_support/cache/strategy/local_cache.rb:72:in `call' + rack (1.4.5) lib/rack/lock.rb:15:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/static.rb:62:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/application.rb:223:in `call' + rack (1.4.5) lib/rack/content_length.rb:14:in `call' + railties (3.2.12) lib/rails/rack/log_tailer.rb:17:in `call' + rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' + /usr/local/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (0.6ms) + + +Started GET "/articles" for 127.0.0.1 at 2013-03-07 18:13:51 +0100 + +ActionController::RoutingError (uninitialized constant ArticlesController): + activesupport (3.2.12) lib/active_support/inflector/methods.rb:230:in `block in constantize' + activesupport (3.2.12) lib/active_support/inflector/methods.rb:229:in `each' + activesupport (3.2.12) lib/active_support/inflector/methods.rb:229:in `constantize' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:69:in `controller_reference' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:54:in `controller' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:32: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.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' + rack (1.4.5) lib/rack/etag.rb:23:in `call' + rack (1.4.5) lib/rack/conditionalget.rb:25:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/head.rb:14:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/params_parser.rb:21:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/flash.rb:242:in `call' + rack (1.4.5) lib/rack/session/abstract/id.rb:210:in `context' + rack (1.4.5) lib/rack/session/abstract/id.rb:205:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/cookies.rb:341:in `call' + activerecord (3.2.12) lib/active_record/query_cache.rb:64:in `call' + activerecord (3.2.12) lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `_run__1690496309857359941__call__4023228510723103448__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:27:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/reloader.rb:65:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' + railties (3.2.12) lib/rails/rack/logger.rb:32:in `call_app' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `block in call' + activesupport (3.2.12) lib/active_support/tagged_logging.rb:22:in `tagged' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/request_id.rb:22:in `call' + rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' + rack (1.4.5) lib/rack/runtime.rb:17:in `call' + activesupport (3.2.12) lib/active_support/cache/strategy/local_cache.rb:72:in `call' + rack (1.4.5) lib/rack/lock.rb:15:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/static.rb:62:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/application.rb:223:in `call' + rack (1.4.5) lib/rack/content_length.rb:14:in `call' + railties (3.2.12) lib/rails/rack/log_tailer.rb:17:in `call' + rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' + /usr/local/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (0.6ms) + + +Started GET "/kblog/articles" for 127.0.0.1 at 2013-03-07 18:14:03 +0100 + +ActionController::RoutingError (No route matches [GET] "/kblog/articles"): + actionpack (3.2.12) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' + railties (3.2.12) lib/rails/rack/logger.rb:32:in `call_app' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `block in call' + activesupport (3.2.12) lib/active_support/tagged_logging.rb:22:in `tagged' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/request_id.rb:22:in `call' + rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' + rack (1.4.5) lib/rack/runtime.rb:17:in `call' + activesupport (3.2.12) lib/active_support/cache/strategy/local_cache.rb:72:in `call' + rack (1.4.5) lib/rack/lock.rb:15:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/static.rb:62:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/application.rb:223:in `call' + rack (1.4.5) lib/rack/content_length.rb:14:in `call' + railties (3.2.12) lib/rails/rack/log_tailer.rb:17:in `call' + rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' + /usr/local/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (0.8ms) + + +Started GET "/articles" for 127.0.0.1 at 2013-03-07 18:16:27 +0100 +Connecting to database specified by database.yml + +ActionController::RoutingError (No route matches [GET] "/articles"): + actionpack (3.2.12) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' + railties (3.2.12) lib/rails/rack/logger.rb:32:in `call_app' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `block in call' + activesupport (3.2.12) lib/active_support/tagged_logging.rb:22:in `tagged' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/request_id.rb:22:in `call' + rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' + rack (1.4.5) lib/rack/runtime.rb:17:in `call' + activesupport (3.2.12) lib/active_support/cache/strategy/local_cache.rb:72:in `call' + rack (1.4.5) lib/rack/lock.rb:15:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/static.rb:62:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/application.rb:223:in `call' + rack (1.4.5) lib/rack/content_length.rb:14:in `call' + railties (3.2.12) lib/rails/rack/log_tailer.rb:17:in `call' + rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' + /usr/local/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (2.3ms) + + +Started GET "/" for 127.0.0.1 at 2013-03-07 18:16:30 +0100 + +ActionController::RoutingError (No route matches [GET] "/"): + actionpack (3.2.12) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' + railties (3.2.12) lib/rails/rack/logger.rb:32:in `call_app' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `block in call' + activesupport (3.2.12) lib/active_support/tagged_logging.rb:22:in `tagged' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/request_id.rb:22:in `call' + rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' + rack (1.4.5) lib/rack/runtime.rb:17:in `call' + activesupport (3.2.12) lib/active_support/cache/strategy/local_cache.rb:72:in `call' + rack (1.4.5) lib/rack/lock.rb:15:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/static.rb:62:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/application.rb:223:in `call' + rack (1.4.5) lib/rack/content_length.rb:14:in `call' + railties (3.2.12) lib/rails/rack/log_tailer.rb:17:in `call' + rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' + /usr/local/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (0.5ms) + + +Started GET "/kblog/" for 127.0.0.1 at 2013-03-07 18:16:34 +0100 +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 3 OFFSET 0 +  (0.1ms) SELECT COUNT(*) FROM "kblog_articles" + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (7.1ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/index.html.erb within layouts/application (23.2ms) + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 1 + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (0.8ms) +Completed 200 OK in 71ms (Views: 40.4ms | ActiveRecord: 2.9ms) + + +Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-07 18:16:34 +0100 +Served asset /application.css - 304 Not Modified (11ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 18:16:34 +0100 +Served asset /kblog/articles.css - 304 Not Modified (2ms) + + +Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-07 18:16:34 +0100 +Served asset /scaffold.css - 304 Not Modified (2ms) + + +Started GET "/assets/users.css?body=1" for 127.0.0.1 at 2013-03-07 18:16:34 +0100 +Served asset /users.css - 304 Not Modified (1ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 18:16:34 +0100 +Served asset /jquery.js - 304 Not Modified (3ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 18:16:34 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (2ms) + + +Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-07 18:16:34 +0100 +Served asset /application.js - 304 Not Modified (5ms) + + +Started GET "/assets/users.js?body=1" for 127.0.0.1 at 2013-03-07 18:16:34 +0100 +Served asset /users.js - 304 Not Modified (1ms) + + +Started GET "/kblog/articles/new" for 127.0.0.1 at 2013-03-07 18:16:36 +0100 +Processing by Kblog::ArticlesController#new as HTML +Kblog::ArticlesController#set_blog_user - start + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_form.html.erb (4.0ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/new.html.erb within layouts/application (5.5ms) + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 1 + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (1.0ms) +Completed 200 OK in 51ms (Views: 50.0ms | ActiveRecord: 0.3ms) + + +Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-07 18:16:37 +0100 +Served asset /application.css - 304 Not Modified (0ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 18:16:37 +0100 +Served asset /jquery.js - 304 Not Modified (6ms) + + +Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-07 18:16:37 +0100 +Served asset /scaffold.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 18:16:37 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 18:16:37 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/users.css?body=1" for 127.0.0.1 at 2013-03-07 18:16:37 +0100 +Served asset /users.css - 304 Not Modified (0ms) + + +Started GET "/assets/users.js?body=1" for 127.0.0.1 at 2013-03-07 18:16:37 +0100 +Served asset /users.js - 304 Not Modified (0ms) + + +Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-07 18:16:37 +0100 +Served asset /application.js - 304 Not Modified (1ms) + + +Started POST "/kblog/articles" for 127.0.0.1 at 2013-03-07 18:16:44 +0100 +Processing by Kblog::ArticlesController#create as HTML + Parameters: {"utf8"=>"✓", "authenticity_token"=>"hpOEghe52ExY/R6STy7MBrxlmBF/38o/IzHyiFCSfTM=", "article"=>{"title"=>"Praesent tellus orci, faucibus pharetra pretium faucibus nullam.", "content"=>"Maecenas a nibh ut risus consequat vulputate! Lorem ipsum dolor sit amet, consectetur adipiscing elit. Pellentesque at aliquet massa. Vivamus et lobortis eros. Nam vestibulum iaculis lacus eget volutpat? Donec venenatis lectus sit amet magna mattis quis aliquet velit consectetur. Suspendisse est ipsum, mollis quis convallis vel, consectetur at nulla posuere.\r\n"}, "commit"=>"Create Article"} +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#authenticate - insufficient rights: user: +Redirected to http://localhost:3000/kblog/articles/new +Filter chain halted as :authenticate rendered or redirected +Completed 302 Found in 3ms (ActiveRecord: 0.0ms) + + +Started GET "/kblog/articles/new" for 127.0.0.1 at 2013-03-07 18:16:44 +0100 +Processing by Kblog::ArticlesController#new as HTML +Kblog::ArticlesController#set_blog_user - start + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_form.html.erb (2.6ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/new.html.erb within layouts/application (4.2ms) + Kblog::Article Load (0.4ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 1 + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (0.9ms) +Completed 200 OK in 20ms (Views: 18.7ms | ActiveRecord: 0.4ms) + + +Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-03-07 18:16:45 +0100 +Served asset /application.css - 304 Not Modified (0ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 18:16:45 +0100 +Served asset /jquery.js - 304 Not Modified (2ms) + + +Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-03-07 18:16:45 +0100 +Served asset /scaffold.css - 304 Not Modified (0ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 18:16:45 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 18:16:45 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/users.js?body=1" for 127.0.0.1 at 2013-03-07 18:16:45 +0100 +Served asset /users.js - 304 Not Modified (0ms) + + +Started GET "/assets/users.css?body=1" for 127.0.0.1 at 2013-03-07 18:16:45 +0100 +Served asset /users.css - 304 Not Modified (0ms) + + +Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-03-07 18:16:45 +0100 +Served asset /application.js - 304 Not Modified (1ms) + + +Started GET "/kblog/articles/new" for 127.0.0.1 at 2013-03-07 18:19:20 +0100 +Connecting to database specified by database.yml +Processing by Kblog::ArticlesController#new as HTML +Kblog::ArticlesController#set_blog_user - start +Completed 500 Internal Server Error in 51ms + +ActionView::MissingTemplate (Missing template layouts/kblog with {:locale=>[:en], :formats=>[:html], :handlers=>[:erb, :builder]}. Searched in: + * "/home/erpe/devel/kblog/test/dummy/app/views" + * "/home/erpe/devel/kblog/app/views" +): + actionpack (3.2.12) lib/action_view/path_set.rb:58:in `find' + actionpack (3.2.12) lib/action_view/lookup_context.rb:109:in `find' + actionpack (3.2.12) lib/action_view/renderer/abstract_renderer.rb:3:in `find_template' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:79:in `resolve_layout' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:86:in `resolve_layout' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:69:in `block in find_layout' + actionpack (3.2.12) lib/action_view/lookup_context.rb:228:in `with_layout_format' + actionpack (3.2.12) lib/action_view/renderer/abstract_renderer.rb:3:in `with_layout_format' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:69:in `find_layout' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:53:in `render_with_layout' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:45:in `render_template' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:18:in `render' + actionpack (3.2.12) lib/action_view/renderer/renderer.rb:36:in `render_template' + actionpack (3.2.12) lib/action_view/renderer/renderer.rb:17:in `render' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:110:in `_render_template' + actionpack (3.2.12) lib/action_controller/metal/streaming.rb:225:in `_render_template' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:103:in `render_to_body' + actionpack (3.2.12) lib/action_controller/metal/renderers.rb:28:in `render_to_body' + actionpack (3.2.12) lib/action_controller/metal/compatibility.rb:50:in `render_to_body' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:88:in `render' + actionpack (3.2.12) lib/action_controller/metal/rendering.rb:16:in `render' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:40:in `block (2 levels) in render' + activesupport (3.2.12) lib/active_support/core_ext/benchmark.rb:5:in `block in ms' + /usr/local/lib/ruby/1.9.1/benchmark.rb:295:in `realtime' + activesupport (3.2.12) lib/active_support/core_ext/benchmark.rb:5:in `ms' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:40:in `block in render' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:83:in `cleanup_view_runtime' + activerecord (3.2.12) lib/active_record/railties/controller_runtime.rb:24:in `cleanup_view_runtime' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:39:in `render' + actionpack (3.2.12) lib/action_controller/metal/implicit_render.rb:10:in `default_render' + actionpack (3.2.12) lib/action_controller/metal/implicit_render.rb:5:in `send_action' + actionpack (3.2.12) lib/abstract_controller/base.rb:167:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/rendering.rb:10:in `process_action' + actionpack (3.2.12) lib/abstract_controller/callbacks.rb:18:in `block in process_action' + activesupport (3.2.12) lib/active_support/callbacks.rb:425:in `_run__3094928549900192566__process_action__3040986739241382504__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/abstract_controller/callbacks.rb:17:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/rescue.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:30:in `block in process_action' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `block in instrument' + activesupport (3.2.12) lib/active_support/notifications/instrumenter.rb:20:in `instrument' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `instrument' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/params_wrapper.rb:207:in `process_action' + activerecord (3.2.12) lib/active_record/railties/controller_runtime.rb:18:in `process_action' + actionpack (3.2.12) lib/abstract_controller/base.rb:121:in `process' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:45:in `process' + actionpack (3.2.12) lib/action_controller/metal.rb:203:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal.rb:246:in `block in action' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' + actionpack (3.2.12) 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.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/railtie/configurable.rb:30:in `method_missing' + journey (1.0.4) lib/journey/router.rb:68:in `block in call' + journey (1.0.4) lib/journey/router.rb:56:in `each' + journey (1.0.4) lib/journey/router.rb:56:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' + rack (1.4.5) lib/rack/etag.rb:23:in `call' + rack (1.4.5) lib/rack/conditionalget.rb:25:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/head.rb:14:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/params_parser.rb:21:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/flash.rb:242:in `call' + rack (1.4.5) lib/rack/session/abstract/id.rb:210:in `context' + rack (1.4.5) lib/rack/session/abstract/id.rb:205:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/cookies.rb:341:in `call' + activerecord (3.2.12) lib/active_record/query_cache.rb:64:in `call' + activerecord (3.2.12) lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `_run__2471683023482252769__call__2954887763050874016__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:27:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/reloader.rb:65:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' + railties (3.2.12) lib/rails/rack/logger.rb:32:in `call_app' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `block in call' + activesupport (3.2.12) lib/active_support/tagged_logging.rb:22:in `tagged' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/request_id.rb:22:in `call' + rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' + rack (1.4.5) lib/rack/runtime.rb:17:in `call' + activesupport (3.2.12) lib/active_support/cache/strategy/local_cache.rb:72:in `call' + rack (1.4.5) lib/rack/lock.rb:15:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/static.rb:62:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/application.rb:223:in `call' + rack (1.4.5) lib/rack/content_length.rb:14:in `call' + railties (3.2.12) lib/rails/rack/log_tailer.rb:17:in `call' + rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' + /usr/local/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/missing_template.erb within rescues/layout (2.2ms) + + +Started GET "/kblog/" for 127.0.0.1 at 2013-03-07 18:19:24 +0100 +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start +Completed 500 Internal Server Error in 4ms + +ActionView::MissingTemplate (Missing template layouts/kblog with {:locale=>[:en], :formats=>[:html], :handlers=>[:erb, :builder]}. Searched in: + * "/home/erpe/devel/kblog/test/dummy/app/views" + * "/home/erpe/devel/kblog/app/views" +): + actionpack (3.2.12) lib/action_view/path_set.rb:58:in `find' + actionpack (3.2.12) lib/action_view/lookup_context.rb:109:in `find' + actionpack (3.2.12) lib/action_view/renderer/abstract_renderer.rb:3:in `find_template' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:79:in `resolve_layout' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:86:in `resolve_layout' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:69:in `block in find_layout' + actionpack (3.2.12) lib/action_view/lookup_context.rb:228:in `with_layout_format' + actionpack (3.2.12) lib/action_view/renderer/abstract_renderer.rb:3:in `with_layout_format' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:69:in `find_layout' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:53:in `render_with_layout' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:45:in `render_template' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:18:in `render' + actionpack (3.2.12) lib/action_view/renderer/renderer.rb:36:in `render_template' + actionpack (3.2.12) lib/action_view/renderer/renderer.rb:17:in `render' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:110:in `_render_template' + actionpack (3.2.12) lib/action_controller/metal/streaming.rb:225:in `_render_template' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:103:in `render_to_body' + actionpack (3.2.12) lib/action_controller/metal/renderers.rb:28:in `render_to_body' + actionpack (3.2.12) lib/action_controller/metal/compatibility.rb:50:in `render_to_body' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:88:in `render' + actionpack (3.2.12) lib/action_controller/metal/rendering.rb:16:in `render' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:40:in `block (2 levels) in render' + activesupport (3.2.12) lib/active_support/core_ext/benchmark.rb:5:in `block in ms' + /usr/local/lib/ruby/1.9.1/benchmark.rb:295:in `realtime' + activesupport (3.2.12) lib/active_support/core_ext/benchmark.rb:5:in `ms' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:40:in `block in render' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:83:in `cleanup_view_runtime' + activerecord (3.2.12) lib/active_record/railties/controller_runtime.rb:24:in `cleanup_view_runtime' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:39:in `render' + actionpack (3.2.12) lib/action_controller/metal/implicit_render.rb:10:in `default_render' + actionpack (3.2.12) lib/action_controller/metal/implicit_render.rb:5:in `send_action' + actionpack (3.2.12) lib/abstract_controller/base.rb:167:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/rendering.rb:10:in `process_action' + actionpack (3.2.12) lib/abstract_controller/callbacks.rb:18:in `block in process_action' + activesupport (3.2.12) lib/active_support/callbacks.rb:425:in `_run__3094928549900192566__process_action__2667629584933842412__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/abstract_controller/callbacks.rb:17:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/rescue.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:30:in `block in process_action' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `block in instrument' + activesupport (3.2.12) lib/active_support/notifications/instrumenter.rb:20:in `instrument' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `instrument' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/params_wrapper.rb:207:in `process_action' + activerecord (3.2.12) lib/active_record/railties/controller_runtime.rb:18:in `process_action' + actionpack (3.2.12) lib/abstract_controller/base.rb:121:in `process' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:45:in `process' + actionpack (3.2.12) lib/action_controller/metal.rb:203:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal.rb:246:in `block in action' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' + actionpack (3.2.12) 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.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/railtie/configurable.rb:30:in `method_missing' + journey (1.0.4) lib/journey/router.rb:68:in `block in call' + journey (1.0.4) lib/journey/router.rb:56:in `each' + journey (1.0.4) lib/journey/router.rb:56:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' + rack (1.4.5) lib/rack/etag.rb:23:in `call' + rack (1.4.5) lib/rack/conditionalget.rb:25:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/head.rb:14:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/params_parser.rb:21:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/flash.rb:242:in `call' + rack (1.4.5) lib/rack/session/abstract/id.rb:210:in `context' + rack (1.4.5) lib/rack/session/abstract/id.rb:205:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/cookies.rb:341:in `call' + activerecord (3.2.12) lib/active_record/query_cache.rb:64:in `call' + activerecord (3.2.12) lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `_run__2471683023482252769__call__2954887763050874016__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:27:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/reloader.rb:65:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' + railties (3.2.12) lib/rails/rack/logger.rb:32:in `call_app' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `block in call' + activesupport (3.2.12) lib/active_support/tagged_logging.rb:22:in `tagged' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/request_id.rb:22:in `call' + rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' + rack (1.4.5) lib/rack/runtime.rb:17:in `call' + activesupport (3.2.12) lib/active_support/cache/strategy/local_cache.rb:72:in `call' + rack (1.4.5) lib/rack/lock.rb:15:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/static.rb:62:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/application.rb:223:in `call' + rack (1.4.5) lib/rack/content_length.rb:14:in `call' + railties (3.2.12) lib/rails/rack/log_tailer.rb:17:in `call' + rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' + /usr/local/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/missing_template.erb within rescues/layout (0.4ms) + + +Started GET "/kblog/articles" for 127.0.0.1 at 2013-03-07 18:19:29 +0100 +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start +Completed 500 Internal Server Error in 4ms + +ActionView::MissingTemplate (Missing template layouts/kblog with {:locale=>[:en], :formats=>[:html], :handlers=>[:erb, :builder]}. Searched in: + * "/home/erpe/devel/kblog/test/dummy/app/views" + * "/home/erpe/devel/kblog/app/views" +): + actionpack (3.2.12) lib/action_view/path_set.rb:58:in `find' + actionpack (3.2.12) lib/action_view/lookup_context.rb:109:in `find' + actionpack (3.2.12) lib/action_view/renderer/abstract_renderer.rb:3:in `find_template' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:79:in `resolve_layout' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:86:in `resolve_layout' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:69:in `block in find_layout' + actionpack (3.2.12) lib/action_view/lookup_context.rb:228:in `with_layout_format' + actionpack (3.2.12) lib/action_view/renderer/abstract_renderer.rb:3:in `with_layout_format' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:69:in `find_layout' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:53:in `render_with_layout' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:45:in `render_template' + actionpack (3.2.12) lib/action_view/renderer/template_renderer.rb:18:in `render' + actionpack (3.2.12) lib/action_view/renderer/renderer.rb:36:in `render_template' + actionpack (3.2.12) lib/action_view/renderer/renderer.rb:17:in `render' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:110:in `_render_template' + actionpack (3.2.12) lib/action_controller/metal/streaming.rb:225:in `_render_template' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:103:in `render_to_body' + actionpack (3.2.12) lib/action_controller/metal/renderers.rb:28:in `render_to_body' + actionpack (3.2.12) lib/action_controller/metal/compatibility.rb:50:in `render_to_body' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:88:in `render' + actionpack (3.2.12) lib/action_controller/metal/rendering.rb:16:in `render' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:40:in `block (2 levels) in render' + activesupport (3.2.12) lib/active_support/core_ext/benchmark.rb:5:in `block in ms' + /usr/local/lib/ruby/1.9.1/benchmark.rb:295:in `realtime' + activesupport (3.2.12) lib/active_support/core_ext/benchmark.rb:5:in `ms' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:40:in `block in render' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:83:in `cleanup_view_runtime' + activerecord (3.2.12) lib/active_record/railties/controller_runtime.rb:24:in `cleanup_view_runtime' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:39:in `render' + actionpack (3.2.12) lib/action_controller/metal/implicit_render.rb:10:in `default_render' + actionpack (3.2.12) lib/action_controller/metal/implicit_render.rb:5:in `send_action' + actionpack (3.2.12) lib/abstract_controller/base.rb:167:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/rendering.rb:10:in `process_action' + actionpack (3.2.12) lib/abstract_controller/callbacks.rb:18:in `block in process_action' + activesupport (3.2.12) lib/active_support/callbacks.rb:425:in `_run__3094928549900192566__process_action__2667629584933842412__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/abstract_controller/callbacks.rb:17:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/rescue.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:30:in `block in process_action' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `block in instrument' + activesupport (3.2.12) lib/active_support/notifications/instrumenter.rb:20:in `instrument' + activesupport (3.2.12) lib/active_support/notifications.rb:123:in `instrument' + actionpack (3.2.12) lib/action_controller/metal/instrumentation.rb:29:in `process_action' + actionpack (3.2.12) lib/action_controller/metal/params_wrapper.rb:207:in `process_action' + activerecord (3.2.12) lib/active_record/railties/controller_runtime.rb:18:in `process_action' + actionpack (3.2.12) lib/abstract_controller/base.rb:121:in `process' + actionpack (3.2.12) lib/abstract_controller/rendering.rb:45:in `process' + actionpack (3.2.12) lib/action_controller/metal.rb:203:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' + actionpack (3.2.12) lib/action_controller/metal.rb:246:in `block in action' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' + actionpack (3.2.12) 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.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/railtie/configurable.rb:30:in `method_missing' + journey (1.0.4) lib/journey/router.rb:68:in `block in call' + journey (1.0.4) lib/journey/router.rb:56:in `each' + journey (1.0.4) lib/journey/router.rb:56:in `call' + actionpack (3.2.12) lib/action_dispatch/routing/route_set.rb:601:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' + rack (1.4.5) lib/rack/etag.rb:23:in `call' + rack (1.4.5) lib/rack/conditionalget.rb:25:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/head.rb:14:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/params_parser.rb:21:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/flash.rb:242:in `call' + rack (1.4.5) lib/rack/session/abstract/id.rb:210:in `context' + rack (1.4.5) lib/rack/session/abstract/id.rb:205:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/cookies.rb:341:in `call' + activerecord (3.2.12) lib/active_record/query_cache.rb:64:in `call' + activerecord (3.2.12) lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `_run__2471683023482252769__call__2954887763050874016__callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.12) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' + activesupport (3.2.12) lib/active_support/callbacks.rb:81:in `run_callbacks' + actionpack (3.2.12) lib/action_dispatch/middleware/callbacks.rb:27:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/reloader.rb:65:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' + railties (3.2.12) lib/rails/rack/logger.rb:32:in `call_app' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `block in call' + activesupport (3.2.12) lib/active_support/tagged_logging.rb:22:in `tagged' + railties (3.2.12) lib/rails/rack/logger.rb:16:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/request_id.rb:22:in `call' + rack (1.4.5) lib/rack/methodoverride.rb:21:in `call' + rack (1.4.5) lib/rack/runtime.rb:17:in `call' + activesupport (3.2.12) lib/active_support/cache/strategy/local_cache.rb:72:in `call' + rack (1.4.5) lib/rack/lock.rb:15:in `call' + actionpack (3.2.12) lib/action_dispatch/middleware/static.rb:62:in `call' + railties (3.2.12) lib/rails/engine.rb:479:in `call' + railties (3.2.12) lib/rails/application.rb:223:in `call' + rack (1.4.5) lib/rack/content_length.rb:14:in `call' + railties (3.2.12) lib/rails/rack/log_tailer.rb:17:in `call' + rack (1.4.5) lib/rack/handler/webrick.rb:59:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' + /usr/local/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' + /usr/local/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' + + + Rendered /usr/local/lib/ruby/gems/1.9.1/gems/actionpack-3.2.12/lib/action_dispatch/middleware/templates/rescues/missing_template.erb within rescues/layout (0.4ms) + + +Started GET "/kblog/articles" for 127.0.0.1 at 2013-03-07 18:20:29 +0100 +Connecting to database specified by database.yml +Processing by Kblog::ArticlesController#index as HTML +Kblog::ArticlesController#set_blog_user - start + Kblog::Article Load (0.3ms) SELECT "kblog_articles".* FROM "kblog_articles" ORDER BY created_at DESC LIMIT 3 OFFSET 0 +  (0.1ms) SELECT COUNT(*) FROM "kblog_articles" + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_article.html.erb (7.0ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/index.html.erb within layouts/kblog/kblog (51.2ms) +Compiled kblog/application.css (2ms) (pid 6153) +Compiled kblog/articles.js (0ms) (pid 6153) +Compiled kblog/application.js (40ms) (pid 6153) +Completed 200 OK in 135ms (Views: 131.9ms | ActiveRecord: 2.2ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 18:20:30 +0100 +Served asset /kblog/application.css - 304 Not Modified (3ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 18:20:30 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (5ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 18:20:30 +0100 +Served asset /kblog/articles.css - 304 Not Modified (2ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 18:20:30 +0100 +Served asset /jquery.js - 304 Not Modified (3ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 18:20:30 +0100 +Served asset /kblog/articles.js - 304 Not Modified (2ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 18:20:30 +0100 +Served asset /kblog/application.js - 304 Not Modified (6ms) + + +Started GET "/kblog/articles/new" for 127.0.0.1 at 2013-03-07 18:20:39 +0100 +Processing by Kblog::ArticlesController#new as HTML +Kblog::ArticlesController#set_blog_user - start + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_form.html.erb (3.9ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/new.html.erb within layouts/kblog/kblog (5.6ms) +Completed 200 OK in 13ms (Views: 11.9ms | ActiveRecord: 0.0ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 18:20:40 +0100 +Served asset /kblog/application.css - 304 Not Modified (0ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 18:20:40 +0100 +Served asset /jquery.js - 304 Not Modified (6ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 18:20:40 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 18:20:40 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 18:20:40 +0100 +Served asset /kblog/articles.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 18:20:40 +0100 +Served asset /kblog/application.js - 304 Not Modified (1ms) + + +Started POST "/kblog/articles" for 127.0.0.1 at 2013-03-07 18:20:49 +0100 +Processing by Kblog::ArticlesController#create as HTML + Parameters: {"utf8"=>"✓", "authenticity_token"=>"hpOEghe52ExY/R6STy7MBrxlmBF/38o/IzHyiFCSfTM=", "article"=>{"title"=>"Curabitur tempus vestibulum diam id aliquam? Fusce mollis metus.", "content"=>"Mauris sollicitudin mauris at arcu aliquam feugiat. Nam vulputate aliquam sem, non dignissim ipsum viverra vel! Pellentesque elit neque, consequat nec interdum quis, placerat et lacus. Sed aliquet, velit in rhoncus iaculis, erat erat commodo nisi, sed sodales felis nisl eu ipsum. Phasellus lacus urna, lobortis ac tempor tempor, tincidunt nec tortor volutpat.\r\n"}, "commit"=>"Create Article"} +Kblog::ArticlesController#set_blog_user - start +Kblog::ArticlesController#authenticate - insufficient rights: user: +Redirected to http://localhost:3000/kblog/articles/new +Filter chain halted as :authenticate rendered or redirected +Completed 302 Found in 33ms (ActiveRecord: 0.0ms) + + +Started GET "/kblog/articles/new" for 127.0.0.1 at 2013-03-07 18:20:50 +0100 +Processing by Kblog::ArticlesController#new as HTML +Kblog::ArticlesController#set_blog_user - start + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/_form.html.erb (2.6ms) + Rendered /home/erpe/devel/kblog/app/views/kblog/articles/new.html.erb within layouts/kblog/kblog (3.7ms) +Completed 200 OK in 13ms (Views: 12.2ms | ActiveRecord: 0.0ms) + + +Started GET "/assets/kblog/application.css?body=1" for 127.0.0.1 at 2013-03-07 18:20:50 +0100 +Served asset /kblog/application.css - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.css?body=1" for 127.0.0.1 at 2013-03-07 18:20:50 +0100 +Served asset /kblog/articles.css - 304 Not Modified (0ms) + + +Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-03-07 18:20:50 +0100 +Served asset /jquery_ujs.js - 304 Not Modified (0ms) + + +Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-03-07 18:20:50 +0100 +Served asset /jquery.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/articles.js?body=1" for 127.0.0.1 at 2013-03-07 18:20:50 +0100 +Served asset /kblog/articles.js - 304 Not Modified (0ms) + + +Started GET "/assets/kblog/application.js?body=1" for 127.0.0.1 at 2013-03-07 18:20:50 +0100 +Served asset /kblog/application.js - 304 Not Modified (1ms)