2025-11-21 11:46:47.816672 | Job console starting...
2025-11-21 11:46:47.828834 | Updating repositories
2025-11-21 11:46:47.867249 | Preparing job workspace
2025-11-21 11:46:52.875853 | Running Ansible setup...
2025-11-21 11:46:56.624508 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-21 11:46:57.298345 | 
2025-11-21 11:46:57.298456 | PLAY [localhost]
2025-11-21 11:46:57.307280 | 
2025-11-21 11:46:57.307366 | TASK [Gathering Facts]
2025-11-21 11:46:58.382835 | localhost | ok
2025-11-21 11:46:58.420723 | 
2025-11-21 11:46:58.420900 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-21 11:46:58.909473 | localhost -> localhost | changed
2025-11-21 11:46:58.915385 | 
2025-11-21 11:46:58.915490 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-21 11:46:59.783794 | localhost -> localhost | changed
2025-11-21 11:46:59.796680 | 
2025-11-21 11:46:59.796840 | TASK [Setup log path fact]
2025-11-21 11:46:59.814870 | localhost | ok
2025-11-21 11:46:59.826474 | 
2025-11-21 11:46:59.826544 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-21 11:46:59.865207 | localhost | ok
2025-11-21 11:46:59.887175 | 
2025-11-21 11:46:59.887294 | TASK [emit-job-header : Print job information]
2025-11-21 11:46:59.916518 | # Job Information
2025-11-21 11:46:59.916829 | Ansible Version: 2.15.12
2025-11-21 11:46:59.916860 | Job: cifmw-molecule-cifmw_external_dns
2025-11-21 11:46:59.916880 | Pipeline: github-check
2025-11-21 11:46:59.916898 | Executor: ze01.softwarefactory-project.io
2025-11-21 11:46:59.916915 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3451
2025-11-21 11:46:59.916933 | Log URL (when completed): https://logserver.rdoproject.org/77a/rdoproject.org/77a2fcc6929c480e855e9121ec9ac676/
2025-11-21 11:46:59.916951 | Event ID: 89f43be0-c6cf-11f0-848c-8d7059119474
2025-11-21 11:46:59.920664 | 
2025-11-21 11:46:59.920743 | LOOP [emit-job-header : Print node information]
2025-11-21 11:47:00.059580 | localhost | ok:
2025-11-21 11:47:00.059828 | localhost | # Node Information
2025-11-21 11:47:00.059862 | localhost | Inventory Hostname: controller
2025-11-21 11:47:00.059885 | localhost | Hostname: np0005530988
2025-11-21 11:47:00.059904 | localhost | Username: zuul
2025-11-21 11:47:00.059924 | localhost | Distro: CentOS 9
2025-11-21 11:47:00.059942 | localhost | Provider: vexxhost-nodepool-tripleo
2025-11-21 11:47:00.059959 | localhost | Region: RegionOne
2025-11-21 11:47:00.059975 | localhost | Label: cloud-centos-9-stream-tripleo
2025-11-21 11:47:00.059992 | localhost | Product Name: OpenStack Nova
2025-11-21 11:47:00.060008 | localhost | Interface IP: 38.102.83.174
2025-11-21 11:47:00.088998 | 
2025-11-21 11:47:00.089139 | PLAY [all]
2025-11-21 11:47:00.095657 | 
2025-11-21 11:47:00.095717 | TASK [Gather network facts]
2025-11-21 11:47:00.588166 | controller | ok
2025-11-21 11:47:00.616269 | 
2025-11-21 11:47:00.616391 | TASK [include_role : start-zuul-console]
2025-11-21 11:47:00.646177 | controller | ok
2025-11-21 11:47:00.657653 | 
2025-11-21 11:47:00.657727 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-21 11:47:01.007747 | controller | ok
2025-11-21 11:47:01.020477 | 
2025-11-21 11:47:01.020577 | TASK [include_role : add-build-sshkey]
2025-11-21 11:47:01.053595 | controller | ok
2025-11-21 11:47:01.066387 | 
2025-11-21 11:47:01.066463 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-21 11:47:01.352119 | controller -> localhost | ok
2025-11-21 11:47:01.357394 | 
2025-11-21 11:47:01.357458 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-21 11:47:01.376050 | controller | ok
2025-11-21 11:47:01.388990 | controller | included: /var/lib/zuul/builds/77a2fcc6929c480e855e9121ec9ac676/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-21 11:47:01.394512 | 
2025-11-21 11:47:01.394572 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-21 11:47:02.061101 | controller -> localhost | Generating public/private rsa key pair.
2025-11-21 11:47:02.061284 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/77a2fcc6929c480e855e9121ec9ac676/work/77a2fcc6929c480e855e9121ec9ac676_id_rsa.
2025-11-21 11:47:02.061314 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/77a2fcc6929c480e855e9121ec9ac676/work/77a2fcc6929c480e855e9121ec9ac676_id_rsa.pub.
2025-11-21 11:47:02.061337 | controller -> localhost | The key fingerprint is:
2025-11-21 11:47:02.061356 | controller -> localhost | SHA256:AlJGsmkvyixFkl36vuR3JwD8bCuDtJVTLBNWDoK+X4A zuul-build-sshkey
2025-11-21 11:47:02.061375 | controller -> localhost | The key's randomart image is:
2025-11-21 11:47:02.061396 | controller -> localhost | +---[RSA 3072]----+
2025-11-21 11:47:02.061416 | controller -> localhost | | .o.*..          |
2025-11-21 11:47:02.061434 | controller -> localhost | |.o Xoo           |
2025-11-21 11:47:02.061452 | controller -> localhost | |+ Xo.o.          |
2025-11-21 11:47:02.061469 | controller -> localhost | | E ==.o          |
2025-11-21 11:47:02.061486 | controller -> localhost | |  + +O. S        |
2025-11-21 11:47:02.061503 | controller -> localhost | |o+.o+.=.         |
2025-11-21 11:47:02.061520 | controller -> localhost | |o+.++o o         |
2025-11-21 11:47:02.061540 | controller -> localhost | |. o+o.o o .      |
2025-11-21 11:47:02.061557 | controller -> localhost | |    o+ . o       |
2025-11-21 11:47:02.061575 | controller -> localhost | +----[SHA256]-----+
2025-11-21 11:47:02.061620 | controller -> localhost | ok: Runtime: 0:00:00.286772
2025-11-21 11:47:02.067588 | 
2025-11-21 11:47:02.067666 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-21 11:47:02.085712 | controller | ok
2025-11-21 11:47:02.095720 | controller | included: /var/lib/zuul/builds/77a2fcc6929c480e855e9121ec9ac676/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-21 11:47:02.104663 | 
2025-11-21 11:47:02.104725 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-21 11:47:02.118285 | controller | skipping: Conditional result was False
2025-11-21 11:47:02.124521 | 
2025-11-21 11:47:02.124600 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-21 11:47:02.673160 | controller | changed
2025-11-21 11:47:02.678127 | 
2025-11-21 11:47:02.678188 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-21 11:47:02.976878 | controller | ok
2025-11-21 11:47:02.986537 | 
2025-11-21 11:47:02.986659 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-21 11:47:03.822914 | controller | changed
2025-11-21 11:47:03.838436 | 
2025-11-21 11:47:03.838571 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-21 11:47:04.643652 | controller | changed
2025-11-21 11:47:04.655692 | 
2025-11-21 11:47:04.655873 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-21 11:47:04.682383 | controller | skipping: Conditional result was False
2025-11-21 11:47:04.694414 | 
2025-11-21 11:47:04.694574 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-21 11:47:05.107920 | controller -> localhost | changed
2025-11-21 11:47:05.121676 | 
2025-11-21 11:47:05.121813 | TASK [add-build-sshkey : Add back temp key]
2025-11-21 11:47:05.421846 | controller -> localhost | Identity added: /var/lib/zuul/builds/77a2fcc6929c480e855e9121ec9ac676/work/77a2fcc6929c480e855e9121ec9ac676_id_rsa (zuul-build-sshkey)
2025-11-21 11:47:05.422131 | controller -> localhost | ok: Runtime: 0:00:00.011853
2025-11-21 11:47:05.427935 | 
2025-11-21 11:47:05.428031 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-21 11:47:05.790922 | controller | ok
2025-11-21 11:47:05.801455 | 
2025-11-21 11:47:05.801594 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-21 11:47:05.850478 | controller | skipping: Conditional result was False
2025-11-21 11:47:05.879199 | 
2025-11-21 11:47:05.879439 | TASK [include_role : validate-host]
2025-11-21 11:47:05.917798 | controller | ok
2025-11-21 11:47:05.954305 | 
2025-11-21 11:47:05.954445 | TASK [validate-host : Define zuul_info_dir fact]
2025-11-21 11:47:05.995629 | controller | ok
2025-11-21 11:47:06.000531 | 
2025-11-21 11:47:06.000616 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-11-21 11:47:06.333067 | controller -> localhost | ok
2025-11-21 11:47:06.339645 | 
2025-11-21 11:47:06.339707 | TASK [validate-host : Collect information about the host]
2025-11-21 11:47:07.114443 | controller | ok
2025-11-21 11:47:07.123214 | 
2025-11-21 11:47:07.123288 | TASK [validate-host : Sanitize hostname]
2025-11-21 11:47:07.184367 | controller | ok
2025-11-21 11:47:07.195551 | 
2025-11-21 11:47:07.195646 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-11-21 11:47:07.615949 | controller -> localhost | changed
2025-11-21 11:47:07.622488 | 
2025-11-21 11:47:07.622565 | TASK [validate-host : Collect information about zuul worker]
2025-11-21 11:47:08.058099 | controller | ok
2025-11-21 11:47:08.063163 | 
2025-11-21 11:47:08.063237 | TASK [validate-host : Write out all zuul information for each host]
2025-11-21 11:47:08.492824 | controller -> localhost | changed
2025-11-21 11:47:08.503836 | 
2025-11-21 11:47:08.503925 | TASK [include_role : prepare-workspace-openshift]
2025-11-21 11:47:08.527655 | controller | skipping: Conditional result was False
2025-11-21 11:47:08.533604 | 
2025-11-21 11:47:08.533683 | TASK [include_role : remove-zuul-sshkey]
2025-11-21 11:47:08.573493 | controller | skipping: Conditional result was False
2025-11-21 11:47:08.579796 | 
2025-11-21 11:47:08.579888 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-21 11:47:08.828494 | controller | ok: "logs"
2025-11-21 11:47:08.828731 | controller | ok: All items complete
2025-11-21 11:47:08.828765 | 
2025-11-21 11:47:09.072397 | controller | ok: "artifacts"
2025-11-21 11:47:09.297802 | controller | ok: "docs"
2025-11-21 11:47:09.309663 | 
2025-11-21 11:47:09.309771 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-21 11:47:09.586550 | controller | changed: "logs"
2025-11-21 11:47:09.810487 | controller | changed: "artifacts"
2025-11-21 11:47:10.050684 | controller | changed: "docs"
2025-11-21 11:47:10.101456 | 
2025-11-21 11:47:10.101740 | PLAY RECAP
2025-11-21 11:47:10.101811 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-21 11:47:10.101849 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-21 11:47:10.101874 | 
2025-11-21 11:47:10.230580 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-21 11:47:10.232164 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-11-21 11:47:10.886752 | 
2025-11-21 11:47:10.886861 | PLAY [localhost]
2025-11-21 11:47:10.902892 | 
2025-11-21 11:47:10.902968 | TASK [configure-ssh-config-fips : Ensure ssh config directory]
2025-11-21 11:47:11.333588 | localhost | ok
2025-11-21 11:47:11.341808 | 
2025-11-21 11:47:11.341962 | TASK [configure-ssh-config-fips : Setup ssh config]
2025-11-21 11:47:12.326827 | localhost | changed
2025-11-21 11:47:12.371363 | 
2025-11-21 11:47:12.371422 | PLAY [all]
2025-11-21 11:47:12.396090 | 
2025-11-21 11:47:12.396154 | TASK [include_role : prepare-workspace]
2025-11-21 11:47:12.455556 | controller | ok
2025-11-21 11:47:12.479943 | 
2025-11-21 11:47:12.480030 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-21 11:47:12.858141 | controller | ok
2025-11-21 11:47:12.865886 | 
2025-11-21 11:47:12.866070 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-21 11:47:15.123183 | controller | Output suppressed because no_log was given
2025-11-21 11:47:15.145626 | 
2025-11-21 11:47:15.145735 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-21 11:47:15.419782 | controller | changed: "logs"
2025-11-21 11:47:15.655563 | controller | changed: "artifacts"
2025-11-21 11:47:15.859893 | controller | changed: "docs"
2025-11-21 11:47:15.874336 | 
2025-11-21 11:47:15.874590 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-21 11:47:16.143516 | controller | changed: "logs"
2025-11-21 11:47:16.144056 | controller | changed: All items complete
2025-11-21 11:47:16.144145 | 
2025-11-21 11:47:16.373158 | controller | changed: "artifacts"
2025-11-21 11:47:16.593250 | controller | changed: "docs"
2025-11-21 11:47:16.606561 | 
2025-11-21 11:47:16.607393 | TASK [Check if worker can sudo]
2025-11-21 11:47:17.150256 | controller | ok: Runtime: 0:00:00.039379
2025-11-21 11:47:17.167389 | 
2025-11-21 11:47:17.167591 | TASK [configure-mirrors : Gather needed facts]
2025-11-21 11:47:17.277226 | controller | skipping: Conditional result was False
2025-11-21 11:47:17.319402 | 
2025-11-21 11:47:17.319544 | TASK [configure-mirrors : Set up infrastructure mirrors]
2025-11-21 11:47:17.397388 | controller | ok
2025-11-21 11:47:17.414667 | controller | included: /var/lib/zuul/builds/77a2fcc6929c480e855e9121ec9ac676/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml
2025-11-21 11:47:17.424436 | 
2025-11-21 11:47:17.424522 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir]
2025-11-21 11:47:17.678537 | controller | ok
2025-11-21 11:47:17.685480 | 
2025-11-21 11:47:17.685638 | LOOP [configure-mirrors : Include OS-specific variables]
2025-11-21 11:47:17.778435 | controller | ok: "/var/lib/zuul/builds/77a2fcc6929c480e855e9121ec9ac676/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml"
2025-11-21 11:47:17.791337 | 
2025-11-21 11:47:17.791419 | TASK [configure-mirrors : Install /etc/pip.conf configuration]
2025-11-21 11:47:18.604500 | controller | changed
2025-11-21 11:47:18.619453 | 
2025-11-21 11:47:18.622403 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors]
2025-11-21 11:47:18.695700 | controller | ok: "/var/lib/zuul/builds/77a2fcc6929c480e855e9121ec9ac676/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml"
2025-11-21 11:47:18.695892 | controller | ok: All items complete
2025-11-21 11:47:18.695919 | 
2025-11-21 11:47:18.785964 | controller | included: /var/lib/zuul/builds/77a2fcc6929c480e855e9121ec9ac676/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml
2025-11-21 11:47:18.800858 | 
2025-11-21 11:47:18.800938 | LOOP [configure-mirrors : Install CentOS 9 repository files]
2025-11-21 11:47:19.806665 | controller | changed: "etc/yum.repos.d/centos.repo"
2025-11-21 11:47:20.651160 | controller | changed: "etc/yum.repos.d/centos-addons.repo"
2025-11-21 11:47:20.667649 | 
2025-11-21 11:47:20.667774 | TASK [configure-mirrors : Disable deltrarpm]
2025-11-21 11:47:21.144491 | controller | changed: section and option added
2025-11-21 11:47:21.190588 | 
2025-11-21 11:47:21.190709 | LOOP [configure-mirrors : Update yum/dnf cache]
2025-11-21 11:47:22.450994 | controller | 29 files removed
2025-11-21 11:47:22.451295 | controller | ok: Item: dnf clean all Runtime: 0:00:00.972776
2025-11-21 11:47:22.451338 | controller | changed: All items complete
2025-11-21 11:47:22.451359 | 
2025-11-21 11:47:34.801936 | 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-11-21 11:47:34.802623 | controller | DNF version: 4.14.0
2025-11-21 11:47:34.802668 | controller | cachedir: /var/cache/dnf
2025-11-21 11:47:34.802703 | controller | Making cache files for all metadata files.
2025-11-21 11:47:34.802748 | controller | baseos: has expired and will be refreshed.
2025-11-21 11:47:34.802773 | controller | appstream: has expired and will be refreshed.
2025-11-21 11:47:34.802798 | controller | crb: has expired and will be refreshed.
2025-11-21 11:47:34.802832 | controller | extras-common: has expired and will be refreshed.
2025-11-21 11:47:34.802857 | controller | repo: downloading from remote: baseos
2025-11-21 11:47:34.802882 | controller | CentOS Stream 9 - BaseOS                         28 MB/s | 8.8 MB     00:00
2025-11-21 11:47:34.802906 | controller | baseos: using metadata from Mon 17 Nov 2025 10:24:02 AM EST.
2025-11-21 11:47:34.802930 | controller | repo: downloading from remote: appstream
2025-11-21 11:47:34.802954 | controller | CentOS Stream 9 - AppStream                      49 MB/s |  25 MB     00:00
2025-11-21 11:47:34.802977 | controller | appstream: using metadata from Mon 17 Nov 2025 10:27:35 AM EST.
2025-11-21 11:47:34.803001 | controller | repo: downloading from remote: crb
2025-11-21 11:47:34.803050 | controller | CentOS Stream 9 - CRB                            50 MB/s | 7.3 MB     00:00
2025-11-21 11:47:34.803083 | controller | crb: using metadata from Mon 17 Nov 2025 10:32:16 AM EST.
2025-11-21 11:47:34.803108 | controller | repo: downloading from remote: extras-common
2025-11-21 11:47:34.803132 | controller | CentOS Stream 9 - Extras packages               1.6 MB/s |  20 kB     00:00
2025-11-21 11:47:34.803156 | controller | extras-common: using metadata from Thu 30 Oct 2025 09:20:08 AM EDT.
2025-11-21 11:47:34.803179 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)'
2025-11-21 11:47:34.803203 | controller | Completion plugin: Generating completion cache...
2025-11-21 11:47:34.803227 | controller | Metadata cache created.
2025-11-21 11:47:34.803262 | controller | ok: Item: dnf makecache -v Runtime: 0:00:11.996750
2025-11-21 11:47:34.819285 | 
2025-11-21 11:47:34.819352 | PLAY RECAP
2025-11-21 11:47:34.819403 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-21 11:47:34.819439 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-21 11:47:34.819464 | 
2025-11-21 11:47:34.918580 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-11-21 11:47:34.920419 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-21 11:47:35.592970 | 
2025-11-21 11:47:35.593105 | PLAY [all]
2025-11-21 11:47:35.617503 | 
2025-11-21 11:47:35.617654 | TASK [Install binary dependencies]
2025-11-21 11:47:35.667383 | controller | ok
2025-11-21 11:47:35.688411 | 
2025-11-21 11:47:35.688496 | TASK [bindep : Include find tasks]
2025-11-21 11:47:35.717457 | controller | ok
2025-11-21 11:47:35.725810 | controller | included: /var/lib/zuul/builds/77a2fcc6929c480e855e9121ec9ac676/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-11-21 11:47:35.733292 | 
2025-11-21 11:47:35.733385 | TASK [bindep : Look for bindep.txt]
2025-11-21 11:47:36.151433 | controller | ok
2025-11-21 11:47:36.158184 | 
2025-11-21 11:47:36.158289 | TASK [bindep : Define bindep_file fact]
2025-11-21 11:47:36.187109 | controller | ok
2025-11-21 11:47:36.192196 | 
2025-11-21 11:47:36.192278 | TASK [bindep : Look for other-requirements.txt]
2025-11-21 11:47:36.205684 | controller | skipping: Conditional result was False
2025-11-21 11:47:36.211291 | 
2025-11-21 11:47:36.211365 | TASK [bindep : Define bindep_file fact]
2025-11-21 11:47:36.234546 | controller | skipping: Conditional result was False
2025-11-21 11:47:36.240263 | 
2025-11-21 11:47:36.240330 | TASK [bindep : Look for bindep fallback file]
2025-11-21 11:47:36.263704 | controller | skipping: Conditional result was False
2025-11-21 11:47:36.269350 | 
2025-11-21 11:47:36.269417 | TASK [bindep : Define bindep_file fact]
2025-11-21 11:47:36.292629 | controller | skipping: Conditional result was False
2025-11-21 11:47:36.299405 | 
2025-11-21 11:47:36.299487 | TASK [bindep : Include bindep tasks]
2025-11-21 11:47:36.327849 | controller | ok
2025-11-21 11:47:36.335294 | controller | included: /var/lib/zuul/builds/77a2fcc6929c480e855e9121ec9ac676/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml
2025-11-21 11:47:36.343160 | 
2025-11-21 11:47:36.343243 | TASK [bindep : Look for bindep command]
2025-11-21 11:47:36.366802 | controller | skipping: Conditional result was False
2025-11-21 11:47:36.373131 | 
2025-11-21 11:47:36.373208 | TASK [bindep : Check for system bindep]
2025-11-21 11:47:36.920672 | controller | ok: Runtime: 0:00:00.004878
2025-11-21 11:47:36.938690 | 
2025-11-21 11:47:36.939244 | TASK [bindep : Define bindep_command fact]
2025-11-21 11:47:36.966896 | controller | skipping: Conditional result was False
2025-11-21 11:47:36.980618 | 
2025-11-21 11:47:36.980804 | TASK [bindep : Include install tasks]
2025-11-21 11:47:37.033692 | controller | ok
2025-11-21 11:47:37.052587 | controller | included: /var/lib/zuul/builds/77a2fcc6929c480e855e9121ec9ac676/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml
2025-11-21 11:47:37.073272 | 
2025-11-21 11:47:37.073419 | TASK [bindep : Create temp dir for bindep]
2025-11-21 11:47:37.443131 | controller | changed
2025-11-21 11:47:37.448579 | 
2025-11-21 11:47:37.448732 | TASK [Ensure we have pip dependencies]
2025-11-21 11:47:37.467550 | controller | ok
2025-11-21 11:47:37.508825 | 
2025-11-21 11:47:37.508968 | TASK [ensure-pip : Check if pip is installed]
2025-11-21 06:47:37.761705 | controller | /usr/bin/pip3
2025-11-21 06:47:37.781745 | controller | /usr/bin/python3: No module named wheel
2025-11-21 11:47:38.040507 | controller | ok: Runtime: 0:00:00.029722
2025-11-21 11:47:38.049552 | 
2025-11-21 11:47:38.049669 | LOOP [ensure-pip : Install pip from packages]
2025-11-21 11:47:38.101793 | controller | ok: "/var/lib/zuul/builds/77a2fcc6929c480e855e9121ec9ac676/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml"
2025-11-21 11:47:38.118725 | controller | included: /var/lib/zuul/builds/77a2fcc6929c480e855e9121ec9ac676/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml
2025-11-21 11:47:38.134990 | 
2025-11-21 11:47:38.135135 | TASK [ensure-pip : Install Python 3 pip]
2025-11-21 11:47:41.982771 | controller | changed
2025-11-21 11:47:41.996194 | 
2025-11-21 11:47:41.996326 | TASK [ensure-pip : Check for EPEL repository]
2025-11-21 11:47:42.034746 | controller | skipping: Conditional result was False
2025-11-21 11:47:42.049277 | 
2025-11-21 11:47:42.049440 | TASK [ensure-pip : Fail with instructions when EPEL is not installed]
2025-11-21 11:47:42.099778 | controller | skipping: Conditional result was False
2025-11-21 11:47:42.114172 | 
2025-11-21 11:47:42.114313 | TASK [ensure-pip : Install Python 2 pip]
2025-11-21 11:47:42.162703 | controller | skipping: Conditional result was False
2025-11-21 11:47:42.176167 | 
2025-11-21 11:47:42.176311 | TASK [ensure-pip : Ensure setuptools]
2025-11-21 11:47:42.206379 | controller | skipping: Conditional result was False
2025-11-21 11:47:42.227865 | 
2025-11-21 11:47:42.227955 | TASK [ensure-pip : Check for ensurepip module]
2025-11-21 11:47:42.768383 | controller | skipping: Conditional result was False
2025-11-21 11:47:42.775525 | 
2025-11-21 11:47:42.775616 | TASK [ensure-pip : Ensure python3-venv]
2025-11-21 11:47:42.790044 | controller | skipping: Conditional result was False
2025-11-21 11:47:42.797990 | 
2025-11-21 11:47:42.798112 | TASK [ensure-pip : Install pip from source]
2025-11-21 11:47:42.831801 | controller | skipping: Conditional result was False
2025-11-21 11:47:42.838758 | 
2025-11-21 11:47:42.838878 | TASK [ensure-pip : Probe for venv python full path]
2025-11-21 06:47:43.129005 | controller | /usr/bin/python3
2025-11-21 11:47:43.380516 | controller | ok: Runtime: 0:00:00.004737
2025-11-21 11:47:43.391717 | 
2025-11-21 11:47:43.391823 | TASK [ensure-pip : Set host default]
2025-11-21 11:47:43.444004 | controller | ok
2025-11-21 11:47:43.449197 | 
2025-11-21 11:47:43.449273 | TASK [ensure-pip : Set ensure_pip_virtualenv_command]
2025-11-21 11:47:43.524307 | controller | ok
2025-11-21 11:47:43.544389 | 
2025-11-21 11:47:43.544543 | TASK [bindep : Install bindep into temporary venv]
2025-11-21 11:47:47.988549 | controller | changed
2025-11-21 11:47:47.997525 | 
2025-11-21 11:47:47.997691 | TASK [bindep : Define bindep_command]
2025-11-21 11:47:48.033448 | controller | ok
2025-11-21 11:47:48.041627 | 
2025-11-21 11:47:48.041753 | LOOP [bindep : Include package tasks]
2025-11-21 11:47:48.089296 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt"
2025-11-21 11:47:48.089903 | controller | ok: All items complete
2025-11-21 11:47:48.090123 | 
2025-11-21 11:47:48.113901 | controller | included: /var/lib/zuul/builds/77a2fcc6929c480e855e9121ec9ac676/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml
2025-11-21 11:47:48.130808 | 
2025-11-21 11:47:48.130993 | TASK [bindep : Define bindep_run fact]
2025-11-21 11:47:48.165814 | controller | ok
2025-11-21 11:47:48.174259 | 
2025-11-21 11:47:48.174432 | TASK [bindep : Get list of packages to install from bindep]
2025-11-21 06:47:49.559915 | controller | podman
2025-11-21 06:47:49.593009 | controller | python3-jmespath
2025-11-21 06:47:49.593084 | controller | python3-libvirt
2025-11-21 06:47:49.593192 | controller | python3-lxml
2025-11-21 06:47:49.593206 | controller | python3-netaddr
2025-11-21 11:47:49.724200 | controller | ok: Runtime: 0:00:01.155741
2025-11-21 11:47:49.739775 | 
2025-11-21 11:47:49.739919 | TASK [bindep : Install distro packages from bindep]
2025-11-21 11:49:04.996447 | controller | changed
2025-11-21 11:49:05.005942 | 
2025-11-21 11:49:05.006106 | TASK [bindep : Check that packages are installed]
2025-11-21 11:49:06.544287 | controller | ok: Runtime: 0:00:01.166239
2025-11-21 11:49:06.557761 | 
2025-11-21 11:49:06.557929 | TASK [bindep : Fail if we cannot install all packages]
2025-11-21 11:49:06.618442 | controller | skipping: Conditional result was False
2025-11-21 11:49:06.632610 | 
2025-11-21 11:49:06.632731 | TASK [Run test-setup role]
2025-11-21 11:49:06.653041 | controller | ok
2025-11-21 11:49:06.672251 | 
2025-11-21 11:49:06.672567 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-11-21 11:49:06.938111 | controller | ok
2025-11-21 11:49:06.943870 | 
2025-11-21 11:49:06.943971 | TASK [test-setup : Run tools/test-setup.sh]
2025-11-21 11:49:07.475298 | controller | skipping: Conditional result was False
2025-11-21 11:49:07.495915 | 
2025-11-21 11:49:07.496075 | TASK [bindep : Remove bindep temp dir]
2025-11-21 11:49:07.883560 | controller | ok
2025-11-21 11:49:07.894056 | 
2025-11-21 11:49:07.894134 | PLAY RECAP
2025-11-21 11:49:07.894174 | controller | ok: 22 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0
2025-11-21 11:49:07.894195 | 
2025-11-21 11:49:08.004526 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-21 11:49:08.005459 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2025-11-21 11:49:08.648148 | 
2025-11-21 11:49:08.648287 | PLAY [all]
2025-11-21 11:49:08.668967 | 
2025-11-21 11:49:08.669122 | TASK [Abort when test_command variable is undefined]
2025-11-21 11:49:08.703866 | controller | skipping: Conditional result was False
2025-11-21 11:49:08.709346 | 
2025-11-21 11:49:08.709416 | TASK [Convert test_command to list]
2025-11-21 11:49:08.743826 | controller | skipping: Conditional result was False
2025-11-21 11:49:08.754442 | 
2025-11-21 11:49:08.754526 | TASK [Use test_command list]
2025-11-21 11:49:08.839600 | controller | ok
2025-11-21 11:49:08.847785 | 
2025-11-21 11:49:08.847913 | LOOP [Run test_command]
2025-11-21 11:49:09.270210 | controller | no check to run
2025-11-21 11:49:09.270436 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.006042
2025-11-21 11:49:09.317174 | 
2025-11-21 11:49:09.317273 | PLAY RECAP
2025-11-21 11:49:09.317314 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-11-21 11:49:09.317335 | 
2025-11-21 11:49:09.435590 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2025-11-21 11:49:09.436451 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-21 11:49:10.054508 | 
2025-11-21 11:49:10.054624 | PLAY [all]
2025-11-21 11:49:10.075682 | 
2025-11-21 11:49:10.075791 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-11-21 11:49:10.498354 | controller | changed: non-zero return code
2025-11-21 11:49:10.510462 | 
2025-11-21 11:49:10.510624 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-11-21 11:49:10.527292 | controller | skipping: Conditional result was False
2025-11-21 11:49:10.539985 | 
2025-11-21 11:49:10.540189 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-11-21 11:49:10.572285 | 
2025-11-21 11:49:10.572716 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-11-21 11:49:10.605701 | 
2025-11-21 11:49:10.606222 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-11-21 11:49:10.624256 | controller | skipping: Conditional result was False
2025-11-21 11:49:10.638251 | 
2025-11-21 11:49:10.638420 | LOOP [fetch-subunit-output : Generate subunit file]
2025-11-21 11:49:10.683673 | 
2025-11-21 11:49:10.683884 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-11-21 11:49:10.708563 | controller | skipping: Conditional result was False
2025-11-21 11:49:10.715362 | 
2025-11-21 11:49:10.715429 | TASK [fetch-subunit-output : Remove the temporary file]
2025-11-21 11:49:10.739367 | controller | skipping: Conditional result was False
2025-11-21 11:49:10.744957 | 
2025-11-21 11:49:10.745036 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-11-21 11:49:10.770680 | controller | skipping: Conditional result was False
2025-11-21 11:49:10.795683 | 
2025-11-21 11:49:10.795756 | PLAY RECAP
2025-11-21 11:49:10.795796 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-21 11:49:10.795816 | 
2025-11-21 11:49:10.885850 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-21 11:49:10.886725 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-21 11:49:11.472658 | 
2025-11-21 11:49:11.472786 | PLAY [all]
2025-11-21 11:49:11.493198 | 
2025-11-21 11:49:11.493300 | TASK [include_role : fetch-output]
2025-11-21 11:49:11.543265 | controller | ok
2025-11-21 11:49:11.561830 | 
2025-11-21 11:49:11.561928 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-21 11:49:11.628090 | controller | skipping: Conditional result was False
2025-11-21 11:49:11.637892 | 
2025-11-21 11:49:11.638068 | TASK [fetch-output : Set log path for single node]
2025-11-21 11:49:11.691418 | controller | ok
2025-11-21 11:49:11.699147 | 
2025-11-21 11:49:11.699322 | LOOP [fetch-output : Ensure local output dirs]
2025-11-21 11:49:12.150650 | controller -> localhost | ok: "/var/lib/zuul/builds/77a2fcc6929c480e855e9121ec9ac676/work/logs"
2025-11-21 11:49:12.426120 | controller -> localhost | changed: "/var/lib/zuul/builds/77a2fcc6929c480e855e9121ec9ac676/work/artifacts"
2025-11-21 11:49:12.664006 | controller -> localhost | changed: "/var/lib/zuul/builds/77a2fcc6929c480e855e9121ec9ac676/work/docs"
2025-11-21 11:49:12.672968 | 
2025-11-21 11:49:12.673106 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-21 11:49:13.385584 | controller | changed: .d..t...... ./
2025-11-21 11:49:13.385845 | controller | changed: All items complete
2025-11-21 11:49:13.385883 | 
2025-11-21 11:49:13.913945 | controller | changed: .d..t...... ./
2025-11-21 11:49:14.452176 | controller | changed: .d..t...... ./
2025-11-21 11:49:14.470062 | 
2025-11-21 11:49:14.470176 | TASK [include_role : fetch-output-openshift]
2025-11-21 11:49:14.496193 | controller | skipping: Conditional result was False
2025-11-21 11:49:14.504688 | 
2025-11-21 11:49:14.504788 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-21 11:49:15.016941 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.014031
2025-11-21 11:49:15.313163 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008127
2025-11-21 11:49:15.345067 | 
2025-11-21 11:49:15.345158 | PLAY [all]
2025-11-21 11:49:15.359851 | 
2025-11-21 11:49:15.359942 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-11-21 11:49:15.845870 | controller | changed
2025-11-21 11:49:15.886431 | 
2025-11-21 11:49:15.886524 | PLAY RECAP
2025-11-21 11:49:15.886565 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-11-21 11:49:15.886586 | 
2025-11-21 11:49:15.999490 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-21 11:49:16.000336 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-11-21 11:49:16.669608 | 
2025-11-21 11:49:16.669788 | PLAY [localhost]
2025-11-21 11:49:16.688405 | 
2025-11-21 11:49:16.688550 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-21 11:49:17.066220 | localhost | changed
2025-11-21 11:49:17.079142 | 
2025-11-21 11:49:17.079308 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-21 11:49:17.113837 | localhost | ok
2025-11-21 11:49:17.130080 | 
2025-11-21 11:49:17.130407 | TASK [add-fileserver : Create SSH private key tempfile]
2025-11-21 11:49:17.515163 | localhost | changed
2025-11-21 11:49:17.526146 | 
2025-11-21 11:49:17.526278 | TASK [add-fileserver : Create SSH private key from secret]
2025-11-21 11:49:18.239270 | localhost | changed
2025-11-21 11:49:18.253861 | 
2025-11-21 11:49:18.253975 | TASK [add-fileserver : Add fileserver ssh key]
2025-11-21 11:49:18.693961 | localhost | Identity added: /var/lib/zuul/builds/77a2fcc6929c480e855e9121ec9ac676/work/tmp/ansible.d2fueg67 (/var/lib/zuul/builds/77a2fcc6929c480e855e9121ec9ac676/work/tmp/ansible.d2fueg67)
2025-11-21 11:49:18.694311 | localhost | ok: Runtime: 0:00:00.008161
2025-11-21 11:49:18.705572 | 
2025-11-21 11:49:18.705738 | TASK [add-fileserver : Remove SSH private key from disk]
2025-11-21 11:49:19.009222 | localhost | ok: Runtime: 0:00:00.008762
2025-11-21 11:49:19.022266 | 
2025-11-21 11:49:19.022426 | TASK [add-fileserver : Add fileserver to inventory]
2025-11-21 11:49:19.092949 | localhost | changed
2025-11-21 11:49:19.099235 | 
2025-11-21 11:49:19.099320 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-11-21 11:49:19.545289 | localhost | changed
2025-11-21 11:49:19.565828 | 
2025-11-21 11:49:19.565931 | PLAY [localhost]
2025-11-21 11:49:19.578303 | 
2025-11-21 11:49:19.578374 | TASK [Generate bulk log download script]
2025-11-21 11:49:19.597877 | localhost | ok
2025-11-21 11:49:19.610758 | 
2025-11-21 11:49:19.610840 | TASK [local-log-download : Check API endpoint is defined]
2025-11-21 11:49:19.662473 | localhost | ok: All assertions passed
2025-11-21 11:49:19.670124 | 
2025-11-21 11:49:19.670228 | TASK [local-log-download : Create download script]
2025-11-21 11:49:20.183352 | localhost -> localhost | changed
2025-11-21 11:49:20.192735 | 
2025-11-21 11:49:20.192816 | TASK [Register quick-download link]
2025-11-21 11:49:20.222163 | localhost | ok
2025-11-21 11:49:20.272420 | 
2025-11-21 11:49:20.272544 | PLAY [logserver.rdoproject.org]
2025-11-21 11:49:20.283576 | 
2025-11-21 11:49:20.283643 | TASK [Set zuul-log-path fact]
2025-11-21 11:49:20.310679 | logserver.rdoproject.org | ok
2025-11-21 11:49:20.320587 | 
2025-11-21 11:49:20.320660 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-21 11:49:20.357964 | logserver.rdoproject.org | ok
2025-11-21 11:49:20.365556 | 
2025-11-21 11:49:20.365646 | TASK [upload-logs : Create log directories]
2025-11-21 11:49:21.231832 | logserver.rdoproject.org | changed
2025-11-21 11:49:21.238660 | 
2025-11-21 11:49:21.238789 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-11-21 11:49:21.543894 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.008668
2025-11-21 11:49:21.556361 | 
2025-11-21 11:49:21.556613 | TASK [upload-logs : Upload logs to log server]
2025-11-21 11:49:22.320655 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-11-21 11:49:22.324377 | 
2025-11-21 11:49:22.324440 | LOOP [upload-logs : Compress console log and json output]
2025-11-21 11:49:22.386774 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-21 11:49:22.396563 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-21 11:49:22.409944 | 
2025-11-21 11:49:22.410137 | LOOP [upload-logs : Upload compressed console log and json output]
2025-11-21 11:49:22.456738 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-21 11:49:22.457223 | 
2025-11-21 11:49:22.459655 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-21 11:49:22.472864 | 
2025-11-21 11:49:22.472993 | LOOP [upload-logs : Upload console log and json output]