2025-10-07 15:08:21.753934 | Job console starting... 2025-10-07 15:08:21.764132 | Updating repositories 2025-10-07 15:08:21.829606 | Preparing job workspace 2025-10-07 15:08:26.318602 | Running Ansible setup... 2025-10-07 15:08:30.676133 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-07 15:08:31.370198 | 2025-10-07 15:08:31.370357 | PLAY [localhost] 2025-10-07 15:08:31.381096 | 2025-10-07 15:08:31.381210 | TASK [Gathering Facts] 2025-10-07 15:08:32.664978 | localhost | ok 2025-10-07 15:08:32.688017 | 2025-10-07 15:08:32.688168 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-07 15:08:33.169676 | localhost -> localhost | changed 2025-10-07 15:08:33.181415 | 2025-10-07 15:08:33.181546 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-07 15:08:34.465652 | localhost -> localhost | changed 2025-10-07 15:08:34.474138 | 2025-10-07 15:08:34.474231 | TASK [Setup log path fact] 2025-10-07 15:08:34.515969 | localhost | ok 2025-10-07 15:08:34.539956 | 2025-10-07 15:08:34.540091 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-07 15:08:34.579141 | localhost | ok 2025-10-07 15:08:34.591485 | 2025-10-07 15:08:34.591624 | TASK [emit-job-header : Print job information] 2025-10-07 15:08:34.654233 | # Job Information 2025-10-07 15:08:34.654435 | Ansible Version: 2.15.12 2025-10-07 15:08:34.654470 | Job: cifmw-molecule-polarion 2025-10-07 15:08:34.654498 | Pipeline: github-check 2025-10-07 15:08:34.654523 | Executor: ze03.softwarefactory-project.io 2025-10-07 15:08:34.654549 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3368 2025-10-07 15:08:34.654575 | Log URL (when completed): https://logserver.rdoproject.org/320/rdoproject.org/3204ab1c17be4e3980997f96ef1904f6/ 2025-10-07 15:08:34.654602 | Event ID: 25975190-a38f-11f0-9c22-f9318513dd27 2025-10-07 15:08:34.659521 | 2025-10-07 15:08:34.659614 | LOOP [emit-job-header : Print node information] 2025-10-07 15:08:34.772964 | localhost | ok: 2025-10-07 15:08:34.773150 | localhost | # Node Information 2025-10-07 15:08:34.773182 | localhost | Inventory Hostname: controller 2025-10-07 15:08:34.773209 | localhost | Hostname: np0005474283 2025-10-07 15:08:34.773232 | localhost | Username: zuul 2025-10-07 15:08:34.773257 | localhost | Distro: CentOS 9 2025-10-07 15:08:34.773280 | localhost | Provider: vexxhost-nodepool-tripleo 2025-10-07 15:08:34.773301 | localhost | Region: RegionOne 2025-10-07 15:08:34.773322 | localhost | Label: cloud-centos-9-stream-tripleo 2025-10-07 15:08:34.773343 | localhost | Product Name: OpenStack Nova 2025-10-07 15:08:34.773364 | localhost | Interface IP: 38.129.56.173 2025-10-07 15:08:34.795742 | 2025-10-07 15:08:34.795844 | PLAY [all] 2025-10-07 15:08:34.802638 | 2025-10-07 15:08:34.802803 | TASK [Gather network facts] 2025-10-07 15:08:35.302619 | controller | ok 2025-10-07 15:08:35.329721 | 2025-10-07 15:08:35.329897 | TASK [include_role : start-zuul-console] 2025-10-07 15:08:35.373612 | controller | ok 2025-10-07 15:08:35.399138 | 2025-10-07 15:08:35.399272 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-07 15:08:35.802143 | controller | ok 2025-10-07 15:08:35.830705 | 2025-10-07 15:08:35.830896 | TASK [include_role : add-build-sshkey] 2025-10-07 15:08:35.864145 | controller | ok 2025-10-07 15:08:35.885637 | 2025-10-07 15:08:35.885810 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-07 15:08:36.193871 | controller -> localhost | ok 2025-10-07 15:08:36.200675 | 2025-10-07 15:08:36.200805 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-07 15:08:36.221894 | controller | ok 2025-10-07 15:08:36.237198 | controller | included: /var/lib/zuul/builds/3204ab1c17be4e3980997f96ef1904f6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-07 15:08:36.245483 | 2025-10-07 15:08:36.245610 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-07 15:08:37.106925 | controller -> localhost | Generating public/private rsa key pair. 2025-10-07 15:08:37.107243 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/3204ab1c17be4e3980997f96ef1904f6/work/3204ab1c17be4e3980997f96ef1904f6_id_rsa. 2025-10-07 15:08:37.107277 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/3204ab1c17be4e3980997f96ef1904f6/work/3204ab1c17be4e3980997f96ef1904f6_id_rsa.pub. 2025-10-07 15:08:37.107300 | controller -> localhost | The key fingerprint is: 2025-10-07 15:08:37.107320 | controller -> localhost | SHA256:CdEoHy4WwxnPeff+VA9qhfXwiTZH9qlvolJrN94NBpQ zuul-build-sshkey 2025-10-07 15:08:37.107343 | controller -> localhost | The key's randomart image is: 2025-10-07 15:08:37.107362 | controller -> localhost | +---[RSA 3072]----+ 2025-10-07 15:08:37.107381 | controller -> localhost | | ..o.o | 2025-10-07 15:08:37.107401 | controller -> localhost | | *ooo. . | 2025-10-07 15:08:37.107419 | controller -> localhost | | *=.. . E oo | 2025-10-07 15:08:37.107437 | controller -> localhost | | o oo o o o+++| 2025-10-07 15:08:37.107455 | controller -> localhost | | . . S ++o+=| 2025-10-07 15:08:37.107473 | controller -> localhost | | o.++o.| 2025-10-07 15:08:37.107490 | controller -> localhost | | . =.+ .| 2025-10-07 15:08:37.107508 | controller -> localhost | | . + Boo.| 2025-10-07 15:08:37.107526 | controller -> localhost | | o.+.=o.| 2025-10-07 15:08:37.107543 | controller -> localhost | +----[SHA256]-----+ 2025-10-07 15:08:37.107595 | controller -> localhost | ok: Runtime: 0:00:00.379072 2025-10-07 15:08:37.114514 | 2025-10-07 15:08:37.114623 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-07 15:08:37.145360 | controller | ok 2025-10-07 15:08:37.159538 | controller | included: /var/lib/zuul/builds/3204ab1c17be4e3980997f96ef1904f6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-07 15:08:37.169505 | 2025-10-07 15:08:37.169675 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-07 15:08:37.195482 | controller | skipping: Conditional result was False 2025-10-07 15:08:37.202200 | 2025-10-07 15:08:37.202286 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-07 15:08:37.702889 | controller | changed 2025-10-07 15:08:37.724573 | 2025-10-07 15:08:37.724768 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-07 15:08:37.971096 | controller | ok 2025-10-07 15:08:37.981286 | 2025-10-07 15:08:37.981442 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-07 15:08:38.828580 | controller | changed 2025-10-07 15:08:38.836179 | 2025-10-07 15:08:38.836332 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-07 15:08:39.629050 | controller | changed 2025-10-07 15:08:39.634320 | 2025-10-07 15:08:39.634396 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-07 15:08:39.658713 | controller | skipping: Conditional result was False 2025-10-07 15:08:39.671610 | 2025-10-07 15:08:39.672828 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-07 15:08:40.161052 | controller -> localhost | changed 2025-10-07 15:08:40.171579 | 2025-10-07 15:08:40.171682 | TASK [add-build-sshkey : Add back temp key] 2025-10-07 15:08:40.657254 | controller -> localhost | Identity added: /var/lib/zuul/builds/3204ab1c17be4e3980997f96ef1904f6/work/3204ab1c17be4e3980997f96ef1904f6_id_rsa (zuul-build-sshkey) 2025-10-07 15:08:40.657509 | controller -> localhost | ok: Runtime: 0:00:00.009373 2025-10-07 15:08:40.668357 | 2025-10-07 15:08:40.668482 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-07 15:08:41.045718 | controller | ok 2025-10-07 15:08:41.052057 | 2025-10-07 15:08:41.052208 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-07 15:08:41.105105 | controller | skipping: Conditional result was False 2025-10-07 15:08:41.126018 | 2025-10-07 15:08:41.126167 | TASK [include_role : validate-host] 2025-10-07 15:08:41.168905 | controller | ok 2025-10-07 15:08:41.192462 | 2025-10-07 15:08:41.192593 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-07 15:08:41.263797 | controller | ok 2025-10-07 15:08:41.274291 | 2025-10-07 15:08:41.274426 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-07 15:08:41.963419 | controller -> localhost | ok 2025-10-07 15:08:41.971062 | 2025-10-07 15:08:41.971473 | TASK [validate-host : Collect information about the host] 2025-10-07 15:08:42.771812 | controller | ok 2025-10-07 15:08:42.781773 | 2025-10-07 15:08:42.781887 | TASK [validate-host : Sanitize hostname] 2025-10-07 15:08:42.843148 | controller | ok 2025-10-07 15:08:42.852140 | 2025-10-07 15:08:42.852277 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-07 15:08:43.475659 | controller -> localhost | changed 2025-10-07 15:08:43.483153 | 2025-10-07 15:08:43.483247 | TASK [validate-host : Collect information about zuul worker] 2025-10-07 15:08:43.968220 | controller | ok 2025-10-07 15:08:43.976757 | 2025-10-07 15:08:43.977387 | TASK [validate-host : Write out all zuul information for each host] 2025-10-07 15:08:44.474597 | controller -> localhost | changed 2025-10-07 15:08:44.491668 | 2025-10-07 15:08:44.491815 | TASK [include_role : prepare-workspace-openshift] 2025-10-07 15:08:44.531016 | controller | skipping: Conditional result was False 2025-10-07 15:08:44.538470 | 2025-10-07 15:08:44.538570 | TASK [include_role : remove-zuul-sshkey] 2025-10-07 15:08:44.577835 | controller | skipping: Conditional result was False 2025-10-07 15:08:44.583173 | 2025-10-07 15:08:44.583294 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-07 15:08:44.868534 | controller | ok: "logs" 2025-10-07 15:08:44.869056 | controller | ok: All items complete 2025-10-07 15:08:44.869112 | 2025-10-07 15:08:45.096906 | controller | ok: "artifacts" 2025-10-07 15:08:45.323447 | controller | ok: "docs" 2025-10-07 15:08:45.334538 | 2025-10-07 15:08:45.334696 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-07 15:08:45.625670 | controller | changed: "logs" 2025-10-07 15:08:45.897064 | controller | changed: "artifacts" 2025-10-07 15:08:46.113012 | controller | changed: "docs" 2025-10-07 15:08:46.143131 | 2025-10-07 15:08:46.143238 | PLAY RECAP 2025-10-07 15:08:46.143282 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-07 15:08:46.143310 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-07 15:08:46.143329 | 2025-10-07 15:08:46.300438 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-07 15:08:46.301478 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2025-10-07 15:08:47.079974 | 2025-10-07 15:08:47.080116 | PLAY [localhost] 2025-10-07 15:08:47.099198 | 2025-10-07 15:08:47.099347 | TASK [configure-ssh-config-fips : Ensure ssh config directory] 2025-10-07 15:08:47.731218 | localhost | ok 2025-10-07 15:08:47.742547 | 2025-10-07 15:08:47.742687 | TASK [configure-ssh-config-fips : Setup ssh config] 2025-10-07 15:08:49.126418 | localhost | changed 2025-10-07 15:08:49.173823 | 2025-10-07 15:08:49.173940 | PLAY [all] 2025-10-07 15:08:49.194113 | 2025-10-07 15:08:49.194220 | TASK [include_role : prepare-workspace] 2025-10-07 15:08:49.224643 | controller | ok 2025-10-07 15:08:49.256740 | 2025-10-07 15:08:49.256885 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-07 15:08:49.853865 | controller | ok 2025-10-07 15:08:49.859955 | 2025-10-07 15:08:49.860043 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-07 15:08:52.240939 | controller | Output suppressed because no_log was given 2025-10-07 15:08:52.270353 | 2025-10-07 15:08:52.270562 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-07 15:08:52.589329 | controller | changed: "logs" 2025-10-07 15:08:52.844241 | controller | changed: "artifacts" 2025-10-07 15:08:53.077364 | controller | changed: "docs" 2025-10-07 15:08:53.085405 | 2025-10-07 15:08:53.085523 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-07 15:08:53.366191 | controller | changed: "logs" 2025-10-07 15:08:53.366403 | controller | changed: All items complete 2025-10-07 15:08:53.366430 | 2025-10-07 15:08:53.615409 | controller | changed: "artifacts" 2025-10-07 15:08:53.840888 | controller | changed: "docs" 2025-10-07 15:08:53.863400 | 2025-10-07 15:08:53.863526 | TASK [Check if worker can sudo] 2025-10-07 15:08:54.403963 | controller | ok: Runtime: 0:00:00.042720 2025-10-07 15:08:54.417519 | 2025-10-07 15:08:54.417683 | TASK [configure-mirrors : Gather needed facts] 2025-10-07 15:08:54.546970 | controller | skipping: Conditional result was False 2025-10-07 15:08:54.553673 | 2025-10-07 15:08:54.553804 | TASK [configure-mirrors : Set up infrastructure mirrors] 2025-10-07 15:08:54.709902 | controller | ok 2025-10-07 15:08:54.720317 | controller | included: /var/lib/zuul/builds/3204ab1c17be4e3980997f96ef1904f6/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml 2025-10-07 15:08:54.726484 | 2025-10-07 15:08:54.726576 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir] 2025-10-07 15:08:54.992531 | controller | ok 2025-10-07 15:08:55.002618 | 2025-10-07 15:08:55.002772 | LOOP [configure-mirrors : Include OS-specific variables] 2025-10-07 15:08:55.071220 | controller | ok: "/var/lib/zuul/builds/3204ab1c17be4e3980997f96ef1904f6/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS-9.yaml" 2025-10-07 15:08:55.087027 | 2025-10-07 15:08:55.087149 | TASK [configure-mirrors : Install /etc/pip.conf configuration] 2025-10-07 15:08:55.950102 | controller | changed 2025-10-07 15:08:55.963590 | 2025-10-07 15:08:55.963780 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors] 2025-10-07 15:08:56.033947 | controller | ok: "/var/lib/zuul/builds/3204ab1c17be4e3980997f96ef1904f6/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml" 2025-10-07 15:08:56.034154 | controller | ok: All items complete 2025-10-07 15:08:56.034187 | 2025-10-07 15:08:56.099325 | controller | included: /var/lib/zuul/builds/3204ab1c17be4e3980997f96ef1904f6/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/CentOS-9.yaml 2025-10-07 15:08:56.107796 | 2025-10-07 15:08:56.107944 | LOOP [configure-mirrors : Install CentOS 9 repository files] 2025-10-07 15:08:57.063496 | controller | changed: "etc/yum.repos.d/centos.repo" 2025-10-07 15:08:58.115135 | controller | changed: "etc/yum.repos.d/centos-addons.repo" 2025-10-07 15:08:58.131090 | 2025-10-07 15:08:58.131212 | TASK [configure-mirrors : Disable deltrarpm] 2025-10-07 15:08:58.740569 | controller | changed: section and option added 2025-10-07 15:08:58.794384 | 2025-10-07 15:08:58.794520 | LOOP [configure-mirrors : Update yum/dnf cache] 2025-10-07 15:09:00.111245 | controller | 29 files removed 2025-10-07 15:09:00.114232 | controller | ok: Item: dnf clean all Runtime: 0:00:00.995214 2025-10-07 15:09:00.114397 | controller | changed: All items complete 2025-10-07 15:09:00.114450 | 2025-10-07 15:09:11.772789 | controller | Loaded plugins: builddep, changelog, config-manager, copr, debug, debuginfo-install, download, generate_completion_cache, groups-manager, needs-restarting, notify-packagekit, playground, repoclosure, repodiff, repograph, repomanage, reposync, system-upgrade 2025-10-07 15:09:11.772929 | controller | DNF version: 4.14.0 2025-10-07 15:09:11.772963 | controller | cachedir: /var/cache/dnf 2025-10-07 15:09:11.772991 | controller | Making cache files for all metadata files. 2025-10-07 15:09:11.773017 | controller | baseos: has expired and will be refreshed. 2025-10-07 15:09:11.773042 | controller | appstream: has expired and will be refreshed. 2025-10-07 15:09:11.773066 | controller | crb: has expired and will be refreshed. 2025-10-07 15:09:11.773103 | controller | extras-common: has expired and will be refreshed. 2025-10-07 15:09:11.773130 | controller | repo: downloading from remote: baseos 2025-10-07 15:09:11.773154 | controller | CentOS Stream 9 - BaseOS 51 MB/s | 8.8 MB 00:00 2025-10-07 15:09:11.773180 | controller | baseos: using metadata from Tue 30 Sep 2025 01:12:56 PM EDT. 2025-10-07 15:09:11.773206 | controller | repo: downloading from remote: appstream 2025-10-07 15:09:11.773231 | controller | CentOS Stream 9 - AppStream 63 MB/s | 25 MB 00:00 2025-10-07 15:09:11.773257 | controller | appstream: using metadata from Tue 30 Sep 2025 01:16:29 PM EDT. 2025-10-07 15:09:11.773283 | controller | repo: downloading from remote: crb 2025-10-07 15:09:11.773309 | controller | CentOS Stream 9 - CRB 29 MB/s | 7.1 MB 00:00 2025-10-07 15:09:11.773336 | controller | crb: using metadata from Tue 30 Sep 2025 01:20:54 PM EDT. 2025-10-07 15:09:11.773514 | controller | repo: downloading from remote: extras-common 2025-10-07 15:09:11.773555 | controller | CentOS Stream 9 - Extras packages 1.2 MB/s | 20 kB 00:00 2025-10-07 15:09:11.773585 | controller | extras-common: using metadata from Mon 15 Sep 2025 02:32:43 PM EDT. 2025-10-07 15:09:11.773612 | controller | User-Agent: constructed: 'libdnf (CentOS Stream 9; generic; Linux.x86_64)' 2025-10-07 15:09:11.773638 | controller | Completion plugin: Generating completion cache... 2025-10-07 15:09:11.773665 | controller | Metadata cache created. 2025-10-07 15:09:11.773765 | controller | ok: Item: dnf makecache -v Runtime: 0:00:11.370508 2025-10-07 15:09:11.852048 | 2025-10-07 15:09:11.852166 | PLAY RECAP 2025-10-07 15:09:11.852223 | controller | ok: 13 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-07 15:09:11.852259 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-07 15:09:11.852286 | 2025-10-07 15:09:12.005422 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2025-10-07 15:09:12.006942 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-07 15:09:12.627093 | 2025-10-07 15:09:12.627226 | PLAY [all] 2025-10-07 15:09:12.651371 | 2025-10-07 15:09:12.651520 | TASK [Install binary dependencies] 2025-10-07 15:09:12.703006 | controller | ok 2025-10-07 15:09:12.726458 | 2025-10-07 15:09:12.726623 | TASK [bindep : Include find tasks] 2025-10-07 15:09:12.756182 | controller | ok 2025-10-07 15:09:12.767801 | controller | included: /var/lib/zuul/builds/3204ab1c17be4e3980997f96ef1904f6/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-10-07 15:09:12.775059 | 2025-10-07 15:09:12.775193 | TASK [bindep : Look for bindep.txt] 2025-10-07 15:09:13.194888 | controller | ok 2025-10-07 15:09:13.202936 | 2025-10-07 15:09:13.203083 | TASK [bindep : Define bindep_file fact] 2025-10-07 15:09:13.239420 | controller | ok 2025-10-07 15:09:13.247800 | 2025-10-07 15:09:13.247961 | TASK [bindep : Look for other-requirements.txt] 2025-10-07 15:09:13.273878 | controller | skipping: Conditional result was False 2025-10-07 15:09:13.283405 | 2025-10-07 15:09:13.283578 | TASK [bindep : Define bindep_file fact] 2025-10-07 15:09:13.309464 | controller | skipping: Conditional result was False 2025-10-07 15:09:13.319155 | 2025-10-07 15:09:13.319315 | TASK [bindep : Look for bindep fallback file] 2025-10-07 15:09:13.345005 | controller | skipping: Conditional result was False 2025-10-07 15:09:13.354791 | 2025-10-07 15:09:13.354991 | TASK [bindep : Define bindep_file fact] 2025-10-07 15:09:13.381530 | controller | skipping: Conditional result was False 2025-10-07 15:09:13.391058 | 2025-10-07 15:09:13.391220 | TASK [bindep : Include bindep tasks] 2025-10-07 15:09:13.426577 | controller | ok 2025-10-07 15:09:13.437575 | controller | included: /var/lib/zuul/builds/3204ab1c17be4e3980997f96ef1904f6/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml 2025-10-07 15:09:13.451009 | 2025-10-07 15:09:13.451168 | TASK [bindep : Look for bindep command] 2025-10-07 15:09:13.476571 | controller | skipping: Conditional result was False 2025-10-07 15:09:13.485882 | 2025-10-07 15:09:13.486050 | TASK [bindep : Check for system bindep] 2025-10-07 15:09:14.030417 | controller | ok: Runtime: 0:00:00.007173 2025-10-07 15:09:14.048436 | 2025-10-07 15:09:14.048611 | TASK [bindep : Define bindep_command fact] 2025-10-07 15:09:14.087047 | controller | skipping: Conditional result was False 2025-10-07 15:09:14.106375 | 2025-10-07 15:09:14.106614 | TASK [bindep : Include install tasks] 2025-10-07 15:09:14.154666 | controller | ok 2025-10-07 15:09:14.165120 | controller | included: /var/lib/zuul/builds/3204ab1c17be4e3980997f96ef1904f6/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml 2025-10-07 15:09:14.176753 | 2025-10-07 15:09:14.176882 | TASK [bindep : Create temp dir for bindep] 2025-10-07 15:09:14.595249 | controller | changed 2025-10-07 15:09:14.608928 | 2025-10-07 15:09:14.609149 | TASK [Ensure we have pip dependencies] 2025-10-07 15:09:14.642495 | controller | ok 2025-10-07 15:09:14.687500 | 2025-10-07 15:09:14.687663 | TASK [ensure-pip : Check if pip is installed] 2025-10-07 11:09:14.979265 | controller | /usr/bin/pip3 2025-10-07 11:09:15.000120 | controller | /usr/bin/python3: No module named wheel 2025-10-07 15:09:15.225895 | controller | ok: Runtime: 0:00:00.030170 2025-10-07 15:09:15.234856 | 2025-10-07 15:09:15.235003 | LOOP [ensure-pip : Install pip from packages] 2025-10-07 15:09:15.274103 | controller | ok: "/var/lib/zuul/builds/3204ab1c17be4e3980997f96ef1904f6/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml" 2025-10-07 15:09:15.286061 | controller | included: /var/lib/zuul/builds/3204ab1c17be4e3980997f96ef1904f6/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml 2025-10-07 15:09:15.302338 | 2025-10-07 15:09:15.302503 | TASK [ensure-pip : Install Python 3 pip] 2025-10-07 15:09:17.729928 | controller | changed 2025-10-07 15:09:17.738472 | 2025-10-07 15:09:17.738631 | TASK [ensure-pip : Check for EPEL repository] 2025-10-07 15:09:17.806051 | controller | skipping: Conditional result was False 2025-10-07 15:09:17.815431 | 2025-10-07 15:09:17.815574 | TASK [ensure-pip : Fail with instructions when EPEL is not installed] 2025-10-07 15:09:17.872878 | controller | skipping: Conditional result was False 2025-10-07 15:09:17.884245 | 2025-10-07 15:09:17.884421 | TASK [ensure-pip : Install Python 2 pip] 2025-10-07 15:09:17.941116 | controller | skipping: Conditional result was False 2025-10-07 15:09:17.951082 | 2025-10-07 15:09:17.951251 | TASK [ensure-pip : Ensure setuptools] 2025-10-07 15:09:17.976868 | controller | skipping: Conditional result was False 2025-10-07 15:09:17.985560 | 2025-10-07 15:09:17.985718 | TASK [ensure-pip : Check for ensurepip module] 2025-10-07 15:09:18.536025 | controller | skipping: Conditional result was False 2025-10-07 15:09:18.543023 | 2025-10-07 15:09:18.543146 | TASK [ensure-pip : Ensure python3-venv] 2025-10-07 15:09:18.567260 | controller | skipping: Conditional result was False 2025-10-07 15:09:18.573405 | 2025-10-07 15:09:18.573533 | TASK [ensure-pip : Install pip from source] 2025-10-07 15:09:18.597999 | controller | skipping: Conditional result was False 2025-10-07 15:09:18.605554 | 2025-10-07 15:09:18.605698 | TASK [ensure-pip : Probe for venv python full path] 2025-10-07 11:09:18.907694 | controller | /usr/bin/python3 2025-10-07 15:09:19.138191 | controller | ok: Runtime: 0:00:00.006516 2025-10-07 15:09:19.146465 | 2025-10-07 15:09:19.146626 | TASK [ensure-pip : Set host default] 2025-10-07 15:09:19.200593 | controller | ok 2025-10-07 15:09:19.207841 | 2025-10-07 15:09:19.207975 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2025-10-07 15:09:19.283631 | controller | ok 2025-10-07 15:09:19.299778 | 2025-10-07 15:09:19.299970 | TASK [bindep : Install bindep into temporary venv] 2025-10-07 15:09:23.705367 | controller | changed 2025-10-07 15:09:23.715317 | 2025-10-07 15:09:23.715492 | TASK [bindep : Define bindep_command] 2025-10-07 15:09:23.749303 | controller | ok 2025-10-07 15:09:23.755665 | 2025-10-07 15:09:23.755830 | LOOP [bindep : Include package tasks] 2025-10-07 15:09:23.814014 | controller | ok: "src/github.com/openstack-k8s-operators/ci-framework/bindep.txt" 2025-10-07 15:09:23.814307 | controller | ok: All items complete 2025-10-07 15:09:23.814349 | 2025-10-07 15:09:23.833121 | controller | included: /var/lib/zuul/builds/3204ab1c17be4e3980997f96ef1904f6/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml 2025-10-07 15:09:23.845618 | 2025-10-07 15:09:23.845777 | TASK [bindep : Define bindep_run fact] 2025-10-07 15:09:23.887447 | controller | ok 2025-10-07 15:09:23.894175 | 2025-10-07 15:09:23.894314 | TASK [bindep : Get list of packages to install from bindep] 2025-10-07 11:09:25.268647 | controller | podman 2025-10-07 11:09:25.312284 | controller | python3-jmespath 2025-10-07 11:09:25.312530 | controller | python3-libvirt 2025-10-07 11:09:25.312541 | controller | python3-lxml 2025-10-07 11:09:25.312552 | controller | python3-netaddr 2025-10-07 15:09:25.432538 | controller | ok: Runtime: 0:00:01.161082 2025-10-07 15:09:25.441771 | 2025-10-07 15:09:25.441911 | TASK [bindep : Install distro packages from bindep] 2025-10-07 15:10:33.439109 | controller | changed 2025-10-07 15:10:33.447206 | 2025-10-07 15:10:33.447306 | TASK [bindep : Check that packages are installed] 2025-10-07 15:10:35.013241 | controller | ok: Runtime: 0:00:01.231917 2025-10-07 15:10:35.019675 | 2025-10-07 15:10:35.019832 | TASK [bindep : Fail if we cannot install all packages] 2025-10-07 15:10:35.056275 | controller | skipping: Conditional result was False 2025-10-07 15:10:35.074996 | 2025-10-07 15:10:35.075163 | TASK [Run test-setup role] 2025-10-07 15:10:35.113836 | controller | ok 2025-10-07 15:10:35.132194 | 2025-10-07 15:10:35.132297 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-10-07 15:10:35.357526 | controller | ok 2025-10-07 15:10:35.363254 | 2025-10-07 15:10:35.363363 | TASK [test-setup : Run tools/test-setup.sh] 2025-10-07 15:10:35.900060 | controller | skipping: Conditional result was False 2025-10-07 15:10:35.928970 | 2025-10-07 15:10:35.929098 | TASK [bindep : Remove bindep temp dir] 2025-10-07 15:10:36.332213 | controller | ok 2025-10-07 15:10:36.357533 | 2025-10-07 15:10:36.357686 | PLAY RECAP 2025-10-07 15:10:36.357820 | controller | ok: 22 changed: 6 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0 2025-10-07 15:10:36.357872 | 2025-10-07 15:10:36.514293 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-07 15:10:36.515508 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2025-10-07 15:10:37.135553 | 2025-10-07 15:10:37.135676 | PLAY [all] 2025-10-07 15:10:37.156791 | 2025-10-07 15:10:37.156941 | TASK [Abort when test_command variable is undefined] 2025-10-07 15:10:37.192418 | controller | skipping: Conditional result was False 2025-10-07 15:10:37.199711 | 2025-10-07 15:10:37.200166 | TASK [Convert test_command to list] 2025-10-07 15:10:37.255685 | controller | skipping: Conditional result was False 2025-10-07 15:10:37.262473 | 2025-10-07 15:10:37.262589 | TASK [Use test_command list] 2025-10-07 15:10:37.328594 | controller | ok 2025-10-07 15:10:37.338201 | 2025-10-07 15:10:37.338303 | LOOP [Run test_command] 2025-10-07 15:10:37.826421 | controller | no check to run 2025-10-07 15:10:37.826631 | controller | ok: Item: echo 'no check to run' Runtime: 0:00:00.006617 2025-10-07 15:10:37.855925 | 2025-10-07 15:10:37.856051 | PLAY RECAP 2025-10-07 15:10:37.856093 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-07 15:10:37.856114 | 2025-10-07 15:10:37.975578 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/run-test-command/run.yaml@master] 2025-10-07 15:10:37.977443 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-07 15:10:38.651704 | 2025-10-07 15:10:38.651882 | PLAY [all] 2025-10-07 15:10:38.674284 | 2025-10-07 15:10:38.674430 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-07 15:10:39.003206 | controller | changed: non-zero return code 2025-10-07 15:10:39.009058 | 2025-10-07 15:10:39.009207 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-07 15:10:39.024009 | controller | skipping: Conditional result was False 2025-10-07 15:10:39.031288 | 2025-10-07 15:10:39.031446 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-07 15:10:39.063319 | 2025-10-07 15:10:39.063522 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-07 15:10:39.094544 | 2025-10-07 15:10:39.094754 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-07 15:10:39.109137 | controller | skipping: Conditional result was False 2025-10-07 15:10:39.115906 | 2025-10-07 15:10:39.116036 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-07 15:10:39.147086 | 2025-10-07 15:10:39.147279 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-07 15:10:39.173488 | controller | skipping: Conditional result was False 2025-10-07 15:10:39.179353 | 2025-10-07 15:10:39.179464 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-07 15:10:39.204012 | controller | skipping: Conditional result was False 2025-10-07 15:10:39.212201 | 2025-10-07 15:10:39.212339 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-07 15:10:39.237898 | controller | skipping: Conditional result was False 2025-10-07 15:10:39.269783 | 2025-10-07 15:10:39.269897 | PLAY RECAP 2025-10-07 15:10:39.269942 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-07 15:10:39.269962 | 2025-10-07 15:10:39.376270 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-07 15:10:39.377968 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-07 15:10:40.018619 | 2025-10-07 15:10:40.018919 | PLAY [all] 2025-10-07 15:10:40.043667 | 2025-10-07 15:10:40.043852 | TASK [include_role : fetch-output] 2025-10-07 15:10:40.085523 | controller | ok 2025-10-07 15:10:40.105041 | 2025-10-07 15:10:40.105176 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-07 15:10:40.170603 | controller | skipping: Conditional result was False 2025-10-07 15:10:40.180025 | 2025-10-07 15:10:40.180173 | TASK [fetch-output : Set log path for single node] 2025-10-07 15:10:40.223853 | controller | ok 2025-10-07 15:10:40.232221 | 2025-10-07 15:10:40.232363 | LOOP [fetch-output : Ensure local output dirs] 2025-10-07 15:10:40.696504 | controller -> localhost | ok: "/var/lib/zuul/builds/3204ab1c17be4e3980997f96ef1904f6/work/logs" 2025-10-07 15:10:40.959026 | controller -> localhost | changed: "/var/lib/zuul/builds/3204ab1c17be4e3980997f96ef1904f6/work/artifacts" 2025-10-07 15:10:41.180484 | controller -> localhost | changed: "/var/lib/zuul/builds/3204ab1c17be4e3980997f96ef1904f6/work/docs" 2025-10-07 15:10:41.197563 | 2025-10-07 15:10:41.197689 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-07 15:10:41.868373 | controller | changed: .d..t...... ./ 2025-10-07 15:10:41.868640 | controller | changed: All items complete 2025-10-07 15:10:41.868680 | 2025-10-07 15:10:42.368999 | controller | changed: .d..t...... ./ 2025-10-07 15:10:42.883659 | controller | changed: .d..t...... ./ 2025-10-07 15:10:42.907690 | 2025-10-07 15:10:42.907874 | TASK [include_role : fetch-output-openshift] 2025-10-07 15:10:42.924132 | controller | skipping: Conditional result was False 2025-10-07 15:10:42.933795 | 2025-10-07 15:10:42.933955 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-07 15:10:43.451379 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.018111 2025-10-07 15:10:43.763599 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.010807 2025-10-07 15:10:43.807460 | 2025-10-07 15:10:43.807857 | PLAY [all] 2025-10-07 15:10:43.824676 | 2025-10-07 15:10:43.824837 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-07 15:10:44.286959 | controller | changed 2025-10-07 15:10:44.326437 | 2025-10-07 15:10:44.326564 | PLAY RECAP 2025-10-07 15:10:44.326622 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-07 15:10:44.326654 | 2025-10-07 15:10:44.462694 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-07 15:10:44.463965 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-07 15:10:45.161907 | 2025-10-07 15:10:45.162047 | PLAY [localhost] 2025-10-07 15:10:45.182979 | 2025-10-07 15:10:45.183125 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-07 15:10:45.631266 | localhost | changed 2025-10-07 15:10:45.636710 | 2025-10-07 15:10:45.636850 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-07 15:10:45.656763 | localhost | ok 2025-10-07 15:10:45.666665 | 2025-10-07 15:10:45.666940 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-07 15:10:46.057626 | localhost | changed 2025-10-07 15:10:46.064301 | 2025-10-07 15:10:46.064416 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-07 15:10:46.830035 | localhost | changed 2025-10-07 15:10:46.837246 | 2025-10-07 15:10:46.837353 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-07 15:10:47.290642 | localhost | Identity added: /var/lib/zuul/builds/3204ab1c17be4e3980997f96ef1904f6/work/tmp/ansible.du_blqxc (/var/lib/zuul/builds/3204ab1c17be4e3980997f96ef1904f6/work/tmp/ansible.du_blqxc) 2025-10-07 15:10:47.290961 | localhost | ok: Runtime: 0:00:00.010214 2025-10-07 15:10:47.299142 | 2025-10-07 15:10:47.299292 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-07 15:10:47.567111 | localhost | ok: Runtime: 0:00:00.008809 2025-10-07 15:10:47.572437 | 2025-10-07 15:10:47.572518 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-07 15:10:47.640058 | localhost | changed 2025-10-07 15:10:47.650935 | 2025-10-07 15:10:47.651141 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-07 15:10:48.052961 | localhost | changed 2025-10-07 15:10:48.075350 | 2025-10-07 15:10:48.075594 | PLAY [localhost] 2025-10-07 15:10:48.088669 | 2025-10-07 15:10:48.088800 | TASK [Generate bulk log download script] 2025-10-07 15:10:48.107066 | localhost | ok 2025-10-07 15:10:48.120183 | 2025-10-07 15:10:48.120307 | TASK [local-log-download : Check API endpoint is defined] 2025-10-07 15:10:48.159206 | localhost | ok: All assertions passed 2025-10-07 15:10:48.164565 | 2025-10-07 15:10:48.164675 | TASK [local-log-download : Create download script] 2025-10-07 15:10:48.607596 | localhost -> localhost | changed 2025-10-07 15:10:48.616415 | 2025-10-07 15:10:48.616497 | TASK [Register quick-download link] 2025-10-07 15:10:48.634273 | localhost | ok 2025-10-07 15:10:48.679659 | 2025-10-07 15:10:48.679799 | PLAY [logserver.rdoproject.org] 2025-10-07 15:10:48.689831 | 2025-10-07 15:10:48.689899 | TASK [Set zuul-log-path fact] 2025-10-07 15:10:48.706502 | logserver.rdoproject.org | ok 2025-10-07 15:10:48.715709 | 2025-10-07 15:10:48.715796 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-07 15:10:48.753947 | logserver.rdoproject.org | ok 2025-10-07 15:10:48.762149 | 2025-10-07 15:10:48.762249 | TASK [upload-logs : Create log directories] 2025-10-07 15:10:49.441288 | logserver.rdoproject.org | changed 2025-10-07 15:10:49.451070 | 2025-10-07 15:10:49.451406 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-07 15:10:49.790352 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.006942 2025-10-07 15:10:49.797200 | 2025-10-07 15:10:49.797334 | TASK [upload-logs : Upload logs to log server] 2025-10-07 15:10:50.585558 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-07 15:10:50.590219 | 2025-10-07 15:10:50.590357 | LOOP [upload-logs : Compress console log and json output] 2025-10-07 15:10:50.655232 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-07 15:10:50.674006 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-07 15:10:50.680111 | 2025-10-07 15:10:50.680231 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-07 15:10:50.736397 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-07 15:10:50.736799 | 2025-10-07 15:10:50.745012 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-07 15:10:50.752402 | 2025-10-07 15:10:50.752564 | LOOP [upload-logs : Upload console log and json output]