2026-06-08 11:05:03.723147 | Job console starting
2026-06-08 11:05:03.856401 | Updating git repos
2026-06-08 11:05:03.892393 | Cloning repos into workspace
2026-06-08 11:05:04.528230 | Restoring repo states
2026-06-08 11:05:04.537862 | Merging changes
2026-06-08 11:05:05.619892 | Checking out repos
2026-06-08 11:05:05.725226 | Preparing playbooks
2026-06-08 11:05:09.323339 | Running Ansible setup
2026-06-08 11:05:13.470603 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-06-08 11:05:14.144615 | 
2026-06-08 11:05:14.144745 | PLAY [localhost]
2026-06-08 11:05:14.152524 | 
2026-06-08 11:05:14.152619 | TASK [Gathering Facts]
2026-06-08 11:05:15.205686 | localhost | ok
2026-06-08 11:05:15.224178 | 
2026-06-08 11:05:15.224517 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-06-08 11:05:15.700782 | localhost -> localhost | changed
2026-06-08 11:05:15.707728 | 
2026-06-08 11:05:15.707839 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-06-08 11:05:16.708364 | localhost -> localhost | changed
2026-06-08 11:05:16.733668 | 
2026-06-08 11:05:16.733978 | TASK [Setup log path fact]
2026-06-08 11:05:16.780272 | localhost | ok
2026-06-08 11:05:16.805229 | 
2026-06-08 11:05:16.805503 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-08 11:05:16.855718 | localhost | ok
2026-06-08 11:05:16.861677 | 
2026-06-08 11:05:16.861749 | TASK [emit-job-header : Print job information]
2026-06-08 11:05:16.899734 | # Job Information
2026-06-08 11:05:16.899931 | Ansible Version: 2.16.18
2026-06-08 11:05:16.899957 | Job: cifmw-molecule-cleanup_openstack
2026-06-08 11:05:16.899976 | Pipeline: github-check
2026-06-08 11:05:16.899994 | Executor: 38.102.83.47
2026-06-08 11:05:16.900011 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3866
2026-06-08 11:05:16.900029 | Log URL (when completed): https://gateway-cloud-softwarefactory.apps.ocp.cloud.ci.centos.org/logs/9d6/rdoproject.org/9d646da3a86042b4a95e66598cd37ef0/
2026-06-08 11:05:16.900049 | Event ID: a77779b0-6329-11f1-9f09-b2aa3530de5e
2026-06-08 11:05:16.903121 | 
2026-06-08 11:05:16.903199 | LOOP [emit-job-header : Print node information]
2026-06-08 11:05:16.993490 | localhost | ok:
2026-06-08 11:05:16.993772 | localhost | # Node Information
2026-06-08 11:05:16.993829 | localhost | Inventory Hostname: controller
2026-06-08 11:05:16.993877 | localhost | Hostname: np0000067106
2026-06-08 11:05:16.993919 | localhost | Username: zuul
2026-06-08 11:05:16.993962 | localhost | Distro: CentOS 9
2026-06-08 11:05:16.994001 | localhost | Provider: vexxhost-nodepool-tripleo
2026-06-08 11:05:16.994037 | localhost | Region: RegionOne
2026-06-08 11:05:16.994073 | localhost | Label: cloud-centos-9-stream-tripleo
2026-06-08 11:05:16.994109 | localhost | Product Name: OpenStack Nova
2026-06-08 11:05:16.994144 | localhost | Interface IP: 38.102.83.103
2026-06-08 11:05:17.008296 | 
2026-06-08 11:05:17.008425 | PLAY [all]
2026-06-08 11:05:17.016262 | 
2026-06-08 11:05:17.016374 | TASK [Gather network facts]
2026-06-08 11:05:17.483011 | controller | ok
2026-06-08 11:05:17.489928 | 
2026-06-08 11:05:17.490056 | TASK [include_role : start-zuul-console]
2026-06-08 11:05:17.508970 | controller | ok
2026-06-08 11:05:17.518988 | 
2026-06-08 11:05:17.519062 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-06-08 11:05:17.922262 | controller | ok
2026-06-08 11:05:17.928345 | 
2026-06-08 11:05:17.928408 | TASK [include_role : add-build-sshkey]
2026-06-08 11:05:17.949497 | controller | ok
2026-06-08 11:05:17.960307 | 
2026-06-08 11:05:17.960385 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-06-08 11:05:18.240071 | controller -> localhost | ok
2026-06-08 11:05:18.247863 | 
2026-06-08 11:05:18.248116 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-06-08 11:05:18.279279 | controller | ok
2026-06-08 11:05:18.297150 | controller | included: /var/lib/zuul/builds/9d646da3a86042b4a95e66598cd37ef0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-06-08 11:05:18.303247 | 
2026-06-08 11:05:18.303361 | TASK [add-build-sshkey : Create Temp SSH key]
2026-06-08 11:05:18.886230 | controller -> localhost | Generating public/private rsa key pair.
2026-06-08 11:05:18.886618 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9d646da3a86042b4a95e66598cd37ef0/work/9d646da3a86042b4a95e66598cd37ef0_id_rsa
2026-06-08 11:05:18.886693 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9d646da3a86042b4a95e66598cd37ef0/work/9d646da3a86042b4a95e66598cd37ef0_id_rsa.pub
2026-06-08 11:05:18.886768 | controller -> localhost | The key fingerprint is:
2026-06-08 11:05:18.886818 | controller -> localhost | SHA256:NkfjhYvTMgpnwJUFXbu6b0qydgWSShrooH8Un104mPs zuul-build-sshkey
2026-06-08 11:05:18.886862 | controller -> localhost | The key's randomart image is:
2026-06-08 11:05:18.886906 | controller -> localhost | +---[RSA 3072]----+
2026-06-08 11:05:18.886949 | controller -> localhost | |      o=...      |
2026-06-08 11:05:18.886994 | controller -> localhost | |   . .. .  o     |
2026-06-08 11:05:18.887037 | controller -> localhost | | .  o  + .= .    |
2026-06-08 11:05:18.887080 | controller -> localhost | |o . o.= +=.=     |
2026-06-08 11:05:18.887122 | controller -> localhost | |+  +.+o=S+*      |
2026-06-08 11:05:18.887164 | controller -> localhost | |... o++o.B.      |
2026-06-08 11:05:18.887206 | controller -> localhost | | . .  o.o.       |
2026-06-08 11:05:18.887248 | controller -> localhost | |  . . .+E..      |
2026-06-08 11:05:18.887290 | controller -> localhost | |   . ...o+.      |
2026-06-08 11:05:18.887332 | controller -> localhost | +----[SHA256]-----+
2026-06-08 11:05:18.887425 | controller -> localhost | ok: Runtime: 0:00:00.138644
2026-06-08 11:05:18.896170 | 
2026-06-08 11:05:18.896307 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-06-08 11:05:18.930792 | controller | ok
2026-06-08 11:05:18.948136 | controller | included: /var/lib/zuul/builds/9d646da3a86042b4a95e66598cd37ef0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-06-08 11:05:18.963321 | 
2026-06-08 11:05:18.963468 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-06-08 11:05:18.998607 | controller | skipping: Conditional result was False
2026-06-08 11:05:19.003161 | 
2026-06-08 11:05:19.003226 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-06-08 11:05:19.510197 | controller | changed
2026-06-08 11:05:19.518403 | 
2026-06-08 11:05:19.518645 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-06-08 11:05:19.754903 | controller | ok
2026-06-08 11:05:19.764545 | 
2026-06-08 11:05:19.764723 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-06-08 11:05:20.700784 | controller | changed
2026-06-08 11:05:20.708508 | 
2026-06-08 11:05:20.708655 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-06-08 11:05:21.479732 | controller | changed
2026-06-08 11:05:21.484711 | 
2026-06-08 11:05:21.484811 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-06-08 11:05:21.521567 | controller | skipping: Conditional result was False
2026-06-08 11:05:21.530323 | 
2026-06-08 11:05:21.530538 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-06-08 11:05:21.952976 | controller -> localhost | changed
2026-06-08 11:05:21.960122 | 
2026-06-08 11:05:21.960214 | TASK [add-build-sshkey : Add back temp key]
2026-06-08 11:05:22.261600 | controller -> localhost | Identity added: /var/lib/zuul/builds/9d646da3a86042b4a95e66598cd37ef0/work/9d646da3a86042b4a95e66598cd37ef0_id_rsa (zuul-build-sshkey)
2026-06-08 11:05:22.261787 | controller -> localhost | ok: Runtime: 0:00:00.017547
2026-06-08 11:05:22.265481 | 
2026-06-08 11:05:22.265546 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-06-08 11:05:22.634202 | controller | ok
2026-06-08 11:05:22.642504 | 
2026-06-08 11:05:22.642792 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-06-08 11:05:22.681048 | controller | skipping: Conditional result was False
2026-06-08 11:05:22.695484 | 
2026-06-08 11:05:22.695622 | TASK [include_role : validate-host]
2026-06-08 11:05:22.722527 | controller | ok
2026-06-08 11:05:22.755188 | 
2026-06-08 11:05:22.755334 | TASK [validate-host : Define zuul_info_dir fact]
2026-06-08 11:05:22.795245 | controller | ok
2026-06-08 11:05:22.799068 | 
2026-06-08 11:05:22.799138 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-06-08 11:05:23.078331 | controller -> localhost | ok
2026-06-08 11:05:23.084569 | 
2026-06-08 11:05:23.084661 | TASK [validate-host : Collect information about the host]
2026-06-08 11:05:23.809792 | controller | ok
2026-06-08 11:05:23.819522 | 
2026-06-08 11:05:23.819625 | TASK [validate-host : Sanitize hostname]
2026-06-08 11:05:23.903665 | controller | ok
2026-06-08 11:05:23.911920 | 
2026-06-08 11:05:23.912064 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-06-08 11:05:24.459239 | controller -> localhost | changed
2026-06-08 11:05:24.475165 | 
2026-06-08 11:05:24.475357 | TASK [validate-host : Collect information about zuul worker]
2026-06-08 11:05:24.906017 | controller | ok
2026-06-08 11:05:24.914039 | 
2026-06-08 11:05:24.914193 | TASK [validate-host : Write out all zuul information for each host]
2026-06-08 11:05:25.494218 | controller -> localhost | changed
2026-06-08 11:05:25.509788 | 
2026-06-08 11:05:25.510012 | TASK [include_role : prepare-workspace-openshift]
2026-06-08 11:05:25.537020 | controller | skipping: Conditional result was False
2026-06-08 11:05:25.545658 | 
2026-06-08 11:05:25.545824 | TASK [include_role : remove-zuul-sshkey]
2026-06-08 11:05:25.573034 | controller | skipping: Conditional result was False
2026-06-08 11:05:25.584916 | 
2026-06-08 11:05:25.585134 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-06-08 11:05:25.830004 | controller | ok: "logs"
2026-06-08 11:05:25.830325 | controller | ok: All items complete
2026-06-08 11:05:25.830353 | 
2026-06-08 11:05:26.082613 | controller | ok: "artifacts"
2026-06-08 11:05:26.295171 | controller | ok: "docs"
2026-06-08 11:05:26.310405 | 
2026-06-08 11:05:26.310739 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-06-08 11:05:26.564413 | controller | changed: "logs"
2026-06-08 11:05:26.789542 | controller | changed: "artifacts"
2026-06-08 11:05:27.050149 | controller | changed: "docs"
2026-06-08 11:05:27.072260 | 
2026-06-08 11:05:27.072533 | PLAY RECAP
2026-06-08 11:05:27.072646 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-06-08 11:05:27.072709 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-08 11:05:27.072749 | 
2026-06-08 11:05:27.268761 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-06-08 11:05:27.269993 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2026-06-08 11:05:28.031138 | 
2026-06-08 11:05:28.031456 | PLAY [localhost]
2026-06-08 11:05:28.041214 | 
2026-06-08 11:05:28.041342 | TASK [configure-ssh-config-fips : Ensure ssh config directory]
2026-06-08 11:05:28.558333 | localhost | changed
2026-06-08 11:05:28.564311 | 
2026-06-08 11:05:28.564423 | TASK [configure-ssh-config-fips : Setup ssh config]
2026-06-08 11:05:29.420585 | localhost | changed
2026-06-08 11:05:29.429232 | 
2026-06-08 11:05:29.429496 | PLAY [all]
2026-06-08 11:05:29.442486 | 
2026-06-08 11:05:29.442667 | TASK [include_role : prepare-workspace]
2026-06-08 11:05:29.475892 | controller | ok
2026-06-08 11:05:29.497648 | 
2026-06-08 11:05:29.497842 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-06-08 11:05:29.911006 | controller | ok
2026-06-08 11:05:29.922527 | 
2026-06-08 11:05:29.922726 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-06-08 11:05:31.434204 | controller | Output suppressed because no_log was given
2026-06-08 11:05:31.450796 | 
2026-06-08 11:05:31.450959 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-06-08 11:05:31.711116 | controller | changed: "logs"
2026-06-08 11:05:31.911120 | controller | changed: "artifacts"
2026-06-08 11:05:32.159915 | controller | changed: "docs"
2026-06-08 11:05:32.168175 | 
2026-06-08 11:05:32.168271 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-06-08 11:05:32.485349 | controller | changed: "logs"
2026-06-08 11:05:32.485820 | controller | changed: All items complete
2026-06-08 11:05:32.485893 | 
2026-06-08 11:05:32.677824 | controller | changed: "artifacts"
2026-06-08 11:05:32.912998 | controller | changed: "docs"
2026-06-08 11:05:32.942870 | 
2026-06-08 11:05:32.943122 | TASK [Check if worker can sudo]
2026-06-08 11:05:33.484102 | controller | ok: Runtime: 0:00:00.074364
2026-06-08 11:05:33.489936 | 
2026-06-08 11:05:33.490058 | TASK [configure-mirrors : Gather needed facts]
2026-06-08 11:05:33.546857 | controller | skipping: Conditional result was False
2026-06-08 11:05:33.551991 | 
2026-06-08 11:05:33.552073 | TASK [configure-mirrors : Set up infrastructure mirrors]
2026-06-08 11:05:33.635520 | controller | ok
2026-06-08 11:05:33.647576 | controller | included: /var/lib/zuul/builds/9d646da3a86042b4a95e66598cd37ef0/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml
2026-06-08 11:05:33.656744 | 
2026-06-08 11:05:33.656852 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir]
2026-06-08 11:05:33.914574 | controller | ok
2026-06-08 11:05:33.924323 | 
2026-06-08 11:05:33.924537 | LOOP [configure-mirrors : Include OS-specific variables]
2026-06-08 11:05:33.999254 | controller | ok: "/var/lib/zuul/builds/9d646da3a86042b4a95e66598cd37ef0/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml"
2026-06-08 11:05:34.011037 | 
2026-06-08 11:05:34.011187 | TASK [configure-mirrors : Install /etc/pip.conf configuration]
2026-06-08 11:05:34.846463 | controller | changed
2026-06-08 11:05:34.856653 | 
2026-06-08 11:05:34.856812 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors]
2026-06-08 11:05:35.027241 | controller | ok: "/var/lib/zuul/builds/9d646da3a86042b4a95e66598cd37ef0/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml"
2026-06-08 11:05:35.027506 | controller | ok: All items complete
2026-06-08 11:05:35.027548 | 
2026-06-08 11:05:35.096767 | controller | included: /var/lib/zuul/builds/9d646da3a86042b4a95e66598cd37ef0/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml
2026-06-08 11:05:35.103641 | 
2026-06-08 11:05:35.103729 | LOOP [configure-mirrors : Install CentOS 9 repository files]
2026-06-08 11:05:35.992853 | controller | changed: "etc/yum.repos.d/centos.repo"
2026-06-08 11:05:36.797584 | controller | changed: "etc/yum.repos.d/centos-addons.repo"
2026-06-08 11:05:36.818076 | 
2026-06-08 11:05:36.818236 | TASK [configure-mirrors : Disable deltrarpm]
2026-06-08 11:05:37.312311 | controller | changed: section and option added
2026-06-08 11:05:37.334664 | 
2026-06-08 11:05:37.334862 | LOOP [configure-mirrors : Update yum/dnf cache]
2026-06-08 11:05:38.058000 | controller | 29 files removed
2026-06-08 11:05:38.058646 | controller | ok: Item: dnf clean all Runtime: 0:00:00.388273
2026-06-08 11:05:38.058733 | controller | changed: All items complete
2026-06-08 11:05:38.058780 | 
2026-06-08 11:05:50.008977 | 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 11:05:50.009147 | controller | DNF version: 4.14.0
2026-06-08 11:05:50.009200 | controller | cachedir: /var/cache/dnf
2026-06-08 11:05:50.009243 | controller | Making cache files for all metadata files.
2026-06-08 11:05:50.009282 | controller | baseos: has expired and will be refreshed.
2026-06-08 11:05:50.009337 | controller | appstream: has expired and will be refreshed.
2026-06-08 11:05:50.009375 | controller | crb: has expired and will be refreshed.
2026-06-08 11:05:50.009412 | controller | extras-common: has expired and will be refreshed.
2026-06-08 11:05:50.009492 | controller | repo: downloading from remote: baseos
2026-06-08 11:05:50.009532 | controller | CentOS Stream 9 - BaseOS                         68 MB/s | 8.9 MB     00:00
2026-06-08 11:05:50.009568 | controller | baseos: using metadata from Mon 01 Jun 2026 05:58:58 PM EDT.
2026-06-08 11:05:50.009603 | controller | repo: downloading from remote: appstream
2026-06-08 11:05:50.009638 | controller | CentOS Stream 9 - AppStream                      84 MB/s |  28 MB     00:00
2026-06-08 11:05:50.009696 | controller | appstream: using metadata from Mon 01 Jun 2026 06:02:34 PM EDT.
2026-06-08 11:05:50.009734 | controller | repo: downloading from remote: crb
2026-06-08 11:05:50.009769 | controller | CentOS Stream 9 - CRB                            42 MB/s | 8.1 MB     00:00
2026-06-08 11:05:50.009805 | controller | crb: using metadata from Mon 01 Jun 2026 06:07:05 PM EDT.
2026-06-08 11:05:50.009841 | controller | repo: downloading from remote: extras-common
2026-06-08 11:05:50.009876 | controller | CentOS Stream 9 - Extras packages               1.8 MB/s |  21 kB     00:00
2026-06-08 11:05:50.009912 | controller | extras-common: using metadata from Tue 24 Mar 2026 03:36:22 PM EDT.
2026-06-08 11:05:50.009946 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)'
2026-06-08 11:05:50.009981 | controller | Completion plugin: Generating completion cache...
2026-06-08 11:05:50.010015 | controller | Metadata cache created.
2026-06-08 11:05:50.010068 | controller | ok: Item: dnf makecache -v Runtime: 0:00:11.707777
2026-06-08 11:05:50.014724 | 
2026-06-08 11:05:50.014897 | PLAY RECAP
2026-06-08 11:05:50.014986 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-08 11:05:50.015040 | localhost | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-08 11:05:50.015078 | 
2026-06-08 11:05:50.159264 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2026-06-08 11:05:50.161751 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-06-08 11:05:50.778952 | 
2026-06-08 11:05:50.779148 | PLAY [all]
2026-06-08 11:05:50.791201 | 
2026-06-08 11:05:50.791276 | TASK [Install binary dependencies]
2026-06-08 11:05:50.861315 | controller | ok
2026-06-08 11:05:50.879465 | 
2026-06-08 11:05:50.879574 | TASK [bindep : Include find tasks]
2026-06-08 11:05:50.921767 | controller | ok
2026-06-08 11:05:50.930804 | controller | included: /var/lib/zuul/builds/9d646da3a86042b4a95e66598cd37ef0/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-06-08 11:05:50.937585 | 
2026-06-08 11:05:50.937704 | TASK [bindep : Look for bindep.txt]
2026-06-08 11:05:51.357306 | controller | ok
2026-06-08 11:05:51.367548 | 
2026-06-08 11:05:51.367851 | TASK [bindep : Define bindep_file fact]
2026-06-08 11:05:51.415060 | controller | ok
2026-06-08 11:05:51.424741 | 
2026-06-08 11:05:51.424880 | TASK [bindep : Look for other-requirements.txt]
2026-06-08 11:05:51.452250 | controller | skipping: Conditional result was False
2026-06-08 11:05:51.461990 | 
2026-06-08 11:05:51.462131 | TASK [bindep : Define bindep_file fact]
2026-06-08 11:05:51.499650 | controller | skipping: Conditional result was False
2026-06-08 11:05:51.508956 | 
2026-06-08 11:05:51.509089 | TASK [bindep : Look for bindep fallback file]
2026-06-08 11:05:51.547851 | controller | skipping: Conditional result was False
2026-06-08 11:05:51.558299 | 
2026-06-08 11:05:51.558612 | TASK [bindep : Define bindep_file fact]
2026-06-08 11:05:51.595944 | controller | skipping: Conditional result was False
2026-06-08 11:05:51.608525 | 
2026-06-08 11:05:51.608800 | TASK [bindep : Include bindep tasks]
2026-06-08 11:05:51.654545 | controller | ok
2026-06-08 11:05:51.665824 | controller | included: /var/lib/zuul/builds/9d646da3a86042b4a95e66598cd37ef0/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml
2026-06-08 11:05:51.682813 | 
2026-06-08 11:05:51.682972 | TASK [bindep : Look for bindep command]
2026-06-08 11:05:51.719328 | controller | skipping: Conditional result was False
2026-06-08 11:05:51.730950 | 
2026-06-08 11:05:51.731123 | TASK [bindep : Check for system bindep]
2026-06-08 11:05:52.299469 | controller | ok: Runtime: 0:00:00.006177
2026-06-08 11:05:52.308769 | 
2026-06-08 11:05:52.308900 | TASK [bindep : Define bindep_command fact]
2026-06-08 11:05:52.345852 | controller | skipping: Conditional result was False
2026-06-08 11:05:52.355382 | 
2026-06-08 11:05:52.355554 | TASK [bindep : Include install tasks]
2026-06-08 11:05:52.402469 | controller | ok
2026-06-08 11:05:52.422928 | controller | included: /var/lib/zuul/builds/9d646da3a86042b4a95e66598cd37ef0/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml
2026-06-08 11:05:52.437075 | 
2026-06-08 11:05:52.437164 | TASK [bindep : Create temp dir for bindep]
2026-06-08 11:05:52.818625 | controller | changed
2026-06-08 11:05:52.828835 | 
2026-06-08 11:05:52.828987 | TASK [Ensure we have pip dependencies]
2026-06-08 11:05:52.860766 | controller | ok
2026-06-08 11:05:52.898297 | 
2026-06-08 11:05:52.898460 | TASK [ensure-pip : Check if pip is installed]
2026-06-08 07:05:53.177095 | controller | /usr/bin/pip3
2026-06-08 07:05:53.210694 | controller | /usr/bin/python3: No module named wheel
2026-06-08 11:05:53.430505 | controller | ok: Runtime: 0:00:00.043017
2026-06-08 11:05:53.436049 | 
2026-06-08 11:05:53.436136 | LOOP [ensure-pip : Install pip from packages]
2026-06-08 11:05:53.483527 | controller | ok: "/var/lib/zuul/builds/9d646da3a86042b4a95e66598cd37ef0/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml"
2026-06-08 11:05:53.501941 | controller | included: /var/lib/zuul/builds/9d646da3a86042b4a95e66598cd37ef0/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml
2026-06-08 11:05:53.562887 | 
2026-06-08 11:05:53.563076 | TASK [ensure-pip : Install Python 3 pip]
2026-06-08 11:05:55.075779 | controller | ok: Nothing to do
2026-06-08 11:05:55.081568 | 
2026-06-08 11:05:55.081657 | TASK [ensure-pip : Install Python3-wheel on older CentOS]
2026-06-08 11:05:59.683279 | controller | changed
2026-06-08 11:05:59.692247 | 
2026-06-08 11:05:59.692384 | TASK [ensure-pip : Check for EPEL repository]
2026-06-08 11:05:59.752461 | controller | skipping: Conditional result was False
2026-06-08 11:05:59.764541 | 
2026-06-08 11:05:59.764801 | TASK [ensure-pip : Fail with instructions when EPEL is not installed]
2026-06-08 11:05:59.834264 | controller | skipping: Conditional result was False
2026-06-08 11:05:59.856609 | 
2026-06-08 11:05:59.856765 | TASK [ensure-pip : Install Python 2 pip]
2026-06-08 11:05:59.927977 | controller | skipping: Conditional result was False
2026-06-08 11:05:59.940365 | 
2026-06-08 11:05:59.940620 | TASK [ensure-pip : Ensure setuptools]
2026-06-08 11:05:59.967615 | controller | skipping: Conditional result was False
2026-06-08 11:05:59.979062 | 
2026-06-08 11:05:59.979219 | TASK [ensure-pip : Check for ensurepip module]
2026-06-08 11:06:00.521772 | controller | skipping: Conditional result was False
2026-06-08 11:06:00.527844 | 
2026-06-08 11:06:00.527917 | TASK [ensure-pip : Ensure python3-venv]
2026-06-08 11:06:00.552314 | controller | skipping: Conditional result was False
2026-06-08 11:06:00.557354 | 
2026-06-08 11:06:00.557464 | TASK [ensure-pip : Install pip from source]
2026-06-08 11:06:00.591978 | controller | skipping: Conditional result was False
2026-06-08 11:06:00.596387 | 
2026-06-08 11:06:00.596474 | TASK [ensure-pip : Probe for venv python full path]
2026-06-08 07:06:00.920444 | controller | /usr/bin/python3
2026-06-08 11:06:01.124467 | controller | ok: Runtime: 0:00:00.004798
2026-06-08 11:06:01.133124 | 
2026-06-08 11:06:01.133258 | TASK [ensure-pip : Set host default]
2026-06-08 11:06:01.209888 | controller | ok
2026-06-08 11:06:01.219466 | 
2026-06-08 11:06:01.219608 | TASK [ensure-pip : Set ensure_pip_virtualenv_command]
2026-06-08 11:06:01.307083 | controller | ok
2026-06-08 11:06:01.324816 | 
2026-06-08 11:06:01.324950 | TASK [bindep : Install bindep into temporary venv]
2026-06-08 11:06:08.123557 | controller | changed
2026-06-08 11:06:08.133006 | 
2026-06-08 11:06:08.133109 | TASK [bindep : Define bindep_command]
2026-06-08 11:06:08.179497 | controller | ok
2026-06-08 11:06:08.187834 | 
2026-06-08 11:06:08.187971 | LOOP [bindep : Include package tasks]
2026-06-08 11:06:08.264150 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt"
2026-06-08 11:06:08.264503 | controller | ok: All items complete
2026-06-08 11:06:08.264569 | 
2026-06-08 11:06:08.285611 | controller | included: /var/lib/zuul/builds/9d646da3a86042b4a95e66598cd37ef0/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml
2026-06-08 11:06:08.308472 | 
2026-06-08 11:06:08.308619 | TASK [bindep : Define bindep_run fact]
2026-06-08 11:06:08.355000 | controller | ok
2026-06-08 11:06:08.363588 | 
2026-06-08 11:06:08.363751 | TASK [bindep : Get list of packages to install from bindep]
2026-06-08 07:06:09.764031 | controller | podman
2026-06-08 07:06:09.764172 | controller | python3-jmespath
2026-06-08 07:06:09.764181 | controller | python3-libvirt
2026-06-08 07:06:09.764188 | controller | python3-lxml
2026-06-08 07:06:09.764193 | controller | python3-netaddr
2026-06-08 11:06:09.907316 | controller | ok: Runtime: 0:00:01.060171
2026-06-08 11:06:09.916517 | 
2026-06-08 11:06:09.916652 | TASK [bindep : Install distro packages from bindep]
2026-06-08 11:06:53.892667 | controller | changed
2026-06-08 11:06:53.902189 | 
2026-06-08 11:06:53.902352 | TASK [bindep : Check that packages are installed]
2026-06-08 11:06:55.451832 | controller | ok: Runtime: 0:00:01.230067
2026-06-08 11:06:55.461057 | 
2026-06-08 11:06:55.461216 | TASK [bindep : Fail if we cannot install all packages]
2026-06-08 11:06:55.497781 | controller | skipping: Conditional result was False
2026-06-08 11:06:55.518597 | 
2026-06-08 11:06:55.518762 | TASK [Run test-setup role]
2026-06-08 11:06:55.553936 | controller | ok
2026-06-08 11:06:55.581139 | 
2026-06-08 11:06:55.581248 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-06-08 11:06:55.807890 | controller | ok
2026-06-08 11:06:55.817407 | 
2026-06-08 11:06:55.817636 | TASK [test-setup : Run tools/test-setup.sh]
2026-06-08 11:06:56.358039 | controller | skipping: Conditional result was False
2026-06-08 11:06:56.373003 | 
2026-06-08 11:06:56.373138 | TASK [bindep : Remove bindep temp dir]
2026-06-08 11:06:56.748656 | controller | ok
2026-06-08 11:06:56.751745 | 
2026-06-08 11:06:56.751841 | PLAY RECAP
2026-06-08 11:06:56.751952 | controller | ok: 23 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0
2026-06-08 11:06:56.752011 | 
2026-06-08 11:06:56.937484 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-06-08 11:06:56.939782 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2026-06-08 11:06:57.561931 | 
2026-06-08 11:06:57.562081 | PLAY [all]
2026-06-08 11:06:57.571516 | 
2026-06-08 11:06:57.571604 | TASK [Abort when test_command variable is undefined]
2026-06-08 11:06:57.606094 | controller | skipping: Conditional result was False
2026-06-08 11:06:57.610650 | 
2026-06-08 11:06:57.610732 | TASK [Convert test_command to list]
2026-06-08 11:06:57.665717 | controller | skipping: Conditional result was False
2026-06-08 11:06:57.671410 | 
2026-06-08 11:06:57.671525 | TASK [Use test_command list]
2026-06-08 11:06:57.725058 | controller | ok
2026-06-08 11:06:57.736670 | 
2026-06-08 11:06:57.736862 | LOOP [Run test_command]
2026-06-08 11:06:58.179587 | controller | no check to run
2026-06-08 11:06:58.179951 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.003754
2026-06-08 11:06:58.186581 | 
2026-06-08 11:06:58.186804 | PLAY RECAP
2026-06-08 11:06:58.186893 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-06-08 11:06:58.186936 | 
2026-06-08 11:06:58.344244 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2026-06-08 11:06:58.345942 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-06-08 11:06:58.965187 | 
2026-06-08 11:06:58.965303 | PLAY [all]
2026-06-08 11:06:58.975779 | 
2026-06-08 11:06:58.975856 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-06-08 11:06:59.312049 | controller | changed: non-zero return code
2026-06-08 11:06:59.320796 | 
2026-06-08 11:06:59.320957 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-06-08 11:06:59.348768 | controller | skipping: Conditional result was False
2026-06-08 11:06:59.360563 | 
2026-06-08 11:06:59.360711 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-06-08 11:06:59.399104 | 
2026-06-08 11:06:59.399361 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-06-08 11:06:59.431937 | 
2026-06-08 11:06:59.432109 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-06-08 11:06:59.457203 | controller | skipping: Conditional result was False
2026-06-08 11:06:59.463500 | 
2026-06-08 11:06:59.463595 | LOOP [fetch-subunit-output : Generate subunit file]
2026-06-08 11:06:59.495121 | 
2026-06-08 11:06:59.495295 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-06-08 11:06:59.521350 | controller | skipping: Conditional result was False
2026-06-08 11:06:59.528273 | 
2026-06-08 11:06:59.528365 | TASK [fetch-subunit-output : Remove the temporary file]
2026-06-08 11:06:59.553860 | controller | skipping: Conditional result was False
2026-06-08 11:06:59.564247 | 
2026-06-08 11:06:59.564397 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-06-08 11:06:59.591237 | controller | skipping: Conditional result was False
2026-06-08 11:06:59.605724 | 
2026-06-08 11:06:59.605830 | PLAY RECAP
2026-06-08 11:06:59.605931 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-06-08 11:06:59.605986 | 
2026-06-08 11:06:59.754362 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-06-08 11:06:59.757578 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-06-08 11:07:00.435287 | 
2026-06-08 11:07:00.435415 | PLAY [all]
2026-06-08 11:07:00.445806 | 
2026-06-08 11:07:00.445909 | TASK [include_role : fetch-output]
2026-06-08 11:07:00.494878 | controller | ok
2026-06-08 11:07:00.511720 | 
2026-06-08 11:07:00.511841 | TASK [fetch-output : Set log path for multiple nodes]
2026-06-08 11:07:00.588570 | controller | skipping: Conditional result was False
2026-06-08 11:07:00.598188 | 
2026-06-08 11:07:00.598342 | TASK [fetch-output : Set log path for single node]
2026-06-08 11:07:00.654491 | controller | ok
2026-06-08 11:07:00.662675 | 
2026-06-08 11:07:00.662826 | LOOP [fetch-output : Ensure local output dirs]
2026-06-08 11:07:01.179864 | controller -> localhost | changed: "/var/lib/zuul/builds/9d646da3a86042b4a95e66598cd37ef0/work/logs"
2026-06-08 11:07:01.469423 | controller -> localhost | changed: "/var/lib/zuul/builds/9d646da3a86042b4a95e66598cd37ef0/work/artifacts"
2026-06-08 11:07:01.744936 | controller -> localhost | changed: "/var/lib/zuul/builds/9d646da3a86042b4a95e66598cd37ef0/work/docs"
2026-06-08 11:07:01.763326 | 
2026-06-08 11:07:01.763518 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-06-08 11:07:02.511802 | controller | changed: .d..t...... ./
2026-06-08 11:07:02.512311 | controller | changed: All items complete
2026-06-08 11:07:02.512382 | 
2026-06-08 11:07:03.053938 | controller | changed: .d..t...... ./
2026-06-08 11:07:03.573236 | controller | changed: .d..t...... ./
2026-06-08 11:07:03.589325 | 
2026-06-08 11:07:03.589472 | TASK [include_role : fetch-output-openshift]
2026-06-08 11:07:03.605120 | controller | skipping: Conditional result was False
2026-06-08 11:07:03.610392 | 
2026-06-08 11:07:03.610470 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-06-08 11:07:04.152998 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.014135
2026-06-08 11:07:04.443859 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009693
2026-06-08 11:07:04.468457 | 
2026-06-08 11:07:04.468659 | PLAY [all]
2026-06-08 11:07:04.480552 | 
2026-06-08 11:07:04.480712 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-06-08 11:07:04.981535 | controller | changed
2026-06-08 11:07:04.991894 | 
2026-06-08 11:07:04.992009 | PLAY RECAP
2026-06-08 11:07:04.992121 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-06-08 11:07:04.992192 | 
2026-06-08 11:07:05.193642 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-06-08 11:07:05.197049 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-06-08 11:07:05.895043 | 
2026-06-08 11:07:05.895241 | PLAY [localhost]
2026-06-08 11:07:05.907819 | 
2026-06-08 11:07:05.907929 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-06-08 11:07:06.372845 | localhost | changed
2026-06-08 11:07:06.383124 | 
2026-06-08 11:07:06.383306 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-06-08 11:07:06.425340 | localhost | ok
2026-06-08 11:07:06.436518 | 
2026-06-08 11:07:06.436684 | TASK [add-fileserver : Create SSH private key tempfile]
2026-06-08 11:07:06.885891 | localhost | changed
2026-06-08 11:07:06.897937 | 
2026-06-08 11:07:06.898150 | TASK [add-fileserver : Create SSH private key from secret]
2026-06-08 11:07:07.753375 | localhost | changed
2026-06-08 11:07:07.764699 | 
2026-06-08 11:07:07.764870 | TASK [add-fileserver : Add fileserver ssh key]
2026-06-08 11:07:08.290258 | localhost | Identity added: /var/lib/zuul/builds/9d646da3a86042b4a95e66598cd37ef0/work/tmp/ansible.4p4dh8dg (/var/lib/zuul/builds/9d646da3a86042b4a95e66598cd37ef0/work/tmp/ansible.4p4dh8dg)
2026-06-08 11:07:08.290573 | localhost | ok: Runtime: 0:00:00.022618
2026-06-08 11:07:08.295741 | 
2026-06-08 11:07:08.295842 | TASK [add-fileserver : Remove SSH private key from disk]
2026-06-08 11:07:08.679079 | localhost | ok: Runtime: 0:00:00.012998
2026-06-08 11:07:08.687646 | 
2026-06-08 11:07:08.687789 | TASK [add-fileserver : Add fileserver to inventory]
2026-06-08 11:07:08.783185 | localhost | changed
2026-06-08 11:07:08.793967 | 
2026-06-08 11:07:08.794115 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-06-08 11:07:09.251101 | localhost | changed
2026-06-08 11:07:09.258365 | 
2026-06-08 11:07:09.258551 | PLAY [localhost]
2026-06-08 11:07:09.263121 | 
2026-06-08 11:07:09.263223 | TASK [Generate bulk log download script]
2026-06-08 11:07:09.282488 | localhost | ok
2026-06-08 11:07:09.293415 | 
2026-06-08 11:07:09.293533 | TASK [local-log-download : Check API endpoint is defined]
2026-06-08 11:07:09.343104 | localhost | ok: All assertions passed
2026-06-08 11:07:09.348220 | 
2026-06-08 11:07:09.348308 | TASK [local-log-download : Create download script]
2026-06-08 11:07:09.941742 | localhost -> localhost | changed
2026-06-08 11:07:10.005934 | 
2026-06-08 11:07:10.006128 | TASK [Register quick-download link]
2026-06-08 11:07:10.039625 | localhost | ok
2026-06-08 11:07:10.044506 | 
2026-06-08 11:07:10.044638 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com]
2026-06-08 11:07:10.054314 | 
2026-06-08 11:07:10.054475 | TASK [Set zuul-log-path fact]
2026-06-08 11:07:10.075667 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-08 11:07:10.088974 | 
2026-06-08 11:07:10.089126 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-08 11:07:10.127795 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-08 11:07:10.130958 | 
2026-06-08 11:07:10.131064 | TASK [upload-logs : Create log directories]
2026-06-08 11:07:11.058313 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed
2026-06-08 11:07:11.062004 | 
2026-06-08 11:07:11.062140 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-06-08 11:07:11.438188 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.010646
2026-06-08 11:07:11.445716 | 
2026-06-08 11:07:11.445852 | TASK [upload-logs : Upload logs to log server]
2026-06-08 11:07:12.805088 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | Output suppressed because no_log was given
2026-06-08 11:07:12.806855 | 
2026-06-08 11:07:12.806942 | LOOP [upload-logs : Compress console log and json output]