[taskcluster 2026-01-09T12:11:55.738Z] Worker Type (scriptworker-1/images) settings:
[taskcluster 2026-01-09T12:11:55.738Z]   {
[taskcluster 2026-01-09T12:11:55.738Z]     "config": {
[taskcluster 2026-01-09T12:11:55.738Z]       "deploymentId": ""
[taskcluster 2026-01-09T12:11:55.738Z]     },
[taskcluster 2026-01-09T12:11:55.738Z]     "generic-worker": {
[taskcluster 2026-01-09T12:11:55.738Z]       "config": {
[taskcluster 2026-01-09T12:11:55.738Z]         "headlessTasks": true
[taskcluster 2026-01-09T12:11:55.738Z]       },
[taskcluster 2026-01-09T12:11:55.738Z]       "engine": "multiuser",
[taskcluster 2026-01-09T12:11:55.738Z]       "go-arch": "amd64",
[taskcluster 2026-01-09T12:11:55.738Z]       "go-os": "linux",
[taskcluster 2026-01-09T12:11:55.738Z]       "go-version": "go1.25.4",
[taskcluster 2026-01-09T12:11:55.738Z]       "release": "https://github.com/taskcluster/taskcluster/releases/tag/v93.1.2",
[taskcluster 2026-01-09T12:11:55.738Z]       "revision": "e954a7555fe32f7de69247759908749d1d670889",
[taskcluster 2026-01-09T12:11:55.738Z]       "source": "https://github.com/taskcluster/taskcluster/commits/e954a7555fe32f7de69247759908749d1d670889",
[taskcluster 2026-01-09T12:11:55.738Z]       "version": "93.1.2"
[taskcluster 2026-01-09T12:11:55.738Z]     },
[taskcluster 2026-01-09T12:11:55.738Z]     "image": "projects/taskcluster-imaging/global/images/gw-fxci-gcp-l1-2404-amd64-headless-googlecompute-2025-11-14",
[taskcluster 2026-01-09T12:11:55.738Z]     "instance-id": "4928742027720216976",
[taskcluster 2026-01-09T12:11:55.738Z]     "instance-type": "projects/887720501152/machineTypes/c2-standard-4",
[taskcluster 2026-01-09T12:11:55.738Z]     "local-ipv4": "10.138.0.74",
[taskcluster 2026-01-09T12:11:55.738Z]     "project-id": "fxci-production-level1-workers",
[taskcluster 2026-01-09T12:11:55.738Z]     "public-hostname": "scriptworker-1-images-qncpvac-sr2ocit03u3daq.c.fxci-production-level1-workers.internal",
[taskcluster 2026-01-09T12:11:55.738Z]     "public-ipv4": "34.169.103.65",
[taskcluster 2026-01-09T12:11:55.738Z]     "region": "us-west1",
[taskcluster 2026-01-09T12:11:55.738Z]     "zone": "us-west1-a"
[taskcluster 2026-01-09T12:11:55.738Z]   }
[taskcluster 2026-01-09T12:11:55.738Z] Task ID: AVLmTTuhSuGKjPmNZmw5Bw
[taskcluster 2026-01-09T12:11:55.738Z] === Task Starting ===
[taskcluster 2026-01-09T12:11:56.404Z] [mounts] No existing writable directory cache 'scriptworker-level-1-checkouts-v3-f59e202b9f4c189775df-bn4Fyl9FQnGhHSh892yfyw' - creating /home/generic-worker/caches/Ai8X7JtVS2uqJe5ywM0d_w
[taskcluster 2026-01-09T12:11:56.404Z] [mounts] Creating directory /home/task_176796064898807/cache0
[taskcluster 2026-01-09T12:11:56.415Z] [mounts] Updating ownership of files inside directory '/home/task_176796064898807/cache0' from root to task_176796064898807
[taskcluster 2026-01-09T12:11:56.416Z] [mounts] Successfully mounted writable directory cache '/home/task_176796064898807/cache0'
[taskcluster 2026-01-09T12:11:56.417Z] [mounts] Downloading task bn4Fyl9FQnGhHSh892yfyw artifact public/image.tar.zst to /home/generic-worker/downloads/IGJ-AiePRC29aUkl6cjPUg
[taskcluster 2026-01-09T12:12:05.888Z] [mounts] Downloaded 298705546 bytes with SHA256 a1728b243829e41add03353c478f19411b8a0672a1f8da0f4e8653de653fe12b from task bn4Fyl9FQnGhHSh892yfyw artifact public/image.tar.zst to /home/generic-worker/downloads/IGJ-AiePRC29aUkl6cjPUg
[taskcluster:warn 2026-01-09T12:12:05.888Z] [mounts] Download /home/generic-worker/downloads/IGJ-AiePRC29aUkl6cjPUg of task bn4Fyl9FQnGhHSh892yfyw artifact public/image.tar.zst has SHA256 a1728b243829e41add03353c478f19411b8a0672a1f8da0f4e8653de653fe12b but task payload does not declare a required value, so content authenticity cannot be verified
[taskcluster 2026-01-09T12:12:05.888Z] [mounts] Creating directory /home/task_176796064898807
[taskcluster 2026-01-09T12:12:05.909Z] [mounts] Copying /home/generic-worker/downloads/IGJ-AiePRC29aUkl6cjPUg to /home/task_176796064898807/dockerimage
[taskcluster 2026-01-09T12:12:06.672Z] [mounts] Granting task_176796064898807 full control of file '/home/task_176796064898807/dockerimage'
[taskcluster 2026-01-09T12:12:07.409Z] [d2g] Loading docker image
[taskcluster 2026-01-09T12:12:19.281Z] [d2g] Loaded docker image "python311:latest"
[taskcluster 2026-01-09T12:12:19.282Z] Executing command 0: docker run -t --name taskcontainer_aOm8bX0LSvmfcPNwHc-bYQ --memory-swap -1 --pids-limit -1 '--add-host=localhost.localdomain:127.0.0.1' -v /home/task_176796064898807/cache0:/builds/worker/checkouts --add-host=taskcluster:host-gateway --env-file 'env.list' e82b2153fc4a2a6e8fb82052e28e58db90f2d392910ee5d2304acb1ae8b627f2 /usr/local/bin/run-task --scriptworker-checkout=/builds/worker/checkouts/vcs/ --task-cwd /builds/worker/checkouts/vcs -- sh -lxce 'uv run tox -e init-py311'
[setup 2026-01-09T12:12:21.444+00:00] run-task started in /
[setup 2026-01-09T12:12:21.444+00:00] Invoked by command: --scriptworker-checkout=/builds/worker/checkouts/vcs/ --task-cwd /builds/worker/checkouts/vcs -- sh -lxce uv run tox -e init-py311
[setup 2026-01-09T12:12:21.444+00:00] Python version: 3.11.9
[cache 2026-01-09T12:12:21.445+00:00] cache /builds/worker/checkouts is empty; writing requirements: gid=1000 uid=1000 version=1
[volume 2026-01-09T12:12:21.445+00:00] changing ownership of volume /builds/worker/.cache to 1000:1000
[volume 2026-01-09T12:12:21.445+00:00] volume /builds/worker/checkouts is a cache
[setup 2026-01-09T12:12:21.446+00:00] running as worker:worker
[vcs 2026-01-09T12:12:21.446+00:00] executing ['git', 'config', '--global', '--add', 'safe.directory', '/builds/worker/checkouts/vcs']
[vcs 2026-01-09T12:12:21.448+00:00] executing ['git', 'clone', 'https://github.com/mozilla-releng/scriptworker-scripts', '/builds/worker/checkouts/vcs']
[vcs 2026-01-09T12:12:21.450+00:00] Cloning into '/builds/worker/checkouts/vcs'...
[vcs 2026-01-09T12:12:26.550+00:00] executing ['git', 'fetch', '--no-tags', 'https://github.com/Eijebong/scriptworker-scripts', 'shipit-mark-as-merged']
[vcs 2026-01-09T12:12:26.898+00:00] From https://github.com/Eijebong/scriptworker-scripts
[vcs 2026-01-09T12:12:26.898+00:00]  * branch              shipit-mark-as-merged -> FETCH_HEAD
[vcs 2026-01-09T12:12:26.900+00:00] executing ['git', 'checkout', '-f', '-B', 'shipit-mark-as-merged', '3de6d30e784f16f01aa43edfa666ec6d94262957']
[vcs 2026-01-09T12:12:27.928+00:00] Switched to a new branch 'shipit-mark-as-merged'
[vcs 2026-01-09T12:12:27.928+00:00] cleaning git checkout...
[vcs 2026-01-09T12:12:27.929+00:00] executing ['git', 'clean', '-nxdff']
[vcs 2026-01-09T12:12:27.936+00:00] removing []
[vcs 2026-01-09T12:12:27.936+00:00] successfully cleaned git checkout!
[vcs 2026-01-09T12:12:27.938+00:00] TinderboxPrint:<a href='https://github.com/Eijebong/scriptworker-scripts/commit/3de6d30e784f16f01aa43edfa666ec6d94262957' title='Built from scriptworker-scripts commit 3de6d30e784f16f01aa43edfa666ec6d94262957'>3de6d30e784f16f01aa43edfa666ec6d94262957</a>
[task 2026-01-09T12:12:27.938+00:00] executing ['sh', '-lxce', 'uv run tox -e init-py311']
[task 2026-01-09T12:12:27.939+00:00] + id -u
[task 2026-01-09T12:12:27.940+00:00] + [ 1000 -eq 0 ]
[task 2026-01-09T12:12:27.940+00:00] + PATH=/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
[task 2026-01-09T12:12:27.940+00:00] + export PATH
[task 2026-01-09T12:12:27.940+00:00] + [ $  ]
[task 2026-01-09T12:12:27.940+00:00] + [  ]
[task 2026-01-09T12:12:27.941+00:00] + id -u
[task 2026-01-09T12:12:27.942+00:00] + [ 1000 -eq 0 ]
[task 2026-01-09T12:12:27.942+00:00] + PS1=$ 
[task 2026-01-09T12:12:27.942+00:00] + [ -d /etc/profile.d ]
[task 2026-01-09T12:12:27.942+00:00] + [ -r /etc/profile.d/*.sh ]
[task 2026-01-09T12:12:27.942+00:00] + unset i
[task 2026-01-09T12:12:27.942+00:00] + uv run tox -e init-py311
[task 2026-01-09T12:12:28.002+00:00] Using CPython 3.11.9 interpreter at: /usr/local/bin/python3
[task 2026-01-09T12:12:28.002+00:00] Creating virtual environment at: .venv
[task 2026-01-09T12:12:28.936+00:00]    Building configloader @ file:///builds/worker/checkouts/vcs/configloader
[task 2026-01-09T12:12:28.975+00:00] Downloading pygments (1.2MiB)
[task 2026-01-09T12:12:28.976+00:00] Downloading uv (21.1MiB)
[task 2026-01-09T12:12:28.976+00:00] Downloading ruff (13.5MiB)
[task 2026-01-09T12:12:28.977+00:00] Downloading mypy (12.8MiB)
[task 2026-01-09T12:12:28.977+00:00] Downloading virtualenv (5.7MiB)
[task 2026-01-09T12:12:29.692+00:00]  Downloading ruff
[task 2026-01-09T12:12:29.766+00:00]  Downloading uv
[task 2026-01-09T12:12:29.767+00:00]  Downloading virtualenv
[task 2026-01-09T12:12:29.828+00:00]  Downloading pygments
[task 2026-01-09T12:12:29.995+00:00]  Downloading mypy
[task 2026-01-09T12:12:30.123+00:00]       Built configloader @ file:///builds/worker/checkouts/vcs/configloader
[task 2026-01-09T12:12:30.230+00:00] Installed 30 packages in 105ms
[task 2026-01-09T12:12:30.930+00:00] init-py311: venv> .venv/bin/uv venv -p /builds/worker/checkouts/vcs/.venv/bin/python --allow-existing --python-preference system /builds/worker/checkouts/vcs/.tox/init-py311
[task 2026-01-09T12:12:31.019+00:00] init-py311: uv-sync> uv sync --locked --python-preference system -p /builds/worker/checkouts/vcs/.venv/bin/python
[task 2026-01-09T12:12:31.680+00:00] init-py311: commands[0]> pytest -n auto tests
[task 2026-01-09T12:12:33.020+00:00] ============================= test session starts ==============================
[task 2026-01-09T12:12:33.020+00:00] platform linux -- Python 3.11.9, pytest-8.4.2, pluggy-1.6.0
[task 2026-01-09T12:12:33.020+00:00] cachedir: .tox/init-py311/.pytest_cache
[task 2026-01-09T12:12:33.020+00:00] rootdir: /builds/worker/checkouts/vcs
[task 2026-01-09T12:12:33.020+00:00] configfile: setup.cfg
[task 2026-01-09T12:12:33.020+00:00] plugins: xdist-3.8.0
[task 2026-01-09T12:12:33.020+00:00] created: 4/4 workers
[task 2026-01-09T12:12:33.020+00:00] 4 workers [264 items]
[task 2026-01-09T12:12:33.022+00:00] 
[task 2026-01-09T12:12:35.190+00:00] ........................................................................ [ 27%]
[task 2026-01-09T12:12:37.556+00:00] ........................................................................ [ 54%]
[task 2026-01-09T12:12:40.834+00:00] ........................................................................ [ 81%]
[task 2026-01-09T12:12:45.480+00:00] ................................................                         [100%]
[task 2026-01-09T12:12:45.480+00:00] ============================= 264 passed in 13.17s =============================
[task 2026-01-09T12:12:45.508+00:00]   init-py311: OK (14.58=setup[0.75]+cmd[13.83] seconds)
[task 2026-01-09T12:12:45.508+00:00]   congratulations :) (14.63 seconds)
[taskcluster 2026-01-09T12:12:45.843Z]                        Exit Code: 0
[taskcluster 2026-01-09T12:12:45.843Z]                        User Time: 11.048ms
[taskcluster 2026-01-09T12:12:45.843Z]                      Kernel Time: 13.995ms
[taskcluster 2026-01-09T12:12:45.843Z]                        Wall Time: 26.561569053s
[taskcluster 2026-01-09T12:12:45.843Z]  Average Available System Memory: 14.74 GiB
[taskcluster 2026-01-09T12:12:45.843Z]       Average System Memory Used: 899.73 MiB
[taskcluster 2026-01-09T12:12:45.843Z]          Peak System Memory Used: 1.02 GiB
[taskcluster 2026-01-09T12:12:45.843Z]              Total System Memory: 15.62 GiB
[taskcluster 2026-01-09T12:12:45.843Z]                           Result: SUCCEEDED
[taskcluster 2026-01-09T12:12:45.844Z] === Task Finished ===
[taskcluster 2026-01-09T12:12:45.844Z] Task Duration: 26.562269524s
[taskcluster 2026-01-09T12:12:46.016Z] [mounts] Preserving cache: Moving "/home/task_176796064898807/cache0" to "/home/generic-worker/caches/Ai8X7JtVS2uqJe5ywM0d_w"