2026-02-16 17:45:49.742016 | Job console starting 2026-02-16 17:45:49.756429 | Updating git repos 2026-02-16 17:45:49.853254 | Cloning repos into workspace 2026-02-16 17:45:49.952172 | Restoring repo states 2026-02-16 17:45:49.965647 | Merging changes 2026-02-16 17:45:50.757860 | Checking out repos 2026-02-16 17:45:51.044059 | Preparing playbooks 2026-02-16 17:45:53.065894 | Running Ansible setup 2026-02-16 17:45:56.931407 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 17:45:57.531752 | 2026-02-16 17:45:57.531895 | PLAY [localhost] 2026-02-16 17:45:57.539364 | 2026-02-16 17:45:57.539434 | TASK [Gathering Facts] 2026-02-16 17:45:58.624485 | localhost | ok 2026-02-16 17:45:58.635523 | 2026-02-16 17:45:58.637993 | TASK [Setup log path fact] 2026-02-16 17:45:58.668925 | localhost | ok 2026-02-16 17:45:58.683800 | 2026-02-16 17:45:58.683892 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 17:45:58.716114 | localhost | ok 2026-02-16 17:45:58.724916 | 2026-02-16 17:45:58.725025 | TASK [emit-job-header : Print job information] 2026-02-16 17:45:58.765964 | # Job Information 2026-02-16 17:45:58.766122 | Ansible Version: 2.16.15 2026-02-16 17:45:58.766156 | Job: magnum-cluster-api-tox-unit 2026-02-16 17:45:58.766192 | Pipeline: check 2026-02-16 17:45:58.766212 | Executor: 3a2793d2bd32 2026-02-16 17:45:58.766232 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884 2026-02-16 17:45:58.766254 | Event ID: 37691bf0-0b5f-11f1-8a15-a065d2c16437 2026-02-16 17:45:58.768984 | 2026-02-16 17:45:58.769056 | LOOP [emit-job-header : Print node information] 2026-02-16 17:45:58.875882 | localhost | ok: 2026-02-16 17:45:58.876163 | localhost | # Node Information 2026-02-16 17:45:58.876602 | localhost | Inventory Hostname: ubuntu-noble 2026-02-16 17:45:58.876681 | localhost | Hostname: np0000155638 2026-02-16 17:45:58.876712 | localhost | Username: zuul 2026-02-16 17:45:58.876760 | localhost | Distro: Ubuntu 24.04 2026-02-16 17:45:58.876802 | localhost | Provider: yul1 2026-02-16 17:45:58.876832 | localhost | Region: ca-ymq-1 2026-02-16 17:45:58.876859 | localhost | Label: ubuntu-noble 2026-02-16 17:45:58.877307 | localhost | Product Name: OpenStack Nova 2026-02-16 17:45:58.877364 | localhost | Interface IP: 162.253.55.49 2026-02-16 17:45:58.888551 | 2026-02-16 17:45:58.888652 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-16 17:45:59.577856 | localhost -> localhost | changed 2026-02-16 17:45:59.596412 | 2026-02-16 17:45:59.596565 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-16 17:46:00.678934 | localhost -> localhost | changed 2026-02-16 17:46:00.690717 | 2026-02-16 17:46:00.690813 | PLAY [all] 2026-02-16 17:46:00.701957 | 2026-02-16 17:46:00.702039 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-16 17:46:00.953760 | ubuntu-noble -> localhost | ok 2026-02-16 17:46:00.969658 | 2026-02-16 17:46:01.034282 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-16 17:46:01.080819 | ubuntu-noble | ok 2026-02-16 17:46:01.105526 | ubuntu-noble | included: /var/lib/zuul/builds/9e63448172674d949207be3d52e0c929/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-16 17:46:01.136235 | 2026-02-16 17:46:01.136377 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-16 17:46:02.491859 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2026-02-16 17:46:02.492059 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/9e63448172674d949207be3d52e0c929/work/9e63448172674d949207be3d52e0c929_id_rsa 2026-02-16 17:46:02.492089 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/9e63448172674d949207be3d52e0c929/work/9e63448172674d949207be3d52e0c929_id_rsa.pub 2026-02-16 17:46:02.492112 | ubuntu-noble -> localhost | The key fingerprint is: 2026-02-16 17:46:02.492134 | ubuntu-noble -> localhost | SHA256:hhWths1Xb/rwvaxDXig7SmQDGjLB9POj4yCjQ5Yj6ZE zuul-build-sshkey 2026-02-16 17:46:02.492174 | ubuntu-noble -> localhost | The key's randomart image is: 2026-02-16 17:46:02.492196 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2026-02-16 17:46:02.492223 | ubuntu-noble -> localhost | | oo .. | 2026-02-16 17:46:02.492245 | ubuntu-noble -> localhost | | .o .. . | 2026-02-16 17:46:02.492266 | ubuntu-noble -> localhost | | o + =.. . . | 2026-02-16 17:46:02.492287 | ubuntu-noble -> localhost | | o *o* . o | 2026-02-16 17:46:02.492307 | ubuntu-noble -> localhost | | .o ..+S= o. | 2026-02-16 17:46:02.492328 | ubuntu-noble -> localhost | |oE ..+ ..oo . | 2026-02-16 17:46:02.492351 | ubuntu-noble -> localhost | |=oo. o . =+.. | 2026-02-16 17:46:02.492372 | ubuntu-noble -> localhost | |o.o o . . o o+ .| 2026-02-16 17:46:02.492394 | ubuntu-noble -> localhost | |.. . .. ..oo.| 2026-02-16 17:46:02.492414 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2026-02-16 17:46:02.492465 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.899725 2026-02-16 17:46:02.498138 | 2026-02-16 17:46:02.498201 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-16 17:46:02.528240 | ubuntu-noble | ok 2026-02-16 17:46:02.592866 | ubuntu-noble | included: /var/lib/zuul/builds/9e63448172674d949207be3d52e0c929/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-16 17:46:02.602150 | 2026-02-16 17:46:02.602211 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-16 17:46:02.626823 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:02.638172 | 2026-02-16 17:46:02.638258 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-16 17:46:03.201643 | ubuntu-noble | changed 2026-02-16 17:46:03.213335 | 2026-02-16 17:46:03.213422 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-16 17:46:03.420311 | ubuntu-noble | ok 2026-02-16 17:46:03.426763 | 2026-02-16 17:46:03.426830 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-16 17:46:03.970773 | ubuntu-noble | changed 2026-02-16 17:46:03.977342 | 2026-02-16 17:46:03.977411 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-16 17:46:04.508633 | ubuntu-noble | changed 2026-02-16 17:46:04.516695 | 2026-02-16 17:46:04.516760 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-16 17:46:04.540288 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:04.552153 | 2026-02-16 17:46:04.552222 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-16 17:46:04.940558 | ubuntu-noble -> localhost | changed 2026-02-16 17:46:04.953601 | 2026-02-16 17:46:04.953667 | TASK [add-build-sshkey : Add back temp key] 2026-02-16 17:46:05.410550 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/9e63448172674d949207be3d52e0c929/work/9e63448172674d949207be3d52e0c929_id_rsa (zuul-build-sshkey) 2026-02-16 17:46:05.410753 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.015632 2026-02-16 17:46:05.417743 | 2026-02-16 17:46:05.417812 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-16 17:46:05.768550 | ubuntu-noble | ok 2026-02-16 17:46:05.774402 | 2026-02-16 17:46:05.774528 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-16 17:46:05.798060 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:05.814819 | 2026-02-16 17:46:05.814886 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-16 17:46:06.204848 | ubuntu-noble | ok 2026-02-16 17:46:06.214081 | 2026-02-16 17:46:06.214146 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-16 17:46:07.342821 | ubuntu-noble | Output suppressed because no_log was given 2026-02-16 17:46:07.402504 | 2026-02-16 17:46:07.402619 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-16 17:46:07.613345 | ubuntu-noble | ok: "logs" 2026-02-16 17:46:07.613621 | ubuntu-noble | ok: All items complete 2026-02-16 17:46:07.613683 | 2026-02-16 17:46:07.838830 | ubuntu-noble | ok: "artifacts" 2026-02-16 17:46:07.982353 | ubuntu-noble | ok: "docs" 2026-02-16 17:46:07.999274 | 2026-02-16 17:46:07.999363 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-16 17:46:08.209430 | ubuntu-noble | changed: "logs" 2026-02-16 17:46:08.395565 | ubuntu-noble | changed: "artifacts" 2026-02-16 17:46:08.604277 | ubuntu-noble | changed: "docs" 2026-02-16 17:46:09.226042 | 2026-02-16 17:46:09.226152 | PLAY RECAP 2026-02-16 17:46:09.226194 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-16 17:46:09.226222 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-16 17:46:09.226244 | 2026-02-16 17:46:09.395805 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 17:46:09.397540 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-16 17:46:09.982214 | 2026-02-16 17:46:09.983058 | PLAY [all] 2026-02-16 17:46:09.999797 | 2026-02-16 17:46:09.999927 | TASK [Install binary dependencies] 2026-02-16 17:46:10.062801 | ubuntu-noble | ok 2026-02-16 17:46:10.083802 | 2026-02-16 17:46:10.083886 | TASK [bindep : Include find tasks] 2026-02-16 17:46:10.119184 | ubuntu-noble | ok 2026-02-16 17:46:10.126771 | ubuntu-noble | included: /var/lib/zuul/builds/9e63448172674d949207be3d52e0c929/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-02-16 17:46:10.132834 | 2026-02-16 17:46:10.132895 | TASK [bindep : Look for bindep.txt] 2026-02-16 17:46:10.509556 | ubuntu-noble | ok 2026-02-16 17:46:10.523089 | 2026-02-16 17:46:10.523172 | TASK [bindep : Define bindep_file fact] 2026-02-16 17:46:10.548737 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:10.594389 | 2026-02-16 17:46:10.594480 | TASK [bindep : Look for other-requirements.txt] 2026-02-16 17:46:10.801560 | ubuntu-noble | ok 2026-02-16 17:46:10.808368 | 2026-02-16 17:46:10.808441 | TASK [bindep : Define bindep_file fact] 2026-02-16 17:46:10.833467 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:10.844836 | 2026-02-16 17:46:10.844999 | TASK [bindep : Look for bindep fallback file] 2026-02-16 17:46:10.870648 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:10.882197 | 2026-02-16 17:46:10.882313 | TASK [bindep : Define bindep_file fact] 2026-02-16 17:46:10.917748 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:10.928709 | 2026-02-16 17:46:10.928802 | TASK [bindep : Include bindep tasks] 2026-02-16 17:46:10.954241 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:10.964651 | 2026-02-16 17:46:10.964734 | TASK [bindep : Include install tasks] 2026-02-16 17:46:10.998552 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:11.008882 | 2026-02-16 17:46:11.008990 | LOOP [bindep : Include package tasks] 2026-02-16 17:46:11.071826 | 2026-02-16 17:46:11.072041 | TASK [Run test-setup role] 2026-02-16 17:46:11.109011 | ubuntu-noble | ok 2026-02-16 17:46:11.127066 | 2026-02-16 17:46:11.127155 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-02-16 17:46:11.335994 | ubuntu-noble | ok 2026-02-16 17:46:11.342715 | 2026-02-16 17:46:11.342793 | TASK [test-setup : Run tools/test-setup.sh] 2026-02-16 17:46:11.876180 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:11.883931 | 2026-02-16 17:46:11.883987 | PLAY RECAP 2026-02-16 17:46:11.884035 | ubuntu-noble | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-16 17:46:11.884055 | 2026-02-16 17:46:12.001895 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-16 17:46:12.066206 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master] 2026-02-16 17:46:12.712811 | 2026-02-16 17:46:12.712975 | PLAY [all] 2026-02-16 17:46:12.727155 | 2026-02-16 17:46:12.727242 | TASK [ensure-python : Validate python_version value] 2026-02-16 17:46:12.752581 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:12.761629 | 2026-02-16 17:46:12.761736 | TASK [ensure-python : Install specified version of python interpreter and development files (DEB)] 2026-02-16 17:46:12.786692 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:12.798944 | 2026-02-16 17:46:12.799045 | TASK [ensure-python : Pull in venv package] 2026-02-16 17:46:12.824051 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:12.835371 | 2026-02-16 17:46:12.835499 | TASK [ensure-python : Set default RPM package name] 2026-02-16 17:46:12.870927 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:12.880758 | 2026-02-16 17:46:12.880896 | TASK [ensure-python : Set RPM package name for CentOS/RHEL 9/10] 2026-02-16 17:46:12.906117 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:12.916998 | 2026-02-16 17:46:12.917108 | TASK [ensure-python : Install RPM package] 2026-02-16 17:46:12.942887 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:12.953072 | 2026-02-16 17:46:12.953188 | TASK [ensure-python : Install python using pyenv] 2026-02-16 17:46:12.979503 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:12.989121 | 2026-02-16 17:46:12.989243 | TASK [ensure-python : Activate python using stow] 2026-02-16 17:46:13.014997 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:13.033166 | 2026-02-16 17:46:13.033232 | TASK [Install pip] 2026-02-16 17:46:13.056946 | ubuntu-noble | ok 2026-02-16 17:46:13.082173 | 2026-02-16 17:46:13.082291 | TASK [ensure-pip : Check if pip is installed] 2026-02-16 17:46:13.622765 | ubuntu-noble | ok: Runtime: 0:00:00.005947 2026-02-16 17:46:13.628704 | 2026-02-16 17:46:13.628770 | LOOP [ensure-pip : Install pip from packages] 2026-02-16 17:46:13.664996 | ubuntu-noble | ok: "/var/lib/zuul/builds/9e63448172674d949207be3d52e0c929/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/Debian.yaml" 2026-02-16 17:46:13.679162 | ubuntu-noble | included: /var/lib/zuul/builds/9e63448172674d949207be3d52e0c929/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/Debian.yaml 2026-02-16 17:46:13.688239 | 2026-02-16 17:46:13.688302 | TASK [ensure-pip : Update package lists] 2026-02-16 17:46:19.483215 | ubuntu-noble | changed 2026-02-16 17:46:19.488415 | 2026-02-16 17:46:19.488482 | TASK [ensure-pip : Install Python 3 pip] 2026-02-16 17:46:25.329479 | ubuntu-noble | changed 2026-02-16 17:46:25.336404 | 2026-02-16 17:46:25.336488 | TASK [ensure-pip : Install Python 2 pip] 2026-02-16 17:46:25.361945 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:25.372992 | 2026-02-16 17:46:25.373062 | TASK [ensure-pip : Ensure setuptools] 2026-02-16 17:46:25.397984 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:25.408899 | 2026-02-16 17:46:25.409021 | TASK [ensure-pip : Check for ensurepip module] 2026-02-16 17:46:25.678321 | ubuntu-noble | usage: python -m ensurepip [-h] [--version] [-v] [-U] [--user] [--root ROOT] 2026-02-16 17:46:25.678423 | ubuntu-noble | [--altinstall] [--default-pip] 2026-02-16 17:46:25.678438 | ubuntu-noble | 2026-02-16 17:46:25.678449 | ubuntu-noble | options: 2026-02-16 17:46:25.678459 | ubuntu-noble | -h, --help show this help message and exit 2026-02-16 17:46:25.678474 | ubuntu-noble | --version Show the version of pip that is bundled with this Python. 2026-02-16 17:46:25.678486 | ubuntu-noble | -v, --verbose Give more output. Option is additive, and can be used up to 3 2026-02-16 17:46:25.678500 | ubuntu-noble | times. 2026-02-16 17:46:25.678510 | ubuntu-noble | -U, --upgrade Upgrade pip and dependencies, even if already installed. 2026-02-16 17:46:25.678519 | ubuntu-noble | --user Install using the user scheme. 2026-02-16 17:46:25.678527 | ubuntu-noble | --root ROOT Install everything relative to this alternate root directory. 2026-02-16 17:46:25.678536 | ubuntu-noble | --altinstall Make an alternate install, installing only the X.Y versioned 2026-02-16 17:46:25.678545 | ubuntu-noble | scripts (Default: pipX, pipX.Y). 2026-02-16 17:46:25.678553 | ubuntu-noble | --default-pip Make a default pip install, installing the unqualified pip in 2026-02-16 17:46:25.678561 | ubuntu-noble | addition to the versioned scripts. 2026-02-16 17:46:25.949044 | ubuntu-noble | ok: Runtime: 0:00:00.058027 2026-02-16 17:46:25.956658 | 2026-02-16 17:46:25.956755 | TASK [ensure-pip : Ensure python3-venv] 2026-02-16 17:46:25.981834 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:25.992382 | 2026-02-16 17:46:25.992489 | TASK [ensure-pip : Install pip from source] 2026-02-16 17:46:26.017890 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:26.068225 | 2026-02-16 17:46:26.068380 | TASK [ensure-pip : Probe for venv python full path] 2026-02-16 17:46:26.297889 | ubuntu-noble | /usr/bin/python3 2026-02-16 17:46:26.605225 | ubuntu-noble | ok: Runtime: 0:00:00.004856 2026-02-16 17:46:26.611310 | 2026-02-16 17:46:26.611378 | TASK [ensure-pip : Set host default] 2026-02-16 17:46:26.667533 | ubuntu-noble | ok 2026-02-16 17:46:27.018592 | 2026-02-16 17:46:27.018708 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2026-02-16 17:46:27.265155 | ubuntu-noble | ok 2026-02-16 17:46:27.279422 | 2026-02-16 17:46:27.279562 | TASK [ensure-tox : Check if tox is installed] 2026-02-16 17:46:27.827444 | ubuntu-noble | ok: Runtime: 0:00:00.004992 2026-02-16 17:46:27.834910 | 2026-02-16 17:46:27.834998 | TASK [ensure-tox : Export preinstalled tox_exectuable] 2026-02-16 17:46:27.859373 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:27.872237 | 2026-02-16 17:46:27.872347 | TASK [ensure-tox : Create local venv] 2026-02-16 17:46:31.414908 | ubuntu-noble | ok: Runtime: 0:00:03.119109 2026-02-16 17:46:31.422521 | 2026-02-16 17:46:31.422672 | TASK [ensure-tox : Install tox to local venv] 2026-02-16 17:46:32.174139 | ubuntu-noble | Collecting tox 2026-02-16 17:46:32.217023 | ubuntu-noble | Downloading tox-4.36.0-py3-none-any.whl.metadata (3.6 kB) 2026-02-16 17:46:32.281779 | ubuntu-noble | Collecting cachetools>=7.0.1 (from tox) 2026-02-16 17:46:32.286119 | ubuntu-noble | Downloading cachetools-7.0.1-py3-none-any.whl.metadata (5.6 kB) 2026-02-16 17:46:32.326896 | ubuntu-noble | Collecting chardet>=5.2 (from tox) 2026-02-16 17:46:32.346218 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl.metadata (3.4 kB) 2026-02-16 17:46:32.388966 | ubuntu-noble | Collecting colorama>=0.4.6 (from tox) 2026-02-16 17:46:32.392676 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl.metadata (17 kB) 2026-02-16 17:46:32.451480 | ubuntu-noble | Collecting filelock>=3.24 (from tox) 2026-02-16 17:46:32.454562 | ubuntu-noble | Downloading filelock-3.24.2-py3-none-any.whl.metadata (2.0 kB) 2026-02-16 17:46:32.505607 | ubuntu-noble | Collecting packaging>=26 (from tox) 2026-02-16 17:46:32.508478 | ubuntu-noble | Downloading packaging-26.0-py3-none-any.whl.metadata (3.3 kB) 2026-02-16 17:46:32.563995 | ubuntu-noble | Collecting platformdirs>=4.9.1 (from tox) 2026-02-16 17:46:32.566942 | ubuntu-noble | Downloading platformdirs-4.9.2-py3-none-any.whl.metadata (4.7 kB) 2026-02-16 17:46:32.605218 | ubuntu-noble | Collecting pluggy>=1.6 (from tox) 2026-02-16 17:46:32.608186 | ubuntu-noble | Downloading pluggy-1.6.0-py3-none-any.whl.metadata (4.8 kB) 2026-02-16 17:46:32.655620 | ubuntu-noble | Collecting pyproject-api>=1.10 (from tox) 2026-02-16 17:46:32.659486 | ubuntu-noble | Downloading pyproject_api-1.10.0-py3-none-any.whl.metadata (2.7 kB) 2026-02-16 17:46:32.774905 | ubuntu-noble | Collecting virtualenv>=20.36.1 (from tox) 2026-02-16 17:46:32.778290 | ubuntu-noble | Downloading virtualenv-20.37.0-py3-none-any.whl.metadata (5.1 kB) 2026-02-16 17:46:32.873871 | ubuntu-noble | Collecting distlib<1,>=0.3.7 (from virtualenv>=20.36.1->tox) 2026-02-16 17:46:32.876993 | ubuntu-noble | Downloading distlib-0.4.0-py2.py3-none-any.whl.metadata (5.2 kB) 2026-02-16 17:46:32.911898 | ubuntu-noble | Downloading tox-4.36.0-py3-none-any.whl (179 kB) 2026-02-16 17:46:32.930732 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 179.1/179.1 kB 10.8 MB/s eta 0:00:00 2026-02-16 17:46:32.934217 | ubuntu-noble | Downloading cachetools-7.0.1-py3-none-any.whl (13 kB) 2026-02-16 17:46:32.946837 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2026-02-16 17:46:32.966455 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 14.2 MB/s eta 0:00:00 2026-02-16 17:46:32.970405 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-02-16 17:46:32.982953 | ubuntu-noble | Downloading filelock-3.24.2-py3-none-any.whl (24 kB) 2026-02-16 17:46:32.997519 | ubuntu-noble | Downloading packaging-26.0-py3-none-any.whl (74 kB) 2026-02-16 17:46:33.012612 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 5.6 MB/s eta 0:00:00 2026-02-16 17:46:33.017109 | ubuntu-noble | Downloading platformdirs-4.9.2-py3-none-any.whl (21 kB) 2026-02-16 17:46:33.031760 | ubuntu-noble | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-02-16 17:46:33.046526 | ubuntu-noble | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-02-16 17:46:33.061915 | ubuntu-noble | Downloading virtualenv-20.37.0-py3-none-any.whl (5.8 MB) 2026-02-16 17:46:33.261304 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.8/5.8 MB 29.9 MB/s eta 0:00:00 2026-02-16 17:46:33.265242 | ubuntu-noble | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-02-16 17:46:33.284750 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 29.9 MB/s eta 0:00:00 2026-02-16 17:46:33.353045 | ubuntu-noble | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2026-02-16 17:46:34.190541 | 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:46:34.465307 | ubuntu-noble | ok: Runtime: 0:00:02.604775 2026-02-16 17:46:34.474923 | 2026-02-16 17:46:34.475038 | TASK [ensure-tox : Export installed tox_executable path] 2026-02-16 17:46:34.785291 | ubuntu-noble | ok 2026-02-16 17:46:34.790567 | 2026-02-16 17:46:34.790647 | TASK [ensure-tox : Output tox version] 2026-02-16 17:46:35.227708 | 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:46:35.241880 | ubuntu-noble | 4.36.0 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2026-02-16 17:46:35.329066 | ubuntu-noble | ok: Runtime: 0:00:00.258864 2026-02-16 17:46:35.336449 | 2026-02-16 17:46:35.336515 | TASK [ensure-tox : Make global symlink] 2026-02-16 17:46:35.361115 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:35.372221 | 2026-02-16 17:46:35.372303 | PLAY RECAP 2026-02-16 17:46:35.372375 | ubuntu-noble | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-02-16 17:46:35.372416 | 2026-02-16 17:46:35.528086 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master] 2026-02-16 17:46:35.530002 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/tox/pre.yml@main] 2026-02-16 17:46:36.241149 | 2026-02-16 17:46:36.241344 | PLAY [all] 2026-02-16 17:46:36.257447 | 2026-02-16 17:46:36.257785 | TASK [ensure-rust : Use rustup] 2026-02-16 17:46:36.293867 | ubuntu-noble | ok 2026-02-16 17:46:36.311248 | ubuntu-noble | included: /var/lib/zuul/builds/9e63448172674d949207be3d52e0c929/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml 2026-02-16 17:46:36.323210 | 2026-02-16 17:46:36.323351 | TASK [ensure-rust : Install Rust] 2026-02-16 17:46:36.757940 | ubuntu-noble | /bin/bash: line 2: curl: command not found 2026-02-16 17:46:36.861962 | ubuntu-noble | ERROR 2026-02-16 17:46:36.862240 | ubuntu-noble | { 2026-02-16 17:46:36.862282 | ubuntu-noble | "delta": "0:00:00.007355", 2026-02-16 17:46:36.862315 | ubuntu-noble | "end": "2026-02-16 17:46:36.758998", 2026-02-16 17:46:36.862343 | ubuntu-noble | "msg": "non-zero return code", 2026-02-16 17:46:36.862369 | ubuntu-noble | "rc": 127, 2026-02-16 17:46:36.862394 | ubuntu-noble | "start": "2026-02-16 17:46:36.751643" 2026-02-16 17:46:36.862427 | ubuntu-noble | } failure 2026-02-16 17:46:36.864354 | 2026-02-16 17:46:36.864422 | PLAY RECAP 2026-02-16 17:46:36.864494 | ubuntu-noble | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-02-16 17:46:36.864523 | 2026-02-16 17:46:36.987248 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/tox/pre.yml@main] 2026-02-16 17:46:36.988646 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2026-02-16 17:46:37.691055 | 2026-02-16 17:46:37.691277 | PLAY [all] 2026-02-16 17:46:37.707548 | 2026-02-16 17:46:37.707701 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-02-16 17:46:37.753910 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:37.764859 | 2026-02-16 17:46:37.765012 | TASK [fetch-tox-output : Set tox log path for single node] 2026-02-16 17:46:37.812054 | ubuntu-noble | ok 2026-02-16 17:46:37.821729 | 2026-02-16 17:46:37.821810 | TASK [fetch-tox-output : Ensure local tox dir] 2026-02-16 17:46:38.243682 | ubuntu-noble -> localhost | changed 2026-02-16 17:46:38.253438 | 2026-02-16 17:46:38.253531 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-02-16 17:46:38.316007 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:38.327469 | 2026-02-16 17:46:38.327606 | TASK [fetch-tox-output : Set envlist fact] 2026-02-16 17:46:38.364559 | ubuntu-noble | ok 2026-02-16 17:46:38.371988 | 2026-02-16 17:46:38.372093 | TASK [fetch-tox-output : Get tox version] 2026-02-16 17:46:38.961047 | 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:46:38.983028 | ubuntu-noble | 4.36.0 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2026-02-16 17:46:39.412078 | ubuntu-noble | ok: Runtime: 0:00:00.280380 2026-02-16 17:46:39.419136 | 2026-02-16 17:46:39.429427 | TASK [fetch-tox-output : Find all default environments] 2026-02-16 17:46:39.962280 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:39.972518 | 2026-02-16 17:46:39.972644 | TASK [fetch-tox-output : Set envlist fact] 2026-02-16 17:46:39.999038 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:40.010796 | 2026-02-16 17:46:40.010962 | TASK [fetch-tox-output : Find all default environments] 2026-02-16 17:46:40.545037 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:40.557381 | 2026-02-16 17:46:40.557526 | TASK [fetch-tox-output : Set envlist fact] 2026-02-16 17:46:40.583410 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:40.594175 | 2026-02-16 17:46:40.594269 | LOOP [fetch-tox-output : Copy tox logs] 2026-02-16 17:46:40.637643 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:40.652735 | 2026-02-16 17:46:40.652891 | LOOP [fetch-tox-output : Collect tox logs] 2026-02-16 17:46:41.397984 | ubuntu-noble | ok: 2026-02-16 17:46:41.398263 | 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:46:41.398314 | 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:46:41.398337 | ubuntu-noble | rsync: [Receiver] write error: Broken pipe (32) 2026-02-16 17:46:41.398366 | 2026-02-16 17:46:41.415827 | 2026-02-16 17:46:41.415946 | PLAY RECAP 2026-02-16 17:46:41.415999 | ubuntu-noble | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-02-16 17:46:41.416023 | 2026-02-16 17:46:41.556662 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2026-02-16 17:46:41.558410 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-16 17:46:42.125102 | 2026-02-16 17:46:42.125263 | PLAY [all] 2026-02-16 17:46:42.138209 | 2026-02-16 17:46:42.138302 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-02-16 17:46:42.318740 | ubuntu-noble | changed: non-zero return code 2026-02-16 17:46:42.324290 | 2026-02-16 17:46:42.324370 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-02-16 17:46:42.349485 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:42.356116 | 2026-02-16 17:46:42.356189 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-02-16 17:46:42.391561 | 2026-02-16 17:46:42.391762 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-02-16 17:46:42.426205 | 2026-02-16 17:46:42.426418 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-02-16 17:46:42.449878 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:42.459688 | 2026-02-16 17:46:42.459801 | LOOP [fetch-subunit-output : Generate subunit file] 2026-02-16 17:46:42.492182 | 2026-02-16 17:46:42.492390 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-02-16 17:46:42.515552 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:42.526636 | 2026-02-16 17:46:42.526728 | TASK [fetch-subunit-output : Remove the temporary file] 2026-02-16 17:46:42.551579 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:42.562604 | 2026-02-16 17:46:42.563288 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-02-16 17:46:42.587802 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:42.598934 | 2026-02-16 17:46:42.613678 | PLAY RECAP 2026-02-16 17:46:42.613822 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-16 17:46:42.613880 | 2026-02-16 17:46:42.731698 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-16 17:46:42.733274 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 17:46:43.350483 | 2026-02-16 17:46:43.350623 | PLAY [all] 2026-02-16 17:46:43.362648 | 2026-02-16 17:46:43.362734 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-16 17:46:43.399425 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:43.409309 | 2026-02-16 17:46:43.409427 | TASK [fetch-output : Set log path for single node] 2026-02-16 17:46:43.459119 | ubuntu-noble | ok 2026-02-16 17:46:43.473719 | 2026-02-16 17:46:43.474805 | LOOP [fetch-output : Ensure local output dirs] 2026-02-16 17:46:43.902239 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/9e63448172674d949207be3d52e0c929/work/logs" 2026-02-16 17:46:44.143412 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/9e63448172674d949207be3d52e0c929/work/artifacts" 2026-02-16 17:46:44.391576 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/9e63448172674d949207be3d52e0c929/work/docs" 2026-02-16 17:46:44.415005 | 2026-02-16 17:46:44.415160 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-16 17:46:45.103532 | ubuntu-noble | changed: .d..t...... ./ 2026-02-16 17:46:45.103820 | ubuntu-noble | changed: All items complete 2026-02-16 17:46:45.103849 | 2026-02-16 17:46:45.554304 | ubuntu-noble | changed: .d..t...... ./ 2026-02-16 17:46:46.018366 | ubuntu-noble | changed: .d..t...... ./ 2026-02-16 17:46:46.073400 | 2026-02-16 17:46:46.073546 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-16 17:46:46.525556 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.008630 2026-02-16 17:46:46.750157 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.008405 2026-02-16 17:46:46.770959 | 2026-02-16 17:46:46.771092 | PLAY [all] 2026-02-16 17:46:46.779440 | 2026-02-16 17:46:46.779512 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-16 17:46:47.231769 | ubuntu-noble | changed 2026-02-16 17:46:47.239712 | 2026-02-16 17:46:47.239772 | PLAY RECAP 2026-02-16 17:46:47.239816 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-16 17:46:47.239838 | 2026-02-16 17:46:47.398941 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 17:46:47.400345 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-16 17:46:48.022221 | 2026-02-16 17:46:48.022404 | PLAY [localhost] 2026-02-16 17:46:48.033796 | 2026-02-16 17:46:48.033884 | TASK [Generate Zuul manifest] 2026-02-16 17:46:48.058720 | localhost | ok 2026-02-16 17:46:48.076887 | 2026-02-16 17:46:48.076984 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-16 17:46:48.442231 | localhost | changed 2026-02-16 17:46:48.454181 | 2026-02-16 17:46:48.454285 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-16 17:46:48.484181 | localhost | ok 2026-02-16 17:46:48.490943 | 2026-02-16 17:46:48.491010 | TASK [Upload logs] 2026-02-16 17:46:48.513817 | localhost | ok 2026-02-16 17:46:48.606064 | 2026-02-16 17:46:48.606241 | TASK [Set zuul-log-path fact] 2026-02-16 17:46:48.626390 | localhost | ok 2026-02-16 17:46:48.639272 | 2026-02-16 17:46:48.639340 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 17:46:48.669746 | localhost | ok 2026-02-16 17:46:48.678803 | 2026-02-16 17:46:48.678869 | TASK [upload-logs : Create log directories] 2026-02-16 17:46:49.188144 | localhost | changed 2026-02-16 17:46:49.194833 | 2026-02-16 17:46:49.194922 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-16 17:46:49.660145 | localhost -> localhost | ok: Runtime: 0:00:00.006133 2026-02-16 17:46:49.664786 | 2026-02-16 17:46:49.664894 | TASK [upload-logs : Upload logs to log server] 2026-02-16 17:46:50.231679 | localhost | Output suppressed because no_log was given 2026-02-16 17:46:50.236784 | 2026-02-16 17:46:50.236883 | LOOP [upload-logs : Compress console log and json output] 2026-02-16 17:46:50.294254 | localhost | skipping: Conditional result was False 2026-02-16 17:46:50.301972 | localhost | skipping: Conditional result was False 2026-02-16 17:46:50.312288 | 2026-02-16 17:46:50.312386 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-16 17:46:50.358639 | localhost | skipping: Conditional result was False 2026-02-16 17:46:50.359043 | 2026-02-16 17:46:50.363561 | localhost | skipping: Conditional result was False 2026-02-16 17:46:50.378589 | 2026-02-16 17:46:50.379176 | LOOP [upload-logs : Upload console log and json output]