2026-03-01 00:28:40.165954 | Job console starting...
2026-03-01 00:28:40.176131 | Updating repositories
2026-03-01 00:28:40.327313 | Preparing job workspace
2026-03-01 00:28:44.338826 | Running Ansible setup...
2026-03-01 00:28:49.410097 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-03-01 00:28:50.104599 | 
2026-03-01 00:28:50.104740 | PLAY [localhost]
2026-03-01 00:28:50.113219 | 
2026-03-01 00:28:50.113288 | TASK [Gathering Facts]
2026-03-01 00:28:51.169779 | localhost | ok
2026-03-01 00:28:51.201632 | 
2026-03-01 00:28:51.201819 | TASK [Setup log path fact]
2026-03-01 00:28:51.223569 | localhost | ok
2026-03-01 00:28:51.240908 | 
2026-03-01 00:28:51.241060 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-01 00:28:51.281797 | localhost | ok
2026-03-01 00:28:51.293287 | 
2026-03-01 00:28:51.293397 | TASK [emit-job-header : Print job information]
2026-03-01 00:28:51.349758 | # Job Information
2026-03-01 00:28:51.350097 | Ansible Version: 2.15.12
2026-03-01 00:28:51.350212 | Job: ansible-test-sanity-docker-stable-2.11
2026-03-01 00:28:51.350295 | Pipeline: periodic
2026-03-01 00:28:51.350343 | Executor: ze01.softwarefactory-project.io
2026-03-01 00:28:51.350387 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-03-01 00:28:51.350438 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/616/ansible/616872c6964046e8a397437cc215f71e/
2026-03-01 00:28:51.350484 | Event ID: 9a68ce5367a74382a3f0efb74cca8a21
2026-03-01 00:28:51.360363 | 
2026-03-01 00:28:51.360545 | LOOP [emit-job-header : Print node information]
2026-03-01 00:28:51.474724 | localhost | ok:
2026-03-01 00:28:51.475010 | localhost | # Node Information
2026-03-01 00:28:51.475107 | localhost | Inventory Hostname: controller
2026-03-01 00:28:51.475153 | localhost | Hostname: np0005634393
2026-03-01 00:28:51.475196 | localhost | Username: zuul
2026-03-01 00:28:51.475245 | localhost | Distro: Fedora 37
2026-03-01 00:28:51.475286 | localhost | Provider: ansible-vexxhost-ca-ymq-1
2026-03-01 00:28:51.475326 | localhost | Region: ca-ymq-1
2026-03-01 00:28:51.475365 | localhost | Label: ansible-fedora-37-1vcpu
2026-03-01 00:28:51.475403 | localhost | Product Name: OpenStack Nova
2026-03-01 00:28:51.475440 | localhost | Interface IP: 162.253.55.204
2026-03-01 00:28:51.499918 | 
2026-03-01 00:28:51.500215 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-01 00:28:51.897488 | localhost -> localhost | changed
2026-03-01 00:28:51.910159 | 
2026-03-01 00:28:51.910458 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-01 00:28:52.872422 | localhost -> localhost | changed
2026-03-01 00:28:52.899746 | 
2026-03-01 00:28:52.900070 | PLAY [all:!appliance*]
2026-03-01 00:28:52.922540 | 
2026-03-01 00:28:52.922633 | TASK [include_role : start-zuul-console]
2026-03-01 00:28:52.944283 | controller | ok
2026-03-01 00:28:52.963567 | 
2026-03-01 00:28:52.963661 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-03-01 00:28:53.366362 | controller | ok
2026-03-01 00:28:53.391875 | 
2026-03-01 00:28:53.392079 | TASK [use-our-mirror : Retrieve the IP address]
2026-03-01 00:28:54.440760 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-03-01 00:28:54.455933 | 
2026-03-01 00:28:54.456129 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-03-01 00:28:55.007613 | controller | skipping: Conditional result was False
2026-03-01 00:28:55.023155 | 
2026-03-01 00:28:55.023895 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-03-01 00:28:55.052289 | controller | skipping: Conditional result was False
2026-03-01 00:28:55.068215 | 
2026-03-01 00:28:55.068374 | TASK [use-our-mirror : Create the podman configuration directory]
2026-03-01 00:28:55.096166 | controller | skipping: Conditional result was False
2026-03-01 00:28:55.110867 | 
2026-03-01 00:28:55.111049 | TASK [use-our-mirror : Copy the podman configuration]
2026-03-01 00:28:55.139064 | controller | skipping: Conditional result was False
2026-03-01 00:28:55.153770 | 
2026-03-01 00:28:55.153947 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-03-01 00:28:55.182765 | controller | skipping: Conditional result was False
2026-03-01 00:28:55.197820 | 
2026-03-01 00:28:55.197977 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-03-01 00:28:55.224935 | controller | skipping: Conditional result was False
2026-03-01 00:28:55.253150 | 
2026-03-01 00:28:55.253316 | TASK [Disable Fedora Modular]
2026-03-01 00:28:55.517002 | controller | changed
2026-03-01 00:28:55.530207 | 
2026-03-01 00:28:55.530340 | TASK [Enable EPEL]
2026-03-01 00:28:55.558887 | controller | skipping: Conditional result was False
2026-03-01 00:28:55.572472 | 
2026-03-01 00:28:55.572606 | TASK [Register the RHEL node]
2026-03-01 00:28:56.138695 | 
2026-03-01 00:28:56.138978 | TASK [Show the subscription-manager status]
2026-03-01 00:28:56.726634 | controller | skipping: Conditional result was False
2026-03-01 00:28:56.741644 | 
2026-03-01 00:28:56.741793 | TASK [Enable EPEL on RHEL]
2026-03-01 00:28:57.297250 | controller | skipping: Conditional result was False
2026-03-01 00:28:57.311354 | 
2026-03-01 00:28:57.311533 | TASK [Install git and tox]
2026-03-01 00:30:16.661164 | controller | changed
2026-03-01 00:30:16.668906 | 
2026-03-01 00:30:16.669007 | TASK [include_role : prepare-workspace]
2026-03-01 00:30:16.703522 | controller | ok
2026-03-01 00:30:16.728847 | 
2026-03-01 00:30:16.729044 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-01 00:30:16.981545 | controller | ok
2026-03-01 00:30:16.995282 | 
2026-03-01 00:30:16.995428 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-01 00:30:29.058779 | controller | Output suppressed because no_log was given
2026-03-01 00:30:29.076357 | 
2026-03-01 00:30:29.076475 | TASK [include_role : prepare-workspace-openshift]
2026-03-01 00:30:29.104794 | controller | skipping: Conditional result was False
2026-03-01 00:30:29.173802 | 
2026-03-01 00:30:29.173931 | PLAY [all:!appliance]
2026-03-01 00:30:29.191918 | 
2026-03-01 00:30:29.191993 | TASK [Run add-build-sshkey role (RSA)]
2026-03-01 00:30:29.223220 | controller | ok
2026-03-01 00:30:29.241947 | 
2026-03-01 00:30:29.242042 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-01 00:30:29.563538 | controller -> localhost | ok
2026-03-01 00:30:29.578428 | 
2026-03-01 00:30:29.578566 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-01 00:30:29.618554 | controller | ok
2026-03-01 00:30:29.647911 | controller | included: /var/lib/zuul/builds/616872c6964046e8a397437cc215f71e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-01 00:30:29.657131 | 
2026-03-01 00:30:29.657222 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-01 00:30:30.221987 | controller -> localhost | Generating public/private rsa key pair.
2026-03-01 00:30:30.222589 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/616872c6964046e8a397437cc215f71e/work/616872c6964046e8a397437cc215f71e_id_rsa.
2026-03-01 00:30:30.222689 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/616872c6964046e8a397437cc215f71e/work/616872c6964046e8a397437cc215f71e_id_rsa.pub.
2026-03-01 00:30:30.222768 | controller -> localhost | The key fingerprint is:
2026-03-01 00:30:30.222838 | controller -> localhost | SHA256:DVJf4iIOd36HZqrk2A6gSCo7iqEsMTakYxrz4NWTl8s zuul-build-sshkey
2026-03-01 00:30:30.222906 | controller -> localhost | The key's randomart image is:
2026-03-01 00:30:30.222967 | controller -> localhost | +---[RSA 2048]----+
2026-03-01 00:30:30.223079 | controller -> localhost | |        . . .    |
2026-03-01 00:30:30.223163 | controller -> localhost | |       . o o     |
2026-03-01 00:30:30.223227 | controller -> localhost | |    . + + o      |
2026-03-01 00:30:30.223287 | controller -> localhost | | .   + = + .     |
2026-03-01 00:30:30.223347 | controller -> localhost | |o.. . o S * .    |
2026-03-01 00:30:30.223473 | controller -> localhost | |%= o + o = .     |
2026-03-01 00:30:30.223562 | controller -> localhost | |@Oo . = o        |
2026-03-01 00:30:30.223655 | controller -> localhost | |O+.  * E         |
2026-03-01 00:30:30.223724 | controller -> localhost | |Bo  ..=          |
2026-03-01 00:30:30.223794 | controller -> localhost | +----[SHA256]-----+
2026-03-01 00:30:30.223929 | controller -> localhost | ok: Runtime: 0:00:00.118794
2026-03-01 00:30:30.245590 | 
2026-03-01 00:30:30.245767 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-01 00:30:30.282245 | controller | ok
2026-03-01 00:30:30.295576 | controller | included: /var/lib/zuul/builds/616872c6964046e8a397437cc215f71e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-01 00:30:30.307829 | 
2026-03-01 00:30:30.307930 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-01 00:30:30.333144 | controller | skipping: Conditional result was False
2026-03-01 00:30:30.343415 | 
2026-03-01 00:30:30.343509 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-01 00:30:30.800146 | controller | changed
2026-03-01 00:30:30.813230 | 
2026-03-01 00:30:30.813365 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-01 00:30:31.040657 | controller | ok
2026-03-01 00:30:31.059190 | 
2026-03-01 00:30:31.059330 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-01 00:30:31.703231 | controller | changed
2026-03-01 00:30:31.716079 | 
2026-03-01 00:30:31.716218 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-01 00:30:32.324213 | controller | changed
2026-03-01 00:30:32.332417 | 
2026-03-01 00:30:32.332508 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-01 00:30:32.367966 | controller | skipping: Conditional result was False
2026-03-01 00:30:32.379705 | 
2026-03-01 00:30:32.379883 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-01 00:30:32.836506 | controller -> localhost | changed
2026-03-01 00:30:32.866278 | 
2026-03-01 00:30:32.866494 | TASK [add-build-sshkey : Add back temp key]
2026-03-01 00:30:33.181110 | controller -> localhost | Identity added: /var/lib/zuul/builds/616872c6964046e8a397437cc215f71e/work/616872c6964046e8a397437cc215f71e_id_rsa (zuul-build-sshkey)
2026-03-01 00:30:33.181375 | controller -> localhost | ok: Runtime: 0:00:00.012636
2026-03-01 00:30:33.189923 | 
2026-03-01 00:30:33.189997 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-01 00:30:33.560870 | controller | ok
2026-03-01 00:30:33.569797 | 
2026-03-01 00:30:33.569879 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-01 00:30:33.606578 | controller | skipping: Conditional result was False
2026-03-01 00:30:33.621763 | 
2026-03-01 00:30:33.621842 | TASK [Run add-build-sshkey role (ECDSA)]
2026-03-01 00:30:33.654462 | controller | ok
2026-03-01 00:30:33.677246 | 
2026-03-01 00:30:33.677382 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-01 00:30:33.959205 | controller -> localhost | ok
2026-03-01 00:30:33.973111 | 
2026-03-01 00:30:33.973246 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-01 00:30:34.012843 | controller | ok
2026-03-01 00:30:34.036103 | controller | included: /var/lib/zuul/builds/616872c6964046e8a397437cc215f71e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-01 00:30:34.044735 | 
2026-03-01 00:30:34.044825 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-01 00:30:34.374911 | controller -> localhost | Generating public/private ecdsa key pair.
2026-03-01 00:30:34.375162 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/616872c6964046e8a397437cc215f71e/work/616872c6964046e8a397437cc215f71e_id_ecdsa.
2026-03-01 00:30:34.375202 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/616872c6964046e8a397437cc215f71e/work/616872c6964046e8a397437cc215f71e_id_ecdsa.pub.
2026-03-01 00:30:34.375243 | controller -> localhost | The key fingerprint is:
2026-03-01 00:30:34.375272 | controller -> localhost | SHA256:UNm5ZoLDSYGLvVM27gC9rTP7r7+Syj1XLKDF2XRRUF8 zuul-build-sshkey
2026-03-01 00:30:34.375299 | controller -> localhost | The key's randomart image is:
2026-03-01 00:30:34.375347 | controller -> localhost | +---[ECDSA 521]---+
2026-03-01 00:30:34.375381 | controller -> localhost | |     ....oo*o   E|
2026-03-01 00:30:34.375407 | controller -> localhost | |    . ..o +  . . |
2026-03-01 00:30:34.375432 | controller -> localhost | |   + =.* . .  .  |
2026-03-01 00:30:34.375458 | controller -> localhost | |  o + &.o +      |
2026-03-01 00:30:34.375483 | controller -> localhost | |   . X +S=       |
2026-03-01 00:30:34.375508 | controller -> localhost | |    * o . o      |
2026-03-01 00:30:34.375533 | controller -> localhost | |     = . o       |
2026-03-01 00:30:34.375557 | controller -> localhost | |   .+.= .        |
2026-03-01 00:30:34.375582 | controller -> localhost | |    +*+B+.       |
2026-03-01 00:30:34.375607 | controller -> localhost | +----[SHA256]-----+
2026-03-01 00:30:34.375671 | controller -> localhost | ok: Runtime: 0:00:00.010848
2026-03-01 00:30:34.386366 | 
2026-03-01 00:30:34.386458 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-01 00:30:34.420395 | controller | ok
2026-03-01 00:30:34.431068 | controller | included: /var/lib/zuul/builds/616872c6964046e8a397437cc215f71e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-01 00:30:34.443653 | 
2026-03-01 00:30:34.443766 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-01 00:30:34.469904 | controller | skipping: Conditional result was False
2026-03-01 00:30:34.479524 | 
2026-03-01 00:30:34.479630 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-01 00:30:34.757661 | controller | changed
2026-03-01 00:30:34.767923 | 
2026-03-01 00:30:34.768102 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-01 00:30:34.997839 | controller | ok
2026-03-01 00:30:35.006987 | 
2026-03-01 00:30:35.007135 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-01 00:30:35.646352 | controller | changed
2026-03-01 00:30:35.659382 | 
2026-03-01 00:30:35.659534 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-01 00:30:36.289400 | controller | changed
2026-03-01 00:30:36.297117 | 
2026-03-01 00:30:36.297231 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-01 00:30:36.322965 | controller | skipping: Conditional result was False
2026-03-01 00:30:36.332509 | 
2026-03-01 00:30:36.332608 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-01 00:30:36.623281 | controller -> localhost | changed
2026-03-01 00:30:36.651079 | 
2026-03-01 00:30:36.651294 | TASK [add-build-sshkey : Add back temp key]
2026-03-01 00:30:37.008967 | controller -> localhost | Identity added: /var/lib/zuul/builds/616872c6964046e8a397437cc215f71e/work/616872c6964046e8a397437cc215f71e_id_ecdsa (zuul-build-sshkey)
2026-03-01 00:30:37.009212 | controller -> localhost | ok: Runtime: 0:00:00.011998
2026-03-01 00:30:37.015808 | 
2026-03-01 00:30:37.015928 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-01 00:30:37.227592 | controller | ok
2026-03-01 00:30:37.240753 | 
2026-03-01 00:30:37.240963 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-01 00:30:37.279888 | controller | skipping: Conditional result was False
2026-03-01 00:30:37.305565 | 
2026-03-01 00:30:37.305747 | TASK [include_role : remove-zuul-sshkey]
2026-03-01 00:30:37.333463 | controller | skipping: Conditional result was False
2026-03-01 00:30:37.349899 | 
2026-03-01 00:30:37.350155 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-01 00:30:37.598090 | controller | ok: "logs"
2026-03-01 00:30:37.598578 | controller | ok: All items complete
2026-03-01 00:30:37.598642 | 
2026-03-01 00:30:37.797571 | controller | ok: "artifacts"
2026-03-01 00:30:37.988952 | controller | ok: "docs"
2026-03-01 00:30:38.011431 | 
2026-03-01 00:30:38.011620 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-01 00:30:38.263159 | controller | changed: "logs"
2026-03-01 00:30:38.450414 | controller | changed: "artifacts"
2026-03-01 00:30:38.648354 | controller | changed: "docs"
2026-03-01 00:30:38.704317 | 
2026-03-01 00:30:38.704590 | PLAY RECAP
2026-03-01 00:30:38.704665 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-03-01 00:30:38.704703 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-01 00:30:38.704729 | 
2026-03-01 00:30:38.846172 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-03-01 00:30:38.847157 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-01 00:30:39.409849 | 
2026-03-01 00:30:39.409960 | PLAY [all]
2026-03-01 00:30:39.432212 | 
2026-03-01 00:30:39.432317 | TASK [Install binary dependencies]
2026-03-01 00:30:39.504119 | controller | ok
2026-03-01 00:30:39.523815 | 
2026-03-01 00:30:39.523914 | TASK [bindep : Include find tasks]
2026-03-01 00:30:39.575400 | controller | ok
2026-03-01 00:30:39.586215 | controller | included: /var/lib/zuul/builds/616872c6964046e8a397437cc215f71e/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-03-01 00:30:39.595149 | 
2026-03-01 00:30:39.595236 | TASK [bindep : Look for bindep.txt]
2026-03-01 00:30:39.949128 | controller | ok
2026-03-01 00:30:39.969424 | 
2026-03-01 00:30:39.969624 | TASK [bindep : Define bindep_file fact]
2026-03-01 00:30:39.996156 | controller | skipping: Conditional result was False
2026-03-01 00:30:40.005047 | 
2026-03-01 00:30:40.005154 | TASK [bindep : Look for other-requirements.txt]
2026-03-01 00:30:40.216263 | controller | ok
2026-03-01 00:30:40.228612 | 
2026-03-01 00:30:40.228761 | TASK [bindep : Define bindep_file fact]
2026-03-01 00:30:40.266137 | controller | skipping: Conditional result was False
2026-03-01 00:30:40.278803 | 
2026-03-01 00:30:40.278947 | TASK [bindep : Look for bindep fallback file]
2026-03-01 00:30:40.316305 | controller | skipping: Conditional result was False
2026-03-01 00:30:40.334956 | 
2026-03-01 00:30:40.335173 | TASK [bindep : Define bindep_file fact]
2026-03-01 00:30:40.361917 | controller | skipping: Conditional result was False
2026-03-01 00:30:40.374964 | 
2026-03-01 00:30:40.375152 | TASK [bindep : Include bindep tasks]
2026-03-01 00:30:40.401576 | controller | skipping: Conditional result was False
2026-03-01 00:30:40.414353 | 
2026-03-01 00:30:40.414493 | TASK [bindep : Include install tasks]
2026-03-01 00:30:40.440881 | controller | skipping: Conditional result was False
2026-03-01 00:30:40.453773 | 
2026-03-01 00:30:40.453907 | LOOP [bindep : Include package tasks]
2026-03-01 00:30:40.525576 | 
2026-03-01 00:30:40.525855 | TASK [Run test-setup role]
2026-03-01 00:30:40.573407 | controller | ok
2026-03-01 00:30:40.597534 | 
2026-03-01 00:30:40.597700 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-03-01 00:30:40.792239 | controller | ok
2026-03-01 00:30:40.798590 | 
2026-03-01 00:30:40.798655 | TASK [test-setup : Run tools/test-setup.sh]
2026-03-01 00:31:10.847144 | [Zuul] Log Stream did not terminate
2026-03-01 00:31:10.847568 | controller | skipping: Conditional result was False
2026-03-01 00:31:10.903797 | 
2026-03-01 00:31:10.903938 | PLAY RECAP
2026-03-01 00:31:10.903996 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-01 00:31:10.904055 | 
2026-03-01 00:31:11.052967 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-01 00:31:11.053834 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-03-01 00:31:11.679290 | 
2026-03-01 00:31:11.679523 | PLAY [controller]
2026-03-01 00:31:11.699883 | 
2026-03-01 00:31:11.699976 | TASK [Create the /root directory]
2026-03-01 00:33:24.014589 | controller | ERROR
2026-03-01 00:33:24.014946 | controller | {
2026-03-01 00:33:24.015057 | controller |   "msg": "Timeout (32s) waiting for privilege escalation prompt: "
2026-03-01 00:33:24.015127 | controller | }
failure
2026-03-01 00:33:24.017474 | 
2026-03-01 00:33:24.017571 | PLAY RECAP
2026-03-01 00:33:24.017652 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-03-01 00:33:24.017697 | 
2026-03-01 00:33:24.135885 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-03-01 00:33:24.136865 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-01 00:33:24.744455 | 
2026-03-01 00:33:24.744569 | PLAY [all]
2026-03-01 00:33:24.765573 | 
2026-03-01 00:33:24.765667 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-03-01 00:36:11.905738 | controller | changed: non-zero return code
2026-03-01 00:36:11.918387 | 
2026-03-01 00:36:11.918539 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-03-01 00:36:11.946162 | controller | skipping: Conditional result was False
2026-03-01 00:36:11.958978 | 
2026-03-01 00:36:11.959158 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-03-01 00:36:11.998963 | 
2026-03-01 00:36:11.999235 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-03-01 00:36:12.038700 | 
2026-03-01 00:36:12.038967 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-03-01 00:36:12.066270 | controller | skipping: Conditional result was False
2026-03-01 00:36:12.080190 | 
2026-03-01 00:36:12.080364 | LOOP [fetch-subunit-output : Generate subunit file]
2026-03-01 00:36:12.131556 | 
2026-03-01 00:36:12.131990 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-03-01 00:36:12.165500 | controller | skipping: Conditional result was False
2026-03-01 00:36:12.174484 | 
2026-03-01 00:36:12.174575 | TASK [fetch-subunit-output : Remove the temporary file]
2026-03-01 00:36:12.200193 | controller | skipping: Conditional result was False
2026-03-01 00:36:12.220114 | 
2026-03-01 00:36:12.220270 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-03-01 00:36:12.247043 | controller | skipping: Conditional result was False
2026-03-01 00:36:12.285484 | 
2026-03-01 00:36:12.285593 | PLAY RECAP
2026-03-01 00:36:12.285649 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-01 00:36:12.285677 | 
2026-03-01 00:36:12.390719 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-01 00:36:12.392582 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-03-01 00:36:12.990124 | 
2026-03-01 00:36:12.990234 | PLAY [all:!appliance*]
2026-03-01 00:36:13.010964 | 
2026-03-01 00:36:13.011095 | TASK [unregister the node]
2026-03-01 00:36:13.541845 | controller | skipping: Conditional result was False
2026-03-01 00:36:13.560122 | 
2026-03-01 00:36:13.560413 | TASK [include_role : fetch-output]
2026-03-01 00:36:13.613865 | controller | ok
2026-03-01 00:36:13.650079 | 
2026-03-01 00:36:13.650260 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-01 00:36:13.706768 | controller | skipping: Conditional result was False
2026-03-01 00:36:13.717114 | 
2026-03-01 00:36:13.717277 | TASK [fetch-output : Set log path for single node]
2026-03-01 00:36:13.771560 | controller | ok
2026-03-01 00:36:13.779338 | 
2026-03-01 00:36:13.779429 | LOOP [fetch-output : Ensure local output dirs]
2026-03-01 00:36:14.257326 | controller -> localhost | ok: "/var/lib/zuul/builds/616872c6964046e8a397437cc215f71e/work/logs"
2026-03-01 00:36:14.535456 | controller -> localhost | changed: "/var/lib/zuul/builds/616872c6964046e8a397437cc215f71e/work/artifacts"
2026-03-01 00:36:14.770177 | controller -> localhost | changed: "/var/lib/zuul/builds/616872c6964046e8a397437cc215f71e/work/docs"
2026-03-01 00:36:14.781008 | 
2026-03-01 00:36:14.781131 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-01 00:36:15.566781 | controller | ok
2026-03-01 00:36:15.567211 | controller | changed: All items complete
2026-03-01 00:36:15.567280 | 
2026-03-01 00:36:16.120763 | controller | changed: .d..t...... ./
2026-03-01 00:36:16.683932 | controller | changed: .d..t...... ./
2026-03-01 00:36:16.720889 | 
2026-03-01 00:36:16.721119 | TASK [include_role : fetch-output-openshift]
2026-03-01 00:36:16.750075 | controller | skipping: Conditional result was False
2026-03-01 00:36:16.759640 | 
2026-03-01 00:36:16.759748 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-01 00:36:16.805118 | controller | skipping: Conditional result was False
2026-03-01 00:36:16.816713 | controller | skipping: Conditional result was False
2026-03-01 00:36:16.866238 | 
2026-03-01 00:36:16.866369 | PLAY [localhost]
2026-03-01 00:36:16.885134 | 
2026-03-01 00:36:16.885261 | TASK [Run Zuul manifest role]
2026-03-01 00:36:16.908170 | localhost | ok
2026-03-01 00:36:16.928327 | 
2026-03-01 00:36:16.928455 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-01 00:36:17.333632 | localhost | changed
2026-03-01 00:36:17.338384 | 
2026-03-01 00:36:17.338448 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-01 00:36:17.377980 | localhost | ok
2026-03-01 00:36:17.386474 | 
2026-03-01 00:36:17.386551 | TASK [Set zuul-log-path fact]
2026-03-01 00:36:17.406495 | localhost | ok
2026-03-01 00:36:17.421627 | 
2026-03-01 00:36:17.421755 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-01 00:36:17.461719 | localhost | ok
2026-03-01 00:36:17.469837 | 
2026-03-01 00:36:17.469911 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)]
2026-03-01 00:36:17.498056 | localhost | ok:
2026-03-01 00:36:17.498410 | localhost | {
2026-03-01 00:36:17.498461 | localhost |   "auth": {
2026-03-01 00:36:17.498498 | localhost |     "password": "IonWCwcuOFib&&<}wp^:[2G/",
2026-03-01 00:36:17.498532 | localhost |     "username": "9LF91O0RK7JU"
2026-03-01 00:36:17.498570 | localhost |   },
2026-03-01 00:36:17.498606 | localhost |   "profile": "rackspace",
2026-03-01 00:36:17.498639 | localhost |   "region_name": "IAD"
2026-03-01 00:36:17.498665 | localhost | }
2026-03-01 00:36:17.498737 | 
2026-03-01 00:36:17.512518 | 
2026-03-01 00:36:17.512620 | TASK [Run upload-logs-failover role (S3 then Swift)]
2026-03-01 00:36:17.533758 | localhost | Output suppressed because no_log was given
2026-03-01 00:36:17.550153 | 
2026-03-01 00:36:17.550268 | TASK [upload-logs-failover : Initialize control vars]
2026-03-01 00:36:17.581752 | localhost | ok
2026-03-01 00:36:17.587498 | 
2026-03-01 00:36:17.587582 | LOOP [upload-logs-failover : Perform log upload]
2026-03-01 00:36:17.677434 | localhost | Output suppressed because no_log was given
2026-03-01 00:36:17.686611 | localhost | included: /var/lib/zuul/builds/616872c6964046e8a397437cc215f71e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml
2026-03-01 00:36:17.696228 | 
2026-03-01 00:36:17.696334 | LOOP [upload-logs-failover : Unpack vars for swift upload]
2026-03-01 00:36:17.787790 | localhost | Output suppressed because no_log was given
2026-03-01 00:36:17.799295 | 
2026-03-01 00:36:17.799477 | TASK [Upload logs to swift]
2026-03-01 00:36:17.833841 | localhost | ok
2026-03-01 00:36:17.875820 | 
2026-03-01 00:36:17.875948 | TASK [Set zuul-log-path fact]
2026-03-01 00:36:17.910546 | localhost | skipping: Conditional result was False
2026-03-01 00:36:17.918663 | 
2026-03-01 00:36:17.918802 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-03-01 00:36:18.344266 | localhost -> localhost | ok: Runtime: 0:00:00.010209
2026-03-01 00:36:18.358088 | 
2026-03-01 00:36:18.358257 | TASK [upload-logs-swift : Upload logs to swift]