2025-03-24 19:39:41.135231 | Job console starting... 2025-03-24 19:39:41.147742 | Updating repositories 2025-03-24 19:39:41.184490 | Preparing job workspace 2025-03-24 19:39:42.356648 | Running Ansible setup... 2025-03-24 19:39:47.890127 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-24 19:39:48.608785 | 2025-03-24 19:39:48.608947 | PLAY [Base pre] 2025-03-24 19:39:48.637255 | 2025-03-24 19:39:48.637405 | TASK [Setup log path fact] 2025-03-24 19:39:48.668483 | ubuntu-noble | ok 2025-03-24 19:39:48.687110 | 2025-03-24 19:39:48.687235 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-03-24 19:39:48.728751 | ubuntu-noble | ok 2025-03-24 19:39:48.741839 | 2025-03-24 19:39:48.741938 | TASK [emit-job-header : Print job information] 2025-03-24 19:39:48.792958 | # Job Information 2025-03-24 19:39:48.793122 | Ansible Version: 2.15.3 2025-03-24 19:39:48.793156 | Job: container-image-ceph-ansible-build-quincy 2025-03-24 19:39:48.793185 | Pipeline: check 2025-03-24 19:39:48.793206 | Executor: 7d211f194f6a 2025-03-24 19:39:48.793225 | Triggered by: https://github.com/osism/container-image-ceph-ansible/pull/570 2025-03-24 19:39:48.793243 | Event ID: 45177c40-08e7-11f0-81ae-9bea12ce1c59 2025-03-24 19:39:48.799666 | 2025-03-24 19:39:48.799772 | LOOP [emit-job-header : Print node information] 2025-03-24 19:39:48.937685 | ubuntu-noble | ok: 2025-03-24 19:39:48.938059 | ubuntu-noble | # Node Information 2025-03-24 19:39:48.938114 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-03-24 19:39:48.938142 | ubuntu-noble | Hostname: ubuntu 2025-03-24 19:39:48.938165 | ubuntu-noble | Username: zuul 2025-03-24 19:39:48.938186 | ubuntu-noble | Distro: Ubuntu 24.04 2025-03-24 19:39:48.938211 | ubuntu-noble | Provider: regiocloud-a 2025-03-24 19:39:48.938233 | ubuntu-noble | Label: ubuntu-noble 2025-03-24 19:39:48.938254 | ubuntu-noble | Product Name: OpenStack Nova 2025-03-24 19:39:48.938274 | ubuntu-noble | Interface IP: 81.163.193.151 2025-03-24 19:39:48.961218 | 2025-03-24 19:39:48.961351 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-24 19:39:49.454401 | ubuntu-noble -> localhost | changed 2025-03-24 19:39:49.462738 | 2025-03-24 19:39:49.462857 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-24 19:39:50.493172 | ubuntu-noble -> localhost | changed 2025-03-24 19:39:50.520133 | 2025-03-24 19:39:50.520274 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-24 19:39:50.790070 | ubuntu-noble -> localhost | ok 2025-03-24 19:39:50.806636 | 2025-03-24 19:39:50.806811 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-24 19:39:50.840060 | ubuntu-noble | ok 2025-03-24 19:39:50.856831 | ubuntu-noble | included: /var/lib/zuul/builds/70b59cffc8a84de8b02c1a0c5135af4c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-24 19:39:50.864740 | 2025-03-24 19:39:50.864841 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-24 19:39:51.625849 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-03-24 19:39:51.626074 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/70b59cffc8a84de8b02c1a0c5135af4c/work/70b59cffc8a84de8b02c1a0c5135af4c_id_rsa 2025-03-24 19:39:51.626115 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/70b59cffc8a84de8b02c1a0c5135af4c/work/70b59cffc8a84de8b02c1a0c5135af4c_id_rsa.pub 2025-03-24 19:39:51.626143 | ubuntu-noble -> localhost | The key fingerprint is: 2025-03-24 19:39:51.626170 | ubuntu-noble -> localhost | SHA256:g7BFjn2LsGNIld52ZP1Ms5Lyyt4t5aEzokBZI5eq7zo zuul-build-sshkey 2025-03-24 19:39:51.626208 | ubuntu-noble -> localhost | The key's randomart image is: 2025-03-24 19:39:51.626233 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-03-24 19:39:51.626256 | ubuntu-noble -> localhost | | ... . | 2025-03-24 19:39:51.626279 | ubuntu-noble -> localhost | | ..= .o . o | 2025-03-24 19:39:51.626302 | ubuntu-noble -> localhost | | ..=.Oo. = o | 2025-03-24 19:39:51.626339 | ubuntu-noble -> localhost | | . ..%o=o.o + | 2025-03-24 19:39:51.626363 | ubuntu-noble -> localhost | | . O.o.So . | 2025-03-24 19:39:51.626397 | ubuntu-noble -> localhost | | + . .. o | 2025-03-24 19:39:51.626429 | ubuntu-noble -> localhost | | . . . . + . | 2025-03-24 19:39:51.626454 | ubuntu-noble -> localhost | | E. . +.=.. | 2025-03-24 19:39:51.626477 | ubuntu-noble -> localhost | | .+o .o...+. | 2025-03-24 19:39:51.626501 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-03-24 19:39:51.626557 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.255534 2025-03-24 19:39:51.636322 | 2025-03-24 19:39:51.636503 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-24 19:39:51.682510 | ubuntu-noble | ok 2025-03-24 19:39:51.696525 | ubuntu-noble | included: /var/lib/zuul/builds/70b59cffc8a84de8b02c1a0c5135af4c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-24 19:39:51.706429 | 2025-03-24 19:39:51.706529 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-24 19:39:51.740630 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 19:39:51.748724 | 2025-03-24 19:39:51.748828 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-24 19:39:52.663116 | ubuntu-noble | changed 2025-03-24 19:39:52.673505 | 2025-03-24 19:39:52.673704 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-24 19:39:52.995987 | ubuntu-noble | ok 2025-03-24 19:39:53.003074 | 2025-03-24 19:39:53.003192 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-24 19:39:53.810135 | ubuntu-noble | changed 2025-03-24 19:39:53.824453 | 2025-03-24 19:39:53.824605 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-24 19:39:54.608884 | ubuntu-noble | changed 2025-03-24 19:39:54.619427 | 2025-03-24 19:39:54.619563 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-24 19:39:54.645649 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 19:39:54.661071 | 2025-03-24 19:39:54.661219 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-24 19:39:55.148527 | ubuntu-noble -> localhost | changed 2025-03-24 19:39:55.171320 | 2025-03-24 19:39:55.171463 | TASK [add-build-sshkey : Add back temp key] 2025-03-24 19:39:55.524832 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/70b59cffc8a84de8b02c1a0c5135af4c/work/70b59cffc8a84de8b02c1a0c5135af4c_id_rsa (zuul-build-sshkey) 2025-03-24 19:39:55.525136 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.014732 2025-03-24 19:39:55.533073 | 2025-03-24 19:39:55.533179 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-24 19:39:56.019667 | ubuntu-noble | ok 2025-03-24 19:39:56.028922 | 2025-03-24 19:39:56.029058 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-24 19:39:56.054593 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 19:39:56.073031 | 2025-03-24 19:39:56.073144 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-24 19:39:56.587488 | ubuntu-noble | ok 2025-03-24 19:39:56.603572 | 2025-03-24 19:39:56.603682 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-24 19:39:56.640287 | ubuntu-noble | ok 2025-03-24 19:39:56.653965 | 2025-03-24 19:39:56.654109 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-24 19:39:56.958516 | ubuntu-noble -> localhost | ok 2025-03-24 19:39:56.966874 | 2025-03-24 19:39:56.966983 | TASK [validate-host : Collect information about the host] 2025-03-24 19:39:58.227539 | ubuntu-noble | ok 2025-03-24 19:39:58.241856 | 2025-03-24 19:39:58.241971 | TASK [validate-host : Sanitize hostname] 2025-03-24 19:39:58.318723 | ubuntu-noble | ok 2025-03-24 19:39:58.330145 | 2025-03-24 19:39:58.330272 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-24 19:39:58.900998 | ubuntu-noble -> localhost | changed 2025-03-24 19:39:58.914526 | 2025-03-24 19:39:58.914688 | TASK [validate-host : Collect information about zuul worker] 2025-03-24 19:39:59.443891 | ubuntu-noble | ok 2025-03-24 19:39:59.456187 | 2025-03-24 19:39:59.456320 | TASK [validate-host : Write out all zuul information for each host] 2025-03-24 19:40:00.025231 | ubuntu-noble -> localhost | changed 2025-03-24 19:40:00.043477 | 2025-03-24 19:40:00.043611 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-24 19:40:00.418382 | ubuntu-noble | ok 2025-03-24 19:40:00.428187 | 2025-03-24 19:40:00.428301 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-24 19:40:01.246540 | ubuntu-noble | changed: 2025-03-24 19:40:01.246822 | ubuntu-noble | cd+++++++++ src/ 2025-03-24 19:40:01.246880 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-03-24 19:40:01.246921 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-03-24 19:40:01.246957 | ubuntu-noble | cd+++++++++ src/github.com/osism/container-image-ceph-ansible/ 2025-03-24 19:40:01.246990 | ubuntu-noble | Containerfile 2025-03-24 19:40:01.247240 | 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-mulvztj4/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-mulvztj4/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-mulvztj4/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-mulvztj4/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-mulvztj4/overlay/lib/python3.12/site-packages/setuptools/__init__.py\", line 116, in setup\n _install_setup_requires(attrs)\n File \"/tmp/pip-build-env-mulvztj4/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-mulvztj4/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-mulvztj4/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-mulvztj4/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:05.976868 | ubuntu-noble | } failure 2025-03-24 19:41:05.979201 | 2025-03-24 19:41:05.979286 | PLAY RECAP 2025-03-24 19:41:05.979353 | ubuntu-noble | ok: 23 changed: 12 unreachable: 0 failed: 1 skipped: 14 rescued: 0 ignored: 0 2025-03-24 19:41:05.979381 | 2025-03-24 19:41:06.125422 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-ceph-ansible/playbooks/pre.yml@main] 2025-03-24 19:41:06.128601 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-03-24 19:41:06.837573 | 2025-03-24 19:41:06.837725 | PLAY [Base post] 2025-03-24 19:41:06.863248 | 2025-03-24 19:41:06.863376 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-03-24 19:41:07.790715 | ubuntu-noble | changed 2025-03-24 19:41:07.826283 | 2025-03-24 19:41:07.826417 | PLAY RECAP 2025-03-24 19:41:07.826484 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-03-24 19:41:07.826582 | 2025-03-24 19:41:07.940179 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-03-24 19:41:07.943370 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-03-24 19:41:08.698738 | 2025-03-24 19:41:08.698899 | PLAY [Base post-logs] 2025-03-24 19:41:08.715727 | 2025-03-24 19:41:08.715860 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-03-24 19:41:09.266663 | localhost | changed 2025-03-24 19:41:09.272351 | 2025-03-24 19:41:09.272538 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-03-24 19:41:09.300980 | localhost | ok 2025-03-24 19:41:09.306941 | 2025-03-24 19:41:09.307039 | TASK [Set zuul-log-path fact] 2025-03-24 19:41:09.333963 | localhost | ok 2025-03-24 19:41:09.344132 | 2025-03-24 19:41:09.344235 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-03-24 19:41:09.378237 | localhost | ok 2025-03-24 19:41:09.391051 | 2025-03-24 19:41:09.391174 | TASK [upload-logs : Create log directories] 2025-03-24 19:41:09.925997 | localhost | changed 2025-03-24 19:41:09.933037 | 2025-03-24 19:41:09.933280 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-03-24 19:41:10.489328 | localhost -> localhost | ok: Runtime: 0:00:00.006934 2025-03-24 19:41:10.495600 | 2025-03-24 19:41:10.495728 | TASK [upload-logs : Upload logs to log server] 2025-03-24 19:41:11.053236 | localhost | Output suppressed because no_log was given 2025-03-24 19:41:11.059071 | 2025-03-24 19:41:11.059243 | LOOP [upload-logs : Compress console log and json output] 2025-03-24 19:41:11.123085 | localhost | skipping: Conditional result was False 2025-03-24 19:41:11.142474 | localhost | skipping: Conditional result was False 2025-03-24 19:41:11.153529 | 2025-03-24 19:41:11.153705 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-24 19:41:11.214636 | localhost | skipping: Conditional result was False 2025-03-24 19:41:11.214902 | 2025-03-24 19:41:11.229031 | localhost | skipping: Conditional result was False 2025-03-24 19:41:11.236854 | 2025-03-24 19:41:11.237027 | LOOP [upload-logs : Upload console log and json output]