2026-06-08 07:39:28.634548 | Job console starting 2026-06-08 07:39:28.752329 | Updating git repos 2026-06-08 07:39:28.783791 | Cloning repos into workspace 2026-06-08 07:39:29.376195 | Restoring repo states 2026-06-08 07:39:29.387158 | Merging changes 2026-06-08 07:39:30.587107 | Checking out repos 2026-06-08 07:39:30.710251 | Preparing playbooks 2026-06-08 07:39:34.584606 | Running Ansible setup 2026-06-08 07:39:38.096365 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-06-08 07:39:38.710532 | 2026-06-08 07:39:38.710685 | PLAY [localhost] 2026-06-08 07:39:38.718018 | 2026-06-08 07:39:38.718089 | TASK [Gathering Facts] 2026-06-08 07:39:39.695285 | localhost | ok 2026-06-08 07:39:39.709727 | 2026-06-08 07:39:39.709939 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-08 07:39:40.181922 | localhost -> localhost | changed 2026-06-08 07:39:40.190958 | 2026-06-08 07:39:40.191103 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-08 07:39:41.148690 | localhost -> localhost | changed 2026-06-08 07:39:41.154533 | 2026-06-08 07:39:41.154616 | TASK [Setup log path fact] 2026-06-08 07:39:41.185592 | localhost | ok 2026-06-08 07:39:41.194345 | 2026-06-08 07:39:41.194429 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-08 07:39:41.220918 | localhost | ok 2026-06-08 07:39:41.225971 | 2026-06-08 07:39:41.226037 | TASK [emit-job-header : Print job information] 2026-06-08 07:39:41.252310 | # Job Information 2026-06-08 07:39:41.252470 | Ansible Version: 2.16.18 2026-06-08 07:39:41.252503 | Job: cifmw-molecule-cleanup_openstack 2026-06-08 07:39:41.252530 | Pipeline: github-check 2026-06-08 07:39:41.252553 | Executor: 38.102.83.40 2026-06-08 07:39:41.252598 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3866 2026-06-08 07:39:41.252632 | Log URL (when completed): https://gateway-cloud-softwarefactory.apps.ocp.cloud.ci.centos.org/logs/7f2/rdoproject.org/7f2617d255494c7bbe20f09be7d3820f/ 2026-06-08 07:39:41.252660 | Event ID: f0608210-630c-11f1-9eec-ab17068426d4 2026-06-08 07:39:41.256196 | 2026-06-08 07:39:41.256278 | LOOP [emit-job-header : Print node information] 2026-06-08 07:39:41.330462 | localhost | ok: 2026-06-08 07:39:41.330661 | localhost | # Node Information 2026-06-08 07:39:41.330687 | localhost | Inventory Hostname: controller 2026-06-08 07:39:41.330709 | localhost | Hostname: np0000066850 2026-06-08 07:39:41.330726 | localhost | Username: zuul 2026-06-08 07:39:41.330745 | localhost | Distro: CentOS 9 2026-06-08 07:39:41.330762 | localhost | Provider: vexxhost-nodepool-tripleo 2026-06-08 07:39:41.330778 | localhost | Region: RegionOne 2026-06-08 07:39:41.330794 | localhost | Label: cloud-centos-9-stream-tripleo 2026-06-08 07:39:41.330810 | localhost | Product Name: OpenStack Nova 2026-06-08 07:39:41.330826 | localhost | Interface IP: 38.102.83.97 2026-06-08 07:39:41.340366 | 2026-06-08 07:39:41.340495 | PLAY [all] 2026-06-08 07:39:41.351199 | 2026-06-08 07:39:41.351325 | TASK [Gather network facts] 2026-06-08 07:39:41.803650 | controller | ok 2026-06-08 07:39:41.815009 | 2026-06-08 07:39:41.815136 | TASK [include_role : start-zuul-console] 2026-06-08 07:39:41.839724 | controller | ok 2026-06-08 07:39:41.859639 | 2026-06-08 07:39:41.859785 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-06-08 07:39:42.300545 | controller | ok 2026-06-08 07:39:42.312535 | 2026-06-08 07:39:42.312694 | TASK [include_role : add-build-sshkey] 2026-06-08 07:39:42.345206 | controller | ok 2026-06-08 07:39:42.366883 | 2026-06-08 07:39:42.367019 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-08 07:39:42.666112 | controller -> localhost | ok 2026-06-08 07:39:42.675006 | 2026-06-08 07:39:42.675160 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-08 07:39:42.717361 | controller | ok 2026-06-08 07:39:42.747117 | controller | included: /var/lib/zuul/builds/7f2617d255494c7bbe20f09be7d3820f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-08 07:39:42.755995 | 2026-06-08 07:39:42.756139 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-08 07:39:43.440890 | controller -> localhost | Generating public/private rsa key pair. 2026-06-08 07:39:43.441199 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/7f2617d255494c7bbe20f09be7d3820f/work/7f2617d255494c7bbe20f09be7d3820f_id_rsa 2026-06-08 07:39:43.441263 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/7f2617d255494c7bbe20f09be7d3820f/work/7f2617d255494c7bbe20f09be7d3820f_id_rsa.pub 2026-06-08 07:39:43.441309 | controller -> localhost | The key fingerprint is: 2026-06-08 07:39:43.441350 | controller -> localhost | SHA256:8POpD8zcmuslOdFwj3+j70j9/dqOT6gYFkXG9WJ6KMM zuul-build-sshkey 2026-06-08 07:39:43.441389 | controller -> localhost | The key's randomart image is: 2026-06-08 07:39:43.441428 | controller -> localhost | +---[RSA 3072]----+ 2026-06-08 07:39:43.441465 | controller -> localhost | | .o.. | 2026-06-08 07:39:43.441504 | controller -> localhost | | o. . | 2026-06-08 07:39:43.441541 | controller -> localhost | | . . . . o .| 2026-06-08 07:39:43.441616 | controller -> localhost | | o = + + . | 2026-06-08 07:39:43.441659 | controller -> localhost | | S E + . | 2026-06-08 07:39:43.441697 | controller -> localhost | | + * * o . | 2026-06-08 07:39:43.441734 | controller -> localhost | | O B o = .| 2026-06-08 07:39:43.441771 | controller -> localhost | | X + = *.| 2026-06-08 07:39:43.441808 | controller -> localhost | | .*oo +o=+O| 2026-06-08 07:39:43.441844 | controller -> localhost | +----[SHA256]-----+ 2026-06-08 07:39:43.441928 | controller -> localhost | ok: Runtime: 0:00:00.184382 2026-06-08 07:39:43.449355 | 2026-06-08 07:39:43.449480 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-08 07:39:43.472103 | controller | ok 2026-06-08 07:39:43.488646 | controller | included: /var/lib/zuul/builds/7f2617d255494c7bbe20f09be7d3820f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-08 07:39:43.502623 | 2026-06-08 07:39:43.502719 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-08 07:39:43.527082 | controller | skipping: Conditional result was False 2026-06-08 07:39:43.532529 | 2026-06-08 07:39:43.532642 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-08 07:39:43.993774 | controller | changed 2026-06-08 07:39:44.001114 | 2026-06-08 07:39:44.001270 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-08 07:39:44.277771 | controller | ok 2026-06-08 07:39:44.285091 | 2026-06-08 07:39:44.285224 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-08 07:39:45.115622 | controller | changed 2026-06-08 07:39:45.122954 | 2026-06-08 07:39:45.123098 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-08 07:39:46.008671 | controller | changed 2026-06-08 07:39:46.016673 | 2026-06-08 07:39:46.016813 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-08 07:39:46.044139 | controller | skipping: Conditional result was False 2026-06-08 07:39:46.054273 | 2026-06-08 07:39:46.054419 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-08 07:39:46.513136 | controller -> localhost | changed 2026-06-08 07:39:46.538781 | 2026-06-08 07:39:46.539049 | TASK [add-build-sshkey : Add back temp key] 2026-06-08 07:39:46.878199 | controller -> localhost | Identity added: /var/lib/zuul/builds/7f2617d255494c7bbe20f09be7d3820f/work/7f2617d255494c7bbe20f09be7d3820f_id_rsa (zuul-build-sshkey) 2026-06-08 07:39:46.878679 | controller -> localhost | ok: Runtime: 0:00:00.015545 2026-06-08 07:39:46.886646 | 2026-06-08 07:39:46.886843 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-08 07:39:47.214212 | controller | ok 2026-06-08 07:39:47.218933 | 2026-06-08 07:39:47.219032 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-08 07:39:47.254709 | controller | skipping: Conditional result was False 2026-06-08 07:39:47.267056 | 2026-06-08 07:39:47.267220 | TASK [include_role : validate-host] 2026-06-08 07:39:47.303474 | controller | ok 2026-06-08 07:39:47.344855 | 2026-06-08 07:39:47.344986 | TASK [validate-host : Define zuul_info_dir fact] 2026-06-08 07:39:47.376939 | controller | ok 2026-06-08 07:39:47.382871 | 2026-06-08 07:39:47.382964 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-06-08 07:39:47.693513 | controller -> localhost | ok 2026-06-08 07:39:47.698489 | 2026-06-08 07:39:47.698554 | TASK [validate-host : Collect information about the host] 2026-06-08 07:39:48.457679 | controller | ok 2026-06-08 07:39:48.475829 | 2026-06-08 07:39:48.476003 | TASK [validate-host : Sanitize hostname] 2026-06-08 07:39:48.562034 | controller | ok 2026-06-08 07:39:48.572899 | 2026-06-08 07:39:48.573090 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-06-08 07:39:49.119794 | controller -> localhost | changed 2026-06-08 07:39:49.131967 | 2026-06-08 07:39:49.132109 | TASK [validate-host : Collect information about zuul worker] 2026-06-08 07:39:49.540992 | controller | ok 2026-06-08 07:39:49.551669 | 2026-06-08 07:39:49.551893 | TASK [validate-host : Write out all zuul information for each host] 2026-06-08 07:39:50.078254 | controller -> localhost | changed 2026-06-08 07:39:50.094689 | 2026-06-08 07:39:50.094826 | TASK [include_role : prepare-workspace-openshift] 2026-06-08 07:39:50.112485 | controller | skipping: Conditional result was False 2026-06-08 07:39:50.127071 | 2026-06-08 07:39:50.127213 | TASK [include_role : remove-zuul-sshkey] 2026-06-08 07:39:50.153541 | controller | skipping: Conditional result was False 2026-06-08 07:39:50.163025 | 2026-06-08 07:39:50.163292 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-08 07:39:50.435038 | controller | ok: "logs" 2026-06-08 07:39:50.435923 | controller | ok: All items complete 2026-06-08 07:39:50.436003 | 2026-06-08 07:39:50.668977 | controller | ok: "artifacts" 2026-06-08 07:39:50.868289 | controller | ok: "docs" 2026-06-08 07:39:50.888884 | 2026-06-08 07:39:50.889256 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-08 07:39:51.168855 | controller | changed: "logs" 2026-06-08 07:39:51.350810 | controller | changed: "artifacts" 2026-06-08 07:39:51.577498 | controller | changed: "docs" 2026-06-08 07:39:51.597405 | 2026-06-08 07:39:51.597567 | PLAY RECAP 2026-06-08 07:39:51.597713 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2026-06-08 07:39:51.597772 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-08 07:39:51.597811 | 2026-06-08 07:39:51.775615 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-06-08 07:39:51.777996 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-06-08 07:39:52.451908 | 2026-06-08 07:39:52.452014 | PLAY [localhost] 2026-06-08 07:39:52.460393 | 2026-06-08 07:39:52.460471 | TASK [configure-ssh-config-fips : Ensure ssh config directory] 2026-06-08 07:39:52.916142 | localhost | changed 2026-06-08 07:39:52.924641 | 2026-06-08 07:39:52.924825 | TASK [configure-ssh-config-fips : Setup ssh config] 2026-06-08 07:39:53.637703 | localhost | changed 2026-06-08 07:39:53.643813 | 2026-06-08 07:39:53.643872 | PLAY [all] 2026-06-08 07:39:53.648778 | 2026-06-08 07:39:53.648837 | TASK [include_role : prepare-workspace] 2026-06-08 07:39:53.682487 | controller | ok 2026-06-08 07:39:53.709859 | 2026-06-08 07:39:53.709946 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-08 07:39:54.136976 | controller | ok 2026-06-08 07:39:54.147812 | 2026-06-08 07:39:54.147969 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-08 07:39:55.686913 | controller | Output suppressed because no_log was given 2026-06-08 07:39:55.701418 | 2026-06-08 07:39:55.701553 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-08 07:39:55.983696 | controller | changed: "logs" 2026-06-08 07:39:56.217030 | controller | changed: "artifacts" 2026-06-08 07:39:56.455675 | controller | changed: "docs" 2026-06-08 07:39:56.473271 | 2026-06-08 07:39:56.473786 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-08 07:39:56.758276 | controller | changed: "logs" 2026-06-08 07:39:56.758708 | controller | changed: All items complete 2026-06-08 07:39:56.758768 | 2026-06-08 07:39:56.989546 | controller | changed: "artifacts" 2026-06-08 07:39:57.215467 | controller | changed: "docs" 2026-06-08 07:39:57.244154 | 2026-06-08 07:39:57.244360 | TASK [Check if worker can sudo] 2026-06-08 07:39:57.793862 | controller | ok: Runtime: 0:00:00.043315 2026-06-08 07:39:57.808114 | 2026-06-08 07:39:57.808303 | TASK [configure-mirrors : Gather needed facts] 2026-06-08 07:39:57.887735 | controller | skipping: Conditional result was False 2026-06-08 07:39:57.895274 | 2026-06-08 07:39:57.895400 | TASK [configure-mirrors : Set up infrastructure mirrors] 2026-06-08 07:39:57.988300 | controller | ok 2026-06-08 07:39:58.003266 | controller | included: /var/lib/zuul/builds/7f2617d255494c7bbe20f09be7d3820f/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml 2026-06-08 07:39:58.017379 | 2026-06-08 07:39:58.017555 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir] 2026-06-08 07:39:58.331224 | controller | ok 2026-06-08 07:39:58.337297 | 2026-06-08 07:39:58.337382 | LOOP [configure-mirrors : Include OS-specific variables] 2026-06-08 07:39:58.413810 | controller | ok: "/var/lib/zuul/builds/7f2617d255494c7bbe20f09be7d3820f/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml" 2026-06-08 07:39:58.426673 | 2026-06-08 07:39:58.426895 | TASK [configure-mirrors : Install /etc/pip.conf configuration] 2026-06-08 07:39:59.342474 | controller | changed 2026-06-08 07:39:59.352697 | 2026-06-08 07:39:59.352839 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors] 2026-06-08 07:39:59.515009 | controller | ok: "/var/lib/zuul/builds/7f2617d255494c7bbe20f09be7d3820f/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml" 2026-06-08 07:39:59.515180 | controller | ok: All items complete 2026-06-08 07:39:59.515214 | 2026-06-08 07:39:59.570222 | controller | included: /var/lib/zuul/builds/7f2617d255494c7bbe20f09be7d3820f/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml 2026-06-08 07:39:59.576894 | 2026-06-08 07:39:59.576979 | LOOP [configure-mirrors : Install CentOS 9 repository files] 2026-06-08 07:40:00.539207 | controller | changed: "etc/yum.repos.d/centos.repo" 2026-06-08 07:40:01.309296 | controller | changed: "etc/yum.repos.d/centos-addons.repo" 2026-06-08 07:40:01.326397 | 2026-06-08 07:40:01.326567 | TASK [configure-mirrors : Disable deltrarpm] 2026-06-08 07:40:01.769107 | controller | changed: section and option added 2026-06-08 07:40:01.792135 | 2026-06-08 07:40:01.792273 | LOOP [configure-mirrors : Update yum/dnf cache] 2026-06-08 07:40:02.573479 | controller | 29 files removed 2026-06-08 07:40:02.574109 | controller | ok: Item: dnf clean all Runtime: 0:00:00.434594 2026-06-08 07:40:02.574219 | controller | changed: All items complete 2026-06-08 07:40:02.574275 | 2026-06-08 07:40:13.866557 | 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:40:13.866717 | controller | DNF version: 4.14.0 2026-06-08 07:40:13.866776 | controller | cachedir: /var/cache/dnf 2026-06-08 07:40:13.866809 | controller | Making cache files for all metadata files. 2026-06-08 07:40:13.866843 | controller | baseos: has expired and will be refreshed. 2026-06-08 07:40:13.866888 | controller | appstream: has expired and will be refreshed. 2026-06-08 07:40:13.866919 | controller | crb: has expired and will be refreshed. 2026-06-08 07:40:13.866950 | controller | extras-common: has expired and will be refreshed. 2026-06-08 07:40:13.866981 | controller | repo: downloading from remote: baseos 2026-06-08 07:40:13.867076 | controller | CentOS Stream 9 - BaseOS 79 MB/s | 8.9 MB 00:00 2026-06-08 07:40:13.867105 | controller | baseos: using metadata from Mon 01 Jun 2026 05:58:58 PM EDT. 2026-06-08 07:40:13.867129 | controller | repo: downloading from remote: appstream 2026-06-08 07:40:13.867152 | controller | CentOS Stream 9 - AppStream 121 MB/s | 28 MB 00:00 2026-06-08 07:40:13.867220 | controller | appstream: using metadata from Mon 01 Jun 2026 06:02:34 PM EDT. 2026-06-08 07:40:13.867245 | controller | repo: downloading from remote: crb 2026-06-08 07:40:13.867262 | controller | CentOS Stream 9 - CRB 67 MB/s | 8.1 MB 00:00 2026-06-08 07:40:13.867279 | controller | crb: using metadata from Mon 01 Jun 2026 06:07:05 PM EDT. 2026-06-08 07:40:13.867295 | controller | repo: downloading from remote: extras-common 2026-06-08 07:40:13.867311 | controller | CentOS Stream 9 - Extras packages 75 kB/s | 21 kB 00:00 2026-06-08 07:40:13.867327 | controller | extras-common: using metadata from Tue 24 Mar 2026 03:36:22 PM EDT. 2026-06-08 07:40:13.867352 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)' 2026-06-08 07:40:13.867371 | controller | Completion plugin: Generating completion cache... 2026-06-08 07:40:13.867393 | controller | Metadata cache created. 2026-06-08 07:40:13.867420 | controller | ok: Item: dnf makecache -v Runtime: 0:00:11.055363 2026-06-08 07:40:13.872168 | 2026-06-08 07:40:13.872571 | PLAY RECAP 2026-06-08 07:40:13.872724 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-08 07:40:13.872787 | localhost | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-08 07:40:13.872827 | 2026-06-08 07:40:14.007675 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-06-08 07:40:14.008816 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-06-08 07:40:14.649440 | 2026-06-08 07:40:14.649618 | PLAY [all] 2026-06-08 07:40:14.661027 | 2026-06-08 07:40:14.661162 | TASK [Install binary dependencies] 2026-06-08 07:40:14.743501 | controller | ok 2026-06-08 07:40:14.773550 | 2026-06-08 07:40:14.773740 | TASK [bindep : Include find tasks] 2026-06-08 07:40:14.815878 | controller | ok 2026-06-08 07:40:14.828613 | controller | included: /var/lib/zuul/builds/7f2617d255494c7bbe20f09be7d3820f/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-06-08 07:40:14.838421 | 2026-06-08 07:40:14.838554 | TASK [bindep : Look for bindep.txt] 2026-06-08 07:40:15.266426 | controller | ok 2026-06-08 07:40:15.275772 | 2026-06-08 07:40:15.275945 | TASK [bindep : Define bindep_file fact] 2026-06-08 07:40:15.309240 | controller | ok 2026-06-08 07:40:15.318186 | 2026-06-08 07:40:15.318327 | TASK [bindep : Look for other-requirements.txt] 2026-06-08 07:40:15.334254 | controller | skipping: Conditional result was False 2026-06-08 07:40:15.344056 | 2026-06-08 07:40:15.344245 | TASK [bindep : Define bindep_file fact] 2026-06-08 07:40:15.370520 | controller | skipping: Conditional result was False 2026-06-08 07:40:15.379611 | 2026-06-08 07:40:15.379759 | TASK [bindep : Look for bindep fallback file] 2026-06-08 07:40:15.406475 | controller | skipping: Conditional result was False 2026-06-08 07:40:15.416201 | 2026-06-08 07:40:15.416347 | TASK [bindep : Define bindep_file fact] 2026-06-08 07:40:15.436191 | controller | skipping: Conditional result was False 2026-06-08 07:40:15.446967 | 2026-06-08 07:40:15.447110 | TASK [bindep : Include bindep tasks] 2026-06-08 07:40:15.490751 | controller | ok 2026-06-08 07:40:15.502348 | controller | included: /var/lib/zuul/builds/7f2617d255494c7bbe20f09be7d3820f/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml 2026-06-08 07:40:15.518837 | 2026-06-08 07:40:15.518925 | TASK [bindep : Look for bindep command] 2026-06-08 07:40:15.572052 | controller | skipping: Conditional result was False 2026-06-08 07:40:15.578868 | 2026-06-08 07:40:15.578954 | TASK [bindep : Check for system bindep] 2026-06-08 07:40:16.115715 | controller | ok: Runtime: 0:00:00.005327 2026-06-08 07:40:16.125005 | 2026-06-08 07:40:16.125139 | TASK [bindep : Define bindep_command fact] 2026-06-08 07:40:16.161238 | controller | skipping: Conditional result was False 2026-06-08 07:40:16.170783 | 2026-06-08 07:40:16.170920 | TASK [bindep : Include install tasks] 2026-06-08 07:40:16.214902 | controller | ok 2026-06-08 07:40:16.228231 | controller | included: /var/lib/zuul/builds/7f2617d255494c7bbe20f09be7d3820f/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml 2026-06-08 07:40:16.245567 | 2026-06-08 07:40:16.245739 | TASK [bindep : Create temp dir for bindep] 2026-06-08 07:40:16.653124 | controller | changed 2026-06-08 07:40:16.656915 | 2026-06-08 07:40:16.656976 | TASK [Ensure we have pip dependencies] 2026-06-08 07:40:16.713977 | controller | ok 2026-06-08 07:40:16.735851 | 2026-06-08 07:40:16.735920 | TASK [ensure-pip : Check if pip is installed] 2026-06-08 03:40:17.022310 | controller | /usr/bin/pip3 2026-06-08 03:40:17.063170 | controller | /usr/bin/python3: No module named wheel 2026-06-08 07:40:17.263068 | controller | ok: Runtime: 0:00:00.052599 2026-06-08 07:40:17.271495 | 2026-06-08 07:40:17.271666 | LOOP [ensure-pip : Install pip from packages] 2026-06-08 07:40:17.315553 | controller | ok: "/var/lib/zuul/builds/7f2617d255494c7bbe20f09be7d3820f/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml" 2026-06-08 07:40:17.332982 | controller | included: /var/lib/zuul/builds/7f2617d255494c7bbe20f09be7d3820f/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml 2026-06-08 07:40:17.395829 | 2026-06-08 07:40:17.396001 | TASK [ensure-pip : Install Python 3 pip] 2026-06-08 07:40:18.952135 | controller | ok: Nothing to do 2026-06-08 07:40:18.958385 | 2026-06-08 07:40:18.958501 | TASK [ensure-pip : Install Python3-wheel on older CentOS] 2026-06-08 07:40:20.543936 | controller | changed 2026-06-08 07:40:20.552788 | 2026-06-08 07:40:20.552920 | TASK [ensure-pip : Check for EPEL repository] 2026-06-08 07:40:20.620528 | controller | skipping: Conditional result was False 2026-06-08 07:40:20.636150 | 2026-06-08 07:40:20.636274 | TASK [ensure-pip : Fail with instructions when EPEL is not installed] 2026-06-08 07:40:20.704128 | controller | skipping: Conditional result was False 2026-06-08 07:40:20.714220 | 2026-06-08 07:40:20.714321 | TASK [ensure-pip : Install Python 2 pip] 2026-06-08 07:40:20.761163 | controller | skipping: Conditional result was False 2026-06-08 07:40:20.769068 | 2026-06-08 07:40:20.769158 | TASK [ensure-pip : Ensure setuptools] 2026-06-08 07:40:20.794891 | controller | skipping: Conditional result was False 2026-06-08 07:40:20.802033 | 2026-06-08 07:40:20.802171 | TASK [ensure-pip : Check for ensurepip module] 2026-06-08 07:40:21.334965 | controller | skipping: Conditional result was False 2026-06-08 07:40:21.347284 | 2026-06-08 07:40:21.347468 | TASK [ensure-pip : Ensure python3-venv] 2026-06-08 07:40:21.373458 | controller | skipping: Conditional result was False 2026-06-08 07:40:21.382917 | 2026-06-08 07:40:21.383044 | TASK [ensure-pip : Install pip from source] 2026-06-08 07:40:21.408824 | controller | skipping: Conditional result was False 2026-06-08 07:40:21.417930 | 2026-06-08 07:40:21.418056 | TASK [ensure-pip : Probe for venv python full path] 2026-06-08 03:40:21.729198 | controller | /usr/bin/python3 2026-06-08 07:40:21.963162 | controller | ok: Runtime: 0:00:00.004746 2026-06-08 07:40:21.971895 | 2026-06-08 07:40:21.972035 | TASK [ensure-pip : Set host default] 2026-06-08 07:40:22.038075 | controller | ok 2026-06-08 07:40:22.046055 | 2026-06-08 07:40:22.046186 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2026-06-08 07:40:22.104730 | controller | ok 2026-06-08 07:40:22.121881 | 2026-06-08 07:40:22.122093 | TASK [bindep : Install bindep into temporary venv] 2026-06-08 07:40:26.429402 | controller | changed 2026-06-08 07:40:26.441158 | 2026-06-08 07:40:26.441337 | TASK [bindep : Define bindep_command] 2026-06-08 07:40:26.493037 | controller | ok 2026-06-08 07:40:26.503067 | 2026-06-08 07:40:26.503247 | LOOP [bindep : Include package tasks] 2026-06-08 07:40:26.585925 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt" 2026-06-08 07:40:26.586272 | controller | ok: All items complete 2026-06-08 07:40:26.586329 | 2026-06-08 07:40:26.605402 | controller | included: /var/lib/zuul/builds/7f2617d255494c7bbe20f09be7d3820f/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml 2026-06-08 07:40:26.636692 | 2026-06-08 07:40:26.636956 | TASK [bindep : Define bindep_run fact] 2026-06-08 07:40:26.684757 | controller | ok 2026-06-08 07:40:26.690424 | 2026-06-08 07:40:26.690537 | TASK [bindep : Get list of packages to install from bindep] 2026-06-08 03:40:27.901437 | controller | podman 2026-06-08 03:40:27.901524 | controller | python3-jmespath 2026-06-08 03:40:27.901542 | controller | python3-libvirt 2026-06-08 03:40:27.901556 | controller | python3-lxml 2026-06-08 03:40:27.901569 | controller | python3-netaddr 2026-06-08 07:40:28.234436 | controller | ok: Runtime: 0:00:00.989520 2026-06-08 07:40:28.243851 | 2026-06-08 07:40:28.244102 | TASK [bindep : Install distro packages from bindep] 2026-06-08 07:41:12.307491 | controller | changed 2026-06-08 07:41:12.313187 | 2026-06-08 07:41:12.313274 | TASK [bindep : Check that packages are installed] 2026-06-08 07:41:13.850115 | controller | ok: Runtime: 0:00:01.074371 2026-06-08 07:41:13.858874 | 2026-06-08 07:41:13.859021 | TASK [bindep : Fail if we cannot install all packages] 2026-06-08 07:41:13.897445 | controller | skipping: Conditional result was False 2026-06-08 07:41:13.915484 | 2026-06-08 07:41:13.915717 | TASK [Run test-setup role] 2026-06-08 07:41:13.943916 | controller | ok 2026-06-08 07:41:13.971849 | 2026-06-08 07:41:13.972037 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-06-08 07:41:14.229946 | controller | ok 2026-06-08 07:41:14.238158 | 2026-06-08 07:41:14.238301 | TASK [test-setup : Run tools/test-setup.sh] 2026-06-08 07:41:14.781850 | controller | skipping: Conditional result was False 2026-06-08 07:41:14.792409 | 2026-06-08 07:41:14.792477 | TASK [bindep : Remove bindep temp dir] 2026-06-08 07:41:15.211480 | controller | ok 2026-06-08 07:41:15.214016 | 2026-06-08 07:41:15.214118 | PLAY RECAP 2026-06-08 07:41:15.214210 | controller | ok: 23 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0 2026-06-08 07:41:15.214256 | 2026-06-08 07:41:15.347816 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-06-08 07:41:15.350425 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2026-06-08 07:41:15.898466 | 2026-06-08 07:41:15.898604 | PLAY [all] 2026-06-08 07:41:15.907727 | 2026-06-08 07:41:15.907843 | TASK [Abort when test_command variable is undefined] 2026-06-08 07:41:15.942103 | controller | skipping: Conditional result was False 2026-06-08 07:41:15.946400 | 2026-06-08 07:41:15.946480 | TASK [Convert test_command to list] 2026-06-08 07:41:16.000934 | controller | skipping: Conditional result was False 2026-06-08 07:41:16.007395 | 2026-06-08 07:41:16.007498 | TASK [Use test_command list] 2026-06-08 07:41:16.056759 | controller | ok 2026-06-08 07:41:16.063701 | 2026-06-08 07:41:16.063892 | LOOP [Run test_command] 2026-06-08 07:41:16.511670 | controller | no check to run 2026-06-08 07:41:16.511966 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.005044 2026-06-08 07:41:16.521940 | 2026-06-08 07:41:16.522095 | PLAY RECAP 2026-06-08 07:41:16.522178 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-08 07:41:16.522222 | 2026-06-08 07:41:16.645699 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2026-06-08 07:41:16.647324 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-08 07:41:17.273282 | 2026-06-08 07:41:17.273389 | PLAY [all] 2026-06-08 07:41:17.283502 | 2026-06-08 07:41:17.283589 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-06-08 07:41:17.692882 | controller | changed: non-zero return code 2026-06-08 07:41:17.702947 | 2026-06-08 07:41:17.703107 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-06-08 07:41:17.729735 | controller | skipping: Conditional result was False 2026-06-08 07:41:17.740724 | 2026-06-08 07:41:17.740871 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-06-08 07:41:17.779698 | 2026-06-08 07:41:17.780017 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-06-08 07:41:17.820633 | 2026-06-08 07:41:17.820920 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-06-08 07:41:17.847949 | controller | skipping: Conditional result was False 2026-06-08 07:41:17.858258 | 2026-06-08 07:41:17.858425 | LOOP [fetch-subunit-output : Generate subunit file] 2026-06-08 07:41:17.893699 | 2026-06-08 07:41:17.893982 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-06-08 07:41:17.921044 | controller | skipping: Conditional result was False 2026-06-08 07:41:17.929890 | 2026-06-08 07:41:17.930030 | TASK [fetch-subunit-output : Remove the temporary file] 2026-06-08 07:41:17.956205 | controller | skipping: Conditional result was False 2026-06-08 07:41:17.965334 | 2026-06-08 07:41:17.965469 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-06-08 07:41:17.991497 | controller | skipping: Conditional result was False 2026-06-08 07:41:18.005412 | 2026-06-08 07:41:18.005539 | PLAY RECAP 2026-06-08 07:41:18.005665 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-06-08 07:41:18.005710 | 2026-06-08 07:41:18.134915 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-08 07:41:18.137480 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-06-08 07:41:18.738970 | 2026-06-08 07:41:18.739083 | PLAY [all] 2026-06-08 07:41:18.748952 | 2026-06-08 07:41:18.749059 | TASK [include_role : fetch-output] 2026-06-08 07:41:18.798610 | controller | ok 2026-06-08 07:41:18.818774 | 2026-06-08 07:41:18.818898 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-08 07:41:18.873150 | controller | skipping: Conditional result was False 2026-06-08 07:41:18.882747 | 2026-06-08 07:41:18.883011 | TASK [fetch-output : Set log path for single node] 2026-06-08 07:41:18.938206 | controller | ok 2026-06-08 07:41:18.946540 | 2026-06-08 07:41:18.946731 | LOOP [fetch-output : Ensure local output dirs] 2026-06-08 07:41:19.424766 | controller -> localhost | changed: "/var/lib/zuul/builds/7f2617d255494c7bbe20f09be7d3820f/work/logs" 2026-06-08 07:41:19.713312 | controller -> localhost | changed: "/var/lib/zuul/builds/7f2617d255494c7bbe20f09be7d3820f/work/artifacts" 2026-06-08 07:41:19.981435 | controller -> localhost | changed: "/var/lib/zuul/builds/7f2617d255494c7bbe20f09be7d3820f/work/docs" 2026-06-08 07:41:19.994138 | 2026-06-08 07:41:19.994385 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-08 07:41:20.818317 | controller | changed: .d..t...... ./ 2026-06-08 07:41:20.818689 | controller | changed: All items complete 2026-06-08 07:41:20.818749 | 2026-06-08 07:41:21.384217 | controller | changed: .d..t...... ./ 2026-06-08 07:41:21.948458 | controller | changed: .d..t...... ./ 2026-06-08 07:41:21.968108 | 2026-06-08 07:41:21.968243 | TASK [include_role : fetch-output-openshift] 2026-06-08 07:41:21.995308 | controller | skipping: Conditional result was False 2026-06-08 07:41:22.004641 | 2026-06-08 07:41:22.004777 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-08 07:41:22.523332 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.014199 2026-06-08 07:41:22.850072 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.013913 2026-06-08 07:41:22.873287 | 2026-06-08 07:41:22.873505 | PLAY [all] 2026-06-08 07:41:22.883696 | 2026-06-08 07:41:22.883836 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-08 07:41:23.363829 | controller | changed 2026-06-08 07:41:23.375197 | 2026-06-08 07:41:23.375355 | PLAY RECAP 2026-06-08 07:41:23.375437 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-08 07:41:23.375480 | 2026-06-08 07:41:23.519514 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-06-08 07:41:23.520841 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2026-06-08 07:41:24.172996 | 2026-06-08 07:41:24.173115 | PLAY [localhost] 2026-06-08 07:41:24.182987 | 2026-06-08 07:41:24.183075 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-08 07:41:24.598614 | localhost | changed 2026-06-08 07:41:24.617861 | 2026-06-08 07:41:24.618226 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-08 07:41:24.660958 | localhost | ok 2026-06-08 07:41:24.673324 | 2026-06-08 07:41:24.673462 | TASK [add-fileserver : Create SSH private key tempfile] 2026-06-08 07:41:25.091892 | localhost | changed 2026-06-08 07:41:25.101729 | 2026-06-08 07:41:25.101876 | TASK [add-fileserver : Create SSH private key from secret] 2026-06-08 07:41:25.943105 | localhost | changed 2026-06-08 07:41:25.947543 | 2026-06-08 07:41:25.947637 | TASK [add-fileserver : Add fileserver ssh key] 2026-06-08 07:41:26.504997 | localhost | Identity added: /var/lib/zuul/builds/7f2617d255494c7bbe20f09be7d3820f/work/tmp/ansible.aydce1be (/var/lib/zuul/builds/7f2617d255494c7bbe20f09be7d3820f/work/tmp/ansible.aydce1be) 2026-06-08 07:41:26.505698 | localhost | ok: Runtime: 0:00:00.021948 2026-06-08 07:41:26.512865 | 2026-06-08 07:41:26.513065 | TASK [add-fileserver : Remove SSH private key from disk] 2026-06-08 07:41:26.857775 | localhost | ok: Runtime: 0:00:00.007824 2026-06-08 07:41:26.866956 | 2026-06-08 07:41:26.867114 | TASK [add-fileserver : Add fileserver to inventory] 2026-06-08 07:41:26.953328 | localhost | changed 2026-06-08 07:41:26.962231 | 2026-06-08 07:41:26.962374 | TASK [add-fileserver : Add fileserver server to known hosts] 2026-06-08 07:41:27.413468 | localhost | changed 2026-06-08 07:41:27.424530 | 2026-06-08 07:41:27.424690 | PLAY [localhost] 2026-06-08 07:41:27.432979 | 2026-06-08 07:41:27.433104 | TASK [Generate bulk log download script] 2026-06-08 07:41:27.455326 | localhost | ok 2026-06-08 07:41:27.476862 | 2026-06-08 07:41:27.476997 | TASK [local-log-download : Check API endpoint is defined] 2026-06-08 07:41:27.528499 | localhost | ok: All assertions passed 2026-06-08 07:41:27.537105 | 2026-06-08 07:41:27.537267 | TASK [local-log-download : Create download script] 2026-06-08 07:41:28.103159 | localhost -> localhost | changed 2026-06-08 07:41:28.166314 | 2026-06-08 07:41:28.166653 | TASK [Register quick-download link] 2026-06-08 07:41:28.186652 | localhost | ok 2026-06-08 07:41:28.190308 | 2026-06-08 07:41:28.190388 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com] 2026-06-08 07:41:28.196925 | 2026-06-08 07:41:28.197011 | TASK [Set zuul-log-path fact] 2026-06-08 07:41:28.213107 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-08 07:41:28.221275 | 2026-06-08 07:41:28.221371 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-08 07:41:28.258864 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-08 07:41:28.263647 | 2026-06-08 07:41:28.263774 | TASK [upload-logs : Create log directories] 2026-06-08 07:41:29.174905 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed 2026-06-08 07:41:29.177135 | 2026-06-08 07:41:29.177216 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-08 07:41:29.455271 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.008578 2026-06-08 07:41:29.462154 | 2026-06-08 07:41:29.462320 | TASK [upload-logs : Upload logs to log server] 2026-06-08 07:41:30.722010 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | Output suppressed because no_log was given 2026-06-08 07:41:30.725291 | 2026-06-08 07:41:30.725427 | LOOP [upload-logs : Compress console log and json output]