2026-02-16 20:45:39.921257 | Job console starting
2026-02-16 20:45:39.931786 | Updating git repos
2026-02-16 20:45:39.965456 | Cloning repos into workspace
2026-02-16 20:45:40.011781 | Restoring repo states
2026-02-16 20:45:40.022408 | Merging changes
2026-02-16 20:45:40.438856 | Checking out repos
2026-02-16 20:45:40.592555 | Preparing playbooks
2026-02-16 20:45:41.646401 | Running Ansible setup
2026-02-16 20:45:45.011638 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 20:45:45.597631 | 
2026-02-16 20:45:45.598397 | PLAY [localhost]
2026-02-16 20:45:45.611664 | 
2026-02-16 20:45:45.611848 | TASK [Gathering Facts]
2026-02-16 20:45:46.507669 | localhost | ok
2026-02-16 20:45:46.519690 | 
2026-02-16 20:45:46.519805 | TASK [Setup log path fact]
2026-02-16 20:45:46.544315 | localhost | ok
2026-02-16 20:45:46.561222 | 
2026-02-16 20:45:46.561292 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 20:45:46.594206 | localhost | ok
2026-02-16 20:45:46.604507 | 
2026-02-16 20:45:46.604607 | TASK [emit-job-header : Print job information]
2026-02-16 20:45:46.658377 | # Job Information
2026-02-16 20:45:46.658623 | Ansible Version: 2.16.15
2026-02-16 20:45:46.658669 | Job: magnum-cluster-api-tox-unit
2026-02-16 20:45:46.658699 | Pipeline: check
2026-02-16 20:45:46.658729 | Executor: 3a2793d2bd32
2026-02-16 20:45:46.658757 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 20:45:46.658788 | Event ID: 1a8d3980-0b78-11f1-9aef-4b1012140893
2026-02-16 20:45:46.662693 | 
2026-02-16 20:45:46.662783 | LOOP [emit-job-header : Print node information]
2026-02-16 20:45:46.761578 | localhost | ok:
2026-02-16 20:45:46.761776 | localhost | # Node Information
2026-02-16 20:45:46.761804 | localhost | Inventory Hostname: ubuntu-noble
2026-02-16 20:45:46.761826 | localhost | Hostname: np0000155697
2026-02-16 20:45:46.761846 | localhost | Username: zuul
2026-02-16 20:45:46.761867 | localhost | Distro: Ubuntu 24.04
2026-02-16 20:45:46.761887 | localhost | Provider: yul1
2026-02-16 20:45:46.761906 | localhost | Region: ca-ymq-1
2026-02-16 20:45:46.761925 | localhost | Label: ubuntu-noble
2026-02-16 20:45:46.761943 | localhost | Product Name: OpenStack Nova
2026-02-16 20:45:46.761961 | localhost | Interface IP: 162.253.55.43
2026-02-16 20:45:46.780914 | 
2026-02-16 20:45:46.781245 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 20:45:47.192862 | localhost -> localhost | changed
2026-02-16 20:45:47.202666 | 
2026-02-16 20:45:47.202767 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 20:45:48.038486 | localhost -> localhost | changed
2026-02-16 20:45:48.048400 | 
2026-02-16 20:45:48.048482 | PLAY [all]
2026-02-16 20:45:48.058925 | 
2026-02-16 20:45:48.058993 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 20:45:48.307152 | ubuntu-noble -> localhost | ok
2026-02-16 20:45:48.322425 | 
2026-02-16 20:45:48.322502 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 20:45:48.359527 | ubuntu-noble | ok
2026-02-16 20:45:48.374533 | ubuntu-noble | included: /var/lib/zuul/builds/cea857c3c19149a7abb9d132bf64eada/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 20:45:48.380612 | 
2026-02-16 20:45:48.380673 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 20:45:50.321387 | ubuntu-noble -> localhost | Generating public/private rsa key pair.
2026-02-16 20:45:50.321615 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/cea857c3c19149a7abb9d132bf64eada/work/cea857c3c19149a7abb9d132bf64eada_id_rsa
2026-02-16 20:45:50.321656 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/cea857c3c19149a7abb9d132bf64eada/work/cea857c3c19149a7abb9d132bf64eada_id_rsa.pub
2026-02-16 20:45:50.321688 | ubuntu-noble -> localhost | The key fingerprint is:
2026-02-16 20:45:50.321718 | ubuntu-noble -> localhost | SHA256:yEr/gmXY2xKHJP4CiuQBpAi7uj2jGzWSoK+6WWR5k3c zuul-build-sshkey
2026-02-16 20:45:50.321768 | ubuntu-noble -> localhost | The key's randomart image is:
2026-02-16 20:45:50.321797 | ubuntu-noble -> localhost | +---[RSA 3072]----+
2026-02-16 20:45:50.321835 | ubuntu-noble -> localhost | |                 |
2026-02-16 20:45:50.321865 | ubuntu-noble -> localhost | |..               |
2026-02-16 20:45:50.321893 | ubuntu-noble -> localhost | |*.               |
2026-02-16 20:45:50.321921 | ubuntu-noble -> localhost | |B. ..o..         |
2026-02-16 20:45:50.321948 | ubuntu-noble -> localhost | |=.*.==+.E        |
2026-02-16 20:45:50.321976 | ubuntu-noble -> localhost | |.O.+o=*..        |
2026-02-16 20:45:50.322003 | ubuntu-noble -> localhost | |*.+..=.=         |
2026-02-16 20:45:50.322029 | ubuntu-noble -> localhost | |+Bo o =..        |
2026-02-16 20:45:50.322059 | ubuntu-noble -> localhost | |X=.o . o.        |
2026-02-16 20:45:50.322087 | ubuntu-noble -> localhost | +----[SHA256]-----+
2026-02-16 20:45:50.322151 | ubuntu-noble -> localhost | ok: Runtime: 0:00:01.488106
2026-02-16 20:45:50.334896 | 
2026-02-16 20:45:50.335067 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 20:45:50.369139 | ubuntu-noble | ok
2026-02-16 20:45:50.381692 | ubuntu-noble | included: /var/lib/zuul/builds/cea857c3c19149a7abb9d132bf64eada/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 20:45:50.389523 | 
2026-02-16 20:45:50.389582 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 20:45:50.414185 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:45:50.425363 | 
2026-02-16 20:45:50.425466 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 20:45:50.957849 | ubuntu-noble | changed
2026-02-16 20:45:50.965440 | 
2026-02-16 20:45:50.965529 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 20:45:51.183284 | ubuntu-noble | ok
2026-02-16 20:45:51.189051 | 
2026-02-16 20:45:51.189141 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 20:45:51.727104 | ubuntu-noble | changed
2026-02-16 20:45:51.736729 | 
2026-02-16 20:45:51.737065 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 20:45:52.278068 | ubuntu-noble | changed
2026-02-16 20:45:52.286943 | 
2026-02-16 20:45:52.287037 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 20:45:52.312107 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:45:52.321932 | 
2026-02-16 20:45:52.322019 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 20:45:52.762627 | ubuntu-noble -> localhost | changed
2026-02-16 20:45:52.779633 | 
2026-02-16 20:45:52.779729 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 20:45:53.056753 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/cea857c3c19149a7abb9d132bf64eada/work/cea857c3c19149a7abb9d132bf64eada_id_rsa (zuul-build-sshkey)
2026-02-16 20:45:53.057043 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.014877
2026-02-16 20:45:53.065564 | 
2026-02-16 20:45:53.065625 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 20:45:53.424867 | ubuntu-noble | ok
2026-02-16 20:45:53.431883 | 
2026-02-16 20:45:53.431947 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 20:45:53.457701 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:45:53.476006 | 
2026-02-16 20:45:53.476073 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 20:45:53.843094 | ubuntu-noble | ok
2026-02-16 20:45:53.852681 | 
2026-02-16 20:45:53.852748 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 20:45:54.891977 | ubuntu-noble | Output suppressed because no_log was given
2026-02-16 20:45:54.902720 | 
2026-02-16 20:45:54.902787 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 20:45:55.137067 | ubuntu-noble | ok: "logs"
2026-02-16 20:45:55.137422 | ubuntu-noble | ok: All items complete
2026-02-16 20:45:55.137467 | 
2026-02-16 20:45:55.402861 | ubuntu-noble | ok: "artifacts"
2026-02-16 20:45:55.559757 | ubuntu-noble | ok: "docs"
2026-02-16 20:45:55.575709 | 
2026-02-16 20:45:55.575865 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 20:45:55.821474 | ubuntu-noble | changed: "logs"
2026-02-16 20:45:56.012363 | ubuntu-noble | changed: "artifacts"
2026-02-16 20:45:56.228197 | ubuntu-noble | changed: "docs"
2026-02-16 20:45:56.248062 | 
2026-02-16 20:45:56.248179 | PLAY RECAP
2026-02-16 20:45:56.248240 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 20:45:56.248281 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 20:45:56.248406 | 
2026-02-16 20:45:56.381175 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 20:45:56.382629 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-02-16 20:45:56.926051 | 
2026-02-16 20:45:56.926200 | PLAY [all]
2026-02-16 20:45:56.937855 | 
2026-02-16 20:45:56.937925 | TASK [Install binary dependencies]
2026-02-16 20:45:56.991142 | ubuntu-noble | ok
2026-02-16 20:45:57.012364 | 
2026-02-16 20:45:57.012445 | TASK [bindep : Include find tasks]
2026-02-16 20:45:57.048169 | ubuntu-noble | ok
2026-02-16 20:45:57.058544 | ubuntu-noble | included: /var/lib/zuul/builds/cea857c3c19149a7abb9d132bf64eada/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-02-16 20:45:57.064650 | 
2026-02-16 20:45:57.064708 | TASK [bindep : Look for bindep.txt]
2026-02-16 20:45:57.454544 | ubuntu-noble | ok
2026-02-16 20:45:57.461223 | 
2026-02-16 20:45:57.461291 | TASK [bindep : Define bindep_file fact]
2026-02-16 20:45:57.486570 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:45:57.496565 | 
2026-02-16 20:45:57.496632 | TASK [bindep : Look for other-requirements.txt]
2026-02-16 20:45:57.736050 | ubuntu-noble | ok
2026-02-16 20:45:57.743298 | 
2026-02-16 20:45:57.743396 | TASK [bindep : Define bindep_file fact]
2026-02-16 20:45:57.770429 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:45:57.780159 | 
2026-02-16 20:45:57.780249 | TASK [bindep : Look for bindep fallback file]
2026-02-16 20:45:57.806099 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:45:57.818727 | 
2026-02-16 20:45:57.818798 | TASK [bindep : Define bindep_file fact]
2026-02-16 20:45:57.844636 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:45:57.854773 | 
2026-02-16 20:45:57.854860 | TASK [bindep : Include bindep tasks]
2026-02-16 20:45:57.880074 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:45:57.890441 | 
2026-02-16 20:45:57.890533 | TASK [bindep : Include install tasks]
2026-02-16 20:45:57.913520 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:45:57.924135 | 
2026-02-16 20:45:57.924209 | LOOP [bindep : Include package tasks]
2026-02-16 20:45:57.985427 | 
2026-02-16 20:45:57.985638 | TASK [Run test-setup role]
2026-02-16 20:45:58.012836 | ubuntu-noble | ok
2026-02-16 20:45:58.041124 | 
2026-02-16 20:45:58.041281 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-02-16 20:45:58.271576 | ubuntu-noble | ok
2026-02-16 20:45:58.278292 | 
2026-02-16 20:45:58.278359 | TASK [test-setup : Run tools/test-setup.sh]
2026-02-16 20:45:58.810211 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:45:58.822023 | 
2026-02-16 20:45:58.822073 | PLAY RECAP
2026-02-16 20:45:58.822121 | ubuntu-noble | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-02-16 20:45:58.822142 | 
2026-02-16 20:45:58.942843 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-02-16 20:45:58.945353 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master]
2026-02-16 20:45:59.526395 | 
2026-02-16 20:45:59.526534 | PLAY [all]
2026-02-16 20:45:59.539964 | 
2026-02-16 20:45:59.540047 | TASK [ensure-python : Validate python_version value]
2026-02-16 20:45:59.565130 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:45:59.577485 | 
2026-02-16 20:45:59.577660 | TASK [ensure-python : Install specified version of python interpreter and development files (DEB)]
2026-02-16 20:45:59.602578 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:45:59.613036 | 
2026-02-16 20:45:59.613134 | TASK [ensure-python : Pull in venv package]
2026-02-16 20:45:59.638611 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:45:59.648648 | 
2026-02-16 20:45:59.648718 | TASK [ensure-python : Set default RPM package name]
2026-02-16 20:45:59.673155 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:45:59.684902 | 
2026-02-16 20:45:59.685205 | TASK [ensure-python : Set RPM package name for CentOS/RHEL 9/10]
2026-02-16 20:45:59.710218 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:45:59.719311 | 
2026-02-16 20:45:59.719400 | TASK [ensure-python : Install RPM package]
2026-02-16 20:45:59.744242 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:45:59.754042 | 
2026-02-16 20:45:59.754137 | TASK [ensure-python : Install python using pyenv]
2026-02-16 20:45:59.779098 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:45:59.788833 | 
2026-02-16 20:45:59.788925 | TASK [ensure-python : Activate python using stow]
2026-02-16 20:45:59.814917 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:45:59.830275 | 
2026-02-16 20:45:59.830339 | TASK [Install pip]
2026-02-16 20:45:59.853973 | ubuntu-noble | ok
2026-02-16 20:45:59.876268 | 
2026-02-16 20:45:59.876330 | TASK [ensure-pip : Check if pip is installed]
2026-02-16 20:46:00.413106 | ubuntu-noble | ok: Runtime: 0:00:00.005795
2026-02-16 20:46:00.418711 | 
2026-02-16 20:46:00.418773 | LOOP [ensure-pip : Install pip from packages]
2026-02-16 20:46:00.456186 | ubuntu-noble | ok: "/var/lib/zuul/builds/cea857c3c19149a7abb9d132bf64eada/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/Debian.yaml"
2026-02-16 20:46:00.476867 | ubuntu-noble | included: /var/lib/zuul/builds/cea857c3c19149a7abb9d132bf64eada/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/Debian.yaml
2026-02-16 20:46:00.485649 | 
2026-02-16 20:46:00.485711 | TASK [ensure-pip : Update package lists]
2026-02-16 20:46:06.987867 | ubuntu-noble | changed
2026-02-16 20:46:06.995061 | 
2026-02-16 20:46:06.995144 | TASK [ensure-pip : Install Python 3 pip]
2026-02-16 20:46:14.235305 | ubuntu-noble | changed
2026-02-16 20:46:14.242684 | 
2026-02-16 20:46:14.242748 | TASK [ensure-pip : Install Python 2 pip]
2026-02-16 20:46:14.267562 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:46:14.278995 | 
2026-02-16 20:46:14.279094 | TASK [ensure-pip : Ensure setuptools]
2026-02-16 20:46:14.304914 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:46:14.315469 | 
2026-02-16 20:46:14.315572 | TASK [ensure-pip : Check for ensurepip module]
2026-02-16 20:46:14.605498 | ubuntu-noble | usage: python -m ensurepip [-h] [--version] [-v] [-U] [--user] [--root ROOT]
2026-02-16 20:46:14.605663 | ubuntu-noble |                            [--altinstall] [--default-pip]
2026-02-16 20:46:14.605679 | ubuntu-noble |
2026-02-16 20:46:14.605689 | ubuntu-noble | options:
2026-02-16 20:46:14.605700 | ubuntu-noble |   -h, --help     show this help message and exit
2026-02-16 20:46:14.605715 | ubuntu-noble |   --version      Show the version of pip that is bundled with this Python.
2026-02-16 20:46:14.605729 | ubuntu-noble |   -v, --verbose  Give more output. Option is additive, and can be used up to 3
2026-02-16 20:46:14.605744 | ubuntu-noble |                  times.
2026-02-16 20:46:14.605756 | ubuntu-noble |   -U, --upgrade  Upgrade pip and dependencies, even if already installed.
2026-02-16 20:46:14.605765 | ubuntu-noble |   --user         Install using the user scheme.
2026-02-16 20:46:14.605774 | ubuntu-noble |   --root ROOT    Install everything relative to this alternate root directory.
2026-02-16 20:46:14.605783 | ubuntu-noble |   --altinstall   Make an alternate install, installing only the X.Y versioned
2026-02-16 20:46:14.605792 | ubuntu-noble |                  scripts (Default: pipX, pipX.Y).
2026-02-16 20:46:14.605800 | ubuntu-noble |   --default-pip  Make a default pip install, installing the unqualified pip in
2026-02-16 20:46:14.605808 | ubuntu-noble |                  addition to the versioned scripts.
2026-02-16 20:46:14.858081 | ubuntu-noble | ok: Runtime: 0:00:00.066316
2026-02-16 20:46:14.866228 | 
2026-02-16 20:46:14.866318 | TASK [ensure-pip : Ensure python3-venv]
2026-02-16 20:46:14.892375 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:46:14.905527 | 
2026-02-16 20:46:14.905668 | TASK [ensure-pip : Install pip from source]
2026-02-16 20:46:14.930503 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:46:14.983168 | 
2026-02-16 20:46:14.983332 | TASK [ensure-pip : Probe for venv python full path]
2026-02-16 20:46:15.226330 | ubuntu-noble | /usr/bin/python3
2026-02-16 20:46:15.521496 | ubuntu-noble | ok: Runtime: 0:00:00.005264
2026-02-16 20:46:15.528939 | 
2026-02-16 20:46:15.529045 | TASK [ensure-pip : Set host default]
2026-02-16 20:46:15.584260 | ubuntu-noble | ok
2026-02-16 20:46:15.592120 | 
2026-02-16 20:46:15.592216 | TASK [ensure-pip : Set ensure_pip_virtualenv_command]
2026-02-16 20:46:15.650483 | ubuntu-noble | ok
2026-02-16 20:46:15.661920 | 
2026-02-16 20:46:15.662004 | TASK [ensure-tox : Check if tox is installed]
2026-02-16 20:46:16.205506 | ubuntu-noble | ok: Runtime: 0:00:00.007160
2026-02-16 20:46:16.211990 | 
2026-02-16 20:46:16.212079 | TASK [ensure-tox : Export preinstalled tox_exectuable]
2026-02-16 20:46:16.237697 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:46:16.250515 | 
2026-02-16 20:46:16.250584 | TASK [ensure-tox : Create local venv]
2026-02-16 20:46:19.787980 | ubuntu-noble | ok: Runtime: 0:00:03.177279
2026-02-16 20:46:19.795435 | 
2026-02-16 20:46:19.795526 | TASK [ensure-tox : Install tox to local venv]
2026-02-16 20:46:20.556622 | ubuntu-noble | Collecting tox
2026-02-16 20:46:20.601195 | ubuntu-noble |   Downloading tox-4.36.0-py3-none-any.whl.metadata (3.6 kB)
2026-02-16 20:46:20.668948 | ubuntu-noble | Collecting cachetools>=7.0.1 (from tox)
2026-02-16 20:46:20.672640 | ubuntu-noble |   Downloading cachetools-7.0.1-py3-none-any.whl.metadata (5.6 kB)
2026-02-16 20:46:20.712357 | ubuntu-noble | Collecting chardet>=5.2 (from tox)
2026-02-16 20:46:20.715181 | ubuntu-noble |   Downloading chardet-5.2.0-py3-none-any.whl.metadata (3.4 kB)
2026-02-16 20:46:20.760773 | ubuntu-noble | Collecting colorama>=0.4.6 (from tox)
2026-02-16 20:46:20.763854 | ubuntu-noble |   Downloading colorama-0.4.6-py2.py3-none-any.whl.metadata (17 kB)
2026-02-16 20:46:20.825885 | ubuntu-noble | Collecting filelock>=3.24 (from tox)
2026-02-16 20:46:20.829027 | ubuntu-noble |   Downloading filelock-3.24.2-py3-none-any.whl.metadata (2.0 kB)
2026-02-16 20:46:20.880753 | ubuntu-noble | Collecting packaging>=26 (from tox)
2026-02-16 20:46:20.884320 | ubuntu-noble |   Downloading packaging-26.0-py3-none-any.whl.metadata (3.3 kB)
2026-02-16 20:46:20.940080 | ubuntu-noble | Collecting platformdirs>=4.9.1 (from tox)
2026-02-16 20:46:20.943237 | ubuntu-noble |   Downloading platformdirs-4.9.2-py3-none-any.whl.metadata (4.7 kB)
2026-02-16 20:46:20.991023 | ubuntu-noble | Collecting pluggy>=1.6 (from tox)
2026-02-16 20:46:20.994279 | ubuntu-noble |   Downloading pluggy-1.6.0-py3-none-any.whl.metadata (4.8 kB)
2026-02-16 20:46:21.037349 | ubuntu-noble | Collecting pyproject-api>=1.10 (from tox)
2026-02-16 20:46:21.041811 | ubuntu-noble |   Downloading pyproject_api-1.10.0-py3-none-any.whl.metadata (2.7 kB)
2026-02-16 20:46:21.159334 | ubuntu-noble | Collecting virtualenv>=20.36.1 (from tox)
2026-02-16 20:46:21.162551 | ubuntu-noble |   Downloading virtualenv-20.37.0-py3-none-any.whl.metadata (5.1 kB)
2026-02-16 20:46:21.252939 | ubuntu-noble | Collecting distlib<1,>=0.3.7 (from virtualenv>=20.36.1->tox)
2026-02-16 20:46:21.256871 | ubuntu-noble |   Downloading distlib-0.4.0-py2.py3-none-any.whl.metadata (5.2 kB)
2026-02-16 20:46:21.293394 | ubuntu-noble | Downloading tox-4.36.0-py3-none-any.whl (179 kB)
2026-02-16 20:46:21.313646 | ubuntu-noble |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 179.1/179.1 kB 10.9 MB/s eta 0:00:00
2026-02-16 20:46:21.316659 | ubuntu-noble | Downloading cachetools-7.0.1-py3-none-any.whl (13 kB)
2026-02-16 20:46:21.327806 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl (199 kB)
2026-02-16 20:46:21.341969 | ubuntu-noble |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 16.5 MB/s eta 0:00:00
2026-02-16 20:46:21.345675 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB)
2026-02-16 20:46:21.359052 | ubuntu-noble | Downloading filelock-3.24.2-py3-none-any.whl (24 kB)
2026-02-16 20:46:21.374414 | ubuntu-noble | Downloading packaging-26.0-py3-none-any.whl (74 kB)
2026-02-16 20:46:21.388835 | ubuntu-noble |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 6.0 MB/s eta 0:00:00
2026-02-16 20:46:21.393312 | ubuntu-noble | Downloading platformdirs-4.9.2-py3-none-any.whl (21 kB)
2026-02-16 20:46:21.405594 | ubuntu-noble | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB)
2026-02-16 20:46:21.419096 | ubuntu-noble | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB)
2026-02-16 20:46:21.433155 | ubuntu-noble | Downloading virtualenv-20.37.0-py3-none-any.whl (5.8 MB)
2026-02-16 20:46:21.522832 | ubuntu-noble |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.8/5.8 MB 69.8 MB/s eta 0:00:00
2026-02-16 20:46:21.526508 | ubuntu-noble | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB)
2026-02-16 20:46:21.546987 | ubuntu-noble |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 31.3 MB/s eta 0:00:00
2026-02-16 20:46:21.616597 | ubuntu-noble | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox
2026-02-16 20:46:22.476582 | 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 20:46:22.840198 | ubuntu-noble | ok: Runtime: 0:00:02.539390
2026-02-16 20:46:22.846465 | 
2026-02-16 20:46:22.846534 | TASK [ensure-tox : Export installed tox_executable path]
2026-02-16 20:46:22.885581 | ubuntu-noble | ok
2026-02-16 20:46:22.891570 | 
2026-02-16 20:46:22.891636 | TASK [ensure-tox : Output tox version]
2026-02-16 20:46:23.342002 | 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 20:46:23.356268 | ubuntu-noble | 4.36.0 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py
2026-02-16 20:46:23.433342 | ubuntu-noble | ok: Runtime: 0:00:00.273573
2026-02-16 20:46:23.438782 | 
2026-02-16 20:46:23.439311 | TASK [ensure-tox : Make global symlink]
2026-02-16 20:46:23.464837 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:46:23.477174 | 
2026-02-16 20:46:23.477229 | PLAY RECAP
2026-02-16 20:46:23.477277 | ubuntu-noble | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0
2026-02-16 20:46:23.477299 | 
2026-02-16 20:46:23.615012 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master]
2026-02-16 20:46:23.617669 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/tox/pre.yml@main]
2026-02-16 20:46:24.205842 | 
2026-02-16 20:46:24.205981 | PLAY [all]
2026-02-16 20:46:24.217911 | 
2026-02-16 20:46:24.217992 | TASK [ensure-rust : Use rustup]
2026-02-16 20:46:24.252682 | ubuntu-noble | ok
2026-02-16 20:46:24.260200 | ubuntu-noble | included: /var/lib/zuul/builds/cea857c3c19149a7abb9d132bf64eada/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 20:46:24.266407 | 
2026-02-16 20:46:24.266485 | TASK [ensure-rust : Install Rust]
2026-02-16 20:46:24.633352 | ubuntu-noble | /bin/bash: line 2: curl: command not found
2026-02-16 20:46:24.805530 | ubuntu-noble | ERROR
2026-02-16 20:46:24.805855 | ubuntu-noble | {
2026-02-16 20:46:24.805922 | ubuntu-noble |   "delta": "0:00:00.006840",
2026-02-16 20:46:24.805973 | ubuntu-noble |   "end": "2026-02-16 20:46:24.634228",
2026-02-16 20:46:24.806017 | ubuntu-noble |   "msg": "non-zero return code",
2026-02-16 20:46:24.806057 | ubuntu-noble |   "rc": 127,
2026-02-16 20:46:24.806097 | ubuntu-noble |   "start": "2026-02-16 20:46:24.627388"
2026-02-16 20:46:24.806199 | ubuntu-noble | }
failure
2026-02-16 20:46:24.808693 | 
2026-02-16 20:46:24.808744 | PLAY RECAP
2026-02-16 20:46:24.808796 | ubuntu-noble | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 20:46:24.808818 | 
2026-02-16 20:46:24.919553 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/tox/pre.yml@main]
2026-02-16 20:46:24.922665 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master]
2026-02-16 20:46:25.479132 | 
2026-02-16 20:46:25.479957 | PLAY [all]
2026-02-16 20:46:25.492143 | 
2026-02-16 20:46:25.492220 | TASK [fetch-tox-output : Set tox log path for multiple nodes]
2026-02-16 20:46:25.537333 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:46:25.549025 | 
2026-02-16 20:46:25.549190 | TASK [fetch-tox-output : Set tox log path for single node]
2026-02-16 20:46:25.595289 | ubuntu-noble | ok
2026-02-16 20:46:25.603133 | 
2026-02-16 20:46:25.603201 | TASK [fetch-tox-output : Ensure local tox dir]
2026-02-16 20:46:26.011491 | ubuntu-noble -> localhost | changed
2026-02-16 20:46:26.018958 | 
2026-02-16 20:46:26.019032 | TASK [fetch-tox-output : Ensure zuul-output tox dir]
2026-02-16 20:46:26.064072 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:46:26.069935 | 
2026-02-16 20:46:26.070003 | TASK [fetch-tox-output : Set envlist fact]
2026-02-16 20:46:26.109333 | ubuntu-noble | ok
2026-02-16 20:46:26.115004 | 
2026-02-16 20:46:26.115070 | TASK [fetch-tox-output : Get tox version]
2026-02-16 20:46:26.689156 | 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 20:46:26.703052 | ubuntu-noble | 4.36.0 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py
2026-02-16 20:46:27.154207 | ubuntu-noble | ok: Runtime: 0:00:00.248952
2026-02-16 20:46:27.162155 | 
2026-02-16 20:46:27.162269 | TASK [fetch-tox-output : Find all default environments]
2026-02-16 20:46:27.691411 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:46:27.703269 | 
2026-02-16 20:46:27.703342 | TASK [fetch-tox-output : Set envlist fact]
2026-02-16 20:46:27.728060 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:46:27.740146 | 
2026-02-16 20:46:27.740281 | TASK [fetch-tox-output : Find all default environments]
2026-02-16 20:46:28.271235 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:46:28.281992 | 
2026-02-16 20:46:28.282100 | TASK [fetch-tox-output : Set envlist fact]
2026-02-16 20:46:28.307598 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:46:28.318897 | 
2026-02-16 20:46:28.318996 | LOOP [fetch-tox-output : Copy tox logs]
2026-02-16 20:46:28.362415 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:46:28.385906 | 
2026-02-16 20:46:28.392397 | LOOP [fetch-tox-output : Collect tox logs]
2026-02-16 20:46:29.183267 | ubuntu-noble | ok:
2026-02-16 20:46:29.183475 | 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 20:46:29.183531 | 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 20:46:29.183564 | ubuntu-noble | rsync: [Receiver] write error: Broken pipe (32)
2026-02-16 20:46:29.183601 | 
2026-02-16 20:46:29.202283 | 
2026-02-16 20:46:29.202411 | PLAY RECAP
2026-02-16 20:46:29.202477 | ubuntu-noble | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0
2026-02-16 20:46:29.202503 | 
2026-02-16 20:46:29.407822 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master]
2026-02-16 20:46:29.413421 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-02-16 20:46:29.991803 | 
2026-02-16 20:46:30.063710 | PLAY [all]
2026-02-16 20:46:30.082640 | 
2026-02-16 20:46:30.082732 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-02-16 20:46:30.270694 | ubuntu-noble | changed: non-zero return code
2026-02-16 20:46:30.276859 | 
2026-02-16 20:46:30.276943 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-02-16 20:46:30.302815 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:46:30.315344 | 
2026-02-16 20:46:30.315457 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-02-16 20:46:30.353021 | 
2026-02-16 20:46:30.353229 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-02-16 20:46:30.385759 | 
2026-02-16 20:46:30.385935 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-02-16 20:46:30.411311 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:46:30.421936 | 
2026-02-16 20:46:30.422052 | LOOP [fetch-subunit-output : Generate subunit file]
2026-02-16 20:46:30.457212 | 
2026-02-16 20:46:30.457385 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-02-16 20:46:30.482238 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:46:30.495165 | 
2026-02-16 20:46:30.495234 | TASK [fetch-subunit-output : Remove the temporary file]
2026-02-16 20:46:30.519600 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:46:30.530284 | 
2026-02-16 20:46:30.530385 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-02-16 20:46:30.556096 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:46:30.568120 | 
2026-02-16 20:46:30.568193 | PLAY RECAP
2026-02-16 20:46:30.568258 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-02-16 20:46:30.568291 | 
2026-02-16 20:46:30.702820 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-02-16 20:46:30.704509 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 20:46:31.311575 | 
2026-02-16 20:46:31.318137 | PLAY [all]
2026-02-16 20:46:31.332072 | 
2026-02-16 20:46:31.332146 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 20:46:31.377636 | ubuntu-noble | skipping: Conditional result was False
2026-02-16 20:46:31.386875 | 
2026-02-16 20:46:31.386951 | TASK [fetch-output : Set log path for single node]
2026-02-16 20:46:31.429104 | ubuntu-noble | ok
2026-02-16 20:46:31.463343 | 
2026-02-16 20:46:31.463436 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 20:46:31.946027 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/cea857c3c19149a7abb9d132bf64eada/work/logs"
2026-02-16 20:46:32.155577 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/cea857c3c19149a7abb9d132bf64eada/work/artifacts"
2026-02-16 20:46:32.393911 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/cea857c3c19149a7abb9d132bf64eada/work/docs"
2026-02-16 20:46:32.411734 | 
2026-02-16 20:46:32.411839 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 20:46:33.083251 | ubuntu-noble | changed: .d..t...... ./
2026-02-16 20:46:33.083545 | ubuntu-noble | changed: All items complete
2026-02-16 20:46:33.083584 | 
2026-02-16 20:46:33.567302 | ubuntu-noble | changed: .d..t...... ./
2026-02-16 20:46:34.007291 | ubuntu-noble | changed: .d..t...... ./
2026-02-16 20:46:34.037454 | 
2026-02-16 20:46:34.037706 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 20:46:34.487945 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.008583
2026-02-16 20:46:34.764627 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.007655
2026-02-16 20:46:34.787507 | 
2026-02-16 20:46:34.787652 | PLAY [all]
2026-02-16 20:46:34.795062 | 
2026-02-16 20:46:34.795128 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 20:46:35.259571 | ubuntu-noble | changed
2026-02-16 20:46:35.268166 | 
2026-02-16 20:46:35.268214 | PLAY RECAP
2026-02-16 20:46:35.268260 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 20:46:35.268282 | 
2026-02-16 20:46:35.433205 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 20:46:35.435824 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 20:46:35.994790 | 
2026-02-16 20:46:36.029039 | PLAY [localhost]
2026-02-16 20:46:36.064485 | 
2026-02-16 20:46:36.064788 | TASK [Generate Zuul manifest]
2026-02-16 20:46:36.090458 | localhost | ok
2026-02-16 20:46:36.116848 | 
2026-02-16 20:46:36.116969 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 20:46:36.451199 | localhost | changed
2026-02-16 20:46:36.462920 | 
2026-02-16 20:46:36.462992 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 20:46:36.490897 | localhost | ok
2026-02-16 20:46:36.497190 | 
2026-02-16 20:46:36.497250 | TASK [Upload logs]
2026-02-16 20:46:36.515476 | localhost | ok
2026-02-16 20:46:36.586003 | 
2026-02-16 20:46:36.586145 | TASK [Set zuul-log-path fact]
2026-02-16 20:46:36.604710 | localhost | ok
2026-02-16 20:46:36.615509 | 
2026-02-16 20:46:36.615576 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 20:46:36.643604 | localhost | ok
2026-02-16 20:46:36.650318 | 
2026-02-16 20:46:36.650383 | TASK [upload-logs : Create log directories]
2026-02-16 20:46:37.053200 | localhost | changed
2026-02-16 20:46:37.058737 | 
2026-02-16 20:46:37.058821 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 20:46:37.481254 | localhost -> localhost | ok: Runtime: 0:00:00.004618
2026-02-16 20:46:37.486170 | 
2026-02-16 20:46:37.486240 | TASK [upload-logs : Upload logs to log server]
2026-02-16 20:46:37.947494 | localhost | Output suppressed because no_log was given
2026-02-16 20:46:37.952274 | 
2026-02-16 20:46:37.952359 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 20:46:37.993403 | localhost | skipping: Conditional result was False
2026-02-16 20:46:37.999663 | localhost | skipping: Conditional result was False
2026-02-16 20:46:38.014179 | 
2026-02-16 20:46:38.014291 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 20:46:38.055145 | localhost | skipping: Conditional result was False
2026-02-16 20:46:38.055408 | 
2026-02-16 20:46:38.058779 | localhost | skipping: Conditional result was False
2026-02-16 20:46:38.064345 | 
2026-02-16 20:46:38.064422 | LOOP [upload-logs : Upload console log and json output]