2026-03-15 00:22:10.216948 | Job console starting...
2026-03-15 00:22:10.228695 | Updating repositories
2026-03-15 00:22:10.361714 | Preparing job workspace
2026-03-15 00:22:14.395362 | Running Ansible setup...
2026-03-15 00:22:19.478979 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-03-15 00:22:20.075568 | 
2026-03-15 00:22:20.075705 | PLAY [localhost]
2026-03-15 00:22:20.085028 | 
2026-03-15 00:22:20.085130 | TASK [Gathering Facts]
2026-03-15 00:22:20.968417 | localhost | ok
2026-03-15 00:22:20.984870 | 
2026-03-15 00:22:20.985015 | TASK [Setup log path fact]
2026-03-15 00:22:21.003569 | localhost | ok
2026-03-15 00:22:21.018296 | 
2026-03-15 00:22:21.018441 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-15 00:22:21.052115 | localhost | ok
2026-03-15 00:22:21.071280 | 
2026-03-15 00:22:21.071486 | TASK [emit-job-header : Print job information]
2026-03-15 00:22:21.102010 | # Job Information
2026-03-15 00:22:21.102198 | Ansible Version: 2.15.12
2026-03-15 00:22:21.102250 | Job: ansible-test-sanity-docker-stable-2.11
2026-03-15 00:22:21.102282 | Pipeline: periodic
2026-03-15 00:22:21.102308 | Executor: ze03.softwarefactory-project.io
2026-03-15 00:22:21.102334 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-03-15 00:22:21.102367 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/d3a/ansible/d3a4d795f86946a99c5438a3c9c63a35/
2026-03-15 00:22:21.102395 | Event ID: a9a533d68ab94149ab6023488646f926
2026-03-15 00:22:21.108119 | 
2026-03-15 00:22:21.108222 | LOOP [emit-job-header : Print node information]
2026-03-15 00:22:21.209038 | localhost | ok:
2026-03-15 00:22:21.209229 | localhost | # Node Information
2026-03-15 00:22:21.209261 | localhost | Inventory Hostname: controller
2026-03-15 00:22:21.209284 | localhost | Hostname: np0005647456
2026-03-15 00:22:21.209308 | localhost | Username: zuul
2026-03-15 00:22:21.209334 | localhost | Distro: Fedora 37
2026-03-15 00:22:21.209357 | localhost | Provider: ansible-vexxhost-ca-ymq-1
2026-03-15 00:22:21.209378 | localhost | Region: ca-ymq-1
2026-03-15 00:22:21.209398 | localhost | Label: ansible-fedora-37-1vcpu
2026-03-15 00:22:21.209419 | localhost | Product Name: OpenStack Nova
2026-03-15 00:22:21.209441 | localhost | Interface IP: 162.253.55.227
2026-03-15 00:22:21.218384 | 
2026-03-15 00:22:21.218488 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-15 00:22:21.641110 | localhost -> localhost | changed
2026-03-15 00:22:21.649562 | 
2026-03-15 00:22:21.649692 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-15 00:22:22.553236 | localhost -> localhost | changed
2026-03-15 00:22:22.581609 | 
2026-03-15 00:22:22.581711 | PLAY [all:!appliance*]
2026-03-15 00:22:22.606349 | 
2026-03-15 00:22:22.606490 | TASK [include_role : start-zuul-console]
2026-03-15 00:22:22.629071 | controller | ok
2026-03-15 00:22:22.650413 | 
2026-03-15 00:22:22.650548 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-03-15 00:22:23.034419 | controller | ok
2026-03-15 00:22:23.055156 | 
2026-03-15 00:22:23.055276 | TASK [use-our-mirror : Retrieve the IP address]
2026-03-15 00:22:24.041149 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-03-15 00:22:24.054890 | 
2026-03-15 00:22:24.055069 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-03-15 00:22:24.593897 | controller | skipping: Conditional result was False
2026-03-15 00:22:24.602995 | 
2026-03-15 00:22:24.603584 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-03-15 00:22:24.628043 | controller | skipping: Conditional result was False
2026-03-15 00:22:24.636663 | 
2026-03-15 00:22:24.636847 | TASK [use-our-mirror : Create the podman configuration directory]
2026-03-15 00:22:24.651386 | controller | skipping: Conditional result was False
2026-03-15 00:22:24.658560 | 
2026-03-15 00:22:24.658677 | TASK [use-our-mirror : Copy the podman configuration]
2026-03-15 00:22:24.683743 | controller | skipping: Conditional result was False
2026-03-15 00:22:24.691000 | 
2026-03-15 00:22:24.691121 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-03-15 00:22:24.705706 | controller | skipping: Conditional result was False
2026-03-15 00:22:24.721619 | 
2026-03-15 00:22:24.721793 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-03-15 00:22:24.766819 | controller | skipping: Conditional result was False
2026-03-15 00:22:24.793912 | 
2026-03-15 00:22:24.794092 | TASK [Disable Fedora Modular]
2026-03-15 00:22:25.039827 | controller | changed
2026-03-15 00:22:25.045553 | 
2026-03-15 00:22:25.045620 | TASK [Enable EPEL]
2026-03-15 00:22:25.062775 | controller | skipping: Conditional result was False
2026-03-15 00:22:25.070710 | 
2026-03-15 00:22:25.070895 | TASK [Register the RHEL node]
2026-03-15 00:22:25.629746 | 
2026-03-15 00:22:25.629938 | TASK [Show the subscription-manager status]
2026-03-15 00:22:26.206294 | controller | skipping: Conditional result was False
2026-03-15 00:22:26.214601 | 
2026-03-15 00:22:26.214733 | TASK [Enable EPEL on RHEL]
2026-03-15 00:22:26.758366 | controller | skipping: Conditional result was False
2026-03-15 00:22:26.766731 | 
2026-03-15 00:22:26.767119 | TASK [Install git and tox]
2026-03-15 00:23:45.968182 | controller | changed
2026-03-15 00:23:45.978653 | 
2026-03-15 00:23:45.978954 | TASK [include_role : prepare-workspace]
2026-03-15 00:23:46.013709 | controller | ok
2026-03-15 00:23:46.034889 | 
2026-03-15 00:23:46.034962 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-15 00:23:46.349701 | controller | ok
2026-03-15 00:23:46.357588 | 
2026-03-15 00:23:46.357681 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-15 00:23:58.688040 | controller | Output suppressed because no_log was given
2026-03-15 00:23:58.704996 | 
2026-03-15 00:23:58.705146 | TASK [include_role : prepare-workspace-openshift]
2026-03-15 00:23:58.730759 | controller | skipping: Conditional result was False
2026-03-15 00:23:58.765001 | 
2026-03-15 00:23:58.765208 | PLAY [all:!appliance]
2026-03-15 00:23:58.817201 | 
2026-03-15 00:23:58.817333 | TASK [Run add-build-sshkey role (RSA)]
2026-03-15 00:23:58.847450 | controller | ok
2026-03-15 00:23:58.862922 | 
2026-03-15 00:23:58.863000 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-15 00:23:59.135988 | controller -> localhost | ok
2026-03-15 00:23:59.150752 | 
2026-03-15 00:23:59.150907 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-15 00:23:59.183815 | controller | ok
2026-03-15 00:23:59.210212 | controller | included: /var/lib/zuul/builds/d3a4d795f86946a99c5438a3c9c63a35/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-15 00:23:59.219139 | 
2026-03-15 00:23:59.219243 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-15 00:23:59.735068 | controller -> localhost | Generating public/private rsa key pair.
2026-03-15 00:23:59.735436 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/d3a4d795f86946a99c5438a3c9c63a35/work/d3a4d795f86946a99c5438a3c9c63a35_id_rsa.
2026-03-15 00:23:59.735501 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/d3a4d795f86946a99c5438a3c9c63a35/work/d3a4d795f86946a99c5438a3c9c63a35_id_rsa.pub.
2026-03-15 00:23:59.735549 | controller -> localhost | The key fingerprint is:
2026-03-15 00:23:59.735593 | controller -> localhost | SHA256:ZJHXHwVAzlccb3dh6SGhvQMxh3ANCyOd/A10B/koo3U zuul-build-sshkey
2026-03-15 00:23:59.735635 | controller -> localhost | The key's randomart image is:
2026-03-15 00:23:59.735677 | controller -> localhost | +---[RSA 2048]----+
2026-03-15 00:23:59.735716 | controller -> localhost | |       .+=+O**=O=|
2026-03-15 00:23:59.736107 | controller -> localhost | |        o===X++++|
2026-03-15 00:23:59.736180 | controller -> localhost | |        o..+=o*.*|
2026-03-15 00:23:59.736240 | controller -> localhost | |       o   =.Eo+o|
2026-03-15 00:23:59.736283 | controller -> localhost | |        S o +o   |
2026-03-15 00:23:59.736371 | controller -> localhost | |         .    .  |
2026-03-15 00:23:59.736425 | controller -> localhost | |                 |
2026-03-15 00:23:59.736468 | controller -> localhost | |                 |
2026-03-15 00:23:59.736509 | controller -> localhost | |                 |
2026-03-15 00:23:59.736563 | controller -> localhost | +----[SHA256]-----+
2026-03-15 00:23:59.736683 | controller -> localhost | ok: Runtime: 0:00:00.120197
2026-03-15 00:23:59.753225 | 
2026-03-15 00:23:59.753401 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-15 00:23:59.794662 | controller | ok
2026-03-15 00:23:59.817310 | controller | included: /var/lib/zuul/builds/d3a4d795f86946a99c5438a3c9c63a35/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-15 00:23:59.832499 | 
2026-03-15 00:23:59.832637 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-15 00:23:59.847838 | controller | skipping: Conditional result was False
2026-03-15 00:23:59.859058 | 
2026-03-15 00:23:59.859195 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-15 00:24:00.308016 | controller | changed
2026-03-15 00:24:00.324681 | 
2026-03-15 00:24:00.324909 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-15 00:24:00.546368 | controller | ok
2026-03-15 00:24:00.554489 | 
2026-03-15 00:24:00.554702 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-15 00:24:01.229988 | controller | changed
2026-03-15 00:24:01.245268 | 
2026-03-15 00:24:01.245450 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-15 00:24:01.891924 | controller | changed
2026-03-15 00:24:01.899809 | 
2026-03-15 00:24:01.899925 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-15 00:24:01.926528 | controller | skipping: Conditional result was False
2026-03-15 00:24:01.936077 | 
2026-03-15 00:24:01.936201 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-15 00:24:02.297832 | controller -> localhost | changed
2026-03-15 00:24:02.310970 | 
2026-03-15 00:24:02.311427 | TASK [add-build-sshkey : Add back temp key]
2026-03-15 00:24:02.590925 | controller -> localhost | Identity added: /var/lib/zuul/builds/d3a4d795f86946a99c5438a3c9c63a35/work/d3a4d795f86946a99c5438a3c9c63a35_id_rsa (zuul-build-sshkey)
2026-03-15 00:24:02.591220 | controller -> localhost | ok: Runtime: 0:00:00.009041
2026-03-15 00:24:02.603432 | 
2026-03-15 00:24:02.603590 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-15 00:24:02.967268 | controller | ok
2026-03-15 00:24:02.976155 | 
2026-03-15 00:24:02.976328 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-15 00:24:03.002029 | controller | skipping: Conditional result was False
2026-03-15 00:24:03.013692 | 
2026-03-15 00:24:03.013792 | TASK [Run add-build-sshkey role (ECDSA)]
2026-03-15 00:24:03.033192 | controller | ok
2026-03-15 00:24:03.051040 | 
2026-03-15 00:24:03.051120 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-15 00:24:03.330980 | controller -> localhost | ok
2026-03-15 00:24:03.346396 | 
2026-03-15 00:24:03.346582 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-15 00:24:03.389161 | controller | ok
2026-03-15 00:24:03.413428 | controller | included: /var/lib/zuul/builds/d3a4d795f86946a99c5438a3c9c63a35/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-15 00:24:03.423068 | 
2026-03-15 00:24:03.423205 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-15 00:24:03.758578 | controller -> localhost | Generating public/private ecdsa key pair.
2026-03-15 00:24:03.759081 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/d3a4d795f86946a99c5438a3c9c63a35/work/d3a4d795f86946a99c5438a3c9c63a35_id_ecdsa.
2026-03-15 00:24:03.759157 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/d3a4d795f86946a99c5438a3c9c63a35/work/d3a4d795f86946a99c5438a3c9c63a35_id_ecdsa.pub.
2026-03-15 00:24:03.759226 | controller -> localhost | The key fingerprint is:
2026-03-15 00:24:03.759315 | controller -> localhost | SHA256:Xyhou/yFmIw7IGqba3Rpb3XJ8IumOouwNSE5roiJ3Tc zuul-build-sshkey
2026-03-15 00:24:03.759367 | controller -> localhost | The key's randomart image is:
2026-03-15 00:24:03.759409 | controller -> localhost | +---[ECDSA 521]---+
2026-03-15 00:24:03.759450 | controller -> localhost | |                 |
2026-03-15 00:24:03.759489 | controller -> localhost | |                 |
2026-03-15 00:24:03.759527 | controller -> localhost | |                 |
2026-03-15 00:24:03.759565 | controller -> localhost | | .     o   .     |
2026-03-15 00:24:03.759604 | controller -> localhost | |+ . . o S o .    |
2026-03-15 00:24:03.759644 | controller -> localhost | |o+.= + = O .     |
2026-03-15 00:24:03.759683 | controller -> localhost | |+o=.o * + +      |
2026-03-15 00:24:03.759721 | controller -> localhost | |BBo+.=E+ o       |
2026-03-15 00:24:03.759806 | controller -> localhost | |X=+oB+=o.        |
2026-03-15 00:24:03.759848 | controller -> localhost | +----[SHA256]-----+
2026-03-15 00:24:03.759935 | controller -> localhost | ok: Runtime: 0:00:00.007816
2026-03-15 00:24:03.776172 | 
2026-03-15 00:24:03.776343 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-15 00:24:03.817944 | controller | ok
2026-03-15 00:24:03.836223 | controller | included: /var/lib/zuul/builds/d3a4d795f86946a99c5438a3c9c63a35/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-15 00:24:03.856575 | 
2026-03-15 00:24:03.856714 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-15 00:24:03.873234 | controller | skipping: Conditional result was False
2026-03-15 00:24:03.883319 | 
2026-03-15 00:24:03.883418 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-15 00:24:04.165249 | controller | changed
2026-03-15 00:24:04.178265 | 
2026-03-15 00:24:04.178521 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-15 00:24:04.401435 | controller | ok
2026-03-15 00:24:04.414613 | 
2026-03-15 00:24:04.414789 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-15 00:24:05.103252 | controller | changed
2026-03-15 00:24:05.119418 | 
2026-03-15 00:24:05.119593 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-15 00:24:05.818607 | controller | changed
2026-03-15 00:24:05.825289 | 
2026-03-15 00:24:05.825411 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-15 00:24:05.861222 | controller | skipping: Conditional result was False
2026-03-15 00:24:05.869293 | 
2026-03-15 00:24:05.869436 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-15 00:24:06.149609 | controller -> localhost | changed
2026-03-15 00:24:06.162484 | 
2026-03-15 00:24:06.162563 | TASK [add-build-sshkey : Add back temp key]
2026-03-15 00:24:06.524405 | controller -> localhost | Identity added: /var/lib/zuul/builds/d3a4d795f86946a99c5438a3c9c63a35/work/d3a4d795f86946a99c5438a3c9c63a35_id_ecdsa (zuul-build-sshkey)
2026-03-15 00:24:06.524616 | controller -> localhost | ok: Runtime: 0:00:00.014356
2026-03-15 00:24:06.531545 | 
2026-03-15 00:24:06.531636 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-15 00:24:06.762720 | controller | ok
2026-03-15 00:24:06.776610 | 
2026-03-15 00:24:06.776805 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-15 00:24:06.815495 | controller | skipping: Conditional result was False
2026-03-15 00:24:06.882068 | 
2026-03-15 00:24:06.882207 | TASK [include_role : remove-zuul-sshkey]
2026-03-15 00:24:06.897784 | controller | skipping: Conditional result was False
2026-03-15 00:24:06.904516 | 
2026-03-15 00:24:06.904614 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-15 00:24:07.140218 | controller | ok: "logs"
2026-03-15 00:24:07.140911 | controller | ok: All items complete
2026-03-15 00:24:07.140999 | 
2026-03-15 00:24:07.346569 | controller | ok: "artifacts"
2026-03-15 00:24:07.565358 | controller | ok: "docs"
2026-03-15 00:24:07.588683 | 
2026-03-15 00:24:07.588882 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-15 00:24:07.862953 | controller | changed: "logs"
2026-03-15 00:24:08.076816 | controller | changed: "artifacts"
2026-03-15 00:24:08.285618 | controller | changed: "docs"
2026-03-15 00:24:08.352109 | 
2026-03-15 00:24:08.352233 | PLAY RECAP
2026-03-15 00:24:08.352293 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-03-15 00:24:08.352331 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-15 00:24:08.352358 | 
2026-03-15 00:24:08.471326 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-03-15 00:24:08.472239 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-15 00:24:09.069042 | 
2026-03-15 00:24:09.069226 | PLAY [all]
2026-03-15 00:24:09.094318 | 
2026-03-15 00:24:09.094475 | TASK [Install binary dependencies]
2026-03-15 00:24:09.147397 | controller | ok
2026-03-15 00:24:09.170457 | 
2026-03-15 00:24:09.170616 | TASK [bindep : Include find tasks]
2026-03-15 00:24:09.215446 | controller | ok
2026-03-15 00:24:09.229004 | controller | included: /var/lib/zuul/builds/d3a4d795f86946a99c5438a3c9c63a35/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-03-15 00:24:09.238861 | 
2026-03-15 00:24:09.238982 | TASK [bindep : Look for bindep.txt]
2026-03-15 00:24:09.617821 | controller | ok
2026-03-15 00:24:09.634595 | 
2026-03-15 00:24:09.634876 | TASK [bindep : Define bindep_file fact]
2026-03-15 00:24:09.652351 | controller | skipping: Conditional result was False
2026-03-15 00:24:09.663666 | 
2026-03-15 00:24:09.663801 | TASK [bindep : Look for other-requirements.txt]
2026-03-15 00:24:09.886241 | controller | ok
2026-03-15 00:24:09.900832 | 
2026-03-15 00:24:09.901042 | TASK [bindep : Define bindep_file fact]
2026-03-15 00:24:09.928897 | controller | skipping: Conditional result was False
2026-03-15 00:24:09.943339 | 
2026-03-15 00:24:09.943493 | TASK [bindep : Look for bindep fallback file]
2026-03-15 00:24:09.981140 | controller | skipping: Conditional result was False
2026-03-15 00:24:09.999806 | 
2026-03-15 00:24:10.000010 | TASK [bindep : Define bindep_file fact]
2026-03-15 00:24:10.038914 | controller | skipping: Conditional result was False
2026-03-15 00:24:10.057357 | 
2026-03-15 00:24:10.057559 | TASK [bindep : Include bindep tasks]
2026-03-15 00:24:10.106291 | controller | skipping: Conditional result was False
2026-03-15 00:24:10.115660 | 
2026-03-15 00:24:10.115791 | TASK [bindep : Include install tasks]
2026-03-15 00:24:10.151346 | controller | skipping: Conditional result was False
2026-03-15 00:24:10.161097 | 
2026-03-15 00:24:10.161206 | LOOP [bindep : Include package tasks]
2026-03-15 00:24:10.235109 | 
2026-03-15 00:24:10.235315 | TASK [Run test-setup role]
2026-03-15 00:24:10.260950 | controller | ok
2026-03-15 00:24:10.285913 | 
2026-03-15 00:24:10.286028 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-03-15 00:24:10.516506 | controller | ok
2026-03-15 00:24:10.530462 | 
2026-03-15 00:24:10.530601 | TASK [test-setup : Run tools/test-setup.sh]
2026-03-15 00:24:40.603075 | [Zuul] Log Stream did not terminate
2026-03-15 00:24:40.603448 | controller | skipping: Conditional result was False
2026-03-15 00:24:40.656574 | 
2026-03-15 00:24:40.656694 | PLAY RECAP
2026-03-15 00:24:40.656772 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-15 00:24:40.656809 | 
2026-03-15 00:24:40.760315 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-15 00:24:40.762172 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-03-15 00:24:41.405297 | 
2026-03-15 00:24:41.405405 | PLAY [controller]
2026-03-15 00:24:41.424442 | 
2026-03-15 00:24:41.424520 | TASK [Create the /root directory]
2026-03-15 00:26:53.740023 | controller | ERROR
2026-03-15 00:26:53.740223 | controller | {
2026-03-15 00:26:53.740258 | controller |   "msg": "Timeout (32s) waiting for privilege escalation prompt: "
2026-03-15 00:26:53.740285 | controller | }
failure
2026-03-15 00:26:53.741623 | 
2026-03-15 00:26:53.741671 | PLAY RECAP
2026-03-15 00:26:53.741709 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-03-15 00:26:53.741747 | 
2026-03-15 00:26:53.845860 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-03-15 00:26:53.847828 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-15 00:26:54.423927 | 
2026-03-15 00:26:54.424037 | PLAY [all]
2026-03-15 00:26:54.444896 | 
2026-03-15 00:26:54.444996 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-03-15 00:29:40.421960 | controller | changed: non-zero return code
2026-03-15 00:29:40.438052 | 
2026-03-15 00:29:40.438312 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-03-15 00:29:40.466435 | controller | skipping: Conditional result was False
2026-03-15 00:29:40.480574 | 
2026-03-15 00:29:40.480790 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-03-15 00:29:40.524481 | 
2026-03-15 00:29:40.524825 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-03-15 00:29:40.567207 | 
2026-03-15 00:29:40.567581 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-03-15 00:29:40.594881 | controller | skipping: Conditional result was False
2026-03-15 00:29:40.609291 | 
2026-03-15 00:29:40.609466 | LOOP [fetch-subunit-output : Generate subunit file]
2026-03-15 00:29:40.648115 | 
2026-03-15 00:29:40.648413 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-03-15 00:29:40.674494 | controller | skipping: Conditional result was False
2026-03-15 00:29:40.687787 | 
2026-03-15 00:29:40.687947 | TASK [fetch-subunit-output : Remove the temporary file]
2026-03-15 00:29:40.713985 | controller | skipping: Conditional result was False
2026-03-15 00:29:40.726803 | 
2026-03-15 00:29:40.727031 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-03-15 00:29:40.753491 | controller | skipping: Conditional result was False
2026-03-15 00:29:40.788958 | 
2026-03-15 00:29:40.789051 | PLAY RECAP
2026-03-15 00:29:40.789121 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-15 00:29:40.789159 | 
2026-03-15 00:29:40.891698 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-15 00:29:40.893517 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-03-15 00:29:41.485470 | 
2026-03-15 00:29:41.485586 | PLAY [all:!appliance*]
2026-03-15 00:29:41.507628 | 
2026-03-15 00:29:41.507764 | TASK [unregister the node]
2026-03-15 00:29:42.038057 | controller | skipping: Conditional result was False
2026-03-15 00:29:42.053151 | 
2026-03-15 00:29:42.053362 | TASK [include_role : fetch-output]
2026-03-15 00:29:42.116306 | controller | ok
2026-03-15 00:29:42.155293 | 
2026-03-15 00:29:42.155570 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-15 00:29:42.201420 | controller | skipping: Conditional result was False
2026-03-15 00:29:42.209977 | 
2026-03-15 00:29:42.210089 | TASK [fetch-output : Set log path for single node]
2026-03-15 00:29:42.249496 | controller | ok
2026-03-15 00:29:42.260437 | 
2026-03-15 00:29:42.260544 | LOOP [fetch-output : Ensure local output dirs]
2026-03-15 00:29:42.698859 | controller -> localhost | ok: "/var/lib/zuul/builds/d3a4d795f86946a99c5438a3c9c63a35/work/logs"
2026-03-15 00:29:42.954291 | controller -> localhost | changed: "/var/lib/zuul/builds/d3a4d795f86946a99c5438a3c9c63a35/work/artifacts"
2026-03-15 00:29:43.276251 | controller -> localhost | changed: "/var/lib/zuul/builds/d3a4d795f86946a99c5438a3c9c63a35/work/docs"
2026-03-15 00:29:43.294969 | 
2026-03-15 00:29:43.295081 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-15 00:29:43.978058 | controller | changed: .d..t...... ./
2026-03-15 00:29:43.978405 | controller | changed: All items complete
2026-03-15 00:29:43.978464 | 
2026-03-15 00:29:44.546687 | controller | changed: .d..t...... ./
2026-03-15 00:29:45.063312 | controller | changed: .d..t...... ./
2026-03-15 00:29:45.099255 | 
2026-03-15 00:29:45.099443 | TASK [include_role : fetch-output-openshift]
2026-03-15 00:29:45.128831 | controller | skipping: Conditional result was False
2026-03-15 00:29:45.143970 | 
2026-03-15 00:29:45.144161 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-15 00:29:45.190860 | controller | skipping: Conditional result was False
2026-03-15 00:29:45.202942 | controller | skipping: Conditional result was False
2026-03-15 00:29:45.272069 | 
2026-03-15 00:29:45.272241 | PLAY [localhost]
2026-03-15 00:29:45.291691 | 
2026-03-15 00:29:45.291823 | TASK [Run Zuul manifest role]
2026-03-15 00:29:45.313994 | localhost | ok
2026-03-15 00:29:45.335134 | 
2026-03-15 00:29:45.335260 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-15 00:29:45.721122 | localhost | changed
2026-03-15 00:29:45.726379 | 
2026-03-15 00:29:45.726478 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-15 00:29:45.766384 | localhost | ok
2026-03-15 00:29:45.775540 | 
2026-03-15 00:29:45.775613 | TASK [Set zuul-log-path fact]
2026-03-15 00:29:45.795069 | localhost | ok
2026-03-15 00:29:45.809154 | 
2026-03-15 00:29:45.809234 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-15 00:29:45.847691 | localhost | ok
2026-03-15 00:29:45.856005 | 
2026-03-15 00:29:45.856072 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)]
2026-03-15 00:29:45.894590 | localhost | ok:
2026-03-15 00:29:45.894963 | localhost | {
2026-03-15 00:29:45.895004 | localhost |   "auth": {
2026-03-15 00:29:45.895032 | localhost |     "password": "IonWCwcuOFib&&<}wp^:[2G/",
2026-03-15 00:29:45.895068 | localhost |     "username": "9LF91O0RK7JU"
2026-03-15 00:29:45.895097 | localhost |   },
2026-03-15 00:29:45.895122 | localhost |   "profile": "rackspace",
2026-03-15 00:29:45.895146 | localhost |   "region_name": "IAD"
2026-03-15 00:29:45.895171 | localhost | }
2026-03-15 00:29:45.895219 | 
2026-03-15 00:29:45.905464 | 
2026-03-15 00:29:45.905572 | TASK [Run upload-logs-failover role (S3 then Swift)]
2026-03-15 00:29:45.925883 | localhost | Output suppressed because no_log was given
2026-03-15 00:29:45.942347 | 
2026-03-15 00:29:45.942478 | TASK [upload-logs-failover : Initialize control vars]
2026-03-15 00:29:45.964843 | localhost | ok
2026-03-15 00:29:45.971278 | 
2026-03-15 00:29:45.971360 | LOOP [upload-logs-failover : Perform log upload]
2026-03-15 00:29:46.010276 | localhost | Output suppressed because no_log was given
2026-03-15 00:29:46.049420 | localhost | included: /var/lib/zuul/builds/d3a4d795f86946a99c5438a3c9c63a35/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml
2026-03-15 00:29:46.055500 | 
2026-03-15 00:29:46.055566 | LOOP [upload-logs-failover : Unpack vars for swift upload]
2026-03-15 00:29:46.137444 | localhost | Output suppressed because no_log was given
2026-03-15 00:29:46.143228 | 
2026-03-15 00:29:46.143320 | TASK [Upload logs to swift]
2026-03-15 00:29:46.163096 | localhost | ok
2026-03-15 00:29:46.190740 | 
2026-03-15 00:29:46.190828 | TASK [Set zuul-log-path fact]
2026-03-15 00:29:46.225327 | localhost | skipping: Conditional result was False
2026-03-15 00:29:46.230965 | 
2026-03-15 00:29:46.231033 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-03-15 00:29:46.655642 | localhost -> localhost | ok: Runtime: 0:00:00.004605
2026-03-15 00:29:46.670283 | 
2026-03-15 00:29:46.670551 | TASK [upload-logs-swift : Upload logs to swift]