2025-03-24 19:36:33.915284 | Job console starting... 2025-03-24 19:36:33.947302 | Updating repositories 2025-03-24 19:36:33.988030 | Preparing job workspace 2025-03-24 19:36:36.709005 | Running Ansible setup... 2025-03-24 19:36:43.604412 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-24 19:36:44.862075 | 2025-03-24 19:36:44.862286 | PLAY [Base pre] 2025-03-24 19:36:44.918156 | 2025-03-24 19:36:44.918284 | TASK [Setup log path fact] 2025-03-24 19:36:44.961045 | ubuntu-noble | ok 2025-03-24 19:36:45.010271 | 2025-03-24 19:36:45.010405 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-03-24 19:36:45.070390 | ubuntu-noble | ok 2025-03-24 19:36:45.088728 | 2025-03-24 19:36:45.088822 | TASK [emit-job-header : Print job information] 2025-03-24 19:36:45.161226 | # Job Information 2025-03-24 19:36:45.161380 | Ansible Version: 2.15.3 2025-03-24 19:36:45.161410 | Job: container-image-ceph-ansible-build-reef 2025-03-24 19:36:45.161435 | Pipeline: check 2025-03-24 19:36:45.161451 | Executor: 7d211f194f6a 2025-03-24 19:36:45.161467 | Triggered by: https://github.com/osism/container-image-ceph-ansible/pull/570 2025-03-24 19:36:45.161482 | Event ID: 45177c40-08e7-11f0-81ae-9bea12ce1c59 2025-03-24 19:36:45.174794 | 2025-03-24 19:36:45.174902 | LOOP [emit-job-header : Print node information] 2025-03-24 19:36:45.331156 | ubuntu-noble | ok: 2025-03-24 19:36:45.331442 | ubuntu-noble | # Node Information 2025-03-24 19:36:45.331577 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-03-24 19:36:45.331614 | ubuntu-noble | Hostname: ubuntu 2025-03-24 19:36:45.331638 | ubuntu-noble | Username: zuul 2025-03-24 19:36:45.331660 | ubuntu-noble | Distro: Ubuntu 24.04 2025-03-24 19:36:45.331685 | ubuntu-noble | Provider: regiocloud-a 2025-03-24 19:36:45.331706 | ubuntu-noble | Label: ubuntu-noble 2025-03-24 19:36:45.331727 | ubuntu-noble | Product Name: OpenStack Nova 2025-03-24 19:36:45.331746 | ubuntu-noble | Interface IP: 81.163.193.84 2025-03-24 19:36:45.350244 | 2025-03-24 19:36:45.350350 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-24 19:36:46.016980 | ubuntu-noble -> localhost | changed 2025-03-24 19:36:46.023600 | 2025-03-24 19:36:46.023689 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-24 19:36:47.447717 | ubuntu-noble -> localhost | changed 2025-03-24 19:36:47.459923 | 2025-03-24 19:36:47.460015 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-24 19:36:47.742961 | ubuntu-noble -> localhost | ok 2025-03-24 19:36:47.749711 | 2025-03-24 19:36:47.749824 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-24 19:36:47.798974 | ubuntu-noble | ok 2025-03-24 19:36:47.825184 | ubuntu-noble | included: /var/lib/zuul/builds/5c8723d2d3dd44f3a31a0f015f70bb25/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-24 19:36:47.837845 | 2025-03-24 19:36:47.837925 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-24 19:36:48.469693 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-03-24 19:36:48.469848 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/5c8723d2d3dd44f3a31a0f015f70bb25/work/5c8723d2d3dd44f3a31a0f015f70bb25_id_rsa 2025-03-24 19:36:48.469877 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/5c8723d2d3dd44f3a31a0f015f70bb25/work/5c8723d2d3dd44f3a31a0f015f70bb25_id_rsa.pub 2025-03-24 19:36:48.469899 | ubuntu-noble -> localhost | The key fingerprint is: 2025-03-24 19:36:48.469919 | ubuntu-noble -> localhost | SHA256:U/kXvgDlWRkYQ/BnpiU5BnqTnT3NVqVMUsDh+zn0MaI zuul-build-sshkey 2025-03-24 19:36:48.469937 | ubuntu-noble -> localhost | The key's randomart image is: 2025-03-24 19:36:48.469955 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-03-24 19:36:48.469972 | ubuntu-noble -> localhost | | o+O*=o+| 2025-03-24 19:36:48.469988 | ubuntu-noble -> localhost | | . Xo@.+.| 2025-03-24 19:36:48.470006 | ubuntu-noble -> localhost | | . B % @ +| 2025-03-24 19:36:48.470030 | ubuntu-noble -> localhost | | o = @ + | 2025-03-24 19:36:48.470048 | ubuntu-noble -> localhost | | S =.+o | 2025-03-24 19:36:48.470065 | ubuntu-noble -> localhost | | . .=.+o| 2025-03-24 19:36:48.470084 | ubuntu-noble -> localhost | | E = .| 2025-03-24 19:36:48.470101 | ubuntu-noble -> localhost | | . | 2025-03-24 19:36:48.470118 | ubuntu-noble -> localhost | | | 2025-03-24 19:36:48.470135 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-03-24 19:36:48.470177 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.190189 2025-03-24 19:36:48.476540 | 2025-03-24 19:36:48.476614 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-24 19:36:48.499943 | ubuntu-noble | ok 2025-03-24 19:36:48.520918 | ubuntu-noble | included: /var/lib/zuul/builds/5c8723d2d3dd44f3a31a0f015f70bb25/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-24 19:36:48.531880 | 2025-03-24 19:36:48.531951 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-24 19:36:48.545253 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 19:36:48.552635 | 2025-03-24 19:36:48.552732 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-24 19:36:49.306612 | ubuntu-noble | changed 2025-03-24 19:36:49.317929 | 2025-03-24 19:36:49.318199 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-24 19:36:49.609307 | ubuntu-noble | ok 2025-03-24 19:36:49.621619 | 2025-03-24 19:36:49.621700 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-24 19:36:50.270046 | ubuntu-noble | changed 2025-03-24 19:36:50.277104 | 2025-03-24 19:36:50.277198 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-24 19:36:51.033505 | ubuntu-noble | changed 2025-03-24 19:36:51.043446 | 2025-03-24 19:36:51.043544 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-24 19:36:51.068832 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 19:36:51.079341 | 2025-03-24 19:36:51.079518 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-24 19:36:51.777078 | ubuntu-noble -> localhost | changed 2025-03-24 19:36:51.806043 | 2025-03-24 19:36:51.806152 | TASK [add-build-sshkey : Add back temp key] 2025-03-24 19:36:52.455282 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/5c8723d2d3dd44f3a31a0f015f70bb25/work/5c8723d2d3dd44f3a31a0f015f70bb25_id_rsa (zuul-build-sshkey) 2025-03-24 19:36:52.455560 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.030059 2025-03-24 19:36:52.465208 | 2025-03-24 19:36:52.465299 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-24 19:36:53.051452 | ubuntu-noble | ok 2025-03-24 19:36:53.059644 | 2025-03-24 19:36:53.059741 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-24 19:36:53.222651 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 19:36:53.369544 | 2025-03-24 19:36:53.369809 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-24 19:36:53.890614 | ubuntu-noble | ok 2025-03-24 19:36:53.907459 | 2025-03-24 19:36:53.907588 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-24 19:36:53.960204 | ubuntu-noble | ok 2025-03-24 19:36:53.968024 | 2025-03-24 19:36:53.968111 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-24 19:36:54.453586 | ubuntu-noble -> localhost | ok 2025-03-24 19:36:54.461663 | 2025-03-24 19:36:54.461808 | TASK [validate-host : Collect information about the host] 2025-03-24 19:36:56.000931 | ubuntu-noble | ok 2025-03-24 19:36:56.014716 | 2025-03-24 19:36:56.014887 | TASK [validate-host : Sanitize hostname] 2025-03-24 19:36:56.079254 | ubuntu-noble | ok 2025-03-24 19:36:56.086029 | 2025-03-24 19:36:56.086182 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-24 19:36:57.050324 | ubuntu-noble -> localhost | changed 2025-03-24 19:36:57.070925 | 2025-03-24 19:36:57.071037 | TASK [validate-host : Collect information about zuul worker] 2025-03-24 19:36:57.592016 | ubuntu-noble | ok 2025-03-24 19:36:57.599498 | 2025-03-24 19:36:57.599898 | TASK [validate-host : Write out all zuul information for each host] 2025-03-24 19:36:58.556715 | ubuntu-noble -> localhost | changed 2025-03-24 19:36:58.566875 | 2025-03-24 19:36:58.566959 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-24 19:36:58.875247 | ubuntu-noble | ok 2025-03-24 19:36:58.899983 | 2025-03-24 19:36:58.900090 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-24 19:36:59.683382 | ubuntu-noble | changed: 2025-03-24 19:36:59.683524 | ubuntu-noble | cd+++++++++ src/ 2025-03-24 19:36:59.683552 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-03-24 19:36:59.683571 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-03-24 19:36:59.683588 | ubuntu-noble | cd+++++++++ src/github.com/osism/container-image-ceph-ansible/ 2025-03-24 19:36:59.683604 | ubuntu-noble | Containerfile 2025-03-24 19:36:59.683723 | 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-59lhpa7c/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-59lhpa7c/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-59lhpa7c/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-59lhpa7c/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-59lhpa7c/overlay/lib/python3.12/site-packages/setuptools/__init__.py\", line 116, in setup\n _install_setup_requires(attrs)\n File \"/tmp/pip-build-env-59lhpa7c/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-59lhpa7c/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-59lhpa7c/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-59lhpa7c/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.915977 | ubuntu-noble | } failure 2025-03-24 19:38:00.930065 | 2025-03-24 19:38:00.930278 | PLAY RECAP 2025-03-24 19:38:00.930351 | ubuntu-noble | ok: 23 changed: 12 unreachable: 0 failed: 1 skipped: 14 rescued: 0 ignored: 0 2025-03-24 19:38:00.930396 | 2025-03-24 19:38:01.153492 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-ceph-ansible/playbooks/pre.yml@main] 2025-03-24 19:38:01.156779 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-03-24 19:38:02.250554 | 2025-03-24 19:38:02.250714 | PLAY [Base post] 2025-03-24 19:38:02.276610 | 2025-03-24 19:38:02.276758 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-03-24 19:38:03.066518 | ubuntu-noble | changed 2025-03-24 19:38:03.159492 | 2025-03-24 19:38:03.159715 | PLAY RECAP 2025-03-24 19:38:03.159796 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-03-24 19:38:03.159860 | 2025-03-24 19:38:03.446466 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-03-24 19:38:03.450782 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-03-24 19:38:04.388447 | 2025-03-24 19:38:04.388617 | PLAY [Base post-logs] 2025-03-24 19:38:04.405104 | 2025-03-24 19:38:04.405237 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-03-24 19:38:04.966925 | localhost | changed 2025-03-24 19:38:04.972317 | 2025-03-24 19:38:04.972549 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-03-24 19:38:05.000078 | localhost | ok 2025-03-24 19:38:05.007002 | 2025-03-24 19:38:05.007118 | TASK [Set zuul-log-path fact] 2025-03-24 19:38:05.025594 | localhost | ok 2025-03-24 19:38:05.036186 | 2025-03-24 19:38:05.036302 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-03-24 19:38:05.073337 | localhost | ok 2025-03-24 19:38:05.081136 | 2025-03-24 19:38:05.081250 | TASK [upload-logs : Create log directories] 2025-03-24 19:38:05.659476 | localhost | changed 2025-03-24 19:38:05.663813 | 2025-03-24 19:38:05.663930 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-03-24 19:38:06.209970 | localhost -> localhost | ok: Runtime: 0:00:00.021451 2025-03-24 19:38:06.215250 | 2025-03-24 19:38:06.215408 | TASK [upload-logs : Upload logs to log server] 2025-03-24 19:38:06.784874 | localhost | Output suppressed because no_log was given 2025-03-24 19:38:06.791425 | 2025-03-24 19:38:06.791554 | LOOP [upload-logs : Compress console log and json output] 2025-03-24 19:38:06.893594 | localhost | skipping: Conditional result was False 2025-03-24 19:38:06.920786 | localhost | skipping: Conditional result was False 2025-03-24 19:38:06.945413 | 2025-03-24 19:38:06.945708 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-24 19:38:07.016817 | localhost | skipping: Conditional result was False 2025-03-24 19:38:07.017249 | 2025-03-24 19:38:07.041334 | localhost | skipping: Conditional result was False 2025-03-24 19:38:07.048991 | 2025-03-24 19:38:07.049109 | LOOP [upload-logs : Upload console log and json output]