log/development.log

   1
   2
   3 Processing ProductsController#index (for 127.0.0.1 at 2008-07-24 08:54:29) [GET]
   4   Session ID: 516264dbb9a2fb7792ea3375d071afc7
   5   Parameters: {"action"=>"index", "controller"=>"products"}
   6   \e[4;36;1mSQL (0.000156)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
   7   \e[4;35;1mProduct Load (0.000000)\e[0m   \e[0mMysql::Error: Table 'depot_development.products' doesn't exist: SELECT * FROM `products` \e[0m
   8
   9
  10 ActiveRecord::StatementInvalid (Mysql::Error: Table 'depot_development.products' doesn't exist: SELECT * FROM `products`     ):
  11     /usr/lib/ruby/gems/1.8/gems/activerecord-2.1.0/lib/active_record/connection_adapters/abstract_adapter.rb:147:in `log'
  12     /usr/lib/ruby/gems/1.8/gems/activerecord-2.1.0/lib/active_record/connection_adapters/mysql_adapter.rb:299:in `execute'
  13     /usr/lib/ruby/gems/1.8/gems/activerecord-2.1.0/lib/active_record/connection_adapters/mysql_adapter.rb:515:in `select'
  14     /usr/lib/ruby/gems/1.8/gems/activerecord-2.1.0/lib/active_record/connection_adapters/abstract/database_statements.rb:7:in `select_all_without_query_cache'
  15     /usr/lib/ruby/gems/1.8/gems/activerecord-2.1.0/lib/active_record/connection_adapters/abstract/query_cache.rb:59:in `select_all'
  16     /usr/lib/ruby/gems/1.8/gems/activerecord-2.1.0/lib/active_record/connection_adapters/abstract/query_cache.rb:80:in `cache_sql'
  17     /usr/lib/ruby/gems/1.8/gems/activerecord-2.1.0/lib/active_record/connection_adapters/abstract/query_cache.rb:59:in `select_all'
  18     /usr/lib/ruby/gems/1.8/gems/activerecord-2.1.0/lib/active_record/base.rb:582:in `find_by_sql'
  19     /usr/lib/ruby/gems/1.8/gems/activerecord-2.1.0/lib/active_record/base.rb:1341:in `find_every'
  20     /usr/lib/ruby/gems/1.8/gems/activerecord-2.1.0/lib/active_record/base.rb:536:in `find'
  21     /app/controllers/products_controller.rb:5:in `index'
  22     /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/base.rb:1162:in `send'
  23     /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/base.rb:1162:in `perform_action_without_filters'
  24     /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/filters.rb:580:in `call_filters'
  25     /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/filters.rb:573:in `perform_action_without_benchmark'
  26     /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
  27     /usr/lib/ruby/1.8/benchmark.rb:293:in `measure'
  28     /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
  29     /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/rescue.rb:201:in `perform_action_without_caching'
  30     /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/caching/sql_cache.rb:13:in `perform_action'
  31     /usr/lib/ruby/gems/1.8/gems/activerecord-2.1.0/lib/active_record/connection_adapters/abstract/query_cache.rb:33:in `cache'
  32     /usr/lib/ruby/gems/1.8/gems/activerecord-2.1.0/lib/active_record/query_cache.rb:8:in `cache'
  33     /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/caching/sql_cache.rb:12:in `perform_action'
  34     /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/base.rb:529:in `send'
  35     /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/base.rb:529:in `process_without_filters'
  36     /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/filters.rb:569:in `process_without_session_management_support'
  37     /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/session_management.rb:130:in `process'
  38     /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/base.rb:389:in `process'
  39     /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/dispatcher.rb:149:in `handle_request'
  40     /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/dispatcher.rb:107:in `dispatch'
  41     /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/dispatcher.rb:104:in `synchronize'
  42     /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/dispatcher.rb:104:in `dispatch'
  43     /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/dispatcher.rb:120:in `dispatch_cgi'
  44     /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/dispatcher.rb:35:in `dispatch'
  45     /usr/lib/ruby/gems/1.8/gems/rails-2.1.0/lib/webrick_server.rb:112:in `handle_dispatch'
  46     /usr/lib/ruby/gems/1.8/gems/rails-2.1.0/lib/webrick_server.rb:78:in `service'
  47     /usr/lib/ruby/1.8/webrick/httpserver.rb:104:in `service'
  48     /usr/lib/ruby/1.8/webrick/httpserver.rb:65:in `run'
  49     /usr/lib/ruby/1.8/webrick/server.rb:173:in `start_thread'
  50     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start'
  51     /usr/lib/ruby/1.8/webrick/server.rb:162:in `start_thread'
  52     /usr/lib/ruby/1.8/webrick/server.rb:95:in `start'
  53     /usr/lib/ruby/1.8/webrick/server.rb:92:in `each'
  54     /usr/lib/ruby/1.8/webrick/server.rb:92:in `start'
  55     /usr/lib/ruby/1.8/webrick/server.rb:23:in `start'
  56     /usr/lib/ruby/1.8/webrick/server.rb:82:in `start'
  57     /usr/lib/ruby/gems/1.8/gems/rails-2.1.0/lib/webrick_server.rb:62:in `dispatch'
  58     /usr/lib/ruby/gems/1.8/gems/rails-2.1.0/lib/commands/servers/webrick.rb:66
  59     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:27:in `gem_original_require'
  60     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:27:in `require'
  61     /usr/lib/ruby/gems/1.8/gems/activesupport-2.1.0/lib/active_support/dependencies.rb:509:in `require'
  62     /usr/lib/ruby/gems/1.8/gems/activesupport-2.1.0/lib/active_support/dependencies.rb:354:in `new_constants_in'
  63     /usr/lib/ruby/gems/1.8/gems/activesupport-2.1.0/lib/active_support/dependencies.rb:509:in `require'
  64     /usr/lib/ruby/gems/1.8/gems/rails-2.1.0/lib/commands/server.rb:39
  65     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:27:in `gem_original_require'
  66     /usr/local/lib/site_ruby/1.8/rubygems/custom_require.rb:27:in `require'
  67     script/server:3
  68
  69 Rendering /usr/lib/ruby/gems/1.8/gems/actionpack-2.1.0/lib/action_controller/templates/rescues/layout.erb (internal_server_error)
  70   \e[4;36;1mSQL (0.000168)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
  71   \e[4;35;1mSQL (0.000306)\e[0m   \e[0mSHOW TABLES\e[0m
  72   \e[4;36;1mSQL (0.043538)\e[0m   \e[0;1mCREATE TABLE `schema_migrations` (`version` varchar(255) NOT NULL) ENGINE=InnoDB\e[0m
  73   \e[4;35;1mSQL (0.004163)\e[0m   \e[0mCREATE UNIQUE INDEX `unique_schema_migrations` ON `schema_migrations` (`version`)\e[0m
  74   \e[4;36;1mSQL (0.000244)\e[0m   \e[0;1mSHOW TABLES\e[0m
  75   \e[4;35;1mSQL (0.000338)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
  76 Migrating to CreateProducts (20080724075409)
  77   \e[4;36;1mSQL (0.000068)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
  78   \e[4;35;1mSQL (0.002555)\e[0m   \e[0mCREATE TABLE `products` (`id` int(11) DEFAULT NULL auto_increment PRIMARY KEY, `title` varchar(255) DEFAULT NULL NULL, `description` text DEFAULT NULL NULL, `image_url` varchar(255) DEFAULT NULL NULL, `created_at` datetime DEFAULT NULL NULL, `updated_at` datetime DEFAULT NULL NULL) ENGINE=InnoDB\e[0m
  79   \e[4;36;1mSQL (0.000787)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20080724075409')\e[0m
  80   \e[4;35;1mSQL (0.000238)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
  81   \e[4;36;1mSQL (0.000215)\e[0m   \e[0;1mSHOW TABLES\e[0m
  82   \e[4;35;1mSQL (0.000852)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
  83   \e[4;36;1mSQL (0.000711)\e[0m   \e[0;1mdescribe `products`\e[0m
  84   \e[4;35;1mSQL (0.000320)\e[0m   \e[0mSHOW KEYS FROM `products`\e[0m
  85
  86
  87 Processing ProductsController#index (for 127.0.0.1 at 2008-07-24 08:54:57) [GET]
  88   Session ID: BAh7BiIKZmxhc2hJQzonQWN0aW9uQ29udHJvbGxlcjo6Rmxhc2g6OkZsYXNo
  89 SGFzaHsABjoKQHVzZWR7AA==--9a7a4cb8d57b822aeb31c468bcb62a33430f150b
  90   Parameters: {"action"=>"index", "controller"=>"products"}
  91   \e[4;36;1mProduct Load (0.000316)\e[0m   \e[0;1mSELECT * FROM `products` \e[0m
  92 Rendering template within layouts/products
  93 Rendering products/index
  94 Completed in 0.01792 (55 reqs/sec) | Rendering: 0.00568 (31%) | DB: 0.00047 (2%) | 200 OK [http://localhost/products]
  95
  96
  97 Processing ProductsController#new (for 127.0.0.1 at 2008-07-24 08:55:01) [GET]
  98   Session ID: BAh7BiIKZmxhc2hJQzonQWN0aW9uQ29udHJvbGxlcjo6Rmxhc2g6OkZsYXNo
  99 SGFzaHsABjoKQHVzZWR7AA==--9a7a4cb8d57b822aeb31c468bcb62a33430f150b
 100   Parameters: {"action"=>"new", "controller"=>"products"}
 101   \e[4;35;1mProduct Columns (0.000866)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 102 Rendering template within layouts/products
 103 Rendering products/new
 104 Completed in 0.02357 (42 reqs/sec) | Rendering: 0.01115 (47%) | DB: 0.00087 (3%) | 200 OK [http://localhost/products/new]
 105
 106
 107 Processing ProductsController#create (for 127.0.0.1 at 2008-07-24 08:55:07) [POST]
 108   Session ID: BAh7BzoMY3NyZl9pZCIlOWM2MWUxN2RiODdmNDI2MTQxNjIwODM5ZmY2Yzdj
 109 OWIiCmZsYXNoSUM6J0FjdGlvbkNvbnRyb2xsZXI6OkZsYXNoOjpGbGFzaEhh
 110 c2h7AAY6CkB1c2VkewA=--be2cb095a3a64313ddeabee8ee4f7ac896aae350
 111   Parameters: {"commit"=>"Create", "product"=>{"image_url"=>"lkj l;j", "title"=>"lkj ", "description"=>"l;kj lk;j l;kj lj"}, "authenticity_token"=>"466a8d52a74d0603850d0b0d709e5f2c5e3ce774", "action"=>"create", "controller"=>"products"}
 112   \e[4;36;1mProduct Columns (0.000988)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 113   \e[4;35;1mSQL (0.000084)\e[0m   \e[0mBEGIN\e[0m
 114   \e[4;36;1mProduct Create (0.000799)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `description`, `created_at`) VALUES('lkj l;j', '2008-07-24 07:55:07', 'lkj ', 'l;kj lk;j l;kj lj', '2008-07-24 07:55:07')\e[0m
 115   \e[4;35;1mSQL (0.002049)\e[0m   \e[0mCOMMIT\e[0m
 116 Redirected to http://localhost:3000/products/1
 117 Completed in 0.02044 (48 reqs/sec) | DB: 0.00392 (19%) | 302 Found [http://localhost/products]
 118
 119
 120 Processing ProductsController#show (for 127.0.0.1 at 2008-07-24 08:55:07) [GET]
 121   Session ID: BAh7BzoMY3NyZl9pZCIlOWM2MWUxN2RiODdmNDI2MTQxNjIwODM5ZmY2Yzdj
 122 OWIiCmZsYXNoSUM6J0FjdGlvbkNvbnRyb2xsZXI6OkZsYXNoOjpGbGFzaEhh
 123 c2h7BjoLbm90aWNlIiZQcm9kdWN0IHdhcyBzdWNjZXNzZnVsbHkgY3JlYXRl
 124 ZC4GOgpAdXNlZHsGOwdG--f2c2ee2eb4620b461f69f905e5860ebe7060c7f2
 125   Parameters: {"action"=>"show", "id"=>"1", "controller"=>"products"}
 126   \e[4;36;1mProduct Columns (0.001114)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 127   \e[4;35;1mProduct Load (0.000544)\e[0m   \e[0mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
 128 Rendering template within layouts/products
 129 Rendering products/show
 130 Completed in 0.02537 (39 reqs/sec) | Rendering: 0.00662 (26%) | DB: 0.00166 (6%) | 200 OK [http://localhost/products/1]
 131
 132
 133 Processing ProductsController#index (for 127.0.0.1 at 2008-07-24 08:55:09) [GET]
 134   Session ID: BAh7BzoMY3NyZl9pZCIlOWM2MWUxN2RiODdmNDI2MTQxNjIwODM5ZmY2Yzdj
 135 OWIiCmZsYXNoSUM6J0FjdGlvbkNvbnRyb2xsZXI6OkZsYXNoOjpGbGFzaEhh
 136 c2h7BjoLbm90aWNlIiZQcm9kdWN0IHdhcyBzdWNjZXNzZnVsbHkgY3JlYXRl
 137 ZC4GOgpAdXNlZHsGOwdU--e278ebd0c668af234fdcc2a973394c40ab1571fd
 138   Parameters: {"action"=>"index", "controller"=>"products"}
 139   \e[4;36;1mProduct Load (0.000324)\e[0m   \e[0;1mSELECT * FROM `products` \e[0m
 140 Rendering template within layouts/products
 141 Rendering products/index
 142   \e[4;35;1mProduct Columns (0.003374)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 143 Completed in 0.02020 (49 reqs/sec) | Rendering: 0.00515 (25%) | DB: 0.00370 (18%) | 200 OK [http://localhost/products]
 144   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 145   \e[4;35;1mSQL (17.9ms)\e[0m   \e[0mSHOW TABLES\e[0m
 146   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
 147 Migrating to CreateProducts (20080724075409)
 148 Migrating to AddProductPrice (20090128142730)
 149   \e[4;35;1mSQL (222.8ms)\e[0m   \e[0mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 2\e[0m
 150   \e[4;36;1mSQL (0.8ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090128142730')\e[0m
 151   \e[4;35;1mSQL (0.6ms)\e[0m   \e[0mSHOW TABLES\e[0m
 152   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
 153   \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mSHOW TABLES\e[0m
 154   \e[4;36;1mSQL (2.2ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 155   \e[4;35;1mSQL (1.9ms)\e[0m   \e[0mdescribe `products`\e[0m
 156   \e[4;36;1mSQL (1.0ms)\e[0m   \e[0;1mSHOW KEYS FROM `products`\e[0m
 157   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 158   \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mSHOW TABLES\e[0m
 159   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
 160   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
 161   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
 162 Migrating to AddProductPrice (20090128142730)
 163   \e[4;35;1mSQL (27.2ms)\e[0m   \e[0mALTER TABLE `products` DROP `price`\e[0m
 164   \e[4;36;1mSQL (40.9ms)\e[0m   \e[0;1mDELETE FROM schema_migrations WHERE version = '20090128142730'\e[0m
 165 Migrating to CreateProducts (20080724075409)
 166   \e[4;35;1mSQL (26.4ms)\e[0m   \e[0mDROP TABLE `products`\e[0m
 167   \e[4;36;1mSQL (38.8ms)\e[0m   \e[0;1mDELETE FROM schema_migrations WHERE version = '20080724075409'\e[0m
 168   \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mSHOW TABLES\e[0m
 169   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
 170   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
 171   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 172   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
 173   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
 174 Migrating to CreateProducts (20080724075409)
 175   \e[4;35;1mSQL (30.6ms)\e[0m   \e[0mCREATE TABLE `products` (`id` int(11) DEFAULT NULL auto_increment PRIMARY KEY, `title` varchar(255), `description` text, `image_url` varchar(255), `created_at` datetime, `updated_at` datetime) ENGINE=InnoDB\e[0m
 176   \e[4;36;1mSQL (25.8ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20080724075409')\e[0m
 177 Migrating to AddProductPrice (20090128142730)
 178   \e[4;35;1mSQL (16.2ms)\e[0m   \e[0mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 0\e[0m
 179   \e[4;36;1mSQL (0.8ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090128142730')\e[0m
 180   \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mSHOW TABLES\e[0m
 181   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
 182   \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mSHOW TABLES\e[0m
 183   \e[4;36;1mSQL (3.6ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 184   \e[4;35;1mSQL (1.9ms)\e[0m   \e[0mdescribe `products`\e[0m
 185   \e[4;36;1mSQL (0.6ms)\e[0m   \e[0;1mSHOW KEYS FROM `products`\e[0m
 186
 187
 188 Processing ProductsController#index (for 127.0.0.1 at 2009-01-30 10:55:37) [GET]
 189   \e[4;36;1mSQL (41.3ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 190   \e[4;35;1mProduct Load (49.7ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
 191 Rendering template within layouts/products
 192 Rendering products/index
 193   \e[4;36;1mProduct Columns (23.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 194 Completed in 234ms (View: 147, DB: 114) | 200 OK [http://localhost/products]
 195
 196
 197 Processing ProductsController#edit (for 127.0.0.1 at 2009-01-30 10:55:41) [GET]
 198   Parameters: {"id"=>"1"}
 199   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 200   \e[4;36;1mProduct Columns (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 201   \e[4;35;1mProduct Load (23.3ms)\e[0m   \e[0mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
 202 Rendering template within layouts/products
 203 Rendering products/edit
 204 Completed in 52ms (View: 16, DB: 24) | 200 OK [http://localhost/products/1/edit]
 205
 206
 207 Processing ProductsController#update (for 127.0.0.1 at 2009-01-30 10:55:42) [PUT]
 208   Parameters: {"commit"=>"Update", "authenticity_token"=>"f081ce86efd30d00f8269cdde39c0b562c696c34", "product"=>{"image_url"=>"", "price"=>"17.00", "title"=>"First product", "description"=>"This is the description of the <i>first</i> product"}, "id"=>"1"}
 209   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 210   \e[4;35;1mProduct Columns (1.2ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 211   \e[4;36;1mProduct Load (0.3ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
 212   \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mBEGIN\e[0m
 213   \e[4;36;1mProduct Exists (62.9ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'First product' AND `products`.id <> 1) LIMIT 1\e[0m
 214   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mROLLBACK\e[0m
 215 Rendering template within layouts/products
 216 Rendering products/edit
 217 Completed in 182ms (View: 22, DB: 65) | 200 OK [http://localhost/products/1]
 218
 219
 220 Processing ProductsController#update (for 127.0.0.1 at 2009-01-30 10:56:00) [PUT]
 221   Parameters: {"commit"=>"Update", "authenticity_token"=>"f081ce86efd30d00f8269cdde39c0b562c696c34", "product"=>{"image_url"=>"prod1", "price"=>"17.00", "title"=>"First product", "description"=>"This is the description of the <i>first</i> product"}, "id"=>"1"}
 222   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 223   \e[4;35;1mProduct Columns (1.3ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 224   \e[4;36;1mProduct Load (0.1ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
 225   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
 226   \e[4;36;1mProduct Exists (0.1ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'First product' AND `products`.id <> 1) LIMIT 1\e[0m
 227   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mROLLBACK\e[0m
 228 Rendering template within layouts/products
 229 Rendering products/edit
 230 Completed in 42ms (View: 16, DB: 2) | 200 OK [http://localhost/products/1]
 231
 232
 233 Processing ProductsController#update (for 127.0.0.1 at 2009-01-30 10:56:04) [PUT]
 234   Parameters: {"commit"=>"Update", "authenticity_token"=>"f081ce86efd30d00f8269cdde39c0b562c696c34", "product"=>{"image_url"=>"prod1.png", "price"=>"17.00", "title"=>"First product", "description"=>"This is the description of the <i>first</i> product"}, "id"=>"1"}
 235   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 236   \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 237   \e[4;36;1mProduct Load (0.2ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
 238   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
 239   \e[4;36;1mProduct Exists (0.1ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'First product' AND `products`.id <> 1) LIMIT 1\e[0m
 240   \e[4;35;1mProduct Update (31.3ms)\e[0m   \e[0mUPDATE `products` SET `updated_at` = '2009-01-30 10:56:04', `image_url` = 'prod1.png' WHERE `id` = 1\e[0m
 241   \e[4;36;1mSQL (8.9ms)\e[0m   \e[0;1mCOMMIT\e[0m
 242 Redirected to #<Product:0xb6716f18>
 243 Completed in 69ms (DB: 42) | 302 Found [http://localhost/products/1]
 244
 245
 246 Processing ProductsController#show (for 127.0.0.1 at 2009-01-30 10:56:04) [GET]
 247   Parameters: {"id"=>"1"}
 248   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 249   \e[4;36;1mProduct Columns (2.4ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 250   \e[4;35;1mProduct Load (0.6ms)\e[0m   \e[0mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
 251 Rendering template within layouts/products
 252 Rendering products/show
 253 Completed in 43ms (View: 17, DB: 3) | 200 OK [http://localhost/products/1]
 254
 255
 256 Processing ProductsController#index (for 127.0.0.1 at 2009-01-30 10:56:07) [GET]
 257   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 258   \e[4;35;1mProduct Load (0.4ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
 259 Rendering template within layouts/products
 260 Rendering products/index
 261   \e[4;36;1mProduct Columns (1.7ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 262 Completed in 28ms (View: 14, DB: 2) | 200 OK [http://localhost/products]
 263
 264
 265 Processing ProductsController#new (for 127.0.0.1 at 2009-01-30 10:56:13) [GET]
 266   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 267   \e[4;36;1mProduct Columns (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 268 Rendering template within layouts/products
 269 Rendering products/new
 270 Completed in 34ms (View: 21, DB: 1) | 200 OK [http://localhost/products/new]
 271
 272
 273 Processing ProductsController#create (for 127.0.0.1 at 2009-01-30 10:56:15) [POST]
 274   Parameters: {"commit"=>"Create", "authenticity_token"=>"f081ce86efd30d00f8269cdde39c0b562c696c34", "product"=>{"image_url"=>"", "price"=>"0.0", "title"=>"", "description"=>""}}
 275   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 276   \e[4;36;1mProduct Columns (1.2ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 277   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
 278   \e[4;36;1mProduct Exists (0.4ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY '') LIMIT 1\e[0m
 279   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mROLLBACK\e[0m
 280 Rendering template within layouts/products
 281 Rendering products/new
 282 Completed in 49ms (View: 18, DB: 2) | 200 OK [http://localhost/products]
 283
 284
 285 Processing ProductsController#index (for 127.0.0.1 at 2009-01-30 10:56:26) [GET]
 286   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 287   \e[4;35;1mProduct Load (0.2ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
 288 Rendering template within layouts/products
 289 Rendering products/index
 290   \e[4;36;1mProduct Columns (1.4ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 291 Completed in 32ms (View: 16, DB: 2) | 200 OK [http://localhost/products]
 292   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 293   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
 294   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
 295 Migrating to CreateProducts (20080724075409)
 296 Migrating to AddProductPrice (20090128142730)
 297 Migrating to AddTestData (20090130111521)
 298   \e[4;35;1mProduct Delete all (8.2ms)\e[0m   \e[0mDELETE FROM `products` \e[0m
 299   \e[4;36;1mProduct Columns (1.7ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 300   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
 301   \e[4;36;1mProduct Exists (0.4ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Pragmatic Version Control') LIMIT 1\e[0m
 302   \e[4;35;1mProduct Create (28.2ms)\e[0m   \e[0mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `description`, `created_at`) VALUES('/images/auto.jpg', '2009-01-30 11:21:28', 'Pragmatic Version Control', 29.95, '<p>\n <em>Pragmatic Project Automation</em> shows you how to improve the \n consistency and repeatability of your project\'s procedures using \n automation to reduce risk and errors.\n </p>\n <p>\n Simply put, we\'re going to put this thing called a computer to work \n for you doing the mundane (but important) project stuff. That means \n you\'ll have more time and energy to do the really \n exciting---and difficult---stuff, like writing quality code.\n </p>', '2009-01-30 11:21:28')\e[0m
 303   \e[4;36;1mSQL (0.6ms)\e[0m   \e[0;1mCOMMIT\e[0m
 304   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
 305   \e[4;36;1mProduct Exists (0.4ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Pragmatic Version Control') LIMIT 1\e[0m
 306   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mROLLBACK\e[0m
 307   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mBEGIN\e[0m
 308   \e[4;35;1mProduct Exists (0.4ms)\e[0m   \e[0mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Pragmatic Unit Testing (C#)') LIMIT 1\e[0m
 309   \e[4;36;1mProduct Create (0.4ms)\e[0m   \e[0;1mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `description`, `created_at`) VALUES('/images/utc.jpg', '2009-01-30 11:21:28', 'Pragmatic Unit Testing (C#)', 27.75, '<p>\n Pragmatic programmers use feedback to drive their development and \n personal processes. The most valuable feedback you can get while \n coding comes from unit testing.\n </p>\n <p>\n Without good tests in place, coding can become a frustrating game of \n \"whack-a-mole.\" That\'s the carnival game where the player strikes at a \n mechanical mole; it retreats and another mole pops up on the opposite side \n of the field. The moles pop up and down so fast that you end up flailing \n your mallet helplessly as the moles continue to pop up where you least \n expect them.\n </p>', '2009-01-30 11:21:28')\e[0m
 310   \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mCOMMIT\e[0m
 311   \e[4;36;1mSQL (23.8ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090130111521')\e[0m
 312   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
 313   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
 314   \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSHOW TABLES\e[0m
 315   \e[4;36;1mSQL (1.0ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 316   \e[4;35;1mSQL (1.1ms)\e[0m   \e[0mdescribe `products`\e[0m
 317   \e[4;36;1mSQL (0.5ms)\e[0m   \e[0;1mSHOW KEYS FROM `products`\e[0m
 318
 319
 320 Processing ProductsController#index (for 127.0.0.1 at 2009-01-30 11:21:36) [GET]
 321   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 322   \e[4;36;1mProduct Load (0.7ms)\e[0m   \e[0;1mSELECT * FROM `products` \e[0m
 323 Rendering template within layouts/products
 324 Rendering products/index
 325   \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 326 Completed in 34ms (View: 19, DB: 2) | 200 OK [http://localhost/products]
 327   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 328   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
 329   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
 330   \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSHOW TABLES\e[0m
 331   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
 332 Migrating to AddTestData (20090130111521)
 333   \e[4;35;1mProduct Delete all (7.2ms)\e[0m   \e[0mDELETE FROM `products` \e[0m
 334   \e[4;36;1mSQL (9.5ms)\e[0m   \e[0;1mDELETE FROM schema_migrations WHERE version = '20090130111521'\e[0m
 335 Migrating to AddProductPrice (20090128142730)
 336   \e[4;35;1mSQL (174.7ms)\e[0m   \e[0mALTER TABLE `products` DROP `price`\e[0m
 337   \e[4;36;1mSQL (0.7ms)\e[0m   \e[0;1mDELETE FROM schema_migrations WHERE version = '20090128142730'\e[0m
 338 Migrating to CreateProducts (20080724075409)
 339   \e[4;35;1mSQL (1.4ms)\e[0m   \e[0mDROP TABLE `products`\e[0m
 340   \e[4;36;1mSQL (0.7ms)\e[0m   \e[0;1mDELETE FROM schema_migrations WHERE version = '20080724075409'\e[0m
 341   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
 342   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
 343   \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mSHOW TABLES\e[0m
 344   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 345   \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mSHOW TABLES\e[0m
 346   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
 347 Migrating to CreateProducts (20080724075409)
 348   \e[4;35;1mSQL (6.1ms)\e[0m   \e[0mCREATE TABLE `products` (`id` int(11) DEFAULT NULL auto_increment PRIMARY KEY, `title` varchar(255), `description` text, `image_url` varchar(255), `created_at` datetime, `updated_at` datetime) ENGINE=InnoDB\e[0m
 349   \e[4;36;1mSQL (0.8ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20080724075409')\e[0m
 350 Migrating to AddProductPrice (20090128142730)
 351   \e[4;35;1mSQL (8.7ms)\e[0m   \e[0mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 0\e[0m
 352   \e[4;36;1mSQL (0.8ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090128142730')\e[0m
 353 Migrating to AddTestData (20090130111521)
 354   \e[4;35;1mProduct Delete all (9.7ms)\e[0m   \e[0mDELETE FROM `products` \e[0m
 355   \e[4;36;1mProduct Columns (2.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 356   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mBEGIN\e[0m
 357   \e[4;36;1mProduct Exists (0.5ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Pragmatic Project Automation') LIMIT 1\e[0m
 358   \e[4;35;1mProduct Create (0.4ms)\e[0m   \e[0mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `description`, `created_at`) VALUES('/images/auto.jpg', '2009-01-30 11:22:38', 'Pragmatic Project Automation', 29.95, '<p>\n <em>Pragmatic Project Automation</em> shows you how to improve the \n consistency and repeatability of your project\'s procedures using \n automation to reduce risk and errors.\n </p>\n <p>\n Simply put, we\'re going to put this thing called a computer to work \n for you doing the mundane (but important) project stuff. That means \n you\'ll have more time and energy to do the really \n exciting---and difficult---stuff, like writing quality code.\n </p>', '2009-01-30 11:22:38')\e[0m
 359   \e[4;36;1mSQL (0.6ms)\e[0m   \e[0;1mCOMMIT\e[0m
 360   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mBEGIN\e[0m
 361   \e[4;36;1mProduct Exists (0.4ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Pragmatic Version Control') LIMIT 1\e[0m
 362   \e[4;35;1mProduct Create (0.4ms)\e[0m   \e[0mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `description`, `created_at`) VALUES('/images/svn.jpg', '2009-01-30 11:22:38', 'Pragmatic Version Control', 28.5, '<p>\n This book is a recipe-based approach to using Subversion that will \n get you up and running quickly---and correctly. All projects need\n version control: it\'s a foundational piece of any project\'s \n infrastructure. Yet half of all project teams in the U.S. don\'t use\n any version control at all. Many others don\'t use it well, and end \n up experiencing time-consuming problems.\n </p>', '2009-01-30 11:22:38')\e[0m
 363   \e[4;36;1mSQL (0.6ms)\e[0m   \e[0;1mCOMMIT\e[0m
 364   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mBEGIN\e[0m
 365   \e[4;36;1mProduct Exists (0.4ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Pragmatic Unit Testing (C#)') LIMIT 1\e[0m
 366   \e[4;35;1mProduct Create (0.5ms)\e[0m   \e[0mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `description`, `created_at`) VALUES('/images/utc.jpg', '2009-01-30 11:22:38', 'Pragmatic Unit Testing (C#)', 27.75, '<p>\n Pragmatic programmers use feedback to drive their development and \n personal processes. The most valuable feedback you can get while \n coding comes from unit testing.\n </p>\n <p>\n Without good tests in place, coding can become a frustrating game of \n \"whack-a-mole.\" That\'s the carnival game where the player strikes at a \n mechanical mole; it retreats and another mole pops up on the opposite side \n of the field. The moles pop up and down so fast that you end up flailing \n your mallet helplessly as the moles continue to pop up where you least \n expect them.\n </p>', '2009-01-30 11:22:38')\e[0m
 367   \e[4;36;1mSQL (6.3ms)\e[0m   \e[0;1mCOMMIT\e[0m
 368   \e[4;35;1mSQL (13.7ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090130111521')\e[0m
 369   \e[4;36;1mSQL (0.5ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 370   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 371   \e[4;36;1mSQL (0.5ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 372   \e[4;35;1mSQL (1.8ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 373   \e[4;36;1mSQL (1.8ms)\e[0m   \e[0;1mdescribe `products`\e[0m
 374   \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mSHOW KEYS FROM `products`\e[0m
 375
 376
 377 Processing ProductsController#index (for 127.0.0.1 at 2009-01-30 11:22:43) [GET]
 378   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 379   \e[4;35;1mProduct Load (0.4ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
 380 Rendering template within layouts/products
 381 Rendering products/index
 382   \e[4;36;1mProduct Columns (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 383 Completed in 31ms (View: 17, DB: 2) | 200 OK [http://localhost/products]
 384   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 385   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
 386   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
 387   \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSHOW TABLES\e[0m
 388   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
 389 Migrating to AddTestData (20090130111521)
 390   \e[4;35;1mProduct Delete all (5.1ms)\e[0m   \e[0mDELETE FROM `products` \e[0m
 391   \e[4;36;1mSQL (12.1ms)\e[0m   \e[0;1mDELETE FROM schema_migrations WHERE version = '20090130111521'\e[0m
 392 Migrating to AddProductPrice (20090128142730)
 393   \e[4;35;1mSQL (30.2ms)\e[0m   \e[0mALTER TABLE `products` DROP `price`\e[0m
 394   \e[4;36;1mSQL (0.7ms)\e[0m   \e[0;1mDELETE FROM schema_migrations WHERE version = '20090128142730'\e[0m
 395 Migrating to CreateProducts (20080724075409)
 396   \e[4;35;1mSQL (2.0ms)\e[0m   \e[0mDROP TABLE `products`\e[0m
 397   \e[4;36;1mSQL (6.3ms)\e[0m   \e[0;1mDELETE FROM schema_migrations WHERE version = '20080724075409'\e[0m
 398   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
 399   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
 400   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
 401   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 402   \e[4;35;1mSQL (1.2ms)\e[0m   \e[0mSHOW TABLES\e[0m
 403   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
 404 Migrating to CreateProducts (20080724075409)
 405   \e[4;35;1mSQL (3.4ms)\e[0m   \e[0mCREATE TABLE `products` (`id` int(11) DEFAULT NULL auto_increment PRIMARY KEY, `title` varchar(255), `description` text, `image_url` varchar(255), `created_at` datetime, `updated_at` datetime) ENGINE=InnoDB\e[0m
 406   \e[4;36;1mSQL (0.8ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20080724075409')\e[0m
 407 Migrating to AddProductPrice (20090128142730)
 408   \e[4;35;1mSQL (6.6ms)\e[0m   \e[0mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 0\e[0m
 409   \e[4;36;1mSQL (0.7ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090128142730')\e[0m
 410 Migrating to AddTestData (20090130111521)
 411   \e[4;35;1mProduct Delete all (0.4ms)\e[0m   \e[0mDELETE FROM `products` \e[0m
 412   \e[4;36;1mProduct Columns (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 413   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
 414   \e[4;36;1mProduct Exists (0.6ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Pragmatic Project Automation') LIMIT 1\e[0m
 415   \e[4;35;1mProduct Create (0.6ms)\e[0m   \e[0mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `description`, `created_at`) VALUES('/images/auto.jpg', '2009-01-30 11:26:48', 'Pragmatic Project Automation', 29.95, '<p>\n <em>Pragmatic Project Automation</em> shows you how to improve the \n consistency and repeatability of your project\'s procedures using \n automation to reduce risk and errors.\n </p>\n <p>\n Simply put, we\'re going to put this thing called a computer to work \n for you doing the mundane (but important) project stuff. That means \n you\'ll have more time and energy to do the really \n exciting---and difficult---stuff, like writing quality code.\n </p>', '2009-01-30 11:26:48')\e[0m
 416   \e[4;36;1mSQL (0.7ms)\e[0m   \e[0;1mCOMMIT\e[0m
 417   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
 418   \e[4;36;1mProduct Exists (0.4ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Pragmatic Version Control') LIMIT 1\e[0m
 419   \e[4;35;1mProduct Create (1.0ms)\e[0m   \e[0mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `description`, `created_at`) VALUES('/images/svn.jpg', '2009-01-30 11:26:48', 'Pragmatic Version Control', 28.5, '<p>\n This book is a recipe-based approach to using Subversion that will \n get you up and running quickly&emdash;and correctly. All projects need\n version control: it\'s a foundational piece of any project\'s \n infrastructure. Yet half of all project teams in the U.S. don\'t use\n any version control at all. Many others don\'t use it well, and end \n up experiencing time-consuming problems.\n </p>', '2009-01-30 11:26:48')\e[0m
 420   \e[4;36;1mSQL (1.8ms)\e[0m   \e[0;1mCOMMIT\e[0m
 421   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
 422   \e[4;36;1mProduct Exists (0.4ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Pragmatic Unit Testing (C#)') LIMIT 1\e[0m
 423   \e[4;35;1mProduct Create (0.4ms)\e[0m   \e[0mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `description`, `created_at`) VALUES('/images/utc.jpg', '2009-01-30 11:26:48', 'Pragmatic Unit Testing (C#)', 27.75, '<p>\n Pragmatic programmers use feedback to drive their development and \n personal processes. The most valuable feedback you can get while \n coding comes from unit testing.\n </p>\n <p>\n Without good tests in place, coding can become a frustrating game of \n \"whack-a-mole.\" That\'s the carnival game where the player strikes at a \n mechanical mole; it retreats and another mole pops up on the opposite side \n of the field. The moles pop up and down so fast that you end up flailing \n your mallet helplessly as the moles continue to pop up where you least \n expect them.\n </p>', '2009-01-30 11:26:48')\e[0m
 424   \e[4;36;1mSQL (2.0ms)\e[0m   \e[0;1mCOMMIT\e[0m
 425   \e[4;35;1mSQL (8.2ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090130111521')\e[0m
 426   \e[4;36;1mSQL (0.4ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 427   \e[4;35;1mSQL (0.3ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 428   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 429   \e[4;35;1mSQL (1.0ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 430   \e[4;36;1mSQL (1.0ms)\e[0m   \e[0;1mdescribe `products`\e[0m
 431   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW KEYS FROM `products`\e[0m
 432
 433
 434 Processing ProductsController#index (for 127.0.0.1 at 2009-01-30 11:26:52) [GET]
 435   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 436   \e[4;36;1mProduct Load (0.4ms)\e[0m   \e[0;1mSELECT * FROM `products` \e[0m
 437 Rendering template within layouts/products
 438 Rendering products/index
 439   \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 440 Completed in 37ms (View: 24, DB: 2) | 200 OK [http://localhost/products]
 441   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 442   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
 443   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
 444   \e[4;35;1mSQL (0.5ms)\e[0m   \e[0mSHOW TABLES\e[0m
 445   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
 446 Migrating to AddTestData (20090130111521)
 447   \e[4;35;1mProduct Delete all (12.5ms)\e[0m   \e[0mDELETE FROM `products` \e[0m
 448   \e[4;36;1mSQL (12.4ms)\e[0m   \e[0;1mDELETE FROM schema_migrations WHERE version = '20090130111521'\e[0m
 449 Migrating to AddProductPrice (20090128142730)
 450   \e[4;35;1mSQL (22.2ms)\e[0m   \e[0mALTER TABLE `products` DROP `price`\e[0m
 451   \e[4;36;1mSQL (0.7ms)\e[0m   \e[0;1mDELETE FROM schema_migrations WHERE version = '20090128142730'\e[0m
 452 Migrating to CreateProducts (20080724075409)
 453   \e[4;35;1mSQL (6.1ms)\e[0m   \e[0mDROP TABLE `products`\e[0m
 454   \e[4;36;1mSQL (0.9ms)\e[0m   \e[0;1mDELETE FROM schema_migrations WHERE version = '20080724075409'\e[0m
 455   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
 456   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
 457   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
 458   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 459   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW TABLES\e[0m
 460   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSELECT version FROM schema_migrations\e[0m
 461 Migrating to CreateProducts (20080724075409)
 462   \e[4;35;1mSQL (4.6ms)\e[0m   \e[0mCREATE TABLE `products` (`id` int(11) DEFAULT NULL auto_increment PRIMARY KEY, `title` varchar(255), `description` text, `image_url` varchar(255), `created_at` datetime, `updated_at` datetime) ENGINE=InnoDB\e[0m
 463   \e[4;36;1mSQL (0.7ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20080724075409')\e[0m
 464 Migrating to AddProductPrice (20090128142730)
 465   \e[4;35;1mSQL (7.3ms)\e[0m   \e[0mALTER TABLE `products` ADD `price` decimal(8,2) DEFAULT 0\e[0m
 466   \e[4;36;1mSQL (0.8ms)\e[0m   \e[0;1mINSERT INTO schema_migrations (version) VALUES ('20090128142730')\e[0m
 467 Migrating to AddTestData (20090130111521)
 468   \e[4;35;1mProduct Delete all (1.1ms)\e[0m   \e[0mDELETE FROM `products` \e[0m
 469   \e[4;36;1mProduct Columns (2.5ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 470   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mBEGIN\e[0m
 471   \e[4;36;1mProduct Exists (0.5ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Pragmatic Project Automation') LIMIT 1\e[0m
 472   \e[4;35;1mProduct Create (0.4ms)\e[0m   \e[0mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `description`, `created_at`) VALUES('/images/auto.jpg', '2009-01-30 11:28:11', 'Pragmatic Project Automation', 29.95, '<p>\n <em>Pragmatic Project Automation</em> shows you how to improve the \n consistency and repeatability of your project\'s procedures using \n automation to reduce risk and errors.\n </p>\n <p>\n Simply put, we\'re going to put this thing called a computer to work \n for you doing the mundane (but important) project stuff. That means \n you\'ll have more time and energy to do the really \n exciting&mdash;and difficult&mdash;stuff, like writing quality code.\n </p>', '2009-01-30 11:28:11')\e[0m
 473   \e[4;36;1mSQL (0.5ms)\e[0m   \e[0;1mCOMMIT\e[0m
 474   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mBEGIN\e[0m
 475   \e[4;36;1mProduct Exists (0.4ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Pragmatic Version Control') LIMIT 1\e[0m
 476   \e[4;35;1mProduct Create (0.3ms)\e[0m   \e[0mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `description`, `created_at`) VALUES('/images/svn.jpg', '2009-01-30 11:28:11', 'Pragmatic Version Control', 28.5, '<p>\n This book is a recipe-based approach to using Subversion that will \n get you up and running quickly&mdash;and correctly. All projects need\n version control: it\'s a foundational piece of any project\'s \n infrastructure. Yet half of all project teams in the U.S. don\'t use\n any version control at all. Many others don\'t use it well, and end \n up experiencing time-consuming problems.\n </p>', '2009-01-30 11:28:11')\e[0m
 477   \e[4;36;1mSQL (0.5ms)\e[0m   \e[0;1mCOMMIT\e[0m
 478   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mBEGIN\e[0m
 479   \e[4;36;1mProduct Exists (0.6ms)\e[0m   \e[0;1mSELECT `products`.id FROM `products` WHERE (`products`.`title` = BINARY 'Pragmatic Unit Testing (C#)') LIMIT 1\e[0m
 480   \e[4;35;1mProduct Create (0.3ms)\e[0m   \e[0mINSERT INTO `products` (`image_url`, `updated_at`, `title`, `price`, `description`, `created_at`) VALUES('/images/utc.jpg', '2009-01-30 11:28:11', 'Pragmatic Unit Testing (C#)', 27.75, '<p>\n Pragmatic programmers use feedback to drive their development and \n personal processes. The most valuable feedback you can get while \n coding comes from unit testing.\n </p>\n <p>\n Without good tests in place, coding can become a frustrating game of \n \"whack-a-mole.\" That\'s the carnival game where the player strikes at a \n mechanical mole; it retreats and another mole pops up on the opposite side \n of the field. The moles pop up and down so fast that you end up flailing \n your mallet helplessly as the moles continue to pop up where you least \n expect them.\n </p>', '2009-01-30 11:28:11')\e[0m
 481   \e[4;36;1mSQL (1.3ms)\e[0m   \e[0;1mCOMMIT\e[0m
 482   \e[4;35;1mSQL (0.7ms)\e[0m   \e[0mINSERT INTO schema_migrations (version) VALUES ('20090130111521')\e[0m
 483   \e[4;36;1mSQL (0.9ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 484   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSELECT version FROM schema_migrations\e[0m
 485   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSHOW TABLES\e[0m
 486   \e[4;35;1mSQL (1.0ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 487   \e[4;36;1mSQL (1.3ms)\e[0m   \e[0;1mdescribe `products`\e[0m
 488   \e[4;35;1mSQL (0.4ms)\e[0m   \e[0mSHOW KEYS FROM `products`\e[0m
 489
 490
 491 Processing ProductsController#index (for 127.0.0.1 at 2009-01-30 11:28:19) [GET]
 492   \e[4;36;1mSQL (0.3ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 493   \e[4;35;1mProduct Load (1.3ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
 494 Rendering template within layouts/products
 495 Rendering products/index
 496   \e[4;36;1mProduct Columns (1.2ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 497 Completed in 30ms (View: 15, DB: 3) | 200 OK [http://localhost/products]
 498
 499
 500 Processing ProductsController#index (for 127.0.0.1 at 2009-01-30 11:53:52) [GET]
 501   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 502   \e[4;36;1mProduct Load (0.3ms)\e[0m   \e[0;1mSELECT * FROM `products` \e[0m
 503 Rendering template within layouts/products
 504 Rendering products/index
 505   \e[4;35;1mProduct Columns (1.3ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 506 Completed in 41ms (View: 24, DB: 2) | 200 OK [http://localhost/products]
 507
 508
 509 Processing ProductsController#show (for 127.0.0.1 at 2009-01-30 11:53:55) [GET]
 510   Parameters: {"id"=>"1"}
 511   \e[4;36;1mSQL (0.2ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 512   \e[4;35;1mProduct Columns (2.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 513   \e[4;36;1mProduct Load (0.7ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 1) \e[0m
 514 Rendering template within layouts/products
 515 Rendering products/show
 516 Completed in 41ms (View: 20, DB: 3) | 200 OK [http://localhost/products/1]
 517
 518
 519 Processing ProductsController#show (for 127.0.0.1 at 2009-01-30 11:54:35) [GET]
 520   Parameters: {"id"=>"3"}
 521   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 522   \e[4;36;1mProduct Columns (1.3ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 523   \e[4;35;1mProduct Load (0.4ms)\e[0m   \e[0mSELECT * FROM `products` WHERE (`products`.`id` = 3) \e[0m
 524 Rendering template within layouts/products
 525 Rendering products/show
 526 Completed in 29ms (View: 14, DB: 2) | 200 OK [http://localhost/products/3]
 527
 528
 529 Processing ProductsController#index (for 127.0.0.1 at 2009-01-30 11:54:44) [GET]
 530   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 531   \e[4;35;1mProduct Load (0.3ms)\e[0m   \e[0mSELECT * FROM `products` \e[0m
 532 Rendering template within layouts/products
 533 Rendering products/index
 534   \e[4;36;1mProduct Columns (1.2ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 535 Completed in 39ms (View: 24, DB: 2) | 200 OK [http://localhost/products]
 536
 537
 538 Processing ProductsController#show (for 127.0.0.1 at 2009-01-30 11:55:35) [GET]
 539   Parameters: {"id"=>"3"}
 540   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 541   \e[4;36;1mProduct Columns (1.1ms)\e[0m   \e[0;1mSHOW FIELDS FROM `products`\e[0m
 542   \e[4;35;1mProduct Load (0.1ms)\e[0m   \e[0mSELECT * FROM `products` WHERE (`products`.`id` = 3) \e[0m
 543 Rendering template within layouts/products
 544 Rendering products/show
 545 Completed in 27ms (View: 13, DB: 1) | 200 OK [http://localhost/products/3]
 546
 547
 548 Processing ProductsController#edit (for 127.0.0.1 at 2009-01-30 11:55:43) [GET]
 549   Parameters: {"id"=>"2"}
 550   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 551   \e[4;35;1mProduct Columns (1.9ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 552   \e[4;36;1mProduct Load (0.5ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 2) \e[0m
 553 Rendering template within layouts/products
 554 Rendering products/edit
 555 Completed in 33ms (View: 18, DB: 2) | 200 OK [http://localhost/products/2/edit]
 556
 557
 558 Processing ProductsController#index (for 127.0.0.1 at 2009-01-30 11:56:05) [GET]
 559   \e[4;35;1mSQL (0.2ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 560   \e[4;36;1mProduct Load (0.2ms)\e[0m   \e[0;1mSELECT * FROM `products` \e[0m
 561 Rendering template within layouts/products
 562 Rendering products/index
 563   \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 564 Completed in 35ms (View: 20, DB: 1) | 200 OK [http://localhost/products]
 565
 566
 567 Processing ProductsController#show (for 127.0.0.1 at 2009-01-30 11:56:08) [GET]
 568   Parameters: {"id"=>"2"}
 569   \e[4;36;1mSQL (0.1ms)\e[0m   \e[0;1mSET SQL_AUTO_IS_NULL=0\e[0m
 570   \e[4;35;1mProduct Columns (1.1ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 571   \e[4;36;1mProduct Load (0.2ms)\e[0m   \e[0;1mSELECT * FROM `products` WHERE (`products`.`id` = 2) \e[0m
 572 Rendering template within layouts/products
 573 Rendering products/show
 574 Completed in 26ms (View: 12, DB: 1) | 200 OK [http://localhost/products/2]
 575
 576
 577 Processing ProductsController#index (for 127.0.0.1 at 2009-01-30 11:56:25) [GET]
 578   \e[4;35;1mSQL (0.1ms)\e[0m   \e[0mSET SQL_AUTO_IS_NULL=0\e[0m
 579   \e[4;36;1mProduct Load (0.2ms)\e[0m   \e[0;1mSELECT * FROM `products` \e[0m
 580 Rendering template within layouts/products
 581 Rendering products/index
 582   \e[4;35;1mProduct Columns (1.9ms)\e[0m   \e[0mSHOW FIELDS FROM `products`\e[0m
 583 Completed in 35ms (View: 21, DB: 2) | 200 OK [http://localhost/products]