2026-03-13 15:50:04.307410 | Job console starting...
2026-03-13 15:50:04.317968 | Updating repositories
2026-03-13 15:50:04.346833 | Preparing job workspace
2026-03-13 15:50:08.097057 | Running Ansible setup...
2026-03-13 15:50:11.855136 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-13 15:50:12.536143 | 
2026-03-13 15:50:12.536289 | PLAY [localhost]
2026-03-13 15:50:12.546244 | 
2026-03-13 15:50:12.546379 | TASK [Gathering Facts]
2026-03-13 15:50:13.509957 | localhost | ok
2026-03-13 15:50:13.530638 | 
2026-03-13 15:50:13.530885 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-13 15:50:13.957973 | localhost -> localhost | changed
2026-03-13 15:50:13.977457 | 
2026-03-13 15:50:13.977597 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-13 15:50:14.823148 | localhost -> localhost | changed
2026-03-13 15:50:14.831606 | 
2026-03-13 15:50:14.831717 | TASK [Setup log path fact]
2026-03-13 15:50:14.848978 | localhost | ok
2026-03-13 15:50:14.861204 | 
2026-03-13 15:50:14.861294 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-13 15:50:14.890469 | localhost | ok
2026-03-13 15:50:14.899938 | 
2026-03-13 15:50:14.900084 | TASK [emit-job-header : Print job information]
2026-03-13 15:50:14.929395 | # Job Information
2026-03-13 15:50:14.929574 | Ansible Version: 2.15.12
2026-03-13 15:50:14.929601 | Job: cifmw-molecule-federation
2026-03-13 15:50:14.929624 | Pipeline: github-check
2026-03-13 15:50:14.929643 | Executor: ze04.softwarefactory-project.io
2026-03-13 15:50:14.929661 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3766
2026-03-13 15:50:14.929698 | Log URL (when completed): https://logserver.rdoproject.org/68f/rdoproject.org/68f7a6ae2a2b4c6fb5d7254382229219/
2026-03-13 15:50:14.929732 | Event ID: 26701850-1ef4-11f1-904b-a854815bb0ff
2026-03-13 15:50:14.934126 | 
2026-03-13 15:50:14.934227 | LOOP [emit-job-header : Print node information]
2026-03-13 15:50:15.037912 | localhost | ok:
2026-03-13 15:50:15.038297 | localhost | # Node Information
2026-03-13 15:50:15.038357 | localhost | Inventory Hostname: controller
2026-03-13 15:50:15.038406 | localhost | Hostname: np0005646441
2026-03-13 15:50:15.038447 | localhost | Username: zuul
2026-03-13 15:50:15.038497 | localhost | Distro: CentOS 9
2026-03-13 15:50:15.038552 | localhost | Provider: vexxhost-nodepool-tripleo
2026-03-13 15:50:15.038606 | localhost | Region: RegionOne
2026-03-13 15:50:15.038654 | localhost | Label: cloud-centos-9-stream-tripleo
2026-03-13 15:50:15.038734 | localhost | Product Name: OpenStack Nova
2026-03-13 15:50:15.038775 | localhost | Interface IP: 38.102.83.192
2026-03-13 15:50:15.083702 | 
2026-03-13 15:50:15.083827 | PLAY [all]
2026-03-13 15:50:15.092126 | 
2026-03-13 15:50:15.092241 | TASK [Gather network facts]
2026-03-13 15:50:15.503149 | controller | ok
2026-03-13 15:50:15.526537 | 
2026-03-13 15:50:15.526979 | TASK [include_role : start-zuul-console]
2026-03-13 15:50:15.548942 | controller | ok
2026-03-13 15:50:15.569269 | 
2026-03-13 15:50:15.569407 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-03-13 15:50:15.962756 | controller | ok
2026-03-13 15:50:15.983328 | 
2026-03-13 15:50:15.983483 | TASK [include_role : add-build-sshkey]
2026-03-13 15:50:16.021834 | controller | ok
2026-03-13 15:50:16.050702 | 
2026-03-13 15:50:16.050834 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-13 15:50:16.360956 | controller -> localhost | ok
2026-03-13 15:50:16.366997 | 
2026-03-13 15:50:16.367094 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-13 15:50:16.396851 | controller | ok
2026-03-13 15:50:16.410769 | controller | included: /var/lib/zuul/builds/68f7a6ae2a2b4c6fb5d7254382229219/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-13 15:50:16.417428 | 
2026-03-13 15:50:16.417495 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-13 15:50:17.167405 | controller -> localhost | Generating public/private rsa key pair.
2026-03-13 15:50:17.167623 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/68f7a6ae2a2b4c6fb5d7254382229219/work/68f7a6ae2a2b4c6fb5d7254382229219_id_rsa.
2026-03-13 15:50:17.167654 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/68f7a6ae2a2b4c6fb5d7254382229219/work/68f7a6ae2a2b4c6fb5d7254382229219_id_rsa.pub.
2026-03-13 15:50:17.167691 | controller -> localhost | The key fingerprint is:
2026-03-13 15:50:17.167717 | controller -> localhost | SHA256:cIeXp4aKJ4lUOE7jsL5U+lg4hqxJngeWglm9P04tKiA zuul-build-sshkey
2026-03-13 15:50:17.167737 | controller -> localhost | The key's randomart image is:
2026-03-13 15:50:17.167755 | controller -> localhost | +---[RSA 3072]----+
2026-03-13 15:50:17.167773 | controller -> localhost | |                 |
2026-03-13 15:50:17.167792 | controller -> localhost | |   .     . .     |
2026-03-13 15:50:17.167809 | controller -> localhost | |. =.. . o + .    |
2026-03-13 15:50:17.167828 | controller -> localhost | | *.+.  o + o     |
2026-03-13 15:50:17.167845 | controller -> localhost | |oo=. .  S o      |
2026-03-13 15:50:17.167862 | controller -> localhost | |E=+..o o .       |
2026-03-13 15:50:17.167879 | controller -> localhost | |=@o.+.= .        |
2026-03-13 15:50:17.167970 | controller -> localhost | |*.O. =o.         |
2026-03-13 15:50:17.168000 | controller -> localhost | |o=.o....         |
2026-03-13 15:50:17.168018 | controller -> localhost | +----[SHA256]-----+
2026-03-13 15:50:17.168093 | controller -> localhost | ok: Runtime: 0:00:00.254154
2026-03-13 15:50:17.174101 | 
2026-03-13 15:50:17.174167 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-13 15:50:17.213574 | controller | ok
2026-03-13 15:50:17.229572 | controller | included: /var/lib/zuul/builds/68f7a6ae2a2b4c6fb5d7254382229219/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-13 15:50:17.242156 | 
2026-03-13 15:50:17.242247 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-13 15:50:17.257786 | controller | skipping: Conditional result was False
2026-03-13 15:50:17.264478 | 
2026-03-13 15:50:17.264575 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-13 15:50:17.747706 | controller | changed
2026-03-13 15:50:17.759423 | 
2026-03-13 15:50:17.759558 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-13 15:50:17.998207 | controller | ok
2026-03-13 15:50:18.004228 | 
2026-03-13 15:50:18.004318 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-13 15:50:18.789469 | controller | changed
2026-03-13 15:50:18.796630 | 
2026-03-13 15:50:18.796752 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-13 15:50:19.619270 | controller | changed
2026-03-13 15:50:19.630524 | 
2026-03-13 15:50:19.630662 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-13 15:50:19.658323 | controller | skipping: Conditional result was False
2026-03-13 15:50:19.667047 | 
2026-03-13 15:50:19.667157 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-13 15:50:20.062554 | controller -> localhost | changed
2026-03-13 15:50:20.072861 | 
2026-03-13 15:50:20.072953 | TASK [add-build-sshkey : Add back temp key]
2026-03-13 15:50:20.412668 | controller -> localhost | Identity added: /var/lib/zuul/builds/68f7a6ae2a2b4c6fb5d7254382229219/work/68f7a6ae2a2b4c6fb5d7254382229219_id_rsa (zuul-build-sshkey)
2026-03-13 15:50:20.412870 | controller -> localhost | ok: Runtime: 0:00:00.009099
2026-03-13 15:50:20.418359 | 
2026-03-13 15:50:20.418419 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-13 15:50:20.760081 | controller | ok
2026-03-13 15:50:20.774544 | 
2026-03-13 15:50:20.774794 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-13 15:50:20.809943 | controller | skipping: Conditional result was False
2026-03-13 15:50:20.835189 | 
2026-03-13 15:50:20.835400 | TASK [include_role : validate-host]
2026-03-13 15:50:20.863153 | controller | ok
2026-03-13 15:50:20.892990 | 
2026-03-13 15:50:20.893120 | TASK [validate-host : Define zuul_info_dir fact]
2026-03-13 15:50:20.922381 | controller | ok
2026-03-13 15:50:20.926950 | 
2026-03-13 15:50:20.927029 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-03-13 15:50:21.184489 | controller -> localhost | ok
2026-03-13 15:50:21.196321 | 
2026-03-13 15:50:21.196469 | TASK [validate-host : Collect information about the host]
2026-03-13 15:50:21.952287 | controller | ok
2026-03-13 15:50:21.963475 | 
2026-03-13 15:50:21.963556 | TASK [validate-host : Sanitize hostname]
2026-03-13 15:50:22.044622 | controller | ok
2026-03-13 15:50:22.051188 | 
2026-03-13 15:50:22.051271 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-03-13 15:50:22.522265 | controller -> localhost | changed
2026-03-13 15:50:22.527650 | 
2026-03-13 15:50:22.527765 | TASK [validate-host : Collect information about zuul worker]
2026-03-13 15:50:22.960258 | controller | ok
2026-03-13 15:50:22.971343 | 
2026-03-13 15:50:22.971487 | TASK [validate-host : Write out all zuul information for each host]
2026-03-13 15:50:23.542207 | controller -> localhost | changed
2026-03-13 15:50:23.551714 | 
2026-03-13 15:50:23.551951 | TASK [include_role : prepare-workspace-openshift]
2026-03-13 15:50:23.568370 | controller | skipping: Conditional result was False
2026-03-13 15:50:23.574149 | 
2026-03-13 15:50:23.574223 | TASK [include_role : remove-zuul-sshkey]
2026-03-13 15:50:23.588075 | controller | skipping: Conditional result was False
2026-03-13 15:50:23.594097 | 
2026-03-13 15:50:23.594175 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-13 15:50:23.857902 | controller | ok: "logs"
2026-03-13 15:50:23.858510 | controller | ok: All items complete
2026-03-13 15:50:23.858576 | 
2026-03-13 15:50:24.086169 | controller | ok: "artifacts"
2026-03-13 15:50:24.281026 | controller | ok: "docs"
2026-03-13 15:50:24.294105 | 
2026-03-13 15:50:24.294226 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-13 15:50:24.568019 | controller | changed: "logs"
2026-03-13 15:50:24.799807 | controller | changed: "artifacts"
2026-03-13 15:50:25.052202 | controller | changed: "docs"
2026-03-13 15:50:25.109467 | 
2026-03-13 15:50:25.109581 | PLAY RECAP
2026-03-13 15:50:25.109638 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-03-13 15:50:25.109674 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-13 15:50:25.109731 | 
2026-03-13 15:50:25.227650 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-13 15:50:25.228620 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2026-03-13 15:50:25.806924 | 
2026-03-13 15:50:25.807041 | PLAY [localhost]
2026-03-13 15:50:25.824759 | 
2026-03-13 15:50:25.824871 | TASK [configure-ssh-config-fips : Ensure ssh config directory]
2026-03-13 15:50:26.270667 | localhost | ok
2026-03-13 15:50:26.275852 | 
2026-03-13 15:50:26.275932 | TASK [configure-ssh-config-fips : Setup ssh config]
2026-03-13 15:50:27.041762 | localhost | changed
2026-03-13 15:50:27.062245 | 
2026-03-13 15:50:27.062406 | PLAY [all]
2026-03-13 15:50:27.078186 | 
2026-03-13 15:50:27.078261 | TASK [include_role : prepare-workspace]
2026-03-13 15:50:27.107698 | controller | ok
2026-03-13 15:50:27.122265 | 
2026-03-13 15:50:27.122343 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-13 15:50:27.495824 | controller | ok
2026-03-13 15:50:27.503542 | 
2026-03-13 15:50:27.503622 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-13 15:50:29.081656 | controller | Output suppressed because no_log was given
2026-03-13 15:50:29.103590 | 
2026-03-13 15:50:29.103799 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-13 15:50:29.402823 | controller | changed: "logs"
2026-03-13 15:50:29.616419 | controller | changed: "artifacts"
2026-03-13 15:50:29.857190 | controller | changed: "docs"
2026-03-13 15:50:29.877908 | 
2026-03-13 15:50:29.878065 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-13 15:50:30.183628 | controller | changed: "logs"
2026-03-13 15:50:30.184157 | controller | changed: All items complete
2026-03-13 15:50:30.184255 | 
2026-03-13 15:50:30.400834 | controller | changed: "artifacts"
2026-03-13 15:50:30.633542 | controller | changed: "docs"
2026-03-13 15:50:30.661402 | 
2026-03-13 15:50:30.661562 | TASK [Check if worker can sudo]
2026-03-13 15:50:31.217764 | controller | ok: Runtime: 0:00:00.049572
2026-03-13 15:50:31.229561 | 
2026-03-13 15:50:31.229750 | TASK [configure-mirrors : Gather needed facts]
2026-03-13 15:50:31.308802 | controller | skipping: Conditional result was False
2026-03-13 15:50:31.321255 | 
2026-03-13 15:50:31.321392 | TASK [configure-mirrors : Set up infrastructure mirrors]
2026-03-13 15:50:31.395848 | controller | ok
2026-03-13 15:50:31.414337 | controller | included: /var/lib/zuul/builds/68f7a6ae2a2b4c6fb5d7254382229219/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml
2026-03-13 15:50:31.457363 | 
2026-03-13 15:50:31.457509 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir]
2026-03-13 15:50:31.780014 | controller | ok
2026-03-13 15:50:31.793272 | 
2026-03-13 15:50:31.793408 | LOOP [configure-mirrors : Include OS-specific variables]
2026-03-13 15:50:31.888860 | controller | ok: "/var/lib/zuul/builds/68f7a6ae2a2b4c6fb5d7254382229219/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml"
2026-03-13 15:50:31.909088 | 
2026-03-13 15:50:31.909275 | TASK [configure-mirrors : Install /etc/pip.conf configuration]
2026-03-13 15:50:32.641655 | controller | changed
2026-03-13 15:50:32.653923 | 
2026-03-13 15:50:32.654060 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors]
2026-03-13 15:50:32.735583 | controller | ok: "/var/lib/zuul/builds/68f7a6ae2a2b4c6fb5d7254382229219/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml"
2026-03-13 15:50:32.735887 | controller | ok: All items complete
2026-03-13 15:50:32.735943 | 
2026-03-13 15:50:32.816253 | controller | included: /var/lib/zuul/builds/68f7a6ae2a2b4c6fb5d7254382229219/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml
2026-03-13 15:50:32.825748 | 
2026-03-13 15:50:32.825840 | LOOP [configure-mirrors : Install CentOS 9 repository files]
2026-03-13 15:50:33.752470 | controller | changed: "etc/yum.repos.d/centos.repo"
2026-03-13 15:50:34.558440 | controller | changed: "etc/yum.repos.d/centos-addons.repo"
2026-03-13 15:50:34.582602 | 
2026-03-13 15:50:34.582957 | TASK [configure-mirrors : Disable deltrarpm]
2026-03-13 15:50:35.061794 | controller | changed: section and option added
2026-03-13 15:50:35.102632 | 
2026-03-13 15:50:35.102774 | LOOP [configure-mirrors : Update yum/dnf cache]
2026-03-13 15:50:35.929654 | controller | 29 files removed
2026-03-13 15:50:35.930090 | controller | ok: Item: dnf clean all Runtime: 0:00:00.472695
2026-03-13 15:50:35.930183 | controller | changed: All items complete
2026-03-13 15:50:35.930231 | 
2026-03-13 15:50:46.831496 | 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-03-13 15:50:46.831722 | controller | DNF version: 4.14.0
2026-03-13 15:50:46.831802 | controller | cachedir: /var/cache/dnf
2026-03-13 15:50:46.831862 | controller | Making cache files for all metadata files.
2026-03-13 15:50:46.831919 | controller | baseos: has expired and will be refreshed.
2026-03-13 15:50:46.831972 | controller | appstream: has expired and will be refreshed.
2026-03-13 15:50:46.832023 | controller | crb: has expired and will be refreshed.
2026-03-13 15:50:46.832322 | controller | extras-common: has expired and will be refreshed.
2026-03-13 15:50:46.832382 | controller | repo: downloading from remote: baseos
2026-03-13 15:50:46.832424 | controller | CentOS Stream 9 - BaseOS                         82 MB/s | 8.9 MB     00:00
2026-03-13 15:50:46.832466 | controller | baseos: using metadata from Mon 09 Mar 2026 10:57:52 AM EDT.
2026-03-13 15:50:46.832504 | controller | repo: downloading from remote: appstream
2026-03-13 15:50:46.832543 | controller | CentOS Stream 9 - AppStream                     120 MB/s |  27 MB     00:00
2026-03-13 15:50:46.832583 | controller | appstream: using metadata from Mon 09 Mar 2026 11:01:27 AM EDT.
2026-03-13 15:50:46.832620 | controller | repo: downloading from remote: crb
2026-03-13 15:50:46.832659 | controller | CentOS Stream 9 - CRB                            75 MB/s | 8.0 MB     00:00
2026-03-13 15:50:46.832765 | controller | crb: using metadata from Mon 09 Mar 2026 11:06:14 AM EDT.
2026-03-13 15:50:46.832810 | controller | repo: downloading from remote: extras-common
2026-03-13 15:50:46.832852 | controller | CentOS Stream 9 - Extras packages               1.7 MB/s |  20 kB     00:00
2026-03-13 15:50:46.832892 | controller | extras-common: using metadata from Mon 09 Mar 2026 09:49:54 AM EDT.
2026-03-13 15:50:46.832931 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)'
2026-03-13 15:50:46.832974 | controller | Completion plugin: Generating completion cache...
2026-03-13 15:50:46.833028 | controller | Metadata cache created.
2026-03-13 15:50:46.833105 | controller | ok: Item: dnf makecache -v Runtime: 0:00:10.661714
2026-03-13 15:50:46.866456 | 
2026-03-13 15:50:46.866623 | PLAY RECAP
2026-03-13 15:50:46.866737 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-03-13 15:50:46.866802 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-13 15:50:46.866842 | 
2026-03-13 15:50:46.985021 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2026-03-13 15:50:46.986627 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-13 15:50:47.615351 | 
2026-03-13 15:50:47.615599 | PLAY [all]
2026-03-13 15:50:47.639749 | 
2026-03-13 15:50:47.639844 | TASK [Install binary dependencies]
2026-03-13 15:50:47.705183 | controller | ok
2026-03-13 15:50:47.729035 | 
2026-03-13 15:50:47.729282 | TASK [bindep : Include find tasks]
2026-03-13 15:50:47.772861 | controller | ok
2026-03-13 15:50:47.781500 | controller | included: /var/lib/zuul/builds/68f7a6ae2a2b4c6fb5d7254382229219/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-03-13 15:50:47.787789 | 
2026-03-13 15:50:47.787856 | TASK [bindep : Look for bindep.txt]
2026-03-13 15:50:48.223315 | controller | ok
2026-03-13 15:50:48.238330 | 
2026-03-13 15:50:48.238480 | TASK [bindep : Define bindep_file fact]
2026-03-13 15:50:48.290172 | controller | ok
2026-03-13 15:50:48.302194 | 
2026-03-13 15:50:48.302339 | TASK [bindep : Look for other-requirements.txt]
2026-03-13 15:50:48.332795 | controller | skipping: Conditional result was False
2026-03-13 15:50:48.348177 | 
2026-03-13 15:50:48.348488 | TASK [bindep : Define bindep_file fact]
2026-03-13 15:50:48.388883 | controller | skipping: Conditional result was False
2026-03-13 15:50:48.404892 | 
2026-03-13 15:50:48.405073 | TASK [bindep : Look for bindep fallback file]
2026-03-13 15:50:48.447040 | controller | skipping: Conditional result was False
2026-03-13 15:50:48.467208 | 
2026-03-13 15:50:48.467494 | TASK [bindep : Define bindep_file fact]
2026-03-13 15:50:48.507322 | controller | skipping: Conditional result was False
2026-03-13 15:50:48.521124 | 
2026-03-13 15:50:48.521266 | TASK [bindep : Include bindep tasks]
2026-03-13 15:50:48.584723 | controller | ok
2026-03-13 15:50:48.599820 | controller | included: /var/lib/zuul/builds/68f7a6ae2a2b4c6fb5d7254382229219/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml
2026-03-13 15:50:48.613968 | 
2026-03-13 15:50:48.614048 | TASK [bindep : Look for bindep command]
2026-03-13 15:50:48.651466 | controller | skipping: Conditional result was False
2026-03-13 15:50:48.661188 | 
2026-03-13 15:50:48.661278 | TASK [bindep : Check for system bindep]
2026-03-13 15:50:49.201570 | controller | ok: Runtime: 0:00:00.004868
2026-03-13 15:50:49.214085 | 
2026-03-13 15:50:49.214225 | TASK [bindep : Define bindep_command fact]
2026-03-13 15:50:49.253964 | controller | skipping: Conditional result was False
2026-03-13 15:50:49.269658 | 
2026-03-13 15:50:49.269861 | TASK [bindep : Include install tasks]
2026-03-13 15:50:49.319655 | controller | ok
2026-03-13 15:50:49.338029 | controller | included: /var/lib/zuul/builds/68f7a6ae2a2b4c6fb5d7254382229219/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml
2026-03-13 15:50:49.354950 | 
2026-03-13 15:50:49.355040 | TASK [bindep : Create temp dir for bindep]
2026-03-13 15:50:49.721535 | controller | changed
2026-03-13 15:50:49.727113 | 
2026-03-13 15:50:49.727174 | TASK [Ensure we have pip dependencies]
2026-03-13 15:50:49.747501 | controller | ok
2026-03-13 15:50:49.771492 | 
2026-03-13 15:50:49.771566 | TASK [ensure-pip : Check if pip is installed]
2026-03-13 11:50:50.035562 | controller | /usr/bin/pip3
2026-03-13 11:50:50.063603 | controller | /usr/bin/python3: No module named wheel
2026-03-13 15:50:50.301383 | controller | ok: Runtime: 0:00:00.038421
2026-03-13 15:50:50.313786 | 
2026-03-13 15:50:50.313924 | LOOP [ensure-pip : Install pip from packages]
2026-03-13 15:50:50.357317 | controller | ok: "/var/lib/zuul/builds/68f7a6ae2a2b4c6fb5d7254382229219/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml"
2026-03-13 15:50:50.382600 | controller | included: /var/lib/zuul/builds/68f7a6ae2a2b4c6fb5d7254382229219/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml
2026-03-13 15:50:50.406980 | 
2026-03-13 15:50:50.407088 | TASK [ensure-pip : Install Python 3 pip]
2026-03-13 15:50:51.798784 | controller | ok: Nothing to do
2026-03-13 15:50:51.810968 | 
2026-03-13 15:50:51.811154 | TASK [ensure-pip : Install Python3-wheel on older CentOS]
2026-03-13 15:50:53.272775 | controller | changed
2026-03-13 15:50:53.288613 | 
2026-03-13 15:50:53.288843 | TASK [ensure-pip : Check for EPEL repository]
2026-03-13 15:50:53.360202 | controller | skipping: Conditional result was False
2026-03-13 15:50:53.380777 | 
2026-03-13 15:50:53.380958 | TASK [ensure-pip : Fail with instructions when EPEL is not installed]
2026-03-13 15:50:53.441217 | controller | skipping: Conditional result was False
2026-03-13 15:50:53.451248 | 
2026-03-13 15:50:53.451362 | TASK [ensure-pip : Install Python 2 pip]
2026-03-13 15:50:53.501489 | controller | skipping: Conditional result was False
2026-03-13 15:50:53.518174 | 
2026-03-13 15:50:53.518351 | TASK [ensure-pip : Ensure setuptools]
2026-03-13 15:50:53.538449 | controller | skipping: Conditional result was False
2026-03-13 15:50:53.558719 | 
2026-03-13 15:50:53.559056 | TASK [ensure-pip : Check for ensurepip module]
2026-03-13 15:50:54.105247 | controller | skipping: Conditional result was False
2026-03-13 15:50:54.120365 | 
2026-03-13 15:50:54.120526 | TASK [ensure-pip : Ensure python3-venv]
2026-03-13 15:50:54.151851 | controller | skipping: Conditional result was False
2026-03-13 15:50:54.167091 | 
2026-03-13 15:50:54.167243 | TASK [ensure-pip : Install pip from source]
2026-03-13 15:50:54.196964 | controller | skipping: Conditional result was False
2026-03-13 15:50:54.213169 | 
2026-03-13 15:50:54.213332 | TASK [ensure-pip : Probe for venv python full path]
2026-03-13 11:50:54.529800 | controller | /usr/bin/python3
2026-03-13 15:50:54.764934 | controller | ok: Runtime: 0:00:00.006732
2026-03-13 15:50:54.781957 | 
2026-03-13 15:50:54.782138 | TASK [ensure-pip : Set host default]
2026-03-13 15:50:54.866640 | controller | ok
2026-03-13 15:50:54.880570 | 
2026-03-13 15:50:54.880862 | TASK [ensure-pip : Set ensure_pip_virtualenv_command]
2026-03-13 15:50:54.956365 | controller | ok
2026-03-13 15:50:54.980245 | 
2026-03-13 15:50:54.980512 | TASK [bindep : Install bindep into temporary venv]
2026-03-13 15:50:59.067181 | controller | changed
2026-03-13 15:50:59.079546 | 
2026-03-13 15:50:59.079740 | TASK [bindep : Define bindep_command]
2026-03-13 15:50:59.121230 | controller | ok
2026-03-13 15:50:59.133070 | 
2026-03-13 15:50:59.133207 | LOOP [bindep : Include package tasks]
2026-03-13 15:50:59.212936 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt"
2026-03-13 15:50:59.213301 | controller | ok: All items complete
2026-03-13 15:50:59.213367 | 
2026-03-13 15:50:59.225839 | controller | included: /var/lib/zuul/builds/68f7a6ae2a2b4c6fb5d7254382229219/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml
2026-03-13 15:50:59.241010 | 
2026-03-13 15:50:59.241097 | TASK [bindep : Define bindep_run fact]
2026-03-13 15:50:59.290161 | controller | ok
2026-03-13 15:50:59.304505 | 
2026-03-13 15:50:59.304730 | TASK [bindep : Get list of packages to install from bindep]
2026-03-13 11:51:00.897144 | controller | podman
2026-03-13 11:51:00.937508 | controller | python3-jmespath
2026-03-13 11:51:00.937555 | controller | python3-libvirt
2026-03-13 11:51:00.937563 | controller | python3-lxml
2026-03-13 11:51:00.937573 | controller | python3-netaddr
2026-03-13 15:51:01.369197 | controller | ok: Runtime: 0:00:01.329129
2026-03-13 15:51:01.384329 | 
2026-03-13 15:51:01.384517 | TASK [bindep : Install distro packages from bindep]
2026-03-13 15:51:44.669578 | controller | changed
2026-03-13 15:51:44.683077 | 
2026-03-13 15:51:44.683248 | TASK [bindep : Check that packages are installed]
2026-03-13 15:51:46.231048 | controller | ok: Runtime: 0:00:01.096301
2026-03-13 15:51:46.237022 | 
2026-03-13 15:51:46.237093 | TASK [bindep : Fail if we cannot install all packages]
2026-03-13 15:51:46.261196 | controller | skipping: Conditional result was False
2026-03-13 15:51:46.271621 | 
2026-03-13 15:51:46.271715 | TASK [Run test-setup role]
2026-03-13 15:51:46.292567 | controller | ok
2026-03-13 15:51:46.309554 | 
2026-03-13 15:51:46.309620 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-03-13 15:51:46.564858 | controller | ok
2026-03-13 15:51:46.572194 | 
2026-03-13 15:51:46.572320 | TASK [test-setup : Run tools/test-setup.sh]
2026-03-13 15:51:47.110215 | controller | skipping: Conditional result was False
2026-03-13 15:51:47.152032 | 
2026-03-13 15:51:47.152217 | TASK [bindep : Remove bindep temp dir]
2026-03-13 15:51:47.522132 | controller | ok
2026-03-13 15:51:47.538764 | 
2026-03-13 15:51:47.538863 | PLAY RECAP
2026-03-13 15:51:47.538920 | controller | ok: 23 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0
2026-03-13 15:51:47.538949 | 
2026-03-13 15:51:47.664513 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-13 15:51:47.666360 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2026-03-13 15:51:48.266749 | 
2026-03-13 15:51:48.266856 | PLAY [all]
2026-03-13 15:51:48.286382 | 
2026-03-13 15:51:48.286467 | TASK [Abort when test_command variable is undefined]
2026-03-13 15:51:48.321154 | controller | skipping: Conditional result was False
2026-03-13 15:51:48.327211 | 
2026-03-13 15:51:48.327286 | TASK [Convert test_command to list]
2026-03-13 15:51:48.382576 | controller | skipping: Conditional result was False
2026-03-13 15:51:48.397478 | 
2026-03-13 15:51:48.397624 | TASK [Use test_command list]
2026-03-13 15:51:48.462295 | controller | ok
2026-03-13 15:51:48.469842 | 
2026-03-13 15:51:48.469927 | LOOP [Run test_command]
2026-03-13 15:51:48.872618 | controller | no check to run
2026-03-13 15:51:48.873038 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.002966
2026-03-13 15:51:48.931508 | 
2026-03-13 15:51:48.931655 | PLAY RECAP
2026-03-13 15:51:48.931774 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-03-13 15:51:48.931844 | 
2026-03-13 15:51:49.034146 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2026-03-13 15:51:49.035922 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-13 15:51:49.618966 | 
2026-03-13 15:51:49.619076 | PLAY [all]
2026-03-13 15:51:49.639463 | 
2026-03-13 15:51:49.639549 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-03-13 15:51:49.974166 | controller | changed: non-zero return code
2026-03-13 15:51:49.986865 | 
2026-03-13 15:51:49.987024 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-03-13 15:51:50.015134 | controller | skipping: Conditional result was False
2026-03-13 15:51:50.027622 | 
2026-03-13 15:51:50.027812 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-03-13 15:51:50.063482 | 
2026-03-13 15:51:50.063657 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-03-13 15:51:50.096035 | 
2026-03-13 15:51:50.096229 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-03-13 15:51:50.121810 | controller | skipping: Conditional result was False
2026-03-13 15:51:50.129960 | 
2026-03-13 15:51:50.130047 | LOOP [fetch-subunit-output : Generate subunit file]
2026-03-13 15:51:50.163416 | 
2026-03-13 15:51:50.163606 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-03-13 15:51:50.191202 | controller | skipping: Conditional result was False
2026-03-13 15:51:50.199114 | 
2026-03-13 15:51:50.199211 | TASK [fetch-subunit-output : Remove the temporary file]
2026-03-13 15:51:50.224958 | controller | skipping: Conditional result was False
2026-03-13 15:51:50.233720 | 
2026-03-13 15:51:50.233821 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-03-13 15:51:50.259607 | controller | skipping: Conditional result was False
2026-03-13 15:51:50.299317 | 
2026-03-13 15:51:50.299429 | PLAY RECAP
2026-03-13 15:51:50.299486 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-13 15:51:50.299514 | 
2026-03-13 15:51:50.413957 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-13 15:51:50.415750 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-13 15:51:51.012118 | 
2026-03-13 15:51:51.012230 | PLAY [all]
2026-03-13 15:51:51.031295 | 
2026-03-13 15:51:51.031385 | TASK [include_role : fetch-output]
2026-03-13 15:51:51.071296 | controller | ok
2026-03-13 15:51:51.088175 | 
2026-03-13 15:51:51.088261 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-13 15:51:51.133021 | controller | skipping: Conditional result was False
2026-03-13 15:51:51.140910 | 
2026-03-13 15:51:51.141004 | TASK [fetch-output : Set log path for single node]
2026-03-13 15:51:51.182805 | controller | ok
2026-03-13 15:51:51.189875 | 
2026-03-13 15:51:51.189963 | LOOP [fetch-output : Ensure local output dirs]
2026-03-13 15:51:51.605886 | controller -> localhost | ok: "/var/lib/zuul/builds/68f7a6ae2a2b4c6fb5d7254382229219/work/logs"
2026-03-13 15:51:51.864953 | controller -> localhost | changed: "/var/lib/zuul/builds/68f7a6ae2a2b4c6fb5d7254382229219/work/artifacts"
2026-03-13 15:51:52.080518 | controller -> localhost | changed: "/var/lib/zuul/builds/68f7a6ae2a2b4c6fb5d7254382229219/work/docs"
2026-03-13 15:51:52.088590 | 
2026-03-13 15:51:52.088665 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-13 15:51:52.792914 | controller | changed: .d..t...... ./
2026-03-13 15:51:52.793424 | controller | changed: All items complete
2026-03-13 15:51:52.793490 | 
2026-03-13 15:51:53.252853 | controller | changed: .d..t...... ./
2026-03-13 15:51:53.712332 | controller | changed: .d..t...... ./
2026-03-13 15:51:53.732766 | 
2026-03-13 15:51:53.732832 | TASK [include_role : fetch-output-openshift]
2026-03-13 15:51:53.757218 | controller | skipping: Conditional result was False
2026-03-13 15:51:53.762912 | 
2026-03-13 15:51:53.762975 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-13 15:51:54.173061 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.009114
2026-03-13 15:51:54.429728 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.013546
2026-03-13 15:51:54.462329 | 
2026-03-13 15:51:54.462390 | PLAY [all]
2026-03-13 15:51:54.476244 | 
2026-03-13 15:51:54.476309 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-03-13 15:51:54.931309 | controller | changed
2026-03-13 15:51:54.953300 | 
2026-03-13 15:51:54.953351 | PLAY RECAP
2026-03-13 15:51:54.953393 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-03-13 15:51:54.953412 | 
2026-03-13 15:51:55.043419 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-13 15:51:55.044244 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-03-13 15:51:55.640129 | 
2026-03-13 15:51:55.640274 | PLAY [localhost]
2026-03-13 15:51:55.658528 | 
2026-03-13 15:51:55.658623 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-13 15:51:56.043142 | localhost | changed
2026-03-13 15:51:56.047778 | 
2026-03-13 15:51:56.047850 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-13 15:51:56.075316 | localhost | ok
2026-03-13 15:51:56.083360 | 
2026-03-13 15:51:56.083442 | TASK [add-fileserver : Create SSH private key tempfile]
2026-03-13 15:51:56.425766 | localhost | changed
2026-03-13 15:51:56.430879 | 
2026-03-13 15:51:56.430946 | TASK [add-fileserver : Create SSH private key from secret]
2026-03-13 15:51:57.167250 | localhost | changed
2026-03-13 15:51:57.178280 | 
2026-03-13 15:51:57.178414 | TASK [add-fileserver : Add fileserver ssh key]
2026-03-13 15:51:57.616297 | localhost | Identity added: /var/lib/zuul/builds/68f7a6ae2a2b4c6fb5d7254382229219/work/tmp/ansible.l6qn9o3u (/var/lib/zuul/builds/68f7a6ae2a2b4c6fb5d7254382229219/work/tmp/ansible.l6qn9o3u)
2026-03-13 15:51:57.616489 | localhost | ok: Runtime: 0:00:00.007324
2026-03-13 15:51:57.620804 | 
2026-03-13 15:51:57.620868 | TASK [add-fileserver : Remove SSH private key from disk]
2026-03-13 15:51:57.899776 | localhost | ok: Runtime: 0:00:00.005383
2026-03-13 15:51:57.911187 | 
2026-03-13 15:51:57.911341 | TASK [add-fileserver : Add fileserver to inventory]
2026-03-13 15:51:57.969916 | localhost | changed
2026-03-13 15:51:57.989589 | 
2026-03-13 15:51:57.989828 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-03-13 15:51:58.373092 | localhost | changed
2026-03-13 15:51:58.416038 | 
2026-03-13 15:51:58.416128 | PLAY [localhost]
2026-03-13 15:51:58.433005 | 
2026-03-13 15:51:58.433094 | TASK [Generate bulk log download script]
2026-03-13 15:51:58.463573 | localhost | ok
2026-03-13 15:51:58.480065 | 
2026-03-13 15:51:58.480155 | TASK [local-log-download : Check API endpoint is defined]
2026-03-13 15:51:58.522160 | localhost | ok: All assertions passed
2026-03-13 15:51:58.528487 | 
2026-03-13 15:51:58.528573 | TASK [local-log-download : Create download script]
2026-03-13 15:51:59.022661 | localhost -> localhost | changed
2026-03-13 15:51:59.032084 | 
2026-03-13 15:51:59.032279 | TASK [Register quick-download link]
2026-03-13 15:51:59.060569 | localhost | ok
2026-03-13 15:51:59.102316 | 
2026-03-13 15:51:59.102425 | PLAY [logserver.rdoproject.org]
2026-03-13 15:51:59.112256 | 
2026-03-13 15:51:59.112320 | TASK [Set zuul-log-path fact]
2026-03-13 15:51:59.129413 | logserver.rdoproject.org | ok
2026-03-13 15:51:59.138579 | 
2026-03-13 15:51:59.138643 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-13 15:51:59.175359 | logserver.rdoproject.org | ok
2026-03-13 15:51:59.182738 | 
2026-03-13 15:51:59.182827 | TASK [upload-logs : Create log directories]
2026-03-13 15:52:00.100533 | logserver.rdoproject.org | changed
2026-03-13 15:52:00.107656 | 
2026-03-13 15:52:00.107823 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-03-13 15:52:00.452306 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.009580
2026-03-13 15:52:00.468014 | 
2026-03-13 15:52:00.468400 | TASK [upload-logs : Upload logs to log server]
2026-03-13 15:52:01.252331 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-03-13 15:52:01.259061 | 
2026-03-13 15:52:01.259203 | LOOP [upload-logs : Compress console log and json output]
2026-03-13 15:52:01.332711 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-13 15:52:01.341846 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-13 15:52:01.355588 | 
2026-03-13 15:52:01.355817 | LOOP [upload-logs : Upload compressed console log and json output]
2026-03-13 15:52:01.405130 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-13 15:52:01.405576 | 
2026-03-13 15:52:01.407972 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-13 15:52:01.421863 | 
2026-03-13 15:52:01.422033 | LOOP [upload-logs : Upload console log and json output]