2026-06-08 07:48:00.297940 | Job console starting 2026-06-08 07:48:00.424921 | Updating git repos 2026-06-08 07:48:00.461190 | Cloning repos into workspace 2026-06-08 07:48:01.396163 | Restoring repo states 2026-06-08 07:48:01.408939 | Merging changes 2026-06-08 07:48:02.992338 | Checking out repos 2026-06-08 07:48:03.123376 | Preparing playbooks 2026-06-08 07:48:09.638463 | Running Ansible setup 2026-06-08 07:48:13.632249 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-06-08 07:48:14.310119 | 2026-06-08 07:48:14.310419 | PLAY [localhost] 2026-06-08 07:48:14.319487 | 2026-06-08 07:48:14.319578 | TASK [Gathering Facts] 2026-06-08 07:48:15.249769 | localhost | ok 2026-06-08 07:48:15.259018 | 2026-06-08 07:48:15.259192 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-08 07:48:15.751893 | localhost -> localhost | changed 2026-06-08 07:48:15.761140 | 2026-06-08 07:48:15.761311 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-08 07:48:16.840498 | localhost -> localhost | changed 2026-06-08 07:48:16.848065 | 2026-06-08 07:48:16.848179 | TASK [Setup log path fact] 2026-06-08 07:48:16.877548 | localhost | ok 2026-06-08 07:48:16.890569 | 2026-06-08 07:48:16.890673 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-08 07:48:16.930683 | localhost | ok 2026-06-08 07:48:16.938031 | 2026-06-08 07:48:16.938117 | TASK [emit-job-header : Print job information] 2026-06-08 07:48:16.988898 | # Job Information 2026-06-08 07:48:16.989146 | Ansible Version: 2.16.18 2026-06-08 07:48:16.989183 | Job: cifmw-molecule-cleanup_openstack 2026-06-08 07:48:16.989212 | Pipeline: github-check 2026-06-08 07:48:16.989238 | Executor: 38.129.56.84 2026-06-08 07:48:16.989263 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3866 2026-06-08 07:48:16.989290 | Log URL (when completed): https://gateway-cloud-softwarefactory.apps.ocp.cloud.ci.centos.org/logs/e5c/rdoproject.org/e5c26fa8a6764dc18c13df83e98f1fde/ 2026-06-08 07:48:16.989318 | Event ID: 252ec4b0-630e-11f1-9e13-1ed34d55b048 2026-06-08 07:48:16.993263 | 2026-06-08 07:48:16.993346 | LOOP [emit-job-header : Print node information] 2026-06-08 07:48:17.120731 | localhost | ok: 2026-06-08 07:48:17.121552 | localhost | # Node Information 2026-06-08 07:48:17.121711 | localhost | Inventory Hostname: controller 2026-06-08 07:48:17.121750 | localhost | Hostname: np0000066865 2026-06-08 07:48:17.121779 | localhost | Username: zuul 2026-06-08 07:48:17.121807 | localhost | Distro: CentOS 9 2026-06-08 07:48:17.121831 | localhost | Provider: vexxhost-nodepool-tripleo 2026-06-08 07:48:17.121913 | localhost | Region: RegionOne 2026-06-08 07:48:17.121937 | localhost | Label: cloud-centos-9-stream-tripleo 2026-06-08 07:48:17.121958 | localhost | Product Name: OpenStack Nova 2026-06-08 07:48:17.121979 | localhost | Interface IP: 38.102.83.2 2026-06-08 07:48:17.137327 | 2026-06-08 07:48:17.137481 | PLAY [all] 2026-06-08 07:48:17.145376 | 2026-06-08 07:48:17.145467 | TASK [Gather network facts] 2026-06-08 07:48:17.625582 | controller | ok 2026-06-08 07:48:17.637262 | 2026-06-08 07:48:17.637381 | TASK [include_role : start-zuul-console] 2026-06-08 07:48:17.660744 | controller | ok 2026-06-08 07:48:17.678776 | 2026-06-08 07:48:17.678878 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-06-08 07:48:18.133411 | controller | ok 2026-06-08 07:48:18.140444 | 2026-06-08 07:48:18.140592 | TASK [include_role : add-build-sshkey] 2026-06-08 07:48:18.168879 | controller | ok 2026-06-08 07:48:18.179470 | 2026-06-08 07:48:18.179599 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-08 07:48:18.529650 | controller -> localhost | ok 2026-06-08 07:48:18.539007 | 2026-06-08 07:48:18.539213 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-08 07:48:18.572648 | controller | ok 2026-06-08 07:48:18.587120 | controller | included: /var/lib/zuul/builds/e5c26fa8a6764dc18c13df83e98f1fde/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-08 07:48:18.591665 | 2026-06-08 07:48:18.591725 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-08 07:48:19.174295 | controller -> localhost | Generating public/private rsa key pair. 2026-06-08 07:48:19.174528 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/e5c26fa8a6764dc18c13df83e98f1fde/work/e5c26fa8a6764dc18c13df83e98f1fde_id_rsa 2026-06-08 07:48:19.174571 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/e5c26fa8a6764dc18c13df83e98f1fde/work/e5c26fa8a6764dc18c13df83e98f1fde_id_rsa.pub 2026-06-08 07:48:19.174602 | controller -> localhost | The key fingerprint is: 2026-06-08 07:48:19.174630 | controller -> localhost | SHA256:5cANBy7Gb9Ai8PGD5K8Mj3vI6vGufjpOEi9I/Idwnak zuul-build-sshkey 2026-06-08 07:48:19.174662 | controller -> localhost | The key's randomart image is: 2026-06-08 07:48:19.174696 | controller -> localhost | +---[RSA 3072]----+ 2026-06-08 07:48:19.174724 | controller -> localhost | | . o o.. | 2026-06-08 07:48:19.174751 | controller -> localhost | | = = + + | 2026-06-08 07:48:19.174776 | controller -> localhost | | = O = o | 2026-06-08 07:48:19.174800 | controller -> localhost | |. .+o* + | 2026-06-08 07:48:19.174824 | controller -> localhost | |.+.. +. S . | 2026-06-08 07:48:19.174871 | controller -> localhost | |oo+=o. . | 2026-06-08 07:48:19.174917 | controller -> localhost | |++oE+. | 2026-06-08 07:48:19.174954 | controller -> localhost | |oo=oo | 2026-06-08 07:48:19.174986 | controller -> localhost | |=BB+ | 2026-06-08 07:48:19.175034 | controller -> localhost | +----[SHA256]-----+ 2026-06-08 07:48:19.175095 | controller -> localhost | ok: Runtime: 0:00:00.072708 2026-06-08 07:48:19.180211 | 2026-06-08 07:48:19.180295 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-08 07:48:19.216716 | controller | ok 2026-06-08 07:48:19.237127 | controller | included: /var/lib/zuul/builds/e5c26fa8a6764dc18c13df83e98f1fde/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-08 07:48:19.249001 | 2026-06-08 07:48:19.249116 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-08 07:48:19.284710 | controller | skipping: Conditional result was False 2026-06-08 07:48:19.294179 | 2026-06-08 07:48:19.294366 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-08 07:48:19.768761 | controller | changed 2026-06-08 07:48:19.783441 | 2026-06-08 07:48:19.783674 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-08 07:48:20.076157 | controller | ok 2026-06-08 07:48:20.084082 | 2026-06-08 07:48:20.084253 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-08 07:48:20.938684 | controller | changed 2026-06-08 07:48:20.946963 | 2026-06-08 07:48:20.947092 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-08 07:48:21.864079 | controller | changed 2026-06-08 07:48:21.868011 | 2026-06-08 07:48:21.868096 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-08 07:48:21.882720 | controller | skipping: Conditional result was False 2026-06-08 07:48:21.887865 | 2026-06-08 07:48:21.887977 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-08 07:48:22.277077 | controller -> localhost | changed 2026-06-08 07:48:22.287367 | 2026-06-08 07:48:22.287543 | TASK [add-build-sshkey : Add back temp key] 2026-06-08 07:48:22.655438 | controller -> localhost | Identity added: /var/lib/zuul/builds/e5c26fa8a6764dc18c13df83e98f1fde/work/e5c26fa8a6764dc18c13df83e98f1fde_id_rsa (zuul-build-sshkey) 2026-06-08 07:48:22.655768 | controller -> localhost | ok: Runtime: 0:00:00.012891 2026-06-08 07:48:22.663802 | 2026-06-08 07:48:22.664009 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-08 07:48:23.054157 | controller | ok 2026-06-08 07:48:23.059938 | 2026-06-08 07:48:23.060103 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-08 07:48:23.098589 | controller | skipping: Conditional result was False 2026-06-08 07:48:23.115593 | 2026-06-08 07:48:23.116070 | TASK [include_role : validate-host] 2026-06-08 07:48:23.153015 | controller | ok 2026-06-08 07:48:23.184492 | 2026-06-08 07:48:23.184649 | TASK [validate-host : Define zuul_info_dir fact] 2026-06-08 07:48:23.226284 | controller | ok 2026-06-08 07:48:23.233034 | 2026-06-08 07:48:23.233193 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-06-08 07:48:23.554818 | controller -> localhost | ok 2026-06-08 07:48:23.559735 | 2026-06-08 07:48:23.559798 | TASK [validate-host : Collect information about the host] 2026-06-08 07:48:24.278828 | controller | ok 2026-06-08 07:48:24.303800 | 2026-06-08 07:48:24.304080 | TASK [validate-host : Sanitize hostname] 2026-06-08 07:48:24.398141 | controller | ok 2026-06-08 07:48:24.403390 | 2026-06-08 07:48:24.403476 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-06-08 07:48:24.955810 | controller -> localhost | changed 2026-06-08 07:48:24.962558 | 2026-06-08 07:48:24.962641 | TASK [validate-host : Collect information about zuul worker] 2026-06-08 07:48:25.444052 | controller | ok 2026-06-08 07:48:25.457812 | 2026-06-08 07:48:25.458089 | TASK [validate-host : Write out all zuul information for each host] 2026-06-08 07:48:26.085012 | controller -> localhost | changed 2026-06-08 07:48:26.101070 | 2026-06-08 07:48:26.101224 | TASK [include_role : prepare-workspace-openshift] 2026-06-08 07:48:26.129026 | controller | skipping: Conditional result was False 2026-06-08 07:48:26.142761 | 2026-06-08 07:48:26.143021 | TASK [include_role : remove-zuul-sshkey] 2026-06-08 07:48:26.170911 | controller | skipping: Conditional result was False 2026-06-08 07:48:26.180179 | 2026-06-08 07:48:26.180311 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-08 07:48:26.492615 | controller | ok: "logs" 2026-06-08 07:48:26.493157 | controller | ok: All items complete 2026-06-08 07:48:26.493224 | 2026-06-08 07:48:26.711125 | controller | ok: "artifacts" 2026-06-08 07:48:26.913536 | controller | ok: "docs" 2026-06-08 07:48:26.929633 | 2026-06-08 07:48:26.929870 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-08 07:48:27.188681 | controller | changed: "logs" 2026-06-08 07:48:27.436364 | controller | changed: "artifacts" 2026-06-08 07:48:27.694790 | controller | changed: "docs" 2026-06-08 07:48:27.710658 | 2026-06-08 07:48:27.710826 | PLAY RECAP 2026-06-08 07:48:27.710950 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2026-06-08 07:48:27.711006 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-08 07:48:27.711044 | 2026-06-08 07:48:27.858176 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-06-08 07:48:27.861085 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-06-08 07:48:28.556527 | 2026-06-08 07:48:28.556707 | PLAY [localhost] 2026-06-08 07:48:28.569306 | 2026-06-08 07:48:28.569522 | TASK [configure-ssh-config-fips : Ensure ssh config directory] 2026-06-08 07:48:29.094156 | localhost | changed 2026-06-08 07:48:29.098631 | 2026-06-08 07:48:29.098714 | TASK [configure-ssh-config-fips : Setup ssh config] 2026-06-08 07:48:29.931251 | localhost | changed 2026-06-08 07:48:29.945462 | 2026-06-08 07:48:29.945577 | PLAY [all] 2026-06-08 07:48:29.958535 | 2026-06-08 07:48:29.958662 | TASK [include_role : prepare-workspace] 2026-06-08 07:48:30.004380 | controller | ok 2026-06-08 07:48:30.032595 | 2026-06-08 07:48:30.032700 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-08 07:48:30.472017 | controller | ok 2026-06-08 07:48:30.491646 | 2026-06-08 07:48:30.491793 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-08 07:48:32.037040 | controller | Output suppressed because no_log was given 2026-06-08 07:48:32.056481 | 2026-06-08 07:48:32.056637 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-08 07:48:32.301494 | controller | changed: "logs" 2026-06-08 07:48:32.548328 | controller | changed: "artifacts" 2026-06-08 07:48:32.779978 | controller | changed: "docs" 2026-06-08 07:48:32.794965 | 2026-06-08 07:48:32.795126 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-08 07:48:33.060934 | controller | changed: "logs" 2026-06-08 07:48:33.061410 | controller | changed: All items complete 2026-06-08 07:48:33.061471 | 2026-06-08 07:48:33.280457 | controller | changed: "artifacts" 2026-06-08 07:48:33.488526 | controller | changed: "docs" 2026-06-08 07:48:33.515452 | 2026-06-08 07:48:33.515699 | TASK [Check if worker can sudo] 2026-06-08 07:48:34.062843 | controller | ok: Runtime: 0:00:00.044153 2026-06-08 07:48:34.072376 | 2026-06-08 07:48:34.072519 | TASK [configure-mirrors : Gather needed facts] 2026-06-08 07:48:34.119781 | controller | skipping: Conditional result was False 2026-06-08 07:48:34.129997 | 2026-06-08 07:48:34.130142 | TASK [configure-mirrors : Set up infrastructure mirrors] 2026-06-08 07:48:34.227344 | controller | ok 2026-06-08 07:48:34.240902 | controller | included: /var/lib/zuul/builds/e5c26fa8a6764dc18c13df83e98f1fde/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml 2026-06-08 07:48:34.251479 | 2026-06-08 07:48:34.251623 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir] 2026-06-08 07:48:34.559969 | controller | ok 2026-06-08 07:48:34.568770 | 2026-06-08 07:48:34.568925 | LOOP [configure-mirrors : Include OS-specific variables] 2026-06-08 07:48:34.664922 | controller | ok: "/var/lib/zuul/builds/e5c26fa8a6764dc18c13df83e98f1fde/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml" 2026-06-08 07:48:34.681869 | 2026-06-08 07:48:34.682046 | TASK [configure-mirrors : Install /etc/pip.conf configuration] 2026-06-08 07:48:35.563425 | controller | changed 2026-06-08 07:48:35.567481 | 2026-06-08 07:48:35.567548 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors] 2026-06-08 07:48:35.729799 | controller | ok: "/var/lib/zuul/builds/e5c26fa8a6764dc18c13df83e98f1fde/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml" 2026-06-08 07:48:35.730011 | controller | ok: All items complete 2026-06-08 07:48:35.730036 | 2026-06-08 07:48:35.773664 | controller | included: /var/lib/zuul/builds/e5c26fa8a6764dc18c13df83e98f1fde/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml 2026-06-08 07:48:35.778863 | 2026-06-08 07:48:35.778976 | LOOP [configure-mirrors : Install CentOS 9 repository files] 2026-06-08 07:48:36.706781 | controller | changed: "etc/yum.repos.d/centos.repo" 2026-06-08 07:48:37.502362 | controller | changed: "etc/yum.repos.d/centos-addons.repo" 2026-06-08 07:48:37.521426 | 2026-06-08 07:48:37.521671 | TASK [configure-mirrors : Disable deltrarpm] 2026-06-08 07:48:38.023104 | controller | changed: section and option added 2026-06-08 07:48:38.060824 | 2026-06-08 07:48:38.061098 | LOOP [configure-mirrors : Update yum/dnf cache] 2026-06-08 07:48:38.883967 | controller | 29 files removed 2026-06-08 07:48:38.884656 | controller | ok: Item: dnf clean all Runtime: 0:00:00.472688 2026-06-08 07:48:38.884710 | controller | changed: All items complete 2026-06-08 07:48:38.884738 | 2026-06-08 07:48:50.070074 | 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-08 07:48:50.070207 | controller | DNF version: 4.14.0 2026-06-08 07:48:50.070241 | controller | cachedir: /var/cache/dnf 2026-06-08 07:48:50.070269 | controller | Making cache files for all metadata files. 2026-06-08 07:48:50.070295 | controller | baseos: has expired and will be refreshed. 2026-06-08 07:48:50.070331 | controller | appstream: has expired and will be refreshed. 2026-06-08 07:48:50.070356 | controller | crb: has expired and will be refreshed. 2026-06-08 07:48:50.070380 | controller | extras-common: has expired and will be refreshed. 2026-06-08 07:48:50.070405 | controller | repo: downloading from remote: baseos 2026-06-08 07:48:50.070430 | controller | CentOS Stream 9 - BaseOS 82 MB/s | 8.9 MB 00:00 2026-06-08 07:48:50.070454 | controller | baseos: using metadata from Mon 01 Jun 2026 05:58:58 PM EDT. 2026-06-08 07:48:50.070479 | controller | repo: downloading from remote: appstream 2026-06-08 07:48:50.070503 | controller | CentOS Stream 9 - AppStream 123 MB/s | 28 MB 00:00 2026-06-08 07:48:50.070528 | controller | appstream: using metadata from Mon 01 Jun 2026 06:02:34 PM EDT. 2026-06-08 07:48:50.070552 | controller | repo: downloading from remote: crb 2026-06-08 07:48:50.070576 | controller | CentOS Stream 9 - CRB 71 MB/s | 8.1 MB 00:00 2026-06-08 07:48:50.070601 | controller | crb: using metadata from Mon 01 Jun 2026 06:07:05 PM EDT. 2026-06-08 07:48:50.070625 | controller | repo: downloading from remote: extras-common 2026-06-08 07:48:50.070650 | controller | CentOS Stream 9 - Extras packages 1.7 MB/s | 21 kB 00:00 2026-06-08 07:48:50.070674 | controller | extras-common: using metadata from Tue 24 Mar 2026 03:36:22 PM EDT. 2026-06-08 07:48:50.070698 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)' 2026-06-08 07:48:50.070722 | controller | Completion plugin: Generating completion cache... 2026-06-08 07:48:50.070746 | controller | Metadata cache created. 2026-06-08 07:48:50.070782 | controller | ok: Item: dnf makecache -v Runtime: 0:00:10.906887 2026-06-08 07:48:50.080786 | 2026-06-08 07:48:50.080862 | PLAY RECAP 2026-06-08 07:48:50.080919 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-08 07:48:50.080954 | localhost | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-08 07:48:50.080978 | 2026-06-08 07:48:50.229120 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-06-08 07:48:50.230443 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-06-08 07:48:50.851402 | 2026-06-08 07:48:50.851526 | PLAY [all] 2026-06-08 07:48:50.862765 | 2026-06-08 07:48:50.862875 | TASK [Install binary dependencies] 2026-06-08 07:48:50.935725 | controller | ok 2026-06-08 07:48:50.965127 | 2026-06-08 07:48:50.965317 | TASK [bindep : Include find tasks] 2026-06-08 07:48:51.014489 | controller | ok 2026-06-08 07:48:51.029614 | controller | included: /var/lib/zuul/builds/e5c26fa8a6764dc18c13df83e98f1fde/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-06-08 07:48:51.039197 | 2026-06-08 07:48:51.039298 | TASK [bindep : Look for bindep.txt] 2026-06-08 07:48:51.490882 | controller | ok 2026-06-08 07:48:51.501285 | 2026-06-08 07:48:51.501522 | TASK [bindep : Define bindep_file fact] 2026-06-08 07:48:51.549057 | controller | ok 2026-06-08 07:48:51.565195 | 2026-06-08 07:48:51.565354 | TASK [bindep : Look for other-requirements.txt] 2026-06-08 07:48:51.592145 | controller | skipping: Conditional result was False 2026-06-08 07:48:51.599174 | 2026-06-08 07:48:51.599297 | TASK [bindep : Define bindep_file fact] 2026-06-08 07:48:51.627967 | controller | skipping: Conditional result was False 2026-06-08 07:48:51.633780 | 2026-06-08 07:48:51.633896 | TASK [bindep : Look for bindep fallback file] 2026-06-08 07:48:51.658771 | controller | skipping: Conditional result was False 2026-06-08 07:48:51.664992 | 2026-06-08 07:48:51.665102 | TASK [bindep : Define bindep_file fact] 2026-06-08 07:48:51.700380 | controller | skipping: Conditional result was False 2026-06-08 07:48:51.706185 | 2026-06-08 07:48:51.706275 | TASK [bindep : Include bindep tasks] 2026-06-08 07:48:51.746148 | controller | ok 2026-06-08 07:48:51.753530 | controller | included: /var/lib/zuul/builds/e5c26fa8a6764dc18c13df83e98f1fde/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml 2026-06-08 07:48:51.764326 | 2026-06-08 07:48:51.764408 | TASK [bindep : Look for bindep command] 2026-06-08 07:48:51.810328 | controller | skipping: Conditional result was False 2026-06-08 07:48:51.818516 | 2026-06-08 07:48:51.818626 | TASK [bindep : Check for system bindep] 2026-06-08 07:48:52.355201 | controller | ok: Runtime: 0:00:00.004227 2026-06-08 07:48:52.366233 | 2026-06-08 07:48:52.366378 | TASK [bindep : Define bindep_command fact] 2026-06-08 07:48:52.403582 | controller | skipping: Conditional result was False 2026-06-08 07:48:52.414106 | 2026-06-08 07:48:52.414478 | TASK [bindep : Include install tasks] 2026-06-08 07:48:52.467034 | controller | ok 2026-06-08 07:48:52.476623 | controller | included: /var/lib/zuul/builds/e5c26fa8a6764dc18c13df83e98f1fde/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml 2026-06-08 07:48:52.496274 | 2026-06-08 07:48:52.496435 | TASK [bindep : Create temp dir for bindep] 2026-06-08 07:48:52.924355 | controller | changed 2026-06-08 07:48:52.932608 | 2026-06-08 07:48:52.932739 | TASK [Ensure we have pip dependencies] 2026-06-08 07:48:52.966255 | controller | ok 2026-06-08 07:48:53.004137 | 2026-06-08 07:48:53.004310 | TASK [ensure-pip : Check if pip is installed] 2026-06-08 03:48:53.277486 | controller | /usr/bin/pip3 2026-06-08 03:48:53.303781 | controller | /usr/bin/python3: No module named wheel 2026-06-08 07:48:53.533799 | controller | ok: Runtime: 0:00:00.038044 2026-06-08 07:48:53.539405 | 2026-06-08 07:48:53.539493 | LOOP [ensure-pip : Install pip from packages] 2026-06-08 07:48:53.591041 | controller | ok: "/var/lib/zuul/builds/e5c26fa8a6764dc18c13df83e98f1fde/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml" 2026-06-08 07:48:53.614968 | controller | included: /var/lib/zuul/builds/e5c26fa8a6764dc18c13df83e98f1fde/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml 2026-06-08 07:48:53.675530 | 2026-06-08 07:48:53.675676 | TASK [ensure-pip : Install Python 3 pip] 2026-06-08 07:48:55.104887 | controller | ok: Nothing to do 2026-06-08 07:48:55.110212 | 2026-06-08 07:48:55.110300 | TASK [ensure-pip : Install Python3-wheel on older CentOS] 2026-06-08 07:48:56.527336 | controller | changed 2026-06-08 07:48:56.531494 | 2026-06-08 07:48:56.531558 | TASK [ensure-pip : Check for EPEL repository] 2026-06-08 07:48:56.597340 | controller | skipping: Conditional result was False 2026-06-08 07:48:56.602808 | 2026-06-08 07:48:56.602903 | TASK [ensure-pip : Fail with instructions when EPEL is not installed] 2026-06-08 07:48:56.649052 | controller | skipping: Conditional result was False 2026-06-08 07:48:56.656776 | 2026-06-08 07:48:56.656883 | TASK [ensure-pip : Install Python 2 pip] 2026-06-08 07:48:56.722807 | controller | skipping: Conditional result was False 2026-06-08 07:48:56.733084 | 2026-06-08 07:48:56.733265 | TASK [ensure-pip : Ensure setuptools] 2026-06-08 07:48:56.761616 | controller | skipping: Conditional result was False 2026-06-08 07:48:56.771561 | 2026-06-08 07:48:56.771704 | TASK [ensure-pip : Check for ensurepip module] 2026-06-08 07:48:57.309096 | controller | skipping: Conditional result was False 2026-06-08 07:48:57.320209 | 2026-06-08 07:48:57.320357 | TASK [ensure-pip : Ensure python3-venv] 2026-06-08 07:48:57.356746 | controller | skipping: Conditional result was False 2026-06-08 07:48:57.366529 | 2026-06-08 07:48:57.366678 | TASK [ensure-pip : Install pip from source] 2026-06-08 07:48:57.393843 | controller | skipping: Conditional result was False 2026-06-08 07:48:57.404784 | 2026-06-08 07:48:57.404981 | TASK [ensure-pip : Probe for venv python full path] 2026-06-08 03:48:57.689183 | controller | /usr/bin/python3 2026-06-08 07:48:57.952207 | controller | ok: Runtime: 0:00:00.004250 2026-06-08 07:48:57.961238 | 2026-06-08 07:48:57.961376 | TASK [ensure-pip : Set host default] 2026-06-08 07:48:58.041113 | controller | ok 2026-06-08 07:48:58.052388 | 2026-06-08 07:48:58.052566 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2026-06-08 07:48:58.142341 | controller | ok 2026-06-08 07:48:58.160403 | 2026-06-08 07:48:58.160587 | TASK [bindep : Install bindep into temporary venv] 2026-06-08 07:49:02.138910 | controller | changed 2026-06-08 07:49:02.148358 | 2026-06-08 07:49:02.148505 | TASK [bindep : Define bindep_command] 2026-06-08 07:49:02.195220 | controller | ok 2026-06-08 07:49:02.203940 | 2026-06-08 07:49:02.204136 | LOOP [bindep : Include package tasks] 2026-06-08 07:49:02.280892 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt" 2026-06-08 07:49:02.281217 | controller | ok: All items complete 2026-06-08 07:49:02.281272 | 2026-06-08 07:49:02.296915 | controller | included: /var/lib/zuul/builds/e5c26fa8a6764dc18c13df83e98f1fde/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml 2026-06-08 07:49:02.310024 | 2026-06-08 07:49:02.310116 | TASK [bindep : Define bindep_run fact] 2026-06-08 07:49:02.350760 | controller | ok 2026-06-08 07:49:02.355638 | 2026-06-08 07:49:02.355726 | TASK [bindep : Get list of packages to install from bindep] 2026-06-08 03:49:03.709971 | controller | podman 2026-06-08 03:49:03.710213 | controller | python3-jmespath 2026-06-08 03:49:03.710227 | controller | python3-libvirt 2026-06-08 03:49:03.710237 | controller | python3-lxml 2026-06-08 03:49:03.710245 | controller | python3-netaddr 2026-06-08 07:49:03.892480 | controller | ok: Runtime: 0:00:01.119597 2026-06-08 07:49:03.901625 | 2026-06-08 07:49:03.901915 | TASK [bindep : Install distro packages from bindep] 2026-06-08 07:49:45.579615 | controller | changed 2026-06-08 07:49:45.588333 | 2026-06-08 07:49:45.588487 | TASK [bindep : Check that packages are installed] 2026-06-08 07:49:47.139836 | controller | ok: Runtime: 0:00:01.042630 2026-06-08 07:49:47.148202 | 2026-06-08 07:49:47.148339 | TASK [bindep : Fail if we cannot install all packages] 2026-06-08 07:49:47.186092 | controller | skipping: Conditional result was False 2026-06-08 07:49:47.195396 | 2026-06-08 07:49:47.195465 | TASK [Run test-setup role] 2026-06-08 07:49:47.224823 | controller | ok 2026-06-08 07:49:47.238788 | 2026-06-08 07:49:47.238868 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-06-08 07:49:47.484419 | controller | ok 2026-06-08 07:49:47.492471 | 2026-06-08 07:49:47.492606 | TASK [test-setup : Run tools/test-setup.sh] 2026-06-08 07:49:48.028818 | controller | skipping: Conditional result was False 2026-06-08 07:49:48.053115 | 2026-06-08 07:49:48.053300 | TASK [bindep : Remove bindep temp dir] 2026-06-08 07:49:48.476134 | controller | ok 2026-06-08 07:49:48.479022 | 2026-06-08 07:49:48.479135 | PLAY RECAP 2026-06-08 07:49:48.479223 | controller | ok: 23 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0 2026-06-08 07:49:48.479266 | 2026-06-08 07:49:48.631504 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-06-08 07:49:48.634145 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2026-06-08 07:49:49.290349 | 2026-06-08 07:49:49.290605 | PLAY [all] 2026-06-08 07:49:49.305125 | 2026-06-08 07:49:49.305301 | TASK [Abort when test_command variable is undefined] 2026-06-08 07:49:49.351223 | controller | skipping: Conditional result was False 2026-06-08 07:49:49.361377 | 2026-06-08 07:49:49.361575 | TASK [Convert test_command to list] 2026-06-08 07:49:49.429377 | controller | skipping: Conditional result was False 2026-06-08 07:49:49.438370 | 2026-06-08 07:49:49.438524 | TASK [Use test_command list] 2026-06-08 07:49:49.513625 | controller | ok 2026-06-08 07:49:49.525049 | 2026-06-08 07:49:49.525226 | LOOP [Run test_command] 2026-06-08 07:49:49.998982 | controller | no check to run 2026-06-08 07:49:49.999308 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.004609 2026-06-08 07:49:50.003262 | 2026-06-08 07:49:50.003463 | PLAY RECAP 2026-06-08 07:49:50.003553 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-08 07:49:50.003597 | 2026-06-08 07:49:50.167951 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2026-06-08 07:49:50.170681 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-08 07:49:50.811502 | 2026-06-08 07:49:50.811629 | PLAY [all] 2026-06-08 07:49:50.822375 | 2026-06-08 07:49:50.822477 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-06-08 07:49:51.205359 | controller | changed: non-zero return code 2026-06-08 07:49:51.209968 | 2026-06-08 07:49:51.210047 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-06-08 07:49:51.236095 | controller | skipping: Conditional result was False 2026-06-08 07:49:51.242326 | 2026-06-08 07:49:51.242402 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-06-08 07:49:51.272838 | 2026-06-08 07:49:51.273006 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-06-08 07:49:51.308524 | 2026-06-08 07:49:51.308786 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-06-08 07:49:51.335495 | controller | skipping: Conditional result was False 2026-06-08 07:49:51.344832 | 2026-06-08 07:49:51.345004 | LOOP [fetch-subunit-output : Generate subunit file] 2026-06-08 07:49:51.381996 | 2026-06-08 07:49:51.382253 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-06-08 07:49:51.408478 | controller | skipping: Conditional result was False 2026-06-08 07:49:51.412766 | 2026-06-08 07:49:51.412831 | TASK [fetch-subunit-output : Remove the temporary file] 2026-06-08 07:49:51.437045 | controller | skipping: Conditional result was False 2026-06-08 07:49:51.444803 | 2026-06-08 07:49:51.444964 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-06-08 07:49:51.471042 | controller | skipping: Conditional result was False 2026-06-08 07:49:51.485131 | 2026-06-08 07:49:51.485235 | PLAY RECAP 2026-06-08 07:49:51.485325 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-06-08 07:49:51.485369 | 2026-06-08 07:49:51.621073 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-08 07:49:51.622340 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-06-08 07:49:52.213902 | 2026-06-08 07:49:52.214034 | PLAY [all] 2026-06-08 07:49:52.223453 | 2026-06-08 07:49:52.223522 | TASK [include_role : fetch-output] 2026-06-08 07:49:52.271586 | controller | ok 2026-06-08 07:49:52.286327 | 2026-06-08 07:49:52.286399 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-08 07:49:52.361880 | controller | skipping: Conditional result was False 2026-06-08 07:49:52.371170 | 2026-06-08 07:49:52.371310 | TASK [fetch-output : Set log path for single node] 2026-06-08 07:49:52.424768 | controller | ok 2026-06-08 07:49:52.432573 | 2026-06-08 07:49:52.432711 | LOOP [fetch-output : Ensure local output dirs] 2026-06-08 07:49:52.951973 | controller -> localhost | changed: "/var/lib/zuul/builds/e5c26fa8a6764dc18c13df83e98f1fde/work/logs" 2026-06-08 07:49:53.234702 | controller -> localhost | changed: "/var/lib/zuul/builds/e5c26fa8a6764dc18c13df83e98f1fde/work/artifacts" 2026-06-08 07:49:53.529993 | controller -> localhost | changed: "/var/lib/zuul/builds/e5c26fa8a6764dc18c13df83e98f1fde/work/docs" 2026-06-08 07:49:53.545242 | 2026-06-08 07:49:53.545434 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-08 07:49:54.247562 | controller | changed: .d..t...... ./ 2026-06-08 07:49:54.247987 | controller | changed: All items complete 2026-06-08 07:49:54.248048 | 2026-06-08 07:49:54.852018 | controller | changed: .d..t...... ./ 2026-06-08 07:49:55.412051 | controller | changed: .d..t...... ./ 2026-06-08 07:49:55.433412 | 2026-06-08 07:49:55.433569 | TASK [include_role : fetch-output-openshift] 2026-06-08 07:49:55.460394 | controller | skipping: Conditional result was False 2026-06-08 07:49:55.470247 | 2026-06-08 07:49:55.470454 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-08 07:49:56.021911 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.012399 2026-06-08 07:49:56.307730 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.014267 2026-06-08 07:49:56.331668 | 2026-06-08 07:49:56.331929 | PLAY [all] 2026-06-08 07:49:56.341706 | 2026-06-08 07:49:56.341888 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-08 07:49:56.849383 | controller | changed 2026-06-08 07:49:56.856788 | 2026-06-08 07:49:56.856884 | PLAY RECAP 2026-06-08 07:49:56.856950 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-08 07:49:56.856979 | 2026-06-08 07:49:57.014497 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-06-08 07:49:57.015691 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2026-06-08 07:49:57.728791 | 2026-06-08 07:49:57.728980 | PLAY [localhost] 2026-06-08 07:49:57.739994 | 2026-06-08 07:49:57.740085 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-08 07:49:58.233830 | localhost | changed 2026-06-08 07:49:58.243753 | 2026-06-08 07:49:58.243917 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-08 07:49:58.282125 | localhost | ok 2026-06-08 07:49:58.288461 | 2026-06-08 07:49:58.288541 | TASK [add-fileserver : Create SSH private key tempfile] 2026-06-08 07:49:58.744976 | localhost | changed 2026-06-08 07:49:58.755096 | 2026-06-08 07:49:58.755233 | TASK [add-fileserver : Create SSH private key from secret] 2026-06-08 07:49:59.678550 | localhost | changed 2026-06-08 07:49:59.682918 | 2026-06-08 07:49:59.682979 | TASK [add-fileserver : Add fileserver ssh key] 2026-06-08 07:50:00.221652 | localhost | Identity added: /var/lib/zuul/builds/e5c26fa8a6764dc18c13df83e98f1fde/work/tmp/ansible.vf2x7m62 (/var/lib/zuul/builds/e5c26fa8a6764dc18c13df83e98f1fde/work/tmp/ansible.vf2x7m62) 2026-06-08 07:50:00.222049 | localhost | ok: Runtime: 0:00:00.016959 2026-06-08 07:50:00.230030 | 2026-06-08 07:50:00.230166 | TASK [add-fileserver : Remove SSH private key from disk] 2026-06-08 07:50:00.566376 | localhost | ok: Runtime: 0:00:00.010223 2026-06-08 07:50:00.575266 | 2026-06-08 07:50:00.575422 | TASK [add-fileserver : Add fileserver to inventory] 2026-06-08 07:50:00.660370 | localhost | changed 2026-06-08 07:50:00.666379 | 2026-06-08 07:50:00.666456 | TASK [add-fileserver : Add fileserver server to known hosts] 2026-06-08 07:50:01.138551 | localhost | changed 2026-06-08 07:50:01.143702 | 2026-06-08 07:50:01.143795 | PLAY [localhost] 2026-06-08 07:50:01.148171 | 2026-06-08 07:50:01.148229 | TASK [Generate bulk log download script] 2026-06-08 07:50:01.165789 | localhost | ok 2026-06-08 07:50:01.175670 | 2026-06-08 07:50:01.175728 | TASK [local-log-download : Check API endpoint is defined] 2026-06-08 07:50:01.202569 | localhost | ok: All assertions passed 2026-06-08 07:50:01.206055 | 2026-06-08 07:50:01.206156 | TASK [local-log-download : Create download script] 2026-06-08 07:50:01.806917 | localhost -> localhost | changed 2026-06-08 07:50:01.847815 | 2026-06-08 07:50:01.847984 | TASK [Register quick-download link] 2026-06-08 07:50:01.875931 | localhost | ok 2026-06-08 07:50:01.877825 | 2026-06-08 07:50:01.877898 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com] 2026-06-08 07:50:01.882460 | 2026-06-08 07:50:01.882517 | TASK [Set zuul-log-path fact] 2026-06-08 07:50:01.907655 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-08 07:50:01.915637 | 2026-06-08 07:50:01.915736 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-08 07:50:01.943742 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-08 07:50:01.947280 | 2026-06-08 07:50:01.947370 | TASK [upload-logs : Create log directories] 2026-06-08 07:50:02.917667 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed 2026-06-08 07:50:02.921307 | 2026-06-08 07:50:02.921442 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-08 07:50:03.284470 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.010490 2026-06-08 07:50:03.290786 | 2026-06-08 07:50:03.290961 | TASK [upload-logs : Upload logs to log server] 2026-06-08 07:50:04.606990 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | Output suppressed because no_log was given 2026-06-08 07:50:04.610436 | 2026-06-08 07:50:04.610574 | LOOP [upload-logs : Compress console log and json output]