2026-06-09 07:25:37.928499 | Job console starting
2026-06-09 07:25:38.065996 | Updating git repos
2026-06-09 07:25:38.101023 | Cloning repos into workspace
2026-06-09 07:25:38.722764 | Restoring repo states
2026-06-09 07:25:38.746033 | Merging changes
2026-06-09 07:25:39.773571 | Checking out repos
2026-06-09 07:25:39.922930 | Preparing playbooks
2026-06-09 07:25:43.782101 | Running Ansible setup
2026-06-09 07:25:47.548660 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-06-09 07:25:48.263464 | 
2026-06-09 07:25:48.263630 | PLAY [localhost]
2026-06-09 07:25:48.273774 | 
2026-06-09 07:25:48.273891 | TASK [Gathering Facts]
2026-06-09 07:25:49.348625 | localhost | ok
2026-06-09 07:25:49.357583 | 
2026-06-09 07:25:49.357689 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-06-09 07:25:49.819338 | localhost -> localhost | changed
2026-06-09 07:25:49.824533 | 
2026-06-09 07:25:49.824623 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-06-09 07:25:50.903777 | localhost -> localhost | changed
2026-06-09 07:25:50.917529 | 
2026-06-09 07:25:50.919518 | TASK [Setup log path fact]
2026-06-09 07:25:50.953639 | localhost | ok
2026-06-09 07:25:50.975643 | 
2026-06-09 07:25:50.975811 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-09 07:25:51.006422 | localhost | ok
2026-06-09 07:25:51.013972 | 
2026-06-09 07:25:51.014083 | TASK [emit-job-header : Print job information]
2026-06-09 07:25:51.053543 | # Job Information
2026-06-09 07:25:51.053712 | Ansible Version: 2.16.18
2026-06-09 07:25:51.053748 | Job: cifmw-molecule-cleanup_openstack
2026-06-09 07:25:51.053775 | Pipeline: github-check
2026-06-09 07:25:51.053800 | Executor: 38.102.83.47
2026-06-09 07:25:51.053824 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3866
2026-06-09 07:25:51.053851 | Log URL (when completed): https://gateway-cloud-softwarefactory.apps.ocp.cloud.ci.centos.org/logs/381/rdoproject.org/381a13ad12f74c88b7b6a78074697c96/
2026-06-09 07:25:51.053878 | Event ID: e8097820-63d3-11f1-9df2-2a5fbdc598a1
2026-06-09 07:25:51.061720 | 
2026-06-09 07:25:51.061826 | LOOP [emit-job-header : Print node information]
2026-06-09 07:25:51.169344 | localhost | ok:
2026-06-09 07:25:51.169600 | localhost | # Node Information
2026-06-09 07:25:51.169627 | localhost | Inventory Hostname: controller
2026-06-09 07:25:51.169650 | localhost | Hostname: np0000068209
2026-06-09 07:25:51.169668 | localhost | Username: zuul
2026-06-09 07:25:51.169687 | localhost | Distro: CentOS 9
2026-06-09 07:25:51.169704 | localhost | Provider: vexxhost-nodepool-tripleo
2026-06-09 07:25:51.169720 | localhost | Region: RegionOne
2026-06-09 07:25:51.169736 | localhost | Label: cloud-centos-9-stream-tripleo
2026-06-09 07:25:51.169752 | localhost | Product Name: OpenStack Nova
2026-06-09 07:25:51.169768 | localhost | Interface IP: 38.102.83.36
2026-06-09 07:25:51.188487 | 
2026-06-09 07:25:51.188648 | PLAY [all]
2026-06-09 07:25:51.199528 | 
2026-06-09 07:25:51.199661 | TASK [Gather network facts]
2026-06-09 07:25:52.443665 | controller | ok
2026-06-09 07:25:52.458016 | 
2026-06-09 07:25:52.458159 | TASK [include_role : start-zuul-console]
2026-06-09 07:25:52.485315 | controller | ok
2026-06-09 07:25:52.503253 | 
2026-06-09 07:25:52.503363 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-06-09 07:25:52.912232 | controller | ok
2026-06-09 07:25:52.924526 | 
2026-06-09 07:25:52.924676 | TASK [include_role : add-build-sshkey]
2026-06-09 07:25:52.959834 | controller | ok
2026-06-09 07:25:52.984799 | 
2026-06-09 07:25:52.985039 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-06-09 07:25:53.321172 | controller -> localhost | ok
2026-06-09 07:25:53.331675 | 
2026-06-09 07:25:53.331817 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-06-09 07:25:53.359676 | controller | ok
2026-06-09 07:25:53.389306 | controller | included: /var/lib/zuul/builds/381a13ad12f74c88b7b6a78074697c96/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-06-09 07:25:53.396347 | 
2026-06-09 07:25:53.396460 | TASK [add-build-sshkey : Create Temp SSH key]
2026-06-09 07:25:54.050556 | controller -> localhost | Generating public/private rsa key pair.
2026-06-09 07:25:54.050989 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/381a13ad12f74c88b7b6a78074697c96/work/381a13ad12f74c88b7b6a78074697c96_id_rsa
2026-06-09 07:25:54.051058 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/381a13ad12f74c88b7b6a78074697c96/work/381a13ad12f74c88b7b6a78074697c96_id_rsa.pub
2026-06-09 07:25:54.051105 | controller -> localhost | The key fingerprint is:
2026-06-09 07:25:54.051148 | controller -> localhost | SHA256:+b49Ut+8IiMgxDelheYDc+VvS/rUkUxh14UPXP6fwLY zuul-build-sshkey
2026-06-09 07:25:54.051207 | controller -> localhost | The key's randomart image is:
2026-06-09 07:25:54.051276 | controller -> localhost | +---[RSA 3072]----+
2026-06-09 07:25:54.051346 | controller -> localhost | |        o.   + +=|
2026-06-09 07:25:54.051496 | controller -> localhost | |     o +.o  . *..|
2026-06-09 07:25:54.051577 | controller -> localhost | |    . * +.   . o.|
2026-06-09 07:25:54.051622 | controller -> localhost | |     o * .. + . o|
2026-06-09 07:25:54.051663 | controller -> localhost | |    . . S  + B  .|
2026-06-09 07:25:54.051703 | controller -> localhost | |     . . .+ = + o|
2026-06-09 07:25:54.051742 | controller -> localhost | |      . ...+ E +.|
2026-06-09 07:25:54.051787 | controller -> localhost | |         o++... o|
2026-06-09 07:25:54.051825 | controller -> localhost | |          +++....|
2026-06-09 07:25:54.051862 | controller -> localhost | +----[SHA256]-----+
2026-06-09 07:25:54.051956 | controller -> localhost | ok: Runtime: 0:00:00.130584
2026-06-09 07:25:54.065861 | 
2026-06-09 07:25:54.066018 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-06-09 07:25:54.131271 | controller | ok
2026-06-09 07:25:54.145486 | controller | included: /var/lib/zuul/builds/381a13ad12f74c88b7b6a78074697c96/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-06-09 07:25:54.154795 | 
2026-06-09 07:25:54.154882 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-06-09 07:25:54.181308 | controller | skipping: Conditional result was False
2026-06-09 07:25:54.187224 | 
2026-06-09 07:25:54.187319 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-06-09 07:25:54.658858 | controller | changed
2026-06-09 07:25:54.667908 | 
2026-06-09 07:25:54.668178 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-06-09 07:25:54.921948 | controller | ok
2026-06-09 07:25:54.934616 | 
2026-06-09 07:25:54.934769 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-06-09 07:25:55.923667 | controller | changed
2026-06-09 07:25:55.931145 | 
2026-06-09 07:25:55.931275 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-06-09 07:25:56.715169 | controller | changed
2026-06-09 07:25:56.722941 | 
2026-06-09 07:25:56.723069 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-06-09 07:25:56.749287 | controller | skipping: Conditional result was False
2026-06-09 07:25:56.758857 | 
2026-06-09 07:25:56.759086 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-06-09 07:25:57.221849 | controller -> localhost | changed
2026-06-09 07:25:57.238662 | 
2026-06-09 07:25:57.238832 | TASK [add-build-sshkey : Add back temp key]
2026-06-09 07:25:57.618040 | controller -> localhost | Identity added: /var/lib/zuul/builds/381a13ad12f74c88b7b6a78074697c96/work/381a13ad12f74c88b7b6a78074697c96_id_rsa (zuul-build-sshkey)
2026-06-09 07:25:57.618462 | controller -> localhost | ok: Runtime: 0:00:00.020099
2026-06-09 07:25:57.626533 | 
2026-06-09 07:25:57.626668 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-06-09 07:25:58.035555 | controller | ok
2026-06-09 07:25:58.042513 | 
2026-06-09 07:25:58.042647 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-06-09 07:25:58.079555 | controller | skipping: Conditional result was False
2026-06-09 07:25:58.094752 | 
2026-06-09 07:25:58.094935 | TASK [include_role : validate-host]
2026-06-09 07:25:58.132085 | controller | ok
2026-06-09 07:25:58.165095 | 
2026-06-09 07:25:58.165216 | TASK [validate-host : Define zuul_info_dir fact]
2026-06-09 07:25:58.204882 | controller | ok
2026-06-09 07:25:58.208388 | 
2026-06-09 07:25:58.208472 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-06-09 07:25:58.517409 | controller -> localhost | ok
2026-06-09 07:25:58.527348 | 
2026-06-09 07:25:58.527540 | TASK [validate-host : Collect information about the host]
2026-06-09 07:25:59.209243 | controller | ok
2026-06-09 07:25:59.220644 | 
2026-06-09 07:25:59.220725 | TASK [validate-host : Sanitize hostname]
2026-06-09 07:25:59.284728 | controller | ok
2026-06-09 07:25:59.290103 | 
2026-06-09 07:25:59.290196 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-06-09 07:25:59.802696 | controller -> localhost | changed
2026-06-09 07:25:59.815410 | 
2026-06-09 07:25:59.815633 | TASK [validate-host : Collect information about zuul worker]
2026-06-09 07:26:00.294848 | controller | ok
2026-06-09 07:26:00.303129 | 
2026-06-09 07:26:00.303263 | TASK [validate-host : Write out all zuul information for each host]
2026-06-09 07:26:00.857602 | controller -> localhost | changed
2026-06-09 07:26:00.867954 | 
2026-06-09 07:26:00.868070 | TASK [include_role : prepare-workspace-openshift]
2026-06-09 07:26:00.896214 | controller | skipping: Conditional result was False
2026-06-09 07:26:00.905318 | 
2026-06-09 07:26:00.905508 | TASK [include_role : remove-zuul-sshkey]
2026-06-09 07:26:00.932828 | controller | skipping: Conditional result was False
2026-06-09 07:26:00.942125 | 
2026-06-09 07:26:00.942282 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-06-09 07:26:01.185851 | controller | ok: "logs"
2026-06-09 07:26:01.186344 | controller | ok: All items complete
2026-06-09 07:26:01.186399 | 
2026-06-09 07:26:01.470621 | controller | ok: "artifacts"
2026-06-09 07:26:01.721191 | controller | ok: "docs"
2026-06-09 07:26:01.741666 | 
2026-06-09 07:26:01.741891 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-06-09 07:26:01.989666 | controller | changed: "logs"
2026-06-09 07:26:02.419401 | controller | changed: "artifacts"
2026-06-09 07:26:02.632117 | controller | changed: "docs"
2026-06-09 07:26:02.647760 | 
2026-06-09 07:26:02.647926 | PLAY RECAP
2026-06-09 07:26:02.647992 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-06-09 07:26:02.648029 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-09 07:26:02.648053 | 
2026-06-09 07:26:02.829507 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-06-09 07:26:02.831625 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2026-06-09 07:26:03.481370 | 
2026-06-09 07:26:03.481528 | PLAY [localhost]
2026-06-09 07:26:03.490316 | 
2026-06-09 07:26:03.490392 | TASK [configure-ssh-config-fips : Ensure ssh config directory]
2026-06-09 07:26:03.958575 | localhost | changed
2026-06-09 07:26:03.967304 | 
2026-06-09 07:26:03.967505 | TASK [configure-ssh-config-fips : Setup ssh config]
2026-06-09 07:26:04.803301 | localhost | changed
2026-06-09 07:26:04.816219 | 
2026-06-09 07:26:04.816346 | PLAY [all]
2026-06-09 07:26:04.827650 | 
2026-06-09 07:26:04.827784 | TASK [include_role : prepare-workspace]
2026-06-09 07:26:04.860923 | controller | ok
2026-06-09 07:26:04.888296 | 
2026-06-09 07:26:04.888393 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-06-09 07:26:07.602424 | controller | ok
2026-06-09 07:26:07.613594 | 
2026-06-09 07:26:07.613740 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-06-09 07:26:09.368500 | controller | Output suppressed because no_log was given
2026-06-09 07:26:09.377691 | 
2026-06-09 07:26:09.377784 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-06-09 07:26:09.634270 | controller | changed: "logs"
2026-06-09 07:26:09.883592 | controller | changed: "artifacts"
2026-06-09 07:26:10.087723 | controller | changed: "docs"
2026-06-09 07:26:10.120653 | 
2026-06-09 07:26:10.120869 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-06-09 07:26:10.369747 | controller | changed: "logs"
2026-06-09 07:26:10.370306 | controller | changed: All items complete
2026-06-09 07:26:10.370373 | 
2026-06-09 07:26:10.593157 | controller | changed: "artifacts"
2026-06-09 07:26:10.808050 | controller | changed: "docs"
2026-06-09 07:26:10.821297 | 
2026-06-09 07:26:10.821425 | TASK [Check if worker can sudo]
2026-06-09 07:26:11.496079 | controller | ok: Runtime: 0:00:00.046070
2026-06-09 07:26:11.504961 | 
2026-06-09 07:26:11.505121 | TASK [configure-mirrors : Gather needed facts]
2026-06-09 07:26:11.585337 | controller | skipping: Conditional result was False
2026-06-09 07:26:11.596498 | 
2026-06-09 07:26:11.596663 | TASK [configure-mirrors : Set up infrastructure mirrors]
2026-06-09 07:26:11.673941 | controller | ok
2026-06-09 07:26:11.691537 | controller | included: /var/lib/zuul/builds/381a13ad12f74c88b7b6a78074697c96/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml
2026-06-09 07:26:11.700785 | 
2026-06-09 07:26:11.700929 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir]
2026-06-09 07:26:12.030279 | controller | ok
2026-06-09 07:26:12.040610 | 
2026-06-09 07:26:12.040918 | LOOP [configure-mirrors : Include OS-specific variables]
2026-06-09 07:26:12.135046 | controller | ok: "/var/lib/zuul/builds/381a13ad12f74c88b7b6a78074697c96/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml"
2026-06-09 07:26:12.149901 | 
2026-06-09 07:26:12.150092 | TASK [configure-mirrors : Install /etc/pip.conf configuration]
2026-06-09 07:26:13.205565 | controller | changed
2026-06-09 07:26:13.217725 | 
2026-06-09 07:26:13.217866 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors]
2026-06-09 07:26:13.403094 | controller | ok: "/var/lib/zuul/builds/381a13ad12f74c88b7b6a78074697c96/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml"
2026-06-09 07:26:13.403319 | controller | ok: All items complete
2026-06-09 07:26:13.403375 | 
2026-06-09 07:26:13.448278 | controller | included: /var/lib/zuul/builds/381a13ad12f74c88b7b6a78074697c96/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml
2026-06-09 07:26:13.454674 | 
2026-06-09 07:26:13.454741 | LOOP [configure-mirrors : Install CentOS 9 repository files]
2026-06-09 07:26:14.406097 | controller | changed: "etc/yum.repos.d/centos.repo"
2026-06-09 07:26:15.236063 | controller | changed: "etc/yum.repos.d/centos-addons.repo"
2026-06-09 07:26:15.255522 | 
2026-06-09 07:26:15.255795 | TASK [configure-mirrors : Disable deltrarpm]
2026-06-09 07:26:15.997822 | controller | changed: section and option added
2026-06-09 07:26:16.027865 | 
2026-06-09 07:26:16.028079 | LOOP [configure-mirrors : Update yum/dnf cache]
2026-06-09 07:26:16.909501 | controller | 29 files removed
2026-06-09 07:26:16.909809 | controller | ok: Item: dnf clean all Runtime: 0:00:00.529007
2026-06-09 07:26:16.909844 | controller | changed: All items complete
2026-06-09 07:26:16.909864 | 
2026-06-09 07:26:30.453013 | 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-09 07:26:30.453203 | controller | DNF version: 4.14.0
2026-06-09 07:26:30.453258 | controller | cachedir: /var/cache/dnf
2026-06-09 07:26:30.453300 | controller | Making cache files for all metadata files.
2026-06-09 07:26:30.453341 | controller | baseos: has expired and will be refreshed.
2026-06-09 07:26:30.453395 | controller | appstream: has expired and will be refreshed.
2026-06-09 07:26:30.453477 | controller | crb: has expired and will be refreshed.
2026-06-09 07:26:30.453527 | controller | extras-common: has expired and will be refreshed.
2026-06-09 07:26:30.453563 | controller | repo: downloading from remote: baseos
2026-06-09 07:26:30.453599 | controller | CentOS Stream 9 - BaseOS                         45 MB/s | 8.9 MB     00:00
2026-06-09 07:26:30.453634 | controller | baseos: using metadata from Mon 01 Jun 2026 05:58:58 PM EDT.
2026-06-09 07:26:30.453667 | controller | repo: downloading from remote: appstream
2026-06-09 07:26:30.453701 | controller | CentOS Stream 9 - AppStream                      14 MB/s |  28 MB     00:02
2026-06-09 07:26:30.453734 | controller | appstream: using metadata from Mon 01 Jun 2026 06:02:34 PM EDT.
2026-06-09 07:26:30.453767 | controller | repo: downloading from remote: crb
2026-06-09 07:26:30.453800 | controller | CentOS Stream 9 - CRB                            44 MB/s | 8.1 MB     00:00
2026-06-09 07:26:30.453835 | controller | crb: using metadata from Mon 01 Jun 2026 06:07:05 PM EDT.
2026-06-09 07:26:30.453869 | controller | repo: downloading from remote: extras-common
2026-06-09 07:26:30.453902 | controller | CentOS Stream 9 - Extras packages                75 kB/s |  21 kB     00:00
2026-06-09 07:26:30.454019 | controller | extras-common: using metadata from Tue 24 Mar 2026 03:36:22 PM EDT.
2026-06-09 07:26:30.454055 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)'
2026-06-09 07:26:30.454090 | controller | Completion plugin: Generating completion cache...
2026-06-09 07:26:30.454124 | controller | Metadata cache created.
2026-06-09 07:26:30.454178 | controller | ok: Item: dnf makecache -v Runtime: 0:00:13.269476
2026-06-09 07:26:30.458136 | 
2026-06-09 07:26:30.458307 | PLAY RECAP
2026-06-09 07:26:30.458358 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-09 07:26:30.458382 | localhost | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-09 07:26:30.458399 | 
2026-06-09 07:26:30.615594 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2026-06-09 07:26:30.618227 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-06-09 07:26:31.231671 | 
2026-06-09 07:26:31.231790 | PLAY [all]
2026-06-09 07:26:31.242546 | 
2026-06-09 07:26:31.242627 | TASK [Install binary dependencies]
2026-06-09 07:26:31.312612 | controller | ok
2026-06-09 07:26:31.335498 | 
2026-06-09 07:26:31.335641 | TASK [bindep : Include find tasks]
2026-06-09 07:26:31.378515 | controller | ok
2026-06-09 07:26:31.391309 | controller | included: /var/lib/zuul/builds/381a13ad12f74c88b7b6a78074697c96/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-06-09 07:26:31.397605 | 
2026-06-09 07:26:31.397687 | TASK [bindep : Look for bindep.txt]
2026-06-09 07:26:31.837025 | controller | ok
2026-06-09 07:26:31.847081 | 
2026-06-09 07:26:31.847230 | TASK [bindep : Define bindep_file fact]
2026-06-09 07:26:31.892955 | controller | ok
2026-06-09 07:26:31.902575 | 
2026-06-09 07:26:31.902873 | TASK [bindep : Look for other-requirements.txt]
2026-06-09 07:26:31.929991 | controller | skipping: Conditional result was False
2026-06-09 07:26:31.939500 | 
2026-06-09 07:26:31.939666 | TASK [bindep : Define bindep_file fact]
2026-06-09 07:26:31.976725 | controller | skipping: Conditional result was False
2026-06-09 07:26:31.986326 | 
2026-06-09 07:26:31.986512 | TASK [bindep : Look for bindep fallback file]
2026-06-09 07:26:32.034165 | controller | skipping: Conditional result was False
2026-06-09 07:26:32.043524 | 
2026-06-09 07:26:32.043669 | TASK [bindep : Define bindep_file fact]
2026-06-09 07:26:32.081042 | controller | skipping: Conditional result was False
2026-06-09 07:26:32.090743 | 
2026-06-09 07:26:32.090899 | TASK [bindep : Include bindep tasks]
2026-06-09 07:26:32.135138 | controller | ok
2026-06-09 07:26:32.146356 | controller | included: /var/lib/zuul/builds/381a13ad12f74c88b7b6a78074697c96/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml
2026-06-09 07:26:32.162600 | 
2026-06-09 07:26:32.162752 | TASK [bindep : Look for bindep command]
2026-06-09 07:26:32.199047 | controller | skipping: Conditional result was False
2026-06-09 07:26:32.207129 | 
2026-06-09 07:26:32.207229 | TASK [bindep : Check for system bindep]
2026-06-09 07:26:32.767871 | controller | ok: Runtime: 0:00:00.005010
2026-06-09 07:26:32.776770 | 
2026-06-09 07:26:32.776940 | TASK [bindep : Define bindep_command fact]
2026-06-09 07:26:32.813725 | controller | skipping: Conditional result was False
2026-06-09 07:26:32.823601 | 
2026-06-09 07:26:32.823744 | TASK [bindep : Include install tasks]
2026-06-09 07:26:32.879681 | controller | ok
2026-06-09 07:26:32.891593 | controller | included: /var/lib/zuul/builds/381a13ad12f74c88b7b6a78074697c96/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml
2026-06-09 07:26:32.908338 | 
2026-06-09 07:26:32.908497 | TASK [bindep : Create temp dir for bindep]
2026-06-09 07:26:33.341031 | controller | changed
2026-06-09 07:26:33.349170 | 
2026-06-09 07:26:33.349292 | TASK [Ensure we have pip dependencies]
2026-06-09 07:26:33.383749 | controller | ok
2026-06-09 07:26:33.425224 | 
2026-06-09 07:26:33.425322 | TASK [ensure-pip : Check if pip is installed]
2026-06-09 03:26:33.727954 | controller | /usr/bin/pip3
2026-06-09 03:26:33.751040 | controller | /usr/bin/python3: No module named wheel
2026-06-09 07:26:33.966749 | controller | ok: Runtime: 0:00:00.034455
2026-06-09 07:26:33.975725 | 
2026-06-09 07:26:33.975856 | LOOP [ensure-pip : Install pip from packages]
2026-06-09 07:26:34.021790 | controller | ok: "/var/lib/zuul/builds/381a13ad12f74c88b7b6a78074697c96/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml"
2026-06-09 07:26:34.034688 | controller | included: /var/lib/zuul/builds/381a13ad12f74c88b7b6a78074697c96/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml
2026-06-09 07:26:34.091992 | 
2026-06-09 07:26:34.092143 | TASK [ensure-pip : Install Python 3 pip]
2026-06-09 07:26:35.909547 | controller | ok: Nothing to do
2026-06-09 07:26:35.918373 | 
2026-06-09 07:26:35.918567 | TASK [ensure-pip : Install Python3-wheel on older CentOS]
2026-06-09 07:26:38.173175 | controller | changed
2026-06-09 07:26:38.182460 | 
2026-06-09 07:26:38.182594 | TASK [ensure-pip : Check for EPEL repository]
2026-06-09 07:26:38.228352 | controller | skipping: Conditional result was False
2026-06-09 07:26:38.238679 | 
2026-06-09 07:26:38.238823 | TASK [ensure-pip : Fail with instructions when EPEL is not installed]
2026-06-09 07:26:38.285840 | controller | skipping: Conditional result was False
2026-06-09 07:26:38.298155 | 
2026-06-09 07:26:38.298289 | TASK [ensure-pip : Install Python 2 pip]
2026-06-09 07:26:38.365395 | controller | skipping: Conditional result was False
2026-06-09 07:26:38.375847 | 
2026-06-09 07:26:38.375990 | TASK [ensure-pip : Ensure setuptools]
2026-06-09 07:26:38.403847 | controller | skipping: Conditional result was False
2026-06-09 07:26:38.414340 | 
2026-06-09 07:26:38.414504 | TASK [ensure-pip : Check for ensurepip module]
2026-06-09 07:26:38.954187 | controller | skipping: Conditional result was False
2026-06-09 07:26:38.965622 | 
2026-06-09 07:26:38.965761 | TASK [ensure-pip : Ensure python3-venv]
2026-06-09 07:26:38.992357 | controller | skipping: Conditional result was False
2026-06-09 07:26:39.002180 | 
2026-06-09 07:26:39.002314 | TASK [ensure-pip : Install pip from source]
2026-06-09 07:26:39.038733 | controller | skipping: Conditional result was False
2026-06-09 07:26:39.048159 | 
2026-06-09 07:26:39.048298 | TASK [ensure-pip : Probe for venv python full path]
2026-06-09 03:26:39.318841 | controller | /usr/bin/python3
2026-06-09 07:26:39.595006 | controller | ok: Runtime: 0:00:00.005150
2026-06-09 07:26:39.604241 | 
2026-06-09 07:26:39.604386 | TASK [ensure-pip : Set host default]
2026-06-09 07:26:39.689632 | controller | ok
2026-06-09 07:26:39.698063 | 
2026-06-09 07:26:39.698197 | TASK [ensure-pip : Set ensure_pip_virtualenv_command]
2026-06-09 07:26:39.776746 | controller | ok
2026-06-09 07:26:39.793264 | 
2026-06-09 07:26:39.793402 | TASK [bindep : Install bindep into temporary venv]
2026-06-09 07:26:44.860115 | controller | changed
2026-06-09 07:26:44.869251 | 
2026-06-09 07:26:44.869393 | TASK [bindep : Define bindep_command]
2026-06-09 07:26:44.913273 | controller | ok
2026-06-09 07:26:44.920887 | 
2026-06-09 07:26:44.921015 | LOOP [bindep : Include package tasks]
2026-06-09 07:26:45.000554 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt"
2026-06-09 07:26:45.000946 | controller | ok: All items complete
2026-06-09 07:26:45.001018 | 
2026-06-09 07:26:45.019877 | controller | included: /var/lib/zuul/builds/381a13ad12f74c88b7b6a78074697c96/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml
2026-06-09 07:26:45.042688 | 
2026-06-09 07:26:45.042854 | TASK [bindep : Define bindep_run fact]
2026-06-09 07:26:45.085299 | controller | ok
2026-06-09 07:26:45.090625 | 
2026-06-09 07:26:45.090711 | TASK [bindep : Get list of packages to install from bindep]
2026-06-09 03:26:46.364328 | controller | podman
2026-06-09 03:26:46.364441 | controller | python3-jmespath
2026-06-09 03:26:46.364454 | controller | python3-libvirt
2026-06-09 03:26:46.364465 | controller | python3-lxml
2026-06-09 03:26:46.364474 | controller | python3-netaddr
2026-06-09 07:26:46.627334 | controller | ok: Runtime: 0:00:01.065727
2026-06-09 07:26:46.636417 | 
2026-06-09 07:26:46.636587 | TASK [bindep : Install distro packages from bindep]
2026-06-09 07:27:32.709165 | controller | changed
2026-06-09 07:27:32.718888 | 
2026-06-09 07:27:32.719039 | TASK [bindep : Check that packages are installed]
2026-06-09 07:27:34.280801 | controller | ok: Runtime: 0:00:00.974461
2026-06-09 07:27:34.289361 | 
2026-06-09 07:27:34.289530 | TASK [bindep : Fail if we cannot install all packages]
2026-06-09 07:27:34.327051 | controller | skipping: Conditional result was False
2026-06-09 07:27:34.345482 | 
2026-06-09 07:27:34.345706 | TASK [Run test-setup role]
2026-06-09 07:27:34.375290 | controller | ok
2026-06-09 07:27:34.403507 | 
2026-06-09 07:27:34.403665 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-06-09 07:27:34.641719 | controller | ok
2026-06-09 07:27:34.649785 | 
2026-06-09 07:27:34.649924 | TASK [test-setup : Run tools/test-setup.sh]
2026-06-09 07:27:35.530091 | controller | skipping: Conditional result was False
2026-06-09 07:27:35.554397 | 
2026-06-09 07:27:35.554635 | TASK [bindep : Remove bindep temp dir]
2026-06-09 07:27:35.980156 | controller | ok
2026-06-09 07:27:35.983589 | 
2026-06-09 07:27:35.983700 | PLAY RECAP
2026-06-09 07:27:35.983793 | controller | ok: 23 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0
2026-06-09 07:27:35.983841 | 
2026-06-09 07:27:36.149684 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-06-09 07:27:36.151519 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2026-06-09 07:27:36.805491 | 
2026-06-09 07:27:36.805632 | PLAY [all]
2026-06-09 07:27:36.815088 | 
2026-06-09 07:27:36.815168 | TASK [Abort when test_command variable is undefined]
2026-06-09 07:27:36.851200 | controller | skipping: Conditional result was False
2026-06-09 07:27:36.856035 | 
2026-06-09 07:27:36.856113 | TASK [Convert test_command to list]
2026-06-09 07:27:36.891535 | controller | skipping: Conditional result was False
2026-06-09 07:27:36.898027 | 
2026-06-09 07:27:36.898128 | TASK [Use test_command list]
2026-06-09 07:27:36.949821 | controller | ok
2026-06-09 07:27:36.960764 | 
2026-06-09 07:27:36.960900 | LOOP [Run test_command]
2026-06-09 07:27:37.370593 | controller | no check to run
2026-06-09 07:27:37.371028 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.004056
2026-06-09 07:27:37.380837 | 
2026-06-09 07:27:37.381019 | PLAY RECAP
2026-06-09 07:27:37.381115 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-06-09 07:27:37.381159 | 
2026-06-09 07:27:37.530936 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2026-06-09 07:27:37.533524 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-06-09 07:27:38.160810 | 
2026-06-09 07:27:38.160938 | PLAY [all]
2026-06-09 07:27:38.174596 | 
2026-06-09 07:27:38.174686 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-06-09 07:27:38.562097 | controller | changed: non-zero return code
2026-06-09 07:27:38.571471 | 
2026-06-09 07:27:38.571630 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-06-09 07:27:38.589346 | controller | skipping: Conditional result was False
2026-06-09 07:27:38.602380 | 
2026-06-09 07:27:38.602609 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-06-09 07:27:38.632745 | 
2026-06-09 07:27:38.633048 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-06-09 07:27:38.680240 | 
2026-06-09 07:27:38.680583 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-06-09 07:27:38.728474 | controller | skipping: Conditional result was False
2026-06-09 07:27:38.739214 | 
2026-06-09 07:27:38.739369 | LOOP [fetch-subunit-output : Generate subunit file]
2026-06-09 07:27:38.776225 | 
2026-06-09 07:27:38.776650 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-06-09 07:27:38.805657 | controller | skipping: Conditional result was False
2026-06-09 07:27:38.816286 | 
2026-06-09 07:27:38.816579 | TASK [fetch-subunit-output : Remove the temporary file]
2026-06-09 07:27:38.844773 | controller | skipping: Conditional result was False
2026-06-09 07:27:38.854832 | 
2026-06-09 07:27:38.854992 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-06-09 07:27:38.882732 | controller | skipping: Conditional result was False
2026-06-09 07:27:38.897696 | 
2026-06-09 07:27:38.897803 | PLAY RECAP
2026-06-09 07:27:38.897895 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-06-09 07:27:38.897939 | 
2026-06-09 07:27:39.062625 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-06-09 07:27:39.065219 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-06-09 07:27:39.683045 | 
2026-06-09 07:27:39.683211 | PLAY [all]
2026-06-09 07:27:39.693358 | 
2026-06-09 07:27:39.693455 | TASK [include_role : fetch-output]
2026-06-09 07:27:39.741516 | controller | ok
2026-06-09 07:27:39.757067 | 
2026-06-09 07:27:39.757158 | TASK [fetch-output : Set log path for multiple nodes]
2026-06-09 07:27:39.833028 | controller | skipping: Conditional result was False
2026-06-09 07:27:39.839726 | 
2026-06-09 07:27:39.839827 | TASK [fetch-output : Set log path for single node]
2026-06-09 07:27:39.882093 | controller | ok
2026-06-09 07:27:39.887667 | 
2026-06-09 07:27:39.887763 | LOOP [fetch-output : Ensure local output dirs]
2026-06-09 07:27:40.430820 | controller -> localhost | changed: "/var/lib/zuul/builds/381a13ad12f74c88b7b6a78074697c96/work/logs"
2026-06-09 07:27:40.678202 | controller -> localhost | changed: "/var/lib/zuul/builds/381a13ad12f74c88b7b6a78074697c96/work/artifacts"
2026-06-09 07:27:40.928959 | controller -> localhost | changed: "/var/lib/zuul/builds/381a13ad12f74c88b7b6a78074697c96/work/docs"
2026-06-09 07:27:40.954246 | 
2026-06-09 07:27:40.958521 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-06-09 07:27:42.371727 | controller | changed: .d..t...... ./
2026-06-09 07:27:42.372134 | controller | changed: All items complete
2026-06-09 07:27:42.372202 | 
2026-06-09 07:27:42.973475 | controller | changed: .d..t...... ./
2026-06-09 07:27:43.553993 | controller | changed: .d..t...... ./
2026-06-09 07:27:43.578752 | 
2026-06-09 07:27:43.579017 | TASK [include_role : fetch-output-openshift]
2026-06-09 07:27:43.608383 | controller | skipping: Conditional result was False
2026-06-09 07:27:43.618744 | 
2026-06-09 07:27:43.618896 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-06-09 07:27:44.066068 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.013403
2026-06-09 07:27:44.377331 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.013729
2026-06-09 07:27:44.396325 | 
2026-06-09 07:27:44.396575 | PLAY [all]
2026-06-09 07:27:44.407106 | 
2026-06-09 07:27:44.407250 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-06-09 07:27:44.859600 | controller | changed
2026-06-09 07:27:44.870700 | 
2026-06-09 07:27:44.870899 | PLAY RECAP
2026-06-09 07:27:44.870988 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-06-09 07:27:44.871039 | 
2026-06-09 07:27:45.066624 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-06-09 07:27:45.068391 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-06-09 07:27:45.731164 | 
2026-06-09 07:27:45.731305 | PLAY [localhost]
2026-06-09 07:27:45.746783 | 
2026-06-09 07:27:45.746929 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-06-09 07:27:46.185041 | localhost | changed
2026-06-09 07:27:46.207525 | 
2026-06-09 07:27:46.207964 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-06-09 07:27:46.246031 | localhost | ok
2026-06-09 07:27:46.259304 | 
2026-06-09 07:27:46.259511 | TASK [add-fileserver : Create SSH private key tempfile]
2026-06-09 07:27:46.733831 | localhost | changed
2026-06-09 07:27:46.744748 | 
2026-06-09 07:27:46.744977 | TASK [add-fileserver : Create SSH private key from secret]
2026-06-09 07:27:47.613753 | localhost | changed
2026-06-09 07:27:47.619131 | 
2026-06-09 07:27:47.619207 | TASK [add-fileserver : Add fileserver ssh key]
2026-06-09 07:27:48.130102 | localhost | Identity added: /var/lib/zuul/builds/381a13ad12f74c88b7b6a78074697c96/work/tmp/ansible.hzv1_gs7 (/var/lib/zuul/builds/381a13ad12f74c88b7b6a78074697c96/work/tmp/ansible.hzv1_gs7)
2026-06-09 07:27:48.130457 | localhost | ok: Runtime: 0:00:00.020076
2026-06-09 07:27:48.135454 | 
2026-06-09 07:27:48.135551 | TASK [add-fileserver : Remove SSH private key from disk]
2026-06-09 07:27:48.492812 | localhost | ok: Runtime: 0:00:00.008564
2026-06-09 07:27:48.501143 | 
2026-06-09 07:27:48.501282 | TASK [add-fileserver : Add fileserver to inventory]
2026-06-09 07:27:48.558811 | localhost | changed
2026-06-09 07:27:48.569725 | 
2026-06-09 07:27:48.569918 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-06-09 07:27:49.052063 | localhost | changed
2026-06-09 07:27:49.062859 | 
2026-06-09 07:27:49.062966 | PLAY [localhost]
2026-06-09 07:27:49.072296 | 
2026-06-09 07:27:49.072474 | TASK [Generate bulk log download script]
2026-06-09 07:27:49.096498 | localhost | ok
2026-06-09 07:27:49.118278 | 
2026-06-09 07:27:49.118368 | TASK [local-log-download : Check API endpoint is defined]
2026-06-09 07:27:49.160909 | localhost | ok: All assertions passed
2026-06-09 07:27:49.169951 | 
2026-06-09 07:27:49.170110 | TASK [local-log-download : Create download script]
2026-06-09 07:27:49.725605 | localhost -> localhost | changed
2026-06-09 07:27:49.798577 | 
2026-06-09 07:27:49.798737 | TASK [Register quick-download link]
2026-06-09 07:27:49.827203 | localhost | ok
2026-06-09 07:27:49.829773 | 
2026-06-09 07:27:49.829836 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com]
2026-06-09 07:27:49.834689 | 
2026-06-09 07:27:49.834749 | TASK [Set zuul-log-path fact]
2026-06-09 07:27:49.860757 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-09 07:27:49.867681 | 
2026-06-09 07:27:49.867747 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-09 07:27:49.906002 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-09 07:27:49.910933 | 
2026-06-09 07:27:49.911066 | TASK [upload-logs : Create log directories]
2026-06-09 07:27:50.844773 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed
2026-06-09 07:27:50.848785 | 
2026-06-09 07:27:50.848928 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-06-09 07:27:51.204255 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.010888
2026-06-09 07:27:51.211280 | 
2026-06-09 07:27:51.211424 | TASK [upload-logs : Upload logs to log server]
2026-06-09 07:27:52.471042 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | Output suppressed because no_log was given
2026-06-09 07:27:52.473982 | 
2026-06-09 07:27:52.474113 | LOOP [upload-logs : Compress console log and json output]