(1.2ms) CREATE TABLE "schema_migrations" ("version" varchar(255) NOT NULL)   (0.1ms) select sqlite_version(*)  (1.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version") ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" Migrating to CreateOrders (20140801133550)  (0.1ms) begin transaction  (0.4ms) CREATE TABLE "orders" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "created_at" datetime, "updated_at" datetime) SQL (0.2ms) INSERT INTO "schema_migrations" ("version") VALUES (?) [["version", "20140801133550"]]  (0.8ms) commit transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" Started GET "/rails/info" for 127.0.0.1 at 2014-08-02 16:08:06 -0500 ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" Processing by Rails::InfoController#index as HTML Redirected to http://localhost:3000/rails/info/routes Completed 302 Found in 1ms (ActiveRecord: 0.0ms) Started GET "/rails/info/routes" for 127.0.0.1 at 2014-08-02 16:08:06 -0500 Processing by Rails::InfoController#routes as HTML Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/actionpack-4.1.4/lib/action_dispatch/middleware/templates/routes/_route.html.erb (1.0ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/actionpack-4.1.4/lib/action_dispatch/middleware/templates/routes/_route.html.erb (0.1ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/actionpack-4.1.4/lib/action_dispatch/middleware/templates/routes/_table.html.erb (7.1ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/railties-4.1.4/lib/rails/templates/rails/info/routes.html.erb within layouts/application (16.1ms) Completed 200 OK in 23ms (Views: 22.5ms | ActiveRecord: 0.0ms) Started GET "/magic_lamp/fixtures" for 127.0.0.1 at 2014-08-02 16:45:22 -0500 ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" Processing by MagicLamp::FixturesController#index as HTML Rendered text template (0.0ms) Completed 200 OK in 4ms (Views: 4.1ms | ActiveRecord: 0.0ms) ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.0ms) CREATE TABLE "schema_migrations" ("version" varchar(255) NOT NULL)   (0.1ms) select sqlite_version(*)  (0.9ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version") ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" Migrating to CreateOrders (20140801133550)  (0.0ms) begin transaction  (0.3ms) CREATE TABLE "orders" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "created_at" datetime, "updated_at" datetime) SQL (0.2ms) INSERT INTO "schema_migrations" ("version") VALUES (?) [["version", "20140801133550"]]  (0.8ms) commit transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (9.1ms) CREATE TABLE "schema_migrations" ("version" varchar(255) NOT NULL)   (0.1ms) select sqlite_version(*)  (1.0ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version") ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" Migrating to CreateOrders (20140801133550)  (0.1ms) begin transaction  (0.3ms) CREATE TABLE "orders" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "created_at" datetime, "updated_at" datetime) SQL (0.1ms) INSERT INTO "schema_migrations" ("version") VALUES (?) [["version", "20140801133550"]]  (0.9ms) commit transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations"  (1.1ms) CREATE TABLE "schema_migrations" ("version" varchar(255) NOT NULL)   (0.1ms) select sqlite_version(*)  (0.9ms) CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version") ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" Migrating to CreateOrders (20140801133550)  (0.1ms) begin transaction  (0.4ms) CREATE TABLE "orders" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "created_at" datetime, "updated_at" datetime) SQL (0.1ms) INSERT INTO "schema_migrations" ("version") VALUES (?) [["version", "20140801133550"]]  (0.7ms) commit transaction ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" Started GET "/magic_lamp" for 127.0.0.1 at 2014-08-03 08:14:27 -0500 ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActionController::RoutingError (No route matches [GET] "/magic_lamp"): actionpack (4.1.4) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' actionpack (4.1.4) lib/action_dispatch/middleware/show_exceptions.rb:30:in `call' railties (4.1.4) lib/rails/rack/logger.rb:38:in `call_app' railties (4.1.4) lib/rails/rack/logger.rb:20:in `block in call' activesupport (4.1.4) lib/active_support/tagged_logging.rb:68:in `block in tagged' activesupport (4.1.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (4.1.4) lib/active_support/tagged_logging.rb:68:in `tagged' railties (4.1.4) lib/rails/rack/logger.rb:20:in `call' actionpack (4.1.4) lib/action_dispatch/middleware/request_id.rb:21:in `call' rack (1.5.2) lib/rack/methodoverride.rb:21:in `call' rack (1.5.2) lib/rack/runtime.rb:17:in `call' activesupport (4.1.4) lib/active_support/cache/strategy/local_cache_middleware.rb:26:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' actionpack (4.1.4) lib/action_dispatch/middleware/static.rb:64:in `call' rack (1.5.2) lib/rack/sendfile.rb:112:in `call' railties (4.1.4) lib/rails/engine.rb:514:in `call' railties (4.1.4) lib/rails/application.rb:144:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' rack (1.5.2) lib/rack/content_length.rb:14:in `call' rack (1.5.2) lib/rack/handler/webrick.rb:60:in `service' /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/2.1.0/webrick/httpserver.rb:138:in `service' /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/2.1.0/webrick/httpserver.rb:94:in `run' /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/2.1.0/webrick/server.rb:295:in `block in start_thread' Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/actionpack-4.1.4/lib/action_dispatch/middleware/templates/rescues/_trace.html.erb (1.0ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/actionpack-4.1.4/lib/action_dispatch/middleware/templates/routes/_route.html.erb (1.0ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/actionpack-4.1.4/lib/action_dispatch/middleware/templates/routes/_route.html.erb (0.0ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/actionpack-4.1.4/lib/action_dispatch/middleware/templates/routes/_table.html.erb (7.9ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/actionpack-4.1.4/lib/action_dispatch/middleware/templates/rescues/routing_error.html.erb within rescues/layout (30.6ms) Started GET "/magic_lamp/fixtures" for 127.0.0.1 at 2014-08-03 08:14:40 -0500 ActionController::RoutingError (No route matches [GET] "/magic_lamp/fixtures"): actionpack (4.1.4) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' actionpack (4.1.4) lib/action_dispatch/middleware/show_exceptions.rb:30:in `call' railties (4.1.4) lib/rails/rack/logger.rb:38:in `call_app' railties (4.1.4) lib/rails/rack/logger.rb:20:in `block in call' activesupport (4.1.4) lib/active_support/tagged_logging.rb:68:in `block in tagged' activesupport (4.1.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (4.1.4) lib/active_support/tagged_logging.rb:68:in `tagged' railties (4.1.4) lib/rails/rack/logger.rb:20:in `call' actionpack (4.1.4) lib/action_dispatch/middleware/request_id.rb:21:in `call' rack (1.5.2) lib/rack/methodoverride.rb:21:in `call' rack (1.5.2) lib/rack/runtime.rb:17:in `call' activesupport (4.1.4) lib/active_support/cache/strategy/local_cache_middleware.rb:26:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' actionpack (4.1.4) lib/action_dispatch/middleware/static.rb:64:in `call' rack (1.5.2) lib/rack/sendfile.rb:112:in `call' railties (4.1.4) lib/rails/engine.rb:514:in `call' railties (4.1.4) lib/rails/application.rb:144:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' rack (1.5.2) lib/rack/content_length.rb:14:in `call' rack (1.5.2) lib/rack/handler/webrick.rb:60:in `service' /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/2.1.0/webrick/httpserver.rb:138:in `service' /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/2.1.0/webrick/httpserver.rb:94:in `run' /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/2.1.0/webrick/server.rb:295:in `block in start_thread' Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/actionpack-4.1.4/lib/action_dispatch/middleware/templates/rescues/_trace.html.erb (1.5ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/actionpack-4.1.4/lib/action_dispatch/middleware/templates/routes/_route.html.erb (1.2ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/actionpack-4.1.4/lib/action_dispatch/middleware/templates/routes/_route.html.erb (0.0ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/actionpack-4.1.4/lib/action_dispatch/middleware/templates/routes/_table.html.erb (0.9ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/actionpack-4.1.4/lib/action_dispatch/middleware/templates/rescues/routing_error.html.erb within rescues/layout (21.9ms) WEBrick 1.3.1 ruby 2.1.1 (2014-02-24) [x86_64-darwin13.0] Rack::Handler::WEBrick is mounted on /. WEBrick::HTTPServer#start: pid=99711 port=53661 accept: 127.0.0.1:53663 close: 127.0.0.1:53663 accept: 127.0.0.1:53664 Rack::Handler::WEBrick is invoked. Started GET "/teaspoon/default?reporter=Console" for 127.0.0.1 at 2014-08-03 08:36:57 -0500 ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" Processing by Teaspoon::SuiteController#show as HTML Parameters: {"reporter"=>"Console", "suite"=>"default"} Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/show.html.erb (22.4ms) Completed 500 Internal Server Error in 35ms close: 127.0.0.1:53664 going to shutdown ... WEBrick::HTTPServer#start done. WEBrick 1.3.1 ruby 2.1.1 (2014-02-24) [x86_64-darwin13.0] Rack::Handler::WEBrick is mounted on /. WEBrick::HTTPServer#start: pid=519 port=53666 accept: 127.0.0.1:53668 close: 127.0.0.1:53668 accept: 127.0.0.1:53669 Rack::Handler::WEBrick is invoked. Started GET "/teaspoon/default?reporter=Console" for 127.0.0.1 at 2014-08-03 08:37:41 -0500 ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" Processing by Teaspoon::SuiteController#show as HTML Parameters: {"reporter"=>"Console", "suite"=>"default"} Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/show.html.erb (32.1ms) Completed 500 Internal Server Error in 37ms close: 127.0.0.1:53669 going to shutdown ... WEBrick::HTTPServer#start done. WEBrick 1.3.1 ruby 2.1.1 (2014-02-24) [x86_64-darwin13.0] Rack::Handler::WEBrick is mounted on /. WEBrick::HTTPServer#start: pid=868 port=53672 accept: 127.0.0.1:53674 close: 127.0.0.1:53674 accept: 127.0.0.1:53675 Rack::Handler::WEBrick is invoked. Started GET "/teaspoon/default?reporter=Console" for 127.0.0.1 at 2014-08-03 08:37:59 -0500 ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" Processing by Teaspoon::SuiteController#show as HTML Parameters: {"reporter"=>"Console", "suite"=>"default"} Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/show.html.erb (30.5ms) Completed 500 Internal Server Error in 35ms close: 127.0.0.1:53675 going to shutdown ... WEBrick::HTTPServer#start done. WEBrick 1.3.1 ruby 2.1.1 (2014-02-24) [x86_64-darwin13.0] Rack::Handler::WEBrick is mounted on /. WEBrick::HTTPServer#start: pid=1122 port=53676 accept: 127.0.0.1:53678 close: 127.0.0.1:53678 accept: 127.0.0.1:53679 Rack::Handler::WEBrick is invoked. Started GET "/teaspoon/default?reporter=Console" for 127.0.0.1 at 2014-08-03 08:38:19 -0500 ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" Processing by Teaspoon::SuiteController#show as HTML Parameters: {"reporter"=>"Console", "suite"=>"default"} Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/_boot.html.erb (17.7ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/_body.html.erb (0.3ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/show.html.erb (46.6ms) Completed 200 OK in 51ms (Views: 51.0ms | ActiveRecord: 0.0ms) accept: 127.0.0.1:53680 Rack::Handler::WEBrick is invoked. accept: 127.0.0.1:53682 accept: 127.0.0.1:53681 Started GET "/assets/teaspoon.css?body=1" for 127.0.0.1 at 2014-08-03 08:38:20 -0500 Rack::Handler::WEBrick is invoked. Rack::Handler::WEBrick is invoked. Rack::Handler::WEBrick is invoked. Started GET "/assets/mocha/1.17.1.js?body=1" for 127.0.0.1 at 2014-08-03 08:38:20 -0500 Started GET "/javascripts/spec_helper.js" for 127.0.0.1 at 2014-08-03 08:38:20 -0500 Started GET "/assets/teaspoon-mocha.js?body=1" for 127.0.0.1 at 2014-08-03 08:38:20 -0500 close: 127.0.0.1:53679 close: 127.0.0.1:53682 close: 127.0.0.1:53681 close: 127.0.0.1:53680 going to shutdown ... WEBrick::HTTPServer#start done. WEBrick 1.3.1 ruby 2.1.1 (2014-02-24) [x86_64-darwin13.0] Rack::Handler::WEBrick is mounted on /. WEBrick::HTTPServer#start: pid=1360 port=53683 accept: 127.0.0.1:53685 close: 127.0.0.1:53685 accept: 127.0.0.1:53686 Rack::Handler::WEBrick is invoked. Started GET "/teaspoon/default?reporter=Console" for 127.0.0.1 at 2014-08-03 08:38:29 -0500 ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" Processing by Teaspoon::SuiteController#show as HTML Parameters: {"reporter"=>"Console", "suite"=>"default"} Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/_boot.html.erb (14.9ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/_body.html.erb (0.3ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/show.html.erb (36.0ms) Completed 200 OK in 41ms (Views: 40.4ms | ActiveRecord: 0.0ms) accept: 127.0.0.1:53688 accept: 127.0.0.1:53687 accept: 127.0.0.1:53689 Rack::Handler::WEBrick is invoked. Started GET "/assets/teaspoon.css?body=1" for 127.0.0.1 at 2014-08-03 08:38:30 -0500 Rack::Handler::WEBrick is invoked. Rack::Handler::WEBrick is invoked. Rack::Handler::WEBrick is invoked. Started GET "/assets/teaspoon-mocha.js?body=1" for 127.0.0.1 at 2014-08-03 08:38:30 -0500 Started GET "/assets/mocha/1.17.1.js?body=1" for 127.0.0.1 at 2014-08-03 08:38:30 -0500 Started GET "/javascripts/spec_helper.js" for 127.0.0.1 at 2014-08-03 08:38:30 -0500 close: 127.0.0.1:53686 close: 127.0.0.1:53687 close: 127.0.0.1:53688 close: 127.0.0.1:53689 going to shutdown ... WEBrick::HTTPServer#start done. WEBrick 1.3.1 ruby 2.1.1 (2014-02-24) [x86_64-darwin13.0] Rack::Handler::WEBrick is mounted on /. WEBrick::HTTPServer#start: pid=1623 port=53691 accept: 127.0.0.1:53693 close: 127.0.0.1:53693 accept: 127.0.0.1:53694 Rack::Handler::WEBrick is invoked. Started GET "/teaspoon/default?reporter=Console" for 127.0.0.1 at 2014-08-03 08:39:52 -0500 ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" Processing by Teaspoon::SuiteController#show as HTML Parameters: {"reporter"=>"Console", "suite"=>"default"} Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/_boot.html.erb (15.0ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/_body.html.erb (0.3ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/show.html.erb (35.6ms) Completed 200 OK in 41ms (Views: 40.2ms | ActiveRecord: 0.0ms) Rack::Handler::WEBrick is invoked. Started GET "/assets/teaspoon.css?body=1" for 127.0.0.1 at 2014-08-03 08:39:52 -0500 accept: 127.0.0.1:53696 accept: 127.0.0.1:53695 accept: 127.0.0.1:53697 Rack::Handler::WEBrick is invoked. Rack::Handler::WEBrick is invoked. Started GET "/assets/mocha/1.17.1.js?body=1" for 127.0.0.1 at 2014-08-03 08:39:52 -0500 Rack::Handler::WEBrick is invoked. Started GET "/assets/teaspoon-mocha.js?body=1" for 127.0.0.1 at 2014-08-03 08:39:52 -0500 Started GET "/javascripts/spec_helper.js" for 127.0.0.1 at 2014-08-03 08:39:52 -0500 close: 127.0.0.1:53696 close: 127.0.0.1:53697 close: 127.0.0.1:53695 close: 127.0.0.1:53694 going to shutdown ... WEBrick::HTTPServer#start done. WEBrick 1.3.1 ruby 2.1.1 (2014-02-24) [x86_64-darwin13.0] Rack::Handler::WEBrick is mounted on /. WEBrick::HTTPServer#start: pid=1886 port=53813 accept: 127.0.0.1:53815 close: 127.0.0.1:53815 accept: 127.0.0.1:53816 Rack::Handler::WEBrick is invoked. Started GET "/teaspoon/default?reporter=Console" for 127.0.0.1 at 2014-08-03 08:42:39 -0500 ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" Processing by Teaspoon::SuiteController#show as HTML Parameters: {"reporter"=>"Console", "suite"=>"default"} Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/_boot.html.erb (15.3ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/_body.html.erb (0.3ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/show.html.erb (36.1ms) Completed 200 OK in 41ms (Views: 40.6ms | ActiveRecord: 0.0ms) accept: 127.0.0.1:53819 accept: 127.0.0.1:53818 accept: 127.0.0.1:53817 Rack::Handler::WEBrick is invoked. Started GET "/assets/teaspoon.css?body=1" for 127.0.0.1 at 2014-08-03 08:42:39 -0500 Rack::Handler::WEBrick is invoked. Started GET "/assets/teaspoon-mocha.js?body=1" for 127.0.0.1 at 2014-08-03 08:42:39 -0500 Rack::Handler::WEBrick is invoked. Rack::Handler::WEBrick is invoked. Started GET "/javascripts/spec_helper.js" for 127.0.0.1 at 2014-08-03 08:42:39 -0500 Started GET "/assets/mocha/1.17.1.js?body=1" for 127.0.0.1 at 2014-08-03 08:42:39 -0500 close: 127.0.0.1:53816 close: 127.0.0.1:53817 close: 127.0.0.1:53819 close: 127.0.0.1:53818 going to shutdown ... WEBrick::HTTPServer#start done. Started GET "/specs/default" for 127.0.0.1 at 2014-08-03 08:44:05 -0500 ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActionController::RoutingError (No route matches [GET] "/specs/default"): actionpack (4.1.4) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' actionpack (4.1.4) lib/action_dispatch/middleware/show_exceptions.rb:30:in `call' railties (4.1.4) lib/rails/rack/logger.rb:38:in `call_app' railties (4.1.4) lib/rails/rack/logger.rb:20:in `block in call' activesupport (4.1.4) lib/active_support/tagged_logging.rb:68:in `block in tagged' activesupport (4.1.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (4.1.4) lib/active_support/tagged_logging.rb:68:in `tagged' railties (4.1.4) lib/rails/rack/logger.rb:20:in `call' actionpack (4.1.4) lib/action_dispatch/middleware/request_id.rb:21:in `call' rack (1.5.2) lib/rack/methodoverride.rb:21:in `call' rack (1.5.2) lib/rack/runtime.rb:17:in `call' activesupport (4.1.4) lib/active_support/cache/strategy/local_cache_middleware.rb:26:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' actionpack (4.1.4) lib/action_dispatch/middleware/static.rb:64:in `call' rack (1.5.2) lib/rack/sendfile.rb:112:in `call' railties (4.1.4) lib/rails/engine.rb:514:in `call' railties (4.1.4) lib/rails/application.rb:144:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' rack (1.5.2) lib/rack/content_length.rb:14:in `call' rack (1.5.2) lib/rack/handler/webrick.rb:60:in `service' /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/2.1.0/webrick/httpserver.rb:138:in `service' /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/2.1.0/webrick/httpserver.rb:94:in `run' /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/2.1.0/webrick/server.rb:295:in `block in start_thread' Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/actionpack-4.1.4/lib/action_dispatch/middleware/templates/rescues/_trace.html.erb (0.9ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/actionpack-4.1.4/lib/action_dispatch/middleware/templates/routes/_route.html.erb (0.9ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/actionpack-4.1.4/lib/action_dispatch/middleware/templates/routes/_route.html.erb (0.2ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/actionpack-4.1.4/lib/action_dispatch/middleware/templates/routes/_route.html.erb (0.0ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/actionpack-4.1.4/lib/action_dispatch/middleware/templates/routes/_table.html.erb (5.5ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/actionpack-4.1.4/lib/action_dispatch/middleware/templates/rescues/routing_error.html.erb within rescues/layout (28.5ms) WEBrick 1.3.1 ruby 2.1.1 (2014-02-24) [x86_64-darwin13.0] Rack::Handler::WEBrick is mounted on /. WEBrick::HTTPServer#start: pid=4831 port=53841 accept: 127.0.0.1:53843 close: 127.0.0.1:53843 accept: 127.0.0.1:53844 Rack::Handler::WEBrick is invoked. Started GET "/teaspoon/default?reporter=Console" for 127.0.0.1 at 2014-08-03 08:48:13 -0500 ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" Processing by Teaspoon::SuiteController#show as HTML Parameters: {"reporter"=>"Console", "suite"=>"default"} Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/show.html.erb (14.7ms) Completed 500 Internal Server Error in 20ms close: 127.0.0.1:53844 going to shutdown ... WEBrick::HTTPServer#start done. WEBrick 1.3.1 ruby 2.1.1 (2014-02-24) [x86_64-darwin13.0] Rack::Handler::WEBrick is mounted on /. WEBrick::HTTPServer#start: pid=5074 port=53845 accept: 127.0.0.1:53847 close: 127.0.0.1:53847 accept: 127.0.0.1:53848 Rack::Handler::WEBrick is invoked. Started GET "/teaspoon/default?reporter=Console" for 127.0.0.1 at 2014-08-03 08:48:28 -0500 ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" Processing by Teaspoon::SuiteController#show as HTML Parameters: {"reporter"=>"Console", "suite"=>"default"} Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/_boot.html.erb (78.6ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/_body.html.erb (0.3ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/show.html.erb (99.7ms) Completed 200 OK in 105ms (Views: 104.2ms | ActiveRecord: 0.0ms) Rack::Handler::WEBrick is invoked. Started GET "/assets/teaspoon.css?body=1" for 127.0.0.1 at 2014-08-03 08:48:28 -0500 accept: 127.0.0.1:53850 accept: 127.0.0.1:53849 accept: 127.0.0.1:53851 accept: 127.0.0.1:53853 accept: 127.0.0.1:53852 Rack::Handler::WEBrick is invoked. Rack::Handler::WEBrick is invoked. Rack::Handler::WEBrick is invoked. Rack::Handler::WEBrick is invoked. Rack::Handler::WEBrick is invoked. Started GET "/assets/mocha/1.17.1.js?body=1" for 127.0.0.1 at 2014-08-03 08:48:28 -0500 Rack::Handler::WEBrick is invoked. Started GET "/assets/spec_helper.js?body=1" for 127.0.0.1 at 2014-08-03 08:48:28 -0500 Rack::Handler::WEBrick is invoked. Started GET "/assets/teaspoon-mocha.js?body=1" for 127.0.0.1 at 2014-08-03 08:48:28 -0500 Started GET "/assets/support/chai.js?body=1" for 127.0.0.1 at 2014-08-03 08:48:28 -0500 Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2014-08-03 08:48:28 -0500 Started GET "/assets/support/bind-poly.js?body=1" for 127.0.0.1 at 2014-08-03 08:48:28 -0500 Started GET "/assets/test_spec.js?body=1" for 127.0.0.1 at 2014-08-03 08:48:28 -0500 close: 127.0.0.1:53853 close: 127.0.0.1:53850 close: 127.0.0.1:53848 close: 127.0.0.1:53852 close: 127.0.0.1:53851 close: 127.0.0.1:53849 going to shutdown ... WEBrick::HTTPServer#start done. WEBrick 1.3.1 ruby 2.1.1 (2014-02-24) [x86_64-darwin13.0] Rack::Handler::WEBrick is mounted on /. WEBrick::HTTPServer#start: pid=16083 port=53866 accept: 127.0.0.1:53868 close: 127.0.0.1:53868 accept: 127.0.0.1:53869 Rack::Handler::WEBrick is invoked. Started GET "/teaspoon/default?reporter=Console" for 127.0.0.1 at 2014-08-03 08:55:33 -0500 ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" Processing by Teaspoon::SuiteController#show as HTML Parameters: {"reporter"=>"Console", "suite"=>"default"} Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/_boot.html.erb (43.6ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/_body.html.erb (0.3ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/show.html.erb (64.0ms) Completed 200 OK in 69ms (Views: 68.3ms | ActiveRecord: 0.0ms) Rack::Handler::WEBrick is invoked. Started GET "/assets/teaspoon.css?body=1" for 127.0.0.1 at 2014-08-03 08:55:33 -0500 accept: 127.0.0.1:53871 accept: 127.0.0.1:53870 accept: 127.0.0.1:53872 accept: 127.0.0.1:53873 accept: 127.0.0.1:53874 Rack::Handler::WEBrick is invoked. Rack::Handler::WEBrick is invoked. Rack::Handler::WEBrick is invoked. Rack::Handler::WEBrick is invoked. Rack::Handler::WEBrick is invoked. Rack::Handler::WEBrick is invoked. Started GET "/assets/support/bind-poly.js?body=1" for 127.0.0.1 at 2014-08-03 08:55:33 -0500 Started GET "/assets/spec_helper.js?body=1" for 127.0.0.1 at 2014-08-03 08:55:33 -0500 Rack::Handler::WEBrick is invoked. Started GET "/assets/mocha/1.17.1.js?body=1" for 127.0.0.1 at 2014-08-03 08:55:33 -0500 Started GET "/assets/teaspoon-mocha.js?body=1" for 127.0.0.1 at 2014-08-03 08:55:33 -0500 Started GET "/assets/support/chai.js?body=1" for 127.0.0.1 at 2014-08-03 08:55:33 -0500 Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2014-08-03 08:55:33 -0500 Started GET "/assets/test_spec.js?body=1" for 127.0.0.1 at 2014-08-03 08:55:33 -0500 close: 127.0.0.1:53873 close: 127.0.0.1:53872 close: 127.0.0.1:53869 close: 127.0.0.1:53871 close: 127.0.0.1:53870 close: 127.0.0.1:53874 going to shutdown ... WEBrick::HTTPServer#start done. WEBrick 1.3.1 ruby 2.1.1 (2014-02-24) [x86_64-darwin13.0] Rack::Handler::WEBrick is mounted on /. WEBrick::HTTPServer#start: pid=17105 port=54467 accept: 127.0.0.1:54469 close: 127.0.0.1:54469 accept: 127.0.0.1:54470 Rack::Handler::WEBrick is invoked. Started GET "/teaspoon/default?reporter=Console" for 127.0.0.1 at 2014-08-03 10:03:18 -0500 ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" Processing by Teaspoon::SuiteController#show as HTML Parameters: {"reporter"=>"Console", "suite"=>"default"} Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/_boot.html.erb (43.9ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/_body.html.erb (0.3ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/show.html.erb (64.5ms) Completed 200 OK in 69ms (Views: 69.0ms | ActiveRecord: 0.0ms) Rack::Handler::WEBrick is invoked. Started GET "/assets/teaspoon.css?body=1" for 127.0.0.1 at 2014-08-03 10:03:18 -0500 accept: 127.0.0.1:54471 accept: 127.0.0.1:54472 accept: 127.0.0.1:54474 accept: 127.0.0.1:54475 accept: 127.0.0.1:54473 Rack::Handler::WEBrick is invoked. Started GET "/assets/spec_helper.js?body=1" for 127.0.0.1 at 2014-08-03 10:03:18 -0500 Rack::Handler::WEBrick is invoked. Rack::Handler::WEBrick is invoked. Rack::Handler::WEBrick is invoked. Rack::Handler::WEBrick is invoked. Rack::Handler::WEBrick is invoked. Started GET "/assets/mocha/1.17.1.js?body=1" for 127.0.0.1 at 2014-08-03 10:03:18 -0500 Rack::Handler::WEBrick is invoked. Started GET "/assets/support/bind-poly.js?body=1" for 127.0.0.1 at 2014-08-03 10:03:18 -0500 Started GET "/assets/application.js?body=1" for 127.0.0.1 at 2014-08-03 10:03:18 -0500 Started GET "/assets/teaspoon-mocha.js?body=1" for 127.0.0.1 at 2014-08-03 10:03:18 -0500 Started GET "/assets/support/chai.js?body=1" for 127.0.0.1 at 2014-08-03 10:03:18 -0500 Started GET "/assets/test_spec.js?body=1" for 127.0.0.1 at 2014-08-03 10:03:18 -0500 close: 127.0.0.1:54471 close: 127.0.0.1:54470 close: 127.0.0.1:54472 close: 127.0.0.1:54474 close: 127.0.0.1:54473 close: 127.0.0.1:54475 going to shutdown ... WEBrick::HTTPServer#start done. Started GET "/specs/default" for 127.0.0.1 at 2014-08-03 11:53:40 -0500 ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" ActionController::RoutingError (No route matches [GET] "/specs/default"): actionpack (4.1.4) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call' actionpack (4.1.4) lib/action_dispatch/middleware/show_exceptions.rb:30:in `call' railties (4.1.4) lib/rails/rack/logger.rb:38:in `call_app' railties (4.1.4) lib/rails/rack/logger.rb:20:in `block in call' activesupport (4.1.4) lib/active_support/tagged_logging.rb:68:in `block in tagged' activesupport (4.1.4) lib/active_support/tagged_logging.rb:26:in `tagged' activesupport (4.1.4) lib/active_support/tagged_logging.rb:68:in `tagged' railties (4.1.4) lib/rails/rack/logger.rb:20:in `call' actionpack (4.1.4) lib/action_dispatch/middleware/request_id.rb:21:in `call' rack (1.5.2) lib/rack/methodoverride.rb:21:in `call' rack (1.5.2) lib/rack/runtime.rb:17:in `call' activesupport (4.1.4) lib/active_support/cache/strategy/local_cache_middleware.rb:26:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' actionpack (4.1.4) lib/action_dispatch/middleware/static.rb:64:in `call' rack (1.5.2) lib/rack/sendfile.rb:112:in `call' railties (4.1.4) lib/rails/engine.rb:514:in `call' railties (4.1.4) lib/rails/application.rb:144:in `call' rack (1.5.2) lib/rack/lock.rb:17:in `call' rack (1.5.2) lib/rack/content_length.rb:14:in `call' rack (1.5.2) lib/rack/handler/webrick.rb:60:in `service' /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/2.1.0/webrick/httpserver.rb:138:in `service' /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/2.1.0/webrick/httpserver.rb:94:in `run' /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/2.1.0/webrick/server.rb:295:in `block in start_thread' Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/actionpack-4.1.4/lib/action_dispatch/middleware/templates/rescues/_trace.html.erb (0.8ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/actionpack-4.1.4/lib/action_dispatch/middleware/templates/routes/_route.html.erb (1.0ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/actionpack-4.1.4/lib/action_dispatch/middleware/templates/routes/_route.html.erb (0.3ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/actionpack-4.1.4/lib/action_dispatch/middleware/templates/routes/_route.html.erb (0.0ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/actionpack-4.1.4/lib/action_dispatch/middleware/templates/routes/_table.html.erb (13.5ms) Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/actionpack-4.1.4/lib/action_dispatch/middleware/templates/rescues/routing_error.html.erb within rescues/layout (33.9ms) Started GET "/teaspoon/default" for 127.0.0.1 at 2014-08-03 11:53:47 -0500 Processing by Teaspoon::SuiteController#show as HTML Parameters: {"suite"=>"default"} Rendered /Users/Michael/.rbenv/versions/2.1.1/lib/ruby/gems/2.1.0/gems/teaspoon-0.8.0/app/views/teaspoon/suite/show.html.erb (22.4ms) Completed 500 Internal Server Error in 26ms ActionView::Template::Error (Asset filtered out and will not be served: add `Rails.application.config.assets.precompile += %w( jasmine/1.3.1.js )` to `config/initializers/assets.rb` and restart your server): 6: <%= csrf_meta_tags %> 7: 8: <%= stylesheet_link_tag *@suite.stylesheets %> 9: <%= javascript_include_tag *@suite.javascripts %> 10: