[taskcluster 2026-02-09T13:34:28.874Z] Worker Type (scriptworker-1/images) settings:
[taskcluster 2026-02-09T13:34:28.874Z]   {
[taskcluster 2026-02-09T13:34:28.874Z]     "config": {
[taskcluster 2026-02-09T13:34:28.874Z]       "deploymentId": ""
[taskcluster 2026-02-09T13:34:28.874Z]     },
[taskcluster 2026-02-09T13:34:28.874Z]     "generic-worker": {
[taskcluster 2026-02-09T13:34:28.874Z]       "config": {
[taskcluster 2026-02-09T13:34:28.874Z]         "headlessTasks": true
[taskcluster 2026-02-09T13:34:28.874Z]       },
[taskcluster 2026-02-09T13:34:28.874Z]       "engine": "multiuser",
[taskcluster 2026-02-09T13:34:28.874Z]       "go-arch": "amd64",
[taskcluster 2026-02-09T13:34:28.874Z]       "go-os": "linux",
[taskcluster 2026-02-09T13:34:28.874Z]       "go-version": "go1.25.5",
[taskcluster 2026-02-09T13:34:28.874Z]       "release": "https://github.com/taskcluster/taskcluster/releases/tag/v95.1.3",
[taskcluster 2026-02-09T13:34:28.874Z]       "revision": "300ac23a15bb11e577f3a3d116a570fde948337e",
[taskcluster 2026-02-09T13:34:28.874Z]       "source": "https://github.com/taskcluster/taskcluster/commits/300ac23a15bb11e577f3a3d116a570fde948337e",
[taskcluster 2026-02-09T13:34:28.874Z]       "version": "95.1.3"
[taskcluster 2026-02-09T13:34:28.874Z]     },
[taskcluster 2026-02-09T13:34:28.874Z]     "image": "projects/taskcluster-imaging/global/images/gw-fxci-gcp-l1-2404-amd64-headless-googlecompute-2026-01-14",
[taskcluster 2026-02-09T13:34:28.874Z]     "instance-id": "5264753919323823328",

...(5 lines hidden)...

[taskcluster 2026-02-09T13:34:28.874Z]     "region": "us-central1",
[taskcluster 2026-02-09T13:34:28.874Z]     "zone": "us-central1-f"
[taskcluster 2026-02-09T13:34:28.874Z]   }
[taskcluster 2026-02-09T13:34:28.874Z] Task ID: a02PZDH5T8m6ODC58e96Ww
[taskcluster 2026-02-09T13:34:28.874Z] === Task Starting ===
[taskcluster 2026-02-09T13:34:30.569Z] [mounts] No existing writable directory cache 'scriptworker-level-1-uv-v3-cbbaf5380474618f5c43-eHyhFiUyQp6MtMF4BhoYZQ' - creating /home/generic-worker/caches/Uy2sg0q5SQSsmdA5cyfHOg
[taskcluster 2026-02-09T13:34:30.569Z] [mounts] Creating directory /home/task_177064406830824/cache0
[taskcluster 2026-02-09T13:34:30.579Z] [mounts] Successfully mounted writable directory cache '/home/task_177064406830824/cache0'
[taskcluster 2026-02-09T13:34:30.579Z] [mounts] No existing writable directory cache 'scriptworker-level-1-checkouts-v3-cbbaf5380474618f5c43-eHyhFiUyQp6MtMF4BhoYZQ' - creating /home/generic-worker/caches/ESFy7gMjTI2hhXHhK749iw
[taskcluster 2026-02-09T13:34:30.580Z] [mounts] Creating directory /home/task_177064406830824/cache1
[taskcluster 2026-02-09T13:34:30.589Z] [mounts] Successfully mounted writable directory cache '/home/task_177064406830824/cache1'
[taskcluster 2026-02-09T13:34:30.589Z] [mounts] Downloading task eHyhFiUyQp6MtMF4BhoYZQ artifact public/image.tar.zst to /home/generic-worker/downloads/NS-mTBHkRiO1ulGE5KISdg
[taskcluster 2026-02-09T13:34:33.064Z] [mounts] Downloaded 98869577 bytes with SHA256 7120b062821d4845ef4d9431beb75c5196b8fcec398dceb8cad4ce0dd5d8a570 from task eHyhFiUyQp6MtMF4BhoYZQ artifact public/image.tar.zst to /home/generic-worker/downloads/NS-mTBHkRiO1ulGE5KISdg
[taskcluster:warn 2026-02-09T13:34:33.064Z] [mounts] Download /home/generic-worker/downloads/NS-mTBHkRiO1ulGE5KISdg of task eHyhFiUyQp6MtMF4BhoYZQ artifact public/image.tar.zst has SHA256 7120b062821d4845ef4d9431beb75c5196b8fcec398dceb8cad4ce0dd5d8a570 but task payload does not declare a required value, so content authenticity cannot be verified
[taskcluster 2026-02-09T13:34:33.064Z] [mounts] Creating directory /home/task_177064406830824
[taskcluster 2026-02-09T13:34:33.084Z] [mounts] Copying /home/generic-worker/downloads/NS-mTBHkRiO1ulGE5KISdg to /home/task_177064406830824/dockerimage
[taskcluster 2026-02-09T13:34:33.305Z] [mounts] Granting task_177064406830824 full control of file '/home/task_177064406830824/dockerimage'
[taskcluster 2026-02-09T13:34:33.552Z] [d2g] Loading docker image
[taskcluster 2026-02-09T13:34:37.927Z] [d2g] Loaded docker image "python3.12:latest"
[taskcluster 2026-02-09T13:34:37.927Z] Executing command 0: docker run -t --name taskcontainer_X7YrZBTORUWv0U8ej34kLw --memory-swap -1 --pids-limit -1 '--add-host=localhost.localdomain:127.0.0.1' -v '/home/task_177064406830824/cache0:/builds/worker/.task-cache/uv' -v /home/task_177064406830824/cache1:/builds/worker/checkouts --add-host=taskcluster:host-gateway --env-file 'env.list' 0acc88a3e5bb9bc3ffeae1b6e799f1598c85d554d12c3b427326467dae027da2 /usr/local/bin/run-task --scriptworker-checkout=/builds/worker/checkouts/vcs/ --task-cwd /builds/worker/checkouts/vcs -- sh -lxce 'uv run tox -e py312'
[setup 2026-02-09T13:34:39.879+00:00] run-task started in /
[setup 2026-02-09T13:34:39.879+00:00] Invoked by command: --scriptworker-checkout=/builds/worker/checkouts/vcs/ --task-cwd /builds/worker/checkouts/vcs -- sh -lxce uv run tox -e py312
[setup 2026-02-09T13:34:39.880+00:00] Python version: 3.12.12
[setup 2026-02-09T13:34:39.880+00:00] Subprocess python version: 
cpython-3.12.12-linux-x86_64-gnu    /usr/local/bin/python3.12
cpython-3.12.12-linux-x86_64-gnu    /usr/local/bin/python3 -> python3.12
cpython-3.12.12-linux-x86_64-gnu    /usr/local/bin/python -> python3
[cache 2026-02-09T13:34:40.050+00:00] cache /builds/worker/.task-cache/uv is empty; writing requirements: gid=1000 uid=1000 version=1
[cache 2026-02-09T13:34:40.051+00:00] cache /builds/worker/checkouts is empty; writing requirements: gid=1000 uid=1000 version=1
[volume 2026-02-09T13:34:40.051+00:00] volume /builds/worker/.task-cache/uv is a cache
[volume 2026-02-09T13:34:40.051+00:00] volume /builds/worker/checkouts is a cache
[setup 2026-02-09T13:34:40.051+00:00] running as worker:worker
[vcs 2026-02-09T13:34:40.051+00:00] executing ['git', 'config', '--global', '--add', 'safe.directory', '/builds/worker/checkouts/vcs']
[vcs 2026-02-09T13:34:40.055+00:00] executing ['git', 'clone', 'https://github.com/mozilla-releng/scriptworker', '/builds/worker/checkouts/vcs']
[vcs 2026-02-09T13:34:40.056+00:00] Cloning into '/builds/worker/checkouts/vcs'...
[vcs 2026-02-09T13:34:40.802+00:00] executing ['git', 'fetch', 'https://github.com/bhearsum/scriptworker', 'push-pvvtsmvpvmpl']
[vcs 2026-02-09T13:34:41.055+00:00] From https://github.com/bhearsum/scriptworker
[vcs 2026-02-09T13:34:41.055+00:00]  * branch            push-pvvtsmvpvmpl -> FETCH_HEAD
[vcs 2026-02-09T13:34:41.057+00:00] executing ['git', 'checkout', '-f', '-B', 'push-pvvtsmvpvmpl', '6c432ca1e4dbf746c9124628043926907d16766f']
[vcs 2026-02-09T13:34:41.129+00:00] Switched to a new branch 'push-pvvtsmvpvmpl'
[vcs 2026-02-09T13:34:41.129+00:00] cleaning git checkout...
[vcs 2026-02-09T13:34:41.129+00:00] executing ['git', 'clean', '-nxdff']
[vcs 2026-02-09T13:34:41.131+00:00] removing []
[vcs 2026-02-09T13:34:41.131+00:00] successfully cleaned git checkout!
[vcs 2026-02-09T13:34:41.133+00:00] TinderboxPrint:<a href='https://github.com/bhearsum/scriptworker/commit/6c432ca1e4dbf746c9124628043926907d16766f' title='Built from scriptworker commit 6c432ca1e4dbf746c9124628043926907d16766f'>6c432ca1e4dbf746c9124628043926907d16766f</a>
[setup 2026-02-09T13:34:41.133+00:00] UV_CACHE_DIR is /builds/worker/.task-cache/uv
[task 2026-02-09T13:34:41.133+00:00] executing ['sh', '-lxce', 'uv run tox -e py312']
[task 2026-02-09T13:34:41.134+00:00] + id -u
[task 2026-02-09T13:34:41.135+00:00] + [ 1000 -eq 0 ]
[task 2026-02-09T13:34:41.135+00:00] + PATH=/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
[task 2026-02-09T13:34:41.135+00:00] + export PATH
[task 2026-02-09T13:34:41.135+00:00] + [ $  ]
[task 2026-02-09T13:34:41.135+00:00] + [  ]
[task 2026-02-09T13:34:41.135+00:00] + id -u
[task 2026-02-09T13:34:41.136+00:00] + [ 1000 -eq 0 ]
[task 2026-02-09T13:34:41.136+00:00] + PS1=$ 
[task 2026-02-09T13:34:41.136+00:00] + [ -d /etc/profile.d ]
[task 2026-02-09T13:34:41.136+00:00] + [ -r /etc/profile.d/*.sh ]
[task 2026-02-09T13:34:41.137+00:00] + unset i
[task 2026-02-09T13:34:41.137+00:00] + uv run tox -e py312
[task 2026-02-09T13:34:41.213+00:00] Using CPython 3.12.12 interpreter at: /usr/local/bin/python3
[task 2026-02-09T13:34:41.213+00:00] Creating virtual environment at: .venv
[task 2026-02-09T13:34:42.410+00:00]    Building scriptworker @ file:///builds/worker/checkouts/vcs
[task 2026-02-09T13:34:42.474+00:00] Downloading uv (21.7MiB)
[task 2026-02-09T13:34:42.474+00:00] Downloading pygments (1.2MiB)
[task 2026-02-09T13:34:42.478+00:00] Downloading aiohttp (1.7MiB)
[task 2026-02-09T13:34:42.479+00:00] Downloading mypy (13.0MiB)
[task 2026-02-09T13:34:42.480+00:00] Downloading cryptography (4.2MiB)
[task 2026-02-09T13:34:42.482+00:00] Downloading black (1.7MiB)
[task 2026-02-09T13:34:42.483+00:00] Downloading virtualenv (5.7MiB)
[task 2026-02-09T13:34:42.868+00:00]    Building docopt==0.6.2
[task 2026-02-09T13:34:43.152+00:00]       Built docopt==0.6.2
[task 2026-02-09T13:34:43.256+00:00]  Downloaded aiohttp
[task 2026-02-09T13:34:43.411+00:00]  Downloaded virtualenv
[task 2026-02-09T13:34:43.455+00:00]  Downloaded black
[task 2026-02-09T13:34:43.456+00:00]  Downloaded uv
[task 2026-02-09T13:34:43.469+00:00]  Downloaded cryptography
[task 2026-02-09T13:34:43.522+00:00]  Downloaded pygments
[task 2026-02-09T13:34:43.683+00:00]       Built scriptworker @ file:///builds/worker/checkouts/vcs
[task 2026-02-09T13:34:43.716+00:00]  Downloaded mypy
[task 2026-02-09T13:34:43.718+00:00] warning: Failed to hardlink files; falling back to full copy. This may lead to degraded performance.
[task 2026-02-09T13:34:43.718+00:00]          If the cache and target directories are on different filesystems, hardlinking may not be supported.
[task 2026-02-09T13:34:43.718+00:00]          If this is intentional, set `export UV_LINK_MODE=copy` or use `--link-mode=copy` to suppress this warning.
[task 2026-02-09T13:34:43.865+00:00] Installed 104 packages in 147ms
[task 2026-02-09T13:34:44.550+00:00] .pkg: remove tox env folder /builds/worker/checkouts/vcs/.tox/.pkg
[task 2026-02-09T13:34:44.555+00:00] py312: venv> .venv/bin/uv venv -p /builds/worker/checkouts/vcs/.venv/bin/python --allow-existing --python-preference system /builds/worker/checkouts/vcs/.tox/py312
[task 2026-02-09T13:34:44.646+00:00] .pkg: venv> .venv/bin/uv venv -p /builds/worker/checkouts/vcs/.venv/bin/python --allow-existing --python-preference system /builds/worker/checkouts/vcs/.tox/.pkg
[task 2026-02-09T13:34:44.690+00:00] .pkg: install_requires> .venv/bin/uv pip install hatchling
[task 2026-02-09T13:34:44.909+00:00] .pkg: _optional_hooks> python /builds/worker/checkouts/vcs/.venv/lib/python3.12/site-packages/pyproject_api/_backend.py True hatchling.build
[task 2026-02-09T13:34:44.953+00:00] .pkg: get_requires_for_build_editable> python /builds/worker/checkouts/vcs/.venv/lib/python3.12/site-packages/pyproject_api/_backend.py True hatchling.build
[task 2026-02-09T13:34:45.054+00:00] .pkg: install_requires_for_build_editable> .venv/bin/uv pip install 'editables~=0.3'
[task 2026-02-09T13:34:45.155+00:00] .pkg: build_editable> python /builds/worker/checkouts/vcs/.venv/lib/python3.12/site-packages/pyproject_api/_backend.py True hatchling.build
[task 2026-02-09T13:34:45.248+00:00] py312: install_package_deps> .venv/bin/uv pip install PyYAML 'aiohttp>=3' 'arrow>=1.0' 'cryptography>=2.6.1' dictdiffer github3.py 'immutabledict>=1.3.0' 'json-e>=2.5.0' 'jsonschema[format-nongpl]' taskcluster-taskgraph 'taskcluster>=40'
[task 2026-02-09T13:34:46.021+00:00] py312: install_package> .venv/bin/uv pip install --reinstall --no-deps scriptworker@/builds/worker/checkouts/vcs/.tox/.tmp/package/1/scriptworker-62.3.0-py3-none-any.whl
[task 2026-02-09T13:34:46.084+00:00] py312: commands[0]> coverage run --source=src/scriptworker --branch --rcfile tox.ini -m pytest tests
[task 2026-02-09T13:34:56.760+00:00] ============================= test session starts ==============================
[task 2026-02-09T13:34:56.760+00:00] platform linux -- Python 3.12.12, pytest-9.0.2, pluggy-1.6.0
[task 2026-02-09T13:34:56.760+00:00] cachedir: .tox/py312/.pytest_cache
[task 2026-02-09T13:34:56.760+00:00] Test order randomisation NOT enabled. Enable with --random-order or --random-order-bucket=<bucket_type>
[task 2026-02-09T13:34:56.760+00:00] rootdir: /builds/worker/checkouts/vcs
[task 2026-02-09T13:34:56.760+00:00] configfile: pyproject.toml (WARNING: ignoring pytest config in tox.ini!)
[task 2026-02-09T13:34:56.760+00:00] plugins: asyncio-1.3.0, random-order-1.2.0, mock-3.15.1
[task 2026-02-09T13:34:56.760+00:00] asyncio: mode=Mode.AUTO, debug=False, asyncio_default_fixture_loop_scope=None, asyncio_default_test_loop_scope=function
[task 2026-02-09T13:34:56.760+00:00] collected 687 items
[task 2026-02-09T13:34:56.760+00:00] 
[task 2026-02-09T13:34:56.930+00:00] tests/test_artifacts.py .........................................        [  5%]
[task 2026-02-09T13:34:57.029+00:00] tests/test_client.py ............................                        [ 10%]
[task 2026-02-09T13:34:57.133+00:00] tests/test_config.py ....................................                [ 15%]
[task 2026-02-09T13:34:57.177+00:00] tests/test_context.py .............                                      [ 17%]
[task 2026-02-09T13:34:57.242+00:00] tests/test_cot_generate.py ......                                        [ 18%]
[task 2026-02-09T13:34:57.515+00:00] tests/test_cot_verify.py ............................................... [ 24%]
[task 2026-02-09T13:35:00.679+00:00] ........................................................................ [ 35%]
[task 2026-02-09T13:35:01.627+00:00] ........................................................................ [ 45%]
[task 2026-02-09T13:35:01.747+00:00] .....................                                                    [ 48%]
[task 2026-02-09T13:35:01.764+00:00] tests/test_ed25519.py ........                                           [ 50%]
[task 2026-02-09T13:35:02.220+00:00] tests/test_github.py ................................................... [ 57%]
[task 2026-02-09T13:35:02.240+00:00] ...                                                                      [ 57%]
[task 2026-02-09T13:35:02.253+00:00] tests/test_integration.py ssssssssssss                                   [ 59%]
[task 2026-02-09T13:35:02.316+00:00] tests/test_log.py ..........                                             [ 61%]
[task 2026-02-09T13:35:02.328+00:00] tests/test_production.py sssssssssss                                     [ 62%]
[task 2026-02-09T13:35:04.588+00:00] tests/test_task.py ..................................................... [ 70%]
[task 2026-02-09T13:35:12.005+00:00] ...............................................................          [ 79%]
[task 2026-02-09T13:35:15.025+00:00] tests/test_task_process.py ....                                          [ 80%]
[task 2026-02-09T13:35:15.668+00:00] tests/test_utils.py .................................................... [ 87%]
[task 2026-02-09T13:35:15.857+00:00] ............................................................             [ 96%]
[task 2026-02-09T13:35:18.336+00:00] tests/test_worker.py ..............Task was destroyed but it is pending!
[task 2026-02-09T13:35:18.336+00:00] task: <Task cancelling name='Task-1870' coro=<noop_async() running at /builds/worker/checkouts/vcs/tests/__init__.py:121>>
[task 2026-02-09T13:35:18.336+00:00] /usr/local/lib/python3.12/asyncio/base_events.py:716: RuntimeWarning: coroutine 'noop_async' was never awaited
[task 2026-02-09T13:35:18.336+00:00]   self._ready.clear()
[task 2026-02-09T13:35:18.337+00:00] RuntimeWarning: Enable tracemalloc to get the object allocation traceback
[task 2026-02-09T13:35:18.337+00:00] Task was destroyed but it is pending!
[task 2026-02-09T13:35:18.337+00:00] task: <Task cancelling name='Task-1893' coro=<noop_async() running at /builds/worker/checkouts/vcs/tests/__init__.py:121>>
[task 2026-02-09T13:35:18.337+00:00] Task was destroyed but it is pending!
[task 2026-02-09T13:35:18.337+00:00] task: <Task cancelling name='Task-1902' coro=<noop_async() running at /builds/worker/checkouts/vcs/tests/__init__.py:121>>
[task 2026-02-09T13:35:18.337+00:00] Task was destroyed but it is pending!
[task 2026-02-09T13:35:18.337+00:00] task: <Task cancelling name='Task-1909' coro=<noop_async() running at /builds/worker/checkouts/vcs/tests/__init__.py:121>>
[task 2026-02-09T13:35:18.337+00:00] Task was destroyed but it is pending!
[task 2026-02-09T13:35:18.337+00:00] task: <Task cancelling name='Task-1917' coro=<noop_async() running at /builds/worker/checkouts/vcs/tests/__init__.py:121>>
[task 2026-02-09T13:35:18.534+00:00] ..........                            [100%]/builds/worker/checkouts/vcs/.venv/lib/python3.12/site-packages/_pytest/unraisableexception.py:67: PytestUnraisableExceptionWarning: Exception ignored in: <function BaseSubprocessTransport.__del__ at 0x7f127088e980>
[task 2026-02-09T13:35:18.534+00:00] 
[task 2026-02-09T13:35:18.534+00:00] Traceback (most recent call last):
[task 2026-02-09T13:35:18.534+00:00]   File "/usr/local/lib/python3.12/asyncio/base_subprocess.py", line 126, in __del__
[task 2026-02-09T13:35:18.534+00:00]     self.close()
[task 2026-02-09T13:35:18.534+00:00]   File "/usr/local/lib/python3.12/asyncio/base_subprocess.py", line 104, in close
[task 2026-02-09T13:35:18.535+00:00]     proto.pipe.close()
[task 2026-02-09T13:35:18.535+00:00]   File "/usr/local/lib/python3.12/asyncio/unix_events.py", line 568, in close
[task 2026-02-09T13:35:18.535+00:00]     self._close(None)
[task 2026-02-09T13:35:18.535+00:00]   File "/usr/local/lib/python3.12/asyncio/unix_events.py", line 592, in _close
[task 2026-02-09T13:35:18.535+00:00]     self._loop.call_soon(self._call_connection_lost, exc)
[task 2026-02-09T13:35:18.535+00:00]   File "/usr/local/lib/python3.12/asyncio/base_events.py", line 799, in call_soon
[task 2026-02-09T13:35:18.535+00:00]     self._check_closed()
[task 2026-02-09T13:35:18.535+00:00]   File "/usr/local/lib/python3.12/asyncio/base_events.py", line 545, in _check_closed
[task 2026-02-09T13:35:18.535+00:00]     raise RuntimeError('Event loop is closed')
[task 2026-02-09T13:35:18.535+00:00] RuntimeError: Event loop is closed
[task 2026-02-09T13:35:18.535+00:00] 
[task 2026-02-09T13:35:18.535+00:00] Enable tracemalloc to get traceback where the object was allocated.
[task 2026-02-09T13:35:18.535+00:00] See https://docs.pytest.org/en/stable/how-to/capture-warnings.html#resource-warnings for more info.
[task 2026-02-09T13:35:18.535+00:00]   warnings.warn(pytest.PytestUnraisableExceptionWarning(msg))
[task 2026-02-09T13:35:18.535+00:00] 
[task 2026-02-09T13:35:18.535+00:00] 
[task 2026-02-09T13:35:18.536+00:00] =============================== warnings summary ===============================
[task 2026-02-09T13:35:18.536+00:00] tests/test_cot_verify.py::test_get_action_context_and_template[action-NdzxKw8bS5Sw5DRhoiM14w-/builds/worker/checkouts/vcs/tests/data/cotv4/action_retrigger.json-c5nn2xbNS9mJxeVC0uNElg-/builds/worker/checkouts/vcs/tests/data/cotv4/decision_try.json-/builds/worker/checkouts/vcs/tests/data/cotv4/retrigger_template.json-/builds/worker/checkouts/vcs/tests/data/cotv4/retrigger_context.json]
[task 2026-02-09T13:35:18.536+00:00] tests/test_cot_verify.py::test_verify_parent_task_definition[decision-VQU9QMO4Teq7zr91FhBusg-/builds/worker/checkouts/vcs/tests/data/cotv2/decision_hg-push.json-VQU9QMO4Teq7zr91FhBusg-/builds/worker/checkouts/vcs/tests/data/cotv2/decision_hg-push.json-/builds/worker/checkouts/vcs/tests/data/cotv2]
[task 2026-02-09T13:35:18.536+00:00] tests/test_cot_verify.py::test_verify_parent_task_definition[action-LpCJV9wUQHSAm5SHyW4Olw-/builds/worker/checkouts/vcs/tests/data/cotv4/action_relpro.json-VQU9QMO4Teq7zr91FhBusg-/builds/worker/checkouts/vcs/tests/data/cotv2/decision_hg-push.json-/builds/worker/checkouts/vcs/tests/data/cotv4]
[task 2026-02-09T13:35:18.536+00:00] tests/test_cot_verify.py::test_verify_parent_task_definition[action-LpCJV9wUQHSAm5SHyW4Olw-/builds/worker/checkouts/vcs/tests/data/cotv4/action_relpro.json-VQU9QMO4Teq7zr91FhBusg-/builds/worker/checkouts/vcs/tests/data/cotv2/decision_hg-push.json-/builds/worker/checkouts/vcs/tests/data/cotv4]
[task 2026-02-09T13:35:18.536+00:00] tests/test_cot_verify.py::test_verify_parent_task_definition[decision-D4euZNyCRtuBci-fnsfn7A-/builds/worker/checkouts/vcs/tests/data/cotv2/cron.json-D4euZNyCRtuBci-fnsfn7A-/builds/worker/checkouts/vcs/tests/data/cotv2/cron.json-/builds/worker/checkouts/vcs/tests/data/cotv2]
[task 2026-02-09T13:35:18.536+00:00] tests/test_cot_verify.py::test_verify_parent_task_definition_vpn[decision-VUTfOIPFQWaGHf7sIbgTEg-/builds/worker/checkouts/vcs/tests/data/cotv4/decision_github_private.json-VUTfOIPFQWaGHf7sIbgTEg-/builds/worker/checkouts/vcs/tests/data/cotv4/decision_github_private.json]
[task 2026-02-09T13:35:18.536+00:00] tests/test_cot_verify.py::test_get_pr_action_context_and_template
[task 2026-02-09T13:35:18.536+00:00] tests/test_cot_verify.py::test_verify_parent_task_definition_failed_diff
[task 2026-02-09T13:35:18.536+00:00]   /builds/worker/checkouts/vcs/.venv/lib/python3.12/site-packages/jsone/shared.py:97: DeprecationWarning: datetime.datetime.utcnow() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.now(datetime.UTC).
[task 2026-02-09T13:35:18.536+00:00]     reference = datetime.datetime.utcnow()
[task 2026-02-09T13:35:18.536+00:00] 
[task 2026-02-09T13:35:18.536+00:00] tests/test_worker.py::test_mocker_run_tasks_noop
[task 2026-02-09T13:35:18.536+00:00] tests/test_worker.py::test_mocker_run_tasks_caught_exception[upload_artifacts-ScriptWorkerException-5]
[task 2026-02-09T13:35:18.536+00:00] tests/test_worker.py::test_unexpected_exception_catch_and_tell_taskcluster
[task 2026-02-09T13:35:18.536+00:00] tests/test_worker.py::test_run_tasks_no_cancel
[task 2026-02-09T13:35:18.536+00:00]   /usr/local/lib/python3.12/asyncio/base_events.py:716: RuntimeWarning: coroutine 'noop_async' was never awaited
[task 2026-02-09T13:35:18.536+00:00]     self._ready.clear()
[task 2026-02-09T13:35:18.536+00:00]   Enable tracemalloc to get traceback where the object was allocated.
[task 2026-02-09T13:35:18.536+00:00]   See https://docs.pytest.org/en/stable/how-to/capture-warnings.html#resource-warnings for more info.
[task 2026-02-09T13:35:18.536+00:00] 
[task 2026-02-09T13:35:18.536+00:00] -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html
[task 2026-02-09T13:35:18.536+00:00] ================ 664 passed, 23 skipped, 12 warnings in 25.71s =================
[task 2026-02-09T13:35:18.833+00:00] .pkg: _exit> python /builds/worker/checkouts/vcs/.venv/lib/python3.12/site-packages/pyproject_api/_backend.py True hatchling.build
[task 2026-02-09T13:35:18.854+00:00]   py312: OK (34.30=setup[1.56]+cmd[32.75] seconds)
[task 2026-02-09T13:35:18.854+00:00]   congratulations :) (34.37 seconds)
[taskcluster 2026-02-09T13:35:19.112Z]                        Exit Code: 0
[taskcluster 2026-02-09T13:35:19.112Z]                        User Time: 29.67ms
[taskcluster 2026-02-09T13:35:19.112Z]                      Kernel Time: 16.184ms
[taskcluster 2026-02-09T13:35:19.112Z]                        Wall Time: 41.185108309s
[taskcluster 2026-02-09T13:35:19.112Z]  Average Available System Memory: 14.75 GiB
[taskcluster 2026-02-09T13:35:19.112Z]       Average System Memory Used: 888.40 MiB
[taskcluster 2026-02-09T13:35:19.112Z]          Peak System Memory Used: 957.89 MiB
[taskcluster 2026-02-09T13:35:19.112Z]              Total System Memory: 15.62 GiB
[taskcluster 2026-02-09T13:35:19.112Z]                           Result: SUCCEEDED
[taskcluster 2026-02-09T13:35:19.113Z] === Task Finished ===
[taskcluster 2026-02-09T13:35:19.113Z] Task Duration: 41.185694133s
[taskcluster 2026-02-09T13:35:19.134Z] [mounts] Preserving cache: Moving "/home/task_177064406830824/cache0" to "/home/generic-worker/caches/Uy2sg0q5SQSsmdA5cyfHOg"
[taskcluster 2026-02-09T13:35:19.134Z] [mounts] Preserving cache: Moving "/home/task_177064406830824/cache1" to "/home/generic-worker/caches/ESFy7gMjTI2hhXHhK749iw"