[vcs 2024-09-03T19:53:48.390Z] successfully cleaned git checkout!
[vcs 2024-09-03T19:53:48.393Z] TinderboxPrint:<a href='https://github.com/YaySushi/taskgraph/commit/daa07826b4e30f283d7a50950e3781adbb275c06' title='Built from taskgraph commit daa07826b4e30f283d7a50950e3781adbb275c06'>daa07826b4e30f283d7a50950e3781adbb275c06</a>
[task 2024-09-03T19:53:48.394Z] executing ['bash', '-cx', "cd /builds/worker/checkouts/src && ln -s /builds/worker/artifacts artifacts && pip3 install --user --break-system-packages . && taskgraph decision --verbose --pushlog-id='0' --pushdate='0' --project='taskgraph' --owner='[email protected]' --level='1' --repository-type=git --tasks-for='github-pull-request-untrusted' --base-repository='https://github.com/taskcluster/taskgraph' --base-ref='main' --base-rev='f0d58f2f23d809ab27f1745d661ac27587da740c' --head-repository='https://github.com/YaySushi/taskgraph' --head-ref='fix-type-checking-errors' --head-rev='daa07826b4e30f283d7a50950e3781adbb275c06' \n"]
[task 2024-09-03T19:53:48.397Z] + cd /builds/worker/checkouts/src
[task 2024-09-03T19:53:48.398Z] + ln -s /builds/worker/artifacts artifacts
[task 2024-09-03T19:53:48.400Z] + pip3 install --user --break-system-packages .
[task 2024-09-03T19:53:48.894Z] Processing /builds/worker/checkouts/src
[task 2024-09-03T19:53:48.900Z]   Installing build dependencies: started
[task 2024-09-03T19:53:51.601Z]   Installing build dependencies: finished with status 'done'
[task 2024-09-03T19:53:51.605Z]   Getting requirements to build wheel: started
[task 2024-09-03T19:53:51.789Z]   Getting requirements to build wheel: finished with status 'done'
[task 2024-09-03T19:53:51.791Z]   Preparing metadata (pyproject.toml): started
[task 2024-09-03T19:53:51.981Z]   Preparing metadata (pyproject.toml): finished with status 'done'
[task 2024-09-03T19:53:51.994Z] Requirement already satisfied: appdirs>=1.4 in /usr/local/lib/python3.11/dist-packages (from taskcluster-taskgraph==10.0.2) (1.4.4)
[task 2024-09-03T19:53:51.996Z] Requirement already satisfied: cookiecutter~=2.1 in /usr/local/lib/python3.11/dist-packages (from taskcluster-taskgraph==10.0.2) (2.6.0)
[task 2024-09-03T19:53:51.997Z] Requirement already satisfied: json-e>=2.7 in /usr/local/lib/python3.11/dist-packages (from taskcluster-taskgraph==10.0.2) (4.7.0)
[task 2024-09-03T19:53:51.998Z] Requirement already satisfied: mozilla-repo-urls in /usr/local/lib/python3.11/dist-packages (from taskcluster-taskgraph==10.0.2) (0.1.1)
[task 2024-09-03T19:53:51.999Z] Requirement already satisfied: PyYAML>=5.3.1 in /usr/local/lib/python3.11/dist-packages (from taskcluster-taskgraph==10.0.2) (6.0.1)
[task 2024-09-03T19:53:52.001Z] Requirement already satisfied: redo>=2.0 in /usr/local/lib/python3.11/dist-packages (from taskcluster-taskgraph==10.0.2) (2.0.4)
[task 2024-09-03T19:53:52.003Z] Requirement already satisfied: requests>=2.25 in /usr/local/lib/python3.11/dist-packages (from taskcluster-taskgraph==10.0.2) (2.32.3)
[task 2024-09-03T19:53:52.004Z] Requirement already satisfied: slugid>=2.0 in /usr/local/lib/python3.11/dist-packages (from taskcluster-taskgraph==10.0.2) (2.0.0)
[task 2024-09-03T19:53:52.005Z] Requirement already satisfied: taskcluster-urls>=11.0 in /usr/local/lib/python3.11/dist-packages (from taskcluster-taskgraph==10.0.2) (13.0.1)
[task 2024-09-03T19:53:52.008Z] Requirement already satisfied: voluptuous>=0.12.1 in /usr/local/lib/python3.11/dist-packages (from taskcluster-taskgraph==10.0.2) (0.14.2)
[task 2024-09-03T19:53:52.017Z] Requirement already satisfied: binaryornot>=0.4.4 in /usr/local/lib/python3.11/dist-packages (from cookiecutter~=2.1->taskcluster-taskgraph==10.0.2) (0.4.4)
[task 2024-09-03T19:53:52.018Z] Requirement already satisfied: Jinja2<4.0.0,>=2.7 in /usr/local/lib/python3.11/dist-packages (from cookiecutter~=2.1->taskcluster-taskgraph==10.0.2) (3.1.4)
[task 2024-09-03T19:53:52.019Z] Requirement already satisfied: click<9.0.0,>=7.0 in /usr/local/lib/python3.11/dist-packages (from cookiecutter~=2.1->taskcluster-taskgraph==10.0.2) (8.1.7)
[task 2024-09-03T19:53:52.021Z] Requirement already satisfied: python-slugify>=4.0.0 in /usr/local/lib/python3.11/dist-packages (from cookiecutter~=2.1->taskcluster-taskgraph==10.0.2) (8.0.4)
[task 2024-09-03T19:53:52.024Z] Requirement already satisfied: arrow in /usr/local/lib/python3.11/dist-packages (from cookiecutter~=2.1->taskcluster-taskgraph==10.0.2) (1.3.0)
[task 2024-09-03T19:53:52.026Z] Requirement already satisfied: rich in /usr/local/lib/python3.11/dist-packages (from cookiecutter~=2.1->taskcluster-taskgraph==10.0.2) (13.7.1)
[task 2024-09-03T19:53:52.043Z] Requirement already satisfied: charset-normalizer<4,>=2 in /usr/local/lib/python3.11/dist-packages (from requests>=2.25->taskcluster-taskgraph==10.0.2) (3.3.2)
[task 2024-09-03T19:53:52.045Z] Requirement already satisfied: idna<4,>=2.5 in /usr/local/lib/python3.11/dist-packages (from requests>=2.25->taskcluster-taskgraph==10.0.2) (3.7)
[task 2024-09-03T19:53:52.047Z] Requirement already satisfied: urllib3<3,>=1.21.1 in /usr/local/lib/python3.11/dist-packages (from requests>=2.25->taskcluster-taskgraph==10.0.2) (2.2.1)
[task 2024-09-03T19:53:52.049Z] Requirement already satisfied: certifi>=2017.4.17 in /usr/local/lib/python3.11/dist-packages (from requests>=2.25->taskcluster-taskgraph==10.0.2) (2024.6.2)
[task 2024-09-03T19:53:52.063Z] Requirement already satisfied: giturlparse in /usr/local/lib/python3.11/dist-packages (from mozilla-repo-urls->taskcluster-taskgraph==10.0.2) (0.12.0)
[task 2024-09-03T19:53:52.070Z] Requirement already satisfied: chardet>=3.0.2 in /usr/local/lib/python3.11/dist-packages (from binaryornot>=0.4.4->cookiecutter~=2.1->taskcluster-taskgraph==10.0.2) (5.2.0)
[task 2024-09-03T19:53:52.100Z] Requirement already satisfied: MarkupSafe>=2.0 in /usr/local/lib/python3.11/dist-packages (from Jinja2<4.0.0,>=2.7->cookiecutter~=2.1->taskcluster-taskgraph==10.0.2) (2.1.5)
[task 2024-09-03T19:53:52.110Z] Requirement already satisfied: text-unidecode>=1.3 in /usr/local/lib/python3.11/dist-packages (from python-slugify>=4.0.0->cookiecutter~=2.1->taskcluster-taskgraph==10.0.2) (1.3)
[task 2024-09-03T19:53:52.140Z] Requirement already satisfied: python-dateutil>=2.7.0 in /usr/local/lib/python3.11/dist-packages (from arrow->cookiecutter~=2.1->taskcluster-taskgraph==10.0.2) (2.9.0.post0)
[task 2024-09-03T19:53:52.142Z] Requirement already satisfied: types-python-dateutil>=2.8.10 in /usr/local/lib/python3.11/dist-packages (from arrow->cookiecutter~=2.1->taskcluster-taskgraph==10.0.2) (2.9.0.20240316)
[task 2024-09-03T19:53:52.161Z] Requirement already satisfied: markdown-it-py>=2.2.0 in /usr/local/lib/python3.11/dist-packages (from rich->cookiecutter~=2.1->taskcluster-taskgraph==10.0.2) (3.0.0)
[task 2024-09-03T19:53:52.162Z] Requirement already satisfied: pygments<3.0.0,>=2.13.0 in /usr/local/lib/python3.11/dist-packages (from rich->cookiecutter~=2.1->taskcluster-taskgraph==10.0.2) (2.18.0)
[task 2024-09-03T19:53:52.197Z] Requirement already satisfied: mdurl~=0.1 in /usr/local/lib/python3.11/dist-packages (from markdown-it-py>=2.2.0->rich->cookiecutter~=2.1->taskcluster-taskgraph==10.0.2) (0.1.2)
[task 2024-09-03T19:53:52.226Z] Requirement already satisfied: six>=1.5 in /usr/local/lib/python3.11/dist-packages (from python-dateutil>=2.7.0->arrow->cookiecutter~=2.1->taskcluster-taskgraph==10.0.2) (1.16.0)
[task 2024-09-03T19:53:52.270Z] Building wheels for collected packages: taskcluster-taskgraph
[task 2024-09-03T19:53:52.272Z]   Building wheel for taskcluster-taskgraph (pyproject.toml): started
[task 2024-09-03T19:53:52.542Z]   Building wheel for taskcluster-taskgraph (pyproject.toml): finished with status 'done'
[task 2024-09-03T19:53:52.543Z]   Created wheel for taskcluster-taskgraph: filename=taskcluster_taskgraph-10.0.2-py3-none-any.whl size=191590 sha256=da3574af12ed84f24b64a3b5b72068c977b4513315a471561160fb673f693c0a
[task 2024-09-03T19:53:52.544Z]   Stored in directory: /tmp/pip-ephem-wheel-cache-d6zfmf0x/wheels/54/fa/b4/d3017bf051c083cc076bfd93af1693baf4c91b8f6fee74b0bc
[task 2024-09-03T19:53:52.547Z] Successfully built taskcluster-taskgraph
[task 2024-09-03T19:53:52.634Z] Installing collected packages: taskcluster-taskgraph
[task 2024-09-03T19:53:52.724Z]   WARNING: The script taskgraph is installed in '/builds/worker/.local/bin' which is not on PATH.
[task 2024-09-03T19:53:52.725Z]   Consider adding this directory to PATH or, if you prefer to suppress this warning, use --no-warn-script-location.
[task 2024-09-03T19:53:52.730Z] Successfully installed taskcluster-taskgraph-10.0.2
[task 2024-09-03T19:53:52.838Z] + taskgraph decision --verbose --pushlog-id=0 --pushdate=0 --project=taskgraph [email protected] --level=1 --repository-type=git --tasks-for=github-pull-request-untrusted --base-repository=https://github.com/taskcluster/taskgraph --base-ref=main --base-rev=f0d58f2f23d809ab27f1745d661ac27587da740c --head-repository=https://github.com/YaySushi/taskgraph --head-ref=fix-type-checking-errors --head-rev=daa07826b4e30f283d7a50950e3781adbb275c06
[task 2024-09-03T19:53:53.088Z] 2024-09-03 19:53:53,088 - INFO - Loading graph configuration.
[task 2024-09-03T19:53:53.088Z] 2024-09-03 19:53:53,088 - DEBUG - loading config from `taskcluster/config.yml`
[task 2024-09-03T19:53:53.097Z] 2024-09-03 19:53:53,097 - WARNING - using default project parameters; add taskgraph to PER_PROJECT_PARAMETERS in /builds/worker/.local/lib/python3.11/site-packages/taskgraph/decision.py to customize behavior for this project
[task 2024-09-03T19:53:53.098Z] 2024-09-03 19:53:53,098 - INFO - Using Parameters(id=9243afa8c75d) (from defaults)
[task 2024-09-03T19:53:53.099Z] 2024-09-03 19:53:53,099 - DEBUG - Dumping parameters:
[task 2024-09-03T19:53:53.099Z] { 'base_ref': 'main',
[task 2024-09-03T19:53:53.099Z]   'base_repository': 'https://github.com/taskcluster/taskgraph',
[task 2024-09-03T19:53:53.099Z]   'base_rev': 'f0d58f2f23d809ab27f1745d661ac27587da740c',
[task 2024-09-03T19:53:53.099Z]   'build_date': 1725393233,
[task 2024-09-03T19:53:53.099Z]   'build_number': 1,
[task 2024-09-03T19:53:53.100Z]   'do_not_optimize': [],
[task 2024-09-03T19:53:53.100Z]   'enable_always_target': True,
[task 2024-09-03T19:53:53.101Z]   'existing_tasks': {},
[task 2024-09-03T19:53:53.101Z]   'files_changed': [ 'src/taskgraph/main.py',
[task 2024-09-03T19:53:53.102Z]                      'src/taskgraph/optimize/base.py',
[task 2024-09-03T19:53:53.102Z]                      'src/taskgraph/util/vcs.py',
[task 2024-09-03T19:53:53.103Z]                      'src/taskgraph/generator.py',
[task 2024-09-03T19:53:53.103Z]                      'src/taskgraph/main.py',
[task 2024-09-03T19:53:53.104Z]                      'src/taskgraph/util/vcs.py',
[task 2024-09-03T19:53:53.105Z]                      'src/taskgraph/actions/cancel.py',
[task 2024-09-03T19:53:53.106Z]                      'src/taskgraph/actions/cancel_all.py',
[task 2024-09-03T19:53:53.106Z]                      'src/taskgraph/actions/rebuild_cached_tasks.py',
[task 2024-09-03T19:53:53.107Z]                      'src/taskgraph/actions/util.py',
[task 2024-09-03T19:53:53.108Z]                      'src/taskgraph/generator.py',
[task 2024-09-03T19:53:53.108Z]                      'src/taskgraph/main.py',
[task 2024-09-03T19:53:53.108Z]                      'src/taskgraph/parameters.py',
[task 2024-09-03T19:53:53.108Z]                      'src/taskgraph/run-task/robustcheckout.py',
[task 2024-09-03T19:53:53.108Z]                      'src/taskgraph/util/vcs.py'],
[task 2024-09-03T19:53:53.108Z]   'filters': ['target_tasks_method'],
[task 2024-09-03T19:53:53.108Z]   'head_ref': 'fix-type-checking-errors',
[task 2024-09-03T19:53:53.108Z]   'head_repository': 'https://github.com/YaySushi/taskgraph',
[task 2024-09-03T19:53:53.108Z]   'head_rev': 'daa07826b4e30f283d7a50950e3781adbb275c06',
[task 2024-09-03T19:53:53.108Z]   'head_tag': '',
[task 2024-09-03T19:53:53.108Z]   'level': '1',
[task 2024-09-03T19:53:53.108Z]   'moz_build_date': '20240903195353',
[task 2024-09-03T19:53:53.109Z]   'next_version': None,
[task 2024-09-03T19:53:53.109Z]   'optimize_strategies': None,
[task 2024-09-03T19:53:53.109Z]   'optimize_target_tasks': True,
[task 2024-09-03T19:53:53.109Z]   'owner': '[email protected]',
[task 2024-09-03T19:53:53.109Z]   'project': 'taskgraph',
[task 2024-09-03T19:53:53.109Z]   'pushdate': 0,
[task 2024-09-03T19:53:53.109Z]   'pushlog_id': '0',
[task 2024-09-03T19:53:53.109Z]   'repository_type': 'git',
[task 2024-09-03T19:53:53.109Z]   'target_tasks_method': 'default',
[task 2024-09-03T19:53:53.109Z]   'tasks_for': 'github-pull-request-untrusted',
[task 2024-09-03T19:53:53.109Z]   'version': None}
[task 2024-09-03T19:53:53.109Z] 2024-09-03 19:53:53,099 - INFO - writing artifact file `parameters.yml`
[task 2024-09-03T19:53:53.109Z] 2024-09-03 19:53:53,108 - INFO - writing artifact file `actions.json`
[task 2024-09-03T19:53:53.110Z] 2024-09-03 19:53:53,110 - INFO - Loading kinds
[task 2024-09-03T19:53:53.110Z] 2024-09-03 19:53:53,110 - DEBUG - loading kind `docker-image` from `taskcluster/kinds/docker-image`
[task 2024-09-03T19:53:53.111Z] 2024-09-03 19:53:53,111 - DEBUG - loading kind `codecov` from `taskcluster/kinds/codecov`
[task 2024-09-03T19:53:53.112Z] 2024-09-03 19:53:53,112 - DEBUG - loading kind `test` from `taskcluster/kinds/test`
[task 2024-09-03T19:53:53.112Z] 2024-09-03 19:53:53,112 - DEBUG - loading kind `push-image` from `taskcluster/kinds/push-image`
[task 2024-09-03T19:53:53.113Z] 2024-09-03 19:53:53,113 - DEBUG - loading kind `doc` from `taskcluster/kinds/doc`
[task 2024-09-03T19:53:53.114Z] 2024-09-03 19:53:53,113 - DEBUG - loading kind `check` from `taskcluster/kinds/check`
[task 2024-09-03T19:53:53.114Z] 2024-09-03 19:53:53,114 - DEBUG - loading kind `fetch` from `taskcluster/kinds/fetch`
[task 2024-09-03T19:53:53.114Z] 2024-09-03 19:53:53,114 - INFO - Generating full task set
[task 2024-09-03T19:53:53.115Z] 2024-09-03 19:53:53,115 - DEBUG - Loading tasks for kind check
[task 2024-09-03T19:53:53.128Z] 2024-09-03 19:53:53,128 - DEBUG - Generating tasks for check type
[task 2024-09-03T19:53:53.130Z] 2024-09-03 19:53:53,130 - INFO - Generated 1 tasks for kind check
[task 2024-09-03T19:53:53.130Z] 2024-09-03 19:53:53,130 - DEBUG - Loading tasks for kind doc
[task 2024-09-03T19:53:53.132Z] 2024-09-03 19:53:53,131 - DEBUG - Generating tasks for doc generate
[task 2024-09-03T19:53:53.132Z] 2024-09-03 19:53:53,132 - INFO - Generated 1 tasks for kind doc
[task 2024-09-03T19:53:53.132Z] 2024-09-03 19:53:53,132 - DEBUG - Loading tasks for kind docker-image
[task 2024-09-03T19:53:53.133Z] 2024-09-03 19:53:53,133 - DEBUG - Generating tasks for docker-image decision
[task 2024-09-03T19:53:53.134Z] 2024-09-03 19:53:53,133 - INFO - Writing docker-contexts/decision.tar.gz for docker image decision
[task 2024-09-03T19:53:53.224Z] 2024-09-03 19:53:53,224 - DEBUG - Generating tasks for docker-image fetch
[task 2024-09-03T19:53:53.224Z] 2024-09-03 19:53:53,224 - INFO - Writing docker-contexts/fetch.tar.gz for docker image fetch
[task 2024-09-03T19:53:53.236Z] 2024-09-03 19:53:53,236 - DEBUG - Generating tasks for docker-image index-task
[task 2024-09-03T19:53:53.237Z] 2024-09-03 19:53:53,236 - INFO - Writing docker-contexts/index-task.tar.gz for docker image index-task
[task 2024-09-03T19:53:53.239Z] 2024-09-03 19:53:53,238 - DEBUG - Generating tasks for docker-image py38
[task 2024-09-03T19:53:53.239Z] 2024-09-03 19:53:53,238 - INFO - Writing docker-contexts/py38.tar.gz for docker image py38
[task 2024-09-03T19:53:53.256Z] 2024-09-03 19:53:53,256 - DEBUG - Generating tasks for docker-image py39
[task 2024-09-03T19:53:53.257Z] 2024-09-03 19:53:53,257 - INFO - Writing docker-contexts/py39.tar.gz for docker image py39
[task 2024-09-03T19:53:53.274Z] 2024-09-03 19:53:53,274 - DEBUG - Generating tasks for docker-image py310
[task 2024-09-03T19:53:53.274Z] 2024-09-03 19:53:53,274 - INFO - Writing docker-contexts/py310.tar.gz for docker image py310
[task 2024-09-03T19:53:53.291Z] 2024-09-03 19:53:53,291 - DEBUG - Generating tasks for docker-image py311
[task 2024-09-03T19:53:53.292Z] 2024-09-03 19:53:53,292 - INFO - Writing docker-contexts/py311.tar.gz for docker image py311
[task 2024-09-03T19:53:53.308Z] 2024-09-03 19:53:53,308 - DEBUG - Generating tasks for docker-image py312
[task 2024-09-03T19:53:53.309Z] 2024-09-03 19:53:53,309 - INFO - Writing docker-contexts/py312.tar.gz for docker image py312
[task 2024-09-03T19:53:53.326Z] 2024-09-03 19:53:53,326 - DEBUG - Generating tasks for docker-image run-task
[task 2024-09-03T19:53:53.326Z] 2024-09-03 19:53:53,326 - INFO - Writing docker-contexts/run-task.tar.gz for docker image run-task
[task 2024-09-03T19:53:53.344Z] 2024-09-03 19:53:53,343 - DEBUG - Generating tasks for docker-image skopeo
[task 2024-09-03T19:53:53.344Z] 2024-09-03 19:53:53,344 - INFO - Writing docker-contexts/skopeo.tar.gz for docker image skopeo
[task 2024-09-03T19:53:53.365Z] 2024-09-03 19:53:53,364 - INFO - Generated 10 tasks for kind docker-image
[task 2024-09-03T19:53:53.365Z] 2024-09-03 19:53:53,365 - DEBUG - Loading tasks for kind fetch
[task 2024-09-03T19:53:53.368Z] 2024-09-03 19:53:53,368 - DEBUG - Generating tasks for fetch codecov-uploader
[task 2024-09-03T19:53:53.369Z] 2024-09-03 19:53:53,369 - INFO - Generated 1 tasks for kind fetch
[task 2024-09-03T19:53:53.369Z] 2024-09-03 19:53:53,369 - DEBUG - Loading tasks for kind push-image
[task 2024-09-03T19:53:53.375Z] 2024-09-03 19:53:53,374 - DEBUG - Generating tasks for push-image decision
[task 2024-09-03T19:53:53.375Z] 2024-09-03 19:53:53,375 - DEBUG - Generating tasks for push-image run-task
[task 2024-09-03T19:53:53.376Z] 2024-09-03 19:53:53,376 - INFO - Generated 2 tasks for kind push-image
[task 2024-09-03T19:53:53.376Z] 2024-09-03 19:53:53,376 - DEBUG - Loading tasks for kind test
[task 2024-09-03T19:53:53.379Z] 2024-09-03 19:53:53,379 - DEBUG - Generating tasks for test unit
[task 2024-09-03T19:53:53.383Z] 2024-09-03 19:53:53,383 - INFO - Generated 5 tasks for kind test
[task 2024-09-03T19:53:53.383Z] 2024-09-03 19:53:53,383 - DEBUG - Loading tasks for kind codecov
[task 2024-09-03T19:53:53.385Z] 2024-09-03 19:53:53,385 - DEBUG - Generating tasks for codecov upload
[task 2024-09-03T19:53:53.386Z] 2024-09-03 19:53:53,386 - INFO - Generated 1 tasks for kind codecov
[task 2024-09-03T19:53:53.386Z] 2024-09-03 19:53:53,386 - INFO - Generating full task graph
[task 2024-09-03T19:53:53.387Z] 2024-09-03 19:53:53,386 - INFO - Full task graph contains 21 tasks and 20 dependencies
[task 2024-09-03T19:53:53.387Z] 2024-09-03 19:53:53,387 - INFO - writing artifact file `full-task-graph.json`
[task 2024-09-03T19:53:53.392Z] 2024-09-03 19:53:53,392 - INFO - writing artifact file `runnable-jobs.json`
[task 2024-09-03T19:53:53.392Z] 2024-09-03 19:53:53,392 - INFO - Generating target task set
[task 2024-09-03T19:53:53.393Z] 2024-09-03 19:53:53,393 - INFO - Filter filter_target_tasks pruned 14 tasks (7 remain)
[task 2024-09-03T19:53:53.393Z] 2024-09-03 19:53:53,393 - INFO - writing artifact file `target-tasks.json`
[task 2024-09-03T19:53:53.393Z] 2024-09-03 19:53:53,393 - INFO - Generating target task graph
[task 2024-09-03T19:53:53.393Z] 2024-09-03 19:53:53,393 - INFO - Adding 10 tasks with `always_target` attribute
[task 2024-09-03T19:53:53.393Z] 2024-09-03 19:53:53,393 - INFO - Generating optimized task graph
[task 2024-09-03T19:53:53.394Z] 2024-09-03 19:53:53,394 - DEBUG - optimize: build-docker-image-decision kept because of index-search (index-search)
[task 2024-09-03T19:53:53.394Z] 2024-09-03 19:53:53,394 - DEBUG - optimize: build-docker-image-fetch kept because of index-search (index-search)
[task 2024-09-03T19:53:53.394Z] 2024-09-03 19:53:53,394 - DEBUG - optimize: build-docker-image-index-task kept because of index-search (index-search)
[task 2024-09-03T19:53:53.394Z] 2024-09-03 19:53:53,394 - DEBUG - optimize: build-docker-image-run-task kept because of dependent tasks
[task 2024-09-03T19:53:53.394Z] 2024-09-03 19:53:53,394 - DEBUG - optimize: build-docker-image-skopeo kept because of index-search (index-search)
[task 2024-09-03T19:53:53.394Z] 2024-09-03 19:53:53,394 - DEBUG - optimize: check-type kept because of never
[task 2024-09-03T19:53:53.394Z] 2024-09-03 19:53:53,394 - DEBUG - optimize: doc-generate kept because of never
[task 2024-09-03T19:53:53.394Z] 2024-09-03 19:53:53,394 - DEBUG - optimize: test-unit-py310 kept because of never
[task 2024-09-03T19:53:53.394Z] 2024-09-03 19:53:53,394 - DEBUG - optimize: test-unit-py311 kept because of never
[task 2024-09-03T19:53:53.394Z] 2024-09-03 19:53:53,394 - DEBUG - optimize: test-unit-py312 kept because of never
[task 2024-09-03T19:53:53.395Z] 2024-09-03 19:53:53,394 - DEBUG - optimize: test-unit-py38 kept because of never
[task 2024-09-03T19:53:53.395Z] 2024-09-03 19:53:53,395 - DEBUG - optimize: test-unit-py39 kept because of never
[task 2024-09-03T19:53:53.395Z] 2024-09-03 19:53:53,395 - DEBUG - optimize: build-docker-image-py310 kept because of dependent tasks
[task 2024-09-03T19:53:53.395Z] 2024-09-03 19:53:53,395 - DEBUG - optimize: build-docker-image-py311 kept because of dependent tasks
[task 2024-09-03T19:53:53.395Z] 2024-09-03 19:53:53,395 - DEBUG - optimize: build-docker-image-py312 kept because of dependent tasks
[task 2024-09-03T19:53:53.395Z] 2024-09-03 19:53:53,395 - DEBUG - optimize: build-docker-image-py38 kept because of dependent tasks
[task 2024-09-03T19:53:53.395Z] 2024-09-03 19:53:53,395 - DEBUG - optimize: build-docker-image-py39 kept because of dependent tasks
[task 2024-09-03T19:53:53.395Z] 2024-09-03 19:53:53,395 - INFO - No tasks removed during optimization
[task 2024-09-03T19:53:53.395Z] 2024-09-03 19:53:53,395 - DEBUG - Running in Taskcluster instance https://firefox-ci-tc.services.mozilla.com with taskcluster-proxy
[task 2024-09-03T19:53:53.398Z] 2024-09-03 19:53:53,398 - DEBUG - Starting new HTTPS connection (1): firefox-ci-tc.services.mozilla.com:443
[task 2024-09-03T19:53:53.484Z] 2024-09-03 19:53:53,484 - DEBUG - https://firefox-ci-tc.services.mozilla.com:443 "POST /api/index/v1/tasks/indexes HTTP/1.1" 200 None
[task 2024-09-03T19:53:53.558Z] 2024-09-03 19:53:53,558 - DEBUG - https://firefox-ci-tc.services.mozilla.com:443 "POST /api/queue/v1/tasks/status HTTP/1.1" 200 None
[task 2024-09-03T19:53:53.560Z] 2024-09-03 19:53:53,559 - DEBUG - replace_tasks: build-docker-image-fetch
[task 2024-09-03T19:53:53.560Z] 2024-09-03 19:53:53,560 - DEBUG - replace_tasks: build-docker-image-fetch replaced with FeSR2ZQCRzaMsAM1OOnz1w by optimization strategy
[task 2024-09-03T19:53:53.560Z] 2024-09-03 19:53:53,560 - DEBUG - replace_tasks: build-docker-image-index-task
[task 2024-09-03T19:53:53.560Z] 2024-09-03 19:53:53,560 - DEBUG - replace_tasks: build-docker-image-index-task replaced with AeoSQIL9QhCfYYsPaMheLw by optimization strategy
[task 2024-09-03T19:53:53.560Z] 2024-09-03 19:53:53,560 - DEBUG - replace_tasks: build-docker-image-py310
[task 2024-09-03T19:53:53.562Z] 2024-09-03 19:53:53,562 - DEBUG - replace_tasks: build-docker-image-py310 replaced with cZIEBjC2TE6S57ROVcurUQ by optimization strategy
[task 2024-09-03T19:53:53.562Z] 2024-09-03 19:53:53,562 - DEBUG - replace_tasks: build-docker-image-py311
[task 2024-09-03T19:53:53.562Z] 2024-09-03 19:53:53,562 - DEBUG - replace_tasks: build-docker-image-py311 replaced with LtArSeWySpqijrrlBvj_3w by optimization strategy
[task 2024-09-03T19:53:53.562Z] 2024-09-03 19:53:53,562 - DEBUG - replace_tasks: build-docker-image-py312
[task 2024-09-03T19:53:53.563Z] 2024-09-03 19:53:53,562 - DEBUG - replace_tasks: build-docker-image-py312 replaced with L46bErmnQuu0AqVdDNn9fA by optimization strategy
[task 2024-09-03T19:53:53.563Z] 2024-09-03 19:53:53,563 - DEBUG - replace_tasks: build-docker-image-py38
[task 2024-09-03T19:53:53.563Z] 2024-09-03 19:53:53,563 - DEBUG - replace_tasks: build-docker-image-py38 replaced with CNpqQFXbSTmV26jC7lsEVg by optimization strategy
[task 2024-09-03T19:53:53.563Z] 2024-09-03 19:53:53,563 - DEBUG - replace_tasks: build-docker-image-py39
[task 2024-09-03T19:53:53.563Z] 2024-09-03 19:53:53,563 - DEBUG - replace_tasks: build-docker-image-py39 replaced with AamlYTEJS5mrEpt5wDttTg by optimization strategy
[task 2024-09-03T19:53:53.563Z] 2024-09-03 19:53:53,563 - DEBUG - replace_tasks: build-docker-image-run-task
[task 2024-09-03T19:53:53.563Z] 2024-09-03 19:53:53,563 - DEBUG - replace_tasks: build-docker-image-run-task replaced with XDrf2NirS9qJXE6F_LnoJA by optimization strategy
[task 2024-09-03T19:53:53.564Z] 2024-09-03 19:53:53,563 - DEBUG - replace_tasks: build-docker-image-skopeo
[task 2024-09-03T19:53:53.564Z] 2024-09-03 19:53:53,564 - DEBUG - replace_tasks: build-docker-image-skopeo replaced with TrOmhg2mTM2VWeP-lQGhUA by optimization strategy
[task 2024-09-03T19:53:53.564Z] 2024-09-03 19:53:53,564 - DEBUG - replace_tasks: check-type
[task 2024-09-03T19:53:53.564Z] 2024-09-03 19:53:53,564 - DEBUG - replace_tasks: check-type kept by optimization strategy
[task 2024-09-03T19:53:53.564Z] 2024-09-03 19:53:53,564 - DEBUG - replace_tasks: doc-generate
[task 2024-09-03T19:53:53.564Z] 2024-09-03 19:53:53,564 - DEBUG - replace_tasks: doc-generate kept by optimization strategy
[task 2024-09-03T19:53:53.564Z] 2024-09-03 19:53:53,564 - DEBUG - replace_tasks: test-unit-py310
[task 2024-09-03T19:53:53.564Z] 2024-09-03 19:53:53,564 - DEBUG - replace_tasks: test-unit-py310 kept by optimization strategy
[task 2024-09-03T19:53:53.564Z] 2024-09-03 19:53:53,564 - DEBUG - replace_tasks: test-unit-py311
[task 2024-09-03T19:53:53.564Z] 2024-09-03 19:53:53,564 - DEBUG - replace_tasks: test-unit-py311 kept by optimization strategy
[task 2024-09-03T19:53:53.564Z] 2024-09-03 19:53:53,564 - DEBUG - replace_tasks: test-unit-py312
[task 2024-09-03T19:53:53.564Z] 2024-09-03 19:53:53,564 - DEBUG - replace_tasks: test-unit-py312 kept by optimization strategy
[task 2024-09-03T19:53:53.564Z] 2024-09-03 19:53:53,564 - DEBUG - replace_tasks: test-unit-py38
[task 2024-09-03T19:53:53.564Z] 2024-09-03 19:53:53,564 - DEBUG - replace_tasks: test-unit-py38 kept by optimization strategy
[task 2024-09-03T19:53:53.565Z] 2024-09-03 19:53:53,564 - DEBUG - replace_tasks: test-unit-py39
[task 2024-09-03T19:53:53.565Z] 2024-09-03 19:53:53,565 - DEBUG - replace_tasks: test-unit-py39 kept by optimization strategy
[task 2024-09-03T19:53:53.565Z] 2024-09-03 19:53:53,565 - DEBUG - replace_tasks: build-docker-image-decision
[task 2024-09-03T19:53:53.565Z] 2024-09-03 19:53:53,565 - DEBUG - replace_tasks: build-docker-image-decision kept by optimization strategy
[task 2024-09-03T19:53:53.565Z] 2024-09-03 19:53:53,565 - INFO - Replaced 9 tasks by index-search (index-search) during optimization.
[task 2024-09-03T19:53:53.566Z] 2024-09-03 19:53:53,566 - DEBUG - Morphing: adding index tasks
[task 2024-09-03T19:53:53.566Z] 2024-09-03 19:53:53,566 - DEBUG - Morphing: adding code review task
[task 2024-09-03T19:53:53.566Z] 2024-09-03 19:53:53,566 - INFO - writing artifact file `task-graph.json`
[task 2024-09-03T19:53:53.569Z] 2024-09-03 19:53:53,569 - INFO - writing artifact file `label-to-taskid.json`
[task 2024-09-03T19:53:53.571Z] 2024-09-03 19:53:53,571 - INFO - Creating task with taskId T0dQTCHvT0q9Yy2O-mjPUQ for doc-generate
[task 2024-09-03T19:53:53.572Z] 2024-09-03 19:53:53,572 - DEBUG - Starting new HTTP connection (1): taskcluster:80
[task 2024-09-03T19:53:53.573Z] 2024-09-03 19:53:53,573 - INFO - Creating task with taskId OsJlz7GHT-WcLUv5oNvWUg for test-unit-py311
[task 2024-09-03T19:53:53.575Z] 2024-09-03 19:53:53,574 - DEBUG - Starting new HTTP connection (2): taskcluster:80
[task 2024-09-03T19:53:53.577Z] 2024-09-03 19:53:53,573 - INFO - Creating task with taskId Kh-zGjRMQeqtbLobZlBRcQ for test-unit-py310
[task 2024-09-03T19:53:53.580Z] 2024-09-03 19:53:53,574 - INFO - Creating task with taskId DNCIb10GQtutgEPW7OLlbQ for test-unit-py312
[task 2024-09-03T19:53:53.581Z] 2024-09-03 19:53:53,577 - DEBUG - Starting new HTTP connection (3): taskcluster:80
[task 2024-09-03T19:53:53.582Z] 2024-09-03 19:53:53,579 - DEBUG - Starting new HTTP connection (4): taskcluster:80
[task 2024-09-03T19:53:53.584Z] 2024-09-03 19:53:53,582 - INFO - Creating task with taskId WLWWp-1nQjy6Ps9IHbCc1w for test-unit-py39
[task 2024-09-03T19:53:53.586Z] 2024-09-03 19:53:53,586 - DEBUG - Starting new HTTP connection (5): taskcluster:80
[task 2024-09-03T19:53:53.587Z] 2024-09-03 19:53:53,583 - INFO - Creating task with taskId Xvqoy70mTH-926F3DpOqfA for test-unit-py38
[task 2024-09-03T19:53:53.589Z] 2024-09-03 19:53:53,588 - DEBUG - Starting new HTTP connection (6): taskcluster:80
[task 2024-09-03T19:53:53.590Z] 2024-09-03 19:53:53,590 - INFO - Creating task with taskId YhbFSTw-QXWJMF9hiNBvdQ for build-docker-image-decision
[task 2024-09-03T19:53:53.592Z] 2024-09-03 19:53:53,592 - DEBUG - Starting new HTTP connection (7): taskcluster:80
[task 2024-09-03T19:53:53.594Z] 2024-09-03 19:53:53,593 - INFO - Creating task with taskId Crn5PXBVT2yvuvdQYRSsqA for check-type
[task 2024-09-03T19:53:53.595Z] 2024-09-03 19:53:53,595 - DEBUG - Starting new HTTP connection (8): taskcluster:80
[task 2024-09-03T19:53:53.706Z] 2024-09-03 19:53:53,706 - DEBUG - http://taskcluster:80 "PUT /queue/v1/task/T0dQTCHvT0q9Yy2O-mjPUQ HTTP/1.1" 200 434
[task 2024-09-03T19:53:53.709Z] 2024-09-03 19:53:53,709 - DEBUG - http://taskcluster:80 "PUT /queue/v1/task/Crn5PXBVT2yvuvdQYRSsqA HTTP/1.1" 200 434
[task 2024-09-03T19:53:53.715Z] 2024-09-03 19:53:53,714 - DEBUG - http://taskcluster:80 "PUT /queue/v1/task/WLWWp-1nQjy6Ps9IHbCc1w HTTP/1.1" 200 434
[task 2024-09-03T19:53:53.718Z] 2024-09-03 19:53:53,718 - DEBUG - http://taskcluster:80 "PUT /queue/v1/task/OsJlz7GHT-WcLUv5oNvWUg HTTP/1.1" 200 434
[task 2024-09-03T19:53:53.724Z] 2024-09-03 19:53:53,724 - DEBUG - http://taskcluster:80 "PUT /queue/v1/task/Kh-zGjRMQeqtbLobZlBRcQ HTTP/1.1" 200 434
[task 2024-09-03T19:53:53.725Z] 2024-09-03 19:53:53,725 - DEBUG - http://taskcluster:80 "PUT /queue/v1/task/Xvqoy70mTH-926F3DpOqfA HTTP/1.1" 200 434
[task 2024-09-03T19:53:53.731Z] 2024-09-03 19:53:53,731 - DEBUG - http://taskcluster:80 "PUT /queue/v1/task/YhbFSTw-QXWJMF9hiNBvdQ HTTP/1.1" 200 436
[task 2024-09-03T19:53:53.744Z] 2024-09-03 19:53:53,743 - DEBUG - http://taskcluster:80 "PUT /queue/v1/task/DNCIb10GQtutgEPW7OLlbQ HTTP/1.1" 200 434
[taskcluster 2024-09-03 19:53:53.999Z] === Task Finished ===
[taskcluster 2024-09-03 19:53:56.234Z] Successful task run with exit code: 0 completed in 22.594 seconds