2026-02-16 17:42:14.878848 | Job console starting 2026-02-16 17:42:14.891388 | Updating git repos 2026-02-16 17:42:14.940221 | Cloning repos into workspace 2026-02-16 17:42:14.984001 | Restoring repo states 2026-02-16 17:42:14.995032 | Merging changes 2026-02-16 17:42:15.525339 | Checking out repos 2026-02-16 17:42:15.750051 | Preparing playbooks 2026-02-16 17:42:17.196519 | Running Ansible setup 2026-02-16 17:42:20.688704 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 17:42:21.254386 | 2026-02-16 17:42:21.254519 | PLAY [localhost] 2026-02-16 17:42:21.261919 | 2026-02-16 17:42:21.261989 | TASK [Gathering Facts] 2026-02-16 17:42:22.129801 | localhost | ok 2026-02-16 17:42:22.139556 | 2026-02-16 17:42:22.139642 | TASK [Setup log path fact] 2026-02-16 17:42:22.159671 | localhost | ok 2026-02-16 17:42:22.173645 | 2026-02-16 17:42:22.173708 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 17:42:22.202913 | localhost | ok 2026-02-16 17:42:22.210913 | 2026-02-16 17:42:22.210979 | TASK [emit-job-header : Print job information] 2026-02-16 17:42:22.263552 | # Job Information 2026-02-16 17:42:22.263672 | Ansible Version: 2.16.15 2026-02-16 17:42:22.263704 | Job: magnum-cluster-api-tox-functional 2026-02-16 17:42:22.263727 | Pipeline: check 2026-02-16 17:42:22.263747 | Executor: 3a2793d2bd32 2026-02-16 17:42:22.263766 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884 2026-02-16 17:42:22.263788 | Event ID: 2685a480-0b5e-11f1-90f6-a235114455cc 2026-02-16 17:42:22.266587 | 2026-02-16 17:42:22.266649 | LOOP [emit-job-header : Print node information] 2026-02-16 17:42:22.392370 | localhost | ok: 2026-02-16 17:42:22.392619 | localhost | # Node Information 2026-02-16 17:42:22.392661 | localhost | Inventory Hostname: ubuntu-noble 2026-02-16 17:42:22.392693 | localhost | Hostname: np0000155627 2026-02-16 17:42:22.392723 | localhost | Username: zuul 2026-02-16 17:42:22.393090 | localhost | Distro: Ubuntu 24.04 2026-02-16 17:42:22.393146 | localhost | Provider: yul1 2026-02-16 17:42:22.393180 | localhost | Region: ca-ymq-1 2026-02-16 17:42:22.393208 | localhost | Label: ubuntu-noble 2026-02-16 17:42:22.393237 | localhost | Product Name: OpenStack Nova 2026-02-16 17:42:22.393266 | localhost | Interface IP: 199.204.45.240 2026-02-16 17:42:22.408819 | 2026-02-16 17:42:22.409078 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-16 17:42:22.789484 | localhost -> localhost | changed 2026-02-16 17:42:22.800669 | 2026-02-16 17:42:22.800764 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-16 17:42:23.666172 | localhost -> localhost | changed 2026-02-16 17:42:23.675009 | 2026-02-16 17:42:23.675089 | PLAY [all] 2026-02-16 17:42:23.684615 | 2026-02-16 17:42:23.684699 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-16 17:42:23.902355 | ubuntu-noble -> localhost | ok 2026-02-16 17:42:23.912733 | 2026-02-16 17:42:23.913013 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-16 17:42:23.947528 | ubuntu-noble | ok 2026-02-16 17:42:23.962898 | ubuntu-noble | included: /var/lib/zuul/builds/ef3fbcba4fd745c7a4b5d26d5d84d387/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-16 17:42:23.969237 | 2026-02-16 17:42:23.969300 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-16 17:42:25.050818 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2026-02-16 17:42:25.051010 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/ef3fbcba4fd745c7a4b5d26d5d84d387/work/ef3fbcba4fd745c7a4b5d26d5d84d387_id_rsa 2026-02-16 17:42:25.051042 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/ef3fbcba4fd745c7a4b5d26d5d84d387/work/ef3fbcba4fd745c7a4b5d26d5d84d387_id_rsa.pub 2026-02-16 17:42:25.051064 | ubuntu-noble -> localhost | The key fingerprint is: 2026-02-16 17:42:25.051086 | ubuntu-noble -> localhost | SHA256:xoSV3/2ZthqTWpB8jYpzP2Zb7GiYtd0uZHTzlrCtiII zuul-build-sshkey 2026-02-16 17:42:25.051117 | ubuntu-noble -> localhost | The key's randomart image is: 2026-02-16 17:42:25.051141 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2026-02-16 17:42:25.051166 | ubuntu-noble -> localhost | | .. | 2026-02-16 17:42:25.051189 | ubuntu-noble -> localhost | | o. | 2026-02-16 17:42:25.051209 | ubuntu-noble -> localhost | | . .. . . | 2026-02-16 17:42:25.051229 | ubuntu-noble -> localhost | | o o o.=..| 2026-02-16 17:42:25.051249 | ubuntu-noble -> localhost | | S + +++*| 2026-02-16 17:42:25.051269 | ubuntu-noble -> localhost | | . . +o=*+| 2026-02-16 17:42:25.051289 | ubuntu-noble -> localhost | | . o.o=B=+o| 2026-02-16 17:42:25.051308 | ubuntu-noble -> localhost | | E . .o++BBo.| 2026-02-16 17:42:25.051330 | ubuntu-noble -> localhost | | . .+=++.| 2026-02-16 17:42:25.051351 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2026-02-16 17:42:25.051405 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.572669 2026-02-16 17:42:25.057095 | 2026-02-16 17:42:25.057157 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-16 17:42:25.091863 | ubuntu-noble | ok 2026-02-16 17:42:25.101839 | ubuntu-noble | included: /var/lib/zuul/builds/ef3fbcba4fd745c7a4b5d26d5d84d387/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-16 17:42:25.109938 | 2026-02-16 17:42:25.110000 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-16 17:42:25.134804 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:25.144976 | 2026-02-16 17:42:25.145074 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-16 17:42:25.673186 | ubuntu-noble | changed 2026-02-16 17:42:25.679081 | 2026-02-16 17:42:25.679155 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-16 17:42:25.927317 | ubuntu-noble | ok 2026-02-16 17:42:25.934607 | 2026-02-16 17:42:25.934692 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-16 17:42:26.446332 | ubuntu-noble | changed 2026-02-16 17:42:26.453641 | 2026-02-16 17:42:26.453717 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-16 17:42:26.991278 | ubuntu-noble | changed 2026-02-16 17:42:27.161422 | 2026-02-16 17:42:27.161544 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-16 17:42:27.186711 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:27.199162 | 2026-02-16 17:42:27.199260 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-16 17:42:27.569647 | ubuntu-noble -> localhost | changed 2026-02-16 17:42:27.580643 | 2026-02-16 17:42:27.580714 | TASK [add-build-sshkey : Add back temp key] 2026-02-16 17:42:27.867500 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/ef3fbcba4fd745c7a4b5d26d5d84d387/work/ef3fbcba4fd745c7a4b5d26d5d84d387_id_rsa (zuul-build-sshkey) 2026-02-16 17:42:27.867745 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.013562 2026-02-16 17:42:27.877268 | 2026-02-16 17:42:27.877353 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-16 17:42:28.234641 | ubuntu-noble | ok 2026-02-16 17:42:28.241793 | 2026-02-16 17:42:28.241879 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-16 17:42:28.267687 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:28.284202 | 2026-02-16 17:42:28.284290 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-16 17:42:28.647823 | ubuntu-noble | ok 2026-02-16 17:42:28.655801 | 2026-02-16 17:42:28.655868 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-16 17:42:29.664218 | ubuntu-noble | Output suppressed because no_log was given 2026-02-16 17:42:29.677868 | 2026-02-16 17:42:29.677939 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-16 17:42:29.894593 | ubuntu-noble | ok: "logs" 2026-02-16 17:42:29.894901 | ubuntu-noble | ok: All items complete 2026-02-16 17:42:29.894959 | 2026-02-16 17:42:30.123887 | ubuntu-noble | ok: "artifacts" 2026-02-16 17:42:30.265089 | ubuntu-noble | ok: "docs" 2026-02-16 17:42:30.279599 | 2026-02-16 17:42:30.279829 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-16 17:42:30.508316 | ubuntu-noble | changed: "logs" 2026-02-16 17:42:30.694362 | ubuntu-noble | changed: "artifacts" 2026-02-16 17:42:30.881570 | ubuntu-noble | changed: "docs" 2026-02-16 17:42:30.902658 | 2026-02-16 17:42:30.902791 | PLAY RECAP 2026-02-16 17:42:30.902839 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-16 17:42:30.902868 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-16 17:42:30.902889 | 2026-02-16 17:42:31.033069 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 17:42:31.045098 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-16 17:42:31.597808 | 2026-02-16 17:42:31.598464 | PLAY [all] 2026-02-16 17:42:31.610542 | 2026-02-16 17:42:31.610614 | TASK [Install binary dependencies] 2026-02-16 17:42:31.666172 | ubuntu-noble | ok 2026-02-16 17:42:31.688439 | 2026-02-16 17:42:31.688523 | TASK [bindep : Include find tasks] 2026-02-16 17:42:31.723241 | ubuntu-noble | ok 2026-02-16 17:42:31.730891 | ubuntu-noble | included: /var/lib/zuul/builds/ef3fbcba4fd745c7a4b5d26d5d84d387/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-02-16 17:42:31.736941 | 2026-02-16 17:42:31.737026 | TASK [bindep : Look for bindep.txt] 2026-02-16 17:42:32.085110 | ubuntu-noble | ok 2026-02-16 17:42:32.093602 | 2026-02-16 17:42:32.093695 | TASK [bindep : Define bindep_file fact] 2026-02-16 17:42:32.109002 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:32.116655 | 2026-02-16 17:42:32.116745 | TASK [bindep : Look for other-requirements.txt] 2026-02-16 17:42:32.326679 | ubuntu-noble | ok 2026-02-16 17:42:32.332362 | 2026-02-16 17:42:32.332432 | TASK [bindep : Define bindep_file fact] 2026-02-16 17:42:32.357397 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:32.367153 | 2026-02-16 17:42:32.367244 | TASK [bindep : Look for bindep fallback file] 2026-02-16 17:42:32.393112 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:32.404993 | 2026-02-16 17:42:32.405110 | TASK [bindep : Define bindep_file fact] 2026-02-16 17:42:32.440715 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:32.451689 | 2026-02-16 17:42:32.451793 | TASK [bindep : Include bindep tasks] 2026-02-16 17:42:32.486989 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:32.496821 | 2026-02-16 17:42:32.496911 | TASK [bindep : Include install tasks] 2026-02-16 17:42:32.522058 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:32.532711 | 2026-02-16 17:42:32.532778 | LOOP [bindep : Include package tasks] 2026-02-16 17:42:32.597106 | 2026-02-16 17:42:32.636350 | TASK [Run test-setup role] 2026-02-16 17:42:32.664532 | ubuntu-noble | ok 2026-02-16 17:42:32.682711 | 2026-02-16 17:42:32.682859 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-02-16 17:42:32.914359 | ubuntu-noble | ok 2026-02-16 17:42:32.920235 | 2026-02-16 17:42:32.920313 | TASK [test-setup : Run tools/test-setup.sh] 2026-02-16 17:42:33.450104 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:33.462609 | 2026-02-16 17:42:33.462689 | PLAY RECAP 2026-02-16 17:42:33.462748 | ubuntu-noble | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-16 17:42:33.462778 | 2026-02-16 17:42:33.592462 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-16 17:42:33.595417 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master] 2026-02-16 17:42:34.215410 | 2026-02-16 17:42:34.215564 | PLAY [all] 2026-02-16 17:42:34.229883 | 2026-02-16 17:42:34.229962 | TASK [ensure-python : Validate python_version value] 2026-02-16 17:42:34.256720 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:34.266864 | 2026-02-16 17:42:34.266975 | TASK [ensure-python : Install specified version of python interpreter and development files (DEB)] 2026-02-16 17:42:34.292746 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:34.304158 | 2026-02-16 17:42:34.304234 | TASK [ensure-python : Pull in venv package] 2026-02-16 17:42:34.329036 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:34.339930 | 2026-02-16 17:42:34.340040 | TASK [ensure-python : Set default RPM package name] 2026-02-16 17:42:34.365648 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:34.375869 | 2026-02-16 17:42:34.375964 | TASK [ensure-python : Set RPM package name for CentOS/RHEL 9/10] 2026-02-16 17:42:34.400854 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:34.411127 | 2026-02-16 17:42:34.411226 | TASK [ensure-python : Install RPM package] 2026-02-16 17:42:34.436936 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:34.448206 | 2026-02-16 17:42:34.448315 | TASK [ensure-python : Install python using pyenv] 2026-02-16 17:42:34.474091 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:34.484152 | 2026-02-16 17:42:34.484220 | TASK [ensure-python : Activate python using stow] 2026-02-16 17:42:34.508622 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:34.527961 | 2026-02-16 17:42:34.528027 | TASK [Install pip] 2026-02-16 17:42:34.550734 | ubuntu-noble | ok 2026-02-16 17:42:34.573084 | 2026-02-16 17:42:34.573148 | TASK [ensure-pip : Check if pip is installed] 2026-02-16 17:42:35.105696 | ubuntu-noble | ok: Runtime: 0:00:00.006748 2026-02-16 17:42:35.111295 | 2026-02-16 17:42:35.111359 | LOOP [ensure-pip : Install pip from packages] 2026-02-16 17:42:35.146857 | ubuntu-noble | ok: "/var/lib/zuul/builds/ef3fbcba4fd745c7a4b5d26d5d84d387/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/Debian.yaml" 2026-02-16 17:42:35.155563 | ubuntu-noble | included: /var/lib/zuul/builds/ef3fbcba4fd745c7a4b5d26d5d84d387/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/Debian.yaml 2026-02-16 17:42:35.164054 | 2026-02-16 17:42:35.164112 | TASK [ensure-pip : Update package lists] 2026-02-16 17:42:41.871236 | ubuntu-noble | changed 2026-02-16 17:42:41.879071 | 2026-02-16 17:42:41.879144 | TASK [ensure-pip : Install Python 3 pip] 2026-02-16 17:42:46.981311 | ubuntu-noble | changed 2026-02-16 17:42:46.989597 | 2026-02-16 17:42:46.989678 | TASK [ensure-pip : Install Python 2 pip] 2026-02-16 17:42:47.015421 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:47.027828 | 2026-02-16 17:42:47.027933 | TASK [ensure-pip : Ensure setuptools] 2026-02-16 17:42:47.044616 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:47.056809 | 2026-02-16 17:42:47.056916 | TASK [ensure-pip : Check for ensurepip module] 2026-02-16 17:42:47.339263 | ubuntu-noble | usage: python -m ensurepip [-h] [--version] [-v] [-U] [--user] [--root ROOT] 2026-02-16 17:42:47.339453 | ubuntu-noble | [--altinstall] [--default-pip] 2026-02-16 17:42:47.339487 | ubuntu-noble | 2026-02-16 17:42:47.339499 | ubuntu-noble | options: 2026-02-16 17:42:47.339509 | ubuntu-noble | -h, --help show this help message and exit 2026-02-16 17:42:47.339525 | ubuntu-noble | --version Show the version of pip that is bundled with this Python. 2026-02-16 17:42:47.339538 | ubuntu-noble | -v, --verbose Give more output. Option is additive, and can be used up to 3 2026-02-16 17:42:47.339553 | ubuntu-noble | times. 2026-02-16 17:42:47.339563 | ubuntu-noble | -U, --upgrade Upgrade pip and dependencies, even if already installed. 2026-02-16 17:42:47.339571 | ubuntu-noble | --user Install using the user scheme. 2026-02-16 17:42:47.339580 | ubuntu-noble | --root ROOT Install everything relative to this alternate root directory. 2026-02-16 17:42:47.339589 | ubuntu-noble | --altinstall Make an alternate install, installing only the X.Y versioned 2026-02-16 17:42:47.339598 | ubuntu-noble | scripts (Default: pipX, pipX.Y). 2026-02-16 17:42:47.339606 | ubuntu-noble | --default-pip Make a default pip install, installing the unqualified pip in 2026-02-16 17:42:47.339615 | ubuntu-noble | addition to the versioned scripts. 2026-02-16 17:42:47.601412 | ubuntu-noble | ok: Runtime: 0:00:00.059121 2026-02-16 17:42:47.608591 | 2026-02-16 17:42:47.608675 | TASK [ensure-pip : Ensure python3-venv] 2026-02-16 17:42:47.634131 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:47.646855 | 2026-02-16 17:42:47.646926 | TASK [ensure-pip : Install pip from source] 2026-02-16 17:42:47.672406 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:47.730188 | 2026-02-16 17:42:47.730550 | TASK [ensure-pip : Probe for venv python full path] 2026-02-16 17:42:47.958455 | ubuntu-noble | /usr/bin/python3 2026-02-16 17:42:48.272605 | ubuntu-noble | ok: Runtime: 0:00:00.004709 2026-02-16 17:42:48.280419 | 2026-02-16 17:42:48.280508 | TASK [ensure-pip : Set host default] 2026-02-16 17:42:48.338606 | ubuntu-noble | ok 2026-02-16 17:42:48.343894 | 2026-02-16 17:42:48.343967 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2026-02-16 17:42:48.410785 | ubuntu-noble | ok 2026-02-16 17:42:48.421670 | 2026-02-16 17:42:48.421737 | TASK [ensure-tox : Check if tox is installed] 2026-02-16 17:42:48.968795 | ubuntu-noble | ok: Runtime: 0:00:00.004937 2026-02-16 17:42:48.974267 | 2026-02-16 17:42:48.974332 | TASK [ensure-tox : Export preinstalled tox_exectuable] 2026-02-16 17:42:48.999271 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:49.011331 | 2026-02-16 17:42:49.011429 | TASK [ensure-tox : Create local venv] 2026-02-16 17:42:52.551299 | ubuntu-noble | ok: Runtime: 0:00:03.061560 2026-02-16 17:42:52.562915 | 2026-02-16 17:42:52.563085 | TASK [ensure-tox : Install tox to local venv] 2026-02-16 17:42:53.335459 | ubuntu-noble | Collecting tox 2026-02-16 17:42:53.379628 | ubuntu-noble | Downloading tox-4.36.0-py3-none-any.whl.metadata (3.6 kB) 2026-02-16 17:42:53.448123 | ubuntu-noble | Collecting cachetools>=7.0.1 (from tox) 2026-02-16 17:42:53.452293 | ubuntu-noble | Downloading cachetools-7.0.1-py3-none-any.whl.metadata (5.6 kB) 2026-02-16 17:42:53.492797 | ubuntu-noble | Collecting chardet>=5.2 (from tox) 2026-02-16 17:42:53.496277 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl.metadata (3.4 kB) 2026-02-16 17:42:53.546260 | ubuntu-noble | Collecting colorama>=0.4.6 (from tox) 2026-02-16 17:42:53.549642 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl.metadata (17 kB) 2026-02-16 17:42:53.608769 | ubuntu-noble | Collecting filelock>=3.24 (from tox) 2026-02-16 17:42:53.612386 | ubuntu-noble | Downloading filelock-3.24.2-py3-none-any.whl.metadata (2.0 kB) 2026-02-16 17:42:53.665282 | ubuntu-noble | Collecting packaging>=26 (from tox) 2026-02-16 17:42:53.668482 | ubuntu-noble | Downloading packaging-26.0-py3-none-any.whl.metadata (3.3 kB) 2026-02-16 17:42:53.723246 | ubuntu-noble | Collecting platformdirs>=4.9.1 (from tox) 2026-02-16 17:42:53.726652 | ubuntu-noble | Downloading platformdirs-4.9.2-py3-none-any.whl.metadata (4.7 kB) 2026-02-16 17:42:53.773806 | ubuntu-noble | Collecting pluggy>=1.6 (from tox) 2026-02-16 17:42:53.777023 | ubuntu-noble | Downloading pluggy-1.6.0-py3-none-any.whl.metadata (4.8 kB) 2026-02-16 17:42:53.822681 | ubuntu-noble | Collecting pyproject-api>=1.10 (from tox) 2026-02-16 17:42:53.826780 | ubuntu-noble | Downloading pyproject_api-1.10.0-py3-none-any.whl.metadata (2.7 kB) 2026-02-16 17:42:53.942402 | ubuntu-noble | Collecting virtualenv>=20.36.1 (from tox) 2026-02-16 17:42:53.945679 | ubuntu-noble | Downloading virtualenv-20.37.0-py3-none-any.whl.metadata (5.1 kB) 2026-02-16 17:42:54.037544 | ubuntu-noble | Collecting distlib<1,>=0.3.7 (from virtualenv>=20.36.1->tox) 2026-02-16 17:42:54.044646 | ubuntu-noble | Downloading distlib-0.4.0-py2.py3-none-any.whl.metadata (5.2 kB) 2026-02-16 17:42:54.077105 | ubuntu-noble | Downloading tox-4.36.0-py3-none-any.whl (179 kB) 2026-02-16 17:42:54.092993 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 179.1/179.1 kB 15.6 MB/s eta 0:00:00 2026-02-16 17:42:54.097492 | ubuntu-noble | Downloading cachetools-7.0.1-py3-none-any.whl (13 kB) 2026-02-16 17:42:54.111916 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2026-02-16 17:42:54.131036 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 12.4 MB/s eta 0:00:00 2026-02-16 17:42:54.135263 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-02-16 17:42:54.152654 | ubuntu-noble | Downloading filelock-3.24.2-py3-none-any.whl (24 kB) 2026-02-16 17:42:54.166476 | ubuntu-noble | Downloading packaging-26.0-py3-none-any.whl (74 kB) 2026-02-16 17:42:54.180744 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 5.6 MB/s eta 0:00:00 2026-02-16 17:42:54.184948 | ubuntu-noble | Downloading platformdirs-4.9.2-py3-none-any.whl (21 kB) 2026-02-16 17:42:54.199932 | ubuntu-noble | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-02-16 17:42:54.217738 | ubuntu-noble | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-02-16 17:42:54.232037 | ubuntu-noble | Downloading virtualenv-20.37.0-py3-none-any.whl (5.8 MB) 2026-02-16 17:42:54.308850 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.8/5.8 MB 79.6 MB/s eta 0:00:00 2026-02-16 17:42:54.315688 | ubuntu-noble | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-02-16 17:42:54.333708 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 32.5 MB/s eta 0:00:00 2026-02-16 17:42:54.404287 | ubuntu-noble | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2026-02-16 17:42:55.288294 | 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:42:55.628679 | ubuntu-noble | ok: Runtime: 0:00:02.581901 2026-02-16 17:42:55.634510 | 2026-02-16 17:42:55.634576 | TASK [ensure-tox : Export installed tox_executable path] 2026-02-16 17:42:55.674215 | ubuntu-noble | ok 2026-02-16 17:42:55.679715 | 2026-02-16 17:42:55.679779 | TASK [ensure-tox : Output tox version] 2026-02-16 17:42:56.122885 | 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:42:56.137011 | ubuntu-noble | 4.36.0 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2026-02-16 17:42:56.214839 | ubuntu-noble | ok: Runtime: 0:00:00.256656 2026-02-16 17:42:56.221723 | 2026-02-16 17:42:56.221798 | TASK [ensure-tox : Make global symlink] 2026-02-16 17:42:56.246447 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:56.255894 | 2026-02-16 17:42:56.255965 | PLAY RECAP 2026-02-16 17:42:56.256028 | ubuntu-noble | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-02-16 17:42:56.256059 | 2026-02-16 17:42:56.418356 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master] 2026-02-16 17:42:56.420817 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/tox/pre.yml@main] 2026-02-16 17:42:57.071602 | 2026-02-16 17:42:57.071815 | PLAY [all] 2026-02-16 17:42:57.085992 | 2026-02-16 17:42:57.086102 | TASK [ensure-rust : Use rustup] 2026-02-16 17:42:57.120892 | ubuntu-noble | ok 2026-02-16 17:42:57.129811 | ubuntu-noble | included: /var/lib/zuul/builds/ef3fbcba4fd745c7a4b5d26d5d84d387/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml 2026-02-16 17:42:57.136436 | 2026-02-16 17:42:57.136519 | TASK [ensure-rust : Install Rust] 2026-02-16 17:42:57.540460 | ubuntu-noble | /bin/bash: line 2: curl: command not found 2026-02-16 17:42:57.674860 | ubuntu-noble | ERROR 2026-02-16 17:42:57.675131 | ubuntu-noble | { 2026-02-16 17:42:57.675197 | ubuntu-noble | "delta": "0:00:00.006958", 2026-02-16 17:42:57.675249 | ubuntu-noble | "end": "2026-02-16 17:42:57.541472", 2026-02-16 17:42:57.675293 | ubuntu-noble | "msg": "non-zero return code", 2026-02-16 17:42:57.675333 | ubuntu-noble | "rc": 127, 2026-02-16 17:42:57.675374 | ubuntu-noble | "start": "2026-02-16 17:42:57.534514" 2026-02-16 17:42:57.675419 | ubuntu-noble | } failure 2026-02-16 17:42:57.678040 | 2026-02-16 17:42:57.678090 | PLAY RECAP 2026-02-16 17:42:57.678137 | ubuntu-noble | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-02-16 17:42:57.678158 | 2026-02-16 17:42:57.813543 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/tox/pre.yml@main] 2026-02-16 17:42:57.816158 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2026-02-16 17:42:58.431363 | 2026-02-16 17:42:58.431514 | PLAY [all] 2026-02-16 17:42:58.444640 | 2026-02-16 17:42:58.444727 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-02-16 17:42:58.501465 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:58.515084 | 2026-02-16 17:42:58.515868 | TASK [fetch-tox-output : Set tox log path for single node] 2026-02-16 17:42:58.563619 | ubuntu-noble | ok 2026-02-16 17:42:58.581849 | 2026-02-16 17:42:58.582237 | TASK [fetch-tox-output : Ensure local tox dir] 2026-02-16 17:42:58.990641 | ubuntu-noble -> localhost | changed 2026-02-16 17:42:59.002287 | 2026-02-16 17:42:59.002423 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-02-16 17:42:59.048738 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:42:59.057860 | 2026-02-16 17:42:59.057963 | TASK [fetch-tox-output : Set envlist fact] 2026-02-16 17:42:59.091273 | ubuntu-noble | ok 2026-02-16 17:42:59.097660 | 2026-02-16 17:42:59.097726 | TASK [fetch-tox-output : Get tox version] 2026-02-16 17:42:59.681614 | 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:42:59.695656 | ubuntu-noble | 4.36.0 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2026-02-16 17:43:00.139083 | ubuntu-noble | ok: Runtime: 0:00:00.247193 2026-02-16 17:43:00.145862 | 2026-02-16 17:43:00.145954 | TASK [fetch-tox-output : Find all default environments] 2026-02-16 17:43:00.675716 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:43:00.682391 | 2026-02-16 17:43:00.682466 | TASK [fetch-tox-output : Set envlist fact] 2026-02-16 17:43:00.707469 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:43:00.716255 | 2026-02-16 17:43:00.716351 | TASK [fetch-tox-output : Find all default environments] 2026-02-16 17:43:01.247391 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:43:01.257472 | 2026-02-16 17:43:01.257548 | TASK [fetch-tox-output : Set envlist fact] 2026-02-16 17:43:01.282373 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:43:01.292732 | 2026-02-16 17:43:01.292825 | LOOP [fetch-tox-output : Copy tox logs] 2026-02-16 17:43:01.331723 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:43:01.349894 | 2026-02-16 17:43:01.350001 | LOOP [fetch-tox-output : Collect tox logs] 2026-02-16 17:43:02.075302 | ubuntu-noble | ok: 2026-02-16 17:43:02.075564 | ubuntu-noble | rsync: [sender] change_dir "/home/zuul/src/github.com/vexxhost/magnum-cluster-api/.tox/functional/log" failed: No such file or directory (2) 2026-02-16 17:43:02.075633 | 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:43:02.075669 | ubuntu-noble | rsync: [Receiver] write error: Broken pipe (32) 2026-02-16 17:43:02.075708 | 2026-02-16 17:43:02.093795 | 2026-02-16 17:43:02.093904 | PLAY RECAP 2026-02-16 17:43:02.093980 | ubuntu-noble | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-02-16 17:43:02.094010 | 2026-02-16 17:43:02.210320 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2026-02-16 17:43:02.211786 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-16 17:43:02.787131 | 2026-02-16 17:43:02.787297 | PLAY [all] 2026-02-16 17:43:02.799505 | 2026-02-16 17:43:02.799580 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-02-16 17:43:02.977283 | ubuntu-noble | changed: non-zero return code 2026-02-16 17:43:02.984070 | 2026-02-16 17:43:02.984155 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-02-16 17:43:03.007484 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:43:03.018106 | 2026-02-16 17:43:03.018199 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-02-16 17:43:03.054622 | 2026-02-16 17:43:03.054751 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-02-16 17:43:03.090709 | 2026-02-16 17:43:03.090850 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-02-16 17:43:03.104500 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:43:03.114992 | 2026-02-16 17:43:03.115061 | LOOP [fetch-subunit-output : Generate subunit file] 2026-02-16 17:43:03.147734 | 2026-02-16 17:43:03.147868 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-02-16 17:43:03.170204 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:43:03.181408 | 2026-02-16 17:43:03.181475 | TASK [fetch-subunit-output : Remove the temporary file] 2026-02-16 17:43:03.205851 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:43:03.215186 | 2026-02-16 17:43:03.215275 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-02-16 17:43:03.241060 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:43:03.252451 | 2026-02-16 17:43:03.252498 | PLAY RECAP 2026-02-16 17:43:03.252546 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-16 17:43:03.252567 | 2026-02-16 17:43:03.383493 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-16 17:43:03.385891 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 17:43:03.939217 | 2026-02-16 17:43:03.939362 | PLAY [all] 2026-02-16 17:43:03.951186 | 2026-02-16 17:43:03.951274 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-16 17:43:03.986999 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:43:03.997209 | 2026-02-16 17:43:03.997293 | TASK [fetch-output : Set log path for single node] 2026-02-16 17:43:04.038782 | ubuntu-noble | ok 2026-02-16 17:43:04.046169 | 2026-02-16 17:43:04.046234 | LOOP [fetch-output : Ensure local output dirs] 2026-02-16 17:43:04.443935 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/ef3fbcba4fd745c7a4b5d26d5d84d387/work/logs" 2026-02-16 17:43:04.643488 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/ef3fbcba4fd745c7a4b5d26d5d84d387/work/artifacts" 2026-02-16 17:43:04.841799 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/ef3fbcba4fd745c7a4b5d26d5d84d387/work/docs" 2026-02-16 17:43:04.856634 | 2026-02-16 17:43:04.856833 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-16 17:43:05.460450 | ubuntu-noble | changed: .d..t...... ./ 2026-02-16 17:43:05.460699 | ubuntu-noble | changed: All items complete 2026-02-16 17:43:05.460726 | 2026-02-16 17:43:05.902493 | ubuntu-noble | changed: .d..t...... ./ 2026-02-16 17:43:06.368272 | ubuntu-noble | changed: .d..t...... ./ 2026-02-16 17:43:06.395259 | 2026-02-16 17:43:06.395406 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-16 17:43:06.816845 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.007528 2026-02-16 17:43:07.043895 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.008126 2026-02-16 17:43:07.067133 | 2026-02-16 17:43:07.067370 | PLAY [all] 2026-02-16 17:43:07.075162 | 2026-02-16 17:43:07.075225 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-16 17:43:07.525753 | ubuntu-noble | changed 2026-02-16 17:43:07.531520 | 2026-02-16 17:43:07.531570 | PLAY RECAP 2026-02-16 17:43:07.531620 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-16 17:43:07.531641 | 2026-02-16 17:43:07.656916 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 17:43:07.659532 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-16 17:43:08.205888 | 2026-02-16 17:43:08.206016 | PLAY [localhost] 2026-02-16 17:43:08.216551 | 2026-02-16 17:43:08.216633 | TASK [Generate Zuul manifest] 2026-02-16 17:43:08.238585 | localhost | ok 2026-02-16 17:43:08.253476 | 2026-02-16 17:43:08.253552 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-16 17:43:08.570840 | localhost | changed 2026-02-16 17:43:08.583672 | 2026-02-16 17:43:08.583750 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-16 17:43:08.615785 | localhost | ok 2026-02-16 17:43:08.623249 | 2026-02-16 17:43:08.623311 | TASK [Upload logs] 2026-02-16 17:43:08.644862 | localhost | ok 2026-02-16 17:43:08.709264 | 2026-02-16 17:43:08.709398 | TASK [Set zuul-log-path fact] 2026-02-16 17:43:08.731665 | localhost | ok 2026-02-16 17:43:08.746834 | 2026-02-16 17:43:08.746955 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 17:43:08.779645 | localhost | ok 2026-02-16 17:43:08.787237 | 2026-02-16 17:43:08.787315 | TASK [upload-logs : Create log directories] 2026-02-16 17:43:09.217212 | localhost | changed 2026-02-16 17:43:09.223378 | 2026-02-16 17:43:09.223446 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-16 17:43:09.674106 | localhost -> localhost | ok: Runtime: 0:00:00.005908 2026-02-16 17:43:09.685164 | 2026-02-16 17:43:09.685369 | TASK [upload-logs : Upload logs to log server] 2026-02-16 17:43:10.147075 | localhost | Output suppressed because no_log was given 2026-02-16 17:43:10.152605 | 2026-02-16 17:43:10.152671 | LOOP [upload-logs : Compress console log and json output] 2026-02-16 17:43:10.197934 | localhost | skipping: Conditional result was False 2026-02-16 17:43:10.204175 | localhost | skipping: Conditional result was False 2026-02-16 17:43:10.219172 | 2026-02-16 17:43:10.219321 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-16 17:43:10.263386 | localhost | skipping: Conditional result was False 2026-02-16 17:43:10.263776 | 2026-02-16 17:43:10.266744 | localhost | skipping: Conditional result was False 2026-02-16 17:43:10.284389 | 2026-02-16 17:43:10.284558 | LOOP [upload-logs : Upload console log and json output]