2025-03-24 19:15:56.538911 | Job console starting... 2025-03-24 19:15:56.547774 | Updating repositories 2025-03-24 19:15:56.581249 | Preparing job workspace 2025-03-24 19:15:57.486978 | Running Ansible setup... 2025-03-24 19:16:03.001651 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-24 19:16:03.637184 | 2025-03-24 19:16:03.637365 | PLAY [Base pre] 2025-03-24 19:16:03.664764 | 2025-03-24 19:16:03.664865 | TASK [Setup log path fact] 2025-03-24 19:16:03.694876 | ubuntu-noble | ok 2025-03-24 19:16:03.712263 | 2025-03-24 19:16:03.712367 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-03-24 19:16:03.754803 | ubuntu-noble | ok 2025-03-24 19:16:03.768189 | 2025-03-24 19:16:03.768286 | TASK [emit-job-header : Print job information] 2025-03-24 19:16:03.823423 | # Job Information 2025-03-24 19:16:03.823648 | Ansible Version: 2.15.3 2025-03-24 19:16:03.823706 | Job: ansible-collection-validations-container-image-osism-ansible-push 2025-03-24 19:16:03.823757 | Pipeline: post 2025-03-24 19:16:03.823794 | Executor: 7d211f194f6a 2025-03-24 19:16:03.823827 | Triggered by: https://github.com/osism/ansible-collection-validations/commit/93a6bbd25503350f64044ff62adec70269b28e84 2025-03-24 19:16:03.823861 | Event ID: bea34f02-08e3-11f0-8ca6-6c97b72aa678 2025-03-24 19:16:03.834451 | 2025-03-24 19:16:03.834570 | LOOP [emit-job-header : Print node information] 2025-03-24 19:16:03.971272 | ubuntu-noble | ok: 2025-03-24 19:16:03.971657 | ubuntu-noble | # Node Information 2025-03-24 19:16:03.971725 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-03-24 19:16:03.971768 | ubuntu-noble | Hostname: ubuntu 2025-03-24 19:16:03.971803 | ubuntu-noble | Username: zuul 2025-03-24 19:16:03.971836 | ubuntu-noble | Distro: Ubuntu 24.04 2025-03-24 19:16:03.971875 | ubuntu-noble | Provider: regiocloud-a 2025-03-24 19:16:03.971908 | ubuntu-noble | Label: ubuntu-noble 2025-03-24 19:16:03.971940 | ubuntu-noble | Product Name: OpenStack Nova 2025-03-24 19:16:03.971968 | ubuntu-noble | Interface IP: 81.163.192.186 2025-03-24 19:16:04.005640 | 2025-03-24 19:16:04.005774 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-24 19:16:04.429098 | ubuntu-noble -> localhost | changed 2025-03-24 19:16:04.446889 | 2025-03-24 19:16:04.447044 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-24 19:16:05.380478 | ubuntu-noble -> localhost | changed 2025-03-24 19:16:05.398906 | 2025-03-24 19:16:05.399034 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-24 19:16:05.672058 | ubuntu-noble -> localhost | ok 2025-03-24 19:16:05.679253 | 2025-03-24 19:16:05.679355 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-24 19:16:05.708496 | ubuntu-noble | ok 2025-03-24 19:16:05.723737 | ubuntu-noble | included: /var/lib/zuul/builds/9f37f971cb7645439fa113706b1735aa/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-24 19:16:05.730952 | 2025-03-24 19:16:05.731031 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-24 19:16:06.714697 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-03-24 19:16:06.715156 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/9f37f971cb7645439fa113706b1735aa/work/9f37f971cb7645439fa113706b1735aa_id_rsa 2025-03-24 19:16:06.715344 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/9f37f971cb7645439fa113706b1735aa/work/9f37f971cb7645439fa113706b1735aa_id_rsa.pub 2025-03-24 19:16:06.715424 | ubuntu-noble -> localhost | The key fingerprint is: 2025-03-24 19:16:06.715496 | ubuntu-noble -> localhost | SHA256:nn257Qm+0sQ+vaJc8w4W+jeE8I+cJxkvo1t8tf8Iqyo zuul-build-sshkey 2025-03-24 19:16:06.715558 | ubuntu-noble -> localhost | The key's randomart image is: 2025-03-24 19:16:06.715619 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-03-24 19:16:06.715677 | ubuntu-noble -> localhost | | | 2025-03-24 19:16:06.715734 | ubuntu-noble -> localhost | | | 2025-03-24 19:16:06.715811 | ubuntu-noble -> localhost | | | 2025-03-24 19:16:06.715870 | ubuntu-noble -> localhost | | . | 2025-03-24 19:16:06.715925 | ubuntu-noble -> localhost | | S +.. .| 2025-03-24 19:16:06.715981 | ubuntu-noble -> localhost | | . o o*o...| 2025-03-24 19:16:06.716053 | ubuntu-noble -> localhost | | o o=%X.. | 2025-03-24 19:16:06.716114 | ubuntu-noble -> localhost | | E ..B&@Bo.| 2025-03-24 19:16:06.716171 | ubuntu-noble -> localhost | | ...***%Oo+| 2025-03-24 19:16:06.716254 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-03-24 19:16:06.716428 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.534811 2025-03-24 19:16:06.733810 | 2025-03-24 19:16:06.733981 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-24 19:16:06.774321 | ubuntu-noble | ok 2025-03-24 19:16:06.788271 | ubuntu-noble | included: /var/lib/zuul/builds/9f37f971cb7645439fa113706b1735aa/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-24 19:16:06.797886 | 2025-03-24 19:16:06.797991 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-24 19:16:06.832728 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 19:16:06.844269 | 2025-03-24 19:16:06.844434 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-24 19:16:07.520411 | ubuntu-noble | changed 2025-03-24 19:16:07.529637 | 2025-03-24 19:16:07.529759 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-24 19:16:07.819714 | ubuntu-noble | ok 2025-03-24 19:16:07.830085 | 2025-03-24 19:16:07.830255 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-24 19:16:08.574322 | ubuntu-noble | changed 2025-03-24 19:16:08.584362 | 2025-03-24 19:16:08.584493 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-24 19:16:09.298378 | ubuntu-noble | changed 2025-03-24 19:16:09.309359 | 2025-03-24 19:16:09.309485 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-24 19:16:09.344381 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 19:16:09.360373 | 2025-03-24 19:16:09.360543 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-24 19:16:09.771992 | ubuntu-noble -> localhost | changed 2025-03-24 19:16:09.797000 | 2025-03-24 19:16:09.797114 | TASK [add-build-sshkey : Add back temp key] 2025-03-24 19:16:10.128321 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/9f37f971cb7645439fa113706b1735aa/work/9f37f971cb7645439fa113706b1735aa_id_rsa (zuul-build-sshkey) 2025-03-24 19:16:10.128867 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.015620 2025-03-24 19:16:10.147447 | 2025-03-24 19:16:10.147607 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-24 19:16:10.595675 | ubuntu-noble | ok 2025-03-24 19:16:10.604947 | 2025-03-24 19:16:10.605076 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-24 19:16:10.640703 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 19:16:10.666624 | 2025-03-24 19:16:10.666757 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-24 19:16:11.125965 | ubuntu-noble | ok 2025-03-24 19:16:11.170580 | 2025-03-24 19:16:11.170705 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-24 19:16:11.211916 | ubuntu-noble | ok 2025-03-24 19:16:11.218386 | 2025-03-24 19:16:11.218493 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-24 19:16:11.528185 | ubuntu-noble -> localhost | ok 2025-03-24 19:16:11.543825 | 2025-03-24 19:16:11.543979 | TASK [validate-host : Collect information about the host] 2025-03-24 19:16:12.687915 | ubuntu-noble | ok 2025-03-24 19:16:12.702599 | 2025-03-24 19:16:12.702719 | TASK [validate-host : Sanitize hostname] 2025-03-24 19:16:12.780492 | ubuntu-noble | ok 2025-03-24 19:16:12.789343 | 2025-03-24 19:16:12.789469 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-24 19:16:13.344702 | ubuntu-noble -> localhost | changed 2025-03-24 19:16:13.351795 | 2025-03-24 19:16:13.351917 | TASK [validate-host : Collect information about zuul worker] 2025-03-24 19:16:13.850044 | ubuntu-noble | ok 2025-03-24 19:16:13.856189 | 2025-03-24 19:16:13.856327 | TASK [validate-host : Write out all zuul information for each host] 2025-03-24 19:16:14.422043 | ubuntu-noble -> localhost | changed 2025-03-24 19:16:14.441508 | 2025-03-24 19:16:14.441637 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-24 19:16:14.805532 | ubuntu-noble | ok 2025-03-24 19:16:14.814980 | 2025-03-24 19:16:14.815100 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-24 19:16:15.654272 | ubuntu-noble | changed: 2025-03-24 19:16:15.654504 | ubuntu-noble | cd+++++++++ src/ 2025-03-24 19:16:15.654549 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-03-24 19:16:15.654581 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-03-24 19:16:15.654608 | ubuntu-noble | cd+++++++++ src/github.com/osism/ansible-collection-validations/ 2025-03-24 19:16:15.654633 | 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-tlpctxbq/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-tlpctxbq/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-tlpctxbq/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-tlpctxbq/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-tlpctxbq/overlay/lib/python3.12/site-packages/setuptools/__init__.py\", line 116, in setup\n _install_setup_requires(attrs)\n File \"/tmp/pip-build-env-tlpctxbq/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-tlpctxbq/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-tlpctxbq/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-tlpctxbq/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:17:15.073259 | ubuntu-noble | } failure 2025-03-24 19:17:15.076342 | 2025-03-24 19:17:15.076537 | PLAY RECAP 2025-03-24 19:17:15.076611 | ubuntu-noble | ok: 23 changed: 12 unreachable: 0 failed: 1 skipped: 14 rescued: 0 ignored: 0 2025-03-24 19:17:15.076645 | 2025-03-24 19:17:15.201694 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-osism-ansible/playbooks/pre.yml@main] 2025-03-24 19:17:15.209321 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-03-24 19:17:15.892299 | 2025-03-24 19:17:15.892505 | PLAY [Base post] 2025-03-24 19:17:15.918141 | 2025-03-24 19:17:15.918283 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-03-24 19:17:16.763328 | ubuntu-noble | changed 2025-03-24 19:17:16.797921 | 2025-03-24 19:17:16.798041 | PLAY RECAP 2025-03-24 19:17:16.798108 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-03-24 19:17:16.798171 | 2025-03-24 19:17:16.907149 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-03-24 19:17:16.915044 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-03-24 19:17:17.630687 | 2025-03-24 19:17:17.630837 | PLAY [Base post-logs] 2025-03-24 19:17:17.647013 | 2025-03-24 19:17:17.647134 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-03-24 19:17:18.096977 | localhost | changed 2025-03-24 19:17:18.100758 | 2025-03-24 19:17:18.100893 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-03-24 19:17:18.148815 | localhost | ok 2025-03-24 19:17:18.155251 | 2025-03-24 19:17:18.155357 | TASK [Set zuul-log-path fact] 2025-03-24 19:17:18.173144 | localhost | ok 2025-03-24 19:17:18.183371 | 2025-03-24 19:17:18.183482 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-03-24 19:17:18.221114 | localhost | ok 2025-03-24 19:17:18.228486 | 2025-03-24 19:17:18.228609 | TASK [upload-logs : Create log directories] 2025-03-24 19:17:18.724480 | localhost | changed 2025-03-24 19:17:18.732479 | 2025-03-24 19:17:18.732629 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-03-24 19:17:19.252073 | localhost -> localhost | ok: Runtime: 0:00:00.007277 2025-03-24 19:17:19.264896 | 2025-03-24 19:17:19.265077 | TASK [upload-logs : Upload logs to log server] 2025-03-24 19:17:19.820972 | localhost | Output suppressed because no_log was given 2025-03-24 19:17:19.826518 | 2025-03-24 19:17:19.826682 | LOOP [upload-logs : Compress console log and json output] 2025-03-24 19:17:19.895696 | localhost | skipping: Conditional result was False 2025-03-24 19:17:19.912023 | localhost | skipping: Conditional result was False 2025-03-24 19:17:19.922113 | 2025-03-24 19:17:19.922346 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-24 19:17:19.984253 | localhost | skipping: Conditional result was False 2025-03-24 19:17:19.984748 | 2025-03-24 19:17:19.997249 | localhost | skipping: Conditional result was False 2025-03-24 19:17:20.009282 | 2025-03-24 19:17:20.009465 | LOOP [upload-logs : Upload console log and json output]