2025-10-04 00:17:21.194921 | Job console starting... 2025-10-04 00:17:21.209913 | Updating repositories 2025-10-04 00:17:21.320793 | Preparing job workspace 2025-10-04 00:17:25.040481 | Running Ansible setup... 2025-10-04 00:17:30.030657 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-10-04 00:17:30.651637 | 2025-10-04 00:17:30.651783 | PLAY [localhost] 2025-10-04 00:17:30.660929 | 2025-10-04 00:17:30.661044 | TASK [Gathering Facts] 2025-10-04 00:17:31.721175 | localhost | ok 2025-10-04 00:17:31.753715 | 2025-10-04 00:17:31.753853 | TASK [Setup log path fact] 2025-10-04 00:17:31.785234 | localhost | ok 2025-10-04 00:17:31.805151 | 2025-10-04 00:17:31.805293 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-04 00:17:31.846951 | localhost | ok 2025-10-04 00:17:31.858388 | 2025-10-04 00:17:31.858498 | TASK [emit-job-header : Print job information] 2025-10-04 00:17:31.902905 | # Job Information 2025-10-04 00:17:31.903229 | Ansible Version: 2.15.12 2025-10-04 00:17:31.903300 | Job: ansible-test-sanity-docker-devel 2025-10-04 00:17:31.903345 | Pipeline: periodic 2025-10-04 00:17:31.903384 | Executor: ze01.softwarefactory-project.io 2025-10-04 00:17:31.903422 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-10-04 00:17:31.903466 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/2e9/ansible/2e9e91ec9cc748cb945adcc146119621/ 2025-10-04 00:17:31.903506 | Event ID: 94a13ac06c46427a92df5bbab63fdd0f 2025-10-04 00:17:31.911467 | 2025-10-04 00:17:31.911559 | LOOP [emit-job-header : Print node information] 2025-10-04 00:17:32.039290 | localhost | ok: 2025-10-04 00:17:32.039517 | localhost | # Node Information 2025-10-04 00:17:32.039551 | localhost | Inventory Hostname: controller 2025-10-04 00:17:32.039576 | localhost | Hostname: np0005470295 2025-10-04 00:17:32.039599 | localhost | Username: zuul 2025-10-04 00:17:32.039627 | localhost | Distro: Fedora 37 2025-10-04 00:17:32.039649 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2025-10-04 00:17:32.039666 | localhost | Region: ca-ymq-1 2025-10-04 00:17:32.039685 | localhost | Label: ansible-fedora-37-1vcpu 2025-10-04 00:17:32.039859 | localhost | Product Name: OpenStack Nova 2025-10-04 00:17:32.039898 | localhost | Interface IP: 162.253.55.209 2025-10-04 00:17:32.059413 | 2025-10-04 00:17:32.059542 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-04 00:17:32.440948 | localhost -> localhost | changed 2025-10-04 00:17:32.446353 | 2025-10-04 00:17:32.446425 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-04 00:17:33.292565 | localhost -> localhost | changed 2025-10-04 00:17:33.313046 | 2025-10-04 00:17:33.313163 | PLAY [all:!appliance*] 2025-10-04 00:17:33.330152 | 2025-10-04 00:17:33.330232 | TASK [include_role : start-zuul-console] 2025-10-04 00:17:33.349580 | controller | ok 2025-10-04 00:17:33.364203 | 2025-10-04 00:17:33.364272 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-04 00:17:33.760567 | controller | ok 2025-10-04 00:17:33.787867 | 2025-10-04 00:17:33.787966 | TASK [use-our-mirror : Retrieve the IP address] 2025-10-04 00:17:34.832626 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-10-04 00:17:34.842165 | 2025-10-04 00:17:34.842257 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-10-04 00:17:35.389261 | controller | skipping: Conditional result was False 2025-10-04 00:17:35.407481 | 2025-10-04 00:17:35.407626 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-10-04 00:17:35.435991 | controller | skipping: Conditional result was False 2025-10-04 00:17:35.445184 | 2025-10-04 00:17:35.445313 | TASK [use-our-mirror : Create the podman configuration directory] 2025-10-04 00:17:35.477579 | controller | skipping: Conditional result was False 2025-10-04 00:17:35.486345 | 2025-10-04 00:17:35.486452 | TASK [use-our-mirror : Copy the podman configuration] 2025-10-04 00:17:35.515794 | controller | skipping: Conditional result was False 2025-10-04 00:17:35.526602 | 2025-10-04 00:17:35.526733 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-10-04 00:17:35.543568 | controller | skipping: Conditional result was False 2025-10-04 00:17:35.551742 | 2025-10-04 00:17:35.551849 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-10-04 00:17:35.566751 | controller | skipping: Conditional result was False 2025-10-04 00:17:35.581796 | 2025-10-04 00:17:35.581903 | TASK [Disable Fedora Modular] 2025-10-04 00:17:35.814609 | controller | changed 2025-10-04 00:17:35.823103 | 2025-10-04 00:17:35.823502 | TASK [Enable EPEL] 2025-10-04 00:17:35.848134 | controller | skipping: Conditional result was False 2025-10-04 00:17:35.854786 | 2025-10-04 00:17:35.854884 | TASK [Register the RHEL node] 2025-10-04 00:17:36.394118 | 2025-10-04 00:17:36.394275 | TASK [Show the subscription-manager status] 2025-10-04 00:17:36.982883 | controller | skipping: Conditional result was False 2025-10-04 00:17:36.993128 | 2025-10-04 00:17:36.993255 | TASK [Enable EPEL on RHEL] 2025-10-04 00:17:37.546769 | controller | skipping: Conditional result was False 2025-10-04 00:17:37.555785 | 2025-10-04 00:17:37.555910 | TASK [Install git and tox] 2025-10-04 00:18:58.219985 | controller | changed 2025-10-04 00:18:58.233882 | 2025-10-04 00:18:58.233991 | TASK [include_role : prepare-workspace] 2025-10-04 00:18:58.264562 | controller | ok 2025-10-04 00:18:58.287740 | 2025-10-04 00:18:58.287820 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-04 00:18:58.523114 | controller | ok 2025-10-04 00:18:58.543454 | 2025-10-04 00:18:58.543630 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-04 00:19:10.732797 | controller | Output suppressed because no_log was given 2025-10-04 00:19:10.743188 | 2025-10-04 00:19:10.743286 | TASK [include_role : prepare-workspace-openshift] 2025-10-04 00:19:10.759283 | controller | skipping: Conditional result was False 2025-10-04 00:19:10.785518 | 2025-10-04 00:19:10.785595 | PLAY [all:!appliance] 2025-10-04 00:19:10.802252 | 2025-10-04 00:19:10.802375 | TASK [Run add-build-sshkey role (RSA)] 2025-10-04 00:19:10.823144 | controller | ok 2025-10-04 00:19:10.840495 | 2025-10-04 00:19:10.840617 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-04 00:19:11.080368 | controller -> localhost | ok 2025-10-04 00:19:11.087385 | 2025-10-04 00:19:11.087484 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-04 00:19:11.116545 | controller | ok 2025-10-04 00:19:11.131796 | controller | included: /var/lib/zuul/builds/2e9e91ec9cc748cb945adcc146119621/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-04 00:19:11.138120 | 2025-10-04 00:19:11.138198 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-04 00:19:11.814512 | controller -> localhost | Generating public/private rsa key pair. 2025-10-04 00:19:11.814710 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/2e9e91ec9cc748cb945adcc146119621/work/2e9e91ec9cc748cb945adcc146119621_id_rsa. 2025-10-04 00:19:11.814739 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/2e9e91ec9cc748cb945adcc146119621/work/2e9e91ec9cc748cb945adcc146119621_id_rsa.pub. 2025-10-04 00:19:11.814759 | controller -> localhost | The key fingerprint is: 2025-10-04 00:19:11.814778 | controller -> localhost | SHA256:4LQSFkUbC6fpq36tSS0sMVPh4dH/pIJtEMC754gpzeM zuul-build-sshkey 2025-10-04 00:19:11.814799 | controller -> localhost | The key's randomart image is: 2025-10-04 00:19:11.814818 | controller -> localhost | +---[RSA 2048]----+ 2025-10-04 00:19:11.814835 | controller -> localhost | | ...*+= | 2025-10-04 00:19:11.814853 | controller -> localhost | | .o.X.+ | 2025-10-04 00:19:11.814871 | controller -> localhost | | .O.=. | 2025-10-04 00:19:11.814888 | controller -> localhost | | .+.+ o. . | 2025-10-04 00:19:11.814905 | controller -> localhost | | +.o+o S+ | 2025-10-04 00:19:11.814963 | controller -> localhost | | .=o++ . . | 2025-10-04 00:19:11.814999 | controller -> localhost | | =.+=o.. | 2025-10-04 00:19:11.815081 | controller -> localhost | |+ =+oo. | 2025-10-04 00:19:11.815113 | controller -> localhost | |.oEoo. | 2025-10-04 00:19:11.815142 | controller -> localhost | +----[SHA256]-----+ 2025-10-04 00:19:11.815204 | controller -> localhost | ok: Runtime: 0:00:00.233960 2025-10-04 00:19:11.822035 | 2025-10-04 00:19:11.822147 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-04 00:19:11.853832 | controller | ok 2025-10-04 00:19:11.864269 | controller | included: /var/lib/zuul/builds/2e9e91ec9cc748cb945adcc146119621/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-04 00:19:11.873612 | 2025-10-04 00:19:11.873688 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-04 00:19:11.908099 | controller | skipping: Conditional result was False 2025-10-04 00:19:11.914554 | 2025-10-04 00:19:11.914661 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-04 00:19:12.388050 | controller | changed 2025-10-04 00:19:12.397003 | 2025-10-04 00:19:12.397160 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-04 00:19:12.616068 | controller | ok 2025-10-04 00:19:12.624097 | 2025-10-04 00:19:12.624206 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-04 00:19:13.258745 | controller | changed 2025-10-04 00:19:13.265249 | 2025-10-04 00:19:13.265393 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-04 00:19:13.915955 | controller | changed 2025-10-04 00:19:13.930911 | 2025-10-04 00:19:13.931093 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-04 00:19:13.966790 | controller | skipping: Conditional result was False 2025-10-04 00:19:13.976946 | 2025-10-04 00:19:13.977105 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-04 00:19:14.410530 | controller -> localhost | changed 2025-10-04 00:19:14.422829 | 2025-10-04 00:19:14.422918 | TASK [add-build-sshkey : Add back temp key] 2025-10-04 00:19:14.733806 | controller -> localhost | Identity added: /var/lib/zuul/builds/2e9e91ec9cc748cb945adcc146119621/work/2e9e91ec9cc748cb945adcc146119621_id_rsa (zuul-build-sshkey) 2025-10-04 00:19:14.734394 | controller -> localhost | ok: Runtime: 0:00:00.014262 2025-10-04 00:19:14.752892 | 2025-10-04 00:19:14.753131 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-04 00:19:15.119562 | controller | ok 2025-10-04 00:19:15.131616 | 2025-10-04 00:19:15.131751 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-04 00:19:15.159408 | controller | skipping: Conditional result was False 2025-10-04 00:19:15.188620 | 2025-10-04 00:19:15.188787 | TASK [Run add-build-sshkey role (ECDSA)] 2025-10-04 00:19:15.223063 | controller | ok 2025-10-04 00:19:15.246121 | 2025-10-04 00:19:15.246229 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-04 00:19:15.517948 | controller -> localhost | ok 2025-10-04 00:19:15.533603 | 2025-10-04 00:19:15.533794 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-04 00:19:15.560371 | controller | ok 2025-10-04 00:19:15.571876 | controller | included: /var/lib/zuul/builds/2e9e91ec9cc748cb945adcc146119621/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-04 00:19:15.579182 | 2025-10-04 00:19:15.579256 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-04 00:19:15.919340 | controller -> localhost | Generating public/private ecdsa key pair. 2025-10-04 00:19:15.919612 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/2e9e91ec9cc748cb945adcc146119621/work/2e9e91ec9cc748cb945adcc146119621_id_ecdsa. 2025-10-04 00:19:15.919643 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/2e9e91ec9cc748cb945adcc146119621/work/2e9e91ec9cc748cb945adcc146119621_id_ecdsa.pub. 2025-10-04 00:19:15.919673 | controller -> localhost | The key fingerprint is: 2025-10-04 00:19:15.919692 | controller -> localhost | SHA256:bYJ7AnnsoPPklVRKs8ULv0qtkt+GR3A9a4ZMHLZrPzg zuul-build-sshkey 2025-10-04 00:19:15.919712 | controller -> localhost | The key's randomart image is: 2025-10-04 00:19:15.919730 | controller -> localhost | +---[ECDSA 521]---+ 2025-10-04 00:19:15.919747 | controller -> localhost | | | 2025-10-04 00:19:15.919764 | controller -> localhost | | .o | 2025-10-04 00:19:15.919782 | controller -> localhost | | +o++ | 2025-10-04 00:19:15.919800 | controller -> localhost | | +.X=oo | 2025-10-04 00:19:15.919816 | controller -> localhost | | + B=Sooo | 2025-10-04 00:19:15.919833 | controller -> localhost | | . * +*++ | 2025-10-04 00:19:15.919849 | controller -> localhost | | o ..B++= | 2025-10-04 00:19:15.919865 | controller -> localhost | | =oo.*E o | 2025-10-04 00:19:15.919881 | controller -> localhost | | oo+o.. . | 2025-10-04 00:19:15.919898 | controller -> localhost | +----[SHA256]-----+ 2025-10-04 00:19:15.919948 | controller -> localhost | ok: Runtime: 0:00:00.010344 2025-10-04 00:19:15.927197 | 2025-10-04 00:19:15.927267 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-04 00:19:15.958863 | controller | ok 2025-10-04 00:19:15.968257 | controller | included: /var/lib/zuul/builds/2e9e91ec9cc748cb945adcc146119621/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-04 00:19:15.978203 | 2025-10-04 00:19:15.978276 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-04 00:19:16.002426 | controller | skipping: Conditional result was False 2025-10-04 00:19:16.011666 | 2025-10-04 00:19:16.011780 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-04 00:19:16.284987 | controller | changed 2025-10-04 00:19:16.300142 | 2025-10-04 00:19:16.300326 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-04 00:19:16.519191 | controller | ok 2025-10-04 00:19:16.526065 | 2025-10-04 00:19:16.526194 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-04 00:19:17.177738 | controller | changed 2025-10-04 00:19:17.194236 | 2025-10-04 00:19:17.194392 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-04 00:19:17.840320 | controller | changed 2025-10-04 00:19:17.882055 | 2025-10-04 00:19:17.882192 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-04 00:19:17.906741 | controller | skipping: Conditional result was False 2025-10-04 00:19:17.917932 | 2025-10-04 00:19:17.918124 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-04 00:19:18.190717 | controller -> localhost | changed 2025-10-04 00:19:18.204141 | 2025-10-04 00:19:18.204278 | TASK [add-build-sshkey : Add back temp key] 2025-10-04 00:19:18.508518 | controller -> localhost | Identity added: /var/lib/zuul/builds/2e9e91ec9cc748cb945adcc146119621/work/2e9e91ec9cc748cb945adcc146119621_id_ecdsa (zuul-build-sshkey) 2025-10-04 00:19:18.509065 | controller -> localhost | ok: Runtime: 0:00:00.013352 2025-10-04 00:19:18.526438 | 2025-10-04 00:19:18.526608 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-04 00:19:18.746081 | controller | ok 2025-10-04 00:19:18.763010 | 2025-10-04 00:19:18.763382 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-04 00:19:18.789987 | controller | skipping: Conditional result was False 2025-10-04 00:19:18.808048 | 2025-10-04 00:19:18.808164 | TASK [include_role : remove-zuul-sshkey] 2025-10-04 00:19:18.824365 | controller | skipping: Conditional result was False 2025-10-04 00:19:18.832051 | 2025-10-04 00:19:18.832175 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-04 00:19:19.056645 | controller | ok: "logs" 2025-10-04 00:19:19.056915 | controller | ok: All items complete 2025-10-04 00:19:19.056944 | 2025-10-04 00:19:19.263160 | controller | ok: "artifacts" 2025-10-04 00:19:19.462445 | controller | ok: "docs" 2025-10-04 00:19:19.474478 | 2025-10-04 00:19:19.474619 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-04 00:19:19.727923 | controller | changed: "logs" 2025-10-04 00:19:19.960263 | controller | changed: "artifacts" 2025-10-04 00:19:20.151499 | controller | changed: "docs" 2025-10-04 00:19:20.197231 | 2025-10-04 00:19:20.197342 | PLAY RECAP 2025-10-04 00:19:20.197388 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-10-04 00:19:20.197414 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-04 00:19:20.197431 | 2025-10-04 00:19:20.318000 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-10-04 00:19:20.319473 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-04 00:19:20.936216 | 2025-10-04 00:19:20.936331 | PLAY [all] 2025-10-04 00:19:20.959197 | 2025-10-04 00:19:20.959301 | TASK [Install binary dependencies] 2025-10-04 00:19:21.020541 | controller | ok 2025-10-04 00:19:21.041940 | 2025-10-04 00:19:21.042069 | TASK [bindep : Include find tasks] 2025-10-04 00:19:21.071997 | controller | ok 2025-10-04 00:19:21.080242 | controller | included: /var/lib/zuul/builds/2e9e91ec9cc748cb945adcc146119621/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-10-04 00:19:21.086664 | 2025-10-04 00:19:21.086729 | TASK [bindep : Look for bindep.txt] 2025-10-04 00:19:21.438932 | controller | ok 2025-10-04 00:19:21.452165 | 2025-10-04 00:19:21.452320 | TASK [bindep : Define bindep_file fact] 2025-10-04 00:19:21.478824 | controller | skipping: Conditional result was False 2025-10-04 00:19:21.493009 | 2025-10-04 00:19:21.493251 | TASK [bindep : Look for other-requirements.txt] 2025-10-04 00:19:21.717301 | controller | ok 2025-10-04 00:19:21.733399 | 2025-10-04 00:19:21.733633 | TASK [bindep : Define bindep_file fact] 2025-10-04 00:19:21.761895 | controller | skipping: Conditional result was False 2025-10-04 00:19:21.774960 | 2025-10-04 00:19:21.775099 | TASK [bindep : Look for bindep fallback file] 2025-10-04 00:19:21.810610 | controller | skipping: Conditional result was False 2025-10-04 00:19:21.828554 | 2025-10-04 00:19:21.828762 | TASK [bindep : Define bindep_file fact] 2025-10-04 00:19:21.855775 | controller | skipping: Conditional result was False 2025-10-04 00:19:21.865093 | 2025-10-04 00:19:21.865213 | TASK [bindep : Include bindep tasks] 2025-10-04 00:19:21.900326 | controller | skipping: Conditional result was False 2025-10-04 00:19:21.915487 | 2025-10-04 00:19:21.915679 | TASK [bindep : Include install tasks] 2025-10-04 00:19:21.952560 | controller | skipping: Conditional result was False 2025-10-04 00:19:21.968363 | 2025-10-04 00:19:21.968594 | LOOP [bindep : Include package tasks] 2025-10-04 00:19:22.055470 | 2025-10-04 00:19:22.055688 | TASK [Run test-setup role] 2025-10-04 00:19:22.080926 | controller | ok 2025-10-04 00:19:22.109290 | 2025-10-04 00:19:22.109453 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-10-04 00:19:22.327696 | controller | ok 2025-10-04 00:19:22.337890 | 2025-10-04 00:19:22.338165 | TASK [test-setup : Run tools/test-setup.sh] 2025-10-04 00:19:52.401582 | [Zuul] Log Stream did not terminate 2025-10-04 00:19:52.401917 | controller | skipping: Conditional result was False 2025-10-04 00:19:52.454262 | 2025-10-04 00:19:52.454383 | PLAY RECAP 2025-10-04 00:19:52.454442 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-04 00:19:52.454473 | 2025-10-04 00:19:52.596574 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-04 00:19:52.597477 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-10-04 00:19:53.263338 | 2025-10-04 00:19:53.263453 | PLAY [controller] 2025-10-04 00:19:53.284337 | 2025-10-04 00:19:53.284456 | TASK [Create the /root directory] 2025-10-04 00:22:05.572736 | controller | ERROR 2025-10-04 00:22:05.572957 | controller | { 2025-10-04 00:22:05.573002 | controller | "msg": "Timeout (32s) waiting for privilege escalation prompt: " 2025-10-04 00:22:05.573055 | controller | } failure 2025-10-04 00:22:05.574683 | 2025-10-04 00:22:05.574734 | PLAY RECAP 2025-10-04 00:22:05.574785 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-10-04 00:22:05.574814 | 2025-10-04 00:22:05.691821 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-10-04 00:22:05.692844 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-04 00:22:06.354187 | 2025-10-04 00:22:06.354308 | PLAY [all] 2025-10-04 00:22:06.376974 | 2025-10-04 00:22:06.377137 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-04 00:24:22.936149 | controller | changed: non-zero return code 2025-10-04 00:24:22.945180 | 2025-10-04 00:24:22.945282 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-04 00:24:22.971380 | controller | skipping: Conditional result was False 2025-10-04 00:24:22.977692 | 2025-10-04 00:24:22.977769 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-04 00:24:23.009269 | 2025-10-04 00:24:23.009433 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-04 00:24:23.044126 | 2025-10-04 00:24:23.044307 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-04 00:24:23.058882 | controller | skipping: Conditional result was False 2025-10-04 00:24:23.066105 | 2025-10-04 00:24:23.066212 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-04 00:24:23.088899 | 2025-10-04 00:24:23.089113 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-04 00:24:23.103275 | controller | skipping: Conditional result was False 2025-10-04 00:24:23.113059 | 2025-10-04 00:24:23.113227 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-04 00:24:23.128238 | controller | skipping: Conditional result was False 2025-10-04 00:24:23.134873 | 2025-10-04 00:24:23.134950 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-04 00:24:23.148894 | controller | skipping: Conditional result was False 2025-10-04 00:24:23.176487 | 2025-10-04 00:24:23.176571 | PLAY RECAP 2025-10-04 00:24:23.176614 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-04 00:24:23.176634 | 2025-10-04 00:24:23.288577 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-04 00:24:23.289850 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-10-04 00:24:23.887304 | 2025-10-04 00:24:23.887465 | PLAY [all:!appliance*] 2025-10-04 00:24:23.911109 | 2025-10-04 00:24:23.911231 | TASK [unregister the node] 2025-10-04 00:24:24.442898 | controller | skipping: Conditional result was False 2025-10-04 00:24:24.455712 | 2025-10-04 00:24:24.455933 | TASK [include_role : fetch-output] 2025-10-04 00:24:24.496283 | controller | ok 2025-10-04 00:24:24.535620 | 2025-10-04 00:24:24.535775 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-04 00:24:24.592417 | controller | skipping: Conditional result was False 2025-10-04 00:24:24.601621 | 2025-10-04 00:24:24.601741 | TASK [fetch-output : Set log path for single node] 2025-10-04 00:24:24.636087 | controller | ok 2025-10-04 00:24:24.646701 | 2025-10-04 00:24:24.646848 | LOOP [fetch-output : Ensure local output dirs] 2025-10-04 00:24:25.036644 | controller -> localhost | ok: "/var/lib/zuul/builds/2e9e91ec9cc748cb945adcc146119621/work/logs" 2025-10-04 00:24:25.249942 | controller -> localhost | changed: "/var/lib/zuul/builds/2e9e91ec9cc748cb945adcc146119621/work/artifacts" 2025-10-04 00:24:25.454881 | controller -> localhost | changed: "/var/lib/zuul/builds/2e9e91ec9cc748cb945adcc146119621/work/docs" 2025-10-04 00:24:25.467072 | 2025-10-04 00:24:25.467183 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-04 00:24:26.094294 | controller | changed: .d..t...... ./ 2025-10-04 00:24:26.094783 | controller | changed: All items complete 2025-10-04 00:24:26.094830 | 2025-10-04 00:24:26.593535 | controller | changed: .d..t...... ./ 2025-10-04 00:24:27.120728 | controller | changed: .d..t...... ./ 2025-10-04 00:24:27.151761 | 2025-10-04 00:24:27.151940 | TASK [include_role : fetch-output-openshift] 2025-10-04 00:24:27.178337 | controller | skipping: Conditional result was False 2025-10-04 00:24:27.197064 | 2025-10-04 00:24:27.197313 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-04 00:24:27.250629 | controller | skipping: Conditional result was False 2025-10-04 00:24:27.262193 | controller | skipping: Conditional result was False 2025-10-04 00:24:27.322728 | 2025-10-04 00:24:27.322897 | PLAY [localhost] 2025-10-04 00:24:27.342312 | 2025-10-04 00:24:27.342455 | TASK [Run Zuul manifest role] 2025-10-04 00:24:27.363404 | localhost | ok 2025-10-04 00:24:27.384143 | 2025-10-04 00:24:27.384256 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-04 00:24:27.812399 | localhost | changed 2025-10-04 00:24:27.820856 | 2025-10-04 00:24:27.820994 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-04 00:24:27.853715 | localhost | ok 2025-10-04 00:24:27.867465 | 2025-10-04 00:24:27.867615 | TASK [Set zuul-log-path fact] 2025-10-04 00:24:27.892068 | localhost | ok 2025-10-04 00:24:27.914719 | 2025-10-04 00:24:27.914870 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-04 00:24:27.950908 | localhost | ok 2025-10-04 00:24:27.964559 | 2025-10-04 00:24:27.964712 | LOOP [Run upload-logs-swift role] 2025-10-04 00:24:28.002776 | localhost | Output suppressed because no_log was given 2025-10-04 00:24:28.046077 | 2025-10-04 00:24:28.046227 | TASK [Set zuul-log-path fact] 2025-10-04 00:24:28.083175 | localhost | skipping: Conditional result was False 2025-10-04 00:24:28.090930 | 2025-10-04 00:24:28.091089 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-10-04 00:24:28.590855 | localhost -> localhost | ok: Runtime: 0:00:00.005765 2025-10-04 00:24:28.608681 | 2025-10-04 00:24:28.608874 | TASK [upload-logs-swift : Upload logs to swift]