[vcs 2024-07-16T13:38:45.025Z]  * branch              prep-9.2.1 -> FETCH_HEAD
[vcs 2024-07-16T13:38:45.027Z] executing ['git', 'checkout', '-f', '-B', 'prep-9.2.1', 'b5b4f8985e8dcd37432bc5305286e378fabeb872']
[vcs 2024-07-16T13:38:45.041Z] Switched to a new branch 'prep-9.2.1'
[vcs 2024-07-16T13:38:45.042Z] cleaning git checkout...
[vcs 2024-07-16T13:38:45.042Z] executing ['git', 'clean', '-nxdff']
[vcs 2024-07-16T13:38:45.045Z] removing []
[vcs 2024-07-16T13:38:45.045Z] successfully cleaned git checkout!
[vcs 2024-07-16T13:38:45.047Z] TinderboxPrint:<a href='https://github.com/jcristau/taskgraph/commit/b5b4f8985e8dcd37432bc5305286e378fabeb872' title='Built from taskgraph commit b5b4f8985e8dcd37432bc5305286e378fabeb872'>b5b4f8985e8dcd37432bc5305286e378fabeb872</a>
[task 2024-07-16T13:38:45.047Z] 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' --base-repository='https://github.com/taskcluster/taskgraph' --base-ref='main' --base-rev='4974ef2ffbc8b2582943b86fece16941bca45559' --head-repository='https://github.com/jcristau/taskgraph' --head-ref='prep-9.2.1' --head-rev='b5b4f8985e8dcd37432bc5305286e378fabeb872' \n"]
[task 2024-07-16T13:38:45.049Z] + cd /builds/worker/checkouts/src
[task 2024-07-16T13:38:45.049Z] + ln -s /builds/worker/artifacts artifacts
[task 2024-07-16T13:38:45.051Z] + pip3 install --user --break-system-packages .
[task 2024-07-16T13:38:45.382Z] Processing /builds/worker/checkouts/src
[task 2024-07-16T13:38:45.385Z]   Installing build dependencies: started
[task 2024-07-16T13:38:47.182Z]   Installing build dependencies: finished with status 'done'
[task 2024-07-16T13:38:47.185Z]   Getting requirements to build wheel: started
[task 2024-07-16T13:38:47.344Z]   Getting requirements to build wheel: finished with status 'done'
[task 2024-07-16T13:38:47.345Z]   Preparing metadata (pyproject.toml): started
[task 2024-07-16T13:38:47.506Z]   Preparing metadata (pyproject.toml): finished with status 'done'
[task 2024-07-16T13:38:47.517Z] Requirement already satisfied: appdirs>=1.4 in /usr/local/lib/python3.11/dist-packages (from taskcluster-taskgraph==9.2.1) (1.4.4)
[task 2024-07-16T13:38:47.519Z] Requirement already satisfied: cookiecutter~=2.1 in /usr/local/lib/python3.11/dist-packages (from taskcluster-taskgraph==9.2.1) (2.6.0)
[task 2024-07-16T13:38:47.521Z] Requirement already satisfied: json-e>=2.7 in /usr/local/lib/python3.11/dist-packages (from taskcluster-taskgraph==9.2.1) (4.7.0)
[task 2024-07-16T13:38:47.522Z] Requirement already satisfied: mozilla-repo-urls in /usr/local/lib/python3.11/dist-packages (from taskcluster-taskgraph==9.2.1) (0.1.1)
[task 2024-07-16T13:38:47.523Z] Requirement already satisfied: PyYAML>=5.3.1 in /usr/local/lib/python3.11/dist-packages (from taskcluster-taskgraph==9.2.1) (6.0.1)
[task 2024-07-16T13:38:47.524Z] Requirement already satisfied: redo>=2.0 in /usr/local/lib/python3.11/dist-packages (from taskcluster-taskgraph==9.2.1) (2.0.4)
[task 2024-07-16T13:38:47.526Z] Requirement already satisfied: requests>=2.25 in /usr/local/lib/python3.11/dist-packages (from taskcluster-taskgraph==9.2.1) (2.32.3)
[task 2024-07-16T13:38:47.527Z] Requirement already satisfied: slugid>=2.0 in /usr/local/lib/python3.11/dist-packages (from taskcluster-taskgraph==9.2.1) (2.0.0)
[task 2024-07-16T13:38:47.528Z] Requirement already satisfied: taskcluster-urls>=11.0 in /usr/local/lib/python3.11/dist-packages (from taskcluster-taskgraph==9.2.1) (13.0.1)
[task 2024-07-16T13:38:47.530Z] Requirement already satisfied: voluptuous>=0.12.1 in /usr/local/lib/python3.11/dist-packages (from taskcluster-taskgraph==9.2.1) (0.14.2)
[task 2024-07-16T13:38:47.538Z] Requirement already satisfied: binaryornot>=0.4.4 in /usr/local/lib/python3.11/dist-packages (from cookiecutter~=2.1->taskcluster-taskgraph==9.2.1) (0.4.4)
[task 2024-07-16T13:38:47.539Z] Requirement already satisfied: Jinja2<4.0.0,>=2.7 in /usr/local/lib/python3.11/dist-packages (from cookiecutter~=2.1->taskcluster-taskgraph==9.2.1) (3.1.4)
[task 2024-07-16T13:38:47.541Z] Requirement already satisfied: click<9.0.0,>=7.0 in /usr/local/lib/python3.11/dist-packages (from cookiecutter~=2.1->taskcluster-taskgraph==9.2.1) (8.1.7)
[task 2024-07-16T13:38:47.542Z] Requirement already satisfied: python-slugify>=4.0.0 in /usr/local/lib/python3.11/dist-packages (from cookiecutter~=2.1->taskcluster-taskgraph==9.2.1) (8.0.4)
[task 2024-07-16T13:38:47.544Z] Requirement already satisfied: arrow in /usr/local/lib/python3.11/dist-packages (from cookiecutter~=2.1->taskcluster-taskgraph==9.2.1) (1.3.0)
[task 2024-07-16T13:38:47.545Z] Requirement already satisfied: rich in /usr/local/lib/python3.11/dist-packages (from cookiecutter~=2.1->taskcluster-taskgraph==9.2.1) (13.7.1)
[task 2024-07-16T13:38:47.560Z] Requirement already satisfied: charset-normalizer<4,>=2 in /usr/local/lib/python3.11/dist-packages (from requests>=2.25->taskcluster-taskgraph==9.2.1) (3.3.2)
[task 2024-07-16T13:38:47.562Z] Requirement already satisfied: idna<4,>=2.5 in /usr/local/lib/python3.11/dist-packages (from requests>=2.25->taskcluster-taskgraph==9.2.1) (3.7)
[task 2024-07-16T13:38:47.564Z] Requirement already satisfied: urllib3<3,>=1.21.1 in /usr/local/lib/python3.11/dist-packages (from requests>=2.25->taskcluster-taskgraph==9.2.1) (2.2.1)
[task 2024-07-16T13:38:47.565Z] Requirement already satisfied: certifi>=2017.4.17 in /usr/local/lib/python3.11/dist-packages (from requests>=2.25->taskcluster-taskgraph==9.2.1) (2024.6.2)
[task 2024-07-16T13:38:47.579Z] Requirement already satisfied: giturlparse in /usr/local/lib/python3.11/dist-packages (from mozilla-repo-urls->taskcluster-taskgraph==9.2.1) (0.12.0)
[task 2024-07-16T13:38:47.585Z] 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==9.2.1) (5.2.0)
[task 2024-07-16T13:38:47.613Z] 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==9.2.1) (2.1.5)
[task 2024-07-16T13:38:47.621Z] 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==9.2.1) (1.3)
[task 2024-07-16T13:38:47.650Z] Requirement already satisfied: python-dateutil>=2.7.0 in /usr/local/lib/python3.11/dist-packages (from arrow->cookiecutter~=2.1->taskcluster-taskgraph==9.2.1) (2.9.0.post0)
[task 2024-07-16T13:38:47.651Z] Requirement already satisfied: types-python-dateutil>=2.8.10 in /usr/local/lib/python3.11/dist-packages (from arrow->cookiecutter~=2.1->taskcluster-taskgraph==9.2.1) (2.9.0.20240316)
[task 2024-07-16T13:38:47.669Z] Requirement already satisfied: markdown-it-py>=2.2.0 in /usr/local/lib/python3.11/dist-packages (from rich->cookiecutter~=2.1->taskcluster-taskgraph==9.2.1) (3.0.0)
[task 2024-07-16T13:38:47.670Z] 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==9.2.1) (2.18.0)
[task 2024-07-16T13:38:47.703Z] 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==9.2.1) (0.1.2)
[task 2024-07-16T13:38:47.729Z] 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==9.2.1) (1.16.0)
[task 2024-07-16T13:38:47.772Z] Building wheels for collected packages: taskcluster-taskgraph
[task 2024-07-16T13:38:47.773Z]   Building wheel for taskcluster-taskgraph (pyproject.toml): started
[task 2024-07-16T13:38:48.011Z]   Building wheel for taskcluster-taskgraph (pyproject.toml): finished with status 'done'
[task 2024-07-16T13:38:48.013Z]   Created wheel for taskcluster-taskgraph: filename=taskcluster_taskgraph-9.2.1-py3-none-any.whl size=191165 sha256=82df8b1328167402381bce21da4ec614310a312505c39e2382803df5447aff0d
[task 2024-07-16T13:38:48.015Z]   Stored in directory: /tmp/pip-ephem-wheel-cache-qbojg9au/wheels/54/fa/b4/d3017bf051c083cc076bfd93af1693baf4c91b8f6fee74b0bc
[task 2024-07-16T13:38:48.017Z] Successfully built taskcluster-taskgraph
[task 2024-07-16T13:38:48.101Z] Installing collected packages: taskcluster-taskgraph
[task 2024-07-16T13:38:48.185Z]   WARNING: The script taskgraph is installed in '/builds/worker/.local/bin' which is not on PATH.
[task 2024-07-16T13:38:48.185Z]   Consider adding this directory to PATH or, if you prefer to suppress this warning, use --no-warn-script-location.
[task 2024-07-16T13:38:48.189Z] Successfully installed taskcluster-taskgraph-9.2.1
[task 2024-07-16T13:38:48.257Z] + taskgraph decision --verbose --pushlog-id=0 --pushdate=0 --project=taskgraph [email protected] --level=1 --repository-type=git --tasks-for=github-pull-request --base-repository=https://github.com/taskcluster/taskgraph --base-ref=main --base-rev=4974ef2ffbc8b2582943b86fece16941bca45559 --head-repository=https://github.com/jcristau/taskgraph --head-ref=prep-9.2.1 --head-rev=b5b4f8985e8dcd37432bc5305286e378fabeb872
[task 2024-07-16T13:38:48.563Z] 2024-07-16 13:38:48,563 - INFO - Loading graph configuration.
[task 2024-07-16T13:38:48.564Z] 2024-07-16 13:38:48,563 - DEBUG - loading config from `taskcluster/config.yml`
[task 2024-07-16T13:38:48.571Z] 2024-07-16 13:38:48,570 - 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-07-16T13:38:48.572Z] 2024-07-16 13:38:48,571 - INFO - Using Parameters(id=881bf0da6517) (from defaults)
[task 2024-07-16T13:38:48.572Z] 2024-07-16 13:38:48,572 - DEBUG - Dumping parameters:
[task 2024-07-16T13:38:48.573Z] { 'base_ref': 'main',
[task 2024-07-16T13:38:48.574Z]   'base_repository': 'https://github.com/taskcluster/taskgraph',
[task 2024-07-16T13:38:48.575Z]   'base_rev': '4974ef2ffbc8b2582943b86fece16941bca45559',
[task 2024-07-16T13:38:48.576Z]   'build_date': 1721137128,
[task 2024-07-16T13:38:48.577Z]   'build_number': 1,
[task 2024-07-16T13:38:48.578Z]   'do_not_optimize': [],
[task 2024-07-16T13:38:48.578Z]   'enable_always_target': True,
[task 2024-07-16T13:38:48.578Z]   'existing_tasks': {},
[task 2024-07-16T13:38:48.578Z]   'files_changed': ['CHANGELOG.md', 'src/taskgraph/__init__.py'],
[task 2024-07-16T13:38:48.578Z]   'filters': ['target_tasks_method'],
[task 2024-07-16T13:38:48.578Z]   'head_ref': 'prep-9.2.1',
[task 2024-07-16T13:38:48.578Z]   'head_repository': 'https://github.com/jcristau/taskgraph',
[task 2024-07-16T13:38:48.578Z]   'head_rev': 'b5b4f8985e8dcd37432bc5305286e378fabeb872',
[task 2024-07-16T13:38:48.578Z]   'head_tag': '',
[task 2024-07-16T13:38:48.578Z]   'level': '1',
[task 2024-07-16T13:38:48.578Z]   'moz_build_date': '20240716133848',
[task 2024-07-16T13:38:48.578Z]   'next_version': None,
[task 2024-07-16T13:38:48.578Z]   'optimize_strategies': None,
[task 2024-07-16T13:38:48.578Z]   'optimize_target_tasks': True,
[task 2024-07-16T13:38:48.579Z]   'owner': '[email protected]',
[task 2024-07-16T13:38:48.579Z]   'project': 'taskgraph',
[task 2024-07-16T13:38:48.580Z]   'pushdate': 0,
[task 2024-07-16T13:38:48.581Z]   'pushlog_id': '0',
[task 2024-07-16T13:38:48.581Z]   'repository_type': 'git',
[task 2024-07-16T13:38:48.581Z]   'target_tasks_method': 'default',
[task 2024-07-16T13:38:48.581Z]   'tasks_for': 'github-pull-request',
[task 2024-07-16T13:38:48.581Z]   'version': None}
[task 2024-07-16T13:38:48.581Z] 2024-07-16 13:38:48,572 - INFO - writing artifact file `parameters.yml`
[task 2024-07-16T13:38:48.581Z] 2024-07-16 13:38:48,579 - INFO - writing artifact file `actions.json`
[task 2024-07-16T13:38:48.581Z] 2024-07-16 13:38:48,580 - INFO - Loading kinds
[task 2024-07-16T13:38:48.581Z] 2024-07-16 13:38:48,580 - DEBUG - loading kind `doc` from `taskcluster/kinds/doc`
[task 2024-07-16T13:38:48.581Z] 2024-07-16 13:38:48,581 - DEBUG - loading kind `test` from `taskcluster/kinds/test`
[task 2024-07-16T13:38:48.582Z] 2024-07-16 13:38:48,581 - DEBUG - loading kind `check` from `taskcluster/kinds/check`
[task 2024-07-16T13:38:48.582Z] 2024-07-16 13:38:48,582 - DEBUG - loading kind `docker-image` from `taskcluster/kinds/docker-image`
[task 2024-07-16T13:38:48.583Z] 2024-07-16 13:38:48,583 - DEBUG - loading kind `codecov` from `taskcluster/kinds/codecov`
[task 2024-07-16T13:38:48.583Z] 2024-07-16 13:38:48,583 - DEBUG - loading kind `push-image` from `taskcluster/kinds/push-image`
[task 2024-07-16T13:38:48.584Z] 2024-07-16 13:38:48,584 - DEBUG - loading kind `fetch` from `taskcluster/kinds/fetch`
[task 2024-07-16T13:38:48.584Z] 2024-07-16 13:38:48,584 - INFO - Generating full task set
[task 2024-07-16T13:38:48.584Z] 2024-07-16 13:38:48,584 - DEBUG - Loading tasks for kind check
[task 2024-07-16T13:38:48.597Z] 2024-07-16 13:38:48,597 - DEBUG - Generating tasks for check type
[task 2024-07-16T13:38:48.599Z] 2024-07-16 13:38:48,599 - INFO - Generated 1 tasks for kind check
[task 2024-07-16T13:38:48.599Z] 2024-07-16 13:38:48,599 - DEBUG - Loading tasks for kind doc
[task 2024-07-16T13:38:48.600Z] 2024-07-16 13:38:48,600 - DEBUG - Generating tasks for doc generate
[task 2024-07-16T13:38:48.601Z] 2024-07-16 13:38:48,601 - INFO - Generated 1 tasks for kind doc
[task 2024-07-16T13:38:48.602Z] 2024-07-16 13:38:48,601 - DEBUG - Loading tasks for kind docker-image
[task 2024-07-16T13:38:48.603Z] 2024-07-16 13:38:48,603 - DEBUG - Generating tasks for docker-image decision
[task 2024-07-16T13:38:48.604Z] 2024-07-16 13:38:48,603 - DEBUG - Generating tasks for docker-image fetch
[task 2024-07-16T13:38:48.604Z] 2024-07-16 13:38:48,603 - DEBUG - Generating tasks for docker-image index-task
[task 2024-07-16T13:38:48.605Z] 2024-07-16 13:38:48,603 - DEBUG - Generating tasks for docker-image py38
[task 2024-07-16T13:38:48.606Z] 2024-07-16 13:38:48,603 - DEBUG - Generating tasks for docker-image py39
[task 2024-07-16T13:38:48.606Z] 2024-07-16 13:38:48,603 - DEBUG - Generating tasks for docker-image py310
[task 2024-07-16T13:38:48.607Z] 2024-07-16 13:38:48,603 - DEBUG - Generating tasks for docker-image py311
[task 2024-07-16T13:38:48.608Z] 2024-07-16 13:38:48,603 - DEBUG - Generating tasks for docker-image py312
[task 2024-07-16T13:38:48.608Z] 2024-07-16 13:38:48,603 - DEBUG - Generating tasks for docker-image run-task
[task 2024-07-16T13:38:48.608Z] 2024-07-16 13:38:48,604 - DEBUG - Generating tasks for docker-image skopeo
[task 2024-07-16T13:38:48.608Z] 2024-07-16 13:38:48,604 - INFO - Writing docker-contexts/decision.tar.gz for docker image decision
[task 2024-07-16T13:38:48.691Z] 2024-07-16 13:38:48,691 - INFO - Writing docker-contexts/fetch.tar.gz for docker image fetch
[task 2024-07-16T13:38:48.703Z] 2024-07-16 13:38:48,703 - INFO - Writing docker-contexts/index-task.tar.gz for docker image index-task
[task 2024-07-16T13:38:48.704Z] 2024-07-16 13:38:48,704 - INFO - Writing docker-contexts/py38.tar.gz for docker image py38
[task 2024-07-16T13:38:48.721Z] 2024-07-16 13:38:48,721 - INFO - Writing docker-contexts/py39.tar.gz for docker image py39
[task 2024-07-16T13:38:48.737Z] 2024-07-16 13:38:48,737 - INFO - Writing docker-contexts/py310.tar.gz for docker image py310
[task 2024-07-16T13:38:48.754Z] 2024-07-16 13:38:48,754 - INFO - Writing docker-contexts/py311.tar.gz for docker image py311
[task 2024-07-16T13:38:48.772Z] 2024-07-16 13:38:48,772 - INFO - Writing docker-contexts/py312.tar.gz for docker image py312
[task 2024-07-16T13:38:48.788Z] 2024-07-16 13:38:48,788 - INFO - Writing docker-contexts/run-task.tar.gz for docker image run-task
[task 2024-07-16T13:38:48.804Z] 2024-07-16 13:38:48,804 - INFO - Writing docker-contexts/skopeo.tar.gz for docker image skopeo
[task 2024-07-16T13:38:48.823Z] 2024-07-16 13:38:48,823 - INFO - Generated 10 tasks for kind docker-image
[task 2024-07-16T13:38:48.824Z] 2024-07-16 13:38:48,823 - DEBUG - Loading tasks for kind fetch
[task 2024-07-16T13:38:48.826Z] 2024-07-16 13:38:48,826 - DEBUG - Generating tasks for fetch codecov-uploader
[task 2024-07-16T13:38:48.827Z] 2024-07-16 13:38:48,827 - INFO - Generated 1 tasks for kind fetch
[task 2024-07-16T13:38:48.827Z] 2024-07-16 13:38:48,827 - DEBUG - Loading tasks for kind push-image
[task 2024-07-16T13:38:48.829Z] 2024-07-16 13:38:48,829 - DEBUG - Generating tasks for push-image decision
[task 2024-07-16T13:38:48.830Z] 2024-07-16 13:38:48,830 - DEBUG - Generating tasks for push-image run-task
[task 2024-07-16T13:38:48.831Z] 2024-07-16 13:38:48,831 - INFO - Generated 2 tasks for kind push-image
[task 2024-07-16T13:38:48.832Z] 2024-07-16 13:38:48,831 - DEBUG - Loading tasks for kind test
[task 2024-07-16T13:38:48.833Z] 2024-07-16 13:38:48,833 - DEBUG - Generating tasks for test unit
[task 2024-07-16T13:38:48.836Z] 2024-07-16 13:38:48,836 - INFO - Generated 5 tasks for kind test
[task 2024-07-16T13:38:48.836Z] 2024-07-16 13:38:48,836 - DEBUG - Loading tasks for kind codecov
[task 2024-07-16T13:38:48.838Z] 2024-07-16 13:38:48,838 - DEBUG - Generating tasks for codecov upload
[task 2024-07-16T13:38:48.839Z] 2024-07-16 13:38:48,839 - INFO - Generated 1 tasks for kind codecov
[task 2024-07-16T13:38:48.840Z] 2024-07-16 13:38:48,839 - INFO - Generating full task graph
[task 2024-07-16T13:38:48.840Z] 2024-07-16 13:38:48,839 - INFO - Full task graph contains 21 tasks and 20 dependencies
[task 2024-07-16T13:38:48.840Z] 2024-07-16 13:38:48,840 - INFO - writing artifact file `full-task-graph.json`
[task 2024-07-16T13:38:48.844Z] 2024-07-16 13:38:48,844 - INFO - writing artifact file `runnable-jobs.json`
[task 2024-07-16T13:38:48.845Z] 2024-07-16 13:38:48,845 - INFO - Generating target task set
[task 2024-07-16T13:38:48.845Z] 2024-07-16 13:38:48,845 - INFO - Filter filter_target_tasks pruned 13 tasks (8 remain)
[task 2024-07-16T13:38:48.845Z] 2024-07-16 13:38:48,845 - INFO - writing artifact file `target-tasks.json`
[task 2024-07-16T13:38:48.846Z] 2024-07-16 13:38:48,845 - INFO - Generating target task graph
[task 2024-07-16T13:38:48.846Z] 2024-07-16 13:38:48,845 - INFO - Adding 10 tasks with `always_target` attribute
[task 2024-07-16T13:38:48.846Z] 2024-07-16 13:38:48,846 - INFO - Generating optimized task graph
[task 2024-07-16T13:38:48.846Z] 2024-07-16 13:38:48,846 - DEBUG - optimize: build-docker-image-decision kept because of index-search (index-search)
[task 2024-07-16T13:38:48.846Z] 2024-07-16 13:38:48,846 - DEBUG - optimize: build-docker-image-index-task kept because of index-search (index-search)
[task 2024-07-16T13:38:48.846Z] 2024-07-16 13:38:48,846 - DEBUG - optimize: build-docker-image-run-task kept because of dependent tasks
[task 2024-07-16T13:38:48.847Z] 2024-07-16 13:38:48,846 - DEBUG - optimize: build-docker-image-skopeo kept because of index-search (index-search)
[task 2024-07-16T13:38:48.847Z] 2024-07-16 13:38:48,846 - DEBUG - optimize: check-type kept because of never
[task 2024-07-16T13:38:48.847Z] 2024-07-16 13:38:48,846 - DEBUG - optimize: codecov-upload kept because of never
[task 2024-07-16T13:38:48.847Z] 2024-07-16 13:38:48,846 - DEBUG - optimize: doc-generate kept because of never
[task 2024-07-16T13:38:48.847Z] 2024-07-16 13:38:48,846 - DEBUG - optimize: fetch-codecov-uploader kept because of dependent tasks
[task 2024-07-16T13:38:48.847Z] 2024-07-16 13:38:48,846 - DEBUG - optimize: test-unit-py310 kept because of dependent tasks
[task 2024-07-16T13:38:48.847Z] 2024-07-16 13:38:48,846 - DEBUG - optimize: test-unit-py311 kept because of dependent tasks
[task 2024-07-16T13:38:48.847Z] 2024-07-16 13:38:48,846 - DEBUG - optimize: test-unit-py312 kept because of dependent tasks
[task 2024-07-16T13:38:48.847Z] 2024-07-16 13:38:48,846 - DEBUG - optimize: test-unit-py38 kept because of dependent tasks
[task 2024-07-16T13:38:48.847Z] 2024-07-16 13:38:48,847 - DEBUG - optimize: test-unit-py39 kept because of dependent tasks
[task 2024-07-16T13:38:48.847Z] 2024-07-16 13:38:48,847 - DEBUG - optimize: build-docker-image-fetch kept because of dependent tasks
[task 2024-07-16T13:38:48.847Z] 2024-07-16 13:38:48,847 - DEBUG - optimize: build-docker-image-py310 kept because of dependent tasks
[task 2024-07-16T13:38:48.847Z] 2024-07-16 13:38:48,847 - DEBUG - optimize: build-docker-image-py311 kept because of dependent tasks
[task 2024-07-16T13:38:48.847Z] 2024-07-16 13:38:48,847 - DEBUG - optimize: build-docker-image-py312 kept because of dependent tasks
[task 2024-07-16T13:38:48.847Z] 2024-07-16 13:38:48,847 - DEBUG - optimize: build-docker-image-py38 kept because of dependent tasks
[task 2024-07-16T13:38:48.847Z] 2024-07-16 13:38:48,847 - DEBUG - optimize: build-docker-image-py39 kept because of dependent tasks
[task 2024-07-16T13:38:48.847Z] 2024-07-16 13:38:48,847 - INFO - No tasks removed during optimization
[task 2024-07-16T13:38:48.847Z] 2024-07-16 13:38:48,847 - DEBUG - Running in Taskcluster instance https://firefox-ci-tc.services.mozilla.com with taskcluster-proxy
[task 2024-07-16T13:38:48.849Z] 2024-07-16 13:38:48,849 - DEBUG - Starting new HTTPS connection (1): firefox-ci-tc.services.mozilla.com:443
[task 2024-07-16T13:38:48.914Z] 2024-07-16 13:38:48,914 - DEBUG - https://firefox-ci-tc.services.mozilla.com:443 "POST /api/index/v1/tasks/indexes HTTP/1.1" 200 None
[task 2024-07-16T13:38:48.976Z] 2024-07-16 13:38:48,976 - DEBUG - https://firefox-ci-tc.services.mozilla.com:443 "POST /api/queue/v1/tasks/status HTTP/1.1" 200 None
[task 2024-07-16T13:38:48.978Z] 2024-07-16 13:38:48,977 - DEBUG - replace_tasks: build-docker-image-fetch
[task 2024-07-16T13:38:48.980Z] 2024-07-16 13:38:48,979 - DEBUG - replace_tasks: build-docker-image-fetch replaced with VWGR3O0BS4OCbwEQIOmYpw by optimization strategy
[task 2024-07-16T13:38:48.980Z] 2024-07-16 13:38:48,980 - DEBUG - replace_tasks: build-docker-image-index-task
[task 2024-07-16T13:38:48.980Z] 2024-07-16 13:38:48,980 - DEBUG - replace_tasks: build-docker-image-index-task replaced with U06WlfMUQxKrRvhL_2TLaQ by optimization strategy
[task 2024-07-16T13:38:48.980Z] 2024-07-16 13:38:48,980 - DEBUG - replace_tasks: build-docker-image-py310
[task 2024-07-16T13:38:48.980Z] 2024-07-16 13:38:48,980 - DEBUG - replace_tasks: build-docker-image-py310 replaced with KU-Zx0W0Rk6l1gq8fAfomg by optimization strategy
[task 2024-07-16T13:38:48.980Z] 2024-07-16 13:38:48,980 - DEBUG - replace_tasks: build-docker-image-py311
[task 2024-07-16T13:38:48.980Z] 2024-07-16 13:38:48,980 - DEBUG - replace_tasks: build-docker-image-py311 replaced with XqtUkrVvSKuIux6fTGOyfQ by optimization strategy
[task 2024-07-16T13:38:48.981Z] 2024-07-16 13:38:48,981 - DEBUG - replace_tasks: build-docker-image-py312
[task 2024-07-16T13:38:48.981Z] 2024-07-16 13:38:48,981 - DEBUG - replace_tasks: build-docker-image-py312 replaced with bRLe1J1-TeOsoM1H5gS7xg by optimization strategy
[task 2024-07-16T13:38:48.981Z] 2024-07-16 13:38:48,981 - DEBUG - replace_tasks: build-docker-image-py38
[task 2024-07-16T13:38:48.981Z] 2024-07-16 13:38:48,981 - DEBUG - replace_tasks: build-docker-image-py38 replaced with EaTEa4S2QoyAvl6FvATsZw by optimization strategy
[task 2024-07-16T13:38:48.982Z] 2024-07-16 13:38:48,981 - DEBUG - replace_tasks: build-docker-image-py39
[task 2024-07-16T13:38:48.982Z] 2024-07-16 13:38:48,982 - DEBUG - replace_tasks: build-docker-image-py39 replaced with b9CEpI3SSMucqbLiYym88A by optimization strategy
[task 2024-07-16T13:38:48.982Z] 2024-07-16 13:38:48,982 - DEBUG - replace_tasks: build-docker-image-run-task
[task 2024-07-16T13:38:48.982Z] 2024-07-16 13:38:48,982 - DEBUG - replace_tasks: build-docker-image-run-task replaced with dpjWsNZ3SLmUBaGqt-4Jdg by optimization strategy
[task 2024-07-16T13:38:48.983Z] 2024-07-16 13:38:48,983 - DEBUG - replace_tasks: build-docker-image-skopeo
[task 2024-07-16T13:38:48.983Z] 2024-07-16 13:38:48,983 - DEBUG - replace_tasks: build-docker-image-skopeo replaced with Osa3puSiR1S77zp3Qb-PSg by optimization strategy
[task 2024-07-16T13:38:48.983Z] 2024-07-16 13:38:48,983 - DEBUG - replace_tasks: check-type
[task 2024-07-16T13:38:48.983Z] 2024-07-16 13:38:48,983 - DEBUG - replace_tasks: check-type kept by optimization strategy
[task 2024-07-16T13:38:48.983Z] 2024-07-16 13:38:48,983 - DEBUG - replace_tasks: doc-generate
[task 2024-07-16T13:38:48.983Z] 2024-07-16 13:38:48,983 - DEBUG - replace_tasks: doc-generate kept by optimization strategy
[task 2024-07-16T13:38:48.983Z] 2024-07-16 13:38:48,983 - DEBUG - replace_tasks: fetch-codecov-uploader
[task 2024-07-16T13:38:48.984Z] 2024-07-16 13:38:48,984 - DEBUG - replace_tasks: fetch-codecov-uploader replaced with HgnXDRTcSVyuqj9xh3feZA by optimization strategy
[task 2024-07-16T13:38:48.984Z] 2024-07-16 13:38:48,984 - DEBUG - replace_tasks: test-unit-py310
[task 2024-07-16T13:38:48.984Z] 2024-07-16 13:38:48,984 - DEBUG - replace_tasks: test-unit-py310 kept by optimization strategy
[task 2024-07-16T13:38:48.984Z] 2024-07-16 13:38:48,984 - DEBUG - replace_tasks: test-unit-py311
[task 2024-07-16T13:38:48.984Z] 2024-07-16 13:38:48,984 - DEBUG - replace_tasks: test-unit-py311 kept by optimization strategy
[task 2024-07-16T13:38:48.984Z] 2024-07-16 13:38:48,984 - DEBUG - replace_tasks: test-unit-py312
[task 2024-07-16T13:38:48.984Z] 2024-07-16 13:38:48,984 - DEBUG - replace_tasks: test-unit-py312 kept by optimization strategy
[task 2024-07-16T13:38:48.985Z] 2024-07-16 13:38:48,985 - DEBUG - replace_tasks: test-unit-py38
[task 2024-07-16T13:38:48.985Z] 2024-07-16 13:38:48,985 - DEBUG - replace_tasks: test-unit-py38 kept by optimization strategy
[task 2024-07-16T13:38:48.985Z] 2024-07-16 13:38:48,985 - DEBUG - replace_tasks: test-unit-py39
[task 2024-07-16T13:38:48.985Z] 2024-07-16 13:38:48,985 - DEBUG - replace_tasks: test-unit-py39 kept by optimization strategy
[task 2024-07-16T13:38:48.985Z] 2024-07-16 13:38:48,985 - DEBUG - replace_tasks: build-docker-image-decision
[task 2024-07-16T13:38:48.985Z] 2024-07-16 13:38:48,985 - DEBUG - replace_tasks: build-docker-image-decision kept by optimization strategy
[task 2024-07-16T13:38:48.985Z] 2024-07-16 13:38:48,985 - DEBUG - replace_tasks: codecov-upload
[task 2024-07-16T13:38:48.985Z] 2024-07-16 13:38:48,985 - DEBUG - replace_tasks: codecov-upload depends on an unreplaced or unremoved task
[task 2024-07-16T13:38:48.986Z] 2024-07-16 13:38:48,986 - INFO - Replaced 10 tasks by index-search (index-search) during optimization.
[task 2024-07-16T13:38:48.987Z] 2024-07-16 13:38:48,987 - DEBUG - Morphing: adding index tasks
[task 2024-07-16T13:38:48.987Z] 2024-07-16 13:38:48,987 - DEBUG - Morphing: adding code review task
[task 2024-07-16T13:38:48.987Z] 2024-07-16 13:38:48,987 - INFO - writing artifact file `task-graph.json`
[task 2024-07-16T13:38:48.992Z] 2024-07-16 13:38:48,990 - INFO - writing artifact file `label-to-taskid.json`
[task 2024-07-16T13:38:48.993Z] 2024-07-16 13:38:48,992 - INFO - Creating task with taskId YNK7QAJSQYeFv9TL6aZ1vg for test-unit-py312
[task 2024-07-16T13:38:48.994Z] 2024-07-16 13:38:48,993 - DEBUG - Starting new HTTP connection (1): taskcluster:80
[task 2024-07-16T13:38:48.995Z] 2024-07-16 13:38:48,994 - INFO - Creating task with taskId Y0tk5sXNQdejMMikJT-utA for test-unit-py38
[task 2024-07-16T13:38:48.998Z] 2024-07-16 13:38:48,995 - DEBUG - Starting new HTTP connection (2): taskcluster:80
[task 2024-07-16T13:38:49.001Z] 2024-07-16 13:38:48,997 - INFO - Creating task with taskId P2RUpTycQ_ye_r1UH5p4Tg for test-unit-py310
[task 2024-07-16T13:38:49.005Z] 2024-07-16 13:38:48,998 - DEBUG - Starting new HTTP connection (3): taskcluster:80
[task 2024-07-16T13:38:49.007Z] 2024-07-16 13:38:48,999 - INFO - Creating task with taskId UUCuZ90LTL-0TsjHngCbBg for build-docker-image-decision
[task 2024-07-16T13:38:49.012Z] 2024-07-16 13:38:49,000 - DEBUG - Starting new HTTP connection (4): taskcluster:80
[task 2024-07-16T13:38:49.012Z] 2024-07-16 13:38:49,000 - INFO - Creating task with taskId GwV8dxGmQ_ealp4ga_fNKA for test-unit-py311
[task 2024-07-16T13:38:49.013Z] 2024-07-16 13:38:49,002 - DEBUG - Starting new HTTP connection (5): taskcluster:80
[task 2024-07-16T13:38:49.013Z] 2024-07-16 13:38:49,002 - INFO - Creating task with taskId QaKWgiY4Tty6JpbUzF_Qew for doc-generate
[task 2024-07-16T13:38:49.014Z] 2024-07-16 13:38:49,005 - DEBUG - Starting new HTTP connection (6): taskcluster:80
[task 2024-07-16T13:38:49.014Z] 2024-07-16 13:38:49,006 - INFO - Creating task with taskId Z-aA1h1KRgSZwuHLK30KsQ for check-type
[task 2024-07-16T13:38:49.014Z] 2024-07-16 13:38:49,007 - DEBUG - Starting new HTTP connection (7): taskcluster:80
[task 2024-07-16T13:38:49.014Z] 2024-07-16 13:38:49,009 - INFO - Creating task with taskId WKz0oerKRvWul_3e2xgG9w for test-unit-py39
[task 2024-07-16T13:38:49.017Z] 2024-07-16 13:38:49,015 - DEBUG - Starting new HTTP connection (8): taskcluster:80
[task 2024-07-16T13:38:49.126Z] 2024-07-16 13:38:49,126 - DEBUG - http://taskcluster:80 "PUT /queue/v1/task/YNK7QAJSQYeFv9TL6aZ1vg HTTP/1.1" 200 434
[task 2024-07-16T13:38:49.128Z] 2024-07-16 13:38:49,128 - DEBUG - http://taskcluster:80 "PUT /queue/v1/task/UUCuZ90LTL-0TsjHngCbBg HTTP/1.1" 200 436
[task 2024-07-16T13:38:49.130Z] 2024-07-16 13:38:49,130 - DEBUG - http://taskcluster:80 "PUT /queue/v1/task/WKz0oerKRvWul_3e2xgG9w HTTP/1.1" 200 434
[task 2024-07-16T13:38:49.132Z] 2024-07-16 13:38:49,132 - DEBUG - http://taskcluster:80 "PUT /queue/v1/task/Y0tk5sXNQdejMMikJT-utA HTTP/1.1" 200 434
[task 2024-07-16T13:38:49.136Z] 2024-07-16 13:38:49,135 - DEBUG - http://taskcluster:80 "PUT /queue/v1/task/P2RUpTycQ_ye_r1UH5p4Tg HTTP/1.1" 200 434
[task 2024-07-16T13:38:49.138Z] 2024-07-16 13:38:49,138 - DEBUG - http://taskcluster:80 "PUT /queue/v1/task/GwV8dxGmQ_ealp4ga_fNKA HTTP/1.1" 200 434
[task 2024-07-16T13:38:49.139Z] 2024-07-16 13:38:49,139 - INFO - Creating task with taskId JAtN3ArkQ-WORx3wx9Pbtw for codecov-upload
[task 2024-07-16T13:38:49.141Z] 2024-07-16 13:38:49,141 - DEBUG - http://taskcluster:80 "PUT /queue/v1/task/QaKWgiY4Tty6JpbUzF_Qew HTTP/1.1" 200 434
[task 2024-07-16T13:38:49.158Z] 2024-07-16 13:38:49,158 - DEBUG - http://taskcluster:80 "PUT /queue/v1/task/Z-aA1h1KRgSZwuHLK30KsQ HTTP/1.1" 200 434
[task 2024-07-16T13:38:49.317Z] 2024-07-16 13:38:49,317 - DEBUG - http://taskcluster:80 "PUT /queue/v1/task/JAtN3ArkQ-WORx3wx9Pbtw HTTP/1.1" 200 434
[taskcluster 2024-07-16 13:38:49.537Z] === Task Finished ===
[taskcluster 2024-07-16 13:38:51.709Z] Successful task run with exit code: 0 completed in 18.663 seconds