2026-02-16 17:45:41.556425 | Job console starting 2026-02-16 17:45:41.565472 | Updating git repos 2026-02-16 17:45:41.645780 | Cloning repos into workspace 2026-02-16 17:45:41.957560 | Restoring repo states 2026-02-16 17:45:41.983727 | Merging changes 2026-02-16 17:45:42.906235 | Checking out repos 2026-02-16 17:45:43.161616 | Preparing playbooks 2026-02-16 17:45:44.290533 | Running Ansible setup 2026-02-16 17:45:47.993845 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 17:45:48.588724 | 2026-02-16 17:45:48.642451 | PLAY [localhost] 2026-02-16 17:45:48.654912 | 2026-02-16 17:45:48.654984 | TASK [Gathering Facts] 2026-02-16 17:45:49.592693 | localhost | ok 2026-02-16 17:45:49.602692 | 2026-02-16 17:45:49.602779 | TASK [Setup log path fact] 2026-02-16 17:45:49.628499 | localhost | ok 2026-02-16 17:45:49.644636 | 2026-02-16 17:45:49.644708 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 17:45:49.679088 | localhost | ok 2026-02-16 17:45:49.696827 | 2026-02-16 17:45:49.696901 | TASK [emit-job-header : Print job information] 2026-02-16 17:45:49.745322 | # Job Information 2026-02-16 17:45:49.747398 | Ansible Version: 2.16.15 2026-02-16 17:45:49.747449 | Job: magnum-cluster-api-tox-functional 2026-02-16 17:45:49.747479 | Pipeline: check 2026-02-16 17:45:49.747506 | Executor: 3a2793d2bd32 2026-02-16 17:45:49.747532 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884 2026-02-16 17:45:49.747563 | Event ID: 37691bf0-0b5f-11f1-8a15-a065d2c16437 2026-02-16 17:45:49.752229 | 2026-02-16 17:45:49.752325 | LOOP [emit-job-header : Print node information] 2026-02-16 17:45:49.853027 | localhost | ok: 2026-02-16 17:45:49.853258 | localhost | # Node Information 2026-02-16 17:45:49.853300 | localhost | Inventory Hostname: ubuntu-noble 2026-02-16 17:45:49.853328 | localhost | Hostname: np0000155632 2026-02-16 17:45:49.853355 | localhost | Username: zuul 2026-02-16 17:45:49.853383 | localhost | Distro: Ubuntu 24.04 2026-02-16 17:45:49.853408 | localhost | Provider: yul1 2026-02-16 17:45:49.853432 | localhost | Region: ca-ymq-1 2026-02-16 17:45:49.853456 | localhost | Label: ubuntu-noble 2026-02-16 17:45:49.853479 | localhost | Product Name: OpenStack Nova 2026-02-16 17:45:49.853502 | localhost | Interface IP: 199.204.45.242 2026-02-16 17:45:49.867696 | 2026-02-16 17:45:49.867836 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-16 17:45:50.284572 | localhost -> localhost | changed 2026-02-16 17:45:50.291225 | 2026-02-16 17:45:50.291315 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-16 17:45:51.178238 | localhost -> localhost | changed 2026-02-16 17:45:51.189366 | 2026-02-16 17:45:51.189426 | PLAY [all] 2026-02-16 17:45:51.198021 | 2026-02-16 17:45:51.198086 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-16 17:45:51.455436 | ubuntu-noble -> localhost | ok 2026-02-16 17:45:51.467670 | 2026-02-16 17:45:51.467765 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-16 17:45:51.501953 | ubuntu-noble | ok 2026-02-16 17:45:51.519423 | ubuntu-noble | included: /var/lib/zuul/builds/25cfc0c9ecba4fcd9f5643e833926d1b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-16 17:45:51.525497 | 2026-02-16 17:45:51.525557 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-16 17:45:52.612702 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2026-02-16 17:45:52.613044 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/25cfc0c9ecba4fcd9f5643e833926d1b/work/25cfc0c9ecba4fcd9f5643e833926d1b_id_rsa 2026-02-16 17:45:52.613115 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/25cfc0c9ecba4fcd9f5643e833926d1b/work/25cfc0c9ecba4fcd9f5643e833926d1b_id_rsa.pub 2026-02-16 17:45:52.613166 | ubuntu-noble -> localhost | The key fingerprint is: 2026-02-16 17:45:52.613210 | ubuntu-noble -> localhost | SHA256:uZn7DqbzCUF5Xo36K2XAXyNaAuhob4XeGAK3X6GNEMM zuul-build-sshkey 2026-02-16 17:45:52.613271 | ubuntu-noble -> localhost | The key's randomart image is: 2026-02-16 17:45:52.613317 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2026-02-16 17:45:52.613375 | ubuntu-noble -> localhost | | .o. . | 2026-02-16 17:45:52.613423 | ubuntu-noble -> localhost | |. E.. o. o | 2026-02-16 17:45:52.613467 | ubuntu-noble -> localhost | | o = =o+. o . | 2026-02-16 17:45:52.613510 | ubuntu-noble -> localhost | | = *.+o++o o | 2026-02-16 17:45:52.613552 | ubuntu-noble -> localhost | | . = *. S* o . | 2026-02-16 17:45:52.613600 | ubuntu-noble -> localhost | | * ...=+ | 2026-02-16 17:45:52.613641 | ubuntu-noble -> localhost | | . . *o. | 2026-02-16 17:45:52.613681 | ubuntu-noble -> localhost | | .+.+ . | 2026-02-16 17:45:52.613714 | ubuntu-noble -> localhost | | .o+++ | 2026-02-16 17:45:52.613743 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2026-02-16 17:45:52.613810 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.660463 2026-02-16 17:45:52.623079 | 2026-02-16 17:45:52.623169 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-16 17:45:52.656451 | ubuntu-noble | ok 2026-02-16 17:45:52.669522 | ubuntu-noble | included: /var/lib/zuul/builds/25cfc0c9ecba4fcd9f5643e833926d1b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-16 17:45:52.680299 | 2026-02-16 17:45:52.680399 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-16 17:45:52.706317 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:45:52.715167 | 2026-02-16 17:45:52.715266 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-16 17:45:53.260031 | ubuntu-noble | changed 2026-02-16 17:45:53.274703 | 2026-02-16 17:45:53.274975 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-16 17:45:53.495072 | ubuntu-noble | ok 2026-02-16 17:45:53.502550 | 2026-02-16 17:45:53.502639 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-16 17:45:54.069839 | ubuntu-noble | changed 2026-02-16 17:45:54.187215 | 2026-02-16 17:45:54.187306 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-16 17:45:54.717270 | ubuntu-noble | changed 2026-02-16 17:45:54.726616 | 2026-02-16 17:45:54.726694 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-16 17:45:54.753089 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:45:54.764014 | 2026-02-16 17:45:54.764122 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-16 17:45:55.215703 | ubuntu-noble -> localhost | changed 2026-02-16 17:45:55.237862 | 2026-02-16 17:45:55.238012 | TASK [add-build-sshkey : Add back temp key] 2026-02-16 17:45:55.545527 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/25cfc0c9ecba4fcd9f5643e833926d1b/work/25cfc0c9ecba4fcd9f5643e833926d1b_id_rsa (zuul-build-sshkey) 2026-02-16 17:45:55.546221 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.013177 2026-02-16 17:45:55.555216 | 2026-02-16 17:45:55.555283 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-16 17:45:55.912160 | ubuntu-noble | ok 2026-02-16 17:45:55.924253 | 2026-02-16 17:45:55.924354 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-16 17:45:55.949849 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:45:55.967180 | 2026-02-16 17:45:55.967250 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-16 17:45:56.334284 | ubuntu-noble | ok 2026-02-16 17:45:56.346064 | 2026-02-16 17:45:56.346199 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-16 17:45:57.444702 | ubuntu-noble | Output suppressed because no_log was given 2026-02-16 17:45:57.456506 | 2026-02-16 17:45:57.456581 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-16 17:45:57.682825 | ubuntu-noble | ok: "logs" 2026-02-16 17:45:57.703976 | ubuntu-noble | ok: All items complete 2026-02-16 17:45:57.704096 | 2026-02-16 17:45:57.938304 | ubuntu-noble | ok: "artifacts" 2026-02-16 17:45:58.116031 | ubuntu-noble | ok: "docs" 2026-02-16 17:45:58.136411 | 2026-02-16 17:45:58.136579 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-16 17:45:58.368632 | ubuntu-noble | changed: "logs" 2026-02-16 17:45:58.565149 | ubuntu-noble | changed: "artifacts" 2026-02-16 17:45:58.768553 | ubuntu-noble | changed: "docs" 2026-02-16 17:45:58.786069 | 2026-02-16 17:45:58.786168 | PLAY RECAP 2026-02-16 17:45:58.786226 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-16 17:45:58.786257 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-16 17:45:58.786279 | 2026-02-16 17:45:59.017179 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 17:45:59.057016 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-16 17:45:59.646386 | 2026-02-16 17:45:59.646896 | PLAY [all] 2026-02-16 17:45:59.662056 | 2026-02-16 17:45:59.662214 | TASK [Install binary dependencies] 2026-02-16 17:45:59.738712 | ubuntu-noble | ok 2026-02-16 17:45:59.808128 | 2026-02-16 17:45:59.809954 | TASK [bindep : Include find tasks] 2026-02-16 17:45:59.845816 | ubuntu-noble | ok 2026-02-16 17:45:59.854599 | ubuntu-noble | included: /var/lib/zuul/builds/25cfc0c9ecba4fcd9f5643e833926d1b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-02-16 17:45:59.861125 | 2026-02-16 17:45:59.861197 | TASK [bindep : Look for bindep.txt] 2026-02-16 17:46:00.222385 | ubuntu-noble | ok 2026-02-16 17:46:00.231064 | 2026-02-16 17:46:00.231143 | TASK [bindep : Define bindep_file fact] 2026-02-16 17:46:00.256046 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:00.267044 | 2026-02-16 17:46:00.267129 | TASK [bindep : Look for other-requirements.txt] 2026-02-16 17:46:00.499455 | ubuntu-noble | ok 2026-02-16 17:46:00.504891 | 2026-02-16 17:46:00.504979 | TASK [bindep : Define bindep_file fact] 2026-02-16 17:46:00.539997 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:00.546912 | 2026-02-16 17:46:00.546996 | TASK [bindep : Look for bindep fallback file] 2026-02-16 17:46:00.589081 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:00.607876 | 2026-02-16 17:46:00.607952 | TASK [bindep : Define bindep_file fact] 2026-02-16 17:46:00.642513 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:00.654758 | 2026-02-16 17:46:00.654831 | TASK [bindep : Include bindep tasks] 2026-02-16 17:46:00.689248 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:00.700138 | 2026-02-16 17:46:00.700229 | TASK [bindep : Include install tasks] 2026-02-16 17:46:00.735286 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:00.747528 | 2026-02-16 17:46:00.747619 | LOOP [bindep : Include package tasks] 2026-02-16 17:46:00.814610 | 2026-02-16 17:46:00.814837 | TASK [Run test-setup role] 2026-02-16 17:46:00.843164 | ubuntu-noble | ok 2026-02-16 17:46:00.861598 | 2026-02-16 17:46:00.861670 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-02-16 17:46:01.078925 | ubuntu-noble | ok 2026-02-16 17:46:01.109218 | 2026-02-16 17:46:01.122850 | TASK [test-setup : Run tools/test-setup.sh] 2026-02-16 17:46:01.653595 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:01.665662 | 2026-02-16 17:46:01.665716 | PLAY RECAP 2026-02-16 17:46:01.665767 | ubuntu-noble | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-16 17:46:01.665789 | 2026-02-16 17:46:01.799281 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-16 17:46:01.801680 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master] 2026-02-16 17:46:02.460644 | 2026-02-16 17:46:02.460777 | PLAY [all] 2026-02-16 17:46:02.476054 | 2026-02-16 17:46:02.476133 | TASK [ensure-python : Validate python_version value] 2026-02-16 17:46:02.511018 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:02.520605 | 2026-02-16 17:46:02.575210 | TASK [ensure-python : Install specified version of python interpreter and development files (DEB)] 2026-02-16 17:46:02.600776 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:02.610192 | 2026-02-16 17:46:02.610292 | TASK [ensure-python : Pull in venv package] 2026-02-16 17:46:02.634368 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:02.641058 | 2026-02-16 17:46:02.641130 | TASK [ensure-python : Set default RPM package name] 2026-02-16 17:46:02.665705 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:02.676118 | 2026-02-16 17:46:02.676190 | TASK [ensure-python : Set RPM package name for CentOS/RHEL 9/10] 2026-02-16 17:46:02.700048 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:02.711560 | 2026-02-16 17:46:02.711628 | TASK [ensure-python : Install RPM package] 2026-02-16 17:46:02.735975 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:02.747306 | 2026-02-16 17:46:02.747388 | TASK [ensure-python : Install python using pyenv] 2026-02-16 17:46:02.771522 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:02.782979 | 2026-02-16 17:46:02.783042 | TASK [ensure-python : Activate python using stow] 2026-02-16 17:46:02.818532 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:02.838619 | 2026-02-16 17:46:02.842424 | TASK [Install pip] 2026-02-16 17:46:02.874577 | ubuntu-noble | ok 2026-02-16 17:46:02.901294 | 2026-02-16 17:46:02.901394 | TASK [ensure-pip : Check if pip is installed] 2026-02-16 17:46:03.440208 | ubuntu-noble | ok: Runtime: 0:00:00.005992 2026-02-16 17:46:03.448278 | 2026-02-16 17:46:03.448374 | LOOP [ensure-pip : Install pip from packages] 2026-02-16 17:46:03.495173 | ubuntu-noble | ok: "/var/lib/zuul/builds/25cfc0c9ecba4fcd9f5643e833926d1b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/Debian.yaml" 2026-02-16 17:46:03.513975 | ubuntu-noble | included: /var/lib/zuul/builds/25cfc0c9ecba4fcd9f5643e833926d1b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/Debian.yaml 2026-02-16 17:46:03.522858 | 2026-02-16 17:46:03.522919 | TASK [ensure-pip : Update package lists] 2026-02-16 17:46:09.313335 | ubuntu-noble | changed 2026-02-16 17:46:09.321663 | 2026-02-16 17:46:09.321728 | TASK [ensure-pip : Install Python 3 pip] 2026-02-16 17:46:15.169491 | ubuntu-noble | changed 2026-02-16 17:46:15.175618 | 2026-02-16 17:46:15.175683 | TASK [ensure-pip : Install Python 2 pip] 2026-02-16 17:46:15.201106 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:15.211547 | 2026-02-16 17:46:15.211641 | TASK [ensure-pip : Ensure setuptools] 2026-02-16 17:46:15.237151 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:15.244417 | 2026-02-16 17:46:15.244485 | TASK [ensure-pip : Check for ensurepip module] 2026-02-16 17:46:15.544432 | ubuntu-noble | usage: python -m ensurepip [-h] [--version] [-v] [-U] [--user] [--root ROOT] 2026-02-16 17:46:15.544556 | ubuntu-noble | [--altinstall] [--default-pip] 2026-02-16 17:46:15.544571 | ubuntu-noble | 2026-02-16 17:46:15.544581 | ubuntu-noble | options: 2026-02-16 17:46:15.544591 | ubuntu-noble | -h, --help show this help message and exit 2026-02-16 17:46:15.544606 | ubuntu-noble | --version Show the version of pip that is bundled with this Python. 2026-02-16 17:46:15.544618 | ubuntu-noble | -v, --verbose Give more output. Option is additive, and can be used up to 3 2026-02-16 17:46:15.544632 | ubuntu-noble | times. 2026-02-16 17:46:15.544641 | ubuntu-noble | -U, --upgrade Upgrade pip and dependencies, even if already installed. 2026-02-16 17:46:15.544650 | ubuntu-noble | --user Install using the user scheme. 2026-02-16 17:46:15.544659 | ubuntu-noble | --root ROOT Install everything relative to this alternate root directory. 2026-02-16 17:46:15.544669 | ubuntu-noble | --altinstall Make an alternate install, installing only the X.Y versioned 2026-02-16 17:46:15.544677 | ubuntu-noble | scripts (Default: pipX, pipX.Y). 2026-02-16 17:46:15.544685 | ubuntu-noble | --default-pip Make a default pip install, installing the unqualified pip in 2026-02-16 17:46:15.544693 | ubuntu-noble | addition to the versioned scripts. 2026-02-16 17:46:15.784517 | ubuntu-noble | ok: Runtime: 0:00:00.064468 2026-02-16 17:46:15.790463 | 2026-02-16 17:46:15.790532 | TASK [ensure-pip : Ensure python3-venv] 2026-02-16 17:46:15.815593 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:15.826187 | 2026-02-16 17:46:15.826251 | TASK [ensure-pip : Install pip from source] 2026-02-16 17:46:15.850365 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:15.895784 | 2026-02-16 17:46:15.895929 | TASK [ensure-pip : Probe for venv python full path] 2026-02-16 17:46:16.150771 | ubuntu-noble | /usr/bin/python3 2026-02-16 17:46:16.435444 | ubuntu-noble | ok: Runtime: 0:00:00.007894 2026-02-16 17:46:16.441082 | 2026-02-16 17:46:16.441168 | TASK [ensure-pip : Set host default] 2026-02-16 17:46:16.494163 | ubuntu-noble | ok 2026-02-16 17:46:16.501785 | 2026-02-16 17:46:16.501914 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2026-02-16 17:46:16.561049 | ubuntu-noble | ok 2026-02-16 17:46:16.570644 | 2026-02-16 17:46:16.570729 | TASK [ensure-tox : Check if tox is installed] 2026-02-16 17:46:17.114754 | ubuntu-noble | ok: Runtime: 0:00:00.006849 2026-02-16 17:46:17.120248 | 2026-02-16 17:46:17.120310 | TASK [ensure-tox : Export preinstalled tox_exectuable] 2026-02-16 17:46:17.144925 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:17.155851 | 2026-02-16 17:46:17.155946 | TASK [ensure-tox : Create local venv] 2026-02-16 17:46:20.696361 | ubuntu-noble | ok: Runtime: 0:00:03.199639 2026-02-16 17:46:20.702913 | 2026-02-16 17:46:20.702978 | TASK [ensure-tox : Install tox to local venv] 2026-02-16 17:46:21.493621 | ubuntu-noble | Collecting tox 2026-02-16 17:46:21.539112 | ubuntu-noble | Downloading tox-4.36.0-py3-none-any.whl.metadata (3.6 kB) 2026-02-16 17:46:21.602987 | ubuntu-noble | Collecting cachetools>=7.0.1 (from tox) 2026-02-16 17:46:21.606127 | ubuntu-noble | Downloading cachetools-7.0.1-py3-none-any.whl.metadata (5.6 kB) 2026-02-16 17:46:21.641475 | ubuntu-noble | Collecting chardet>=5.2 (from tox) 2026-02-16 17:46:21.644954 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl.metadata (3.4 kB) 2026-02-16 17:46:21.747865 | ubuntu-noble | Collecting colorama>=0.4.6 (from tox) 2026-02-16 17:46:21.751126 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl.metadata (17 kB) 2026-02-16 17:46:21.819214 | ubuntu-noble | Collecting filelock>=3.24 (from tox) 2026-02-16 17:46:21.822070 | ubuntu-noble | Downloading filelock-3.24.2-py3-none-any.whl.metadata (2.0 kB) 2026-02-16 17:46:21.882716 | ubuntu-noble | Collecting packaging>=26 (from tox) 2026-02-16 17:46:21.886385 | ubuntu-noble | Downloading packaging-26.0-py3-none-any.whl.metadata (3.3 kB) 2026-02-16 17:46:22.009867 | ubuntu-noble | Collecting platformdirs>=4.9.1 (from tox) 2026-02-16 17:46:22.013005 | ubuntu-noble | Downloading platformdirs-4.9.2-py3-none-any.whl.metadata (4.7 kB) 2026-02-16 17:46:22.061903 | ubuntu-noble | Collecting pluggy>=1.6 (from tox) 2026-02-16 17:46:22.065939 | ubuntu-noble | Downloading pluggy-1.6.0-py3-none-any.whl.metadata (4.8 kB) 2026-02-16 17:46:22.141383 | ubuntu-noble | Collecting pyproject-api>=1.10 (from tox) 2026-02-16 17:46:22.148353 | ubuntu-noble | Downloading pyproject_api-1.10.0-py3-none-any.whl.metadata (2.7 kB) 2026-02-16 17:46:22.282696 | ubuntu-noble | Collecting virtualenv>=20.36.1 (from tox) 2026-02-16 17:46:22.287725 | ubuntu-noble | Downloading virtualenv-20.37.0-py3-none-any.whl.metadata (5.1 kB) 2026-02-16 17:46:22.386161 | ubuntu-noble | Collecting distlib<1,>=0.3.7 (from virtualenv>=20.36.1->tox) 2026-02-16 17:46:22.390652 | ubuntu-noble | Downloading distlib-0.4.0-py2.py3-none-any.whl.metadata (5.2 kB) 2026-02-16 17:46:22.424141 | ubuntu-noble | Downloading tox-4.36.0-py3-none-any.whl (179 kB) 2026-02-16 17:46:22.442258 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 179.1/179.1 kB 11.5 MB/s eta 0:00:00 2026-02-16 17:46:22.447269 | ubuntu-noble | Downloading cachetools-7.0.1-py3-none-any.whl (13 kB) 2026-02-16 17:46:22.459001 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2026-02-16 17:46:22.473273 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 16.4 MB/s eta 0:00:00 2026-02-16 17:46:22.476816 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-02-16 17:46:22.490856 | ubuntu-noble | Downloading filelock-3.24.2-py3-none-any.whl (24 kB) 2026-02-16 17:46:22.507045 | ubuntu-noble | Downloading packaging-26.0-py3-none-any.whl (74 kB) 2026-02-16 17:46:22.519438 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 7.3 MB/s eta 0:00:00 2026-02-16 17:46:22.523217 | ubuntu-noble | Downloading platformdirs-4.9.2-py3-none-any.whl (21 kB) 2026-02-16 17:46:22.536317 | ubuntu-noble | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-02-16 17:46:22.550841 | ubuntu-noble | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-02-16 17:46:22.568002 | ubuntu-noble | Downloading virtualenv-20.37.0-py3-none-any.whl (5.8 MB) 2026-02-16 17:46:22.657008 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.8/5.8 MB 68.3 MB/s eta 0:00:00 2026-02-16 17:46:22.710239 | ubuntu-noble | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-02-16 17:46:22.729987 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 29.3 MB/s eta 0:00:00 2026-02-16 17:46:22.812979 | ubuntu-noble | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2026-02-16 17:46:23.690552 | ubuntu-noble | Successfully installed cachetools-7.0.1 chardet-5.2.0 colorama-0.4.6 distlib-0.4.0 filelock-3.24.2 packaging-26.0 platformdirs-4.9.2 pluggy-1.6.0 pyproject-api-1.10.0 tox-4.36.0 virtualenv-20.37.0 2026-02-16 17:46:24.249253 | ubuntu-noble | ok: Runtime: 0:00:02.847079 2026-02-16 17:46:24.255249 | 2026-02-16 17:46:24.255313 | TASK [ensure-tox : Export installed tox_executable path] 2026-02-16 17:46:24.289175 | ubuntu-noble | ok 2026-02-16 17:46:24.296253 | 2026-02-16 17:46:24.296338 | TASK [ensure-tox : Output tox version] 2026-02-16 17:46:24.750092 | ubuntu-noble | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-02-16 17:46:24.764182 | ubuntu-noble | 4.36.0 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2026-02-16 17:46:24.843447 | ubuntu-noble | ok: Runtime: 0:00:00.270816 2026-02-16 17:46:24.849308 | 2026-02-16 17:46:24.849378 | TASK [ensure-tox : Make global symlink] 2026-02-16 17:46:24.874507 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:24.885589 | 2026-02-16 17:46:24.885647 | PLAY RECAP 2026-02-16 17:46:24.885703 | ubuntu-noble | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-02-16 17:46:24.885727 | 2026-02-16 17:46:25.052738 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master] 2026-02-16 17:46:25.055730 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/tox/pre.yml@main] 2026-02-16 17:46:25.650590 | 2026-02-16 17:46:25.651137 | PLAY [all] 2026-02-16 17:46:25.664994 | 2026-02-16 17:46:25.665102 | TASK [ensure-rust : Use rustup] 2026-02-16 17:46:25.696500 | ubuntu-noble | ok 2026-02-16 17:46:25.704387 | ubuntu-noble | included: /var/lib/zuul/builds/25cfc0c9ecba4fcd9f5643e833926d1b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml 2026-02-16 17:46:25.710487 | 2026-02-16 17:46:25.710564 | TASK [ensure-rust : Install Rust] 2026-02-16 17:46:26.116211 | ubuntu-noble | /bin/bash: line 2: curl: command not found 2026-02-16 17:46:26.254512 | ubuntu-noble | ERROR 2026-02-16 17:46:26.254826 | ubuntu-noble | { 2026-02-16 17:46:26.254890 | ubuntu-noble | "delta": "0:00:00.008754", 2026-02-16 17:46:26.254937 | ubuntu-noble | "end": "2026-02-16 17:46:26.116833", 2026-02-16 17:46:26.254978 | ubuntu-noble | "msg": "non-zero return code", 2026-02-16 17:46:26.255020 | ubuntu-noble | "rc": 127, 2026-02-16 17:46:26.255061 | ubuntu-noble | "start": "2026-02-16 17:46:26.108079" 2026-02-16 17:46:26.255101 | ubuntu-noble | } failure 2026-02-16 17:46:26.257741 | 2026-02-16 17:46:26.257890 | PLAY RECAP 2026-02-16 17:46:26.257982 | ubuntu-noble | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-02-16 17:46:26.258030 | 2026-02-16 17:46:26.416903 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/tox/pre.yml@main] 2026-02-16 17:46:26.418376 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2026-02-16 17:46:27.082844 | 2026-02-16 17:46:27.083003 | PLAY [all] 2026-02-16 17:46:27.097820 | 2026-02-16 17:46:27.097927 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-02-16 17:46:27.154493 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:27.167717 | 2026-02-16 17:46:27.167805 | TASK [fetch-tox-output : Set tox log path for single node] 2026-02-16 17:46:27.209455 | ubuntu-noble | ok 2026-02-16 17:46:27.218153 | 2026-02-16 17:46:27.218235 | TASK [fetch-tox-output : Ensure local tox dir] 2026-02-16 17:46:27.630873 | ubuntu-noble -> localhost | changed 2026-02-16 17:46:27.642492 | 2026-02-16 17:46:27.642566 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-02-16 17:46:27.678306 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:27.690562 | 2026-02-16 17:46:27.690671 | TASK [fetch-tox-output : Set envlist fact] 2026-02-16 17:46:27.727034 | ubuntu-noble | ok 2026-02-16 17:46:27.735031 | 2026-02-16 17:46:27.735101 | TASK [fetch-tox-output : Get tox version] 2026-02-16 17:46:28.335128 | ubuntu-noble | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-02-16 17:46:28.349504 | ubuntu-noble | 4.36.0 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2026-02-16 17:46:28.778983 | ubuntu-noble | ok: Runtime: 0:00:00.273847 2026-02-16 17:46:28.787342 | 2026-02-16 17:46:28.787439 | TASK [fetch-tox-output : Find all default environments] 2026-02-16 17:46:29.316321 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:29.325547 | 2026-02-16 17:46:29.325654 | TASK [fetch-tox-output : Set envlist fact] 2026-02-16 17:46:29.350667 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:29.361878 | 2026-02-16 17:46:29.361945 | TASK [fetch-tox-output : Find all default environments] 2026-02-16 17:46:29.890749 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:29.902356 | 2026-02-16 17:46:29.902436 | TASK [fetch-tox-output : Set envlist fact] 2026-02-16 17:46:29.927378 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:29.940758 | 2026-02-16 17:46:29.940933 | LOOP [fetch-tox-output : Copy tox logs] 2026-02-16 17:46:29.983447 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:29.999019 | 2026-02-16 17:46:29.999207 | LOOP [fetch-tox-output : Collect tox logs] 2026-02-16 17:46:30.808759 | ubuntu-noble | ok: 2026-02-16 17:46:30.809072 | 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:46:30.809153 | ubuntu-noble | rsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1865) [Receiver=3.2.7] 2026-02-16 17:46:30.809203 | ubuntu-noble | rsync: [Receiver] write error: Broken pipe (32) 2026-02-16 17:46:30.809256 | 2026-02-16 17:46:30.823590 | 2026-02-16 17:46:30.823716 | PLAY RECAP 2026-02-16 17:46:30.823769 | ubuntu-noble | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-02-16 17:46:30.823792 | 2026-02-16 17:46:30.995764 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2026-02-16 17:46:30.997344 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-16 17:46:31.640699 | 2026-02-16 17:46:31.640846 | PLAY [all] 2026-02-16 17:46:31.653222 | 2026-02-16 17:46:31.653314 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-02-16 17:46:31.837011 | ubuntu-noble | changed: non-zero return code 2026-02-16 17:46:31.842540 | 2026-02-16 17:46:31.842616 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-02-16 17:46:31.869436 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:31.879462 | 2026-02-16 17:46:31.879563 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-02-16 17:46:31.914759 | 2026-02-16 17:46:31.914928 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-02-16 17:46:31.949659 | 2026-02-16 17:46:31.949824 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-02-16 17:46:31.975994 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:31.986299 | 2026-02-16 17:46:31.986437 | LOOP [fetch-subunit-output : Generate subunit file] 2026-02-16 17:46:32.024138 | 2026-02-16 17:46:32.024362 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-02-16 17:46:32.049840 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:32.060951 | 2026-02-16 17:46:32.061074 | TASK [fetch-subunit-output : Remove the temporary file] 2026-02-16 17:46:32.087075 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:32.098382 | 2026-02-16 17:46:32.098454 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-02-16 17:46:32.123477 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:32.132952 | 2026-02-16 17:46:32.133041 | PLAY RECAP 2026-02-16 17:46:32.133104 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-16 17:46:32.133134 | 2026-02-16 17:46:32.272297 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-16 17:46:32.274824 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 17:46:32.886541 | 2026-02-16 17:46:32.886702 | PLAY [all] 2026-02-16 17:46:32.899926 | 2026-02-16 17:46:32.900032 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-16 17:46:32.936298 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:46:32.946282 | 2026-02-16 17:46:32.946390 | TASK [fetch-output : Set log path for single node] 2026-02-16 17:46:32.991958 | ubuntu-noble | ok 2026-02-16 17:46:33.000727 | 2026-02-16 17:46:33.000800 | LOOP [fetch-output : Ensure local output dirs] 2026-02-16 17:46:33.402879 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/25cfc0c9ecba4fcd9f5643e833926d1b/work/logs" 2026-02-16 17:46:33.625007 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/25cfc0c9ecba4fcd9f5643e833926d1b/work/artifacts" 2026-02-16 17:46:33.847888 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/25cfc0c9ecba4fcd9f5643e833926d1b/work/docs" 2026-02-16 17:46:33.865604 | 2026-02-16 17:46:33.865811 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-16 17:46:34.540537 | ubuntu-noble | changed: .d..t...... ./ 2026-02-16 17:46:34.540802 | ubuntu-noble | changed: All items complete 2026-02-16 17:46:34.540832 | 2026-02-16 17:46:34.996591 | ubuntu-noble | changed: .d..t...... ./ 2026-02-16 17:46:35.425547 | ubuntu-noble | changed: .d..t...... ./ 2026-02-16 17:46:35.453390 | 2026-02-16 17:46:35.453526 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-16 17:46:35.918985 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.007486 2026-02-16 17:46:36.162058 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.008152 2026-02-16 17:46:36.174372 | 2026-02-16 17:46:36.174521 | PLAY [all] 2026-02-16 17:46:36.182352 | 2026-02-16 17:46:36.182420 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-16 17:46:36.684634 | ubuntu-noble | changed 2026-02-16 17:46:36.859388 | 2026-02-16 17:46:36.859529 | PLAY RECAP 2026-02-16 17:46:36.859598 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-16 17:46:36.859630 | 2026-02-16 17:46:37.005247 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 17:46:37.068500 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-16 17:46:37.651006 | 2026-02-16 17:46:37.651131 | PLAY [localhost] 2026-02-16 17:46:37.666675 | 2026-02-16 17:46:37.666783 | TASK [Generate Zuul manifest] 2026-02-16 17:46:37.699892 | localhost | ok 2026-02-16 17:46:37.726007 | 2026-02-16 17:46:37.726213 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-16 17:46:38.049056 | localhost | changed 2026-02-16 17:46:38.060575 | 2026-02-16 17:46:38.060666 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-16 17:46:38.113776 | localhost | ok 2026-02-16 17:46:38.122803 | 2026-02-16 17:46:38.122894 | TASK [Upload logs] 2026-02-16 17:46:38.143483 | localhost | ok 2026-02-16 17:46:38.202387 | 2026-02-16 17:46:38.202504 | TASK [Set zuul-log-path fact] 2026-02-16 17:46:38.224534 | localhost | ok 2026-02-16 17:46:38.235617 | 2026-02-16 17:46:38.235707 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 17:46:38.279419 | localhost | ok 2026-02-16 17:46:38.286823 | 2026-02-16 17:46:38.286934 | TASK [upload-logs : Create log directories] 2026-02-16 17:46:38.720571 | localhost | changed 2026-02-16 17:46:38.725734 | 2026-02-16 17:46:38.725809 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-16 17:46:39.200810 | localhost -> localhost | ok: Runtime: 0:00:00.006194 2026-02-16 17:46:39.206416 | 2026-02-16 17:46:39.208587 | TASK [upload-logs : Upload logs to log server] 2026-02-16 17:46:39.694507 | localhost | Output suppressed because no_log was given 2026-02-16 17:46:39.700111 | 2026-02-16 17:46:39.700206 | LOOP [upload-logs : Compress console log and json output] 2026-02-16 17:46:39.745061 | localhost | skipping: Conditional result was False 2026-02-16 17:46:39.751327 | localhost | skipping: Conditional result was False 2026-02-16 17:46:39.764687 | 2026-02-16 17:46:39.764874 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-16 17:46:39.803677 | localhost | skipping: Conditional result was False 2026-02-16 17:46:39.804014 | 2026-02-16 17:46:39.807313 | localhost | skipping: Conditional result was False 2026-02-16 17:46:39.818949 | 2026-02-16 17:46:39.819090 | LOOP [upload-logs : Upload console log and json output]