2025-10-03 13:23:39.619011 | Job console starting...
2025-10-03 13:23:39.628552 | Updating repositories
2025-10-03 13:23:39.659720 | Preparing job workspace
2025-10-03 13:23:43.766306 | Running Ansible setup...
2025-10-03 13:23:47.820303 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-03 13:23:48.472855 | 
2025-10-03 13:23:48.472990 | PLAY [localhost]
2025-10-03 13:23:48.483331 | 
2025-10-03 13:23:48.483456 | TASK [Gathering Facts]
2025-10-03 13:23:49.550714 | localhost | ok
2025-10-03 13:23:49.581109 | 
2025-10-03 13:23:49.581279 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-03 13:23:50.003987 | localhost -> localhost | changed
2025-10-03 13:23:50.035646 | 
2025-10-03 13:23:50.035898 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-03 13:23:50.934532 | localhost -> localhost | changed
2025-10-03 13:23:50.946174 | 
2025-10-03 13:23:50.946294 | TASK [Setup log path fact]
2025-10-03 13:23:50.967093 | localhost | ok
2025-10-03 13:23:50.983076 | 
2025-10-03 13:23:50.983213 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-03 13:23:51.023158 | localhost | ok
2025-10-03 13:23:51.046804 | 
2025-10-03 13:23:51.046939 | TASK [emit-job-header : Print job information]
2025-10-03 13:23:51.090076 | # Job Information
2025-10-03 13:23:51.090230 | Ansible Version: 2.15.12
2025-10-03 13:23:51.090257 | Job: cifmw-molecule-switch_config
2025-10-03 13:23:51.090277 | Pipeline: github-check
2025-10-03 13:23:51.090295 | Executor: ze04.softwarefactory-project.io
2025-10-03 13:23:51.090313 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3362
2025-10-03 13:23:51.090332 | Log URL (when completed): https://logserver.rdoproject.org/e98/rdoproject.org/e98e7174e7b7486388dd1c3bc45194f1/
2025-10-03 13:23:51.090351 | Event ID: f3678040-a05b-11f0-90a2-408975ecafa3
2025-10-03 13:23:51.095197 | 
2025-10-03 13:23:51.095288 | LOOP [emit-job-header : Print node information]
2025-10-03 13:23:51.282738 | localhost | ok:
2025-10-03 13:23:51.282945 | localhost | # Node Information
2025-10-03 13:23:51.282989 | localhost | Inventory Hostname: controller
2025-10-03 13:23:51.283027 | localhost | Hostname: np0005469353
2025-10-03 13:23:51.283059 | localhost | Username: zuul
2025-10-03 13:23:51.283087 | localhost | Distro: CentOS 9
2025-10-03 13:23:51.283110 | localhost | Provider: vexxhost-nodepool-tripleo
2025-10-03 13:23:51.283131 | localhost | Region: RegionOne
2025-10-03 13:23:51.283150 | localhost | Label: cloud-centos-9-stream-tripleo
2025-10-03 13:23:51.283169 | localhost | Product Name: OpenStack Nova
2025-10-03 13:23:51.283188 | localhost | Interface IP: 38.129.56.161
2025-10-03 13:23:51.310285 | 
2025-10-03 13:23:51.310404 | PLAY [all]
2025-10-03 13:23:51.317258 | 
2025-10-03 13:23:51.317323 | TASK [Gather network facts]
2025-10-03 13:23:51.793862 | controller | ok
2025-10-03 13:23:51.810863 | 
2025-10-03 13:23:51.810950 | TASK [include_role : start-zuul-console]
2025-10-03 13:23:51.831647 | controller | ok
2025-10-03 13:23:51.843542 | 
2025-10-03 13:23:51.843628 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-03 13:23:52.278100 | controller | ok
2025-10-03 13:23:52.295343 | 
2025-10-03 13:23:52.295473 | TASK [include_role : add-build-sshkey]
2025-10-03 13:23:52.348023 | controller | ok
2025-10-03 13:23:52.366608 | 
2025-10-03 13:23:52.366780 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-03 13:23:52.667123 | controller -> localhost | ok
2025-10-03 13:23:52.672850 | 
2025-10-03 13:23:52.672966 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-03 13:23:52.703394 | controller | ok
2025-10-03 13:23:52.718819 | controller | included: /var/lib/zuul/builds/e98e7174e7b7486388dd1c3bc45194f1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-03 13:23:52.725118 | 
2025-10-03 13:23:52.725371 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-03 13:23:53.312482 | controller -> localhost | Generating public/private rsa key pair.
2025-10-03 13:23:53.312664 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/e98e7174e7b7486388dd1c3bc45194f1/work/e98e7174e7b7486388dd1c3bc45194f1_id_rsa.
2025-10-03 13:23:53.312719 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/e98e7174e7b7486388dd1c3bc45194f1/work/e98e7174e7b7486388dd1c3bc45194f1_id_rsa.pub.
2025-10-03 13:23:53.312748 | controller -> localhost | The key fingerprint is:
2025-10-03 13:23:53.312776 | controller -> localhost | SHA256:xRC5VJkEQs/6Nx86goQoYfi2eooUdsyncmbiCqVPjcE zuul-build-sshkey
2025-10-03 13:23:53.312798 | controller -> localhost | The key's randomart image is:
2025-10-03 13:23:53.312825 | controller -> localhost | +---[RSA 3072]----+
2025-10-03 13:23:53.312848 | controller -> localhost | |     .o +*oo     |
2025-10-03 13:23:53.312868 | controller -> localhost | |       +ooo      |
2025-10-03 13:23:53.312885 | controller -> localhost | |.      .o.o      |
2025-10-03 13:23:53.312903 | controller -> localhost | |ooo    ...       |
2025-10-03 13:23:53.312920 | controller -> localhost | |.=E= o. S        |
2025-10-03 13:23:53.312937 | controller -> localhost | |oo*++ ..         |
2025-10-03 13:23:53.312953 | controller -> localhost | |o*o*.. .. o .    |
2025-10-03 13:23:53.312973 | controller -> localhost | |=oO   . ...+ .   |
2025-10-03 13:23:53.312990 | controller -> localhost | |*=.      ....    |
2025-10-03 13:23:53.313007 | controller -> localhost | +----[SHA256]-----+
2025-10-03 13:23:53.313048 | controller -> localhost | ok: Runtime: 0:00:00.068517
2025-10-03 13:23:53.319103 | 
2025-10-03 13:23:53.319169 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-03 13:23:53.353057 | controller | ok
2025-10-03 13:23:53.364881 | controller | included: /var/lib/zuul/builds/e98e7174e7b7486388dd1c3bc45194f1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-03 13:23:53.373501 | 
2025-10-03 13:23:53.373610 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-03 13:23:53.388107 | controller | skipping: Conditional result was False
2025-10-03 13:23:53.396604 | 
2025-10-03 13:23:53.396772 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-03 13:23:53.951787 | controller | changed
2025-10-03 13:23:53.962011 | 
2025-10-03 13:23:53.962121 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-03 13:23:54.204958 | controller | ok
2025-10-03 13:23:54.210233 | 
2025-10-03 13:23:54.210299 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-03 13:23:55.071545 | controller | changed
2025-10-03 13:23:55.078586 | 
2025-10-03 13:23:55.078748 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-03 13:23:56.064298 | controller | changed
2025-10-03 13:23:56.072390 | 
2025-10-03 13:23:56.072731 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-03 13:23:56.108866 | controller | skipping: Conditional result was False
2025-10-03 13:23:56.117013 | 
2025-10-03 13:23:56.117145 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-03 13:23:56.589155 | controller -> localhost | changed
2025-10-03 13:23:56.604269 | 
2025-10-03 13:23:56.604383 | TASK [add-build-sshkey : Add back temp key]
2025-10-03 13:23:57.031225 | controller -> localhost | Identity added: /var/lib/zuul/builds/e98e7174e7b7486388dd1c3bc45194f1/work/e98e7174e7b7486388dd1c3bc45194f1_id_rsa (zuul-build-sshkey)
2025-10-03 13:23:57.031409 | controller -> localhost | ok: Runtime: 0:00:00.012743
2025-10-03 13:23:57.038220 | 
2025-10-03 13:23:57.038318 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-03 13:23:57.436770 | controller | ok
2025-10-03 13:23:57.446358 | 
2025-10-03 13:23:57.446470 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-03 13:23:57.473436 | controller | skipping: Conditional result was False
2025-10-03 13:23:57.485744 | 
2025-10-03 13:23:57.485834 | TASK [include_role : validate-host]
2025-10-03 13:23:57.509006 | controller | ok
2025-10-03 13:23:57.537240 | 
2025-10-03 13:23:57.537332 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-03 13:23:57.576407 | controller | ok
2025-10-03 13:23:57.581422 | 
2025-10-03 13:23:57.581482 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-03 13:23:57.943664 | controller -> localhost | ok
2025-10-03 13:23:57.952152 | 
2025-10-03 13:23:57.952248 | TASK [validate-host : Collect information about the host]
2025-10-03 13:23:58.753229 | controller | ok
2025-10-03 13:23:58.766516 | 
2025-10-03 13:23:58.766619 | TASK [validate-host : Sanitize hostname]
2025-10-03 13:23:58.817081 | controller | ok
2025-10-03 13:23:58.822092 | 
2025-10-03 13:23:58.822166 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-03 13:23:59.305466 | controller -> localhost | changed
2025-10-03 13:23:59.311755 | 
2025-10-03 13:23:59.311855 | TASK [validate-host : Collect information about zuul worker]
2025-10-03 13:23:59.907911 | controller | ok
2025-10-03 13:23:59.913151 | 
2025-10-03 13:23:59.913259 | TASK [validate-host : Write out all zuul information for each host]
2025-10-03 13:24:00.452496 | controller -> localhost | changed
2025-10-03 13:24:00.463319 | 
2025-10-03 13:24:00.463437 | TASK [include_role : prepare-workspace-openshift]
2025-10-03 13:24:00.478425 | controller | skipping: Conditional result was False
2025-10-03 13:24:00.483732 | 
2025-10-03 13:24:00.483828 | TASK [include_role : remove-zuul-sshkey]
2025-10-03 13:24:00.507872 | controller | skipping: Conditional result was False
2025-10-03 13:24:00.513649 | 
2025-10-03 13:24:00.513787 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-03 13:24:00.820756 | controller | ok: "logs"
2025-10-03 13:24:00.821064 | controller | ok: All items complete
2025-10-03 13:24:00.821105 | 
2025-10-03 13:24:01.044128 | controller | ok: "artifacts"
2025-10-03 13:24:01.289772 | controller | ok: "docs"
2025-10-03 13:24:01.304280 | 
2025-10-03 13:24:01.304420 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-03 13:24:01.705795 | controller | changed: "logs"
2025-10-03 13:24:01.976965 | controller | changed: "artifacts"
2025-10-03 13:24:02.254441 | controller | changed: "docs"
2025-10-03 13:24:02.336515 | 
2025-10-03 13:24:02.336618 | PLAY RECAP
2025-10-03 13:24:02.336662 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-03 13:24:02.336710 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-03 13:24:02.336731 | 
2025-10-03 13:24:02.602316 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-03 13:24:02.603838 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-10-03 13:24:05.393753 | 
2025-10-03 13:24:05.393904 | PLAY [localhost]
2025-10-03 13:24:05.414568 | 
2025-10-03 13:24:05.414768 | TASK [configure-ssh-config-fips : Ensure ssh config directory]
2025-10-03 13:24:05.961437 | localhost | ok
2025-10-03 13:24:05.972835 | 
2025-10-03 13:24:05.972994 | TASK [configure-ssh-config-fips : Setup ssh config]
2025-10-03 13:24:06.845253 | localhost | changed
2025-10-03 13:24:06.882530 | 
2025-10-03 13:24:06.882648 | PLAY [all]
2025-10-03 13:24:06.905630 | 
2025-10-03 13:24:06.905796 | TASK [include_role : prepare-workspace]
2025-10-03 13:24:06.944338 | controller | ok
2025-10-03 13:24:06.961914 | 
2025-10-03 13:24:06.962028 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-03 13:24:07.409532 | controller | ok
2025-10-03 13:24:07.418721 | 
2025-10-03 13:24:07.418840 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-03 13:24:09.807970 | controller | Output suppressed because no_log was given
2025-10-03 13:24:09.830757 | 
2025-10-03 13:24:09.830945 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-03 13:24:10.080665 | controller | changed: "logs"
2025-10-03 13:24:10.311776 | controller | changed: "artifacts"
2025-10-03 13:24:10.518404 | controller | changed: "docs"
2025-10-03 13:24:10.528132 | 
2025-10-03 13:24:10.528255 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-03 13:24:10.823259 | controller | changed: "logs"
2025-10-03 13:24:10.823488 | controller | changed: All items complete
2025-10-03 13:24:10.823516 | 
2025-10-03 13:24:11.034531 | controller | changed: "artifacts"
2025-10-03 13:24:11.247271 | controller | changed: "docs"
2025-10-03 13:24:11.262313 | 
2025-10-03 13:24:11.262400 | TASK [Check if worker can sudo]
2025-10-03 13:24:11.830224 | controller | ok: Runtime: 0:00:00.043580
2025-10-03 13:24:11.836057 | 
2025-10-03 13:24:11.836128 | TASK [configure-mirrors : Gather needed facts]
2025-10-03 13:24:11.903340 | controller | skipping: Conditional result was False
2025-10-03 13:24:11.910217 | 
2025-10-03 13:24:11.910438 | TASK [configure-mirrors : Set up infrastructure mirrors]
2025-10-03 13:24:12.082862 | controller | ok
2025-10-03 13:24:12.129176 | controller | included: /var/lib/zuul/builds/e98e7174e7b7486388dd1c3bc45194f1/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml
2025-10-03 13:24:12.135229 | 
2025-10-03 13:24:12.135296 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir]
2025-10-03 13:24:12.469978 | controller | ok
2025-10-03 13:24:12.483633 | 
2025-10-03 13:24:12.484133 | LOOP [configure-mirrors : Include OS-specific variables]
2025-10-03 13:24:12.623271 | controller | ok: "/var/lib/zuul/builds/e98e7174e7b7486388dd1c3bc45194f1/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml"
2025-10-03 13:24:12.645278 | 
2025-10-03 13:24:12.645410 | TASK [configure-mirrors : Install /etc/pip.conf configuration]
2025-10-03 13:24:13.667489 | controller | changed
2025-10-03 13:24:13.676973 | 
2025-10-03 13:24:13.677065 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors]
2025-10-03 13:24:13.751028 | controller | ok: "/var/lib/zuul/builds/e98e7174e7b7486388dd1c3bc45194f1/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml"
2025-10-03 13:24:13.751275 | controller | ok: All items complete
2025-10-03 13:24:13.751325 | 
2025-10-03 13:24:13.802786 | controller | included: /var/lib/zuul/builds/e98e7174e7b7486388dd1c3bc45194f1/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml
2025-10-03 13:24:13.810458 | 
2025-10-03 13:24:13.810547 | LOOP [configure-mirrors : Install CentOS 9 repository files]
2025-10-03 13:24:14.690085 | controller | changed: "etc/yum.repos.d/centos.repo"
2025-10-03 13:24:15.650712 | controller | changed: "etc/yum.repos.d/centos-addons.repo"
2025-10-03 13:24:15.671028 | 
2025-10-03 13:24:15.671188 | TASK [configure-mirrors : Disable deltrarpm]
2025-10-03 13:24:16.272911 | controller | changed: section and option added
2025-10-03 13:24:16.321160 | 
2025-10-03 13:24:16.321277 | LOOP [configure-mirrors : Update yum/dnf cache]
2025-10-03 13:24:17.140263 | controller | 29 files removed
2025-10-03 13:24:17.140471 | controller | ok: Item: dnf clean all Runtime: 0:00:00.467974
2025-10-03 13:24:17.140511 | controller | changed: All items complete
2025-10-03 13:24:17.140533 | 
2025-10-03 13:24:28.310656 | controller | Loaded plugins: builddep, changelog, config-manager, copr, debug, debuginfo-install, download, generate_completion_cache, groups-manager, needs-restarting, notify-packagekit, playground, repoclosure, repodiff, repograph, repomanage, reposync, system-upgrade
2025-10-03 13:24:28.310783 | controller | DNF version: 4.14.0
2025-10-03 13:24:28.310808 | controller | cachedir: /var/cache/dnf
2025-10-03 13:24:28.310828 | controller | Making cache files for all metadata files.
2025-10-03 13:24:28.310848 | controller | baseos: has expired and will be refreshed.
2025-10-03 13:24:28.310873 | controller | appstream: has expired and will be refreshed.
2025-10-03 13:24:28.310899 | controller | crb: has expired and will be refreshed.
2025-10-03 13:24:28.310936 | controller | extras-common: has expired and will be refreshed.
2025-10-03 13:24:28.310964 | controller | repo: downloading from remote: baseos
2025-10-03 13:24:28.310990 | controller | CentOS Stream 9 - BaseOS                         67 MB/s | 8.8 MB     00:00
2025-10-03 13:24:28.311017 | controller | baseos: using metadata from Tue 30 Sep 2025 01:12:56 PM EDT.
2025-10-03 13:24:28.311046 | controller | repo: downloading from remote: appstream
2025-10-03 13:24:28.311073 | controller | CentOS Stream 9 - AppStream                      58 MB/s |  25 MB     00:00
2025-10-03 13:24:28.311100 | controller | appstream: using metadata from Tue 30 Sep 2025 01:16:29 PM EDT.
2025-10-03 13:24:28.311127 | controller | repo: downloading from remote: crb
2025-10-03 13:24:28.311150 | controller | CentOS Stream 9 - CRB                            64 MB/s | 7.1 MB     00:00
2025-10-03 13:24:28.311167 | controller | crb: using metadata from Tue 30 Sep 2025 01:20:54 PM EDT.
2025-10-03 13:24:28.311184 | controller | repo: downloading from remote: extras-common
2025-10-03 13:24:28.311201 | controller | CentOS Stream 9 - Extras packages               1.5 MB/s |  20 kB     00:00
2025-10-03 13:24:28.311217 | controller | extras-common: using metadata from Mon 15 Sep 2025 02:32:43 PM EDT.
2025-10-03 13:24:28.311233 | controller | Last metadata expiration check: 0:00:01 ago on Fri 03 Oct 2025 09:24:26 AM EDT.
2025-10-03 13:24:28.311249 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)'
2025-10-03 13:24:28.311265 | controller | Completion plugin: Generating completion cache...
2025-10-03 13:24:28.311281 | controller | Metadata cache created.
2025-10-03 13:24:28.311308 | controller | ok: Item: dnf makecache -v Runtime: 0:00:10.915883
2025-10-03 13:24:28.336822 | 
2025-10-03 13:24:28.336925 | PLAY RECAP
2025-10-03 13:24:28.336968 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-03 13:24:28.336995 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-03 13:24:28.337013 | 
2025-10-03 13:24:28.470534 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-10-03 13:24:28.471404 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-10-03 13:24:29.096209 | 
2025-10-03 13:24:29.096349 | PLAY [all]
2025-10-03 13:24:29.122351 | 
2025-10-03 13:24:29.122471 | TASK [Install binary dependencies]
2025-10-03 13:24:29.185115 | controller | ok
2025-10-03 13:24:29.213329 | 
2025-10-03 13:24:29.213553 | TASK [bindep : Include find tasks]
2025-10-03 13:24:29.255484 | controller | ok
2025-10-03 13:24:29.263192 | controller | included: /var/lib/zuul/builds/e98e7174e7b7486388dd1c3bc45194f1/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-10-03 13:24:29.269551 | 
2025-10-03 13:24:29.269618 | TASK [bindep : Look for bindep.txt]
2025-10-03 13:24:29.687110 | controller | ok
2025-10-03 13:24:29.693898 | 
2025-10-03 13:24:29.693981 | TASK [bindep : Define bindep_file fact]
2025-10-03 13:24:29.724884 | controller | ok
2025-10-03 13:24:29.731174 | 
2025-10-03 13:24:29.731264 | TASK [bindep : Look for other-requirements.txt]
2025-10-03 13:24:29.746786 | controller | skipping: Conditional result was False
2025-10-03 13:24:29.753967 | 
2025-10-03 13:24:29.754105 | TASK [bindep : Define bindep_file fact]
2025-10-03 13:24:29.783262 | controller | skipping: Conditional result was False
2025-10-03 13:24:29.792663 | 
2025-10-03 13:24:29.792838 | TASK [bindep : Look for bindep fallback file]
2025-10-03 13:24:29.818927 | controller | skipping: Conditional result was False
2025-10-03 13:24:29.827838 | 
2025-10-03 13:24:29.827980 | TASK [bindep : Define bindep_file fact]
2025-10-03 13:24:29.853309 | controller | skipping: Conditional result was False
2025-10-03 13:24:29.864076 | 
2025-10-03 13:24:29.864227 | TASK [bindep : Include bindep tasks]
2025-10-03 13:24:29.896907 | controller | ok
2025-10-03 13:24:29.904938 | controller | included: /var/lib/zuul/builds/e98e7174e7b7486388dd1c3bc45194f1/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml
2025-10-03 13:24:29.914160 | 
2025-10-03 13:24:29.914271 | TASK [bindep : Look for bindep command]
2025-10-03 13:24:29.938089 | controller | skipping: Conditional result was False
2025-10-03 13:24:29.946204 | 
2025-10-03 13:24:29.946287 | TASK [bindep : Check for system bindep]
2025-10-03 13:24:30.492288 | controller | ok: Runtime: 0:00:00.008421
2025-10-03 13:24:30.498426 | 
2025-10-03 13:24:30.498520 | TASK [bindep : Define bindep_command fact]
2025-10-03 13:24:30.537775 | controller | skipping: Conditional result was False
2025-10-03 13:24:30.554569 | 
2025-10-03 13:24:30.554779 | TASK [bindep : Include install tasks]
2025-10-03 13:24:30.597818 | controller | ok
2025-10-03 13:24:30.607520 | controller | included: /var/lib/zuul/builds/e98e7174e7b7486388dd1c3bc45194f1/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml
2025-10-03 13:24:30.617503 | 
2025-10-03 13:24:30.617591 | TASK [bindep : Create temp dir for bindep]
2025-10-03 13:24:31.011630 | controller | changed
2025-10-03 13:24:31.020126 | 
2025-10-03 13:24:31.020233 | TASK [Ensure we have pip dependencies]
2025-10-03 13:24:31.042432 | controller | ok
2025-10-03 13:24:31.077258 | 
2025-10-03 13:24:31.077382 | TASK [ensure-pip : Check if pip is installed]
2025-10-03 09:24:31.375404 | controller | /usr/bin/pip3
2025-10-03 09:24:31.398771 | controller | /usr/bin/python3: No module named wheel
2025-10-03 13:24:31.611095 | controller | ok: Runtime: 0:00:00.033580
2025-10-03 13:24:31.625228 | 
2025-10-03 13:24:31.625381 | LOOP [ensure-pip : Install pip from packages]
2025-10-03 13:24:31.688575 | controller | ok: "/var/lib/zuul/builds/e98e7174e7b7486388dd1c3bc45194f1/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml"
2025-10-03 13:24:31.714989 | controller | included: /var/lib/zuul/builds/e98e7174e7b7486388dd1c3bc45194f1/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml
2025-10-03 13:24:31.739828 | 
2025-10-03 13:24:31.739953 | TASK [ensure-pip : Install Python 3 pip]
2025-10-03 13:24:34.133161 | controller | changed
2025-10-03 13:24:34.139882 | 
2025-10-03 13:24:34.139986 | TASK [ensure-pip : Check for EPEL repository]
2025-10-03 13:24:34.206773 | controller | skipping: Conditional result was False
2025-10-03 13:24:34.213573 | 
2025-10-03 13:24:34.213668 | TASK [ensure-pip : Fail with instructions when EPEL is not installed]
2025-10-03 13:24:34.265790 | controller | skipping: Conditional result was False
2025-10-03 13:24:34.275325 | 
2025-10-03 13:24:34.275442 | TASK [ensure-pip : Install Python 2 pip]
2025-10-03 13:24:34.331400 | controller | skipping: Conditional result was False
2025-10-03 13:24:34.338306 | 
2025-10-03 13:24:34.338377 | TASK [ensure-pip : Ensure setuptools]
2025-10-03 13:24:34.352432 | controller | skipping: Conditional result was False
2025-10-03 13:24:34.359407 | 
2025-10-03 13:24:34.359481 | TASK [ensure-pip : Check for ensurepip module]
2025-10-03 13:24:34.896424 | controller | skipping: Conditional result was False
2025-10-03 13:24:34.904936 | 
2025-10-03 13:24:34.905033 | TASK [ensure-pip : Ensure python3-venv]
2025-10-03 13:24:34.930995 | controller | skipping: Conditional result was False
2025-10-03 13:24:34.945797 | 
2025-10-03 13:24:34.945943 | TASK [ensure-pip : Install pip from source]
2025-10-03 13:24:34.973007 | controller | skipping: Conditional result was False
2025-10-03 13:24:34.983022 | 
2025-10-03 13:24:34.983129 | TASK [ensure-pip : Probe for venv python full path]
2025-10-03 09:24:35.258433 | controller | /usr/bin/python3
2025-10-03 13:24:35.529294 | controller | ok: Runtime: 0:00:00.004290
2025-10-03 13:24:35.544064 | 
2025-10-03 13:24:35.544267 | TASK [ensure-pip : Set host default]
2025-10-03 13:24:35.622905 | controller | ok
2025-10-03 13:24:35.630099 | 
2025-10-03 13:24:35.630221 | TASK [ensure-pip : Set ensure_pip_virtualenv_command]
2025-10-03 13:24:35.697162 | controller | ok
2025-10-03 13:24:35.714377 | 
2025-10-03 13:24:35.714483 | TASK [bindep : Install bindep into temporary venv]
2025-10-03 13:24:40.141105 | controller | changed
2025-10-03 13:24:40.150372 | 
2025-10-03 13:24:40.150530 | TASK [bindep : Define bindep_command]
2025-10-03 13:24:40.190661 | controller | ok
2025-10-03 13:24:40.199571 | 
2025-10-03 13:24:40.199722 | LOOP [bindep : Include package tasks]
2025-10-03 13:24:40.262293 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt"
2025-10-03 13:24:40.262474 | controller | ok: All items complete
2025-10-03 13:24:40.262500 | 
2025-10-03 13:24:40.274962 | controller | included: /var/lib/zuul/builds/e98e7174e7b7486388dd1c3bc45194f1/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml
2025-10-03 13:24:40.287509 | 
2025-10-03 13:24:40.287640 | TASK [bindep : Define bindep_run fact]
2025-10-03 13:24:40.317406 | controller | ok
2025-10-03 13:24:40.323500 | 
2025-10-03 13:24:40.323627 | TASK [bindep : Get list of packages to install from bindep]
2025-10-03 09:24:42.113628 | controller | podman
2025-10-03 09:24:42.156544 | controller | python3-jmespath
2025-10-03 09:24:42.156615 | controller | python3-libvirt
2025-10-03 09:24:42.156623 | controller | python3-lxml
2025-10-03 09:24:42.156631 | controller | python3-netaddr
2025-10-03 13:24:42.363517 | controller | ok: Runtime: 0:00:01.599253
2025-10-03 13:24:42.370038 | 
2025-10-03 13:24:42.370145 | TASK [bindep : Install distro packages from bindep]
2025-10-03 13:25:49.909264 | controller | changed
2025-10-03 13:25:49.924000 | 
2025-10-03 13:25:49.924156 | TASK [bindep : Check that packages are installed]
2025-10-03 13:25:51.469362 | controller | ok: Runtime: 0:00:01.136738
2025-10-03 13:25:51.479321 | 
2025-10-03 13:25:51.479454 | TASK [bindep : Fail if we cannot install all packages]
2025-10-03 13:25:51.519874 | controller | skipping: Conditional result was False
2025-10-03 13:25:51.537556 | 
2025-10-03 13:25:51.537731 | TASK [Run test-setup role]
2025-10-03 13:25:51.563633 | controller | ok
2025-10-03 13:25:51.599645 | 
2025-10-03 13:25:51.599781 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-10-03 13:25:51.866238 | controller | ok
2025-10-03 13:25:51.871360 | 
2025-10-03 13:25:51.871434 | TASK [test-setup : Run tools/test-setup.sh]
2025-10-03 13:25:52.400496 | controller | skipping: Conditional result was False
2025-10-03 13:25:52.427739 | 
2025-10-03 13:25:52.428082 | TASK [bindep : Remove bindep temp dir]
2025-10-03 13:25:52.838067 | controller | ok
2025-10-03 13:25:52.863235 | 
2025-10-03 13:25:52.863340 | PLAY RECAP
2025-10-03 13:25:52.863383 | controller | ok: 22 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0
2025-10-03 13:25:52.863404 | 
2025-10-03 13:25:52.998321 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-10-03 13:25:52.999185 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2025-10-03 13:25:53.692388 | 
2025-10-03 13:25:53.692529 | PLAY [all]
2025-10-03 13:25:53.719740 | 
2025-10-03 13:25:53.719878 | TASK [Abort when test_command variable is undefined]
2025-10-03 13:25:53.744913 | controller | skipping: Conditional result was False
2025-10-03 13:25:53.750616 | 
2025-10-03 13:25:53.750772 | TASK [Convert test_command to list]
2025-10-03 13:25:53.795531 | controller | skipping: Conditional result was False
2025-10-03 13:25:53.802657 | 
2025-10-03 13:25:53.802792 | TASK [Use test_command list]
2025-10-03 13:25:53.850070 | controller | ok
2025-10-03 13:25:53.855630 | 
2025-10-03 13:25:53.855725 | LOOP [Run test_command]
2025-10-03 13:25:54.286672 | controller | no check to run
2025-10-03 13:25:54.286950 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.006422
2025-10-03 13:25:54.330217 | 
2025-10-03 13:25:54.330351 | PLAY RECAP
2025-10-03 13:25:54.330463 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-03 13:25:54.330530 | 
2025-10-03 13:25:54.452808 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2025-10-03 13:25:54.453712 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-10-03 13:25:55.095542 | 
2025-10-03 13:25:55.095704 | PLAY [all]
2025-10-03 13:25:55.121119 | 
2025-10-03 13:25:55.121240 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-10-03 13:25:55.458827 | controller | changed: non-zero return code
2025-10-03 13:25:55.465329 | 
2025-10-03 13:25:55.465438 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-10-03 13:25:55.490379 | controller | skipping: Conditional result was False
2025-10-03 13:25:55.497325 | 
2025-10-03 13:25:55.497425 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-10-03 13:25:55.521304 | 
2025-10-03 13:25:55.521495 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-10-03 13:25:55.542977 | 
2025-10-03 13:25:55.543116 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-10-03 13:25:55.567470 | controller | skipping: Conditional result was False
2025-10-03 13:25:55.574733 | 
2025-10-03 13:25:55.574815 | LOOP [fetch-subunit-output : Generate subunit file]
2025-10-03 13:25:55.605797 | 
2025-10-03 13:25:55.606159 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-10-03 13:25:55.632168 | controller | skipping: Conditional result was False
2025-10-03 13:25:55.637757 | 
2025-10-03 13:25:55.637825 | TASK [fetch-subunit-output : Remove the temporary file]
2025-10-03 13:25:55.652000 | controller | skipping: Conditional result was False
2025-10-03 13:25:55.659284 | 
2025-10-03 13:25:55.659485 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-10-03 13:25:55.673344 | controller | skipping: Conditional result was False
2025-10-03 13:25:55.700172 | 
2025-10-03 13:25:55.700280 | PLAY RECAP
2025-10-03 13:25:55.700324 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-10-03 13:25:55.700345 | 
2025-10-03 13:25:55.809772 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-10-03 13:25:55.813802 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-03 13:25:56.449456 | 
2025-10-03 13:25:56.449581 | PLAY [all]
2025-10-03 13:25:56.471226 | 
2025-10-03 13:25:56.471371 | TASK [include_role : fetch-output]
2025-10-03 13:25:56.504235 | controller | ok
2025-10-03 13:25:56.525547 | 
2025-10-03 13:25:56.525716 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-03 13:25:56.570995 | controller | skipping: Conditional result was False
2025-10-03 13:25:56.576995 | 
2025-10-03 13:25:56.577076 | TASK [fetch-output : Set log path for single node]
2025-10-03 13:25:56.608116 | controller | ok
2025-10-03 13:25:56.615608 | 
2025-10-03 13:25:56.615762 | LOOP [fetch-output : Ensure local output dirs]
2025-10-03 13:25:57.074559 | controller -> localhost | ok: "/var/lib/zuul/builds/e98e7174e7b7486388dd1c3bc45194f1/work/logs"
2025-10-03 13:25:57.317175 | controller -> localhost | changed: "/var/lib/zuul/builds/e98e7174e7b7486388dd1c3bc45194f1/work/artifacts"
2025-10-03 13:25:57.585423 | controller -> localhost | changed: "/var/lib/zuul/builds/e98e7174e7b7486388dd1c3bc45194f1/work/docs"
2025-10-03 13:25:57.602527 | 
2025-10-03 13:25:57.602611 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-03 13:25:58.240653 | controller | changed: .d..t...... ./
2025-10-03 13:25:58.241096 | controller | changed: All items complete
2025-10-03 13:25:58.241133 | 
2025-10-03 13:25:58.690543 | controller | changed: .d..t...... ./
2025-10-03 13:25:59.158930 | controller | changed: .d..t...... ./
2025-10-03 13:25:59.173798 | 
2025-10-03 13:25:59.174049 | TASK [include_role : fetch-output-openshift]
2025-10-03 13:25:59.188183 | controller | skipping: Conditional result was False
2025-10-03 13:25:59.194626 | 
2025-10-03 13:25:59.194748 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-03 13:25:59.631616 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.020265
2025-10-03 13:25:59.891937 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008528
2025-10-03 13:25:59.927517 | 
2025-10-03 13:25:59.927617 | PLAY [all]
2025-10-03 13:25:59.949269 | 
2025-10-03 13:25:59.949393 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-03 13:26:00.392261 | controller | changed
2025-10-03 13:26:00.428820 | 
2025-10-03 13:26:00.428943 | PLAY RECAP
2025-10-03 13:26:00.429002 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-03 13:26:00.429034 | 
2025-10-03 13:26:00.557711 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-03 13:26:00.558558 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-03 13:26:01.317518 | 
2025-10-03 13:26:01.317628 | PLAY [localhost]
2025-10-03 13:26:01.340010 | 
2025-10-03 13:26:01.340142 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-03 13:26:01.789439 | localhost | changed
2025-10-03 13:26:01.796366 | 
2025-10-03 13:26:01.796576 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-03 13:26:01.827393 | localhost | ok
2025-10-03 13:26:01.838797 | 
2025-10-03 13:26:01.838916 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-03 13:26:02.238522 | localhost | changed
2025-10-03 13:26:02.244613 | 
2025-10-03 13:26:02.244759 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-03 13:26:03.054580 | localhost | changed
2025-10-03 13:26:03.063897 | 
2025-10-03 13:26:03.064034 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-03 13:26:03.604636 | localhost | Identity added: /var/lib/zuul/builds/e98e7174e7b7486388dd1c3bc45194f1/work/tmp/ansible.9a5ne46r (/var/lib/zuul/builds/e98e7174e7b7486388dd1c3bc45194f1/work/tmp/ansible.9a5ne46r)
2025-10-03 13:26:03.605463 | localhost | ok: Runtime: 0:00:00.008689
2025-10-03 13:26:03.616544 | 
2025-10-03 13:26:03.616761 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-03 13:26:03.928795 | localhost | ok: Runtime: 0:00:00.022276
2025-10-03 13:26:03.935044 | 
2025-10-03 13:26:03.935173 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-03 13:26:03.997817 | localhost | changed
2025-10-03 13:26:04.002258 | 
2025-10-03 13:26:04.002328 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-03 13:26:04.494196 | localhost | changed
2025-10-03 13:26:04.524301 | 
2025-10-03 13:26:04.524434 | PLAY [localhost]
2025-10-03 13:26:04.541249 | 
2025-10-03 13:26:04.541402 | TASK [Generate bulk log download script]
2025-10-03 13:26:04.560381 | localhost | ok
2025-10-03 13:26:04.573204 | 
2025-10-03 13:26:04.573341 | TASK [local-log-download : Check API endpoint is defined]
2025-10-03 13:26:04.601601 | localhost | ok: All assertions passed
2025-10-03 13:26:04.606135 | 
2025-10-03 13:26:04.606198 | TASK [local-log-download : Create download script]
2025-10-03 13:26:05.058167 | localhost -> localhost | changed
2025-10-03 13:26:05.067547 | 
2025-10-03 13:26:05.067663 | TASK [Register quick-download link]
2025-10-03 13:26:05.087587 | localhost | ok
2025-10-03 13:26:05.153000 | 
2025-10-03 13:26:05.153105 | PLAY [logserver.rdoproject.org]
2025-10-03 13:26:05.168392 | 
2025-10-03 13:26:05.168516 | TASK [Set zuul-log-path fact]
2025-10-03 13:26:05.192672 | logserver.rdoproject.org | ok
2025-10-03 13:26:05.204171 | 
2025-10-03 13:26:05.204252 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-03 13:26:05.231367 | logserver.rdoproject.org | ok
2025-10-03 13:26:05.240496 | 
2025-10-03 13:26:05.240610 | TASK [upload-logs : Create log directories]
2025-10-03 13:26:05.913959 | logserver.rdoproject.org | changed
2025-10-03 13:26:05.917437 | 
2025-10-03 13:26:05.917499 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-03 13:26:06.189463 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004705
2025-10-03 13:26:06.194564 | 
2025-10-03 13:26:06.194644 | TASK [upload-logs : Upload logs to log server]
2025-10-03 13:26:06.922097 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-03 13:26:06.925433 | 
2025-10-03 13:26:06.925522 | LOOP [upload-logs : Compress console log and json output]
2025-10-03 13:26:06.976462 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 13:26:06.988717 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 13:26:06.995373 | 
2025-10-03 13:26:06.995501 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-03 13:26:07.043861 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 13:26:07.044189 | 
2025-10-03 13:26:07.048213 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 13:26:07.065635 | 
2025-10-03 13:26:07.065792 | LOOP [upload-logs : Upload console log and json output]