[taskcluster 2026-07-24T20:24:47.391Z] Worker Type (scriptworker-1/images) settings:
[taskcluster 2026-07-24T20:24:47.391Z]   {
[taskcluster 2026-07-24T20:24:47.391Z]     "generic-worker": {
[taskcluster 2026-07-24T20:24:47.391Z]       "config": {
[taskcluster 2026-07-24T20:24:47.391Z]         "headlessTasks": true
[taskcluster 2026-07-24T20:24:47.391Z]       },
[taskcluster 2026-07-24T20:24:47.391Z]       "engine": "multiuser",
[taskcluster 2026-07-24T20:24:47.391Z]       "go-arch": "amd64",
[taskcluster 2026-07-24T20:24:47.391Z]       "go-os": "linux",
[taskcluster 2026-07-24T20:24:47.391Z]       "go-version": "go1.26.2",
[taskcluster 2026-07-24T20:24:47.391Z]       "release": "https://github.com/taskcluster/taskcluster/releases/tag/v99.2.1",
[taskcluster 2026-07-24T20:24:47.391Z]       "revision": "ddb9ce7efdb98ae2d0917b69778e9f3cd125e07f",
[taskcluster 2026-07-24T20:24:47.391Z]       "source": "https://github.com/taskcluster/taskcluster/commits/ddb9ce7efdb98ae2d0917b69778e9f3cd125e07f",
[taskcluster 2026-07-24T20:24:47.391Z]       "version": "99.2.1"
[taskcluster 2026-07-24T20:24:47.391Z]     },
[taskcluster 2026-07-24T20:24:47.391Z]     "image": "projects/taskcluster-imaging/global/images/gw-fxci-gcp-l1-2404-amd64-headless-googlecompute-2026-05-04",
[taskcluster 2026-07-24T20:24:47.391Z]     "instance-id": "6547631152764450638",
[taskcluster 2026-07-24T20:24:47.391Z]     "instance-type": "projects/887720501152/machineTypes/c2-standard-4",
[taskcluster 2026-07-24T20:24:47.391Z]     "local-ipv4": "10.138.0.41",
[taskcluster 2026-07-24T20:24:47.391Z]     "project-id": "fxci-production-level1-workers",
[taskcluster 2026-07-24T20:24:47.391Z]     "public-hostname": "scriptworker-1-images-w0t7ssj2t-i-nj7hcg6fsa.c.fxci-production-level1-workers.internal",
[taskcluster 2026-07-24T20:24:47.391Z]     "public-ipv4": "34.83.197.57",
[taskcluster 2026-07-24T20:24:47.391Z]     "region": "us-west1",
[taskcluster 2026-07-24T20:24:47.391Z]     "zone": "us-west1-c"
[taskcluster 2026-07-24T20:24:47.391Z]   }
[taskcluster 2026-07-24T20:24:47.391Z] Task ID: M0qkenKtRR2cuPdR57qNKg
[taskcluster 2026-07-24T20:24:47.391Z] === Task Starting ===
[taskcluster 2026-07-24T20:24:49.616Z] [mounts] No existing writable directory cache 'scriptworker-level-1-checkouts-v3-c8442a4dac36bbb86016-LcHkz4eMSNSMxSeUpYsnfg' - creating /home/generic-worker/caches/IDGW6f6HSoCD68ljNbSuqA
[taskcluster 2026-07-24T20:24:49.616Z] [mounts] Creating directory /home/task_178492468644649/cache0
[taskcluster 2026-07-24T20:24:49.627Z] [mounts] Successfully mounted writable directory cache '/home/task_178492468644649/cache0'
[taskcluster 2026-07-24T20:24:49.627Z] [mounts] Downloading task LcHkz4eMSNSMxSeUpYsnfg artifact public/image.tar.zst to /home/generic-worker/downloads/NM6mHKy3StaE7YuKfF_cQA
[taskcluster 2026-07-24T20:24:51.523Z] [mounts] Downloaded 86171684 bytes with SHA256 8c2d15ae8f2e8ca11d310a2df5de488109503433cac92b627045668543c8c8c9 from task LcHkz4eMSNSMxSeUpYsnfg artifact public/image.tar.zst to /home/generic-worker/downloads/NM6mHKy3StaE7YuKfF_cQA
[taskcluster:warn 2026-07-24T20:24:51.523Z] [mounts] Download /home/generic-worker/downloads/NM6mHKy3StaE7YuKfF_cQA of task LcHkz4eMSNSMxSeUpYsnfg artifact public/image.tar.zst has SHA256 8c2d15ae8f2e8ca11d310a2df5de488109503433cac92b627045668543c8c8c9 but task payload does not declare a required value, so content authenticity cannot be verified
[taskcluster 2026-07-24T20:24:51.523Z] [mounts] File mount "dockerimage" handled by registered handler (cache: /home/generic-worker/downloads/NM6mHKy3StaE7YuKfF_cQA, SHA256: 8c2d15ae8f2e8ca11d310a2df5de488109503433cac92b627045668543c8c8c9)
[taskcluster 2026-07-24T20:24:51.523Z] [d2g] Loading docker image
[taskcluster 2026-07-24T20:24:55.099Z] [d2g] Loaded docker image "python311:latest"
[taskcluster 2026-07-24T20:24:55.100Z] Executing command 0: docker run -t --name taskcontainer_JtqIPd7wRWu5xgeRx-10kw --memory-swap -1 --pids-limit -1 --pull=never --log-driver=none '--add-host=localhost.localdomain:127.0.0.1' -v /home/task_178492468644649/cache0:/builds/worker/checkouts --add-host=taskcluster:host-gateway --env-file 'env.list' 87341f4a1d7fcaa739e166f160e550a096437cda48040f11bb066c6cb0057b07 /usr/local/bin/run-task --scriptworker-checkout=/builds/worker/checkouts/vcs/ --task-cwd /builds/worker/checkouts/vcs -- sh -lxce 'uv run tox -e landoscript-py311'
[setup 2026-07-24T20:24:57.167+00:00] run-task started in /
[setup 2026-07-24T20:24:57.167+00:00] Invoked by command: --scriptworker-checkout=/builds/worker/checkouts/vcs/ --task-cwd /builds/worker/checkouts/vcs -- sh -lxce uv run tox -e landoscript-py311
[setup 2026-07-24T20:24:57.167+00:00] Python version: 3.11.15
[setup 2026-07-24T20:24:57.167+00:00] Subprocess python version: 
cpython-3.11.15-linux-x86_64-gnu    /usr/local/bin/python3.11
cpython-3.11.15-linux-x86_64-gnu    /usr/local/bin/python3 -> python3.11
cpython-3.11.15-linux-x86_64-gnu    /usr/local/bin/python -> python3
[cache 2026-07-24T20:24:57.318+00:00] cache /builds/worker/checkouts is empty; writing requirements: gid=1000 uid=1000 version=1
[volume 2026-07-24T20:24:57.318+00:00] changing ownership of volume /builds/worker/.cache to 1000:1000
[volume 2026-07-24T20:24:57.318+00:00] volume /builds/worker/checkouts is a cache
[setup 2026-07-24T20:24:57.318+00:00] running as worker:worker
[vcs 2026-07-24T20:24:57.319+00:00] executing ['git', 'config', '--global', '--add', 'safe.directory', '/builds/worker/checkouts/vcs']
[vcs 2026-07-24T20:24:57.321+00:00] executing ['git', 'clone', 'https://github.com/mozilla-releng/scriptworker-scripts', '/builds/worker/checkouts/vcs']
[vcs 2026-07-24T20:24:57.322+00:00] Cloning into '/builds/worker/checkouts/vcs'...
[vcs 2026-07-24T20:25:03.002+00:00] executing ['git', 'fetch', 'https://github.com/bhearsum/scriptworker-scripts', 'bug-2055645-support-direct-git']
[vcs 2026-07-24T20:25:03.371+00:00] From https://github.com/bhearsum/scriptworker-scripts
[vcs 2026-07-24T20:25:03.371+00:00]  * branch              bug-2055645-support-direct-git -> FETCH_HEAD
[vcs 2026-07-24T20:25:03.374+00:00] executing ['git', 'checkout', '-f', '-B', 'bug-2055645-support-direct-git', '7ed7e538f0335edc7123a2a367fcb84c13308e4e']
[vcs 2026-07-24T20:25:04.422+00:00] Switched to a new branch 'bug-2055645-support-direct-git'
[vcs 2026-07-24T20:25:04.423+00:00] cleaning git checkout...
[vcs 2026-07-24T20:25:04.423+00:00] executing ['git', 'clean', '-nxdff']
[vcs 2026-07-24T20:25:04.430+00:00] removing []
[vcs 2026-07-24T20:25:04.430+00:00] successfully cleaned git checkout!
[vcs 2026-07-24T20:25:04.432+00:00] TinderboxPrint:<a href='https://github.com/bhearsum/scriptworker-scripts/commit/7ed7e538f0335edc7123a2a367fcb84c13308e4e' title='Built from scriptworker-scripts commit 7ed7e538f0335edc7123a2a367fcb84c13308e4e'>7ed7e538f0335edc7123a2a367fcb84c13308e4e</a>
PERFHERDER_DATA: {"framework": {"name": "vcs"}, "suites": [{"extraOptions": ["projects/887720501152/machineTypes/c2-standard-4"], "lowerIsBetter": true, "name": "clone", "shouldAlert": false, "subtests": [], "value": 5.68054723739624}, {"extraOptions": ["projects/887720501152/machineTypes/c2-standard-4"], "lowerIsBetter": true, "name": "pull", "shouldAlert": false, "subtests": [], "value": 0.3719291687011719}, {"extraOptions": ["projects/887720501152/machineTypes/c2-standard-4"], "lowerIsBetter": true, "name": "update", "shouldAlert": false, "subtests": [], "value": 1.0491130352020264}, {"extraOptions": ["projects/887720501152/machineTypes/c2-standard-4"], "lowerIsBetter": true, "name": "purge", "shouldAlert": false, "subtests": [], "value": 0.007489442825317383}, {"extraOptions": ["projects/887720501152/machineTypes/c2-standard-4"], "lowerIsBetter": true, "name": "overall", "shouldAlert": false, "subtests": [], "value": 7.11167573928833}, {"extraOptions": ["projects/887720501152/machineTypes/c2-standard-4"], "lowerIsBetter": true, "name": "overall_clone", "shouldAlert": false, "subtests": [], "value": 7.11167573928833}]}
[task 2026-07-24T20:25:04.432+00:00] executing ['sh', '-lxce', 'uv run tox -e landoscript-py311']
[task 2026-07-24T20:25:04.433+00:00] + id -u
[task 2026-07-24T20:25:04.434+00:00] + [ 1000 -eq 0 ]
[task 2026-07-24T20:25:04.434+00:00] + PATH=/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
[task 2026-07-24T20:25:04.434+00:00] + export PATH
[task 2026-07-24T20:25:04.434+00:00] + [ $  ]
[task 2026-07-24T20:25:04.434+00:00] + [  ]
[task 2026-07-24T20:25:04.434+00:00] + id -u
[task 2026-07-24T20:25:04.435+00:00] + [ 1000 -eq 0 ]
[task 2026-07-24T20:25:04.435+00:00] + PS1=$ 
[task 2026-07-24T20:25:04.435+00:00] + [ -d /etc/profile.d ]
[task 2026-07-24T20:25:04.436+00:00] + run-parts --list --regex ^[a-zA-Z0-9_][a-zA-Z0-9._-]*\.sh$ /etc/profile.d
[task 2026-07-24T20:25:04.436+00:00] + unset i
[task 2026-07-24T20:25:04.436+00:00] + uv run tox -e landoscript-py311
[task 2026-07-24T20:25:04.492+00:00] Using CPython 3.11.15 interpreter at: /usr/local/bin/python3
[task 2026-07-24T20:25:04.492+00:00] Creating virtual environment at: .venv
[task 2026-07-24T20:25:05.392+00:00]    Building configloader @ file:///builds/worker/checkouts/vcs/configloader
[task 2026-07-24T20:25:05.438+00:00] Downloading virtualenv (5.3MiB)
[task 2026-07-24T20:25:05.439+00:00] Downloading ast-serialize (1.2MiB)
[task 2026-07-24T20:25:05.439+00:00] Downloading pygments (1.2MiB)
[task 2026-07-24T20:25:05.440+00:00] Downloading uv (25.7MiB)
[task 2026-07-24T20:25:05.441+00:00] Downloading mypy (14.4MiB)
[task 2026-07-24T20:25:05.441+00:00] Downloading ruff (11.0MiB)
[task 2026-07-24T20:25:05.644+00:00]  Downloading ast-serialize
[task 2026-07-24T20:25:06.076+00:00]  Downloading virtualenv
[task 2026-07-24T20:25:06.078+00:00]  Downloading ruff
[task 2026-07-24T20:25:06.257+00:00]  Downloading uv
[task 2026-07-24T20:25:06.308+00:00]  Downloading pygments
[task 2026-07-24T20:25:06.482+00:00]  Downloading mypy
[task 2026-07-24T20:25:06.618+00:00]       Built configloader @ file:///builds/worker/checkouts/vcs/configloader
[task 2026-07-24T20:25:06.725+00:00] Installed 33 packages in 105ms
[task 2026-07-24T20:25:07.403+00:00] landoscript-py311: venv> .venv/bin/uv venv -p cpython3.11 --allow-existing '--prompt=vcs[landoscript-py311]' --python-preference system /builds/worker/checkouts/vcs/.tox/landoscript-py311
[task 2026-07-24T20:25:07.622+00:00] landoscript-py311: uv-sync> .venv/bin/uv sync --locked --python-preference system -p cpython3.11
[task 2026-07-24T20:25:09.147+00:00] landoscript-py311: commands[0] /builds/worker/checkouts/vcs/landoscript> tox -e py311
[task 2026-07-24T20:25:09.825+00:00] py311: venv> /builds/worker/checkouts/vcs/.tox/landoscript-py311/bin/uv venv -p cpython3.11 --allow-existing '--prompt=landoscript[py311]' --python-preference system /builds/worker/checkouts/vcs/.tox/py311
[task 2026-07-24T20:25:09.943+00:00] py311: uv-sync> /builds/worker/checkouts/vcs/.tox/landoscript-py311/bin/uv sync --locked --python-preference system -p cpython3.11
[task 2026-07-24T20:25:11.268+00:00] py311: commands[0]> py.test --cov-config=tox.ini --cov-append --cov=/builds/worker/checkouts/vcs/landoscript/src/landoscript --cov-report term-missing tests
[task 2026-07-24T20:25:16.561+00:00] ============================= test session starts ==============================
[task 2026-07-24T20:25:16.561+00:00] platform linux -- Python 3.11.15, pytest-9.1.1, pluggy-1.6.0
[task 2026-07-24T20:25:16.561+00:00] cachedir: /builds/worker/checkouts/vcs/.tox/py311/.pytest_cache
[task 2026-07-24T20:25:16.561+00:00] rootdir: /builds/worker/checkouts/vcs/landoscript
[task 2026-07-24T20:25:16.561+00:00] configfile: tox.ini
[task 2026-07-24T20:25:16.561+00:00] plugins: aioresponses-0.3.0, anyio-4.14.2, scriptworker-client-0.1.0, responses-0.6.0, cov-7.1.0, asyncio-1.4.0
[task 2026-07-24T20:25:16.561+00:00] asyncio: mode=Mode.STRICT, debug=False, asyncio_default_fixture_loop_scope=function, asyncio_default_test_loop_scope=function
[task 2026-07-24T20:25:16.561+00:00] collected 125 items
[task 2026-07-24T20:25:16.561+00:00] 
[task 2026-07-24T20:25:17.396+00:00] tests/test_android_l10n_import.py .......                                [  5%]
[task 2026-07-24T20:25:17.812+00:00] tests/test_android_l10n_sync.py ......                                   [ 10%]
[task 2026-07-24T20:25:19.349+00:00] tests/test_l10n_bump.py ..............                                   [ 21%]
[task 2026-07-24T20:25:20.305+00:00] tests/test_merge_day.py ........                                         [ 28%]
[task 2026-07-24T20:25:21.107+00:00] tests/test_merge_day_legacy.py ........                                  [ 34%]
[task 2026-07-24T20:25:21.238+00:00] tests/test_rerun_handling.py ......                                      [ 39%]
[task 2026-07-24T20:25:22.422+00:00] tests/test_script.py ................................                    [ 64%]
[task 2026-07-24T20:25:22.543+00:00] tests/test_tag.py ........                                               [ 71%]
[task 2026-07-24T20:25:22.560+00:00] tests/test_util_diffs.py ..........                                      [ 79%]
[task 2026-07-24T20:25:30.172+00:00] tests/test_version_bump.py ....................                          [ 95%]
[task 2026-07-24T20:25:30.527+00:00] tests/test_version_bump_json_manifest.py ......                          [100%]
[task 2026-07-24T20:25:30.527+00:00] 
[task 2026-07-24T20:25:30.527+00:00] ================================ tests coverage ================================
[task 2026-07-24T20:25:30.527+00:00] _______________ coverage: platform linux, python 3.11.15-final-0 _______________
[task 2026-07-24T20:25:30.527+00:00] 
[task 2026-07-24T20:25:30.527+00:00] Name                                             Stmts   Miss Branch BrPart  Cover   Missing
[task 2026-07-24T20:25:30.527+00:00] --------------------------------------------------------------------------------------------
[task 2026-07-24T20:25:30.527+00:00] src/landoscript/__init__.py                          0      0      0      0   100%
[task 2026-07-24T20:25:30.527+00:00] src/landoscript/actions/__init__.py                  0      0      0      0   100%
[task 2026-07-24T20:25:30.527+00:00] src/landoscript/actions/android_l10n_import.py      96      5     28      3    94%   96-97, 100-101, 116
[task 2026-07-24T20:25:30.527+00:00] src/landoscript/actions/android_l10n_sync.py        90      6     28      4    92%   88-89, 92-93, 105, 114
[task 2026-07-24T20:25:30.527+00:00] src/landoscript/actions/l10n_bump.py               119      4     32      2    96%   70-71, 79, 144
[task 2026-07-24T20:25:30.527+00:00] src/landoscript/actions/merge_day.py               205      7     66     11    93%   128->132, 132->147, 141, 162, 205, 208->220, 212, 248, 260->262, 300, 313
[task 2026-07-24T20:25:30.527+00:00] src/landoscript/actions/tag.py                      31      1      8      1    95%   32
[task 2026-07-24T20:25:30.527+00:00] src/landoscript/actions/version_bump.py             87      2     26      1    97%   99-100
[task 2026-07-24T20:25:30.527+00:00] src/landoscript/errors.py                            7      0      0      0   100%
[task 2026-07-24T20:25:30.527+00:00] src/landoscript/lando.py                            80      1     24      1    98%   121
[task 2026-07-24T20:25:30.527+00:00] src/landoscript/script.py                          168      6     68      4    96%   120->130, 178-179, 245, 258-259, 263
[task 2026-07-24T20:25:30.527+00:00] src/landoscript/treestatus.py                       17      0      2      0   100%
[task 2026-07-24T20:25:30.527+00:00] src/landoscript/util/__init__.py                     0      0      0      0   100%
[task 2026-07-24T20:25:30.527+00:00] src/landoscript/util/diffs.py                       33      0     16      0   100%
[task 2026-07-24T20:25:30.527+00:00] src/landoscript/util/l10n.py                        20      0      4      0   100%
[task 2026-07-24T20:25:30.527+00:00] src/landoscript/util/log.py                          5      0      2      0   100%
[task 2026-07-24T20:25:30.527+00:00] src/landoscript/util/version.py                     12      2      4      2    75%   20, 24
[task 2026-07-24T20:25:30.527+00:00] --------------------------------------------------------------------------------------------
[task 2026-07-24T20:25:30.527+00:00] TOTAL                                              970     34    308     29    95%
[task 2026-07-24T20:25:30.527+00:00] ============================= 125 passed in 14.03s =============================
[task 2026-07-24T20:25:30.879+00:00]   py311: OK (21.06=setup[1.45]+cmd[19.61] seconds)
[task 2026-07-24T20:25:30.879+00:00]   congratulations :) (21.11 seconds)
[task 2026-07-24T20:25:30.924+00:00]   landoscript-py311: OK (23.52=setup[1.75]+cmd[21.78] seconds)
[task 2026-07-24T20:25:30.924+00:00]   congratulations :) (23.60 seconds)
[taskcluster 2026-07-24T20:25:31.813Z]                        Exit Code: 0
[taskcluster 2026-07-24T20:25:31.813Z]                        User Time: 15.046ms
[taskcluster 2026-07-24T20:25:31.813Z]                      Kernel Time: 12.538ms
[taskcluster 2026-07-24T20:25:31.813Z]                        Wall Time: 36.713684845s
[taskcluster 2026-07-24T20:25:31.813Z]  Average Available System Memory: 14.47 GiB
[taskcluster 2026-07-24T20:25:31.813Z]       Average System Memory Used: 1.15 GiB
[taskcluster 2026-07-24T20:25:31.813Z]          Peak System Memory Used: 1.29 GiB
[taskcluster 2026-07-24T20:25:31.813Z]              Total System Memory: 15.61 GiB
[taskcluster 2026-07-24T20:25:31.813Z]                           Result: SUCCEEDED
[taskcluster 2026-07-24T20:25:31.814Z] === Task Finished ===
[taskcluster 2026-07-24T20:25:31.814Z] Task Duration: 36.714259428s
[taskcluster 2026-07-24T20:25:31.846Z] [mounts] Preserving cache: Moving "/home/task_178492468644649/cache0" to "/home/generic-worker/caches/IDGW6f6HSoCD68ljNbSuqA"