Connecting to database specified by database.yml Connecting to database specified by database.yml Connecting to database specified by database.yml Started GET "/" for 127.0.0.1 at 2013-01-21 19:40:11 +0200 Connecting to database specified by database.yml ActionController::RoutingError (No route matches [GET] "/"): actionpack (3.2.8) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.8) lib/rails/rack/logger.rb:26:in `call_app' railties (3.2.8) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.1) lib/rack/methodoverride.rb:21:in `call' rack (1.4.1) lib/rack/runtime.rb:17:in `call' activesupport (3.2.8) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.1) lib/rack/lock.rb:15:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/static.rb:62:in `call' railties (3.2.8) lib/rails/engine.rb:479:in `call' railties (3.2.8) lib/rails/application.rb:223:in `call' rack (1.4.1) lib/rack/content_length.rb:14:in `call' railties (3.2.8) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.1) lib/rack/handler/webrick.rb:59:in `service' /usr/local/rvm/rubies/ruby-1.9.3-p286/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /usr/local/rvm/rubies/ruby-1.9.3-p286/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /usr/local/rvm/rubies/ruby-1.9.3-p286/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (37.8ms) Started GET "/phones" for 127.0.0.1 at 2013-01-21 19:40:36 +0200 ArgumentError (Unknown validator: 'PhoneValidator'): app/models/phone.rb:4:in `' app/models/phone.rb:1:in `' app/controllers/phones_controller.rb:1:in `' Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (2.6ms) Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (5.5ms) Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (20.4ms) Started GET "/phones" for 127.0.0.1 at 2013-01-21 19:41:02 +0200 ArgumentError (Unknown validator: 'PhoneValidator'): app/models/phone.rb:5:in `' app/models/phone.rb:2:in `' app/controllers/phones_controller.rb:1:in `' Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (2.6ms) Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.8ms) Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (13.9ms) Started GET "/phones" for 127.0.0.1 at 2013-01-21 19:41:27 +0200 Connecting to database specified by database.yml ArgumentError (Unknown validator: 'PhoneValidator'): app/models/phone.rb:5:in `' app/models/phone.rb:2:in `' app/controllers/phones_controller.rb:1:in `' Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (2.7ms) Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (4.3ms) Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (19.2ms) Started GET "/phones" for 127.0.0.1 at 2013-01-21 19:41:27 +0200 NoMethodError (undefined method `key?' for nil:NilClass): actionpack (3.2.8) lib/action_controller/metal/hide_actions.rb:36:in `visible_action?' actionpack (3.2.8) lib/action_controller/metal/hide_actions.rb:18:in `method_for_action' actionpack (3.2.8) lib/action_controller/metal/implicit_render.rb:14:in `method_for_action' actionpack (3.2.8) lib/action_controller/metal/compatibility.rb:61:in `method_for_action' actionpack (3.2.8) lib/abstract_controller/base.rb:115:in `process' actionpack (3.2.8) lib/abstract_controller/rendering.rb:45:in `process' actionpack (3.2.8) lib/action_controller/metal.rb:203:in `dispatch' actionpack (3.2.8) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' actionpack (3.2.8) lib/action_controller/metal.rb:246:in `block in action' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:73:in `call' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:36:in `call' journey (1.0.4) lib/journey/router.rb:68:in `block in call' journey (1.0.4) lib/journey/router.rb:56:in `each' journey (1.0.4) lib/journey/router.rb:56:in `call' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:600:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' rack (1.4.1) lib/rack/etag.rb:23:in `call' rack (1.4.1) lib/rack/conditionalget.rb:25:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/head.rb:14:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/params_parser.rb:21:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/flash.rb:242:in `call' rack (1.4.1) lib/rack/session/abstract/id.rb:205:in `context' rack (1.4.1) lib/rack/session/abstract/id.rb:200:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/cookies.rb:339:in `call' activerecord (3.2.8) lib/active_record/query_cache.rb:64:in `call' activerecord (3.2.8) lib/active_record/connection_adapters/abstract/connection_pool.rb:473:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' activesupport (3.2.8) lib/active_support/callbacks.rb:405:in `_run__2714639589700303063__call__2992415629966378876__callbacks' activesupport (3.2.8) lib/active_support/callbacks.rb:405:in `__run_callback' activesupport (3.2.8) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' activesupport (3.2.8) lib/active_support/callbacks.rb:81:in `run_callbacks' actionpack (3.2.8) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/reloader.rb:65:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.8) lib/rails/rack/logger.rb:26:in `call_app' railties (3.2.8) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.1) lib/rack/methodoverride.rb:21:in `call' rack (1.4.1) lib/rack/runtime.rb:17:in `call' activesupport (3.2.8) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.1) lib/rack/lock.rb:15:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/static.rb:62:in `call' railties (3.2.8) lib/rails/engine.rb:479:in `call' railties (3.2.8) lib/rails/application.rb:223:in `call' rack (1.4.1) lib/rack/content_length.rb:14:in `call' railties (3.2.8) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.1) lib/rack/handler/webrick.rb:59:in `service' /usr/local/rvm/rubies/ruby-1.9.3-p286/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /usr/local/rvm/rubies/ruby-1.9.3-p286/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /usr/local/rvm/rubies/ruby-1.9.3-p286/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (2.5ms) Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.7ms) Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (14.1ms) Connecting to database specified by database.yml  (0.2ms) select sqlite_version(*)  (2.2ms) CREATE TABLE "schema_migrations" ("version" varchar(255) NOT NULL)  (0.0ms) PRAGMA index_list("schema_migrations")  (1.9ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations"  Migrating to CreatePhones (20130121173847)  (0.1ms) begin transaction  (0.5ms) CREATE TABLE "phones" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "number" varchar(255), "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)   (0.2ms) INSERT INTO "schema_migrations" ("version") VALUES ('20130121173847')  (1.7ms) commit transaction  (0.3ms) select sqlite_version(*)  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations"   (0.0ms) PRAGMA index_list("phones") Started GET "/phones" for 127.0.0.1 at 2013-01-21 19:42:46 +0200 Connecting to database specified by database.yml ArgumentError (Unknown validator: 'PhoneValidator'): app/models/phone.rb:5:in `' app/models/phone.rb:2:in `' app/controllers/phones_controller.rb:1:in `' Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (2.8ms) Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (4.0ms) Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (19.4ms) Started GET "/phones" for 127.0.0.1 at 2013-01-21 19:43:25 +0200 TypeError (wrong argument type Class (expected Module)): app/models/phone.rb:4:in `include' app/models/phone.rb:4:in `' app/models/phone.rb:1:in `' app/controllers/phones_controller.rb:1:in `' Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (2.5ms) Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.8ms) Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (13.4ms) Started GET "/phones" for 127.0.0.1 at 2013-01-21 19:44:06 +0200 ActionController::RoutingError (uninitialized constant Phone::PhoneValidator): app/models/phone.rb:4:in `' app/models/phone.rb:1:in `' app/controllers/phones_controller.rb:1:in `' Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (1.0ms) Started GET "/phones" for 127.0.0.1 at 2013-01-21 19:44:15 +0200 TypeError (wrong argument type Class (expected Module)): app/models/phone.rb:4:in `include' app/models/phone.rb:4:in `' app/models/phone.rb:1:in `' app/controllers/phones_controller.rb:1:in `' Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (2.7ms) Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.9ms) Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (38.5ms) Started GET "/phones" for 127.0.0.1 at 2013-01-21 19:45:11 +0200 TypeError (wrong argument type Class (expected Module)): app/models/phone.rb:6:in `include' app/models/phone.rb:6:in `' app/models/phone.rb:3:in `' app/controllers/phones_controller.rb:1:in `' Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (2.8ms) Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.8ms) Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (14.3ms) Started GET "/phones" for 127.0.0.1 at 2013-01-21 19:45:13 +0200 NoMethodError (undefined method `key?' for nil:NilClass): actionpack (3.2.8) lib/action_controller/metal/hide_actions.rb:36:in `visible_action?' actionpack (3.2.8) lib/action_controller/metal/hide_actions.rb:18:in `method_for_action' actionpack (3.2.8) lib/action_controller/metal/implicit_render.rb:14:in `method_for_action' actionpack (3.2.8) lib/action_controller/metal/compatibility.rb:61:in `method_for_action' actionpack (3.2.8) lib/abstract_controller/base.rb:115:in `process' actionpack (3.2.8) lib/abstract_controller/rendering.rb:45:in `process' actionpack (3.2.8) lib/action_controller/metal.rb:203:in `dispatch' actionpack (3.2.8) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' actionpack (3.2.8) lib/action_controller/metal.rb:246:in `block in action' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:73:in `call' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:36:in `call' journey (1.0.4) lib/journey/router.rb:68:in `block in call' journey (1.0.4) lib/journey/router.rb:56:in `each' journey (1.0.4) lib/journey/router.rb:56:in `call' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:600:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' rack (1.4.1) lib/rack/etag.rb:23:in `call' rack (1.4.1) lib/rack/conditionalget.rb:25:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/head.rb:14:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/params_parser.rb:21:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/flash.rb:242:in `call' rack (1.4.1) lib/rack/session/abstract/id.rb:205:in `context' rack (1.4.1) lib/rack/session/abstract/id.rb:200:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/cookies.rb:339:in `call' activerecord (3.2.8) lib/active_record/query_cache.rb:64:in `call' activerecord (3.2.8) lib/active_record/connection_adapters/abstract/connection_pool.rb:473:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' activesupport (3.2.8) lib/active_support/callbacks.rb:405:in `_run__2222861710604666620__call__1835404948743703635__callbacks' activesupport (3.2.8) lib/active_support/callbacks.rb:405:in `__run_callback' activesupport (3.2.8) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' activesupport (3.2.8) lib/active_support/callbacks.rb:81:in `run_callbacks' actionpack (3.2.8) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/reloader.rb:65:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.8) lib/rails/rack/logger.rb:26:in `call_app' railties (3.2.8) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.1) lib/rack/methodoverride.rb:21:in `call' rack (1.4.1) lib/rack/runtime.rb:17:in `call' activesupport (3.2.8) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.1) lib/rack/lock.rb:15:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/static.rb:62:in `call' railties (3.2.8) lib/rails/engine.rb:479:in `call' railties (3.2.8) lib/rails/application.rb:223:in `call' rack (1.4.1) lib/rack/content_length.rb:14:in `call' railties (3.2.8) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.1) lib/rack/handler/webrick.rb:59:in `service' /usr/local/rvm/rubies/ruby-1.9.3-p286/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /usr/local/rvm/rubies/ruby-1.9.3-p286/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /usr/local/rvm/rubies/ruby-1.9.3-p286/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (2.4ms) Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.8ms) Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (13.7ms) Started GET "/phones" for 127.0.0.1 at 2013-01-21 19:45:15 +0200 NoMethodError (undefined method `key?' for nil:NilClass): actionpack (3.2.8) lib/action_controller/metal/hide_actions.rb:36:in `visible_action?' actionpack (3.2.8) lib/action_controller/metal/hide_actions.rb:18:in `method_for_action' actionpack (3.2.8) lib/action_controller/metal/implicit_render.rb:14:in `method_for_action' actionpack (3.2.8) lib/action_controller/metal/compatibility.rb:61:in `method_for_action' actionpack (3.2.8) lib/abstract_controller/base.rb:115:in `process' actionpack (3.2.8) lib/abstract_controller/rendering.rb:45:in `process' actionpack (3.2.8) lib/action_controller/metal.rb:203:in `dispatch' actionpack (3.2.8) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' actionpack (3.2.8) lib/action_controller/metal.rb:246:in `block in action' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:73:in `call' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:36:in `call' journey (1.0.4) lib/journey/router.rb:68:in `block in call' journey (1.0.4) lib/journey/router.rb:56:in `each' journey (1.0.4) lib/journey/router.rb:56:in `call' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:600:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' rack (1.4.1) lib/rack/etag.rb:23:in `call' rack (1.4.1) lib/rack/conditionalget.rb:25:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/head.rb:14:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/params_parser.rb:21:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/flash.rb:242:in `call' rack (1.4.1) lib/rack/session/abstract/id.rb:205:in `context' rack (1.4.1) lib/rack/session/abstract/id.rb:200:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/cookies.rb:339:in `call' activerecord (3.2.8) lib/active_record/query_cache.rb:64:in `call' activerecord (3.2.8) lib/active_record/connection_adapters/abstract/connection_pool.rb:473:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' activesupport (3.2.8) lib/active_support/callbacks.rb:405:in `_run__2222861710604666620__call__1835404948743703635__callbacks' activesupport (3.2.8) lib/active_support/callbacks.rb:405:in `__run_callback' activesupport (3.2.8) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' activesupport (3.2.8) lib/active_support/callbacks.rb:81:in `run_callbacks' actionpack (3.2.8) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/reloader.rb:65:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.8) lib/rails/rack/logger.rb:26:in `call_app' railties (3.2.8) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.1) lib/rack/methodoverride.rb:21:in `call' rack (1.4.1) lib/rack/runtime.rb:17:in `call' activesupport (3.2.8) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.1) lib/rack/lock.rb:15:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/static.rb:62:in `call' railties (3.2.8) lib/rails/engine.rb:479:in `call' railties (3.2.8) lib/rails/application.rb:223:in `call' rack (1.4.1) lib/rack/content_length.rb:14:in `call' railties (3.2.8) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.1) lib/rack/handler/webrick.rb:59:in `service' /usr/local/rvm/rubies/ruby-1.9.3-p286/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /usr/local/rvm/rubies/ruby-1.9.3-p286/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /usr/local/rvm/rubies/ruby-1.9.3-p286/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (2.7ms) Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.8ms) Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (14.2ms) Started GET "/phones" for 127.0.0.1 at 2013-01-21 19:45:44 +0200 NoMethodError (undefined method `key?' for nil:NilClass): actionpack (3.2.8) lib/action_controller/metal/hide_actions.rb:36:in `visible_action?' actionpack (3.2.8) lib/action_controller/metal/hide_actions.rb:18:in `method_for_action' actionpack (3.2.8) lib/action_controller/metal/implicit_render.rb:14:in `method_for_action' actionpack (3.2.8) lib/action_controller/metal/compatibility.rb:61:in `method_for_action' actionpack (3.2.8) lib/abstract_controller/base.rb:115:in `process' actionpack (3.2.8) lib/abstract_controller/rendering.rb:45:in `process' actionpack (3.2.8) lib/action_controller/metal.rb:203:in `dispatch' actionpack (3.2.8) lib/action_controller/metal/rack_delegation.rb:14:in `dispatch' actionpack (3.2.8) lib/action_controller/metal.rb:246:in `block in action' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:73:in `call' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:73:in `dispatch' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:36:in `call' journey (1.0.4) lib/journey/router.rb:68:in `block in call' journey (1.0.4) lib/journey/router.rb:56:in `each' journey (1.0.4) lib/journey/router.rb:56:in `call' actionpack (3.2.8) lib/action_dispatch/routing/route_set.rb:600:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/best_standards_support.rb:17:in `call' rack (1.4.1) lib/rack/etag.rb:23:in `call' rack (1.4.1) lib/rack/conditionalget.rb:25:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/head.rb:14:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/params_parser.rb:21:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/flash.rb:242:in `call' rack (1.4.1) lib/rack/session/abstract/id.rb:205:in `context' rack (1.4.1) lib/rack/session/abstract/id.rb:200:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/cookies.rb:339:in `call' activerecord (3.2.8) lib/active_record/query_cache.rb:64:in `call' activerecord (3.2.8) lib/active_record/connection_adapters/abstract/connection_pool.rb:473:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' activesupport (3.2.8) lib/active_support/callbacks.rb:405:in `_run__2222861710604666620__call__1835404948743703635__callbacks' activesupport (3.2.8) lib/active_support/callbacks.rb:405:in `__run_callback' activesupport (3.2.8) lib/active_support/callbacks.rb:385:in `_run_call_callbacks' activesupport (3.2.8) lib/active_support/callbacks.rb:81:in `run_callbacks' actionpack (3.2.8) lib/action_dispatch/middleware/callbacks.rb:27:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/reloader.rb:65:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/remote_ip.rb:31:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call' railties (3.2.8) lib/rails/rack/logger.rb:26:in `call_app' railties (3.2.8) lib/rails/rack/logger.rb:16:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/request_id.rb:22:in `call' rack (1.4.1) lib/rack/methodoverride.rb:21:in `call' rack (1.4.1) lib/rack/runtime.rb:17:in `call' activesupport (3.2.8) lib/active_support/cache/strategy/local_cache.rb:72:in `call' rack (1.4.1) lib/rack/lock.rb:15:in `call' actionpack (3.2.8) lib/action_dispatch/middleware/static.rb:62:in `call' railties (3.2.8) lib/rails/engine.rb:479:in `call' railties (3.2.8) lib/rails/application.rb:223:in `call' rack (1.4.1) lib/rack/content_length.rb:14:in `call' railties (3.2.8) lib/rails/rack/log_tailer.rb:17:in `call' rack (1.4.1) lib/rack/handler/webrick.rb:59:in `service' /usr/local/rvm/rubies/ruby-1.9.3-p286/lib/ruby/1.9.1/webrick/httpserver.rb:138:in `service' /usr/local/rvm/rubies/ruby-1.9.3-p286/lib/ruby/1.9.1/webrick/httpserver.rb:94:in `run' /usr/local/rvm/rubies/ruby-1.9.3-p286/lib/ruby/1.9.1/webrick/server.rb:191:in `block in start_thread' Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (2.4ms) Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (1.7ms) Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (13.3ms) Started GET "/phones" for 127.0.0.1 at 2013-01-21 20:01:28 +0200 Connecting to database specified by database.yml Processing by PhonesController#index as HTML Phone Load (0.1ms) SELECT "phones".* FROM "phones"  Rendered phones/index.html.erb within layouts/application (5.1ms) Compiled phones.css (0ms) (pid 68339) Compiled scaffold.css (0ms) (pid 68339) Compiled application.css (29ms) (pid 68339) Compiled jquery.js (9ms) (pid 68339) Compiled jquery_ujs.js (0ms) (pid 68339) Compiled phones.js (0ms) (pid 68339) Compiled application.js (109ms) (pid 68339) Completed 200 OK in 333ms (Views: 260.7ms | ActiveRecord: 2.7ms) Started GET "/assets/phones.css?body=1" for 127.0.0.1 at 2013-01-21 20:01:29 +0200 Served asset /phones.css - 200 OK (8ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-01-21 20:01:29 +0200 Served asset /jquery.js - 200 OK (7ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-01-21 20:01:29 +0200 Served asset /application.css - 200 OK (26ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-01-21 20:01:29 +0200 Served asset /application.js - 200 OK (7ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-01-21 20:01:29 +0200 Served asset /scaffold.css - 200 OK (2ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-01-21 20:01:29 +0200 Served asset /jquery_ujs.js - 200 OK (7ms) Started GET "/assets/phones.js?body=1" for 127.0.0.1 at 2013-01-21 20:01:29 +0200 Served asset /phones.js - 200 OK (2ms) Connecting to database specified by database.yml  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations"   (0.3ms) select sqlite_version(*)  (2.3ms) CREATE TABLE "phones" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "number" varchar(255), "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)   (1.9ms) CREATE TABLE "schema_migrations" ("version" varchar(255) NOT NULL)  (0.0ms) PRAGMA index_list("schema_migrations")  (1.9ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (1.9ms) INSERT INTO "schema_migrations" (version) VALUES ('20130121173847') Connecting to database specified by database.yml  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations"   (0.3ms) select sqlite_version(*)  (2.9ms) CREATE TABLE "phones" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "number" varchar(255), "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)   (2.0ms) CREATE TABLE "schema_migrations" ("version" varchar(255) NOT NULL)  (0.0ms) PRAGMA index_list("schema_migrations")  (1.7ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.2ms) SELECT version FROM "schema_migrations"  (3.1ms) INSERT INTO "schema_migrations" (version) VALUES ('20130121173847') Connecting to database specified by database.yml  (0.2ms) SELECT "schema_migrations"."version" FROM "schema_migrations"   (0.3ms) select sqlite_version(*)  (4.4ms) CREATE TABLE "phones" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "number" varchar(255), "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)   (2.0ms) CREATE TABLE "schema_migrations" ("version" varchar(255) NOT NULL)  (0.0ms) PRAGMA index_list("schema_migrations")  (1.9ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (2.1ms) INSERT INTO "schema_migrations" (version) VALUES ('20130121173847') Connecting to database specified by database.yml  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations"   (0.3ms) select sqlite_version(*)  (4.4ms) CREATE TABLE "phones" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "number" varchar(255), "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)   (2.0ms) CREATE TABLE "schema_migrations" ("version" varchar(255) NOT NULL)  (0.0ms) PRAGMA index_list("schema_migrations")  (1.9ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (1.6ms) INSERT INTO "schema_migrations" (version) VALUES ('20130121173847') Connecting to database specified by database.yml  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations"   (0.3ms) select sqlite_version(*)  (4.2ms) CREATE TABLE "phones" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "number" varchar(255), "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)   (1.9ms) CREATE TABLE "schema_migrations" ("version" varchar(255) NOT NULL)  (0.0ms) PRAGMA index_list("schema_migrations")  (1.7ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (1.9ms) INSERT INTO "schema_migrations" (version) VALUES ('20130121173847') Started GET "/phones/new" for 127.0.0.1 at 2013-01-21 20:17:59 +0200 Connecting to database specified by database.yml Processing by PhonesController#new as HTML Rendered phones/_form.html.erb (17.0ms) Rendered phones/new.html.erb within layouts/application (28.6ms) Completed 200 OK in 148ms (Views: 104.9ms | ActiveRecord: 2.8ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-01-21 20:17:59 +0200 Served asset /scaffold.css - 304 Not Modified (2ms) Started GET "/assets/phones.css?body=1" for 127.0.0.1 at 2013-01-21 20:17:59 +0200 Served asset /phones.css - 304 Not Modified (3ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-01-21 20:17:59 +0200 Served asset /jquery_ujs.js - 304 Not Modified (8ms) Started GET "/assets/phones.js?body=1" for 127.0.0.1 at 2013-01-21 20:17:59 +0200 Served asset /phones.js - 304 Not Modified (2ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-01-21 20:17:59 +0200 Served asset /jquery.js - 304 Not Modified (7ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-01-21 20:17:59 +0200 Served asset /application.css - 304 Not Modified (5ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-01-21 20:17:59 +0200 Served asset /application.js - 304 Not Modified (10ms) Started POST "/phones" for 127.0.0.1 at 2013-01-21 20:18:03 +0200 Processing by PhonesController#create as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"juy+71mIBytVSnXymhY6ThKZetKorLx+yfYMGW7+xy4=", "phone"=>{"number"=>"sdfsdfsfd"}, "commit"=>"Create Phone"}  (0.1ms) begin transaction  (0.1ms) rollback transaction Completed 500 Internal Server Error in 3ms Errno::ENOENT (No such file or directory - data/phone_data.yml): app/controllers/phones_controller.rb:46:in `block in create' app/controllers/phones_controller.rb:45:in `create' Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_trace.erb (3.2ms) Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/_request_and_response.erb (2.0ms) Rendered /usr/local/rvm/gems/ruby-1.9.3-p286/gems/actionpack-3.2.8/lib/action_dispatch/middleware/templates/rescues/diagnostics.erb within rescues/layout (15.6ms) Connecting to database specified by database.yml  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations"   (0.4ms) select sqlite_version(*)  (5.5ms) CREATE TABLE "phones" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "number" varchar(255), "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)   (2.0ms) CREATE TABLE "schema_migrations" ("version" varchar(255) NOT NULL)  (0.0ms) PRAGMA index_list("schema_migrations")  (1.9ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (2.0ms) INSERT INTO "schema_migrations" (version) VALUES ('20130121173847') Started POST "/phones" for 127.0.0.1 at 2013-01-21 20:22:13 +0200 Connecting to database specified by database.yml Processing by PhonesController#create as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"juy+71mIBytVSnXymhY6ThKZetKorLx+yfYMGW7+xy4=", "phone"=>{"number"=>"sdfsdfsfd"}, "commit"=>"Create Phone"}  (0.1ms) begin transaction  (0.1ms) rollback transaction Rendered phones/_form.html.erb (62.5ms) Rendered phones/new.html.erb within layouts/application (72.6ms) Completed 200 OK in 1940ms (Views: 109.6ms | ActiveRecord: 2.9ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-01-21 20:22:16 +0200 Served asset /jquery.js - 304 Not Modified (4ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-01-21 20:22:16 +0200 Served asset /jquery_ujs.js - 304 Not Modified (3ms) Started GET "/assets/phones.css?body=1" for 127.0.0.1 at 2013-01-21 20:22:16 +0200 Served asset /phones.css - 304 Not Modified (9ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-01-21 20:22:16 +0200 Served asset /scaffold.css - 304 Not Modified (3ms) Started GET "/assets/phones.js?body=1" for 127.0.0.1 at 2013-01-21 20:22:16 +0200 Served asset /phones.js - 304 Not Modified (2ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-01-21 20:22:16 +0200 Served asset /application.js - 304 Not Modified (9ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-01-21 20:22:16 +0200 Served asset /application.css - 304 Not Modified (7ms) Started POST "/phones" for 127.0.0.1 at 2013-01-21 20:22:29 +0200 Processing by PhonesController#create as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"juy+71mIBytVSnXymhY6ThKZetKorLx+yfYMGW7+xy4=", "phone"=>{"number"=>"972541234567"}, "commit"=>"Create Phone"}  (0.1ms) begin transaction SQL (10.4ms) INSERT INTO "phones" ("created_at", "number", "updated_at") VALUES (?, ?, ?) [["created_at", Mon, 21 Jan 2013 18:22:29 UTC +00:00], ["number", "972541234567"], ["updated_at", Mon, 21 Jan 2013 18:22:29 UTC +00:00]]  (2.2ms) commit transaction Redirected to http://localhost:3000/phones/1 Completed 302 Found in 20ms (ActiveRecord: 12.7ms) Started GET "/phones/1" for 127.0.0.1 at 2013-01-21 20:22:29 +0200 Processing by PhonesController#show as HTML Parameters: {"id"=>"1"} Phone Load (0.4ms) SELECT "phones".* FROM "phones" WHERE "phones"."id" = ? LIMIT 1 [["id", "1"]] Rendered phones/show.html.erb within layouts/application (2.2ms) Completed 200 OK in 14ms (Views: 10.2ms | ActiveRecord: 0.4ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-01-21 20:22:29 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/phones.css?body=1" for 127.0.0.1 at 2013-01-21 20:22:29 +0200 Served asset /phones.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-01-21 20:22:29 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-01-21 20:22:29 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-01-21 20:22:29 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/phones.js?body=1" for 127.0.0.1 at 2013-01-21 20:22:29 +0200 Served asset /phones.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-01-21 20:22:29 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/phones" for 127.0.0.1 at 2013-01-21 20:22:33 +0200 Processing by PhonesController#index as HTML Phone Load (0.2ms) SELECT "phones".* FROM "phones"  Rendered phones/index.html.erb within layouts/application (3.3ms) Completed 200 OK in 13ms (Views: 11.0ms | ActiveRecord: 0.2ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-01-21 20:22:33 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-01-21 20:22:33 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/phones.css?body=1" for 127.0.0.1 at 2013-01-21 20:22:33 +0200 Served asset /phones.css - 304 Not Modified (0ms) Started GET "/assets/phones.js?body=1" for 127.0.0.1 at 2013-01-21 20:22:34 +0200 Served asset /phones.js - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-01-21 20:22:34 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-01-21 20:22:34 +0200 Served asset /jquery_ujs.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-01-21 20:22:34 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/phones/new" for 127.0.0.1 at 2013-01-21 20:22:37 +0200 Processing by PhonesController#new as HTML Rendered phones/_form.html.erb (2.8ms) Rendered phones/new.html.erb within layouts/application (4.0ms) Completed 200 OK in 12ms (Views: 11.6ms | ActiveRecord: 0.0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-01-21 20:22:37 +0200 Served asset /application.css - 304 Not Modified (47ms) Started GET "/assets/phones.css?body=1" for 127.0.0.1 at 2013-01-21 20:22:37 +0200 Served asset /phones.css - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-01-21 20:22:37 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-01-21 20:22:37 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-01-21 20:22:37 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/phones.js?body=1" for 127.0.0.1 at 2013-01-21 20:22:37 +0200 Served asset /phones.js - 304 Not Modified (0ms) Started POST "/phones" for 127.0.0.1 at 2013-01-21 20:22:44 +0200 Processing by PhonesController#create as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"juy+71mIBytVSnXymhY6ThKZetKorLx+yfYMGW7+xy4=", "phone"=>{"number"=>"dfgdfg23423423434"}, "commit"=>"Create Phone"}  (0.1ms) begin transaction SQL (0.6ms) INSERT INTO "phones" ("created_at", "number", "updated_at") VALUES (?, ?, ?) [["created_at", Mon, 21 Jan 2013 18:22:44 UTC +00:00], ["number", "dfgdfg23423423434"], ["updated_at", Mon, 21 Jan 2013 18:22:44 UTC +00:00]]  (1.9ms) commit transaction Redirected to http://localhost:3000/phones/2 Completed 302 Found in 8ms (ActiveRecord: 2.7ms) Started GET "/phones/2" for 127.0.0.1 at 2013-01-21 20:22:44 +0200 Processing by PhonesController#show as HTML Parameters: {"id"=>"2"} Phone Load (0.2ms) SELECT "phones".* FROM "phones" WHERE "phones"."id" = ? LIMIT 1 [["id", "2"]] Rendered phones/show.html.erb within layouts/application (1.0ms) Completed 200 OK in 10ms (Views: 8.8ms | ActiveRecord: 0.2ms) Started GET "/assets/jquery_ujs.js?body=1" for 127.0.0.1 at 2013-01-21 20:22:44 +0200 Served asset /jquery_ujs.js - 200 OK (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-01-21 20:22:44 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/phones.css?body=1" for 127.0.0.1 at 2013-01-21 20:22:44 +0200 Served asset /phones.css - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-01-21 20:22:44 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-01-21 20:22:44 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-01-21 20:22:44 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/phones.js?body=1" for 127.0.0.1 at 2013-01-21 20:22:44 +0200 Served asset /phones.js - 304 Not Modified (0ms) Started GET "/phones" for 127.0.0.1 at 2013-01-21 20:22:47 +0200 Processing by PhonesController#index as HTML Phone Load (0.2ms) SELECT "phones".* FROM "phones" Rendered phones/index.html.erb within layouts/application (3.2ms) Completed 200 OK in 12ms (Views: 10.3ms | ActiveRecord: 0.2ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-01-21 20:22:47 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/phones.css?body=1" for 127.0.0.1 at 2013-01-21 20:22:47 +0200 Served asset /phones.css - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-01-21 20:22:47 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-01-21 20:22:47 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/phones.js?body=1" for 127.0.0.1 at 2013-01-21 20:22:47 +0200 Served asset /phones.js - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-01-21 20:22:47 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started DELETE "/phones/2" for 127.0.0.1 at 2013-01-21 20:23:05 +0200 Processing by PhonesController#destroy as HTML Parameters: {"authenticity_token"=>"juy+71mIBytVSnXymhY6ThKZetKorLx+yfYMGW7+xy4=", "id"=>"2"} Phone Load (0.2ms) SELECT "phones".* FROM "phones" WHERE "phones"."id" = ? LIMIT 1 [["id", "2"]]  (0.1ms) begin transaction SQL (0.4ms) DELETE FROM "phones" WHERE "phones"."id" = ? [["id", 2]]  (1.5ms) commit transaction Redirected to http://localhost:3000/phones Completed 302 Found in 7ms (ActiveRecord: 2.2ms) Started GET "/phones" for 127.0.0.1 at 2013-01-21 20:23:05 +0200 Processing by PhonesController#index as HTML Phone Load (0.3ms) SELECT "phones".* FROM "phones"  Rendered phones/index.html.erb within layouts/application (2.1ms) Completed 200 OK in 11ms (Views: 9.8ms | ActiveRecord: 0.3ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-01-21 20:23:05 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/phones.js?body=1" for 127.0.0.1 at 2013-01-21 20:23:05 +0200 Served asset /phones.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-01-21 20:23:05 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-01-21 20:23:05 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-01-21 20:23:05 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/phones.css?body=1" for 127.0.0.1 at 2013-01-21 20:23:05 +0200 Served asset /phones.css - 304 Not Modified (2ms) Started DELETE "/phones/1" for 127.0.0.1 at 2013-01-21 20:23:08 +0200 Processing by PhonesController#destroy as HTML Parameters: {"authenticity_token"=>"juy+71mIBytVSnXymhY6ThKZetKorLx+yfYMGW7+xy4=", "id"=>"1"} Phone Load (0.2ms) SELECT "phones".* FROM "phones" WHERE "phones"."id" = ? LIMIT 1 [["id", "1"]]  (0.1ms) begin transaction SQL (0.3ms) DELETE FROM "phones" WHERE "phones"."id" = ? [["id", 1]]  (3.1ms) commit transaction Redirected to http://localhost:3000/phones Completed 302 Found in 7ms (ActiveRecord: 3.6ms) Started GET "/phones" for 127.0.0.1 at 2013-01-21 20:23:08 +0200 Processing by PhonesController#index as HTML Phone Load (0.2ms) SELECT "phones".* FROM "phones" Rendered phones/index.html.erb within layouts/application (0.5ms) Completed 200 OK in 9ms (Views: 7.8ms | ActiveRecord: 0.2ms) Started GET "/assets/phones.css?body=1" for 127.0.0.1 at 2013-01-21 20:23:08 +0200 Served asset /phones.css - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-01-21 20:23:08 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-01-21 20:23:08 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/phones.js?body=1" for 127.0.0.1 at 2013-01-21 20:23:08 +0200 Served asset /phones.js - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-01-21 20:23:08 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-01-21 20:23:08 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/phones/new" for 127.0.0.1 at 2013-01-21 20:23:10 +0200 Processing by PhonesController#new as HTML Rendered phones/_form.html.erb (2.8ms) Rendered phones/new.html.erb within layouts/application (4.0ms) Completed 200 OK in 12ms (Views: 11.2ms | ActiveRecord: 0.0ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-01-21 20:23:10 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-01-21 20:23:10 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-01-21 20:23:11 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Started GET "/assets/phones.css?body=1" for 127.0.0.1 at 2013-01-21 20:23:11 +0200 Served asset /phones.css - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-01-21 20:23:11 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started POST "/phones" for 127.0.0.1 at 2013-01-21 20:23:13 +0200 Processing by PhonesController#create as HTML Parameters: {"utf8"=>"✓", "authenticity_token"=>"juy+71mIBytVSnXymhY6ThKZetKorLx+yfYMGW7+xy4=", "phone"=>{"number"=>"werwer"}, "commit"=>"Create Phone"}  (0.1ms) begin transaction  (0.1ms) rollback transaction Rendered phones/_form.html.erb (3.9ms) Rendered phones/new.html.erb within layouts/application (5.2ms) Completed 200 OK in 15ms (Views: 12.6ms | ActiveRecord: 0.2ms) Started GET "/assets/application.css?body=1" for 127.0.0.1 at 2013-01-21 20:23:13 +0200 Served asset /application.css - 304 Not Modified (0ms) Started GET "/assets/phones.js?body=1" for 127.0.0.1 at 2013-01-21 20:23:13 +0200 Served asset /phones.js - 200 OK (0ms) Started GET "/assets/phones.css?body=1" for 127.0.0.1 at 2013-01-21 20:23:13 +0200 Served asset /phones.css - 304 Not Modified (0ms) Started GET "/assets/scaffold.css?body=1" for 127.0.0.1 at 2013-01-21 20:23:13 +0200 Served asset /scaffold.css - 304 Not Modified (0ms) Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2013-01-21 20:23:13 +0200 Served asset /application.js - 304 Not Modified (0ms) Started GET "/assets/jquery.js?body=1" for 127.0.0.1 at 2013-01-21 20:23:13 +0200 Served asset /jquery.js - 304 Not Modified (0ms) Connecting to database specified by database.yml  (0.2ms) SELECT "schema_migrations"."version" FROM "schema_migrations"   (0.3ms) select sqlite_version(*)  (4.0ms) CREATE TABLE "phones" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "number" varchar(255), "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)   (1.9ms) CREATE TABLE "schema_migrations" ("version" varchar(255) NOT NULL)  (0.0ms) PRAGMA index_list("schema_migrations")  (1.9ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (2.1ms) INSERT INTO "schema_migrations" (version) VALUES ('20130121173847') Connecting to database specified by database.yml  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations"   (0.3ms) select sqlite_version(*)  (4.3ms) CREATE TABLE "phones" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "number" varchar(255), "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)   (1.7ms) CREATE TABLE "schema_migrations" ("version" varchar(255) NOT NULL)  (0.0ms) PRAGMA index_list("schema_migrations")  (1.9ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (1.4ms) INSERT INTO "schema_migrations" (version) VALUES ('20130121173847') Connecting to database specified by database.yml  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations"   (0.3ms) select sqlite_version(*)  (3.8ms) CREATE TABLE "phones" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "number" varchar(255), "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)   (1.7ms) CREATE TABLE "schema_migrations" ("version" varchar(255) NOT NULL)  (0.0ms) PRAGMA index_list("schema_migrations")  (1.9ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (1.9ms) INSERT INTO "schema_migrations" (version) VALUES ('20130121173847') Connecting to database specified by database.yml Connecting to database specified by database.yml  (0.2ms) SELECT "schema_migrations"."version" FROM "schema_migrations"  Migrating to CreatePhones (20130121173847) Migrating to AddPossibleNumberToPhone (20130122075331)  (0.1ms) select sqlite_version(*)  (0.1ms) begin transaction  (0.7ms) ALTER TABLE "phones" ADD "possible_number" varchar(255)  (0.2ms) INSERT INTO "schema_migrations" ("version") VALUES ('20130122075331')  (3.7ms) commit transaction  (0.4ms) select sqlite_version(*)  (0.1ms) SELECT "schema_migrations"."version" FROM "schema_migrations"  (0.0ms) PRAGMA index_list("phones") Connecting to database specified by database.yml  (0.2ms) SELECT "schema_migrations"."version" FROM "schema_migrations"   (0.3ms) select sqlite_version(*)  (4.2ms) CREATE TABLE "phones" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "number" varchar(255), "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL, "possible_number" varchar(255))   (1.9ms) CREATE TABLE "schema_migrations" ("version" varchar(255) NOT NULL)  (0.0ms) PRAGMA index_list("schema_migrations")  (1.7ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")  (0.1ms) SELECT version FROM "schema_migrations"  (1.5ms) INSERT INTO "schema_migrations" (version) VALUES ('20130122075331')  (2.0ms) INSERT INTO "schema_migrations" (version) VALUES ('20130121173847')