Open Bug 1863117 Opened 2 years ago Updated 2 years ago

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

Categories

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

Product:
Toolkit
Component:
Add-ons Manager
Type:
defect

Tracking

()

Tracking Flags:
Tracking Status
firefox-esr115 --- unaffected
firefox119 --- unaffected
firefox120 --- unaffected
firefox121 --- affected
firefox122 --- affected

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression, test-verify-fail)

Attachments

(1 obsolete file)

Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=434953623&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/SoD9llf-S0mlQwD0iOMGIA/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/SoD9llf-S0mlQwD0iOMGIA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2023-11-04T02:39:24.844Z] 02:39:24     INFO -  TEST-START | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_gmpProvider.js
[task 2023-11-04T02:39:36.277Z] 02:39:36  WARNING -  TEST-UNEXPECTED-FAIL | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_gmpProvider.js | xpcshell return code: 1
[task 2023-11-04T02:39:36.278Z] 02:39:36     INFO -  TEST-INFO took 11434ms
[task 2023-11-04T02:39:36.278Z] 02:39:36     INFO -  >>>>>>>
[task 2023-11-04T02:39:36.278Z] 02:39:36     INFO -  PID 3534 | *** You are running in chaos test mode. See ChaosMode.h. ***
[task 2023-11-04T02:39:36.278Z] 02:39:36     INFO -  PID 3534 | [Parent 3534, Main Thread] WARNING: Couldn't get the user appdata directory. Crash events may not be produced.: file /builds/worker/checkouts/gecko/toolkit/crashreporter/nsExceptionHandler.cpp:2886
[task 2023-11-04T02:39:36.278Z] 02:39:36     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2023-11-04T02:39:36.279Z] 02:39:36     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2023-11-04T02:39:36.279Z] 02:39:36     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2023-11-04T02:39:36.279Z] 02:39:36     INFO -  running event loop
[task 2023-11-04T02:39:36.279Z] 02:39:36     INFO -  xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_gmpProvider.js | Starting setup
[task 2023-11-04T02:39:36.279Z] 02:39:36     INFO -  (xpcshell/head.js) | test pending (2)
[task 2023-11-04T02:39:36.279Z] 02:39:36     INFO -  TEST-PASS | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_gmpProvider.js |  - set of mock addons matches the actual set of plugins - ["gmp-gmpopenh264","gmp-widevinecdm-l1","gmp-widevinecdm"] deepEqual ["gmp-gmpopenh264","gmp-widevinecdm-l1","gmp-widevinecdm"]
[task 2023-11-04T02:39:36.279Z] 02:39:36     INFO -  PID 3534 | [Parent 3534, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, NS_ERROR_INVALID_ARG) failed with result 0x80520012 (NS_ERROR_FILE_NOT_FOUND): file /builds/worker/checkouts/gecko/intl/l10n/L10nRegistry.cpp:385
[task 2023-11-04T02:39:36.280Z] 02:39:36     INFO -  PID 3534 | [Parent 3534, GMPThread] WARNING: Failed to delete GMP storage directory: file /builds/worker/checkouts/gecko/dom/media/gmp/GMPServiceParent.cpp:1863
[task 2023-11-04T02:39:36.280Z] 02:39:36     INFO -  (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2023-11-04T02:39:36.280Z] 02:39:36     INFO -  "CONSOLE_MESSAGE: (info) 1699065565309	addons.manager	DEBUG	Application has been upgraded"
[task 2023-11-04T02:39:36.280Z] 02:39:36     INFO -  "CONSOLE_MESSAGE: (info) 1699065565310	addons.manager	DEBUG	Loaded provider scope for resource://gre/modules/addons/GMPProvider.sys.mjs"
[task 2023-11-04T02:39:36.280Z] 02:39:36     INFO -  "CONSOLE_MESSAGE: (info) 1699065565314	addons.manager	DEBUG	Loaded provider scope for resource://gre/modules/addons/SitePermsAddonProvider.sys.mjs"
[task 2023-11-04T02:39:36.280Z] 02:39:36     INFO -  "CONSOLE_MESSAGE: (info) 1699065565321	addons.manager	DEBUG	Starting provider: GMPProvider"
[task 2023-11-04T02:39:36.281Z] 02:39:36     INFO -  "CONSOLE_MESSAGE: (info) 1699065565340	addons.manager	DEBUG	Registering shutdown blocker for GMPProvider"
[task 2023-11-04T02:39:36.281Z] 02:39:36     INFO -  "CONSOLE_MESSAGE: (info) 1699065565344	addons.manager	DEBUG	Provider finished startup: GMPProvider"
[task 2023-11-04T02:39:36.281Z] 02:39:36     INFO -  "CONSOLE_MESSAGE: (info) 1699065565346	addons.manager	DEBUG	Starting provider: XPIProvider"
[task 2023-11-04T02:39:36.281Z] 02:39:36     INFO -  "CONSOLE_MESSAGE: (info) 1699065565350	addons.xpi	DEBUG	startup"
[task 2023-11-04T02:39:36.282Z] 02:39:36     INFO -  "CONSOLE_MESSAGE: (info) 1699065565351	addons.xpi	DEBUG	List of built-in add-ons is missing or invalid.: [Exception... "Component returned failure code: 0xc1f30001 (NS_ERROR_NOT_INITIALIZED) [nsIXPCComponents_Utils.readUTF8URI]"  nsresult: "0xc1f30001 (NS_ERROR_NOT_INITIALIZED)"  location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm :: startup :: line 2509"  data: no] Stack trace: startup()@resource://gre/modules/addons/XPIProvider.jsm:2509
[task 2023-11-04T02:39:36.282Z] 02:39:36     INFO -  callProvider()@resource://gre/modules/AddonManager.sys.mjs:229
[task 2023-11-04T02:39:36.282Z] 02:39:36     INFO -  _startProvider()@resource://gre/modules/AddonManager.sys.mjs:538
[task 2023-11-04T02:39:36.282Z] 02:39:36     INFO -  startup()@resource://gre/modules/AddonManager.sys.mjs:749
[task 2023-11-04T02:39:36.282Z] 02:39:36     INFO -  startup()@resource://gre/modules/AddonManager.sys.mjs:3690
[task 2023-11-04T02:39:36.283Z] 02:39:36     INFO -  observe()@resource://gre/modules/amManager.sys.mjs:73
[task 2023-11-04T02:39:36.283Z] 02:39:36     INFO -  promiseStartupManager()@resource://testing-common/AddonTestUtils.sys.mjs:776
[task 2023-11-04T02:39:36.283Z] 02:39:36     INFO -  /opt/worker/tasks/task_169906358688164/build/tests/xpcshell/tests/toolkit/mozapps/extensions/test/xpcshell/test_gmpProvider.js:96
[task 2023-11-04T02:39:36.283Z] 02:39:36     INFO -  _run_next_test/<()@/opt/worker/tasks/task_169906358688164/build/tests/xpcshell/head.js:1758
[task 2023-11-04T02:39:36.283Z] 02:39:36     INFO -  _run_next_test()@/opt/worker/tasks/task_169906358688164/build/tests/xpcshell/head.js:1758
[task 2023-11-04T02:39:36.284Z] 02:39:36     INFO -  run()@/opt/worker/tasks/task_169906358688164/build/tests/xpcshell/head.js:804
[task 2023-11-04T02:39:36.284Z] 02:39:36     INFO -  _do_main()@/opt/worker/tasks/task_169906358688164/build/tests/xpcshell/head.js:245
[task 2023-11-04T02:39:36.284Z] 02:39:36     INFO -  _execute_test()@/opt/worker/tasks/task_169906358688164/build/tests/xpcshell/head.js:592
[task 2023-11-04T02:39:36.284Z] 02:39:36     INFO -  -e:1"
[task 2023-11-04T02:39:36.284Z] 02:39:36     INFO -  "CONSOLE_MESSAGE: (info) 1699065565362	addons.xpi	INFO	SystemAddonLocation directory is missing"
[task 2023-11-04T02:39:36.284Z] 02:39:36     INFO -  "CONSOLE_MESSAGE: (info) 1699065565387	addons.xpi	INFO	Removing all system add-on upgrades."
[task 2023-11-04T02:39:36.284Z] 02:39:36     INFO -  "CONSOLE_MESSAGE: (info) 1699065565388	addons.xpi	DEBUG	checkForChanges"
[task 2023-11-04T02:39:36.285Z] 02:39:36     INFO -  "CONSOLE_MESSAGE: (info) 1699065565392	addons.xpi	DEBUG	Loaded add-on state: ${}"
[task 2023-11-04T02:39:36.285Z] 02:39:36     INFO -  "CONSOLE_MESSAGE: (info) 1699065565403	addons.xpi	DEBUG	scanForChanges changed: false, state: {}"
[task 2023-11-04T02:39:36.285Z] 02:39:36     INFO -  "CONSOLE_MESSAGE: (info) 1699065565406	addons.xpi	DEBUG	Empty XPI database, setting schema version preference to 35"
[task 2023-11-04T02:39:36.285Z] 02:39:36     INFO -  "CONSOLE_MESSAGE: (info) 1699065565413	addons.xpi	DEBUG	No changes found"
[task 2023-11-04T02:39:36.285Z] 02:39:36     INFO -  "CONSOLE_MESSAGE: (info) 1699065565427	addons.manager	DEBUG	Registering shutdown blocker for XPIProvider"
[task 2023-11-04T02:39:36.285Z] 02:39:36     INFO -  "CONSOLE_MESSAGE: (info) 1699065565435	addons.manager	DEBUG	Provider finished startup: XPIProvider"
[task 2023-11-04T02:39:36.285Z] 02:39:36     INFO -  "CONSOLE_MESSAGE: (info) 1699065565439	addons.manager	DEBUG	Completed startup sequence"
[task 2023-11-04T02:39:36.286Z] 02:39:36     INFO -  (xpcshell/head.js) | test run_next_test 1 pending (2)
[task 2023-11-04T02:39:36.286Z] 02:39:36     INFO -  (xpcshell/head.js) | test finished (2)
[task 2023-11-04T02:39:36.286Z] 02:39:36     INFO -  xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_gmpProvider.js | Starting test_notInstalled
[task 2023-11-04T02:39:36.286Z] 02:39:36     INFO -  (xpcshell/head.js) | test test_notInstalled pending (2)
[task 2023-11-04T02:39:36.286Z] 02:39:36     INFO -  PID 3534 | 1699065565645	Toolkit.GMP	INFO	GMPWrapper(gmp-gmpopenh264) _handleEnabledChanged() id=gmp-gmpopenh264 isActive=false
[task 2023-11-04T02:39:36.286Z] 02:39:36     INFO -  PID 3534 | 1699065565652	Toolkit.GMP	INFO	GMPWrapper(gmp-widevinecdm-l1) _handleEnabledChanged() id=gmp-widevinecdm-l1 isActive=false
[task 2023-11-04T02:39:36.287Z] 02:39:36     INFO -  PID 3534 | 1699065565660	Toolkit.GMP	INFO	GMPWrapper(gmp-widevinecdm) _handleEnabledChanged() id=gmp-widevinecdm isActive=false
[task 2023-11-04T02:39:36.287Z] 02:39:36     INFO -  (xpcshell/head.js) | test run_next_test 1 finished (2)
[task 2023-11-04T02:39:36.287Z] 02:39:36     INFO -  "CONSOLE_MESSAGE: (info) 1699065565645	Toolkit.GMP	INFO	GMPWrapper(gmp-gmpopenh264) _handleEnabledChanged() id=gmp-gmpopenh264 isActive=false"
[task 2023-11-04T02:39:36.287Z] 02:39:36     INFO -  "CONSOLE_MESSAGE: (info) 1699065565652	Toolkit.GMP	INFO	GMPWrapper(gmp-widevinecdm-l1) _handleEnabledChanged() id=gmp-widevinecdm-l1 isActive=false"
[task 2023-11-04T02:39:36.287Z] 02:39:36     INFO -  PID 3534 | [WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2023-11-04T02:39:36.288Z] 02:39:36     INFO -  "CONSOLE_MESSAGE: (info) 1699065565660	Toolkit.GMP	INFO	GMPWrapper(gmp-widevinecdm) _handleEnabledChanged() id=gmp-widevinecdm isActive=false"
[task 2023-11-04T02:39:36.288Z] 02:39:36     INFO -  "CONSOLE_MESSAGE: (info) 1699065565677	addons.xpi-utils	DEBUG	Starting async load of XPI database /var/folders/vt/_9k8c5ys2n994wzpkn_p5msh000014/T/xpc-profile-3edz9ud0/extensions.json"
[task 2023-11-04T02:39:36.288Z] 02:39:36     INFO -  TEST-PASS | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_gmpProvider.js | test_notInstalled - [test_notInstalled : 117] 3 == 3
[task 2023-11-04T02:39:36.288Z] 02:39:36     INFO -  TEST-PASS | xpcshell.ini:toolkit/mozapps/extensions/test/xpcshell/test_gmpProvider.js | test_notInstalled - [test_notInstalled : 120] true == true

:aosmond, since you are the author of the regressor, bug 1862051, could you take a look?

For more information, please visit BugBot documentation.

Flags: needinfo?(aosmond)

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

status-firefox119: --- → unaffected
status-firefox120: --- → unaffected
status-firefox121: --- → affected
status-firefox-esr115: --- → unaffected

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

status-firefox122: --- → affected
Flags: needinfo?(aosmond)
Attachment #9384071 - Attachment is obsolete: true
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: