[taskcluster 2026-05-14T21:11:37.409Z] Worker Type (scriptworker-1/images) settings:
[taskcluster 2026-05-14T21:11:37.409Z]   {
[taskcluster 2026-05-14T21:11:37.409Z]     "generic-worker": {
[taskcluster 2026-05-14T21:11:37.409Z]       "config": {
[taskcluster 2026-05-14T21:11:37.409Z]         "headlessTasks": true
[taskcluster 2026-05-14T21:11:37.409Z]       },
[taskcluster 2026-05-14T21:11:37.409Z]       "engine": "multiuser",
[taskcluster 2026-05-14T21:11:37.409Z]       "go-arch": "amd64",
[taskcluster 2026-05-14T21:11:37.409Z]       "go-os": "linux",
[taskcluster 2026-05-14T21:11:37.409Z]       "go-version": "go1.26.2",
[taskcluster 2026-05-14T21:11:37.409Z]       "release": "https://github.com/taskcluster/taskcluster/releases/tag/v99.2.1",
[taskcluster 2026-05-14T21:11:37.409Z]       "revision": "ddb9ce7efdb98ae2d0917b69778e9f3cd125e07f",
[taskcluster 2026-05-14T21:11:37.409Z]       "source": "https://github.com/taskcluster/taskcluster/commits/ddb9ce7efdb98ae2d0917b69778e9f3cd125e07f",
[taskcluster 2026-05-14T21:11:37.409Z]       "version": "99.2.1"
[taskcluster 2026-05-14T21:11:37.409Z]     },
[taskcluster 2026-05-14T21:11:37.409Z]     "image": "projects/taskcluster-imaging/global/images/gw-fxci-gcp-l1-2404-amd64-headless-googlecompute-2026-05-04",
[taskcluster 2026-05-14T21:11:37.409Z]     "instance-id": "1741264725052458754",
[taskcluster 2026-05-14T21:11:37.409Z]     "instance-type": "projects/887720501152/machineTypes/c2-standard-4",
[taskcluster 2026-05-14T21:11:37.409Z]     "local-ipv4": "10.128.2.77",
[taskcluster 2026-05-14T21:11:37.409Z]     "project-id": "fxci-production-level1-workers",
[taskcluster 2026-05-14T21:11:37.409Z]     "public-hostname": "scriptworker-1-images-ki-jbfpyqpo7dprnzoqtkw.c.fxci-production-level1-workers.internal",
[taskcluster 2026-05-14T21:11:37.409Z]     "public-ipv4": "136.115.112.1",
[taskcluster 2026-05-14T21:11:37.409Z]     "region": "us-central1",
[taskcluster 2026-05-14T21:11:37.409Z]     "zone": "us-central1-b"
[taskcluster 2026-05-14T21:11:37.409Z]   }
[taskcluster 2026-05-14T21:11:37.409Z] Task ID: NImGIEEcS-CMjQD26-t7tQ
[taskcluster 2026-05-14T21:11:37.409Z] === Task Starting ===
[taskcluster 2026-05-14T21:11:38.049Z] [mounts] Moving existing writable directory cache scriptworker-level-1-checkouts-v3-5ddaa3ce44d7548e0d3c-LKxaDnZCTkG_LO1_mn7JPw from /home/generic-worker/caches/Kg04UmqQTyW9AIXP9soaTg to /home/task_177879309689823/cache0
[taskcluster 2026-05-14T21:11:38.049Z] [mounts] Creating directory /home/task_177879309689823
[taskcluster 2026-05-14T21:11:38.060Z] [mounts] Successfully mounted writable directory cache '/home/task_177879309689823/cache0'
[taskcluster:warn 2026-05-14T21:11:38.331Z] [mounts] No SHA256 specified in task mounts for artifact:LKxaDnZCTkG_LO1_mn7JPw:public/image.tar.zst - SHA256 from downloaded file /home/generic-worker/downloads/Nz7VixkMS1SSw5yj9YgHAw is 58fff973ad089d6ef1f5227d01cfacb31270f5550d78662d70d69b67a775e1d9.
[taskcluster 2026-05-14T21:11:38.331Z] [mounts] File mount "dockerimage" handled by registered handler (cache: /home/generic-worker/downloads/Nz7VixkMS1SSw5yj9YgHAw, SHA256: 58fff973ad089d6ef1f5227d01cfacb31270f5550d78662d70d69b67a775e1d9)
[taskcluster 2026-05-14T21:11:38.332Z] [d2g] Using cached docker image "python314:latest"
[taskcluster 2026-05-14T21:11:38.332Z] Executing command 0: docker run -t --name taskcontainer_NR97HbRKQXqe0rbeNcQtgg --memory-swap -1 --pids-limit -1 --pull=never --log-driver=none '--add-host=localhost.localdomain:127.0.0.1' -v /home/task_177879309689823/cache0:/builds/worker/checkouts --add-host=taskcluster:host-gateway --env-file 'env.list' 2c371e2415d7d2bff585517eaa74558ffaac171e4ea1adc4118b4a063bb9ccaa /usr/local/bin/run-task --scriptworker-checkout=/builds/worker/checkouts/vcs/ --task-cwd /builds/worker/checkouts/vcs -- sh -lxce 'uv run tox -e pushmsixscript-py314'
[setup 2026-05-14T21:11:38.833+00:00] run-task started in /
[setup 2026-05-14T21:11:38.833+00:00] Invoked by command: --scriptworker-checkout=/builds/worker/checkouts/vcs/ --task-cwd /builds/worker/checkouts/vcs -- sh -lxce uv run tox -e pushmsixscript-py314
[setup 2026-05-14T21:11:38.833+00:00] Python version: 3.14.3
[setup 2026-05-14T21:11:38.833+00:00] Subprocess python version: 
cpython-3.14.3-linux-x86_64-gnu    /usr/local/bin/python3.14
cpython-3.14.3-linux-x86_64-gnu    /usr/local/bin/python3 -> python3.14
cpython-3.14.3-linux-x86_64-gnu    /usr/local/bin/python -> python3
[cache 2026-05-14T21:11:39.132+00:00] cache /builds/worker/checkouts exists; requirements: gid=1000 uid=1000 version=1
[volume 2026-05-14T21:11:39.132+00:00] changing ownership of volume /builds/worker/.cache to 1000:1000
[volume 2026-05-14T21:11:39.132+00:00] volume /builds/worker/checkouts is a cache
[setup 2026-05-14T21:11:39.132+00:00] running as worker:worker
[vcs 2026-05-14T21:11:39.133+00:00] executing ['git', 'config', '--global', '--add', 'safe.directory', '/builds/worker/checkouts/vcs']
[vcs 2026-05-14T21:11:39.135+00:00] executing ['git', 'fetch', '--tags', '--force', 'https://github.com/mozilla-releng/scriptworker-scripts', 'renovate/mozillareleases-taskgraph-23.x']
[vcs 2026-05-14T21:11:39.323+00:00] From https://github.com/mozilla-releng/scriptworker-scripts
[vcs 2026-05-14T21:11:39.323+00:00]  * branch              renovate/mozillareleases-taskgraph-23.x -> FETCH_HEAD
[vcs 2026-05-14T21:11:39.329+00:00] executing ['git', 'checkout', '-f', '-B', 'renovate/mozillareleases-taskgraph-23.x', 'ba2507aca76b8b31b4aafc44497fff34962d7831']
[vcs 2026-05-14T21:11:39.336+00:00] Reset branch 'renovate/mozillareleases-taskgraph-23.x'
[vcs 2026-05-14T21:11:39.337+00:00] cleaning git checkout...
[vcs 2026-05-14T21:11:39.337+00:00] executing ['git', 'clean', '-nxdff']
[vcs 2026-05-14T21:11:39.400+00:00] removing ['/builds/worker/checkouts/vcs/.tox/', '/builds/worker/checkouts/vcs/.venv/', '/builds/worker/checkouts/vcs/configloader/.coverage', '/builds/worker/checkouts/vcs/vendored/mozbuild/mozbuild.egg-info/']
[vcs 2026-05-14T21:11:39.698+00:00] successfully cleaned git checkout!
[vcs 2026-05-14T21:11:39.700+00:00] TinderboxPrint:<a href='https://github.com/mozilla-releng/scriptworker-scripts/commit/ba2507aca76b8b31b4aafc44497fff34962d7831' title='Built from scriptworker-scripts commit ba2507aca76b8b31b4aafc44497fff34962d7831'>ba2507aca76b8b31b4aafc44497fff34962d7831</a>
[task 2026-05-14T21:11:39.700+00:00] executing ['sh', '-lxce', 'uv run tox -e pushmsixscript-py314']
[task 2026-05-14T21:11:39.702+00:00] + id -u
[task 2026-05-14T21:11:39.703+00:00] + [ 1000 -eq 0 ]
[task 2026-05-14T21:11:39.703+00:00] + PATH=/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
[task 2026-05-14T21:11:39.703+00:00] + export PATH
[task 2026-05-14T21:11:39.703+00:00] + [ $  ]
[task 2026-05-14T21:11:39.703+00:00] + [  ]
[task 2026-05-14T21:11:39.703+00:00] + id -u
[task 2026-05-14T21:11:39.704+00:00] + [ 1000 -eq 0 ]
[task 2026-05-14T21:11:39.704+00:00] + PS1=$ 
[task 2026-05-14T21:11:39.704+00:00] + [ -d /etc/profile.d ]
[task 2026-05-14T21:11:39.704+00:00] + run-parts --list --regex ^[a-zA-Z0-9_][a-zA-Z0-9._-]*\.sh$ /etc/profile.d
[task 2026-05-14T21:11:39.705+00:00] + unset i
[task 2026-05-14T21:11:39.705+00:00] + uv run tox -e pushmsixscript-py314
[task 2026-05-14T21:11:39.773+00:00] Using CPython 3.14.3 interpreter at: /usr/local/bin/python3
[task 2026-05-14T21:11:39.773+00:00] Creating virtual environment at: .venv
[task 2026-05-14T21:11:40.890+00:00]    Building configloader @ file:///builds/worker/checkouts/vcs/configloader
[task 2026-05-14T21:11:40.958+00:00] Downloading virtualenv (7.2MiB)
[task 2026-05-14T21:11:40.959+00:00] Downloading pygments (1.2MiB)
[task 2026-05-14T21:11:40.960+00:00] Downloading ruff (10.8MiB)
[task 2026-05-14T21:11:40.960+00:00] Downloading uv (23.7MiB)
[task 2026-05-14T21:11:40.960+00:00] Downloading mypy (14.0MiB)
[task 2026-05-14T21:11:41.479+00:00]  Downloading virtualenv
[task 2026-05-14T21:11:41.586+00:00]  Downloading ruff
[task 2026-05-14T21:11:41.799+00:00]  Downloading uv
[task 2026-05-14T21:11:41.813+00:00]  Downloading pygments
[task 2026-05-14T21:11:41.985+00:00]  Downloading mypy
[task 2026-05-14T21:11:42.120+00:00]       Built configloader @ file:///builds/worker/checkouts/vcs/configloader
[task 2026-05-14T21:11:42.214+00:00] Installed 32 packages in 92ms
[task 2026-05-14T21:11:43.031+00:00] pushmsixscript-py314: venv> .venv/bin/uv venv -p cpython3.14 --allow-existing '--prompt=vcs[pushmsixscript-py314]' --python-preference system /builds/worker/checkouts/vcs/.tox/pushmsixscript-py314
[task 2026-05-14T21:11:43.318+00:00] pushmsixscript-py314: uv-sync> .venv/bin/uv sync --locked --python-preference system -p cpython3.14
[task 2026-05-14T21:11:44.943+00:00] pushmsixscript-py314: commands[0] /builds/worker/checkouts/vcs/pushmsixscript> tox -e py314
[task 2026-05-14T21:11:45.720+00:00] py314: venv> /builds/worker/checkouts/vcs/.tox/pushmsixscript-py314/bin/uv venv -p cpython3.14 --allow-existing '--prompt=pushmsixscript[py314]' --python-preference system /builds/worker/checkouts/vcs/.tox/py314
[task 2026-05-14T21:11:45.885+00:00] py314: uv-sync> /builds/worker/checkouts/vcs/.tox/pushmsixscript-py314/bin/uv sync --locked --python-preference system -p cpython3.14
[task 2026-05-14T21:11:47.124+00:00] py314: commands[0]> py.test --cov-config=tox.ini --cov-append --cov=/builds/worker/checkouts/vcs/pushmsixscript/src/pushmsixscript --cov-report term-missing tests
[task 2026-05-14T21:11:50.749+00:00] ============================= test session starts ==============================
[task 2026-05-14T21:11:50.749+00:00] platform linux -- Python 3.14.3, pytest-8.4.2, pluggy-1.6.0
[task 2026-05-14T21:11:50.749+00:00] cachedir: /builds/worker/checkouts/vcs/.tox/py314/.pytest_cache
[task 2026-05-14T21:11:50.749+00:00] rootdir: /builds/worker/checkouts/vcs/pushmsixscript
[task 2026-05-14T21:11:50.749+00:00] configfile: tox.ini
[task 2026-05-14T21:11:50.749+00:00] plugins: anyio-4.13.0, requests-mock-1.12.1, asyncio-0.26.0, cov-7.1.0
[task 2026-05-14T21:11:50.749+00:00] asyncio: mode=Mode.STRICT, asyncio_default_fixture_loop_scope=function, asyncio_default_test_loop_scope=function
[task 2026-05-14T21:11:50.749+00:00] collected 88 items
[task 2026-05-14T21:11:50.749+00:00] 
[task 2026-05-14T21:11:50.803+00:00] tests/integration/test_integration_script.py ..                          [  2%]
[task 2026-05-14T21:11:50.809+00:00] tests/test_artifacts.py ......                                           [  9%]
[task 2026-05-14T21:11:50.831+00:00] tests/test_config.py ...                                                 [ 12%]
[task 2026-05-14T21:11:50.842+00:00] tests/test_manifest.py ...                                               [ 15%]
[task 2026-05-14T21:11:50.902+00:00] tests/test_microsoft_store.py .......................................... [ 63%]
[task 2026-05-14T21:11:50.930+00:00] ................                                                         [ 81%]
[task 2026-05-14T21:11:50.933+00:00] tests/test_script.py ...                                                 [ 85%]
[task 2026-05-14T21:11:51.404+00:00] tests/test_task.py .............                                         [100%]
[task 2026-05-14T21:11:51.404+00:00] 
[task 2026-05-14T21:11:51.404+00:00] =============================== warnings summary ===============================
[task 2026-05-14T21:11:51.404+00:00] tests/integration/test_integration_script.py::test_script_can_push_msix[config0-release-Immediate-False-0]
[task 2026-05-14T21:11:51.404+00:00]   /builds/worker/checkouts/vcs/.tox/py314/lib/python3.14/site-packages/pytest_asyncio/plugin.py:1216: DeprecationWarning: 'asyncio.get_event_loop_policy' is deprecated and slated for removal in Python 3.16
[task 2026-05-14T21:11:51.404+00:00]     return asyncio.get_event_loop_policy()
[task 2026-05-14T21:11:51.404+00:00] 
[task 2026-05-14T21:11:51.404+00:00] tests/test_script.py::test_async_main
[task 2026-05-14T21:11:51.404+00:00]   /builds/worker/checkouts/vcs/.tox/py314/lib/python3.14/site-packages/pytest_asyncio/plugin.py:874: DeprecationWarning: 'asyncio.get_event_loop_policy' is deprecated and slated for removal in Python 3.16
[task 2026-05-14T21:11:51.404+00:00]     _restore_event_loop_policy(asyncio.get_event_loop_policy()),
[task 2026-05-14T21:11:51.404+00:00] 
[task 2026-05-14T21:11:51.404+00:00] tests/test_script.py::test_async_main
[task 2026-05-14T21:11:51.404+00:00]   /builds/worker/checkouts/vcs/.tox/py314/lib/python3.14/site-packages/pytest_asyncio/plugin.py:772: DeprecationWarning: 'asyncio.get_event_loop_policy' is deprecated and slated for removal in Python 3.16
[task 2026-05-14T21:11:51.404+00:00]     old_loop_policy = asyncio.get_event_loop_policy()
[task 2026-05-14T21:11:51.404+00:00] 
[task 2026-05-14T21:11:51.404+00:00] tests/test_script.py::test_async_main
[task 2026-05-14T21:11:51.404+00:00]   /builds/worker/checkouts/vcs/.tox/py314/lib/python3.14/site-packages/pytest_asyncio/plugin.py:777: DeprecationWarning: 'asyncio.set_event_loop_policy' is deprecated and slated for removal in Python 3.16
[task 2026-05-14T21:11:51.404+00:00]     asyncio.set_event_loop_policy(policy)
[task 2026-05-14T21:11:51.404+00:00] 
[task 2026-05-14T21:11:51.404+00:00] tests/test_script.py::test_async_main
[task 2026-05-14T21:11:51.404+00:00]   /builds/worker/checkouts/vcs/.tox/py314/lib/python3.14/site-packages/pytest_asyncio/plugin.py:1183: DeprecationWarning: 'asyncio.get_event_loop_policy' is deprecated and slated for removal in Python 3.16
[task 2026-05-14T21:11:51.404+00:00]     loop = asyncio.get_event_loop_policy().new_event_loop()
[task 2026-05-14T21:11:51.404+00:00] 
[task 2026-05-14T21:11:51.404+00:00] tests/test_script.py::test_async_main
[task 2026-05-14T21:11:51.404+00:00]   /builds/worker/checkouts/vcs/.tox/py314/lib/python3.14/site-packages/pytest_asyncio/plugin.py:889: DeprecationWarning: 'asyncio.get_event_loop_policy' is deprecated and slated for removal in Python 3.16
[task 2026-05-14T21:11:51.404+00:00]     policy = asyncio.get_event_loop_policy()
[task 2026-05-14T21:11:51.404+00:00] 
[task 2026-05-14T21:11:51.404+00:00] tests/test_script.py::test_async_main
[task 2026-05-14T21:11:51.404+00:00]   /builds/worker/checkouts/vcs/.tox/py314/lib/python3.14/site-packages/pytest_asyncio/plugin.py:794: DeprecationWarning: 'asyncio.set_event_loop_policy' is deprecated and slated for removal in Python 3.16
[task 2026-05-14T21:11:51.404+00:00]     asyncio.set_event_loop_policy(old_loop_policy)
[task 2026-05-14T21:11:51.404+00:00] 
[task 2026-05-14T21:11:51.404+00:00] tests/test_script.py::test_async_main
[task 2026-05-14T21:11:51.404+00:00]   /builds/worker/checkouts/vcs/.tox/py314/lib/python3.14/site-packages/pytest_asyncio/plugin.py:942: DeprecationWarning: 'asyncio.get_event_loop_policy' is deprecated and slated for removal in Python 3.16
[task 2026-05-14T21:11:51.404+00:00]     policy = asyncio.get_event_loop_policy()
[task 2026-05-14T21:11:51.404+00:00] 
[task 2026-05-14T21:11:51.404+00:00] tests/test_script.py::test_async_main
[task 2026-05-14T21:11:51.404+00:00]   /builds/worker/checkouts/vcs/.tox/py314/lib/python3.14/site-packages/pytest_asyncio/plugin.py:966: DeprecationWarning: 'asyncio.set_event_loop_policy' is deprecated and slated for removal in Python 3.16
[task 2026-05-14T21:11:51.404+00:00]     asyncio.set_event_loop_policy(previous_policy)
[task 2026-05-14T21:11:51.404+00:00] 
[task 2026-05-14T21:11:51.404+00:00] tests/test_script.py::test_async_main
[task 2026-05-14T21:11:51.404+00:00]   /builds/worker/checkouts/vcs/.tox/py314/lib/python3.14/site-packages/pytest_asyncio/plugin.py:978: DeprecationWarning: 'asyncio.get_event_loop_policy' is deprecated and slated for removal in Python 3.16
[task 2026-05-14T21:11:51.404+00:00]     policy = asyncio.get_event_loop_policy()
[task 2026-05-14T21:11:51.404+00:00] 
[task 2026-05-14T21:11:51.404+00:00] -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html
[task 2026-05-14T21:11:51.404+00:00] ================================ tests coverage ================================
[task 2026-05-14T21:11:51.404+00:00] _______________ coverage: platform linux, python 3.14.3-final-0 ________________
[task 2026-05-14T21:11:51.404+00:00] 
[task 2026-05-14T21:11:51.404+00:00] Name                                    Stmts   Miss Branch BrPart  Cover   Missing
[task 2026-05-14T21:11:51.404+00:00] -----------------------------------------------------------------------------------
[task 2026-05-14T21:11:51.404+00:00] src/pushmsixscript/__init__.py              0      0      0      0   100%
[task 2026-05-14T21:11:51.404+00:00] src/pushmsixscript/artifacts.py             9      0      2      0   100%
[task 2026-05-14T21:11:51.404+00:00] src/pushmsixscript/manifest.py             38      2      6      2    91%   32, 41
[task 2026-05-14T21:11:51.404+00:00] src/pushmsixscript/microsoft_store.py     175      4     34      2    97%   82, 92->exit, 96-98
[task 2026-05-14T21:11:51.404+00:00] src/pushmsixscript/script.py               24      0      4      0   100%
[task 2026-05-14T21:11:51.404+00:00] src/pushmsixscript/task.py                 16      0      6      0   100%
[task 2026-05-14T21:11:51.404+00:00] -----------------------------------------------------------------------------------
[task 2026-05-14T21:11:51.404+00:00] TOTAL                                     262      6     52      4    97%
[task 2026-05-14T21:11:51.404+00:00] ======================= 88 passed, 10 warnings in 2.43s ========================
[task 2026-05-14T21:11:51.827+00:00]   py314: OK (6.11=setup[1.41]+cmd[4.70] seconds)
[task 2026-05-14T21:11:51.827+00:00]   congratulations :) (6.17 seconds)
[task 2026-05-14T21:11:51.880+00:00]   pushmsixscript-py314: OK (8.85=setup[1.92]+cmd[6.94] seconds)
[task 2026-05-14T21:11:51.880+00:00]   congratulations :) (8.95 seconds)
[taskcluster 2026-05-14T21:11:53.393Z]                        Exit Code: 0
[taskcluster 2026-05-14T21:11:53.393Z]                        User Time: 9.275ms
[taskcluster 2026-05-14T21:11:53.393Z]                      Kernel Time: 17.519ms
[taskcluster 2026-05-14T21:11:53.393Z]                        Wall Time: 15.061667912s
[taskcluster 2026-05-14T21:11:53.393Z]  Average Available System Memory: 14.68 GiB
[taskcluster 2026-05-14T21:11:53.393Z]       Average System Memory Used: 951.86 MiB
[taskcluster 2026-05-14T21:11:53.393Z]          Peak System Memory Used: 1.01 GiB
[taskcluster 2026-05-14T21:11:53.393Z]              Total System Memory: 15.61 GiB
[taskcluster 2026-05-14T21:11:53.393Z]                           Result: SUCCEEDED
[taskcluster 2026-05-14T21:11:53.394Z] === Task Finished ===
[taskcluster 2026-05-14T21:11:53.394Z] Task Duration: 15.062114157s
[taskcluster 2026-05-14T21:11:53.416Z] [mounts] Preserving cache: Moving "/home/task_177879309689823/cache0" to "/home/generic-worker/caches/Kg04UmqQTyW9AIXP9soaTg"