[taskcluster 2026-04-23T22:08:40.794Z] Worker Type (scriptworker-1/images) settings:
[taskcluster 2026-04-23T22:08:40.794Z]   {
[taskcluster 2026-04-23T22:08:40.794Z]     "generic-worker": {
[taskcluster 2026-04-23T22:08:40.794Z]       "config": {
[taskcluster 2026-04-23T22:08:40.794Z]         "headlessTasks": true
[taskcluster 2026-04-23T22:08:40.794Z]       },
[taskcluster 2026-04-23T22:08:40.794Z]       "engine": "multiuser",
[taskcluster 2026-04-23T22:08:40.794Z]       "go-arch": "amd64",
[taskcluster 2026-04-23T22:08:40.794Z]       "go-os": "linux",
[taskcluster 2026-04-23T22:08:40.794Z]       "go-version": "go1.26.0",
[taskcluster 2026-04-23T22:08:40.794Z]       "release": "https://github.com/taskcluster/taskcluster/releases/tag/v97.0.1",
[taskcluster 2026-04-23T22:08:40.794Z]       "revision": "6519c127aad40a47b1412136d9a5797a76eca200",
[taskcluster 2026-04-23T22:08:40.794Z]       "source": "https://github.com/taskcluster/taskcluster/commits/6519c127aad40a47b1412136d9a5797a76eca200",
[taskcluster 2026-04-23T22:08:40.794Z]       "version": "97.0.1"
[taskcluster 2026-04-23T22:08:40.794Z]     },
[taskcluster 2026-04-23T22:08:40.794Z]     "image": "projects/taskcluster-imaging/global/images/gw-fxci-gcp-l1-2404-amd64-headless-googlecompute-2026-03-05",
[taskcluster 2026-04-23T22:08:40.794Z]     "instance-id": "4399245325450553057",
[taskcluster 2026-04-23T22:08:40.794Z]     "instance-type": "projects/887720501152/machineTypes/c2-standard-4",
[taskcluster 2026-04-23T22:08:40.794Z]     "local-ipv4": "10.128.1.99",
[taskcluster 2026-04-23T22:08:40.794Z]     "project-id": "fxci-production-level1-workers",
[taskcluster 2026-04-23T22:08:40.794Z]     "public-hostname": "scriptworker-1-images-kaa6gsrtrgmgouj7gy8q-w.c.fxci-production-level1-workers.internal",
[taskcluster 2026-04-23T22:08:40.794Z]     "public-ipv4": "104.154.226.190",
[taskcluster 2026-04-23T22:08:40.794Z]     "region": "us-central1",
[taskcluster 2026-04-23T22:08:40.794Z]     "zone": "us-central1-c"
[taskcluster 2026-04-23T22:08:40.794Z]   }
[taskcluster 2026-04-23T22:08:40.794Z] Task ID: CUpHK_8LRjuhVqY1KfsPTA
[taskcluster 2026-04-23T22:08:40.794Z] === Task Starting ===
[taskcluster 2026-04-23T22:08:42.046Z] [mounts] No existing writable directory cache 'scriptworker-level-1-uv-v3-aeb56a8acbdc7df6c7d5-O6Uv8DzKScCsTVB9yK9uMg' - creating /home/generic-worker/caches/L5_4fXh1RX6em9KUgPgc-w
[taskcluster 2026-04-23T22:08:42.046Z] [mounts] Creating directory /home/task_177698211990334/cache0
[taskcluster 2026-04-23T22:08:42.057Z] [mounts] Successfully mounted writable directory cache '/home/task_177698211990334/cache0'
[taskcluster 2026-04-23T22:08:42.057Z] [mounts] No existing writable directory cache 'scriptworker-level-1-checkouts-v3-aeb56a8acbdc7df6c7d5-O6Uv8DzKScCsTVB9yK9uMg' - creating /home/generic-worker/caches/UYACUeeSRimjYQCFNKmY6A
[taskcluster 2026-04-23T22:08:42.057Z] [mounts] Creating directory /home/task_177698211990334/cache1
[taskcluster 2026-04-23T22:08:42.067Z] [mounts] Successfully mounted writable directory cache '/home/task_177698211990334/cache1'
[taskcluster 2026-04-23T22:08:42.067Z] [mounts] Downloading task O6Uv8DzKScCsTVB9yK9uMg artifact public/image.tar.zst to /home/generic-worker/downloads/Edeq-SQoRlaST7HFYar-4Q
[taskcluster 2026-04-23T22:08:44.830Z] [mounts] Downloaded 98351948 bytes with SHA256 05b1e9b4f8ca05dbb661db130fb5d6887e322b34766c3b094b5afd3aeac5b12a from task O6Uv8DzKScCsTVB9yK9uMg artifact public/image.tar.zst to /home/generic-worker/downloads/Edeq-SQoRlaST7HFYar-4Q
[taskcluster:warn 2026-04-23T22:08:44.830Z] [mounts] Download /home/generic-worker/downloads/Edeq-SQoRlaST7HFYar-4Q of task O6Uv8DzKScCsTVB9yK9uMg artifact public/image.tar.zst has SHA256 05b1e9b4f8ca05dbb661db130fb5d6887e322b34766c3b094b5afd3aeac5b12a but task payload does not declare a required value, so content authenticity cannot be verified
[taskcluster 2026-04-23T22:08:44.830Z] [mounts] File mount "dockerimage" handled by registered handler (cache: /home/generic-worker/downloads/Edeq-SQoRlaST7HFYar-4Q, SHA256: 05b1e9b4f8ca05dbb661db130fb5d6887e322b34766c3b094b5afd3aeac5b12a)
[taskcluster 2026-04-23T22:08:44.830Z] [d2g] Loading docker image
[taskcluster 2026-04-23T22:08:49.382Z] [d2g] Loaded docker image "python3.13:latest"
[taskcluster 2026-04-23T22:08:49.382Z] Executing command 0: docker run -t --name taskcontainer_PIShHszOTbCVV14d1LUFOw --memory-swap -1 --pids-limit -1 '--add-host=localhost.localdomain:127.0.0.1' -v '/home/task_177698211990334/cache0:/builds/worker/.task-cache/uv' -v /home/task_177698211990334/cache1:/builds/worker/checkouts --add-host=taskcluster:host-gateway --env-file 'env.list' bfd0395299d8287ec0dd7ec455578e7c4d1623e8ef6b345295363cde57ef4a8b /usr/local/bin/run-task --scriptworker-checkout=/builds/worker/checkouts/vcs/ --task-cwd /builds/worker/checkouts/vcs -- sh -lxce 'uv run tox -e check'
[setup 2026-04-23T22:08:51.270+00:00] run-task started in /
[setup 2026-04-23T22:08:51.270+00:00] Invoked by command: --scriptworker-checkout=/builds/worker/checkouts/vcs/ --task-cwd /builds/worker/checkouts/vcs -- sh -lxce uv run tox -e check
[setup 2026-04-23T22:08:51.270+00:00] Python version: 3.13.10
[setup 2026-04-23T22:08:51.270+00:00] Subprocess python version: 
cpython-3.13.10-linux-x86_64-gnu    /usr/local/bin/python3.13
cpython-3.13.10-linux-x86_64-gnu    /usr/local/bin/python3 -> python3.13
cpython-3.13.10-linux-x86_64-gnu    /usr/local/bin/python -> python3
[cache 2026-04-23T22:08:51.450+00:00] cache /builds/worker/.task-cache/uv is empty; writing requirements: gid=1000 uid=1000 version=1
[cache 2026-04-23T22:08:51.450+00:00] cache /builds/worker/checkouts is empty; writing requirements: gid=1000 uid=1000 version=1
[volume 2026-04-23T22:08:51.451+00:00] volume /builds/worker/.task-cache/uv is a cache
[volume 2026-04-23T22:08:51.451+00:00] volume /builds/worker/checkouts is a cache
[setup 2026-04-23T22:08:51.451+00:00] running as worker:worker
[vcs 2026-04-23T22:08:51.451+00:00] executing ['git', 'config', '--global', '--add', 'safe.directory', '/builds/worker/checkouts/vcs']
[vcs 2026-04-23T22:08:51.458+00:00] executing ['git', 'clone', 'https://github.com/mozilla-releng/scriptworker', '/builds/worker/checkouts/vcs']
[vcs 2026-04-23T22:08:51.460+00:00] Cloning into '/builds/worker/checkouts/vcs'...
[vcs 2026-04-23T22:08:52.439+00:00] executing ['git', 'fetch', '--tags', '--force', 'https://github.com/mozilla-releng/scriptworker', 'hneiva/json-error']
[vcs 2026-04-23T22:08:52.655+00:00] From https://github.com/mozilla-releng/scriptworker
[vcs 2026-04-23T22:08:52.655+00:00]  * branch            hneiva/json-error -> FETCH_HEAD
[vcs 2026-04-23T22:08:52.665+00:00] executing ['git', 'checkout', '-f', '-B', 'hneiva/json-error', 'c5ca4ceb2f5a9dea3e789cae0e2cfc23228ae917']
[vcs 2026-04-23T22:08:52.738+00:00] Switched to a new branch 'hneiva/json-error'
[vcs 2026-04-23T22:08:52.739+00:00] cleaning git checkout...
[vcs 2026-04-23T22:08:52.739+00:00] executing ['git', 'clean', '-nxdff']
[vcs 2026-04-23T22:08:52.741+00:00] removing []
[vcs 2026-04-23T22:08:52.741+00:00] successfully cleaned git checkout!
[vcs 2026-04-23T22:08:52.742+00:00] TinderboxPrint:<a href='https://github.com/mozilla-releng/scriptworker/commit/c5ca4ceb2f5a9dea3e789cae0e2cfc23228ae917' title='Built from scriptworker commit c5ca4ceb2f5a9dea3e789cae0e2cfc23228ae917'>c5ca4ceb2f5a9dea3e789cae0e2cfc23228ae917</a>
[setup 2026-04-23T22:08:52.742+00:00] UV_CACHE_DIR is /builds/worker/.task-cache/uv
[task 2026-04-23T22:08:52.742+00:00] executing ['sh', '-lxce', 'uv run tox -e check']
[task 2026-04-23T22:08:52.744+00:00] + id -u
[task 2026-04-23T22:08:52.745+00:00] + [ 1000 -eq 0 ]
[task 2026-04-23T22:08:52.745+00:00] + PATH=/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
[task 2026-04-23T22:08:52.745+00:00] + export PATH
[task 2026-04-23T22:08:52.745+00:00] + [ $  ]
[task 2026-04-23T22:08:52.745+00:00] + [  ]
[task 2026-04-23T22:08:52.745+00:00] + id -u
[task 2026-04-23T22:08:52.746+00:00] + [ 1000 -eq 0 ]
[task 2026-04-23T22:08:52.746+00:00] + PS1=$ 
[task 2026-04-23T22:08:52.746+00:00] + [ -d /etc/profile.d ]
[task 2026-04-23T22:08:52.747+00:00] + [ -r /etc/profile.d/*.sh ]
[task 2026-04-23T22:08:52.747+00:00] + unset i
[task 2026-04-23T22:08:52.747+00:00] + uv run tox -e check
[task 2026-04-23T22:08:52.831+00:00] Using CPython 3.13.10 interpreter at: /usr/local/bin/python3
[task 2026-04-23T22:08:52.831+00:00] Creating virtual environment at: .venv
[task 2026-04-23T22:08:53.472+00:00]    Building scriptworker @ file:///builds/worker/checkouts/vcs
[task 2026-04-23T22:08:53.536+00:00] Downloading pygments (1.2MiB)
[task 2026-04-23T22:08:53.537+00:00] Downloading uv (23.8MiB)
[task 2026-04-23T22:08:53.538+00:00] Downloading virtualenv (5.6MiB)
[task 2026-04-23T22:08:53.539+00:00] Downloading aiohttp (1.7MiB)
[task 2026-04-23T22:08:53.541+00:00] Downloading cryptography (4.3MiB)
[task 2026-04-23T22:08:53.542+00:00] Downloading black (1.7MiB)
[task 2026-04-23T22:08:53.543+00:00] Downloading mypy (14.0MiB)
[task 2026-04-23T22:08:54.412+00:00]  Downloaded virtualenv
[task 2026-04-23T22:08:54.598+00:00]  Downloaded aiohttp
[task 2026-04-23T22:08:54.662+00:00]  Downloaded uv
[task 2026-04-23T22:08:54.674+00:00]  Downloaded black
[task 2026-04-23T22:08:54.677+00:00]  Downloaded cryptography
[task 2026-04-23T22:08:54.745+00:00]  Downloaded pygments
[task 2026-04-23T22:08:54.950+00:00]  Downloaded mypy
[task 2026-04-23T22:08:54.995+00:00]       Built scriptworker @ file:///builds/worker/checkouts/vcs
[task 2026-04-23T22:08:54.999+00:00] warning: Failed to hardlink files; falling back to full copy. This may lead to degraded performance.
[task 2026-04-23T22:08:54.999+00:00]          If the cache and target directories are on different filesystems, hardlinking may not be supported.
[task 2026-04-23T22:08:54.999+00:00]          If this is intentional, set `export UV_LINK_MODE=copy` or use `--link-mode=copy` to suppress this warning.
[task 2026-04-23T22:08:55.189+00:00] Installed 108 packages in 192ms
[task 2026-04-23T22:08:55.864+00:00] check: venv> .venv/bin/uv venv -p /builds/worker/checkouts/vcs/.venv/bin/python --allow-existing '--prompt=vcs[check]' --python-preference system /builds/worker/checkouts/vcs/.tox/check
[task 2026-04-23T22:08:55.964+00:00] check: commands[0]> black --check /builds/worker/checkouts/vcs
[task 2026-04-23T22:08:57.868+00:00] All done! ✨ 🍰 ✨
[task 2026-04-23T22:08:57.869+00:00] 41 files would be left unchanged.
[task 2026-04-23T22:08:57.901+00:00] check: commands[1]> isort --check --df /builds/worker/checkouts/vcs
[task 2026-04-23T22:08:58.171+00:00] Skipped 3 files
[task 2026-04-23T22:08:58.189+00:00] check: commands[2]> flake8 /builds/worker/checkouts/vcs
[task 2026-04-23T22:09:00.002+00:00]   check: OK (4.14=setup[0.11]+cmd[1.94,0.29,1.81] seconds)
[task 2026-04-23T22:09:00.002+00:00]   congratulations :) (4.23 seconds)
[taskcluster 2026-04-23T22:09:01.337Z]                        Exit Code: 0
[taskcluster 2026-04-23T22:09:01.337Z]                        User Time: 16.612ms
[taskcluster 2026-04-23T22:09:01.337Z]                      Kernel Time: 13.497ms
[taskcluster 2026-04-23T22:09:01.337Z]                        Wall Time: 11.954651132s
[taskcluster 2026-04-23T22:09:01.337Z]  Average Available System Memory: 14.12 GiB
[taskcluster 2026-04-23T22:09:01.337Z]       Average System Memory Used: 1.49 GiB
[taskcluster 2026-04-23T22:09:01.337Z]          Peak System Memory Used: 1.63 GiB
[taskcluster 2026-04-23T22:09:01.337Z]              Total System Memory: 15.61 GiB
[taskcluster 2026-04-23T22:09:01.337Z]                           Result: SUCCEEDED
[taskcluster 2026-04-23T22:09:01.337Z] === Task Finished ===
[taskcluster 2026-04-23T22:09:01.337Z] Task Duration: 11.955283621s
[taskcluster 2026-04-23T22:09:01.377Z] [mounts] Preserving cache: Moving "/home/task_177698211990334/cache0" to "/home/generic-worker/caches/L5_4fXh1RX6em9KUgPgc-w"
[taskcluster 2026-04-23T22:09:01.377Z] [mounts] Preserving cache: Moving "/home/task_177698211990334/cache1" to "/home/generic-worker/caches/UYACUeeSRimjYQCFNKmY6A"