Closed Bug 1834080 Opened 3 years ago Closed 1 year ago

Intermittent toolkit/mozapps/extensions/test/xpcshell/test_system_allowed.js | single tracking bug

Categories

(Toolkit :: Add-ons Manager, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase)

Filed by: smolnar [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=416375924&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/MRtkIHEoQViYDpu9ljRWWw/runs/0/artifacts/public/logs/live_backing.log


INFO -  TEST-PASS | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_system_allowed.js | test_allowed_addons - [test_allowed_addons : 51] {} != null
[task 2023-05-19T15:26:08.380Z] 15:26:08     INFO -  "CONSOLE_MESSAGE: (info) 1684509968001	addons.xpi	DEBUG	Calling bootstrap method shutdown on system3@tests.mozilla.org version 1.0"
[task 2023-05-19T15:26:08.380Z] 15:26:08     INFO -  "CONSOLE_MESSAGE: (info) 1684509968001	addons.manager	DEBUG	before shutdown"
[task 2023-05-19T15:26:08.380Z] 15:26:08     INFO -  "CONSOLE_MESSAGE: (info) 1684509968001	addons.manager	DEBUG	shutdown"
[task 2023-05-19T15:26:08.380Z] 15:26:08     INFO -  "CONSOLE_MESSAGE: (info) 1684509968001	addons.manager	DEBUG	Calling shutdown blocker for XPIProvider"
[task 2023-05-19T15:26:08.380Z] 15:26:08     INFO -  "CONSOLE_MESSAGE: (info) 1684509968001	addons.xpi	DEBUG	shutdown"
[task 2023-05-19T15:26:08.380Z] 15:26:08     INFO -  "CONSOLE_MESSAGE: (info) 1684509968001	addons.xpi-utils	DEBUG	shutdown"
[task 2023-05-19T15:26:08.381Z] 15:26:08     INFO -  (xpcshell/head.js) | test run_next_test 1 pending (2)
[task 2023-05-19T15:26:08.381Z] 15:26:08     INFO -  (xpcshell/head.js) | test test_allowed_addons finished (2)
[task 2023-05-19T15:26:08.381Z] 15:26:08     INFO -  "CONSOLE_MESSAGE: (info) 1684509968010	addons.xpi-utils	DEBUG	XPI Database saved, setting schema version preference to 35"
[task 2023-05-19T15:26:08.381Z] 15:26:08     INFO -  "CONSOLE_MESSAGE: (info) 1684509968010	addons.manager	DEBUG	Async provider shutdown done"
[task 2023-05-19T15:26:08.381Z] 15:26:08     INFO -  (xpcshell/head.js) | test run_next_test 1 finished (1)
[task 2023-05-19T15:26:08.382Z] 15:26:08     INFO -  exiting test
[task 2023-05-19T15:26:08.382Z] 15:26:08     INFO -  "Force a GC"
[task 2023-05-19T15:26:08.382Z] 15:26:08     INFO -  PID 8193 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 724: Error: Phase "IOUtils: waiting for profileBeforeChange IO to complete" is finished, it is too late to register completion condition "Flush WebExtension StartupCache"
[task 2023-05-19T15:26:08.382Z] 15:26:08     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "Error: Phase "IOUtils: waiting for profileBeforeChange IO to complete" is finished, it is too late to register completion condition "Flush WebExtension StartupCache"" {file: "resource://gre/modules/AsyncShutdown.sys.mjs" line: 724}]"
[task 2023-05-19T15:26:08.383Z] 15:26:08     INFO -  "CONSOLE_MESSAGE: (info) QM_TRY failure (ERROR): 'Unavailable failed with resultCode 0x80004004, resultName NS_ERROR_ABORT, context dom::quota::FirstOriginInitializationAttempt::PersistentOrigin', file dom/quota/ActorsParent.cpp:4872"
[task 2023-05-19T15:26:08.383Z] 15:26:08     INFO -  "CONSOLE_MESSAGE: (info) QM_TRY failure (ERROR): 'Unavailable failed with resultCode 0x80004004, resultName NS_ERROR_ABORT, context dom::quota::FirstOriginInitializationAttempt::PersistentOrigin', file dom/quota/ActorsParent.cpp:6052"
[task 2023-05-19T15:26:08.383Z] 15:26:08     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "IndexedDB UnknownErr: ActorsParent.cpp:560"]"
[task 2023-05-19T15:26:08.384Z] 15:26:08     INFO -  PID 8193 | console.error: (new UnknownError("IndexedDB: main/addons-manager-settings getLastModified() IndexedDB:   The operation failed for reasons unrelated to the database itself and not covered by any other error code.", "resource://services-settings/IDBHelpers.jsm", 18))
[task 2023-05-19T15:26:08.384Z] 15:26:08     INFO -  PID 8193 | console.error: (new UnknownError("IndexedDB: main/addons-manager-settings getLastModified() IndexedDB:   The operation failed for reasons unrelated to the database itself and not covered by any other error code.", "resource://services-settings/IDBHelpers.jsm", 18))
[task 2023-05-19T15:26:08.384Z] 15:26:08  WARNING -  TEST-UNEXPECTED-FAIL | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_system_allowed.js | test_allowed_addons - [test_allowed_addons : 266] A promise chain failed to handle a rejection: Phase "IOUtils: waiting for profileBeforeChange IO to complete" is finished, it is too late to register completion condition "Flush WebExtension StartupCache" - stack: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:724:15
[task 2023-05-19T15:26:08.385Z] 15:26:08     INFO -  save@resource://gre/modules/ExtensionParent.jsm:2126:35
[task 2023-05-19T15:26:08.385Z] 15:26:08     INFO -  set@resource://gre/modules/ExtensionParent.jsm:2253:18
[task 2023-05-19T15:26:08.385Z] 15:26:08     INFO -  _execute_test@/opt/worker/tasks/task_168450841317429/build/tests/xpcshell/head.js:702:23
[task 2023-05-19T15:26:08.385Z] 15:26:08     INFO -  @-e:1:1
[task 2023-05-19T15:26:08.385Z] 15:26:08     INFO -  Rejection date: Fri May 19 2023 15:26:08 GMT+0000 (Greenwich Mean Time) - false == true
[task 2023-05-19T15:26:08.385Z] 15:26:08     INFO -  resource://testing-common/PromiseTestUtils.sys.mjs:assertNoUncaughtRejections:266
[task 2023-05-19T15:26:08.386Z] 15:26:08     INFO -  /opt/worker/tasks/task_168450841317429/build/tests/xpcshell/head.js:_execute_test:711
[task 2023-05-19T15:26:08.386Z] 15:26:08     INFO -  -e:null:1
[task 2023-05-19T15:26:08.386Z] 15:26:08     INFO -  exiting test
[task 2023-05-19T15:26:08.386Z] 15:26:08     INFO -  PID 8193 | JavaScript error: /opt/worker/tasks/task_168450841317429/build/tests/xpcshell/head.js, line 868: NS_ERROR_ABORT:
[task 2023-05-19T15:26:08.386Z] 15:26:08     INFO -  <<<<<<<
[task 2023-05-19T15:26:08.386Z] 15:26:08     INFO -  TEST-START | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_update_noSystemAddonUpdate.js
[task 2023-05-19T15:26:09.367Z] 15:26:09  WARNING -  TEST-UNEXPECTED-FAIL | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_update_noSystemAddonUpdate.js | xpcshell return code: 0
[task 2023-05-19T15:26:09.367Z] 15:26:09     INFO -  TEST-INFO took 1001ms
[task 2023-05-19T15:26:09.367Z] 15:26:09     INFO -  >>>>>>>
[task 2023-05-19T15:26:09.368Z] 15:26:09     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2023-05-19T15:26:09.368Z] 15:26:09     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2023-05-19T15:26:09.368Z] 15:26:09     INFO -  running event loop
[task 2023-05-19T15:26:09.368Z] 15:26:09     INFO -  xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_update_noSystemAddonUpdate.js | Starting
[task 2023-05-19T15:26:09.368Z] 15:26:09     INFO -  (xpcshell/head.js) | test pending (2)
[task 2023-05-19T15:26:09.368Z] 15:26:09     INFO -  (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2023-05-19T15:26:09.369Z] 15:26:09     INFO -  (xpcshell/head.js) | test run_next_test 1 pending (2)
[task 2023-05-19T15:26:09.369Z] 15:26:09     INFO -  (xpcshell/head.js) | test finished (2)
[task 2023-05-19T15:26:09.369Z] 15:26:09     INFO -  xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_update_noSystemAddonUpdate.js | Starting test_systems_update_uninstall_check
[task 2023-05-19T15:26:09.369Z] 15:26:09     INFO -  (xpcshell/head.js) | test test_systems_update_uninstall_check pending (2)
[task 2023-05-19T15:26:09.369Z] 15:26:09     INFO -  "Clearing existing database."
[task 2023-05-19T15:26:09.369Z] 15:26:09     INFO -  (xpcshell/head.js) | test run_next_test 1 finished (2)
[task 2023-05-19T15:26:09.370Z] 15:26:09     INFO -  "CONSOLE_MESSAGE: (info) 1684509968895	addons.manager	DEBUG	Application has been upgraded"
<...>
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 3 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago1 year ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 1 year ago1 year ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.