2025-12-05 15:18:40.668941 | Job console starting...
2025-12-05 15:18:40.677898 | Updating repositories
2025-12-05 15:18:40.704329 | Preparing job workspace
2025-12-05 15:18:44.682149 | Running Ansible setup...
2025-12-05 15:18:48.947980 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 15:18:49.717365 | 
2025-12-05 15:18:49.717515 | PLAY [localhost]
2025-12-05 15:18:49.726972 | 
2025-12-05 15:18:49.727065 | TASK [Gathering Facts]
2025-12-05 15:18:50.742002 | localhost | ok
2025-12-05 15:18:50.760893 | 
2025-12-05 15:18:50.761042 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-05 15:18:51.152753 | localhost -> localhost | changed
2025-12-05 15:18:51.158607 | 
2025-12-05 15:18:51.158697 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-05 15:18:51.960157 | localhost -> localhost | changed
2025-12-05 15:18:51.968643 | 
2025-12-05 15:18:51.968735 | TASK [Setup log path fact]
2025-12-05 15:18:51.997514 | localhost | ok
2025-12-05 15:18:52.012594 | 
2025-12-05 15:18:52.012681 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 15:18:52.039796 | localhost | ok
2025-12-05 15:18:52.047241 | 
2025-12-05 15:18:52.047303 | TASK [emit-job-header : Print job information]
2025-12-05 15:18:52.075353 | # Job Information
2025-12-05 15:18:52.075683 | Ansible Version: 2.15.12
2025-12-05 15:18:52.075715 | Job: cifmw-molecule-cifmw_setup
2025-12-05 15:18:52.075736 | Pipeline: github-check
2025-12-05 15:18:52.075754 | Executor: ze02.softwarefactory-project.io
2025-12-05 15:18:52.075772 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3539
2025-12-05 15:18:52.075790 | Log URL (when completed): https://logserver.rdoproject.org/641/rdoproject.org/6414a239b3e3403d90c81f78c4af09e8/
2025-12-05 15:18:52.075808 | Event ID: 748a4b40-d1ed-11f0-8510-c0ded0ca5b3b
2025-12-05 15:18:52.080218 | 
2025-12-05 15:18:52.080289 | LOOP [emit-job-header : Print node information]
2025-12-05 15:18:52.187206 | localhost | ok:
2025-12-05 15:18:52.187417 | localhost | # Node Information
2025-12-05 15:18:52.187445 | localhost | Inventory Hostname: controller
2025-12-05 15:18:52.187471 | localhost | Hostname: np0005547217
2025-12-05 15:18:52.187491 | localhost | Username: zuul
2025-12-05 15:18:52.187511 | localhost | Distro: CentOS 9
2025-12-05 15:18:52.187529 | localhost | Provider: vexxhost-nodepool-tripleo
2025-12-05 15:18:52.187546 | localhost | Region: RegionOne
2025-12-05 15:18:52.187563 | localhost | Label: cloud-centos-9-stream-tripleo
2025-12-05 15:18:52.187579 | localhost | Product Name: OpenStack Nova
2025-12-05 15:18:52.187595 | localhost | Interface IP: 38.102.83.200
2025-12-05 15:18:52.220409 | 
2025-12-05 15:18:52.220487 | PLAY [all]
2025-12-05 15:18:52.227235 | 
2025-12-05 15:18:52.227294 | TASK [Gather network facts]
2025-12-05 15:18:52.679625 | controller | ok
2025-12-05 15:18:52.706517 | 
2025-12-05 15:18:52.706590 | TASK [include_role : start-zuul-console]
2025-12-05 15:18:52.750321 | controller | ok
2025-12-05 15:18:52.772002 | 
2025-12-05 15:18:52.772097 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-05 15:18:53.226908 | controller | ok
2025-12-05 15:18:53.235506 | 
2025-12-05 15:18:53.235571 | TASK [include_role : add-build-sshkey]
2025-12-05 15:18:53.272917 | controller | ok
2025-12-05 15:18:53.292773 | 
2025-12-05 15:18:53.292847 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-05 15:18:53.698922 | controller -> localhost | ok
2025-12-05 15:18:53.704656 | 
2025-12-05 15:18:53.704735 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-05 15:18:53.735249 | controller | ok
2025-12-05 15:18:53.748442 | controller | included: /var/lib/zuul/builds/6414a239b3e3403d90c81f78c4af09e8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-05 15:18:53.754031 | 
2025-12-05 15:18:53.754094 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-05 15:18:54.347245 | controller -> localhost | Generating public/private rsa key pair.
2025-12-05 15:18:54.347558 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/6414a239b3e3403d90c81f78c4af09e8/work/6414a239b3e3403d90c81f78c4af09e8_id_rsa.
2025-12-05 15:18:54.347625 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/6414a239b3e3403d90c81f78c4af09e8/work/6414a239b3e3403d90c81f78c4af09e8_id_rsa.pub.
2025-12-05 15:18:54.347712 | controller -> localhost | The key fingerprint is:
2025-12-05 15:18:54.347766 | controller -> localhost | SHA256:XHe6bs7BniM6sAY03qh8uZjzdSTrDwPMqqcZ3+szTm0 zuul-build-sshkey
2025-12-05 15:18:54.347810 | controller -> localhost | The key's randomart image is:
2025-12-05 15:18:54.347850 | controller -> localhost | +---[RSA 3072]----+
2025-12-05 15:18:54.347889 | controller -> localhost | |                 |
2025-12-05 15:18:54.347929 | controller -> localhost | |                 |
2025-12-05 15:18:54.347968 | controller -> localhost | |          . . .  |
2025-12-05 15:18:54.348007 | controller -> localhost | |   oo  . . . o   |
2025-12-05 15:18:54.348044 | controller -> localhost | |   o++. S   .    |
2025-12-05 15:18:54.348082 | controller -> localhost | |   .+oo+   . .   |
2025-12-05 15:18:54.348120 | controller -> localhost | |.....oEo.   +    |
2025-12-05 15:18:54.348158 | controller -> localhost | | ====+o+. .+oo   |
2025-12-05 15:18:54.348195 | controller -> localhost | |+o+*B*..oo +*.   |
2025-12-05 15:18:54.348232 | controller -> localhost | +----[SHA256]-----+
2025-12-05 15:18:54.348324 | controller -> localhost | ok: Runtime: 0:00:00.124131
2025-12-05 15:18:54.361977 | 
2025-12-05 15:18:54.362066 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-05 15:18:54.393739 | controller | ok
2025-12-05 15:18:54.417304 | controller | included: /var/lib/zuul/builds/6414a239b3e3403d90c81f78c4af09e8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-05 15:18:54.436037 | 
2025-12-05 15:18:54.436162 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-05 15:18:54.460770 | controller | skipping: Conditional result was False
2025-12-05 15:18:54.466028 | 
2025-12-05 15:18:54.466096 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-05 15:18:54.972628 | controller | changed
2025-12-05 15:18:54.977741 | 
2025-12-05 15:18:54.977799 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-05 15:18:55.297592 | controller | ok
2025-12-05 15:18:55.308441 | 
2025-12-05 15:18:55.308573 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-05 15:18:56.289604 | controller | changed
2025-12-05 15:18:56.298913 | 
2025-12-05 15:18:56.298989 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-05 15:18:57.212281 | controller | changed
2025-12-05 15:18:57.223616 | 
2025-12-05 15:18:57.223802 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-05 15:18:57.264339 | controller | skipping: Conditional result was False
2025-12-05 15:18:57.290351 | 
2025-12-05 15:18:57.290515 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-05 15:18:57.666159 | controller -> localhost | changed
2025-12-05 15:18:57.677979 | 
2025-12-05 15:18:57.678052 | TASK [add-build-sshkey : Add back temp key]
2025-12-05 15:18:57.925197 | controller -> localhost | Identity added: /var/lib/zuul/builds/6414a239b3e3403d90c81f78c4af09e8/work/6414a239b3e3403d90c81f78c4af09e8_id_rsa (zuul-build-sshkey)
2025-12-05 15:18:57.925492 | controller -> localhost | ok: Runtime: 0:00:00.010574
2025-12-05 15:18:57.931533 | 
2025-12-05 15:18:57.931593 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-05 15:18:58.325721 | controller | ok
2025-12-05 15:18:58.330639 | 
2025-12-05 15:18:58.332496 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-05 15:18:58.357902 | controller | skipping: Conditional result was False
2025-12-05 15:18:58.367584 | 
2025-12-05 15:18:58.367649 | TASK [include_role : validate-host]
2025-12-05 15:18:58.406874 | controller | ok
2025-12-05 15:18:58.430018 | 
2025-12-05 15:18:58.430096 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-05 15:18:58.479069 | controller | ok
2025-12-05 15:18:58.483811 | 
2025-12-05 15:18:58.483869 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-05 15:18:58.793405 | controller -> localhost | ok
2025-12-05 15:18:58.799726 | 
2025-12-05 15:18:58.799787 | TASK [validate-host : Collect information about the host]
2025-12-05 15:18:59.620555 | controller | ok
2025-12-05 15:18:59.638992 | 
2025-12-05 15:18:59.639122 | TASK [validate-host : Sanitize hostname]
2025-12-05 15:18:59.710168 | controller | ok
2025-12-05 15:18:59.721889 | 
2025-12-05 15:18:59.722021 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-05 15:19:00.160637 | controller -> localhost | changed
2025-12-05 15:19:00.169805 | 
2025-12-05 15:19:00.169892 | TASK [validate-host : Collect information about zuul worker]
2025-12-05 15:19:00.599241 | controller | ok
2025-12-05 15:19:00.603771 | 
2025-12-05 15:19:00.603831 | TASK [validate-host : Write out all zuul information for each host]
2025-12-05 15:19:01.103075 | controller -> localhost | changed
2025-12-05 15:19:01.112556 | 
2025-12-05 15:19:01.112620 | TASK [include_role : prepare-workspace-openshift]
2025-12-05 15:19:01.136641 | controller | skipping: Conditional result was False
2025-12-05 15:19:01.142121 | 
2025-12-05 15:19:01.142181 | TASK [include_role : remove-zuul-sshkey]
2025-12-05 15:19:01.155962 | controller | skipping: Conditional result was False
2025-12-05 15:19:01.161633 | 
2025-12-05 15:19:01.161724 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-05 15:19:01.416146 | controller | ok: "logs"
2025-12-05 15:19:01.416406 | controller | ok: All items complete
2025-12-05 15:19:01.416433 | 
2025-12-05 15:19:01.609300 | controller | ok: "artifacts"
2025-12-05 15:19:01.831829 | controller | ok: "docs"
2025-12-05 15:19:01.843055 | 
2025-12-05 15:19:01.843141 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-05 15:19:02.102791 | controller | changed: "logs"
2025-12-05 15:19:02.330924 | controller | changed: "artifacts"
2025-12-05 15:19:02.608264 | controller | changed: "docs"
2025-12-05 15:19:02.652936 | 
2025-12-05 15:19:02.653065 | PLAY RECAP
2025-12-05 15:19:02.653112 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-05 15:19:02.653138 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 15:19:02.653156 | 
2025-12-05 15:19:02.775770 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 15:19:02.776571 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-12-05 15:19:03.381484 | 
2025-12-05 15:19:03.381603 | PLAY [localhost]
2025-12-05 15:19:03.398443 | 
2025-12-05 15:19:03.398535 | TASK [configure-ssh-config-fips : Ensure ssh config directory]
2025-12-05 15:19:03.853811 | localhost | ok
2025-12-05 15:19:03.859316 | 
2025-12-05 15:19:03.859390 | TASK [configure-ssh-config-fips : Setup ssh config]
2025-12-05 15:19:04.655659 | localhost | changed
2025-12-05 15:19:04.691302 | 
2025-12-05 15:19:04.691535 | PLAY [all]
2025-12-05 15:19:04.715167 | 
2025-12-05 15:19:04.715272 | TASK [include_role : prepare-workspace]
2025-12-05 15:19:04.737760 | controller | ok
2025-12-05 15:19:04.758028 | 
2025-12-05 15:19:04.758107 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-05 15:19:05.249511 | controller | ok
2025-12-05 15:19:05.261848 | 
2025-12-05 15:19:05.262041 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-05 15:19:07.193558 | controller | Output suppressed because no_log was given
2025-12-05 15:19:07.217105 | 
2025-12-05 15:19:07.217390 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-05 15:19:07.532619 | controller | changed: "logs"
2025-12-05 15:19:07.751750 | controller | changed: "artifacts"
2025-12-05 15:19:07.971839 | controller | changed: "docs"
2025-12-05 15:19:07.990817 | 
2025-12-05 15:19:07.991114 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-05 15:19:08.313557 | controller | changed: "logs"
2025-12-05 15:19:08.314080 | controller | changed: All items complete
2025-12-05 15:19:08.314142 | 
2025-12-05 15:19:08.585088 | controller | changed: "artifacts"
2025-12-05 15:19:08.838087 | controller | changed: "docs"
2025-12-05 15:19:08.852693 | 
2025-12-05 15:19:08.852841 | TASK [Check if worker can sudo]
2025-12-05 15:19:09.402271 | controller | ok: Runtime: 0:00:00.039259
2025-12-05 15:19:09.414466 | 
2025-12-05 15:19:09.414593 | TASK [configure-mirrors : Gather needed facts]
2025-12-05 15:19:09.515121 | controller | skipping: Conditional result was False
2025-12-05 15:19:09.524241 | 
2025-12-05 15:19:09.524335 | TASK [configure-mirrors : Set up infrastructure mirrors]
2025-12-05 15:19:09.632257 | controller | ok
2025-12-05 15:19:09.687025 | controller | included: /var/lib/zuul/builds/6414a239b3e3403d90c81f78c4af09e8/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml
2025-12-05 15:19:09.695033 | 
2025-12-05 15:19:09.695117 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir]
2025-12-05 15:19:09.995130 | controller | ok
2025-12-05 15:19:10.007726 | 
2025-12-05 15:19:10.007854 | LOOP [configure-mirrors : Include OS-specific variables]
2025-12-05 15:19:10.105213 | controller | ok: "/var/lib/zuul/builds/6414a239b3e3403d90c81f78c4af09e8/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml"
2025-12-05 15:19:10.125291 | 
2025-12-05 15:19:10.125478 | TASK [configure-mirrors : Install /etc/pip.conf configuration]
2025-12-05 15:19:11.182385 | controller | changed
2025-12-05 15:19:11.194648 | 
2025-12-05 15:19:11.194808 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors]
2025-12-05 15:19:11.273542 | controller | ok: "/var/lib/zuul/builds/6414a239b3e3403d90c81f78c4af09e8/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml"
2025-12-05 15:19:11.274099 | controller | ok: All items complete
2025-12-05 15:19:11.274136 | 
2025-12-05 15:19:11.359822 | controller | included: /var/lib/zuul/builds/6414a239b3e3403d90c81f78c4af09e8/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml
2025-12-05 15:19:11.368105 | 
2025-12-05 15:19:11.368180 | LOOP [configure-mirrors : Install CentOS 9 repository files]
2025-12-05 15:19:12.447185 | controller | changed: "etc/yum.repos.d/centos.repo"
2025-12-05 15:19:13.442493 | controller | changed: "etc/yum.repos.d/centos-addons.repo"
2025-12-05 15:19:13.467037 | 
2025-12-05 15:19:13.467184 | TASK [configure-mirrors : Disable deltrarpm]
2025-12-05 15:19:13.985383 | controller | changed: section and option added
2025-12-05 15:19:14.004231 | 
2025-12-05 15:19:14.004292 | LOOP [configure-mirrors : Update yum/dnf cache]
2025-12-05 15:19:14.591040 | controller | 29 files removed
2025-12-05 15:19:14.591898 | controller | ok: Item: dnf clean all Runtime: 0:00:00.241172
2025-12-05 15:19:14.591937 | controller | changed: All items complete
2025-12-05 15:19:14.591958 | 
2025-12-05 15:19:25.091159 | 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:25.091333 | controller | DNF version: 4.14.0
2025-12-05 15:19:25.091386 | controller | cachedir: /var/cache/dnf
2025-12-05 15:19:25.091428 | controller | Making cache files for all metadata files.
2025-12-05 15:19:25.091494 | controller | baseos: has expired and will be refreshed.
2025-12-05 15:19:25.091532 | controller | appstream: has expired and will be refreshed.
2025-12-05 15:19:25.091568 | controller | crb: has expired and will be refreshed.
2025-12-05 15:19:25.091619 | controller | extras-common: has expired and will be refreshed.
2025-12-05 15:19:25.091656 | controller | repo: downloading from remote: baseos
2025-12-05 15:19:25.091745 | controller | CentOS Stream 9 - BaseOS                         57 MB/s | 8.8 MB     00:00
2025-12-05 15:19:25.091786 | controller | baseos: using metadata from Mon 01 Dec 2025 12:58:39 PM EST.
2025-12-05 15:19:25.091822 | controller | repo: downloading from remote: appstream
2025-12-05 15:19:25.091857 | controller | CentOS Stream 9 - AppStream                      71 MB/s |  25 MB     00:00
2025-12-05 15:19:25.091891 | controller | appstream: using metadata from Mon 01 Dec 2025 01:01:55 PM EST.
2025-12-05 15:19:25.091926 | controller | repo: downloading from remote: crb
2025-12-05 15:19:25.091959 | controller | CentOS Stream 9 - CRB                            68 MB/s | 7.3 MB     00:00
2025-12-05 15:19:25.091994 | controller | crb: using metadata from Mon 01 Dec 2025 01:06:07 PM EST.
2025-12-05 15:19:25.092028 | controller | repo: downloading from remote: extras-common
2025-12-05 15:19:25.092063 | controller | CentOS Stream 9 - Extras packages               1.6 MB/s |  20 kB     00:00
2025-12-05 15:19:25.092097 | controller | extras-common: using metadata from Thu 30 Oct 2025 09:20:08 AM EDT.
2025-12-05 15:19:25.092132 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)'
2025-12-05 15:19:25.092166 | controller | Completion plugin: Generating completion cache...
2025-12-05 15:19:25.092201 | controller | Metadata cache created.
2025-12-05 15:19:25.092254 | controller | ok: Item: dnf makecache -v Runtime: 0:00:10.225743
2025-12-05 15:19:25.108948 | 
2025-12-05 15:19:25.109061 | PLAY RECAP
2025-12-05 15:19:25.109118 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-05 15:19:25.109151 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 15:19:25.109175 | 
2025-12-05 15:19:25.290931 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-12-05 15:19:25.291916 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-05 15:19:25.865887 | 
2025-12-05 15:19:25.865991 | PLAY [all]
2025-12-05 15:19:25.886948 | 
2025-12-05 15:19:25.887024 | TASK [Install binary dependencies]
2025-12-05 15:19:25.947343 | controller | ok
2025-12-05 15:19:25.966374 | 
2025-12-05 15:19:25.966450 | TASK [bindep : Include find tasks]
2025-12-05 15:19:26.010135 | controller | ok
2025-12-05 15:19:26.020580 | controller | included: /var/lib/zuul/builds/6414a239b3e3403d90c81f78c4af09e8/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-12-05 15:19:26.028904 | 
2025-12-05 15:19:26.028994 | TASK [bindep : Look for bindep.txt]
2025-12-05 15:19:26.524262 | controller | ok
2025-12-05 15:19:26.538464 | 
2025-12-05 15:19:26.538581 | TASK [bindep : Define bindep_file fact]
2025-12-05 15:19:26.598486 | controller | ok
2025-12-05 15:19:26.609949 | 
2025-12-05 15:19:26.610085 | TASK [bindep : Look for other-requirements.txt]
2025-12-05 15:19:26.638163 | controller | skipping: Conditional result was False
2025-12-05 15:19:26.646533 | 
2025-12-05 15:19:26.646625 | TASK [bindep : Define bindep_file fact]
2025-12-05 15:19:26.682807 | controller | skipping: Conditional result was False
2025-12-05 15:19:26.690971 | 
2025-12-05 15:19:26.691060 | TASK [bindep : Look for bindep fallback file]
2025-12-05 15:19:26.727328 | controller | skipping: Conditional result was False
2025-12-05 15:19:26.735376 | 
2025-12-05 15:19:26.735468 | TASK [bindep : Define bindep_file fact]
2025-12-05 15:19:26.772127 | controller | skipping: Conditional result was False
2025-12-05 15:19:26.780597 | 
2025-12-05 15:19:26.780706 | TASK [bindep : Include bindep tasks]
2025-12-05 15:19:26.826386 | controller | ok
2025-12-05 15:19:26.835583 | controller | included: /var/lib/zuul/builds/6414a239b3e3403d90c81f78c4af09e8/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml
2025-12-05 15:19:26.846481 | 
2025-12-05 15:19:26.846567 | TASK [bindep : Look for bindep command]
2025-12-05 15:19:26.873781 | controller | skipping: Conditional result was False
2025-12-05 15:19:26.882828 | 
2025-12-05 15:19:26.882914 | TASK [bindep : Check for system bindep]
2025-12-05 15:19:27.420033 | controller | ok: Runtime: 0:00:00.008032
2025-12-05 15:19:27.432552 | 
2025-12-05 15:19:27.432750 | TASK [bindep : Define bindep_command fact]
2025-12-05 15:19:27.470780 | controller | skipping: Conditional result was False
2025-12-05 15:19:27.484662 | 
2025-12-05 15:19:27.484889 | TASK [bindep : Include install tasks]
2025-12-05 15:19:27.541804 | controller | ok
2025-12-05 15:19:27.558231 | controller | included: /var/lib/zuul/builds/6414a239b3e3403d90c81f78c4af09e8/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml
2025-12-05 15:19:27.571959 | 
2025-12-05 15:19:27.572041 | TASK [bindep : Create temp dir for bindep]
2025-12-05 15:19:27.990240 | controller | changed
2025-12-05 15:19:27.995721 | 
2025-12-05 15:19:27.995784 | TASK [Ensure we have pip dependencies]
2025-12-05 15:19:28.016305 | controller | ok
2025-12-05 15:19:28.041969 | 
2025-12-05 15:19:28.042039 | TASK [ensure-pip : Check if pip is installed]
2025-12-05 10:19:28.317114 | controller | /usr/bin/pip3
2025-12-05 10:19:28.365987 | controller | /usr/bin/python3: No module named wheel
2025-12-05 15:19:28.573178 | controller | ok: Runtime: 0:00:00.061998
2025-12-05 15:19:28.584517 | 
2025-12-05 15:19:28.584614 | LOOP [ensure-pip : Install pip from packages]
2025-12-05 15:19:28.620023 | controller | ok: "/var/lib/zuul/builds/6414a239b3e3403d90c81f78c4af09e8/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml"
2025-12-05 15:19:28.633148 | controller | included: /var/lib/zuul/builds/6414a239b3e3403d90c81f78c4af09e8/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml
2025-12-05 15:19:28.647726 | 
2025-12-05 15:19:28.647819 | TASK [ensure-pip : Install Python 3 pip]
2025-12-05 15:19:30.579888 | controller | changed
2025-12-05 15:19:30.591353 | 
2025-12-05 15:19:30.591475 | TASK [ensure-pip : Check for EPEL repository]
2025-12-05 15:19:30.660337 | controller | skipping: Conditional result was False
2025-12-05 15:19:30.675789 | 
2025-12-05 15:19:30.675935 | TASK [ensure-pip : Fail with instructions when EPEL is not installed]
2025-12-05 15:19:30.741910 | controller | skipping: Conditional result was False
2025-12-05 15:19:30.756998 | 
2025-12-05 15:19:30.757145 | TASK [ensure-pip : Install Python 2 pip]
2025-12-05 15:19:30.805307 | controller | skipping: Conditional result was False
2025-12-05 15:19:30.820616 | 
2025-12-05 15:19:30.820797 | TASK [ensure-pip : Ensure setuptools]
2025-12-05 15:19:30.849086 | controller | skipping: Conditional result was False
2025-12-05 15:19:30.861718 | 
2025-12-05 15:19:30.861858 | TASK [ensure-pip : Check for ensurepip module]
2025-12-05 15:19:31.409272 | controller | skipping: Conditional result was False
2025-12-05 15:19:31.422297 | 
2025-12-05 15:19:31.422437 | TASK [ensure-pip : Ensure python3-venv]
2025-12-05 15:19:31.450462 | controller | skipping: Conditional result was False
2025-12-05 15:19:31.466093 | 
2025-12-05 15:19:31.466234 | TASK [ensure-pip : Install pip from source]
2025-12-05 15:19:31.493787 | controller | skipping: Conditional result was False
2025-12-05 15:19:31.506588 | 
2025-12-05 15:19:31.506778 | TASK [ensure-pip : Probe for venv python full path]
2025-12-05 10:19:31.804371 | controller | /usr/bin/python3
2025-12-05 15:19:32.054961 | controller | ok: Runtime: 0:00:00.007453
2025-12-05 15:19:32.061143 | 
2025-12-05 15:19:32.061208 | TASK [ensure-pip : Set host default]
2025-12-05 15:19:32.142830 | controller | ok
2025-12-05 15:19:32.149598 | 
2025-12-05 15:19:32.149661 | TASK [ensure-pip : Set ensure_pip_virtualenv_command]
2025-12-05 15:19:32.212617 | controller | ok
2025-12-05 15:19:32.223183 | 
2025-12-05 15:19:32.223247 | TASK [bindep : Install bindep into temporary venv]
2025-12-05 15:19:36.174331 | controller | changed
2025-12-05 15:19:36.187386 | 
2025-12-05 15:19:36.187533 | TASK [bindep : Define bindep_command]
2025-12-05 15:19:36.237878 | controller | ok
2025-12-05 15:19:36.249096 | 
2025-12-05 15:19:36.249230 | LOOP [bindep : Include package tasks]
2025-12-05 15:19:36.335855 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt"
2025-12-05 15:19:36.336165 | controller | ok: All items complete
2025-12-05 15:19:36.336219 | 
2025-12-05 15:19:36.361912 | controller | included: /var/lib/zuul/builds/6414a239b3e3403d90c81f78c4af09e8/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml
2025-12-05 15:19:36.389570 | 
2025-12-05 15:19:36.389751 | TASK [bindep : Define bindep_run fact]
2025-12-05 15:19:36.433369 | controller | ok
2025-12-05 15:19:36.440538 | 
2025-12-05 15:19:36.440621 | TASK [bindep : Get list of packages to install from bindep]
2025-12-05 10:19:37.871448 | controller | podman
2025-12-05 10:19:37.905293 | controller | python3-jmespath
2025-12-05 10:19:37.905318 | controller | python3-libvirt
2025-12-05 10:19:37.905328 | controller | python3-lxml
2025-12-05 10:19:37.905339 | controller | python3-netaddr
2025-12-05 15:19:38.006367 | controller | ok: Runtime: 0:00:01.138037
2025-12-05 15:19:38.018929 | 
2025-12-05 15:19:38.019088 | TASK [bindep : Install distro packages from bindep]
2025-12-05 15:20:45.094317 | controller | changed
2025-12-05 15:20:45.100183 | 
2025-12-05 15:20:45.100251 | TASK [bindep : Check that packages are installed]
2025-12-05 15:20:46.679299 | controller | ok: Runtime: 0:00:01.188030
2025-12-05 15:20:46.684835 | 
2025-12-05 15:20:46.684896 | TASK [bindep : Fail if we cannot install all packages]
2025-12-05 15:20:46.709478 | controller | skipping: Conditional result was False
2025-12-05 15:20:46.725150 | 
2025-12-05 15:20:46.725232 | TASK [Run test-setup role]
2025-12-05 15:20:46.748123 | controller | ok
2025-12-05 15:20:46.767418 | 
2025-12-05 15:20:46.767496 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-12-05 15:20:46.991509 | controller | ok
2025-12-05 15:20:46.998455 | 
2025-12-05 15:20:46.998519 | TASK [test-setup : Run tools/test-setup.sh]
2025-12-05 15:20:47.530806 | controller | skipping: Conditional result was False
2025-12-05 15:20:47.579380 | 
2025-12-05 15:20:47.579515 | TASK [bindep : Remove bindep temp dir]
2025-12-05 15:20:48.069297 | controller | ok
2025-12-05 15:20:48.079680 | 
2025-12-05 15:20:48.079738 | PLAY RECAP
2025-12-05 15:20:48.079783 | controller | ok: 22 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0
2025-12-05 15:20:48.079803 | 
2025-12-05 15:20:48.182541 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-05 15:20:48.183441 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2025-12-05 15:20:48.753058 | 
2025-12-05 15:20:48.753180 | PLAY [all]
2025-12-05 15:20:48.781451 | 
2025-12-05 15:20:48.781521 | TASK [Abort when test_command variable is undefined]
2025-12-05 15:20:48.816461 | controller | skipping: Conditional result was False
2025-12-05 15:20:48.822643 | 
2025-12-05 15:20:48.822765 | TASK [Convert test_command to list]
2025-12-05 15:20:48.865497 | controller | skipping: Conditional result was False
2025-12-05 15:20:48.872277 | 
2025-12-05 15:20:48.872346 | TASK [Use test_command list]
2025-12-05 15:20:48.933518 | controller | ok
2025-12-05 15:20:48.938822 | 
2025-12-05 15:20:48.938882 | LOOP [Run test_command]
2025-12-05 15:20:49.356495 | controller | no check to run
2025-12-05 15:20:49.356734 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.006573
2025-12-05 15:20:49.382511 | 
2025-12-05 15:20:49.382604 | PLAY RECAP
2025-12-05 15:20:49.382645 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-05 15:20:49.382717 | 
2025-12-05 15:20:49.493480 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2025-12-05 15:20:49.494369 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-05 15:20:50.088120 | 
2025-12-05 15:20:50.088236 | PLAY [all]
2025-12-05 15:20:50.108468 | 
2025-12-05 15:20:50.108548 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-12-05 15:20:50.455980 | controller | changed: non-zero return code
2025-12-05 15:20:50.471485 | 
2025-12-05 15:20:50.471563 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-12-05 15:20:50.490254 | controller | skipping: Conditional result was False
2025-12-05 15:20:50.496633 | 
2025-12-05 15:20:50.496732 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-12-05 15:20:50.527782 | 
2025-12-05 15:20:50.527947 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-12-05 15:20:50.557184 | 
2025-12-05 15:20:50.557314 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-12-05 15:20:50.582027 | controller | skipping: Conditional result was False
2025-12-05 15:20:50.588240 | 
2025-12-05 15:20:50.588324 | LOOP [fetch-subunit-output : Generate subunit file]
2025-12-05 15:20:50.623897 | 
2025-12-05 15:20:50.624038 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-12-05 15:20:50.639084 | controller | skipping: Conditional result was False
2025-12-05 15:20:50.646556 | 
2025-12-05 15:20:50.646622 | TASK [fetch-subunit-output : Remove the temporary file]
2025-12-05 15:20:50.660659 | controller | skipping: Conditional result was False
2025-12-05 15:20:50.667968 | 
2025-12-05 15:20:50.668054 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-12-05 15:20:50.683881 | controller | skipping: Conditional result was False
2025-12-05 15:20:50.707837 | 
2025-12-05 15:20:50.707888 | PLAY RECAP
2025-12-05 15:20:50.707931 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-05 15:20:50.707951 | 
2025-12-05 15:20:50.799377 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-05 15:20:50.800240 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 15:20:51.383366 | 
2025-12-05 15:20:51.383488 | PLAY [all]
2025-12-05 15:20:51.403189 | 
2025-12-05 15:20:51.403284 | TASK [include_role : fetch-output]
2025-12-05 15:20:51.433518 | controller | ok
2025-12-05 15:20:51.451134 | 
2025-12-05 15:20:51.451216 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-05 15:20:51.496560 | controller | skipping: Conditional result was False
2025-12-05 15:20:51.504117 | 
2025-12-05 15:20:51.504205 | TASK [fetch-output : Set log path for single node]
2025-12-05 15:20:51.535930 | controller | ok
2025-12-05 15:20:51.542334 | 
2025-12-05 15:20:51.542427 | LOOP [fetch-output : Ensure local output dirs]
2025-12-05 15:20:51.945061 | controller -> localhost | ok: "/var/lib/zuul/builds/6414a239b3e3403d90c81f78c4af09e8/work/logs"
2025-12-05 15:20:52.155026 | controller -> localhost | changed: "/var/lib/zuul/builds/6414a239b3e3403d90c81f78c4af09e8/work/artifacts"
2025-12-05 15:20:52.370472 | controller -> localhost | changed: "/var/lib/zuul/builds/6414a239b3e3403d90c81f78c4af09e8/work/docs"
2025-12-05 15:20:52.385600 | 
2025-12-05 15:20:52.385741 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-05 15:20:53.018986 | controller | changed: .d..t...... ./
2025-12-05 15:20:53.019229 | controller | changed: All items complete
2025-12-05 15:20:53.019267 | 
2025-12-05 15:20:53.531769 | controller | changed: .d..t...... ./
2025-12-05 15:20:53.981123 | controller | changed: .d..t...... ./
2025-12-05 15:20:54.004235 | 
2025-12-05 15:20:54.004392 | TASK [include_role : fetch-output-openshift]
2025-12-05 15:20:54.030699 | controller | skipping: Conditional result was False
2025-12-05 15:20:54.039022 | 
2025-12-05 15:20:54.039131 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-05 15:20:54.484806 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.015729
2025-12-05 15:20:54.707650 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008693
2025-12-05 15:20:54.739195 | 
2025-12-05 15:20:54.739297 | PLAY [all]
2025-12-05 15:20:54.755275 | 
2025-12-05 15:20:54.755343 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-05 15:20:55.214047 | controller | changed
2025-12-05 15:20:55.236582 | 
2025-12-05 15:20:55.236638 | PLAY RECAP
2025-12-05 15:20:55.236698 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-05 15:20:55.236724 | 
2025-12-05 15:20:55.344800 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 15:20:55.346648 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-05 15:20:55.987922 | 
2025-12-05 15:20:55.988095 | PLAY [localhost]
2025-12-05 15:20:56.006803 | 
2025-12-05 15:20:56.006895 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-05 15:20:56.442920 | localhost | changed
2025-12-05 15:20:56.448135 | 
2025-12-05 15:20:56.448214 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-05 15:20:56.489841 | localhost | ok
2025-12-05 15:20:56.497821 | 
2025-12-05 15:20:56.497891 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-05 15:20:56.944085 | localhost | changed
2025-12-05 15:20:56.950969 | 
2025-12-05 15:20:56.951043 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-05 15:20:57.754763 | localhost | changed
2025-12-05 15:20:57.760629 | 
2025-12-05 15:20:57.760714 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-05 15:20:58.249771 | localhost | Identity added: /var/lib/zuul/builds/6414a239b3e3403d90c81f78c4af09e8/work/tmp/ansible.lw61fvlk (/var/lib/zuul/builds/6414a239b3e3403d90c81f78c4af09e8/work/tmp/ansible.lw61fvlk)
2025-12-05 15:20:58.250061 | localhost | ok: Runtime: 0:00:00.008964
2025-12-05 15:20:58.254754 | 
2025-12-05 15:20:58.254824 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-05 15:20:58.553915 | localhost | ok: Runtime: 0:00:00.006151
2025-12-05 15:20:58.565846 | 
2025-12-05 15:20:58.565984 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-05 15:20:58.623859 | localhost | changed
2025-12-05 15:20:58.633357 | 
2025-12-05 15:20:58.633493 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-05 15:20:59.062115 | localhost | changed
2025-12-05 15:20:59.083691 | 
2025-12-05 15:20:59.083755 | PLAY [localhost]
2025-12-05 15:20:59.096366 | 
2025-12-05 15:20:59.096438 | TASK [Generate bulk log download script]
2025-12-05 15:20:59.116489 | localhost | ok
2025-12-05 15:20:59.128690 | 
2025-12-05 15:20:59.128755 | TASK [local-log-download : Check API endpoint is defined]
2025-12-05 15:20:59.158392 | localhost | ok: All assertions passed
2025-12-05 15:20:59.163202 | 
2025-12-05 15:20:59.163262 | TASK [local-log-download : Create download script]
2025-12-05 15:20:59.605552 | localhost -> localhost | changed
2025-12-05 15:20:59.625844 | 
2025-12-05 15:20:59.625977 | TASK [Register quick-download link]
2025-12-05 15:20:59.666542 | localhost | ok
2025-12-05 15:20:59.737257 | 
2025-12-05 15:20:59.737437 | PLAY [logserver.rdoproject.org]
2025-12-05 15:20:59.747326 | 
2025-12-05 15:20:59.747391 | TASK [Set zuul-log-path fact]
2025-12-05 15:20:59.767102 | logserver.rdoproject.org | ok
2025-12-05 15:20:59.776630 | 
2025-12-05 15:20:59.776717 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 15:20:59.814449 | logserver.rdoproject.org | ok
2025-12-05 15:20:59.820367 | 
2025-12-05 15:20:59.820438 | TASK [upload-logs : Create log directories]
2025-12-05 15:21:00.455319 | logserver.rdoproject.org | changed
2025-12-05 15:21:00.462807 | 
2025-12-05 15:21:00.462960 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-05 15:21:00.767751 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.005700
2025-12-05 15:21:00.778723 | 
2025-12-05 15:21:00.778865 | TASK [upload-logs : Upload logs to log server]
2025-12-05 15:21:01.489914 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-05 15:21:01.496625 | 
2025-12-05 15:21:01.496794 | LOOP [upload-logs : Compress console log and json output]
2025-12-05 15:21:01.580698 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:21:01.592605 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:21:01.602706 | 
2025-12-05 15:21:01.602902 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-05 15:21:01.675598 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:21:01.676022 | 
2025-12-05 15:21:01.682644 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:21:01.695305 | 
2025-12-05 15:21:01.695472 | LOOP [upload-logs : Upload console log and json output]