log/development.log

   1   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
   2   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
   3   \e[4;36;1mSQL (4.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
   4   \e[4;35;1mSQL (7.1ms)\e[0m   \e[0mCREATE TABLE `schema_migrations` (`version` varchar(255) NOT NULL) ENGINE=InnoDB\e[0m
   5   \e[4;36;1mSQL (41.8ms)\e[0m   \e[0;1mCREATE UNIQUE INDEX `unique_schema_migrations` ON `schema_migrations` (`version`)\e[0m
   6   \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSHOW TABLES\e[0m
   7   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
   8 Migrating to CreateProducts (20090202160210)
   9   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
  10   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
  11   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
  12   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
  13 Migrating to CreateProducts (20090202160210)
  14   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
  15   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
  16   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
  17   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
  18   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
  19   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
  20 Migrating to CreateProducts (20090202160210)
  21   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
  22   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
  23   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
  24   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
  25 Migrating to CreateProducts (20090202160210)
  26   \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
  27   \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160210')\e[0m
  28   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
  29   \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
  30   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
  31   \e[4;35;1mSQL (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
  32   \e[4;36;1mSQL (1.2ms)\e[0m   \e[0;1mdescribe `products`\e[0m
  33   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW KEYS FROM `products`\e[0m
  34
  35
  36 Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 16:05:24) [GET]
  37
  38
  39 CGI::Session::CookieStore::TamperedWithCookie (CGI::Session::CookieStore::TamperedWithCookie):
  40     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/session/cookie_store.rb:145:in `unmarshal'
  41     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/session/cookie_store.rb:102:in `restore'
  42     /usr/lib/ruby/1.8/cgi/session.rb:304:in `[]'
  43     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/cgi_process.rb:98:in `session'
  44     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/cgi_process.rb:130:in `stale_session_check!'
  45     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/cgi_process.rb:78:in `session'
  46     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:1205:in `assign_shortcuts_without_flash'
  47     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/flash.rb:166:in `assign_shortcuts'
  48     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:519:in `process_without_filters'
  49     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:606:in `process_without_session_management_support'
  50     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/session_management.rb:134:in `process'
  51     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:392:in `process'
  52     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:183:in `handle_request'
  53     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
  54     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
  55     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
  56     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
  57     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
  58     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
  59     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
  60     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
  61     /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
  62     /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
  63     /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
  64     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
  65     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
  66     /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
  67     /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
  68     /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
  69     /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
  70     /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
  71     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
  72     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
  73     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
  74     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
  75     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
  76     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
  77     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
  78     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
  79     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
  80     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
  81     script/server:3
  82
  83 Rendered /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/_trace (45.0ms)
  84 Rendered /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/_request_and_response (1.3ms)
  85 Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (internal_server_error)
  86
  87
  88 Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 16:06:12) [GET]
  89   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
  90   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
  91   \e[4;36;1mProduct Load (0.4ms)\e[0m   \e[0;1mSELECT * FROM `products` \e[0m
  92 Rendering template within layouts/products
  93 Rendering products/index
  94 Completed in 22ms (View: 8, DB: 1) | 200 OK [http://localhost/products]
  95
  96
  97 Processing ApplicationController#index (for 127.0.0.1 at 2009-02-02 16:06:21) [GET]
  98
  99
 100 ActionController::RoutingError (No route matches "/store/index" with {:method=>:get}):
 101     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/recognition_optimisation.rb:66:in `recognize_path'
 102     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/route_set.rb:386:in `recognize'
 103     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:182:in `handle_request'
 104     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
 105     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
 106     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
 107     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
 108     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
 109     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
 110     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
 111     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
 112     /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
 113     /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
 114     /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
 115     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
 116     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
 117     /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
 118     /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
 119     /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
 120     /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
 121     /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
 122     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
 123     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
 124     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
 125     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
 126     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
 127     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
 128     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
 129     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
 130     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
 131     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
 132     script/server:3
 133
 134 Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (not_found)
 135
 136
 137 Processing ApplicationController#index (for 127.0.0.1 at 2009-02-02 16:06:28) [GET]
 138
 139
 140 ActionController::RoutingError (No route matches "/store/index" with {:method=>:get}):
 141     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/recognition_optimisation.rb:66:in `recognize_path'
 142     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/route_set.rb:386:in `recognize'
 143     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:182:in `handle_request'
 144     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
 145     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
 146     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
 147     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
 148     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
 149     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
 150     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
 151     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
 152     /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
 153     /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
 154     /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
 155     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
 156     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
 157     /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
 158     /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
 159     /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
 160     /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
 161     /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
 162     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
 163     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
 164     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
 165     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
 166     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
 167     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
 168     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
 169     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
 170     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
 171     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
 172     script/server:3
 173
 174 Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (not_found)
 175   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
 176   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 177   \e[4;36;1mSQL (0.6ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 178   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 179 Migrating to CreateProducts (20090202160210)
 180 Migrating to AddPriceToProduct (20090202160745)
 181   \e[4;36;1mSQL (47.0ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 2\e[0m
 182   \e[4;35;1mSQL (0.8ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160745')\e[0m
 183 Migrating to AddDateAvailableToProduct (20090202160925)
 184   \e[4;36;1mSQL (8.3ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `date_available` date\e[0m
 185   \e[4;35;1mSQL (0.8ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160925')\e[0m
 186   \e[4;36;1mSQL (0.5ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 187   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 188   \e[4;36;1mSQL (0.6ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 189   \e[4;35;1mSQL (1.9ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 190   \e[4;36;1mSQL (1.8ms)\e[0m   \e[0;1mdescribe `products`\e[0m
 191   \e[4;35;1mSQL (0.6ms)\e[0m   \e[0mSHOW KEYS FROM `products`\e[0m
 192
 193
 194 Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 16:15:31) [GET]
 195   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
 196   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 197   \e[4;35;1mProduct Load (0.6ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
 198 Rendering template within layouts/products
 199 Rendering products/index
 200 Completed in 22ms (View: 8, DB: 1) | 200 OK [http://localhost/products]
 201
 202
 203 Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 16:15:56) [GET]
 204   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
 205   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 206   \e[4;36;1mProduct Load (0.2ms)\e[0m   \e[0;1mSELECT * FROM `products` \e[0m
 207 Rendering template within layouts/products
 208 Rendering products/index
 209 Completed in 22ms (View: 11, DB: 0) | 200 OK [http://localhost/products]
 210
 211
 212 Processing ProductsController#new (for 127.0.0.1 at 2009-02-02 16:16:00) [GET]
 213   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
 214   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 215   \e[4;35;1mProduct Columns (2.5ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 216 Rendering template within layouts/products
 217 Rendering products/new
 218 Completed in 57ms (View: 34, DB: 3) | 200 OK [http://localhost/products/new]
 219
 220
 221 Processing ProductsController#create (for 127.0.0.1 at 2009-02-02 16:16:18) [POST]
 222   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"}}
 223   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
 224   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 225   \e[4;36;1mProduct Columns (1.5ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 226   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
 227   \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
 228   \e[4;35;1mSQL (4.4ms)\e[0m   \e[0mCOMMIT\e[0m
 229 Redirected to #<Product:0xb71dba48>
 230 Completed in 27ms (DB: 7) | 302 Found [http://localhost/products]
 231
 232
 233 Processing ProductsController#show (for 127.0.0.1 at 2009-02-02 16:16:18) [GET]
 234   Parameters: {"id"=>"1"}
 235   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
 236   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 237   \e[4;36;1mProduct Columns (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 238   \e[4;35;1mProduct Load (0.4ms)\e[0m   \e[0mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
 239 Rendering template within layouts/products
 240 Rendering products/show
 241 Completed in 30ms (View: 12, DB: 2) | 200 OK [http://localhost/products/1]
 242
 243
 244 Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 16:16:20) [GET]
 245   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
 246   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 247   \e[4;36;1mProduct Load (0.5ms)\e[0m   \e[0;1mSELECT * FROM `products` \e[0m
 248 Rendering template within layouts/products
 249 Rendering products/index
 250   \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 251 Completed in 32ms (View: 18, DB: 2) | 200 OK [http://localhost/products]
 252   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
 253   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 254   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 255   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 256   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 257   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 258 Migrating to AddDateAvailableToProduct (20090202160925)
 259   \e[4;36;1mSQL (29.2ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `date_available`\e[0m
 260   \e[4;35;1mSQL (7.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160925'\e[0m
 261 Migrating to AddPriceToProduct (20090202160745)
 262   \e[4;36;1mSQL (24.1ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `price`\e[0m
 263   \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160745'\e[0m
 264 Migrating to CreateProducts (20090202160210)
 265   \e[4;36;1mSQL (1.4ms)\e[0m   \e[0;1mDROP TABLE `products`\e[0m
 266   \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160210'\e[0m
 267   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 268   \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 269   \e[4;36;1mSQL (0.5ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 270   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
 271   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 272   \e[4;36;1mSQL (0.8ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 273   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 274 Migrating to CreateProducts (20090202160210)
 275   \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
 276   \e[4;35;1mSQL (7.5ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160210')\e[0m
 277 Migrating to AddPriceToProduct (20090202160745)
 278   \e[4;36;1mSQL (7.8ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 0\e[0m
 279   \e[4;35;1mSQL (0.6ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160745')\e[0m
 280 Migrating to AddDateAvailableToProduct (20090202160925)
 281   \e[4;36;1mSQL (12.4ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `date_available` date\e[0m
 282   \e[4;35;1mSQL (4.0ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160925')\e[0m
 283   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 284   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 285   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 286   \e[4;35;1mSQL (1.7ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 287   \e[4;36;1mSQL (1.1ms)\e[0m   \e[0;1mdescribe `products`\e[0m
 288   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW KEYS FROM `products`\e[0m
 289
 290
 291 Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 16:18:14) [GET]
 292   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
 293   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 294   \e[4;36;1mProduct Load (0.3ms)\e[0m   \e[0;1mSELECT * FROM `products` \e[0m
 295 Rendering template within layouts/products
 296 Rendering products/index
 297 Completed in 19ms (View: 8, DB: 1) | 200 OK [http://localhost/products]
 298
 299
 300 Processing ProductsController#new (for 127.0.0.1 at 2009-02-02 16:18:16) [GET]
 301   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
 302   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 303   \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 304 Rendering template within layouts/products
 305 Rendering products/new
 306 Completed in 41ms (View: 28, DB: 1) | 200 OK [http://localhost/products/new]
 307
 308
 309 Processing ProductsController#create (for 127.0.0.1 at 2009-02-02 16:18:39) [POST]
 310   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"}}
 311   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
 312   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 313   \e[4;36;1mProduct Columns (1.5ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 314   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
 315   \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
 316   \e[4;35;1mSQL (5.2ms)\e[0m   \e[0mCOMMIT\e[0m
 317 Redirected to #<Product:0xb70345b4>
 318 Completed in 37ms (DB: 8) | 302 Found [http://localhost/products]
 319
 320
 321 Processing ProductsController#show (for 127.0.0.1 at 2009-02-02 16:18:39) [GET]
 322   Parameters: {"id"=>"1"}
 323   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
 324   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 325   \e[4;36;1mProduct Columns (2.3ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 326   \e[4;35;1mProduct Load (0.6ms)\e[0m   \e[0mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
 327 Rendering template within layouts/products
 328 Rendering products/show
 329 Completed in 49ms (View: 29, DB: 3) | 200 OK [http://localhost/products/1]
 330
 331
 332 Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 16:18:41) [GET]
 333   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
 334   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 335   \e[4;36;1mProduct Load (0.4ms)\e[0m   \e[0;1mSELECT * FROM `products` \e[0m
 336 Rendering template within layouts/products
 337 Rendering products/index
 338   \e[4;35;1mProduct Columns (1.2ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 339 Completed in 33ms (View: 20, DB: 2) | 200 OK [http://localhost/products]
 340
 341
 342 Processing ApplicationController#index (for 127.0.0.1 at 2009-02-02 16:18:47) [GET]
 343
 344
 345 ActionController::RoutingError (No route matches "/store/index" with {:method=>:get}):
 346     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/recognition_optimisation.rb:66:in `recognize_path'
 347     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/route_set.rb:386:in `recognize'
 348     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:182:in `handle_request'
 349     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
 350     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
 351     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
 352     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
 353     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
 354     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
 355     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
 356     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
 357     /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
 358     /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
 359     /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
 360     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
 361     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
 362     /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
 363     /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
 364     /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
 365     /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
 366     /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
 367     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
 368     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
 369     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
 370     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
 371     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
 372     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
 373     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
 374     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
 375     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
 376     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
 377     script/server:3
 378
 379 Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (not_found)
 380
 381
 382 Processing ApplicationController#index (for 127.0.0.1 at 2009-02-02 16:18:51) [GET]
 383
 384
 385 ActionController::RoutingError (No route matches "/store" with {:method=>:get}):
 386     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/recognition_optimisation.rb:66:in `recognize_path'
 387     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/route_set.rb:386:in `recognize'
 388     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:182:in `handle_request'
 389     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
 390     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
 391     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
 392     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
 393     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
 394     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
 395     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
 396     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
 397     /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
 398     /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
 399     /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
 400     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
 401     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
 402     /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
 403     /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
 404     /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
 405     /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
 406     /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
 407     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
 408     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
 409     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
 410     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
 411     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
 412     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
 413     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
 414     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
 415     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
 416     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
 417     script/server:3
 418
 419 Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (not_found)
 420
 421
 422 Processing ProductsController#new (for 127.0.0.1 at 2009-02-02 16:24:25) [GET]
 423   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
 424   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 425
 426
 427 SyntaxError (/home/neil/programming/rails/depot/app/models/product.rb:7: syntax error, unexpected $undefined
 428                       :with %r{\.(gif|jpg|png)$}i,
 429                                 ^
 430 /home/neil/programming/rails/depot/app/models/product.rb:7: syntax error, unexpected ')', expecting '}'
 431                       :with %r{\.(gif|jpg|png)$}i,
 432                                               ^
 433 /home/neil/programming/rails/depot/app/models/product.rb:8: syntax error, unexpected tSTRING_BEG, expecting tCOLON2 or '[' or '.'
 434                       :message "must be a URL for GIF, JPG, or PNG image."
 435                                 ^
 436 /home/neil/programming/rails/depot/app/models/product.rb:14: syntax error, unexpected kEND, expecting '}'):
 437     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:382:in `load_without_new_constant_marking'
 438     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:382:in `load_file'
 439     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
 440     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:381:in `load_file'
 441     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:256:in `require_or_load'
 442     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:427:in `load_missing_constant'
 443     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:77:in `const_missing'
 444     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:89:in `const_missing'
 445     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:439:in `load_missing_constant'
 446     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:93:in `const_missing'
 447     /app/controllers/products_controller.rb:27:in `new'
 448     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:1253:in `send'
 449     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:1253:in `perform_action_without_filters'
 450     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:617:in `call_filters'
 451     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:610:in `perform_action_without_benchmark'
 452     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
 453     /usr/lib/ruby/1.8/benchmark.rb:293:in `measure'
 454     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
 455     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/rescue.rb:136:in `perform_action_without_caching'
 456     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:13:in `perform_action'
 457     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/connection_adapters/abstract/query_cache.rb:34:in `cache'
 458     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/query_cache.rb:8:in `cache'
 459     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:12:in `perform_action'
 460     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `send'
 461     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `process_without_filters'
 462     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:606:in `process_without_session_management_support'
 463     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/session_management.rb:134:in `process'
 464     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:392:in `process'
 465     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:183:in `handle_request'
 466     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
 467     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
 468     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
 469     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
 470     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
 471     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
 472     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
 473     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
 474     /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
 475     /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
 476     /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
 477     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
 478     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
 479     /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
 480     /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
 481     /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
 482     /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
 483     /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
 484     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
 485     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
 486     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
 487     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
 488     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
 489     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
 490     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
 491     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
 492     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
 493     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
 494     script/server:3
 495
 496 Rendered /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/_trace (91.3ms)
 497 Rendered /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/_request_and_response (2.1ms)
 498 Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (internal_server_error)
 499
 500
 501 Processing ProductsController#new (for 127.0.0.1 at 2009-02-02 16:25:14) [GET]
 502   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
 503   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 504   \e[4;36;1mProduct Columns (2.9ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 505 Rendering template within layouts/products
 506 Rendering products/new
 507 Completed in 53ms (View: 33, DB: 3) | 200 OK [http://localhost/products/new]
 508
 509
 510 Processing ProductsController#create (for 127.0.0.1 at 2009-02-02 16:25:16) [POST]
 511   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"}}
 512   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
 513   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 514   \e[4;35;1mProduct Columns (1.5ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 515   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
 516   \e[4;35;1mProduct Exists (0.5ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY '') LIMIT 1\e[0m
 517   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mROLLBACK\e[0m
 518 Rendering template within layouts/products
 519 Rendering products/new
 520 Completed in 67ms (View: 24, DB: 3) | 200 OK [http://localhost/products]
 521
 522
 523 Processing ProductsController#new (for 127.0.0.1 at 2009-02-02 16:26:36) [GET]
 524   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
 525   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 526   \e[4;35;1mProduct Columns (2.3ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 527 Rendering template within layouts/products
 528 Rendering products/new
 529 Completed in 39ms (View: 21, DB: 3) | 200 OK [http://localhost/products/new]
 530
 531
 532 Processing ProductsController#create (for 127.0.0.1 at 2009-02-02 16:26:45) [POST]
 533   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"}}
 534   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
 535   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 536   \e[4;36;1mProduct Columns (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 537   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
 538   \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
 539   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mROLLBACK\e[0m
 540 Rendering template within layouts/products
 541 Rendering products/new
 542 Completed in 47ms (View: 17, DB: 2) | 200 OK [http://localhost/products]
 543
 544
 545 Processing ProductsController#create (for 127.0.0.1 at 2009-02-02 16:26:56) [POST]
 546   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"}}
 547   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
 548   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 549   \e[4;36;1mProduct Columns (1.3ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 550   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
 551   \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
 552   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mROLLBACK\e[0m
 553 Rendering template within layouts/products
 554 Rendering products/new
 555 Completed in 62ms (View: 24, DB: 2) | 200 OK [http://localhost/products]
 556
 557
 558 Processing ProductsController#create (for 127.0.0.1 at 2009-02-02 16:27:03) [POST]
 559   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"}}
 560   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
 561   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 562   \e[4;36;1mProduct Columns (1.6ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 563   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
 564   \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
 565   \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
 566   \e[4;36;1mSQL (45.2ms)\e[0m   \e[0;1mCOMMIT\e[0m
 567 Redirected to #<Product:0xb71ca720>
 568 Completed in 80ms (DB: 48) | 302 Found [http://localhost/products]
 569
 570
 571 Processing ProductsController#show (for 127.0.0.1 at 2009-02-02 16:27:03) [GET]
 572   Parameters: {"id"=>"2"}
 573   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
 574   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 575   \e[4;35;1mProduct Columns (2.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 576   \e[4;36;1mProduct Load (0.6ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 2) \e[0m
 577 Rendering template within layouts/products
 578 Rendering products/show
 579 Completed in 43ms (View: 19, DB: 3) | 200 OK [http://localhost/products/2]
 580
 581
 582 Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 16:27:04) [GET]
 583   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
 584   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 585   \e[4;35;1mProduct Load (0.4ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
 586 Rendering template within layouts/products
 587 Rendering products/index
 588   \e[4;36;1mProduct Columns (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 589 Completed in 33ms (View: 17, DB: 2) | 200 OK [http://localhost/products]
 590
 591
 592 Processing ProductsController#edit (for 127.0.0.1 at 2009-02-02 16:27:10) [GET]
 593   Parameters: {"id"=>"1"}
 594   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
 595   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 596   \e[4;35;1mProduct Columns (1.2ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 597   \e[4;36;1mProduct Load (0.5ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
 598 Rendering template within layouts/products
 599 Rendering products/edit
 600 Completed in 39ms (View: 25, DB: 2) | 200 OK [http://localhost/products/1/edit]
 601
 602
 603 Processing ProductsController#update (for 127.0.0.1 at 2009-02-02 16:27:18) [PUT]
 604   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"}
 605   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
 606   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 607   \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 608   \e[4;36;1mProduct Load (0.1ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
 609   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mBEGIN\e[0m
 610   \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
 611   \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
 612   \e[4;36;1mSQL (2.2ms)\e[0m   \e[0;1mCOMMIT\e[0m
 613 Redirected to #<Product:0xb7060790>
 614 Completed in 37ms (DB: 5) | 302 Found [http://localhost/products/1]
 615
 616
 617 Processing ProductsController#show (for 127.0.0.1 at 2009-02-02 16:27:18) [GET]
 618   Parameters: {"id"=>"1"}
 619   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
 620   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 621   \e[4;35;1mProduct Columns (1.3ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 622   \e[4;36;1mProduct Load (0.5ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
 623 Rendering template within layouts/products
 624 Rendering products/show
 625 Completed in 37ms (View: 20, DB: 2) | 200 OK [http://localhost/products/1]
 626
 627
 628 Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 16:27:22) [GET]
 629   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
 630   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 631   \e[4;35;1mProduct Load (0.4ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
 632 Rendering template within layouts/products
 633 Rendering products/index
 634   \e[4;36;1mProduct Columns (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 635 Completed in 35ms (View: 22, DB: 2) | 200 OK [http://localhost/products]
 636   \e[4;36;1mSQL (0.5ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
 637   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 638   \e[4;36;1mSQL (16.7ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 639   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 640 Migrating to CreateProducts (20090202160210)
 641 Migrating to AddPriceToProduct (20090202160745)
 642 Migrating to AddDateAvailableToProduct (20090202160925)
 643 Migrating to AddTestData (20090202163608)
 644   \e[4;36;1mProduct Delete all (44.9ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
 645   \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 646   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
 647   \e[4;35;1mProduct Exists (0.6ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Snowball') LIMIT 1\e[0m
 648   \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
 649   \e[4;35;1mSQL (5.9ms)\e[0m   \e[0mCOMMIT\e[0m
 650   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
 651   \e[4;35;1mProduct Exists (0.4ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Daisy') LIMIT 1\e[0m
 652   \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
 653   \e[4;35;1mSQL (0.6ms)\e[0m   \e[0mCOMMIT\e[0m
 654   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mBEGIN\e[0m
 655   \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
 656   \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
 657   \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mCOMMIT\e[0m
 658   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mBEGIN\e[0m
 659   \e[4;35;1mProduct Exists (0.4ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Air') LIMIT 1\e[0m
 660   \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
 661   \e[4;35;1mSQL (11.8ms)\e[0m   \e[0mCOMMIT\e[0m
 662   \e[4;36;1mSQL (0.6ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090202163608')\e[0m
 663   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
 664   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
 665   \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSHOW TABLES\e[0m
 666   \e[4;36;1mSQL (1.5ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 667   \e[4;35;1mSQL (1.5ms)\e[0m   \e[0mdescribe `products`\e[0m
 668   \e[4;36;1mSQL (0.7ms)\e[0m   \e[0;1mSHOW KEYS FROM `products`\e[0m
 669
 670
 671 Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 17:21:25) [GET]
 672   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
 673   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 674   \e[4;35;1mProduct Load (0.5ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
 675 Rendering template within layouts/products
 676 Rendering products/index
 677   \e[4;36;1mProduct Columns (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 678 Completed in 166ms (View: 150, DB: 2) | 200 OK [http://localhost/products]
 679
 680
 681 Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 17:36:09) [GET]
 682   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
 683   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 684   \e[4;35;1mProduct Load (0.2ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
 685 Rendering template within layouts/products
 686 Rendering products/index
 687   \e[4;36;1mProduct Columns (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 688 Completed in 55ms (View: 38, DB: 2) | 200 OK [http://localhost/products]
 689
 690
 691 Processing ApplicationController#index (for 127.0.0.1 at 2009-02-02 17:36:09) [GET]
 692
 693
 694 ActionController::RoutingError (No route matches "/images/nowball.jpg" with {:method=>:get}):
 695     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/recognition_optimisation.rb:66:in `recognize_path'
 696     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/route_set.rb:386:in `recognize'
 697     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:182:in `handle_request'
 698     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
 699     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
 700     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
 701     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
 702     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
 703     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
 704     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
 705     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
 706     /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
 707     /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
 708     /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
 709     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
 710     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
 711     /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
 712     /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
 713     /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
 714     /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
 715     /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
 716     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
 717     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
 718     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
 719     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
 720     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
 721     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
 722     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
 723     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
 724     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
 725     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
 726     script/server:3
 727
 728 Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (not_found)
 729   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
 730   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 731   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 732   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 733   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 734   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 735 Migrating to AddTestData (20090202163608)
 736   \e[4;36;1mProduct Delete all (28.7ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
 737   \e[4;35;1mSQL (5.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202163608'\e[0m
 738 Migrating to AddDateAvailableToProduct (20090202160925)
 739   \e[4;36;1mSQL (60.2ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `date_available`\e[0m
 740   \e[4;35;1mSQL (3.3ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160925'\e[0m
 741 Migrating to AddPriceToProduct (20090202160745)
 742   \e[4;36;1mSQL (33.2ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `price`\e[0m
 743   \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160745'\e[0m
 744 Migrating to CreateProducts (20090202160210)
 745   \e[4;36;1mSQL (1.4ms)\e[0m   \e[0;1mDROP TABLE `products`\e[0m
 746   \e[4;35;1mSQL (0.8ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160210'\e[0m
 747   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 748   \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 749   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 750   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
 751   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 752   \e[4;36;1mSQL (0.7ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 753   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 754 Migrating to CreateProducts (20090202160210)
 755   \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
 756   \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160210')\e[0m
 757 Migrating to AddPriceToProduct (20090202160745)
 758   \e[4;36;1mSQL (29.7ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 0\e[0m
 759   \e[4;35;1mSQL (7.3ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160745')\e[0m
 760 Migrating to AddDateAvailableToProduct (20090202160925)
 761   \e[4;36;1mSQL (6.3ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `date_available` date\e[0m
 762   \e[4;35;1mSQL (12.2ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160925')\e[0m
 763 Migrating to AddTestData (20090202163608)
 764   \e[4;36;1mProduct Delete all (0.3ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
 765   \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 766   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mBEGIN\e[0m
 767   \e[4;35;1mProduct Exists (0.5ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Snowball') LIMIT 1\e[0m
 768   \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
 769   \e[4;35;1mSQL (1.1ms)\e[0m   \e[0mCOMMIT\e[0m
 770   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
 771   \e[4;35;1mProduct Exists (0.3ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Daisy') LIMIT 1\e[0m
 772   \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
 773   \e[4;35;1mSQL (0.6ms)\e[0m   \e[0mCOMMIT\e[0m
 774   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
 775   \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
 776   \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
 777   \e[4;35;1mSQL (5.4ms)\e[0m   \e[0mCOMMIT\e[0m
 778   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mBEGIN\e[0m
 779   \e[4;35;1mProduct Exists (0.3ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Air') LIMIT 1\e[0m
 780   \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
 781   \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mCOMMIT\e[0m
 782   \e[4;36;1mSQL (0.7ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090202163608')\e[0m
 783   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
 784   \e[4;36;1mSQL (3.5ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
 785   \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mSHOW TABLES\e[0m
 786   \e[4;36;1mSQL (2.4ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 787   \e[4;35;1mSQL (2.3ms)\e[0m   \e[0mdescribe `products`\e[0m
 788   \e[4;36;1mSQL (1.5ms)\e[0m   \e[0;1mSHOW KEYS FROM `products`\e[0m
 789
 790
 791 Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 17:36:57) [GET]
 792   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
 793   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 794   \e[4;35;1mProduct Load (0.7ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
 795 Rendering template within layouts/products
 796 Rendering products/index
 797   \e[4;36;1mProduct Columns (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 798 Completed in 48ms (View: 35, DB: 2) | 200 OK [http://localhost/products]
 799
 800
 801 Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 20:17:43) [GET]
 802   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
 803   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 804   \e[4;35;1mProduct Load (0.2ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
 805 Rendering template within layouts/products
 806 Rendering products/index
 807   \e[4;36;1mProduct Columns (1.7ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 808 Completed in 59ms (View: 41, DB: 2) | 200 OK [http://localhost/products]
 809   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
 810   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 811   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 812   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 813   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 814   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 815 Migrating to AddTestData (20090202163608)
 816   \e[4;36;1mProduct Delete all (28.1ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
 817   \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202163608'\e[0m
 818 Migrating to AddDateAvailableToProduct (20090202160925)
 819   \e[4;36;1mSQL (38.8ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `date_available`\e[0m
 820   \e[4;35;1mSQL (1.1ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160925'\e[0m
 821 Migrating to AddPriceToProduct (20090202160745)
 822   \e[4;36;1mSQL (6.4ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `price`\e[0m
 823   \e[4;35;1mSQL (0.8ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160745'\e[0m
 824 Migrating to CreateProducts (20090202160210)
 825   \e[4;36;1mSQL (1.4ms)\e[0m   \e[0;1mDROP TABLE `products`\e[0m
 826   \e[4;35;1mSQL (0.9ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160210'\e[0m
 827   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 828   \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 829   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 830   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
 831   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 832   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 833   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 834 Migrating to CreateProducts (20090202160210)
 835   \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
 836   \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160210')\e[0m
 837 Migrating to AddPriceToProduct (20090202160745)
 838   \e[4;36;1mSQL (21.6ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 0\e[0m
 839   \e[4;35;1mSQL (0.6ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160745')\e[0m
 840 Migrating to AddDateAvailableToProduct (20090202160925)
 841   \e[4;36;1mSQL (6.7ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `date_available` date\e[0m
 842   \e[4;35;1mSQL (0.6ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160925')\e[0m
 843 Migrating to AddTestData (20090202163608)
 844   \e[4;36;1mProduct Delete all (0.3ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
 845   \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 846   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mBEGIN\e[0m
 847   \e[4;35;1mProduct Exists (0.3ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Snowball') LIMIT 1\e[0m
 848   \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
 849   \e[4;35;1mSQL (0.6ms)\e[0m   \e[0mCOMMIT\e[0m
 850   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
 851   \e[4;35;1mProduct Exists (0.4ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Daisy') LIMIT 1\e[0m
 852   \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
 853   \e[4;35;1mSQL (1.1ms)\e[0m   \e[0mCOMMIT\e[0m
 854   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mBEGIN\e[0m
 855   \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
 856   \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
 857   \e[4;35;1mSQL (0.8ms)\e[0m   \e[0mCOMMIT\e[0m
 858   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mBEGIN\e[0m
 859   \e[4;35;1mProduct Exists (0.5ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Air') LIMIT 1\e[0m
 860   \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
 861   \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mCOMMIT\e[0m
 862   \e[4;36;1mSQL (0.6ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090202163608')\e[0m
 863   \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSHOW TABLES\e[0m
 864   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
 865   \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSHOW TABLES\e[0m
 866   \e[4;36;1mSQL (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 867   \e[4;35;1mSQL (1.0ms)\e[0m   \e[0mdescribe `products`\e[0m
 868   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW KEYS FROM `products`\e[0m
 869
 870
 871 Processing ProductsController#index (for 127.0.0.1 at 2009-02-02 22:16:08) [GET]
 872   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
 873   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 874   \e[4;35;1mProduct Load (0.5ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
 875 Rendering template within layouts/products
 876 Rendering products/index
 877   \e[4;36;1mProduct Columns (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 878 Completed in 56ms (View: 38, DB: 2) | 200 OK [http://localhost/products]
 879
 880
 881 Processing ProductsController#edit (for 127.0.0.1 at 2009-02-02 22:16:31) [GET]
 882   Parameters: {"id"=>"1"}
 883   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
 884   \e[4;36;1mSQL (1.3ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 885   \e[4;35;1mProduct Columns (1.8ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 886   \e[4;36;1mProduct Load (0.4ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
 887 Rendering template within layouts/products
 888 Rendering products/edit
 889 Completed in 57ms (View: 37, DB: 4) | 200 OK [http://localhost/products/1/edit]
 890   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
 891   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 892   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 893   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 894   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 895   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 896 Migrating to AddTestData (20090202163608)
 897   \e[4;36;1mProduct Delete all (38.0ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
 898   \e[4;35;1mSQL (0.8ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202163608'\e[0m
 899 Migrating to AddDateAvailableToProduct (20090202160925)
 900   \e[4;36;1mSQL (26.5ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `date_available`\e[0m
 901   \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160925'\e[0m
 902 Migrating to AddPriceToProduct (20090202160745)
 903   \e[4;36;1mSQL (14.0ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `price`\e[0m
 904   \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160745'\e[0m
 905 Migrating to CreateProducts (20090202160210)
 906   \e[4;36;1mSQL (1.5ms)\e[0m   \e[0;1mDROP TABLE `products`\e[0m
 907   \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160210'\e[0m
 908   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 909   \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 910   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 911   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
 912   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 913   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 914   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 915 Migrating to CreateProducts (20090202160210)
 916   \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
 917   \e[4;35;1mSQL (1.3ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160210')\e[0m
 918 Migrating to AddPriceToProduct (20090202160745)
 919   \e[4;36;1mSQL (23.0ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 0\e[0m
 920   \e[4;35;1mSQL (12.3ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160745')\e[0m
 921 Migrating to AddDateAvailableToProduct (20090202160925)
 922   \e[4;36;1mSQL (17.7ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `date_available` date\e[0m
 923   \e[4;35;1mSQL (2.5ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160925')\e[0m
 924 Migrating to AddTestData (20090202163608)
 925   \e[4;36;1mProduct Delete all (0.3ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
 926   \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 927   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
 928   \e[4;35;1mProduct Exists (2.3ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Snowball') LIMIT 1\e[0m
 929   \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
 930   \e[4;35;1mSQL (1.0ms)\e[0m   \e[0mCOMMIT\e[0m
 931   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
 932   \e[4;35;1mProduct Exists (0.3ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Daisy') LIMIT 1\e[0m
 933   \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
 934   \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mCOMMIT\e[0m
 935   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
 936   \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
 937   \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
 938   \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mCOMMIT\e[0m
 939   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
 940   \e[4;35;1mProduct Exists (0.4ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Air') LIMIT 1\e[0m
 941   \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
 942   \e[4;35;1mSQL (8.3ms)\e[0m   \e[0mCOMMIT\e[0m
 943   \e[4;36;1mSQL (0.6ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090202163608')\e[0m
 944   \e[4;35;1mSQL (9.7ms)\e[0m   \e[0mSHOW TABLES\e[0m
 945   \e[4;36;1mSQL (0.8ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
 946   \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mSHOW TABLES\e[0m
 947   \e[4;36;1mSQL (1.7ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 948   \e[4;35;1mSQL (2.1ms)\e[0m   \e[0mdescribe `products`\e[0m
 949   \e[4;36;1mSQL (0.5ms)\e[0m   \e[0;1mSHOW KEYS FROM `products`\e[0m
 950
 951
 952 Processing ProductsController#edit (for 127.0.0.1 at 2009-02-02 22:17:11) [GET]
 953   Parameters: {"id"=>"1"}
 954   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
 955   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 956   \e[4;35;1mProduct Columns (1.4ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 957   \e[4;36;1mProduct Load (0.4ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
 958 Rendering template within layouts/products
 959 Rendering products/edit
 960 Completed in 43ms (View: 26, DB: 2) | 200 OK [http://localhost/products/1/edit]
 961   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
 962   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 963   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 964   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 965   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 966   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 967 Migrating to AddTestData (20090202163608)
 968   \e[4;36;1mProduct Delete all (2.2ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
 969   \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202163608'\e[0m
 970 Migrating to AddDateAvailableToProduct (20090202160925)
 971   \e[4;36;1mSQL (7.5ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `date_available`\e[0m
 972   \e[4;35;1mSQL (0.9ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160925'\e[0m
 973 Migrating to AddPriceToProduct (20090202160745)
 974   \e[4;36;1mSQL (23.4ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `price`\e[0m
 975   \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160745'\e[0m
 976 Migrating to CreateProducts (20090202160210)
 977   \e[4;36;1mSQL (2.9ms)\e[0m   \e[0;1mDROP TABLE `products`\e[0m
 978   \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160210'\e[0m
 979   \e[4;36;1mSQL (0.8ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 980   \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 981   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 982   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
 983   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 984   \e[4;36;1mSQL (0.5ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 985   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 986 Migrating to CreateProducts (20090202160210)
 987   \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
 988   \e[4;35;1mSQL (1.3ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160210')\e[0m
 989 Migrating to AddPriceToProduct (20090202160745)
 990   \e[4;36;1mSQL (17.0ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 0\e[0m
 991   \e[4;35;1mSQL (1.9ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160745')\e[0m
 992 Migrating to AddDateAvailableToProduct (20090202160925)
 993   \e[4;36;1mSQL (6.5ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `date_available` date\e[0m
 994   \e[4;35;1mSQL (0.6ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160925')\e[0m
 995 Migrating to AddTestData (20090202163608)
 996   \e[4;36;1mProduct Delete all (0.3ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
 997   \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 998   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
 999   \e[4;35;1mProduct Exists (0.3ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Snowball') LIMIT 1\e[0m
1000   \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
1001   \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mCOMMIT\e[0m
1002   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
1003   \e[4;35;1mProduct Exists (0.5ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Daisy') LIMIT 1\e[0m
1004   \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
1005   \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mCOMMIT\e[0m
1006   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
1007   \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
1008   \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
1009   \e[4;35;1mSQL (1.3ms)\e[0m   \e[0mCOMMIT\e[0m
1010   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mBEGIN\e[0m
1011   \e[4;35;1mProduct Exists (0.7ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Air') LIMIT 1\e[0m
1012   \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
1013   \e[4;35;1mSQL (9.4ms)\e[0m   \e[0mCOMMIT\e[0m
1014   \e[4;36;1mSQL (0.6ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090202163608')\e[0m
1015   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
1016   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
1017   \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSHOW TABLES\e[0m
1018   \e[4;36;1mSQL (1.0ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
1019   \e[4;35;1mSQL (1.1ms)\e[0m   \e[0mdescribe `products`\e[0m
1020   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW KEYS FROM `products`\e[0m
1021
1022
1023 Processing ProductsController#edit (for 127.0.0.1 at 2009-02-02 22:17:48) [GET]
1024   Parameters: {"id"=>"1"}
1025   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
1026   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
1027   \e[4;35;1mProduct Columns (1.6ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1028   \e[4;36;1mProduct Load (0.4ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
1029 Rendering template within layouts/products
1030 Rendering products/edit
1031 Completed in 44ms (View: 27, DB: 2) | 200 OK [http://localhost/products/1/edit]
1032
1033
1034 Processing ProductsController#edit (for 127.0.0.1 at 2009-02-02 22:17:52) [GET]
1035   Parameters: {"id"=>"2"}
1036   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
1037   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
1038   \e[4;35;1mProduct Columns (2.6ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1039   \e[4;36;1mProduct Load (0.8ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 2) \e[0m
1040 Rendering template within layouts/products
1041 Rendering products/edit
1042 Completed in 62ms (View: 37, DB: 4) | 200 OK [http://localhost/products/2/edit]
1043
1044
1045 Processing ProductsController#edit (for 127.0.0.1 at 2009-02-02 22:17:56) [GET]
1046   Parameters: {"id"=>"3"}
1047   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
1048   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
1049   \e[4;35;1mProduct Columns (1.6ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1050   \e[4;36;1mProduct Load (0.5ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 3) \e[0m
1051 Rendering template within layouts/products
1052 Rendering products/edit
1053 Completed in 77ms (View: 50, DB: 3) | 200 OK [http://localhost/products/3/edit]
1054
1055
1056 Processing ProductsController#edit (for 127.0.0.1 at 2009-02-02 22:18:00) [GET]
1057   Parameters: {"id"=>"4"}
1058   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
1059   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
1060   \e[4;35;1mProduct Columns (1.9ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1061   \e[4;36;1mProduct Load (0.6ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 4) \e[0m
1062 Rendering template within layouts/products
1063 Rendering products/edit
1064 Completed in 58ms (View: 37, DB: 3) | 200 OK [http://localhost/products/4/edit]
1065
1066
1067 Processing ProductsController#index (for 127.0.0.1 at 2009-02-03 08:54:41) [GET]
1068   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
1069   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
1070   \e[4;35;1mProduct Load (18.9ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
1071 Rendering template within layouts/products
1072 Rendering products/index
1073   \e[4;36;1mProduct Columns (28.5ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
1074 Completed in 190ms (View: 126, DB: 48) | 200 OK [http://localhost/products]
1075   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1076   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1077   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
1078   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
1079   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
1080   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
1081 Migrating to AddTestData (20090202163608)
1082   \e[4;36;1mProduct Delete all (54.6ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
1083   \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202163608'\e[0m
1084 Migrating to AddDateAvailableToProduct (20090202160925)
1085   \e[4;36;1mSQL (82.6ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `date_available`\e[0m
1086   \e[4;35;1mSQL (0.9ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160925'\e[0m
1087 Migrating to AddPriceToProduct (20090202160745)
1088   \e[4;36;1mSQL (23.1ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `price`\e[0m
1089   \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160745'\e[0m
1090 Migrating to CreateProducts (20090202160210)
1091   \e[4;36;1mSQL (1.6ms)\e[0m   \e[0;1mDROP TABLE `products`\e[0m
1092   \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160210'\e[0m
1093   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
1094   \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
1095   \e[4;36;1mSQL (0.5ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
1096   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1097   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1098   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
1099   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
1100 Migrating to CreateProducts (20090202160210)
1101   \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
1102   \e[4;35;1mSQL (10.8ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160210')\e[0m
1103 Migrating to AddPriceToProduct (20090202160745)
1104   \e[4;36;1mSQL (11.9ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 0\e[0m
1105   \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160745')\e[0m
1106 Migrating to AddDateAvailableToProduct (20090202160925)
1107   \e[4;36;1mSQL (34.6ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `date_available` date\e[0m
1108   \e[4;35;1mSQL (3.8ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160925')\e[0m
1109 Migrating to AddTestData (20090202163608)
1110   \e[4;36;1mProduct Delete all (0.4ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
1111   \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1112   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
1113   \e[4;35;1mProduct Exists (0.3ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Snowball') LIMIT 1\e[0m
1114   \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
1115   \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mCOMMIT\e[0m
1116   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
1117   \e[4;35;1mProduct Exists (0.5ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Daisy') LIMIT 1\e[0m
1118   \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
1119   \e[4;35;1mSQL (1.5ms)\e[0m   \e[0mCOMMIT\e[0m
1120   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
1121   \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
1122   \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
1123   \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mCOMMIT\e[0m
1124   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
1125   \e[4;35;1mProduct Exists (0.4ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Air') LIMIT 1\e[0m
1126   \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
1127   \e[4;35;1mSQL (3.5ms)\e[0m   \e[0mCOMMIT\e[0m
1128   \e[4;36;1mSQL (0.9ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090202163608')\e[0m
1129   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
1130   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
1131   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
1132   \e[4;36;1mSQL (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
1133   \e[4;35;1mSQL (1.1ms)\e[0m   \e[0mdescribe `products`\e[0m
1134   \e[4;36;1mSQL (0.5ms)\e[0m   \e[0;1mSHOW KEYS FROM `products`\e[0m
1135
1136
1137 Processing ProductsController#index (for 127.0.0.1 at 2009-02-03 08:55:37) [GET]
1138   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
1139   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
1140   \e[4;35;1mProduct Load (0.5ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
1141 Rendering template within layouts/products
1142 Rendering products/index
1143   \e[4;36;1mProduct Columns (1.2ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
1144 Completed in 58ms (View: 42, DB: 2) | 200 OK [http://localhost/products]
1145   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1146   \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1147   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
1148   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
1149   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
1150   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
1151 Migrating to AddTestData (20090202163608)
1152   \e[4;36;1mProduct Delete all (27.5ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
1153   \e[4;35;1mSQL (29.7ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202163608'\e[0m
1154 Migrating to AddDateAvailableToProduct (20090202160925)
1155   \e[4;36;1mSQL (20.7ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `date_available`\e[0m
1156   \e[4;35;1mSQL (10.0ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160925'\e[0m
1157 Migrating to AddPriceToProduct (20090202160745)
1158   \e[4;36;1mSQL (6.5ms)\e[0m   \e[0;1mALTER TABLE `products` DROP `price`\e[0m
1159   \e[4;35;1mSQL (5.6ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160745'\e[0m
1160 Migrating to CreateProducts (20090202160210)
1161   \e[4;36;1mSQL (1.4ms)\e[0m   \e[0;1mDROP TABLE `products`\e[0m
1162   \e[4;35;1mSQL (0.8ms)\e[0m   \e[0mDELETE FROM schema_migrations WHERE version = '20090202160210'\e[0m
1163   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
1164   \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
1165   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
1166   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1167   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1168   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
1169   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
1170 Migrating to CreateProducts (20090202160210)
1171   \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
1172   \e[4;35;1mSQL (13.7ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160210')\e[0m
1173 Migrating to AddPriceToProduct (20090202160745)
1174   \e[4;36;1mSQL (8.1ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 0\e[0m
1175   \e[4;35;1mSQL (2.7ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160745')\e[0m
1176 Migrating to AddDateAvailableToProduct (20090202160925)
1177   \e[4;36;1mSQL (41.8ms)\e[0m   \e[0;1mALTER TABLE `products` ADD `date_available` date\e[0m
1178   \e[4;35;1mSQL (1.3ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090202160925')\e[0m
1179 Migrating to AddTestData (20090202163608)
1180   \e[4;36;1mProduct Delete all (0.4ms)\e[0m   \e[0;1mDELETE FROM `products` \e[0m
1181   \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1182   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mBEGIN\e[0m
1183   \e[4;35;1mProduct Exists (0.3ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Snowball') LIMIT 1\e[0m
1184   \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
1185   \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mCOMMIT\e[0m
1186   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
1187   \e[4;35;1mProduct Exists (0.4ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Daisy') LIMIT 1\e[0m
1188   \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
1189   \e[4;35;1mSQL (0.9ms)\e[0m   \e[0mCOMMIT\e[0m
1190   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
1191   \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
1192   \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
1193   \e[4;35;1mSQL (7.1ms)\e[0m   \e[0mCOMMIT\e[0m
1194   \e[4;36;1mSQL (2.4ms)\e[0m   \e[0;1mBEGIN\e[0m
1195   \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
1196   \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
1197   \e[4;35;1mSQL (0.9ms)\e[0m   \e[0mCOMMIT\e[0m
1198   \e[4;36;1mSQL (0.8ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090202163608')\e[0m
1199   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
1200   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
1201   \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSHOW TABLES\e[0m
1202   \e[4;36;1mSQL (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
1203   \e[4;35;1mSQL (1.1ms)\e[0m   \e[0mdescribe `products`\e[0m
1204   \e[4;36;1mSQL (0.5ms)\e[0m   \e[0;1mSHOW KEYS FROM `products`\e[0m
1205
1206
1207 Processing ProductsController#index (for 127.0.0.1 at 2009-02-03 08:57:00) [GET]
1208   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
1209   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
1210   \e[4;35;1mProduct Load (0.5ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
1211 Rendering template within layouts/products
1212 Rendering products/index
1213   \e[4;36;1mProduct Columns (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
1214 Completed in 53ms (View: 40, DB: 2) | 200 OK [http://localhost/products]
1215
1216
1217 Processing ProductsController#index (for 127.0.0.1 at 2009-02-03 10:08:16) [GET]
1218   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
1219   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
1220   \e[4;35;1mProduct Load (0.3ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
1221 Rendering template within layouts/products
1222 Rendering products/index
1223   \e[4;36;1mProduct Columns (2.4ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
1224 Completed in 118ms (View: 80, DB: 3) | 200 OK [http://localhost/products]
1225
1226
1227 Processing ApplicationController#index (for 127.0.0.1 at 2009-02-03 10:08:16) [GET]
1228
1229
1230 ActionController::RoutingError (No route matches "/store" with {:method=>:get}):
1231     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/recognition_optimisation.rb:66:in `recognize_path'
1232     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/route_set.rb:386:in `recognize'
1233     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:182:in `handle_request'
1234     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
1235     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
1236     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
1237     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
1238     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
1239     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
1240     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
1241     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
1242     /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
1243     /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
1244     /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
1245     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
1246     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
1247     /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
1248     /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
1249     /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
1250     /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
1251     /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
1252     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
1253     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
1254     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
1255     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
1256     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
1257     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
1258     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
1259     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
1260     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
1261     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
1262     script/server:3
1263
1264 Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (not_found)
1265
1266
1267 Processing ApplicationController#index (for 127.0.0.1 at 2009-02-04 13:58:28) [GET]
1268
1269
1270 ActionController::RoutingError (No route matches "/store" with {:method=>:get}):
1271     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/recognition_optimisation.rb:66:in `recognize_path'
1272     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/route_set.rb:386:in `recognize'
1273     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:182:in `handle_request'
1274     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
1275     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
1276     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
1277     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
1278     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
1279     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
1280     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
1281     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
1282     /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
1283     /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
1284     /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
1285     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
1286     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
1287     /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
1288     /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
1289     /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
1290     /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
1291     /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
1292     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
1293     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
1294     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
1295     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
1296     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
1297     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
1298     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
1299     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
1300     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
1301     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
1302     script/server:3
1303
1304 Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (not_found)
1305
1306
1307 Processing ProductsController#index (for 127.0.0.1 at 2009-02-04 13:59:23) [GET]
1308   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
1309   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
1310   \e[4;35;1mProduct Load (0.3ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
1311 Rendering template within layouts/products
1312 Rendering products/index
1313   \e[4;36;1mProduct Columns (4.4ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
1314 Completed in 189ms (View: 154, DB: 5) | 200 OK [http://localhost/products]
1315
1316
1317 Processing ProductsController#show (for 127.0.0.1 at 2009-02-04 13:59:27) [GET]
1318   Parameters: {"id"=>"1"}
1319   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
1320   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
1321   \e[4;35;1mProduct Columns (1.5ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1322   \e[4;36;1mProduct Load (0.5ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
1323 Rendering template within layouts/products
1324 Rendering products/show
1325 Completed in 49ms (View: 30, DB: 2) | 200 OK [http://localhost/products/1]
1326
1327
1328 Processing ProductsController#index (for 127.0.0.1 at 2009-02-04 13:59:29) [GET]
1329   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET NAMES 'utf8'\e[0m
1330   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
1331   \e[4;35;1mProduct Load (0.3ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
1332 Rendering template within layouts/products
1333 Rendering products/index
1334   \e[4;36;1mProduct Columns (2.0ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
1335 Completed in 70ms (View: 49, DB: 2) | 200 OK [http://localhost/products]
1336
1337
1338 Processing ApplicationController#index (for 127.0.0.1 at 2009-02-04 13:59:32) [GET]
1339
1340
1341 ActionController::RoutingError (No route matches "/store" with {:method=>:get}):
1342     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/recognition_optimisation.rb:66:in `recognize_path'
1343     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/route_set.rb:386:in `recognize'
1344     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:182:in `handle_request'
1345     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
1346     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
1347     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
1348     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
1349     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
1350     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
1351     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
1352     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
1353     /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
1354     /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
1355     /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
1356     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
1357     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
1358     /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
1359     /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
1360     /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
1361     /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
1362     /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
1363     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
1364     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
1365     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
1366     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
1367     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
1368     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
1369     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
1370     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
1371     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
1372     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
1373     script/server:3
1374
1375 Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (not_found)
1376
1377
1378 Processing ApplicationController#index (for 127.0.0.1 at 2009-02-04 13:59:36) [GET]
1379
1380
1381 ActionController::RoutingError (No route matches "/store" with {:method=>:get}):
1382     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/recognition_optimisation.rb:66:in `recognize_path'
1383     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/routing/route_set.rb:386:in `recognize'
1384     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:182:in `handle_request'
1385     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
1386     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
1387     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
1388     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
1389     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
1390     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
1391     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
1392     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
1393     /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
1394     /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
1395     /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
1396     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
1397     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
1398     /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
1399     /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
1400     /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
1401     /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
1402     /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
1403     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
1404     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
1405     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
1406     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
1407     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
1408     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
1409     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
1410     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
1411     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
1412     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
1413     script/server:3
1414
1415 Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (not_found)
1416
1417
1418 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 14:01:27) [GET]
1419   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1420   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1421 Rendering store/index
1422 Completed in 7ms (View: 3, DB: 0) | 200 OK [http://localhost/store]
1423
1424
1425 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 14:12:04) [GET]
1426   \e[4;36;1mSQL (1.8ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1427   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1428   \e[4;36;1mSQL (14.7ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
1429   \e[4;35;1mProduct Columns (3.8ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1430
1431
1432 NameError (undefined local variable or method `conditions' for #<Class:0xb70fec24>):
1433     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/base.rb:1833:in `method_missing'
1434     /app/models/product.rb:12:in `find_products_for_sale'
1435     /app/controllers/store_controller.rb:3:in `index'
1436     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:1253:in `send'
1437     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:1253:in `perform_action_without_filters'
1438     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:617:in `call_filters'
1439     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:610:in `perform_action_without_benchmark'
1440     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
1441     /usr/lib/ruby/1.8/benchmark.rb:293:in `measure'
1442     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
1443     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/rescue.rb:136:in `perform_action_without_caching'
1444     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:13:in `perform_action'
1445     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/connection_adapters/abstract/query_cache.rb:34:in `cache'
1446     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/query_cache.rb:8:in `cache'
1447     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:12:in `perform_action'
1448     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `send'
1449     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `process_without_filters'
1450     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:606:in `process_without_session_management_support'
1451     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/session_management.rb:134:in `process'
1452     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:392:in `process'
1453     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:183:in `handle_request'
1454     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
1455     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
1456     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
1457     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
1458     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
1459     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
1460     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
1461     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
1462     /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
1463     /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
1464     /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
1465     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
1466     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
1467     /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
1468     /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
1469     /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
1470     /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
1471     /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
1472     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
1473     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
1474     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
1475     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
1476     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
1477     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
1478     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
1479     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
1480     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
1481     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
1482     script/server:3
1483
1484 Rendered /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/_trace (172.9ms)
1485 Rendered /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/_request_and_response (2.9ms)
1486 Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (internal_server_error)
1487
1488
1489 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 14:12:32) [GET]
1490   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1491   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1492   \e[4;36;1mProduct Load (141.7ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
1493 Rendering store/index
1494   \e[4;35;1mProduct Columns (2.0ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1495 Completed in 172ms (View: 18, DB: 142) | 200 OK [http://localhost/store]
1496
1497
1498 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 14:12:53) [GET]
1499   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1500   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1501   \e[4;36;1mProduct Load (0.6ms)\e[0m   \e[0;1mSELECT * FROM `products` ORDER BY title\e[0m
1502 Rendering store/index
1503   \e[4;35;1mProduct Columns (1.3ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1504 Completed in 27ms (View: 15, DB: 1) | 200 OK [http://localhost/store]
1505
1506
1507 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 14:13:05) [GET]
1508   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1509   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1510   \e[4;36;1mProduct Load (0.9ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
1511 Rendering store/index
1512   \e[4;35;1mProduct Columns (1.9ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1513 Completed in 39ms (View: 19, DB: 1) | 200 OK [http://localhost/store]
1514
1515
1516 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 14:15:08) [GET]
1517   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1518   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1519   \e[4;36;1mProduct Load (0.8ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
1520 Rendering store/index
1521   \e[4;35;1mProduct Columns (2.0ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1522 Completed in 50ms (View: 29, DB: 1) | 200 OK [http://localhost/store]
1523
1524
1525 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:32:09) [GET]
1526   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1527   \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1528   \e[4;36;1mProduct Load (0.9ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
1529 Rendering template within layouts/store
1530 Rendering store/index
1531   \e[4;35;1mProduct Columns (2.2ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1532 Completed in 39ms (View: 27, DB: 1) | 200 OK [http://localhost/store]
1533
1534
1535 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:33:51) [GET]
1536   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1537   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1538   \e[4;36;1mProduct Load (0.6ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
1539 Rendering template within layouts/store
1540 Rendering store/index
1541   \e[4;35;1mProduct Columns (1.2ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1542 Completed in 39ms (View: 25, DB: 1) | 200 OK [http://localhost/store]
1543
1544
1545 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:35:16) [GET]
1546   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1547   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1548   \e[4;36;1mProduct Load (0.9ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
1549 Rendering template within layouts/store
1550 Rendering store/index
1551   \e[4;35;1mProduct Columns (1.9ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1552 Completed in 49ms (View: 31, DB: 1) | 200 OK [http://localhost/store]
1553
1554
1555 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:38:26) [GET]
1556   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1557   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1558   \e[4;36;1mProduct Load (0.8ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
1559 Rendering template within layouts/store
1560 Rendering store/index
1561   \e[4;35;1mProduct Columns (2.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1562 Completed in 57ms (View: 38, DB: 1) | 200 OK [http://localhost/store]
1563
1564
1565 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:40:47) [GET]
1566   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1567   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1568   \e[4;36;1mProduct Load (0.9ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
1569 Rendering template within layouts/store
1570 Rendering store/index
1571   \e[4;35;1mProduct Columns (2.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1572 Completed in 51ms (View: 33, DB: 1) | 200 OK [http://localhost/store]
1573
1574
1575 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:41:01) [GET]
1576   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1577   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1578   \e[4;36;1mProduct Load (0.6ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
1579 Rendering template within layouts/store
1580 Rendering store/index
1581   \e[4;35;1mProduct Columns (1.4ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1582 Completed in 40ms (View: 26, DB: 1) | 200 OK [http://localhost/store]
1583
1584
1585 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:41:26) [GET]
1586   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1587   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1588   \e[4;36;1mProduct Load (0.6ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
1589 Rendering template within layouts/store
1590 Rendering store/index
1591   \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1592 Completed in 38ms (View: 27, DB: 1) | 200 OK [http://localhost/store]
1593
1594
1595 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:41:32) [POST]
1596   Parameters: {"authenticity_token"=>"e5d513dc6072444247eccfd6ee064972eb3383d9"}
1597   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1598   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1599   \e[4;36;1mProduct Load (0.6ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
1600 Rendering template within layouts/store
1601 Rendering store/index
1602   \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1603 Completed in 39ms (View: 28, DB: 1) | 200 OK [http://localhost/store]
1604
1605
1606 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:52:17) [POST]
1607   Parameters: {"authenticity_token"=>"e5d513dc6072444247eccfd6ee064972eb3383d9"}
1608   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1609   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1610   \e[4;36;1mProduct Load (0.9ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
1611 Rendering template within layouts/store
1612 Rendering store/index
1613   \e[4;35;1mProduct Columns (2.0ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1614
1615
1616 ActionView::TemplateError (undefined local variable or method `post' for #<ActionView::Base:0xb7046584>) on line #5 of app/views/store/index.html.erb:
1617 2:
1618 3: <% for product in @products -%>
1619 4:   <div class="entry">
1620 5:     <%= link_to image_tag(product.image_url), :method => post %>
1621 6:     <h3><%=h product.title %></h3>
1622 7:     <%= product.description %>
1623 8:     <div class="price-line">
1624
1625     app/views/store/index.html.erb:5
1626     app/views/store/index.html.erb:3:in `each'
1627     app/views/store/index.html.erb:3
1628     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_view/renderable.rb:39:in `send'
1629     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_view/renderable.rb:39:in `render'
1630     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_view/template.rb:73:in `render_template'
1631     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_view/base.rb:256:in `render'
1632     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_view/base.rb:367:in `_render_with_layout'
1633     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_view/base.rb:254:in `render'
1634     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:1174:in `render_for_file'
1635     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:896:in `render_without_benchmark'
1636     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:51:in `render'
1637     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/core_ext/benchmark.rb:8:in `realtime'
1638     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:51:in `render'
1639     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:868:in `render_without_benchmark'
1640     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:51:in `render'
1641     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/core_ext/benchmark.rb:8:in `realtime'
1642     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:51:in `render'
1643     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:1248:in `default_render'
1644     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:1254:in `perform_action_without_filters'
1645     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:617:in `call_filters'
1646     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:610:in `perform_action_without_benchmark'
1647     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
1648     /usr/lib/ruby/1.8/benchmark.rb:293:in `measure'
1649     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
1650     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/rescue.rb:136:in `perform_action_without_caching'
1651     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:13:in `perform_action'
1652     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/connection_adapters/abstract/query_cache.rb:34:in `cache'
1653     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/query_cache.rb:8:in `cache'
1654     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:12:in `perform_action'
1655     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `send'
1656     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `process_without_filters'
1657     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:606:in `process_without_session_management_support'
1658     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/session_management.rb:134:in `process'
1659     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:392:in `process'
1660     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:183:in `handle_request'
1661     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
1662     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
1663     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
1664     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
1665     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
1666     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
1667     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
1668     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
1669     /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
1670     /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
1671     /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
1672     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
1673     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
1674     /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
1675     /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
1676     /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
1677     /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
1678     /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
1679     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
1680     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
1681     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
1682     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
1683     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
1684     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
1685     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
1686     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
1687     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
1688     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
1689     script/server:3
1690
1691 Rendered /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/_trace (98.4ms)
1692 Rendered /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/_request_and_response (2.4ms)
1693 Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (internal_server_error)
1694
1695
1696 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:52:32) [POST]
1697   Parameters: {"authenticity_token"=>"e5d513dc6072444247eccfd6ee064972eb3383d9"}
1698   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1699   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1700   \e[4;36;1mProduct Load (1.0ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
1701 Rendering template within layouts/store
1702 Rendering store/index
1703   \e[4;35;1mProduct Columns (1.8ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1704 Completed in 60ms (View: 41, DB: 1) | 200 OK [http://localhost/store]
1705
1706
1707 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:52:36) [POST]
1708   Parameters: {"authenticity_token"=>"e5d513dc6072444247eccfd6ee064972eb3383d9"}
1709   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1710   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1711   \e[4;36;1mProduct Load (0.8ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
1712 Rendering template within layouts/store
1713 Rendering store/index
1714   \e[4;35;1mProduct Columns (1.6ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1715 Completed in 41ms (View: 27, DB: 1) | 200 OK [http://localhost/store]
1716
1717
1718 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:52:37) [POST]
1719   Parameters: {"authenticity_token"=>"e5d513dc6072444247eccfd6ee064972eb3383d9"}
1720   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1721   \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1722   \e[4;36;1mProduct Load (0.6ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
1723 Rendering template within layouts/store
1724 Rendering store/index
1725   \e[4;35;1mProduct Columns (2.2ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1726 Completed in 43ms (View: 31, DB: 1) | 200 OK [http://localhost/store]
1727
1728
1729 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:52:38) [GET]
1730   Parameters: {"method"=>"post"}
1731   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1732   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1733   \e[4;36;1mProduct Load (0.8ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
1734 Rendering template within layouts/store
1735 Rendering store/index
1736   \e[4;35;1mProduct Columns (1.3ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1737 Completed in 46ms (View: 26, DB: 1) | 200 OK [http://localhost/store?method=post]
1738
1739
1740 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:52:40) [GET]
1741   Parameters: {"method"=>"post"}
1742   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1743   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1744   \e[4;36;1mProduct Load (0.5ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
1745 Rendering template within layouts/store
1746 Rendering store/index
1747   \e[4;35;1mProduct Columns (1.9ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1748 Completed in 43ms (View: 30, DB: 1) | 200 OK [http://localhost/store?method=post]
1749
1750
1751 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:52:41) [POST]
1752   Parameters: {"authenticity_token"=>"e5d513dc6072444247eccfd6ee064972eb3383d9"}
1753   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1754   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1755   \e[4;36;1mProduct Load (0.5ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
1756 Rendering template within layouts/store
1757 Rendering store/index
1758   \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1759 Completed in 42ms (View: 28, DB: 1) | 200 OK [http://localhost/store]
1760
1761
1762 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:52:42) [POST]
1763   Parameters: {"authenticity_token"=>"e5d513dc6072444247eccfd6ee064972eb3383d9"}
1764   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1765   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1766   \e[4;36;1mProduct Load (0.8ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
1767 Rendering template within layouts/store
1768 Rendering store/index
1769   \e[4;35;1mProduct Columns (1.8ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1770 Completed in 50ms (View: 33, DB: 1) | 200 OK [http://localhost/store]
1771
1772
1773 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:52:44) [POST]
1774   Parameters: {"authenticity_token"=>"e5d513dc6072444247eccfd6ee064972eb3383d9"}
1775   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1776   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1777   \e[4;36;1mProduct Load (0.5ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
1778 Rendering template within layouts/store
1779 Rendering store/index
1780   \e[4;35;1mProduct Columns (1.8ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1781 Completed in 48ms (View: 28, DB: 1) | 200 OK [http://localhost/store]
1782
1783
1784 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:52:46) [GET]
1785   Parameters: {"method"=>"post"}
1786   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1787   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1788   \e[4;36;1mProduct Load (0.6ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
1789 Rendering template within layouts/store
1790 Rendering store/index
1791   \e[4;35;1mProduct Columns (1.9ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1792 Completed in 52ms (View: 37, DB: 1) | 200 OK [http://localhost/store?method=post]
1793
1794
1795 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:55:16) [GET]
1796   Parameters: {"method"=>"post"}
1797   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1798   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1799   \e[4;36;1mProduct Load (0.6ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
1800 Rendering template within layouts/store
1801 Rendering store/index
1802   \e[4;35;1mProduct Columns (2.2ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1803 Completed in 37ms (View: 26, DB: 1) | 200 OK [http://localhost/store?method=post]
1804
1805
1806 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:55:23) [POST]
1807   Parameters: {"authenticity_token"=>"e5d513dc6072444247eccfd6ee064972eb3383d9"}
1808   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1809   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1810   \e[4;36;1mProduct Load (0.7ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
1811 Rendering template within layouts/store
1812 Rendering store/index
1813   \e[4;35;1mProduct Columns (2.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1814 Completed in 50ms (View: 33, DB: 1) | 200 OK [http://localhost/store]
1815
1816
1817 Processing StoreController#add_to_cart (for 127.0.0.1 at 2009-02-04 21:55:24) [GET]
1818   Parameters: {"method"=>"post"}
1819   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1820   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1821
1822
1823 ActionController::UnknownAction (No action responded to add_to_cart. Actions: index):
1824     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:617:in `call_filters'
1825     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:610:in `perform_action_without_benchmark'
1826     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
1827     /usr/lib/ruby/1.8/benchmark.rb:293:in `measure'
1828     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
1829     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/rescue.rb:136:in `perform_action_without_caching'
1830     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:13:in `perform_action'
1831     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/connection_adapters/abstract/query_cache.rb:34:in `cache'
1832     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/query_cache.rb:8:in `cache'
1833     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:12:in `perform_action'
1834     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `send'
1835     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `process_without_filters'
1836     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:606:in `process_without_session_management_support'
1837     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/session_management.rb:134:in `process'
1838     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:392:in `process'
1839     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:183:in `handle_request'
1840     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
1841     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
1842     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
1843     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
1844     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
1845     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
1846     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
1847     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
1848     /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
1849     /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
1850     /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
1851     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
1852     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
1853     /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
1854     /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
1855     /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
1856     /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
1857     /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
1858     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
1859     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
1860     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
1861     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
1862     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
1863     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
1864     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
1865     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
1866     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
1867     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
1868     script/server:3
1869
1870 Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (not_found)
1871
1872
1873 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:57:05) [POST]
1874   Parameters: {"authenticity_token"=>"e5d513dc6072444247eccfd6ee064972eb3383d9"}
1875   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1876   \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1877   \e[4;36;1mProduct Load (0.9ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
1878 Rendering template within layouts/store
1879 Rendering store/index
1880   \e[4;35;1mProduct Columns (2.0ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1881 Completed in 71ms (View: 51, DB: 1) | 200 OK [http://localhost/store]
1882
1883
1884 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:58:21) [POST]
1885   Parameters: {"authenticity_token"=>"e5d513dc6072444247eccfd6ee064972eb3383d9"}
1886   \e[4;36;1mSQL (0.8ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1887   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1888   \e[4;36;1mProduct Load (0.9ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
1889 Rendering template within layouts/store
1890 Rendering store/index
1891   \e[4;35;1mProduct Columns (2.2ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
1892 Completed in 63ms (View: 43, DB: 2) | 200 OK [http://localhost/store]
1893
1894
1895 Processing StoreController#add_to_cart (for 127.0.0.1 at 2009-02-04 21:58:23) [GET]
1896   Parameters: {"id"=>"4", "method"=>"post"}
1897   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1898   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1899
1900
1901 ActionController::UnknownAction (No action responded to add_to_cart. Actions: index):
1902     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:617:in `call_filters'
1903     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:610:in `perform_action_without_benchmark'
1904     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
1905     /usr/lib/ruby/1.8/benchmark.rb:293:in `measure'
1906     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
1907     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/rescue.rb:136:in `perform_action_without_caching'
1908     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:13:in `perform_action'
1909     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/connection_adapters/abstract/query_cache.rb:34:in `cache'
1910     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/query_cache.rb:8:in `cache'
1911     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:12:in `perform_action'
1912     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `send'
1913     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `process_without_filters'
1914     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:606:in `process_without_session_management_support'
1915     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/session_management.rb:134:in `process'
1916     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:392:in `process'
1917     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:183:in `handle_request'
1918     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
1919     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
1920     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
1921     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
1922     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
1923     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
1924     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
1925     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
1926     /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
1927     /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
1928     /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
1929     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
1930     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
1931     /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
1932     /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
1933     /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
1934     /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
1935     /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
1936     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
1937     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
1938     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
1939     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
1940     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
1941     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
1942     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
1943     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
1944     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
1945     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
1946     script/server:3
1947
1948 Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (not_found)
1949
1950
1951 Processing StoreController#add_to_cart (for 127.0.0.1 at 2009-02-04 21:58:27) [POST]
1952   Parameters: {"authenticity_token"=>"e5d513dc6072444247eccfd6ee064972eb3383d9", "id"=>"4"}
1953   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
1954   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
1955
1956
1957 ActionController::UnknownAction (No action responded to add_to_cart. Actions: index):
1958     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:617:in `call_filters'
1959     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:610:in `perform_action_without_benchmark'
1960     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
1961     /usr/lib/ruby/1.8/benchmark.rb:293:in `measure'
1962     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
1963     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/rescue.rb:136:in `perform_action_without_caching'
1964     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:13:in `perform_action'
1965     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/connection_adapters/abstract/query_cache.rb:34:in `cache'
1966     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/query_cache.rb:8:in `cache'
1967     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:12:in `perform_action'
1968     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `send'
1969     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `process_without_filters'
1970     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:606:in `process_without_session_management_support'
1971     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/session_management.rb:134:in `process'
1972     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:392:in `process'
1973     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:183:in `handle_request'
1974     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
1975     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
1976     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
1977     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
1978     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
1979     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
1980     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
1981     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
1982     /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
1983     /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
1984     /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
1985     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
1986     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
1987     /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
1988     /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
1989     /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
1990     /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
1991     /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
1992     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
1993     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
1994     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
1995     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
1996     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
1997     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
1998     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
1999     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
2000     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
2001     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
2002     script/server:3
2003
2004 Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (not_found)
2005
2006
2007 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:58:48) [POST]
2008   Parameters: {"authenticity_token"=>"e5d513dc6072444247eccfd6ee064972eb3383d9"}
2009   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
2010   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
2011   \e[4;36;1mProduct Load (0.8ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
2012 Rendering template within layouts/store
2013 Rendering store/index
2014   \e[4;35;1mProduct Columns (2.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
2015 Completed in 61ms (View: 43, DB: 1) | 200 OK [http://localhost/store]
2016
2017
2018 Processing StoreController#add_to_cart (for 127.0.0.1 at 2009-02-04 21:58:50) [POST]
2019   Parameters: {"authenticity_token"=>"e5d513dc6072444247eccfd6ee064972eb3383d9", "id"=>"4"}
2020   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
2021   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
2022
2023
2024 ActionController::UnknownAction (No action responded to add_to_cart. Actions: index):
2025     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:617:in `call_filters'
2026     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:610:in `perform_action_without_benchmark'
2027     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
2028     /usr/lib/ruby/1.8/benchmark.rb:293:in `measure'
2029     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
2030     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/rescue.rb:136:in `perform_action_without_caching'
2031     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:13:in `perform_action'
2032     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/connection_adapters/abstract/query_cache.rb:34:in `cache'
2033     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/query_cache.rb:8:in `cache'
2034     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:12:in `perform_action'
2035     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `send'
2036     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `process_without_filters'
2037     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:606:in `process_without_session_management_support'
2038     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/session_management.rb:134:in `process'
2039     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:392:in `process'
2040     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:183:in `handle_request'
2041     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
2042     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
2043     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
2044     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
2045     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
2046     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
2047     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
2048     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
2049     /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
2050     /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
2051     /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
2052     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
2053     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
2054     /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
2055     /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
2056     /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
2057     /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
2058     /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
2059     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
2060     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
2061     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
2062     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
2063     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
2064     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
2065     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
2066     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
2067     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
2068     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
2069     script/server:3
2070
2071 Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (not_found)
2072
2073
2074 Processing StoreController#add_to_cart (for 127.0.0.1 at 2009-02-04 21:58:53) [POST]
2075   Parameters: {"authenticity_token"=>"e5d513dc6072444247eccfd6ee064972eb3383d9", "id"=>"4"}
2076   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
2077   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
2078
2079
2080 ActionController::UnknownAction (No action responded to add_to_cart. Actions: index):
2081     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:617:in `call_filters'
2082     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:610:in `perform_action_without_benchmark'
2083     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
2084     /usr/lib/ruby/1.8/benchmark.rb:293:in `measure'
2085     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
2086     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/rescue.rb:136:in `perform_action_without_caching'
2087     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:13:in `perform_action'
2088     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/connection_adapters/abstract/query_cache.rb:34:in `cache'
2089     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/query_cache.rb:8:in `cache'
2090     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:12:in `perform_action'
2091     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `send'
2092     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `process_without_filters'
2093     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:606:in `process_without_session_management_support'
2094     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/session_management.rb:134:in `process'
2095     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:392:in `process'
2096     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:183:in `handle_request'
2097     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
2098     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
2099     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
2100     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
2101     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
2102     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
2103     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
2104     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
2105     /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
2106     /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
2107     /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
2108     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
2109     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
2110     /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
2111     /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
2112     /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
2113     /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
2114     /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
2115     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
2116     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
2117     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
2118     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
2119     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
2120     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
2121     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
2122     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
2123     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
2124     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
2125     script/server:3
2126
2127 Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (not_found)
2128
2129
2130 Processing StoreController#add_to_cart (for 127.0.0.1 at 2009-02-04 21:58:57) [POST]
2131   Parameters: {"authenticity_token"=>"e5d513dc6072444247eccfd6ee064972eb3383d9", "id"=>"4"}
2132   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
2133   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
2134
2135
2136 ActionController::UnknownAction (No action responded to add_to_cart. Actions: index):
2137     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:617:in `call_filters'
2138     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:610:in `perform_action_without_benchmark'
2139     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
2140     /usr/lib/ruby/1.8/benchmark.rb:293:in `measure'
2141     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
2142     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/rescue.rb:136:in `perform_action_without_caching'
2143     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:13:in `perform_action'
2144     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/connection_adapters/abstract/query_cache.rb:34:in `cache'
2145     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/query_cache.rb:8:in `cache'
2146     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:12:in `perform_action'
2147     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `send'
2148     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `process_without_filters'
2149     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:606:in `process_without_session_management_support'
2150     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/session_management.rb:134:in `process'
2151     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:392:in `process'
2152     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:183:in `handle_request'
2153     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
2154     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
2155     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
2156     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
2157     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
2158     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
2159     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
2160     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
2161     /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
2162     /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
2163     /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
2164     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
2165     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
2166     /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
2167     /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
2168     /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
2169     /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
2170     /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
2171     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
2172     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
2173     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
2174     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
2175     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
2176     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
2177     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
2178     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
2179     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
2180     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
2181     script/server:3
2182
2183 Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (not_found)
2184
2185
2186 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 21:59:50) [POST]
2187   Parameters: {"authenticity_token"=>"e5d513dc6072444247eccfd6ee064972eb3383d9"}
2188   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
2189   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
2190   \e[4;36;1mProduct Load (1.0ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
2191 Rendering template within layouts/store
2192 Rendering store/index
2193   \e[4;35;1mProduct Columns (2.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
2194 Completed in 61ms (View: 41, DB: 1) | 200 OK [http://localhost/store]
2195
2196
2197 Processing StoreController#add_to_cart (for 127.0.0.1 at 2009-02-04 21:59:54) [POST]
2198   Parameters: {"authenticity_token"=>"e5d513dc6072444247eccfd6ee064972eb3383d9", "id"=>"4"}
2199   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
2200   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
2201
2202
2203 ActionController::UnknownAction (No action responded to add_to_cart. Actions: index):
2204     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:617:in `call_filters'
2205     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:610:in `perform_action_without_benchmark'
2206     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
2207     /usr/lib/ruby/1.8/benchmark.rb:293:in `measure'
2208     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
2209     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/rescue.rb:136:in `perform_action_without_caching'
2210     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:13:in `perform_action'
2211     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/connection_adapters/abstract/query_cache.rb:34:in `cache'
2212     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/query_cache.rb:8:in `cache'
2213     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:12:in `perform_action'
2214     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `send'
2215     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `process_without_filters'
2216     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:606:in `process_without_session_management_support'
2217     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/session_management.rb:134:in `process'
2218     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:392:in `process'
2219     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:183:in `handle_request'
2220     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
2221     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
2222     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
2223     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
2224     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
2225     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
2226     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
2227     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
2228     /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
2229     /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
2230     /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
2231     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
2232     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
2233     /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
2234     /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
2235     /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
2236     /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
2237     /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
2238     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
2239     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
2240     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
2241     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
2242     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
2243     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
2244     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
2245     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
2246     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
2247     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
2248     script/server:3
2249
2250 Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (not_found)
2251
2252
2253 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 22:00:51) [POST]
2254   Parameters: {"authenticity_token"=>"e5d513dc6072444247eccfd6ee064972eb3383d9"}
2255   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
2256   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
2257   \e[4;36;1mProduct Load (0.8ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
2258 Rendering template within layouts/store
2259 Rendering store/index
2260   \e[4;35;1mProduct Columns (2.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
2261 Completed in 64ms (View: 45, DB: 1) | 200 OK [http://localhost/store]
2262
2263
2264 Processing StoreController#index (for 127.0.0.1 at 2009-02-04 22:05:28) [POST]
2265   Parameters: {"authenticity_token"=>"e5d513dc6072444247eccfd6ee064972eb3383d9"}
2266   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
2267   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
2268   \e[4;36;1mProduct Load (0.9ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (date_available <= now()) ORDER BY title\e[0m
2269 Rendering template within layouts/store
2270 Rendering store/index
2271   \e[4;35;1mProduct Columns (2.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
2272 Completed in 72ms (View: 53, DB: 1) | 200 OK [http://localhost/store]
2273
2274
2275 Processing StoreController#add_to_cart (for 127.0.0.1 at 2009-02-04 22:05:32) [POST]
2276   Parameters: {"authenticity_token"=>"e5d513dc6072444247eccfd6ee064972eb3383d9", "id"=>"4"}
2277   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
2278   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
2279
2280
2281 ActionController::UnknownAction (No action responded to add_to_cart. Actions: index):
2282     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:617:in `call_filters'
2283     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:610:in `perform_action_without_benchmark'
2284     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
2285     /usr/lib/ruby/1.8/benchmark.rb:293:in `measure'
2286     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
2287     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/rescue.rb:136:in `perform_action_without_caching'
2288     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:13:in `perform_action'
2289     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/connection_adapters/abstract/query_cache.rb:34:in `cache'
2290     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/query_cache.rb:8:in `cache'
2291     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:12:in `perform_action'
2292     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `send'
2293     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `process_without_filters'
2294     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:606:in `process_without_session_management_support'
2295     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/session_management.rb:134:in `process'
2296     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:392:in `process'
2297     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:183:in `handle_request'
2298     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
2299     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
2300     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
2301     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
2302     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
2303     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
2304     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
2305     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
2306     /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
2307     /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
2308     /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
2309     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
2310     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
2311     /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
2312     /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
2313     /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
2314     /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
2315     /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
2316     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
2317     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
2318     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
2319     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
2320     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
2321     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
2322     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
2323     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
2324     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
2325     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
2326     script/server:3
2327
2328 Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (not_found)
2329
2330
2331 Processing StoreController#add_to_cart (for 127.0.0.1 at 2009-02-04 22:05:38) [POST]
2332   Parameters: {"authenticity_token"=>"e5d513dc6072444247eccfd6ee064972eb3383d9", "id"=>"3"}
2333   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
2334   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
2335
2336
2337 ActionController::UnknownAction (No action responded to add_to_cart. Actions: index):
2338     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:617:in `call_filters'
2339     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:610:in `perform_action_without_benchmark'
2340     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
2341     /usr/lib/ruby/1.8/benchmark.rb:293:in `measure'
2342     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
2343     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/rescue.rb:136:in `perform_action_without_caching'
2344     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:13:in `perform_action'
2345     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/connection_adapters/abstract/query_cache.rb:34:in `cache'
2346     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/query_cache.rb:8:in `cache'
2347     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:12:in `perform_action'
2348     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `send'
2349     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `process_without_filters'
2350     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:606:in `process_without_session_management_support'
2351     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/session_management.rb:134:in `process'
2352     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:392:in `process'
2353     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:183:in `handle_request'
2354     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
2355     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
2356     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
2357     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
2358     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
2359     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
2360     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
2361     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
2362     /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
2363     /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
2364     /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
2365     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
2366     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
2367     /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
2368     /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
2369     /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
2370     /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
2371     /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
2372     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
2373     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
2374     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
2375     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
2376     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
2377     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
2378     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
2379     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
2380     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
2381     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
2382     script/server:3
2383
2384 Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (not_found)
2385
2386
2387 Processing StoreController#add_to_cart (for 127.0.0.1 at 2009-02-04 22:05:42) [POST]
2388   Parameters: {"authenticity_token"=>"e5d513dc6072444247eccfd6ee064972eb3383d9", "id"=>"3"}
2389   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
2390   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
2391
2392
2393 ActionController::UnknownAction (No action responded to add_to_cart. Actions: index):
2394     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:617:in `call_filters'
2395     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:610:in `perform_action_without_benchmark'
2396     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
2397     /usr/lib/ruby/1.8/benchmark.rb:293:in `measure'
2398     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
2399     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/rescue.rb:136:in `perform_action_without_caching'
2400     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:13:in `perform_action'
2401     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/connection_adapters/abstract/query_cache.rb:34:in `cache'
2402     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/query_cache.rb:8:in `cache'
2403     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:12:in `perform_action'
2404     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `send'
2405     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `process_without_filters'
2406     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:606:in `process_without_session_management_support'
2407     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/session_management.rb:134:in `process'
2408     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:392:in `process'
2409     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:183:in `handle_request'
2410     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
2411     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
2412     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
2413     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
2414     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
2415     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
2416     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
2417     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
2418     /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
2419     /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
2420     /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
2421     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
2422     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
2423     /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
2424     /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
2425     /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
2426     /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
2427     /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
2428     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
2429     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
2430     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
2431     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
2432     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
2433     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
2434     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
2435     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
2436     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
2437     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
2438     script/server:3
2439
2440 Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (not_found)
2441
2442
2443 Processing StoreController#add_to_cart (for 127.0.0.1 at 2009-02-04 22:05:44) [POST]
2444   Parameters: {"authenticity_token"=>"e5d513dc6072444247eccfd6ee064972eb3383d9", "id"=>"3"}
2445   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET NAMES 'utf8'\e[0m
2446   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
2447
2448
2449 ActionController::UnknownAction (No action responded to add_to_cart. Actions: index):
2450     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:617:in `call_filters'
2451     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:610:in `perform_action_without_benchmark'
2452     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
2453     /usr/lib/ruby/1.8/benchmark.rb:293:in `measure'
2454     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
2455     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/rescue.rb:136:in `perform_action_without_caching'
2456     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:13:in `perform_action'
2457     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/connection_adapters/abstract/query_cache.rb:34:in `cache'
2458     /usr/lib/ruby/gems/1.8/gems/activerecord-2.2.2/lib/active_record/query_cache.rb:8:in `cache'
2459     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/caching/sql_cache.rb:12:in `perform_action'
2460     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `send'
2461     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:524:in `process_without_filters'
2462     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/filters.rb:606:in `process_without_session_management_support'
2463     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/session_management.rb:134:in `process'
2464     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/base.rb:392:in `process'
2465     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:183:in `handle_request'
2466     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:110:in `dispatch_unlocked'
2467     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:123:in `dispatch'
2468     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `synchronize'
2469     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:122:in `dispatch'
2470     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:132:in `dispatch_cgi'
2471     /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/dispatcher.rb:39:in `dispatch'
2472     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:103:in `handle_dispatch'
2473     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:74:in `service'
2474     /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
2475     /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
2476     /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
2477     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
2478     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
2479     /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
2480     /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
2481     /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
2482     /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
2483     /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
2484     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/webrick_server.rb:60:in `dispatch'
2485     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/servers/webrick.rb:66
2486     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
2487     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
2488     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
2489     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:521:in `new_constants_in'
2490     /usr/lib/ruby/gems/1.8/gems/activesupport-2.2.2/lib/active_support/dependencies.rb:153:in `require'
2491     /usr/lib/ruby/gems/1.8/gems/rails-2.2.2/lib/commands/server.rb:49
2492     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `gem_original_require'
2493     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:31:in `require'
2494     script/server:3
2495
2496 Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.2.2/lib/action_controller/templates/rescues/layout.erb (not_found)