Build failed in Hudson: Ruby Client - Ubuntu - Firefox #98
hud...@openqa.org
------------------------------------------
[...truncated 974 lines...]
Supported browsers include:
*firefox
*mock
*firefoxproxy
*chrome
*iexploreproxy
*pifirefox
*iexplore
*firefox3
*safariproxy
*googlechrome
*konqueror
*firefox2
*safari
*piiexplore
*opera
*iehta
*custom
at org.openqa.selenium.server.browserlaunchers.BrowserLauncherFactory.browserNotSupported(BrowserLauncherFactory.java:123)
at org.openqa.selenium.server.browserlaunchers.BrowserLauncherFactory.getBrowserLauncher(BrowserLauncherFactory.java:99)
at org.openqa.selenium.server.BrowserSessionFactory.createNewRemoteSession(BrowserSessionFactory.java:341)
at org.openqa.selenium.server.BrowserSessionFactory.getNewBrowserSession(BrowserSessionFactory.java:119)
at org.openqa.selenium.server.BrowserSessionFactory.getNewBrowserSession(BrowserSessionFactory.java:82)
at org.openqa.selenium.server.SeleniumDriverResourceHandler.getNewBrowserSession(SeleniumDriverResourceHandler.java:658)
at org.openqa.selenium.server.SeleniumDriverResourceHandler.doCommand(SeleniumDriverResourceHandler.java:392)
at org.openqa.selenium.server.SeleniumDriverResourceHandler.handleCommandRequest(SeleniumDriverResourceHandler.java:368)
at org.openqa.selenium.server.SeleniumDriverResourceHandler.handle(SeleniumDriverResourceHandler.java:129)
at org.mortbay.http.HttpContext.handle(HttpContext.java:1530)
at org.mortbay.http.HttpContext.handle(HttpContext.java:1482)
at org.mortbay.http.HttpServer.service(HttpServer.java:909)
at org.mortbay.http.HttpConnection.service(HttpConnection.java:816)
at org.mortbay.http.HttpConnection.handleNext(HttpConnection.java:982)
at org.mortbay.http.HttpConnection.handle(HttpConnection.java:833)
at org.mortbay.http.SocketListener.handleConnection(SocketListener.java:244)
at org.mortbay.util.ThreadedServer.handle(ThreadedServer.java:357)
at org.mortbay.util.ThreadPool$PoolThread.run(ThreadPool.java:534)
21:18:01.048 INFO - Command request: retrieveLastRemoteControlLogs[, ] on session null
21:18:01.048 INFO - Got result:OK,21:17:29.401 INFO [18] org.... on session null
21:18:01.052 INFO - Command request: captureScreenshotToString[, ] on session null
21:18:01.291 INFO - Got result: [base64 encoded PNG] on session null
21:18:01.297 INFO - Command request: getNewBrowserSession[*firefox, http://invalid.host.closeqa.com:4444, ] on session null
21:18:01.298 INFO - creating new remote session
21:18:01.298 INFO - Allocated session d210707454fb448eba82a3252b3b3547 for http://invalid.host.closeqa.com:4444, launching...
21:18:01.304 INFO - Preparing Firefox profile...
21:18:02.301 INFO - Command request: retrieveLastRemoteControlLogs[, ] on session null
21:18:02.302 INFO - Got result:OK,21:18:00.574 INFO [17] org.... on session null
21:18:02.306 INFO - Command request: captureScreenshotToString[, ] on session null
21:18:02.556 INFO - Got result: [base64 encoded PNG] on session null
21:18:14.925 INFO - Launching Firefox...
21:18:39.230 INFO - Got result: OK,d210707454fb448eba82a3252b3b3547 on session d210707454fb448eba82a3252b3b3547
21:19:32.658 INFO - Command request: open[http://localhost:4444/selenium-server/tests/html/test_click_page1.html, ] on session null
21:19:32.659 ERROR - Exception running 'open 'command on session null
java.lang.NullPointerException: sessionId should not be null; has this session been started yet?
at org.openqa.selenium.server.FrameGroupCommandQueueSet.getQueueSet(FrameGroupCommandQueueSet.java:212)
at org.openqa.selenium.server.commands.SeleniumCoreCommand.execute(SeleniumCoreCommand.java:34)
at org.openqa.selenium.server.SeleniumDriverResourceHandler.doCommand(SeleniumDriverResourceHandler.java:516)
at org.openqa.selenium.server.SeleniumDriverResourceHandler.handleCommandRequest(SeleniumDriverResourceHandler.java:368)
at org.openqa.selenium.server.SeleniumDriverResourceHandler.handle(SeleniumDriverResourceHandler.java:129)
at org.mortbay.http.HttpContext.handle(HttpContext.java:1530)
at org.mortbay.http.HttpContext.handle(HttpContext.java:1482)
at org.mortbay.http.HttpServer.service(HttpServer.java:909)
at org.mortbay.http.HttpConnection.service(HttpConnection.java:816)
at org.mortbay.http.HttpConnection.handleNext(HttpConnection.java:982)
at org.mortbay.http.HttpConnection.handle(HttpConnection.java:833)
at org.mortbay.http.SocketListener.handleConnection(SocketListener.java:244)
at org.mortbay.util.ThreadedServer.handle(ThreadedServer.java:357)
at org.mortbay.util.ThreadPool$PoolThread.run(ThreadPool.java:534)
21:19:32.659 INFO - Got result: ERROR Server Exception: sessionId should not be null; has this session been started yet? on session null
21:19:32.662 INFO - Command request: retrieveLastRemoteControlLogs[, ] on session null
21:19:32.663 INFO - Got result:OK,21:18:00.694 INFO [18] org.... on session null
21:19:32.666 INFO - Command request: captureScreenshotToString[, ] on session null
21:19:32.938 INFO - Got result: [base64 encoded PNG] on session null
21:19:32.943 INFO - Command request: getNewBrowserSession[*firefox, http://localhost:4444, ] on session null
21:19:32.943 INFO - creating new remote session
21:19:32.943 INFO - Allocated session 30df592315c747ab9ac1f987a62751ed for http://localhost:4444, launching...
21:19:32.950 INFO - Preparing Firefox profile...
21:19:35.410 INFO - Launching Firefox...
21:19:37.515 INFO - Got result: OK,30df592315c747ab9ac1f987a62751ed on session 30df592315c747ab9ac1f987a62751ed
21:19:37.517 INFO - Command request: setTimeout[60000, ] on session 30df592315c747ab9ac1f987a62751ed
21:19:37.540 INFO - Got result: OK on session 30df592315c747ab9ac1f987a62751ed
21:19:37.543 INFO - Command request: setContext[Starting example 'Another test case that passes', ] on session 30df592315c747ab9ac1f987a62751ed
21:19:37.557 INFO - Got result: OK on session 30df592315c747ab9ac1f987a62751ed
21:19:37.573 INFO - Command request: open[http://localhost:4444/selenium-server/tests/html/test_click_page1.html, ] on session 30df592315c747ab9ac1f987a62751ed
21:19:37.607 INFO - Got result: OK on session 30df592315c747ab9ac1f987a62751ed
21:19:37.609 INFO - Command request: doubleClick[doubleClickable, ] on session 30df592315c747ab9ac1f987a62751ed
21:19:37.646 INFO - Got result: OK on session 30df592315c747ab9ac1f987a62751ed
21:19:37.648 INFO - Command request: getAlert[, ] on session 30df592315c747ab9ac1f987a62751ed
21:19:37.655 INFO - Got result: OK,double clicked! on session 30df592315c747ab9ac1f987a62751ed
21:19:37.657 INFO - Command request: setContext[Ending example 'Another test case that passes', ] on session 30df592315c747ab9ac1f987a62751ed
21:19:37.665 INFO - Got result: OK on session 30df592315c747ab9ac1f987a62751ed
21:19:37.667 INFO - Command request: testComplete[, ] on session 30df592315c747ab9ac1f987a62751ed
21:19:37.667 INFO - Killing Firefox...
21:19:37.691 INFO - Got result: OK on session 30df592315c747ab9ac1f987a62751ed
21:19:37.714 INFO - Command request: setContext[Ending example 'Reports accurate test results', ] on session fab2ed9fafea4b7db4d9acda123b4503
21:19:37.734 INFO - Got result: OK on session fab2ed9fafea4b7db4d9acda123b4503
21:19:37.749 INFO - Command request: testComplete[, ] on session fab2ed9fafea4b7db4d9acda123b4503
21:19:37.750 INFO - Killing Firefox...
21:19:37.762 INFO - Got result: OK on session fab2ed9fafea4b7db4d9acda123b4503
.21:19:37.767 INFO - Command request: getNewBrowserSession[*firefox, http://localhost:3000, ] on session null
21:19:37.768 INFO - creating new remote session
21:19:37.768 INFO - Allocated session 4a49fa54a44a456980bf505b0a04210d for http://localhost:3000, launching...
21:19:37.775 INFO - Preparing Firefox profile...
21:19:40.191 INFO - Launching Firefox...
21:19:42.179 INFO - Got result: OK,4a49fa54a44a456980bf505b0a04210d on session 4a49fa54a44a456980bf505b0a04210d
21:19:42.182 INFO - Command request: setTimeout[60000, ] on session 4a49fa54a44a456980bf505b0a04210d
21:19:42.203 INFO - Got result: OK on session 4a49fa54a44a456980bf505b0a04210d
21:19:42.206 INFO - Command request: setContext[Starting example 'Retrieves remote control logs on local file system when no session is started', ] on session 4a49fa54a44a456980bf505b0a04210d
21:19:42.224 INFO - Got result: OK on session 4a49fa54a44a456980bf505b0a04210d
21:19:42.226 INFO - Command request: setContext[Starting example 'Retrieves remote control logs on local file system when no session is started', ] on session 4a49fa54a44a456980bf505b0a04210d
21:19:42.250 INFO - Got result: OK on session 4a49fa54a44a456980bf505b0a04210d
21:19:42.253 INFO - Command request: testComplete[, ] on session 4a49fa54a44a456980bf505b0a04210d
21:19:42.253 INFO - Killing Firefox...
21:19:42.300 INFO - Got result: OK on session 4a49fa54a44a456980bf505b0a04210d
21:19:42.313 INFO - Command request: retrieveLastRemoteControlLogs[, ] on session null
21:19:42.314 INFO - Got result:OK,21:19:37.609 INFO [22] org.... on session null
.21:19:42.319 INFO - Command request: getNewBrowserSession[*firefox, http://localhost:3000, ] on session null
21:19:42.319 INFO - creating new remote session
21:19:42.319 INFO - Allocated session dff28ca772d9450982948a786ecaa689 for http://localhost:3000, launching...
21:19:42.326 INFO - Preparing Firefox profile...
21:19:44.720 INFO - Launching Firefox...
21:19:46.732 INFO - Got result: OK,dff28ca772d9450982948a786ecaa689 on session dff28ca772d9450982948a786ecaa689
21:19:46.735 INFO - Command request: setTimeout[60000, ] on session dff28ca772d9450982948a786ecaa689
21:19:46.758 INFO - Got result: OK on session dff28ca772d9450982948a786ecaa689
21:19:46.761 INFO - Command request: setContext[Starting example 'Retrieves remote control logs on local file system when there is a session', ] on session dff28ca772d9450982948a786ecaa689
21:19:46.778 INFO - Got result: OK on session dff28ca772d9450982948a786ecaa689
21:19:46.780 INFO - Command request: setContext[Starting example 'Retrieves remote control logs on local file system when there is a session', ] on session dff28ca772d9450982948a786ecaa689
21:19:46.803 INFO - Got result: OK on session dff28ca772d9450982948a786ecaa689
21:19:46.806 INFO - Command request: retrieveLastRemoteControlLogs[, ] on session dff28ca772d9450982948a786ecaa689
21:19:46.806 INFO - Got result:OK,21:19:37.767 INFO [16] org.... on session dff28ca772d9450982948a786ecaa689
21:19:46.809 INFO - Command request: setContext[Ending example 'Retrieves remote control logs on local file system when there is a session', ] on session dff28ca772d9450982948a786ecaa689
21:19:46.833 INFO - Got result: OK on session dff28ca772d9450982948a786ecaa689
21:19:46.837 INFO - Command request: testComplete[, ] on session dff28ca772d9450982948a786ecaa689
21:19:46.837 INFO - Killing Firefox...
21:19:46.865 INFO - Got result: OK on session dff28ca772d9450982948a786ecaa689
.21:19:46.867 INFO - Command request: getNewBrowserSession[*firefox, http://localhost:3000, ] on session null
21:19:46.867 INFO - creating new remote session
21:19:46.868 INFO - Allocated session 9d0875dbf7584f94b8c7d3ade4aae1c2 for http://localhost:3000, launching...
21:19:46.874 INFO - Preparing Firefox profile...
21:20:14.565 INFO - Launching Firefox...
21:20:39.396 INFO - Got result: OK,9d0875dbf7584f94b8c7d3ade4aae1c2 on session 9d0875dbf7584f94b8c7d3ade4aae1c2
21:20:39.399 INFO - Command request: setTimeout[60000, ] on session 9d0875dbf7584f94b8c7d3ade4aae1c2
21:20:39.421 INFO - Got result: OK on session 9d0875dbf7584f94b8c7d3ade4aae1c2
21:20:39.423 INFO - Command request: setContext[Starting example 'Retrieving HTML Snapshot should not bomb when there is no session', ] on session 9d0875dbf7584f94b8c7d3ade4aae1c2
21:20:39.440 INFO - Got result: OK on session 9d0875dbf7584f94b8c7d3ade4aae1c2
21:20:39.443 INFO - Command request: setContext[Starting example 'Retrieving HTML Snapshot should not bomb when there is no session', ] on session 9d0875dbf7584f94b8c7d3ade4aae1c2
21:20:39.467 INFO - Got result: OK on session 9d0875dbf7584f94b8c7d3ade4aae1c2
21:20:39.469 INFO - Command request: testComplete[, ] on session 9d0875dbf7584f94b8c7d3ade4aae1c2
21:20:39.469 INFO - Killing Firefox...
21:20:39.494 INFO - Got result: OK on session 9d0875dbf7584f94b8c7d3ade4aae1c2
.21:20:39.497 INFO - Command request: getNewBrowserSession[*firefox, http://localhost:3000, ] on session null
21:20:39.497 INFO - creating new remote session
21:20:39.498 INFO - Allocated session 3210af108f654f46bfe827c6a5b4afa9 for http://localhost:3000, launching...
21:20:39.506 INFO - Preparing Firefox profile...
21:20:54.157 INFO - Launching Firefox...
21:20:56.133 INFO - Got result: OK,3210af108f654f46bfe827c6a5b4afa9 on session 3210af108f654f46bfe827c6a5b4afa9
21:20:56.135 INFO - Command request: setTimeout[60000, ] on session 3210af108f654f46bfe827c6a5b4afa9
21:20:56.158 INFO - Got result: OK on session 3210af108f654f46bfe827c6a5b4afa9
21:20:56.162 INFO - Command request: setContext[Starting example 'Retrieves HTML Snapshots on local file system when session is started', ] on session 3210af108f654f46bfe827c6a5b4afa9
21:20:56.179 INFO - Got result: OK on session 3210af108f654f46bfe827c6a5b4afa9
21:20:56.181 INFO - Command request: setContext[Starting example 'Retrieves HTML Snapshots on local file system when session is started', ] on session 3210af108f654f46bfe827c6a5b4afa9
21:20:56.205 INFO - Got result: OK on session 3210af108f654f46bfe827c6a5b4afa9
21:20:56.208 INFO - Command request: getHtmlSource[, ] on session 3210af108f654f46bfe827c6a5b4afa9
21:20:56.232 INFO - Got result: OK,<head>
<meta content="text/html; charset=ISO-8859-1" http-equiv="content-type">
</head><body>
</body> on session 3210af108f654f46bfe827c6a5b4afa9
21:20:56.238 INFO - Command request: setContext[Ending example 'Retrieves HTML Snapshots on local file system when session is started', ] on session 3210af108f654f46bfe827c6a5b4afa9
21:20:56.254 INFO - Got result: OK on session 3210af108f654f46bfe827c6a5b4afa9
21:20:56.256 INFO - Command request: testComplete[, ] on session 3210af108f654f46bfe827c6a5b4afa9
21:20:56.256 INFO - Killing Firefox...
21:20:56.300 INFO - Got result: OK on session 3210af108f654f46bfe827c6a5b4afa9
.21:20:56.302 INFO - Command request: getNewBrowserSession[*firefox, http://localhost:3000, ] on session null
21:20:56.302 INFO - creating new remote session
21:20:56.303 INFO - Allocated session 665da70854404afc82caabc6e9340aa2 for http://localhost:3000, launching...
21:20:56.309 INFO - Preparing Firefox profile...
21:21:19.553 INFO - Launching Firefox...
21:21:39.380 INFO - Got result: OK,665da70854404afc82caabc6e9340aa2 on session 665da70854404afc82caabc6e9340aa2
21:21:39.383 INFO - Command request: setTimeout[60000, ] on session 665da70854404afc82caabc6e9340aa2
21:21:39.405 INFO - Got result: OK on session 665da70854404afc82caabc6e9340aa2
21:21:39.407 INFO - Command request: setContext[Starting example 'Retrieves system screenshot on local file system when session is started', ] on session 665da70854404afc82caabc6e9340aa2
21:21:39.424 INFO - Got result: OK on session 665da70854404afc82caabc6e9340aa2
21:21:39.426 INFO - Command request: setContext[Starting example 'Retrieves system screenshot on local file system when session is started', ] on session 665da70854404afc82caabc6e9340aa2
21:21:39.450 INFO - Got result: OK on session 665da70854404afc82caabc6e9340aa2
21:21:39.454 INFO - Command request: windowMaximize[, ] on session 665da70854404afc82caabc6e9340aa2
21:21:39.483 INFO - Got result: OK on session 665da70854404afc82caabc6e9340aa2
21:21:39.485 INFO - Command request: captureScreenshotToString[, ] on session 665da70854404afc82caabc6e9340aa2
21:21:39.723 INFO - Got result: [base64 encoded PNG] on session 665da70854404afc82caabc6e9340aa2
21:21:39.726 INFO - Command request: setContext[Ending example 'Retrieves system screenshot on local file system when session is started', ] on session 665da70854404afc82caabc6e9340aa2
21:21:39.744 INFO - Got result: OK on session 665da70854404afc82caabc6e9340aa2
21:21:39.758 INFO - Command request: testComplete[, ] on session 665da70854404afc82caabc6e9340aa2
21:21:39.759 INFO - Killing Firefox...
21:21:39.783 INFO - Got result: OK on session 665da70854404afc82caabc6e9340aa2
.21:21:39.785 INFO - Command request: getNewBrowserSession[*firefox, http://localhost:3000, ] on session null
21:21:39.786 INFO - creating new remote session
21:21:39.786 INFO - Allocated session 7be9a9f84c4844a8985188fe324f091f for http://localhost:3000, launching...
21:21:39.792 INFO - Preparing Firefox profile...
21:22:09.130 INFO - Launching Firefox...
21:22:11.197 INFO - Got result: OK,7be9a9f84c4844a8985188fe324f091f on session 7be9a9f84c4844a8985188fe324f091f
21:22:11.200 INFO - Command request: setTimeout[60000, ] on session 7be9a9f84c4844a8985188fe324f091f
21:22:11.222 INFO - Got result: OK on session 7be9a9f84c4844a8985188fe324f091f
21:22:11.224 INFO - Command request: setContext[Starting example 'Retrieves page screenshot on local file system when session is started', ] on session 7be9a9f84c4844a8985188fe324f091f
21:22:11.241 INFO - Got result: OK on session 7be9a9f84c4844a8985188fe324f091f
21:22:11.243 INFO - Command request: setContext[Starting example 'Retrieves page screenshot on local file system when session is started', ] on session 7be9a9f84c4844a8985188fe324f091f
21:22:11.269 INFO - Got result: OK on session 7be9a9f84c4844a8985188fe324f091f
21:22:11.271 INFO - Command request: open[http://localhost:4444/selenium-server/tests/html/test_form_events.html, ] on session 7be9a9f84c4844a8985188fe324f091f
21:22:11.271 WARN - you appear to be changing domains from http://localhost:3000 to http://localhost:4444/selenium-server/tests/html/test_form_events.html
this may lead to a 'Permission denied' from the browser (unless it is running as *iehta or *chrome,
or alternatively the selenium server is running in proxy injection mode)
21:22:11.479 INFO - Got result: OK on session 7be9a9f84c4844a8985188fe324f091f
21:22:11.482 INFO - Command request: captureEntirePageScreenshotToString[, ] on session 7be9a9f84c4844a8985188fe324f091f
21:22:11.586 INFO - Got result: [base64 encoded PNG] on session 7be9a9f84c4844a8985188fe324f091f
21:22:11.592 INFO - Command request: setContext[Ending example 'Retrieves page screenshot on local file system when session is started', ] on session 7be9a9f84c4844a8985188fe324f091f
21:22:11.602 INFO - Got result: OK on session 7be9a9f84c4844a8985188fe324f091f
21:22:11.604 INFO - Command request: testComplete[, ] on session 7be9a9f84c4844a8985188fe324f091f
21:22:11.604 INFO - Killing Firefox...
21:22:11.629 INFO - Got result: OK on session 7be9a9f84c4844a8985188fe324f091f
.
1)
Timeout::Error in 'Element API Element API :: Can detect element presence'
execution expired
http://xserve.openqa.org:8080/job/Ruby%20Client%20-%20Ubuntu%20-%20Firefox/ws/ruby/lib/selenium/client/protocol.rb :90:in `http_post'
http://xserve.openqa.org:8080/job/Ruby%20Client%20-%20Ubuntu%20-%20Firefox/ws/ruby/lib/selenium/client/protocol.rb :12:in `remote_control_command'
http://xserve.openqa.org:8080/job/Ruby%20Client%20-%20Ubuntu%20-%20Firefox/ws/ruby/lib/selenium/client/protocol.rb :11:in `remote_control_command'
http://xserve.openqa.org:8080/job/Ruby%20Client%20-%20Ubuntu%20-%20Firefox/ws/ruby/lib/selenium/client/protocol.rb :19:in `string_command'
http://xserve.openqa.org:8080/job/Ruby%20Client%20-%20Ubuntu%20-%20Firefox/ws/ruby/lib/selenium/client/base.rb :30:in `start_new_browser_session'
http://xserve.openqa.org:8080/job/Ruby%20Client%20-%20Ubuntu%20-%20Firefox/ws/ruby/test/integration/spec_helper.rb :38:in `start_new_browser_session'
http://xserve.openqa.org:8080/job/Ruby%20Client%20-%20Ubuntu%20-%20Firefox/ws/ruby/test/integration/spec_helper.rb :10:
http://xserve.openqa.org:8080/job/Ruby%20Client%20-%20Ubuntu%20-%20Firefox/ws/ruby/lib/selenium/rspec/rspec_extensions.rb :28:in `execute'
http://xserve.openqa.org:8080/job/Ruby%20Client%20-%20Ubuntu%20-%20Firefox/ws/ruby/lib/selenium/rspec/rspec_extensions.rb :26:in `execute'
Finished in 906.737468 seconds
26 examples, 1 failure
21:22:11.647 INFO - Command request: shutDown[, ] on session null
21:22:11.647 INFO - Shutdown command received
21:22:11.648 INFO - initiating shutdown
21:22:11.649 INFO - Got result: null on session null
21:22:12.148 INFO - Shutting down...
21:22:12.149 INFO - Stopping Acceptor ServerSocket[addr=0.0.0.0/0.0.0.0,port=0,localport=4444]
rake aborted!
Command /usr/bin/ruby1.8 -I"/var/lib/gems/1.8/gems/rspec-1.1.12/lib" "/var/lib/gems/1.8/gems/rspec-1.1.12/bin/spec" "test/integration/smoke/google_search_spec.rb" "test/integration/smoke/backward_compatible_api_spec.rb" "test/integration/api/cookie_spec.rb" "test/integration/api/click_spec.rb" "test/integration/api/element_spec.rb" "test/integration/api/retrieve_last_remote_control_logs_spec.rb" "test/integration/api/start_stop_spec.rb" "test/integration/api/screenshot_spec.rb" "test/integration/api/select_window_spec.rb" "test/integration/reporting/selenium_test_report_formatter_spec.rb" "test/integration/reporting/system_capture_spec.rb" --color --require 'lib/selenium/rspec/reporting/selenium_test_report_formatter' --format=Selenium::RSpec::SeleniumTestReportFormatter:./target/integration_tests_report.html --format=progress failed
http://xserve.openqa.org:8080/job/Ruby%20Client%20-%20Ubuntu%20-%20Firefox/ws/ruby/Rakefile :39
(See full trace by running task with --trace)
Stopping Selenium Remote Control running at localhost:4444...
.
Stopped Selenium Remote Control running at localhost:4444