2023-09-26T02:30:00,794 Created temporary directory: /tmp/pip-build-tracker-vrn5herv
2023-09-26T02:30:00,795 Initialized build tracking at /tmp/pip-build-tracker-vrn5herv
2023-09-26T02:30:00,796 Created build tracker: /tmp/pip-build-tracker-vrn5herv
2023-09-26T02:30:00,796 Entered build tracker: /tmp/pip-build-tracker-vrn5herv
2023-09-26T02:30:00,797 Created temporary directory: /tmp/pip-wheel-up9m6ibu
2023-09-26T02:30:00,800 Created temporary directory: /tmp/pip-ephem-wheel-cache-x5132ejp
2023-09-26T02:30:00,821 Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple
2023-09-26T02:30:00,825 2 location(s) to search for versions of robocorp-log-pytest:
2023-09-26T02:30:00,825 * https://pypi.org/simple/robocorp-log-pytest/
2023-09-26T02:30:00,825 * https://www.piwheels.org/simple/robocorp-log-pytest/
2023-09-26T02:30:00,826 Fetching project page and analyzing links: https://pypi.org/simple/robocorp-log-pytest/
2023-09-26T02:30:00,827 Getting page https://pypi.org/simple/robocorp-log-pytest/
2023-09-26T02:30:00,828 Found index url https://pypi.org/simple/
2023-09-26T02:30:01,100 Fetched page https://pypi.org/simple/robocorp-log-pytest/ as application/vnd.pypi.simple.v1+json
2023-09-26T02:30:01,102   Skipping link: No binaries permitted for robocorp-log-pytest: https://files.pythonhosted.org/packages/e0/5a/235783efb4d4d6a0bc7b7382209ed7de91027a912045ec9864570c6c3974/robocorp_log_pytest-0.0.1-py3-none-any.whl (from https://pypi.org/simple/robocorp-log-pytest/) (requires-python:>=3.9,<4.0)
2023-09-26T02:30:01,103   Found link https://files.pythonhosted.org/packages/cf/0b/a05807f2835480eaf370f3f453e11f3d97c1deea30f1dc1f26145b612cdd/robocorp_log_pytest-0.0.1.tar.gz (from https://pypi.org/simple/robocorp-log-pytest/) (requires-python:>=3.9,<4.0), version: 0.0.1
2023-09-26T02:30:01,103 Fetching project page and analyzing links: https://www.piwheels.org/simple/robocorp-log-pytest/
2023-09-26T02:30:01,104 Getting page https://www.piwheels.org/simple/robocorp-log-pytest/
2023-09-26T02:30:01,105 Found index url https://www.piwheels.org/simple/
2023-09-26T02:30:01,265 Fetched page https://www.piwheels.org/simple/robocorp-log-pytest/ as text/html
2023-09-26T02:30:01,266 Skipping link: not a file: https://www.piwheels.org/simple/robocorp-log-pytest/
2023-09-26T02:30:01,267 Skipping link: not a file: https://pypi.org/simple/robocorp-log-pytest/
2023-09-26T02:30:01,285 Given no hashes to check 1 links for project 'robocorp-log-pytest': discarding no candidates
2023-09-26T02:30:01,303 Collecting robocorp-log-pytest==0.0.1
2023-09-26T02:30:01,306   Created temporary directory: /tmp/pip-unpack-eo_cnxho
2023-09-26T02:30:01,531   Downloading robocorp_log_pytest-0.0.1.tar.gz (3.8 kB)
2023-09-26T02:30:01,548   Added robocorp-log-pytest==0.0.1 from https://files.pythonhosted.org/packages/cf/0b/a05807f2835480eaf370f3f453e11f3d97c1deea30f1dc1f26145b612cdd/robocorp_log_pytest-0.0.1.tar.gz to build tracker '/tmp/pip-build-tracker-vrn5herv'
2023-09-26T02:30:01,553   Created temporary directory: /tmp/pip-build-env-phyg6sox
2023-09-26T02:30:01,558   Installing build dependencies: started
2023-09-26T02:30:01,559   Running command pip subprocess to install build dependencies
2023-09-26T02:30:02,703   Using pip 23.2.1 from /usr/local/lib/python3.11/dist-packages/pip (python 3.11)
2023-09-26T02:30:03,219   Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple
2023-09-26T02:30:03,713   Collecting poetry-core
2023-09-26T02:30:03,758     Using cached https://www.piwheels.org/simple/poetry-core/poetry_core-1.7.0-py3-none-any.whl (426 kB)
2023-09-26T02:30:05,996   Installing collected packages: poetry-core
2023-09-26T02:30:07,716   Successfully installed poetry-core-1.7.0
2023-09-26T02:30:08,258   Installing build dependencies: finished with status 'done'
2023-09-26T02:30:08,261   Getting requirements to build wheel: started
2023-09-26T02:30:08,263   Running command Getting requirements to build wheel
2023-09-26T02:30:08,604   Getting requirements to build wheel: finished with status 'done'
2023-09-26T02:30:08,607   Created temporary directory: /tmp/pip-modern-metadata-njseil0y
2023-09-26T02:30:08,609   Preparing metadata (pyproject.toml): started
2023-09-26T02:30:08,611   Running command Preparing metadata (pyproject.toml)
2023-09-26T02:30:09,344   Traceback (most recent call last):
2023-09-26T02:30:09,344     File "/usr/local/lib/python3.11/dist-packages/pip/_vendor/pyproject_hooks/_in_process/_in_process.py", line 353, in <module>
2023-09-26T02:30:09,344       main()
2023-09-26T02:30:09,345     File "/usr/local/lib/python3.11/dist-packages/pip/_vendor/pyproject_hooks/_in_process/_in_process.py", line 335, in main
2023-09-26T02:30:09,345       json_out['return_val'] = hook(**hook_input['kwargs'])
2023-09-26T02:30:09,346                                ^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-09-26T02:30:09,346     File "/usr/local/lib/python3.11/dist-packages/pip/_vendor/pyproject_hooks/_in_process/_in_process.py", line 149, in prepare_metadata_for_build_wheel
2023-09-26T02:30:09,347       return hook(metadata_directory, config_settings)
2023-09-26T02:30:09,347              ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-09-26T02:30:09,348     File "/tmp/pip-build-env-phyg6sox/overlay/local/lib/python3.11/dist-packages/poetry/core/masonry/api.py", line 42, in prepare_metadata_for_build_wheel
2023-09-26T02:30:09,348       builder = WheelBuilder(poetry)
2023-09-26T02:30:09,349                 ^^^^^^^^^^^^^^^^^^^^
2023-09-26T02:30:09,349     File "/tmp/pip-build-env-phyg6sox/overlay/local/lib/python3.11/dist-packages/poetry/core/masonry/builders/wheel.py", line 61, in __init__
2023-09-26T02:30:09,350       super().__init__(poetry, executable=executable)
2023-09-26T02:30:09,350     File "/tmp/pip-build-env-phyg6sox/overlay/local/lib/python3.11/dist-packages/poetry/core/masonry/builders/builder.py", line 92, in __init__
2023-09-26T02:30:09,351       self._meta = Metadata.from_package(self._package)
2023-09-26T02:30:09,352                    ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-09-26T02:30:09,353     File "/tmp/pip-build-env-phyg6sox/overlay/local/lib/python3.11/dist-packages/poetry/core/masonry/metadata.py", line 58, in from_package
2023-09-26T02:30:09,354       with readme.open(encoding="utf-8") as f:
2023-09-26T02:30:09,355            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-09-26T02:30:09,356     File "/usr/lib/python3.11/pathlib.py", line 1045, in open
2023-09-26T02:30:09,356       return io.open(self, mode, buffering, encoding, errors, newline)
2023-09-26T02:30:09,357              ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-09-26T02:30:09,358   FileNotFoundError: [Errno 2] No such file or directory: '/tmp/pip-wheel-up9m6ibu/robocorp-log-pytest_11e52a05dcd84aabbff8a5d288ff56bc/README.md'
2023-09-26T02:30:09,436   ERROR: [present-rich] Preparing metadata (pyproject.toml) exited with 1
2023-09-26T02:30:09,450   [bold magenta]full command[/]: [blue]/usr/bin/python3 /usr/local/lib/python3.11/dist-packages/pip/_vendor/pyproject_hooks/_in_process/_in_process.py prepare_metadata_for_build_wheel /tmp/tmp95zqvx4_[/]
2023-09-26T02:30:09,451   [bold magenta]cwd[/]: /tmp/pip-wheel-up9m6ibu/robocorp-log-pytest_11e52a05dcd84aabbff8a5d288ff56bc
2023-09-26T02:30:09,451   Preparing metadata (pyproject.toml): finished with status 'error'
2023-09-26T02:30:09,454 ERROR: [present-rich] metadata generation failed
2023-09-26T02:30:09,459 Exception information:
2023-09-26T02:30:09,459 Traceback (most recent call last):
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_internal/operations/build/metadata.py", line 35, in generate_metadata
2023-09-26T02:30:09,459     distinfo_dir = backend.prepare_metadata_for_build_wheel(metadata_dir)
2023-09-26T02:30:09,459                    ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_internal/utils/misc.py", line 718, in prepare_metadata_for_build_wheel
2023-09-26T02:30:09,459     return super().prepare_metadata_for_build_wheel(
2023-09-26T02:30:09,459            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_vendor/pyproject_hooks/_impl.py", line 186, in prepare_metadata_for_build_wheel
2023-09-26T02:30:09,459     return self._call_hook('prepare_metadata_for_build_wheel', {
2023-09-26T02:30:09,459            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_vendor/pyproject_hooks/_impl.py", line 311, in _call_hook
2023-09-26T02:30:09,459     self._subprocess_runner(
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_internal/utils/subprocess.py", line 252, in runner
2023-09-26T02:30:09,459     call_subprocess(
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_internal/utils/subprocess.py", line 224, in call_subprocess
2023-09-26T02:30:09,459     raise error
2023-09-26T02:30:09,459 pip._internal.exceptions.InstallationSubprocessError: Preparing metadata (pyproject.toml) exited with 1
2023-09-26T02:30:09,459 
2023-09-26T02:30:09,459 The above exception was the direct cause of the following exception:
2023-09-26T02:30:09,459 
2023-09-26T02:30:09,459 Traceback (most recent call last):
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_internal/cli/base_command.py", line 180, in exc_logging_wrapper
2023-09-26T02:30:09,459     status = run_func(*args)
2023-09-26T02:30:09,459              ^^^^^^^^^^^^^^^
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_internal/cli/req_command.py", line 248, in wrapper
2023-09-26T02:30:09,459     return func(self, options, args)
2023-09-26T02:30:09,459            ^^^^^^^^^^^^^^^^^^^^^^^^^
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_internal/commands/wheel.py", line 147, in run
2023-09-26T02:30:09,459     requirement_set = resolver.resolve(reqs, check_supported_wheels=True)
2023-09-26T02:30:09,459                       ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_internal/resolution/resolvelib/resolver.py", line 92, in resolve
2023-09-26T02:30:09,459     result = self._result = resolver.resolve(
2023-09-26T02:30:09,459                             ^^^^^^^^^^^^^^^^^
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_vendor/resolvelib/resolvers.py", line 546, in resolve
2023-09-26T02:30:09,459     state = resolution.resolve(requirements, max_rounds=max_rounds)
2023-09-26T02:30:09,459             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_vendor/resolvelib/resolvers.py", line 397, in resolve
2023-09-26T02:30:09,459     self._add_to_criteria(self.state.criteria, r, parent=None)
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_vendor/resolvelib/resolvers.py", line 173, in _add_to_criteria
2023-09-26T02:30:09,459     if not criterion.candidates:
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_vendor/resolvelib/structs.py", line 156, in __bool__
2023-09-26T02:30:09,459     return bool(self._sequence)
2023-09-26T02:30:09,459            ^^^^^^^^^^^^^^^^^^^^
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_internal/resolution/resolvelib/found_candidates.py", line 155, in __bool__
2023-09-26T02:30:09,459     return any(self)
2023-09-26T02:30:09,459            ^^^^^^^^^
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_internal/resolution/resolvelib/found_candidates.py", line 143, in <genexpr>
2023-09-26T02:30:09,459     return (c for c in iterator if id(c) not in self._incompatible_ids)
2023-09-26T02:30:09,459            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_internal/resolution/resolvelib/found_candidates.py", line 47, in _iter_built
2023-09-26T02:30:09,459     candidate = func()
2023-09-26T02:30:09,459                 ^^^^^^
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_internal/resolution/resolvelib/factory.py", line 206, in _make_candidate_from_link
2023-09-26T02:30:09,459     self._link_candidate_cache[link] = LinkCandidate(
2023-09-26T02:30:09,459                                        ^^^^^^^^^^^^^^
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_internal/resolution/resolvelib/candidates.py", line 293, in __init__
2023-09-26T02:30:09,459     super().__init__(
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_internal/resolution/resolvelib/candidates.py", line 156, in __init__
2023-09-26T02:30:09,459     self.dist = self._prepare()
2023-09-26T02:30:09,459                 ^^^^^^^^^^^^^^^
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_internal/resolution/resolvelib/candidates.py", line 225, in _prepare
2023-09-26T02:30:09,459     dist = self._prepare_distribution()
2023-09-26T02:30:09,459            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_internal/resolution/resolvelib/candidates.py", line 304, in _prepare_distribution
2023-09-26T02:30:09,459     return preparer.prepare_linked_requirement(self._ireq, parallel_builds=True)
2023-09-26T02:30:09,459            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_internal/operations/prepare.py", line 538, in prepare_linked_requirement
2023-09-26T02:30:09,459     return self._prepare_linked_requirement(req, parallel_builds)
2023-09-26T02:30:09,459            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_internal/operations/prepare.py", line 653, in _prepare_linked_requirement
2023-09-26T02:30:09,459     dist = _get_prepared_distribution(
2023-09-26T02:30:09,459            ^^^^^^^^^^^^^^^^^^^^^^^^^^^
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_internal/operations/prepare.py", line 69, in _get_prepared_distribution
2023-09-26T02:30:09,459     abstract_dist.prepare_distribution_metadata(
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_internal/distributions/sdist.py", line 61, in prepare_distribution_metadata
2023-09-26T02:30:09,459     self.req.prepare_metadata()
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_internal/req/req_install.py", line 562, in prepare_metadata
2023-09-26T02:30:09,459     self.metadata_directory = generate_metadata(
2023-09-26T02:30:09,459                               ^^^^^^^^^^^^^^^^^^
2023-09-26T02:30:09,459   File "/usr/local/lib/python3.11/dist-packages/pip/_internal/operations/build/metadata.py", line 37, in generate_metadata
2023-09-26T02:30:09,459     raise MetadataGenerationFailed(package_details=details) from error
2023-09-26T02:30:09,459 pip._internal.exceptions.MetadataGenerationFailed: metadata generation failed
2023-09-26T02:30:09,474 Removed robocorp-log-pytest==0.0.1 from https://files.pythonhosted.org/packages/cf/0b/a05807f2835480eaf370f3f453e11f3d97c1deea30f1dc1f26145b612cdd/robocorp_log_pytest-0.0.1.tar.gz from build tracker '/tmp/pip-build-tracker-vrn5herv'
2023-09-26T02:30:09,475 Removed build tracker: '/tmp/pip-build-tracker-vrn5herv'