Filed by: ccozmuta [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=379647047&repo=mozilla-beta
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/SQUbFu24TR6UAM8EBMgrww/runs/0/artifacts/public/logs/live_backing.log
[task 2022-05-30T15:23:13.872Z] 15:23:13 INFO - TEST-START | layout/base/tests/marionette/test_accessiblecaret_cursor_mode.py AccessibleCaretCursorModeTestCase.test_move_cursor_to_the_right_by_one_character_textarea
[task 2022-05-30T15:23:13.872Z] 15:23:13 INFO - 1653924193871 Marionette DEBUG Accepted connection 21 from 127.0.0.1:51413
[task 2022-05-30T15:23:13.872Z] 15:23:13 INFO - 1653924193872 Marionette DEBUG 21 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2022-05-30T15:23:13.872Z] 15:23:13 INFO - 1653924193872 Marionette DEBUG Waiting for initial application window
[task 2022-05-30T15:23:13.873Z] 15:23:13 INFO - 1653924193872 RemoteAgent TRACE [44] Document already finished loading: http://127.0.0.1:49214/layout/test_carets_cursor.html
[task 2022-05-30T15:23:13.874Z] 15:23:13 INFO - 1653924193873 Marionette DEBUG 21 <- [1,1,null,{"sessionId":"ae11d951-0fee-40ac-9962-b2aef2d9462d","capabilities":{"browserName":"firefox","browserVersion":"102.0 ... ownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2022-05-30T15:23:13.874Z] 15:23:13 INFO - 1653924193874 Marionette DEBUG 21 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2022-05-30T15:23:13.874Z] 15:23:13 INFO - 1653924193874 Marionette DEBUG 21 <- [1,2,null,{"value":null}]
[task 2022-05-30T15:23:13.875Z] 15:23:13 INFO - 1653924193874 Marionette DEBUG 21 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2022-05-30T15:23:13.875Z] 15:23:13 INFO - 1653924193875 Marionette DEBUG 21 <- [1,3,null,{"value":null}]
[task 2022-05-30T15:23:13.876Z] 15:23:13 INFO - 1653924193876 Marionette DEBUG 21 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2022-05-30T15:23:13.876Z] 15:23:13 INFO - 1653924193876 Marionette DEBUG 21 <- [1,4,null,{"value":null}]
[task 2022-05-30T15:23:13.877Z] 15:23:13 INFO - 1653924193877 Marionette DEBUG 21 -> [0,5,"Marionette:GetContext",{}]
[task 2022-05-30T15:23:13.878Z] 15:23:13 INFO - 1653924193877 Marionette DEBUG 21 <- [1,5,null,{"value":"content"}]
[task 2022-05-30T15:23:13.878Z] 15:23:13 INFO - 1653924193878 Marionette DEBUG 21 -> [0,6,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-05-30T15:23:13.878Z] 15:23:13 INFO - 1653924193878 Marionette DEBUG 21 <- [1,6,null,{"value":null}]
[task 2022-05-30T15:23:13.879Z] 15:23:13 INFO - 1653924193878 Marionette DEBUG 21 -> [0,7,"WebDriver:ExecuteScript",{"script":"const { Preferences } = ChromeUtils.import(\n \"resource://gre/mod ... ox":true,"sandbox":"default","line":849,"filename":"../../venv/lib/python3.7/site-packages/marionette_driver/marionette.py"}]
[task 2022-05-30T15:23:13.879Z] 15:23:13 INFO - 1653924193879 Marionette TRACE [9] MarionetteCommands actor created for window id 4
[task 2022-05-30T15:23:13.880Z] 15:23:13 INFO - 1653924193880 Marionette DEBUG 21 <- [1,7,null,{"value":null}]
[task 2022-05-30T15:23:13.881Z] 15:23:13 INFO - 1653924193880 Marionette DEBUG 21 -> [0,8,"Marionette:SetContext",{"value":"content"}]
[task 2022-05-30T15:23:13.881Z] 15:23:13 INFO - 1653924193881 Marionette DEBUG 21 <- [1,8,null,{"value":null}]
[task 2022-05-30T15:23:13.882Z] 15:23:13 INFO - 1653924193881 Marionette DEBUG 21 -> [0,9,"Marionette:GetContext",{}]
[task 2022-05-30T15:23:13.882Z] 15:23:13 INFO - 1653924193882 Marionette DEBUG 21 <- [1,9,null,{"value":"content"}]
<...>
[task 2022-05-30T15:23:14.504Z] 15:23:14 INFO - 1653924194503 Marionette DEBUG 21 -> [0,60,"Marionette:SetContext",{"value":"content"}]
[task 2022-05-30T15:23:14.504Z] 15:23:14 INFO - 1653924194504 Marionette DEBUG 21 <- [1,60,null,{"value":null}]
[task 2022-05-30T15:23:14.504Z] 15:23:14 INFO - 1653924194504 Marionette DEBUG 21 -> [0,61,"WebDriver:GetPageSource",{}]
[task 2022-05-30T15:23:14.505Z] 15:23:14 INFO - 1653924194504 Marionette DEBUG 21 <- [1,61,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 2022-05-30T15:23:14.505Z] 15:23:14 INFO - 1653924194505 Marionette DEBUG 21 -> [0,62,"Marionette:SetContext",{"value":"content"}]
[task 2022-05-30T15:23:14.505Z] 15:23:14 INFO - 1653924194505 Marionette DEBUG 21 <- [1,62,null,{"value":null}]
[task 2022-05-30T15:23:14.565Z] 15:23:14 INFO - TEST-UNEXPECTED-FAIL | layout/base/tests/marionette/test_accessiblecaret_cursor_mode.py AccessibleCaretCursorModeTestCase.test_move_cursor_to_the_right_by_one_character_textarea | AssertionError: 'A!BCDEFGHI' != 'ABCDEFG!'
[task 2022-05-30T15:23:14.565Z] 15:23:14 INFO - - A!BCDEFGHI
[task 2022-05-30T15:23:14.565Z] 15:23:14 INFO - ? - ^^
[task 2022-05-30T15:23:14.565Z] 15:23:14 INFO - + ABCDEFG!
[task 2022-05-30T15:23:14.565Z] 15:23:14 INFO - ? ^
[task 2022-05-30T15:23:14.566Z] 15:23:14 INFO - Traceback (most recent call last):
[task 2022-05-30T15:23:14.566Z] 15:23:14 INFO - File "/opt/worker/tasks/task_165392358511905/build/venv/lib/python3.7/site-packages/marionette_harness/marionette_test/testcases.py", line 202, in run
[task 2022-05-30T15:23:14.566Z] 15:23:14 INFO - testMethod()
[task 2022-05-30T15:23:14.566Z] 15:23:14 INFO - File "/opt/worker/tasks/task_165392358511905/build/venv/lib/python3.7/site-packages/marionette_harness/marionette_test/testcases.py", line 52, in wrapper
[task 2022-05-30T15:23:14.566Z] 15:23:14 INFO - return func(self, *args, **kwargs)
[task 2022-05-30T15:23:14.566Z] 15:23:14 INFO - File "/opt/worker/tasks/task_165392358511905/build/tests/marionette/tests/layout/base/tests/marionette/test_accessiblecaret_cursor_mode.py", line 94, in test_move_cursor_to_the_right_by_one_character
[task 2022-05-30T15:23:14.566Z] 15:23:14 INFO - self.assertEqual(target_content, sel.content)
[task 2022-05-30T15:23:14.566Z] 15:23:14 INFO - TEST-INFO took 635ms
[task 2022-05-30T15:23:14.566Z] 15:23:14 INFO - 1653924194512 Marionette DEBUG 21 -> [0,63,"WebDriver:ReleaseActions",{}]
[task 2022-05-30T15:23:14.566Z] 15:23:14 INFO - 1653924194513 Marionette DEBUG 21 <- [1,63,null,{"value":null}]
[task 2022-05-30T15:23:14.567Z] 15:23:14 INFO - 1653924194513 Marionette DEBUG 21 -> [0,64,"WebDriver:DeleteSession",{}]
[task 2022-05-30T15:23:14.567Z] 15:23:14 INFO - 1653924194514 Marionette DEBUG 21 <- [1,64,null,{"value":null}]
[task 2022-05-30T15:23:14.567Z] 15:23:14 INFO - 1653924194514 Marionette DEBUG Closed connection 21
[task 2022-05-30T15:23:14.567Z] 15:23:14 INFO - TEST-START | layout/base/tests/marionette/test_accessiblecaret_selection_mode.py AccessibleCaretSelectionModeTestCase.test_carets_initialized_in_display_none
Description
•