2025-03-24 19:36:33.746817 | Job console starting... 2025-03-24 19:36:33.773538 | Updating repositories 2025-03-24 19:36:33.845996 | Preparing job workspace 2025-03-24 19:36:36.544938 | Running Ansible setup... 2025-03-24 19:36:43.553660 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-24 19:36:44.489158 | 2025-03-24 19:36:44.489270 | PLAY [Base pre] 2025-03-24 19:36:44.534473 | 2025-03-24 19:36:44.534602 | TASK [Setup log path fact] 2025-03-24 19:36:44.573410 | ubuntu-noble | ok 2025-03-24 19:36:44.608939 | 2025-03-24 19:36:44.609054 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-03-24 19:36:44.657831 | ubuntu-noble | ok 2025-03-24 19:36:44.668073 | 2025-03-24 19:36:44.668157 | TASK [emit-job-header : Print job information] 2025-03-24 19:36:44.737075 | # Job Information 2025-03-24 19:36:44.737209 | Ansible Version: 2.15.3 2025-03-24 19:36:44.737236 | Job: container-image-ceph-ansible-build-quincy 2025-03-24 19:36:44.737260 | Pipeline: check 2025-03-24 19:36:44.737276 | Executor: 7d211f194f6a 2025-03-24 19:36:44.737292 | Triggered by: https://github.com/osism/container-image-ceph-ansible/pull/570 2025-03-24 19:36:44.737307 | Event ID: 45177c40-08e7-11f0-81ae-9bea12ce1c59 2025-03-24 19:36:44.745582 | 2025-03-24 19:36:44.745677 | LOOP [emit-job-header : Print node information] 2025-03-24 19:36:44.987506 | ubuntu-noble | ok: 2025-03-24 19:36:44.987700 | ubuntu-noble | # Node Information 2025-03-24 19:36:44.987731 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-03-24 19:36:44.987751 | ubuntu-noble | Hostname: ubuntu 2025-03-24 19:36:44.987769 | ubuntu-noble | Username: zuul 2025-03-24 19:36:44.987785 | ubuntu-noble | Distro: Ubuntu 24.04 2025-03-24 19:36:44.987805 | ubuntu-noble | Provider: regiocloud-a 2025-03-24 19:36:44.987821 | ubuntu-noble | Label: ubuntu-noble 2025-03-24 19:36:44.987838 | ubuntu-noble | Product Name: OpenStack Nova 2025-03-24 19:36:44.987853 | ubuntu-noble | Interface IP: 81.163.192.78 2025-03-24 19:36:45.006349 | 2025-03-24 19:36:45.006474 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-24 19:36:45.970457 | ubuntu-noble -> localhost | changed 2025-03-24 19:36:45.977784 | 2025-03-24 19:36:45.977869 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-24 19:36:47.387179 | ubuntu-noble -> localhost | changed 2025-03-24 19:36:47.403002 | 2025-03-24 19:36:47.403094 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-24 19:36:47.712277 | ubuntu-noble -> localhost | ok 2025-03-24 19:36:47.718432 | 2025-03-24 19:36:47.718520 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-24 19:36:47.756964 | ubuntu-noble | ok 2025-03-24 19:36:47.774088 | ubuntu-noble | included: /var/lib/zuul/builds/bdeef76c0fa140098d0da2aa2d3ba55b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-24 19:36:47.781305 | 2025-03-24 19:36:47.781402 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-24 19:36:48.671398 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-03-24 19:36:48.671552 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/bdeef76c0fa140098d0da2aa2d3ba55b/work/bdeef76c0fa140098d0da2aa2d3ba55b_id_rsa 2025-03-24 19:36:48.671580 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/bdeef76c0fa140098d0da2aa2d3ba55b/work/bdeef76c0fa140098d0da2aa2d3ba55b_id_rsa.pub 2025-03-24 19:36:48.671599 | ubuntu-noble -> localhost | The key fingerprint is: 2025-03-24 19:36:48.671618 | ubuntu-noble -> localhost | SHA256:jsjZbIY9Yfym47F4zu6DwnvsZSzkYKkJQj4SuEZ2FXU zuul-build-sshkey 2025-03-24 19:36:48.671635 | ubuntu-noble -> localhost | The key's randomart image is: 2025-03-24 19:36:48.671652 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-03-24 19:36:48.671668 | ubuntu-noble -> localhost | | oo. E | 2025-03-24 19:36:48.671684 | ubuntu-noble -> localhost | |. . . | 2025-03-24 19:36:48.671700 | ubuntu-noble -> localhost | |o+ . | 2025-03-24 19:36:48.671724 | ubuntu-noble -> localhost | |=o.. . | 2025-03-24 19:36:48.671741 | ubuntu-noble -> localhost | |=++ . + S | 2025-03-24 19:36:48.671756 | ubuntu-noble -> localhost | |=+.= X = | 2025-03-24 19:36:48.671778 | ubuntu-noble -> localhost | |o. .B.& + | 2025-03-24 19:36:48.671794 | ubuntu-noble -> localhost | | o +O+* | 2025-03-24 19:36:48.671810 | ubuntu-noble -> localhost | | .=o*Oo | 2025-03-24 19:36:48.671826 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-03-24 19:36:48.671864 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.373925 2025-03-24 19:36:48.678617 | 2025-03-24 19:36:48.678708 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-24 19:36:48.706944 | ubuntu-noble | ok 2025-03-24 19:36:48.715950 | ubuntu-noble | included: /var/lib/zuul/builds/bdeef76c0fa140098d0da2aa2d3ba55b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-24 19:36:48.723683 | 2025-03-24 19:36:48.723753 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-24 19:36:48.736915 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 19:36:48.745044 | 2025-03-24 19:36:48.745136 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-24 19:36:49.447211 | ubuntu-noble | changed 2025-03-24 19:36:49.464029 | 2025-03-24 19:36:49.464139 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-24 19:36:49.743442 | ubuntu-noble | ok 2025-03-24 19:36:49.750808 | 2025-03-24 19:36:49.750901 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-24 19:36:50.457332 | ubuntu-noble | changed 2025-03-24 19:36:50.466106 | 2025-03-24 19:36:50.466207 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-24 19:36:51.130880 | ubuntu-noble | changed 2025-03-24 19:36:51.141426 | 2025-03-24 19:36:51.141671 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-24 19:36:51.187223 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 19:36:51.199379 | 2025-03-24 19:36:51.199486 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-24 19:36:52.141777 | ubuntu-noble -> localhost | changed 2025-03-24 19:36:52.176520 | 2025-03-24 19:36:52.176637 | TASK [add-build-sshkey : Add back temp key] 2025-03-24 19:36:52.828237 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/bdeef76c0fa140098d0da2aa2d3ba55b/work/bdeef76c0fa140098d0da2aa2d3ba55b_id_rsa (zuul-build-sshkey) 2025-03-24 19:36:52.828463 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.008040 2025-03-24 19:36:52.838304 | 2025-03-24 19:36:52.838435 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-24 19:36:53.293612 | ubuntu-noble | ok 2025-03-24 19:36:53.336682 | 2025-03-24 19:36:53.337102 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-24 19:36:53.437194 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 19:36:53.518380 | 2025-03-24 19:36:53.518608 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-24 19:36:54.006020 | ubuntu-noble | ok 2025-03-24 19:36:54.018161 | 2025-03-24 19:36:54.018249 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-24 19:36:54.048718 | ubuntu-noble | ok 2025-03-24 19:36:54.058986 | 2025-03-24 19:36:54.059402 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-24 19:36:54.987858 | ubuntu-noble -> localhost | ok 2025-03-24 19:36:55.010209 | 2025-03-24 19:36:55.010312 | TASK [validate-host : Collect information about the host] 2025-03-24 19:36:56.273562 | ubuntu-noble | ok 2025-03-24 19:36:56.299258 | 2025-03-24 19:36:56.299378 | TASK [validate-host : Sanitize hostname] 2025-03-24 19:36:56.495929 | ubuntu-noble | ok 2025-03-24 19:36:56.507929 | 2025-03-24 19:36:56.508312 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-24 19:36:58.053613 | ubuntu-noble -> localhost | changed 2025-03-24 19:36:58.079277 | 2025-03-24 19:36:58.081019 | TASK [validate-host : Collect information about zuul worker] 2025-03-24 19:36:58.719040 | ubuntu-noble | ok 2025-03-24 19:36:58.724948 | 2025-03-24 19:36:58.725041 | TASK [validate-host : Write out all zuul information for each host] 2025-03-24 19:36:59.580880 | ubuntu-noble -> localhost | changed 2025-03-24 19:36:59.592963 | 2025-03-24 19:36:59.593050 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-24 19:36:59.854137 | ubuntu-noble | ok 2025-03-24 19:36:59.859620 | 2025-03-24 19:36:59.859707 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-24 19:37:00.663206 | ubuntu-noble | changed: 2025-03-24 19:37:00.663342 | ubuntu-noble | cd+++++++++ src/ 2025-03-24 19:37:00.663397 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-03-24 19:37:00.663419 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-03-24 19:37:00.663437 | ubuntu-noble | cd+++++++++ src/github.com/osism/container-image-ceph-ansible/ 2025-03-24 19:37:00.663455 | ubuntu-noble | Containerfile 2025-03-24 19:37:00.663575 | 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-x6madfmu/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-x6madfmu/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-x6madfmu/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-x6madfmu/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-x6madfmu/overlay/lib/python3.12/site-packages/setuptools/__init__.py\", line 116, in setup\n _install_setup_requires(attrs)\n File \"/tmp/pip-build-env-x6madfmu/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-x6madfmu/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-x6madfmu/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-x6madfmu/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:38:00.469588 | ubuntu-noble | } failure 2025-03-24 19:38:00.472361 | 2025-03-24 19:38:00.472576 | PLAY RECAP 2025-03-24 19:38:00.472682 | ubuntu-noble | ok: 23 changed: 12 unreachable: 0 failed: 1 skipped: 14 rescued: 0 ignored: 0 2025-03-24 19:38:00.472733 | 2025-03-24 19:38:00.629547 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-ceph-ansible/playbooks/pre.yml@main] 2025-03-24 19:38:00.632805 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-03-24 19:38:01.484187 | 2025-03-24 19:38:01.484358 | PLAY [Base post] 2025-03-24 19:38:01.545869 | 2025-03-24 19:38:01.546052 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-03-24 19:38:02.513959 | ubuntu-noble | changed 2025-03-24 19:38:02.553303 | 2025-03-24 19:38:02.553450 | PLAY RECAP 2025-03-24 19:38:02.553520 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-03-24 19:38:02.553584 | 2025-03-24 19:38:02.682402 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-03-24 19:38:02.686157 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-03-24 19:38:03.481963 | 2025-03-24 19:38:03.482129 | PLAY [Base post-logs] 2025-03-24 19:38:03.499108 | 2025-03-24 19:38:03.499249 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-03-24 19:38:04.190899 | localhost | changed 2025-03-24 19:38:04.199645 | 2025-03-24 19:38:04.200101 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-03-24 19:38:04.243568 | localhost | ok 2025-03-24 19:38:04.254262 | 2025-03-24 19:38:04.254401 | TASK [Set zuul-log-path fact] 2025-03-24 19:38:04.288706 | localhost | ok 2025-03-24 19:38:04.315021 | 2025-03-24 19:38:04.315291 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-03-24 19:38:04.358499 | localhost | ok 2025-03-24 19:38:04.367876 | 2025-03-24 19:38:04.368018 | TASK [upload-logs : Create log directories] 2025-03-24 19:38:04.969501 | localhost | changed 2025-03-24 19:38:04.974607 | 2025-03-24 19:38:04.974726 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-03-24 19:38:05.500735 | localhost -> localhost | ok: Runtime: 0:00:00.005613 2025-03-24 19:38:05.506819 | 2025-03-24 19:38:05.506941 | TASK [upload-logs : Upload logs to log server] 2025-03-24 19:38:06.076538 | localhost | Output suppressed because no_log was given 2025-03-24 19:38:06.080864 | 2025-03-24 19:38:06.081056 | LOOP [upload-logs : Compress console log and json output] 2025-03-24 19:38:06.149490 | localhost | skipping: Conditional result was False 2025-03-24 19:38:06.169801 | localhost | skipping: Conditional result was False 2025-03-24 19:38:06.174845 | 2025-03-24 19:38:06.174974 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-24 19:38:06.245284 | localhost | skipping: Conditional result was False 2025-03-24 19:38:06.245626 | 2025-03-24 19:38:06.264621 | localhost | skipping: Conditional result was False 2025-03-24 19:38:06.270531 | 2025-03-24 19:38:06.270648 | LOOP [upload-logs : Upload console log and json output]