2026-03-05 00:17:14.778282 | Job console starting... 2026-03-05 00:17:14.791886 | Updating repositories 2026-03-05 00:17:14.967551 | Preparing job workspace 2026-03-05 00:17:19.114892 | Running Ansible setup... 2026-03-05 00:17:23.545018 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-05 00:17:24.227692 | 2026-03-05 00:17:24.227855 | PLAY [localhost] 2026-03-05 00:17:24.237075 | 2026-03-05 00:17:24.237149 | TASK [Gathering Facts] 2026-03-05 00:17:25.296994 | localhost | ok 2026-03-05 00:17:25.329445 | 2026-03-05 00:17:25.329765 | TASK [Setup log path fact] 2026-03-05 00:17:25.352633 | localhost | ok 2026-03-05 00:17:25.370393 | 2026-03-05 00:17:25.370492 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-05 00:17:25.411713 | localhost | ok 2026-03-05 00:17:25.422994 | 2026-03-05 00:17:25.423092 | TASK [emit-job-header : Print job information] 2026-03-05 00:17:25.474948 | # Job Information 2026-03-05 00:17:25.475136 | Ansible Version: 2.15.12 2026-03-05 00:17:25.475185 | Job: ansible-test-sanity-docker-devel 2026-03-05 00:17:25.475215 | Pipeline: periodic 2026-03-05 00:17:25.475241 | Executor: ze04.softwarefactory-project.io 2026-03-05 00:17:25.475267 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-03-05 00:17:25.475295 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/5be/ansible/5bec561e24574eaeaa58f0d278dc13c2/ 2026-03-05 00:17:25.475322 | Event ID: 4fa9857b728648278118233f7d4d0858 2026-03-05 00:17:25.481017 | 2026-03-05 00:17:25.481104 | LOOP [emit-job-header : Print node information] 2026-03-05 00:17:25.619319 | localhost | ok: 2026-03-05 00:17:25.619795 | localhost | # Node Information 2026-03-05 00:17:25.619895 | localhost | Inventory Hostname: controller 2026-03-05 00:17:25.619979 | localhost | Hostname: np0005638184 2026-03-05 00:17:25.620112 | localhost | Username: zuul 2026-03-05 00:17:25.620200 | localhost | Distro: Fedora 37 2026-03-05 00:17:25.620279 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-03-05 00:17:25.620354 | localhost | Region: ca-ymq-1 2026-03-05 00:17:25.620431 | localhost | Label: ansible-fedora-37-1vcpu 2026-03-05 00:17:25.620506 | localhost | Product Name: OpenStack Nova 2026-03-05 00:17:25.620578 | localhost | Interface IP: 162.253.55.204 2026-03-05 00:17:25.651261 | 2026-03-05 00:17:25.651439 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-05 00:17:26.109900 | localhost -> localhost | changed 2026-03-05 00:17:26.117947 | 2026-03-05 00:17:26.118045 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-05 00:17:27.052931 | localhost -> localhost | changed 2026-03-05 00:17:27.077745 | 2026-03-05 00:17:27.077874 | PLAY [all:!appliance*] 2026-03-05 00:17:27.096271 | 2026-03-05 00:17:27.096392 | TASK [include_role : start-zuul-console] 2026-03-05 00:17:27.116753 | controller | ok 2026-03-05 00:17:27.132270 | 2026-03-05 00:17:27.132370 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-03-05 00:17:27.530670 | controller | ok 2026-03-05 00:17:27.542823 | 2026-03-05 00:17:27.542906 | TASK [use-our-mirror : Retrieve the IP address] 2026-03-05 00:17:28.521456 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-03-05 00:17:28.531896 | 2026-03-05 00:17:28.532020 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-03-05 00:17:29.085847 | controller | skipping: Conditional result was False 2026-03-05 00:17:29.100574 | 2026-03-05 00:17:29.100771 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-03-05 00:17:29.128098 | controller | skipping: Conditional result was False 2026-03-05 00:17:29.145673 | 2026-03-05 00:17:29.145936 | TASK [use-our-mirror : Create the podman configuration directory] 2026-03-05 00:17:29.173074 | controller | skipping: Conditional result was False 2026-03-05 00:17:29.189172 | 2026-03-05 00:17:29.189338 | TASK [use-our-mirror : Copy the podman configuration] 2026-03-05 00:17:29.207836 | controller | skipping: Conditional result was False 2026-03-05 00:17:29.214992 | 2026-03-05 00:17:29.215081 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-03-05 00:17:29.230392 | controller | skipping: Conditional result was False 2026-03-05 00:17:29.237797 | 2026-03-05 00:17:29.237898 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-03-05 00:17:29.261513 | controller | skipping: Conditional result was False 2026-03-05 00:17:29.276137 | 2026-03-05 00:17:29.276239 | TASK [Disable Fedora Modular] 2026-03-05 00:17:29.532273 | controller | changed 2026-03-05 00:17:29.549483 | 2026-03-05 00:17:29.549665 | TASK [Enable EPEL] 2026-03-05 00:17:29.569193 | controller | skipping: Conditional result was False 2026-03-05 00:17:29.585456 | 2026-03-05 00:17:29.585605 | TASK [Register the RHEL node] 2026-03-05 00:17:30.144639 | 2026-03-05 00:17:30.144866 | TASK [Show the subscription-manager status] 2026-03-05 00:17:30.726864 | controller | skipping: Conditional result was False 2026-03-05 00:17:30.733982 | 2026-03-05 00:17:30.734061 | TASK [Enable EPEL on RHEL] 2026-03-05 00:17:31.268630 | controller | skipping: Conditional result was False 2026-03-05 00:17:31.283916 | 2026-03-05 00:17:31.284108 | TASK [Install git and tox] 2026-03-05 00:18:49.439025 | controller | changed 2026-03-05 00:18:49.455390 | 2026-03-05 00:18:49.455732 | TASK [include_role : prepare-workspace] 2026-03-05 00:18:49.500533 | controller | ok 2026-03-05 00:18:49.550182 | 2026-03-05 00:18:49.550439 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-05 00:18:49.790236 | controller | ok 2026-03-05 00:18:49.799550 | 2026-03-05 00:18:49.799627 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-05 00:19:02.523591 | controller | Output suppressed because no_log was given 2026-03-05 00:19:02.535373 | 2026-03-05 00:19:02.535446 | TASK [include_role : prepare-workspace-openshift] 2026-03-05 00:19:02.560914 | controller | skipping: Conditional result was False 2026-03-05 00:19:02.638609 | 2026-03-05 00:19:02.638779 | PLAY [all:!appliance] 2026-03-05 00:19:02.656163 | 2026-03-05 00:19:02.656227 | TASK [Run add-build-sshkey role (RSA)] 2026-03-05 00:19:02.689591 | controller | ok 2026-03-05 00:19:02.706405 | 2026-03-05 00:19:02.706511 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-05 00:19:02.977632 | controller -> localhost | ok 2026-03-05 00:19:02.986299 | 2026-03-05 00:19:02.986385 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-05 00:19:03.031112 | controller | ok 2026-03-05 00:19:03.048520 | controller | included: /var/lib/zuul/builds/5bec561e24574eaeaa58f0d278dc13c2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-05 00:19:03.057038 | 2026-03-05 00:19:03.057114 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-05 00:19:03.593598 | controller -> localhost | Generating public/private rsa key pair. 2026-03-05 00:19:03.593938 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/5bec561e24574eaeaa58f0d278dc13c2/work/5bec561e24574eaeaa58f0d278dc13c2_id_rsa. 2026-03-05 00:19:03.593981 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/5bec561e24574eaeaa58f0d278dc13c2/work/5bec561e24574eaeaa58f0d278dc13c2_id_rsa.pub. 2026-03-05 00:19:03.594012 | controller -> localhost | The key fingerprint is: 2026-03-05 00:19:03.594042 | controller -> localhost | SHA256:vs0ST4RcKFMSGIIL8XqNG4EazynujueC+3BMicGqTaY zuul-build-sshkey 2026-03-05 00:19:03.594071 | controller -> localhost | The key's randomart image is: 2026-03-05 00:19:03.594098 | controller -> localhost | +---[RSA 2048]----+ 2026-03-05 00:19:03.594126 | controller -> localhost | |.o. .ooo.. | 2026-03-05 00:19:03.594152 | controller -> localhost | |+o .. o.. . | 2026-03-05 00:19:03.594179 | controller -> localhost | |=oo + o | 2026-03-05 00:19:03.594206 | controller -> localhost | |+B * o . | 2026-03-05 00:19:03.594233 | controller -> localhost | |* % . S. | 2026-03-05 00:19:03.594273 | controller -> localhost | |oX o .. . | 2026-03-05 00:19:03.594308 | controller -> localhost | |E.= .+ | 2026-03-05 00:19:03.594338 | controller -> localhost | |=o. .+. | 2026-03-05 00:19:03.594365 | controller -> localhost | |+Oo ..o | 2026-03-05 00:19:03.594395 | controller -> localhost | +----[SHA256]-----+ 2026-03-05 00:19:03.594465 | controller -> localhost | ok: Runtime: 0:00:00.081139 2026-03-05 00:19:03.604171 | 2026-03-05 00:19:03.604276 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-05 00:19:03.642773 | controller | ok 2026-03-05 00:19:03.656985 | controller | included: /var/lib/zuul/builds/5bec561e24574eaeaa58f0d278dc13c2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-05 00:19:03.668573 | 2026-03-05 00:19:03.668722 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-05 00:19:03.695109 | controller | skipping: Conditional result was False 2026-03-05 00:19:03.703575 | 2026-03-05 00:19:03.703700 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-05 00:19:04.196602 | controller | changed 2026-03-05 00:19:04.206032 | 2026-03-05 00:19:04.206132 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-05 00:19:04.451039 | controller | ok 2026-03-05 00:19:04.460612 | 2026-03-05 00:19:04.460722 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-05 00:19:05.080517 | controller | changed 2026-03-05 00:19:05.098390 | 2026-03-05 00:19:05.098669 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-05 00:19:05.716813 | controller | changed 2026-03-05 00:19:05.726674 | 2026-03-05 00:19:05.726808 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-05 00:19:05.761492 | controller | skipping: Conditional result was False 2026-03-05 00:19:05.771650 | 2026-03-05 00:19:05.771784 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-05 00:19:06.224668 | controller -> localhost | changed 2026-03-05 00:19:06.237796 | 2026-03-05 00:19:06.237924 | TASK [add-build-sshkey : Add back temp key] 2026-03-05 00:19:06.517812 | controller -> localhost | Identity added: /var/lib/zuul/builds/5bec561e24574eaeaa58f0d278dc13c2/work/5bec561e24574eaeaa58f0d278dc13c2_id_rsa (zuul-build-sshkey) 2026-03-05 00:19:06.518101 | controller -> localhost | ok: Runtime: 0:00:00.014300 2026-03-05 00:19:06.525027 | 2026-03-05 00:19:06.525094 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-05 00:19:06.900615 | controller | ok 2026-03-05 00:19:06.912743 | 2026-03-05 00:19:06.912901 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-05 00:19:06.951642 | controller | skipping: Conditional result was False 2026-03-05 00:19:06.970070 | 2026-03-05 00:19:06.970217 | TASK [Run add-build-sshkey role (ECDSA)] 2026-03-05 00:19:06.995937 | controller | ok 2026-03-05 00:19:07.021192 | 2026-03-05 00:19:07.021328 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-05 00:19:07.260524 | controller -> localhost | ok 2026-03-05 00:19:07.270963 | 2026-03-05 00:19:07.271081 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-05 00:19:07.310373 | controller | ok 2026-03-05 00:19:07.335996 | controller | included: /var/lib/zuul/builds/5bec561e24574eaeaa58f0d278dc13c2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-05 00:19:07.349053 | 2026-03-05 00:19:07.349196 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-05 00:19:07.683204 | controller -> localhost | Generating public/private ecdsa key pair. 2026-03-05 00:19:07.683421 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/5bec561e24574eaeaa58f0d278dc13c2/work/5bec561e24574eaeaa58f0d278dc13c2_id_ecdsa. 2026-03-05 00:19:07.683462 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/5bec561e24574eaeaa58f0d278dc13c2/work/5bec561e24574eaeaa58f0d278dc13c2_id_ecdsa.pub. 2026-03-05 00:19:07.683502 | controller -> localhost | The key fingerprint is: 2026-03-05 00:19:07.683534 | controller -> localhost | SHA256:RZrE1mu+bsQlQaygTxf/oVT+pvx0+pOqCFTsGhW47RU zuul-build-sshkey 2026-03-05 00:19:07.683564 | controller -> localhost | The key's randomart image is: 2026-03-05 00:19:07.683591 | controller -> localhost | +---[ECDSA 521]---+ 2026-03-05 00:19:07.683617 | controller -> localhost | | .o=o | 2026-03-05 00:19:07.683644 | controller -> localhost | | .o=+=E. | 2026-03-05 00:19:07.683670 | controller -> localhost | | . o=B.=. | 2026-03-05 00:19:07.683754 | controller -> localhost | | . ..*o*.+ | 2026-03-05 00:19:07.683783 | controller -> localhost | | o +S*.= o | 2026-03-05 00:19:07.683809 | controller -> localhost | | o o.= . o | 2026-03-05 00:19:07.683835 | controller -> localhost | | o . o o. o| 2026-03-05 00:19:07.683859 | controller -> localhost | | . + o. = | 2026-03-05 00:19:07.683885 | controller -> localhost | | +...o+.o| 2026-03-05 00:19:07.683910 | controller -> localhost | +----[SHA256]-----+ 2026-03-05 00:19:07.683971 | controller -> localhost | ok: Runtime: 0:00:00.014048 2026-03-05 00:19:07.695500 | 2026-03-05 00:19:07.695619 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-05 00:19:07.735848 | controller | ok 2026-03-05 00:19:07.752322 | controller | included: /var/lib/zuul/builds/5bec561e24574eaeaa58f0d278dc13c2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-05 00:19:07.765368 | 2026-03-05 00:19:07.765462 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-05 00:19:07.800804 | controller | skipping: Conditional result was False 2026-03-05 00:19:07.809779 | 2026-03-05 00:19:07.809870 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-05 00:19:08.090888 | controller | changed 2026-03-05 00:19:08.101887 | 2026-03-05 00:19:08.102101 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-05 00:19:08.332568 | controller | ok 2026-03-05 00:19:08.349908 | 2026-03-05 00:19:08.350104 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-05 00:19:08.964441 | controller | changed 2026-03-05 00:19:08.980054 | 2026-03-05 00:19:08.980191 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-05 00:19:09.584095 | controller | changed 2026-03-05 00:19:09.593616 | 2026-03-05 00:19:09.593774 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-05 00:19:09.620937 | controller | skipping: Conditional result was False 2026-03-05 00:19:09.636527 | 2026-03-05 00:19:09.636744 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-05 00:19:09.888022 | controller -> localhost | changed 2026-03-05 00:19:09.933027 | 2026-03-05 00:19:09.933153 | TASK [add-build-sshkey : Add back temp key] 2026-03-05 00:19:10.212521 | controller -> localhost | Identity added: /var/lib/zuul/builds/5bec561e24574eaeaa58f0d278dc13c2/work/5bec561e24574eaeaa58f0d278dc13c2_id_ecdsa (zuul-build-sshkey) 2026-03-05 00:19:10.212819 | controller -> localhost | ok: Runtime: 0:00:00.008372 2026-03-05 00:19:10.221855 | 2026-03-05 00:19:10.221965 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-05 00:19:10.428315 | controller | ok 2026-03-05 00:19:10.438140 | 2026-03-05 00:19:10.438288 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-05 00:19:10.484188 | controller | skipping: Conditional result was False 2026-03-05 00:19:10.501450 | 2026-03-05 00:19:10.501572 | TASK [include_role : remove-zuul-sshkey] 2026-03-05 00:19:10.527015 | controller | skipping: Conditional result was False 2026-03-05 00:19:10.538844 | 2026-03-05 00:19:10.538969 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-05 00:19:10.768358 | controller | ok: "logs" 2026-03-05 00:19:10.768635 | controller | ok: All items complete 2026-03-05 00:19:10.768667 | 2026-03-05 00:19:10.963446 | controller | ok: "artifacts" 2026-03-05 00:19:11.160360 | controller | ok: "docs" 2026-03-05 00:19:11.186362 | 2026-03-05 00:19:11.186586 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-05 00:19:11.433721 | controller | changed: "logs" 2026-03-05 00:19:11.652478 | controller | changed: "artifacts" 2026-03-05 00:19:11.846386 | controller | changed: "docs" 2026-03-05 00:19:11.889233 | 2026-03-05 00:19:11.889356 | PLAY RECAP 2026-03-05 00:19:11.889417 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-03-05 00:19:11.889456 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-05 00:19:11.889482 | 2026-03-05 00:19:12.015614 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-05 00:19:12.017378 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-05 00:19:12.642461 | 2026-03-05 00:19:12.642588 | PLAY [all] 2026-03-05 00:19:12.667617 | 2026-03-05 00:19:12.667781 | TASK [Install binary dependencies] 2026-03-05 00:19:12.721734 | controller | ok 2026-03-05 00:19:12.747406 | 2026-03-05 00:19:12.747542 | TASK [bindep : Include find tasks] 2026-03-05 00:19:12.776971 | controller | ok 2026-03-05 00:19:12.785421 | controller | included: /var/lib/zuul/builds/5bec561e24574eaeaa58f0d278dc13c2/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-03-05 00:19:12.792719 | 2026-03-05 00:19:12.792793 | TASK [bindep : Look for bindep.txt] 2026-03-05 00:19:13.158707 | controller | ok 2026-03-05 00:19:13.175024 | 2026-03-05 00:19:13.175390 | TASK [bindep : Define bindep_file fact] 2026-03-05 00:19:13.204094 | controller | skipping: Conditional result was False 2026-03-05 00:19:13.215672 | 2026-03-05 00:19:13.215790 | TASK [bindep : Look for other-requirements.txt] 2026-03-05 00:19:13.434957 | controller | ok 2026-03-05 00:19:13.443905 | 2026-03-05 00:19:13.444008 | TASK [bindep : Define bindep_file fact] 2026-03-05 00:19:13.469023 | controller | skipping: Conditional result was False 2026-03-05 00:19:13.481468 | 2026-03-05 00:19:13.481594 | TASK [bindep : Look for bindep fallback file] 2026-03-05 00:19:13.506521 | controller | skipping: Conditional result was False 2026-03-05 00:19:13.516507 | 2026-03-05 00:19:13.516608 | TASK [bindep : Define bindep_file fact] 2026-03-05 00:19:13.543485 | controller | skipping: Conditional result was False 2026-03-05 00:19:13.553246 | 2026-03-05 00:19:13.553349 | TASK [bindep : Include bindep tasks] 2026-03-05 00:19:13.581365 | controller | skipping: Conditional result was False 2026-03-05 00:19:13.590241 | 2026-03-05 00:19:13.590327 | TASK [bindep : Include install tasks] 2026-03-05 00:19:13.614582 | controller | skipping: Conditional result was False 2026-03-05 00:19:13.624944 | 2026-03-05 00:19:13.625039 | LOOP [bindep : Include package tasks] 2026-03-05 00:19:13.691828 | 2026-03-05 00:19:13.692039 | TASK [Run test-setup role] 2026-03-05 00:19:13.717665 | controller | ok 2026-03-05 00:19:13.746833 | 2026-03-05 00:19:13.746966 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-03-05 00:19:13.961863 | controller | ok 2026-03-05 00:19:13.969299 | 2026-03-05 00:19:13.969372 | TASK [test-setup : Run tools/test-setup.sh] 2026-03-05 00:19:14.514033 | controller | skipping: Conditional result was False 2026-03-05 00:19:14.546486 | 2026-03-05 00:19:14.546586 | PLAY RECAP 2026-03-05 00:19:14.546626 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-05 00:19:14.546647 | 2026-03-05 00:19:14.666333 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-05 00:19:14.667199 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-05 00:19:15.302090 | 2026-03-05 00:19:15.302225 | PLAY [controller] 2026-03-05 00:19:15.324076 | 2026-03-05 00:19:15.324177 | TASK [Create the /root directory] 2026-03-05 00:21:27.656296 | controller | ERROR 2026-03-05 00:21:27.656652 | controller | { 2026-03-05 00:21:27.656792 | controller | "msg": "Timeout (32s) waiting for privilege escalation prompt: " 2026-03-05 00:21:27.656856 | controller | } failure 2026-03-05 00:21:27.658626 | 2026-03-05 00:21:27.658763 | PLAY RECAP 2026-03-05 00:21:27.658843 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-03-05 00:21:27.658890 | 2026-03-05 00:21:27.786304 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-05 00:21:27.788102 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-05 00:21:28.461424 | 2026-03-05 00:21:28.461552 | PLAY [all] 2026-03-05 00:21:28.486584 | 2026-03-05 00:21:28.486737 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-03-05 00:25:06.319210 | controller | changed: non-zero return code 2026-03-05 00:25:06.335559 | 2026-03-05 00:25:06.335756 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-03-05 00:25:06.350382 | controller | skipping: Conditional result was False 2026-03-05 00:25:06.358299 | 2026-03-05 00:25:06.358402 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-03-05 00:25:06.397647 | 2026-03-05 00:25:06.397903 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-03-05 00:25:06.435166 | 2026-03-05 00:25:06.435399 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-03-05 00:25:06.462884 | controller | skipping: Conditional result was False 2026-03-05 00:25:06.474337 | 2026-03-05 00:25:06.474475 | LOOP [fetch-subunit-output : Generate subunit file] 2026-03-05 00:25:06.511515 | 2026-03-05 00:25:06.511778 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-03-05 00:25:06.539228 | controller | skipping: Conditional result was False 2026-03-05 00:25:06.549101 | 2026-03-05 00:25:06.549236 | TASK [fetch-subunit-output : Remove the temporary file] 2026-03-05 00:25:06.576584 | controller | skipping: Conditional result was False 2026-03-05 00:25:06.584262 | 2026-03-05 00:25:06.584355 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-03-05 00:25:06.610119 | controller | skipping: Conditional result was False 2026-03-05 00:25:06.652042 | 2026-03-05 00:25:06.652230 | PLAY RECAP 2026-03-05 00:25:06.652302 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-05 00:25:06.652338 | 2026-03-05 00:25:06.786708 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-05 00:25:06.787831 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-03-05 00:25:07.424567 | 2026-03-05 00:25:07.424709 | PLAY [all:!appliance*] 2026-03-05 00:25:07.461938 | 2026-03-05 00:25:07.462059 | TASK [unregister the node] 2026-03-05 00:25:07.999068 | controller | skipping: Conditional result was False 2026-03-05 00:25:08.010059 | 2026-03-05 00:25:08.010298 | TASK [include_role : fetch-output] 2026-03-05 00:25:08.066792 | controller | ok 2026-03-05 00:25:08.107092 | 2026-03-05 00:25:08.107312 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-05 00:25:08.184663 | controller | skipping: Conditional result was False 2026-03-05 00:25:08.194136 | 2026-03-05 00:25:08.194272 | TASK [fetch-output : Set log path for single node] 2026-03-05 00:25:08.226146 | controller | ok 2026-03-05 00:25:08.232103 | 2026-03-05 00:25:08.232174 | LOOP [fetch-output : Ensure local output dirs] 2026-03-05 00:25:08.699846 | controller -> localhost | ok: "/var/lib/zuul/builds/5bec561e24574eaeaa58f0d278dc13c2/work/logs" 2026-03-05 00:25:08.965170 | controller -> localhost | changed: "/var/lib/zuul/builds/5bec561e24574eaeaa58f0d278dc13c2/work/artifacts" 2026-03-05 00:25:09.205489 | controller -> localhost | changed: "/var/lib/zuul/builds/5bec561e24574eaeaa58f0d278dc13c2/work/docs" 2026-03-05 00:25:09.236874 | 2026-03-05 00:25:09.237119 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-05 00:25:09.983711 | controller | ok 2026-03-05 00:25:09.983966 | controller | changed: All items complete 2026-03-05 00:25:09.983999 | 2026-03-05 00:25:10.564816 | controller | changed: .d..t...... ./ 2026-03-05 00:25:11.038807 | controller | changed: .d..t...... ./ 2026-03-05 00:25:11.062370 | 2026-03-05 00:25:11.062521 | TASK [include_role : fetch-output-openshift] 2026-03-05 00:25:11.088926 | controller | skipping: Conditional result was False 2026-03-05 00:25:11.099350 | 2026-03-05 00:25:11.099498 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-05 00:25:11.149871 | controller | skipping: Conditional result was False 2026-03-05 00:25:11.163977 | controller | skipping: Conditional result was False 2026-03-05 00:25:11.212968 | 2026-03-05 00:25:11.213096 | PLAY [localhost] 2026-03-05 00:25:11.233522 | 2026-03-05 00:25:11.233645 | TASK [Run Zuul manifest role] 2026-03-05 00:25:11.254430 | localhost | ok 2026-03-05 00:25:11.276475 | 2026-03-05 00:25:11.276631 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-05 00:25:11.755525 | localhost | changed 2026-03-05 00:25:11.762616 | 2026-03-05 00:25:11.762740 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-05 00:25:11.801536 | localhost | ok 2026-03-05 00:25:11.809876 | 2026-03-05 00:25:11.809944 | TASK [Set zuul-log-path fact] 2026-03-05 00:25:11.828445 | localhost | ok 2026-03-05 00:25:11.844951 | 2026-03-05 00:25:11.845070 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-05 00:25:11.885269 | localhost | ok 2026-03-05 00:25:11.896537 | 2026-03-05 00:25:11.896644 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)] 2026-03-05 00:25:11.924656 | localhost | ok: 2026-03-05 00:25:11.924914 | localhost | { 2026-03-05 00:25:11.924941 | localhost | "auth": { 2026-03-05 00:25:11.924960 | localhost | "password": "IonWCwcuOFib&&<}wp^:[2G/", 2026-03-05 00:25:11.924977 | localhost | "username": "9LF91O0RK7JU" 2026-03-05 00:25:11.924998 | localhost | }, 2026-03-05 00:25:11.925015 | localhost | "profile": "rackspace", 2026-03-05 00:25:11.925031 | localhost | "project_name": "1251317", 2026-03-05 00:25:11.925047 | localhost | "region_name": "DFW" 2026-03-05 00:25:11.925067 | localhost | } 2026-03-05 00:25:11.925118 | 2026-03-05 00:25:11.938175 | 2026-03-05 00:25:11.938287 | TASK [Run upload-logs-failover role (S3 then Swift)] 2026-03-05 00:25:11.958971 | localhost | Output suppressed because no_log was given 2026-03-05 00:25:11.977066 | 2026-03-05 00:25:11.977203 | TASK [upload-logs-failover : Initialize control vars] 2026-03-05 00:25:12.010502 | localhost | ok 2026-03-05 00:25:12.019391 | 2026-03-05 00:25:12.019492 | LOOP [upload-logs-failover : Perform log upload] 2026-03-05 00:25:12.095310 | localhost | Output suppressed because no_log was given 2026-03-05 00:25:12.106083 | localhost | included: /var/lib/zuul/builds/5bec561e24574eaeaa58f0d278dc13c2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml 2026-03-05 00:25:12.116640 | 2026-03-05 00:25:12.116780 | LOOP [upload-logs-failover : Unpack vars for swift upload] 2026-03-05 00:25:12.212995 | localhost | Output suppressed because no_log was given 2026-03-05 00:25:12.222358 | 2026-03-05 00:25:12.222483 | TASK [Upload logs to swift] 2026-03-05 00:25:12.244111 | localhost | ok 2026-03-05 00:25:12.283643 | 2026-03-05 00:25:12.283775 | TASK [Set zuul-log-path fact] 2026-03-05 00:25:12.308206 | localhost | skipping: Conditional result was False 2026-03-05 00:25:12.316534 | 2026-03-05 00:25:12.316642 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-03-05 00:25:12.791216 | localhost -> localhost | ok: Runtime: 0:00:00.009111 2026-03-05 00:25:12.797508 | 2026-03-05 00:25:12.797597 | TASK [upload-logs-swift : Upload logs to swift]