Closed Bug 1673638 Opened 5 years ago Closed 4 years ago

Intermittent layout/base/tests/marionette/test_accessiblecaret_cursor_mode.py AccessibleCaretCursorModeTestCase.test_caret_not_jump_to_front_when_dragging_up_to_editable_content_boundary_textarea_one_line | JavascriptException: [Exception... "Unexpected

Categories

(Core :: Layout, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: rmaries [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=319870219&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/d9hP1O5lTpe8pOY4NMSlEw/runs/0/artifacts/public/logs/live_backing.log


[task 2020-10-27T14:22:09.595Z] 14:22:09     INFO - TEST-START | layout/base/tests/marionette/test_accessiblecaret_cursor_mode.py AccessibleCaretCursorModeTestCase.test_caret_not_jump_to_front_when_dragging_up_to_editable_content_boundary_textarea_one_line

[task 2020-10-27T14:22:10.395Z] 14:22:10     INFO -  1603808530350	Marionette	DEBUG	12 -> [0,28,"WebDriver:GetPageSource",{}]
[task 2020-10-27T14:22:10.395Z] 14:22:10     INFO -  1603808530357	Marionette	DEBUG	12 <- [1,28,null,{"value":"<html id=\"html\"><head>\n    <title>Marionette tests for AccessibleCaret in cursor mode</title>\n    <s ... \n    <br>\n    <div class=\"block\" id=\"contenteditable\" contenteditable=\"true\">ABCDEFGHI</div>\n  \n\n</body></html>"}]
[task 2020-10-27T14:22:10.403Z] 14:22:10     INFO - TEST-UNEXPECTED-ERROR | layout/base/tests/marionette/test_accessiblecaret_cursor_mode.py AccessibleCaretCursorModeTestCase.test_caret_not_jump_to_front_when_dragging_up_to_editable_content_boundary_textarea_one_line | JavascriptException: [Exception... "Unexpected error"  nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)"  location: "JS frame :: tests/layout/base/tests/marionette/selection.py :: <TOP_LEVEL> :: line 133"  data: no]
[task 2020-10-27T14:22:10.403Z] 14:22:10     INFO - stacktrace:
[task 2020-10-27T14:22:10.403Z] 14:22:10     INFO - 	@tests/layout/base/tests/marionette/selection.py:133:23
[task 2020-10-27T14:22:10.403Z] 14:22:10     INFO - 	@tests/layout/base/tests/marionette/selection.py:135:8
[task 2020-10-27T14:22:10.403Z] 14:22:10     INFO - 	evaluate.sandbox/promise<@chrome://marionette/content/evaluate.js:136:10
[task 2020-10-27T14:22:10.403Z] 14:22:10     INFO - 	evaluate.sandbox@chrome://marionette/content/evaluate.js:112:17
[task 2020-10-27T14:22:10.403Z] 14:22:10     INFO - 	executeScript@chrome://marionette/content/listener.js:317:19
[task 2020-10-27T14:22:10.404Z] 14:22:10     INFO - 	dispatch/</req<@chrome://marionette/content/listener.js:105:17
[task 2020-10-27T14:22:10.404Z] 14:22:10     INFO - 	dispatch/<@chrome://marionette/content/listener.js:100:15
[task 2020-10-27T14:22:10.404Z] 14:22:10     INFO - Traceback (most recent call last):
[task 2020-10-27T14:22:10.404Z] 14:22:10     INFO -   File "/Users/cltbld/tasks/task_1603807796/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 214, in run
[task 2020-10-27T14:22:10.404Z] 14:22:10     INFO -     testMethod()
[task 2020-10-27T14:22:10.404Z] 14:22:10     INFO -   File "/Users/cltbld/tasks/task_1603807796/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 64, in wrapper
[task 2020-10-27T14:22:10.404Z] 14:22:10     INFO -     return func(self, *args, **kwargs)
[task 2020-10-27T14:22:10.404Z] 14:22:10     INFO -   File "/Users/cltbld/tasks/task_1603807796/build/tests/marionette/tests/layout/base/tests/marionette/test_accessiblecaret_cursor_mode.py", line 212, in test_caret_not_jump_to_front_when_dragging_up_to_editable_content_boundary
[task 2020-10-27T14:22:10.404Z] 14:22:10     INFO -     sel.move_cursor_by_offset(1, backward=True)
[task 2020-10-27T14:22:10.404Z] 14:22:10     INFO -   File "/Users/cltbld/tasks/task_1603807796/build/tests/marionette/tests/layout/base/tests/marionette/selection.py", line 130, in move_cursor_by_offset
[task 2020-10-27T14:22:10.404Z] 14:22:10     INFO -     cmd, script_args=(self.element,), sandbox="system"
[task 2020-10-27T14:22:10.404Z] 14:22:10     INFO -   File "/Users/cltbld/tasks/task_1603807796/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1644, in execute_script
[task 2020-10-27T14:22:10.404Z] 14:22:10     INFO -     rv = self._send_message("WebDriver:ExecuteScript", body, key="value")
[task 2020-10-27T14:22:10.405Z] 14:22:10     INFO -   File "/Users/cltbld/tasks/task_1603807796/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2020-10-27T14:22:10.405Z] 14:22:10     INFO -     return func(*args, **kwargs)
[task 2020-10-27T14:22:10.405Z] 14:22:10     INFO -   File "/Users/cltbld/tasks/task_1603807796/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 625, in _send_message
[task 2020-10-27T14:22:10.405Z] 14:22:10     INFO -     self._handle_error(err)
[task 2020-10-27T14:22:10.405Z] 14:22:10     INFO -   File "/Users/cltbld/tasks/task_1603807796/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 647, in _handle_error
[task 2020-10-27T14:22:10.405Z] 14:22:10     INFO -     raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2020-10-27T14:22:10.405Z] 14:22:10     INFO - TEST-INFO took 786ms```
Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=337966535&repo=autoland&lineNumber=32633

[task 2021-04-28T03:37:02.280Z] 03:37:02 INFO - [Child 1466, Main Thread] WARNING: '!aContent->GetComposedDoc()', file /builds/worker/checkouts/gecko/layout/base/nsCSSFrameConstructor.cpp:8427
[task 2021-04-28T03:37:02.281Z] 03:37:02 INFO - 1619581022281 Marionette DEBUG 15 -> [0,25,"WebDriver:ExecuteScript",{"script":"var sel = arguments[0].editor.selection;\n for (let i = 0; i < 1; ++i ... c8209b64c95"}],"newSandbox":true,"sandbox":"system","line":130,"filename":"tests/layout/base/tests/marionette/selection.py"}]
[task 2021-04-28T03:37:02.282Z] 03:37:02 INFO - [Child 1466, Main Thread] WARNING: '!aContent->GetComposedDoc()', file /builds/worker/checkouts/gecko/layout/base/nsCSSFrameConstructor.cpp:8427
[task 2021-04-28T03:37:02.292Z] 03:37:02 INFO - [Child 1465, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/generic/nsFrameSelection.cpp:1591
[task 2021-04-28T03:37:02.303Z] 03:37:02 INFO - 1619581022302 Marionette DEBUG 15 <- [1,25,{"error":"javascript error","message":"[Exception... "Unexpected error" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED) ... onetteCommandsChild.jsm:201:21\nreceiveMessage@chrome://marionette/content/actors/MarionetteCommandsChild.jsm:88:31\n"},null]
[task 2021-04-28T03:37:02.303Z] 03:37:02 INFO - DEBUG: Adding blocker Transaction (5) for phase content-prefs.sqlite#0: waiting for clients
[task 2021-04-28T03:37:02.306Z] 03:37:02 INFO - DEBUG: Completed blocker Transaction (5) for phase content-prefs.sqlite#0: waiting for clients
[task 2021-04-28T03:37:02.306Z] 03:37:02 INFO - 1619581022306 Marionette DEBUG 15 -> [0,26,"Marionette:GetContext",{}]
[task 2021-04-28T03:37:02.306Z] 03:37:02 INFO - 1619581022306 Marionette DEBUG 15 <- [1,26,null,{"value":"content"}]
[task 2021-04-28T03:37:02.307Z] 03:37:02 INFO - 1619581022307 Marionette DEBUG 15 -> [0,27,"Marionette:SetContext",{"value":"chrome"}]
[task 2021-04-28T03:37:02.308Z] 03:37:02 INFO - 1619581022307 Marionette DEBUG 15 <- [1,27,null,{"value":null}]
[task 2021-04-28T03:37:02.308Z] 03:37:02 INFO - 1619581022308 Marionette DEBUG 15 -> [0,28,"WebDriver:TakeScreenshot",{"id":null,"full":true,"hash":false,"scroll":true}]
[task 2021-04-28T03:37:02.374Z] 03:37:02 INFO - 1619581022374 Marionette DEBUG 15 <- [1,28,null,{"value":"iVBORw0KGgoAAAANSUhEUgAABQAAAAPNCAYAAADWQsenAAAgAElEQVR4nOy9aVQUd8K23x/fcx759FcSPZw8kZlHMzkxiZk4yTsZE5Mn ... gAAAAAYwIQAAAAAMYEIAAAAACMCUAAAAAAGBOAAAAAADAmAAEAAABgTAACAAAAwJgABAAAAIAxAQgAAAAAYwIQAAAAAMYCRlkwgS9BEuIAAAAASUVORK5CYII="}]
[task 2021-04-28T03:37:02.378Z] 03:37:02 INFO - 1619581022378 Marionette DEBUG 15 -> [0,29,"Marionette:SetContext",{"value":"content"}]
[task 2021-04-28T03:37:02.378Z] 03:37:02 INFO - 1619581022378 Marionette DEBUG 15 <- [1,29,null,{"value":null}]
[task 2021-04-28T03:37:02.379Z] 03:37:02 INFO - 1619581022379 Marionette DEBUG 15 -> [0,30,"Marionette:GetContext",{}]
[task 2021-04-28T03:37:02.380Z] 03:37:02 INFO - 1619581022379 Marionette DEBUG 15 <- [1,30,null,{"value":"content"}]
[task 2021-04-28T03:37:02.381Z] 03:37:02 INFO - 1619581022380 Marionette DEBUG 15 -> [0,31,"Marionette:SetContext",{"value":"content"}]
[task 2021-04-28T03:37:02.381Z] 03:37:02 INFO - 1619581022381 Marionette DEBUG 15 <- [1,31,null,{"value":null}]
[task 2021-04-28T03:37:02.382Z] 03:37:02 INFO - 1619581022382 Marionette DEBUG 15 -> [0,32,"WebDriver:GetPageSource",{}]
[task 2021-04-28T03:37:02.384Z] 03:37:02 INFO - 1619581022383 Marionette DEBUG 15 <- [1,32,null,{"value":"<html id="html"><head>\n <title>Marionette tests for AccessibleCaret in cursor mode</title>\n <s ... \n <br>\n <div class="block" id="contenteditable" contenteditable="true">ABCDEFGHI</div>\n \n\n</body></html>"}]
[task 2021-04-28T03:37:02.384Z] 03:37:02 INFO - 1619581022384 Marionette DEBUG 15 -> [0,33,"Marionette:SetContext",{"value":"content"}]
[task 2021-04-28T03:37:02.385Z] 03:37:02 INFO - 1619581022384 Marionette DEBUG 15 <- [1,33,null,{"value":null}]
[task 2021-04-28T03:37:02.423Z] 03:37:02 INFO - TEST-UNEXPECTED-ERROR | layout/base/tests/marionette/test_accessiblecaret_cursor_mode.py AccessibleCaretCursorModeTestCase.test_caret_not_jump_to_front_when_dragging_up_to_editable_content_boundary_textarea_one_line | marionette_driver.errors.JavascriptException: [Exception... "Unexpected error" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: tests/layout/base/tests/marionette/selection.py :: <TOP_LEVEL> :: line 133" data: no]
[task 2021-04-28T03:37:02.423Z] 03:37:02 INFO - stacktrace:
[task 2021-04-28T03:37:02.423Z] 03:37:02 INFO - @tests/layout/base/tests/marionette/selection.py:133:23
[task 2021-04-28T03:37:02.423Z] 03:37:02 INFO - @tests/layout/base/tests/marionette/selection.py:135:8
[task 2021-04-28T03:37:02.423Z] 03:37:02 INFO - evaluate.sandbox/promise<@chrome://marionette/content/evaluate.js:136:10
[task 2021-04-28T03:37:02.423Z] 03:37:02 INFO - evaluate.sandbox@chrome://marionette/content/evaluate.js:112:17
[task 2021-04-28T03:37:02.423Z] 03:37:02 INFO - executeScript@chrome://marionette/content/actors/MarionetteCommandsChild.jsm:201:21
[task 2021-04-28T03:37:02.423Z] 03:37:02 INFO - receiveMessage@chrome://marionette/content/actors/MarionetteCommandsChild.jsm:88:31
[task 2021-04-28T03:37:02.423Z] 03:37:02 INFO - Traceback (most recent call last):
[task 2021-04-28T03:37:02.423Z] 03:37:02 INFO - File "/opt/worker/tasks/task_161958044605776/build/venv/lib/python3.7/site-packages/marionette_harness/marionette_test/testcases.py", line 214, in run
[task 2021-04-28T03:37:02.423Z] 03:37:02 INFO - testMethod()
[task 2021-04-28T03:37:02.423Z] 03:37:02 INFO - File "/opt/worker/tasks/task_161958044605776/build/venv/lib/python3.7/site-packages/marionette_harness/marionette_test/testcases.py", line 64, in wrapper
[task 2021-04-28T03:37:02.424Z] 03:37:02 INFO - return func(self, *args, **kwargs)
[task 2021-04-28T03:37:02.424Z] 03:37:02 INFO - File "/opt/worker/tasks/task_161958044605776/build/tests/marionette/tests/layout/base/tests/marionette/test_accessiblecaret_cursor_mode.py", line 215, in test_caret_not_jump_to_front_when_dragging_up_to_editable_content_boundary
[task 2021-04-28T03:37:02.424Z] 03:37:02 INFO - sel.move_cursor_by_offset(1, backward=True)
[task 2021-04-28T03:37:02.424Z] 03:37:02 INFO - File "/opt/worker/tasks/task_161958044605776/build/tests/marionette/tests/layout/base/tests/marionette/selection.py", line 130, in move_cursor_by_offset
[task 2021-04-28T03:37:02.424Z] 03:37:02 INFO - cmd, script_args=(self.element,), sandbox="system"
[task 2021-04-28T03:37:02.424Z] 03:37:02 INFO - File "/opt/worker/tasks/task_161958044605776/build/venv/lib/python3.7/site-packages/marionette_driver/marionette.py", line 1659, in execute_script
[task 2021-04-28T03:37:02.424Z] 03:37:02 INFO - rv = self._send_message("WebDriver:ExecuteScript", body, key="value")
[task 2021-04-28T03:37:02.424Z] 03:37:02 INFO - File "/opt/worker/tasks/task_161958044605776/build/venv/lib/python3.7/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2021-04-28T03:37:02.424Z] 03:37:02 INFO - return func(*args, **kwargs)
[task 2021-04-28T03:37:02.424Z] 03:37:02 INFO - File "/opt/worker/tasks/task_161958044605776/build/venv/lib/python3.7/site-packages/marionette_driver/marionette.py", line 629, in _send_message
[task 2021-04-28T03:37:02.424Z] 03:37:02 INFO - self._handle_error(err)
[task 2021-04-28T03:37:02.424Z] 03:37:02 INFO - File "/opt/worker/tasks/task_161958044605776/build/venv/lib/python3.7/site-packages/marionette_driver/marionette.py", line 651, in _handle_error
[task 2021-04-28T03:37:02.424Z] 03:37:02 INFO - raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2021-04-28T03:37:02.424Z] 03:37:02 INFO - TEST-INFO took 384ms
[task 2021-04-28T03:37:02.425Z] 03:37:02 INFO - 1619581022386 Marionette DEBUG 15 -> [0,34,"WebDriver:ReleaseActions",{}]
[task 2021-04-28T03:37:02.425Z] 03:37:02 INFO - 1619581022387 Marionette DEBUG 15 <- [1,34,null,{"value":null}]
[task 2021-04-28T03:37:02.425Z] 03:37:02 INFO - 1619581022388 Marionette DEBUG 15 -> [0,35,"WebDriver:DeleteSession",{}]
[task 2021-04-28T03:37:02.425Z] 03:37:02 INFO - 1619581022389 Marionette DEBUG 15 <- [1,35,null,{"value":null}]

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 5 years ago5 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 5 years ago4 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 4 years ago4 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.