2025-10-13 10:46:46.572034 | Job console starting... 2025-10-13 10:46:46.582070 | Updating repositories 2025-10-13 10:46:46.601758 | Preparing job workspace 2025-10-13 10:46:50.654217 | Running Ansible setup... 2025-10-13 10:46:54.724372 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-13 10:46:55.423561 | 2025-10-13 10:46:55.423690 | PLAY [localhost] 2025-10-13 10:46:55.442605 | 2025-10-13 10:46:55.442716 | TASK [Gathering Facts] 2025-10-13 10:46:56.757163 | localhost | ok 2025-10-13 10:46:56.772659 | 2025-10-13 10:46:56.772958 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-13 10:46:57.226417 | localhost -> localhost | changed 2025-10-13 10:46:57.238993 | 2025-10-13 10:46:57.239114 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-13 10:46:58.357821 | localhost -> localhost | changed 2025-10-13 10:46:58.366563 | 2025-10-13 10:46:58.366628 | TASK [Setup log path fact] 2025-10-13 10:46:58.406811 | localhost | ok 2025-10-13 10:46:58.421111 | 2025-10-13 10:46:58.421203 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-13 10:46:58.474029 | localhost | ok 2025-10-13 10:46:58.481581 | 2025-10-13 10:46:58.481646 | TASK [emit-job-header : Print job information] 2025-10-13 10:46:58.549184 | # Job Information 2025-10-13 10:46:58.549393 | Ansible Version: 2.15.12 2025-10-13 10:46:58.549420 | Job: cifmw-molecule-cifmw_helpers 2025-10-13 10:46:58.549440 | Pipeline: github-check 2025-10-13 10:46:58.549458 | Executor: ze03.softwarefactory-project.io 2025-10-13 10:46:58.549476 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3402 2025-10-13 10:46:58.549495 | Log URL (when completed): https://logserver.rdoproject.org/b39/rdoproject.org/b394f40e261341569e5402100767dfec/ 2025-10-13 10:46:58.549513 | Event ID: 80394770-a821-11f0-833e-e6a021be57f6 2025-10-13 10:46:58.557405 | 2025-10-13 10:46:58.557559 | LOOP [emit-job-header : Print node information] 2025-10-13 10:46:58.695856 | localhost | ok: 2025-10-13 10:46:58.696065 | localhost | # Node Information 2025-10-13 10:46:58.696092 | localhost | Inventory Hostname: controller 2025-10-13 10:46:58.696113 | localhost | Hostname: np0005483735 2025-10-13 10:46:58.696132 | localhost | Username: zuul 2025-10-13 10:46:58.696151 | localhost | Distro: CentOS 9 2025-10-13 10:46:58.696169 | localhost | Provider: vexxhost-nodepool-tripleo 2025-10-13 10:46:58.696186 | localhost | Region: RegionOne 2025-10-13 10:46:58.696202 | localhost | Label: cloud-centos-9-stream-tripleo 2025-10-13 10:46:58.696218 | localhost | Product Name: OpenStack Nova 2025-10-13 10:46:58.696234 | localhost | Interface IP: 38.102.83.192 2025-10-13 10:46:58.737366 | 2025-10-13 10:46:58.737571 | PLAY [all] 2025-10-13 10:46:58.747512 | 2025-10-13 10:46:58.747602 | TASK [Gather network facts] 2025-10-13 10:46:59.236563 | controller | ok 2025-10-13 10:46:59.276043 | 2025-10-13 10:46:59.276160 | TASK [include_role : start-zuul-console] 2025-10-13 10:46:59.295570 | controller | ok 2025-10-13 10:46:59.333964 | 2025-10-13 10:46:59.334100 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-13 10:46:59.822877 | controller | ok 2025-10-13 10:46:59.835994 | 2025-10-13 10:46:59.836120 | TASK [include_role : add-build-sshkey] 2025-10-13 10:46:59.886197 | controller | ok 2025-10-13 10:46:59.917613 | 2025-10-13 10:46:59.917768 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-13 10:47:00.231045 | controller -> localhost | ok 2025-10-13 10:47:00.237878 | 2025-10-13 10:47:00.237944 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-13 10:47:00.286158 | controller | ok 2025-10-13 10:47:00.324009 | controller | included: /var/lib/zuul/builds/b394f40e261341569e5402100767dfec/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-13 10:47:00.334443 | 2025-10-13 10:47:00.334571 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-13 10:47:01.150244 | controller -> localhost | Generating public/private rsa key pair. 2025-10-13 10:47:01.150442 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/b394f40e261341569e5402100767dfec/work/b394f40e261341569e5402100767dfec_id_rsa. 2025-10-13 10:47:01.150476 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/b394f40e261341569e5402100767dfec/work/b394f40e261341569e5402100767dfec_id_rsa.pub. 2025-10-13 10:47:01.150499 | controller -> localhost | The key fingerprint is: 2025-10-13 10:47:01.150519 | controller -> localhost | SHA256:FyFufl5Dlr6deU5pxARC32nb5LQ0GysSL/IMe1g5NEI zuul-build-sshkey 2025-10-13 10:47:01.150538 | controller -> localhost | The key's randomart image is: 2025-10-13 10:47:01.150557 | controller -> localhost | +---[RSA 3072]----+ 2025-10-13 10:47:01.150575 | controller -> localhost | | .Eoo . | 2025-10-13 10:47:01.150594 | controller -> localhost | | ... .o.o .| 2025-10-13 10:47:01.150612 | controller -> localhost | | o..++. Oo| 2025-10-13 10:47:01.150630 | controller -> localhost | | o o== =+O| 2025-10-13 10:47:01.150649 | controller -> localhost | | Soo*+o Bo| 2025-10-13 10:47:01.150667 | controller -> localhost | | +O.++oo.| 2025-10-13 10:47:01.150684 | controller -> localhost | | o.+. +oo| 2025-10-13 10:47:01.150702 | controller -> localhost | | . .+ | 2025-10-13 10:47:01.150719 | controller -> localhost | | .| 2025-10-13 10:47:01.150761 | controller -> localhost | +----[SHA256]-----+ 2025-10-13 10:47:01.150808 | controller -> localhost | ok: Runtime: 0:00:00.231806 2025-10-13 10:47:01.156595 | 2025-10-13 10:47:01.156667 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-13 10:47:01.176041 | controller | ok 2025-10-13 10:47:01.189124 | controller | included: /var/lib/zuul/builds/b394f40e261341569e5402100767dfec/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-13 10:47:01.197638 | 2025-10-13 10:47:01.197717 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-13 10:47:01.223303 | controller | skipping: Conditional result was False 2025-10-13 10:47:01.229775 | 2025-10-13 10:47:01.229849 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-13 10:47:01.668483 | controller | changed 2025-10-13 10:47:01.673419 | 2025-10-13 10:47:01.673481 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-13 10:47:01.905536 | controller | ok 2025-10-13 10:47:01.921685 | 2025-10-13 10:47:01.921845 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-13 10:47:02.740216 | controller | changed 2025-10-13 10:47:02.747044 | 2025-10-13 10:47:02.747119 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-13 10:47:03.551128 | controller | changed 2025-10-13 10:47:03.556362 | 2025-10-13 10:47:03.556450 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-13 10:47:03.592522 | controller | skipping: Conditional result was False 2025-10-13 10:47:03.601055 | 2025-10-13 10:47:03.601314 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-13 10:47:03.977375 | controller -> localhost | changed 2025-10-13 10:47:03.989170 | 2025-10-13 10:47:03.989302 | TASK [add-build-sshkey : Add back temp key] 2025-10-13 10:47:04.261536 | controller -> localhost | Identity added: /var/lib/zuul/builds/b394f40e261341569e5402100767dfec/work/b394f40e261341569e5402100767dfec_id_rsa (zuul-build-sshkey) 2025-10-13 10:47:04.261713 | controller -> localhost | ok: Runtime: 0:00:00.009044 2025-10-13 10:47:04.268104 | 2025-10-13 10:47:04.268167 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-13 10:47:04.696170 | controller | ok 2025-10-13 10:47:04.700908 | 2025-10-13 10:47:04.700967 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-13 10:47:04.735042 | controller | skipping: Conditional result was False 2025-10-13 10:47:04.744654 | 2025-10-13 10:47:04.744781 | TASK [include_role : validate-host] 2025-10-13 10:47:04.766454 | controller | ok 2025-10-13 10:47:04.790281 | 2025-10-13 10:47:04.790406 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-13 10:47:04.827032 | controller | ok 2025-10-13 10:47:04.832223 | 2025-10-13 10:47:04.832424 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-13 10:47:05.053544 | controller -> localhost | ok 2025-10-13 10:47:05.098435 | 2025-10-13 10:47:05.098548 | TASK [validate-host : Collect information about the host] 2025-10-13 10:47:05.797242 | controller | ok 2025-10-13 10:47:05.811004 | 2025-10-13 10:47:05.811127 | TASK [validate-host : Sanitize hostname] 2025-10-13 10:47:05.885104 | controller | ok 2025-10-13 10:47:05.893229 | 2025-10-13 10:47:05.893344 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-13 10:47:06.317922 | controller -> localhost | changed 2025-10-13 10:47:06.342356 | 2025-10-13 10:47:06.342508 | TASK [validate-host : Collect information about zuul worker] 2025-10-13 10:47:06.728171 | controller | ok 2025-10-13 10:47:06.737166 | 2025-10-13 10:47:06.737300 | TASK [validate-host : Write out all zuul information for each host] 2025-10-13 10:47:07.233941 | controller -> localhost | changed 2025-10-13 10:47:07.243781 | 2025-10-13 10:47:07.243846 | TASK [include_role : prepare-workspace-openshift] 2025-10-13 10:47:07.257369 | controller | skipping: Conditional result was False 2025-10-13 10:47:07.262989 | 2025-10-13 10:47:07.263049 | TASK [include_role : remove-zuul-sshkey] 2025-10-13 10:47:07.276938 | controller | skipping: Conditional result was False 2025-10-13 10:47:07.283136 | 2025-10-13 10:47:07.283210 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-13 10:47:07.550557 | controller | ok: "logs" 2025-10-13 10:47:07.551014 | controller | ok: All items complete 2025-10-13 10:47:07.551066 | 2025-10-13 10:47:07.826040 | controller | ok: "artifacts" 2025-10-13 10:47:08.054552 | controller | ok: "docs" 2025-10-13 10:47:08.065221 | 2025-10-13 10:47:08.065449 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-13 10:47:08.338240 | controller | changed: "logs" 2025-10-13 10:47:08.568212 | controller | changed: "artifacts" 2025-10-13 10:47:08.854128 | controller | changed: "docs" 2025-10-13 10:47:08.910616 | 2025-10-13 10:47:08.910841 | PLAY RECAP 2025-10-13 10:47:08.910966 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-13 10:47:08.911025 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-13 10:47:08.911063 | 2025-10-13 10:47:09.047561 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-13 10:47:09.048382 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2025-10-13 10:47:09.628462 | 2025-10-13 10:47:09.628598 | PLAY [localhost] 2025-10-13 10:47:09.646277 | 2025-10-13 10:47:09.646410 | TASK [configure-ssh-config-fips : Ensure ssh config directory] 2025-10-13 10:47:10.155236 | localhost | ok 2025-10-13 10:47:10.161652 | 2025-10-13 10:47:10.161765 | TASK [configure-ssh-config-fips : Setup ssh config] 2025-10-13 10:47:10.844317 | localhost | changed 2025-10-13 10:47:10.868098 | 2025-10-13 10:47:10.868234 | PLAY [all] 2025-10-13 10:47:10.883796 | 2025-10-13 10:47:10.883875 | TASK [include_role : prepare-workspace] 2025-10-13 10:47:10.912661 | controller | ok 2025-10-13 10:47:10.927769 | 2025-10-13 10:47:10.927839 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-13 10:47:11.420127 | controller | ok 2025-10-13 10:47:11.427627 | 2025-10-13 10:47:11.427779 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-13 10:47:13.167907 | controller | Output suppressed because no_log was given 2025-10-13 10:47:13.177394 | 2025-10-13 10:47:13.177458 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-13 10:47:13.480704 | controller | changed: "logs" 2025-10-13 10:47:13.735698 | controller | changed: "artifacts" 2025-10-13 10:47:13.974622 | controller | changed: "docs" 2025-10-13 10:47:13.998181 | 2025-10-13 10:47:13.998344 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-13 10:47:14.289257 | controller | changed: "logs" 2025-10-13 10:47:14.289799 | controller | changed: All items complete 2025-10-13 10:47:14.289897 | 2025-10-13 10:47:14.508258 | controller | changed: "artifacts" 2025-10-13 10:47:14.721002 | controller | changed: "docs" 2025-10-13 10:47:14.756305 | 2025-10-13 10:47:14.756495 | TASK [Check if worker can sudo] 2025-10-13 10:47:15.324888 | controller | ok: Runtime: 0:00:00.055307 2025-10-13 10:47:15.330892 | 2025-10-13 10:47:15.330977 | TASK [configure-mirrors : Gather needed facts] 2025-10-13 10:47:15.385514 | controller | skipping: Conditional result was False 2025-10-13 10:47:15.393705 | 2025-10-13 10:47:15.393834 | TASK [configure-mirrors : Set up infrastructure mirrors] 2025-10-13 10:47:15.456589 | controller | ok 2025-10-13 10:47:15.467617 | controller | included: /var/lib/zuul/builds/b394f40e261341569e5402100767dfec/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml 2025-10-13 10:47:15.507024 | 2025-10-13 10:47:15.507154 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir] 2025-10-13 10:47:15.856786 | controller | ok 2025-10-13 10:47:15.867620 | 2025-10-13 10:47:15.867838 | LOOP [configure-mirrors : Include OS-specific variables] 2025-10-13 10:47:15.961111 | controller | ok: "/var/lib/zuul/builds/b394f40e261341569e5402100767dfec/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml" 2025-10-13 10:47:15.981750 | 2025-10-13 10:47:15.981987 | TASK [configure-mirrors : Install /etc/pip.conf configuration] 2025-10-13 10:47:16.994214 | controller | changed 2025-10-13 10:47:16.999693 | 2025-10-13 10:47:16.999809 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors] 2025-10-13 10:47:17.104946 | controller | ok: "/var/lib/zuul/builds/b394f40e261341569e5402100767dfec/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml" 2025-10-13 10:47:17.105131 | controller | ok: All items complete 2025-10-13 10:47:17.105158 | 2025-10-13 10:47:17.212336 | controller | included: /var/lib/zuul/builds/b394f40e261341569e5402100767dfec/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml 2025-10-13 10:47:17.238945 | 2025-10-13 10:47:17.239093 | LOOP [configure-mirrors : Install CentOS 9 repository files] 2025-10-13 10:47:18.270135 | controller | changed: "etc/yum.repos.d/centos.repo" 2025-10-13 10:47:19.126327 | controller | changed: "etc/yum.repos.d/centos-addons.repo" 2025-10-13 10:47:19.152311 | 2025-10-13 10:47:19.152496 | TASK [configure-mirrors : Disable deltrarpm] 2025-10-13 10:47:19.608815 | controller | changed: section and option added 2025-10-13 10:47:19.656344 | 2025-10-13 10:47:19.656670 | LOOP [configure-mirrors : Update yum/dnf cache] 2025-10-13 10:47:20.404990 | controller | 29 files removed 2025-10-13 10:47:20.405219 | controller | ok: Item: dnf clean all Runtime: 0:00:00.447687 2025-10-13 10:47:20.405258 | controller | changed: All items complete 2025-10-13 10:47:20.405278 | 2025-10-13 10:47:31.355285 | 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:47:31.355471 | controller | DNF version: 4.14.0 2025-10-13 10:47:31.355554 | controller | cachedir: /var/cache/dnf 2025-10-13 10:47:31.355621 | controller | Making cache files for all metadata files. 2025-10-13 10:47:31.355691 | controller | baseos: has expired and will be refreshed. 2025-10-13 10:47:31.356035 | controller | appstream: has expired and will be refreshed. 2025-10-13 10:47:31.356103 | controller | crb: has expired and will be refreshed. 2025-10-13 10:47:31.356168 | controller | extras-common: has expired and will be refreshed. 2025-10-13 10:47:31.356206 | controller | repo: downloading from remote: baseos 2025-10-13 10:47:31.356242 | controller | CentOS Stream 9 - BaseOS 86 MB/s | 8.8 MB 00:00 2025-10-13 10:47:31.356279 | controller | baseos: using metadata from Wed 08 Oct 2025 09:42:12 AM EDT. 2025-10-13 10:47:31.356314 | controller | repo: downloading from remote: appstream 2025-10-13 10:47:31.356349 | controller | CentOS Stream 9 - AppStream 101 MB/s | 25 MB 00:00 2025-10-13 10:47:31.356383 | controller | appstream: using metadata from Wed 08 Oct 2025 09:45:45 AM EDT. 2025-10-13 10:47:31.356417 | controller | repo: downloading from remote: crb 2025-10-13 10:47:31.356452 | controller | CentOS Stream 9 - CRB 80 MB/s | 7.2 MB 00:00 2025-10-13 10:47:31.356486 | controller | crb: using metadata from Wed 08 Oct 2025 09:50:07 AM EDT. 2025-10-13 10:47:31.356521 | controller | repo: downloading from remote: extras-common 2025-10-13 10:47:31.356555 | controller | CentOS Stream 9 - Extras packages 1.1 MB/s | 20 kB 00:00 2025-10-13 10:47:31.356589 | controller | extras-common: using metadata from Mon 15 Sep 2025 02:32:43 PM EDT. 2025-10-13 10:47:31.356622 | controller | Last metadata expiration check: 0:00:01 ago on Mon 13 Oct 2025 06:47:29 AM EDT. 2025-10-13 10:47:31.356656 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)' 2025-10-13 10:47:31.356692 | controller | Completion plugin: Generating completion cache... 2025-10-13 10:47:31.356813 | controller | Metadata cache created. 2025-10-13 10:47:31.356886 | controller | ok: Item: dnf makecache -v Runtime: 0:00:10.693473 2025-10-13 10:47:31.382583 | 2025-10-13 10:47:31.382710 | PLAY RECAP 2025-10-13 10:47:31.382802 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-13 10:47:31.382849 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-13 10:47:31.382875 | 2025-10-13 10:47:31.523527 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2025-10-13 10:47:31.524357 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-13 10:47:32.153300 | 2025-10-13 10:47:32.153448 | PLAY [all] 2025-10-13 10:47:32.184140 | 2025-10-13 10:47:32.184280 | TASK [Install binary dependencies] 2025-10-13 10:47:32.244950 | controller | ok 2025-10-13 10:47:32.268275 | 2025-10-13 10:47:32.268402 | TASK [bindep : Include find tasks] 2025-10-13 10:47:32.296749 | controller | ok 2025-10-13 10:47:32.304522 | controller | included: /var/lib/zuul/builds/b394f40e261341569e5402100767dfec/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-10-13 10:47:32.310860 | 2025-10-13 10:47:32.310948 | TASK [bindep : Look for bindep.txt] 2025-10-13 10:47:32.755797 | controller | ok 2025-10-13 10:47:32.762861 | 2025-10-13 10:47:32.762943 | TASK [bindep : Define bindep_file fact] 2025-10-13 10:47:32.804062 | controller | ok 2025-10-13 10:47:32.809241 | 2025-10-13 10:47:32.809306 | TASK [bindep : Look for other-requirements.txt] 2025-10-13 10:47:32.833939 | controller | skipping: Conditional result was False 2025-10-13 10:47:32.840789 | 2025-10-13 10:47:32.840859 | TASK [bindep : Define bindep_file fact] 2025-10-13 10:47:32.875714 | controller | skipping: Conditional result was False 2025-10-13 10:47:32.881834 | 2025-10-13 10:47:32.881901 | TASK [bindep : Look for bindep fallback file] 2025-10-13 10:47:32.917611 | controller | skipping: Conditional result was False 2025-10-13 10:47:32.923934 | 2025-10-13 10:47:32.924005 | TASK [bindep : Define bindep_file fact] 2025-10-13 10:47:32.948446 | controller | skipping: Conditional result was False 2025-10-13 10:47:32.959088 | 2025-10-13 10:47:32.959228 | TASK [bindep : Include bindep tasks] 2025-10-13 10:47:33.012466 | controller | ok 2025-10-13 10:47:33.022902 | controller | included: /var/lib/zuul/builds/b394f40e261341569e5402100767dfec/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml 2025-10-13 10:47:33.034462 | 2025-10-13 10:47:33.034557 | TASK [bindep : Look for bindep command] 2025-10-13 10:47:33.080561 | controller | skipping: Conditional result was False 2025-10-13 10:47:33.090098 | 2025-10-13 10:47:33.090198 | TASK [bindep : Check for system bindep] 2025-10-13 10:47:33.640586 | controller | ok: Runtime: 0:00:00.008756 2025-10-13 10:47:33.646625 | 2025-10-13 10:47:33.646696 | TASK [bindep : Define bindep_command fact] 2025-10-13 10:47:33.682485 | controller | skipping: Conditional result was False 2025-10-13 10:47:33.688921 | 2025-10-13 10:47:33.689286 | TASK [bindep : Include install tasks] 2025-10-13 10:47:33.742503 | controller | ok 2025-10-13 10:47:33.750281 | controller | included: /var/lib/zuul/builds/b394f40e261341569e5402100767dfec/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml 2025-10-13 10:47:33.759539 | 2025-10-13 10:47:33.759602 | TASK [bindep : Create temp dir for bindep] 2025-10-13 10:47:34.179354 | controller | changed 2025-10-13 10:47:34.187894 | 2025-10-13 10:47:34.188020 | TASK [Ensure we have pip dependencies] 2025-10-13 10:47:34.213869 | controller | ok 2025-10-13 10:47:34.246300 | 2025-10-13 10:47:34.246419 | TASK [ensure-pip : Check if pip is installed] 2025-10-13 06:47:34.570031 | controller | /usr/bin/pip3 2025-10-13 06:47:34.590359 | controller | /usr/bin/python3: No module named wheel 2025-10-13 10:47:34.822425 | controller | ok: Runtime: 0:00:00.029690 2025-10-13 10:47:34.840209 | 2025-10-13 10:47:34.840480 | LOOP [ensure-pip : Install pip from packages] 2025-10-13 10:47:34.897546 | controller | ok: "/var/lib/zuul/builds/b394f40e261341569e5402100767dfec/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml" 2025-10-13 10:47:34.918598 | controller | included: /var/lib/zuul/builds/b394f40e261341569e5402100767dfec/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml 2025-10-13 10:47:34.941150 | 2025-10-13 10:47:34.941307 | TASK [ensure-pip : Install Python 3 pip] 2025-10-13 10:47:37.306395 | controller | changed 2025-10-13 10:47:37.319340 | 2025-10-13 10:47:37.319492 | TASK [ensure-pip : Check for EPEL repository] 2025-10-13 10:47:37.400555 | controller | skipping: Conditional result was False 2025-10-13 10:47:37.410104 | 2025-10-13 10:47:37.410226 | TASK [ensure-pip : Fail with instructions when EPEL is not installed] 2025-10-13 10:47:37.477928 | controller | skipping: Conditional result was False 2025-10-13 10:47:37.488998 | 2025-10-13 10:47:37.489168 | TASK [ensure-pip : Install Python 2 pip] 2025-10-13 10:47:37.547361 | controller | skipping: Conditional result was False 2025-10-13 10:47:37.563567 | 2025-10-13 10:47:37.563849 | TASK [ensure-pip : Ensure setuptools] 2025-10-13 10:47:37.593937 | controller | skipping: Conditional result was False 2025-10-13 10:47:37.607213 | 2025-10-13 10:47:37.607385 | TASK [ensure-pip : Check for ensurepip module] 2025-10-13 10:47:38.160943 | controller | skipping: Conditional result was False 2025-10-13 10:47:38.176048 | 2025-10-13 10:47:38.176197 | TASK [ensure-pip : Ensure python3-venv] 2025-10-13 10:47:38.203177 | controller | skipping: Conditional result was False 2025-10-13 10:47:38.217423 | 2025-10-13 10:47:38.217575 | TASK [ensure-pip : Install pip from source] 2025-10-13 10:47:38.244651 | controller | skipping: Conditional result was False 2025-10-13 10:47:38.258894 | 2025-10-13 10:47:38.259053 | TASK [ensure-pip : Probe for venv python full path] 2025-10-13 06:47:38.520015 | controller | /usr/bin/python3 2025-10-13 10:47:38.805603 | controller | ok: Runtime: 0:00:00.008315 2025-10-13 10:47:38.818891 | 2025-10-13 10:47:38.819297 | TASK [ensure-pip : Set host default] 2025-10-13 10:47:38.895033 | controller | ok 2025-10-13 10:47:38.907068 | 2025-10-13 10:47:38.907404 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2025-10-13 10:47:38.981567 | controller | ok 2025-10-13 10:47:39.004195 | 2025-10-13 10:47:39.004371 | TASK [bindep : Install bindep into temporary venv] 2025-10-13 10:47:43.224153 | controller | changed 2025-10-13 10:47:43.236917 | 2025-10-13 10:47:43.237070 | TASK [bindep : Define bindep_command] 2025-10-13 10:47:43.275515 | controller | ok 2025-10-13 10:47:43.289572 | 2025-10-13 10:47:43.289800 | LOOP [bindep : Include package tasks] 2025-10-13 10:47:43.351627 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt" 2025-10-13 10:47:43.351803 | controller | ok: All items complete 2025-10-13 10:47:43.351829 | 2025-10-13 10:47:43.372948 | controller | included: /var/lib/zuul/builds/b394f40e261341569e5402100767dfec/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml 2025-10-13 10:47:43.391410 | 2025-10-13 10:47:43.391563 | TASK [bindep : Define bindep_run fact] 2025-10-13 10:47:43.435036 | controller | ok 2025-10-13 10:47:43.442953 | 2025-10-13 10:47:43.443045 | TASK [bindep : Get list of packages to install from bindep] 2025-10-13 06:47:45.011412 | controller | podman 2025-10-13 06:47:45.050418 | controller | python3-jmespath 2025-10-13 06:47:45.050643 | controller | python3-libvirt 2025-10-13 06:47:45.050659 | controller | python3-lxml 2025-10-13 06:47:45.050683 | controller | python3-netaddr 2025-10-13 10:47:45.489133 | controller | ok: Runtime: 0:00:01.289826 2025-10-13 10:47:45.503694 | 2025-10-13 10:47:45.503947 | TASK [bindep : Install distro packages from bindep] 2025-10-13 10:48:49.574878 | controller | changed 2025-10-13 10:48:49.580895 | 2025-10-13 10:48:49.580993 | TASK [bindep : Check that packages are installed] 2025-10-13 10:48:51.125361 | controller | ok: Runtime: 0:00:01.170145 2025-10-13 10:48:51.138667 | 2025-10-13 10:48:51.138875 | TASK [bindep : Fail if we cannot install all packages] 2025-10-13 10:48:51.177304 | controller | skipping: Conditional result was False 2025-10-13 10:48:51.216112 | 2025-10-13 10:48:51.216234 | TASK [Run test-setup role] 2025-10-13 10:48:51.259128 | controller | ok 2025-10-13 10:48:51.290713 | 2025-10-13 10:48:51.290920 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-10-13 10:48:51.529454 | controller | ok 2025-10-13 10:48:51.534982 | 2025-10-13 10:48:51.535075 | TASK [test-setup : Run tools/test-setup.sh] 2025-10-13 10:48:52.067966 | controller | skipping: Conditional result was False 2025-10-13 10:48:52.095236 | 2025-10-13 10:48:52.095549 | TASK [bindep : Remove bindep temp dir] 2025-10-13 10:48:52.542470 | controller | ok 2025-10-13 10:48:52.553761 | 2025-10-13 10:48:52.553838 | PLAY RECAP 2025-10-13 10:48:52.553886 | controller | ok: 22 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0 2025-10-13 10:48:52.553912 | 2025-10-13 10:48:52.662210 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-13 10:48:52.663087 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2025-10-13 10:48:53.236233 | 2025-10-13 10:48:53.236396 | PLAY [all] 2025-10-13 10:48:53.256122 | 2025-10-13 10:48:53.256216 | TASK [Abort when test_command variable is undefined] 2025-10-13 10:48:53.290789 | controller | skipping: Conditional result was False 2025-10-13 10:48:53.297079 | 2025-10-13 10:48:53.297195 | TASK [Convert test_command to list] 2025-10-13 10:48:53.352598 | controller | skipping: Conditional result was False 2025-10-13 10:48:53.361619 | 2025-10-13 10:48:53.361745 | TASK [Use test_command list] 2025-10-13 10:48:53.423685 | controller | ok 2025-10-13 10:48:53.431500 | 2025-10-13 10:48:53.431593 | LOOP [Run test_command] 2025-10-13 10:48:53.847718 | controller | no check to run 2025-10-13 10:48:53.848097 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.007011 2025-10-13 10:48:53.899416 | 2025-10-13 10:48:53.899577 | PLAY RECAP 2025-10-13 10:48:53.899649 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-13 10:48:53.899703 | 2025-10-13 10:48:54.004689 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2025-10-13 10:48:54.006437 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-13 10:48:54.552544 | 2025-10-13 10:48:54.552654 | PLAY [all] 2025-10-13 10:48:54.572401 | 2025-10-13 10:48:54.572478 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-13 10:48:54.974254 | controller | changed: non-zero return code 2025-10-13 10:48:54.981841 | 2025-10-13 10:48:54.981941 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-13 10:48:55.007957 | controller | skipping: Conditional result was False 2025-10-13 10:48:55.016413 | 2025-10-13 10:48:55.016551 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-13 10:48:55.049570 | 2025-10-13 10:48:55.049824 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-13 10:48:55.083948 | 2025-10-13 10:48:55.084160 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-13 10:48:55.099322 | controller | skipping: Conditional result was False 2025-10-13 10:48:55.111825 | 2025-10-13 10:48:55.112520 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-13 10:48:55.144023 | 2025-10-13 10:48:55.144194 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-13 10:48:55.157868 | controller | skipping: Conditional result was False 2025-10-13 10:48:55.164805 | 2025-10-13 10:48:55.164911 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-13 10:48:55.181991 | controller | skipping: Conditional result was False 2025-10-13 10:48:55.189490 | 2025-10-13 10:48:55.189604 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-13 10:48:55.209263 | controller | skipping: Conditional result was False 2025-10-13 10:48:55.236995 | 2025-10-13 10:48:55.237099 | PLAY RECAP 2025-10-13 10:48:55.237139 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-13 10:48:55.237159 | 2025-10-13 10:48:55.344846 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-13 10:48:55.346018 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-13 10:48:55.938470 | 2025-10-13 10:48:55.938589 | PLAY [all] 2025-10-13 10:48:55.957770 | 2025-10-13 10:48:55.957876 | TASK [include_role : fetch-output] 2025-10-13 10:48:55.998288 | controller | ok 2025-10-13 10:48:56.015568 | 2025-10-13 10:48:56.015687 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-13 10:48:56.091167 | controller | skipping: Conditional result was False 2025-10-13 10:48:56.104135 | 2025-10-13 10:48:56.104297 | TASK [fetch-output : Set log path for single node] 2025-10-13 10:48:56.152817 | controller | ok 2025-10-13 10:48:56.164464 | 2025-10-13 10:48:56.164613 | LOOP [fetch-output : Ensure local output dirs] 2025-10-13 10:48:56.628089 | controller -> localhost | ok: "/var/lib/zuul/builds/b394f40e261341569e5402100767dfec/work/logs" 2025-10-13 10:48:56.837267 | controller -> localhost | changed: "/var/lib/zuul/builds/b394f40e261341569e5402100767dfec/work/artifacts" 2025-10-13 10:48:57.096383 | controller -> localhost | changed: "/var/lib/zuul/builds/b394f40e261341569e5402100767dfec/work/docs" 2025-10-13 10:48:57.115779 | 2025-10-13 10:48:57.115978 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-13 10:48:57.798585 | controller | changed: .d..t...... ./ 2025-10-13 10:48:57.798846 | controller | changed: All items complete 2025-10-13 10:48:57.798874 | 2025-10-13 10:48:58.346918 | controller | changed: .d..t...... ./ 2025-10-13 10:48:58.922785 | controller | changed: .d..t...... ./ 2025-10-13 10:48:58.951519 | 2025-10-13 10:48:58.951756 | TASK [include_role : fetch-output-openshift] 2025-10-13 10:48:58.979941 | controller | skipping: Conditional result was False 2025-10-13 10:48:58.993002 | 2025-10-13 10:48:58.993282 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-13 10:48:59.474425 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.012272 2025-10-13 10:48:59.720316 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.012200 2025-10-13 10:48:59.779177 | 2025-10-13 10:48:59.779295 | PLAY [all] 2025-10-13 10:48:59.801104 | 2025-10-13 10:48:59.801253 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-13 10:49:00.247761 | controller | changed 2025-10-13 10:49:00.296022 | 2025-10-13 10:49:00.296139 | PLAY RECAP 2025-10-13 10:49:00.296195 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-13 10:49:00.296225 | 2025-10-13 10:49:00.401640 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-13 10:49:00.402478 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-13 10:49:01.056619 | 2025-10-13 10:49:01.056753 | PLAY [localhost] 2025-10-13 10:49:01.076383 | 2025-10-13 10:49:01.076497 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-13 10:49:01.528977 | localhost | changed 2025-10-13 10:49:01.540373 | 2025-10-13 10:49:01.540550 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-13 10:49:01.573541 | localhost | ok 2025-10-13 10:49:01.581714 | 2025-10-13 10:49:01.581811 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-13 10:49:02.004085 | localhost | changed 2025-10-13 10:49:02.017183 | 2025-10-13 10:49:02.017336 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-13 10:49:02.760403 | localhost | changed 2025-10-13 10:49:02.765712 | 2025-10-13 10:49:02.765806 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-13 10:49:03.168410 | localhost | Identity added: /var/lib/zuul/builds/b394f40e261341569e5402100767dfec/work/tmp/ansible.hupt_vz5 (/var/lib/zuul/builds/b394f40e261341569e5402100767dfec/work/tmp/ansible.hupt_vz5) 2025-10-13 10:49:03.168592 | localhost | ok: Runtime: 0:00:00.013999 2025-10-13 10:49:03.173057 | 2025-10-13 10:49:03.173125 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-13 10:49:03.488565 | localhost | ok: Runtime: 0:00:00.008353 2025-10-13 10:49:03.493414 | 2025-10-13 10:49:03.493481 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-13 10:49:03.573354 | localhost | changed 2025-10-13 10:49:03.577603 | 2025-10-13 10:49:03.577666 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-13 10:49:03.981202 | localhost | changed 2025-10-13 10:49:04.002155 | 2025-10-13 10:49:04.002220 | PLAY [localhost] 2025-10-13 10:49:04.014766 | 2025-10-13 10:49:04.014836 | TASK [Generate bulk log download script] 2025-10-13 10:49:04.033534 | localhost | ok 2025-10-13 10:49:04.045431 | 2025-10-13 10:49:04.045499 | TASK [local-log-download : Check API endpoint is defined] 2025-10-13 10:49:04.093314 | localhost | ok: All assertions passed 2025-10-13 10:49:04.097642 | 2025-10-13 10:49:04.097701 | TASK [local-log-download : Create download script] 2025-10-13 10:49:04.506504 | localhost -> localhost | changed 2025-10-13 10:49:04.515384 | 2025-10-13 10:49:04.515462 | TASK [Register quick-download link] 2025-10-13 10:49:04.543592 | localhost | ok 2025-10-13 10:49:04.582350 | 2025-10-13 10:49:04.582456 | PLAY [logserver.rdoproject.org] 2025-10-13 10:49:04.591986 | 2025-10-13 10:49:04.592047 | TASK [Set zuul-log-path fact] 2025-10-13 10:49:04.608567 | logserver.rdoproject.org | ok 2025-10-13 10:49:04.617589 | 2025-10-13 10:49:04.617654 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-13 10:49:04.654341 | logserver.rdoproject.org | ok 2025-10-13 10:49:04.659741 | 2025-10-13 10:49:04.659807 | TASK [upload-logs : Create log directories] 2025-10-13 10:49:05.539585 | logserver.rdoproject.org | changed 2025-10-13 10:49:05.546130 | 2025-10-13 10:49:05.546256 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-13 10:49:05.881839 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.008992 2025-10-13 10:49:05.886717 | 2025-10-13 10:49:05.886824 | TASK [upload-logs : Upload logs to log server] 2025-10-13 10:49:06.619414 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-13 10:49:06.625706 | 2025-10-13 10:49:06.625880 | LOOP [upload-logs : Compress console log and json output] 2025-10-13 10:49:06.694865 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-13 10:49:06.704086 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-13 10:49:06.733252 | 2025-10-13 10:49:06.733424 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-13 10:49:06.785937 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-13 10:49:06.786397 | 2025-10-13 10:49:06.789080 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-13 10:49:06.801301 | 2025-10-13 10:49:06.801447 | LOOP [upload-logs : Upload console log and json output]