2025-10-13 10:18:16.080634 | Job console starting... 2025-10-13 10:18:16.096527 | Updating repositories 2025-10-13 10:18:16.130378 | Preparing job workspace 2025-10-13 10:18:19.778774 | Running Ansible setup... 2025-10-13 10:18:23.801698 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-13 10:18:24.520338 | 2025-10-13 10:18:24.520525 | PLAY [localhost] 2025-10-13 10:18:24.530155 | 2025-10-13 10:18:24.530245 | TASK [Gathering Facts] 2025-10-13 10:18:25.742300 | localhost | ok 2025-10-13 10:18:25.759266 | 2025-10-13 10:18:25.759408 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-13 10:18:26.400705 | localhost -> localhost | changed 2025-10-13 10:18:26.406673 | 2025-10-13 10:18:26.406870 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-13 10:18:27.249630 | localhost -> localhost | changed 2025-10-13 10:18:27.258796 | 2025-10-13 10:18:27.258993 | TASK [Setup log path fact] 2025-10-13 10:18:27.289387 | localhost | ok 2025-10-13 10:18:27.303848 | 2025-10-13 10:18:27.303969 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-13 10:18:27.331905 | localhost | ok 2025-10-13 10:18:27.339948 | 2025-10-13 10:18:27.340053 | TASK [emit-job-header : Print job information] 2025-10-13 10:18:27.378104 | # Job Information 2025-10-13 10:18:27.378250 | Ansible Version: 2.15.12 2025-10-13 10:18:27.378276 | Job: cifmw-molecule-cifmw_setup 2025-10-13 10:18:27.378295 | Pipeline: github-check 2025-10-13 10:18:27.378313 | Executor: ze01.softwarefactory-project.io 2025-10-13 10:18:27.378330 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3402 2025-10-13 10:18:27.378349 | Log URL (when completed): https://logserver.rdoproject.org/6a4/rdoproject.org/6a44f134a0084eb3bc27d1538a56c230/ 2025-10-13 10:18:27.378367 | Event ID: 9221ec20-a81d-11f0-9e49-0c0985a8c4b7 2025-10-13 10:18:27.381903 | 2025-10-13 10:18:27.381966 | LOOP [emit-job-header : Print node information] 2025-10-13 10:18:27.503585 | localhost | ok: 2025-10-13 10:18:27.503803 | localhost | # Node Information 2025-10-13 10:18:27.503831 | localhost | Inventory Hostname: controller 2025-10-13 10:18:27.503853 | localhost | Hostname: np0005483417 2025-10-13 10:18:27.503872 | localhost | Username: zuul 2025-10-13 10:18:27.503891 | localhost | Distro: CentOS 9 2025-10-13 10:18:27.503908 | localhost | Provider: vexxhost-nodepool-tripleo 2025-10-13 10:18:27.503925 | localhost | Region: RegionOne 2025-10-13 10:18:27.503941 | localhost | Label: cloud-centos-9-stream-tripleo 2025-10-13 10:18:27.503957 | localhost | Product Name: OpenStack Nova 2025-10-13 10:18:27.503973 | localhost | Interface IP: 38.102.83.132 2025-10-13 10:18:27.528554 | 2025-10-13 10:18:27.528666 | PLAY [all] 2025-10-13 10:18:27.535278 | 2025-10-13 10:18:27.535341 | TASK [Gather network facts] 2025-10-13 10:18:27.944973 | controller | ok 2025-10-13 10:18:27.965163 | 2025-10-13 10:18:27.965280 | TASK [include_role : start-zuul-console] 2025-10-13 10:18:27.987370 | controller | ok 2025-10-13 10:18:27.999682 | 2025-10-13 10:18:27.999790 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-13 10:18:28.424205 | controller | ok 2025-10-13 10:18:28.433336 | 2025-10-13 10:18:28.433419 | TASK [include_role : add-build-sshkey] 2025-10-13 10:18:28.451753 | controller | ok 2025-10-13 10:18:28.465498 | 2025-10-13 10:18:28.465594 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-13 10:18:28.685330 | controller -> localhost | ok 2025-10-13 10:18:28.695721 | 2025-10-13 10:18:28.695821 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-13 10:18:28.719225 | controller | ok 2025-10-13 10:18:28.738351 | controller | included: /var/lib/zuul/builds/6a44f134a0084eb3bc27d1538a56c230/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-13 10:18:28.744879 | 2025-10-13 10:18:28.744968 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-13 10:18:29.461083 | controller -> localhost | Generating public/private rsa key pair. 2025-10-13 10:18:29.461297 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/6a44f134a0084eb3bc27d1538a56c230/work/6a44f134a0084eb3bc27d1538a56c230_id_rsa. 2025-10-13 10:18:29.461328 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/6a44f134a0084eb3bc27d1538a56c230/work/6a44f134a0084eb3bc27d1538a56c230_id_rsa.pub. 2025-10-13 10:18:29.461349 | controller -> localhost | The key fingerprint is: 2025-10-13 10:18:29.461368 | controller -> localhost | SHA256:bWlZyw0pscC6Ih54ARkjwCjnei9EHJIl2Eu3mWt0zUw zuul-build-sshkey 2025-10-13 10:18:29.461387 | controller -> localhost | The key's randomart image is: 2025-10-13 10:18:29.461405 | controller -> localhost | +---[RSA 3072]----+ 2025-10-13 10:18:29.461422 | controller -> localhost | |@*. .. . | 2025-10-13 10:18:29.461441 | controller -> localhost | |X+= . E. o . | 2025-10-13 10:18:29.461458 | controller -> localhost | |.B + + * o + | 2025-10-13 10:18:29.461475 | controller -> localhost | | * = o +. * + | 2025-10-13 10:18:29.461492 | controller -> localhost | | + o o .S * o . | 2025-10-13 10:18:29.461509 | controller -> localhost | |o * + . o | 2025-10-13 10:18:29.461526 | controller -> localhost | | = = . | 2025-10-13 10:18:29.461560 | controller -> localhost | | o . | 2025-10-13 10:18:29.461578 | controller -> localhost | | . | 2025-10-13 10:18:29.461595 | controller -> localhost | +----[SHA256]-----+ 2025-10-13 10:18:29.461641 | controller -> localhost | ok: Runtime: 0:00:00.248086 2025-10-13 10:18:29.467610 | 2025-10-13 10:18:29.467676 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-13 10:18:29.507282 | controller | ok 2025-10-13 10:18:29.523950 | controller | included: /var/lib/zuul/builds/6a44f134a0084eb3bc27d1538a56c230/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-13 10:18:29.542414 | 2025-10-13 10:18:29.542520 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-13 10:18:29.600379 | controller | skipping: Conditional result was False 2025-10-13 10:18:29.606161 | 2025-10-13 10:18:29.606239 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-13 10:18:30.206762 | controller | changed 2025-10-13 10:18:30.212459 | 2025-10-13 10:18:30.212560 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-13 10:18:30.494827 | controller | ok 2025-10-13 10:18:30.501966 | 2025-10-13 10:18:30.502106 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-13 10:18:31.459898 | controller | changed 2025-10-13 10:18:31.466385 | 2025-10-13 10:18:31.466477 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-13 10:18:32.394997 | controller | changed 2025-10-13 10:18:32.408498 | 2025-10-13 10:18:32.408691 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-13 10:18:32.437153 | controller | skipping: Conditional result was False 2025-10-13 10:18:32.447311 | 2025-10-13 10:18:32.447453 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-13 10:18:32.903291 | controller -> localhost | changed 2025-10-13 10:18:32.914102 | 2025-10-13 10:18:32.914232 | TASK [add-build-sshkey : Add back temp key] 2025-10-13 10:18:33.184000 | controller -> localhost | Identity added: /var/lib/zuul/builds/6a44f134a0084eb3bc27d1538a56c230/work/6a44f134a0084eb3bc27d1538a56c230_id_rsa (zuul-build-sshkey) 2025-10-13 10:18:33.184207 | controller -> localhost | ok: Runtime: 0:00:00.018841 2025-10-13 10:18:33.191258 | 2025-10-13 10:18:33.191353 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-13 10:18:33.579664 | controller | ok 2025-10-13 10:18:33.586552 | 2025-10-13 10:18:33.586677 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-13 10:18:33.612270 | controller | skipping: Conditional result was False 2025-10-13 10:18:33.623520 | 2025-10-13 10:18:33.623634 | TASK [include_role : validate-host] 2025-10-13 10:18:33.643561 | controller | ok 2025-10-13 10:18:33.666892 | 2025-10-13 10:18:33.667040 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-13 10:18:33.697489 | controller | ok 2025-10-13 10:18:33.703330 | 2025-10-13 10:18:33.703435 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-13 10:18:33.943801 | controller -> localhost | ok 2025-10-13 10:18:33.982038 | 2025-10-13 10:18:33.982149 | TASK [validate-host : Collect information about the host] 2025-10-13 10:18:34.806773 | controller | ok 2025-10-13 10:18:34.829427 | 2025-10-13 10:18:34.829662 | TASK [validate-host : Sanitize hostname] 2025-10-13 10:18:34.898798 | controller | ok 2025-10-13 10:18:34.909537 | 2025-10-13 10:18:34.909692 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-13 10:18:35.472090 | controller -> localhost | changed 2025-10-13 10:18:35.477818 | 2025-10-13 10:18:35.478002 | TASK [validate-host : Collect information about zuul worker] 2025-10-13 10:18:36.020669 | controller | ok 2025-10-13 10:18:36.028433 | 2025-10-13 10:18:36.028542 | TASK [validate-host : Write out all zuul information for each host] 2025-10-13 10:18:36.506788 | controller -> localhost | changed 2025-10-13 10:18:36.522902 | 2025-10-13 10:18:36.523090 | TASK [include_role : prepare-workspace-openshift] 2025-10-13 10:18:36.569879 | controller | skipping: Conditional result was False 2025-10-13 10:18:36.575566 | 2025-10-13 10:18:36.575648 | TASK [include_role : remove-zuul-sshkey] 2025-10-13 10:18:36.600051 | controller | skipping: Conditional result was False 2025-10-13 10:18:36.606560 | 2025-10-13 10:18:36.606652 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-13 10:18:36.896263 | controller | ok: "logs" 2025-10-13 10:18:36.896517 | controller | ok: All items complete 2025-10-13 10:18:36.896552 | 2025-10-13 10:18:37.172633 | controller | ok: "artifacts" 2025-10-13 10:18:37.404948 | controller | ok: "docs" 2025-10-13 10:18:37.416882 | 2025-10-13 10:18:37.416981 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-13 10:18:37.722256 | controller | changed: "logs" 2025-10-13 10:18:37.974799 | controller | changed: "artifacts" 2025-10-13 10:18:38.212369 | controller | changed: "docs" 2025-10-13 10:18:38.259716 | 2025-10-13 10:18:38.259800 | PLAY RECAP 2025-10-13 10:18:38.259844 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-13 10:18:38.259870 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-13 10:18:38.259887 | 2025-10-13 10:18:38.384310 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-13 10:18:38.385899 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2025-10-13 10:18:39.015856 | 2025-10-13 10:18:39.015973 | PLAY [localhost] 2025-10-13 10:18:39.033913 | 2025-10-13 10:18:39.034070 | TASK [configure-ssh-config-fips : Ensure ssh config directory] 2025-10-13 10:18:39.491279 | localhost | ok 2025-10-13 10:18:39.501519 | 2025-10-13 10:18:39.501696 | TASK [configure-ssh-config-fips : Setup ssh config] 2025-10-13 10:18:40.250132 | localhost | changed 2025-10-13 10:18:40.272233 | 2025-10-13 10:18:40.272338 | PLAY [all] 2025-10-13 10:18:40.290455 | 2025-10-13 10:18:40.290580 | TASK [include_role : prepare-workspace] 2025-10-13 10:18:40.310226 | controller | ok 2025-10-13 10:18:40.325681 | 2025-10-13 10:18:40.325827 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-13 10:18:40.753589 | controller | ok 2025-10-13 10:18:40.770270 | 2025-10-13 10:18:40.770415 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-13 10:18:42.933678 | controller | Output suppressed because no_log was given 2025-10-13 10:18:42.944128 | 2025-10-13 10:18:42.944195 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-13 10:18:43.201717 | controller | changed: "logs" 2025-10-13 10:18:43.414261 | controller | changed: "artifacts" 2025-10-13 10:18:43.638614 | controller | changed: "docs" 2025-10-13 10:18:43.667767 | 2025-10-13 10:18:43.667917 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-13 10:18:43.972493 | controller | changed: "logs" 2025-10-13 10:18:43.972724 | controller | changed: All items complete 2025-10-13 10:18:43.972757 | 2025-10-13 10:18:44.191321 | controller | changed: "artifacts" 2025-10-13 10:18:44.405319 | controller | changed: "docs" 2025-10-13 10:18:44.427588 | 2025-10-13 10:18:44.427979 | TASK [Check if worker can sudo] 2025-10-13 10:18:44.963307 | controller | ok: Runtime: 0:00:00.044042 2025-10-13 10:18:44.974874 | 2025-10-13 10:18:44.975054 | TASK [configure-mirrors : Gather needed facts] 2025-10-13 10:18:45.033788 | controller | skipping: Conditional result was False 2025-10-13 10:18:45.039744 | 2025-10-13 10:18:45.039841 | TASK [configure-mirrors : Set up infrastructure mirrors] 2025-10-13 10:18:45.122508 | controller | ok 2025-10-13 10:18:45.133998 | controller | included: /var/lib/zuul/builds/6a44f134a0084eb3bc27d1538a56c230/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml 2025-10-13 10:18:45.173783 | 2025-10-13 10:18:45.173903 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir] 2025-10-13 10:18:45.445821 | controller | ok 2025-10-13 10:18:45.459158 | 2025-10-13 10:18:45.459340 | LOOP [configure-mirrors : Include OS-specific variables] 2025-10-13 10:18:45.548992 | controller | ok: "/var/lib/zuul/builds/6a44f134a0084eb3bc27d1538a56c230/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml" 2025-10-13 10:18:45.566962 | 2025-10-13 10:18:45.567092 | TASK [configure-mirrors : Install /etc/pip.conf configuration] 2025-10-13 10:18:46.567055 | controller | changed 2025-10-13 10:18:46.583210 | 2025-10-13 10:18:46.583366 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors] 2025-10-13 10:18:46.675402 | controller | ok: "/var/lib/zuul/builds/6a44f134a0084eb3bc27d1538a56c230/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml" 2025-10-13 10:18:46.675566 | controller | ok: All items complete 2025-10-13 10:18:46.675591 | 2025-10-13 10:18:46.728127 | controller | included: /var/lib/zuul/builds/6a44f134a0084eb3bc27d1538a56c230/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml 2025-10-13 10:18:46.734814 | 2025-10-13 10:18:46.734944 | LOOP [configure-mirrors : Install CentOS 9 repository files] 2025-10-13 10:18:47.686105 | controller | changed: "etc/yum.repos.d/centos.repo" 2025-10-13 10:18:48.624283 | controller | changed: "etc/yum.repos.d/centos-addons.repo" 2025-10-13 10:18:48.636803 | 2025-10-13 10:18:48.636935 | TASK [configure-mirrors : Disable deltrarpm] 2025-10-13 10:18:49.087101 | controller | changed: section and option added 2025-10-13 10:18:49.136723 | 2025-10-13 10:18:49.136846 | LOOP [configure-mirrors : Update yum/dnf cache] 2025-10-13 10:18:51.228550 | controller | 29 files removed 2025-10-13 10:18:51.228785 | controller | ok: Item: dnf clean all Runtime: 0:00:01.733647 2025-10-13 10:18:51.228824 | controller | changed: All items complete 2025-10-13 10:18:51.228844 | 2025-10-13 10:19:01.712165 | 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-13 10:19:01.712272 | controller | DNF version: 4.14.0 2025-10-13 10:19:01.712296 | controller | cachedir: /var/cache/dnf 2025-10-13 10:19:01.712316 | controller | Making cache files for all metadata files. 2025-10-13 10:19:01.712334 | controller | baseos: has expired and will be refreshed. 2025-10-13 10:19:01.712352 | controller | appstream: has expired and will be refreshed. 2025-10-13 10:19:01.712368 | controller | crb: has expired and will be refreshed. 2025-10-13 10:19:01.712394 | controller | extras-common: has expired and will be refreshed. 2025-10-13 10:19:01.712411 | controller | repo: downloading from remote: baseos 2025-10-13 10:19:01.712428 | controller | CentOS Stream 9 - BaseOS 72 MB/s | 8.8 MB 00:00 2025-10-13 10:19:01.712443 | controller | baseos: using metadata from Wed 08 Oct 2025 09:42:12 AM EDT. 2025-10-13 10:19:01.712459 | controller | repo: downloading from remote: appstream 2025-10-13 10:19:01.712475 | controller | CentOS Stream 9 - AppStream 115 MB/s | 25 MB 00:00 2025-10-13 10:19:01.712490 | controller | appstream: using metadata from Wed 08 Oct 2025 09:45:45 AM EDT. 2025-10-13 10:19:01.712506 | controller | repo: downloading from remote: crb 2025-10-13 10:19:01.712521 | controller | CentOS Stream 9 - CRB 83 MB/s | 7.2 MB 00:00 2025-10-13 10:19:01.712537 | controller | crb: using metadata from Wed 08 Oct 2025 09:50:07 AM EDT. 2025-10-13 10:19:01.712553 | controller | repo: downloading from remote: extras-common 2025-10-13 10:19:01.712569 | controller | CentOS Stream 9 - Extras packages 1.5 MB/s | 20 kB 00:00 2025-10-13 10:19:01.712585 | controller | extras-common: using metadata from Mon 15 Sep 2025 02:32:43 PM EDT. 2025-10-13 10:19:01.712600 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)' 2025-10-13 10:19:01.712615 | controller | Completion plugin: Generating completion cache... 2025-10-13 10:19:01.712631 | controller | Metadata cache created. 2025-10-13 10:19:01.712656 | controller | ok: Item: dnf makecache -v Runtime: 0:00:10.181226 2025-10-13 10:19:01.746699 | 2025-10-13 10:19:01.746855 | PLAY RECAP 2025-10-13 10:19:01.746938 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-13 10:19:01.746994 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-13 10:19:01.747070 | 2025-10-13 10:19:01.877912 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2025-10-13 10:19:01.879056 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-13 10:19:02.507438 | 2025-10-13 10:19:02.507578 | PLAY [all] 2025-10-13 10:19:02.531398 | 2025-10-13 10:19:02.531545 | TASK [Install binary dependencies] 2025-10-13 10:19:02.581542 | controller | ok 2025-10-13 10:19:02.600418 | 2025-10-13 10:19:02.600564 | TASK [bindep : Include find tasks] 2025-10-13 10:19:02.632516 | controller | ok 2025-10-13 10:19:02.643935 | controller | included: /var/lib/zuul/builds/6a44f134a0084eb3bc27d1538a56c230/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-10-13 10:19:02.653157 | 2025-10-13 10:19:02.653236 | TASK [bindep : Look for bindep.txt] 2025-10-13 10:19:03.176575 | controller | ok 2025-10-13 10:19:03.191235 | 2025-10-13 10:19:03.191420 | TASK [bindep : Define bindep_file fact] 2025-10-13 10:19:03.225654 | controller | ok 2025-10-13 10:19:03.232706 | 2025-10-13 10:19:03.232853 | TASK [bindep : Look for other-requirements.txt] 2025-10-13 10:19:03.258457 | controller | skipping: Conditional result was False 2025-10-13 10:19:03.266667 | 2025-10-13 10:19:03.266820 | TASK [bindep : Define bindep_file fact] 2025-10-13 10:19:03.292527 | controller | skipping: Conditional result was False 2025-10-13 10:19:03.300694 | 2025-10-13 10:19:03.300831 | TASK [bindep : Look for bindep fallback file] 2025-10-13 10:19:03.336848 | controller | skipping: Conditional result was False 2025-10-13 10:19:03.346850 | 2025-10-13 10:19:03.347061 | TASK [bindep : Define bindep_file fact] 2025-10-13 10:19:03.373613 | controller | skipping: Conditional result was False 2025-10-13 10:19:03.383377 | 2025-10-13 10:19:03.383522 | TASK [bindep : Include bindep tasks] 2025-10-13 10:19:03.428588 | controller | ok 2025-10-13 10:19:03.438265 | controller | included: /var/lib/zuul/builds/6a44f134a0084eb3bc27d1538a56c230/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml 2025-10-13 10:19:03.450685 | 2025-10-13 10:19:03.450827 | TASK [bindep : Look for bindep command] 2025-10-13 10:19:03.476346 | controller | skipping: Conditional result was False 2025-10-13 10:19:03.487339 | 2025-10-13 10:19:03.487490 | TASK [bindep : Check for system bindep] 2025-10-13 10:19:04.030551 | controller | ok: Runtime: 0:00:00.008104 2025-10-13 10:19:04.043253 | 2025-10-13 10:19:04.043469 | TASK [bindep : Define bindep_command fact] 2025-10-13 10:19:04.070697 | controller | skipping: Conditional result was False 2025-10-13 10:19:04.085138 | 2025-10-13 10:19:04.085331 | TASK [bindep : Include install tasks] 2025-10-13 10:19:04.138481 | controller | ok 2025-10-13 10:19:04.156704 | controller | included: /var/lib/zuul/builds/6a44f134a0084eb3bc27d1538a56c230/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml 2025-10-13 10:19:04.171510 | 2025-10-13 10:19:04.171653 | TASK [bindep : Create temp dir for bindep] 2025-10-13 10:19:04.559951 | controller | changed 2025-10-13 10:19:04.569868 | 2025-10-13 10:19:04.569999 | TASK [Ensure we have pip dependencies] 2025-10-13 10:19:04.590003 | controller | ok 2025-10-13 10:19:04.616583 | 2025-10-13 10:19:04.616701 | TASK [ensure-pip : Check if pip is installed] 2025-10-13 06:19:04.889855 | controller | /usr/bin/pip3 2025-10-13 06:19:04.908576 | controller | /usr/bin/python3: No module named wheel 2025-10-13 10:19:05.149348 | controller | ok: Runtime: 0:00:00.027374 2025-10-13 10:19:05.158866 | 2025-10-13 10:19:05.159007 | LOOP [ensure-pip : Install pip from packages] 2025-10-13 10:19:05.197947 | controller | ok: "/var/lib/zuul/builds/6a44f134a0084eb3bc27d1538a56c230/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml" 2025-10-13 10:19:05.226359 | controller | included: /var/lib/zuul/builds/6a44f134a0084eb3bc27d1538a56c230/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml 2025-10-13 10:19:05.245542 | 2025-10-13 10:19:05.245680 | TASK [ensure-pip : Install Python 3 pip] 2025-10-13 10:19:07.879185 | controller | changed 2025-10-13 10:19:07.894459 | 2025-10-13 10:19:07.894723 | TASK [ensure-pip : Check for EPEL repository] 2025-10-13 10:19:07.972585 | controller | skipping: Conditional result was False 2025-10-13 10:19:07.992445 | 2025-10-13 10:19:07.992582 | TASK [ensure-pip : Fail with instructions when EPEL is not installed] 2025-10-13 10:19:08.059832 | controller | skipping: Conditional result was False 2025-10-13 10:19:08.075229 | 2025-10-13 10:19:08.075438 | TASK [ensure-pip : Install Python 2 pip] 2025-10-13 10:19:08.134232 | controller | skipping: Conditional result was False 2025-10-13 10:19:08.149716 | 2025-10-13 10:19:08.150145 | TASK [ensure-pip : Ensure setuptools] 2025-10-13 10:19:08.177430 | controller | skipping: Conditional result was False 2025-10-13 10:19:08.191836 | 2025-10-13 10:19:08.191983 | TASK [ensure-pip : Check for ensurepip module] 2025-10-13 10:19:08.736369 | controller | skipping: Conditional result was False 2025-10-13 10:19:08.750870 | 2025-10-13 10:19:08.751105 | TASK [ensure-pip : Ensure python3-venv] 2025-10-13 10:19:08.786740 | controller | skipping: Conditional result was False 2025-10-13 10:19:08.799920 | 2025-10-13 10:19:08.800126 | TASK [ensure-pip : Install pip from source] 2025-10-13 10:19:08.828963 | controller | skipping: Conditional result was False 2025-10-13 10:19:08.840684 | 2025-10-13 10:19:08.840837 | TASK [ensure-pip : Probe for venv python full path] 2025-10-13 06:19:09.148982 | controller | /usr/bin/python3 2025-10-13 10:19:09.384424 | controller | ok: Runtime: 0:00:00.007680 2025-10-13 10:19:09.396761 | 2025-10-13 10:19:09.396902 | TASK [ensure-pip : Set host default] 2025-10-13 10:19:09.480058 | controller | ok 2025-10-13 10:19:09.491742 | 2025-10-13 10:19:09.492047 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2025-10-13 10:19:09.566071 | controller | ok 2025-10-13 10:19:09.590995 | 2025-10-13 10:19:09.591177 | TASK [bindep : Install bindep into temporary venv] 2025-10-13 10:19:13.817287 | controller | changed 2025-10-13 10:19:13.825985 | 2025-10-13 10:19:13.826593 | TASK [bindep : Define bindep_command] 2025-10-13 10:19:13.867503 | controller | ok 2025-10-13 10:19:13.873304 | 2025-10-13 10:19:13.873409 | LOOP [bindep : Include package tasks] 2025-10-13 10:19:13.931251 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt" 2025-10-13 10:19:13.931497 | controller | ok: All items complete 2025-10-13 10:19:13.931525 | 2025-10-13 10:19:13.959923 | controller | included: /var/lib/zuul/builds/6a44f134a0084eb3bc27d1538a56c230/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml 2025-10-13 10:19:13.985434 | 2025-10-13 10:19:13.985603 | TASK [bindep : Define bindep_run fact] 2025-10-13 10:19:14.019692 | controller | ok 2025-10-13 10:19:14.028094 | 2025-10-13 10:19:14.028231 | TASK [bindep : Get list of packages to install from bindep] 2025-10-13 06:19:15.538853 | controller | podman 2025-10-13 06:19:15.589959 | controller | python3-jmespath 2025-10-13 06:19:15.590146 | controller | python3-libvirt 2025-10-13 06:19:15.590165 | controller | python3-lxml 2025-10-13 06:19:15.590184 | controller | python3-netaddr 2025-10-13 10:19:16.074730 | controller | ok: Runtime: 0:00:01.265912 2025-10-13 10:19:16.087424 | 2025-10-13 10:19:16.087618 | TASK [bindep : Install distro packages from bindep] 2025-10-13 10:20:18.556324 | controller | changed 2025-10-13 10:20:18.563781 | 2025-10-13 10:20:18.564359 | TASK [bindep : Check that packages are installed] 2025-10-13 10:20:20.104485 | controller | ok: Runtime: 0:00:01.084132 2025-10-13 10:20:20.114167 | 2025-10-13 10:20:20.114315 | TASK [bindep : Fail if we cannot install all packages] 2025-10-13 10:20:20.140749 | controller | skipping: Conditional result was False 2025-10-13 10:20:20.163339 | 2025-10-13 10:20:20.163483 | TASK [Run test-setup role] 2025-10-13 10:20:20.189668 | controller | ok 2025-10-13 10:20:20.215228 | 2025-10-13 10:20:20.215361 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-10-13 10:20:20.452293 | controller | ok 2025-10-13 10:20:20.457343 | 2025-10-13 10:20:20.457412 | TASK [test-setup : Run tools/test-setup.sh] 2025-10-13 10:20:20.987688 | controller | skipping: Conditional result was False 2025-10-13 10:20:21.035514 | 2025-10-13 10:20:21.035674 | TASK [bindep : Remove bindep temp dir] 2025-10-13 10:20:21.425002 | controller | ok 2025-10-13 10:20:21.436205 | 2025-10-13 10:20:21.436306 | PLAY RECAP 2025-10-13 10:20:21.436348 | controller | ok: 22 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0 2025-10-13 10:20:21.436369 | 2025-10-13 10:20:21.551005 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-13 10:20:21.551958 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2025-10-13 10:20:22.182546 | 2025-10-13 10:20:22.182738 | PLAY [all] 2025-10-13 10:20:22.204738 | 2025-10-13 10:20:22.204897 | TASK [Abort when test_command variable is undefined] 2025-10-13 10:20:22.240545 | controller | skipping: Conditional result was False 2025-10-13 10:20:22.247375 | 2025-10-13 10:20:22.247504 | TASK [Convert test_command to list] 2025-10-13 10:20:22.292418 | controller | skipping: Conditional result was False 2025-10-13 10:20:22.299133 | 2025-10-13 10:20:22.299254 | TASK [Use test_command list] 2025-10-13 10:20:22.352591 | controller | ok 2025-10-13 10:20:22.363675 | 2025-10-13 10:20:22.363831 | LOOP [Run test_command] 2025-10-13 10:20:22.768765 | controller | no check to run 2025-10-13 10:20:22.768963 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.005676 2025-10-13 10:20:22.819334 | 2025-10-13 10:20:22.819494 | PLAY RECAP 2025-10-13 10:20:22.819580 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-13 10:20:22.819626 | 2025-10-13 10:20:22.924382 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2025-10-13 10:20:22.925727 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-13 10:20:23.533847 | 2025-10-13 10:20:23.533999 | PLAY [all] 2025-10-13 10:20:23.555252 | 2025-10-13 10:20:23.555371 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-13 10:20:23.943753 | controller | changed: non-zero return code 2025-10-13 10:20:23.949604 | 2025-10-13 10:20:23.949705 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-13 10:20:23.964341 | controller | skipping: Conditional result was False 2025-10-13 10:20:23.970358 | 2025-10-13 10:20:23.970452 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-13 10:20:23.990989 | 2025-10-13 10:20:23.991163 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-13 10:20:24.021882 | 2025-10-13 10:20:24.022063 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-13 10:20:24.036319 | controller | skipping: Conditional result was False 2025-10-13 10:20:24.043506 | 2025-10-13 10:20:24.043653 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-13 10:20:24.083885 | 2025-10-13 10:20:24.084121 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-13 10:20:24.100809 | controller | skipping: Conditional result was False 2025-10-13 10:20:24.107612 | 2025-10-13 10:20:24.107744 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-13 10:20:24.122164 | controller | skipping: Conditional result was False 2025-10-13 10:20:24.129186 | 2025-10-13 10:20:24.129281 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-13 10:20:24.153785 | controller | skipping: Conditional result was False 2025-10-13 10:20:24.181617 | 2025-10-13 10:20:24.181737 | PLAY RECAP 2025-10-13 10:20:24.181792 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-13 10:20:24.181817 | 2025-10-13 10:20:24.303011 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-13 10:20:24.303876 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-13 10:20:24.947512 | 2025-10-13 10:20:24.947628 | PLAY [all] 2025-10-13 10:20:24.967266 | 2025-10-13 10:20:24.967354 | TASK [include_role : fetch-output] 2025-10-13 10:20:24.996805 | controller | ok 2025-10-13 10:20:25.015995 | 2025-10-13 10:20:25.016104 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-13 10:20:25.060981 | controller | skipping: Conditional result was False 2025-10-13 10:20:25.066905 | 2025-10-13 10:20:25.066972 | TASK [fetch-output : Set log path for single node] 2025-10-13 10:20:25.106056 | controller | ok 2025-10-13 10:20:25.111079 | 2025-10-13 10:20:25.111149 | LOOP [fetch-output : Ensure local output dirs] 2025-10-13 10:20:25.569725 | controller -> localhost | ok: "/var/lib/zuul/builds/6a44f134a0084eb3bc27d1538a56c230/work/logs" 2025-10-13 10:20:25.792842 | controller -> localhost | changed: "/var/lib/zuul/builds/6a44f134a0084eb3bc27d1538a56c230/work/artifacts" 2025-10-13 10:20:26.034252 | controller -> localhost | changed: "/var/lib/zuul/builds/6a44f134a0084eb3bc27d1538a56c230/work/docs" 2025-10-13 10:20:26.054951 | 2025-10-13 10:20:26.055108 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-13 10:20:26.736139 | controller | changed: .d..t...... ./ 2025-10-13 10:20:26.736353 | controller | changed: All items complete 2025-10-13 10:20:26.736395 | 2025-10-13 10:20:27.306867 | controller | changed: .d..t...... ./ 2025-10-13 10:20:27.791496 | controller | changed: .d..t...... ./ 2025-10-13 10:20:27.814413 | 2025-10-13 10:20:27.814540 | TASK [include_role : fetch-output-openshift] 2025-10-13 10:20:27.828876 | controller | skipping: Conditional result was False 2025-10-13 10:20:27.835836 | 2025-10-13 10:20:27.835919 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-13 10:20:28.312365 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.012601 2025-10-13 10:20:28.565306 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008046 2025-10-13 10:20:28.605088 | 2025-10-13 10:20:28.605215 | PLAY [all] 2025-10-13 10:20:28.620671 | 2025-10-13 10:20:28.620777 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-13 10:20:29.062260 | controller | changed 2025-10-13 10:20:29.086838 | 2025-10-13 10:20:29.086924 | PLAY RECAP 2025-10-13 10:20:29.086963 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-13 10:20:29.086984 | 2025-10-13 10:20:29.211904 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-13 10:20:29.212763 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-13 10:20:29.922758 | 2025-10-13 10:20:29.922883 | PLAY [localhost] 2025-10-13 10:20:29.942372 | 2025-10-13 10:20:29.942490 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-13 10:20:30.295835 | localhost | changed 2025-10-13 10:20:30.300547 | 2025-10-13 10:20:30.300625 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-13 10:20:30.319764 | localhost | ok 2025-10-13 10:20:30.328697 | 2025-10-13 10:20:30.328784 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-13 10:20:30.670837 | localhost | changed 2025-10-13 10:20:30.675970 | 2025-10-13 10:20:30.676067 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-13 10:20:31.380323 | localhost | changed 2025-10-13 10:20:31.385464 | 2025-10-13 10:20:31.385610 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-13 10:20:31.844011 | localhost | Identity added: /var/lib/zuul/builds/6a44f134a0084eb3bc27d1538a56c230/work/tmp/ansible.i8chqoyj (/var/lib/zuul/builds/6a44f134a0084eb3bc27d1538a56c230/work/tmp/ansible.i8chqoyj) 2025-10-13 10:20:31.844254 | localhost | ok: Runtime: 0:00:00.014912 2025-10-13 10:20:31.849178 | 2025-10-13 10:20:31.849271 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-13 10:20:32.133964 | localhost | ok: Runtime: 0:00:00.005169 2025-10-13 10:20:32.139355 | 2025-10-13 10:20:32.139441 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-13 10:20:32.222962 | localhost | changed 2025-10-13 10:20:32.229691 | 2025-10-13 10:20:32.229813 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-13 10:20:32.672297 | localhost | changed 2025-10-13 10:20:32.694281 | 2025-10-13 10:20:32.694401 | PLAY [localhost] 2025-10-13 10:20:32.706206 | 2025-10-13 10:20:32.706339 | TASK [Generate bulk log download script] 2025-10-13 10:20:32.737388 | localhost | ok 2025-10-13 10:20:32.750339 | 2025-10-13 10:20:32.750457 | TASK [local-log-download : Check API endpoint is defined] 2025-10-13 10:20:32.794331 | localhost | ok: All assertions passed 2025-10-13 10:20:32.804520 | 2025-10-13 10:20:32.804699 | TASK [local-log-download : Create download script] 2025-10-13 10:20:33.252046 | localhost -> localhost | changed 2025-10-13 10:20:33.260984 | 2025-10-13 10:20:33.261168 | TASK [Register quick-download link] 2025-10-13 10:20:33.289999 | localhost | ok 2025-10-13 10:20:33.334857 | 2025-10-13 10:20:33.334948 | PLAY [logserver.rdoproject.org] 2025-10-13 10:20:33.345006 | 2025-10-13 10:20:33.345108 | TASK [Set zuul-log-path fact] 2025-10-13 10:20:33.362273 | logserver.rdoproject.org | ok 2025-10-13 10:20:33.372503 | 2025-10-13 10:20:33.372619 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-13 10:20:33.409648 | logserver.rdoproject.org | ok 2025-10-13 10:20:33.418000 | 2025-10-13 10:20:33.418143 | TASK [upload-logs : Create log directories] 2025-10-13 10:20:34.577409 | logserver.rdoproject.org | changed 2025-10-13 10:20:34.580986 | 2025-10-13 10:20:34.581192 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-13 10:20:34.853969 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004989 2025-10-13 10:20:34.858970 | 2025-10-13 10:20:34.859069 | TASK [upload-logs : Upload logs to log server] 2025-10-13 10:20:35.521563 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-13 10:20:35.525647 | 2025-10-13 10:20:35.525738 | LOOP [upload-logs : Compress console log and json output] 2025-10-13 10:20:35.573655 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-13 10:20:35.586875 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-13 10:20:35.599637 | 2025-10-13 10:20:35.599767 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-13 10:20:35.665157 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-13 10:20:35.665397 | 2025-10-13 10:20:35.666053 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-13 10:20:35.672785 | 2025-10-13 10:20:35.672904 | LOOP [upload-logs : Upload console log and json output]