2026-02-16 19:17:36.280482 | Job console starting 2026-02-16 19:17:36.292551 | Updating git repos 2026-02-16 19:17:36.328467 | Cloning repos into workspace 2026-02-16 19:17:36.382758 | Restoring repo states 2026-02-16 19:17:36.398356 | Merging changes 2026-02-16 19:17:36.865305 | Checking out repos 2026-02-16 19:17:37.160670 | Preparing playbooks 2026-02-16 19:17:41.143806 | Running Ansible setup 2026-02-16 19:17:45.060802 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 19:17:45.682169 | 2026-02-16 19:17:45.682329 | PLAY [localhost] 2026-02-16 19:17:45.690762 | 2026-02-16 19:17:45.690877 | TASK [Gathering Facts] 2026-02-16 19:17:46.663787 | localhost | ok 2026-02-16 19:17:46.674309 | 2026-02-16 19:17:46.674504 | TASK [Setup log path fact] 2026-02-16 19:17:46.694406 | localhost | ok 2026-02-16 19:17:46.709804 | 2026-02-16 19:17:46.709950 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 19:17:46.740578 | localhost | ok 2026-02-16 19:17:46.753282 | 2026-02-16 19:17:46.753495 | TASK [emit-job-header : Print job information] 2026-02-16 19:17:46.798749 | # Job Information 2026-02-16 19:17:46.799505 | Ansible Version: 2.16.15 2026-02-16 19:17:46.799560 | Job: magnum-cluster-api-tox-unit 2026-02-16 19:17:46.799585 | Pipeline: check 2026-02-16 19:17:46.799606 | Executor: 3a2793d2bd32 2026-02-16 19:17:46.799626 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884 2026-02-16 19:17:46.799651 | Event ID: 036266b0-0b6c-11f1-9c94-e50e60db5a7d 2026-02-16 19:17:46.803195 | 2026-02-16 19:17:46.803324 | LOOP [emit-job-header : Print node information] 2026-02-16 19:17:46.931385 | localhost | ok: 2026-02-16 19:17:46.931704 | localhost | # Node Information 2026-02-16 19:17:46.931761 | localhost | Inventory Hostname: ubuntu-noble 2026-02-16 19:17:46.931794 | localhost | Hostname: np0000155670 2026-02-16 19:17:46.931823 | localhost | Username: zuul 2026-02-16 19:17:46.931855 | localhost | Distro: Ubuntu 24.04 2026-02-16 19:17:46.931882 | localhost | Provider: yul1 2026-02-16 19:17:46.931909 | localhost | Region: ca-ymq-1 2026-02-16 19:17:46.931935 | localhost | Label: ubuntu-noble 2026-02-16 19:17:46.931967 | localhost | Product Name: OpenStack Nova 2026-02-16 19:17:46.931999 | localhost | Interface IP: 199.204.45.74 2026-02-16 19:17:46.953367 | 2026-02-16 19:17:46.953579 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-16 19:17:47.590092 | localhost -> localhost | changed 2026-02-16 19:17:47.600933 | 2026-02-16 19:17:47.601066 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-16 19:17:48.753400 | localhost -> localhost | changed 2026-02-16 19:17:48.763472 | 2026-02-16 19:17:48.763619 | PLAY [all] 2026-02-16 19:17:48.775951 | 2026-02-16 19:17:48.776085 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-16 19:17:49.066295 | ubuntu-noble -> localhost | ok 2026-02-16 19:17:49.076355 | 2026-02-16 19:17:49.076528 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-16 19:17:49.110712 | ubuntu-noble | ok 2026-02-16 19:17:49.134773 | ubuntu-noble | included: /var/lib/zuul/builds/b5bb2b0fbbb7417ea8f9b162cf6c8ba4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-16 19:17:49.147388 | 2026-02-16 19:17:49.147577 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-16 19:17:50.268502 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2026-02-16 19:17:50.268717 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/b5bb2b0fbbb7417ea8f9b162cf6c8ba4/work/b5bb2b0fbbb7417ea8f9b162cf6c8ba4_id_rsa 2026-02-16 19:17:50.268755 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/b5bb2b0fbbb7417ea8f9b162cf6c8ba4/work/b5bb2b0fbbb7417ea8f9b162cf6c8ba4_id_rsa.pub 2026-02-16 19:17:50.268786 | ubuntu-noble -> localhost | The key fingerprint is: 2026-02-16 19:17:50.268815 | ubuntu-noble -> localhost | SHA256:kT5UwmNhwrhNaJCgkq8YL2KZ3E3tj/AZlnZWZaJsTqU zuul-build-sshkey 2026-02-16 19:17:50.268855 | ubuntu-noble -> localhost | The key's randomart image is: 2026-02-16 19:17:50.268884 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2026-02-16 19:17:50.268915 | ubuntu-noble -> localhost | |...o +..+.. | 2026-02-16 19:17:50.268944 | ubuntu-noble -> localhost | |... + oo++ | 2026-02-16 19:17:50.268991 | ubuntu-noble -> localhost | |+ . + .+. o o | 2026-02-16 19:17:50.269020 | ubuntu-noble -> localhost | |.. . oo..+ + | 2026-02-16 19:17:50.269047 | ubuntu-noble -> localhost | |. . . .SE . | 2026-02-16 19:17:50.269073 | ubuntu-noble -> localhost | |o++ o . =.. | 2026-02-16 19:17:50.269099 | ubuntu-noble -> localhost | |==.. o * + | 2026-02-16 19:17:50.269125 | ubuntu-noble -> localhost | |o. = B | 2026-02-16 19:17:50.269153 | ubuntu-noble -> localhost | | + . | 2026-02-16 19:17:50.269180 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2026-02-16 19:17:50.269257 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.622330 2026-02-16 19:17:50.277663 | 2026-02-16 19:17:50.277778 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-16 19:17:50.311860 | ubuntu-noble | ok 2026-02-16 19:17:50.332110 | ubuntu-noble | included: /var/lib/zuul/builds/b5bb2b0fbbb7417ea8f9b162cf6c8ba4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-16 19:17:50.345191 | 2026-02-16 19:17:50.405184 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-16 19:17:50.433944 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:17:50.445133 | 2026-02-16 19:17:50.445244 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-16 19:17:50.998141 | ubuntu-noble | changed 2026-02-16 19:17:51.004216 | 2026-02-16 19:17:51.004293 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-16 19:17:51.222205 | ubuntu-noble | ok 2026-02-16 19:17:51.354496 | 2026-02-16 19:17:51.354647 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-16 19:17:51.900718 | ubuntu-noble | changed 2026-02-16 19:17:51.906855 | 2026-02-16 19:17:51.906930 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-16 19:17:52.475617 | ubuntu-noble | changed 2026-02-16 19:17:52.485846 | 2026-02-16 19:17:52.486084 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-16 19:17:52.512554 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:17:52.524200 | 2026-02-16 19:17:52.524408 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-16 19:17:52.927443 | ubuntu-noble -> localhost | changed 2026-02-16 19:17:52.943711 | 2026-02-16 19:17:52.943885 | TASK [add-build-sshkey : Add back temp key] 2026-02-16 19:17:53.280144 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/b5bb2b0fbbb7417ea8f9b162cf6c8ba4/work/b5bb2b0fbbb7417ea8f9b162cf6c8ba4_id_rsa (zuul-build-sshkey) 2026-02-16 19:17:53.280486 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.016404 2026-02-16 19:17:53.295160 | 2026-02-16 19:17:53.295414 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-16 19:17:53.676027 | ubuntu-noble | ok 2026-02-16 19:17:53.792586 | 2026-02-16 19:17:53.792818 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-16 19:17:53.818192 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:17:53.836461 | 2026-02-16 19:17:53.836600 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-16 19:17:54.226889 | ubuntu-noble | ok 2026-02-16 19:17:54.238753 | 2026-02-16 19:17:54.239070 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-16 19:17:55.357985 | ubuntu-noble | Output suppressed because no_log was given 2026-02-16 19:17:55.375140 | 2026-02-16 19:17:55.375350 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-16 19:17:55.601813 | ubuntu-noble | ok: "logs" 2026-02-16 19:17:55.602133 | ubuntu-noble | ok: All items complete 2026-02-16 19:17:55.602264 | 2026-02-16 19:17:55.867755 | ubuntu-noble | ok: "artifacts" 2026-02-16 19:17:56.000588 | ubuntu-noble | ok: "docs" 2026-02-16 19:17:56.018189 | 2026-02-16 19:17:56.018776 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-16 19:17:56.240179 | ubuntu-noble | changed: "logs" 2026-02-16 19:17:56.437689 | ubuntu-noble | changed: "artifacts" 2026-02-16 19:17:56.628485 | ubuntu-noble | changed: "docs" 2026-02-16 19:17:56.642405 | 2026-02-16 19:17:56.642537 | PLAY RECAP 2026-02-16 19:17:56.642585 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-16 19:17:56.642614 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-16 19:17:56.642636 | 2026-02-16 19:17:56.788619 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 19:17:56.802340 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-16 19:17:57.426899 | 2026-02-16 19:17:57.427372 | PLAY [all] 2026-02-16 19:17:57.441242 | 2026-02-16 19:17:57.441416 | TASK [Install binary dependencies] 2026-02-16 19:17:57.494528 | ubuntu-noble | ok 2026-02-16 19:17:57.516265 | 2026-02-16 19:17:57.516436 | TASK [bindep : Include find tasks] 2026-02-16 19:17:57.550830 | ubuntu-noble | ok 2026-02-16 19:17:57.566501 | ubuntu-noble | included: /var/lib/zuul/builds/b5bb2b0fbbb7417ea8f9b162cf6c8ba4/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-02-16 19:17:57.638040 | 2026-02-16 19:17:57.638417 | TASK [bindep : Look for bindep.txt] 2026-02-16 19:17:58.040704 | ubuntu-noble | ok 2026-02-16 19:17:58.053694 | 2026-02-16 19:17:58.053925 | TASK [bindep : Define bindep_file fact] 2026-02-16 19:17:58.079711 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:17:58.090677 | 2026-02-16 19:17:58.090817 | TASK [bindep : Look for other-requirements.txt] 2026-02-16 19:17:58.339215 | ubuntu-noble | ok 2026-02-16 19:17:58.344945 | 2026-02-16 19:17:58.345043 | TASK [bindep : Define bindep_file fact] 2026-02-16 19:17:58.370141 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:17:58.380845 | 2026-02-16 19:17:58.380994 | TASK [bindep : Look for bindep fallback file] 2026-02-16 19:17:58.418293 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:17:58.618723 | 2026-02-16 19:17:58.618862 | TASK [bindep : Define bindep_file fact] 2026-02-16 19:17:58.664108 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:17:58.675939 | 2026-02-16 19:17:58.676238 | TASK [bindep : Include bindep tasks] 2026-02-16 19:17:58.701148 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:17:58.713971 | 2026-02-16 19:17:58.714350 | TASK [bindep : Include install tasks] 2026-02-16 19:17:58.740424 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:17:58.752351 | 2026-02-16 19:17:58.752471 | LOOP [bindep : Include package tasks] 2026-02-16 19:17:58.812193 | 2026-02-16 19:17:58.812386 | TASK [Run test-setup role] 2026-02-16 19:17:58.841653 | ubuntu-noble | ok 2026-02-16 19:17:58.861082 | 2026-02-16 19:17:58.861173 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-02-16 19:17:59.097055 | ubuntu-noble | ok 2026-02-16 19:17:59.105221 | 2026-02-16 19:17:59.105308 | TASK [test-setup : Run tools/test-setup.sh] 2026-02-16 19:17:59.639540 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:17:59.651426 | 2026-02-16 19:17:59.651518 | PLAY RECAP 2026-02-16 19:17:59.651566 | ubuntu-noble | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-16 19:17:59.651588 | 2026-02-16 19:17:59.886062 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-16 19:17:59.926820 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master] 2026-02-16 19:18:00.685282 | 2026-02-16 19:18:00.685418 | PLAY [all] 2026-02-16 19:18:00.698732 | 2026-02-16 19:18:00.698839 | TASK [ensure-python : Validate python_version value] 2026-02-16 19:18:00.733182 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:00.743452 | 2026-02-16 19:18:00.743558 | TASK [ensure-python : Install specified version of python interpreter and development files (DEB)] 2026-02-16 19:18:00.767610 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:00.776859 | 2026-02-16 19:18:00.776942 | TASK [ensure-python : Pull in venv package] 2026-02-16 19:18:00.802877 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:00.814760 | 2026-02-16 19:18:00.814869 | TASK [ensure-python : Set default RPM package name] 2026-02-16 19:18:00.840114 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:00.850924 | 2026-02-16 19:18:00.851103 | TASK [ensure-python : Set RPM package name for CentOS/RHEL 9/10] 2026-02-16 19:18:00.875959 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:00.887871 | 2026-02-16 19:18:00.887985 | TASK [ensure-python : Install RPM package] 2026-02-16 19:18:00.912916 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:00.918986 | 2026-02-16 19:18:00.919059 | TASK [ensure-python : Install python using pyenv] 2026-02-16 19:18:00.943156 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:00.949154 | 2026-02-16 19:18:00.949225 | TASK [ensure-python : Activate python using stow] 2026-02-16 19:18:00.974461 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:00.992234 | 2026-02-16 19:18:00.992313 | TASK [Install pip] 2026-02-16 19:18:01.020469 | ubuntu-noble | ok 2026-02-16 19:18:01.057906 | 2026-02-16 19:18:01.141492 | TASK [ensure-pip : Check if pip is installed] 2026-02-16 19:18:01.685142 | ubuntu-noble | ok: Runtime: 0:00:00.008680 2026-02-16 19:18:01.692791 | 2026-02-16 19:18:01.692883 | LOOP [ensure-pip : Install pip from packages] 2026-02-16 19:18:01.726137 | ubuntu-noble | ok: "/var/lib/zuul/builds/b5bb2b0fbbb7417ea8f9b162cf6c8ba4/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/Debian.yaml" 2026-02-16 19:18:01.743402 | ubuntu-noble | included: /var/lib/zuul/builds/b5bb2b0fbbb7417ea8f9b162cf6c8ba4/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/Debian.yaml 2026-02-16 19:18:01.797328 | 2026-02-16 19:18:01.797999 | TASK [ensure-pip : Update package lists] 2026-02-16 19:18:08.090551 | ubuntu-noble | changed 2026-02-16 19:18:08.096429 | 2026-02-16 19:18:08.096508 | TASK [ensure-pip : Install Python 3 pip] 2026-02-16 19:18:14.454843 | ubuntu-noble | changed 2026-02-16 19:18:14.461246 | 2026-02-16 19:18:14.461333 | TASK [ensure-pip : Install Python 2 pip] 2026-02-16 19:18:14.486331 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:14.506013 | 2026-02-16 19:18:14.628150 | TASK [ensure-pip : Ensure setuptools] 2026-02-16 19:18:14.664990 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:14.677104 | 2026-02-16 19:18:14.677234 | TASK [ensure-pip : Check for ensurepip module] 2026-02-16 19:18:14.976781 | ubuntu-noble | usage: python -m ensurepip [-h] [--version] [-v] [-U] [--user] [--root ROOT] 2026-02-16 19:18:14.976860 | ubuntu-noble | [--altinstall] [--default-pip] 2026-02-16 19:18:14.976867 | ubuntu-noble | 2026-02-16 19:18:14.976872 | ubuntu-noble | options: 2026-02-16 19:18:14.976877 | ubuntu-noble | -h, --help show this help message and exit 2026-02-16 19:18:14.976885 | ubuntu-noble | --version Show the version of pip that is bundled with this Python. 2026-02-16 19:18:14.976892 | ubuntu-noble | -v, --verbose Give more output. Option is additive, and can be used up to 3 2026-02-16 19:18:14.976899 | ubuntu-noble | times. 2026-02-16 19:18:14.976905 | ubuntu-noble | -U, --upgrade Upgrade pip and dependencies, even if already installed. 2026-02-16 19:18:14.976909 | ubuntu-noble | --user Install using the user scheme. 2026-02-16 19:18:14.976916 | ubuntu-noble | --root ROOT Install everything relative to this alternate root directory. 2026-02-16 19:18:14.976920 | ubuntu-noble | --altinstall Make an alternate install, installing only the X.Y versioned 2026-02-16 19:18:14.976925 | ubuntu-noble | scripts (Default: pipX, pipX.Y). 2026-02-16 19:18:14.976930 | ubuntu-noble | --default-pip Make a default pip install, installing the unqualified pip in 2026-02-16 19:18:14.976934 | ubuntu-noble | addition to the versioned scripts. 2026-02-16 19:18:15.222858 | ubuntu-noble | ok: Runtime: 0:00:00.072227 2026-02-16 19:18:15.230300 | 2026-02-16 19:18:15.230373 | TASK [ensure-pip : Ensure python3-venv] 2026-02-16 19:18:15.254690 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:15.268160 | 2026-02-16 19:18:15.268245 | TASK [ensure-pip : Install pip from source] 2026-02-16 19:18:15.292838 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:15.358517 | 2026-02-16 19:18:15.358660 | TASK [ensure-pip : Probe for venv python full path] 2026-02-16 19:18:15.598819 | ubuntu-noble | /usr/bin/python3 2026-02-16 19:18:15.969263 | ubuntu-noble | ok: Runtime: 0:00:00.006068 2026-02-16 19:18:15.982462 | 2026-02-16 19:18:15.982643 | TASK [ensure-pip : Set host default] 2026-02-16 19:18:16.036129 | ubuntu-noble | ok 2026-02-16 19:18:16.041900 | 2026-02-16 19:18:16.041993 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2026-02-16 19:18:16.217960 | ubuntu-noble | ok 2026-02-16 19:18:16.236217 | 2026-02-16 19:18:16.236439 | TASK [ensure-tox : Check if tox is installed] 2026-02-16 19:18:17.138762 | ubuntu-noble | ok: Runtime: 0:00:00.008887 2026-02-16 19:18:17.146013 | 2026-02-16 19:18:17.146144 | TASK [ensure-tox : Export preinstalled tox_exectuable] 2026-02-16 19:18:17.171421 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:17.185453 | 2026-02-16 19:18:17.185649 | TASK [ensure-tox : Create local venv] 2026-02-16 19:18:20.754769 | ubuntu-noble | ok: Runtime: 0:00:03.255286 2026-02-16 19:18:20.764758 | 2026-02-16 19:18:20.765031 | TASK [ensure-tox : Install tox to local venv] 2026-02-16 19:18:21.598329 | ubuntu-noble | Collecting tox 2026-02-16 19:18:21.643897 | ubuntu-noble | Downloading tox-4.36.0-py3-none-any.whl.metadata (3.6 kB) 2026-02-16 19:18:21.713783 | ubuntu-noble | Collecting cachetools>=7.0.1 (from tox) 2026-02-16 19:18:21.718047 | ubuntu-noble | Downloading cachetools-7.0.1-py3-none-any.whl.metadata (5.6 kB) 2026-02-16 19:18:21.749753 | ubuntu-noble | Collecting chardet>=5.2 (from tox) 2026-02-16 19:18:21.752846 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl.metadata (3.4 kB) 2026-02-16 19:18:21.789502 | ubuntu-noble | Collecting colorama>=0.4.6 (from tox) 2026-02-16 19:18:21.793000 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl.metadata (17 kB) 2026-02-16 19:18:21.847837 | ubuntu-noble | Collecting filelock>=3.24 (from tox) 2026-02-16 19:18:21.851604 | ubuntu-noble | Downloading filelock-3.24.2-py3-none-any.whl.metadata (2.0 kB) 2026-02-16 19:18:21.898565 | ubuntu-noble | Collecting packaging>=26 (from tox) 2026-02-16 19:18:21.901931 | ubuntu-noble | Downloading packaging-26.0-py3-none-any.whl.metadata (3.3 kB) 2026-02-16 19:18:21.956579 | ubuntu-noble | Collecting platformdirs>=4.9.1 (from tox) 2026-02-16 19:18:21.960471 | ubuntu-noble | Downloading platformdirs-4.9.2-py3-none-any.whl.metadata (4.7 kB) 2026-02-16 19:18:22.005803 | ubuntu-noble | Collecting pluggy>=1.6 (from tox) 2026-02-16 19:18:22.538334 | ubuntu-noble | Downloading pluggy-1.6.0-py3-none-any.whl.metadata (4.8 kB) 2026-02-16 19:18:22.586497 | ubuntu-noble | Collecting pyproject-api>=1.10 (from tox) 2026-02-16 19:18:22.592591 | ubuntu-noble | Downloading pyproject_api-1.10.0-py3-none-any.whl.metadata (2.7 kB) 2026-02-16 19:18:22.745772 | ubuntu-noble | Collecting virtualenv>=20.36.1 (from tox) 2026-02-16 19:18:22.749466 | ubuntu-noble | Downloading virtualenv-20.37.0-py3-none-any.whl.metadata (5.1 kB) 2026-02-16 19:18:22.843948 | ubuntu-noble | Collecting distlib<1,>=0.3.7 (from virtualenv>=20.36.1->tox) 2026-02-16 19:18:22.847445 | ubuntu-noble | Downloading distlib-0.4.0-py2.py3-none-any.whl.metadata (5.2 kB) 2026-02-16 19:18:22.884061 | ubuntu-noble | Downloading tox-4.36.0-py3-none-any.whl (179 kB) 2026-02-16 19:18:22.944013 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 179.1/179.1 kB 3.1 MB/s eta 0:00:00 2026-02-16 19:18:22.948632 | ubuntu-noble | Downloading cachetools-7.0.1-py3-none-any.whl (13 kB) 2026-02-16 19:18:22.962257 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2026-02-16 19:18:22.980429 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 12.8 MB/s eta 0:00:00 2026-02-16 19:18:22.984416 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-02-16 19:18:22.999633 | ubuntu-noble | Downloading filelock-3.24.2-py3-none-any.whl (24 kB) 2026-02-16 19:18:23.016278 | ubuntu-noble | Downloading packaging-26.0-py3-none-any.whl (74 kB) 2026-02-16 19:18:23.029698 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 6.5 MB/s eta 0:00:00 2026-02-16 19:18:23.034401 | ubuntu-noble | Downloading platformdirs-4.9.2-py3-none-any.whl (21 kB) 2026-02-16 19:18:23.045544 | ubuntu-noble | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-02-16 19:18:23.059581 | ubuntu-noble | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-02-16 19:18:23.073070 | ubuntu-noble | Downloading virtualenv-20.37.0-py3-none-any.whl (5.8 MB) 2026-02-16 19:18:23.181324 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.8/5.8 MB 56.6 MB/s eta 0:00:00 2026-02-16 19:18:23.185356 | ubuntu-noble | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-02-16 19:18:23.224911 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 12.9 MB/s eta 0:00:00 2026-02-16 19:18:23.297137 | ubuntu-noble | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2026-02-16 19:18:24.263994 | 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 19:18:24.821583 | ubuntu-noble | ok: Runtime: 0:00:03.372747 2026-02-16 19:18:24.829451 | 2026-02-16 19:18:24.829537 | TASK [ensure-tox : Export installed tox_executable path] 2026-02-16 19:18:24.888272 | ubuntu-noble | ok 2026-02-16 19:18:24.899905 | 2026-02-16 19:18:24.900118 | TASK [ensure-tox : Output tox version] 2026-02-16 19:18:26.975588 | 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 19:18:26.990037 | ubuntu-noble | 4.36.0 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2026-02-16 19:18:27.132404 | ubuntu-noble | ok: Runtime: 0:00:00.301779 2026-02-16 19:18:27.140253 | 2026-02-16 19:18:27.140426 | TASK [ensure-tox : Make global symlink] 2026-02-16 19:18:27.167269 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:27.176431 | 2026-02-16 19:18:27.176580 | PLAY RECAP 2026-02-16 19:18:27.176651 | ubuntu-noble | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-02-16 19:18:27.176675 | 2026-02-16 19:18:27.366749 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master] 2026-02-16 19:18:27.368146 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/tox/pre.yml@main] 2026-02-16 19:18:28.056227 | 2026-02-16 19:18:28.056442 | PLAY [all] 2026-02-16 19:18:28.078219 | 2026-02-16 19:18:28.078407 | TASK [ensure-rust : Use rustup] 2026-02-16 19:18:28.112902 | ubuntu-noble | ok 2026-02-16 19:18:28.122584 | ubuntu-noble | included: /var/lib/zuul/builds/b5bb2b0fbbb7417ea8f9b162cf6c8ba4/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml 2026-02-16 19:18:28.131712 | 2026-02-16 19:18:28.131938 | TASK [ensure-rust : Install Rust] 2026-02-16 19:18:28.634732 | ubuntu-noble | /bin/bash: line 2: curl: command not found 2026-02-16 19:18:28.687157 | ubuntu-noble | ERROR 2026-02-16 19:18:28.687453 | ubuntu-noble | { 2026-02-16 19:18:28.687496 | ubuntu-noble | "delta": "0:00:00.010399", 2026-02-16 19:18:28.687528 | ubuntu-noble | "end": "2026-02-16 19:18:28.636760", 2026-02-16 19:18:28.687557 | ubuntu-noble | "msg": "non-zero return code", 2026-02-16 19:18:28.687585 | ubuntu-noble | "rc": 127, 2026-02-16 19:18:28.687612 | ubuntu-noble | "start": "2026-02-16 19:18:28.626361" 2026-02-16 19:18:28.687644 | ubuntu-noble | } failure 2026-02-16 19:18:28.689608 | 2026-02-16 19:18:28.689692 | PLAY RECAP 2026-02-16 19:18:28.689751 | ubuntu-noble | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-02-16 19:18:28.689782 | 2026-02-16 19:18:28.909477 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/tox/pre.yml@main] 2026-02-16 19:18:28.911448 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2026-02-16 19:18:29.597757 | 2026-02-16 19:18:29.598931 | PLAY [all] 2026-02-16 19:18:29.618650 | 2026-02-16 19:18:29.618833 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-02-16 19:18:29.675724 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:29.684769 | 2026-02-16 19:18:29.685003 | TASK [fetch-tox-output : Set tox log path for single node] 2026-02-16 19:18:29.750188 | ubuntu-noble | ok 2026-02-16 19:18:29.758652 | 2026-02-16 19:18:29.758757 | TASK [fetch-tox-output : Ensure local tox dir] 2026-02-16 19:18:30.233931 | ubuntu-noble -> localhost | changed 2026-02-16 19:18:30.245707 | 2026-02-16 19:18:30.245920 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-02-16 19:18:30.291235 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:30.301370 | 2026-02-16 19:18:30.301562 | TASK [fetch-tox-output : Set envlist fact] 2026-02-16 19:18:30.345023 | ubuntu-noble | ok 2026-02-16 19:18:30.352493 | 2026-02-16 19:18:30.352617 | TASK [fetch-tox-output : Get tox version] 2026-02-16 19:18:31.021073 | 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 19:18:31.035814 | ubuntu-noble | 4.36.0 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2026-02-16 19:18:31.399020 | ubuntu-noble | ok: Runtime: 0:00:00.293175 2026-02-16 19:18:31.407255 | 2026-02-16 19:18:31.407386 | TASK [fetch-tox-output : Find all default environments] 2026-02-16 19:18:31.937259 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:31.946124 | 2026-02-16 19:18:31.946321 | TASK [fetch-tox-output : Set envlist fact] 2026-02-16 19:18:31.981910 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:31.993805 | 2026-02-16 19:18:31.993974 | TASK [fetch-tox-output : Find all default environments] 2026-02-16 19:18:32.528079 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:32.538643 | 2026-02-16 19:18:32.538751 | TASK [fetch-tox-output : Set envlist fact] 2026-02-16 19:18:32.563510 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:32.574982 | 2026-02-16 19:18:32.575129 | LOOP [fetch-tox-output : Copy tox logs] 2026-02-16 19:18:32.618837 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:32.636455 | 2026-02-16 19:18:32.636635 | LOOP [fetch-tox-output : Collect tox logs] 2026-02-16 19:18:33.460466 | ubuntu-noble | ok: 2026-02-16 19:18:33.460689 | 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 19:18:33.460756 | 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 19:18:33.460784 | ubuntu-noble | rsync: [Receiver] write error: Broken pipe (32) 2026-02-16 19:18:33.460814 | 2026-02-16 19:18:33.473161 | 2026-02-16 19:18:33.473283 | PLAY RECAP 2026-02-16 19:18:33.473333 | ubuntu-noble | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-02-16 19:18:33.473355 | 2026-02-16 19:18:33.711698 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2026-02-16 19:18:33.713686 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-16 19:18:34.428224 | 2026-02-16 19:18:34.428395 | PLAY [all] 2026-02-16 19:18:34.442488 | 2026-02-16 19:18:34.442655 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-02-16 19:18:34.644000 | ubuntu-noble | changed: non-zero return code 2026-02-16 19:18:34.654042 | 2026-02-16 19:18:34.654235 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-02-16 19:18:34.692880 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:34.726072 | 2026-02-16 19:18:34.726336 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-02-16 19:18:34.766880 | 2026-02-16 19:18:34.767145 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-02-16 19:18:34.801555 | 2026-02-16 19:18:34.801795 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-02-16 19:18:34.828397 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:34.835220 | 2026-02-16 19:18:34.835306 | LOOP [fetch-subunit-output : Generate subunit file] 2026-02-16 19:18:34.882115 | 2026-02-16 19:18:34.882339 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-02-16 19:18:34.907819 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:34.921699 | 2026-02-16 19:18:34.921828 | TASK [fetch-subunit-output : Remove the temporary file] 2026-02-16 19:18:34.948060 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:34.962389 | 2026-02-16 19:18:34.962617 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-02-16 19:18:34.989080 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:35.001743 | 2026-02-16 19:18:35.001890 | PLAY RECAP 2026-02-16 19:18:35.001944 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-16 19:18:35.001966 | 2026-02-16 19:18:35.223359 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-16 19:18:35.224679 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 19:18:35.970810 | 2026-02-16 19:18:35.971076 | PLAY [all] 2026-02-16 19:18:35.988413 | 2026-02-16 19:18:35.988627 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-16 19:18:36.065962 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 19:18:36.077235 | 2026-02-16 19:18:36.077488 | TASK [fetch-output : Set log path for single node] 2026-02-16 19:18:36.119603 | ubuntu-noble | ok 2026-02-16 19:18:36.126290 | 2026-02-16 19:18:36.126373 | LOOP [fetch-output : Ensure local output dirs] 2026-02-16 19:18:36.646115 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/b5bb2b0fbbb7417ea8f9b162cf6c8ba4/work/logs" 2026-02-16 19:18:36.936234 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/b5bb2b0fbbb7417ea8f9b162cf6c8ba4/work/artifacts" 2026-02-16 19:18:37.216078 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/b5bb2b0fbbb7417ea8f9b162cf6c8ba4/work/docs" 2026-02-16 19:18:37.230908 | 2026-02-16 19:18:37.231062 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-16 19:18:38.789241 | ubuntu-noble | changed: .d..t...... ./ 2026-02-16 19:18:38.789582 | ubuntu-noble | changed: All items complete 2026-02-16 19:18:38.789625 | 2026-02-16 19:18:40.114190 | ubuntu-noble | changed: .d..t...... ./ 2026-02-16 19:18:40.669791 | ubuntu-noble | changed: .d..t...... ./ 2026-02-16 19:18:40.691443 | 2026-02-16 19:18:40.691626 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-16 19:18:41.187479 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.007967 2026-02-16 19:18:41.559410 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.010366 2026-02-16 19:18:41.579928 | 2026-02-16 19:18:41.580057 | PLAY [all] 2026-02-16 19:18:41.593607 | 2026-02-16 19:18:41.593709 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-16 19:18:42.111420 | ubuntu-noble | changed 2026-02-16 19:18:42.118231 | 2026-02-16 19:18:42.118295 | PLAY RECAP 2026-02-16 19:18:42.118348 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-16 19:18:42.118370 | 2026-02-16 19:18:42.279615 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 19:18:42.281100 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-16 19:18:42.921539 | 2026-02-16 19:18:42.921682 | PLAY [localhost] 2026-02-16 19:18:42.932585 | 2026-02-16 19:18:42.932661 | TASK [Generate Zuul manifest] 2026-02-16 19:18:42.975304 | localhost | ok 2026-02-16 19:18:42.994288 | 2026-02-16 19:18:42.994422 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-16 19:18:43.405820 | localhost | changed 2026-02-16 19:18:43.421077 | 2026-02-16 19:18:43.421256 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-16 19:18:43.471833 | localhost | ok 2026-02-16 19:18:43.480778 | 2026-02-16 19:18:43.480990 | TASK [Upload logs] 2026-02-16 19:18:43.512823 | localhost | ok 2026-02-16 19:18:43.586952 | 2026-02-16 19:18:43.587116 | TASK [Set zuul-log-path fact] 2026-02-16 19:18:43.609129 | localhost | ok 2026-02-16 19:18:43.622754 | 2026-02-16 19:18:43.622851 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 19:18:43.656236 | localhost | ok 2026-02-16 19:18:43.665850 | 2026-02-16 19:18:43.665938 | TASK [upload-logs : Create log directories] 2026-02-16 19:18:44.130823 | localhost | changed 2026-02-16 19:18:44.135786 | 2026-02-16 19:18:44.135897 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-16 19:18:44.577301 | localhost -> localhost | ok: Runtime: 0:00:00.006557 2026-02-16 19:18:44.585552 | 2026-02-16 19:18:44.585708 | TASK [upload-logs : Upload logs to log server] 2026-02-16 19:18:45.155619 | localhost | Output suppressed because no_log was given 2026-02-16 19:18:45.161346 | 2026-02-16 19:18:45.161447 | LOOP [upload-logs : Compress console log and json output] 2026-02-16 19:18:45.208702 | localhost | skipping: Conditional result was False 2026-02-16 19:18:45.215512 | localhost | skipping: Conditional result was False 2026-02-16 19:18:45.225839 | 2026-02-16 19:18:45.225998 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-16 19:18:45.268530 | localhost | skipping: Conditional result was False 2026-02-16 19:18:45.268847 | 2026-02-16 19:18:45.272536 | localhost | skipping: Conditional result was False 2026-02-16 19:18:45.279764 | 2026-02-16 19:18:45.279928 | LOOP [upload-logs : Upload console log and json output]