Closed Bug 1699531 Opened 5 years ago Closed 5 years ago

Intermittent layout/base/tests/marionette/test_accessiblecaret_selection_mode.py AccessibleCaretSelectionModeTestCase.test_handle_tilt_when_carets_overlap_each_other_textarea_rtl | IOError: Process killed because the connection to Marionette server lost

Categories

(Core :: Layout, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: apavel [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=333651462&repo=mozilla-esr78
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/aAkkeRvGTwqF4Bg5mFqkvg/runs/0/artifacts/public/logs/live_backing.log


[task 2021-03-18T15:46:56.135Z] 15:46:56     INFO - TEST-START | layout/base/tests/marionette/test_accessiblecaret_selection_mode.py AccessibleCaretSelectionModeTestCase.test_handle_tilt_when_carets_overlap_each_other_textarea_rtl
[task 2021-03-18T15:46:56.143Z] 15:46:56     INFO -  1616082416135	Marionette	DEBUG	Closed connection 78
[task 2021-03-18T15:46:56.146Z] 15:46:56     INFO -  1616082416136	Marionette	DEBUG	Accepted connection 79 from 127.0.0.1:58928
[task 2021-03-18T15:46:56.148Z] 15:46:56     INFO -  1616082416138	Marionette	DEBUG	79 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2021-03-18T15:46:56.149Z] 15:46:56     INFO -  1616082416140	Marionette	TRACE	[7] Frame script loaded
[task 2021-03-18T15:46:56.151Z] 15:46:56     INFO -  1616082416141	Marionette	TRACE	[7] Frame script registered
[task 2021-03-18T15:46:56.151Z] 15:46:56     INFO -  1616082416144	Marionette	DEBUG	79 <- [1,1,null,{"sessionId":"135b0af4-05bb-4176-b9a3-b3500c484806","capabilities":{"browserName":"firefox","browserVersion":"78.9. ... p/tmpSSBLyH.mozrunner","moz:shutdownTimeout":180000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2021-03-18T15:46:56.159Z] 15:46:56     INFO -  1616082416150	Marionette	DEBUG	79 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2021-03-18T15:46:56.159Z] 15:46:56     INFO -  1616082416152	Marionette	DEBUG	79 <- [1,2,null,{"value":null}]
[task 2021-03-18T15:46:56.159Z] 15:46:56     INFO -  1616082416153	Marionette	DEBUG	79 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2021-03-18T15:46:56.159Z] 15:46:56     INFO -  1616082416153	Marionette	DEBUG	79 <- [1,3,null,{"value":null}]
[task 2021-03-18T15:46:56.159Z] 15:46:56     INFO -  1616082416154	Marionette	DEBUG	79 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2021-03-18T15:46:56.159Z] 15:46:56     INFO -  1616082416154	Marionette	DEBUG	79 <- [1,4,null,{"value":null}]
[task 2021-03-18T15:46:56.159Z] 15:46:56     INFO -  1616082416155	Marionette	DEBUG	79 -> [0,5,"Marionette:GetContext",{}]
[task 2021-03-18T15:46:56.159Z] 15:46:56     INFO -  1616082416155	Marionette	DEBUG	79 <- [1,5,null,{"value":"content"}]
[task 2021-03-18T15:46:56.160Z] 15:46:56     INFO -  1616082416156	Marionette	DEBUG	79 -> [0,6,"Marionette:SetContext",{"value":"chrome"}]
[task 2021-03-18T15:46:56.160Z] 15:46:56     INFO -  1616082416156	Marionette	DEBUG	79 <- [1,6,null,{"value":null}]
[task 2021-03-18T15:46:56.160Z] 15:46:56     INFO -  1616082416157	Marionette	DEBUG	79 -> [0,7,"WebDriver:ExecuteScript",{"script":"Components.utils.import(\"resource://gre/modules/Preferences.jsm\");\n\n            ... e,false],"filename":"../../venv/lib/python2.7/site-packages/marionette_driver/marionette.py","sandbox":"default","line":770}]
[task 2021-03-18T15:46:56.168Z] 15:46:56     INFO -  1616082416162	Marionette	DEBUG	79 <- [1,7,null,{"value":null}]
[task 2021-03-18T15:46:56.168Z] 15:46:56     INFO -  1616082416163	Marionette	DEBUG	79 -> [0,8,"Marionette:SetContext",{"value":"content"}]
[task 2021-03-18T15:46:56.168Z] 15:46:56     INFO -  1616082416163	Marionette	DEBUG	79 <- [1,8,null,{"value":null}]
[task 2021-03-18T15:46:56.168Z] 15:46:56     INFO -  1616082416164	Marionette	DEBUG	79 -> [0,9,"Marionette:GetContext",{}]
[task 2021-03-18T15:46:56.169Z] 15:46:56     INFO -  1616082416164	Marionette	DEBUG	79 <- [1,9,null,{"value":"content"}]
[task 2021-03-18T15:46:56.169Z] 15:46:56     INFO -  1616082416165	Marionette	DEBUG	79 -> [0,10,"Marionette:SetContext",{"value":"chrome"}]
[task 2021-03-18T15:46:56.169Z] 15:46:56     INFO -  1616082416165	Marionette	DEBUG	79 <- [1,10,null,{"value":null}]
[task 2021-03-18T15:46:56.169Z] 15:46:56     INFO -  1616082416166	Marionette	DEBUG	79 -> [0,11,"WebDriver:ExecuteScript",{"script":"Components.utils.import(\"resource://gre/modules/Preferences.jsm\");\n\n           ... e,false],"filename":"../../venv/lib/python2.7/site-packages/marionette_driver/marionette.py","sandbox":"default","line":770}]
[task 2021-03-18T15:46:56.172Z] 15:46:56     INFO -  1616082416171	Marionette	DEBUG	79 <- [1,11,null,{"value":null}]
[task 2021-03-18T15:46:56.173Z] 15:46:56     INFO -  1616082416172	Marionette	DEBUG	79 -> [0,12,"Marionette:SetContext",{"value":"content"}]
[task 2021-03-18T15:46:56.173Z] 15:46:56     INFO -  1616082416172	Marionette	DEBUG	79 <- [1,12,null,{"value":null}]
[task 2021-03-18T15:46:56.176Z] 15:46:56     INFO -  1616082416174	Marionette	DEBUG	79 -> [0,13,"WebDriver:Navigate",{"url":"http://127.0.0.1:43489/layout/test_carets_selection.html"}]
[task 2021-03-18T15:46:56.180Z] 15:46:56     INFO -  1616082416178	Marionette	TRACE	[7] Received DOM event beforeunload for http://127.0.0.1:43489/layout/test_carets_selection.html
[task 2021-03-18T15:46:56.195Z] 15:46:56     INFO -  1616082416192	Marionette	TRACE	[7] Received DOM event pagehide for http://127.0.0.1:43489/layout/test_carets_selection.html
[task 2021-03-18T15:46:56.236Z] 15:46:56     INFO -  1616082416232	Marionette	TRACE	[7] Received DOM event DOMContentLoaded for http://127.0.0.1:43489/layout/test_carets_selection.html
[task 2021-03-18T15:46:56.252Z] 15:46:56     INFO -  1616082416246	Marionette	TRACE	[7] Received DOM event pageshow for http://127.0.0.1:43489/layout/test_carets_selection.html
[task 2021-03-18T15:46:56.259Z] 15:46:56     INFO -  1616082416256	Marionette	DEBUG	79 <- [1,13,null,{"value":null}]

[task 2021-03-18T15:46:56.792Z] 15:46:56     INFO -  1616082416782	Marionette	DEBUG	79 <- [1,49,null,{"value":"textarea"}]
[task 2021-03-18T15:46:56.827Z] 15:46:56     INFO -  1616082416819	Marionette	DEBUG	79 -> [0,50,"WebDriver:ExecuteScript",{"script":"var sel = arguments[0].editor.selection;return sel.rangeCount;","newSandbox":true, ... 8-91ce-4b05-8d6f-82ed83761a04"}],"filename":"tests/layout/base/tests/marionette/selection.py","sandbox":"system","line":167}]
[task 2021-03-18T15:46:56.829Z] 15:46:56     INFO -  1616082416826	Marionette	DEBUG	79 <- [1,50,null,{"value":1}]
[task 2021-03-18T15:46:56.832Z] 15:46:56     INFO -  1616082416830	Marionette	DEBUG	79 -> [0,51,"WebDriver:GetElementTagName",{"id":"0de63e98-91ce-4b05-8d6f-82ed83761a04"}]
[task 2021-03-18T15:46:56.835Z] 15:46:56     INFO -  1616082416833	Marionette	DEBUG	79 <- [1,51,null,{"value":"textarea"}]
[task 2021-03-18T15:46:56.891Z] 15:46:56     INFO -  1616082416884	Marionette	DEBUG	79 -> [0,52,"WebDriver:ExecuteScript",{"script":"var sel = arguments[0].editor.selection;return sel.getRangeAt(0).getClientRects(); ... 8-91ce-4b05-8d6f-82ed83761a04"}],"filename":"tests/layout/base/tests/marionette/selection.py","sandbox":"system","line":160}]
[task 2021-03-18T15:46:56.899Z] 15:46:56     INFO -  1616082416896	Marionette	DEBUG	79 <- [1,52,null,{"value":{"0":{"x":43.01666259765625,"y":177.6666717529297,"width":3.5,"height":15,"top":177.6666717529297,"right":46.51666259765625,"bottom":192.6666717529297,"left":43.01666259765625},"item":{},"length":1}}]
[task 2021-03-18T15:46:56.914Z] 15:46:56     INFO -  1616082416912	Marionette	DEBUG	79 -> [0,53,"WebDriver:GetElementTagName",{"id":"0de63e98-91ce-4b05-8d6f-82ed83761a04"}]
[task 2021-03-18T15:46:56.917Z] 15:46:56     INFO -  1616082416915	Marionette	DEBUG	79 <- [1,53,null,{"value":"textarea"}]
[task 2021-03-18T15:46:56.958Z] 15:46:56     INFO -  1616082416946	Marionette	DEBUG	79 -> [0,54,"WebDriver:ExecuteScript",{"script":"var sel = arguments[0].editor.selection;return sel.getRangeAt(0).getClientRects(); ... 8-91ce-4b05-8d6f-82ed83761a04"}],"filename":"tests/layout/base/tests/marionette/selection.py","sandbox":"system","line":160}]
[task 2021-03-18T15:56:08.137Z] 15:56:08     INFO - TEST-UNEXPECTED-ERROR | layout/base/tests/marionette/test_accessiblecaret_selection_mode.py AccessibleCaretSelectionModeTestCase.test_handle_tilt_when_carets_overlap_each_other_textarea_rtl | IOError: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason: Connection timed out after 360.0s)
[task 2021-03-18T15:56:08.137Z] 15:56:08     INFO - Traceback (most recent call last):
[task 2021-03-18T15:56:08.137Z] 15:56:08     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 191, in run
[task 2021-03-18T15:56:08.137Z] 15:56:08     INFO -     testMethod()
[task 2021-03-18T15:56:08.138Z] 15:56:08     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 65, in wrapper
[task 2021-03-18T15:56:08.138Z] 15:56:08     INFO -     return func(self, *args, **kwargs)
[task 2021-03-18T15:56:08.138Z] 15:56:08     INFO -   File "/builds/worker/workspace/build/tests/marionette/tests/layout/base/tests/marionette/test_accessiblecaret_selection_mode.py", line 390, in test_handle_tilt_when_carets_overlap_each_other
[task 2021-03-18T15:56:08.138Z] 15:56:08     INFO -     (caret3_x, caret3_y), (caret4_x, caret4_y) = sel.carets_location()
[task 2021-03-18T15:56:08.138Z] 15:56:08     INFO -   File "/builds/worker/workspace/build/tests/marionette/tests/layout/base/tests/marionette/selection.py", line 229, in carets_location
[task 2021-03-18T15:56:08.138Z] 15:56:08     INFO -     return self._selection_location_helper('caret')
[task 2021-03-18T15:56:08.138Z] 15:56:08     INFO -   File "/builds/worker/workspace/build/tests/marionette/tests/layout/base/tests/marionette/selection.py", line 180, in _selection_location_helper
[task 2021-03-18T15:56:08.138Z] 15:56:08     INFO -     last_rect_list = self.selection_rect_list(range_count - 1)
[task 2021-03-18T15:56:08.138Z] 15:56:08     INFO -   File "/builds/worker/workspace/build/tests/marionette/tests/layout/base/tests/marionette/selection.py", line 160, in selection_rect_list
[task 2021-03-18T15:56:08.138Z] 15:56:08     INFO -     cmd, script_args=(self.element,), sandbox='system')
[task 2021-03-18T15:56:08.138Z] 15:56:08     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1601, in execute_script
[task 2021-03-18T15:56:08.138Z] 15:56:08     INFO -     rv = self._send_message("WebDriver:ExecuteScript", body, key="value")
[task 2021-03-18T15:56:08.138Z] 15:56:08     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 36, in _
[task 2021-03-18T15:56:08.138Z] 15:56:08     INFO -     m._handle_socket_failure()
[task 2021-03-18T15:56:08.138Z] 15:56:08     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 682, in _handle_socket_failure
[task 2021-03-18T15:56:08.138Z] 15:56:08     INFO -     reraise(IOError, IOError(message.format(returncode=returncode, reason=exc)), tb)
[task 2021-03-18T15:56:08.138Z] 15:56:08     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _
[task 2021-03-18T15:56:08.138Z] 15:56:08     INFO -     return func(*args, **kwargs)
[task 2021-03-18T15:56:08.138Z] 15:56:08     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 594, in _send_message
[task 2021-03-18T15:56:08.138Z] 15:56:08     INFO -     msg = self.client.request(name, params)
[task 2021-03-18T15:56:08.138Z] 15:56:08     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/transport.py", line 276, in request
[task 2021-03-18T15:56:08.138Z] 15:56:08     INFO -     return self.receive()
[task 2021-03-18T15:56:08.139Z] 15:56:08     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/transport.py", line 186, in receive
[task 2021-03-18T15:56:08.139Z] 15:56:08     INFO -     raise socket.timeout("Connection timed out after {}s".format(self.socket_timeout))
[task 2021-03-18T15:56:08.139Z] 15:56:08     INFO - TEST-INFO took 552000ms
[task 2021-03-18T15:56:08.139Z] 15:56:08    ERROR - test_end for layout/base/tests/marionette/test_accessiblecaret_selection_mode.py AccessibleCaretSelectionModeTestCase.test_handle_tilt_when_carets_overlap_each_other_textarea_rtl logged while not in progress. Logged with data: {"status": "ERROR", "extra": {"class_name": "test_accessiblecaret_selection_mode.AccessibleCaretSelectionModeTestCase", "method_name": "test_handle_tilt_when_carets_overlap_each_other_textarea_rtl"}, "expected": "PASS", "test": "layout/base/tests/marionette/test_accessiblecaret_selection_mode.py AccessibleCaretSelectionModeTestCase.test_handle_tilt_when_carets_overlap_each_other_textarea_rtl", "message": "InvalidSessionIdException: Please start a session", "stack": "Traceback (most recent call last):\n  File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py\", line 222, in run\n    self.tearDown()\n  File \"/builds/worker/workspace/build/tests/marionette/tests/layout/base/tests/marionette/test_accessiblecaret_selection_mode.py\", line 61, in tearDown\n    self.marionette.actions.release()\n  File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py\", line 186, in release\n    return self.marionette._send_message(\"WebDriver:ReleaseActions\")\n  File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py\", line 26, in _\n    return func(*args, **kwargs)\n  File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py\", line 591, in _send_message\n    raise errors.InvalidSessionIdException(\"Please start a session\")\n"}
Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.