[taskcluster 2025-10-08 21:18:15.448Z] Task ID: YAFvdOcWSPC1TWd79ss3Qw
[taskcluster 2025-10-08 21:18:15.448Z] Worker ID: 1807820916983032408
[taskcluster 2025-10-08 21:18:15.448Z] Worker Group: us-central1-b
[taskcluster 2025-10-08 21:18:15.449Z] Worker Node Type: projects/887720501152/machineTypes/c2-standard-4
[taskcluster 2025-10-08 21:18:15.449Z] Worker Pool: scriptworker-1/b-linux-gcp
[taskcluster 2025-10-08 21:18:15.449Z] Worker Version: 38.0.5
[taskcluster 2025-10-08 21:18:15.449Z] Public IP: 34.57.1.73
[taskcluster 2025-10-08 21:18:15.449Z] Hostname: scriptworker-1-b-linux-gcp-gx2ggl5rtfew5y0jeoof6q
[taskcluster 2025-10-08 21:18:15.449Z] using cache "scriptworker-level-1-checkouts-v3-f59e202b9f4c189775df-bn4Fyl9FQnGhHSh892yfyw" -> /builds/worker/checkouts

[taskcluster 2025-10-08 21:18:18.949Z] Downloading artifact "public/image.tar.zst" from task ID: bn4Fyl9FQnGhHSh892yfyw.
[taskcluster 2025-10-08 21:18:22.451Z] Downloaded artifact successfully.
[taskcluster 2025-10-08 21:18:22.452Z] Downloaded 284.868 mb
[taskcluster 2025-10-08 21:18:22.453Z] Decompressing downloaded image
[taskcluster 2025-10-08 21:18:25.127Z] Loading docker image from downloaded archive.
[taskcluster 2025-10-08 21:18:42.259Z] Image 'public/image.tar.zst' from task 'bn4Fyl9FQnGhHSh892yfyw' loaded.  Using image ID sha256:e82b2153fc4a2a6e8fb82052e28e58db90f2d392910ee5d2304acb1ae8b627f2.
[taskcluster 2025-10-08 21:18:42.528Z] === Task Starting ===
[setup 2025-10-08T21:18:45.059+00:00] run-task started in /
[setup 2025-10-08T21:18:45.059+00:00] Invoked by command: --scriptworker-checkout=/builds/worker/checkouts/vcs/ --task-cwd /builds/worker/checkouts/vcs -- sh -lxce uv run tox -e pushmsixscript-py311
[setup 2025-10-08T21:18:45.059+00:00] Python version: 3.11.9
[cache 2025-10-08T21:18:45.061+00:00] cache /builds/worker/checkouts is empty; writing requirements: gid=1000 uid=1000 version=1
[volume 2025-10-08T21:18:45.061+00:00] changing ownership of volume /builds/worker/.cache to 1000:1000
[volume 2025-10-08T21:18:45.061+00:00] volume /builds/worker/checkouts is a cache
[setup 2025-10-08T21:18:45.061+00:00] running as worker:worker
[vcs 2025-10-08T21:18:45.061+00:00] executing ['git', 'config', '--global', '--add', 'safe.directory', '/builds/worker/checkouts/vcs']
[vcs 2025-10-08T21:18:45.064+00:00] executing ['git', 'clone', 'https://github.com/mozilla-releng/scriptworker-scripts', '/builds/worker/checkouts/vcs']
[vcs 2025-10-08T21:18:45.066+00:00] Cloning into '/builds/worker/checkouts/vcs'...
[vcs 2025-10-08T21:18:51.692+00:00] executing ['git', 'fetch', '--tags', '--force', 'https://github.com/mozilla-releng/scriptworker-scripts', 'hneiva/pushmsix-polling']
[vcs 2025-10-08T21:18:51.909+00:00] From https://github.com/mozilla-releng/scriptworker-scripts
[vcs 2025-10-08T21:18:51.909+00:00]  * branch              hneiva/pushmsix-polling -> FETCH_HEAD
[vcs 2025-10-08T21:18:51.915+00:00] executing ['git', 'fetch', '--no-tags', 'https://github.com/mozilla-releng/scriptworker-scripts', 'hneiva/pushmsix-polling']
[vcs 2025-10-08T21:18:52.140+00:00] From https://github.com/mozilla-releng/scriptworker-scripts
[vcs 2025-10-08T21:18:52.140+00:00]  * branch              hneiva/pushmsix-polling -> FETCH_HEAD
[vcs 2025-10-08T21:18:52.146+00:00] executing ['git', 'checkout', '-f', '-B', 'hneiva/pushmsix-polling', '1a2cbf4982715786ef1d8e5fafd6ede4f62b7914']
[vcs 2025-10-08T21:18:53.175+00:00] Switched to a new branch 'hneiva/pushmsix-polling'
[vcs 2025-10-08T21:18:53.175+00:00] cleaning git checkout...
[vcs 2025-10-08T21:18:53.175+00:00] executing ['git', 'clean', '-nxdff']
[vcs 2025-10-08T21:18:53.183+00:00] removing []
[vcs 2025-10-08T21:18:53.183+00:00] successfully cleaned git checkout!
[vcs 2025-10-08T21:18:53.185+00:00] TinderboxPrint:<a href='https://github.com/mozilla-releng/scriptworker-scripts/commit/1a2cbf4982715786ef1d8e5fafd6ede4f62b7914' title='Built from scriptworker-scripts commit 1a2cbf4982715786ef1d8e5fafd6ede4f62b7914'>1a2cbf4982715786ef1d8e5fafd6ede4f62b7914</a>
[task 2025-10-08T21:18:53.185+00:00] executing ['sh', '-lxce', 'uv run tox -e pushmsixscript-py311']
[task 2025-10-08T21:18:53.186+00:00] + id -u
[task 2025-10-08T21:18:53.188+00:00] + [ 1000 -eq 0 ]
[task 2025-10-08T21:18:53.188+00:00] + PATH=/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
[task 2025-10-08T21:18:53.188+00:00] + export PATH
[task 2025-10-08T21:18:53.188+00:00] + [ $  ]
[task 2025-10-08T21:18:53.188+00:00] + [  ]
[task 2025-10-08T21:18:53.188+00:00] + id -u
[task 2025-10-08T21:18:53.189+00:00] + [ 1000 -eq 0 ]
[task 2025-10-08T21:18:53.189+00:00] + PS1=$ 
[task 2025-10-08T21:18:53.189+00:00] + [ -d /etc/profile.d ]
[task 2025-10-08T21:18:53.189+00:00] + [ -r /etc/profile.d/*.sh ]
[task 2025-10-08T21:18:53.189+00:00] + unset i
[task 2025-10-08T21:18:53.189+00:00] + uv run tox -e pushmsixscript-py311
[task 2025-10-08T21:18:53.257+00:00] Using CPython 3.11.9 interpreter at: /usr/local/bin/python3
[task 2025-10-08T21:18:53.258+00:00] Creating virtual environment at: .venv
[task 2025-10-08T21:18:54.591+00:00]    Building configloader @ file:///builds/worker/checkouts/vcs/configloader
[task 2025-10-08T21:18:54.673+00:00] Downloading uv (20.2MiB)
[task 2025-10-08T21:18:54.673+00:00] Downloading mypy (12.6MiB)
[task 2025-10-08T21:18:54.673+00:00] Downloading ruff (12.8MiB)
[task 2025-10-08T21:18:54.693+00:00] Downloading pygments (1.2MiB)
[task 2025-10-08T21:18:54.693+00:00] Downloading virtualenv (5.7MiB)
[task 2025-10-08T21:18:55.323+00:00]  Downloading virtualenv
[task 2025-10-08T21:18:55.421+00:00]  Downloading ruff
[task 2025-10-08T21:18:55.519+00:00]  Downloading uv
[task 2025-10-08T21:18:55.533+00:00]  Downloading pygments
[task 2025-10-08T21:18:55.711+00:00]  Downloading mypy
[task 2025-10-08T21:18:55.812+00:00]       Built configloader @ file:///builds/worker/checkouts/vcs/configloader
[task 2025-10-08T21:18:55.914+00:00] Installed 29 packages in 100ms
[task 2025-10-08T21:18:56.772+00:00] pushmsixscript-py311: venv> .venv/bin/uv venv -p /builds/worker/checkouts/vcs/.venv/bin/python --allow-existing --python-preference system /builds/worker/checkouts/vcs/.tox/pushmsixscript-py311
[task 2025-10-08T21:18:56.847+00:00] pushmsixscript-py311: uv-sync> uv sync --locked --python-preference system -p /builds/worker/checkouts/vcs/.venv/bin/python
[task 2025-10-08T21:18:57.681+00:00] pushmsixscript-py311: commands[0] /builds/worker/checkouts/vcs/pushmsixscript> tox -e py311
[task 2025-10-08T21:18:58.545+00:00] py311: venv> /builds/worker/checkouts/vcs/.tox/pushmsixscript-py311/bin/uv venv -p /builds/worker/checkouts/vcs/.tox/pushmsixscript-py311/bin/python3 --allow-existing --python-preference system /builds/worker/checkouts/vcs/.tox/py311
[task 2025-10-08T21:18:58.562+00:00] py311: uv-sync> uv sync --locked --python-preference system -p /builds/worker/checkouts/vcs/.tox/pushmsixscript-py311/bin/python3
[task 2025-10-08T21:18:59.600+00:00] py311: commands[0]> py.test --cov-config=tox.ini --cov-append --cov=/builds/worker/checkouts/vcs/pushmsixscript/src/pushmsixscript --cov-report term-missing tests
[task 2025-10-08T21:19:05.502+00:00] ============================= test session starts ==============================
[task 2025-10-08T21:19:05.502+00:00] platform linux -- Python 3.11.9, pytest-8.4.2, pluggy-1.6.0
[task 2025-10-08T21:19:05.502+00:00] cachedir: /builds/worker/checkouts/vcs/.tox/py311/.pytest_cache
[task 2025-10-08T21:19:05.502+00:00] rootdir: /builds/worker/checkouts/vcs/pushmsixscript
[task 2025-10-08T21:19:05.502+00:00] configfile: tox.ini
[task 2025-10-08T21:19:05.502+00:00] plugins: anyio-4.11.0, asyncio-0.26.0, cov-7.0.0, requests-mock-1.12.1
[task 2025-10-08T21:19:05.502+00:00] asyncio: mode=Mode.STRICT, asyncio_default_fixture_loop_scope=function, asyncio_default_test_loop_scope=function
[task 2025-10-08T21:19:05.502+00:00] collected 70 items
[task 2025-10-08T21:19:05.502+00:00] 
[task 2025-10-08T21:19:05.564+00:00] tests/integration/test_integration_script.py ..                          [  2%]
[task 2025-10-08T21:19:05.577+00:00] tests/test_artifacts.py ......                                           [ 11%]
[task 2025-10-08T21:19:05.641+00:00] tests/test_config.py ...                                                 [ 15%]
[task 2025-10-08T21:19:05.652+00:00] tests/test_manifest.py ...                                               [ 20%]
[task 2025-10-08T21:19:05.806+00:00] tests/test_microsoft_store.py ........................................   [ 77%]
[task 2025-10-08T21:19:05.814+00:00] tests/test_script.py ...                                                 [ 81%]
[task 2025-10-08T21:19:06.308+00:00] tests/test_task.py .............                                         [100%]
[task 2025-10-08T21:19:06.308+00:00] 
[task 2025-10-08T21:19:06.308+00:00] ================================ tests coverage ================================
[task 2025-10-08T21:19:06.308+00:00] _______________ coverage: platform linux, python 3.11.9-final-0 ________________
[task 2025-10-08T21:19:06.308+00:00] 
[task 2025-10-08T21:19:06.308+00:00] Name                                    Stmts   Miss Branch BrPart  Cover   Missing
[task 2025-10-08T21:19:06.308+00:00] -----------------------------------------------------------------------------------
[task 2025-10-08T21:19:06.308+00:00] src/pushmsixscript/__init__.py              0      0      0      0   100%
[task 2025-10-08T21:19:06.308+00:00] src/pushmsixscript/artifacts.py             9      0      2      0   100%
[task 2025-10-08T21:19:06.308+00:00] src/pushmsixscript/manifest.py             38      2      6      2    91%   32, 41
[task 2025-10-08T21:19:06.308+00:00] src/pushmsixscript/microsoft_store.py     168      4     34      2    97%   68, 78->exit, 82-84
[task 2025-10-08T21:19:06.308+00:00] src/pushmsixscript/script.py               24      0      4      0   100%
[task 2025-10-08T21:19:06.308+00:00] src/pushmsixscript/task.py                 16      0      6      0   100%
[task 2025-10-08T21:19:06.308+00:00] -----------------------------------------------------------------------------------
[task 2025-10-08T21:19:06.308+00:00] TOTAL                                     255      6     52      4    97%
[task 2025-10-08T21:19:06.309+00:00] ============================== 70 passed in 4.92s ==============================
[task 2025-10-08T21:19:06.740+00:00]   py311: OK (8.20=setup[1.06]+cmd[7.14] seconds)
[task 2025-10-08T21:19:06.740+00:00]   congratulations :) (8.24 seconds)
[task 2025-10-08T21:19:06.804+00:00]   pushmsixscript-py311: OK (10.04=setup[0.91]+cmd[9.12] seconds)
[task 2025-10-08T21:19:06.804+00:00]   congratulations :) (10.10 seconds)
[taskcluster 2025-10-08 21:19:09.373Z] === Task Finished ===
[taskcluster 2025-10-08 21:19:09.374Z] Successful task run with exit code: 0 completed in 53.927 seconds