test/dummy/log/test.log in bento_search-1.0.0 vs test/dummy/log/test.log in bento_search-1.0.1

- old
+ new

@@ -282601,5 +282601,2529 @@  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) rollback transaction +Connecting to database specified by database.yml +  (0.5ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (7.7ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/atom_results.atom.builder (64.6ms) +  (0.1ms) rollback transaction +  (0.1ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (6.7ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (6.7ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (65.1ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (6.7ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (0.0ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (7.0ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (6.7ms) +  (0.1ms) rollback transaction +  (0.1ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (6.7ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (3.7ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +Processing by BentoSearch::SearchControllerTest::CustomSearchController#search as HTML + Parameters: {"engine_id"=>"mock", "query"=>"my search"} +Completed 403 Forbidden in 4ms (Views: 3.8ms | ActiveRecord: 0.0ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +Processing by BentoSearch::SearchController#search as HTML + Parameters: {"query"=>"my search", "engine_id"=>"with_layout_config"} + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.9ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (6.3ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_wrap_with_count.html.erb (7.7ms) +Completed 200 OK in 63ms (Views: 61.5ms | ActiveRecord: 0.0ms) +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Processing by BentoSearch::SearchController#search as HTML + Parameters: {"query"=>"my search", "engine_id"=>"failed_response"} + Rendered /home/rochkind/bento_search/app/views/bento_search/_search_error.html.erb (4.9ms) +Completed 200 OK in 7ms (Views: 6.3ms | ActiveRecord: 0.0ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +Processing by BentoSearch::SearchController#search as HTML + Parameters: {"query"=>"my search", "engine_id"=>"not_existing"} +Completed 404 Not Found in 1ms (Views: 0.3ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +Processing by BentoSearch::SearchController#search as HTML + Parameters: {"query"=>"my search", "engine_id"=>"not_routable"} +Completed 403 Forbidden in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +Processing by BentoSearch::SearchController#search as HTML + Parameters: {"query"=>"query", "sort"=>"sort", "per_page"=>"15", "page"=>"6", "search_field"=>"title", "not_allowed"=>"not allowed", "engine_id"=>"mock"} + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (5.7ms) +Completed 200 OK in 7ms (Views: 6.3ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +Processing by BentoSearch::SearchController#search as HTML + Parameters: {"query"=>"my search", "engine_id"=>"mock"} + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (3.7ms) +Completed 200 OK in 5ms (Views: 4.2ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +  (0.2ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered _test_custom_item_partial.html.erb (3.6ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_no_results.html.erb (0.5ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.9ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (8.6ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_search_error.html.erb (0.7ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (3.8ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (3.8ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.8ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +EDS timing AUTH: 0.092616s +EDS timing GET: 0.005591:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=n +EDS timing GET: 0.007004:http://eds-api.ebscohost.com/edsapi/rest/search?view=detailed&query=AND%2Ccancer&searchmode=all&highlight=y&resultsperpage=10 +EDS timing GET: 0.007065:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=26cf851d-9a36-435b-a50a-14a5aea15338.KErj2NrKZC8UxoZu2SfB28G2%2Fw9Gjg%2BwmkWnBdUv3As%3D +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +EDS timing AUTH: 0.006103s +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +EDS timing AUTH: 0.004327s +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +EDS timing AUTH: 0.060873s +EDS timing GET: 0.005437:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y +EDS timing GET: 0.004044:http://eds-api.ebscohost.com/edsapi/rest/info +EDS timing GET: 0.006408:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=c6f7d532-8726-47a7-8e75-b3851a615c68.lS%2FLBviHYcah4372jECVTkM%2Fb4%2FDpExjKLExBuNU0eA%3D +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +EDS timing AUTH: 0.011951s +EDS timing GET: 0.002891:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y +EDS timing GET: 0.002842:http://eds-api.ebscohost.com/edsapi/rest/info +EDS auth failed, getting auth again +EDS timing AUTH: 0.002912s +EDS timing GET: 0.006407:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=d389e524-95ea-4518-bd4a-6b0eb7c0af44.kEvPxolRdsxALTno8ChwQHJ3j2LNLih2ApF64T4yK4w%3D +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_link.html.erb (0.6ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_link.html.erb (0.2ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +BentoSearch::SearchEngine failed results: #<Timeout::Error: I am a horrible engine> + /home/rochkind/bento_search/test/unit/search_engine_base_test.rb:206:in `search_implementation' + /home/rochkind/bento_search/app/models/bento_search/search_engine.rb:215:in `search' + /home/rochkind/bento_search/test/unit/search_engine_base_test.rb:212:in `test_rescues_exceptions' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:949:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit/testcase.rb:17:in `run' + activesupport (3.2.9) lib/active_support/testing/setup_and_teardown.rb:36:in `block in run' + activesupport (3.2.9) lib/active_support/callbacks.rb:425:in `_run__4444376825689105339__setup__407622498459240528__callbacks' + activesupport (3.2.9) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.9) lib/active_support/callbacks.rb:385:in `_run_setup_callbacks' + activesupport (3.2.9) lib/active_support/callbacks.rb:81:in `run_callbacks' + activesupport (3.2.9) lib/active_support/testing/setup_and_teardown.rb:35:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:787:in `block in _run_suite' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:780:in `map' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:780:in `_run_suite' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:565:in `block in _run_suites' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:563:in `each' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:563:in `_run_suites' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:746:in `_run_anything' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:909:in `run_tests' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:896:in `block in _run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:895:in `each' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:895:in `_run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:884:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:21:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:326:in `block (2 levels) in autorun' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:27:in `run_once' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:325:in `block in autorun' +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +BentoSearch::SearchEngine failed results: #<Timeout::Error: MockEngine forced raise> + /home/rochkind/bento_search/app/search_engines/bento_search/mock_engine.rb:29:in `search_implementation' + /home/rochkind/bento_search/app/models/bento_search/search_engine.rb:215:in `search' + /home/rochkind/bento_search/app/search_engines/bento_search/mock_engine.rb:47:in `search' + /home/rochkind/bento_search/test/unit/search_engine_test.rb:131:in `block in <class:SearchEngineTest>' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:949:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit/testcase.rb:17:in `run' + activesupport (3.2.9) lib/active_support/testing/setup_and_teardown.rb:36:in `block in run' + activesupport (3.2.9) lib/active_support/callbacks.rb:425:in `_run__1092739677905571412__setup__407622498459240528__callbacks' + activesupport (3.2.9) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.9) lib/active_support/callbacks.rb:385:in `_run_setup_callbacks' + activesupport (3.2.9) lib/active_support/callbacks.rb:81:in `run_callbacks' + activesupport (3.2.9) lib/active_support/testing/setup_and_teardown.rb:35:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:787:in `block in _run_suite' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:780:in `map' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:780:in `_run_suite' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:565:in `block in _run_suites' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:563:in `each' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:563:in `_run_suites' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:746:in `_run_anything' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:909:in `run_tests' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:896:in `block in _run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:895:in `each' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:895:in `_run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:884:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:21:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:326:in `block (2 levels) in autorun' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:27:in `run_once' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:325:in `block in autorun' +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.7ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (2.5ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.4ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.8ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.5ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.5ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_link.html.erb (0.5ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (4.1ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.5ms) +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +Connecting to database specified by database.yml +  (0.5ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (9.0ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/atom_results.atom.builder (65.5ms) +  (0.1ms) rollback transaction +  (0.1ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (6.7ms) +  (0.1ms) rollback transaction +  (0.1ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (6.7ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (65.3ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (6.7ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (0.0ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (6.7ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (7.1ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (6.7ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (3.6ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +Processing by BentoSearch::SearchControllerTest::CustomSearchController#search as HTML + Parameters: {"engine_id"=>"mock", "query"=>"my search"} +Completed 403 Forbidden in 4ms (Views: 3.8ms | ActiveRecord: 0.0ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +Processing by BentoSearch::SearchController#search as HTML + Parameters: {"query"=>"my search", "engine_id"=>"with_layout_config"} + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.9ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (6.1ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_wrap_with_count.html.erb (7.5ms) +Completed 200 OK in 62ms (Views: 61.3ms | ActiveRecord: 0.0ms) +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Processing by BentoSearch::SearchController#search as HTML + Parameters: {"query"=>"my search", "engine_id"=>"failed_response"} + Rendered /home/rochkind/bento_search/app/views/bento_search/_search_error.html.erb (5.0ms) +Completed 200 OK in 7ms (Views: 6.3ms | ActiveRecord: 0.0ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +Processing by BentoSearch::SearchController#search as HTML + Parameters: {"query"=>"my search", "engine_id"=>"not_existing"} +Completed 404 Not Found in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +Processing by BentoSearch::SearchController#search as HTML + Parameters: {"query"=>"my search", "engine_id"=>"not_routable"} +Completed 403 Forbidden in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +Processing by BentoSearch::SearchController#search as HTML + Parameters: {"query"=>"query", "sort"=>"sort", "per_page"=>"15", "page"=>"6", "search_field"=>"title", "not_allowed"=>"not allowed", "engine_id"=>"mock"} + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (5.6ms) +Completed 200 OK in 7ms (Views: 6.3ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +Processing by BentoSearch::SearchController#search as HTML + Parameters: {"query"=>"my search", "engine_id"=>"mock"} + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (3.6ms) +Completed 200 OK in 5ms (Views: 4.2ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +  (0.2ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered _test_custom_item_partial.html.erb (3.5ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_no_results.html.erb (0.4ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.9ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (8.4ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_search_error.html.erb (0.7ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (3.8ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (3.7ms) +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.5ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +EDS timing AUTH: 0.041448s +EDS timing GET: 0.005348:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=n +EDS timing GET: 0.006945:http://eds-api.ebscohost.com/edsapi/rest/search?view=detailed&query=AND%2Ccancer&searchmode=all&highlight=y&resultsperpage=10 +EDS timing GET: 0.007319:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=26cf851d-9a36-435b-a50a-14a5aea15338.KErj2NrKZC8UxoZu2SfB28G2%2Fw9Gjg%2BwmkWnBdUv3As%3D +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +EDS timing AUTH: 0.006034s +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +EDS timing AUTH: 0.004242s +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +EDS timing AUTH: 0.008788s +EDS timing GET: 0.005016:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y +EDS timing GET: 0.004049:http://eds-api.ebscohost.com/edsapi/rest/info +EDS timing GET: 0.006506:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=c6f7d532-8726-47a7-8e75-b3851a615c68.lS%2FLBviHYcah4372jECVTkM%2Fb4%2FDpExjKLExBuNU0eA%3D +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +EDS timing AUTH: 0.012202s +EDS timing GET: 0.002935:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y +EDS timing GET: 0.002769:http://eds-api.ebscohost.com/edsapi/rest/info +EDS auth failed, getting auth again +EDS timing AUTH: 0.002946s +EDS timing GET: 0.007223:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=d389e524-95ea-4518-bd4a-6b0eb7c0af44.kEvPxolRdsxALTno8ChwQHJ3j2LNLih2ApF64T4yK4w%3D +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.4ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_link.html.erb (0.6ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_link.html.erb (0.2ms) +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +BentoSearch::SearchEngine failed results: #<Timeout::Error: I am a horrible engine> + /home/rochkind/bento_search/test/unit/search_engine_base_test.rb:206:in `search_implementation' + /home/rochkind/bento_search/app/models/bento_search/search_engine.rb:215:in `search' + /home/rochkind/bento_search/test/unit/search_engine_base_test.rb:212:in `test_rescues_exceptions' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:949:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit/testcase.rb:17:in `run' + activesupport (3.2.9) lib/active_support/testing/setup_and_teardown.rb:36:in `block in run' + activesupport (3.2.9) lib/active_support/callbacks.rb:425:in `_run__1011578922187487204__setup__3524658082364380238__callbacks' + activesupport (3.2.9) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.9) lib/active_support/callbacks.rb:385:in `_run_setup_callbacks' + activesupport (3.2.9) lib/active_support/callbacks.rb:81:in `run_callbacks' + activesupport (3.2.9) lib/active_support/testing/setup_and_teardown.rb:35:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:787:in `block in _run_suite' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:780:in `map' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:780:in `_run_suite' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:565:in `block in _run_suites' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:563:in `each' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:563:in `_run_suites' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:746:in `_run_anything' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:909:in `run_tests' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:896:in `block in _run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:895:in `each' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:895:in `_run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:884:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:21:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:326:in `block (2 levels) in autorun' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:27:in `run_once' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:325:in `block in autorun' +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +BentoSearch::SearchEngine failed results: #<Timeout::Error: MockEngine forced raise> + /home/rochkind/bento_search/app/search_engines/bento_search/mock_engine.rb:29:in `search_implementation' + /home/rochkind/bento_search/app/models/bento_search/search_engine.rb:215:in `search' + /home/rochkind/bento_search/app/search_engines/bento_search/mock_engine.rb:47:in `search' + /home/rochkind/bento_search/test/unit/search_engine_test.rb:131:in `block in <class:SearchEngineTest>' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:949:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit/testcase.rb:17:in `run' + activesupport (3.2.9) lib/active_support/testing/setup_and_teardown.rb:36:in `block in run' + activesupport (3.2.9) lib/active_support/callbacks.rb:425:in `_run__2727147452979036049__setup__3524658082364380238__callbacks' + activesupport (3.2.9) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.9) lib/active_support/callbacks.rb:385:in `_run_setup_callbacks' + activesupport (3.2.9) lib/active_support/callbacks.rb:81:in `run_callbacks' + activesupport (3.2.9) lib/active_support/testing/setup_and_teardown.rb:35:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:787:in `block in _run_suite' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:780:in `map' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:780:in `_run_suite' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:565:in `block in _run_suites' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:563:in `each' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:563:in `_run_suites' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:746:in `_run_anything' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:909:in `run_tests' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:896:in `block in _run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:895:in `each' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:895:in `_run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:884:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:21:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:326:in `block (2 levels) in autorun' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:27:in `run_once' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:325:in `block in autorun' +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.6ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (2.4ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.4ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.8ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.5ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.5ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_link.html.erb (0.5ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (4.1ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.5ms) +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +Connecting to database specified by database.yml +  (0.5ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (8.1ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/atom_results.atom.builder (65.9ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (6.6ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (6.7ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (65.3ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (6.6ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (0.0ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (6.7ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (6.7ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (6.6ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (3.7ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +Processing by BentoSearch::SearchControllerTest::CustomSearchController#search as HTML + Parameters: {"engine_id"=>"mock", "query"=>"my search"} +Completed 403 Forbidden in 4ms (Views: 3.8ms | ActiveRecord: 0.0ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +Processing by BentoSearch::SearchController#search as HTML + Parameters: {"query"=>"my search", "engine_id"=>"with_layout_config"} + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.9ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (6.3ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_wrap_with_count.html.erb (7.6ms) +Completed 200 OK in 63ms (Views: 62.3ms | ActiveRecord: 0.0ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +Processing by BentoSearch::SearchController#search as HTML + Parameters: {"query"=>"my search", "engine_id"=>"failed_response"} + Rendered /home/rochkind/bento_search/app/views/bento_search/_search_error.html.erb (4.9ms) +Completed 200 OK in 7ms (Views: 6.2ms | ActiveRecord: 0.0ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +Processing by BentoSearch::SearchController#search as HTML + Parameters: {"query"=>"my search", "engine_id"=>"not_existing"} +Completed 404 Not Found in 1ms (Views: 0.3ms | ActiveRecord: 0.0ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +Processing by BentoSearch::SearchController#search as HTML + Parameters: {"query"=>"my search", "engine_id"=>"not_routable"} +Completed 403 Forbidden in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +Processing by BentoSearch::SearchController#search as HTML + Parameters: {"query"=>"query", "sort"=>"sort", "per_page"=>"15", "page"=>"6", "search_field"=>"title", "not_allowed"=>"not allowed", "engine_id"=>"mock"} + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (5.7ms) +Completed 200 OK in 8ms (Views: 6.4ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +Processing by BentoSearch::SearchController#search as HTML + Parameters: {"query"=>"my search", "engine_id"=>"mock"} + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (3.7ms) +Completed 200 OK in 5ms (Views: 4.3ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +  (0.2ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered _test_custom_item_partial.html.erb (3.5ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_no_results.html.erb (0.5ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.9ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (8.6ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_search_error.html.erb (0.7ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (3.8ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (3.8ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.8ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +EDS timing AUTH: 0.091526s +EDS timing GET: 0.005559:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=n +EDS timing GET: 0.00691:http://eds-api.ebscohost.com/edsapi/rest/search?view=detailed&query=AND%2Ccancer&searchmode=all&highlight=y&resultsperpage=10 +EDS timing GET: 0.007146:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=26cf851d-9a36-435b-a50a-14a5aea15338.KErj2NrKZC8UxoZu2SfB28G2%2Fw9Gjg%2BwmkWnBdUv3As%3D +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +EDS timing AUTH: 0.005988s +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +EDS timing AUTH: 0.00428s +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +EDS timing AUTH: 0.061043s +EDS timing GET: 0.00543:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y +EDS timing GET: 0.004032:http://eds-api.ebscohost.com/edsapi/rest/info +EDS timing GET: 0.006532:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=c6f7d532-8726-47a7-8e75-b3851a615c68.lS%2FLBviHYcah4372jECVTkM%2Fb4%2FDpExjKLExBuNU0eA%3D +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +EDS timing AUTH: 0.011977s +EDS timing GET: 0.002912:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y +EDS timing GET: 0.002786:http://eds-api.ebscohost.com/edsapi/rest/info +EDS auth failed, getting auth again +EDS timing AUTH: 0.002913s +EDS timing GET: 0.006484:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=d389e524-95ea-4518-bd4a-6b0eb7c0af44.kEvPxolRdsxALTno8ChwQHJ3j2LNLih2ApF64T4yK4w%3D +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_link.html.erb (0.6ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_link.html.erb (0.2ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +BentoSearch::SearchEngine failed results: #<Timeout::Error: I am a horrible engine> + /home/rochkind/bento_search/test/unit/search_engine_base_test.rb:206:in `search_implementation' + /home/rochkind/bento_search/app/models/bento_search/search_engine.rb:215:in `search' + /home/rochkind/bento_search/test/unit/search_engine_base_test.rb:212:in `test_rescues_exceptions' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:949:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit/testcase.rb:17:in `run' + activesupport (3.2.9) lib/active_support/testing/setup_and_teardown.rb:36:in `block in run' + activesupport (3.2.9) lib/active_support/callbacks.rb:425:in `_run__239219124293608124__setup__3043395761351428959__callbacks' + activesupport (3.2.9) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.9) lib/active_support/callbacks.rb:385:in `_run_setup_callbacks' + activesupport (3.2.9) lib/active_support/callbacks.rb:81:in `run_callbacks' + activesupport (3.2.9) lib/active_support/testing/setup_and_teardown.rb:35:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:787:in `block in _run_suite' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:780:in `map' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:780:in `_run_suite' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:565:in `block in _run_suites' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:563:in `each' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:563:in `_run_suites' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:746:in `_run_anything' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:909:in `run_tests' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:896:in `block in _run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:895:in `each' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:895:in `_run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:884:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:21:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:326:in `block (2 levels) in autorun' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:27:in `run_once' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:325:in `block in autorun' +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +BentoSearch::SearchEngine failed results: #<Timeout::Error: MockEngine forced raise> + /home/rochkind/bento_search/app/search_engines/bento_search/mock_engine.rb:29:in `search_implementation' + /home/rochkind/bento_search/app/models/bento_search/search_engine.rb:215:in `search' + /home/rochkind/bento_search/app/search_engines/bento_search/mock_engine.rb:47:in `search' + /home/rochkind/bento_search/test/unit/search_engine_test.rb:131:in `block in <class:SearchEngineTest>' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:949:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit/testcase.rb:17:in `run' + activesupport (3.2.9) lib/active_support/testing/setup_and_teardown.rb:36:in `block in run' + activesupport (3.2.9) lib/active_support/callbacks.rb:425:in `_run__3785728066625319736__setup__3043395761351428959__callbacks' + activesupport (3.2.9) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.9) lib/active_support/callbacks.rb:385:in `_run_setup_callbacks' + activesupport (3.2.9) lib/active_support/callbacks.rb:81:in `run_callbacks' + activesupport (3.2.9) lib/active_support/testing/setup_and_teardown.rb:35:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:787:in `block in _run_suite' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:780:in `map' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:780:in `_run_suite' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:565:in `block in _run_suites' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:563:in `each' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:563:in `_run_suites' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:746:in `_run_anything' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:909:in `run_tests' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:896:in `block in _run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:895:in `each' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:895:in `_run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/minitest/unit.rb:884:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:21:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:326:in `block (2 levels) in autorun' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:27:in `run_once' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p374/lib/ruby/1.9.1/test/unit.rb:325:in `block in autorun' +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.6ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (2.5ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.4ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.8ms) +  (0.0ms) rollback transaction +  (0.1ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.5ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.5ms) +  (0.1ms) rollback transaction +  (0.1ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_link.html.erb (0.5ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (4.2ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.5ms) +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +Connecting to database specified by database.yml +  (0.5ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (7.3ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/atom_results.atom.builder (68.3ms) +  (0.1ms) rollback transaction +  (0.1ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (6.4ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (6.3ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (68.3ms) +  (0.1ms) rollback transaction +  (0.1ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (6.5ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (0.0ms) +  (0.0ms) rollback transaction +  (0.1ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (6.3ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (6.5ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (6.3ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_atom_item.atom.builder (3.5ms) +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Processing by BentoSearch::SearchControllerTest::CustomSearchController#search as HTML + Parameters: {"engine_id"=>"mock", "query"=>"my search"} +Completed 403 Forbidden in 4ms (Views: 3.4ms | ActiveRecord: 0.0ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +Processing by BentoSearch::SearchController#search as HTML + Parameters: {"query"=>"my search", "engine_id"=>"with_layout_config"} + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.9ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (6.1ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_wrap_with_count.html.erb (7.5ms) +Completed 200 OK in 67ms (Views: 66.2ms | ActiveRecord: 0.0ms) +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Processing by BentoSearch::SearchController#search as HTML + Parameters: {"query"=>"my search", "engine_id"=>"failed_response"} + Rendered /home/rochkind/bento_search/app/views/bento_search/_search_error.html.erb (4.8ms) +Completed 200 OK in 7ms (Views: 6.0ms | ActiveRecord: 0.0ms) +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Processing by BentoSearch::SearchController#search as HTML + Parameters: {"query"=>"my search", "engine_id"=>"not_existing"} +Completed 404 Not Found in 1ms (Views: 0.3ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +Processing by BentoSearch::SearchController#search as HTML + Parameters: {"query"=>"my search", "engine_id"=>"not_routable"} +Completed 403 Forbidden in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +Processing by BentoSearch::SearchController#search as HTML + Parameters: {"query"=>"query", "sort"=>"sort", "per_page"=>"15", "page"=>"6", "search_field"=>"title", "not_allowed"=>"not allowed", "engine_id"=>"mock"} + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (5.7ms) +Completed 200 OK in 7ms (Views: 6.4ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +Processing by BentoSearch::SearchController#search as HTML + Parameters: {"query"=>"my search", "engine_id"=>"mock"} + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (3.8ms) +Completed 200 OK in 5ms (Views: 4.4ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered _test_custom_item_partial.html.erb (57.1ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_no_results.html.erb (0.5ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.9ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (8.9ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_search_error.html.erb (0.7ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (3.7ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (3.7ms) +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +EDS timing AUTH: 0.043519s +EDS timing GET: 0.00508:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=n +EDS timing GET: 0.006623:http://eds-api.ebscohost.com/edsapi/rest/search?view=detailed&query=AND%2Ccancer&searchmode=all&highlight=y&resultsperpage=10 +EDS timing GET: 0.006983:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=26cf851d-9a36-435b-a50a-14a5aea15338.KErj2NrKZC8UxoZu2SfB28G2%2Fw9Gjg%2BwmkWnBdUv3As%3D +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +EDS timing AUTH: 0.010038s +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +EDS timing AUTH: 0.008117s +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +EDS timing AUTH: 0.012448s +EDS timing GET: 0.062488:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y +EDS timing GET: 0.003998:http://eds-api.ebscohost.com/edsapi/rest/info +EDS timing GET: 0.006099:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=c6f7d532-8726-47a7-8e75-b3851a615c68.lS%2FLBviHYcah4372jECVTkM%2Fb4%2FDpExjKLExBuNU0eA%3D +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +EDS timing AUTH: 0.016012s +EDS timing GET: 0.002995:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y +EDS timing GET: 0.00289:http://eds-api.ebscohost.com/edsapi/rest/info +EDS auth failed, getting auth again +EDS timing AUTH: 0.003022s +EDS timing GET: 0.006079:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=d389e524-95ea-4518-bd4a-6b0eb7c0af44.kEvPxolRdsxALTno8ChwQHJ3j2LNLih2ApF64T4yK4w%3D +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_link.html.erb (0.5ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_link.html.erb (0.2ms) +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +BentoSearch::SearchEngine failed results: #<Timeout::Error: I am a horrible engine> + /home/rochkind/bento_search/test/unit/search_engine_base_test.rb:206:in `search_implementation' + /home/rochkind/bento_search/app/models/bento_search/search_engine.rb:215:in `search' + /home/rochkind/bento_search/test/unit/search_engine_base_test.rb:212:in `test_rescues_exceptions' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:949:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/test/unit/testcase.rb:17:in `run' + activesupport (3.2.9) lib/active_support/testing/setup_and_teardown.rb:36:in `block in run' + activesupport (3.2.9) lib/active_support/callbacks.rb:425:in `_run__2231739404768155636__setup__498021496091099526__callbacks' + activesupport (3.2.9) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.9) lib/active_support/callbacks.rb:385:in `_run_setup_callbacks' + activesupport (3.2.9) lib/active_support/callbacks.rb:81:in `run_callbacks' + activesupport (3.2.9) lib/active_support/testing/setup_and_teardown.rb:35:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:787:in `block in _run_suite' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:780:in `map' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:780:in `_run_suite' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/test/unit.rb:565:in `block in _run_suites' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/test/unit.rb:563:in `each' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/test/unit.rb:563:in `_run_suites' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:746:in `_run_anything' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:909:in `run_tests' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:896:in `block in _run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:895:in `each' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:895:in `_run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:884:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/test/unit.rb:21:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/test/unit.rb:326:in `block (2 levels) in autorun' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/test/unit.rb:27:in `run_once' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/test/unit.rb:325:in `block in autorun' +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +BentoSearch::SearchEngine failed results: #<Timeout::Error: MockEngine forced raise> + /home/rochkind/bento_search/app/search_engines/bento_search/mock_engine.rb:29:in `search_implementation' + /home/rochkind/bento_search/app/models/bento_search/search_engine.rb:215:in `search' + /home/rochkind/bento_search/app/search_engines/bento_search/mock_engine.rb:47:in `search' + /home/rochkind/bento_search/test/unit/search_engine_test.rb:131:in `block in <class:SearchEngineTest>' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:949:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/test/unit/testcase.rb:17:in `run' + activesupport (3.2.9) lib/active_support/testing/setup_and_teardown.rb:36:in `block in run' + activesupport (3.2.9) lib/active_support/callbacks.rb:425:in `_run__2925715017413207378__setup__498021496091099526__callbacks' + activesupport (3.2.9) lib/active_support/callbacks.rb:405:in `__run_callback' + activesupport (3.2.9) lib/active_support/callbacks.rb:385:in `_run_setup_callbacks' + activesupport (3.2.9) lib/active_support/callbacks.rb:81:in `run_callbacks' + activesupport (3.2.9) lib/active_support/testing/setup_and_teardown.rb:35:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:787:in `block in _run_suite' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:780:in `map' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:780:in `_run_suite' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/test/unit.rb:565:in `block in _run_suites' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/test/unit.rb:563:in `each' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/test/unit.rb:563:in `_run_suites' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:746:in `_run_anything' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:909:in `run_tests' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:896:in `block in _run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:895:in `each' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:895:in `_run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:884:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/test/unit.rb:21:in `run' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/test/unit.rb:326:in `block (2 levels) in autorun' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/test/unit.rb:27:in `run_once' + /home/rochkind/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/test/unit.rb:325:in `block in autorun' +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.6ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (2.6ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.4ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.8ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.5ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.5ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_link.html.erb (0.5ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (3.9ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction + Rendered /home/rochkind/bento_search/app/views/bento_search/_item_title.html.erb (0.2ms) + Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.5ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction