2025-10-02 14:12:52.073516 | Job console starting...
2025-10-02 14:12:52.083791 | Updating repositories
2025-10-02 14:12:52.115557 | Preparing job workspace
2025-10-02 14:12:55.916486 | Running Ansible setup...
2025-10-02 14:12:59.822980 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-02 14:13:00.376172 | 
2025-10-02 14:13:00.376275 | PLAY [localhost]
2025-10-02 14:13:00.384468 | 
2025-10-02 14:13:00.384536 | TASK [Gathering Facts]
2025-10-02 14:13:01.521088 | localhost | ok
2025-10-02 14:13:01.546051 | 
2025-10-02 14:13:01.546183 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-02 14:13:02.100922 | localhost -> localhost | changed
2025-10-02 14:13:02.106754 | 
2025-10-02 14:13:02.106828 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-02 14:13:02.927824 | localhost -> localhost | changed
2025-10-02 14:13:02.936583 | 
2025-10-02 14:13:02.936650 | TASK [Setup log path fact]
2025-10-02 14:13:02.954777 | localhost | ok
2025-10-02 14:13:02.966550 | 
2025-10-02 14:13:02.966619 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-02 14:13:02.992502 | localhost | ok
2025-10-02 14:13:03.009380 | 
2025-10-02 14:13:03.009601 | TASK [emit-job-header : Print job information]
2025-10-02 14:13:03.038854 | # Job Information
2025-10-02 14:13:03.039022 | Ansible Version: 2.15.12
2025-10-02 14:13:03.039058 | Job: cifmw-molecule-fix_python_encodings
2025-10-02 14:13:03.039086 | Pipeline: github-check
2025-10-02 14:13:03.039111 | Executor: ze04.softwarefactory-project.io
2025-10-02 14:13:03.039135 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3362
2025-10-02 14:13:03.039161 | Log URL (when completed): https://logserver.rdoproject.org/62c/rdoproject.org/62c6ca518a4040688148fe8d710aa378/
2025-10-02 14:13:03.039186 | Event ID: be9565c0-9f99-11f0-9e8d-1085adf9bc04
2025-10-02 14:13:03.045433 | 
2025-10-02 14:13:03.045503 | LOOP [emit-job-header : Print node information]
2025-10-02 14:13:03.160922 | localhost | ok:
2025-10-02 14:13:03.161207 | localhost | # Node Information
2025-10-02 14:13:03.161240 | localhost | Inventory Hostname: controller
2025-10-02 14:13:03.161266 | localhost | Hostname: np0005466320
2025-10-02 14:13:03.161285 | localhost | Username: zuul
2025-10-02 14:13:03.161306 | localhost | Distro: CentOS 9
2025-10-02 14:13:03.161325 | localhost | Provider: vexxhost-nodepool-tripleo
2025-10-02 14:13:03.161342 | localhost | Region: RegionOne
2025-10-02 14:13:03.161358 | localhost | Label: cloud-centos-9-stream-tripleo
2025-10-02 14:13:03.161375 | localhost | Product Name: OpenStack Nova
2025-10-02 14:13:03.161391 | localhost | Interface IP: 38.102.83.200
2025-10-02 14:13:03.191399 | 
2025-10-02 14:13:03.191493 | PLAY [all]
2025-10-02 14:13:03.197840 | 
2025-10-02 14:13:03.197903 | TASK [Gather network facts]
2025-10-02 14:13:03.685021 | controller | ok
2025-10-02 14:13:03.714429 | 
2025-10-02 14:13:03.714516 | TASK [include_role : start-zuul-console]
2025-10-02 14:13:03.738705 | controller | ok
2025-10-02 14:13:03.751885 | 
2025-10-02 14:13:03.751951 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-02 14:13:04.199013 | controller | ok
2025-10-02 14:13:04.208149 | 
2025-10-02 14:13:04.208213 | TASK [include_role : add-build-sshkey]
2025-10-02 14:13:04.234060 | controller | ok
2025-10-02 14:13:04.257065 | 
2025-10-02 14:13:04.257161 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-02 14:13:04.586136 | controller -> localhost | ok
2025-10-02 14:13:04.593697 | 
2025-10-02 14:13:04.593831 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-02 14:13:04.613388 | controller | ok
2025-10-02 14:13:04.640810 | controller | included: /var/lib/zuul/builds/62c6ca518a4040688148fe8d710aa378/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-02 14:13:04.647816 | 
2025-10-02 14:13:04.647877 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-02 14:13:05.553488 | controller -> localhost | Generating public/private rsa key pair.
2025-10-02 14:13:05.553724 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/62c6ca518a4040688148fe8d710aa378/work/62c6ca518a4040688148fe8d710aa378_id_rsa.
2025-10-02 14:13:05.553761 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/62c6ca518a4040688148fe8d710aa378/work/62c6ca518a4040688148fe8d710aa378_id_rsa.pub.
2025-10-02 14:13:05.553784 | controller -> localhost | The key fingerprint is:
2025-10-02 14:13:05.553803 | controller -> localhost | SHA256:dp73CcwbhMAqXDV8bMmYqMovuK5cItPtcRCyYVMzeLQ zuul-build-sshkey
2025-10-02 14:13:05.553822 | controller -> localhost | The key's randomart image is:
2025-10-02 14:13:05.553840 | controller -> localhost | +---[RSA 3072]----+
2025-10-02 14:13:05.553857 | controller -> localhost | |   o=  oo= .     |
2025-10-02 14:13:05.553876 | controller -> localhost | |  ...+.o+.*      |
2025-10-02 14:13:05.553893 | controller -> localhost | |  =.E.. oo       |
2025-10-02 14:13:05.553911 | controller -> localhost | | . *.o . . .     |
2025-10-02 14:13:05.553928 | controller -> localhost | | ...+ . S o .    |
2025-10-02 14:13:05.553945 | controller -> localhost | | .o. o . o =     |
2025-10-02 14:13:05.553962 | controller -> localhost | |oo.oo .   o *    |
2025-10-02 14:13:05.553979 | controller -> localhost | |+.+..o     . = . |
2025-10-02 14:13:05.553997 | controller -> localhost | |=+ ..       . o  |
2025-10-02 14:13:05.554013 | controller -> localhost | +----[SHA256]-----+
2025-10-02 14:13:05.554059 | controller -> localhost | ok: Runtime: 0:00:00.432814
2025-10-02 14:13:05.559827 | 
2025-10-02 14:13:05.559887 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-02 14:13:05.584849 | controller | ok
2025-10-02 14:13:05.594833 | controller | included: /var/lib/zuul/builds/62c6ca518a4040688148fe8d710aa378/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-02 14:13:05.604768 | 
2025-10-02 14:13:05.604830 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-02 14:13:05.633023 | controller | skipping: Conditional result was False
2025-10-02 14:13:05.640962 | 
2025-10-02 14:13:05.641044 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-02 14:13:06.137299 | controller | changed
2025-10-02 14:13:06.142423 | 
2025-10-02 14:13:06.142494 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-02 14:13:06.364382 | controller | ok
2025-10-02 14:13:06.369376 | 
2025-10-02 14:13:06.369437 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-02 14:13:07.278860 | controller | changed
2025-10-02 14:13:07.284556 | 
2025-10-02 14:13:07.284620 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-02 14:13:08.137194 | controller | changed
2025-10-02 14:13:08.155579 | 
2025-10-02 14:13:08.155658 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-02 14:13:08.201810 | controller | skipping: Conditional result was False
2025-10-02 14:13:08.207200 | 
2025-10-02 14:13:08.207265 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-02 14:13:08.814910 | controller -> localhost | changed
2025-10-02 14:13:08.827996 | 
2025-10-02 14:13:08.828087 | TASK [add-build-sshkey : Add back temp key]
2025-10-02 14:13:09.710547 | controller -> localhost | Identity added: /var/lib/zuul/builds/62c6ca518a4040688148fe8d710aa378/work/62c6ca518a4040688148fe8d710aa378_id_rsa (zuul-build-sshkey)
2025-10-02 14:13:09.710760 | controller -> localhost | ok: Runtime: 0:00:00.031172
2025-10-02 14:13:09.728827 | 
2025-10-02 14:13:09.728950 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-02 14:13:10.378064 | controller | ok
2025-10-02 14:13:10.382829 | 
2025-10-02 14:13:10.382890 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-02 14:13:10.438380 | controller | skipping: Conditional result was False
2025-10-02 14:13:10.449596 | 
2025-10-02 14:13:10.449724 | TASK [include_role : validate-host]
2025-10-02 14:13:10.512778 | controller | ok
2025-10-02 14:13:10.547652 | 
2025-10-02 14:13:10.547767 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-02 14:13:10.598728 | controller | ok
2025-10-02 14:13:10.647330 | 
2025-10-02 14:13:10.647444 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-02 14:13:10.905405 | controller -> localhost | ok
2025-10-02 14:13:10.911665 | 
2025-10-02 14:13:10.911744 | TASK [validate-host : Collect information about the host]
2025-10-02 14:13:11.800659 | controller | ok
2025-10-02 14:13:11.810045 | 
2025-10-02 14:13:11.810140 | TASK [validate-host : Sanitize hostname]
2025-10-02 14:13:11.879139 | controller | ok
2025-10-02 14:13:11.884018 | 
2025-10-02 14:13:11.884088 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-02 14:13:12.963931 | controller -> localhost | changed
2025-10-02 14:13:12.969510 | 
2025-10-02 14:13:12.969572 | TASK [validate-host : Collect information about zuul worker]
2025-10-02 14:13:13.486425 | controller | ok
2025-10-02 14:13:13.491297 | 
2025-10-02 14:13:13.491365 | TASK [validate-host : Write out all zuul information for each host]
2025-10-02 14:13:14.046604 | controller -> localhost | changed
2025-10-02 14:13:14.058344 | 
2025-10-02 14:13:14.058406 | TASK [include_role : prepare-workspace-openshift]
2025-10-02 14:13:14.081912 | controller | skipping: Conditional result was False
2025-10-02 14:13:14.091060 | 
2025-10-02 14:13:14.091132 | TASK [include_role : remove-zuul-sshkey]
2025-10-02 14:13:14.115012 | controller | skipping: Conditional result was False
2025-10-02 14:13:14.121850 | 
2025-10-02 14:13:14.121920 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-02 14:13:14.432768 | controller | ok: "logs"
2025-10-02 14:13:14.433496 | controller | ok: All items complete
2025-10-02 14:13:14.433583 | 
2025-10-02 14:13:14.658748 | controller | ok: "artifacts"
2025-10-02 14:13:14.924896 | controller | ok: "docs"
2025-10-02 14:13:14.934791 | 
2025-10-02 14:13:14.934904 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-02 14:13:15.250215 | controller | changed: "logs"
2025-10-02 14:13:15.507155 | controller | changed: "artifacts"
2025-10-02 14:13:15.745961 | controller | changed: "docs"
2025-10-02 14:13:15.777953 | 
2025-10-02 14:13:15.778035 | PLAY RECAP
2025-10-02 14:13:15.778079 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-02 14:13:15.778109 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-02 14:13:15.778127 | 
2025-10-02 14:13:15.897617 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-02 14:13:15.898507 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-10-02 14:13:16.716082 | 
2025-10-02 14:13:16.716200 | PLAY [localhost]
2025-10-02 14:13:16.748611 | 
2025-10-02 14:13:16.748739 | TASK [configure-ssh-config-fips : Ensure ssh config directory]
2025-10-02 14:13:17.181863 | localhost | ok
2025-10-02 14:13:17.198371 | 
2025-10-02 14:13:17.198488 | TASK [configure-ssh-config-fips : Setup ssh config]
2025-10-02 14:13:18.140131 | localhost | changed
2025-10-02 14:13:18.161048 | 
2025-10-02 14:13:18.161136 | PLAY [all]
2025-10-02 14:13:18.176410 | 
2025-10-02 14:13:18.176472 | TASK [include_role : prepare-workspace]
2025-10-02 14:13:18.198232 | controller | ok
2025-10-02 14:13:18.213419 | 
2025-10-02 14:13:18.213497 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-02 14:13:18.619787 | controller | ok
2025-10-02 14:13:18.627209 | 
2025-10-02 14:13:18.627294 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-02 14:13:21.161785 | controller | Output suppressed because no_log was given
2025-10-02 14:13:21.171761 | 
2025-10-02 14:13:21.171829 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-02 14:13:21.495115 | controller | changed: "logs"
2025-10-02 14:13:21.700944 | controller | changed: "artifacts"
2025-10-02 14:13:21.948658 | controller | changed: "docs"
2025-10-02 14:13:21.964386 | 
2025-10-02 14:13:21.964508 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-02 14:13:22.254141 | controller | changed: "logs"
2025-10-02 14:13:22.254366 | controller | changed: All items complete
2025-10-02 14:13:22.254394 | 
2025-10-02 14:13:22.525518 | controller | changed: "artifacts"
2025-10-02 14:13:22.756743 | controller | changed: "docs"
2025-10-02 14:13:22.770582 | 
2025-10-02 14:13:22.772725 | TASK [Check if worker can sudo]
2025-10-02 14:13:23.353073 | controller | ok: Runtime: 0:00:00.055519
2025-10-02 14:13:23.360644 | 
2025-10-02 14:13:23.360747 | TASK [configure-mirrors : Gather needed facts]
2025-10-02 14:13:23.476222 | controller | skipping: Conditional result was False
2025-10-02 14:13:23.483228 | 
2025-10-02 14:13:23.483468 | TASK [configure-mirrors : Set up infrastructure mirrors]
2025-10-02 14:13:23.590196 | controller | ok
2025-10-02 14:13:23.652132 | controller | included: /var/lib/zuul/builds/62c6ca518a4040688148fe8d710aa378/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml
2025-10-02 14:13:23.663597 | 
2025-10-02 14:13:23.663698 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir]
2025-10-02 14:13:23.989904 | controller | ok
2025-10-02 14:13:24.000315 | 
2025-10-02 14:13:24.000384 | LOOP [configure-mirrors : Include OS-specific variables]
2025-10-02 14:13:24.064395 | controller | ok: "/var/lib/zuul/builds/62c6ca518a4040688148fe8d710aa378/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml"
2025-10-02 14:13:24.083271 | 
2025-10-02 14:13:24.083367 | TASK [configure-mirrors : Install /etc/pip.conf configuration]
2025-10-02 14:13:25.101573 | controller | changed
2025-10-02 14:13:25.112433 | 
2025-10-02 14:13:25.112532 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors]
2025-10-02 14:13:25.172102 | controller | ok: "/var/lib/zuul/builds/62c6ca518a4040688148fe8d710aa378/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml"
2025-10-02 14:13:25.172332 | controller | ok: All items complete
2025-10-02 14:13:25.172373 | 
2025-10-02 14:13:25.234105 | controller | included: /var/lib/zuul/builds/62c6ca518a4040688148fe8d710aa378/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml
2025-10-02 14:13:25.242789 | 
2025-10-02 14:13:25.242869 | LOOP [configure-mirrors : Install CentOS 9 repository files]
2025-10-02 14:13:26.322240 | controller | changed: "etc/yum.repos.d/centos.repo"
2025-10-02 14:13:27.252030 | controller | changed: "etc/yum.repos.d/centos-addons.repo"
2025-10-02 14:13:27.266002 | 
2025-10-02 14:13:27.266128 | TASK [configure-mirrors : Disable deltrarpm]
2025-10-02 14:13:27.806897 | controller | changed: section and option added
2025-10-02 14:13:27.905544 | 
2025-10-02 14:13:27.905663 | LOOP [configure-mirrors : Update yum/dnf cache]
2025-10-02 14:13:29.388832 | controller | 29 files removed
2025-10-02 14:13:29.389209 | controller | ok: Item: dnf clean all Runtime: 0:00:00.998080
2025-10-02 14:13:29.389252 | controller | changed: All items complete
2025-10-02 14:13:29.389274 | 
2025-10-02 14:13:40.239264 | 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-02 14:13:40.239366 | controller | DNF version: 4.14.0
2025-10-02 14:13:40.239391 | controller | cachedir: /var/cache/dnf
2025-10-02 14:13:40.239411 | controller | Making cache files for all metadata files.
2025-10-02 14:13:40.239429 | controller | baseos: has expired and will be refreshed.
2025-10-02 14:13:40.239445 | controller | appstream: has expired and will be refreshed.
2025-10-02 14:13:40.239462 | controller | crb: has expired and will be refreshed.
2025-10-02 14:13:40.239496 | controller | extras-common: has expired and will be refreshed.
2025-10-02 14:13:40.239512 | controller | repo: downloading from remote: baseos
2025-10-02 14:13:40.239528 | controller | CentOS Stream 9 - BaseOS                         78 MB/s | 8.8 MB     00:00
2025-10-02 14:13:40.239544 | controller | baseos: using metadata from Tue 30 Sep 2025 01:12:56 PM EDT.
2025-10-02 14:13:40.239560 | controller | repo: downloading from remote: appstream
2025-10-02 14:13:40.239575 | controller | CentOS Stream 9 - AppStream                      75 MB/s |  25 MB     00:00
2025-10-02 14:13:40.239636 | controller | appstream: using metadata from Tue 30 Sep 2025 01:16:29 PM EDT.
2025-10-02 14:13:40.239656 | controller | repo: downloading from remote: crb
2025-10-02 14:13:40.239672 | controller | CentOS Stream 9 - CRB                            72 MB/s | 7.1 MB     00:00
2025-10-02 14:13:40.239709 | controller | crb: using metadata from Tue 30 Sep 2025 01:20:54 PM EDT.
2025-10-02 14:13:40.239727 | controller | repo: downloading from remote: extras-common
2025-10-02 14:13:40.239744 | controller | CentOS Stream 9 - Extras packages               1.4 MB/s |  20 kB     00:00
2025-10-02 14:13:40.239760 | controller | extras-common: using metadata from Mon 15 Sep 2025 02:32:43 PM EDT.
2025-10-02 14:13:40.239778 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)'
2025-10-02 14:13:40.239796 | controller | Completion plugin: Generating completion cache...
2025-10-02 14:13:40.239812 | controller | Metadata cache created.
2025-10-02 14:13:40.239837 | controller | ok: Item: dnf makecache -v Runtime: 0:00:10.552305
2025-10-02 14:13:40.257564 | 
2025-10-02 14:13:40.257661 | PLAY RECAP
2025-10-02 14:13:40.257738 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-02 14:13:40.257767 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-02 14:13:40.257787 | 
2025-10-02 14:13:40.389577 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-10-02 14:13:40.390393 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-10-02 14:13:41.041759 | 
2025-10-02 14:13:41.041875 | PLAY [all]
2025-10-02 14:13:41.089883 | 
2025-10-02 14:13:41.090046 | TASK [Install binary dependencies]
2025-10-02 14:13:41.250549 | controller | ok
2025-10-02 14:13:41.299601 | 
2025-10-02 14:13:41.299773 | TASK [bindep : Include find tasks]
2025-10-02 14:13:41.408621 | controller | ok
2025-10-02 14:13:41.422581 | controller | included: /var/lib/zuul/builds/62c6ca518a4040688148fe8d710aa378/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-10-02 14:13:41.428641 | 
2025-10-02 14:13:41.428741 | TASK [bindep : Look for bindep.txt]
2025-10-02 14:13:41.958584 | controller | ok
2025-10-02 14:13:41.966363 | 
2025-10-02 14:13:41.966468 | TASK [bindep : Define bindep_file fact]
2025-10-02 14:13:42.027119 | controller | ok
2025-10-02 14:13:42.057329 | 
2025-10-02 14:13:42.059449 | TASK [bindep : Look for other-requirements.txt]
2025-10-02 14:13:42.094891 | controller | skipping: Conditional result was False
2025-10-02 14:13:42.103190 | 
2025-10-02 14:13:42.103317 | TASK [bindep : Define bindep_file fact]
2025-10-02 14:13:42.188240 | controller | skipping: Conditional result was False
2025-10-02 14:13:42.195382 | 
2025-10-02 14:13:42.195473 | TASK [bindep : Look for bindep fallback file]
2025-10-02 14:13:42.219482 | controller | skipping: Conditional result was False
2025-10-02 14:13:42.226274 | 
2025-10-02 14:13:42.226386 | TASK [bindep : Define bindep_file fact]
2025-10-02 14:13:42.303725 | controller | skipping: Conditional result was False
2025-10-02 14:13:42.311771 | 
2025-10-02 14:13:42.311868 | TASK [bindep : Include bindep tasks]
2025-10-02 14:13:42.420049 | controller | ok
2025-10-02 14:13:42.432510 | controller | included: /var/lib/zuul/builds/62c6ca518a4040688148fe8d710aa378/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml
2025-10-02 14:13:42.448386 | 
2025-10-02 14:13:42.448466 | TASK [bindep : Look for bindep command]
2025-10-02 14:13:42.508640 | controller | skipping: Conditional result was False
2025-10-02 14:13:42.515634 | 
2025-10-02 14:13:42.515728 | TASK [bindep : Check for system bindep]
2025-10-02 14:13:43.618970 | controller | ok: Runtime: 0:00:00.008185
2025-10-02 14:13:43.631445 | 
2025-10-02 14:13:43.631542 | TASK [bindep : Define bindep_command fact]
2025-10-02 14:13:43.723307 | controller | skipping: Conditional result was False
2025-10-02 14:13:43.733845 | 
2025-10-02 14:13:43.733929 | TASK [bindep : Include install tasks]
2025-10-02 14:13:43.807558 | controller | ok
2025-10-02 14:13:43.819154 | controller | included: /var/lib/zuul/builds/62c6ca518a4040688148fe8d710aa378/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml
2025-10-02 14:13:43.846996 | 
2025-10-02 14:13:43.847122 | TASK [bindep : Create temp dir for bindep]
2025-10-02 14:13:44.474090 | controller | changed
2025-10-02 14:13:44.493527 | 
2025-10-02 14:13:44.493629 | TASK [Ensure we have pip dependencies]
2025-10-02 14:13:44.537505 | controller | ok
2025-10-02 14:13:44.622575 | 
2025-10-02 14:13:44.623747 | TASK [ensure-pip : Check if pip is installed]
2025-10-02 10:13:44.999487 | controller | /usr/bin/pip3
2025-10-02 10:13:45.022188 | controller | /usr/bin/python3: No module named wheel
2025-10-02 14:13:45.198203 | controller | ok: Runtime: 0:00:00.035233
2025-10-02 14:13:45.205086 | 
2025-10-02 14:13:45.205168 | LOOP [ensure-pip : Install pip from packages]
2025-10-02 14:13:45.302579 | controller | ok: "/var/lib/zuul/builds/62c6ca518a4040688148fe8d710aa378/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml"
2025-10-02 14:13:45.313352 | controller | included: /var/lib/zuul/builds/62c6ca518a4040688148fe8d710aa378/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml
2025-10-02 14:13:45.336596 | 
2025-10-02 14:13:45.336711 | TASK [ensure-pip : Install Python 3 pip]
2025-10-02 14:13:48.243645 | controller | changed
2025-10-02 14:13:48.256349 | 
2025-10-02 14:13:48.256475 | TASK [ensure-pip : Check for EPEL repository]
2025-10-02 14:13:48.328569 | controller | skipping: Conditional result was False
2025-10-02 14:13:48.336924 | 
2025-10-02 14:13:48.337024 | TASK [ensure-pip : Fail with instructions when EPEL is not installed]
2025-10-02 14:13:48.405511 | controller | skipping: Conditional result was False
2025-10-02 14:13:48.429116 | 
2025-10-02 14:13:48.429272 | TASK [ensure-pip : Install Python 2 pip]
2025-10-02 14:13:48.566296 | controller | skipping: Conditional result was False
2025-10-02 14:13:48.573806 | 
2025-10-02 14:13:48.573902 | TASK [ensure-pip : Ensure setuptools]
2025-10-02 14:13:48.670191 | controller | skipping: Conditional result was False
2025-10-02 14:13:48.680823 | 
2025-10-02 14:13:48.680929 | TASK [ensure-pip : Check for ensurepip module]
2025-10-02 14:13:49.285072 | controller | skipping: Conditional result was False
2025-10-02 14:13:49.292085 | 
2025-10-02 14:13:49.292154 | TASK [ensure-pip : Ensure python3-venv]
2025-10-02 14:13:49.346939 | controller | skipping: Conditional result was False
2025-10-02 14:13:49.353983 | 
2025-10-02 14:13:49.354059 | TASK [ensure-pip : Install pip from source]
2025-10-02 14:13:49.392147 | controller | skipping: Conditional result was False
2025-10-02 14:13:49.399751 | 
2025-10-02 14:13:49.399838 | TASK [ensure-pip : Probe for venv python full path]
2025-10-02 10:13:49.881213 | controller | /usr/bin/python3
2025-10-02 14:13:50.018334 | controller | ok: Runtime: 0:00:00.005823
2025-10-02 14:13:50.039314 | 
2025-10-02 14:13:50.039457 | TASK [ensure-pip : Set host default]
2025-10-02 14:13:50.135483 | controller | ok
2025-10-02 14:13:50.148199 | 
2025-10-02 14:13:50.148289 | TASK [ensure-pip : Set ensure_pip_virtualenv_command]
2025-10-02 14:13:50.263446 | controller | ok
2025-10-02 14:13:50.288358 | 
2025-10-02 14:13:50.288470 | TASK [bindep : Install bindep into temporary venv]
2025-10-02 14:13:55.037881 | controller | changed
2025-10-02 14:13:55.046067 | 
2025-10-02 14:13:55.046192 | TASK [bindep : Define bindep_command]
2025-10-02 14:13:55.066247 | controller | ok
2025-10-02 14:13:55.071167 | 
2025-10-02 14:13:55.071227 | LOOP [bindep : Include package tasks]
2025-10-02 14:13:55.116645 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt"
2025-10-02 14:13:55.116917 | controller | ok: All items complete
2025-10-02 14:13:55.116953 | 
2025-10-02 14:13:55.136463 | controller | included: /var/lib/zuul/builds/62c6ca518a4040688148fe8d710aa378/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml
2025-10-02 14:13:55.153932 | 
2025-10-02 14:13:55.154052 | TASK [bindep : Define bindep_run fact]
2025-10-02 14:13:55.190719 | controller | ok
2025-10-02 14:13:55.199995 | 
2025-10-02 14:13:55.200085 | TASK [bindep : Get list of packages to install from bindep]
2025-10-02 10:13:56.602558 | controller | podman
2025-10-02 10:13:56.653010 | controller | python3-jmespath
2025-10-02 10:13:56.653102 | controller | python3-libvirt
2025-10-02 10:13:56.653119 | controller | python3-lxml
2025-10-02 10:13:56.653220 | controller | python3-netaddr
2025-10-02 14:13:56.736904 | controller | ok: Runtime: 0:00:01.180335
2025-10-02 14:13:56.742703 | 
2025-10-02 14:13:56.742767 | TASK [bindep : Install distro packages from bindep]
2025-10-02 14:15:02.155878 | controller | changed
2025-10-02 14:15:02.162473 | 
2025-10-02 14:15:02.162550 | TASK [bindep : Check that packages are installed]
2025-10-02 14:15:03.696599 | controller | ok: Runtime: 0:00:01.107398
2025-10-02 14:15:03.710098 | 
2025-10-02 14:15:03.710249 | TASK [bindep : Fail if we cannot install all packages]
2025-10-02 14:15:03.737967 | controller | skipping: Conditional result was False
2025-10-02 14:15:03.767028 | 
2025-10-02 14:15:03.767156 | TASK [Run test-setup role]
2025-10-02 14:15:03.791043 | controller | ok
2025-10-02 14:15:03.821103 | 
2025-10-02 14:15:03.821257 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-10-02 14:15:04.091106 | controller | ok
2025-10-02 14:15:04.102454 | 
2025-10-02 14:15:04.102587 | TASK [test-setup : Run tools/test-setup.sh]
2025-10-02 14:15:04.646169 | controller | skipping: Conditional result was False
2025-10-02 14:15:04.671648 | 
2025-10-02 14:15:04.671873 | TASK [bindep : Remove bindep temp dir]
2025-10-02 14:15:05.099491 | controller | ok
2025-10-02 14:15:05.110332 | 
2025-10-02 14:15:05.110384 | PLAY RECAP
2025-10-02 14:15:05.110423 | controller | ok: 22 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0
2025-10-02 14:15:05.110443 | 
2025-10-02 14:15:05.300009 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-10-02 14:15:05.302819 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2025-10-02 14:15:05.885194 | 
2025-10-02 14:15:05.885311 | PLAY [all]
2025-10-02 14:15:05.904901 | 
2025-10-02 14:15:05.905004 | TASK [Abort when test_command variable is undefined]
2025-10-02 14:15:05.929771 | controller | skipping: Conditional result was False
2025-10-02 14:15:05.935249 | 
2025-10-02 14:15:05.935324 | TASK [Convert test_command to list]
2025-10-02 14:15:05.978933 | controller | skipping: Conditional result was False
2025-10-02 14:15:05.985976 | 
2025-10-02 14:15:05.986055 | TASK [Use test_command list]
2025-10-02 14:15:06.039289 | controller | ok
2025-10-02 14:15:06.048799 | 
2025-10-02 14:15:06.048900 | LOOP [Run test_command]
2025-10-02 14:15:06.465098 | controller | no check to run
2025-10-02 14:15:06.465500 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.007083
2025-10-02 14:15:06.495936 | 
2025-10-02 14:15:06.496033 | PLAY RECAP
2025-10-02 14:15:06.496076 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-02 14:15:06.496096 | 
2025-10-02 14:15:06.617133 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2025-10-02 14:15:06.621371 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-10-02 14:15:07.246058 | 
2025-10-02 14:15:07.246182 | PLAY [all]
2025-10-02 14:15:07.268469 | 
2025-10-02 14:15:07.268599 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-10-02 14:15:07.596389 | controller | changed: non-zero return code
2025-10-02 14:15:07.604145 | 
2025-10-02 14:15:07.604260 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-10-02 14:15:07.620919 | controller | skipping: Conditional result was False
2025-10-02 14:15:07.629358 | 
2025-10-02 14:15:07.629451 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-10-02 14:15:07.674875 | 
2025-10-02 14:15:07.675036 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-10-02 14:15:07.697839 | 
2025-10-02 14:15:07.698006 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-10-02 14:15:07.714987 | controller | skipping: Conditional result was False
2025-10-02 14:15:07.727069 | 
2025-10-02 14:15:07.727185 | LOOP [fetch-subunit-output : Generate subunit file]
2025-10-02 14:15:07.748878 | 
2025-10-02 14:15:07.749118 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-10-02 14:15:07.778580 | controller | skipping: Conditional result was False
2025-10-02 14:15:07.786218 | 
2025-10-02 14:15:07.786326 | TASK [fetch-subunit-output : Remove the temporary file]
2025-10-02 14:15:07.820948 | controller | skipping: Conditional result was False
2025-10-02 14:15:07.833043 | 
2025-10-02 14:15:07.833183 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-10-02 14:15:07.857664 | controller | skipping: Conditional result was False
2025-10-02 14:15:07.889163 | 
2025-10-02 14:15:07.889265 | PLAY RECAP
2025-10-02 14:15:07.889309 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-10-02 14:15:07.889328 | 
2025-10-02 14:15:08.007965 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-10-02 14:15:08.011267 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-02 14:15:08.569234 | 
2025-10-02 14:15:08.569345 | PLAY [all]
2025-10-02 14:15:08.588487 | 
2025-10-02 14:15:08.588565 | TASK [include_role : fetch-output]
2025-10-02 14:15:08.621277 | controller | ok
2025-10-02 14:15:08.647826 | 
2025-10-02 14:15:08.647932 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-02 14:15:08.702705 | controller | skipping: Conditional result was False
2025-10-02 14:15:08.709021 | 
2025-10-02 14:15:08.709341 | TASK [fetch-output : Set log path for single node]
2025-10-02 14:15:08.739430 | controller | ok
2025-10-02 14:15:08.744818 | 
2025-10-02 14:15:08.744885 | LOOP [fetch-output : Ensure local output dirs]
2025-10-02 14:15:09.162665 | controller -> localhost | ok: "/var/lib/zuul/builds/62c6ca518a4040688148fe8d710aa378/work/logs"
2025-10-02 14:15:09.459167 | controller -> localhost | changed: "/var/lib/zuul/builds/62c6ca518a4040688148fe8d710aa378/work/artifacts"
2025-10-02 14:15:09.713116 | controller -> localhost | changed: "/var/lib/zuul/builds/62c6ca518a4040688148fe8d710aa378/work/docs"
2025-10-02 14:15:09.743925 | 
2025-10-02 14:15:09.744117 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-02 14:15:10.463522 | controller | changed: .d..t...... ./
2025-10-02 14:15:10.464037 | controller | changed: All items complete
2025-10-02 14:15:10.464077 | 
2025-10-02 14:15:10.953974 | controller | changed: .d..t...... ./
2025-10-02 14:15:11.439230 | controller | changed: .d..t...... ./
2025-10-02 14:15:11.456622 | 
2025-10-02 14:15:11.456734 | TASK [include_role : fetch-output-openshift]
2025-10-02 14:15:11.482500 | controller | skipping: Conditional result was False
2025-10-02 14:15:11.490003 | 
2025-10-02 14:15:11.490277 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-02 14:15:11.975638 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.010864
2025-10-02 14:15:12.221564 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007867
2025-10-02 14:15:12.260040 | 
2025-10-02 14:15:12.260194 | PLAY [all]
2025-10-02 14:15:12.281092 | 
2025-10-02 14:15:12.281245 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-02 14:15:12.801916 | controller | changed
2025-10-02 14:15:12.851362 | 
2025-10-02 14:15:12.851471 | PLAY RECAP
2025-10-02 14:15:12.851513 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-02 14:15:12.851533 | 
2025-10-02 14:15:12.956658 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-02 14:15:12.958843 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-02 14:15:13.670206 | 
2025-10-02 14:15:13.670323 | PLAY [localhost]
2025-10-02 14:15:13.690198 | 
2025-10-02 14:15:13.690300 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-02 14:15:14.077443 | localhost | changed
2025-10-02 14:15:14.088601 | 
2025-10-02 14:15:14.088700 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-02 14:15:14.119701 | localhost | ok
2025-10-02 14:15:14.135429 | 
2025-10-02 14:15:14.135504 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-02 14:15:14.499464 | localhost | changed
2025-10-02 14:15:14.504892 | 
2025-10-02 14:15:14.504962 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-02 14:15:15.155745 | localhost | changed
2025-10-02 14:15:15.161008 | 
2025-10-02 14:15:15.161075 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-02 14:15:15.646987 | localhost | Identity added: /var/lib/zuul/builds/62c6ca518a4040688148fe8d710aa378/work/tmp/ansible.zfv7fj1x (/var/lib/zuul/builds/62c6ca518a4040688148fe8d710aa378/work/tmp/ansible.zfv7fj1x)
2025-10-02 14:15:15.647187 | localhost | ok: Runtime: 0:00:00.009479
2025-10-02 14:15:15.651666 | 
2025-10-02 14:15:15.651747 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-02 14:15:15.913884 | localhost | ok: Runtime: 0:00:00.011474
2025-10-02 14:15:15.928062 | 
2025-10-02 14:15:15.928183 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-02 14:15:16.001278 | localhost | changed
2025-10-02 14:15:16.005615 | 
2025-10-02 14:15:16.005701 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-02 14:15:16.414784 | localhost | changed
2025-10-02 14:15:16.441971 | 
2025-10-02 14:15:16.442074 | PLAY [localhost]
2025-10-02 14:15:16.464613 | 
2025-10-02 14:15:16.464749 | TASK [Generate bulk log download script]
2025-10-02 14:15:16.503937 | localhost | ok
2025-10-02 14:15:16.517587 | 
2025-10-02 14:15:16.517663 | TASK [local-log-download : Check API endpoint is defined]
2025-10-02 14:15:16.546240 | localhost | ok: All assertions passed
2025-10-02 14:15:16.552907 | 
2025-10-02 14:15:16.552984 | TASK [local-log-download : Create download script]
2025-10-02 14:15:16.950253 | localhost -> localhost | changed
2025-10-02 14:15:16.960438 | 
2025-10-02 14:15:16.960524 | TASK [Register quick-download link]
2025-10-02 14:15:16.980010 | localhost | ok
2025-10-02 14:15:17.030867 | 
2025-10-02 14:15:17.030964 | PLAY [logserver.rdoproject.org]
2025-10-02 14:15:17.043923 | 
2025-10-02 14:15:17.044119 | TASK [Set zuul-log-path fact]
2025-10-02 14:15:17.060221 | logserver.rdoproject.org | ok
2025-10-02 14:15:17.069815 | 
2025-10-02 14:15:17.069877 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-02 14:15:17.096304 | logserver.rdoproject.org | ok
2025-10-02 14:15:17.103128 | 
2025-10-02 14:15:17.103200 | TASK [upload-logs : Create log directories]
2025-10-02 14:15:17.766864 | logserver.rdoproject.org | changed
2025-10-02 14:15:17.771660 | 
2025-10-02 14:15:17.771779 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-02 14:15:18.040745 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.014506
2025-10-02 14:15:18.045654 | 
2025-10-02 14:15:18.045767 | TASK [upload-logs : Upload logs to log server]
2025-10-02 14:15:18.851074 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-02 14:15:18.855014 | 
2025-10-02 14:15:18.855074 | LOOP [upload-logs : Compress console log and json output]
2025-10-02 14:15:18.906312 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 14:15:18.907866 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 14:15:18.913474 | 
2025-10-02 14:15:18.913545 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-02 14:15:18.959132 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 14:15:18.959430 | 
2025-10-02 14:15:18.966461 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-02 14:15:18.973209 | 
2025-10-02 14:15:18.973281 | LOOP [upload-logs : Upload console log and json output]