2026-06-12 17:20:15.164912 | Job console starting 2026-06-12 17:20:15.305408 | Updating git repos 2026-06-12 17:20:15.337064 | Cloning repos into workspace 2026-06-12 17:20:15.972837 | Restoring repo states 2026-06-12 17:20:15.987023 | Merging changes 2026-06-12 17:20:17.120280 | Checking out repos 2026-06-12 17:20:17.256487 | Preparing playbooks 2026-06-12 17:20:21.405489 | Running Ansible setup 2026-06-12 17:20:25.351966 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-06-12 17:20:25.995120 | 2026-06-12 17:20:25.995275 | PLAY [localhost] 2026-06-12 17:20:26.003325 | 2026-06-12 17:20:26.003415 | TASK [Gathering Facts] 2026-06-12 17:20:27.014380 | localhost | ok 2026-06-12 17:20:27.027986 | 2026-06-12 17:20:27.028222 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-12 17:20:27.538773 | localhost -> localhost | changed 2026-06-12 17:20:27.548366 | 2026-06-12 17:20:27.548503 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-12 17:20:28.563526 | localhost -> localhost | changed 2026-06-12 17:20:28.578546 | 2026-06-12 17:20:28.578739 | TASK [Setup log path fact] 2026-06-12 17:20:28.609525 | localhost | ok 2026-06-12 17:20:28.631705 | 2026-06-12 17:20:28.631813 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-12 17:20:28.680374 | localhost | ok 2026-06-12 17:20:28.688625 | 2026-06-12 17:20:28.688728 | TASK [emit-job-header : Print job information] 2026-06-12 17:20:28.733396 | # Job Information 2026-06-12 17:20:28.733662 | Ansible Version: 2.16.18 2026-06-12 17:20:28.733718 | Job: cifmw-molecule-ci_dcn_site 2026-06-12 17:20:28.733759 | Pipeline: github-check 2026-06-12 17:20:28.733796 | Executor: 38.102.83.40 2026-06-12 17:20:28.733831 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3963 2026-06-12 17:20:28.733874 | Log URL (when completed): https://gateway-cloud-softwarefactory.apps.ocp.cloud.ci.centos.org/logs/4ab/rdoproject.org/4abd1f01bfee47d4adf30900ae55e610/ 2026-06-12 17:20:28.733918 | Event ID: c1bb5f60-6682-11f1-95d6-a2f9a7a822df 2026-06-12 17:20:28.739850 | 2026-06-12 17:20:28.739983 | LOOP [emit-job-header : Print node information] 2026-06-12 17:20:28.883521 | localhost | ok: 2026-06-12 17:20:28.883878 | localhost | # Node Information 2026-06-12 17:20:28.883937 | localhost | Inventory Hostname: controller 2026-06-12 17:20:28.883985 | localhost | Hostname: np0000072194 2026-06-12 17:20:28.884028 | localhost | Username: zuul 2026-06-12 17:20:28.884073 | localhost | Distro: CentOS 9 2026-06-12 17:20:28.884113 | localhost | Provider: vexxhost-nodepool-tripleo 2026-06-12 17:20:28.884150 | localhost | Region: RegionOne 2026-06-12 17:20:28.884188 | localhost | Label: cloud-centos-9-stream-tripleo 2026-06-12 17:20:28.884225 | localhost | Product Name: OpenStack Nova 2026-06-12 17:20:28.884261 | localhost | Interface IP: 38.102.83.58 2026-06-12 17:20:28.897537 | 2026-06-12 17:20:28.897700 | PLAY [all] 2026-06-12 17:20:28.905186 | 2026-06-12 17:20:28.905273 | TASK [Gather network facts] 2026-06-12 17:20:29.397495 | controller | ok 2026-06-12 17:20:29.409696 | 2026-06-12 17:20:29.409781 | TASK [include_role : start-zuul-console] 2026-06-12 17:20:29.430066 | controller | ok 2026-06-12 17:20:29.443627 | 2026-06-12 17:20:29.443717 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-06-12 17:20:29.921883 | controller | ok 2026-06-12 17:20:29.935260 | 2026-06-12 17:20:29.935401 | TASK [include_role : add-build-sshkey] 2026-06-12 17:20:29.969001 | controller | ok 2026-06-12 17:20:29.990652 | 2026-06-12 17:20:29.990784 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-12 17:20:30.303972 | controller -> localhost | ok 2026-06-12 17:20:30.312918 | 2026-06-12 17:20:30.313044 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-12 17:20:30.346231 | controller | ok 2026-06-12 17:20:30.375856 | controller | included: /var/lib/zuul/builds/4abd1f01bfee47d4adf30900ae55e610/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-12 17:20:30.383016 | 2026-06-12 17:20:30.383102 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-12 17:20:31.138735 | controller -> localhost | Generating public/private rsa key pair. 2026-06-12 17:20:31.139090 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/4abd1f01bfee47d4adf30900ae55e610/work/4abd1f01bfee47d4adf30900ae55e610_id_rsa 2026-06-12 17:20:31.139162 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/4abd1f01bfee47d4adf30900ae55e610/work/4abd1f01bfee47d4adf30900ae55e610_id_rsa.pub 2026-06-12 17:20:31.139211 | controller -> localhost | The key fingerprint is: 2026-06-12 17:20:31.139256 | controller -> localhost | SHA256:eypHjobOjobx6rYZ0Q3/0Lyz/JBK/4UheWqDP4/ULdw zuul-build-sshkey 2026-06-12 17:20:31.139298 | controller -> localhost | The key's randomart image is: 2026-06-12 17:20:31.139340 | controller -> localhost | +---[RSA 3072]----+ 2026-06-12 17:20:31.139379 | controller -> localhost | | | 2026-06-12 17:20:31.139420 | controller -> localhost | | | 2026-06-12 17:20:31.139461 | controller -> localhost | | . | 2026-06-12 17:20:31.139503 | controller -> localhost | | . + o . | 2026-06-12 17:20:31.139542 | controller -> localhost | | . . + =So | 2026-06-12 17:20:31.139642 | controller -> localhost | |. . + X.= | 2026-06-12 17:20:31.139698 | controller -> localhost | | = + #.=.E | 2026-06-12 17:20:31.139744 | controller -> localhost | |..=oo @.Ooo | 2026-06-12 17:20:31.139784 | controller -> localhost | |+*oo+o O*+ | 2026-06-12 17:20:31.139823 | controller -> localhost | +----[SHA256]-----+ 2026-06-12 17:20:31.139919 | controller -> localhost | ok: Runtime: 0:00:00.218611 2026-06-12 17:20:31.148041 | 2026-06-12 17:20:31.148178 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-12 17:20:31.188307 | controller | ok 2026-06-12 17:20:31.206385 | controller | included: /var/lib/zuul/builds/4abd1f01bfee47d4adf30900ae55e610/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-12 17:20:31.216224 | 2026-06-12 17:20:31.216314 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-12 17:20:31.251908 | controller | skipping: Conditional result was False 2026-06-12 17:20:31.257996 | 2026-06-12 17:20:31.258087 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-12 17:20:31.751388 | controller | changed 2026-06-12 17:20:31.755370 | 2026-06-12 17:20:31.755471 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-12 17:20:32.054429 | controller | ok 2026-06-12 17:20:32.063020 | 2026-06-12 17:20:32.063191 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-12 17:20:32.838753 | controller | changed 2026-06-12 17:20:32.846448 | 2026-06-12 17:20:32.846610 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-12 17:20:33.678339 | controller | changed 2026-06-12 17:20:33.686663 | 2026-06-12 17:20:33.686796 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-12 17:20:33.713748 | controller | skipping: Conditional result was False 2026-06-12 17:20:33.722229 | 2026-06-12 17:20:33.722367 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-12 17:20:34.168782 | controller -> localhost | changed 2026-06-12 17:20:34.183899 | 2026-06-12 17:20:34.184029 | TASK [add-build-sshkey : Add back temp key] 2026-06-12 17:20:34.552882 | controller -> localhost | Identity added: /var/lib/zuul/builds/4abd1f01bfee47d4adf30900ae55e610/work/4abd1f01bfee47d4adf30900ae55e610_id_rsa (zuul-build-sshkey) 2026-06-12 17:20:34.553236 | controller -> localhost | ok: Runtime: 0:00:00.022018 2026-06-12 17:20:34.562030 | 2026-06-12 17:20:34.562176 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-12 17:20:34.930709 | controller | ok 2026-06-12 17:20:34.937520 | 2026-06-12 17:20:34.937764 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-12 17:20:34.975201 | controller | skipping: Conditional result was False 2026-06-12 17:20:34.989388 | 2026-06-12 17:20:34.989517 | TASK [include_role : validate-host] 2026-06-12 17:20:35.015755 | controller | ok 2026-06-12 17:20:35.045739 | 2026-06-12 17:20:35.045825 | TASK [validate-host : Define zuul_info_dir fact] 2026-06-12 17:20:35.084533 | controller | ok 2026-06-12 17:20:35.088659 | 2026-06-12 17:20:35.088739 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-06-12 17:20:35.418058 | controller -> localhost | ok 2026-06-12 17:20:35.427869 | 2026-06-12 17:20:35.428005 | TASK [validate-host : Collect information about the host] 2026-06-12 17:20:36.148325 | controller | ok 2026-06-12 17:20:36.164670 | 2026-06-12 17:20:36.164806 | TASK [validate-host : Sanitize hostname] 2026-06-12 17:20:36.249377 | controller | ok 2026-06-12 17:20:36.257355 | 2026-06-12 17:20:36.257499 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-06-12 17:20:36.845136 | controller -> localhost | changed 2026-06-12 17:20:36.854154 | 2026-06-12 17:20:36.854255 | TASK [validate-host : Collect information about zuul worker] 2026-06-12 17:20:37.282371 | controller | ok 2026-06-12 17:20:37.290884 | 2026-06-12 17:20:37.291022 | TASK [validate-host : Write out all zuul information for each host] 2026-06-12 17:20:37.881264 | controller -> localhost | changed 2026-06-12 17:20:37.889173 | 2026-06-12 17:20:37.889239 | TASK [include_role : prepare-workspace-openshift] 2026-06-12 17:20:37.915525 | controller | skipping: Conditional result was False 2026-06-12 17:20:37.920262 | 2026-06-12 17:20:37.920324 | TASK [include_role : remove-zuul-sshkey] 2026-06-12 17:20:37.946111 | controller | skipping: Conditional result was False 2026-06-12 17:20:37.950849 | 2026-06-12 17:20:37.950915 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-12 17:20:38.211288 | controller | ok: "logs" 2026-06-12 17:20:38.211823 | controller | ok: All items complete 2026-06-12 17:20:38.211893 | 2026-06-12 17:20:38.466849 | controller | ok: "artifacts" 2026-06-12 17:20:38.685951 | controller | ok: "docs" 2026-06-12 17:20:38.704619 | 2026-06-12 17:20:38.704765 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-12 17:20:38.949915 | controller | changed: "logs" 2026-06-12 17:20:39.176846 | controller | changed: "artifacts" 2026-06-12 17:20:39.395487 | controller | changed: "docs" 2026-06-12 17:20:39.416187 | 2026-06-12 17:20:39.416292 | PLAY RECAP 2026-06-12 17:20:39.416380 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2026-06-12 17:20:39.416438 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-12 17:20:39.416480 | 2026-06-12 17:20:39.581786 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-06-12 17:20:39.584474 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-06-12 17:20:40.245363 | 2026-06-12 17:20:40.245513 | PLAY [localhost] 2026-06-12 17:20:40.254929 | 2026-06-12 17:20:40.255005 | TASK [configure-ssh-config-fips : Ensure ssh config directory] 2026-06-12 17:20:40.668780 | localhost | changed 2026-06-12 17:20:40.674061 | 2026-06-12 17:20:40.674152 | TASK [configure-ssh-config-fips : Setup ssh config] 2026-06-12 17:20:41.482730 | localhost | changed 2026-06-12 17:20:41.491843 | 2026-06-12 17:20:41.491921 | PLAY [all] 2026-06-12 17:20:41.499225 | 2026-06-12 17:20:41.499301 | TASK [include_role : prepare-workspace] 2026-06-12 17:20:41.543474 | controller | ok 2026-06-12 17:20:41.574781 | 2026-06-12 17:20:41.574923 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-12 17:20:41.967270 | controller | ok 2026-06-12 17:20:41.979292 | 2026-06-12 17:20:41.979481 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-12 17:20:43.532226 | controller | Output suppressed because no_log was given 2026-06-12 17:20:43.542037 | 2026-06-12 17:20:43.542133 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-12 17:20:43.803202 | controller | changed: "logs" 2026-06-12 17:20:44.022434 | controller | changed: "artifacts" 2026-06-12 17:20:44.234668 | controller | changed: "docs" 2026-06-12 17:20:44.247717 | 2026-06-12 17:20:44.247952 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-12 17:20:44.499100 | controller | changed: "logs" 2026-06-12 17:20:44.499688 | controller | changed: All items complete 2026-06-12 17:20:44.499761 | 2026-06-12 17:20:44.762717 | controller | changed: "artifacts" 2026-06-12 17:20:45.002418 | controller | changed: "docs" 2026-06-12 17:20:45.026835 | 2026-06-12 17:20:45.027046 | TASK [Check if worker can sudo] 2026-06-12 17:20:45.575015 | controller | ok: Runtime: 0:00:00.049106 2026-06-12 17:20:45.583501 | 2026-06-12 17:20:45.583662 | TASK [configure-mirrors : Gather needed facts] 2026-06-12 17:20:45.662864 | controller | skipping: Conditional result was False 2026-06-12 17:20:45.672629 | 2026-06-12 17:20:45.672802 | TASK [configure-mirrors : Set up infrastructure mirrors] 2026-06-12 17:20:45.765636 | controller | ok 2026-06-12 17:20:45.773008 | controller | included: /var/lib/zuul/builds/4abd1f01bfee47d4adf30900ae55e610/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml 2026-06-12 17:20:45.777199 | 2026-06-12 17:20:45.777255 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir] 2026-06-12 17:20:46.088702 | controller | ok 2026-06-12 17:20:46.093741 | 2026-06-12 17:20:46.093825 | LOOP [configure-mirrors : Include OS-specific variables] 2026-06-12 17:20:46.182420 | controller | ok: "/var/lib/zuul/builds/4abd1f01bfee47d4adf30900ae55e610/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml" 2026-06-12 17:20:46.196255 | 2026-06-12 17:20:46.196360 | TASK [configure-mirrors : Install /etc/pip.conf configuration] 2026-06-12 17:20:47.134975 | controller | changed 2026-06-12 17:20:47.145810 | 2026-06-12 17:20:47.145947 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors] 2026-06-12 17:20:47.316269 | controller | ok: "/var/lib/zuul/builds/4abd1f01bfee47d4adf30900ae55e610/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml" 2026-06-12 17:20:47.316460 | controller | ok: All items complete 2026-06-12 17:20:47.316497 | 2026-06-12 17:20:47.395698 | controller | included: /var/lib/zuul/builds/4abd1f01bfee47d4adf30900ae55e610/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml 2026-06-12 17:20:47.405134 | 2026-06-12 17:20:47.405232 | LOOP [configure-mirrors : Install CentOS 9 repository files] 2026-06-12 17:20:48.413154 | controller | changed: "etc/yum.repos.d/centos.repo" 2026-06-12 17:20:49.217885 | controller | changed: "etc/yum.repos.d/centos-addons.repo" 2026-06-12 17:20:49.231467 | 2026-06-12 17:20:49.231717 | TASK [configure-mirrors : Disable deltrarpm] 2026-06-12 17:20:49.706017 | controller | changed: section and option added 2026-06-12 17:20:49.730821 | 2026-06-12 17:20:49.731135 | LOOP [configure-mirrors : Update yum/dnf cache] 2026-06-12 17:20:50.509081 | controller | 29 files removed 2026-06-12 17:20:50.509567 | controller | ok: Item: dnf clean all Runtime: 0:00:00.429640 2026-06-12 17:20:50.509718 | controller | changed: All items complete 2026-06-12 17:20:50.509774 | 2026-06-12 17:21:02.292870 | controller | Loaded plugins: builddep, changelog, config-manager, copr, debug, debuginfo-install, download, generate_completion_cache, groups-manager, needs-restarting, playground, repoclosure, repodiff, repograph, repomanage, reposync, system-upgrade 2026-06-12 17:21:02.293042 | controller | DNF version: 4.14.0 2026-06-12 17:21:02.293096 | controller | cachedir: /var/cache/dnf 2026-06-12 17:21:02.293138 | controller | Making cache files for all metadata files. 2026-06-12 17:21:02.293178 | controller | baseos: has expired and will be refreshed. 2026-06-12 17:21:02.293239 | controller | appstream: has expired and will be refreshed. 2026-06-12 17:21:02.293277 | controller | crb: has expired and will be refreshed. 2026-06-12 17:21:02.293313 | controller | extras-common: has expired and will be refreshed. 2026-06-12 17:21:02.293349 | controller | repo: downloading from remote: baseos 2026-06-12 17:21:02.293384 | controller | CentOS Stream 9 - BaseOS 73 MB/s | 8.9 MB 00:00 2026-06-12 17:21:02.293419 | controller | baseos: using metadata from Thu 11 Jun 2026 01:27:06 PM EDT. 2026-06-12 17:21:02.293454 | controller | repo: downloading from remote: appstream 2026-06-12 17:21:02.293489 | controller | CentOS Stream 9 - AppStream 115 MB/s | 28 MB 00:00 2026-06-12 17:21:02.293523 | controller | appstream: using metadata from Thu 11 Jun 2026 01:30:52 PM EDT. 2026-06-12 17:21:02.293558 | controller | repo: downloading from remote: crb 2026-06-12 17:21:02.293648 | controller | CentOS Stream 9 - CRB 56 MB/s | 8.3 MB 00:00 2026-06-12 17:21:02.293689 | controller | crb: using metadata from Thu 11 Jun 2026 01:35:27 PM EDT. 2026-06-12 17:21:02.293725 | controller | repo: downloading from remote: extras-common 2026-06-12 17:21:02.293760 | controller | CentOS Stream 9 - Extras packages 1.6 MB/s | 21 kB 00:00 2026-06-12 17:21:02.293795 | controller | extras-common: using metadata from Wed 10 Jun 2026 10:05:57 AM EDT. 2026-06-12 17:21:02.293829 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)' 2026-06-12 17:21:02.293863 | controller | Completion plugin: Generating completion cache... 2026-06-12 17:21:02.293898 | controller | Metadata cache created. 2026-06-12 17:21:02.293952 | controller | ok: Item: dnf makecache -v Runtime: 0:00:11.500166 2026-06-12 17:21:02.299484 | 2026-06-12 17:21:02.299725 | PLAY RECAP 2026-06-12 17:21:02.299823 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-12 17:21:02.299879 | localhost | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-12 17:21:02.299916 | 2026-06-12 17:21:02.489419 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-06-12 17:21:02.491946 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-06-12 17:21:03.113255 | 2026-06-12 17:21:03.113367 | PLAY [all] 2026-06-12 17:21:03.123946 | 2026-06-12 17:21:03.124016 | TASK [Install binary dependencies] 2026-06-12 17:21:03.194334 | controller | ok 2026-06-12 17:21:03.217504 | 2026-06-12 17:21:03.217707 | TASK [bindep : Include find tasks] 2026-06-12 17:21:03.258187 | controller | ok 2026-06-12 17:21:03.266939 | controller | included: /var/lib/zuul/builds/4abd1f01bfee47d4adf30900ae55e610/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-06-12 17:21:03.273419 | 2026-06-12 17:21:03.273527 | TASK [bindep : Look for bindep.txt] 2026-06-12 17:21:03.713716 | controller | ok 2026-06-12 17:21:03.724519 | 2026-06-12 17:21:03.724818 | TASK [bindep : Define bindep_file fact] 2026-06-12 17:21:03.761757 | controller | ok 2026-06-12 17:21:03.771854 | 2026-06-12 17:21:03.772126 | TASK [bindep : Look for other-requirements.txt] 2026-06-12 17:21:03.789101 | controller | skipping: Conditional result was False 2026-06-12 17:21:03.799254 | 2026-06-12 17:21:03.799430 | TASK [bindep : Define bindep_file fact] 2026-06-12 17:21:03.826292 | controller | skipping: Conditional result was False 2026-06-12 17:21:03.837481 | 2026-06-12 17:21:03.837731 | TASK [bindep : Look for bindep fallback file] 2026-06-12 17:21:03.875279 | controller | skipping: Conditional result was False 2026-06-12 17:21:03.880557 | 2026-06-12 17:21:03.880648 | TASK [bindep : Define bindep_file fact] 2026-06-12 17:21:03.905182 | controller | skipping: Conditional result was False 2026-06-12 17:21:03.914377 | 2026-06-12 17:21:03.914510 | TASK [bindep : Include bindep tasks] 2026-06-12 17:21:03.949734 | controller | ok 2026-06-12 17:21:03.961811 | controller | included: /var/lib/zuul/builds/4abd1f01bfee47d4adf30900ae55e610/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml 2026-06-12 17:21:03.977707 | 2026-06-12 17:21:03.977793 | TASK [bindep : Look for bindep command] 2026-06-12 17:21:04.003079 | controller | skipping: Conditional result was False 2026-06-12 17:21:04.010356 | 2026-06-12 17:21:04.010439 | TASK [bindep : Check for system bindep] 2026-06-12 17:21:04.548712 | controller | ok: Runtime: 0:00:00.005147 2026-06-12 17:21:04.558563 | 2026-06-12 17:21:04.558777 | TASK [bindep : Define bindep_command fact] 2026-06-12 17:21:04.599379 | controller | skipping: Conditional result was False 2026-06-12 17:21:04.609177 | 2026-06-12 17:21:04.609322 | TASK [bindep : Include install tasks] 2026-06-12 17:21:04.675042 | controller | ok 2026-06-12 17:21:04.688742 | controller | included: /var/lib/zuul/builds/4abd1f01bfee47d4adf30900ae55e610/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml 2026-06-12 17:21:04.699484 | 2026-06-12 17:21:04.699603 | TASK [bindep : Create temp dir for bindep] 2026-06-12 17:21:05.099569 | controller | changed 2026-06-12 17:21:05.108203 | 2026-06-12 17:21:05.108333 | TASK [Ensure we have pip dependencies] 2026-06-12 17:21:05.153681 | controller | ok 2026-06-12 17:21:05.196435 | 2026-06-12 17:21:05.196537 | TASK [ensure-pip : Check if pip is installed] 2026-06-12 13:21:05.508303 | controller | /usr/bin/pip3 2026-06-12 13:21:05.532368 | controller | /usr/bin/python3: No module named wheel 2026-06-12 17:21:05.732933 | controller | ok: Runtime: 0:00:00.039423 2026-06-12 17:21:05.741700 | 2026-06-12 17:21:05.741835 | LOOP [ensure-pip : Install pip from packages] 2026-06-12 17:21:05.801402 | controller | ok: "/var/lib/zuul/builds/4abd1f01bfee47d4adf30900ae55e610/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml" 2026-06-12 17:21:05.826969 | controller | included: /var/lib/zuul/builds/4abd1f01bfee47d4adf30900ae55e610/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml 2026-06-12 17:21:05.892190 | 2026-06-12 17:21:05.892334 | TASK [ensure-pip : Install Python 3 pip] 2026-06-12 17:21:07.384175 | controller | ok: Nothing to do 2026-06-12 17:21:07.393307 | 2026-06-12 17:21:07.393441 | TASK [ensure-pip : Install Python3-wheel on older CentOS] 2026-06-12 17:21:08.900643 | controller | changed 2026-06-12 17:21:08.910298 | 2026-06-12 17:21:08.910432 | TASK [ensure-pip : Check for EPEL repository] 2026-06-12 17:21:08.979344 | controller | skipping: Conditional result was False 2026-06-12 17:21:08.990510 | 2026-06-12 17:21:08.990689 | TASK [ensure-pip : Fail with instructions when EPEL is not installed] 2026-06-12 17:21:09.061044 | controller | skipping: Conditional result was False 2026-06-12 17:21:09.076360 | 2026-06-12 17:21:09.076533 | TASK [ensure-pip : Install Python 2 pip] 2026-06-12 17:21:09.145139 | controller | skipping: Conditional result was False 2026-06-12 17:21:09.156202 | 2026-06-12 17:21:09.156404 | TASK [ensure-pip : Ensure setuptools] 2026-06-12 17:21:09.185165 | controller | skipping: Conditional result was False 2026-06-12 17:21:09.196378 | 2026-06-12 17:21:09.196515 | TASK [ensure-pip : Check for ensurepip module] 2026-06-12 17:21:09.733813 | controller | skipping: Conditional result was False 2026-06-12 17:21:09.746732 | 2026-06-12 17:21:09.746891 | TASK [ensure-pip : Ensure python3-venv] 2026-06-12 17:21:09.776655 | controller | skipping: Conditional result was False 2026-06-12 17:21:09.789670 | 2026-06-12 17:21:09.789840 | TASK [ensure-pip : Install pip from source] 2026-06-12 17:21:09.826754 | controller | skipping: Conditional result was False 2026-06-12 17:21:09.842601 | 2026-06-12 17:21:09.842815 | TASK [ensure-pip : Probe for venv python full path] 2026-06-12 13:21:10.127308 | controller | /usr/bin/python3 2026-06-12 17:21:10.388948 | controller | ok: Runtime: 0:00:00.005007 2026-06-12 17:21:10.397689 | 2026-06-12 17:21:10.397826 | TASK [ensure-pip : Set host default] 2026-06-12 17:21:10.453474 | controller | ok 2026-06-12 17:21:10.461877 | 2026-06-12 17:21:10.462008 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2026-06-12 17:21:10.552608 | controller | ok 2026-06-12 17:21:10.569925 | 2026-06-12 17:21:10.570055 | TASK [bindep : Install bindep into temporary venv] 2026-06-12 17:21:15.095014 | controller | changed 2026-06-12 17:21:15.103747 | 2026-06-12 17:21:15.103878 | TASK [bindep : Define bindep_command] 2026-06-12 17:21:15.148084 | controller | ok 2026-06-12 17:21:15.156851 | 2026-06-12 17:21:15.156994 | LOOP [bindep : Include package tasks] 2026-06-12 17:21:15.233179 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt" 2026-06-12 17:21:15.233516 | controller | ok: All items complete 2026-06-12 17:21:15.233568 | 2026-06-12 17:21:15.253735 | controller | included: /var/lib/zuul/builds/4abd1f01bfee47d4adf30900ae55e610/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml 2026-06-12 17:21:15.277791 | 2026-06-12 17:21:15.277981 | TASK [bindep : Define bindep_run fact] 2026-06-12 17:21:15.332695 | controller | ok 2026-06-12 17:21:15.338358 | 2026-06-12 17:21:15.338450 | TASK [bindep : Get list of packages to install from bindep] 2026-06-12 13:21:16.691512 | controller | podman 2026-06-12 13:21:16.691649 | controller | python3-jmespath 2026-06-12 13:21:16.691656 | controller | python3-libvirt 2026-06-12 13:21:16.691663 | controller | python3-lxml 2026-06-12 13:21:16.691668 | controller | python3-netaddr 2026-06-12 17:21:16.878707 | controller | ok: Runtime: 0:00:01.092753 2026-06-12 17:21:16.887693 | 2026-06-12 17:21:16.887831 | TASK [bindep : Install distro packages from bindep] 2026-06-12 17:21:59.989314 | controller | changed 2026-06-12 17:21:59.995218 | 2026-06-12 17:21:59.995301 | TASK [bindep : Check that packages are installed] 2026-06-12 17:22:01.534472 | controller | ok: Runtime: 0:00:01.050087 2026-06-12 17:22:01.543999 | 2026-06-12 17:22:01.544150 | TASK [bindep : Fail if we cannot install all packages] 2026-06-12 17:22:01.582413 | controller | skipping: Conditional result was False 2026-06-12 17:22:01.602655 | 2026-06-12 17:22:01.602821 | TASK [Run test-setup role] 2026-06-12 17:22:01.640077 | controller | ok 2026-06-12 17:22:01.667414 | 2026-06-12 17:22:01.667526 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-06-12 17:22:01.935432 | controller | ok 2026-06-12 17:22:01.943501 | 2026-06-12 17:22:01.943670 | TASK [test-setup : Run tools/test-setup.sh] 2026-06-12 17:22:02.483988 | controller | skipping: Conditional result was False 2026-06-12 17:22:02.508330 | 2026-06-12 17:22:02.508484 | TASK [bindep : Remove bindep temp dir] 2026-06-12 17:22:02.938321 | controller | ok 2026-06-12 17:22:02.940379 | 2026-06-12 17:22:02.940453 | PLAY RECAP 2026-06-12 17:22:02.940510 | controller | ok: 23 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0 2026-06-12 17:22:02.940538 | 2026-06-12 17:22:03.093728 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-06-12 17:22:03.096391 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2026-06-12 17:22:03.721281 | 2026-06-12 17:22:03.721426 | PLAY [all] 2026-06-12 17:22:03.730551 | 2026-06-12 17:22:03.730647 | TASK [Abort when test_command variable is undefined] 2026-06-12 17:22:03.775156 | controller | skipping: Conditional result was False 2026-06-12 17:22:03.779697 | 2026-06-12 17:22:03.779773 | TASK [Convert test_command to list] 2026-06-12 17:22:03.845035 | controller | skipping: Conditional result was False 2026-06-12 17:22:03.854100 | 2026-06-12 17:22:03.854240 | TASK [Use test_command list] 2026-06-12 17:22:03.921366 | controller | ok 2026-06-12 17:22:03.933702 | 2026-06-12 17:22:03.933848 | LOOP [Run test_command] 2026-06-12 17:22:04.379672 | controller | no check to run 2026-06-12 17:22:04.380003 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.003046 2026-06-12 17:22:04.384059 | 2026-06-12 17:22:04.384275 | PLAY RECAP 2026-06-12 17:22:04.384363 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-12 17:22:04.384406 | 2026-06-12 17:22:04.540904 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2026-06-12 17:22:04.542418 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-12 17:22:05.159461 | 2026-06-12 17:22:05.159626 | PLAY [all] 2026-06-12 17:22:05.170369 | 2026-06-12 17:22:05.170443 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-06-12 17:22:05.617860 | controller | changed: non-zero return code 2026-06-12 17:22:05.627842 | 2026-06-12 17:22:05.628087 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-06-12 17:22:05.660004 | controller | skipping: Conditional result was False 2026-06-12 17:22:05.672123 | 2026-06-12 17:22:05.672279 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-06-12 17:22:05.719282 | 2026-06-12 17:22:05.719565 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-06-12 17:22:05.767237 | 2026-06-12 17:22:05.767618 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-06-12 17:22:05.795335 | controller | skipping: Conditional result was False 2026-06-12 17:22:05.805962 | 2026-06-12 17:22:05.806109 | LOOP [fetch-subunit-output : Generate subunit file] 2026-06-12 17:22:05.844456 | 2026-06-12 17:22:05.844832 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-06-12 17:22:05.872908 | controller | skipping: Conditional result was False 2026-06-12 17:22:05.879272 | 2026-06-12 17:22:05.879366 | TASK [fetch-subunit-output : Remove the temporary file] 2026-06-12 17:22:05.904713 | controller | skipping: Conditional result was False 2026-06-12 17:22:05.914323 | 2026-06-12 17:22:05.914480 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-06-12 17:22:05.941568 | controller | skipping: Conditional result was False 2026-06-12 17:22:05.956527 | 2026-06-12 17:22:05.956725 | PLAY RECAP 2026-06-12 17:22:05.956817 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-06-12 17:22:05.956861 | 2026-06-12 17:22:06.096157 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-12 17:22:06.097900 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-06-12 17:22:06.701928 | 2026-06-12 17:22:06.702076 | PLAY [all] 2026-06-12 17:22:06.711786 | 2026-06-12 17:22:06.711859 | TASK [include_role : fetch-output] 2026-06-12 17:22:06.752662 | controller | ok 2026-06-12 17:22:06.768923 | 2026-06-12 17:22:06.769088 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-12 17:22:06.837458 | controller | skipping: Conditional result was False 2026-06-12 17:22:06.849343 | 2026-06-12 17:22:06.849541 | TASK [fetch-output : Set log path for single node] 2026-06-12 17:22:06.916060 | controller | ok 2026-06-12 17:22:06.924152 | 2026-06-12 17:22:06.924294 | LOOP [fetch-output : Ensure local output dirs] 2026-06-12 17:22:07.484620 | controller -> localhost | changed: "/var/lib/zuul/builds/4abd1f01bfee47d4adf30900ae55e610/work/logs" 2026-06-12 17:22:07.804136 | controller -> localhost | changed: "/var/lib/zuul/builds/4abd1f01bfee47d4adf30900ae55e610/work/artifacts" 2026-06-12 17:22:08.078040 | controller -> localhost | changed: "/var/lib/zuul/builds/4abd1f01bfee47d4adf30900ae55e610/work/docs" 2026-06-12 17:22:08.098754 | 2026-06-12 17:22:08.099001 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-12 17:22:08.882924 | controller | changed: .d..t...... ./ 2026-06-12 17:22:08.883288 | controller | changed: All items complete 2026-06-12 17:22:08.883346 | 2026-06-12 17:22:09.477138 | controller | changed: .d..t...... ./ 2026-06-12 17:22:10.051551 | controller | changed: .d..t...... ./ 2026-06-12 17:22:10.077626 | 2026-06-12 17:22:10.077892 | TASK [include_role : fetch-output-openshift] 2026-06-12 17:22:10.105670 | controller | skipping: Conditional result was False 2026-06-12 17:22:10.116467 | 2026-06-12 17:22:10.116667 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-12 17:22:10.662840 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.014465 2026-06-12 17:22:10.948640 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.014495 2026-06-12 17:22:10.974247 | 2026-06-12 17:22:10.974438 | PLAY [all] 2026-06-12 17:22:10.986616 | 2026-06-12 17:22:10.986800 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-12 17:22:11.446478 | controller | changed 2026-06-12 17:22:11.451460 | 2026-06-12 17:22:11.451516 | PLAY RECAP 2026-06-12 17:22:11.451560 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-12 17:22:11.451609 | 2026-06-12 17:22:11.591406 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-06-12 17:22:11.594088 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2026-06-12 17:22:12.362081 | 2026-06-12 17:22:12.362444 | PLAY [localhost] 2026-06-12 17:22:12.372901 | 2026-06-12 17:22:12.372975 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-12 17:22:12.844923 | localhost | changed 2026-06-12 17:22:12.854992 | 2026-06-12 17:22:12.855137 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-12 17:22:12.896989 | localhost | ok 2026-06-12 17:22:12.909881 | 2026-06-12 17:22:12.910022 | TASK [add-fileserver : Create SSH private key tempfile] 2026-06-12 17:22:13.338154 | localhost | changed 2026-06-12 17:22:13.349454 | 2026-06-12 17:22:13.349638 | TASK [add-fileserver : Create SSH private key from secret] 2026-06-12 17:22:14.286174 | localhost | changed 2026-06-12 17:22:14.295787 | 2026-06-12 17:22:14.295923 | TASK [add-fileserver : Add fileserver ssh key] 2026-06-12 17:22:14.785069 | localhost | Identity added: /var/lib/zuul/builds/4abd1f01bfee47d4adf30900ae55e610/work/tmp/ansible.2bnig6dc (/var/lib/zuul/builds/4abd1f01bfee47d4adf30900ae55e610/work/tmp/ansible.2bnig6dc) 2026-06-12 17:22:14.785286 | localhost | ok: Runtime: 0:00:00.021334 2026-06-12 17:22:14.788787 | 2026-06-12 17:22:14.788856 | TASK [add-fileserver : Remove SSH private key from disk] 2026-06-12 17:22:15.122352 | localhost | ok: Runtime: 0:00:00.006941 2026-06-12 17:22:15.126486 | 2026-06-12 17:22:15.126553 | TASK [add-fileserver : Add fileserver to inventory] 2026-06-12 17:22:15.206688 | localhost | changed 2026-06-12 17:22:15.211712 | 2026-06-12 17:22:15.211780 | TASK [add-fileserver : Add fileserver server to known hosts] 2026-06-12 17:22:15.725005 | localhost | changed 2026-06-12 17:22:15.730370 | 2026-06-12 17:22:15.730427 | PLAY [localhost] 2026-06-12 17:22:15.734711 | 2026-06-12 17:22:15.734765 | TASK [Generate bulk log download script] 2026-06-12 17:22:15.762324 | localhost | ok 2026-06-12 17:22:15.772197 | 2026-06-12 17:22:15.772258 | TASK [local-log-download : Check API endpoint is defined] 2026-06-12 17:22:15.819734 | localhost | ok: All assertions passed 2026-06-12 17:22:15.823309 | 2026-06-12 17:22:15.823369 | TASK [local-log-download : Create download script] 2026-06-12 17:22:16.436951 | localhost -> localhost | changed 2026-06-12 17:22:16.505159 | 2026-06-12 17:22:16.505357 | TASK [Register quick-download link] 2026-06-12 17:22:16.536877 | localhost | ok 2026-06-12 17:22:16.539884 | 2026-06-12 17:22:16.539960 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com] 2026-06-12 17:22:16.546636 | 2026-06-12 17:22:16.546714 | TASK [Set zuul-log-path fact] 2026-06-12 17:22:16.574631 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-12 17:22:16.588492 | 2026-06-12 17:22:16.588616 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-12 17:22:16.627138 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok 2026-06-12 17:22:16.630883 | 2026-06-12 17:22:16.630971 | TASK [upload-logs : Create log directories] 2026-06-12 17:22:17.570007 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed 2026-06-12 17:22:17.572757 | 2026-06-12 17:22:17.572860 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-12 17:22:17.938288 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.008710 2026-06-12 17:22:17.943558 | 2026-06-12 17:22:17.943697 | TASK [upload-logs : Upload logs to log server] 2026-06-12 17:22:19.196811 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | Output suppressed because no_log was given 2026-06-12 17:22:19.200261 | 2026-06-12 17:22:19.200494 | LOOP [upload-logs : Compress console log and json output]