Closed Bug 1791578 Opened 3 years ago Closed 3 years ago

Intermittent layout/base/tests/marionette/test_accessiblecaret_selection_mode.py AccessibleCaretSelectionModeTestCase.test_minimum_select_one_character_input | marionette_driver.errors.JavascriptException: [Exception... "Unexpected error"

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=390923513&repo=try
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/cl9YO6VKRO-e-BkHwh355Q/runs/0/artifacts/public/logs/live_backing.log


[task 2022-09-20T12:23:49.897Z] 12:23:49     INFO -  1663676629897	Marionette	DEBUG	77 -> [0,32,"WebDriver:GetElementProperty",{"id":"02f972c3-e216-4a66-968a-ab6a3acfd342","name":"value"}]
[task 2022-09-20T12:23:49.898Z] 12:23:49     INFO -  DEBUG: Adding blocker UserInteractionTimer 76 for document 501c000 for phase xpcom-will-shutdown
[task 2022-09-20T12:23:49.907Z] 12:23:49     INFO -  1663676629907	Marionette	DEBUG	77 <- [1,32,null,{"value":"ABC DEF GHI"}]
[task 2022-09-20T12:23:49.910Z] 12:23:49     INFO -  1663676629909	Marionette	DEBUG	77 -> [0,33,"Marionette:SingleTap",{"id":"02f972c3-e216-4a66-968a-ab6a3acfd342","x":null,"y":null}]
[task 2022-09-20T12:23:49.917Z] 12:23:49     INFO -  1663676629917	Marionette	DEBUG	Emitting mousemove mouse event at coordinates (82.5, 31.333328247070312) relative to the viewport, button: undefined, clickCount: undefined
[task 2022-09-20T12:23:49.918Z] 12:23:49     INFO -  1663676629917	Marionette	DEBUG	Emitting mousedown mouse event at coordinates (82.5, 31.333328247070312) relative to the viewport, button: undefined, clickCount: undefined
[task 2022-09-20T12:23:49.920Z] 12:23:49     INFO -  1663676629920	Marionette	DEBUG	Emitting mouseup mouse event at coordinates (82.5, 31.333328247070312) relative to the viewport, button: undefined, clickCount: undefined
[task 2022-09-20T12:23:49.921Z] 12:23:49     INFO -  1663676629924	Marionette	DEBUG	77 <- [1,33,null,{"value":null}]
[task 2022-09-20T12:23:49.924Z] 12:23:49     INFO -  1663676629925	Marionette	DEBUG	77 -> [0,34,"WebDriver:GetElementTagName",{"id":"02f972c3-e216-4a66-968a-ab6a3acfd342"}]
[task 2022-09-20T12:23:49.926Z] 12:23:49     INFO -  1663676629928	Marionette	DEBUG	77 <- [1,34,null,{"value":"input"}]
[task 2022-09-20T12:23:49.940Z] 12:23:49     INFO -  1663676629950	Marionette	DEBUG	77 -> [0,35,"WebDriver:ExecuteScript",{"script":"arguments[0].setSelectionRange(0, 0);","args":[{"element-6066-11e4-a52e-4f735466cecf":"02f972c3-e216-4a66-968a-ab6a3acfd342"}],"newSandbox":true,"sandbox":null,"line":141,"filename":"Z:\\task_166366707196853\\build\\tests\\marionette\\tests\\layout\\base\\tests\\marionette\\selection.py"}]
[task 2022-09-20T12:23:49.959Z] 12:23:49     INFO -  [Child 7820, Main Thread] WARNING: '!aContent->GetComposedDoc()', file /builds/worker/checkouts/gecko/layout/base/nsCSSFrameConstructor.cpp:8493
[task 2022-09-20T12:23:49.962Z] 12:23:49     INFO -  [Child 7820, Main Thread] WARNING: '!aContent->GetComposedDoc()', file /builds/worker/checkouts/gecko/layout/base/nsCSSFrameConstructor.cpp:8493
[task 2022-09-20T12:23:49.963Z] 12:23:49     INFO -  1663676629962	Marionette	DEBUG	77 <- [1,35,null,{"value":null}]
[task 2022-09-20T12:23:49.964Z] 12:23:49     INFO -  [Child 7820, Main Thread] WARNING: '!aContent->GetComposedDoc()', file /builds/worker/checkouts/gecko/layout/base/nsCSSFrameConstructor.cpp:8493
[task 2022-09-20T12:23:49.965Z] 12:23:49     INFO -  1663676629965	Marionette	DEBUG	77 -> [0,36,"WebDriver:GetElementTagName",{"id":"02f972c3-e216-4a66-968a-ab6a3acfd342"}]
[task 2022-09-20T12:23:49.966Z] 12:23:49     INFO -  [Child 7820, Main Thread] WARNING: '!aContent->GetComposedDoc()', file /builds/worker/checkouts/gecko/layout/base/nsCSSFrameConstructor.cpp:8493
[task 2022-09-20T12:23:49.967Z] 12:23:49     INFO -  1663676629967	Marionette	DEBUG	77 <- [1,36,null,{"value":"input"}]
[task 2022-09-20T12:23:49.968Z] 12:23:49     INFO -  [Child 7820, Main Thread] WARNING: '!aContent->GetComposedDoc()', file /builds/worker/checkouts/gecko/layout/base/nsCSSFrameConstructor.cpp:8493
[task 2022-09-20T12:23:49.970Z] 12:23:49     INFO -  [Child 7820, Main Thread] WARNING: '!aContent->GetComposedDoc()', file /builds/worker/checkouts/gecko/layout/base/nsCSSFrameConstructor.cpp:8493
[task 2022-09-20T12:23:49.972Z] 12:23:49     INFO -  1663676629971	Marionette	DEBUG	77 -> [0,37,"WebDriver:ExecuteScript",{"script":"var sel = arguments[0].editor.selection;\n              for (let i = 0; i < 1; ++i) {\n                  sel.modify(\"move\", \"forward\", \"character\");\n              }","args":[{"element-6066-11e4-a52e-4f735466cecf":"02f972c3-e216-4a66-968a-ab6a3acfd342"}],"newSandbox":true,"sandbox":"system","line":129,"filename":"Z:\\task_166366707196853\\build\\tests\\marionette\\tests\\layout\\base\\tests\\marionette\\selection.py"}]
[task 2022-09-20T12:23:49.984Z] 12:23:49     INFO -  [Child 6260, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/generic/nsFrameSelection.cpp:1600
[task 2022-09-20T12:23:49.997Z] 12:23:49     INFO -  1663676629995	Marionette	DEBUG	77 <- [1,37,{"error":"javascript error","message":"[Exception... \"Unexpected error\"  nsresult: \"0x8000ffff (NS_ERROR_UNEXPECTED)\"  location: \"JS frame :: Z:\\task_166366707196853\\build\\tests\\marionette\\tests\\layout\\base\\tests\\marionette\\selection.py :: <TOP_LEVEL> :: line 132\"  data: no]","stacktrace":"@Z:\\task_166366707196853\\build\\tests\\marionette\\tests\\layout\\base\\tests\\marionette\\selection.py:132:23\n@Z:\\task_1663667071968 ... arionetteCommandsChild.jsm:224:26\nreceiveMessage@chrome://remote/content/marionette/actors/MarionetteCommandsChild.jsm:96:31\n"},null]
[task 2022-09-20T12:23:50.000Z] 12:23:49     INFO -  1663676629999	Marionette	DEBUG	77 -> [0,38,"Marionette:GetContext",{}]
[task 2022-09-20T12:23:50.000Z] 12:23:50     INFO -  1663676630000	Marionette	DEBUG	77 <- [1,38,null,{"value":"content"}]
[task 2022-09-20T12:23:50.002Z] 12:23:50     INFO -  1663676630001	Marionette	DEBUG	77 -> [0,39,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-09-20T12:23:50.002Z] 12:23:50     INFO -  1663676630002	Marionette	DEBUG	77 <- [1,39,null,{"value":null}]
[task 2022-09-20T12:23:50.003Z] 12:23:50     INFO -  1663676630003	Marionette	DEBUG	77 -> [0,40,"WebDriver:TakeScreenshot",{"id":null,"full":true,"hash":false,"scroll":true}]
[task 2022-09-20T12:23:50.012Z] 12:23:50     INFO -  [Child 6260, Main Thread] WARNING: '!aContent->GetComposedDoc()', file /builds/worker/checkouts/gecko/layout/base/nsCSSFrameConstructor.cpp:8493
[task 2022-09-20T12:23:50.071Z] 12:23:50     INFO -  1663676630080	Marionette	DEBUG	77 <- [1,40,null,{"value":"iVBORw0KGgoAAAANSUhEUgAABQAAAAP/CAYAAACf8GEBAAAgAElEQVR4XuzdC5wU1Z33/98MDLdBEFDUDAhe8JKIUZMAitEYLyGaCLiQRXA3hhjzbEQCRHPZ3SdId ... IECBAgAABAgQIECBAgAABAgTSBATANHqDCRAgQIAAAQIECBAgQIAAAQIECMQLCIDxxiYQIECAAAECBAgQIECAAAECBAgQSBP4f/al2jnEP3ZKAAAAAElFTkSuQmCC"}]
[task 2022-09-20T12:23:50.082Z] 12:23:50     INFO -  1663676630088	Marionette	DEBUG	77 -> [0,41,"Marionette:SetContext",{"value":"content"}]
[task 2022-09-20T12:23:50.089Z] 12:23:50     INFO -  1663676630088	Marionette	DEBUG	77 <- [1,41,null,{"value":null}]
[task 2022-09-20T12:23:50.090Z] 12:23:50     INFO -  1663676630090	Marionette	DEBUG	77 -> [0,42,"Marionette:GetContext",{}]
[task 2022-09-20T12:23:50.090Z] 12:23:50     INFO -  1663676630090	Marionette	DEBUG	77 <- [1,42,null,{"value":"content"}]
[task 2022-09-20T12:23:50.091Z] 12:23:50     INFO -  1663676630091	Marionette	DEBUG	77 -> [0,43,"Marionette:SetContext",{"value":"content"}]
[task 2022-09-20T12:23:50.092Z] 12:23:50     INFO -  1663676630092	Marionette	DEBUG	77 <- [1,43,null,{"value":null}]
[task 2022-09-20T12:23:50.093Z] 12:23:50     INFO -  1663676630093	Marionette	DEBUG	77 -> [0,44,"WebDriver:GetPageSource",{}]
[task 2022-09-20T12:23:50.097Z] 12:23:50     INFO -  1663676630096	Marionette	DEBUG	77 <- [1,44,null,{"value":"<html id=\"html\"><head>\n    <meta http-equiv=\"Content-Type\" content=\"text/html; charset=utf-8\">\n    <title>Marionette tests fo ... t\">ABC DEF GHI</div>\n    <br>\n    <div style=\"user-select: none;\" id=\"non-selectable\">Non-selectable</div>\n  \n\n</body></html>"}]
[task 2022-09-20T12:23:50.098Z] 12:23:50     INFO -  1663676630098	Marionette	DEBUG	77 -> [0,45,"Marionette:SetContext",{"value":"content"}]
[task 2022-09-20T12:23:50.098Z] 12:23:50     INFO -  1663676630098	Marionette	DEBUG	77 <- [1,45,null,{"value":null}]
[task 2022-09-20T12:23:50.106Z] 12:23:50    ERROR -  TEST-UNEXPECTED-ERROR | layout/base/tests/marionette/test_accessiblecaret_selection_mode.py AccessibleCaretSelectionModeTestCase.test_minimum_select_one_character_input | marionette_driver.errors.JavascriptException: [Exception... "Unexpected error"  nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)"  location: "JS frame :: Z:\task_166366707196853\build\tests\marionette\tests\layout\base\tests\marionette\selection.py :: <TOP_LEVEL> :: line 132"  data: no]
[task 2022-09-20T12:23:50.107Z] 12:23:50     INFO -  stacktrace:
[task 2022-09-20T12:23:50.107Z] 12:23:50     INFO -  	@Z:\task_166366707196853\build\tests\marionette\tests\layout\base\tests\marionette\selection.py:132:23
[task 2022-09-20T12:23:50.107Z] 12:23:50     INFO -  	@Z:\task_166366707196853\build\tests\marionette\tests\layout\base\tests\marionette\selection.py:134:8
[task 2022-09-20T12:23:50.108Z] 12:23:50     INFO -  	evaluate.sandbox/promise<@chrome://remote/content/marionette/evaluate.js:165:10
[task 2022-09-20T12:23:50.108Z] 12:23:50     INFO -  	evaluate.sandbox@chrome://remote/content/marionette/evaluate.js:141:17
[task 2022-09-20T12:23:50.108Z] 12:23:50     INFO -  	executeScript@chrome://remote/content/marionette/actors/MarionetteCommandsChild.jsm:224:26
[task 2022-09-20T12:23:50.108Z] 12:23:50     INFO -  	receiveMessage@chrome://remote/content/marionette/actors/MarionetteCommandsChild.jsm:96:31
[task 2022-09-20T12:23:50.108Z] 12:23:50     INFO -  Traceback (most recent call last):
[task 2022-09-20T12:23:50.109Z] 12:23:50     INFO -    File "Z:\task_166366707196853\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 202, in run
[task 2022-09-20T12:23:50.109Z] 12:23:50     INFO -      testMethod()
[task 2022-09-20T12:23:50.109Z] 12:23:50     INFO -    File "Z:\task_166366707196853\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 52, in wrapper
[task 2022-09-20T12:23:50.110Z] 12:23:50     INFO -      return func(self, *args, **kwargs)
[task 2022-09-20T12:23:50.110Z] 12:23:50     INFO -    File "Z:\task_166366707196853\build\tests\marionette\tests\layout\base\tests\marionette\test_accessiblecaret_selection_mode.py", line 277, in test_minimum_select_one_character
[task 2022-09-20T12:23:50.111Z] 12:23:50     INFO -      self._test_minimum_select_one_character(el)
[task 2022-09-20T12:23:50.111Z] 12:23:50     INFO -    File "Z:\task_166366707196853\build\tests\marionette\tests\layout\base\tests\marionette\test_accessiblecaret_selection_mode.py", line 302, in _test_minimum_select_one_character
[task 2022-09-20T12:23:50.111Z] 12:23:50     INFO -      self.long_press_on_word(el, 0)
[task 2022-09-20T12:23:50.112Z] 12:23:50     INFO -    File "Z:\task_166366707196853\build\tests\marionette\tests\layout\base\tests\marionette\test_accessiblecaret_selection_mode.py", line 164, in long_press_on_word
[task 2022-09-20T12:23:50.112Z] 12:23:50     INFO -      x, y = self.word_location(el, wordOrdinal)
[task 2022-09-20T12:23:50.112Z] 12:23:50     INFO -    File "Z:\task_166366707196853\build\tests\marionette\tests\layout\base\tests\marionette\test_accessiblecaret_selection_mode.py", line 115, in word_location
[task 2022-09-20T12:23:50.112Z] 12:23:50     INFO -      sel.move_cursor_by_offset(offset)
[task 2022-09-20T12:23:50.113Z] 12:23:50     INFO -    File "Z:\task_166366707196853\build\tests\marionette\tests\layout\base\tests\marionette\selection.py", line 129, in move_cursor_by_offset
[task 2022-09-20T12:23:50.113Z] 12:23:50     INFO -      self.element.marionette.execute_script(
[task 2022-09-20T12:23:50.113Z] 12:23:50     INFO -    File "Z:\task_166366707196853\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1726, in execute_script
[task 2022-09-20T12:23:50.113Z] 12:23:50     INFO -      rv = self._send_message("WebDriver:ExecuteScript", body, key="value")
[task 2022-09-20T12:23:50.114Z] 12:23:50     INFO -    File "Z:\task_166366707196853\build\venv\lib\site-packages\marionette_driver\decorators.py", line 27, in _
[task 2022-09-20T12:23:50.114Z] 12:23:50     INFO -      return func(*args, **kwargs)
[task 2022-09-20T12:23:50.114Z] 12:23:50     INFO -    File "Z:\task_166366707196853\build\venv\lib\site-packages\marionette_driver\marionette.py", line 668, in _send_message
[task 2022-09-20T12:23:50.114Z] 12:23:50     INFO -      self._handle_error(err)
[task 2022-09-20T12:23:50.115Z] 12:23:50     INFO -    File "Z:\task_166366707196853\build\venv\lib\site-packages\marionette_driver\marionette.py", line 680, in _handle_error
[task 2022-09-20T12:23:50.115Z] 12:23:50     INFO -      raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2022-09-20T12:23:50.115Z] 12:23:50     INFO -  TEST-INFO took 493ms
[task 2022-09-20T12:23:50.116Z] 12:23:50     INFO -  1663676630108	Marionette	DEBUG	77 -> [0,46,"WebDriver:ReleaseActions",{}]
[task 2022-09-20T12:23:50.130Z] 12:23:50     INFO -  1663676630137	Marionette	DEBUG	77 <- [1,46,null,{"value":null}]
[task 2022-09-20T12:23:50.145Z] 12:23:50     INFO -  1663676630144	Marionette	DEBUG	77 -> [0,47,"WebDriver:DeleteSession",{}]
[task 2022-09-20T12:23:50.147Z] 12:23:50     INFO -  1663676630147	Marionette	DEBUG	77 <- [1,47,null,{"value":null}]
[task 2022-09-20T12:23:50.149Z] 12:23:50     INFO -  1663676630148	Marionette	DEBUG	Closed connection 77
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.