2026-06-09 09:12:59.992014 | Job console starting 2026-06-09 09:13:00.137083 | Updating git repos 2026-06-09 09:13:00.165979 | Cloning repos into workspace 2026-06-09 09:13:00.840755 | Restoring repo states 2026-06-09 09:13:00.852910 | Merging changes 2026-06-09 09:13:02.249875 | Checking out repos 2026-06-09 09:13:02.384612 | Preparing playbooks 2026-06-09 09:13:05.986406 | Running Ansible setup 2026-06-09 09:13:09.936905 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-06-09 09:13:10.598794 | 2026-06-09 09:13:10.598930 | PLAY [localhost] 2026-06-09 09:13:10.606983 | 2026-06-09 09:13:10.607056 | TASK [Gathering Facts] 2026-06-09 09:13:11.601557 | localhost | ok 2026-06-09 09:13:11.614278 | 2026-06-09 09:13:11.614436 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-09 09:13:12.106657 | localhost -> localhost | changed 2026-06-09 09:13:12.115311 | 2026-06-09 09:13:12.115445 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-09 09:13:13.035184 | localhost -> localhost | changed 2026-06-09 09:13:13.040626 | 2026-06-09 09:13:13.040688 | TASK [Setup log path fact] 2026-06-09 09:13:13.057935 | localhost | ok 2026-06-09 09:13:13.066633 | 2026-06-09 09:13:13.066707 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-09 09:13:13.087097 | localhost | ok 2026-06-09 09:13:13.096581 | 2026-06-09 09:13:13.096682 | TASK [emit-job-header : Print job information] 2026-06-09 09:13:13.134477 | # Job Information 2026-06-09 09:13:13.134660 | Ansible Version: 2.16.18 2026-06-09 09:13:13.134708 | Job: cifmw-molecule-ci_dcn_site 2026-06-09 09:13:13.134745 | Pipeline: github-check 2026-06-09 09:13:13.134771 | Executor: 38.102.83.32 2026-06-09 09:13:13.134794 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3963 2026-06-09 09:13:13.134822 | Log URL (when completed): https://gateway-cloud-softwarefactory.apps.ocp.cloud.ci.centos.org/logs/ebb/rdoproject.org/ebbb7be45f4c46edb91e5d8546b99136/ 2026-06-09 09:13:13.134849 | Event ID: 1742dd20-63e3-11f1-9526-32c2076d3492 2026-06-09 09:13:13.138545 | 2026-06-09 09:13:13.138633 | LOOP [emit-job-header : Print node information] 2026-06-09 09:13:13.276399 | localhost | ok: 2026-06-09 09:13:13.277944 | localhost | # Node Information 2026-06-09 09:13:13.278103 | localhost | Inventory Hostname: controller 2026-06-09 09:13:13.278174 | localhost | Hostname: np0000068298 2026-06-09 09:13:13.278226 | localhost | Username: zuul 2026-06-09 09:13:13.278277 | localhost | Distro: CentOS 9 2026-06-09 09:13:13.278323 | localhost | Provider: vexxhost-nodepool-tripleo 2026-06-09 09:13:13.278366 | localhost | Region: RegionOne 2026-06-09 09:13:13.278409 | localhost | Label: cloud-centos-9-stream-tripleo 2026-06-09 09:13:13.278452 | localhost | Product Name: OpenStack Nova 2026-06-09 09:13:13.278488 | localhost | Interface IP: 38.129.56.209 2026-06-09 09:13:13.295599 | 2026-06-09 09:13:13.295795 | PLAY [all] 2026-06-09 09:13:13.304830 | 2026-06-09 09:13:13.304968 | TASK [Gather network facts] 2026-06-09 09:13:13.803606 | controller | ok 2026-06-09 09:13:13.815212 | 2026-06-09 09:13:13.815360 | TASK [include_role : start-zuul-console] 2026-06-09 09:13:13.838667 | controller | ok 2026-06-09 09:13:13.856080 | 2026-06-09 09:13:13.856227 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-06-09 09:13:14.237605 | controller | ok 2026-06-09 09:13:14.243125 | 2026-06-09 09:13:14.243187 | TASK [include_role : add-build-sshkey] 2026-06-09 09:13:14.270240 | controller | ok 2026-06-09 09:13:14.281088 | 2026-06-09 09:13:14.281168 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-09 09:13:14.579888 | controller -> localhost | ok 2026-06-09 09:13:14.588277 | 2026-06-09 09:13:14.588411 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-09 09:13:14.608719 | controller | ok 2026-06-09 09:13:14.625621 | controller | included: /var/lib/zuul/builds/ebbb7be45f4c46edb91e5d8546b99136/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-09 09:13:14.631881 | 2026-06-09 09:13:14.631996 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-09 09:13:15.215000 | controller -> localhost | Generating public/private rsa key pair. 2026-06-09 09:13:15.215281 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ebbb7be45f4c46edb91e5d8546b99136/work/ebbb7be45f4c46edb91e5d8546b99136_id_rsa 2026-06-09 09:13:15.215343 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ebbb7be45f4c46edb91e5d8546b99136/work/ebbb7be45f4c46edb91e5d8546b99136_id_rsa.pub 2026-06-09 09:13:15.215390 | controller -> localhost | The key fingerprint is: 2026-06-09 09:13:15.215433 | controller -> localhost | SHA256:PN9pcL+webxuCFITpw3/7GAJ6Rp3se7ZyBgx/zotTYY zuul-build-sshkey 2026-06-09 09:13:15.215557 | controller -> localhost | The key's randomart image is: 2026-06-09 09:13:15.215642 | controller -> localhost | +---[RSA 3072]----+ 2026-06-09 09:13:15.215738 | controller -> localhost | | | 2026-06-09 09:13:15.215819 | controller -> localhost | | o . | 2026-06-09 09:13:15.215882 | controller -> localhost | | O | 2026-06-09 09:13:15.215926 | controller -> localhost | | . * + | 2026-06-09 09:13:15.215965 | controller -> localhost | | So+o.B | 2026-06-09 09:13:15.216004 | controller -> localhost | | oo+BEo= | 2026-06-09 09:13:15.216041 | controller -> localhost | | =o=*X. | 2026-06-09 09:13:15.216083 | controller -> localhost | | . ==OB. | 2026-06-09 09:13:15.216121 | controller -> localhost | | ..OB*. | 2026-06-09 09:13:15.216158 | controller -> localhost | +----[SHA256]-----+ 2026-06-09 09:13:15.216248 | controller -> localhost | ok: Runtime: 0:00:00.042352 2026-06-09 09:13:15.234471 | 2026-06-09 09:13:15.234680 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-09 09:13:15.268898 | controller | ok 2026-06-09 09:13:15.288423 | controller | included: /var/lib/zuul/builds/ebbb7be45f4c46edb91e5d8546b99136/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-09 09:13:15.315704 | 2026-06-09 09:13:15.315843 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-09 09:13:15.351080 | controller | skipping: Conditional result was False 2026-06-09 09:13:15.359185 | 2026-06-09 09:13:15.359322 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-09 09:13:15.850623 | controller | changed 2026-06-09 09:13:15.859364 | 2026-06-09 09:13:15.859567 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-09 09:13:16.126357 | controller | ok 2026-06-09 09:13:16.133642 | 2026-06-09 09:13:16.133761 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-09 09:13:16.950320 | controller | changed 2026-06-09 09:13:16.953619 | 2026-06-09 09:13:16.953678 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-09 09:13:17.744368 | controller | changed 2026-06-09 09:13:17.751921 | 2026-06-09 09:13:17.752053 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-09 09:13:17.769438 | controller | skipping: Conditional result was False 2026-06-09 09:13:17.778075 | 2026-06-09 09:13:17.778234 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-09 09:13:18.210060 | controller -> localhost | changed 2026-06-09 09:13:18.224152 | 2026-06-09 09:13:18.224283 | TASK [add-build-sshkey : Add back temp key] 2026-06-09 09:13:18.540756 | controller -> localhost | Identity added: /var/lib/zuul/builds/ebbb7be45f4c46edb91e5d8546b99136/work/ebbb7be45f4c46edb91e5d8546b99136_id_rsa (zuul-build-sshkey) 2026-06-09 09:13:18.541127 | controller -> localhost | ok: Runtime: 0:00:00.011218 2026-06-09 09:13:18.550412 | 2026-06-09 09:13:18.550582 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-09 09:13:18.880872 | controller | ok 2026-06-09 09:13:18.889039 | 2026-06-09 09:13:18.889208 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-09 09:13:18.931356 | controller | skipping: Conditional result was False 2026-06-09 09:13:18.948067 | 2026-06-09 09:13:18.948300 | TASK [include_role : validate-host] 2026-06-09 09:13:18.971062 | controller | ok 2026-06-09 09:13:19.009623 | 2026-06-09 09:13:19.009813 | TASK [validate-host : Define zuul_info_dir fact] 2026-06-09 09:13:19.050033 | controller | ok 2026-06-09 09:13:19.053122 | 2026-06-09 09:13:19.053181 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-06-09 09:13:19.319414 | controller -> localhost | ok 2026-06-09 09:13:19.324189 | 2026-06-09 09:13:19.324261 | TASK [validate-host : Collect information about the host] 2026-06-09 09:13:20.021089 | controller | ok 2026-06-09 09:13:20.037142 | 2026-06-09 09:13:20.037287 | TASK [validate-host : Sanitize hostname] 2026-06-09 09:13:20.125735 | controller | ok 2026-06-09 09:13:20.134589 | 2026-06-09 09:13:20.134786 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-06-09 09:13:20.699020 | controller -> localhost | changed 2026-06-09 09:13:20.708944 | 2026-06-09 09:13:20.709046 | TASK [validate-host : Collect information about zuul worker] 2026-06-09 09:13:21.147264 | controller | ok 2026-06-09 09:13:21.153681 | 2026-06-09 09:13:21.153935 | TASK [validate-host : Write out all zuul information for each host] 2026-06-09 09:13:21.703110 | controller -> localhost | changed 2026-06-09 09:13:21.719126 | 2026-06-09 09:13:21.719267 | TASK [include_role : prepare-workspace-openshift] 2026-06-09 09:13:21.747564 | controller | skipping: Conditional result was False 2026-06-09 09:13:21.756865 | 2026-06-09 09:13:21.757224 | TASK [include_role : remove-zuul-sshkey] 2026-06-09 09:13:21.787370 | controller | skipping: Conditional result was False 2026-06-09 09:13:21.794053 | 2026-06-09 09:13:21.794167 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-09 09:13:22.019158 | controller | ok: "logs" 2026-06-09 09:13:22.021396 | controller | ok: All items complete 2026-06-09 09:13:22.021478 | 2026-06-09 09:13:22.205588 | controller | ok: "artifacts" 2026-06-09 09:13:22.399343 | controller | ok: "docs" 2026-06-09 09:13:22.429087 | 2026-06-09 09:13:22.429237 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-09 09:13:22.665471 | controller | changed: "logs" 2026-06-09 09:13:22.867888 | controller | changed: "artifacts" 2026-06-09 09:13:23.130423 | controller | changed: "docs" 2026-06-09 09:13:23.138221 | 2026-06-09 09:13:23.138324 | PLAY RECAP 2026-06-09 09:13:23.138367 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2026-06-09 09:13:23.138393 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-09 09:13:23.138412 | 2026-06-09 09:13:23.282144 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-06-09 09:13:23.286797 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-06-09 09:13:24.009757 | 2026-06-09 09:13:24.010089 | PLAY [localhost] 2026-06-09 09:13:24.019688 | 2026-06-09 09:13:24.019806 | TASK [configure-ssh-config-fips : Ensure ssh config directory] 2026-06-09 09:13:24.466636 | localhost | changed 2026-06-09 09:13:24.476095 | 2026-06-09 09:13:24.476286 | TASK [configure-ssh-config-fips : Setup ssh config] 2026-06-09 09:13:25.264750 | localhost | changed 2026-06-09 09:13:25.278724 | 2026-06-09 09:13:25.278908 | PLAY [all] 2026-06-09 09:13:25.292449 | 2026-06-09 09:13:25.292642 | TASK [include_role : prepare-workspace] 2026-06-09 09:13:25.323675 | controller | ok 2026-06-09 09:13:25.343258 | 2026-06-09 09:13:25.343386 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-09 09:13:25.719851 | controller | ok 2026-06-09 09:13:25.732921 | 2026-06-09 09:13:25.733091 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-09 09:13:27.299027 | controller | Output suppressed because no_log was given 2026-06-09 09:13:27.308842 | 2026-06-09 09:13:27.308944 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-09 09:13:27.531166 | controller | changed: "logs" 2026-06-09 09:13:27.724677 | controller | changed: "artifacts" 2026-06-09 09:13:27.904411 | controller | changed: "docs" 2026-06-09 09:13:27.921876 | 2026-06-09 09:13:27.922035 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-09 09:13:28.162356 | controller | changed: "logs" 2026-06-09 09:13:28.162769 | controller | changed: All items complete 2026-06-09 09:13:28.162828 | 2026-06-09 09:13:28.371994 | controller | changed: "artifacts" 2026-06-09 09:13:28.591941 | controller | changed: "docs" 2026-06-09 09:13:28.622613 | 2026-06-09 09:13:28.622772 | TASK [Check if worker can sudo] 2026-06-09 09:13:29.165689 | controller | ok: Runtime: 0:00:00.047332 2026-06-09 09:13:29.174375 | 2026-06-09 09:13:29.174559 | TASK [configure-mirrors : Gather needed facts] 2026-06-09 09:13:29.251437 | controller | skipping: Conditional result was False 2026-06-09 09:13:29.257840 | 2026-06-09 09:13:29.257961 | TASK [configure-mirrors : Set up infrastructure mirrors] 2026-06-09 09:13:29.342456 | controller | ok 2026-06-09 09:13:29.358219 | controller | included: /var/lib/zuul/builds/ebbb7be45f4c46edb91e5d8546b99136/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml 2026-06-09 09:13:29.369121 | 2026-06-09 09:13:29.369258 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir] 2026-06-09 09:13:29.650096 | controller | ok 2026-06-09 09:13:29.662597 | 2026-06-09 09:13:29.662769 | LOOP [configure-mirrors : Include OS-specific variables] 2026-06-09 09:13:29.758005 | controller | ok: "/var/lib/zuul/builds/ebbb7be45f4c46edb91e5d8546b99136/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml" 2026-06-09 09:13:29.778397 | 2026-06-09 09:13:29.778680 | TASK [configure-mirrors : Install /etc/pip.conf configuration] 2026-06-09 09:13:30.602476 | controller | changed 2026-06-09 09:13:30.615889 | 2026-06-09 09:13:30.616112 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors] 2026-06-09 09:13:30.783780 | controller | ok: "/var/lib/zuul/builds/ebbb7be45f4c46edb91e5d8546b99136/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml" 2026-06-09 09:13:30.783948 | controller | ok: All items complete 2026-06-09 09:13:30.783983 | 2026-06-09 09:13:30.828850 | controller | included: /var/lib/zuul/builds/ebbb7be45f4c46edb91e5d8546b99136/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml 2026-06-09 09:13:30.834190 | 2026-06-09 09:13:30.834260 | LOOP [configure-mirrors : Install CentOS 9 repository files] 2026-06-09 09:13:31.595964 | controller | changed: "etc/yum.repos.d/centos.repo" 2026-06-09 09:13:32.305992 | controller | changed: "etc/yum.repos.d/centos-addons.repo" 2026-06-09 09:13:32.325716 | 2026-06-09 09:13:32.328440 | TASK [configure-mirrors : Disable deltrarpm] 2026-06-09 09:13:32.790792 | controller | changed: section and option added 2026-06-09 09:13:32.861618 | 2026-06-09 09:13:32.861776 | LOOP [configure-mirrors : Update yum/dnf cache] 2026-06-09 09:13:33.791568 | controller | 29 files removed 2026-06-09 09:13:33.792005 | controller | ok: Item: dnf clean all Runtime: 0:00:00.598539 2026-06-09 09:13:33.792082 | controller | changed: All items complete 2026-06-09 09:13:33.792118 | 2026-06-09 09:13:45.814968 | 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-06-09 09:13:45.815203 | controller | DNF version: 4.14.0 2026-06-09 09:13:45.815261 | controller | cachedir: /var/cache/dnf 2026-06-09 09:13:45.815307 | controller | Making cache files for all metadata files. 2026-06-09 09:13:45.815361 | controller | baseos: has expired and will be refreshed. 2026-06-09 09:13:45.815456 | controller | appstream: has expired and will be refreshed. 2026-06-09 09:13:45.815509 | controller | crb: has expired and will be refreshed. 2026-06-09 09:13:45.815599 | controller | extras-common: has expired and will be refreshed. 2026-06-09 09:13:45.815642 | controller | repo: downloading from remote: baseos 2026-06-09 09:13:45.815680 | controller | CentOS Stream 9 - BaseOS 49 MB/s | 8.9 MB 00:00 2026-06-09 09:13:45.815718 | controller | baseos: using metadata from Mon 01 Jun 2026 05:58:58 PM EDT. 2026-06-09 09:13:45.815755 | controller | repo: downloading from remote: appstream 2026-06-09 09:13:45.815794 | controller | CentOS Stream 9 - AppStream 61 MB/s | 28 MB 00:00 2026-06-09 09:13:45.815831 | controller | appstream: using metadata from Mon 01 Jun 2026 06:02:34 PM EDT. 2026-06-09 09:13:45.815868 | controller | repo: downloading from remote: crb 2026-06-09 09:13:45.815906 | controller | CentOS Stream 9 - CRB 23 MB/s | 8.1 MB 00:00 2026-06-09 09:13:45.815946 | controller | crb: using metadata from Mon 01 Jun 2026 06:07:05 PM EDT. 2026-06-09 09:13:45.815983 | controller | repo: downloading from remote: extras-common 2026-06-09 09:13:45.816020 | controller | CentOS Stream 9 - Extras packages 309 kB/s | 21 kB 00:00 2026-06-09 09:13:45.816057 | controller | extras-common: using metadata from Tue 24 Mar 2026 03:36:22 PM EDT. 2026-06-09 09:13:45.816095 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)' 2026-06-09 09:13:45.816133 | controller | Completion plugin: Generating completion cache... 2026-06-09 09:13:45.816170 | controller | Metadata cache created. 2026-06-09 09:13:45.816229 | controller | ok: Item: dnf makecache -v Runtime: 0:00:11.813346 2026-06-09 09:13:45.826352 | 2026-06-09 09:13:45.826587 | PLAY RECAP 2026-06-09 09:13:45.826682 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-09 09:13:45.826766 | localhost | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-09 09:13:45.826807 | 2026-06-09 09:13:45.969466 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-06-09 09:13:45.971876 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-06-09 09:13:46.640855 | 2026-06-09 09:13:46.641072 | PLAY [all] 2026-06-09 09:13:46.666462 | 2026-06-09 09:13:46.666710 | TASK [Install binary dependencies] 2026-06-09 09:13:46.739854 | controller | ok 2026-06-09 09:13:46.762021 | 2026-06-09 09:13:46.762157 | TASK [bindep : Include find tasks] 2026-06-09 09:13:46.790738 | controller | ok 2026-06-09 09:13:46.798891 | controller | included: /var/lib/zuul/builds/ebbb7be45f4c46edb91e5d8546b99136/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-06-09 09:13:46.805101 | 2026-06-09 09:13:46.805186 | TASK [bindep : Look for bindep.txt] 2026-06-09 09:13:47.226998 | controller | ok 2026-06-09 09:13:47.243581 | 2026-06-09 09:13:47.243907 | TASK [bindep : Define bindep_file fact] 2026-06-09 09:13:47.292432 | controller | ok 2026-06-09 09:13:47.305157 | 2026-06-09 09:13:47.305469 | TASK [bindep : Look for other-requirements.txt] 2026-06-09 09:13:47.335882 | controller | skipping: Conditional result was False 2026-06-09 09:13:47.343971 | 2026-06-09 09:13:47.344210 | TASK [bindep : Define bindep_file fact] 2026-06-09 09:13:47.382485 | controller | skipping: Conditional result was False 2026-06-09 09:13:47.389989 | 2026-06-09 09:13:47.390092 | TASK [bindep : Look for bindep fallback file] 2026-06-09 09:13:47.438562 | controller | skipping: Conditional result was False 2026-06-09 09:13:47.445696 | 2026-06-09 09:13:47.445933 | TASK [bindep : Define bindep_file fact] 2026-06-09 09:13:47.473673 | controller | skipping: Conditional result was False 2026-06-09 09:13:47.481124 | 2026-06-09 09:13:47.481224 | TASK [bindep : Include bindep tasks] 2026-06-09 09:13:47.512129 | controller | ok 2026-06-09 09:13:47.520164 | controller | included: /var/lib/zuul/builds/ebbb7be45f4c46edb91e5d8546b99136/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml 2026-06-09 09:13:47.531986 | 2026-06-09 09:13:47.532079 | TASK [bindep : Look for bindep command] 2026-06-09 09:13:47.559554 | controller | skipping: Conditional result was False 2026-06-09 09:13:47.568037 | 2026-06-09 09:13:47.568135 | TASK [bindep : Check for system bindep] 2026-06-09 09:13:48.108358 | controller | ok: Runtime: 0:00:00.004376 2026-06-09 09:13:48.117884 | 2026-06-09 09:13:48.118016 | TASK [bindep : Define bindep_command fact] 2026-06-09 09:13:48.156690 | controller | skipping: Conditional result was False 2026-06-09 09:13:48.166192 | 2026-06-09 09:13:48.166362 | TASK [bindep : Include install tasks] 2026-06-09 09:13:48.212048 | controller | ok 2026-06-09 09:13:48.224090 | controller | included: /var/lib/zuul/builds/ebbb7be45f4c46edb91e5d8546b99136/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml 2026-06-09 09:13:48.240541 | 2026-06-09 09:13:48.240636 | TASK [bindep : Create temp dir for bindep] 2026-06-09 09:13:48.610672 | controller | changed 2026-06-09 09:13:48.619203 | 2026-06-09 09:13:48.619330 | TASK [Ensure we have pip dependencies] 2026-06-09 09:13:48.644683 | controller | ok 2026-06-09 09:13:48.684719 | 2026-06-09 09:13:48.684838 | TASK [ensure-pip : Check if pip is installed] 2026-06-09 05:13:48.974014 | controller | /usr/bin/pip3 2026-06-09 05:13:48.992438 | controller | /usr/bin/python3: No module named wheel 2026-06-09 09:13:49.449168 | controller | ok: Runtime: 0:00:00.027868 2026-06-09 09:13:49.457705 | 2026-06-09 09:13:49.457840 | LOOP [ensure-pip : Install pip from packages] 2026-06-09 09:13:49.498923 | controller | ok: "/var/lib/zuul/builds/ebbb7be45f4c46edb91e5d8546b99136/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml" 2026-06-09 09:13:49.508378 | controller | included: /var/lib/zuul/builds/ebbb7be45f4c46edb91e5d8546b99136/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml 2026-06-09 09:13:49.551419 | 2026-06-09 09:13:49.551555 | TASK [ensure-pip : Install Python 3 pip] 2026-06-09 09:13:51.326858 | controller | ok: Nothing to do 2026-06-09 09:13:51.335671 | 2026-06-09 09:13:51.335802 | TASK [ensure-pip : Install Python3-wheel on older CentOS] 2026-06-09 09:13:53.094626 | controller | changed 2026-06-09 09:13:53.103278 | 2026-06-09 09:13:53.103403 | TASK [ensure-pip : Check for EPEL repository] 2026-06-09 09:13:53.149995 | controller | skipping: Conditional result was False 2026-06-09 09:13:53.159789 | 2026-06-09 09:13:53.159924 | TASK [ensure-pip : Fail with instructions when EPEL is not installed] 2026-06-09 09:13:53.206232 | controller | skipping: Conditional result was False 2026-06-09 09:13:53.218215 | 2026-06-09 09:13:53.218346 | TASK [ensure-pip : Install Python 2 pip] 2026-06-09 09:13:53.264043 | controller | skipping: Conditional result was False 2026-06-09 09:13:53.273165 | 2026-06-09 09:13:53.273318 | TASK [ensure-pip : Ensure setuptools] 2026-06-09 09:13:53.288810 | controller | skipping: Conditional result was False 2026-06-09 09:13:53.297824 | 2026-06-09 09:13:53.297955 | TASK [ensure-pip : Check for ensurepip module] 2026-06-09 09:13:53.361233 | controller | skipping: Conditional result was False 2026-06-09 09:13:53.368696 | 2026-06-09 09:13:53.368784 | TASK [ensure-pip : Ensure python3-venv] 2026-06-09 09:13:53.383800 | controller | skipping: Conditional result was False 2026-06-09 09:13:53.389986 | 2026-06-09 09:13:53.390071 | TASK [ensure-pip : Install pip from source] 2026-06-09 09:13:53.414334 | controller | skipping: Conditional result was False 2026-06-09 09:13:53.420618 | 2026-06-09 09:13:53.420704 | TASK [ensure-pip : Probe for venv python full path] 2026-06-09 05:13:54.208600 | controller | /usr/bin/python3 2026-06-09 09:13:55.040791 | controller | ok: Runtime: 0:00:00.003257 2026-06-09 09:13:55.050793 | 2026-06-09 09:13:55.050960 | TASK [ensure-pip : Set host default] 2026-06-09 09:13:55.127318 | controller | ok 2026-06-09 09:13:55.135550 | 2026-06-09 09:13:55.135685 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2026-06-09 09:13:55.214587 | controller | ok 2026-06-09 09:13:55.232707 | 2026-06-09 09:13:55.232875 | TASK [bindep : Install bindep into temporary venv] 2026-06-09 09:14:04.972992 | controller | changed 2026-06-09 09:14:04.981637 | 2026-06-09 09:14:04.981770 | TASK [bindep : Define bindep_command] 2026-06-09 09:14:05.016335 | controller | ok 2026-06-09 09:14:05.024384 | 2026-06-09 09:14:05.024627 | LOOP [bindep : Include package tasks] 2026-06-09 09:14:05.072401 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt" 2026-06-09 09:14:05.072714 | controller | ok: All items complete 2026-06-09 09:14:05.072760 | 2026-06-09 09:14:05.097392 | controller | included: /var/lib/zuul/builds/ebbb7be45f4c46edb91e5d8546b99136/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml 2026-06-09 09:14:05.119688 | 2026-06-09 09:14:05.119842 | TASK [bindep : Define bindep_run fact] 2026-06-09 09:14:05.150969 | controller | ok 2026-06-09 09:14:05.155787 | 2026-06-09 09:14:05.155871 | TASK [bindep : Get list of packages to install from bindep] 2026-06-09 05:14:07.338381 | controller | podman 2026-06-09 05:14:07.338525 | controller | python3-jmespath 2026-06-09 05:14:07.338533 | controller | python3-libvirt 2026-06-09 05:14:07.338538 | controller | python3-lxml 2026-06-09 05:14:07.338542 | controller | python3-netaddr 2026-06-09 09:14:07.694775 | controller | ok: Runtime: 0:00:01.329421 2026-06-09 09:14:07.703928 | 2026-06-09 09:14:07.704089 | TASK [bindep : Install distro packages from bindep] 2026-06-09 09:15:10.950405 | controller | changed 2026-06-09 09:15:10.960199 | 2026-06-09 09:15:10.960334 | TASK [bindep : Check that packages are installed] 2026-06-09 09:15:12.502892 | controller | ok: Runtime: 0:00:01.179147 2026-06-09 09:15:12.511143 | 2026-06-09 09:15:12.511287 | TASK [bindep : Fail if we cannot install all packages] 2026-06-09 09:15:12.547727 | controller | skipping: Conditional result was False 2026-06-09 09:15:12.564894 | 2026-06-09 09:15:12.565055 | TASK [Run test-setup role] 2026-06-09 09:15:12.590879 | controller | ok 2026-06-09 09:15:12.621446 | 2026-06-09 09:15:12.621630 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-06-09 09:15:12.832756 | controller | ok 2026-06-09 09:15:12.840584 | 2026-06-09 09:15:12.840720 | TASK [test-setup : Run tools/test-setup.sh] 2026-06-09 09:15:13.382374 | controller | skipping: Conditional result was False 2026-06-09 09:15:13.394479 | 2026-06-09 09:15:13.394590 | TASK [bindep : Remove bindep temp dir] 2026-06-09 09:15:13.808197 | controller | ok 2026-06-09 09:15:13.810803 | 2026-06-09 09:15:13.810899 | PLAY RECAP 2026-06-09 09:15:13.810982 | controller | ok: 23 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0 2026-06-09 09:15:13.811025 | 2026-06-09 09:15:13.958408 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-06-09 09:15:13.961258 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2026-06-09 09:15:14.632644 | 2026-06-09 09:15:14.632817 | PLAY [all] 2026-06-09 09:15:14.642333 | 2026-06-09 09:15:14.642422 | TASK [Abort when test_command variable is undefined] 2026-06-09 09:15:14.688025 | controller | skipping: Conditional result was False 2026-06-09 09:15:14.693002 | 2026-06-09 09:15:14.693078 | TASK [Convert test_command to list] 2026-06-09 09:15:14.759509 | controller | skipping: Conditional result was False 2026-06-09 09:15:14.769979 | 2026-06-09 09:15:14.770130 | TASK [Use test_command list] 2026-06-09 09:15:14.836340 | controller | ok 2026-06-09 09:15:14.848838 | 2026-06-09 09:15:14.849068 | LOOP [Run test_command] 2026-06-09 09:15:15.257682 | controller | no check to run 2026-06-09 09:15:15.257951 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.004902 2026-06-09 09:15:15.261751 | 2026-06-09 09:15:15.261922 | PLAY RECAP 2026-06-09 09:15:15.262006 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-09 09:15:15.262050 | 2026-06-09 09:15:15.395952 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2026-06-09 09:15:15.398610 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-09 09:15:16.076318 | 2026-06-09 09:15:16.076450 | PLAY [all] 2026-06-09 09:15:16.087426 | 2026-06-09 09:15:16.087557 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-06-09 09:15:16.421302 | controller | changed: non-zero return code 2026-06-09 09:15:16.427667 | 2026-06-09 09:15:16.427810 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-06-09 09:15:16.455983 | controller | skipping: Conditional result was False 2026-06-09 09:15:16.472641 | 2026-06-09 09:15:16.472926 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-06-09 09:15:16.512962 | 2026-06-09 09:15:16.513305 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-06-09 09:15:16.551885 | 2026-06-09 09:15:16.552219 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-06-09 09:15:16.580426 | controller | skipping: Conditional result was False 2026-06-09 09:15:16.592197 | 2026-06-09 09:15:16.592474 | LOOP [fetch-subunit-output : Generate subunit file] 2026-06-09 09:15:16.633451 | 2026-06-09 09:15:16.633941 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-06-09 09:15:16.665259 | controller | skipping: Conditional result was False 2026-06-09 09:15:16.677572 | 2026-06-09 09:15:16.677767 | TASK [fetch-subunit-output : Remove the temporary file] 2026-06-09 09:15:16.696183 | controller | skipping: Conditional result was False 2026-06-09 09:15:16.706037 | 2026-06-09 09:15:16.706189 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-06-09 09:15:16.732297 | controller | skipping: Conditional result was False 2026-06-09 09:15:16.745308 | 2026-06-09 09:15:16.745419 | PLAY RECAP 2026-06-09 09:15:16.745519 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-06-09 09:15:16.745596 | 2026-06-09 09:15:16.920023 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-09 09:15:16.922648 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-06-09 09:15:17.614778 | 2026-06-09 09:15:17.614938 | PLAY [all] 2026-06-09 09:15:17.625669 | 2026-06-09 09:15:17.625748 | TASK [include_role : fetch-output] 2026-06-09 09:15:17.674994 | controller | ok 2026-06-09 09:15:17.690680 | 2026-06-09 09:15:17.690775 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-09 09:15:17.767197 | controller | skipping: Conditional result was False 2026-06-09 09:15:17.777994 | 2026-06-09 09:15:17.778167 | TASK [fetch-output : Set log path for single node] 2026-06-09 09:15:17.826081 | controller | ok 2026-06-09 09:15:17.835003 | 2026-06-09 09:15:17.835160 | LOOP [fetch-output : Ensure local output dirs] 2026-06-09 09:15:18.371380 | controller -> localhost | changed: "/var/lib/zuul/builds/ebbb7be45f4c46edb91e5d8546b99136/work/logs" 2026-06-09 09:15:18.655723 | controller -> localhost | changed: "/var/lib/zuul/builds/ebbb7be45f4c46edb91e5d8546b99136/work/artifacts" 2026-06-09 09:15:18.935048 | controller -> localhost | changed: "/var/lib/zuul/builds/ebbb7be45f4c46edb91e5d8546b99136/work/docs" 2026-06-09 09:15:18.957400 | 2026-06-09 09:15:18.957705 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-09 09:15:19.702180 | controller | changed: .d..t...... ./ 2026-06-09 09:15:19.702614 | controller | changed: All items complete 2026-06-09 09:15:19.702682 | 2026-06-09 09:15:20.256516 | controller | changed: .d..t...... ./ 2026-06-09 09:15:20.786000 | controller | changed: .d..t...... ./ 2026-06-09 09:15:20.799103 | 2026-06-09 09:15:20.799207 | TASK [include_role : fetch-output-openshift] 2026-06-09 09:15:20.823185 | controller | skipping: Conditional result was False 2026-06-09 09:15:20.829141 | 2026-06-09 09:15:20.829230 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-09 09:15:21.403671 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.014434 2026-06-09 09:15:21.742201 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.011776 2026-06-09 09:15:21.764068 | 2026-06-09 09:15:21.764190 | PLAY [all] 2026-06-09 09:15:21.774760 | 2026-06-09 09:15:21.774892 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-09 09:15:22.214830 | controller | changed 2026-06-09 09:15:22.224069 | 2026-06-09 09:15:22.224169 | PLAY RECAP 2026-06-09 09:15:22.224244 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-09 09:15:22.224285 | 2026-06-09 09:15:22.357582 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-06-09 09:15:22.359826 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2026-06-09 09:15:23.038682 | 2026-06-09 09:15:23.038785 | PLAY [localhost] 2026-06-09 09:15:23.048879 | 2026-06-09 09:15:23.048970 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-09 09:15:23.487720 | localhost | changed 2026-06-09 09:15:23.500488 | 2026-06-09 09:15:23.500732 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-09 09:15:23.542108 | localhost | ok 2026-06-09 09:15:23.556157 | 2026-06-09 09:15:23.556313 | TASK [add-fileserver : Create SSH private key tempfile] 2026-06-09 09:15:24.018193 | localhost | changed 2026-06-09 09:15:24.027947 | 2026-06-09 09:15:24.028122 | TASK [add-fileserver : Create SSH private key from secret] 2026-06-09 09:15:24.871351 | localhost | changed 2026-06-09 09:15:24.875745 | 2026-06-09 09:15:24.875831 | TASK [add-fileserver : Add fileserver ssh key] 2026-06-09 09:15:25.375271 | localhost | Identity added: /var/lib/zuul/builds/ebbb7be45f4c46edb91e5d8546b99136/work/tmp/ansible.3wtozz92 (/var/lib/zuul/builds/ebbb7be45f4c46edb91e5d8546b99136/work/tmp/ansible.3wtozz92) 2026-06-09 09:15:25.375454 | localhost | ok: Runtime: 0:00:00.019361 2026-06-09 09:15:25.378747 | 2026-06-09 09:15:25.378811 | TASK [add-fileserver : Remove SSH private key from disk] 2026-06-09 09:15:25.698310 | localhost | ok: Runtime: 0:00:00.009500 2026-06-09 09:15:25.706431 | 2026-06-09 09:15:25.706611 | TASK [add-fileserver : Add fileserver to inventory] 2026-06-09 09:15:25.759734 | localhost | changed 2026-06-09 09:15:25.768987 | 2026-06-09 09:15:25.769125 | TASK [add-fileserver : Add fileserver server to known hosts] 2026-06-09 09:15:26.234838 | localhost | changed 2026-06-09 09:15:26.239806 | 2026-06-09 09:15:26.239861 | PLAY [localhost] 2026-06-09 09:15:26.243752 | 2026-06-09 09:15:26.243809 | TASK [Generate bulk log download script] 2026-06-09 09:15:26.259928 | localhost | ok 2026-06-09 09:15:26.269337 | 2026-06-09 09:15:26.269395 | TASK [local-log-download : Check API endpoint is defined] 2026-06-09 09:15:26.296485 | localhost | ok: All assertions passed 2026-06-09 09:15:26.300220 | 2026-06-09 09:15:26.300281 | TASK [local-log-download : Create download script] 2026-06-09 09:15:26.836001 | localhost -> localhost | changed 2026-06-09 09:15:26.900091 | 2026-06-09 09:15:26.900232 | TASK [Register quick-download link] 2026-06-09 09:15:26.918863 | localhost | ok 2026-06-09 09:15:26.921404 | 2026-06-09 09:15:26.921471 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com] 2026-06-09 09:15:26.927600 | 2026-06-09 09:15:26.927679 | TASK [Set zuul-log-path fact] 2026-06-09 09:15:26.944988 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-09 09:15:26.953281 | 2026-06-09 09:15:26.953363 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-09 09:15:26.989896 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-09 09:15:26.993106 | 2026-06-09 09:15:26.993186 | TASK [upload-logs : Create log directories] 2026-06-09 09:15:27.872372 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed 2026-06-09 09:15:27.874041 | 2026-06-09 09:15:27.874102 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-09 09:15:28.229988 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.009049 2026-06-09 09:15:28.236211 | 2026-06-09 09:15:28.236342 | TASK [upload-logs : Upload logs to log server] 2026-06-09 09:15:29.450793 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | Output suppressed because no_log was given 2026-06-09 09:15:29.452873 | 2026-06-09 09:15:29.452963 | LOOP [upload-logs : Compress console log and json output]