1894145 - Perma Windows 11 Add-AppxPackage : Deployment failed with HRESULT: 0x80073CF0, Package could not be opened.

Status: RESOLVED FIXED

(Firefox Build System :: Task Configuration, defect, P1)

Description

[Treeherder Bug Filer]

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

---

[task 2024-04-30T01:02:09.576Z] 01:02:09     INFO - Using _rmtree_windows ...
[task 2024-04-30T01:02:09.576Z] 01:02:09     INFO - Running command: del /F /Q "D:\task_171443867847847\tmpfile_stderr"
[task 2024-04-30T01:02:09.585Z] 01:02:09     INFO - Return code: 0
[task 2024-04-30T01:02:09.586Z] 01:02:09     INFO - Using _rmtree_windows ...
[task 2024-04-30T01:02:09.586Z] 01:02:09     INFO - Running command: del /F /Q "D:\task_171443867847847\tmpfile_stdout"
[task 2024-04-30T01:02:09.595Z] 01:02:09     INFO - Return code: 0
[task 2024-04-30T01:02:09.595Z] 01:02:09     INFO - Return code: 0
[task 2024-04-30T01:02:09.595Z] 01:02:09     INFO - mkdir: D:\task_171443867847847\build\application
[task 2024-04-30T01:02:09.595Z] 01:02:09     INFO - Getting output from command: ['D:\\task_171443867847847\\build\\venv\\Scripts\\mozinstall', 'D:\\task_171443867847847\\build\\installer.msix', '--destination', 'D:\\task_171443867847847\\build\\application']
[task 2024-04-30T01:02:09.595Z] 01:02:09     INFO - Copy/paste: D:\task_171443867847847\build\venv\Scripts\mozinstall D:\task_171443867847847\build\installer.msix --destination D:\task_171443867847847\build\application
[task 2024-04-30T01:02:11.816Z] 01:02:11     INFO - Reading from file tmpfile_stderr
[task 2024-04-30T01:02:11.818Z] 01:02:11    ERROR - Errors received:
[task 2024-04-30T01:02:11.818Z] 01:02:11    ERROR -  Add-AppxPackage : Deployment failed with HRESULT: 0x80073CF0, Package could not be opened.
[task 2024-04-30T01:02:11.818Z] 01:02:11    ERROR -  error 0x8007000B: The app manifest publisher name (CN=Mozilla Corporation, OU=Firefox Engineering Operations, O=Mozilla Corporation, 
[task 2024-04-30T01:02:11.818Z] 01:02:11    ERROR -  L=Mountain View, S=California, C=US) must match the subject name of the signing certificate (CN=Mozilla Fake SPC).
[task 2024-04-30T01:02:11.818Z] 01:02:11    ERROR -  NOTE: For additional information, look for [ActivityId] cb6c4045-149d-0006-eb80-e876999ada01 in the Event Log or use the command line 
[task 2024-04-30T01:02:11.818Z] 01:02:11    ERROR -  Get-AppPackageLog -ActivityID cb6c4045-149d-0006-eb80-e876999ada01
[task 2024-04-30T01:02:11.819Z] 01:02:11    ERROR -  At line:1 char:1
[task 2024-04-30T01:02:11.819Z] 01:02:11    ERROR -  + Add-AppxPackage -Path D:\task_171443867847847\build\installer.msix
[task 2024-04-30T01:02:11.819Z] 01:02:11    ERROR -  + ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
[task 2024-04-30T01:02:11.819Z] 01:02:11    ERROR -      + CategoryInfo          : OpenError: (D:\task_1714438...\installer.msix:String) [Add-AppxPackage], FileNotFoundException
[task 2024-04-30T01:02:11.819Z] 01:02:11    ERROR -      + FullyQualifiedErrorId : DeploymentError,Microsoft.Windows.Appx.PackageManager.Commands.AddAppxPackageCommand
[task 2024-04-30T01:02:11.819Z] 01:02:11    ERROR -  Traceback (most recent call last):
[task 2024-04-30T01:02:11.819Z] 01:02:11    ERROR -    File "D:\task_171443867847847\build\venv\Lib\site-packages\mozinstall\mozinstall.py", line 136, in install
[task 2024-04-30T01:02:11.819Z] 01:02:11    ERROR -      install_dir = _install_msix(src)
[task 2024-04-30T01:02:11.819Z] 01:02:11    ERROR -                    ^^^^^^^^^^^^^^^^^^
[task 2024-04-30T01:02:11.819Z] 01:02:11    ERROR -    File "D:\task_171443867847847\build\venv\Lib\site-packages\mozinstall\mozinstall.py", line 398, in _install_msix
[task 2024-04-30T01:02:11.819Z] 01:02:11    ERROR -      subprocess.check_call(cmd)
[task 2024-04-30T01:02:11.819Z] 01:02:11    ERROR -    File "c:\mozilla-build\python3\Lib\subprocess.py", line 413, in check_call
[task 2024-04-30T01:02:11.819Z] 01:02:11    ERROR -      raise CalledProcessError(retcode, cmd)
[task 2024-04-30T01:02:11.819Z] 01:02:11    ERROR -  subprocess.CalledProcessError: Command 'powershell.exe "Add-AppxPackage" "-Path" "D:\task_171443867847847\build\installer.msix"' returned non-zero exit status 1.
[task 2024-04-30T01:02:11.819Z] 01:02:11    ERROR -  During handling of the above exception, another exception occurred:
[task 2024-04-30T01:02:11.819Z] 01:02:11    ERROR -  Traceback (most recent call last):
[task 2024-04-30T01:02:11.819Z] 01:02:11    ERROR -    File "D:\task_171443867847847\build\venv\Scripts\mozinstall-script.py", line 33, in <module>
[task 2024-04-30T01:02:11.819Z] 01:02:11    ERROR -      sys.exit(load_entry_point('mozInstall==2.1.0', 'console_scripts', 'mozinstall')())
[task 2024-04-30T01:02:11.819Z] 01:02:11    ERROR -               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[task 2024-04-30T01:02:11.819Z] 01:02:11    ERROR -    File "D:\task_171443867847847\build\venv\Lib\site-packages\mozinstall\mozinstall.py", line 429, in install_cli
[task 2024-04-30T01:02:11.819Z] 01:02:11    ERROR -      install_path = install(src, options.dest)
[task 2024-04-30T01:02:11.820Z] 01:02:11    ERROR -                     ^^^^^^^^^^^^^^^^^^^^^^^^^^
[task 2024-04-30T01:02:11.820Z] 01:02:11    ERROR -    File "D:\task_171443867847847\build\venv\Lib\site-packages\mozinstall\mozinstall.py", line 157, in install
[task 2024-04-30T01:02:11.820Z] 01:02:11    ERROR -      reraise(InstallError, error, trbk)
[task 2024-04-30T01:02:11.820Z] 01:02:11    ERROR -    File "D:\task_171443867847847\build\venv\Lib\site-packages\six.py", line 695, in reraise
[task 2024-04-30T01:02:11.820Z] 01:02:11    ERROR -      raise value.with_traceback(tb)
[task 2024-04-30T01:02:11.820Z] 01:02:11    ERROR -    File "D:\task_171443867847847\build\venv\Lib\site-packages\mozinstall\mozinstall.py", line 136, in install
[task 2024-04-30T01:02:11.820Z] 01:02:11    ERROR -      install_dir = _install_msix(src)
[task 2024-04-30T01:02:11.820Z] 01:02:11    ERROR -                    ^^^^^^^^^^^^^^^^^^
[task 2024-04-30T01:02:11.820Z] 01:02:11    ERROR -    File "D:\task_171443867847847\build\venv\Lib\site-packages\mozinstall\mozinstall.py", line 398, in _install_msix
[task 2024-04-30T01:02:11.820Z] 01:02:11    ERROR -      subprocess.check_call(cmd)
[task 2024-04-30T01:02:11.820Z] 01:02:11    ERROR -    File "c:\mozilla-build\python3\Lib\subprocess.py", line 413, in check_call
[task 2024-04-30T01:02:11.820Z] 01:02:11    ERROR -      raise CalledProcessError(retcode, cmd)
[task 2024-04-30T01:02:11.820Z] 01:02:11    ERROR -  mozinstall.mozinstall.InstallError: Failed to install "D:\task_171443867847847\build\installer.msix (Command 'powershell.exe "Add-AppxPackage" "-Path" "D:\task_171443867847847\build\installer.msix"' returned non-zero exit status 1.)"
[task 2024-04-30T01:02:11.820Z] 01:02:11     INFO - Using _rmtree_windows ...
[task 2024-04-30T01:02:11.820Z] 01:02:11     INFO - Running command: del /F /Q "D:\task_171443867847847\tmpfile_stderr"
[task 2024-04-30T01:02:11.830Z] 01:02:11     INFO - Return code: 0
[task 2024-04-30T01:02:11.830Z] 01:02:11     INFO - Using _rmtree_windows ...
[task 2024-04-30T01:02:11.831Z] 01:02:11     INFO - Running command: del /F /Q "D:\task_171443867847847\tmpfile_stdout"
[task 2024-04-30T01:02:11.840Z] 01:02:11     INFO - Return code: 0
[task 2024-04-30T01:02:11.840Z] 01:02:11     INFO - Return code: 1
[task 2024-04-30T01:02:11.840Z] 01:02:11  WARNING - setting return code to 3
[task 2024-04-30T01:02:11.840Z] 01:02:11    FATAL - Halting on failure while running ['D:\\task_171443867847847\\build\\venv\\Scripts\\mozinstall', 'D:\\task_171443867847847\\build\\installer.msix', '--destination', 'D:\\task_171443867847847\\build\\application']
[task 2024-04-30T01:02:11.840Z] 01:02:11    FATAL - Running post_fatal callback...
[task 2024-04-30T01:02:11.840Z] 01:02:11    FATAL - Exiting 3
[task 2024-04-30T01:02:11.840Z] 01:02:11     INFO - Running post-action listener: _resource_record_post_action
[task 2024-04-30T01:02:11.840Z] 01:02:11     INFO - [mozharness: 2024-04-30 01:02:11.840161Z] Finished install step (failed)
[task 2024-04-30T01:02:11.840Z] 01:02:11     INFO - Running post-run listener: _resource_record_post_run
[task 2024-04-30T01:02:11.941Z] 01:02:11     INFO - instance_metadata.json not found; unable to determine instance type
[task 2024-04-30T01:02:11.948Z] 01:02:11     INFO - Validating Perfherder data against D:\task_171443867847847\mozharness\external_tools\performance-artifact-schema.json
[task 2024-04-30T01:02:11.950Z] 01:02:11     INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "mochitest.mochitest-browser-chrome.overall", "extraOptions": ["e10s", "buildbot-unknown"], "subtests": [{"name": "cpu_percent", "value": 41.677}, {"name": "io_write_bytes", "value": 831488}, {"name": "io.read_bytes", "value": 2459648}, {"name": "io_write_time", "value": 0}, {"name": "io_read_time", "value": 0}]}, {"name": "mochitest.mochitest-browser-chrome.start-pulseaudio", "subtests": [{"name": "time", "value": 0.0}, {"name": "cpu_percent", "value": 0}]}, {"name": "mochitest.mochitest-browser-chrome.install", "subtests": [{"name": "time", "value": 3.0619999999999834}, {"name": "cpu_percent", "value": 41.677}]}]}
[task 2024-04-30T01:02:11.950Z] 01:02:11     INFO - Total resource usage - Wall time: 3s; CPU: 42%; Read bytes: 2459648; Write bytes: 831488; Read time: 0; Write time: 0
[task 2024-04-30T01:02:11.950Z] 01:02:11     INFO - TinderboxPrint: CPU usage<br/>41.7%
[task 2024-04-30T01:02:11.950Z] 01:02:11     INFO - TinderboxPrint: I/O read bytes / time<br/>2,459,648 / 0
[task 2024-04-30T01:02:11.950Z] 01:02:11     INFO - TinderboxPrint: I/O write bytes / time<br/>831,488 / 0
[task 2024-04-30T01:02:11.950Z] 01:02:11     INFO - TinderboxPrint: CPU idle<br/>11.9 (58.7%)
[task 2024-04-30T01:02:11.950Z] 01:02:11     INFO - TinderboxPrint: CPU system<br/>3.5 (17.1%)
[task 2024-04-30T01:02:11.950Z] 01:02:11     INFO - TinderboxPrint: CPU user<br/>4.9 (24.1%)
[task 2024-04-30T01:02:11.950Z] 01:02:11     INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-04-30T01:02:11.951Z] 01:02:11     INFO - install - Wall time: 3s; CPU: 42%; Read bytes: 2459648; Write bytes: 831488; Read time: 0; Write time: 0
[taskcluster 2024-04-30T01:02:12.018Z]    Exit Code: 3
[taskcluster 2024-04-30T01:02:12.018Z]    User Time: 15.625ms
[taskcluster 2024-04-30T01:02:12.018Z]  Kernel Time: 31.25ms
[taskcluster 2024-04-30T01:02:12.018Z]    Wall Time: 2m27.8111574s
[taskcluster 2024-04-30T01:02:12.018Z]       Result: FAILED
[taskcluster 2024-04-30T01:02:12.018Z] === Task Finished ===
[taskcluster 2024-04-30T01:02:12.018Z] Task Duration: 2m27.8143843s
[taskcluster 2024-04-30T01:02:12.455Z] Uploading artifact public/logs/localconfig.json from file D:\task_171443867847847\logs\localconfig.json with content encoding "gzip", mime type "application/json" and expiry 2025-04-30T00:27:10.896Z
[taskcluster 2024-04-30T01:02:12.840Z] Uploading artifact public/test_info/profile_resource-usage.json from file D:\task_171443867847847\build\blobber_upload_dir\profile_resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2025-04-30T00:27:10.896Z
[taskcluster 2024-04-30T01:02:13.131Z] Uploading artifact public/test_info/resource-usage.json from file D:\task_171443867847847\build\blobber_upload_dir\resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2025-04-30T00:27:10.896Z
[taskcluster 2024-04-30T01:02:13.424Z] Uploading link artifact public/logs/live.log to artifact public/logs/live_backing.log with expiry 2025-04-30T00:27:10.896Z
[taskcluster:error] exit status 3

