2026-06-17 08:25:26.726846 | Job console starting 2026-06-17 08:25:26.860225 | Updating git repos 2026-06-17 08:25:26.899214 | Cloning repos into workspace 2026-06-17 08:25:27.514305 | Restoring repo states 2026-06-17 08:25:27.526664 | Merging changes 2026-06-17 08:25:28.680988 | Checking out repos 2026-06-17 08:25:28.786927 | Preparing playbooks 2026-06-17 08:25:32.485177 | Running Ansible setup 2026-06-17 08:25:37.186819 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-06-17 08:25:37.783010 | 2026-06-17 08:25:37.783151 | PLAY [localhost] 2026-06-17 08:25:37.791961 | 2026-06-17 08:25:37.792071 | TASK [Gathering Facts] 2026-06-17 08:25:38.741331 | localhost | ok 2026-06-17 08:25:38.749903 | 2026-06-17 08:25:38.750019 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-17 08:25:39.191757 | localhost -> localhost | changed 2026-06-17 08:25:39.198357 | 2026-06-17 08:25:39.198430 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-17 08:25:40.120598 | localhost -> localhost | changed 2026-06-17 08:25:40.126292 | 2026-06-17 08:25:40.126358 | TASK [Setup log path fact] 2026-06-17 08:25:40.153281 | localhost | ok 2026-06-17 08:25:40.174301 | 2026-06-17 08:25:40.174564 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-17 08:25:40.198916 | localhost | ok 2026-06-17 08:25:40.210844 | 2026-06-17 08:25:40.210996 | TASK [emit-job-header : Print job information] 2026-06-17 08:25:40.242938 | # Job Information 2026-06-17 08:25:40.243146 | Ansible Version: 2.16.18 2026-06-17 08:25:40.243189 | Job: cifmw-molecule-cifmw_setup 2026-06-17 08:25:40.243223 | Pipeline: github-check 2026-06-17 08:25:40.243256 | Executor: 38.102.83.32 2026-06-17 08:25:40.243284 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3955 2026-06-17 08:25:40.243315 | Log URL (when completed): https://gateway-cloud-softwarefactory.apps.ocp.cloud.ci.centos.org/logs/f41/rdoproject.org/f417738dd12f46faaaef0d22f8b3bcad/ 2026-06-17 08:25:40.243346 | Event ID: dfbc4d70-6a25-11f1-8356-e24f4a346c6c 2026-06-17 08:25:40.247706 | 2026-06-17 08:25:40.247792 | LOOP [emit-job-header : Print node information] 2026-06-17 08:25:40.325669 | localhost | ok: 2026-06-17 08:25:40.325958 | localhost | # Node Information 2026-06-17 08:25:40.326030 | localhost | Inventory Hostname: controller 2026-06-17 08:25:40.326089 | localhost | Hostname: np0000077130 2026-06-17 08:25:40.326250 | localhost | Username: zuul 2026-06-17 08:25:40.326309 | localhost | Distro: CentOS 9 2026-06-17 08:25:40.326359 | localhost | Provider: vexxhost-nodepool-tripleo 2026-06-17 08:25:40.326406 | localhost | Region: RegionOne 2026-06-17 08:25:40.326452 | localhost | Label: cloud-centos-9-stream-tripleo 2026-06-17 08:25:40.326498 | localhost | Product Name: OpenStack Nova 2026-06-17 08:25:40.326644 | localhost | Interface IP: 38.102.83.69 2026-06-17 08:25:40.342919 | 2026-06-17 08:25:40.343084 | PLAY [all] 2026-06-17 08:25:40.355239 | 2026-06-17 08:25:40.355437 | TASK [Gather network facts] 2026-06-17 08:25:40.836260 | controller | ok 2026-06-17 08:25:40.850036 | 2026-06-17 08:25:40.850172 | TASK [include_role : start-zuul-console] 2026-06-17 08:25:40.873991 | controller | ok 2026-06-17 08:25:40.893625 | 2026-06-17 08:25:40.893763 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-06-17 08:25:41.328381 | controller | ok 2026-06-17 08:25:41.336938 | 2026-06-17 08:25:41.337026 | TASK [include_role : add-build-sshkey] 2026-06-17 08:25:41.367986 | controller | ok 2026-06-17 08:25:41.389779 | 2026-06-17 08:25:41.389912 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-17 08:25:41.683004 | controller -> localhost | ok 2026-06-17 08:25:41.689131 | 2026-06-17 08:25:41.689214 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-17 08:25:41.710985 | controller | ok 2026-06-17 08:25:41.732180 | controller | included: /var/lib/zuul/builds/f417738dd12f46faaaef0d22f8b3bcad/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-17 08:25:41.738662 | 2026-06-17 08:25:41.738747 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-17 08:25:42.396326 | controller -> localhost | Generating public/private rsa key pair. 2026-06-17 08:25:42.396760 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/f417738dd12f46faaaef0d22f8b3bcad/work/f417738dd12f46faaaef0d22f8b3bcad_id_rsa 2026-06-17 08:25:42.396817 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/f417738dd12f46faaaef0d22f8b3bcad/work/f417738dd12f46faaaef0d22f8b3bcad_id_rsa.pub 2026-06-17 08:25:42.396861 | controller -> localhost | The key fingerprint is: 2026-06-17 08:25:42.396898 | controller -> localhost | SHA256:9SapBcsmkwBRMwHlRGrFa/ClQ/5aRms0l2+SyeE6k20 zuul-build-sshkey 2026-06-17 08:25:42.396934 | controller -> localhost | The key's randomart image is: 2026-06-17 08:25:42.396970 | controller -> localhost | +---[RSA 3072]----+ 2026-06-17 08:25:42.397005 | controller -> localhost | | +BX. | 2026-06-17 08:25:42.397059 | controller -> localhost | | .*oo. | 2026-06-17 08:25:42.397100 | controller -> localhost | | o=o+ ... | 2026-06-17 08:25:42.397138 | controller -> localhost | | . B.+o++ o | 2026-06-17 08:25:42.397177 | controller -> localhost | | . =+*S=+ o | 2026-06-17 08:25:42.397214 | controller -> localhost | | *+*ooo | 2026-06-17 08:25:42.397246 | controller -> localhost | | = +.o | 2026-06-17 08:25:42.397283 | controller -> localhost | | . = E | 2026-06-17 08:25:42.397317 | controller -> localhost | | + | 2026-06-17 08:25:42.397352 | controller -> localhost | +----[SHA256]-----+ 2026-06-17 08:25:42.397434 | controller -> localhost | ok: Runtime: 0:00:00.205084 2026-06-17 08:25:42.404945 | 2026-06-17 08:25:42.405131 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-17 08:25:42.447758 | controller | ok 2026-06-17 08:25:42.462143 | controller | included: /var/lib/zuul/builds/f417738dd12f46faaaef0d22f8b3bcad/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-17 08:25:42.485093 | 2026-06-17 08:25:42.485318 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-17 08:25:42.510922 | controller | skipping: Conditional result was False 2026-06-17 08:25:42.518881 | 2026-06-17 08:25:42.519007 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-17 08:25:42.986004 | controller | changed 2026-06-17 08:25:42.989766 | 2026-06-17 08:25:42.989829 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-17 08:25:43.261078 | controller | ok 2026-06-17 08:25:43.265945 | 2026-06-17 08:25:43.266023 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-17 08:25:44.156014 | controller | changed 2026-06-17 08:25:44.164102 | 2026-06-17 08:25:44.164253 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-17 08:25:45.072872 | controller | changed 2026-06-17 08:25:45.081173 | 2026-06-17 08:25:45.081310 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-17 08:25:45.117964 | controller | skipping: Conditional result was False 2026-06-17 08:25:45.127707 | 2026-06-17 08:25:45.127933 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-17 08:25:45.550145 | controller -> localhost | changed 2026-06-17 08:25:45.566460 | 2026-06-17 08:25:45.566689 | TASK [add-build-sshkey : Add back temp key] 2026-06-17 08:25:45.919097 | controller -> localhost | Identity added: /var/lib/zuul/builds/f417738dd12f46faaaef0d22f8b3bcad/work/f417738dd12f46faaaef0d22f8b3bcad_id_rsa (zuul-build-sshkey) 2026-06-17 08:25:45.919405 | controller -> localhost | ok: Runtime: 0:00:00.019355 2026-06-17 08:25:45.927774 | 2026-06-17 08:25:45.927916 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-17 08:25:46.275148 | controller | ok 2026-06-17 08:25:46.282210 | 2026-06-17 08:25:46.282366 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-17 08:25:46.307856 | controller | skipping: Conditional result was False 2026-06-17 08:25:46.321853 | 2026-06-17 08:25:46.322006 | TASK [include_role : validate-host] 2026-06-17 08:25:46.347862 | controller | ok 2026-06-17 08:25:46.387343 | 2026-06-17 08:25:46.387480 | TASK [validate-host : Define zuul_info_dir fact] 2026-06-17 08:25:46.431494 | controller | ok 2026-06-17 08:25:46.438811 | 2026-06-17 08:25:46.438967 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-06-17 08:25:46.721861 | controller -> localhost | ok 2026-06-17 08:25:46.726498 | 2026-06-17 08:25:46.726587 | TASK [validate-host : Collect information about the host] 2026-06-17 08:25:47.445131 | controller | ok 2026-06-17 08:25:47.461054 | 2026-06-17 08:25:47.461182 | TASK [validate-host : Sanitize hostname] 2026-06-17 08:25:47.533207 | controller | ok 2026-06-17 08:25:47.541028 | 2026-06-17 08:25:47.541160 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-06-17 08:25:48.022727 | controller -> localhost | changed 2026-06-17 08:25:48.028548 | 2026-06-17 08:25:48.028624 | TASK [validate-host : Collect information about zuul worker] 2026-06-17 08:25:48.526738 | controller | ok 2026-06-17 08:25:48.535814 | 2026-06-17 08:25:48.535969 | TASK [validate-host : Write out all zuul information for each host] 2026-06-17 08:25:48.978877 | controller -> localhost | changed 2026-06-17 08:25:48.988390 | 2026-06-17 08:25:48.988482 | TASK [include_role : prepare-workspace-openshift] 2026-06-17 08:25:49.003090 | controller | skipping: Conditional result was False 2026-06-17 08:25:49.008624 | 2026-06-17 08:25:49.008715 | TASK [include_role : remove-zuul-sshkey] 2026-06-17 08:25:49.023088 | controller | skipping: Conditional result was False 2026-06-17 08:25:49.028605 | 2026-06-17 08:25:49.028698 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-17 08:25:49.262642 | controller | ok: "logs" 2026-06-17 08:25:49.262881 | controller | ok: All items complete 2026-06-17 08:25:49.262908 | 2026-06-17 08:25:49.496937 | controller | ok: "artifacts" 2026-06-17 08:25:49.691268 | controller | ok: "docs" 2026-06-17 08:25:49.697438 | 2026-06-17 08:25:49.697596 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-17 08:25:49.941995 | controller | changed: "logs" 2026-06-17 08:25:50.162503 | controller | changed: "artifacts" 2026-06-17 08:25:50.401817 | controller | changed: "docs" 2026-06-17 08:25:50.414528 | 2026-06-17 08:25:50.414627 | PLAY RECAP 2026-06-17 08:25:50.414674 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2026-06-17 08:25:50.414701 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-17 08:25:50.414719 | 2026-06-17 08:25:50.570464 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-06-17 08:25:50.572114 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-06-17 08:25:51.215728 | 2026-06-17 08:25:51.215855 | PLAY [localhost] 2026-06-17 08:25:51.224432 | 2026-06-17 08:25:51.224529 | TASK [configure-ssh-config-fips : Ensure ssh config directory] 2026-06-17 08:25:51.649400 | localhost | changed 2026-06-17 08:25:51.658091 | 2026-06-17 08:25:51.658266 | TASK [configure-ssh-config-fips : Setup ssh config] 2026-06-17 08:25:52.454266 | localhost | changed 2026-06-17 08:25:52.466556 | 2026-06-17 08:25:52.466676 | PLAY [all] 2026-06-17 08:25:52.477293 | 2026-06-17 08:25:52.477421 | TASK [include_role : prepare-workspace] 2026-06-17 08:25:52.511599 | controller | ok 2026-06-17 08:25:52.535609 | 2026-06-17 08:25:52.535761 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-17 08:25:52.943010 | controller | ok 2026-06-17 08:25:52.954219 | 2026-06-17 08:25:52.954357 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-17 08:25:54.460829 | controller | Output suppressed because no_log was given 2026-06-17 08:25:54.476915 | 2026-06-17 08:25:54.477059 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-17 08:25:54.768295 | controller | changed: "logs" 2026-06-17 08:25:54.966721 | controller | changed: "artifacts" 2026-06-17 08:25:55.185086 | controller | changed: "docs" 2026-06-17 08:25:55.202423 | 2026-06-17 08:25:55.202655 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-17 08:25:55.493351 | controller | changed: "logs" 2026-06-17 08:25:55.493700 | controller | changed: All items complete 2026-06-17 08:25:55.493748 | 2026-06-17 08:25:55.750696 | controller | changed: "artifacts" 2026-06-17 08:25:55.957045 | controller | changed: "docs" 2026-06-17 08:25:55.983148 | 2026-06-17 08:25:55.983413 | TASK [Check if worker can sudo] 2026-06-17 08:25:56.518135 | controller | ok: Runtime: 0:00:00.052114 2026-06-17 08:25:56.526677 | 2026-06-17 08:25:56.526827 | TASK [configure-mirrors : Gather needed facts] 2026-06-17 08:25:56.605901 | controller | skipping: Conditional result was False 2026-06-17 08:25:56.614943 | 2026-06-17 08:25:56.615100 | TASK [configure-mirrors : Set up infrastructure mirrors] 2026-06-17 08:25:56.699972 | controller | ok 2026-06-17 08:25:56.716296 | controller | included: /var/lib/zuul/builds/f417738dd12f46faaaef0d22f8b3bcad/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml 2026-06-17 08:25:56.730794 | 2026-06-17 08:25:56.730969 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir] 2026-06-17 08:25:57.032990 | controller | ok 2026-06-17 08:25:57.042687 | 2026-06-17 08:25:57.042841 | LOOP [configure-mirrors : Include OS-specific variables] 2026-06-17 08:25:57.134483 | controller | ok: "/var/lib/zuul/builds/f417738dd12f46faaaef0d22f8b3bcad/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml" 2026-06-17 08:25:57.154407 | 2026-06-17 08:25:57.154600 | TASK [configure-mirrors : Install /etc/pip.conf configuration] 2026-06-17 08:25:58.008751 | controller | changed 2026-06-17 08:25:58.017136 | 2026-06-17 08:25:58.017258 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors] 2026-06-17 08:25:58.170556 | controller | ok: "/var/lib/zuul/builds/f417738dd12f46faaaef0d22f8b3bcad/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml" 2026-06-17 08:25:58.170736 | controller | ok: All items complete 2026-06-17 08:25:58.170773 | 2026-06-17 08:25:58.241023 | controller | included: /var/lib/zuul/builds/f417738dd12f46faaaef0d22f8b3bcad/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml 2026-06-17 08:25:58.246742 | 2026-06-17 08:25:58.246805 | LOOP [configure-mirrors : Install CentOS 9 repository files] 2026-06-17 08:25:59.156377 | controller | changed: "etc/yum.repos.d/centos.repo" 2026-06-17 08:26:00.088116 | controller | changed: "etc/yum.repos.d/centos-addons.repo" 2026-06-17 08:26:00.108148 | 2026-06-17 08:26:00.108362 | TASK [configure-mirrors : Disable deltrarpm] 2026-06-17 08:26:00.633114 | controller | changed: section and option added 2026-06-17 08:26:00.661774 | 2026-06-17 08:26:00.661948 | LOOP [configure-mirrors : Update yum/dnf cache] 2026-06-17 08:26:01.471981 | controller | 29 files removed 2026-06-17 08:26:01.472387 | controller | ok: Item: dnf clean all Runtime: 0:00:00.479374 2026-06-17 08:26:01.472474 | controller | changed: All items complete 2026-06-17 08:26:01.472565 | 2026-06-17 08:26:13.820849 | 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-17 08:26:13.820984 | controller | DNF version: 4.14.0 2026-06-17 08:26:13.821026 | controller | cachedir: /var/cache/dnf 2026-06-17 08:26:13.821116 | controller | Making cache files for all metadata files. 2026-06-17 08:26:13.821151 | controller | baseos: has expired and will be refreshed. 2026-06-17 08:26:13.821187 | controller | appstream: has expired and will be refreshed. 2026-06-17 08:26:13.821214 | controller | crb: has expired and will be refreshed. 2026-06-17 08:26:13.821249 | controller | extras-common: has expired and will be refreshed. 2026-06-17 08:26:13.821283 | controller | repo: downloading from remote: baseos 2026-06-17 08:26:13.821314 | controller | CentOS Stream 9 - BaseOS 70 MB/s | 8.9 MB 00:00 2026-06-17 08:26:13.821348 | controller | baseos: using metadata from Mon 15 Jun 2026 11:14:06 AM EDT. 2026-06-17 08:26:13.821380 | controller | repo: downloading from remote: appstream 2026-06-17 08:26:13.821412 | controller | CentOS Stream 9 - AppStream 106 MB/s | 28 MB 00:00 2026-06-17 08:26:13.821438 | controller | appstream: using metadata from Mon 15 Jun 2026 11:17:44 AM EDT. 2026-06-17 08:26:13.821463 | controller | repo: downloading from remote: crb 2026-06-17 08:26:13.821486 | controller | CentOS Stream 9 - CRB 81 MB/s | 8.3 MB 00:00 2026-06-17 08:26:13.821527 | controller | crb: using metadata from Mon 15 Jun 2026 11:22:13 AM EDT. 2026-06-17 08:26:13.821563 | controller | repo: downloading from remote: extras-common 2026-06-17 08:26:13.821589 | controller | CentOS Stream 9 - Extras packages 1.3 MB/s | 21 kB 00:00 2026-06-17 08:26:13.821612 | controller | extras-common: using metadata from Wed 10 Jun 2026 10:05:57 AM EDT. 2026-06-17 08:26:13.821642 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)' 2026-06-17 08:26:13.821673 | controller | Completion plugin: Generating completion cache... 2026-06-17 08:26:13.821703 | controller | Metadata cache created. 2026-06-17 08:26:13.821743 | controller | ok: Item: dnf makecache -v Runtime: 0:00:12.081852 2026-06-17 08:26:13.827792 | 2026-06-17 08:26:13.827946 | PLAY RECAP 2026-06-17 08:26:13.828038 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-17 08:26:13.828102 | localhost | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-17 08:26:13.828158 | 2026-06-17 08:26:13.997564 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-06-17 08:26:13.999031 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-06-17 08:26:14.604337 | 2026-06-17 08:26:14.604472 | PLAY [all] 2026-06-17 08:26:14.614991 | 2026-06-17 08:26:14.615119 | TASK [Install binary dependencies] 2026-06-17 08:26:14.672875 | controller | ok 2026-06-17 08:26:14.688897 | 2026-06-17 08:26:14.688997 | TASK [bindep : Include find tasks] 2026-06-17 08:26:14.727349 | controller | ok 2026-06-17 08:26:14.735204 | controller | included: /var/lib/zuul/builds/f417738dd12f46faaaef0d22f8b3bcad/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-06-17 08:26:14.741278 | 2026-06-17 08:26:14.741364 | TASK [bindep : Look for bindep.txt] 2026-06-17 08:26:15.158645 | controller | ok 2026-06-17 08:26:15.168274 | 2026-06-17 08:26:15.168410 | TASK [bindep : Define bindep_file fact] 2026-06-17 08:26:15.213093 | controller | ok 2026-06-17 08:26:15.227162 | 2026-06-17 08:26:15.228289 | TASK [bindep : Look for other-requirements.txt] 2026-06-17 08:26:15.256164 | controller | skipping: Conditional result was False 2026-06-17 08:26:15.264541 | 2026-06-17 08:26:15.264654 | TASK [bindep : Define bindep_file fact] 2026-06-17 08:26:15.300728 | controller | skipping: Conditional result was False 2026-06-17 08:26:15.308576 | 2026-06-17 08:26:15.308681 | TASK [bindep : Look for bindep fallback file] 2026-06-17 08:26:15.365954 | controller | skipping: Conditional result was False 2026-06-17 08:26:15.373569 | 2026-06-17 08:26:15.373711 | TASK [bindep : Define bindep_file fact] 2026-06-17 08:26:15.399077 | controller | skipping: Conditional result was False 2026-06-17 08:26:15.405661 | 2026-06-17 08:26:15.405759 | TASK [bindep : Include bindep tasks] 2026-06-17 08:26:15.448191 | controller | ok 2026-06-17 08:26:15.459261 | controller | included: /var/lib/zuul/builds/f417738dd12f46faaaef0d22f8b3bcad/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml 2026-06-17 08:26:15.474464 | 2026-06-17 08:26:15.474571 | TASK [bindep : Look for bindep command] 2026-06-17 08:26:15.509384 | controller | skipping: Conditional result was False 2026-06-17 08:26:15.523857 | 2026-06-17 08:26:15.524021 | TASK [bindep : Check for system bindep] 2026-06-17 08:26:16.071718 | controller | ok: Runtime: 0:00:00.006891 2026-06-17 08:26:16.080237 | 2026-06-17 08:26:16.080387 | TASK [bindep : Define bindep_command fact] 2026-06-17 08:26:16.117198 | controller | skipping: Conditional result was False 2026-06-17 08:26:16.126966 | 2026-06-17 08:26:16.127129 | TASK [bindep : Include install tasks] 2026-06-17 08:26:16.187640 | controller | ok 2026-06-17 08:26:16.200619 | controller | included: /var/lib/zuul/builds/f417738dd12f46faaaef0d22f8b3bcad/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml 2026-06-17 08:26:16.216346 | 2026-06-17 08:26:16.216533 | TASK [bindep : Create temp dir for bindep] 2026-06-17 08:26:16.585151 | controller | changed 2026-06-17 08:26:16.593390 | 2026-06-17 08:26:16.593543 | TASK [Ensure we have pip dependencies] 2026-06-17 08:26:16.619222 | controller | ok 2026-06-17 08:26:16.642080 | 2026-06-17 08:26:16.642166 | TASK [ensure-pip : Check if pip is installed] 2026-06-17 04:26:16.931645 | controller | /usr/bin/pip3 2026-06-17 04:26:16.950324 | controller | /usr/bin/python3: No module named wheel 2026-06-17 08:26:17.169220 | controller | ok: Runtime: 0:00:00.028929 2026-06-17 08:26:17.175036 | 2026-06-17 08:26:17.175147 | LOOP [ensure-pip : Install pip from packages] 2026-06-17 08:26:17.218370 | controller | ok: "/var/lib/zuul/builds/f417738dd12f46faaaef0d22f8b3bcad/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml" 2026-06-17 08:26:17.236681 | controller | included: /var/lib/zuul/builds/f417738dd12f46faaaef0d22f8b3bcad/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml 2026-06-17 08:26:17.290764 | 2026-06-17 08:26:17.290925 | TASK [ensure-pip : Install Python 3 pip] 2026-06-17 08:26:18.745287 | controller | ok: Nothing to do 2026-06-17 08:26:18.753753 | 2026-06-17 08:26:18.753883 | TASK [ensure-pip : Install Python3-wheel on older CentOS] 2026-06-17 08:26:20.338444 | controller | changed 2026-06-17 08:26:20.347904 | 2026-06-17 08:26:20.348056 | TASK [ensure-pip : Check for EPEL repository] 2026-06-17 08:26:20.414391 | controller | skipping: Conditional result was False 2026-06-17 08:26:20.422305 | 2026-06-17 08:26:20.422408 | TASK [ensure-pip : Fail with instructions when EPEL is not installed] 2026-06-17 08:26:20.477300 | controller | skipping: Conditional result was False 2026-06-17 08:26:20.485378 | 2026-06-17 08:26:20.485469 | TASK [ensure-pip : Install Python 2 pip] 2026-06-17 08:26:20.529796 | controller | skipping: Conditional result was False 2026-06-17 08:26:20.536107 | 2026-06-17 08:26:20.536201 | TASK [ensure-pip : Ensure setuptools] 2026-06-17 08:26:20.560276 | controller | skipping: Conditional result was False 2026-06-17 08:26:20.566149 | 2026-06-17 08:26:20.566246 | TASK [ensure-pip : Check for ensurepip module] 2026-06-17 08:26:21.094177 | controller | skipping: Conditional result was False 2026-06-17 08:26:21.105671 | 2026-06-17 08:26:21.105840 | TASK [ensure-pip : Ensure python3-venv] 2026-06-17 08:26:21.131633 | controller | skipping: Conditional result was False 2026-06-17 08:26:21.140951 | 2026-06-17 08:26:21.141086 | TASK [ensure-pip : Install pip from source] 2026-06-17 08:26:21.167343 | controller | skipping: Conditional result was False 2026-06-17 08:26:21.176678 | 2026-06-17 08:26:21.176815 | TASK [ensure-pip : Probe for venv python full path] 2026-06-17 04:26:21.458263 | controller | /usr/bin/python3 2026-06-17 08:26:21.726955 | controller | ok: Runtime: 0:00:00.006748 2026-06-17 08:26:21.738033 | 2026-06-17 08:26:21.738198 | TASK [ensure-pip : Set host default] 2026-06-17 08:26:21.814369 | controller | ok 2026-06-17 08:26:21.822792 | 2026-06-17 08:26:21.822927 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2026-06-17 08:26:21.898731 | controller | ok 2026-06-17 08:26:21.914282 | 2026-06-17 08:26:21.914420 | TASK [bindep : Install bindep into temporary venv] 2026-06-17 08:26:26.357358 | controller | changed 2026-06-17 08:26:26.368305 | 2026-06-17 08:26:26.368450 | TASK [bindep : Define bindep_command] 2026-06-17 08:26:26.411938 | controller | ok 2026-06-17 08:26:26.420087 | 2026-06-17 08:26:26.420215 | LOOP [bindep : Include package tasks] 2026-06-17 08:26:26.497389 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt" 2026-06-17 08:26:26.497767 | controller | ok: All items complete 2026-06-17 08:26:26.497862 | 2026-06-17 08:26:26.512302 | controller | included: /var/lib/zuul/builds/f417738dd12f46faaaef0d22f8b3bcad/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml 2026-06-17 08:26:26.533163 | 2026-06-17 08:26:26.533293 | TASK [bindep : Define bindep_run fact] 2026-06-17 08:26:26.578352 | controller | ok 2026-06-17 08:26:26.586272 | 2026-06-17 08:26:26.586398 | TASK [bindep : Get list of packages to install from bindep] 2026-06-17 04:26:28.379967 | controller | podman 2026-06-17 04:26:28.380168 | controller | python3-jmespath 2026-06-17 04:26:28.380182 | controller | python3-libvirt 2026-06-17 04:26:28.380193 | controller | python3-lxml 2026-06-17 04:26:28.380203 | controller | python3-netaddr 2026-06-17 08:26:28.622697 | controller | ok: Runtime: 0:00:01.544563 2026-06-17 08:26:28.631126 | 2026-06-17 08:26:28.631264 | TASK [bindep : Install distro packages from bindep] 2026-06-17 08:27:13.658672 | controller | changed 2026-06-17 08:27:13.664106 | 2026-06-17 08:27:13.664228 | TASK [bindep : Check that packages are installed] 2026-06-17 08:27:15.707189 | controller | ok: Runtime: 0:00:01.494233 2026-06-17 08:27:15.713466 | 2026-06-17 08:27:15.713600 | TASK [bindep : Fail if we cannot install all packages] 2026-06-17 08:27:15.738154 | controller | skipping: Conditional result was False 2026-06-17 08:27:15.746913 | 2026-06-17 08:27:15.746997 | TASK [Run test-setup role] 2026-06-17 08:27:15.764654 | controller | ok 2026-06-17 08:27:15.778257 | 2026-06-17 08:27:15.778346 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-06-17 08:27:16.018367 | controller | ok 2026-06-17 08:27:16.022356 | 2026-06-17 08:27:16.022425 | TASK [test-setup : Run tools/test-setup.sh] 2026-06-17 08:27:16.543832 | controller | skipping: Conditional result was False 2026-06-17 08:27:16.560801 | 2026-06-17 08:27:16.560986 | TASK [bindep : Remove bindep temp dir] 2026-06-17 08:27:16.931669 | controller | ok 2026-06-17 08:27:16.934248 | 2026-06-17 08:27:16.934344 | PLAY RECAP 2026-06-17 08:27:16.934427 | controller | ok: 23 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0 2026-06-17 08:27:16.934473 | 2026-06-17 08:27:17.103089 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-06-17 08:27:17.105856 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2026-06-17 08:27:17.788973 | 2026-06-17 08:27:17.789154 | PLAY [all] 2026-06-17 08:27:17.799474 | 2026-06-17 08:27:17.799613 | TASK [Abort when test_command variable is undefined] 2026-06-17 08:27:17.825743 | controller | skipping: Conditional result was False 2026-06-17 08:27:17.830948 | 2026-06-17 08:27:17.831024 | TASK [Convert test_command to list] 2026-06-17 08:27:17.886141 | controller | skipping: Conditional result was False 2026-06-17 08:27:17.890665 | 2026-06-17 08:27:17.890731 | TASK [Use test_command list] 2026-06-17 08:27:17.939550 | controller | ok 2026-06-17 08:27:17.945466 | 2026-06-17 08:27:17.945552 | LOOP [Run test_command] 2026-06-17 08:27:18.434031 | controller | no check to run 2026-06-17 08:27:18.434262 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.006015 2026-06-17 08:27:18.440168 | 2026-06-17 08:27:18.440378 | PLAY RECAP 2026-06-17 08:27:18.440462 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-17 08:27:18.440505 | 2026-06-17 08:27:18.623787 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2026-06-17 08:27:18.626905 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-17 08:27:19.327373 | 2026-06-17 08:27:19.327497 | PLAY [all] 2026-06-17 08:27:19.338596 | 2026-06-17 08:27:19.338695 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-06-17 08:27:19.712218 | controller | changed: non-zero return code 2026-06-17 08:27:19.717903 | 2026-06-17 08:27:19.718006 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-06-17 08:27:19.744502 | controller | skipping: Conditional result was False 2026-06-17 08:27:19.752014 | 2026-06-17 08:27:19.752116 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-06-17 08:27:19.789159 | 2026-06-17 08:27:19.789584 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-06-17 08:27:19.811171 | 2026-06-17 08:27:19.811349 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-06-17 08:27:19.825671 | controller | skipping: Conditional result was False 2026-06-17 08:27:19.830887 | 2026-06-17 08:27:19.830975 | LOOP [fetch-subunit-output : Generate subunit file] 2026-06-17 08:27:19.866065 | 2026-06-17 08:27:19.866326 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-06-17 08:27:19.887943 | controller | skipping: Conditional result was False 2026-06-17 08:27:19.894618 | 2026-06-17 08:27:19.894716 | TASK [fetch-subunit-output : Remove the temporary file] 2026-06-17 08:27:19.912161 | controller | skipping: Conditional result was False 2026-06-17 08:27:19.925323 | 2026-06-17 08:27:19.925463 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-06-17 08:27:19.959660 | controller | skipping: Conditional result was False 2026-06-17 08:27:19.966979 | 2026-06-17 08:27:19.967037 | PLAY RECAP 2026-06-17 08:27:19.967087 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-06-17 08:27:19.967108 | 2026-06-17 08:27:20.148760 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-17 08:27:20.151054 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-06-17 08:27:20.859058 | 2026-06-17 08:27:20.859176 | PLAY [all] 2026-06-17 08:27:20.868975 | 2026-06-17 08:27:20.869107 | TASK [include_role : fetch-output] 2026-06-17 08:27:20.908107 | controller | ok 2026-06-17 08:27:20.923108 | 2026-06-17 08:27:20.923202 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-17 08:27:20.987941 | controller | skipping: Conditional result was False 2026-06-17 08:27:20.997334 | 2026-06-17 08:27:20.997476 | TASK [fetch-output : Set log path for single node] 2026-06-17 08:27:21.040927 | controller | ok 2026-06-17 08:27:21.048693 | 2026-06-17 08:27:21.048827 | LOOP [fetch-output : Ensure local output dirs] 2026-06-17 08:27:21.596971 | controller -> localhost | changed: "/var/lib/zuul/builds/f417738dd12f46faaaef0d22f8b3bcad/work/logs" 2026-06-17 08:27:21.862403 | controller -> localhost | changed: "/var/lib/zuul/builds/f417738dd12f46faaaef0d22f8b3bcad/work/artifacts" 2026-06-17 08:27:22.111445 | controller -> localhost | changed: "/var/lib/zuul/builds/f417738dd12f46faaaef0d22f8b3bcad/work/docs" 2026-06-17 08:27:22.123226 | 2026-06-17 08:27:22.123342 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-17 08:27:22.903687 | controller | changed: .d..t...... ./ 2026-06-17 08:27:22.904138 | controller | changed: All items complete 2026-06-17 08:27:22.904213 | 2026-06-17 08:27:23.475805 | controller | changed: .d..t...... ./ 2026-06-17 08:27:24.039017 | controller | changed: .d..t...... ./ 2026-06-17 08:27:24.058034 | 2026-06-17 08:27:24.058224 | TASK [include_role : fetch-output-openshift] 2026-06-17 08:27:24.085422 | controller | skipping: Conditional result was False 2026-06-17 08:27:24.094877 | 2026-06-17 08:27:24.095014 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-17 08:27:24.603890 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.013246 2026-06-17 08:27:24.919079 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.012794 2026-06-17 08:27:24.928383 | 2026-06-17 08:27:24.928488 | PLAY [all] 2026-06-17 08:27:24.933377 | 2026-06-17 08:27:24.933440 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-17 08:27:25.431321 | controller | changed 2026-06-17 08:27:25.441290 | 2026-06-17 08:27:25.441396 | PLAY RECAP 2026-06-17 08:27:25.441484 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-17 08:27:25.441602 | 2026-06-17 08:27:25.563010 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-06-17 08:27:25.565424 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2026-06-17 08:27:26.236484 | 2026-06-17 08:27:26.236619 | PLAY [localhost] 2026-06-17 08:27:26.247275 | 2026-06-17 08:27:26.247350 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-17 08:27:26.710229 | localhost | changed 2026-06-17 08:27:26.719354 | 2026-06-17 08:27:26.719497 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-17 08:27:26.746308 | localhost | ok 2026-06-17 08:27:26.752150 | 2026-06-17 08:27:26.752214 | TASK [add-fileserver : Create SSH private key tempfile] 2026-06-17 08:27:27.184084 | localhost | changed 2026-06-17 08:27:27.189496 | 2026-06-17 08:27:27.189588 | TASK [add-fileserver : Create SSH private key from secret] 2026-06-17 08:27:28.010169 | localhost | changed 2026-06-17 08:27:28.014224 | 2026-06-17 08:27:28.014287 | TASK [add-fileserver : Add fileserver ssh key] 2026-06-17 08:27:28.467503 | localhost | Identity added: /var/lib/zuul/builds/f417738dd12f46faaaef0d22f8b3bcad/work/tmp/ansible.apl17hfc (/var/lib/zuul/builds/f417738dd12f46faaaef0d22f8b3bcad/work/tmp/ansible.apl17hfc) 2026-06-17 08:27:28.467718 | localhost | ok: Runtime: 0:00:00.020990 2026-06-17 08:27:28.471005 | 2026-06-17 08:27:28.471066 | TASK [add-fileserver : Remove SSH private key from disk] 2026-06-17 08:27:28.809650 | localhost | ok: Runtime: 0:00:00.009341 2026-06-17 08:27:28.817353 | 2026-06-17 08:27:28.817483 | TASK [add-fileserver : Add fileserver to inventory] 2026-06-17 08:27:28.901571 | localhost | changed 2026-06-17 08:27:28.910486 | 2026-06-17 08:27:28.910669 | TASK [add-fileserver : Add fileserver server to known hosts] 2026-06-17 08:27:29.355171 | localhost | changed 2026-06-17 08:27:29.360912 | 2026-06-17 08:27:29.360965 | PLAY [localhost] 2026-06-17 08:27:29.365095 | 2026-06-17 08:27:29.365155 | TASK [Generate bulk log download script] 2026-06-17 08:27:29.385626 | localhost | ok 2026-06-17 08:27:29.396096 | 2026-06-17 08:27:29.396162 | TASK [local-log-download : Check API endpoint is defined] 2026-06-17 08:27:29.445528 | localhost | ok: All assertions passed 2026-06-17 08:27:29.450418 | 2026-06-17 08:27:29.450495 | TASK [local-log-download : Create download script] 2026-06-17 08:27:29.973406 | localhost -> localhost | changed 2026-06-17 08:27:30.047721 | 2026-06-17 08:27:30.047908 | TASK [Register quick-download link] 2026-06-17 08:27:30.078448 | localhost | ok 2026-06-17 08:27:30.081254 | 2026-06-17 08:27:30.081325 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com] 2026-06-17 08:27:30.091149 | 2026-06-17 08:27:30.091233 | TASK [Set zuul-log-path fact] 2026-06-17 08:27:30.119056 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-17 08:27:30.132973 | 2026-06-17 08:27:30.133132 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-17 08:27:30.161678 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-17 08:27:30.164970 | 2026-06-17 08:27:30.165056 | TASK [upload-logs : Create log directories] 2026-06-17 08:27:31.076987 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed 2026-06-17 08:27:31.080541 | 2026-06-17 08:27:31.080670 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-17 08:27:31.392899 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.007228 2026-06-17 08:27:31.400501 | 2026-06-17 08:27:31.400674 | TASK [upload-logs : Upload logs to log server] 2026-06-17 08:27:32.788002 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | Output suppressed because no_log was given 2026-06-17 08:27:32.790628 | 2026-06-17 08:27:32.790709 | LOOP [upload-logs : Compress console log and json output]