2026-03-05 00:28:18.888561 | Job console starting... 2026-03-05 00:28:18.902812 | Updating repositories 2026-03-05 00:28:19.037252 | Preparing job workspace 2026-03-05 00:28:22.935168 | Running Ansible setup... 2026-03-05 00:28:27.889082 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-05 00:28:28.509948 | 2026-03-05 00:28:28.510093 | PLAY [localhost] 2026-03-05 00:28:28.520284 | 2026-03-05 00:28:28.520414 | TASK [Gathering Facts] 2026-03-05 00:28:29.556142 | localhost | ok 2026-03-05 00:28:29.570883 | 2026-03-05 00:28:29.571035 | TASK [Setup log path fact] 2026-03-05 00:28:29.588906 | localhost | ok 2026-03-05 00:28:29.602640 | 2026-03-05 00:28:29.602779 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-05 00:28:29.632252 | localhost | ok 2026-03-05 00:28:29.643700 | 2026-03-05 00:28:29.643817 | TASK [emit-job-header : Print job information] 2026-03-05 00:28:29.695474 | # Job Information 2026-03-05 00:28:29.695701 | Ansible Version: 2.15.12 2026-03-05 00:28:29.695755 | Job: ansible-test-sanity-docker-stable-2.11 2026-03-05 00:28:29.695791 | Pipeline: periodic 2026-03-05 00:28:29.695822 | Executor: ze04.softwarefactory-project.io 2026-03-05 00:28:29.695852 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-03-05 00:28:29.695885 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/ee5/ansible/ee50b4fd96904bb6acc84c99ea545121/ 2026-03-05 00:28:29.695916 | Event ID: 4fa9857b728648278118233f7d4d0858 2026-03-05 00:28:29.701960 | 2026-03-05 00:28:29.702067 | LOOP [emit-job-header : Print node information] 2026-03-05 00:28:29.828429 | localhost | ok: 2026-03-05 00:28:29.828794 | localhost | # Node Information 2026-03-05 00:28:29.828908 | localhost | Inventory Hostname: controller 2026-03-05 00:28:29.828993 | localhost | Hostname: np0005638191 2026-03-05 00:28:29.829076 | localhost | Username: zuul 2026-03-05 00:28:29.829164 | localhost | Distro: Fedora 37 2026-03-05 00:28:29.829241 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-03-05 00:28:29.829321 | localhost | Region: ca-ymq-1 2026-03-05 00:28:29.829480 | localhost | Label: ansible-fedora-37-1vcpu 2026-03-05 00:28:29.829563 | localhost | Product Name: OpenStack Nova 2026-03-05 00:28:29.829624 | localhost | Interface IP: 162.253.55.200 2026-03-05 00:28:29.848365 | 2026-03-05 00:28:29.848472 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-05 00:28:30.250024 | localhost -> localhost | changed 2026-03-05 00:28:30.255902 | 2026-03-05 00:28:30.255981 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-05 00:28:31.207161 | localhost -> localhost | changed 2026-03-05 00:28:31.229489 | 2026-03-05 00:28:31.229639 | PLAY [all:!appliance*] 2026-03-05 00:28:31.247953 | 2026-03-05 00:28:31.248059 | TASK [include_role : start-zuul-console] 2026-03-05 00:28:31.277242 | controller | ok 2026-03-05 00:28:31.291600 | 2026-03-05 00:28:31.291699 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-03-05 00:28:31.689392 | controller | ok 2026-03-05 00:28:31.714950 | 2026-03-05 00:28:31.715097 | TASK [use-our-mirror : Retrieve the IP address] 2026-03-05 00:28:32.690302 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-03-05 00:28:32.710654 | 2026-03-05 00:28:32.710885 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-03-05 00:28:33.265106 | controller | skipping: Conditional result was False 2026-03-05 00:28:33.285487 | 2026-03-05 00:28:33.285704 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-03-05 00:28:33.312156 | controller | skipping: Conditional result was False 2026-03-05 00:28:33.329442 | 2026-03-05 00:28:33.329706 | TASK [use-our-mirror : Create the podman configuration directory] 2026-03-05 00:28:33.358496 | controller | skipping: Conditional result was False 2026-03-05 00:28:33.368928 | 2026-03-05 00:28:33.369048 | TASK [use-our-mirror : Copy the podman configuration] 2026-03-05 00:28:33.394329 | controller | skipping: Conditional result was False 2026-03-05 00:28:33.405597 | 2026-03-05 00:28:33.405765 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-03-05 00:28:33.421956 | controller | skipping: Conditional result was False 2026-03-05 00:28:33.432291 | 2026-03-05 00:28:33.432399 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-03-05 00:28:33.468520 | controller | skipping: Conditional result was False 2026-03-05 00:28:33.488328 | 2026-03-05 00:28:33.488473 | TASK [Disable Fedora Modular] 2026-03-05 00:28:33.731983 | controller | changed 2026-03-05 00:28:33.750738 | 2026-03-05 00:28:33.750850 | TASK [Enable EPEL] 2026-03-05 00:28:33.778324 | controller | skipping: Conditional result was False 2026-03-05 00:28:33.796126 | 2026-03-05 00:28:33.796333 | TASK [Register the RHEL node] 2026-03-05 00:28:34.360336 | 2026-03-05 00:28:34.360606 | TASK [Show the subscription-manager status] 2026-03-05 00:28:34.949235 | controller | skipping: Conditional result was False 2026-03-05 00:28:34.966301 | 2026-03-05 00:28:34.966495 | TASK [Enable EPEL on RHEL] 2026-03-05 00:28:35.556215 | controller | skipping: Conditional result was False 2026-03-05 00:28:35.571350 | 2026-03-05 00:28:35.571528 | TASK [Install git and tox] 2026-03-05 00:29:55.238258 | controller | changed 2026-03-05 00:29:55.244879 | 2026-03-05 00:29:55.244954 | TASK [include_role : prepare-workspace] 2026-03-05 00:29:55.274143 | controller | ok 2026-03-05 00:29:55.295343 | 2026-03-05 00:29:55.295446 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-05 00:29:55.536715 | controller | ok 2026-03-05 00:29:55.551653 | 2026-03-05 00:29:55.551854 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-05 00:30:08.231884 | controller | Output suppressed because no_log was given 2026-03-05 00:30:08.243244 | 2026-03-05 00:30:08.243316 | TASK [include_role : prepare-workspace-openshift] 2026-03-05 00:30:08.267483 | controller | skipping: Conditional result was False 2026-03-05 00:30:08.319811 | 2026-03-05 00:30:08.319905 | PLAY [all:!appliance] 2026-03-05 00:30:08.338441 | 2026-03-05 00:30:08.338534 | TASK [Run add-build-sshkey role (RSA)] 2026-03-05 00:30:08.369884 | controller | ok 2026-03-05 00:30:08.386652 | 2026-03-05 00:30:08.386768 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-05 00:30:08.625227 | controller -> localhost | ok 2026-03-05 00:30:08.632754 | 2026-03-05 00:30:08.632823 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-05 00:30:08.657272 | controller | ok 2026-03-05 00:30:08.678458 | controller | included: /var/lib/zuul/builds/ee50b4fd96904bb6acc84c99ea545121/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-05 00:30:08.687912 | 2026-03-05 00:30:08.688096 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-05 00:30:09.093594 | controller -> localhost | Generating public/private rsa key pair. 2026-03-05 00:30:09.095853 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ee50b4fd96904bb6acc84c99ea545121/work/ee50b4fd96904bb6acc84c99ea545121_id_rsa. 2026-03-05 00:30:09.095892 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ee50b4fd96904bb6acc84c99ea545121/work/ee50b4fd96904bb6acc84c99ea545121_id_rsa.pub. 2026-03-05 00:30:09.095916 | controller -> localhost | The key fingerprint is: 2026-03-05 00:30:09.095937 | controller -> localhost | SHA256:7KC/jSJWYcPOA6QPPD2qAThQVXKezyI4oRjDt26ZtLE zuul-build-sshkey 2026-03-05 00:30:09.095956 | controller -> localhost | The key's randomart image is: 2026-03-05 00:30:09.095976 | controller -> localhost | +---[RSA 2048]----+ 2026-03-05 00:30:09.095995 | controller -> localhost | | ...o.o | 2026-03-05 00:30:09.096015 | controller -> localhost | |o . + . | 2026-03-05 00:30:09.096033 | controller -> localhost | |*+oo o | 2026-03-05 00:30:09.096051 | controller -> localhost | |B*o=* + | 2026-03-05 00:30:09.096069 | controller -> localhost | |++=Oooo S | 2026-03-05 00:30:09.096098 | controller -> localhost | |..+.@o + | 2026-03-05 00:30:09.096122 | controller -> localhost | |.. E.. . | 2026-03-05 00:30:09.096141 | controller -> localhost | |. + .. o | 2026-03-05 00:30:09.096159 | controller -> localhost | | . . .+.. | 2026-03-05 00:30:09.096180 | controller -> localhost | +----[SHA256]-----+ 2026-03-05 00:30:09.096236 | controller -> localhost | ok: Runtime: 0:00:00.061726 2026-03-05 00:30:09.108559 | 2026-03-05 00:30:09.113704 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-05 00:30:09.144219 | controller | ok 2026-03-05 00:30:09.155969 | controller | included: /var/lib/zuul/builds/ee50b4fd96904bb6acc84c99ea545121/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-05 00:30:09.165735 | 2026-03-05 00:30:09.165815 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-05 00:30:09.180117 | controller | skipping: Conditional result was False 2026-03-05 00:30:09.188233 | 2026-03-05 00:30:09.188334 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-05 00:30:09.619420 | controller | changed 2026-03-05 00:30:09.629354 | 2026-03-05 00:30:09.629513 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-05 00:30:09.864456 | controller | ok 2026-03-05 00:30:09.870934 | 2026-03-05 00:30:09.871024 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-05 00:30:10.545209 | controller | changed 2026-03-05 00:30:10.551485 | 2026-03-05 00:30:10.551550 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-05 00:30:11.197597 | controller | changed 2026-03-05 00:30:11.213591 | 2026-03-05 00:30:11.213804 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-05 00:30:11.240453 | controller | skipping: Conditional result was False 2026-03-05 00:30:11.250917 | 2026-03-05 00:30:11.251044 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-05 00:30:11.653295 | controller -> localhost | changed 2026-03-05 00:30:11.665360 | 2026-03-05 00:30:11.665458 | TASK [add-build-sshkey : Add back temp key] 2026-03-05 00:30:11.953597 | controller -> localhost | Identity added: /var/lib/zuul/builds/ee50b4fd96904bb6acc84c99ea545121/work/ee50b4fd96904bb6acc84c99ea545121_id_rsa (zuul-build-sshkey) 2026-03-05 00:30:11.953896 | controller -> localhost | ok: Runtime: 0:00:00.006990 2026-03-05 00:30:11.962063 | 2026-03-05 00:30:11.962141 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-05 00:30:12.307286 | controller | ok 2026-03-05 00:30:12.313809 | 2026-03-05 00:30:12.313880 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-05 00:30:12.358033 | controller | skipping: Conditional result was False 2026-03-05 00:30:12.380268 | 2026-03-05 00:30:12.380391 | TASK [Run add-build-sshkey role (ECDSA)] 2026-03-05 00:30:12.417040 | controller | ok 2026-03-05 00:30:12.446048 | 2026-03-05 00:30:12.446166 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-05 00:30:12.677648 | controller -> localhost | ok 2026-03-05 00:30:12.684775 | 2026-03-05 00:30:12.684892 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-05 00:30:12.710185 | controller | ok 2026-03-05 00:30:12.727312 | controller | included: /var/lib/zuul/builds/ee50b4fd96904bb6acc84c99ea545121/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-05 00:30:12.738867 | 2026-03-05 00:30:12.738999 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-05 00:30:13.000144 | controller -> localhost | Generating public/private ecdsa key pair. 2026-03-05 00:30:13.000316 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ee50b4fd96904bb6acc84c99ea545121/work/ee50b4fd96904bb6acc84c99ea545121_id_ecdsa. 2026-03-05 00:30:13.000345 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ee50b4fd96904bb6acc84c99ea545121/work/ee50b4fd96904bb6acc84c99ea545121_id_ecdsa.pub. 2026-03-05 00:30:13.000375 | controller -> localhost | The key fingerprint is: 2026-03-05 00:30:13.000396 | controller -> localhost | SHA256:DhKsT3/h2ke3l9Gwx+Pj07DNUsJkJRRPAFOM7HR3Bv4 zuul-build-sshkey 2026-03-05 00:30:13.000415 | controller -> localhost | The key's randomart image is: 2026-03-05 00:30:13.000433 | controller -> localhost | +---[ECDSA 521]---+ 2026-03-05 00:30:13.000451 | controller -> localhost | | .o**+.| 2026-03-05 00:30:13.000469 | controller -> localhost | | . +o+o=| 2026-03-05 00:30:13.000487 | controller -> localhost | | o o ..=o| 2026-03-05 00:30:13.000504 | controller -> localhost | | . . . +. | 2026-03-05 00:30:13.000522 | controller -> localhost | | . o . S + =E| 2026-03-05 00:30:13.000540 | controller -> localhost | | o o + .. . *.=| 2026-03-05 00:30:13.000557 | controller -> localhost | | . . +. . ..@o| 2026-03-05 00:30:13.000574 | controller -> localhost | | + . . =++| 2026-03-05 00:30:13.000592 | controller -> localhost | | . .. ..oo| 2026-03-05 00:30:13.000609 | controller -> localhost | +----[SHA256]-----+ 2026-03-05 00:30:13.000653 | controller -> localhost | ok: Runtime: 0:00:00.007653 2026-03-05 00:30:13.007525 | 2026-03-05 00:30:13.007608 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-05 00:30:13.027984 | controller | ok 2026-03-05 00:30:13.036066 | controller | included: /var/lib/zuul/builds/ee50b4fd96904bb6acc84c99ea545121/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-05 00:30:13.045508 | 2026-03-05 00:30:13.045579 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-05 00:30:13.059535 | controller | skipping: Conditional result was False 2026-03-05 00:30:13.067041 | 2026-03-05 00:30:13.067131 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-05 00:30:13.361047 | controller | changed 2026-03-05 00:30:13.374977 | 2026-03-05 00:30:13.375120 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-05 00:30:13.622287 | controller | ok 2026-03-05 00:30:13.628857 | 2026-03-05 00:30:13.628943 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-05 00:30:14.346008 | controller | changed 2026-03-05 00:30:14.360625 | 2026-03-05 00:30:14.360836 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-05 00:30:15.039074 | controller | changed 2026-03-05 00:30:15.047860 | 2026-03-05 00:30:15.047943 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-05 00:30:15.072617 | controller | skipping: Conditional result was False 2026-03-05 00:30:15.080520 | 2026-03-05 00:30:15.080594 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-05 00:30:15.351996 | controller -> localhost | changed 2026-03-05 00:30:15.412169 | 2026-03-05 00:30:15.412295 | TASK [add-build-sshkey : Add back temp key] 2026-03-05 00:30:15.723581 | controller -> localhost | Identity added: /var/lib/zuul/builds/ee50b4fd96904bb6acc84c99ea545121/work/ee50b4fd96904bb6acc84c99ea545121_id_ecdsa (zuul-build-sshkey) 2026-03-05 00:30:15.724020 | controller -> localhost | ok: Runtime: 0:00:00.016181 2026-03-05 00:30:15.732551 | 2026-03-05 00:30:15.732639 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-05 00:30:15.985039 | controller | ok 2026-03-05 00:30:15.998954 | 2026-03-05 00:30:15.999086 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-05 00:30:16.025152 | controller | skipping: Conditional result was False 2026-03-05 00:30:16.039066 | 2026-03-05 00:30:16.039171 | TASK [include_role : remove-zuul-sshkey] 2026-03-05 00:30:16.063697 | controller | skipping: Conditional result was False 2026-03-05 00:30:16.072926 | 2026-03-05 00:30:16.073040 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-05 00:30:16.312838 | controller | ok: "logs" 2026-03-05 00:30:16.313194 | controller | ok: All items complete 2026-03-05 00:30:16.313233 | 2026-03-05 00:30:16.507904 | controller | ok: "artifacts" 2026-03-05 00:30:16.700036 | controller | ok: "docs" 2026-03-05 00:30:16.708371 | 2026-03-05 00:30:16.708471 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-05 00:30:16.960565 | controller | changed: "logs" 2026-03-05 00:30:17.185476 | controller | changed: "artifacts" 2026-03-05 00:30:17.401460 | controller | changed: "docs" 2026-03-05 00:30:17.476296 | 2026-03-05 00:30:17.476428 | PLAY RECAP 2026-03-05 00:30:17.476493 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-03-05 00:30:17.476532 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-05 00:30:17.476559 | 2026-03-05 00:30:17.611370 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-05 00:30:17.612872 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-05 00:30:18.232270 | 2026-03-05 00:30:18.232393 | PLAY [all] 2026-03-05 00:30:18.256057 | 2026-03-05 00:30:18.256162 | TASK [Install binary dependencies] 2026-03-05 00:30:18.307894 | controller | ok 2026-03-05 00:30:18.328471 | 2026-03-05 00:30:18.328616 | TASK [bindep : Include find tasks] 2026-03-05 00:30:18.358178 | controller | ok 2026-03-05 00:30:18.366583 | controller | included: /var/lib/zuul/builds/ee50b4fd96904bb6acc84c99ea545121/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-03-05 00:30:18.373885 | 2026-03-05 00:30:18.373990 | TASK [bindep : Look for bindep.txt] 2026-03-05 00:30:18.745385 | controller | ok 2026-03-05 00:30:18.757788 | 2026-03-05 00:30:18.757923 | TASK [bindep : Define bindep_file fact] 2026-03-05 00:30:18.774262 | controller | skipping: Conditional result was False 2026-03-05 00:30:18.785182 | 2026-03-05 00:30:18.785293 | TASK [bindep : Look for other-requirements.txt] 2026-03-05 00:30:18.992847 | controller | ok 2026-03-05 00:30:19.003245 | 2026-03-05 00:30:19.003431 | TASK [bindep : Define bindep_file fact] 2026-03-05 00:30:19.028933 | controller | skipping: Conditional result was False 2026-03-05 00:30:19.039723 | 2026-03-05 00:30:19.039872 | TASK [bindep : Look for bindep fallback file] 2026-03-05 00:30:19.076020 | controller | skipping: Conditional result was False 2026-03-05 00:30:19.090551 | 2026-03-05 00:30:19.090734 | TASK [bindep : Define bindep_file fact] 2026-03-05 00:30:19.143019 | controller | skipping: Conditional result was False 2026-03-05 00:30:19.152202 | 2026-03-05 00:30:19.152308 | TASK [bindep : Include bindep tasks] 2026-03-05 00:30:19.176800 | controller | skipping: Conditional result was False 2026-03-05 00:30:19.187071 | 2026-03-05 00:30:19.187201 | TASK [bindep : Include install tasks] 2026-03-05 00:30:19.212252 | controller | skipping: Conditional result was False 2026-03-05 00:30:19.221439 | 2026-03-05 00:30:19.221545 | LOOP [bindep : Include package tasks] 2026-03-05 00:30:19.292632 | 2026-03-05 00:30:19.292817 | TASK [Run test-setup role] 2026-03-05 00:30:19.314793 | controller | ok 2026-03-05 00:30:19.339210 | 2026-03-05 00:30:19.339332 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-03-05 00:30:19.542828 | controller | ok 2026-03-05 00:30:19.550147 | 2026-03-05 00:30:19.550241 | TASK [test-setup : Run tools/test-setup.sh] 2026-03-05 00:30:20.093207 | controller | skipping: Conditional result was False 2026-03-05 00:30:20.140875 | 2026-03-05 00:30:20.141198 | PLAY RECAP 2026-03-05 00:30:20.141278 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-05 00:30:20.141322 | 2026-03-05 00:30:20.265041 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-05 00:30:20.265993 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-05 00:30:20.903111 | 2026-03-05 00:30:20.903282 | PLAY [controller] 2026-03-05 00:30:20.948846 | 2026-03-05 00:30:20.949019 | TASK [Create the /root directory] 2026-03-05 00:32:33.241819 | controller | ERROR 2026-03-05 00:32:33.242213 | controller | { 2026-03-05 00:32:33.242308 | controller | "msg": "Timeout (32s) waiting for privilege escalation prompt: " 2026-03-05 00:32:33.242374 | controller | } failure 2026-03-05 00:32:33.244181 | 2026-03-05 00:32:33.244281 | PLAY RECAP 2026-03-05 00:32:33.244364 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-03-05 00:32:33.244414 | 2026-03-05 00:32:33.357961 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-05 00:32:33.360097 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-05 00:32:33.982416 | 2026-03-05 00:32:33.982540 | PLAY [all] 2026-03-05 00:32:34.005399 | 2026-03-05 00:32:34.005540 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-03-05 00:36:34.732111 | controller | changed: non-zero return code 2026-03-05 00:36:34.747253 | 2026-03-05 00:36:34.747430 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-03-05 00:36:34.777055 | controller | skipping: Conditional result was False 2026-03-05 00:36:34.792235 | 2026-03-05 00:36:34.792404 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-03-05 00:36:34.836103 | 2026-03-05 00:36:34.836376 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-03-05 00:36:34.878060 | 2026-03-05 00:36:34.878365 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-03-05 00:36:34.906094 | controller | skipping: Conditional result was False 2026-03-05 00:36:34.915129 | 2026-03-05 00:36:34.915222 | LOOP [fetch-subunit-output : Generate subunit file] 2026-03-05 00:36:34.949641 | 2026-03-05 00:36:34.949865 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-03-05 00:36:34.975441 | controller | skipping: Conditional result was False 2026-03-05 00:36:34.984218 | 2026-03-05 00:36:34.984416 | TASK [fetch-subunit-output : Remove the temporary file] 2026-03-05 00:36:35.009502 | controller | skipping: Conditional result was False 2026-03-05 00:36:35.018013 | 2026-03-05 00:36:35.018118 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-03-05 00:36:35.043006 | controller | skipping: Conditional result was False 2026-03-05 00:36:35.074086 | 2026-03-05 00:36:35.074180 | PLAY RECAP 2026-03-05 00:36:35.074261 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-05 00:36:35.074290 | 2026-03-05 00:36:35.218595 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-05 00:36:35.219963 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-03-05 00:36:35.880866 | 2026-03-05 00:36:35.880994 | PLAY [all:!appliance*] 2026-03-05 00:36:35.904080 | 2026-03-05 00:36:35.904204 | TASK [unregister the node] 2026-03-05 00:36:36.440016 | controller | skipping: Conditional result was False 2026-03-05 00:36:36.449362 | 2026-03-05 00:36:36.449509 | TASK [include_role : fetch-output] 2026-03-05 00:36:36.493999 | controller | ok 2026-03-05 00:36:36.524107 | 2026-03-05 00:36:36.524246 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-05 00:36:36.580134 | controller | skipping: Conditional result was False 2026-03-05 00:36:36.589456 | 2026-03-05 00:36:36.589619 | TASK [fetch-output : Set log path for single node] 2026-03-05 00:36:36.632595 | controller | ok 2026-03-05 00:36:36.641652 | 2026-03-05 00:36:36.641792 | LOOP [fetch-output : Ensure local output dirs] 2026-03-05 00:36:37.136719 | controller -> localhost | ok: "/var/lib/zuul/builds/ee50b4fd96904bb6acc84c99ea545121/work/logs" 2026-03-05 00:36:37.406865 | controller -> localhost | changed: "/var/lib/zuul/builds/ee50b4fd96904bb6acc84c99ea545121/work/artifacts" 2026-03-05 00:36:37.669572 | controller -> localhost | changed: "/var/lib/zuul/builds/ee50b4fd96904bb6acc84c99ea545121/work/docs" 2026-03-05 00:36:37.686596 | 2026-03-05 00:36:37.686728 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-05 00:36:38.403526 | controller | changed: .d..t...... ./ 2026-03-05 00:36:38.403957 | controller | changed: All items complete 2026-03-05 00:36:38.404026 | 2026-03-05 00:36:38.975725 | controller | changed: .d..t...... ./ 2026-03-05 00:36:39.472419 | controller | changed: .d..t...... ./ 2026-03-05 00:36:39.504227 | 2026-03-05 00:36:39.504558 | TASK [include_role : fetch-output-openshift] 2026-03-05 00:36:39.529027 | controller | skipping: Conditional result was False 2026-03-05 00:36:39.537833 | 2026-03-05 00:36:39.537932 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-05 00:36:39.571870 | controller | skipping: Conditional result was False 2026-03-05 00:36:39.585496 | controller | skipping: Conditional result was False 2026-03-05 00:36:39.649782 | 2026-03-05 00:36:39.649904 | PLAY [localhost] 2026-03-05 00:36:39.667536 | 2026-03-05 00:36:39.667640 | TASK [Run Zuul manifest role] 2026-03-05 00:36:39.687979 | localhost | ok 2026-03-05 00:36:39.707185 | 2026-03-05 00:36:39.707281 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-05 00:36:40.155999 | localhost | changed 2026-03-05 00:36:40.163083 | 2026-03-05 00:36:40.163177 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-05 00:36:40.199481 | localhost | ok 2026-03-05 00:36:40.214741 | 2026-03-05 00:36:40.214865 | TASK [Set zuul-log-path fact] 2026-03-05 00:36:40.237331 | localhost | ok 2026-03-05 00:36:40.262063 | 2026-03-05 00:36:40.262214 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-05 00:36:40.293366 | localhost | ok 2026-03-05 00:36:40.305278 | 2026-03-05 00:36:40.305384 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)] 2026-03-05 00:36:40.336809 | localhost | ok: 2026-03-05 00:36:40.337129 | localhost | { 2026-03-05 00:36:40.337185 | localhost | "auth": { 2026-03-05 00:36:40.337227 | localhost | "password": "IonWCwcuOFib&&<}wp^:[2G/", 2026-03-05 00:36:40.337265 | localhost | "username": "9LF91O0RK7JU" 2026-03-05 00:36:40.337310 | localhost | }, 2026-03-05 00:36:40.337348 | localhost | "profile": "rackspace", 2026-03-05 00:36:40.337385 | localhost | "region_name": "ORD" 2026-03-05 00:36:40.337421 | localhost | } 2026-03-05 00:36:40.337470 | 2026-03-05 00:36:40.351441 | 2026-03-05 00:36:40.351587 | TASK [Run upload-logs-failover role (S3 then Swift)] 2026-03-05 00:36:40.374346 | localhost | Output suppressed because no_log was given 2026-03-05 00:36:40.393890 | 2026-03-05 00:36:40.394046 | TASK [upload-logs-failover : Initialize control vars] 2026-03-05 00:36:40.426367 | localhost | ok 2026-03-05 00:36:40.433044 | 2026-03-05 00:36:40.433137 | LOOP [upload-logs-failover : Perform log upload] 2026-03-05 00:36:40.511911 | localhost | Output suppressed because no_log was given 2026-03-05 00:36:40.526852 | localhost | included: /var/lib/zuul/builds/ee50b4fd96904bb6acc84c99ea545121/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml 2026-03-05 00:36:40.542956 | 2026-03-05 00:36:40.543124 | LOOP [upload-logs-failover : Unpack vars for swift upload] 2026-03-05 00:36:40.634292 | localhost | Output suppressed because no_log was given 2026-03-05 00:36:40.647254 | 2026-03-05 00:36:40.647435 | TASK [Upload logs to swift] 2026-03-05 00:36:40.675015 | localhost | ok 2026-03-05 00:36:40.722735 | 2026-03-05 00:36:40.722856 | TASK [Set zuul-log-path fact] 2026-03-05 00:36:40.748367 | localhost | skipping: Conditional result was False 2026-03-05 00:36:40.756357 | 2026-03-05 00:36:40.756455 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-03-05 00:36:41.183587 | localhost -> localhost | ok: Runtime: 0:00:00.011496 2026-03-05 00:36:41.189478 | 2026-03-05 00:36:41.189557 | TASK [upload-logs-swift : Upload logs to swift]