2026-02-26 12:22:51.696236 | Job console starting...
2026-02-26 12:22:51.711566 | Updating repositories
2026-02-26 12:22:51.735924 | Preparing job workspace
2026-02-26 12:22:55.397776 | Running Ansible setup...
2026-02-26 12:23:04.013222 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-02-26 12:23:04.647728 | 
2026-02-26 12:23:04.647910 | PLAY [localhost]
2026-02-26 12:23:04.657706 | 
2026-02-26 12:23:04.657808 | TASK [Gathering Facts]
2026-02-26 12:23:05.677563 | localhost | ok
2026-02-26 12:23:05.714258 | 
2026-02-26 12:23:05.714576 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-26 12:23:06.192587 | localhost -> localhost | changed
2026-02-26 12:23:06.207574 | 
2026-02-26 12:23:06.207785 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-26 12:23:07.171945 | localhost -> localhost | changed
2026-02-26 12:23:07.182593 | 
2026-02-26 12:23:07.182682 | TASK [Setup log path fact]
2026-02-26 12:23:07.209177 | localhost | ok
2026-02-26 12:23:07.223337 | 
2026-02-26 12:23:07.223496 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-26 12:23:07.252767 | localhost | ok
2026-02-26 12:23:07.260996 | 
2026-02-26 12:23:07.261106 | TASK [emit-job-header : Print job information]
2026-02-26 12:23:07.300199 | # Job Information
2026-02-26 12:23:07.300401 | Ansible Version: 2.15.12
2026-02-26 12:23:07.300432 | Job: cifmw-molecule-ci_dcn_site
2026-02-26 12:23:07.300453 | Pipeline: github-check
2026-02-26 12:23:07.300473 | Executor: ze01.softwarefactory-project.io
2026-02-26 12:23:07.300492 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3715
2026-02-26 12:23:07.300513 | Log URL (when completed): https://logserver.rdoproject.org/19c/rdoproject.org/19c85a839ef642bba1b5c3d09e2a2ed7/
2026-02-26 12:23:07.300532 | Event ID: 9f8df7e0-130d-11f1-9a5a-6510fb8659ec
2026-02-26 12:23:07.304321 | 
2026-02-26 12:23:07.304388 | LOOP [emit-job-header : Print node information]
2026-02-26 12:23:07.414272 | localhost | ok:
2026-02-26 12:23:07.414511 | localhost | # Node Information
2026-02-26 12:23:07.414541 | localhost | Inventory Hostname: controller
2026-02-26 12:23:07.414566 | localhost | Hostname: np0005631443
2026-02-26 12:23:07.414585 | localhost | Username: zuul
2026-02-26 12:23:07.414606 | localhost | Distro: CentOS 9
2026-02-26 12:23:07.414623 | localhost | Provider: vexxhost-nodepool-tripleo
2026-02-26 12:23:07.414640 | localhost | Region: RegionOne
2026-02-26 12:23:07.414657 | localhost | Label: cloud-centos-9-stream-tripleo
2026-02-26 12:23:07.414673 | localhost | Product Name: OpenStack Nova
2026-02-26 12:23:07.414689 | localhost | Interface IP: 38.102.83.38
2026-02-26 12:23:07.442944 | 
2026-02-26 12:23:07.443104 | PLAY [all]
2026-02-26 12:23:07.450614 | 
2026-02-26 12:23:07.450723 | TASK [Gather network facts]
2026-02-26 12:23:07.915815 | controller | ok
2026-02-26 12:23:07.938736 | 
2026-02-26 12:23:07.938901 | TASK [include_role : start-zuul-console]
2026-02-26 12:23:07.963379 | controller | ok
2026-02-26 12:23:07.978350 | 
2026-02-26 12:23:07.978513 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-02-26 12:23:08.380336 | controller | ok
2026-02-26 12:23:08.390688 | 
2026-02-26 12:23:08.390799 | TASK [include_role : add-build-sshkey]
2026-02-26 12:23:08.422475 | controller | ok
2026-02-26 12:23:08.437312 | 
2026-02-26 12:23:08.437507 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-26 12:23:08.719462 | controller -> localhost | ok
2026-02-26 12:23:08.728980 | 
2026-02-26 12:23:08.729193 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-26 12:23:08.774912 | controller | ok
2026-02-26 12:23:08.794681 | controller | included: /var/lib/zuul/builds/19c85a839ef642bba1b5c3d09e2a2ed7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-26 12:23:08.803563 | 
2026-02-26 12:23:08.803673 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-26 12:23:09.539573 | controller -> localhost | Generating public/private rsa key pair.
2026-02-26 12:23:09.539838 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/19c85a839ef642bba1b5c3d09e2a2ed7/work/19c85a839ef642bba1b5c3d09e2a2ed7_id_rsa.
2026-02-26 12:23:09.539869 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/19c85a839ef642bba1b5c3d09e2a2ed7/work/19c85a839ef642bba1b5c3d09e2a2ed7_id_rsa.pub.
2026-02-26 12:23:09.539889 | controller -> localhost | The key fingerprint is:
2026-02-26 12:23:09.539908 | controller -> localhost | SHA256:9zCPSAWFRMkmuIAF2YwfrcZdpuB6OOFS2fUXVq0eshw zuul-build-sshkey
2026-02-26 12:23:09.539926 | controller -> localhost | The key's randomart image is:
2026-02-26 12:23:09.539943 | controller -> localhost | +---[RSA 3072]----+
2026-02-26 12:23:09.539960 | controller -> localhost | |.O.. . +++....   |
2026-02-26 12:23:09.539978 | controller -> localhost | |+ * o = =.o   .  |
2026-02-26 12:23:09.539996 | controller -> localhost | | + O * + ... .   |
2026-02-26 12:23:09.540126 | controller -> localhost | |. O =   ..E o    |
2026-02-26 12:23:09.540161 | controller -> localhost | |.*      So+= .   |
2026-02-26 12:23:09.540181 | controller -> localhost | |=..    . oo*.    |
2026-02-26 12:23:09.540199 | controller -> localhost | |.o      . . o    |
2026-02-26 12:23:09.540217 | controller -> localhost | |                 |
2026-02-26 12:23:09.540234 | controller -> localhost | |                 |
2026-02-26 12:23:09.540250 | controller -> localhost | +----[SHA256]-----+
2026-02-26 12:23:09.540298 | controller -> localhost | ok: Runtime: 0:00:00.224278
2026-02-26 12:23:09.546496 | 
2026-02-26 12:23:09.546567 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-26 12:23:09.575653 | controller | ok
2026-02-26 12:23:09.586275 | controller | included: /var/lib/zuul/builds/19c85a839ef642bba1b5c3d09e2a2ed7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-26 12:23:09.594491 | 
2026-02-26 12:23:09.594557 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-26 12:23:09.619516 | controller | skipping: Conditional result was False
2026-02-26 12:23:09.626651 | 
2026-02-26 12:23:09.626778 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-26 12:23:10.110333 | controller | changed
2026-02-26 12:23:10.123128 | 
2026-02-26 12:23:10.123279 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-26 12:23:10.502896 | controller | ok
2026-02-26 12:23:10.513818 | 
2026-02-26 12:23:10.513968 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-26 12:23:11.254751 | controller | changed
2026-02-26 12:23:11.264078 | 
2026-02-26 12:23:11.264340 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-26 12:23:11.977229 | controller | changed
2026-02-26 12:23:11.990678 | 
2026-02-26 12:23:11.991059 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-26 12:23:12.019791 | controller | skipping: Conditional result was False
2026-02-26 12:23:12.032671 | 
2026-02-26 12:23:12.032839 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-26 12:23:14.158924 | controller -> localhost | changed
2026-02-26 12:23:14.171601 | 
2026-02-26 12:23:14.171703 | TASK [add-build-sshkey : Add back temp key]
2026-02-26 12:23:14.659621 | controller -> localhost | Identity added: /var/lib/zuul/builds/19c85a839ef642bba1b5c3d09e2a2ed7/work/19c85a839ef642bba1b5c3d09e2a2ed7_id_rsa (zuul-build-sshkey)
2026-02-26 12:23:14.659950 | controller -> localhost | ok: Runtime: 0:00:00.010054
2026-02-26 12:23:14.667389 | 
2026-02-26 12:23:14.667458 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-26 12:23:15.630142 | controller | ok
2026-02-26 12:23:15.637280 | 
2026-02-26 12:23:15.637451 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-26 12:23:15.663134 | controller | skipping: Conditional result was False
2026-02-26 12:23:15.673616 | 
2026-02-26 12:23:15.673756 | TASK [include_role : validate-host]
2026-02-26 12:23:15.695222 | controller | ok
2026-02-26 12:23:15.768552 | 
2026-02-26 12:23:15.768739 | TASK [validate-host : Define zuul_info_dir fact]
2026-02-26 12:23:15.799923 | controller | ok
2026-02-26 12:23:15.805621 | 
2026-02-26 12:23:15.805729 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-02-26 12:23:16.056160 | controller -> localhost | ok
2026-02-26 12:23:16.062801 | 
2026-02-26 12:23:16.062889 | TASK [validate-host : Collect information about the host]
2026-02-26 12:23:16.789289 | controller | ok
2026-02-26 12:23:16.807245 | 
2026-02-26 12:23:16.807455 | TASK [validate-host : Sanitize hostname]
2026-02-26 12:23:16.899475 | controller | ok
2026-02-26 12:23:16.913525 | 
2026-02-26 12:23:16.913674 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-02-26 12:23:17.410009 | controller -> localhost | changed
2026-02-26 12:23:17.416235 | 
2026-02-26 12:23:17.416320 | TASK [validate-host : Collect information about zuul worker]
2026-02-26 12:23:17.790782 | controller | ok
2026-02-26 12:23:17.803254 | 
2026-02-26 12:23:17.803460 | TASK [validate-host : Write out all zuul information for each host]
2026-02-26 12:23:18.303228 | controller -> localhost | changed
2026-02-26 12:23:18.315927 | 
2026-02-26 12:23:18.316058 | TASK [include_role : prepare-workspace-openshift]
2026-02-26 12:23:18.332166 | controller | skipping: Conditional result was False
2026-02-26 12:23:18.338197 | 
2026-02-26 12:23:18.338269 | TASK [include_role : remove-zuul-sshkey]
2026-02-26 12:23:18.363266 | controller | skipping: Conditional result was False
2026-02-26 12:23:18.369513 | 
2026-02-26 12:23:18.369583 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-26 12:23:18.627824 | controller | ok: "logs"
2026-02-26 12:23:18.628238 | controller | ok: All items complete
2026-02-26 12:23:18.628273 | 
2026-02-26 12:23:18.861235 | controller | ok: "artifacts"
2026-02-26 12:23:19.063266 | controller | ok: "docs"
2026-02-26 12:23:19.077299 | 
2026-02-26 12:23:19.077504 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-26 12:23:19.325751 | controller | changed: "logs"
2026-02-26 12:23:19.558965 | controller | changed: "artifacts"
2026-02-26 12:23:19.801251 | controller | changed: "docs"
2026-02-26 12:23:19.860108 | 
2026-02-26 12:23:19.860216 | PLAY RECAP
2026-02-26 12:23:19.860279 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-02-26 12:23:19.860315 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-26 12:23:19.860339 | 
2026-02-26 12:23:20.037229 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-02-26 12:23:20.038395 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2026-02-26 12:23:20.662279 | 
2026-02-26 12:23:20.662472 | PLAY [localhost]
2026-02-26 12:23:20.680738 | 
2026-02-26 12:23:20.680889 | TASK [configure-ssh-config-fips : Ensure ssh config directory]
2026-02-26 12:23:21.204558 | localhost | ok
2026-02-26 12:23:21.212335 | 
2026-02-26 12:23:21.212467 | TASK [configure-ssh-config-fips : Setup ssh config]
2026-02-26 12:23:21.871639 | localhost | changed
2026-02-26 12:23:21.929670 | 
2026-02-26 12:23:21.929834 | PLAY [all]
2026-02-26 12:23:21.953179 | 
2026-02-26 12:23:21.953332 | TASK [include_role : prepare-workspace]
2026-02-26 12:23:21.987318 | controller | ok
2026-02-26 12:23:22.009883 | 
2026-02-26 12:23:22.010205 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-26 12:23:22.423627 | controller | ok
2026-02-26 12:23:22.434334 | 
2026-02-26 12:23:22.434498 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-26 12:23:24.075822 | controller | Output suppressed because no_log was given
2026-02-26 12:23:24.098062 | 
2026-02-26 12:23:24.098213 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-26 12:23:24.376350 | controller | changed: "logs"
2026-02-26 12:23:24.588192 | controller | changed: "artifacts"
2026-02-26 12:23:24.783911 | controller | changed: "docs"
2026-02-26 12:23:24.795067 | 
2026-02-26 12:23:24.795235 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-26 12:23:25.046477 | controller | changed: "logs"
2026-02-26 12:23:25.046808 | controller | changed: All items complete
2026-02-26 12:23:25.046846 | 
2026-02-26 12:23:25.259557 | controller | changed: "artifacts"
2026-02-26 12:23:25.476771 | controller | changed: "docs"
2026-02-26 12:23:25.499089 | 
2026-02-26 12:23:25.499302 | TASK [Check if worker can sudo]
2026-02-26 12:23:26.533752 | controller | ok: Runtime: 0:00:00.055864
2026-02-26 12:23:26.546441 | 
2026-02-26 12:23:26.546663 | TASK [configure-mirrors : Gather needed facts]
2026-02-26 12:23:26.626714 | controller | skipping: Conditional result was False
2026-02-26 12:23:26.640598 | 
2026-02-26 12:23:26.640759 | TASK [configure-mirrors : Set up infrastructure mirrors]
2026-02-26 12:23:26.721075 | controller | ok
2026-02-26 12:23:26.738591 | controller | included: /var/lib/zuul/builds/19c85a839ef642bba1b5c3d09e2a2ed7/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml
2026-02-26 12:23:26.751732 | 
2026-02-26 12:23:26.751831 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir]
2026-02-26 12:23:27.120378 | controller | ok
2026-02-26 12:23:27.131950 | 
2026-02-26 12:23:27.132143 | LOOP [configure-mirrors : Include OS-specific variables]
2026-02-26 12:23:27.233136 | controller | ok: "/var/lib/zuul/builds/19c85a839ef642bba1b5c3d09e2a2ed7/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml"
2026-02-26 12:23:27.252162 | 
2026-02-26 12:23:27.252344 | TASK [configure-mirrors : Install /etc/pip.conf configuration]
2026-02-26 12:23:28.107848 | controller | changed
2026-02-26 12:23:28.121433 | 
2026-02-26 12:23:28.121582 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors]
2026-02-26 12:23:28.215369 | controller | ok: "/var/lib/zuul/builds/19c85a839ef642bba1b5c3d09e2a2ed7/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml"
2026-02-26 12:23:28.216373 | controller | ok: All items complete
2026-02-26 12:23:28.216496 | 
2026-02-26 12:23:28.290614 | controller | included: /var/lib/zuul/builds/19c85a839ef642bba1b5c3d09e2a2ed7/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml
2026-02-26 12:23:28.298902 | 
2026-02-26 12:23:28.299043 | LOOP [configure-mirrors : Install CentOS 9 repository files]
2026-02-26 12:23:29.366829 | controller | changed: "etc/yum.repos.d/centos.repo"
2026-02-26 12:23:30.143482 | controller | changed: "etc/yum.repos.d/centos-addons.repo"
2026-02-26 12:23:30.167174 | 
2026-02-26 12:23:30.167417 | TASK [configure-mirrors : Disable deltrarpm]
2026-02-26 12:23:30.640443 | controller | changed: section and option added
2026-02-26 12:23:30.702842 | 
2026-02-26 12:23:30.703188 | LOOP [configure-mirrors : Update yum/dnf cache]
2026-02-26 12:23:31.577729 | controller | 29 files removed
2026-02-26 12:23:31.586489 | controller | ok: Item: dnf clean all Runtime: 0:00:00.557865
2026-02-26 12:23:31.586612 | controller | changed: All items complete
2026-02-26 12:23:31.586711 | 
2026-02-26 12:23:46.699267 | controller | Loaded plugins: builddep, changelog, config-manager, copr, debug, debuginfo-install, download, generate_completion_cache, groups-manager, needs-restarting, playground, repoclosure, repodiff, repograph, repomanage, reposync, system-upgrade
2026-02-26 12:23:46.699492 | controller | DNF version: 4.14.0
2026-02-26 12:23:46.699547 | controller | cachedir: /var/cache/dnf
2026-02-26 12:23:46.699591 | controller | Making cache files for all metadata files.
2026-02-26 12:23:46.699634 | controller | baseos: has expired and will be refreshed.
2026-02-26 12:23:46.699676 | controller | appstream: has expired and will be refreshed.
2026-02-26 12:23:46.699715 | controller | crb: has expired and will be refreshed.
2026-02-26 12:23:46.699773 | controller | extras-common: has expired and will be refreshed.
2026-02-26 12:23:46.710384 | controller | repo: downloading from remote: baseos
2026-02-26 12:23:46.710930 | controller | CentOS Stream 9 - BaseOS                         58 MB/s | 8.9 MB     00:00
2026-02-26 12:23:46.711094 | controller | baseos: using metadata from Mon 23 Feb 2026 10:40:46 AM EST.
2026-02-26 12:23:46.711146 | controller | repo: downloading from remote: appstream
2026-02-26 12:23:46.711187 | controller | CentOS Stream 9 - AppStream                      10 MB/s |  27 MB     00:02
2026-02-26 12:23:46.711225 | controller | appstream: using metadata from Mon 23 Feb 2026 10:44:24 AM EST.
2026-02-26 12:23:46.711262 | controller | repo: downloading from remote: crb
2026-02-26 12:23:46.711300 | controller | CentOS Stream 9 - CRB                            46 MB/s | 8.0 MB     00:00
2026-02-26 12:23:46.711340 | controller | crb: using metadata from Mon 23 Feb 2026 10:48:55 AM EST.
2026-02-26 12:23:46.711378 | controller | repo: downloading from remote: extras-common
2026-02-26 12:23:46.711416 | controller | CentOS Stream 9 - Extras packages                64 kB/s |  20 kB     00:00
2026-02-26 12:23:46.711454 | controller | extras-common: using metadata from Thu 12 Feb 2026 03:34:59 PM EST.
2026-02-26 12:23:46.711491 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)'
2026-02-26 12:23:46.711528 | controller | Completion plugin: Generating completion cache...
2026-02-26 12:23:46.711566 | controller | Metadata cache created.
2026-02-26 12:23:46.711636 | controller | ok: Item: dnf makecache -v Runtime: 0:00:14.865806
2026-02-26 12:23:46.779278 | 
2026-02-26 12:23:46.779597 | PLAY RECAP
2026-02-26 12:23:46.779868 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-26 12:23:46.779941 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-26 12:23:46.779982 | 
2026-02-26 12:23:46.946446 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2026-02-26 12:23:46.947711 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-02-26 12:23:47.591525 | 
2026-02-26 12:23:47.591697 | PLAY [all]
2026-02-26 12:23:47.616123 | 
2026-02-26 12:23:47.616376 | TASK [Install binary dependencies]
2026-02-26 12:23:47.658204 | controller | ok
2026-02-26 12:23:47.683561 | 
2026-02-26 12:23:47.683768 | TASK [bindep : Include find tasks]
2026-02-26 12:23:47.735728 | controller | ok
2026-02-26 12:23:47.743548 | controller | included: /var/lib/zuul/builds/19c85a839ef642bba1b5c3d09e2a2ed7/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-02-26 12:23:47.749550 | 
2026-02-26 12:23:47.749617 | TASK [bindep : Look for bindep.txt]
2026-02-26 12:23:48.188602 | controller | ok
2026-02-26 12:23:48.204155 | 
2026-02-26 12:23:48.204387 | TASK [bindep : Define bindep_file fact]
2026-02-26 12:23:48.246275 | controller | ok
2026-02-26 12:23:48.261257 | 
2026-02-26 12:23:48.261438 | TASK [bindep : Look for other-requirements.txt]
2026-02-26 12:23:48.279826 | controller | skipping: Conditional result was False
2026-02-26 12:23:48.289210 | 
2026-02-26 12:23:48.289376 | TASK [bindep : Define bindep_file fact]
2026-02-26 12:23:48.317137 | controller | skipping: Conditional result was False
2026-02-26 12:23:48.325704 | 
2026-02-26 12:23:48.325798 | TASK [bindep : Look for bindep fallback file]
2026-02-26 12:23:48.371791 | controller | skipping: Conditional result was False
2026-02-26 12:23:48.383586 | 
2026-02-26 12:23:48.383740 | TASK [bindep : Define bindep_file fact]
2026-02-26 12:23:48.410409 | controller | skipping: Conditional result was False
2026-02-26 12:23:48.423792 | 
2026-02-26 12:23:48.423948 | TASK [bindep : Include bindep tasks]
2026-02-26 12:23:48.463614 | controller | ok
2026-02-26 12:23:48.479080 | controller | included: /var/lib/zuul/builds/19c85a839ef642bba1b5c3d09e2a2ed7/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml
2026-02-26 12:23:48.491263 | 
2026-02-26 12:23:48.491350 | TASK [bindep : Look for bindep command]
2026-02-26 12:23:48.517181 | controller | skipping: Conditional result was False
2026-02-26 12:23:48.526009 | 
2026-02-26 12:23:48.526130 | TASK [bindep : Check for system bindep]
2026-02-26 12:23:49.063635 | controller | ok: Runtime: 0:00:00.006470
2026-02-26 12:23:49.076795 | 
2026-02-26 12:23:49.076991 | TASK [bindep : Define bindep_command fact]
2026-02-26 12:23:49.108667 | controller | skipping: Conditional result was False
2026-02-26 12:23:49.122109 | 
2026-02-26 12:23:49.122320 | TASK [bindep : Include install tasks]
2026-02-26 12:23:49.155976 | controller | ok
2026-02-26 12:23:49.163613 | controller | included: /var/lib/zuul/builds/19c85a839ef642bba1b5c3d09e2a2ed7/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml
2026-02-26 12:23:49.172958 | 
2026-02-26 12:23:49.173055 | TASK [bindep : Create temp dir for bindep]
2026-02-26 12:23:49.557876 | controller | changed
2026-02-26 12:23:49.573443 | 
2026-02-26 12:23:49.573695 | TASK [Ensure we have pip dependencies]
2026-02-26 12:23:49.608876 | controller | ok
2026-02-26 12:23:49.654972 | 
2026-02-26 12:23:49.655192 | TASK [ensure-pip : Check if pip is installed]
2026-02-26 07:23:49.901320 | controller | /usr/bin/pip3
2026-02-26 07:23:49.922407 | controller | /usr/bin/python3: No module named wheel
2026-02-26 12:23:50.187452 | controller | ok: Runtime: 0:00:00.031425
2026-02-26 12:23:50.200909 | 
2026-02-26 12:23:50.201150 | LOOP [ensure-pip : Install pip from packages]
2026-02-26 12:23:50.229140 | controller | ok: "/var/lib/zuul/builds/19c85a839ef642bba1b5c3d09e2a2ed7/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml"
2026-02-26 12:23:50.240843 | controller | included: /var/lib/zuul/builds/19c85a839ef642bba1b5c3d09e2a2ed7/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml
2026-02-26 12:23:50.255510 | 
2026-02-26 12:23:50.255658 | TASK [ensure-pip : Install Python 3 pip]
2026-02-26 12:23:53.321600 | controller | changed
2026-02-26 12:23:53.344825 | 
2026-02-26 12:23:53.345431 | TASK [ensure-pip : Check for EPEL repository]
2026-02-26 12:23:53.405539 | controller | skipping: Conditional result was False
2026-02-26 12:23:53.415694 | 
2026-02-26 12:23:53.415878 | TASK [ensure-pip : Fail with instructions when EPEL is not installed]
2026-02-26 12:23:53.452816 | controller | skipping: Conditional result was False
2026-02-26 12:23:53.463709 | 
2026-02-26 12:23:53.463815 | TASK [ensure-pip : Install Python 2 pip]
2026-02-26 12:23:53.510827 | controller | skipping: Conditional result was False
2026-02-26 12:23:53.525446 | 
2026-02-26 12:23:53.525636 | TASK [ensure-pip : Ensure setuptools]
2026-02-26 12:23:53.554466 | controller | skipping: Conditional result was False
2026-02-26 12:23:53.573408 | 
2026-02-26 12:23:53.573680 | TASK [ensure-pip : Check for ensurepip module]
2026-02-26 12:23:54.121196 | controller | skipping: Conditional result was False
2026-02-26 12:23:54.130753 | 
2026-02-26 12:23:54.130900 | TASK [ensure-pip : Ensure python3-venv]
2026-02-26 12:23:54.156457 | controller | skipping: Conditional result was False
2026-02-26 12:23:54.166400 | 
2026-02-26 12:23:54.166502 | TASK [ensure-pip : Install pip from source]
2026-02-26 12:23:54.192650 | controller | skipping: Conditional result was False
2026-02-26 12:23:54.203159 | 
2026-02-26 12:23:54.203301 | TASK [ensure-pip : Probe for venv python full path]
2026-02-26 07:23:54.473967 | controller | /usr/bin/python3
2026-02-26 12:23:54.746326 | controller | ok: Runtime: 0:00:00.005668
2026-02-26 12:23:54.760993 | 
2026-02-26 12:23:54.761224 | TASK [ensure-pip : Set host default]
2026-02-26 12:23:54.848893 | controller | ok
2026-02-26 12:23:54.857147 | 
2026-02-26 12:23:54.857239 | TASK [ensure-pip : Set ensure_pip_virtualenv_command]
2026-02-26 12:23:54.934718 | controller | ok
2026-02-26 12:23:54.951847 | 
2026-02-26 12:23:54.952209 | TASK [bindep : Install bindep into temporary venv]
2026-02-26 12:23:59.787964 | controller | changed
2026-02-26 12:23:59.801796 | 
2026-02-26 12:23:59.801979 | TASK [bindep : Define bindep_command]
2026-02-26 12:23:59.851513 | controller | ok
2026-02-26 12:23:59.863805 | 
2026-02-26 12:23:59.863965 | LOOP [bindep : Include package tasks]
2026-02-26 12:23:59.944619 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt"
2026-02-26 12:23:59.945071 | controller | ok: All items complete
2026-02-26 12:23:59.945170 | 
2026-02-26 12:23:59.958522 | controller | included: /var/lib/zuul/builds/19c85a839ef642bba1b5c3d09e2a2ed7/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml
2026-02-26 12:23:59.975959 | 
2026-02-26 12:23:59.976175 | TASK [bindep : Define bindep_run fact]
2026-02-26 12:24:00.010684 | controller | ok
2026-02-26 12:24:00.017998 | 
2026-02-26 12:24:00.018127 | TASK [bindep : Get list of packages to install from bindep]
2026-02-26 07:24:01.432959 | controller | podman
2026-02-26 07:24:01.457715 | controller | python3-jmespath
2026-02-26 07:24:01.457965 | controller | python3-libvirt
2026-02-26 07:24:01.457982 | controller | python3-lxml
2026-02-26 07:24:01.457999 | controller | python3-netaddr
2026-02-26 12:24:01.560603 | controller | ok: Runtime: 0:00:01.144085
2026-02-26 12:24:01.574212 | 
2026-02-26 12:24:01.574445 | TASK [bindep : Install distro packages from bindep]
2026-02-26 12:24:59.978099 | controller | changed
2026-02-26 12:24:59.991286 | 
2026-02-26 12:24:59.991395 | TASK [bindep : Check that packages are installed]
2026-02-26 12:25:02.035291 | controller | ok: Runtime: 0:00:01.264144
2026-02-26 12:25:02.046408 | 
2026-02-26 12:25:02.046629 | TASK [bindep : Fail if we cannot install all packages]
2026-02-26 12:25:02.084089 | controller | skipping: Conditional result was False
2026-02-26 12:25:02.102857 | 
2026-02-26 12:25:02.103065 | TASK [Run test-setup role]
2026-02-26 12:25:02.128874 | controller | ok
2026-02-26 12:25:02.160502 | 
2026-02-26 12:25:02.160683 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-02-26 12:25:02.373492 | controller | ok
2026-02-26 12:25:02.389153 | 
2026-02-26 12:25:02.389351 | TASK [test-setup : Run tools/test-setup.sh]
2026-02-26 12:25:02.940747 | controller | skipping: Conditional result was False
2026-02-26 12:25:02.992091 | 
2026-02-26 12:25:02.992256 | TASK [bindep : Remove bindep temp dir]
2026-02-26 12:25:03.399355 | controller | ok
2026-02-26 12:25:03.426067 | 
2026-02-26 12:25:03.426239 | PLAY RECAP
2026-02-26 12:25:03.426332 | controller | ok: 22 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0
2026-02-26 12:25:03.426379 | 
2026-02-26 12:25:03.582763 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-02-26 12:25:03.585844 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2026-02-26 12:25:04.215497 | 
2026-02-26 12:25:04.215645 | PLAY [all]
2026-02-26 12:25:04.236492 | 
2026-02-26 12:25:04.236630 | TASK [Abort when test_command variable is undefined]
2026-02-26 12:25:04.261011 | controller | skipping: Conditional result was False
2026-02-26 12:25:04.267106 | 
2026-02-26 12:25:04.267182 | TASK [Convert test_command to list]
2026-02-26 12:25:04.311431 | controller | skipping: Conditional result was False
2026-02-26 12:25:04.318635 | 
2026-02-26 12:25:04.318726 | TASK [Use test_command list]
2026-02-26 12:25:04.368468 | controller | ok
2026-02-26 12:25:04.374774 | 
2026-02-26 12:25:04.374889 | LOOP [Run test_command]
2026-02-26 12:25:04.818318 | controller | no check to run
2026-02-26 12:25:04.818780 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.005558
2026-02-26 12:25:04.867426 | 
2026-02-26 12:25:04.867634 | PLAY RECAP
2026-02-26 12:25:04.867700 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-02-26 12:25:04.867727 | 
2026-02-26 12:25:04.997503 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2026-02-26 12:25:04.999870 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-02-26 12:25:05.645368 | 
2026-02-26 12:25:05.645525 | PLAY [all]
2026-02-26 12:25:05.668076 | 
2026-02-26 12:25:05.668225 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-02-26 12:25:06.009684 | controller | changed: non-zero return code
2026-02-26 12:25:06.018920 | 
2026-02-26 12:25:06.019173 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-02-26 12:25:06.036055 | controller | skipping: Conditional result was False
2026-02-26 12:25:06.047894 | 
2026-02-26 12:25:06.048109 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-02-26 12:25:06.078764 | 
2026-02-26 12:25:06.078978 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-02-26 12:25:06.111649 | 
2026-02-26 12:25:06.111856 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-02-26 12:25:06.136337 | controller | skipping: Conditional result was False
2026-02-26 12:25:06.145033 | 
2026-02-26 12:25:06.145134 | LOOP [fetch-subunit-output : Generate subunit file]
2026-02-26 12:25:06.178405 | 
2026-02-26 12:25:06.178631 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-02-26 12:25:06.205736 | controller | skipping: Conditional result was False
2026-02-26 12:25:06.213216 | 
2026-02-26 12:25:06.213312 | TASK [fetch-subunit-output : Remove the temporary file]
2026-02-26 12:25:08.123422 | controller | skipping: Conditional result was False
2026-02-26 12:25:08.130557 | 
2026-02-26 12:25:08.130639 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-02-26 12:25:08.144960 | controller | skipping: Conditional result was False
2026-02-26 12:25:08.172443 | 
2026-02-26 12:25:08.172658 | PLAY RECAP
2026-02-26 12:25:08.172708 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-02-26 12:25:08.172729 | 
2026-02-26 12:25:08.316822 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-02-26 12:25:08.317796 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-02-26 12:25:08.919118 | 
2026-02-26 12:25:08.919283 | PLAY [all]
2026-02-26 12:25:08.939643 | 
2026-02-26 12:25:08.939756 | TASK [include_role : fetch-output]
2026-02-26 12:25:08.969116 | controller | ok
2026-02-26 12:25:08.986792 | 
2026-02-26 12:25:08.986928 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-26 12:25:09.052211 | controller | skipping: Conditional result was False
2026-02-26 12:25:09.060591 | 
2026-02-26 12:25:09.060695 | TASK [fetch-output : Set log path for single node]
2026-02-26 12:25:09.102647 | controller | ok
2026-02-26 12:25:09.110155 | 
2026-02-26 12:25:09.110254 | LOOP [fetch-output : Ensure local output dirs]
2026-02-26 12:25:09.583621 | controller -> localhost | ok: "/var/lib/zuul/builds/19c85a839ef642bba1b5c3d09e2a2ed7/work/logs"
2026-02-26 12:25:09.920836 | controller -> localhost | changed: "/var/lib/zuul/builds/19c85a839ef642bba1b5c3d09e2a2ed7/work/artifacts"
2026-02-26 12:25:10.165616 | controller -> localhost | changed: "/var/lib/zuul/builds/19c85a839ef642bba1b5c3d09e2a2ed7/work/docs"
2026-02-26 12:25:10.179288 | 
2026-02-26 12:25:10.179456 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-26 12:25:10.890987 | controller | changed: .d..t...... ./
2026-02-26 12:25:10.891240 | controller | changed: All items complete
2026-02-26 12:25:10.891270 | 
2026-02-26 12:25:11.889491 | controller | changed: .d..t...... ./
2026-02-26 12:25:12.395583 | controller | changed: .d..t...... ./
2026-02-26 12:25:12.428456 | 
2026-02-26 12:25:12.428703 | TASK [include_role : fetch-output-openshift]
2026-02-26 12:25:12.456136 | controller | skipping: Conditional result was False
2026-02-26 12:25:12.470587 | 
2026-02-26 12:25:12.470825 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-26 12:25:12.945623 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008378
2026-02-26 12:25:13.178188 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007895
2026-02-26 12:25:13.238390 | 
2026-02-26 12:25:13.238575 | PLAY [all]
2026-02-26 12:25:13.260004 | 
2026-02-26 12:25:13.260121 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-26 12:25:13.736965 | controller | changed
2026-02-26 12:25:13.786968 | 
2026-02-26 12:25:13.787266 | PLAY RECAP
2026-02-26 12:25:13.787355 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-02-26 12:25:13.787400 | 
2026-02-26 12:25:13.946862 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-02-26 12:25:13.948100 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-02-26 12:25:14.656970 | 
2026-02-26 12:25:14.657184 | PLAY [localhost]
2026-02-26 12:25:14.677073 | 
2026-02-26 12:25:14.677217 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-26 12:25:15.107190 | localhost | changed
2026-02-26 12:25:15.119224 | 
2026-02-26 12:25:15.119507 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-26 12:25:15.156966 | localhost | ok
2026-02-26 12:25:15.177113 | 
2026-02-26 12:25:15.177283 | TASK [add-fileserver : Create SSH private key tempfile]
2026-02-26 12:25:15.585646 | localhost | changed
2026-02-26 12:25:15.591907 | 
2026-02-26 12:25:15.591975 | TASK [add-fileserver : Create SSH private key from secret]
2026-02-26 12:25:16.312293 | localhost | changed
2026-02-26 12:25:16.322798 | 
2026-02-26 12:25:16.322962 | TASK [add-fileserver : Add fileserver ssh key]
2026-02-26 12:25:16.844244 | localhost | Identity added: /var/lib/zuul/builds/19c85a839ef642bba1b5c3d09e2a2ed7/work/tmp/ansible.ucpvb4_a (/var/lib/zuul/builds/19c85a839ef642bba1b5c3d09e2a2ed7/work/tmp/ansible.ucpvb4_a)
2026-02-26 12:25:16.844464 | localhost | ok: Runtime: 0:00:00.014753
2026-02-26 12:25:16.849533 | 
2026-02-26 12:25:16.849631 | TASK [add-fileserver : Remove SSH private key from disk]
2026-02-26 12:25:17.124445 | localhost | ok: Runtime: 0:00:00.009908
2026-02-26 12:25:17.129474 | 
2026-02-26 12:25:17.129539 | TASK [add-fileserver : Add fileserver to inventory]
2026-02-26 12:25:17.217681 | localhost | changed
2026-02-26 12:25:17.228470 | 
2026-02-26 12:25:17.228625 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-02-26 12:25:17.719050 | localhost | changed
2026-02-26 12:25:17.760075 | 
2026-02-26 12:25:17.760221 | PLAY [localhost]
2026-02-26 12:25:17.781176 | 
2026-02-26 12:25:17.781330 | TASK [Generate bulk log download script]
2026-02-26 12:25:17.813845 | localhost | ok
2026-02-26 12:25:17.833096 | 
2026-02-26 12:25:17.833276 | TASK [local-log-download : Check API endpoint is defined]
2026-02-26 12:25:17.875573 | localhost | ok: All assertions passed
2026-02-26 12:25:17.882622 | 
2026-02-26 12:25:17.882716 | TASK [local-log-download : Create download script]
2026-02-26 12:25:18.407787 | localhost -> localhost | changed
2026-02-26 12:25:18.417491 | 
2026-02-26 12:25:18.417585 | TASK [Register quick-download link]
2026-02-26 12:25:18.448705 | localhost | ok
2026-02-26 12:25:18.508757 | 
2026-02-26 12:25:18.508896 | PLAY [logserver.rdoproject.org]
2026-02-26 12:25:18.518955 | 
2026-02-26 12:25:18.519058 | TASK [Set zuul-log-path fact]
2026-02-26 12:25:18.537176 | logserver.rdoproject.org | ok
2026-02-26 12:25:18.547844 | 
2026-02-26 12:25:18.547930 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-26 12:25:18.585400 | logserver.rdoproject.org | ok
2026-02-26 12:25:18.591247 | 
2026-02-26 12:25:18.591313 | TASK [upload-logs : Create log directories]
2026-02-26 12:25:19.462295 | logserver.rdoproject.org | changed
2026-02-26 12:25:19.469836 | 
2026-02-26 12:25:19.469981 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-26 12:25:19.794309 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.008622
2026-02-26 12:25:19.804980 | 
2026-02-26 12:25:19.805174 | TASK [upload-logs : Upload logs to log server]
2026-02-26 12:25:20.567289 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-02-26 12:25:20.571607 | 
2026-02-26 12:25:20.571706 | LOOP [upload-logs : Compress console log and json output]
2026-02-26 12:25:20.644729 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-26 12:25:20.659625 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-26 12:25:20.678670 | 
2026-02-26 12:25:20.678933 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-26 12:25:20.731423 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-26 12:25:20.731875 | 
2026-02-26 12:25:20.735212 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-26 12:25:20.746497 | 
2026-02-26 12:25:20.746689 | LOOP [upload-logs : Upload console log and json output]