2026-03-05 00:07:40.048436 | Job console starting... 2026-03-05 00:07:40.062059 | Updating repositories 2026-03-05 00:07:43.115512 | Preparing job workspace 2026-03-05 00:07:46.940128 | Running Ansible setup... 2026-03-05 00:07:51.762653 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-05 00:07:52.449652 | 2026-03-05 00:07:52.449816 | PLAY [localhost] 2026-03-05 00:07:52.459750 | 2026-03-05 00:07:52.459866 | TASK [Gathering Facts] 2026-03-05 00:07:53.480262 | localhost | ok 2026-03-05 00:07:53.504842 | 2026-03-05 00:07:53.505019 | TASK [Setup log path fact] 2026-03-05 00:07:53.527260 | localhost | ok 2026-03-05 00:07:53.549259 | 2026-03-05 00:07:53.549424 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-05 00:07:53.581284 | localhost | ok 2026-03-05 00:07:53.594423 | 2026-03-05 00:07:53.594569 | TASK [emit-job-header : Print job information] 2026-03-05 00:07:53.626015 | # Job Information 2026-03-05 00:07:53.626210 | Ansible Version: 2.15.12 2026-03-05 00:07:53.626257 | Job: ansible-test-sanity-docker-stable-2.12 2026-03-05 00:07:53.626289 | Pipeline: periodic 2026-03-05 00:07:53.626317 | Executor: ze04.softwarefactory-project.io 2026-03-05 00:07:53.626344 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-03-05 00:07:53.626376 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/25d/ansible/25dbba8ee47e4914a3c88deed7d43e45/ 2026-03-05 00:07:53.626405 | Event ID: 4fa9857b728648278118233f7d4d0858 2026-03-05 00:07:53.632142 | 2026-03-05 00:07:53.632234 | LOOP [emit-job-header : Print node information] 2026-03-05 00:07:53.748492 | localhost | ok: 2026-03-05 00:07:53.748649 | localhost | # Node Information 2026-03-05 00:07:53.748697 | localhost | Inventory Hostname: controller 2026-03-05 00:07:53.748734 | localhost | Hostname: np0005638181 2026-03-05 00:07:53.748761 | localhost | Username: zuul 2026-03-05 00:07:53.748786 | localhost | Distro: Fedora 37 2026-03-05 00:07:53.748804 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-03-05 00:07:53.748822 | localhost | Region: ca-ymq-1 2026-03-05 00:07:53.748838 | localhost | Label: ansible-fedora-37-1vcpu 2026-03-05 00:07:53.748855 | localhost | Product Name: OpenStack Nova 2026-03-05 00:07:53.748872 | localhost | Interface IP: 162.253.55.204 2026-03-05 00:07:53.758228 | 2026-03-05 00:07:53.758308 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-05 00:07:54.149902 | localhost -> localhost | changed 2026-03-05 00:07:54.158643 | 2026-03-05 00:07:54.158784 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-05 00:07:55.097511 | localhost -> localhost | changed 2026-03-05 00:07:55.145912 | 2026-03-05 00:07:55.146032 | PLAY [all:!appliance*] 2026-03-05 00:07:55.180226 | 2026-03-05 00:07:55.180350 | TASK [include_role : start-zuul-console] 2026-03-05 00:07:55.200648 | controller | ok 2026-03-05 00:07:55.216053 | 2026-03-05 00:07:55.216131 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-03-05 00:07:55.615271 | controller | ok 2026-03-05 00:07:55.628182 | 2026-03-05 00:07:55.628300 | TASK [use-our-mirror : Retrieve the IP address] 2026-03-05 00:07:56.934414 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-03-05 00:07:56.941665 | 2026-03-05 00:07:56.941796 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-03-05 00:07:57.486065 | controller | skipping: Conditional result was False 2026-03-05 00:07:57.496122 | 2026-03-05 00:07:57.496274 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-03-05 00:07:57.531754 | controller | skipping: Conditional result was False 2026-03-05 00:07:57.541230 | 2026-03-05 00:07:57.541357 | TASK [use-our-mirror : Create the podman configuration directory] 2026-03-05 00:07:57.566846 | controller | skipping: Conditional result was False 2026-03-05 00:07:57.576084 | 2026-03-05 00:07:57.576219 | TASK [use-our-mirror : Copy the podman configuration] 2026-03-05 00:07:57.601914 | controller | skipping: Conditional result was False 2026-03-05 00:07:57.613903 | 2026-03-05 00:07:57.614023 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-03-05 00:07:57.639935 | controller | skipping: Conditional result was False 2026-03-05 00:07:57.652134 | 2026-03-05 00:07:57.652250 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-03-05 00:07:57.677398 | controller | skipping: Conditional result was False 2026-03-05 00:07:57.691031 | 2026-03-05 00:07:57.691128 | TASK [Disable Fedora Modular] 2026-03-05 00:07:57.915673 | controller | changed 2026-03-05 00:07:57.922239 | 2026-03-05 00:07:57.922305 | TASK [Enable EPEL] 2026-03-05 00:07:57.947073 | controller | skipping: Conditional result was False 2026-03-05 00:07:57.953976 | 2026-03-05 00:07:57.954169 | TASK [Register the RHEL node] 2026-03-05 00:07:58.516144 | 2026-03-05 00:07:58.516590 | TASK [Show the subscription-manager status] 2026-03-05 00:07:59.387387 | controller | skipping: Conditional result was False 2026-03-05 00:07:59.403359 | 2026-03-05 00:07:59.403554 | TASK [Enable EPEL on RHEL] 2026-03-05 00:07:59.961650 | controller | skipping: Conditional result was False 2026-03-05 00:07:59.976355 | 2026-03-05 00:07:59.976540 | TASK [Install git and tox] 2026-03-05 00:09:16.604662 | controller | changed 2026-03-05 00:09:16.612893 | 2026-03-05 00:09:16.612971 | TASK [include_role : prepare-workspace] 2026-03-05 00:09:16.634173 | controller | ok 2026-03-05 00:09:16.658531 | 2026-03-05 00:09:16.658661 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-05 00:09:16.893608 | controller | ok 2026-03-05 00:09:16.911411 | 2026-03-05 00:09:16.911587 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-05 00:09:29.980246 | controller | Output suppressed because no_log was given 2026-03-05 00:09:29.995765 | 2026-03-05 00:09:29.995878 | TASK [include_role : prepare-workspace-openshift] 2026-03-05 00:09:30.011769 | controller | skipping: Conditional result was False 2026-03-05 00:09:30.039887 | 2026-03-05 00:09:30.039978 | PLAY [all:!appliance] 2026-03-05 00:09:30.089109 | 2026-03-05 00:09:30.089283 | TASK [Run add-build-sshkey role (RSA)] 2026-03-05 00:09:30.111118 | controller | ok 2026-03-05 00:09:30.126810 | 2026-03-05 00:09:30.126904 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-05 00:09:30.346084 | controller -> localhost | ok 2026-03-05 00:09:30.356108 | 2026-03-05 00:09:30.356249 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-05 00:09:30.384957 | controller | ok 2026-03-05 00:09:30.405743 | controller | included: /var/lib/zuul/builds/25dbba8ee47e4914a3c88deed7d43e45/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-05 00:09:30.415229 | 2026-03-05 00:09:30.415343 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-05 00:09:30.889522 | controller -> localhost | Generating public/private rsa key pair. 2026-03-05 00:09:30.889813 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/25dbba8ee47e4914a3c88deed7d43e45/work/25dbba8ee47e4914a3c88deed7d43e45_id_rsa. 2026-03-05 00:09:30.889859 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/25dbba8ee47e4914a3c88deed7d43e45/work/25dbba8ee47e4914a3c88deed7d43e45_id_rsa.pub. 2026-03-05 00:09:30.889893 | controller -> localhost | The key fingerprint is: 2026-03-05 00:09:30.889922 | controller -> localhost | SHA256:4EOkaSuAi3Ml1jk8hucKOxEIv94frUVP6RQt/sZ8faw zuul-build-sshkey 2026-03-05 00:09:30.889951 | controller -> localhost | The key's randomart image is: 2026-03-05 00:09:30.889980 | controller -> localhost | +---[RSA 2048]----+ 2026-03-05 00:09:30.890007 | controller -> localhost | |. . | 2026-03-05 00:09:30.890034 | controller -> localhost | |+. + = . | 2026-03-05 00:09:30.890060 | controller -> localhost | |= = & o o . | 2026-03-05 00:09:30.890087 | controller -> localhost | |.= O * . . + | 2026-03-05 00:09:30.890114 | controller -> localhost | |* + o o S = | 2026-03-05 00:09:30.890152 | controller -> localhost | | B + + = + o | 2026-03-05 00:09:30.890183 | controller -> localhost | |o o . . o o = . +| 2026-03-05 00:09:30.890213 | controller -> localhost | | . . + . . ..| 2026-03-05 00:09:30.890242 | controller -> localhost | | o E | 2026-03-05 00:09:30.890273 | controller -> localhost | +----[SHA256]-----+ 2026-03-05 00:09:30.890333 | controller -> localhost | ok: Runtime: 0:00:00.079704 2026-03-05 00:09:30.901213 | 2026-03-05 00:09:30.901312 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-05 00:09:30.923641 | controller | ok 2026-03-05 00:09:30.934413 | controller | included: /var/lib/zuul/builds/25dbba8ee47e4914a3c88deed7d43e45/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-05 00:09:30.944067 | 2026-03-05 00:09:30.944160 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-05 00:09:30.958298 | controller | skipping: Conditional result was False 2026-03-05 00:09:30.969694 | 2026-03-05 00:09:30.969832 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-05 00:09:31.426428 | controller | changed 2026-03-05 00:09:31.435153 | 2026-03-05 00:09:31.435267 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-05 00:09:31.671778 | controller | ok 2026-03-05 00:09:31.689879 | 2026-03-05 00:09:31.690063 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-05 00:09:32.319207 | controller | changed 2026-03-05 00:09:32.329179 | 2026-03-05 00:09:32.329305 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-05 00:09:32.933099 | controller | changed 2026-03-05 00:09:32.949073 | 2026-03-05 00:09:32.949238 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-05 00:09:32.980878 | controller | skipping: Conditional result was False 2026-03-05 00:09:32.999845 | 2026-03-05 00:09:33.000056 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-05 00:09:33.456357 | controller -> localhost | changed 2026-03-05 00:09:33.470385 | 2026-03-05 00:09:33.470483 | TASK [add-build-sshkey : Add back temp key] 2026-03-05 00:09:33.799937 | controller -> localhost | Identity added: /var/lib/zuul/builds/25dbba8ee47e4914a3c88deed7d43e45/work/25dbba8ee47e4914a3c88deed7d43e45_id_rsa (zuul-build-sshkey) 2026-03-05 00:09:33.800139 | controller -> localhost | ok: Runtime: 0:00:00.011200 2026-03-05 00:09:33.807350 | 2026-03-05 00:09:33.807420 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-05 00:09:34.167745 | controller | ok 2026-03-05 00:09:34.177319 | 2026-03-05 00:09:34.177423 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-05 00:09:34.203446 | controller | skipping: Conditional result was False 2026-03-05 00:09:34.222268 | 2026-03-05 00:09:34.222385 | TASK [Run add-build-sshkey role (ECDSA)] 2026-03-05 00:09:34.256394 | controller | ok 2026-03-05 00:09:34.276974 | 2026-03-05 00:09:34.277088 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-05 00:09:34.580083 | controller -> localhost | ok 2026-03-05 00:09:34.589063 | 2026-03-05 00:09:34.589141 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-05 00:09:34.624575 | controller | ok 2026-03-05 00:09:34.646131 | controller | included: /var/lib/zuul/builds/25dbba8ee47e4914a3c88deed7d43e45/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-05 00:09:34.658104 | 2026-03-05 00:09:34.658196 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-05 00:09:34.960497 | controller -> localhost | Generating public/private ecdsa key pair. 2026-03-05 00:09:34.960722 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/25dbba8ee47e4914a3c88deed7d43e45/work/25dbba8ee47e4914a3c88deed7d43e45_id_ecdsa. 2026-03-05 00:09:34.960755 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/25dbba8ee47e4914a3c88deed7d43e45/work/25dbba8ee47e4914a3c88deed7d43e45_id_ecdsa.pub. 2026-03-05 00:09:34.960785 | controller -> localhost | The key fingerprint is: 2026-03-05 00:09:34.960806 | controller -> localhost | SHA256:bN4eJWp9Ws9g4teqONSlMflWqXAC0DTsioGrz3o37CQ zuul-build-sshkey 2026-03-05 00:09:34.960826 | controller -> localhost | The key's randomart image is: 2026-03-05 00:09:34.960846 | controller -> localhost | +---[ECDSA 521]---+ 2026-03-05 00:09:34.960865 | controller -> localhost | | .=o | 2026-03-05 00:09:34.960883 | controller -> localhost | | +. | 2026-03-05 00:09:34.960902 | controller -> localhost | | . . . . . | 2026-03-05 00:09:34.960920 | controller -> localhost | | . . .. * o o | 2026-03-05 00:09:34.960939 | controller -> localhost | | . o .S..X.o | 2026-03-05 00:09:34.960959 | controller -> localhost | | . . .o.+oo+ | 2026-03-05 00:09:34.961001 | controller -> localhost | | .E.. .+ =.=. | 2026-03-05 00:09:34.961021 | controller -> localhost | |...o+ ..+ B.+. | 2026-03-05 00:09:34.961038 | controller -> localhost | |.+oo.. ..=o..o | 2026-03-05 00:09:34.961057 | controller -> localhost | +----[SHA256]-----+ 2026-03-05 00:09:34.961107 | controller -> localhost | ok: Runtime: 0:00:00.013506 2026-03-05 00:09:34.969288 | 2026-03-05 00:09:34.969356 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-05 00:09:35.001891 | controller | ok 2026-03-05 00:09:35.010174 | controller | included: /var/lib/zuul/builds/25dbba8ee47e4914a3c88deed7d43e45/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-05 00:09:35.020517 | 2026-03-05 00:09:35.020581 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-05 00:09:35.035025 | controller | skipping: Conditional result was False 2026-03-05 00:09:35.044208 | 2026-03-05 00:09:35.044296 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-05 00:09:35.311659 | controller | changed 2026-03-05 00:09:35.318890 | 2026-03-05 00:09:35.319016 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-05 00:09:35.545345 | controller | ok 2026-03-05 00:09:35.556649 | 2026-03-05 00:09:35.556779 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-05 00:09:36.190639 | controller | changed 2026-03-05 00:09:36.206644 | 2026-03-05 00:09:36.206922 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-05 00:09:36.825888 | controller | changed 2026-03-05 00:09:36.836450 | 2026-03-05 00:09:36.836577 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-05 00:09:36.872925 | controller | skipping: Conditional result was False 2026-03-05 00:09:36.882962 | 2026-03-05 00:09:36.883078 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-05 00:09:37.154040 | controller -> localhost | changed 2026-03-05 00:09:37.169302 | 2026-03-05 00:09:37.169436 | TASK [add-build-sshkey : Add back temp key] 2026-03-05 00:09:37.430718 | controller -> localhost | Identity added: /var/lib/zuul/builds/25dbba8ee47e4914a3c88deed7d43e45/work/25dbba8ee47e4914a3c88deed7d43e45_id_ecdsa (zuul-build-sshkey) 2026-03-05 00:09:37.430959 | controller -> localhost | ok: Runtime: 0:00:00.009804 2026-03-05 00:09:37.439093 | 2026-03-05 00:09:37.439202 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-05 00:09:37.655447 | controller | ok 2026-03-05 00:09:37.695297 | 2026-03-05 00:09:37.695417 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-05 00:09:37.720075 | controller | skipping: Conditional result was False 2026-03-05 00:09:37.734069 | 2026-03-05 00:09:37.734186 | TASK [include_role : remove-zuul-sshkey] 2026-03-05 00:09:37.749367 | controller | skipping: Conditional result was False 2026-03-05 00:09:37.757417 | 2026-03-05 00:09:37.757531 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-05 00:09:38.002027 | controller | ok: "logs" 2026-03-05 00:09:38.002261 | controller | ok: All items complete 2026-03-05 00:09:38.002289 | 2026-03-05 00:09:38.211311 | controller | ok: "artifacts" 2026-03-05 00:09:38.426282 | controller | ok: "docs" 2026-03-05 00:09:38.444947 | 2026-03-05 00:09:38.445116 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-05 00:09:38.706009 | controller | changed: "logs" 2026-03-05 00:09:38.919478 | controller | changed: "artifacts" 2026-03-05 00:09:39.113313 | controller | changed: "docs" 2026-03-05 00:09:39.177295 | 2026-03-05 00:09:39.177414 | PLAY RECAP 2026-03-05 00:09:39.177474 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-03-05 00:09:39.177523 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-05 00:09:39.177552 | 2026-03-05 00:09:39.333991 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-05 00:09:39.335619 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-05 00:09:39.972139 | 2026-03-05 00:09:39.972274 | PLAY [all] 2026-03-05 00:09:39.997756 | 2026-03-05 00:09:39.997884 | TASK [Install binary dependencies] 2026-03-05 00:09:40.048650 | controller | ok 2026-03-05 00:09:40.071452 | 2026-03-05 00:09:40.071585 | TASK [bindep : Include find tasks] 2026-03-05 00:09:40.101349 | controller | ok 2026-03-05 00:09:40.109989 | controller | included: /var/lib/zuul/builds/25dbba8ee47e4914a3c88deed7d43e45/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-03-05 00:09:40.117265 | 2026-03-05 00:09:40.117349 | TASK [bindep : Look for bindep.txt] 2026-03-05 00:09:40.486890 | controller | ok 2026-03-05 00:09:40.495584 | 2026-03-05 00:09:40.495728 | TASK [bindep : Define bindep_file fact] 2026-03-05 00:09:40.522461 | controller | skipping: Conditional result was False 2026-03-05 00:09:40.531848 | 2026-03-05 00:09:40.531940 | TASK [bindep : Look for other-requirements.txt] 2026-03-05 00:09:40.767149 | controller | ok 2026-03-05 00:09:40.782440 | 2026-03-05 00:09:40.782654 | TASK [bindep : Define bindep_file fact] 2026-03-05 00:09:40.810590 | controller | skipping: Conditional result was False 2026-03-05 00:09:40.822086 | 2026-03-05 00:09:40.822213 | TASK [bindep : Look for bindep fallback file] 2026-03-05 00:09:40.847264 | controller | skipping: Conditional result was False 2026-03-05 00:09:40.858355 | 2026-03-05 00:09:40.858494 | TASK [bindep : Define bindep_file fact] 2026-03-05 00:09:40.882947 | controller | skipping: Conditional result was False 2026-03-05 00:09:40.892854 | 2026-03-05 00:09:40.892984 | TASK [bindep : Include bindep tasks] 2026-03-05 00:09:40.917529 | controller | skipping: Conditional result was False 2026-03-05 00:09:40.926947 | 2026-03-05 00:09:40.927069 | TASK [bindep : Include install tasks] 2026-03-05 00:09:40.952316 | controller | skipping: Conditional result was False 2026-03-05 00:09:40.962855 | 2026-03-05 00:09:40.962986 | LOOP [bindep : Include package tasks] 2026-03-05 00:09:41.034273 | 2026-03-05 00:09:41.034433 | TASK [Run test-setup role] 2026-03-05 00:09:41.059063 | controller | ok 2026-03-05 00:09:41.086524 | 2026-03-05 00:09:41.086648 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-03-05 00:09:41.289727 | controller | ok 2026-03-05 00:09:41.297009 | 2026-03-05 00:09:41.297096 | TASK [test-setup : Run tools/test-setup.sh] 2026-03-05 00:10:11.337603 | [Zuul] Log Stream did not terminate 2026-03-05 00:10:11.337868 | controller | skipping: Conditional result was False 2026-03-05 00:10:11.368169 | 2026-03-05 00:10:11.368272 | PLAY RECAP 2026-03-05 00:10:11.368314 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-05 00:10:11.368335 | 2026-03-05 00:10:11.473772 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-05 00:10:11.474625 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-05 00:10:12.098985 | 2026-03-05 00:10:12.099111 | PLAY [controller] 2026-03-05 00:10:12.123518 | 2026-03-05 00:10:12.123644 | TASK [Create the /root directory] 2026-03-05 00:12:24.422431 | controller | ERROR 2026-03-05 00:12:24.422792 | controller | { 2026-03-05 00:12:24.422891 | controller | "msg": "Timeout (32s) waiting for privilege escalation prompt: " 2026-03-05 00:12:24.422980 | controller | } failure 2026-03-05 00:12:24.424744 | 2026-03-05 00:12:24.424846 | PLAY RECAP 2026-03-05 00:12:24.424928 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-03-05 00:12:24.424977 | 2026-03-05 00:12:24.544588 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-05 00:12:24.545717 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-05 00:12:25.191935 | 2026-03-05 00:12:25.192074 | PLAY [all] 2026-03-05 00:12:25.215239 | 2026-03-05 00:12:25.215355 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-03-05 00:15:45.184506 | controller | changed: non-zero return code 2026-03-05 00:15:45.193924 | 2026-03-05 00:15:45.194059 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-03-05 00:15:45.220172 | controller | skipping: Conditional result was False 2026-03-05 00:15:45.230351 | 2026-03-05 00:15:45.230459 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-03-05 00:15:45.266753 | 2026-03-05 00:15:45.266975 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-03-05 00:15:45.302095 | 2026-03-05 00:15:45.302273 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-03-05 00:15:45.327485 | controller | skipping: Conditional result was False 2026-03-05 00:15:45.337220 | 2026-03-05 00:15:45.337318 | LOOP [fetch-subunit-output : Generate subunit file] 2026-03-05 00:15:45.371610 | 2026-03-05 00:15:45.371835 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-03-05 00:15:45.396496 | controller | skipping: Conditional result was False 2026-03-05 00:15:45.406603 | 2026-03-05 00:15:45.406785 | TASK [fetch-subunit-output : Remove the temporary file] 2026-03-05 00:15:45.431832 | controller | skipping: Conditional result was False 2026-03-05 00:15:45.443019 | 2026-03-05 00:15:45.443138 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-03-05 00:15:45.467824 | controller | skipping: Conditional result was False 2026-03-05 00:15:45.512572 | 2026-03-05 00:15:45.512715 | PLAY RECAP 2026-03-05 00:15:45.512780 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-05 00:15:45.512810 | 2026-03-05 00:15:45.616512 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-05 00:15:45.618255 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-03-05 00:15:46.218900 | 2026-03-05 00:15:46.219030 | PLAY [all:!appliance*] 2026-03-05 00:15:46.241896 | 2026-03-05 00:15:46.241992 | TASK [unregister the node] 2026-03-05 00:15:46.778026 | controller | skipping: Conditional result was False 2026-03-05 00:15:46.792793 | 2026-03-05 00:15:46.793099 | TASK [include_role : fetch-output] 2026-03-05 00:15:46.830825 | controller | ok 2026-03-05 00:15:46.864702 | 2026-03-05 00:15:46.864856 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-05 00:15:46.941493 | controller | skipping: Conditional result was False 2026-03-05 00:15:46.951341 | 2026-03-05 00:15:46.951463 | TASK [fetch-output : Set log path for single node] 2026-03-05 00:15:46.995392 | controller | ok 2026-03-05 00:15:47.004248 | 2026-03-05 00:15:47.004368 | LOOP [fetch-output : Ensure local output dirs] 2026-03-05 00:15:47.415592 | controller -> localhost | ok: "/var/lib/zuul/builds/25dbba8ee47e4914a3c88deed7d43e45/work/logs" 2026-03-05 00:15:47.638955 | controller -> localhost | changed: "/var/lib/zuul/builds/25dbba8ee47e4914a3c88deed7d43e45/work/artifacts" 2026-03-05 00:15:47.936613 | controller -> localhost | changed: "/var/lib/zuul/builds/25dbba8ee47e4914a3c88deed7d43e45/work/docs" 2026-03-05 00:15:47.956267 | 2026-03-05 00:15:47.956447 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-05 00:15:48.686861 | controller | changed: .d..t...... ./ 2026-03-05 00:15:48.687274 | controller | changed: All items complete 2026-03-05 00:15:48.687346 | 2026-03-05 00:15:49.220892 | controller | changed: .d..t...... ./ 2026-03-05 00:15:49.774092 | controller | changed: .d..t...... ./ 2026-03-05 00:15:49.812146 | 2026-03-05 00:15:49.812418 | TASK [include_role : fetch-output-openshift] 2026-03-05 00:15:49.841539 | controller | skipping: Conditional result was False 2026-03-05 00:15:49.856416 | 2026-03-05 00:15:49.856621 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-05 00:15:49.892869 | controller | skipping: Conditional result was False 2026-03-05 00:15:49.905316 | controller | skipping: Conditional result was False 2026-03-05 00:15:49.957352 | 2026-03-05 00:15:49.957470 | PLAY [localhost] 2026-03-05 00:15:49.976509 | 2026-03-05 00:15:49.976608 | TASK [Run Zuul manifest role] 2026-03-05 00:15:49.995602 | localhost | ok 2026-03-05 00:15:50.011079 | 2026-03-05 00:15:50.011150 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-05 00:15:50.437628 | localhost | changed 2026-03-05 00:15:50.442904 | 2026-03-05 00:15:50.442973 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-05 00:15:50.486858 | localhost | ok 2026-03-05 00:15:50.506578 | 2026-03-05 00:15:50.506735 | TASK [Set zuul-log-path fact] 2026-03-05 00:15:50.528398 | localhost | ok 2026-03-05 00:15:50.551470 | 2026-03-05 00:15:50.551589 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-05 00:15:50.601627 | localhost | ok 2026-03-05 00:15:50.610011 | 2026-03-05 00:15:50.610070 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)] 2026-03-05 00:15:50.636260 | localhost | ok: 2026-03-05 00:15:50.636504 | localhost | { 2026-03-05 00:15:50.636532 | localhost | "auth": { 2026-03-05 00:15:50.636549 | localhost | "password": "IonWCwcuOFib&&<}wp^:[2G/", 2026-03-05 00:15:50.636566 | localhost | "username": "9LF91O0RK7JU" 2026-03-05 00:15:50.636587 | localhost | }, 2026-03-05 00:15:50.636603 | localhost | "profile": "rackspace", 2026-03-05 00:15:50.636619 | localhost | "region_name": "ORD" 2026-03-05 00:15:50.636635 | localhost | } 2026-03-05 00:15:50.636669 | 2026-03-05 00:15:50.649946 | 2026-03-05 00:15:50.650043 | TASK [Run upload-logs-failover role (S3 then Swift)] 2026-03-05 00:15:50.668429 | localhost | Output suppressed because no_log was given 2026-03-05 00:15:50.681770 | 2026-03-05 00:15:50.681848 | TASK [upload-logs-failover : Initialize control vars] 2026-03-05 00:15:50.710279 | localhost | ok 2026-03-05 00:15:50.715026 | 2026-03-05 00:15:50.715090 | LOOP [upload-logs-failover : Perform log upload] 2026-03-05 00:15:50.824377 | localhost | Output suppressed because no_log was given 2026-03-05 00:15:50.835828 | localhost | included: /var/lib/zuul/builds/25dbba8ee47e4914a3c88deed7d43e45/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml 2026-03-05 00:15:50.846099 | 2026-03-05 00:15:50.846205 | LOOP [upload-logs-failover : Unpack vars for swift upload] 2026-03-05 00:15:50.954278 | localhost | Output suppressed because no_log was given 2026-03-05 00:15:50.961954 | 2026-03-05 00:15:50.962036 | TASK [Upload logs to swift] 2026-03-05 00:15:50.991665 | localhost | ok 2026-03-05 00:15:51.022548 | 2026-03-05 00:15:51.022651 | TASK [Set zuul-log-path fact] 2026-03-05 00:15:51.058584 | localhost | skipping: Conditional result was False 2026-03-05 00:15:51.068491 | 2026-03-05 00:15:51.068616 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-03-05 00:15:51.494997 | localhost -> localhost | ok: Runtime: 0:00:00.009323 2026-03-05 00:15:51.504089 | 2026-03-05 00:15:51.504217 | TASK [upload-logs-swift : Upload logs to swift]