2025-12-05 10:27:10.066552 | Job console starting...
2025-12-05 10:27:10.074243 | Updating repositories
2025-12-05 10:27:10.102572 | Preparing job workspace
2025-12-05 10:27:15.346222 | Running Ansible setup...
2025-12-05 10:27:19.222244 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 10:27:19.814030 | 
2025-12-05 10:27:19.814149 | PLAY [localhost]
2025-12-05 10:27:19.823125 | 
2025-12-05 10:27:19.823217 | TASK [Gathering Facts]
2025-12-05 10:27:20.859641 | localhost | ok
2025-12-05 10:27:20.875997 | 
2025-12-05 10:27:20.876105 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-05 10:27:21.245191 | localhost -> localhost | changed
2025-12-05 10:27:21.251089 | 
2025-12-05 10:27:21.251161 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-05 10:27:22.187028 | localhost -> localhost | changed
2025-12-05 10:27:22.196257 | 
2025-12-05 10:27:22.196325 | TASK [Setup log path fact]
2025-12-05 10:27:22.217771 | localhost | ok
2025-12-05 10:27:22.238560 | 
2025-12-05 10:27:22.239008 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 10:27:22.257984 | localhost | ok
2025-12-05 10:27:22.266670 | 
2025-12-05 10:27:22.266749 | TASK [emit-job-header : Print job information]
2025-12-05 10:27:22.296842 | # Job Information
2025-12-05 10:27:22.297006 | Ansible Version: 2.15.12
2025-12-05 10:27:22.297037 | Job: cifmw-molecule-ci_dcn_site
2025-12-05 10:27:22.297057 | Pipeline: github-check
2025-12-05 10:27:22.297074 | Executor: ze04.softwarefactory-project.io
2025-12-05 10:27:22.297091 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3533
2025-12-05 10:27:22.297109 | Log URL (when completed): https://logserver.rdoproject.org/83f/rdoproject.org/83f519c0996e45a890c0b2fce300de4c/
2025-12-05 10:27:22.297126 | Event ID: c701bb20-d1c4-11f0-8a19-d2d4cf562300
2025-12-05 10:27:22.301615 | 
2025-12-05 10:27:22.301692 | LOOP [emit-job-header : Print node information]
2025-12-05 10:27:22.446344 | localhost | ok:
2025-12-05 10:27:22.446891 | localhost | # Node Information
2025-12-05 10:27:22.446953 | localhost | Inventory Hostname: controller
2025-12-05 10:27:22.447002 | localhost | Hostname: np0005546819
2025-12-05 10:27:22.447044 | localhost | Username: zuul
2025-12-05 10:27:22.447091 | localhost | Distro: CentOS 9
2025-12-05 10:27:22.447130 | localhost | Provider: vexxhost-nodepool-tripleo
2025-12-05 10:27:22.447167 | localhost | Region: RegionOne
2025-12-05 10:27:22.447204 | localhost | Label: cloud-centos-9-stream-tripleo
2025-12-05 10:27:22.447240 | localhost | Product Name: OpenStack Nova
2025-12-05 10:27:22.447276 | localhost | Interface IP: 38.129.56.121
2025-12-05 10:27:22.471974 | 
2025-12-05 10:27:22.472082 | PLAY [all]
2025-12-05 10:27:22.480187 | 
2025-12-05 10:27:22.480251 | TASK [Gather network facts]
2025-12-05 10:27:22.991299 | controller | ok
2025-12-05 10:27:23.006480 | 
2025-12-05 10:27:23.006540 | TASK [include_role : start-zuul-console]
2025-12-05 10:27:23.025627 | controller | ok
2025-12-05 10:27:23.037735 | 
2025-12-05 10:27:23.037798 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-05 10:27:23.430530 | controller | ok
2025-12-05 10:27:23.442759 | 
2025-12-05 10:27:23.442837 | TASK [include_role : add-build-sshkey]
2025-12-05 10:27:23.464161 | controller | ok
2025-12-05 10:27:23.479320 | 
2025-12-05 10:27:23.479416 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-05 10:27:23.726197 | controller -> localhost | ok
2025-12-05 10:27:23.732117 | 
2025-12-05 10:27:23.732181 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-05 10:27:23.783893 | controller | ok
2025-12-05 10:27:23.806851 | controller | included: /var/lib/zuul/builds/83f519c0996e45a890c0b2fce300de4c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-05 10:27:23.829508 | 
2025-12-05 10:27:23.829621 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-05 10:27:24.526642 | controller -> localhost | Generating public/private rsa key pair.
2025-12-05 10:27:24.526902 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/83f519c0996e45a890c0b2fce300de4c/work/83f519c0996e45a890c0b2fce300de4c_id_rsa.
2025-12-05 10:27:24.526934 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/83f519c0996e45a890c0b2fce300de4c/work/83f519c0996e45a890c0b2fce300de4c_id_rsa.pub.
2025-12-05 10:27:24.526957 | controller -> localhost | The key fingerprint is:
2025-12-05 10:27:24.526979 | controller -> localhost | SHA256:KsqZCKimQBypFaHJ9AZe3wcgIpuejj/rXvN6H3L/xqk zuul-build-sshkey
2025-12-05 10:27:24.526999 | controller -> localhost | The key's randomart image is:
2025-12-05 10:27:24.527018 | controller -> localhost | +---[RSA 3072]----+
2025-12-05 10:27:24.527037 | controller -> localhost | |.+o+ ...         |
2025-12-05 10:27:24.527057 | controller -> localhost | |==*.o . .        |
2025-12-05 10:27:24.527076 | controller -> localhost | |==.o . . .       |
2025-12-05 10:27:24.527095 | controller -> localhost | |+.+     .        |
2025-12-05 10:27:24.527114 | controller -> localhost | |.=      S        |
2025-12-05 10:27:24.527132 | controller -> localhost | |=      .         |
2025-12-05 10:27:24.527151 | controller -> localhost | |=.  + o o  . .   |
2025-12-05 10:27:24.527169 | controller -> localhost | |+=.= +.o o  +    |
2025-12-05 10:27:24.527187 | controller -> localhost | |*oXo.o... E+.    |
2025-12-05 10:27:24.527206 | controller -> localhost | +----[SHA256]-----+
2025-12-05 10:27:24.527255 | controller -> localhost | ok: Runtime: 0:00:00.199480
2025-12-05 10:27:24.532880 | 
2025-12-05 10:27:24.532942 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-05 10:27:24.551623 | controller | ok
2025-12-05 10:27:24.561322 | controller | included: /var/lib/zuul/builds/83f519c0996e45a890c0b2fce300de4c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-05 10:27:24.569153 | 
2025-12-05 10:27:24.569223 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-05 10:27:24.593055 | controller | skipping: Conditional result was False
2025-12-05 10:27:24.598395 | 
2025-12-05 10:27:24.598460 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-05 10:27:25.360547 | controller | changed
2025-12-05 10:27:25.365776 | 
2025-12-05 10:27:25.365839 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-05 10:27:25.629772 | controller | ok
2025-12-05 10:27:25.636173 | 
2025-12-05 10:27:25.636235 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-05 10:27:26.452854 | controller | changed
2025-12-05 10:27:26.457648 | 
2025-12-05 10:27:26.457743 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-05 10:27:27.231037 | controller | changed
2025-12-05 10:27:27.237077 | 
2025-12-05 10:27:27.237148 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-05 10:27:27.262410 | controller | skipping: Conditional result was False
2025-12-05 10:27:27.268811 | 
2025-12-05 10:27:27.268882 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-05 10:27:27.750957 | controller -> localhost | changed
2025-12-05 10:27:27.760888 | 
2025-12-05 10:27:27.760952 | TASK [add-build-sshkey : Add back temp key]
2025-12-05 10:27:28.052040 | controller -> localhost | Identity added: /var/lib/zuul/builds/83f519c0996e45a890c0b2fce300de4c/work/83f519c0996e45a890c0b2fce300de4c_id_rsa (zuul-build-sshkey)
2025-12-05 10:27:28.052251 | controller -> localhost | ok: Runtime: 0:00:00.010274
2025-12-05 10:27:28.058800 | 
2025-12-05 10:27:28.058860 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-05 10:27:28.444155 | controller | ok
2025-12-05 10:27:28.454593 | 
2025-12-05 10:27:28.454796 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-05 10:27:28.492181 | controller | skipping: Conditional result was False
2025-12-05 10:27:28.505674 | 
2025-12-05 10:27:28.505783 | TASK [include_role : validate-host]
2025-12-05 10:27:28.538466 | controller | ok
2025-12-05 10:27:28.564517 | 
2025-12-05 10:27:28.564617 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-05 10:27:28.627109 | controller | ok
2025-12-05 10:27:28.631672 | 
2025-12-05 10:27:28.631758 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-05 10:27:28.868650 | controller -> localhost | ok
2025-12-05 10:27:28.874827 | 
2025-12-05 10:27:28.874909 | TASK [validate-host : Collect information about the host]
2025-12-05 10:27:29.661928 | controller | ok
2025-12-05 10:27:29.670517 | 
2025-12-05 10:27:29.670582 | TASK [validate-host : Sanitize hostname]
2025-12-05 10:27:29.719900 | controller | ok
2025-12-05 10:27:29.725200 | 
2025-12-05 10:27:29.725270 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-05 10:27:30.159093 | controller -> localhost | changed
2025-12-05 10:27:30.164614 | 
2025-12-05 10:27:30.164702 | TASK [validate-host : Collect information about zuul worker]
2025-12-05 10:27:30.577993 | controller | ok
2025-12-05 10:27:30.589148 | 
2025-12-05 10:27:30.589290 | TASK [validate-host : Write out all zuul information for each host]
2025-12-05 10:27:31.087851 | controller -> localhost | changed
2025-12-05 10:27:31.097114 | 
2025-12-05 10:27:31.097173 | TASK [include_role : prepare-workspace-openshift]
2025-12-05 10:27:31.112737 | controller | skipping: Conditional result was False
2025-12-05 10:27:31.118505 | 
2025-12-05 10:27:31.118568 | TASK [include_role : remove-zuul-sshkey]
2025-12-05 10:27:31.133544 | controller | skipping: Conditional result was False
2025-12-05 10:27:31.142965 | 
2025-12-05 10:27:31.143060 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-05 10:27:31.402439 | controller | ok: "logs"
2025-12-05 10:27:31.402884 | controller | ok: All items complete
2025-12-05 10:27:31.402946 | 
2025-12-05 10:27:31.620411 | controller | ok: "artifacts"
2025-12-05 10:27:31.848177 | controller | ok: "docs"
2025-12-05 10:27:31.866718 | 
2025-12-05 10:27:31.866863 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-05 10:27:32.158055 | controller | changed: "logs"
2025-12-05 10:27:32.389315 | controller | changed: "artifacts"
2025-12-05 10:27:32.608226 | controller | changed: "docs"
2025-12-05 10:27:32.657254 | 
2025-12-05 10:27:32.657378 | PLAY RECAP
2025-12-05 10:27:32.657435 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-05 10:27:32.657471 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 10:27:32.657497 | 
2025-12-05 10:27:32.772944 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 10:27:32.773766 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-12-05 10:27:33.332503 | 
2025-12-05 10:27:33.332619 | PLAY [localhost]
2025-12-05 10:27:33.364987 | 
2025-12-05 10:27:33.365119 | TASK [configure-ssh-config-fips : Ensure ssh config directory]
2025-12-05 10:27:33.807931 | localhost | ok
2025-12-05 10:27:33.819186 | 
2025-12-05 10:27:33.819351 | TASK [configure-ssh-config-fips : Setup ssh config]
2025-12-05 10:27:34.589085 | localhost | changed
2025-12-05 10:27:34.609386 | 
2025-12-05 10:27:34.609445 | PLAY [all]
2025-12-05 10:27:34.625154 | 
2025-12-05 10:27:34.625217 | TASK [include_role : prepare-workspace]
2025-12-05 10:27:34.645978 | controller | ok
2025-12-05 10:27:34.660161 | 
2025-12-05 10:27:34.660224 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-05 10:27:35.094650 | controller | ok
2025-12-05 10:27:35.101673 | 
2025-12-05 10:27:35.101762 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-05 10:27:36.888628 | controller | Output suppressed because no_log was given
2025-12-05 10:27:36.898438 | 
2025-12-05 10:27:36.898732 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-05 10:27:37.176658 | controller | changed: "logs"
2025-12-05 10:27:37.398759 | controller | changed: "artifacts"
2025-12-05 10:27:37.625300 | controller | changed: "docs"
2025-12-05 10:27:37.632781 | 
2025-12-05 10:27:37.632849 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-05 10:27:37.897196 | controller | changed: "logs"
2025-12-05 10:27:37.897570 | controller | changed: All items complete
2025-12-05 10:27:37.897627 | 
2025-12-05 10:27:38.125605 | controller | changed: "artifacts"
2025-12-05 10:27:38.346492 | controller | changed: "docs"
2025-12-05 10:27:38.381936 | 
2025-12-05 10:27:38.382141 | TASK [Check if worker can sudo]
2025-12-05 10:27:38.940173 | controller | ok: Runtime: 0:00:00.033537
2025-12-05 10:27:38.952532 | 
2025-12-05 10:27:38.952667 | TASK [configure-mirrors : Gather needed facts]
2025-12-05 10:27:39.034052 | controller | skipping: Conditional result was False
2025-12-05 10:27:39.047436 | 
2025-12-05 10:27:39.047580 | TASK [configure-mirrors : Set up infrastructure mirrors]
2025-12-05 10:27:39.130774 | controller | ok
2025-12-05 10:27:39.138605 | controller | included: /var/lib/zuul/builds/83f519c0996e45a890c0b2fce300de4c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml
2025-12-05 10:27:39.145380 | 
2025-12-05 10:27:39.145438 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir]
2025-12-05 10:27:39.484015 | controller | ok
2025-12-05 10:27:39.490079 | 
2025-12-05 10:27:39.490140 | LOOP [configure-mirrors : Include OS-specific variables]
2025-12-05 10:27:39.574503 | controller | ok: "/var/lib/zuul/builds/83f519c0996e45a890c0b2fce300de4c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml"
2025-12-05 10:27:39.588331 | 
2025-12-05 10:27:39.588492 | TASK [configure-mirrors : Install /etc/pip.conf configuration]
2025-12-05 10:27:40.519194 | controller | changed
2025-12-05 10:27:40.547415 | 
2025-12-05 10:27:40.547561 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors]
2025-12-05 10:27:40.641917 | controller | ok: "/var/lib/zuul/builds/83f519c0996e45a890c0b2fce300de4c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml"
2025-12-05 10:27:40.642100 | controller | ok: All items complete
2025-12-05 10:27:40.642125 | 
2025-12-05 10:27:40.721161 | controller | included: /var/lib/zuul/builds/83f519c0996e45a890c0b2fce300de4c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml
2025-12-05 10:27:40.730486 | 
2025-12-05 10:27:40.730554 | LOOP [configure-mirrors : Install CentOS 9 repository files]
2025-12-05 10:27:41.707121 | controller | changed: "etc/yum.repos.d/centos.repo"
2025-12-05 10:27:42.535005 | controller | changed: "etc/yum.repos.d/centos-addons.repo"
2025-12-05 10:27:42.559845 | 
2025-12-05 10:27:42.560102 | TASK [configure-mirrors : Disable deltrarpm]
2025-12-05 10:27:43.032089 | controller | changed: section and option added
2025-12-05 10:27:43.053574 | 
2025-12-05 10:27:43.053731 | LOOP [configure-mirrors : Update yum/dnf cache]
2025-12-05 10:27:43.696271 | controller | 29 files removed
2025-12-05 10:27:43.696612 | controller | ok: Item: dnf clean all Runtime: 0:00:00.326109
2025-12-05 10:27:43.696656 | controller | changed: All items complete
2025-12-05 10:27:43.696725 | 
2025-12-05 10:27:55.220007 | 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 10:27:55.220184 | controller | DNF version: 4.14.0
2025-12-05 10:27:55.220240 | controller | cachedir: /var/cache/dnf
2025-12-05 10:27:55.220290 | controller | Making cache files for all metadata files.
2025-12-05 10:27:55.220332 | controller | baseos: has expired and will be refreshed.
2025-12-05 10:27:55.220371 | controller | appstream: has expired and will be refreshed.
2025-12-05 10:27:55.220409 | controller | crb: has expired and will be refreshed.
2025-12-05 10:27:55.220461 | controller | extras-common: has expired and will be refreshed.
2025-12-05 10:27:55.220500 | controller | repo: downloading from remote: baseos
2025-12-05 10:27:55.220537 | controller | CentOS Stream 9 - BaseOS                         55 MB/s | 8.8 MB     00:00
2025-12-05 10:27:55.220574 | controller | baseos: using metadata from Mon 01 Dec 2025 12:58:39 PM EST.
2025-12-05 10:27:55.220610 | controller | repo: downloading from remote: appstream
2025-12-05 10:27:55.220645 | controller | CentOS Stream 9 - AppStream                      65 MB/s |  25 MB     00:00
2025-12-05 10:27:55.220714 | controller | appstream: using metadata from Mon 01 Dec 2025 01:01:55 PM EST.
2025-12-05 10:27:55.220763 | controller | repo: downloading from remote: crb
2025-12-05 10:27:55.220801 | controller | CentOS Stream 9 - CRB                            58 MB/s | 7.3 MB     00:00
2025-12-05 10:27:55.220838 | controller | crb: using metadata from Mon 01 Dec 2025 01:06:07 PM EST.
2025-12-05 10:27:55.220874 | controller | repo: downloading from remote: extras-common
2025-12-05 10:27:55.220911 | controller | CentOS Stream 9 - Extras packages               1.5 MB/s |  20 kB     00:00
2025-12-05 10:27:55.220947 | controller | extras-common: using metadata from Thu 30 Oct 2025 09:20:08 AM EDT.
2025-12-05 10:27:55.220981 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)'
2025-12-05 10:27:55.221017 | controller | Completion plugin: Generating completion cache...
2025-12-05 10:27:55.221053 | controller | Metadata cache created.
2025-12-05 10:27:55.221108 | controller | ok: Item: dnf makecache -v Runtime: 0:00:11.245538
2025-12-05 10:27:55.250653 | 
2025-12-05 10:27:55.250813 | PLAY RECAP
2025-12-05 10:27:55.250924 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-05 10:27:55.250980 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 10:27:55.251019 | 
2025-12-05 10:27:55.369199 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-12-05 10:27:55.371002 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-05 10:27:55.951561 | 
2025-12-05 10:27:55.951671 | PLAY [all]
2025-12-05 10:27:55.974559 | 
2025-12-05 10:27:55.974647 | TASK [Install binary dependencies]
2025-12-05 10:27:56.047574 | controller | ok
2025-12-05 10:27:56.085314 | 
2025-12-05 10:27:56.085446 | TASK [bindep : Include find tasks]
2025-12-05 10:27:56.122460 | controller | ok
2025-12-05 10:27:56.130281 | controller | included: /var/lib/zuul/builds/83f519c0996e45a890c0b2fce300de4c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-12-05 10:27:56.136176 | 
2025-12-05 10:27:56.136241 | TASK [bindep : Look for bindep.txt]
2025-12-05 10:27:56.520832 | controller | ok
2025-12-05 10:27:56.535973 | 
2025-12-05 10:27:56.536119 | TASK [bindep : Define bindep_file fact]
2025-12-05 10:27:56.585555 | controller | ok
2025-12-05 10:27:56.597157 | 
2025-12-05 10:27:56.597293 | TASK [bindep : Look for other-requirements.txt]
2025-12-05 10:27:56.625617 | controller | skipping: Conditional result was False
2025-12-05 10:27:56.639224 | 
2025-12-05 10:27:56.639381 | TASK [bindep : Define bindep_file fact]
2025-12-05 10:27:56.676727 | controller | skipping: Conditional result was False
2025-12-05 10:27:56.689535 | 
2025-12-05 10:27:56.689713 | TASK [bindep : Look for bindep fallback file]
2025-12-05 10:27:56.728009 | controller | skipping: Conditional result was False
2025-12-05 10:27:56.734130 | 
2025-12-05 10:27:56.734200 | TASK [bindep : Define bindep_file fact]
2025-12-05 10:27:56.758619 | controller | skipping: Conditional result was False
2025-12-05 10:27:56.764717 | 
2025-12-05 10:27:56.764785 | TASK [bindep : Include bindep tasks]
2025-12-05 10:27:56.805661 | controller | ok
2025-12-05 10:27:56.812378 | controller | included: /var/lib/zuul/builds/83f519c0996e45a890c0b2fce300de4c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml
2025-12-05 10:27:56.820055 | 
2025-12-05 10:27:56.820117 | TASK [bindep : Look for bindep command]
2025-12-05 10:27:56.857138 | controller | skipping: Conditional result was False
2025-12-05 10:27:56.863507 | 
2025-12-05 10:27:56.863571 | TASK [bindep : Check for system bindep]
2025-12-05 10:27:57.393413 | controller | ok: Runtime: 0:00:00.005839
2025-12-05 10:27:57.405186 | 
2025-12-05 10:27:57.405323 | TASK [bindep : Define bindep_command fact]
2025-12-05 10:27:57.434745 | controller | skipping: Conditional result was False
2025-12-05 10:27:57.446934 | 
2025-12-05 10:27:57.447077 | TASK [bindep : Include install tasks]
2025-12-05 10:27:57.484436 | controller | ok
2025-12-05 10:27:57.500172 | controller | included: /var/lib/zuul/builds/83f519c0996e45a890c0b2fce300de4c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml
2025-12-05 10:27:57.513477 | 
2025-12-05 10:27:57.513560 | TASK [bindep : Create temp dir for bindep]
2025-12-05 10:27:57.920763 | controller | changed
2025-12-05 10:27:57.927920 | 
2025-12-05 10:27:57.928006 | TASK [Ensure we have pip dependencies]
2025-12-05 10:27:57.948940 | controller | ok
2025-12-05 10:27:57.979125 | 
2025-12-05 10:27:57.979223 | TASK [ensure-pip : Check if pip is installed]
2025-12-05 05:27:58.241217 | controller | /usr/bin/pip3
2025-12-05 05:27:58.264267 | controller | /usr/bin/python3: No module named wheel
2025-12-05 10:27:58.508425 | controller | ok: Runtime: 0:00:00.031852
2025-12-05 10:27:58.514408 | 
2025-12-05 10:27:58.514476 | LOOP [ensure-pip : Install pip from packages]
2025-12-05 10:27:58.571091 | controller | ok: "/var/lib/zuul/builds/83f519c0996e45a890c0b2fce300de4c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml"
2025-12-05 10:27:58.587221 | controller | included: /var/lib/zuul/builds/83f519c0996e45a890c0b2fce300de4c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml
2025-12-05 10:27:58.598070 | 
2025-12-05 10:27:58.598135 | TASK [ensure-pip : Install Python 3 pip]
2025-12-05 10:28:00.546164 | controller | changed
2025-12-05 10:28:00.557393 | 
2025-12-05 10:28:00.557492 | TASK [ensure-pip : Check for EPEL repository]
2025-12-05 10:28:00.604503 | controller | skipping: Conditional result was False
2025-12-05 10:28:00.611938 | 
2025-12-05 10:28:00.612015 | TASK [ensure-pip : Fail with instructions when EPEL is not installed]
2025-12-05 10:28:00.658661 | controller | skipping: Conditional result was False
2025-12-05 10:28:00.666429 | 
2025-12-05 10:28:00.666502 | TASK [ensure-pip : Install Python 2 pip]
2025-12-05 10:28:00.721866 | controller | skipping: Conditional result was False
2025-12-05 10:28:00.729517 | 
2025-12-05 10:28:00.729597 | TASK [ensure-pip : Ensure setuptools]
2025-12-05 10:28:00.753945 | controller | skipping: Conditional result was False
2025-12-05 10:28:00.761780 | 
2025-12-05 10:28:00.761847 | TASK [ensure-pip : Check for ensurepip module]
2025-12-05 10:28:01.293874 | controller | skipping: Conditional result was False
2025-12-05 10:28:01.303779 | 
2025-12-05 10:28:01.303887 | TASK [ensure-pip : Ensure python3-venv]
2025-12-05 10:28:01.339721 | controller | skipping: Conditional result was False
2025-12-05 10:28:01.349881 | 
2025-12-05 10:28:01.349975 | TASK [ensure-pip : Install pip from source]
2025-12-05 10:28:01.367349 | controller | skipping: Conditional result was False
2025-12-05 10:28:01.376794 | 
2025-12-05 10:28:01.376935 | TASK [ensure-pip : Probe for venv python full path]
2025-12-05 05:28:01.614429 | controller | /usr/bin/python3
2025-12-05 10:28:01.915304 | controller | ok: Runtime: 0:00:00.007875
2025-12-05 10:28:01.922609 | 
2025-12-05 10:28:01.922706 | TASK [ensure-pip : Set host default]
2025-12-05 10:28:02.040285 | controller | ok
2025-12-05 10:28:02.055321 | 
2025-12-05 10:28:02.055474 | TASK [ensure-pip : Set ensure_pip_virtualenv_command]
2025-12-05 10:28:02.131166 | controller | ok
2025-12-05 10:28:02.140981 | 
2025-12-05 10:28:02.141045 | TASK [bindep : Install bindep into temporary venv]
2025-12-05 10:28:06.517784 | controller | changed
2025-12-05 10:28:06.533123 | 
2025-12-05 10:28:06.533204 | TASK [bindep : Define bindep_command]
2025-12-05 10:28:06.587658 | controller | ok
2025-12-05 10:28:06.594442 | 
2025-12-05 10:28:06.594507 | LOOP [bindep : Include package tasks]
2025-12-05 10:28:06.703724 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt"
2025-12-05 10:28:06.703876 | controller | ok: All items complete
2025-12-05 10:28:06.703903 | 
2025-12-05 10:28:06.736015 | controller | included: /var/lib/zuul/builds/83f519c0996e45a890c0b2fce300de4c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml
2025-12-05 10:28:06.749896 | 
2025-12-05 10:28:06.750022 | TASK [bindep : Define bindep_run fact]
2025-12-05 10:28:06.784353 | controller | ok
2025-12-05 10:28:06.793774 | 
2025-12-05 10:28:06.793881 | TASK [bindep : Get list of packages to install from bindep]
2025-12-05 05:28:08.376844 | controller | podman
2025-12-05 05:28:08.415145 | controller | python3-jmespath
2025-12-05 05:28:08.415371 | controller | python3-libvirt
2025-12-05 05:28:08.415392 | controller | python3-lxml
2025-12-05 05:28:08.415409 | controller | python3-netaddr
2025-12-05 10:28:08.843319 | controller | ok: Runtime: 0:00:01.201893
2025-12-05 10:28:08.850434 | 
2025-12-05 10:28:08.850507 | TASK [bindep : Install distro packages from bindep]
2025-12-05 10:29:30.939798 | controller | changed
2025-12-05 10:29:30.952792 | 
2025-12-05 10:29:30.952931 | TASK [bindep : Check that packages are installed]
2025-12-05 10:29:33.527378 | controller | ok: Runtime: 0:00:01.401059
2025-12-05 10:29:33.533247 | 
2025-12-05 10:29:33.533310 | TASK [bindep : Fail if we cannot install all packages]
2025-12-05 10:29:33.558308 | controller | skipping: Conditional result was False
2025-12-05 10:29:33.569013 | 
2025-12-05 10:29:33.569079 | TASK [Run test-setup role]
2025-12-05 10:29:33.587831 | controller | ok
2025-12-05 10:29:33.606366 | 
2025-12-05 10:29:33.606450 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-12-05 10:29:33.857127 | controller | ok
2025-12-05 10:29:33.869372 | 
2025-12-05 10:29:33.869513 | TASK [test-setup : Run tools/test-setup.sh]
2025-12-05 10:29:34.417140 | controller | skipping: Conditional result was False
2025-12-05 10:29:34.446418 | 
2025-12-05 10:29:34.446631 | TASK [bindep : Remove bindep temp dir]
2025-12-05 10:29:34.800030 | controller | ok
2025-12-05 10:29:34.811280 | 
2025-12-05 10:29:34.811340 | PLAY RECAP
2025-12-05 10:29:34.811382 | controller | ok: 22 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0
2025-12-05 10:29:34.811403 | 
2025-12-05 10:29:34.922883 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-05 10:29:34.923748 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2025-12-05 10:29:35.547870 | 
2025-12-05 10:29:35.547997 | PLAY [all]
2025-12-05 10:29:35.568957 | 
2025-12-05 10:29:35.569045 | TASK [Abort when test_command variable is undefined]
2025-12-05 10:29:35.615260 | controller | skipping: Conditional result was False
2025-12-05 10:29:35.622015 | 
2025-12-05 10:29:35.622100 | TASK [Convert test_command to list]
2025-12-05 10:29:35.666332 | controller | skipping: Conditional result was False
2025-12-05 10:29:35.672994 | 
2025-12-05 10:29:35.673069 | TASK [Use test_command list]
2025-12-05 10:29:35.723158 | controller | ok
2025-12-05 10:29:35.729009 | 
2025-12-05 10:29:35.729075 | LOOP [Run test_command]
2025-12-05 10:29:36.178994 | controller | no check to run
2025-12-05 10:29:36.179257 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.006684
2025-12-05 10:29:36.204140 | 
2025-12-05 10:29:36.204259 | PLAY RECAP
2025-12-05 10:29:36.204301 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-05 10:29:36.204322 | 
2025-12-05 10:29:36.313066 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2025-12-05 10:29:36.315362 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-05 10:29:36.931151 | 
2025-12-05 10:29:36.931277 | PLAY [all]
2025-12-05 10:29:36.952936 | 
2025-12-05 10:29:36.953039 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-12-05 10:29:37.372593 | controller | changed: non-zero return code
2025-12-05 10:29:37.388765 | 
2025-12-05 10:29:37.389054 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-12-05 10:29:37.411341 | controller | skipping: Conditional result was False
2025-12-05 10:29:37.419644 | 
2025-12-05 10:29:37.419781 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-12-05 10:29:37.443543 | 
2025-12-05 10:29:37.443835 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-12-05 10:29:37.477096 | 
2025-12-05 10:29:37.477280 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-12-05 10:29:37.493075 | controller | skipping: Conditional result was False
2025-12-05 10:29:37.505256 | 
2025-12-05 10:29:37.505410 | LOOP [fetch-subunit-output : Generate subunit file]
2025-12-05 10:29:37.541300 | 
2025-12-05 10:29:37.541500 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-12-05 10:29:37.557023 | controller | skipping: Conditional result was False
2025-12-05 10:29:37.565498 | 
2025-12-05 10:29:37.565601 | TASK [fetch-subunit-output : Remove the temporary file]
2025-12-05 10:29:37.589955 | controller | skipping: Conditional result was False
2025-12-05 10:29:37.597929 | 
2025-12-05 10:29:37.598016 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-12-05 10:29:37.613300 | controller | skipping: Conditional result was False
2025-12-05 10:29:37.666207 | 
2025-12-05 10:29:37.666316 | PLAY RECAP
2025-12-05 10:29:37.666375 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-05 10:29:37.666415 | 
2025-12-05 10:29:37.797521 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-05 10:29:37.799265 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 10:29:38.389084 | 
2025-12-05 10:29:38.389210 | PLAY [all]
2025-12-05 10:29:38.409144 | 
2025-12-05 10:29:38.409227 | TASK [include_role : fetch-output]
2025-12-05 10:29:38.448413 | controller | ok
2025-12-05 10:29:38.466901 | 
2025-12-05 10:29:38.466998 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-05 10:29:38.511262 | controller | skipping: Conditional result was False
2025-12-05 10:29:38.517037 | 
2025-12-05 10:29:38.517104 | TASK [fetch-output : Set log path for single node]
2025-12-05 10:29:38.555816 | controller | ok
2025-12-05 10:29:38.561240 | 
2025-12-05 10:29:38.561305 | LOOP [fetch-output : Ensure local output dirs]
2025-12-05 10:29:38.986082 | controller -> localhost | ok: "/var/lib/zuul/builds/83f519c0996e45a890c0b2fce300de4c/work/logs"
2025-12-05 10:29:39.202086 | controller -> localhost | changed: "/var/lib/zuul/builds/83f519c0996e45a890c0b2fce300de4c/work/artifacts"
2025-12-05 10:29:39.431379 | controller -> localhost | changed: "/var/lib/zuul/builds/83f519c0996e45a890c0b2fce300de4c/work/docs"
2025-12-05 10:29:39.450488 | 
2025-12-05 10:29:39.450735 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-05 10:29:40.168079 | controller | changed: .d..t...... ./
2025-12-05 10:29:40.168286 | controller | changed: All items complete
2025-12-05 10:29:40.168313 | 
2025-12-05 10:29:40.743963 | controller | changed: .d..t...... ./
2025-12-05 10:29:41.230832 | controller | changed: .d..t...... ./
2025-12-05 10:29:41.243431 | 
2025-12-05 10:29:41.243542 | TASK [include_role : fetch-output-openshift]
2025-12-05 10:29:41.267734 | controller | skipping: Conditional result was False
2025-12-05 10:29:41.273585 | 
2025-12-05 10:29:41.273653 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-05 10:29:41.670135 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.009597
2025-12-05 10:29:41.912259 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009346
2025-12-05 10:29:41.941896 | 
2025-12-05 10:29:41.941992 | PLAY [all]
2025-12-05 10:29:41.957724 | 
2025-12-05 10:29:41.957804 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-05 10:29:42.465417 | controller | changed
2025-12-05 10:29:42.491183 | 
2025-12-05 10:29:42.491287 | PLAY RECAP
2025-12-05 10:29:42.491327 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-05 10:29:42.491349 | 
2025-12-05 10:29:42.633398 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 10:29:42.635035 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-05 10:29:43.339041 | 
2025-12-05 10:29:43.339152 | PLAY [localhost]
2025-12-05 10:29:43.360123 | 
2025-12-05 10:29:43.360299 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-05 10:29:43.779171 | localhost | changed
2025-12-05 10:29:43.784264 | 
2025-12-05 10:29:43.784368 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-05 10:29:43.812456 | localhost | ok
2025-12-05 10:29:43.821528 | 
2025-12-05 10:29:43.821596 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-05 10:29:44.185316 | localhost | changed
2025-12-05 10:29:44.193832 | 
2025-12-05 10:29:44.193912 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-05 10:29:44.927136 | localhost | changed
2025-12-05 10:29:44.934508 | 
2025-12-05 10:29:44.934576 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-05 10:29:45.364882 | localhost | Identity added: /var/lib/zuul/builds/83f519c0996e45a890c0b2fce300de4c/work/tmp/ansible.9r68pnby (/var/lib/zuul/builds/83f519c0996e45a890c0b2fce300de4c/work/tmp/ansible.9r68pnby)
2025-12-05 10:29:45.365075 | localhost | ok: Runtime: 0:00:00.007517
2025-12-05 10:29:45.369474 | 
2025-12-05 10:29:45.369540 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-05 10:29:45.651255 | localhost | ok: Runtime: 0:00:00.010008
2025-12-05 10:29:45.659336 | 
2025-12-05 10:29:45.659470 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-05 10:29:45.746729 | localhost | changed
2025-12-05 10:29:45.756027 | 
2025-12-05 10:29:45.756159 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-05 10:29:46.160298 | localhost | changed
2025-12-05 10:29:46.181068 | 
2025-12-05 10:29:46.181187 | PLAY [localhost]
2025-12-05 10:29:46.211879 | 
2025-12-05 10:29:46.212076 | TASK [Generate bulk log download script]
2025-12-05 10:29:46.242464 | localhost | ok
2025-12-05 10:29:46.266701 | 
2025-12-05 10:29:46.266828 | TASK [local-log-download : Check API endpoint is defined]
2025-12-05 10:29:46.307970 | localhost | ok: All assertions passed
2025-12-05 10:29:46.314460 | 
2025-12-05 10:29:46.314568 | TASK [local-log-download : Create download script]
2025-12-05 10:29:46.785234 | localhost -> localhost | changed
2025-12-05 10:29:46.806637 | 
2025-12-05 10:29:46.806794 | TASK [Register quick-download link]
2025-12-05 10:29:46.845831 | localhost | ok
2025-12-05 10:29:46.906206 | 
2025-12-05 10:29:46.906309 | PLAY [logserver.rdoproject.org]
2025-12-05 10:29:46.915904 | 
2025-12-05 10:29:46.915972 | TASK [Set zuul-log-path fact]
2025-12-05 10:29:46.934542 | logserver.rdoproject.org | ok
2025-12-05 10:29:46.944158 | 
2025-12-05 10:29:46.944221 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 10:29:46.981161 | logserver.rdoproject.org | ok
2025-12-05 10:29:46.986851 | 
2025-12-05 10:29:46.986914 | TASK [upload-logs : Create log directories]
2025-12-05 10:29:47.720204 | logserver.rdoproject.org | changed
2025-12-05 10:29:47.727180 | 
2025-12-05 10:29:47.727311 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-05 10:29:48.091035 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.005559
2025-12-05 10:29:48.095766 | 
2025-12-05 10:29:48.095827 | TASK [upload-logs : Upload logs to log server]
2025-12-05 10:29:48.828995 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-05 10:29:48.833124 | 
2025-12-05 10:29:48.833209 | LOOP [upload-logs : Compress console log and json output]
2025-12-05 10:29:48.904928 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 10:29:48.918368 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 10:29:48.926988 | 
2025-12-05 10:29:48.927188 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-05 10:29:48.976138 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 10:29:48.976386 | 
2025-12-05 10:29:48.982376 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 10:29:48.994114 | 
2025-12-05 10:29:48.994236 | LOOP [upload-logs : Upload console log and json output]