2026-03-08 00:28:08.867704 | Job console starting... 2026-03-08 00:28:08.880800 | Updating repositories 2026-03-08 00:28:09.030173 | Preparing job workspace 2026-03-08 00:28:12.869845 | Running Ansible setup... 2026-03-08 00:28:17.792687 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-08 00:28:18.452609 | 2026-03-08 00:28:18.452816 | PLAY [localhost] 2026-03-08 00:28:18.464547 | 2026-03-08 00:28:18.464682 | TASK [Gathering Facts] 2026-03-08 00:28:19.523194 | localhost | ok 2026-03-08 00:28:19.551593 | 2026-03-08 00:28:19.551868 | TASK [Setup log path fact] 2026-03-08 00:28:19.572541 | localhost | ok 2026-03-08 00:28:19.593787 | 2026-03-08 00:28:19.594034 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-08 00:28:19.627463 | localhost | ok 2026-03-08 00:28:19.639236 | 2026-03-08 00:28:19.639341 | TASK [emit-job-header : Print job information] 2026-03-08 00:28:19.695672 | # Job Information 2026-03-08 00:28:19.696046 | Ansible Version: 2.15.12 2026-03-08 00:28:19.696124 | Job: ansible-test-sanity-docker-devel 2026-03-08 00:28:19.696171 | Pipeline: periodic 2026-03-08 00:28:19.696212 | Executor: ze03.softwarefactory-project.io 2026-03-08 00:28:19.696252 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-03-08 00:28:19.696299 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/042/ansible/042b7256614d4bcc92db2741548a107f/ 2026-03-08 00:28:19.696343 | Event ID: 5cf2cc93c96b4f3a806cea0572acc5d8 2026-03-08 00:28:19.705127 | 2026-03-08 00:28:19.705285 | LOOP [emit-job-header : Print node information] 2026-03-08 00:28:19.812498 | localhost | ok: 2026-03-08 00:28:19.812756 | localhost | # Node Information 2026-03-08 00:28:19.812797 | localhost | Inventory Hostname: controller 2026-03-08 00:28:19.812827 | localhost | Hostname: np0005641493 2026-03-08 00:28:19.812855 | localhost | Username: zuul 2026-03-08 00:28:19.812884 | localhost | Distro: Fedora 37 2026-03-08 00:28:19.812904 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-03-08 00:28:19.812922 | localhost | Region: ca-ymq-1 2026-03-08 00:28:19.812939 | localhost | Label: ansible-fedora-37-1vcpu 2026-03-08 00:28:19.812956 | localhost | Product Name: OpenStack Nova 2026-03-08 00:28:19.812973 | localhost | Interface IP: 162.253.55.140 2026-03-08 00:28:19.825640 | 2026-03-08 00:28:19.825879 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-08 00:28:20.263492 | localhost -> localhost | changed 2026-03-08 00:28:20.269539 | 2026-03-08 00:28:20.269626 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-08 00:28:21.196284 | localhost -> localhost | changed 2026-03-08 00:28:21.235191 | 2026-03-08 00:28:21.235301 | PLAY [all:!appliance*] 2026-03-08 00:28:21.259247 | 2026-03-08 00:28:21.259372 | TASK [include_role : start-zuul-console] 2026-03-08 00:28:21.282482 | controller | ok 2026-03-08 00:28:21.303543 | 2026-03-08 00:28:21.303672 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-03-08 00:28:21.688866 | controller | ok 2026-03-08 00:28:21.715379 | 2026-03-08 00:28:21.715568 | TASK [use-our-mirror : Retrieve the IP address] 2026-03-08 00:28:22.898715 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-03-08 00:28:22.906443 | 2026-03-08 00:28:22.906568 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-03-08 00:28:23.439086 | controller | skipping: Conditional result was False 2026-03-08 00:28:23.449200 | 2026-03-08 00:28:23.449345 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-03-08 00:28:23.484770 | controller | skipping: Conditional result was False 2026-03-08 00:28:23.493917 | 2026-03-08 00:28:23.494062 | TASK [use-our-mirror : Create the podman configuration directory] 2026-03-08 00:28:23.519404 | controller | skipping: Conditional result was False 2026-03-08 00:28:23.529060 | 2026-03-08 00:28:23.529193 | TASK [use-our-mirror : Copy the podman configuration] 2026-03-08 00:28:23.554345 | controller | skipping: Conditional result was False 2026-03-08 00:28:23.564269 | 2026-03-08 00:28:23.564414 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-03-08 00:28:23.589950 | controller | skipping: Conditional result was False 2026-03-08 00:28:23.603466 | 2026-03-08 00:28:23.603611 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-03-08 00:28:23.628981 | controller | skipping: Conditional result was False 2026-03-08 00:28:23.646111 | 2026-03-08 00:28:23.646256 | TASK [Disable Fedora Modular] 2026-03-08 00:28:23.897440 | controller | changed 2026-03-08 00:28:23.910920 | 2026-03-08 00:28:23.911086 | TASK [Enable EPEL] 2026-03-08 00:28:23.938268 | controller | skipping: Conditional result was False 2026-03-08 00:28:23.947936 | 2026-03-08 00:28:23.948098 | TASK [Register the RHEL node] 2026-03-08 00:28:24.502278 | 2026-03-08 00:28:24.502451 | TASK [Show the subscription-manager status] 2026-03-08 00:28:25.070325 | controller | skipping: Conditional result was False 2026-03-08 00:28:25.080499 | 2026-03-08 00:28:25.080630 | TASK [Enable EPEL on RHEL] 2026-03-08 00:28:25.631996 | controller | skipping: Conditional result was False 2026-03-08 00:28:25.645518 | 2026-03-08 00:28:25.645687 | TASK [Install git and tox] 2026-03-08 00:29:45.261604 | controller | changed 2026-03-08 00:29:45.273595 | 2026-03-08 00:29:45.273754 | TASK [include_role : prepare-workspace] 2026-03-08 00:29:45.304624 | controller | ok 2026-03-08 00:29:45.326359 | 2026-03-08 00:29:45.326468 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-08 00:29:45.561566 | controller | ok 2026-03-08 00:29:45.569580 | 2026-03-08 00:29:45.569722 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-08 00:29:58.751293 | controller | Output suppressed because no_log was given 2026-03-08 00:29:58.763163 | 2026-03-08 00:29:58.763264 | TASK [include_role : prepare-workspace-openshift] 2026-03-08 00:29:58.779841 | controller | skipping: Conditional result was False 2026-03-08 00:29:58.808529 | 2026-03-08 00:29:58.808656 | PLAY [all:!appliance] 2026-03-08 00:29:58.828686 | 2026-03-08 00:29:58.828854 | TASK [Run add-build-sshkey role (RSA)] 2026-03-08 00:29:58.850105 | controller | ok 2026-03-08 00:29:58.867932 | 2026-03-08 00:29:58.868054 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-08 00:29:59.136994 | controller -> localhost | ok 2026-03-08 00:29:59.144949 | 2026-03-08 00:29:59.145074 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-08 00:29:59.166704 | controller | ok 2026-03-08 00:29:59.187782 | controller | included: /var/lib/zuul/builds/042b7256614d4bcc92db2741548a107f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-08 00:29:59.194768 | 2026-03-08 00:29:59.194874 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-08 00:29:59.683624 | controller -> localhost | Generating public/private rsa key pair. 2026-03-08 00:29:59.683852 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/042b7256614d4bcc92db2741548a107f/work/042b7256614d4bcc92db2741548a107f_id_rsa. 2026-03-08 00:29:59.683887 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/042b7256614d4bcc92db2741548a107f/work/042b7256614d4bcc92db2741548a107f_id_rsa.pub. 2026-03-08 00:29:59.683911 | controller -> localhost | The key fingerprint is: 2026-03-08 00:29:59.683933 | controller -> localhost | SHA256:P0Zu0CRmz+0DeSV7Z9cr2iPYQ9CIR1K81bNg6TKkROg zuul-build-sshkey 2026-03-08 00:29:59.683956 | controller -> localhost | The key's randomart image is: 2026-03-08 00:29:59.683978 | controller -> localhost | +---[RSA 2048]----+ 2026-03-08 00:29:59.684004 | controller -> localhost | | o.o. o | 2026-03-08 00:29:59.684031 | controller -> localhost | | . o + = o | 2026-03-08 00:29:59.684052 | controller -> localhost | | . . X O o + | 2026-03-08 00:29:59.684070 | controller -> localhost | | E = & = = .| 2026-03-08 00:29:59.684088 | controller -> localhost | | S @ + . =| 2026-03-08 00:29:59.684117 | controller -> localhost | | = = . oo| 2026-03-08 00:29:59.684140 | controller -> localhost | | X o. . | 2026-03-08 00:29:59.684158 | controller -> localhost | | + =oo. | 2026-03-08 00:29:59.684176 | controller -> localhost | | .o.. | 2026-03-08 00:29:59.684196 | controller -> localhost | +----[SHA256]-----+ 2026-03-08 00:29:59.684239 | controller -> localhost | ok: Runtime: 0:00:00.116863 2026-03-08 00:29:59.695232 | 2026-03-08 00:29:59.695362 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-08 00:29:59.725740 | controller | ok 2026-03-08 00:29:59.736223 | controller | included: /var/lib/zuul/builds/042b7256614d4bcc92db2741548a107f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-08 00:29:59.745549 | 2026-03-08 00:29:59.745638 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-08 00:29:59.761279 | controller | skipping: Conditional result was False 2026-03-08 00:29:59.769713 | 2026-03-08 00:29:59.769890 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-08 00:30:00.225283 | controller | changed 2026-03-08 00:30:00.232359 | 2026-03-08 00:30:00.232467 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-08 00:30:00.439987 | controller | ok 2026-03-08 00:30:00.448928 | 2026-03-08 00:30:00.449055 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-08 00:30:01.054718 | controller | changed 2026-03-08 00:30:01.067224 | 2026-03-08 00:30:01.067361 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-08 00:30:01.683248 | controller | changed 2026-03-08 00:30:01.690953 | 2026-03-08 00:30:01.691224 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-08 00:30:01.716818 | controller | skipping: Conditional result was False 2026-03-08 00:30:01.725700 | 2026-03-08 00:30:01.726072 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-08 00:30:02.168076 | controller -> localhost | changed 2026-03-08 00:30:02.185190 | 2026-03-08 00:30:02.185351 | TASK [add-build-sshkey : Add back temp key] 2026-03-08 00:30:02.466479 | controller -> localhost | Identity added: /var/lib/zuul/builds/042b7256614d4bcc92db2741548a107f/work/042b7256614d4bcc92db2741548a107f_id_rsa (zuul-build-sshkey) 2026-03-08 00:30:02.467071 | controller -> localhost | ok: Runtime: 0:00:00.009643 2026-03-08 00:30:02.486384 | 2026-03-08 00:30:02.486658 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-08 00:30:02.858833 | controller | ok 2026-03-08 00:30:02.865704 | 2026-03-08 00:30:02.865855 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-08 00:30:02.893137 | controller | skipping: Conditional result was False 2026-03-08 00:30:02.910979 | 2026-03-08 00:30:02.911183 | TASK [Run add-build-sshkey role (ECDSA)] 2026-03-08 00:30:02.948466 | controller | ok 2026-03-08 00:30:02.975081 | 2026-03-08 00:30:02.975261 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-08 00:30:03.247504 | controller -> localhost | ok 2026-03-08 00:30:03.257306 | 2026-03-08 00:30:03.257454 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-08 00:30:03.293891 | controller | ok 2026-03-08 00:30:03.306322 | controller | included: /var/lib/zuul/builds/042b7256614d4bcc92db2741548a107f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-08 00:30:03.313204 | 2026-03-08 00:30:03.313337 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-08 00:30:03.603912 | controller -> localhost | Generating public/private ecdsa key pair. 2026-03-08 00:30:03.604125 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/042b7256614d4bcc92db2741548a107f/work/042b7256614d4bcc92db2741548a107f_id_ecdsa. 2026-03-08 00:30:03.604161 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/042b7256614d4bcc92db2741548a107f/work/042b7256614d4bcc92db2741548a107f_id_ecdsa.pub. 2026-03-08 00:30:03.604197 | controller -> localhost | The key fingerprint is: 2026-03-08 00:30:03.604220 | controller -> localhost | SHA256:on35jAc4WgYQB8onOt/1rTPvv320qD8Fv5nN+k7H6aA zuul-build-sshkey 2026-03-08 00:30:03.604242 | controller -> localhost | The key's randomart image is: 2026-03-08 00:30:03.604264 | controller -> localhost | +---[ECDSA 521]---+ 2026-03-08 00:30:03.604285 | controller -> localhost | | oo. | 2026-03-08 00:30:03.604306 | controller -> localhost | |.... | 2026-03-08 00:30:03.604326 | controller -> localhost | |.o o | 2026-03-08 00:30:03.604346 | controller -> localhost | |. o . . | 2026-03-08 00:30:03.604366 | controller -> localhost | |o .o.S o | 2026-03-08 00:30:03.604386 | controller -> localhost | | o . +=o.o +o| 2026-03-08 00:30:03.604406 | controller -> localhost | | . o+..+.. .o+X| 2026-03-08 00:30:03.604426 | controller -> localhost | | . .o=. .+o*=| 2026-03-08 00:30:03.604446 | controller -> localhost | | o*=E=o+*o| 2026-03-08 00:30:03.604469 | controller -> localhost | +----[SHA256]-----+ 2026-03-08 00:30:03.604526 | controller -> localhost | ok: Runtime: 0:00:00.012832 2026-03-08 00:30:03.611618 | 2026-03-08 00:30:03.611751 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-08 00:30:03.642893 | controller | ok 2026-03-08 00:30:03.653479 | controller | included: /var/lib/zuul/builds/042b7256614d4bcc92db2741548a107f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-08 00:30:03.663097 | 2026-03-08 00:30:03.663224 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-08 00:30:03.687942 | controller | skipping: Conditional result was False 2026-03-08 00:30:03.695851 | 2026-03-08 00:30:03.696001 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-08 00:30:03.967574 | controller | changed 2026-03-08 00:30:03.974567 | 2026-03-08 00:30:03.974686 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-08 00:30:04.193546 | controller | ok 2026-03-08 00:30:04.203522 | 2026-03-08 00:30:04.203859 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-08 00:30:04.833127 | controller | changed 2026-03-08 00:30:04.842561 | 2026-03-08 00:30:04.842702 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-08 00:30:05.466386 | controller | changed 2026-03-08 00:30:05.526194 | 2026-03-08 00:30:05.526327 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-08 00:30:05.551083 | controller | skipping: Conditional result was False 2026-03-08 00:30:05.557961 | 2026-03-08 00:30:05.558067 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-08 00:30:05.840559 | controller -> localhost | changed 2026-03-08 00:30:05.855013 | 2026-03-08 00:30:05.855144 | TASK [add-build-sshkey : Add back temp key] 2026-03-08 00:30:06.148000 | controller -> localhost | Identity added: /var/lib/zuul/builds/042b7256614d4bcc92db2741548a107f/work/042b7256614d4bcc92db2741548a107f_id_ecdsa (zuul-build-sshkey) 2026-03-08 00:30:06.148832 | controller -> localhost | ok: Runtime: 0:00:00.014379 2026-03-08 00:30:06.169437 | 2026-03-08 00:30:06.169676 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-08 00:30:06.381973 | controller | ok 2026-03-08 00:30:06.404137 | 2026-03-08 00:30:06.404301 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-08 00:30:06.447387 | controller | skipping: Conditional result was False 2026-03-08 00:30:06.463883 | 2026-03-08 00:30:06.464024 | TASK [include_role : remove-zuul-sshkey] 2026-03-08 00:30:06.490061 | controller | skipping: Conditional result was False 2026-03-08 00:30:06.501241 | 2026-03-08 00:30:06.501368 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-08 00:30:06.734146 | controller | ok: "logs" 2026-03-08 00:30:06.734397 | controller | ok: All items complete 2026-03-08 00:30:06.734426 | 2026-03-08 00:30:06.922394 | controller | ok: "artifacts" 2026-03-08 00:30:07.109329 | controller | ok: "docs" 2026-03-08 00:30:07.125188 | 2026-03-08 00:30:07.125322 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-08 00:30:07.389043 | controller | changed: "logs" 2026-03-08 00:30:07.598206 | controller | changed: "artifacts" 2026-03-08 00:30:07.785635 | controller | changed: "docs" 2026-03-08 00:30:07.819189 | 2026-03-08 00:30:07.819308 | PLAY RECAP 2026-03-08 00:30:07.819353 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-03-08 00:30:07.819380 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-08 00:30:07.819398 | 2026-03-08 00:30:07.973131 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-08 00:30:07.974021 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-08 00:30:08.701785 | 2026-03-08 00:30:08.701936 | PLAY [all] 2026-03-08 00:30:08.731861 | 2026-03-08 00:30:08.732034 | TASK [Install binary dependencies] 2026-03-08 00:30:08.792118 | controller | ok 2026-03-08 00:30:08.820650 | 2026-03-08 00:30:08.820871 | TASK [bindep : Include find tasks] 2026-03-08 00:30:08.851585 | controller | ok 2026-03-08 00:30:08.860351 | controller | included: /var/lib/zuul/builds/042b7256614d4bcc92db2741548a107f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-03-08 00:30:08.869906 | 2026-03-08 00:30:08.870070 | TASK [bindep : Look for bindep.txt] 2026-03-08 00:30:09.253030 | controller | ok 2026-03-08 00:30:09.261979 | 2026-03-08 00:30:09.262131 | TASK [bindep : Define bindep_file fact] 2026-03-08 00:30:09.287986 | controller | skipping: Conditional result was False 2026-03-08 00:30:09.297005 | 2026-03-08 00:30:09.297149 | TASK [bindep : Look for other-requirements.txt] 2026-03-08 00:30:09.502430 | controller | ok 2026-03-08 00:30:09.508990 | 2026-03-08 00:30:09.509086 | TASK [bindep : Define bindep_file fact] 2026-03-08 00:30:09.533428 | controller | skipping: Conditional result was False 2026-03-08 00:30:09.540504 | 2026-03-08 00:30:09.540615 | TASK [bindep : Look for bindep fallback file] 2026-03-08 00:30:09.565439 | controller | skipping: Conditional result was False 2026-03-08 00:30:09.573309 | 2026-03-08 00:30:09.573442 | TASK [bindep : Define bindep_file fact] 2026-03-08 00:30:09.598339 | controller | skipping: Conditional result was False 2026-03-08 00:30:09.607003 | 2026-03-08 00:30:09.607115 | TASK [bindep : Include bindep tasks] 2026-03-08 00:30:09.632669 | controller | skipping: Conditional result was False 2026-03-08 00:30:09.642310 | 2026-03-08 00:30:09.642442 | TASK [bindep : Include install tasks] 2026-03-08 00:30:09.669467 | controller | skipping: Conditional result was False 2026-03-08 00:30:09.679263 | 2026-03-08 00:30:09.679414 | LOOP [bindep : Include package tasks] 2026-03-08 00:30:09.738753 | 2026-03-08 00:30:09.738939 | TASK [Run test-setup role] 2026-03-08 00:30:09.761334 | controller | ok 2026-03-08 00:30:09.781169 | 2026-03-08 00:30:09.781301 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-03-08 00:30:09.988285 | controller | ok 2026-03-08 00:30:09.997458 | 2026-03-08 00:30:09.997594 | TASK [test-setup : Run tools/test-setup.sh] 2026-03-08 00:30:40.059661 | [Zuul] Log Stream did not terminate 2026-03-08 00:30:40.059978 | controller | skipping: Conditional result was False 2026-03-08 00:30:40.090575 | 2026-03-08 00:30:40.090676 | PLAY RECAP 2026-03-08 00:30:40.090718 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-08 00:30:40.090762 | 2026-03-08 00:30:40.211627 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-08 00:30:40.212493 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-08 00:30:40.852363 | 2026-03-08 00:30:40.852502 | PLAY [controller] 2026-03-08 00:30:40.875059 | 2026-03-08 00:30:40.875198 | TASK [Create the /root directory] 2026-03-08 00:32:53.191856 | controller | ERROR 2026-03-08 00:32:53.192065 | controller | { 2026-03-08 00:32:53.192103 | controller | "msg": "Timeout (32s) waiting for privilege escalation prompt: " 2026-03-08 00:32:53.192132 | controller | } failure 2026-03-08 00:32:53.193154 | 2026-03-08 00:32:53.193201 | PLAY RECAP 2026-03-08 00:32:53.193239 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-03-08 00:32:53.193261 | 2026-03-08 00:32:53.324315 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-08 00:32:53.325496 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-08 00:32:54.025085 | 2026-03-08 00:32:54.025278 | PLAY [all] 2026-03-08 00:32:54.051325 | 2026-03-08 00:32:54.051514 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-03-08 00:35:41.862520 | controller | changed: non-zero return code 2026-03-08 00:35:41.873199 | 2026-03-08 00:35:41.873382 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-03-08 00:35:41.889453 | controller | skipping: Conditional result was False 2026-03-08 00:35:41.902886 | 2026-03-08 00:35:41.903089 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-03-08 00:35:41.940130 | 2026-03-08 00:35:41.940346 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-03-08 00:35:41.972918 | 2026-03-08 00:35:41.973101 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-03-08 00:35:41.987958 | controller | skipping: Conditional result was False 2026-03-08 00:35:41.996117 | 2026-03-08 00:35:41.996243 | LOOP [fetch-subunit-output : Generate subunit file] 2026-03-08 00:35:42.019165 | 2026-03-08 00:35:42.019363 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-03-08 00:35:42.035807 | controller | skipping: Conditional result was False 2026-03-08 00:35:42.045122 | 2026-03-08 00:35:42.045232 | TASK [fetch-subunit-output : Remove the temporary file] 2026-03-08 00:35:42.070261 | controller | skipping: Conditional result was False 2026-03-08 00:35:42.077756 | 2026-03-08 00:35:42.077906 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-03-08 00:35:42.103364 | controller | skipping: Conditional result was False 2026-03-08 00:35:42.135165 | 2026-03-08 00:35:42.135289 | PLAY RECAP 2026-03-08 00:35:42.135335 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-08 00:35:42.135356 | 2026-03-08 00:35:42.303768 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-08 00:35:42.304682 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-03-08 00:35:43.003221 | 2026-03-08 00:35:43.003374 | PLAY [all:!appliance*] 2026-03-08 00:35:43.029065 | 2026-03-08 00:35:43.029248 | TASK [unregister the node] 2026-03-08 00:35:43.568803 | controller | skipping: Conditional result was False 2026-03-08 00:35:43.578631 | 2026-03-08 00:35:43.578788 | TASK [include_role : fetch-output] 2026-03-08 00:35:43.616986 | controller | ok 2026-03-08 00:35:43.655159 | 2026-03-08 00:35:43.655332 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-08 00:35:43.701944 | controller | skipping: Conditional result was False 2026-03-08 00:35:43.710143 | 2026-03-08 00:35:43.710238 | TASK [fetch-output : Set log path for single node] 2026-03-08 00:35:43.753012 | controller | ok 2026-03-08 00:35:43.759164 | 2026-03-08 00:35:43.759253 | LOOP [fetch-output : Ensure local output dirs] 2026-03-08 00:35:44.214114 | controller -> localhost | ok: "/var/lib/zuul/builds/042b7256614d4bcc92db2741548a107f/work/logs" 2026-03-08 00:35:44.455974 | controller -> localhost | changed: "/var/lib/zuul/builds/042b7256614d4bcc92db2741548a107f/work/artifacts" 2026-03-08 00:35:44.668996 | controller -> localhost | changed: "/var/lib/zuul/builds/042b7256614d4bcc92db2741548a107f/work/docs" 2026-03-08 00:35:44.681526 | 2026-03-08 00:35:44.681620 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-08 00:35:45.410882 | controller | ok 2026-03-08 00:35:45.411216 | controller | changed: All items complete 2026-03-08 00:35:45.411255 | 2026-03-08 00:35:45.870506 | controller | changed: .d..t...... ./ 2026-03-08 00:35:46.334680 | controller | changed: .d..t...... ./ 2026-03-08 00:35:46.358472 | 2026-03-08 00:35:46.358628 | TASK [include_role : fetch-output-openshift] 2026-03-08 00:35:46.384319 | controller | skipping: Conditional result was False 2026-03-08 00:35:46.392577 | 2026-03-08 00:35:46.392699 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-08 00:35:46.450658 | controller | skipping: Conditional result was False 2026-03-08 00:35:46.475134 | controller | skipping: Conditional result was False 2026-03-08 00:35:46.515043 | 2026-03-08 00:35:46.515169 | PLAY [localhost] 2026-03-08 00:35:46.527959 | 2026-03-08 00:35:46.528082 | TASK [Run Zuul manifest role] 2026-03-08 00:35:46.546306 | localhost | ok 2026-03-08 00:35:46.560477 | 2026-03-08 00:35:46.560583 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-08 00:35:46.927685 | localhost | changed 2026-03-08 00:35:46.932472 | 2026-03-08 00:35:46.932548 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-08 00:35:46.962399 | localhost | ok 2026-03-08 00:35:46.971033 | 2026-03-08 00:35:46.971121 | TASK [Set zuul-log-path fact] 2026-03-08 00:35:46.989885 | localhost | ok 2026-03-08 00:35:47.003228 | 2026-03-08 00:35:47.003351 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-08 00:35:47.032912 | localhost | ok 2026-03-08 00:35:47.041469 | 2026-03-08 00:35:47.041562 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)] 2026-03-08 00:35:47.070073 | localhost | ok: 2026-03-08 00:35:47.070300 | localhost | { 2026-03-08 00:35:47.070334 | localhost | "auth": { 2026-03-08 00:35:47.070362 | localhost | "password": "IonWCwcuOFib&&<}wp^:[2G/", 2026-03-08 00:35:47.070385 | localhost | "username": "9LF91O0RK7JU" 2026-03-08 00:35:47.070406 | localhost | }, 2026-03-08 00:35:47.070424 | localhost | "profile": "rackspace", 2026-03-08 00:35:47.070440 | localhost | "region_name": "ORD" 2026-03-08 00:35:47.070457 | localhost | } 2026-03-08 00:35:47.070485 | 2026-03-08 00:35:47.081291 | 2026-03-08 00:35:47.081415 | TASK [Run upload-logs-failover role (S3 then Swift)] 2026-03-08 00:35:47.099895 | localhost | Output suppressed because no_log was given 2026-03-08 00:35:47.113215 | 2026-03-08 00:35:47.113338 | TASK [upload-logs-failover : Initialize control vars] 2026-03-08 00:35:47.132194 | localhost | ok 2026-03-08 00:35:47.136896 | 2026-03-08 00:35:47.136974 | LOOP [upload-logs-failover : Perform log upload] 2026-03-08 00:35:47.164877 | localhost | Output suppressed because no_log was given 2026-03-08 00:35:47.173418 | localhost | included: /var/lib/zuul/builds/042b7256614d4bcc92db2741548a107f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml 2026-03-08 00:35:47.215166 | 2026-03-08 00:35:47.215325 | LOOP [upload-logs-failover : Unpack vars for swift upload] 2026-03-08 00:35:47.301892 | localhost | Output suppressed because no_log was given 2026-03-08 00:35:47.308330 | 2026-03-08 00:35:47.308438 | TASK [Upload logs to swift] 2026-03-08 00:35:47.328038 | localhost | ok 2026-03-08 00:35:47.356003 | 2026-03-08 00:35:47.356132 | TASK [Set zuul-log-path fact] 2026-03-08 00:35:47.390562 | localhost | skipping: Conditional result was False 2026-03-08 00:35:47.398568 | 2026-03-08 00:35:47.398676 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-03-08 00:35:47.802156 | localhost -> localhost | ok: Runtime: 0:00:00.005149 2026-03-08 00:35:47.809126 | 2026-03-08 00:35:47.809267 | TASK [upload-logs-swift : Upload logs to swift]