2025-10-03 07:32:52.817688 | Job console starting... 2025-10-03 07:32:52.831525 | Updating repositories 2025-10-03 07:32:52.870610 | Preparing job workspace 2025-10-03 07:32:56.247826 | Running Ansible setup... 2025-10-03 07:33:00.109243 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-03 07:33:00.688228 | 2025-10-03 07:33:00.688346 | PLAY [localhost] 2025-10-03 07:33:00.696947 | 2025-10-03 07:33:00.697016 | TASK [Gathering Facts] 2025-10-03 07:33:01.770890 | localhost | ok 2025-10-03 07:33:01.790716 | 2025-10-03 07:33:01.790857 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-03 07:33:02.266316 | localhost -> localhost | changed 2025-10-03 07:33:02.272222 | 2025-10-03 07:33:02.272306 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-03 07:33:03.101367 | localhost -> localhost | changed 2025-10-03 07:33:03.109913 | 2025-10-03 07:33:03.109979 | TASK [Setup log path fact] 2025-10-03 07:33:03.127503 | localhost | ok 2025-10-03 07:33:03.138722 | 2025-10-03 07:33:03.138790 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-03 07:33:03.165818 | localhost | ok 2025-10-03 07:33:03.173398 | 2025-10-03 07:33:03.173460 | TASK [emit-job-header : Print job information] 2025-10-03 07:33:03.201095 | # Job Information 2025-10-03 07:33:03.201228 | Ansible Version: 2.15.12 2025-10-03 07:33:03.201257 | Job: cifmw-molecule-switch_config 2025-10-03 07:33:03.201277 | Pipeline: github-check 2025-10-03 07:33:03.201295 | Executor: ze02.softwarefactory-project.io 2025-10-03 07:33:03.201313 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3362 2025-10-03 07:33:03.201331 | Log URL (when completed): https://logserver.rdoproject.org/b89/rdoproject.org/b89f355396b54a038a6e77f1c642ca00/ 2025-10-03 07:33:03.201349 | Event ID: f958eab0-a02a-11f0-9ba1-0df9636946d8 2025-10-03 07:33:03.206944 | 2025-10-03 07:33:03.207008 | LOOP [emit-job-header : Print node information] 2025-10-03 07:33:03.305321 | localhost | ok: 2025-10-03 07:33:03.305490 | localhost | # Node Information 2025-10-03 07:33:03.305516 | localhost | Inventory Hostname: controller 2025-10-03 07:33:03.305538 | localhost | Hostname: np0005468164 2025-10-03 07:33:03.305558 | localhost | Username: zuul 2025-10-03 07:33:03.305578 | localhost | Distro: CentOS 9 2025-10-03 07:33:03.305596 | localhost | Provider: vexxhost-nodepool-tripleo 2025-10-03 07:33:03.305613 | localhost | Region: RegionOne 2025-10-03 07:33:03.305629 | localhost | Label: cloud-centos-9-stream-tripleo 2025-10-03 07:33:03.305646 | localhost | Product Name: OpenStack Nova 2025-10-03 07:33:03.305677 | localhost | Interface IP: 38.129.56.194 2025-10-03 07:33:03.331475 | 2025-10-03 07:33:03.331589 | PLAY [all] 2025-10-03 07:33:03.338451 | 2025-10-03 07:33:03.338514 | TASK [Gather network facts] 2025-10-03 07:33:03.874328 | controller | ok 2025-10-03 07:33:03.891774 | 2025-10-03 07:33:03.891909 | TASK [include_role : start-zuul-console] 2025-10-03 07:33:03.921345 | controller | ok 2025-10-03 07:33:03.934369 | 2025-10-03 07:33:03.934471 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-03 07:33:04.373849 | controller | ok 2025-10-03 07:33:04.390567 | 2025-10-03 07:33:04.390678 | TASK [include_role : add-build-sshkey] 2025-10-03 07:33:04.408753 | controller | ok 2025-10-03 07:33:04.421505 | 2025-10-03 07:33:04.421569 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-03 07:33:04.638520 | controller -> localhost | ok 2025-10-03 07:33:04.643994 | 2025-10-03 07:33:04.644065 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-03 07:33:04.673781 | controller | ok 2025-10-03 07:33:04.687101 | controller | included: /var/lib/zuul/builds/b89f355396b54a038a6e77f1c642ca00/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-03 07:33:04.694004 | 2025-10-03 07:33:04.694070 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-03 07:33:05.347821 | controller -> localhost | Generating public/private rsa key pair. 2025-10-03 07:33:05.348066 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/b89f355396b54a038a6e77f1c642ca00/work/b89f355396b54a038a6e77f1c642ca00_id_rsa. 2025-10-03 07:33:05.348099 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/b89f355396b54a038a6e77f1c642ca00/work/b89f355396b54a038a6e77f1c642ca00_id_rsa.pub. 2025-10-03 07:33:05.348120 | controller -> localhost | The key fingerprint is: 2025-10-03 07:33:05.348142 | controller -> localhost | SHA256:lfD/5k9vdkBX4AJ+B2+Ky+i+FsAw1a8UB6VsRXR9EYE zuul-build-sshkey 2025-10-03 07:33:05.348170 | controller -> localhost | The key's randomart image is: 2025-10-03 07:33:05.348192 | controller -> localhost | +---[RSA 3072]----+ 2025-10-03 07:33:05.348211 | controller -> localhost | | ...o=* o.o=+| 2025-10-03 07:33:05.348231 | controller -> localhost | | o .o*.+ E. o| 2025-10-03 07:33:05.348249 | controller -> localhost | | + ++= o =..| 2025-10-03 07:33:05.348266 | controller -> localhost | | o....+ =. .| 2025-10-03 07:33:05.348284 | controller -> localhost | | oS.. o. . | 2025-10-03 07:33:05.348300 | controller -> localhost | | oo . .. | 2025-10-03 07:33:05.348318 | controller -> localhost | | ..o o..| 2025-10-03 07:33:05.348337 | controller -> localhost | | .. o .=| 2025-10-03 07:33:05.348355 | controller -> localhost | | o+. .++| 2025-10-03 07:33:05.348372 | controller -> localhost | +----[SHA256]-----+ 2025-10-03 07:33:05.348415 | controller -> localhost | ok: Runtime: 0:00:00.278586 2025-10-03 07:33:05.356796 | 2025-10-03 07:33:05.356877 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-03 07:33:05.386595 | controller | ok 2025-10-03 07:33:05.397067 | controller | included: /var/lib/zuul/builds/b89f355396b54a038a6e77f1c642ca00/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-03 07:33:05.405382 | 2025-10-03 07:33:05.405444 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-03 07:33:05.429871 | controller | skipping: Conditional result was False 2025-10-03 07:33:05.435732 | 2025-10-03 07:33:05.435800 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-03 07:33:06.010737 | controller | changed 2025-10-03 07:33:06.015645 | 2025-10-03 07:33:06.015721 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-03 07:33:06.296787 | controller | ok 2025-10-03 07:33:06.302555 | 2025-10-03 07:33:06.302626 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-03 07:33:07.217317 | controller | changed 2025-10-03 07:33:07.222862 | 2025-10-03 07:33:07.222936 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-03 07:33:08.170557 | controller | changed 2025-10-03 07:33:08.175688 | 2025-10-03 07:33:08.175751 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-03 07:33:08.211122 | controller | skipping: Conditional result was False 2025-10-03 07:33:08.216975 | 2025-10-03 07:33:08.217048 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-03 07:33:08.622031 | controller -> localhost | changed 2025-10-03 07:33:08.632747 | 2025-10-03 07:33:08.632814 | TASK [add-build-sshkey : Add back temp key] 2025-10-03 07:33:08.937112 | controller -> localhost | Identity added: /var/lib/zuul/builds/b89f355396b54a038a6e77f1c642ca00/work/b89f355396b54a038a6e77f1c642ca00_id_rsa (zuul-build-sshkey) 2025-10-03 07:33:08.937390 | controller -> localhost | ok: Runtime: 0:00:00.007732 2025-10-03 07:33:08.943120 | 2025-10-03 07:33:08.943179 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-03 07:33:09.329412 | controller | ok 2025-10-03 07:33:09.335031 | 2025-10-03 07:33:09.335173 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-03 07:33:09.359701 | controller | skipping: Conditional result was False 2025-10-03 07:33:09.370268 | 2025-10-03 07:33:09.370334 | TASK [include_role : validate-host] 2025-10-03 07:33:09.389504 | controller | ok 2025-10-03 07:33:09.411123 | 2025-10-03 07:33:09.411209 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-03 07:33:09.440877 | controller | ok 2025-10-03 07:33:09.445796 | 2025-10-03 07:33:09.445860 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-03 07:33:09.678534 | controller -> localhost | ok 2025-10-03 07:33:09.684987 | 2025-10-03 07:33:09.685050 | TASK [validate-host : Collect information about the host] 2025-10-03 07:33:10.521018 | controller | ok 2025-10-03 07:33:10.530116 | 2025-10-03 07:33:10.530176 | TASK [validate-host : Sanitize hostname] 2025-10-03 07:33:10.592323 | controller | ok 2025-10-03 07:33:10.611443 | 2025-10-03 07:33:10.611683 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-03 07:33:11.102542 | controller -> localhost | changed 2025-10-03 07:33:11.124583 | 2025-10-03 07:33:11.124726 | TASK [validate-host : Collect information about zuul worker] 2025-10-03 07:33:11.600775 | controller | ok 2025-10-03 07:33:11.611336 | 2025-10-03 07:33:11.611474 | TASK [validate-host : Write out all zuul information for each host] 2025-10-03 07:33:12.159577 | controller -> localhost | changed 2025-10-03 07:33:12.169406 | 2025-10-03 07:33:12.169486 | TASK [include_role : prepare-workspace-openshift] 2025-10-03 07:33:12.187846 | controller | skipping: Conditional result was False 2025-10-03 07:33:12.194636 | 2025-10-03 07:33:12.194743 | TASK [include_role : remove-zuul-sshkey] 2025-10-03 07:33:12.211444 | controller | skipping: Conditional result was False 2025-10-03 07:33:12.218160 | 2025-10-03 07:33:12.218249 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-03 07:33:12.526544 | controller | ok: "logs" 2025-10-03 07:33:12.526819 | controller | ok: All items complete 2025-10-03 07:33:12.526854 | 2025-10-03 07:33:12.730161 | controller | ok: "artifacts" 2025-10-03 07:33:12.982778 | controller | ok: "docs" 2025-10-03 07:33:12.998040 | 2025-10-03 07:33:12.998353 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-03 07:33:13.297980 | controller | changed: "logs" 2025-10-03 07:33:13.542845 | controller | changed: "artifacts" 2025-10-03 07:33:13.778597 | controller | changed: "docs" 2025-10-03 07:33:13.809547 | 2025-10-03 07:33:13.809599 | PLAY RECAP 2025-10-03 07:33:13.809639 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-03 07:33:13.809670 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-03 07:33:13.809709 | 2025-10-03 07:33:13.922220 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-03 07:33:13.923044 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2025-10-03 07:33:14.602390 | 2025-10-03 07:33:14.602584 | PLAY [localhost] 2025-10-03 07:33:14.632402 | 2025-10-03 07:33:14.632543 | TASK [configure-ssh-config-fips : Ensure ssh config directory] 2025-10-03 07:33:15.040811 | localhost | ok 2025-10-03 07:33:15.045961 | 2025-10-03 07:33:15.046099 | TASK [configure-ssh-config-fips : Setup ssh config] 2025-10-03 07:33:15.731734 | localhost | changed 2025-10-03 07:33:15.758248 | 2025-10-03 07:33:15.758352 | PLAY [all] 2025-10-03 07:33:15.775299 | 2025-10-03 07:33:15.775420 | TASK [include_role : prepare-workspace] 2025-10-03 07:33:15.794910 | controller | ok 2025-10-03 07:33:15.809173 | 2025-10-03 07:33:15.809272 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-03 07:33:16.215467 | controller | ok 2025-10-03 07:33:16.228852 | 2025-10-03 07:33:16.229005 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-03 07:33:18.224869 | controller | Output suppressed because no_log was given 2025-10-03 07:33:18.234600 | 2025-10-03 07:33:18.234667 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-03 07:33:18.534394 | controller | changed: "logs" 2025-10-03 07:33:18.751284 | controller | changed: "artifacts" 2025-10-03 07:33:18.995796 | controller | changed: "docs" 2025-10-03 07:33:19.007481 | 2025-10-03 07:33:19.007579 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-03 07:33:19.291489 | controller | changed: "logs" 2025-10-03 07:33:19.291790 | controller | changed: All items complete 2025-10-03 07:33:19.291830 | 2025-10-03 07:33:19.506352 | controller | changed: "artifacts" 2025-10-03 07:33:19.740425 | controller | changed: "docs" 2025-10-03 07:33:19.767468 | 2025-10-03 07:33:19.767600 | TASK [Check if worker can sudo] 2025-10-03 07:33:20.321442 | controller | ok: Runtime: 0:00:00.055886 2025-10-03 07:33:20.332094 | 2025-10-03 07:33:20.332261 | TASK [configure-mirrors : Gather needed facts] 2025-10-03 07:33:20.398915 | controller | skipping: Conditional result was False 2025-10-03 07:33:20.405619 | 2025-10-03 07:33:20.405734 | TASK [configure-mirrors : Set up infrastructure mirrors] 2025-10-03 07:33:20.477603 | controller | ok 2025-10-03 07:33:20.486050 | controller | included: /var/lib/zuul/builds/b89f355396b54a038a6e77f1c642ca00/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml 2025-10-03 07:33:20.493724 | 2025-10-03 07:33:20.493792 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir] 2025-10-03 07:33:20.809779 | controller | ok 2025-10-03 07:33:20.816601 | 2025-10-03 07:33:20.816725 | LOOP [configure-mirrors : Include OS-specific variables] 2025-10-03 07:33:20.877391 | controller | ok: "/var/lib/zuul/builds/b89f355396b54a038a6e77f1c642ca00/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml" 2025-10-03 07:33:20.899742 | 2025-10-03 07:33:20.900184 | TASK [configure-mirrors : Install /etc/pip.conf configuration] 2025-10-03 07:33:21.727697 | controller | changed 2025-10-03 07:33:21.733222 | 2025-10-03 07:33:21.733287 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors] 2025-10-03 07:33:21.804938 | controller | ok: "/var/lib/zuul/builds/b89f355396b54a038a6e77f1c642ca00/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml" 2025-10-03 07:33:21.805231 | controller | ok: All items complete 2025-10-03 07:33:21.805274 | 2025-10-03 07:33:21.860722 | controller | included: /var/lib/zuul/builds/b89f355396b54a038a6e77f1c642ca00/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml 2025-10-03 07:33:21.868095 | 2025-10-03 07:33:21.868174 | LOOP [configure-mirrors : Install CentOS 9 repository files] 2025-10-03 07:33:22.865723 | controller | changed: "etc/yum.repos.d/centos.repo" 2025-10-03 07:33:23.789367 | controller | changed: "etc/yum.repos.d/centos-addons.repo" 2025-10-03 07:33:23.805309 | 2025-10-03 07:33:23.805434 | TASK [configure-mirrors : Disable deltrarpm] 2025-10-03 07:33:24.344536 | controller | changed: section and option added 2025-10-03 07:33:24.372723 | 2025-10-03 07:33:24.372880 | LOOP [configure-mirrors : Update yum/dnf cache] 2025-10-03 07:33:25.394206 | controller | 29 files removed 2025-10-03 07:33:25.394416 | controller | ok: Item: dnf clean all Runtime: 0:00:00.690416 2025-10-03 07:33:25.394455 | controller | changed: All items complete 2025-10-03 07:33:25.394475 | 2025-10-03 07:33:36.039468 | controller | Loaded plugins: builddep, changelog, config-manager, copr, debug, debuginfo-install, download, generate_completion_cache, groups-manager, needs-restarting, notify-packagekit, playground, repoclosure, repodiff, repograph, repomanage, reposync, system-upgrade 2025-10-03 07:33:36.040002 | controller | DNF version: 4.14.0 2025-10-03 07:33:36.040043 | controller | cachedir: /var/cache/dnf 2025-10-03 07:33:36.040064 | controller | Making cache files for all metadata files. 2025-10-03 07:33:36.040083 | controller | baseos: has expired and will be refreshed. 2025-10-03 07:33:36.040100 | controller | appstream: has expired and will be refreshed. 2025-10-03 07:33:36.040116 | controller | crb: has expired and will be refreshed. 2025-10-03 07:33:36.040140 | controller | extras-common: has expired and will be refreshed. 2025-10-03 07:33:36.040157 | controller | repo: downloading from remote: baseos 2025-10-03 07:33:36.040173 | controller | CentOS Stream 9 - BaseOS 38 MB/s | 8.8 MB 00:00 2025-10-03 07:33:36.040190 | controller | baseos: using metadata from Tue 30 Sep 2025 01:12:56 PM EDT. 2025-10-03 07:33:36.040206 | controller | repo: downloading from remote: appstream 2025-10-03 07:33:36.040222 | controller | CentOS Stream 9 - AppStream 77 MB/s | 25 MB 00:00 2025-10-03 07:33:36.040237 | controller | appstream: using metadata from Tue 30 Sep 2025 01:16:29 PM EDT. 2025-10-03 07:33:36.040253 | controller | repo: downloading from remote: crb 2025-10-03 07:33:36.040269 | controller | CentOS Stream 9 - CRB 65 MB/s | 7.1 MB 00:00 2025-10-03 07:33:36.040285 | controller | crb: using metadata from Tue 30 Sep 2025 01:20:54 PM EDT. 2025-10-03 07:33:36.040301 | controller | repo: downloading from remote: extras-common 2025-10-03 07:33:36.040317 | controller | CentOS Stream 9 - Extras packages 1.4 MB/s | 20 kB 00:00 2025-10-03 07:33:36.040333 | controller | extras-common: using metadata from Mon 15 Sep 2025 02:32:43 PM EDT. 2025-10-03 07:33:36.040349 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)' 2025-10-03 07:33:36.040365 | controller | Completion plugin: Generating completion cache... 2025-10-03 07:33:36.040381 | controller | Metadata cache created. 2025-10-03 07:33:36.040406 | controller | ok: Item: dnf makecache -v Runtime: 0:00:10.392745 2025-10-03 07:33:36.066352 | 2025-10-03 07:33:36.066451 | PLAY RECAP 2025-10-03 07:33:36.066529 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-03 07:33:36.066564 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-03 07:33:36.066583 | 2025-10-03 07:33:36.191185 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2025-10-03 07:33:36.192007 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-03 07:33:37.016195 | 2025-10-03 07:33:37.016302 | PLAY [all] 2025-10-03 07:33:37.037337 | 2025-10-03 07:33:37.037421 | TASK [Install binary dependencies] 2025-10-03 07:33:37.086351 | controller | ok 2025-10-03 07:33:37.106519 | 2025-10-03 07:33:37.106608 | TASK [bindep : Include find tasks] 2025-10-03 07:33:37.135548 | controller | ok 2025-10-03 07:33:37.143116 | controller | included: /var/lib/zuul/builds/b89f355396b54a038a6e77f1c642ca00/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-10-03 07:33:37.149036 | 2025-10-03 07:33:37.149098 | TASK [bindep : Look for bindep.txt] 2025-10-03 07:33:37.580621 | controller | ok 2025-10-03 07:33:37.587302 | 2025-10-03 07:33:37.587378 | TASK [bindep : Define bindep_file fact] 2025-10-03 07:33:37.615735 | controller | ok 2025-10-03 07:33:37.620881 | 2025-10-03 07:33:37.620960 | TASK [bindep : Look for other-requirements.txt] 2025-10-03 07:33:37.644777 | controller | skipping: Conditional result was False 2025-10-03 07:33:37.651234 | 2025-10-03 07:33:37.651312 | TASK [bindep : Define bindep_file fact] 2025-10-03 07:33:37.685851 | controller | skipping: Conditional result was False 2025-10-03 07:33:37.692485 | 2025-10-03 07:33:37.692553 | TASK [bindep : Look for bindep fallback file] 2025-10-03 07:33:37.727426 | controller | skipping: Conditional result was False 2025-10-03 07:33:37.740305 | 2025-10-03 07:33:37.740465 | TASK [bindep : Define bindep_file fact] 2025-10-03 07:33:37.776979 | controller | skipping: Conditional result was False 2025-10-03 07:33:37.785102 | 2025-10-03 07:33:37.785209 | TASK [bindep : Include bindep tasks] 2025-10-03 07:33:37.833083 | controller | ok 2025-10-03 07:33:37.846756 | controller | included: /var/lib/zuul/builds/b89f355396b54a038a6e77f1c642ca00/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml 2025-10-03 07:33:37.869044 | 2025-10-03 07:33:37.869561 | TASK [bindep : Look for bindep command] 2025-10-03 07:33:37.907297 | controller | skipping: Conditional result was False 2025-10-03 07:33:37.918748 | 2025-10-03 07:33:37.918845 | TASK [bindep : Check for system bindep] 2025-10-03 07:33:38.475543 | controller | ok: Runtime: 0:00:00.005203 2025-10-03 07:33:38.482848 | 2025-10-03 07:33:38.482936 | TASK [bindep : Define bindep_command fact] 2025-10-03 07:33:38.518108 | controller | skipping: Conditional result was False 2025-10-03 07:33:38.526312 | 2025-10-03 07:33:38.526399 | TASK [bindep : Include install tasks] 2025-10-03 07:33:38.568314 | controller | ok 2025-10-03 07:33:38.579224 | controller | included: /var/lib/zuul/builds/b89f355396b54a038a6e77f1c642ca00/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml 2025-10-03 07:33:38.591858 | 2025-10-03 07:33:38.591944 | TASK [bindep : Create temp dir for bindep] 2025-10-03 07:33:38.981018 | controller | changed 2025-10-03 07:33:38.988960 | 2025-10-03 07:33:38.989023 | TASK [Ensure we have pip dependencies] 2025-10-03 07:33:39.014804 | controller | ok 2025-10-03 07:33:39.064559 | 2025-10-03 07:33:39.064715 | TASK [ensure-pip : Check if pip is installed] 2025-10-03 03:33:39.360621 | controller | /usr/bin/pip3 2025-10-03 03:33:39.381058 | controller | /usr/bin/python3: No module named wheel 2025-10-03 07:33:39.623239 | controller | ok: Runtime: 0:00:00.030605 2025-10-03 07:33:39.636337 | 2025-10-03 07:33:39.636478 | LOOP [ensure-pip : Install pip from packages] 2025-10-03 07:33:39.678932 | controller | ok: "/var/lib/zuul/builds/b89f355396b54a038a6e77f1c642ca00/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml" 2025-10-03 07:33:39.709195 | controller | included: /var/lib/zuul/builds/b89f355396b54a038a6e77f1c642ca00/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml 2025-10-03 07:33:39.723591 | 2025-10-03 07:33:39.723790 | TASK [ensure-pip : Install Python 3 pip] 2025-10-03 07:33:42.002734 | controller | changed 2025-10-03 07:33:42.011599 | 2025-10-03 07:33:42.011725 | TASK [ensure-pip : Check for EPEL repository] 2025-10-03 07:33:42.055801 | controller | skipping: Conditional result was False 2025-10-03 07:33:42.062487 | 2025-10-03 07:33:42.062561 | TASK [ensure-pip : Fail with instructions when EPEL is not installed] 2025-10-03 07:33:42.117016 | controller | skipping: Conditional result was False 2025-10-03 07:33:42.123761 | 2025-10-03 07:33:42.123839 | TASK [ensure-pip : Install Python 2 pip] 2025-10-03 07:33:42.168245 | controller | skipping: Conditional result was False 2025-10-03 07:33:42.175342 | 2025-10-03 07:33:42.175418 | TASK [ensure-pip : Ensure setuptools] 2025-10-03 07:33:42.189788 | controller | skipping: Conditional result was False 2025-10-03 07:33:42.197176 | 2025-10-03 07:33:42.197264 | TASK [ensure-pip : Check for ensurepip module] 2025-10-03 07:33:42.729876 | controller | skipping: Conditional result was False 2025-10-03 07:33:42.742938 | 2025-10-03 07:33:42.743050 | TASK [ensure-pip : Ensure python3-venv] 2025-10-03 07:33:42.767772 | controller | skipping: Conditional result was False 2025-10-03 07:33:42.774152 | 2025-10-03 07:33:42.774220 | TASK [ensure-pip : Install pip from source] 2025-10-03 07:33:42.799184 | controller | skipping: Conditional result was False 2025-10-03 07:33:42.808595 | 2025-10-03 07:33:42.808727 | TASK [ensure-pip : Probe for venv python full path] 2025-10-03 03:33:43.107163 | controller | /usr/bin/python3 2025-10-03 07:33:43.344391 | controller | ok: Runtime: 0:00:00.007636 2025-10-03 07:33:43.350287 | 2025-10-03 07:33:43.350348 | TASK [ensure-pip : Set host default] 2025-10-03 07:33:43.420660 | controller | ok 2025-10-03 07:33:43.426057 | 2025-10-03 07:33:43.426117 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2025-10-03 07:33:43.508253 | controller | ok 2025-10-03 07:33:43.529000 | 2025-10-03 07:33:43.529087 | TASK [bindep : Install bindep into temporary venv] 2025-10-03 07:33:47.607130 | controller | changed 2025-10-03 07:33:47.615460 | 2025-10-03 07:33:47.615675 | TASK [bindep : Define bindep_command] 2025-10-03 07:33:47.652674 | controller | ok 2025-10-03 07:33:47.661283 | 2025-10-03 07:33:47.661423 | LOOP [bindep : Include package tasks] 2025-10-03 07:33:47.719808 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt" 2025-10-03 07:33:47.719990 | controller | ok: All items complete 2025-10-03 07:33:47.720020 | 2025-10-03 07:33:47.738307 | controller | included: /var/lib/zuul/builds/b89f355396b54a038a6e77f1c642ca00/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml 2025-10-03 07:33:47.750445 | 2025-10-03 07:33:47.750536 | TASK [bindep : Define bindep_run fact] 2025-10-03 07:33:47.782860 | controller | ok 2025-10-03 07:33:47.788132 | 2025-10-03 07:33:47.788201 | TASK [bindep : Get list of packages to install from bindep] 2025-10-03 03:33:49.086632 | controller | podman 2025-10-03 03:33:49.123213 | controller | python3-jmespath 2025-10-03 03:33:49.123270 | controller | python3-libvirt 2025-10-03 03:33:49.123286 | controller | python3-lxml 2025-10-03 03:33:49.123362 | controller | python3-netaddr 2025-10-03 07:33:49.346204 | controller | ok: Runtime: 0:00:01.086437 2025-10-03 07:33:49.351922 | 2025-10-03 07:33:49.351992 | TASK [bindep : Install distro packages from bindep] 2025-10-03 07:34:51.567351 | controller | changed 2025-10-03 07:34:51.578992 | 2025-10-03 07:34:51.579123 | TASK [bindep : Check that packages are installed] 2025-10-03 07:34:53.128474 | controller | ok: Runtime: 0:00:01.162386 2025-10-03 07:34:53.141337 | 2025-10-03 07:34:53.141538 | TASK [bindep : Fail if we cannot install all packages] 2025-10-03 07:34:53.180289 | controller | skipping: Conditional result was False 2025-10-03 07:34:53.207990 | 2025-10-03 07:34:53.208233 | TASK [Run test-setup role] 2025-10-03 07:34:53.237107 | controller | ok 2025-10-03 07:34:53.269364 | 2025-10-03 07:34:53.269449 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-10-03 07:34:53.498797 | controller | ok 2025-10-03 07:34:53.511511 | 2025-10-03 07:34:53.511649 | TASK [test-setup : Run tools/test-setup.sh] 2025-10-03 07:34:54.070504 | controller | skipping: Conditional result was False 2025-10-03 07:34:54.117977 | 2025-10-03 07:34:54.118128 | TASK [bindep : Remove bindep temp dir] 2025-10-03 07:34:54.506231 | controller | ok 2025-10-03 07:34:54.530439 | 2025-10-03 07:34:54.530551 | PLAY RECAP 2025-10-03 07:34:54.530640 | controller | ok: 22 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0 2025-10-03 07:34:54.530766 | 2025-10-03 07:34:54.648337 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-03 07:34:54.651339 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2025-10-03 07:34:55.275013 | 2025-10-03 07:34:55.275174 | PLAY [all] 2025-10-03 07:34:55.294505 | 2025-10-03 07:34:55.294592 | TASK [Abort when test_command variable is undefined] 2025-10-03 07:34:55.328980 | controller | skipping: Conditional result was False 2025-10-03 07:34:55.334995 | 2025-10-03 07:34:55.335136 | TASK [Convert test_command to list] 2025-10-03 07:34:55.389802 | controller | skipping: Conditional result was False 2025-10-03 07:34:55.399840 | 2025-10-03 07:34:55.399958 | TASK [Use test_command list] 2025-10-03 07:34:55.498140 | controller | ok 2025-10-03 07:34:55.513121 | 2025-10-03 07:34:55.513262 | LOOP [Run test_command] 2025-10-03 07:34:55.946319 | controller | no check to run 2025-10-03 07:34:55.946727 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.007851 2025-10-03 07:34:55.997045 | 2025-10-03 07:34:55.997218 | PLAY RECAP 2025-10-03 07:34:55.997305 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-03 07:34:55.997600 | 2025-10-03 07:34:56.115748 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2025-10-03 07:34:56.118267 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-03 07:34:56.668215 | 2025-10-03 07:34:56.668363 | PLAY [all] 2025-10-03 07:34:56.704528 | 2025-10-03 07:34:56.704658 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-03 07:34:57.069056 | controller | changed: non-zero return code 2025-10-03 07:34:57.074405 | 2025-10-03 07:34:57.074478 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-03 07:34:57.088736 | controller | skipping: Conditional result was False 2025-10-03 07:34:57.095426 | 2025-10-03 07:34:57.095497 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-03 07:34:57.115327 | 2025-10-03 07:34:57.115448 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-03 07:34:57.134945 | 2025-10-03 07:34:57.135073 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-03 07:34:57.148627 | controller | skipping: Conditional result was False 2025-10-03 07:34:57.154624 | 2025-10-03 07:34:57.154712 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-03 07:34:57.173812 | 2025-10-03 07:34:57.173935 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-03 07:34:57.188256 | controller | skipping: Conditional result was False 2025-10-03 07:34:57.199460 | 2025-10-03 07:34:57.199589 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-03 07:34:57.214459 | controller | skipping: Conditional result was False 2025-10-03 07:34:57.222865 | 2025-10-03 07:34:57.222963 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-03 07:34:57.237444 | controller | skipping: Conditional result was False 2025-10-03 07:34:57.273653 | 2025-10-03 07:34:57.273731 | PLAY RECAP 2025-10-03 07:34:57.273772 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-03 07:34:57.273793 | 2025-10-03 07:34:57.377038 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-03 07:34:57.378782 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-03 07:34:57.980699 | 2025-10-03 07:34:57.980852 | PLAY [all] 2025-10-03 07:34:58.000906 | 2025-10-03 07:34:58.001017 | TASK [include_role : fetch-output] 2025-10-03 07:34:58.041018 | controller | ok 2025-10-03 07:34:58.057857 | 2025-10-03 07:34:58.057934 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-03 07:34:58.102581 | controller | skipping: Conditional result was False 2025-10-03 07:34:58.109167 | 2025-10-03 07:34:58.109267 | TASK [fetch-output : Set log path for single node] 2025-10-03 07:34:58.138813 | controller | ok 2025-10-03 07:34:58.143787 | 2025-10-03 07:34:58.143861 | LOOP [fetch-output : Ensure local output dirs] 2025-10-03 07:34:58.581415 | controller -> localhost | ok: "/var/lib/zuul/builds/b89f355396b54a038a6e77f1c642ca00/work/logs" 2025-10-03 07:34:58.820462 | controller -> localhost | changed: "/var/lib/zuul/builds/b89f355396b54a038a6e77f1c642ca00/work/artifacts" 2025-10-03 07:34:59.086299 | controller -> localhost | changed: "/var/lib/zuul/builds/b89f355396b54a038a6e77f1c642ca00/work/docs" 2025-10-03 07:34:59.108919 | 2025-10-03 07:34:59.109127 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-03 07:34:59.864788 | controller | changed: .d..t...... ./ 2025-10-03 07:34:59.866840 | controller | changed: All items complete 2025-10-03 07:34:59.866933 | 2025-10-03 07:35:00.408740 | controller | changed: .d..t...... ./ 2025-10-03 07:35:01.031149 | controller | changed: .d..t...... ./ 2025-10-03 07:35:01.060138 | 2025-10-03 07:35:01.060330 | TASK [include_role : fetch-output-openshift] 2025-10-03 07:35:01.088406 | controller | skipping: Conditional result was False 2025-10-03 07:35:01.101817 | 2025-10-03 07:35:01.101962 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-03 07:35:01.553609 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.010261 2025-10-03 07:35:01.792449 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009922 2025-10-03 07:35:01.851338 | 2025-10-03 07:35:01.851466 | PLAY [all] 2025-10-03 07:35:01.871653 | 2025-10-03 07:35:01.871786 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-03 07:35:02.350555 | controller | changed 2025-10-03 07:35:02.400896 | 2025-10-03 07:35:02.401012 | PLAY RECAP 2025-10-03 07:35:02.401068 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-03 07:35:02.401098 | 2025-10-03 07:35:02.510049 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-03 07:35:02.511155 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-03 07:35:03.174442 | 2025-10-03 07:35:03.174563 | PLAY [localhost] 2025-10-03 07:35:03.193026 | 2025-10-03 07:35:03.193123 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-03 07:35:03.648337 | localhost | changed 2025-10-03 07:35:03.653153 | 2025-10-03 07:35:03.653239 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-03 07:35:03.693701 | localhost | ok 2025-10-03 07:35:03.704618 | 2025-10-03 07:35:03.704739 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-03 07:35:04.137396 | localhost | changed 2025-10-03 07:35:04.149118 | 2025-10-03 07:35:04.149290 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-03 07:35:04.937836 | localhost | changed 2025-10-03 07:35:04.959359 | 2025-10-03 07:35:04.959565 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-03 07:35:05.431594 | localhost | Identity added: /var/lib/zuul/builds/b89f355396b54a038a6e77f1c642ca00/work/tmp/ansible.g1qku0mw (/var/lib/zuul/builds/b89f355396b54a038a6e77f1c642ca00/work/tmp/ansible.g1qku0mw) 2025-10-03 07:35:05.431920 | localhost | ok: Runtime: 0:00:00.021667 2025-10-03 07:35:05.438217 | 2025-10-03 07:35:05.438320 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-03 07:35:05.769002 | localhost | ok: Runtime: 0:00:00.008691 2025-10-03 07:35:05.781139 | 2025-10-03 07:35:05.781285 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-03 07:35:05.859597 | localhost | changed 2025-10-03 07:35:05.869851 | 2025-10-03 07:35:05.870002 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-03 07:35:06.325181 | localhost | changed 2025-10-03 07:35:06.368221 | 2025-10-03 07:35:06.368317 | PLAY [localhost] 2025-10-03 07:35:06.385182 | 2025-10-03 07:35:06.385264 | TASK [Generate bulk log download script] 2025-10-03 07:35:06.406709 | localhost | ok 2025-10-03 07:35:06.423273 | 2025-10-03 07:35:06.423364 | TASK [local-log-download : Check API endpoint is defined] 2025-10-03 07:35:06.477266 | localhost | ok: All assertions passed 2025-10-03 07:35:06.487888 | 2025-10-03 07:35:06.487975 | TASK [local-log-download : Create download script] 2025-10-03 07:35:06.938867 | localhost -> localhost | changed 2025-10-03 07:35:06.947689 | 2025-10-03 07:35:06.947753 | TASK [Register quick-download link] 2025-10-03 07:35:06.976255 | localhost | ok 2025-10-03 07:35:07.016409 | 2025-10-03 07:35:07.016515 | PLAY [logserver.rdoproject.org] 2025-10-03 07:35:07.025814 | 2025-10-03 07:35:07.025873 | TASK [Set zuul-log-path fact] 2025-10-03 07:35:07.051775 | logserver.rdoproject.org | ok 2025-10-03 07:35:07.060508 | 2025-10-03 07:35:07.060572 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-03 07:35:07.098441 | logserver.rdoproject.org | ok 2025-10-03 07:35:07.105655 | 2025-10-03 07:35:07.105764 | TASK [upload-logs : Create log directories] 2025-10-03 07:35:07.780305 | logserver.rdoproject.org | changed 2025-10-03 07:35:07.787183 | 2025-10-03 07:35:07.787312 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-03 07:35:08.120593 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.007114 2025-10-03 07:35:08.130281 | 2025-10-03 07:35:08.130416 | TASK [upload-logs : Upload logs to log server] 2025-10-03 07:35:08.848532 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-03 07:35:08.855893 | 2025-10-03 07:35:08.856034 | LOOP [upload-logs : Compress console log and json output] 2025-10-03 07:35:08.927512 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 07:35:08.939640 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 07:35:08.952122 | 2025-10-03 07:35:08.952312 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-03 07:35:09.000757 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 07:35:09.001442 | 2025-10-03 07:35:09.003786 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 07:35:09.019574 | 2025-10-03 07:35:09.019787 | LOOP [upload-logs : Upload console log and json output]