2025-12-05 15:18:27.902057 | Job console starting...
2025-12-05 15:18:27.912308 | Updating repositories
2025-12-05 15:18:27.938046 | Preparing job workspace
2025-12-05 15:18:31.410379 | Running Ansible setup...
2025-12-05 15:18:35.162041 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 15:18:35.788477 | 
2025-12-05 15:18:35.788593 | PLAY [localhost]
2025-12-05 15:18:35.810454 | 
2025-12-05 15:18:35.810546 | TASK [Gathering Facts]
2025-12-05 15:18:36.792995 | localhost | ok
2025-12-05 15:18:36.820925 | 
2025-12-05 15:18:36.821114 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-05 15:18:37.286325 | localhost -> localhost | changed
2025-12-05 15:18:37.292153 | 
2025-12-05 15:18:37.292227 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-05 15:18:38.375456 | localhost -> localhost | changed
2025-12-05 15:18:38.391156 | 
2025-12-05 15:18:38.391252 | TASK [Setup log path fact]
2025-12-05 15:18:38.423696 | localhost | ok
2025-12-05 15:18:38.440674 | 
2025-12-05 15:18:38.440756 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 15:18:38.478847 | localhost | ok
2025-12-05 15:18:38.486934 | 
2025-12-05 15:18:38.487045 | TASK [emit-job-header : Print job information]
2025-12-05 15:18:38.517514 | # Job Information
2025-12-05 15:18:38.517725 | Ansible Version: 2.15.12
2025-12-05 15:18:38.517754 | Job: cifmw-molecule-ci_dcn_site
2025-12-05 15:18:38.517773 | Pipeline: github-check
2025-12-05 15:18:38.517792 | Executor: ze01.softwarefactory-project.io
2025-12-05 15:18:38.517809 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3539
2025-12-05 15:18:38.517828 | Log URL (when completed): https://logserver.rdoproject.org/bf7/rdoproject.org/bf7c72164acf4dd4b0796effb1d06bab/
2025-12-05 15:18:38.517847 | Event ID: 748a4b40-d1ed-11f0-8510-c0ded0ca5b3b
2025-12-05 15:18:38.521929 | 
2025-12-05 15:18:38.522005 | LOOP [emit-job-header : Print node information]
2025-12-05 15:18:38.635099 | localhost | ok:
2025-12-05 15:18:38.635317 | localhost | # Node Information
2025-12-05 15:18:38.635348 | localhost | Inventory Hostname: controller
2025-12-05 15:18:38.635377 | localhost | Hostname: np0005547210
2025-12-05 15:18:38.635399 | localhost | Username: zuul
2025-12-05 15:18:38.635421 | localhost | Distro: CentOS 9
2025-12-05 15:18:38.635440 | localhost | Provider: vexxhost-nodepool-tripleo
2025-12-05 15:18:38.635460 | localhost | Region: RegionOne
2025-12-05 15:18:38.635486 | localhost | Label: cloud-centos-9-stream-tripleo
2025-12-05 15:18:38.635512 | localhost | Product Name: OpenStack Nova
2025-12-05 15:18:38.635536 | localhost | Interface IP: 38.102.83.234
2025-12-05 15:18:38.682341 | 
2025-12-05 15:18:38.682443 | PLAY [all]
2025-12-05 15:18:38.696123 | 
2025-12-05 15:18:38.696224 | TASK [Gather network facts]
2025-12-05 15:18:39.260210 | controller | ok
2025-12-05 15:18:39.288879 | 
2025-12-05 15:18:39.288970 | TASK [include_role : start-zuul-console]
2025-12-05 15:18:39.313916 | controller | ok
2025-12-05 15:18:39.335666 | 
2025-12-05 15:18:39.335753 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-05 15:18:39.783316 | controller | ok
2025-12-05 15:18:39.793592 | 
2025-12-05 15:18:39.793721 | TASK [include_role : add-build-sshkey]
2025-12-05 15:18:39.813536 | controller | ok
2025-12-05 15:18:39.838908 | 
2025-12-05 15:18:39.839032 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-05 15:18:40.132058 | controller -> localhost | ok
2025-12-05 15:18:40.138407 | 
2025-12-05 15:18:40.138469 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-05 15:18:40.192217 | controller | ok
2025-12-05 15:18:40.209334 | controller | included: /var/lib/zuul/builds/bf7c72164acf4dd4b0796effb1d06bab/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-05 15:18:40.215132 | 
2025-12-05 15:18:40.215193 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-05 15:18:41.056193 | controller -> localhost | Generating public/private rsa key pair.
2025-12-05 15:18:41.056364 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/bf7c72164acf4dd4b0796effb1d06bab/work/bf7c72164acf4dd4b0796effb1d06bab_id_rsa.
2025-12-05 15:18:41.056393 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/bf7c72164acf4dd4b0796effb1d06bab/work/bf7c72164acf4dd4b0796effb1d06bab_id_rsa.pub.
2025-12-05 15:18:41.056415 | controller -> localhost | The key fingerprint is:
2025-12-05 15:18:41.056434 | controller -> localhost | SHA256:IeA6pffk9fb0GPKx1tKW993rfk97S97nKB4csiLXZ5U zuul-build-sshkey
2025-12-05 15:18:41.056452 | controller -> localhost | The key's randomart image is:
2025-12-05 15:18:41.056470 | controller -> localhost | +---[RSA 3072]----+
2025-12-05 15:18:41.056487 | controller -> localhost | |    .            |
2025-12-05 15:18:41.056506 | controller -> localhost | |   . .           |
2025-12-05 15:18:41.056523 | controller -> localhost | |    o . .        |
2025-12-05 15:18:41.056541 | controller -> localhost | |   +   . .     . |
2025-12-05 15:18:41.056559 | controller -> localhost | |  + . . S . . E  |
2025-12-05 15:18:41.056578 | controller -> localhost | |   o + . o + o   |
2025-12-05 15:18:41.056596 | controller -> localhost | |      + o * Oo o.|
2025-12-05 15:18:41.056614 | controller -> localhost | |       o o B+BBoX|
2025-12-05 15:18:41.056632 | controller -> localhost | |           o*+=X/|
2025-12-05 15:18:41.056649 | controller -> localhost | +----[SHA256]-----+
2025-12-05 15:18:41.056691 | controller -> localhost | ok: Runtime: 0:00:00.290379
2025-12-05 15:18:41.063868 | 
2025-12-05 15:18:41.063929 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-05 15:18:41.102949 | controller | ok
2025-12-05 15:18:41.113444 | controller | included: /var/lib/zuul/builds/bf7c72164acf4dd4b0796effb1d06bab/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-05 15:18:41.136795 | 
2025-12-05 15:18:41.136889 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-05 15:18:41.171046 | controller | skipping: Conditional result was False
2025-12-05 15:18:41.176748 | 
2025-12-05 15:18:41.176837 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-05 15:18:41.875683 | controller | changed
2025-12-05 15:18:41.880866 | 
2025-12-05 15:18:41.880929 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-05 15:18:42.162480 | controller | ok
2025-12-05 15:18:42.179473 | 
2025-12-05 15:18:42.179577 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-05 15:18:43.307860 | controller | changed
2025-12-05 15:18:43.314811 | 
2025-12-05 15:18:43.314875 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-05 15:18:44.473004 | controller | changed
2025-12-05 15:18:44.479166 | 
2025-12-05 15:18:44.479242 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-05 15:18:44.533134 | controller | skipping: Conditional result was False
2025-12-05 15:18:44.539339 | 
2025-12-05 15:18:44.539407 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-05 15:18:45.107480 | controller -> localhost | changed
2025-12-05 15:18:45.118236 | 
2025-12-05 15:18:45.118302 | TASK [add-build-sshkey : Add back temp key]
2025-12-05 15:18:45.423771 | controller -> localhost | Identity added: /var/lib/zuul/builds/bf7c72164acf4dd4b0796effb1d06bab/work/bf7c72164acf4dd4b0796effb1d06bab_id_rsa (zuul-build-sshkey)
2025-12-05 15:18:45.424061 | controller -> localhost | ok: Runtime: 0:00:00.015030
2025-12-05 15:18:45.429452 | 
2025-12-05 15:18:45.429511 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-05 15:18:45.918141 | controller | ok
2025-12-05 15:18:45.924875 | 
2025-12-05 15:18:45.925178 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-05 15:18:45.962117 | controller | skipping: Conditional result was False
2025-12-05 15:18:45.975641 | 
2025-12-05 15:18:45.975738 | TASK [include_role : validate-host]
2025-12-05 15:18:46.004109 | controller | ok
2025-12-05 15:18:46.039511 | 
2025-12-05 15:18:46.039612 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-05 15:18:46.089480 | controller | ok
2025-12-05 15:18:46.134348 | 
2025-12-05 15:18:46.134463 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-05 15:18:46.484904 | controller -> localhost | ok
2025-12-05 15:18:46.491157 | 
2025-12-05 15:18:46.491220 | TASK [validate-host : Collect information about the host]
2025-12-05 15:18:47.371434 | controller | ok
2025-12-05 15:18:47.387663 | 
2025-12-05 15:18:47.387754 | TASK [validate-host : Sanitize hostname]
2025-12-05 15:18:47.446430 | controller | ok
2025-12-05 15:18:47.454298 | 
2025-12-05 15:18:47.454367 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-05 15:18:48.101132 | controller -> localhost | changed
2025-12-05 15:18:48.107225 | 
2025-12-05 15:18:48.107298 | TASK [validate-host : Collect information about zuul worker]
2025-12-05 15:18:48.556499 | controller | ok
2025-12-05 15:18:48.561560 | 
2025-12-05 15:18:48.561623 | TASK [validate-host : Write out all zuul information for each host]
2025-12-05 15:18:49.069639 | controller -> localhost | changed
2025-12-05 15:18:49.080516 | 
2025-12-05 15:18:49.080610 | TASK [include_role : prepare-workspace-openshift]
2025-12-05 15:18:49.127428 | controller | skipping: Conditional result was False
2025-12-05 15:18:49.135786 | 
2025-12-05 15:18:49.135875 | TASK [include_role : remove-zuul-sshkey]
2025-12-05 15:18:49.200472 | controller | skipping: Conditional result was False
2025-12-05 15:18:49.209004 | 
2025-12-05 15:18:49.209141 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-05 15:18:49.531415 | controller | ok: "logs"
2025-12-05 15:18:49.531658 | controller | ok: All items complete
2025-12-05 15:18:49.531687 | 
2025-12-05 15:18:49.801068 | controller | ok: "artifacts"
2025-12-05 15:18:50.032103 | controller | ok: "docs"
2025-12-05 15:18:50.044777 | 
2025-12-05 15:18:50.044924 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-05 15:18:50.313759 | controller | changed: "logs"
2025-12-05 15:18:50.573270 | controller | changed: "artifacts"
2025-12-05 15:18:50.766101 | controller | changed: "docs"
2025-12-05 15:18:50.810556 | 
2025-12-05 15:18:50.810654 | PLAY RECAP
2025-12-05 15:18:50.810696 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-05 15:18:50.810722 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 15:18:50.810739 | 
2025-12-05 15:18:51.028922 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 15:18:51.029707 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-12-05 15:18:51.649856 | 
2025-12-05 15:18:51.649970 | PLAY [localhost]
2025-12-05 15:18:51.666766 | 
2025-12-05 15:18:51.666865 | TASK [configure-ssh-config-fips : Ensure ssh config directory]
2025-12-05 15:18:52.061886 | localhost | ok
2025-12-05 15:18:52.067323 | 
2025-12-05 15:18:52.067407 | TASK [configure-ssh-config-fips : Setup ssh config]
2025-12-05 15:18:52.808252 | localhost | changed
2025-12-05 15:18:52.828927 | 
2025-12-05 15:18:52.828984 | PLAY [all]
2025-12-05 15:18:52.849483 | 
2025-12-05 15:18:52.849559 | TASK [include_role : prepare-workspace]
2025-12-05 15:18:52.880797 | controller | ok
2025-12-05 15:18:52.894890 | 
2025-12-05 15:18:52.894957 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-05 15:18:53.316789 | controller | ok
2025-12-05 15:18:53.324448 | 
2025-12-05 15:18:53.324571 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-05 15:18:55.064065 | controller | Output suppressed because no_log was given
2025-12-05 15:18:55.077475 | 
2025-12-05 15:18:55.077570 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-05 15:18:55.392147 | controller | changed: "logs"
2025-12-05 15:18:55.616927 | controller | changed: "artifacts"
2025-12-05 15:18:55.861547 | controller | changed: "docs"
2025-12-05 15:18:55.875633 | 
2025-12-05 15:18:55.875711 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-05 15:18:56.175165 | controller | changed: "logs"
2025-12-05 15:18:56.175382 | controller | changed: All items complete
2025-12-05 15:18:56.175410 | 
2025-12-05 15:18:56.419890 | controller | changed: "artifacts"
2025-12-05 15:18:56.668797 | controller | changed: "docs"
2025-12-05 15:18:56.695074 | 
2025-12-05 15:18:56.695179 | TASK [Check if worker can sudo]
2025-12-05 15:18:57.749768 | controller | ok: Runtime: 0:00:00.045914
2025-12-05 15:18:57.755545 | 
2025-12-05 15:18:57.755611 | TASK [configure-mirrors : Gather needed facts]
2025-12-05 15:18:57.823224 | controller | skipping: Conditional result was False
2025-12-05 15:18:57.829533 | 
2025-12-05 15:18:57.829621 | TASK [configure-mirrors : Set up infrastructure mirrors]
2025-12-05 15:18:57.930209 | controller | ok
2025-12-05 15:18:57.939846 | controller | included: /var/lib/zuul/builds/bf7c72164acf4dd4b0796effb1d06bab/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml
2025-12-05 15:18:57.946935 | 
2025-12-05 15:18:57.947002 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir]
2025-12-05 15:18:58.294275 | controller | ok
2025-12-05 15:18:58.299696 | 
2025-12-05 15:18:58.299756 | LOOP [configure-mirrors : Include OS-specific variables]
2025-12-05 15:18:58.363808 | controller | ok: "/var/lib/zuul/builds/bf7c72164acf4dd4b0796effb1d06bab/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml"
2025-12-05 15:18:58.371216 | 
2025-12-05 15:18:58.371313 | TASK [configure-mirrors : Install /etc/pip.conf configuration]
2025-12-05 15:18:59.320511 | controller | changed
2025-12-05 15:18:59.325956 | 
2025-12-05 15:18:59.326044 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors]
2025-12-05 15:18:59.399154 | controller | ok: "/var/lib/zuul/builds/bf7c72164acf4dd4b0796effb1d06bab/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml"
2025-12-05 15:18:59.399323 | controller | ok: All items complete
2025-12-05 15:18:59.399349 | 
2025-12-05 15:18:59.505212 | controller | included: /var/lib/zuul/builds/bf7c72164acf4dd4b0796effb1d06bab/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml
2025-12-05 15:18:59.511720 | 
2025-12-05 15:18:59.511785 | LOOP [configure-mirrors : Install CentOS 9 repository files]
2025-12-05 15:19:00.541036 | controller | changed: "etc/yum.repos.d/centos.repo"
2025-12-05 15:19:01.520081 | controller | changed: "etc/yum.repos.d/centos-addons.repo"
2025-12-05 15:19:01.548910 | 
2025-12-05 15:19:01.549164 | TASK [configure-mirrors : Disable deltrarpm]
2025-12-05 15:19:02.350674 | controller | changed: section and option added
2025-12-05 15:19:02.380341 | 
2025-12-05 15:19:02.380475 | LOOP [configure-mirrors : Update yum/dnf cache]
2025-12-05 15:19:03.011084 | controller | 29 files removed
2025-12-05 15:19:03.011282 | controller | ok: Item: dnf clean all Runtime: 0:00:00.301899
2025-12-05 15:19:03.011321 | controller | changed: All items complete
2025-12-05 15:19:03.011344 | 
2025-12-05 15:19:13.669805 | controller | Loaded plugins: builddep, changelog, config-manager, copr, debug, debuginfo-install, download, generate_completion_cache, groups-manager, needs-restarting, playground, repoclosure, repodiff, repograph, repomanage, reposync, system-upgrade
2025-12-05 15:19:13.669915 | controller | DNF version: 4.14.0
2025-12-05 15:19:13.669951 | controller | cachedir: /var/cache/dnf
2025-12-05 15:19:13.669983 | controller | Making cache files for all metadata files.
2025-12-05 15:19:13.670010 | controller | baseos: has expired and will be refreshed.
2025-12-05 15:19:13.670064 | controller | appstream: has expired and will be refreshed.
2025-12-05 15:19:13.670090 | controller | crb: has expired and will be refreshed.
2025-12-05 15:19:13.670121 | controller | extras-common: has expired and will be refreshed.
2025-12-05 15:19:13.670144 | controller | repo: downloading from remote: baseos
2025-12-05 15:19:13.670167 | controller | CentOS Stream 9 - BaseOS                         41 MB/s | 8.8 MB     00:00
2025-12-05 15:19:13.670190 | controller | baseos: using metadata from Mon 01 Dec 2025 12:58:39 PM EST.
2025-12-05 15:19:13.670212 | controller | repo: downloading from remote: appstream
2025-12-05 15:19:13.670234 | controller | CentOS Stream 9 - AppStream                      83 MB/s |  25 MB     00:00
2025-12-05 15:19:13.670256 | controller | appstream: using metadata from Mon 01 Dec 2025 01:01:55 PM EST.
2025-12-05 15:19:13.670277 | controller | repo: downloading from remote: crb
2025-12-05 15:19:13.670299 | controller | CentOS Stream 9 - CRB                            64 MB/s | 7.3 MB     00:00
2025-12-05 15:19:13.670322 | controller | crb: using metadata from Mon 01 Dec 2025 01:06:07 PM EST.
2025-12-05 15:19:13.670345 | controller | repo: downloading from remote: extras-common
2025-12-05 15:19:13.670368 | controller | CentOS Stream 9 - Extras packages               1.2 MB/s |  20 kB     00:00
2025-12-05 15:19:13.670391 | controller | extras-common: using metadata from Thu 30 Oct 2025 09:20:08 AM EDT.
2025-12-05 15:19:13.670414 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)'
2025-12-05 15:19:13.670437 | controller | Completion plugin: Generating completion cache...
2025-12-05 15:19:13.670460 | controller | Metadata cache created.
2025-12-05 15:19:13.670495 | controller | ok: Item: dnf makecache -v Runtime: 0:00:10.371959
2025-12-05 15:19:13.691984 | 
2025-12-05 15:19:13.692115 | PLAY RECAP
2025-12-05 15:19:13.692172 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-05 15:19:13.692238 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 15:19:13.692270 | 
2025-12-05 15:19:13.809342 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-12-05 15:19:13.810176 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-05 15:19:14.512797 | 
2025-12-05 15:19:14.512920 | PLAY [all]
2025-12-05 15:19:14.535527 | 
2025-12-05 15:19:14.535634 | TASK [Install binary dependencies]
2025-12-05 15:19:14.595498 | controller | ok
2025-12-05 15:19:14.615253 | 
2025-12-05 15:19:14.615374 | TASK [bindep : Include find tasks]
2025-12-05 15:19:14.643939 | controller | ok
2025-12-05 15:19:14.651559 | controller | included: /var/lib/zuul/builds/bf7c72164acf4dd4b0796effb1d06bab/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-12-05 15:19:14.658007 | 
2025-12-05 15:19:14.658175 | TASK [bindep : Look for bindep.txt]
2025-12-05 15:19:15.090120 | controller | ok
2025-12-05 15:19:15.097423 | 
2025-12-05 15:19:15.097541 | TASK [bindep : Define bindep_file fact]
2025-12-05 15:19:15.127478 | controller | ok
2025-12-05 15:19:15.133026 | 
2025-12-05 15:19:15.133130 | TASK [bindep : Look for other-requirements.txt]
2025-12-05 15:19:15.147164 | controller | skipping: Conditional result was False
2025-12-05 15:19:15.158779 | 
2025-12-05 15:19:15.158921 | TASK [bindep : Define bindep_file fact]
2025-12-05 15:19:15.187589 | controller | skipping: Conditional result was False
2025-12-05 15:19:15.193667 | 
2025-12-05 15:19:15.193739 | TASK [bindep : Look for bindep fallback file]
2025-12-05 15:19:15.217886 | controller | skipping: Conditional result was False
2025-12-05 15:19:15.224322 | 
2025-12-05 15:19:15.224394 | TASK [bindep : Define bindep_file fact]
2025-12-05 15:19:15.250628 | controller | skipping: Conditional result was False
2025-12-05 15:19:15.256776 | 
2025-12-05 15:19:15.256844 | TASK [bindep : Include bindep tasks]
2025-12-05 15:19:15.299870 | controller | ok
2025-12-05 15:19:15.306858 | controller | included: /var/lib/zuul/builds/bf7c72164acf4dd4b0796effb1d06bab/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml
2025-12-05 15:19:15.317971 | 
2025-12-05 15:19:15.318080 | TASK [bindep : Look for bindep command]
2025-12-05 15:19:15.341835 | controller | skipping: Conditional result was False
2025-12-05 15:19:15.349987 | 
2025-12-05 15:19:15.350116 | TASK [bindep : Check for system bindep]
2025-12-05 15:19:15.887232 | controller | ok: Runtime: 0:00:00.007534
2025-12-05 15:19:15.892952 | 
2025-12-05 15:19:15.893033 | TASK [bindep : Define bindep_command fact]
2025-12-05 15:19:15.938465 | controller | skipping: Conditional result was False
2025-12-05 15:19:15.944487 | 
2025-12-05 15:19:15.944569 | TASK [bindep : Include install tasks]
2025-12-05 15:19:15.985977 | controller | ok
2025-12-05 15:19:15.995316 | controller | included: /var/lib/zuul/builds/bf7c72164acf4dd4b0796effb1d06bab/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml
2025-12-05 15:19:16.005880 | 
2025-12-05 15:19:16.005984 | TASK [bindep : Create temp dir for bindep]
2025-12-05 15:19:16.437225 | controller | changed
2025-12-05 15:19:16.445839 | 
2025-12-05 15:19:16.445935 | TASK [Ensure we have pip dependencies]
2025-12-05 15:19:16.470031 | controller | ok
2025-12-05 15:19:16.500120 | 
2025-12-05 15:19:16.500226 | TASK [ensure-pip : Check if pip is installed]
2025-12-05 10:19:16.817606 | controller | /usr/bin/pip3
2025-12-05 10:19:16.869253 | controller | /usr/bin/python3: No module named wheel
2025-12-05 15:19:17.030053 | controller | ok: Runtime: 0:00:00.065314
2025-12-05 15:19:17.035559 | 
2025-12-05 15:19:17.035619 | LOOP [ensure-pip : Install pip from packages]
2025-12-05 15:19:17.059578 | controller | ok: "/var/lib/zuul/builds/bf7c72164acf4dd4b0796effb1d06bab/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml"
2025-12-05 15:19:17.067652 | controller | included: /var/lib/zuul/builds/bf7c72164acf4dd4b0796effb1d06bab/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml
2025-12-05 15:19:17.077707 | 
2025-12-05 15:19:17.077777 | TASK [ensure-pip : Install Python 3 pip]
2025-12-05 15:19:19.137289 | controller | changed
2025-12-05 15:19:19.148901 | 
2025-12-05 15:19:19.149064 | TASK [ensure-pip : Check for EPEL repository]
2025-12-05 15:19:19.217198 | controller | skipping: Conditional result was False
2025-12-05 15:19:19.227248 | 
2025-12-05 15:19:19.227360 | TASK [ensure-pip : Fail with instructions when EPEL is not installed]
2025-12-05 15:19:19.277926 | controller | skipping: Conditional result was False
2025-12-05 15:19:19.292139 | 
2025-12-05 15:19:19.292280 | TASK [ensure-pip : Install Python 2 pip]
2025-12-05 15:19:19.359399 | controller | skipping: Conditional result was False
2025-12-05 15:19:19.373562 | 
2025-12-05 15:19:19.373702 | TASK [ensure-pip : Ensure setuptools]
2025-12-05 15:19:19.407869 | controller | skipping: Conditional result was False
2025-12-05 15:19:19.413872 | 
2025-12-05 15:19:19.413935 | TASK [ensure-pip : Check for ensurepip module]
2025-12-05 15:19:19.943350 | controller | skipping: Conditional result was False
2025-12-05 15:19:19.949700 | 
2025-12-05 15:19:19.949774 | TASK [ensure-pip : Ensure python3-venv]
2025-12-05 15:19:19.984099 | controller | skipping: Conditional result was False
2025-12-05 15:19:19.990995 | 
2025-12-05 15:19:19.991079 | TASK [ensure-pip : Install pip from source]
2025-12-05 15:19:20.015328 | controller | skipping: Conditional result was False
2025-12-05 15:19:20.021415 | 
2025-12-05 15:19:20.021480 | TASK [ensure-pip : Probe for venv python full path]
2025-12-05 10:19:20.285828 | controller | /usr/bin/python3
2025-12-05 15:19:20.551550 | controller | ok: Runtime: 0:00:00.008065
2025-12-05 15:19:20.561834 | 
2025-12-05 15:19:20.562062 | TASK [ensure-pip : Set host default]
2025-12-05 15:19:20.636074 | controller | ok
2025-12-05 15:19:20.643375 | 
2025-12-05 15:19:20.643461 | TASK [ensure-pip : Set ensure_pip_virtualenv_command]
2025-12-05 15:19:20.709553 | controller | ok
2025-12-05 15:19:20.729666 | 
2025-12-05 15:19:20.729775 | TASK [bindep : Install bindep into temporary venv]
2025-12-05 15:19:24.677496 | controller | changed
2025-12-05 15:19:24.687286 | 
2025-12-05 15:19:24.687388 | TASK [bindep : Define bindep_command]
2025-12-05 15:19:24.716578 | controller | ok
2025-12-05 15:19:24.723821 | 
2025-12-05 15:19:24.723884 | LOOP [bindep : Include package tasks]
2025-12-05 15:19:24.785990 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt"
2025-12-05 15:19:24.786179 | controller | ok: All items complete
2025-12-05 15:19:24.786206 | 
2025-12-05 15:19:24.798842 | controller | included: /var/lib/zuul/builds/bf7c72164acf4dd4b0796effb1d06bab/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml
2025-12-05 15:19:24.815198 | 
2025-12-05 15:19:24.815277 | TASK [bindep : Define bindep_run fact]
2025-12-05 15:19:24.878658 | controller | ok
2025-12-05 15:19:24.885344 | 
2025-12-05 15:19:24.885436 | TASK [bindep : Get list of packages to install from bindep]
2025-12-05 10:19:26.277032 | controller | podman
2025-12-05 10:19:26.323719 | controller | python3-jmespath
2025-12-05 10:19:26.323757 | controller | python3-libvirt
2025-12-05 10:19:26.323767 | controller | python3-lxml
2025-12-05 10:19:26.323778 | controller | python3-netaddr
2025-12-05 15:19:26.418541 | controller | ok: Runtime: 0:00:01.170938
2025-12-05 15:19:26.431557 | 
2025-12-05 15:19:26.431740 | TASK [bindep : Install distro packages from bindep]
2025-12-05 15:20:31.862715 | controller | changed
2025-12-05 15:20:31.911331 | 
2025-12-05 15:20:31.911473 | TASK [bindep : Check that packages are installed]
2025-12-05 15:20:33.946967 | controller | ok: Runtime: 0:00:01.233976
2025-12-05 15:20:33.955387 | 
2025-12-05 15:20:33.955483 | TASK [bindep : Fail if we cannot install all packages]
2025-12-05 15:20:33.981292 | controller | skipping: Conditional result was False
2025-12-05 15:20:33.995865 | 
2025-12-05 15:20:33.995964 | TASK [Run test-setup role]
2025-12-05 15:20:34.015520 | controller | ok
2025-12-05 15:20:34.034452 | 
2025-12-05 15:20:34.034564 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-12-05 15:20:34.297762 | controller | ok
2025-12-05 15:20:34.303279 | 
2025-12-05 15:20:34.303345 | TASK [test-setup : Run tools/test-setup.sh]
2025-12-05 15:20:34.833171 | controller | skipping: Conditional result was False
2025-12-05 15:20:34.882732 | 
2025-12-05 15:20:34.882877 | TASK [bindep : Remove bindep temp dir]
2025-12-05 15:20:35.259262 | controller | ok
2025-12-05 15:20:35.285526 | 
2025-12-05 15:20:35.285688 | PLAY RECAP
2025-12-05 15:20:35.285773 | controller | ok: 22 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0
2025-12-05 15:20:35.285822 | 
2025-12-05 15:20:35.413617 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-05 15:20:35.414509 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2025-12-05 15:20:36.019892 | 
2025-12-05 15:20:36.020001 | PLAY [all]
2025-12-05 15:20:36.040221 | 
2025-12-05 15:20:36.040302 | TASK [Abort when test_command variable is undefined]
2025-12-05 15:20:36.085002 | controller | skipping: Conditional result was False
2025-12-05 15:20:36.091000 | 
2025-12-05 15:20:36.091092 | TASK [Convert test_command to list]
2025-12-05 15:20:36.125490 | controller | skipping: Conditional result was False
2025-12-05 15:20:36.132083 | 
2025-12-05 15:20:36.132153 | TASK [Use test_command list]
2025-12-05 15:20:36.181884 | controller | ok
2025-12-05 15:20:36.187270 | 
2025-12-05 15:20:36.187334 | LOOP [Run test_command]
2025-12-05 15:20:36.607172 | controller | no check to run
2025-12-05 15:20:36.607351 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.004442
2025-12-05 15:20:36.630444 | 
2025-12-05 15:20:36.630543 | PLAY RECAP
2025-12-05 15:20:36.630584 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-05 15:20:36.630604 | 
2025-12-05 15:20:36.725963 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master]
2025-12-05 15:20:36.727741 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-05 15:20:37.329314 | 
2025-12-05 15:20:37.329427 | PLAY [all]
2025-12-05 15:20:37.350226 | 
2025-12-05 15:20:37.350309 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-12-05 15:20:37.714579 | controller | changed: non-zero return code
2025-12-05 15:20:37.720743 | 
2025-12-05 15:20:37.720818 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-12-05 15:20:37.745763 | controller | skipping: Conditional result was False
2025-12-05 15:20:37.751847 | 
2025-12-05 15:20:37.751915 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-12-05 15:20:37.782486 | 
2025-12-05 15:20:37.782648 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-12-05 15:20:37.813617 | 
2025-12-05 15:20:37.813748 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-12-05 15:20:37.838074 | controller | skipping: Conditional result was False
2025-12-05 15:20:37.844846 | 
2025-12-05 15:20:37.844943 | LOOP [fetch-subunit-output : Generate subunit file]
2025-12-05 15:20:37.875166 | 
2025-12-05 15:20:37.875299 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-12-05 15:20:37.899621 | controller | skipping: Conditional result was False
2025-12-05 15:20:37.905010 | 
2025-12-05 15:20:37.905090 | TASK [fetch-subunit-output : Remove the temporary file]
2025-12-05 15:20:37.929107 | controller | skipping: Conditional result was False
2025-12-05 15:20:37.935054 | 
2025-12-05 15:20:37.935119 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-12-05 15:20:37.950463 | controller | skipping: Conditional result was False
2025-12-05 15:20:37.975436 | 
2025-12-05 15:20:37.975519 | PLAY RECAP
2025-12-05 15:20:37.975561 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-05 15:20:37.975581 | 
2025-12-05 15:20:38.090131 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-05 15:20:38.090999 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 15:20:38.633048 | 
2025-12-05 15:20:38.633161 | PLAY [all]
2025-12-05 15:20:38.651554 | 
2025-12-05 15:20:38.651630 | TASK [include_role : fetch-output]
2025-12-05 15:20:38.690494 | controller | ok
2025-12-05 15:20:38.707384 | 
2025-12-05 15:20:38.707463 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-05 15:20:38.771629 | controller | skipping: Conditional result was False
2025-12-05 15:20:38.777266 | 
2025-12-05 15:20:38.777334 | TASK [fetch-output : Set log path for single node]
2025-12-05 15:20:38.815992 | controller | ok
2025-12-05 15:20:38.821010 | 
2025-12-05 15:20:38.821093 | LOOP [fetch-output : Ensure local output dirs]
2025-12-05 15:20:39.162162 | controller -> localhost | ok: "/var/lib/zuul/builds/bf7c72164acf4dd4b0796effb1d06bab/work/logs"
2025-12-05 15:20:39.403619 | controller -> localhost | changed: "/var/lib/zuul/builds/bf7c72164acf4dd4b0796effb1d06bab/work/artifacts"
2025-12-05 15:20:39.633384 | controller -> localhost | changed: "/var/lib/zuul/builds/bf7c72164acf4dd4b0796effb1d06bab/work/docs"
2025-12-05 15:20:39.673336 | 
2025-12-05 15:20:39.673570 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-05 15:20:40.322319 | controller | changed: .d..t...... ./
2025-12-05 15:20:40.322559 | controller | changed: All items complete
2025-12-05 15:20:40.322596 | 
2025-12-05 15:20:40.852164 | controller | changed: .d..t...... ./
2025-12-05 15:20:41.321778 | controller | changed: .d..t...... ./
2025-12-05 15:20:41.344272 | 
2025-12-05 15:20:41.344434 | TASK [include_role : fetch-output-openshift]
2025-12-05 15:20:41.369244 | controller | skipping: Conditional result was False
2025-12-05 15:20:41.377331 | 
2025-12-05 15:20:41.377421 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-05 15:20:41.794823 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.013164
2025-12-05 15:20:42.028011 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007576
2025-12-05 15:20:42.061161 | 
2025-12-05 15:20:42.061257 | PLAY [all]
2025-12-05 15:20:42.077967 | 
2025-12-05 15:20:42.078292 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-05 15:20:42.555057 | controller | changed
2025-12-05 15:20:42.597733 | 
2025-12-05 15:20:42.597850 | PLAY RECAP
2025-12-05 15:20:42.597903 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-05 15:20:42.597932 | 
2025-12-05 15:20:42.696903 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 15:20:42.697672 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-05 15:20:43.308137 | 
2025-12-05 15:20:43.308267 | PLAY [localhost]
2025-12-05 15:20:43.335237 | 
2025-12-05 15:20:43.335363 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-05 15:20:43.716679 | localhost | changed
2025-12-05 15:20:43.727175 | 
2025-12-05 15:20:43.727325 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-05 15:20:43.778434 | localhost | ok
2025-12-05 15:20:43.794144 | 
2025-12-05 15:20:43.794357 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-05 15:20:44.152886 | localhost | changed
2025-12-05 15:20:44.157732 | 
2025-12-05 15:20:44.157799 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-05 15:20:44.852094 | localhost | changed
2025-12-05 15:20:44.857767 | 
2025-12-05 15:20:44.857856 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-05 15:20:45.297603 | localhost | Identity added: /var/lib/zuul/builds/bf7c72164acf4dd4b0796effb1d06bab/work/tmp/ansible.n05fb271 (/var/lib/zuul/builds/bf7c72164acf4dd4b0796effb1d06bab/work/tmp/ansible.n05fb271)
2025-12-05 15:20:45.297786 | localhost | ok: Runtime: 0:00:00.012746
2025-12-05 15:20:45.302178 | 
2025-12-05 15:20:45.302243 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-05 15:20:45.601315 | localhost | ok: Runtime: 0:00:00.005393
2025-12-05 15:20:45.606338 | 
2025-12-05 15:20:45.606403 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-05 15:20:45.655403 | localhost | changed
2025-12-05 15:20:45.659824 | 
2025-12-05 15:20:45.659888 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-05 15:20:46.099480 | localhost | changed
2025-12-05 15:20:46.129002 | 
2025-12-05 15:20:46.129143 | PLAY [localhost]
2025-12-05 15:20:46.148715 | 
2025-12-05 15:20:46.148838 | TASK [Generate bulk log download script]
2025-12-05 15:20:46.185150 | localhost | ok
2025-12-05 15:20:46.200145 | 
2025-12-05 15:20:46.200271 | TASK [local-log-download : Check API endpoint is defined]
2025-12-05 15:20:46.244665 | localhost | ok: All assertions passed
2025-12-05 15:20:46.249901 | 
2025-12-05 15:20:46.249976 | TASK [local-log-download : Create download script]
2025-12-05 15:20:46.697565 | localhost -> localhost | changed
2025-12-05 15:20:46.712315 | 
2025-12-05 15:20:46.712430 | TASK [Register quick-download link]
2025-12-05 15:20:46.770177 | localhost | ok
2025-12-05 15:20:46.856942 | 
2025-12-05 15:20:46.857062 | PLAY [logserver.rdoproject.org]
2025-12-05 15:20:46.873766 | 
2025-12-05 15:20:46.873843 | TASK [Set zuul-log-path fact]
2025-12-05 15:20:46.893514 | logserver.rdoproject.org | ok
2025-12-05 15:20:46.902458 | 
2025-12-05 15:20:46.902523 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 15:20:46.924117 | logserver.rdoproject.org | ok
2025-12-05 15:20:46.929829 | 
2025-12-05 15:20:46.929889 | TASK [upload-logs : Create log directories]
2025-12-05 15:20:47.585448 | logserver.rdoproject.org | changed
2025-12-05 15:20:47.588749 | 
2025-12-05 15:20:47.588809 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-05 15:20:47.934743 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.005630
2025-12-05 15:20:47.939943 | 
2025-12-05 15:20:47.940027 | TASK [upload-logs : Upload logs to log server]
2025-12-05 15:20:48.608383 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-05 15:20:48.611292 | 
2025-12-05 15:20:48.611357 | LOOP [upload-logs : Compress console log and json output]
2025-12-05 15:20:48.655174 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:20:48.664347 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:20:48.670258 | 
2025-12-05 15:20:48.670390 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-05 15:20:48.711635 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:20:48.711856 | 
2025-12-05 15:20:48.716689 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 15:20:48.722620 | 
2025-12-05 15:20:48.722687 | LOOP [upload-logs : Upload console log and json output]