End chapter 8

[depot.git] / log / development.log

diff --git a/log/development.log b/log/development.log
index 95b56a7e5cbaa665a32daa5b828a7690287b908a..d4301bcbe69836e6068869531a38b1ccd5ca1e15 100644 (file)
--- a/log/development.log
+++ b/log/development.log
@@ -1,49 +1,63 @@
+  \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;1mSQL (4.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (7.1ms)\e[0m   \e[0mCREATE TABLE `schema_migrations` (`version` varchar(255) NOT NULL) ENGINE=InnoDB\e[0m
+  \e[4;36;1mSQL (41.8ms)\e[0m   \e[0;1mCREATE UNIQUE INDEX `unique_schema_migrations` ON `schema_migrations` (`version`)\e[0m
+  \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSHOW TABLES\e[0m
+  \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
+Migrating to CreateProducts (20090202160210)
+  \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;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+Migrating to CreateProducts (20090202160210)
+  \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;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+Migrating to CreateProducts (20090202160210)
+  \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;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+Migrating to CreateProducts (20090202160210)
+  \e[4;36;1mSQL (22.1ms)\e[0m   \e[0;1mCREATE TABLE `products` (`id` int(11) DEFAULT NULL auto_increment PRIMARY KEY, `title` varchar(255), `description` text, `image_url` varchar(255), `created_at` datetime, `updated_at` datetime) ENGINE=InnoDB\e[0m
+  \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160210')\e[0m
+  \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+  \e[4;36;1mSQL (1.2ms)\e[0m   \e[0;1mdescribe `products`\e[0m
+  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW KEYS FROM `products`\e[0m
 
 
-Processing ProductsController#index (for 127.0.0.1 at 2008-07-24 08:54:29) [GET]
-  Session ID: 516264dbb9a2fb7792ea3375d071afc7
-  Parameters: {"action"=>"index", "controller"=>"products"}
-  \e[4;36;1mSQL (0.000156)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;35;1mProduct Load (0.000000)\e[0m   \e[0mMysql::Error: Table 'depot_development.products' doesn't exist: SELECT * FROM `products` \e[0m
-
-
-ActiveRecord::StatementInvalid (Mysql::Error: Table 'depot_development.products' doesn't exist: SELECT * FROM `products`     ):
-    /usr/lib/ruby/gems/1.8/gems/activerecord-2.1.0/lib/active_record/connection_adapters/abstract_adapter.rb:147:in `log'
-    /usr/lib/ruby/gems/1.8/gems/activerecord-2.1.0/lib/active_record/connection_adapters/mysql_adapter.rb:299:in `execute'
-    /usr/lib/ruby/gems/1.8/gems/activerecord-2.1.0/lib/active_record/connection_adapters/mysql_adapter.rb:515:in `select'
-    /usr/lib/ruby/gems/1.8/gems/activerecord-2.1.0/lib/active_record/connection_adapters/abstract/database_statements.rb:7:in `select_all_without_query_cache'
-    /usr/lib/ruby/gems/1.8/gems/activerecord-2.1.0/lib/active_record/connection_adapters/abstract/query_cache.rb:59:in `select_all'
-    /usr/lib/ruby/gems/1.8/gems/activerecord-2.1.0/lib/active_record/connection_adapters/abstract/query_cache.rb:80:in `cache_sql'
-    /usr/lib/ruby/gems/1.8/gems/activerecord-2.1.0/lib/active_record/connection_adapters/abstract/query_cache.rb:59:in `select_all'
-    /usr/lib/ruby/gems/1.8/gems/activerecord-2.1.0/lib/active_record/base.rb:582:in `find_by_sql'
-    /usr/lib/ruby/gems/1.8/gems/activerecord-2.1.0/lib/active_record/base.rb:1341:in `find_every'
-    /usr/lib/ruby/gems/1.8/gems/activerecord-2.1.0/lib/active_record/base.rb:536:in `find'
-    /app/controllers/products_controller.rb:5:in `index'
-    /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/base.rb:1162:in `send'
-    /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/base.rb:1162:in `perform_action_without_filters'
-    /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/filters.rb:580:in `call_filters'
-    /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/filters.rb:573:in `perform_action_without_benchmark'
-    /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/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.1.0/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
-    /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/rescue.rb:201:in `perform_action_without_caching'
-    /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/caching/sql_cache.rb:13:in `perform_action'
-    /usr/lib/ruby/gems/1.8/gems/activerecord-2.1.0/lib/active_record/connection_adapters/abstract/query_cache.rb:33:in `cache'
-    /usr/lib/ruby/gems/1.8/gems/activerecord-2.1.0/lib/active_record/query_cache.rb:8:in `cache'
-    /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/caching/sql_cache.rb:12:in `perform_action'
-    /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/base.rb:529:in `send'
-    /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/base.rb:529:in `process_without_filters'
-    /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/filters.rb:569:in `process_without_session_management_support'
-    /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/session_management.rb:130:in `process'
-    /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/base.rb:389:in `process'
-    /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/dispatcher.rb:149:in `handle_request'
-    /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/dispatcher.rb:107:in `dispatch'
-    /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/dispatcher.rb:104:in `synchronize'
-    /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/dispatcher.rb:104:in `dispatch'
-    /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/dispatcher.rb:120:in `dispatch_cgi'
-    /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/dispatcher.rb:35:in `dispatch'
-    /usr/lib/ruby/gems/1.8/gems/rails-2.1.0/lib/webrick_server.rb:112:in `handle_dispatch'
-    /usr/lib/ruby/gems/1.8/gems/rails-2.1.0/lib/webrick_server.rb:78:in `service'
+Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 16:05:24) [GET]
+
+
+CGI::Session::CookieStore::TamperedWithCookie (CGI::Session::CookieStore::TamperedWithCookie):
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/session/cookie_store.rb:145:in `unmarshal'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/session/cookie_store.rb:102:in `restore'
+    /usr/lib/ruby/1.8/cgi/session.rb:304:in `[]'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/cgi_process.rb:98:in `session'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/cgi_process.rb:130:in `stale_session_check!'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/cgi_process.rb:78:in `session'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:1205:in `assign_shortcuts_without_flash'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/flash.rb:166:in `assign_shortcuts'
+    /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:519: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'
@@ -54,530 +68,1536 @@ ActiveRecord::StatementInvalid (Mysql::Error: Table 'depot_development.products'
     /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.1.0/lib/webrick_server.rb:62:in `dispatch'
-    /usr/lib/ruby/gems/1.8/gems/rails-2.1.0/lib/commands/servers/webrick.rb:66
-    /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:27:in `gem_original_require'
-    /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:27:in `require'
-    /usr/lib/ruby/gems/1.8/gems/activesupport-2.1.0/lib/active_support/dependencies.rb:509:in `require'
-    /usr/lib/ruby/gems/1.8/gems/activesupport-2.1.0/lib/active_support/dependencies.rb:354:in `new_constants_in'
-    /usr/lib/ruby/gems/1.8/gems/activesupport-2.1.0/lib/active_support/dependencies.rb:509:in `require'
-    /usr/lib/ruby/gems/1.8/gems/rails-2.1.0/lib/commands/server.rb:39
-    /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:27:in `gem_original_require'
-    /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:27:in `require'
+    /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.1.0/lib/action_controller/templates/rescues/layout.erb (internal_server_error)
-  \e[4;36;1mSQL (0.000168)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;35;1mSQL (0.000306)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.043538)\e[0m   \e[0;1mCREATE TABLE `schema_migrations` (`version` varchar(255) NOT NULL) ENGINE=InnoDB\e[0m
-  \e[4;35;1mSQL (0.004163)\e[0m   \e[0mCREATE UNIQUE INDEX `unique_schema_migrations` ON `schema_migrations` (`version`)\e[0m
-  \e[4;36;1mSQL (0.000244)\e[0m   \e[0;1mSHOW TABLES\e[0m
-  \e[4;35;1mSQL (0.000338)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
-Migrating to CreateProducts (20080724075409)
-  \e[4;36;1mSQL (0.000068)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
-  \e[4;35;1mSQL (0.002555)\e[0m   \e[0mCREATE TABLE `products` (`id` int(11) DEFAULT NULL auto_increment PRIMARY KEY, `title` varchar(255) DEFAULT NULL NULL, `description` text DEFAULT NULL NULL, `image_url` varchar(255) DEFAULT NULL NULL, `created_at` datetime DEFAULT NULL NULL, `updated_at` datetime DEFAULT NULL NULL) ENGINE=InnoDB\e[0m
-  \e[4;36;1mSQL (0.000787)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20080724075409')\e[0m
-  \e[4;35;1mSQL (0.000238)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
-  \e[4;36;1mSQL (0.000215)\e[0m   \e[0;1mSHOW TABLES\e[0m
-  \e[4;35;1mSQL (0.000852)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
-  \e[4;36;1mSQL (0.000711)\e[0m   \e[0;1mdescribe `products`\e[0m
-  \e[4;35;1mSQL (0.000320)\e[0m   \e[0mSHOW KEYS FROM `products`\e[0m
-
-
-Processing ProductsController#index (for 127.0.0.1 at 2008-07-24 08:54:57) [GET]
-  Session ID: BAh7BiIKZmxhc2hJQzonQWN0aW9uQ29udHJvbGxlcjo6Rmxhc2g6OkZsYXNo
-SGFzaHsABjoKQHVzZWR7AA==--9a7a4cb8d57b822aeb31c468bcb62a33430f150b
-  Parameters: {"action"=>"index", "controller"=>"products"}
-  \e[4;36;1mProduct Load (0.000316)\e[0m   \e[0;1mSELECT * FROM `products` \e[0m
-Rendering template within layouts/products
-Rendering products/index
-Completed in 0.01792 (55 reqs/sec) | Rendering: 0.00568 (31%) | DB: 0.00047 (2%) | 200 OK [http://localhost/products]
-
-
-Processing ProductsController#new (for 127.0.0.1 at 2008-07-24 08:55:01) [GET]
-  Session ID: BAh7BiIKZmxhc2hJQzonQWN0aW9uQ29udHJvbGxlcjo6Rmxhc2g6OkZsYXNo
-SGFzaHsABjoKQHVzZWR7AA==--9a7a4cb8d57b822aeb31c468bcb62a33430f150b
-  Parameters: {"action"=>"new", "controller"=>"products"}
-  \e[4;35;1mProduct Columns (0.000866)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
-Rendering template within layouts/products
-Rendering products/new
-Completed in 0.02357 (42 reqs/sec) | Rendering: 0.01115 (47%) | DB: 0.00087 (3%) | 200 OK [http://localhost/products/new]
-
-
-Processing ProductsController#create (for 127.0.0.1 at 2008-07-24 08:55:07) [POST]
-  Session ID: BAh7BzoMY3NyZl9pZCIlOWM2MWUxN2RiODdmNDI2MTQxNjIwODM5ZmY2Yzdj
-OWIiCmZsYXNoSUM6J0FjdGlvbkNvbnRyb2xsZXI6OkZsYXNoOjpGbGFzaEhh
-c2h7AAY6CkB1c2VkewA=--be2cb095a3a64313ddeabee8ee4f7ac896aae350
-  Parameters: {"commit"=>"Create", "product"=>{"image_url"=>"lkj l;j", "title"=>"lkj ", "description"=>"l;kj lk;j l;kj lj"}, "authenticity_token"=>"466a8d52a74d0603850d0b0d709e5f2c5e3ce774", "action"=>"create", "controller"=>"products"}
-  \e[4;36;1mProduct Columns (0.000988)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
-  \e[4;35;1mSQL (0.000084)\e[0m   \e[0mBEGIN\e[0m
-  \e[4;36;1mProduct Create (0.000799)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `description`, `created_at`) VALUES('lkj l;j', '2008-07-24 07:55:07', 'lkj ', 'l;kj lk;j l;kj lj', '2008-07-24 07:55:07')\e[0m
-  \e[4;35;1mSQL (0.002049)\e[0m   \e[0mCOMMIT\e[0m
-Redirected to http://localhost:3000/products/1
-Completed in 0.02044 (48 reqs/sec) | DB: 0.00392 (19%) | 302 Found [http://localhost/products]
-
-
-Processing ProductsController#show (for 127.0.0.1 at 2008-07-24 08:55:07) [GET]
-  Session ID: BAh7BzoMY3NyZl9pZCIlOWM2MWUxN2RiODdmNDI2MTQxNjIwODM5ZmY2Yzdj
-OWIiCmZsYXNoSUM6J0FjdGlvbkNvbnRyb2xsZXI6OkZsYXNoOjpGbGFzaEhh
-c2h7BjoLbm90aWNlIiZQcm9kdWN0IHdhcyBzdWNjZXNzZnVsbHkgY3JlYXRl
-ZC4GOgpAdXNlZHsGOwdG--f2c2ee2eb4620b461f69f905e5860ebe7060c7f2
-  Parameters: {"action"=>"show", "id"=>"1", "controller"=>"products"}
-  \e[4;36;1mProduct Columns (0.001114)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
-  \e[4;35;1mProduct Load (0.000544)\e[0m   \e[0mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
-Rendering template within layouts/products
-Rendering products/show
-Completed in 0.02537 (39 reqs/sec) | Rendering: 0.00662 (26%) | DB: 0.00166 (6%) | 200 OK [http://localhost/products/1]
-
-
-Processing ProductsController#index (for 127.0.0.1 at 2008-07-24 08:55:09) [GET]
-  Session ID: BAh7BzoMY3NyZl9pZCIlOWM2MWUxN2RiODdmNDI2MTQxNjIwODM5ZmY2Yzdj
-OWIiCmZsYXNoSUM6J0FjdGlvbkNvbnRyb2xsZXI6OkZsYXNoOjpGbGFzaEhh
-c2h7BjoLbm90aWNlIiZQcm9kdWN0IHdhcyBzdWNjZXNzZnVsbHkgY3JlYXRl
-ZC4GOgpAdXNlZHsGOwdU--e278ebd0c668af234fdcc2a973394c40ab1571fd
-  Parameters: {"action"=>"index", "controller"=>"products"}
-  \e[4;36;1mProduct Load (0.000324)\e[0m   \e[0;1mSELECT * FROM `products` \e[0m
-Rendering template within layouts/products
-Rendering products/index
-  \e[4;35;1mProduct Columns (0.003374)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
-Completed in 0.02020 (49 reqs/sec) | Rendering: 0.00515 (25%) | DB: 0.00370 (18%) | 200 OK [http://localhost/products]
-  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;35;1mSQL (17.9ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
-Migrating to CreateProducts (20080724075409)
-Migrating to AddProductPrice (20090128142730)
-  \e[4;35;1mSQL (222.8ms)\e[0m   \e[0mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 2\e[0m
-  \e[4;36;1mSQL (0.8ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090128142730')\e[0m
-  \e[4;35;1mSQL (0.6ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
-  \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (2.2ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
-  \e[4;35;1mSQL (1.9ms)\e[0m   \e[0mdescribe `products`\e[0m
-  \e[4;36;1mSQL (1.0ms)\e[0m   \e[0;1mSHOW KEYS FROM `products`\e[0m
-  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
-  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
-Migrating to AddProductPrice (20090128142730)
-  \e[4;35;1mSQL (27.2ms)\e[0m   \e[0mALTER TABLE `products` DROP `price`\e[0m
-  \e[4;36;1mSQL (40.9ms)\e[0m   \e[0;1mDELETE FROM schema_migrations WHERE version = '20090128142730'\e[0m
-Migrating to CreateProducts (20080724075409)
-  \e[4;35;1mSQL (26.4ms)\e[0m   \e[0mDROP TABLE `products`\e[0m
-  \e[4;36;1mSQL (38.8ms)\e[0m   \e[0;1mDELETE FROM schema_migrations WHERE version = '20080724075409'\e[0m
-  \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
-  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
-Migrating to CreateProducts (20080724075409)
-  \e[4;35;1mSQL (30.6ms)\e[0m   \e[0mCREATE TABLE `products` (`id` int(11) DEFAULT NULL auto_increment PRIMARY KEY, `title` varchar(255), `description` text, `image_url` varchar(255), `created_at` datetime, `updated_at` datetime) ENGINE=InnoDB\e[0m
-  \e[4;36;1mSQL (25.8ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20080724075409')\e[0m
-Migrating to AddProductPrice (20090128142730)
-  \e[4;35;1mSQL (16.2ms)\e[0m   \e[0mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 0\e[0m
-  \e[4;36;1mSQL (0.8ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090128142730')\e[0m
-  \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
-  \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (3.6ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
-  \e[4;35;1mSQL (1.9ms)\e[0m   \e[0mdescribe `products`\e[0m
-  \e[4;36;1mSQL (0.6ms)\e[0m   \e[0;1mSHOW KEYS FROM `products`\e[0m
+Rendered /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/_trace (45.0ms)
+Rendered /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/_request_and_response (1.3ms)
+Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (internal_server_error)
 
 
-Processing ProductsController#index (for 127.0.0.1 at 2009-01-30 10:55:37) [GET]
-  \e[4;36;1mSQL (41.3ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;35;1mProduct Load (49.7ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
+Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 16:06:12) [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.4ms)\e[0m   \e[0;1mSELECT * FROM `products` \e[0m
 Rendering template within layouts/products
 Rendering products/index
-  \e[4;36;1mProduct Columns (23.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
-Completed in 234ms (View: 147, DB: 114) | 200 OK [http://localhost/products]
+Completed in 22ms (View: 8, DB: 1) | 200 OK [http://localhost/products]
 
 
-Processing ProductsController#edit (for 127.0.0.1 at 2009-01-30 10:55:41) [GET]
-  Parameters: {"id"=>"1"}
-  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;36;1mProduct Columns (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
-  \e[4;35;1mProduct Load (23.3ms)\e[0m   \e[0mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
-Rendering template within layouts/products
-Rendering products/edit
-Completed in 52ms (View: 16, DB: 24) | 200 OK [http://localhost/products/1/edit]
+Processing ApplicationController#index (for 127.0.0.1 at 2009-02-02 16:06:21) [GET]
 
 
-Processing ProductsController#update (for 127.0.0.1 at 2009-01-30 10:55:42) [PUT]
-  Parameters: {"commit"=>"Update", "authenticity_token"=>"f081ce86efd30d00f8269cdde39c0b562c696c34", "product"=>{"image_url"=>"", "price"=>"17.00", "title"=>"First product", "description"=>"This is the description of the <i>first</i> product"}, "id"=>"1"}
-  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;35;1mProduct Columns (1.2ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
-  \e[4;36;1mProduct Load (0.3ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
-  \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mBEGIN\e[0m
-  \e[4;36;1mProduct Exists (62.9ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'First product' AND `products`.id <> 1) LIMIT 1\e[0m
-  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mROLLBACK\e[0m
-Rendering template within layouts/products
-Rendering products/edit
-Completed in 182ms (View: 22, DB: 65) | 200 OK [http://localhost/products/1]
+ActionController::RoutingError (No route matches "/store/index" 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#update (for 127.0.0.1 at 2009-01-30 10:56:00) [PUT]
-  Parameters: {"commit"=>"Update", "authenticity_token"=>"f081ce86efd30d00f8269cdde39c0b562c696c34", "product"=>{"image_url"=>"prod1", "price"=>"17.00", "title"=>"First product", "description"=>"This is the description of the <i>first</i> product"}, "id"=>"1"}
-  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;35;1mProduct Columns (1.3ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
-  \e[4;36;1mProduct Load (0.1ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
-  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
-  \e[4;36;1mProduct Exists (0.1ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'First product' AND `products`.id <> 1) LIMIT 1\e[0m
-  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mROLLBACK\e[0m
-Rendering template within layouts/products
-Rendering products/edit
-Completed in 42ms (View: 16, DB: 2) | 200 OK [http://localhost/products/1]
 
+Processing ApplicationController#index (for 127.0.0.1 at 2009-02-02 16:06:28) [GET]
 
-Processing ProductsController#update (for 127.0.0.1 at 2009-01-30 10:56:04) [PUT]
-  Parameters: {"commit"=>"Update", "authenticity_token"=>"f081ce86efd30d00f8269cdde39c0b562c696c34", "product"=>{"image_url"=>"prod1.png", "price"=>"17.00", "title"=>"First product", "description"=>"This is the description of the <i>first</i> product"}, "id"=>"1"}
-  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
-  \e[4;36;1mProduct Load (0.2ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
-  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
-  \e[4;36;1mProduct Exists (0.1ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'First product' AND `products`.id <> 1) LIMIT 1\e[0m
-  \e[4;35;1mProduct Update (31.3ms)\e[0m   \e[0mUPDATE `products` SET `updated_at` = '2009-01-30 10:56:04', `image_url` = 'prod1.png' WHERE `id` = 1\e[0m
-  \e[4;36;1mSQL (8.9ms)\e[0m   \e[0;1mCOMMIT\e[0m
-Redirected to #<Product:0xb6716f18>
-Completed in 69ms (DB: 42) | 302 Found [http://localhost/products/1]
 
+ActionController::RoutingError (No route matches "/store/index" 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
 
-Processing ProductsController#show (for 127.0.0.1 at 2009-01-30 10:56:04) [GET]
-  Parameters: {"id"=>"1"}
+Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (not_found)
+  \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 Columns (2.4ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
-  \e[4;35;1mProduct Load (0.6ms)\e[0m   \e[0mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
-Rendering template within layouts/products
-Rendering products/show
-Completed in 43ms (View: 17, DB: 3) | 200 OK [http://localhost/products/1]
+  \e[4;36;1mSQL (0.6ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+Migrating to CreateProducts (20090202160210)
+Migrating to AddPriceToProduct (20090202160745)
+  \e[4;36;1mSQL (47.0ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 2\e[0m
+  \e[4;35;1mSQL (0.8ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160745')\e[0m
+Migrating to AddDateAvailableToProduct (20090202160925)
+  \e[4;36;1mSQL (8.3ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `date_available` date\e[0m
+  \e[4;35;1mSQL (0.8ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160925')\e[0m
+  \e[4;36;1mSQL (0.5ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+  \e[4;36;1mSQL (0.6ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (1.9ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+  \e[4;36;1mSQL (1.8ms)\e[0m   \e[0;1mdescribe `products`\e[0m
+  \e[4;35;1mSQL (0.6ms)\e[0m   \e[0mSHOW KEYS FROM `products`\e[0m
 
 
-Processing ProductsController#index (for 127.0.0.1 at 2009-01-30 10:56:07) [GET]
-  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;35;1mProduct Load (0.4ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
+Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 16:15:31) [GET]
+  \e[4;35;1mSQL (0.1ms)\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.6ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
 Rendering template within layouts/products
 Rendering products/index
-  \e[4;36;1mProduct Columns (1.7ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
-Completed in 28ms (View: 14, DB: 2) | 200 OK [http://localhost/products]
+Completed in 22ms (View: 8, DB: 1) | 200 OK [http://localhost/products]
 
 
-Processing ProductsController#new (for 127.0.0.1 at 2009-01-30 10:56:13) [GET]
+Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 16:15:56) [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 Columns (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
+  \e[4;36;1mProduct Load (0.2ms)\e[0m   \e[0;1mSELECT * FROM `products` \e[0m
 Rendering template within layouts/products
-Rendering products/new
-Completed in 34ms (View: 21, DB: 1) | 200 OK [http://localhost/products/new]
+Rendering products/index
+Completed in 22ms (View: 11, DB: 0) | 200 OK [http://localhost/products]
 
 
-Processing ProductsController#create (for 127.0.0.1 at 2009-01-30 10:56:15) [POST]
-  Parameters: {"commit"=>"Create", "authenticity_token"=>"f081ce86efd30d00f8269cdde39c0b562c696c34", "product"=>{"image_url"=>"", "price"=>"0.0", "title"=>"", "description"=>""}}
-  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;36;1mProduct Columns (1.2ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
-  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
-  \e[4;36;1mProduct Exists (0.4ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY '') LIMIT 1\e[0m
-  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mROLLBACK\e[0m
+Processing ProductsController#new (for 127.0.0.1 at 2009-02-02 16:16:00) [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 Columns (2.5ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 Rendering template within layouts/products
 Rendering products/new
-Completed in 49ms (View: 18, DB: 2) | 200 OK [http://localhost/products]
+Completed in 57ms (View: 34, DB: 3) | 200 OK [http://localhost/products/new]
 
 
-Processing ProductsController#index (for 127.0.0.1 at 2009-01-30 10:56:26) [GET]
-  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;35;1mProduct Load (0.2ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
-Rendering template within layouts/products
-Rendering products/index
-  \e[4;36;1mProduct Columns (1.4ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
-Completed in 32ms (View: 16, DB: 2) | 200 OK [http://localhost/products]
-  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
-Migrating to CreateProducts (20080724075409)
-Migrating to AddProductPrice (20090128142730)
-Migrating to AddTestData (20090130111521)
-  \e[4;35;1mProduct Delete all (8.2ms)\e[0m   \e[0mDELETE FROM `products` \e[0m
-  \e[4;36;1mProduct Columns (1.7ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
-  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
-  \e[4;36;1mProduct Exists (0.4ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Pragmatic Version Control') LIMIT 1\e[0m
-  \e[4;35;1mProduct Create (28.2ms)\e[0m   \e[0mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `description`, `created_at`) VALUES('/images/auto.jpg', '2009-01-30 11:21:28', 'Pragmatic Version Control', 29.95, '<p>\n <em>Pragmatic Project Automation</em> shows you how to improve the \n consistency and repeatability of your project\'s procedures using \n automation to reduce risk and errors.\n </p>\n <p>\n Simply put, we\'re going to put this thing called a computer to work \n for you doing the mundane (but important) project stuff. That means \n you\'ll have more time and energy to do the really \n exciting---and difficult---stuff, like writing quality code.\n </p>', '2009-01-30 11:21:28')\e[0m
-  \e[4;36;1mSQL (0.6ms)\e[0m   \e[0;1mCOMMIT\e[0m
+Processing ProductsController#create (for 127.0.0.1 at 2009-02-02 16:16:18) [POST]
+  Parameters: {"commit"=>"Create", "authenticity_token"=>"8cca4a5b8c4003866a07c7a42d5a3b724d9e5d00", "product"=>{"image_url"=>"", "price"=>"2.0", "title"=>"frist produce", "date_available(1i)"=>"2009", "date_available(2i)"=>"2", "description"=>"a bunch of descirpiton", "date_available(3i)"=>"2"}}
+  \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 Columns (1.5ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
-  \e[4;36;1mProduct Exists (0.4ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Pragmatic Version Control') LIMIT 1\e[0m
-  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mROLLBACK\e[0m
-  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
-  \e[4;35;1mProduct Exists (0.4ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Pragmatic Unit Testing (C#)') LIMIT 1\e[0m
-  \e[4;36;1mProduct Create (0.4ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `description`, `created_at`) VALUES('/images/utc.jpg', '2009-01-30 11:21:28', 'Pragmatic Unit Testing (C#)', 27.75, '<p>\n Pragmatic programmers use feedback to drive their development and \n personal processes. The most valuable feedback you can get while \n coding comes from unit testing.\n </p>\n <p>\n Without good tests in place, coding can become a frustrating game of \n \"whack-a-mole.\" That\'s the carnival game where the player strikes at a \n mechanical mole; it retreats and another mole pops up on the opposite side \n of the field. The moles pop up and down so fast that you end up flailing \n your mallet helplessly as the moles continue to pop up where you least \n expect them.\n </p>', '2009-01-30 11:21:28')\e[0m
-  \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mCOMMIT\e[0m
-  \e[4;36;1mSQL (23.8ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090130111521')\e[0m
-  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
-  \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (1.0ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
-  \e[4;35;1mSQL (1.1ms)\e[0m   \e[0mdescribe `products`\e[0m
-  \e[4;36;1mSQL (0.5ms)\e[0m   \e[0;1mSHOW KEYS FROM `products`\e[0m
+  \e[4;36;1mProduct Create (0.3ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('', '2009-02-02 16:16:18', 'frist produce', 2.0, '2009-02-02', 'a bunch of descirpiton', '2009-02-02 16:16:18')\e[0m
+  \e[4;35;1mSQL (4.4ms)\e[0m   \e[0mCOMMIT\e[0m
+Redirected to #<Product:0xb71dba48>
+Completed in 27ms (DB: 7) | 302 Found [http://localhost/products]
 
 
-Processing ProductsController#index (for 127.0.0.1 at 2009-01-30 11:21:36) [GET]
+Processing ProductsController#show (for 127.0.0.1 at 2009-02-02 16:16:18) [GET]
+  Parameters: {"id"=>"1"}
+  \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.7ms)\e[0m   \e[0;1mSELECT * FROM `products` \e[0m
+  \e[4;36;1mProduct Columns (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
+  \e[4;35;1mProduct Load (0.4ms)\e[0m   \e[0mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
 Rendering template within layouts/products
-Rendering products/index
-  \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
-Completed in 34ms (View: 19, DB: 2) | 200 OK [http://localhost/products]
-  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
-  \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
-Migrating to AddTestData (20090130111521)
-  \e[4;35;1mProduct Delete all (7.2ms)\e[0m   \e[0mDELETE FROM `products` \e[0m
-  \e[4;36;1mSQL (9.5ms)\e[0m   \e[0;1mDELETE FROM schema_migrations WHERE version = '20090130111521'\e[0m
-Migrating to AddProductPrice (20090128142730)
-  \e[4;35;1mSQL (174.7ms)\e[0m   \e[0mALTER TABLE `products` DROP `price`\e[0m
-  \e[4;36;1mSQL (0.7ms)\e[0m   \e[0;1mDELETE FROM schema_migrations WHERE version = '20090128142730'\e[0m
-Migrating to CreateProducts (20080724075409)
-  \e[4;35;1mSQL (1.4ms)\e[0m   \e[0mDROP TABLE `products`\e[0m
-  \e[4;36;1mSQL (0.7ms)\e[0m   \e[0;1mDELETE FROM schema_migrations WHERE version = '20080724075409'\e[0m
-  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
-  \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
-Migrating to CreateProducts (20080724075409)
-  \e[4;35;1mSQL (6.1ms)\e[0m   \e[0mCREATE TABLE `products` (`id` int(11) DEFAULT NULL auto_increment PRIMARY KEY, `title` varchar(255), `description` text, `image_url` varchar(255), `created_at` datetime, `updated_at` datetime) ENGINE=InnoDB\e[0m
-  \e[4;36;1mSQL (0.8ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20080724075409')\e[0m
-Migrating to AddProductPrice (20090128142730)
-  \e[4;35;1mSQL (8.7ms)\e[0m   \e[0mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 0\e[0m
-  \e[4;36;1mSQL (0.8ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090128142730')\e[0m
-Migrating to AddTestData (20090130111521)
-  \e[4;35;1mProduct Delete all (9.7ms)\e[0m   \e[0mDELETE FROM `products` \e[0m
-  \e[4;36;1mProduct Columns (2.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
-  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mBEGIN\e[0m
-  \e[4;36;1mProduct Exists (0.5ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Pragmatic Project Automation') LIMIT 1\e[0m
-  \e[4;35;1mProduct Create (0.4ms)\e[0m   \e[0mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `description`, `created_at`) VALUES('/images/auto.jpg', '2009-01-30 11:22:38', 'Pragmatic Project Automation', 29.95, '<p>\n <em>Pragmatic Project Automation</em> shows you how to improve the \n consistency and repeatability of your project\'s procedures using \n automation to reduce risk and errors.\n </p>\n <p>\n Simply put, we\'re going to put this thing called a computer to work \n for you doing the mundane (but important) project stuff. That means \n you\'ll have more time and energy to do the really \n exciting---and difficult---stuff, like writing quality code.\n </p>', '2009-01-30 11:22:38')\e[0m
-  \e[4;36;1mSQL (0.6ms)\e[0m   \e[0;1mCOMMIT\e[0m
-  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mBEGIN\e[0m
-  \e[4;36;1mProduct Exists (0.4ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Pragmatic Version Control') LIMIT 1\e[0m
-  \e[4;35;1mProduct Create (0.4ms)\e[0m   \e[0mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `description`, `created_at`) VALUES('/images/svn.jpg', '2009-01-30 11:22:38', 'Pragmatic Version Control', 28.5, '<p>\n This book is a recipe-based approach to using Subversion that will \n get you up and running quickly---and correctly. All projects need\n version control: it\'s a foundational piece of any project\'s \n infrastructure. Yet half of all project teams in the U.S. don\'t use\n any version control at all. Many others don\'t use it well, and end \n up experiencing time-consuming problems.\n </p>', '2009-01-30 11:22:38')\e[0m
-  \e[4;36;1mSQL (0.6ms)\e[0m   \e[0;1mCOMMIT\e[0m
-  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mBEGIN\e[0m
-  \e[4;36;1mProduct Exists (0.4ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Pragmatic Unit Testing (C#)') LIMIT 1\e[0m
-  \e[4;35;1mProduct Create (0.5ms)\e[0m   \e[0mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `description`, `created_at`) VALUES('/images/utc.jpg', '2009-01-30 11:22:38', 'Pragmatic Unit Testing (C#)', 27.75, '<p>\n Pragmatic programmers use feedback to drive their development and \n personal processes. The most valuable feedback you can get while \n coding comes from unit testing.\n </p>\n <p>\n Without good tests in place, coding can become a frustrating game of \n \"whack-a-mole.\" That\'s the carnival game where the player strikes at a \n mechanical mole; it retreats and another mole pops up on the opposite side \n of the field. The moles pop up and down so fast that you end up flailing \n your mallet helplessly as the moles continue to pop up where you least \n expect them.\n </p>', '2009-01-30 11:22:38')\e[0m
-  \e[4;36;1mSQL (6.3ms)\e[0m   \e[0;1mCOMMIT\e[0m
-  \e[4;35;1mSQL (13.7ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090130111521')\e[0m
-  \e[4;36;1mSQL (0.5ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
-  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
-  \e[4;36;1mSQL (0.5ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
-  \e[4;35;1mSQL (1.8ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
-  \e[4;36;1mSQL (1.8ms)\e[0m   \e[0;1mdescribe `products`\e[0m
-  \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mSHOW KEYS FROM `products`\e[0m
+Rendering products/show
+Completed in 30ms (View: 12, DB: 2) | 200 OK [http://localhost/products/1]
 
 
-Processing ProductsController#index (for 127.0.0.1 at 2009-01-30 11:22:43) [GET]
-  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;35;1mProduct Load (0.4ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
+Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 16:16:20) [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.5ms)\e[0m   \e[0;1mSELECT * FROM `products` \e[0m
 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 31ms (View: 17, DB: 2) | 200 OK [http://localhost/products]
-  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
-  \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
-Migrating to AddTestData (20090130111521)
-  \e[4;35;1mProduct Delete all (5.1ms)\e[0m   \e[0mDELETE FROM `products` \e[0m
-  \e[4;36;1mSQL (12.1ms)\e[0m   \e[0;1mDELETE FROM schema_migrations WHERE version = '20090130111521'\e[0m
-Migrating to AddProductPrice (20090128142730)
-  \e[4;35;1mSQL (30.2ms)\e[0m   \e[0mALTER TABLE `products` DROP `price`\e[0m
-  \e[4;36;1mSQL (0.7ms)\e[0m   \e[0;1mDELETE FROM schema_migrations WHERE version = '20090128142730'\e[0m
-Migrating to CreateProducts (20080724075409)
-  \e[4;35;1mSQL (2.0ms)\e[0m   \e[0mDROP TABLE `products`\e[0m
-  \e[4;36;1mSQL (6.3ms)\e[0m   \e[0;1mDELETE FROM schema_migrations WHERE version = '20080724075409'\e[0m
-  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
-  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;35;1mSQL (1.2ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
-Migrating to CreateProducts (20080724075409)
-  \e[4;35;1mSQL (3.4ms)\e[0m   \e[0mCREATE TABLE `products` (`id` int(11) DEFAULT NULL auto_increment PRIMARY KEY, `title` varchar(255), `description` text, `image_url` varchar(255), `created_at` datetime, `updated_at` datetime) ENGINE=InnoDB\e[0m
-  \e[4;36;1mSQL (0.8ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20080724075409')\e[0m
-Migrating to AddProductPrice (20090128142730)
-  \e[4;35;1mSQL (6.6ms)\e[0m   \e[0mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 0\e[0m
-  \e[4;36;1mSQL (0.7ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090128142730')\e[0m
-Migrating to AddTestData (20090130111521)
-  \e[4;35;1mProduct Delete all (0.4ms)\e[0m   \e[0mDELETE FROM `products` \e[0m
-  \e[4;36;1mProduct Columns (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
-  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
-  \e[4;36;1mProduct Exists (0.6ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Pragmatic Project Automation') LIMIT 1\e[0m
-  \e[4;35;1mProduct Create (0.6ms)\e[0m   \e[0mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `description`, `created_at`) VALUES('/images/auto.jpg', '2009-01-30 11:26:48', 'Pragmatic Project Automation', 29.95, '<p>\n <em>Pragmatic Project Automation</em> shows you how to improve the \n consistency and repeatability of your project\'s procedures using \n automation to reduce risk and errors.\n </p>\n <p>\n Simply put, we\'re going to put this thing called a computer to work \n for you doing the mundane (but important) project stuff. That means \n you\'ll have more time and energy to do the really \n exciting---and difficult---stuff, like writing quality code.\n </p>', '2009-01-30 11:26:48')\e[0m
-  \e[4;36;1mSQL (0.7ms)\e[0m   \e[0;1mCOMMIT\e[0m
-  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
-  \e[4;36;1mProduct Exists (0.4ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Pragmatic Version Control') LIMIT 1\e[0m
-  \e[4;35;1mProduct Create (1.0ms)\e[0m   \e[0mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `description`, `created_at`) VALUES('/images/svn.jpg', '2009-01-30 11:26:48', 'Pragmatic Version Control', 28.5, '<p>\n This book is a recipe-based approach to using Subversion that will \n get you up and running quickly&emdash;and correctly. All projects need\n version control: it\'s a foundational piece of any project\'s \n infrastructure. Yet half of all project teams in the U.S. don\'t use\n any version control at all. Many others don\'t use it well, and end \n up experiencing time-consuming problems.\n </p>', '2009-01-30 11:26:48')\e[0m
-  \e[4;36;1mSQL (1.8ms)\e[0m   \e[0;1mCOMMIT\e[0m
-  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
-  \e[4;36;1mProduct Exists (0.4ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Pragmatic Unit Testing (C#)') LIMIT 1\e[0m
-  \e[4;35;1mProduct Create (0.4ms)\e[0m   \e[0mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `description`, `created_at`) VALUES('/images/utc.jpg', '2009-01-30 11:26:48', 'Pragmatic Unit Testing (C#)', 27.75, '<p>\n Pragmatic programmers use feedback to drive their development and \n personal processes. The most valuable feedback you can get while \n coding comes from unit testing.\n </p>\n <p>\n Without good tests in place, coding can become a frustrating game of \n \"whack-a-mole.\" That\'s the carnival game where the player strikes at a \n mechanical mole; it retreats and another mole pops up on the opposite side \n of the field. The moles pop up and down so fast that you end up flailing \n your mallet helplessly as the moles continue to pop up where you least \n expect them.\n </p>', '2009-01-30 11:26:48')\e[0m
-  \e[4;36;1mSQL (2.0ms)\e[0m   \e[0;1mCOMMIT\e[0m
-  \e[4;35;1mSQL (8.2ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090130111521')\e[0m
+  \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+Completed in 32ms (View: 18, DB: 2) | 200 OK [http://localhost/products]
+  \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;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+Migrating to AddDateAvailableToProduct (20090202160925)
+  \e[4;36;1mSQL (29.2ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `date_available`\e[0m
+  \e[4;35;1mSQL (7.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160925'\e[0m
+Migrating to AddPriceToProduct (20090202160745)
+  \e[4;36;1mSQL (24.1ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `price`\e[0m
+  \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160745'\e[0m
+Migrating to CreateProducts (20090202160210)
+  \e[4;36;1mSQL (1.4ms)\e[0m   \e[0;1mDROP TABLE `products`\e[0m
+  \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160210'\e[0m
   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
   \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+  \e[4;36;1mSQL (0.5ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \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;1mSQL (0.8ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+Migrating to CreateProducts (20090202160210)
+  \e[4;36;1mSQL (175.4ms)\e[0m   \e[0;1mCREATE TABLE `products` (`id` int(11) DEFAULT NULL auto_increment PRIMARY KEY, `title` varchar(255), `description` text, `image_url` varchar(255), `created_at` datetime, `updated_at` datetime) ENGINE=InnoDB\e[0m
+  \e[4;35;1mSQL (7.5ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160210')\e[0m
+Migrating to AddPriceToProduct (20090202160745)
+  \e[4;36;1mSQL (7.8ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 0\e[0m
+  \e[4;35;1mSQL (0.6ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160745')\e[0m
+Migrating to AddDateAvailableToProduct (20090202160925)
+  \e[4;36;1mSQL (12.4ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `date_available` date\e[0m
+  \e[4;35;1mSQL (4.0ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160925')\e[0m
+  \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
-  \e[4;35;1mSQL (1.0ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
-  \e[4;36;1mSQL (1.0ms)\e[0m   \e[0;1mdescribe `products`\e[0m
+  \e[4;35;1mSQL (1.7ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+  \e[4;36;1mSQL (1.1ms)\e[0m   \e[0;1mdescribe `products`\e[0m
   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW KEYS FROM `products`\e[0m
 
 
-Processing ProductsController#index (for 127.0.0.1 at 2009-01-30 11:26:52) [GET]
+Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 16:18:14) [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.4ms)\e[0m   \e[0;1mSELECT * FROM `products` \e[0m
+  \e[4;36;1mProduct Load (0.3ms)\e[0m   \e[0;1mSELECT * FROM `products` \e[0m
 Rendering template within layouts/products
 Rendering products/index
-  \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
-Completed in 37ms (View: 24, DB: 2) | 200 OK [http://localhost/products]
-  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
-  \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
-Migrating to AddTestData (20090130111521)
-  \e[4;35;1mProduct Delete all (12.5ms)\e[0m   \e[0mDELETE FROM `products` \e[0m
-  \e[4;36;1mSQL (12.4ms)\e[0m   \e[0;1mDELETE FROM schema_migrations WHERE version = '20090130111521'\e[0m
-Migrating to AddProductPrice (20090128142730)
-  \e[4;35;1mSQL (22.2ms)\e[0m   \e[0mALTER TABLE `products` DROP `price`\e[0m
-  \e[4;36;1mSQL (0.7ms)\e[0m   \e[0;1mDELETE FROM schema_migrations WHERE version = '20090128142730'\e[0m
-Migrating to CreateProducts (20080724075409)
-  \e[4;35;1mSQL (6.1ms)\e[0m   \e[0mDROP TABLE `products`\e[0m
-  \e[4;36;1mSQL (0.9ms)\e[0m   \e[0;1mDELETE FROM schema_migrations WHERE version = '20080724075409'\e[0m
-  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
-  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
-  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
-Migrating to CreateProducts (20080724075409)
-  \e[4;35;1mSQL (4.6ms)\e[0m   \e[0mCREATE TABLE `products` (`id` int(11) DEFAULT NULL auto_increment PRIMARY KEY, `title` varchar(255), `description` text, `image_url` varchar(255), `created_at` datetime, `updated_at` datetime) ENGINE=InnoDB\e[0m
-  \e[4;36;1mSQL (0.7ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20080724075409')\e[0m
-Migrating to AddProductPrice (20090128142730)
-  \e[4;35;1mSQL (7.3ms)\e[0m   \e[0mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 0\e[0m
-  \e[4;36;1mSQL (0.8ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090128142730')\e[0m
-Migrating to AddTestData (20090130111521)
-  \e[4;35;1mProduct Delete all (1.1ms)\e[0m   \e[0mDELETE FROM `products` \e[0m
-  \e[4;36;1mProduct Columns (2.5ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
-  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mBEGIN\e[0m
-  \e[4;36;1mProduct Exists (0.5ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Pragmatic Project Automation') LIMIT 1\e[0m
-  \e[4;35;1mProduct Create (0.4ms)\e[0m   \e[0mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `description`, `created_at`) VALUES('/images/auto.jpg', '2009-01-30 11:28:11', 'Pragmatic Project Automation', 29.95, '<p>\n <em>Pragmatic Project Automation</em> shows you how to improve the \n consistency and repeatability of your project\'s procedures using \n automation to reduce risk and errors.\n </p>\n <p>\n Simply put, we\'re going to put this thing called a computer to work \n for you doing the mundane (but important) project stuff. That means \n you\'ll have more time and energy to do the really \n exciting&mdash;and difficult&mdash;stuff, like writing quality code.\n </p>', '2009-01-30 11:28:11')\e[0m
-  \e[4;36;1mSQL (0.5ms)\e[0m   \e[0;1mCOMMIT\e[0m
-  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
-  \e[4;36;1mProduct Exists (0.4ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Pragmatic Version Control') LIMIT 1\e[0m
-  \e[4;35;1mProduct Create (0.3ms)\e[0m   \e[0mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `description`, `created_at`) VALUES('/images/svn.jpg', '2009-01-30 11:28:11', 'Pragmatic Version Control', 28.5, '<p>\n This book is a recipe-based approach to using Subversion that will \n get you up and running quickly&mdash;and correctly. All projects need\n version control: it\'s a foundational piece of any project\'s \n infrastructure. Yet half of all project teams in the U.S. don\'t use\n any version control at all. Many others don\'t use it well, and end \n up experiencing time-consuming problems.\n </p>', '2009-01-30 11:28:11')\e[0m
-  \e[4;36;1mSQL (0.5ms)\e[0m   \e[0;1mCOMMIT\e[0m
-  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mBEGIN\e[0m
-  \e[4;36;1mProduct Exists (0.6ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Pragmatic Unit Testing (C#)') LIMIT 1\e[0m
-  \e[4;35;1mProduct Create (0.3ms)\e[0m   \e[0mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `description`, `created_at`) VALUES('/images/utc.jpg', '2009-01-30 11:28:11', 'Pragmatic Unit Testing (C#)', 27.75, '<p>\n Pragmatic programmers use feedback to drive their development and \n personal processes. The most valuable feedback you can get while \n coding comes from unit testing.\n </p>\n <p>\n Without good tests in place, coding can become a frustrating game of \n \"whack-a-mole.\" That\'s the carnival game where the player strikes at a \n mechanical mole; it retreats and another mole pops up on the opposite side \n of the field. The moles pop up and down so fast that you end up flailing \n your mallet helplessly as the moles continue to pop up where you least \n expect them.\n </p>', '2009-01-30 11:28:11')\e[0m
-  \e[4;36;1mSQL (1.3ms)\e[0m   \e[0;1mCOMMIT\e[0m
-  \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090130111521')\e[0m
-  \e[4;36;1mSQL (0.9ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
-  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
-  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
-  \e[4;35;1mSQL (1.0ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
-  \e[4;36;1mSQL (1.3ms)\e[0m   \e[0;1mdescribe `products`\e[0m
-  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW KEYS FROM `products`\e[0m
+Completed in 19ms (View: 8, DB: 1) | 200 OK [http://localhost/products]
 
 
-Processing ProductsController#index (for 127.0.0.1 at 2009-01-30 11:28:19) [GET]
-  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;35;1mProduct Load (1.3ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
+Processing ProductsController#new (for 127.0.0.1 at 2009-02-02 16:18:16) [GET]
+  \e[4;35;1mSQL (0.2ms)\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 Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 Rendering template within layouts/products
-Rendering products/index
-  \e[4;36;1mProduct Columns (1.2ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
-Completed in 30ms (View: 15, DB: 3) | 200 OK [http://localhost/products]
+Rendering products/new
+Completed in 41ms (View: 28, DB: 1) | 200 OK [http://localhost/products/new]
 
 
-Processing ProductsController#index (for 127.0.0.1 at 2009-01-30 11:53:52) [GET]
+Processing ProductsController#create (for 127.0.0.1 at 2009-02-02 16:18:39) [POST]
+  Parameters: {"commit"=>"Create", "authenticity_token"=>"8cca4a5b8c4003866a07c7a42d5a3b724d9e5d00", "product"=>{"image_url"=>"ss.png", "price"=>"10", "title"=>"fist prod", "date_available(1i)"=>"2009", "date_available(2i)"=>"2", "description"=>"desription and stuff", "date_available(3i)"=>"2"}}
+  \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.3ms)\e[0m   \e[0;1mSELECT * FROM `products` \e[0m
-Rendering template within layouts/products
-Rendering products/index
-  \e[4;35;1mProduct Columns (1.3ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
-Completed in 41ms (View: 24, DB: 2) | 200 OK [http://localhost/products]
+  \e[4;36;1mProduct Columns (1.5ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
+  \e[4;36;1mProduct Create (0.5ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('ss.png', '2009-02-02 16:18:39', 'fist prod', 10.0, '2009-02-02', 'desription and stuff', '2009-02-02 16:18:39')\e[0m
+  \e[4;35;1mSQL (5.2ms)\e[0m   \e[0mCOMMIT\e[0m
+Redirected to #<Product:0xb70345b4>
+Completed in 37ms (DB: 8) | 302 Found [http://localhost/products]
 
 
-Processing ProductsController#show (for 127.0.0.1 at 2009-01-30 11:53:55) [GET]
+Processing ProductsController#show (for 127.0.0.1 at 2009-02-02 16:18:39) [GET]
   Parameters: {"id"=>"1"}
-  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;35;1mProduct Columns (2.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
-  \e[4;36;1mProduct Load (0.7ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
+  \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 Columns (2.3ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
+  \e[4;35;1mProduct Load (0.6ms)\e[0m   \e[0mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
 Rendering template within layouts/products
 Rendering products/show
-Completed in 41ms (View: 20, DB: 3) | 200 OK [http://localhost/products/1]
+Completed in 49ms (View: 29, DB: 3) | 200 OK [http://localhost/products/1]
 
 
-Processing ProductsController#show (for 127.0.0.1 at 2009-01-30 11:54:35) [GET]
-  Parameters: {"id"=>"3"}
+Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 16:18:41) [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 Columns (1.3ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
-  \e[4;35;1mProduct Load (0.4ms)\e[0m   \e[0mSELECT * FROM `products` WHERE (`products`.`id` = 3) \e[0m
+  \e[4;36;1mProduct Load (0.4ms)\e[0m   \e[0;1mSELECT * FROM `products` \e[0m
 Rendering template within layouts/products
-Rendering products/show
-Completed in 29ms (View: 14, DB: 2) | 200 OK [http://localhost/products/3]
+Rendering products/index
+  \e[4;35;1mProduct Columns (1.2ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+Completed in 33ms (View: 20, DB: 2) | 200 OK [http://localhost/products]
 
 
-Processing ProductsController#index (for 127.0.0.1 at 2009-01-30 11:54:44) [GET]
-  \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 (1.2ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
-Completed in 39ms (View: 24, DB: 2) | 200 OK [http://localhost/products]
+Processing ApplicationController#index (for 127.0.0.1 at 2009-02-02 16:18:47) [GET]
 
 
-Processing ProductsController#show (for 127.0.0.1 at 2009-01-30 11:55:35) [GET]
-  Parameters: {"id"=>"3"}
-  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;36;1mProduct Columns (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
-  \e[4;35;1mProduct Load (0.1ms)\e[0m   \e[0mSELECT * FROM `products` WHERE (`products`.`id` = 3) \e[0m
-Rendering template within layouts/products
-Rendering products/show
-Completed in 27ms (View: 13, DB: 1) | 200 OK [http://localhost/products/3]
+ActionController::RoutingError (No route matches "/store/index" 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#edit (for 127.0.0.1 at 2009-01-30 11:55:43) [GET]
-  Parameters: {"id"=>"2"}
-  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;35;1mProduct Columns (1.9ms)\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` = 2) \e[0m
-Rendering template within layouts/products
-Rendering products/edit
-Completed in 33ms (View: 18, DB: 2) | 200 OK [http://localhost/products/2/edit]
 
+Processing ApplicationController#index (for 127.0.0.1 at 2009-02-02 16:18:51) [GET]
 
-Processing ProductsController#index (for 127.0.0.1 at 2009-01-30 11:56:05) [GET]
-  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;36;1mProduct Load (0.2ms)\e[0m   \e[0;1mSELECT * FROM `products` \e[0m
-Rendering template within layouts/products
-Rendering products/index
-  \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
-Completed in 35ms (View: 20, DB: 1) | 200 OK [http://localhost/products]
 
+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
 
-Processing ProductsController#show (for 127.0.0.1 at 2009-01-30 11:56:08) [GET]
-  Parameters: {"id"=>"2"}
-  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
-  \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
-  \e[4;36;1mProduct Load (0.2ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 2) \e[0m
-Rendering template within layouts/products
-Rendering products/show
-Completed in 26ms (View: 12, DB: 1) | 200 OK [http://localhost/products/2]
+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-01-30 11:56:25) [GET]
+Processing ProductsController#new (for 127.0.0.1 at 2009-02-02 16:24:25) [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.2ms)\e[0m   \e[0;1mSELECT * FROM `products` \e[0m
-Rendering template within layouts/products
+
+
+SyntaxError (/home/neil/programming/rails/depot/app/models/product.rb:7: syntax error, unexpected $undefined
+                      :with %r{\.(gif|jpg|png)$}i,
+                                ^
+/home/neil/programming/rails/depot/app/models/product.rb:7: syntax error, unexpected ')', expecting '}'
+                      :with %r{\.(gif|jpg|png)$}i,
+                                              ^
+/home/neil/programming/rails/depot/app/models/product.rb:8: syntax error, unexpected tSTRING_BEG, expecting tCOLON2 or '[' or '.'
+                      :message "must be a URL for GIF, JPG, or PNG image."
+                                ^
+/home/neil/programming/rails/depot/app/models/product.rb:14: syntax error, unexpected kEND, expecting '}'):
+    /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:382:in `load_without_new_constant_marking'
+    /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:382:in `load_file'
+    /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:381:in `load_file'
+    /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:256:in `require_or_load'
+    /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:427:in `load_missing_constant'
+    /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:77:in `const_missing'
+    /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:89:in `const_missing'
+    /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:439:in `load_missing_constant'
+    /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:93:in `const_missing'
+    /app/controllers/products_controller.rb:27:in `new'
+    /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 (91.3ms)
+Rendered /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/_request_and_response (2.1ms)
+Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (internal_server_error)
+
+
+Processing ProductsController#new (for 127.0.0.1 at 2009-02-02 16:25:14) [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 Columns (2.9ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
+Rendering template within layouts/products
+Rendering products/new
+Completed in 53ms (View: 33, DB: 3) | 200 OK [http://localhost/products/new]
+
+
+Processing ProductsController#create (for 127.0.0.1 at 2009-02-02 16:25:16) [POST]
+  Parameters: {"commit"=>"Create", "authenticity_token"=>"8cca4a5b8c4003866a07c7a42d5a3b724d9e5d00", "product"=>{"image_url"=>"", "price"=>"0.0", "title"=>"", "date_available(1i)"=>"2009", "date_available(2i)"=>"2", "description"=>"", "date_available(3i)"=>"2"}}
+  \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;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.5ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY '') LIMIT 1\e[0m
+  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mROLLBACK\e[0m
+Rendering template within layouts/products
+Rendering products/new
+Completed in 67ms (View: 24, DB: 3) | 200 OK [http://localhost/products]
+
+
+Processing ProductsController#new (for 127.0.0.1 at 2009-02-02 16:26:36) [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 Columns (2.3ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+Rendering template within layouts/products
+Rendering products/new
+Completed in 39ms (View: 21, DB: 3) | 200 OK [http://localhost/products/new]
+
+
+Processing ProductsController#create (for 127.0.0.1 at 2009-02-02 16:26:45) [POST]
+  Parameters: {"commit"=>"Create", "authenticity_token"=>"8cca4a5b8c4003866a07c7a42d5a3b724d9e5d00", "product"=>{"image_url"=>"", "price"=>"0.0", "title"=>"Second product", "date_available(1i)"=>"2009", "date_available(2i)"=>"2", "description"=>"kjhkjh", "date_available(3i)"=>"2"}}
+  \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 Columns (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
+  \e[4;36;1mProduct Exists (0.6ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Second product') LIMIT 1\e[0m
+  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mROLLBACK\e[0m
+Rendering template within layouts/products
+Rendering products/new
+Completed in 47ms (View: 17, DB: 2) | 200 OK [http://localhost/products]
+
+
+Processing ProductsController#create (for 127.0.0.1 at 2009-02-02 16:26:56) [POST]
+  Parameters: {"commit"=>"Create", "authenticity_token"=>"8cca4a5b8c4003866a07c7a42d5a3b724d9e5d00", "product"=>{"image_url"=>"host.png", "price"=>"aardvark", "title"=>"Second product", "date_available(1i)"=>"2009", "date_available(2i)"=>"2", "description"=>"kjhkjh", "date_available(3i)"=>"2"}}
+  \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 Columns (1.3ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
+  \e[4;36;1mProduct Exists (0.2ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Second product') LIMIT 1\e[0m
+  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mROLLBACK\e[0m
+Rendering template within layouts/products
+Rendering products/new
+Completed in 62ms (View: 24, DB: 2) | 200 OK [http://localhost/products]
+
+
+Processing ProductsController#create (for 127.0.0.1 at 2009-02-02 16:27:03) [POST]
+  Parameters: {"commit"=>"Create", "authenticity_token"=>"8cca4a5b8c4003866a07c7a42d5a3b724d9e5d00", "product"=>{"image_url"=>"host.png", "price"=>"22", "title"=>"Second product", "date_available(1i)"=>"2009", "date_available(2i)"=>"2", "description"=>"kjhkjh", "date_available(3i)"=>"2"}}
+  \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 Columns (1.6ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
+  \e[4;36;1mProduct Exists (0.1ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Second product') LIMIT 1\e[0m
+  \e[4;35;1mProduct Create (0.3ms)\e[0m   \e[0mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('host.png', '2009-02-02 16:27:03', 'Second product', 22.0, '2009-02-02', 'kjhkjh', '2009-02-02 16:27:03')\e[0m
+  \e[4;36;1mSQL (45.2ms)\e[0m   \e[0;1mCOMMIT\e[0m
+Redirected to #<Product:0xb71ca720>
+Completed in 80ms (DB: 48) | 302 Found [http://localhost/products]
+
+
+Processing ProductsController#show (for 127.0.0.1 at 2009-02-02 16:27:03) [GET]
+  Parameters: {"id"=>"2"}
+  \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 (2.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+  \e[4;36;1mProduct Load (0.6ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 2) \e[0m
+Rendering template within layouts/products
+Rendering products/show
+Completed in 43ms (View: 19, DB: 3) | 200 OK [http://localhost/products/2]
+
+
+Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 16:27:04) [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.4ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
+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 33ms (View: 17, DB: 2) | 200 OK [http://localhost/products]
+
+
+Processing ProductsController#edit (for 127.0.0.1 at 2009-02-02 16:27:10) [GET]
+  Parameters: {"id"=>"1"}
+  \e[4;35;1mSQL (0.2ms)\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 Columns (1.2ms)\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/edit
+Completed in 39ms (View: 25, DB: 2) | 200 OK [http://localhost/products/1/edit]
+
+
+Processing ProductsController#update (for 127.0.0.1 at 2009-02-02 16:27:18) [PUT]
+  Parameters: {"commit"=>"Update", "authenticity_token"=>"8cca4a5b8c4003866a07c7a42d5a3b724d9e5d00", "product"=>{"image_url"=>"ss.png", "price"=>"10.00", "title"=>"fist prod", "date_available(1i)"=>"2009", "date_available(2i)"=>"2", "description"=>"desription and stuff", "date_available(3i)"=>"31"}, "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.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+  \e[4;36;1mProduct Load (0.1ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
+  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mBEGIN\e[0m
+  \e[4;36;1mProduct Exists (0.5ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'fist prod' AND `products`.id <> 1) LIMIT 1\e[0m
+  \e[4;35;1mProduct Update (0.4ms)\e[0m   \e[0mUPDATE `products` SET `date_available` = '2009-03-03', `updated_at` = '2009-02-02 16:27:18' WHERE `id` = 1\e[0m
+  \e[4;36;1mSQL (2.2ms)\e[0m   \e[0;1mCOMMIT\e[0m
+Redirected to #<Product:0xb7060790>
+Completed in 37ms (DB: 5) | 302 Found [http://localhost/products/1]
+
+
+Processing ProductsController#show (for 127.0.0.1 at 2009-02-02 16:27:18) [GET]
+  Parameters: {"id"=>"1"}
+  \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 Columns (1.3ms)\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 37ms (View: 20, DB: 2) | 200 OK [http://localhost/products/1]
+
+
+Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 16:27:22) [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.4ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
+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 35ms (View: 22, DB: 2) | 200 OK [http://localhost/products]
+  \e[4;36;1mSQL (0.5ms)\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;1mSQL (16.7ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+Migrating to CreateProducts (20090202160210)
+Migrating to AddPriceToProduct (20090202160745)
+Migrating to AddDateAvailableToProduct (20090202160925)
+Migrating to AddTestData (20090202163608)
+  \e[4;36;1mProduct Delete all (44.9ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
+  \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.6ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Snowball') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.4ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/nowball.jpg', '2009-02-02 17:21:21', 'Snowball', 12.0, '2008-12-31 00:00:00', '<p>\n The perfect gift for a hot summer\'s day. What could be better \n than to surprise a loved one a gift of winter coolness? This\n snowball has been loving crafted from the purest snow, hand-collected\n from the pristine lawn of our back garden. \n </p>\n <p>\n Note that this is a perisiable item and some melting may occur \n while the snowball is in transit. \n </p>\n ', '2009-02-02 17:21:21')\e[0m
+  \e[4;35;1mSQL (5.9ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.4ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Daisy') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.4ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/daisy.gif', '2009-02-02 17:21:21', 'Daisy', 5.0, '2011-07-01 00:00:00', '<p>\n All the world is better for having flowers in it. And your home \n will be the better for having this fine example of <i>Bellis \n perennis</i> gracing its walls. The range of uses of this item are\n truly remarkable. You can place it in an eggcup of water, you can \n insert it in a jacket for a subtle buttonhole posy, or you even \n purchase a multi-pack to create your own daisy chain!\n </p>\n ', '2009-02-02 17:21:21')\e[0m
+  \e[4;35;1mSQL (0.6ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.4ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Cardboard Box') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.3ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/cardboard-box.jpg', '2009-02-02 17:21:21', 'Cardboard Box', 10.0, '2007-01-01 00:00:00', '<p>The gift that keeps on giving, you\'ll wonder how you ever did \n without a cardboard box in your home. Us it for a range of uses, \n including putting things in and keeping things in. We can provide\n you with a cardboard box that is versatile and suitable for any\n occasion. Some of our directors even use them for sleeping in!\n </p>\n ', '2009-02-02 17:21:21')\e[0m
+  \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.4ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Air') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.6ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/can-of-air.jpg', '2009-02-02 17:21:21', 'Air', 5000.0, '2009-01-01 00:00:00', '<p>An essential for all activities, <i>Air</i> is vital for your\n wellbeing and vitality. Our air is carefully packaged to contain\n only 100% pure air, with no artificial colours or preservatives.\n </p>\n <p>May contain nuts.\n </p>\n ', '2009-02-02 17:21:21')\e[0m
+  \e[4;35;1mSQL (11.8ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.6ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090202163608')\e[0m
+  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
+  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
+  \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSHOW TABLES\e[0m
+  \e[4;36;1mSQL (1.5ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
+  \e[4;35;1mSQL (1.5ms)\e[0m   \e[0mdescribe `products`\e[0m
+  \e[4;36;1mSQL (0.7ms)\e[0m   \e[0;1mSHOW KEYS FROM `products`\e[0m
+
+
+Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 17:21:25) [GET]
+  \e[4;35;1mSQL (0.1ms)\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.5ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
+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 166ms (View: 150, DB: 2) | 200 OK [http://localhost/products]
+
+
+Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 17:36:09) [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.2ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
+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 55ms (View: 38, DB: 2) | 200 OK [http://localhost/products]
+
+
+Processing ApplicationController#index (for 127.0.0.1 at 2009-02-02 17:36:09) [GET]
+
+
+ActionController::RoutingError (No route matches "/images/nowball.jpg" 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)
+  \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;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+Migrating to AddTestData (20090202163608)
+  \e[4;36;1mProduct Delete all (28.7ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
+  \e[4;35;1mSQL (5.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202163608'\e[0m
+Migrating to AddDateAvailableToProduct (20090202160925)
+  \e[4;36;1mSQL (60.2ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `date_available`\e[0m
+  \e[4;35;1mSQL (3.3ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160925'\e[0m
+Migrating to AddPriceToProduct (20090202160745)
+  \e[4;36;1mSQL (33.2ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `price`\e[0m
+  \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160745'\e[0m
+Migrating to CreateProducts (20090202160210)
+  \e[4;36;1mSQL (1.4ms)\e[0m   \e[0;1mDROP TABLE `products`\e[0m
+  \e[4;35;1mSQL (0.8ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160210'\e[0m
+  \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \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;1mSQL (0.7ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+Migrating to CreateProducts (20090202160210)
+  \e[4;36;1mSQL (9.2ms)\e[0m   \e[0;1mCREATE TABLE `products` (`id` int(11) DEFAULT NULL auto_increment PRIMARY KEY, `title` varchar(255), `description` text, `image_url` varchar(255), `created_at` datetime, `updated_at` datetime) ENGINE=InnoDB\e[0m
+  \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160210')\e[0m
+Migrating to AddPriceToProduct (20090202160745)
+  \e[4;36;1mSQL (29.7ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 0\e[0m
+  \e[4;35;1mSQL (7.3ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160745')\e[0m
+Migrating to AddDateAvailableToProduct (20090202160925)
+  \e[4;36;1mSQL (6.3ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `date_available` date\e[0m
+  \e[4;35;1mSQL (12.2ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160925')\e[0m
+Migrating to AddTestData (20090202163608)
+  \e[4;36;1mProduct Delete all (0.3ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
+  \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.5ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Snowball') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.6ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/snowball.jpg', '2009-02-02 17:36:52', 'Snowball', 12.0, '2008-12-31 00:00:00', '<p>\n The perfect gift for a hot summer\'s day. What could be better \n than to surprise a loved one a gift of winter coolness? This\n snowball has been loving crafted from the purest snow, hand-collected\n from the pristine lawn of our back garden. \n </p>\n <p>\n Note that this is a perisiable item and some melting may occur \n while the snowball is in transit. \n </p>\n ', '2009-02-02 17:36:52')\e[0m
+  \e[4;35;1mSQL (1.1ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.3ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Daisy') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.4ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/daisy.gif', '2009-02-02 17:36:52', 'Daisy', 5.0, '2011-07-01 00:00:00', '<p>\n All the world is better for having flowers in it. And your home \n will be the better for having this fine example of <i>Bellis \n perennis</i> gracing its walls. The range of uses of this item are\n truly remarkable. You can place it in an eggcup of water, you can \n insert it in a jacket for a subtle buttonhole posy, or you even \n purchase a multi-pack to create your own daisy chain!\n </p>\n ', '2009-02-02 17:36:52')\e[0m
+  \e[4;35;1mSQL (0.6ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.3ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Cardboard Box') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.3ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/cardboard-box.jpg', '2009-02-02 17:36:52', 'Cardboard Box', 10.0, '2007-01-01 00:00:00', '<p>The gift that keeps on giving, you\'ll wonder how you ever did \n without a cardboard box in your home. Us it for a range of uses, \n including putting things in and keeping things in. We can provide\n you with a cardboard box that is versatile and suitable for any\n occasion. Some of our directors even use them for sleeping in!\n </p>\n ', '2009-02-02 17:36:52')\e[0m
+  \e[4;35;1mSQL (5.4ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.3ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Air') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.3ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/can-of-air.jpg', '2009-02-02 17:36:52', 'Air', 5000.0, '2009-01-01 00:00:00', '<p>An essential for all activities, <i>Air</i> is vital for your\n wellbeing and vitality. Our air is carefully packaged to contain\n only 100% pure air, with no artificial colours or preservatives.\n </p>\n <p>May contain nuts.\n </p>\n ', '2009-02-02 17:36:52')\e[0m
+  \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.7ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090202163608')\e[0m
+  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
+  \e[4;36;1mSQL (3.5ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
+  \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mSHOW TABLES\e[0m
+  \e[4;36;1mSQL (2.4ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
+  \e[4;35;1mSQL (2.3ms)\e[0m   \e[0mdescribe `products`\e[0m
+  \e[4;36;1mSQL (1.5ms)\e[0m   \e[0;1mSHOW KEYS FROM `products`\e[0m
+
+
+Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 17:36:57) [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.7ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
+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 48ms (View: 35, DB: 2) | 200 OK [http://localhost/products]
+
+
+Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 20:17:43) [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.2ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
+Rendering template within layouts/products
+Rendering products/index
+  \e[4;36;1mProduct Columns (1.7ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
+Completed in 59ms (View: 41, DB: 2) | 200 OK [http://localhost/products]
+  \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;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+Migrating to AddTestData (20090202163608)
+  \e[4;36;1mProduct Delete all (28.1ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
+  \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202163608'\e[0m
+Migrating to AddDateAvailableToProduct (20090202160925)
+  \e[4;36;1mSQL (38.8ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `date_available`\e[0m
+  \e[4;35;1mSQL (1.1ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160925'\e[0m
+Migrating to AddPriceToProduct (20090202160745)
+  \e[4;36;1mSQL (6.4ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `price`\e[0m
+  \e[4;35;1mSQL (0.8ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160745'\e[0m
+Migrating to CreateProducts (20090202160210)
+  \e[4;36;1mSQL (1.4ms)\e[0m   \e[0;1mDROP TABLE `products`\e[0m
+  \e[4;35;1mSQL (0.9ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160210'\e[0m
+  \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \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;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+Migrating to CreateProducts (20090202160210)
+  \e[4;36;1mSQL (37.1ms)\e[0m   \e[0;1mCREATE TABLE `products` (`id` int(11) DEFAULT NULL auto_increment PRIMARY KEY, `title` varchar(255), `description` text, `image_url` varchar(255), `created_at` datetime, `updated_at` datetime) ENGINE=InnoDB\e[0m
+  \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160210')\e[0m
+Migrating to AddPriceToProduct (20090202160745)
+  \e[4;36;1mSQL (21.6ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 0\e[0m
+  \e[4;35;1mSQL (0.6ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160745')\e[0m
+Migrating to AddDateAvailableToProduct (20090202160925)
+  \e[4;36;1mSQL (6.7ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `date_available` date\e[0m
+  \e[4;35;1mSQL (0.6ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160925')\e[0m
+Migrating to AddTestData (20090202163608)
+  \e[4;36;1mProduct Delete all (0.3ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
+  \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.3ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Snowball') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.4ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/snowball.jpg', '2009-02-02 22:16:01', 'Snowball', 12.0, '2008-12-31 00:00:00', '<p>The perfect gift for a hot summer\'s day. What could be better \n than to surprise a loved one a gift of winter coolness? This\n snowball has been loving crafted from the purest snow, hand-collected\n from the pristine lawn of our back garden. \n </p>\n <p>\n Note that this is a perisiable item and some melting may occur \n while the snowball is in transit. \n </p>\n ', '2009-02-02 22:16:01')\e[0m
+  \e[4;35;1mSQL (0.6ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.4ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Daisy') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.6ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/daisy.gif', '2009-02-02 22:16:01', 'Daisy', 5.0, '2011-07-01 00:00:00', '<p>All the world is better for having flowers in it. And your home \n will be the better for having this fine example of <i>Bellis \n perennis</i> gracing its walls. The range of uses of this item are\n truly remarkable. You can place it in an eggcup of water, you can \n insert it in a jacket for a subtle buttonhole posy, or you even \n purchase a multi-pack to create your own daisy chain!\n </p>\n ', '2009-02-02 22:16:01')\e[0m
+  \e[4;35;1mSQL (1.1ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.9ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Cardboard Box') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.5ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/cardboard-box.jpg', '2009-02-02 22:16:01', 'Cardboard Box', 10.0, '2007-01-01 00:00:00', '<p>The gift that keeps on giving, you\'ll wonder how you ever did \n without a cardboard box in your home. Us it for a range of uses, \n including putting things in and keeping things in. We can provide\n you with a cardboard box that is versatile and suitable for any\n occasion. Some of our directors even use them for sleeping in!\n </p>\n ', '2009-02-02 22:16:01')\e[0m
+  \e[4;35;1mSQL (0.8ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.5ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Air') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.3ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/can-of-air.jpg', '2009-02-02 22:16:01', 'Air', 5000.0, '2009-01-01 00:00:00', '<p>An essential for all activities, <i>Air</i> is vital for your\n wellbeing and vitality. Our air is carefully packaged to contain\n only 100% pure air, with no artificial colours or preservatives.\n </p>\n <p>May contain nuts.\n </p>\n ', '2009-02-02 22:16:01')\e[0m
+  \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.6ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090202163608')\e[0m
+  \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSHOW TABLES\e[0m
+  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
+  \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSHOW TABLES\e[0m
+  \e[4;36;1mSQL (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
+  \e[4;35;1mSQL (1.0ms)\e[0m   \e[0mdescribe `products`\e[0m
+  \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW KEYS FROM `products`\e[0m
+
+
+Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 22:16:08) [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.5ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
+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 56ms (View: 38, DB: 2) | 200 OK [http://localhost/products]
+
+
+Processing ProductsController#edit (for 127.0.0.1 at 2009-02-02 22:16:31) [GET]
+  Parameters: {"id"=>"1"}
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
+  \e[4;36;1mSQL (1.3ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
+  \e[4;35;1mProduct Columns (1.8ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+  \e[4;36;1mProduct Load (0.4ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
+Rendering template within layouts/products
+Rendering products/edit
+Completed in 57ms (View: 37, DB: 4) | 200 OK [http://localhost/products/1/edit]
+  \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;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+Migrating to AddTestData (20090202163608)
+  \e[4;36;1mProduct Delete all (38.0ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
+  \e[4;35;1mSQL (0.8ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202163608'\e[0m
+Migrating to AddDateAvailableToProduct (20090202160925)
+  \e[4;36;1mSQL (26.5ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `date_available`\e[0m
+  \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160925'\e[0m
+Migrating to AddPriceToProduct (20090202160745)
+  \e[4;36;1mSQL (14.0ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `price`\e[0m
+  \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160745'\e[0m
+Migrating to CreateProducts (20090202160210)
+  \e[4;36;1mSQL (1.5ms)\e[0m   \e[0;1mDROP TABLE `products`\e[0m
+  \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160210'\e[0m
+  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \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;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+Migrating to CreateProducts (20090202160210)
+  \e[4;36;1mSQL (7.7ms)\e[0m   \e[0;1mCREATE TABLE `products` (`id` int(11) DEFAULT NULL auto_increment PRIMARY KEY, `title` varchar(255), `description` text, `image_url` varchar(255), `created_at` datetime, `updated_at` datetime) ENGINE=InnoDB\e[0m
+  \e[4;35;1mSQL (1.3ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160210')\e[0m
+Migrating to AddPriceToProduct (20090202160745)
+  \e[4;36;1mSQL (23.0ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 0\e[0m
+  \e[4;35;1mSQL (12.3ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160745')\e[0m
+Migrating to AddDateAvailableToProduct (20090202160925)
+  \e[4;36;1mSQL (17.7ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `date_available` date\e[0m
+  \e[4;35;1mSQL (2.5ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160925')\e[0m
+Migrating to AddTestData (20090202163608)
+  \e[4;36;1mProduct Delete all (0.3ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
+  \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (2.3ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Snowball') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.8ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/snowball.jpg', '2009-02-02 22:17:07', 'Snowball', 12.0, '2008-12-31 00:00:00', '<p>The perfect gift for a hot summer\'s day. What could be better \n than to surprise a loved one a gift of winter coolness? This\n snowball has been loving crafted from the purest snow, hand-collected\n from the pristine lawn of our back garden. \n </p>\n <p>\n Note that this is a perishiable item and some melting may occur \n while the snowball is in transit. \n </p>\n ', '2009-02-02 22:17:07')\e[0m
+  \e[4;35;1mSQL (1.0ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.3ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Daisy') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.7ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/daisy.gif', '2009-02-02 22:17:07', 'Daisy', 5.0, '2011-07-01 00:00:00', '<p>All the world is better for having flowers in it. And your home \n will be the better for having this fine example of <i>Bellis \n perennis</i> gracing its walls. The range of uses of this item are\n truly remarkable. You can place it in an eggcup of water, you can \n insert it in a jacket for a subtle buttonhole posy, or you even \n purchase a multi-pack to create your own daisy chain!\n </p>\n ', '2009-02-02 22:17:07')\e[0m
+  \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.3ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Cardboard Box') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.3ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/cardboard-box.jpg', '2009-02-02 22:17:07', 'Cardboard Box', 10.0, '2007-01-01 00:00:00', '<p>The gift that keeps on giving, you\'ll wonder how you ever did \n without a cardboard box in your home. Us it for a range of uses, \n including putting things in and keeping things in. We can provide\n you with a cardboard box that is versatile and suitable for any\n occasion. Some of our directors even use them for sleeping in!\n </p>\n ', '2009-02-02 22:17:07')\e[0m
+  \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.4ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Air') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.3ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/can-of-air.jpg', '2009-02-02 22:17:07', 'Air', 5000.0, '2009-01-01 00:00:00', '<p>An essential for all activities, <i>Air</i> is vital for your\n wellbeing and vitality. Our air is carefully packaged to contain\n only 100% pure air, with no artificial colours or preservatives.\n </p>\n <p>May contain nuts.\n </p>\n ', '2009-02-02 22:17:07')\e[0m
+  \e[4;35;1mSQL (8.3ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.6ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090202163608')\e[0m
+  \e[4;35;1mSQL (9.7ms)\e[0m   \e[0mSHOW TABLES\e[0m
+  \e[4;36;1mSQL (0.8ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
+  \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mSHOW TABLES\e[0m
+  \e[4;36;1mSQL (1.7ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
+  \e[4;35;1mSQL (2.1ms)\e[0m   \e[0mdescribe `products`\e[0m
+  \e[4;36;1mSQL (0.5ms)\e[0m   \e[0;1mSHOW KEYS FROM `products`\e[0m
+
+
+Processing ProductsController#edit (for 127.0.0.1 at 2009-02-02 22:17:11) [GET]
+  Parameters: {"id"=>"1"}
+  \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 Columns (1.4ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+  \e[4;36;1mProduct Load (0.4ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
+Rendering template within layouts/products
+Rendering products/edit
+Completed in 43ms (View: 26, DB: 2) | 200 OK [http://localhost/products/1/edit]
+  \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;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+Migrating to AddTestData (20090202163608)
+  \e[4;36;1mProduct Delete all (2.2ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
+  \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202163608'\e[0m
+Migrating to AddDateAvailableToProduct (20090202160925)
+  \e[4;36;1mSQL (7.5ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `date_available`\e[0m
+  \e[4;35;1mSQL (0.9ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160925'\e[0m
+Migrating to AddPriceToProduct (20090202160745)
+  \e[4;36;1mSQL (23.4ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `price`\e[0m
+  \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160745'\e[0m
+Migrating to CreateProducts (20090202160210)
+  \e[4;36;1mSQL (2.9ms)\e[0m   \e[0;1mDROP TABLE `products`\e[0m
+  \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160210'\e[0m
+  \e[4;36;1mSQL (0.8ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+  \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \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;1mSQL (0.5ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+Migrating to CreateProducts (20090202160210)
+  \e[4;36;1mSQL (37.0ms)\e[0m   \e[0;1mCREATE TABLE `products` (`id` int(11) DEFAULT NULL auto_increment PRIMARY KEY, `title` varchar(255), `description` text, `image_url` varchar(255), `created_at` datetime, `updated_at` datetime) ENGINE=InnoDB\e[0m
+  \e[4;35;1mSQL (1.3ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160210')\e[0m
+Migrating to AddPriceToProduct (20090202160745)
+  \e[4;36;1mSQL (17.0ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 0\e[0m
+  \e[4;35;1mSQL (1.9ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160745')\e[0m
+Migrating to AddDateAvailableToProduct (20090202160925)
+  \e[4;36;1mSQL (6.5ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `date_available` date\e[0m
+  \e[4;35;1mSQL (0.6ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160925')\e[0m
+Migrating to AddTestData (20090202163608)
+  \e[4;36;1mProduct Delete all (0.3ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
+  \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.3ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Snowball') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.4ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/snowball.jpg', '2009-02-02 22:17:43', 'Snowball', 12.0, '2008-12-31 00:00:00', '<p>The perfect gift for a hot summer\'s day. What could be better \n than to surprise a loved one a gift of winter coolness? This\n snowball has been loving crafted from the purest snow, hand-collected\n from the pristine lawn of our back garden. \n </p>\n <p>Note that this is a perishable item and some melting may occur \n while the snowball is in transit. \n </p>\n ', '2009-02-02 22:17:43')\e[0m
+  \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.5ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Daisy') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.4ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/daisy.gif', '2009-02-02 22:17:43', 'Daisy', 5.0, '2011-07-01 00:00:00', '<p>All the world is better for having flowers in it. And your home \n will be the better for having this fine example of <i>Bellis \n perennis</i> gracing its walls. The range of uses of this item are\n truly remarkable. You can place it in an eggcup of water, you can \n insert it in a jacket for a subtle buttonhole posy, or you even \n purchase a multi-pack to create your own daisy chain!\n </p>\n ', '2009-02-02 22:17:43')\e[0m
+  \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.4ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Cardboard Box') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.5ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/cardboard-box.jpg', '2009-02-02 22:17:43', 'Cardboard Box', 10.0, '2007-01-01 00:00:00', '<p>The gift that keeps on giving, you\'ll wonder how you ever did \n without a cardboard box in your home. Us it for a range of uses, \n including putting things in and keeping things in. We can provide\n you with a cardboard box that is versatile and suitable for any\n occasion. Some of our directors even use them for sleeping in!\n </p>\n ', '2009-02-02 22:17:43')\e[0m
+  \e[4;35;1mSQL (1.3ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.7ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Air') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.7ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/can-of-air.jpg', '2009-02-02 22:17:43', 'Air', 5000.0, '2009-01-01 00:00:00', '<p>An essential for all activities, <i>Air</i> is vital for your\n wellbeing and vitality. Our air is carefully packaged to contain\n only 100% pure air, with no artificial colours or preservatives.\n </p>\n <p>May contain nuts.\n </p>\n ', '2009-02-02 22:17:43')\e[0m
+  \e[4;35;1mSQL (9.4ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.6ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090202163608')\e[0m
+  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
+  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
+  \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSHOW TABLES\e[0m
+  \e[4;36;1mSQL (1.0ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
+  \e[4;35;1mSQL (1.1ms)\e[0m   \e[0mdescribe `products`\e[0m
+  \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW KEYS FROM `products`\e[0m
+
+
+Processing ProductsController#edit (for 127.0.0.1 at 2009-02-02 22:17:48) [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.6ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+  \e[4;36;1mProduct Load (0.4ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
+Rendering template within layouts/products
+Rendering products/edit
+Completed in 44ms (View: 27, DB: 2) | 200 OK [http://localhost/products/1/edit]
+
+
+Processing ProductsController#edit (for 127.0.0.1 at 2009-02-02 22:17:52) [GET]
+  Parameters: {"id"=>"2"}
+  \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 (2.6ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+  \e[4;36;1mProduct Load (0.8ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 2) \e[0m
+Rendering template within layouts/products
+Rendering products/edit
+Completed in 62ms (View: 37, DB: 4) | 200 OK [http://localhost/products/2/edit]
+
+
+Processing ProductsController#edit (for 127.0.0.1 at 2009-02-02 22:17:56) [GET]
+  Parameters: {"id"=>"3"}
+  \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.6ms)\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` = 3) \e[0m
+Rendering template within layouts/products
+Rendering products/edit
+Completed in 77ms (View: 50, DB: 3) | 200 OK [http://localhost/products/3/edit]
+
+
+Processing ProductsController#edit (for 127.0.0.1 at 2009-02-02 22:18:00) [GET]
+  Parameters: {"id"=>"4"}
+  \e[4;35;1mSQL (0.1ms)\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.9ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+  \e[4;36;1mProduct Load (0.6ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 4) \e[0m
+Rendering template within layouts/products
+Rendering products/edit
+Completed in 58ms (View: 37, DB: 3) | 200 OK [http://localhost/products/4/edit]
+
+
+Processing ProductsController#index (for 127.0.0.1 at 2009-02-03 08:54:41) [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 (18.9ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
+Rendering template within layouts/products
+Rendering products/index
+  \e[4;36;1mProduct Columns (28.5ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
+Completed in 190ms (View: 126, DB: 48) | 200 OK [http://localhost/products]
+  \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;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+Migrating to AddTestData (20090202163608)
+  \e[4;36;1mProduct Delete all (54.6ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
+  \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202163608'\e[0m
+Migrating to AddDateAvailableToProduct (20090202160925)
+  \e[4;36;1mSQL (82.6ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `date_available`\e[0m
+  \e[4;35;1mSQL (0.9ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160925'\e[0m
+Migrating to AddPriceToProduct (20090202160745)
+  \e[4;36;1mSQL (23.1ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `price`\e[0m
+  \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160745'\e[0m
+Migrating to CreateProducts (20090202160210)
+  \e[4;36;1mSQL (1.6ms)\e[0m   \e[0;1mDROP TABLE `products`\e[0m
+  \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160210'\e[0m
+  \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+  \e[4;36;1mSQL (0.5ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \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;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+Migrating to CreateProducts (20090202160210)
+  \e[4;36;1mSQL (40.6ms)\e[0m   \e[0;1mCREATE TABLE `products` (`id` int(11) DEFAULT NULL auto_increment PRIMARY KEY, `title` varchar(255), `description` text, `image_url` varchar(255), `created_at` datetime, `updated_at` datetime) ENGINE=InnoDB\e[0m
+  \e[4;35;1mSQL (10.8ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160210')\e[0m
+Migrating to AddPriceToProduct (20090202160745)
+  \e[4;36;1mSQL (11.9ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 0\e[0m
+  \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160745')\e[0m
+Migrating to AddDateAvailableToProduct (20090202160925)
+  \e[4;36;1mSQL (34.6ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `date_available` date\e[0m
+  \e[4;35;1mSQL (3.8ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160925')\e[0m
+Migrating to AddTestData (20090202163608)
+  \e[4;36;1mProduct Delete all (0.4ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
+  \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.3ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Snowball') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.4ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/snowball.jpg', '2009-02-03 08:55:34', 'Snowball', 11.99, '2008-12-31 00:00:00', '<p>The perfect gift for a hot summer\'s day. What could be better \n than to surprise a loved one a gift of winter coolness? This\n snowball has been loving crafted from the purest snow, hand-collected\n from the pristine lawn of our back garden. \n </p>\n <p>Note that this is a perishable item and some melting may occur \n while the snowball is in transit. \n </p>\n ', '2009-02-03 08:55:34')\e[0m
+  \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.5ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Daisy') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.4ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/daisy.gif', '2009-02-03 08:55:34', 'Daisy', 5.0, '2011-07-01 00:00:00', '<p>All the world is better for having flowers in it. And your home \n will be the better for having this fine example of <i>Bellis \n perennis</i> gracing its walls. The range of uses of this item are\n truly remarkable. You can place it in an eggcup of water, you can \n insert it in a jacket for a subtle buttonhole posy, or you even \n purchase a multi-pack to create your own daisy chain!\n </p>\n ', '2009-02-03 08:55:34')\e[0m
+  \e[4;35;1mSQL (1.5ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.3ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Cardboard Box') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.4ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/cardboard-box.jpg', '2009-02-03 08:55:34', 'Cardboard Box', 10.0, '2007-01-01 00:00:00', '<p>The gift that keeps on giving, you\'ll wonder how you ever did \n without a cardboard box in your home. Us it for a range of uses, \n including putting things in and keeping things in. We can provide\n you with a cardboard box that is versatile and suitable for any\n occasion. Some of our directors even use them for sleeping in!\n </p>\n ', '2009-02-03 08:55:34')\e[0m
+  \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.4ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Air') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.5ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/can-of-air.jpg', '2009-02-03 08:55:34', 'Air', 5000.0, '2009-01-01 00:00:00', '<p>An essential for all activities, <i>Air</i> is vital for your\n wellbeing and vitality. Our air is carefully packaged to contain\n only 100% pure air, with no artificial colours or preservatives.\n </p>\n <p>May contain nuts.\n </p>\n ', '2009-02-03 08:55:34')\e[0m
+  \e[4;35;1mSQL (3.5ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.9ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090202163608')\e[0m
+  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
+  \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
+  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
+  \e[4;36;1mSQL (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
+  \e[4;35;1mSQL (1.1ms)\e[0m   \e[0mdescribe `products`\e[0m
+  \e[4;36;1mSQL (0.5ms)\e[0m   \e[0;1mSHOW KEYS FROM `products`\e[0m
+
+
+Processing ProductsController#index (for 127.0.0.1 at 2009-02-03 08:55:37) [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.5ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
+Rendering template within layouts/products
+Rendering products/index
+  \e[4;36;1mProduct Columns (1.2ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
+Completed in 58ms (View: 42, DB: 2) | 200 OK [http://localhost/products]
+  \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;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+Migrating to AddTestData (20090202163608)
+  \e[4;36;1mProduct Delete all (27.5ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
+  \e[4;35;1mSQL (29.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202163608'\e[0m
+Migrating to AddDateAvailableToProduct (20090202160925)
+  \e[4;36;1mSQL (20.7ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `date_available`\e[0m
+  \e[4;35;1mSQL (10.0ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160925'\e[0m
+Migrating to AddPriceToProduct (20090202160745)
+  \e[4;36;1mSQL (6.5ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `price`\e[0m
+  \e[4;35;1mSQL (5.6ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160745'\e[0m
+Migrating to CreateProducts (20090202160210)
+  \e[4;36;1mSQL (1.4ms)\e[0m   \e[0;1mDROP TABLE `products`\e[0m
+  \e[4;35;1mSQL (0.8ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160210'\e[0m
+  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \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;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
+  \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
+Migrating to CreateProducts (20090202160210)
+  \e[4;36;1mSQL (43.0ms)\e[0m   \e[0;1mCREATE TABLE `products` (`id` int(11) DEFAULT NULL auto_increment PRIMARY KEY, `title` varchar(255), `description` text, `image_url` varchar(255), `created_at` datetime, `updated_at` datetime) ENGINE=InnoDB\e[0m
+  \e[4;35;1mSQL (13.7ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160210')\e[0m
+Migrating to AddPriceToProduct (20090202160745)
+  \e[4;36;1mSQL (8.1ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 0\e[0m
+  \e[4;35;1mSQL (2.7ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160745')\e[0m
+Migrating to AddDateAvailableToProduct (20090202160925)
+  \e[4;36;1mSQL (41.8ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `date_available` date\e[0m
+  \e[4;35;1mSQL (1.3ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160925')\e[0m
+Migrating to AddTestData (20090202163608)
+  \e[4;36;1mProduct Delete all (0.4ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
+  \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
+  \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.3ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Snowball') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.4ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/snowball.jpg', '2009-02-03 08:56:57', 'Snowball', 11.99, '2008-12-31 00:00:00', '<p>The perfect gift for a hot summer\'s day. What could be better \n than to surprise a loved one a gift of winter coolness? This\n snowball has been loving crafted from the purest snow, hand-collected\n from the pristine lawn of our back garden. \n </p>\n <p>Note that this is a perishable item and some melting may occur \n while the snowball is in transit. \n </p>\n ', '2009-02-03 08:56:57')\e[0m
+  \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.4ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Daisy') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.4ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/daisy.gif', '2009-02-03 08:56:57', 'Daisy', 5.0, '2011-07-01 00:00:00', '<p>All the world is better for having flowers in it. And your home \n will be the better for having this fine example of <i>Bellis \n perennis</i> gracing its walls. The range of uses of this item are\n truly remarkable. You can place it in an eggcup of water, you can \n insert it in a jacket for a subtle buttonhole posy, or you even \n purchase a multi-pack to create your own daisy chain!\n </p>\n ', '2009-02-03 08:56:57')\e[0m
+  \e[4;35;1mSQL (0.9ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (0.5ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Cardboard Box') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.6ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/cardboard-box.jpg', '2009-02-03 08:56:57', 'Cardboard Box', 10.0, '2007-01-01 00:00:00', '<p>The gift that keeps on giving, you\'ll wonder how you ever did \n without a cardboard box in your home. Us it for a range of uses, \n including putting things in and keeping things in. We can provide\n you with a cardboard box that is versatile and suitable for any\n occasion. Some of our directors even use them for sleeping in!\n </p>\n ', '2009-02-03 08:56:57')\e[0m
+  \e[4;35;1mSQL (7.1ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (2.4ms)\e[0m   \e[0;1mBEGIN\e[0m
+  \e[4;35;1mProduct Exists (4.7ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Air in a Can') LIMIT 1\e[0m
+  \e[4;36;1mProduct Create (0.4ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `date_available`, `description`, `created_at`) VALUES('/images/can-of-air.jpg', '2009-02-03 08:56:57', 'Air in a Can', 5000.0, '2009-01-01 00:00:00', '<p>An essential for all activities, <i>Air</i> is vital for your\n wellbeing and vitality. Our air is carefully packaged to contain\n only 100% pure air, with no artificial colours or preservatives.\n </p>\n <p>May contain nuts.\n </p>\n ', '2009-02-03 08:56:57')\e[0m
+  \e[4;35;1mSQL (0.9ms)\e[0m   \e[0mCOMMIT\e[0m
+  \e[4;36;1mSQL (0.8ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090202163608')\e[0m
+  \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
+  \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
+  \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSHOW TABLES\e[0m
+  \e[4;36;1mSQL (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
+  \e[4;35;1mSQL (1.1ms)\e[0m   \e[0mdescribe `products`\e[0m
+  \e[4;36;1mSQL (0.5ms)\e[0m   \e[0;1mSHOW KEYS FROM `products`\e[0m
+
+
+Processing ProductsController#index (for 127.0.0.1 at 2009-02-03 08:57:00) [GET]
+  \e[4;35;1mSQL (0.2ms)\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.5ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
+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 35ms (View: 21, DB: 2) | 200 OK [http://localhost/products]
+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]