Filed by: ncsoregi [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=195486029&repo=mozilla-inbound https://queue.taskcluster.net/v1/task/K1DREF-1QlGCJvdaTH19Mg/runs/0/artifacts/public/logs/live_backing.log [task 2018-08-23T11:19:36.069Z] 11:19:36 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/actions/pointer_origin.py::test_element_outside_of_view_port [task 2018-08-23T11:19:36.073Z] 11:19:36 INFO - STDOUT: ERROR [task 2018-08-23T11:19:36.073Z] 11:19:36 INFO - STDOUT: ==================================== ERRORS ==================================== [task 2018-08-23T11:19:36.075Z] 11:19:36 INFO - STDOUT: ____________ ERROR at teardown of test_element_outside_of_view_port ____________ [task 2018-08-23T11:19:36.076Z] 11:19:36 INFO - STDOUT: self = <webdriver.client.Actions object at 0x7f131647db90>, args = () [task 2018-08-23T11:19:36.077Z] 11:19:36 INFO - STDOUT: kwargs = {}, session = <Session d9a8cce3-8e85-4eab-b9e0-84caabb4d2ad> [task 2018-08-23T11:19:36.078Z] 11:19:36 INFO - STDOUT: def inner(self, *args, **kwargs): [task 2018-08-23T11:19:36.079Z] 11:19:36 INFO - STDOUT: if hasattr(self, "session"): [task 2018-08-23T11:19:36.080Z] 11:19:36 INFO - STDOUT: session = self.session [task 2018-08-23T11:19:36.083Z] 11:19:36 INFO - STDOUT: else: [task 2018-08-23T11:19:36.083Z] 11:19:36 INFO - STDOUT: session = self [task 2018-08-23T11:19:36.085Z] 11:19:36 INFO - STDOUT: [task 2018-08-23T11:19:36.087Z] 11:19:36 INFO - STDOUT: if session.session_id is None: [task 2018-08-23T11:19:36.087Z] 11:19:36 INFO - STDOUT: session.start() [task 2018-08-23T11:19:36.087Z] 11:19:36 INFO - STDOUT: [task 2018-08-23T11:19:36.096Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.097Z] 11:19:36 INFO - TEST-PASS | /webdriver/tests/actions/pointer_origin.py | test_viewport_inside [task 2018-08-23T11:19:36.097Z] 11:19:36 INFO - TEST-PASS | /webdriver/tests/actions/pointer_origin.py | test_viewport_outside [task 2018-08-23T11:19:36.097Z] 11:19:36 INFO - TEST-PASS | /webdriver/tests/actions/pointer_origin.py | test_pointer_inside [task 2018-08-23T11:19:36.098Z] 11:19:36 INFO - TEST-PASS | /webdriver/tests/actions/pointer_origin.py | test_pointer_outside [task 2018-08-23T11:19:36.098Z] 11:19:36 INFO - TEST-PASS | /webdriver/tests/actions/pointer_origin.py | test_element_center_point [task 2018-08-23T11:19:36.098Z] 11:19:36 INFO - TEST-PASS | /webdriver/tests/actions/pointer_origin.py | test_element_center_point_with_offset [task 2018-08-23T11:19:36.098Z] 11:19:36 INFO - TEST-PASS | /webdriver/tests/actions/pointer_origin.py | test_element_in_view_center_point_partly_visible [task 2018-08-23T11:19:36.099Z] 11:19:36 INFO - TEST-PASS | /webdriver/tests/actions/pointer_origin.py | test_element_larger_than_viewport [task 2018-08-23T11:19:36.099Z] 11:19:36 INFO - TEST-UNEXPECTED-FAIL | /webdriver/tests/actions/pointer_origin.py | test_element_outside_of_view_port - UnknownErrorException: unknown error (500): Failed to convert data to an object [task 2018-08-23T11:19:36.099Z] 11:19:36 INFO - session = <Session a961cc02-2965-454a-8124-f660bb5a0268> [task 2018-08-23T11:19:36.099Z] 11:19:36 INFO - mouse_chain = <webdriver.client.ActionSequence object at 0x7f1316767050> [task 2018-08-23T11:19:36.100Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.100Z] 11:19:36 INFO - def test_element_outside_of_view_port(session, mouse_chain): [task 2018-08-23T11:19:36.100Z] 11:19:36 INFO - session.url = origin_doc("""width: 100px; height: 50px; background: green; [task 2018-08-23T11:19:36.100Z] 11:19:36 INFO - position: relative; left: -200px; top: -100px;""") [task 2018-08-23T11:19:36.100Z] 11:19:36 INFO - > elem = session.find.css("#inner", all=False) [task 2018-08-23T11:19:36.101Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.101Z] 11:19:36 INFO - mouse_chain = <webdriver.client.ActionSequence object at 0x7f1316767050> [task 2018-08-23T11:19:36.101Z] 11:19:36 INFO - session = <Session a961cc02-2965-454a-8124-f660bb5a0268> [task 2018-08-23T11:19:36.102Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.102Z] 11:19:36 INFO - tests/web-platform/tests/webdriver/tests/actions/pointer_origin.py:124: [task 2018-08-23T11:19:36.103Z] 11:19:36 INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ [task 2018-08-23T11:19:36.103Z] 11:19:36 INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:20: in inner [task 2018-08-23T11:19:36.103Z] 11:19:36 INFO - return func(self, *args, **kwargs) [task 2018-08-23T11:19:36.104Z] 11:19:36 INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:312: in css [task 2018-08-23T11:19:36.104Z] 11:19:36 INFO - return self._find_element("css selector", selector, all) [task 2018-08-23T11:19:36.104Z] 11:19:36 INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:318: in _find_element [task 2018-08-23T11:19:36.104Z] 11:19:36 INFO - return self.session.send_session_command("POST", route, body) [task 2018-08-23T11:19:36.105Z] 11:19:36 INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:509: in send_session_command [task 2018-08-23T11:19:36.106Z] 11:19:36 INFO - return self.send_command(method, url, body) [task 2018-08-23T11:19:36.106Z] 11:19:36 INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ [task 2018-08-23T11:19:36.107Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.108Z] 11:19:36 INFO - self = <Session a961cc02-2965-454a-8124-f660bb5a0268>, method = 'POST' [task 2018-08-23T11:19:36.109Z] 11:19:36 INFO - url = 'session/a961cc02-2965-454a-8124-f660bb5a0268/element' [task 2018-08-23T11:19:36.109Z] 11:19:36 INFO - body = {'using': 'css selector', 'value': '#inner'} [task 2018-08-23T11:19:36.110Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.110Z] 11:19:36 INFO - def send_command(self, method, url, body=None): [task 2018-08-23T11:19:36.110Z] 11:19:36 INFO - """ [task 2018-08-23T11:19:36.111Z] 11:19:36 INFO - Send a command to the remote end and validate its success. [task 2018-08-23T11:19:36.111Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.111Z] 11:19:36 INFO - :param method: HTTP method to use in request. [task 2018-08-23T11:19:36.112Z] 11:19:36 INFO - :param uri: "Command part" of the HTTP request URL, [task 2018-08-23T11:19:36.112Z] 11:19:36 INFO - e.g. `window/rect`. [task 2018-08-23T11:19:36.112Z] 11:19:36 INFO - :param body: Optional body of the HTTP request. [task 2018-08-23T11:19:36.113Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.113Z] 11:19:36 INFO - :return: `None` if the HTTP response body was empty, otherwise [task 2018-08-23T11:19:36.114Z] 11:19:36 INFO - the `value` field returned after parsing the response [task 2018-08-23T11:19:36.114Z] 11:19:36 INFO - body as JSON. [task 2018-08-23T11:19:36.114Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.114Z] 11:19:36 INFO - :raises error.WebDriverException: If the remote end returns [task 2018-08-23T11:19:36.115Z] 11:19:36 INFO - an error. [task 2018-08-23T11:19:36.115Z] 11:19:36 INFO - :raises ValueError: If the response body does not contain a [task 2018-08-23T11:19:36.115Z] 11:19:36 INFO - `value` key. [task 2018-08-23T11:19:36.115Z] 11:19:36 INFO - """ [task 2018-08-23T11:19:36.116Z] 11:19:36 INFO - response = self.transport.send( [task 2018-08-23T11:19:36.116Z] 11:19:36 INFO - method, url, body, [task 2018-08-23T11:19:36.116Z] 11:19:36 INFO - encoder=protocol.Encoder, decoder=protocol.Decoder, [task 2018-08-23T11:19:36.117Z] 11:19:36 INFO - session=self) [task 2018-08-23T11:19:36.117Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.118Z] 11:19:36 INFO - if response.status != 200: [task 2018-08-23T11:19:36.118Z] 11:19:36 INFO - err = error.from_response(response) [task 2018-08-23T11:19:36.119Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.119Z] 11:19:36 INFO - if isinstance(err, error.InvalidSessionIdException): [task 2018-08-23T11:19:36.120Z] 11:19:36 INFO - # The driver could have already been deleted the session. [task 2018-08-23T11:19:36.120Z] 11:19:36 INFO - self.session_id = None [task 2018-08-23T11:19:36.120Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.121Z] 11:19:36 INFO - > raise err [task 2018-08-23T11:19:36.121Z] 11:19:36 INFO - E UnknownErrorException: unknown error (500): Failed to convert data to an object [task 2018-08-23T11:19:36.122Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.122Z] 11:19:36 INFO - body = {'using': 'css selector', 'value': '#inner'} [task 2018-08-23T11:19:36.123Z] 11:19:36 INFO - err = <UnknownErrorException http_status=500> [task 2018-08-23T11:19:36.123Z] 11:19:36 INFO - method = 'POST' [task 2018-08-23T11:19:36.123Z] 11:19:36 INFO - response = <Response status=500 error=<UnknownErrorException http_status=500>> [task 2018-08-23T11:19:36.124Z] 11:19:36 INFO - self = <Session a961cc02-2965-454a-8124-f660bb5a0268> [task 2018-08-23T11:19:36.124Z] 11:19:36 INFO - url = 'session/a961cc02-2965-454a-8124-f660bb5a0268/element' [task 2018-08-23T11:19:36.124Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.124Z] 11:19:36 INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:473: UnknownErrorException [task 2018-08-23T11:19:36.125Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.125Z] 11:19:36 INFO - TEST-UNEXPECTED-ERROR | /webdriver/tests/actions/pointer_origin.py | test_element_outside_of_view_port - teardown error [task 2018-08-23T11:19:36.125Z] 11:19:36 INFO - self = <webdriver.client.Actions object at 0x7f131647db90>, args = () [task 2018-08-23T11:19:36.126Z] 11:19:36 INFO - kwargs = {}, session = <Session d9a8cce3-8e85-4eab-b9e0-84caabb4d2ad> [task 2018-08-23T11:19:36.127Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.127Z] 11:19:36 INFO - def inner(self, *args, **kwargs): [task 2018-08-23T11:19:36.127Z] 11:19:36 INFO - if hasattr(self, "session"): [task 2018-08-23T11:19:36.128Z] 11:19:36 INFO - session = self.session [task 2018-08-23T11:19:36.129Z] 11:19:36 INFO - else: [task 2018-08-23T11:19:36.130Z] 11:19:36 INFO - session = self [task 2018-08-23T11:19:36.130Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.130Z] 11:19:36 INFO - if session.session_id is None: [task 2018-08-23T11:19:36.131Z] 11:19:36 INFO - session.start() [task 2018-08-23T11:19:36.131Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.131Z] 11:19:36 INFO - > return func(self, *args, **kwargs) [task 2018-08-23T11:19:36.131Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.131Z] 11:19:36 INFO - args = () [task 2018-08-23T11:19:36.132Z] 11:19:36 INFO - func = <function release at 0x7f1316660410> [task 2018-08-23T11:19:36.132Z] 11:19:36 INFO - kwargs = {} [task 2018-08-23T11:19:36.132Z] 11:19:36 INFO - self = <webdriver.client.Actions object at 0x7f131647db90> [task 2018-08-23T11:19:36.133Z] 11:19:36 INFO - session = <Session d9a8cce3-8e85-4eab-b9e0-84caabb4d2ad> [task 2018-08-23T11:19:36.133Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.134Z] 11:19:36 INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:20: [task 2018-08-23T11:19:36.134Z] 11:19:36 INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ [task 2018-08-23T11:19:36.134Z] 11:19:36 INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:225: in release [task 2018-08-23T11:19:36.135Z] 11:19:36 INFO - return self.session.send_session_command("DELETE", "actions") [task 2018-08-23T11:19:36.135Z] 11:19:36 INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:509: in send_session_command [task 2018-08-23T11:19:36.135Z] 11:19:36 INFO - return self.send_command(method, url, body) [task 2018-08-23T11:19:36.135Z] 11:19:36 INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ [task 2018-08-23T11:19:36.135Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.136Z] 11:19:36 INFO - self = <Session d9a8cce3-8e85-4eab-b9e0-84caabb4d2ad>, method = 'DELETE' [task 2018-08-23T11:19:36.136Z] 11:19:36 INFO - url = 'session/a961cc02-2965-454a-8124-f660bb5a0268/actions', body = None [task 2018-08-23T11:19:36.136Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.136Z] 11:19:36 INFO - def send_command(self, method, url, body=None): [task 2018-08-23T11:19:36.137Z] 11:19:36 INFO - """ [task 2018-08-23T11:19:36.137Z] 11:19:36 INFO - Send a command to the remote end and validate its success. [task 2018-08-23T11:19:36.137Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.137Z] 11:19:36 INFO - :param method: HTTP method to use in request. [task 2018-08-23T11:19:36.138Z] 11:19:36 INFO - :param uri: "Command part" of the HTTP request URL, [task 2018-08-23T11:19:36.138Z] 11:19:36 INFO - e.g. `window/rect`. [task 2018-08-23T11:19:36.138Z] 11:19:36 INFO - :param body: Optional body of the HTTP request. [task 2018-08-23T11:19:36.138Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.139Z] 11:19:36 INFO - :return: `None` if the HTTP response body was empty, otherwise [task 2018-08-23T11:19:36.139Z] 11:19:36 INFO - the `value` field returned after parsing the response [task 2018-08-23T11:19:36.140Z] 11:19:36 INFO - body as JSON. [task 2018-08-23T11:19:36.140Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.140Z] 11:19:36 INFO - :raises error.WebDriverException: If the remote end returns [task 2018-08-23T11:19:36.140Z] 11:19:36 INFO - an error. [task 2018-08-23T11:19:36.140Z] 11:19:36 INFO - :raises ValueError: If the response body does not contain a [task 2018-08-23T11:19:36.140Z] 11:19:36 INFO - `value` key. [task 2018-08-23T11:19:36.141Z] 11:19:36 INFO - """ [task 2018-08-23T11:19:36.141Z] 11:19:36 INFO - response = self.transport.send( [task 2018-08-23T11:19:36.141Z] 11:19:36 INFO - method, url, body, [task 2018-08-23T11:19:36.142Z] 11:19:36 INFO - encoder=protocol.Encoder, decoder=protocol.Decoder, [task 2018-08-23T11:19:36.142Z] 11:19:36 INFO - session=self) [task 2018-08-23T11:19:36.143Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.144Z] 11:19:36 INFO - if response.status != 200: [task 2018-08-23T11:19:36.144Z] 11:19:36 INFO - err = error.from_response(response) [task 2018-08-23T11:19:36.144Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.144Z] 11:19:36 INFO - if isinstance(err, error.InvalidSessionIdException): [task 2018-08-23T11:19:36.144Z] 11:19:36 INFO - # The driver could have already been deleted the session. [task 2018-08-23T11:19:36.144Z] 11:19:36 INFO - self.session_id = None [task 2018-08-23T11:19:36.144Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.145Z] 11:19:36 INFO - > raise err [task 2018-08-23T11:19:36.146Z] 11:19:36 INFO - E UnknownErrorException: unknown error (500): Failed to decode response from marionette [task 2018-08-23T11:19:36.146Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.147Z] 11:19:36 INFO - body = None [task 2018-08-23T11:19:36.148Z] 11:19:36 INFO - err = <UnknownErrorException http_status=500> [task 2018-08-23T11:19:36.148Z] 11:19:36 INFO - method = 'DELETE' [task 2018-08-23T11:19:36.149Z] 11:19:36 INFO - response = <Response status=500 error=<UnknownErrorException http_status=500>> [task 2018-08-23T11:19:36.149Z] 11:19:36 INFO - self = <Session d9a8cce3-8e85-4eab-b9e0-84caabb4d2ad> [task 2018-08-23T11:19:36.150Z] 11:19:36 INFO - url = 'session/a961cc02-2965-454a-8124-f660bb5a0268/actions' [task 2018-08-23T11:19:36.150Z] 11:19:36 INFO - [task 2018-08-23T11:19:36.151Z] 11:19:36 INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:473: UnknownErrorException [task 2018-08-23T11:19:36.152Z] 11:19:36 INFO - TEST-OK | /webdriver/tests/actions/pointer_origin.py | took 4343ms

There is a content crash which causes this problem: [task 2018-08-23T11:19:33.122Z] 11:19:33 INFO - PID 1127 | A content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down [task 2018-08-23T11:19:33.204Z] 11:19:33 INFO - PID 1127 | 1535023173197 Marionette DEBUG Received DOM event unload for [object XULDocument] [task 2018-08-23T11:19:33.212Z] 11:19:33 INFO - PID 1127 | 1535023173207 Marionette DEBUG Received observer notification message-manager-disconnect [task 2018-08-23T11:19:33.212Z] 11:19:33 INFO - PID 1127 | 1535023173209 Marionette TRACE 0 <- [1,1667,null,{"value":null}] [task 2018-08-23T11:19:33.228Z] 11:19:33 INFO - PID 1127 | 1535023173217 webdriver::server DEBUG <- 500 Internal Server Error {"value":{"error":"unknown error","message":"Failed to convert data to an object","stacktrace":""}} Most likely caused by bug 1482029.

Moving bug to Remote Protocol::Marionette component per bug 1815831.