Intermittent Linux Wd TEST-UNEXPECTED-TIMEOUT | expected OK (TEST-INFO took 80006ms)
Categories
(Remote Protocol :: Marionette, defect, P5)
Tracking
(Not tracked)
People
(Reporter: intermittent-bug-filer, Unassigned)
References
Details
(Keywords: intermittent-failure)
Filed by: imoraru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=408649751&repo=try
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Vbl0YCvyT8G5FwZci5o1bA/runs/0/artifacts/public/logs/live_backing.log
[task 2023-03-12T11:45:40.675Z] 11:45:40 INFO - STDOUT: FAILED
[task 2023-03-12T11:45:40.675Z] 11:45:40 INFO - PID 1832 | 1678621540674 webdriver::server DEBUG -> POST /session/873dd24b-49e2-4217-9054-81b893584c63/timeouts {"implicit": 0}
[task 2023-03-12T11:45:40.753Z] 11:45:40 INFO - PID 1832 | 1678621540750 Marionette DEBUG 0 -> [0,791,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2023-03-12T11:45:40.754Z] 11:45:40 INFO - PID 1832 | 1678621540751 Marionette DEBUG 0 <- [1,791,null,{"value":null}]
[task 2023-03-12T11:45:40.782Z] 11:45:40 INFO - PID 1832 | 1678621540781 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2023-03-12T11:45:40.785Z] 11:45:40 INFO - PID 1832 | 1678621540784 webdriver::server DEBUG -> POST /session/873dd24b-49e2-4217-9054-81b893584c63/timeouts {"pageLoad": 300000}
[task 2023-03-12T11:45:40.798Z] 11:45:40 INFO - PID 1832 | 1678621540797 Marionette DEBUG 0 -> [0,792,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2023-03-12T11:45:40.801Z] 11:45:40 INFO - PID 1832 | 1678621540798 Marionette DEBUG 0 <- [1,792,null,{"value":null}]
[task 2023-03-12T11:45:40.811Z] 11:45:40 INFO - PID 1832 | 1678621540810 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2023-03-12T11:45:40.813Z] 11:45:40 INFO - PID 1832 | 1678621540812 webdriver::server DEBUG -> POST /session/873dd24b-49e2-4217-9054-81b893584c63/timeouts {"script": 30000}
[task 2023-03-12T11:45:40.872Z] 11:45:40 INFO - PID 1832 | 1678621540871 Marionette DEBUG 0 -> [0,793,"WebDriver:SetTimeouts",{"script":30000}]
[task 2023-03-12T11:45:40.873Z] 11:45:40 INFO - PID 1832 | 1678621540872 Marionette DEBUG 0 <- [1,793,null,{"value":null}]
[task 2023-03-12T11:45:40.891Z] 11:45:40 INFO - PID 1832 | 1678621540889 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2023-03-12T11:45:40.892Z] 11:45:40 INFO - PID 1832 | 1678621540890 webdriver::server DEBUG -> GET /session/873dd24b-49e2-4217-9054-81b893584c63/window
[task 2023-03-12T11:45:40.965Z] 11:45:40 INFO - PID 1832 | 1678621540964 Marionette DEBUG 0 -> [0,794,"WebDriver:GetWindowHandle",{}]
[task 2023-03-12T11:45:40.967Z] 11:45:40 INFO - PID 1832 | 1678621540965 Marionette DEBUG 0 <- [1,794,null,{"value":"214dc363-59f5-4649-a34e-a51585690ec7"}]
[task 2023-03-12T11:45:41.001Z] 11:45:41 INFO - PID 1832 | 1678621541000 webdriver::server DEBUG <- 200 OK {"value":"214dc363-59f5-4649-a34e-a51585690ec7"}
[task 2023-03-12T11:45:41.003Z] 11:45:41 INFO - PID 1832 | 1678621541001 webdriver::server DEBUG -> GET /session/873dd24b-49e2-4217-9054-81b893584c63/window
[task 2023-03-12T11:45:41.044Z] 11:45:41 INFO - PID 1832 | 1678621541042 Marionette DEBUG 0 -> [0,795,"WebDriver:GetWindowHandle",{}]
[task 2023-03-12T11:45:41.045Z] 11:45:41 INFO - PID 1832 | 1678621541043 Marionette DEBUG 0 <- [1,795,null,{"value":"214dc363-59f5-4649-a34e-a51585690ec7"}]
[task 2023-03-12T11:45:41.047Z] 11:45:41 INFO - PID 1832 | 1678621541046 webdriver::server DEBUG <- 200 OK {"value":"214dc363-59f5-4649-a34e-a51585690ec7"}
[task 2023-03-12T11:45:41.049Z] 11:45:41 INFO - PID 1832 | 1678621541047 webdriver::server DEBUG -> GET /session/873dd24b-49e2-4217-9054-81b893584c63/window/handles
[task 2023-03-12T11:45:41.100Z] 11:45:41 INFO - PID 1832 | 1678621541099 Marionette DEBUG 0 -> [0,796,"WebDriver:GetWindowHandles",{}]
[task 2023-03-12T11:45:41.102Z] 11:45:41 INFO - PID 1832 | 1678621541101 Marionette DEBUG 0 <- [1,796,null,["214dc363-59f5-4649-a34e-a51585690ec7"]]
[task 2023-03-12T11:45:41.146Z] 11:45:41 INFO - PID 1832 | 1678621541145 webdriver::server DEBUG <- 200 OK {"value":["214dc363-59f5-4649-a34e-a51585690ec7"]}
[task 2023-03-12T11:45:41.147Z] 11:45:41 INFO - PID 1832 | 1678621541146 webdriver::server DEBUG -> POST /session/873dd24b-49e2-4217-9054-81b893584c63/window {"handle": "214dc363-59f5-4649-a34e-a51585690ec7"}
[task 2023-03-12T11:45:41.157Z] 11:45:41 INFO - PID 1832 | 1678621541156 Marionette DEBUG 0 -> [0,797,"WebDriver:SwitchToWindow",{"handle":"214dc363-59f5-4649-a34e-a51585690ec7"}]
[task 2023-03-12T11:45:41.160Z] 11:45:41 INFO - PID 1832 | 1678621541159 Marionette DEBUG 0 <- [1,797,null,{"value":null}]
[task 2023-03-12T11:45:41.163Z] 11:45:41 INFO - PID 1832 | 1678621541163 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2023-03-12T11:45:41.165Z] 11:45:41 INFO - PID 1832 | 1678621541164 webdriver::server DEBUG -> POST /session/873dd24b-49e2-4217-9054-81b893584c63/alert/dismiss {}
[task 2023-03-12T11:45:41.228Z] 11:45:41 INFO - PID 1832 | 1678621541227 Marionette DEBUG 0 -> [0,798,"WebDriver:DismissAlert",{}]
[task 2023-03-12T11:45:41.229Z] 11:45:41 INFO - PID 1832 | 1678621541228 Marionette DEBUG 0 <- [1,798,{"error":"no such alert","message":"","stacktrace":"RemoteError@chrome://remote/content/shared/RemoteError.sys.mjs:8:8\nWebDriverError@chrome://remote/content/shared/webdriver/Er ... ote/content/marionette/server.sys.mjs:249:9\n_onJSONObjectReady/<@chrome://remote/content/marionette/transport.sys.mjs:496:20\n"},null]
[task 2023-03-12T11:45:41.252Z] 11:45:41 INFO - PID 1832 | 1678621541251 webdriver::server DEBUG <- 404 Not Found {"value":{"error":"no such alert","message":"","stacktrace":"RemoteError@chrome://remote/content/shared/Remo ... ontent/marionette/server.sys.mjs:249:9\n_onJSONObjectReady/<@chrome://remote/content/marionette/transport.sys.mjs:496:20\n"}}
[task 2023-03-12T11:45:41.255Z] 11:45:41 INFO - PID 1832 | 1678621541252 webdriver::server DEBUG -> POST /session/873dd24b-49e2-4217-9054-81b893584c63/window {"handle": "214dc363-59f5-4649-a34e-a51585690ec7"}
[task 2023-03-12T11:45:41.265Z] 11:45:41 INFO - PID 1832 | 1678621541264 Marionette DEBUG 0 -> [0,799,"WebDriver:SwitchToWindow",{"handle":"214dc363-59f5-4649-a34e-a51585690ec7"}]
[task 2023-03-12T11:45:41.267Z] 11:45:41 INFO - PID 1832 | 1678621541266 Marionette DEBUG 0 <- [1,799,null,{"value":null}]
[task 2023-03-12T11:45:41.293Z] 11:45:41 INFO - PID 1832 | 1678621541292 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2023-03-12T11:45:41.295Z] 11:45:41 INFO - PID 1832 | 1678621541293 webdriver::server DEBUG -> GET /session/873dd24b-49e2-4217-9054-81b893584c63/window
[task 2023-03-12T11:45:41.334Z] 11:45:41 INFO - PID 1832 | 1678621541333 Marionette DEBUG 0 -> [0,800,"WebDriver:GetWindowHandle",{}]
[task 2023-03-12T11:45:41.334Z] 11:45:41 INFO - PID 1832 | 1678621541333 Marionette DEBUG 0 <- [1,800,null,{"value":"214dc363-59f5-4649-a34e-a51585690ec7"}]
[task 2023-03-12T11:45:41.336Z] 11:45:41 INFO - PID 1832 | 1678621541335 webdriver::server DEBUG <- 200 OK {"value":"214dc363-59f5-4649-a34e-a51585690ec7"}
[task 2023-03-12T11:45:41.338Z] 11:45:41 INFO - PID 1832 | 1678621541337 webdriver::server DEBUG -> GET /session/873dd24b-49e2-4217-9054-81b893584c63/window/handles
[task 2023-03-12T11:45:41.374Z] 11:45:41 INFO - PID 1832 | 1678621541372 Marionette DEBUG 0 -> [0,801,"WebDriver:GetWindowHandles",{}]
[task 2023-03-12T11:45:41.375Z] 11:45:41 INFO - PID 1832 | 1678621541373 Marionette DEBUG 0 <- [1,801,null,["214dc363-59f5-4649-a34e-a51585690ec7"]]
[task 2023-03-12T11:45:41.425Z] 11:45:41 INFO - PID 1832 | 1678621541424 webdriver::server DEBUG <- 200 OK {"value":["214dc363-59f5-4649-a34e-a51585690ec7"]}
[task 2023-03-12T11:45:41.427Z] 11:45:41 INFO - PID 1832 | 1678621541426 webdriver::server DEBUG -> POST /session/873dd24b-49e2-4217-9054-81b893584c63/window {"handle": "214dc363-59f5-4649-a34e-a51585690ec7"}
[task 2023-03-12T11:45:41.438Z] 11:45:41 INFO - PID 1832 | 1678621541437 Marionette DEBUG 0 -> [0,802,"WebDriver:SwitchToWindow",{"handle":"214dc363-59f5-4649-a34e-a51585690ec7"}]
[task 2023-03-12T11:45:41.440Z] 11:45:41 INFO - PID 1832 | 1678621541439 Marionette DEBUG 0 <- [1,802,null,{"value":null}]
[task 2023-03-12T11:45:41.444Z] 11:45:41 INFO - PID 1832 | 1678621541443 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2023-03-12T11:45:41.446Z] 11:45:41 INFO - PID 1832 | 1678621541445 webdriver::server DEBUG -> POST /session/873dd24b-49e2-4217-9054-81b893584c63/window/rect {"width": 800, "height": 600}
[task 2023-03-12T11:45:41.509Z] 11:45:41 INFO - PID 1832 | 1678621541508 Marionette DEBUG 0 -> [0,803,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2023-03-12T11:45:41.511Z] 11:45:41 INFO - PID 1832 | 1678621541509 Marionette TRACE Requested window geometry matches
[task 2023-03-12T11:45:41.512Z] 11:45:41 INFO - PID 1832 | 1678621541511 Marionette DEBUG 0 <- [1,803,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2023-03-12T11:45:41.547Z] 11:45:41 INFO - PID 1832 | 1678621541546 webdriver::server DEBUG <- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2023-03-12T11:45:41.549Z] 11:45:41 INFO - PID 1832 | 1678621541547 webdriver::server DEBUG -> POST /session/873dd24b-49e2-4217-9054-81b893584c63/frame {"id": null}
[task 2023-03-12T11:45:41.565Z] 11:45:41 INFO - PID 1832 | 1678621541564 Marionette DEBUG 0 -> [0,804,"WebDriver:SwitchToFrame",{"id":null}]
[task 2023-03-12T11:45:41.606Z] 11:45:41 INFO - PID 1832 | 1678621541605 Marionette DEBUG 0 <- [1,804,null,{"value":null}]
[task 2023-03-12T11:45:41.650Z] 11:45:41 INFO - PID 1832 | 1678621541648 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2023-03-12T11:45:41.656Z] 11:45:41 INFO - PID 1832 | 1678621541655 webdriver::server DEBUG -> DELETE /session/873dd24b-49e2-4217-9054-81b893584c63
[task 2023-03-12T11:45:41.694Z] 11:45:41 INFO - PID 1832 | 1678621541693 Marionette DEBUG 0 -> [0,805,"Marionette:Quit",{"flags":["eForceQuit"]}]
[task 2023-03-12T11:45:41.695Z] 11:45:41 INFO - PID 1832 | 1678621541694 Marionette INFO Stopped listening on port 35052
[task 2023-03-12T11:45:41.700Z] 11:45:41 INFO - PID 1832 | 1678621541697 Marionette TRACE [30] MarionetteCommands actor destroyed for window id 21474836575
[task 2023-03-12T11:45:41.701Z] 11:45:41 INFO - PID 1832 | 1678621541699 Marionette TRACE [33] MarionetteCommands actor destroyed for window id 21474836584
[task 2023-03-12T11:45:41.702Z] 11:45:41 INFO - PID 1832 | 1678621541700 Marionette TRACE [31] MarionetteCommands actor destroyed for window id 21474836578
[task 2023-03-12T11:45:41.702Z] 11:45:41 INFO - PID 1832 | 1678621541700 Marionette TRACE [32] MarionetteCommands actor destroyed for window id 21474836581
[task 2023-03-12T11:45:42.093Z] 11:45:42 INFO - PID 1832 | 1678621542091 Marionette TRACE Received observer notification quit-application
[task 2023-03-12T11:45:42.101Z] 11:45:42 INFO - PID 1832 | 1678621542100 Marionette TRACE Received observer notification quit-application
[task 2023-03-12T11:45:42.103Z] 11:45:42 INFO - PID 1832 | 1678621542102 Marionette DEBUG Marionette stopped listening
[task 2023-03-12T11:45:42.118Z] 11:45:42 INFO - PID 1832 | 1678621542116 Marionette DEBUG 0 <- [1,805,null,{"cause":"shutdown","forced":false,"in_app":true}]
[task 2023-03-12T11:45:42.308Z] 11:45:42 INFO - PID 1832 | 1678621542306 webdriver::server DEBUG Teardown session
[task 2023-03-12T11:45:42.334Z] 11:45:42 INFO - PID 1832 | 1678621542329 Marionette DEBUG Closed connection 0
[task 2023-03-12T11:45:43.524Z] 11:45:43 INFO - PID 1832 | console.error: RemoteSecuritySettings.jsm:
[task 2023-03-12T11:45:43.525Z] 11:45:43 INFO - PID 1832 | Failed to download attachment: TypeError: NetworkError: Network request failed
[task 2023-03-12T11:45:43.538Z] 11:45:43 INFO - PID 1832 | console.error: RemoteSecuritySettings.jsm:
<...>
[task 2023-03-12T11:45:45.592Z] 11:45:45 INFO - PID 1832 | Phase: profile-before-change-telemetry
[task 2023-03-12T11:45:45.593Z] 11:45:45 INFO - PID 1832 | State: Error getting state: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange" at addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-03-12T11:45:45.593Z] 11:45:45 INFO - PID 1832 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-03-12T11:45:45.593Z] 11:45:45 INFO - PID 1832 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-03-12T11:45:45.593Z] 11:45:45 INFO - PID 1832 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-03-12T11:45:45.593Z] 11:45:45 INFO - PID 1832 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-03-12T11:45:45.593Z] 11:45:45 INFO - PID 1832 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-03-12T11:45:45.593Z] 11:45:45 INFO - PID 1832 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-03-12T11:45:45.594Z] 11:45:45 INFO - PID 1832 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-03-12T11:45:45.594Z] 11:45:45 INFO - PID 1832 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-03-12T11:45:45.595Z] 11:45:45 INFO - PID 1832 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-03-12T11:45:45.596Z] 11:45:45 INFO - PID 1832 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:824:36
[task 2023-03-12T11:45:45.596Z] 11:45:45 INFO - PID 1832 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-03-12T11:45:45.599Z] 11:45:45 INFO - PID 1832 | WARNING: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-03-12T11:45:45.599Z] 11:45:45 INFO - PID 1832 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-03-12T11:45:45.599Z] 11:45:45 INFO - PID 1832 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-03-12T11:45:45.599Z] 11:45:45 INFO - PID 1832 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-03-12T11:45:45.599Z] 11:45:45 INFO - PID 1832 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-03-12T11:45:45.599Z] 11:45:45 INFO - PID 1832 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-03-12T11:45:45.600Z] 11:45:45 INFO - PID 1832 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-03-12T11:45:45.600Z] 11:45:45 INFO - PID 1832 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-03-12T11:45:45.600Z] 11:45:45 INFO - PID 1832 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-03-12T11:45:45.600Z] 11:45:45 INFO - PID 1832 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-03-12T11:45:45.600Z] 11:45:45 INFO - PID 1832 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-03-12T11:45:45.600Z] 11:45:45 INFO - PID 1832 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:824:36
[task 2023-03-12T11:45:45.600Z] 11:45:45 INFO - PID 1832 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-03-12T11:45:45.605Z] 11:45:45 INFO - PID 1832 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-03-12T11:45:45.606Z] 11:45:45 INFO - PID 1832 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-03-12T11:45:45.607Z] 11:45:45 INFO - PID 1832 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-03-12T11:45:45.608Z] 11:45:45 INFO - PID 1832 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-03-12T11:45:45.609Z] 11:45:45 INFO - PID 1832 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-03-12T11:45:48.423Z] 11:45:48 INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/find_element_from_shadow_root/find.py | expected OK
[task 2023-03-12T11:45:48.423Z] 11:45:48 INFO - TEST-INFO took 80006ms
[task 2023-03-12T11:45:48.435Z] 11:45:48 INFO - PID 1832 | ==1846==WARNING: Can't write to symbolizer at fd 39
[task 2023-03-12T11:45:49.126Z] 11:45:49 INFO - Closing logging queue
[task 2023-03-12T11:45:49.126Z] 11:45:49 INFO - queue closed
[task 2023-03-12T11:45:49.133Z] 11:45:49 INFO - PID 2337 | 1678621549131 geckodriver INFO Listening on 127.0.0.1:42969
[task 2023-03-12T11:45:49.134Z] 11:45:49 INFO - Starting runner
[task 2023-03-12T11:45:49.367Z] 11:45:49 INFO - TEST-START | /webdriver/tests/find_element_from_shadow_root/user_prompts.py
Comment 1•2 years ago
|
||
Hi Henrik! Can you please take a look at this? Can't figure out what is the reason for the timeout. And if this does not need to be a general bug, please do tell so we can file bugs for each of the failures that were classified with this.
Thank you!
Comment hidden (Intermittent Failures Robot) |
Comment 3•2 years ago
|
||
There might be different reasons.
-
I can see a lot of failures from
RemoteSecuritySettings
trying to download an attachment. I filed bug 1821981. -
There is a slight delay in shutdown as caused by Telemetry. I filed bug 1821983.
-
But the main problem here is the amount of time Firefox took to startup. It was 11 seconds:
[task 2023-03-12T11:44:30.725Z] 11:44:30 INFO - PID 1832 | 1678621470724 Marionette DEBUG Waiting for initial application window
[task 2023-03-12T11:44:37.247Z] 11:44:37 INFO - PID 1832 | console.error: ({})
[task 2023-03-12T11:44:39.373Z] 11:44:39 INFO - PID 1832 | [ERROR glean_core] Error setting metrics feature config: Json(Error("EOF while parsing a value", line: 1, column: 0))
[task 2023-03-12T11:44:41.852Z] 11:44:41 INFO - PID 1832 | 1678621481851 Marionette TRACE Received observer notification browser-idle-startup-tasks-finished
Given that this is bug 1791951 I'm going to dupe.
Description
•