Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=380091134&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/MnOBIp5GQOOAC1DZgU7rZQ/runs/0/artifacts/public/logs/live_backing.log
[task 2022-06-02T23:45:32.331Z] 23:45:32 INFO - TEST-START | layout/base/tests/marionette/test_accessiblecaret_cursor_mode.py AccessibleCaretCursorModeTestCase.test_move_cursor_to_the_right_by_one_character_input
[task 2022-06-02T23:45:32.332Z] 23:45:32 INFO - 1654213532331 Marionette DEBUG Accepted connection 20 from 127.0.0.1:51448
[task 2022-06-02T23:45:32.332Z] 23:45:32 INFO - 1654213532332 Marionette DEBUG 20 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2022-06-02T23:45:32.333Z] 23:45:32 INFO - 1654213532332 Marionette DEBUG Waiting for initial application window
[task 2022-06-02T23:45:32.333Z] 23:45:32 INFO - 1654213532333 RemoteAgent TRACE [44] Document already finished loading: http://127.0.0.1:49236/layout/test_carets_cursor.html
[task 2022-06-02T23:45:32.335Z] 23:45:32 INFO - 1654213532334 Marionette DEBUG 20 <- [1,1,null,{"sessionId":"c3304495-308d-406e-ba10-a50be2fdbcfd","capabilities":{"browserName":"firefox","browserVersion":"103.0 ... ownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2022-06-02T23:45:32.335Z] 23:45:32 INFO - 1654213532335 Marionette DEBUG 20 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2022-06-02T23:45:32.336Z] 23:45:32 INFO - 1654213532335 Marionette DEBUG 20 <- [1,2,null,{"value":null}]
[task 2022-06-02T23:45:32.336Z] 23:45:32 INFO - 1654213532336 Marionette DEBUG 20 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2022-06-02T23:45:32.337Z] 23:45:32 INFO - 1654213532336 Marionette DEBUG 20 <- [1,3,null,{"value":null}]
[task 2022-06-02T23:45:32.339Z] 23:45:32 INFO - 1654213532338 Marionette DEBUG 20 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2022-06-02T23:45:32.339Z] 23:45:32 INFO - 1654213532338 Marionette DEBUG 20 <- [1,4,null,{"value":null}]
[task 2022-06-02T23:45:32.341Z] 23:45:32 INFO - 1654213532340 Marionette DEBUG 20 -> [0,5,"Marionette:GetContext",{}]
[task 2022-06-02T23:45:32.341Z] 23:45:32 INFO - 1654213532340 Marionette DEBUG 20 <- [1,5,null,{"value":"content"}]
[task 2022-06-02T23:45:32.351Z] 23:45:32 INFO - 1654213532350 Marionette DEBUG 20 -> [0,6,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-06-02T23:45:32.351Z] 23:45:32 INFO - 1654213532350 Marionette DEBUG 20 <- [1,6,null,{"value":null}]
[task 2022-06-02T23:45:32.352Z] 23:45:32 INFO - 1654213532351 Marionette DEBUG 20 -> [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-06-02T23:45:32.352Z] 23:45:32 INFO - 1654213532352 Marionette TRACE [9] MarionetteCommands actor created for window id 4
[task 2022-06-02T23:45:32.353Z] 23:45:32 INFO - 1654213532353 Marionette DEBUG 20 <- [1,7,null,{"value":null}]
[task 2022-06-02T23:45:32.355Z] 23:45:32 INFO - 1654213532354 Marionette DEBUG 20 -> [0,8,"Marionette:SetContext",{"value":"content"}]
[task 2022-06-02T23:45:32.355Z] 23:45:32 INFO - 1654213532355 Marionette DEBUG 20 <- [1,8,null,{"value":null}]
[task 2022-06-02T23:45:32.356Z] 23:45:32 INFO - 1654213532355 Marionette DEBUG 20 -> [0,9,"Marionette:GetContext",{}]
[task 2022-06-02T23:45:32.356Z] 23:45:32 INFO - 1654213532355 Marionette DEBUG 20 <- [1,9,null,{"value":"content"}]
[task 2022-06-02T23:45:32.356Z] 23:45:32 INFO - 1654213532356 Marionette DEBUG 20 -> [0,10,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-06-02T23:45:32.357Z] 23:45:32 INFO - 1654213532356 Marionette DEBUG 20 <- [1,10,null,{"value":null}]
[task 2022-06-02T23:45:32.357Z] 23:45:32 INFO - 1654213532357 Marionette DEBUG 20 -> [0,11,"WebDriver:ExecuteScript",{"script":"const { Preferences } = ChromeUtils.import(\n \"resource://gre/mo ... ox":true,"sandbox":"default","line":849,"filename":"../../venv/lib/python3.7/site-packages/marionette_driver/marionette.py"}]
[task 2022-06-02T23:45:32.359Z] 23:45:32 INFO - 1654213532358 Marionette DEBUG 20 <- [1,11,null,{"value":null}]
[task 2022-06-02T23:45:32.359Z] 23:45:32 INFO - 1654213532359 Marionette DEBUG 20 -> [0,12,"Marionette:SetContext",{"value":"content"}]
[task 2022-06-02T23:45:32.360Z] 23:45:32 INFO - 1654213532359 Marionette DEBUG 20 <- [1,12,null,{"value":null}]
[task 2022-06-02T23:45:32.360Z] 23:45:32 INFO - 1654213532360 Marionette DEBUG 20 -> [0,13,"Marionette:GetContext",{}]
[task 2022-06-02T23:45:32.360Z] 23:45:32 INFO - 1654213532360 Marionette DEBUG 20 <- [1,13,null,{"value":"content"}]
[task 2022-06-02T23:45:32.361Z] 23:45:32 INFO - 1654213532360 Marionette DEBUG 20 -> [0,14,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-06-02T23:45:32.361Z] 23:45:32 INFO - 1654213532360 Marionette DEBUG 20 <- [1,14,null,{"value":null}]
[task 2022-06-02T23:45:32.361Z] 23:45:32 INFO - 1654213532361 Marionette DEBUG 20 -> [0,15,"WebDriver:ExecuteScript",{"script":"const { Preferences } = ChromeUtils.import(\n \"resource://gre/mo ... ox":true,"sandbox":"default","line":849,"filename":"../../venv/lib/python3.7/site-packages/marionette_driver/marionette.py"}]
[task 2022-06-02T23:45:32.362Z] 23:45:32 INFO - 1654213532362 Marionette DEBUG 20 <- [1,15,null,{"value":null}]
[task 2022-06-02T23:45:32.363Z] 23:45:32 INFO - 1654213532363 Marionette DEBUG 20 -> [0,16,"Marionette:SetContext",{"value":"content"}]
[task 2022-06-02T23:45:32.363Z] 23:45:32 INFO - 1654213532363 Marionette DEBUG 20 <- [1,16,null,{"value":null}]
[task 2022-06-02T23:45:32.364Z] 23:45:32 INFO - 1654213532363 Marionette DEBUG 20 -> [0,17,"WebDriver:Navigate",{"url":"http://127.0.0.1:49236/layout/test_carets_cursor.html"}]
[task 2022-06-02T23:45:32.365Z] 23:45:32 INFO - 1654213532365 Marionette TRACE [44] Received event beforeunload for http://127.0.0.1:49236/layout/test_carets_cursor.html
[task 2022-06-02T23:45:32.369Z] 23:45:32 INFO - 1654213532369 Marionette TRACE [44] Received event pagehide for http://127.0.0.1:49236/layout/test_carets_cursor.html
[task 2022-06-02T23:45:32.378Z] 23:45:32 INFO - 1654213532378 Marionette TRACE [44] Received event DOMContentLoaded for http://127.0.0.1:49236/layout/test_carets_cursor.html
[task 2022-06-02T23:45:32.379Z] 23:45:32 INFO - 1654213532378 Marionette TRACE [44] Received event pageshow for http://127.0.0.1:49236/layout/test_carets_cursor.html
[task 2022-06-02T23:45:32.379Z] 23:45:32 INFO - 1654213532379 Marionette DEBUG 20 <- [1,17,null,{"value":null}]
[task 2022-06-02T23:45:32.383Z] 23:45:32 INFO - 1654213532382 Marionette DEBUG 20 -> [0,18,"WebDriver:FindElement",{"value":"input","using":"id"}]
[task 2022-06-02T23:45:32.383Z] 23:45:32 INFO - 1654213532383 Marionette TRACE [44] MarionetteCommands actor created for window id 6442450963
[task 2022-06-02T23:45:32.384Z] 23:45:32 INFO - 1654213532383 Marionette DEBUG 20 <- [1,18,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"65238d80-3e0b-4f9e-af41-e67bce9b1a3d"}}]
[task 2022-06-02T23:45:32.384Z] 23:45:32 INFO - 1654213532384 Marionette DEBUG 20 -> [0,19,"WebDriver:GetElementTagName",{"id":"65238d80-3e0b-4f9e-af41-e67bce9b1a3d"}]
[task 2022-06-02T23:45:32.385Z] 23:45:32 INFO - 1654213532384 Marionette DEBUG 20 <- [1,19,null,{"value":"input"}]
[task 2022-06-02T23:45:32.385Z] 23:45:32 INFO - 1654213532385 Marionette DEBUG 20 -> [0,20,"WebDriver:GetElementProperty",{"id":"65238d80-3e0b-4f9e-af41-e67bce9b1a3d","name":"value"}]
[task 2022-06-02T23:45:32.386Z] 23:45:32 INFO - 1654213532385 Marionette DEBUG 20 <- [1,20,null,{"value":"ABCDEFGHI"}]
[task 2022-06-02T23:45:32.386Z] 23:45:32 INFO - 1654213532386 Marionette DEBUG 20 -> [0,21,"Marionette:SingleTap",{"id":"65238d80-3e0b-4f9e-af41-e67bce9b1a3d","x":null,"y":null}]
[task 2022-06-02T23:45:32.389Z] 23:45:32 INFO - 1654213532389 Marionette DEBUG Emitting mousemove mouse event at coordinates (95.16666412353516, 31.333328247070312) relative to the viewport, button: undefined, clickCount: undefined
[task 2022-06-02T23:45:32.390Z] 23:45:32 INFO - 1654213532389 Marionette DEBUG Emitting mousedown mouse event at coordinates (95.16666412353516, 31.333328247070312) relative to the viewport, button: undefined, clickCount: undefined
[task 2022-06-02T23:45:32.391Z] 23:45:32 INFO - 1654213532390 Marionette DEBUG Emitting mouseup mouse event at coordinates (95.16666412353516, 31.333328247070312) relative to the viewport, button: undefined, clickCount: undefined
[task 2022-06-02T23:45:32.393Z] 23:45:32 INFO - DEBUG: Adding blocker UserInteractionTimer 19 for document 11d6a9000 for phase xpcom-will-shutdown
[task 2022-06-02T23:45:32.393Z] 23:45:32 INFO - 1654213532393 Marionette DEBUG 20 <- [1,21,null,{"value":null}]
[task 2022-06-02T23:45:32.394Z] 23:45:32 INFO - 1654213532394 Marionette DEBUG 20 -> [0,22,"WebDriver:GetElementTagName",{"id":"65238d80-3e0b-4f9e-af41-e67bce9b1a3d"}]
[task 2022-06-02T23:45:32.417Z] 23:45:32 INFO - 1654213532416 Marionette DEBUG 20 <- [1,22,null,{"value":"input"}]
[task 2022-06-02T23:45:32.417Z] 23:45:32 INFO - 1654213532417 Marionette DEBUG 20 -> [0,23,"WebDriver:ExecuteScript",{"script":"arguments[0].setSelectionRange(0, 0);","args":[{"element-6066-11e4-a52e-4f735466ce ... 41-e67bce9b1a3d"}],"newSandbox":true,"sandbox":null,"line":142,"filename":"tests/layout/base/tests/marionette/selection.py"}]
[task 2022-06-02T23:45:32.419Z] 23:45:32 INFO - 1654213532418 Marionette DEBUG 20 <- [1,23,null,{"value":null}]
[task 2022-06-02T23:45:32.419Z] 23:45:32 INFO - 1654213532419 Marionette DEBUG 20 -> [0,24,"WebDriver:GetElementTagName",{"id":"65238d80-3e0b-4f9e-af41-e67bce9b1a3d"}]
[task 2022-06-02T23:45:32.419Z] 23:45:32 INFO - 1654213532419 Marionette DEBUG 20 <- [1,24,null,{"value":"input"}]
[task 2022-06-02T23:45:32.420Z] 23:45:32 INFO - 1654213532420 Marionette DEBUG 20 -> [0,25,"WebDriver:ExecuteScript",{"script":"var sel = arguments[0].editor.selection;return sel.rangeCount;","args":[{"element- ... 67bce9b1a3d"}],"newSandbox":true,"sandbox":"system","line":178,"filename":"tests/layout/base/tests/marionette/selection.py"}]
[task 2022-06-02T23:45:32.441Z] 23:45:32 INFO - 1654213532441 Marionette DEBUG 20 <- [1,25,null,{"value":1}]
[task 2022-06-02T23:45:32.463Z] 23:45:32 INFO - 1654213532462 Marionette DEBUG 20 -> [0,26,"WebDriver:GetElementTagName",{"id":"65238d80-3e0b-4f9e-af41-e67bce9b1a3d"}]
[task 2022-06-02T23:45:32.463Z] 23:45:32 INFO - 1654213532463 Marionette DEBUG 20 <- [1,26,null,{"value":"input"}]
[task 2022-06-02T23:45:32.464Z] 23:45:32 INFO - 1654213532464 Marionette DEBUG 20 -> [0,27,"WebDriver:ExecuteScript",{"script":"var sel = arguments[0].editor.selection;return sel.getRangeAt(0).getClientRects(); ... 67bce9b1a3d"}],"newSandbox":true,"sandbox":"system","line":171,"filename":"tests/layout/base/tests/marionette/selection.py"}]
[task 2022-06-02T23:45:32.466Z] 23:45:32 INFO - 1654213532465 Marionette DEBUG 20 <- [1,27,null,{"value":{"0":{"x":12,"y":23.333328247070312,"width":0,"height":16,"top":23.333328247070312,"right":12,"bottom":39.33332824707031,"left":12},"item":{},"length":1}}]
[task 2022-06-02T23:45:32.466Z] 23:45:32 INFO - 1654213532466 Marionette DEBUG 20 -> [0,28,"WebDriver:GetElementRect",{"id":"65238d80-3e0b-4f9e-af41-e67bce9b1a3d"}]
[task 2022-06-02T23:45:32.467Z] 23:45:32 INFO - 1654213532467 Marionette DEBUG 20 <- [1,28,null,{"x":8,"y":20.333328247070312,"width":174.3333282470703,"height":22}]
[task 2022-06-02T23:45:32.467Z] 23:45:32 INFO - 1654213532467 Marionette DEBUG 20 -> [0,29,"WebDriver:GetElementRect",{"id":"65238d80-3e0b-4f9e-af41-e67bce9b1a3d"}]
[task 2022-06-02T23:45:32.468Z] 23:45:32 INFO - 1654213532468 Marionette DEBUG 20 <- [1,29,null,{"x":8,"y":20.333328247070312,"width":174.3333282470703,"height":22}]
[task 2022-06-02T23:45:32.469Z] 23:45:32 INFO - 1654213532468 Marionette DEBUG 20 -> [0,30,"WebDriver:GetElementProperty",{"id":"65238d80-3e0b-4f9e-af41-e67bce9b1a3d","name":"dir"}]
[task 2022-06-02T23:45:32.469Z] 23:45:32 INFO - 1654213532469 Marionette DEBUG 20 <- [1,30,null,{"value":""}]
[task 2022-06-02T23:45:32.470Z] 23:45:32 INFO - 1654213532469 Marionette DEBUG 20 -> [0,31,"WebDriver:GetElementTagName",{"id":"65238d80-3e0b-4f9e-af41-e67bce9b1a3d"}]
[task 2022-06-02T23:45:32.470Z] 23:45:32 INFO - 1654213532470 Marionette DEBUG 20 <- [1,31,null,{"value":"input"}]
[task 2022-06-02T23:45:32.471Z] 23:45:32 INFO - 1654213532470 Marionette DEBUG 20 -> [0,32,"WebDriver:ExecuteScript",{"script":"var sel = arguments[0].editor.selection;return sel.rangeCount;","args":[{"element- ... 67bce9b1a3d"}],"newSandbox":true,"sandbox":"system","line":178,"filename":"tests/layout/base/tests/marionette/selection.py"}]
[task 2022-06-02T23:45:32.472Z] 23:45:32 INFO - 1654213532471 Marionette DEBUG 20 <- [1,32,null,{"value":1}]
[task 2022-06-02T23:45:32.472Z] 23:45:32 INFO - 1654213532472 Marionette DEBUG 20 -> [0,33,"WebDriver:GetElementTagName",{"id":"65238d80-3e0b-4f9e-af41-e67bce9b1a3d"}]
[task 2022-06-02T23:45:32.473Z] 23:45:32 INFO - 1654213532472 Marionette DEBUG 20 <- [1,33,null,{"value":"input"}]
[task 2022-06-02T23:45:32.474Z] 23:45:32 INFO - 1654213532473 Marionette DEBUG 20 -> [0,34,"WebDriver:ExecuteScript",{"script":"var sel = arguments[0].editor.selection;return sel.getRangeAt(0).getClientRects(); ... 67bce9b1a3d"}],"newSandbox":true,"sandbox":"system","line":171,"filename":"tests/layout/base/tests/marionette/selection.py"}]
[task 2022-06-02T23:45:32.475Z] 23:45:32 INFO - 1654213532474 Marionette DEBUG 20 <- [1,34,null,{"value":{"0":{"x":12,"y":23.333328247070312,"width":0,"height":16,"top":23.333328247070312,"right":12,"bottom":39.33332824707031,"left":12},"item":{},"length":1}}]
[task 2022-06-02T23:45:32.475Z] 23:45:32 INFO - 1654213532475 Marionette DEBUG 20 -> [0,35,"WebDriver:GetElementRect",{"id":"65238d80-3e0b-4f9e-af41-e67bce9b1a3d"}]
[task 2022-06-02T23:45:32.476Z] 23:45:32 INFO - 1654213532475 Marionette DEBUG 20 <- [1,35,null,{"x":8,"y":20.333328247070312,"width":174.3333282470703,"height":22}]
[task 2022-06-02T23:45:32.476Z] 23:45:32 INFO - 1654213532476 Marionette DEBUG 20 -> [0,36,"WebDriver:GetElementRect",{"id":"65238d80-3e0b-4f9e-af41-e67bce9b1a3d"}]
[task 2022-06-02T23:45:32.477Z] 23:45:32 INFO - 1654213532477 Marionette DEBUG 20 <- [1,36,null,{"x":8,"y":20.333328247070312,"width":174.3333282470703,"height":22}]
[task 2022-06-02T23:45:32.478Z] 23:45:32 INFO - 1654213532477 Marionette DEBUG 20 -> [0,37,"WebDriver:GetElementProperty",{"id":"65238d80-3e0b-4f9e-af41-e67bce9b1a3d","name":"dir"}]
[task 2022-06-02T23:45:32.478Z] 23:45:32 INFO - 1654213532478 Marionette DEBUG 20 <- [1,37,null,{"value":""}]
[task 2022-06-02T23:45:32.479Z] 23:45:32 INFO - 1654213532478 Marionette DEBUG 20 -> [0,38,"WebDriver:GetElementTagName",{"id":"65238d80-3e0b-4f9e-af41-e67bce9b1a3d"}]
[task 2022-06-02T23:45:32.479Z] 23:45:32 INFO - 1654213532479 Marionette DEBUG 20 <- [1,38,null,{"value":"input"}]
[task 2022-06-02T23:45:32.480Z] 23:45:32 INFO - 1654213532480 Marionette DEBUG 20 -> [0,39,"WebDriver:ExecuteScript",{"script":"var sel = arguments[0].editor.selection;\n for (let i = 0; i < 1; ++i ... 67bce9b1a3d"}],"newSandbox":true,"sandbox":"system","line":130,"filename":"tests/layout/base/tests/marionette/selection.py"}]
[task 2022-06-02T23:45:32.481Z] 23:45:32 INFO - 1654213532481 Marionette DEBUG 20 <- [1,39,null,{"value":null}]
[task 2022-06-02T23:45:32.482Z] 23:45:32 INFO - 1654213532482 Marionette DEBUG 20 -> [0,40,"WebDriver:GetElementTagName",{"id":"65238d80-3e0b-4f9e-af41-e67bce9b1a3d"}]
[task 2022-06-02T23:45:32.482Z] 23:45:32 INFO - 1654213532482 Marionette DEBUG 20 <- [1,40,null,{"value":"input"}]
[task 2022-06-02T23:45:32.483Z] 23:45:32 INFO - 1654213532483 Marionette DEBUG 20 -> [0,41,"WebDriver:ExecuteScript",{"script":"var sel = arguments[0].editor.selection;return sel.rangeCount;","args":[{"element- ... 67bce9b1a3d"}],"newSandbox":true,"sandbox":"system","line":178,"filename":"tests/layout/base/tests/marionette/selection.py"}]
[task 2022-06-02T23:45:32.484Z] 23:45:32 INFO - 1654213532484 Marionette DEBUG 20 <- [1,41,null,{"value":1}]
[task 2022-06-02T23:45:32.485Z] 23:45:32 INFO - 1654213532484 Marionette DEBUG 20 -> [0,42,"WebDriver:GetElementTagName",{"id":"65238d80-3e0b-4f9e-af41-e67bce9b1a3d"}]
[task 2022-06-02T23:45:32.485Z] 23:45:32 INFO - 1654213532485 Marionette DEBUG 20 <- [1,42,null,{"value":"input"}]
[task 2022-06-02T23:45:32.486Z] 23:45:32 INFO - 1654213532486 Marionette DEBUG 20 -> [0,43,"WebDriver:ExecuteScript",{"script":"var sel = arguments[0].editor.selection;return sel.getRangeAt(0).getClientRects(); ... 67bce9b1a3d"}],"newSandbox":true,"sandbox":"system","line":171,"filename":"tests/layout/base/tests/marionette/selection.py"}]
[task 2022-06-02T23:45:32.488Z] 23:45:32 INFO - 1654213532488 Marionette DEBUG 20 <- [1,43,null,{"value":{"0":{"x":20.899993896484375,"y":23.333328247070312,"width":0,"height":16,"top":23.333328247070312,"right":20.899993896484375,"bottom":39.33332824707031,"left":20.899993896484375},"item":{},"length":1}}]
[task 2022-06-02T23:45:32.488Z] 23:45:32 INFO - 1654213532488 Marionette DEBUG 20 -> [0,44,"WebDriver:GetElementRect",{"id":"65238d80-3e0b-4f9e-af41-e67bce9b1a3d"}]
[task 2022-06-02T23:45:32.513Z] 23:45:32 INFO - 1654213532512 Marionette DEBUG 20 <- [1,44,null,{"x":8,"y":20.333328247070312,"width":174.3333282470703,"height":22}]
[task 2022-06-02T23:45:32.534Z] 23:45:32 INFO - 1654213532534 Marionette DEBUG 20 -> [0,45,"WebDriver:GetElementRect",{"id":"65238d80-3e0b-4f9e-af41-e67bce9b1a3d"}]
[task 2022-06-02T23:45:32.555Z] 23:45:32 INFO - 1654213532554 Marionette DEBUG 20 <- [1,45,null,{"x":8,"y":20.333328247070312,"width":174.3333282470703,"height":22}]
[task 2022-06-02T23:45:32.576Z] 23:45:32 INFO - 1654213532575 Marionette DEBUG 20 -> [0,46,"WebDriver:GetElementProperty",{"id":"65238d80-3e0b-4f9e-af41-e67bce9b1a3d","name":"dir"}]
[task 2022-06-02T23:45:32.598Z] 23:45:32 INFO - 1654213532598 Marionette DEBUG 20 <- [1,46,null,{"value":""}]
[task 2022-06-02T23:45:32.620Z] 23:45:32 INFO - 1654213532619 Marionette DEBUG 20 -> [0,47,"Marionette:SingleTap",{"id":"65238d80-3e0b-4f9e-af41-e67bce9b1a3d","x":4,"y":11}]
[task 2022-06-02T23:45:32.621Z] 23:45:32 INFO - 1654213532620 Marionette DEBUG Emitting mousemove mouse event at coordinates (12, 31.333328247070312) relative to the viewport, button: undefined, clickCount: undefined
[task 2022-06-02T23:45:32.621Z] 23:45:32 INFO - 1654213532621 Marionette DEBUG Emitting mousedown mouse event at coordinates (12, 31.333328247070312) relative to the viewport, button: undefined, clickCount: undefined
[task 2022-06-02T23:45:32.622Z] 23:45:32 INFO - 1654213532621 Marionette DEBUG Emitting mouseup mouse event at coordinates (12, 31.333328247070312) relative to the viewport, button: undefined, clickCount: undefined
[task 2022-06-02T23:45:32.648Z] 23:45:32 INFO - 1654213532647 Marionette DEBUG 20 <- [1,47,null,{"value":null}]
[task 2022-06-02T23:45:32.669Z] 23:45:32 INFO - 1654213532668 Marionette DEBUG 20 -> [0,48,"WebDriver:GetElementRect",{"id":"65238d80-3e0b-4f9e-af41-e67bce9b1a3d"}]
[task 2022-06-02T23:45:32.690Z] 23:45:32 INFO - 1654213532690 Marionette DEBUG 20 <- [1,48,null,{"x":8,"y":20.333328247070312,"width":174.3333282470703,"height":22}]
[task 2022-06-02T23:45:32.691Z] 23:45:32 INFO - 1654213532690 Marionette DEBUG 20 -> [0,49,"WebDriver:PerformActions",{"actions":[{"type":"pointer","id":"pointer_id","actions":[{"type":"pointerMove","x":12,"y": ... {"type":"pointerMove","x":20,"y":44,"duration":200},{"type":"pointerUp","button":0}],"parameters":{"pointerType":"mouse"}}]}]
[task 2022-06-02T23:45:32.939Z] 23:45:32 INFO - 1654213532938 Marionette DEBUG 20 <- [1,49,null,{"value":null}]
[task 2022-06-02T23:45:32.940Z] 23:45:32 INFO - 1654213532939 Marionette DEBUG 20 -> [0,50,"WebDriver:PerformActions",{"actions":[{"type":"key","id":"keyboard_id","actions":[]}]}]
[task 2022-06-02T23:45:32.940Z] 23:45:32 INFO - 1654213532940 Marionette DEBUG 20 <- [1,50,null,{"value":null}]
[task 2022-06-02T23:45:32.941Z] 23:45:32 INFO - 1654213532941 Marionette DEBUG 20 -> [0,51,"WebDriver:PerformActions",{"actions":[{"type":"pointer","id":"pointer_id","actions":[],"parameters":{"pointerType":"mouse"}}]}]
[task 2022-06-02T23:45:32.942Z] 23:45:32 INFO - 1654213532941 Marionette DEBUG 20 <- [1,51,null,{"value":null}]
[task 2022-06-02T23:45:32.942Z] 23:45:32 INFO - 1654213532942 Marionette DEBUG 20 -> [0,52,"WebDriver:PerformActions",{"actions":[{"type":"key","id":"keyboard_id","actions":[{"type":"keyDown","value":"!"},{"type":"keyUp","value":"!"}]}]}]
[task 2022-06-02T23:45:32.947Z] 23:45:32 INFO - 1654213532947 Marionette DEBUG 20 <- [1,52,null,{"value":null}]
[task 2022-06-02T23:45:32.947Z] 23:45:32 INFO - 1654213532947 Marionette DEBUG 20 -> [0,53,"WebDriver:GetElementTagName",{"id":"65238d80-3e0b-4f9e-af41-e67bce9b1a3d"}]
[task 2022-06-02T23:45:32.948Z] 23:45:32 INFO - 1654213532948 Marionette DEBUG 20 <- [1,53,null,{"value":"input"}]
[task 2022-06-02T23:45:32.949Z] 23:45:32 INFO - 1654213532948 Marionette DEBUG 20 -> [0,54,"WebDriver:GetElementProperty",{"id":"65238d80-3e0b-4f9e-af41-e67bce9b1a3d","name":"value"}]
[task 2022-06-02T23:45:32.949Z] 23:45:32 INFO - 1654213532949 Marionette DEBUG 20 <- [1,54,null,{"value":"ABCDEFGHI"}]
[task 2022-06-02T23:45:32.950Z] 23:45:32 INFO - 1654213532950 Marionette DEBUG 20 -> [0,55,"Marionette:GetContext",{}]
[task 2022-06-02T23:45:32.950Z] 23:45:32 INFO - 1654213532950 Marionette DEBUG 20 <- [1,55,null,{"value":"content"}]
[task 2022-06-02T23:45:32.951Z] 23:45:32 INFO - 1654213532950 Marionette DEBUG 20 -> [0,56,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-06-02T23:45:32.951Z] 23:45:32 INFO - 1654213532950 Marionette DEBUG 20 <- [1,56,null,{"value":null}]
[task 2022-06-02T23:45:32.951Z] 23:45:32 INFO - 1654213532951 Marionette DEBUG 20 -> [0,57,"WebDriver:TakeScreenshot",{"id":null,"full":true,"hash":false,"scroll":true}]
[task 2022-06-02T23:45:32.993Z] 23:45:32 INFO - 1654213532992 Marionette DEBUG 20 <- [1,57,null,{"value":"iVBORw0KGgoAAAANSUhEUgAABQAAAAPNCAYAAADWQsenAAAgAElEQVR4XuydB4AURdaAH0vOimSQICCoiAKKICoiwXiAip4BEyhnQMUA ... AAAQIECBAgQIAAAQIECBC4ERAAb17hEAIECBAgQIAAAQIECBAgQIAAAQK9gADYm1okQIAAAQIECBAgQIAAAQIECBAgcCMwRh9OjzS9fG0AAAAASUVORK5CYII="}]
[task 2022-06-02T23:45:32.994Z] 23:45:32 INFO - 1654213532994 Marionette DEBUG 20 -> [0,58,"Marionette:SetContext",{"value":"content"}]
[task 2022-06-02T23:45:32.994Z] 23:45:32 INFO - 1654213532994 Marionette DEBUG 20 <- [1,58,null,{"value":null}]
[task 2022-06-02T23:45:32.995Z] 23:45:32 INFO - 1654213532994 Marionette DEBUG 20 -> [0,59,"Marionette:GetContext",{}]
[task 2022-06-02T23:45:32.995Z] 23:45:32 INFO - 1654213532994 Marionette DEBUG 20 <- [1,59,null,{"value":"content"}]
[task 2022-06-02T23:45:32.995Z] 23:45:32 INFO - 1654213532995 Marionette DEBUG 20 -> [0,60,"Marionette:SetContext",{"value":"content"}]
[task 2022-06-02T23:45:32.995Z] 23:45:32 INFO - 1654213532995 Marionette DEBUG 20 <- [1,60,null,{"value":null}]
[task 2022-06-02T23:45:32.996Z] 23:45:32 INFO - 1654213532995 Marionette DEBUG 20 -> [0,61,"WebDriver:GetPageSource",{}]
[task 2022-06-02T23:45:32.997Z] 23:45:32 INFO - 1654213532996 Marionette DEBUG 20 <- [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-06-02T23:45:32.997Z] 23:45:32 INFO - 1654213532997 Marionette DEBUG 20 -> [0,62,"Marionette:SetContext",{"value":"content"}]
[task 2022-06-02T23:45:32.997Z] 23:45:32 INFO - 1654213532997 Marionette DEBUG 20 <- [1,62,null,{"value":null}]
[task 2022-06-02T23:45:33.061Z] 23:45:33 INFO - TEST-UNEXPECTED-FAIL | layout/base/tests/marionette/test_accessiblecaret_cursor_mode.py AccessibleCaretCursorModeTestCase.test_move_cursor_to_the_right_by_one_character_input | AssertionError: 'A!BCDEFGHI' != 'ABCDEFGHI'
[task 2022-06-02T23:45:33.061Z] 23:45:33 INFO - - A!BCDEFGHI
[task 2022-06-02T23:45:33.061Z] 23:45:33 INFO - ? -
[task 2022-06-02T23:45:33.061Z] 23:45:33 INFO - + ABCDEFGHI
[task 2022-06-02T23:45:33.062Z] 23:45:33 INFO - Traceback (most recent call last):
[task 2022-06-02T23:45:33.062Z] 23:45:33 INFO - File "/opt/worker/tasks/task_165421235088227/build/venv/lib/python3.7/site-packages/marionette_harness/marionette_test/testcases.py", line 202, in run
[task 2022-06-02T23:45:33.062Z] 23:45:33 INFO - testMethod()
[task 2022-06-02T23:45:33.062Z] 23:45:33 INFO - File "/opt/worker/tasks/task_165421235088227/build/venv/lib/python3.7/site-packages/marionette_harness/marionette_test/testcases.py", line 52, in wrapper
[task 2022-06-02T23:45:33.062Z] 23:45:33 INFO - return func(self, *args, **kwargs)
[task 2022-06-02T23:45:33.062Z] 23:45:33 INFO - File "/opt/worker/tasks/task_165421235088227/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-06-02T23:45:33.062Z] 23:45:33 INFO - self.assertEqual(target_content, sel.content)
[task 2022-06-02T23:45:33.062Z] 23:45:33 INFO - TEST-INFO took 667ms
[task 2022-06-02T23:45:33.062Z] 23:45:33 INFO - 1654213533003 Marionette DEBUG 20 -> [0,63,"WebDriver:ReleaseActions",{}]
[task 2022-06-02T23:45:33.062Z] 23:45:33 INFO - 1654213533004 Marionette DEBUG 20 <- [1,63,null,{"value":null}]
[task 2022-06-02T23:45:33.063Z] 23:45:33 INFO - 1654213533004 Marionette DEBUG 20 -> [0,64,"WebDriver:DeleteSession",{}]
[task 2022-06-02T23:45:33.063Z] 23:45:33 INFO - 1654213533005 Marionette DEBUG 20 <- [1,64,null,{"value":null}]
[task 2022-06-02T23:45:33.063Z] 23:45:33 INFO - TEST-START | layout/base/tests/marionette/test_accessiblecaret_cursor_mode.py AccessibleCaretCursorModeTestCase.test_move_cursor_to_the_right_by_one_character_textarea
Description
•