2026-06-11 10:24:53.457141 | Job console starting 2026-06-11 10:24:53.589975 | Updating git repos 2026-06-11 10:24:53.632954 | Cloning repos into workspace 2026-06-11 10:24:54.254370 | Restoring repo states 2026-06-11 10:24:54.265934 | Merging changes 2026-06-11 10:24:55.447611 | Checking out repos 2026-06-11 10:24:55.571597 | Preparing playbooks 2026-06-11 10:24:59.539613 | Running Ansible setup 2026-06-11 10:25:04.205757 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-06-11 10:25:04.824872 | 2026-06-11 10:25:04.825007 | PLAY [localhost] 2026-06-11 10:25:04.832691 | 2026-06-11 10:25:04.832767 | TASK [Gathering Facts] 2026-06-11 10:25:05.903518 | localhost | ok 2026-06-11 10:25:05.911740 | 2026-06-11 10:25:05.911882 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-11 10:25:06.426938 | localhost -> localhost | changed 2026-06-11 10:25:06.436415 | 2026-06-11 10:25:06.436631 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-11 10:25:08.577207 | localhost -> localhost | changed 2026-06-11 10:25:08.587390 | 2026-06-11 10:25:08.587526 | TASK [Setup log path fact] 2026-06-11 10:25:08.608152 | localhost | ok 2026-06-11 10:25:08.620629 | 2026-06-11 10:25:08.620718 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-11 10:25:08.659322 | localhost | ok 2026-06-11 10:25:08.666238 | 2026-06-11 10:25:08.666325 | TASK [emit-job-header : Print job information] 2026-06-11 10:25:08.726711 | # Job Information 2026-06-11 10:25:08.726964 | Ansible Version: 2.16.18 2026-06-11 10:25:08.727022 | Job: cifmw-molecule-ci_dcn_site 2026-06-11 10:25:08.727064 | Pipeline: github-check 2026-06-11 10:25:08.727102 | Executor: 38.129.56.84 2026-06-11 10:25:08.727138 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3963 2026-06-11 10:25:08.727178 | Log URL (when completed): https://gateway-cloud-softwarefactory.apps.ocp.cloud.ci.centos.org/logs/ec4/rdoproject.org/ec47da7c0bbd433aae6675c197fe1170/ 2026-06-11 10:25:08.727221 | Event ID: 8e25a600-657f-11f1-9927-e5eefd3bdaa9 2026-06-11 10:25:08.732939 | 2026-06-11 10:25:08.733072 | LOOP [emit-job-header : Print node information] 2026-06-11 10:25:08.886013 | localhost | ok: 2026-06-11 10:25:08.886541 | localhost | # Node Information 2026-06-11 10:25:08.886656 | localhost | Inventory Hostname: controller 2026-06-11 10:25:08.886756 | localhost | Hostname: np0000070594 2026-06-11 10:25:08.886845 | localhost | Username: zuul 2026-06-11 10:25:08.886995 | localhost | Distro: CentOS 9 2026-06-11 10:25:08.887045 | localhost | Provider: vexxhost-nodepool-tripleo 2026-06-11 10:25:08.887129 | localhost | Region: RegionOne 2026-06-11 10:25:08.887165 | localhost | Label: cloud-centos-9-stream-tripleo 2026-06-11 10:25:08.887201 | localhost | Product Name: OpenStack Nova 2026-06-11 10:25:08.887489 | localhost | Interface IP: 38.102.83.70 2026-06-11 10:25:08.904811 | 2026-06-11 10:25:08.905011 | PLAY [all] 2026-06-11 10:25:08.916908 | 2026-06-11 10:25:08.917132 | TASK [Gather network facts] 2026-06-11 10:25:09.379631 | controller | ok 2026-06-11 10:25:09.387562 | 2026-06-11 10:25:09.387652 | TASK [include_role : start-zuul-console] 2026-06-11 10:25:09.414401 | controller | ok 2026-06-11 10:25:09.427153 | 2026-06-11 10:25:09.427311 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-06-11 10:25:09.797959 | controller | ok 2026-06-11 10:25:09.810268 | 2026-06-11 10:25:09.810430 | TASK [include_role : add-build-sshkey] 2026-06-11 10:25:09.844085 | controller | ok 2026-06-11 10:25:09.866123 | 2026-06-11 10:25:09.866318 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-11 10:25:10.240633 | controller -> localhost | ok 2026-06-11 10:25:10.249538 | 2026-06-11 10:25:10.249667 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-11 10:25:10.293209 | controller | ok 2026-06-11 10:25:10.338869 | controller | included: /var/lib/zuul/builds/ec47da7c0bbd433aae6675c197fe1170/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-11 10:25:10.345213 | 2026-06-11 10:25:10.345376 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-11 10:25:11.042374 | controller -> localhost | Generating public/private rsa key pair. 2026-06-11 10:25:11.042563 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ec47da7c0bbd433aae6675c197fe1170/work/ec47da7c0bbd433aae6675c197fe1170_id_rsa 2026-06-11 10:25:11.042593 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ec47da7c0bbd433aae6675c197fe1170/work/ec47da7c0bbd433aae6675c197fe1170_id_rsa.pub 2026-06-11 10:25:11.042614 | controller -> localhost | The key fingerprint is: 2026-06-11 10:25:11.042633 | controller -> localhost | SHA256:7NpoGMUUy5lhuHSR2Dd2lmPJrXYfglUr9Op3AZGguQU zuul-build-sshkey 2026-06-11 10:25:11.042652 | controller -> localhost | The key's randomart image is: 2026-06-11 10:25:11.042669 | controller -> localhost | +---[RSA 3072]----+ 2026-06-11 10:25:11.042687 | controller -> localhost | | +=+ E =ooo | 2026-06-11 10:25:11.042705 | controller -> localhost | | +o==+ %.oo.. | 2026-06-11 10:25:11.042722 | controller -> localhost | | . ==o B *..o | 2026-06-11 10:25:11.042740 | controller -> localhost | | . o. * ooo | 2026-06-11 10:25:11.042757 | controller -> localhost | | . So ..o o | 2026-06-11 10:25:11.042774 | controller -> localhost | | . . . . .| 2026-06-11 10:25:11.042791 | controller -> localhost | | o . . . .| 2026-06-11 10:25:11.042809 | controller -> localhost | | . .+ . . | 2026-06-11 10:25:11.042826 | controller -> localhost | | .o . | 2026-06-11 10:25:11.042843 | controller -> localhost | +----[SHA256]-----+ 2026-06-11 10:25:11.042909 | controller -> localhost | ok: Runtime: 0:00:00.164383 2026-06-11 10:25:11.046436 | 2026-06-11 10:25:11.046492 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-11 10:25:11.076283 | controller | ok 2026-06-11 10:25:11.084590 | controller | included: /var/lib/zuul/builds/ec47da7c0bbd433aae6675c197fe1170/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-11 10:25:11.091003 | 2026-06-11 10:25:11.091061 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-11 10:25:11.124116 | controller | skipping: Conditional result was False 2026-06-11 10:25:11.128330 | 2026-06-11 10:25:11.128392 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-11 10:25:11.617360 | controller | changed 2026-06-11 10:25:11.624946 | 2026-06-11 10:25:11.625105 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-11 10:25:11.905107 | controller | ok 2026-06-11 10:25:11.912805 | 2026-06-11 10:25:11.912970 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-11 10:25:12.717883 | controller | changed 2026-06-11 10:25:12.725794 | 2026-06-11 10:25:12.726003 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-11 10:25:13.586828 | controller | changed 2026-06-11 10:25:13.594696 | 2026-06-11 10:25:13.594833 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-11 10:25:13.630984 | controller | skipping: Conditional result was False 2026-06-11 10:25:13.635551 | 2026-06-11 10:25:13.635620 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-11 10:25:14.116200 | controller -> localhost | changed 2026-06-11 10:25:14.123417 | 2026-06-11 10:25:14.123483 | TASK [add-build-sshkey : Add back temp key] 2026-06-11 10:25:14.483634 | controller -> localhost | Identity added: /var/lib/zuul/builds/ec47da7c0bbd433aae6675c197fe1170/work/ec47da7c0bbd433aae6675c197fe1170_id_rsa (zuul-build-sshkey) 2026-06-11 10:25:14.484003 | controller -> localhost | ok: Runtime: 0:00:00.018713 2026-06-11 10:25:14.491788 | 2026-06-11 10:25:14.491945 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-11 10:25:14.900695 | controller | ok 2026-06-11 10:25:14.907641 | 2026-06-11 10:25:14.907815 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-11 10:25:14.945731 | controller | skipping: Conditional result was False 2026-06-11 10:25:14.960751 | 2026-06-11 10:25:14.961020 | TASK [include_role : validate-host] 2026-06-11 10:25:15.008054 | controller | ok 2026-06-11 10:25:15.040025 | 2026-06-11 10:25:15.040211 | TASK [validate-host : Define zuul_info_dir fact] 2026-06-11 10:25:15.084037 | controller | ok 2026-06-11 10:25:15.090670 | 2026-06-11 10:25:15.090791 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-06-11 10:25:15.372609 | controller -> localhost | ok 2026-06-11 10:25:15.382171 | 2026-06-11 10:25:15.382319 | TASK [validate-host : Collect information about the host] 2026-06-11 10:25:17.177388 | controller | ok 2026-06-11 10:25:17.194670 | 2026-06-11 10:25:17.194832 | TASK [validate-host : Sanitize hostname] 2026-06-11 10:25:17.270556 | controller | ok 2026-06-11 10:25:17.278323 | 2026-06-11 10:25:17.278484 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-06-11 10:25:17.863899 | controller -> localhost | changed 2026-06-11 10:25:17.876239 | 2026-06-11 10:25:17.876379 | TASK [validate-host : Collect information about zuul worker] 2026-06-11 10:25:18.331077 | controller | ok 2026-06-11 10:25:18.339306 | 2026-06-11 10:25:18.339431 | TASK [validate-host : Write out all zuul information for each host] 2026-06-11 10:25:18.906041 | controller -> localhost | changed 2026-06-11 10:25:18.913624 | 2026-06-11 10:25:18.913688 | TASK [include_role : prepare-workspace-openshift] 2026-06-11 10:25:18.938323 | controller | skipping: Conditional result was False 2026-06-11 10:25:18.942442 | 2026-06-11 10:25:18.942508 | TASK [include_role : remove-zuul-sshkey] 2026-06-11 10:25:18.967251 | controller | skipping: Conditional result was False 2026-06-11 10:25:18.973691 | 2026-06-11 10:25:18.973798 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-11 10:25:19.225203 | controller | ok: "logs" 2026-06-11 10:25:19.225673 | controller | ok: All items complete 2026-06-11 10:25:19.225730 | 2026-06-11 10:25:19.462565 | controller | ok: "artifacts" 2026-06-11 10:25:19.680764 | controller | ok: "docs" 2026-06-11 10:25:19.691772 | 2026-06-11 10:25:19.692011 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-11 10:25:19.981498 | controller | changed: "logs" 2026-06-11 10:25:20.215156 | controller | changed: "artifacts" 2026-06-11 10:25:20.425965 | controller | changed: "docs" 2026-06-11 10:25:20.445618 | 2026-06-11 10:25:20.445887 | PLAY RECAP 2026-06-11 10:25:20.445992 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2026-06-11 10:25:20.446082 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-11 10:25:20.446125 | 2026-06-11 10:25:20.608514 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-06-11 10:25:20.610823 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-06-11 10:25:21.244932 | 2026-06-11 10:25:21.245075 | PLAY [localhost] 2026-06-11 10:25:21.254119 | 2026-06-11 10:25:21.254192 | TASK [configure-ssh-config-fips : Ensure ssh config directory] 2026-06-11 10:25:21.713016 | localhost | changed 2026-06-11 10:25:21.721752 | 2026-06-11 10:25:21.721963 | TASK [configure-ssh-config-fips : Setup ssh config] 2026-06-11 10:25:22.557459 | localhost | changed 2026-06-11 10:25:22.564074 | 2026-06-11 10:25:22.564136 | PLAY [all] 2026-06-11 10:25:22.572644 | 2026-06-11 10:25:22.572768 | TASK [include_role : prepare-workspace] 2026-06-11 10:25:22.609240 | controller | ok 2026-06-11 10:25:22.628628 | 2026-06-11 10:25:22.628777 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-11 10:25:23.072120 | controller | ok 2026-06-11 10:25:23.077259 | 2026-06-11 10:25:23.077323 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-11 10:25:24.638752 | controller | Output suppressed because no_log was given 2026-06-11 10:25:24.654160 | 2026-06-11 10:25:24.654370 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-11 10:25:24.951575 | controller | changed: "logs" 2026-06-11 10:25:25.155037 | controller | changed: "artifacts" 2026-06-11 10:25:25.377027 | controller | changed: "docs" 2026-06-11 10:25:25.390313 | 2026-06-11 10:25:25.390591 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-11 10:25:25.699830 | controller | changed: "logs" 2026-06-11 10:25:25.700405 | controller | changed: All items complete 2026-06-11 10:25:25.700496 | 2026-06-11 10:25:25.925408 | controller | changed: "artifacts" 2026-06-11 10:25:26.181458 | controller | changed: "docs" 2026-06-11 10:25:26.211104 | 2026-06-11 10:25:26.211320 | TASK [Check if worker can sudo] 2026-06-11 10:25:26.762425 | controller | ok: Runtime: 0:00:00.045342 2026-06-11 10:25:26.771767 | 2026-06-11 10:25:26.771983 | TASK [configure-mirrors : Gather needed facts] 2026-06-11 10:25:26.851049 | controller | skipping: Conditional result was False 2026-06-11 10:25:26.866755 | 2026-06-11 10:25:26.867047 | TASK [configure-mirrors : Set up infrastructure mirrors] 2026-06-11 10:25:26.966205 | controller | ok 2026-06-11 10:25:26.981682 | controller | included: /var/lib/zuul/builds/ec47da7c0bbd433aae6675c197fe1170/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml 2026-06-11 10:25:26.991989 | 2026-06-11 10:25:26.992112 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir] 2026-06-11 10:25:27.308954 | controller | ok 2026-06-11 10:25:27.318904 | 2026-06-11 10:25:27.319045 | LOOP [configure-mirrors : Include OS-specific variables] 2026-06-11 10:25:27.417117 | controller | ok: "/var/lib/zuul/builds/ec47da7c0bbd433aae6675c197fe1170/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml" 2026-06-11 10:25:27.429512 | 2026-06-11 10:25:27.429723 | TASK [configure-mirrors : Install /etc/pip.conf configuration] 2026-06-11 10:25:28.338096 | controller | changed 2026-06-11 10:25:28.350055 | 2026-06-11 10:25:28.350245 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors] 2026-06-11 10:25:28.547233 | controller | ok: "/var/lib/zuul/builds/ec47da7c0bbd433aae6675c197fe1170/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml" 2026-06-11 10:25:28.547434 | controller | ok: All items complete 2026-06-11 10:25:28.547467 | 2026-06-11 10:25:28.610059 | controller | included: /var/lib/zuul/builds/ec47da7c0bbd433aae6675c197fe1170/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml 2026-06-11 10:25:28.616686 | 2026-06-11 10:25:28.616811 | LOOP [configure-mirrors : Install CentOS 9 repository files] 2026-06-11 10:25:29.565955 | controller | changed: "etc/yum.repos.d/centos.repo" 2026-06-11 10:25:31.210731 | controller | changed: "etc/yum.repos.d/centos-addons.repo" 2026-06-11 10:25:31.229242 | 2026-06-11 10:25:31.229449 | TASK [configure-mirrors : Disable deltrarpm] 2026-06-11 10:25:31.700620 | controller | changed: section and option added 2026-06-11 10:25:31.723092 | 2026-06-11 10:25:31.723237 | LOOP [configure-mirrors : Update yum/dnf cache] 2026-06-11 10:25:32.466021 | controller | 29 files removed 2026-06-11 10:25:32.476982 | controller | ok: Item: dnf clean all Runtime: 0:00:00.411820 2026-06-11 10:25:32.477083 | controller | changed: All items complete 2026-06-11 10:25:32.477107 | 2026-06-11 10:25:44.281153 | controller | Loaded plugins: builddep, changelog, config-manager, copr, debug, debuginfo-install, download, generate_completion_cache, groups-manager, needs-restarting, playground, repoclosure, repodiff, repograph, repomanage, reposync, system-upgrade 2026-06-11 10:25:44.281325 | controller | DNF version: 4.14.0 2026-06-11 10:25:44.281355 | controller | cachedir: /var/cache/dnf 2026-06-11 10:25:44.281376 | controller | Making cache files for all metadata files. 2026-06-11 10:25:44.281394 | controller | baseos: has expired and will be refreshed. 2026-06-11 10:25:44.281421 | controller | appstream: has expired and will be refreshed. 2026-06-11 10:25:44.281439 | controller | crb: has expired and will be refreshed. 2026-06-11 10:25:44.281455 | controller | extras-common: has expired and will be refreshed. 2026-06-11 10:25:44.281471 | controller | repo: downloading from remote: baseos 2026-06-11 10:25:44.281487 | controller | CentOS Stream 9 - BaseOS 78 MB/s | 8.9 MB 00:00 2026-06-11 10:25:44.281503 | controller | baseos: using metadata from Tue 09 Jun 2026 05:37:52 PM EDT. 2026-06-11 10:25:44.281519 | controller | repo: downloading from remote: appstream 2026-06-11 10:25:44.281535 | controller | CentOS Stream 9 - AppStream 83 MB/s | 28 MB 00:00 2026-06-11 10:25:44.281551 | controller | appstream: using metadata from Tue 09 Jun 2026 05:41:28 PM EDT. 2026-06-11 10:25:44.281567 | controller | repo: downloading from remote: crb 2026-06-11 10:25:44.281583 | controller | CentOS Stream 9 - CRB 86 MB/s | 8.3 MB 00:00 2026-06-11 10:25:44.281599 | controller | crb: using metadata from Tue 09 Jun 2026 05:46:02 PM EDT. 2026-06-11 10:25:44.281615 | controller | repo: downloading from remote: extras-common 2026-06-11 10:25:44.281631 | controller | CentOS Stream 9 - Extras packages 1.6 MB/s | 21 kB 00:00 2026-06-11 10:25:44.281647 | controller | extras-common: using metadata from Wed 10 Jun 2026 10:05:57 AM EDT. 2026-06-11 10:25:44.281663 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)' 2026-06-11 10:25:44.281679 | controller | Completion plugin: Generating completion cache... 2026-06-11 10:25:44.281695 | controller | Metadata cache created. 2026-06-11 10:25:44.281720 | controller | ok: Item: dnf makecache -v Runtime: 0:00:11.543654 2026-06-11 10:25:44.286478 | 2026-06-11 10:25:44.286605 | PLAY RECAP 2026-06-11 10:25:44.286652 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-11 10:25:44.286679 | localhost | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-11 10:25:44.286696 | 2026-06-11 10:25:44.434029 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-06-11 10:25:44.435232 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-06-11 10:25:45.108126 | 2026-06-11 10:25:45.108414 | PLAY [all] 2026-06-11 10:25:45.118967 | 2026-06-11 10:25:45.119040 | TASK [Install binary dependencies] 2026-06-11 10:25:45.190737 | controller | ok 2026-06-11 10:25:45.212310 | 2026-06-11 10:25:45.212417 | TASK [bindep : Include find tasks] 2026-06-11 10:25:45.265639 | controller | ok 2026-06-11 10:25:45.278271 | controller | included: /var/lib/zuul/builds/ec47da7c0bbd433aae6675c197fe1170/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-06-11 10:25:45.287230 | 2026-06-11 10:25:45.287313 | TASK [bindep : Look for bindep.txt] 2026-06-11 10:25:45.750915 | controller | ok 2026-06-11 10:25:45.755636 | 2026-06-11 10:25:45.755706 | TASK [bindep : Define bindep_file fact] 2026-06-11 10:25:45.797306 | controller | ok 2026-06-11 10:25:45.806122 | 2026-06-11 10:25:45.806253 | TASK [bindep : Look for other-requirements.txt] 2026-06-11 10:25:45.842182 | controller | skipping: Conditional result was False 2026-06-11 10:25:45.848827 | 2026-06-11 10:25:45.848997 | TASK [bindep : Define bindep_file fact] 2026-06-11 10:25:45.886169 | controller | skipping: Conditional result was False 2026-06-11 10:25:45.894962 | 2026-06-11 10:25:45.895107 | TASK [bindep : Look for bindep fallback file] 2026-06-11 10:25:45.921759 | controller | skipping: Conditional result was False 2026-06-11 10:25:45.930511 | 2026-06-11 10:25:45.930656 | TASK [bindep : Define bindep_file fact] 2026-06-11 10:25:45.957979 | controller | skipping: Conditional result was False 2026-06-11 10:25:45.967252 | 2026-06-11 10:25:45.967390 | TASK [bindep : Include bindep tasks] 2026-06-11 10:25:46.021170 | controller | ok 2026-06-11 10:25:46.032666 | controller | included: /var/lib/zuul/builds/ec47da7c0bbd433aae6675c197fe1170/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml 2026-06-11 10:25:46.048920 | 2026-06-11 10:25:46.049045 | TASK [bindep : Look for bindep command] 2026-06-11 10:25:46.085707 | controller | skipping: Conditional result was False 2026-06-11 10:25:46.096526 | 2026-06-11 10:25:46.096666 | TASK [bindep : Check for system bindep] 2026-06-11 10:25:46.641193 | controller | ok: Runtime: 0:00:00.003939 2026-06-11 10:25:46.649488 | 2026-06-11 10:25:46.649615 | TASK [bindep : Define bindep_command fact] 2026-06-11 10:25:46.688228 | controller | skipping: Conditional result was False 2026-06-11 10:25:46.698133 | 2026-06-11 10:25:46.698293 | TASK [bindep : Include install tasks] 2026-06-11 10:25:46.753005 | controller | ok 2026-06-11 10:25:46.764511 | controller | included: /var/lib/zuul/builds/ec47da7c0bbd433aae6675c197fe1170/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml 2026-06-11 10:25:46.780257 | 2026-06-11 10:25:46.780382 | TASK [bindep : Create temp dir for bindep] 2026-06-11 10:25:47.213418 | controller | changed 2026-06-11 10:25:47.221373 | 2026-06-11 10:25:47.221502 | TASK [Ensure we have pip dependencies] 2026-06-11 10:25:47.243489 | controller | ok 2026-06-11 10:25:47.284052 | 2026-06-11 10:25:47.284259 | TASK [ensure-pip : Check if pip is installed] 2026-06-11 06:25:47.547033 | controller | /usr/bin/pip3 2026-06-11 06:25:47.594645 | controller | /usr/bin/python3: No module named wheel 2026-06-11 10:25:47.816362 | controller | ok: Runtime: 0:00:00.058695 2026-06-11 10:25:47.825650 | 2026-06-11 10:25:47.825942 | LOOP [ensure-pip : Install pip from packages] 2026-06-11 10:25:47.862511 | controller | ok: "/var/lib/zuul/builds/ec47da7c0bbd433aae6675c197fe1170/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml" 2026-06-11 10:25:47.888559 | controller | included: /var/lib/zuul/builds/ec47da7c0bbd433aae6675c197fe1170/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml 2026-06-11 10:25:47.958809 | 2026-06-11 10:25:47.959009 | TASK [ensure-pip : Install Python 3 pip] 2026-06-11 10:25:49.410523 | controller | ok: Nothing to do 2026-06-11 10:25:49.419106 | 2026-06-11 10:25:49.419278 | TASK [ensure-pip : Install Python3-wheel on older CentOS] 2026-06-11 10:25:51.188314 | controller | changed 2026-06-11 10:25:51.197213 | 2026-06-11 10:25:51.197359 | TASK [ensure-pip : Check for EPEL repository] 2026-06-11 10:25:51.255637 | controller | skipping: Conditional result was False 2026-06-11 10:25:51.265794 | 2026-06-11 10:25:51.266026 | TASK [ensure-pip : Fail with instructions when EPEL is not installed] 2026-06-11 10:25:51.339730 | controller | skipping: Conditional result was False 2026-06-11 10:25:51.354413 | 2026-06-11 10:25:51.354653 | TASK [ensure-pip : Install Python 2 pip] 2026-06-11 10:25:51.402932 | controller | skipping: Conditional result was False 2026-06-11 10:25:51.415485 | 2026-06-11 10:25:51.415746 | TASK [ensure-pip : Ensure setuptools] 2026-06-11 10:25:51.442744 | controller | skipping: Conditional result was False 2026-06-11 10:25:51.457006 | 2026-06-11 10:25:51.457221 | TASK [ensure-pip : Check for ensurepip module] 2026-06-11 10:25:51.993150 | controller | skipping: Conditional result was False 2026-06-11 10:25:52.005047 | 2026-06-11 10:25:52.005207 | TASK [ensure-pip : Ensure python3-venv] 2026-06-11 10:25:52.033429 | controller | skipping: Conditional result was False 2026-06-11 10:25:52.044174 | 2026-06-11 10:25:52.044323 | TASK [ensure-pip : Install pip from source] 2026-06-11 10:25:52.081262 | controller | skipping: Conditional result was False 2026-06-11 10:25:52.091700 | 2026-06-11 10:25:52.091846 | TASK [ensure-pip : Probe for venv python full path] 2026-06-11 06:25:52.385443 | controller | /usr/bin/python3 2026-06-11 10:25:52.638526 | controller | ok: Runtime: 0:00:00.004900 2026-06-11 10:25:52.647078 | 2026-06-11 10:25:52.647210 | TASK [ensure-pip : Set host default] 2026-06-11 10:25:52.742311 | controller | ok 2026-06-11 10:25:52.750771 | 2026-06-11 10:25:52.750938 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2026-06-11 10:25:52.842093 | controller | ok 2026-06-11 10:25:52.852966 | 2026-06-11 10:25:52.853052 | TASK [bindep : Install bindep into temporary venv] 2026-06-11 10:25:57.271989 | controller | changed 2026-06-11 10:25:57.280641 | 2026-06-11 10:25:57.280775 | TASK [bindep : Define bindep_command] 2026-06-11 10:25:57.326040 | controller | ok 2026-06-11 10:25:57.334500 | 2026-06-11 10:25:57.334631 | LOOP [bindep : Include package tasks] 2026-06-11 10:25:57.412553 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt" 2026-06-11 10:25:57.412923 | controller | ok: All items complete 2026-06-11 10:25:57.412987 | 2026-06-11 10:25:57.426297 | controller | included: /var/lib/zuul/builds/ec47da7c0bbd433aae6675c197fe1170/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml 2026-06-11 10:25:57.448632 | 2026-06-11 10:25:57.448771 | TASK [bindep : Define bindep_run fact] 2026-06-11 10:25:57.493543 | controller | ok 2026-06-11 10:25:57.502810 | 2026-06-11 10:25:57.503135 | TASK [bindep : Get list of packages to install from bindep] 2026-06-11 06:25:58.910702 | controller | podman 2026-06-11 06:25:58.910809 | controller | python3-jmespath 2026-06-11 06:25:58.910815 | controller | python3-libvirt 2026-06-11 06:25:58.910820 | controller | python3-lxml 2026-06-11 06:25:58.910824 | controller | python3-netaddr 2026-06-11 10:25:59.045674 | controller | ok: Runtime: 0:00:01.153422 2026-06-11 10:25:59.054934 | 2026-06-11 10:25:59.055106 | TASK [bindep : Install distro packages from bindep] 2026-06-11 10:26:47.730189 | controller | changed 2026-06-11 10:26:47.734269 | 2026-06-11 10:26:47.734329 | TASK [bindep : Check that packages are installed] 2026-06-11 10:26:49.523438 | controller | ok: Runtime: 0:00:01.055587 2026-06-11 10:26:49.534193 | 2026-06-11 10:26:49.534343 | TASK [bindep : Fail if we cannot install all packages] 2026-06-11 10:26:49.572575 | controller | skipping: Conditional result was False 2026-06-11 10:26:49.594506 | 2026-06-11 10:26:49.594712 | TASK [Run test-setup role] 2026-06-11 10:26:49.642599 | controller | ok 2026-06-11 10:26:49.660886 | 2026-06-11 10:26:49.661020 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-06-11 10:26:49.897563 | controller | ok 2026-06-11 10:26:49.905971 | 2026-06-11 10:26:49.906110 | TASK [test-setup : Run tools/test-setup.sh] 2026-06-11 10:26:50.448216 | controller | skipping: Conditional result was False 2026-06-11 10:26:50.469177 | 2026-06-11 10:26:50.469284 | TASK [bindep : Remove bindep temp dir] 2026-06-11 10:26:50.922123 | controller | ok 2026-06-11 10:26:50.924912 | 2026-06-11 10:26:50.925024 | PLAY RECAP 2026-06-11 10:26:50.925122 | controller | ok: 23 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0 2026-06-11 10:26:50.925167 | 2026-06-11 10:26:51.116176 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-06-11 10:26:51.119032 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2026-06-11 10:26:51.820682 | 2026-06-11 10:26:51.820812 | PLAY [all] 2026-06-11 10:26:51.829836 | 2026-06-11 10:26:51.829933 | TASK [Abort when test_command variable is undefined] 2026-06-11 10:26:51.854083 | controller | skipping: Conditional result was False 2026-06-11 10:26:51.858470 | 2026-06-11 10:26:51.858539 | TASK [Convert test_command to list] 2026-06-11 10:26:51.933544 | controller | skipping: Conditional result was False 2026-06-11 10:26:51.939725 | 2026-06-11 10:26:51.939818 | TASK [Use test_command list] 2026-06-11 10:26:52.013040 | controller | ok 2026-06-11 10:26:52.024742 | 2026-06-11 10:26:52.024913 | LOOP [Run test_command] 2026-06-11 10:26:52.490428 | controller | no check to run 2026-06-11 10:26:52.490718 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.003590 2026-06-11 10:26:52.501178 | 2026-06-11 10:26:52.501292 | PLAY RECAP 2026-06-11 10:26:52.501372 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-11 10:26:52.501414 | 2026-06-11 10:26:52.694522 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2026-06-11 10:26:52.697419 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-11 10:26:53.353259 | 2026-06-11 10:26:53.353400 | PLAY [all] 2026-06-11 10:26:53.364676 | 2026-06-11 10:26:53.364756 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-06-11 10:26:53.804474 | controller | changed: non-zero return code 2026-06-11 10:26:53.813063 | 2026-06-11 10:26:53.813235 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-06-11 10:26:53.840387 | controller | skipping: Conditional result was False 2026-06-11 10:26:53.851170 | 2026-06-11 10:26:53.851381 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-06-11 10:26:53.888650 | 2026-06-11 10:26:53.888978 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-06-11 10:26:53.935354 | 2026-06-11 10:26:53.935676 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-06-11 10:26:53.963378 | controller | skipping: Conditional result was False 2026-06-11 10:26:53.975361 | 2026-06-11 10:26:53.975513 | LOOP [fetch-subunit-output : Generate subunit file] 2026-06-11 10:26:54.022375 | 2026-06-11 10:26:54.022728 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-06-11 10:26:54.050652 | controller | skipping: Conditional result was False 2026-06-11 10:26:54.061297 | 2026-06-11 10:26:54.061469 | TASK [fetch-subunit-output : Remove the temporary file] 2026-06-11 10:26:54.088799 | controller | skipping: Conditional result was False 2026-06-11 10:26:54.100143 | 2026-06-11 10:26:54.100288 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-06-11 10:26:54.127607 | controller | skipping: Conditional result was False 2026-06-11 10:26:54.141802 | 2026-06-11 10:26:54.141946 | PLAY RECAP 2026-06-11 10:26:54.142030 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-06-11 10:26:54.142071 | 2026-06-11 10:26:54.287554 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-11 10:26:54.290029 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-06-11 10:26:54.897741 | 2026-06-11 10:26:54.897913 | PLAY [all] 2026-06-11 10:26:54.907750 | 2026-06-11 10:26:54.907870 | TASK [include_role : fetch-output] 2026-06-11 10:26:54.959113 | controller | ok 2026-06-11 10:26:54.983533 | 2026-06-11 10:26:54.983754 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-11 10:26:55.050781 | controller | skipping: Conditional result was False 2026-06-11 10:26:55.056520 | 2026-06-11 10:26:55.056597 | TASK [fetch-output : Set log path for single node] 2026-06-11 10:26:55.095790 | controller | ok 2026-06-11 10:26:55.099453 | 2026-06-11 10:26:55.099516 | LOOP [fetch-output : Ensure local output dirs] 2026-06-11 10:26:55.705691 | controller -> localhost | changed: "/var/lib/zuul/builds/ec47da7c0bbd433aae6675c197fe1170/work/logs" 2026-06-11 10:26:56.046967 | controller -> localhost | changed: "/var/lib/zuul/builds/ec47da7c0bbd433aae6675c197fe1170/work/artifacts" 2026-06-11 10:26:56.296972 | controller -> localhost | changed: "/var/lib/zuul/builds/ec47da7c0bbd433aae6675c197fe1170/work/docs" 2026-06-11 10:26:56.323553 | 2026-06-11 10:26:56.323996 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-11 10:26:57.081190 | controller | changed: .d..t...... ./ 2026-06-11 10:26:57.081436 | controller | changed: All items complete 2026-06-11 10:26:57.081463 | 2026-06-11 10:26:57.639488 | controller | changed: .d..t...... ./ 2026-06-11 10:26:58.214224 | controller | changed: .d..t...... ./ 2026-06-11 10:26:58.233750 | 2026-06-11 10:26:58.233952 | TASK [include_role : fetch-output-openshift] 2026-06-11 10:26:58.261402 | controller | skipping: Conditional result was False 2026-06-11 10:26:58.267430 | 2026-06-11 10:26:58.267505 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-11 10:26:58.832400 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.015938 2026-06-11 10:26:59.091139 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.014748 2026-06-11 10:26:59.112416 | 2026-06-11 10:26:59.112536 | PLAY [all] 2026-06-11 10:26:59.122581 | 2026-06-11 10:26:59.122733 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-11 10:26:59.586735 | controller | changed 2026-06-11 10:26:59.596113 | 2026-06-11 10:26:59.596214 | PLAY RECAP 2026-06-11 10:26:59.596303 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-11 10:26:59.596349 | 2026-06-11 10:26:59.725058 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-06-11 10:26:59.727546 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2026-06-11 10:27:00.402072 | 2026-06-11 10:27:00.402187 | PLAY [localhost] 2026-06-11 10:27:00.412479 | 2026-06-11 10:27:00.412555 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-11 10:27:00.889701 | localhost | changed 2026-06-11 10:27:00.911698 | 2026-06-11 10:27:00.912181 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-11 10:27:00.957056 | localhost | ok 2026-06-11 10:27:00.970098 | 2026-06-11 10:27:00.970246 | TASK [add-fileserver : Create SSH private key tempfile] 2026-06-11 10:27:01.422717 | localhost | changed 2026-06-11 10:27:01.434384 | 2026-06-11 10:27:01.434591 | TASK [add-fileserver : Create SSH private key from secret] 2026-06-11 10:27:02.361986 | localhost | changed 2026-06-11 10:27:02.371558 | 2026-06-11 10:27:02.371705 | TASK [add-fileserver : Add fileserver ssh key] 2026-06-11 10:27:02.880298 | localhost | Identity added: /var/lib/zuul/builds/ec47da7c0bbd433aae6675c197fe1170/work/tmp/ansible.cgsnobn5 (/var/lib/zuul/builds/ec47da7c0bbd433aae6675c197fe1170/work/tmp/ansible.cgsnobn5) 2026-06-11 10:27:02.880701 | localhost | ok: Runtime: 0:00:00.022573 2026-06-11 10:27:02.888486 | 2026-06-11 10:27:02.888636 | TASK [add-fileserver : Remove SSH private key from disk] 2026-06-11 10:27:03.264379 | localhost | ok: Runtime: 0:00:00.007298 2026-06-11 10:27:03.272528 | 2026-06-11 10:27:03.272688 | TASK [add-fileserver : Add fileserver to inventory] 2026-06-11 10:27:03.348172 | localhost | changed 2026-06-11 10:27:03.357932 | 2026-06-11 10:27:03.358131 | TASK [add-fileserver : Add fileserver server to known hosts] 2026-06-11 10:27:03.831526 | localhost | changed 2026-06-11 10:27:03.844758 | 2026-06-11 10:27:03.844943 | PLAY [localhost] 2026-06-11 10:27:03.855344 | 2026-06-11 10:27:03.855474 | TASK [Generate bulk log download script] 2026-06-11 10:27:03.880191 | localhost | ok 2026-06-11 10:27:03.901658 | 2026-06-11 10:27:03.901789 | TASK [local-log-download : Check API endpoint is defined] 2026-06-11 10:27:03.943429 | localhost | ok: All assertions passed 2026-06-11 10:27:03.951406 | 2026-06-11 10:27:03.951591 | TASK [local-log-download : Create download script] 2026-06-11 10:27:04.476720 | localhost -> localhost | changed 2026-06-11 10:27:04.537082 | 2026-06-11 10:27:04.537250 | TASK [Register quick-download link] 2026-06-11 10:27:04.567082 | localhost | ok 2026-06-11 10:27:04.570092 | 2026-06-11 10:27:04.570167 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com] 2026-06-11 10:27:04.577989 | 2026-06-11 10:27:04.578474 | TASK [Set zuul-log-path fact] 2026-06-11 10:27:04.601007 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-11 10:27:04.614424 | 2026-06-11 10:27:04.614630 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-11 10:27:04.654698 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-11 10:27:04.660002 | 2026-06-11 10:27:04.660167 | TASK [upload-logs : Create log directories] 2026-06-11 10:27:05.579564 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed 2026-06-11 10:27:05.581968 | 2026-06-11 10:27:05.582043 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-11 10:27:05.873769 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.010882 2026-06-11 10:27:05.881223 | 2026-06-11 10:27:05.881370 | TASK [upload-logs : Upload logs to log server] 2026-06-11 10:27:07.147233 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | Output suppressed because no_log was given 2026-06-11 10:27:07.150388 | 2026-06-11 10:27:07.150516 | LOOP [upload-logs : Compress console log and json output]