2026-02-16 17:21:15.813440 | Job console starting 2026-02-16 17:21:15.842519 | Updating git repos 2026-02-16 17:21:15.882528 | Cloning repos into workspace 2026-02-16 17:21:16.127597 | Restoring repo states 2026-02-16 17:21:16.142275 | Merging changes 2026-02-16 17:21:16.619307 | Checking out repos 2026-02-16 17:21:16.829459 | Preparing playbooks 2026-02-16 17:21:17.913115 | Running Ansible setup 2026-02-16 17:21:21.563289 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 17:21:22.178110 | 2026-02-16 17:21:22.178288 | PLAY [localhost] 2026-02-16 17:21:22.186745 | 2026-02-16 17:21:22.186848 | TASK [Gathering Facts] 2026-02-16 17:21:23.196737 | localhost | ok 2026-02-16 17:21:23.207570 | 2026-02-16 17:21:23.207671 | TASK [Setup log path fact] 2026-02-16 17:21:23.229548 | localhost | ok 2026-02-16 17:21:23.244449 | 2026-02-16 17:21:23.244540 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 17:21:23.272229 | localhost | ok 2026-02-16 17:21:23.280353 | 2026-02-16 17:21:23.280447 | TASK [emit-job-header : Print job information] 2026-02-16 17:21:23.323573 | # Job Information 2026-02-16 17:21:23.323730 | Ansible Version: 2.16.15 2026-02-16 17:21:23.323775 | Job: magnum-cluster-api-tox-unit 2026-02-16 17:21:23.323805 | Pipeline: check 2026-02-16 17:21:23.323833 | Executor: 3a2793d2bd32 2026-02-16 17:21:23.323860 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884 2026-02-16 17:21:23.323892 | Event ID: 942d9220-0b5b-11f1-9435-38659876bbc5 2026-02-16 17:21:23.327563 | 2026-02-16 17:21:23.327652 | LOOP [emit-job-header : Print node information] 2026-02-16 17:21:23.424164 | localhost | ok: 2026-02-16 17:21:23.424525 | localhost | # Node Information 2026-02-16 17:21:23.424599 | localhost | Inventory Hostname: ubuntu-noble 2026-02-16 17:21:23.424623 | localhost | Hostname: np0000155583 2026-02-16 17:21:23.424664 | localhost | Username: zuul 2026-02-16 17:21:23.424695 | localhost | Distro: Ubuntu 24.04 2026-02-16 17:21:23.424736 | localhost | Provider: yul1 2026-02-16 17:21:23.424758 | localhost | Region: ca-ymq-1 2026-02-16 17:21:23.424776 | localhost | Label: ubuntu-noble 2026-02-16 17:21:23.424794 | localhost | Product Name: OpenStack Nova 2026-02-16 17:21:23.424811 | localhost | Interface IP: 199.204.45.216 2026-02-16 17:21:23.444104 | 2026-02-16 17:21:23.444372 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-16 17:21:23.925883 | localhost -> localhost | changed 2026-02-16 17:21:23.931186 | 2026-02-16 17:21:23.931261 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-16 17:21:24.860372 | localhost -> localhost | changed 2026-02-16 17:21:24.872298 | 2026-02-16 17:21:24.872397 | PLAY [all] 2026-02-16 17:21:24.881542 | 2026-02-16 17:21:24.881624 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-16 17:21:25.109668 | ubuntu-noble -> localhost | ok 2026-02-16 17:21:25.118777 | 2026-02-16 17:21:25.118954 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-16 17:21:25.153581 | ubuntu-noble | ok 2026-02-16 17:21:25.179309 | ubuntu-noble | included: /var/lib/zuul/builds/779ee2e584b94ffdb8025b9bb394b2f0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-16 17:21:25.192379 | 2026-02-16 17:21:25.192549 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-16 17:21:26.186538 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2026-02-16 17:21:26.186755 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/779ee2e584b94ffdb8025b9bb394b2f0/work/779ee2e584b94ffdb8025b9bb394b2f0_id_rsa 2026-02-16 17:21:26.186786 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/779ee2e584b94ffdb8025b9bb394b2f0/work/779ee2e584b94ffdb8025b9bb394b2f0_id_rsa.pub 2026-02-16 17:21:26.186809 | ubuntu-noble -> localhost | The key fingerprint is: 2026-02-16 17:21:26.186830 | ubuntu-noble -> localhost | SHA256:Qgg6X1Rn7YoSVMqIw6axo/91sdS/8nCA+1EYxRRB5TM zuul-build-sshkey 2026-02-16 17:21:26.186866 | ubuntu-noble -> localhost | The key's randomart image is: 2026-02-16 17:21:26.186887 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2026-02-16 17:21:26.186913 | ubuntu-noble -> localhost | | . .oo o. +*+. | 2026-02-16 17:21:26.186935 | ubuntu-noble -> localhost | |.o =.o o .... | 2026-02-16 17:21:26.186955 | ubuntu-noble -> localhost | |*o..= . .. E | 2026-02-16 17:21:26.186975 | ubuntu-noble -> localhost | |o* ... o.o o | 2026-02-16 17:21:26.186994 | ubuntu-noble -> localhost | |+ . ...S.+ . | 2026-02-16 17:21:26.187014 | ubuntu-noble -> localhost | |.. . .o.+ + | 2026-02-16 17:21:26.187033 | ubuntu-noble -> localhost | |. .. + o o | 2026-02-16 17:21:26.187052 | ubuntu-noble -> localhost | | . . . ..+ . | 2026-02-16 17:21:26.187074 | ubuntu-noble -> localhost | | ... .oo | 2026-02-16 17:21:26.187093 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2026-02-16 17:21:26.187139 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.525064 2026-02-16 17:21:26.193150 | 2026-02-16 17:21:26.193212 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-16 17:21:26.223282 | ubuntu-noble | ok 2026-02-16 17:21:26.234034 | ubuntu-noble | included: /var/lib/zuul/builds/779ee2e584b94ffdb8025b9bb394b2f0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-16 17:21:26.242692 | 2026-02-16 17:21:26.242784 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-16 17:21:26.267353 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:21:26.276333 | 2026-02-16 17:21:26.276426 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-16 17:21:26.884422 | ubuntu-noble | changed 2026-02-16 17:21:26.889896 | 2026-02-16 17:21:26.889960 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-16 17:21:27.137552 | ubuntu-noble | ok 2026-02-16 17:21:27.143625 | 2026-02-16 17:21:27.143696 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-16 17:21:27.700971 | ubuntu-noble | changed 2026-02-16 17:21:27.706552 | 2026-02-16 17:21:27.706621 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-16 17:21:28.243170 | ubuntu-noble | changed 2026-02-16 17:21:28.251781 | 2026-02-16 17:21:28.251871 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-16 17:21:28.277110 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:21:28.289410 | 2026-02-16 17:21:28.289474 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-16 17:21:28.681974 | ubuntu-noble -> localhost | changed 2026-02-16 17:21:28.694516 | 2026-02-16 17:21:28.694580 | TASK [add-build-sshkey : Add back temp key] 2026-02-16 17:21:29.036701 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/779ee2e584b94ffdb8025b9bb394b2f0/work/779ee2e584b94ffdb8025b9bb394b2f0_id_rsa (zuul-build-sshkey) 2026-02-16 17:21:29.036886 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.013826 2026-02-16 17:21:29.043992 | 2026-02-16 17:21:29.044053 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-16 17:21:29.381142 | ubuntu-noble | ok 2026-02-16 17:21:29.386801 | 2026-02-16 17:21:29.386864 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-16 17:21:29.417050 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:21:29.431092 | 2026-02-16 17:21:29.431173 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-16 17:21:29.850641 | ubuntu-noble | ok 2026-02-16 17:21:29.859965 | 2026-02-16 17:21:29.860062 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-16 17:21:30.945655 | ubuntu-noble | Output suppressed because no_log was given 2026-02-16 17:21:30.955822 | 2026-02-16 17:21:30.955896 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-16 17:21:31.182982 | ubuntu-noble | ok: "logs" 2026-02-16 17:21:31.183255 | ubuntu-noble | ok: All items complete 2026-02-16 17:21:31.183736 | 2026-02-16 17:21:31.428828 | ubuntu-noble | ok: "artifacts" 2026-02-16 17:21:31.569315 | ubuntu-noble | ok: "docs" 2026-02-16 17:21:31.589093 | 2026-02-16 17:21:31.589264 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-16 17:21:31.823435 | ubuntu-noble | changed: "logs" 2026-02-16 17:21:32.014438 | ubuntu-noble | changed: "artifacts" 2026-02-16 17:21:32.205937 | ubuntu-noble | changed: "docs" 2026-02-16 17:21:32.221063 | 2026-02-16 17:21:32.221130 | PLAY RECAP 2026-02-16 17:21:32.221192 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-16 17:21:32.221251 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-16 17:21:32.221298 | 2026-02-16 17:21:32.438970 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 17:21:32.441416 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-16 17:21:33.019538 | 2026-02-16 17:21:33.019699 | PLAY [all] 2026-02-16 17:21:33.033987 | 2026-02-16 17:21:33.034085 | TASK [Install binary dependencies] 2026-02-16 17:21:33.097868 | ubuntu-noble | ok 2026-02-16 17:21:33.120125 | 2026-02-16 17:21:33.120220 | TASK [bindep : Include find tasks] 2026-02-16 17:21:33.165579 | ubuntu-noble | ok 2026-02-16 17:21:33.174090 | ubuntu-noble | included: /var/lib/zuul/builds/779ee2e584b94ffdb8025b9bb394b2f0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-02-16 17:21:33.180691 | 2026-02-16 17:21:33.180755 | TASK [bindep : Look for bindep.txt] 2026-02-16 17:21:33.566761 | ubuntu-noble | ok 2026-02-16 17:21:33.573336 | 2026-02-16 17:21:33.573406 | TASK [bindep : Define bindep_file fact] 2026-02-16 17:21:33.598288 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:21:33.607973 | 2026-02-16 17:21:33.608065 | TASK [bindep : Look for other-requirements.txt] 2026-02-16 17:21:33.816010 | ubuntu-noble | ok 2026-02-16 17:21:33.822006 | 2026-02-16 17:21:33.822079 | TASK [bindep : Define bindep_file fact] 2026-02-16 17:21:33.847705 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:21:33.859660 | 2026-02-16 17:21:33.859762 | TASK [bindep : Look for bindep fallback file] 2026-02-16 17:21:33.896065 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:21:33.908413 | 2026-02-16 17:21:33.908509 | TASK [bindep : Define bindep_file fact] 2026-02-16 17:21:33.934221 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:21:33.946420 | 2026-02-16 17:21:33.946542 | TASK [bindep : Include bindep tasks] 2026-02-16 17:21:33.972277 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:21:33.984239 | 2026-02-16 17:21:33.984317 | TASK [bindep : Include install tasks] 2026-02-16 17:21:34.010414 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:21:34.021075 | 2026-02-16 17:21:34.021144 | LOOP [bindep : Include package tasks] 2026-02-16 17:21:34.086228 | 2026-02-16 17:21:34.086407 | TASK [Run test-setup role] 2026-02-16 17:21:34.113858 | ubuntu-noble | ok 2026-02-16 17:21:34.139044 | 2026-02-16 17:21:34.139167 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-02-16 17:21:34.358207 | ubuntu-noble | ok 2026-02-16 17:21:34.366080 | 2026-02-16 17:21:34.366149 | TASK [test-setup : Run tools/test-setup.sh] 2026-02-16 17:21:34.900435 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:21:34.912237 | 2026-02-16 17:21:34.912315 | PLAY RECAP 2026-02-16 17:21:34.912381 | ubuntu-noble | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-16 17:21:34.912418 | 2026-02-16 17:21:35.087546 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-16 17:21:35.089790 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master] 2026-02-16 17:21:35.743344 | 2026-02-16 17:21:35.743569 | PLAY [all] 2026-02-16 17:21:35.768960 | 2026-02-16 17:21:35.769157 | TASK [ensure-python : Validate python_version value] 2026-02-16 17:21:35.796586 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:21:35.814307 | 2026-02-16 17:21:35.814501 | TASK [ensure-python : Install specified version of python interpreter and development files (DEB)] 2026-02-16 17:21:35.842074 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:21:35.853893 | 2026-02-16 17:21:35.853996 | TASK [ensure-python : Pull in venv package] 2026-02-16 17:21:35.879692 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:21:35.892319 | 2026-02-16 17:21:35.892436 | TASK [ensure-python : Set default RPM package name] 2026-02-16 17:21:35.918272 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:21:35.930943 | 2026-02-16 17:21:35.931089 | TASK [ensure-python : Set RPM package name for CentOS/RHEL 9/10] 2026-02-16 17:21:35.959104 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:21:35.970380 | 2026-02-16 17:21:35.970512 | TASK [ensure-python : Install RPM package] 2026-02-16 17:21:35.997996 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:21:36.008145 | 2026-02-16 17:21:36.008288 | TASK [ensure-python : Install python using pyenv] 2026-02-16 17:21:36.034909 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:21:36.045674 | 2026-02-16 17:21:36.045823 | TASK [ensure-python : Activate python using stow] 2026-02-16 17:21:36.082850 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:21:36.101753 | 2026-02-16 17:21:36.101853 | TASK [Install pip] 2026-02-16 17:21:36.122184 | ubuntu-noble | ok 2026-02-16 17:21:36.153286 | 2026-02-16 17:21:36.153418 | TASK [ensure-pip : Check if pip is installed] 2026-02-16 17:21:36.694569 | ubuntu-noble | ok: Runtime: 0:00:00.007143 2026-02-16 17:21:36.703170 | 2026-02-16 17:21:36.703277 | LOOP [ensure-pip : Install pip from packages] 2026-02-16 17:21:36.735737 | ubuntu-noble | ok: "/var/lib/zuul/builds/779ee2e584b94ffdb8025b9bb394b2f0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/Debian.yaml" 2026-02-16 17:21:36.753168 | ubuntu-noble | included: /var/lib/zuul/builds/779ee2e584b94ffdb8025b9bb394b2f0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/Debian.yaml 2026-02-16 17:21:36.763402 | 2026-02-16 17:21:36.763473 | TASK [ensure-pip : Update package lists] 2026-02-16 17:21:43.807212 | ubuntu-noble | changed 2026-02-16 17:21:43.813604 | 2026-02-16 17:21:43.813665 | TASK [ensure-pip : Install Python 3 pip] 2026-02-16 17:21:49.804332 | ubuntu-noble | changed 2026-02-16 17:21:49.811401 | 2026-02-16 17:21:49.811465 | TASK [ensure-pip : Install Python 2 pip] 2026-02-16 17:21:49.836349 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:21:49.846436 | 2026-02-16 17:21:49.846523 | TASK [ensure-pip : Ensure setuptools] 2026-02-16 17:21:49.871043 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:21:49.882139 | 2026-02-16 17:21:49.882204 | TASK [ensure-pip : Check for ensurepip module] 2026-02-16 17:21:50.089698 | ubuntu-noble | usage: python -m ensurepip [-h] [--version] [-v] [-U] [--user] [--root ROOT] 2026-02-16 17:21:50.089780 | ubuntu-noble | [--altinstall] [--default-pip] 2026-02-16 17:21:50.089787 | ubuntu-noble | 2026-02-16 17:21:50.089792 | ubuntu-noble | options: 2026-02-16 17:21:50.089798 | ubuntu-noble | -h, --help show this help message and exit 2026-02-16 17:21:50.089806 | ubuntu-noble | --version Show the version of pip that is bundled with this Python. 2026-02-16 17:21:50.089811 | ubuntu-noble | -v, --verbose Give more output. Option is additive, and can be used up to 3 2026-02-16 17:21:50.089818 | ubuntu-noble | times. 2026-02-16 17:21:50.089824 | ubuntu-noble | -U, --upgrade Upgrade pip and dependencies, even if already installed. 2026-02-16 17:21:50.089828 | ubuntu-noble | --user Install using the user scheme. 2026-02-16 17:21:50.089832 | ubuntu-noble | --root ROOT Install everything relative to this alternate root directory. 2026-02-16 17:21:50.089836 | ubuntu-noble | --altinstall Make an alternate install, installing only the X.Y versioned 2026-02-16 17:21:50.089840 | ubuntu-noble | scripts (Default: pipX, pipX.Y). 2026-02-16 17:21:50.089844 | ubuntu-noble | --default-pip Make a default pip install, installing the unqualified pip in 2026-02-16 17:21:50.089848 | ubuntu-noble | addition to the versioned scripts. 2026-02-16 17:21:50.415406 | ubuntu-noble | ok: Runtime: 0:00:00.069681 2026-02-16 17:21:50.421446 | 2026-02-16 17:21:50.421514 | TASK [ensure-pip : Ensure python3-venv] 2026-02-16 17:21:50.437578 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:21:50.446810 | 2026-02-16 17:21:50.446904 | TASK [ensure-pip : Install pip from source] 2026-02-16 17:21:50.471893 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:21:50.521035 | 2026-02-16 17:21:50.521209 | TASK [ensure-pip : Probe for venv python full path] 2026-02-16 17:21:50.685076 | ubuntu-noble | /usr/bin/python3 2026-02-16 17:21:51.053881 | ubuntu-noble | ok: Runtime: 0:00:00.010756 2026-02-16 17:21:51.060715 | 2026-02-16 17:21:51.060779 | TASK [ensure-pip : Set host default] 2026-02-16 17:21:51.115740 | ubuntu-noble | ok 2026-02-16 17:21:51.120841 | 2026-02-16 17:21:51.120905 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2026-02-16 17:21:51.179040 | ubuntu-noble | ok 2026-02-16 17:21:51.188833 | 2026-02-16 17:21:51.188911 | TASK [ensure-tox : Check if tox is installed] 2026-02-16 17:21:51.722718 | ubuntu-noble | ok: Runtime: 0:00:00.007601 2026-02-16 17:21:51.728832 | 2026-02-16 17:21:51.728912 | TASK [ensure-tox : Export preinstalled tox_exectuable] 2026-02-16 17:21:51.755022 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:21:51.763651 | 2026-02-16 17:21:51.763741 | TASK [ensure-tox : Create local venv] 2026-02-16 17:21:55.284855 | ubuntu-noble | ok: Runtime: 0:00:03.089572 2026-02-16 17:21:55.291476 | 2026-02-16 17:21:55.291542 | TASK [ensure-tox : Install tox to local venv] 2026-02-16 17:21:56.020515 | ubuntu-noble | Collecting tox 2026-02-16 17:21:56.087059 | ubuntu-noble | Downloading tox-4.36.0-py3-none-any.whl.metadata (3.6 kB) 2026-02-16 17:21:56.161599 | ubuntu-noble | Collecting cachetools>=7.0.1 (from tox) 2026-02-16 17:21:56.173037 | ubuntu-noble | Downloading cachetools-7.0.1-py3-none-any.whl.metadata (5.6 kB) 2026-02-16 17:21:56.222853 | ubuntu-noble | Collecting chardet>=5.2 (from tox) 2026-02-16 17:21:56.231870 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl.metadata (3.4 kB) 2026-02-16 17:21:56.283232 | ubuntu-noble | Collecting colorama>=0.4.6 (from tox) 2026-02-16 17:21:56.292462 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl.metadata (17 kB) 2026-02-16 17:21:56.362621 | ubuntu-noble | Collecting filelock>=3.24 (from tox) 2026-02-16 17:21:56.371740 | ubuntu-noble | Downloading filelock-3.24.2-py3-none-any.whl.metadata (2.0 kB) 2026-02-16 17:21:56.434427 | ubuntu-noble | Collecting packaging>=26 (from tox) 2026-02-16 17:21:56.446169 | ubuntu-noble | Downloading packaging-26.0-py3-none-any.whl.metadata (3.3 kB) 2026-02-16 17:21:56.510615 | ubuntu-noble | Collecting platformdirs>=4.9.1 (from tox) 2026-02-16 17:21:56.525930 | ubuntu-noble | Downloading platformdirs-4.9.2-py3-none-any.whl.metadata (4.7 kB) 2026-02-16 17:21:56.581491 | ubuntu-noble | Collecting pluggy>=1.6 (from tox) 2026-02-16 17:21:56.590462 | ubuntu-noble | Downloading pluggy-1.6.0-py3-none-any.whl.metadata (4.8 kB) 2026-02-16 17:21:56.648579 | ubuntu-noble | Collecting pyproject-api>=1.10 (from tox) 2026-02-16 17:21:56.668175 | ubuntu-noble | Downloading pyproject_api-1.10.0-py3-none-any.whl.metadata (2.7 kB) 2026-02-16 17:21:56.799153 | ubuntu-noble | Collecting virtualenv>=20.36.1 (from tox) 2026-02-16 17:21:56.808449 | ubuntu-noble | Downloading virtualenv-20.37.0-py3-none-any.whl.metadata (5.1 kB) 2026-02-16 17:21:56.906220 | ubuntu-noble | Collecting distlib<1,>=0.3.7 (from virtualenv>=20.36.1->tox) 2026-02-16 17:21:56.929610 | ubuntu-noble | Downloading distlib-0.4.0-py2.py3-none-any.whl.metadata (5.2 kB) 2026-02-16 17:21:56.969709 | ubuntu-noble | Downloading tox-4.36.0-py3-none-any.whl (179 kB) 2026-02-16 17:21:57.001352 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 179.1/179.1 kB 6.0 MB/s eta 0:00:00 2026-02-16 17:21:57.013092 | ubuntu-noble | Downloading cachetools-7.0.1-py3-none-any.whl (13 kB) 2026-02-16 17:21:57.034720 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2026-02-16 17:21:57.055141 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 11.8 MB/s eta 0:00:00 2026-02-16 17:21:57.065211 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-02-16 17:21:57.084529 | ubuntu-noble | Downloading filelock-3.24.2-py3-none-any.whl (24 kB) 2026-02-16 17:21:57.105026 | ubuntu-noble | Downloading packaging-26.0-py3-none-any.whl (74 kB) 2026-02-16 17:21:57.119517 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 5.4 MB/s eta 0:00:00 2026-02-16 17:21:57.129323 | ubuntu-noble | Downloading platformdirs-4.9.2-py3-none-any.whl (21 kB) 2026-02-16 17:21:57.152620 | ubuntu-noble | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-02-16 17:21:57.176622 | ubuntu-noble | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-02-16 17:21:57.221359 | ubuntu-noble | Downloading virtualenv-20.37.0-py3-none-any.whl (5.8 MB) 2026-02-16 17:21:57.299478 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.8/5.8 MB 79.4 MB/s eta 0:00:00 2026-02-16 17:21:57.309397 | ubuntu-noble | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-02-16 17:21:57.332997 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 23.4 MB/s eta 0:00:00 2026-02-16 17:21:57.401883 | ubuntu-noble | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2026-02-16 17:21:58.270484 | ubuntu-noble | Successfully installed cachetools-7.0.1 chardet-5.2.0 colorama-0.4.6 distlib-0.4.0 filelock-3.24.2 packaging-26.0 platformdirs-4.9.2 pluggy-1.6.0 pyproject-api-1.10.0 tox-4.36.0 virtualenv-20.37.0 2026-02-16 17:21:58.821290 | ubuntu-noble | ok: Runtime: 0:00:02.878490 2026-02-16 17:21:58.827265 | 2026-02-16 17:21:58.827326 | TASK [ensure-tox : Export installed tox_executable path] 2026-02-16 17:21:58.864943 | ubuntu-noble | ok 2026-02-16 17:21:58.869921 | 2026-02-16 17:21:58.869985 | TASK [ensure-tox : Output tox version] 2026-02-16 17:21:59.283564 | ubuntu-noble | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-02-16 17:21:59.297965 | ubuntu-noble | 4.36.0 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2026-02-16 17:21:59.402099 | ubuntu-noble | ok: Runtime: 0:00:00.264410 2026-02-16 17:21:59.408311 | 2026-02-16 17:21:59.408373 | TASK [ensure-tox : Make global symlink] 2026-02-16 17:21:59.434495 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:21:59.444712 | 2026-02-16 17:21:59.444758 | PLAY RECAP 2026-02-16 17:21:59.444806 | ubuntu-noble | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-02-16 17:21:59.444828 | 2026-02-16 17:21:59.612528 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master] 2026-02-16 17:21:59.615633 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/tox/pre.yml@main] 2026-02-16 17:22:00.215777 | 2026-02-16 17:22:00.215955 | PLAY [all] 2026-02-16 17:22:00.229489 | 2026-02-16 17:22:00.229646 | TASK [ensure-rust : Use rustup] 2026-02-16 17:22:00.267267 | ubuntu-noble | ok 2026-02-16 17:22:00.277603 | ubuntu-noble | included: /var/lib/zuul/builds/779ee2e584b94ffdb8025b9bb394b2f0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml 2026-02-16 17:22:00.283660 | 2026-02-16 17:22:00.283736 | TASK [ensure-rust : Install Rust] 2026-02-16 17:22:00.654660 | ubuntu-noble | /bin/bash: line 2: curl: command not found 2026-02-16 17:22:00.827577 | ubuntu-noble | ERROR 2026-02-16 17:22:00.828085 | ubuntu-noble | { 2026-02-16 17:22:00.828132 | ubuntu-noble | "delta": "0:00:00.007993", 2026-02-16 17:22:00.828164 | ubuntu-noble | "end": "2026-02-16 17:22:00.656037", 2026-02-16 17:22:00.828191 | ubuntu-noble | "msg": "non-zero return code", 2026-02-16 17:22:00.828219 | ubuntu-noble | "rc": 127, 2026-02-16 17:22:00.828246 | ubuntu-noble | "start": "2026-02-16 17:22:00.648044" 2026-02-16 17:22:00.828274 | ubuntu-noble | } failure 2026-02-16 17:22:00.830539 | 2026-02-16 17:22:00.830609 | PLAY RECAP 2026-02-16 17:22:00.830671 | ubuntu-noble | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-02-16 17:22:00.830703 | 2026-02-16 17:22:00.942703 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/tox/pre.yml@main] 2026-02-16 17:22:00.944851 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2026-02-16 17:22:01.525134 | 2026-02-16 17:22:01.525281 | PLAY [all] 2026-02-16 17:22:01.537511 | 2026-02-16 17:22:01.537610 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-02-16 17:22:01.583707 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:22:01.593205 | 2026-02-16 17:22:01.593283 | TASK [fetch-tox-output : Set tox log path for single node] 2026-02-16 17:22:01.638385 | ubuntu-noble | ok 2026-02-16 17:22:01.644495 | 2026-02-16 17:22:01.644576 | TASK [fetch-tox-output : Ensure local tox dir] 2026-02-16 17:22:02.014018 | ubuntu-noble -> localhost | changed 2026-02-16 17:22:02.023693 | 2026-02-16 17:22:02.023788 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-02-16 17:22:02.069733 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:22:02.080994 | 2026-02-16 17:22:02.081065 | TASK [fetch-tox-output : Set envlist fact] 2026-02-16 17:22:02.125196 | ubuntu-noble | ok 2026-02-16 17:22:02.133129 | 2026-02-16 17:22:02.133233 | TASK [fetch-tox-output : Get tox version] 2026-02-16 17:22:02.686275 | ubuntu-noble | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-02-16 17:22:02.700580 | ubuntu-noble | 4.36.0 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2026-02-16 17:22:03.171250 | ubuntu-noble | ok: Runtime: 0:00:00.270241 2026-02-16 17:22:03.179029 | 2026-02-16 17:22:03.179120 | TASK [fetch-tox-output : Find all default environments] 2026-02-16 17:22:03.709860 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:22:03.720245 | 2026-02-16 17:22:03.720371 | TASK [fetch-tox-output : Set envlist fact] 2026-02-16 17:22:03.755593 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:22:03.765126 | 2026-02-16 17:22:04.019403 | TASK [fetch-tox-output : Find all default environments] 2026-02-16 17:22:04.556738 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:22:04.572859 | 2026-02-16 17:22:04.573868 | TASK [fetch-tox-output : Set envlist fact] 2026-02-16 17:22:04.599614 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:22:04.611555 | 2026-02-16 17:22:04.611704 | LOOP [fetch-tox-output : Copy tox logs] 2026-02-16 17:22:04.651711 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:22:04.669861 | 2026-02-16 17:22:04.670036 | LOOP [fetch-tox-output : Collect tox logs] 2026-02-16 17:22:05.411419 | ubuntu-noble | ok: 2026-02-16 17:22:05.771842 | ubuntu-noble | rsync: [sender] change_dir "/home/zuul/src/github.com/vexxhost/magnum-cluster-api/.tox/unit/log" failed: No such file or directory (2) 2026-02-16 17:22:05.772081 | ubuntu-noble | rsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1865) [Receiver=3.2.7] 2026-02-16 17:22:05.772144 | ubuntu-noble | rsync: [Receiver] write error: Broken pipe (32) 2026-02-16 17:22:05.772238 | 2026-02-16 17:22:05.788913 | 2026-02-16 17:22:05.789019 | PLAY RECAP 2026-02-16 17:22:05.789085 | ubuntu-noble | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-02-16 17:22:05.789116 | 2026-02-16 17:22:05.909339 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2026-02-16 17:22:05.911940 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-16 17:22:06.505895 | 2026-02-16 17:22:06.768061 | PLAY [all] 2026-02-16 17:22:06.794099 | 2026-02-16 17:22:06.794222 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-02-16 17:22:06.975317 | ubuntu-noble | changed: non-zero return code 2026-02-16 17:22:06.982423 | 2026-02-16 17:22:06.982508 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-02-16 17:22:07.006145 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:22:07.017432 | 2026-02-16 17:22:07.017546 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-02-16 17:22:07.054624 | 2026-02-16 17:22:07.054807 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-02-16 17:22:07.091327 | 2026-02-16 17:22:07.091500 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-02-16 17:22:07.118506 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:22:07.133310 | 2026-02-16 17:22:07.133466 | LOOP [fetch-subunit-output : Generate subunit file] 2026-02-16 17:22:07.169069 | 2026-02-16 17:22:07.169240 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-02-16 17:22:07.194564 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:22:07.207518 | 2026-02-16 17:22:07.207594 | TASK [fetch-subunit-output : Remove the temporary file] 2026-02-16 17:22:07.232036 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:22:07.242064 | 2026-02-16 17:22:07.242139 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-02-16 17:22:07.264447 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:22:07.277452 | 2026-02-16 17:22:07.277501 | PLAY RECAP 2026-02-16 17:22:07.277550 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-16 17:22:07.277570 | 2026-02-16 17:22:07.405759 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-16 17:22:07.408136 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 17:22:07.964447 | 2026-02-16 17:22:07.965341 | PLAY [all] 2026-02-16 17:22:07.977138 | 2026-02-16 17:22:07.977215 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-16 17:22:08.013770 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:22:08.021919 | 2026-02-16 17:22:08.022012 | TASK [fetch-output : Set log path for single node] 2026-02-16 17:22:08.063749 | ubuntu-noble | ok 2026-02-16 17:22:08.071989 | 2026-02-16 17:22:08.072081 | LOOP [fetch-output : Ensure local output dirs] 2026-02-16 17:22:08.447569 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/779ee2e584b94ffdb8025b9bb394b2f0/work/logs" 2026-02-16 17:22:08.670086 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/779ee2e584b94ffdb8025b9bb394b2f0/work/artifacts" 2026-02-16 17:22:08.879152 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/779ee2e584b94ffdb8025b9bb394b2f0/work/docs" 2026-02-16 17:22:08.894620 | 2026-02-16 17:22:08.894815 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-16 17:22:09.515066 | ubuntu-noble | changed: .d..t...... ./ 2026-02-16 17:22:09.515333 | ubuntu-noble | changed: All items complete 2026-02-16 17:22:09.515371 | 2026-02-16 17:22:09.974570 | ubuntu-noble | changed: .d..t...... ./ 2026-02-16 17:22:10.440291 | ubuntu-noble | changed: .d..t...... ./ 2026-02-16 17:22:10.468803 | 2026-02-16 17:22:10.468971 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-16 17:22:10.914412 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.031135 2026-02-16 17:22:11.138124 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.007385 2026-02-16 17:22:11.161112 | 2026-02-16 17:22:11.161252 | PLAY [all] 2026-02-16 17:22:11.168662 | 2026-02-16 17:22:11.168731 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-16 17:22:11.654134 | ubuntu-noble | changed 2026-02-16 17:22:11.659967 | 2026-02-16 17:22:11.660016 | PLAY RECAP 2026-02-16 17:22:11.660057 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-16 17:22:11.660078 | 2026-02-16 17:22:11.773233 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 17:22:11.775912 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-16 17:22:12.332061 | 2026-02-16 17:22:12.332193 | PLAY [localhost] 2026-02-16 17:22:12.342561 | 2026-02-16 17:22:12.342636 | TASK [Generate Zuul manifest] 2026-02-16 17:22:12.361958 | localhost | ok 2026-02-16 17:22:12.379540 | 2026-02-16 17:22:12.379624 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-16 17:22:12.692919 | localhost | changed 2026-02-16 17:22:12.709587 | 2026-02-16 17:22:12.709661 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-16 17:22:12.741534 | localhost | ok 2026-02-16 17:22:12.750357 | 2026-02-16 17:22:12.750484 | TASK [Upload logs] 2026-02-16 17:22:12.773415 | localhost | ok 2026-02-16 17:22:12.834492 | 2026-02-16 17:22:12.834613 | TASK [Set zuul-log-path fact] 2026-02-16 17:22:12.854774 | localhost | ok 2026-02-16 17:22:12.869500 | 2026-02-16 17:22:12.869573 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 17:22:12.900630 | localhost | ok 2026-02-16 17:22:12.908433 | 2026-02-16 17:22:12.908501 | TASK [upload-logs : Create log directories] 2026-02-16 17:22:13.293859 | localhost | changed 2026-02-16 17:22:13.298554 | 2026-02-16 17:22:13.298628 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-16 17:22:13.700403 | localhost -> localhost | ok: Runtime: 0:00:00.006202 2026-02-16 17:22:13.707184 | 2026-02-16 17:22:13.707274 | TASK [upload-logs : Upload logs to log server] 2026-02-16 17:22:14.164174 | localhost | Output suppressed because no_log was given 2026-02-16 17:22:14.169103 | 2026-02-16 17:22:14.169194 | LOOP [upload-logs : Compress console log and json output] 2026-02-16 17:22:14.210485 | localhost | skipping: Conditional result was False 2026-02-16 17:22:14.217037 | localhost | skipping: Conditional result was False 2026-02-16 17:22:14.232851 | 2026-02-16 17:22:14.233037 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-16 17:22:14.274911 | localhost | skipping: Conditional result was False 2026-02-16 17:22:14.275384 | 2026-02-16 17:22:14.277724 | localhost | skipping: Conditional result was False 2026-02-16 17:22:14.290056 | 2026-02-16 17:22:14.290430 | LOOP [upload-logs : Upload console log and json output]