2025-12-05 11:18:21.570781 | Job console starting...
2025-12-05 11:18:21.581121 | Updating repositories
2025-12-05 11:18:21.610367 | Preparing job workspace
2025-12-05 11:18:25.349886 | Running Ansible setup...
2025-12-05 11:18:29.146615 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 11:18:29.725140 | 
2025-12-05 11:18:29.725238 | PLAY [localhost]
2025-12-05 11:18:29.733751 | 
2025-12-05 11:18:29.733819 | TASK [Gathering Facts]
2025-12-05 11:18:30.726221 | localhost | ok
2025-12-05 11:18:30.762797 | 
2025-12-05 11:18:30.762927 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-05 11:18:31.270139 | localhost -> localhost | changed
2025-12-05 11:18:31.280240 | 
2025-12-05 11:18:31.280394 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-05 11:18:32.155125 | localhost -> localhost | changed
2025-12-05 11:18:32.163859 | 
2025-12-05 11:18:32.163928 | TASK [Setup log path fact]
2025-12-05 11:18:32.182607 | localhost | ok
2025-12-05 11:18:32.194837 | 
2025-12-05 11:18:32.194901 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 11:18:32.232695 | localhost | ok
2025-12-05 11:18:32.241268 | 
2025-12-05 11:18:32.241334 | TASK [emit-job-header : Print job information]
2025-12-05 11:18:32.269467 | # Job Information
2025-12-05 11:18:32.269592 | Ansible Version: 2.15.12
2025-12-05 11:18:32.269619 | Job: cifmw-molecule-fdp_update_container_images
2025-12-05 11:18:32.269640 | Pipeline: github-check
2025-12-05 11:18:32.269660 | Executor: ze03.softwarefactory-project.io
2025-12-05 11:18:32.269678 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3467
2025-12-05 11:18:32.269699 | Log URL (when completed): https://logserver.rdoproject.org/199/rdoproject.org/1991e80b3aa547df9d0b889f87e9cb7d/
2025-12-05 11:18:32.269718 | Event ID: ee769340-d1cb-11f0-81a5-3d656e1ac3e6
2025-12-05 11:18:32.273348 | 
2025-12-05 11:18:32.273406 | LOOP [emit-job-header : Print node information]
2025-12-05 11:18:32.370801 | localhost | ok:
2025-12-05 11:18:32.371403 | localhost | # Node Information
2025-12-05 11:18:32.371504 | localhost | Inventory Hostname: controller
2025-12-05 11:18:32.371580 | localhost | Hostname: np0005546925
2025-12-05 11:18:32.371643 | localhost | Username: zuul
2025-12-05 11:18:32.371712 | localhost | Distro: CentOS 9
2025-12-05 11:18:32.371832 | localhost | Provider: vexxhost-nodepool-tripleo
2025-12-05 11:18:32.371894 | localhost | Region: RegionOne
2025-12-05 11:18:32.371952 | localhost | Label: cloud-centos-9-stream-tripleo
2025-12-05 11:18:32.372002 | localhost | Product Name: OpenStack Nova
2025-12-05 11:18:32.372051 | localhost | Interface IP: 38.102.83.107
2025-12-05 11:18:32.401523 | 
2025-12-05 11:18:32.401641 | PLAY [all]
2025-12-05 11:18:32.408412 | 
2025-12-05 11:18:32.408475 | TASK [Gather network facts]
2025-12-05 11:18:32.892093 | controller | ok
2025-12-05 11:18:32.906475 | 
2025-12-05 11:18:32.906566 | TASK [include_role : start-zuul-console]
2025-12-05 11:18:32.925886 | controller | ok
2025-12-05 11:18:32.937128 | 
2025-12-05 11:18:32.937205 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-05 11:18:33.318271 | controller | ok
2025-12-05 11:18:33.337058 | 
2025-12-05 11:18:33.337183 | TASK [include_role : add-build-sshkey]
2025-12-05 11:18:33.374293 | controller | ok
2025-12-05 11:18:33.402135 | 
2025-12-05 11:18:33.402263 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-05 11:18:33.661940 | controller -> localhost | ok
2025-12-05 11:18:33.667450 | 
2025-12-05 11:18:33.667514 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-05 11:18:33.696086 | controller | ok
2025-12-05 11:18:33.708765 | controller | included: /var/lib/zuul/builds/1991e80b3aa547df9d0b889f87e9cb7d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-05 11:18:33.714649 | 
2025-12-05 11:18:33.714713 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-05 11:18:34.323033 | controller -> localhost | Generating public/private rsa key pair.
2025-12-05 11:18:34.323216 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/1991e80b3aa547df9d0b889f87e9cb7d/work/1991e80b3aa547df9d0b889f87e9cb7d_id_rsa.
2025-12-05 11:18:34.323247 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/1991e80b3aa547df9d0b889f87e9cb7d/work/1991e80b3aa547df9d0b889f87e9cb7d_id_rsa.pub.
2025-12-05 11:18:34.323269 | controller -> localhost | The key fingerprint is:
2025-12-05 11:18:34.323289 | controller -> localhost | SHA256:LSKGatX6NDGQf8SMIAyrorlTjGaFM2C1RfBK0x1Ys7I zuul-build-sshkey
2025-12-05 11:18:34.323307 | controller -> localhost | The key's randomart image is:
2025-12-05 11:18:34.323325 | controller -> localhost | +---[RSA 3072]----+
2025-12-05 11:18:34.323342 | controller -> localhost | |+..+ooo+         |
2025-12-05 11:18:34.323360 | controller -> localhost | |.+. B.= +        |
2025-12-05 11:18:34.323378 | controller -> localhost | |+ .* = *         |
2025-12-05 11:18:34.323395 | controller -> localhost | |.+.o* +  .       |
2025-12-05 11:18:34.323431 | controller -> localhost | |oo=ooE..S .      |
2025-12-05 11:18:34.323448 | controller -> localhost | |+=+...+. .       |
2025-12-05 11:18:34.323466 | controller -> localhost | |*o . o           |
2025-12-05 11:18:34.323484 | controller -> localhost | |o.  o .          |
2025-12-05 11:18:34.323501 | controller -> localhost | |..   .           |
2025-12-05 11:18:34.323519 | controller -> localhost | +----[SHA256]-----+
2025-12-05 11:18:34.323561 | controller -> localhost | ok: Runtime: 0:00:00.174722
2025-12-05 11:18:34.329112 | 
2025-12-05 11:18:34.329177 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-05 11:18:34.360875 | controller | ok
2025-12-05 11:18:34.370448 | controller | included: /var/lib/zuul/builds/1991e80b3aa547df9d0b889f87e9cb7d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-05 11:18:34.378360 | 
2025-12-05 11:18:34.378417 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-05 11:18:34.402430 | controller | skipping: Conditional result was False
2025-12-05 11:18:34.408416 | 
2025-12-05 11:18:34.408483 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-05 11:18:34.978377 | controller | changed
2025-12-05 11:18:34.983129 | 
2025-12-05 11:18:34.983195 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-05 11:18:35.255660 | controller | ok
2025-12-05 11:18:35.261446 | 
2025-12-05 11:18:35.261556 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-05 11:18:36.195466 | controller | changed
2025-12-05 11:18:36.206242 | 
2025-12-05 11:18:36.206372 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-05 11:18:37.039522 | controller | changed
2025-12-05 11:18:37.050395 | 
2025-12-05 11:18:37.050524 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-05 11:18:37.077250 | controller | skipping: Conditional result was False
2025-12-05 11:18:37.090474 | 
2025-12-05 11:18:37.090635 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-05 11:18:37.525267 | controller -> localhost | changed
2025-12-05 11:18:37.534840 | 
2025-12-05 11:18:37.534906 | TASK [add-build-sshkey : Add back temp key]
2025-12-05 11:18:37.854964 | controller -> localhost | Identity added: /var/lib/zuul/builds/1991e80b3aa547df9d0b889f87e9cb7d/work/1991e80b3aa547df9d0b889f87e9cb7d_id_rsa (zuul-build-sshkey)
2025-12-05 11:18:37.855332 | controller -> localhost | ok: Runtime: 0:00:00.019774
2025-12-05 11:18:37.867267 | 
2025-12-05 11:18:37.867394 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-05 11:18:38.282641 | controller | ok
2025-12-05 11:18:38.292566 | 
2025-12-05 11:18:38.292695 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-05 11:18:38.333150 | controller | skipping: Conditional result was False
2025-12-05 11:18:38.353814 | 
2025-12-05 11:18:38.353946 | TASK [include_role : validate-host]
2025-12-05 11:18:38.391014 | controller | ok
2025-12-05 11:18:38.424251 | 
2025-12-05 11:18:38.424342 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-05 11:18:38.469177 | controller | ok
2025-12-05 11:18:38.476862 | 
2025-12-05 11:18:38.476992 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-05 11:18:38.780327 | controller -> localhost | ok
2025-12-05 11:18:38.786052 | 
2025-12-05 11:18:38.786123 | TASK [validate-host : Collect information about the host]
2025-12-05 11:18:39.667959 | controller | ok
2025-12-05 11:18:39.676474 | 
2025-12-05 11:18:39.676534 | TASK [validate-host : Sanitize hostname]
2025-12-05 11:18:39.737963 | controller | ok
2025-12-05 11:18:39.742815 | 
2025-12-05 11:18:39.742878 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-05 11:18:40.244619 | controller -> localhost | changed
2025-12-05 11:18:40.250665 | 
2025-12-05 11:18:40.250767 | TASK [validate-host : Collect information about zuul worker]
2025-12-05 11:18:40.673198 | controller | ok
2025-12-05 11:18:40.683570 | 
2025-12-05 11:18:40.683700 | TASK [validate-host : Write out all zuul information for each host]
2025-12-05 11:18:41.206210 | controller -> localhost | changed
2025-12-05 11:18:41.221067 | 
2025-12-05 11:18:41.221204 | TASK [include_role : prepare-workspace-openshift]
2025-12-05 11:18:41.235266 | controller | skipping: Conditional result was False
2025-12-05 11:18:41.242233 | 
2025-12-05 11:18:41.242317 | TASK [include_role : remove-zuul-sshkey]
2025-12-05 11:18:41.278584 | controller | skipping: Conditional result was False
2025-12-05 11:18:41.285931 | 
2025-12-05 11:18:41.286019 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-05 11:18:41.570404 | controller | ok: "logs"
2025-12-05 11:18:41.570636 | controller | ok: All items complete
2025-12-05 11:18:41.570663 | 
2025-12-05 11:18:41.820389 | controller | ok: "artifacts"
2025-12-05 11:18:42.073413 | controller | ok: "docs"
2025-12-05 11:18:42.092108 | 
2025-12-05 11:18:42.092300 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-05 11:18:42.408457 | controller | changed: "logs"
2025-12-05 11:18:42.648919 | controller | changed: "artifacts"
2025-12-05 11:18:42.887667 | controller | changed: "docs"
2025-12-05 11:18:42.926098 | 
2025-12-05 11:18:42.926201 | PLAY RECAP
2025-12-05 11:18:42.926260 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-05 11:18:42.926298 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 11:18:42.926325 | 
2025-12-05 11:18:43.031846 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 11:18:43.032633 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-12-05 11:18:43.648115 | 
2025-12-05 11:18:43.648313 | PLAY [localhost]
2025-12-05 11:18:43.675827 | 
2025-12-05 11:18:43.675991 | TASK [configure-ssh-config-fips : Ensure ssh config directory]
2025-12-05 11:18:44.071743 | localhost | ok
2025-12-05 11:18:44.079547 | 
2025-12-05 11:18:44.079620 | TASK [configure-ssh-config-fips : Setup ssh config]
2025-12-05 11:18:44.820899 | localhost | changed
2025-12-05 11:18:44.843277 | 
2025-12-05 11:18:44.843377 | PLAY [all]
2025-12-05 11:18:44.864338 | 
2025-12-05 11:18:44.864487 | TASK [include_role : prepare-workspace]
2025-12-05 11:18:44.895935 | controller | ok
2025-12-05 11:18:44.915529 | 
2025-12-05 11:18:44.915666 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-05 11:18:45.346380 | controller | ok
2025-12-05 11:18:45.361170 | 
2025-12-05 11:18:45.361340 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-05 11:18:47.409862 | controller | Output suppressed because no_log was given
2025-12-05 11:18:47.431672 | 
2025-12-05 11:18:47.431859 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-05 11:18:47.699831 | controller | changed: "logs"
2025-12-05 11:18:47.941179 | controller | changed: "artifacts"
2025-12-05 11:18:48.194000 | controller | changed: "docs"
2025-12-05 11:18:48.210936 | 
2025-12-05 11:18:48.211106 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-05 11:18:48.472092 | controller | changed: "logs"
2025-12-05 11:18:48.472472 | controller | changed: All items complete
2025-12-05 11:18:48.472531 | 
2025-12-05 11:18:48.729008 | controller | changed: "artifacts"
2025-12-05 11:18:48.971304 | controller | changed: "docs"
2025-12-05 11:18:49.001701 | 
2025-12-05 11:18:49.001863 | TASK [Check if worker can sudo]
2025-12-05 11:18:49.542320 | controller | ok: Runtime: 0:00:00.039903
2025-12-05 11:18:49.555887 | 
2025-12-05 11:18:49.556023 | TASK [configure-mirrors : Gather needed facts]
2025-12-05 11:18:49.636675 | controller | skipping: Conditional result was False
2025-12-05 11:18:49.650923 | 
2025-12-05 11:18:49.651064 | TASK [configure-mirrors : Set up infrastructure mirrors]
2025-12-05 11:18:49.764278 | controller | ok
2025-12-05 11:18:49.781369 | controller | included: /var/lib/zuul/builds/1991e80b3aa547df9d0b889f87e9cb7d/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml
2025-12-05 11:18:49.820199 | 
2025-12-05 11:18:49.820336 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir]
2025-12-05 11:18:50.086188 | controller | ok
2025-12-05 11:18:50.105034 | 
2025-12-05 11:18:50.105220 | LOOP [configure-mirrors : Include OS-specific variables]
2025-12-05 11:18:50.196012 | controller | ok: "/var/lib/zuul/builds/1991e80b3aa547df9d0b889f87e9cb7d/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml"
2025-12-05 11:18:50.214469 | 
2025-12-05 11:18:50.214654 | TASK [configure-mirrors : Install /etc/pip.conf configuration]
2025-12-05 11:18:51.057422 | controller | changed
2025-12-05 11:18:51.069977 | 
2025-12-05 11:18:51.070121 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors]
2025-12-05 11:18:51.160505 | controller | ok: "/var/lib/zuul/builds/1991e80b3aa547df9d0b889f87e9cb7d/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml"
2025-12-05 11:18:51.160894 | controller | ok: All items complete
2025-12-05 11:18:51.160931 | 
2025-12-05 11:18:51.233444 | controller | included: /var/lib/zuul/builds/1991e80b3aa547df9d0b889f87e9cb7d/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml
2025-12-05 11:18:51.241501 | 
2025-12-05 11:18:51.241623 | LOOP [configure-mirrors : Install CentOS 9 repository files]
2025-12-05 11:18:52.170582 | controller | changed: "etc/yum.repos.d/centos.repo"
2025-12-05 11:18:52.941551 | controller | changed: "etc/yum.repos.d/centos-addons.repo"
2025-12-05 11:18:52.959103 | 
2025-12-05 11:18:52.959436 | TASK [configure-mirrors : Disable deltrarpm]
2025-12-05 11:18:53.443840 | controller | changed: section and option added
2025-12-05 11:18:53.508949 | 
2025-12-05 11:18:53.509078 | LOOP [configure-mirrors : Update yum/dnf cache]
2025-12-05 11:18:54.092585 | controller | 29 files removed
2025-12-05 11:18:54.092910 | controller | ok: Item: dnf clean all Runtime: 0:00:00.271949
2025-12-05 11:18:54.092973 | controller | changed: All items complete
2025-12-05 11:18:54.093009 | 
2025-12-05 11:19:05.342043 | 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
2025-12-05 11:19:05.342123 | controller | DNF version: 4.14.0
2025-12-05 11:19:05.342149 | controller | cachedir: /var/cache/dnf
2025-12-05 11:19:05.342169 | controller | Making cache files for all metadata files.
2025-12-05 11:19:05.342188 | controller | baseos: has expired and will be refreshed.
2025-12-05 11:19:05.342206 | controller | appstream: has expired and will be refreshed.
2025-12-05 11:19:05.342223 | controller | crb: has expired and will be refreshed.
2025-12-05 11:19:05.342249 | controller | extras-common: has expired and will be refreshed.
2025-12-05 11:19:05.342267 | controller | repo: downloading from remote: baseos
2025-12-05 11:19:05.342283 | controller | CentOS Stream 9 - BaseOS                         66 MB/s | 8.8 MB     00:00
2025-12-05 11:19:05.342300 | controller | baseos: using metadata from Mon 01 Dec 2025 12:58:39 PM EST.
2025-12-05 11:19:05.342316 | controller | repo: downloading from remote: appstream
2025-12-05 11:19:05.342333 | controller | CentOS Stream 9 - AppStream                      76 MB/s |  25 MB     00:00
2025-12-05 11:19:05.342349 | controller | appstream: using metadata from Mon 01 Dec 2025 01:01:55 PM EST.
2025-12-05 11:19:05.342365 | controller | repo: downloading from remote: crb
2025-12-05 11:19:05.342381 | controller | CentOS Stream 9 - CRB                            55 MB/s | 7.3 MB     00:00
2025-12-05 11:19:05.342398 | controller | crb: using metadata from Mon 01 Dec 2025 01:06:07 PM EST.
2025-12-05 11:19:05.342414 | controller | repo: downloading from remote: extras-common
2025-12-05 11:19:05.342430 | controller | CentOS Stream 9 - Extras packages               1.1 MB/s |  20 kB     00:00
2025-12-05 11:19:05.342447 | controller | extras-common: using metadata from Thu 30 Oct 2025 09:20:08 AM EDT.
2025-12-05 11:19:05.342463 | controller | Last metadata expiration check: 0:00:01 ago on Fri 05 Dec 2025 06:19:03 AM EST.
2025-12-05 11:19:05.342479 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)'
2025-12-05 11:19:05.342495 | controller | Completion plugin: Generating completion cache...
2025-12-05 11:19:05.342512 | controller | Metadata cache created.
2025-12-05 11:19:05.342537 | controller | ok: Item: dnf makecache -v Runtime: 0:00:11.003182
2025-12-05 11:19:05.376079 | 
2025-12-05 11:19:05.376206 | PLAY RECAP
2025-12-05 11:19:05.376260 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-05 11:19:05.376295 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 11:19:05.376319 | 
2025-12-05 11:19:05.476275 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-12-05 11:19:05.477104 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-05 11:19:06.013592 | 
2025-12-05 11:19:06.013772 | PLAY [all]
2025-12-05 11:19:06.039081 | 
2025-12-05 11:19:06.039194 | TASK [Install binary dependencies]
2025-12-05 11:19:06.078601 | controller | ok
2025-12-05 11:19:06.097196 | 
2025-12-05 11:19:06.097273 | TASK [bindep : Include find tasks]
2025-12-05 11:19:06.125967 | controller | ok
2025-12-05 11:19:06.133209 | controller | included: /var/lib/zuul/builds/1991e80b3aa547df9d0b889f87e9cb7d/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-12-05 11:19:06.139180 | 
2025-12-05 11:19:06.139253 | TASK [bindep : Look for bindep.txt]
2025-12-05 11:19:06.543704 | controller | ok
2025-12-05 11:19:06.557367 | 
2025-12-05 11:19:06.557509 | TASK [bindep : Define bindep_file fact]
2025-12-05 11:19:06.614611 | controller | ok
2025-12-05 11:19:06.626234 | 
2025-12-05 11:19:06.626321 | TASK [bindep : Look for other-requirements.txt]
2025-12-05 11:19:06.650512 | controller | skipping: Conditional result was False
2025-12-05 11:19:06.658103 | 
2025-12-05 11:19:06.658197 | TASK [bindep : Define bindep_file fact]
2025-12-05 11:19:06.692175 | controller | skipping: Conditional result was False
2025-12-05 11:19:06.700342 | 
2025-12-05 11:19:06.700486 | TASK [bindep : Look for bindep fallback file]
2025-12-05 11:19:06.728208 | controller | skipping: Conditional result was False
2025-12-05 11:19:06.738721 | 
2025-12-05 11:19:06.740978 | TASK [bindep : Define bindep_file fact]
2025-12-05 11:19:06.769861 | controller | skipping: Conditional result was False
2025-12-05 11:19:06.776049 | 
2025-12-05 11:19:06.776126 | TASK [bindep : Include bindep tasks]
2025-12-05 11:19:06.806046 | controller | ok
2025-12-05 11:19:06.813312 | controller | included: /var/lib/zuul/builds/1991e80b3aa547df9d0b889f87e9cb7d/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml
2025-12-05 11:19:06.821594 | 
2025-12-05 11:19:06.821662 | TASK [bindep : Look for bindep command]
2025-12-05 11:19:06.845547 | controller | skipping: Conditional result was False
2025-12-05 11:19:06.852215 | 
2025-12-05 11:19:06.852294 | TASK [bindep : Check for system bindep]
2025-12-05 11:19:07.383248 | controller | ok: Runtime: 0:00:00.008434
2025-12-05 11:19:07.396367 | 
2025-12-05 11:19:07.396553 | TASK [bindep : Define bindep_command fact]
2025-12-05 11:19:07.433827 | controller | skipping: Conditional result was False
2025-12-05 11:19:07.442155 | 
2025-12-05 11:19:07.442247 | TASK [bindep : Include install tasks]
2025-12-05 11:19:07.495655 | controller | ok
2025-12-05 11:19:07.509022 | controller | included: /var/lib/zuul/builds/1991e80b3aa547df9d0b889f87e9cb7d/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml
2025-12-05 11:19:07.522538 | 
2025-12-05 11:19:07.522671 | TASK [bindep : Create temp dir for bindep]
2025-12-05 11:19:07.887433 | controller | changed
2025-12-05 11:19:07.895028 | 
2025-12-05 11:19:07.895097 | TASK [Ensure we have pip dependencies]
2025-12-05 11:19:07.914871 | controller | ok
2025-12-05 11:19:07.938349 | 
2025-12-05 11:19:07.938426 | TASK [ensure-pip : Check if pip is installed]
2025-12-05 06:19:08.207627 | controller | /usr/bin/pip3
2025-12-05 06:19:08.230262 | controller | /usr/bin/python3: No module named wheel
2025-12-05 11:19:08.471080 | controller | ok: Runtime: 0:00:00.033451
2025-12-05 11:19:08.476649 | 
2025-12-05 11:19:08.476713 | LOOP [ensure-pip : Install pip from packages]
2025-12-05 11:19:08.514776 | controller | ok: "/var/lib/zuul/builds/1991e80b3aa547df9d0b889f87e9cb7d/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml"
2025-12-05 11:19:08.529502 | controller | included: /var/lib/zuul/builds/1991e80b3aa547df9d0b889f87e9cb7d/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml
2025-12-05 11:19:08.552138 | 
2025-12-05 11:19:08.552264 | TASK [ensure-pip : Install Python 3 pip]
2025-12-05 11:19:10.740941 | controller | changed
2025-12-05 11:19:10.747280 | 
2025-12-05 11:19:10.747338 | TASK [ensure-pip : Check for EPEL repository]
2025-12-05 11:19:10.791908 | controller | skipping: Conditional result was False
2025-12-05 11:19:10.798711 | 
2025-12-05 11:19:10.798842 | TASK [ensure-pip : Fail with instructions when EPEL is not installed]
2025-12-05 11:19:10.845931 | controller | skipping: Conditional result was False
2025-12-05 11:19:10.851978 | 
2025-12-05 11:19:10.852040 | TASK [ensure-pip : Install Python 2 pip]
2025-12-05 11:19:10.885925 | controller | skipping: Conditional result was False
2025-12-05 11:19:10.891866 | 
2025-12-05 11:19:10.891931 | TASK [ensure-pip : Ensure setuptools]
2025-12-05 11:19:10.908877 | controller | skipping: Conditional result was False
2025-12-05 11:19:10.914373 | 
2025-12-05 11:19:10.914435 | TASK [ensure-pip : Check for ensurepip module]
2025-12-05 11:19:11.443325 | controller | skipping: Conditional result was False
2025-12-05 11:19:11.457973 | 
2025-12-05 11:19:11.458143 | TASK [ensure-pip : Ensure python3-venv]
2025-12-05 11:19:11.496766 | controller | skipping: Conditional result was False
2025-12-05 11:19:11.524326 | 
2025-12-05 11:19:11.524515 | TASK [ensure-pip : Install pip from source]
2025-12-05 11:19:11.553688 | controller | skipping: Conditional result was False
2025-12-05 11:19:11.567033 | 
2025-12-05 11:19:11.567177 | TASK [ensure-pip : Probe for venv python full path]
2025-12-05 06:19:11.859259 | controller | /usr/bin/python3
2025-12-05 11:19:12.109229 | controller | ok: Runtime: 0:00:00.005178
2025-12-05 11:19:12.115117 | 
2025-12-05 11:19:12.115181 | TASK [ensure-pip : Set host default]
2025-12-05 11:19:12.164852 | controller | ok
2025-12-05 11:19:12.169968 | 
2025-12-05 11:19:12.170028 | TASK [ensure-pip : Set ensure_pip_virtualenv_command]
2025-12-05 11:19:12.220665 | controller | ok
2025-12-05 11:19:12.231106 | 
2025-12-05 11:19:12.231171 | TASK [bindep : Install bindep into temporary venv]
2025-12-05 11:19:16.532715 | controller | changed
2025-12-05 11:19:16.547227 | 
2025-12-05 11:19:16.547427 | TASK [bindep : Define bindep_command]
2025-12-05 11:19:16.596817 | controller | ok
2025-12-05 11:19:16.609266 | 
2025-12-05 11:19:16.609420 | LOOP [bindep : Include package tasks]
2025-12-05 11:19:16.680486 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt"
2025-12-05 11:19:16.680663 | controller | ok: All items complete
2025-12-05 11:19:16.680691 | 
2025-12-05 11:19:16.725656 | controller | included: /var/lib/zuul/builds/1991e80b3aa547df9d0b889f87e9cb7d/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml
2025-12-05 11:19:16.744083 | 
2025-12-05 11:19:16.744250 | TASK [bindep : Define bindep_run fact]
2025-12-05 11:19:16.789840 | controller | ok
2025-12-05 11:19:16.800593 | 
2025-12-05 11:19:16.800761 | TASK [bindep : Get list of packages to install from bindep]
2025-12-05 06:19:18.174401 | controller | podman
2025-12-05 06:19:18.208774 | controller | python3-jmespath
2025-12-05 06:19:18.208954 | controller | python3-libvirt
2025-12-05 06:19:18.208969 | controller | python3-lxml
2025-12-05 06:19:18.208982 | controller | python3-netaddr
2025-12-05 11:19:18.344716 | controller | ok: Runtime: 0:00:01.107935
2025-12-05 11:19:18.357096 | 
2025-12-05 11:19:18.357234 | TASK [bindep : Install distro packages from bindep]
2025-12-05 11:20:24.599948 | controller | changed
2025-12-05 11:20:24.605442 | 
2025-12-05 11:20:24.605505 | TASK [bindep : Check that packages are installed]
2025-12-05 11:20:26.660122 | controller | ok: Runtime: 0:00:01.437692
2025-12-05 11:20:26.672251 | 
2025-12-05 11:20:26.672404 | TASK [bindep : Fail if we cannot install all packages]
2025-12-05 11:20:26.709781 | controller | skipping: Conditional result was False
2025-12-05 11:20:26.733328 | 
2025-12-05 11:20:26.733459 | TASK [Run test-setup role]
2025-12-05 11:20:26.761936 | controller | ok
2025-12-05 11:20:26.789970 | 
2025-12-05 11:20:26.790051 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-12-05 11:20:27.060273 | controller | ok
2025-12-05 11:20:27.072095 | 
2025-12-05 11:20:27.072233 | TASK [test-setup : Run tools/test-setup.sh]
2025-12-05 11:20:27.631678 | controller | skipping: Conditional result was False
2025-12-05 11:20:27.674104 | 
2025-12-05 11:20:27.674248 | TASK [bindep : Remove bindep temp dir]
2025-12-05 11:20:28.096906 | controller | ok
2025-12-05 11:20:28.118636 | 
2025-12-05 11:20:28.118792 | PLAY RECAP
2025-12-05 11:20:28.118884 | controller | ok: 22 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0
2025-12-05 11:20:28.118928 | 
2025-12-05 11:20:28.251106 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-05 11:20:28.252815 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2025-12-05 11:20:28.890508 | 
2025-12-05 11:20:28.890628 | PLAY [all]
2025-12-05 11:20:28.910440 | 
2025-12-05 11:20:28.910526 | TASK [Abort when test_command variable is undefined]
2025-12-05 11:20:28.944747 | controller | skipping: Conditional result was False
2025-12-05 11:20:28.950806 | 
2025-12-05 11:20:28.950879 | TASK [Convert test_command to list]
2025-12-05 11:20:28.995416 | controller | skipping: Conditional result was False
2025-12-05 11:20:29.008692 | 
2025-12-05 11:20:29.008864 | TASK [Use test_command list]
2025-12-05 11:20:29.068757 | controller | ok
2025-12-05 11:20:29.080562 | 
2025-12-05 11:20:29.080689 | LOOP [Run test_command]
2025-12-05 11:20:29.476563 | controller | no check to run
2025-12-05 11:20:29.476927 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.004180
2025-12-05 11:20:29.521216 | 
2025-12-05 11:20:29.521369 | PLAY RECAP
2025-12-05 11:20:29.521445 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-05 11:20:29.521505 | 
2025-12-05 11:20:29.626912 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2025-12-05 11:20:29.629132 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-05 11:20:30.242622 | 
2025-12-05 11:20:30.242750 | PLAY [all]
2025-12-05 11:20:30.262822 | 
2025-12-05 11:20:30.262916 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-12-05 11:20:30.668149 | controller | changed: non-zero return code
2025-12-05 11:20:30.680482 | 
2025-12-05 11:20:30.680649 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-12-05 11:20:30.709135 | controller | skipping: Conditional result was False
2025-12-05 11:20:30.721286 | 
2025-12-05 11:20:30.721431 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-12-05 11:20:30.762648 | 
2025-12-05 11:20:30.762935 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-12-05 11:20:30.802452 | 
2025-12-05 11:20:30.802712 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-12-05 11:20:30.830240 | controller | skipping: Conditional result was False
2025-12-05 11:20:30.842805 | 
2025-12-05 11:20:30.842949 | LOOP [fetch-subunit-output : Generate subunit file]
2025-12-05 11:20:30.885190 | 
2025-12-05 11:20:30.885449 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-12-05 11:20:30.912439 | controller | skipping: Conditional result was False
2025-12-05 11:20:30.924254 | 
2025-12-05 11:20:30.924396 | TASK [fetch-subunit-output : Remove the temporary file]
2025-12-05 11:20:30.951435 | controller | skipping: Conditional result was False
2025-12-05 11:20:30.964097 | 
2025-12-05 11:20:30.964244 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-12-05 11:20:30.992171 | controller | skipping: Conditional result was False
2025-12-05 11:20:31.037634 | 
2025-12-05 11:20:31.037761 | PLAY RECAP
2025-12-05 11:20:31.037820 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-05 11:20:31.037847 | 
2025-12-05 11:20:31.132860 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-05 11:20:31.133637 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 11:20:31.688466 | 
2025-12-05 11:20:31.688570 | PLAY [all]
2025-12-05 11:20:31.707783 | 
2025-12-05 11:20:31.707876 | TASK [include_role : fetch-output]
2025-12-05 11:20:31.746528 | controller | ok
2025-12-05 11:20:31.763913 | 
2025-12-05 11:20:31.763993 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-05 11:20:31.828554 | controller | skipping: Conditional result was False
2025-12-05 11:20:31.836429 | 
2025-12-05 11:20:31.836520 | TASK [fetch-output : Set log path for single node]
2025-12-05 11:20:31.877842 | controller | ok
2025-12-05 11:20:31.884853 | 
2025-12-05 11:20:31.884939 | LOOP [fetch-output : Ensure local output dirs]
2025-12-05 11:20:32.304360 | controller -> localhost | ok: "/var/lib/zuul/builds/1991e80b3aa547df9d0b889f87e9cb7d/work/logs"
2025-12-05 11:20:32.537920 | controller -> localhost | changed: "/var/lib/zuul/builds/1991e80b3aa547df9d0b889f87e9cb7d/work/artifacts"
2025-12-05 11:20:32.803784 | controller -> localhost | changed: "/var/lib/zuul/builds/1991e80b3aa547df9d0b889f87e9cb7d/work/docs"
2025-12-05 11:20:32.813721 | 
2025-12-05 11:20:32.813869 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-05 11:20:33.543206 | controller | changed: .d..t...... ./
2025-12-05 11:20:33.543435 | controller | changed: All items complete
2025-12-05 11:20:33.543472 | 
2025-12-05 11:20:34.071250 | controller | changed: .d..t...... ./
2025-12-05 11:20:34.532363 | controller | changed: .d..t...... ./
2025-12-05 11:20:34.562292 | 
2025-12-05 11:20:34.562505 | TASK [include_role : fetch-output-openshift]
2025-12-05 11:20:34.589805 | controller | skipping: Conditional result was False
2025-12-05 11:20:34.604227 | 
2025-12-05 11:20:34.604398 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-05 11:20:35.062306 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.013745
2025-12-05 11:20:35.361636 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.013771
2025-12-05 11:20:35.397970 | 
2025-12-05 11:20:35.398070 | PLAY [all]
2025-12-05 11:20:35.413073 | 
2025-12-05 11:20:35.413136 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-05 11:20:35.902589 | controller | changed
2025-12-05 11:20:35.948211 | 
2025-12-05 11:20:35.948332 | PLAY RECAP
2025-12-05 11:20:35.948388 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-05 11:20:35.948417 | 
2025-12-05 11:20:36.041722 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 11:20:36.043373 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-05 11:20:36.695428 | 
2025-12-05 11:20:36.695536 | PLAY [localhost]
2025-12-05 11:20:36.715038 | 
2025-12-05 11:20:36.715158 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-05 11:20:37.149268 | localhost | changed
2025-12-05 11:20:37.159816 | 
2025-12-05 11:20:37.160010 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-05 11:20:37.192447 | localhost | ok
2025-12-05 11:20:37.204380 | 
2025-12-05 11:20:37.204516 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-05 11:20:37.598692 | localhost | changed
2025-12-05 11:20:37.612172 | 
2025-12-05 11:20:37.612326 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-05 11:20:38.368975 | localhost | changed
2025-12-05 11:20:38.380548 | 
2025-12-05 11:20:38.380680 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-05 11:20:38.825843 | localhost | Identity added: /var/lib/zuul/builds/1991e80b3aa547df9d0b889f87e9cb7d/work/tmp/ansible.hltsz30i (/var/lib/zuul/builds/1991e80b3aa547df9d0b889f87e9cb7d/work/tmp/ansible.hltsz30i)
2025-12-05 11:20:38.826171 | localhost | ok: Runtime: 0:00:00.013322
2025-12-05 11:20:38.836094 | 
2025-12-05 11:20:38.836232 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-05 11:20:39.109484 | localhost | ok: Runtime: 0:00:00.008021
2025-12-05 11:20:39.120688 | 
2025-12-05 11:20:39.120865 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-05 11:20:39.178522 | localhost | changed
2025-12-05 11:20:39.188431 | 
2025-12-05 11:20:39.188569 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-05 11:20:39.592828 | localhost | changed
2025-12-05 11:20:39.635705 | 
2025-12-05 11:20:39.635842 | PLAY [localhost]
2025-12-05 11:20:39.653223 | 
2025-12-05 11:20:39.653303 | TASK [Generate bulk log download script]
2025-12-05 11:20:39.673879 | localhost | ok
2025-12-05 11:20:39.689956 | 
2025-12-05 11:20:39.690042 | TASK [local-log-download : Check API endpoint is defined]
2025-12-05 11:20:39.730104 | localhost | ok: All assertions passed
2025-12-05 11:20:39.736437 | 
2025-12-05 11:20:39.736518 | TASK [local-log-download : Create download script]
2025-12-05 11:20:40.152517 | localhost -> localhost | changed
2025-12-05 11:20:40.173039 | 
2025-12-05 11:20:40.173173 | TASK [Register quick-download link]
2025-12-05 11:20:40.209217 | localhost | ok
2025-12-05 11:20:40.268501 | 
2025-12-05 11:20:40.268661 | PLAY [logserver.rdoproject.org]
2025-12-05 11:20:40.277719 | 
2025-12-05 11:20:40.277795 | TASK [Set zuul-log-path fact]
2025-12-05 11:20:40.294050 | logserver.rdoproject.org | ok
2025-12-05 11:20:40.303040 | 
2025-12-05 11:20:40.303104 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 11:20:40.329269 | logserver.rdoproject.org | ok
2025-12-05 11:20:40.334633 | 
2025-12-05 11:20:40.334690 | TASK [upload-logs : Create log directories]
2025-12-05 11:20:41.148521 | logserver.rdoproject.org | changed
2025-12-05 11:20:41.155996 | 
2025-12-05 11:20:41.156128 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-05 11:20:41.481989 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.008312
2025-12-05 11:20:41.492144 | 
2025-12-05 11:20:41.492275 | TASK [upload-logs : Upload logs to log server]
2025-12-05 11:20:42.185150 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-05 11:20:42.188572 | 
2025-12-05 11:20:42.188632 | LOOP [upload-logs : Compress console log and json output]
2025-12-05 11:20:42.261098 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 11:20:42.271128 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 11:20:42.281334 | 
2025-12-05 11:20:42.281401 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-05 11:20:42.346468 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 11:20:42.346992 | 
2025-12-05 11:20:42.350806 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 11:20:42.362058 | 
2025-12-05 11:20:42.362139 | LOOP [upload-logs : Upload console log and json output]