2026-03-09 00:21:57.401735 | Job console starting...
2026-03-09 00:21:57.418229 | Updating repositories
2026-03-09 00:21:57.570598 | Preparing job workspace
2026-03-09 00:22:01.296220 | Running Ansible setup...
2026-03-09 00:22:05.574370 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-03-09 00:22:06.235282 | 
2026-03-09 00:22:06.235435 | PLAY [localhost]
2026-03-09 00:22:06.245181 | 
2026-03-09 00:22:06.245346 | TASK [Gathering Facts]
2026-03-09 00:22:07.167285 | localhost | ok
2026-03-09 00:22:07.202480 | 
2026-03-09 00:22:07.202689 | TASK [Setup log path fact]
2026-03-09 00:22:07.224254 | localhost | ok
2026-03-09 00:22:07.244150 | 
2026-03-09 00:22:07.244298 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-09 00:22:07.275343 | localhost | ok
2026-03-09 00:22:07.287738 | 
2026-03-09 00:22:07.287889 | TASK [emit-job-header : Print job information]
2026-03-09 00:22:07.318898 | # Job Information
2026-03-09 00:22:07.319220 | Ansible Version: 2.15.12
2026-03-09 00:22:07.319267 | Job: ansible-test-sanity-docker-devel
2026-03-09 00:22:07.319299 | Pipeline: periodic
2026-03-09 00:22:07.319327 | Executor: ze01.softwarefactory-project.io
2026-03-09 00:22:07.319355 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-03-09 00:22:07.319389 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/c05/ansible/c05bf0f35471412089d156bb803b14ca/
2026-03-09 00:22:07.319418 | Event ID: 6ed282d1e81147dcab9935779dfad26a
2026-03-09 00:22:07.325373 | 
2026-03-09 00:22:07.325468 | LOOP [emit-job-header : Print node information]
2026-03-09 00:22:07.431325 | localhost | ok:
2026-03-09 00:22:07.431470 | localhost | # Node Information
2026-03-09 00:22:07.431498 | localhost | Inventory Hostname: controller
2026-03-09 00:22:07.431519 | localhost | Hostname: np0005641928
2026-03-09 00:22:07.431538 | localhost | Username: zuul
2026-03-09 00:22:07.431559 | localhost | Distro: Fedora 37
2026-03-09 00:22:07.431578 | localhost | Provider: ansible-vexxhost-ca-ymq-1
2026-03-09 00:22:07.431598 | localhost | Region: ca-ymq-1
2026-03-09 00:22:07.431616 | localhost | Label: ansible-fedora-37-1vcpu
2026-03-09 00:22:07.431635 | localhost | Product Name: OpenStack Nova
2026-03-09 00:22:07.431655 | localhost | Interface IP: 162.253.55.49
2026-03-09 00:22:07.440303 | 
2026-03-09 00:22:07.440400 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-09 00:22:07.831369 | localhost -> localhost | changed
2026-03-09 00:22:07.837494 | 
2026-03-09 00:22:07.837581 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-09 00:22:08.729884 | localhost -> localhost | changed
2026-03-09 00:22:08.756616 | 
2026-03-09 00:22:08.756762 | PLAY [all:!appliance*]
2026-03-09 00:22:08.779091 | 
2026-03-09 00:22:08.779220 | TASK [include_role : start-zuul-console]
2026-03-09 00:22:08.801465 | controller | ok
2026-03-09 00:22:08.820651 | 
2026-03-09 00:22:08.820787 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-03-09 00:22:09.189443 | controller | ok
2026-03-09 00:22:09.217225 | 
2026-03-09 00:22:09.217425 | TASK [use-our-mirror : Retrieve the IP address]
2026-03-09 00:22:09.988214 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-03-09 00:22:09.995455 | 
2026-03-09 00:22:09.995555 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-03-09 00:22:10.527818 | controller | skipping: Conditional result was False
2026-03-09 00:22:10.538515 | 
2026-03-09 00:22:10.538694 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-03-09 00:22:10.576218 | controller | skipping: Conditional result was False
2026-03-09 00:22:10.585169 | 
2026-03-09 00:22:10.585322 | TASK [use-our-mirror : Create the podman configuration directory]
2026-03-09 00:22:10.613912 | controller | skipping: Conditional result was False
2026-03-09 00:22:10.625539 | 
2026-03-09 00:22:10.625768 | TASK [use-our-mirror : Copy the podman configuration]
2026-03-09 00:22:10.643532 | controller | skipping: Conditional result was False
2026-03-09 00:22:10.651797 | 
2026-03-09 00:22:10.651959 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-03-09 00:22:10.677561 | controller | skipping: Conditional result was False
2026-03-09 00:22:10.686500 | 
2026-03-09 00:22:10.686647 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-03-09 00:22:10.712304 | controller | skipping: Conditional result was False
2026-03-09 00:22:10.726323 | 
2026-03-09 00:22:10.726463 | TASK [Disable Fedora Modular]
2026-03-09 00:22:10.951944 | controller | changed
2026-03-09 00:22:10.959054 | 
2026-03-09 00:22:10.959141 | TASK [Enable EPEL]
2026-03-09 00:22:10.983564 | controller | skipping: Conditional result was False
2026-03-09 00:22:10.990734 | 
2026-03-09 00:22:10.990845 | TASK [Register the RHEL node]
2026-03-09 00:22:11.533390 | 
2026-03-09 00:22:11.533553 | TASK [Show the subscription-manager status]
2026-03-09 00:22:12.101306 | controller | skipping: Conditional result was False
2026-03-09 00:22:12.115913 | 
2026-03-09 00:22:12.116005 | TASK [Enable EPEL on RHEL]
2026-03-09 00:22:12.662537 | controller | skipping: Conditional result was False
2026-03-09 00:22:12.671682 | 
2026-03-09 00:22:12.671862 | TASK [Install git and tox]
2026-03-09 00:23:32.138777 | controller | changed
2026-03-09 00:23:32.152120 | 
2026-03-09 00:23:32.152287 | TASK [include_role : prepare-workspace]
2026-03-09 00:23:32.193590 | controller | ok
2026-03-09 00:23:32.236557 | 
2026-03-09 00:23:32.236775 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-09 00:23:32.481593 | controller | ok
2026-03-09 00:23:32.495312 | 
2026-03-09 00:23:32.495488 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-09 00:23:45.266693 | controller | Output suppressed because no_log was given
2026-03-09 00:23:45.309359 | 
2026-03-09 00:23:45.309473 | TASK [include_role : prepare-workspace-openshift]
2026-03-09 00:23:45.325422 | controller | skipping: Conditional result was False
2026-03-09 00:23:45.348938 | 
2026-03-09 00:23:45.349067 | PLAY [all:!appliance]
2026-03-09 00:23:45.367171 | 
2026-03-09 00:23:45.367287 | TASK [Run add-build-sshkey role (RSA)]
2026-03-09 00:23:45.397753 | controller | ok
2026-03-09 00:23:45.413469 | 
2026-03-09 00:23:45.413582 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-09 00:23:45.652415 | controller -> localhost | ok
2026-03-09 00:23:45.660690 | 
2026-03-09 00:23:45.660820 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-09 00:23:45.684604 | controller | ok
2026-03-09 00:23:45.702417 | controller | included: /var/lib/zuul/builds/c05bf0f35471412089d156bb803b14ca/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-09 00:23:45.709305 | 
2026-03-09 00:23:45.709375 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-09 00:23:46.145820 | controller -> localhost | Generating public/private rsa key pair.
2026-03-09 00:23:46.146097 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/c05bf0f35471412089d156bb803b14ca/work/c05bf0f35471412089d156bb803b14ca_id_rsa.
2026-03-09 00:23:46.146159 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/c05bf0f35471412089d156bb803b14ca/work/c05bf0f35471412089d156bb803b14ca_id_rsa.pub.
2026-03-09 00:23:46.146194 | controller -> localhost | The key fingerprint is:
2026-03-09 00:23:46.146224 | controller -> localhost | SHA256:vcWPQ5r8g4/Ua8YQbPfwZfMj6qleXWwWvD0oVhI+LGQ zuul-build-sshkey
2026-03-09 00:23:46.146250 | controller -> localhost | The key's randomart image is:
2026-03-09 00:23:46.146270 | controller -> localhost | +---[RSA 2048]----+
2026-03-09 00:23:46.146288 | controller -> localhost | |         E .     |
2026-03-09 00:23:46.146306 | controller -> localhost | |        o o . .  |
2026-03-09 00:23:46.146323 | controller -> localhost | |         o = . o |
2026-03-09 00:23:46.146340 | controller -> localhost | |         .=.* o.*|
2026-03-09 00:23:46.146356 | controller -> localhost | |        S..=+= X+|
2026-03-09 00:23:46.146385 | controller -> localhost | |         .oBo+*.o|
2026-03-09 00:23:46.146408 | controller -> localhost | |          *==.o .|
2026-03-09 00:23:46.146429 | controller -> localhost | |         .o+*o   |
2026-03-09 00:23:46.146448 | controller -> localhost | |        .o+*+.   |
2026-03-09 00:23:46.146468 | controller -> localhost | +----[SHA256]-----+
2026-03-09 00:23:46.146517 | controller -> localhost | ok: Runtime: 0:00:00.057884
2026-03-09 00:23:46.154576 | 
2026-03-09 00:23:46.154711 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-09 00:23:46.186710 | controller | ok
2026-03-09 00:23:46.197890 | controller | included: /var/lib/zuul/builds/c05bf0f35471412089d156bb803b14ca/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-09 00:23:46.208491 | 
2026-03-09 00:23:46.208702 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-09 00:23:46.242935 | controller | skipping: Conditional result was False
2026-03-09 00:23:46.257710 | 
2026-03-09 00:23:46.257851 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-09 00:23:46.748998 | controller | changed
2026-03-09 00:23:46.762521 | 
2026-03-09 00:23:46.762694 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-09 00:23:46.974335 | controller | ok
2026-03-09 00:23:46.981747 | 
2026-03-09 00:23:46.981866 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-09 00:23:47.589284 | controller | changed
2026-03-09 00:23:47.602288 | 
2026-03-09 00:23:47.602428 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-09 00:23:48.243863 | controller | changed
2026-03-09 00:23:48.254233 | 
2026-03-09 00:23:48.254357 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-09 00:23:48.280758 | controller | skipping: Conditional result was False
2026-03-09 00:23:48.291648 | 
2026-03-09 00:23:48.291835 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-09 00:23:48.700440 | controller -> localhost | changed
2026-03-09 00:23:48.719532 | 
2026-03-09 00:23:48.719672 | TASK [add-build-sshkey : Add back temp key]
2026-03-09 00:23:49.055282 | controller -> localhost | Identity added: /var/lib/zuul/builds/c05bf0f35471412089d156bb803b14ca/work/c05bf0f35471412089d156bb803b14ca_id_rsa (zuul-build-sshkey)
2026-03-09 00:23:49.055554 | controller -> localhost | ok: Runtime: 0:00:00.017591
2026-03-09 00:23:49.064911 | 
2026-03-09 00:23:49.065041 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-09 00:23:49.404676 | controller | ok
2026-03-09 00:23:49.426382 | 
2026-03-09 00:23:49.426502 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-09 00:23:49.452721 | controller | skipping: Conditional result was False
2026-03-09 00:23:49.469290 | 
2026-03-09 00:23:49.469430 | TASK [Run add-build-sshkey role (ECDSA)]
2026-03-09 00:23:49.504599 | controller | ok
2026-03-09 00:23:49.529689 | 
2026-03-09 00:23:49.530191 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-09 00:23:49.801695 | controller -> localhost | ok
2026-03-09 00:23:49.812133 | 
2026-03-09 00:23:49.812290 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-09 00:23:49.845977 | controller | ok
2026-03-09 00:23:49.861090 | controller | included: /var/lib/zuul/builds/c05bf0f35471412089d156bb803b14ca/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-09 00:23:49.871962 | 
2026-03-09 00:23:49.872147 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-09 00:23:50.194046 | controller -> localhost | Generating public/private ecdsa key pair.
2026-03-09 00:23:50.194252 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/c05bf0f35471412089d156bb803b14ca/work/c05bf0f35471412089d156bb803b14ca_id_ecdsa.
2026-03-09 00:23:50.194292 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/c05bf0f35471412089d156bb803b14ca/work/c05bf0f35471412089d156bb803b14ca_id_ecdsa.pub.
2026-03-09 00:23:50.194344 | controller -> localhost | The key fingerprint is:
2026-03-09 00:23:50.194371 | controller -> localhost | SHA256:XkTaHUHEiheJgHZhOYpn9rmnQnPxYC3MQ7HRRNhABVM zuul-build-sshkey
2026-03-09 00:23:50.194398 | controller -> localhost | The key's randomart image is:
2026-03-09 00:23:50.194426 | controller -> localhost | +---[ECDSA 521]---+
2026-03-09 00:23:50.194449 | controller -> localhost | |     o@/E..==.   |
2026-03-09 00:23:50.194473 | controller -> localhost | |    o.*+++o...   |
2026-03-09 00:23:50.194498 | controller -> localhost | |   o *.o..oo.    |
2026-03-09 00:23:50.194521 | controller -> localhost | |  . = O o.o      |
2026-03-09 00:23:50.194547 | controller -> localhost | |   + o BS..      |
2026-03-09 00:23:50.194572 | controller -> localhost | |    o +...       |
2026-03-09 00:23:50.194597 | controller -> localhost | |   . o ..        |
2026-03-09 00:23:50.194619 | controller -> localhost | |    . . .        |
2026-03-09 00:23:50.194641 | controller -> localhost | |     ..o         |
2026-03-09 00:23:50.194666 | controller -> localhost | +----[SHA256]-----+
2026-03-09 00:23:50.194729 | controller -> localhost | ok: Runtime: 0:00:00.014709
2026-03-09 00:23:50.203838 | 
2026-03-09 00:23:50.203942 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-09 00:23:50.237195 | controller | ok
2026-03-09 00:23:50.247291 | controller | included: /var/lib/zuul/builds/c05bf0f35471412089d156bb803b14ca/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-09 00:23:50.258508 | 
2026-03-09 00:23:50.258606 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-09 00:23:50.273449 | controller | skipping: Conditional result was False
2026-03-09 00:23:50.281560 | 
2026-03-09 00:23:50.281672 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-09 00:23:50.537405 | controller | changed
2026-03-09 00:23:50.543177 | 
2026-03-09 00:23:50.543243 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-09 00:23:50.758416 | controller | ok
2026-03-09 00:23:50.764393 | 
2026-03-09 00:23:50.764458 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-09 00:23:51.388937 | controller | changed
2026-03-09 00:23:51.395043 | 
2026-03-09 00:23:51.395139 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-09 00:23:51.987690 | controller | changed
2026-03-09 00:23:51.994582 | 
2026-03-09 00:23:51.994683 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-09 00:23:52.030707 | controller | skipping: Conditional result was False
2026-03-09 00:23:52.039903 | 
2026-03-09 00:23:52.040038 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-09 00:23:52.376909 | controller -> localhost | changed
2026-03-09 00:23:52.390119 | 
2026-03-09 00:23:52.390293 | TASK [add-build-sshkey : Add back temp key]
2026-03-09 00:23:52.681135 | controller -> localhost | Identity added: /var/lib/zuul/builds/c05bf0f35471412089d156bb803b14ca/work/c05bf0f35471412089d156bb803b14ca_id_ecdsa (zuul-build-sshkey)
2026-03-09 00:23:52.681346 | controller -> localhost | ok: Runtime: 0:00:00.010729
2026-03-09 00:23:52.689742 | 
2026-03-09 00:23:52.689818 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-09 00:23:52.930348 | controller | ok
2026-03-09 00:23:52.942784 | 
2026-03-09 00:23:52.942915 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-09 00:23:52.984513 | controller | skipping: Conditional result was False
2026-03-09 00:23:52.997173 | 
2026-03-09 00:23:52.997259 | TASK [include_role : remove-zuul-sshkey]
2026-03-09 00:23:53.012704 | controller | skipping: Conditional result was False
2026-03-09 00:23:53.020127 | 
2026-03-09 00:23:53.020248 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-09 00:23:53.232906 | controller | ok: "logs"
2026-03-09 00:23:53.233190 | controller | ok: All items complete
2026-03-09 00:23:53.233220 | 
2026-03-09 00:23:53.417628 | controller | ok: "artifacts"
2026-03-09 00:23:53.610003 | controller | ok: "docs"
2026-03-09 00:23:53.623627 | 
2026-03-09 00:23:53.623738 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-09 00:23:53.880223 | controller | changed: "logs"
2026-03-09 00:23:54.092632 | controller | changed: "artifacts"
2026-03-09 00:23:54.310554 | controller | changed: "docs"
2026-03-09 00:23:54.364056 | 
2026-03-09 00:23:54.364155 | PLAY RECAP
2026-03-09 00:23:54.364200 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-03-09 00:23:54.364228 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-09 00:23:54.364248 | 
2026-03-09 00:23:54.519078 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-03-09 00:23:54.519894 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-09 00:23:55.139416 | 
2026-03-09 00:23:55.139552 | PLAY [all]
2026-03-09 00:23:55.164658 | 
2026-03-09 00:23:55.164817 | TASK [Install binary dependencies]
2026-03-09 00:23:55.225559 | controller | ok
2026-03-09 00:23:55.249503 | 
2026-03-09 00:23:55.249662 | TASK [bindep : Include find tasks]
2026-03-09 00:23:55.289489 | controller | ok
2026-03-09 00:23:55.306859 | controller | included: /var/lib/zuul/builds/c05bf0f35471412089d156bb803b14ca/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-03-09 00:23:55.314648 | 
2026-03-09 00:23:55.314781 | TASK [bindep : Look for bindep.txt]
2026-03-09 00:23:55.656566 | controller | ok
2026-03-09 00:23:55.668444 | 
2026-03-09 00:23:55.668624 | TASK [bindep : Define bindep_file fact]
2026-03-09 00:23:55.684738 | controller | skipping: Conditional result was False
2026-03-09 00:23:55.694592 | 
2026-03-09 00:23:55.694764 | TASK [bindep : Look for other-requirements.txt]
2026-03-09 00:23:55.919226 | controller | ok
2026-03-09 00:23:55.928686 | 
2026-03-09 00:23:55.928836 | TASK [bindep : Define bindep_file fact]
2026-03-09 00:23:55.954030 | controller | skipping: Conditional result was False
2026-03-09 00:23:55.961657 | 
2026-03-09 00:23:55.961774 | TASK [bindep : Look for bindep fallback file]
2026-03-09 00:23:55.989674 | controller | skipping: Conditional result was False
2026-03-09 00:23:55.998404 | 
2026-03-09 00:23:55.998564 | TASK [bindep : Define bindep_file fact]
2026-03-09 00:23:56.023079 | controller | skipping: Conditional result was False
2026-03-09 00:23:56.030276 | 
2026-03-09 00:23:56.030413 | TASK [bindep : Include bindep tasks]
2026-03-09 00:23:56.054409 | controller | skipping: Conditional result was False
2026-03-09 00:23:56.061544 | 
2026-03-09 00:23:56.061647 | TASK [bindep : Include install tasks]
2026-03-09 00:23:56.085248 | controller | skipping: Conditional result was False
2026-03-09 00:23:56.092062 | 
2026-03-09 00:23:56.092154 | LOOP [bindep : Include package tasks]
2026-03-09 00:23:56.147465 | 
2026-03-09 00:23:56.147623 | TASK [Run test-setup role]
2026-03-09 00:23:56.168519 | controller | ok
2026-03-09 00:23:56.190340 | 
2026-03-09 00:23:56.190458 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-03-09 00:23:56.400885 | controller | ok
2026-03-09 00:23:56.413936 | 
2026-03-09 00:23:56.414127 | TASK [test-setup : Run tools/test-setup.sh]
2026-03-09 00:24:26.460595 | [Zuul] Log Stream did not terminate
2026-03-09 00:24:26.460977 | controller | skipping: Conditional result was False
2026-03-09 00:24:26.518279 | 
2026-03-09 00:24:26.518407 | PLAY RECAP
2026-03-09 00:24:26.518473 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-09 00:24:26.518504 | 
2026-03-09 00:24:26.650518 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-09 00:24:26.651695 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-03-09 00:24:27.285472 | 
2026-03-09 00:24:27.285621 | PLAY [controller]
2026-03-09 00:24:27.308051 | 
2026-03-09 00:24:27.308207 | TASK [Create the /root directory]
2026-03-09 00:26:39.596573 | controller | ERROR
2026-03-09 00:26:39.596902 | controller | {
2026-03-09 00:26:39.596999 | controller |   "msg": "Timeout (32s) waiting for privilege escalation prompt: "
2026-03-09 00:26:39.597140 | controller | }
failure
2026-03-09 00:26:39.598557 | 
2026-03-09 00:26:39.598694 | PLAY RECAP
2026-03-09 00:26:39.598814 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-03-09 00:26:39.598901 | 
2026-03-09 00:26:39.774080 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-03-09 00:26:39.776170 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-09 00:26:40.418886 | 
2026-03-09 00:26:40.419031 | PLAY [all]
2026-03-09 00:26:40.441034 | 
2026-03-09 00:26:40.441155 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-03-09 00:29:34.589554 | controller | changed: non-zero return code
2026-03-09 00:29:34.600628 | 
2026-03-09 00:29:34.600842 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-03-09 00:29:34.617803 | controller | skipping: Conditional result was False
2026-03-09 00:29:34.632544 | 
2026-03-09 00:29:34.632689 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-03-09 00:29:34.663870 | 
2026-03-09 00:29:34.664175 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-03-09 00:29:34.695494 | 
2026-03-09 00:29:34.695780 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-03-09 00:29:34.711512 | controller | skipping: Conditional result was False
2026-03-09 00:29:34.721564 | 
2026-03-09 00:29:34.721677 | LOOP [fetch-subunit-output : Generate subunit file]
2026-03-09 00:29:34.746391 | 
2026-03-09 00:29:34.746620 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-03-09 00:29:34.761330 | controller | skipping: Conditional result was False
2026-03-09 00:29:34.772327 | 
2026-03-09 00:29:34.772451 | TASK [fetch-subunit-output : Remove the temporary file]
2026-03-09 00:29:34.788333 | controller | skipping: Conditional result was False
2026-03-09 00:29:34.803204 | 
2026-03-09 00:29:34.803381 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-03-09 00:29:34.821303 | controller | skipping: Conditional result was False
2026-03-09 00:29:34.862487 | 
2026-03-09 00:29:34.862692 | PLAY RECAP
2026-03-09 00:29:34.862761 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-09 00:29:34.862791 | 
2026-03-09 00:29:35.000186 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-09 00:29:35.001123 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-03-09 00:29:35.681626 | 
2026-03-09 00:29:35.681752 | PLAY [all:!appliance*]
2026-03-09 00:29:35.716563 | 
2026-03-09 00:29:35.716685 | TASK [unregister the node]
2026-03-09 00:29:36.249450 | controller | skipping: Conditional result was False
2026-03-09 00:29:36.272869 | 
2026-03-09 00:29:36.273121 | TASK [include_role : fetch-output]
2026-03-09 00:29:36.308482 | controller | ok
2026-03-09 00:29:36.337976 | 
2026-03-09 00:29:36.338113 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-09 00:29:36.382272 | controller | skipping: Conditional result was False
2026-03-09 00:29:36.388623 | 
2026-03-09 00:29:36.388716 | TASK [fetch-output : Set log path for single node]
2026-03-09 00:29:36.420220 | controller | ok
2026-03-09 00:29:36.425744 | 
2026-03-09 00:29:36.425829 | LOOP [fetch-output : Ensure local output dirs]
2026-03-09 00:29:36.855246 | controller -> localhost | ok: "/var/lib/zuul/builds/c05bf0f35471412089d156bb803b14ca/work/logs"
2026-03-09 00:29:37.128695 | controller -> localhost | changed: "/var/lib/zuul/builds/c05bf0f35471412089d156bb803b14ca/work/artifacts"
2026-03-09 00:29:37.353870 | controller -> localhost | changed: "/var/lib/zuul/builds/c05bf0f35471412089d156bb803b14ca/work/docs"
2026-03-09 00:29:37.373723 | 
2026-03-09 00:29:37.373901 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-09 00:29:38.093310 | controller | changed: .d..t...... ./
2026-03-09 00:29:38.093687 | controller | changed: All items complete
2026-03-09 00:29:38.093748 | 
2026-03-09 00:29:38.636001 | controller | changed: .d..t...... ./
2026-03-09 00:29:39.060997 | controller | changed: .d..t...... ./
2026-03-09 00:29:39.083586 | 
2026-03-09 00:29:39.083710 | TASK [include_role : fetch-output-openshift]
2026-03-09 00:29:39.109875 | controller | skipping: Conditional result was False
2026-03-09 00:29:39.119343 | 
2026-03-09 00:29:39.119457 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-09 00:29:39.166185 | controller | skipping: Conditional result was False
2026-03-09 00:29:39.166861 | controller | skipping: Conditional result was False
2026-03-09 00:29:39.222442 | 
2026-03-09 00:29:39.222526 | PLAY [localhost]
2026-03-09 00:29:39.235552 | 
2026-03-09 00:29:39.235673 | TASK [Run Zuul manifest role]
2026-03-09 00:29:39.253476 | localhost | ok
2026-03-09 00:29:39.267347 | 
2026-03-09 00:29:39.267447 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-09 00:29:39.639045 | localhost | changed
2026-03-09 00:29:39.643664 | 
2026-03-09 00:29:39.643729 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-09 00:29:39.671663 | localhost | ok
2026-03-09 00:29:39.679714 | 
2026-03-09 00:29:39.679777 | TASK [Set zuul-log-path fact]
2026-03-09 00:29:39.697604 | localhost | ok
2026-03-09 00:29:39.712124 | 
2026-03-09 00:29:39.712204 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-09 00:29:39.742521 | localhost | ok
2026-03-09 00:29:39.754542 | 
2026-03-09 00:29:39.754637 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)]
2026-03-09 00:29:39.789152 | localhost | ok:
2026-03-09 00:29:39.789510 | localhost | {
2026-03-09 00:29:39.789551 | localhost |   "auth": {
2026-03-09 00:29:39.789577 | localhost |     "password": "IonWCwcuOFib&&<}wp^:[2G/",
2026-03-09 00:29:39.789600 | localhost |     "username": "9LF91O0RK7JU"
2026-03-09 00:29:39.789627 | localhost |   },
2026-03-09 00:29:39.789650 | localhost |   "profile": "rackspace",
2026-03-09 00:29:39.789673 | localhost |   "project_name": "1251317",
2026-03-09 00:29:39.789695 | localhost |   "region_name": "DFW"
2026-03-09 00:29:39.789716 | localhost | }
2026-03-09 00:29:39.789745 | 
2026-03-09 00:29:39.798891 | 
2026-03-09 00:29:39.799110 | TASK [Run upload-logs-failover role (S3 then Swift)]
2026-03-09 00:29:39.834183 | localhost | Output suppressed because no_log was given
2026-03-09 00:29:39.851804 | 
2026-03-09 00:29:39.851921 | TASK [upload-logs-failover : Initialize control vars]
2026-03-09 00:29:39.883356 | localhost | ok
2026-03-09 00:29:39.889208 | 
2026-03-09 00:29:39.889290 | LOOP [upload-logs-failover : Perform log upload]
2026-03-09 00:29:39.936196 | localhost | Output suppressed because no_log was given
2026-03-09 00:29:39.986959 | localhost | included: /var/lib/zuul/builds/c05bf0f35471412089d156bb803b14ca/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml
2026-03-09 00:29:39.996697 | 
2026-03-09 00:29:39.996810 | LOOP [upload-logs-failover : Unpack vars for swift upload]
2026-03-09 00:29:40.126987 | localhost | Output suppressed because no_log was given
2026-03-09 00:29:40.134729 | 
2026-03-09 00:29:40.134827 | TASK [Upload logs to swift]
2026-03-09 00:29:40.168458 | localhost | ok
2026-03-09 00:29:40.209558 | 
2026-03-09 00:29:40.209704 | TASK [Set zuul-log-path fact]
2026-03-09 00:29:40.236057 | localhost | skipping: Conditional result was False
2026-03-09 00:29:40.242781 | 
2026-03-09 00:29:40.242898 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-03-09 00:29:40.659317 | localhost -> localhost | ok: Runtime: 0:00:00.007135
2026-03-09 00:29:40.668098 | 
2026-03-09 00:29:40.668283 | TASK [upload-logs-swift : Upload logs to swift]