Closed Bug 1494964 Opened 6 years ago Closed 6 years ago

Intermittent linux64 /webdriver/tests/get_element_text/user_prompts.py | test_default[alert-None] - setup error

Categories

(Remote Protocol :: Marionette, defect, P5)

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

Tracking

(firefox-esr60 unaffected, firefox62 unaffected, firefox63 unaffected, firefox64 affected)

Status:
RESOLVED DUPLICATE of bug 1498944
Tracking Flags:
Tracking Status
firefox-esr60 --- unaffected
firefox62 --- unaffected
firefox63 --- unaffected
firefox64 --- affected

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Filed by: ebalazs [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=202197564&repo=try https://queue.taskcluster.net/v1/task/Xeuu2CKiSsaNpdqxYUrfqQ/runs/0/artifacts/public/logs/live_backing.log This started on Early Beta : https://treeherder.mozilla.org/#/jobs?repo=try&resultStatus=testfailed,busted,exception,usercancel,runnable&revision=fd61d77d1a3dc8a13c9a011759ae7fd9a075f639&searchStr=linux64,opt,wd&selectedJob=202197564 Failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=202197564&repo=try&lineNumber=43180 ==================================== ERRORS ==================================== [task 2018-09-28T12:05:32.592Z] 12:05:32 INFO - STDOUT: __________________ ERROR at setup of test_default[alert-None] __________________ [task 2018-09-28T12:05:32.593Z] 12:05:32 INFO - STDOUT: capabilities = {} [task 2018-09-28T12:05:32.594Z] 12:05:32 INFO - STDOUT: configuration = {'capabilities': {'moz:firefoxOptions': {'binary': '/builds/worker/workspace/build/application/firefox/firefox', 'pref...b-platform.test,www.web-platform.test,xn--n8j6ds53lwwkrqhv28a.web-platform.test'}}}, 'host': '127.0.0.1', 'port': 4444} [task 2018-09-28T12:05:32.602Z] 12:05:32 INFO - STDOUT: request = <SubRequest 'session' for <Function 'test_default[alert-None]'>> [task 2018-09-28T12:05:32.602Z] 12:05:32 INFO - STDOUT: @pytest.fixture(scope="function") [task 2018-09-28T12:05:32.602Z] 12:05:32 INFO - STDOUT: def session(capabilities, configuration, request): [task 2018-09-28T12:05:32.602Z] 12:05:32 INFO - STDOUT: """Create and start a session for a test that does not itself test session creation. [task 2018-09-28T12:05:32.602Z] 12:05:32 INFO - STDOUT: [task 2018-09-28T12:05:32.611Z] 12:05:32 INFO - [task 2018-09-28T12:05:32.643Z] 12:05:32 INFO - TEST-PASS | /webdriver/tests/get_element_text/user_prompts.py | test_accept_and_notify[capabilities0-prompt-] [task 2018-09-28T12:05:32.643Z] 12:05:32 INFO - TEST-PASS | /webdriver/tests/get_element_text/user_prompts.py | test_dismiss[capabilities0-alert-None] [task 2018-09-28T12:05:32.644Z] 12:05:32 INFO - TEST-PASS | /webdriver/tests/get_element_text/user_prompts.py | test_dismiss[capabilities0-confirm-False] [task 2018-09-28T12:05:32.644Z] 12:05:32 INFO - TEST-PASS | /webdriver/tests/get_element_text/user_prompts.py | test_dismiss[capabilities0-prompt-None] [task 2018-09-28T12:05:32.645Z] 12:05:32 INFO - TEST-PASS | /webdriver/tests/get_element_text/user_prompts.py | test_dismiss_and_notify[capabilities0-alert-None] [task 2018-09-28T12:05:32.645Z] 12:05:32 INFO - TEST-PASS | /webdriver/tests/get_element_text/user_prompts.py | test_dismiss_and_notify[capabilities0-confirm-False] [task 2018-09-28T12:05:32.645Z] 12:05:32 INFO - TEST-PASS | /webdriver/tests/get_element_text/user_prompts.py | test_dismiss_and_notify[capabilities0-prompt-None] [task 2018-09-28T12:05:32.651Z] 12:05:32 INFO - TEST-PASS | /webdriver/tests/get_element_text/user_prompts.py | test_ignore[capabilities0-alert] [task 2018-09-28T12:05:32.651Z] 12:05:32 INFO - TEST-PASS | /webdriver/tests/get_element_text/user_prompts.py | test_ignore[capabilities0-confirm] [task 2018-09-28T12:05:32.652Z] 12:05:32 INFO - TEST-PASS | /webdriver/tests/get_element_text/user_prompts.py | test_ignore[capabilities0-prompt] [task 2018-09-28T12:05:32.652Z] 12:05:32 INFO - TEST-UNEXPECTED-ERROR | /webdriver/tests/get_element_text/user_prompts.py | test_default[alert-None] - setup error [task 2018-09-28T12:05:32.652Z] 12:05:32 INFO - capabilities = {} [task 2018-09-28T12:05:32.653Z] 12:05:32 INFO - configuration = {'capabilities': {'moz:firefoxOptions': {'binary': '/builds/worker/workspace/build/application/firefox/firefox', 'pref...b-platform.test,www.web-platform.test,xn--n8j6ds53lwwkrqhv28a.web-platform.test'}}}, 'host': '127.0.0.1', 'port': 4444} [task 2018-09-28T12:05:32.653Z] 12:05:32 INFO - request = <SubRequest 'session' for <Function 'test_default[alert-None]'>> [task 2018-09-28T12:05:32.653Z] 12:05:32 INFO - [task 2018-09-28T12:05:32.654Z] 12:05:32 INFO - @pytest.fixture(scope="function") [task 2018-09-28T12:05:32.654Z] 12:05:32 INFO - def session(capabilities, configuration, request): [task 2018-09-28T12:05:32.655Z] 12:05:32 INFO - """Create and start a session for a test that does not itself test session creation. [task 2018-09-28T12:05:32.656Z] 12:05:32 INFO - [task 2018-09-28T12:05:32.656Z] 12:05:32 INFO - By default the session will stay open after each test, but we always try to start a [task 2018-09-28T12:05:32.657Z] 12:05:32 INFO - new one and assume that if that fails there is already a valid session. This makes it [task 2018-09-28T12:05:32.657Z] 12:05:32 INFO - possible to recover from some errors that might leave the session in a bad state, but [task 2018-09-28T12:05:32.658Z] 12:05:32 INFO - does not demand that we start a new session per test.""" [task 2018-09-28T12:05:32.659Z] 12:05:32 INFO - global _current_session [task 2018-09-28T12:05:32.659Z] 12:05:32 INFO - [task 2018-09-28T12:05:32.660Z] 12:05:32 INFO - # Update configuration capabilities with custom ones from the [task 2018-09-28T12:05:32.660Z] 12:05:32 INFO - # capabilities fixture, which can be set by tests [task 2018-09-28T12:05:32.661Z] 12:05:32 INFO - caps = copy.deepcopy(configuration["capabilities"]) [task 2018-09-28T12:05:32.662Z] 12:05:32 INFO - caps.update(capabilities) [task 2018-09-28T12:05:32.662Z] 12:05:32 INFO - caps = {"alwaysMatch": caps} [task 2018-09-28T12:05:32.663Z] 12:05:32 INFO - [task 2018-09-28T12:05:32.664Z] 12:05:32 INFO - # If there is a session with different capabilities active, end it now [task 2018-09-28T12:05:32.664Z] 12:05:32 INFO - if _current_session is not None and ( [task 2018-09-28T12:05:32.665Z] 12:05:32 INFO - caps != _current_session.requested_capabilities): [task 2018-09-28T12:05:32.665Z] 12:05:32 INFO - _current_session.end() [task 2018-09-28T12:05:32.665Z] 12:05:32 INFO - _current_session = None [task 2018-09-28T12:05:32.665Z] 12:05:32 INFO - [task 2018-09-28T12:05:32.666Z] 12:05:32 INFO - if _current_session is None: [task 2018-09-28T12:05:32.666Z] 12:05:32 INFO - _current_session = webdriver.Session( [task 2018-09-28T12:05:32.666Z] 12:05:32 INFO - configuration["host"], [task 2018-09-28T12:05:32.666Z] 12:05:32 INFO - configuration["port"], [task 2018-09-28T12:05:32.667Z] 12:05:32 INFO - capabilities=caps) [task 2018-09-28T12:05:32.667Z] 12:05:32 INFO - try: [task 2018-09-28T12:05:32.667Z] 12:05:32 INFO - > _current_session.start() [task 2018-09-28T12:05:32.667Z] 12:05:32 INFO - [task 2018-09-28T12:05:32.668Z] 12:05:32 INFO - capabilities = {} [task 2018-09-28T12:05:32.669Z] 12:05:32 INFO - caps = {'alwaysMatch': {'moz:firefoxOptions': {'binary': '/builds/worker/workspace/build/application/firefox/firefox', 'prefs...w1.not-web-platform.test,www.not-web-platform.test,www.web-platform.test,xn--n8j6ds53lwwkrqhv28a.web-platform.test'}}}} [task 2018-09-28T12:05:32.669Z] 12:05:32 INFO - configuration = {'capabilities': {'moz:firefoxOptions': {'binary': '/builds/worker/workspace/build/application/firefox/firefox', 'pref...b-platform.test,www.web-platform.test,xn--n8j6ds53lwwkrqhv28a.web-platform.test'}}}, 'host': '127.0.0.1', 'port': 4444} [task 2018-09-28T12:05:32.669Z] 12:05:32 INFO - request = <SubRequest 'session' for <Function 'test_default[alert-None]'>> [task 2018-09-28T12:05:32.669Z] 12:05:32 INFO - [task 2018-09-28T12:05:32.670Z] 12:05:32 INFO - tests/web-platform/tests/webdriver/tests/conftest.py:150: [task 2018-09-28T12:05:32.670Z] 12:05:32 INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ [task 2018-09-28T12:05:32.670Z] 12:05:32 INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:422: in start [task 2018-09-28T12:05:32.670Z] 12:05:32 INFO - value = self.send_command("POST", "session", body=body) [task 2018-09-28T12:05:32.671Z] 12:05:32 INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ [task 2018-09-28T12:05:32.671Z] 12:05:32 INFO - [task 2018-09-28T12:05:32.671Z] 12:05:32 INFO - self = <Session (disconnected)>, method = 'POST', url = 'session' [task 2018-09-28T12:05:32.671Z] 12:05:32 INFO - body = {'capabilities': {'alwaysMatch': {'moz:firefoxOptions': {'binary': '/builds/worker/workspace/build/application/firefox...1.not-web-platform.test,www.not-web-platform.test,www.web-platform.test,xn--n8j6ds53lwwkrqhv28a.web-platform.test'}}}}} [task 2018-09-28T12:05:32.672Z] 12:05:32 INFO - [task 2018-09-28T12:05:32.672Z] 12:05:32 INFO - def send_command(self, method, url, body=None): [task 2018-09-28T12:05:32.673Z] 12:05:32 INFO - """ [task 2018-09-28T12:05:32.673Z] 12:05:32 INFO - Send a command to the remote end and validate its success. [task 2018-09-28T12:05:32.674Z] 12:05:32 INFO - [task 2018-09-28T12:05:32.674Z] 12:05:32 INFO - :param method: HTTP method to use in request. [task 2018-09-28T12:05:32.675Z] 12:05:32 INFO - :param uri: "Command part" of the HTTP request URL, [task 2018-09-28T12:05:32.675Z] 12:05:32 INFO - e.g. `window/rect`. [task 2018-09-28T12:05:32.675Z] 12:05:32 INFO - :param body: Optional body of the HTTP request. [task 2018-09-28T12:05:32.675Z] 12:05:32 INFO - [task 2018-09-28T12:05:32.676Z] 12:05:32 INFO - :return: `None` if the HTTP response body was empty, otherwise [task 2018-09-28T12:05:32.676Z] 12:05:32 INFO - the `value` field returned after parsing the response [task 2018-09-28T12:05:32.676Z] 12:05:32 INFO - body as JSON. [task 2018-09-28T12:05:32.676Z] 12:05:32 INFO - [task 2018-09-28T12:05:32.677Z] 12:05:32 INFO - :raises error.WebDriverException: If the remote end returns [task 2018-09-28T12:05:32.677Z] 12:05:32 INFO - an error. [task 2018-09-28T12:05:32.678Z] 12:05:32 INFO - :raises ValueError: If the response body does not contain a [task 2018-09-28T12:05:32.678Z] 12:05:32 INFO - `value` key. [task 2018-09-28T12:05:32.678Z] 12:05:32 INFO - """ [task 2018-09-28T12:05:32.678Z] 12:05:32 INFO - response = self.transport.send( [task 2018-09-28T12:05:32.679Z] 12:05:32 INFO - method, url, body, [task 2018-09-28T12:05:32.679Z] 12:05:32 INFO - encoder=protocol.Encoder, decoder=protocol.Decoder, [task 2018-09-28T12:05:32.680Z] 12:05:32 INFO - session=self) [task 2018-09-28T12:05:32.680Z] 12:05:32 INFO - [task 2018-09-28T12:05:32.680Z] 12:05:32 INFO - if response.status != 200: [task 2018-09-28T12:05:32.681Z] 12:05:32 INFO - err = error.from_response(response) [task 2018-09-28T12:05:32.681Z] 12:05:32 INFO - [task 2018-09-28T12:05:32.681Z] 12:05:32 INFO - if isinstance(err, error.InvalidSessionIdException): [task 2018-09-28T12:05:32.681Z] 12:05:32 INFO - # The driver could have already been deleted the session. [task 2018-09-28T12:05:32.682Z] 12:05:32 INFO - self.session_id = None [task 2018-09-28T12:05:32.682Z] 12:05:32 INFO - [task 2018-09-28T12:05:32.683Z] 12:05:32 INFO - > raise err [task 2018-09-28T12:05:32.683Z] 12:05:32 INFO - E UnknownErrorException: unknown error (500): connection refused [task 2018-09-28T12:05:32.683Z] 12:05:32 INFO - [task 2018-09-28T12:05:32.684Z] 12:05:32 INFO - body = {'capabilities': {'alwaysMatch': {'moz:firefoxOptions': {'binary': '/builds/worker/workspace/build/application/firefox...1.not-web-platform.test,www.not-web-platform.test,www.web-platform.test,xn--n8j6ds53lwwkrqhv28a.web-platform.test'}}}}} [task 2018-09-28T12:05:32.685Z] 12:05:32 INFO - err = <UnknownErrorException http_status=500> [task 2018-09-28T12:05:32.685Z] 12:05:32 INFO - method = 'POST' [task 2018-09-28T12:05:32.685Z] 12:05:32 INFO - response = <Response status=500 error=<UnknownErrorException http_status=500>> [task 2018-09-28T12:05:32.686Z] 12:05:32 INFO - self = <Session (disconnected)> [task 2018-09-28T12:05:32.686Z] 12:05:32 INFO - url = 'session'
Similar to other failures this is a hang during startup of Firefox. There is no sessionstore-windows-restored notification received, which means it depends on bug 1382162.
tracking-firefox64: ? → ---
Depends on: 1382162
Flags: needinfo?(hskupin)
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → DUPLICATE
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.