2026-02-17 12:47:38.409833 | Job console starting...
2026-02-17 12:47:38.422220 | Updating repositories
2026-02-17 12:47:39.323868 | Preparing job workspace
2026-02-17 12:47:43.698834 | Running Ansible setup...
2026-02-17 12:47:47.384605 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-02-17 12:47:48.042412 | 
2026-02-17 12:47:48.042547 | PLAY [localhost]
2026-02-17 12:47:48.071760 | 
2026-02-17 12:47:48.071859 | TASK [Gathering Facts]
2026-02-17 12:47:49.141586 | localhost | ok
2026-02-17 12:47:49.172999 | 
2026-02-17 12:47:49.173205 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-17 12:47:49.665631 | localhost -> localhost | changed
2026-02-17 12:47:49.678560 | 
2026-02-17 12:47:49.678730 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-17 12:47:50.663975 | localhost -> localhost | changed
2026-02-17 12:47:50.677817 | 
2026-02-17 12:47:50.677969 | TASK [Setup log path fact]
2026-02-17 12:47:50.697161 | localhost | ok
2026-02-17 12:47:50.710333 | 
2026-02-17 12:47:50.710500 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-17 12:47:50.739783 | localhost | ok
2026-02-17 12:47:50.748231 | 
2026-02-17 12:47:50.748359 | TASK [emit-job-header : Print job information]
2026-02-17 12:47:50.789180 | # Job Information
2026-02-17 12:47:50.789401 | Ansible Version: 2.15.12
2026-02-17 12:47:50.789435 | Job: cifmw-molecule-cleanup_openstack
2026-02-17 12:47:50.789456 | Pipeline: github-check
2026-02-17 12:47:50.789474 | Executor: ze01.softwarefactory-project.io
2026-02-17 12:47:50.789491 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3636
2026-02-17 12:47:50.789509 | Log URL (when completed): https://logserver.rdoproject.org/0fe/rdoproject.org/0fe8e3defa8248b9b7f038280ef99463/
2026-02-17 12:47:50.789527 | Event ID: a2e34c80-0bfe-11f1-9c59-89edddebf72d
2026-02-17 12:47:50.795469 | 
2026-02-17 12:47:50.795577 | LOOP [emit-job-header : Print node information]
2026-02-17 12:47:50.910688 | localhost | ok:
2026-02-17 12:47:50.910979 | localhost | # Node Information
2026-02-17 12:47:50.911075 | localhost | Inventory Hostname: controller
2026-02-17 12:47:50.911120 | localhost | Hostname: np0005621840
2026-02-17 12:47:50.911150 | localhost | Username: zuul
2026-02-17 12:47:50.911179 | localhost | Distro: CentOS 9
2026-02-17 12:47:50.911206 | localhost | Provider: vexxhost-nodepool-tripleo
2026-02-17 12:47:50.911231 | localhost | Region: RegionOne
2026-02-17 12:47:50.911256 | localhost | Label: cloud-centos-9-stream-tripleo
2026-02-17 12:47:50.911281 | localhost | Product Name: OpenStack Nova
2026-02-17 12:47:50.911305 | localhost | Interface IP: 38.102.83.39
2026-02-17 12:47:50.956893 | 
2026-02-17 12:47:50.957125 | PLAY [all]
2026-02-17 12:47:50.974086 | 
2026-02-17 12:47:50.974233 | TASK [Gather network facts]
2026-02-17 12:47:51.402278 | controller | ok
2026-02-17 12:47:51.428709 | 
2026-02-17 12:47:51.428823 | TASK [include_role : start-zuul-console]
2026-02-17 12:47:51.452937 | controller | ok
2026-02-17 12:47:51.469665 | 
2026-02-17 12:47:51.469762 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-02-17 12:47:51.940628 | controller | ok
2026-02-17 12:47:51.961471 | 
2026-02-17 12:47:51.961606 | TASK [include_role : add-build-sshkey]
2026-02-17 12:47:52.001753 | controller | ok
2026-02-17 12:47:52.027625 | 
2026-02-17 12:47:52.027720 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-17 12:47:52.326423 | controller -> localhost | ok
2026-02-17 12:47:52.339861 | 
2026-02-17 12:47:52.340075 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-17 12:47:52.391408 | controller | ok
2026-02-17 12:47:52.422183 | controller | included: /var/lib/zuul/builds/0fe8e3defa8248b9b7f038280ef99463/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-17 12:47:52.432458 | 
2026-02-17 12:47:52.432636 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-17 12:47:53.263406 | controller -> localhost | Generating public/private rsa key pair.
2026-02-17 12:47:53.263649 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/0fe8e3defa8248b9b7f038280ef99463/work/0fe8e3defa8248b9b7f038280ef99463_id_rsa.
2026-02-17 12:47:53.263681 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/0fe8e3defa8248b9b7f038280ef99463/work/0fe8e3defa8248b9b7f038280ef99463_id_rsa.pub.
2026-02-17 12:47:53.263704 | controller -> localhost | The key fingerprint is:
2026-02-17 12:47:53.263725 | controller -> localhost | SHA256:UhegtS+8SWWOzMCcVes6/p3i4uXoQyoHM4V/vg0Uxt0 zuul-build-sshkey
2026-02-17 12:47:53.263744 | controller -> localhost | The key's randomart image is:
2026-02-17 12:47:53.263762 | controller -> localhost | +---[RSA 3072]----+
2026-02-17 12:47:53.263780 | controller -> localhost | |        +oo      |
2026-02-17 12:47:53.263798 | controller -> localhost | |     o * o +     |
2026-02-17 12:47:53.263816 | controller -> localhost | |     .* * * E    |
2026-02-17 12:47:53.263839 | controller -> localhost | |    . .B X       |
2026-02-17 12:47:53.263876 | controller -> localhost | |     o. S +      |
2026-02-17 12:47:53.263895 | controller -> localhost | |    + .+o=       |
2026-02-17 12:47:53.263913 | controller -> localhost | |     + =* .      |
2026-02-17 12:47:53.263933 | controller -> localhost | |    . o.+B.. .   |
2026-02-17 12:47:53.263952 | controller -> localhost | |     o o**=.o    |
2026-02-17 12:47:53.263970 | controller -> localhost | +----[SHA256]-----+
2026-02-17 12:47:53.264023 | controller -> localhost | ok: Runtime: 0:00:00.400274
2026-02-17 12:47:53.270305 | 
2026-02-17 12:47:53.270369 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-17 12:47:53.301956 | controller | ok
2026-02-17 12:47:53.313199 | controller | included: /var/lib/zuul/builds/0fe8e3defa8248b9b7f038280ef99463/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-17 12:47:53.322621 | 
2026-02-17 12:47:53.322921 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-17 12:47:53.348197 | controller | skipping: Conditional result was False
2026-02-17 12:47:53.355064 | 
2026-02-17 12:47:53.355161 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-17 12:47:53.849094 | controller | changed
2026-02-17 12:47:53.860405 | 
2026-02-17 12:47:53.860571 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-17 12:47:54.143495 | controller | ok
2026-02-17 12:47:54.155283 | 
2026-02-17 12:47:54.155438 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-17 12:47:54.954424 | controller | changed
2026-02-17 12:47:54.962284 | 
2026-02-17 12:47:54.962394 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-17 12:47:55.835040 | controller | changed
2026-02-17 12:47:55.842561 | 
2026-02-17 12:47:55.842654 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-17 12:47:55.868488 | controller | skipping: Conditional result was False
2026-02-17 12:47:55.876962 | 
2026-02-17 12:47:55.877075 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-17 12:47:56.270936 | controller -> localhost | changed
2026-02-17 12:47:56.281521 | 
2026-02-17 12:47:56.281617 | TASK [add-build-sshkey : Add back temp key]
2026-02-17 12:47:56.561018 | controller -> localhost | Identity added: /var/lib/zuul/builds/0fe8e3defa8248b9b7f038280ef99463/work/0fe8e3defa8248b9b7f038280ef99463_id_rsa (zuul-build-sshkey)
2026-02-17 12:47:56.561593 | controller -> localhost | ok: Runtime: 0:00:00.016820
2026-02-17 12:47:56.575306 | 
2026-02-17 12:47:56.575478 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-17 12:47:57.026902 | controller | ok
2026-02-17 12:47:57.039515 | 
2026-02-17 12:47:57.039664 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-17 12:47:57.068074 | controller | skipping: Conditional result was False
2026-02-17 12:47:57.084187 | 
2026-02-17 12:47:57.084296 | TASK [include_role : validate-host]
2026-02-17 12:47:57.119214 | controller | ok
2026-02-17 12:47:57.150851 | 
2026-02-17 12:47:57.151024 | TASK [validate-host : Define zuul_info_dir fact]
2026-02-17 12:47:57.201545 | controller | ok
2026-02-17 12:47:57.212401 | 
2026-02-17 12:47:57.212541 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-02-17 12:47:57.519656 | controller -> localhost | ok
2026-02-17 12:47:57.529291 | 
2026-02-17 12:47:57.529406 | TASK [validate-host : Collect information about the host]
2026-02-17 12:47:58.343975 | controller | ok
2026-02-17 12:47:58.362207 | 
2026-02-17 12:47:58.362382 | TASK [validate-host : Sanitize hostname]
2026-02-17 12:47:58.431160 | controller | ok
2026-02-17 12:47:58.442408 | 
2026-02-17 12:47:58.442542 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-02-17 12:47:59.008325 | controller -> localhost | changed
2026-02-17 12:47:59.014091 | 
2026-02-17 12:47:59.014158 | TASK [validate-host : Collect information about zuul worker]
2026-02-17 12:47:59.409214 | controller | ok
2026-02-17 12:47:59.420353 | 
2026-02-17 12:47:59.420493 | TASK [validate-host : Write out all zuul information for each host]
2026-02-17 12:47:59.970699 | controller -> localhost | changed
2026-02-17 12:47:59.992363 | 
2026-02-17 12:47:59.992554 | TASK [include_role : prepare-workspace-openshift]
2026-02-17 12:48:00.020611 | controller | skipping: Conditional result was False
2026-02-17 12:48:00.033429 | 
2026-02-17 12:48:00.033560 | TASK [include_role : remove-zuul-sshkey]
2026-02-17 12:48:00.060651 | controller | skipping: Conditional result was False
2026-02-17 12:48:00.074923 | 
2026-02-17 12:48:00.075143 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-17 12:48:00.380265 | controller | ok: "logs"
2026-02-17 12:48:00.380748 | controller | ok: All items complete
2026-02-17 12:48:00.380807 | 
2026-02-17 12:48:00.587748 | controller | ok: "artifacts"
2026-02-17 12:48:00.824260 | controller | ok: "docs"
2026-02-17 12:48:00.847669 | 
2026-02-17 12:48:00.847892 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-17 12:48:01.118498 | controller | changed: "logs"
2026-02-17 12:48:01.368802 | controller | changed: "artifacts"
2026-02-17 12:48:01.565537 | controller | changed: "docs"
2026-02-17 12:48:01.619240 | 
2026-02-17 12:48:01.619406 | PLAY RECAP
2026-02-17 12:48:01.619473 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-02-17 12:48:01.619510 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-17 12:48:01.619537 | 
2026-02-17 12:48:01.768343 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-02-17 12:48:01.770178 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2026-02-17 12:48:02.415899 | 
2026-02-17 12:48:02.416097 | PLAY [localhost]
2026-02-17 12:48:02.433097 | 
2026-02-17 12:48:02.433229 | TASK [configure-ssh-config-fips : Ensure ssh config directory]
2026-02-17 12:48:02.891039 | localhost | ok
2026-02-17 12:48:02.896401 | 
2026-02-17 12:48:02.896485 | TASK [configure-ssh-config-fips : Setup ssh config]
2026-02-17 12:48:03.612252 | localhost | changed
2026-02-17 12:48:03.654131 | 
2026-02-17 12:48:03.654293 | PLAY [all]
2026-02-17 12:48:03.671650 | 
2026-02-17 12:48:03.671735 | TASK [include_role : prepare-workspace]
2026-02-17 12:48:03.692255 | controller | ok
2026-02-17 12:48:03.706997 | 
2026-02-17 12:48:03.707103 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-17 12:48:04.074709 | controller | ok
2026-02-17 12:48:04.081564 | 
2026-02-17 12:48:04.081629 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-17 12:48:05.634814 | controller | Output suppressed because no_log was given
2026-02-17 12:48:05.644600 | 
2026-02-17 12:48:05.644668 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-17 12:48:05.935597 | controller | changed: "logs"
2026-02-17 12:48:06.166072 | controller | changed: "artifacts"
2026-02-17 12:48:06.404683 | controller | changed: "docs"
2026-02-17 12:48:06.418910 | 
2026-02-17 12:48:06.427848 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-17 12:48:06.705871 | controller | changed: "logs"
2026-02-17 12:48:06.706532 | controller | changed: All items complete
2026-02-17 12:48:06.706593 | 
2026-02-17 12:48:06.960529 | controller | changed: "artifacts"
2026-02-17 12:48:07.193091 | controller | changed: "docs"
2026-02-17 12:48:07.217457 | 
2026-02-17 12:48:07.217637 | TASK [Check if worker can sudo]
2026-02-17 12:48:07.751654 | controller | ok: Runtime: 0:00:00.039668
2026-02-17 12:48:07.763639 | 
2026-02-17 12:48:07.763789 | TASK [configure-mirrors : Gather needed facts]
2026-02-17 12:48:07.812185 | controller | skipping: Conditional result was False
2026-02-17 12:48:07.821084 | 
2026-02-17 12:48:07.821176 | TASK [configure-mirrors : Set up infrastructure mirrors]
2026-02-17 12:48:07.878308 | controller | ok
2026-02-17 12:48:07.893926 | controller | included: /var/lib/zuul/builds/0fe8e3defa8248b9b7f038280ef99463/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml
2026-02-17 12:48:07.903230 | 
2026-02-17 12:48:07.903342 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir]
2026-02-17 12:48:08.254941 | controller | ok
2026-02-17 12:48:08.268636 | 
2026-02-17 12:48:08.268772 | LOOP [configure-mirrors : Include OS-specific variables]
2026-02-17 12:48:08.366760 | controller | ok: "/var/lib/zuul/builds/0fe8e3defa8248b9b7f038280ef99463/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml"
2026-02-17 12:48:08.385279 | 
2026-02-17 12:48:08.385510 | TASK [configure-mirrors : Install /etc/pip.conf configuration]
2026-02-17 12:48:09.259203 | controller | changed
2026-02-17 12:48:09.264940 | 
2026-02-17 12:48:09.265037 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors]
2026-02-17 12:48:09.354980 | controller | ok: "/var/lib/zuul/builds/0fe8e3defa8248b9b7f038280ef99463/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml"
2026-02-17 12:48:09.355378 | controller | ok: All items complete
2026-02-17 12:48:09.355437 | 
2026-02-17 12:48:09.434704 | controller | included: /var/lib/zuul/builds/0fe8e3defa8248b9b7f038280ef99463/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml
2026-02-17 12:48:09.442588 | 
2026-02-17 12:48:09.442702 | LOOP [configure-mirrors : Install CentOS 9 repository files]
2026-02-17 12:48:10.403626 | controller | changed: "etc/yum.repos.d/centos.repo"
2026-02-17 12:48:11.185203 | controller | changed: "etc/yum.repos.d/centos-addons.repo"
2026-02-17 12:48:11.212124 | 
2026-02-17 12:48:11.212392 | TASK [configure-mirrors : Disable deltrarpm]
2026-02-17 12:48:11.689122 | controller | changed: section and option added
2026-02-17 12:48:11.714814 | 
2026-02-17 12:48:11.714910 | LOOP [configure-mirrors : Update yum/dnf cache]
2026-02-17 12:48:12.481614 | controller | 29 files removed
2026-02-17 12:48:12.482168 | controller | ok: Item: dnf clean all Runtime: 0:00:00.436974
2026-02-17 12:48:12.482260 | controller | changed: All items complete
2026-02-17 12:48:12.482309 | 
2026-02-17 12:48:23.508366 | 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-02-17 12:48:23.508499 | controller | DNF version: 4.14.0
2026-02-17 12:48:23.508524 | controller | cachedir: /var/cache/dnf
2026-02-17 12:48:23.508544 | controller | Making cache files for all metadata files.
2026-02-17 12:48:23.508563 | controller | baseos: has expired and will be refreshed.
2026-02-17 12:48:23.508581 | controller | appstream: has expired and will be refreshed.
2026-02-17 12:48:23.508598 | controller | crb: has expired and will be refreshed.
2026-02-17 12:48:23.508625 | controller | extras-common: has expired and will be refreshed.
2026-02-17 12:48:23.508643 | controller | repo: downloading from remote: baseos
2026-02-17 12:48:23.508659 | controller | CentOS Stream 9 - BaseOS                         72 MB/s | 8.9 MB     00:00
2026-02-17 12:48:23.508675 | controller | baseos: using metadata from Mon 16 Feb 2026 09:52:14 AM EST.
2026-02-17 12:48:23.508691 | controller | repo: downloading from remote: appstream
2026-02-17 12:48:23.508708 | controller | CentOS Stream 9 - AppStream                     124 MB/s |  27 MB     00:00
2026-02-17 12:48:23.508723 | controller | appstream: using metadata from Mon 16 Feb 2026 09:55:57 AM EST.
2026-02-17 12:48:23.508739 | controller | repo: downloading from remote: crb
2026-02-17 12:48:23.508756 | controller | CentOS Stream 9 - CRB                            64 MB/s | 7.8 MB     00:00
2026-02-17 12:48:23.508772 | controller | crb: using metadata from Mon 16 Feb 2026 10:00:28 AM EST.
2026-02-17 12:48:23.508788 | controller | repo: downloading from remote: extras-common
2026-02-17 12:48:23.508804 | controller | CentOS Stream 9 - Extras packages                70 kB/s |  20 kB     00:00
2026-02-17 12:48:23.508821 | controller | extras-common: using metadata from Thu 12 Feb 2026 03:34:59 PM EST.
2026-02-17 12:48:23.508837 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)'
2026-02-17 12:48:23.508852 | controller | Completion plugin: Generating completion cache...
2026-02-17 12:48:23.508868 | controller | Metadata cache created.
2026-02-17 12:48:23.508895 | controller | ok: Item: dnf makecache -v Runtime: 0:00:10.757607
2026-02-17 12:48:23.540489 | 
2026-02-17 12:48:23.540692 | PLAY RECAP
2026-02-17 12:48:23.540780 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-17 12:48:23.540837 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-17 12:48:23.540877 | 
2026-02-17 12:48:23.732010 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2026-02-17 12:48:23.733984 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-02-17 12:48:24.355381 | 
2026-02-17 12:48:24.355510 | PLAY [all]
2026-02-17 12:48:24.376281 | 
2026-02-17 12:48:24.376359 | TASK [Install binary dependencies]
2026-02-17 12:48:24.459145 | controller | ok
2026-02-17 12:48:24.491214 | 
2026-02-17 12:48:24.491344 | TASK [bindep : Include find tasks]
2026-02-17 12:48:24.531887 | controller | ok
2026-02-17 12:48:24.539700 | controller | included: /var/lib/zuul/builds/0fe8e3defa8248b9b7f038280ef99463/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-02-17 12:48:24.545601 | 
2026-02-17 12:48:24.545664 | TASK [bindep : Look for bindep.txt]
2026-02-17 12:48:25.018718 | controller | ok
2026-02-17 12:48:25.034912 | 
2026-02-17 12:48:25.035161 | TASK [bindep : Define bindep_file fact]
2026-02-17 12:48:25.070119 | controller | ok
2026-02-17 12:48:25.078089 | 
2026-02-17 12:48:25.078197 | TASK [bindep : Look for other-requirements.txt]
2026-02-17 12:48:25.107668 | controller | skipping: Conditional result was False
2026-02-17 12:48:25.120112 | 
2026-02-17 12:48:25.120285 | TASK [bindep : Define bindep_file fact]
2026-02-17 12:48:25.158767 | controller | skipping: Conditional result was False
2026-02-17 12:48:25.167836 | 
2026-02-17 12:48:25.167939 | TASK [bindep : Look for bindep fallback file]
2026-02-17 12:48:25.214901 | controller | skipping: Conditional result was False
2026-02-17 12:48:25.226865 | 
2026-02-17 12:48:25.227128 | TASK [bindep : Define bindep_file fact]
2026-02-17 12:48:25.256293 | controller | skipping: Conditional result was False
2026-02-17 12:48:25.272997 | 
2026-02-17 12:48:25.273306 | TASK [bindep : Include bindep tasks]
2026-02-17 12:48:25.322963 | controller | ok
2026-02-17 12:48:25.329923 | controller | included: /var/lib/zuul/builds/0fe8e3defa8248b9b7f038280ef99463/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml
2026-02-17 12:48:25.337962 | 
2026-02-17 12:48:25.338072 | TASK [bindep : Look for bindep command]
2026-02-17 12:48:25.363527 | controller | skipping: Conditional result was False
2026-02-17 12:48:25.371484 | 
2026-02-17 12:48:25.371560 | TASK [bindep : Check for system bindep]
2026-02-17 12:48:25.902614 | controller | ok: Runtime: 0:00:00.005258
2026-02-17 12:48:25.916556 | 
2026-02-17 12:48:25.916798 | TASK [bindep : Define bindep_command fact]
2026-02-17 12:48:25.957584 | controller | skipping: Conditional result was False
2026-02-17 12:48:25.972199 | 
2026-02-17 12:48:25.972431 | TASK [bindep : Include install tasks]
2026-02-17 12:48:26.019260 | controller | ok
2026-02-17 12:48:26.031636 | controller | included: /var/lib/zuul/builds/0fe8e3defa8248b9b7f038280ef99463/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml
2026-02-17 12:48:26.045779 | 
2026-02-17 12:48:26.045915 | TASK [bindep : Create temp dir for bindep]
2026-02-17 12:48:26.472611 | controller | changed
2026-02-17 12:48:26.487136 | 
2026-02-17 12:48:26.487321 | TASK [Ensure we have pip dependencies]
2026-02-17 12:48:26.545389 | controller | ok
2026-02-17 12:48:26.587080 | 
2026-02-17 12:48:26.587241 | TASK [ensure-pip : Check if pip is installed]
2026-02-17 07:48:26.867850 | controller | /usr/bin/pip3
2026-02-17 07:48:26.906752 | controller | /usr/bin/python3: No module named wheel
2026-02-17 12:48:27.120753 | controller | ok: Runtime: 0:00:00.049046
2026-02-17 12:48:27.137049 | 
2026-02-17 12:48:27.137281 | LOOP [ensure-pip : Install pip from packages]
2026-02-17 12:48:27.199790 | controller | ok: "/var/lib/zuul/builds/0fe8e3defa8248b9b7f038280ef99463/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml"
2026-02-17 12:48:27.215220 | controller | included: /var/lib/zuul/builds/0fe8e3defa8248b9b7f038280ef99463/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml
2026-02-17 12:48:27.230397 | 
2026-02-17 12:48:27.230520 | TASK [ensure-pip : Install Python 3 pip]
2026-02-17 12:48:29.379798 | controller | changed
2026-02-17 12:48:29.391543 | 
2026-02-17 12:48:29.391688 | TASK [ensure-pip : Check for EPEL repository]
2026-02-17 12:48:29.461428 | controller | skipping: Conditional result was False
2026-02-17 12:48:29.474998 | 
2026-02-17 12:48:29.475201 | TASK [ensure-pip : Fail with instructions when EPEL is not installed]
2026-02-17 12:48:29.544412 | controller | skipping: Conditional result was False
2026-02-17 12:48:29.559535 | 
2026-02-17 12:48:29.559678 | TASK [ensure-pip : Install Python 2 pip]
2026-02-17 12:48:29.618453 | controller | skipping: Conditional result was False
2026-02-17 12:48:29.633295 | 
2026-02-17 12:48:29.633444 | TASK [ensure-pip : Ensure setuptools]
2026-02-17 12:48:29.660572 | controller | skipping: Conditional result was False
2026-02-17 12:48:29.673284 | 
2026-02-17 12:48:29.673425 | TASK [ensure-pip : Check for ensurepip module]
2026-02-17 12:48:30.221593 | controller | skipping: Conditional result was False
2026-02-17 12:48:30.239006 | 
2026-02-17 12:48:30.239290 | TASK [ensure-pip : Ensure python3-venv]
2026-02-17 12:48:30.267798 | controller | skipping: Conditional result was False
2026-02-17 12:48:30.281402 | 
2026-02-17 12:48:30.281585 | TASK [ensure-pip : Install pip from source]
2026-02-17 12:48:30.309689 | controller | skipping: Conditional result was False
2026-02-17 12:48:30.323521 | 
2026-02-17 12:48:30.323745 | TASK [ensure-pip : Probe for venv python full path]
2026-02-17 07:48:30.617338 | controller | /usr/bin/python3
2026-02-17 12:48:30.874264 | controller | ok: Runtime: 0:00:00.005264
2026-02-17 12:48:30.887208 | 
2026-02-17 12:48:30.887345 | TASK [ensure-pip : Set host default]
2026-02-17 12:48:30.967376 | controller | ok
2026-02-17 12:48:30.979789 | 
2026-02-17 12:48:30.979950 | TASK [ensure-pip : Set ensure_pip_virtualenv_command]
2026-02-17 12:48:31.064334 | controller | ok
2026-02-17 12:48:31.087896 | 
2026-02-17 12:48:31.088097 | TASK [bindep : Install bindep into temporary venv]
2026-02-17 12:48:35.556907 | controller | changed
2026-02-17 12:48:35.571233 | 
2026-02-17 12:48:35.571367 | TASK [bindep : Define bindep_command]
2026-02-17 12:48:35.612962 | controller | ok
2026-02-17 12:48:35.618424 | 
2026-02-17 12:48:35.618492 | LOOP [bindep : Include package tasks]
2026-02-17 12:48:35.689874 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt"
2026-02-17 12:48:35.690320 | controller | ok: All items complete
2026-02-17 12:48:35.690354 | 
2026-02-17 12:48:35.714933 | controller | included: /var/lib/zuul/builds/0fe8e3defa8248b9b7f038280ef99463/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml
2026-02-17 12:48:35.731055 | 
2026-02-17 12:48:35.731160 | TASK [bindep : Define bindep_run fact]
2026-02-17 12:48:35.775213 | controller | ok
2026-02-17 12:48:35.782946 | 
2026-02-17 12:48:35.783068 | TASK [bindep : Get list of packages to install from bindep]
2026-02-17 07:48:37.122119 | controller | podman
2026-02-17 07:48:37.165606 | controller | python3-jmespath
2026-02-17 07:48:37.165851 | controller | python3-libvirt
2026-02-17 07:48:37.165861 | controller | python3-lxml
2026-02-17 07:48:37.165869 | controller | python3-netaddr
2026-02-17 12:48:37.333770 | controller | ok: Runtime: 0:00:01.122439
2026-02-17 12:48:37.346141 | 
2026-02-17 12:48:37.346282 | TASK [bindep : Install distro packages from bindep]
2026-02-17 12:49:20.507614 | controller | changed
2026-02-17 12:49:20.514618 | 
2026-02-17 12:49:20.514681 | TASK [bindep : Check that packages are installed]
2026-02-17 12:49:22.045727 | controller | ok: Runtime: 0:00:01.042866
2026-02-17 12:49:22.053409 | 
2026-02-17 12:49:22.053509 | TASK [bindep : Fail if we cannot install all packages]
2026-02-17 12:49:22.088881 | controller | skipping: Conditional result was False
2026-02-17 12:49:22.103485 | 
2026-02-17 12:49:22.103583 | TASK [Run test-setup role]
2026-02-17 12:49:22.135370 | controller | ok
2026-02-17 12:49:22.155131 | 
2026-02-17 12:49:22.155236 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-02-17 12:49:22.419544 | controller | ok
2026-02-17 12:49:22.432045 | 
2026-02-17 12:49:22.432222 | TASK [test-setup : Run tools/test-setup.sh]
2026-02-17 12:49:22.983228 | controller | skipping: Conditional result was False
2026-02-17 12:49:23.025960 | 
2026-02-17 12:49:23.026158 | TASK [bindep : Remove bindep temp dir]
2026-02-17 12:49:23.451488 | controller | ok
2026-02-17 12:49:23.474260 | 
2026-02-17 12:49:23.474388 | PLAY RECAP
2026-02-17 12:49:23.474481 | controller | ok: 22 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0
2026-02-17 12:49:23.474548 | 
2026-02-17 12:49:23.610456 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-02-17 12:49:23.612999 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2026-02-17 12:49:24.233089 | 
2026-02-17 12:49:24.233228 | PLAY [all]
2026-02-17 12:49:24.252376 | 
2026-02-17 12:49:24.252463 | TASK [Abort when test_command variable is undefined]
2026-02-17 12:49:24.277504 | controller | skipping: Conditional result was False
2026-02-17 12:49:24.283205 | 
2026-02-17 12:49:24.283279 | TASK [Convert test_command to list]
2026-02-17 12:49:24.338453 | controller | skipping: Conditional result was False
2026-02-17 12:49:24.345453 | 
2026-02-17 12:49:24.345532 | TASK [Use test_command list]
2026-02-17 12:49:24.405533 | controller | ok
2026-02-17 12:49:24.410914 | 
2026-02-17 12:49:24.410979 | LOOP [Run test_command]
2026-02-17 12:49:24.832095 | controller | no check to run
2026-02-17 12:49:24.832483 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.003340
2026-02-17 12:49:24.874896 | 
2026-02-17 12:49:24.875196 | PLAY RECAP
2026-02-17 12:49:24.875268 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-02-17 12:49:24.875300 | 
2026-02-17 12:49:25.031423 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2026-02-17 12:49:25.033368 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-02-17 12:49:25.665755 | 
2026-02-17 12:49:25.665909 | PLAY [all]
2026-02-17 12:49:25.689342 | 
2026-02-17 12:49:25.689521 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-02-17 12:49:26.044922 | controller | changed: non-zero return code
2026-02-17 12:49:26.059563 | 
2026-02-17 12:49:26.059850 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-02-17 12:49:26.088028 | controller | skipping: Conditional result was False
2026-02-17 12:49:26.102134 | 
2026-02-17 12:49:26.102282 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-02-17 12:49:26.143933 | 
2026-02-17 12:49:26.144239 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-02-17 12:49:26.183995 | 
2026-02-17 12:49:26.184301 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-02-17 12:49:26.211768 | controller | skipping: Conditional result was False
2026-02-17 12:49:26.224848 | 
2026-02-17 12:49:26.224988 | LOOP [fetch-subunit-output : Generate subunit file]
2026-02-17 12:49:26.265291 | 
2026-02-17 12:49:26.265539 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-02-17 12:49:26.306352 | controller | skipping: Conditional result was False
2026-02-17 12:49:26.318483 | 
2026-02-17 12:49:26.318787 | TASK [fetch-subunit-output : Remove the temporary file]
2026-02-17 12:49:26.347088 | controller | skipping: Conditional result was False
2026-02-17 12:49:26.360468 | 
2026-02-17 12:49:26.360656 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-02-17 12:49:26.388199 | controller | skipping: Conditional result was False
2026-02-17 12:49:26.435204 | 
2026-02-17 12:49:26.435390 | PLAY RECAP
2026-02-17 12:49:26.435474 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-02-17 12:49:26.435512 | 
2026-02-17 12:49:26.564671 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-02-17 12:49:26.566375 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-02-17 12:49:27.206478 | 
2026-02-17 12:49:27.206682 | PLAY [all]
2026-02-17 12:49:27.229466 | 
2026-02-17 12:49:27.229671 | TASK [include_role : fetch-output]
2026-02-17 12:49:27.279947 | controller | ok
2026-02-17 12:49:27.298571 | 
2026-02-17 12:49:27.298685 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-17 12:49:27.364423 | controller | skipping: Conditional result was False
2026-02-17 12:49:27.371427 | 
2026-02-17 12:49:27.371514 | TASK [fetch-output : Set log path for single node]
2026-02-17 12:49:27.425115 | controller | ok
2026-02-17 12:49:27.434078 | 
2026-02-17 12:49:27.434212 | LOOP [fetch-output : Ensure local output dirs]
2026-02-17 12:49:27.886802 | controller -> localhost | ok: "/var/lib/zuul/builds/0fe8e3defa8248b9b7f038280ef99463/work/logs"
2026-02-17 12:49:28.137523 | controller -> localhost | changed: "/var/lib/zuul/builds/0fe8e3defa8248b9b7f038280ef99463/work/artifacts"
2026-02-17 12:49:28.398323 | controller -> localhost | changed: "/var/lib/zuul/builds/0fe8e3defa8248b9b7f038280ef99463/work/docs"
2026-02-17 12:49:28.425082 | 
2026-02-17 12:49:28.425393 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-17 12:49:29.075859 | controller | changed: .d..t...... ./
2026-02-17 12:49:29.076263 | controller | changed: All items complete
2026-02-17 12:49:29.076329 | 
2026-02-17 12:49:29.550277 | controller | changed: .d..t...... ./
2026-02-17 12:49:30.015901 | controller | changed: .d..t...... ./
2026-02-17 12:49:30.037631 | 
2026-02-17 12:49:30.037823 | TASK [include_role : fetch-output-openshift]
2026-02-17 12:49:30.063281 | controller | skipping: Conditional result was False
2026-02-17 12:49:30.072259 | 
2026-02-17 12:49:30.072357 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-17 12:49:30.520709 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.009109
2026-02-17 12:49:30.772226 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.013688
2026-02-17 12:49:30.808359 | 
2026-02-17 12:49:30.808511 | PLAY [all]
2026-02-17 12:49:30.823166 | 
2026-02-17 12:49:30.823239 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-17 12:49:31.300010 | controller | changed
2026-02-17 12:49:31.348620 | 
2026-02-17 12:49:31.348794 | PLAY RECAP
2026-02-17 12:49:31.348851 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-02-17 12:49:31.348880 | 
2026-02-17 12:49:31.478102 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-02-17 12:49:31.479859 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-02-17 12:49:32.150040 | 
2026-02-17 12:49:32.150229 | PLAY [localhost]
2026-02-17 12:49:32.168611 | 
2026-02-17 12:49:32.168711 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-17 12:49:32.621892 | localhost | changed
2026-02-17 12:49:32.632739 | 
2026-02-17 12:49:32.632897 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-17 12:49:32.680626 | localhost | ok
2026-02-17 12:49:32.699322 | 
2026-02-17 12:49:32.699462 | TASK [add-fileserver : Create SSH private key tempfile]
2026-02-17 12:49:33.149070 | localhost | changed
2026-02-17 12:49:33.161911 | 
2026-02-17 12:49:33.162116 | TASK [add-fileserver : Create SSH private key from secret]
2026-02-17 12:49:33.985947 | localhost | changed
2026-02-17 12:49:33.997329 | 
2026-02-17 12:49:33.997467 | TASK [add-fileserver : Add fileserver ssh key]
2026-02-17 12:49:34.461089 | localhost | Identity added: /var/lib/zuul/builds/0fe8e3defa8248b9b7f038280ef99463/work/tmp/ansible.ag29m7k5 (/var/lib/zuul/builds/0fe8e3defa8248b9b7f038280ef99463/work/tmp/ansible.ag29m7k5)
2026-02-17 12:49:34.461334 | localhost | ok: Runtime: 0:00:00.014529
2026-02-17 12:49:34.466031 | 
2026-02-17 12:49:34.466098 | TASK [add-fileserver : Remove SSH private key from disk]
2026-02-17 12:49:34.801752 | localhost | ok: Runtime: 0:00:00.009034
2026-02-17 12:49:34.814141 | 
2026-02-17 12:49:34.814298 | TASK [add-fileserver : Add fileserver to inventory]
2026-02-17 12:49:34.892693 | localhost | changed
2026-02-17 12:49:34.902910 | 
2026-02-17 12:49:34.903078 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-02-17 12:49:35.350440 | localhost | changed
2026-02-17 12:49:35.394056 | 
2026-02-17 12:49:35.394210 | PLAY [localhost]
2026-02-17 12:49:35.411564 | 
2026-02-17 12:49:35.411670 | TASK [Generate bulk log download script]
2026-02-17 12:49:35.435695 | localhost | ok
2026-02-17 12:49:35.452559 | 
2026-02-17 12:49:35.452660 | TASK [local-log-download : Check API endpoint is defined]
2026-02-17 12:49:35.504583 | localhost | ok: All assertions passed
2026-02-17 12:49:35.511235 | 
2026-02-17 12:49:35.511317 | TASK [local-log-download : Create download script]
2026-02-17 12:49:35.965230 | localhost -> localhost | changed
2026-02-17 12:49:35.974375 | 
2026-02-17 12:49:35.974439 | TASK [Register quick-download link]
2026-02-17 12:49:36.004468 | localhost | ok
2026-02-17 12:49:36.053404 | 
2026-02-17 12:49:36.053534 | PLAY [logserver.rdoproject.org]
2026-02-17 12:49:36.063324 | 
2026-02-17 12:49:36.063392 | TASK [Set zuul-log-path fact]
2026-02-17 12:49:36.082365 | logserver.rdoproject.org | ok
2026-02-17 12:49:36.091689 | 
2026-02-17 12:49:36.091768 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-17 12:49:36.130265 | logserver.rdoproject.org | ok
2026-02-17 12:49:36.135462 | 
2026-02-17 12:49:36.135526 | TASK [upload-logs : Create log directories]
2026-02-17 12:49:37.061870 | logserver.rdoproject.org | changed
2026-02-17 12:49:37.069263 | 
2026-02-17 12:49:37.069424 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-17 12:49:37.383333 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.010402
2026-02-17 12:49:37.393986 | 
2026-02-17 12:49:37.394177 | TASK [upload-logs : Upload logs to log server]
2026-02-17 12:49:38.175689 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-02-17 12:49:38.183156 | 
2026-02-17 12:49:38.183299 | LOOP [upload-logs : Compress console log and json output]
2026-02-17 12:49:38.256555 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-17 12:49:38.268527 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-17 12:49:38.283012 | 
2026-02-17 12:49:38.283273 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-17 12:49:38.334541 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-17 12:49:38.335000 | 
2026-02-17 12:49:38.337624 | logserver.rdoproject.org | skipping: Conditional result was False
2026-02-17 12:49:38.350411 | 
2026-02-17 12:49:38.350783 | LOOP [upload-logs : Upload console log and json output]