[taskcluster 2026-03-11T12:45:14.471Z] Worker Type (scriptworker-1/decision) settings:
[taskcluster 2026-03-11T12:45:14.471Z]   {
[taskcluster 2026-03-11T12:45:14.471Z]     "generic-worker": {
[taskcluster 2026-03-11T12:45:14.471Z]       "config": {
[taskcluster 2026-03-11T12:45:14.471Z]         "headlessTasks": true
[taskcluster 2026-03-11T12:45:14.471Z]       },
[taskcluster 2026-03-11T12:45:14.471Z]       "engine": "multiuser",
[taskcluster 2026-03-11T12:45:14.471Z]       "go-arch": "amd64",
[taskcluster 2026-03-11T12:45:14.471Z]       "go-os": "linux",
[taskcluster 2026-03-11T12:45:14.471Z]       "go-version": "go1.26.0",
[taskcluster 2026-03-11T12:45:14.471Z]       "release": "https://github.com/taskcluster/taskcluster/releases/tag/v97.0.1",
[taskcluster 2026-03-11T12:45:14.471Z]       "revision": "6519c127aad40a47b1412136d9a5797a76eca200",
[taskcluster 2026-03-11T12:45:14.471Z]       "source": "https://github.com/taskcluster/taskcluster/commits/6519c127aad40a47b1412136d9a5797a76eca200",
[taskcluster 2026-03-11T12:45:14.471Z]       "version": "97.0.1"
[taskcluster 2026-03-11T12:45:14.471Z]     },
[taskcluster 2026-03-11T12:45:14.471Z]     "image": "projects/taskcluster-imaging/global/images/gw-fxci-gcp-l1-2404-amd64-headless-googlecompute-2026-03-05",
[taskcluster 2026-03-11T12:45:14.471Z]     "instance-id": "1397818047520890219",
[taskcluster 2026-03-11T12:45:14.471Z]     "instance-type": "projects/887720501152/machineTypes/n2-standard-2",
[taskcluster 2026-03-11T12:45:14.471Z]     "local-ipv4": "10.128.4.126",
[taskcluster 2026-03-11T12:45:14.471Z]     "project-id": "fxci-production-level1-workers",
[taskcluster 2026-03-11T12:45:14.471Z]     "public-hostname": "scriptworker-1-decision-sryvcituqp6kwhlnuqlvpw.c.fxci-production-level1-workers.internal",
[taskcluster 2026-03-11T12:45:14.471Z]     "public-ipv4": "34.55.178.143",
[taskcluster 2026-03-11T12:45:14.471Z]     "region": "us-central1",
[taskcluster 2026-03-11T12:45:14.471Z]     "zone": "us-central1-f"
[taskcluster 2026-03-11T12:45:14.471Z]   }
[taskcluster 2026-03-11T12:45:14.471Z] Task ID: G8bZceB8Qfizr2DdqwJxQg
[taskcluster 2026-03-11T12:45:14.471Z] === Task Starting ===
[taskcluster 2026-03-11T12:45:17.592Z] [d2g] Loading docker image
[taskcluster 2026-03-11T12:45:27.401Z] [d2g] Loaded docker image "docker.io/mozillareleases/taskgraph:decision-v19.2.1@sha256:6cf28e0019ac35234e3ad4dc42586161fb77e6791fd593b8440a53f2f8aff8db"
[taskcluster 2026-03-11T12:45:27.401Z] Executing command 0: docker run -t --name taskcontainer_KQpU7XRMT_aTkzQPKUNq_A --memory-swap -1 --pids-limit -1 '--add-host=localhost.localdomain:127.0.0.1' --add-host=taskcluster:host-gateway --env-file 'env.list' 'mozillareleases/taskgraph:decision-v19.2.1@sha256:6cf28e0019ac35234e3ad4dc42586161fb77e6791fd593b8440a53f2f8aff8db' run-task --scriptworker-checkout=/builds/worker/checkouts/src -- bash -cx 'cd /builds/worker/checkouts/src && ln -s /builds/worker/artifacts artifacts && taskgraph decision --pushlog-id='\''0'\'' --pushdate='\''0'\'' --project='\''scriptworker'\'' --owner='\''jcristau@users.noreply.github.com'\'' --level='\''1'\'' --repository-type=git --tasks-for='\''github-pull-request'\'' --base-repository='\''https://github.com/mozilla-releng/scriptworker'\'' --base-ref='\''main'\'' --base-rev='\''548edd8ce5dfc5b4aae52a86979f9ddaad7d0e64'\'' --head-repository='\''https://github.com/jcristau/scriptworker'\'' --head-ref='\''bug2015223'\'' --head-rev='\''f262ba66d3203c8db5048fd3ba81c41c82018a62'\'' 
[taskcluster 2026-03-11T12:45:27.401Z] '
[setup 2026-03-11T12:45:28.609+00:00] run-task started in /
[setup 2026-03-11T12:45:28.610+00:00] Invoked by command: --scriptworker-checkout=/builds/worker/checkouts/src -- bash -cx cd /builds/worker/checkouts/src && ln -s /builds/worker/artifacts artifacts && taskgraph decision --pushlog-id='0' --pushdate='0' --project='scriptworker' --owner='jcristau@users.noreply.github.com' --level='1' --repository-type=git --tasks-for='github-pull-request' --base-repository='https://github.com/mozilla-releng/scriptworker' --base-ref='main' --base-rev='548edd8ce5dfc5b4aae52a86979f9ddaad7d0e64' --head-repository='https://github.com/jcristau/scriptworker' --head-ref='bug2015223' --head-rev='f262ba66d3203c8db5048fd3ba81c41c82018a62' 

