2026-03-12 00:14:18.660773 | Job console starting...
2026-03-12 00:14:18.671478 | Updating repositories
2026-03-12 00:14:18.796584 | Preparing job workspace
2026-03-12 00:14:22.696065 | Running Ansible setup...
2026-03-12 00:14:27.676528 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-03-12 00:14:28.316989 | 
2026-03-12 00:14:28.317175 | PLAY [localhost]
2026-03-12 00:14:28.327885 | 
2026-03-12 00:14:28.328002 | TASK [Gathering Facts]
2026-03-12 00:14:29.357804 | localhost | ok
2026-03-12 00:14:29.385414 | 
2026-03-12 00:14:29.385697 | TASK [Setup log path fact]
2026-03-12 00:14:29.414471 | localhost | ok
2026-03-12 00:14:29.441057 | 
2026-03-12 00:14:29.441186 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-12 00:14:29.472073 | localhost | ok
2026-03-12 00:14:29.483793 | 
2026-03-12 00:14:29.483893 | TASK [emit-job-header : Print job information]
2026-03-12 00:14:29.514499 | # Job Information
2026-03-12 00:14:29.514692 | Ansible Version: 2.15.12
2026-03-12 00:14:29.514746 | Job: ansible-test-sanity-docker-stable-2.12
2026-03-12 00:14:29.514778 | Pipeline: periodic
2026-03-12 00:14:29.514804 | Executor: ze02.softwarefactory-project.io
2026-03-12 00:14:29.514836 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-03-12 00:14:29.514867 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/579/ansible/579d2d90e02942598c0725dae617956b/
2026-03-12 00:14:29.514894 | Event ID: 1521915404ba4f519e0765e96d83261c
2026-03-12 00:14:29.520701 | 
2026-03-12 00:14:29.520794 | LOOP [emit-job-header : Print node information]
2026-03-12 00:14:29.620025 | localhost | ok:
2026-03-12 00:14:29.620210 | localhost | # Node Information
2026-03-12 00:14:29.620242 | localhost | Inventory Hostname: controller
2026-03-12 00:14:29.620266 | localhost | Hostname: np0005644944
2026-03-12 00:14:29.620289 | localhost | Username: zuul
2026-03-12 00:14:29.620315 | localhost | Distro: Fedora 37
2026-03-12 00:14:29.620338 | localhost | Provider: ansible-vexxhost-ca-ymq-1
2026-03-12 00:14:29.620361 | localhost | Region: ca-ymq-1
2026-03-12 00:14:29.620382 | localhost | Label: ansible-fedora-37-1vcpu
2026-03-12 00:14:29.620404 | localhost | Product Name: OpenStack Nova
2026-03-12 00:14:29.620425 | localhost | Interface IP: 162.253.55.221
2026-03-12 00:14:29.636348 | 
2026-03-12 00:14:29.636465 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-12 00:14:30.078280 | localhost -> localhost | changed
2026-03-12 00:14:30.085343 | 
2026-03-12 00:14:30.085436 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-12 00:14:30.984232 | localhost -> localhost | changed
2026-03-12 00:14:31.004395 | 
2026-03-12 00:14:31.004462 | PLAY [all:!appliance*]
2026-03-12 00:14:31.020385 | 
2026-03-12 00:14:31.020454 | TASK [include_role : start-zuul-console]
2026-03-12 00:14:31.040853 | controller | ok
2026-03-12 00:14:31.055182 | 
2026-03-12 00:14:31.055260 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-03-12 00:14:31.457954 | controller | ok
2026-03-12 00:14:31.487494 | 
2026-03-12 00:14:31.487723 | TASK [use-our-mirror : Retrieve the IP address]
2026-03-12 00:14:32.610697 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-03-12 00:14:32.620239 | 
2026-03-12 00:14:32.620351 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-03-12 00:14:33.163067 | controller | skipping: Conditional result was False
2026-03-12 00:14:33.177802 | 
2026-03-12 00:14:33.177953 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-03-12 00:14:33.195395 | controller | skipping: Conditional result was False
2026-03-12 00:14:33.209906 | 
2026-03-12 00:14:33.210289 | TASK [use-our-mirror : Create the podman configuration directory]
2026-03-12 00:14:33.227137 | controller | skipping: Conditional result was False
2026-03-12 00:14:33.243249 | 
2026-03-12 00:14:33.243484 | TASK [use-our-mirror : Copy the podman configuration]
2026-03-12 00:14:33.270911 | controller | skipping: Conditional result was False
2026-03-12 00:14:33.282912 | 
2026-03-12 00:14:33.283072 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-03-12 00:14:33.309460 | controller | skipping: Conditional result was False
2026-03-12 00:14:33.322351 | 
2026-03-12 00:14:33.322519 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-03-12 00:14:33.348990 | controller | skipping: Conditional result was False
2026-03-12 00:14:33.370308 | 
2026-03-12 00:14:33.370468 | TASK [Disable Fedora Modular]
2026-03-12 00:14:33.616859 | controller | changed
2026-03-12 00:14:33.633437 | 
2026-03-12 00:14:33.633595 | TASK [Enable EPEL]
2026-03-12 00:14:33.672230 | controller | skipping: Conditional result was False
2026-03-12 00:14:33.687433 | 
2026-03-12 00:14:33.687607 | TASK [Register the RHEL node]
2026-03-12 00:14:34.258117 | 
2026-03-12 00:14:34.258386 | TASK [Show the subscription-manager status]
2026-03-12 00:14:34.845885 | controller | skipping: Conditional result was False
2026-03-12 00:14:34.855100 | 
2026-03-12 00:14:34.855197 | TASK [Enable EPEL on RHEL]
2026-03-12 00:14:35.391242 | controller | skipping: Conditional result was False
2026-03-12 00:14:35.402698 | 
2026-03-12 00:14:35.402820 | TASK [Install git and tox]
2026-03-12 00:15:54.384047 | controller | changed
2026-03-12 00:15:54.398007 | 
2026-03-12 00:15:54.398135 | TASK [include_role : prepare-workspace]
2026-03-12 00:15:54.438823 | controller | ok
2026-03-12 00:15:54.479926 | 
2026-03-12 00:15:54.480058 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-12 00:15:54.726926 | controller | ok
2026-03-12 00:15:54.740860 | 
2026-03-12 00:15:54.740999 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-12 00:16:08.113565 | controller | Output suppressed because no_log was given
2026-03-12 00:16:08.166402 | 
2026-03-12 00:16:08.166526 | TASK [include_role : prepare-workspace-openshift]
2026-03-12 00:16:08.206967 | controller | skipping: Conditional result was False
2026-03-12 00:16:08.230933 | 
2026-03-12 00:16:08.231028 | PLAY [all:!appliance]
2026-03-12 00:16:08.250820 | 
2026-03-12 00:16:08.250935 | TASK [Run add-build-sshkey role (RSA)]
2026-03-12 00:16:08.280694 | controller | ok
2026-03-12 00:16:08.296213 | 
2026-03-12 00:16:08.296313 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-12 00:16:08.526279 | controller -> localhost | ok
2026-03-12 00:16:08.533754 | 
2026-03-12 00:16:08.533866 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-12 00:16:08.554762 | controller | ok
2026-03-12 00:16:08.574290 | controller | included: /var/lib/zuul/builds/579d2d90e02942598c0725dae617956b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-12 00:16:08.581067 | 
2026-03-12 00:16:08.581146 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-12 00:16:09.118198 | controller -> localhost | Generating public/private rsa key pair.
2026-03-12 00:16:09.118424 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/579d2d90e02942598c0725dae617956b/work/579d2d90e02942598c0725dae617956b_id_rsa.
2026-03-12 00:16:09.118456 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/579d2d90e02942598c0725dae617956b/work/579d2d90e02942598c0725dae617956b_id_rsa.pub.
2026-03-12 00:16:09.118478 | controller -> localhost | The key fingerprint is:
2026-03-12 00:16:09.118497 | controller -> localhost | SHA256:GB9qygwUPe6PWXvjSYtSrSSLdvkmDsU08oewrbSwTww zuul-build-sshkey
2026-03-12 00:16:09.118515 | controller -> localhost | The key's randomart image is:
2026-03-12 00:16:09.118533 | controller -> localhost | +---[RSA 2048]----+
2026-03-12 00:16:09.118552 | controller -> localhost | |  ..             |
2026-03-12 00:16:09.118571 | controller -> localhost | |   .o            |
2026-03-12 00:16:09.118589 | controller -> localhost | |  +.o.. .        |
2026-03-12 00:16:09.118607 | controller -> localhost | | . O.o = .       |
2026-03-12 00:16:09.118625 | controller -> localhost | |E +.* =.S        |
2026-03-12 00:16:09.118654 | controller -> localhost | | * Bo++ .        |
2026-03-12 00:16:09.118717 | controller -> localhost | |. *.+@ o.        |
2026-03-12 00:16:09.118751 | controller -> localhost | | oo.O =ooo       |
2026-03-12 00:16:09.118774 | controller -> localhost | | ..o.=oo+.       |
2026-03-12 00:16:09.118796 | controller -> localhost | +----[SHA256]-----+
2026-03-12 00:16:09.118849 | controller -> localhost | ok: Runtime: 0:00:00.022613
2026-03-12 00:16:09.126733 | 
2026-03-12 00:16:09.126820 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-12 00:16:09.159086 | controller | ok
2026-03-12 00:16:09.170896 | controller | included: /var/lib/zuul/builds/579d2d90e02942598c0725dae617956b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-12 00:16:09.181063 | 
2026-03-12 00:16:09.181142 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-12 00:16:09.215686 | controller | skipping: Conditional result was False
2026-03-12 00:16:09.224562 | 
2026-03-12 00:16:09.224693 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-12 00:16:09.684278 | controller | changed
2026-03-12 00:16:09.689817 | 
2026-03-12 00:16:09.689881 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-12 00:16:09.905912 | controller | ok
2026-03-12 00:16:09.912420 | 
2026-03-12 00:16:09.912493 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-12 00:16:10.528203 | controller | changed
2026-03-12 00:16:10.538237 | 
2026-03-12 00:16:10.538375 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-12 00:16:11.185759 | controller | changed
2026-03-12 00:16:11.192087 | 
2026-03-12 00:16:11.192158 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-12 00:16:11.216119 | controller | skipping: Conditional result was False
2026-03-12 00:16:11.224148 | 
2026-03-12 00:16:11.224266 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-12 00:16:11.662293 | controller -> localhost | changed
2026-03-12 00:16:11.679729 | 
2026-03-12 00:16:11.679859 | TASK [add-build-sshkey : Add back temp key]
2026-03-12 00:16:12.016864 | controller -> localhost | Identity added: /var/lib/zuul/builds/579d2d90e02942598c0725dae617956b/work/579d2d90e02942598c0725dae617956b_id_rsa (zuul-build-sshkey)
2026-03-12 00:16:12.017120 | controller -> localhost | ok: Runtime: 0:00:00.021876
2026-03-12 00:16:12.025901 | 
2026-03-12 00:16:12.026006 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-12 00:16:12.393650 | controller | ok
2026-03-12 00:16:12.403408 | 
2026-03-12 00:16:12.403526 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-12 00:16:12.427925 | controller | skipping: Conditional result was False
2026-03-12 00:16:12.441809 | 
2026-03-12 00:16:12.441958 | TASK [Run add-build-sshkey role (ECDSA)]
2026-03-12 00:16:12.472205 | controller | ok
2026-03-12 00:16:12.488653 | 
2026-03-12 00:16:12.488775 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-12 00:16:12.727399 | controller -> localhost | ok
2026-03-12 00:16:12.737362 | 
2026-03-12 00:16:12.737490 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-12 00:16:12.781135 | controller | ok
2026-03-12 00:16:12.793147 | controller | included: /var/lib/zuul/builds/579d2d90e02942598c0725dae617956b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-12 00:16:12.799909 | 
2026-03-12 00:16:12.799978 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-12 00:16:13.090714 | controller -> localhost | Generating public/private ecdsa key pair.
2026-03-12 00:16:13.090962 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/579d2d90e02942598c0725dae617956b/work/579d2d90e02942598c0725dae617956b_id_ecdsa.
2026-03-12 00:16:13.090992 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/579d2d90e02942598c0725dae617956b/work/579d2d90e02942598c0725dae617956b_id_ecdsa.pub.
2026-03-12 00:16:13.091025 | controller -> localhost | The key fingerprint is:
2026-03-12 00:16:13.091046 | controller -> localhost | SHA256:zhsIGCZKuD+Xk1veSycBuICV4/VZDq78wp3q9a5ClW4 zuul-build-sshkey
2026-03-12 00:16:13.091066 | controller -> localhost | The key's randomart image is:
2026-03-12 00:16:13.091085 | controller -> localhost | +---[ECDSA 521]---+
2026-03-12 00:16:13.091104 | controller -> localhost | |  ..             |
2026-03-12 00:16:13.091121 | controller -> localhost | |.oo ... .        |
2026-03-12 00:16:13.091139 | controller -> localhost | |+o+o.o.*         |
2026-03-12 00:16:13.091157 | controller -> localhost | |o+.+ .*..        |
2026-03-12 00:16:13.091182 | controller -> localhost | |o ..o+  S        |
2026-03-12 00:16:13.091206 | controller -> localhost | | .  ++E+ .       |
2026-03-12 00:16:13.091225 | controller -> localhost | |  oo=++.* .      |
2026-03-12 00:16:13.091242 | controller -> localhost | |   o+*++ =       |
2026-03-12 00:16:13.091260 | controller -> localhost | |   .++oo*.       |
2026-03-12 00:16:13.091292 | controller -> localhost | +----[SHA256]-----+
2026-03-12 00:16:13.091364 | controller -> localhost | ok: Runtime: 0:00:00.008266
2026-03-12 00:16:13.098871 | 
2026-03-12 00:16:13.098940 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-12 00:16:13.142846 | controller | ok
2026-03-12 00:16:13.161908 | controller | included: /var/lib/zuul/builds/579d2d90e02942598c0725dae617956b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-12 00:16:13.176640 | 
2026-03-12 00:16:13.176763 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-12 00:16:13.201971 | controller | skipping: Conditional result was False
2026-03-12 00:16:13.212657 | 
2026-03-12 00:16:13.212788 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-12 00:16:13.501595 | controller | changed
2026-03-12 00:16:13.507856 | 
2026-03-12 00:16:13.507957 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-12 00:16:13.725431 | controller | ok
2026-03-12 00:16:13.735983 | 
2026-03-12 00:16:13.736230 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-12 00:16:14.375373 | controller | changed
2026-03-12 00:16:14.385377 | 
2026-03-12 00:16:14.385532 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-12 00:16:15.042517 | controller | changed
2026-03-12 00:16:15.050510 | 
2026-03-12 00:16:15.050602 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-12 00:16:15.076550 | controller | skipping: Conditional result was False
2026-03-12 00:16:15.119370 | 
2026-03-12 00:16:15.119509 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-12 00:16:15.378403 | controller -> localhost | changed
2026-03-12 00:16:15.408089 | 
2026-03-12 00:16:15.408287 | TASK [add-build-sshkey : Add back temp key]
2026-03-12 00:16:15.709916 | controller -> localhost | Identity added: /var/lib/zuul/builds/579d2d90e02942598c0725dae617956b/work/579d2d90e02942598c0725dae617956b_id_ecdsa (zuul-build-sshkey)
2026-03-12 00:16:15.710156 | controller -> localhost | ok: Runtime: 0:00:00.010026
2026-03-12 00:16:15.716637 | 
2026-03-12 00:16:15.716721 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-12 00:16:15.923296 | controller | ok
2026-03-12 00:16:15.934243 | 
2026-03-12 00:16:15.934372 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-12 00:16:15.970312 | controller | skipping: Conditional result was False
2026-03-12 00:16:15.990975 | 
2026-03-12 00:16:15.991096 | TASK [include_role : remove-zuul-sshkey]
2026-03-12 00:16:16.026532 | controller | skipping: Conditional result was False
2026-03-12 00:16:16.033507 | 
2026-03-12 00:16:16.033617 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-12 00:16:16.267761 | controller | ok: "logs"
2026-03-12 00:16:16.268054 | controller | ok: All items complete
2026-03-12 00:16:16.268084 | 
2026-03-12 00:16:16.495897 | controller | ok: "artifacts"
2026-03-12 00:16:16.687658 | controller | ok: "docs"
2026-03-12 00:16:16.707208 | 
2026-03-12 00:16:16.707461 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-12 00:16:16.944541 | controller | changed: "logs"
2026-03-12 00:16:17.140812 | controller | changed: "artifacts"
2026-03-12 00:16:17.326068 | controller | changed: "docs"
2026-03-12 00:16:17.367087 | 
2026-03-12 00:16:17.367200 | PLAY RECAP
2026-03-12 00:16:17.367247 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-03-12 00:16:17.367275 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-12 00:16:17.367294 | 
2026-03-12 00:16:17.492343 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-03-12 00:16:17.494360 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-12 00:16:18.084620 | 
2026-03-12 00:16:18.084760 | PLAY [all]
2026-03-12 00:16:18.108998 | 
2026-03-12 00:16:18.109156 | TASK [Install binary dependencies]
2026-03-12 00:16:18.169766 | controller | ok
2026-03-12 00:16:18.189517 | 
2026-03-12 00:16:18.189600 | TASK [bindep : Include find tasks]
2026-03-12 00:16:18.218290 | controller | ok
2026-03-12 00:16:18.226435 | controller | included: /var/lib/zuul/builds/579d2d90e02942598c0725dae617956b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-03-12 00:16:18.232851 | 
2026-03-12 00:16:18.232917 | TASK [bindep : Look for bindep.txt]
2026-03-12 00:16:18.567248 | controller | ok
2026-03-12 00:16:18.573048 | 
2026-03-12 00:16:18.573122 | TASK [bindep : Define bindep_file fact]
2026-03-12 00:16:18.602294 | controller | skipping: Conditional result was False
2026-03-12 00:16:18.612029 | 
2026-03-12 00:16:18.612150 | TASK [bindep : Look for other-requirements.txt]
2026-03-12 00:16:18.820323 | controller | ok
2026-03-12 00:16:18.825947 | 
2026-03-12 00:16:18.826026 | TASK [bindep : Define bindep_file fact]
2026-03-12 00:16:18.849841 | controller | skipping: Conditional result was False
2026-03-12 00:16:18.857013 | 
2026-03-12 00:16:18.857109 | TASK [bindep : Look for bindep fallback file]
2026-03-12 00:16:18.881236 | controller | skipping: Conditional result was False
2026-03-12 00:16:18.888873 | 
2026-03-12 00:16:18.888970 | TASK [bindep : Define bindep_file fact]
2026-03-12 00:16:18.912817 | controller | skipping: Conditional result was False
2026-03-12 00:16:18.918804 | 
2026-03-12 00:16:18.918873 | TASK [bindep : Include bindep tasks]
2026-03-12 00:16:18.947387 | controller | skipping: Conditional result was False
2026-03-12 00:16:18.953321 | 
2026-03-12 00:16:18.953395 | TASK [bindep : Include install tasks]
2026-03-12 00:16:19.015485 | controller | skipping: Conditional result was False
2026-03-12 00:16:19.021967 | 
2026-03-12 00:16:19.022080 | LOOP [bindep : Include package tasks]
2026-03-12 00:16:19.101177 | 
2026-03-12 00:16:19.101411 | TASK [Run test-setup role]
2026-03-12 00:16:19.126904 | controller | ok
2026-03-12 00:16:19.164036 | 
2026-03-12 00:16:19.164151 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-03-12 00:16:19.378631 | controller | ok
2026-03-12 00:16:19.386119 | 
2026-03-12 00:16:19.386197 | TASK [test-setup : Run tools/test-setup.sh]
2026-03-12 00:16:19.934248 | controller | skipping: Conditional result was False
2026-03-12 00:16:19.975598 | 
2026-03-12 00:16:19.975734 | PLAY RECAP
2026-03-12 00:16:19.975827 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-12 00:16:19.975861 | 
2026-03-12 00:16:20.091792 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-12 00:16:20.092831 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-03-12 00:16:20.712301 | 
2026-03-12 00:16:20.712499 | PLAY [controller]
2026-03-12 00:16:20.735411 | 
2026-03-12 00:16:20.735512 | TASK [Create the /root directory]
2026-03-12 00:18:33.018926 | controller | ERROR
2026-03-12 00:18:33.019374 | controller | {
2026-03-12 00:18:33.019429 | controller |   "msg": "Timeout (32s) waiting for privilege escalation prompt: "
2026-03-12 00:18:33.019471 | controller | }
failure
2026-03-12 00:18:33.023956 | 
2026-03-12 00:18:33.024117 | PLAY RECAP
2026-03-12 00:18:33.024175 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-03-12 00:18:33.024199 | 
2026-03-12 00:18:33.154302 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-03-12 00:18:33.155596 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-12 00:18:33.809643 | 
2026-03-12 00:18:33.809818 | PLAY [all]
2026-03-12 00:18:33.834034 | 
2026-03-12 00:18:33.834188 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-03-12 00:22:03.219832 | controller | changed: non-zero return code
2026-03-12 00:22:03.229304 | 
2026-03-12 00:22:03.229438 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-03-12 00:22:03.245714 | controller | skipping: Conditional result was False
2026-03-12 00:22:03.256324 | 
2026-03-12 00:22:03.256442 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-03-12 00:22:03.320733 | 
2026-03-12 00:22:03.320952 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-03-12 00:22:03.348608 | 
2026-03-12 00:22:03.348935 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-03-12 00:22:03.373239 | controller | skipping: Conditional result was False
2026-03-12 00:22:03.383950 | 
2026-03-12 00:22:03.384159 | LOOP [fetch-subunit-output : Generate subunit file]
2026-03-12 00:22:03.415453 | 
2026-03-12 00:22:03.415717 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-03-12 00:22:03.431758 | controller | skipping: Conditional result was False
2026-03-12 00:22:03.442620 | 
2026-03-12 00:22:03.442763 | TASK [fetch-subunit-output : Remove the temporary file]
2026-03-12 00:22:03.459364 | controller | skipping: Conditional result was False
2026-03-12 00:22:03.470689 | 
2026-03-12 00:22:03.470838 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-03-12 00:22:03.486850 | controller | skipping: Conditional result was False
2026-03-12 00:22:03.526166 | 
2026-03-12 00:22:03.526335 | PLAY RECAP
2026-03-12 00:22:03.526383 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-12 00:22:03.526403 | 
2026-03-12 00:22:03.651571 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-12 00:22:03.652568 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-03-12 00:22:04.254384 | 
2026-03-12 00:22:04.254518 | PLAY [all:!appliance*]
2026-03-12 00:22:04.277661 | 
2026-03-12 00:22:04.277828 | TASK [unregister the node]
2026-03-12 00:22:04.822503 | controller | skipping: Conditional result was False
2026-03-12 00:22:04.836145 | 
2026-03-12 00:22:04.836365 | TASK [include_role : fetch-output]
2026-03-12 00:22:04.876280 | controller | ok
2026-03-12 00:22:04.907281 | 
2026-03-12 00:22:04.907436 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-12 00:22:04.962631 | controller | skipping: Conditional result was False
2026-03-12 00:22:04.969502 | 
2026-03-12 00:22:04.969594 | TASK [fetch-output : Set log path for single node]
2026-03-12 00:22:05.008856 | controller | ok
2026-03-12 00:22:05.014462 | 
2026-03-12 00:22:05.014529 | LOOP [fetch-output : Ensure local output dirs]
2026-03-12 00:22:05.433917 | controller -> localhost | ok: "/var/lib/zuul/builds/579d2d90e02942598c0725dae617956b/work/logs"
2026-03-12 00:22:05.703935 | controller -> localhost | changed: "/var/lib/zuul/builds/579d2d90e02942598c0725dae617956b/work/artifacts"
2026-03-12 00:22:05.997106 | controller -> localhost | changed: "/var/lib/zuul/builds/579d2d90e02942598c0725dae617956b/work/docs"
2026-03-12 00:22:06.012321 | 
2026-03-12 00:22:06.012444 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-12 00:22:06.702581 | controller | changed: .d..t...... ./
2026-03-12 00:22:06.703473 | controller | changed: All items complete
2026-03-12 00:22:06.703525 | 
2026-03-12 00:22:07.226404 | controller | changed: .d..t...... ./
2026-03-12 00:22:07.767792 | controller | changed: .d..t...... ./
2026-03-12 00:22:07.793038 | 
2026-03-12 00:22:07.793178 | TASK [include_role : fetch-output-openshift]
2026-03-12 00:22:07.820552 | controller | skipping: Conditional result was False
2026-03-12 00:22:07.828531 | 
2026-03-12 00:22:07.828625 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-12 00:22:07.877169 | controller | skipping: Conditional result was False
2026-03-12 00:22:07.891154 | controller | skipping: Conditional result was False
2026-03-12 00:22:07.931171 | 
2026-03-12 00:22:07.931471 | PLAY [localhost]
2026-03-12 00:22:07.948906 | 
2026-03-12 00:22:07.949044 | TASK [Run Zuul manifest role]
2026-03-12 00:22:07.969686 | localhost | ok
2026-03-12 00:22:07.992506 | 
2026-03-12 00:22:07.992752 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-12 00:22:08.453959 | localhost | changed
2026-03-12 00:22:08.458946 | 
2026-03-12 00:22:08.459031 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-12 00:22:08.488691 | localhost | ok
2026-03-12 00:22:08.497032 | 
2026-03-12 00:22:08.497101 | TASK [Set zuul-log-path fact]
2026-03-12 00:22:08.515224 | localhost | ok
2026-03-12 00:22:08.529488 | 
2026-03-12 00:22:08.529563 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-12 00:22:08.559087 | localhost | ok
2026-03-12 00:22:08.567633 | 
2026-03-12 00:22:08.567744 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)]
2026-03-12 00:22:08.618184 | localhost | ok:
2026-03-12 00:22:08.618554 | localhost | {
2026-03-12 00:22:08.618608 | localhost |   "auth": {
2026-03-12 00:22:08.618646 | localhost |     "password": "IonWCwcuOFib&&<}wp^:[2G/",
2026-03-12 00:22:08.618726 | localhost |     "username": "9LF91O0RK7JU"
2026-03-12 00:22:08.618816 | localhost |   },
2026-03-12 00:22:08.618857 | localhost |   "profile": "rackspace",
2026-03-12 00:22:08.618892 | localhost |   "region_name": "IAD"
2026-03-12 00:22:08.618925 | localhost | }
2026-03-12 00:22:08.618986 | 
2026-03-12 00:22:08.625895 | 
2026-03-12 00:22:08.626028 | TASK [Run upload-logs-failover role (S3 then Swift)]
2026-03-12 00:22:08.644269 | localhost | Output suppressed because no_log was given
2026-03-12 00:22:08.657498 | 
2026-03-12 00:22:08.657574 | TASK [upload-logs-failover : Initialize control vars]
2026-03-12 00:22:08.676810 | localhost | ok
2026-03-12 00:22:08.682736 | 
2026-03-12 00:22:08.682851 | LOOP [upload-logs-failover : Perform log upload]
2026-03-12 00:22:08.753090 | localhost | Output suppressed because no_log was given
2026-03-12 00:22:08.763643 | localhost | included: /var/lib/zuul/builds/579d2d90e02942598c0725dae617956b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml
2026-03-12 00:22:08.773388 | 
2026-03-12 00:22:08.773483 | LOOP [upload-logs-failover : Unpack vars for swift upload]
2026-03-12 00:22:08.871204 | localhost | Output suppressed because no_log was given
2026-03-12 00:22:08.885168 | 
2026-03-12 00:22:08.885364 | TASK [Upload logs to swift]
2026-03-12 00:22:08.922700 | localhost | ok
2026-03-12 00:22:08.962103 | 
2026-03-12 00:22:08.962264 | TASK [Set zuul-log-path fact]
2026-03-12 00:22:08.998239 | localhost | skipping: Conditional result was False
2026-03-12 00:22:09.006136 | 
2026-03-12 00:22:09.006232 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-03-12 00:22:09.518080 | localhost -> localhost | ok: Runtime: 0:00:00.008316
2026-03-12 00:22:09.536574 | 
2026-03-12 00:22:09.536825 | TASK [upload-logs-swift : Upload logs to swift]