2025-03-24 15:13:01.346350 | Job console starting... 2025-03-24 15:13:01.369808 | Updating repositories 2025-03-24 15:13:01.435834 | Preparing job workspace 2025-03-24 15:13:03.961638 | Running Ansible setup... 2025-03-24 15:13:10.062418 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-24 15:13:10.784484 | 2025-03-24 15:13:10.784589 | PLAY [Base pre] 2025-03-24 15:13:10.813401 | 2025-03-24 15:13:10.813503 | TASK [Setup log path fact] 2025-03-24 15:13:10.831502 | ubuntu-noble | ok 2025-03-24 15:13:10.846059 | 2025-03-24 15:13:10.846160 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-03-24 15:13:10.884663 | ubuntu-noble | ok 2025-03-24 15:13:10.896747 | 2025-03-24 15:13:10.896831 | TASK [emit-job-header : Print job information] 2025-03-24 15:13:10.935263 | # Job Information 2025-03-24 15:13:10.935387 | Ansible Version: 2.15.3 2025-03-24 15:13:10.935416 | Job: container-image-python-osism-build 2025-03-24 15:13:10.935441 | Pipeline: check 2025-03-24 15:13:10.935458 | Executor: 7d211f194f6a 2025-03-24 15:13:10.935474 | Triggered by: https://github.com/osism/python-osism/pull/1243 2025-03-24 15:13:10.935490 | Event ID: 74ede230-08c2-11f0-877f-02b3b3381e20 2025-03-24 15:13:10.942105 | 2025-03-24 15:13:10.942193 | LOOP [emit-job-header : Print node information] 2025-03-24 15:13:11.093054 | ubuntu-noble | ok: 2025-03-24 15:13:11.093186 | ubuntu-noble | # Node Information 2025-03-24 15:13:11.093214 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-03-24 15:13:11.093234 | ubuntu-noble | Hostname: ubuntu 2025-03-24 15:13:11.093252 | ubuntu-noble | Username: zuul 2025-03-24 15:13:11.093268 | ubuntu-noble | Distro: Ubuntu 24.04 2025-03-24 15:13:11.093286 | ubuntu-noble | Provider: regiocloud-a 2025-03-24 15:13:11.093303 | ubuntu-noble | Label: ubuntu-noble 2025-03-24 15:13:11.093320 | ubuntu-noble | Product Name: OpenStack Nova 2025-03-24 15:13:11.093336 | ubuntu-noble | Interface IP: 81.163.193.117 2025-03-24 15:13:11.130619 | 2025-03-24 15:13:11.130720 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-24 15:13:11.982166 | ubuntu-noble -> localhost | changed 2025-03-24 15:13:11.988749 | 2025-03-24 15:13:11.988839 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-24 15:13:13.417949 | ubuntu-noble -> localhost | changed 2025-03-24 15:13:13.445544 | 2025-03-24 15:13:13.445663 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-24 15:13:13.770580 | ubuntu-noble -> localhost | ok 2025-03-24 15:13:13.777921 | 2025-03-24 15:13:13.778024 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-24 15:13:13.803715 | ubuntu-noble | ok 2025-03-24 15:13:13.821441 | ubuntu-noble | included: /var/lib/zuul/builds/0197f99fd7b34db9b4b54dbe18ea0c1a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-24 15:13:13.828042 | 2025-03-24 15:13:13.828127 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-24 15:13:14.376059 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-03-24 15:13:14.376230 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/0197f99fd7b34db9b4b54dbe18ea0c1a/work/0197f99fd7b34db9b4b54dbe18ea0c1a_id_rsa 2025-03-24 15:13:14.376261 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/0197f99fd7b34db9b4b54dbe18ea0c1a/work/0197f99fd7b34db9b4b54dbe18ea0c1a_id_rsa.pub 2025-03-24 15:13:14.376282 | ubuntu-noble -> localhost | The key fingerprint is: 2025-03-24 15:13:14.376302 | ubuntu-noble -> localhost | SHA256:cT6ylcoHxC9xyFni8W/QNWgsuTPcjh4mgltTIni3Aw8 zuul-build-sshkey 2025-03-24 15:13:14.376321 | ubuntu-noble -> localhost | The key's randomart image is: 2025-03-24 15:13:14.376338 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-03-24 15:13:14.376355 | ubuntu-noble -> localhost | | o .o .o | 2025-03-24 15:13:14.376371 | ubuntu-noble -> localhost | | + Bo.+. . | 2025-03-24 15:13:14.376407 | ubuntu-noble -> localhost | | . X.==. | 2025-03-24 15:13:14.376433 | ubuntu-noble -> localhost | | . E o..B=+. | 2025-03-24 15:13:14.376450 | ubuntu-noble -> localhost | | . B +S *=o | 2025-03-24 15:13:14.376466 | ubuntu-noble -> localhost | | . B..B+o. | 2025-03-24 15:13:14.376489 | ubuntu-noble -> localhost | | o +++.. | 2025-03-24 15:13:14.376506 | ubuntu-noble -> localhost | | . .. | 2025-03-24 15:13:14.376522 | ubuntu-noble -> localhost | | | 2025-03-24 15:13:14.376539 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-03-24 15:13:14.376578 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.193980 2025-03-24 15:13:14.382653 | 2025-03-24 15:13:14.382725 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-24 15:13:14.422155 | ubuntu-noble | ok 2025-03-24 15:13:14.431056 | ubuntu-noble | included: /var/lib/zuul/builds/0197f99fd7b34db9b4b54dbe18ea0c1a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-24 15:13:14.441547 | 2025-03-24 15:13:14.441644 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-24 15:13:14.475251 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 15:13:14.481199 | 2025-03-24 15:13:14.481281 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-24 15:13:15.192294 | ubuntu-noble | changed 2025-03-24 15:13:15.201490 | 2025-03-24 15:13:15.201622 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-24 15:13:15.475212 | ubuntu-noble | ok 2025-03-24 15:13:15.486424 | 2025-03-24 15:13:15.486527 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-24 15:13:16.135205 | ubuntu-noble | changed 2025-03-24 15:13:16.148064 | 2025-03-24 15:13:16.148165 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-24 15:13:16.896050 | ubuntu-noble | changed 2025-03-24 15:13:16.929885 | 2025-03-24 15:13:16.929990 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-24 15:13:16.968311 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 15:13:16.975661 | 2025-03-24 15:13:16.975758 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-24 15:13:17.523498 | ubuntu-noble -> localhost | changed 2025-03-24 15:13:17.538471 | 2025-03-24 15:13:17.538575 | TASK [add-build-sshkey : Add back temp key] 2025-03-24 15:13:17.853415 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/0197f99fd7b34db9b4b54dbe18ea0c1a/work/0197f99fd7b34db9b4b54dbe18ea0c1a_id_rsa (zuul-build-sshkey) 2025-03-24 15:13:17.853860 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.007178 2025-03-24 15:13:17.863443 | 2025-03-24 15:13:17.863552 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-24 15:13:18.253204 | ubuntu-noble | ok 2025-03-24 15:13:18.262202 | 2025-03-24 15:13:18.262300 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-24 15:13:18.285848 | ubuntu-noble | skipping: Conditional result was False 2025-03-24 15:13:18.302573 | 2025-03-24 15:13:18.302691 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-24 15:13:18.858363 | ubuntu-noble | ok 2025-03-24 15:13:18.891951 | 2025-03-24 15:13:18.892051 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-24 15:13:18.922146 | ubuntu-noble | ok 2025-03-24 15:13:18.928470 | 2025-03-24 15:13:18.928558 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-24 15:13:19.178363 | ubuntu-noble -> localhost | ok 2025-03-24 15:13:19.186968 | 2025-03-24 15:13:19.187076 | TASK [validate-host : Collect information about the host] 2025-03-24 15:13:20.269115 | ubuntu-noble | ok 2025-03-24 15:13:20.280844 | 2025-03-24 15:13:20.280934 | TASK [validate-host : Sanitize hostname] 2025-03-24 15:13:20.340226 | ubuntu-noble | ok 2025-03-24 15:13:20.345739 | 2025-03-24 15:13:20.352481 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-24 15:13:21.035995 | ubuntu-noble -> localhost | changed 2025-03-24 15:13:21.041467 | 2025-03-24 15:13:21.041542 | TASK [validate-host : Collect information about zuul worker] 2025-03-24 15:13:21.505654 | ubuntu-noble | ok 2025-03-24 15:13:21.510495 | 2025-03-24 15:13:21.510575 | TASK [validate-host : Write out all zuul information for each host] 2025-03-24 15:13:22.108329 | ubuntu-noble -> localhost | changed 2025-03-24 15:13:22.118194 | 2025-03-24 15:13:22.118275 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-24 15:13:22.431036 | ubuntu-noble | ok 2025-03-24 15:13:22.440465 | 2025-03-24 15:13:22.440549 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-24 15:13:23.396729 | ubuntu-noble | changed: 2025-03-24 15:13:23.396891 | ubuntu-noble | cd+++++++++ src/ 2025-03-24 15:13:23.396928 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-03-24 15:13:23.396954 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-03-24 15:13:23.396976 | ubuntu-noble | cd+++++++++ src/github.com/osism/python-osism/ 2025-03-24 15:13:23.396997 | ubuntu-noble | Containerfile 2025-03-24 15:13:23.397108 | 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-79as1_0k/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-79as1_0k/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-79as1_0k/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-79as1_0k/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-79as1_0k/overlay/lib/python3.12/site-packages/setuptools/__init__.py\", line 116, in setup\n _install_setup_requires(attrs)\n File \"/tmp/pip-build-env-79as1_0k/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-79as1_0k/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-79as1_0k/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-79as1_0k/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 15:14:39.779591 | ubuntu-noble | } failure 2025-03-24 15:14:39.782458 | 2025-03-24 15:14:39.782551 | PLAY RECAP 2025-03-24 15:14:39.782644 | ubuntu-noble | ok: 31 changed: 16 unreachable: 0 failed: 1 skipped: 18 rescued: 0 ignored: 0 2025-03-24 15:14:39.782682 | 2025-03-24 15:14:39.908791 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/python-osism/playbooks/pre.yml@main] 2025-03-24 15:14:39.916486 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-03-24 15:14:40.590565 | 2025-03-24 15:14:40.591742 | PLAY [Base post] 2025-03-24 15:14:40.617505 | 2025-03-24 15:14:40.617646 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-03-24 15:14:41.401032 | ubuntu-noble | changed 2025-03-24 15:14:41.433239 | 2025-03-24 15:14:41.433390 | PLAY RECAP 2025-03-24 15:14:41.433468 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-03-24 15:14:41.433539 | 2025-03-24 15:14:41.543501 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-03-24 15:14:41.546439 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-03-24 15:14:42.296827 | 2025-03-24 15:14:42.296982 | PLAY [Base post-logs] 2025-03-24 15:14:42.313018 | 2025-03-24 15:14:42.313140 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-03-24 15:14:42.761555 | localhost | changed 2025-03-24 15:14:42.765425 | 2025-03-24 15:14:42.765559 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-03-24 15:14:42.806710 | localhost | ok 2025-03-24 15:14:42.816323 | 2025-03-24 15:14:42.816507 | TASK [Set zuul-log-path fact] 2025-03-24 15:14:42.835313 | localhost | ok 2025-03-24 15:14:42.847696 | 2025-03-24 15:14:42.847802 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-03-24 15:14:42.887508 | localhost | ok 2025-03-24 15:14:42.896348 | 2025-03-24 15:14:42.896506 | TASK [upload-logs : Create log directories] 2025-03-24 15:14:43.409761 | localhost | changed 2025-03-24 15:14:43.418003 | 2025-03-24 15:14:43.418159 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-03-24 15:14:43.904373 | localhost -> localhost | ok: Runtime: 0:00:00.007086 2025-03-24 15:14:43.915667 | 2025-03-24 15:14:43.915836 | TASK [upload-logs : Upload logs to log server] 2025-03-24 15:14:44.475296 | localhost | Output suppressed because no_log was given 2025-03-24 15:14:44.478437 | 2025-03-24 15:14:44.478542 | LOOP [upload-logs : Compress console log and json output] 2025-03-24 15:14:44.545703 | localhost | skipping: Conditional result was False 2025-03-24 15:14:44.562776 | localhost | skipping: Conditional result was False 2025-03-24 15:14:44.568644 | 2025-03-24 15:14:44.568761 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-24 15:14:44.629674 | localhost | skipping: Conditional result was False 2025-03-24 15:14:44.629986 | 2025-03-24 15:14:44.642976 | localhost | skipping: Conditional result was False 2025-03-24 15:14:44.648228 | 2025-03-24 15:14:44.648347 | LOOP [upload-logs : Upload console log and json output]