Closed Bug 1489711 Opened 7 years ago Closed 7 years ago

Intermittent /webdriver/tests/new_session/create_alwaysMatch.py | test_valid[browserName-None] - AssertionError: unknown error (500): connection refused

Categories

(Remote Protocol :: Marionette, defect, P5)

Product:
Remote Protocol
Component:
Marionette
Version:
Version 3
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Depends on 1 open bug)

Details

(Keywords: intermittent-failure)

Filed by: apavel [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=198149883&repo=mozilla-inbound https://queue.taskcluster.net/v1/task/UZM4dPZ0RTSMl1MRW13s7g/runs/0/artifacts/public/logs/live_backing.log [task 2018-09-07T22:38:22.433Z] 22:38:22 INFO - STDOUT: _________________________ test_valid[browserName-None] _________________________ [task 2018-09-07T22:38:22.433Z] 22:38:22 INFO - STDOUT: new_session = <function new_session at 0x7ff76812c410> [task 2018-09-07T22:38:22.438Z] 22:38:22 INFO - STDOUT: add_browser_capabilities = <function add_browser_capabilities at 0x7ff76812c320> [task 2018-09-07T22:38:22.439Z] 22:38:22 INFO - STDOUT: key = 'browserName', value = None [task 2018-09-07T22:38:22.439Z] 22:38:22 INFO - STDOUT: @pytest.mark.parametrize("key,value", flatten(product(*item) for item in valid_data)) [task 2018-09-07T22:38:22.440Z] 22:38:22 INFO - STDOUT: def test_valid(new_session, add_browser_capabilities, key, value): [task 2018-09-07T22:38:22.440Z] 22:38:22 INFO - STDOUT: response, _ = new_session({"capabilities": { [task 2018-09-07T22:38:22.440Z] 22:38:22 INFO - STDOUT: "alwaysMatch": add_browser_capabilities({key: value})}}) [task 2018-09-07T22:38:22.441Z] 22:38:22 INFO - STDOUT: > assert_success(response) [task 2018-09-07T22:38:22.441Z] 22:38:22 INFO - STDOUT: _ = None [task 2018-09-07T22:38:22.442Z] 22:38:22 INFO - STDOUT: add_browser_capabilities = <function add_browser_capabilities at 0x7ff76812c320> [task 2018-09-07T22:38:22.443Z] 22:38:22 INFO - STDOUT: key = 'browserName' [task 2018-09-07T22:38:22.443Z] 22:38:22 INFO - STDOUT: new_session = <function new_session at 0x7ff76812c410> [task 2018-09-07T22:38:22.444Z] 22:38:22 INFO - STDOUT: response = <Response status=500 error=<UnknownErrorException http_status=500>> [task 2018-09-07T22:38:22.444Z] 22:38:22 INFO - STDOUT: value = None [task 2018-09-07T22:38:22.445Z] 22:38:22 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/new_session/create_alwaysMatch.py [task 2018-09-07T22:38:22.445Z] 22:38:22 INFO - STDOUT: :15: [task 2018-09-07T22:38:22.446Z] 22:38:22 INFO - STDOUT: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ [task 2018-09-07T22:38:22.447Z] 22:38:22 INFO - STDOUT: response = <Response status=500 error=<UnknownErrorException http_status=500>> [task 2018-09-07T22:38:22.447Z] 22:38:22 INFO - STDOUT: value = None [task 2018-09-07T22:38:22.447Z] 22:38:22 INFO - STDOUT: def assert_success(response, value=None): [task 2018-09-07T22:38:22.448Z] 22:38:22 INFO - STDOUT: """ [task 2018-09-07T22:38:22.448Z] 22:38:22 INFO - STDOUT: Verify that the provided webdriver.Response instance described [task 2018-09-07T22:38:22.448Z] 22:38:22 INFO - STDOUT: a valid error response as defined by `dfn-send-an-error` and [task 2018-09-07T22:38:22.449Z] 22:38:22 INFO - STDOUT: the provided error code. [task 2018-09-07T22:38:22.449Z] 22:38:22 INFO - STDOUT: [task 2018-09-07T22:38:22.449Z] 22:38:22 INFO - STDOUT: :param response: ``webdriver.Response`` instance. [task 2018-09-07T22:38:22.450Z] 22:38:22 INFO - STDOUT: :param value: Expected value of the response body, if any. [task 2018-09-07T22:38:22.450Z] 22:38:22 INFO - STDOUT: """ [task 2018-09-07T22:38:22.451Z] 22:38:22 INFO - STDOUT: > assert response.status == 200, str(response.error) [task 2018-09-07T22:38:22.451Z] 22:38:22 INFO - STDOUT: E AssertionError: unknown error (500): connection refused [task 2018-09-07T22:38:22.451Z] 22:38:22 INFO - STDOUT: E [task 2018-09-07T22:38:22.452Z] 22:38:22 INFO - STDOUT: E assert 500 == 200 [task 2018-09-07T22:38:22.452Z] 22:38:22 INFO - STDOUT: E + where 500 = <Response status=500 error=<UnknownErrorException http_status=500>>.status [task 2018-09-07T22:38:22.452Z] 22:38:22 INFO - STDOUT: response = <Response status=500 error=<UnknownErrorException http_status=500>> [task 2018-09-07T22:38:22.453Z] 22:38:22 INFO - STDOUT: value = None [task 2018-09-07T22:38:22.453Z] 22:38:22 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/support/asserts.py [task 2018-09-07T22:38:22.453Z] 22:38:22 INFO - STDOUT: :62: AssertionError [task 2018-09-07T22:38:22.454Z] 22:38:22 INFO - STDOUT: =============================== warnings summary =============================== [task 2018-09-07T22:38:22.454Z] 22:38:22 INFO - STDOUT: <undetermined location> [task 2018-09-07T22:38:22.454Z] 22:38:22 INFO - STDOUT: Module already imported so cannot be rewritten: mozlog [task 2018-09-07T22:38:22.455Z] 22:38:22 INFO - STDOUT: -- Docs: http://doc.pytest.org/en/latest/warnings.html [task 2018-09-07T22:38:22.455Z] 22:38:22 INFO - STDOUT: ============== 1 failed, 23 passed, 1 warnings in 255.17 seconds =============== [task 2018-09-07T22:38:22.475Z] 22:38:22 INFO - [task 2018-09-07T22:38:22.475Z] 22:38:22 INFO - TEST-PASS | /webdriver/tests/new_session/create_alwaysMatch.py | test_valid[acceptInsecureCerts-False] [task 2018-09-07T22:38:22.475Z] 22:38:22 INFO - TEST-PASS | /webdriver/tests/new_session/create_alwaysMatch.py | test_valid[acceptInsecureCerts-None] [task 2018-09-07T22:38:22.476Z] 22:38:22 INFO - TEST-UNEXPECTED-FAIL | /webdriver/tests/new_session/create_alwaysMatch.py | test_valid[browserName-None] - AssertionError: unknown error (500): connection refused [task 2018-09-07T22:38:22.476Z] 22:38:22 INFO - new_session = <function new_session at 0x7ff76812c410> [task 2018-09-07T22:38:22.477Z] 22:38:22 INFO - add_browser_capabilities = <function add_browser_capabilities at 0x7ff76812c320> [task 2018-09-07T22:38:22.477Z] 22:38:22 INFO - key = 'browserName', value = None [task 2018-09-07T22:38:22.478Z] 22:38:22 INFO - [task 2018-09-07T22:38:22.478Z] 22:38:22 INFO - @pytest.mark.parametrize("key,value", flatten(product(*item) for item in valid_data)) [task 2018-09-07T22:38:22.481Z] 22:38:22 INFO - def test_valid(new_session, add_browser_capabilities, key, value): [task 2018-09-07T22:38:22.481Z] 22:38:22 INFO - response, _ = new_session({"capabilities": { [task 2018-09-07T22:38:22.481Z] 22:38:22 INFO - "alwaysMatch": add_browser_capabilities({key: value})}}) [task 2018-09-07T22:38:22.482Z] 22:38:22 INFO - > assert_success(response) [task 2018-09-07T22:38:22.482Z] 22:38:22 INFO - [task 2018-09-07T22:38:22.482Z] 22:38:22 INFO - _ = None [task 2018-09-07T22:38:22.482Z] 22:38:22 INFO - add_browser_capabilities = <function add_browser_capabilities at 0x7ff76812c320> [task 2018-09-07T22:38:22.483Z] 22:38:22 INFO - key = 'browserName' [task 2018-09-07T22:38:22.483Z] 22:38:22 INFO - new_session = <function new_session at 0x7ff76812c410> [task 2018-09-07T22:38:22.483Z] 22:38:22 INFO - response = <Response status=500 error=<UnknownErrorException http_status=500>> [task 2018-09-07T22:38:22.483Z] 22:38:22 INFO - value = None [task 2018-09-07T22:38:22.483Z] 22:38:22 INFO - [task 2018-09-07T22:38:22.484Z] 22:38:22 INFO - tests/web-platform/tests/webdriver/tests/new_session/create_alwaysMatch.py:15: [task 2018-09-07T22:38:22.484Z] 22:38:22 INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ [task 2018-09-07T22:38:22.484Z] 22:38:22 INFO - [task 2018-09-07T22:38:22.484Z] 22:38:22 INFO - response = <Response status=500 error=<UnknownErrorException http_status=500>> [task 2018-09-07T22:38:22.485Z] 22:38:22 INFO - value = None [task 2018-09-07T22:38:22.485Z] 22:38:22 INFO - [task 2018-09-07T22:38:22.486Z] 22:38:22 INFO - def assert_success(response, value=None): [task 2018-09-07T22:38:22.487Z] 22:38:22 INFO - """ [task 2018-09-07T22:38:22.488Z] 22:38:22 INFO - Verify that the provided webdriver.Response instance described [task 2018-09-07T22:38:22.488Z] 22:38:22 INFO - a valid error response as defined by `dfn-send-an-error` and [task 2018-09-07T22:38:22.488Z] 22:38:22 INFO - the provided error code. [task 2018-09-07T22:38:22.488Z] 22:38:22 INFO - [task 2018-09-07T22:38:22.489Z] 22:38:22 INFO - :param response: ``webdriver.Response`` instance. [task 2018-09-07T22:38:22.489Z] 22:38:22 INFO - :param value: Expected value of the response body, if any. [task 2018-09-07T22:38:22.489Z] 22:38:22 INFO - """ [task 2018-09-07T22:38:22.489Z] 22:38:22 INFO - > assert response.status == 200, str(response.error) [task 2018-09-07T22:38:22.489Z] 22:38:22 INFO - E AssertionError: unknown error (500): connection refused [task 2018-09-07T22:38:22.489Z] 22:38:22 INFO - E [task 2018-09-07T22:38:22.489Z] 22:38:22 INFO - E assert 500 == 200 [task 2018-09-07T22:38:22.489Z] 22:38:22 INFO - E + where 500 = <Response status=500 error=<UnknownErrorException http_status=500>>.status [task 2018-09-07T22:38:22.490Z] 22:38:22 INFO - [task 2018-09-07T22:38:22.490Z] 22:38:22 INFO - response = <Response status=500 error=<UnknownErrorException http_status=500>> [task 2018-09-07T22:38:22.490Z] 22:38:22 INFO - value = None [task 2018-09-07T22:38:22.490Z] 22:38:22 INFO - [task 2018-09-07T22:38:22.490Z] 22:38:22 INFO - tests/web-platform/tests/webdriver/tests/support/asserts.py:62: AssertionError [task 2018-09-07T22:38:22.491Z] 22:38:22 INFO - ..................... [task 2018-09-07T22:38:22.491Z] 22:38:22 INFO - TEST-OK | /webdriver/tests/new_session/create_alwaysMatch.py | took 255489ms [task 2018-09-07T22:38:22.593Z] 22:38:22 WARNING - u'runner_teardown': () [task 2018-09-07T22:38:22.594Z] 22:38:22 INFO - Starting runner [task 2018-09-07T22:38:22.621Z] 22:38:22 INFO - PID 20480 | 1536359902615 webdriver::httpapi DEBUG Creating routes [task 2018-09-07T22:38:22.623Z] 22:38:22 INFO - PID 20480 | 1536359902620 geckodriver DEBUG Listening on 127.0.0.1:4444 [task 2018-09-07T22:38:23.122Z] 22:38:23 INFO - WebDriver HTTP server listening at http://127.0.0.1:4444/
Hang in Firefox startup, and geckodriver killed Firefox after 60s: > [task 2018-09-07T22:34:25.724Z] 22:34:25 INFO - PID 16090 | 1536359665722 geckodriver::marionette DEBUG Waiting 60s to connect to browser on 127.0.0.1:2828 > [task 2018-09-07T22:34:27.441Z] 22:34:27 INFO - PID 16090 | 1536359667437 Marionette DEBUG Received observer notification profile-after-change > [task 2018-09-07T22:34:27.732Z] 22:34:27 INFO - PID 16090 | 1536359667727 Marionette DEBUG Received observer notification command-line-startup > [task 2018-09-07T22:34:27.732Z] 22:34:27 INFO - PID 16090 | 1536359667727 Marionette DEBUG Received observer notification nsPref:changed > [task 2018-09-07T22:34:27.733Z] 22:34:27 INFO - PID 16090 | 1536359667727 Marionette DEBUG Init aborted (running=false, enabled=true, finalUIStartup=false) > [task 2018-09-07T22:34:28.539Z] 22:34:28 INFO - PID 16090 | 1536359668535 Marionette DEBUG Received observer notification toplevel-window-ready > [task 2018-09-07T22:35:25.766Z] 22:35:25 INFO - PID 16090 | 1536359725757 mozrunner::runner DEBUG Killing process 16478 > [task 2018-09-07T22:35:25.782Z] 22:35:25 INFO - PID 16090 | 1536359725777 webdriver::server DEBUG <- 500 Internal Server Error {"value":{"error":"unknown error","message":"connection refused","stacktrace":""}}
Depends on: 1414495
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
Moving bug to Remote Protocol::Marionette component per bug 1815831.
Component: geckodriver → Marionette
Product: Testing → Remote Protocol
You need to log in before you can comment on or make changes to this bug.