2025-12-05 15:18:35.288624 | Job console starting...
2025-12-05 15:18:35.296456 | Updating repositories
2025-12-05 15:18:35.328404 | Preparing job workspace
2025-12-05 15:18:40.078133 | Running Ansible setup...
2025-12-05 15:18:44.434422 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 15:18:45.070203 | 
2025-12-05 15:18:45.070311 | PLAY [localhost]
2025-12-05 15:18:45.079096 | 
2025-12-05 15:18:45.079188 | TASK [Gathering Facts]
2025-12-05 15:18:46.146258 | localhost | ok
2025-12-05 15:18:46.160823 | 
2025-12-05 15:18:46.160906 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-05 15:18:46.780729 | localhost -> localhost | changed
2025-12-05 15:18:46.786380 | 
2025-12-05 15:18:46.786453 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-05 15:18:48.302283 | localhost -> localhost | changed
2025-12-05 15:18:48.310818 | 
2025-12-05 15:18:48.310902 | TASK [Setup log path fact]
2025-12-05 15:18:48.339977 | localhost | ok
2025-12-05 15:18:48.359211 | 
2025-12-05 15:18:48.359285 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 15:18:48.420511 | localhost | ok
2025-12-05 15:18:48.432572 | 
2025-12-05 15:18:48.432638 | TASK [emit-job-header : Print job information]
2025-12-05 15:18:48.520286 | # Job Information
2025-12-05 15:18:48.520421 | Ansible Version: 2.15.12
2025-12-05 15:18:48.520448 | Job: cifmw-molecule-fdp_update_container_images
2025-12-05 15:18:48.520468 | Pipeline: github-check
2025-12-05 15:18:48.520486 | Executor: ze04.softwarefactory-project.io
2025-12-05 15:18:48.520503 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3539
2025-12-05 15:18:48.520522 | Log URL (when completed): https://logserver.rdoproject.org/142/rdoproject.org/142a36bb88914c70b263d1cc2a857a7a/
2025-12-05 15:18:48.520541 | Event ID: 748a4b40-d1ed-11f0-8510-c0ded0ca5b3b
2025-12-05 15:18:48.528927 | 
2025-12-05 15:18:48.528995 | LOOP [emit-job-header : Print node information]
2025-12-05 15:18:48.632014 | localhost | ok:
2025-12-05 15:18:48.632181 | localhost | # Node Information
2025-12-05 15:18:48.632208 | localhost | Inventory Hostname: controller
2025-12-05 15:18:48.632232 | localhost | Hostname: np0005547220
2025-12-05 15:18:48.632252 | localhost | Username: zuul
2025-12-05 15:18:48.632272 | localhost | Distro: CentOS 9
2025-12-05 15:18:48.632289 | localhost | Provider: vexxhost-nodepool-tripleo
2025-12-05 15:18:48.632307 | localhost | Region: RegionOne
2025-12-05 15:18:48.632323 | localhost | Label: cloud-centos-9-stream-tripleo
2025-12-05 15:18:48.632340 | localhost | Product Name: OpenStack Nova
2025-12-05 15:18:48.632356 | localhost | Interface IP: 38.102.83.158
2025-12-05 15:18:48.658715 | 
2025-12-05 15:18:48.658769 | PLAY [all]
2025-12-05 15:18:48.666335 | 
2025-12-05 15:18:48.666398 | TASK [Gather network facts]
2025-12-05 15:18:49.154419 | controller | ok
2025-12-05 15:18:49.179365 | 
2025-12-05 15:18:49.179438 | TASK [include_role : start-zuul-console]
2025-12-05 15:18:49.217687 | controller | ok
2025-12-05 15:18:49.239038 | 
2025-12-05 15:18:49.239100 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-05 15:18:49.662805 | controller | ok
2025-12-05 15:18:49.673024 | 
2025-12-05 15:18:49.673092 | TASK [include_role : add-build-sshkey]
2025-12-05 15:18:49.745585 | controller | ok
2025-12-05 15:18:49.763546 | 
2025-12-05 15:18:49.763648 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-05 15:18:50.178328 | controller -> localhost | ok
2025-12-05 15:18:50.183878 | 
2025-12-05 15:18:50.183949 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-05 15:18:50.213106 | controller | ok
2025-12-05 15:18:50.236977 | controller | included: /var/lib/zuul/builds/142a36bb88914c70b263d1cc2a857a7a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-05 15:18:50.253972 | 
2025-12-05 15:18:50.254085 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-05 15:18:52.207473 | controller -> localhost | Generating public/private rsa key pair.
2025-12-05 15:18:52.207654 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/142a36bb88914c70b263d1cc2a857a7a/work/142a36bb88914c70b263d1cc2a857a7a_id_rsa.
2025-12-05 15:18:52.207703 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/142a36bb88914c70b263d1cc2a857a7a/work/142a36bb88914c70b263d1cc2a857a7a_id_rsa.pub.
2025-12-05 15:18:52.207728 | controller -> localhost | The key fingerprint is:
2025-12-05 15:18:52.207749 | controller -> localhost | SHA256:rEUvSZUV+YpSd8PGDlWw5uZvY7iG4mhc6/zkPMe2mmo zuul-build-sshkey
2025-12-05 15:18:52.207769 | controller -> localhost | The key's randomart image is:
2025-12-05 15:18:52.207788 | controller -> localhost | +---[RSA 3072]----+
2025-12-05 15:18:52.207808 | controller -> localhost | |          .ooo.o.|
2025-12-05 15:18:52.207827 | controller -> localhost | |         .. . .. |
2025-12-05 15:18:52.207846 | controller -> localhost | |        o    =o  |
2025-12-05 15:18:52.207865 | controller -> localhost | |       + o. ooB  |
2025-12-05 15:18:52.207884 | controller -> localhost | |        S..o *o. |
2025-12-05 15:18:52.207902 | controller -> localhost | |       o.o. .o.  |
2025-12-05 15:18:52.207920 | controller -> localhost | |      o ....o o  |
2025-12-05 15:18:52.207939 | controller -> localhost | |       ooE+o.* +.|
2025-12-05 15:18:52.207957 | controller -> localhost | |      ..+=+**o+.o|
2025-12-05 15:18:52.207975 | controller -> localhost | +----[SHA256]-----+
2025-12-05 15:18:52.208021 | controller -> localhost | ok: Runtime: 0:00:01.270788
2025-12-05 15:18:52.214186 | 
2025-12-05 15:18:52.214250 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-05 15:18:52.252877 | controller | ok
2025-12-05 15:18:52.268766 | controller | included: /var/lib/zuul/builds/142a36bb88914c70b263d1cc2a857a7a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-05 15:18:52.298304 | 
2025-12-05 15:18:52.298401 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-05 15:18:52.325464 | controller | skipping: Conditional result was False
2025-12-05 15:18:52.331123 | 
2025-12-05 15:18:52.331188 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-05 15:18:52.976572 | controller | changed
2025-12-05 15:18:52.981536 | 
2025-12-05 15:18:52.981600 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-05 15:18:53.256576 | controller | ok
2025-12-05 15:18:53.264891 | 
2025-12-05 15:18:53.264961 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-05 15:18:54.177563 | controller | changed
2025-12-05 15:18:54.191775 | 
2025-12-05 15:18:54.191863 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-05 15:18:55.167739 | controller | changed
2025-12-05 15:18:55.172817 | 
2025-12-05 15:18:55.172878 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-05 15:18:55.197075 | controller | skipping: Conditional result was False
2025-12-05 15:18:55.202882 | 
2025-12-05 15:18:55.202945 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-05 15:18:55.575957 | controller -> localhost | changed
2025-12-05 15:18:55.586273 | 
2025-12-05 15:18:55.586335 | TASK [add-build-sshkey : Add back temp key]
2025-12-05 15:18:56.078951 | controller -> localhost | Identity added: /var/lib/zuul/builds/142a36bb88914c70b263d1cc2a857a7a/work/142a36bb88914c70b263d1cc2a857a7a_id_rsa (zuul-build-sshkey)
2025-12-05 15:18:56.079152 | controller -> localhost | ok: Runtime: 0:00:00.019899
2025-12-05 15:18:56.087314 | 
2025-12-05 15:18:56.094984 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-05 15:18:56.524768 | controller | ok
2025-12-05 15:18:56.529354 | 
2025-12-05 15:18:56.529416 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-05 15:18:56.553828 | controller | skipping: Conditional result was False
2025-12-05 15:18:56.562805 | 
2025-12-05 15:18:56.562868 | TASK [include_role : validate-host]
2025-12-05 15:18:56.581638 | controller | ok
2025-12-05 15:18:56.627715 | 
2025-12-05 15:18:56.627827 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-05 15:18:56.664436 | controller | ok
2025-12-05 15:18:56.669093 | 
2025-12-05 15:18:56.669153 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-05 15:18:56.916453 | controller -> localhost | ok
2025-12-05 15:18:56.923262 | 
2025-12-05 15:18:56.923360 | TASK [validate-host : Collect information about the host]
2025-12-05 15:18:57.728756 | controller | ok
2025-12-05 15:18:57.743240 | 
2025-12-05 15:18:57.743303 | TASK [validate-host : Sanitize hostname]
2025-12-05 15:18:57.866736 | controller | ok
2025-12-05 15:18:57.871629 | 
2025-12-05 15:18:57.871716 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-05 15:18:58.623332 | controller -> localhost | changed
2025-12-05 15:18:58.629013 | 
2025-12-05 15:18:58.629076 | TASK [validate-host : Collect information about zuul worker]
2025-12-05 15:18:59.078672 | controller | ok
2025-12-05 15:18:59.084894 | 
2025-12-05 15:18:59.084976 | TASK [validate-host : Write out all zuul information for each host]
2025-12-05 15:18:59.648871 | controller -> localhost | changed
2025-12-05 15:18:59.660336 | 
2025-12-05 15:18:59.660411 | TASK [include_role : prepare-workspace-openshift]
2025-12-05 15:18:59.687203 | controller | skipping: Conditional result was False
2025-12-05 15:18:59.708322 | 
2025-12-05 15:18:59.708410 | TASK [include_role : remove-zuul-sshkey]
2025-12-05 15:18:59.753578 | controller | skipping: Conditional result was False
2025-12-05 15:18:59.759191 | 
2025-12-05 15:18:59.759257 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-05 15:19:00.055486 | controller | ok: "logs"
2025-12-05 15:19:00.055747 | controller | ok: All items complete
2025-12-05 15:19:00.055776 | 
2025-12-05 15:19:00.281916 | controller | ok: "artifacts"
2025-12-05 15:19:00.506300 | controller | ok: "docs"
2025-12-05 15:19:00.516029 | 
2025-12-05 15:19:00.516135 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-05 15:19:00.787629 | controller | changed: "logs"
2025-12-05 15:19:00.989611 | controller | changed: "artifacts"
2025-12-05 15:19:01.240639 | controller | changed: "docs"
2025-12-05 15:19:01.271440 | 
2025-12-05 15:19:01.271531 | PLAY RECAP
2025-12-05 15:19:01.271574 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-05 15:19:01.271599 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 15:19:01.271616 | 
2025-12-05 15:19:01.421552 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 15:19:01.422375 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-12-05 15:19:02.186899 | 
2025-12-05 15:19:02.187018 | PLAY [localhost]
2025-12-05 15:19:02.220116 | 
2025-12-05 15:19:02.220207 | TASK [configure-ssh-config-fips : Ensure ssh config directory]
2025-12-05 15:19:02.685088 | localhost | ok
2025-12-05 15:19:02.690127 | 
2025-12-05 15:19:02.690201 | TASK [configure-ssh-config-fips : Setup ssh config]
2025-12-05 15:19:03.360850 | localhost | changed
2025-12-05 15:19:03.381033 | 
2025-12-05 15:19:03.381086 | PLAY [all]
2025-12-05 15:19:03.401295 | 
2025-12-05 15:19:03.401354 | TASK [include_role : prepare-workspace]
2025-12-05 15:19:03.429776 | controller | ok
2025-12-05 15:19:03.443782 | 
2025-12-05 15:19:03.443851 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-05 15:19:03.888553 | controller | ok
2025-12-05 15:19:03.895311 | 
2025-12-05 15:19:03.895375 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-05 15:19:05.742177 | controller | Output suppressed because no_log was given
2025-12-05 15:19:05.752673 | 
2025-12-05 15:19:05.752754 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-05 15:19:06.022079 | controller | changed: "logs"
2025-12-05 15:19:06.320867 | controller | changed: "artifacts"
2025-12-05 15:19:06.551036 | controller | changed: "docs"
2025-12-05 15:19:06.572935 | 
2025-12-05 15:19:06.573095 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-05 15:19:06.884551 | controller | changed: "logs"
2025-12-05 15:19:06.884970 | controller | changed: All items complete
2025-12-05 15:19:06.885035 | 
2025-12-05 15:19:07.127825 | controller | changed: "artifacts"
2025-12-05 15:19:07.396899 | controller | changed: "docs"
2025-12-05 15:19:07.431347 | 
2025-12-05 15:19:07.431446 | TASK [Check if worker can sudo]
2025-12-05 15:19:07.971140 | controller | ok: Runtime: 0:00:00.037326
2025-12-05 15:19:07.985533 | 
2025-12-05 15:19:07.985734 | TASK [configure-mirrors : Gather needed facts]
2025-12-05 15:19:08.072304 | controller | skipping: Conditional result was False
2025-12-05 15:19:08.093048 | 
2025-12-05 15:19:08.093199 | TASK [configure-mirrors : Set up infrastructure mirrors]
2025-12-05 15:19:08.172805 | controller | ok
2025-12-05 15:19:08.187110 | controller | included: /var/lib/zuul/builds/142a36bb88914c70b263d1cc2a857a7a/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml
2025-12-05 15:19:08.216372 | 
2025-12-05 15:19:08.216488 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir]
2025-12-05 15:19:08.501905 | controller | ok
2025-12-05 15:19:08.507423 | 
2025-12-05 15:19:08.507484 | LOOP [configure-mirrors : Include OS-specific variables]
2025-12-05 15:19:08.567672 | controller | ok: "/var/lib/zuul/builds/142a36bb88914c70b263d1cc2a857a7a/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml"
2025-12-05 15:19:08.581069 | 
2025-12-05 15:19:08.581322 | TASK [configure-mirrors : Install /etc/pip.conf configuration]
2025-12-05 15:19:09.516858 | controller | changed
2025-12-05 15:19:09.522644 | 
2025-12-05 15:19:09.522723 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors]
2025-12-05 15:19:09.603262 | controller | ok: "/var/lib/zuul/builds/142a36bb88914c70b263d1cc2a857a7a/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml"
2025-12-05 15:19:09.603540 | controller | ok: All items complete
2025-12-05 15:19:09.603579 | 
2025-12-05 15:19:09.675537 | controller | included: /var/lib/zuul/builds/142a36bb88914c70b263d1cc2a857a7a/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml
2025-12-05 15:19:09.682758 | 
2025-12-05 15:19:09.682826 | LOOP [configure-mirrors : Install CentOS 9 repository files]
2025-12-05 15:19:10.594473 | controller | changed: "etc/yum.repos.d/centos.repo"
2025-12-05 15:19:11.627302 | controller | changed: "etc/yum.repos.d/centos-addons.repo"
2025-12-05 15:19:11.649354 | 
2025-12-05 15:19:11.649801 | TASK [configure-mirrors : Disable deltrarpm]
2025-12-05 15:19:12.165514 | controller | changed: section and option added
2025-12-05 15:19:12.205455 | 
2025-12-05 15:19:12.205584 | LOOP [configure-mirrors : Update yum/dnf cache]
2025-12-05 15:19:12.905265 | controller | 29 files removed
2025-12-05 15:19:12.905824 | controller | ok: Item: dnf clean all Runtime: 0:00:00.341059
2025-12-05 15:19:12.905925 | controller | changed: All items complete
2025-12-05 15:19:12.905973 | 
2025-12-05 15:19:24.032201 | controller | Loaded plugins: builddep, changelog, config-manager, copr, debug, debuginfo-install, download, generate_completion_cache, groups-manager, needs-restarting, playground, repoclosure, repodiff, repograph, repomanage, reposync, system-upgrade
2025-12-05 15:19:24.032317 | controller | DNF version: 4.14.0
2025-12-05 15:19:24.032341 | controller | cachedir: /var/cache/dnf
2025-12-05 15:19:24.032360 | controller | Making cache files for all metadata files.
2025-12-05 15:19:24.032378 | controller | baseos: has expired and will be refreshed.
2025-12-05 15:19:24.032395 | controller | appstream: has expired and will be refreshed.
2025-12-05 15:19:24.032411 | controller | crb: has expired and will be refreshed.
2025-12-05 15:19:24.032435 | controller | extras-common: has expired and will be refreshed.
2025-12-05 15:19:24.032452 | controller | repo: downloading from remote: baseos
2025-12-05 15:19:24.032468 | controller | CentOS Stream 9 - BaseOS                         68 MB/s | 8.8 MB     00:00
2025-12-05 15:19:24.032484 | controller | baseos: using metadata from Mon 01 Dec 2025 12:58:39 PM EST.
2025-12-05 15:19:24.032499 | controller | repo: downloading from remote: appstream
2025-12-05 15:19:24.032515 | controller | CentOS Stream 9 - AppStream                      92 MB/s |  25 MB     00:00
2025-12-05 15:19:24.032531 | controller | appstream: using metadata from Mon 01 Dec 2025 01:01:55 PM EST.
2025-12-05 15:19:24.032546 | controller | repo: downloading from remote: crb
2025-12-05 15:19:24.032562 | controller | CentOS Stream 9 - CRB                            51 MB/s | 7.3 MB     00:00
2025-12-05 15:19:24.032578 | controller | crb: using metadata from Mon 01 Dec 2025 01:06:07 PM EST.
2025-12-05 15:19:24.032594 | controller | repo: downloading from remote: extras-common
2025-12-05 15:19:24.032610 | controller | CentOS Stream 9 - Extras packages               1.3 MB/s |  20 kB     00:00
2025-12-05 15:19:24.032626 | controller | extras-common: using metadata from Thu 30 Oct 2025 09:20:08 AM EDT.
2025-12-05 15:19:24.032641 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)'
2025-12-05 15:19:24.032657 | controller | Completion plugin: Generating completion cache...
2025-12-05 15:19:24.032672 | controller | Metadata cache created.
2025-12-05 15:19:24.032719 | controller | ok: Item: dnf makecache -v Runtime: 0:00:10.854308
2025-12-05 15:19:24.064366 | 
2025-12-05 15:19:24.064440 | PLAY RECAP
2025-12-05 15:19:24.064496 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-05 15:19:24.064530 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 15:19:24.064556 | 
2025-12-05 15:19:24.177818 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-12-05 15:19:24.178582 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-05 15:19:24.773125 | 
2025-12-05 15:19:24.773231 | PLAY [all]
2025-12-05 15:19:24.793619 | 
2025-12-05 15:19:24.793719 | TASK [Install binary dependencies]
2025-12-05 15:19:24.855837 | controller | ok
2025-12-05 15:19:24.881990 | 
2025-12-05 15:19:24.882100 | TASK [bindep : Include find tasks]
2025-12-05 15:19:24.928822 | controller | ok
2025-12-05 15:19:24.942171 | controller | included: /var/lib/zuul/builds/142a36bb88914c70b263d1cc2a857a7a/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-12-05 15:19:24.950669 | 
2025-12-05 15:19:24.950784 | TASK [bindep : Look for bindep.txt]
2025-12-05 15:19:25.383466 | controller | ok
2025-12-05 15:19:25.397328 | 
2025-12-05 15:19:25.397472 | TASK [bindep : Define bindep_file fact]
2025-12-05 15:19:25.434256 | controller | ok
2025-12-05 15:19:25.445306 | 
2025-12-05 15:19:25.445445 | TASK [bindep : Look for other-requirements.txt]
2025-12-05 15:19:25.472088 | controller | skipping: Conditional result was False
2025-12-05 15:19:25.486372 | 
2025-12-05 15:19:25.486524 | TASK [bindep : Define bindep_file fact]
2025-12-05 15:19:25.513476 | controller | skipping: Conditional result was False
2025-12-05 15:19:25.520989 | 
2025-12-05 15:19:25.521076 | TASK [bindep : Look for bindep fallback file]
2025-12-05 15:19:25.545628 | controller | skipping: Conditional result was False
2025-12-05 15:19:25.553777 | 
2025-12-05 15:19:25.553867 | TASK [bindep : Define bindep_file fact]
2025-12-05 15:19:25.578804 | controller | skipping: Conditional result was False
2025-12-05 15:19:25.587267 | 
2025-12-05 15:19:25.587354 | TASK [bindep : Include bindep tasks]
2025-12-05 15:19:25.622733 | controller | ok
2025-12-05 15:19:25.633579 | controller | included: /var/lib/zuul/builds/142a36bb88914c70b263d1cc2a857a7a/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml
2025-12-05 15:19:25.647953 | 
2025-12-05 15:19:25.648038 | TASK [bindep : Look for bindep command]
2025-12-05 15:19:25.673587 | controller | skipping: Conditional result was False
2025-12-05 15:19:25.683647 | 
2025-12-05 15:19:25.683756 | TASK [bindep : Check for system bindep]
2025-12-05 15:19:26.227596 | controller | ok: Runtime: 0:00:00.007286
2025-12-05 15:19:26.233311 | 
2025-12-05 15:19:26.233375 | TASK [bindep : Define bindep_command fact]
2025-12-05 15:19:26.257823 | controller | skipping: Conditional result was False
2025-12-05 15:19:26.263843 | 
2025-12-05 15:19:26.263907 | TASK [bindep : Include install tasks]
2025-12-05 15:19:26.303858 | controller | ok
2025-12-05 15:19:26.311398 | controller | included: /var/lib/zuul/builds/142a36bb88914c70b263d1cc2a857a7a/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml
2025-12-05 15:19:26.320168 | 
2025-12-05 15:19:26.320227 | TASK [bindep : Create temp dir for bindep]
2025-12-05 15:19:26.699836 | controller | changed
2025-12-05 15:19:26.711825 | 
2025-12-05 15:19:26.711955 | TASK [Ensure we have pip dependencies]
2025-12-05 15:19:26.740071 | controller | ok
2025-12-05 15:19:26.777296 | 
2025-12-05 15:19:26.777385 | TASK [ensure-pip : Check if pip is installed]
2025-12-05 10:19:27.073257 | controller | /usr/bin/pip3
2025-12-05 10:19:27.099179 | controller | /usr/bin/python3: No module named wheel
2025-12-05 15:19:27.313132 | controller | ok: Runtime: 0:00:00.036869
2025-12-05 15:19:27.325015 | 
2025-12-05 15:19:27.325103 | LOOP [ensure-pip : Install pip from packages]
2025-12-05 15:19:27.410434 | controller | ok: "/var/lib/zuul/builds/142a36bb88914c70b263d1cc2a857a7a/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml"
2025-12-05 15:19:27.428294 | controller | included: /var/lib/zuul/builds/142a36bb88914c70b263d1cc2a857a7a/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml
2025-12-05 15:19:27.438429 | 
2025-12-05 15:19:27.438491 | TASK [ensure-pip : Install Python 3 pip]
2025-12-05 15:19:29.187773 | controller | changed
2025-12-05 15:19:29.193377 | 
2025-12-05 15:19:29.193439 | TASK [ensure-pip : Check for EPEL repository]
2025-12-05 15:19:29.228405 | controller | skipping: Conditional result was False
2025-12-05 15:19:29.235230 | 
2025-12-05 15:19:29.235295 | TASK [ensure-pip : Fail with instructions when EPEL is not installed]
2025-12-05 15:19:29.269627 | controller | skipping: Conditional result was False
2025-12-05 15:19:29.276018 | 
2025-12-05 15:19:29.276080 | TASK [ensure-pip : Install Python 2 pip]
2025-12-05 15:19:29.310096 | controller | skipping: Conditional result was False
2025-12-05 15:19:29.316407 | 
2025-12-05 15:19:29.316475 | TASK [ensure-pip : Ensure setuptools]
2025-12-05 15:19:29.330191 | controller | skipping: Conditional result was False
2025-12-05 15:19:29.337611 | 
2025-12-05 15:19:29.337674 | TASK [ensure-pip : Check for ensurepip module]
2025-12-05 15:19:29.865821 | controller | skipping: Conditional result was False
2025-12-05 15:19:29.872257 | 
2025-12-05 15:19:29.872337 | TASK [ensure-pip : Ensure python3-venv]
2025-12-05 15:19:29.896817 | controller | skipping: Conditional result was False
2025-12-05 15:19:29.902521 | 
2025-12-05 15:19:29.902582 | TASK [ensure-pip : Install pip from source]
2025-12-05 15:19:29.937150 | controller | skipping: Conditional result was False
2025-12-05 15:19:29.957122 | 
2025-12-05 15:19:29.957225 | TASK [ensure-pip : Probe for venv python full path]
2025-12-05 10:19:30.209633 | controller | /usr/bin/python3
2025-12-05 15:19:30.496238 | controller | ok: Runtime: 0:00:00.007606
2025-12-05 15:19:30.505745 | 
2025-12-05 15:19:30.506019 | TASK [ensure-pip : Set host default]
2025-12-05 15:19:30.619693 | controller | ok
2025-12-05 15:19:30.626282 | 
2025-12-05 15:19:30.626352 | TASK [ensure-pip : Set ensure_pip_virtualenv_command]
2025-12-05 15:19:30.742586 | controller | ok
2025-12-05 15:19:30.754444 | 
2025-12-05 15:19:30.755134 | TASK [bindep : Install bindep into temporary venv]
2025-12-05 15:19:34.970085 | controller | changed
2025-12-05 15:19:34.975969 | 
2025-12-05 15:19:34.976056 | TASK [bindep : Define bindep_command]
2025-12-05 15:19:35.027623 | controller | ok
2025-12-05 15:19:35.034421 | 
2025-12-05 15:19:35.034499 | LOOP [bindep : Include package tasks]
2025-12-05 15:19:35.082614 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt"
2025-12-05 15:19:35.083029 | controller | ok: All items complete
2025-12-05 15:19:35.083058 | 
2025-12-05 15:19:35.100961 | controller | included: /var/lib/zuul/builds/142a36bb88914c70b263d1cc2a857a7a/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml
2025-12-05 15:19:35.112671 | 
2025-12-05 15:19:35.112747 | TASK [bindep : Define bindep_run fact]
2025-12-05 15:19:35.141988 | controller | ok
2025-12-05 15:19:35.147465 | 
2025-12-05 15:19:35.147612 | TASK [bindep : Get list of packages to install from bindep]
2025-12-05 10:19:36.515878 | controller | podman
2025-12-05 10:19:36.549495 | controller | python3-jmespath
2025-12-05 10:19:36.549670 | controller | python3-libvirt
2025-12-05 10:19:36.549683 | controller | python3-lxml
2025-12-05 10:19:36.549693 | controller | python3-netaddr
2025-12-05 15:19:36.685075 | controller | ok: Runtime: 0:00:01.099933
2025-12-05 15:19:36.690648 | 
2025-12-05 15:19:36.690728 | TASK [bindep : Install distro packages from bindep]
2025-12-05 15:20:40.015252 | controller | changed
2025-12-05 15:20:40.022782 | 
2025-12-05 15:20:40.022844 | TASK [bindep : Check that packages are installed]
2025-12-05 15:20:42.059184 | controller | ok: Runtime: 0:00:01.663383
2025-12-05 15:20:42.101901 | 
2025-12-05 15:20:42.102026 | TASK [bindep : Fail if we cannot install all packages]
2025-12-05 15:20:42.136508 | controller | skipping: Conditional result was False
2025-12-05 15:20:42.147116 | 
2025-12-05 15:20:42.147181 | TASK [Run test-setup role]
2025-12-05 15:20:42.165943 | controller | ok
2025-12-05 15:20:42.182597 | 
2025-12-05 15:20:42.182697 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-12-05 15:20:42.396139 | controller | ok
2025-12-05 15:20:42.401289 | 
2025-12-05 15:20:42.401352 | TASK [test-setup : Run tools/test-setup.sh]
2025-12-05 15:20:42.925885 | controller | skipping: Conditional result was False
2025-12-05 15:20:42.946578 | 
2025-12-05 15:20:42.946728 | TASK [bindep : Remove bindep temp dir]
2025-12-05 15:20:43.322512 | controller | ok
2025-12-05 15:20:43.333543 | 
2025-12-05 15:20:43.333593 | PLAY RECAP
2025-12-05 15:20:43.333633 | controller | ok: 22 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0
2025-12-05 15:20:43.333653 | 
2025-12-05 15:20:43.434980 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-05 15:20:43.435848 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2025-12-05 15:20:43.964570 | 
2025-12-05 15:20:43.964675 | PLAY [all]
2025-12-05 15:20:43.983868 | 
2025-12-05 15:20:43.983939 | TASK [Abort when test_command variable is undefined]
2025-12-05 15:20:44.018630 | controller | skipping: Conditional result was False
2025-12-05 15:20:44.024257 | 
2025-12-05 15:20:44.024326 | TASK [Convert test_command to list]
2025-12-05 15:20:44.058923 | controller | skipping: Conditional result was False
2025-12-05 15:20:44.067535 | 
2025-12-05 15:20:44.067622 | TASK [Use test_command list]
2025-12-05 15:20:44.119434 | controller | ok
2025-12-05 15:20:44.126552 | 
2025-12-05 15:20:44.126653 | LOOP [Run test_command]
2025-12-05 15:20:44.528178 | controller | no check to run
2025-12-05 15:20:44.528372 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.004867
2025-12-05 15:20:44.556991 | 
2025-12-05 15:20:44.557090 | PLAY RECAP
2025-12-05 15:20:44.557132 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-05 15:20:44.557152 | 
2025-12-05 15:20:44.648068 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2025-12-05 15:20:44.648915 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-05 15:20:45.202640 | 
2025-12-05 15:20:45.202766 | PLAY [all]
2025-12-05 15:20:45.222782 | 
2025-12-05 15:20:45.222856 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-12-05 15:20:45.567410 | controller | changed: non-zero return code
2025-12-05 15:20:45.572783 | 
2025-12-05 15:20:45.572859 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-12-05 15:20:45.596956 | controller | skipping: Conditional result was False
2025-12-05 15:20:45.602419 | 
2025-12-05 15:20:45.602482 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-12-05 15:20:45.632813 | 
2025-12-05 15:20:45.632970 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-12-05 15:20:45.652943 | 
2025-12-05 15:20:45.653073 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-12-05 15:20:45.677080 | controller | skipping: Conditional result was False
2025-12-05 15:20:45.684176 | 
2025-12-05 15:20:45.684463 | LOOP [fetch-subunit-output : Generate subunit file]
2025-12-05 15:20:45.713482 | 
2025-12-05 15:20:45.713750 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-12-05 15:20:45.729867 | controller | skipping: Conditional result was False
2025-12-05 15:20:45.744412 | 
2025-12-05 15:20:45.744554 | TASK [fetch-subunit-output : Remove the temporary file]
2025-12-05 15:20:45.760474 | controller | skipping: Conditional result was False
2025-12-05 15:20:45.772160 | 
2025-12-05 15:20:45.772295 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-12-05 15:20:45.788228 | controller | skipping: Conditional result was False
2025-12-05 15:20:45.828837 | 
2025-12-05 15:20:45.829009 | PLAY RECAP
2025-12-05 15:20:45.829104 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-05 15:20:45.829149 | 
2025-12-05 15:20:45.948619 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-05 15:20:45.949452 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 15:20:46.509637 | 
2025-12-05 15:20:46.509774 | PLAY [all]
2025-12-05 15:20:46.528396 | 
2025-12-05 15:20:46.528476 | TASK [include_role : fetch-output]
2025-12-05 15:20:46.565875 | controller | ok
2025-12-05 15:20:46.582467 | 
2025-12-05 15:20:46.582668 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-05 15:20:46.628054 | controller | skipping: Conditional result was False
2025-12-05 15:20:46.633768 | 
2025-12-05 15:20:46.633853 | TASK [fetch-output : Set log path for single node]
2025-12-05 15:20:46.663321 | controller | ok
2025-12-05 15:20:46.670643 | 
2025-12-05 15:20:46.670762 | LOOP [fetch-output : Ensure local output dirs]
2025-12-05 15:20:47.042046 | controller -> localhost | ok: "/var/lib/zuul/builds/142a36bb88914c70b263d1cc2a857a7a/work/logs"
2025-12-05 15:20:47.254206 | controller -> localhost | changed: "/var/lib/zuul/builds/142a36bb88914c70b263d1cc2a857a7a/work/artifacts"
2025-12-05 15:20:47.456513 | controller -> localhost | changed: "/var/lib/zuul/builds/142a36bb88914c70b263d1cc2a857a7a/work/docs"
2025-12-05 15:20:47.473146 | 
2025-12-05 15:20:47.473258 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-05 15:20:48.063524 | controller | changed: .d..t...... ./
2025-12-05 15:20:48.063742 | controller | changed: All items complete
2025-12-05 15:20:48.063773 | 
2025-12-05 15:20:48.476507 | controller | changed: .d..t...... ./
2025-12-05 15:20:48.941859 | controller | changed: .d..t...... ./
2025-12-05 15:20:48.954499 | 
2025-12-05 15:20:48.954569 | TASK [include_role : fetch-output-openshift]
2025-12-05 15:20:48.968442 | controller | skipping: Conditional result was False
2025-12-05 15:20:48.976198 | 
2025-12-05 15:20:48.976277 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-05 15:20:49.413903 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.025397
2025-12-05 15:20:49.637030 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.024104
2025-12-05 15:20:49.669560 | 
2025-12-05 15:20:49.669691 | PLAY [all]
2025-12-05 15:20:49.683429 | 
2025-12-05 15:20:49.683490 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-05 15:20:50.113200 | controller | changed
2025-12-05 15:20:50.144652 | 
2025-12-05 15:20:50.144733 | PLAY RECAP
2025-12-05 15:20:50.144783 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-05 15:20:50.144807 | 
2025-12-05 15:20:50.249533 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 15:20:50.250376 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-05 15:20:50.839128 | 
2025-12-05 15:20:50.839251 | PLAY [localhost]
2025-12-05 15:20:50.857060 | 
2025-12-05 15:20:50.857144 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-05 15:20:51.210019 | localhost | changed
2025-12-05 15:20:51.214765 | 
2025-12-05 15:20:51.214838 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-05 15:20:51.249413 | localhost | ok
2025-12-05 15:20:51.257811 | 
2025-12-05 15:20:51.257874 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-05 15:20:51.587931 | localhost | changed
2025-12-05 15:20:51.592712 | 
2025-12-05 15:20:51.592779 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-05 15:20:52.226252 | localhost | changed
2025-12-05 15:20:52.231280 | 
2025-12-05 15:20:52.231341 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-05 15:20:52.647372 | localhost | Identity added: /var/lib/zuul/builds/142a36bb88914c70b263d1cc2a857a7a/work/tmp/ansible.qw5706oo (/var/lib/zuul/builds/142a36bb88914c70b263d1cc2a857a7a/work/tmp/ansible.qw5706oo)
2025-12-05 15:20:52.647599 | localhost | ok: Runtime: 0:00:00.014701
2025-12-05 15:20:52.653561 | 
2025-12-05 15:20:52.653647 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-05 15:20:52.895883 | localhost | ok: Runtime: 0:00:00.008154
2025-12-05 15:20:52.900783 | 
2025-12-05 15:20:52.900848 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-05 15:20:52.959553 | localhost | changed
2025-12-05 15:20:52.964413 | 
2025-12-05 15:20:52.964478 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-05 15:20:53.310144 | localhost | changed
2025-12-05 15:20:53.330388 | 
2025-12-05 15:20:53.330444 | PLAY [localhost]
2025-12-05 15:20:53.341831 | 
2025-12-05 15:20:53.341892 | TASK [Generate bulk log download script]
2025-12-05 15:20:53.360986 | localhost | ok
2025-12-05 15:20:53.372827 | 
2025-12-05 15:20:53.372893 | TASK [local-log-download : Check API endpoint is defined]
2025-12-05 15:20:53.411481 | localhost | ok: All assertions passed
2025-12-05 15:20:53.415956 | 
2025-12-05 15:20:53.416013 | TASK [local-log-download : Create download script]
2025-12-05 15:20:53.866996 | localhost -> localhost | changed
2025-12-05 15:20:53.875635 | 
2025-12-05 15:20:53.875713 | TASK [Register quick-download link]
2025-12-05 15:20:53.902622 | localhost | ok
2025-12-05 15:20:53.941146 | 
2025-12-05 15:20:53.941248 | PLAY [logserver.rdoproject.org]
2025-12-05 15:20:53.950638 | 
2025-12-05 15:20:53.950711 | TASK [Set zuul-log-path fact]
2025-12-05 15:20:53.966528 | logserver.rdoproject.org | ok
2025-12-05 15:20:53.975351 | 
2025-12-05 15:20:53.975413 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 15:20:54.001411 | logserver.rdoproject.org | ok
2025-12-05 15:20:54.006493 | 
2025-12-05 15:20:54.006554 | TASK [upload-logs : Create log directories]
2025-12-05 15:20:54.687952 | logserver.rdoproject.org | changed
2025-12-05 15:20:54.694739 | 
2025-12-05 15:20:54.694866 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-05 15:20:55.027169 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.008533
2025-12-05 15:20:55.037471 | 
2025-12-05 15:20:55.037607 | TASK [upload-logs : Upload logs to log server]
2025-12-05 15:20:55.803860 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-05 15:20:55.806792 | 
2025-12-05 15:20:55.806855 | LOOP [upload-logs : Compress console log and json output]
2025-12-05 15:20:55.884544 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:20:55.896173 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:20:55.915150 | 
2025-12-05 15:20:55.915331 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-05 15:20:55.955726 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:20:55.956143 | 
2025-12-05 15:20:55.962020 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:20:55.970481 | 
2025-12-05 15:20:55.970649 | LOOP [upload-logs : Upload console log and json output]