2025-11-24 08:34:10.869878 | Job console starting...
2025-11-24 08:34:10.879924 | Updating repositories
2025-11-24 08:34:10.912331 | Preparing job workspace
2025-11-24 08:34:17.122912 | Running Ansible setup...
2025-11-24 08:34:22.229586 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-24 08:34:22.912803 | 
2025-11-24 08:34:22.912986 | PLAY [localhost]
2025-11-24 08:34:22.924407 | 
2025-11-24 08:34:22.924554 | TASK [Gathering Facts]
2025-11-24 08:34:23.993018 | localhost | ok
2025-11-24 08:34:24.014328 | 
2025-11-24 08:34:24.014412 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-24 08:34:24.511843 | localhost -> localhost | changed
2025-11-24 08:34:24.523818 | 
2025-11-24 08:34:24.523977 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-24 08:34:25.475265 | localhost -> localhost | changed
2025-11-24 08:34:25.498322 | 
2025-11-24 08:34:25.498477 | TASK [Setup log path fact]
2025-11-24 08:34:25.517951 | localhost | ok
2025-11-24 08:34:25.529916 | 
2025-11-24 08:34:25.530016 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-24 08:34:25.558288 | localhost | ok
2025-11-24 08:34:25.566486 | 
2025-11-24 08:34:25.566567 | TASK [emit-job-header : Print job information]
2025-11-24 08:34:25.605921 | # Job Information
2025-11-24 08:34:25.606090 | Ansible Version: 2.15.12
2025-11-24 08:34:25.606115 | Job: cifmw-molecule-ci_lvms_storage
2025-11-24 08:34:25.606135 | Pipeline: github-check
2025-11-24 08:34:25.606153 | Executor: ze02.softwarefactory-project.io
2025-11-24 08:34:25.606171 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3451
2025-11-24 08:34:25.606189 | Log URL (when completed): https://logserver.rdoproject.org/2cd/rdoproject.org/2cdb23a37a644b1cb1f1372e340da67f/
2025-11-24 08:34:25.606208 | Event ID: 2dae9040-c910-11f0-818a-433f9d14d6bc
2025-11-24 08:34:25.609897 | 
2025-11-24 08:34:25.609965 | LOOP [emit-job-header : Print node information]
2025-11-24 08:34:25.711611 | localhost | ok:
2025-11-24 08:34:25.711870 | localhost | # Node Information
2025-11-24 08:34:25.711912 | localhost | Inventory Hostname: controller
2025-11-24 08:34:25.711944 | localhost | Hostname: np0005533170
2025-11-24 08:34:25.711979 | localhost | Username: zuul
2025-11-24 08:34:25.712043 | localhost | Distro: CentOS 9
2025-11-24 08:34:25.712082 | localhost | Provider: vexxhost-nodepool-tripleo
2025-11-24 08:34:25.712109 | localhost | Region: RegionOne
2025-11-24 08:34:25.712132 | localhost | Label: cloud-centos-9-stream-tripleo
2025-11-24 08:34:25.712155 | localhost | Product Name: OpenStack Nova
2025-11-24 08:34:25.712177 | localhost | Interface IP: 38.129.56.70
2025-11-24 08:34:25.735791 | 
2025-11-24 08:34:25.735933 | PLAY [all]
2025-11-24 08:34:25.744109 | 
2025-11-24 08:34:25.744207 | TASK [Gather network facts]
2025-11-24 08:34:26.221290 | controller | ok
2025-11-24 08:34:26.244525 | 
2025-11-24 08:34:26.244686 | TASK [include_role : start-zuul-console]
2025-11-24 08:34:26.267507 | controller | ok
2025-11-24 08:34:26.286520 | 
2025-11-24 08:34:26.286727 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-24 08:34:26.701353 | controller | ok
2025-11-24 08:34:26.714287 | 
2025-11-24 08:34:26.714398 | TASK [include_role : add-build-sshkey]
2025-11-24 08:34:26.745955 | controller | ok
2025-11-24 08:34:26.759255 | 
2025-11-24 08:34:26.759329 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-24 08:34:27.028783 | controller -> localhost | ok
2025-11-24 08:34:27.044977 | 
2025-11-24 08:34:27.045154 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-24 08:34:27.086902 | controller | ok
2025-11-24 08:34:27.102808 | controller | included: /var/lib/zuul/builds/2cdb23a37a644b1cb1f1372e340da67f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-24 08:34:27.110819 | 
2025-11-24 08:34:27.110928 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-24 08:34:28.112947 | controller -> localhost | Generating public/private rsa key pair.
2025-11-24 08:34:28.113205 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/2cdb23a37a644b1cb1f1372e340da67f/work/2cdb23a37a644b1cb1f1372e340da67f_id_rsa.
2025-11-24 08:34:28.113254 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/2cdb23a37a644b1cb1f1372e340da67f/work/2cdb23a37a644b1cb1f1372e340da67f_id_rsa.pub.
2025-11-24 08:34:28.113288 | controller -> localhost | The key fingerprint is:
2025-11-24 08:34:28.113319 | controller -> localhost | SHA256:8fKUR8NWfYILbgwliuh7qd76dNJSJ3N0DOIPwRmGKqM zuul-build-sshkey
2025-11-24 08:34:28.113344 | controller -> localhost | The key's randomart image is:
2025-11-24 08:34:28.113367 | controller -> localhost | +---[RSA 3072]----+
2025-11-24 08:34:28.113388 | controller -> localhost | |     o=oo..  ... |
2025-11-24 08:34:28.113410 | controller -> localhost | |   ..+o+.+..... o|
2025-11-24 08:34:28.113435 | controller -> localhost | |  ... + o+o.=. ..|
2025-11-24 08:34:28.113464 | controller -> localhost | |o..    + +++..   |
2025-11-24 08:34:28.113495 | controller -> localhost | |.o.   + S.+ .    |
2025-11-24 08:34:28.113522 | controller -> localhost | |E  . + = + .     |
2025-11-24 08:34:28.113542 | controller -> localhost | |  . * o   .      |
2025-11-24 08:34:28.113564 | controller -> localhost | |   = +           |
2025-11-24 08:34:28.113583 | controller -> localhost | | .+oo            |
2025-11-24 08:34:28.113603 | controller -> localhost | +----[SHA256]-----+
2025-11-24 08:34:28.113651 | controller -> localhost | ok: Runtime: 0:00:00.436190
2025-11-24 08:34:28.120021 | 
2025-11-24 08:34:28.120121 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-24 08:34:28.153441 | controller | ok
2025-11-24 08:34:28.172969 | controller | included: /var/lib/zuul/builds/2cdb23a37a644b1cb1f1372e340da67f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-24 08:34:28.183326 | 
2025-11-24 08:34:28.183406 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-24 08:34:28.208583 | controller | skipping: Conditional result was False
2025-11-24 08:34:28.216798 | 
2025-11-24 08:34:28.216881 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-24 08:34:28.727999 | controller | changed
2025-11-24 08:34:28.742420 | 
2025-11-24 08:34:28.742581 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-24 08:34:29.031608 | controller | ok
2025-11-24 08:34:29.040028 | 
2025-11-24 08:34:29.040150 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-24 08:34:29.982814 | controller | changed
2025-11-24 08:34:29.987921 | 
2025-11-24 08:34:29.987999 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-24 08:34:30.853719 | controller | changed
2025-11-24 08:34:30.859044 | 
2025-11-24 08:34:30.859112 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-24 08:34:30.884942 | controller | skipping: Conditional result was False
2025-11-24 08:34:30.894707 | 
2025-11-24 08:34:30.894814 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-24 08:34:31.413485 | controller -> localhost | changed
2025-11-24 08:34:31.427306 | 
2025-11-24 08:34:31.427554 | TASK [add-build-sshkey : Add back temp key]
2025-11-24 08:34:31.754070 | controller -> localhost | Identity added: /var/lib/zuul/builds/2cdb23a37a644b1cb1f1372e340da67f/work/2cdb23a37a644b1cb1f1372e340da67f_id_rsa (zuul-build-sshkey)
2025-11-24 08:34:31.754321 | controller -> localhost | ok: Runtime: 0:00:00.011430
2025-11-24 08:34:31.760584 | 
2025-11-24 08:34:31.760698 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-24 08:34:32.146699 | controller | ok
2025-11-24 08:34:32.153045 | 
2025-11-24 08:34:32.153131 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-24 08:34:32.214531 | controller | skipping: Conditional result was False
2025-11-24 08:34:32.227582 | 
2025-11-24 08:34:32.228435 | TASK [include_role : validate-host]
2025-11-24 08:34:32.276362 | controller | ok
2025-11-24 08:34:32.332425 | 
2025-11-24 08:34:32.332593 | TASK [validate-host : Define zuul_info_dir fact]
2025-11-24 08:34:32.376058 | controller | ok
2025-11-24 08:34:32.442504 | 
2025-11-24 08:34:32.442688 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-11-24 08:34:32.820150 | controller -> localhost | ok
2025-11-24 08:34:32.826826 | 
2025-11-24 08:34:32.826912 | TASK [validate-host : Collect information about the host]
2025-11-24 08:34:33.630380 | controller | ok
2025-11-24 08:34:33.649013 | 
2025-11-24 08:34:33.649239 | TASK [validate-host : Sanitize hostname]
2025-11-24 08:34:33.702361 | controller | ok
2025-11-24 08:34:33.713071 | 
2025-11-24 08:34:33.713261 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-11-24 08:34:34.259872 | controller -> localhost | changed
2025-11-24 08:34:34.266139 | 
2025-11-24 08:34:34.266231 | TASK [validate-host : Collect information about zuul worker]
2025-11-24 08:34:34.718329 | controller | ok
2025-11-24 08:34:34.727612 | 
2025-11-24 08:34:34.727738 | TASK [validate-host : Write out all zuul information for each host]
2025-11-24 08:34:35.271386 | controller -> localhost | changed
2025-11-24 08:34:35.282836 | 
2025-11-24 08:34:35.282959 | TASK [include_role : prepare-workspace-openshift]
2025-11-24 08:34:35.309366 | controller | skipping: Conditional result was False
2025-11-24 08:34:35.316631 | 
2025-11-24 08:34:35.316759 | TASK [include_role : remove-zuul-sshkey]
2025-11-24 08:34:35.345039 | controller | skipping: Conditional result was False
2025-11-24 08:34:35.351285 | 
2025-11-24 08:34:35.351396 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-24 08:34:35.659903 | controller | ok: "logs"
2025-11-24 08:34:35.660203 | controller | ok: All items complete
2025-11-24 08:34:35.660233 | 
2025-11-24 08:34:35.891760 | controller | ok: "artifacts"
2025-11-24 08:34:36.117578 | controller | ok: "docs"
2025-11-24 08:34:36.126222 | 
2025-11-24 08:34:36.126393 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-24 08:34:36.419444 | controller | changed: "logs"
2025-11-24 08:34:36.646223 | controller | changed: "artifacts"
2025-11-24 08:34:36.865938 | controller | changed: "docs"
2025-11-24 08:34:36.894612 | 
2025-11-24 08:34:36.894750 | PLAY RECAP
2025-11-24 08:34:36.894798 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-24 08:34:36.894826 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-24 08:34:36.894845 | 
2025-11-24 08:34:37.072714 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-24 08:34:37.073569 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-11-24 08:34:37.827580 | 
2025-11-24 08:34:37.827771 | PLAY [localhost]
2025-11-24 08:34:37.861346 | 
2025-11-24 08:34:37.861493 | TASK [configure-ssh-config-fips : Ensure ssh config directory]
2025-11-24 08:34:38.395620 | localhost | ok
2025-11-24 08:34:38.401205 | 
2025-11-24 08:34:38.401293 | TASK [configure-ssh-config-fips : Setup ssh config]
2025-11-24 08:34:39.291602 | localhost | changed
2025-11-24 08:34:39.330130 | 
2025-11-24 08:34:39.330289 | PLAY [all]
2025-11-24 08:34:39.350827 | 
2025-11-24 08:34:39.350906 | TASK [include_role : prepare-workspace]
2025-11-24 08:34:39.371842 | controller | ok
2025-11-24 08:34:39.389481 | 
2025-11-24 08:34:39.389563 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-24 08:34:39.782745 | controller | ok
2025-11-24 08:34:39.791765 | 
2025-11-24 08:34:39.791908 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-24 08:34:42.802497 | controller | Output suppressed because no_log was given
2025-11-24 08:34:42.835623 | 
2025-11-24 08:34:42.835796 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-24 08:34:43.109375 | controller | changed: "logs"
2025-11-24 08:34:43.370941 | controller | changed: "artifacts"
2025-11-24 08:34:43.629528 | controller | changed: "docs"
2025-11-24 08:34:43.645216 | 
2025-11-24 08:34:43.645335 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-24 08:34:44.005475 | controller | changed: "logs"
2025-11-24 08:34:44.005855 | controller | changed: All items complete
2025-11-24 08:34:44.005886 | 
2025-11-24 08:34:44.254002 | controller | changed: "artifacts"
2025-11-24 08:34:44.495029 | controller | changed: "docs"
2025-11-24 08:34:44.514390 | 
2025-11-24 08:34:44.514542 | TASK [Check if worker can sudo]
2025-11-24 08:34:45.055598 | controller | ok: Runtime: 0:00:00.042765
2025-11-24 08:34:45.063650 | 
2025-11-24 08:34:45.063876 | TASK [configure-mirrors : Gather needed facts]
2025-11-24 08:34:45.240771 | controller | skipping: Conditional result was False
2025-11-24 08:34:45.270512 | 
2025-11-24 08:34:45.270657 | TASK [configure-mirrors : Set up infrastructure mirrors]
2025-11-24 08:34:45.343796 | controller | ok
2025-11-24 08:34:45.358565 | controller | included: /var/lib/zuul/builds/2cdb23a37a644b1cb1f1372e340da67f/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml
2025-11-24 08:34:45.369430 | 
2025-11-24 08:34:45.369501 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir]
2025-11-24 08:34:45.749022 | controller | ok
2025-11-24 08:34:45.763131 | 
2025-11-24 08:34:45.763265 | LOOP [configure-mirrors : Include OS-specific variables]
2025-11-24 08:34:45.902479 | controller | ok: "/var/lib/zuul/builds/2cdb23a37a644b1cb1f1372e340da67f/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml"
2025-11-24 08:34:45.913854 | 
2025-11-24 08:34:45.914021 | TASK [configure-mirrors : Install /etc/pip.conf configuration]
2025-11-24 08:34:46.876898 | controller | changed
2025-11-24 08:34:46.882568 | 
2025-11-24 08:34:46.882637 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors]
2025-11-24 08:34:46.937642 | controller | ok: "/var/lib/zuul/builds/2cdb23a37a644b1cb1f1372e340da67f/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml"
2025-11-24 08:34:46.937859 | controller | ok: All items complete
2025-11-24 08:34:46.937885 | 
2025-11-24 08:34:46.978221 | controller | included: /var/lib/zuul/builds/2cdb23a37a644b1cb1f1372e340da67f/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml
2025-11-24 08:34:46.984897 | 
2025-11-24 08:34:46.984965 | LOOP [configure-mirrors : Install CentOS 9 repository files]
2025-11-24 08:34:47.991298 | controller | changed: "etc/yum.repos.d/centos.repo"
2025-11-24 08:34:48.880889 | controller | changed: "etc/yum.repos.d/centos-addons.repo"
2025-11-24 08:34:48.889745 | 
2025-11-24 08:34:48.889917 | TASK [configure-mirrors : Disable deltrarpm]
2025-11-24 08:34:49.383631 | controller | changed: section and option added
2025-11-24 08:34:49.407823 | 
2025-11-24 08:34:49.407985 | LOOP [configure-mirrors : Update yum/dnf cache]
2025-11-24 08:34:50.243153 | controller | 29 files removed
2025-11-24 08:34:50.243431 | controller | ok: Item: dnf clean all Runtime: 0:00:00.548194
2025-11-24 08:34:50.243498 | controller | changed: All items complete
2025-11-24 08:34:50.243526 | 
2025-11-24 08:35:01.228373 | 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-11-24 08:35:01.228584 | controller | DNF version: 4.14.0
2025-11-24 08:35:01.228623 | controller | cachedir: /var/cache/dnf
2025-11-24 08:35:01.228652 | controller | Making cache files for all metadata files.
2025-11-24 08:35:01.228707 | controller | baseos: has expired and will be refreshed.
2025-11-24 08:35:01.228735 | controller | appstream: has expired and will be refreshed.
2025-11-24 08:35:01.228761 | controller | crb: has expired and will be refreshed.
2025-11-24 08:35:01.228799 | controller | extras-common: has expired and will be refreshed.
2025-11-24 08:35:01.228826 | controller | repo: downloading from remote: baseos
2025-11-24 08:35:01.228853 | controller | CentOS Stream 9 - BaseOS                         56 MB/s | 8.8 MB     00:00
2025-11-24 08:35:01.228877 | controller | baseos: using metadata from Mon 17 Nov 2025 10:24:02 AM EST.
2025-11-24 08:35:01.228902 | controller | repo: downloading from remote: appstream
2025-11-24 08:35:01.228929 | controller | CentOS Stream 9 - AppStream                      70 MB/s |  25 MB     00:00
2025-11-24 08:35:01.228954 | controller | appstream: using metadata from Mon 17 Nov 2025 10:27:35 AM EST.
2025-11-24 08:35:01.228977 | controller | repo: downloading from remote: crb
2025-11-24 08:35:01.229005 | controller | CentOS Stream 9 - CRB                            60 MB/s | 7.3 MB     00:00
2025-11-24 08:35:01.229033 | controller | crb: using metadata from Mon 17 Nov 2025 10:32:16 AM EST.
2025-11-24 08:35:01.229058 | controller | repo: downloading from remote: extras-common
2025-11-24 08:35:01.229082 | controller | CentOS Stream 9 - Extras packages               1.6 MB/s |  20 kB     00:00
2025-11-24 08:35:01.229106 | controller | extras-common: using metadata from Thu 30 Oct 2025 09:20:08 AM EDT.
2025-11-24 08:35:01.229130 | controller | Last metadata expiration check: 0:00:01 ago on Mon 24 Nov 2025 03:34:59 AM EST.
2025-11-24 08:35:01.229155 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)'
2025-11-24 08:35:01.229178 | controller | Completion plugin: Generating completion cache...
2025-11-24 08:35:01.229204 | controller | Metadata cache created.
2025-11-24 08:35:01.229244 | controller | ok: Item: dnf makecache -v Runtime: 0:00:10.713499
2025-11-24 08:35:01.291093 | 
2025-11-24 08:35:01.291224 | PLAY RECAP
2025-11-24 08:35:01.291270 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-24 08:35:01.291295 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-24 08:35:01.291313 | 
2025-11-24 08:35:01.433610 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-11-24 08:35:01.434923 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-24 08:35:02.150190 | 
2025-11-24 08:35:02.150318 | PLAY [all]
2025-11-24 08:35:02.170581 | 
2025-11-24 08:35:02.170681 | TASK [Install binary dependencies]
2025-11-24 08:35:02.224295 | controller | ok
2025-11-24 08:35:02.250258 | 
2025-11-24 08:35:02.250359 | TASK [bindep : Include find tasks]
2025-11-24 08:35:02.281019 | controller | ok
2025-11-24 08:35:02.288812 | controller | included: /var/lib/zuul/builds/2cdb23a37a644b1cb1f1372e340da67f/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-11-24 08:35:02.295008 | 
2025-11-24 08:35:02.295073 | TASK [bindep : Look for bindep.txt]
2025-11-24 08:35:02.735536 | controller | ok
2025-11-24 08:35:02.753357 | 
2025-11-24 08:35:02.753536 | TASK [bindep : Define bindep_file fact]
2025-11-24 08:35:02.812790 | controller | ok
2025-11-24 08:35:02.823136 | 
2025-11-24 08:35:02.823293 | TASK [bindep : Look for other-requirements.txt]
2025-11-24 08:35:02.843427 | controller | skipping: Conditional result was False
2025-11-24 08:35:02.853173 | 
2025-11-24 08:35:02.853290 | TASK [bindep : Define bindep_file fact]
2025-11-24 08:35:02.880300 | controller | skipping: Conditional result was False
2025-11-24 08:35:02.895582 | 
2025-11-24 08:35:02.897262 | TASK [bindep : Look for bindep fallback file]
2025-11-24 08:35:02.953426 | controller | skipping: Conditional result was False
2025-11-24 08:35:02.959991 | 
2025-11-24 08:35:02.960065 | TASK [bindep : Define bindep_file fact]
2025-11-24 08:35:02.985356 | controller | skipping: Conditional result was False
2025-11-24 08:35:02.994968 | 
2025-11-24 08:35:02.995067 | TASK [bindep : Include bindep tasks]
2025-11-24 08:35:03.039260 | controller | ok
2025-11-24 08:35:03.049991 | controller | included: /var/lib/zuul/builds/2cdb23a37a644b1cb1f1372e340da67f/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml
2025-11-24 08:35:03.058385 | 
2025-11-24 08:35:03.058452 | TASK [bindep : Look for bindep command]
2025-11-24 08:35:03.094403 | controller | skipping: Conditional result was False
2025-11-24 08:35:03.102751 | 
2025-11-24 08:35:03.102835 | TASK [bindep : Check for system bindep]
2025-11-24 08:35:03.670952 | controller | ok: Runtime: 0:00:00.005883
2025-11-24 08:35:03.695168 | 
2025-11-24 08:35:03.695353 | TASK [bindep : Define bindep_command fact]
2025-11-24 08:35:03.721116 | controller | skipping: Conditional result was False
2025-11-24 08:35:03.728221 | 
2025-11-24 08:35:03.728290 | TASK [bindep : Include install tasks]
2025-11-24 08:35:03.759177 | controller | ok
2025-11-24 08:35:03.768928 | controller | included: /var/lib/zuul/builds/2cdb23a37a644b1cb1f1372e340da67f/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml
2025-11-24 08:35:03.778967 | 
2025-11-24 08:35:03.779045 | TASK [bindep : Create temp dir for bindep]
2025-11-24 08:35:04.193157 | controller | changed
2025-11-24 08:35:04.202978 | 
2025-11-24 08:35:04.203116 | TASK [Ensure we have pip dependencies]
2025-11-24 08:35:04.240852 | controller | ok
2025-11-24 08:35:04.282189 | 
2025-11-24 08:35:04.282348 | TASK [ensure-pip : Check if pip is installed]
2025-11-24 03:35:04.769555 | controller | /usr/bin/pip3
2025-11-24 03:35:04.794985 | controller | /usr/bin/python3: No module named wheel
2025-11-24 08:35:04.831092 | controller | ok: Runtime: 0:00:00.036911
2025-11-24 08:35:04.840429 | 
2025-11-24 08:35:04.840524 | LOOP [ensure-pip : Install pip from packages]
2025-11-24 08:35:04.886250 | controller | ok: "/var/lib/zuul/builds/2cdb23a37a644b1cb1f1372e340da67f/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml"
2025-11-24 08:35:04.908844 | controller | included: /var/lib/zuul/builds/2cdb23a37a644b1cb1f1372e340da67f/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml
2025-11-24 08:35:04.934641 | 
2025-11-24 08:35:04.934858 | TASK [ensure-pip : Install Python 3 pip]
2025-11-24 08:35:07.051350 | controller | changed
2025-11-24 08:35:07.059147 | 
2025-11-24 08:35:07.059216 | TASK [ensure-pip : Check for EPEL repository]
2025-11-24 08:35:07.143453 | controller | skipping: Conditional result was False
2025-11-24 08:35:07.155528 | 
2025-11-24 08:35:07.155608 | TASK [ensure-pip : Fail with instructions when EPEL is not installed]
2025-11-24 08:35:07.231186 | controller | skipping: Conditional result was False
2025-11-24 08:35:07.237887 | 
2025-11-24 08:35:07.238044 | TASK [ensure-pip : Install Python 2 pip]
2025-11-24 08:35:07.368701 | controller | skipping: Conditional result was False
2025-11-24 08:35:07.374850 | 
2025-11-24 08:35:07.374922 | TASK [ensure-pip : Ensure setuptools]
2025-11-24 08:35:07.425307 | controller | skipping: Conditional result was False
2025-11-24 08:35:07.432413 | 
2025-11-24 08:35:07.432495 | TASK [ensure-pip : Check for ensurepip module]
2025-11-24 08:35:07.996291 | controller | skipping: Conditional result was False
2025-11-24 08:35:08.004033 | 
2025-11-24 08:35:08.004121 | TASK [ensure-pip : Ensure python3-venv]
2025-11-24 08:35:08.033433 | controller | skipping: Conditional result was False
2025-11-24 08:35:08.041171 | 
2025-11-24 08:35:08.041267 | TASK [ensure-pip : Install pip from source]
2025-11-24 08:35:08.079444 | controller | skipping: Conditional result was False
2025-11-24 08:35:08.086125 | 
2025-11-24 08:35:08.086205 | TASK [ensure-pip : Probe for venv python full path]
2025-11-24 03:35:08.374210 | controller | /usr/bin/python3
2025-11-24 08:35:08.630556 | controller | ok: Runtime: 0:00:00.005959
2025-11-24 08:35:08.637532 | 
2025-11-24 08:35:08.637722 | TASK [ensure-pip : Set host default]
2025-11-24 08:35:08.720281 | controller | ok
2025-11-24 08:35:08.730368 | 
2025-11-24 08:35:08.730507 | TASK [ensure-pip : Set ensure_pip_virtualenv_command]
2025-11-24 08:35:08.794953 | controller | ok
2025-11-24 08:35:08.806351 | 
2025-11-24 08:35:08.806460 | TASK [bindep : Install bindep into temporary venv]
2025-11-24 08:35:13.495063 | controller | changed
2025-11-24 08:35:13.501200 | 
2025-11-24 08:35:13.501283 | TASK [bindep : Define bindep_command]
2025-11-24 08:35:13.532808 | controller | ok
2025-11-24 08:35:13.539299 | 
2025-11-24 08:35:13.539364 | LOOP [bindep : Include package tasks]
2025-11-24 08:35:13.601523 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt"
2025-11-24 08:35:13.601789 | controller | ok: All items complete
2025-11-24 08:35:13.601816 | 
2025-11-24 08:35:13.615291 | controller | included: /var/lib/zuul/builds/2cdb23a37a644b1cb1f1372e340da67f/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml
2025-11-24 08:35:13.626829 | 
2025-11-24 08:35:13.626904 | TASK [bindep : Define bindep_run fact]
2025-11-24 08:35:13.657731 | controller | ok
2025-11-24 08:35:13.663186 | 
2025-11-24 08:35:13.663251 | TASK [bindep : Get list of packages to install from bindep]
2025-11-24 03:35:15.135509 | controller | podman
2025-11-24 03:35:15.176868 | controller | python3-jmespath
2025-11-24 03:35:15.176931 | controller | python3-libvirt
2025-11-24 03:35:15.176938 | controller | python3-lxml
2025-11-24 03:35:15.176954 | controller | python3-netaddr
2025-11-24 08:35:15.208961 | controller | ok: Runtime: 0:00:01.192129
2025-11-24 08:35:15.215173 | 
2025-11-24 08:35:15.215247 | TASK [bindep : Install distro packages from bindep]
2025-11-24 08:36:21.263403 | controller | changed
2025-11-24 08:36:21.276567 | 
2025-11-24 08:36:21.276753 | TASK [bindep : Check that packages are installed]
2025-11-24 08:36:22.826464 | controller | ok: Runtime: 0:00:01.111397
2025-11-24 08:36:22.843337 | 
2025-11-24 08:36:22.843481 | TASK [bindep : Fail if we cannot install all packages]
2025-11-24 08:36:22.881739 | controller | skipping: Conditional result was False
2025-11-24 08:36:22.898610 | 
2025-11-24 08:36:22.898770 | TASK [Run test-setup role]
2025-11-24 08:36:22.924132 | controller | ok
2025-11-24 08:36:22.954643 | 
2025-11-24 08:36:22.954873 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-11-24 08:36:23.182838 | controller | ok
2025-11-24 08:36:23.191580 | 
2025-11-24 08:36:23.191817 | TASK [test-setup : Run tools/test-setup.sh]
2025-11-24 08:36:23.735878 | controller | skipping: Conditional result was False
2025-11-24 08:36:23.786621 | 
2025-11-24 08:36:23.786837 | TASK [bindep : Remove bindep temp dir]
2025-11-24 08:36:24.204487 | controller | ok
2025-11-24 08:36:24.227316 | 
2025-11-24 08:36:24.227468 | PLAY RECAP
2025-11-24 08:36:24.227557 | controller | ok: 22 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0
2025-11-24 08:36:24.227602 | 
2025-11-24 08:36:24.370927 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-24 08:36:24.372588 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2025-11-24 08:36:25.028694 | 
2025-11-24 08:36:25.028866 | PLAY [all]
2025-11-24 08:36:25.049532 | 
2025-11-24 08:36:25.049716 | TASK [Abort when test_command variable is undefined]
2025-11-24 08:36:25.076576 | controller | skipping: Conditional result was False
2025-11-24 08:36:25.082849 | 
2025-11-24 08:36:25.082925 | TASK [Convert test_command to list]
2025-11-24 08:36:25.117237 | controller | skipping: Conditional result was False
2025-11-24 08:36:25.125243 | 
2025-11-24 08:36:25.125350 | TASK [Use test_command list]
2025-11-24 08:36:25.185485 | controller | ok
2025-11-24 08:36:25.191355 | 
2025-11-24 08:36:25.191420 | LOOP [Run test_command]
2025-11-24 08:36:25.595920 | controller | no check to run
2025-11-24 08:36:25.596156 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.006032
2025-11-24 08:36:25.625153 | 
2025-11-24 08:36:25.625265 | PLAY RECAP
2025-11-24 08:36:25.625312 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-11-24 08:36:25.625332 | 
2025-11-24 08:36:25.749366 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2025-11-24 08:36:25.750381 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-24 08:36:26.348290 | 
2025-11-24 08:36:26.348434 | PLAY [all]
2025-11-24 08:36:26.369023 | 
2025-11-24 08:36:26.369103 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-11-24 08:36:26.765959 | controller | changed: non-zero return code
2025-11-24 08:36:26.772445 | 
2025-11-24 08:36:26.772522 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-11-24 08:36:26.798730 | controller | skipping: Conditional result was False
2025-11-24 08:36:26.804583 | 
2025-11-24 08:36:26.804652 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-11-24 08:36:26.836052 | 
2025-11-24 08:36:26.836189 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-11-24 08:36:26.867176 | 
2025-11-24 08:36:26.867315 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-11-24 08:36:26.892555 | controller | skipping: Conditional result was False
2025-11-24 08:36:26.898772 | 
2025-11-24 08:36:26.898863 | LOOP [fetch-subunit-output : Generate subunit file]
2025-11-24 08:36:26.935359 | 
2025-11-24 08:36:26.935590 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-11-24 08:36:26.961572 | controller | skipping: Conditional result was False
2025-11-24 08:36:26.972799 | 
2025-11-24 08:36:26.972963 | TASK [fetch-subunit-output : Remove the temporary file]
2025-11-24 08:36:27.000430 | controller | skipping: Conditional result was False
2025-11-24 08:36:27.010313 | 
2025-11-24 08:36:27.010463 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-11-24 08:36:27.037327 | controller | skipping: Conditional result was False
2025-11-24 08:36:27.076126 | 
2025-11-24 08:36:27.076391 | PLAY RECAP
2025-11-24 08:36:27.076466 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-24 08:36:27.076499 | 
2025-11-24 08:36:27.223566 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-24 08:36:27.224557 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-24 08:36:27.912145 | 
2025-11-24 08:36:27.912307 | PLAY [all]
2025-11-24 08:36:27.933436 | 
2025-11-24 08:36:27.933606 | TASK [include_role : fetch-output]
2025-11-24 08:36:27.975294 | controller | ok
2025-11-24 08:36:28.003396 | 
2025-11-24 08:36:28.003635 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-24 08:36:28.091468 | controller | skipping: Conditional result was False
2025-11-24 08:36:28.099142 | 
2025-11-24 08:36:28.099228 | TASK [fetch-output : Set log path for single node]
2025-11-24 08:36:28.142500 | controller | ok
2025-11-24 08:36:28.150631 | 
2025-11-24 08:36:28.150826 | LOOP [fetch-output : Ensure local output dirs]
2025-11-24 08:36:28.612352 | controller -> localhost | ok: "/var/lib/zuul/builds/2cdb23a37a644b1cb1f1372e340da67f/work/logs"
2025-11-24 08:36:28.967639 | controller -> localhost | changed: "/var/lib/zuul/builds/2cdb23a37a644b1cb1f1372e340da67f/work/artifacts"
2025-11-24 08:36:29.293707 | controller -> localhost | changed: "/var/lib/zuul/builds/2cdb23a37a644b1cb1f1372e340da67f/work/docs"
2025-11-24 08:36:29.318950 | 
2025-11-24 08:36:29.319169 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-24 08:36:30.106951 | controller | changed: .d..t...... ./
2025-11-24 08:36:30.107439 | controller | changed: All items complete
2025-11-24 08:36:30.107503 | 
2025-11-24 08:36:30.679677 | controller | changed: .d..t...... ./
2025-11-24 08:36:31.298169 | controller | changed: .d..t...... ./
2025-11-24 08:36:31.318472 | 
2025-11-24 08:36:31.318603 | TASK [include_role : fetch-output-openshift]
2025-11-24 08:36:31.345180 | controller | skipping: Conditional result was False
2025-11-24 08:36:31.355043 | 
2025-11-24 08:36:31.355155 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-24 08:36:31.980799 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.016653
2025-11-24 08:36:32.248717 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.017325
2025-11-24 08:36:32.283055 | 
2025-11-24 08:36:32.283259 | PLAY [all]
2025-11-24 08:36:32.300268 | 
2025-11-24 08:36:32.300444 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-11-24 08:36:32.822587 | controller | changed
2025-11-24 08:36:32.865902 | 
2025-11-24 08:36:32.866044 | PLAY RECAP
2025-11-24 08:36:32.866123 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-11-24 08:36:32.866175 | 
2025-11-24 08:36:33.000651 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-24 08:36:33.002777 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-11-24 08:36:33.715276 | 
2025-11-24 08:36:33.715408 | PLAY [localhost]
2025-11-24 08:36:33.733980 | 
2025-11-24 08:36:33.734111 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-24 08:36:34.172109 | localhost | changed
2025-11-24 08:36:34.176870 | 
2025-11-24 08:36:34.176943 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-24 08:36:34.206484 | localhost | ok
2025-11-24 08:36:34.214829 | 
2025-11-24 08:36:34.214898 | TASK [add-fileserver : Create SSH private key tempfile]
2025-11-24 08:36:34.622786 | localhost | changed
2025-11-24 08:36:34.628307 | 
2025-11-24 08:36:34.628394 | TASK [add-fileserver : Create SSH private key from secret]
2025-11-24 08:36:35.572443 | localhost | changed
2025-11-24 08:36:35.586866 | 
2025-11-24 08:36:35.587075 | TASK [add-fileserver : Add fileserver ssh key]
2025-11-24 08:36:36.068886 | localhost | Identity added: /var/lib/zuul/builds/2cdb23a37a644b1cb1f1372e340da67f/work/tmp/ansible.3zxpf47w (/var/lib/zuul/builds/2cdb23a37a644b1cb1f1372e340da67f/work/tmp/ansible.3zxpf47w)
2025-11-24 08:36:36.069289 | localhost | ok: Runtime: 0:00:00.014901
2025-11-24 08:36:36.079531 | 
2025-11-24 08:36:36.079718 | TASK [add-fileserver : Remove SSH private key from disk]
2025-11-24 08:36:36.404996 | localhost | ok: Runtime: 0:00:00.010621
2025-11-24 08:36:36.417706 | 
2025-11-24 08:36:36.417871 | TASK [add-fileserver : Add fileserver to inventory]
2025-11-24 08:36:36.507809 | localhost | changed
2025-11-24 08:36:36.518872 | 
2025-11-24 08:36:36.519036 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-11-24 08:36:37.043901 | localhost | changed
2025-11-24 08:36:37.074204 | 
2025-11-24 08:36:37.074412 | PLAY [localhost]
2025-11-24 08:36:37.099852 | 
2025-11-24 08:36:37.100019 | TASK [Generate bulk log download script]
2025-11-24 08:36:37.121615 | localhost | ok
2025-11-24 08:36:37.136560 | 
2025-11-24 08:36:37.136740 | TASK [local-log-download : Check API endpoint is defined]
2025-11-24 08:36:37.187975 | localhost | ok: All assertions passed
2025-11-24 08:36:37.193303 | 
2025-11-24 08:36:37.193376 | TASK [local-log-download : Create download script]
2025-11-24 08:36:37.740550 | localhost -> localhost | changed
2025-11-24 08:36:37.751996 | 
2025-11-24 08:36:37.752095 | TASK [Register quick-download link]
2025-11-24 08:36:37.825784 | localhost | ok
2025-11-24 08:36:37.854762 | 
2025-11-24 08:36:37.855048 | PLAY [logserver.rdoproject.org]
2025-11-24 08:36:37.879846 | 
2025-11-24 08:36:37.880014 | TASK [Set zuul-log-path fact]
2025-11-24 08:36:37.902854 | logserver.rdoproject.org | ok
2025-11-24 08:36:37.922490 | 
2025-11-24 08:36:37.922772 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-24 08:36:37.954286 | logserver.rdoproject.org | ok
2025-11-24 08:36:37.963311 | 
2025-11-24 08:36:37.963495 | TASK [upload-logs : Create log directories]
2025-11-24 08:36:38.825361 | logserver.rdoproject.org | changed
2025-11-24 08:36:38.833227 | 
2025-11-24 08:36:38.833374 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-11-24 08:36:39.221305 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.009687
2025-11-24 08:36:39.229130 | 
2025-11-24 08:36:39.229407 | TASK [upload-logs : Upload logs to log server]
2025-11-24 08:36:40.047817 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-11-24 08:36:40.054491 | 
2025-11-24 08:36:40.054625 | LOOP [upload-logs : Compress console log and json output]
2025-11-24 08:36:40.125994 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-24 08:36:40.138273 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-24 08:36:40.156865 | 
2025-11-24 08:36:40.157128 | LOOP [upload-logs : Upload compressed console log and json output]
2025-11-24 08:36:40.233010 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-24 08:36:40.233534 | 
2025-11-24 08:36:40.239209 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-24 08:36:40.256594 | 
2025-11-24 08:36:40.256907 | LOOP [upload-logs : Upload console log and json output]