2026-06-12 14:15:56.509909 | Job console starting 2026-06-12 14:15:56.627080 | Updating git repos 2026-06-12 14:15:56.663095 | Cloning repos into workspace 2026-06-12 14:15:57.189659 | Restoring repo states 2026-06-12 14:15:57.200462 | Merging changes 2026-06-12 14:15:58.326834 | Checking out repos 2026-06-12 14:15:58.443653 | Preparing playbooks 2026-06-12 14:16:02.140841 | Running Ansible setup 2026-06-12 14:16:05.900294 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-06-12 14:16:06.492512 | 2026-06-12 14:16:06.492632 | PLAY [localhost] 2026-06-12 14:16:06.500003 | 2026-06-12 14:16:06.500068 | TASK [Gathering Facts] 2026-06-12 14:16:07.436860 | localhost | ok 2026-06-12 14:16:07.449617 | 2026-06-12 14:16:07.449859 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-12 14:16:07.860592 | localhost -> localhost | changed 2026-06-12 14:16:07.865301 | 2026-06-12 14:16:07.865387 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-12 14:16:08.874285 | localhost -> localhost | changed 2026-06-12 14:16:08.887244 | 2026-06-12 14:16:08.887429 | TASK [Setup log path fact] 2026-06-12 14:16:08.909961 | localhost | ok 2026-06-12 14:16:08.929833 | 2026-06-12 14:16:08.929964 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-12 14:16:08.962268 | localhost | ok 2026-06-12 14:16:08.974603 | 2026-06-12 14:16:08.974744 | TASK [emit-job-header : Print job information] 2026-06-12 14:16:09.024481 | # Job Information 2026-06-12 14:16:09.024628 | Ansible Version: 2.16.18 2026-06-12 14:16:09.024662 | Job: cifmw-molecule-ci_dcn_site 2026-06-12 14:16:09.024688 | Pipeline: github-check 2026-06-12 14:16:09.024712 | Executor: 38.102.83.51 2026-06-12 14:16:09.024735 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3963 2026-06-12 14:16:09.024764 | Log URL (when completed): https://gateway-cloud-softwarefactory.apps.ocp.cloud.ci.centos.org/logs/733/rdoproject.org/73346a6fdb12407aa98a6ca096ba800a/ 2026-06-12 14:16:09.024792 | Event ID: f7587f00-6668-11f1-80c9-094a88fb2275 2026-06-12 14:16:09.028558 | 2026-06-12 14:16:09.028643 | LOOP [emit-job-header : Print node information] 2026-06-12 14:16:09.122863 | localhost | ok: 2026-06-12 14:16:09.123129 | localhost | # Node Information 2026-06-12 14:16:09.123185 | localhost | Inventory Hostname: controller 2026-06-12 14:16:09.123232 | localhost | Hostname: np0000072001 2026-06-12 14:16:09.123272 | localhost | Username: zuul 2026-06-12 14:16:09.123314 | localhost | Distro: CentOS 9 2026-06-12 14:16:09.123384 | localhost | Provider: vexxhost-nodepool-tripleo 2026-06-12 14:16:09.123431 | localhost | Region: RegionOne 2026-06-12 14:16:09.123468 | localhost | Label: cloud-centos-9-stream-tripleo 2026-06-12 14:16:09.123505 | localhost | Product Name: OpenStack Nova 2026-06-12 14:16:09.123541 | localhost | Interface IP: 38.102.83.180 2026-06-12 14:16:09.134315 | 2026-06-12 14:16:09.134485 | PLAY [all] 2026-06-12 14:16:09.146537 | 2026-06-12 14:16:09.146663 | TASK [Gather network facts] 2026-06-12 14:16:09.711798 | controller | ok 2026-06-12 14:16:09.723593 | 2026-06-12 14:16:09.723719 | TASK [include_role : start-zuul-console] 2026-06-12 14:16:09.758953 | controller | ok 2026-06-12 14:16:09.777712 | 2026-06-12 14:16:09.777840 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-06-12 14:16:10.226424 | controller | ok 2026-06-12 14:16:10.239009 | 2026-06-12 14:16:10.239131 | TASK [include_role : add-build-sshkey] 2026-06-12 14:16:10.271690 | controller | ok 2026-06-12 14:16:10.293306 | 2026-06-12 14:16:10.293484 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-12 14:16:10.636044 | controller -> localhost | ok 2026-06-12 14:16:10.640983 | 2026-06-12 14:16:10.641053 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-12 14:16:10.672892 | controller | ok 2026-06-12 14:16:10.693168 | controller | included: /var/lib/zuul/builds/73346a6fdb12407aa98a6ca096ba800a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-12 14:16:10.699866 | 2026-06-12 14:16:10.699965 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-12 14:16:11.517743 | controller -> localhost | Generating public/private rsa key pair. 2026-06-12 14:16:11.517932 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/73346a6fdb12407aa98a6ca096ba800a/work/73346a6fdb12407aa98a6ca096ba800a_id_rsa 2026-06-12 14:16:11.517960 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/73346a6fdb12407aa98a6ca096ba800a/work/73346a6fdb12407aa98a6ca096ba800a_id_rsa.pub 2026-06-12 14:16:11.517981 | controller -> localhost | The key fingerprint is: 2026-06-12 14:16:11.518000 | controller -> localhost | SHA256:Tbj1KSX1iGEAJYm1AQUO3hOwvGxIkv6qUp8GQHnlfKQ zuul-build-sshkey 2026-06-12 14:16:11.518018 | controller -> localhost | The key's randomart image is: 2026-06-12 14:16:11.518036 | controller -> localhost | +---[RSA 3072]----+ 2026-06-12 14:16:11.518053 | controller -> localhost | | +.=B*=o.o . | 2026-06-12 14:16:11.518071 | controller -> localhost | | * *+.++ o + o | 2026-06-12 14:16:11.518088 | controller -> localhost | |+.= +E... = o . | 2026-06-12 14:16:11.518105 | controller -> localhost | |=o . .. = + . | 2026-06-12 14:16:11.518122 | controller -> localhost | |.o+ S o o | 2026-06-12 14:16:11.518138 | controller -> localhost | | .+ . | 2026-06-12 14:16:11.518154 | controller -> localhost | | . + . | 2026-06-12 14:16:11.518171 | controller -> localhost | |. . + | 2026-06-12 14:16:11.518188 | controller -> localhost | |+. . | 2026-06-12 14:16:11.518205 | controller -> localhost | +----[SHA256]-----+ 2026-06-12 14:16:11.518252 | controller -> localhost | ok: Runtime: 0:00:00.255352 2026-06-12 14:16:11.521788 | 2026-06-12 14:16:11.521846 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-12 14:16:11.550698 | controller | ok 2026-06-12 14:16:11.561344 | controller | included: /var/lib/zuul/builds/73346a6fdb12407aa98a6ca096ba800a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-12 14:16:11.570194 | 2026-06-12 14:16:11.570272 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-12 14:16:11.604327 | controller | skipping: Conditional result was False 2026-06-12 14:16:11.612570 | 2026-06-12 14:16:11.612704 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-12 14:16:12.065958 | controller | changed 2026-06-12 14:16:12.069457 | 2026-06-12 14:16:12.069519 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-12 14:16:12.312272 | controller | ok 2026-06-12 14:16:12.319729 | 2026-06-12 14:16:12.319876 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-12 14:16:13.181441 | controller | changed 2026-06-12 14:16:13.188838 | 2026-06-12 14:16:13.188968 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-12 14:16:13.992299 | controller | changed 2026-06-12 14:16:13.999836 | 2026-06-12 14:16:13.999963 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-12 14:16:14.037792 | controller | skipping: Conditional result was False 2026-06-12 14:16:14.046753 | 2026-06-12 14:16:14.046895 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-12 14:16:14.499437 | controller -> localhost | changed 2026-06-12 14:16:14.514492 | 2026-06-12 14:16:14.514630 | TASK [add-build-sshkey : Add back temp key] 2026-06-12 14:16:14.852429 | controller -> localhost | Identity added: /var/lib/zuul/builds/73346a6fdb12407aa98a6ca096ba800a/work/73346a6fdb12407aa98a6ca096ba800a_id_rsa (zuul-build-sshkey) 2026-06-12 14:16:14.852756 | controller -> localhost | ok: Runtime: 0:00:00.023077 2026-06-12 14:16:14.860658 | 2026-06-12 14:16:14.860786 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-12 14:16:15.258019 | controller | ok 2026-06-12 14:16:15.264937 | 2026-06-12 14:16:15.265166 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-12 14:16:15.302423 | controller | skipping: Conditional result was False 2026-06-12 14:16:15.316416 | 2026-06-12 14:16:15.316549 | TASK [include_role : validate-host] 2026-06-12 14:16:15.351819 | controller | ok 2026-06-12 14:16:15.382171 | 2026-06-12 14:16:15.382255 | TASK [validate-host : Define zuul_info_dir fact] 2026-06-12 14:16:15.433850 | controller | ok 2026-06-12 14:16:15.452789 | 2026-06-12 14:16:15.452926 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-06-12 14:16:15.767518 | controller -> localhost | ok 2026-06-12 14:16:15.776712 | 2026-06-12 14:16:15.776840 | TASK [validate-host : Collect information about the host] 2026-06-12 14:16:16.512725 | controller | ok 2026-06-12 14:16:16.531057 | 2026-06-12 14:16:16.531273 | TASK [validate-host : Sanitize hostname] 2026-06-12 14:16:16.604918 | controller | ok 2026-06-12 14:16:16.612074 | 2026-06-12 14:16:16.612236 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-06-12 14:16:17.116329 | controller -> localhost | changed 2026-06-12 14:16:17.122514 | 2026-06-12 14:16:17.122588 | TASK [validate-host : Collect information about zuul worker] 2026-06-12 14:16:17.610402 | controller | ok 2026-06-12 14:16:17.618044 | 2026-06-12 14:16:17.618185 | TASK [validate-host : Write out all zuul information for each host] 2026-06-12 14:16:18.253286 | controller -> localhost | changed 2026-06-12 14:16:18.267676 | 2026-06-12 14:16:18.267803 | TASK [include_role : prepare-workspace-openshift] 2026-06-12 14:16:18.303694 | controller | skipping: Conditional result was False 2026-06-12 14:16:18.311568 | 2026-06-12 14:16:18.311694 | TASK [include_role : remove-zuul-sshkey] 2026-06-12 14:16:18.337906 | controller | skipping: Conditional result was False 2026-06-12 14:16:18.346023 | 2026-06-12 14:16:18.346157 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-12 14:16:18.587196 | controller | ok: "logs" 2026-06-12 14:16:18.587904 | controller | ok: All items complete 2026-06-12 14:16:18.587982 | 2026-06-12 14:16:18.796001 | controller | ok: "artifacts" 2026-06-12 14:16:18.979192 | controller | ok: "docs" 2026-06-12 14:16:18.996297 | 2026-06-12 14:16:18.996607 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-12 14:16:19.311318 | controller | changed: "logs" 2026-06-12 14:16:19.518312 | controller | changed: "artifacts" 2026-06-12 14:16:19.711915 | controller | changed: "docs" 2026-06-12 14:16:19.725320 | 2026-06-12 14:16:19.725569 | PLAY RECAP 2026-06-12 14:16:19.725672 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2026-06-12 14:16:19.725728 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-12 14:16:19.725767 | 2026-06-12 14:16:19.901954 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-06-12 14:16:19.903720 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-06-12 14:16:20.589815 | 2026-06-12 14:16:20.589962 | PLAY [localhost] 2026-06-12 14:16:20.599050 | 2026-06-12 14:16:20.599119 | TASK [configure-ssh-config-fips : Ensure ssh config directory] 2026-06-12 14:16:21.069855 | localhost | changed 2026-06-12 14:16:21.082170 | 2026-06-12 14:16:21.082499 | TASK [configure-ssh-config-fips : Setup ssh config] 2026-06-12 14:16:21.892252 | localhost | changed 2026-06-12 14:16:21.913063 | 2026-06-12 14:16:21.913224 | PLAY [all] 2026-06-12 14:16:21.926555 | 2026-06-12 14:16:21.926684 | TASK [include_role : prepare-workspace] 2026-06-12 14:16:21.963183 | controller | ok 2026-06-12 14:16:21.992003 | 2026-06-12 14:16:21.992152 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-12 14:16:22.501673 | controller | ok 2026-06-12 14:16:22.512534 | 2026-06-12 14:16:22.512669 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-12 14:16:23.990596 | controller | Output suppressed because no_log was given 2026-06-12 14:16:24.006965 | 2026-06-12 14:16:24.007106 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-12 14:16:24.307500 | controller | changed: "logs" 2026-06-12 14:16:24.504459 | controller | changed: "artifacts" 2026-06-12 14:16:24.728514 | controller | changed: "docs" 2026-06-12 14:16:24.747641 | 2026-06-12 14:16:24.747838 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-12 14:16:25.025020 | controller | changed: "logs" 2026-06-12 14:16:25.025548 | controller | changed: All items complete 2026-06-12 14:16:25.025601 | 2026-06-12 14:16:25.256807 | controller | changed: "artifacts" 2026-06-12 14:16:25.464948 | controller | changed: "docs" 2026-06-12 14:16:25.494073 | 2026-06-12 14:16:25.494299 | TASK [Check if worker can sudo] 2026-06-12 14:16:26.038218 | controller | ok: Runtime: 0:00:00.037489 2026-06-12 14:16:26.046519 | 2026-06-12 14:16:26.046648 | TASK [configure-mirrors : Gather needed facts] 2026-06-12 14:16:26.124376 | controller | skipping: Conditional result was False 2026-06-12 14:16:26.134128 | 2026-06-12 14:16:26.134264 | TASK [configure-mirrors : Set up infrastructure mirrors] 2026-06-12 14:16:26.208839 | controller | ok 2026-06-12 14:16:26.221803 | controller | included: /var/lib/zuul/builds/73346a6fdb12407aa98a6ca096ba800a/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml 2026-06-12 14:16:26.231862 | 2026-06-12 14:16:26.231987 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir] 2026-06-12 14:16:26.483255 | controller | ok 2026-06-12 14:16:26.493116 | 2026-06-12 14:16:26.493264 | LOOP [configure-mirrors : Include OS-specific variables] 2026-06-12 14:16:26.587871 | controller | ok: "/var/lib/zuul/builds/73346a6fdb12407aa98a6ca096ba800a/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml" 2026-06-12 14:16:26.601713 | 2026-06-12 14:16:26.601890 | TASK [configure-mirrors : Install /etc/pip.conf configuration] 2026-06-12 14:16:27.414661 | controller | changed 2026-06-12 14:16:27.423279 | 2026-06-12 14:16:27.423436 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors] 2026-06-12 14:16:27.545966 | controller | ok: "/var/lib/zuul/builds/73346a6fdb12407aa98a6ca096ba800a/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml" 2026-06-12 14:16:27.546195 | controller | ok: All items complete 2026-06-12 14:16:27.546219 | 2026-06-12 14:16:27.640262 | controller | included: /var/lib/zuul/builds/73346a6fdb12407aa98a6ca096ba800a/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml 2026-06-12 14:16:27.645417 | 2026-06-12 14:16:27.645483 | LOOP [configure-mirrors : Install CentOS 9 repository files] 2026-06-12 14:16:28.559016 | controller | changed: "etc/yum.repos.d/centos.repo" 2026-06-12 14:16:29.373102 | controller | changed: "etc/yum.repos.d/centos-addons.repo" 2026-06-12 14:16:29.385694 | 2026-06-12 14:16:29.385903 | TASK [configure-mirrors : Disable deltrarpm] 2026-06-12 14:16:29.888396 | controller | changed: section and option added 2026-06-12 14:16:29.915278 | 2026-06-12 14:16:29.915385 | LOOP [configure-mirrors : Update yum/dnf cache] 2026-06-12 14:16:30.792694 | controller | 29 files removed 2026-06-12 14:16:30.793193 | controller | ok: Item: dnf clean all Runtime: 0:00:00.554870 2026-06-12 14:16:30.793273 | controller | changed: All items complete 2026-06-12 14:16:30.793317 | 2026-06-12 14:16:42.693579 | 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 14:16:42.693703 | controller | DNF version: 4.14.0 2026-06-12 14:16:42.693825 | controller | cachedir: /var/cache/dnf 2026-06-12 14:16:42.693856 | controller | Making cache files for all metadata files. 2026-06-12 14:16:42.693881 | controller | baseos: has expired and will be refreshed. 2026-06-12 14:16:42.693916 | controller | appstream: has expired and will be refreshed. 2026-06-12 14:16:42.693940 | controller | crb: has expired and will be refreshed. 2026-06-12 14:16:42.693962 | controller | extras-common: has expired and will be refreshed. 2026-06-12 14:16:42.693984 | controller | repo: downloading from remote: baseos 2026-06-12 14:16:42.694006 | controller | CentOS Stream 9 - BaseOS 60 MB/s | 8.9 MB 00:00 2026-06-12 14:16:42.694029 | controller | baseos: using metadata from Thu 11 Jun 2026 01:27:06 PM EDT. 2026-06-12 14:16:42.694050 | controller | repo: downloading from remote: appstream 2026-06-12 14:16:42.694071 | controller | CentOS Stream 9 - AppStream 89 MB/s | 28 MB 00:00 2026-06-12 14:16:42.694093 | controller | appstream: using metadata from Thu 11 Jun 2026 01:30:52 PM EDT. 2026-06-12 14:16:42.694114 | controller | repo: downloading from remote: crb 2026-06-12 14:16:42.694135 | controller | CentOS Stream 9 - CRB 26 MB/s | 8.3 MB 00:00 2026-06-12 14:16:42.694157 | controller | crb: using metadata from Thu 11 Jun 2026 01:35:27 PM EDT. 2026-06-12 14:16:42.694179 | controller | repo: downloading from remote: extras-common 2026-06-12 14:16:42.694200 | controller | CentOS Stream 9 - Extras packages 1.3 MB/s | 21 kB 00:00 2026-06-12 14:16:42.694223 | controller | extras-common: using metadata from Wed 10 Jun 2026 10:05:57 AM EDT. 2026-06-12 14:16:42.694255 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)' 2026-06-12 14:16:42.694288 | controller | Completion plugin: Generating completion cache... 2026-06-12 14:16:42.694312 | controller | Metadata cache created. 2026-06-12 14:16:42.694367 | controller | ok: Item: dnf makecache -v Runtime: 0:00:11.651368 2026-06-12 14:16:42.704220 | 2026-06-12 14:16:42.704296 | PLAY RECAP 2026-06-12 14:16:42.704373 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-12 14:16:42.704414 | localhost | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-12 14:16:42.704439 | 2026-06-12 14:16:42.833314 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-06-12 14:16:42.836100 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-06-12 14:16:43.559316 | 2026-06-12 14:16:43.559451 | PLAY [all] 2026-06-12 14:16:43.570038 | 2026-06-12 14:16:43.570105 | TASK [Install binary dependencies] 2026-06-12 14:16:43.649798 | controller | ok 2026-06-12 14:16:43.672101 | 2026-06-12 14:16:43.672293 | TASK [bindep : Include find tasks] 2026-06-12 14:16:43.712925 | controller | ok 2026-06-12 14:16:43.721829 | controller | included: /var/lib/zuul/builds/73346a6fdb12407aa98a6ca096ba800a/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-06-12 14:16:43.728088 | 2026-06-12 14:16:43.728175 | TASK [bindep : Look for bindep.txt] 2026-06-12 14:16:44.167403 | controller | ok 2026-06-12 14:16:44.176882 | 2026-06-12 14:16:44.177022 | TASK [bindep : Define bindep_file fact] 2026-06-12 14:16:44.222291 | controller | ok 2026-06-12 14:16:44.233918 | 2026-06-12 14:16:44.234046 | TASK [bindep : Look for other-requirements.txt] 2026-06-12 14:16:44.260812 | controller | skipping: Conditional result was False 2026-06-12 14:16:44.269972 | 2026-06-12 14:16:44.270113 | TASK [bindep : Define bindep_file fact] 2026-06-12 14:16:44.306560 | controller | skipping: Conditional result was False 2026-06-12 14:16:44.315276 | 2026-06-12 14:16:44.315507 | TASK [bindep : Look for bindep fallback file] 2026-06-12 14:16:44.351833 | controller | skipping: Conditional result was False 2026-06-12 14:16:44.358518 | 2026-06-12 14:16:44.358611 | TASK [bindep : Define bindep_file fact] 2026-06-12 14:16:44.412954 | controller | skipping: Conditional result was False 2026-06-12 14:16:44.419164 | 2026-06-12 14:16:44.419251 | TASK [bindep : Include bindep tasks] 2026-06-12 14:16:44.462054 | controller | ok 2026-06-12 14:16:44.473499 | controller | included: /var/lib/zuul/builds/73346a6fdb12407aa98a6ca096ba800a/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml 2026-06-12 14:16:44.484270 | 2026-06-12 14:16:44.484375 | TASK [bindep : Look for bindep command] 2026-06-12 14:16:44.529806 | controller | skipping: Conditional result was False 2026-06-12 14:16:44.540528 | 2026-06-12 14:16:44.540664 | TASK [bindep : Check for system bindep] 2026-06-12 14:16:45.080501 | controller | ok: Runtime: 0:00:00.005716 2026-06-12 14:16:45.084709 | 2026-06-12 14:16:45.084771 | TASK [bindep : Define bindep_command fact] 2026-06-12 14:16:45.120190 | controller | skipping: Conditional result was False 2026-06-12 14:16:45.126699 | 2026-06-12 14:16:45.126787 | TASK [bindep : Include install tasks] 2026-06-12 14:16:45.180657 | controller | ok 2026-06-12 14:16:45.193460 | controller | included: /var/lib/zuul/builds/73346a6fdb12407aa98a6ca096ba800a/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml 2026-06-12 14:16:45.207291 | 2026-06-12 14:16:45.207397 | TASK [bindep : Create temp dir for bindep] 2026-06-12 14:16:45.616664 | controller | changed 2026-06-12 14:16:45.624501 | 2026-06-12 14:16:45.624621 | TASK [Ensure we have pip dependencies] 2026-06-12 14:16:45.661923 | controller | ok 2026-06-12 14:16:45.691256 | 2026-06-12 14:16:45.691342 | TASK [ensure-pip : Check if pip is installed] 2026-06-12 10:16:46.002064 | controller | /usr/bin/pip3 2026-06-12 10:16:46.045890 | controller | /usr/bin/python3: No module named wheel 2026-06-12 14:16:46.220113 | controller | ok: Runtime: 0:00:00.058088 2026-06-12 14:16:46.224307 | 2026-06-12 14:16:46.224388 | LOOP [ensure-pip : Install pip from packages] 2026-06-12 14:16:46.266996 | controller | ok: "/var/lib/zuul/builds/73346a6fdb12407aa98a6ca096ba800a/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml" 2026-06-12 14:16:46.276038 | controller | included: /var/lib/zuul/builds/73346a6fdb12407aa98a6ca096ba800a/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml 2026-06-12 14:16:46.317795 | 2026-06-12 14:16:46.317929 | TASK [ensure-pip : Install Python 3 pip] 2026-06-12 14:16:47.875505 | controller | ok: Nothing to do 2026-06-12 14:16:47.882559 | 2026-06-12 14:16:47.882689 | TASK [ensure-pip : Install Python3-wheel on older CentOS] 2026-06-12 14:16:49.401640 | controller | changed 2026-06-12 14:16:49.410329 | 2026-06-12 14:16:49.410498 | TASK [ensure-pip : Check for EPEL repository] 2026-06-12 14:16:49.457690 | controller | skipping: Conditional result was False 2026-06-12 14:16:49.464407 | 2026-06-12 14:16:49.464498 | TASK [ensure-pip : Fail with instructions when EPEL is not installed] 2026-06-12 14:16:49.509593 | controller | skipping: Conditional result was False 2026-06-12 14:16:49.517332 | 2026-06-12 14:16:49.517443 | TASK [ensure-pip : Install Python 2 pip] 2026-06-12 14:16:49.562213 | controller | skipping: Conditional result was False 2026-06-12 14:16:49.571246 | 2026-06-12 14:16:49.571431 | TASK [ensure-pip : Ensure setuptools] 2026-06-12 14:16:49.597534 | controller | skipping: Conditional result was False 2026-06-12 14:16:49.606480 | 2026-06-12 14:16:49.606618 | TASK [ensure-pip : Check for ensurepip module] 2026-06-12 14:16:50.142394 | controller | skipping: Conditional result was False 2026-06-12 14:16:50.154412 | 2026-06-12 14:16:50.154552 | TASK [ensure-pip : Ensure python3-venv] 2026-06-12 14:16:50.181143 | controller | skipping: Conditional result was False 2026-06-12 14:16:50.190532 | 2026-06-12 14:16:50.190666 | TASK [ensure-pip : Install pip from source] 2026-06-12 14:16:50.219843 | controller | skipping: Conditional result was False 2026-06-12 14:16:50.229046 | 2026-06-12 14:16:50.229177 | TASK [ensure-pip : Probe for venv python full path] 2026-06-12 10:16:50.503900 | controller | /usr/bin/python3 2026-06-12 14:16:50.773690 | controller | ok: Runtime: 0:00:00.003698 2026-06-12 14:16:50.781906 | 2026-06-12 14:16:50.782032 | TASK [ensure-pip : Set host default] 2026-06-12 14:16:50.856647 | controller | ok 2026-06-12 14:16:50.864416 | 2026-06-12 14:16:50.864541 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2026-06-12 14:16:50.942075 | controller | ok 2026-06-12 14:16:50.959162 | 2026-06-12 14:16:50.959290 | TASK [bindep : Install bindep into temporary venv] 2026-06-12 14:16:55.446661 | controller | changed 2026-06-12 14:16:55.462797 | 2026-06-12 14:16:55.463057 | TASK [bindep : Define bindep_command] 2026-06-12 14:16:55.499205 | controller | ok 2026-06-12 14:16:55.507910 | 2026-06-12 14:16:55.508171 | LOOP [bindep : Include package tasks] 2026-06-12 14:16:55.585756 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt" 2026-06-12 14:16:55.585989 | controller | ok: All items complete 2026-06-12 14:16:55.586018 | 2026-06-12 14:16:55.600447 | controller | included: /var/lib/zuul/builds/73346a6fdb12407aa98a6ca096ba800a/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml 2026-06-12 14:16:55.623031 | 2026-06-12 14:16:55.623165 | TASK [bindep : Define bindep_run fact] 2026-06-12 14:16:55.665480 | controller | ok 2026-06-12 14:16:55.673934 | 2026-06-12 14:16:55.674025 | TASK [bindep : Get list of packages to install from bindep] 2026-06-12 10:16:56.984587 | controller | podman 2026-06-12 10:16:56.984692 | controller | python3-jmespath 2026-06-12 10:16:56.984701 | controller | python3-libvirt 2026-06-12 10:16:56.984705 | controller | python3-lxml 2026-06-12 10:16:56.984709 | controller | python3-netaddr 2026-06-12 14:16:57.214810 | controller | ok: Runtime: 0:00:01.072056 2026-06-12 14:16:57.220423 | 2026-06-12 14:16:57.220512 | TASK [bindep : Install distro packages from bindep] 2026-06-12 14:17:44.841017 | controller | changed 2026-06-12 14:17:44.849659 | 2026-06-12 14:17:44.849784 | TASK [bindep : Check that packages are installed] 2026-06-12 14:17:46.395221 | controller | ok: Runtime: 0:00:01.032835 2026-06-12 14:17:46.403458 | 2026-06-12 14:17:46.403601 | TASK [bindep : Fail if we cannot install all packages] 2026-06-12 14:17:46.440654 | controller | skipping: Conditional result was False 2026-06-12 14:17:46.469682 | 2026-06-12 14:17:46.469823 | TASK [Run test-setup role] 2026-06-12 14:17:46.503076 | controller | ok 2026-06-12 14:17:46.531061 | 2026-06-12 14:17:46.531151 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-06-12 14:17:46.783618 | controller | ok 2026-06-12 14:17:46.789071 | 2026-06-12 14:17:46.789390 | TASK [test-setup : Run tools/test-setup.sh] 2026-06-12 14:17:47.322001 | controller | skipping: Conditional result was False 2026-06-12 14:17:47.345924 | 2026-06-12 14:17:47.346065 | TASK [bindep : Remove bindep temp dir] 2026-06-12 14:17:47.741588 | controller | ok 2026-06-12 14:17:47.743523 | 2026-06-12 14:17:47.743623 | PLAY RECAP 2026-06-12 14:17:47.743686 | controller | ok: 23 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0 2026-06-12 14:17:47.743713 | 2026-06-12 14:17:47.894570 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-06-12 14:17:47.896463 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2026-06-12 14:17:48.536255 | 2026-06-12 14:17:48.536419 | PLAY [all] 2026-06-12 14:17:48.545514 | 2026-06-12 14:17:48.545644 | TASK [Abort when test_command variable is undefined] 2026-06-12 14:17:48.580382 | controller | skipping: Conditional result was False 2026-06-12 14:17:48.585421 | 2026-06-12 14:17:48.585503 | TASK [Convert test_command to list] 2026-06-12 14:17:48.630962 | controller | skipping: Conditional result was False 2026-06-12 14:17:48.640487 | 2026-06-12 14:17:48.640641 | TASK [Use test_command list] 2026-06-12 14:17:48.705116 | controller | ok 2026-06-12 14:17:48.729124 | 2026-06-12 14:17:48.729295 | LOOP [Run test_command] 2026-06-12 14:17:49.173278 | controller | no check to run 2026-06-12 14:17:49.173650 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.003450 2026-06-12 14:17:49.177810 | 2026-06-12 14:17:49.178056 | PLAY RECAP 2026-06-12 14:17:49.178195 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-12 14:17:49.178249 | 2026-06-12 14:17:49.337804 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2026-06-12 14:17:49.339140 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-12 14:17:49.982491 | 2026-06-12 14:17:49.982620 | PLAY [all] 2026-06-12 14:17:49.993298 | 2026-06-12 14:17:49.993392 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-06-12 14:17:50.340216 | controller | changed: non-zero return code 2026-06-12 14:17:50.349082 | 2026-06-12 14:17:50.349241 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-06-12 14:17:50.377729 | controller | skipping: Conditional result was False 2026-06-12 14:17:50.388532 | 2026-06-12 14:17:50.388671 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-06-12 14:17:50.425884 | 2026-06-12 14:17:50.426160 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-06-12 14:17:50.463039 | 2026-06-12 14:17:50.463310 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-06-12 14:17:50.491103 | controller | skipping: Conditional result was False 2026-06-12 14:17:50.499785 | 2026-06-12 14:17:50.499942 | LOOP [fetch-subunit-output : Generate subunit file] 2026-06-12 14:17:50.532110 | 2026-06-12 14:17:50.532313 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-06-12 14:17:50.557802 | controller | skipping: Conditional result was False 2026-06-12 14:17:50.564115 | 2026-06-12 14:17:50.564207 | TASK [fetch-subunit-output : Remove the temporary file] 2026-06-12 14:17:50.589669 | controller | skipping: Conditional result was False 2026-06-12 14:17:50.600265 | 2026-06-12 14:17:50.600566 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-06-12 14:17:50.627236 | controller | skipping: Conditional result was False 2026-06-12 14:17:50.642614 | 2026-06-12 14:17:50.642738 | PLAY RECAP 2026-06-12 14:17:50.642827 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-06-12 14:17:50.642869 | 2026-06-12 14:17:50.778743 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-12 14:17:50.781344 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-06-12 14:17:51.496251 | 2026-06-12 14:17:51.496419 | PLAY [all] 2026-06-12 14:17:51.506191 | 2026-06-12 14:17:51.506261 | TASK [include_role : fetch-output] 2026-06-12 14:17:51.554474 | controller | ok 2026-06-12 14:17:51.569754 | 2026-06-12 14:17:51.569831 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-12 14:17:51.615721 | controller | skipping: Conditional result was False 2026-06-12 14:17:51.624458 | 2026-06-12 14:17:51.624599 | TASK [fetch-output : Set log path for single node] 2026-06-12 14:17:51.658389 | controller | ok 2026-06-12 14:17:51.666311 | 2026-06-12 14:17:51.666483 | LOOP [fetch-output : Ensure local output dirs] 2026-06-12 14:17:52.134444 | controller -> localhost | changed: "/var/lib/zuul/builds/73346a6fdb12407aa98a6ca096ba800a/work/logs" 2026-06-12 14:17:52.418307 | controller -> localhost | changed: "/var/lib/zuul/builds/73346a6fdb12407aa98a6ca096ba800a/work/artifacts" 2026-06-12 14:17:52.733571 | controller -> localhost | changed: "/var/lib/zuul/builds/73346a6fdb12407aa98a6ca096ba800a/work/docs" 2026-06-12 14:17:52.753713 | 2026-06-12 14:17:52.753880 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-12 14:17:53.443017 | controller | changed: .d..t...... ./ 2026-06-12 14:17:53.443416 | controller | changed: All items complete 2026-06-12 14:17:53.443484 | 2026-06-12 14:17:54.046223 | controller | changed: .d..t...... ./ 2026-06-12 14:17:54.604677 | controller | changed: .d..t...... ./ 2026-06-12 14:17:54.631858 | 2026-06-12 14:17:54.632100 | TASK [include_role : fetch-output-openshift] 2026-06-12 14:17:54.662141 | controller | skipping: Conditional result was False 2026-06-12 14:17:54.674227 | 2026-06-12 14:17:54.674417 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-12 14:17:55.216132 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.017019 2026-06-12 14:17:55.524080 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.013254 2026-06-12 14:17:55.540732 | 2026-06-12 14:17:55.540888 | PLAY [all] 2026-06-12 14:17:55.552462 | 2026-06-12 14:17:55.552597 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-12 14:17:56.120502 | controller | changed 2026-06-12 14:17:56.126721 | 2026-06-12 14:17:56.126774 | PLAY RECAP 2026-06-12 14:17:56.126860 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-12 14:17:56.126883 | 2026-06-12 14:17:56.277505 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-06-12 14:17:56.279148 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2026-06-12 14:17:56.952019 | 2026-06-12 14:17:56.952183 | PLAY [localhost] 2026-06-12 14:17:56.963146 | 2026-06-12 14:17:56.963261 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-12 14:17:57.416377 | localhost | changed 2026-06-12 14:17:57.426609 | 2026-06-12 14:17:57.426765 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-12 14:17:57.466787 | localhost | ok 2026-06-12 14:17:57.474763 | 2026-06-12 14:17:57.474852 | TASK [add-fileserver : Create SSH private key tempfile] 2026-06-12 14:17:57.910528 | localhost | changed 2026-06-12 14:17:57.917650 | 2026-06-12 14:17:57.917745 | TASK [add-fileserver : Create SSH private key from secret] 2026-06-12 14:17:58.775135 | localhost | changed 2026-06-12 14:17:58.781553 | 2026-06-12 14:17:58.781653 | TASK [add-fileserver : Add fileserver ssh key] 2026-06-12 14:17:59.334978 | localhost | Identity added: /var/lib/zuul/builds/73346a6fdb12407aa98a6ca096ba800a/work/tmp/ansible.hknsc3wh (/var/lib/zuul/builds/73346a6fdb12407aa98a6ca096ba800a/work/tmp/ansible.hknsc3wh) 2026-06-12 14:17:59.335381 | localhost | ok: Runtime: 0:00:00.019561 2026-06-12 14:17:59.349936 | 2026-06-12 14:17:59.350080 | TASK [add-fileserver : Remove SSH private key from disk] 2026-06-12 14:17:59.725479 | localhost | ok: Runtime: 0:00:00.008404 2026-06-12 14:17:59.729552 | 2026-06-12 14:17:59.729625 | TASK [add-fileserver : Add fileserver to inventory] 2026-06-12 14:17:59.811932 | localhost | changed 2026-06-12 14:17:59.822730 | 2026-06-12 14:17:59.822864 | TASK [add-fileserver : Add fileserver server to known hosts] 2026-06-12 14:18:00.300031 | localhost | changed 2026-06-12 14:18:00.307873 | 2026-06-12 14:18:00.307951 | PLAY [localhost] 2026-06-12 14:18:00.313635 | 2026-06-12 14:18:00.313718 | TASK [Generate bulk log download script] 2026-06-12 14:18:00.335041 | localhost | ok 2026-06-12 14:18:00.349972 | 2026-06-12 14:18:00.350077 | TASK [local-log-download : Check API endpoint is defined] 2026-06-12 14:18:00.400769 | localhost | ok: All assertions passed 2026-06-12 14:18:00.406290 | 2026-06-12 14:18:00.406398 | TASK [local-log-download : Create download script] 2026-06-12 14:18:01.019542 | localhost -> localhost | changed 2026-06-12 14:18:01.081662 | 2026-06-12 14:18:01.081845 | TASK [Register quick-download link] 2026-06-12 14:18:01.113049 | localhost | ok 2026-06-12 14:18:01.116461 | 2026-06-12 14:18:01.116531 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com] 2026-06-12 14:18:01.122830 | 2026-06-12 14:18:01.122907 | TASK [Set zuul-log-path fact] 2026-06-12 14:18:01.148866 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-12 14:18:01.157055 | 2026-06-12 14:18:01.157136 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-12 14:18:01.194045 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-12 14:18:01.197107 | 2026-06-12 14:18:01.197187 | TASK [upload-logs : Create log directories] 2026-06-12 14:18:02.147555 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed 2026-06-12 14:18:02.151583 | 2026-06-12 14:18:02.151718 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-12 14:18:02.506247 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.010496 2026-06-12 14:18:02.512929 | 2026-06-12 14:18:02.513063 | TASK [upload-logs : Upload logs to log server] 2026-06-12 14:18:03.840730 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | Output suppressed because no_log was given 2026-06-12 14:18:03.844625 | 2026-06-12 14:18:03.844793 | LOOP [upload-logs : Compress console log and json output]