2026-03-15 00:06:58.723171 | Job console starting... 2026-03-15 00:06:58.736428 | Updating repositories 2026-03-15 00:06:59.793434 | Preparing job workspace 2026-03-15 00:07:03.683887 | Running Ansible setup... 2026-03-15 00:07:08.002819 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-15 00:07:08.670162 | 2026-03-15 00:07:08.670286 | PLAY [localhost] 2026-03-15 00:07:08.678907 | 2026-03-15 00:07:08.678990 | TASK [Gathering Facts] 2026-03-15 00:07:09.766238 | localhost | ok 2026-03-15 00:07:09.797978 | 2026-03-15 00:07:09.798282 | TASK [Setup log path fact] 2026-03-15 00:07:09.820713 | localhost | ok 2026-03-15 00:07:09.839023 | 2026-03-15 00:07:09.839172 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-15 00:07:09.869707 | localhost | ok 2026-03-15 00:07:09.880943 | 2026-03-15 00:07:09.881072 | TASK [emit-job-header : Print job information] 2026-03-15 00:07:09.922227 | # Job Information 2026-03-15 00:07:09.922494 | Ansible Version: 2.15.12 2026-03-15 00:07:09.922574 | Job: ansible-test-sanity-docker-milestone 2026-03-15 00:07:09.922613 | Pipeline: periodic 2026-03-15 00:07:09.922706 | Executor: ze02.softwarefactory-project.io 2026-03-15 00:07:09.922744 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-03-15 00:07:09.922813 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/f7d/ansible/f7d3c01caf6a4c17afacebb63a99978d/ 2026-03-15 00:07:09.922869 | Event ID: a9a533d68ab94149ab6023488646f926 2026-03-15 00:07:09.929731 | 2026-03-15 00:07:09.929839 | LOOP [emit-job-header : Print node information] 2026-03-15 00:07:10.105834 | localhost | ok: 2026-03-15 00:07:10.106046 | localhost | # Node Information 2026-03-15 00:07:10.106073 | localhost | Inventory Hostname: controller 2026-03-15 00:07:10.106093 | localhost | Hostname: np0005647441 2026-03-15 00:07:10.106111 | localhost | Username: zuul 2026-03-15 00:07:10.106131 | localhost | Distro: Fedora 37 2026-03-15 00:07:10.106148 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-03-15 00:07:10.106165 | localhost | Region: ca-ymq-1 2026-03-15 00:07:10.106182 | localhost | Label: ansible-fedora-37-1vcpu 2026-03-15 00:07:10.106199 | localhost | Product Name: OpenStack Nova 2026-03-15 00:07:10.106215 | localhost | Interface IP: 199.204.45.99 2026-03-15 00:07:10.117011 | 2026-03-15 00:07:10.117166 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-15 00:07:10.629213 | localhost -> localhost | changed 2026-03-15 00:07:10.641562 | 2026-03-15 00:07:10.641806 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-15 00:07:11.581540 | localhost -> localhost | changed 2026-03-15 00:07:11.604398 | 2026-03-15 00:07:11.604480 | PLAY [all:!appliance*] 2026-03-15 00:07:11.621451 | 2026-03-15 00:07:11.621524 | TASK [include_role : start-zuul-console] 2026-03-15 00:07:11.640895 | controller | ok 2026-03-15 00:07:11.654261 | 2026-03-15 00:07:11.654322 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-03-15 00:07:12.041818 | controller | ok 2026-03-15 00:07:12.073290 | 2026-03-15 00:07:12.073433 | TASK [use-our-mirror : Retrieve the IP address] 2026-03-15 00:07:13.051584 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-03-15 00:07:13.065808 | 2026-03-15 00:07:13.065964 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-03-15 00:07:13.611965 | controller | skipping: Conditional result was False 2026-03-15 00:07:13.618887 | 2026-03-15 00:07:13.618981 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-03-15 00:07:13.653321 | controller | skipping: Conditional result was False 2026-03-15 00:07:13.660116 | 2026-03-15 00:07:13.660193 | TASK [use-our-mirror : Create the podman configuration directory] 2026-03-15 00:07:13.673474 | controller | skipping: Conditional result was False 2026-03-15 00:07:13.680817 | 2026-03-15 00:07:13.680911 | TASK [use-our-mirror : Copy the podman configuration] 2026-03-15 00:07:13.693908 | controller | skipping: Conditional result was False 2026-03-15 00:07:13.701000 | 2026-03-15 00:07:13.701093 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-03-15 00:07:13.714926 | controller | skipping: Conditional result was False 2026-03-15 00:07:13.722730 | 2026-03-15 00:07:13.722832 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-03-15 00:07:13.736259 | controller | skipping: Conditional result was False 2026-03-15 00:07:13.748436 | 2026-03-15 00:07:13.748536 | TASK [Disable Fedora Modular] 2026-03-15 00:07:13.999658 | controller | changed 2026-03-15 00:07:14.011646 | 2026-03-15 00:07:14.011818 | TASK [Enable EPEL] 2026-03-15 00:07:14.038568 | controller | skipping: Conditional result was False 2026-03-15 00:07:14.052164 | 2026-03-15 00:07:14.052363 | TASK [Register the RHEL node] 2026-03-15 00:07:14.614578 | 2026-03-15 00:07:14.614874 | TASK [Show the subscription-manager status] 2026-03-15 00:07:15.213213 | controller | skipping: Conditional result was False 2026-03-15 00:07:15.225141 | 2026-03-15 00:07:15.225267 | TASK [Enable EPEL on RHEL] 2026-03-15 00:07:15.771950 | controller | skipping: Conditional result was False 2026-03-15 00:07:15.780247 | 2026-03-15 00:07:15.780334 | TASK [Install git and tox] 2026-03-15 00:08:35.802932 | controller | changed 2026-03-15 00:08:35.812655 | 2026-03-15 00:08:35.812801 | TASK [include_role : prepare-workspace] 2026-03-15 00:08:35.835196 | controller | ok 2026-03-15 00:08:35.858606 | 2026-03-15 00:08:35.858746 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-15 00:08:36.074220 | controller | ok 2026-03-15 00:08:36.083583 | 2026-03-15 00:08:36.083703 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-15 00:08:48.665165 | controller | Output suppressed because no_log was given 2026-03-15 00:08:48.679255 | 2026-03-15 00:08:48.679370 | TASK [include_role : prepare-workspace-openshift] 2026-03-15 00:08:48.708292 | controller | skipping: Conditional result was False 2026-03-15 00:08:48.773048 | 2026-03-15 00:08:48.773189 | PLAY [all:!appliance] 2026-03-15 00:08:48.806970 | 2026-03-15 00:08:48.807075 | TASK [Run add-build-sshkey role (RSA)] 2026-03-15 00:08:48.841225 | controller | ok 2026-03-15 00:08:48.868556 | 2026-03-15 00:08:48.868722 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-15 00:08:49.159015 | controller -> localhost | ok 2026-03-15 00:08:49.173959 | 2026-03-15 00:08:49.174107 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-15 00:08:49.213589 | controller | ok 2026-03-15 00:08:49.240134 | controller | included: /var/lib/zuul/builds/f7d3c01caf6a4c17afacebb63a99978d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-15 00:08:49.248223 | 2026-03-15 00:08:49.248310 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-15 00:08:49.682444 | controller -> localhost | Generating public/private rsa key pair. 2026-03-15 00:08:49.682823 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/f7d3c01caf6a4c17afacebb63a99978d/work/f7d3c01caf6a4c17afacebb63a99978d_id_rsa. 2026-03-15 00:08:49.682889 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/f7d3c01caf6a4c17afacebb63a99978d/work/f7d3c01caf6a4c17afacebb63a99978d_id_rsa.pub. 2026-03-15 00:08:49.682935 | controller -> localhost | The key fingerprint is: 2026-03-15 00:08:49.682979 | controller -> localhost | SHA256:vrQAnFUxVdyD3UE3twAInhIgELmpsb1vtQNhb1eTcrs zuul-build-sshkey 2026-03-15 00:08:49.683021 | controller -> localhost | The key's randomart image is: 2026-03-15 00:08:49.683061 | controller -> localhost | +---[RSA 2048]----+ 2026-03-15 00:08:49.683100 | controller -> localhost | |++ ... .=oo+o=.=+| 2026-03-15 00:08:49.683141 | controller -> localhost | |. . o..o o = *| 2026-03-15 00:08:49.683212 | controller -> localhost | | o ..o . o | 2026-03-15 00:08:49.683253 | controller -> localhost | |+ .oo. . = | 2026-03-15 00:08:49.683292 | controller -> localhost | |.+ .+o S+ o | 2026-03-15 00:08:49.683345 | controller -> localhost | |o . ..+.. . | 2026-03-15 00:08:49.683397 | controller -> localhost | | . +.oo . | 2026-03-15 00:08:49.683441 | controller -> localhost | | . . oo oE | 2026-03-15 00:08:49.683483 | controller -> localhost | | o. .o | 2026-03-15 00:08:49.683530 | controller -> localhost | +----[SHA256]-----+ 2026-03-15 00:08:49.683623 | controller -> localhost | ok: Runtime: 0:00:00.044438 2026-03-15 00:08:49.698515 | 2026-03-15 00:08:49.698651 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-15 00:08:49.725804 | controller | ok 2026-03-15 00:08:49.746090 | controller | included: /var/lib/zuul/builds/f7d3c01caf6a4c17afacebb63a99978d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-15 00:08:49.758774 | 2026-03-15 00:08:49.758865 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-15 00:08:49.773384 | controller | skipping: Conditional result was False 2026-03-15 00:08:49.782204 | 2026-03-15 00:08:49.782301 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-15 00:08:50.211576 | controller | changed 2026-03-15 00:08:50.225335 | 2026-03-15 00:08:50.225485 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-15 00:08:50.490605 | controller | ok 2026-03-15 00:08:50.504322 | 2026-03-15 00:08:50.504560 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-15 00:08:51.173451 | controller | changed 2026-03-15 00:08:51.188716 | 2026-03-15 00:08:51.188903 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-15 00:08:51.874369 | controller | changed 2026-03-15 00:08:51.888031 | 2026-03-15 00:08:51.888241 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-15 00:08:51.915955 | controller | skipping: Conditional result was False 2026-03-15 00:08:51.932098 | 2026-03-15 00:08:51.932271 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-15 00:08:52.396992 | controller -> localhost | changed 2026-03-15 00:08:52.427392 | 2026-03-15 00:08:52.427582 | TASK [add-build-sshkey : Add back temp key] 2026-03-15 00:08:52.739040 | controller -> localhost | Identity added: /var/lib/zuul/builds/f7d3c01caf6a4c17afacebb63a99978d/work/f7d3c01caf6a4c17afacebb63a99978d_id_rsa (zuul-build-sshkey) 2026-03-15 00:08:52.739311 | controller -> localhost | ok: Runtime: 0:00:00.013112 2026-03-15 00:08:52.748461 | 2026-03-15 00:08:52.748541 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-15 00:08:53.118044 | controller | ok 2026-03-15 00:08:53.125366 | 2026-03-15 00:08:53.125465 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-15 00:08:53.184852 | controller | skipping: Conditional result was False 2026-03-15 00:08:53.202201 | 2026-03-15 00:08:53.202346 | TASK [Run add-build-sshkey role (ECDSA)] 2026-03-15 00:08:53.235401 | controller | ok 2026-03-15 00:08:53.260229 | 2026-03-15 00:08:53.260377 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-15 00:08:53.582072 | controller -> localhost | ok 2026-03-15 00:08:53.602387 | 2026-03-15 00:08:53.602607 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-15 00:08:53.641217 | controller | ok 2026-03-15 00:08:53.658510 | controller | included: /var/lib/zuul/builds/f7d3c01caf6a4c17afacebb63a99978d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-15 00:08:53.668540 | 2026-03-15 00:08:53.668660 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-15 00:08:53.984127 | controller -> localhost | Generating public/private ecdsa key pair. 2026-03-15 00:08:53.984441 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/f7d3c01caf6a4c17afacebb63a99978d/work/f7d3c01caf6a4c17afacebb63a99978d_id_ecdsa. 2026-03-15 00:08:53.984502 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/f7d3c01caf6a4c17afacebb63a99978d/work/f7d3c01caf6a4c17afacebb63a99978d_id_ecdsa.pub. 2026-03-15 00:08:53.984562 | controller -> localhost | The key fingerprint is: 2026-03-15 00:08:53.984606 | controller -> localhost | SHA256:/MpxNV3/OKOZledIXuOS9JAIfVInelLvulkOI7yUE98 zuul-build-sshkey 2026-03-15 00:08:53.984647 | controller -> localhost | The key's randomart image is: 2026-03-15 00:08:53.984732 | controller -> localhost | +---[ECDSA 521]---+ 2026-03-15 00:08:53.984792 | controller -> localhost | | | 2026-03-15 00:08:53.984835 | controller -> localhost | | + . | 2026-03-15 00:08:53.984875 | controller -> localhost | | . + + .| 2026-03-15 00:08:53.984914 | controller -> localhost | | . . = + o.| 2026-03-15 00:08:53.984953 | controller -> localhost | | S ..O + .| 2026-03-15 00:08:53.984991 | controller -> localhost | | ..o+=.+.| 2026-03-15 00:08:53.985030 | controller -> localhost | | . o*.+%E+| 2026-03-15 00:08:53.985068 | controller -> localhost | | . +. +@BO.| 2026-03-15 00:08:53.985106 | controller -> localhost | | o .+o=o.| 2026-03-15 00:08:53.985144 | controller -> localhost | +----[SHA256]-----+ 2026-03-15 00:08:53.985242 | controller -> localhost | ok: Runtime: 0:00:00.017065 2026-03-15 00:08:54.004656 | 2026-03-15 00:08:54.004899 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-15 00:08:54.032168 | controller | ok 2026-03-15 00:08:54.044150 | controller | included: /var/lib/zuul/builds/f7d3c01caf6a4c17afacebb63a99978d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-15 00:08:54.059271 | 2026-03-15 00:08:54.059373 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-15 00:08:54.086191 | controller | skipping: Conditional result was False 2026-03-15 00:08:54.093585 | 2026-03-15 00:08:54.093692 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-15 00:08:54.360502 | controller | changed 2026-03-15 00:08:54.375360 | 2026-03-15 00:08:54.375538 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-15 00:08:54.608390 | controller | ok 2026-03-15 00:08:54.636124 | 2026-03-15 00:08:54.636261 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-15 00:08:55.291249 | controller | changed 2026-03-15 00:08:55.305047 | 2026-03-15 00:08:55.305349 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-15 00:08:55.966773 | controller | changed 2026-03-15 00:08:55.982055 | 2026-03-15 00:08:55.982230 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-15 00:08:56.010081 | controller | skipping: Conditional result was False 2026-03-15 00:08:56.062138 | 2026-03-15 00:08:56.062259 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-15 00:08:56.289425 | controller -> localhost | changed 2026-03-15 00:08:56.303257 | 2026-03-15 00:08:56.303337 | TASK [add-build-sshkey : Add back temp key] 2026-03-15 00:08:56.650175 | controller -> localhost | Identity added: /var/lib/zuul/builds/f7d3c01caf6a4c17afacebb63a99978d/work/f7d3c01caf6a4c17afacebb63a99978d_id_ecdsa (zuul-build-sshkey) 2026-03-15 00:08:56.650407 | controller -> localhost | ok: Runtime: 0:00:00.012364 2026-03-15 00:08:56.657588 | 2026-03-15 00:08:56.657691 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-15 00:08:56.880016 | controller | ok 2026-03-15 00:08:56.889899 | 2026-03-15 00:08:56.890009 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-15 00:08:56.925785 | controller | skipping: Conditional result was False 2026-03-15 00:08:56.943334 | 2026-03-15 00:08:56.943461 | TASK [include_role : remove-zuul-sshkey] 2026-03-15 00:08:56.968546 | controller | skipping: Conditional result was False 2026-03-15 00:08:56.978728 | 2026-03-15 00:08:56.978849 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-15 00:08:57.232905 | controller | ok: "logs" 2026-03-15 00:08:57.233918 | controller | ok: All items complete 2026-03-15 00:08:57.234013 | 2026-03-15 00:08:57.423846 | controller | ok: "artifacts" 2026-03-15 00:08:57.621379 | controller | ok: "docs" 2026-03-15 00:08:57.648978 | 2026-03-15 00:08:57.649882 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-15 00:08:57.884550 | controller | changed: "logs" 2026-03-15 00:08:58.094490 | controller | changed: "artifacts" 2026-03-15 00:08:58.293027 | controller | changed: "docs" 2026-03-15 00:08:58.353801 | 2026-03-15 00:08:58.353904 | PLAY RECAP 2026-03-15 00:08:58.353965 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-03-15 00:08:58.354004 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-15 00:08:58.354029 | 2026-03-15 00:08:58.466408 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-15 00:08:58.468589 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-15 00:08:59.091552 | 2026-03-15 00:08:59.091706 | PLAY [all] 2026-03-15 00:08:59.116613 | 2026-03-15 00:08:59.116832 | TASK [Install binary dependencies] 2026-03-15 00:08:59.169085 | controller | ok 2026-03-15 00:08:59.189652 | 2026-03-15 00:08:59.189787 | TASK [bindep : Include find tasks] 2026-03-15 00:08:59.218778 | controller | ok 2026-03-15 00:08:59.226452 | controller | included: /var/lib/zuul/builds/f7d3c01caf6a4c17afacebb63a99978d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-03-15 00:08:59.232833 | 2026-03-15 00:08:59.232914 | TASK [bindep : Look for bindep.txt] 2026-03-15 00:08:59.604378 | controller | ok 2026-03-15 00:08:59.613191 | 2026-03-15 00:08:59.613296 | TASK [bindep : Define bindep_file fact] 2026-03-15 00:08:59.639120 | controller | skipping: Conditional result was False 2026-03-15 00:08:59.648196 | 2026-03-15 00:08:59.648298 | TASK [bindep : Look for other-requirements.txt] 2026-03-15 00:08:59.882240 | controller | ok 2026-03-15 00:08:59.897346 | 2026-03-15 00:08:59.897524 | TASK [bindep : Define bindep_file fact] 2026-03-15 00:08:59.935656 | controller | skipping: Conditional result was False 2026-03-15 00:08:59.944812 | 2026-03-15 00:08:59.944919 | TASK [bindep : Look for bindep fallback file] 2026-03-15 00:08:59.970190 | controller | skipping: Conditional result was False 2026-03-15 00:08:59.980600 | 2026-03-15 00:08:59.980798 | TASK [bindep : Define bindep_file fact] 2026-03-15 00:09:00.015916 | controller | skipping: Conditional result was False 2026-03-15 00:09:00.024252 | 2026-03-15 00:09:00.024346 | TASK [bindep : Include bindep tasks] 2026-03-15 00:09:00.058993 | controller | skipping: Conditional result was False 2026-03-15 00:09:00.075083 | 2026-03-15 00:09:00.075252 | TASK [bindep : Include install tasks] 2026-03-15 00:09:00.102015 | controller | skipping: Conditional result was False 2026-03-15 00:09:00.113524 | 2026-03-15 00:09:00.113729 | LOOP [bindep : Include package tasks] 2026-03-15 00:09:00.184179 | 2026-03-15 00:09:00.184467 | TASK [Run test-setup role] 2026-03-15 00:09:00.209454 | controller | ok 2026-03-15 00:09:00.234508 | 2026-03-15 00:09:00.234621 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-03-15 00:09:00.455445 | controller | ok 2026-03-15 00:09:00.469446 | 2026-03-15 00:09:00.469580 | TASK [test-setup : Run tools/test-setup.sh] 2026-03-15 00:09:30.538938 | [Zuul] Log Stream did not terminate 2026-03-15 00:09:30.539230 | controller | skipping: Conditional result was False 2026-03-15 00:09:30.569912 | 2026-03-15 00:09:30.570013 | PLAY RECAP 2026-03-15 00:09:30.570057 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-15 00:09:30.570096 | 2026-03-15 00:09:30.670117 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-15 00:09:30.671921 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-15 00:09:31.253442 | 2026-03-15 00:09:31.253650 | PLAY [controller] 2026-03-15 00:09:31.275162 | 2026-03-15 00:09:31.275251 | TASK [Create the /root directory] 2026-03-15 00:11:43.635885 | controller | ERROR 2026-03-15 00:11:43.636939 | controller | { 2026-03-15 00:11:43.642219 | controller | "msg": "Timeout (32s) waiting for privilege escalation prompt: " 2026-03-15 00:11:43.642320 | controller | } failure 2026-03-15 00:11:43.644783 | 2026-03-15 00:11:43.644897 | PLAY RECAP 2026-03-15 00:11:43.645004 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-03-15 00:11:43.645062 | 2026-03-15 00:11:43.773145 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-15 00:11:43.775497 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-15 00:11:44.379284 | 2026-03-15 00:11:44.379400 | PLAY [all] 2026-03-15 00:11:44.401308 | 2026-03-15 00:11:44.401401 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-03-15 00:15:20.764411 | controller | changed: non-zero return code 2026-03-15 00:15:20.779641 | 2026-03-15 00:15:20.779847 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-03-15 00:15:20.809226 | controller | skipping: Conditional result was False 2026-03-15 00:15:20.823361 | 2026-03-15 00:15:20.823552 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-03-15 00:15:20.876084 | 2026-03-15 00:15:20.876371 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-03-15 00:15:20.918331 | 2026-03-15 00:15:20.918784 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-03-15 00:15:20.945649 | controller | skipping: Conditional result was False 2026-03-15 00:15:20.960473 | 2026-03-15 00:15:20.960640 | LOOP [fetch-subunit-output : Generate subunit file] 2026-03-15 00:15:21.001977 | 2026-03-15 00:15:21.002295 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-03-15 00:15:21.028979 | controller | skipping: Conditional result was False 2026-03-15 00:15:21.046321 | 2026-03-15 00:15:21.046502 | TASK [fetch-subunit-output : Remove the temporary file] 2026-03-15 00:15:21.074715 | controller | skipping: Conditional result was False 2026-03-15 00:15:21.085024 | 2026-03-15 00:15:21.085137 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-03-15 00:15:21.110300 | controller | skipping: Conditional result was False 2026-03-15 00:15:21.153136 | 2026-03-15 00:15:21.153283 | PLAY RECAP 2026-03-15 00:15:21.153341 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-15 00:15:21.153370 | 2026-03-15 00:15:21.283910 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-15 00:15:21.285187 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-03-15 00:15:21.939398 | 2026-03-15 00:15:21.939524 | PLAY [all:!appliance*] 2026-03-15 00:15:21.972808 | 2026-03-15 00:15:21.972936 | TASK [unregister the node] 2026-03-15 00:15:22.505463 | controller | skipping: Conditional result was False 2026-03-15 00:15:22.519114 | 2026-03-15 00:15:22.519316 | TASK [include_role : fetch-output] 2026-03-15 00:15:22.572154 | controller | ok 2026-03-15 00:15:22.620099 | 2026-03-15 00:15:22.620254 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-15 00:15:22.698330 | controller | skipping: Conditional result was False 2026-03-15 00:15:22.705059 | 2026-03-15 00:15:22.705141 | TASK [fetch-output : Set log path for single node] 2026-03-15 00:15:22.749906 | controller | ok 2026-03-15 00:15:22.759903 | 2026-03-15 00:15:22.760007 | LOOP [fetch-output : Ensure local output dirs] 2026-03-15 00:15:23.240537 | controller -> localhost | ok: "/var/lib/zuul/builds/f7d3c01caf6a4c17afacebb63a99978d/work/logs" 2026-03-15 00:15:23.466713 | controller -> localhost | changed: "/var/lib/zuul/builds/f7d3c01caf6a4c17afacebb63a99978d/work/artifacts" 2026-03-15 00:15:23.705756 | controller -> localhost | changed: "/var/lib/zuul/builds/f7d3c01caf6a4c17afacebb63a99978d/work/docs" 2026-03-15 00:15:23.725235 | 2026-03-15 00:15:23.725354 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-15 00:15:24.399372 | controller | changed: .d..t...... ./ 2026-03-15 00:15:24.399742 | controller | changed: All items complete 2026-03-15 00:15:24.399815 | 2026-03-15 00:15:24.890018 | controller | changed: .d..t...... ./ 2026-03-15 00:15:25.441596 | controller | changed: .d..t...... ./ 2026-03-15 00:15:25.465426 | 2026-03-15 00:15:25.465532 | TASK [include_role : fetch-output-openshift] 2026-03-15 00:15:25.501577 | controller | skipping: Conditional result was False 2026-03-15 00:15:25.510799 | 2026-03-15 00:15:25.511034 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-15 00:15:25.555130 | controller | skipping: Conditional result was False 2026-03-15 00:15:25.568940 | controller | skipping: Conditional result was False 2026-03-15 00:15:25.629223 | 2026-03-15 00:15:25.629371 | PLAY [localhost] 2026-03-15 00:15:25.655243 | 2026-03-15 00:15:25.655358 | TASK [Run Zuul manifest role] 2026-03-15 00:15:25.676058 | localhost | ok 2026-03-15 00:15:25.695862 | 2026-03-15 00:15:25.695978 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-15 00:15:26.100207 | localhost | changed 2026-03-15 00:15:26.110952 | 2026-03-15 00:15:26.111094 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-15 00:15:26.147658 | localhost | ok 2026-03-15 00:15:26.165918 | 2026-03-15 00:15:26.166044 | TASK [Set zuul-log-path fact] 2026-03-15 00:15:26.188366 | localhost | ok 2026-03-15 00:15:26.208846 | 2026-03-15 00:15:26.208945 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-15 00:15:26.240409 | localhost | ok 2026-03-15 00:15:26.253987 | 2026-03-15 00:15:26.254106 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)] 2026-03-15 00:15:26.309369 | localhost | ok: 2026-03-15 00:15:26.309701 | localhost | { 2026-03-15 00:15:26.309750 | localhost | "auth": { 2026-03-15 00:15:26.309785 | localhost | "password": "IonWCwcuOFib&&<}wp^:[2G/", 2026-03-15 00:15:26.309816 | localhost | "username": "9LF91O0RK7JU" 2026-03-15 00:15:26.309856 | localhost | }, 2026-03-15 00:15:26.309900 | localhost | "profile": "rackspace", 2026-03-15 00:15:26.309939 | localhost | "project_name": "1251317", 2026-03-15 00:15:26.309976 | localhost | "region_name": "DFW" 2026-03-15 00:15:26.310007 | localhost | } 2026-03-15 00:15:26.310056 | 2026-03-15 00:15:26.318800 | 2026-03-15 00:15:26.318967 | TASK [Run upload-logs-failover role (S3 then Swift)] 2026-03-15 00:15:26.340141 | localhost | Output suppressed because no_log was given 2026-03-15 00:15:26.359326 | 2026-03-15 00:15:26.359469 | TASK [upload-logs-failover : Initialize control vars] 2026-03-15 00:15:26.392975 | localhost | ok 2026-03-15 00:15:26.399850 | 2026-03-15 00:15:26.399936 | LOOP [upload-logs-failover : Perform log upload] 2026-03-15 00:15:26.438311 | localhost | Output suppressed because no_log was given 2026-03-15 00:15:26.498906 | localhost | included: /var/lib/zuul/builds/f7d3c01caf6a4c17afacebb63a99978d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml 2026-03-15 00:15:26.507214 | 2026-03-15 00:15:26.507331 | LOOP [upload-logs-failover : Unpack vars for swift upload] 2026-03-15 00:15:26.596956 | localhost | Output suppressed because no_log was given 2026-03-15 00:15:26.608999 | 2026-03-15 00:15:26.609145 | TASK [Upload logs to swift] 2026-03-15 00:15:26.642150 | localhost | ok 2026-03-15 00:15:26.690241 | 2026-03-15 00:15:26.690362 | TASK [Set zuul-log-path fact] 2026-03-15 00:15:26.716007 | localhost | skipping: Conditional result was False 2026-03-15 00:15:26.721238 | 2026-03-15 00:15:26.721315 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-03-15 00:15:27.113587 | localhost -> localhost | ok: Runtime: 0:00:00.005149 2026-03-15 00:15:27.119405 | 2026-03-15 00:15:27.119474 | TASK [upload-logs-swift : Upload logs to swift]