2025-12-05 15:33:23.325795 | Job console starting... 2025-12-05 15:33:23.333748 | Updating repositories 2025-12-05 15:33:23.359359 | Preparing job workspace 2025-12-05 15:33:27.209660 | Running Ansible setup... 2025-12-05 15:33:31.331051 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-12-05 15:33:31.992659 | 2025-12-05 15:33:31.992784 | PLAY [localhost] 2025-12-05 15:33:32.000882 | 2025-12-05 15:33:32.000955 | TASK [Gathering Facts] 2025-12-05 15:33:33.318334 | localhost | ok 2025-12-05 15:33:33.336850 | 2025-12-05 15:33:33.337002 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-05 15:33:33.927352 | localhost -> localhost | changed 2025-12-05 15:33:33.935385 | 2025-12-05 15:33:33.935483 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-05 15:33:34.806752 | localhost -> localhost | changed 2025-12-05 15:33:34.816644 | 2025-12-05 15:33:34.816805 | TASK [Setup log path fact] 2025-12-05 15:33:34.835323 | localhost | ok 2025-12-05 15:33:34.847610 | 2025-12-05 15:33:34.847715 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-05 15:33:34.875318 | localhost | ok 2025-12-05 15:33:34.883364 | 2025-12-05 15:33:34.883431 | TASK [emit-job-header : Print job information] 2025-12-05 15:33:34.921626 | # Job Information 2025-12-05 15:33:34.921790 | Ansible Version: 2.15.12 2025-12-05 15:33:34.921819 | Job: cifmw-molecule-ovirt 2025-12-05 15:33:34.921839 | Pipeline: github-check 2025-12-05 15:33:34.921858 | Executor: ze03.softwarefactory-project.io 2025-12-05 15:33:34.921876 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3539 2025-12-05 15:33:34.921895 | Log URL (when completed): https://logserver.rdoproject.org/ad6/rdoproject.org/ad6807756a7d4d12a88ffa933cf2695c/ 2025-12-05 15:33:34.921913 | Event ID: 757b9e30-d1ef-11f0-97eb-f505e6ea5a00 2025-12-05 15:33:34.925568 | 2025-12-05 15:33:34.925635 | LOOP [emit-job-header : Print node information] 2025-12-05 15:33:35.027099 | localhost | ok: 2025-12-05 15:33:35.027273 | localhost | # Node Information 2025-12-05 15:33:35.027299 | localhost | Inventory Hostname: controller 2025-12-05 15:33:35.027323 | localhost | Hostname: np0005547432 2025-12-05 15:33:35.027342 | localhost | Username: zuul 2025-12-05 15:33:35.027361 | localhost | Distro: CentOS 9 2025-12-05 15:33:35.027378 | localhost | Provider: vexxhost-nodepool-tripleo 2025-12-05 15:33:35.027395 | localhost | Region: RegionOne 2025-12-05 15:33:35.027410 | localhost | Label: cloud-centos-9-stream-tripleo 2025-12-05 15:33:35.027427 | localhost | Product Name: OpenStack Nova 2025-12-05 15:33:35.027442 | localhost | Interface IP: 38.102.83.162 2025-12-05 15:33:35.054787 | 2025-12-05 15:33:35.054845 | PLAY [all] 2025-12-05 15:33:35.061316 | 2025-12-05 15:33:35.061377 | TASK [Gather network facts] 2025-12-05 15:33:35.507326 | controller | ok 2025-12-05 15:33:35.532830 | 2025-12-05 15:33:35.532952 | TASK [include_role : start-zuul-console] 2025-12-05 15:33:35.563321 | controller | ok 2025-12-05 15:33:35.575119 | 2025-12-05 15:33:35.575207 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-05 15:33:35.954477 | controller | ok 2025-12-05 15:33:35.964839 | 2025-12-05 15:33:35.964927 | TASK [include_role : add-build-sshkey] 2025-12-05 15:33:35.984115 | controller | ok 2025-12-05 15:33:35.998280 | 2025-12-05 15:33:35.998399 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-05 15:33:36.227965 | controller -> localhost | ok 2025-12-05 15:33:36.235405 | 2025-12-05 15:33:36.235486 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-05 15:33:36.255093 | controller | ok 2025-12-05 15:33:36.271516 | controller | included: /var/lib/zuul/builds/ad6807756a7d4d12a88ffa933cf2695c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-05 15:33:36.277261 | 2025-12-05 15:33:36.277321 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-05 15:33:36.960450 | controller -> localhost | Generating public/private rsa key pair. 2025-12-05 15:33:36.960656 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ad6807756a7d4d12a88ffa933cf2695c/work/ad6807756a7d4d12a88ffa933cf2695c_id_rsa. 2025-12-05 15:33:36.960688 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ad6807756a7d4d12a88ffa933cf2695c/work/ad6807756a7d4d12a88ffa933cf2695c_id_rsa.pub. 2025-12-05 15:33:36.960711 | controller -> localhost | The key fingerprint is: 2025-12-05 15:33:36.960749 | controller -> localhost | SHA256:0he879tK9nOpt2fZEfEf8HYtfdTWtFouYqp7Ol21GVc zuul-build-sshkey 2025-12-05 15:33:36.960770 | controller -> localhost | The key's randomart image is: 2025-12-05 15:33:36.960790 | controller -> localhost | +---[RSA 3072]----+ 2025-12-05 15:33:36.960809 | controller -> localhost | | .+| 2025-12-05 15:33:36.960828 | controller -> localhost | | . . .E| 2025-12-05 15:33:36.960847 | controller -> localhost | | o o*=| 2025-12-05 15:33:36.960866 | controller -> localhost | | . o o=**| 2025-12-05 15:33:36.960884 | controller -> localhost | | . S oo.o*o*| 2025-12-05 15:33:36.960902 | controller -> localhost | | . .oo.o...| 2025-12-05 15:33:36.960920 | controller -> localhost | | ... + =| 2025-12-05 15:33:36.960941 | controller -> localhost | | ..o + o.+=| 2025-12-05 15:33:36.960959 | controller -> localhost | | += ++==o| 2025-12-05 15:33:36.960977 | controller -> localhost | +----[SHA256]-----+ 2025-12-05 15:33:36.961024 | controller -> localhost | ok: Runtime: 0:00:00.070020 2025-12-05 15:33:36.967477 | 2025-12-05 15:33:36.967541 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-05 15:33:37.010544 | controller | ok 2025-12-05 15:33:37.021525 | controller | included: /var/lib/zuul/builds/ad6807756a7d4d12a88ffa933cf2695c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-05 15:33:37.034256 | 2025-12-05 15:33:37.034328 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-05 15:33:37.078016 | controller | skipping: Conditional result was False 2025-12-05 15:33:37.086951 | 2025-12-05 15:33:37.087085 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-05 15:33:37.533254 | controller | changed 2025-12-05 15:33:37.547296 | 2025-12-05 15:33:37.547468 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-05 15:33:37.787014 | controller | ok 2025-12-05 15:33:37.794891 | 2025-12-05 15:33:37.794959 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-05 15:33:38.564971 | controller | changed 2025-12-05 15:33:38.571813 | 2025-12-05 15:33:38.571926 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-05 15:33:39.472606 | controller | changed 2025-12-05 15:33:39.477750 | 2025-12-05 15:33:39.477823 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-05 15:33:39.507657 | controller | skipping: Conditional result was False 2025-12-05 15:33:39.515900 | 2025-12-05 15:33:39.515974 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-05 15:33:39.967311 | controller -> localhost | changed 2025-12-05 15:33:39.978515 | 2025-12-05 15:33:39.978580 | TASK [add-build-sshkey : Add back temp key] 2025-12-05 15:33:40.280521 | controller -> localhost | Identity added: /var/lib/zuul/builds/ad6807756a7d4d12a88ffa933cf2695c/work/ad6807756a7d4d12a88ffa933cf2695c_id_rsa (zuul-build-sshkey) 2025-12-05 15:33:40.280679 | controller -> localhost | ok: Runtime: 0:00:00.017986 2025-12-05 15:33:40.288595 | 2025-12-05 15:33:40.289830 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-05 15:33:40.760637 | controller | ok 2025-12-05 15:33:40.767229 | 2025-12-05 15:33:40.767298 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-05 15:33:40.802214 | controller | skipping: Conditional result was False 2025-12-05 15:33:40.816533 | 2025-12-05 15:33:40.816618 | TASK [include_role : validate-host] 2025-12-05 15:33:40.852545 | controller | ok 2025-12-05 15:33:40.878884 | 2025-12-05 15:33:40.879003 | TASK [validate-host : Define zuul_info_dir fact] 2025-12-05 15:33:40.920349 | controller | ok 2025-12-05 15:33:40.924975 | 2025-12-05 15:33:40.925039 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-12-05 15:33:41.231945 | controller -> localhost | ok 2025-12-05 15:33:41.239485 | 2025-12-05 15:33:41.239596 | TASK [validate-host : Collect information about the host] 2025-12-05 15:33:42.003946 | controller | ok 2025-12-05 15:33:42.017469 | 2025-12-05 15:33:42.017549 | TASK [validate-host : Sanitize hostname] 2025-12-05 15:33:42.078342 | controller | ok 2025-12-05 15:33:42.083467 | 2025-12-05 15:33:42.083533 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-12-05 15:33:42.932461 | controller -> localhost | changed 2025-12-05 15:33:42.946281 | 2025-12-05 15:33:42.946402 | TASK [validate-host : Collect information about zuul worker] 2025-12-05 15:33:43.454168 | controller | ok 2025-12-05 15:33:43.462196 | 2025-12-05 15:33:43.462297 | TASK [validate-host : Write out all zuul information for each host] 2025-12-05 15:33:43.944424 | controller -> localhost | changed 2025-12-05 15:33:43.960269 | 2025-12-05 15:33:43.960369 | TASK [include_role : prepare-workspace-openshift] 2025-12-05 15:33:44.008311 | controller | skipping: Conditional result was False 2025-12-05 15:33:44.014361 | 2025-12-05 15:33:44.014435 | TASK [include_role : remove-zuul-sshkey] 2025-12-05 15:33:44.072475 | controller | skipping: Conditional result was False 2025-12-05 15:33:44.078718 | 2025-12-05 15:33:44.078822 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-12-05 15:33:44.352837 | controller | ok: "logs" 2025-12-05 15:33:44.353092 | controller | ok: All items complete 2025-12-05 15:33:44.353119 | 2025-12-05 15:33:44.574268 | controller | ok: "artifacts" 2025-12-05 15:33:44.795372 | controller | ok: "docs" 2025-12-05 15:33:44.810052 | 2025-12-05 15:33:44.810169 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-12-05 15:33:45.097911 | controller | changed: "logs" 2025-12-05 15:33:45.359824 | controller | changed: "artifacts" 2025-12-05 15:33:45.612304 | controller | changed: "docs" 2025-12-05 15:33:45.657133 | 2025-12-05 15:33:45.657410 | PLAY RECAP 2025-12-05 15:33:45.657460 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-12-05 15:33:45.657486 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-05 15:33:45.657504 | 2025-12-05 15:33:45.802983 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-12-05 15:33:45.805408 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2025-12-05 15:33:46.517465 | 2025-12-05 15:33:46.517620 | PLAY [localhost] 2025-12-05 15:33:46.538397 | 2025-12-05 15:33:46.538566 | TASK [configure-ssh-config-fips : Ensure ssh config directory] 2025-12-05 15:33:47.129738 | localhost | ok 2025-12-05 15:33:47.137616 | 2025-12-05 15:33:47.137700 | TASK [configure-ssh-config-fips : Setup ssh config] 2025-12-05 15:33:48.281111 | localhost | changed 2025-12-05 15:33:48.322828 | 2025-12-05 15:33:48.322931 | PLAY [all] 2025-12-05 15:33:48.350940 | 2025-12-05 15:33:48.351009 | TASK [include_role : prepare-workspace] 2025-12-05 15:33:48.370015 | controller | ok 2025-12-05 15:33:48.386761 | 2025-12-05 15:33:48.386844 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-12-05 15:33:48.816497 | controller | ok 2025-12-05 15:33:48.822449 | 2025-12-05 15:33:48.822513 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-12-05 15:33:50.832024 | controller | Output suppressed because no_log was given 2025-12-05 15:33:50.843690 | 2025-12-05 15:33:50.843795 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-12-05 15:33:51.080473 | controller | changed: "logs" 2025-12-05 15:33:51.276922 | controller | changed: "artifacts" 2025-12-05 15:33:51.493266 | controller | changed: "docs" 2025-12-05 15:33:51.503236 | 2025-12-05 15:33:51.503305 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-12-05 15:33:51.773499 | controller | changed: "logs" 2025-12-05 15:33:51.773959 | controller | changed: All items complete 2025-12-05 15:33:51.774021 | 2025-12-05 15:33:51.985449 | controller | changed: "artifacts" 2025-12-05 15:33:52.228204 | controller | changed: "docs" 2025-12-05 15:33:52.252003 | 2025-12-05 15:33:52.252090 | TASK [Check if worker can sudo] 2025-12-05 15:33:52.786704 | controller | ok: Runtime: 0:00:00.047345 2025-12-05 15:33:52.793556 | 2025-12-05 15:33:52.793678 | TASK [configure-mirrors : Gather needed facts] 2025-12-05 15:33:52.848141 | controller | skipping: Conditional result was False 2025-12-05 15:33:52.854116 | 2025-12-05 15:33:52.854184 | TASK [configure-mirrors : Set up infrastructure mirrors] 2025-12-05 15:33:52.921282 | controller | ok 2025-12-05 15:33:52.931515 | controller | included: /var/lib/zuul/builds/ad6807756a7d4d12a88ffa933cf2695c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml 2025-12-05 15:33:52.965381 | 2025-12-05 15:33:52.965520 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir] 2025-12-05 15:33:53.235442 | controller | ok 2025-12-05 15:33:53.245486 | 2025-12-05 15:33:53.245671 | LOOP [configure-mirrors : Include OS-specific variables] 2025-12-05 15:33:53.420317 | controller | ok: "/var/lib/zuul/builds/ad6807756a7d4d12a88ffa933cf2695c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml" 2025-12-05 15:33:53.443339 | 2025-12-05 15:33:53.443548 | TASK [configure-mirrors : Install /etc/pip.conf configuration] 2025-12-05 15:33:54.336266 | controller | changed 2025-12-05 15:33:54.353353 | 2025-12-05 15:33:54.353824 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors] 2025-12-05 15:33:54.408974 | controller | ok: "/var/lib/zuul/builds/ad6807756a7d4d12a88ffa933cf2695c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml" 2025-12-05 15:33:54.409260 | controller | ok: All items complete 2025-12-05 15:33:54.409299 | 2025-12-05 15:33:54.475561 | controller | included: /var/lib/zuul/builds/ad6807756a7d4d12a88ffa933cf2695c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml 2025-12-05 15:33:54.484353 | 2025-12-05 15:33:54.484437 | LOOP [configure-mirrors : Install CentOS 9 repository files] 2025-12-05 15:33:55.378584 | controller | changed: "etc/yum.repos.d/centos.repo" 2025-12-05 15:33:56.215926 | controller | changed: "etc/yum.repos.d/centos-addons.repo" 2025-12-05 15:33:56.236639 | 2025-12-05 15:33:56.236777 | TASK [configure-mirrors : Disable deltrarpm] 2025-12-05 15:33:56.678953 | controller | changed: section and option added 2025-12-05 15:33:56.710963 | 2025-12-05 15:33:56.711072 | LOOP [configure-mirrors : Update yum/dnf cache] 2025-12-05 15:33:57.299133 | controller | 29 files removed 2025-12-05 15:33:57.299349 | controller | ok: Item: dnf clean all Runtime: 0:00:00.284072 2025-12-05 15:33:57.299391 | controller | changed: All items complete 2025-12-05 15:33:57.299429 | 2025-12-05 15:34:08.499120 | controller | Loaded plugins: builddep, changelog, config-manager, copr, debug, debuginfo-install, download, generate_completion_cache, groups-manager, needs-restarting, playground, repoclosure, repodiff, repograph, repomanage, reposync, system-upgrade 2025-12-05 15:34:08.499238 | controller | DNF version: 4.14.0 2025-12-05 15:34:08.499263 | controller | cachedir: /var/cache/dnf 2025-12-05 15:34:08.499282 | controller | Making cache files for all metadata files. 2025-12-05 15:34:08.499300 | controller | baseos: has expired and will be refreshed. 2025-12-05 15:34:08.499317 | controller | appstream: has expired and will be refreshed. 2025-12-05 15:34:08.499334 | controller | crb: has expired and will be refreshed. 2025-12-05 15:34:08.499358 | controller | extras-common: has expired and will be refreshed. 2025-12-05 15:34:08.499375 | controller | repo: downloading from remote: baseos 2025-12-05 15:34:08.499391 | controller | CentOS Stream 9 - BaseOS 66 MB/s | 8.8 MB 00:00 2025-12-05 15:34:08.499407 | controller | baseos: using metadata from Mon 01 Dec 2025 12:58:39 PM EST. 2025-12-05 15:34:08.499423 | controller | repo: downloading from remote: appstream 2025-12-05 15:34:08.499438 | controller | CentOS Stream 9 - AppStream 62 MB/s | 25 MB 00:00 2025-12-05 15:34:08.499454 | controller | appstream: using metadata from Mon 01 Dec 2025 01:01:55 PM EST. 2025-12-05 15:34:08.499481 | controller | repo: downloading from remote: crb 2025-12-05 15:34:08.499498 | controller | CentOS Stream 9 - CRB 57 MB/s | 7.3 MB 00:00 2025-12-05 15:34:08.499516 | controller | crb: using metadata from Mon 01 Dec 2025 01:06:07 PM EST. 2025-12-05 15:34:08.499534 | controller | repo: downloading from remote: extras-common 2025-12-05 15:34:08.499550 | controller | CentOS Stream 9 - Extras packages 1.5 MB/s | 20 kB 00:00 2025-12-05 15:34:08.499566 | controller | extras-common: using metadata from Thu 30 Oct 2025 09:20:08 AM EDT. 2025-12-05 15:34:08.499582 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)' 2025-12-05 15:34:08.499597 | controller | Completion plugin: Generating completion cache... 2025-12-05 15:34:08.499613 | controller | Metadata cache created. 2025-12-05 15:34:08.499641 | controller | ok: Item: dnf makecache -v Runtime: 0:00:10.960168 2025-12-05 15:34:08.521671 | 2025-12-05 15:34:08.521757 | PLAY RECAP 2025-12-05 15:34:08.521806 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-12-05 15:34:08.521831 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-05 15:34:08.521848 | 2025-12-05 15:34:08.663288 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2025-12-05 15:34:08.664100 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-12-05 15:34:09.291603 | 2025-12-05 15:34:09.291711 | PLAY [all] 2025-12-05 15:34:09.313824 | 2025-12-05 15:34:09.313909 | TASK [Install binary dependencies] 2025-12-05 15:34:09.364601 | controller | ok 2025-12-05 15:34:09.383326 | 2025-12-05 15:34:09.383407 | TASK [bindep : Include find tasks] 2025-12-05 15:34:09.413407 | controller | ok 2025-12-05 15:34:09.421267 | controller | included: /var/lib/zuul/builds/ad6807756a7d4d12a88ffa933cf2695c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-12-05 15:34:09.427065 | 2025-12-05 15:34:09.427131 | TASK [bindep : Look for bindep.txt] 2025-12-05 15:34:09.839953 | controller | ok 2025-12-05 15:34:09.846420 | 2025-12-05 15:34:09.846485 | TASK [bindep : Define bindep_file fact] 2025-12-05 15:34:09.875872 | controller | ok 2025-12-05 15:34:09.881279 | 2025-12-05 15:34:09.881343 | TASK [bindep : Look for other-requirements.txt] 2025-12-05 15:34:09.905841 | controller | skipping: Conditional result was False 2025-12-05 15:34:09.911868 | 2025-12-05 15:34:09.911943 | TASK [bindep : Define bindep_file fact] 2025-12-05 15:34:09.936942 | controller | skipping: Conditional result was False 2025-12-05 15:34:09.942787 | 2025-12-05 15:34:09.942852 | TASK [bindep : Look for bindep fallback file] 2025-12-05 15:34:09.966798 | controller | skipping: Conditional result was False 2025-12-05 15:34:09.972876 | 2025-12-05 15:34:09.972940 | TASK [bindep : Define bindep_file fact] 2025-12-05 15:34:09.996828 | controller | skipping: Conditional result was False 2025-12-05 15:34:10.002773 | 2025-12-05 15:34:10.002835 | TASK [bindep : Include bindep tasks] 2025-12-05 15:34:10.032817 | controller | ok 2025-12-05 15:34:10.039548 | controller | included: /var/lib/zuul/builds/ad6807756a7d4d12a88ffa933cf2695c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml 2025-12-05 15:34:10.047163 | 2025-12-05 15:34:10.047226 | TASK [bindep : Look for bindep command] 2025-12-05 15:34:10.076132 | controller | skipping: Conditional result was False 2025-12-05 15:34:10.083082 | 2025-12-05 15:34:10.083154 | TASK [bindep : Check for system bindep] 2025-12-05 15:34:10.630356 | controller | ok: Runtime: 0:00:00.005797 2025-12-05 15:34:10.643674 | 2025-12-05 15:34:10.643856 | TASK [bindep : Define bindep_command fact] 2025-12-05 15:34:10.682359 | controller | skipping: Conditional result was False 2025-12-05 15:34:10.694537 | 2025-12-05 15:34:10.694660 | TASK [bindep : Include install tasks] 2025-12-05 15:34:10.738959 | controller | ok 2025-12-05 15:34:10.751518 | controller | included: /var/lib/zuul/builds/ad6807756a7d4d12a88ffa933cf2695c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml 2025-12-05 15:34:10.763537 | 2025-12-05 15:34:10.763600 | TASK [bindep : Create temp dir for bindep] 2025-12-05 15:34:11.118398 | controller | changed 2025-12-05 15:34:11.141404 | 2025-12-05 15:34:11.141572 | TASK [Ensure we have pip dependencies] 2025-12-05 15:34:11.175983 | controller | ok 2025-12-05 15:34:11.208276 | 2025-12-05 15:34:11.208348 | TASK [ensure-pip : Check if pip is installed] 2025-12-05 10:34:11.452092 | controller | /usr/bin/pip3 2025-12-05 10:34:11.472601 | controller | /usr/bin/python3: No module named wheel 2025-12-05 15:34:11.748160 | controller | ok: Runtime: 0:00:00.031278 2025-12-05 15:34:11.760537 | 2025-12-05 15:34:11.760643 | LOOP [ensure-pip : Install pip from packages] 2025-12-05 15:34:11.850017 | controller | ok: "/var/lib/zuul/builds/ad6807756a7d4d12a88ffa933cf2695c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml" 2025-12-05 15:34:11.863273 | controller | included: /var/lib/zuul/builds/ad6807756a7d4d12a88ffa933cf2695c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml 2025-12-05 15:34:11.885910 | 2025-12-05 15:34:11.886009 | TASK [ensure-pip : Install Python 3 pip] 2025-12-05 15:34:13.687589 | controller | changed 2025-12-05 15:34:13.692888 | 2025-12-05 15:34:13.692952 | TASK [ensure-pip : Check for EPEL repository] 2025-12-05 15:34:13.727622 | controller | skipping: Conditional result was False 2025-12-05 15:34:13.734043 | 2025-12-05 15:34:13.734122 | TASK [ensure-pip : Fail with instructions when EPEL is not installed] 2025-12-05 15:34:13.778117 | controller | skipping: Conditional result was False 2025-12-05 15:34:13.784348 | 2025-12-05 15:34:13.784413 | TASK [ensure-pip : Install Python 2 pip] 2025-12-05 15:34:13.828089 | controller | skipping: Conditional result was False 2025-12-05 15:34:13.836247 | 2025-12-05 15:34:13.836359 | TASK [ensure-pip : Ensure setuptools] 2025-12-05 15:34:13.851398 | controller | skipping: Conditional result was False 2025-12-05 15:34:13.860852 | 2025-12-05 15:34:13.860939 | TASK [ensure-pip : Check for ensurepip module] 2025-12-05 15:34:14.393582 | controller | skipping: Conditional result was False 2025-12-05 15:34:14.399679 | 2025-12-05 15:34:14.399769 | TASK [ensure-pip : Ensure python3-venv] 2025-12-05 15:34:14.429246 | controller | skipping: Conditional result was False 2025-12-05 15:34:14.434899 | 2025-12-05 15:34:14.434961 | TASK [ensure-pip : Install pip from source] 2025-12-05 15:34:14.469140 | controller | skipping: Conditional result was False 2025-12-05 15:34:14.475367 | 2025-12-05 15:34:14.475429 | TASK [ensure-pip : Probe for venv python full path] 2025-12-05 10:34:14.715599 | controller | /usr/bin/python3 2025-12-05 15:34:15.011375 | controller | ok: Runtime: 0:00:00.005953 2025-12-05 15:34:15.024506 | 2025-12-05 15:34:15.024677 | TASK [ensure-pip : Set host default] 2025-12-05 15:34:15.112373 | controller | ok 2025-12-05 15:34:15.121989 | 2025-12-05 15:34:15.122171 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2025-12-05 15:34:15.183134 | controller | ok 2025-12-05 15:34:15.193984 | 2025-12-05 15:34:15.194053 | TASK [bindep : Install bindep into temporary venv] 2025-12-05 15:34:19.415514 | controller | changed 2025-12-05 15:34:19.421168 | 2025-12-05 15:34:19.421232 | TASK [bindep : Define bindep_command] 2025-12-05 15:34:19.455328 | controller | ok 2025-12-05 15:34:19.462688 | 2025-12-05 15:34:19.462773 | LOOP [bindep : Include package tasks] 2025-12-05 15:34:19.533283 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt" 2025-12-05 15:34:19.533586 | controller | ok: All items complete 2025-12-05 15:34:19.533624 | 2025-12-05 15:34:19.546308 | controller | included: /var/lib/zuul/builds/ad6807756a7d4d12a88ffa933cf2695c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml 2025-12-05 15:34:19.557907 | 2025-12-05 15:34:19.557978 | TASK [bindep : Define bindep_run fact] 2025-12-05 15:34:19.597643 | controller | ok 2025-12-05 15:34:19.602918 | 2025-12-05 15:34:19.602983 | TASK [bindep : Get list of packages to install from bindep] 2025-12-05 10:34:21.010521 | controller | podman 2025-12-05 10:34:21.053006 | controller | python3-jmespath 2025-12-05 10:34:21.053151 | controller | python3-libvirt 2025-12-05 10:34:21.053246 | controller | python3-lxml 2025-12-05 10:34:21.053264 | controller | python3-netaddr 2025-12-05 15:34:21.142909 | controller | ok: Runtime: 0:00:01.194965 2025-12-05 15:34:21.155782 | 2025-12-05 15:34:21.155924 | TASK [bindep : Install distro packages from bindep] 2025-12-05 15:35:25.999162 | controller | changed 2025-12-05 15:35:26.006279 | 2025-12-05 15:35:26.006376 | TASK [bindep : Check that packages are installed] 2025-12-05 15:35:27.559496 | controller | ok: Runtime: 0:00:01.203274 2025-12-05 15:35:27.565554 | 2025-12-05 15:35:27.565615 | TASK [bindep : Fail if we cannot install all packages] 2025-12-05 15:35:27.619934 | controller | skipping: Conditional result was False 2025-12-05 15:35:27.630613 | 2025-12-05 15:35:27.630745 | TASK [Run test-setup role] 2025-12-05 15:35:27.652414 | controller | ok 2025-12-05 15:35:27.705383 | 2025-12-05 15:35:27.705498 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-12-05 15:35:27.930355 | controller | ok 2025-12-05 15:35:27.936813 | 2025-12-05 15:35:27.936889 | TASK [test-setup : Run tools/test-setup.sh] 2025-12-05 15:35:28.491420 | controller | skipping: Conditional result was False 2025-12-05 15:35:28.519182 | 2025-12-05 15:35:28.519293 | TASK [bindep : Remove bindep temp dir] 2025-12-05 15:35:28.876498 | controller | ok 2025-12-05 15:35:28.915111 | 2025-12-05 15:35:28.915203 | PLAY RECAP 2025-12-05 15:35:28.915241 | controller | ok: 22 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0 2025-12-05 15:35:28.915261 | 2025-12-05 15:35:29.025041 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-12-05 15:35:29.026015 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2025-12-05 15:35:29.668117 | 2025-12-05 15:35:29.668224 | PLAY [all] 2025-12-05 15:35:29.687588 | 2025-12-05 15:35:29.687664 | TASK [Abort when test_command variable is undefined] 2025-12-05 15:35:29.711821 | controller | skipping: Conditional result was False 2025-12-05 15:35:29.717269 | 2025-12-05 15:35:29.717344 | TASK [Convert test_command to list] 2025-12-05 15:35:29.773231 | controller | skipping: Conditional result was False 2025-12-05 15:35:29.785145 | 2025-12-05 15:35:29.785232 | TASK [Use test_command list] 2025-12-05 15:35:29.883222 | controller | ok 2025-12-05 15:35:29.890324 | 2025-12-05 15:35:29.890420 | LOOP [Run test_command] 2025-12-05 15:35:30.289541 | controller | no check to run 2025-12-05 15:35:30.289743 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.007486 2025-12-05 15:35:30.313096 | 2025-12-05 15:35:30.313194 | PLAY RECAP 2025-12-05 15:35:30.313232 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-12-05 15:35:30.313252 | 2025-12-05 15:35:30.422523 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2025-12-05 15:35:30.423718 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-05 15:35:31.197013 | 2025-12-05 15:35:31.197273 | PLAY [all] 2025-12-05 15:35:31.253446 | 2025-12-05 15:35:31.253581 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-12-05 15:35:31.608482 | controller | changed: non-zero return code 2025-12-05 15:35:31.617154 | 2025-12-05 15:35:31.617279 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-12-05 15:35:31.632997 | controller | skipping: Conditional result was False 2025-12-05 15:35:31.639591 | 2025-12-05 15:35:31.639667 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-12-05 15:35:31.670944 | 2025-12-05 15:35:31.671104 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-12-05 15:35:31.702979 | 2025-12-05 15:35:31.703146 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-12-05 15:35:31.717004 | controller | skipping: Conditional result was False 2025-12-05 15:35:31.725206 | 2025-12-05 15:35:31.725317 | LOOP [fetch-subunit-output : Generate subunit file] 2025-12-05 15:35:31.746588 | 2025-12-05 15:35:31.746850 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-12-05 15:35:31.762993 | controller | skipping: Conditional result was False 2025-12-05 15:35:31.770802 | 2025-12-05 15:35:31.770901 | TASK [fetch-subunit-output : Remove the temporary file] 2025-12-05 15:35:31.785428 | controller | skipping: Conditional result was False 2025-12-05 15:35:31.791875 | 2025-12-05 15:35:31.791991 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-12-05 15:35:31.807373 | controller | skipping: Conditional result was False 2025-12-05 15:35:31.836570 | 2025-12-05 15:35:31.836696 | PLAY RECAP 2025-12-05 15:35:31.836759 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-12-05 15:35:31.836784 | 2025-12-05 15:35:31.974107 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-05 15:35:31.974940 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-12-05 15:35:32.746451 | 2025-12-05 15:35:32.746589 | PLAY [all] 2025-12-05 15:35:32.784909 | 2025-12-05 15:35:32.785060 | TASK [include_role : fetch-output] 2025-12-05 15:35:32.835852 | controller | ok 2025-12-05 15:35:32.874476 | 2025-12-05 15:35:32.874607 | TASK [fetch-output : Set log path for multiple nodes] 2025-12-05 15:35:32.930339 | controller | skipping: Conditional result was False 2025-12-05 15:35:32.936230 | 2025-12-05 15:35:32.936361 | TASK [fetch-output : Set log path for single node] 2025-12-05 15:35:32.965446 | controller | ok 2025-12-05 15:35:32.971361 | 2025-12-05 15:35:32.971436 | LOOP [fetch-output : Ensure local output dirs] 2025-12-05 15:35:33.540122 | controller -> localhost | ok: "/var/lib/zuul/builds/ad6807756a7d4d12a88ffa933cf2695c/work/logs" 2025-12-05 15:35:33.816601 | controller -> localhost | changed: "/var/lib/zuul/builds/ad6807756a7d4d12a88ffa933cf2695c/work/artifacts" 2025-12-05 15:35:34.150435 | controller -> localhost | changed: "/var/lib/zuul/builds/ad6807756a7d4d12a88ffa933cf2695c/work/docs" 2025-12-05 15:35:34.163549 | 2025-12-05 15:35:34.163662 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-12-05 15:35:34.900224 | controller | changed: .d..t...... ./ 2025-12-05 15:35:34.900508 | controller | changed: All items complete 2025-12-05 15:35:34.900553 | 2025-12-05 15:35:35.510698 | controller | changed: .d..t...... ./ 2025-12-05 15:35:36.402227 | controller | changed: .d..t...... ./ 2025-12-05 15:35:36.418652 | 2025-12-05 15:35:36.418765 | TASK [include_role : fetch-output-openshift] 2025-12-05 15:35:36.453941 | controller | skipping: Conditional result was False 2025-12-05 15:35:36.461137 | 2025-12-05 15:35:36.461220 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-12-05 15:35:37.049893 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.015320 2025-12-05 15:35:37.380996 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.019541 2025-12-05 15:35:37.488998 | 2025-12-05 15:35:37.489120 | PLAY [all] 2025-12-05 15:35:37.543902 | 2025-12-05 15:35:37.544045 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-12-05 15:35:38.229386 | controller | changed 2025-12-05 15:35:38.276273 | 2025-12-05 15:35:38.276386 | PLAY RECAP 2025-12-05 15:35:38.276430 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-12-05 15:35:38.276450 | 2025-12-05 15:35:38.424461 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-12-05 15:35:38.425501 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-12-05 15:35:39.767791 | 2025-12-05 15:35:39.767895 | PLAY [localhost] 2025-12-05 15:35:39.786059 | 2025-12-05 15:35:39.786138 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-12-05 15:35:40.223202 | localhost | changed 2025-12-05 15:35:40.228301 | 2025-12-05 15:35:40.228540 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-12-05 15:35:40.267101 | localhost | ok 2025-12-05 15:35:40.275217 | 2025-12-05 15:35:40.275282 | TASK [add-fileserver : Create SSH private key tempfile] 2025-12-05 15:35:40.692210 | localhost | changed 2025-12-05 15:35:40.704440 | 2025-12-05 15:35:40.704546 | TASK [add-fileserver : Create SSH private key from secret] 2025-12-05 15:35:41.764476 | localhost | changed 2025-12-05 15:35:41.780635 | 2025-12-05 15:35:41.780842 | TASK [add-fileserver : Add fileserver ssh key] 2025-12-05 15:35:42.402173 | localhost | Identity added: /var/lib/zuul/builds/ad6807756a7d4d12a88ffa933cf2695c/work/tmp/ansible.rs0gl72x (/var/lib/zuul/builds/ad6807756a7d4d12a88ffa933cf2695c/work/tmp/ansible.rs0gl72x) 2025-12-05 15:35:42.402361 | localhost | ok: Runtime: 0:00:00.009974 2025-12-05 15:35:42.406926 | 2025-12-05 15:35:42.406996 | TASK [add-fileserver : Remove SSH private key from disk] 2025-12-05 15:35:42.702578 | localhost | ok: Runtime: 0:00:00.006192 2025-12-05 15:35:42.708943 | 2025-12-05 15:35:42.709067 | TASK [add-fileserver : Add fileserver to inventory] 2025-12-05 15:35:42.768517 | localhost | changed 2025-12-05 15:35:42.773353 | 2025-12-05 15:35:42.773624 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-12-05 15:35:43.326818 | localhost | changed 2025-12-05 15:35:43.348530 | 2025-12-05 15:35:43.348615 | PLAY [localhost] 2025-12-05 15:35:43.365521 | 2025-12-05 15:35:43.365634 | TASK [Generate bulk log download script] 2025-12-05 15:35:43.394504 | localhost | ok 2025-12-05 15:35:43.429466 | 2025-12-05 15:35:43.429590 | TASK [local-log-download : Check API endpoint is defined] 2025-12-05 15:35:43.507282 | localhost | ok: All assertions passed 2025-12-05 15:35:43.520005 | 2025-12-05 15:35:43.520136 | TASK [local-log-download : Create download script] 2025-12-05 15:35:44.138107 | localhost -> localhost | changed 2025-12-05 15:35:44.172527 | 2025-12-05 15:35:44.172650 | TASK [Register quick-download link] 2025-12-05 15:35:44.199077 | localhost | ok 2025-12-05 15:35:44.226423 | 2025-12-05 15:35:44.226516 | PLAY [logserver.rdoproject.org] 2025-12-05 15:35:44.236893 | 2025-12-05 15:35:44.236955 | TASK [Set zuul-log-path fact] 2025-12-05 15:35:44.253962 | logserver.rdoproject.org | ok 2025-12-05 15:35:44.267832 | 2025-12-05 15:35:44.267926 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-05 15:35:44.305751 | logserver.rdoproject.org | ok 2025-12-05 15:35:44.312361 | 2025-12-05 15:35:44.312430 | TASK [upload-logs : Create log directories] 2025-12-05 15:35:44.976717 | logserver.rdoproject.org | changed 2025-12-05 15:35:44.980879 | 2025-12-05 15:35:44.980956 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-12-05 15:35:45.370776 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.005284 2025-12-05 15:35:45.375299 | 2025-12-05 15:35:45.375376 | TASK [upload-logs : Upload logs to log server] 2025-12-05 15:35:46.224062 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-12-05 15:35:46.226967 | 2025-12-05 15:35:46.227029 | LOOP [upload-logs : Compress console log and json output] 2025-12-05 15:35:46.306664 | logserver.rdoproject.org | skipping: Conditional result was False 2025-12-05 15:35:46.313401 | logserver.rdoproject.org | skipping: Conditional result was False 2025-12-05 15:35:46.329084 | 2025-12-05 15:35:46.329223 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-05 15:35:46.376700 | logserver.rdoproject.org | skipping: Conditional result was False 2025-12-05 15:35:46.376962 | 2025-12-05 15:35:46.380346 | logserver.rdoproject.org | skipping: Conditional result was False 2025-12-05 15:35:46.389549 | 2025-12-05 15:35:46.389669 | LOOP [upload-logs : Upload console log and json output]