2026-02-16 18:26:25.362003 | Job console starting 2026-02-16 18:26:25.384347 | Updating git repos 2026-02-16 18:26:25.447994 | Cloning repos into workspace 2026-02-16 18:26:25.513117 | Restoring repo states 2026-02-16 18:26:25.569306 | Merging changes 2026-02-16 18:26:26.474922 | Checking out repos 2026-02-16 18:26:26.855829 | Preparing playbooks 2026-02-16 18:26:32.764630 | Running Ansible setup 2026-02-16 18:26:36.894404 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 18:26:37.541619 | 2026-02-16 18:26:37.563283 | PLAY [localhost] 2026-02-16 18:26:37.571929 | 2026-02-16 18:26:37.571999 | TASK [Gathering Facts] 2026-02-16 18:26:38.460506 | localhost | ok 2026-02-16 18:26:38.469961 | 2026-02-16 18:26:38.596098 | TASK [Setup log path fact] 2026-02-16 18:26:38.616777 | localhost | ok 2026-02-16 18:26:38.631250 | 2026-02-16 18:26:38.631343 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 18:26:38.662388 | localhost | ok 2026-02-16 18:26:38.672728 | 2026-02-16 18:26:38.672800 | TASK [emit-job-header : Print job information] 2026-02-16 18:26:38.727091 | # Job Information 2026-02-16 18:26:38.727290 | Ansible Version: 2.16.15 2026-02-16 18:26:38.727337 | Job: magnum-cluster-api-tox-unit 2026-02-16 18:26:38.727370 | Pipeline: check 2026-02-16 18:26:38.727400 | Executor: 3a2793d2bd32 2026-02-16 18:26:38.727428 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884 2026-02-16 18:26:38.727461 | Event ID: e6955ee0-0b64-11f1-9362-3d643ff18065 2026-02-16 18:26:38.731498 | 2026-02-16 18:26:38.731595 | LOOP [emit-job-header : Print node information] 2026-02-16 18:26:38.825426 | localhost | ok: 2026-02-16 18:26:38.825671 | localhost | # Node Information 2026-02-16 18:26:38.825713 | localhost | Inventory Hostname: ubuntu-noble 2026-02-16 18:26:38.825746 | localhost | Hostname: np0000155656 2026-02-16 18:26:38.825776 | localhost | Username: zuul 2026-02-16 18:26:38.825810 | localhost | Distro: Ubuntu 24.04 2026-02-16 18:26:38.825882 | localhost | Provider: yul1 2026-02-16 18:26:38.825912 | localhost | Region: ca-ymq-1 2026-02-16 18:26:38.825940 | localhost | Label: ubuntu-noble 2026-02-16 18:26:38.825969 | localhost | Product Name: OpenStack Nova 2026-02-16 18:26:38.825996 | localhost | Interface IP: 199.204.45.41 2026-02-16 18:26:38.838844 | 2026-02-16 18:26:38.839029 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-16 18:26:39.362030 | localhost -> localhost | changed 2026-02-16 18:26:39.371842 | 2026-02-16 18:26:39.372035 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-16 18:26:40.319272 | localhost -> localhost | changed 2026-02-16 18:26:40.325233 | 2026-02-16 18:26:40.325288 | PLAY [all] 2026-02-16 18:26:40.346078 | 2026-02-16 18:26:40.346166 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-16 18:26:40.562458 | ubuntu-noble -> localhost | ok 2026-02-16 18:26:40.574574 | 2026-02-16 18:26:40.574644 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-16 18:26:40.607791 | ubuntu-noble | ok 2026-02-16 18:26:40.625084 | ubuntu-noble | included: /var/lib/zuul/builds/8f45d0cb31be4998bddbc5fd7d4c9b89/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-16 18:26:40.630957 | 2026-02-16 18:26:40.631018 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-16 18:26:41.372741 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2026-02-16 18:26:41.373040 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/8f45d0cb31be4998bddbc5fd7d4c9b89/work/8f45d0cb31be4998bddbc5fd7d4c9b89_id_rsa 2026-02-16 18:26:41.373110 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/8f45d0cb31be4998bddbc5fd7d4c9b89/work/8f45d0cb31be4998bddbc5fd7d4c9b89_id_rsa.pub 2026-02-16 18:26:41.373160 | ubuntu-noble -> localhost | The key fingerprint is: 2026-02-16 18:26:41.373211 | ubuntu-noble -> localhost | SHA256:2oPM+JDspXjRqxxoSxhm9Gu3NumUiFIeDqybU4RtIkI zuul-build-sshkey 2026-02-16 18:26:41.373278 | ubuntu-noble -> localhost | The key's randomart image is: 2026-02-16 18:26:41.373324 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2026-02-16 18:26:41.373375 | ubuntu-noble -> localhost | | | 2026-02-16 18:26:41.373423 | ubuntu-noble -> localhost | | E | 2026-02-16 18:26:41.373469 | ubuntu-noble -> localhost | |.+ | 2026-02-16 18:26:41.373513 | ubuntu-noble -> localhost | |B = | 2026-02-16 18:26:41.373557 | ubuntu-noble -> localhost | |=Bo. . S | 2026-02-16 18:26:41.373603 | ubuntu-noble -> localhost | |+Bo++=o+ | 2026-02-16 18:26:41.373647 | ubuntu-noble -> localhost | |+o*+*=B.o | 2026-02-16 18:26:41.373695 | ubuntu-noble -> localhost | |o=.=+Xo . | 2026-02-16 18:26:41.373746 | ubuntu-noble -> localhost | |o.o.B+o | 2026-02-16 18:26:41.373797 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2026-02-16 18:26:41.373897 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.332547 2026-02-16 18:26:41.381672 | 2026-02-16 18:26:41.381760 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-16 18:26:41.413375 | ubuntu-noble | ok 2026-02-16 18:26:41.426341 | ubuntu-noble | included: /var/lib/zuul/builds/8f45d0cb31be4998bddbc5fd7d4c9b89/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-16 18:26:41.434277 | 2026-02-16 18:26:41.434339 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-16 18:26:41.457067 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:26:41.466756 | 2026-02-16 18:26:41.466848 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-16 18:26:41.972578 | ubuntu-noble | changed 2026-02-16 18:26:41.978100 | 2026-02-16 18:26:41.978166 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-16 18:26:42.203530 | ubuntu-noble | ok 2026-02-16 18:26:42.241233 | 2026-02-16 18:26:42.241335 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-16 18:26:42.792200 | ubuntu-noble | changed 2026-02-16 18:26:42.802213 | 2026-02-16 18:26:42.802287 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-16 18:26:43.319092 | ubuntu-noble | changed 2026-02-16 18:26:43.432133 | 2026-02-16 18:26:43.432228 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-16 18:26:43.456610 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:26:43.465698 | 2026-02-16 18:26:43.465788 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-16 18:26:43.824664 | ubuntu-noble -> localhost | changed 2026-02-16 18:26:43.841134 | 2026-02-16 18:26:43.841245 | TASK [add-build-sshkey : Add back temp key] 2026-02-16 18:26:44.135396 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/8f45d0cb31be4998bddbc5fd7d4c9b89/work/8f45d0cb31be4998bddbc5fd7d4c9b89_id_rsa (zuul-build-sshkey) 2026-02-16 18:26:44.135597 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.015437 2026-02-16 18:26:44.142361 | 2026-02-16 18:26:44.142426 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-16 18:26:44.480278 | ubuntu-noble | ok 2026-02-16 18:26:44.495478 | 2026-02-16 18:26:44.495569 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-16 18:26:44.520721 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:26:44.536157 | 2026-02-16 18:26:44.536244 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-16 18:26:44.904269 | ubuntu-noble | ok 2026-02-16 18:26:44.916362 | 2026-02-16 18:26:45.096354 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-16 18:26:46.231705 | ubuntu-noble | Output suppressed because no_log was given 2026-02-16 18:26:46.241690 | 2026-02-16 18:26:46.241774 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-16 18:26:46.472694 | ubuntu-noble | ok: "logs" 2026-02-16 18:26:46.472939 | ubuntu-noble | ok: All items complete 2026-02-16 18:26:46.473002 | 2026-02-16 18:26:46.726101 | ubuntu-noble | ok: "artifacts" 2026-02-16 18:26:46.886682 | ubuntu-noble | ok: "docs" 2026-02-16 18:26:46.898311 | 2026-02-16 18:26:46.898493 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-16 18:26:47.125695 | ubuntu-noble | changed: "logs" 2026-02-16 18:26:47.312329 | ubuntu-noble | changed: "artifacts" 2026-02-16 18:26:47.489707 | ubuntu-noble | changed: "docs" 2026-02-16 18:26:47.506743 | 2026-02-16 18:26:47.576686 | PLAY RECAP 2026-02-16 18:26:47.576839 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-16 18:26:47.576894 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-16 18:26:47.576936 | 2026-02-16 18:26:47.771006 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-16 18:26:47.779434 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-16 18:26:48.410226 | 2026-02-16 18:26:48.410404 | PLAY [all] 2026-02-16 18:26:48.425740 | 2026-02-16 18:26:48.425917 | TASK [Install binary dependencies] 2026-02-16 18:26:48.470193 | ubuntu-noble | ok 2026-02-16 18:26:48.491994 | 2026-02-16 18:26:48.492145 | TASK [bindep : Include find tasks] 2026-02-16 18:26:48.526494 | ubuntu-noble | ok 2026-02-16 18:26:48.653247 | ubuntu-noble | included: /var/lib/zuul/builds/8f45d0cb31be4998bddbc5fd7d4c9b89/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-02-16 18:26:48.661007 | 2026-02-16 18:26:48.661080 | TASK [bindep : Look for bindep.txt] 2026-02-16 18:26:49.060237 | ubuntu-noble | ok 2026-02-16 18:26:49.072519 | 2026-02-16 18:26:49.072591 | TASK [bindep : Define bindep_file fact] 2026-02-16 18:26:49.096134 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:26:49.108269 | 2026-02-16 18:26:49.108410 | TASK [bindep : Look for other-requirements.txt] 2026-02-16 18:26:49.317020 | ubuntu-noble | ok 2026-02-16 18:26:49.373699 | 2026-02-16 18:26:49.373830 | TASK [bindep : Define bindep_file fact] 2026-02-16 18:26:49.398413 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:26:49.408186 | 2026-02-16 18:26:49.408258 | TASK [bindep : Look for bindep fallback file] 2026-02-16 18:26:49.432471 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:26:49.446924 | 2026-02-16 18:26:49.448340 | TASK [bindep : Define bindep_file fact] 2026-02-16 18:26:49.472896 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:26:49.485063 | 2026-02-16 18:26:49.485153 | TASK [bindep : Include bindep tasks] 2026-02-16 18:26:49.510814 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:26:49.559115 | 2026-02-16 18:26:49.559223 | TASK [bindep : Include install tasks] 2026-02-16 18:26:49.587267 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:26:49.599892 | 2026-02-16 18:26:49.600060 | LOOP [bindep : Include package tasks] 2026-02-16 18:26:49.667382 | 2026-02-16 18:26:49.667626 | TASK [Run test-setup role] 2026-02-16 18:26:49.710226 | ubuntu-noble | ok 2026-02-16 18:26:49.730013 | 2026-02-16 18:26:49.730105 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-02-16 18:26:49.941285 | ubuntu-noble | ok 2026-02-16 18:26:49.950313 | 2026-02-16 18:26:49.950380 | TASK [test-setup : Run tools/test-setup.sh] 2026-02-16 18:26:50.490182 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:26:50.516496 | 2026-02-16 18:26:50.516633 | PLAY RECAP 2026-02-16 18:26:50.516685 | ubuntu-noble | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-16 18:26:50.516708 | 2026-02-16 18:26:50.660432 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-16 18:26:50.661959 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master] 2026-02-16 18:26:51.281776 | 2026-02-16 18:26:51.281926 | PLAY [all] 2026-02-16 18:26:51.297395 | 2026-02-16 18:26:51.297480 | TASK [ensure-python : Validate python_version value] 2026-02-16 18:26:51.333007 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:26:51.342704 | 2026-02-16 18:26:51.342811 | TASK [ensure-python : Install specified version of python interpreter and development files (DEB)] 2026-02-16 18:26:51.367879 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:26:51.377166 | 2026-02-16 18:26:51.377241 | TASK [ensure-python : Pull in venv package] 2026-02-16 18:26:51.401874 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:26:51.414028 | 2026-02-16 18:26:51.414109 | TASK [ensure-python : Set default RPM package name] 2026-02-16 18:26:51.438885 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:26:51.449822 | 2026-02-16 18:26:51.449894 | TASK [ensure-python : Set RPM package name for CentOS/RHEL 9/10] 2026-02-16 18:26:51.474006 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:26:51.485649 | 2026-02-16 18:26:51.485722 | TASK [ensure-python : Install RPM package] 2026-02-16 18:26:51.510210 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:26:51.523835 | 2026-02-16 18:26:51.524085 | TASK [ensure-python : Install python using pyenv] 2026-02-16 18:26:51.548133 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:26:51.557995 | 2026-02-16 18:26:51.558065 | TASK [ensure-python : Activate python using stow] 2026-02-16 18:26:51.592332 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:26:51.615540 | 2026-02-16 18:26:51.615611 | TASK [Install pip] 2026-02-16 18:26:51.645639 | ubuntu-noble | ok 2026-02-16 18:26:51.670370 | 2026-02-16 18:26:51.670442 | TASK [ensure-pip : Check if pip is installed] 2026-02-16 18:26:52.211330 | ubuntu-noble | ok: Runtime: 0:00:00.006123 2026-02-16 18:26:52.220035 | 2026-02-16 18:26:52.220181 | LOOP [ensure-pip : Install pip from packages] 2026-02-16 18:26:52.256824 | ubuntu-noble | ok: "/var/lib/zuul/builds/8f45d0cb31be4998bddbc5fd7d4c9b89/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/Debian.yaml" 2026-02-16 18:26:52.370233 | ubuntu-noble | included: /var/lib/zuul/builds/8f45d0cb31be4998bddbc5fd7d4c9b89/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/Debian.yaml 2026-02-16 18:26:52.382417 | 2026-02-16 18:26:52.382559 | TASK [ensure-pip : Update package lists] 2026-02-16 18:26:58.649067 | ubuntu-noble | changed 2026-02-16 18:26:58.655380 | 2026-02-16 18:26:58.655446 | TASK [ensure-pip : Install Python 3 pip] 2026-02-16 18:27:04.020443 | ubuntu-noble | changed 2026-02-16 18:27:04.029989 | 2026-02-16 18:27:04.030126 | TASK [ensure-pip : Install Python 2 pip] 2026-02-16 18:27:04.056486 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:27:04.069708 | 2026-02-16 18:27:04.069897 | TASK [ensure-pip : Ensure setuptools] 2026-02-16 18:27:04.096530 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:27:04.107656 | 2026-02-16 18:27:04.107801 | TASK [ensure-pip : Check for ensurepip module] 2026-02-16 18:27:04.392526 | ubuntu-noble | usage: python -m ensurepip [-h] [--version] [-v] [-U] [--user] [--root ROOT] 2026-02-16 18:27:04.392632 | ubuntu-noble | [--altinstall] [--default-pip] 2026-02-16 18:27:04.392646 | ubuntu-noble | 2026-02-16 18:27:04.392657 | ubuntu-noble | options: 2026-02-16 18:27:04.392667 | ubuntu-noble | -h, --help show this help message and exit 2026-02-16 18:27:04.392682 | ubuntu-noble | --version Show the version of pip that is bundled with this Python. 2026-02-16 18:27:04.392694 | ubuntu-noble | -v, --verbose Give more output. Option is additive, and can be used up to 3 2026-02-16 18:27:04.392709 | ubuntu-noble | times. 2026-02-16 18:27:04.392718 | ubuntu-noble | -U, --upgrade Upgrade pip and dependencies, even if already installed. 2026-02-16 18:27:04.392747 | ubuntu-noble | --user Install using the user scheme. 2026-02-16 18:27:04.392756 | ubuntu-noble | --root ROOT Install everything relative to this alternate root directory. 2026-02-16 18:27:04.392765 | ubuntu-noble | --altinstall Make an alternate install, installing only the X.Y versioned 2026-02-16 18:27:04.392774 | ubuntu-noble | scripts (Default: pipX, pipX.Y). 2026-02-16 18:27:04.392782 | ubuntu-noble | --default-pip Make a default pip install, installing the unqualified pip in 2026-02-16 18:27:04.392805 | ubuntu-noble | addition to the versioned scripts. 2026-02-16 18:27:04.665002 | ubuntu-noble | ok: Runtime: 0:00:00.058249 2026-02-16 18:27:04.672803 | 2026-02-16 18:27:04.672899 | TASK [ensure-pip : Ensure python3-venv] 2026-02-16 18:27:04.704264 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:27:04.716450 | 2026-02-16 18:27:04.716544 | TASK [ensure-pip : Install pip from source] 2026-02-16 18:27:04.743718 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:27:04.798638 | 2026-02-16 18:27:04.798794 | TASK [ensure-pip : Probe for venv python full path] 2026-02-16 18:27:05.043167 | ubuntu-noble | /usr/bin/python3 2026-02-16 18:27:05.340275 | ubuntu-noble | ok: Runtime: 0:00:00.007398 2026-02-16 18:27:05.347025 | 2026-02-16 18:27:05.347095 | TASK [ensure-pip : Set host default] 2026-02-16 18:27:05.400167 | ubuntu-noble | ok 2026-02-16 18:27:05.405884 | 2026-02-16 18:27:05.405953 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2026-02-16 18:27:05.460482 | ubuntu-noble | ok 2026-02-16 18:27:05.471640 | 2026-02-16 18:27:05.471714 | TASK [ensure-tox : Check if tox is installed] 2026-02-16 18:27:06.010898 | ubuntu-noble | ok: Runtime: 0:00:00.005470 2026-02-16 18:27:06.016324 | 2026-02-16 18:27:06.016386 | TASK [ensure-tox : Export preinstalled tox_exectuable] 2026-02-16 18:27:06.040648 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:27:06.051993 | 2026-02-16 18:27:06.052065 | TASK [ensure-tox : Create local venv] 2026-02-16 18:27:09.588017 | ubuntu-noble | ok: Runtime: 0:00:03.240549 2026-02-16 18:27:09.594026 | 2026-02-16 18:27:09.594118 | TASK [ensure-tox : Install tox to local venv] 2026-02-16 18:27:10.433591 | ubuntu-noble | Collecting tox 2026-02-16 18:27:10.501567 | ubuntu-noble | Downloading tox-4.36.0-py3-none-any.whl.metadata (3.6 kB) 2026-02-16 18:27:10.827195 | ubuntu-noble | Collecting cachetools>=7.0.1 (from tox) 2026-02-16 18:27:10.842238 | ubuntu-noble | Downloading cachetools-7.0.1-py3-none-any.whl.metadata (5.6 kB) 2026-02-16 18:27:11.065958 | ubuntu-noble | Collecting chardet>=5.2 (from tox) 2026-02-16 18:27:11.076765 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl.metadata (3.4 kB) 2026-02-16 18:27:11.212923 | ubuntu-noble | Collecting colorama>=0.4.6 (from tox) 2026-02-16 18:27:11.229535 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl.metadata (17 kB) 2026-02-16 18:27:11.294818 | ubuntu-noble | Collecting filelock>=3.24 (from tox) 2026-02-16 18:27:11.304175 | ubuntu-noble | Downloading filelock-3.24.2-py3-none-any.whl.metadata (2.0 kB) 2026-02-16 18:27:11.354362 | ubuntu-noble | Collecting packaging>=26 (from tox) 2026-02-16 18:27:11.364581 | ubuntu-noble | Downloading packaging-26.0-py3-none-any.whl.metadata (3.3 kB) 2026-02-16 18:27:11.424769 | ubuntu-noble | Collecting platformdirs>=4.9.1 (from tox) 2026-02-16 18:27:11.434336 | ubuntu-noble | Downloading platformdirs-4.9.2-py3-none-any.whl.metadata (4.7 kB) 2026-02-16 18:27:11.479124 | ubuntu-noble | Collecting pluggy>=1.6 (from tox) 2026-02-16 18:27:11.488448 | ubuntu-noble | Downloading pluggy-1.6.0-py3-none-any.whl.metadata (4.8 kB) 2026-02-16 18:27:11.538738 | ubuntu-noble | Collecting pyproject-api>=1.10 (from tox) 2026-02-16 18:27:11.552446 | ubuntu-noble | Downloading pyproject_api-1.10.0-py3-none-any.whl.metadata (2.7 kB) 2026-02-16 18:27:11.670911 | ubuntu-noble | Collecting virtualenv>=20.36.1 (from tox) 2026-02-16 18:27:11.681074 | ubuntu-noble | Downloading virtualenv-20.37.0-py3-none-any.whl.metadata (5.1 kB) 2026-02-16 18:27:11.774860 | ubuntu-noble | Collecting distlib<1,>=0.3.7 (from virtualenv>=20.36.1->tox) 2026-02-16 18:27:11.784244 | ubuntu-noble | Downloading distlib-0.4.0-py2.py3-none-any.whl.metadata (5.2 kB) 2026-02-16 18:27:11.824196 | ubuntu-noble | Downloading tox-4.36.0-py3-none-any.whl (179 kB) 2026-02-16 18:27:11.853784 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 179.1/179.1 kB 6.7 MB/s eta 0:00:00 2026-02-16 18:27:11.865213 | ubuntu-noble | Downloading cachetools-7.0.1-py3-none-any.whl (13 kB) 2026-02-16 18:27:11.886520 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2026-02-16 18:27:11.902621 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 15.1 MB/s eta 0:00:00 2026-02-16 18:27:11.912403 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-02-16 18:27:11.933730 | ubuntu-noble | Downloading filelock-3.24.2-py3-none-any.whl (24 kB) 2026-02-16 18:27:11.951769 | ubuntu-noble | Downloading packaging-26.0-py3-none-any.whl (74 kB) 2026-02-16 18:27:11.965467 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 6.0 MB/s eta 0:00:00 2026-02-16 18:27:11.975899 | ubuntu-noble | Downloading platformdirs-4.9.2-py3-none-any.whl (21 kB) 2026-02-16 18:27:11.996558 | ubuntu-noble | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-02-16 18:27:12.016925 | ubuntu-noble | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-02-16 18:27:12.036684 | ubuntu-noble | Downloading virtualenv-20.37.0-py3-none-any.whl (5.8 MB) 2026-02-16 18:27:12.125052 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.8/5.8 MB 70.3 MB/s eta 0:00:00 2026-02-16 18:27:12.144117 | ubuntu-noble | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-02-16 18:27:12.164195 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 27.2 MB/s eta 0:00:00 2026-02-16 18:27:12.235245 | ubuntu-noble | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2026-02-16 18:27:13.078527 | ubuntu-noble | Successfully installed cachetools-7.0.1 chardet-5.2.0 colorama-0.4.6 distlib-0.4.0 filelock-3.24.2 packaging-26.0 platformdirs-4.9.2 pluggy-1.6.0 pyproject-api-1.10.0 tox-4.36.0 virtualenv-20.37.0 2026-02-16 18:27:13.647327 | ubuntu-noble | ok: Runtime: 0:00:03.305995 2026-02-16 18:27:13.655157 | 2026-02-16 18:27:13.655241 | TASK [ensure-tox : Export installed tox_executable path] 2026-02-16 18:27:13.690971 | ubuntu-noble | ok 2026-02-16 18:27:13.699154 | 2026-02-16 18:27:13.699277 | TASK [ensure-tox : Output tox version] 2026-02-16 18:27:14.208165 | ubuntu-noble | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-02-16 18:27:14.222516 | ubuntu-noble | 4.36.0 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2026-02-16 18:27:14.750685 | ubuntu-noble | ok: Runtime: 0:00:00.290050 2026-02-16 18:27:14.757555 | 2026-02-16 18:27:14.757626 | TASK [ensure-tox : Make global symlink] 2026-02-16 18:27:14.771744 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:27:14.782341 | 2026-02-16 18:27:14.782404 | PLAY RECAP 2026-02-16 18:27:14.782462 | ubuntu-noble | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-02-16 18:27:14.782485 | 2026-02-16 18:27:14.951227 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master] 2026-02-16 18:27:14.952450 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/tox/pre.yml@main] 2026-02-16 18:27:15.631089 | 2026-02-16 18:27:15.631469 | PLAY [all] 2026-02-16 18:27:15.643746 | 2026-02-16 18:27:15.643871 | TASK [ensure-rust : Use rustup] 2026-02-16 18:27:15.677431 | ubuntu-noble | ok 2026-02-16 18:27:15.685504 | ubuntu-noble | included: /var/lib/zuul/builds/8f45d0cb31be4998bddbc5fd7d4c9b89/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml 2026-02-16 18:27:15.691521 | 2026-02-16 18:27:15.691600 | TASK [ensure-rust : Install Rust] 2026-02-16 18:27:16.172659 | ubuntu-noble | /bin/bash: line 2: curl: command not found 2026-02-16 18:27:16.225888 | ubuntu-noble | ERROR 2026-02-16 18:27:16.226276 | ubuntu-noble | { 2026-02-16 18:27:16.226311 | ubuntu-noble | "delta": "0:00:00.008038", 2026-02-16 18:27:16.226334 | ubuntu-noble | "end": "2026-02-16 18:27:16.173773", 2026-02-16 18:27:16.226354 | ubuntu-noble | "msg": "non-zero return code", 2026-02-16 18:27:16.226372 | ubuntu-noble | "rc": 127, 2026-02-16 18:27:16.226390 | ubuntu-noble | "start": "2026-02-16 18:27:16.165735" 2026-02-16 18:27:16.226413 | ubuntu-noble | } failure 2026-02-16 18:27:16.233917 | 2026-02-16 18:27:16.234038 | PLAY RECAP 2026-02-16 18:27:16.234096 | ubuntu-noble | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-02-16 18:27:16.234118 | 2026-02-16 18:27:16.423795 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/tox/pre.yml@main] 2026-02-16 18:27:16.425164 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2026-02-16 18:27:17.029929 | 2026-02-16 18:27:17.030073 | PLAY [all] 2026-02-16 18:27:17.042439 | 2026-02-16 18:27:17.042527 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-02-16 18:27:17.097451 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:27:17.105782 | 2026-02-16 18:27:17.105893 | TASK [fetch-tox-output : Set tox log path for single node] 2026-02-16 18:27:17.148335 | ubuntu-noble | ok 2026-02-16 18:27:17.156464 | 2026-02-16 18:27:17.156560 | TASK [fetch-tox-output : Ensure local tox dir] 2026-02-16 18:27:17.620464 | ubuntu-noble -> localhost | changed 2026-02-16 18:27:17.637216 | 2026-02-16 18:27:17.637418 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-02-16 18:27:17.704335 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:27:17.713555 | 2026-02-16 18:27:17.713669 | TASK [fetch-tox-output : Set envlist fact] 2026-02-16 18:27:17.768411 | ubuntu-noble | ok 2026-02-16 18:27:17.778749 | 2026-02-16 18:27:17.778897 | TASK [fetch-tox-output : Get tox version] 2026-02-16 18:27:18.407197 | ubuntu-noble | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-02-16 18:27:18.421481 | ubuntu-noble | 4.36.0 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2026-02-16 18:27:18.826194 | ubuntu-noble | ok: Runtime: 0:00:00.257037 2026-02-16 18:27:18.833643 | 2026-02-16 18:27:18.833726 | TASK [fetch-tox-output : Find all default environments] 2026-02-16 18:27:19.366708 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:27:19.374184 | 2026-02-16 18:27:19.374290 | TASK [fetch-tox-output : Set envlist fact] 2026-02-16 18:27:19.401025 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:27:19.409762 | 2026-02-16 18:27:19.409893 | TASK [fetch-tox-output : Find all default environments] 2026-02-16 18:27:19.949868 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:27:19.956979 | 2026-02-16 18:27:19.957104 | TASK [fetch-tox-output : Set envlist fact] 2026-02-16 18:27:19.982179 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:27:19.994770 | 2026-02-16 18:27:19.994988 | LOOP [fetch-tox-output : Copy tox logs] 2026-02-16 18:27:20.057620 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:27:20.071904 | 2026-02-16 18:27:20.072119 | LOOP [fetch-tox-output : Collect tox logs] 2026-02-16 18:27:20.862732 | ubuntu-noble | ok: 2026-02-16 18:27:20.863033 | 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 18:27:20.863159 | ubuntu-noble | rsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1865) [Receiver=3.2.7] 2026-02-16 18:27:20.863203 | ubuntu-noble | rsync: [Receiver] write error: Broken pipe (32) 2026-02-16 18:27:20.863245 | 2026-02-16 18:27:20.890420 | 2026-02-16 18:27:20.890627 | PLAY RECAP 2026-02-16 18:27:20.890738 | ubuntu-noble | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-02-16 18:27:20.890766 | 2026-02-16 18:27:21.042949 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2026-02-16 18:27:21.044195 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-16 18:27:21.712096 | 2026-02-16 18:27:21.712308 | PLAY [all] 2026-02-16 18:27:21.726505 | 2026-02-16 18:27:21.726583 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-02-16 18:27:21.902688 | ubuntu-noble | changed: non-zero return code 2026-02-16 18:27:21.909594 | 2026-02-16 18:27:21.909678 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-02-16 18:27:21.934902 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:27:21.946903 | 2026-02-16 18:27:21.947842 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-02-16 18:27:21.982241 | 2026-02-16 18:27:21.982466 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-02-16 18:27:22.020977 | 2026-02-16 18:27:22.021253 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-02-16 18:27:22.036903 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:27:22.048410 | 2026-02-16 18:27:22.048485 | LOOP [fetch-subunit-output : Generate subunit file] 2026-02-16 18:27:22.084393 | 2026-02-16 18:27:22.084586 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-02-16 18:27:22.109756 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:27:22.118146 | 2026-02-16 18:27:22.118233 | TASK [fetch-subunit-output : Remove the temporary file] 2026-02-16 18:27:22.143452 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:27:22.153056 | 2026-02-16 18:27:22.153157 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-02-16 18:27:22.178914 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:27:22.189567 | 2026-02-16 18:27:22.189615 | PLAY RECAP 2026-02-16 18:27:22.189661 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-16 18:27:22.189683 | 2026-02-16 18:27:22.328335 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-16 18:27:22.330048 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 18:27:22.968807 | 2026-02-16 18:27:22.969000 | PLAY [all] 2026-02-16 18:27:22.983002 | 2026-02-16 18:27:22.983164 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-16 18:27:23.029323 | ubuntu-noble | skipping: Conditional result was False 2026-02-16 18:27:23.038398 | 2026-02-16 18:27:23.038533 | TASK [fetch-output : Set log path for single node] 2026-02-16 18:27:23.079349 | ubuntu-noble | ok 2026-02-16 18:27:23.085326 | 2026-02-16 18:27:23.085393 | LOOP [fetch-output : Ensure local output dirs] 2026-02-16 18:27:23.528059 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/8f45d0cb31be4998bddbc5fd7d4c9b89/work/logs" 2026-02-16 18:27:23.756827 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/8f45d0cb31be4998bddbc5fd7d4c9b89/work/artifacts" 2026-02-16 18:27:24.034735 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/8f45d0cb31be4998bddbc5fd7d4c9b89/work/docs" 2026-02-16 18:27:24.051643 | 2026-02-16 18:27:24.051814 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-16 18:27:24.764041 | ubuntu-noble | changed: .d..t...... ./ 2026-02-16 18:27:24.764270 | ubuntu-noble | changed: All items complete 2026-02-16 18:27:24.764302 | 2026-02-16 18:27:25.257440 | ubuntu-noble | changed: .d..t...... ./ 2026-02-16 18:27:25.756582 | ubuntu-noble | changed: .d..t...... ./ 2026-02-16 18:27:25.770680 | 2026-02-16 18:27:25.770826 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-16 18:27:26.229328 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.006542 2026-02-16 18:27:26.468174 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.008501 2026-02-16 18:27:26.490469 | 2026-02-16 18:27:26.490916 | PLAY [all] 2026-02-16 18:27:26.503097 | 2026-02-16 18:27:26.503335 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-16 18:27:26.992577 | ubuntu-noble | changed 2026-02-16 18:27:26.999322 | 2026-02-16 18:27:26.999378 | PLAY RECAP 2026-02-16 18:27:26.999428 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-16 18:27:26.999450 | 2026-02-16 18:27:27.161527 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-16 18:27:27.163038 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-16 18:27:27.784479 | 2026-02-16 18:27:27.784610 | PLAY [localhost] 2026-02-16 18:27:27.795192 | 2026-02-16 18:27:27.795270 | TASK [Generate Zuul manifest] 2026-02-16 18:27:27.819302 | localhost | ok 2026-02-16 18:27:27.835214 | 2026-02-16 18:27:27.835297 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-16 18:27:28.192032 | localhost | changed 2026-02-16 18:27:28.206442 | 2026-02-16 18:27:28.206711 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-16 18:27:28.238714 | localhost | ok 2026-02-16 18:27:28.246963 | 2026-02-16 18:27:28.247027 | TASK [Upload logs] 2026-02-16 18:27:28.266559 | localhost | ok 2026-02-16 18:27:28.327105 | 2026-02-16 18:27:28.327237 | TASK [Set zuul-log-path fact] 2026-02-16 18:27:28.352185 | localhost | ok 2026-02-16 18:27:28.363905 | 2026-02-16 18:27:28.363978 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-16 18:27:28.396401 | localhost | ok 2026-02-16 18:27:28.403661 | 2026-02-16 18:27:28.403725 | TASK [upload-logs : Create log directories] 2026-02-16 18:27:28.860563 | localhost | changed 2026-02-16 18:27:28.868331 | 2026-02-16 18:27:28.868416 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-16 18:27:29.315433 | localhost -> localhost | ok: Runtime: 0:00:00.005708 2026-02-16 18:27:29.321377 | 2026-02-16 18:27:29.321453 | TASK [upload-logs : Upload logs to log server] 2026-02-16 18:27:29.848766 | localhost | Output suppressed because no_log was given 2026-02-16 18:27:29.855473 | 2026-02-16 18:27:29.855551 | LOOP [upload-logs : Compress console log and json output] 2026-02-16 18:27:29.903989 | localhost | skipping: Conditional result was False 2026-02-16 18:27:29.910632 | localhost | skipping: Conditional result was False 2026-02-16 18:27:29.919673 | 2026-02-16 18:27:29.919900 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-16 18:27:29.962474 | localhost | skipping: Conditional result was False 2026-02-16 18:27:29.962762 | 2026-02-16 18:27:29.966079 | localhost | skipping: Conditional result was False 2026-02-16 18:27:29.984356 | 2026-02-16 18:27:29.984546 | LOOP [upload-logs : Upload console log and json output]