Filed by: mlaza [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=408286716&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/bKz-MbD3TTGpho9B6onyzg/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/bKz-MbD3TTGpho9B6onyzg/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2023-03-08T14:55:04.477Z] 14:55:04     INFO -  "CONSOLE_MESSAGE: (info) 1678287303897	addons.xpi	DEBUG	Calling bootstrap method shutdown on test-extension-0@mozilla version 1.0"
[task 2023-03-08T14:55:04.478Z] 14:55:04     INFO -  "CONSOLE_MESSAGE: (info) 1678287303901	addons.xpi-utils	DEBUG	Finished async read of XPI database, parsing..."
[task 2023-03-08T14:55:04.478Z] 14:55:04     INFO -  "CONSOLE_MESSAGE: (info) 1678287303912	addons.xpi-utils	DEBUG	Successfully read XPI database"
[task 2023-03-08T14:55:04.478Z] 14:55:04     INFO -  "CONSOLE_MESSAGE: (info) 1678287303912	addons.manager	DEBUG	before shutdown"
[task 2023-03-08T14:55:04.479Z] 14:55:04     INFO -  "CONSOLE_MESSAGE: (info) 1678287303912	addons.manager	DEBUG	shutdown"
[task 2023-03-08T14:55:04.479Z] 14:55:04     INFO -  "CONSOLE_MESSAGE: (info) 1678287303912	addons.manager	DEBUG	Calling shutdown blocker for SitePermsAddonProvider"
[task 2023-03-08T14:55:04.479Z] 14:55:04     INFO -  "CONSOLE_MESSAGE: (info) 1678287303912	addons.manager	DEBUG	Calling shutdown blocker for GMPProvider"
[task 2023-03-08T14:55:04.480Z] 14:55:04     INFO -  "CONSOLE_MESSAGE: (info) 1678287303913	addons.manager	DEBUG	Calling shutdown blocker for XPIProvider"
[task 2023-03-08T14:55:04.480Z] 14:55:04     INFO -  "CONSOLE_MESSAGE: (info) 1678287303913	addons.xpi	DEBUG	shutdown"
[task 2023-03-08T14:55:04.480Z] 14:55:04     INFO -  "CONSOLE_MESSAGE: (info) 1678287303913	addons.xpi-utils	DEBUG	shutdown"
[task 2023-03-08T14:55:04.480Z] 14:55:04     INFO -  (xpcshell/head.js) | test run_next_test 2 pending (2)
[task 2023-03-08T14:55:04.480Z] 14:55:04     INFO -  (xpcshell/head.js) | test test_wait_addons_startup_before_granting_quit finished (2)
[task 2023-03-08T14:55:04.480Z] 14:55:04     INFO -  "CONSOLE_MESSAGE: (info) 1678287303914	addons.manager	DEBUG	Async provider shutdown done"
[task 2023-03-08T14:55:04.481Z] 14:55:04     INFO -  (xpcshell/head.js) | test run_next_test 2 finished (1)
[task 2023-03-08T14:55:04.481Z] 14:55:04     INFO -  exiting test
[task 2023-03-08T14:55:04.481Z] 14:55:04     INFO -  "Force a GC"
[task 2023-03-08T14:55:04.481Z] 14:55:04     INFO -  PID 1718 | JavaScript error: resource://gre/modules/DeferredTask.sys.mjs, line 221: Error: Unable to arm timer, the object has been finalized.
[task 2023-03-08T14:55:04.481Z] 14:55:04     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "Error: Unable to arm timer, the object has been finalized." {file: "resource://gre/modules/DeferredTask.sys.mjs" line: 221}]"
[task 2023-03-08T14:55:04.481Z] 14:55:04  WARNING -  TEST-UNEXPECTED-FAIL | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_shutdown_barriers.js | test_wait_addons_startup_before_granting_quit - [test_wait_addons_startup_before_granting_quit : 266] A promise chain failed to handle a rejection: Unable to arm timer, the object has been finalized. - stack: arm@resource://gre/modules/DeferredTask.sys.mjs:221:13
[task 2023-03-08T14:55:04.482Z] 14:55:04     INFO -  save@resource://gre/modules/ExtensionParent.jsm:2135:27
[task 2023-03-08T14:55:04.482Z] 14:55:04     INFO -  set@resource://gre/modules/ExtensionParent.jsm:2253:18
[task 2023-03-08T14:55:04.482Z] 14:55:04     INFO -  observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-03-08T14:55:04.482Z] 14:55:04     INFO -  _execute_test@/opt/worker/tasks/task_167828682543524/build/tests/xpcshell/head.js:699:23
[task 2023-03-08T14:55:04.483Z] 14:55:04     INFO -  @-e:1:1
[task 2023-03-08T14:55:04.483Z] 14:55:04     INFO -  Rejection date: Wed Mar 08 2023 14:55:03 GMT+0000 (Greenwich Mean Time) - false == true
[task 2023-03-08T14:55:04.483Z] 14:55:04     INFO -  resource://testing-common/PromiseTestUtils.sys.mjs:assertNoUncaughtRejections:266
[task 2023-03-08T14:55:04.483Z] 14:55:04     INFO -  /opt/worker/tasks/task_167828682543524/build/tests/xpcshell/head.js:_execute_test:711
[task 2023-03-08T14:55:04.483Z] 14:55:04     INFO -  -e:null:1
[task 2023-03-08T14:55:04.483Z] 14:55:04     INFO -  exiting test
[task 2023-03-08T14:55:04.483Z] 14:55:04     INFO -  PID 1718 | JavaScript error: /opt/worker/tasks/task_167828682543524/build/tests/xpcshell/head.js, line 863: NS_ERROR_ABORT:
[task 2023-03-08T14:55:04.484Z] 14:55:04     INFO -  PID 1718 | JavaScript error: resource://gre/modules/ExtensionParent.jsm, line 2126: AbortError: IOUtils.profileBeforeChange getter: IOUtils: profileBeforeChange phase has already finished
[task 2023-03-08T14:55:04.484Z] 14:55:04     INFO -  <<<<<<<
[task 2023-03-08T14:55:04.484Z] 14:55:04     INFO -  TEST-START | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_shutdown_barriers.js

:jstutte, since you are the author of the regressor, bug 1799421, could you take a look?

For more information, please visit auto_nag documentation.

I hope this will be addressed by the updated patch on bug 1799421.

Set release status flags based on info from the regressing bug 1799421

Set release status flags based on info from the regressing bug 1799421

(In reply to Jens Stutte [:jstutte] from comment #2)

I hope this will be addressed by the updated patch on bug 1799421.

It seems it helped.