2025-03-24 19:38:09.129482 | Job console starting... 2025-03-24 19:38:09.154053 | Updating repositories 2025-03-24 19:38:09.201841 | Preparing job workspace 2025-03-24 19:38:10.747406 | Running Ansible setup... 2025-03-24 19:38:16.046571 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-24 19:38:16.703780 | 2025-03-24 19:38:16.704016 | PLAY [Base pre] 2025-03-24 19:38:16.742213 | 2025-03-24 19:38:16.742430 | TASK [Setup log path fact] 2025-03-24 19:38:16.788101 | ubuntu-noble | ok 2025-03-24 19:38:16.808475 | 2025-03-24 19:38:16.808619 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-03-24 19:38:16.853423 | ubuntu-noble | ok 2025-03-24 19:38:16.869898 | 2025-03-24 19:38:16.870009 | TASK [emit-job-header : Print job information] 2025-03-24 19:38:16.939654 | # Job Information 2025-03-24 19:38:16.939897 | Ansible Version: 2.15.3 2025-03-24 19:38:16.939952 | Job: container-image-ceph-ansible-build-reef 2025-03-24 19:38:16.939996 | Pipeline: check 2025-03-24 19:38:16.940026 | Executor: 7d211f194f6a 2025-03-24 19:38:16.940054 | Triggered by: https://github.com/osism/container-image-ceph-ansible/pull/570 2025-03-24 19:38:16.940081 | Event ID: 45177c40-08e7-11f0-81ae-9bea12ce1c59 2025-03-24 19:38:16.948282 | 2025-03-24 19:38:16.948468 | LOOP [emit-job-header : Print node information] 2025-03-24 19:38:17.104047 | ubuntu-noble | ok: 2025-03-24 19:38:17.104329 | ubuntu-noble | # Node Information 2025-03-24 19:38:17.104468 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-03-24 19:38:17.104502 | ubuntu-noble | Hostname: ubuntu 2025-03-24 19:38:17.104530 | ubuntu-noble | Username: zuul 2025-03-24 19:38:17.104552 | ubuntu-noble | Distro: Ubuntu 24.04 2025-03-24 19:38:17.104576 | ubuntu-noble | Provider: regiocloud-a 2025-03-24 19:38:17.104596 | ubuntu-noble | Label: ubuntu-noble 2025-03-24 19:38:17.104616 | ubuntu-noble | Product Name: OpenStack Nova 2025-03-24 19:38:17.104637 | ubuntu-noble | Interface IP: 81.163.192.153 2025-03-24 19:38:17.130011 | 2025-03-24 19:38:17.130132 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-24 19:38:17.562554 | ubuntu-noble -> localhost | changed 2025-03-24 19:38:17.576936 | 2025-03-24 19:38:17.577053 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-24 19:38:18.549216 | ubuntu-noble -> localhost | changed 2025-03-24 19:38:18.564673 | 2025-03-24 19:38:18.564797 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-24 19:38:18.868626 | ubuntu-noble -> localhost | ok 2025-03-24 19:38:18.885532 | 2025-03-24 19:38:18.885705 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-24 19:38:18.923959 | ubuntu-noble | ok 2025-03-24 19:38:18.945575 | ubuntu-noble | included: /var/lib/zuul/builds/421ef59461d040e5951acbb77d5e3892/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-24 19:38:18.957217 | 2025-03-24 19:38:18.957321 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-24 19:38:19.983252 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-03-24 19:38:19.983489 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/421ef59461d040e5951acbb77d5e3892/work/421ef59461d040e5951acbb77d5e3892_id_rsa 2025-03-24 19:38:19.983528 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/421ef59461d040e5951acbb77d5e3892/work/421ef59461d040e5951acbb77d5e3892_id_rsa.pub 2025-03-24 19:38:19.983554 | ubuntu-noble -> localhost | The key fingerprint is: 2025-03-24 19:38:19.983579 | ubuntu-noble -> localhost | SHA256:eqaisrVNBqEJGH16H2pXDlonmj8qEGTRhea+Vrne8MU zuul-build-sshkey 2025-03-24 19:38:19.983601 | ubuntu-noble -> localhost | The key's randomart image is: 2025-03-24 19:38:19.983623 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-03-24 19:38:19.983644 | ubuntu-noble -> localhost | |.oo o. | 2025-03-24 19:38:19.983665 | ubuntu-noble -> localhost | |.+.+. | 2025-03-24 19:38:19.983684 | ubuntu-noble -> localhost | |= +o | 2025-03-24 19:38:19.983719 | ubuntu-noble -> localhost | |oo.o. = o | 2025-03-24 19:38:19.983741 | ubuntu-noble -> localhost | |o.o. B.BS | 2025-03-24 19:38:19.983761 | ubuntu-noble -> localhost | |. o*oo.o | 2025-03-24 19:38:19.983787 | ubuntu-noble -> localhost | | ...=+o oE | 2025-03-24 19:38:19.983808 | ubuntu-noble -> localhost | |...B..*+. | 2025-03-24 19:38:19.983829 | ubuntu-noble -> localhost | |.o+o++.+ | 2025-03-24 19:38:19.983849 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-03-24 19:38:19.983906 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.533431 2025-03-24 19:38:19.991840 | 2025-03-24 19:38:19.991952 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-24 19:38:20.030820 | ubuntu-noble | ok 2025-03-24 19:38:20.046366 | ubuntu-noble | included: /var/lib/zuul/builds/421ef59461d040e5951acbb77d5e3892/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-24 19:38:20.056678 | 2025-03-24 19:38:20.056778 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-24 19:38:20.092134 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 19:38:20.101759 | 2025-03-24 19:38:20.101875 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-24 19:38:20.850894 | ubuntu-noble | changed 2025-03-24 19:38:20.860944 | 2025-03-24 19:38:20.861067 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-24 19:38:21.165453 | ubuntu-noble | ok 2025-03-24 19:38:21.173932 | 2025-03-24 19:38:21.174034 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-24 19:38:21.910681 | ubuntu-noble | changed 2025-03-24 19:38:21.922612 | 2025-03-24 19:38:21.922737 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-24 19:38:22.607973 | ubuntu-noble | changed 2025-03-24 19:38:22.615344 | 2025-03-24 19:38:22.615467 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-24 19:38:22.641015 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 19:38:22.662021 | 2025-03-24 19:38:22.662176 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-24 19:38:23.118389 | ubuntu-noble -> localhost | changed 2025-03-24 19:38:23.134139 | 2025-03-24 19:38:23.134260 | TASK [add-build-sshkey : Add back temp key] 2025-03-24 19:38:23.485718 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/421ef59461d040e5951acbb77d5e3892/work/421ef59461d040e5951acbb77d5e3892_id_rsa (zuul-build-sshkey) 2025-03-24 19:38:23.485957 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.017640 2025-03-24 19:38:23.493859 | 2025-03-24 19:38:23.493978 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-24 19:38:23.952288 | ubuntu-noble | ok 2025-03-24 19:38:23.961744 | 2025-03-24 19:38:23.961874 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-24 19:38:23.987463 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 19:38:24.013183 | 2025-03-24 19:38:24.013340 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-24 19:38:24.507232 | ubuntu-noble | ok 2025-03-24 19:38:24.519280 | 2025-03-24 19:38:24.519404 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-24 19:38:24.560233 | ubuntu-noble | ok 2025-03-24 19:38:24.568615 | 2025-03-24 19:38:24.568727 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-24 19:38:24.900727 | ubuntu-noble -> localhost | ok 2025-03-24 19:38:24.909143 | 2025-03-24 19:38:24.909258 | TASK [validate-host : Collect information about the host] 2025-03-24 19:38:26.070435 | ubuntu-noble | ok 2025-03-24 19:38:26.086552 | 2025-03-24 19:38:26.086677 | TASK [validate-host : Sanitize hostname] 2025-03-24 19:38:26.166774 | ubuntu-noble | ok 2025-03-24 19:38:26.176492 | 2025-03-24 19:38:26.176630 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-24 19:38:26.765510 | ubuntu-noble -> localhost | changed 2025-03-24 19:38:26.782303 | 2025-03-24 19:38:26.782495 | TASK [validate-host : Collect information about zuul worker] 2025-03-24 19:38:27.282217 | ubuntu-noble | ok 2025-03-24 19:38:27.290631 | 2025-03-24 19:38:27.290742 | TASK [validate-host : Write out all zuul information for each host] 2025-03-24 19:38:27.880014 | ubuntu-noble -> localhost | changed 2025-03-24 19:38:27.902221 | 2025-03-24 19:38:27.902347 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-24 19:38:28.201405 | ubuntu-noble | ok 2025-03-24 19:38:28.209133 | 2025-03-24 19:38:28.209241 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-24 19:38:29.082879 | ubuntu-noble | changed: 2025-03-24 19:38:29.083243 | ubuntu-noble | cd+++++++++ src/ 2025-03-24 19:38:29.083312 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-03-24 19:38:29.083358 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-03-24 19:38:29.083416 | ubuntu-noble | cd+++++++++ src/github.com/osism/container-image-ceph-ansible/ 2025-03-24 19:38:29.083454 | ubuntu-noble | Containerfile 2025-03-24 19:38:29.083722 | 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-xwcrd_6j/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-xwcrd_6j/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-xwcrd_6j/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-xwcrd_6j/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-xwcrd_6j/overlay/lib/python3.12/site-packages/setuptools/__init__.py\", line 116, in setup\n _install_setup_requires(attrs)\n File \"/tmp/pip-build-env-xwcrd_6j/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-xwcrd_6j/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-xwcrd_6j/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-xwcrd_6j/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:39:27.688129 | ubuntu-noble | } failure 2025-03-24 19:39:27.692069 | 2025-03-24 19:39:27.692177 | PLAY RECAP 2025-03-24 19:39:27.692261 | ubuntu-noble | ok: 23 changed: 12 unreachable: 0 failed: 1 skipped: 14 rescued: 0 ignored: 0 2025-03-24 19:39:27.692301 | 2025-03-24 19:39:27.821538 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-ceph-ansible/playbooks/pre.yml@main] 2025-03-24 19:39:27.824884 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-03-24 19:39:28.544473 | 2025-03-24 19:39:28.544614 | PLAY [Base post] 2025-03-24 19:39:28.569684 | 2025-03-24 19:39:28.569809 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-03-24 19:39:29.364763 | ubuntu-noble | changed 2025-03-24 19:39:29.400270 | 2025-03-24 19:39:29.400464 | PLAY RECAP 2025-03-24 19:39:29.400539 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-03-24 19:39:29.400603 | 2025-03-24 19:39:29.504570 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-03-24 19:39:29.512247 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-03-24 19:39:30.291209 | 2025-03-24 19:39:30.291375 | PLAY [Base post-logs] 2025-03-24 19:39:30.308836 | 2025-03-24 19:39:30.308971 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-03-24 19:39:30.751322 | localhost | changed 2025-03-24 19:39:30.757181 | 2025-03-24 19:39:30.757328 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-03-24 19:39:30.798151 | localhost | ok 2025-03-24 19:39:30.807070 | 2025-03-24 19:39:30.807185 | TASK [Set zuul-log-path fact] 2025-03-24 19:39:30.828714 | localhost | ok 2025-03-24 19:39:30.840437 | 2025-03-24 19:39:30.840541 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-03-24 19:39:30.879731 | localhost | ok 2025-03-24 19:39:30.888160 | 2025-03-24 19:39:30.888286 | TASK [upload-logs : Create log directories] 2025-03-24 19:39:31.414258 | localhost | changed 2025-03-24 19:39:31.422467 | 2025-03-24 19:39:31.422618 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-03-24 19:39:31.928249 | localhost -> localhost | ok: Runtime: 0:00:00.007056 2025-03-24 19:39:31.934566 | 2025-03-24 19:39:31.934679 | TASK [upload-logs : Upload logs to log server] 2025-03-24 19:39:32.523318 | localhost | Output suppressed because no_log was given 2025-03-24 19:39:32.530344 | 2025-03-24 19:39:32.530600 | LOOP [upload-logs : Compress console log and json output] 2025-03-24 19:39:32.596029 | localhost | skipping: Conditional result was False 2025-03-24 19:39:32.612480 | localhost | skipping: Conditional result was False 2025-03-24 19:39:32.627618 | 2025-03-24 19:39:32.627803 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-24 19:39:32.698289 | localhost | skipping: Conditional result was False 2025-03-24 19:39:32.698981 | 2025-03-24 19:39:32.711050 | localhost | skipping: Conditional result was False 2025-03-24 19:39:32.725972 | 2025-03-24 19:39:32.726190 | LOOP [upload-logs : Upload console log and json output]