2025-03-24 19:13:57.238269 | Job console starting... 2025-03-24 19:13:57.246880 | Updating repositories 2025-03-24 19:13:57.291804 | Preparing job workspace 2025-03-24 19:13:58.263583 | Running Ansible setup... 2025-03-24 19:14:03.762642 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-24 19:14:04.524466 | 2025-03-24 19:14:04.524614 | PLAY [Base pre] 2025-03-24 19:14:04.554832 | 2025-03-24 19:14:04.554960 | TASK [Setup log path fact] 2025-03-24 19:14:04.575937 | ubuntu-noble | ok 2025-03-24 19:14:04.594400 | 2025-03-24 19:14:04.594527 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-03-24 19:14:04.624630 | ubuntu-noble | ok 2025-03-24 19:14:04.637526 | 2025-03-24 19:14:04.637628 | TASK [emit-job-header : Print job information] 2025-03-24 19:14:04.704670 | # Job Information 2025-03-24 19:14:04.704881 | Ansible Version: 2.15.3 2025-03-24 19:14:04.704931 | Job: ansible-collection-validations-container-image-osism-ansible-push 2025-03-24 19:14:04.704974 | Pipeline: post 2025-03-24 19:14:04.705005 | Executor: 7d211f194f6a 2025-03-24 19:14:04.705033 | Triggered by: https://github.com/osism/ansible-collection-validations/commit/7bca25709ad12578b4d26cf2d0ffdd43497922e3 2025-03-24 19:14:04.705061 | Event ID: b312ab7e-08e3-11f0-9de1-e0d4428b56c7 2025-03-24 19:14:04.712791 | 2025-03-24 19:14:04.712908 | LOOP [emit-job-header : Print node information] 2025-03-24 19:14:04.866400 | ubuntu-noble | ok: 2025-03-24 19:14:04.866573 | ubuntu-noble | # Node Information 2025-03-24 19:14:04.866607 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-03-24 19:14:04.866632 | ubuntu-noble | Hostname: ubuntu 2025-03-24 19:14:04.866654 | ubuntu-noble | Username: zuul 2025-03-24 19:14:04.866675 | ubuntu-noble | Distro: Ubuntu 24.04 2025-03-24 19:14:04.866699 | ubuntu-noble | Provider: regiocloud-a 2025-03-24 19:14:04.866719 | ubuntu-noble | Label: ubuntu-noble 2025-03-24 19:14:04.866740 | ubuntu-noble | Product Name: OpenStack Nova 2025-03-24 19:14:04.866759 | ubuntu-noble | Interface IP: 81.163.193.47 2025-03-24 19:14:04.895050 | 2025-03-24 19:14:04.895184 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-24 19:14:05.419444 | ubuntu-noble -> localhost | changed 2025-03-24 19:14:05.436576 | 2025-03-24 19:14:05.436733 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-24 19:14:06.478933 | ubuntu-noble -> localhost | changed 2025-03-24 19:14:06.516096 | 2025-03-24 19:14:06.516242 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-24 19:14:06.788709 | ubuntu-noble -> localhost | ok 2025-03-24 19:14:06.804597 | 2025-03-24 19:14:06.804845 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-24 19:14:06.846297 | ubuntu-noble | ok 2025-03-24 19:14:06.865480 | ubuntu-noble | included: /var/lib/zuul/builds/f91274726d464cb082453746f459ec82/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-24 19:14:06.873431 | 2025-03-24 19:14:06.873532 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-24 19:14:07.469628 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-03-24 19:14:07.469855 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/f91274726d464cb082453746f459ec82/work/f91274726d464cb082453746f459ec82_id_rsa 2025-03-24 19:14:07.469894 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/f91274726d464cb082453746f459ec82/work/f91274726d464cb082453746f459ec82_id_rsa.pub 2025-03-24 19:14:07.469922 | ubuntu-noble -> localhost | The key fingerprint is: 2025-03-24 19:14:07.469948 | ubuntu-noble -> localhost | SHA256:MtsgDgzimyHZ+J2sXAnuhG3WnZOeMie9pI408SnwyO4 zuul-build-sshkey 2025-03-24 19:14:07.469971 | ubuntu-noble -> localhost | The key's randomart image is: 2025-03-24 19:14:07.469993 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-03-24 19:14:07.470015 | ubuntu-noble -> localhost | | | 2025-03-24 19:14:07.470036 | ubuntu-noble -> localhost | | | 2025-03-24 19:14:07.470070 | ubuntu-noble -> localhost | |o | 2025-03-24 19:14:07.470092 | ubuntu-noble -> localhost | |o* | 2025-03-24 19:14:07.470113 | ubuntu-noble -> localhost | |=o* . + S | 2025-03-24 19:14:07.470134 | ubuntu-noble -> localhost | |oO+X * O | 2025-03-24 19:14:07.470163 | ubuntu-noble -> localhost | |o+% @.B . | 2025-03-24 19:14:07.470185 | ubuntu-noble -> localhost | |.B *++oo | 2025-03-24 19:14:07.470255 | ubuntu-noble -> localhost | |.E=.o=+. | 2025-03-24 19:14:07.470279 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-03-24 19:14:07.470337 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.126694 2025-03-24 19:14:07.480560 | 2025-03-24 19:14:07.480684 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-24 19:14:07.523620 | ubuntu-noble | ok 2025-03-24 19:14:07.535746 | ubuntu-noble | included: /var/lib/zuul/builds/f91274726d464cb082453746f459ec82/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-24 19:14:07.545567 | 2025-03-24 19:14:07.545672 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-24 19:14:07.570240 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 19:14:07.588495 | 2025-03-24 19:14:07.588653 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-24 19:14:08.372954 | ubuntu-noble | changed 2025-03-24 19:14:08.384463 | 2025-03-24 19:14:08.384588 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-24 19:14:08.699994 | ubuntu-noble | ok 2025-03-24 19:14:08.710401 | 2025-03-24 19:14:08.710535 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-24 19:14:09.433257 | ubuntu-noble | changed 2025-03-24 19:14:09.443455 | 2025-03-24 19:14:09.443584 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-24 19:14:10.188932 | ubuntu-noble | changed 2025-03-24 19:14:10.198533 | 2025-03-24 19:14:10.198654 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-24 19:14:10.233769 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 19:14:10.247914 | 2025-03-24 19:14:10.248045 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-24 19:14:10.682486 | ubuntu-noble -> localhost | changed 2025-03-24 19:14:10.710204 | 2025-03-24 19:14:10.710346 | TASK [add-build-sshkey : Add back temp key] 2025-03-24 19:14:11.061610 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/f91274726d464cb082453746f459ec82/work/f91274726d464cb082453746f459ec82_id_rsa (zuul-build-sshkey) 2025-03-24 19:14:11.062031 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.016325 2025-03-24 19:14:11.076445 | 2025-03-24 19:14:11.076581 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-24 19:14:11.513183 | ubuntu-noble | ok 2025-03-24 19:14:11.524802 | 2025-03-24 19:14:11.524928 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-24 19:14:11.562192 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 19:14:11.588863 | 2025-03-24 19:14:11.588985 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-24 19:14:12.037816 | ubuntu-noble | ok 2025-03-24 19:14:12.086158 | 2025-03-24 19:14:12.086303 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-24 19:14:12.132080 | ubuntu-noble | ok 2025-03-24 19:14:12.141041 | 2025-03-24 19:14:12.141154 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-24 19:14:12.442138 | ubuntu-noble -> localhost | ok 2025-03-24 19:14:12.458052 | 2025-03-24 19:14:12.458229 | TASK [validate-host : Collect information about the host] 2025-03-24 19:14:13.633824 | ubuntu-noble | ok 2025-03-24 19:14:13.650739 | 2025-03-24 19:14:13.650866 | TASK [validate-host : Sanitize hostname] 2025-03-24 19:14:13.729484 | ubuntu-noble | ok 2025-03-24 19:14:13.738496 | 2025-03-24 19:14:13.738621 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-24 19:14:14.304980 | ubuntu-noble -> localhost | changed 2025-03-24 19:14:14.312344 | 2025-03-24 19:14:14.312489 | TASK [validate-host : Collect information about zuul worker] 2025-03-24 19:14:14.792052 | ubuntu-noble | ok 2025-03-24 19:14:14.800693 | 2025-03-24 19:14:14.800840 | TASK [validate-host : Write out all zuul information for each host] 2025-03-24 19:14:15.338588 | ubuntu-noble -> localhost | changed 2025-03-24 19:14:15.361003 | 2025-03-24 19:14:15.361134 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-24 19:14:15.675776 | ubuntu-noble | ok 2025-03-24 19:14:15.685856 | 2025-03-24 19:14:15.685972 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-24 19:14:16.516666 | ubuntu-noble | changed: 2025-03-24 19:14:16.516857 | ubuntu-noble | cd+++++++++ src/ 2025-03-24 19:14:16.516893 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-03-24 19:14:16.516919 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-03-24 19:14:16.516941 | ubuntu-noble | cd+++++++++ src/github.com/osism/ansible-collection-validations/ 2025-03-24 19:14:16.516961 | 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-x2w1xgd7/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-x2w1xgd7/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-x2w1xgd7/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-x2w1xgd7/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-x2w1xgd7/overlay/lib/python3.12/site-packages/setuptools/__init__.py\", line 116, in setup\n _install_setup_requires(attrs)\n File \"/tmp/pip-build-env-x2w1xgd7/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-x2w1xgd7/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-x2w1xgd7/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-x2w1xgd7/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:15:17.697781 | ubuntu-noble | } failure 2025-03-24 19:15:17.699907 | 2025-03-24 19:15:17.699990 | PLAY RECAP 2025-03-24 19:15:17.700049 | ubuntu-noble | ok: 23 changed: 12 unreachable: 0 failed: 1 skipped: 14 rescued: 0 ignored: 0 2025-03-24 19:15:17.700106 | 2025-03-24 19:15:17.831973 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-osism-ansible/playbooks/pre.yml@main] 2025-03-24 19:15:17.839913 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-03-24 19:15:18.519244 | 2025-03-24 19:15:18.519384 | PLAY [Base post] 2025-03-24 19:15:18.544773 | 2025-03-24 19:15:18.544895 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-03-24 19:15:19.403598 | ubuntu-noble | changed 2025-03-24 19:15:19.438457 | 2025-03-24 19:15:19.438567 | PLAY RECAP 2025-03-24 19:15:19.438630 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-03-24 19:15:19.438692 | 2025-03-24 19:15:19.540481 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-03-24 19:15:19.548955 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-03-24 19:15:20.295927 | 2025-03-24 19:15:20.296067 | PLAY [Base post-logs] 2025-03-24 19:15:20.311761 | 2025-03-24 19:15:20.311879 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-03-24 19:15:20.754811 | localhost | changed 2025-03-24 19:15:20.762120 | 2025-03-24 19:15:20.762332 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-03-24 19:15:20.805652 | localhost | ok 2025-03-24 19:15:20.820822 | 2025-03-24 19:15:20.820938 | TASK [Set zuul-log-path fact] 2025-03-24 19:15:20.857700 | localhost | ok 2025-03-24 19:15:20.875102 | 2025-03-24 19:15:20.875284 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-03-24 19:15:20.915500 | localhost | ok 2025-03-24 19:15:20.924228 | 2025-03-24 19:15:20.924362 | TASK [upload-logs : Create log directories] 2025-03-24 19:15:21.440637 | localhost | changed 2025-03-24 19:15:21.448655 | 2025-03-24 19:15:21.448813 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-03-24 19:15:21.955283 | localhost -> localhost | ok: Runtime: 0:00:00.006874 2025-03-24 19:15:21.967674 | 2025-03-24 19:15:21.967845 | TASK [upload-logs : Upload logs to log server] 2025-03-24 19:15:22.542766 | localhost | Output suppressed because no_log was given 2025-03-24 19:15:22.546706 | 2025-03-24 19:15:22.546837 | LOOP [upload-logs : Compress console log and json output] 2025-03-24 19:15:22.617958 | localhost | skipping: Conditional result was False 2025-03-24 19:15:22.634623 | localhost | skipping: Conditional result was False 2025-03-24 19:15:22.652452 | 2025-03-24 19:15:22.652674 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-24 19:15:22.713530 | localhost | skipping: Conditional result was False 2025-03-24 19:15:22.714168 | 2025-03-24 19:15:22.725879 | localhost | skipping: Conditional result was False 2025-03-24 19:15:22.739294 | 2025-03-24 19:15:22.739475 | LOOP [upload-logs : Upload console log and json output]