[taskcluster 2026-02-04T15:33:08.327Z] Worker Type (scriptworker-1/images) settings:
[taskcluster 2026-02-04T15:33:08.327Z]   {
[taskcluster 2026-02-04T15:33:08.327Z]     "config": {
[taskcluster 2026-02-04T15:33:08.327Z]       "deploymentId": ""
[taskcluster 2026-02-04T15:33:08.327Z]     },
[taskcluster 2026-02-04T15:33:08.327Z]     "generic-worker": {
[taskcluster 2026-02-04T15:33:08.327Z]       "config": {
[taskcluster 2026-02-04T15:33:08.327Z]         "headlessTasks": true
[taskcluster 2026-02-04T15:33:08.327Z]       },
[taskcluster 2026-02-04T15:33:08.327Z]       "engine": "multiuser",
[taskcluster 2026-02-04T15:33:08.327Z]       "go-arch": "amd64",
[taskcluster 2026-02-04T15:33:08.327Z]       "go-os": "linux",
[taskcluster 2026-02-04T15:33:08.327Z]       "go-version": "go1.25.5",
[taskcluster 2026-02-04T15:33:08.327Z]       "release": "https://github.com/taskcluster/taskcluster/releases/tag/v95.1.3",
[taskcluster 2026-02-04T15:33:08.327Z]       "revision": "300ac23a15bb11e577f3a3d116a570fde948337e",
[taskcluster 2026-02-04T15:33:08.327Z]       "source": "https://github.com/taskcluster/taskcluster/commits/300ac23a15bb11e577f3a3d116a570fde948337e",
[taskcluster 2026-02-04T15:33:08.327Z]       "version": "95.1.3"
[taskcluster 2026-02-04T15:33:08.327Z]     },
[taskcluster 2026-02-04T15:33:08.327Z]     "image": "projects/taskcluster-imaging/global/images/gw-fxci-gcp-l1-2404-amd64-headless-googlecompute-2026-01-14",
[taskcluster 2026-02-04T15:33:08.327Z]     "instance-id": "9134701503799286793",
[taskcluster 2026-02-04T15:33:08.327Z]     "instance-type": "projects/887720501152/machineTypes/c2-standard-4",
[taskcluster 2026-02-04T15:33:08.327Z]     "local-ipv4": "10.128.0.26",
[taskcluster 2026-02-04T15:33:08.327Z]     "project-id": "fxci-production-level1-workers",
[taskcluster 2026-02-04T15:33:08.327Z]     "public-hostname": "scriptworker-1-images-r-brjkpormepn3o2jm4xkg.c.fxci-production-level1-workers.internal",
[taskcluster 2026-02-04T15:33:08.327Z]     "public-ipv4": "34.69.249.66",
[taskcluster 2026-02-04T15:33:08.327Z]     "region": "us-central1",
[taskcluster 2026-02-04T15:33:08.327Z]     "zone": "us-central1-f"
[taskcluster 2026-02-04T15:33:08.327Z]   }
[taskcluster 2026-02-04T15:33:08.327Z] Task ID: e7XRw3fPQzKpkU_hBfK8iw
[taskcluster 2026-02-04T15:33:08.327Z] === Task Starting ===
[taskcluster 2026-02-04T15:33:08.956Z] [mounts] No existing writable directory cache 'scriptworker-level-1-checkouts-v3-cbbaf5380474618f5c43-c1EqJiWNRNGW_8JenzNONw' - creating /home/generic-worker/caches/FA-h-cMDRI-FFOna285Pxw
[taskcluster 2026-02-04T15:33:08.956Z] [mounts] Creating directory /home/task_177021892191675/cache0
[taskcluster 2026-02-04T15:33:08.966Z] [mounts] Successfully mounted writable directory cache '/home/task_177021892191675/cache0'
[taskcluster 2026-02-04T15:33:08.966Z] [mounts] No existing writable directory cache 'scriptworker-level-1-uv-v3-cbbaf5380474618f5c43-c1EqJiWNRNGW_8JenzNONw' - creating /home/generic-worker/caches/Vf-o4VBHTBuTh2i_gv_2Ug
[taskcluster 2026-02-04T15:33:08.966Z] [mounts] Creating directory /home/task_177021892191675/cache1
[taskcluster 2026-02-04T15:33:08.976Z] [mounts] Successfully mounted writable directory cache '/home/task_177021892191675/cache1'
[taskcluster 2026-02-04T15:33:08.976Z] [mounts] Downloading task c1EqJiWNRNGW_8JenzNONw artifact public/image.tar.zst to /home/generic-worker/downloads/Kg6FpNjNQYC7p08ilKrW7g
[taskcluster 2026-02-04T15:33:13.362Z] [mounts] Downloaded 120216569 bytes with SHA256 c49b656a9f90a84381859a9b7b81f4f101b2998a9be808db4e8e14a254588ae5 from task c1EqJiWNRNGW_8JenzNONw artifact public/image.tar.zst to /home/generic-worker/downloads/Kg6FpNjNQYC7p08ilKrW7g
[taskcluster:warn 2026-02-04T15:33:13.362Z] [mounts] Download /home/generic-worker/downloads/Kg6FpNjNQYC7p08ilKrW7g of task c1EqJiWNRNGW_8JenzNONw artifact public/image.tar.zst has SHA256 c49b656a9f90a84381859a9b7b81f4f101b2998a9be808db4e8e14a254588ae5 but task payload does not declare a required value, so content authenticity cannot be verified
[taskcluster 2026-02-04T15:33:13.362Z] [mounts] Creating directory /home/task_177021892191675
[taskcluster 2026-02-04T15:33:13.381Z] [mounts] Copying /home/generic-worker/downloads/Kg6FpNjNQYC7p08ilKrW7g to /home/task_177021892191675/dockerimage
[taskcluster 2026-02-04T15:33:13.696Z] [mounts] Granting task_177021892191675 full control of file '/home/task_177021892191675/dockerimage'
[taskcluster 2026-02-04T15:33:13.991Z] [d2g] Loading docker image
[taskcluster 2026-02-04T15:33:17.896Z] [d2g] Loaded docker image "python3.14:latest"
[taskcluster 2026-02-04T15:33:17.896Z] Executing command 0: docker run -t --name taskcontainer_THixaCvrRu-niDeAvTdRvw --memory-swap -1 --pids-limit -1 '--add-host=localhost.localdomain:127.0.0.1' -v /home/task_177021892191675/cache0:/builds/worker/checkouts -v '/home/task_177021892191675/cache1:/builds/worker/.task-cache/uv' --add-host=taskcluster:host-gateway --env-file 'env.list' 0e7e8a13fa67e51ab3b5d7383df5dee0195b53e4788a82c631db8f7c9aed18b4 /usr/local/bin/run-task --scriptworker-checkout=/builds/worker/checkouts/vcs/ --task-cwd /builds/worker/checkouts/vcs -- sh -lxce 'uv run tox -e py314-cot'
[setup 2026-02-04T15:33:19.263+00:00] run-task started in /
[setup 2026-02-04T15:33:19.263+00:00] Invoked by command: --scriptworker-checkout=/builds/worker/checkouts/vcs/ --task-cwd /builds/worker/checkouts/vcs -- sh -lxce uv run tox -e py314-cot
[setup 2026-02-04T15:33:19.263+00:00] Python version: 3.14.1
[setup 2026-02-04T15:33:19.263+00:00] Subprocess python version: 
cpython-3.14.1-linux-x86_64-gnu    /usr/local/bin/python3.14
cpython-3.14.1-linux-x86_64-gnu    /usr/local/bin/python3 -> python3.14
cpython-3.14.1-linux-x86_64-gnu    /usr/local/bin/python -> python3
[cache 2026-02-04T15:33:19.464+00:00] cache /builds/worker/.task-cache/uv is empty; writing requirements: gid=1000 uid=1000 version=1
[cache 2026-02-04T15:33:19.465+00:00] cache /builds/worker/checkouts is empty; writing requirements: gid=1000 uid=1000 version=1
[volume 2026-02-04T15:33:19.465+00:00] volume /builds/worker/.task-cache/uv is a cache
[volume 2026-02-04T15:33:19.465+00:00] volume /builds/worker/checkouts is a cache
[setup 2026-02-04T15:33:19.465+00:00] running as worker:worker
[vcs 2026-02-04T15:33:19.465+00:00] executing ['git', 'config', '--global', '--add', 'safe.directory', '/builds/worker/checkouts/vcs']
[vcs 2026-02-04T15:33:19.468+00:00] executing ['git', 'clone', 'https://github.com/mozilla-releng/scriptworker', '/builds/worker/checkouts/vcs']
[vcs 2026-02-04T15:33:19.469+00:00] Cloning into '/builds/worker/checkouts/vcs'...
[vcs 2026-02-04T15:33:20.243+00:00] executing ['git', 'fetch', 'https://github.com/Eijebong/scriptworker', 'py314']
[vcs 2026-02-04T15:33:20.510+00:00] From https://github.com/Eijebong/scriptworker
[vcs 2026-02-04T15:33:20.510+00:00]  * branch            py314      -> FETCH_HEAD
[vcs 2026-02-04T15:33:20.512+00:00] executing ['git', 'checkout', '-f', '-B', 'py314', '9087ccd92ba1bc41a6e85618446e231455e1b98b']
[vcs 2026-02-04T15:33:20.585+00:00] Switched to a new branch 'py314'
[vcs 2026-02-04T15:33:20.586+00:00] cleaning git checkout...
[vcs 2026-02-04T15:33:20.586+00:00] executing ['git', 'clean', '-nxdff']
[vcs 2026-02-04T15:33:20.588+00:00] removing []
[vcs 2026-02-04T15:33:20.588+00:00] successfully cleaned git checkout!
[vcs 2026-02-04T15:33:20.589+00:00] TinderboxPrint:<a href='https://github.com/Eijebong/scriptworker/commit/9087ccd92ba1bc41a6e85618446e231455e1b98b' title='Built from scriptworker commit 9087ccd92ba1bc41a6e85618446e231455e1b98b'>9087ccd92ba1bc41a6e85618446e231455e1b98b</a>
[setup 2026-02-04T15:33:20.589+00:00] UV_CACHE_DIR is /builds/worker/.task-cache/uv
[task 2026-02-04T15:33:20.589+00:00] executing ['sh', '-lxce', 'uv run tox -e py314-cot']
[task 2026-02-04T15:33:20.591+00:00] + id -u
[task 2026-02-04T15:33:20.591+00:00] + [ 1000 -eq 0 ]
[task 2026-02-04T15:33:20.591+00:00] + PATH=/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
[task 2026-02-04T15:33:20.591+00:00] + export PATH
[task 2026-02-04T15:33:20.592+00:00] + [ $  ]
[task 2026-02-04T15:33:20.592+00:00] + [  ]
[task 2026-02-04T15:33:20.592+00:00] + id -u
[task 2026-02-04T15:33:20.593+00:00] + [ 1000 -eq 0 ]
[task 2026-02-04T15:33:20.593+00:00] + PS1=$ 
[task 2026-02-04T15:33:20.593+00:00] + [ -d /etc/profile.d ]
[task 2026-02-04T15:33:20.593+00:00] + [ -r /etc/profile.d/*.sh ]
[task 2026-02-04T15:33:20.593+00:00] + unset i
[task 2026-02-04T15:33:20.593+00:00] + uv run tox -e py314-cot
[task 2026-02-04T15:33:20.674+00:00] Using CPython 3.14.1 interpreter at: /usr/local/bin/python3
[task 2026-02-04T15:33:20.674+00:00] Creating virtual environment at: .venv
[task 2026-02-04T15:33:21.993+00:00]    Building scriptworker @ file:///builds/worker/checkouts/vcs
[task 2026-02-04T15:33:22.057+00:00] Downloading cryptography (4.2MiB)
[task 2026-02-04T15:33:22.059+00:00] Downloading virtualenv (5.7MiB)
[task 2026-02-04T15:33:22.059+00:00] Downloading uv (21.7MiB)
[task 2026-02-04T15:33:22.063+00:00] Downloading black (1.7MiB)
[task 2026-02-04T15:33:22.063+00:00] Downloading pygments (1.2MiB)
[task 2026-02-04T15:33:22.063+00:00] Downloading aiohttp (1.6MiB)
[task 2026-02-04T15:33:22.065+00:00] Downloading mypy (13.0MiB)
[task 2026-02-04T15:33:22.424+00:00]    Building docopt==0.6.2
[task 2026-02-04T15:33:22.737+00:00]       Built docopt==0.6.2
[task 2026-02-04T15:33:22.852+00:00]  Downloaded aiohttp
[task 2026-02-04T15:33:22.942+00:00]  Downloaded virtualenv
[task 2026-02-04T15:33:22.971+00:00]  Downloaded black
[task 2026-02-04T15:33:23.016+00:00]  Downloaded cryptography
[task 2026-02-04T15:33:23.045+00:00]  Downloaded uv
[task 2026-02-04T15:33:23.097+00:00]  Downloaded pygments
[task 2026-02-04T15:33:23.168+00:00]       Built scriptworker @ file:///builds/worker/checkouts/vcs
[task 2026-02-04T15:33:23.275+00:00]  Downloaded mypy
[task 2026-02-04T15:33:23.276+00:00] warning: Failed to hardlink files; falling back to full copy. This may lead to degraded performance.
[task 2026-02-04T15:33:23.276+00:00]          If the cache and target directories are on different filesystems, hardlinking may not be supported.
[task 2026-02-04T15:33:23.276+00:00]          If this is intentional, set `export UV_LINK_MODE=copy` or use `--link-mode=copy` to suppress this warning.
[task 2026-02-04T15:33:23.433+00:00] Installed 103 packages in 157ms
[task 2026-02-04T15:33:24.204+00:00] .pkg: remove tox env folder /builds/worker/checkouts/vcs/.tox/.pkg
[task 2026-02-04T15:33:24.208+00:00] py314-cot: venv> .venv/bin/uv venv -p /builds/worker/checkouts/vcs/.venv/bin/python --allow-existing --python-preference system /builds/worker/checkouts/vcs/.tox/py314-cot
[task 2026-02-04T15:33:24.304+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-04T15:33:24.347+00:00] .pkg: install_requires> .venv/bin/uv pip install hatchling
[task 2026-02-04T15:33:24.498+00:00] .pkg: _optional_hooks> python /builds/worker/checkouts/vcs/.venv/lib/python3.14/site-packages/pyproject_api/_backend.py True hatchling.build
[task 2026-02-04T15:33:24.552+00:00] .pkg: get_requires_for_build_editable> python /builds/worker/checkouts/vcs/.venv/lib/python3.14/site-packages/pyproject_api/_backend.py True hatchling.build
[task 2026-02-04T15:33:24.658+00:00] .pkg: install_requires_for_build_editable> .venv/bin/uv pip install 'editables~=0.3'
[task 2026-02-04T15:33:24.708+00:00] .pkg: build_editable> python /builds/worker/checkouts/vcs/.venv/lib/python3.14/site-packages/pyproject_api/_backend.py True hatchling.build
[task 2026-02-04T15:33:24.808+00:00] py314-cot: 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-04T15:33:25.303+00:00] py314-cot: 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-04T15:33:25.371+00:00] py314-cot: commands[0]> py.test -k test_verify_production_cot --random-order-bucket=none
[task 2026-02-04T15:33:28.669+00:00] ============================= test session starts ==============================
[task 2026-02-04T15:33:28.669+00:00] platform linux -- Python 3.14.1, pytest-9.0.2, pluggy-1.6.0
[task 2026-02-04T15:33:28.669+00:00] cachedir: .tox/py314-cot/.pytest_cache
[task 2026-02-04T15:33:28.669+00:00] Using --random-order-bucket=none
[task 2026-02-04T15:33:28.669+00:00] Using --random-order-seed=183405
[task 2026-02-04T15:33:28.669+00:00] 
[task 2026-02-04T15:33:28.669+00:00] rootdir: /builds/worker/checkouts/vcs
[task 2026-02-04T15:33:28.669+00:00] configfile: pyproject.toml (WARNING: ignoring pytest config in tox.ini!)
[task 2026-02-04T15:33:28.669+00:00] plugins: random-order-1.2.0, asyncio-1.3.0, mock-3.15.1
[task 2026-02-04T15:33:28.669+00:00] asyncio: mode=Mode.AUTO, debug=False, asyncio_default_fixture_loop_scope=None, asyncio_default_test_loop_scope=function
[task 2026-02-04T15:33:28.669+00:00] collected 687 items / 676 deselected / 11 selected
[task 2026-02-04T15:33:28.669+00:00] 
[task 2026-02-04T15:35:37.421+00:00] tests/test_production.py ...........                                     [100%]
[task 2026-02-04T15:35:37.421+00:00] 
[task 2026-02-04T15:35:37.421+00:00] =============================== warnings summary ===============================
[task 2026-02-04T15:35:37.421+00:00] tests/test_production.py: 32 warnings
[task 2026-02-04T15:35:37.421+00:00]   /builds/worker/checkouts/vcs/.venv/lib/python3.14/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-04T15:35:37.421+00:00]     reference = datetime.datetime.utcnow()
[task 2026-02-04T15:35:37.421+00:00] 
[task 2026-02-04T15:35:37.421+00:00] -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html
[task 2026-02-04T15:35:37.421+00:00] ========= 11 passed, 676 deselected, 32 warnings in 130.32s (0:02:10) ==========
[task 2026-02-04T15:35:37.728+00:00] .pkg: _exit> python /builds/worker/checkouts/vcs/.venv/lib/python3.14/site-packages/pyproject_api/_backend.py True hatchling.build
[task 2026-02-04T15:35:37.757+00:00]   py314-cot: OK (133.55=setup[1.20]+cmd[132.36] seconds)
[task 2026-02-04T15:35:37.757+00:00]   congratulations :) (133.63 seconds)
[taskcluster 2026-02-04T15:35:37.914Z]                        Exit Code: 0
[taskcluster 2026-02-04T15:35:37.914Z]                        User Time: 18.684ms
[taskcluster 2026-02-04T15:35:37.914Z]                      Kernel Time: 12.456ms
[taskcluster 2026-02-04T15:35:37.914Z]                        Wall Time: 2m20.017060227s
[taskcluster 2026-02-04T15:35:37.914Z]  Average Available System Memory: 14.62 GiB
[taskcluster 2026-02-04T15:35:37.914Z]       Average System Memory Used: 1021.52 MiB
[taskcluster 2026-02-04T15:35:37.914Z]          Peak System Memory Used: 1.11 GiB
[taskcluster 2026-02-04T15:35:37.914Z]              Total System Memory: 15.62 GiB
[taskcluster 2026-02-04T15:35:37.914Z]                           Result: SUCCEEDED
[taskcluster 2026-02-04T15:35:37.914Z] === Task Finished ===
[taskcluster 2026-02-04T15:35:37.914Z] Task Duration: 2m20.017791793s
[taskcluster 2026-02-04T15:35:37.945Z] [mounts] Preserving cache: Moving "/home/task_177021892191675/cache0" to "/home/generic-worker/caches/FA-h-cMDRI-FFOna285Pxw"
[taskcluster 2026-02-04T15:35:37.946Z] [mounts] Preserving cache: Moving "/home/task_177021892191675/cache1" to "/home/generic-worker/caches/Vf-o4VBHTBuTh2i_gv_2Ug"