2025-03-24 19:10:55.219444 | Job console starting... 2025-03-24 19:10:55.231856 | Updating repositories 2025-03-24 19:10:55.270883 | Preparing job workspace 2025-03-24 19:10:56.212259 | Running Ansible setup... 2025-03-24 19:11:01.708436 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-24 19:11:02.415623 | 2025-03-24 19:11:02.415760 | PLAY [Base pre] 2025-03-24 19:11:02.460089 | 2025-03-24 19:11:02.460229 | TASK [Setup log path fact] 2025-03-24 19:11:02.481179 | ubuntu-noble | ok 2025-03-24 19:11:02.499335 | 2025-03-24 19:11:02.499456 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-03-24 19:11:02.541184 | ubuntu-noble | ok 2025-03-24 19:11:02.554085 | 2025-03-24 19:11:02.554184 | TASK [emit-job-header : Print job information] 2025-03-24 19:11:02.622794 | # Job Information 2025-03-24 19:11:02.623058 | Ansible Version: 2.15.3 2025-03-24 19:11:02.623123 | Job: ansible-collection-validations-container-image-osism-ansible-push 2025-03-24 19:11:02.623180 | Pipeline: post 2025-03-24 19:11:02.623244 | Executor: 7d211f194f6a 2025-03-24 19:11:02.623285 | Triggered by: https://github.com/osism/ansible-collection-validations/commit/7bca25709ad12578b4d26cf2d0ffdd43497922e3 2025-03-24 19:11:02.623325 | Event ID: b312ab7e-08e3-11f0-9de1-e0d4428b56c7 2025-03-24 19:11:02.633176 | 2025-03-24 19:11:02.633350 | LOOP [emit-job-header : Print node information] 2025-03-24 19:11:02.785576 | ubuntu-noble | ok: 2025-03-24 19:11:02.785799 | ubuntu-noble | # Node Information 2025-03-24 19:11:02.785845 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-03-24 19:11:02.785880 | ubuntu-noble | Hostname: ubuntu 2025-03-24 19:11:02.785910 | ubuntu-noble | Username: zuul 2025-03-24 19:11:02.785939 | ubuntu-noble | Distro: Ubuntu 24.04 2025-03-24 19:11:02.785972 | ubuntu-noble | Provider: regiocloud-a 2025-03-24 19:11:02.786002 | ubuntu-noble | Label: ubuntu-noble 2025-03-24 19:11:02.786030 | ubuntu-noble | Product Name: OpenStack Nova 2025-03-24 19:11:02.786058 | ubuntu-noble | Interface IP: 81.163.193.107 2025-03-24 19:11:02.817748 | 2025-03-24 19:11:02.817889 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-24 19:11:03.305055 | ubuntu-noble -> localhost | changed 2025-03-24 19:11:03.323349 | 2025-03-24 19:11:03.323511 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-24 19:11:04.360009 | ubuntu-noble -> localhost | changed 2025-03-24 19:11:04.385680 | 2025-03-24 19:11:04.385804 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-24 19:11:04.666799 | ubuntu-noble -> localhost | ok 2025-03-24 19:11:04.682363 | 2025-03-24 19:11:04.682523 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-24 19:11:04.731782 | ubuntu-noble | ok 2025-03-24 19:11:04.751759 | ubuntu-noble | included: /var/lib/zuul/builds/2a2e9f3a05f545c4a853da86667381ba/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-24 19:11:04.759614 | 2025-03-24 19:11:04.759712 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-24 19:11:05.608825 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-03-24 19:11:05.609285 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/2a2e9f3a05f545c4a853da86667381ba/work/2a2e9f3a05f545c4a853da86667381ba_id_rsa 2025-03-24 19:11:05.609385 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/2a2e9f3a05f545c4a853da86667381ba/work/2a2e9f3a05f545c4a853da86667381ba_id_rsa.pub 2025-03-24 19:11:05.609456 | ubuntu-noble -> localhost | The key fingerprint is: 2025-03-24 19:11:05.609524 | ubuntu-noble -> localhost | SHA256:u/iVRQLHIQoTEaCqGyYMSvzOe8D0bxnaSJ6/ZQHitok zuul-build-sshkey 2025-03-24 19:11:05.609585 | ubuntu-noble -> localhost | The key's randomart image is: 2025-03-24 19:11:05.609644 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-03-24 19:11:05.609701 | ubuntu-noble -> localhost | | ..*+ o.o. | 2025-03-24 19:11:05.609757 | ubuntu-noble -> localhost | | . o . +. | 2025-03-24 19:11:05.609833 | ubuntu-noble -> localhost | |. ... . . | 2025-03-24 19:11:05.609892 | ubuntu-noble -> localhost | |o .. . . o | 2025-03-24 19:11:05.609946 | ubuntu-noble -> localhost | |ooo .o S. . | 2025-03-24 19:11:05.610003 | ubuntu-noble -> localhost | |* .oooo. ..o | 2025-03-24 19:11:05.610067 | ubuntu-noble -> localhost | |=o E+o* +oo | 2025-03-24 19:11:05.610125 | ubuntu-noble -> localhost | |ooo * *oo | 2025-03-24 19:11:05.610181 | ubuntu-noble -> localhost | |. +o +++ | 2025-03-24 19:11:05.610303 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-03-24 19:11:05.610435 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.358160 2025-03-24 19:11:05.626525 | 2025-03-24 19:11:05.626676 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-24 19:11:05.671886 | ubuntu-noble | ok 2025-03-24 19:11:05.686819 | ubuntu-noble | included: /var/lib/zuul/builds/2a2e9f3a05f545c4a853da86667381ba/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-24 19:11:05.696341 | 2025-03-24 19:11:05.696470 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-24 19:11:05.720734 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 19:11:05.729144 | 2025-03-24 19:11:05.729296 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-24 19:11:06.502041 | ubuntu-noble | changed 2025-03-24 19:11:06.511888 | 2025-03-24 19:11:06.512010 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-24 19:11:06.818680 | ubuntu-noble | ok 2025-03-24 19:11:06.828586 | 2025-03-24 19:11:06.828714 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-24 19:11:07.633130 | ubuntu-noble | changed 2025-03-24 19:11:07.643528 | 2025-03-24 19:11:07.643655 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-24 19:11:08.406975 | ubuntu-noble | changed 2025-03-24 19:11:08.417294 | 2025-03-24 19:11:08.417422 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-24 19:11:08.452376 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 19:11:08.465783 | 2025-03-24 19:11:08.465937 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-24 19:11:08.898058 | ubuntu-noble -> localhost | changed 2025-03-24 19:11:08.921270 | 2025-03-24 19:11:08.921413 | TASK [add-build-sshkey : Add back temp key] 2025-03-24 19:11:09.268869 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/2a2e9f3a05f545c4a853da86667381ba/work/2a2e9f3a05f545c4a853da86667381ba_id_rsa (zuul-build-sshkey) 2025-03-24 19:11:09.269155 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.013658 2025-03-24 19:11:09.276847 | 2025-03-24 19:11:09.276961 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-24 19:11:09.766752 | ubuntu-noble | ok 2025-03-24 19:11:09.777037 | 2025-03-24 19:11:09.777155 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-24 19:11:09.812820 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 19:11:09.838994 | 2025-03-24 19:11:09.839121 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-24 19:11:10.378648 | ubuntu-noble | ok 2025-03-24 19:11:10.390817 | 2025-03-24 19:11:10.390922 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-24 19:11:10.432103 | ubuntu-noble | ok 2025-03-24 19:11:10.439676 | 2025-03-24 19:11:10.439777 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-24 19:11:10.743977 | ubuntu-noble -> localhost | ok 2025-03-24 19:11:10.760997 | 2025-03-24 19:11:10.761219 | TASK [validate-host : Collect information about the host] 2025-03-24 19:11:12.012456 | ubuntu-noble | ok 2025-03-24 19:11:12.026849 | 2025-03-24 19:11:12.026959 | TASK [validate-host : Sanitize hostname] 2025-03-24 19:11:12.104496 | ubuntu-noble | ok 2025-03-24 19:11:12.112583 | 2025-03-24 19:11:12.112690 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-24 19:11:12.761159 | ubuntu-noble -> localhost | changed 2025-03-24 19:11:12.776250 | 2025-03-24 19:11:12.776489 | TASK [validate-host : Collect information about zuul worker] 2025-03-24 19:11:13.278291 | ubuntu-noble | ok 2025-03-24 19:11:13.286713 | 2025-03-24 19:11:13.286833 | TASK [validate-host : Write out all zuul information for each host] 2025-03-24 19:11:13.843554 | ubuntu-noble -> localhost | changed 2025-03-24 19:11:13.871297 | 2025-03-24 19:11:13.871444 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-24 19:11:14.219243 | ubuntu-noble | ok 2025-03-24 19:11:14.229230 | 2025-03-24 19:11:14.229345 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-24 19:11:15.182048 | ubuntu-noble | changed: 2025-03-24 19:11:15.182343 | ubuntu-noble | cd+++++++++ src/ 2025-03-24 19:11:15.182398 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-03-24 19:11:15.182434 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-03-24 19:11:15.182467 | ubuntu-noble | cd+++++++++ src/github.com/osism/ansible-collection-validations/ 2025-03-24 19:11:15.182496 | 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-poec9gjs/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-poec9gjs/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-poec9gjs/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-poec9gjs/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-poec9gjs/overlay/lib/python3.12/site-packages/setuptools/__init__.py\", line 116, in setup\n _install_setup_requires(attrs)\n File \"/tmp/pip-build-env-poec9gjs/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-poec9gjs/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-poec9gjs/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-poec9gjs/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:12:19.382134 | ubuntu-noble | } failure 2025-03-24 19:12:19.385004 | 2025-03-24 19:12:19.385112 | PLAY RECAP 2025-03-24 19:12:19.385186 | ubuntu-noble | ok: 23 changed: 12 unreachable: 0 failed: 1 skipped: 14 rescued: 0 ignored: 0 2025-03-24 19:12:19.385239 | 2025-03-24 19:12:19.522743 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-osism-ansible/playbooks/pre.yml@main] 2025-03-24 19:12:19.530331 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-03-24 19:12:20.215570 | 2025-03-24 19:12:20.215724 | PLAY [Base post] 2025-03-24 19:12:20.255697 | 2025-03-24 19:12:20.255840 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-03-24 19:12:21.155033 | ubuntu-noble | changed 2025-03-24 19:12:21.189173 | 2025-03-24 19:12:21.189309 | PLAY RECAP 2025-03-24 19:12:21.189373 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-03-24 19:12:21.189438 | 2025-03-24 19:12:21.296445 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-03-24 19:12:21.305060 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-03-24 19:12:22.046174 | 2025-03-24 19:12:22.046343 | PLAY [Base post-logs] 2025-03-24 19:12:22.062452 | 2025-03-24 19:12:22.062579 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-03-24 19:12:22.534562 | localhost | changed 2025-03-24 19:12:22.541591 | 2025-03-24 19:12:22.541782 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-03-24 19:12:22.574660 | localhost | ok 2025-03-24 19:12:22.586879 | 2025-03-24 19:12:22.586990 | TASK [Set zuul-log-path fact] 2025-03-24 19:12:22.617086 | localhost | ok 2025-03-24 19:12:22.628329 | 2025-03-24 19:12:22.628465 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-03-24 19:12:22.667158 | localhost | ok 2025-03-24 19:12:22.676175 | 2025-03-24 19:12:22.676322 | TASK [upload-logs : Create log directories] 2025-03-24 19:12:23.186363 | localhost | changed 2025-03-24 19:12:23.193883 | 2025-03-24 19:12:23.194016 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-03-24 19:12:23.713923 | localhost -> localhost | ok: Runtime: 0:00:00.007604 2025-03-24 19:12:23.727163 | 2025-03-24 19:12:23.727361 | TASK [upload-logs : Upload logs to log server] 2025-03-24 19:12:24.300154 | localhost | Output suppressed because no_log was given 2025-03-24 19:12:24.303389 | 2025-03-24 19:12:24.303504 | LOOP [upload-logs : Compress console log and json output] 2025-03-24 19:12:24.372693 | localhost | skipping: Conditional result was False 2025-03-24 19:12:24.388970 | localhost | skipping: Conditional result was False 2025-03-24 19:12:24.408826 | 2025-03-24 19:12:24.409047 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-24 19:12:24.475828 | localhost | skipping: Conditional result was False 2025-03-24 19:12:24.476270 | 2025-03-24 19:12:24.501485 | localhost | skipping: Conditional result was False 2025-03-24 19:12:24.516764 | 2025-03-24 19:12:24.516976 | LOOP [upload-logs : Upload console log and json output]