2026-01-23 11:43:21.222198 | Job console starting...
2026-01-23 11:43:21.232090 | Updating repositories
2026-01-23 11:43:21.265068 | Preparing job workspace
2026-01-23 11:43:24.727919 | Running Ansible setup...
2026-01-23 11:43:28.427556 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-01-23 11:43:28.997353 | 
2026-01-23 11:43:28.997460 | PLAY [localhost]
2026-01-23 11:43:29.005821 | 
2026-01-23 11:43:29.005889 | TASK [Gathering Facts]
2026-01-23 11:43:29.981165 | localhost | ok
2026-01-23 11:43:29.999359 | 
2026-01-23 11:43:29.999494 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-23 11:43:30.446434 | localhost -> localhost | changed
2026-01-23 11:43:30.457543 | 
2026-01-23 11:43:30.457741 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-23 11:43:31.288550 | localhost -> localhost | changed
2026-01-23 11:43:31.312037 | 
2026-01-23 11:43:31.312131 | TASK [Setup log path fact]
2026-01-23 11:43:31.331924 | localhost | ok
2026-01-23 11:43:31.347376 | 
2026-01-23 11:43:31.347464 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-23 11:43:31.376855 | localhost | ok
2026-01-23 11:43:31.387228 | 
2026-01-23 11:43:31.387314 | TASK [emit-job-header : Print job information]
2026-01-23 11:43:31.425880 | # Job Information
2026-01-23 11:43:31.426026 | Ansible Version: 2.15.12
2026-01-23 11:43:31.426051 | Job: cifmw-molecule-federation
2026-01-23 11:43:31.426070 | Pipeline: github-check
2026-01-23 11:43:31.426088 | Executor: ze02.softwarefactory-project.io
2026-01-23 11:43:31.426105 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3575
2026-01-23 11:43:31.426123 | Log URL (when completed): https://logserver.rdoproject.org/ba7/rdoproject.org/ba71653b3abe48a8a049fd3426c53d93/
2026-01-23 11:43:31.426140 | Event ID: 80f5f440-f850-11f0-9420-2ae891876bc9
2026-01-23 11:43:31.429837 | 
2026-01-23 11:43:31.429902 | LOOP [emit-job-header : Print node information]
2026-01-23 11:43:31.519236 | localhost | ok:
2026-01-23 11:43:31.519511 | localhost | # Node Information
2026-01-23 11:43:31.519543 | localhost | Inventory Hostname: controller
2026-01-23 11:43:31.519570 | localhost | Hostname: np0005593458
2026-01-23 11:43:31.519593 | localhost | Username: zuul
2026-01-23 11:43:31.519616 | localhost | Distro: CentOS 9
2026-01-23 11:43:31.519635 | localhost | Provider: vexxhost-nodepool-tripleo
2026-01-23 11:43:31.519652 | localhost | Region: RegionOne
2026-01-23 11:43:31.519692 | localhost | Label: cloud-centos-9-stream-tripleo
2026-01-23 11:43:31.519712 | localhost | Product Name: OpenStack Nova
2026-01-23 11:43:31.519729 | localhost | Interface IP: 38.102.83.27
2026-01-23 11:43:31.564396 | 
2026-01-23 11:43:31.564509 | PLAY [all]
2026-01-23 11:43:31.573280 | 
2026-01-23 11:43:31.573363 | TASK [Gather network facts]
2026-01-23 11:43:32.101742 | controller | ok
2026-01-23 11:43:32.133743 | 
2026-01-23 11:43:32.133870 | TASK [include_role : start-zuul-console]
2026-01-23 11:43:32.156561 | controller | ok
2026-01-23 11:43:32.172048 | 
2026-01-23 11:43:32.172129 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-01-23 11:43:32.637883 | controller | ok
2026-01-23 11:43:32.646265 | 
2026-01-23 11:43:32.646323 | TASK [include_role : add-build-sshkey]
2026-01-23 11:43:32.675063 | controller | ok
2026-01-23 11:43:32.687574 | 
2026-01-23 11:43:32.687633 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-23 11:43:32.976757 | controller -> localhost | ok
2026-01-23 11:43:32.989803 | 
2026-01-23 11:43:32.989929 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-23 11:43:33.026210 | controller | ok
2026-01-23 11:43:33.053510 | controller | included: /var/lib/zuul/builds/ba71653b3abe48a8a049fd3426c53d93/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-23 11:43:33.061833 | 
2026-01-23 11:43:33.061919 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-23 11:43:33.874329 | controller -> localhost | Generating public/private rsa key pair.
2026-01-23 11:43:33.874734 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ba71653b3abe48a8a049fd3426c53d93/work/ba71653b3abe48a8a049fd3426c53d93_id_rsa.
2026-01-23 11:43:33.874808 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ba71653b3abe48a8a049fd3426c53d93/work/ba71653b3abe48a8a049fd3426c53d93_id_rsa.pub.
2026-01-23 11:43:33.874855 | controller -> localhost | The key fingerprint is:
2026-01-23 11:43:33.874898 | controller -> localhost | SHA256:Nx3hxXeYxjxgpmU329U5kNITCRwrEMgXaoM+AvDVnko zuul-build-sshkey
2026-01-23 11:43:33.874939 | controller -> localhost | The key's randomart image is:
2026-01-23 11:43:33.874979 | controller -> localhost | +---[RSA 3072]----+
2026-01-23 11:43:33.875016 | controller -> localhost | |.   o..+o .o@BOo+|
2026-01-23 11:43:33.875056 | controller -> localhost | |.. ..oo..  X.OO*=|
2026-01-23 11:43:33.875094 | controller -> localhost | |. .. =.. ...+.oo+|
2026-01-23 11:43:33.875132 | controller -> localhost | |. . E +   .. .   |
2026-01-23 11:43:33.875170 | controller -> localhost | | . + .  S o .    |
2026-01-23 11:43:33.875207 | controller -> localhost | |  . o    . .     |
2026-01-23 11:43:33.875243 | controller -> localhost | |                 |
2026-01-23 11:43:33.875284 | controller -> localhost | |                 |
2026-01-23 11:43:33.875322 | controller -> localhost | |                 |
2026-01-23 11:43:33.875359 | controller -> localhost | +----[SHA256]-----+
2026-01-23 11:43:33.875450 | controller -> localhost | ok: Runtime: 0:00:00.313653
2026-01-23 11:43:33.887418 | 
2026-01-23 11:43:33.887542 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-23 11:43:33.924053 | controller | ok
2026-01-23 11:43:33.944231 | controller | included: /var/lib/zuul/builds/ba71653b3abe48a8a049fd3426c53d93/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-23 11:43:33.957114 | 
2026-01-23 11:43:33.957192 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-23 11:43:33.983581 | controller | skipping: Conditional result was False
2026-01-23 11:43:33.992402 | 
2026-01-23 11:43:33.992484 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-23 11:43:34.478227 | controller | changed
2026-01-23 11:43:34.488791 | 
2026-01-23 11:43:34.488951 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-23 11:43:35.167212 | controller | ok
2026-01-23 11:43:35.174263 | 
2026-01-23 11:43:35.174352 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-23 11:43:36.093848 | controller | changed
2026-01-23 11:43:36.105030 | 
2026-01-23 11:43:36.105164 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-23 11:43:37.101526 | controller | changed
2026-01-23 11:43:37.113806 | 
2026-01-23 11:43:37.113936 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-23 11:43:37.140753 | controller | skipping: Conditional result was False
2026-01-23 11:43:37.152304 | 
2026-01-23 11:43:37.152440 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-23 11:43:37.525133 | controller -> localhost | changed
2026-01-23 11:43:37.547209 | 
2026-01-23 11:43:37.547287 | TASK [add-build-sshkey : Add back temp key]
2026-01-23 11:43:37.845444 | controller -> localhost | Identity added: /var/lib/zuul/builds/ba71653b3abe48a8a049fd3426c53d93/work/ba71653b3abe48a8a049fd3426c53d93_id_rsa (zuul-build-sshkey)
2026-01-23 11:43:37.845698 | controller -> localhost | ok: Runtime: 0:00:00.014562
2026-01-23 11:43:37.851975 | 
2026-01-23 11:43:37.852038 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-23 11:43:38.208103 | controller | ok
2026-01-23 11:43:38.223551 | 
2026-01-23 11:43:38.223658 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-23 11:43:38.259847 | controller | skipping: Conditional result was False
2026-01-23 11:43:38.279425 | 
2026-01-23 11:43:38.279564 | TASK [include_role : validate-host]
2026-01-23 11:43:38.312338 | controller | ok
2026-01-23 11:43:38.340067 | 
2026-01-23 11:43:38.340160 | TASK [validate-host : Define zuul_info_dir fact]
2026-01-23 11:43:38.381461 | controller | ok
2026-01-23 11:43:38.388133 | 
2026-01-23 11:43:38.388217 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-01-23 11:43:38.640588 | controller -> localhost | ok
2026-01-23 11:43:38.685742 | 
2026-01-23 11:43:38.685864 | TASK [validate-host : Collect information about the host]
2026-01-23 11:43:39.445001 | controller | ok
2026-01-23 11:43:39.462964 | 
2026-01-23 11:43:39.463092 | TASK [validate-host : Sanitize hostname]
2026-01-23 11:43:39.539633 | controller | ok
2026-01-23 11:43:39.549636 | 
2026-01-23 11:43:39.549796 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-01-23 11:43:39.995515 | controller -> localhost | changed
2026-01-23 11:43:40.017327 | 
2026-01-23 11:43:40.017471 | TASK [validate-host : Collect information about zuul worker]
2026-01-23 11:43:40.411367 | controller | ok
2026-01-23 11:43:40.421360 | 
2026-01-23 11:43:40.421492 | TASK [validate-host : Write out all zuul information for each host]
2026-01-23 11:43:40.928660 | controller -> localhost | changed
2026-01-23 11:43:40.937699 | 
2026-01-23 11:43:40.937757 | TASK [include_role : prepare-workspace-openshift]
2026-01-23 11:43:40.950909 | controller | skipping: Conditional result was False
2026-01-23 11:43:40.956431 | 
2026-01-23 11:43:40.956489 | TASK [include_role : remove-zuul-sshkey]
2026-01-23 11:43:40.969823 | controller | skipping: Conditional result was False
2026-01-23 11:43:40.974951 | 
2026-01-23 11:43:40.975011 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-23 11:43:41.214283 | controller | ok: "logs"
2026-01-23 11:43:41.214758 | controller | ok: All items complete
2026-01-23 11:43:41.214826 | 
2026-01-23 11:43:41.472795 | controller | ok: "artifacts"
2026-01-23 11:43:41.677174 | controller | ok: "docs"
2026-01-23 11:43:41.690783 | 
2026-01-23 11:43:41.690997 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-23 11:43:41.990802 | controller | changed: "logs"
2026-01-23 11:43:42.243931 | controller | changed: "artifacts"
2026-01-23 11:43:42.490226 | controller | changed: "docs"
2026-01-23 11:43:42.535765 | 
2026-01-23 11:43:42.535902 | PLAY RECAP
2026-01-23 11:43:42.535961 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-01-23 11:43:42.535995 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-23 11:43:42.536018 | 
2026-01-23 11:43:42.673148 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-01-23 11:43:42.674925 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2026-01-23 11:43:43.247119 | 
2026-01-23 11:43:43.247230 | PLAY [localhost]
2026-01-23 11:43:43.262935 | 
2026-01-23 11:43:43.263007 | TASK [configure-ssh-config-fips : Ensure ssh config directory]
2026-01-23 11:43:43.700271 | localhost | ok
2026-01-23 11:43:43.705805 | 
2026-01-23 11:43:43.705881 | TASK [configure-ssh-config-fips : Setup ssh config]
2026-01-23 11:43:44.428879 | localhost | changed
2026-01-23 11:43:44.456745 | 
2026-01-23 11:43:44.456859 | PLAY [all]
2026-01-23 11:43:44.472323 | 
2026-01-23 11:43:44.472382 | TASK [include_role : prepare-workspace]
2026-01-23 11:43:44.503788 | controller | ok
2026-01-23 11:43:44.521218 | 
2026-01-23 11:43:44.521296 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-01-23 11:43:44.980915 | controller | ok
2026-01-23 11:43:44.994732 | 
2026-01-23 11:43:44.994866 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-01-23 11:43:46.915173 | controller | Output suppressed because no_log was given
2026-01-23 11:43:46.927720 | 
2026-01-23 11:43:46.927791 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-23 11:43:47.225344 | controller | changed: "logs"
2026-01-23 11:43:47.480886 | controller | changed: "artifacts"
2026-01-23 11:43:47.695002 | controller | changed: "docs"
2026-01-23 11:43:47.710055 | 
2026-01-23 11:43:47.710245 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-23 11:43:47.993600 | controller | changed: "logs"
2026-01-23 11:43:47.994003 | controller | changed: All items complete
2026-01-23 11:43:47.994063 | 
2026-01-23 11:43:48.262640 | controller | changed: "artifacts"
2026-01-23 11:43:48.542390 | controller | changed: "docs"
2026-01-23 11:43:48.574033 | 
2026-01-23 11:43:48.574220 | TASK [Check if worker can sudo]
2026-01-23 11:43:49.123739 | controller | ok: Runtime: 0:00:00.044319
2026-01-23 11:43:49.135641 | 
2026-01-23 11:43:49.135829 | TASK [configure-mirrors : Gather needed facts]
2026-01-23 11:43:49.193557 | controller | skipping: Conditional result was False
2026-01-23 11:43:49.206438 | 
2026-01-23 11:43:49.206594 | TASK [configure-mirrors : Set up infrastructure mirrors]
2026-01-23 11:43:49.274228 | controller | ok
2026-01-23 11:43:49.291254 | controller | included: /var/lib/zuul/builds/ba71653b3abe48a8a049fd3426c53d93/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml
2026-01-23 11:43:49.333635 | 
2026-01-23 11:43:49.333817 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir]
2026-01-23 11:43:49.671821 | controller | ok
2026-01-23 11:43:49.681552 | 
2026-01-23 11:43:49.681625 | LOOP [configure-mirrors : Include OS-specific variables]
2026-01-23 11:43:49.750745 | controller | ok: "/var/lib/zuul/builds/ba71653b3abe48a8a049fd3426c53d93/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml"
2026-01-23 11:43:49.764984 | 
2026-01-23 11:43:49.765114 | TASK [configure-mirrors : Install /etc/pip.conf configuration]
2026-01-23 11:43:50.669014 | controller | changed
2026-01-23 11:43:50.674561 | 
2026-01-23 11:43:50.674623 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors]
2026-01-23 11:43:50.762771 | controller | ok: "/var/lib/zuul/builds/ba71653b3abe48a8a049fd3426c53d93/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml"
2026-01-23 11:43:50.763060 | controller | ok: All items complete
2026-01-23 11:43:50.763119 | 
2026-01-23 11:43:50.817900 | controller | included: /var/lib/zuul/builds/ba71653b3abe48a8a049fd3426c53d93/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml
2026-01-23 11:43:50.832860 | 
2026-01-23 11:43:50.832997 | LOOP [configure-mirrors : Install CentOS 9 repository files]
2026-01-23 11:43:51.767773 | controller | changed: "etc/yum.repos.d/centos.repo"
2026-01-23 11:43:52.596150 | controller | changed: "etc/yum.repos.d/centos-addons.repo"
2026-01-23 11:43:52.610719 | 
2026-01-23 11:43:52.610849 | TASK [configure-mirrors : Disable deltrarpm]
2026-01-23 11:43:53.016654 | controller | changed: section and option added
2026-01-23 11:43:53.042603 | 
2026-01-23 11:43:53.042710 | LOOP [configure-mirrors : Update yum/dnf cache]
2026-01-23 11:43:53.709246 | controller | 29 files removed
2026-01-23 11:43:53.709576 | controller | ok: Item: dnf clean all Runtime: 0:00:00.315562
2026-01-23 11:43:53.709620 | controller | changed: All items complete
2026-01-23 11:43:53.709641 | 
2026-01-23 11:44:04.287988 | 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-01-23 11:44:04.288192 | controller | DNF version: 4.14.0
2026-01-23 11:44:04.288224 | controller | cachedir: /var/cache/dnf
2026-01-23 11:44:04.288245 | controller | Making cache files for all metadata files.
2026-01-23 11:44:04.288263 | controller | baseos: has expired and will be refreshed.
2026-01-23 11:44:04.288281 | controller | appstream: has expired and will be refreshed.
2026-01-23 11:44:04.288299 | controller | crb: has expired and will be refreshed.
2026-01-23 11:44:04.288325 | controller | extras-common: has expired and will be refreshed.
2026-01-23 11:44:04.288343 | controller | repo: downloading from remote: baseos
2026-01-23 11:44:04.288360 | controller | CentOS Stream 9 - BaseOS                         84 MB/s | 8.9 MB     00:00
2026-01-23 11:44:04.288377 | controller | baseos: using metadata from Mon 19 Jan 2026 01:03:35 PM EST.
2026-01-23 11:44:04.288394 | controller | repo: downloading from remote: appstream
2026-01-23 11:44:04.288410 | controller | CentOS Stream 9 - AppStream                      81 MB/s |  26 MB     00:00
2026-01-23 11:44:04.288427 | controller | appstream: using metadata from Mon 19 Jan 2026 01:07:04 PM EST.
2026-01-23 11:44:04.288444 | controller | repo: downloading from remote: crb
2026-01-23 11:44:04.288461 | controller | CentOS Stream 9 - CRB                            74 MB/s | 7.6 MB     00:00
2026-01-23 11:44:04.288478 | controller | crb: using metadata from Mon 19 Jan 2026 01:11:31 PM EST.
2026-01-23 11:44:04.288495 | controller | repo: downloading from remote: extras-common
2026-01-23 11:44:04.288512 | controller | CentOS Stream 9 - Extras packages               1.3 MB/s |  20 kB     00:00
2026-01-23 11:44:04.288529 | controller | extras-common: using metadata from Thu 30 Oct 2025 09:20:08 AM EDT.
2026-01-23 11:44:04.288545 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)'
2026-01-23 11:44:04.288562 | controller | Completion plugin: Generating completion cache...
2026-01-23 11:44:04.288578 | controller | Metadata cache created.
2026-01-23 11:44:04.288603 | controller | ok: Item: dnf makecache -v Runtime: 0:00:10.342154
2026-01-23 11:44:04.313992 | 
2026-01-23 11:44:04.314095 | PLAY RECAP
2026-01-23 11:44:04.314135 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-01-23 11:44:04.314161 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-23 11:44:04.314178 | 
2026-01-23 11:44:04.438622 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2026-01-23 11:44:04.439487 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-23 11:44:04.974870 | 
2026-01-23 11:44:04.974990 | PLAY [all]
2026-01-23 11:44:04.996235 | 
2026-01-23 11:44:04.996321 | TASK [Install binary dependencies]
2026-01-23 11:44:05.057286 | controller | ok
2026-01-23 11:44:05.076027 | 
2026-01-23 11:44:05.076105 | TASK [bindep : Include find tasks]
2026-01-23 11:44:05.105123 | controller | ok
2026-01-23 11:44:05.112497 | controller | included: /var/lib/zuul/builds/ba71653b3abe48a8a049fd3426c53d93/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-01-23 11:44:05.118525 | 
2026-01-23 11:44:05.118586 | TASK [bindep : Look for bindep.txt]
2026-01-23 11:44:05.507447 | controller | ok
2026-01-23 11:44:05.521247 | 
2026-01-23 11:44:05.521390 | TASK [bindep : Define bindep_file fact]
2026-01-23 11:44:05.553293 | controller | ok
2026-01-23 11:44:05.558601 | 
2026-01-23 11:44:05.558685 | TASK [bindep : Look for other-requirements.txt]
2026-01-23 11:44:05.572813 | controller | skipping: Conditional result was False
2026-01-23 11:44:05.579425 | 
2026-01-23 11:44:05.579500 | TASK [bindep : Define bindep_file fact]
2026-01-23 11:44:05.613897 | controller | skipping: Conditional result was False
2026-01-23 11:44:05.620245 | 
2026-01-23 11:44:05.620322 | TASK [bindep : Look for bindep fallback file]
2026-01-23 11:44:05.654608 | controller | skipping: Conditional result was False
2026-01-23 11:44:05.660813 | 
2026-01-23 11:44:05.660884 | TASK [bindep : Define bindep_file fact]
2026-01-23 11:44:05.685033 | controller | skipping: Conditional result was False
2026-01-23 11:44:05.691512 | 
2026-01-23 11:44:05.691577 | TASK [bindep : Include bindep tasks]
2026-01-23 11:44:05.732237 | controller | ok
2026-01-23 11:44:05.738843 | controller | included: /var/lib/zuul/builds/ba71653b3abe48a8a049fd3426c53d93/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml
2026-01-23 11:44:05.746799 | 
2026-01-23 11:44:05.746862 | TASK [bindep : Look for bindep command]
2026-01-23 11:44:05.770187 | controller | skipping: Conditional result was False
2026-01-23 11:44:05.776973 | 
2026-01-23 11:44:05.777037 | TASK [bindep : Check for system bindep]
2026-01-23 11:44:06.310877 | controller | ok: Runtime: 0:00:00.008368
2026-01-23 11:44:06.316501 | 
2026-01-23 11:44:06.316563 | TASK [bindep : Define bindep_command fact]
2026-01-23 11:44:06.350380 | controller | skipping: Conditional result was False
2026-01-23 11:44:06.357641 | 
2026-01-23 11:44:06.357730 | TASK [bindep : Include install tasks]
2026-01-23 11:44:06.407162 | controller | ok
2026-01-23 11:44:06.414809 | controller | included: /var/lib/zuul/builds/ba71653b3abe48a8a049fd3426c53d93/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml
2026-01-23 11:44:06.423801 | 
2026-01-23 11:44:06.423864 | TASK [bindep : Create temp dir for bindep]
2026-01-23 11:44:06.837457 | controller | changed
2026-01-23 11:44:06.848839 | 
2026-01-23 11:44:06.848922 | TASK [Ensure we have pip dependencies]
2026-01-23 11:44:06.889451 | controller | ok
2026-01-23 11:44:06.917146 | 
2026-01-23 11:44:06.917243 | TASK [ensure-pip : Check if pip is installed]
2026-01-23 06:44:07.167814 | controller | /usr/bin/pip3
2026-01-23 06:44:07.212058 | controller | /usr/bin/python3: No module named wheel
2026-01-23 11:44:07.458598 | controller | ok: Runtime: 0:00:00.064262
2026-01-23 11:44:07.464453 | 
2026-01-23 11:44:07.464516 | LOOP [ensure-pip : Install pip from packages]
2026-01-23 11:44:07.502050 | controller | ok: "/var/lib/zuul/builds/ba71653b3abe48a8a049fd3426c53d93/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml"
2026-01-23 11:44:07.510699 | controller | included: /var/lib/zuul/builds/ba71653b3abe48a8a049fd3426c53d93/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml
2026-01-23 11:44:07.521058 | 
2026-01-23 11:44:07.521120 | TASK [ensure-pip : Install Python 3 pip]
2026-01-23 11:44:09.530828 | controller | changed
2026-01-23 11:44:09.547225 | 
2026-01-23 11:44:09.547307 | TASK [ensure-pip : Check for EPEL repository]
2026-01-23 11:44:09.593931 | controller | skipping: Conditional result was False
2026-01-23 11:44:09.601521 | 
2026-01-23 11:44:09.601584 | TASK [ensure-pip : Fail with instructions when EPEL is not installed]
2026-01-23 11:44:09.646594 | controller | skipping: Conditional result was False
2026-01-23 11:44:09.655742 | 
2026-01-23 11:44:09.655835 | TASK [ensure-pip : Install Python 2 pip]
2026-01-23 11:44:09.703324 | controller | skipping: Conditional result was False
2026-01-23 11:44:09.710731 | 
2026-01-23 11:44:09.710819 | TASK [ensure-pip : Ensure setuptools]
2026-01-23 11:44:09.739500 | controller | skipping: Conditional result was False
2026-01-23 11:44:09.746174 | 
2026-01-23 11:44:09.746239 | TASK [ensure-pip : Check for ensurepip module]
2026-01-23 11:44:10.276795 | controller | skipping: Conditional result was False
2026-01-23 11:44:10.291804 | 
2026-01-23 11:44:10.291967 | TASK [ensure-pip : Ensure python3-venv]
2026-01-23 11:44:10.319625 | controller | skipping: Conditional result was False
2026-01-23 11:44:10.333916 | 
2026-01-23 11:44:10.334062 | TASK [ensure-pip : Install pip from source]
2026-01-23 11:44:10.361558 | controller | skipping: Conditional result was False
2026-01-23 11:44:10.389582 | 
2026-01-23 11:44:10.389771 | TASK [ensure-pip : Probe for venv python full path]
2026-01-23 06:44:10.723265 | controller | /usr/bin/python3
2026-01-23 11:44:10.932496 | controller | ok: Runtime: 0:00:00.009129
2026-01-23 11:44:10.944970 | 
2026-01-23 11:44:10.945106 | TASK [ensure-pip : Set host default]
2026-01-23 11:44:10.994757 | controller | ok
2026-01-23 11:44:11.003033 | 
2026-01-23 11:44:11.003155 | TASK [ensure-pip : Set ensure_pip_virtualenv_command]
2026-01-23 11:44:11.080370 | controller | ok
2026-01-23 11:44:11.104883 | 
2026-01-23 11:44:11.105505 | TASK [bindep : Install bindep into temporary venv]
2026-01-23 11:44:15.854042 | controller | changed
2026-01-23 11:44:15.869959 | 
2026-01-23 11:44:15.870263 | TASK [bindep : Define bindep_command]
2026-01-23 11:44:15.909604 | controller | ok
2026-01-23 11:44:15.920943 | 
2026-01-23 11:44:15.921071 | LOOP [bindep : Include package tasks]
2026-01-23 11:44:15.989731 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt"
2026-01-23 11:44:15.990010 | controller | ok: All items complete
2026-01-23 11:44:15.990042 | 
2026-01-23 11:44:16.008470 | controller | included: /var/lib/zuul/builds/ba71653b3abe48a8a049fd3426c53d93/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml
2026-01-23 11:44:16.018339 | 
2026-01-23 11:44:16.018396 | TASK [bindep : Define bindep_run fact]
2026-01-23 11:44:16.038313 | controller | ok
2026-01-23 11:44:16.043575 | 
2026-01-23 11:44:16.043640 | TASK [bindep : Get list of packages to install from bindep]
2026-01-23 06:44:17.578755 | controller | podman
2026-01-23 06:44:17.616612 | controller | python3-jmespath
2026-01-23 06:44:17.616852 | controller | python3-libvirt
2026-01-23 06:44:17.616880 | controller | python3-lxml
2026-01-23 06:44:17.616906 | controller | python3-netaddr
2026-01-23 11:44:18.082050 | controller | ok: Runtime: 0:00:01.280181
2026-01-23 11:44:18.087964 | 
2026-01-23 11:44:18.088038 | TASK [bindep : Install distro packages from bindep]
2026-01-23 11:45:00.693901 | controller | changed
2026-01-23 11:45:00.699278 | 
2026-01-23 11:45:00.699337 | TASK [bindep : Check that packages are installed]
2026-01-23 11:45:02.736865 | controller | ok: Runtime: 0:00:01.285524
2026-01-23 11:45:02.749085 | 
2026-01-23 11:45:02.749235 | TASK [bindep : Fail if we cannot install all packages]
2026-01-23 11:45:02.786331 | controller | skipping: Conditional result was False
2026-01-23 11:45:02.844503 | 
2026-01-23 11:45:02.844649 | TASK [Run test-setup role]
2026-01-23 11:45:02.866374 | controller | ok
2026-01-23 11:45:02.888721 | 
2026-01-23 11:45:02.888820 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-01-23 11:45:03.179503 | controller | ok
2026-01-23 11:45:03.190483 | 
2026-01-23 11:45:03.190618 | TASK [test-setup : Run tools/test-setup.sh]
2026-01-23 11:45:03.739774 | controller | skipping: Conditional result was False
2026-01-23 11:45:03.781285 | 
2026-01-23 11:45:03.781427 | TASK [bindep : Remove bindep temp dir]
2026-01-23 11:45:04.181603 | controller | ok
2026-01-23 11:45:04.195695 | 
2026-01-23 11:45:04.195766 | PLAY RECAP
2026-01-23 11:45:04.195818 | controller | ok: 22 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0
2026-01-23 11:45:04.195845 | 
2026-01-23 11:45:04.296553 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-23 11:45:04.298439 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2026-01-23 11:45:04.895029 | 
2026-01-23 11:45:04.895156 | PLAY [all]
2026-01-23 11:45:04.914590 | 
2026-01-23 11:45:04.914696 | TASK [Abort when test_command variable is undefined]
2026-01-23 11:45:04.949398 | controller | skipping: Conditional result was False
2026-01-23 11:45:04.954763 | 
2026-01-23 11:45:04.954833 | TASK [Convert test_command to list]
2026-01-23 11:45:05.009162 | controller | skipping: Conditional result was False
2026-01-23 11:45:05.017660 | 
2026-01-23 11:45:05.017787 | TASK [Use test_command list]
2026-01-23 11:45:05.059237 | controller | ok
2026-01-23 11:45:05.066448 | 
2026-01-23 11:45:05.066530 | LOOP [Run test_command]
2026-01-23 11:45:05.502692 | controller | no check to run
2026-01-23 11:45:05.502953 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.008632
2026-01-23 11:45:05.555510 | 
2026-01-23 11:45:05.555713 | PLAY RECAP
2026-01-23 11:45:05.555808 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-01-23 11:45:05.555857 | 
2026-01-23 11:45:05.667186 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2026-01-23 11:45:05.668361 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-23 11:45:06.268633 | 
2026-01-23 11:45:06.268772 | PLAY [all]
2026-01-23 11:45:06.289242 | 
2026-01-23 11:45:06.289314 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-01-23 11:45:06.714797 | controller | changed: non-zero return code
2026-01-23 11:45:06.720113 | 
2026-01-23 11:45:06.720187 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-01-23 11:45:06.744653 | controller | skipping: Conditional result was False
2026-01-23 11:45:06.750232 | 
2026-01-23 11:45:06.750301 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-01-23 11:45:06.787099 | 
2026-01-23 11:45:06.787354 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-01-23 11:45:06.825608 | 
2026-01-23 11:45:06.825962 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-01-23 11:45:06.852895 | controller | skipping: Conditional result was False
2026-01-23 11:45:06.861173 | 
2026-01-23 11:45:06.861287 | LOOP [fetch-subunit-output : Generate subunit file]
2026-01-23 11:45:06.895428 | 
2026-01-23 11:45:06.895627 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-01-23 11:45:06.920074 | controller | skipping: Conditional result was False
2026-01-23 11:45:06.927902 | 
2026-01-23 11:45:06.927993 | TASK [fetch-subunit-output : Remove the temporary file]
2026-01-23 11:45:06.952978 | controller | skipping: Conditional result was False
2026-01-23 11:45:06.960974 | 
2026-01-23 11:45:06.961064 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-01-23 11:45:06.985537 | controller | skipping: Conditional result was False
2026-01-23 11:45:07.018556 | 
2026-01-23 11:45:07.018637 | PLAY RECAP
2026-01-23 11:45:07.018731 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-01-23 11:45:07.018769 | 
2026-01-23 11:45:07.110932 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-23 11:45:07.111824 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-01-23 11:45:07.668047 | 
2026-01-23 11:45:07.668155 | PLAY [all]
2026-01-23 11:45:07.686807 | 
2026-01-23 11:45:07.686882 | TASK [include_role : fetch-output]
2026-01-23 11:45:07.715504 | controller | ok
2026-01-23 11:45:07.733264 | 
2026-01-23 11:45:07.733361 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-23 11:45:07.798487 | controller | skipping: Conditional result was False
2026-01-23 11:45:07.811866 | 
2026-01-23 11:45:07.812021 | TASK [fetch-output : Set log path for single node]
2026-01-23 11:45:07.860447 | controller | ok
2026-01-23 11:45:07.872703 | 
2026-01-23 11:45:07.872853 | LOOP [fetch-output : Ensure local output dirs]
2026-01-23 11:45:08.295135 | controller -> localhost | ok: "/var/lib/zuul/builds/ba71653b3abe48a8a049fd3426c53d93/work/logs"
2026-01-23 11:45:08.530239 | controller -> localhost | changed: "/var/lib/zuul/builds/ba71653b3abe48a8a049fd3426c53d93/work/artifacts"
2026-01-23 11:45:08.725172 | controller -> localhost | changed: "/var/lib/zuul/builds/ba71653b3abe48a8a049fd3426c53d93/work/docs"
2026-01-23 11:45:08.741614 | 
2026-01-23 11:45:08.741717 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-23 11:45:09.401044 | controller | changed: .d..t...... ./
2026-01-23 11:45:09.401238 | controller | changed: All items complete
2026-01-23 11:45:09.401265 | 
2026-01-23 11:45:09.939710 | controller | changed: .d..t...... ./
2026-01-23 11:45:10.471110 | controller | changed: .d..t...... ./
2026-01-23 11:45:10.503561 | 
2026-01-23 11:45:10.503796 | TASK [include_role : fetch-output-openshift]
2026-01-23 11:45:10.531111 | controller | skipping: Conditional result was False
2026-01-23 11:45:10.543967 | 
2026-01-23 11:45:10.544109 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-23 11:45:10.985737 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.013308
2026-01-23 11:45:11.273880 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.012797
2026-01-23 11:45:11.328488 | 
2026-01-23 11:45:11.328608 | PLAY [all]
2026-01-23 11:45:11.347833 | 
2026-01-23 11:45:11.347923 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-01-23 11:45:11.833199 | controller | changed
2026-01-23 11:45:11.878962 | 
2026-01-23 11:45:11.879032 | PLAY RECAP
2026-01-23 11:45:11.879085 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-01-23 11:45:11.879112 | 
2026-01-23 11:45:11.977599 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-01-23 11:45:11.979492 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-01-23 11:45:12.547134 | 
2026-01-23 11:45:12.547248 | PLAY [localhost]
2026-01-23 11:45:12.565092 | 
2026-01-23 11:45:12.565167 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-23 11:45:12.998494 | localhost | changed
2026-01-23 11:45:13.008583 | 
2026-01-23 11:45:13.008781 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-23 11:45:13.044263 | localhost | ok
2026-01-23 11:45:13.061852 | 
2026-01-23 11:45:13.061998 | TASK [add-fileserver : Create SSH private key tempfile]
2026-01-23 11:45:13.437434 | localhost | changed
2026-01-23 11:45:13.445175 | 
2026-01-23 11:45:13.445269 | TASK [add-fileserver : Create SSH private key from secret]
2026-01-23 11:45:14.115452 | localhost | changed
2026-01-23 11:45:14.126271 | 
2026-01-23 11:45:14.126413 | TASK [add-fileserver : Add fileserver ssh key]
2026-01-23 11:45:14.543631 | localhost | Identity added: /var/lib/zuul/builds/ba71653b3abe48a8a049fd3426c53d93/work/tmp/ansible.j7ima46z (/var/lib/zuul/builds/ba71653b3abe48a8a049fd3426c53d93/work/tmp/ansible.j7ima46z)
2026-01-23 11:45:14.543874 | localhost | ok: Runtime: 0:00:00.007371
2026-01-23 11:45:14.550062 | 
2026-01-23 11:45:14.550153 | TASK [add-fileserver : Remove SSH private key from disk]
2026-01-23 11:45:14.829579 | localhost | ok: Runtime: 0:00:00.005842
2026-01-23 11:45:14.840132 | 
2026-01-23 11:45:14.840399 | TASK [add-fileserver : Add fileserver to inventory]
2026-01-23 11:45:14.908211 | localhost | changed
2026-01-23 11:45:14.917366 | 
2026-01-23 11:45:14.917508 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-01-23 11:45:15.339739 | localhost | changed
2026-01-23 11:45:15.377363 | 
2026-01-23 11:45:15.377437 | PLAY [localhost]
2026-01-23 11:45:15.394157 | 
2026-01-23 11:45:15.394237 | TASK [Generate bulk log download script]
2026-01-23 11:45:15.413725 | localhost | ok
2026-01-23 11:45:15.429141 | 
2026-01-23 11:45:15.429226 | TASK [local-log-download : Check API endpoint is defined]
2026-01-23 11:45:15.458870 | localhost | ok: All assertions passed
2026-01-23 11:45:15.464914 | 
2026-01-23 11:45:15.464999 | TASK [local-log-download : Create download script]
2026-01-23 11:45:15.906962 | localhost -> localhost | changed
2026-01-23 11:45:15.925558 | 
2026-01-23 11:45:15.925721 | TASK [Register quick-download link]
2026-01-23 11:45:15.950265 | localhost | ok
2026-01-23 11:45:16.007177 | 
2026-01-23 11:45:16.007280 | PLAY [logserver.rdoproject.org]
2026-01-23 11:45:16.016312 | 
2026-01-23 11:45:16.016370 | TASK [Set zuul-log-path fact]
2026-01-23 11:45:16.033045 | logserver.rdoproject.org | ok
2026-01-23 11:45:16.041756 | 
2026-01-23 11:45:16.041818 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-23 11:45:16.079029 | logserver.rdoproject.org | ok
2026-01-23 11:45:16.086834 | 
2026-01-23 11:45:16.086920 | TASK [upload-logs : Create log directories]
2026-01-23 11:45:16.964343 | logserver.rdoproject.org | changed
2026-01-23 11:45:16.971096 | 
2026-01-23 11:45:16.971227 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-01-23 11:45:17.319527 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.008864
2026-01-23 11:45:17.329448 | 
2026-01-23 11:45:17.329587 | TASK [upload-logs : Upload logs to log server]
2026-01-23 11:45:18.088170 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-01-23 11:45:18.094638 | 
2026-01-23 11:45:18.094834 | LOOP [upload-logs : Compress console log and json output]
2026-01-23 11:45:18.164150 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-23 11:45:18.175381 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-23 11:45:18.189636 | 
2026-01-23 11:45:18.189829 | LOOP [upload-logs : Upload compressed console log and json output]
2026-01-23 11:45:18.238240 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-23 11:45:18.238658 | 
2026-01-23 11:45:18.241481 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-23 11:45:18.256490 | 
2026-01-23 11:45:18.256656 | LOOP [upload-logs : Upload console log and json output]