Comment 1

[Nipun Shukla]

It looks like the app manifest publisher location was changed from L=San Francisco to L=Mountain View without updating the signing certificate. Marking as P1 S2 as trying to install an up-to-date MSIX build from https://archive.mozilla.org/pub/firefox/nightly/2024/04/2024-04-29-21-05-02-mozilla-central/ gives an error and doesn't allow installation. Release Fx125.0.3 downloaded from the Microsoft Store looks unaffected on my machine.

:RyanVM I'm not really sure who is best to reach out to about this, would you know?

Comment 2

[Intermittent Failures Robot]

36 failures in 741 pushes (0.049 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 36

Platform and build breakdown:

• windows11-64-2009-shippable-qr: 36
  • opt: 36

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1894145&startday=2024-04-29&endday=2024-04-29&tree=all

Comment 3

[Julien Cristau [:jcristau]]

Attachment: Bug 1894145 - update msix publisher attributes to match new signing certificate. r?#releng

Comment 4

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 5

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/6c8cd1a8cb0b
update msix publisher attributes to match new signing certificate. r=jlorenzo

Comment 6

[Julien Cristau [:jcristau]]

:rjl heads-up that comm-central probably needs this as well

Comment 7

[Aron Cseh]

https://hg.mozilla.org/mozilla-central/rev/6c8cd1a8cb0b

Comment 8

[Cosmin Sabou [:CosminS]]

Backed out out of central as requested by bhearsum for causing Bug 1894138. https://hg.mozilla.org/mozilla-central/rev/b7a1a8a3af7f8cb576e533dc6537f14dab105c55

Comment 9

[Intermittent Failures Robot]

31 failures in 772 pushes (0.04 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 3
• comm-central: 16
• mozilla-central: 12

Platform and build breakdown:

• windows11-64-2009-shippable-qr: 31
  • opt: 31

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1894145&startday=2024-04-30&endday=2024-04-30&tree=all

Comment 10

[Julien Cristau [:jcristau]]

Will re-land as part of bug 1889299.

Comment 11

[Intermittent Failures Robot]

67 failures in 4059 pushes (0.017 failures/push) were associated with this bug in the last 7 days.

This is the #14 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• mozilla-central: 48
• autoland: 3
• comm-central: 16

Platform and build breakdown:

• windows11-64-2009-shippable-qr: 67
  • opt: 67

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1894145&startday=2024-04-29&endday=2024-05-05&tree=all