2026-06-12 20:19:26.468548 | Job console starting 2026-06-12 20:19:26.604865 | Updating git repos 2026-06-12 20:19:26.651093 | Cloning repos into workspace 2026-06-12 20:19:28.134948 | Restoring repo states 2026-06-12 20:19:28.156156 | Merging changes 2026-06-12 20:19:30.480001 | Checking out repos 2026-06-12 20:19:30.637673 | Preparing playbooks 2026-06-12 20:19:34.484486 | Running Ansible setup 2026-06-12 20:19:38.026363 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-06-12 20:19:38.665523 | 2026-06-12 20:19:38.665641 | PLAY [localhost] 2026-06-12 20:19:38.673510 | 2026-06-12 20:19:38.673606 | TASK [Gathering Facts] 2026-06-12 20:19:39.587651 | localhost | ok 2026-06-12 20:19:39.601015 | 2026-06-12 20:19:39.601179 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-12 20:19:40.095878 | localhost -> localhost | changed 2026-06-12 20:19:40.100959 | 2026-06-12 20:19:40.101024 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-12 20:19:41.115205 | localhost -> localhost | changed 2026-06-12 20:19:41.127084 | 2026-06-12 20:19:41.127223 | TASK [Setup log path fact] 2026-06-12 20:19:41.149848 | localhost | ok 2026-06-12 20:19:41.168984 | 2026-06-12 20:19:41.169113 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-12 20:19:41.209032 | localhost | ok 2026-06-12 20:19:41.216788 | 2026-06-12 20:19:41.216889 | TASK [emit-job-header : Print job information] 2026-06-12 20:19:41.265171 | # Job Information 2026-06-12 20:19:41.265362 | Ansible Version: 2.16.18 2026-06-12 20:19:41.265398 | Job: cifmw-molecule-federation 2026-06-12 20:19:41.265425 | Pipeline: github-check 2026-06-12 20:19:41.265479 | Executor: 38.102.83.47 2026-06-12 20:19:41.265505 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3965 2026-06-12 20:19:41.265534 | Log URL (when completed): https://gateway-cloud-softwarefactory.apps.ocp.cloud.ci.centos.org/logs/593/rdoproject.org/5939af7d8082455b8c6034cb0a200c51/ 2026-06-12 20:19:41.265564 | Event ID: c8c08420-669b-11f1-833a-96505ed7e25f 2026-06-12 20:19:41.269504 | 2026-06-12 20:19:41.269615 | LOOP [emit-job-header : Print node information] 2026-06-12 20:19:41.364326 | localhost | ok: 2026-06-12 20:19:41.364609 | localhost | # Node Information 2026-06-12 20:19:41.364664 | localhost | Inventory Hostname: controller 2026-06-12 20:19:41.364711 | localhost | Hostname: np0000072355 2026-06-12 20:19:41.364752 | localhost | Username: zuul 2026-06-12 20:19:41.364795 | localhost | Distro: CentOS 9 2026-06-12 20:19:41.364834 | localhost | Provider: vexxhost-nodepool-tripleo 2026-06-12 20:19:41.364872 | localhost | Region: RegionOne 2026-06-12 20:19:41.364908 | localhost | Label: cloud-centos-9-stream-tripleo 2026-06-12 20:19:41.364944 | localhost | Product Name: OpenStack Nova 2026-06-12 20:19:41.364980 | localhost | Interface IP: 38.102.83.144 2026-06-12 20:19:41.374731 | 2026-06-12 20:19:41.374813 | PLAY [all] 2026-06-12 20:19:41.384463 | 2026-06-12 20:19:41.384583 | TASK [Gather network facts] 2026-06-12 20:19:41.805073 | controller | ok 2026-06-12 20:19:41.816689 | 2026-06-12 20:19:41.816808 | TASK [include_role : start-zuul-console] 2026-06-12 20:19:41.841895 | controller | ok 2026-06-12 20:19:41.862215 | 2026-06-12 20:19:41.862355 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-06-12 20:19:42.283576 | controller | ok 2026-06-12 20:19:42.300239 | 2026-06-12 20:19:42.300396 | TASK [include_role : add-build-sshkey] 2026-06-12 20:19:42.334792 | controller | ok 2026-06-12 20:19:42.360692 | 2026-06-12 20:19:42.360885 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-12 20:19:42.661937 | controller -> localhost | ok 2026-06-12 20:19:42.671225 | 2026-06-12 20:19:42.671359 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-12 20:19:42.704883 | controller | ok 2026-06-12 20:19:42.732076 | controller | included: /var/lib/zuul/builds/5939af7d8082455b8c6034cb0a200c51/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-12 20:19:42.740283 | 2026-06-12 20:19:42.740366 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-12 20:19:43.527787 | controller -> localhost | Generating public/private rsa key pair. 2026-06-12 20:19:43.527957 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/5939af7d8082455b8c6034cb0a200c51/work/5939af7d8082455b8c6034cb0a200c51_id_rsa 2026-06-12 20:19:43.527989 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/5939af7d8082455b8c6034cb0a200c51/work/5939af7d8082455b8c6034cb0a200c51_id_rsa.pub 2026-06-12 20:19:43.528011 | controller -> localhost | The key fingerprint is: 2026-06-12 20:19:43.528032 | controller -> localhost | SHA256:EsKUlH+pBGAgvQh94Jc1RIjvzJdD4uinBKKw3TRKVyQ zuul-build-sshkey 2026-06-12 20:19:43.528052 | controller -> localhost | The key's randomart image is: 2026-06-12 20:19:43.528073 | controller -> localhost | +---[RSA 3072]----+ 2026-06-12 20:19:43.528093 | controller -> localhost | |o++=E** | 2026-06-12 20:19:43.528114 | controller -> localhost | |oo+o*= . | 2026-06-12 20:19:43.528133 | controller -> localhost | |...+=oo . | 2026-06-12 20:19:43.528153 | controller -> localhost | |. ..oo+.o | 2026-06-12 20:19:43.528173 | controller -> localhost | |+ .*++.+S | 2026-06-12 20:19:43.528192 | controller -> localhost | |+=.==.=. | 2026-06-12 20:19:43.528211 | controller -> localhost | |o.+ .. . | 2026-06-12 20:19:43.528230 | controller -> localhost | | .. . | 2026-06-12 20:19:43.528250 | controller -> localhost | | .o | 2026-06-12 20:19:43.528269 | controller -> localhost | +----[SHA256]-----+ 2026-06-12 20:19:43.528312 | controller -> localhost | ok: Runtime: 0:00:00.271784 2026-06-12 20:19:43.538015 | 2026-06-12 20:19:43.538099 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-12 20:19:43.565413 | controller | ok 2026-06-12 20:19:43.573289 | controller | included: /var/lib/zuul/builds/5939af7d8082455b8c6034cb0a200c51/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-12 20:19:43.579711 | 2026-06-12 20:19:43.579768 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-12 20:19:43.602881 | controller | skipping: Conditional result was False 2026-06-12 20:19:43.606645 | 2026-06-12 20:19:43.606705 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-12 20:19:44.226911 | controller | changed 2026-06-12 20:19:44.234258 | 2026-06-12 20:19:44.234379 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-12 20:19:44.480644 | controller | ok 2026-06-12 20:19:44.488157 | 2026-06-12 20:19:44.488289 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-12 20:19:45.379199 | controller | changed 2026-06-12 20:19:45.386587 | 2026-06-12 20:19:45.386718 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-12 20:19:46.192126 | controller | changed 2026-06-12 20:19:46.199647 | 2026-06-12 20:19:46.199774 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-12 20:19:46.226936 | controller | skipping: Conditional result was False 2026-06-12 20:19:46.236673 | 2026-06-12 20:19:46.236870 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-12 20:19:46.703725 | controller -> localhost | changed 2026-06-12 20:19:46.718474 | 2026-06-12 20:19:46.718645 | TASK [add-build-sshkey : Add back temp key] 2026-06-12 20:19:47.153085 | controller -> localhost | Identity added: /var/lib/zuul/builds/5939af7d8082455b8c6034cb0a200c51/work/5939af7d8082455b8c6034cb0a200c51_id_rsa (zuul-build-sshkey) 2026-06-12 20:19:47.153590 | controller -> localhost | ok: Runtime: 0:00:00.019696 2026-06-12 20:19:47.166300 | 2026-06-12 20:19:47.166537 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-12 20:19:47.557309 | controller | ok 2026-06-12 20:19:47.564081 | 2026-06-12 20:19:47.564204 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-12 20:19:47.601006 | controller | skipping: Conditional result was False 2026-06-12 20:19:47.615239 | 2026-06-12 20:19:47.615370 | TASK [include_role : validate-host] 2026-06-12 20:19:47.652589 | controller | ok 2026-06-12 20:19:47.684566 | 2026-06-12 20:19:47.684649 | TASK [validate-host : Define zuul_info_dir fact] 2026-06-12 20:19:47.723457 | controller | ok 2026-06-12 20:19:47.727784 | 2026-06-12 20:19:47.727868 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-06-12 20:19:48.065712 | controller -> localhost | ok 2026-06-12 20:19:48.075908 | 2026-06-12 20:19:48.076066 | TASK [validate-host : Collect information about the host] 2026-06-12 20:19:48.790087 | controller | ok 2026-06-12 20:19:48.801218 | 2026-06-12 20:19:48.801304 | TASK [validate-host : Sanitize hostname] 2026-06-12 20:19:48.882878 | controller | ok 2026-06-12 20:19:48.886862 | 2026-06-12 20:19:48.886932 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-06-12 20:19:49.467904 | controller -> localhost | changed 2026-06-12 20:19:49.476167 | 2026-06-12 20:19:49.476265 | TASK [validate-host : Collect information about zuul worker] 2026-06-12 20:19:49.931182 | controller | ok 2026-06-12 20:19:49.935703 | 2026-06-12 20:19:49.935793 | TASK [validate-host : Write out all zuul information for each host] 2026-06-12 20:19:50.468500 | controller -> localhost | changed 2026-06-12 20:19:50.482711 | 2026-06-12 20:19:50.482839 | TASK [include_role : prepare-workspace-openshift] 2026-06-12 20:19:50.508761 | controller | skipping: Conditional result was False 2026-06-12 20:19:50.514133 | 2026-06-12 20:19:50.514217 | TASK [include_role : remove-zuul-sshkey] 2026-06-12 20:19:50.529486 | controller | skipping: Conditional result was False 2026-06-12 20:19:50.534224 | 2026-06-12 20:19:50.534303 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-12 20:19:50.805820 | controller | ok: "logs" 2026-06-12 20:19:50.806381 | controller | ok: All items complete 2026-06-12 20:19:50.806485 | 2026-06-12 20:19:51.026107 | controller | ok: "artifacts" 2026-06-12 20:19:51.318323 | controller | ok: "docs" 2026-06-12 20:19:51.326943 | 2026-06-12 20:19:51.327114 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-12 20:19:51.603978 | controller | changed: "logs" 2026-06-12 20:19:51.889973 | controller | changed: "artifacts" 2026-06-12 20:19:52.117879 | controller | changed: "docs" 2026-06-12 20:19:52.135790 | 2026-06-12 20:19:52.135946 | PLAY RECAP 2026-06-12 20:19:52.136034 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2026-06-12 20:19:52.136087 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-12 20:19:52.136123 | 2026-06-12 20:19:52.273048 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-06-12 20:19:52.275531 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-06-12 20:19:52.931159 | 2026-06-12 20:19:52.931312 | PLAY [localhost] 2026-06-12 20:19:52.939583 | 2026-06-12 20:19:52.939651 | TASK [configure-ssh-config-fips : Ensure ssh config directory] 2026-06-12 20:19:53.429767 | localhost | changed 2026-06-12 20:19:53.439035 | 2026-06-12 20:19:53.439200 | TASK [configure-ssh-config-fips : Setup ssh config] 2026-06-12 20:19:54.222189 | localhost | changed 2026-06-12 20:19:54.235151 | 2026-06-12 20:19:54.235270 | PLAY [all] 2026-06-12 20:19:54.246656 | 2026-06-12 20:19:54.246778 | TASK [include_role : prepare-workspace] 2026-06-12 20:19:54.280189 | controller | ok 2026-06-12 20:19:54.307937 | 2026-06-12 20:19:54.308040 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-12 20:19:54.744730 | controller | ok 2026-06-12 20:19:54.755118 | 2026-06-12 20:19:54.755276 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-12 20:19:56.655953 | controller | Output suppressed because no_log was given 2026-06-12 20:19:56.670574 | 2026-06-12 20:19:56.670712 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-12 20:19:56.932991 | controller | changed: "logs" 2026-06-12 20:19:57.129943 | controller | changed: "artifacts" 2026-06-12 20:19:57.381949 | controller | changed: "docs" 2026-06-12 20:19:57.404177 | 2026-06-12 20:19:57.404376 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-12 20:19:57.653681 | controller | changed: "logs" 2026-06-12 20:19:57.654166 | controller | changed: All items complete 2026-06-12 20:19:57.654232 | 2026-06-12 20:19:57.864957 | controller | changed: "artifacts" 2026-06-12 20:19:58.070618 | controller | changed: "docs" 2026-06-12 20:19:58.104281 | 2026-06-12 20:19:58.104486 | TASK [Check if worker can sudo] 2026-06-12 20:19:58.653079 | controller | ok: Runtime: 0:00:00.057934 2026-06-12 20:19:58.661771 | 2026-06-12 20:19:58.661892 | TASK [configure-mirrors : Gather needed facts] 2026-06-12 20:19:58.740835 | controller | skipping: Conditional result was False 2026-06-12 20:19:58.755050 | 2026-06-12 20:19:58.755262 | TASK [configure-mirrors : Set up infrastructure mirrors] 2026-06-12 20:19:58.844178 | controller | ok 2026-06-12 20:19:58.857238 | controller | included: /var/lib/zuul/builds/5939af7d8082455b8c6034cb0a200c51/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml 2026-06-12 20:19:58.866075 | 2026-06-12 20:19:58.866204 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir] 2026-06-12 20:19:59.203342 | controller | ok 2026-06-12 20:19:59.254042 | 2026-06-12 20:19:59.254183 | LOOP [configure-mirrors : Include OS-specific variables] 2026-06-12 20:19:59.340592 | controller | ok: "/var/lib/zuul/builds/5939af7d8082455b8c6034cb0a200c51/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml" 2026-06-12 20:19:59.360998 | 2026-06-12 20:19:59.361178 | TASK [configure-mirrors : Install /etc/pip.conf configuration] 2026-06-12 20:20:00.297389 | controller | changed 2026-06-12 20:20:00.308876 | 2026-06-12 20:20:00.309012 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors] 2026-06-12 20:20:00.396747 | controller | ok: "/var/lib/zuul/builds/5939af7d8082455b8c6034cb0a200c51/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml" 2026-06-12 20:20:00.397024 | controller | ok: All items complete 2026-06-12 20:20:00.397091 | 2026-06-12 20:20:00.471781 | controller | included: /var/lib/zuul/builds/5939af7d8082455b8c6034cb0a200c51/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml 2026-06-12 20:20:00.478755 | 2026-06-12 20:20:00.478840 | LOOP [configure-mirrors : Install CentOS 9 repository files] 2026-06-12 20:20:01.451594 | controller | changed: "etc/yum.repos.d/centos.repo" 2026-06-12 20:20:02.368299 | controller | changed: "etc/yum.repos.d/centos-addons.repo" 2026-06-12 20:20:02.386262 | 2026-06-12 20:20:02.386652 | TASK [configure-mirrors : Disable deltrarpm] 2026-06-12 20:20:02.858809 | controller | changed: section and option added 2026-06-12 20:20:02.880246 | 2026-06-12 20:20:02.880383 | LOOP [configure-mirrors : Update yum/dnf cache] 2026-06-12 20:20:03.589923 | controller | 29 files removed 2026-06-12 20:20:03.590155 | controller | ok: Item: dnf clean all Runtime: 0:00:00.393980 2026-06-12 20:20:03.590195 | controller | changed: All items complete 2026-06-12 20:20:03.590216 | 2026-06-12 20:20:14.945153 | 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:20:14.945368 | controller | DNF version: 4.14.0 2026-06-12 20:20:14.945423 | controller | cachedir: /var/cache/dnf 2026-06-12 20:20:14.945505 | controller | Making cache files for all metadata files. 2026-06-12 20:20:14.945546 | controller | baseos: has expired and will be refreshed. 2026-06-12 20:20:14.945600 | controller | appstream: has expired and will be refreshed. 2026-06-12 20:20:14.945637 | controller | crb: has expired and will be refreshed. 2026-06-12 20:20:14.945671 | controller | extras-common: has expired and will be refreshed. 2026-06-12 20:20:14.945707 | controller | repo: downloading from remote: baseos 2026-06-12 20:20:14.945742 | controller | CentOS Stream 9 - BaseOS 70 MB/s | 8.9 MB 00:00 2026-06-12 20:20:14.945778 | controller | baseos: using metadata from Thu 11 Jun 2026 01:27:06 PM EDT. 2026-06-12 20:20:14.945814 | controller | repo: downloading from remote: appstream 2026-06-12 20:20:14.945849 | controller | CentOS Stream 9 - AppStream 96 MB/s | 28 MB 00:00 2026-06-12 20:20:14.945884 | controller | appstream: using metadata from Thu 11 Jun 2026 01:30:52 PM EDT. 2026-06-12 20:20:14.945918 | controller | repo: downloading from remote: crb 2026-06-12 20:20:14.945953 | controller | CentOS Stream 9 - CRB 52 MB/s | 8.3 MB 00:00 2026-06-12 20:20:14.945988 | controller | crb: using metadata from Thu 11 Jun 2026 01:35:27 PM EDT. 2026-06-12 20:20:14.946023 | controller | repo: downloading from remote: extras-common 2026-06-12 20:20:14.946058 | controller | CentOS Stream 9 - Extras packages 1.6 MB/s | 21 kB 00:00 2026-06-12 20:20:14.946093 | controller | extras-common: using metadata from Wed 10 Jun 2026 10:05:57 AM EDT. 2026-06-12 20:20:14.946127 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)' 2026-06-12 20:20:14.946161 | controller | Completion plugin: Generating completion cache... 2026-06-12 20:20:14.946196 | controller | Metadata cache created. 2026-06-12 20:20:14.946249 | controller | ok: Item: dnf makecache -v Runtime: 0:00:11.060969 2026-06-12 20:20:14.956349 | 2026-06-12 20:20:14.956490 | PLAY RECAP 2026-06-12 20:20:14.956710 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-12 20:20:14.956778 | localhost | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-12 20:20:14.956822 | 2026-06-12 20:20:15.107147 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-06-12 20:20:15.109508 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-06-12 20:20:15.762992 | 2026-06-12 20:20:15.763109 | PLAY [all] 2026-06-12 20:20:15.773294 | 2026-06-12 20:20:15.773363 | TASK [Install binary dependencies] 2026-06-12 20:20:15.841945 | controller | ok 2026-06-12 20:20:15.863299 | 2026-06-12 20:20:15.863408 | TASK [bindep : Include find tasks] 2026-06-12 20:20:15.892645 | controller | ok 2026-06-12 20:20:15.900833 | controller | included: /var/lib/zuul/builds/5939af7d8082455b8c6034cb0a200c51/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-06-12 20:20:15.906803 | 2026-06-12 20:20:15.906889 | TASK [bindep : Look for bindep.txt] 2026-06-12 20:20:16.282941 | controller | ok 2026-06-12 20:20:16.292308 | 2026-06-12 20:20:16.292495 | TASK [bindep : Define bindep_file fact] 2026-06-12 20:20:16.335911 | controller | ok 2026-06-12 20:20:16.344630 | 2026-06-12 20:20:16.344756 | TASK [bindep : Look for other-requirements.txt] 2026-06-12 20:20:16.360518 | controller | skipping: Conditional result was False 2026-06-12 20:20:16.369321 | 2026-06-12 20:20:16.369483 | TASK [bindep : Define bindep_file fact] 2026-06-12 20:20:16.394953 | controller | skipping: Conditional result was False 2026-06-12 20:20:16.404911 | 2026-06-12 20:20:16.405043 | TASK [bindep : Look for bindep fallback file] 2026-06-12 20:20:16.440873 | controller | skipping: Conditional result was False 2026-06-12 20:20:16.449478 | 2026-06-12 20:20:16.449619 | TASK [bindep : Define bindep_file fact] 2026-06-12 20:20:16.485525 | controller | skipping: Conditional result was False 2026-06-12 20:20:16.494153 | 2026-06-12 20:20:16.494288 | TASK [bindep : Include bindep tasks] 2026-06-12 20:20:16.537194 | controller | ok 2026-06-12 20:20:16.548035 | controller | included: /var/lib/zuul/builds/5939af7d8082455b8c6034cb0a200c51/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml 2026-06-12 20:20:16.563756 | 2026-06-12 20:20:16.563895 | TASK [bindep : Look for bindep command] 2026-06-12 20:20:16.599899 | controller | skipping: Conditional result was False 2026-06-12 20:20:16.609986 | 2026-06-12 20:20:16.610121 | TASK [bindep : Check for system bindep] 2026-06-12 20:20:17.153327 | controller | ok: Runtime: 0:00:00.006450 2026-06-12 20:20:17.161561 | 2026-06-12 20:20:17.161691 | TASK [bindep : Define bindep_command fact] 2026-06-12 20:20:17.197514 | controller | skipping: Conditional result was False 2026-06-12 20:20:17.206582 | 2026-06-12 20:20:17.206717 | TASK [bindep : Include install tasks] 2026-06-12 20:20:17.250897 | controller | ok 2026-06-12 20:20:17.263392 | controller | included: /var/lib/zuul/builds/5939af7d8082455b8c6034cb0a200c51/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml 2026-06-12 20:20:17.279584 | 2026-06-12 20:20:17.279720 | TASK [bindep : Create temp dir for bindep] 2026-06-12 20:20:17.679041 | controller | changed 2026-06-12 20:20:17.686927 | 2026-06-12 20:20:17.687043 | TASK [Ensure we have pip dependencies] 2026-06-12 20:20:17.711157 | controller | ok 2026-06-12 20:20:17.750688 | 2026-06-12 20:20:17.750827 | TASK [ensure-pip : Check if pip is installed] 2026-06-12 16:20:18.023805 | controller | /usr/bin/pip3 2026-06-12 16:20:18.067971 | controller | /usr/bin/python3: No module named wheel 2026-06-12 20:20:18.286145 | controller | ok: Runtime: 0:00:00.057447 2026-06-12 20:20:18.295170 | 2026-06-12 20:20:18.295296 | LOOP [ensure-pip : Install pip from packages] 2026-06-12 20:20:18.335573 | controller | ok: "/var/lib/zuul/builds/5939af7d8082455b8c6034cb0a200c51/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml" 2026-06-12 20:20:18.358695 | controller | included: /var/lib/zuul/builds/5939af7d8082455b8c6034cb0a200c51/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml 2026-06-12 20:20:18.412875 | 2026-06-12 20:20:18.413022 | TASK [ensure-pip : Install Python 3 pip] 2026-06-12 20:20:19.871327 | controller | ok: Nothing to do 2026-06-12 20:20:19.879508 | 2026-06-12 20:20:19.879636 | TASK [ensure-pip : Install Python3-wheel on older CentOS] 2026-06-12 20:20:21.667683 | controller | changed 2026-06-12 20:20:21.671579 | 2026-06-12 20:20:21.671637 | TASK [ensure-pip : Check for EPEL repository] 2026-06-12 20:20:21.735832 | controller | skipping: Conditional result was False 2026-06-12 20:20:21.744914 | 2026-06-12 20:20:21.745052 | TASK [ensure-pip : Fail with instructions when EPEL is not installed] 2026-06-12 20:20:21.811826 | controller | skipping: Conditional result was False 2026-06-12 20:20:21.823883 | 2026-06-12 20:20:21.824024 | TASK [ensure-pip : Install Python 2 pip] 2026-06-12 20:20:21.889782 | controller | skipping: Conditional result was False 2026-06-12 20:20:21.898890 | 2026-06-12 20:20:21.899030 | TASK [ensure-pip : Ensure setuptools] 2026-06-12 20:20:21.924948 | controller | skipping: Conditional result was False 2026-06-12 20:20:21.933795 | 2026-06-12 20:20:21.933928 | TASK [ensure-pip : Check for ensurepip module] 2026-06-12 20:20:22.473975 | controller | skipping: Conditional result was False 2026-06-12 20:20:22.484297 | 2026-06-12 20:20:22.484476 | TASK [ensure-pip : Ensure python3-venv] 2026-06-12 20:20:22.499581 | controller | skipping: Conditional result was False 2026-06-12 20:20:22.503951 | 2026-06-12 20:20:22.504030 | TASK [ensure-pip : Install pip from source] 2026-06-12 20:20:22.517781 | controller | skipping: Conditional result was False 2026-06-12 20:20:22.526612 | 2026-06-12 20:20:22.526750 | TASK [ensure-pip : Probe for venv python full path] 2026-06-12 16:20:22.841463 | controller | /usr/bin/python3 2026-06-12 20:20:23.064377 | controller | ok: Runtime: 0:00:00.006749 2026-06-12 20:20:23.072768 | 2026-06-12 20:20:23.072899 | TASK [ensure-pip : Set host default] 2026-06-12 20:20:23.149332 | controller | ok 2026-06-12 20:20:23.158155 | 2026-06-12 20:20:23.158290 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2026-06-12 20:20:23.236965 | controller | ok 2026-06-12 20:20:23.253261 | 2026-06-12 20:20:23.253387 | TASK [bindep : Install bindep into temporary venv] 2026-06-12 20:20:27.733824 | controller | changed 2026-06-12 20:20:27.742182 | 2026-06-12 20:20:27.742317 | TASK [bindep : Define bindep_command] 2026-06-12 20:20:27.787002 | controller | ok 2026-06-12 20:20:27.794389 | 2026-06-12 20:20:27.794545 | LOOP [bindep : Include package tasks] 2026-06-12 20:20:27.866308 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt" 2026-06-12 20:20:27.866604 | controller | ok: All items complete 2026-06-12 20:20:27.866658 | 2026-06-12 20:20:27.886856 | controller | included: /var/lib/zuul/builds/5939af7d8082455b8c6034cb0a200c51/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml 2026-06-12 20:20:27.906951 | 2026-06-12 20:20:27.907076 | TASK [bindep : Define bindep_run fact] 2026-06-12 20:20:27.941458 | controller | ok 2026-06-12 20:20:27.958208 | 2026-06-12 20:20:27.958339 | TASK [bindep : Get list of packages to install from bindep] 2026-06-12 16:20:29.369896 | controller | podman 2026-06-12 16:20:29.370166 | controller | python3-jmespath 2026-06-12 16:20:29.370182 | controller | python3-libvirt 2026-06-12 16:20:29.370192 | controller | python3-lxml 2026-06-12 16:20:29.370200 | controller | python3-netaddr 2026-06-12 20:20:29.497235 | controller | ok: Runtime: 0:00:01.152434 2026-06-12 20:20:29.505730 | 2026-06-12 20:20:29.505854 | TASK [bindep : Install distro packages from bindep] 2026-06-12 20:21:11.862284 | controller | changed 2026-06-12 20:21:11.871463 | 2026-06-12 20:21:11.871594 | TASK [bindep : Check that packages are installed] 2026-06-12 20:21:13.419498 | controller | ok: Runtime: 0:00:01.174902 2026-06-12 20:21:13.428421 | 2026-06-12 20:21:13.428590 | TASK [bindep : Fail if we cannot install all packages] 2026-06-12 20:21:13.465270 | controller | skipping: Conditional result was False 2026-06-12 20:21:13.483269 | 2026-06-12 20:21:13.483405 | TASK [Run test-setup role] 2026-06-12 20:21:13.509288 | controller | ok 2026-06-12 20:21:13.538399 | 2026-06-12 20:21:13.538502 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-06-12 20:21:13.834631 | controller | ok 2026-06-12 20:21:13.843418 | 2026-06-12 20:21:13.843609 | TASK [test-setup : Run tools/test-setup.sh] 2026-06-12 20:21:14.385420 | controller | skipping: Conditional result was False 2026-06-12 20:21:14.408159 | 2026-06-12 20:21:14.408315 | TASK [bindep : Remove bindep temp dir] 2026-06-12 20:21:14.856919 | controller | ok 2026-06-12 20:21:14.859400 | 2026-06-12 20:21:14.859537 | PLAY RECAP 2026-06-12 20:21:14.859625 | controller | ok: 23 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0 2026-06-12 20:21:14.859671 | 2026-06-12 20:21:14.997089 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-06-12 20:21:14.999754 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2026-06-12 20:21:15.564700 | 2026-06-12 20:21:15.564810 | PLAY [all] 2026-06-12 20:21:15.573195 | 2026-06-12 20:21:15.573260 | TASK [Abort when test_command variable is undefined] 2026-06-12 20:21:15.607257 | controller | skipping: Conditional result was False 2026-06-12 20:21:15.612712 | 2026-06-12 20:21:15.612806 | TASK [Convert test_command to list] 2026-06-12 20:21:15.677624 | controller | skipping: Conditional result was False 2026-06-12 20:21:15.686127 | 2026-06-12 20:21:15.686270 | TASK [Use test_command list] 2026-06-12 20:21:15.759127 | controller | ok 2026-06-12 20:21:15.769507 | 2026-06-12 20:21:15.769636 | LOOP [Run test_command] 2026-06-12 20:21:16.237959 | controller | no check to run 2026-06-12 20:21:16.238281 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.004270 2026-06-12 20:21:16.247764 | 2026-06-12 20:21:16.247891 | PLAY RECAP 2026-06-12 20:21:16.247973 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-12 20:21:16.248016 | 2026-06-12 20:21:16.370940 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2026-06-12 20:21:16.373513 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-12 20:21:16.977312 | 2026-06-12 20:21:16.977420 | PLAY [all] 2026-06-12 20:21:16.987509 | 2026-06-12 20:21:16.987583 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-06-12 20:21:17.382602 | controller | changed: non-zero return code 2026-06-12 20:21:17.391194 | 2026-06-12 20:21:17.391345 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-06-12 20:21:17.417760 | controller | skipping: Conditional result was False 2026-06-12 20:21:17.428615 | 2026-06-12 20:21:17.428761 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-06-12 20:21:17.464400 | 2026-06-12 20:21:17.464686 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-06-12 20:21:17.498753 | 2026-06-12 20:21:17.498986 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-06-12 20:21:17.515668 | controller | skipping: Conditional result was False 2026-06-12 20:21:17.524988 | 2026-06-12 20:21:17.525125 | LOOP [fetch-subunit-output : Generate subunit file] 2026-06-12 20:21:17.550980 | 2026-06-12 20:21:17.551211 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-06-12 20:21:17.577582 | controller | skipping: Conditional result was False 2026-06-12 20:21:17.585808 | 2026-06-12 20:21:17.585939 | TASK [fetch-subunit-output : Remove the temporary file] 2026-06-12 20:21:17.603091 | controller | skipping: Conditional result was False 2026-06-12 20:21:17.611991 | 2026-06-12 20:21:17.612120 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-06-12 20:21:17.629790 | controller | skipping: Conditional result was False 2026-06-12 20:21:17.643136 | 2026-06-12 20:21:17.643261 | PLAY RECAP 2026-06-12 20:21:17.643342 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-06-12 20:21:17.643386 | 2026-06-12 20:21:17.752144 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-12 20:21:17.753355 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-06-12 20:21:18.326221 | 2026-06-12 20:21:18.326328 | PLAY [all] 2026-06-12 20:21:18.335667 | 2026-06-12 20:21:18.335752 | TASK [include_role : fetch-output] 2026-06-12 20:21:18.372738 | controller | ok 2026-06-12 20:21:18.386809 | 2026-06-12 20:21:18.386901 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-12 20:21:18.452024 | controller | skipping: Conditional result was False 2026-06-12 20:21:18.461029 | 2026-06-12 20:21:18.461171 | TASK [fetch-output : Set log path for single node] 2026-06-12 20:21:18.501792 | controller | ok 2026-06-12 20:21:18.506553 | 2026-06-12 20:21:18.506639 | LOOP [fetch-output : Ensure local output dirs] 2026-06-12 20:21:18.952935 | controller -> localhost | changed: "/var/lib/zuul/builds/5939af7d8082455b8c6034cb0a200c51/work/logs" 2026-06-12 20:21:19.196014 | controller -> localhost | changed: "/var/lib/zuul/builds/5939af7d8082455b8c6034cb0a200c51/work/artifacts" 2026-06-12 20:21:19.471647 | controller -> localhost | changed: "/var/lib/zuul/builds/5939af7d8082455b8c6034cb0a200c51/work/docs" 2026-06-12 20:21:19.486693 | 2026-06-12 20:21:19.486888 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-12 20:21:20.233679 | controller | changed: .d..t...... ./ 2026-06-12 20:21:20.234101 | controller | changed: All items complete 2026-06-12 20:21:20.234167 | 2026-06-12 20:21:20.794071 | controller | changed: .d..t...... ./ 2026-06-12 20:21:21.350272 | controller | changed: .d..t...... ./ 2026-06-12 20:21:21.377831 | 2026-06-12 20:21:21.378028 | TASK [include_role : fetch-output-openshift] 2026-06-12 20:21:21.404598 | controller | skipping: Conditional result was False 2026-06-12 20:21:21.415968 | 2026-06-12 20:21:21.416179 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-12 20:21:21.865674 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.011824 2026-06-12 20:21:22.117505 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006421 2026-06-12 20:21:22.133630 | 2026-06-12 20:21:22.133999 | PLAY [all] 2026-06-12 20:21:22.144078 | 2026-06-12 20:21:22.144208 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-12 20:21:22.615255 | controller | changed 2026-06-12 20:21:22.624225 | 2026-06-12 20:21:22.624322 | PLAY RECAP 2026-06-12 20:21:22.624401 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-12 20:21:22.624504 | 2026-06-12 20:21:22.745398 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-06-12 20:21:22.746532 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2026-06-12 20:21:23.385739 | 2026-06-12 20:21:23.385855 | PLAY [localhost] 2026-06-12 20:21:23.395729 | 2026-06-12 20:21:23.395802 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-12 20:21:23.852078 | localhost | changed 2026-06-12 20:21:23.870937 | 2026-06-12 20:21:23.871255 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-12 20:21:23.913882 | localhost | ok 2026-06-12 20:21:23.926327 | 2026-06-12 20:21:23.926492 | TASK [add-fileserver : Create SSH private key tempfile] 2026-06-12 20:21:24.364509 | localhost | changed 2026-06-12 20:21:24.374286 | 2026-06-12 20:21:24.374463 | TASK [add-fileserver : Create SSH private key from secret] 2026-06-12 20:21:25.162093 | localhost | changed 2026-06-12 20:21:25.166337 | 2026-06-12 20:21:25.166400 | TASK [add-fileserver : Add fileserver ssh key] 2026-06-12 20:21:25.642344 | localhost | Identity added: /var/lib/zuul/builds/5939af7d8082455b8c6034cb0a200c51/work/tmp/ansible.e7p74o3o (/var/lib/zuul/builds/5939af7d8082455b8c6034cb0a200c51/work/tmp/ansible.e7p74o3o) 2026-06-12 20:21:25.642692 | localhost | ok: Runtime: 0:00:00.021338 2026-06-12 20:21:25.649695 | 2026-06-12 20:21:25.649822 | TASK [add-fileserver : Remove SSH private key from disk] 2026-06-12 20:21:25.975397 | localhost | ok: Runtime: 0:00:00.004610 2026-06-12 20:21:25.983182 | 2026-06-12 20:21:25.983321 | TASK [add-fileserver : Add fileserver to inventory] 2026-06-12 20:21:26.056898 | localhost | changed 2026-06-12 20:21:26.065760 | 2026-06-12 20:21:26.065886 | TASK [add-fileserver : Add fileserver server to known hosts] 2026-06-12 20:21:26.542682 | localhost | changed 2026-06-12 20:21:26.553332 | 2026-06-12 20:21:26.553478 | PLAY [localhost] 2026-06-12 20:21:26.561817 | 2026-06-12 20:21:26.561932 | TASK [Generate bulk log download script] 2026-06-12 20:21:26.594779 | localhost | ok 2026-06-12 20:21:26.616005 | 2026-06-12 20:21:26.616136 | TASK [local-log-download : Check API endpoint is defined] 2026-06-12 20:21:26.667342 | localhost | ok: All assertions passed 2026-06-12 20:21:26.674834 | 2026-06-12 20:21:26.674984 | TASK [local-log-download : Create download script] 2026-06-12 20:21:27.196050 | localhost -> localhost | changed 2026-06-12 20:21:27.249564 | 2026-06-12 20:21:27.249713 | TASK [Register quick-download link] 2026-06-12 20:21:27.278285 | localhost | ok 2026-06-12 20:21:27.280807 | 2026-06-12 20:21:27.280878 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com] 2026-06-12 20:21:27.287159 | 2026-06-12 20:21:27.287237 | TASK [Set zuul-log-path fact] 2026-06-12 20:21:27.312914 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-12 20:21:27.321505 | 2026-06-12 20:21:27.321601 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-12 20:21:27.358507 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-12 20:21:27.361945 | 2026-06-12 20:21:27.362027 | TASK [upload-logs : Create log directories] 2026-06-12 20:21:28.279714 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed 2026-06-12 20:21:28.283530 | 2026-06-12 20:21:28.283661 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-12 20:21:28.645747 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.009195 2026-06-12 20:21:28.651834 | 2026-06-12 20:21:28.651938 | TASK [upload-logs : Upload logs to log server] 2026-06-12 20:21:29.899410 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | Output suppressed because no_log was given 2026-06-12 20:21:29.901000 | 2026-06-12 20:21:29.901064 | LOOP [upload-logs : Compress console log and json output]