2026-02-16 18:29:12.297913 | Job console starting
2026-02-16 18:29:12.305986 | Updating git repos
2026-02-16 18:29:12.335060 | Cloning repos into workspace
2026-02-16 18:29:12.378632 | Restoring repo states
2026-02-16 18:29:12.391094 | Merging changes
2026-02-16 18:29:12.780113 | Checking out repos
2026-02-16 18:29:12.953867 | Preparing playbooks
2026-02-16 18:29:13.940891 | Running Ansible setup
2026-02-16 18:29:17.354791 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 18:29:17.922477 | 
2026-02-16 18:29:17.922611 | PLAY [localhost]
2026-02-16 18:29:17.930061 | 
2026-02-16 18:29:17.930132 | TASK [Gathering Facts]
2026-02-16 18:29:18.770211 | localhost | ok
2026-02-16 18:29:18.779227 | 
2026-02-16 18:29:18.779301 | TASK [Setup log path fact]
2026-02-16 18:29:18.797452 | localhost | ok
2026-02-16 18:29:18.809406 | 
2026-02-16 18:29:18.809468 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 18:29:18.836767 | localhost | ok
2026-02-16 18:29:18.843785 | 
2026-02-16 18:29:18.843852 | TASK [emit-job-header : Print job information]
2026-02-16 18:29:18.885673 | # Job Information
2026-02-16 18:29:18.885887 | Ansible Version: 2.16.15
2026-02-16 18:29:18.885961 | Job: magnum-cluster-api-tox-functional
2026-02-16 18:29:18.886012 | Pipeline: check
2026-02-16 18:29:18.886058 | Executor: 3a2793d2bd32
2026-02-16 18:29:18.886102 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 18:29:18.886154 | Event ID: e6955ee0-0b64-11f1-9362-3d643ff18065
2026-02-16 18:29:18.890852 | 
2026-02-16 18:29:18.890915 | LOOP [emit-job-header : Print node information]
2026-02-16 18:29:18.984194 | localhost | ok:
2026-02-16 18:29:18.984509 | localhost | # Node Information
2026-02-16 18:29:18.984571 | localhost | Inventory Hostname: ubuntu-noble
2026-02-16 18:29:18.984618 | localhost | Hostname: np0000155667
2026-02-16 18:29:18.984662 | localhost | Username: zuul
2026-02-16 18:29:18.984709 | localhost | Distro: Ubuntu 24.04
2026-02-16 18:29:18.984752 | localhost | Provider: yul1
2026-02-16 18:29:18.984794 | localhost | Region: ca-ymq-1
2026-02-16 18:29:18.984835 | localhost | Label: ubuntu-noble
2026-02-16 18:29:18.984876 | localhost | Product Name: OpenStack Nova
2026-02-16 18:29:18.984916 | localhost | Interface IP: 199.204.45.95
2026-02-16 18:29:18.996115 | 
2026-02-16 18:29:18.996994 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 18:29:19.377951 | localhost -> localhost | changed
2026-02-16 18:29:19.385181 | 
2026-02-16 18:29:19.385282 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 18:29:20.201725 | localhost -> localhost | changed
2026-02-16 18:29:20.211189 | 
2026-02-16 18:29:20.211267 | PLAY [all]
2026-02-16 18:29:20.220204 | 
2026-02-16 18:29:20.220267 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 18:29:20.427469 | ubuntu-noble -> localhost | ok
2026-02-16 18:29:20.437372 | 
2026-02-16 18:29:20.437436 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 18:29:20.470025 | ubuntu-noble | ok
2026-02-16 18:29:20.485401 | ubuntu-noble | included: /var/lib/zuul/builds/6cfb294fce2248dca7c675aa7ee1dfd0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 18:29:20.491272 | 
2026-02-16 18:29:20.491329 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 18:29:22.409758 | ubuntu-noble -> localhost | Generating public/private rsa key pair.
2026-02-16 18:29:22.409946 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/6cfb294fce2248dca7c675aa7ee1dfd0/work/6cfb294fce2248dca7c675aa7ee1dfd0_id_rsa
2026-02-16 18:29:22.409976 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/6cfb294fce2248dca7c675aa7ee1dfd0/work/6cfb294fce2248dca7c675aa7ee1dfd0_id_rsa.pub
2026-02-16 18:29:22.409998 | ubuntu-noble -> localhost | The key fingerprint is:
2026-02-16 18:29:22.410020 | ubuntu-noble -> localhost | SHA256:IfrD43wVcK8++xQNHr9ns/dGpY8aKBbPnZ0hfeqgc8o zuul-build-sshkey
2026-02-16 18:29:22.410059 | ubuntu-noble -> localhost | The key's randomart image is:
2026-02-16 18:29:22.410080 | ubuntu-noble -> localhost | +---[RSA 3072]----+
2026-02-16 18:29:22.410107 | ubuntu-noble -> localhost | |                 |
2026-02-16 18:29:22.410128 | ubuntu-noble -> localhost | |        . .      |
2026-02-16 18:29:22.410148 | ubuntu-noble -> localhost | |      . .o .o    |
2026-02-16 18:29:22.410168 | ubuntu-noble -> localhost | |     . . ....*  .|
2026-02-16 18:29:22.410187 | ubuntu-noble -> localhost | |    .   S  o+ =.o|
2026-02-16 18:29:22.410206 | ubuntu-noble -> localhost | |     o   +oo =.*.|
2026-02-16 18:29:22.410225 | ubuntu-noble -> localhost | |      = oo+ * ==o|
2026-02-16 18:29:22.410244 | ubuntu-noble -> localhost | |     o +.o+o.+.o*|
2026-02-16 18:29:22.410266 | ubuntu-noble -> localhost | |      o.  EBo..o+|
2026-02-16 18:29:22.410286 | ubuntu-noble -> localhost | +----[SHA256]-----+
2026-02-16 18:29:22.410334 | ubuntu-noble -> localhost | ok: Runtime: 0:00:01.529074
2026-02-16 18:29:22.416852 | 
2026-02-16 18:29:22.416915 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 18:29:22.451165 | ubuntu-noble | ok
2026-02-16 18:29:22.462075 | ubuntu-noble | included: /var/lib/zuul/builds/6cfb294fce2248dca7c675aa7ee1dfd0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 18:29:22.469791 | 
2026-02-16 18:29:22.469851 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 18:29:22.493912 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:22.504264 | 
2026-02-16 18:29:22.504354 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 18:29:23.010996 | ubuntu-noble | changed
2026-02-16 18:29:23.016570 | 
2026-02-16 18:29:23.016636 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 18:29:23.234730 | ubuntu-noble | ok
2026-02-16 18:29:23.240174 | 
2026-02-16 18:29:23.240240 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 18:29:23.781665 | ubuntu-noble | changed
2026-02-16 18:29:23.787150 | 
2026-02-16 18:29:23.787218 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 18:29:24.327586 | ubuntu-noble | changed
2026-02-16 18:29:24.333872 | 
2026-02-16 18:29:24.333940 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 18:29:24.358588 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:24.367865 | 
2026-02-16 18:29:24.367949 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 18:29:24.707668 | ubuntu-noble -> localhost | changed
2026-02-16 18:29:24.724532 | 
2026-02-16 18:29:24.724622 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 18:29:24.990652 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/6cfb294fce2248dca7c675aa7ee1dfd0/work/6cfb294fce2248dca7c675aa7ee1dfd0_id_rsa (zuul-build-sshkey)
2026-02-16 18:29:24.990904 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.012452
2026-02-16 18:29:24.999607 | 
2026-02-16 18:29:24.999669 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 18:29:25.347977 | ubuntu-noble | ok
2026-02-16 18:29:25.353077 | 
2026-02-16 18:29:25.353142 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 18:29:25.377635 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:25.392629 | 
2026-02-16 18:29:25.392708 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 18:29:25.739393 | ubuntu-noble | ok
2026-02-16 18:29:25.746897 | 
2026-02-16 18:29:25.746966 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 18:29:26.752815 | ubuntu-noble | Output suppressed because no_log was given
2026-02-16 18:29:26.762308 | 
2026-02-16 18:29:26.762374 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 18:29:26.981104 | ubuntu-noble | ok: "logs"
2026-02-16 18:29:26.981578 | ubuntu-noble | ok: All items complete
2026-02-16 18:29:26.981659 | 
2026-02-16 18:29:27.203245 | ubuntu-noble | ok: "artifacts"
2026-02-16 18:29:27.349153 | ubuntu-noble | ok: "docs"
2026-02-16 18:29:27.368936 | 
2026-02-16 18:29:27.369118 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 18:29:27.586047 | ubuntu-noble | changed: "logs"
2026-02-16 18:29:27.805413 | ubuntu-noble | changed: "artifacts"
2026-02-16 18:29:27.999029 | ubuntu-noble | changed: "docs"
2026-02-16 18:29:28.012893 | 
2026-02-16 18:29:28.013053 | PLAY RECAP
2026-02-16 18:29:28.013116 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 18:29:28.013156 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 18:29:28.013185 | 
2026-02-16 18:29:28.129898 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 18:29:28.133154 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-02-16 18:29:28.684903 | 
2026-02-16 18:29:28.685051 | PLAY [all]
2026-02-16 18:29:28.696900 | 
2026-02-16 18:29:28.696992 | TASK [Install binary dependencies]
2026-02-16 18:29:28.746463 | ubuntu-noble | ok
2026-02-16 18:29:28.765077 | 
2026-02-16 18:29:28.765168 | TASK [bindep : Include find tasks]
2026-02-16 18:29:28.794266 | ubuntu-noble | ok
2026-02-16 18:29:28.801663 | ubuntu-noble | included: /var/lib/zuul/builds/6cfb294fce2248dca7c675aa7ee1dfd0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-02-16 18:29:28.807610 | 
2026-02-16 18:29:28.807670 | TASK [bindep : Look for bindep.txt]
2026-02-16 18:29:29.164965 | ubuntu-noble | ok
2026-02-16 18:29:29.171239 | 
2026-02-16 18:29:29.171307 | TASK [bindep : Define bindep_file fact]
2026-02-16 18:29:29.196129 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:29.205901 | 
2026-02-16 18:29:29.205995 | TASK [bindep : Look for other-requirements.txt]
2026-02-16 18:29:29.423215 | ubuntu-noble | ok
2026-02-16 18:29:29.430065 | 
2026-02-16 18:29:29.430142 | TASK [bindep : Define bindep_file fact]
2026-02-16 18:29:29.455824 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:29.467247 | 
2026-02-16 18:29:29.467476 | TASK [bindep : Look for bindep fallback file]
2026-02-16 18:29:29.493128 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:29.506415 | 
2026-02-16 18:29:29.506487 | TASK [bindep : Define bindep_file fact]
2026-02-16 18:29:29.531576 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:29.542784 | 
2026-02-16 18:29:29.542875 | TASK [bindep : Include bindep tasks]
2026-02-16 18:29:29.568410 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:29.577796 | 
2026-02-16 18:29:29.577887 | TASK [bindep : Include install tasks]
2026-02-16 18:29:29.602821 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:29.614522 | 
2026-02-16 18:29:29.614659 | LOOP [bindep : Include package tasks]
2026-02-16 18:29:29.675233 | 
2026-02-16 18:29:29.675379 | TASK [Run test-setup role]
2026-02-16 18:29:29.704498 | ubuntu-noble | ok
2026-02-16 18:29:29.727214 | 
2026-02-16 18:29:29.727299 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-02-16 18:29:29.931378 | ubuntu-noble | ok
2026-02-16 18:29:29.937256 | 
2026-02-16 18:29:29.937320 | TASK [test-setup : Run tools/test-setup.sh]
2026-02-16 18:29:30.466499 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:30.473743 | 
2026-02-16 18:29:30.473791 | PLAY RECAP
2026-02-16 18:29:30.473835 | ubuntu-noble | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-02-16 18:29:30.473857 | 
2026-02-16 18:29:30.580691 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-02-16 18:29:30.582094 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master]
2026-02-16 18:29:31.147207 | 
2026-02-16 18:29:31.147347 | PLAY [all]
2026-02-16 18:29:31.160720 | 
2026-02-16 18:29:31.160804 | TASK [ensure-python : Validate python_version value]
2026-02-16 18:29:31.185803 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:31.195004 | 
2026-02-16 18:29:31.195117 | TASK [ensure-python : Install specified version of python interpreter and development files (DEB)]
2026-02-16 18:29:31.219935 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:31.230900 | 
2026-02-16 18:29:31.230996 | TASK [ensure-python : Pull in venv package]
2026-02-16 18:29:31.255668 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:31.265672 | 
2026-02-16 18:29:31.265763 | TASK [ensure-python : Set default RPM package name]
2026-02-16 18:29:31.290573 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:31.298120 | 
2026-02-16 18:29:31.298209 | TASK [ensure-python : Set RPM package name for CentOS/RHEL 9/10]
2026-02-16 18:29:31.323382 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:31.331445 | 
2026-02-16 18:29:31.331547 | TASK [ensure-python : Install RPM package]
2026-02-16 18:29:31.356839 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:31.364667 | 
2026-02-16 18:29:31.364763 | TASK [ensure-python : Install python using pyenv]
2026-02-16 18:29:31.389902 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:31.400095 | 
2026-02-16 18:29:31.400163 | TASK [ensure-python : Activate python using stow]
2026-02-16 18:29:31.434928 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:31.452469 | 
2026-02-16 18:29:31.452533 | TASK [Install pip]
2026-02-16 18:29:31.473326 | ubuntu-noble | ok
2026-02-16 18:29:31.498022 | 
2026-02-16 18:29:31.498089 | TASK [ensure-pip : Check if pip is installed]
2026-02-16 18:29:32.035396 | ubuntu-noble | ok: Runtime: 0:00:00.006905
2026-02-16 18:29:32.042697 | 
2026-02-16 18:29:32.042760 | LOOP [ensure-pip : Install pip from packages]
2026-02-16 18:29:32.075910 | ubuntu-noble | ok: "/var/lib/zuul/builds/6cfb294fce2248dca7c675aa7ee1dfd0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/Debian.yaml"
2026-02-16 18:29:32.097064 | ubuntu-noble | included: /var/lib/zuul/builds/6cfb294fce2248dca7c675aa7ee1dfd0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/Debian.yaml
2026-02-16 18:29:32.106123 | 
2026-02-16 18:29:32.106190 | TASK [ensure-pip : Update package lists]
2026-02-16 18:29:38.122382 | ubuntu-noble | changed
2026-02-16 18:29:38.127650 | 
2026-02-16 18:29:38.127716 | TASK [ensure-pip : Install Python 3 pip]
2026-02-16 18:29:44.080115 | ubuntu-noble | changed
2026-02-16 18:29:44.086169 | 
2026-02-16 18:29:44.086235 | TASK [ensure-pip : Install Python 2 pip]
2026-02-16 18:29:44.109266 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:44.119882 | 
2026-02-16 18:29:44.119975 | TASK [ensure-pip : Ensure setuptools]
2026-02-16 18:29:44.144774 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:44.155602 | 
2026-02-16 18:29:44.155689 | TASK [ensure-pip : Check for ensurepip module]
2026-02-16 18:29:44.433144 | ubuntu-noble | usage: python -m ensurepip [-h] [--version] [-v] [-U] [--user] [--root ROOT]
2026-02-16 18:29:44.433233 | ubuntu-noble |                            [--altinstall] [--default-pip]
2026-02-16 18:29:44.433247 | ubuntu-noble |
2026-02-16 18:29:44.433258 | ubuntu-noble | options:
2026-02-16 18:29:44.433267 | ubuntu-noble |   -h, --help     show this help message and exit
2026-02-16 18:29:44.433282 | ubuntu-noble |   --version      Show the version of pip that is bundled with this Python.
2026-02-16 18:29:44.433294 | ubuntu-noble |   -v, --verbose  Give more output. Option is additive, and can be used up to 3
2026-02-16 18:29:44.433308 | ubuntu-noble |                  times.
2026-02-16 18:29:44.433318 | ubuntu-noble |   -U, --upgrade  Upgrade pip and dependencies, even if already installed.
2026-02-16 18:29:44.433326 | ubuntu-noble |   --user         Install using the user scheme.
2026-02-16 18:29:44.433338 | ubuntu-noble |   --root ROOT    Install everything relative to this alternate root directory.
2026-02-16 18:29:44.433347 | ubuntu-noble |   --altinstall   Make an alternate install, installing only the X.Y versioned
2026-02-16 18:29:44.433356 | ubuntu-noble |                  scripts (Default: pipX, pipX.Y).
2026-02-16 18:29:44.433364 | ubuntu-noble |   --default-pip  Make a default pip install, installing the unqualified pip in
2026-02-16 18:29:44.433372 | ubuntu-noble |                  addition to the versioned scripts.
2026-02-16 18:29:44.695922 | ubuntu-noble | ok: Runtime: 0:00:00.058818
2026-02-16 18:29:44.702356 | 
2026-02-16 18:29:44.702421 | TASK [ensure-pip : Ensure python3-venv]
2026-02-16 18:29:44.727975 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:44.739026 | 
2026-02-16 18:29:44.739113 | TASK [ensure-pip : Install pip from source]
2026-02-16 18:29:44.764156 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:44.810243 | 
2026-02-16 18:29:44.810403 | TASK [ensure-pip : Probe for venv python full path]
2026-02-16 18:29:45.038697 | ubuntu-noble | /usr/bin/python3
2026-02-16 18:29:45.347257 | ubuntu-noble | ok: Runtime: 0:00:00.004523
2026-02-16 18:29:45.354746 | 
2026-02-16 18:29:45.354837 | TASK [ensure-pip : Set host default]
2026-02-16 18:29:45.410249 | ubuntu-noble | ok
2026-02-16 18:29:45.415975 | 
2026-02-16 18:29:45.416038 | TASK [ensure-pip : Set ensure_pip_virtualenv_command]
2026-02-16 18:29:45.473903 | ubuntu-noble | ok
2026-02-16 18:29:45.484758 | 
2026-02-16 18:29:45.484826 | TASK [ensure-tox : Check if tox is installed]
2026-02-16 18:29:46.019682 | ubuntu-noble | ok: Runtime: 0:00:00.004793
2026-02-16 18:29:46.026895 | 
2026-02-16 18:29:46.026982 | TASK [ensure-tox : Export preinstalled tox_exectuable]
2026-02-16 18:29:46.052109 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:46.063051 | 
2026-02-16 18:29:46.063145 | TASK [ensure-tox : Create local venv]
2026-02-16 18:29:49.602649 | ubuntu-noble | ok: Runtime: 0:00:03.155033
2026-02-16 18:29:49.610054 | 
2026-02-16 18:29:49.610142 | TASK [ensure-tox : Install tox to local venv]
2026-02-16 18:29:50.348813 | ubuntu-noble | Collecting tox
2026-02-16 18:29:50.414482 | ubuntu-noble |   Downloading tox-4.36.0-py3-none-any.whl.metadata (3.6 kB)
2026-02-16 18:29:50.470564 | ubuntu-noble | Collecting cachetools>=7.0.1 (from tox)
2026-02-16 18:29:50.482589 | ubuntu-noble |   Downloading cachetools-7.0.1-py3-none-any.whl.metadata (5.6 kB)
2026-02-16 18:29:50.517824 | ubuntu-noble | Collecting chardet>=5.2 (from tox)
2026-02-16 18:29:50.527104 | ubuntu-noble |   Downloading chardet-5.2.0-py3-none-any.whl.metadata (3.4 kB)
2026-02-16 18:29:50.570455 | ubuntu-noble | Collecting colorama>=0.4.6 (from tox)
2026-02-16 18:29:50.579929 | ubuntu-noble |   Downloading colorama-0.4.6-py2.py3-none-any.whl.metadata (17 kB)
2026-02-16 18:29:50.639816 | ubuntu-noble | Collecting filelock>=3.24 (from tox)
2026-02-16 18:29:50.648427 | ubuntu-noble |   Downloading filelock-3.24.2-py3-none-any.whl.metadata (2.0 kB)
2026-02-16 18:29:50.698812 | ubuntu-noble | Collecting packaging>=26 (from tox)
2026-02-16 18:29:50.707589 | ubuntu-noble |   Downloading packaging-26.0-py3-none-any.whl.metadata (3.3 kB)
2026-02-16 18:29:50.764674 | ubuntu-noble | Collecting platformdirs>=4.9.1 (from tox)
2026-02-16 18:29:50.773480 | ubuntu-noble |   Downloading platformdirs-4.9.2-py3-none-any.whl.metadata (4.7 kB)
2026-02-16 18:29:50.815661 | ubuntu-noble | Collecting pluggy>=1.6 (from tox)
2026-02-16 18:29:50.824467 | ubuntu-noble |   Downloading pluggy-1.6.0-py3-none-any.whl.metadata (4.8 kB)
2026-02-16 18:29:50.862120 | ubuntu-noble | Collecting pyproject-api>=1.10 (from tox)
2026-02-16 18:29:50.872335 | ubuntu-noble |   Downloading pyproject_api-1.10.0-py3-none-any.whl.metadata (2.7 kB)
2026-02-16 18:29:50.988713 | ubuntu-noble | Collecting virtualenv>=20.36.1 (from tox)
2026-02-16 18:29:50.998603 | ubuntu-noble |   Downloading virtualenv-20.37.0-py3-none-any.whl.metadata (5.1 kB)
2026-02-16 18:29:51.089459 | ubuntu-noble | Collecting distlib<1,>=0.3.7 (from virtualenv>=20.36.1->tox)
2026-02-16 18:29:51.098428 | ubuntu-noble |   Downloading distlib-0.4.0-py2.py3-none-any.whl.metadata (5.2 kB)
2026-02-16 18:29:51.141039 | ubuntu-noble | Downloading tox-4.36.0-py3-none-any.whl (179 kB)
2026-02-16 18:29:51.172735 | ubuntu-noble |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 179.1/179.1 kB 6.1 MB/s eta 0:00:00
2026-02-16 18:29:51.182500 | ubuntu-noble | Downloading cachetools-7.0.1-py3-none-any.whl (13 kB)
2026-02-16 18:29:51.204862 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl (199 kB)
2026-02-16 18:29:51.219634 | ubuntu-noble |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 16.0 MB/s eta 0:00:00
2026-02-16 18:29:51.229645 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB)
2026-02-16 18:29:51.248462 | ubuntu-noble | Downloading filelock-3.24.2-py3-none-any.whl (24 kB)
2026-02-16 18:29:51.267970 | ubuntu-noble | Downloading packaging-26.0-py3-none-any.whl (74 kB)
2026-02-16 18:29:51.281787 | ubuntu-noble |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 5.5 MB/s eta 0:00:00
2026-02-16 18:29:51.291414 | ubuntu-noble | Downloading platformdirs-4.9.2-py3-none-any.whl (21 kB)
2026-02-16 18:29:51.309588 | ubuntu-noble | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB)
2026-02-16 18:29:51.328158 | ubuntu-noble | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB)
2026-02-16 18:29:51.348839 | ubuntu-noble | Downloading virtualenv-20.37.0-py3-none-any.whl (5.8 MB)
2026-02-16 18:29:51.516844 | ubuntu-noble |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.8/5.8 MB 35.4 MB/s eta 0:00:00
2026-02-16 18:29:51.526982 | ubuntu-noble | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB)
2026-02-16 18:29:51.550811 | ubuntu-noble |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 22.9 MB/s eta 0:00:00
2026-02-16 18:29:51.621616 | ubuntu-noble | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox
2026-02-16 18:29:52.459103 | 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 18:29:52.651089 | ubuntu-noble | ok: Runtime: 0:00:02.715609
2026-02-16 18:29:52.660051 | 
2026-02-16 18:29:52.660170 | TASK [ensure-tox : Export installed tox_executable path]
2026-02-16 18:29:52.697805 | ubuntu-noble | ok
2026-02-16 18:29:52.702711 | 
2026-02-16 18:29:52.702776 | TASK [ensure-tox : Output tox version]
2026-02-16 18:29:53.145900 | 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 18:29:53.159205 | ubuntu-noble | 4.36.0 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py
2026-02-16 18:29:53.239804 | ubuntu-noble | ok: Runtime: 0:00:00.263043
2026-02-16 18:29:53.245318 | 
2026-02-16 18:29:53.245388 | TASK [ensure-tox : Make global symlink]
2026-02-16 18:29:53.270497 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:53.284639 | 
2026-02-16 18:29:53.284733 | PLAY RECAP
2026-02-16 18:29:53.284780 | ubuntu-noble | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0
2026-02-16 18:29:53.284802 | 
2026-02-16 18:29:53.419931 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master]
2026-02-16 18:29:53.422415 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/tox/pre.yml@main]
2026-02-16 18:29:53.991829 | 
2026-02-16 18:29:53.991976 | PLAY [all]
2026-02-16 18:29:54.004306 | 
2026-02-16 18:29:54.004416 | TASK [ensure-rust : Use rustup]
2026-02-16 18:29:54.038863 | ubuntu-noble | ok
2026-02-16 18:29:54.048399 | ubuntu-noble | included: /var/lib/zuul/builds/6cfb294fce2248dca7c675aa7ee1dfd0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 18:29:54.054703 | 
2026-02-16 18:29:54.054778 | TASK [ensure-rust : Install Rust]
2026-02-16 18:29:54.433605 | ubuntu-noble | /bin/bash: line 2: curl: command not found
2026-02-16 18:29:54.593665 | ubuntu-noble | ERROR
2026-02-16 18:29:54.593933 | ubuntu-noble | {
2026-02-16 18:29:54.593973 | ubuntu-noble |   "delta": "0:00:00.009698",
2026-02-16 18:29:54.594005 | ubuntu-noble |   "end": "2026-02-16 18:29:54.435212",
2026-02-16 18:29:54.594032 | ubuntu-noble |   "msg": "non-zero return code",
2026-02-16 18:29:54.594058 | ubuntu-noble |   "rc": 127,
2026-02-16 18:29:54.594083 | ubuntu-noble |   "start": "2026-02-16 18:29:54.425514"
2026-02-16 18:29:54.594111 | ubuntu-noble | }
failure
2026-02-16 18:29:54.596078 | 
2026-02-16 18:29:54.596141 | PLAY RECAP
2026-02-16 18:29:54.596201 | ubuntu-noble | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 18:29:54.596231 | 
2026-02-16 18:29:54.718974 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/tox/pre.yml@main]
2026-02-16 18:29:54.722026 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master]
2026-02-16 18:29:55.321903 | 
2026-02-16 18:29:55.322042 | PLAY [all]
2026-02-16 18:29:55.335503 | 
2026-02-16 18:29:55.335595 | TASK [fetch-tox-output : Set tox log path for multiple nodes]
2026-02-16 18:29:55.371472 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:55.382602 | 
2026-02-16 18:29:55.382713 | TASK [fetch-tox-output : Set tox log path for single node]
2026-02-16 18:29:55.416433 | ubuntu-noble | ok
2026-02-16 18:29:55.422886 | 
2026-02-16 18:29:55.422953 | TASK [fetch-tox-output : Ensure local tox dir]
2026-02-16 18:29:55.790405 | ubuntu-noble -> localhost | changed
2026-02-16 18:29:55.801102 | 
2026-02-16 18:29:55.801209 | TASK [fetch-tox-output : Ensure zuul-output tox dir]
2026-02-16 18:29:55.846644 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:55.857617 | 
2026-02-16 18:29:55.857711 | TASK [fetch-tox-output : Set envlist fact]
2026-02-16 18:29:55.893475 | ubuntu-noble | ok
2026-02-16 18:29:55.901694 | 
2026-02-16 18:29:55.901825 | TASK [fetch-tox-output : Get tox version]
2026-02-16 18:29:56.489958 | 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 18:29:56.504219 | ubuntu-noble | 4.36.0 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py
2026-02-16 18:29:56.944109 | ubuntu-noble | ok: Runtime: 0:00:00.270940
2026-02-16 18:29:56.952725 | 
2026-02-16 18:29:56.952792 | TASK [fetch-tox-output : Find all default environments]
2026-02-16 18:29:57.481642 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:57.494662 | 
2026-02-16 18:29:57.494783 | TASK [fetch-tox-output : Set envlist fact]
2026-02-16 18:29:57.520416 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:57.529200 | 
2026-02-16 18:29:57.529307 | TASK [fetch-tox-output : Find all default environments]
2026-02-16 18:29:58.065168 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:58.076914 | 
2026-02-16 18:29:58.077038 | TASK [fetch-tox-output : Set envlist fact]
2026-02-16 18:29:58.101730 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:58.108165 | 
2026-02-16 18:29:58.108235 | LOOP [fetch-tox-output : Copy tox logs]
2026-02-16 18:29:58.148866 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:58.160384 | 
2026-02-16 18:29:58.160484 | LOOP [fetch-tox-output : Collect tox logs]
2026-02-16 18:29:58.852734 | ubuntu-noble | ok:
2026-02-16 18:29:58.853096 | 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 18:29:58.853171 | 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 18:29:58.853204 | ubuntu-noble | rsync: [Receiver] write error: Broken pipe (32)
2026-02-16 18:29:58.853240 | 
2026-02-16 18:29:58.875113 | 
2026-02-16 18:29:58.875256 | PLAY RECAP
2026-02-16 18:29:58.875323 | ubuntu-noble | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0
2026-02-16 18:29:58.875353 | 
2026-02-16 18:29:58.994086 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master]
2026-02-16 18:29:58.996467 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-02-16 18:29:59.587673 | 
2026-02-16 18:29:59.587843 | PLAY [all]
2026-02-16 18:29:59.600563 | 
2026-02-16 18:29:59.600646 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-02-16 18:29:59.787676 | ubuntu-noble | changed: non-zero return code
2026-02-16 18:29:59.795610 | 
2026-02-16 18:29:59.795728 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-02-16 18:29:59.821633 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:59.832211 | 
2026-02-16 18:29:59.832443 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-02-16 18:29:59.869800 | 
2026-02-16 18:29:59.869988 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-02-16 18:29:59.906726 | 
2026-02-16 18:29:59.906891 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-02-16 18:29:59.932389 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:29:59.942976 | 
2026-02-16 18:29:59.943069 | LOOP [fetch-subunit-output : Generate subunit file]
2026-02-16 18:29:59.977523 | 
2026-02-16 18:29:59.977693 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-02-16 18:30:00.003010 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:30:00.015910 | 
2026-02-16 18:30:00.015988 | TASK [fetch-subunit-output : Remove the temporary file]
2026-02-16 18:30:00.041294 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:30:00.053425 | 
2026-02-16 18:30:00.053505 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-02-16 18:30:00.078555 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:30:00.089486 | 
2026-02-16 18:30:00.089541 | PLAY RECAP
2026-02-16 18:30:00.089591 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-02-16 18:30:00.089615 | 
2026-02-16 18:30:00.234532 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-02-16 18:30:00.237028 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 18:30:00.797986 | 
2026-02-16 18:30:00.798121 | PLAY [all]
2026-02-16 18:30:00.809545 | 
2026-02-16 18:30:00.809622 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 18:30:00.846471 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 18:30:00.855053 | 
2026-02-16 18:30:00.855195 | TASK [fetch-output : Set log path for single node]
2026-02-16 18:30:00.897983 | ubuntu-noble | ok
2026-02-16 18:30:00.906900 | 
2026-02-16 18:30:00.907042 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 18:30:01.297187 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/6cfb294fce2248dca7c675aa7ee1dfd0/work/logs"
2026-02-16 18:30:01.492683 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/6cfb294fce2248dca7c675aa7ee1dfd0/work/artifacts"
2026-02-16 18:30:01.707926 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/6cfb294fce2248dca7c675aa7ee1dfd0/work/docs"
2026-02-16 18:30:01.723298 | 
2026-02-16 18:30:01.723404 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 18:30:02.336314 | ubuntu-noble | changed: .d..t...... ./
2026-02-16 18:30:02.336546 | ubuntu-noble | changed: All items complete
2026-02-16 18:30:02.336921 | 
2026-02-16 18:30:02.793756 | ubuntu-noble | changed: .d..t...... ./
2026-02-16 18:30:03.244113 | ubuntu-noble | changed: .d..t...... ./
2026-02-16 18:30:03.272402 | 
2026-02-16 18:30:03.272570 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 18:30:03.686696 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.007326
2026-02-16 18:30:03.903997 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.006934
2026-02-16 18:30:03.922506 | 
2026-02-16 18:30:03.922581 | PLAY [all]
2026-02-16 18:30:03.930748 | 
2026-02-16 18:30:03.930865 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 18:30:04.400017 | ubuntu-noble | changed
2026-02-16 18:30:04.405810 | 
2026-02-16 18:30:04.405858 | PLAY RECAP
2026-02-16 18:30:04.405900 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 18:30:04.405922 | 
2026-02-16 18:30:04.521204 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 18:30:04.523928 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 18:30:05.075428 | 
2026-02-16 18:30:05.075596 | PLAY [localhost]
2026-02-16 18:30:05.091385 | 
2026-02-16 18:30:05.091463 | TASK [Generate Zuul manifest]
2026-02-16 18:30:05.111243 | localhost | ok
2026-02-16 18:30:05.128328 | 
2026-02-16 18:30:05.128402 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 18:30:05.432935 | localhost | changed
2026-02-16 18:30:05.446255 | 
2026-02-16 18:30:05.446330 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 18:30:05.478600 | localhost | ok
2026-02-16 18:30:05.487366 | 
2026-02-16 18:30:05.487447 | TASK [Upload logs]
2026-02-16 18:30:05.509252 | localhost | ok
2026-02-16 18:30:05.567619 | 
2026-02-16 18:30:05.567722 | TASK [Set zuul-log-path fact]
2026-02-16 18:30:05.589907 | localhost | ok
2026-02-16 18:30:05.600528 | 
2026-02-16 18:30:05.600594 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 18:30:05.631929 | localhost | ok
2026-02-16 18:30:05.640750 | 
2026-02-16 18:30:05.640833 | TASK [upload-logs : Create log directories]
2026-02-16 18:30:06.036994 | localhost | changed
2026-02-16 18:30:06.042403 | 
2026-02-16 18:30:06.042468 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 18:30:06.451641 | localhost -> localhost | ok: Runtime: 0:00:00.005314
2026-02-16 18:30:06.458264 | 
2026-02-16 18:30:06.458360 | TASK [upload-logs : Upload logs to log server]
2026-02-16 18:30:06.907043 | localhost | Output suppressed because no_log was given
2026-02-16 18:30:06.912091 | 
2026-02-16 18:30:06.912182 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 18:30:06.957134 | localhost | skipping: Conditional result was False
2026-02-16 18:30:06.963165 | localhost | skipping: Conditional result was False
2026-02-16 18:30:06.976045 | 
2026-02-16 18:30:06.976172 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 18:30:07.017391 | localhost | skipping: Conditional result was False
2026-02-16 18:30:07.017760 | 
2026-02-16 18:30:07.020808 | localhost | skipping: Conditional result was False
2026-02-16 18:30:07.033610 | 
2026-02-16 18:30:07.033744 | LOOP [upload-logs : Upload console log and json output]