2026-06-08 13:55:16.697417 | Job console starting 2026-06-08 13:55:16.827515 | Updating git repos 2026-06-08 13:55:16.863810 | Cloning repos into workspace 2026-06-08 13:55:17.501401 | Restoring repo states 2026-06-08 13:55:17.516675 | Merging changes 2026-06-08 13:55:18.676323 | Checking out repos 2026-06-08 13:55:18.798990 | Preparing playbooks 2026-06-08 13:55:22.607983 | Running Ansible setup 2026-06-08 13:55:26.410260 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-06-08 13:55:27.120707 | 2026-06-08 13:55:27.120859 | PLAY [localhost] 2026-06-08 13:55:27.128994 | 2026-06-08 13:55:27.129064 | TASK [Gathering Facts] 2026-06-08 13:55:28.051966 | localhost | ok 2026-06-08 13:55:28.066248 | 2026-06-08 13:55:28.066446 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-08 13:55:28.532756 | localhost -> localhost | changed 2026-06-08 13:55:28.537911 | 2026-06-08 13:55:28.537980 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-08 13:55:29.540632 | localhost -> localhost | changed 2026-06-08 13:55:29.553720 | 2026-06-08 13:55:29.553905 | TASK [Setup log path fact] 2026-06-08 13:55:29.587825 | localhost | ok 2026-06-08 13:55:29.607655 | 2026-06-08 13:55:29.607793 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-08 13:55:29.652940 | localhost | ok 2026-06-08 13:55:29.671094 | 2026-06-08 13:55:29.671225 | TASK [emit-job-header : Print job information] 2026-06-08 13:55:29.724150 | # Job Information 2026-06-08 13:55:29.724433 | Ansible Version: 2.16.18 2026-06-08 13:55:29.724492 | Job: cifmw-molecule-cleanup_openstack 2026-06-08 13:55:29.724537 | Pipeline: github-check 2026-06-08 13:55:29.724578 | Executor: 38.102.83.51 2026-06-08 13:55:29.724617 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3866 2026-06-08 13:55:29.724660 | Log URL (when completed): https://gateway-cloud-softwarefactory.apps.ocp.cloud.ci.centos.org/logs/ca7/rdoproject.org/ca714a9ccaf14a92b32a88327653d822/ 2026-06-08 13:55:29.724706 | Event ID: 651bd100-633e-11f1-9225-494af5a35e66 2026-06-08 13:55:29.730875 | 2026-06-08 13:55:29.731013 | LOOP [emit-job-header : Print node information] 2026-06-08 13:55:29.839099 | localhost | ok: 2026-06-08 13:55:29.839452 | localhost | # Node Information 2026-06-08 13:55:29.839512 | localhost | Inventory Hostname: controller 2026-06-08 13:55:29.839561 | localhost | Hostname: np0000067330 2026-06-08 13:55:29.839601 | localhost | Username: zuul 2026-06-08 13:55:29.839644 | localhost | Distro: CentOS 9 2026-06-08 13:55:29.839680 | localhost | Provider: vexxhost-nodepool-tripleo 2026-06-08 13:55:29.839716 | localhost | Region: RegionOne 2026-06-08 13:55:29.839751 | localhost | Label: cloud-centos-9-stream-tripleo 2026-06-08 13:55:29.839785 | localhost | Product Name: OpenStack Nova 2026-06-08 13:55:29.839818 | localhost | Interface IP: 38.102.83.94 2026-06-08 13:55:29.869085 | 2026-06-08 13:55:29.869452 | PLAY [all] 2026-06-08 13:55:29.881088 | 2026-06-08 13:55:29.881218 | TASK [Gather network facts] 2026-06-08 13:55:30.333463 | controller | ok 2026-06-08 13:55:30.345613 | 2026-06-08 13:55:30.345744 | TASK [include_role : start-zuul-console] 2026-06-08 13:55:30.383753 | controller | ok 2026-06-08 13:55:30.402835 | 2026-06-08 13:55:30.402989 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-06-08 13:55:30.862764 | controller | ok 2026-06-08 13:55:30.875202 | 2026-06-08 13:55:30.875327 | TASK [include_role : add-build-sshkey] 2026-06-08 13:55:30.910558 | controller | ok 2026-06-08 13:55:30.932111 | 2026-06-08 13:55:30.932247 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-08 13:55:31.211302 | controller -> localhost | ok 2026-06-08 13:55:31.220543 | 2026-06-08 13:55:31.220720 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-08 13:55:31.254926 | controller | ok 2026-06-08 13:55:31.282474 | controller | included: /var/lib/zuul/builds/ca714a9ccaf14a92b32a88327653d822/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-08 13:55:31.291303 | 2026-06-08 13:55:31.291405 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-08 13:55:31.956715 | controller -> localhost | Generating public/private rsa key pair. 2026-06-08 13:55:31.957170 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ca714a9ccaf14a92b32a88327653d822/work/ca714a9ccaf14a92b32a88327653d822_id_rsa 2026-06-08 13:55:31.957246 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ca714a9ccaf14a92b32a88327653d822/work/ca714a9ccaf14a92b32a88327653d822_id_rsa.pub 2026-06-08 13:55:31.957295 | controller -> localhost | The key fingerprint is: 2026-06-08 13:55:31.957336 | controller -> localhost | SHA256:RIOOFdb2hGuNVDgAQjTnp42MjaLQ1MPkBjjxLM96KPg zuul-build-sshkey 2026-06-08 13:55:31.957413 | controller -> localhost | The key's randomart image is: 2026-06-08 13:55:31.957454 | controller -> localhost | +---[RSA 3072]----+ 2026-06-08 13:55:31.957492 | controller -> localhost | |.=* +.+=o+. | 2026-06-08 13:55:31.957531 | controller -> localhost | |oo @ .o.B.. | 2026-06-08 13:55:31.957567 | controller -> localhost | |..+ B+.o.B | 2026-06-08 13:55:31.957604 | controller -> localhost | | * *.*..+ o | 2026-06-08 13:55:31.957641 | controller -> localhost | |o * = ..S | 2026-06-08 13:55:31.957677 | controller -> localhost | |++ | 2026-06-08 13:55:31.957713 | controller -> localhost | |* . | 2026-06-08 13:55:31.957750 | controller -> localhost | |.o | 2026-06-08 13:55:31.957787 | controller -> localhost | | E | 2026-06-08 13:55:31.957823 | controller -> localhost | +----[SHA256]-----+ 2026-06-08 13:55:31.957939 | controller -> localhost | ok: Runtime: 0:00:00.177064 2026-06-08 13:55:31.966094 | 2026-06-08 13:55:31.966220 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-08 13:55:32.043879 | controller | ok 2026-06-08 13:55:32.062430 | controller | included: /var/lib/zuul/builds/ca714a9ccaf14a92b32a88327653d822/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-08 13:55:32.071883 | 2026-06-08 13:55:32.071966 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-08 13:55:32.107877 | controller | skipping: Conditional result was False 2026-06-08 13:55:32.116106 | 2026-06-08 13:55:32.116247 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-08 13:55:32.625815 | controller | changed 2026-06-08 13:55:32.633572 | 2026-06-08 13:55:32.633714 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-08 13:55:32.890994 | controller | ok 2026-06-08 13:55:32.898826 | 2026-06-08 13:55:32.898956 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-08 13:55:33.682294 | controller | changed 2026-06-08 13:55:33.690021 | 2026-06-08 13:55:33.690177 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-08 13:55:34.515087 | controller | changed 2026-06-08 13:55:34.522960 | 2026-06-08 13:55:34.523093 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-08 13:55:34.550680 | controller | skipping: Conditional result was False 2026-06-08 13:55:34.560520 | 2026-06-08 13:55:34.560663 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-08 13:55:35.023641 | controller -> localhost | changed 2026-06-08 13:55:35.039712 | 2026-06-08 13:55:35.039889 | TASK [add-build-sshkey : Add back temp key] 2026-06-08 13:55:35.418289 | controller -> localhost | Identity added: /var/lib/zuul/builds/ca714a9ccaf14a92b32a88327653d822/work/ca714a9ccaf14a92b32a88327653d822_id_rsa (zuul-build-sshkey) 2026-06-08 13:55:35.418706 | controller -> localhost | ok: Runtime: 0:00:00.017675 2026-06-08 13:55:35.426294 | 2026-06-08 13:55:35.426459 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-08 13:55:35.871011 | controller | ok 2026-06-08 13:55:35.878534 | 2026-06-08 13:55:35.878667 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-08 13:55:35.916817 | controller | skipping: Conditional result was False 2026-06-08 13:55:35.932722 | 2026-06-08 13:55:35.932859 | TASK [include_role : validate-host] 2026-06-08 13:55:35.992098 | controller | ok 2026-06-08 13:55:36.015287 | 2026-06-08 13:55:36.015456 | TASK [validate-host : Define zuul_info_dir fact] 2026-06-08 13:55:36.058030 | controller | ok 2026-06-08 13:55:36.064663 | 2026-06-08 13:55:36.064795 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-06-08 13:55:36.384930 | controller -> localhost | ok 2026-06-08 13:55:36.390549 | 2026-06-08 13:55:36.390619 | TASK [validate-host : Collect information about the host] 2026-06-08 13:55:37.084341 | controller | ok 2026-06-08 13:55:37.100201 | 2026-06-08 13:55:37.100390 | TASK [validate-host : Sanitize hostname] 2026-06-08 13:55:37.183541 | controller | ok 2026-06-08 13:55:37.191825 | 2026-06-08 13:55:37.191981 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-06-08 13:55:37.735601 | controller -> localhost | changed 2026-06-08 13:55:37.748436 | 2026-06-08 13:55:37.748586 | TASK [validate-host : Collect information about zuul worker] 2026-06-08 13:55:38.198331 | controller | ok 2026-06-08 13:55:38.206932 | 2026-06-08 13:55:38.207073 | TASK [validate-host : Write out all zuul information for each host] 2026-06-08 13:55:38.700974 | controller -> localhost | changed 2026-06-08 13:55:38.719230 | 2026-06-08 13:55:38.719404 | TASK [include_role : prepare-workspace-openshift] 2026-06-08 13:55:38.747310 | controller | skipping: Conditional result was False 2026-06-08 13:55:38.756781 | 2026-06-08 13:55:38.756914 | TASK [include_role : remove-zuul-sshkey] 2026-06-08 13:55:38.785118 | controller | skipping: Conditional result was False 2026-06-08 13:55:38.800867 | 2026-06-08 13:55:38.801039 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-08 13:55:39.083437 | controller | ok: "logs" 2026-06-08 13:55:39.084005 | controller | ok: All items complete 2026-06-08 13:55:39.084111 | 2026-06-08 13:55:39.292161 | controller | ok: "artifacts" 2026-06-08 13:55:39.540189 | controller | ok: "docs" 2026-06-08 13:55:39.550203 | 2026-06-08 13:55:39.550432 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-08 13:55:39.810971 | controller | changed: "logs" 2026-06-08 13:55:40.019866 | controller | changed: "artifacts" 2026-06-08 13:55:40.261500 | controller | changed: "docs" 2026-06-08 13:55:40.280171 | 2026-06-08 13:55:40.281050 | PLAY RECAP 2026-06-08 13:55:40.281160 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2026-06-08 13:55:40.281218 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-08 13:55:40.281257 | 2026-06-08 13:55:40.512078 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-06-08 13:55:40.515066 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-06-08 13:55:41.157988 | 2026-06-08 13:55:41.158145 | PLAY [localhost] 2026-06-08 13:55:41.166757 | 2026-06-08 13:55:41.166827 | TASK [configure-ssh-config-fips : Ensure ssh config directory] 2026-06-08 13:55:41.601959 | localhost | changed 2026-06-08 13:55:41.610683 | 2026-06-08 13:55:41.610840 | TASK [configure-ssh-config-fips : Setup ssh config] 2026-06-08 13:55:42.427556 | localhost | changed 2026-06-08 13:55:42.435374 | 2026-06-08 13:55:42.435460 | PLAY [all] 2026-06-08 13:55:42.440610 | 2026-06-08 13:55:42.440674 | TASK [include_role : prepare-workspace] 2026-06-08 13:55:42.469267 | controller | ok 2026-06-08 13:55:42.482530 | 2026-06-08 13:55:42.482592 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-08 13:55:42.962687 | controller | ok 2026-06-08 13:55:42.973533 | 2026-06-08 13:55:42.973665 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-08 13:55:44.481966 | controller | Output suppressed because no_log was given 2026-06-08 13:55:44.496553 | 2026-06-08 13:55:44.496697 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-08 13:55:44.752340 | controller | changed: "logs" 2026-06-08 13:55:44.960461 | controller | changed: "artifacts" 2026-06-08 13:55:45.177954 | controller | changed: "docs" 2026-06-08 13:55:45.196900 | 2026-06-08 13:55:45.197336 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-08 13:55:45.476791 | controller | changed: "logs" 2026-06-08 13:55:45.477225 | controller | changed: All items complete 2026-06-08 13:55:45.477281 | 2026-06-08 13:55:45.694675 | controller | changed: "artifacts" 2026-06-08 13:55:45.915703 | controller | changed: "docs" 2026-06-08 13:55:45.945557 | 2026-06-08 13:55:45.945787 | TASK [Check if worker can sudo] 2026-06-08 13:55:46.490017 | controller | ok: Runtime: 0:00:00.044311 2026-06-08 13:55:46.498382 | 2026-06-08 13:55:46.498517 | TASK [configure-mirrors : Gather needed facts] 2026-06-08 13:55:46.576105 | controller | skipping: Conditional result was False 2026-06-08 13:55:46.585539 | 2026-06-08 13:55:46.585676 | TASK [configure-mirrors : Set up infrastructure mirrors] 2026-06-08 13:55:46.670848 | controller | ok 2026-06-08 13:55:46.685652 | controller | included: /var/lib/zuul/builds/ca714a9ccaf14a92b32a88327653d822/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml 2026-06-08 13:55:46.694262 | 2026-06-08 13:55:46.694419 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir] 2026-06-08 13:55:47.002247 | controller | ok 2026-06-08 13:55:47.010935 | 2026-06-08 13:55:47.011063 | LOOP [configure-mirrors : Include OS-specific variables] 2026-06-08 13:55:47.099260 | controller | ok: "/var/lib/zuul/builds/ca714a9ccaf14a92b32a88327653d822/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml" 2026-06-08 13:55:47.110919 | 2026-06-08 13:55:47.111118 | TASK [configure-mirrors : Install /etc/pip.conf configuration] 2026-06-08 13:55:47.997963 | controller | changed 2026-06-08 13:55:48.009082 | 2026-06-08 13:55:48.009227 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors] 2026-06-08 13:55:48.179619 | controller | ok: "/var/lib/zuul/builds/ca714a9ccaf14a92b32a88327653d822/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml" 2026-06-08 13:55:48.179819 | controller | ok: All items complete 2026-06-08 13:55:48.179852 | 2026-06-08 13:55:48.256735 | controller | included: /var/lib/zuul/builds/ca714a9ccaf14a92b32a88327653d822/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml 2026-06-08 13:55:48.263992 | 2026-06-08 13:55:48.264088 | LOOP [configure-mirrors : Install CentOS 9 repository files] 2026-06-08 13:55:49.230577 | controller | changed: "etc/yum.repos.d/centos.repo" 2026-06-08 13:55:50.106976 | controller | changed: "etc/yum.repos.d/centos-addons.repo" 2026-06-08 13:55:50.126039 | 2026-06-08 13:55:50.126268 | TASK [configure-mirrors : Disable deltrarpm] 2026-06-08 13:55:50.582007 | controller | changed: section and option added 2026-06-08 13:55:50.603949 | 2026-06-08 13:55:50.604087 | LOOP [configure-mirrors : Update yum/dnf cache] 2026-06-08 13:55:51.364419 | controller | 29 files removed 2026-06-08 13:55:51.364995 | controller | ok: Item: dnf clean all Runtime: 0:00:00.458876 2026-06-08 13:55:51.365074 | controller | changed: All items complete 2026-06-08 13:55:51.365117 | 2026-06-08 13:56:02.678235 | 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 13:56:02.678504 | controller | DNF version: 4.14.0 2026-06-08 13:56:02.678563 | controller | cachedir: /var/cache/dnf 2026-06-08 13:56:02.678605 | controller | Making cache files for all metadata files. 2026-06-08 13:56:02.678643 | controller | baseos: has expired and will be refreshed. 2026-06-08 13:56:02.678702 | controller | appstream: has expired and will be refreshed. 2026-06-08 13:56:02.678739 | controller | crb: has expired and will be refreshed. 2026-06-08 13:56:02.678773 | controller | extras-common: has expired and will be refreshed. 2026-06-08 13:56:02.678836 | controller | repo: downloading from remote: baseos 2026-06-08 13:56:02.678873 | controller | CentOS Stream 9 - BaseOS 73 MB/s | 8.9 MB 00:00 2026-06-08 13:56:02.678908 | controller | baseos: using metadata from Mon 01 Jun 2026 05:58:58 PM EDT. 2026-06-08 13:56:02.678944 | controller | repo: downloading from remote: appstream 2026-06-08 13:56:02.678979 | controller | CentOS Stream 9 - AppStream 118 MB/s | 28 MB 00:00 2026-06-08 13:56:02.679013 | controller | appstream: using metadata from Mon 01 Jun 2026 06:02:34 PM EDT. 2026-06-08 13:56:02.679048 | controller | repo: downloading from remote: crb 2026-06-08 13:56:02.679082 | controller | CentOS Stream 9 - CRB 63 MB/s | 8.1 MB 00:00 2026-06-08 13:56:02.679117 | controller | crb: using metadata from Mon 01 Jun 2026 06:07:05 PM EDT. 2026-06-08 13:56:02.679152 | controller | repo: downloading from remote: extras-common 2026-06-08 13:56:02.679186 | controller | CentOS Stream 9 - Extras packages 1.1 MB/s | 21 kB 00:00 2026-06-08 13:56:02.679219 | controller | extras-common: using metadata from Tue 24 Mar 2026 03:36:22 PM EDT. 2026-06-08 13:56:02.679253 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)' 2026-06-08 13:56:02.679287 | controller | Completion plugin: Generating completion cache... 2026-06-08 13:56:02.679322 | controller | Metadata cache created. 2026-06-08 13:56:02.679412 | controller | ok: Item: dnf makecache -v Runtime: 0:00:11.082084 2026-06-08 13:56:02.688093 | 2026-06-08 13:56:02.688263 | PLAY RECAP 2026-06-08 13:56:02.688314 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-08 13:56:02.688339 | localhost | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-08 13:56:02.688378 | 2026-06-08 13:56:02.888257 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-06-08 13:56:02.890662 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-06-08 13:56:03.509550 | 2026-06-08 13:56:03.509676 | PLAY [all] 2026-06-08 13:56:03.520950 | 2026-06-08 13:56:03.521028 | TASK [Install binary dependencies] 2026-06-08 13:56:03.590918 | controller | ok 2026-06-08 13:56:03.608263 | 2026-06-08 13:56:03.608354 | TASK [bindep : Include find tasks] 2026-06-08 13:56:03.648207 | controller | ok 2026-06-08 13:56:03.654979 | controller | included: /var/lib/zuul/builds/ca714a9ccaf14a92b32a88327653d822/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-06-08 13:56:03.659634 | 2026-06-08 13:56:03.659695 | TASK [bindep : Look for bindep.txt] 2026-06-08 13:56:04.088569 | controller | ok 2026-06-08 13:56:04.098511 | 2026-06-08 13:56:04.098668 | TASK [bindep : Define bindep_file fact] 2026-06-08 13:56:04.143136 | controller | ok 2026-06-08 13:56:04.153235 | 2026-06-08 13:56:04.153394 | TASK [bindep : Look for other-requirements.txt] 2026-06-08 13:56:04.179104 | controller | skipping: Conditional result was False 2026-06-08 13:56:04.189739 | 2026-06-08 13:56:04.189894 | TASK [bindep : Define bindep_file fact] 2026-06-08 13:56:04.225645 | controller | skipping: Conditional result was False 2026-06-08 13:56:04.236292 | 2026-06-08 13:56:04.236585 | TASK [bindep : Look for bindep fallback file] 2026-06-08 13:56:04.274237 | controller | skipping: Conditional result was False 2026-06-08 13:56:04.284362 | 2026-06-08 13:56:04.284708 | TASK [bindep : Define bindep_file fact] 2026-06-08 13:56:04.321762 | controller | skipping: Conditional result was False 2026-06-08 13:56:04.331341 | 2026-06-08 13:56:04.331507 | TASK [bindep : Include bindep tasks] 2026-06-08 13:56:04.375570 | controller | ok 2026-06-08 13:56:04.387944 | controller | included: /var/lib/zuul/builds/ca714a9ccaf14a92b32a88327653d822/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml 2026-06-08 13:56:04.404569 | 2026-06-08 13:56:04.404696 | TASK [bindep : Look for bindep command] 2026-06-08 13:56:04.440901 | controller | skipping: Conditional result was False 2026-06-08 13:56:04.449619 | 2026-06-08 13:56:04.449725 | TASK [bindep : Check for system bindep] 2026-06-08 13:56:04.986521 | controller | ok: Runtime: 0:00:00.005354 2026-06-08 13:56:04.995038 | 2026-06-08 13:56:04.995186 | TASK [bindep : Define bindep_command fact] 2026-06-08 13:56:05.031964 | controller | skipping: Conditional result was False 2026-06-08 13:56:05.040808 | 2026-06-08 13:56:05.040941 | TASK [bindep : Include install tasks] 2026-06-08 13:56:05.086939 | controller | ok 2026-06-08 13:56:05.104148 | controller | included: /var/lib/zuul/builds/ca714a9ccaf14a92b32a88327653d822/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml 2026-06-08 13:56:05.118309 | 2026-06-08 13:56:05.118417 | TASK [bindep : Create temp dir for bindep] 2026-06-08 13:56:05.497331 | controller | changed 2026-06-08 13:56:05.505923 | 2026-06-08 13:56:05.506064 | TASK [Ensure we have pip dependencies] 2026-06-08 13:56:05.526894 | controller | ok 2026-06-08 13:56:05.548244 | 2026-06-08 13:56:05.548335 | TASK [ensure-pip : Check if pip is installed] 2026-06-08 09:56:05.852060 | controller | /usr/bin/pip3 2026-06-08 09:56:05.869478 | controller | /usr/bin/python3: No module named wheel 2026-06-08 13:56:06.078803 | controller | ok: Runtime: 0:00:00.024800 2026-06-08 13:56:06.088147 | 2026-06-08 13:56:06.088289 | LOOP [ensure-pip : Install pip from packages] 2026-06-08 13:56:06.135401 | controller | ok: "/var/lib/zuul/builds/ca714a9ccaf14a92b32a88327653d822/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml" 2026-06-08 13:56:06.157087 | controller | included: /var/lib/zuul/builds/ca714a9ccaf14a92b32a88327653d822/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml 2026-06-08 13:56:06.224780 | 2026-06-08 13:56:06.224949 | TASK [ensure-pip : Install Python 3 pip] 2026-06-08 13:56:07.587813 | controller | ok: Nothing to do 2026-06-08 13:56:07.597085 | 2026-06-08 13:56:07.597221 | TASK [ensure-pip : Install Python3-wheel on older CentOS] 2026-06-08 13:56:09.291474 | controller | changed 2026-06-08 13:56:09.300211 | 2026-06-08 13:56:09.300343 | TASK [ensure-pip : Check for EPEL repository] 2026-06-08 13:56:09.368592 | controller | skipping: Conditional result was False 2026-06-08 13:56:09.378795 | 2026-06-08 13:56:09.379019 | TASK [ensure-pip : Fail with instructions when EPEL is not installed] 2026-06-08 13:56:09.426824 | controller | skipping: Conditional result was False 2026-06-08 13:56:09.439772 | 2026-06-08 13:56:09.439910 | TASK [ensure-pip : Install Python 2 pip] 2026-06-08 13:56:09.486931 | controller | skipping: Conditional result was False 2026-06-08 13:56:09.496684 | 2026-06-08 13:56:09.496819 | TASK [ensure-pip : Ensure setuptools] 2026-06-08 13:56:09.523623 | controller | skipping: Conditional result was False 2026-06-08 13:56:09.533024 | 2026-06-08 13:56:09.533171 | TASK [ensure-pip : Check for ensurepip module] 2026-06-08 13:56:10.071600 | controller | skipping: Conditional result was False 2026-06-08 13:56:10.083250 | 2026-06-08 13:56:10.083432 | TASK [ensure-pip : Ensure python3-venv] 2026-06-08 13:56:10.121050 | controller | skipping: Conditional result was False 2026-06-08 13:56:10.130274 | 2026-06-08 13:56:10.130450 | TASK [ensure-pip : Install pip from source] 2026-06-08 13:56:10.157321 | controller | skipping: Conditional result was False 2026-06-08 13:56:10.167284 | 2026-06-08 13:56:10.167446 | TASK [ensure-pip : Probe for venv python full path] 2026-06-08 09:56:10.488952 | controller | /usr/bin/python3 2026-06-08 13:56:10.714480 | controller | ok: Runtime: 0:00:00.004919 2026-06-08 13:56:10.722852 | 2026-06-08 13:56:10.722989 | TASK [ensure-pip : Set host default] 2026-06-08 13:56:10.798436 | controller | ok 2026-06-08 13:56:10.806230 | 2026-06-08 13:56:10.806402 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2026-06-08 13:56:10.885150 | controller | ok 2026-06-08 13:56:10.903101 | 2026-06-08 13:56:10.903229 | TASK [bindep : Install bindep into temporary venv] 2026-06-08 13:56:15.204294 | controller | changed 2026-06-08 13:56:15.213266 | 2026-06-08 13:56:15.213428 | TASK [bindep : Define bindep_command] 2026-06-08 13:56:15.248522 | controller | ok 2026-06-08 13:56:15.256489 | 2026-06-08 13:56:15.256617 | LOOP [bindep : Include package tasks] 2026-06-08 13:56:15.324551 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt" 2026-06-08 13:56:15.325007 | controller | ok: All items complete 2026-06-08 13:56:15.325069 | 2026-06-08 13:56:15.348643 | controller | included: /var/lib/zuul/builds/ca714a9ccaf14a92b32a88327653d822/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml 2026-06-08 13:56:15.369854 | 2026-06-08 13:56:15.369983 | TASK [bindep : Define bindep_run fact] 2026-06-08 13:56:15.427487 | controller | ok 2026-06-08 13:56:15.435479 | 2026-06-08 13:56:15.435608 | TASK [bindep : Get list of packages to install from bindep] 2026-06-08 09:56:16.713884 | controller | podman 2026-06-08 09:56:16.714033 | controller | python3-jmespath 2026-06-08 09:56:16.714044 | controller | python3-libvirt 2026-06-08 09:56:16.714051 | controller | python3-lxml 2026-06-08 09:56:16.714056 | controller | python3-netaddr 2026-06-08 13:56:16.973518 | controller | ok: Runtime: 0:00:01.024936 2026-06-08 13:56:16.982175 | 2026-06-08 13:56:16.982314 | TASK [bindep : Install distro packages from bindep] 2026-06-08 13:57:01.115093 | controller | changed 2026-06-08 13:57:01.128659 | 2026-06-08 13:57:01.128791 | TASK [bindep : Check that packages are installed] 2026-06-08 13:57:02.670477 | controller | ok: Runtime: 0:00:00.989323 2026-06-08 13:57:02.678645 | 2026-06-08 13:57:02.678778 | TASK [bindep : Fail if we cannot install all packages] 2026-06-08 13:57:02.716742 | controller | skipping: Conditional result was False 2026-06-08 13:57:02.730935 | 2026-06-08 13:57:02.731042 | TASK [Run test-setup role] 2026-06-08 13:57:02.762044 | controller | ok 2026-06-08 13:57:02.781543 | 2026-06-08 13:57:02.781641 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-06-08 13:57:03.008518 | controller | ok 2026-06-08 13:57:03.016500 | 2026-06-08 13:57:03.016629 | TASK [test-setup : Run tools/test-setup.sh] 2026-06-08 13:57:03.555857 | controller | skipping: Conditional result was False 2026-06-08 13:57:03.571186 | 2026-06-08 13:57:03.571280 | TASK [bindep : Remove bindep temp dir] 2026-06-08 13:57:03.990033 | controller | ok 2026-06-08 13:57:03.992620 | 2026-06-08 13:57:03.992709 | PLAY RECAP 2026-06-08 13:57:03.992794 | controller | ok: 23 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0 2026-06-08 13:57:03.992836 | 2026-06-08 13:57:04.191313 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-06-08 13:57:04.193416 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2026-06-08 13:57:04.817270 | 2026-06-08 13:57:04.817671 | PLAY [all] 2026-06-08 13:57:04.827178 | 2026-06-08 13:57:04.827252 | TASK [Abort when test_command variable is undefined] 2026-06-08 13:57:04.872301 | controller | skipping: Conditional result was False 2026-06-08 13:57:04.876957 | 2026-06-08 13:57:04.877030 | TASK [Convert test_command to list] 2026-06-08 13:57:04.942302 | controller | skipping: Conditional result was False 2026-06-08 13:57:04.957551 | 2026-06-08 13:57:04.957929 | TASK [Use test_command list] 2026-06-08 13:57:05.021943 | controller | ok 2026-06-08 13:57:05.034266 | 2026-06-08 13:57:05.034470 | LOOP [Run test_command] 2026-06-08 13:57:05.511175 | controller | no check to run 2026-06-08 13:57:05.511610 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.004411 2026-06-08 13:57:05.518169 | 2026-06-08 13:57:05.518387 | PLAY RECAP 2026-06-08 13:57:05.518484 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-08 13:57:05.518529 | 2026-06-08 13:57:05.707964 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2026-06-08 13:57:05.709623 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-08 13:57:06.322760 | 2026-06-08 13:57:06.322892 | PLAY [all] 2026-06-08 13:57:06.333675 | 2026-06-08 13:57:06.333750 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-06-08 13:57:06.672343 | controller | changed: non-zero return code 2026-06-08 13:57:06.681243 | 2026-06-08 13:57:06.681441 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-06-08 13:57:06.709741 | controller | skipping: Conditional result was False 2026-06-08 13:57:06.722812 | 2026-06-08 13:57:06.723049 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-06-08 13:57:06.762606 | 2026-06-08 13:57:06.762964 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-06-08 13:57:06.796817 | 2026-06-08 13:57:06.796993 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-06-08 13:57:06.822050 | controller | skipping: Conditional result was False 2026-06-08 13:57:06.828248 | 2026-06-08 13:57:06.828344 | LOOP [fetch-subunit-output : Generate subunit file] 2026-06-08 13:57:06.858928 | 2026-06-08 13:57:06.859112 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-06-08 13:57:06.894465 | controller | skipping: Conditional result was False 2026-06-08 13:57:06.904040 | 2026-06-08 13:57:06.904210 | TASK [fetch-subunit-output : Remove the temporary file] 2026-06-08 13:57:06.931143 | controller | skipping: Conditional result was False 2026-06-08 13:57:06.941048 | 2026-06-08 13:57:06.941196 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-06-08 13:57:06.968108 | controller | skipping: Conditional result was False 2026-06-08 13:57:06.985335 | 2026-06-08 13:57:06.985483 | PLAY RECAP 2026-06-08 13:57:06.985578 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-06-08 13:57:06.985623 | 2026-06-08 13:57:07.175250 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-08 13:57:07.176671 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-06-08 13:57:07.801737 | 2026-06-08 13:57:07.801865 | PLAY [all] 2026-06-08 13:57:07.811781 | 2026-06-08 13:57:07.811850 | TASK [include_role : fetch-output] 2026-06-08 13:57:07.850970 | controller | ok 2026-06-08 13:57:07.867365 | 2026-06-08 13:57:07.867459 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-08 13:57:07.942589 | controller | skipping: Conditional result was False 2026-06-08 13:57:07.951428 | 2026-06-08 13:57:07.951570 | TASK [fetch-output : Set log path for single node] 2026-06-08 13:57:08.016541 | controller | ok 2026-06-08 13:57:08.025685 | 2026-06-08 13:57:08.025816 | LOOP [fetch-output : Ensure local output dirs] 2026-06-08 13:57:08.555369 | controller -> localhost | changed: "/var/lib/zuul/builds/ca714a9ccaf14a92b32a88327653d822/work/logs" 2026-06-08 13:57:08.875195 | controller -> localhost | changed: "/var/lib/zuul/builds/ca714a9ccaf14a92b32a88327653d822/work/artifacts" 2026-06-08 13:57:09.134967 | controller -> localhost | changed: "/var/lib/zuul/builds/ca714a9ccaf14a92b32a88327653d822/work/docs" 2026-06-08 13:57:09.155528 | 2026-06-08 13:57:09.155717 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-08 13:57:09.889253 | controller | changed: .d..t...... ./ 2026-06-08 13:57:09.889838 | controller | changed: All items complete 2026-06-08 13:57:09.889900 | 2026-06-08 13:57:10.509444 | controller | changed: .d..t...... ./ 2026-06-08 13:57:11.020668 | controller | changed: .d..t...... ./ 2026-06-08 13:57:11.033881 | 2026-06-08 13:57:11.033998 | TASK [include_role : fetch-output-openshift] 2026-06-08 13:57:11.060581 | controller | skipping: Conditional result was False 2026-06-08 13:57:11.066089 | 2026-06-08 13:57:11.066161 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-08 13:57:11.654030 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.012742 2026-06-08 13:57:11.995723 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.014035 2026-06-08 13:57:12.007453 | 2026-06-08 13:57:12.007510 | PLAY [all] 2026-06-08 13:57:12.012093 | 2026-06-08 13:57:12.012158 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-08 13:57:12.486481 | controller | changed 2026-06-08 13:57:12.491381 | 2026-06-08 13:57:12.491621 | PLAY RECAP 2026-06-08 13:57:12.491667 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-08 13:57:12.491686 | 2026-06-08 13:57:12.636442 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-06-08 13:57:12.637701 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2026-06-08 13:57:13.249128 | 2026-06-08 13:57:13.249266 | PLAY [localhost] 2026-06-08 13:57:13.260073 | 2026-06-08 13:57:13.260150 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-08 13:57:13.720398 | localhost | changed 2026-06-08 13:57:13.730458 | 2026-06-08 13:57:13.730603 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-08 13:57:13.769180 | localhost | ok 2026-06-08 13:57:13.775122 | 2026-06-08 13:57:13.775186 | TASK [add-fileserver : Create SSH private key tempfile] 2026-06-08 13:57:14.252703 | localhost | changed 2026-06-08 13:57:14.263159 | 2026-06-08 13:57:14.263319 | TASK [add-fileserver : Create SSH private key from secret] 2026-06-08 13:57:15.203992 | localhost | changed 2026-06-08 13:57:15.208584 | 2026-06-08 13:57:15.208656 | TASK [add-fileserver : Add fileserver ssh key] 2026-06-08 13:57:15.726843 | localhost | Identity added: /var/lib/zuul/builds/ca714a9ccaf14a92b32a88327653d822/work/tmp/ansible.l_fajmga (/var/lib/zuul/builds/ca714a9ccaf14a92b32a88327653d822/work/tmp/ansible.l_fajmga) 2026-06-08 13:57:15.727191 | localhost | ok: Runtime: 0:00:00.021546 2026-06-08 13:57:15.734431 | 2026-06-08 13:57:15.734573 | TASK [add-fileserver : Remove SSH private key from disk] 2026-06-08 13:57:16.077804 | localhost | ok: Runtime: 0:00:00.010399 2026-06-08 13:57:16.086129 | 2026-06-08 13:57:16.086267 | TASK [add-fileserver : Add fileserver to inventory] 2026-06-08 13:57:16.161251 | localhost | changed 2026-06-08 13:57:16.171441 | 2026-06-08 13:57:16.171609 | TASK [add-fileserver : Add fileserver server to known hosts] 2026-06-08 13:57:16.700718 | localhost | changed 2026-06-08 13:57:16.707525 | 2026-06-08 13:57:16.707598 | PLAY [localhost] 2026-06-08 13:57:16.713198 | 2026-06-08 13:57:16.713280 | TASK [Generate bulk log download script] 2026-06-08 13:57:16.742919 | localhost | ok 2026-06-08 13:57:16.756634 | 2026-06-08 13:57:16.756716 | TASK [local-log-download : Check API endpoint is defined] 2026-06-08 13:57:16.808506 | localhost | ok: All assertions passed 2026-06-08 13:57:16.816173 | 2026-06-08 13:57:16.816300 | TASK [local-log-download : Create download script] 2026-06-08 13:57:17.441715 | localhost -> localhost | changed 2026-06-08 13:57:17.524036 | 2026-06-08 13:57:17.524282 | TASK [Register quick-download link] 2026-06-08 13:57:17.569886 | localhost | ok 2026-06-08 13:57:17.574225 | 2026-06-08 13:57:17.574337 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com] 2026-06-08 13:57:17.584025 | 2026-06-08 13:57:17.584146 | TASK [Set zuul-log-path fact] 2026-06-08 13:57:17.612822 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-08 13:57:17.625535 | 2026-06-08 13:57:17.625665 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-08 13:57:17.666420 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-08 13:57:17.672137 | 2026-06-08 13:57:17.672267 | TASK [upload-logs : Create log directories] 2026-06-08 13:57:18.624587 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed 2026-06-08 13:57:18.628328 | 2026-06-08 13:57:18.628492 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-08 13:57:18.986456 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.015383 2026-06-08 13:57:18.993442 | 2026-06-08 13:57:18.993635 | TASK [upload-logs : Upload logs to log server] 2026-06-08 13:57:20.385510 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | Output suppressed because no_log was given 2026-06-08 13:57:20.387585 | 2026-06-08 13:57:20.387656 | LOOP [upload-logs : Compress console log and json output]