2026-03-16 16:29:00.904981 | Job console starting...
2026-03-16 16:29:00.920869 | Updating repositories
2026-03-16 16:29:01.554292 | Preparing job workspace
2026-03-16 16:29:05.829282 | Running Ansible setup...
2026-03-16 16:29:10.250895 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-16 16:29:10.839588 | 
2026-03-16 16:29:10.839712 | PLAY [localhost]
2026-03-16 16:29:10.848491 | 
2026-03-16 16:29:10.848580 | TASK [Gathering Facts]
2026-03-16 16:29:11.847468 | localhost | ok
2026-03-16 16:29:11.866492 | 
2026-03-16 16:29:11.866622 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-16 16:29:12.295503 | localhost -> localhost | changed
2026-03-16 16:29:12.302296 | 
2026-03-16 16:29:12.302383 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-16 16:29:13.123379 | localhost -> localhost | changed
2026-03-16 16:29:13.138846 | 
2026-03-16 16:29:13.138953 | TASK [Setup log path fact]
2026-03-16 16:29:13.157208 | localhost | ok
2026-03-16 16:29:13.169586 | 
2026-03-16 16:29:13.169703 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-16 16:29:13.200871 | localhost | ok
2026-03-16 16:29:13.214460 | 
2026-03-16 16:29:13.214613 | TASK [emit-job-header : Print job information]
2026-03-16 16:29:13.259870 | # Job Information
2026-03-16 16:29:13.260151 | Ansible Version: 2.15.12
2026-03-16 16:29:13.260208 | Job: cifmw-molecule-ipa
2026-03-16 16:29:13.260249 | Pipeline: github-check
2026-03-16 16:29:13.260286 | Executor: ze01.softwarefactory-project.io
2026-03-16 16:29:13.260326 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3770
2026-03-16 16:29:13.260367 | Log URL (when completed): https://logserver.rdoproject.org/160/rdoproject.org/16028bc2dcbe47edb7f3e0a2b5763617/
2026-03-16 16:29:13.260407 | Event ID: 072a8580-2155-11f1-9026-7307b7bf6b98
2026-03-16 16:29:13.268400 | 
2026-03-16 16:29:13.268866 | LOOP [emit-job-header : Print node information]
2026-03-16 16:29:13.380600 | localhost | ok:
2026-03-16 16:29:13.380766 | localhost | # Node Information
2026-03-16 16:29:13.380796 | localhost | Inventory Hostname: controller
2026-03-16 16:29:13.380821 | localhost | Hostname: np0005648698
2026-03-16 16:29:13.380841 | localhost | Username: zuul
2026-03-16 16:29:13.380862 | localhost | Distro: CentOS 9
2026-03-16 16:29:13.380880 | localhost | Provider: vexxhost-nodepool-tripleo
2026-03-16 16:29:13.380897 | localhost | Region: RegionOne
2026-03-16 16:29:13.380919 | localhost | Label: cloud-centos-9-stream-tripleo
2026-03-16 16:29:13.380942 | localhost | Product Name: OpenStack Nova
2026-03-16 16:29:13.380963 | localhost | Interface IP: 38.102.83.162
2026-03-16 16:29:13.425948 | 
2026-03-16 16:29:13.426056 | PLAY [all]
2026-03-16 16:29:13.436939 | 
2026-03-16 16:29:13.437107 | TASK [Gather network facts]
2026-03-16 16:29:13.930593 | controller | ok
2026-03-16 16:29:13.963842 | 
2026-03-16 16:29:13.963962 | TASK [include_role : start-zuul-console]
2026-03-16 16:29:14.005600 | controller | ok
2026-03-16 16:29:14.029095 | 
2026-03-16 16:29:14.029198 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-03-16 16:29:14.484987 | controller | ok
2026-03-16 16:29:14.514431 | 
2026-03-16 16:29:14.514533 | TASK [include_role : add-build-sshkey]
2026-03-16 16:29:14.578910 | controller | ok
2026-03-16 16:29:14.595887 | 
2026-03-16 16:29:14.596005 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-16 16:29:14.970822 | controller -> localhost | ok
2026-03-16 16:29:14.976540 | 
2026-03-16 16:29:14.976629 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-16 16:29:15.014661 | controller | ok
2026-03-16 16:29:15.030637 | controller | included: /var/lib/zuul/builds/16028bc2dcbe47edb7f3e0a2b5763617/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-16 16:29:15.036932 | 
2026-03-16 16:29:15.036998 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-16 16:29:15.759032 | controller -> localhost | Generating public/private rsa key pair.
2026-03-16 16:29:15.759243 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/16028bc2dcbe47edb7f3e0a2b5763617/work/16028bc2dcbe47edb7f3e0a2b5763617_id_rsa.
2026-03-16 16:29:15.759275 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/16028bc2dcbe47edb7f3e0a2b5763617/work/16028bc2dcbe47edb7f3e0a2b5763617_id_rsa.pub.
2026-03-16 16:29:15.759297 | controller -> localhost | The key fingerprint is:
2026-03-16 16:29:15.759318 | controller -> localhost | SHA256:UBDjCfAGHO3FP3aOIg+NliQd4q2DYNy49o5Mpm2G8/I zuul-build-sshkey
2026-03-16 16:29:15.759337 | controller -> localhost | The key's randomart image is:
2026-03-16 16:29:15.759355 | controller -> localhost | +---[RSA 3072]----+
2026-03-16 16:29:15.759375 | controller -> localhost | | .++..+o.        |
2026-03-16 16:29:15.759396 | controller -> localhost | |  ooooo+         |
2026-03-16 16:29:15.759416 | controller -> localhost | |..o=oo+.         |
2026-03-16 16:29:15.759435 | controller -> localhost | |.+oo=  .+ .      |
2026-03-16 16:29:15.759453 | controller -> localhost | |o..+ + .S=       |
2026-03-16 16:29:15.759471 | controller -> localhost | |.oo * o . .      |
2026-03-16 16:29:15.759488 | controller -> localhost | |.+.o + .         |
2026-03-16 16:29:15.759508 | controller -> localhost | |O.+.  .          |
2026-03-16 16:29:15.759527 | controller -> localhost | |.XE.             |
2026-03-16 16:29:15.759545 | controller -> localhost | +----[SHA256]-----+
2026-03-16 16:29:15.759587 | controller -> localhost | ok: Runtime: 0:00:00.267071
2026-03-16 16:29:15.766347 | 
2026-03-16 16:29:15.766450 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-16 16:29:15.786697 | controller | ok
2026-03-16 16:29:15.798161 | controller | included: /var/lib/zuul/builds/16028bc2dcbe47edb7f3e0a2b5763617/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-16 16:29:15.807410 | 
2026-03-16 16:29:15.807498 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-16 16:29:15.821698 | controller | skipping: Conditional result was False
2026-03-16 16:29:15.829207 | 
2026-03-16 16:29:15.829296 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-16 16:29:16.325095 | controller | changed
2026-03-16 16:29:16.333966 | 
2026-03-16 16:29:16.334114 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-16 16:29:16.566795 | controller | ok
2026-03-16 16:29:16.581129 | 
2026-03-16 16:29:16.581284 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-16 16:29:17.451977 | controller | changed
2026-03-16 16:29:17.458093 | 
2026-03-16 16:29:17.458172 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-16 16:29:18.194688 | controller | changed
2026-03-16 16:29:18.213156 | 
2026-03-16 16:29:18.213255 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-16 16:29:18.237347 | controller | skipping: Conditional result was False
2026-03-16 16:29:18.242891 | 
2026-03-16 16:29:18.242957 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-16 16:29:18.855562 | controller -> localhost | changed
2026-03-16 16:29:18.874722 | 
2026-03-16 16:29:18.874948 | TASK [add-build-sshkey : Add back temp key]
2026-03-16 16:29:19.267564 | controller -> localhost | Identity added: /var/lib/zuul/builds/16028bc2dcbe47edb7f3e0a2b5763617/work/16028bc2dcbe47edb7f3e0a2b5763617_id_rsa (zuul-build-sshkey)
2026-03-16 16:29:19.267754 | controller -> localhost | ok: Runtime: 0:00:00.017904
2026-03-16 16:29:19.274009 | 
2026-03-16 16:29:19.274085 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-16 16:29:19.710039 | controller | ok
2026-03-16 16:29:19.722987 | 
2026-03-16 16:29:19.723097 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-16 16:29:19.788183 | controller | skipping: Conditional result was False
2026-03-16 16:29:19.799544 | 
2026-03-16 16:29:19.799610 | TASK [include_role : validate-host]
2026-03-16 16:29:19.899392 | controller | ok
2026-03-16 16:29:19.944616 | 
2026-03-16 16:29:19.944730 | TASK [validate-host : Define zuul_info_dir fact]
2026-03-16 16:29:19.980004 | controller | ok
2026-03-16 16:29:19.985467 | 
2026-03-16 16:29:19.985549 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-03-16 16:29:20.272074 | controller -> localhost | ok
2026-03-16 16:29:20.279792 | 
2026-03-16 16:29:20.279915 | TASK [validate-host : Collect information about the host]
2026-03-16 16:29:21.040327 | controller | ok
2026-03-16 16:29:21.051956 | 
2026-03-16 16:29:21.052120 | TASK [validate-host : Sanitize hostname]
2026-03-16 16:29:21.144251 | controller | ok
2026-03-16 16:29:21.163316 | 
2026-03-16 16:29:21.163431 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-03-16 16:29:21.763545 | controller -> localhost | changed
2026-03-16 16:29:21.771906 | 
2026-03-16 16:29:21.772166 | TASK [validate-host : Collect information about zuul worker]
2026-03-16 16:29:22.174177 | controller | ok
2026-03-16 16:29:22.180058 | 
2026-03-16 16:29:22.180191 | TASK [validate-host : Write out all zuul information for each host]
2026-03-16 16:29:22.798494 | controller -> localhost | changed
2026-03-16 16:29:22.811156 | 
2026-03-16 16:29:22.811262 | TASK [include_role : prepare-workspace-openshift]
2026-03-16 16:29:22.835291 | controller | skipping: Conditional result was False
2026-03-16 16:29:22.841169 | 
2026-03-16 16:29:22.841261 | TASK [include_role : remove-zuul-sshkey]
2026-03-16 16:29:22.885754 | controller | skipping: Conditional result was False
2026-03-16 16:29:22.892394 | 
2026-03-16 16:29:22.892492 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-16 16:29:23.135720 | controller | ok: "logs"
2026-03-16 16:29:23.136609 | controller | ok: All items complete
2026-03-16 16:29:23.136654 | 
2026-03-16 16:29:23.366502 | controller | ok: "artifacts"
2026-03-16 16:29:23.568704 | controller | ok: "docs"
2026-03-16 16:29:23.581539 | 
2026-03-16 16:29:23.581652 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-16 16:29:23.858115 | controller | changed: "logs"
2026-03-16 16:29:24.069076 | controller | changed: "artifacts"
2026-03-16 16:29:24.295098 | controller | changed: "docs"
2026-03-16 16:29:24.407001 | 
2026-03-16 16:29:24.407117 | PLAY RECAP
2026-03-16 16:29:24.407446 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-03-16 16:29:24.407480 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-16 16:29:24.407500 | 
2026-03-16 16:29:24.690682 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-03-16 16:29:24.691492 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2026-03-16 16:29:25.818003 | 
2026-03-16 16:29:25.818172 | PLAY [localhost]
2026-03-16 16:29:25.865180 | 
2026-03-16 16:29:25.865340 | TASK [configure-ssh-config-fips : Ensure ssh config directory]
2026-03-16 16:29:26.600399 | localhost | ok
2026-03-16 16:29:26.608930 | 
2026-03-16 16:29:26.609115 | TASK [configure-ssh-config-fips : Setup ssh config]
2026-03-16 16:29:27.609719 | localhost | changed
2026-03-16 16:29:27.660440 | 
2026-03-16 16:29:27.660526 | PLAY [all]
2026-03-16 16:29:27.692221 | 
2026-03-16 16:29:27.692338 | TASK [include_role : prepare-workspace]
2026-03-16 16:29:27.721901 | controller | ok
2026-03-16 16:29:27.748326 | 
2026-03-16 16:29:27.748453 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-16 16:29:28.243554 | controller | ok
2026-03-16 16:29:28.250358 | 
2026-03-16 16:29:28.250451 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-16 16:29:30.573909 | controller | Output suppressed because no_log was given
2026-03-16 16:29:30.594881 | 
2026-03-16 16:29:30.595010 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-16 16:29:30.936461 | controller | changed: "logs"
2026-03-16 16:29:31.124941 | controller | changed: "artifacts"
2026-03-16 16:29:31.355864 | controller | changed: "docs"
2026-03-16 16:29:31.370231 | 
2026-03-16 16:29:31.370347 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-16 16:29:31.623369 | controller | changed: "logs"
2026-03-16 16:29:31.623599 | controller | changed: All items complete
2026-03-16 16:29:31.623626 | 
2026-03-16 16:29:31.842871 | controller | changed: "artifacts"
2026-03-16 16:29:32.089126 | controller | changed: "docs"
2026-03-16 16:29:32.112188 | 
2026-03-16 16:29:32.112306 | TASK [Check if worker can sudo]
2026-03-16 16:29:32.642742 | controller | ok: Runtime: 0:00:00.042470
2026-03-16 16:29:32.649510 | 
2026-03-16 16:29:32.649724 | TASK [configure-mirrors : Gather needed facts]
2026-03-16 16:29:32.716296 | controller | skipping: Conditional result was False
2026-03-16 16:29:32.725061 | 
2026-03-16 16:29:32.725144 | TASK [configure-mirrors : Set up infrastructure mirrors]
2026-03-16 16:29:32.816810 | controller | ok
2026-03-16 16:29:32.864867 | controller | included: /var/lib/zuul/builds/16028bc2dcbe47edb7f3e0a2b5763617/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml
2026-03-16 16:29:32.873756 | 
2026-03-16 16:29:32.873947 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir]
2026-03-16 16:29:33.120031 | controller | ok
2026-03-16 16:29:33.126365 | 
2026-03-16 16:29:33.126438 | LOOP [configure-mirrors : Include OS-specific variables]
2026-03-16 16:29:33.208866 | controller | ok: "/var/lib/zuul/builds/16028bc2dcbe47edb7f3e0a2b5763617/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml"
2026-03-16 16:29:33.221433 | 
2026-03-16 16:29:33.221546 | TASK [configure-mirrors : Install /etc/pip.conf configuration]
2026-03-16 16:29:34.077473 | controller | changed
2026-03-16 16:29:34.090657 | 
2026-03-16 16:29:34.090809 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors]
2026-03-16 16:29:34.159583 | controller | ok: "/var/lib/zuul/builds/16028bc2dcbe47edb7f3e0a2b5763617/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml"
2026-03-16 16:29:34.159737 | controller | ok: All items complete
2026-03-16 16:29:34.159764 | 
2026-03-16 16:29:34.217874 | controller | included: /var/lib/zuul/builds/16028bc2dcbe47edb7f3e0a2b5763617/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml
2026-03-16 16:29:34.224900 | 
2026-03-16 16:29:34.224971 | LOOP [configure-mirrors : Install CentOS 9 repository files]
2026-03-16 16:29:34.994715 | controller | changed: "etc/yum.repos.d/centos.repo"
2026-03-16 16:29:35.723335 | controller | changed: "etc/yum.repos.d/centos-addons.repo"
2026-03-16 16:29:35.740515 | 
2026-03-16 16:29:35.740623 | TASK [configure-mirrors : Disable deltrarpm]
2026-03-16 16:29:36.267381 | controller | changed: section and option added
2026-03-16 16:29:36.286986 | 
2026-03-16 16:29:36.287072 | LOOP [configure-mirrors : Update yum/dnf cache]
2026-03-16 16:29:37.137507 | controller | 29 files removed
2026-03-16 16:29:37.137897 | controller | ok: Item: dnf clean all Runtime: 0:00:00.582238
2026-03-16 16:29:37.138004 | controller | changed: All items complete
2026-03-16 16:29:37.138108 | 
2026-03-16 16:29:48.646342 | 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
2026-03-16 16:29:48.646476 | controller | DNF version: 4.14.0
2026-03-16 16:29:48.646517 | controller | cachedir: /var/cache/dnf
2026-03-16 16:29:48.646549 | controller | Making cache files for all metadata files.
2026-03-16 16:29:48.646784 | controller | baseos: has expired and will be refreshed.
2026-03-16 16:29:48.646821 | controller | appstream: has expired and will be refreshed.
2026-03-16 16:29:48.646844 | controller | crb: has expired and will be refreshed.
2026-03-16 16:29:48.646882 | controller | extras-common: has expired and will be refreshed.
2026-03-16 16:29:48.646915 | controller | repo: downloading from remote: baseos
2026-03-16 16:29:48.647010 | controller | CentOS Stream 9 - BaseOS                         56 MB/s | 8.9 MB     00:00
2026-03-16 16:29:48.647079 | controller | baseos: using metadata from Mon 09 Mar 2026 10:57:52 AM EDT.
2026-03-16 16:29:48.647113 | controller | repo: downloading from remote: appstream
2026-03-16 16:29:48.647142 | controller | CentOS Stream 9 - AppStream                      76 MB/s |  27 MB     00:00
2026-03-16 16:29:48.647175 | controller | appstream: using metadata from Mon 09 Mar 2026 11:01:27 AM EDT.
2026-03-16 16:29:48.647210 | controller | repo: downloading from remote: crb
2026-03-16 16:29:48.647241 | controller | CentOS Stream 9 - CRB                            43 MB/s | 8.0 MB     00:00
2026-03-16 16:29:48.647272 | controller | crb: using metadata from Mon 09 Mar 2026 11:06:14 AM EDT.
2026-03-16 16:29:48.647301 | controller | repo: downloading from remote: extras-common
2026-03-16 16:29:48.647330 | controller | CentOS Stream 9 - Extras packages               1.7 MB/s |  20 kB     00:00
2026-03-16 16:29:48.647432 | controller | extras-common: using metadata from Mon 09 Mar 2026 09:49:54 AM EDT.
2026-03-16 16:29:48.647468 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)'
2026-03-16 16:29:48.647499 | controller | Completion plugin: Generating completion cache...
2026-03-16 16:29:48.647531 | controller | Metadata cache created.
2026-03-16 16:29:48.647575 | controller | ok: Item: dnf makecache -v Runtime: 0:00:11.289521
2026-03-16 16:29:48.684759 | 
2026-03-16 16:29:48.684883 | PLAY RECAP
2026-03-16 16:29:48.685270 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-03-16 16:29:48.685327 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-16 16:29:48.685354 | 
2026-03-16 16:29:48.854414 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2026-03-16 16:29:48.855224 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-16 16:29:49.527545 | 
2026-03-16 16:29:49.527670 | PLAY [all]
2026-03-16 16:29:49.560734 | 
2026-03-16 16:29:49.560859 | TASK [Install binary dependencies]
2026-03-16 16:29:49.622056 | controller | ok
2026-03-16 16:29:49.646688 | 
2026-03-16 16:29:49.646962 | TASK [bindep : Include find tasks]
2026-03-16 16:29:49.692910 | controller | ok
2026-03-16 16:29:49.706831 | controller | included: /var/lib/zuul/builds/16028bc2dcbe47edb7f3e0a2b5763617/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-03-16 16:29:49.715662 | 
2026-03-16 16:29:49.715764 | TASK [bindep : Look for bindep.txt]
2026-03-16 16:29:50.090134 | controller | ok
2026-03-16 16:29:50.101078 | 
2026-03-16 16:29:50.101209 | TASK [bindep : Define bindep_file fact]
2026-03-16 16:29:50.153066 | controller | ok
2026-03-16 16:29:50.161490 | 
2026-03-16 16:29:50.161620 | TASK [bindep : Look for other-requirements.txt]
2026-03-16 16:29:50.177033 | controller | skipping: Conditional result was False
2026-03-16 16:29:50.185163 | 
2026-03-16 16:29:50.185272 | TASK [bindep : Define bindep_file fact]
2026-03-16 16:29:50.209580 | controller | skipping: Conditional result was False
2026-03-16 16:29:50.217394 | 
2026-03-16 16:29:50.217509 | TASK [bindep : Look for bindep fallback file]
2026-03-16 16:29:50.241259 | controller | skipping: Conditional result was False
2026-03-16 16:29:50.247797 | 
2026-03-16 16:29:50.247899 | TASK [bindep : Define bindep_file fact]
2026-03-16 16:29:50.272352 | controller | skipping: Conditional result was False
2026-03-16 16:29:50.279997 | 
2026-03-16 16:29:50.280155 | TASK [bindep : Include bindep tasks]
2026-03-16 16:29:50.334474 | controller | ok
2026-03-16 16:29:50.342826 | controller | included: /var/lib/zuul/builds/16028bc2dcbe47edb7f3e0a2b5763617/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml
2026-03-16 16:29:50.354163 | 
2026-03-16 16:29:50.354256 | TASK [bindep : Look for bindep command]
2026-03-16 16:29:50.388459 | controller | skipping: Conditional result was False
2026-03-16 16:29:50.396202 | 
2026-03-16 16:29:50.396293 | TASK [bindep : Check for system bindep]
2026-03-16 16:29:50.973216 | controller | ok: Runtime: 0:00:00.004284
2026-03-16 16:29:50.981390 | 
2026-03-16 16:29:50.981576 | TASK [bindep : Define bindep_command fact]
2026-03-16 16:29:51.006197 | controller | skipping: Conditional result was False
2026-03-16 16:29:51.015605 | 
2026-03-16 16:29:51.015712 | TASK [bindep : Include install tasks]
2026-03-16 16:29:51.048058 | controller | ok
2026-03-16 16:29:51.059637 | controller | included: /var/lib/zuul/builds/16028bc2dcbe47edb7f3e0a2b5763617/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml
2026-03-16 16:29:51.073116 | 
2026-03-16 16:29:51.073209 | TASK [bindep : Create temp dir for bindep]
2026-03-16 16:29:51.395199 | controller | changed
2026-03-16 16:29:51.402876 | 
2026-03-16 16:29:51.402961 | TASK [Ensure we have pip dependencies]
2026-03-16 16:29:51.423694 | controller | ok
2026-03-16 16:29:51.452675 | 
2026-03-16 16:29:51.452805 | TASK [ensure-pip : Check if pip is installed]
2026-03-16 12:29:51.680227 | controller | /usr/bin/pip3
2026-03-16 12:29:51.700270 | controller | /usr/bin/python3: No module named wheel
2026-03-16 16:29:51.986418 | controller | ok: Runtime: 0:00:00.027643
2026-03-16 16:29:51.992500 | 
2026-03-16 16:29:51.992588 | LOOP [ensure-pip : Install pip from packages]
2026-03-16 16:29:52.027644 | controller | ok: "/var/lib/zuul/builds/16028bc2dcbe47edb7f3e0a2b5763617/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml"
2026-03-16 16:29:52.054349 | controller | included: /var/lib/zuul/builds/16028bc2dcbe47edb7f3e0a2b5763617/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml
2026-03-16 16:29:52.080705 | 
2026-03-16 16:29:52.080799 | TASK [ensure-pip : Install Python 3 pip]
2026-03-16 16:29:53.566956 | controller | ok: Nothing to do
2026-03-16 16:29:53.572615 | 
2026-03-16 16:29:53.572678 | TASK [ensure-pip : Install Python3-wheel on older CentOS]
2026-03-16 16:29:55.101681 | controller | changed
2026-03-16 16:29:55.107611 | 
2026-03-16 16:29:55.107678 | TASK [ensure-pip : Check for EPEL repository]
2026-03-16 16:29:55.173402 | controller | skipping: Conditional result was False
2026-03-16 16:29:55.180973 | 
2026-03-16 16:29:55.181076 | TASK [ensure-pip : Fail with instructions when EPEL is not installed]
2026-03-16 16:29:55.236229 | controller | skipping: Conditional result was False
2026-03-16 16:29:55.243288 | 
2026-03-16 16:29:55.243364 | TASK [ensure-pip : Install Python 2 pip]
2026-03-16 16:29:55.300300 | controller | skipping: Conditional result was False
2026-03-16 16:29:55.312623 | 
2026-03-16 16:29:55.312731 | TASK [ensure-pip : Ensure setuptools]
2026-03-16 16:29:55.360710 | controller | skipping: Conditional result was False
2026-03-16 16:29:55.369329 | 
2026-03-16 16:29:55.369422 | TASK [ensure-pip : Check for ensurepip module]
2026-03-16 16:29:55.913814 | controller | skipping: Conditional result was False
2026-03-16 16:29:55.920237 | 
2026-03-16 16:29:55.920328 | TASK [ensure-pip : Ensure python3-venv]
2026-03-16 16:29:55.944618 | controller | skipping: Conditional result was False
2026-03-16 16:29:55.951136 | 
2026-03-16 16:29:55.951239 | TASK [ensure-pip : Install pip from source]
2026-03-16 16:29:55.965261 | controller | skipping: Conditional result was False
2026-03-16 16:29:55.972176 | 
2026-03-16 16:29:55.972268 | TASK [ensure-pip : Probe for venv python full path]
2026-03-16 12:29:56.199660 | controller | /usr/bin/python3
2026-03-16 16:29:56.513361 | controller | ok: Runtime: 0:00:00.006004
2026-03-16 16:29:56.518980 | 
2026-03-16 16:29:56.519067 | TASK [ensure-pip : Set host default]
2026-03-16 16:29:56.601241 | controller | ok
2026-03-16 16:29:56.606519 | 
2026-03-16 16:29:56.606599 | TASK [ensure-pip : Set ensure_pip_virtualenv_command]
2026-03-16 16:29:56.757945 | controller | ok
2026-03-16 16:29:56.785293 | 
2026-03-16 16:29:56.785418 | TASK [bindep : Install bindep into temporary venv]
2026-03-16 16:30:01.128314 | controller | changed
2026-03-16 16:30:01.133711 | 
2026-03-16 16:30:01.133776 | TASK [bindep : Define bindep_command]
2026-03-16 16:30:01.163075 | controller | ok
2026-03-16 16:30:01.169075 | 
2026-03-16 16:30:01.169160 | LOOP [bindep : Include package tasks]
2026-03-16 16:30:01.234213 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt"
2026-03-16 16:30:01.234410 | controller | ok: All items complete
2026-03-16 16:30:01.234450 | 
2026-03-16 16:30:01.243531 | controller | included: /var/lib/zuul/builds/16028bc2dcbe47edb7f3e0a2b5763617/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml
2026-03-16 16:30:01.256825 | 
2026-03-16 16:30:01.256920 | TASK [bindep : Define bindep_run fact]
2026-03-16 16:30:01.290750 | controller | ok
2026-03-16 16:30:01.296697 | 
2026-03-16 16:30:01.296854 | TASK [bindep : Get list of packages to install from bindep]
2026-03-16 12:30:02.531279 | controller | podman
2026-03-16 12:30:02.557719 | controller | python3-jmespath
2026-03-16 12:30:02.557870 | controller | python3-libvirt
2026-03-16 12:30:02.557883 | controller | python3-lxml
2026-03-16 12:30:02.557893 | controller | python3-netaddr
2026-03-16 16:30:02.842794 | controller | ok: Runtime: 0:00:01.017319
2026-03-16 16:30:02.849658 | 
2026-03-16 16:30:02.849765 | TASK [bindep : Install distro packages from bindep]
2026-03-16 16:30:49.211790 | controller | changed
2026-03-16 16:30:49.217771 | 
2026-03-16 16:30:49.217858 | TASK [bindep : Check that packages are installed]
2026-03-16 16:30:52.584433 | controller | ok: Runtime: 0:00:01.054897
2026-03-16 16:30:52.590503 | 
2026-03-16 16:30:52.590601 | TASK [bindep : Fail if we cannot install all packages]
2026-03-16 16:30:52.616380 | controller | skipping: Conditional result was False
2026-03-16 16:30:52.628681 | 
2026-03-16 16:30:52.628794 | TASK [Run test-setup role]
2026-03-16 16:30:52.649425 | controller | ok
2026-03-16 16:30:52.673786 | 
2026-03-16 16:30:52.673996 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-03-16 16:30:52.919880 | controller | ok
2026-03-16 16:30:52.925574 | 
2026-03-16 16:30:52.925685 | TASK [test-setup : Run tools/test-setup.sh]
2026-03-16 16:30:53.466503 | controller | skipping: Conditional result was False
2026-03-16 16:30:53.489121 | 
2026-03-16 16:30:53.489317 | TASK [bindep : Remove bindep temp dir]
2026-03-16 16:30:57.417687 | controller | ok
2026-03-16 16:30:57.437731 | 
2026-03-16 16:30:57.437815 | PLAY RECAP
2026-03-16 16:30:57.437858 | controller | ok: 23 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0
2026-03-16 16:30:57.437877 | 
2026-03-16 16:31:02.623048 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-16 16:31:02.623980 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2026-03-16 16:31:03.802832 | 
2026-03-16 16:31:03.802989 | PLAY [all]
2026-03-16 16:31:03.856377 | 
2026-03-16 16:31:03.856511 | TASK [Abort when test_command variable is undefined]
2026-03-16 16:31:03.911926 | controller | skipping: Conditional result was False
2026-03-16 16:31:03.918953 | 
2026-03-16 16:31:03.919080 | TASK [Convert test_command to list]
2026-03-16 16:31:03.999637 | controller | skipping: Conditional result was False
2026-03-16 16:31:04.007285 | 
2026-03-16 16:31:04.007363 | TASK [Use test_command list]
2026-03-16 16:31:04.071717 | controller | ok
2026-03-16 16:31:04.077586 | 
2026-03-16 16:31:04.077679 | LOOP [Run test_command]
2026-03-16 16:31:04.542103 | controller | no check to run
2026-03-16 16:31:04.542303 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.004459
2026-03-16 16:31:04.574670 | 
2026-03-16 16:31:04.574798 | PLAY RECAP
2026-03-16 16:31:04.574843 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-03-16 16:31:04.574870 | 
2026-03-16 16:31:04.755739 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2026-03-16 16:31:04.756956 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-16 16:31:05.747880 | 
2026-03-16 16:31:05.747987 | PLAY [all]
2026-03-16 16:31:05.790484 | 
2026-03-16 16:31:05.790625 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-03-16 16:31:06.167313 | controller | changed: non-zero return code
2026-03-16 16:31:06.172689 | 
2026-03-16 16:31:06.172762 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-03-16 16:31:06.226984 | controller | skipping: Conditional result was False
2026-03-16 16:31:06.232632 | 
2026-03-16 16:31:06.232701 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-03-16 16:31:06.283687 | 
2026-03-16 16:31:06.283844 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-03-16 16:31:06.344154 | 
2026-03-16 16:31:06.344321 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-03-16 16:31:06.378289 | controller | skipping: Conditional result was False
2026-03-16 16:31:06.384920 | 
2026-03-16 16:31:06.384989 | LOOP [fetch-subunit-output : Generate subunit file]
2026-03-16 16:31:06.436503 | 
2026-03-16 16:31:06.436655 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-03-16 16:31:06.463522 | controller | skipping: Conditional result was False
2026-03-16 16:31:06.469198 | 
2026-03-16 16:31:06.469266 | TASK [fetch-subunit-output : Remove the temporary file]
2026-03-16 16:31:06.513215 | controller | skipping: Conditional result was False
2026-03-16 16:31:06.519213 | 
2026-03-16 16:31:06.519280 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-03-16 16:31:06.583906 | controller | skipping: Conditional result was False
2026-03-16 16:31:06.636028 | 
2026-03-16 16:31:06.636147 | PLAY RECAP
2026-03-16 16:31:06.636198 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-16 16:31:06.636219 | 
2026-03-16 16:31:06.881109 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-16 16:31:06.882062 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-16 16:31:08.103563 | 
2026-03-16 16:31:08.103702 | PLAY [all]
2026-03-16 16:31:08.132071 | 
2026-03-16 16:31:08.132224 | TASK [include_role : fetch-output]
2026-03-16 16:31:08.228453 | controller | ok
2026-03-16 16:31:08.269363 | 
2026-03-16 16:31:08.269493 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-16 16:31:08.339371 | controller | skipping: Conditional result was False
2026-03-16 16:31:08.346077 | 
2026-03-16 16:31:08.346163 | TASK [fetch-output : Set log path for single node]
2026-03-16 16:31:08.414065 | controller | ok
2026-03-16 16:31:08.419779 | 
2026-03-16 16:31:08.419848 | LOOP [fetch-output : Ensure local output dirs]
2026-03-16 16:31:09.096951 | controller -> localhost | ok: "/var/lib/zuul/builds/16028bc2dcbe47edb7f3e0a2b5763617/work/logs"
2026-03-16 16:31:09.430105 | controller -> localhost | changed: "/var/lib/zuul/builds/16028bc2dcbe47edb7f3e0a2b5763617/work/artifacts"
2026-03-16 16:31:09.903490 | controller -> localhost | changed: "/var/lib/zuul/builds/16028bc2dcbe47edb7f3e0a2b5763617/work/docs"
2026-03-16 16:31:09.919470 | 
2026-03-16 16:31:09.919614 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-16 16:31:10.955000 | controller | changed: .d..t...... ./
2026-03-16 16:31:10.955210 | controller | changed: All items complete
2026-03-16 16:31:10.955239 | 
2026-03-16 16:31:11.695619 | controller | changed: .d..t...... ./
2026-03-16 16:31:12.327288 | controller | changed: .d..t...... ./
2026-03-16 16:31:12.342338 | 
2026-03-16 16:31:12.342451 | TASK [include_role : fetch-output-openshift]
2026-03-16 16:31:12.386905 | controller | skipping: Conditional result was False
2026-03-16 16:31:12.393175 | 
2026-03-16 16:31:12.393269 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-16 16:31:12.976980 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008344
2026-03-16 16:31:13.201151 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008124
2026-03-16 16:31:13.239661 | 
2026-03-16 16:31:13.239765 | PLAY [all]
2026-03-16 16:31:13.263646 | 
2026-03-16 16:31:13.263771 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-03-16 16:31:13.795630 | controller | changed
2026-03-16 16:31:13.838157 | 
2026-03-16 16:31:13.838255 | PLAY RECAP
2026-03-16 16:31:13.838299 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-03-16 16:31:13.838319 | 
2026-03-16 16:31:14.149290 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-03-16 16:31:14.150108 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-03-16 16:31:15.217229 | 
2026-03-16 16:31:15.217373 | PLAY [localhost]
2026-03-16 16:31:15.261971 | 
2026-03-16 16:31:15.262181 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-16 16:31:16.071496 | localhost | changed
2026-03-16 16:31:16.076652 | 
2026-03-16 16:31:16.076770 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-16 16:31:16.148823 | localhost | ok
2026-03-16 16:31:16.168725 | 
2026-03-16 16:31:16.168859 | TASK [add-fileserver : Create SSH private key tempfile]
2026-03-16 16:31:16.705848 | localhost | changed
2026-03-16 16:31:16.716375 | 
2026-03-16 16:31:16.717812 | TASK [add-fileserver : Create SSH private key from secret]
2026-03-16 16:31:18.122623 | localhost | changed
2026-03-16 16:31:18.127887 | 
2026-03-16 16:31:18.127967 | TASK [add-fileserver : Add fileserver ssh key]
2026-03-16 16:31:18.700585 | localhost | Identity added: /var/lib/zuul/builds/16028bc2dcbe47edb7f3e0a2b5763617/work/tmp/ansible.4xs_v10t (/var/lib/zuul/builds/16028bc2dcbe47edb7f3e0a2b5763617/work/tmp/ansible.4xs_v10t)
2026-03-16 16:31:18.700787 | localhost | ok: Runtime: 0:00:00.010163
2026-03-16 16:31:18.705836 | 
2026-03-16 16:31:18.705906 | TASK [add-fileserver : Remove SSH private key from disk]
2026-03-16 16:31:19.116671 | localhost | ok: Runtime: 0:00:00.005966
2026-03-16 16:31:19.133716 | 
2026-03-16 16:31:19.133842 | TASK [add-fileserver : Add fileserver to inventory]
2026-03-16 16:31:19.210963 | localhost | changed
2026-03-16 16:31:19.218238 | 
2026-03-16 16:31:19.218385 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-03-16 16:31:19.703290 | localhost | changed
2026-03-16 16:31:19.727341 | 
2026-03-16 16:31:19.727441 | PLAY [localhost]
2026-03-16 16:31:19.740560 | 
2026-03-16 16:31:19.740812 | TASK [Generate bulk log download script]
2026-03-16 16:31:19.769167 | localhost | ok
2026-03-16 16:31:19.784657 | 
2026-03-16 16:31:19.784781 | TASK [local-log-download : Check API endpoint is defined]
2026-03-16 16:31:19.823652 | localhost | ok: All assertions passed
2026-03-16 16:31:19.828915 | 
2026-03-16 16:31:19.829001 | TASK [local-log-download : Create download script]
2026-03-16 16:31:20.225736 | localhost -> localhost | changed
2026-03-16 16:31:20.285129 | 
2026-03-16 16:31:20.285243 | TASK [Register quick-download link]
2026-03-16 16:31:20.325187 | localhost | ok
2026-03-16 16:31:20.346776 | 
2026-03-16 16:31:20.346881 | PLAY [logserver.rdoproject.org]
2026-03-16 16:31:20.360083 | 
2026-03-16 16:31:20.360193 | TASK [Set zuul-log-path fact]
2026-03-16 16:31:20.378036 | logserver.rdoproject.org | ok
2026-03-16 16:31:20.391222 | 
2026-03-16 16:31:20.391346 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-16 16:31:20.422172 | logserver.rdoproject.org | ok
2026-03-16 16:31:20.429782 | 
2026-03-16 16:31:20.429909 | TASK [upload-logs : Create log directories]
2026-03-16 16:31:21.389878 | logserver.rdoproject.org | changed
2026-03-16 16:31:21.393112 | 
2026-03-16 16:31:21.393216 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-03-16 16:31:21.756110 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.020133
2026-03-16 16:31:21.761173 | 
2026-03-16 16:31:21.761275 | TASK [upload-logs : Upload logs to log server]
2026-03-16 16:31:22.490207 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-03-16 16:31:22.493366 | 
2026-03-16 16:31:22.493431 | LOOP [upload-logs : Compress console log and json output]
2026-03-16 16:31:22.547282 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-16 16:31:22.551336 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-16 16:31:22.563156 | 
2026-03-16 16:31:22.563275 | LOOP [upload-logs : Upload compressed console log and json output]
2026-03-16 16:31:22.652921 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-16 16:31:22.653195 | 
2026-03-16 16:31:22.657657 | logserver.rdoproject.org | skipping: Conditional result was False
2026-03-16 16:31:22.664052 | 
2026-03-16 16:31:22.664193 | LOOP [upload-logs : Upload console log and json output]