2025-09-30 11:18:10.804150 | Job console starting... 2025-09-30 11:18:10.814857 | Updating repositories 2025-09-30 11:18:10.865085 | Preparing job workspace 2025-09-30 11:18:15.329471 | Running Ansible setup... 2025-09-30 11:18:19.525910 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-09-30 11:18:20.107559 | 2025-09-30 11:18:20.107670 | PLAY [localhost] 2025-09-30 11:18:20.118815 | 2025-09-30 11:18:20.118889 | TASK [Gathering Facts] 2025-09-30 11:18:21.100997 | localhost | ok 2025-09-30 11:18:21.123015 | 2025-09-30 11:18:21.123161 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-30 11:18:21.523888 | localhost -> localhost | changed 2025-09-30 11:18:21.529772 | 2025-09-30 11:18:21.529844 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-30 11:18:22.420408 | localhost -> localhost | changed 2025-09-30 11:18:22.429031 | 2025-09-30 11:18:22.429099 | TASK [Setup log path fact] 2025-09-30 11:18:22.446599 | localhost | ok 2025-09-30 11:18:22.458166 | 2025-09-30 11:18:22.458241 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-30 11:18:22.475646 | localhost | ok 2025-09-30 11:18:22.483198 | 2025-09-30 11:18:22.483260 | TASK [emit-job-header : Print job information] 2025-09-30 11:18:22.510500 | # Job Information 2025-09-30 11:18:22.510643 | Ansible Version: 2.15.12 2025-09-30 11:18:22.510669 | Job: cifmw-molecule-openshift_adm 2025-09-30 11:18:22.510706 | Pipeline: github-check 2025-09-30 11:18:22.510726 | Executor: ze04.softwarefactory-project.io 2025-09-30 11:18:22.510744 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3333 2025-09-30 11:18:22.510763 | Log URL (when completed): https://logserver.rdoproject.org/8c0/rdoproject.org/8c0f46b08ccb4c709060d528a9177e26/ 2025-09-30 11:18:22.510781 | Event ID: c7820690-9dee-11f0-870e-caa834dfb11c 2025-09-30 11:18:22.514708 | 2025-09-30 11:18:22.514771 | LOOP [emit-job-header : Print node information] 2025-09-30 11:18:22.629878 | localhost | ok: 2025-09-30 11:18:22.630138 | localhost | # Node Information 2025-09-30 11:18:22.630169 | localhost | Inventory Hostname: controller 2025-09-30 11:18:22.630197 | localhost | Hostname: np0005462640 2025-09-30 11:18:22.630219 | localhost | Username: zuul 2025-09-30 11:18:22.630241 | localhost | Distro: CentOS 9 2025-09-30 11:18:22.630266 | localhost | Provider: vexxhost-nodepool-tripleo 2025-09-30 11:18:22.630292 | localhost | Region: RegionOne 2025-09-30 11:18:22.630314 | localhost | Label: cloud-centos-9-stream-tripleo 2025-09-30 11:18:22.630339 | localhost | Product Name: OpenStack Nova 2025-09-30 11:18:22.630366 | localhost | Interface IP: 38.129.56.208 2025-09-30 11:18:22.659283 | 2025-09-30 11:18:22.659362 | PLAY [all] 2025-09-30 11:18:22.666048 | 2025-09-30 11:18:22.666113 | TASK [Gather network facts] 2025-09-30 11:18:23.172021 | controller | ok 2025-09-30 11:18:23.195300 | 2025-09-30 11:18:23.195412 | TASK [include_role : start-zuul-console] 2025-09-30 11:18:23.216822 | controller | ok 2025-09-30 11:18:23.234665 | 2025-09-30 11:18:23.234817 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-30 11:18:23.652481 | controller | ok 2025-09-30 11:18:23.663457 | 2025-09-30 11:18:23.663564 | TASK [include_role : add-build-sshkey] 2025-09-30 11:18:23.696368 | controller | ok 2025-09-30 11:18:23.709172 | 2025-09-30 11:18:23.709236 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-30 11:18:23.951010 | controller -> localhost | ok 2025-09-30 11:18:23.964229 | 2025-09-30 11:18:23.964524 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-30 11:18:23.995909 | controller | ok 2025-09-30 11:18:24.009794 | controller | included: /var/lib/zuul/builds/8c0f46b08ccb4c709060d528a9177e26/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-30 11:18:24.015446 | 2025-09-30 11:18:24.015512 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-30 11:18:24.560898 | controller -> localhost | Generating public/private rsa key pair. 2025-09-30 11:18:24.561101 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/8c0f46b08ccb4c709060d528a9177e26/work/8c0f46b08ccb4c709060d528a9177e26_id_rsa. 2025-09-30 11:18:24.561133 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/8c0f46b08ccb4c709060d528a9177e26/work/8c0f46b08ccb4c709060d528a9177e26_id_rsa.pub. 2025-09-30 11:18:24.561155 | controller -> localhost | The key fingerprint is: 2025-09-30 11:18:24.561175 | controller -> localhost | SHA256:EUSUirPpM20gpiH3ESde249rKDz5O+ObZwVP8FmubdU zuul-build-sshkey 2025-09-30 11:18:24.561194 | controller -> localhost | The key's randomart image is: 2025-09-30 11:18:24.561211 | controller -> localhost | +---[RSA 3072]----+ 2025-09-30 11:18:24.561229 | controller -> localhost | | +=. | 2025-09-30 11:18:24.561247 | controller -> localhost | | o. . | 2025-09-30 11:18:24.561264 | controller -> localhost | | . ..o + . | 2025-09-30 11:18:24.561282 | controller -> localhost | | = + ..+ . . E| 2025-09-30 11:18:24.561299 | controller -> localhost | | . O oS+ o . | 2025-09-30 11:18:24.561315 | controller -> localhost | |o + * . . + o | 2025-09-30 11:18:24.561332 | controller -> localhost | |.= = = . + . | 2025-09-30 11:18:24.561351 | controller -> localhost | |. X *.= . | 2025-09-30 11:18:24.561370 | controller -> localhost | | O*O.. | 2025-09-30 11:18:24.561387 | controller -> localhost | +----[SHA256]-----+ 2025-09-30 11:18:24.561433 | controller -> localhost | ok: Runtime: 0:00:00.120383 2025-09-30 11:18:24.567962 | 2025-09-30 11:18:24.568024 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-30 11:18:24.591338 | controller | ok 2025-09-30 11:18:24.610269 | controller | included: /var/lib/zuul/builds/8c0f46b08ccb4c709060d528a9177e26/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-30 11:18:24.629230 | 2025-09-30 11:18:24.629349 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-30 11:18:24.658470 | controller | skipping: Conditional result was False 2025-09-30 11:18:24.664080 | 2025-09-30 11:18:24.664145 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-30 11:18:25.165577 | controller | changed 2025-09-30 11:18:25.170588 | 2025-09-30 11:18:25.170649 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-30 11:18:25.448478 | controller | ok 2025-09-30 11:18:25.455195 | 2025-09-30 11:18:25.455284 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-30 11:18:26.340780 | controller | changed 2025-09-30 11:18:26.348786 | 2025-09-30 11:18:26.348870 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-30 11:18:27.246050 | controller | changed 2025-09-30 11:18:27.253350 | 2025-09-30 11:18:27.253423 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-30 11:18:27.323973 | controller | skipping: Conditional result was False 2025-09-30 11:18:27.332845 | 2025-09-30 11:18:27.332925 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-30 11:18:27.857520 | controller -> localhost | changed 2025-09-30 11:18:27.880131 | 2025-09-30 11:18:27.880230 | TASK [add-build-sshkey : Add back temp key] 2025-09-30 11:18:28.188809 | controller -> localhost | Identity added: /var/lib/zuul/builds/8c0f46b08ccb4c709060d528a9177e26/work/8c0f46b08ccb4c709060d528a9177e26_id_rsa (zuul-build-sshkey) 2025-09-30 11:18:28.189012 | controller -> localhost | ok: Runtime: 0:00:00.009599 2025-09-30 11:18:28.195248 | 2025-09-30 11:18:28.195320 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-30 11:18:28.696895 | controller | ok 2025-09-30 11:18:28.707653 | 2025-09-30 11:18:28.707790 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-30 11:18:28.764108 | controller | skipping: Conditional result was False 2025-09-30 11:18:28.775996 | 2025-09-30 11:18:28.776068 | TASK [include_role : validate-host] 2025-09-30 11:18:28.827941 | controller | ok 2025-09-30 11:18:28.865748 | 2025-09-30 11:18:28.865857 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-30 11:18:28.918941 | controller | ok 2025-09-30 11:18:28.923746 | 2025-09-30 11:18:28.923807 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-30 11:18:29.154887 | controller -> localhost | ok 2025-09-30 11:18:29.160743 | 2025-09-30 11:18:29.160980 | TASK [validate-host : Collect information about the host] 2025-09-30 11:18:29.940472 | controller | ok 2025-09-30 11:18:29.952764 | 2025-09-30 11:18:29.952837 | TASK [validate-host : Sanitize hostname] 2025-09-30 11:18:30.002164 | controller | ok 2025-09-30 11:18:30.007373 | 2025-09-30 11:18:30.007436 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-30 11:18:30.681258 | controller -> localhost | changed 2025-09-30 11:18:30.687279 | 2025-09-30 11:18:30.687359 | TASK [validate-host : Collect information about zuul worker] 2025-09-30 11:18:31.223216 | controller | ok 2025-09-30 11:18:31.229650 | 2025-09-30 11:18:31.229779 | TASK [validate-host : Write out all zuul information for each host] 2025-09-30 11:18:31.949951 | controller -> localhost | changed 2025-09-30 11:18:31.961533 | 2025-09-30 11:18:31.961656 | TASK [include_role : prepare-workspace-openshift] 2025-09-30 11:18:32.013502 | controller | skipping: Conditional result was False 2025-09-30 11:18:32.019981 | 2025-09-30 11:18:32.020064 | TASK [include_role : remove-zuul-sshkey] 2025-09-30 11:18:32.044640 | controller | skipping: Conditional result was False 2025-09-30 11:18:32.052130 | 2025-09-30 11:18:32.052224 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-09-30 11:18:32.332694 | controller | ok: "logs" 2025-09-30 11:18:32.332917 | controller | ok: All items complete 2025-09-30 11:18:32.332944 | 2025-09-30 11:18:32.584613 | controller | ok: "artifacts" 2025-09-30 11:18:32.852386 | controller | ok: "docs" 2025-09-30 11:18:32.859410 | 2025-09-30 11:18:32.859535 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-09-30 11:18:33.210205 | controller | changed: "logs" 2025-09-30 11:18:33.460426 | controller | changed: "artifacts" 2025-09-30 11:18:33.690023 | controller | changed: "docs" 2025-09-30 11:18:33.725947 | 2025-09-30 11:18:33.726050 | PLAY RECAP 2025-09-30 11:18:33.726093 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-09-30 11:18:33.726120 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-30 11:18:33.726138 | 2025-09-30 11:18:33.859262 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-09-30 11:18:33.860091 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2025-09-30 11:18:35.361897 | 2025-09-30 11:18:35.362035 | PLAY [localhost] 2025-09-30 11:18:35.396619 | 2025-09-30 11:18:35.396783 | TASK [configure-ssh-config-fips : Ensure ssh config directory] 2025-09-30 11:18:36.039049 | localhost | ok 2025-09-30 11:18:36.046148 | 2025-09-30 11:18:36.046238 | TASK [configure-ssh-config-fips : Setup ssh config] 2025-09-30 11:18:36.911919 | localhost | changed 2025-09-30 11:18:36.986957 | 2025-09-30 11:18:36.987109 | PLAY [all] 2025-09-30 11:18:37.026969 | 2025-09-30 11:18:37.027091 | TASK [include_role : prepare-workspace] 2025-09-30 11:18:37.065966 | controller | ok 2025-09-30 11:18:37.093822 | 2025-09-30 11:18:37.093928 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-09-30 11:18:37.634945 | controller | ok 2025-09-30 11:18:37.645384 | 2025-09-30 11:18:37.645487 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-09-30 11:18:39.556836 | controller | Output suppressed because no_log was given 2025-09-30 11:18:39.572090 | 2025-09-30 11:18:39.572193 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-09-30 11:18:39.834374 | controller | changed: "logs" 2025-09-30 11:18:40.035941 | controller | changed: "artifacts" 2025-09-30 11:18:40.257348 | controller | changed: "docs" 2025-09-30 11:18:40.270177 | 2025-09-30 11:18:40.270289 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-09-30 11:18:40.532495 | controller | changed: "logs" 2025-09-30 11:18:40.533296 | controller | changed: All items complete 2025-09-30 11:18:40.533381 | 2025-09-30 11:18:40.772751 | controller | changed: "artifacts" 2025-09-30 11:18:41.028042 | controller | changed: "docs" 2025-09-30 11:18:41.064013 | 2025-09-30 11:18:41.064145 | TASK [Check if worker can sudo] 2025-09-30 11:18:41.621036 | controller | ok: Runtime: 0:00:00.048923 2025-09-30 11:18:41.627630 | 2025-09-30 11:18:41.627732 | TASK [configure-mirrors : Gather needed facts] 2025-09-30 11:18:41.672516 | controller | skipping: Conditional result was False 2025-09-30 11:18:41.679271 | 2025-09-30 11:18:41.679569 | TASK [configure-mirrors : Set up infrastructure mirrors] 2025-09-30 11:18:41.790511 | controller | ok 2025-09-30 11:18:41.827340 | controller | included: /var/lib/zuul/builds/8c0f46b08ccb4c709060d528a9177e26/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml 2025-09-30 11:18:41.833306 | 2025-09-30 11:18:41.833369 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir] 2025-09-30 11:18:42.134262 | controller | ok 2025-09-30 11:18:42.142827 | 2025-09-30 11:18:42.142901 | LOOP [configure-mirrors : Include OS-specific variables] 2025-09-30 11:18:42.201277 | controller | ok: "/var/lib/zuul/builds/8c0f46b08ccb4c709060d528a9177e26/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml" 2025-09-30 11:18:42.213461 | 2025-09-30 11:18:42.213551 | TASK [configure-mirrors : Install /etc/pip.conf configuration] 2025-09-30 11:18:43.032100 | controller | changed 2025-09-30 11:18:43.041277 | 2025-09-30 11:18:43.041351 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors] 2025-09-30 11:18:43.137352 | controller | ok: "/var/lib/zuul/builds/8c0f46b08ccb4c709060d528a9177e26/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml" 2025-09-30 11:18:43.137502 | controller | ok: All items complete 2025-09-30 11:18:43.137527 | 2025-09-30 11:18:43.189180 | controller | included: /var/lib/zuul/builds/8c0f46b08ccb4c709060d528a9177e26/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml 2025-09-30 11:18:43.206273 | 2025-09-30 11:18:43.206388 | LOOP [configure-mirrors : Install CentOS 9 repository files] 2025-09-30 11:18:44.132430 | controller | changed: "etc/yum.repos.d/centos.repo" 2025-09-30 11:18:45.038588 | controller | changed: "etc/yum.repos.d/centos-addons.repo" 2025-09-30 11:18:45.058878 | 2025-09-30 11:18:45.059067 | TASK [configure-mirrors : Disable deltrarpm] 2025-09-30 11:18:45.494566 | controller | changed: section and option added 2025-09-30 11:18:45.535964 | 2025-09-30 11:18:45.536080 | LOOP [configure-mirrors : Update yum/dnf cache] 2025-09-30 11:18:46.309778 | controller | 29 files removed 2025-09-30 11:18:46.310163 | controller | ok: Item: dnf clean all Runtime: 0:00:00.461937 2025-09-30 11:18:46.310243 | controller | changed: All items complete 2025-09-30 11:18:46.310288 | 2025-09-30 11:18:57.242755 | 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-09-30 11:18:57.242854 | controller | DNF version: 4.14.0 2025-09-30 11:18:57.242887 | controller | cachedir: /var/cache/dnf 2025-09-30 11:18:57.242913 | controller | Making cache files for all metadata files. 2025-09-30 11:18:57.242956 | controller | baseos: has expired and will be refreshed. 2025-09-30 11:18:57.242979 | controller | appstream: has expired and will be refreshed. 2025-09-30 11:18:57.243000 | controller | crb: has expired and will be refreshed. 2025-09-30 11:18:57.243128 | controller | extras-common: has expired and will be refreshed. 2025-09-30 11:18:57.243160 | controller | repo: downloading from remote: baseos 2025-09-30 11:18:57.243179 | controller | CentOS Stream 9 - BaseOS 71 MB/s | 8.8 MB 00:00 2025-09-30 11:18:57.243196 | controller | baseos: using metadata from Mon 22 Sep 2025 10:29:43 AM EDT. 2025-09-30 11:18:57.243212 | controller | repo: downloading from remote: appstream 2025-09-30 11:18:57.243228 | controller | CentOS Stream 9 - AppStream 97 MB/s | 25 MB 00:00 2025-09-30 11:18:57.243244 | controller | appstream: using metadata from Mon 22 Sep 2025 10:33:24 AM EDT. 2025-09-30 11:18:57.243260 | controller | repo: downloading from remote: crb 2025-09-30 11:18:57.243275 | controller | CentOS Stream 9 - CRB 43 MB/s | 7.1 MB 00:00 2025-09-30 11:18:57.243291 | controller | crb: using metadata from Mon 22 Sep 2025 10:37:51 AM EDT. 2025-09-30 11:18:57.243307 | controller | repo: downloading from remote: extras-common 2025-09-30 11:18:57.243323 | controller | CentOS Stream 9 - Extras packages 1.4 MB/s | 20 kB 00:00 2025-09-30 11:18:57.243339 | controller | extras-common: using metadata from Mon 15 Sep 2025 02:32:43 PM EDT. 2025-09-30 11:18:57.243355 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)' 2025-09-30 11:18:57.243371 | controller | Completion plugin: Generating completion cache... 2025-09-30 11:18:57.243387 | controller | Metadata cache created. 2025-09-30 11:18:57.243412 | controller | ok: Item: dnf makecache -v Runtime: 0:00:10.668225 2025-09-30 11:18:57.265590 | 2025-09-30 11:18:57.265643 | PLAY RECAP 2025-09-30 11:18:57.265702 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-09-30 11:18:57.265732 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-30 11:18:57.265751 | 2025-09-30 11:18:57.372039 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2025-09-30 11:18:57.372904 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-09-30 11:18:57.953417 | 2025-09-30 11:18:57.953528 | PLAY [all] 2025-09-30 11:18:57.977331 | 2025-09-30 11:18:57.977460 | TASK [Install binary dependencies] 2025-09-30 11:18:58.047610 | controller | ok 2025-09-30 11:18:58.069021 | 2025-09-30 11:18:58.069213 | TASK [bindep : Include find tasks] 2025-09-30 11:18:58.102362 | controller | ok 2025-09-30 11:18:58.110477 | controller | included: /var/lib/zuul/builds/8c0f46b08ccb4c709060d528a9177e26/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-09-30 11:18:58.117177 | 2025-09-30 11:18:58.117256 | TASK [bindep : Look for bindep.txt] 2025-09-30 11:18:58.496108 | controller | ok 2025-09-30 11:18:58.508906 | 2025-09-30 11:18:58.509004 | TASK [bindep : Define bindep_file fact] 2025-09-30 11:18:58.541073 | controller | ok 2025-09-30 11:18:58.548440 | 2025-09-30 11:18:58.548527 | TASK [bindep : Look for other-requirements.txt] 2025-09-30 11:18:58.573934 | controller | skipping: Conditional result was False 2025-09-30 11:18:58.581955 | 2025-09-30 11:18:58.582049 | TASK [bindep : Define bindep_file fact] 2025-09-30 11:18:58.606782 | controller | skipping: Conditional result was False 2025-09-30 11:18:58.615385 | 2025-09-30 11:18:58.615480 | TASK [bindep : Look for bindep fallback file] 2025-09-30 11:18:58.640596 | controller | skipping: Conditional result was False 2025-09-30 11:18:58.648673 | 2025-09-30 11:18:58.648806 | TASK [bindep : Define bindep_file fact] 2025-09-30 11:18:58.673923 | controller | skipping: Conditional result was False 2025-09-30 11:18:58.682445 | 2025-09-30 11:18:58.682549 | TASK [bindep : Include bindep tasks] 2025-09-30 11:18:58.723950 | controller | ok 2025-09-30 11:18:58.735202 | controller | included: /var/lib/zuul/builds/8c0f46b08ccb4c709060d528a9177e26/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml 2025-09-30 11:18:58.746819 | 2025-09-30 11:18:58.746925 | TASK [bindep : Look for bindep command] 2025-09-30 11:18:58.772477 | controller | skipping: Conditional result was False 2025-09-30 11:18:58.782839 | 2025-09-30 11:18:58.782938 | TASK [bindep : Check for system bindep] 2025-09-30 11:18:59.319460 | controller | ok: Runtime: 0:00:00.006474 2025-09-30 11:18:59.325087 | 2025-09-30 11:18:59.325157 | TASK [bindep : Define bindep_command fact] 2025-09-30 11:18:59.359946 | controller | skipping: Conditional result was False 2025-09-30 11:18:59.365931 | 2025-09-30 11:18:59.365998 | TASK [bindep : Include install tasks] 2025-09-30 11:18:59.417206 | controller | ok 2025-09-30 11:18:59.436008 | controller | included: /var/lib/zuul/builds/8c0f46b08ccb4c709060d528a9177e26/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml 2025-09-30 11:18:59.448888 | 2025-09-30 11:18:59.448950 | TASK [bindep : Create temp dir for bindep] 2025-09-30 11:18:59.853740 | controller | changed 2025-09-30 11:18:59.873429 | 2025-09-30 11:18:59.873616 | TASK [Ensure we have pip dependencies] 2025-09-30 11:18:59.910895 | controller | ok 2025-09-30 11:18:59.964186 | 2025-09-30 11:18:59.964342 | TASK [ensure-pip : Check if pip is installed] 2025-09-30 07:19:00.397145 | controller | /usr/bin/pip3 2025-09-30 07:19:00.419317 | controller | /usr/bin/python3: No module named wheel 2025-09-30 11:19:00.511137 | controller | ok: Runtime: 0:00:00.032839 2025-09-30 11:19:00.527883 | 2025-09-30 11:19:00.528069 | LOOP [ensure-pip : Install pip from packages] 2025-09-30 11:19:00.562081 | controller | ok: "/var/lib/zuul/builds/8c0f46b08ccb4c709060d528a9177e26/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml" 2025-09-30 11:19:00.583198 | controller | included: /var/lib/zuul/builds/8c0f46b08ccb4c709060d528a9177e26/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml 2025-09-30 11:19:00.610067 | 2025-09-30 11:19:00.610210 | TASK [ensure-pip : Install Python 3 pip] 2025-09-30 11:19:03.580605 | controller | changed 2025-09-30 11:19:03.591524 | 2025-09-30 11:19:03.591657 | TASK [ensure-pip : Check for EPEL repository] 2025-09-30 11:19:03.648829 | controller | skipping: Conditional result was False 2025-09-30 11:19:03.655377 | 2025-09-30 11:19:03.655503 | TASK [ensure-pip : Fail with instructions when EPEL is not installed] 2025-09-30 11:19:03.702859 | controller | skipping: Conditional result was False 2025-09-30 11:19:03.709707 | 2025-09-30 11:19:03.709783 | TASK [ensure-pip : Install Python 2 pip] 2025-09-30 11:19:03.753844 | controller | skipping: Conditional result was False 2025-09-30 11:19:03.760246 | 2025-09-30 11:19:03.760325 | TASK [ensure-pip : Ensure setuptools] 2025-09-30 11:19:03.774755 | controller | skipping: Conditional result was False 2025-09-30 11:19:03.781648 | 2025-09-30 11:19:03.781757 | TASK [ensure-pip : Check for ensurepip module] 2025-09-30 11:19:04.316959 | controller | skipping: Conditional result was False 2025-09-30 11:19:04.330011 | 2025-09-30 11:19:04.330157 | TASK [ensure-pip : Ensure python3-venv] 2025-09-30 11:19:04.357067 | controller | skipping: Conditional result was False 2025-09-30 11:19:04.370230 | 2025-09-30 11:19:04.370370 | TASK [ensure-pip : Install pip from source] 2025-09-30 11:19:04.399278 | controller | skipping: Conditional result was False 2025-09-30 11:19:04.413966 | 2025-09-30 11:19:04.414113 | TASK [ensure-pip : Probe for venv python full path] 2025-09-30 07:19:04.714143 | controller | /usr/bin/python3 2025-09-30 11:19:04.959832 | controller | ok: Runtime: 0:00:00.005675 2025-09-30 11:19:04.974420 | 2025-09-30 11:19:04.974593 | TASK [ensure-pip : Set host default] 2025-09-30 11:19:05.074056 | controller | ok 2025-09-30 11:19:05.082891 | 2025-09-30 11:19:05.083017 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2025-09-30 11:19:05.135442 | controller | ok 2025-09-30 11:19:05.149112 | 2025-09-30 11:19:05.149236 | TASK [bindep : Install bindep into temporary venv] 2025-09-30 11:19:09.882224 | controller | changed 2025-09-30 11:19:09.896841 | 2025-09-30 11:19:09.896941 | TASK [bindep : Define bindep_command] 2025-09-30 11:19:09.934885 | controller | ok 2025-09-30 11:19:09.945108 | 2025-09-30 11:19:09.945206 | LOOP [bindep : Include package tasks] 2025-09-30 11:19:10.011860 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt" 2025-09-30 11:19:10.012108 | controller | ok: All items complete 2025-09-30 11:19:10.012140 | 2025-09-30 11:19:10.033809 | controller | included: /var/lib/zuul/builds/8c0f46b08ccb4c709060d528a9177e26/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml 2025-09-30 11:19:10.047666 | 2025-09-30 11:19:10.047771 | TASK [bindep : Define bindep_run fact] 2025-09-30 11:19:10.084086 | controller | ok 2025-09-30 11:19:10.091832 | 2025-09-30 11:19:10.091931 | TASK [bindep : Get list of packages to install from bindep] 2025-09-30 07:19:11.379211 | controller | podman 2025-09-30 07:19:11.417382 | controller | python3-jmespath 2025-09-30 07:19:11.417541 | controller | python3-libvirt 2025-09-30 07:19:11.417552 | controller | python3-lxml 2025-09-30 07:19:11.417560 | controller | python3-netaddr 2025-09-30 11:19:11.630066 | controller | ok: Runtime: 0:00:01.075393 2025-09-30 11:19:11.637096 | 2025-09-30 11:19:11.637170 | TASK [bindep : Install distro packages from bindep] 2025-09-30 11:20:22.544649 | controller | changed 2025-09-30 11:20:22.551509 | 2025-09-30 11:20:22.551573 | TASK [bindep : Check that packages are installed] 2025-09-30 11:20:24.584654 | controller | ok: Runtime: 0:00:01.388624 2025-09-30 11:20:24.594601 | 2025-09-30 11:20:24.594747 | TASK [bindep : Fail if we cannot install all packages] 2025-09-30 11:20:24.625369 | controller | skipping: Conditional result was False 2025-09-30 11:20:24.636265 | 2025-09-30 11:20:24.636343 | TASK [Run test-setup role] 2025-09-30 11:20:24.655518 | controller | ok 2025-09-30 11:20:24.675626 | 2025-09-30 11:20:24.675752 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-09-30 11:20:24.902520 | controller | ok 2025-09-30 11:20:24.908180 | 2025-09-30 11:20:24.908244 | TASK [test-setup : Run tools/test-setup.sh] 2025-09-30 11:20:25.438673 | controller | skipping: Conditional result was False 2025-09-30 11:20:25.459957 | 2025-09-30 11:20:25.460032 | TASK [bindep : Remove bindep temp dir] 2025-09-30 11:20:25.853997 | controller | ok 2025-09-30 11:20:25.879435 | 2025-09-30 11:20:25.879567 | PLAY RECAP 2025-09-30 11:20:25.879638 | controller | ok: 22 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0 2025-09-30 11:20:25.879669 | 2025-09-30 11:20:25.984454 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-09-30 11:20:25.985695 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2025-09-30 11:20:26.579116 | 2025-09-30 11:20:26.579230 | PLAY [all] 2025-09-30 11:20:26.599305 | 2025-09-30 11:20:26.599395 | TASK [Abort when test_command variable is undefined] 2025-09-30 11:20:26.623915 | controller | skipping: Conditional result was False 2025-09-30 11:20:26.629294 | 2025-09-30 11:20:26.629368 | TASK [Convert test_command to list] 2025-09-30 11:20:26.663263 | controller | skipping: Conditional result was False 2025-09-30 11:20:26.670130 | 2025-09-30 11:20:26.670199 | TASK [Use test_command list] 2025-09-30 11:20:26.726130 | controller | ok 2025-09-30 11:20:26.738624 | 2025-09-30 11:20:26.738836 | LOOP [Run test_command] 2025-09-30 11:20:27.165738 | controller | no check to run 2025-09-30 11:20:27.166206 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.006050 2025-09-30 11:20:27.204398 | 2025-09-30 11:20:27.204510 | PLAY RECAP 2025-09-30 11:20:27.204564 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-09-30 11:20:27.204592 | 2025-09-30 11:20:27.314531 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2025-09-30 11:20:27.316553 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-30 11:20:27.925493 | 2025-09-30 11:20:27.925620 | PLAY [all] 2025-09-30 11:20:27.947041 | 2025-09-30 11:20:27.947169 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-09-30 11:20:28.312454 | controller | changed: non-zero return code 2025-09-30 11:20:28.318073 | 2025-09-30 11:20:28.318148 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-09-30 11:20:28.342791 | controller | skipping: Conditional result was False 2025-09-30 11:20:28.348616 | 2025-09-30 11:20:28.348704 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-09-30 11:20:28.379850 | 2025-09-30 11:20:28.380388 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-09-30 11:20:28.410177 | 2025-09-30 11:20:28.410316 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-09-30 11:20:28.424778 | controller | skipping: Conditional result was False 2025-09-30 11:20:28.431582 | 2025-09-30 11:20:28.431702 | LOOP [fetch-subunit-output : Generate subunit file] 2025-09-30 11:20:28.453345 | 2025-09-30 11:20:28.453474 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-09-30 11:20:28.477592 | controller | skipping: Conditional result was False 2025-09-30 11:20:28.485309 | 2025-09-30 11:20:28.485547 | TASK [fetch-subunit-output : Remove the temporary file] 2025-09-30 11:20:28.509520 | controller | skipping: Conditional result was False 2025-09-30 11:20:28.521667 | 2025-09-30 11:20:28.521780 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-09-30 11:20:28.546747 | controller | skipping: Conditional result was False 2025-09-30 11:20:28.588434 | 2025-09-30 11:20:28.588529 | PLAY RECAP 2025-09-30 11:20:28.588593 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-09-30 11:20:28.588629 | 2025-09-30 11:20:28.717312 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-30 11:20:28.718483 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-09-30 11:20:29.335327 | 2025-09-30 11:20:29.335450 | PLAY [all] 2025-09-30 11:20:29.355923 | 2025-09-30 11:20:29.356050 | TASK [include_role : fetch-output] 2025-09-30 11:20:29.385553 | controller | ok 2025-09-30 11:20:29.404505 | 2025-09-30 11:20:29.404641 | TASK [fetch-output : Set log path for multiple nodes] 2025-09-30 11:20:29.461296 | controller | skipping: Conditional result was False 2025-09-30 11:20:29.468073 | 2025-09-30 11:20:29.468184 | TASK [fetch-output : Set log path for single node] 2025-09-30 11:20:29.508714 | controller | ok 2025-09-30 11:20:29.514130 | 2025-09-30 11:20:29.514210 | LOOP [fetch-output : Ensure local output dirs] 2025-09-30 11:20:29.931212 | controller -> localhost | ok: "/var/lib/zuul/builds/8c0f46b08ccb4c709060d528a9177e26/work/logs" 2025-09-30 11:20:30.193977 | controller -> localhost | changed: "/var/lib/zuul/builds/8c0f46b08ccb4c709060d528a9177e26/work/artifacts" 2025-09-30 11:20:30.433418 | controller -> localhost | changed: "/var/lib/zuul/builds/8c0f46b08ccb4c709060d528a9177e26/work/docs" 2025-09-30 11:20:30.447585 | 2025-09-30 11:20:30.447698 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-09-30 11:20:31.117886 | controller | changed: .d..t...... ./ 2025-09-30 11:20:31.118250 | controller | changed: All items complete 2025-09-30 11:20:31.118323 | 2025-09-30 11:20:31.609841 | controller | changed: .d..t...... ./ 2025-09-30 11:20:32.135491 | controller | changed: .d..t...... ./ 2025-09-30 11:20:32.158810 | 2025-09-30 11:20:32.158926 | TASK [include_role : fetch-output-openshift] 2025-09-30 11:20:32.183541 | controller | skipping: Conditional result was False 2025-09-30 11:20:32.192187 | 2025-09-30 11:20:32.192283 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-09-30 11:20:32.634792 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.011544 2025-09-30 11:20:32.877179 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.012426 2025-09-30 11:20:32.907708 | 2025-09-30 11:20:32.907820 | PLAY [all] 2025-09-30 11:20:32.924797 | 2025-09-30 11:20:32.924864 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-30 11:20:33.396671 | controller | changed 2025-09-30 11:20:33.430539 | 2025-09-30 11:20:33.430643 | PLAY RECAP 2025-09-30 11:20:33.430712 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-09-30 11:20:33.430739 | 2025-09-30 11:20:33.526413 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-09-30 11:20:33.527276 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-09-30 11:20:34.230870 | 2025-09-30 11:20:34.231035 | PLAY [localhost] 2025-09-30 11:20:34.251093 | 2025-09-30 11:20:34.251214 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-30 11:20:34.752636 | localhost | changed 2025-09-30 11:20:34.759548 | 2025-09-30 11:20:34.759638 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-30 11:20:34.787528 | localhost | ok 2025-09-30 11:20:34.813911 | 2025-09-30 11:20:34.814065 | TASK [add-fileserver : Create SSH private key tempfile] 2025-09-30 11:20:35.196124 | localhost | changed 2025-09-30 11:20:35.207961 | 2025-09-30 11:20:35.208113 | TASK [add-fileserver : Create SSH private key from secret] 2025-09-30 11:20:35.809629 | localhost | changed 2025-09-30 11:20:35.814774 | 2025-09-30 11:20:35.814849 | TASK [add-fileserver : Add fileserver ssh key] 2025-09-30 11:20:36.194618 | localhost | Identity added: /var/lib/zuul/builds/8c0f46b08ccb4c709060d528a9177e26/work/tmp/ansible.xyofi2v5 (/var/lib/zuul/builds/8c0f46b08ccb4c709060d528a9177e26/work/tmp/ansible.xyofi2v5) 2025-09-30 11:20:36.194903 | localhost | ok: Runtime: 0:00:00.009787 2025-09-30 11:20:36.201650 | 2025-09-30 11:20:36.201789 | TASK [add-fileserver : Remove SSH private key from disk] 2025-09-30 11:20:36.463211 | localhost | ok: Runtime: 0:00:00.006221 2025-09-30 11:20:36.468297 | 2025-09-30 11:20:36.468366 | TASK [add-fileserver : Add fileserver to inventory] 2025-09-30 11:20:36.550331 | localhost | changed 2025-09-30 11:20:36.556087 | 2025-09-30 11:20:36.556167 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-09-30 11:20:36.967605 | localhost | changed 2025-09-30 11:20:36.996165 | 2025-09-30 11:20:36.996247 | PLAY [localhost] 2025-09-30 11:20:37.009182 | 2025-09-30 11:20:37.009256 | TASK [Generate bulk log download script] 2025-09-30 11:20:37.028333 | localhost | ok 2025-09-30 11:20:37.041003 | 2025-09-30 11:20:37.041111 | TASK [local-log-download : Check API endpoint is defined] 2025-09-30 11:20:37.082182 | localhost | ok: All assertions passed 2025-09-30 11:20:37.087096 | 2025-09-30 11:20:37.087158 | TASK [local-log-download : Create download script] 2025-09-30 11:20:37.536484 | localhost -> localhost | changed 2025-09-30 11:20:37.547127 | 2025-09-30 11:20:37.547234 | TASK [Register quick-download link] 2025-09-30 11:20:37.565649 | localhost | ok 2025-09-30 11:20:37.606432 | 2025-09-30 11:20:37.606534 | PLAY [logserver.rdoproject.org] 2025-09-30 11:20:37.615997 | 2025-09-30 11:20:37.616057 | TASK [Set zuul-log-path fact] 2025-09-30 11:20:37.632100 | logserver.rdoproject.org | ok 2025-09-30 11:20:37.642787 | 2025-09-30 11:20:37.642854 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-30 11:20:37.670438 | logserver.rdoproject.org | ok 2025-09-30 11:20:37.678172 | 2025-09-30 11:20:37.678255 | TASK [upload-logs : Create log directories] 2025-09-30 11:20:38.415621 | logserver.rdoproject.org | changed 2025-09-30 11:20:38.419084 | 2025-09-30 11:20:38.419145 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-30 11:20:38.697197 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.006630 2025-09-30 11:20:38.702139 | 2025-09-30 11:20:38.702203 | TASK [upload-logs : Upload logs to log server] 2025-09-30 11:20:39.410965 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-09-30 11:20:39.414419 | 2025-09-30 11:20:39.414482 | LOOP [upload-logs : Compress console log and json output] 2025-09-30 11:20:39.481122 | logserver.rdoproject.org | skipping: Conditional result was False 2025-09-30 11:20:39.494455 | logserver.rdoproject.org | skipping: Conditional result was False 2025-09-30 11:20:39.503300 | 2025-09-30 11:20:39.503421 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-30 11:20:39.547588 | logserver.rdoproject.org | skipping: Conditional result was False 2025-09-30 11:20:39.547855 | 2025-09-30 11:20:39.552552 | logserver.rdoproject.org | skipping: Conditional result was False 2025-09-30 11:20:39.566233 | 2025-09-30 11:20:39.566417 | LOOP [upload-logs : Upload console log and json output]