2026-06-15 17:18:09.877140 | Job console starting 2026-06-15 17:18:09.994774 | Updating git repos 2026-06-15 17:18:11.258689 | Cloning repos into workspace 2026-06-15 17:18:12.130118 | Restoring repo states 2026-06-15 17:18:12.143920 | Merging changes 2026-06-15 17:18:13.264134 | Checking out repos 2026-06-15 17:18:13.375690 | Preparing playbooks 2026-06-15 17:18:16.868716 | Running Ansible setup 2026-06-15 17:18:20.852056 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-06-15 17:18:21.477434 | 2026-06-15 17:18:21.477548 | PLAY [localhost] 2026-06-15 17:18:21.485514 | 2026-06-15 17:18:21.485595 | TASK [Gathering Facts] 2026-06-15 17:18:22.463441 | localhost | ok 2026-06-15 17:18:22.471420 | 2026-06-15 17:18:22.471520 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-15 17:18:22.931476 | localhost -> localhost | changed 2026-06-15 17:18:22.941583 | 2026-06-15 17:18:22.941743 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-15 17:18:23.871667 | localhost -> localhost | changed 2026-06-15 17:18:23.884653 | 2026-06-15 17:18:23.884800 | TASK [Setup log path fact] 2026-06-15 17:18:23.904755 | localhost | ok 2026-06-15 17:18:23.913596 | 2026-06-15 17:18:23.913662 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-15 17:18:23.939394 | localhost | ok 2026-06-15 17:18:23.944289 | 2026-06-15 17:18:23.944367 | TASK [emit-job-header : Print job information] 2026-06-15 17:18:23.983463 | # Job Information 2026-06-15 17:18:23.983699 | Ansible Version: 2.16.18 2026-06-15 17:18:23.983754 | Job: cifmw-molecule-ci_dcn_site 2026-06-15 17:18:23.983794 | Pipeline: github-check 2026-06-15 17:18:23.983831 | Executor: 38.102.83.51 2026-06-15 17:18:23.983867 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3963 2026-06-15 17:18:23.983909 | Log URL (when completed): https://gateway-cloud-softwarefactory.apps.ocp.cloud.ci.centos.org/logs/93d/rdoproject.org/93df41bf27ac470dbff81e111c3a40b9/ 2026-06-15 17:18:23.983952 | Event ID: f3e77750-68dd-11f1-91f0-1d8cbbbf14eb 2026-06-15 17:18:23.989893 | 2026-06-15 17:18:23.990027 | LOOP [emit-job-header : Print node information] 2026-06-15 17:18:24.103296 | localhost | ok: 2026-06-15 17:18:24.103627 | localhost | # Node Information 2026-06-15 17:18:24.103688 | localhost | Inventory Hostname: controller 2026-06-15 17:18:24.103734 | localhost | Hostname: np0000074456 2026-06-15 17:18:24.103773 | localhost | Username: zuul 2026-06-15 17:18:24.103833 | localhost | Distro: CentOS 9 2026-06-15 17:18:24.103879 | localhost | Provider: vexxhost-nodepool-tripleo 2026-06-15 17:18:24.103917 | localhost | Region: RegionOne 2026-06-15 17:18:24.103952 | localhost | Label: cloud-centos-9-stream-tripleo 2026-06-15 17:18:24.103986 | localhost | Product Name: OpenStack Nova 2026-06-15 17:18:24.104021 | localhost | Interface IP: 38.102.83.36 2026-06-15 17:18:24.119892 | 2026-06-15 17:18:24.120044 | PLAY [all] 2026-06-15 17:18:24.130770 | 2026-06-15 17:18:24.130894 | TASK [Gather network facts] 2026-06-15 17:18:25.217172 | controller | ok 2026-06-15 17:18:25.229078 | 2026-06-15 17:18:25.229209 | TASK [include_role : start-zuul-console] 2026-06-15 17:18:25.254427 | controller | ok 2026-06-15 17:18:25.273603 | 2026-06-15 17:18:25.273736 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-06-15 17:18:25.782066 | controller | ok 2026-06-15 17:18:25.794560 | 2026-06-15 17:18:25.794705 | TASK [include_role : add-build-sshkey] 2026-06-15 17:18:25.826667 | controller | ok 2026-06-15 17:18:25.847814 | 2026-06-15 17:18:25.847946 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-15 17:18:26.126919 | controller -> localhost | ok 2026-06-15 17:18:26.131318 | 2026-06-15 17:18:26.131398 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-15 17:18:26.159032 | controller | ok 2026-06-15 17:18:26.171281 | controller | included: /var/lib/zuul/builds/93df41bf27ac470dbff81e111c3a40b9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-15 17:18:26.175480 | 2026-06-15 17:18:26.175539 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-15 17:18:26.925192 | controller -> localhost | Generating public/private rsa key pair. 2026-06-15 17:18:26.925506 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/93df41bf27ac470dbff81e111c3a40b9/work/93df41bf27ac470dbff81e111c3a40b9_id_rsa 2026-06-15 17:18:26.925572 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/93df41bf27ac470dbff81e111c3a40b9/work/93df41bf27ac470dbff81e111c3a40b9_id_rsa.pub 2026-06-15 17:18:26.925617 | controller -> localhost | The key fingerprint is: 2026-06-15 17:18:26.925659 | controller -> localhost | SHA256:KBdbUnzz39XhMAEDf1mwI1kaNiw7GDDrNOU4K1PrO0s zuul-build-sshkey 2026-06-15 17:18:26.925699 | controller -> localhost | The key's randomart image is: 2026-06-15 17:18:26.925738 | controller -> localhost | +---[RSA 3072]----+ 2026-06-15 17:18:26.925777 | controller -> localhost | | o.o..o*.+o. | 2026-06-15 17:18:26.925817 | controller -> localhost | | *o..=.Bo+. | 2026-06-15 17:18:26.925856 | controller -> localhost | | Bo.+.oB =+ o| 2026-06-15 17:18:26.925894 | controller -> localhost | | + =B o + ..o| 2026-06-15 17:18:26.925933 | controller -> localhost | | o.++ S . . ..| 2026-06-15 17:18:26.926536 | controller -> localhost | | +o . .| 2026-06-15 17:18:26.926587 | controller -> localhost | | E | 2026-06-15 17:18:26.926641 | controller -> localhost | | ... | 2026-06-15 17:18:26.926682 | controller -> localhost | | oo | 2026-06-15 17:18:26.926720 | controller -> localhost | +----[SHA256]-----+ 2026-06-15 17:18:26.926804 | controller -> localhost | ok: Runtime: 0:00:00.245649 2026-06-15 17:18:26.934393 | 2026-06-15 17:18:26.934542 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-15 17:18:26.967323 | controller | ok 2026-06-15 17:18:26.984068 | controller | included: /var/lib/zuul/builds/93df41bf27ac470dbff81e111c3a40b9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-15 17:18:26.998334 | 2026-06-15 17:18:26.998483 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-15 17:18:27.024118 | controller | skipping: Conditional result was False 2026-06-15 17:18:27.032627 | 2026-06-15 17:18:27.032756 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-15 17:18:27.541387 | controller | changed 2026-06-15 17:18:27.549404 | 2026-06-15 17:18:27.549577 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-15 17:18:27.827238 | controller | ok 2026-06-15 17:18:27.834868 | 2026-06-15 17:18:27.835014 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-15 17:18:28.607871 | controller | changed 2026-06-15 17:18:28.615097 | 2026-06-15 17:18:28.615229 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-15 17:18:29.372811 | controller | changed 2026-06-15 17:18:29.386923 | 2026-06-15 17:18:29.387064 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-15 17:18:29.403118 | controller | skipping: Conditional result was False 2026-06-15 17:18:29.411735 | 2026-06-15 17:18:29.411870 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-15 17:18:29.804957 | controller -> localhost | changed 2026-06-15 17:18:29.821268 | 2026-06-15 17:18:29.821438 | TASK [add-build-sshkey : Add back temp key] 2026-06-15 17:18:30.129522 | controller -> localhost | Identity added: /var/lib/zuul/builds/93df41bf27ac470dbff81e111c3a40b9/work/93df41bf27ac470dbff81e111c3a40b9_id_rsa (zuul-build-sshkey) 2026-06-15 17:18:30.129832 | controller -> localhost | ok: Runtime: 0:00:00.010114 2026-06-15 17:18:30.137253 | 2026-06-15 17:18:30.137447 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-15 17:18:30.497561 | controller | ok 2026-06-15 17:18:30.504261 | 2026-06-15 17:18:30.504429 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-15 17:18:30.541017 | controller | skipping: Conditional result was False 2026-06-15 17:18:30.555867 | 2026-06-15 17:18:30.556022 | TASK [include_role : validate-host] 2026-06-15 17:18:30.593250 | controller | ok 2026-06-15 17:18:30.624998 | 2026-06-15 17:18:30.625124 | TASK [validate-host : Define zuul_info_dir fact] 2026-06-15 17:18:30.666988 | controller | ok 2026-06-15 17:18:30.674736 | 2026-06-15 17:18:30.674897 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-06-15 17:18:30.965270 | controller -> localhost | ok 2026-06-15 17:18:30.974686 | 2026-06-15 17:18:30.974822 | TASK [validate-host : Collect information about the host] 2026-06-15 17:18:31.679967 | controller | ok 2026-06-15 17:18:31.696849 | 2026-06-15 17:18:31.697093 | TASK [validate-host : Sanitize hostname] 2026-06-15 17:18:31.780763 | controller | ok 2026-06-15 17:18:31.788334 | 2026-06-15 17:18:31.788520 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-06-15 17:18:32.301061 | controller -> localhost | changed 2026-06-15 17:18:32.315268 | 2026-06-15 17:18:32.315459 | TASK [validate-host : Collect information about zuul worker] 2026-06-15 17:18:32.753723 | controller | ok 2026-06-15 17:18:32.761737 | 2026-06-15 17:18:32.761929 | TASK [validate-host : Write out all zuul information for each host] 2026-06-15 17:18:33.285828 | controller -> localhost | changed 2026-06-15 17:18:33.299959 | 2026-06-15 17:18:33.300094 | TASK [include_role : prepare-workspace-openshift] 2026-06-15 17:18:33.326412 | controller | skipping: Conditional result was False 2026-06-15 17:18:33.336384 | 2026-06-15 17:18:33.336551 | TASK [include_role : remove-zuul-sshkey] 2026-06-15 17:18:33.353103 | controller | skipping: Conditional result was False 2026-06-15 17:18:33.361588 | 2026-06-15 17:18:33.361726 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-15 17:18:33.653488 | controller | ok: "logs" 2026-06-15 17:18:33.654019 | controller | ok: All items complete 2026-06-15 17:18:33.654088 | 2026-06-15 17:18:33.905122 | controller | ok: "artifacts" 2026-06-15 17:18:34.111213 | controller | ok: "docs" 2026-06-15 17:18:34.129246 | 2026-06-15 17:18:34.129421 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-15 17:18:34.369140 | controller | changed: "logs" 2026-06-15 17:18:34.569212 | controller | changed: "artifacts" 2026-06-15 17:18:34.831396 | controller | changed: "docs" 2026-06-15 17:18:34.843136 | 2026-06-15 17:18:34.843265 | PLAY RECAP 2026-06-15 17:18:34.843392 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2026-06-15 17:18:34.843464 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-15 17:18:34.843507 | 2026-06-15 17:18:34.985826 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-06-15 17:18:34.987020 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-06-15 17:18:35.631245 | 2026-06-15 17:18:35.631372 | PLAY [localhost] 2026-06-15 17:18:35.639729 | 2026-06-15 17:18:35.639801 | TASK [configure-ssh-config-fips : Ensure ssh config directory] 2026-06-15 17:18:36.099639 | localhost | changed 2026-06-15 17:18:36.107945 | 2026-06-15 17:18:36.108103 | TASK [configure-ssh-config-fips : Setup ssh config] 2026-06-15 17:18:36.864341 | localhost | changed 2026-06-15 17:18:36.876649 | 2026-06-15 17:18:36.876760 | PLAY [all] 2026-06-15 17:18:36.887922 | 2026-06-15 17:18:36.888041 | TASK [include_role : prepare-workspace] 2026-06-15 17:18:36.911103 | controller | ok 2026-06-15 17:18:36.936797 | 2026-06-15 17:18:36.936880 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-15 17:18:37.292127 | controller | ok 2026-06-15 17:18:37.302543 | 2026-06-15 17:18:37.302665 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-15 17:18:38.652905 | controller | Output suppressed because no_log was given 2026-06-15 17:18:38.667838 | 2026-06-15 17:18:38.667982 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-15 17:18:38.965801 | controller | changed: "logs" 2026-06-15 17:18:39.182088 | controller | changed: "artifacts" 2026-06-15 17:18:39.400927 | controller | changed: "docs" 2026-06-15 17:18:39.415533 | 2026-06-15 17:18:39.415723 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-15 17:18:39.663055 | controller | changed: "logs" 2026-06-15 17:18:39.663568 | controller | changed: All items complete 2026-06-15 17:18:39.663648 | 2026-06-15 17:18:39.870939 | controller | changed: "artifacts" 2026-06-15 17:18:40.106294 | controller | changed: "docs" 2026-06-15 17:18:40.133421 | 2026-06-15 17:18:40.133552 | TASK [Check if worker can sudo] 2026-06-15 17:18:40.681675 | controller | ok: Runtime: 0:00:00.037584 2026-06-15 17:18:40.691037 | 2026-06-15 17:18:40.691161 | TASK [configure-mirrors : Gather needed facts] 2026-06-15 17:18:40.768017 | controller | skipping: Conditional result was False 2026-06-15 17:18:40.778209 | 2026-06-15 17:18:40.778407 | TASK [configure-mirrors : Set up infrastructure mirrors] 2026-06-15 17:18:40.854244 | controller | ok 2026-06-15 17:18:40.868741 | controller | included: /var/lib/zuul/builds/93df41bf27ac470dbff81e111c3a40b9/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml 2026-06-15 17:18:40.880056 | 2026-06-15 17:18:40.880176 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir] 2026-06-15 17:18:41.166343 | controller | ok 2026-06-15 17:18:41.174957 | 2026-06-15 17:18:41.175144 | LOOP [configure-mirrors : Include OS-specific variables] 2026-06-15 17:18:41.260055 | controller | ok: "/var/lib/zuul/builds/93df41bf27ac470dbff81e111c3a40b9/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml" 2026-06-15 17:18:41.270617 | 2026-06-15 17:18:41.270769 | TASK [configure-mirrors : Install /etc/pip.conf configuration] 2026-06-15 17:18:42.102010 | controller | changed 2026-06-15 17:18:42.110712 | 2026-06-15 17:18:42.110840 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors] 2026-06-15 17:18:42.271824 | controller | ok: "/var/lib/zuul/builds/93df41bf27ac470dbff81e111c3a40b9/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml" 2026-06-15 17:18:42.272114 | controller | ok: All items complete 2026-06-15 17:18:42.272153 | 2026-06-15 17:18:42.370414 | controller | included: /var/lib/zuul/builds/93df41bf27ac470dbff81e111c3a40b9/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml 2026-06-15 17:18:42.380107 | 2026-06-15 17:18:42.380212 | LOOP [configure-mirrors : Install CentOS 9 repository files] 2026-06-15 17:18:43.253099 | controller | changed: "etc/yum.repos.d/centos.repo" 2026-06-15 17:18:44.002294 | controller | changed: "etc/yum.repos.d/centos-addons.repo" 2026-06-15 17:18:44.022937 | 2026-06-15 17:18:44.023125 | TASK [configure-mirrors : Disable deltrarpm] 2026-06-15 17:18:44.495618 | controller | changed: section and option added 2026-06-15 17:18:44.516809 | 2026-06-15 17:18:44.517007 | LOOP [configure-mirrors : Update yum/dnf cache] 2026-06-15 17:18:45.324521 | controller | 29 files removed 2026-06-15 17:18:45.324752 | controller | ok: Item: dnf clean all Runtime: 0:00:00.464605 2026-06-15 17:18:45.324788 | controller | changed: All items complete 2026-06-15 17:18:45.324808 | 2026-06-15 17:18:58.750657 | 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-15 17:18:58.750823 | controller | DNF version: 4.14.0 2026-06-15 17:18:58.750874 | controller | cachedir: /var/cache/dnf 2026-06-15 17:18:58.750915 | controller | Making cache files for all metadata files. 2026-06-15 17:18:58.750955 | controller | baseos: has expired and will be refreshed. 2026-06-15 17:18:58.751007 | controller | appstream: has expired and will be refreshed. 2026-06-15 17:18:58.751045 | controller | crb: has expired and will be refreshed. 2026-06-15 17:18:58.751082 | controller | extras-common: has expired and will be refreshed. 2026-06-15 17:18:58.751117 | controller | repo: downloading from remote: baseos 2026-06-15 17:18:58.751153 | controller | CentOS Stream 9 - BaseOS 6.3 MB/s | 8.9 MB 00:01 2026-06-15 17:18:58.751188 | controller | baseos: using metadata from Thu 11 Jun 2026 01:27:06 PM EDT. 2026-06-15 17:18:58.751224 | controller | repo: downloading from remote: appstream 2026-06-15 17:18:58.751286 | controller | CentOS Stream 9 - AppStream 55 MB/s | 28 MB 00:00 2026-06-15 17:18:58.751322 | controller | appstream: using metadata from Thu 11 Jun 2026 01:30:52 PM EDT. 2026-06-15 17:18:58.751401 | controller | repo: downloading from remote: crb 2026-06-15 17:18:58.751442 | controller | CentOS Stream 9 - CRB 76 MB/s | 8.3 MB 00:00 2026-06-15 17:18:58.751477 | controller | crb: using metadata from Thu 11 Jun 2026 01:35:27 PM EDT. 2026-06-15 17:18:58.751512 | controller | repo: downloading from remote: extras-common 2026-06-15 17:18:58.751546 | controller | CentOS Stream 9 - Extras packages 769 kB/s | 21 kB 00:00 2026-06-15 17:18:58.751580 | controller | extras-common: using metadata from Wed 10 Jun 2026 10:05:57 AM EDT. 2026-06-15 17:18:58.751613 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)' 2026-06-15 17:18:58.751647 | controller | Completion plugin: Generating completion cache... 2026-06-15 17:18:58.751682 | controller | Metadata cache created. 2026-06-15 17:18:58.751736 | controller | ok: Item: dnf makecache -v Runtime: 0:00:13.176572 2026-06-15 17:18:58.755831 | 2026-06-15 17:18:58.755935 | PLAY RECAP 2026-06-15 17:18:58.756189 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-15 17:18:58.756242 | localhost | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-15 17:18:58.756279 | 2026-06-15 17:18:58.909520 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-06-15 17:18:58.911929 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-06-15 17:18:59.555342 | 2026-06-15 17:18:59.555481 | PLAY [all] 2026-06-15 17:18:59.565852 | 2026-06-15 17:18:59.565919 | TASK [Install binary dependencies] 2026-06-15 17:18:59.635909 | controller | ok 2026-06-15 17:18:59.659640 | 2026-06-15 17:18:59.659787 | TASK [bindep : Include find tasks] 2026-06-15 17:18:59.698647 | controller | ok 2026-06-15 17:18:59.706855 | controller | included: /var/lib/zuul/builds/93df41bf27ac470dbff81e111c3a40b9/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-06-15 17:18:59.714687 | 2026-06-15 17:18:59.714772 | TASK [bindep : Look for bindep.txt] 2026-06-15 17:19:00.152042 | controller | ok 2026-06-15 17:19:00.161502 | 2026-06-15 17:19:00.161644 | TASK [bindep : Define bindep_file fact] 2026-06-15 17:19:00.205476 | controller | ok 2026-06-15 17:19:00.214169 | 2026-06-15 17:19:00.214316 | TASK [bindep : Look for other-requirements.txt] 2026-06-15 17:19:00.240529 | controller | skipping: Conditional result was False 2026-06-15 17:19:00.249054 | 2026-06-15 17:19:00.249191 | TASK [bindep : Define bindep_file fact] 2026-06-15 17:19:00.285382 | controller | skipping: Conditional result was False 2026-06-15 17:19:00.293781 | 2026-06-15 17:19:00.293916 | TASK [bindep : Look for bindep fallback file] 2026-06-15 17:19:00.330071 | controller | skipping: Conditional result was False 2026-06-15 17:19:00.338629 | 2026-06-15 17:19:00.338762 | TASK [bindep : Define bindep_file fact] 2026-06-15 17:19:00.364486 | controller | skipping: Conditional result was False 2026-06-15 17:19:00.373305 | 2026-06-15 17:19:00.373471 | TASK [bindep : Include bindep tasks] 2026-06-15 17:19:00.412452 | controller | ok 2026-06-15 17:19:00.417624 | controller | included: /var/lib/zuul/builds/93df41bf27ac470dbff81e111c3a40b9/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml 2026-06-15 17:19:00.425481 | 2026-06-15 17:19:00.425544 | TASK [bindep : Look for bindep command] 2026-06-15 17:19:00.449315 | controller | skipping: Conditional result was False 2026-06-15 17:19:00.454569 | 2026-06-15 17:19:00.454640 | TASK [bindep : Check for system bindep] 2026-06-15 17:19:00.984248 | controller | ok: Runtime: 0:00:00.005721 2026-06-15 17:19:00.992727 | 2026-06-15 17:19:00.992867 | TASK [bindep : Define bindep_command fact] 2026-06-15 17:19:01.029771 | controller | skipping: Conditional result was False 2026-06-15 17:19:01.043972 | 2026-06-15 17:19:01.044190 | TASK [bindep : Include install tasks] 2026-06-15 17:19:01.092948 | controller | ok 2026-06-15 17:19:01.108425 | controller | included: /var/lib/zuul/builds/93df41bf27ac470dbff81e111c3a40b9/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml 2026-06-15 17:19:01.122749 | 2026-06-15 17:19:01.122837 | TASK [bindep : Create temp dir for bindep] 2026-06-15 17:19:01.501199 | controller | changed 2026-06-15 17:19:01.509051 | 2026-06-15 17:19:01.509180 | TASK [Ensure we have pip dependencies] 2026-06-15 17:19:01.534212 | controller | ok 2026-06-15 17:19:01.572737 | 2026-06-15 17:19:01.572848 | TASK [ensure-pip : Check if pip is installed] 2026-06-15 13:19:01.870489 | controller | /usr/bin/pip3 2026-06-15 13:19:01.900512 | controller | /usr/bin/python3: No module named wheel 2026-06-15 17:19:02.107474 | controller | ok: Runtime: 0:00:00.039973 2026-06-15 17:19:02.117805 | 2026-06-15 17:19:02.117951 | LOOP [ensure-pip : Install pip from packages] 2026-06-15 17:19:02.163407 | controller | ok: "/var/lib/zuul/builds/93df41bf27ac470dbff81e111c3a40b9/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml" 2026-06-15 17:19:02.185689 | controller | included: /var/lib/zuul/builds/93df41bf27ac470dbff81e111c3a40b9/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml 2026-06-15 17:19:02.244880 | 2026-06-15 17:19:02.245027 | TASK [ensure-pip : Install Python 3 pip] 2026-06-15 17:19:03.693535 | controller | ok: Nothing to do 2026-06-15 17:19:03.704077 | 2026-06-15 17:19:03.704247 | TASK [ensure-pip : Install Python3-wheel on older CentOS] 2026-06-15 17:19:05.800427 | controller | changed 2026-06-15 17:19:05.808908 | 2026-06-15 17:19:05.809034 | TASK [ensure-pip : Check for EPEL repository] 2026-06-15 17:19:05.875949 | controller | skipping: Conditional result was False 2026-06-15 17:19:05.886192 | 2026-06-15 17:19:05.886334 | TASK [ensure-pip : Fail with instructions when EPEL is not installed] 2026-06-15 17:19:05.943500 | controller | skipping: Conditional result was False 2026-06-15 17:19:05.956509 | 2026-06-15 17:19:05.956675 | TASK [ensure-pip : Install Python 2 pip] 2026-06-15 17:19:06.025669 | controller | skipping: Conditional result was False 2026-06-15 17:19:06.035502 | 2026-06-15 17:19:06.035649 | TASK [ensure-pip : Ensure setuptools] 2026-06-15 17:19:06.061972 | controller | skipping: Conditional result was False 2026-06-15 17:19:06.071200 | 2026-06-15 17:19:06.071339 | TASK [ensure-pip : Check for ensurepip module] 2026-06-15 17:19:06.832243 | controller | skipping: Conditional result was False 2026-06-15 17:19:06.843007 | 2026-06-15 17:19:06.843145 | TASK [ensure-pip : Ensure python3-venv] 2026-06-15 17:19:06.868779 | controller | skipping: Conditional result was False 2026-06-15 17:19:06.878446 | 2026-06-15 17:19:06.878596 | TASK [ensure-pip : Install pip from source] 2026-06-15 17:19:06.905129 | controller | skipping: Conditional result was False 2026-06-15 17:19:06.915121 | 2026-06-15 17:19:06.915256 | TASK [ensure-pip : Probe for venv python full path] 2026-06-15 13:19:07.647769 | controller | /usr/bin/python3 2026-06-15 17:19:07.796129 | controller | ok: Runtime: 0:00:00.003327 2026-06-15 17:19:07.804252 | 2026-06-15 17:19:07.804420 | TASK [ensure-pip : Set host default] 2026-06-15 17:19:07.878195 | controller | ok 2026-06-15 17:19:07.885918 | 2026-06-15 17:19:07.886044 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2026-06-15 17:19:07.963406 | controller | ok 2026-06-15 17:19:07.979075 | 2026-06-15 17:19:07.979215 | TASK [bindep : Install bindep into temporary venv] 2026-06-15 17:19:19.773652 | controller | changed 2026-06-15 17:19:19.783132 | 2026-06-15 17:19:19.783267 | TASK [bindep : Define bindep_command] 2026-06-15 17:19:19.814781 | controller | ok 2026-06-15 17:19:19.819996 | 2026-06-15 17:19:19.820084 | LOOP [bindep : Include package tasks] 2026-06-15 17:19:19.889933 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt" 2026-06-15 17:19:19.890140 | controller | ok: All items complete 2026-06-15 17:19:19.890178 | 2026-06-15 17:19:19.912499 | controller | included: /var/lib/zuul/builds/93df41bf27ac470dbff81e111c3a40b9/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml 2026-06-15 17:19:19.937729 | 2026-06-15 17:19:19.938019 | TASK [bindep : Define bindep_run fact] 2026-06-15 17:19:19.980057 | controller | ok 2026-06-15 17:19:19.985095 | 2026-06-15 17:19:19.985181 | TASK [bindep : Get list of packages to install from bindep] 2026-06-15 13:19:21.378752 | controller | podman 2026-06-15 13:19:21.378862 | controller | python3-jmespath 2026-06-15 13:19:21.378880 | controller | python3-libvirt 2026-06-15 13:19:21.378893 | controller | python3-lxml 2026-06-15 13:19:21.378906 | controller | python3-netaddr 2026-06-15 17:19:21.520173 | controller | ok: Runtime: 0:00:01.150309 2026-06-15 17:19:21.529003 | 2026-06-15 17:19:21.529136 | TASK [bindep : Install distro packages from bindep] 2026-06-15 17:20:10.602591 | controller | changed 2026-06-15 17:20:10.611220 | 2026-06-15 17:20:10.611376 | TASK [bindep : Check that packages are installed] 2026-06-15 17:20:12.158941 | controller | ok: Runtime: 0:00:01.126038 2026-06-15 17:20:12.167195 | 2026-06-15 17:20:12.167335 | TASK [bindep : Fail if we cannot install all packages] 2026-06-15 17:20:12.203796 | controller | skipping: Conditional result was False 2026-06-15 17:20:12.222163 | 2026-06-15 17:20:12.222304 | TASK [Run test-setup role] 2026-06-15 17:20:12.256979 | controller | ok 2026-06-15 17:20:12.285506 | 2026-06-15 17:20:12.285601 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-06-15 17:20:12.552153 | controller | ok 2026-06-15 17:20:12.560117 | 2026-06-15 17:20:12.560249 | TASK [test-setup : Run tools/test-setup.sh] 2026-06-15 17:20:13.101114 | controller | skipping: Conditional result was False 2026-06-15 17:20:13.123639 | 2026-06-15 17:20:13.123784 | TASK [bindep : Remove bindep temp dir] 2026-06-15 17:20:13.545330 | controller | ok 2026-06-15 17:20:13.548114 | 2026-06-15 17:20:13.548216 | PLAY RECAP 2026-06-15 17:20:13.548307 | controller | ok: 23 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0 2026-06-15 17:20:13.548386 | 2026-06-15 17:20:13.761766 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-06-15 17:20:13.765212 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2026-06-15 17:20:14.374907 | 2026-06-15 17:20:14.375076 | PLAY [all] 2026-06-15 17:20:14.383862 | 2026-06-15 17:20:14.383931 | TASK [Abort when test_command variable is undefined] 2026-06-15 17:20:14.428343 | controller | skipping: Conditional result was False 2026-06-15 17:20:14.433847 | 2026-06-15 17:20:14.433940 | TASK [Convert test_command to list] 2026-06-15 17:20:14.488760 | controller | skipping: Conditional result was False 2026-06-15 17:20:14.497236 | 2026-06-15 17:20:14.497434 | TASK [Use test_command list] 2026-06-15 17:20:14.550946 | controller | ok 2026-06-15 17:20:14.561483 | 2026-06-15 17:20:14.561614 | LOOP [Run test_command] 2026-06-15 17:20:14.961492 | controller | no check to run 2026-06-15 17:20:14.961778 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.003551 2026-06-15 17:20:14.965994 | 2026-06-15 17:20:14.966167 | PLAY RECAP 2026-06-15 17:20:14.966248 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-15 17:20:14.966290 | 2026-06-15 17:20:15.107886 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2026-06-15 17:20:15.110570 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-15 17:20:15.734156 | 2026-06-15 17:20:15.734275 | PLAY [all] 2026-06-15 17:20:15.744499 | 2026-06-15 17:20:15.744574 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-06-15 17:20:16.099300 | controller | changed: non-zero return code 2026-06-15 17:20:16.108014 | 2026-06-15 17:20:16.108175 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-06-15 17:20:16.134675 | controller | skipping: Conditional result was False 2026-06-15 17:20:16.145027 | 2026-06-15 17:20:16.145176 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-06-15 17:20:16.190255 | 2026-06-15 17:20:16.190552 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-06-15 17:20:16.224592 | 2026-06-15 17:20:16.224844 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-06-15 17:20:16.250666 | controller | skipping: Conditional result was False 2026-06-15 17:20:16.259731 | 2026-06-15 17:20:16.259878 | LOOP [fetch-subunit-output : Generate subunit file] 2026-06-15 17:20:16.293753 | 2026-06-15 17:20:16.294007 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-06-15 17:20:16.320094 | controller | skipping: Conditional result was False 2026-06-15 17:20:16.328761 | 2026-06-15 17:20:16.328904 | TASK [fetch-subunit-output : Remove the temporary file] 2026-06-15 17:20:16.354871 | controller | skipping: Conditional result was False 2026-06-15 17:20:16.363931 | 2026-06-15 17:20:16.364067 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-06-15 17:20:16.389756 | controller | skipping: Conditional result was False 2026-06-15 17:20:16.402472 | 2026-06-15 17:20:16.402590 | PLAY RECAP 2026-06-15 17:20:16.402677 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-06-15 17:20:16.402721 | 2026-06-15 17:20:16.525635 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-15 17:20:16.528763 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-06-15 17:20:17.150591 | 2026-06-15 17:20:17.150714 | PLAY [all] 2026-06-15 17:20:17.159840 | 2026-06-15 17:20:17.159916 | TASK [include_role : fetch-output] 2026-06-15 17:20:17.197069 | controller | ok 2026-06-15 17:20:17.211638 | 2026-06-15 17:20:17.211734 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-15 17:20:17.276229 | controller | skipping: Conditional result was False 2026-06-15 17:20:17.285559 | 2026-06-15 17:20:17.285716 | TASK [fetch-output : Set log path for single node] 2026-06-15 17:20:17.328976 | controller | ok 2026-06-15 17:20:17.337446 | 2026-06-15 17:20:17.337604 | LOOP [fetch-output : Ensure local output dirs] 2026-06-15 17:20:17.806038 | controller -> localhost | changed: "/var/lib/zuul/builds/93df41bf27ac470dbff81e111c3a40b9/work/logs" 2026-06-15 17:20:18.076442 | controller -> localhost | changed: "/var/lib/zuul/builds/93df41bf27ac470dbff81e111c3a40b9/work/artifacts" 2026-06-15 17:20:18.349714 | controller -> localhost | changed: "/var/lib/zuul/builds/93df41bf27ac470dbff81e111c3a40b9/work/docs" 2026-06-15 17:20:18.361905 | 2026-06-15 17:20:18.361998 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-15 17:20:19.132481 | controller | changed: .d..t...... ./ 2026-06-15 17:20:19.132835 | controller | changed: All items complete 2026-06-15 17:20:19.132920 | 2026-06-15 17:20:19.714937 | controller | changed: .d..t...... ./ 2026-06-15 17:20:20.267373 | controller | changed: .d..t...... ./ 2026-06-15 17:20:20.294677 | 2026-06-15 17:20:20.294866 | TASK [include_role : fetch-output-openshift] 2026-06-15 17:20:20.322250 | controller | skipping: Conditional result was False 2026-06-15 17:20:20.331517 | 2026-06-15 17:20:20.331661 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-15 17:20:20.870044 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.013406 2026-06-15 17:20:21.171880 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.012619 2026-06-15 17:20:21.187809 | 2026-06-15 17:20:21.187940 | PLAY [all] 2026-06-15 17:20:21.192580 | 2026-06-15 17:20:21.192643 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-15 17:20:21.686805 | controller | changed 2026-06-15 17:20:21.695527 | 2026-06-15 17:20:21.695628 | PLAY RECAP 2026-06-15 17:20:21.695772 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-15 17:20:21.695828 | 2026-06-15 17:20:21.835651 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-06-15 17:20:21.836874 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2026-06-15 17:20:22.622353 | 2026-06-15 17:20:22.622503 | PLAY [localhost] 2026-06-15 17:20:22.632994 | 2026-06-15 17:20:22.633068 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-15 17:20:23.064576 | localhost | changed 2026-06-15 17:20:23.073907 | 2026-06-15 17:20:23.074048 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-15 17:20:23.100669 | localhost | ok 2026-06-15 17:20:23.106314 | 2026-06-15 17:20:23.106392 | TASK [add-fileserver : Create SSH private key tempfile] 2026-06-15 17:20:23.555059 | localhost | changed 2026-06-15 17:20:23.568148 | 2026-06-15 17:20:23.568306 | TASK [add-fileserver : Create SSH private key from secret] 2026-06-15 17:20:24.459282 | localhost | changed 2026-06-15 17:20:24.472256 | 2026-06-15 17:20:24.472440 | TASK [add-fileserver : Add fileserver ssh key] 2026-06-15 17:20:24.990906 | localhost | Identity added: /var/lib/zuul/builds/93df41bf27ac470dbff81e111c3a40b9/work/tmp/ansible.4ubg8c_s (/var/lib/zuul/builds/93df41bf27ac470dbff81e111c3a40b9/work/tmp/ansible.4ubg8c_s) 2026-06-15 17:20:24.991076 | localhost | ok: Runtime: 0:00:00.013419 2026-06-15 17:20:24.994343 | 2026-06-15 17:20:24.994428 | TASK [add-fileserver : Remove SSH private key from disk] 2026-06-15 17:20:25.357669 | localhost | ok: Runtime: 0:00:00.012058 2026-06-15 17:20:25.365890 | 2026-06-15 17:20:25.366017 | TASK [add-fileserver : Add fileserver to inventory] 2026-06-15 17:20:25.419491 | localhost | changed 2026-06-15 17:20:25.428456 | 2026-06-15 17:20:25.428590 | TASK [add-fileserver : Add fileserver server to known hosts] 2026-06-15 17:20:25.904791 | localhost | changed 2026-06-15 17:20:25.910092 | 2026-06-15 17:20:25.910141 | PLAY [localhost] 2026-06-15 17:20:25.914107 | 2026-06-15 17:20:25.914163 | TASK [Generate bulk log download script] 2026-06-15 17:20:25.932077 | localhost | ok 2026-06-15 17:20:25.942064 | 2026-06-15 17:20:25.942142 | TASK [local-log-download : Check API endpoint is defined] 2026-06-15 17:20:25.981139 | localhost | ok: All assertions passed 2026-06-15 17:20:25.985860 | 2026-06-15 17:20:25.985940 | TASK [local-log-download : Create download script] 2026-06-15 17:20:26.558089 | localhost -> localhost | changed 2026-06-15 17:20:26.605102 | 2026-06-15 17:20:26.605257 | TASK [Register quick-download link] 2026-06-15 17:20:26.633564 | localhost | ok 2026-06-15 17:20:26.636296 | 2026-06-15 17:20:26.636393 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com] 2026-06-15 17:20:26.642638 | 2026-06-15 17:20:26.642714 | TASK [Set zuul-log-path fact] 2026-06-15 17:20:26.659608 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-15 17:20:26.667416 | 2026-06-15 17:20:26.667497 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-15 17:20:26.705103 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-15 17:20:26.709658 | 2026-06-15 17:20:26.709784 | TASK [upload-logs : Create log directories] 2026-06-15 17:20:27.577449 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed 2026-06-15 17:20:27.580856 | 2026-06-15 17:20:27.580990 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-15 17:20:27.957275 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.009968 2026-06-15 17:20:27.963218 | 2026-06-15 17:20:27.963356 | TASK [upload-logs : Upload logs to log server] 2026-06-15 17:20:29.178640 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | Output suppressed because no_log was given 2026-06-15 17:20:29.180822 | 2026-06-15 17:20:29.180918 | LOOP [upload-logs : Compress console log and json output]