2026-02-16 17:40:57.113138 | Job console starting 2026-02-16 17:40:57.144667 | Updating git repos 2026-02-16 17:40:57.183490 | Cloning repos into workspace 2026-02-16 17:40:57.262612 | Restoring repo states 2026-02-16 17:40:57.275537 | Merging changes 2026-02-16 17:40:57.675370 | Checking out repos 2026-02-16 17:40:57.874219 | Preparing playbooks 2026-02-16 17:40:59.447035 | Running Ansible setup 2026-02-16 17:41:03.031836 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 17:41:03.682458 | 2026-02-16 17:41:03.682601 | PLAY [localhost] 2026-02-16 17:41:03.693332 | 2026-02-16 17:41:03.693568 | TASK [Gathering Facts] 2026-02-16 17:41:04.618179 | localhost | ok 2026-02-16 17:41:04.625446 | 2026-02-16 17:41:04.625538 | TASK [Setup log path fact] 2026-02-16 17:41:04.647509 | localhost | ok 2026-02-16 17:41:04.659790 | 2026-02-16 17:41:04.659856 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 17:41:04.690364 | localhost | ok 2026-02-16 17:41:04.698935 | 2026-02-16 17:41:04.699030 | TASK [emit-job-header : Print job information] 2026-02-16 17:41:04.742677 | # Job Information 2026-02-16 17:41:04.742841 | Ansible Version: 2.16.15 2026-02-16 17:41:04.742885 | Job: magnum-cluster-api-tox-unit 2026-02-16 17:41:04.742915 | Pipeline: check 2026-02-16 17:41:04.742943 | Executor: 3a2793d2bd32 2026-02-16 17:41:04.742993 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884 2026-02-16 17:41:04.743028 | Event ID: 2685a480-0b5e-11f1-90f6-a235114455cc 2026-02-16 17:41:04.747556 | 2026-02-16 17:41:04.747679 | LOOP [emit-job-header : Print node information] 2026-02-16 17:41:04.859262 | localhost | ok: 2026-02-16 17:41:04.859602 | localhost | # Node Information 2026-02-16 17:41:04.859667 | localhost | Inventory Hostname: ubuntu-noble 2026-02-16 17:41:04.859715 | localhost | Hostname: np0000155619 2026-02-16 17:41:04.859759 | localhost | Username: zuul 2026-02-16 17:41:04.859810 | localhost | Distro: Ubuntu 24.04 2026-02-16 17:41:04.859854 | localhost | Provider: yul1 2026-02-16 17:41:04.859898 | localhost | Region: ca-ymq-1 2026-02-16 17:41:04.859938 | localhost | Label: ubuntu-noble 2026-02-16 17:41:04.859978 | localhost | Product Name: OpenStack Nova 2026-02-16 17:41:04.860019 | localhost | Interface IP: 199.204.45.4 2026-02-16 17:41:04.877804 | 2026-02-16 17:41:04.877983 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-16 17:41:05.307160 | localhost -> localhost | changed 2026-02-16 17:41:05.316428 | 2026-02-16 17:41:05.316527 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-16 17:41:06.178932 | localhost -> localhost | changed 2026-02-16 17:41:06.186778 | 2026-02-16 17:41:06.187641 | PLAY [all] 2026-02-16 17:41:06.197282 | 2026-02-16 17:41:06.197354 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-16 17:41:06.437319 | ubuntu-noble -> localhost | ok 2026-02-16 17:41:06.447796 | 2026-02-16 17:41:06.447872 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-16 17:41:06.481417 | ubuntu-noble | ok 2026-02-16 17:41:06.497592 | ubuntu-noble | included: /var/lib/zuul/builds/91ee02536e8848e9a68c249dffb57d98/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-16 17:41:06.504139 | 2026-02-16 17:41:06.504201 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-16 17:41:07.532280 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2026-02-16 17:41:07.532512 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/91ee02536e8848e9a68c249dffb57d98/work/91ee02536e8848e9a68c249dffb57d98_id_rsa 2026-02-16 17:41:07.532558 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/91ee02536e8848e9a68c249dffb57d98/work/91ee02536e8848e9a68c249dffb57d98_id_rsa.pub 2026-02-16 17:41:07.532593 | ubuntu-noble -> localhost | The key fingerprint is: 2026-02-16 17:41:07.532625 | ubuntu-noble -> localhost | SHA256:BWEClUak7TDtbA3RA16rZ4PkZjbioVklFysQNYqIEU8 zuul-build-sshkey 2026-02-16 17:41:07.532673 | ubuntu-noble -> localhost | The key's randomart image is: 2026-02-16 17:41:07.532705 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2026-02-16 17:41:07.532743 | ubuntu-noble -> localhost | |o.Eo+=O+=. | 2026-02-16 17:41:07.532775 | ubuntu-noble -> localhost | |o= o *oBoo | 2026-02-16 17:41:07.532805 | ubuntu-noble -> localhost | |+ o *.X ... | 2026-02-16 17:41:07.532834 | ubuntu-noble -> localhost | | & = . | 2026-02-16 17:41:07.532863 | ubuntu-noble -> localhost | | + & S | 2026-02-16 17:41:07.532891 | ubuntu-noble -> localhost | | = B + . | 2026-02-16 17:41:07.532920 | ubuntu-noble -> localhost | | o . | 2026-02-16 17:41:07.532948 | ubuntu-noble -> localhost | | | 2026-02-16 17:41:07.533042 | ubuntu-noble -> localhost | | | 2026-02-16 17:41:07.533087 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2026-02-16 17:41:07.533170 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.558963 2026-02-16 17:41:07.539979 | 2026-02-16 17:41:07.540069 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-16 17:41:07.590519 | ubuntu-noble | ok 2026-02-16 17:41:07.617119 | ubuntu-noble | included: /var/lib/zuul/builds/91ee02536e8848e9a68c249dffb57d98/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-16 17:41:07.625479 | 2026-02-16 17:41:07.625547 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-16 17:41:07.651002 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:07.661477 | 2026-02-16 17:41:07.661579 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-16 17:41:08.255117 | ubuntu-noble | changed 2026-02-16 17:41:08.260533 | 2026-02-16 17:41:08.260597 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-16 17:41:08.495863 | ubuntu-noble | ok 2026-02-16 17:41:08.501322 | 2026-02-16 17:41:08.501385 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-16 17:41:09.079951 | ubuntu-noble | changed 2026-02-16 17:41:09.086618 | 2026-02-16 17:41:09.086682 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-16 17:41:09.638626 | ubuntu-noble | changed 2026-02-16 17:41:09.647232 | 2026-02-16 17:41:09.647320 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-16 17:41:09.673167 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:09.683875 | 2026-02-16 17:41:09.683981 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-16 17:41:10.063212 | ubuntu-noble -> localhost | changed 2026-02-16 17:41:10.078111 | 2026-02-16 17:41:10.078181 | TASK [add-build-sshkey : Add back temp key] 2026-02-16 17:41:10.394726 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/91ee02536e8848e9a68c249dffb57d98/work/91ee02536e8848e9a68c249dffb57d98_id_rsa (zuul-build-sshkey) 2026-02-16 17:41:10.394961 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.015695 2026-02-16 17:41:10.404138 | 2026-02-16 17:41:10.404205 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-16 17:41:10.770587 | ubuntu-noble | ok 2026-02-16 17:41:10.776110 | 2026-02-16 17:41:10.776181 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-16 17:41:10.801823 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:10.816153 | 2026-02-16 17:41:10.816220 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-16 17:41:11.210528 | ubuntu-noble | ok 2026-02-16 17:41:11.218455 | 2026-02-16 17:41:11.218525 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-16 17:41:12.229781 | ubuntu-noble | Output suppressed because no_log was given 2026-02-16 17:41:12.239153 | 2026-02-16 17:41:12.239216 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-16 17:41:12.459054 | ubuntu-noble | ok: "logs" 2026-02-16 17:41:12.459364 | ubuntu-noble | ok: All items complete 2026-02-16 17:41:12.459413 | 2026-02-16 17:41:12.690289 | ubuntu-noble | ok: "artifacts" 2026-02-16 17:41:12.833716 | ubuntu-noble | ok: "docs" 2026-02-16 17:41:12.849014 | 2026-02-16 17:41:12.849170 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-16 17:41:13.079019 | ubuntu-noble | changed: "logs" 2026-02-16 17:41:13.274827 | ubuntu-noble | changed: "artifacts" 2026-02-16 17:41:13.469295 | ubuntu-noble | changed: "docs" 2026-02-16 17:41:13.487881 | 2026-02-16 17:41:13.487997 | PLAY RECAP 2026-02-16 17:41:13.488043 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-16 17:41:13.488074 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-16 17:41:13.488097 | 2026-02-16 17:41:13.631718 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 17:41:13.677531 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-16 17:41:14.244861 | 2026-02-16 17:41:14.245023 | PLAY [all] 2026-02-16 17:41:14.257308 | 2026-02-16 17:41:14.257379 | TASK [Install binary dependencies] 2026-02-16 17:41:14.322260 | ubuntu-noble | ok 2026-02-16 17:41:14.341683 | 2026-02-16 17:41:14.341834 | TASK [bindep : Include find tasks] 2026-02-16 17:41:14.378564 | ubuntu-noble | ok 2026-02-16 17:41:14.386120 | ubuntu-noble | included: /var/lib/zuul/builds/91ee02536e8848e9a68c249dffb57d98/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-02-16 17:41:14.392169 | 2026-02-16 17:41:14.392233 | TASK [bindep : Look for bindep.txt] 2026-02-16 17:41:14.762473 | ubuntu-noble | ok 2026-02-16 17:41:14.768837 | 2026-02-16 17:41:14.768908 | TASK [bindep : Define bindep_file fact] 2026-02-16 17:41:14.794568 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:14.808141 | 2026-02-16 17:41:14.808347 | TASK [bindep : Look for other-requirements.txt] 2026-02-16 17:41:15.015764 | ubuntu-noble | ok 2026-02-16 17:41:15.034666 | 2026-02-16 17:41:15.034795 | TASK [bindep : Define bindep_file fact] 2026-02-16 17:41:15.060171 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:15.066528 | 2026-02-16 17:41:15.066604 | TASK [bindep : Look for bindep fallback file] 2026-02-16 17:41:15.091695 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:15.100004 | 2026-02-16 17:41:15.100097 | TASK [bindep : Define bindep_file fact] 2026-02-16 17:41:15.125159 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:15.131749 | 2026-02-16 17:41:15.131823 | TASK [bindep : Include bindep tasks] 2026-02-16 17:41:15.156748 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:15.162799 | 2026-02-16 17:41:15.162865 | TASK [bindep : Include install tasks] 2026-02-16 17:41:15.188061 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:15.194875 | 2026-02-16 17:41:15.194945 | LOOP [bindep : Include package tasks] 2026-02-16 17:41:15.248562 | 2026-02-16 17:41:15.248732 | TASK [Run test-setup role] 2026-02-16 17:41:15.271633 | ubuntu-noble | ok 2026-02-16 17:41:15.290610 | 2026-02-16 17:41:15.290786 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-02-16 17:41:15.514447 | ubuntu-noble | ok 2026-02-16 17:41:15.520471 | 2026-02-16 17:41:15.520540 | TASK [test-setup : Run tools/test-setup.sh] 2026-02-16 17:41:16.047424 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:16.060341 | 2026-02-16 17:41:16.060392 | PLAY RECAP 2026-02-16 17:41:16.060439 | ubuntu-noble | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-16 17:41:16.060460 | 2026-02-16 17:41:16.243768 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-16 17:41:16.246170 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master] 2026-02-16 17:41:16.851381 | 2026-02-16 17:41:16.851517 | PLAY [all] 2026-02-16 17:41:16.865481 | 2026-02-16 17:41:16.865557 | TASK [ensure-python : Validate python_version value] 2026-02-16 17:41:16.901052 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:16.910769 | 2026-02-16 17:41:16.910853 | TASK [ensure-python : Install specified version of python interpreter and development files (DEB)] 2026-02-16 17:41:16.935364 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:16.946000 | 2026-02-16 17:41:16.946098 | TASK [ensure-python : Pull in venv package] 2026-02-16 17:41:16.990948 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:17.002162 | 2026-02-16 17:41:17.002250 | TASK [ensure-python : Set default RPM package name] 2026-02-16 17:41:17.027845 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:17.036848 | 2026-02-16 17:41:17.036935 | TASK [ensure-python : Set RPM package name for CentOS/RHEL 9/10] 2026-02-16 17:41:17.071621 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:17.081768 | 2026-02-16 17:41:17.081838 | TASK [ensure-python : Install RPM package] 2026-02-16 17:41:17.107109 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:17.116806 | 2026-02-16 17:41:17.116897 | TASK [ensure-python : Install python using pyenv] 2026-02-16 17:41:17.142071 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:17.152939 | 2026-02-16 17:41:17.153021 | TASK [ensure-python : Activate python using stow] 2026-02-16 17:41:17.178417 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:17.195997 | 2026-02-16 17:41:17.196068 | TASK [Install pip] 2026-02-16 17:41:17.219502 | ubuntu-noble | ok 2026-02-16 17:41:17.243822 | 2026-02-16 17:41:17.243900 | TASK [ensure-pip : Check if pip is installed] 2026-02-16 17:41:17.784092 | ubuntu-noble | ok: Runtime: 0:00:00.006790 2026-02-16 17:41:17.790942 | 2026-02-16 17:41:17.791020 | LOOP [ensure-pip : Install pip from packages] 2026-02-16 17:41:17.835404 | ubuntu-noble | ok: "/var/lib/zuul/builds/91ee02536e8848e9a68c249dffb57d98/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/Debian.yaml" 2026-02-16 17:41:17.871749 | ubuntu-noble | included: /var/lib/zuul/builds/91ee02536e8848e9a68c249dffb57d98/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/Debian.yaml 2026-02-16 17:41:17.882243 | 2026-02-16 17:41:17.882357 | TASK [ensure-pip : Update package lists] 2026-02-16 17:41:24.063975 | ubuntu-noble | changed 2026-02-16 17:41:24.070310 | 2026-02-16 17:41:24.070377 | TASK [ensure-pip : Install Python 3 pip] 2026-02-16 17:41:30.315504 | ubuntu-noble | changed 2026-02-16 17:41:30.321543 | 2026-02-16 17:41:30.321606 | TASK [ensure-pip : Install Python 2 pip] 2026-02-16 17:41:30.346723 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:30.357816 | 2026-02-16 17:41:30.357885 | TASK [ensure-pip : Ensure setuptools] 2026-02-16 17:41:30.383926 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:30.395825 | 2026-02-16 17:41:30.395920 | TASK [ensure-pip : Check for ensurepip module] 2026-02-16 17:41:30.576294 | ubuntu-noble | usage: python -m ensurepip [-h] [--version] [-v] [-U] [--user] [--root ROOT] 2026-02-16 17:41:30.576383 | ubuntu-noble | [--altinstall] [--default-pip] 2026-02-16 17:41:30.576398 | ubuntu-noble | 2026-02-16 17:41:30.576408 | ubuntu-noble | options: 2026-02-16 17:41:30.576419 | ubuntu-noble | -h, --help show this help message and exit 2026-02-16 17:41:30.576434 | ubuntu-noble | --version Show the version of pip that is bundled with this Python. 2026-02-16 17:41:30.576446 | ubuntu-noble | -v, --verbose Give more output. Option is additive, and can be used up to 3 2026-02-16 17:41:30.576460 | ubuntu-noble | times. 2026-02-16 17:41:30.576469 | ubuntu-noble | -U, --upgrade Upgrade pip and dependencies, even if already installed. 2026-02-16 17:41:30.576478 | ubuntu-noble | --user Install using the user scheme. 2026-02-16 17:41:30.576487 | ubuntu-noble | --root ROOT Install everything relative to this alternate root directory. 2026-02-16 17:41:30.576496 | ubuntu-noble | --altinstall Make an alternate install, installing only the X.Y versioned 2026-02-16 17:41:30.576505 | ubuntu-noble | scripts (Default: pipX, pipX.Y). 2026-02-16 17:41:30.576513 | ubuntu-noble | --default-pip Make a default pip install, installing the unqualified pip in 2026-02-16 17:41:30.576522 | ubuntu-noble | addition to the versioned scripts. 2026-02-16 17:41:30.927574 | ubuntu-noble | ok: Runtime: 0:00:00.070043 2026-02-16 17:41:30.935531 | 2026-02-16 17:41:30.935601 | TASK [ensure-pip : Ensure python3-venv] 2026-02-16 17:41:30.960832 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:30.970657 | 2026-02-16 17:41:30.970722 | TASK [ensure-pip : Install pip from source] 2026-02-16 17:41:30.995073 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:31.045876 | 2026-02-16 17:41:31.046045 | TASK [ensure-pip : Probe for venv python full path] 2026-02-16 17:41:31.183361 | ubuntu-noble | /usr/bin/python3 2026-02-16 17:41:31.594435 | ubuntu-noble | ok: Runtime: 0:00:00.004569 2026-02-16 17:41:31.600222 | 2026-02-16 17:41:31.600293 | TASK [ensure-pip : Set host default] 2026-02-16 17:41:31.655687 | ubuntu-noble | ok 2026-02-16 17:41:31.663528 | 2026-02-16 17:41:31.663709 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2026-02-16 17:41:31.722943 | ubuntu-noble | ok 2026-02-16 17:41:31.732753 | 2026-02-16 17:41:31.732822 | TASK [ensure-tox : Check if tox is installed] 2026-02-16 17:41:32.261397 | ubuntu-noble | ok: Runtime: 0:00:00.008418 2026-02-16 17:41:32.267470 | 2026-02-16 17:41:32.267556 | TASK [ensure-tox : Export preinstalled tox_exectuable] 2026-02-16 17:41:32.299314 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:32.310196 | 2026-02-16 17:41:32.310271 | TASK [ensure-tox : Create local venv] 2026-02-16 17:41:35.820522 | ubuntu-noble | ok: Runtime: 0:00:03.152579 2026-02-16 17:41:35.826924 | 2026-02-16 17:41:35.826989 | TASK [ensure-tox : Install tox to local venv] 2026-02-16 17:41:36.530958 | ubuntu-noble | Collecting tox 2026-02-16 17:41:36.599815 | ubuntu-noble | Downloading tox-4.36.0-py3-none-any.whl.metadata (3.6 kB) 2026-02-16 17:41:36.671289 | ubuntu-noble | Collecting cachetools>=7.0.1 (from tox) 2026-02-16 17:41:36.681127 | ubuntu-noble | Downloading cachetools-7.0.1-py3-none-any.whl.metadata (5.6 kB) 2026-02-16 17:41:36.726973 | ubuntu-noble | Collecting chardet>=5.2 (from tox) 2026-02-16 17:41:36.736696 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl.metadata (3.4 kB) 2026-02-16 17:41:36.787185 | ubuntu-noble | Collecting colorama>=0.4.6 (from tox) 2026-02-16 17:41:36.798303 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl.metadata (17 kB) 2026-02-16 17:41:36.869169 | ubuntu-noble | Collecting filelock>=3.24 (from tox) 2026-02-16 17:41:36.878378 | ubuntu-noble | Downloading filelock-3.24.2-py3-none-any.whl.metadata (2.0 kB) 2026-02-16 17:41:36.941459 | ubuntu-noble | Collecting packaging>=26 (from tox) 2026-02-16 17:41:36.951468 | ubuntu-noble | Downloading packaging-26.0-py3-none-any.whl.metadata (3.3 kB) 2026-02-16 17:41:37.011102 | ubuntu-noble | Collecting platformdirs>=4.9.1 (from tox) 2026-02-16 17:41:37.020347 | ubuntu-noble | Downloading platformdirs-4.9.2-py3-none-any.whl.metadata (4.7 kB) 2026-02-16 17:41:37.072299 | ubuntu-noble | Collecting pluggy>=1.6 (from tox) 2026-02-16 17:41:37.080930 | ubuntu-noble | Downloading pluggy-1.6.0-py3-none-any.whl.metadata (4.8 kB) 2026-02-16 17:41:37.126406 | ubuntu-noble | Collecting pyproject-api>=1.10 (from tox) 2026-02-16 17:41:37.147157 | ubuntu-noble | Downloading pyproject_api-1.10.0-py3-none-any.whl.metadata (2.7 kB) 2026-02-16 17:41:37.284343 | ubuntu-noble | Collecting virtualenv>=20.36.1 (from tox) 2026-02-16 17:41:37.293219 | ubuntu-noble | Downloading virtualenv-20.37.0-py3-none-any.whl.metadata (5.1 kB) 2026-02-16 17:41:37.391256 | ubuntu-noble | Collecting distlib<1,>=0.3.7 (from virtualenv>=20.36.1->tox) 2026-02-16 17:41:37.400736 | ubuntu-noble | Downloading distlib-0.4.0-py2.py3-none-any.whl.metadata (5.2 kB) 2026-02-16 17:41:37.446056 | ubuntu-noble | Downloading tox-4.36.0-py3-none-any.whl (179 kB) 2026-02-16 17:41:37.478503 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 179.1/179.1 kB 6.1 MB/s eta 0:00:00 2026-02-16 17:41:37.495935 | ubuntu-noble | Downloading cachetools-7.0.1-py3-none-any.whl (13 kB) 2026-02-16 17:41:37.520694 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2026-02-16 17:41:37.547510 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 8.1 MB/s eta 0:00:00 2026-02-16 17:41:37.560351 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-02-16 17:41:37.580622 | ubuntu-noble | Downloading filelock-3.24.2-py3-none-any.whl (24 kB) 2026-02-16 17:41:37.601439 | ubuntu-noble | Downloading packaging-26.0-py3-none-any.whl (74 kB) 2026-02-16 17:41:37.616673 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 5.2 MB/s eta 0:00:00 2026-02-16 17:41:37.626947 | ubuntu-noble | Downloading platformdirs-4.9.2-py3-none-any.whl (21 kB) 2026-02-16 17:41:37.647012 | ubuntu-noble | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-02-16 17:41:37.666973 | ubuntu-noble | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-02-16 17:41:37.689643 | ubuntu-noble | Downloading virtualenv-20.37.0-py3-none-any.whl (5.8 MB) 2026-02-16 17:41:37.874138 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.8/5.8 MB 32.4 MB/s eta 0:00:00 2026-02-16 17:41:37.884055 | ubuntu-noble | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-02-16 17:41:37.906701 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 26.5 MB/s eta 0:00:00 2026-02-16 17:41:37.976117 | ubuntu-noble | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2026-02-16 17:41:38.834395 | 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:41:39.346984 | ubuntu-noble | ok: Runtime: 0:00:02.934410 2026-02-16 17:41:39.355177 | 2026-02-16 17:41:39.355247 | TASK [ensure-tox : Export installed tox_executable path] 2026-02-16 17:41:39.400750 | ubuntu-noble | ok 2026-02-16 17:41:39.408104 | 2026-02-16 17:41:39.408174 | TASK [ensure-tox : Output tox version] 2026-02-16 17:41:39.813387 | 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:41:39.827622 | ubuntu-noble | 4.36.0 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2026-02-16 17:41:39.941396 | ubuntu-noble | ok: Runtime: 0:00:00.265553 2026-02-16 17:41:39.948636 | 2026-02-16 17:41:39.948722 | TASK [ensure-tox : Make global symlink] 2026-02-16 17:41:39.974035 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:39.984892 | 2026-02-16 17:41:39.984944 | PLAY RECAP 2026-02-16 17:41:39.985008 | ubuntu-noble | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-02-16 17:41:39.985031 | 2026-02-16 17:41:40.124596 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master] 2026-02-16 17:41:40.127418 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/tox/pre.yml@main] 2026-02-16 17:41:40.731017 | 2026-02-16 17:41:40.731189 | PLAY [all] 2026-02-16 17:41:40.747578 | 2026-02-16 17:41:40.747692 | TASK [ensure-rust : Use rustup] 2026-02-16 17:41:40.781873 | ubuntu-noble | ok 2026-02-16 17:41:40.790975 | ubuntu-noble | included: /var/lib/zuul/builds/91ee02536e8848e9a68c249dffb57d98/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml 2026-02-16 17:41:40.797280 | 2026-02-16 17:41:40.797355 | TASK [ensure-rust : Install Rust] 2026-02-16 17:41:41.140445 | ubuntu-noble | /bin/bash: line 2: curl: command not found 2026-02-16 17:41:41.334035 | ubuntu-noble | ERROR 2026-02-16 17:41:41.334365 | ubuntu-noble | { 2026-02-16 17:41:41.334431 | ubuntu-noble | "delta": "0:00:00.009269", 2026-02-16 17:41:41.334482 | ubuntu-noble | "end": "2026-02-16 17:41:41.142231", 2026-02-16 17:41:41.334526 | ubuntu-noble | "msg": "non-zero return code", 2026-02-16 17:41:41.334566 | ubuntu-noble | "rc": 127, 2026-02-16 17:41:41.334607 | ubuntu-noble | "start": "2026-02-16 17:41:41.132962" 2026-02-16 17:41:41.334655 | ubuntu-noble | } failure 2026-02-16 17:41:41.337029 | 2026-02-16 17:41:41.337097 | PLAY RECAP 2026-02-16 17:41:41.337149 | ubuntu-noble | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-02-16 17:41:41.337174 | 2026-02-16 17:41:41.458083 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/tox/pre.yml@main] 2026-02-16 17:41:41.460500 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2026-02-16 17:41:42.076027 | 2026-02-16 17:41:42.076176 | PLAY [all] 2026-02-16 17:41:42.088080 | 2026-02-16 17:41:42.088174 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-02-16 17:41:42.133722 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:42.142693 | 2026-02-16 17:41:42.142798 | TASK [fetch-tox-output : Set tox log path for single node] 2026-02-16 17:41:42.187549 | ubuntu-noble | ok 2026-02-16 17:41:42.195819 | 2026-02-16 17:41:42.195909 | TASK [fetch-tox-output : Ensure local tox dir] 2026-02-16 17:41:42.601674 | ubuntu-noble -> localhost | changed 2026-02-16 17:41:42.614002 | 2026-02-16 17:41:42.614096 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-02-16 17:41:42.659850 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:42.669777 | 2026-02-16 17:41:42.669845 | TASK [fetch-tox-output : Set envlist fact] 2026-02-16 17:41:42.712883 | ubuntu-noble | ok 2026-02-16 17:41:42.721367 | 2026-02-16 17:41:42.721438 | TASK [fetch-tox-output : Get tox version] 2026-02-16 17:41:43.266239 | 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:41:43.280253 | ubuntu-noble | 4.36.0 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2026-02-16 17:41:43.768578 | ubuntu-noble | ok: Runtime: 0:00:00.246150 2026-02-16 17:41:43.773977 | 2026-02-16 17:41:43.774046 | TASK [fetch-tox-output : Find all default environments] 2026-02-16 17:41:44.300608 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:44.312536 | 2026-02-16 17:41:44.312681 | TASK [fetch-tox-output : Set envlist fact] 2026-02-16 17:41:44.337860 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:44.347418 | 2026-02-16 17:41:44.347511 | TASK [fetch-tox-output : Find all default environments] 2026-02-16 17:41:44.875451 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:44.885695 | 2026-02-16 17:41:44.885779 | TASK [fetch-tox-output : Set envlist fact] 2026-02-16 17:41:44.910499 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:44.921267 | 2026-02-16 17:41:44.921350 | LOOP [fetch-tox-output : Copy tox logs] 2026-02-16 17:41:44.961093 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:44.981614 | 2026-02-16 17:41:44.981720 | LOOP [fetch-tox-output : Collect tox logs] 2026-02-16 17:41:45.712465 | ubuntu-noble | ok: 2026-02-16 17:41:45.712662 | 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:41:45.712714 | 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:41:45.712746 | ubuntu-noble | rsync: [Receiver] write error: Broken pipe (32) 2026-02-16 17:41:45.712780 | 2026-02-16 17:41:45.728083 | 2026-02-16 17:41:45.728204 | PLAY RECAP 2026-02-16 17:41:45.728259 | ubuntu-noble | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-02-16 17:41:45.728282 | 2026-02-16 17:41:45.846723 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2026-02-16 17:41:45.849001 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-16 17:41:46.491571 | 2026-02-16 17:41:46.491793 | PLAY [all] 2026-02-16 17:41:46.505745 | 2026-02-16 17:41:46.505845 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-02-16 17:41:46.695760 | ubuntu-noble | changed: non-zero return code 2026-02-16 17:41:46.702647 | 2026-02-16 17:41:46.702739 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-02-16 17:41:46.727725 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:46.738598 | 2026-02-16 17:41:46.738693 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-02-16 17:41:46.775164 | 2026-02-16 17:41:46.775335 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-02-16 17:41:46.810190 | 2026-02-16 17:41:46.810356 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-02-16 17:41:46.834927 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:46.845761 | 2026-02-16 17:41:46.845866 | LOOP [fetch-subunit-output : Generate subunit file] 2026-02-16 17:41:46.881517 | 2026-02-16 17:41:46.881767 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-02-16 17:41:46.907106 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:46.919557 | 2026-02-16 17:41:46.919664 | TASK [fetch-subunit-output : Remove the temporary file] 2026-02-16 17:41:46.944479 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:46.954112 | 2026-02-16 17:41:46.954202 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-02-16 17:41:46.978821 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:46.990276 | 2026-02-16 17:41:46.990349 | PLAY RECAP 2026-02-16 17:41:46.990417 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-16 17:41:46.990448 | 2026-02-16 17:41:47.160890 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-16 17:41:47.172083 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 17:41:47.749516 | 2026-02-16 17:41:47.749699 | PLAY [all] 2026-02-16 17:41:47.762680 | 2026-02-16 17:41:47.762765 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-16 17:41:47.808657 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:41:47.818355 | 2026-02-16 17:41:47.818471 | TASK [fetch-output : Set log path for single node] 2026-02-16 17:41:47.854039 | ubuntu-noble | ok 2026-02-16 17:41:47.862671 | 2026-02-16 17:41:47.862768 | LOOP [fetch-output : Ensure local output dirs] 2026-02-16 17:41:48.238675 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/91ee02536e8848e9a68c249dffb57d98/work/logs" 2026-02-16 17:41:48.428819 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/91ee02536e8848e9a68c249dffb57d98/work/artifacts" 2026-02-16 17:41:48.630038 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/91ee02536e8848e9a68c249dffb57d98/work/docs" 2026-02-16 17:41:48.649364 | 2026-02-16 17:41:48.649559 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-16 17:41:49.265302 | ubuntu-noble | changed: .d..t...... ./ 2026-02-16 17:41:49.265592 | ubuntu-noble | changed: All items complete 2026-02-16 17:41:49.265630 | 2026-02-16 17:41:49.721962 | ubuntu-noble | changed: .d..t...... ./ 2026-02-16 17:41:50.174036 | ubuntu-noble | changed: .d..t...... ./ 2026-02-16 17:41:50.197656 | 2026-02-16 17:41:50.197844 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-16 17:41:50.615435 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.007781 2026-02-16 17:41:50.857900 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.005630 2026-02-16 17:41:50.875074 | 2026-02-16 17:41:50.875236 | PLAY [all] 2026-02-16 17:41:50.883230 | 2026-02-16 17:41:50.883312 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-16 17:41:51.350262 | ubuntu-noble | changed 2026-02-16 17:41:51.356397 | 2026-02-16 17:41:51.356449 | PLAY RECAP 2026-02-16 17:41:51.356499 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-16 17:41:51.356520 | 2026-02-16 17:41:51.517948 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 17:41:51.520697 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-16 17:41:52.078493 | 2026-02-16 17:41:52.078622 | PLAY [localhost] 2026-02-16 17:41:52.089021 | 2026-02-16 17:41:52.089097 | TASK [Generate Zuul manifest] 2026-02-16 17:41:52.107119 | localhost | ok 2026-02-16 17:41:52.125305 | 2026-02-16 17:41:52.125388 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-16 17:41:52.466781 | localhost | changed 2026-02-16 17:41:52.477705 | 2026-02-16 17:41:52.477782 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-16 17:41:52.507977 | localhost | ok 2026-02-16 17:41:52.514569 | 2026-02-16 17:41:52.544402 | TASK [Upload logs] 2026-02-16 17:41:52.567059 | localhost | ok 2026-02-16 17:41:52.628163 | 2026-02-16 17:41:52.628267 | TASK [Set zuul-log-path fact] 2026-02-16 17:41:52.652561 | localhost | ok 2026-02-16 17:41:52.664649 | 2026-02-16 17:41:52.664714 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 17:41:52.696159 | localhost | ok 2026-02-16 17:41:52.706026 | 2026-02-16 17:41:52.706092 | TASK [upload-logs : Create log directories] 2026-02-16 17:41:53.194822 | localhost | changed 2026-02-16 17:41:53.201110 | 2026-02-16 17:41:53.201182 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-16 17:41:53.790485 | localhost -> localhost | ok: Runtime: 0:00:00.005702 2026-02-16 17:41:53.795444 | 2026-02-16 17:41:53.795518 | TASK [upload-logs : Upload logs to log server] 2026-02-16 17:41:54.260664 | localhost | Output suppressed because no_log was given 2026-02-16 17:41:54.266479 | 2026-02-16 17:41:54.266577 | LOOP [upload-logs : Compress console log and json output] 2026-02-16 17:41:54.311656 | localhost | skipping: Conditional result was False 2026-02-16 17:41:54.317957 | localhost | skipping: Conditional result was False 2026-02-16 17:41:54.331906 | 2026-02-16 17:41:54.332099 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-16 17:41:54.374639 | localhost | skipping: Conditional result was False 2026-02-16 17:41:54.375032 | 2026-02-16 17:41:54.377898 | localhost | skipping: Conditional result was False 2026-02-16 17:41:54.393686 | 2026-02-16 17:41:54.393853 | LOOP [upload-logs : Upload console log and json output]