End chapter 8
[depot.git] / log / development.log
index df17da6f0d22a636fffeaaf1c3b68ab82daa9ff0..d4301bcbe69836e6068869531a38b1ccd5ca1e15 100644 (file)
@@ -1212,3 +1212,392 @@ Rendering template within layouts/products
 Rendering products/index
   \e[4;36;1mProduct Columns (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 Completed in 53ms (View: 40, DB: 2) | 200 OK [http://localhost/products]
+
+
+Processing ProductsController#index (for 127.0.0.1 at 2009-02-03 10:08:16) [GET]
+  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
+  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
+  \e[4;35;1mProduct Load (0.3ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
+Rendering template within layouts/products
+Rendering products/index
+  \e[4;36;1mProduct Columns (2.4ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
+Completed in 118ms (View: 80, DB: 3) | 200 OK [http://localhost/products]
+
+
+Processing ApplicationController#index (for 127.0.0.1 at 2009-02-03 10:08:16) [GET]
+
+
+ActionController::RoutingError (No route matches "/store" with {:method=>:get}):
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/recognition_optimisation.rb:66:in `recognize_path'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/route_set.rb:386:in `recognize'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:182:in `handle_request'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
+    /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
+    /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
+    /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
+    /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
+    /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
+    /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
+    /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
+    /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
+    /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
+    /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
+    /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
+    /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
+    /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
+    /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
+    /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
+    /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
+    /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
+    /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
+    /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
+    /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
+    /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
+    /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
+    script/server:3
+
+Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (not_found)
+
+
+Processing ApplicationController#index (for 127.0.0.1 at 2009-02-04 13:58:28) [GET]
+
+
+ActionController::RoutingError (No route matches "/store" with {:method=>:get}):
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/recognition_optimisation.rb:66:in `recognize_path'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/route_set.rb:386:in `recognize'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:182:in `handle_request'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
+    /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
+    /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
+    /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
+    /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
+    /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
+    /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
+    /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
+    /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
+    /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
+    /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
+    /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
+    /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
+    /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
+    /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
+    /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
+    /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
+    /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
+    /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
+    /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
+    /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
+    /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
+    /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
+    script/server:3
+
+Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (not_found)
+
+
+Processing ProductsController#index (for 127.0.0.1 at 2009-02-04 13:59:23) [GET]
+  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
+  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
+  \e[4;35;1mProduct Load (0.3ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
+Rendering template within layouts/products
+Rendering products/index
+  \e[4;36;1mProduct Columns (4.4ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
+Completed in 189ms (View: 154, DB: 5) | 200 OK [http://localhost/products]
+
+
+Processing ProductsController#show (for 127.0.0.1 at 2009-02-04 13:59:27) [GET]
+  Parameters: {"id"=>"1"}
+  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
+  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
+  \e[4;35;1mProduct Columns (1.5ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+  \e[4;36;1mProduct Load (0.5ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
+Rendering template within layouts/products
+Rendering products/show
+Completed in 49ms (View: 30, DB: 2) | 200 OK [http://localhost/products/1]
+
+
+Processing ProductsController#index (for 127.0.0.1 at 2009-02-04 13:59:29) [GET]
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
+  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
+  \e[4;35;1mProduct Load (0.3ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
+Rendering template within layouts/products
+Rendering products/index
+  \e[4;36;1mProduct Columns (2.0ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
+Completed in 70ms (View: 49, DB: 2) | 200 OK [http://localhost/products]
+
+
+Processing ApplicationController#index (for 127.0.0.1 at 2009-02-04 13:59:32) [GET]
+
+
+ActionController::RoutingError (No route matches "/store" with {:method=>:get}):
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/recognition_optimisation.rb:66:in `recognize_path'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/route_set.rb:386:in `recognize'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:182:in `handle_request'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
+    /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
+    /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
+    /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
+    /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
+    /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
+    /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
+    /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
+    /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
+    /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
+    /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
+    /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
+    /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
+    /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
+    /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
+    /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
+    /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
+    /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
+    /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
+    /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
+    /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
+    /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
+    /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
+    script/server:3
+
+Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (not_found)
+
+
+Processing ApplicationController#index (for 127.0.0.1 at 2009-02-04 13:59:36) [GET]
+
+
+ActionController::RoutingError (No route matches "/store" with {:method=>:get}):
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/recognition_optimisation.rb:66:in `recognize_path'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/route_set.rb:386:in `recognize'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:182:in `handle_request'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
+    /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
+    /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
+    /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
+    /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
+    /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
+    /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
+    /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
+    /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
+    /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
+    /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
+    /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
+    /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
+    /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
+    /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
+    /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
+    /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
+    /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
+    /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
+    /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
+    /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
+    /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
+    /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
+    script/server:3
+
+Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (not_found)
+
+
+Processing StoreController#index (for 127.0.0.1 at 2009-02-04 14:01:27) [GET]
+  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
+  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
+Rendering store/index
+Completed in 7ms (View: 3, DB: 0) | 200 OK [http://localhost/store]
+
+
+Processing StoreController#index (for 127.0.0.1 at 2009-02-04 14:12:04) [GET]
+  \e[4;36;1mSQL (1.8ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
+  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
+  \e[4;36;1mSQL (14.7ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mProduct Columns (3.8ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+
+
+NameError (undefined local variable or method `conditions' for #<Class:0xb70fec24>):
+    /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/base.rb:1833:in `method_missing'
+    /app/models/product.rb:12:in `find_products_for_sale'
+    /app/controllers/store_controller.rb:3:in `index'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:1253:in `send'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:1253:in `perform_action_without_filters'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:617:in `call_filters'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:610:in `perform_action_without_benchmark'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
+    /usr/lib/ruby/1.8/benchmark.rb:293:in `measure'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/rescue.rb:136:in `perform_action_without_caching'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:13:in `perform_action'
+    /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/connection_adapters/abstract/query_cache.rb:34:in `cache'
+    /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/query_cache.rb:8:in `cache'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:12:in `perform_action'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `send'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `process_without_filters'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:606:in `process_without_session_management_support'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/session_management.rb:134:in `process'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:392:in `process'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:183:in `handle_request'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
+    /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
+    /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
+    /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
+    /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
+    /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
+    /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
+    /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
+    /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
+    /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
+    /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
+    /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
+    /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
+    /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
+    /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
+    /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
+    /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
+    /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
+    /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
+    /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
+    /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
+    /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
+    /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
+    script/server:3
+
+Rendered /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/_trace (172.9ms)
+Rendered /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/_request_and_response (2.9ms)
+Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (internal_server_error)
+
+
+Processing StoreController#index (for 127.0.0.1 at 2009-02-04 14:12:32) [GET]
+  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
+  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
+  \e[4;36;1mProduct Load (141.7ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
+Rendering store/index
+  \e[4;35;1mProduct Columns (2.0ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+Completed in 172ms (View: 18, DB: 142) | 200 OK [http://localhost/store]
+
+
+Processing StoreController#index (for 127.0.0.1 at 2009-02-04 14:12:53) [GET]
+  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
+  \e[4;36;1mProduct Load (0.6ms)\e[0m   \e[0;1mSELECT * FROM `products` ORDER BY title\e[0m
+Rendering store/index
+  \e[4;35;1mProduct Columns (1.3ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+Completed in 27ms (View: 15, DB: 1) | 200 OK [http://localhost/store]
+
+
+Processing StoreController#index (for 127.0.0.1 at 2009-02-04 14:13:05) [GET]
+  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
+  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
+  \e[4;36;1mProduct Load (0.9ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
+Rendering store/index
+  \e[4;35;1mProduct Columns (1.9ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+Completed in 39ms (View: 19, DB: 1) | 200 OK [http://localhost/store]
+
+
+Processing StoreController#index (for 127.0.0.1 at 2009-02-04 14:15:08) [GET]
+  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
+  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
+  \e[4;36;1mProduct Load (0.8ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
+Rendering store/index
+  \e[4;35;1mProduct Columns (2.0ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+Completed in 50ms (View: 29, DB: 1) | 200 OK [http://localhost/store]
+
+
+Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:32:09) [GET]
+  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
+  \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
+  \e[4;36;1mProduct Load (0.9ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
+Rendering template within layouts/store
+Rendering store/index
+  \e[4;35;1mProduct Columns (2.2ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+Completed in 39ms (View: 27, DB: 1) | 200 OK [http://localhost/store]
+
+
+Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:33:51) [GET]
+  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
+  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
+  \e[4;36;1mProduct Load (0.6ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
+Rendering template within layouts/store
+Rendering store/index
+  \e[4;35;1mProduct Columns (1.2ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+Completed in 39ms (View: 25, DB: 1) | 200 OK [http://localhost/store]
+
+
+Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:35:16) [GET]
+  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
+  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
+  \e[4;36;1mProduct Load (0.9ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
+Rendering template within layouts/store
+Rendering store/index
+  \e[4;35;1mProduct Columns (1.9ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+Completed in 49ms (View: 31, DB: 1) | 200 OK [http://localhost/store]
+
+
+Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:38:26) [GET]
+  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
+  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
+  \e[4;36;1mProduct Load (0.8ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
+Rendering template within layouts/store
+Rendering store/index
+  \e[4;35;1mProduct Columns (2.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+Completed in 57ms (View: 38, DB: 1) | 200 OK [http://localhost/store]
+
+
+Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:40:47) [GET]
+  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
+  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
+  \e[4;36;1mProduct Load (0.9ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
+Rendering template within layouts/store
+Rendering store/index
+  \e[4;35;1mProduct Columns (2.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+Completed in 51ms (View: 33, DB: 1) | 200 OK [http://localhost/store]
+
+
+Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:41:01) [GET]
+  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
+  \e[4;36;1mProduct Load (0.6ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
+Rendering template within layouts/store
+Rendering store/index
+  \e[4;35;1mProduct Columns (1.4ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+Completed in 40ms (View: 26, DB: 1) | 200 OK [http://localhost/store]
+
+
+Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:41:26) [GET]
+  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
+  \e[4;36;1mProduct Load (0.6ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
+Rendering template within layouts/store
+Rendering store/index
+  \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+Completed in 38ms (View: 27, DB: 1) | 200 OK [http://localhost/store]
+
+
+Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:41:32) [POST]
+  Parameters: {"authenticity_token"=>"e5d513dc6072444247eccfd6ee064972eb3383d9"}
+  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
+  \e[4;36;1mProduct Load (0.6ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
+Rendering template within layouts/store
+Rendering store/index
+  \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+Completed in 39ms (View: 28, DB: 1) | 200 OK [http://localhost/store]