Closed
Bug 1899595
Opened 2 years ago
Closed 11 months ago
Intermittent testing/marionette/harness/marionette_harness/tests/unit/test_chrome_action.py | single tracking bug
Categories
(Testing :: Marionette Client and Harness, defect, P5)
Testing
Marionette Client and Harness
Tracking
(firefox-esr140 unaffected, firefox145 unaffected, firefox146 unaffected, firefox147 unaffected, firefox148 affected)
RESOLVED
INCOMPLETE
| Tracking | Status | |
|---|---|---|
| firefox-esr140 | --- | unaffected |
| firefox145 | --- | unaffected |
| firefox146 | --- | unaffected |
| firefox147 | --- | unaffected |
| firefox148 | --- | affected |
People
(Reporter: intermittent-bug-filer, Unassigned)
References
(Regression)
Details
(Keywords: intermittent-failure, intermittent-testcase, regression)
Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=460176151&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/LKOybVyRQUWGnqSBscUT4g/runs/0/artifacts/public/logs/live_backing.log
[task 2024-05-29T16:13:47.470Z] 16:13:47 INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_chrome_action.py TestPointerActions.test_click_action
[task 2024-05-29T16:13:47.470Z] 16:13:47 INFO - 1716999227463 Marionette DEBUG 155 <- [1,62,null,{"value":null}]
[task 2024-05-29T16:13:47.471Z] 16:13:47 INFO - 1716999227469 Marionette DEBUG Closed connection 155
[task 2024-05-29T16:13:47.471Z] 16:13:47 INFO - 1716999227469 Marionette DEBUG Accepted connection 156 from 127.0.0.1:57828
[task 2024-05-29T16:13:47.473Z] 16:13:47 INFO - 1716999227472 Marionette DEBUG 156 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2024-05-29T16:13:47.474Z] 16:13:47 INFO - 1716999227474 Marionette DEBUG Waiting for initial application window
[task 2024-05-29T16:13:47.477Z] 16:13:47 INFO - 1716999227475 RemoteAgent TRACE [17] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=40000 waitForExplicitStart=false
[task 2024-05-29T16:13:47.478Z] 16:13:47 INFO - 1716999227475 RemoteAgent TRACE [17] ProgressListener Setting unload timer (40000ms)
[task 2024-05-29T16:13:47.479Z] 16:13:47 INFO - 1716999227475 RemoteAgent TRACE [17] Wait for initial navigation: isInitial=false, isLoadingDocument=false
[task 2024-05-29T16:13:47.480Z] 16:13:47 INFO - 1716999227475 RemoteAgent TRACE [17] Document already finished loading: http://127.0.0.1:46778/test.html
[task 2024-05-29T16:13:47.480Z] 16:13:47 INFO - 1716999227475 RemoteAgent TRACE [17] ProgressListener Stop: has error=false url=http://127.0.0.1:46778/test.html
[task 2024-05-29T16:13:47.481Z] 16:13:47 INFO - 1716999227479 Marionette DEBUG 156 <- [1,1,null,{"sessionId":"80943bb1-d586-41c1-8d89-e15e184d12f3","capabilities":{"browserName":"firefox","browserVersion":"128.0a1","platformName":"linux","acceptInsecureCerts":false,"unhandledPromptBehavior":"dismiss and notify","userAgent":"Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0","pageLoadStrategy":"normal","timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"setWindowRect":true,"strictFileInteractability":true,"moz:accessibilityChecks":false,"moz:buildID":"20240529133404","moz:headless":false,"moz:platformVersion":"4.4.0-1014-aws","moz:processID":569,"moz:profile":"/tmp/tmpbebmbjp7.mozrunner","moz:shutdownTimeout":60000,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2024-05-29T16:13:47.482Z] 16:13:47 INFO - 1716999227481 Marionette DEBUG 156 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2024-05-29T16:13:47.482Z] 16:13:47 INFO - 1716999227482 Marionette DEBUG 156 <- [1,2,null,{"value":null}]
[task 2024-05-29T16:13:47.483Z] 16:13:47 INFO - 1716999227483 Marionette DEBUG 156 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2024-05-29T16:13:47.484Z] 16:13:47 INFO - 1716999227483 Marionette DEBUG 156 <- [1,3,null,{"value":null}]
[task 2024-05-29T16:13:47.485Z] 16:13:47 INFO - 1716999227485 Marionette DEBUG 156 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2024-05-29T16:13:47.490Z] 16:13:47 INFO - 1716999227489 Marionette DEBUG 156 <- [1,4,null,{"value":null}]
[task 2024-05-29T16:13:47.492Z] 16:13:47 INFO - 1716999227492 Marionette DEBUG 156 -> [0,5,"Marionette:GetContext",{}]
[task 2024-05-29T16:13:47.493Z] 16:13:47 INFO - 1716999227492 Marionette DEBUG 156 <- [1,5,null,{"value":"content"}]
[task 2024-05-29T16:13:47.494Z] 16:13:47 INFO - 1716999227494 Marionette DEBUG 156 -> [0,6,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-05-29T16:13:47.495Z] 16:13:47 INFO - 1716999227495 Marionette DEBUG 156 <- [1,6,null,{"value":null}]
[task 2024-05-29T16:13:47.496Z] 16:13:47 INFO - 1716999227496 Marionette DEBUG 156 -> [0,7,"WebDriver:GetWindowHandle",{}]
[task 2024-05-29T16:13:47.497Z] 16:13:47 INFO - 1716999227497 Marionette DEBUG 156 <- [1,7,null,{"value":"1d6fd8d8-a57d-423d-a214-78e5aa26f892"}]
[task 2024-05-29T16:13:47.498Z] 16:13:47 INFO - 1716999227498 Marionette DEBUG 156 -> [0,8,"Marionette:SetContext",{"value":"content"}]
[task 2024-05-29T16:13:47.499Z] 16:13:47 INFO - 1716999227498 Marionette DEBUG 156 <- [1,8,null,{"value":null}]
[task 2024-05-29T16:13:47.499Z] 16:13:47 INFO - 1716999227499 Marionette DEBUG 156 -> [0,9,"Marionette:GetContext",{}]
[task 2024-05-29T16:13:47.500Z] 16:13:47 INFO - 1716999227499 Marionette DEBUG 156 <- [1,9,null,{"value":"content"}]
[task 2024-05-29T16:13:47.501Z] 16:13:47 INFO - 1716999227501 Marionette DEBUG 156 -> [0,10,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-05-29T16:13:47.502Z] 16:13:47 INFO - 1716999227501 Marionette DEBUG 156 <- [1,10,null,{"value":null}]
[task 2024-05-29T16:13:47.502Z] 16:13:47 INFO - 1716999227502 Marionette DEBUG 156 -> [0,11,"WebDriver:GetWindowHandles",{}]
[task 2024-05-29T16:13:47.503Z] 16:13:47 INFO - 1716999227503 Marionette DEBUG 156 <- [1,11,null,["1d6fd8d8-a57d-423d-a214-78e5aa26f892"]]
[task 2024-05-29T16:13:47.504Z] 16:13:47 INFO - 1716999227504 Marionette DEBUG 156 -> [0,12,"Marionette:SetContext",{"value":"content"}]
[task 2024-05-29T16:13:47.505Z] 16:13:47 INFO - 1716999227504 Marionette DEBUG 156 <- [1,12,null,{"value":null}]
[task 2024-05-29T16:13:47.505Z] 16:13:47 INFO - 1716999227505 Marionette DEBUG 156 -> [0,13,"Marionette:GetContext",{}]
[task 2024-05-29T16:13:47.506Z] 16:13:47 INFO - 1716999227505 Marionette DEBUG 156 <- [1,13,null,{"value":"content"}]
[task 2024-05-29T16:13:47.507Z] 16:13:47 INFO - 1716999227507 Marionette DEBUG 156 -> [0,14,"Marionette:SetContext",{"value":"content"}]
[task 2024-05-29T16:13:47.508Z] 16:13:47 INFO - 1716999227507 Marionette DEBUG 156 <- [1,14,null,{"value":null}]
[task 2024-05-29T16:13:47.508Z] 16:13:47 INFO - 1716999227508 Marionette DEBUG 156 -> [0,15,"WebDriver:GetWindowHandle",{}]
[task 2024-05-29T16:13:47.509Z] 16:13:47 INFO - 1716999227509 Marionette DEBUG 156 <- [1,15,null,{"value":"167ea4ba-7f34-4204-850c-9dee06a29a79"}]
[task 2024-05-29T16:13:47.510Z] 16:13:47 INFO - 1716999227510 Marionette DEBUG 156 -> [0,16,"Marionette:SetContext",{"value":"content"}]
[task 2024-05-29T16:13:47.511Z] 16:13:47 INFO - 1716999227510 Marionette DEBUG 156 <- [1,16,null,{"value":null}]
[task 2024-05-29T16:13:47.512Z] 16:13:47 INFO - 1716999227511 Marionette DEBUG 156 -> [0,17,"Marionette:GetContext",{}]
[task 2024-05-29T16:13:47.512Z] 16:13:47 INFO - 1716999227512 Marionette DEBUG 156 <- [1,17,null,{"value":"content"}]
[task 2024-05-29T16:13:47.513Z] 16:13:47 INFO - 1716999227513 Marionette DEBUG 156 -> [0,18,"Marionette:SetContext",{"value":"content"}]
[task 2024-05-29T16:13:47.514Z] 16:13:47 INFO - 1716999227513 Marionette DEBUG 156 <- [1,18,null,{"value":null}]
[task 2024-05-29T16:13:47.515Z] 16:13:47 INFO - 1716999227514 Marionette DEBUG 156 -> [0,19,"WebDriver:GetWindowHandles",{}]
[task 2024-05-29T16:13:47.515Z] 16:13:47 INFO - 1716999227515 Marionette DEBUG 156 <- [1,19,null,["167ea4ba-7f34-4204-850c-9dee06a29a79"]]
[task 2024-05-29T16:13:47.518Z] 16:13:47 INFO - 1716999227516 Marionette DEBUG 156 -> [0,20,"Marionette:SetContext",{"value":"content"}]
[task 2024-05-29T16:13:47.518Z] 16:13:47 INFO - 1716999227516 Marionette DEBUG 156 <- [1,20,null,{"value":null}]
[task 2024-05-29T16:13:47.519Z] 16:13:47 INFO - 1716999227517 Marionette DEBUG 156 -> [0,21,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-05-29T16:13:47.520Z] 16:13:47 INFO - 1716999227518 Marionette DEBUG 156 <- [1,21,null,{"value":null}]
[task 2024-05-29T16:13:47.520Z] 16:13:47 INFO - 1716999227519 Marionette DEBUG 156 -> [0,22,"Marionette:GetContext",{}]
[task 2024-05-29T16:13:47.521Z] 16:13:47 INFO - 1716999227519 Marionette DEBUG 156 <- [1,22,null,{"value":"chrome"}]
[task 2024-05-29T16:13:47.521Z] 16:13:47 INFO - 1716999227521 Marionette DEBUG 156 -> [0,23,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-05-29T16:13:47.522Z] 16:13:47 INFO - 1716999227521 Marionette DEBUG 156 <- [1,23,null,{"value":null}]
[task 2024-05-29T16:13:47.523Z] 16:13:47 INFO - 1716999227522 Marionette DEBUG 156 -> [0,24,"Marionette:GetContext",{}]
[task 2024-05-29T16:13:47.523Z] 16:13:47 INFO - 1716999227523 Marionette DEBUG 156 <- [1,24,null,{"value":"chrome"}]
[task 2024-05-29T16:13:47.524Z] 16:13:47 INFO - 1716999227524 Marionette DEBUG 156 -> [0,25,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-05-29T16:13:47.525Z] 16:13:47 INFO - 1716999227524 Marionette DEBUG 156 <- [1,25,null,{"value":null}]
[task 2024-05-29T16:13:47.528Z] 16:13:47 INFO - 1716999227525 Marionette DEBUG 156 -> [0,26,"WebDriver:GetWindowHandles",{}]
[task 2024-05-29T16:13:47.528Z] 16:13:47 INFO - 1716999227526 Marionette DEBUG 156 <- [1,26,null,["1d6fd8d8-a57d-423d-a214-78e5aa26f892"]]
[task 2024-05-29T16:13:47.529Z] 16:13:47 INFO - 1716999227526 Marionette DEBUG 156 -> [0,27,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-05-29T16:13:47.529Z] 16:13:47 INFO - 1716999227527 Marionette DEBUG 156 <- [1,27,null,{"value":null}]
[task 2024-05-29T16:13:47.530Z] 16:13:47 INFO - 1716999227528 Marionette DEBUG 156 -> [0,28,"Marionette:GetContext",{}]
[task 2024-05-29T16:13:47.530Z] 16:13:47 INFO - 1716999227528 Marionette DEBUG 156 <- [1,28,null,{"value":"chrome"}]
[task 2024-05-29T16:13:47.531Z] 16:13:47 INFO - 1716999227529 Marionette DEBUG 156 -> [0,29,"Marionette:SetContext",{"value":"content"}]
[task 2024-05-29T16:13:47.531Z] 16:13:47 INFO - 1716999227530 Marionette DEBUG 156 <- [1,29,null,{"value":null}]
[task 2024-05-29T16:13:47.532Z] 16:13:47 INFO - 1716999227531 Marionette DEBUG 156 -> [0,30,"WebDriver:GetWindowHandles",{}]
[task 2024-05-29T16:13:47.532Z] 16:13:47 INFO - 1716999227532 Marionette DEBUG 156 <- [1,30,null,["167ea4ba-7f34-4204-850c-9dee06a29a79"]]
[task 2024-05-29T16:13:47.533Z] 16:13:47 INFO - 1716999227533 Marionette DEBUG 156 -> [0,31,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-05-29T16:13:47.534Z] 16:13:47 INFO - 1716999227534 Marionette DEBUG 156 <- [1,31,null,{"value":null}]
[task 2024-05-29T16:13:47.536Z] 16:13:47 INFO - 1716999227536 Marionette DEBUG 156 -> [0,32,"Marionette:GetContext",{}]
[task 2024-05-29T16:13:47.537Z] 16:13:47 INFO - 1716999227536 Marionette DEBUG 156 <- [1,32,null,{"value":"chrome"}]
[task 2024-05-29T16:13:47.538Z] 16:13:47 INFO - 1716999227537 Marionette DEBUG 156 -> [0,33,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-05-29T16:13:47.538Z] 16:13:47 INFO - 1716999227538 Marionette DEBUG 156 <- [1,33,null,{"value":null}]
[task 2024-05-29T16:13:47.540Z] 16:13:47 INFO - 1716999227539 Marionette DEBUG 156 -> [0,34,"WebDriver:ExecuteAsyncScript",{"script":"let [url, focus, resolve] = arguments;\n\n function waitForEvent(target, type, args) {\n ret ... await focused;\n }\n\n resolve(win.docShell.browsingContext.id);\n })();","args":["chrome://remote/content/marionette/test.xhtml",false],"newSandbox":true,"sandbox":"default","scriptTimeout":null,"line":166,"filename":"../../venv/lib/python3.8/site-packages/marionette_harness/runner/mixins/window_manager.py"}]
[task 2024-05-29T16:13:47.546Z] 16:13:47 INFO - 1716999227541 RemoteAgent TRACE WebDriverProcessData actor created for PID 569
[task 2024-05-29T16:13:47.547Z] 16:13:47 INFO - 1716999227542 Marionette TRACE [1] MarionetteCommands actor created for window id 2
[task 2024-05-29T16:13:47.555Z] 16:13:47 INFO - 1716999227554 RemoteAgent TRACE Received observer notification domwindowopened
[task 2024-05-29T16:13:47.556Z] 16:13:47 INFO - [Parent 569, Main Thread] WARNING: NS_ENSURE_TRUE(uri) failed: file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1494
[task 2024-05-29T16:13:47.562Z] 16:13:47 INFO - [Child 756, Main Thread] WARNING: DispatchEvent called on non-current inner window, dropping. Please check the window in the caller instead.: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowInner.cpp:4198
[task 2024-05-29T16:13:47.562Z] 16:13:47 INFO - [Child 756, Main Thread] WARNING: DispatchEvent called on non-current inner window, dropping. Please check the window in the caller instead.: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowInner.cpp:4198
[task 2024-05-29T16:13:47.569Z] 16:13:47 INFO - ** Received DOM event activate for [object Window]
[task 2024-05-29T16:13:47.570Z] 16:13:47 INFO - ** Received DOM event focus for [object HTMLDocument]
[task 2024-05-29T16:13:47.573Z] 16:13:47 INFO - [Child 756, Main Thread] WARNING: DispatchEvent called on non-current inner window, dropping. Please check the window in the caller instead.: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowInner.cpp:4198
[task 2024-05-29T16:13:47.574Z] 16:13:47 INFO - [Child 756, Main Thread] WARNING: DispatchEvent called on non-current inner window, dropping. Please check the window in the caller instead.: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowInner.cpp:4198
[task 2024-05-29T16:13:47.575Z] 16:13:47 INFO - ** Received DOM event activate for [object Window]
[task 2024-05-29T16:13:47.576Z] 16:13:47 INFO - ** Received DOM event focus for [object HTMLDocument]
[task 2024-05-29T16:13:47.587Z] 16:13:47 INFO - 1716999227586 Marionette DEBUG 156 <- [1,34,null,{"value":25}]
[task 2024-05-29T16:13:47.638Z] 16:13:47 INFO - [Parent 569, Main Thread] WARNING: NS_ENSURE_TRUE(root) failed: file /builds/worker/checkouts/gecko/layout/base/nsDocumentViewer.cpp:2586
[task 2024-05-29T16:13:47.649Z] 16:13:47 INFO - [Parent 569, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/chrome/nsChromeRegistry.cpp:182
[task 2024-05-29T16:13:47.650Z] 16:13:47 INFO - [Parent 569, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/chrome/nsChromeProtocolHandler.cpp:73
[task 2024-05-29T16:13:47.656Z] 16:13:47 INFO - [Parent 569, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/chrome/nsChromeRegistry.cpp:182
[task 2024-05-29T16:13:47.656Z] 16:13:47 INFO - [Parent 569, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/chrome/nsChromeProtocolHandler.cpp:73
[task 2024-05-29T16:13:47.661Z] 16:13:47 INFO - [Parent 569, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/chrome/nsChromeRegistry.cpp:182
[task 2024-05-29T16:13:47.662Z] 16:13:47 INFO - [Parent 569, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/chrome/nsChromeProtocolHandler.cpp:73
[task 2024-05-29T16:13:47.674Z] 16:13:47 INFO - 1716999227674 Marionette DEBUG 156 -> [0,35,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-05-29T16:13:47.675Z] 16:13:47 INFO - 1716999227675 Marionette DEBUG 156 <- [1,35,null,{"value":null}]
[task 2024-05-29T16:13:47.681Z] 16:13:47 INFO - 1716999227681 Marionette DEBUG 156 -> [0,36,"Marionette:GetContext",{}]
[task 2024-05-29T16:13:47.682Z] 16:13:47 INFO - 1716999227681 Marionette DEBUG 156 <- [1,36,null,{"value":"chrome"}]
[task 2024-05-29T16:13:47.790Z] 16:13:47 INFO - 1716999227790 Marionette DEBUG 156 -> [0,37,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-05-29T16:13:47.792Z] 16:13:47 INFO - 1716999227790 Marionette DEBUG 156 <- [1,37,null,{"value":null}]
[task 2024-05-29T16:13:47.806Z] 16:13:47 INFO - [Child 756, Main Thread] WARNING: DispatchEvent called on non-current inner window, dropping. Please check the window in the caller instead.: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowInner.cpp:4198
[task 2024-05-29T16:13:47.806Z] 16:13:47 INFO - [Child 756, Main Thread] WARNING: DispatchEvent called on non-current inner window, dropping. Please check the window in the caller instead.: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowInner.cpp:4198
[task 2024-05-29T16:13:47.843Z] 16:13:47 INFO - [WARN webrender::device::gl] Missing optimized shader source for gpu_cache_update
[task 2024-05-29T16:13:47.915Z] 16:13:47 INFO - 1716999227914 Marionette DEBUG 156 -> [0,38,"WebDriver:GetWindowHandles",{}]
[task 2024-05-29T16:13:47.921Z] 16:13:47 INFO - 1716999227921 Marionette DEBUG 156 <- [1,38,null,["1d6fd8d8-a57d-423d-a214-78e5aa26f892","feb4d0be-86e5-4045-a5bc-07593aabe42f"]]
[task 2024-05-29T16:13:47.927Z] 16:13:47 INFO - 1716999227926 Marionette DEBUG 156 -> [0,39,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-05-29T16:13:47.930Z] 16:13:47 INFO - 1716999227927 Marionette DEBUG 156 <- [1,39,null,{"value":null}]
[task 2024-05-29T16:13:47.933Z] 16:13:47 INFO - 1716999227932 Marionette DEBUG 156 -> [0,40,"Marionette:GetContext",{}]
[task 2024-05-29T16:13:47.936Z] 16:13:47 INFO - 1716999227933 Marionette DEBUG 156 <- [1,40,null,{"value":"chrome"}]
[task 2024-05-29T16:13:47.938Z] 16:13:47 INFO - 1716999227935 Marionette DEBUG 156 -> [0,41,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-05-29T16:13:47.942Z] 16:13:47 INFO - 1716999227935 Marionette DEBUG 156 <- [1,41,null,{"value":null}]
[task 2024-05-29T16:13:47.944Z] 16:13:47 INFO - 1716999227937 Marionette DEBUG 156 -> [0,42,"WebDriver:GetWindowHandles",{}]
[task 2024-05-29T16:13:47.947Z] 16:13:47 INFO - 1716999227938 Marionette DEBUG 156 <- [1,42,null,["1d6fd8d8-a57d-423d-a214-78e5aa26f892","feb4d0be-86e5-4045-a5bc-07593aabe42f"]]
[task 2024-05-29T16:13:47.949Z] 16:13:47 INFO - 1716999227940 Marionette DEBUG 156 -> [0,43,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-05-29T16:13:47.951Z] 16:13:47 INFO - 1716999227941 Marionette DEBUG 156 <- [1,43,null,{"value":null}]
[task 2024-05-29T16:13:47.951Z] 16:13:47 INFO - 1716999227946 Marionette DEBUG 156 -> [0,44,"Marionette:GetContext",{}]
[task 2024-05-29T16:13:47.951Z] 16:13:47 INFO - 1716999227948 Marionette DEBUG 156 <- [1,44,null,{"value":"chrome"}]
[task 2024-05-29T16:13:47.952Z] 16:13:47 INFO - 1716999227952 Marionette DEBUG 156 -> [0,45,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-05-29T16:13:47.954Z] 16:13:47 INFO - 1716999227954 Marionette DEBUG 156 <- [1,45,null,{"value":null}]
[task 2024-05-29T16:13:47.969Z] 16:13:47 INFO - 1716999227968 Marionette DEBUG 156 -> [0,46,"WebDriver:ExecuteScript",{"script":"const { windowManager } = ChromeUtils.importESModule(\n \"chrome://remote/content/shared/WindowManager.sys.m ... nst win = windowManager.findWindowByHandle(arguments[0]).win;\n return win.document.readyState == \"complete\";","args":["feb4d0be-86e5-4045-a5bc-07593aabe42f"],"newSandbox":true,"sandbox":"default","line":95,"filename":"../../venv/lib/python3.8/site-packages/marionette_harness/runner/mixins/window_manager.py"}]
[task 2024-05-29T16:13:48.006Z] 16:13:48 INFO - 1716999228005 Marionette DEBUG 156 <- [1,46,null,{"value":true}]
[task 2024-05-29T16:13:48.008Z] 16:13:48 INFO - 1716999228007 Marionette DEBUG 156 -> [0,47,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-05-29T16:13:48.009Z] 16:13:48 INFO - 1716999228009 Marionette DEBUG 156 <- [1,47,null,{"value":null}]
[task 2024-05-29T16:13:48.023Z] 16:13:48 INFO - 1716999228023 Marionette DEBUG 156 -> [0,48,"Marionette:GetContext",{}]
[task 2024-05-29T16:13:48.025Z] 16:13:48 INFO - 1716999228024 Marionette DEBUG 156 <- [1,48,null,{"value":"chrome"}]
[task 2024-05-29T16:13:48.027Z] 16:13:48 INFO - 1716999228026 Marionette DEBUG 156 -> [0,49,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-05-29T16:13:48.028Z] 16:13:48 INFO - 1716999228027 Marionette DEBUG 156 <- [1,49,null,{"value":null}]
[task 2024-05-29T16:13:48.032Z] 16:13:48 INFO - 1716999228032 Marionette DEBUG 156 -> [0,50,"WebDriver:SwitchToWindow",{"handle":"feb4d0be-86e5-4045-a5bc-07593aabe42f","focus":true}]
[task 2024-05-29T16:13:48.037Z] 16:13:48 INFO - 1716999228037 Marionette DEBUG 156 <- [1,50,null,{"value":null}]
[task 2024-05-29T16:13:48.047Z] 16:13:48 INFO - 1716999228046 Marionette DEBUG 156 -> [0,51,"WebDriver:FindElement",{"value":"testBox","using":"id"}]
[task 2024-05-29T16:13:48.054Z] 16:13:48 INFO - 1716999228054 Marionette TRACE [25] MarionetteCommands actor created for window id 52
[task 2024-05-29T16:13:48.064Z] 16:13:48 INFO - 1716999228063 Marionette DEBUG 156 <- [1,51,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"065beeb4-90b2-47d0-bf40-e86d115ad9db"}}]
[task 2024-05-29T16:13:48.066Z] 16:13:48 INFO - 1716999228066 Marionette DEBUG 156 -> [0,52,"WebDriver:GetElementProperty",{"id":"065beeb4-90b2-47d0-bf40-e86d115ad9db","name":"localName"}]
[task 2024-05-29T16:13:48.070Z] 16:13:48 INFO - 1716999228070 Marionette DEBUG 156 <- [1,52,null,{"value":"checkbox"}]
[task 2024-05-29T16:13:48.073Z] 16:13:48 INFO - 1716999228072 Marionette DEBUG 156 -> [0,53,"WebDriver:ExecuteScript",{"script":"return document.getElementById('testBox').checked","args":[],"newSandbox":true,"sandbox":"default","line":42,"filename":"tests/testing/marionette/harness/marionette_harness/tests/unit/test_chrome_action.py"}]
[task 2024-05-29T16:13:48.082Z] 16:13:48 INFO - 1716999228081 Marionette DEBUG 156 <- [1,53,null,{"value":false}]
[task 2024-05-29T16:13:48.084Z] 16:13:48 INFO - 1716999228083 Marionette DEBUG 156 -> [0,54,"WebDriver:PerformActions",{"actions":[{"type":"pointer","id":"pointer_id","actions":[{"type":"pointerMove","x":0,"y":0,"origin":{"element-6066-11e4-a52e-4f735466cecf":"065beeb4-90b2-47d0-bf40-e86d115ad9db"}},{"type":"pointerDown","button":0},{"type":"pointerUp","button":0}],"parameters":{"pointerType":"mouse"}}]}]
[task 2024-05-29T16:13:48.092Z] 16:13:48 INFO - [Child 942, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:757
[task 2024-05-29T16:13:48.108Z] 16:13:48 INFO - 1716999228108 Marionette TRACE Dispatching tick 1/3
[task 2024-05-29T16:13:48.110Z] 16:13:48 INFO - 1716999228109 Marionette TRACE Dispatch PointerMoveAction mouse with id: pointer_id x: 0 y: 0
[task 2024-05-29T16:13:48.112Z] 16:13:48 INFO - 1716999228111 Marionette TRACE moveOverTime start: 0,0 target: 102,121 duration: 0
[task 2024-05-29T16:13:48.113Z] 16:13:48 INFO - 1716999228112 Marionette TRACE PointerMoveAction.performPointerMoveStep [102,121]
[task 2024-05-29T16:13:48.138Z] 16:13:48 INFO - 1716999228138 Marionette TRACE Dispatching tick 2/3
[task 2024-05-29T16:13:48.140Z] 16:13:48 INFO - 1716999228139 Marionette TRACE Dispatch PointerDownAction mouse with id: pointer_id button: 0
[task 2024-05-29T16:13:48.144Z] 16:13:48 INFO - 1716999228144 Marionette TRACE Dispatching tick 3/3
[task 2024-05-29T16:13:48.145Z] 16:13:48 INFO - 1716999228145 Marionette TRACE Dispatch PointerUpAction mouse with id: pointer_id button: 0
[task 2024-05-29T16:13:48.164Z] 16:13:48 INFO - 1716999228163 Marionette DEBUG 156 <- [1,54,null,{"value":null}]
[task 2024-05-29T16:13:48.166Z] 16:13:48 INFO - 1716999228166 Marionette DEBUG 156 -> [0,55,"WebDriver:ExecuteScript",{"script":"return document.getElementById('testBox').checked","args":[],"newSandbox":true,"sandbox":"default","line":48,"filename":"tests/testing/marionette/harness/marionette_harness/tests/unit/test_chrome_action.py"}]
[task 2024-05-29T16:13:48.175Z] 16:13:48 INFO - 1716999228174 Marionette DEBUG 156 <- [1,55,null,{"value":true}]
[task 2024-05-29T16:13:48.177Z] 16:13:48 INFO - 1716999228177 Marionette DEBUG 156 -> [0,56,"WebDriver:ReleaseActions",{}]
[task 2024-05-29T16:13:48.179Z] 16:13:48 INFO - 1716999228179 Marionette TRACE Dispatch PointerUpAction mouse with id: pointer_id button: 0
[task 2024-05-29T16:13:48.181Z] 16:13:48 INFO - 1716999228181 Marionette DEBUG 156 <- [1,56,null,{"value":null}]
[task 2024-05-29T16:13:48.183Z] 16:13:48 INFO - 1716999228183 Marionette DEBUG 156 -> [0,57,"Marionette:GetContext",{}]
[task 2024-05-29T16:13:48.185Z] 16:13:48 INFO - 1716999228184 Marionette DEBUG 156 <- [1,57,null,{"value":"chrome"}]
[task 2024-05-29T16:13:48.187Z] 16:13:48 INFO - 1716999228187 Marionette DEBUG 156 -> [0,58,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-05-29T16:13:48.188Z] 16:13:48 INFO - 1716999228188 Marionette DEBUG 156 <- [1,58,null,{"value":null}]
[task 2024-05-29T16:13:48.191Z] 16:13:48 INFO - 1716999228191 Marionette DEBUG 156 -> [0,59,"WebDriver:GetWindowHandles",{}]
[task 2024-05-29T16:13:48.193Z] 16:13:48 INFO - 1716999228192 Marionette DEBUG 156 <- [1,59,null,["1d6fd8d8-a57d-423d-a214-78e5aa26f892","feb4d0be-86e5-4045-a5bc-07593aabe42f"]]
[task 2024-05-29T16:13:48.195Z] 16:13:48 INFO - 1716999228195 Marionette DEBUG 156 -> [0,60,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-05-29T16:13:48.197Z] 16:13:48 INFO - 1716999228195 Marionette DEBUG 156 <- [1,60,null,{"value":null}]
[task 2024-05-29T16:13:48.199Z] 16:13:48 INFO - 1716999228198 Marionette DEBUG 156 -> [0,61,"WebDriver:SwitchToWindow",{"handle":"feb4d0be-86e5-4045-a5bc-07593aabe42f","focus":true}]
[task 2024-05-29T16:13:48.201Z] 16:13:48 INFO - 1716999228201 Marionette DEBUG 156 <- [1,61,null,{"value":null}]
[task 2024-05-29T16:13:48.203Z] 16:13:48 INFO - 1716999228203 Marionette DEBUG 156 -> [0,62,"WebDriver:CloseChromeWindow",{}]
[task 2024-05-29T16:13:48.232Z] 16:13:48 INFO - [Utility 747, IPC I/O Child] WARNING: [E305A182A4681174.4AF0AC94DDF0B47C]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:365
[task 2024-05-29T16:13:48.233Z] 16:13:48 INFO - [Child 781, IPC I/O Child] WARNING: [F22105D978CB5473.18CB45FA742821DA]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:365
[task 2024-05-29T16:13:48.233Z] 16:13:48 INFO - Exiting due to channel error.
[task 2024-05-29T16:13:48.233Z] 16:13:48 INFO - [Child 756, IPC I/O Child] WARNING: [479E19FBA0DEA5F1.BA0D8766B8C3A168]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:365
[task 2024-05-29T16:13:48.233Z] 16:13:48 INFO - Exiting due to channel error.
[task 2024-05-29T16:13:48.233Z] 16:13:48 INFO - [Utility 747, Main Thread] WARNING: Shutting down Utility process early due to a crash!: file /builds/worker/checkouts/gecko/ipc/glue/UtilityProcessChild.cpp:352
[task 2024-05-29T16:13:48.275Z] 16:13:48 INFO - [Child 824, IPC I/O Child] WARNING: [527B3CC4401B9075.80D67A402B023B25]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:365
[task 2024-05-29T16:13:48.277Z] 16:13:48 INFO - Exiting due to channel error.
[task 2024-05-29T16:13:48.279Z] 16:13:48 INFO - [Child 675, IPC I/O Child] WARNING: [851A71D1CCC7DC5B.4F2409828546FB2A]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:365
[task 2024-05-29T16:13:48.279Z] 16:13:48 INFO - Exiting due to channel error.
[task 2024-05-29T16:13:48.281Z] 16:13:48 INFO - [Child 942, IPC I/O Child] WARNING: [EF029B630355268F.C6A3AB732C8E675B]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:365
[task 2024-05-29T16:13:48.282Z] 16:13:48 INFO - Exiting due to channel error.
[task 2024-05-29T16:13:48.325Z] 16:13:48 INFO - TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_chrome_action.py TestPointerActions.test_click_action | OSError: Process has been unexpectedly closed (Exit code: -11) (Reason: No data received over socket)
[task 2024-05-29T16:13:48.326Z] 16:13:48 INFO - Traceback (most recent call last):
[task 2024-05-29T16:13:48.326Z] 16:13:48 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_harness/marionette_test/testcases.py", line 231, in run
[task 2024-05-29T16:13:48.327Z] 16:13:48 INFO - self.tearDown()
[task 2024-05-29T16:13:48.327Z] 16:13:48 INFO - File "/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_chrome_action.py", line 34, in tearDown
[task 2024-05-29T16:13:48.328Z] 16:13:48 INFO - self.close_all_windows()
[task 2024-05-29T16:13:48.328Z] 16:13:48 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_harness/runner/mixins/window_manager.py", line 54, in close_all_windows
[task 2024-05-29T16:13:48.328Z] 16:13:48 INFO - self.marionette.close_chrome_window()
[task 2024-05-29T16:13:48.328Z] 16:13:48 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/marionette.py", line 1567, in close_chrome_window
[task 2024-05-29T16:13:48.328Z] 16:13:48 INFO - return self._send_message("WebDriver:CloseChromeWindow")
[task 2024-05-29T16:13:48.328Z] 16:13:48 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/decorators.py", line 34, in _
[task 2024-05-29T16:13:48.328Z] 16:13:48 INFO - m._handle_socket_failure()
[task 2024-05-29T16:13:48.328Z] 16:13:48 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/marionette.py", line 853, in _handle_socket_failure
[task 2024-05-29T16:13:48.329Z] 16:13:48 INFO - reraise(
[task 2024-05-29T16:13:48.329Z] 16:13:48 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/six.py", line 702, in reraise
[task 2024-05-29T16:13:48.329Z] 16:13:48 INFO - raise value.with_traceback(tb)
[task 2024-05-29T16:13:48.329Z] 16:13:48 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/decorators.py", line 24, in _
[task 2024-05-29T16:13:48.329Z] 16:13:48 INFO - return func(*args, **kwargs)
[task 2024-05-29T16:13:48.329Z] 16:13:48 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/marionette.py", line 769, in _send_message
[task 2024-05-29T16:13:48.329Z] 16:13:48 INFO - msg = self.client.request(name, params)
[task 2024-05-29T16:13:48.329Z] 16:13:48 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/transport.py", line 382, in request
[task 2024-05-29T16:13:48.329Z] 16:13:48 INFO - return self.receive()
[task 2024-05-29T16:13:48.332Z] 16:13:48 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/transport.py", line 229, in receive
[task 2024-05-29T16:13:48.332Z] 16:13:48 INFO - raise socket.error("No data received over socket")
[task 2024-05-29T16:13:48.332Z] 16:13:48 INFO - TEST-INFO took 855ms
[task 2024-05-29T16:13:48.332Z] 16:13:48 INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_chrome_action.py TestPointerActions.test_key_action
|
||
Comment 1•2 years ago
|
||
This failure is bug 1898472.
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
|
Reporter | |
Comment 2•11 months ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=537104455&repo=autoland
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
|
||
Comment 3•11 months ago
|
||
with the landing of 543435 the failure rate has seen an increase
Keywords: regression
Regressed by: sync-about-blank
Summary: Intermittent testing/marionette/harness/marionette_harness/tests/unit/test_chrome_action.py | single tracking bug → High frequency testing/marionette/harness/marionette_harness/tests/unit/test_chrome_action.py | single tracking bug
|
||
Comment 4•11 months ago
|
||
Set release status flags based on info from the regressing bug 543435
:vhilla, since you are the author of the regressor, bug 543435, could you take a look?
For more information, please visit BugBot documentation.
status-firefox145:
--- → unaffected
status-firefox146:
--- → unaffected
status-firefox147:
--- → affected
status-firefox-esr140:
--- → unaffected
Flags: needinfo?(vhilla)
|
||
Comment 5•11 months ago
|
||
Hey Henri, please help take a look at this? Thank you.
Flags: needinfo?(vhilla) → needinfo?(hsivonen)
|
||
Comment 6•11 months ago
|
||
My suspicion is that the harness waits only for the new window to have gained focus, not for the new window to have loaded the requested doc, and the new about:blank changes timing.
Flags: needinfo?(hsivonen)
|
|
||
Updated•11 months ago
|
Flags: needinfo?(hskupin)
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 8•11 months ago
|
||
This looks to be an issue only for slow running builds like debug, ASAN and CCOV. Surprisingly I see failures only for Linux but not on MacOS and Windows. We will find an owner during our triage meeting today.
|
|
||
Updated•11 months ago
|
Flags: needinfo?(hskupin)
|
|
||
Comment 9•11 months ago
|
||
The sub test that is failing here is always test_click_action but not test_key_action which is run as next sub test. Both have the same pre-condition, same as for a lot of other chrome protocol related tests in that folder as well. So my assumption is that once the custom chrome URL is loaded once it's somewhat cached and as such loaded faster in following tests.
I'm going to take this bug.
Assignee: nobody → hskupin
Status: REOPENED → ASSIGNED
|
|
||
Comment 10•11 months ago
|
||
Note that I'm not able to reproduce this problem locally on a Linux machine with a very slow ASAN build. The test is not failing at all. Also it's not visible when running all the chrome related tests. So something specific as set for our machines in CI seems to trigger this issue.
|
|
||
Comment 11•11 months ago
|
||
(In reply to Henri Sivonen (:hsivonen) from comment #6)
My suspicion is that the harness waits only for the new window to have gained focus, not for the new window to have loaded the requested doc, and the new about:blank changes timing.
So this code in the window manager mixin actually calls open_window() which itself waits for the window to be loaded. It means that this cannot be an issue with the window not finished loading yet.
This can also be seen in the log files when we check the readyState of the document:
[task 2025-12-02T10:07:44.300+00:00] 10:07:44 INFO - 1764670064299 Marionette DEBUG 144 -> [0,47,"WebDriver:ExecuteScript",{"script":"const { NavigableManager } = ChromeUtils.importESModule(\n \"chrome://remote/content/shared/NavigableManager ... NavigableManager.getBrowsingContextById(arguments[0]).window;\n return win.document.readyState == \"complete\";","args":["716222ed-070b-471b-9d59-cdf476361ada"],"newSandbox":true,"sandbox":"default","line":92,"filename":"../../venv/lib/python3.12/site-packages/marionette_harness/runner/mixins/window_manager.py"}]
[task 2025-12-02T10:07:44.317+00:00] 10:07:44 INFO - 1764670064317 Marionette DEBUG 144 <- [1,47,null,{"value":true}]
Further we are even not able to actually create a screenshot of the window because it fails with a NS_ERROR_FAILURE when calling browsingContext.currentWindowGlobal.drawSnapshot():
[task 2025-12-02T10:07:44.334+00:00] 10:07:44 INFO - 1764670064334 Marionette DEBUG 144 -> [0,55,"WebDriver:TakeScreenshot",{"id":null,"full":true,"hash":false,"scroll":true}]
[task 2025-12-02T10:07:44.340+00:00] 10:07:44 INFO - [WARN webrender::device::gl] Missing optimized shader source for gpu_cache_update
[task 2025-12-02T10:07:44.527+00:00] 10:07:44 INFO - 1764670064526 Marionette DEBUG 144 <- [1,55,{"error":"unknown error","message":"[Exception... \"Failure\" nsresult: \"0x80004005 (NS_ERROR_FAILURE)\" location: \"JS frame :: chrome://remote/content/shared/Capture.sys.mjs :: capture.canvas :: line 136\" data: no]","stacktrace":"capture.canvas@chrome://remote/content/shared/Capture.sys.mjs:136:62\ntakeScreenshot@chrome://remote/content/marionette/actors/MarionetteCommandsParent.sys.mjs:325:37\n"},null]
That means in this situation something is completely busted with the window.
|
|
||
Comment 12•11 months ago
|
||
I triggered a try build with some extra logging for. the initial and uncommitted initial state and only running the affected test for an ASAN build. Sadly there is not a single failure at all:
https://treeherder.mozilla.org/jobs?repo=try&revision=94cade5c4952fee703c98c63e685d7e4d4129325
I've now pushed another try build with all the jobs included that are getting run before this problematic one:
https://treeherder.mozilla.org/jobs?repo=try&revision=01b548db3486c5fb33beef957444385f275f0794
I'm also trying to see if running the test with Wayland may make a difference:
https://treeherder.mozilla.org/jobs?repo=try&revision=e3057e78e282afad2073e80c3b5b5e506f7b5902&searchStr=asan
|
||
Comment 13•11 months ago
|
||
(In reply to Henrik Skupin [:whimboo][⌚️UTC+2] from comment #11)
So this code in the window manager mixin actually calls
open_window()which itself waits for the window to be loaded. It means that this cannot be an issue with the window not finished loading yet.
Ah, but that might be the issue. readyState is not reliable for the initial document.
|
|
||
Comment 14•11 months ago
|
||
(In reply to Vincent Hilla [:vhilla] from comment #13)
Ah, but that might be the issue.
readyStateis not reliable for the initial document.
Is that bug 2003514 we would depend on? If yes, are you planning to fix that soon? Shall we temporarily skip this test for slow builds on Linux to prevent the failures?
Flags: needinfo?(vhilla)
|
|
||
Comment 15•11 months ago
|
||
Until recently, I was under the impression that it should always be readyState==complete for the initial about:blank and I think that is how Firefox currently behaves. :hsivonen would know better why we decided for this. I just found bug 2003514 indicating that our assumption here might be incorrect.
|
|
||
Comment 16•11 months ago
|
||
One more thing regarding the latest try build.
In case of a failure I see (log):
[task 2025-12-03T08:46:40.350+00:00] 08:46:40 INFO - ** is uncommitted initial: true
[task 2025-12-03T08:46:40.351+00:00] 08:46:40 INFO - ** is initial: true
While for a passing test (log):
[task 2025-12-03T08:46:28.039+00:00] 08:46:28 INFO - ** is uncommitted initial: false
[task 2025-12-03T08:46:28.039+00:00] 08:46:28 INFO - ** is initial: false
So maybe we should wait until the isInitialDocument flag is set to false given that we always load a specific URL?
|
|
||
Comment 17•11 months ago
|
||
I think you should check something like readyState == complete && !document.isUncommittedInitialDocument as we do e.g. here.
Also see bug 2003720, we plan to backout the change from beta and keep it in nightly. I think the readyState of this document is difficult to observe from the web and we'll need to figure out how exactly it should progress. Right now, this seems lower priority than the other regressions from bug 543435.
Flags: needinfo?(vhilla)
|
|
||
Comment 18•11 months ago
|
||
Good idea. Let me try the same approach and check if that works. Here a new try build for ASAN:
https://treeherder.mozilla.org/jobs?repo=try&landoCommitID=166746
|
|
||
Comment 19•11 months ago
|
||
Yes, looks like this approach fixes it:
https://treeherder.mozilla.org/logviewer?job_id=538366617&repo=try&task=FG471NMbS5-cKRZMtqlP4A.0&lineNumber=4869
As it can be seen we check multiple times now for the load to be done. Let me actually try to make that a execute_async_script call so that we don't have to run the same command multiple times.
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 21•11 months ago
|
||
The failures are completely gone. Moving back as a generic intermittent single failure in case we need to track it again in the future.
Status: ASSIGNED → RESOLVED
Closed: 2 years ago → 11 months ago
status-firefox147:
affected → ---
Resolution: --- → INCOMPLETE
Summary: High frequency testing/marionette/harness/marionette_harness/tests/unit/test_chrome_action.py | single tracking bug → Intermittent testing/marionette/harness/marionette_harness/tests/unit/test_chrome_action.py | single tracking bug
|
|
Reporter | |
Comment 22•11 months ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=538551915&repo=mozilla-central
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
|
|
||
Comment 23•11 months ago
|
||
Looks like we have to leave it open for some more days until all older test failures are classified.
|
|
||
Comment 24•11 months ago
|
||
Set release status flags based on info from the regressing bug 543435
status-firefox147:
--- → unaffected
status-firefox148:
--- → affected
|
|
||
Comment 25•11 months ago
|
||
No more failures since the fix on bug 2003768 landed. Lets close the bug for now.
Assignee: hskupin → nobody
Status: REOPENED → RESOLVED
Closed: 11 months ago → 11 months ago
Resolution: --- → INCOMPLETE
|
|
||
Updated•11 months ago
|
|
|
Reporter | |
Comment 26•11 months ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=538551915&repo=mozilla-central
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
|
|
||
Updated•11 months ago
|
|
|
||
Updated•11 months ago
|
Status: REOPENED → RESOLVED
Closed: 11 months ago → 11 months ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•