2026-06-08 07:52:28.444627 | Job console starting 2026-06-08 07:52:28.556065 | Updating git repos 2026-06-08 07:52:28.584922 | Cloning repos into workspace 2026-06-08 07:52:29.202413 | Restoring repo states 2026-06-08 07:52:29.216234 | Merging changes 2026-06-08 07:52:30.373783 | Checking out repos 2026-06-08 07:52:30.467174 | Preparing playbooks 2026-06-08 07:52:34.163276 | Running Ansible setup 2026-06-08 07:52:37.886857 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-06-08 07:52:38.513946 | 2026-06-08 07:52:38.514241 | PLAY [localhost] 2026-06-08 07:52:38.521936 | 2026-06-08 07:52:38.522021 | TASK [Gathering Facts] 2026-06-08 07:52:39.531215 | localhost | ok 2026-06-08 07:52:39.544809 | 2026-06-08 07:52:39.544982 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-08 07:52:40.028085 | localhost -> localhost | changed 2026-06-08 07:52:40.037791 | 2026-06-08 07:52:40.037947 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-08 07:52:41.037262 | localhost -> localhost | changed 2026-06-08 07:52:41.049583 | 2026-06-08 07:52:41.049682 | TASK [Setup log path fact] 2026-06-08 07:52:41.068866 | localhost | ok 2026-06-08 07:52:41.088485 | 2026-06-08 07:52:41.088648 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-08 07:52:41.119122 | localhost | ok 2026-06-08 07:52:41.126849 | 2026-06-08 07:52:41.126945 | TASK [emit-job-header : Print job information] 2026-06-08 07:52:41.166299 | # Job Information 2026-06-08 07:52:41.166497 | Ansible Version: 2.16.18 2026-06-08 07:52:41.166535 | Job: cifmw-molecule-cleanup_openstack 2026-06-08 07:52:41.166561 | Pipeline: github-check 2026-06-08 07:52:41.166585 | Executor: 38.102.83.47 2026-06-08 07:52:41.166608 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3866 2026-06-08 07:52:41.166637 | Log URL (when completed): https://gateway-cloud-softwarefactory.apps.ocp.cloud.ci.centos.org/logs/794/rdoproject.org/794872cb7b9d400bb8bdf01143e7179e/ 2026-06-08 07:52:41.166662 | Event ID: c2a19f60-630e-11f1-88eb-9ec9555ba031 2026-06-08 07:52:41.170469 | 2026-06-08 07:52:41.170563 | LOOP [emit-job-header : Print node information] 2026-06-08 07:52:41.259076 | localhost | ok: 2026-06-08 07:52:41.259406 | localhost | # Node Information 2026-06-08 07:52:41.259541 | localhost | Inventory Hostname: controller 2026-06-08 07:52:41.259627 | localhost | Hostname: np0000066870 2026-06-08 07:52:41.259702 | localhost | Username: zuul 2026-06-08 07:52:41.259778 | localhost | Distro: CentOS 9 2026-06-08 07:52:41.259848 | localhost | Provider: vexxhost-nodepool-tripleo 2026-06-08 07:52:41.260035 | localhost | Region: RegionOne 2026-06-08 07:52:41.260123 | localhost | Label: cloud-centos-9-stream-tripleo 2026-06-08 07:52:41.260186 | localhost | Product Name: OpenStack Nova 2026-06-08 07:52:41.260251 | localhost | Interface IP: 38.102.83.20 2026-06-08 07:52:41.271284 | 2026-06-08 07:52:41.271400 | PLAY [all] 2026-06-08 07:52:41.286014 | 2026-06-08 07:52:41.286201 | TASK [Gather network facts] 2026-06-08 07:52:41.767217 | controller | ok 2026-06-08 07:52:41.774930 | 2026-06-08 07:52:41.775022 | TASK [include_role : start-zuul-console] 2026-06-08 07:52:41.807407 | controller | ok 2026-06-08 07:52:41.827412 | 2026-06-08 07:52:41.827584 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-06-08 07:52:42.254811 | controller | ok 2026-06-08 07:52:42.267688 | 2026-06-08 07:52:42.267831 | TASK [include_role : add-build-sshkey] 2026-06-08 07:52:42.300892 | controller | ok 2026-06-08 07:52:42.322697 | 2026-06-08 07:52:42.322832 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-08 07:52:42.683187 | controller -> localhost | ok 2026-06-08 07:52:42.692478 | 2026-06-08 07:52:42.692615 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-08 07:52:42.727583 | controller | ok 2026-06-08 07:52:42.759379 | controller | included: /var/lib/zuul/builds/794872cb7b9d400bb8bdf01143e7179e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-08 07:52:42.769647 | 2026-06-08 07:52:42.769816 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-08 07:52:43.457938 | controller -> localhost | Generating public/private rsa key pair. 2026-06-08 07:52:43.458138 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/794872cb7b9d400bb8bdf01143e7179e/work/794872cb7b9d400bb8bdf01143e7179e_id_rsa 2026-06-08 07:52:43.458169 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/794872cb7b9d400bb8bdf01143e7179e/work/794872cb7b9d400bb8bdf01143e7179e_id_rsa.pub 2026-06-08 07:52:43.458191 | controller -> localhost | The key fingerprint is: 2026-06-08 07:52:43.458211 | controller -> localhost | SHA256:5q58swPEYY2wDaI9bQhsCaByyCOU/9kz8VSao13xypE zuul-build-sshkey 2026-06-08 07:52:43.458229 | controller -> localhost | The key's randomart image is: 2026-06-08 07:52:43.458247 | controller -> localhost | +---[RSA 3072]----+ 2026-06-08 07:52:43.458266 | controller -> localhost | |B.+ o. o | 2026-06-08 07:52:43.458284 | controller -> localhost | |+X + ++ . o | 2026-06-08 07:52:43.458302 | controller -> localhost | |Bo* +o.. + + | 2026-06-08 07:52:43.458321 | controller -> localhost | |o..+ o. = E . | 2026-06-08 07:52:43.458339 | controller -> localhost | | ..o S + o | 2026-06-08 07:52:43.458356 | controller -> localhost | | o.B o o | 2026-06-08 07:52:43.458373 | controller -> localhost | | .+ | 2026-06-08 07:52:43.458391 | controller -> localhost | | . .+ | 2026-06-08 07:52:43.458408 | controller -> localhost | | ooo+ | 2026-06-08 07:52:43.458425 | controller -> localhost | +----[SHA256]-----+ 2026-06-08 07:52:43.458494 | controller -> localhost | ok: Runtime: 0:00:00.141780 2026-06-08 07:52:43.462136 | 2026-06-08 07:52:43.462204 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-08 07:52:43.491466 | controller | ok 2026-06-08 07:52:43.499958 | controller | included: /var/lib/zuul/builds/794872cb7b9d400bb8bdf01143e7179e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-08 07:52:43.506536 | 2026-06-08 07:52:43.506599 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-08 07:52:43.529789 | controller | skipping: Conditional result was False 2026-06-08 07:52:43.534607 | 2026-06-08 07:52:43.534678 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-08 07:52:44.077058 | controller | changed 2026-06-08 07:52:44.084965 | 2026-06-08 07:52:44.085138 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-08 07:52:44.333049 | controller | ok 2026-06-08 07:52:44.337845 | 2026-06-08 07:52:44.337939 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-08 07:52:45.203146 | controller | changed 2026-06-08 07:52:45.211656 | 2026-06-08 07:52:45.211800 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-08 07:52:46.117109 | controller | changed 2026-06-08 07:52:46.124743 | 2026-06-08 07:52:46.124885 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-08 07:52:46.152874 | controller | skipping: Conditional result was False 2026-06-08 07:52:46.166414 | 2026-06-08 07:52:46.166770 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-08 07:52:46.640407 | controller -> localhost | changed 2026-06-08 07:52:46.656745 | 2026-06-08 07:52:46.656898 | TASK [add-build-sshkey : Add back temp key] 2026-06-08 07:52:46.963811 | controller -> localhost | Identity added: /var/lib/zuul/builds/794872cb7b9d400bb8bdf01143e7179e/work/794872cb7b9d400bb8bdf01143e7179e_id_rsa (zuul-build-sshkey) 2026-06-08 07:52:46.964154 | controller -> localhost | ok: Runtime: 0:00:00.011730 2026-06-08 07:52:46.971692 | 2026-06-08 07:52:46.971823 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-08 07:52:47.341324 | controller | ok 2026-06-08 07:52:47.350603 | 2026-06-08 07:52:47.350734 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-08 07:52:47.392791 | controller | skipping: Conditional result was False 2026-06-08 07:52:47.407703 | 2026-06-08 07:52:47.407842 | TASK [include_role : validate-host] 2026-06-08 07:52:47.448883 | controller | ok 2026-06-08 07:52:47.479682 | 2026-06-08 07:52:47.479782 | TASK [validate-host : Define zuul_info_dir fact] 2026-06-08 07:52:47.519357 | controller | ok 2026-06-08 07:52:47.523767 | 2026-06-08 07:52:47.523855 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-06-08 07:52:47.863108 | controller -> localhost | ok 2026-06-08 07:52:47.873079 | 2026-06-08 07:52:47.873215 | TASK [validate-host : Collect information about the host] 2026-06-08 07:52:48.626869 | controller | ok 2026-06-08 07:52:48.643483 | 2026-06-08 07:52:48.643691 | TASK [validate-host : Sanitize hostname] 2026-06-08 07:52:48.711531 | controller | ok 2026-06-08 07:52:48.722996 | 2026-06-08 07:52:48.723196 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-06-08 07:52:49.278620 | controller -> localhost | changed 2026-06-08 07:52:49.291559 | 2026-06-08 07:52:49.291706 | TASK [validate-host : Collect information about zuul worker] 2026-06-08 07:52:49.756413 | controller | ok 2026-06-08 07:52:49.759956 | 2026-06-08 07:52:49.760023 | TASK [validate-host : Write out all zuul information for each host] 2026-06-08 07:52:50.350027 | controller -> localhost | changed 2026-06-08 07:52:50.365425 | 2026-06-08 07:52:50.365592 | TASK [include_role : prepare-workspace-openshift] 2026-06-08 07:52:50.392361 | controller | skipping: Conditional result was False 2026-06-08 07:52:50.400381 | 2026-06-08 07:52:50.400548 | TASK [include_role : remove-zuul-sshkey] 2026-06-08 07:52:50.426930 | controller | skipping: Conditional result was False 2026-06-08 07:52:50.435537 | 2026-06-08 07:52:50.435672 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-08 07:52:50.712189 | controller | ok: "logs" 2026-06-08 07:52:50.712769 | controller | ok: All items complete 2026-06-08 07:52:50.712827 | 2026-06-08 07:52:50.966282 | controller | ok: "artifacts" 2026-06-08 07:52:51.226726 | controller | ok: "docs" 2026-06-08 07:52:51.238160 | 2026-06-08 07:52:51.238412 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-08 07:52:51.495719 | controller | changed: "logs" 2026-06-08 07:52:51.734767 | controller | changed: "artifacts" 2026-06-08 07:52:51.945862 | controller | changed: "docs" 2026-06-08 07:52:51.957548 | 2026-06-08 07:52:51.957666 | PLAY RECAP 2026-06-08 07:52:51.957753 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2026-06-08 07:52:51.957807 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-08 07:52:51.957843 | 2026-06-08 07:52:52.123828 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-06-08 07:52:52.125013 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-06-08 07:52:52.735671 | 2026-06-08 07:52:52.735999 | PLAY [localhost] 2026-06-08 07:52:52.752028 | 2026-06-08 07:52:52.752122 | TASK [configure-ssh-config-fips : Ensure ssh config directory] 2026-06-08 07:52:53.174917 | localhost | changed 2026-06-08 07:52:53.179226 | 2026-06-08 07:52:53.179320 | TASK [configure-ssh-config-fips : Setup ssh config] 2026-06-08 07:52:53.993901 | localhost | changed 2026-06-08 07:52:54.006756 | 2026-06-08 07:52:54.006869 | PLAY [all] 2026-06-08 07:52:54.020211 | 2026-06-08 07:52:54.020358 | TASK [include_role : prepare-workspace] 2026-06-08 07:52:54.055660 | controller | ok 2026-06-08 07:52:54.082498 | 2026-06-08 07:52:54.082587 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-08 07:52:54.516069 | controller | ok 2026-06-08 07:52:54.521867 | 2026-06-08 07:52:54.521954 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-08 07:52:56.062130 | controller | Output suppressed because no_log was given 2026-06-08 07:52:56.077883 | 2026-06-08 07:52:56.078028 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-08 07:52:56.377881 | controller | changed: "logs" 2026-06-08 07:52:56.588581 | controller | changed: "artifacts" 2026-06-08 07:52:56.818750 | controller | changed: "docs" 2026-06-08 07:52:56.837294 | 2026-06-08 07:52:56.837543 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-08 07:52:57.147730 | controller | changed: "logs" 2026-06-08 07:52:57.148145 | controller | changed: All items complete 2026-06-08 07:52:57.148206 | 2026-06-08 07:52:57.391455 | controller | changed: "artifacts" 2026-06-08 07:52:57.629883 | controller | changed: "docs" 2026-06-08 07:52:57.659538 | 2026-06-08 07:52:57.659737 | TASK [Check if worker can sudo] 2026-06-08 07:52:58.201026 | controller | ok: Runtime: 0:00:00.046047 2026-06-08 07:52:58.207467 | 2026-06-08 07:52:58.207559 | TASK [configure-mirrors : Gather needed facts] 2026-06-08 07:52:58.283296 | controller | skipping: Conditional result was False 2026-06-08 07:52:58.293761 | 2026-06-08 07:52:58.293939 | TASK [configure-mirrors : Set up infrastructure mirrors] 2026-06-08 07:52:58.389993 | controller | ok 2026-06-08 07:52:58.405805 | controller | included: /var/lib/zuul/builds/794872cb7b9d400bb8bdf01143e7179e/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml 2026-06-08 07:52:58.417585 | 2026-06-08 07:52:58.417723 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir] 2026-06-08 07:52:58.729589 | controller | ok 2026-06-08 07:52:58.739102 | 2026-06-08 07:52:58.739231 | LOOP [configure-mirrors : Include OS-specific variables] 2026-06-08 07:52:58.836059 | controller | ok: "/var/lib/zuul/builds/794872cb7b9d400bb8bdf01143e7179e/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml" 2026-06-08 07:52:58.853849 | 2026-06-08 07:52:58.854008 | TASK [configure-mirrors : Install /etc/pip.conf configuration] 2026-06-08 07:52:59.809280 | controller | changed 2026-06-08 07:52:59.814751 | 2026-06-08 07:52:59.814835 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors] 2026-06-08 07:52:59.978959 | controller | ok: "/var/lib/zuul/builds/794872cb7b9d400bb8bdf01143e7179e/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml" 2026-06-08 07:52:59.979132 | controller | ok: All items complete 2026-06-08 07:52:59.979156 | 2026-06-08 07:53:00.026754 | controller | included: /var/lib/zuul/builds/794872cb7b9d400bb8bdf01143e7179e/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml 2026-06-08 07:53:00.032213 | 2026-06-08 07:53:00.032276 | LOOP [configure-mirrors : Install CentOS 9 repository files] 2026-06-08 07:53:01.045964 | controller | changed: "etc/yum.repos.d/centos.repo" 2026-06-08 07:53:01.938871 | controller | changed: "etc/yum.repos.d/centos-addons.repo" 2026-06-08 07:53:01.958262 | 2026-06-08 07:53:01.958502 | TASK [configure-mirrors : Disable deltrarpm] 2026-06-08 07:53:02.476918 | controller | changed: section and option added 2026-06-08 07:53:02.487499 | 2026-06-08 07:53:02.487567 | LOOP [configure-mirrors : Update yum/dnf cache] 2026-06-08 07:53:03.317816 | controller | 29 files removed 2026-06-08 07:53:03.318320 | controller | ok: Item: dnf clean all Runtime: 0:00:00.471610 2026-06-08 07:53:03.318402 | controller | changed: All items complete 2026-06-08 07:53:03.318489 | 2026-06-08 07:53:14.521819 | 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:53:14.522011 | controller | DNF version: 4.14.0 2026-06-08 07:53:14.522066 | controller | cachedir: /var/cache/dnf 2026-06-08 07:53:14.522109 | controller | Making cache files for all metadata files. 2026-06-08 07:53:14.522147 | controller | baseos: has expired and will be refreshed. 2026-06-08 07:53:14.522203 | controller | appstream: has expired and will be refreshed. 2026-06-08 07:53:14.522240 | controller | crb: has expired and will be refreshed. 2026-06-08 07:53:14.522275 | controller | extras-common: has expired and will be refreshed. 2026-06-08 07:53:14.522309 | controller | repo: downloading from remote: baseos 2026-06-08 07:53:14.522371 | controller | CentOS Stream 9 - BaseOS 78 MB/s | 8.9 MB 00:00 2026-06-08 07:53:14.522408 | controller | baseos: using metadata from Mon 01 Jun 2026 05:58:58 PM EDT. 2026-06-08 07:53:14.522484 | controller | repo: downloading from remote: appstream 2026-06-08 07:53:14.522523 | controller | CentOS Stream 9 - AppStream 122 MB/s | 28 MB 00:00 2026-06-08 07:53:14.522558 | controller | appstream: using metadata from Mon 01 Jun 2026 06:02:34 PM EDT. 2026-06-08 07:53:14.522592 | controller | repo: downloading from remote: crb 2026-06-08 07:53:14.522626 | controller | CentOS Stream 9 - CRB 76 MB/s | 8.1 MB 00:00 2026-06-08 07:53:14.522661 | controller | crb: using metadata from Mon 01 Jun 2026 06:07:05 PM EDT. 2026-06-08 07:53:14.522695 | controller | repo: downloading from remote: extras-common 2026-06-08 07:53:14.522730 | controller | CentOS Stream 9 - Extras packages 1.6 MB/s | 21 kB 00:00 2026-06-08 07:53:14.522767 | controller | extras-common: using metadata from Tue 24 Mar 2026 03:36:22 PM EDT. 2026-06-08 07:53:14.522801 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)' 2026-06-08 07:53:14.522835 | controller | Completion plugin: Generating completion cache... 2026-06-08 07:53:14.522869 | controller | Metadata cache created. 2026-06-08 07:53:14.522921 | controller | ok: Item: dnf makecache -v Runtime: 0:00:10.909490 2026-06-08 07:53:14.535540 | 2026-06-08 07:53:14.535643 | PLAY RECAP 2026-06-08 07:53:14.535850 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-08 07:53:14.535924 | localhost | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-08 07:53:14.535962 | 2026-06-08 07:53:14.699996 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-06-08 07:53:14.702857 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-06-08 07:53:15.366853 | 2026-06-08 07:53:15.366979 | PLAY [all] 2026-06-08 07:53:15.377499 | 2026-06-08 07:53:15.377570 | TASK [Install binary dependencies] 2026-06-08 07:53:15.466557 | controller | ok 2026-06-08 07:53:15.483534 | 2026-06-08 07:53:15.483615 | TASK [bindep : Include find tasks] 2026-06-08 07:53:15.535745 | controller | ok 2026-06-08 07:53:15.548276 | controller | included: /var/lib/zuul/builds/794872cb7b9d400bb8bdf01143e7179e/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-06-08 07:53:15.557995 | 2026-06-08 07:53:15.558121 | TASK [bindep : Look for bindep.txt] 2026-06-08 07:53:16.068264 | controller | ok 2026-06-08 07:53:16.078292 | 2026-06-08 07:53:16.078474 | TASK [bindep : Define bindep_file fact] 2026-06-08 07:53:16.123692 | controller | ok 2026-06-08 07:53:16.133597 | 2026-06-08 07:53:16.133729 | TASK [bindep : Look for other-requirements.txt] 2026-06-08 07:53:16.159182 | controller | skipping: Conditional result was False 2026-06-08 07:53:16.168216 | 2026-06-08 07:53:16.168360 | TASK [bindep : Define bindep_file fact] 2026-06-08 07:53:16.203704 | controller | skipping: Conditional result was False 2026-06-08 07:53:16.213643 | 2026-06-08 07:53:16.213791 | TASK [bindep : Look for bindep fallback file] 2026-06-08 07:53:16.259615 | controller | skipping: Conditional result was False 2026-06-08 07:53:16.268946 | 2026-06-08 07:53:16.269122 | TASK [bindep : Define bindep_file fact] 2026-06-08 07:53:16.306792 | controller | skipping: Conditional result was False 2026-06-08 07:53:16.316500 | 2026-06-08 07:53:16.316644 | TASK [bindep : Include bindep tasks] 2026-06-08 07:53:16.360562 | controller | ok 2026-06-08 07:53:16.372832 | controller | included: /var/lib/zuul/builds/794872cb7b9d400bb8bdf01143e7179e/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml 2026-06-08 07:53:16.390008 | 2026-06-08 07:53:16.390133 | TASK [bindep : Look for bindep command] 2026-06-08 07:53:16.435477 | controller | skipping: Conditional result was False 2026-06-08 07:53:16.442828 | 2026-06-08 07:53:16.442912 | TASK [bindep : Check for system bindep] 2026-06-08 07:53:16.979425 | controller | ok: Runtime: 0:00:00.007351 2026-06-08 07:53:16.988119 | 2026-06-08 07:53:16.988321 | TASK [bindep : Define bindep_command fact] 2026-06-08 07:53:17.025636 | controller | skipping: Conditional result was False 2026-06-08 07:53:17.036716 | 2026-06-08 07:53:17.036930 | TASK [bindep : Include install tasks] 2026-06-08 07:53:17.083394 | controller | ok 2026-06-08 07:53:17.097293 | controller | included: /var/lib/zuul/builds/794872cb7b9d400bb8bdf01143e7179e/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml 2026-06-08 07:53:17.118806 | 2026-06-08 07:53:17.118973 | TASK [bindep : Create temp dir for bindep] 2026-06-08 07:53:17.513981 | controller | changed 2026-06-08 07:53:17.522947 | 2026-06-08 07:53:17.523144 | TASK [Ensure we have pip dependencies] 2026-06-08 07:53:17.549851 | controller | ok 2026-06-08 07:53:17.589393 | 2026-06-08 07:53:17.589582 | TASK [ensure-pip : Check if pip is installed] 2026-06-08 03:53:17.883445 | controller | /usr/bin/pip3 2026-06-08 03:53:17.924789 | controller | /usr/bin/python3: No module named wheel 2026-06-08 07:53:18.130389 | controller | ok: Runtime: 0:00:00.054582 2026-06-08 07:53:18.139897 | 2026-06-08 07:53:18.140037 | LOOP [ensure-pip : Install pip from packages] 2026-06-08 07:53:18.174013 | controller | ok: "/var/lib/zuul/builds/794872cb7b9d400bb8bdf01143e7179e/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml" 2026-06-08 07:53:18.193303 | controller | included: /var/lib/zuul/builds/794872cb7b9d400bb8bdf01143e7179e/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml 2026-06-08 07:53:18.254302 | 2026-06-08 07:53:18.254477 | TASK [ensure-pip : Install Python 3 pip] 2026-06-08 07:53:19.764114 | controller | ok: Nothing to do 2026-06-08 07:53:19.768564 | 2026-06-08 07:53:19.768637 | TASK [ensure-pip : Install Python3-wheel on older CentOS] 2026-06-08 07:53:21.756357 | controller | changed 2026-06-08 07:53:21.760579 | 2026-06-08 07:53:21.760647 | TASK [ensure-pip : Check for EPEL repository] 2026-06-08 07:53:21.825876 | controller | skipping: Conditional result was False 2026-06-08 07:53:21.831723 | 2026-06-08 07:53:21.831792 | TASK [ensure-pip : Fail with instructions when EPEL is not installed] 2026-06-08 07:53:21.887295 | controller | skipping: Conditional result was False 2026-06-08 07:53:21.900676 | 2026-06-08 07:53:21.900822 | TASK [ensure-pip : Install Python 2 pip] 2026-06-08 07:53:21.948749 | controller | skipping: Conditional result was False 2026-06-08 07:53:21.963553 | 2026-06-08 07:53:21.963718 | TASK [ensure-pip : Ensure setuptools] 2026-06-08 07:53:21.996017 | controller | skipping: Conditional result was False 2026-06-08 07:53:22.006863 | 2026-06-08 07:53:22.007014 | TASK [ensure-pip : Check for ensurepip module] 2026-06-08 07:53:22.545763 | controller | skipping: Conditional result was False 2026-06-08 07:53:22.559949 | 2026-06-08 07:53:22.560183 | TASK [ensure-pip : Ensure python3-venv] 2026-06-08 07:53:22.597477 | controller | skipping: Conditional result was False 2026-06-08 07:53:22.610069 | 2026-06-08 07:53:22.610352 | TASK [ensure-pip : Install pip from source] 2026-06-08 07:53:22.638478 | controller | skipping: Conditional result was False 2026-06-08 07:53:22.648980 | 2026-06-08 07:53:22.649116 | TASK [ensure-pip : Probe for venv python full path] 2026-06-08 03:53:23.000368 | controller | /usr/bin/python3 2026-06-08 07:53:23.205253 | controller | ok: Runtime: 0:00:00.006175 2026-06-08 07:53:23.213900 | 2026-06-08 07:53:23.214040 | TASK [ensure-pip : Set host default] 2026-06-08 07:53:23.279270 | controller | ok 2026-06-08 07:53:23.287460 | 2026-06-08 07:53:23.287596 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2026-06-08 07:53:23.356942 | controller | ok 2026-06-08 07:53:23.374075 | 2026-06-08 07:53:23.374251 | TASK [bindep : Install bindep into temporary venv] 2026-06-08 07:53:27.720939 | controller | changed 2026-06-08 07:53:27.729341 | 2026-06-08 07:53:27.729569 | TASK [bindep : Define bindep_command] 2026-06-08 07:53:27.780841 | controller | ok 2026-06-08 07:53:27.789341 | 2026-06-08 07:53:27.789616 | LOOP [bindep : Include package tasks] 2026-06-08 07:53:27.876571 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt" 2026-06-08 07:53:27.877213 | controller | ok: All items complete 2026-06-08 07:53:27.877275 | 2026-06-08 07:53:27.899080 | controller | included: /var/lib/zuul/builds/794872cb7b9d400bb8bdf01143e7179e/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml 2026-06-08 07:53:27.925223 | 2026-06-08 07:53:27.925406 | TASK [bindep : Define bindep_run fact] 2026-06-08 07:53:27.972796 | controller | ok 2026-06-08 07:53:27.978096 | 2026-06-08 07:53:27.978246 | TASK [bindep : Get list of packages to install from bindep] 2026-06-08 03:53:29.522964 | controller | podman 2026-06-08 03:53:29.523065 | controller | python3-jmespath 2026-06-08 03:53:29.523077 | controller | python3-libvirt 2026-06-08 03:53:29.523087 | controller | python3-lxml 2026-06-08 03:53:29.523098 | controller | python3-netaddr 2026-06-08 07:53:30.026709 | controller | ok: Runtime: 0:00:01.230301 2026-06-08 07:53:30.036521 | 2026-06-08 07:53:30.036994 | TASK [bindep : Install distro packages from bindep] 2026-06-08 07:54:12.133186 | controller | changed 2026-06-08 07:54:12.141818 | 2026-06-08 07:54:12.141951 | TASK [bindep : Check that packages are installed] 2026-06-08 07:54:13.690681 | controller | ok: Runtime: 0:00:01.206236 2026-06-08 07:54:13.699470 | 2026-06-08 07:54:13.699607 | TASK [bindep : Fail if we cannot install all packages] 2026-06-08 07:54:13.725480 | controller | skipping: Conditional result was False 2026-06-08 07:54:13.746202 | 2026-06-08 07:54:13.746377 | TASK [Run test-setup role] 2026-06-08 07:54:13.772129 | controller | ok 2026-06-08 07:54:13.796083 | 2026-06-08 07:54:13.796172 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-06-08 07:54:14.063182 | controller | ok 2026-06-08 07:54:14.072658 | 2026-06-08 07:54:14.072803 | TASK [test-setup : Run tools/test-setup.sh] 2026-06-08 07:54:14.610983 | controller | skipping: Conditional result was False 2026-06-08 07:54:14.635403 | 2026-06-08 07:54:14.635614 | TASK [bindep : Remove bindep temp dir] 2026-06-08 07:54:15.077770 | controller | ok 2026-06-08 07:54:15.080279 | 2026-06-08 07:54:15.080527 | PLAY RECAP 2026-06-08 07:54:15.080627 | controller | ok: 23 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0 2026-06-08 07:54:15.080672 | 2026-06-08 07:54:15.249134 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-06-08 07:54:15.252487 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2026-06-08 07:54:15.895534 | 2026-06-08 07:54:15.895673 | PLAY [all] 2026-06-08 07:54:15.904569 | 2026-06-08 07:54:15.904646 | TASK [Abort when test_command variable is undefined] 2026-06-08 07:54:15.948829 | controller | skipping: Conditional result was False 2026-06-08 07:54:15.953809 | 2026-06-08 07:54:15.953899 | TASK [Convert test_command to list] 2026-06-08 07:54:16.017463 | controller | skipping: Conditional result was False 2026-06-08 07:54:16.021582 | 2026-06-08 07:54:16.021650 | TASK [Use test_command list] 2026-06-08 07:54:16.088254 | controller | ok 2026-06-08 07:54:16.093643 | 2026-06-08 07:54:16.093708 | LOOP [Run test_command] 2026-06-08 07:54:16.525581 | controller | no check to run 2026-06-08 07:54:16.525792 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.005309 2026-06-08 07:54:16.534422 | 2026-06-08 07:54:16.534547 | PLAY RECAP 2026-06-08 07:54:16.534631 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-08 07:54:16.534675 | 2026-06-08 07:54:16.675396 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2026-06-08 07:54:16.676774 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-08 07:54:17.237860 | 2026-06-08 07:54:17.237984 | PLAY [all] 2026-06-08 07:54:17.248358 | 2026-06-08 07:54:17.248448 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-06-08 07:54:17.651559 | controller | changed: non-zero return code 2026-06-08 07:54:17.656979 | 2026-06-08 07:54:17.657081 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-06-08 07:54:17.682285 | controller | skipping: Conditional result was False 2026-06-08 07:54:17.688960 | 2026-06-08 07:54:17.689055 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-06-08 07:54:17.720014 | 2026-06-08 07:54:17.720182 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-06-08 07:54:17.760123 | 2026-06-08 07:54:17.760301 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-06-08 07:54:17.784756 | controller | skipping: Conditional result was False 2026-06-08 07:54:17.790505 | 2026-06-08 07:54:17.790598 | LOOP [fetch-subunit-output : Generate subunit file] 2026-06-08 07:54:17.820198 | 2026-06-08 07:54:17.820367 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-06-08 07:54:17.844933 | controller | skipping: Conditional result was False 2026-06-08 07:54:17.853688 | 2026-06-08 07:54:17.853835 | TASK [fetch-subunit-output : Remove the temporary file] 2026-06-08 07:54:17.879593 | controller | skipping: Conditional result was False 2026-06-08 07:54:17.888117 | 2026-06-08 07:54:17.888253 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-06-08 07:54:17.914177 | controller | skipping: Conditional result was False 2026-06-08 07:54:17.927063 | 2026-06-08 07:54:17.927159 | PLAY RECAP 2026-06-08 07:54:17.927243 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-06-08 07:54:17.927285 | 2026-06-08 07:54:18.054588 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-08 07:54:18.057165 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-06-08 07:54:18.737841 | 2026-06-08 07:54:18.738006 | PLAY [all] 2026-06-08 07:54:18.748812 | 2026-06-08 07:54:18.748976 | TASK [include_role : fetch-output] 2026-06-08 07:54:18.798487 | controller | ok 2026-06-08 07:54:18.813560 | 2026-06-08 07:54:18.813656 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-08 07:54:18.889184 | controller | skipping: Conditional result was False 2026-06-08 07:54:18.898346 | 2026-06-08 07:54:18.898539 | TASK [fetch-output : Set log path for single node] 2026-06-08 07:54:18.953921 | controller | ok 2026-06-08 07:54:18.962350 | 2026-06-08 07:54:18.962525 | LOOP [fetch-output : Ensure local output dirs] 2026-06-08 07:54:19.451391 | controller -> localhost | changed: "/var/lib/zuul/builds/794872cb7b9d400bb8bdf01143e7179e/work/logs" 2026-06-08 07:54:19.701977 | controller -> localhost | changed: "/var/lib/zuul/builds/794872cb7b9d400bb8bdf01143e7179e/work/artifacts" 2026-06-08 07:54:19.986758 | controller -> localhost | changed: "/var/lib/zuul/builds/794872cb7b9d400bb8bdf01143e7179e/work/docs" 2026-06-08 07:54:19.995025 | 2026-06-08 07:54:19.995171 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-08 07:54:20.786522 | controller | changed: .d..t...... ./ 2026-06-08 07:54:20.787508 | controller | changed: All items complete 2026-06-08 07:54:20.787931 | 2026-06-08 07:54:21.363679 | controller | changed: .d..t...... ./ 2026-06-08 07:54:21.920729 | controller | changed: .d..t...... ./ 2026-06-08 07:54:21.937394 | 2026-06-08 07:54:21.937576 | TASK [include_role : fetch-output-openshift] 2026-06-08 07:54:21.965137 | controller | skipping: Conditional result was False 2026-06-08 07:54:21.979336 | 2026-06-08 07:54:21.979523 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-08 07:54:22.516648 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.013540 2026-06-08 07:54:22.808558 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.013557 2026-06-08 07:54:22.836427 | 2026-06-08 07:54:22.836720 | PLAY [all] 2026-06-08 07:54:22.847417 | 2026-06-08 07:54:22.847586 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-08 07:54:23.339360 | controller | changed 2026-06-08 07:54:23.349185 | 2026-06-08 07:54:23.349295 | PLAY RECAP 2026-06-08 07:54:23.349385 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-08 07:54:23.349478 | 2026-06-08 07:54:23.491220 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-06-08 07:54:23.493812 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2026-06-08 07:54:24.213235 | 2026-06-08 07:54:24.213384 | PLAY [localhost] 2026-06-08 07:54:24.223982 | 2026-06-08 07:54:24.224067 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-08 07:54:24.701009 | localhost | changed 2026-06-08 07:54:24.721746 | 2026-06-08 07:54:24.722057 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-08 07:54:24.765706 | localhost | ok 2026-06-08 07:54:24.779613 | 2026-06-08 07:54:24.779764 | TASK [add-fileserver : Create SSH private key tempfile] 2026-06-08 07:54:25.219689 | localhost | changed 2026-06-08 07:54:25.230702 | 2026-06-08 07:54:25.230846 | TASK [add-fileserver : Create SSH private key from secret] 2026-06-08 07:54:26.090186 | localhost | changed 2026-06-08 07:54:26.099477 | 2026-06-08 07:54:26.099625 | TASK [add-fileserver : Add fileserver ssh key] 2026-06-08 07:54:26.574597 | localhost | Identity added: /var/lib/zuul/builds/794872cb7b9d400bb8bdf01143e7179e/work/tmp/ansible.2epf5jjf (/var/lib/zuul/builds/794872cb7b9d400bb8bdf01143e7179e/work/tmp/ansible.2epf5jjf) 2026-06-08 07:54:26.574929 | localhost | ok: Runtime: 0:00:00.020965 2026-06-08 07:54:26.582385 | 2026-06-08 07:54:26.582565 | TASK [add-fileserver : Remove SSH private key from disk] 2026-06-08 07:54:26.939363 | localhost | ok: Runtime: 0:00:00.013639 2026-06-08 07:54:26.943414 | 2026-06-08 07:54:26.943513 | TASK [add-fileserver : Add fileserver to inventory] 2026-06-08 07:54:27.027081 | localhost | changed 2026-06-08 07:54:27.036911 | 2026-06-08 07:54:27.037059 | TASK [add-fileserver : Add fileserver server to known hosts] 2026-06-08 07:54:27.516921 | localhost | changed 2026-06-08 07:54:27.528545 | 2026-06-08 07:54:27.528693 | PLAY [localhost] 2026-06-08 07:54:27.540326 | 2026-06-08 07:54:27.540517 | TASK [Generate bulk log download script] 2026-06-08 07:54:27.564188 | localhost | ok 2026-06-08 07:54:27.586291 | 2026-06-08 07:54:27.586473 | TASK [local-log-download : Check API endpoint is defined] 2026-06-08 07:54:27.636842 | localhost | ok: All assertions passed 2026-06-08 07:54:27.641909 | 2026-06-08 07:54:27.641999 | TASK [local-log-download : Create download script] 2026-06-08 07:54:28.226011 | localhost -> localhost | changed 2026-06-08 07:54:28.285711 | 2026-06-08 07:54:28.285875 | TASK [Register quick-download link] 2026-06-08 07:54:28.305131 | localhost | ok 2026-06-08 07:54:28.307696 | 2026-06-08 07:54:28.307767 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com] 2026-06-08 07:54:28.313675 | 2026-06-08 07:54:28.313752 | TASK [Set zuul-log-path fact] 2026-06-08 07:54:28.328872 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-08 07:54:28.337068 | 2026-06-08 07:54:28.337151 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-08 07:54:28.374350 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-08 07:54:28.378996 | 2026-06-08 07:54:28.379124 | TASK [upload-logs : Create log directories] 2026-06-08 07:54:29.272144 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed 2026-06-08 07:54:29.275825 | 2026-06-08 07:54:29.275965 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-08 07:54:29.689967 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.010382 2026-06-08 07:54:29.694238 | 2026-06-08 07:54:29.694332 | TASK [upload-logs : Upload logs to log server] 2026-06-08 07:54:30.967108 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | Output suppressed because no_log was given 2026-06-08 07:54:30.971131 | 2026-06-08 07:54:30.971314 | LOOP [upload-logs : Compress console log and json output]