2026-02-16 17:51:17.893215 | Job console starting 2026-02-16 17:51:18.009919 | Updating git repos 2026-02-16 17:51:18.548369 | Cloning repos into workspace 2026-02-16 17:51:18.939372 | Restoring repo states 2026-02-16 17:51:18.987130 | Merging changes 2026-02-16 17:51:19.679207 | Checking out repos 2026-02-16 17:51:20.002045 | Preparing playbooks 2026-02-16 17:51:21.827608 | Running Ansible setup 2026-02-16 17:51:25.430261 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 17:51:25.995422 | 2026-02-16 17:51:25.995562 | PLAY [localhost] 2026-02-16 17:51:26.002966 | 2026-02-16 17:51:26.003034 | TASK [Gathering Facts] 2026-02-16 17:51:27.031684 | localhost | ok 2026-02-16 17:51:27.038640 | 2026-02-16 17:51:27.038721 | TASK [Setup log path fact] 2026-02-16 17:51:27.057028 | localhost | ok 2026-02-16 17:51:27.067885 | 2026-02-16 17:51:27.067951 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 17:51:27.103486 | localhost | ok 2026-02-16 17:51:27.112569 | 2026-02-16 17:51:27.112647 | TASK [emit-job-header : Print job information] 2026-02-16 17:51:27.166035 | # Job Information 2026-02-16 17:51:27.166274 | Ansible Version: 2.16.15 2026-02-16 17:51:27.166322 | Job: magnum-cluster-api-tox-unit 2026-02-16 17:51:27.166356 | Pipeline: check 2026-02-16 17:51:27.166385 | Executor: 3a2793d2bd32 2026-02-16 17:51:27.166415 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884 2026-02-16 17:51:27.166449 | Event ID: ed3be480-0b5f-11f1-9b41-a55389b20acd 2026-02-16 17:51:27.170486 | 2026-02-16 17:51:27.170578 | LOOP [emit-job-header : Print node information] 2026-02-16 17:51:27.266335 | localhost | ok: 2026-02-16 17:51:27.266509 | localhost | # Node Information 2026-02-16 17:51:27.266537 | localhost | Inventory Hostname: ubuntu-noble 2026-02-16 17:51:27.266559 | localhost | Hostname: np0000155645 2026-02-16 17:51:27.266579 | localhost | Username: zuul 2026-02-16 17:51:27.266602 | localhost | Distro: Ubuntu 24.04 2026-02-16 17:51:27.266621 | localhost | Provider: yul1 2026-02-16 17:51:27.266641 | localhost | Region: ca-ymq-1 2026-02-16 17:51:27.266660 | localhost | Label: ubuntu-noble 2026-02-16 17:51:27.266677 | localhost | Product Name: OpenStack Nova 2026-02-16 17:51:27.266698 | localhost | Interface IP: 199.204.45.74 2026-02-16 17:51:27.279029 | 2026-02-16 17:51:27.279212 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-16 17:51:27.769797 | localhost -> localhost | changed 2026-02-16 17:51:27.780377 | 2026-02-16 17:51:27.780530 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-16 17:51:28.856744 | localhost -> localhost | changed 2026-02-16 17:51:28.867075 | 2026-02-16 17:51:28.867176 | PLAY [all] 2026-02-16 17:51:28.880510 | 2026-02-16 17:51:28.880795 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-16 17:51:29.139579 | ubuntu-noble -> localhost | ok 2026-02-16 17:51:29.147506 | 2026-02-16 17:51:29.147571 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-16 17:51:29.177356 | ubuntu-noble | ok 2026-02-16 17:51:29.193652 | ubuntu-noble | included: /var/lib/zuul/builds/db079f5065a740f3a100980609d7c97f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-16 17:51:29.199957 | 2026-02-16 17:51:29.200046 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-16 17:51:30.193893 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2026-02-16 17:51:30.194096 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/db079f5065a740f3a100980609d7c97f/work/db079f5065a740f3a100980609d7c97f_id_rsa 2026-02-16 17:51:30.194126 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/db079f5065a740f3a100980609d7c97f/work/db079f5065a740f3a100980609d7c97f_id_rsa.pub 2026-02-16 17:51:30.194148 | ubuntu-noble -> localhost | The key fingerprint is: 2026-02-16 17:51:30.194169 | ubuntu-noble -> localhost | SHA256:Yp69rqpeU8cR+MKXQKXZoJegY7CBv04yP/g0mI9YgsI zuul-build-sshkey 2026-02-16 17:51:30.194202 | ubuntu-noble -> localhost | The key's randomart image is: 2026-02-16 17:51:30.194223 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2026-02-16 17:51:30.194247 | ubuntu-noble -> localhost | |+ ..ooo | 2026-02-16 17:51:30.194268 | ubuntu-noble -> localhost | |.+ . oo* . | 2026-02-16 17:51:30.194287 | ubuntu-noble -> localhost | |..+ ..=oo. | 2026-02-16 17:51:30.194306 | ubuntu-noble -> localhost | | ... .o.+. | 2026-02-16 17:51:30.194325 | ubuntu-noble -> localhost | | . +oS | 2026-02-16 17:51:30.194343 | ubuntu-noble -> localhost | |=oo + = | 2026-02-16 17:51:30.194361 | ubuntu-noble -> localhost | |*E+ o o . | 2026-02-16 17:51:30.194380 | ubuntu-noble -> localhost | |+*+o . . | 2026-02-16 17:51:30.194402 | ubuntu-noble -> localhost | |oo=o...oo | 2026-02-16 17:51:30.194422 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2026-02-16 17:51:30.194472 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.402550 2026-02-16 17:51:30.201435 | 2026-02-16 17:51:30.201503 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-16 17:51:30.237638 | ubuntu-noble | ok 2026-02-16 17:51:30.446525 | ubuntu-noble | included: /var/lib/zuul/builds/db079f5065a740f3a100980609d7c97f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-16 17:51:30.457091 | 2026-02-16 17:51:30.457180 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-16 17:51:30.482113 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:51:30.493664 | 2026-02-16 17:51:30.493796 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-16 17:51:31.068812 | ubuntu-noble | changed 2026-02-16 17:51:31.077207 | 2026-02-16 17:51:31.077280 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-16 17:51:31.309605 | ubuntu-noble | ok 2026-02-16 17:51:31.317481 | 2026-02-16 17:51:31.466568 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-16 17:51:32.000071 | ubuntu-noble | changed 2026-02-16 17:51:32.041965 | 2026-02-16 17:51:32.042081 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-16 17:51:32.577067 | ubuntu-noble | changed 2026-02-16 17:51:32.657063 | 2026-02-16 17:51:32.657174 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-16 17:51:32.682347 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:51:32.692123 | 2026-02-16 17:51:32.692214 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-16 17:51:33.114575 | ubuntu-noble -> localhost | changed 2026-02-16 17:51:33.129921 | 2026-02-16 17:51:33.130014 | TASK [add-build-sshkey : Add back temp key] 2026-02-16 17:51:33.526759 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/db079f5065a740f3a100980609d7c97f/work/db079f5065a740f3a100980609d7c97f_id_rsa (zuul-build-sshkey) 2026-02-16 17:51:33.527009 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.012000 2026-02-16 17:51:33.537034 | 2026-02-16 17:51:33.537100 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-16 17:51:33.877321 | ubuntu-noble | ok 2026-02-16 17:51:33.883562 | 2026-02-16 17:51:33.883631 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-16 17:51:33.910275 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:51:33.925785 | 2026-02-16 17:51:33.925853 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-16 17:51:34.295510 | ubuntu-noble | ok 2026-02-16 17:51:34.303349 | 2026-02-16 17:51:34.303414 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-16 17:51:35.329196 | ubuntu-noble | Output suppressed because no_log was given 2026-02-16 17:51:35.342175 | 2026-02-16 17:51:35.342244 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-16 17:51:35.580890 | ubuntu-noble | ok: "logs" 2026-02-16 17:51:35.581405 | ubuntu-noble | ok: All items complete 2026-02-16 17:51:35.581452 | 2026-02-16 17:51:35.830362 | ubuntu-noble | ok: "artifacts" 2026-02-16 17:51:35.965798 | ubuntu-noble | ok: "docs" 2026-02-16 17:51:35.974644 | 2026-02-16 17:51:35.974734 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-16 17:51:36.207487 | ubuntu-noble | changed: "logs" 2026-02-16 17:51:36.382549 | ubuntu-noble | changed: "artifacts" 2026-02-16 17:51:36.579740 | ubuntu-noble | changed: "docs" 2026-02-16 17:51:36.726589 | 2026-02-16 17:51:36.726743 | PLAY RECAP 2026-02-16 17:51:36.726808 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-16 17:51:36.726850 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-16 17:51:36.726882 | 2026-02-16 17:51:36.926712 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 17:51:36.931291 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-16 17:51:37.566116 | 2026-02-16 17:51:37.566338 | PLAY [all] 2026-02-16 17:51:37.580326 | 2026-02-16 17:51:37.580432 | TASK [Install binary dependencies] 2026-02-16 17:51:37.636852 | ubuntu-noble | ok 2026-02-16 17:51:37.657854 | 2026-02-16 17:51:37.657980 | TASK [bindep : Include find tasks] 2026-02-16 17:51:37.691512 | ubuntu-noble | ok 2026-02-16 17:51:37.703325 | ubuntu-noble | included: /var/lib/zuul/builds/db079f5065a740f3a100980609d7c97f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-02-16 17:51:37.710197 | 2026-02-16 17:51:37.710264 | TASK [bindep : Look for bindep.txt] 2026-02-16 17:51:38.080060 | ubuntu-noble | ok 2026-02-16 17:51:38.088592 | 2026-02-16 17:51:38.088662 | TASK [bindep : Define bindep_file fact] 2026-02-16 17:51:38.112848 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:51:38.124427 | 2026-02-16 17:51:38.124503 | TASK [bindep : Look for other-requirements.txt] 2026-02-16 17:51:38.343025 | ubuntu-noble | ok 2026-02-16 17:51:38.348260 | 2026-02-16 17:51:38.348329 | TASK [bindep : Define bindep_file fact] 2026-02-16 17:51:38.373535 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:51:38.383635 | 2026-02-16 17:51:38.383726 | TASK [bindep : Look for bindep fallback file] 2026-02-16 17:51:38.409588 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:51:38.422832 | 2026-02-16 17:51:38.422927 | TASK [bindep : Define bindep_file fact] 2026-02-16 17:51:38.448161 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:51:38.458438 | 2026-02-16 17:51:38.458535 | TASK [bindep : Include bindep tasks] 2026-02-16 17:51:38.483974 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:51:38.494954 | 2026-02-16 17:51:38.495045 | TASK [bindep : Include install tasks] 2026-02-16 17:51:38.520207 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:51:38.530854 | 2026-02-16 17:51:38.530958 | LOOP [bindep : Include package tasks] 2026-02-16 17:51:38.594770 | 2026-02-16 17:51:38.594910 | TASK [Run test-setup role] 2026-02-16 17:51:38.621611 | ubuntu-noble | ok 2026-02-16 17:51:38.638574 | 2026-02-16 17:51:38.638648 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-02-16 17:51:38.856022 | ubuntu-noble | ok 2026-02-16 17:51:38.862402 | 2026-02-16 17:51:38.862479 | TASK [test-setup : Run tools/test-setup.sh] 2026-02-16 17:51:39.392424 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:51:39.414817 | 2026-02-16 17:51:39.414886 | PLAY RECAP 2026-02-16 17:51:39.414950 | ubuntu-noble | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-16 17:51:39.414982 | 2026-02-16 17:51:39.572264 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-16 17:51:39.574666 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master] 2026-02-16 17:51:40.159507 | 2026-02-16 17:51:40.167512 | PLAY [all] 2026-02-16 17:51:40.187158 | 2026-02-16 17:51:40.187236 | TASK [ensure-python : Validate python_version value] 2026-02-16 17:51:40.211326 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:51:40.221983 | 2026-02-16 17:51:40.222092 | TASK [ensure-python : Install specified version of python interpreter and development files (DEB)] 2026-02-16 17:51:40.246747 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:51:40.259490 | 2026-02-16 17:51:40.259600 | TASK [ensure-python : Pull in venv package] 2026-02-16 17:51:40.285432 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:51:40.298031 | 2026-02-16 17:51:40.298127 | TASK [ensure-python : Set default RPM package name] 2026-02-16 17:51:40.332841 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:51:40.342924 | 2026-02-16 17:51:40.343017 | TASK [ensure-python : Set RPM package name for CentOS/RHEL 9/10] 2026-02-16 17:51:40.367259 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:51:40.379328 | 2026-02-16 17:51:40.379398 | TASK [ensure-python : Install RPM package] 2026-02-16 17:51:40.404456 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:51:40.413464 | 2026-02-16 17:51:40.413556 | TASK [ensure-python : Install python using pyenv] 2026-02-16 17:51:40.438497 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:51:40.449241 | 2026-02-16 17:51:40.449336 | TASK [ensure-python : Activate python using stow] 2026-02-16 17:51:40.483964 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:51:40.503438 | 2026-02-16 17:51:40.503505 | TASK [Install pip] 2026-02-16 17:51:40.524618 | ubuntu-noble | ok 2026-02-16 17:51:40.550306 | 2026-02-16 17:51:40.550384 | TASK [ensure-pip : Check if pip is installed] 2026-02-16 17:51:41.103842 | ubuntu-noble | ok: Runtime: 0:00:00.010291 2026-02-16 17:51:41.200660 | 2026-02-16 17:51:41.200769 | LOOP [ensure-pip : Install pip from packages] 2026-02-16 17:51:41.234813 | ubuntu-noble | ok: "/var/lib/zuul/builds/db079f5065a740f3a100980609d7c97f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/Debian.yaml" 2026-02-16 17:51:41.249887 | ubuntu-noble | included: /var/lib/zuul/builds/db079f5065a740f3a100980609d7c97f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/Debian.yaml 2026-02-16 17:51:41.259001 | 2026-02-16 17:51:41.259065 | TASK [ensure-pip : Update package lists] 2026-02-16 17:51:47.417185 | ubuntu-noble | changed 2026-02-16 17:51:47.424539 | 2026-02-16 17:51:47.424634 | TASK [ensure-pip : Install Python 3 pip] 2026-02-16 17:51:52.711592 | ubuntu-noble | changed 2026-02-16 17:51:52.726188 | 2026-02-16 17:51:52.726292 | TASK [ensure-pip : Install Python 2 pip] 2026-02-16 17:51:52.748774 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:51:52.765422 | 2026-02-16 17:51:52.765520 | TASK [ensure-pip : Ensure setuptools] 2026-02-16 17:51:52.792208 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:51:52.803829 | 2026-02-16 17:51:52.803919 | TASK [ensure-pip : Check for ensurepip module] 2026-02-16 17:51:53.105856 | ubuntu-noble | usage: python -m ensurepip [-h] [--version] [-v] [-U] [--user] [--root ROOT] 2026-02-16 17:51:53.105912 | ubuntu-noble | [--altinstall] [--default-pip] 2026-02-16 17:51:53.105919 | ubuntu-noble | 2026-02-16 17:51:53.105924 | ubuntu-noble | options: 2026-02-16 17:51:53.105929 | ubuntu-noble | -h, --help show this help message and exit 2026-02-16 17:51:53.105937 | ubuntu-noble | --version Show the version of pip that is bundled with this Python. 2026-02-16 17:51:53.105943 | ubuntu-noble | -v, --verbose Give more output. Option is additive, and can be used up to 3 2026-02-16 17:51:53.105950 | ubuntu-noble | times. 2026-02-16 17:51:53.105954 | ubuntu-noble | -U, --upgrade Upgrade pip and dependencies, even if already installed. 2026-02-16 17:51:53.105958 | ubuntu-noble | --user Install using the user scheme. 2026-02-16 17:51:53.105962 | ubuntu-noble | --root ROOT Install everything relative to this alternate root directory. 2026-02-16 17:51:53.105966 | ubuntu-noble | --altinstall Make an alternate install, installing only the X.Y versioned 2026-02-16 17:51:53.105970 | ubuntu-noble | scripts (Default: pipX, pipX.Y). 2026-02-16 17:51:53.105974 | ubuntu-noble | --default-pip Make a default pip install, installing the unqualified pip in 2026-02-16 17:51:53.105978 | ubuntu-noble | addition to the versioned scripts. 2026-02-16 17:51:53.343478 | ubuntu-noble | ok: Runtime: 0:00:00.064928 2026-02-16 17:51:53.352077 | 2026-02-16 17:51:53.352173 | TASK [ensure-pip : Ensure python3-venv] 2026-02-16 17:51:53.377311 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:51:53.389217 | 2026-02-16 17:51:53.389426 | TASK [ensure-pip : Install pip from source] 2026-02-16 17:51:53.411597 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:51:53.461271 | 2026-02-16 17:51:53.461451 | TASK [ensure-pip : Probe for venv python full path] 2026-02-16 17:51:53.683439 | ubuntu-noble | /usr/bin/python3 2026-02-16 17:51:53.999193 | ubuntu-noble | ok: Runtime: 0:00:00.004378 2026-02-16 17:51:54.004707 | 2026-02-16 17:51:54.004779 | TASK [ensure-pip : Set host default] 2026-02-16 17:51:54.070224 | ubuntu-noble | ok 2026-02-16 17:51:54.077499 | 2026-02-16 17:51:54.077562 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2026-02-16 17:51:54.134944 | ubuntu-noble | ok 2026-02-16 17:51:54.145799 | 2026-02-16 17:51:54.145865 | TASK [ensure-tox : Check if tox is installed] 2026-02-16 17:51:54.687006 | ubuntu-noble | ok: Runtime: 0:00:00.004479 2026-02-16 17:51:54.692493 | 2026-02-16 17:51:54.692572 | TASK [ensure-tox : Export preinstalled tox_exectuable] 2026-02-16 17:51:54.716889 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:51:54.727390 | 2026-02-16 17:51:54.727514 | TASK [ensure-tox : Create local venv] 2026-02-16 17:51:58.270075 | ubuntu-noble | ok: Runtime: 0:00:03.112535 2026-02-16 17:51:58.275959 | 2026-02-16 17:51:58.276038 | TASK [ensure-tox : Install tox to local venv] 2026-02-16 17:51:59.110898 | ubuntu-noble | Collecting tox 2026-02-16 17:51:59.182409 | ubuntu-noble | Downloading tox-4.36.0-py3-none-any.whl.metadata (3.6 kB) 2026-02-16 17:51:59.375547 | ubuntu-noble | Collecting cachetools>=7.0.1 (from tox) 2026-02-16 17:51:59.386480 | ubuntu-noble | Downloading cachetools-7.0.1-py3-none-any.whl.metadata (5.6 kB) 2026-02-16 17:51:59.511152 | ubuntu-noble | Collecting chardet>=5.2 (from tox) 2026-02-16 17:51:59.521295 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl.metadata (3.4 kB) 2026-02-16 17:51:59.636843 | ubuntu-noble | Collecting colorama>=0.4.6 (from tox) 2026-02-16 17:51:59.650106 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl.metadata (17 kB) 2026-02-16 17:51:59.711037 | ubuntu-noble | Collecting filelock>=3.24 (from tox) 2026-02-16 17:51:59.721595 | ubuntu-noble | Downloading filelock-3.24.2-py3-none-any.whl.metadata (2.0 kB) 2026-02-16 17:51:59.774903 | ubuntu-noble | Collecting packaging>=26 (from tox) 2026-02-16 17:51:59.790988 | ubuntu-noble | Downloading packaging-26.0-py3-none-any.whl.metadata (3.3 kB) 2026-02-16 17:51:59.846912 | ubuntu-noble | Collecting platformdirs>=4.9.1 (from tox) 2026-02-16 17:51:59.866132 | ubuntu-noble | Downloading platformdirs-4.9.2-py3-none-any.whl.metadata (4.7 kB) 2026-02-16 17:51:59.914172 | ubuntu-noble | Collecting pluggy>=1.6 (from tox) 2026-02-16 17:51:59.926020 | ubuntu-noble | Downloading pluggy-1.6.0-py3-none-any.whl.metadata (4.8 kB) 2026-02-16 17:51:59.971711 | ubuntu-noble | Collecting pyproject-api>=1.10 (from tox) 2026-02-16 17:51:59.982947 | ubuntu-noble | Downloading pyproject_api-1.10.0-py3-none-any.whl.metadata (2.7 kB) 2026-02-16 17:52:00.111784 | ubuntu-noble | Collecting virtualenv>=20.36.1 (from tox) 2026-02-16 17:52:00.122344 | ubuntu-noble | Downloading virtualenv-20.37.0-py3-none-any.whl.metadata (5.1 kB) 2026-02-16 17:52:00.224061 | ubuntu-noble | Collecting distlib<1,>=0.3.7 (from virtualenv>=20.36.1->tox) 2026-02-16 17:52:00.234075 | ubuntu-noble | Downloading distlib-0.4.0-py2.py3-none-any.whl.metadata (5.2 kB) 2026-02-16 17:52:00.288554 | ubuntu-noble | Downloading tox-4.36.0-py3-none-any.whl (179 kB) 2026-02-16 17:52:00.321366 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 179.1/179.1 kB 6.4 MB/s eta 0:00:00 2026-02-16 17:52:00.333860 | ubuntu-noble | Downloading cachetools-7.0.1-py3-none-any.whl (13 kB) 2026-02-16 17:52:00.359102 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2026-02-16 17:52:00.379234 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 12.0 MB/s eta 0:00:00 2026-02-16 17:52:00.390287 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-02-16 17:52:00.410451 | ubuntu-noble | Downloading filelock-3.24.2-py3-none-any.whl (24 kB) 2026-02-16 17:52:00.439167 | ubuntu-noble | Downloading packaging-26.0-py3-none-any.whl (74 kB) 2026-02-16 17:52:00.453754 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 5.7 MB/s eta 0:00:00 2026-02-16 17:52:00.467136 | ubuntu-noble | Downloading platformdirs-4.9.2-py3-none-any.whl (21 kB) 2026-02-16 17:52:00.487936 | ubuntu-noble | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-02-16 17:52:00.507636 | ubuntu-noble | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-02-16 17:52:00.529005 | ubuntu-noble | Downloading virtualenv-20.37.0-py3-none-any.whl (5.8 MB) 2026-02-16 17:52:00.802408 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.8/5.8 MB 21.7 MB/s eta 0:00:00 2026-02-16 17:52:00.813859 | ubuntu-noble | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-02-16 17:52:00.834853 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 28.6 MB/s eta 0:00:00 2026-02-16 17:52:00.905847 | ubuntu-noble | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2026-02-16 17:52:01.770123 | 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:52:02.320204 | ubuntu-noble | ok: Runtime: 0:00:03.330190 2026-02-16 17:52:02.329601 | 2026-02-16 17:52:02.329710 | TASK [ensure-tox : Export installed tox_executable path] 2026-02-16 17:52:02.399690 | ubuntu-noble | ok 2026-02-16 17:52:02.408793 | 2026-02-16 17:52:02.408915 | TASK [ensure-tox : Output tox version] 2026-02-16 17:52:02.874904 | 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:52:02.888235 | ubuntu-noble | 4.36.0 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2026-02-16 17:52:02.980063 | ubuntu-noble | ok: Runtime: 0:00:00.281289 2026-02-16 17:52:02.997764 | 2026-02-16 17:52:02.997875 | TASK [ensure-tox : Make global symlink] 2026-02-16 17:52:03.037649 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:52:03.050441 | 2026-02-16 17:52:03.050589 | PLAY RECAP 2026-02-16 17:52:03.050758 | ubuntu-noble | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-02-16 17:52:03.050832 | 2026-02-16 17:52:03.200280 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master] 2026-02-16 17:52:03.202719 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/tox/pre.yml@main] 2026-02-16 17:52:03.799160 | 2026-02-16 17:52:03.799336 | PLAY [all] 2026-02-16 17:52:03.812102 | 2026-02-16 17:52:03.812194 | TASK [ensure-rust : Use rustup] 2026-02-16 17:52:03.846770 | ubuntu-noble | ok 2026-02-16 17:52:03.857357 | ubuntu-noble | included: /var/lib/zuul/builds/db079f5065a740f3a100980609d7c97f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml 2026-02-16 17:52:03.863637 | 2026-02-16 17:52:03.863724 | TASK [ensure-rust : Install Rust] 2026-02-16 17:52:04.245277 | ubuntu-noble | /bin/bash: line 2: curl: command not found 2026-02-16 17:52:04.401050 | ubuntu-noble | ERROR 2026-02-16 17:52:04.401336 | ubuntu-noble | { 2026-02-16 17:52:04.401378 | ubuntu-noble | "delta": "0:00:00.007612", 2026-02-16 17:52:04.401410 | ubuntu-noble | "end": "2026-02-16 17:52:04.246224", 2026-02-16 17:52:04.401438 | ubuntu-noble | "msg": "non-zero return code", 2026-02-16 17:52:04.401463 | ubuntu-noble | "rc": 127, 2026-02-16 17:52:04.401488 | ubuntu-noble | "start": "2026-02-16 17:52:04.238612" 2026-02-16 17:52:04.401519 | ubuntu-noble | } failure 2026-02-16 17:52:04.403842 | 2026-02-16 17:52:04.403912 | PLAY RECAP 2026-02-16 17:52:04.403974 | ubuntu-noble | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-02-16 17:52:04.404005 | 2026-02-16 17:52:04.627169 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/tox/pre.yml@main] 2026-02-16 17:52:04.629024 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2026-02-16 17:52:05.257986 | 2026-02-16 17:52:05.258208 | PLAY [all] 2026-02-16 17:52:05.273384 | 2026-02-16 17:52:05.273509 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-02-16 17:52:05.340257 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:52:05.353255 | 2026-02-16 17:52:05.353373 | TASK [fetch-tox-output : Set tox log path for single node] 2026-02-16 17:52:05.402688 | ubuntu-noble | ok 2026-02-16 17:52:05.413481 | 2026-02-16 17:52:05.413604 | TASK [fetch-tox-output : Ensure local tox dir] 2026-02-16 17:52:05.805561 | ubuntu-noble -> localhost | changed 2026-02-16 17:52:05.815958 | 2026-02-16 17:52:05.816102 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-02-16 17:52:05.854994 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:52:05.866390 | 2026-02-16 17:52:05.866530 | TASK [fetch-tox-output : Set envlist fact] 2026-02-16 17:52:05.915085 | ubuntu-noble | ok 2026-02-16 17:52:05.925219 | 2026-02-16 17:52:05.925548 | TASK [fetch-tox-output : Get tox version] 2026-02-16 17:52:06.585857 | 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:52:06.598970 | ubuntu-noble | 4.36.0 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2026-02-16 17:52:06.983643 | ubuntu-noble | ok: Runtime: 0:00:00.252337 2026-02-16 17:52:06.992601 | 2026-02-16 17:52:06.992765 | TASK [fetch-tox-output : Find all default environments] 2026-02-16 17:52:07.526238 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:52:07.535162 | 2026-02-16 17:52:07.535262 | TASK [fetch-tox-output : Set envlist fact] 2026-02-16 17:52:07.563141 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:52:07.572763 | 2026-02-16 17:52:07.572859 | TASK [fetch-tox-output : Find all default environments] 2026-02-16 17:52:08.106227 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:52:08.112813 | 2026-02-16 17:52:08.112879 | TASK [fetch-tox-output : Set envlist fact] 2026-02-16 17:52:08.138505 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:52:08.150614 | 2026-02-16 17:52:08.150702 | LOOP [fetch-tox-output : Copy tox logs] 2026-02-16 17:52:08.194988 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:52:08.208535 | 2026-02-16 17:52:08.208697 | LOOP [fetch-tox-output : Collect tox logs] 2026-02-16 17:52:08.941019 | ubuntu-noble | ok: 2026-02-16 17:52:08.941253 | 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 17:52:08.941310 | 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:52:08.941341 | ubuntu-noble | rsync: [Receiver] write error: Broken pipe (32) 2026-02-16 17:52:08.941381 | 2026-02-16 17:52:08.959382 | 2026-02-16 17:52:08.959531 | PLAY RECAP 2026-02-16 17:52:08.959585 | ubuntu-noble | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-02-16 17:52:08.959608 | 2026-02-16 17:52:09.116466 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2026-02-16 17:52:09.118290 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-16 17:52:09.755083 | 2026-02-16 17:52:09.755267 | PLAY [all] 2026-02-16 17:52:09.767916 | 2026-02-16 17:52:09.767994 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-02-16 17:52:09.949956 | ubuntu-noble | changed: non-zero return code 2026-02-16 17:52:09.955708 | 2026-02-16 17:52:09.955788 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-02-16 17:52:09.981245 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:52:09.993466 | 2026-02-16 17:52:09.993536 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-02-16 17:52:10.030324 | 2026-02-16 17:52:10.030468 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-02-16 17:52:10.066164 | 2026-02-16 17:52:10.066340 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-02-16 17:52:10.091339 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:52:10.102418 | 2026-02-16 17:52:10.102515 | LOOP [fetch-subunit-output : Generate subunit file] 2026-02-16 17:52:10.138993 | 2026-02-16 17:52:10.139179 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-02-16 17:52:10.165153 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:52:10.177970 | 2026-02-16 17:52:10.178071 | TASK [fetch-subunit-output : Remove the temporary file] 2026-02-16 17:52:10.205649 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:52:10.211893 | 2026-02-16 17:52:10.211962 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-02-16 17:52:10.237229 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:52:10.249917 | 2026-02-16 17:52:10.249999 | PLAY RECAP 2026-02-16 17:52:10.250050 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-16 17:52:10.250072 | 2026-02-16 17:52:10.402550 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-16 17:52:10.404299 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 17:52:11.091870 | 2026-02-16 17:52:11.092011 | PLAY [all] 2026-02-16 17:52:11.103661 | 2026-02-16 17:52:11.103739 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-16 17:52:11.159293 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 17:52:11.169618 | 2026-02-16 17:52:11.169706 | TASK [fetch-output : Set log path for single node] 2026-02-16 17:52:11.213623 | ubuntu-noble | ok 2026-02-16 17:52:11.219808 | 2026-02-16 17:52:11.219875 | LOOP [fetch-output : Ensure local output dirs] 2026-02-16 17:52:11.661467 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/db079f5065a740f3a100980609d7c97f/work/logs" 2026-02-16 17:52:11.907002 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/db079f5065a740f3a100980609d7c97f/work/artifacts" 2026-02-16 17:52:12.261946 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/db079f5065a740f3a100980609d7c97f/work/docs" 2026-02-16 17:52:12.279361 | 2026-02-16 17:52:12.279520 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-16 17:52:12.952037 | ubuntu-noble | changed: .d..t...... ./ 2026-02-16 17:52:12.952355 | ubuntu-noble | changed: All items complete 2026-02-16 17:52:12.952387 | 2026-02-16 17:52:13.417328 | ubuntu-noble | changed: .d..t...... ./ 2026-02-16 17:52:13.897578 | ubuntu-noble | changed: .d..t...... ./ 2026-02-16 17:52:13.917026 | 2026-02-16 17:52:13.917194 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-16 17:52:14.368203 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.009009 2026-02-16 17:52:14.591413 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.007396 2026-02-16 17:52:14.613451 | 2026-02-16 17:52:14.613616 | PLAY [all] 2026-02-16 17:52:14.622729 | 2026-02-16 17:52:14.622829 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-16 17:52:15.077953 | ubuntu-noble | changed 2026-02-16 17:52:15.085565 | 2026-02-16 17:52:15.085617 | PLAY RECAP 2026-02-16 17:52:15.085668 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-16 17:52:15.085691 | 2026-02-16 17:52:15.228303 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 17:52:15.229631 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-16 17:52:15.824074 | 2026-02-16 17:52:15.824231 | PLAY [localhost] 2026-02-16 17:52:15.835744 | 2026-02-16 17:52:15.835818 | TASK [Generate Zuul manifest] 2026-02-16 17:52:15.856500 | localhost | ok 2026-02-16 17:52:15.873568 | 2026-02-16 17:52:15.873647 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-16 17:52:16.208528 | localhost | changed 2026-02-16 17:52:16.224999 | 2026-02-16 17:52:16.225096 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-16 17:52:16.254411 | localhost | ok 2026-02-16 17:52:16.264685 | 2026-02-16 17:52:16.264749 | TASK [Upload logs] 2026-02-16 17:52:16.283818 | localhost | ok 2026-02-16 17:52:16.346519 | 2026-02-16 17:52:16.346666 | TASK [Set zuul-log-path fact] 2026-02-16 17:52:16.365758 | localhost | ok 2026-02-16 17:52:16.377087 | 2026-02-16 17:52:16.377163 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 17:52:16.409332 | localhost | ok 2026-02-16 17:52:16.417799 | 2026-02-16 17:52:16.417865 | TASK [upload-logs : Create log directories] 2026-02-16 17:52:16.835712 | localhost | changed 2026-02-16 17:52:16.842519 | 2026-02-16 17:52:16.842594 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-16 17:52:17.240613 | localhost -> localhost | ok: Runtime: 0:00:00.004847 2026-02-16 17:52:17.246927 | 2026-02-16 17:52:17.247011 | TASK [upload-logs : Upload logs to log server] 2026-02-16 17:52:17.734512 | localhost | Output suppressed because no_log was given 2026-02-16 17:52:17.739524 | 2026-02-16 17:52:17.739591 | LOOP [upload-logs : Compress console log and json output] 2026-02-16 17:52:17.782771 | localhost | skipping: Conditional result was False 2026-02-16 17:52:17.789695 | localhost | skipping: Conditional result was False 2026-02-16 17:52:17.801663 | 2026-02-16 17:52:17.801822 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-16 17:52:17.846212 | localhost | skipping: Conditional result was False 2026-02-16 17:52:17.846686 | 2026-02-16 17:52:17.849761 | localhost | skipping: Conditional result was False 2026-02-16 17:52:17.866425 | 2026-02-16 17:52:17.866616 | LOOP [upload-logs : Upload console log and json output]