2026-06-11 10:51:04.360825 | Job console starting 2026-06-11 10:51:04.480450 | Updating git repos 2026-06-11 10:51:04.513721 | Cloning repos into workspace 2026-06-11 10:51:05.137423 | Restoring repo states 2026-06-11 10:51:05.147497 | Merging changes 2026-06-11 10:51:06.358237 | Checking out repos 2026-06-11 10:51:06.470887 | Preparing playbooks 2026-06-11 10:51:10.195763 | Running Ansible setup 2026-06-11 10:51:14.046555 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-06-11 10:51:14.642836 | 2026-06-11 10:51:14.642946 | PLAY [localhost] 2026-06-11 10:51:14.650667 | 2026-06-11 10:51:14.650734 | TASK [Gathering Facts] 2026-06-11 10:51:15.581570 | localhost | ok 2026-06-11 10:51:15.594401 | 2026-06-11 10:51:15.594568 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-11 10:51:16.059701 | localhost -> localhost | changed 2026-06-11 10:51:16.068541 | 2026-06-11 10:51:16.068707 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-11 10:51:17.059535 | localhost -> localhost | changed 2026-06-11 10:51:17.073047 | 2026-06-11 10:51:17.073183 | TASK [Setup log path fact] 2026-06-11 10:51:17.097028 | localhost | ok 2026-06-11 10:51:17.116465 | 2026-06-11 10:51:17.116630 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-11 10:51:17.156119 | localhost | ok 2026-06-11 10:51:17.163172 | 2026-06-11 10:51:17.163257 | TASK [emit-job-header : Print job information] 2026-06-11 10:51:17.213467 | # Job Information 2026-06-11 10:51:17.213717 | Ansible Version: 2.16.18 2026-06-11 10:51:17.213773 | Job: cifmw-molecule-ci_dcn_site 2026-06-11 10:51:17.213814 | Pipeline: github-check 2026-06-11 10:51:17.213852 | Executor: 38.102.83.40 2026-06-11 10:51:17.213888 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3963 2026-06-11 10:51:17.213930 | Log URL (when completed): https://gateway-cloud-softwarefactory.apps.ocp.cloud.ci.centos.org/logs/1db/rdoproject.org/1db52962e1ab468c9181ff09fb8da3e4/ 2026-06-11 10:51:17.213969 | Event ID: 2ea8c690-6583-11f1-96f0-934a86b6f397 2026-06-11 10:51:17.219546 | 2026-06-11 10:51:17.219710 | LOOP [emit-job-header : Print node information] 2026-06-11 10:51:17.320396 | localhost | ok: 2026-06-11 10:51:17.320875 | localhost | # Node Information 2026-06-11 10:51:17.320957 | localhost | Inventory Hostname: controller 2026-06-11 10:51:17.321024 | localhost | Hostname: np0000070629 2026-06-11 10:51:17.321084 | localhost | Username: zuul 2026-06-11 10:51:17.321146 | localhost | Distro: CentOS 9 2026-06-11 10:51:17.321199 | localhost | Provider: vexxhost-nodepool-tripleo 2026-06-11 10:51:17.321253 | localhost | Region: RegionOne 2026-06-11 10:51:17.321306 | localhost | Label: cloud-centos-9-stream-tripleo 2026-06-11 10:51:17.321354 | localhost | Product Name: OpenStack Nova 2026-06-11 10:51:17.321404 | localhost | Interface IP: 38.102.83.120 2026-06-11 10:51:17.336516 | 2026-06-11 10:51:17.336699 | PLAY [all] 2026-06-11 10:51:17.347741 | 2026-06-11 10:51:17.347866 | TASK [Gather network facts] 2026-06-11 10:51:18.142907 | controller | ok 2026-06-11 10:51:18.154992 | 2026-06-11 10:51:18.155113 | TASK [include_role : start-zuul-console] 2026-06-11 10:51:18.179601 | controller | ok 2026-06-11 10:51:18.198406 | 2026-06-11 10:51:18.198560 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-06-11 10:51:18.646456 | controller | ok 2026-06-11 10:51:18.659930 | 2026-06-11 10:51:18.660083 | TASK [include_role : add-build-sshkey] 2026-06-11 10:51:18.695833 | controller | ok 2026-06-11 10:51:18.719484 | 2026-06-11 10:51:18.719670 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-11 10:51:19.007964 | controller -> localhost | ok 2026-06-11 10:51:19.017277 | 2026-06-11 10:51:19.017409 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-11 10:51:19.051637 | controller | ok 2026-06-11 10:51:19.081773 | controller | included: /var/lib/zuul/builds/1db52962e1ab468c9181ff09fb8da3e4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-11 10:51:19.088657 | 2026-06-11 10:51:19.088738 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-11 10:51:19.758311 | controller -> localhost | Generating public/private rsa key pair. 2026-06-11 10:51:19.758674 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/1db52962e1ab468c9181ff09fb8da3e4/work/1db52962e1ab468c9181ff09fb8da3e4_id_rsa 2026-06-11 10:51:19.758739 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/1db52962e1ab468c9181ff09fb8da3e4/work/1db52962e1ab468c9181ff09fb8da3e4_id_rsa.pub 2026-06-11 10:51:19.758787 | controller -> localhost | The key fingerprint is: 2026-06-11 10:51:19.758831 | controller -> localhost | SHA256:E+6U+iztpXH5g35SGsWfqtWIqOSO+cI8ZbMhC3xukzA zuul-build-sshkey 2026-06-11 10:51:19.758873 | controller -> localhost | The key's randomart image is: 2026-06-11 10:51:19.758913 | controller -> localhost | +---[RSA 3072]----+ 2026-06-11 10:51:19.758952 | controller -> localhost | | | 2026-06-11 10:51:19.758992 | controller -> localhost | | | 2026-06-11 10:51:19.759032 | controller -> localhost | | . . | 2026-06-11 10:51:19.759071 | controller -> localhost | | . o o | 2026-06-11 10:51:19.759141 | controller -> localhost | | . S . . . | 2026-06-11 10:51:19.759186 | controller -> localhost | | E o =+ o.o.oo | 2026-06-11 10:51:19.759225 | controller -> localhost | | O *o=+ ==o.. | 2026-06-11 10:51:19.759266 | controller -> localhost | | @=o+.=+o+ | 2026-06-11 10:51:19.759304 | controller -> localhost | | .o*=o=.o+.. | 2026-06-11 10:51:19.759343 | controller -> localhost | +----[SHA256]-----+ 2026-06-11 10:51:19.759430 | controller -> localhost | ok: Runtime: 0:00:00.174606 2026-06-11 10:51:19.767346 | 2026-06-11 10:51:19.767471 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-11 10:51:19.801560 | controller | ok 2026-06-11 10:51:19.818302 | controller | included: /var/lib/zuul/builds/1db52962e1ab468c9181ff09fb8da3e4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-11 10:51:19.832150 | 2026-06-11 10:51:19.832227 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-11 10:51:19.857173 | controller | skipping: Conditional result was False 2026-06-11 10:51:19.863024 | 2026-06-11 10:51:19.863104 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-11 10:51:20.320130 | controller | changed 2026-06-11 10:51:20.327849 | 2026-06-11 10:51:20.327981 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-11 10:51:20.557466 | controller | ok 2026-06-11 10:51:20.569618 | 2026-06-11 10:51:20.569831 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-11 10:51:23.493670 | controller | changed 2026-06-11 10:51:23.501361 | 2026-06-11 10:51:23.501488 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-11 10:51:25.146773 | controller | changed 2026-06-11 10:51:25.154656 | 2026-06-11 10:51:25.154784 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-11 10:51:25.181514 | controller | skipping: Conditional result was False 2026-06-11 10:51:25.190618 | 2026-06-11 10:51:25.190758 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-11 10:51:25.632246 | controller -> localhost | changed 2026-06-11 10:51:25.649066 | 2026-06-11 10:51:25.649218 | TASK [add-build-sshkey : Add back temp key] 2026-06-11 10:51:26.006348 | controller -> localhost | Identity added: /var/lib/zuul/builds/1db52962e1ab468c9181ff09fb8da3e4/work/1db52962e1ab468c9181ff09fb8da3e4_id_rsa (zuul-build-sshkey) 2026-06-11 10:51:26.006708 | controller -> localhost | ok: Runtime: 0:00:00.017146 2026-06-11 10:51:26.014072 | 2026-06-11 10:51:26.014197 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-11 10:51:26.362684 | controller | ok 2026-06-11 10:51:26.369402 | 2026-06-11 10:51:26.369607 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-11 10:51:26.396124 | controller | skipping: Conditional result was False 2026-06-11 10:51:26.424356 | 2026-06-11 10:51:26.424650 | TASK [include_role : validate-host] 2026-06-11 10:51:26.459222 | controller | ok 2026-06-11 10:51:26.478510 | 2026-06-11 10:51:26.478605 | TASK [validate-host : Define zuul_info_dir fact] 2026-06-11 10:51:26.517285 | controller | ok 2026-06-11 10:51:26.521875 | 2026-06-11 10:51:26.521964 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-06-11 10:51:26.840004 | controller -> localhost | ok 2026-06-11 10:51:26.845013 | 2026-06-11 10:51:26.845082 | TASK [validate-host : Collect information about the host] 2026-06-11 10:51:27.588264 | controller | ok 2026-06-11 10:51:27.603865 | 2026-06-11 10:51:27.604012 | TASK [validate-host : Sanitize hostname] 2026-06-11 10:51:27.676863 | controller | ok 2026-06-11 10:51:27.684688 | 2026-06-11 10:51:27.684828 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-06-11 10:51:28.217878 | controller -> localhost | changed 2026-06-11 10:51:28.224255 | 2026-06-11 10:51:28.224338 | TASK [validate-host : Collect information about zuul worker] 2026-06-11 10:51:29.255883 | controller | ok 2026-06-11 10:51:29.263217 | 2026-06-11 10:51:29.263351 | TASK [validate-host : Write out all zuul information for each host] 2026-06-11 10:51:29.792717 | controller -> localhost | changed 2026-06-11 10:51:29.807238 | 2026-06-11 10:51:29.807361 | TASK [include_role : prepare-workspace-openshift] 2026-06-11 10:51:29.825004 | controller | skipping: Conditional result was False 2026-06-11 10:51:29.832441 | 2026-06-11 10:51:29.832568 | TASK [include_role : remove-zuul-sshkey] 2026-06-11 10:51:29.849010 | controller | skipping: Conditional result was False 2026-06-11 10:51:29.857272 | 2026-06-11 10:51:29.857410 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-11 10:51:31.723194 | controller | ok: "logs" 2026-06-11 10:51:31.723666 | controller | ok: All items complete 2026-06-11 10:51:31.723734 | 2026-06-11 10:51:32.417616 | controller | ok: "artifacts" 2026-06-11 10:51:32.875026 | controller | ok: "docs" 2026-06-11 10:51:32.885404 | 2026-06-11 10:51:32.885666 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-11 10:51:33.386677 | controller | changed: "logs" 2026-06-11 10:51:33.817054 | controller | changed: "artifacts" 2026-06-11 10:51:34.069322 | controller | changed: "docs" 2026-06-11 10:51:34.083675 | 2026-06-11 10:51:34.083841 | PLAY RECAP 2026-06-11 10:51:34.083896 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2026-06-11 10:51:34.083924 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-11 10:51:34.083943 | 2026-06-11 10:51:34.254881 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-06-11 10:51:34.257165 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-06-11 10:51:34.877988 | 2026-06-11 10:51:34.878117 | PLAY [localhost] 2026-06-11 10:51:34.887131 | 2026-06-11 10:51:34.887201 | TASK [configure-ssh-config-fips : Ensure ssh config directory] 2026-06-11 10:51:35.361936 | localhost | changed 2026-06-11 10:51:35.370346 | 2026-06-11 10:51:35.370627 | TASK [configure-ssh-config-fips : Setup ssh config] 2026-06-11 10:51:36.123969 | localhost | changed 2026-06-11 10:51:36.135866 | 2026-06-11 10:51:36.135975 | PLAY [all] 2026-06-11 10:51:36.146351 | 2026-06-11 10:51:36.146476 | TASK [include_role : prepare-workspace] 2026-06-11 10:51:36.179762 | controller | ok 2026-06-11 10:51:36.208849 | 2026-06-11 10:51:36.209009 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-11 10:51:36.647484 | controller | ok 2026-06-11 10:51:36.658669 | 2026-06-11 10:51:36.658816 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-11 10:51:38.140160 | controller | Output suppressed because no_log was given 2026-06-11 10:51:38.148940 | 2026-06-11 10:51:38.149022 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-11 10:51:38.377824 | controller | changed: "logs" 2026-06-11 10:51:38.575879 | controller | changed: "artifacts" 2026-06-11 10:51:38.795883 | controller | changed: "docs" 2026-06-11 10:51:38.808387 | 2026-06-11 10:51:38.808607 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-11 10:51:39.062134 | controller | changed: "logs" 2026-06-11 10:51:39.062639 | controller | changed: All items complete 2026-06-11 10:51:39.062711 | 2026-06-11 10:51:39.260713 | controller | changed: "artifacts" 2026-06-11 10:51:39.450491 | controller | changed: "docs" 2026-06-11 10:51:39.469131 | 2026-06-11 10:51:39.469267 | TASK [Check if worker can sudo] 2026-06-11 10:51:40.013695 | controller | ok: Runtime: 0:00:00.032531 2026-06-11 10:51:40.022304 | 2026-06-11 10:51:40.022437 | TASK [configure-mirrors : Gather needed facts] 2026-06-11 10:51:40.099010 | controller | skipping: Conditional result was False 2026-06-11 10:51:40.110170 | 2026-06-11 10:51:40.110312 | TASK [configure-mirrors : Set up infrastructure mirrors] 2026-06-11 10:51:40.196172 | controller | ok 2026-06-11 10:51:40.212098 | controller | included: /var/lib/zuul/builds/1db52962e1ab468c9181ff09fb8da3e4/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml 2026-06-11 10:51:40.221323 | 2026-06-11 10:51:40.221456 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir] 2026-06-11 10:51:40.527695 | controller | ok 2026-06-11 10:51:40.536526 | 2026-06-11 10:51:40.536690 | LOOP [configure-mirrors : Include OS-specific variables] 2026-06-11 10:51:40.623752 | controller | ok: "/var/lib/zuul/builds/1db52962e1ab468c9181ff09fb8da3e4/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml" 2026-06-11 10:51:40.635187 | 2026-06-11 10:51:40.635325 | TASK [configure-mirrors : Install /etc/pip.conf configuration] 2026-06-11 10:51:41.490325 | controller | changed 2026-06-11 10:51:41.500643 | 2026-06-11 10:51:41.500775 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors] 2026-06-11 10:51:41.664464 | controller | ok: "/var/lib/zuul/builds/1db52962e1ab468c9181ff09fb8da3e4/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml" 2026-06-11 10:51:41.664693 | controller | ok: All items complete 2026-06-11 10:51:41.664728 | 2026-06-11 10:51:41.735378 | controller | included: /var/lib/zuul/builds/1db52962e1ab468c9181ff09fb8da3e4/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml 2026-06-11 10:51:41.742598 | 2026-06-11 10:51:41.742686 | LOOP [configure-mirrors : Install CentOS 9 repository files] 2026-06-11 10:51:42.523104 | controller | changed: "etc/yum.repos.d/centos.repo" 2026-06-11 10:51:43.322284 | controller | changed: "etc/yum.repos.d/centos-addons.repo" 2026-06-11 10:51:43.333934 | 2026-06-11 10:51:43.334132 | TASK [configure-mirrors : Disable deltrarpm] 2026-06-11 10:51:43.794177 | controller | changed: section and option added 2026-06-11 10:51:43.816910 | 2026-06-11 10:51:43.817064 | LOOP [configure-mirrors : Update yum/dnf cache] 2026-06-11 10:51:45.253705 | controller | 29 files removed 2026-06-11 10:51:45.254111 | controller | ok: Item: dnf clean all Runtime: 0:00:01.109885 2026-06-11 10:51:45.254188 | controller | changed: All items complete 2026-06-11 10:51:45.254234 | 2026-06-11 10:51:58.966240 | 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-11 10:51:58.966417 | controller | DNF version: 4.14.0 2026-06-11 10:51:58.966473 | controller | cachedir: /var/cache/dnf 2026-06-11 10:51:58.966518 | controller | Making cache files for all metadata files. 2026-06-11 10:51:58.966560 | controller | baseos: has expired and will be refreshed. 2026-06-11 10:51:58.966665 | controller | appstream: has expired and will be refreshed. 2026-06-11 10:51:58.966709 | controller | crb: has expired and will be refreshed. 2026-06-11 10:51:58.966748 | controller | extras-common: has expired and will be refreshed. 2026-06-11 10:51:58.966786 | controller | repo: downloading from remote: baseos 2026-06-11 10:51:58.966824 | controller | CentOS Stream 9 - BaseOS 16 MB/s | 8.9 MB 00:00 2026-06-11 10:51:58.966861 | controller | baseos: using metadata from Tue 09 Jun 2026 05:37:52 PM EDT. 2026-06-11 10:51:58.966900 | controller | repo: downloading from remote: appstream 2026-06-11 10:51:58.966937 | controller | CentOS Stream 9 - AppStream 54 MB/s | 28 MB 00:00 2026-06-11 10:51:58.966974 | controller | appstream: using metadata from Tue 09 Jun 2026 05:41:28 PM EDT. 2026-06-11 10:51:58.967011 | controller | repo: downloading from remote: crb 2026-06-11 10:51:58.967049 | controller | CentOS Stream 9 - CRB 6.7 MB/s | 8.3 MB 00:01 2026-06-11 10:51:58.967087 | controller | crb: using metadata from Tue 09 Jun 2026 05:46:02 PM EDT. 2026-06-11 10:51:58.967124 | controller | repo: downloading from remote: extras-common 2026-06-11 10:51:58.967162 | controller | CentOS Stream 9 - Extras packages 1.8 MB/s | 21 kB 00:00 2026-06-11 10:51:58.967200 | controller | extras-common: using metadata from Wed 10 Jun 2026 10:05:57 AM EDT. 2026-06-11 10:51:58.967236 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)' 2026-06-11 10:51:58.967274 | controller | Completion plugin: Generating completion cache... 2026-06-11 10:51:58.967311 | controller | Metadata cache created. 2026-06-11 10:51:58.967369 | controller | ok: Item: dnf makecache -v Runtime: 0:00:13.441083 2026-06-11 10:51:58.972214 | 2026-06-11 10:51:58.972396 | PLAY RECAP 2026-06-11 10:51:58.972517 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-11 10:51:58.972647 | localhost | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-11 10:51:58.972718 | 2026-06-11 10:51:59.122509 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-06-11 10:51:59.124901 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-06-11 10:51:59.735716 | 2026-06-11 10:51:59.735859 | PLAY [all] 2026-06-11 10:51:59.746622 | 2026-06-11 10:51:59.746704 | TASK [Install binary dependencies] 2026-06-11 10:51:59.816164 | controller | ok 2026-06-11 10:51:59.838872 | 2026-06-11 10:51:59.838996 | TASK [bindep : Include find tasks] 2026-06-11 10:51:59.891119 | controller | ok 2026-06-11 10:51:59.903854 | controller | included: /var/lib/zuul/builds/1db52962e1ab468c9181ff09fb8da3e4/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-06-11 10:51:59.912919 | 2026-06-11 10:51:59.913012 | TASK [bindep : Look for bindep.txt] 2026-06-11 10:52:00.291062 | controller | ok 2026-06-11 10:52:00.300428 | 2026-06-11 10:52:00.300567 | TASK [bindep : Define bindep_file fact] 2026-06-11 10:52:00.344533 | controller | ok 2026-06-11 10:52:00.353550 | 2026-06-11 10:52:00.353721 | TASK [bindep : Look for other-requirements.txt] 2026-06-11 10:52:00.380504 | controller | skipping: Conditional result was False 2026-06-11 10:52:00.389791 | 2026-06-11 10:52:00.389938 | TASK [bindep : Define bindep_file fact] 2026-06-11 10:52:00.426468 | controller | skipping: Conditional result was False 2026-06-11 10:52:00.435249 | 2026-06-11 10:52:00.435388 | TASK [bindep : Look for bindep fallback file] 2026-06-11 10:52:00.482200 | controller | skipping: Conditional result was False 2026-06-11 10:52:00.492893 | 2026-06-11 10:52:00.493043 | TASK [bindep : Define bindep_file fact] 2026-06-11 10:52:00.529151 | controller | skipping: Conditional result was False 2026-06-11 10:52:00.538497 | 2026-06-11 10:52:00.538697 | TASK [bindep : Include bindep tasks] 2026-06-11 10:52:00.583275 | controller | ok 2026-06-11 10:52:00.594888 | controller | included: /var/lib/zuul/builds/1db52962e1ab468c9181ff09fb8da3e4/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml 2026-06-11 10:52:00.612637 | 2026-06-11 10:52:00.612847 | TASK [bindep : Look for bindep command] 2026-06-11 10:52:00.648291 | controller | skipping: Conditional result was False 2026-06-11 10:52:00.655499 | 2026-06-11 10:52:00.655626 | TASK [bindep : Check for system bindep] 2026-06-11 10:52:01.191166 | controller | ok: Runtime: 0:00:00.003414 2026-06-11 10:52:01.199491 | 2026-06-11 10:52:01.199662 | TASK [bindep : Define bindep_command fact] 2026-06-11 10:52:01.236099 | controller | skipping: Conditional result was False 2026-06-11 10:52:01.244527 | 2026-06-11 10:52:01.244689 | TASK [bindep : Include install tasks] 2026-06-11 10:52:01.291949 | controller | ok 2026-06-11 10:52:01.305764 | controller | included: /var/lib/zuul/builds/1db52962e1ab468c9181ff09fb8da3e4/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml 2026-06-11 10:52:01.320846 | 2026-06-11 10:52:01.320929 | TASK [bindep : Create temp dir for bindep] 2026-06-11 10:52:01.711648 | controller | changed 2026-06-11 10:52:01.720192 | 2026-06-11 10:52:01.720331 | TASK [Ensure we have pip dependencies] 2026-06-11 10:52:01.745382 | controller | ok 2026-06-11 10:52:01.784423 | 2026-06-11 10:52:01.784526 | TASK [ensure-pip : Check if pip is installed] 2026-06-11 06:52:02.081355 | controller | /usr/bin/pip3 2026-06-11 06:52:02.108883 | controller | /usr/bin/python3: No module named wheel 2026-06-11 10:52:02.321032 | controller | ok: Runtime: 0:00:00.039348 2026-06-11 10:52:02.329803 | 2026-06-11 10:52:02.329945 | LOOP [ensure-pip : Install pip from packages] 2026-06-11 10:52:02.362152 | controller | ok: "/var/lib/zuul/builds/1db52962e1ab468c9181ff09fb8da3e4/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml" 2026-06-11 10:52:02.384816 | controller | included: /var/lib/zuul/builds/1db52962e1ab468c9181ff09fb8da3e4/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml 2026-06-11 10:52:02.438858 | 2026-06-11 10:52:02.439008 | TASK [ensure-pip : Install Python 3 pip] 2026-06-11 10:52:03.880925 | controller | ok: Nothing to do 2026-06-11 10:52:03.889322 | 2026-06-11 10:52:03.889452 | TASK [ensure-pip : Install Python3-wheel on older CentOS] 2026-06-11 10:52:05.342005 | controller | changed 2026-06-11 10:52:05.350647 | 2026-06-11 10:52:05.350776 | TASK [ensure-pip : Check for EPEL repository] 2026-06-11 10:52:05.417293 | controller | skipping: Conditional result was False 2026-06-11 10:52:05.426980 | 2026-06-11 10:52:05.427122 | TASK [ensure-pip : Fail with instructions when EPEL is not installed] 2026-06-11 10:52:05.484975 | controller | skipping: Conditional result was False 2026-06-11 10:52:05.496701 | 2026-06-11 10:52:05.496835 | TASK [ensure-pip : Install Python 2 pip] 2026-06-11 10:52:05.544806 | controller | skipping: Conditional result was False 2026-06-11 10:52:05.555168 | 2026-06-11 10:52:05.555313 | TASK [ensure-pip : Ensure setuptools] 2026-06-11 10:52:05.582036 | controller | skipping: Conditional result was False 2026-06-11 10:52:05.590918 | 2026-06-11 10:52:05.591052 | TASK [ensure-pip : Check for ensurepip module] 2026-06-11 10:52:06.127804 | controller | skipping: Conditional result was False 2026-06-11 10:52:06.138506 | 2026-06-11 10:52:06.138685 | TASK [ensure-pip : Ensure python3-venv] 2026-06-11 10:52:06.164976 | controller | skipping: Conditional result was False 2026-06-11 10:52:06.174408 | 2026-06-11 10:52:06.174544 | TASK [ensure-pip : Install pip from source] 2026-06-11 10:52:06.200838 | controller | skipping: Conditional result was False 2026-06-11 10:52:06.210357 | 2026-06-11 10:52:06.210495 | TASK [ensure-pip : Probe for venv python full path] 2026-06-11 06:52:06.508188 | controller | /usr/bin/python3 2026-06-11 10:52:06.754319 | controller | ok: Runtime: 0:00:00.002863 2026-06-11 10:52:06.762479 | 2026-06-11 10:52:06.762646 | TASK [ensure-pip : Set host default] 2026-06-11 10:52:06.836569 | controller | ok 2026-06-11 10:52:06.844199 | 2026-06-11 10:52:06.844325 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2026-06-11 10:52:06.921113 | controller | ok 2026-06-11 10:52:06.940497 | 2026-06-11 10:52:06.940747 | TASK [bindep : Install bindep into temporary venv] 2026-06-11 10:52:11.817745 | controller | changed 2026-06-11 10:52:11.831466 | 2026-06-11 10:52:11.831706 | TASK [bindep : Define bindep_command] 2026-06-11 10:52:11.897082 | controller | ok 2026-06-11 10:52:11.911163 | 2026-06-11 10:52:11.911310 | LOOP [bindep : Include package tasks] 2026-06-11 10:52:11.982792 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt" 2026-06-11 10:52:11.983045 | controller | ok: All items complete 2026-06-11 10:52:11.983099 | 2026-06-11 10:52:12.002560 | controller | included: /var/lib/zuul/builds/1db52962e1ab468c9181ff09fb8da3e4/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml 2026-06-11 10:52:12.023476 | 2026-06-11 10:52:12.023653 | TASK [bindep : Define bindep_run fact] 2026-06-11 10:52:12.067818 | controller | ok 2026-06-11 10:52:12.075626 | 2026-06-11 10:52:12.075810 | TASK [bindep : Get list of packages to install from bindep] 2026-06-11 06:52:13.203994 | controller | podman 2026-06-11 06:52:13.204152 | controller | python3-jmespath 2026-06-11 06:52:13.204165 | controller | python3-libvirt 2026-06-11 06:52:13.204176 | controller | python3-lxml 2026-06-11 06:52:13.204185 | controller | python3-netaddr 2026-06-11 10:52:13.701146 | controller | ok: Runtime: 0:00:00.899244 2026-06-11 10:52:13.713508 | 2026-06-11 10:52:13.713734 | TASK [bindep : Install distro packages from bindep] 2026-06-11 10:52:59.301689 | controller | changed 2026-06-11 10:52:59.310725 | 2026-06-11 10:52:59.310862 | TASK [bindep : Check that packages are installed] 2026-06-11 10:53:00.860615 | controller | ok: Runtime: 0:00:01.072166 2026-06-11 10:53:00.869662 | 2026-06-11 10:53:00.869852 | TASK [bindep : Fail if we cannot install all packages] 2026-06-11 10:53:00.908167 | controller | skipping: Conditional result was False 2026-06-11 10:53:00.927838 | 2026-06-11 10:53:00.927980 | TASK [Run test-setup role] 2026-06-11 10:53:00.955201 | controller | ok 2026-06-11 10:53:00.983718 | 2026-06-11 10:53:00.983810 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-06-11 10:53:01.716421 | controller | ok 2026-06-11 10:53:01.724522 | 2026-06-11 10:53:01.724687 | TASK [test-setup : Run tools/test-setup.sh] 2026-06-11 10:53:02.267144 | controller | skipping: Conditional result was False 2026-06-11 10:53:02.289568 | 2026-06-11 10:53:02.289767 | TASK [bindep : Remove bindep temp dir] 2026-06-11 10:53:02.708033 | controller | ok 2026-06-11 10:53:02.710518 | 2026-06-11 10:53:02.710656 | PLAY RECAP 2026-06-11 10:53:02.710750 | controller | ok: 23 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0 2026-06-11 10:53:02.710794 | 2026-06-11 10:53:02.860149 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-06-11 10:53:02.863178 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2026-06-11 10:53:03.479730 | 2026-06-11 10:53:03.479844 | PLAY [all] 2026-06-11 10:53:03.488950 | 2026-06-11 10:53:03.489030 | TASK [Abort when test_command variable is undefined] 2026-06-11 10:53:03.523551 | controller | skipping: Conditional result was False 2026-06-11 10:53:03.528013 | 2026-06-11 10:53:03.528082 | TASK [Convert test_command to list] 2026-06-11 10:53:03.583240 | controller | skipping: Conditional result was False 2026-06-11 10:53:03.589748 | 2026-06-11 10:53:03.589846 | TASK [Use test_command list] 2026-06-11 10:53:03.653251 | controller | ok 2026-06-11 10:53:03.664645 | 2026-06-11 10:53:03.664781 | LOOP [Run test_command] 2026-06-11 10:53:04.133199 | controller | no check to run 2026-06-11 10:53:04.133493 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.005092 2026-06-11 10:53:04.143453 | 2026-06-11 10:53:04.143657 | PLAY RECAP 2026-06-11 10:53:04.143744 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-11 10:53:04.143787 | 2026-06-11 10:53:04.268836 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2026-06-11 10:53:04.271631 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-11 10:53:04.833349 | 2026-06-11 10:53:04.833459 | PLAY [all] 2026-06-11 10:53:04.843465 | 2026-06-11 10:53:04.843535 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-06-11 10:53:05.238888 | controller | changed: non-zero return code 2026-06-11 10:53:05.247076 | 2026-06-11 10:53:05.247251 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-06-11 10:53:05.273966 | controller | skipping: Conditional result was False 2026-06-11 10:53:05.284121 | 2026-06-11 10:53:05.284254 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-06-11 10:53:05.319937 | 2026-06-11 10:53:05.320168 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-06-11 10:53:05.355252 | 2026-06-11 10:53:05.355510 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-06-11 10:53:05.381705 | controller | skipping: Conditional result was False 2026-06-11 10:53:05.390485 | 2026-06-11 10:53:05.390648 | LOOP [fetch-subunit-output : Generate subunit file] 2026-06-11 10:53:05.424975 | 2026-06-11 10:53:05.425206 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-06-11 10:53:05.451260 | controller | skipping: Conditional result was False 2026-06-11 10:53:05.459606 | 2026-06-11 10:53:05.459740 | TASK [fetch-subunit-output : Remove the temporary file] 2026-06-11 10:53:05.485486 | controller | skipping: Conditional result was False 2026-06-11 10:53:05.493978 | 2026-06-11 10:53:05.494105 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-06-11 10:53:05.520044 | controller | skipping: Conditional result was False 2026-06-11 10:53:05.532484 | 2026-06-11 10:53:05.532614 | PLAY RECAP 2026-06-11 10:53:05.532707 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-06-11 10:53:05.532750 | 2026-06-11 10:53:05.656675 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-11 10:53:05.659480 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-06-11 10:53:06.270306 | 2026-06-11 10:53:06.270418 | PLAY [all] 2026-06-11 10:53:06.279984 | 2026-06-11 10:53:06.280052 | TASK [include_role : fetch-output] 2026-06-11 10:53:06.318620 | controller | ok 2026-06-11 10:53:06.333234 | 2026-06-11 10:53:06.333313 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-11 10:53:06.408257 | controller | skipping: Conditional result was False 2026-06-11 10:53:06.416996 | 2026-06-11 10:53:06.417138 | TASK [fetch-output : Set log path for single node] 2026-06-11 10:53:06.472669 | controller | ok 2026-06-11 10:53:06.482285 | 2026-06-11 10:53:06.482525 | LOOP [fetch-output : Ensure local output dirs] 2026-06-11 10:53:06.970464 | controller -> localhost | changed: "/var/lib/zuul/builds/1db52962e1ab468c9181ff09fb8da3e4/work/logs" 2026-06-11 10:53:07.243732 | controller -> localhost | changed: "/var/lib/zuul/builds/1db52962e1ab468c9181ff09fb8da3e4/work/artifacts" 2026-06-11 10:53:07.523476 | controller -> localhost | changed: "/var/lib/zuul/builds/1db52962e1ab468c9181ff09fb8da3e4/work/docs" 2026-06-11 10:53:07.544289 | 2026-06-11 10:53:07.544512 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-11 10:53:08.296315 | controller | changed: .d..t...... ./ 2026-06-11 10:53:08.296832 | controller | changed: All items complete 2026-06-11 10:53:08.296898 | 2026-06-11 10:53:08.828283 | controller | changed: .d..t...... ./ 2026-06-11 10:53:09.359955 | controller | changed: .d..t...... ./ 2026-06-11 10:53:09.384712 | 2026-06-11 10:53:09.384930 | TASK [include_role : fetch-output-openshift] 2026-06-11 10:53:09.413394 | controller | skipping: Conditional result was False 2026-06-11 10:53:09.423159 | 2026-06-11 10:53:09.423322 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-11 10:53:09.954148 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.014183 2026-06-11 10:53:10.258407 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.014469 2026-06-11 10:53:10.282764 | 2026-06-11 10:53:10.282976 | PLAY [all] 2026-06-11 10:53:10.293333 | 2026-06-11 10:53:10.293471 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-11 10:53:10.773385 | controller | changed 2026-06-11 10:53:10.779605 | 2026-06-11 10:53:10.779670 | PLAY RECAP 2026-06-11 10:53:10.779723 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-11 10:53:10.779751 | 2026-06-11 10:53:10.926780 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-06-11 10:53:10.929221 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2026-06-11 10:53:11.584309 | 2026-06-11 10:53:11.584432 | PLAY [localhost] 2026-06-11 10:53:11.594743 | 2026-06-11 10:53:11.594819 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-11 10:53:12.040252 | localhost | changed 2026-06-11 10:53:12.066058 | 2026-06-11 10:53:12.066373 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-11 10:53:12.108532 | localhost | ok 2026-06-11 10:53:12.121036 | 2026-06-11 10:53:12.121167 | TASK [add-fileserver : Create SSH private key tempfile] 2026-06-11 10:53:12.580225 | localhost | changed 2026-06-11 10:53:12.590429 | 2026-06-11 10:53:12.590571 | TASK [add-fileserver : Create SSH private key from secret] 2026-06-11 10:53:13.425842 | localhost | changed 2026-06-11 10:53:13.434624 | 2026-06-11 10:53:13.434791 | TASK [add-fileserver : Add fileserver ssh key] 2026-06-11 10:53:13.951219 | localhost | Identity added: /var/lib/zuul/builds/1db52962e1ab468c9181ff09fb8da3e4/work/tmp/ansible.g43h42v8 (/var/lib/zuul/builds/1db52962e1ab468c9181ff09fb8da3e4/work/tmp/ansible.g43h42v8) 2026-06-11 10:53:13.951554 | localhost | ok: Runtime: 0:00:00.020905 2026-06-11 10:53:13.959205 | 2026-06-11 10:53:13.959356 | TASK [add-fileserver : Remove SSH private key from disk] 2026-06-11 10:53:14.305358 | localhost | ok: Runtime: 0:00:00.009833 2026-06-11 10:53:14.313445 | 2026-06-11 10:53:14.313607 | TASK [add-fileserver : Add fileserver to inventory] 2026-06-11 10:53:14.374311 | localhost | changed 2026-06-11 10:53:14.379132 | 2026-06-11 10:53:14.379201 | TASK [add-fileserver : Add fileserver server to known hosts] 2026-06-11 10:53:14.825044 | localhost | changed 2026-06-11 10:53:14.836181 | 2026-06-11 10:53:14.836309 | PLAY [localhost] 2026-06-11 10:53:14.845266 | 2026-06-11 10:53:14.845398 | TASK [Generate bulk log download script] 2026-06-11 10:53:14.869780 | localhost | ok 2026-06-11 10:53:14.891341 | 2026-06-11 10:53:14.891481 | TASK [local-log-download : Check API endpoint is defined] 2026-06-11 10:53:14.945799 | localhost | ok: All assertions passed 2026-06-11 10:53:14.954099 | 2026-06-11 10:53:14.954250 | TASK [local-log-download : Create download script] 2026-06-11 10:53:15.487358 | localhost -> localhost | changed 2026-06-11 10:53:15.542131 | 2026-06-11 10:53:15.542494 | TASK [Register quick-download link] 2026-06-11 10:53:15.571918 | localhost | ok 2026-06-11 10:53:15.574322 | 2026-06-11 10:53:15.574389 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com] 2026-06-11 10:53:15.580132 | 2026-06-11 10:53:15.580208 | TASK [Set zuul-log-path fact] 2026-06-11 10:53:15.595787 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-11 10:53:15.603979 | 2026-06-11 10:53:15.604068 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-11 10:53:15.641243 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-11 10:53:15.646685 | 2026-06-11 10:53:15.646849 | TASK [upload-logs : Create log directories] 2026-06-11 10:53:16.559679 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed 2026-06-11 10:53:16.563412 | 2026-06-11 10:53:16.563546 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-11 10:53:16.897406 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.006879 2026-06-11 10:53:16.903124 | 2026-06-11 10:53:16.903244 | TASK [upload-logs : Upload logs to log server] 2026-06-11 10:53:18.221627 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | Output suppressed because no_log was given 2026-06-11 10:53:18.227219 | 2026-06-11 10:53:18.227390 | LOOP [upload-logs : Compress console log and json output]