Connecting to database specified by database.yml  (0.6ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.4ms) begin transaction EDS timing AUTH: 0.070969s EDS timing GET: 0.005073:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=n EDS timing GET: 0.006575:http://eds-api.ebscohost.com/edsapi/rest/search?view=detailed&query=AND%2Ccancer&searchmode=all&highlight=y&resultsperpage=10 EDS timing GET: 0.006726:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=26cf851d-9a36-435b-a50a-14a5aea15338.KErj2NrKZC8UxoZu2SfB28G2%2Fw9Gjg%2BwmkWnBdUv3As%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.009707s  (0.0ms) rollback transaction  (0.0ms) begin transaction EDS timing AUTH: 0.007806s  (0.0ms) rollback transaction  (0.0ms) begin transaction EDS timing AUTH: 0.012039s EDS timing GET: 0.0048:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.003999:http://eds-api.ebscohost.com/edsapi/rest/info EDS timing GET: 0.006441:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=c6f7d532-8726-47a7-8e75-b3851a615c68.lS%2FLBviHYcah4372jECVTkM%2Fb4%2FDpExjKLExBuNU0eA%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.047924s EDS timing GET: 0.003037:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.002784:http://eds-api.ebscohost.com/edsapi/rest/info EDS auth failed, getting auth again EDS timing AUTH: 0.002967s EDS timing GET: 0.006095:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=d389e524-95ea-4518-bd4a-6b0eb7c0af44.kEvPxolRdsxALTno8ChwQHJ3j2LNLih2ApF64T4yK4w%3D  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.6ms) begin transaction EDS timing AUTH: 0.942925s EDS timing GET: 2.945485:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=n EDS timing GET: 3.844817:http://eds-api.ebscohost.com/edsapi/rest/search?view=detailed&query=AND%2Ccancer&searchmode=all&highlight=y&resultsperpage=10 EDS timing GET: 0.055401:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=d2df8c3b-da33-4d1e-a3ab-e2afc1e41da6.Q7ANUPMiBwCr%2FNJnBQZrMjs4UrxU7dpLQYwSjj0iNEE%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.754436s  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.801104s  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.769531s EDS timing GET: 0.642401:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.044779:http://eds-api.ebscohost.com/edsapi/rest/info EDS timing GET: 0.048195:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=58b3b9c6-92d1-4fa0-ba7b-ae4c23e7e7a3.5tU1TZLDfOTSQgUcBezVdLzYXaL3VNxPjzDRYHfDHNs%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.740568s EDS timing GET: 0.762168:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.029053:http://eds-api.ebscohost.com/edsapi/rest/info EDS auth failed, getting auth again EDS timing AUTH: 0.034124s EDS timing GET: 0.047491:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=0d63a4d8-e7fa-4471-91ef-78a8103e7ad0.oXqfM9hzTYfdOtBRBfTDa5Sn997deM5tz0HoFtbmRb0%3D  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.4ms) begin transaction EDS timing AUTH: 0.816647s EDS timing GET: 0.734006:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=n EDS timing GET: 4.183034:http://eds-api.ebscohost.com/edsapi/rest/search?view=detailed&query=AND%2Ccancer&searchmode=all&highlight=y&resultsperpage=10 EDS timing GET: 0.080037:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=c856851e-8416-4806-b27c-6096205f4f52.myEqzXNyOK9WYYUlQQitYatMbPVDlOhLAG1qlMsHtmY%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction Connecting to database specified by database.yml  (0.4ms) begin transaction EDS timing AUTH: 0.78661s EDS timing GET: 0.683898:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=n EDS timing GET: 4.399789:http://eds-api.ebscohost.com/edsapi/rest/search?view=detailed&query=AND%2Ccancer&searchmode=all&highlight=y&resultsperpage=10 EDS timing GET: 0.053621:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=90049bcf-67f4-4aba-af04-f4c0fb95eaa5.P6odna3tDqyu7fIySaFqy7Or01Gdg0Q3BoERf9lEUVg%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.778231s  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.793426s  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.757648s EDS timing GET: 1.020794:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.0458:http://eds-api.ebscohost.com/edsapi/rest/info EDS timing GET: 0.048433:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=78add2c0-afa4-4949-ba82-718571fcc06e.1F0MavT%2FSMH9yMsy6KdX3rHXUPgvIRHBDda2OLkY4AY%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.753987s EDS timing GET: 0.660385:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.029827:http://eds-api.ebscohost.com/edsapi/rest/info EDS auth failed, getting auth again EDS timing AUTH: 0.034799s EDS timing GET: 0.049002:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=a0fe0db5-c65d-43ae-a965-29f51c8983fa.7wTBtxFhBedyDhGIzkK16tDYXfzZiOVrFdvsDcCfY1s%3D  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.5ms) begin transaction Processing by BentoSearch::SearchControllerTest::CustomSearchController#search as HTML Parameters: {"engine_id"=>"mock", "query"=>"my search"} Rendered text template (0.0ms) Completed 403 Forbidden in 37ms (Views: 36.6ms | 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.7ms | 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"=>"my search", "engine_id"=>"mock"} Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (29.6ms) Completed 200 OK in 37ms (Views: 36.6ms | ActiveRecord: 0.0ms)  (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 Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (2.3ms)  (0.0ms) 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/_std_item.html.erb (1.6ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (1.6ms)  (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.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) 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.0ms) rollback transaction  (0.0ms) begin transaction EDS timing AUTH: 0.073708s EDS timing GET: 0.005134:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=n EDS timing GET: 0.006819:http://eds-api.ebscohost.com/edsapi/rest/search?view=detailed&query=AND%2Ccancer&searchmode=all&highlight=y&resultsperpage=10 EDS timing GET: 0.006465:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=26cf851d-9a36-435b-a50a-14a5aea15338.KErj2NrKZC8UxoZu2SfB28G2%2Fw9Gjg%2BwmkWnBdUv3As%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.010242s  (0.0ms) rollback transaction  (0.0ms) begin transaction EDS timing AUTH: 0.007863s  (0.0ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.012104s EDS timing GET: 0.005099: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.006265: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.015177s EDS timing GET: 0.002972:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.002839:http://eds-api.ebscohost.com/edsapi/rest/info EDS auth failed, getting auth again EDS timing AUTH: 0.002995s EDS timing GET: 0.006212: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.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.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.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.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.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.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.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) 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.1ms) 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/_std_item.html.erb (1.3ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.4ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.2ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction 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.7ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.3ms)  (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.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.1ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.6ms) begin transaction Processing by BentoSearch::SearchControllerTest::CustomSearchController#search as HTML Parameters: {"engine_id"=>"mock", "query"=>"my search"} Rendered text template (0.0ms) Completed 403 Forbidden in 45ms (Views: 44.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"=>"not_existing"} Completed 404 Not Found in 2ms (Views: 0.8ms | 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_routable"} Completed 403 Forbidden in 1ms (Views: 0.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"=>"mock"} Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (34.8ms) Completed 200 OK in 45ms (Views: 44.0ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (3.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_search_error.html.erb (0.9ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (2.4ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (2.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.4ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.06438s EDS timing GET: 0.0071:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=n EDS timing GET: 0.009651:http://eds-api.ebscohost.com/edsapi/rest/search?view=detailed&query=AND%2Ccancer&searchmode=all&highlight=y&resultsperpage=10 EDS timing GET: 0.009427:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=26cf851d-9a36-435b-a50a-14a5aea15338.KErj2NrKZC8UxoZu2SfB28G2%2Fw9Gjg%2BwmkWnBdUv3As%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.013872s  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.011271s  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.01771s EDS timing GET: 0.006997:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.005779:http://eds-api.ebscohost.com/edsapi/rest/info EDS timing GET: 0.008878:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=c6f7d532-8726-47a7-8e75-b3851a615c68.lS%2FLBviHYcah4372jECVTkM%2Fb4%2FDpExjKLExBuNU0eA%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.022158s EDS timing GET: 0.043772:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.004092:http://eds-api.ebscohost.com/edsapi/rest/info EDS auth failed, getting auth again EDS timing AUTH: 0.004375s EDS timing GET: 0.00912:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=d389e524-95ea-4518-bd4a-6b0eb7c0af44.kEvPxolRdsxALTno8ChwQHJ3j2LNLih2ApF64T4yK4w%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) 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.1ms) 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.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.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.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) 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.1ms) 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/_std_item.html.erb (1.3ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.4ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.2ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction 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.7ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.2ms)  (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.1ms) 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.0ms) 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.4ms) begin transaction  (0.2ms) rollback transaction Connecting to database specified by database.yml  (0.4ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.6ms) begin transaction  (0.2ms) rollback transaction Connecting to database specified by database.yml  (0.6ms) begin transaction Connecting to database specified by database.yml  (0.4ms) begin transaction  (0.2ms) rollback transaction Connecting to database specified by database.yml  (0.4ms) begin transaction Connecting to database specified by database.yml  (0.4ms) begin transaction  (0.2ms) rollback transaction Connecting to database specified by database.yml  (0.5ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.4ms) begin transaction Connecting to database specified by database.yml  (0.4ms) begin transaction  (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.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.4ms) 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 Connecting to database specified by database.yml  (0.4ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction Connecting to database specified by database.yml  (0.4ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.2ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.4ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.2ms) rollback transaction  (0.1ms) begin transaction  (0.2ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.4ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction Connecting to database specified by database.yml  (0.6ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) 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 Connecting to database specified by database.yml  (0.4ms) begin transaction Processing by BentoSearch::SearchControllerTest::CustomSearchController#search as HTML Parameters: {"engine_id"=>"mock", "query"=>"my search"} Rendered text template (0.0ms) Completed 403 Forbidden in 44ms (Views: 43.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"=>"not_existing"} Completed 404 Not Found in 1ms (Views: 0.6ms | 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"=>"my search", "engine_id"=>"mock"} Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (2.5ms) Completed 200 OK in 42ms (Views: 41.7ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) 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  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (2.4ms)  (0.1ms) rollback transaction  (0.1ms) 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/_std_item.html.erb (1.7ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (1.7ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) rollback transaction  (0.1ms) begin transaction  (0.3ms) 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.1ms) begin transaction EDS timing AUTH: 0.043661s EDS timing GET: 0.004922:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=n EDS timing GET: 0.006663:http://eds-api.ebscohost.com/edsapi/rest/search?view=detailed&query=AND%2Ccancer&searchmode=all&highlight=y&resultsperpage=10 EDS timing GET: 0.006671:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=26cf851d-9a36-435b-a50a-14a5aea15338.KErj2NrKZC8UxoZu2SfB28G2%2Fw9Gjg%2BwmkWnBdUv3As%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.009951s  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.008161s  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.012415s EDS timing GET: 0.004849:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.004031:http://eds-api.ebscohost.com/edsapi/rest/info EDS timing GET: 0.006333:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=c6f7d532-8726-47a7-8e75-b3851a615c68.lS%2FLBviHYcah4372jECVTkM%2Fb4%2FDpExjKLExBuNU0eA%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.054686s EDS timing GET: 0.003129:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.002867:http://eds-api.ebscohost.com/edsapi/rest/info EDS auth failed, getting auth again EDS timing AUTH: 0.002965s EDS timing GET: 0.006157:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=d389e524-95ea-4518-bd4a-6b0eb7c0af44.kEvPxolRdsxALTno8ChwQHJ3j2LNLih2ApF64T4yK4w%3D  (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.1ms) 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.0ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) 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.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.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.1ms) begin transaction Connecting to database specified by database.yml  (0.6ms) begin transaction Processing by BentoSearch::SearchControllerTest::CustomSearchController#search as HTML Parameters: {"engine_id"=>"mock", "query"=>"my search"} Rendered text template (0.0ms) Completed 403 Forbidden in 61ms (Views: 60.1ms | 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 2ms (Views: 0.9ms | 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_routable"} Completed 403 Forbidden in 1ms (Views: 0.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"=>"mock"} Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (3.4ms) Completed 200 OK in 56ms (Views: 55.3ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (3.5ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_search_error.html.erb (1.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (2.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (2.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.4ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.071979s EDS timing GET: 0.008061:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=n EDS timing GET: 0.010516:http://eds-api.ebscohost.com/edsapi/rest/search?view=detailed&query=AND%2Ccancer&searchmode=all&highlight=y&resultsperpage=10 EDS timing GET: 0.009709:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=26cf851d-9a36-435b-a50a-14a5aea15338.KErj2NrKZC8UxoZu2SfB28G2%2Fw9Gjg%2BwmkWnBdUv3As%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.014261s  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.011495s  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.022385s EDS timing GET: 0.007735:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.006302:http://eds-api.ebscohost.com/edsapi/rest/info EDS timing GET: 0.008894:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=c6f7d532-8726-47a7-8e75-b3851a615c68.lS%2FLBviHYcah4372jECVTkM%2Fb4%2FDpExjKLExBuNU0eA%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.075015s EDS timing GET: 0.004969:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.004604:http://eds-api.ebscohost.com/edsapi/rest/info EDS auth failed, getting auth again EDS timing AUTH: 0.004581s EDS timing GET: 0.009077:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=d389e524-95ea-4518-bd4a-6b0eb7c0af44.kEvPxolRdsxALTno8ChwQHJ3j2LNLih2ApF64T4yK4w%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (1.9ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction 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/_std_item.html.erb (0.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_link.html.erb (0.7ms) Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (5.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.6ms) begin transaction Processing by BentoSearch::SearchControllerTest::CustomSearchController#search as HTML Parameters: {"engine_id"=>"mock", "query"=>"my search"} Rendered text template (0.0ms) Completed 403 Forbidden in 56ms (Views: 55.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"=>"not_existing"} Completed 404 Not Found in 2ms (Views: 0.9ms | 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_routable"} Completed 403 Forbidden in 1ms (Views: 0.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"=>"mock"} Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (3.3ms) Completed 200 OK in 14ms (Views: 13.0ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (3.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_search_error.html.erb (0.9ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (2.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (2.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.2ms) rollback transaction  (0.1ms) begin transaction  (0.5ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.109974s EDS timing GET: 0.007293:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=n EDS timing GET: 0.009908:http://eds-api.ebscohost.com/edsapi/rest/search?view=detailed&query=AND%2Ccancer&searchmode=all&highlight=y&resultsperpage=10 EDS timing GET: 0.009392:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=26cf851d-9a36-435b-a50a-14a5aea15338.KErj2NrKZC8UxoZu2SfB28G2%2Fw9Gjg%2BwmkWnBdUv3As%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.057918s  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.011815s  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.017784s EDS timing GET: 0.007053:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.005884:http://eds-api.ebscohost.com/edsapi/rest/info EDS timing GET: 0.009079:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=c6f7d532-8726-47a7-8e75-b3851a615c68.lS%2FLBviHYcah4372jECVTkM%2Fb4%2FDpExjKLExBuNU0eA%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.022592s EDS timing GET: 0.004198:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.004137:http://eds-api.ebscohost.com/edsapi/rest/info EDS auth failed, getting auth again EDS timing AUTH: 0.00425s EDS timing GET: 0.008915:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=d389e524-95ea-4518-bd4a-6b0eb7c0af44.kEvPxolRdsxALTno8ChwQHJ3j2LNLih2ApF64T4yK4w%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (2.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.6ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_link.html.erb (0.7ms) Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (5.5ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.4ms) begin transaction Processing by BentoSearch::SearchControllerTest::CustomSearchController#search as HTML Parameters: {"engine_id"=>"mock", "query"=>"my search"} Rendered text template (0.1ms) Completed 403 Forbidden in 48ms (Views: 47.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"=>"not_existing"} Completed 404 Not Found in 2ms (Views: 0.9ms | 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_routable"} Completed 403 Forbidden in 1ms (Views: 0.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"=>"mock"} Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (3.2ms) Completed 200 OK in 13ms (Views: 12.7ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (3.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_search_error.html.erb (0.9ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (2.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (2.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.2ms) rollback transaction  (0.1ms) begin transaction  (0.4ms) 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.0ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.886671s EDS timing GET: 3.565897:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=n EDS timing GET: 8.262243:http://eds-api.ebscohost.com/edsapi/rest/search?view=detailed&query=AND%2Ccancer&searchmode=all&highlight=y&resultsperpage=10 EDS timing GET: 0.053914:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=ee4f8bdb-6a38-4637-b6dd-63c53f23cbea.xkR6p6hqA4xWmTAs16G5C2YxpKeW0Ub%2B8ReDzUfURY8%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.036653s  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.055097s  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.036847s EDS timing GET: 0.05633:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.046132:http://eds-api.ebscohost.com/edsapi/rest/info EDS timing GET: 0.046947:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=c00efbcf-87dd-4200-ac24-3182907ab329.SRYKjnBKAw248Vgo%2F%2FIeNUiXCjyzsA%2BJjq6yjSs19OE%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.036877s EDS timing GET: 0.05172:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.029549:http://eds-api.ebscohost.com/edsapi/rest/info EDS auth failed, getting auth again EDS timing AUTH: 0.03488s EDS timing GET: 0.046689:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=aaa69d32-2f87-432e-9cb7-f42519d973b7.AzfaP%2Fvm9GQ79nSLxtB7S89VKQP%2FGMOlUfY4KyHZ6hM%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) 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.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.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.1ms) 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.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (2.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.6ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_link.html.erb (0.7ms) Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (5.4ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.4ms) begin transaction Processing by BentoSearch::SearchControllerTest::CustomSearchController#search as HTML Parameters: {"engine_id"=>"mock", "query"=>"my search"} Rendered text template (0.0ms) Completed 403 Forbidden in 48ms (Views: 46.9ms | 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.7ms | 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.1ms) 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/_std_item.html.erb (2.3ms) Completed 200 OK in 10ms (Views: 9.5ms | ActiveRecord: 0.0ms)  (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.1ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (2.3ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_search_error.html.erb (0.6ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (1.6ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (1.6ms)  (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  (0.2ms) rollback transaction  (0.1ms) 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.0ms) rollback transaction  (0.0ms) begin transaction EDS timing AUTH: 0.08755s EDS timing GET: 0.005166:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=n EDS timing GET: 0.006805:http://eds-api.ebscohost.com/edsapi/rest/search?view=detailed&query=AND%2Ccancer&searchmode=all&highlight=y&resultsperpage=10 EDS timing GET: 0.006515:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=ee4f8bdb-6a38-4637-b6dd-63c53f23cbea.xkR6p6hqA4xWmTAs16G5C2YxpKeW0Ub%2B8ReDzUfURY8%3D  (0.1ms) rollback transaction  (0.3ms) begin transaction EDS timing AUTH: 0.059025s  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.00896s  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.012106s EDS timing GET: 0.00496:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.004069:http://eds-api.ebscohost.com/edsapi/rest/info EDS timing GET: 0.006315:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=c00efbcf-87dd-4200-ac24-3182907ab329.SRYKjnBKAw248Vgo%2F%2FIeNUiXCjyzsA%2BJjq6yjSs19OE%3D  (0.1ms) rollback transaction  (0.0ms) begin transaction EDS timing AUTH: 0.013408s EDS timing GET: 0.00293:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.002887:http://eds-api.ebscohost.com/edsapi/rest/info EDS auth failed, getting auth again EDS timing AUTH: 0.00294s EDS timing GET: 0.006263:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=aaa69d32-2f87-432e-9cb7-f42519d973b7.AzfaP%2Fvm9GQ79nSLxtB7S89VKQP%2FGMOlUfY4KyHZ6hM%3D  (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.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (1.9ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction 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/_std_item.html.erb (0.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_link.html.erb (0.7ms) Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (5.5ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.4ms) begin transaction Processing by BentoSearch::SearchControllerTest::CustomSearchController#search as HTML Parameters: {"engine_id"=>"mock", "query"=>"my search"} Rendered text template (0.0ms) Completed 403 Forbidden in 48ms (Views: 47.1ms | 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.7ms | 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"=>"my search", "engine_id"=>"mock"} Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (2.4ms) Completed 200 OK in 10ms (Views: 9.6ms | ActiveRecord: 0.0ms)  (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.1ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) rollback transaction  (0.0ms) begin transaction 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/_search_error.html.erb (0.9ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (1.8ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (1.8ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.2ms) rollback transaction  (0.1ms) begin transaction  (0.6ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.111301s EDS timing GET: 0.007247:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=n EDS timing GET: 0.009797:http://eds-api.ebscohost.com/edsapi/rest/search?view=detailed&query=AND%2Ccancer&searchmode=all&highlight=y&resultsperpage=10 EDS timing GET: 0.009449:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=26cf851d-9a36-435b-a50a-14a5aea15338.KErj2NrKZC8UxoZu2SfB28G2%2Fw9Gjg%2BwmkWnBdUv3As%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.058024s  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.012156s  (0.2ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.018197s EDS timing GET: 0.007098:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.005803:http://eds-api.ebscohost.com/edsapi/rest/info EDS timing GET: 0.009082:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=c6f7d532-8726-47a7-8e75-b3851a615c68.lS%2FLBviHYcah4372jECVTkM%2Fb4%2FDpExjKLExBuNU0eA%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.022607s EDS timing GET: 0.004252:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.00402:http://eds-api.ebscohost.com/edsapi/rest/info EDS auth failed, getting auth again EDS timing AUTH: 0.004232s EDS timing GET: 0.008979:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=d389e524-95ea-4518-bd4a-6b0eb7c0af44.kEvPxolRdsxALTno8ChwQHJ3j2LNLih2ApF64T4yK4w%3D  (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.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.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.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.1ms) 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.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.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.1ms) 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.1ms) begin transaction  (0.1ms) 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.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 Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (1.4ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.4ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.2ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction 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.7ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.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.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.1ms) 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.0ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.5ms) begin transaction Processing by BentoSearch::SearchControllerTest::CustomSearchController#search as HTML Parameters: {"engine_id"=>"mock", "query"=>"my search"} Rendered text template (0.0ms) Completed 403 Forbidden in 44ms (Views: 43.7ms | 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.6ms | 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"=>"my search", "engine_id"=>"mock"} Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (2.4ms) Completed 200 OK in 43ms (Views: 42.5ms | ActiveRecord: 0.0ms)  (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 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/_search_error.html.erb (0.7ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (1.7ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (1.6ms)  (0.1ms) 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.1ms) begin transaction  (0.1ms) 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 EDS timing AUTH: 0.043864s EDS timing GET: 0.00513:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=n EDS timing GET: 0.006678:http://eds-api.ebscohost.com/edsapi/rest/search?view=detailed&query=AND%2Ccancer&searchmode=all&highlight=y&resultsperpage=10 EDS timing GET: 0.007244:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=26cf851d-9a36-435b-a50a-14a5aea15338.KErj2NrKZC8UxoZu2SfB28G2%2Fw9Gjg%2BwmkWnBdUv3As%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.011299s  (0.1ms) rollback transaction  (0.0ms) begin transaction EDS timing AUTH: 0.009049s  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.012877s EDS timing GET: 0.004989:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.004046:http://eds-api.ebscohost.com/edsapi/rest/info EDS timing GET: 0.006187: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.055436s EDS timing GET: 0.003133:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.002843:http://eds-api.ebscohost.com/edsapi/rest/info EDS auth failed, getting auth again EDS timing AUTH: 0.00315s EDS timing GET: 0.0063:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=d389e524-95ea-4518-bd4a-6b0eb7c0af44.kEvPxolRdsxALTno8ChwQHJ3j2LNLih2ApF64T4yK4w%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) 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.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.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.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.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.2ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (2.2ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.6ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_link.html.erb (0.8ms) Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (6.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) 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.1ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.4ms) begin transaction EDS timing AUTH: 0.044499s EDS timing GET: 0.005264:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=n EDS timing GET: 0.006844:http://eds-api.ebscohost.com/edsapi/rest/search?view=detailed&query=AND%2Ccancer&searchmode=all&highlight=y&resultsperpage=10 EDS timing GET: 0.00697:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=26cf851d-9a36-435b-a50a-14a5aea15338.KErj2NrKZC8UxoZu2SfB28G2%2Fw9Gjg%2BwmkWnBdUv3As%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.010266s  (0.1ms) rollback transaction  (0.0ms) begin transaction EDS timing AUTH: 0.007862s  (0.0ms) rollback transaction  (0.0ms) begin transaction EDS timing AUTH: 0.011978s EDS timing GET: 0.004904:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.005731:http://eds-api.ebscohost.com/edsapi/rest/info EDS timing GET: 0.008848:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=c6f7d532-8726-47a7-8e75-b3851a615c68.lS%2FLBviHYcah4372jECVTkM%2Fb4%2FDpExjKLExBuNU0eA%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.022218s EDS timing GET: 0.004177:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.004018:http://eds-api.ebscohost.com/edsapi/rest/info EDS auth failed, getting auth again EDS timing AUTH: 0.004208s EDS timing GET: 0.008946:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=d389e524-95ea-4518-bd4a-6b0eb7c0af44.kEvPxolRdsxALTno8ChwQHJ3j2LNLih2ApF64T4yK4w%3D  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.4ms) begin transaction Processing by BentoSearch::SearchControllerTest::CustomSearchController#search as HTML Parameters: {"engine_id"=>"mock", "query"=>"my search"} Rendered text template (0.0ms) Completed 403 Forbidden in 43ms (Views: 42.1ms | 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.6ms | 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"=>"my search", "engine_id"=>"mock"} Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (2.4ms) Completed 200 OK in 43ms (Views: 42.8ms | ActiveRecord: 0.0ms)  (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 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/_search_error.html.erb (0.7ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (1.7ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (1.6ms)  (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.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) 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.0ms) rollback transaction  (0.0ms) begin transaction EDS timing AUTH: 0.043403s EDS timing GET: 0.004929:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=n EDS timing GET: 0.00662:http://eds-api.ebscohost.com/edsapi/rest/search?view=detailed&query=AND%2Ccancer&searchmode=all&highlight=y&resultsperpage=10 EDS timing GET: 0.006599:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=26cf851d-9a36-435b-a50a-14a5aea15338.KErj2NrKZC8UxoZu2SfB28G2%2Fw9Gjg%2BwmkWnBdUv3As%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.00965s  (0.0ms) rollback transaction  (0.0ms) begin transaction EDS timing AUTH: 0.007815s  (0.0ms) rollback transaction  (0.0ms) begin transaction EDS timing AUTH: 0.012251s EDS timing GET: 0.004834:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.004091:http://eds-api.ebscohost.com/edsapi/rest/info EDS timing GET: 0.00608: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.053019s EDS timing GET: 0.003031:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.002826:http://eds-api.ebscohost.com/edsapi/rest/info EDS auth failed, getting auth again EDS timing AUTH: 0.002944s EDS timing GET: 0.006158: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.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.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  (0.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.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (2.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.6ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_link.html.erb (0.7ms) Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (5.2ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.4ms) begin transaction Processing by BentoSearch::SearchControllerTest::CustomSearchController#search as HTML Parameters: {"engine_id"=>"mock", "query"=>"my search"} Rendered text template (0.0ms) Completed 403 Forbidden in 46ms (Views: 45.1ms | 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.6ms | 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"=>"my search", "engine_id"=>"mock"} Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (2.7ms) Completed 200 OK in 45ms (Views: 44.4ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction  (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.1ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (2.6ms)  (0.1ms) rollback transaction  (0.1ms) 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/_std_item.html.erb (1.8ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (1.9ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) 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.0ms) rollback transaction  (0.0ms) begin transaction EDS timing AUTH: 0.043853s EDS timing GET: 0.004974:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=n EDS timing GET: 0.006681:http://eds-api.ebscohost.com/edsapi/rest/search?view=detailed&query=AND%2Ccancer&searchmode=all&highlight=y&resultsperpage=10 EDS timing GET: 0.006663:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=26cf851d-9a36-435b-a50a-14a5aea15338.KErj2NrKZC8UxoZu2SfB28G2%2Fw9Gjg%2BwmkWnBdUv3As%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.00969s  (0.0ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.00786s  (0.0ms) rollback transaction  (0.0ms) begin transaction EDS timing AUTH: 0.01203s EDS timing GET: 0.004857:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.004047:http://eds-api.ebscohost.com/edsapi/rest/info EDS timing GET: 0.006295:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=c6f7d532-8726-47a7-8e75-b3851a615c68.lS%2FLBviHYcah4372jECVTkM%2Fb4%2FDpExjKLExBuNU0eA%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.053803s EDS timing GET: 0.003052:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.002809:http://eds-api.ebscohost.com/edsapi/rest/info EDS auth failed, getting auth again EDS timing AUTH: 0.002982s EDS timing GET: 0.006214:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=d389e524-95ea-4518-bd4a-6b0eb7c0af44.kEvPxolRdsxALTno8ChwQHJ3j2LNLih2ApF64T4yK4w%3D  (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.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  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) 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.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.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.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (1.9ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.6ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_link.html.erb (0.7ms) Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (5.4ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.3ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.5ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.5ms) begin transaction Connecting to database specified by database.yml  (0.4ms) begin transaction Connecting to database specified by database.yml  (0.4ms) begin transaction Connecting to database specified by database.yml  (0.6ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.4ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.4ms) begin transaction  (0.2ms) rollback transaction Connecting to database specified by database.yml  (0.7ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.4ms) begin transaction Connecting to database specified by database.yml  (0.4ms) begin transaction Connecting to database specified by database.yml  (0.6ms) begin transaction Connecting to database specified by database.yml  (0.4ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.5ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.4ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.5ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.6ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.6ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.6ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.6ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.6ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.5ms) begin transaction Processing by BentoSearch::SearchControllerTest::CustomSearchController#search as HTML Parameters: {"engine_id"=>"mock", "query"=>"my search"} Rendered text template (0.0ms) Completed 403 Forbidden in 54ms (Views: 53.8ms | 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.7ms | 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.1ms) 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/_std_item.html.erb (2.9ms) Completed 200 OK in 56ms (Views: 55.4ms | ActiveRecord: 0.0ms)  (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.1ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) rollback transaction  (0.0ms) begin transaction 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/_search_error.html.erb (0.7ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (1.8ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (1.7ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.064803s EDS timing GET: 0.007394:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=n EDS timing GET: 0.010537:http://eds-api.ebscohost.com/edsapi/rest/search?view=detailed&query=AND%2Ccancer&searchmode=all&highlight=y&resultsperpage=10 EDS timing GET: 0.009236:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=26cf851d-9a36-435b-a50a-14a5aea15338.KErj2NrKZC8UxoZu2SfB28G2%2Fw9Gjg%2BwmkWnBdUv3As%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.058206s  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.011972s  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.018014s EDS timing GET: 0.006907:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.005718:http://eds-api.ebscohost.com/edsapi/rest/info EDS timing GET: 0.008916:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=c6f7d532-8726-47a7-8e75-b3851a615c68.lS%2FLBviHYcah4372jECVTkM%2Fb4%2FDpExjKLExBuNU0eA%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction EDS timing AUTH: 0.022451s EDS timing GET: 0.004318:http://eds-api.ebscohost.com/edsapi/rest/createsession?profile=edsapi&guest=y EDS timing GET: 0.004042:http://eds-api.ebscohost.com/edsapi/rest/info EDS auth failed, getting auth again EDS timing AUTH: 0.004225s EDS timing GET: 0.008844:http://eds-api.ebscohost.com/edsapi/rest/endsession?sessiontoken=d389e524-95ea-4518-bd4a-6b0eb7c0af44.kEvPxolRdsxALTno8ChwQHJ3j2LNLih2ApF64T4yK4w%3D  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) rollback transaction  (0.1ms) 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  (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/_std_item.html.erb (1.4ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.4ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.2ms)  (0.0ms) rollback transaction  (0.1ms) begin transaction 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.7ms)  (0.0ms) rollback transaction  (0.0ms) begin transaction Rendered /home/rochkind/bento_search/app/views/bento_search/_std_item.html.erb (0.2ms)  (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.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.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.0ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.4ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.4ms) begin transaction  (0.1ms) rollback transaction Connecting to database specified by database.yml  (0.5ms) begin transaction  (0.1ms) rollback transaction