2026-06-12 20:24:22.102751 | Job console starting
2026-06-12 20:24:22.227764 | Updating git repos
2026-06-12 20:24:22.286864 | Cloning repos into workspace
2026-06-12 20:24:23.433708 | Restoring repo states
2026-06-12 20:24:23.457155 | Merging changes
2026-06-12 20:24:25.506248 | Checking out repos
2026-06-12 20:24:25.729698 | Preparing playbooks
2026-06-12 20:24:29.507828 | Running Ansible setup
2026-06-12 20:24:33.065934 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-06-12 20:24:33.703343 | 
2026-06-12 20:24:33.703469 | PLAY [localhost]
2026-06-12 20:24:33.710932 | 
2026-06-12 20:24:33.711000 | TASK [Gathering Facts]
2026-06-12 20:24:34.648014 | localhost | ok
2026-06-12 20:24:34.660924 | 
2026-06-12 20:24:34.661082 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-06-12 20:24:35.102646 | localhost -> localhost | changed
2026-06-12 20:24:35.107076 | 
2026-06-12 20:24:35.107144 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-06-12 20:24:36.034483 | localhost -> localhost | changed
2026-06-12 20:24:36.040693 | 
2026-06-12 20:24:36.040778 | TASK [Setup log path fact]
2026-06-12 20:24:36.060526 | localhost | ok
2026-06-12 20:24:36.071297 | 
2026-06-12 20:24:36.071359 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-12 20:24:36.108214 | localhost | ok
2026-06-12 20:24:36.115494 | 
2026-06-12 20:24:36.115611 | TASK [emit-job-header : Print job information]
2026-06-12 20:24:36.164570 | # Job Information
2026-06-12 20:24:36.164758 | Ansible Version: 2.16.18
2026-06-12 20:24:36.164793 | Job: cifmw-molecule-federation
2026-06-12 20:24:36.164818 | Pipeline: github-check
2026-06-12 20:24:36.164842 | Executor: 38.102.83.32
2026-06-12 20:24:36.164865 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3965
2026-06-12 20:24:36.164891 | Log URL (when completed): https://gateway-cloud-softwarefactory.apps.ocp.cloud.ci.centos.org/logs/ca7/rdoproject.org/ca74d82890be4de89ec8379962b8e096/
2026-06-12 20:24:36.164917 | Event ID: 7605a9d0-669c-11f1-94d2-7745baa5c23d
2026-06-12 20:24:36.168665 | 
2026-06-12 20:24:36.168753 | LOOP [emit-job-header : Print node information]
2026-06-12 20:24:36.253284 | localhost | ok:
2026-06-12 20:24:36.253616 | localhost | # Node Information
2026-06-12 20:24:36.253684 | localhost | Inventory Hostname: controller
2026-06-12 20:24:36.253745 | localhost | Hostname: np0000072363
2026-06-12 20:24:36.253795 | localhost | Username: zuul
2026-06-12 20:24:36.253847 | localhost | Distro: CentOS 9
2026-06-12 20:24:36.253894 | localhost | Provider: vexxhost-nodepool-tripleo
2026-06-12 20:24:36.253938 | localhost | Region: RegionOne
2026-06-12 20:24:36.253982 | localhost | Label: cloud-centos-9-stream-tripleo
2026-06-12 20:24:36.254026 | localhost | Product Name: OpenStack Nova
2026-06-12 20:24:36.254068 | localhost | Interface IP: 38.102.83.145
2026-06-12 20:24:36.265573 | 
2026-06-12 20:24:36.265738 | PLAY [all]
2026-06-12 20:24:36.276660 | 
2026-06-12 20:24:36.276794 | TASK [Gather network facts]
2026-06-12 20:24:36.668309 | controller | ok
2026-06-12 20:24:36.680761 | 
2026-06-12 20:24:36.680895 | TASK [include_role : start-zuul-console]
2026-06-12 20:24:36.702106 | controller | ok
2026-06-12 20:24:36.716981 | 
2026-06-12 20:24:36.717102 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-06-12 20:24:37.084453 | controller | ok
2026-06-12 20:24:37.096899 | 
2026-06-12 20:24:37.097023 | TASK [include_role : add-build-sshkey]
2026-06-12 20:24:37.130253 | controller | ok
2026-06-12 20:24:37.152883 | 
2026-06-12 20:24:37.153024 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-06-12 20:24:37.453648 | controller -> localhost | ok
2026-06-12 20:24:37.458130 | 
2026-06-12 20:24:37.458193 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-06-12 20:24:37.486455 | controller | ok
2026-06-12 20:24:37.499807 | controller | included: /var/lib/zuul/builds/ca74d82890be4de89ec8379962b8e096/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-06-12 20:24:37.504131 | 
2026-06-12 20:24:37.504188 | TASK [add-build-sshkey : Create Temp SSH key]
2026-06-12 20:24:38.197627 | controller -> localhost | Generating public/private rsa key pair.
2026-06-12 20:24:38.197904 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ca74d82890be4de89ec8379962b8e096/work/ca74d82890be4de89ec8379962b8e096_id_rsa
2026-06-12 20:24:38.197966 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ca74d82890be4de89ec8379962b8e096/work/ca74d82890be4de89ec8379962b8e096_id_rsa.pub
2026-06-12 20:24:38.198014 | controller -> localhost | The key fingerprint is:
2026-06-12 20:24:38.198057 | controller -> localhost | SHA256:ZTpoZdnwzySDzkdUQsOW4cdCACceFEEsllUVQ1ykMV4 zuul-build-sshkey
2026-06-12 20:24:38.198098 | controller -> localhost | The key's randomart image is:
2026-06-12 20:24:38.198138 | controller -> localhost | +---[RSA 3072]----+
2026-06-12 20:24:38.198176 | controller -> localhost | |     *X==B#BE    |
2026-06-12 20:24:38.198215 | controller -> localhost | |    +..+ @=X     |
2026-06-12 20:24:38.198255 | controller -> localhost | |   . .. =.& +    |
2026-06-12 20:24:38.198295 | controller -> localhost | |       * = O     |
2026-06-12 20:24:38.198334 | controller -> localhost | |      o S . o    |
2026-06-12 20:24:38.198372 | controller -> localhost | |     .   o       |
2026-06-12 20:24:38.198409 | controller -> localhost | |                 |
2026-06-12 20:24:38.198451 | controller -> localhost | |                 |
2026-06-12 20:24:38.198490 | controller -> localhost | |                 |
2026-06-12 20:24:38.198563 | controller -> localhost | +----[SHA256]-----+
2026-06-12 20:24:38.198654 | controller -> localhost | ok: Runtime: 0:00:00.241503
2026-06-12 20:24:38.206407 | 
2026-06-12 20:24:38.206563 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-06-12 20:24:38.241345 | controller | ok
2026-06-12 20:24:38.258317 | controller | included: /var/lib/zuul/builds/ca74d82890be4de89ec8379962b8e096/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-06-12 20:24:38.272477 | 
2026-06-12 20:24:38.272612 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-06-12 20:24:38.298016 | controller | skipping: Conditional result was False
2026-06-12 20:24:38.304139 | 
2026-06-12 20:24:38.304237 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-06-12 20:24:38.793602 | controller | changed
2026-06-12 20:24:38.801186 | 
2026-06-12 20:24:38.801320 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-06-12 20:24:39.058099 | controller | ok
2026-06-12 20:24:39.065219 | 
2026-06-12 20:24:39.065352 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-06-12 20:24:39.967659 | controller | changed
2026-06-12 20:24:39.974953 | 
2026-06-12 20:24:39.975084 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-06-12 20:24:40.670043 | controller | changed
2026-06-12 20:24:40.673454 | 
2026-06-12 20:24:40.673622 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-06-12 20:24:40.697218 | controller | skipping: Conditional result was False
2026-06-12 20:24:40.701923 | 
2026-06-12 20:24:40.702005 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-06-12 20:24:41.125125 | controller -> localhost | changed
2026-06-12 20:24:41.134413 | 
2026-06-12 20:24:41.134500 | TASK [add-build-sshkey : Add back temp key]
2026-06-12 20:24:41.480445 | controller -> localhost | Identity added: /var/lib/zuul/builds/ca74d82890be4de89ec8379962b8e096/work/ca74d82890be4de89ec8379962b8e096_id_rsa (zuul-build-sshkey)
2026-06-12 20:24:41.480798 | controller -> localhost | ok: Runtime: 0:00:00.016564
2026-06-12 20:24:41.488039 | 
2026-06-12 20:24:41.488168 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-06-12 20:24:41.945411 | controller | ok
2026-06-12 20:24:41.951832 | 
2026-06-12 20:24:41.951959 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-06-12 20:24:41.994801 | controller | skipping: Conditional result was False
2026-06-12 20:24:42.008173 | 
2026-06-12 20:24:42.008295 | TASK [include_role : validate-host]
2026-06-12 20:24:42.033717 | controller | ok
2026-06-12 20:24:42.064491 | 
2026-06-12 20:24:42.064618 | TASK [validate-host : Define zuul_info_dir fact]
2026-06-12 20:24:42.106150 | controller | ok
2026-06-12 20:24:42.112701 | 
2026-06-12 20:24:42.112829 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-06-12 20:24:42.422888 | controller -> localhost | ok
2026-06-12 20:24:42.433934 | 
2026-06-12 20:24:42.434074 | TASK [validate-host : Collect information about the host]
2026-06-12 20:24:43.160359 | controller | ok
2026-06-12 20:24:43.176040 | 
2026-06-12 20:24:43.176171 | TASK [validate-host : Sanitize hostname]
2026-06-12 20:24:43.227745 | controller | ok
2026-06-12 20:24:43.233084 | 
2026-06-12 20:24:43.233177 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-06-12 20:24:43.804027 | controller -> localhost | changed
2026-06-12 20:24:43.809887 | 
2026-06-12 20:24:43.809951 | TASK [validate-host : Collect information about zuul worker]
2026-06-12 20:24:44.264971 | controller | ok
2026-06-12 20:24:44.276723 | 
2026-06-12 20:24:44.276859 | TASK [validate-host : Write out all zuul information for each host]
2026-06-12 20:24:44.796051 | controller -> localhost | changed
2026-06-12 20:24:44.809619 | 
2026-06-12 20:24:44.809742 | TASK [include_role : prepare-workspace-openshift]
2026-06-12 20:24:44.835381 | controller | skipping: Conditional result was False
2026-06-12 20:24:44.842903 | 
2026-06-12 20:24:44.843032 | TASK [include_role : remove-zuul-sshkey]
2026-06-12 20:24:44.876819 | controller | skipping: Conditional result was False
2026-06-12 20:24:44.884559 | 
2026-06-12 20:24:44.884696 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-06-12 20:24:45.171304 | controller | ok: "logs"
2026-06-12 20:24:45.171719 | controller | ok: All items complete
2026-06-12 20:24:45.171777 | 
2026-06-12 20:24:45.399935 | controller | ok: "artifacts"
2026-06-12 20:24:45.644302 | controller | ok: "docs"
2026-06-12 20:24:45.662281 | 
2026-06-12 20:24:45.662409 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-06-12 20:24:45.940823 | controller | changed: "logs"
2026-06-12 20:24:46.216954 | controller | changed: "artifacts"
2026-06-12 20:24:46.445692 | controller | changed: "docs"
2026-06-12 20:24:46.458619 | 
2026-06-12 20:24:46.458710 | PLAY RECAP
2026-06-12 20:24:46.458780 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-06-12 20:24:46.458824 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-12 20:24:46.458859 | 
2026-06-12 20:24:46.591621 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-06-12 20:24:46.594071 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2026-06-12 20:24:47.203926 | 
2026-06-12 20:24:47.204039 | PLAY [localhost]
2026-06-12 20:24:47.212472 | 
2026-06-12 20:24:47.212561 | TASK [configure-ssh-config-fips : Ensure ssh config directory]
2026-06-12 20:24:47.677799 | localhost | changed
2026-06-12 20:24:47.686702 | 
2026-06-12 20:24:47.686871 | TASK [configure-ssh-config-fips : Setup ssh config]
2026-06-12 20:24:48.480819 | localhost | changed
2026-06-12 20:24:48.498430 | 
2026-06-12 20:24:48.498608 | PLAY [all]
2026-06-12 20:24:48.510303 | 
2026-06-12 20:24:48.510424 | TASK [include_role : prepare-workspace]
2026-06-12 20:24:48.543234 | controller | ok
2026-06-12 20:24:48.568593 | 
2026-06-12 20:24:48.568680 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-06-12 20:24:48.954678 | controller | ok
2026-06-12 20:24:48.965153 | 
2026-06-12 20:24:48.965285 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-06-12 20:24:50.796539 | controller | Output suppressed because no_log was given
2026-06-12 20:24:50.810350 | 
2026-06-12 20:24:50.810485 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-06-12 20:24:51.062794 | controller | changed: "logs"
2026-06-12 20:24:51.311173 | controller | changed: "artifacts"
2026-06-12 20:24:51.501834 | controller | changed: "docs"
2026-06-12 20:24:51.513384 | 
2026-06-12 20:24:51.513599 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-06-12 20:24:51.752192 | controller | changed: "logs"
2026-06-12 20:24:51.752424 | controller | changed: All items complete
2026-06-12 20:24:51.752455 | 
2026-06-12 20:24:51.979562 | controller | changed: "artifacts"
2026-06-12 20:24:52.238455 | controller | changed: "docs"
2026-06-12 20:24:52.262812 | 
2026-06-12 20:24:52.262971 | TASK [Check if worker can sudo]
2026-06-12 20:24:52.803577 | controller | ok: Runtime: 0:00:00.042492
2026-06-12 20:24:52.812797 | 
2026-06-12 20:24:52.812964 | TASK [configure-mirrors : Gather needed facts]
2026-06-12 20:24:52.890892 | controller | skipping: Conditional result was False
2026-06-12 20:24:52.900699 | 
2026-06-12 20:24:52.900842 | TASK [configure-mirrors : Set up infrastructure mirrors]
2026-06-12 20:24:52.964888 | controller | ok
2026-06-12 20:24:52.977924 | controller | included: /var/lib/zuul/builds/ca74d82890be4de89ec8379962b8e096/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml
2026-06-12 20:24:52.986648 | 
2026-06-12 20:24:52.986775 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir]
2026-06-12 20:24:53.233899 | controller | ok
2026-06-12 20:24:53.246842 | 
2026-06-12 20:24:53.246972 | LOOP [configure-mirrors : Include OS-specific variables]
2026-06-12 20:24:53.396302 | controller | ok: "/var/lib/zuul/builds/ca74d82890be4de89ec8379962b8e096/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml"
2026-06-12 20:24:53.413594 | 
2026-06-12 20:24:53.413780 | TASK [configure-mirrors : Install /etc/pip.conf configuration]
2026-06-12 20:24:54.308618 | controller | changed
2026-06-12 20:24:54.321326 | 
2026-06-12 20:24:54.321462 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors]
2026-06-12 20:24:54.412746 | controller | ok: "/var/lib/zuul/builds/ca74d82890be4de89ec8379962b8e096/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml"
2026-06-12 20:24:54.413086 | controller | ok: All items complete
2026-06-12 20:24:54.413174 | 
2026-06-12 20:24:54.461471 | controller | included: /var/lib/zuul/builds/ca74d82890be4de89ec8379962b8e096/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml
2026-06-12 20:24:54.468213 | 
2026-06-12 20:24:54.468299 | LOOP [configure-mirrors : Install CentOS 9 repository files]
2026-06-12 20:24:55.336819 | controller | changed: "etc/yum.repos.d/centos.repo"
2026-06-12 20:24:56.022300 | controller | changed: "etc/yum.repos.d/centos-addons.repo"
2026-06-12 20:24:56.043308 | 
2026-06-12 20:24:56.043505 | TASK [configure-mirrors : Disable deltrarpm]
2026-06-12 20:24:56.512624 | controller | changed: section and option added
2026-06-12 20:24:56.533783 | 
2026-06-12 20:24:56.533925 | LOOP [configure-mirrors : Update yum/dnf cache]
2026-06-12 20:24:57.342644 | controller | 29 files removed
2026-06-12 20:24:57.342875 | controller | ok: Item: dnf clean all Runtime: 0:00:00.467887
2026-06-12 20:24:57.342916 | controller | changed: All items complete
2026-06-12 20:24:57.342938 | 
2026-06-12 20:25:08.890951 | 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-06-12 20:25:08.891241 | controller | DNF version: 4.14.0
2026-06-12 20:25:08.891294 | controller | cachedir: /var/cache/dnf
2026-06-12 20:25:08.891337 | controller | Making cache files for all metadata files.
2026-06-12 20:25:08.891376 | controller | baseos: has expired and will be refreshed.
2026-06-12 20:25:08.891431 | controller | appstream: has expired and will be refreshed.
2026-06-12 20:25:08.891469 | controller | crb: has expired and will be refreshed.
2026-06-12 20:25:08.891506 | controller | extras-common: has expired and will be refreshed.
2026-06-12 20:25:08.891582 | controller | repo: downloading from remote: baseos
2026-06-12 20:25:08.891622 | controller | CentOS Stream 9 - BaseOS                         81 MB/s | 8.9 MB     00:00
2026-06-12 20:25:08.891659 | controller | baseos: using metadata from Thu 11 Jun 2026 01:27:06 PM EDT.
2026-06-12 20:25:08.891738 | controller | repo: downloading from remote: appstream
2026-06-12 20:25:08.891784 | controller | CentOS Stream 9 - AppStream                     110 MB/s |  28 MB     00:00
2026-06-12 20:25:08.891820 | controller | appstream: using metadata from Thu 11 Jun 2026 01:30:52 PM EDT.
2026-06-12 20:25:08.891857 | controller | repo: downloading from remote: crb
2026-06-12 20:25:08.891893 | controller | CentOS Stream 9 - CRB                            63 MB/s | 8.3 MB     00:00
2026-06-12 20:25:08.891930 | controller | crb: using metadata from Thu 11 Jun 2026 01:35:27 PM EDT.
2026-06-12 20:25:08.891965 | controller | repo: downloading from remote: extras-common
2026-06-12 20:25:08.892001 | controller | CentOS Stream 9 - Extras packages               1.5 MB/s |  21 kB     00:00
2026-06-12 20:25:08.892037 | controller | extras-common: using metadata from Wed 10 Jun 2026 10:05:57 AM EDT.
2026-06-12 20:25:08.892072 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)'
2026-06-12 20:25:08.892107 | controller | Completion plugin: Generating completion cache...
2026-06-12 20:25:08.892142 | controller | Metadata cache created.
2026-06-12 20:25:08.892194 | controller | ok: Item: dnf makecache -v Runtime: 0:00:11.255286
2026-06-12 20:25:08.901905 | 
2026-06-12 20:25:08.902080 | PLAY RECAP
2026-06-12 20:25:08.902171 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-12 20:25:08.902230 | localhost | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-12 20:25:08.902271 | 
2026-06-12 20:25:09.051643 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2026-06-12 20:25:09.053926 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-06-12 20:25:09.734680 | 
2026-06-12 20:25:09.734801 | PLAY [all]
2026-06-12 20:25:09.745332 | 
2026-06-12 20:25:09.745401 | TASK [Install binary dependencies]
2026-06-12 20:25:09.827416 | controller | ok
2026-06-12 20:25:09.859672 | 
2026-06-12 20:25:09.859773 | TASK [bindep : Include find tasks]
2026-06-12 20:25:09.899751 | controller | ok
2026-06-12 20:25:09.908303 | controller | included: /var/lib/zuul/builds/ca74d82890be4de89ec8379962b8e096/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-06-12 20:25:09.921160 | 
2026-06-12 20:25:09.921387 | TASK [bindep : Look for bindep.txt]
2026-06-12 20:25:10.378911 | controller | ok
2026-06-12 20:25:10.388600 | 
2026-06-12 20:25:10.388824 | TASK [bindep : Define bindep_file fact]
2026-06-12 20:25:10.432927 | controller | ok
2026-06-12 20:25:10.441868 | 
2026-06-12 20:25:10.442059 | TASK [bindep : Look for other-requirements.txt]
2026-06-12 20:25:10.468337 | controller | skipping: Conditional result was False
2026-06-12 20:25:10.477136 | 
2026-06-12 20:25:10.477287 | TASK [bindep : Define bindep_file fact]
2026-06-12 20:25:10.513224 | controller | skipping: Conditional result was False
2026-06-12 20:25:10.521550 | 
2026-06-12 20:25:10.521691 | TASK [bindep : Look for bindep fallback file]
2026-06-12 20:25:10.557342 | controller | skipping: Conditional result was False
2026-06-12 20:25:10.565680 | 
2026-06-12 20:25:10.565822 | TASK [bindep : Define bindep_file fact]
2026-06-12 20:25:10.591329 | controller | skipping: Conditional result was False
2026-06-12 20:25:10.600259 | 
2026-06-12 20:25:10.600417 | TASK [bindep : Include bindep tasks]
2026-06-12 20:25:10.642832 | controller | ok
2026-06-12 20:25:10.653154 | controller | included: /var/lib/zuul/builds/ca74d82890be4de89ec8379962b8e096/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml
2026-06-12 20:25:10.668712 | 
2026-06-12 20:25:10.668838 | TASK [bindep : Look for bindep command]
2026-06-12 20:25:10.704207 | controller | skipping: Conditional result was False
2026-06-12 20:25:10.714068 | 
2026-06-12 20:25:10.714203 | TASK [bindep : Check for system bindep]
2026-06-12 20:25:11.254323 | controller | ok: Runtime: 0:00:00.003799
2026-06-12 20:25:11.262451 | 
2026-06-12 20:25:11.262609 | TASK [bindep : Define bindep_command fact]
2026-06-12 20:25:11.298500 | controller | skipping: Conditional result was False
2026-06-12 20:25:11.306752 | 
2026-06-12 20:25:11.306886 | TASK [bindep : Include install tasks]
2026-06-12 20:25:11.359492 | controller | ok
2026-06-12 20:25:11.371745 | controller | included: /var/lib/zuul/builds/ca74d82890be4de89ec8379962b8e096/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml
2026-06-12 20:25:11.388112 | 
2026-06-12 20:25:11.388244 | TASK [bindep : Create temp dir for bindep]
2026-06-12 20:25:11.798083 | controller | changed
2026-06-12 20:25:11.806168 | 
2026-06-12 20:25:11.806293 | TASK [Ensure we have pip dependencies]
2026-06-12 20:25:11.829406 | controller | ok
2026-06-12 20:25:11.866540 | 
2026-06-12 20:25:11.866647 | TASK [ensure-pip : Check if pip is installed]
2026-06-12 16:25:12.173564 | controller | /usr/bin/pip3
2026-06-12 16:25:12.216448 | controller | /usr/bin/python3: No module named wheel
2026-06-12 20:25:12.402351 | controller | ok: Runtime: 0:00:00.053696
2026-06-12 20:25:12.411102 | 
2026-06-12 20:25:12.411250 | LOOP [ensure-pip : Install pip from packages]
2026-06-12 20:25:12.457691 | controller | ok: "/var/lib/zuul/builds/ca74d82890be4de89ec8379962b8e096/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml"
2026-06-12 20:25:12.472725 | controller | included: /var/lib/zuul/builds/ca74d82890be4de89ec8379962b8e096/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml
2026-06-12 20:25:12.524446 | 
2026-06-12 20:25:12.524613 | TASK [ensure-pip : Install Python 3 pip]
2026-06-12 20:25:14.157679 | controller | ok: Nothing to do
2026-06-12 20:25:14.166256 | 
2026-06-12 20:25:14.166396 | TASK [ensure-pip : Install Python3-wheel on older CentOS]
2026-06-12 20:25:15.700424 | controller | changed
2026-06-12 20:25:15.704368 | 
2026-06-12 20:25:15.704432 | TASK [ensure-pip : Check for EPEL repository]
2026-06-12 20:25:15.768848 | controller | skipping: Conditional result was False
2026-06-12 20:25:15.774954 | 
2026-06-12 20:25:15.775046 | TASK [ensure-pip : Fail with instructions when EPEL is not installed]
2026-06-12 20:25:15.819302 | controller | skipping: Conditional result was False
2026-06-12 20:25:15.827230 | 
2026-06-12 20:25:15.827321 | TASK [ensure-pip : Install Python 2 pip]
2026-06-12 20:25:15.884000 | controller | skipping: Conditional result was False
2026-06-12 20:25:15.893293 | 
2026-06-12 20:25:15.893433 | TASK [ensure-pip : Ensure setuptools]
2026-06-12 20:25:15.920011 | controller | skipping: Conditional result was False
2026-06-12 20:25:15.931032 | 
2026-06-12 20:25:15.931201 | TASK [ensure-pip : Check for ensurepip module]
2026-06-12 20:25:16.464999 | controller | skipping: Conditional result was False
2026-06-12 20:25:16.475125 | 
2026-06-12 20:25:16.475269 | TASK [ensure-pip : Ensure python3-venv]
2026-06-12 20:25:16.500793 | controller | skipping: Conditional result was False
2026-06-12 20:25:16.509647 | 
2026-06-12 20:25:16.509805 | TASK [ensure-pip : Install pip from source]
2026-06-12 20:25:16.534951 | controller | skipping: Conditional result was False
2026-06-12 20:25:16.543703 | 
2026-06-12 20:25:16.543840 | TASK [ensure-pip : Probe for venv python full path]
2026-06-12 16:25:16.862549 | controller | /usr/bin/python3
2026-06-12 20:25:17.100257 | controller | ok: Runtime: 0:00:00.005098
2026-06-12 20:25:17.108317 | 
2026-06-12 20:25:17.108450 | TASK [ensure-pip : Set host default]
2026-06-12 20:25:17.184580 | controller | ok
2026-06-12 20:25:17.193165 | 
2026-06-12 20:25:17.193336 | TASK [ensure-pip : Set ensure_pip_virtualenv_command]
2026-06-12 20:25:17.273796 | controller | ok
2026-06-12 20:25:17.289953 | 
2026-06-12 20:25:17.290080 | TASK [bindep : Install bindep into temporary venv]
2026-06-12 20:25:21.201446 | controller | changed
2026-06-12 20:25:21.216554 | 
2026-06-12 20:25:21.216723 | TASK [bindep : Define bindep_command]
2026-06-12 20:25:21.261622 | controller | ok
2026-06-12 20:25:21.269203 | 
2026-06-12 20:25:21.269316 | LOOP [bindep : Include package tasks]
2026-06-12 20:25:21.350746 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt"
2026-06-12 20:25:21.351211 | controller | ok: All items complete
2026-06-12 20:25:21.351280 | 
2026-06-12 20:25:21.367889 | controller | included: /var/lib/zuul/builds/ca74d82890be4de89ec8379962b8e096/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml
2026-06-12 20:25:21.377754 | 
2026-06-12 20:25:21.377818 | TASK [bindep : Define bindep_run fact]
2026-06-12 20:25:21.406295 | controller | ok
2026-06-12 20:25:21.409657 | 
2026-06-12 20:25:21.409716 | TASK [bindep : Get list of packages to install from bindep]
2026-06-12 16:25:22.691924 | controller | podman
2026-06-12 16:25:22.692104 | controller | python3-jmespath
2026-06-12 16:25:22.692117 | controller | python3-libvirt
2026-06-12 16:25:22.692126 | controller | python3-lxml
2026-06-12 16:25:22.692135 | controller | python3-netaddr
2026-06-12 20:25:22.940196 | controller | ok: Runtime: 0:00:01.096310
2026-06-12 20:25:22.948269 | 
2026-06-12 20:25:22.948399 | TASK [bindep : Install distro packages from bindep]
2026-06-12 20:26:05.433529 | controller | changed
2026-06-12 20:26:05.437410 | 
2026-06-12 20:26:05.437473 | TASK [bindep : Check that packages are installed]
2026-06-12 20:26:06.968228 | controller | ok: Runtime: 0:00:01.020180
2026-06-12 20:26:06.980748 | 
2026-06-12 20:26:06.981004 | TASK [bindep : Fail if we cannot install all packages]
2026-06-12 20:26:07.020574 | controller | skipping: Conditional result was False
2026-06-12 20:26:07.042276 | 
2026-06-12 20:26:07.042653 | TASK [Run test-setup role]
2026-06-12 20:26:07.072317 | controller | ok
2026-06-12 20:26:07.099023 | 
2026-06-12 20:26:07.099168 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-06-12 20:26:07.334392 | controller | ok
2026-06-12 20:26:07.339793 | 
2026-06-12 20:26:07.339886 | TASK [test-setup : Run tools/test-setup.sh]
2026-06-12 20:26:07.877883 | controller | skipping: Conditional result was False
2026-06-12 20:26:07.902225 | 
2026-06-12 20:26:07.902404 | TASK [bindep : Remove bindep temp dir]
2026-06-12 20:26:08.329179 | controller | ok
2026-06-12 20:26:08.330992 | 
2026-06-12 20:26:08.331041 | PLAY RECAP
2026-06-12 20:26:08.331085 | controller | ok: 23 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0
2026-06-12 20:26:08.331106 | 
2026-06-12 20:26:08.505341 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-06-12 20:26:08.508210 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2026-06-12 20:26:09.104675 | 
2026-06-12 20:26:09.104776 | PLAY [all]
2026-06-12 20:26:09.113357 | 
2026-06-12 20:26:09.113423 | TASK [Abort when test_command variable is undefined]
2026-06-12 20:26:09.157544 | controller | skipping: Conditional result was False
2026-06-12 20:26:09.161949 | 
2026-06-12 20:26:09.162023 | TASK [Convert test_command to list]
2026-06-12 20:26:09.226224 | controller | skipping: Conditional result was False
2026-06-12 20:26:09.235024 | 
2026-06-12 20:26:09.235160 | TASK [Use test_command list]
2026-06-12 20:26:09.299627 | controller | ok
2026-06-12 20:26:09.310325 | 
2026-06-12 20:26:09.310457 | LOOP [Run test_command]
2026-06-12 20:26:09.796664 | controller | no check to run
2026-06-12 20:26:09.796980 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.003377
2026-06-12 20:26:09.807736 | 
2026-06-12 20:26:09.807887 | PLAY RECAP
2026-06-12 20:26:09.807974 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-06-12 20:26:09.808018 | 
2026-06-12 20:26:09.959116 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2026-06-12 20:26:09.962175 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-06-12 20:26:10.600624 | 
2026-06-12 20:26:10.600749 | PLAY [all]
2026-06-12 20:26:10.611295 | 
2026-06-12 20:26:10.611367 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-06-12 20:26:11.031663 | controller | changed: non-zero return code
2026-06-12 20:26:11.040479 | 
2026-06-12 20:26:11.040664 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-06-12 20:26:11.067560 | controller | skipping: Conditional result was False
2026-06-12 20:26:11.078869 | 
2026-06-12 20:26:11.079013 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-06-12 20:26:11.117125 | 
2026-06-12 20:26:11.117447 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-06-12 20:26:11.154271 | 
2026-06-12 20:26:11.154569 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-06-12 20:26:11.181812 | controller | skipping: Conditional result was False
2026-06-12 20:26:11.193616 | 
2026-06-12 20:26:11.193901 | LOOP [fetch-subunit-output : Generate subunit file]
2026-06-12 20:26:11.231952 | 
2026-06-12 20:26:11.232223 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-06-12 20:26:11.259853 | controller | skipping: Conditional result was False
2026-06-12 20:26:11.270480 | 
2026-06-12 20:26:11.270663 | TASK [fetch-subunit-output : Remove the temporary file]
2026-06-12 20:26:11.297197 | controller | skipping: Conditional result was False
2026-06-12 20:26:11.306664 | 
2026-06-12 20:26:11.306807 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-06-12 20:26:11.333404 | controller | skipping: Conditional result was False
2026-06-12 20:26:11.347626 | 
2026-06-12 20:26:11.347726 | PLAY RECAP
2026-06-12 20:26:11.347809 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-06-12 20:26:11.347851 | 
2026-06-12 20:26:11.489689 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-06-12 20:26:11.492413 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-06-12 20:26:12.188173 | 
2026-06-12 20:26:12.188322 | PLAY [all]
2026-06-12 20:26:12.198362 | 
2026-06-12 20:26:12.198439 | TASK [include_role : fetch-output]
2026-06-12 20:26:12.247420 | controller | ok
2026-06-12 20:26:12.264367 | 
2026-06-12 20:26:12.264473 | TASK [fetch-output : Set log path for multiple nodes]
2026-06-12 20:26:12.340896 | controller | skipping: Conditional result was False
2026-06-12 20:26:12.346258 | 
2026-06-12 20:26:12.346332 | TASK [fetch-output : Set log path for single node]
2026-06-12 20:26:12.385672 | controller | ok
2026-06-12 20:26:12.389330 | 
2026-06-12 20:26:12.389393 | LOOP [fetch-output : Ensure local output dirs]
2026-06-12 20:26:12.813711 | controller -> localhost | changed: "/var/lib/zuul/builds/ca74d82890be4de89ec8379962b8e096/work/logs"
2026-06-12 20:26:13.082168 | controller -> localhost | changed: "/var/lib/zuul/builds/ca74d82890be4de89ec8379962b8e096/work/artifacts"
2026-06-12 20:26:13.318143 | controller -> localhost | changed: "/var/lib/zuul/builds/ca74d82890be4de89ec8379962b8e096/work/docs"
2026-06-12 20:26:13.331333 | 
2026-06-12 20:26:13.331505 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-06-12 20:26:14.094893 | controller | changed: .d..t...... ./
2026-06-12 20:26:14.095239 | controller | changed: All items complete
2026-06-12 20:26:14.095296 | 
2026-06-12 20:26:14.664291 | controller | changed: .d..t...... ./
2026-06-12 20:26:15.218114 | controller | changed: .d..t...... ./
2026-06-12 20:26:15.235584 | 
2026-06-12 20:26:15.235718 | TASK [include_role : fetch-output-openshift]
2026-06-12 20:26:15.262047 | controller | skipping: Conditional result was False
2026-06-12 20:26:15.271188 | 
2026-06-12 20:26:15.271327 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-06-12 20:26:15.760091 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.013037
2026-06-12 20:26:16.058182 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.012304
2026-06-12 20:26:16.078394 | 
2026-06-12 20:26:16.078499 | PLAY [all]
2026-06-12 20:26:16.088439 | 
2026-06-12 20:26:16.088607 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-06-12 20:26:16.563853 | controller | changed
2026-06-12 20:26:16.573069 | 
2026-06-12 20:26:16.573165 | PLAY RECAP
2026-06-12 20:26:16.573244 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-06-12 20:26:16.573285 | 
2026-06-12 20:26:16.729373 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-06-12 20:26:16.731749 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-06-12 20:26:17.377138 | 
2026-06-12 20:26:17.377469 | PLAY [localhost]
2026-06-12 20:26:17.387852 | 
2026-06-12 20:26:17.387925 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-06-12 20:26:17.833258 | localhost | changed
2026-06-12 20:26:17.853309 | 
2026-06-12 20:26:17.853683 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-06-12 20:26:17.899229 | localhost | ok
2026-06-12 20:26:17.914184 | 
2026-06-12 20:26:17.914324 | TASK [add-fileserver : Create SSH private key tempfile]
2026-06-12 20:26:18.354000 | localhost | changed
2026-06-12 20:26:18.363874 | 
2026-06-12 20:26:18.364012 | TASK [add-fileserver : Create SSH private key from secret]
2026-06-12 20:26:19.181906 | localhost | changed
2026-06-12 20:26:19.186104 | 
2026-06-12 20:26:19.186169 | TASK [add-fileserver : Add fileserver ssh key]
2026-06-12 20:26:19.671564 | localhost | Identity added: /var/lib/zuul/builds/ca74d82890be4de89ec8379962b8e096/work/tmp/ansible.hpmtj7my (/var/lib/zuul/builds/ca74d82890be4de89ec8379962b8e096/work/tmp/ansible.hpmtj7my)
2026-06-12 20:26:19.671769 | localhost | ok: Runtime: 0:00:00.026075
2026-06-12 20:26:19.674942 | 
2026-06-12 20:26:19.675004 | TASK [add-fileserver : Remove SSH private key from disk]
2026-06-12 20:26:20.016793 | localhost | ok: Runtime: 0:00:00.009421
2026-06-12 20:26:20.020542 | 
2026-06-12 20:26:20.020606 | TASK [add-fileserver : Add fileserver to inventory]
2026-06-12 20:26:20.096815 | localhost | changed
2026-06-12 20:26:20.101060 | 
2026-06-12 20:26:20.101122 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-06-12 20:26:20.525596 | localhost | changed
2026-06-12 20:26:20.536588 | 
2026-06-12 20:26:20.536750 | PLAY [localhost]
2026-06-12 20:26:20.544762 | 
2026-06-12 20:26:20.544886 | TASK [Generate bulk log download script]
2026-06-12 20:26:20.567877 | localhost | ok
2026-06-12 20:26:20.589762 | 
2026-06-12 20:26:20.589868 | TASK [local-log-download : Check API endpoint is defined]
2026-06-12 20:26:20.641856 | localhost | ok: All assertions passed
2026-06-12 20:26:20.650219 | 
2026-06-12 20:26:20.650350 | TASK [local-log-download : Create download script]
2026-06-12 20:26:21.191857 | localhost -> localhost | changed
2026-06-12 20:26:21.225264 | 
2026-06-12 20:26:21.225391 | TASK [Register quick-download link]
2026-06-12 20:26:21.253416 | localhost | ok
2026-06-12 20:26:21.255868 | 
2026-06-12 20:26:21.255935 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com]
2026-06-12 20:26:21.261661 | 
2026-06-12 20:26:21.261742 | TASK [Set zuul-log-path fact]
2026-06-12 20:26:21.278438 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-12 20:26:21.288239 | 
2026-06-12 20:26:21.288337 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-12 20:26:21.326376 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-12 20:26:21.330121 | 
2026-06-12 20:26:21.330214 | TASK [upload-logs : Create log directories]
2026-06-12 20:26:22.253065 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed
2026-06-12 20:26:22.256388 | 
2026-06-12 20:26:22.256558 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-06-12 20:26:22.597855 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.005138
2026-06-12 20:26:22.600944 | 
2026-06-12 20:26:22.601006 | TASK [upload-logs : Upload logs to log server]
2026-06-12 20:26:23.897264 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | Output suppressed because no_log was given
2026-06-12 20:26:23.900805 | 
2026-06-12 20:26:23.900942 | LOOP [upload-logs : Compress console log and json output]