[setup 2026-03-11T12:45:28.610+00:00] Python version: 3.11.2
[setup 2026-03-11T12:45:28.610+00:00] Subprocess python version: 
cpython-3.11.2-linux-x86_64-gnu    /usr/bin/python3.11
cpython-3.11.2-linux-x86_64-gnu    /usr/bin/python3 -> python3.11
[vcs 2026-03-11T12:45:28.733+00:00] WARNING: vcs checkout path (/builds/worker/checkouts/src) not in cache or volume; performance will likely suffer
[setup 2026-03-11T12:45:28.735+00:00] running as worker:worker
[vcs 2026-03-11T12:45:28.735+00:00] executing ['git', 'config', '--global', '--add', 'safe.directory', '/builds/worker/checkouts/src']
[vcs 2026-03-11T12:45:28.739+00:00] executing ['git', 'clone', 'https://github.com/mozilla-releng/scriptworker', '/builds/worker/checkouts/src']
[vcs 2026-03-11T12:45:28.754+00:00] Cloning into '/builds/worker/checkouts/src'...
[vcs 2026-03-11T12:45:30.584+00:00] executing ['git', 'fetch', 'origin', '548edd8ce5dfc5b4aae52a86979f9ddaad7d0e64']
[vcs 2026-03-11T12:45:30.899+00:00] From https://github.com/mozilla-releng/scriptworker
[vcs 2026-03-11T12:45:30.899+00:00]  * branch            548edd8ce5dfc5b4aae52a86979f9ddaad7d0e64 -> FETCH_HEAD
[vcs 2026-03-11T12:45:30.907+00:00] executing ['git', 'fetch', 'https://github.com/jcristau/scriptworker', 'bug2015223']
[vcs 2026-03-11T12:45:31.408+00:00] From https://github.com/jcristau/scriptworker
[vcs 2026-03-11T12:45:31.408+00:00]  * branch            bug2015223 -> FETCH_HEAD
[vcs 2026-03-11T12:45:31.412+00:00] executing ['git', 'checkout', '-f', '-B', 'bug2015223', 'f262ba66d3203c8db5048fd3ba81c41c82018a62']
[vcs 2026-03-11T12:45:31.548+00:00] Switched to a new branch 'bug2015223'
[vcs 2026-03-11T12:45:31.549+00:00] cleaning git checkout...
[vcs 2026-03-11T12:45:31.550+00:00] executing ['git', 'clean', '-nxdff']
[vcs 2026-03-11T12:45:31.554+00:00] removing []
[vcs 2026-03-11T12:45:31.554+00:00] successfully cleaned git checkout!
[vcs 2026-03-11T12:45:31.556+00:00] TinderboxPrint:<a href='https://github.com/jcristau/scriptworker/commit/f262ba66d3203c8db5048fd3ba81c41c82018a62' title='Built from scriptworker commit f262ba66d3203c8db5048fd3ba81c41c82018a62'>f262ba66d3203c8db5048fd3ba81c41c82018a62</a>
[task 2026-03-11T12:45:31.557+00:00] executing ['bash', '-cx', "cd /builds/worker/checkouts/src && ln -s /builds/worker/artifacts artifacts && taskgraph decision --pushlog-id='0' --pushdate='0' --project='scriptworker' --owner='jcristau@users.noreply.github.com' --level='1' --repository-type=git --tasks-for='github-pull-request' --base-repository='https://github.com/mozilla-releng/scriptworker' --base-ref='main' --base-rev='548edd8ce5dfc5b4aae52a86979f9ddaad7d0e64' --head-repository='https://github.com/jcristau/scriptworker' --head-ref='bug2015223' --head-rev='f262ba66d3203c8db5048fd3ba81c41c82018a62' \n"]
[task 2026-03-11T12:45:31.559+00:00] + cd /builds/worker/checkouts/src
[task 2026-03-11T12:45:31.560+00:00] + ln -s /builds/worker/artifacts artifacts
[task 2026-03-11T12:45:31.562+00:00] + taskgraph decision --pushlog-id=0 --pushdate=0 --project=scriptworker --owner=jcristau@users.noreply.github.com --level=1 --repository-type=git --tasks-for=github-pull-request --base-repository=https://github.com/mozilla-releng/scriptworker --base-ref=main --base-rev=548edd8ce5dfc5b4aae52a86979f9ddaad7d0e64 --head-repository=https://github.com/jcristau/scriptworker --head-ref=bug2015223 --head-rev=f262ba66d3203c8db5048fd3ba81c41c82018a62
[task 2026-03-11T12:45:32.358+00:00] 2026-03-11 12:45:32,357 - INFO - Loading graph configuration.
[task 2026-03-11T12:45:32.367+00:00] 2026-03-11 12:45:32,367 - WARNING - using default project parameters; add scriptworker to PER_PROJECT_PARAMETERS in /usr/local/lib/python3.11/dist-packages/taskgraph/decision.py to customize behavior for this project
[task 2026-03-11T12:45:32.369+00:00] 2026-03-11 12:45:32,369 - INFO - Using Parameters(id=c80b92030758) (from defaults)
[task 2026-03-11T12:45:32.370+00:00] 2026-03-11 12:45:32,370 - INFO - writing artifact file `parameters.yml`
[task 2026-03-11T12:45:32.383+00:00] 2026-03-11 12:45:32,382 - INFO - writing artifact file `actions.json`
[task 2026-03-11T12:45:32.384+00:00] 2026-03-11 12:45:32,384 - INFO - Loading kinds
[task 2026-03-11T12:45:32.386+00:00] 2026-03-11 12:45:32,386 - INFO - Generating full task set
[task 2026-03-11T12:45:32.410+00:00] 2026-03-11 12:45:32,410 - INFO - Writing docker-contexts/python3.14.tar.gz for docker image python3.14
[task 2026-03-11T12:45:32.432+00:00] 2026-03-11 12:45:32,432 - INFO - Writing docker-contexts/python3.13.tar.gz for docker image python3.13
[task 2026-03-11T12:45:32.459+00:00] 2026-03-11 12:45:32,459 - INFO - Writing docker-contexts/python3.12.tar.gz for docker image python3.12
[task 2026-03-11T12:45:32.487+00:00] 2026-03-11 12:45:32,487 - INFO - Writing docker-contexts/python3.11.tar.gz for docker image python3.11
[task 2026-03-11T12:45:32.509+00:00] 2026-03-11 12:45:32,509 - INFO - Generated 4 tasks for kind docker-image
[task 2026-03-11T12:45:32.552+00:00] 2026-03-11 12:45:32,552 - INFO - Generated 9 tasks for kind tox
[task 2026-03-11T12:45:32.557+00:00] 2026-03-11 12:45:32,557 - INFO - Generated 1 tasks for kind pr
[task 2026-03-11T12:45:32.564+00:00] 2026-03-11 12:45:32,564 - INFO - Generating full task graph
[task 2026-03-11T12:45:32.565+00:00] 2026-03-11 12:45:32,565 - INFO - Full task graph contains 14 tasks and 9 dependencies
[task 2026-03-11T12:45:32.567+00:00] 2026-03-11 12:45:32,567 - INFO - writing artifact file `full-task-graph.json`
[task 2026-03-11T12:45:32.569+00:00] 2026-03-11 12:45:32,568 - INFO - writing artifact file `runnable-jobs.json`
[task 2026-03-11T12:45:32.569+00:00] 2026-03-11 12:45:32,569 - INFO - Generating target task set
[task 2026-03-11T12:45:32.570+00:00] 2026-03-11 12:45:32,570 - INFO - Filter filter_target_tasks pruned 4 tasks (10 remain)
[task 2026-03-11T12:45:32.570+00:00] 2026-03-11 12:45:32,570 - INFO - writing artifact file `target-tasks.json`
[task 2026-03-11T12:45:32.571+00:00] 2026-03-11 12:45:32,571 - INFO - Generating target task graph
[task 2026-03-11T12:45:32.572+00:00] 2026-03-11 12:45:32,571 - INFO - Adding 4 tasks with `always_target` attribute
[task 2026-03-11T12:45:32.572+00:00] 2026-03-11 12:45:32,572 - INFO - Generating optimized task graph
[task 2026-03-11T12:45:32.573+00:00] 2026-03-11 12:45:32,573 - INFO - No tasks removed during optimization
[task 2026-03-11T12:45:32.929+00:00] 2026-03-11 12:45:32,928 - INFO - Replaced 4 tasks by index-search (index-search) during optimization.
[task 2026-03-11T12:45:32.929+00:00] 2026-03-11 12:45:32,929 - INFO - writing artifact file `task-graph.json`
[task 2026-03-11T12:45:32.930+00:00] 2026-03-11 12:45:32,930 - INFO - writing artifact file `label-to-taskid.json`
[task 2026-03-11T12:45:32.932+00:00] 2026-03-11 12:45:32,932 - INFO - Creating task with taskId Ean2ctomTpuFSsNyABRomA for tox-py313-cot
[task 2026-03-11T12:45:32.933+00:00] 2026-03-11 12:45:32,933 - INFO - Creating task with taskId N9-NGfYfTi6T97SLg-UMBg for tox-py313
[task 2026-03-11T12:45:32.935+00:00] 2026-03-11 12:45:32,935 - INFO - Creating task with taskId ZpBGVQDlRCOoRHXe0TlOnQ for tox-py311-cot
[task 2026-03-11T12:45:32.937+00:00] 2026-03-11 12:45:32,937 - INFO - Creating task with taskId YbqBZRlWQ5ud3JxJqeT3lg for tox-py314
[task 2026-03-11T12:45:32.938+00:00] 2026-03-11 12:45:32,938 - INFO - Creating task with taskId WmKHN4xFRiqYCCslB3jWbw for tox-py312-cot
[task 2026-03-11T12:45:32.940+00:00] 2026-03-11 12:45:32,940 - INFO - Creating task with taskId CaYevlgmTS2R8at1CzgX1A for tox-py314-cot
[task 2026-03-11T12:45:32.941+00:00] 2026-03-11 12:45:32,940 - INFO - Creating task with taskId UV82xf55RXSZKrjRITCnAw for tox-check
[task 2026-03-11T12:45:32.943+00:00] 2026-03-11 12:45:32,942 - INFO - Creating task with taskId K5_UfB6rTiKyrekYk2qZRQ for tox-py311
[task 2026-03-11T12:45:32.946+00:00] 2026-03-11 12:45:32,942 - INFO - Creating task with taskId b1v9qWpZS7W5BN7I2hGg9w for tox-py312
[task 2026-03-11T12:45:33.179+00:00] 2026-03-11 12:45:33,178 - INFO - Creating task with taskId Onr_fBl5SL6KhlXJPvuSXA for pr-complete
[taskcluster 2026-03-11T12:45:33.519Z]                        Exit Code: 0
[taskcluster 2026-03-11T12:45:33.519Z]                        User Time: 15.291ms
[taskcluster 2026-03-11T12:45:33.519Z]                      Kernel Time: 11.214ms
[taskcluster 2026-03-11T12:45:33.519Z]                        Wall Time: 6.117359284s
[taskcluster 2026-03-11T12:45:33.519Z]  Average Available System Memory: 6.72 GiB
[taskcluster 2026-03-11T12:45:33.519Z]       Average System Memory Used: 1.03 GiB
[taskcluster 2026-03-11T12:45:33.519Z]          Peak System Memory Used: 1.13 GiB
[taskcluster 2026-03-11T12:45:33.519Z]              Total System Memory: 7.75 GiB
[taskcluster 2026-03-11T12:45:33.519Z]                           Result: SUCCEEDED
[taskcluster 2026-03-11T12:45:33.520Z] === Task Finished ===
[taskcluster 2026-03-11T12:45:33.520Z] Task Duration: 6.11933828s