2026-01-18 00:13:22.378894 | Job console starting... 2026-01-18 00:13:22.392796 | Updating repositories 2026-01-18 00:13:22.552720 | Preparing job workspace 2026-01-18 00:13:26.972867 | Running Ansible setup... 2026-01-18 00:13:32.165719 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-01-18 00:13:32.824191 | 2026-01-18 00:13:32.824530 | PLAY [localhost] 2026-01-18 00:13:32.835382 | 2026-01-18 00:13:32.835501 | TASK [Gathering Facts] 2026-01-18 00:13:33.931397 | localhost | ok 2026-01-18 00:13:33.962549 | 2026-01-18 00:13:33.962835 | TASK [Setup log path fact] 2026-01-18 00:13:33.992824 | localhost | ok 2026-01-18 00:13:34.008563 | 2026-01-18 00:13:34.008873 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-18 00:13:34.040259 | localhost | ok 2026-01-18 00:13:34.051065 | 2026-01-18 00:13:34.051268 | TASK [emit-job-header : Print job information] 2026-01-18 00:13:34.094024 | # Job Information 2026-01-18 00:13:34.094471 | Ansible Version: 2.15.12 2026-01-18 00:13:34.094650 | Job: ansible-test-sanity-docker-devel 2026-01-18 00:13:34.094677 | Pipeline: periodic 2026-01-18 00:13:34.094697 | Executor: ze03.softwarefactory-project.io 2026-01-18 00:13:34.094716 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-01-18 00:13:34.094786 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/701/ansible/7015228b14744801972519e2e590c2ef/ 2026-01-18 00:13:34.094809 | Event ID: 9ecfb4d48ef5425f8ac323d52d7bd141 2026-01-18 00:13:34.099704 | 2026-01-18 00:13:34.099815 | LOOP [emit-job-header : Print node information] 2026-01-18 00:13:34.246037 | localhost | ok: 2026-01-18 00:13:34.246294 | localhost | # Node Information 2026-01-18 00:13:34.246326 | localhost | Inventory Hostname: controller 2026-01-18 00:13:34.246348 | localhost | Hostname: np0005586991 2026-01-18 00:13:34.246369 | localhost | Username: zuul 2026-01-18 00:13:34.246393 | localhost | Distro: Fedora 37 2026-01-18 00:13:34.246413 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-01-18 00:13:34.246433 | localhost | Region: ca-ymq-1 2026-01-18 00:13:34.246452 | localhost | Label: ansible-fedora-37-1vcpu 2026-01-18 00:13:34.246472 | localhost | Product Name: OpenStack Nova 2026-01-18 00:13:34.246493 | localhost | Interface IP: 162.253.55.49 2026-01-18 00:13:34.267195 | 2026-01-18 00:13:34.267356 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-01-18 00:13:34.752085 | localhost -> localhost | changed 2026-01-18 00:13:34.759478 | 2026-01-18 00:13:34.759808 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-01-18 00:13:35.724582 | localhost -> localhost | changed 2026-01-18 00:13:35.751098 | 2026-01-18 00:13:35.751213 | PLAY [all:!appliance*] 2026-01-18 00:13:35.775548 | 2026-01-18 00:13:35.775701 | TASK [include_role : start-zuul-console] 2026-01-18 00:13:35.798776 | controller | ok 2026-01-18 00:13:35.815182 | 2026-01-18 00:13:35.815300 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-01-18 00:13:36.204782 | controller | ok 2026-01-18 00:13:36.222499 | 2026-01-18 00:13:36.222641 | TASK [use-our-mirror : Retrieve the IP address] 2026-01-18 00:13:37.164128 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-01-18 00:13:37.171398 | 2026-01-18 00:13:37.171499 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-01-18 00:13:37.720933 | controller | skipping: Conditional result was False 2026-01-18 00:13:37.733494 | 2026-01-18 00:13:37.733624 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-01-18 00:13:37.759963 | controller | skipping: Conditional result was False 2026-01-18 00:13:37.775187 | 2026-01-18 00:13:37.775408 | TASK [use-our-mirror : Create the podman configuration directory] 2026-01-18 00:13:37.800386 | controller | skipping: Conditional result was False 2026-01-18 00:13:37.807408 | 2026-01-18 00:13:37.807501 | TASK [use-our-mirror : Copy the podman configuration] 2026-01-18 00:13:37.831592 | controller | skipping: Conditional result was False 2026-01-18 00:13:37.837844 | 2026-01-18 00:13:37.837924 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-01-18 00:13:37.861715 | controller | skipping: Conditional result was False 2026-01-18 00:13:37.873199 | 2026-01-18 00:13:37.873347 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-01-18 00:13:37.898791 | controller | skipping: Conditional result was False 2026-01-18 00:13:37.913789 | 2026-01-18 00:13:37.913938 | TASK [Disable Fedora Modular] 2026-01-18 00:13:38.161362 | controller | changed 2026-01-18 00:13:38.171799 | 2026-01-18 00:13:38.171915 | TASK [Enable EPEL] 2026-01-18 00:13:38.197304 | controller | skipping: Conditional result was False 2026-01-18 00:13:38.205112 | 2026-01-18 00:13:38.205198 | TASK [Register the RHEL node] 2026-01-18 00:13:38.750287 | 2026-01-18 00:13:38.750473 | TASK [Show the subscription-manager status] 2026-01-18 00:13:39.325059 | controller | skipping: Conditional result was False 2026-01-18 00:13:39.333996 | 2026-01-18 00:13:39.334111 | TASK [Enable EPEL on RHEL] 2026-01-18 00:13:39.878391 | controller | skipping: Conditional result was False 2026-01-18 00:13:39.885398 | 2026-01-18 00:13:39.885482 | TASK [Install git and tox] 2026-01-18 00:14:57.457486 | controller | changed 2026-01-18 00:14:57.464037 | 2026-01-18 00:14:57.464105 | TASK [include_role : prepare-workspace] 2026-01-18 00:14:57.495445 | controller | ok 2026-01-18 00:14:57.518325 | 2026-01-18 00:14:57.518426 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-01-18 00:14:57.775512 | controller | ok 2026-01-18 00:14:57.788990 | 2026-01-18 00:14:57.789134 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-01-18 00:15:11.671967 | controller | Output suppressed because no_log was given 2026-01-18 00:15:11.731002 | 2026-01-18 00:15:11.731133 | TASK [include_role : prepare-workspace-openshift] 2026-01-18 00:15:11.767262 | controller | skipping: Conditional result was False 2026-01-18 00:15:11.801973 | 2026-01-18 00:15:11.802081 | PLAY [all:!appliance] 2026-01-18 00:15:11.826815 | 2026-01-18 00:15:11.826959 | TASK [Run add-build-sshkey role (RSA)] 2026-01-18 00:15:11.870115 | controller | ok 2026-01-18 00:15:11.891550 | 2026-01-18 00:15:11.892049 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-18 00:15:12.193297 | controller -> localhost | ok 2026-01-18 00:15:12.202518 | 2026-01-18 00:15:12.202645 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-18 00:15:12.236934 | controller | ok 2026-01-18 00:15:12.257451 | controller | included: /var/lib/zuul/builds/7015228b14744801972519e2e590c2ef/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-18 00:15:12.269918 | 2026-01-18 00:15:12.270054 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-18 00:15:12.901839 | controller -> localhost | Generating public/private rsa key pair. 2026-01-18 00:15:12.902135 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/7015228b14744801972519e2e590c2ef/work/7015228b14744801972519e2e590c2ef_id_rsa. 2026-01-18 00:15:12.902175 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/7015228b14744801972519e2e590c2ef/work/7015228b14744801972519e2e590c2ef_id_rsa.pub. 2026-01-18 00:15:12.902204 | controller -> localhost | The key fingerprint is: 2026-01-18 00:15:12.902231 | controller -> localhost | SHA256:WbC5dDH7dQaI1x5cMT2GYgDloWV+vPgQirQwkbRzucg zuul-build-sshkey 2026-01-18 00:15:12.902269 | controller -> localhost | The key's randomart image is: 2026-01-18 00:15:12.902295 | controller -> localhost | +---[RSA 2048]----+ 2026-01-18 00:15:12.902320 | controller -> localhost | | .o. .+*+. +o++| 2026-01-18 00:15:12.902347 | controller -> localhost | | .o . *++*o.++o| 2026-01-18 00:15:12.902372 | controller -> localhost | | = + .++=+..o.+| 2026-01-18 00:15:12.902397 | controller -> localhost | | . B +..=+....o | 2026-01-18 00:15:12.902422 | controller -> localhost | | E + .So .. | 2026-01-18 00:15:12.902458 | controller -> localhost | | o | 2026-01-18 00:15:12.902491 | controller -> localhost | | . | 2026-01-18 00:15:12.902520 | controller -> localhost | | | 2026-01-18 00:15:12.902545 | controller -> localhost | | | 2026-01-18 00:15:12.902573 | controller -> localhost | +----[SHA256]-----+ 2026-01-18 00:15:12.902634 | controller -> localhost | ok: Runtime: 0:00:00.080771 2026-01-18 00:15:12.913353 | 2026-01-18 00:15:12.913506 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-18 00:15:12.956504 | controller | ok 2026-01-18 00:15:12.969572 | controller | included: /var/lib/zuul/builds/7015228b14744801972519e2e590c2ef/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-18 00:15:12.981507 | 2026-01-18 00:15:12.981654 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-18 00:15:13.007208 | controller | skipping: Conditional result was False 2026-01-18 00:15:13.014099 | 2026-01-18 00:15:13.014170 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-18 00:15:13.501486 | controller | changed 2026-01-18 00:15:13.509048 | 2026-01-18 00:15:13.509128 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-18 00:15:13.715416 | controller | ok 2026-01-18 00:15:13.725341 | 2026-01-18 00:15:13.725474 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-18 00:15:14.331758 | controller | changed 2026-01-18 00:15:14.339490 | 2026-01-18 00:15:14.339589 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-18 00:15:14.948470 | controller | changed 2026-01-18 00:15:14.955380 | 2026-01-18 00:15:14.955475 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-18 00:15:14.980292 | controller | skipping: Conditional result was False 2026-01-18 00:15:14.989901 | 2026-01-18 00:15:14.990011 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-18 00:15:15.518261 | controller -> localhost | changed 2026-01-18 00:15:15.537250 | 2026-01-18 00:15:15.537395 | TASK [add-build-sshkey : Add back temp key] 2026-01-18 00:15:15.931373 | controller -> localhost | Identity added: /var/lib/zuul/builds/7015228b14744801972519e2e590c2ef/work/7015228b14744801972519e2e590c2ef_id_rsa (zuul-build-sshkey) 2026-01-18 00:15:15.931598 | controller -> localhost | ok: Runtime: 0:00:00.017910 2026-01-18 00:15:15.938358 | 2026-01-18 00:15:15.938495 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-18 00:15:16.350389 | controller | ok 2026-01-18 00:15:16.361591 | 2026-01-18 00:15:16.361772 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-18 00:15:16.402839 | controller | skipping: Conditional result was False 2026-01-18 00:15:16.416293 | 2026-01-18 00:15:16.416452 | TASK [Run add-build-sshkey role (ECDSA)] 2026-01-18 00:15:16.446621 | controller | ok 2026-01-18 00:15:16.464182 | 2026-01-18 00:15:16.464308 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-18 00:15:16.714992 | controller -> localhost | ok 2026-01-18 00:15:16.722751 | 2026-01-18 00:15:16.722835 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-18 00:15:16.756263 | controller | ok 2026-01-18 00:15:16.774286 | controller | included: /var/lib/zuul/builds/7015228b14744801972519e2e590c2ef/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-18 00:15:16.782819 | 2026-01-18 00:15:16.782931 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-18 00:15:17.092869 | controller -> localhost | Generating public/private ecdsa key pair. 2026-01-18 00:15:17.093106 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/7015228b14744801972519e2e590c2ef/work/7015228b14744801972519e2e590c2ef_id_ecdsa. 2026-01-18 00:15:17.093137 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/7015228b14744801972519e2e590c2ef/work/7015228b14744801972519e2e590c2ef_id_ecdsa.pub. 2026-01-18 00:15:17.093182 | controller -> localhost | The key fingerprint is: 2026-01-18 00:15:17.093206 | controller -> localhost | SHA256:RfFlou2GLNcbR5g1tuo2yJkuHxh+9nRlMKU7QZj0qCM zuul-build-sshkey 2026-01-18 00:15:17.093227 | controller -> localhost | The key's randomart image is: 2026-01-18 00:15:17.093247 | controller -> localhost | +---[ECDSA 521]---+ 2026-01-18 00:15:17.093265 | controller -> localhost | | ooooB .| 2026-01-18 00:15:17.093284 | controller -> localhost | | . +o% = | 2026-01-18 00:15:17.093312 | controller -> localhost | | o * O | 2026-01-18 00:15:17.093332 | controller -> localhost | | o = o = | 2026-01-18 00:15:17.093350 | controller -> localhost | | E * * + o| 2026-01-18 00:15:17.093367 | controller -> localhost | | . B B + + | 2026-01-18 00:15:17.093385 | controller -> localhost | | o O * . | 2026-01-18 00:15:17.093403 | controller -> localhost | | .+ = o | 2026-01-18 00:15:17.093420 | controller -> localhost | | oo . | 2026-01-18 00:15:17.093438 | controller -> localhost | +----[SHA256]-----+ 2026-01-18 00:15:17.093500 | controller -> localhost | ok: Runtime: 0:00:00.015254 2026-01-18 00:15:17.101517 | 2026-01-18 00:15:17.101635 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-18 00:15:17.132154 | controller | ok 2026-01-18 00:15:17.140353 | controller | included: /var/lib/zuul/builds/7015228b14744801972519e2e590c2ef/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-18 00:15:17.150910 | 2026-01-18 00:15:17.151024 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-18 00:15:17.185678 | controller | skipping: Conditional result was False 2026-01-18 00:15:17.192716 | 2026-01-18 00:15:17.192815 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-18 00:15:17.477853 | controller | changed 2026-01-18 00:15:17.486052 | 2026-01-18 00:15:17.486131 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-18 00:15:17.732932 | controller | ok 2026-01-18 00:15:17.744300 | 2026-01-18 00:15:17.744385 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-18 00:15:18.373411 | controller | changed 2026-01-18 00:15:18.383577 | 2026-01-18 00:15:18.383857 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-18 00:15:19.028541 | controller | changed 2026-01-18 00:15:19.037927 | 2026-01-18 00:15:19.038063 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-18 00:15:19.085256 | controller | skipping: Conditional result was False 2026-01-18 00:15:19.164964 | 2026-01-18 00:15:19.165091 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-18 00:15:19.496368 | controller -> localhost | changed 2026-01-18 00:15:19.517792 | 2026-01-18 00:15:19.518277 | TASK [add-build-sshkey : Add back temp key] 2026-01-18 00:15:20.023022 | controller -> localhost | Identity added: /var/lib/zuul/builds/7015228b14744801972519e2e590c2ef/work/7015228b14744801972519e2e590c2ef_id_ecdsa (zuul-build-sshkey) 2026-01-18 00:15:20.023291 | controller -> localhost | ok: Runtime: 0:00:00.029948 2026-01-18 00:15:20.030777 | 2026-01-18 00:15:20.030875 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-18 00:15:20.249984 | controller | ok 2026-01-18 00:15:20.268250 | 2026-01-18 00:15:20.268427 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-18 00:15:20.330527 | controller | skipping: Conditional result was False 2026-01-18 00:15:20.352892 | 2026-01-18 00:15:20.353126 | TASK [include_role : remove-zuul-sshkey] 2026-01-18 00:15:20.383001 | controller | skipping: Conditional result was False 2026-01-18 00:15:20.393789 | 2026-01-18 00:15:20.393984 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-01-18 00:15:20.632647 | controller | ok: "logs" 2026-01-18 00:15:20.632971 | controller | ok: All items complete 2026-01-18 00:15:20.633005 | 2026-01-18 00:15:20.826379 | controller | ok: "artifacts" 2026-01-18 00:15:21.016227 | controller | ok: "docs" 2026-01-18 00:15:21.025939 | 2026-01-18 00:15:21.026089 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-01-18 00:15:21.294438 | controller | changed: "logs" 2026-01-18 00:15:21.489745 | controller | changed: "artifacts" 2026-01-18 00:15:21.684914 | controller | changed: "docs" 2026-01-18 00:15:21.751833 | 2026-01-18 00:15:21.752065 | PLAY RECAP 2026-01-18 00:15:21.752121 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-01-18 00:15:21.752495 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-18 00:15:21.752531 | 2026-01-18 00:15:21.962417 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-01-18 00:15:21.963349 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-01-18 00:15:22.619067 | 2026-01-18 00:15:22.619239 | PLAY [all] 2026-01-18 00:15:22.649303 | 2026-01-18 00:15:22.649446 | TASK [Install binary dependencies] 2026-01-18 00:15:22.711865 | controller | ok 2026-01-18 00:15:22.733836 | 2026-01-18 00:15:22.734087 | TASK [bindep : Include find tasks] 2026-01-18 00:15:22.764260 | controller | ok 2026-01-18 00:15:22.773571 | controller | included: /var/lib/zuul/builds/7015228b14744801972519e2e590c2ef/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-01-18 00:15:22.780393 | 2026-01-18 00:15:22.780473 | TASK [bindep : Look for bindep.txt] 2026-01-18 00:15:23.140984 | controller | ok 2026-01-18 00:15:23.148044 | 2026-01-18 00:15:23.148157 | TASK [bindep : Define bindep_file fact] 2026-01-18 00:15:23.173023 | controller | skipping: Conditional result was False 2026-01-18 00:15:23.179886 | 2026-01-18 00:15:23.179961 | TASK [bindep : Look for other-requirements.txt] 2026-01-18 00:15:23.394893 | controller | ok 2026-01-18 00:15:23.410588 | 2026-01-18 00:15:23.410813 | TASK [bindep : Define bindep_file fact] 2026-01-18 00:15:23.447791 | controller | skipping: Conditional result was False 2026-01-18 00:15:23.457671 | 2026-01-18 00:15:23.457840 | TASK [bindep : Look for bindep fallback file] 2026-01-18 00:15:23.552665 | controller | skipping: Conditional result was False 2026-01-18 00:15:23.559821 | 2026-01-18 00:15:23.559922 | TASK [bindep : Define bindep_file fact] 2026-01-18 00:15:23.603921 | controller | skipping: Conditional result was False 2026-01-18 00:15:23.610291 | 2026-01-18 00:15:23.610364 | TASK [bindep : Include bindep tasks] 2026-01-18 00:15:23.634269 | controller | skipping: Conditional result was False 2026-01-18 00:15:23.640341 | 2026-01-18 00:15:23.640415 | TASK [bindep : Include install tasks] 2026-01-18 00:15:23.664132 | controller | skipping: Conditional result was False 2026-01-18 00:15:23.670542 | 2026-01-18 00:15:23.670612 | LOOP [bindep : Include package tasks] 2026-01-18 00:15:23.725923 | 2026-01-18 00:15:23.726286 | TASK [Run test-setup role] 2026-01-18 00:15:23.747257 | controller | ok 2026-01-18 00:15:23.767148 | 2026-01-18 00:15:23.767262 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-01-18 00:15:23.981693 | controller | ok 2026-01-18 00:15:23.991800 | 2026-01-18 00:15:23.991958 | TASK [test-setup : Run tools/test-setup.sh] 2026-01-18 00:15:54.044922 | [Zuul] Log Stream did not terminate 2026-01-18 00:15:54.045340 | controller | skipping: Conditional result was False 2026-01-18 00:15:54.101355 | 2026-01-18 00:15:54.101487 | PLAY RECAP 2026-01-18 00:15:54.101554 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-01-18 00:15:54.101588 | 2026-01-18 00:15:54.218185 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-01-18 00:15:54.219114 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-01-18 00:15:55.008654 | 2026-01-18 00:15:55.008868 | PLAY [controller] 2026-01-18 00:15:55.039102 | 2026-01-18 00:15:55.039303 | TASK [Create the /root directory] 2026-01-18 00:18:07.349965 | controller | ERROR 2026-01-18 00:18:07.350292 | controller | { 2026-01-18 00:18:07.350348 | controller | "msg": "Timeout (32s) waiting for privilege escalation prompt: " 2026-01-18 00:18:07.350393 | controller | } failure 2026-01-18 00:18:07.352478 | 2026-01-18 00:18:07.352557 | PLAY RECAP 2026-01-18 00:18:07.352620 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-01-18 00:18:07.352656 | 2026-01-18 00:18:07.556231 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-01-18 00:18:07.558773 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-01-18 00:18:08.256501 | 2026-01-18 00:18:08.256669 | PLAY [all] 2026-01-18 00:18:08.281341 | 2026-01-18 00:18:08.281518 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-01-18 00:20:35.110079 | controller | changed: non-zero return code 2026-01-18 00:20:35.117348 | 2026-01-18 00:20:35.117465 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-01-18 00:20:35.141707 | controller | skipping: Conditional result was False 2026-01-18 00:20:35.148946 | 2026-01-18 00:20:35.149048 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-01-18 00:20:35.175807 | 2026-01-18 00:20:35.176010 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-01-18 00:20:35.210697 | 2026-01-18 00:20:35.210936 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-01-18 00:20:35.237519 | controller | skipping: Conditional result was False 2026-01-18 00:20:35.244101 | 2026-01-18 00:20:35.244192 | LOOP [fetch-subunit-output : Generate subunit file] 2026-01-18 00:20:35.278611 | 2026-01-18 00:20:35.278838 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-01-18 00:20:35.297166 | controller | skipping: Conditional result was False 2026-01-18 00:20:35.308290 | 2026-01-18 00:20:35.308445 | TASK [fetch-subunit-output : Remove the temporary file] 2026-01-18 00:20:35.323655 | controller | skipping: Conditional result was False 2026-01-18 00:20:35.334426 | 2026-01-18 00:20:35.334565 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-01-18 00:20:35.361448 | controller | skipping: Conditional result was False 2026-01-18 00:20:35.404585 | 2026-01-18 00:20:35.404717 | PLAY RECAP 2026-01-18 00:20:35.404812 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-01-18 00:20:35.404838 | 2026-01-18 00:20:35.540957 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-01-18 00:20:35.541898 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-01-18 00:20:36.117120 | 2026-01-18 00:20:36.117255 | PLAY [all:!appliance*] 2026-01-18 00:20:36.140414 | 2026-01-18 00:20:36.140538 | TASK [unregister the node] 2026-01-18 00:20:36.682329 | controller | skipping: Conditional result was False 2026-01-18 00:20:36.695859 | 2026-01-18 00:20:36.696110 | TASK [include_role : fetch-output] 2026-01-18 00:20:36.756019 | controller | ok 2026-01-18 00:20:36.806480 | 2026-01-18 00:20:36.806626 | TASK [fetch-output : Set log path for multiple nodes] 2026-01-18 00:20:36.863231 | controller | skipping: Conditional result was False 2026-01-18 00:20:36.876615 | 2026-01-18 00:20:36.876837 | TASK [fetch-output : Set log path for single node] 2026-01-18 00:20:36.916882 | controller | ok 2026-01-18 00:20:36.929489 | 2026-01-18 00:20:36.929690 | LOOP [fetch-output : Ensure local output dirs] 2026-01-18 00:20:37.345981 | controller -> localhost | ok: "/var/lib/zuul/builds/7015228b14744801972519e2e590c2ef/work/logs" 2026-01-18 00:20:37.607224 | controller -> localhost | changed: "/var/lib/zuul/builds/7015228b14744801972519e2e590c2ef/work/artifacts" 2026-01-18 00:20:37.911592 | controller -> localhost | changed: "/var/lib/zuul/builds/7015228b14744801972519e2e590c2ef/work/docs" 2026-01-18 00:20:37.933902 | 2026-01-18 00:20:37.934047 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-01-18 00:20:38.736420 | controller | ok 2026-01-18 00:20:38.737337 | controller | changed: All items complete 2026-01-18 00:20:38.737422 | 2026-01-18 00:20:39.228929 | controller | changed: .d..t...... ./ 2026-01-18 00:20:39.745051 | controller | changed: .d..t...... ./ 2026-01-18 00:20:39.782963 | 2026-01-18 00:20:39.783129 | TASK [include_role : fetch-output-openshift] 2026-01-18 00:20:39.801266 | controller | skipping: Conditional result was False 2026-01-18 00:20:39.813860 | 2026-01-18 00:20:39.813986 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-01-18 00:20:39.840579 | controller | skipping: Conditional result was False 2026-01-18 00:20:39.850197 | controller | skipping: Conditional result was False 2026-01-18 00:20:39.889917 | 2026-01-18 00:20:39.890020 | PLAY [localhost] 2026-01-18 00:20:39.903446 | 2026-01-18 00:20:39.903566 | TASK [Run Zuul manifest role] 2026-01-18 00:20:39.923446 | localhost | ok 2026-01-18 00:20:39.940287 | 2026-01-18 00:20:39.940404 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-01-18 00:20:40.368062 | localhost | changed 2026-01-18 00:20:40.373602 | 2026-01-18 00:20:40.373751 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-01-18 00:20:40.406458 | localhost | ok 2026-01-18 00:20:40.417298 | 2026-01-18 00:20:40.417431 | TASK [Set zuul-log-path fact] 2026-01-18 00:20:40.440812 | localhost | ok 2026-01-18 00:20:40.463266 | 2026-01-18 00:20:40.463400 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-18 00:20:40.495349 | localhost | ok 2026-01-18 00:20:40.507774 | 2026-01-18 00:20:40.507917 | LOOP [Run upload-logs-swift role] 2026-01-18 00:20:40.546345 | localhost | Output suppressed because no_log was given 2026-01-18 00:20:40.586588 | 2026-01-18 00:20:40.586754 | TASK [Set zuul-log-path fact] 2026-01-18 00:20:40.613460 | localhost | skipping: Conditional result was False 2026-01-18 00:20:40.619538 | 2026-01-18 00:20:40.619673 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-01-18 00:20:41.065965 | localhost -> localhost | ok: Runtime: 0:00:00.007097 2026-01-18 00:20:41.118039 | 2026-01-18 00:20:41.118234 | TASK [upload-logs-swift : Upload logs to swift]