2025-03-24 19:39:34.589439 | Job console starting... 2025-03-24 19:39:34.602951 | Updating repositories 2025-03-24 19:39:34.928448 | Preparing job workspace 2025-03-24 19:39:36.301618 | Running Ansible setup... 2025-03-24 19:39:41.644118 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-24 19:39:42.333179 | 2025-03-24 19:39:42.333348 | PLAY [Base pre] 2025-03-24 19:39:42.364020 | 2025-03-24 19:39:42.364167 | TASK [Setup log path fact] 2025-03-24 19:39:42.398578 | ubuntu-noble | ok 2025-03-24 19:39:42.424463 | 2025-03-24 19:39:42.424623 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-03-24 19:39:42.503064 | ubuntu-noble | ok 2025-03-24 19:39:42.528953 | 2025-03-24 19:39:42.529142 | TASK [emit-job-header : Print job information] 2025-03-24 19:39:42.598546 | # Job Information 2025-03-24 19:39:42.598781 | Ansible Version: 2.15.3 2025-03-24 19:39:42.598835 | Job: container-image-ceph-ansible-build-reef 2025-03-24 19:39:42.598881 | Pipeline: check 2025-03-24 19:39:42.598913 | Executor: 7d211f194f6a 2025-03-24 19:39:42.598941 | Triggered by: https://github.com/osism/container-image-ceph-ansible/pull/570 2025-03-24 19:39:42.599070 | Event ID: 45177c40-08e7-11f0-81ae-9bea12ce1c59 2025-03-24 19:39:42.608957 | 2025-03-24 19:39:42.609074 | LOOP [emit-job-header : Print node information] 2025-03-24 19:39:42.769081 | ubuntu-noble | ok: 2025-03-24 19:39:42.769522 | ubuntu-noble | # Node Information 2025-03-24 19:39:42.769598 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-03-24 19:39:42.769629 | ubuntu-noble | Hostname: ubuntu 2025-03-24 19:39:42.769656 | ubuntu-noble | Username: zuul 2025-03-24 19:39:42.769680 | ubuntu-noble | Distro: Ubuntu 24.04 2025-03-24 19:39:42.769709 | ubuntu-noble | Provider: regiocloud-a 2025-03-24 19:39:42.769733 | ubuntu-noble | Label: ubuntu-noble 2025-03-24 19:39:42.769759 | ubuntu-noble | Product Name: OpenStack Nova 2025-03-24 19:39:42.769783 | ubuntu-noble | Interface IP: 81.163.192.204 2025-03-24 19:39:42.796933 | 2025-03-24 19:39:42.797051 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-24 19:39:43.294922 | ubuntu-noble -> localhost | changed 2025-03-24 19:39:43.310630 | 2025-03-24 19:39:43.310782 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-24 19:39:44.372514 | ubuntu-noble -> localhost | changed 2025-03-24 19:39:44.395477 | 2025-03-24 19:39:44.395613 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-24 19:39:44.695842 | ubuntu-noble -> localhost | ok 2025-03-24 19:39:44.703546 | 2025-03-24 19:39:44.703675 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-24 19:39:44.734585 | ubuntu-noble | ok 2025-03-24 19:39:44.751326 | ubuntu-noble | included: /var/lib/zuul/builds/891e748af8ac47ef94f3ef2b25d0863b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-24 19:39:44.759080 | 2025-03-24 19:39:44.759181 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-24 19:39:45.425474 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-03-24 19:39:45.425689 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/891e748af8ac47ef94f3ef2b25d0863b/work/891e748af8ac47ef94f3ef2b25d0863b_id_rsa 2025-03-24 19:39:45.425724 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/891e748af8ac47ef94f3ef2b25d0863b/work/891e748af8ac47ef94f3ef2b25d0863b_id_rsa.pub 2025-03-24 19:39:45.425748 | ubuntu-noble -> localhost | The key fingerprint is: 2025-03-24 19:39:45.425772 | ubuntu-noble -> localhost | SHA256:Fg4fNzqLEpFG8BPbCxiNb7SdfjwjIUTgN8nhMcmkAgQ zuul-build-sshkey 2025-03-24 19:39:45.425794 | ubuntu-noble -> localhost | The key's randomart image is: 2025-03-24 19:39:45.425814 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-03-24 19:39:45.425834 | ubuntu-noble -> localhost | |E.+OO. | 2025-03-24 19:39:45.425852 | ubuntu-noble -> localhost | |...O*X | 2025-03-24 19:39:45.425871 | ubuntu-noble -> localhost | |. ++#oo.o o | 2025-03-24 19:39:45.425902 | ubuntu-noble -> localhost | | . o==+= = . | 2025-03-24 19:39:45.425922 | ubuntu-noble -> localhost | | ..o.oS | 2025-03-24 19:39:45.425942 | ubuntu-noble -> localhost | | .oo=o | 2025-03-24 19:39:45.425968 | ubuntu-noble -> localhost | | . .o.o | 2025-03-24 19:39:45.425989 | ubuntu-noble -> localhost | | . | 2025-03-24 19:39:45.426009 | ubuntu-noble -> localhost | | | 2025-03-24 19:39:45.426028 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-03-24 19:39:45.426074 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.166632 2025-03-24 19:39:45.435450 | 2025-03-24 19:39:45.435574 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-24 19:39:45.466362 | ubuntu-noble | ok 2025-03-24 19:39:45.478094 | ubuntu-noble | included: /var/lib/zuul/builds/891e748af8ac47ef94f3ef2b25d0863b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-24 19:39:45.487861 | 2025-03-24 19:39:45.487966 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-24 19:39:45.512451 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 19:39:45.520064 | 2025-03-24 19:39:45.520180 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-24 19:39:46.360591 | ubuntu-noble | changed 2025-03-24 19:39:46.370275 | 2025-03-24 19:39:46.370439 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-24 19:39:46.726691 | ubuntu-noble | ok 2025-03-24 19:39:46.735752 | 2025-03-24 19:39:46.735867 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-24 19:39:47.516741 | ubuntu-noble | changed 2025-03-24 19:39:47.530641 | 2025-03-24 19:39:47.530757 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-24 19:39:48.423266 | ubuntu-noble | changed 2025-03-24 19:39:48.433520 | 2025-03-24 19:39:48.433652 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-24 19:39:48.460730 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 19:39:48.472781 | 2025-03-24 19:39:48.472926 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-24 19:39:48.918713 | ubuntu-noble -> localhost | changed 2025-03-24 19:39:48.941071 | 2025-03-24 19:39:48.941202 | TASK [add-build-sshkey : Add back temp key] 2025-03-24 19:39:49.335264 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/891e748af8ac47ef94f3ef2b25d0863b/work/891e748af8ac47ef94f3ef2b25d0863b_id_rsa (zuul-build-sshkey) 2025-03-24 19:39:49.335793 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.018496 2025-03-24 19:39:49.353176 | 2025-03-24 19:39:49.353330 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-24 19:39:49.864440 | ubuntu-noble | ok 2025-03-24 19:39:49.873651 | 2025-03-24 19:39:49.873779 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-24 19:39:49.909053 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 19:39:49.928100 | 2025-03-24 19:39:49.928209 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-24 19:39:50.481680 | ubuntu-noble | ok 2025-03-24 19:39:50.500854 | 2025-03-24 19:39:50.501024 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-24 19:39:50.536943 | ubuntu-noble | ok 2025-03-24 19:39:50.545090 | 2025-03-24 19:39:50.545197 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-24 19:39:50.825153 | ubuntu-noble -> localhost | ok 2025-03-24 19:39:50.833531 | 2025-03-24 19:39:50.833638 | TASK [validate-host : Collect information about the host] 2025-03-24 19:39:52.147920 | ubuntu-noble | ok 2025-03-24 19:39:52.162720 | 2025-03-24 19:39:52.162828 | TASK [validate-host : Sanitize hostname] 2025-03-24 19:39:52.241311 | ubuntu-noble | ok 2025-03-24 19:39:52.250533 | 2025-03-24 19:39:52.250652 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-24 19:39:52.840789 | ubuntu-noble -> localhost | changed 2025-03-24 19:39:52.853876 | 2025-03-24 19:39:52.854011 | TASK [validate-host : Collect information about zuul worker] 2025-03-24 19:39:53.392725 | ubuntu-noble | ok 2025-03-24 19:39:53.401119 | 2025-03-24 19:39:53.401254 | TASK [validate-host : Write out all zuul information for each host] 2025-03-24 19:39:53.949578 | ubuntu-noble -> localhost | changed 2025-03-24 19:39:53.973086 | 2025-03-24 19:39:53.973212 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-24 19:39:54.333947 | ubuntu-noble | ok 2025-03-24 19:39:54.341511 | 2025-03-24 19:39:54.341628 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-24 19:39:55.122455 | ubuntu-noble | changed: 2025-03-24 19:39:55.122643 | ubuntu-noble | cd+++++++++ src/ 2025-03-24 19:39:55.122678 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-03-24 19:39:55.122703 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-03-24 19:39:55.122725 | ubuntu-noble | cd+++++++++ src/github.com/osism/container-image-ceph-ansible/ 2025-03-24 19:39:55.122746 | ubuntu-noble | Containerfile 2025-03-24 19:39:55.122892 | ubuntu-noble | [31 lines of output]\n Traceback (most recent call last):\n File \"/tmp/venv/lib/python3.12/site-packages/pip/_vendor/pyproject_hooks/_in_process/_in_process.py\", line 353, in \n main()\n File \"/tmp/venv/lib/python3.12/site-packages/pip/_vendor/pyproject_hooks/_in_process/_in_process.py\", line 335, in main\n json_out['return_val'] = hook(**hook_input['kwargs'])\n ^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n File \"/tmp/venv/lib/python3.12/site-packages/pip/_vendor/pyproject_hooks/_in_process/_in_process.py\", line 118, in get_requires_for_build_wheel\n return hook(config_settings)\n ^^^^^^^^^^^^^^^^^^^^^\n File \"/tmp/pip-build-env-msk7d62e/overlay/lib/python3.12/site-packages/setuptools/build_meta.py\", line 334, in get_requires_for_build_wheel\n return self._get_build_requires(config_settings, requirements=[])\n ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n File \"/tmp/pip-build-env-msk7d62e/overlay/lib/python3.12/site-packages/setuptools/build_meta.py\", line 304, in _get_build_requires\n self.run_setup()\n File \"/tmp/pip-build-env-msk7d62e/overlay/lib/python3.12/site-packages/setuptools/build_meta.py\", line 522, in run_setup\n super().run_setup(setup_script=setup_script)\n File \"/tmp/pip-build-env-msk7d62e/overlay/lib/python3.12/site-packages/setuptools/build_meta.py\", line 320, in run_setup\n exec(code, locals())\n File \"\", line 7, in \n File \"/tmp/pip-build-env-msk7d62e/overlay/lib/python3.12/site-packages/setuptools/__init__.py\", line 116, in setup\n _install_setup_requires(attrs)\n File \"/tmp/pip-build-env-msk7d62e/overlay/lib/python3.12/site-packages/setuptools/__init__.py\", line 87, in _install_setup_requires\n dist.parse_config_files(ignore_option_errors=True)\n File \"/tmp/pip-build-env-msk7d62e/overlay/lib/python3.12/site-packages/setuptools/dist.py\", line 730, in parse_config_files\n self._parse_config_files(filenames=inifiles)\n File \"/tmp/pip-build-env-msk7d62e/overlay/lib/python3.12/site-packages/setuptools/dist.py\", line 599, in _parse_config_files\n opt = self._enforce_underscore(opt, section)\n ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n File \"/tmp/pip-build-env-msk7d62e/overlay/lib/python3.12/site-packages/setuptools/dist.py\", line 629, in _enforce_underscore\n raise InvalidConfigError(\n setuptools.errors.InvalidConfigError: Invalid dash-separated key 'description-file' in 'metadata' (setup.cfg), please use the underscore name 'description_file' instead.\n [end of output]\n \n note: This error originates from a subprocess, and is likely not a problem with pip.\nerror: subprocess-exited-with-error\n\n\u00d7 Getting requirements to build wheel did not run successfully.\n\u2502 exit code: 1\n\u2570\u2500> See above for output.\n\nnote: This error originates from a subprocess, and is likely not a problem with pip.\n" 2025-03-24 19:41:01.211040 | ubuntu-noble | } failure 2025-03-24 19:41:01.213742 | 2025-03-24 19:41:01.213846 | PLAY RECAP 2025-03-24 19:41:01.213919 | ubuntu-noble | ok: 23 changed: 12 unreachable: 0 failed: 1 skipped: 14 rescued: 0 ignored: 0 2025-03-24 19:41:01.213956 | 2025-03-24 19:41:01.335946 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-ceph-ansible/playbooks/pre.yml@main] 2025-03-24 19:41:01.339180 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-03-24 19:41:02.078843 | 2025-03-24 19:41:02.078991 | PLAY [Base post] 2025-03-24 19:41:02.105093 | 2025-03-24 19:41:02.105223 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-03-24 19:41:02.966942 | ubuntu-noble | changed 2025-03-24 19:41:02.999951 | 2025-03-24 19:41:03.000080 | PLAY RECAP 2025-03-24 19:41:03.000150 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-03-24 19:41:03.000213 | 2025-03-24 19:41:03.114274 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-03-24 19:41:03.117410 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-03-24 19:41:03.865560 | 2025-03-24 19:41:03.865722 | PLAY [Base post-logs] 2025-03-24 19:41:03.882086 | 2025-03-24 19:41:03.882220 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-03-24 19:41:04.330014 | localhost | changed 2025-03-24 19:41:04.333801 | 2025-03-24 19:41:04.333935 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-03-24 19:41:04.378081 | localhost | ok 2025-03-24 19:41:04.389959 | 2025-03-24 19:41:04.390090 | TASK [Set zuul-log-path fact] 2025-03-24 19:41:04.418320 | localhost | ok 2025-03-24 19:41:04.429119 | 2025-03-24 19:41:04.429230 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-03-24 19:41:04.467247 | localhost | ok 2025-03-24 19:41:04.474931 | 2025-03-24 19:41:04.475056 | TASK [upload-logs : Create log directories] 2025-03-24 19:41:04.991696 | localhost | changed 2025-03-24 19:41:04.999654 | 2025-03-24 19:41:04.999803 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-03-24 19:41:05.516852 | localhost -> localhost | ok: Runtime: 0:00:00.005930 2025-03-24 19:41:05.528526 | 2025-03-24 19:41:05.528724 | TASK [upload-logs : Upload logs to log server] 2025-03-24 19:41:06.083193 | localhost | Output suppressed because no_log was given 2025-03-24 19:41:06.086426 | 2025-03-24 19:41:06.086544 | LOOP [upload-logs : Compress console log and json output] 2025-03-24 19:41:06.165285 | localhost | skipping: Conditional result was False 2025-03-24 19:41:06.184271 | localhost | skipping: Conditional result was False 2025-03-24 19:41:06.196113 | 2025-03-24 19:41:06.196217 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-24 19:41:06.262032 | localhost | skipping: Conditional result was False 2025-03-24 19:41:06.262671 | 2025-03-24 19:41:06.274753 | localhost | skipping: Conditional result was False 2025-03-24 19:41:06.292498 | 2025-03-24 19:41:06.292683 | LOOP [upload-logs : Upload console log and json output]