2025-12-16 00:12:06.651881 | Job console starting...
2025-12-16 00:12:06.663264 | Updating repositories
2025-12-16 00:12:06.810401 | Preparing job workspace
2025-12-16 00:12:12.037231 | Running Ansible setup...
2025-12-16 00:12:16.909293 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-12-16 00:12:17.586105 | 
2025-12-16 00:12:17.586236 | PLAY [localhost]
2025-12-16 00:12:17.594660 | 
2025-12-16 00:12:17.594747 | TASK [Gathering Facts]
2025-12-16 00:12:18.702811 | localhost | ok
2025-12-16 00:12:18.738884 | 
2025-12-16 00:12:18.739068 | TASK [Setup log path fact]
2025-12-16 00:12:18.769618 | localhost | ok
2025-12-16 00:12:18.789123 | 
2025-12-16 00:12:18.789273 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-16 00:12:18.828833 | localhost | ok
2025-12-16 00:12:18.836416 | 
2025-12-16 00:12:18.836489 | TASK [emit-job-header : Print job information]
2025-12-16 00:12:18.900484 | # Job Information
2025-12-16 00:12:18.900786 | Ansible Version: 2.15.12
2025-12-16 00:12:18.900854 | Job: ansible-test-sanity-docker-stable-2.9
2025-12-16 00:12:18.900907 | Pipeline: periodic
2025-12-16 00:12:18.900949 | Executor: ze02.softwarefactory-project.io
2025-12-16 00:12:18.900987 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-12-16 00:12:18.901032 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/bfb/ansible/bfbae78b58bf456cbd3d68f6c55fe35f/
2025-12-16 00:12:18.901072 | Event ID: 6301a3cf5b5644d59b3c28e66ab8aad4
2025-12-16 00:12:18.908325 | 
2025-12-16 00:12:18.908421 | LOOP [emit-job-header : Print node information]
2025-12-16 00:12:19.031713 | localhost | ok:
2025-12-16 00:12:19.031996 | localhost | # Node Information
2025-12-16 00:12:19.032055 | localhost | Inventory Hostname: controller
2025-12-16 00:12:19.032100 | localhost | Hostname: np0005560928
2025-12-16 00:12:19.032143 | localhost | Username: zuul
2025-12-16 00:12:19.032192 | localhost | Distro: Fedora 37
2025-12-16 00:12:19.032241 | localhost | Provider: ansible-vexxhost-ca-ymq-1
2025-12-16 00:12:19.032283 | localhost | Region: ca-ymq-1
2025-12-16 00:12:19.032322 | localhost | Label: ansible-fedora-37-1vcpu
2025-12-16 00:12:19.032360 | localhost | Product Name: OpenStack Nova
2025-12-16 00:12:19.032398 | localhost | Interface IP: 162.253.55.221
2025-12-16 00:12:19.045463 | 
2025-12-16 00:12:19.045592 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-16 00:12:19.517959 | localhost -> localhost | changed
2025-12-16 00:12:19.524561 | 
2025-12-16 00:12:19.524657 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-16 00:12:20.489568 | localhost -> localhost | changed
2025-12-16 00:12:20.510588 | 
2025-12-16 00:12:20.510683 | PLAY [all:!appliance*]
2025-12-16 00:12:20.527033 | 
2025-12-16 00:12:20.527115 | TASK [include_role : start-zuul-console]
2025-12-16 00:12:20.560597 | controller | ok
2025-12-16 00:12:20.579317 | 
2025-12-16 00:12:20.579415 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-16 00:12:20.963493 | controller | ok
2025-12-16 00:12:20.987573 | 
2025-12-16 00:12:20.987757 | TASK [use-our-mirror : Retrieve the IP address]
2025-12-16 00:12:21.747289 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-12-16 00:12:21.763903 | 
2025-12-16 00:12:21.764073 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-12-16 00:12:22.311629 | controller | skipping: Conditional result was False
2025-12-16 00:12:22.318559 | 
2025-12-16 00:12:22.318646 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-12-16 00:12:22.342532 | controller | skipping: Conditional result was False
2025-12-16 00:12:22.349112 | 
2025-12-16 00:12:22.349217 | TASK [use-our-mirror : Create the podman configuration directory]
2025-12-16 00:12:22.363643 | controller | skipping: Conditional result was False
2025-12-16 00:12:22.371598 | 
2025-12-16 00:12:22.371714 | TASK [use-our-mirror : Copy the podman configuration]
2025-12-16 00:12:22.385608 | controller | skipping: Conditional result was False
2025-12-16 00:12:22.392706 | 
2025-12-16 00:12:22.392793 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-12-16 00:12:22.406482 | controller | skipping: Conditional result was False
2025-12-16 00:12:22.414009 | 
2025-12-16 00:12:22.414154 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-12-16 00:12:22.429787 | controller | skipping: Conditional result was False
2025-12-16 00:12:22.444029 | 
2025-12-16 00:12:22.444160 | TASK [Disable Fedora Modular]
2025-12-16 00:12:25.777574 | controller | changed
2025-12-16 00:12:25.784251 | 
2025-12-16 00:12:25.784316 | TASK [Enable EPEL]
2025-12-16 00:12:25.808198 | controller | skipping: Conditional result was False
2025-12-16 00:12:25.814853 | 
2025-12-16 00:12:25.814934 | TASK [Register the RHEL node]
2025-12-16 00:12:26.378905 | 
2025-12-16 00:12:26.379067 | TASK [Show the subscription-manager status]
2025-12-16 00:12:26.908644 | controller | skipping: Conditional result was False
2025-12-16 00:12:26.922582 | 
2025-12-16 00:12:26.922776 | TASK [Enable EPEL on RHEL]
2025-12-16 00:12:27.474403 | controller | skipping: Conditional result was False
2025-12-16 00:12:27.485685 | 
2025-12-16 00:12:27.485826 | TASK [Install git and tox]
2025-12-16 00:13:50.450739 | controller | changed
2025-12-16 00:13:50.466301 | 
2025-12-16 00:13:50.466476 | TASK [include_role : prepare-workspace]
2025-12-16 00:13:50.520132 | controller | ok
2025-12-16 00:13:50.558625 | 
2025-12-16 00:13:50.558750 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-16 00:13:50.835113 | controller | ok
2025-12-16 00:13:50.849327 | 
2025-12-16 00:13:50.849460 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-16 00:20:43.208346 | controller | Output suppressed because no_log was given
2025-12-16 00:20:43.234905 | 
2025-12-16 00:20:43.235107 | TASK [include_role : prepare-workspace-openshift]
2025-12-16 00:20:43.279268 | controller | skipping: Conditional result was False
2025-12-16 00:20:43.324972 | 
2025-12-16 00:20:43.325091 | PLAY [all:!appliance]
2025-12-16 00:20:43.350213 | 
2025-12-16 00:20:43.350353 | TASK [Run add-build-sshkey role (RSA)]
2025-12-16 00:20:43.384488 | controller | ok
2025-12-16 00:20:43.406723 | 
2025-12-16 00:20:43.406853 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-16 00:20:43.748744 | controller -> localhost | ok
2025-12-16 00:20:43.765801 | 
2025-12-16 00:20:43.765975 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-16 00:20:43.806954 | controller | ok
2025-12-16 00:20:43.839445 | controller | included: /var/lib/zuul/builds/bfbae78b58bf456cbd3d68f6c55fe35f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-16 00:20:43.851573 | 
2025-12-16 00:20:43.851697 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-16 00:20:44.426633 | controller -> localhost | Generating public/private rsa key pair.
2025-12-16 00:20:44.426867 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/bfbae78b58bf456cbd3d68f6c55fe35f/work/bfbae78b58bf456cbd3d68f6c55fe35f_id_rsa.
2025-12-16 00:20:44.426896 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/bfbae78b58bf456cbd3d68f6c55fe35f/work/bfbae78b58bf456cbd3d68f6c55fe35f_id_rsa.pub.
2025-12-16 00:20:44.426916 | controller -> localhost | The key fingerprint is:
2025-12-16 00:20:44.426934 | controller -> localhost | SHA256:5PIKZqjAZVF8UKAVRTRgfs+037cvw3mxWrFXlIWQeAg zuul-build-sshkey
2025-12-16 00:20:44.426952 | controller -> localhost | The key's randomart image is:
2025-12-16 00:20:44.426970 | controller -> localhost | +---[RSA 2048]----+
2025-12-16 00:20:44.426987 | controller -> localhost | |    .OX*E. o.o ..|
2025-12-16 00:20:44.427005 | controller -> localhost | |    *. .. o o . o|
2025-12-16 00:20:44.427023 | controller -> localhost | |   o ..... .   ..|
2025-12-16 00:20:44.427040 | controller -> localhost | |    . .o+ .    . |
2025-12-16 00:20:44.427057 | controller -> localhost | |   o  . S+     ..|
2025-12-16 00:20:44.427084 | controller -> localhost | |. o.   o  . .  .+|
2025-12-16 00:20:44.427106 | controller -> localhost | |... +   .  . o =+|
2025-12-16 00:20:44.427125 | controller -> localhost | |.. o . .      B.+|
2025-12-16 00:20:44.427142 | controller -> localhost | |.     .      ..=o|
2025-12-16 00:20:44.427162 | controller -> localhost | +----[SHA256]-----+
2025-12-16 00:20:44.427211 | controller -> localhost | ok: Runtime: 0:00:00.092114
2025-12-16 00:20:44.435041 | 
2025-12-16 00:20:44.435207 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-16 00:20:44.456793 | controller | ok
2025-12-16 00:20:44.467358 | controller | included: /var/lib/zuul/builds/bfbae78b58bf456cbd3d68f6c55fe35f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-16 00:20:44.477876 | 
2025-12-16 00:20:44.478015 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-16 00:20:44.502887 | controller | skipping: Conditional result was False
2025-12-16 00:20:44.509512 | 
2025-12-16 00:20:44.509583 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-16 00:20:45.132381 | controller | changed
2025-12-16 00:20:45.146563 | 
2025-12-16 00:20:45.146775 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-16 00:20:45.391035 | controller | ok
2025-12-16 00:20:45.406279 | 
2025-12-16 00:20:45.406466 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-16 00:20:46.071344 | controller | changed
2025-12-16 00:20:46.086656 | 
2025-12-16 00:20:46.086842 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-16 00:20:46.755878 | controller | changed
2025-12-16 00:20:46.775927 | 
2025-12-16 00:20:46.776306 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-16 00:20:46.805114 | controller | skipping: Conditional result was False
2025-12-16 00:20:46.823590 | 
2025-12-16 00:20:46.823790 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-16 00:20:47.249655 | controller -> localhost | changed
2025-12-16 00:20:47.276827 | 
2025-12-16 00:20:47.276992 | TASK [add-build-sshkey : Add back temp key]
2025-12-16 00:20:47.648134 | controller -> localhost | Identity added: /var/lib/zuul/builds/bfbae78b58bf456cbd3d68f6c55fe35f/work/bfbae78b58bf456cbd3d68f6c55fe35f_id_rsa (zuul-build-sshkey)
2025-12-16 00:20:47.648426 | controller -> localhost | ok: Runtime: 0:00:00.023597
2025-12-16 00:20:47.661083 | 
2025-12-16 00:20:47.661220 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-16 00:20:47.957350 | controller | ok
2025-12-16 00:20:47.970370 | 
2025-12-16 00:20:47.970510 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-16 00:20:48.009734 | controller | skipping: Conditional result was False
2025-12-16 00:20:48.037942 | 
2025-12-16 00:20:48.038109 | TASK [Run add-build-sshkey role (ECDSA)]
2025-12-16 00:20:48.074918 | controller | ok
2025-12-16 00:20:48.097162 | 
2025-12-16 00:20:48.097255 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-16 00:20:48.379385 | controller -> localhost | ok
2025-12-16 00:20:48.394769 | 
2025-12-16 00:20:48.395015 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-16 00:20:48.427883 | controller | ok
2025-12-16 00:20:48.452263 | controller | included: /var/lib/zuul/builds/bfbae78b58bf456cbd3d68f6c55fe35f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-16 00:20:48.463980 | 
2025-12-16 00:20:48.464075 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-16 00:20:48.798933 | controller -> localhost | Generating public/private ecdsa key pair.
2025-12-16 00:20:48.799216 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/bfbae78b58bf456cbd3d68f6c55fe35f/work/bfbae78b58bf456cbd3d68f6c55fe35f_id_ecdsa.
2025-12-16 00:20:48.799274 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/bfbae78b58bf456cbd3d68f6c55fe35f/work/bfbae78b58bf456cbd3d68f6c55fe35f_id_ecdsa.pub.
2025-12-16 00:20:48.799332 | controller -> localhost | The key fingerprint is:
2025-12-16 00:20:48.799376 | controller -> localhost | SHA256:l0oE5BeqwvrEVt7Yb6deM30ci/lbg9LJXNgveuCr5y8 zuul-build-sshkey
2025-12-16 00:20:48.799415 | controller -> localhost | The key's randomart image is:
2025-12-16 00:20:48.799454 | controller -> localhost | +---[ECDSA 521]---+
2025-12-16 00:20:48.799493 | controller -> localhost | |     .o .        |
2025-12-16 00:20:48.799531 | controller -> localhost | |     . o .       |
2025-12-16 00:20:48.799569 | controller -> localhost | |      o o        |
2025-12-16 00:20:48.799606 | controller -> localhost | | .   . o   .  o  |
2025-12-16 00:20:48.799643 | controller -> localhost | |  o o   S o  ..o |
2025-12-16 00:20:48.799720 | controller -> localhost | | o + + . o .=++o.|
2025-12-16 00:20:48.799762 | controller -> localhost | |. + o o . +o+B=.o|
2025-12-16 00:20:48.799798 | controller -> localhost | | +     ....oE+.o.|
2025-12-16 00:20:48.799833 | controller -> localhost | |  .    o+o.++=+. |
2025-12-16 00:20:48.799869 | controller -> localhost | +----[SHA256]-----+
2025-12-16 00:20:48.799954 | controller -> localhost | ok: Runtime: 0:00:00.010302
2025-12-16 00:20:48.814938 | 
2025-12-16 00:20:48.815076 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-16 00:20:48.844983 | controller | ok
2025-12-16 00:20:48.862051 | controller | included: /var/lib/zuul/builds/bfbae78b58bf456cbd3d68f6c55fe35f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-16 00:20:48.875528 | 
2025-12-16 00:20:48.875615 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-16 00:20:48.901448 | controller | skipping: Conditional result was False
2025-12-16 00:20:48.911901 | 
2025-12-16 00:20:48.911989 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-16 00:20:49.223252 | controller | changed
2025-12-16 00:20:49.232136 | 
2025-12-16 00:20:49.232285 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-16 00:20:49.469149 | controller | ok
2025-12-16 00:20:49.475395 | 
2025-12-16 00:20:49.475469 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-16 00:20:50.135092 | controller | changed
2025-12-16 00:20:50.147966 | 
2025-12-16 00:20:50.148114 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-16 00:20:50.820968 | controller | changed
2025-12-16 00:20:50.833421 | 
2025-12-16 00:20:50.833565 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-16 00:20:50.850766 | controller | skipping: Conditional result was False
2025-12-16 00:20:50.865869 | 
2025-12-16 00:20:50.866031 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-16 00:20:51.146814 | controller -> localhost | changed
2025-12-16 00:20:51.171422 | 
2025-12-16 00:20:51.171583 | TASK [add-build-sshkey : Add back temp key]
2025-12-16 00:20:51.483706 | controller -> localhost | Identity added: /var/lib/zuul/builds/bfbae78b58bf456cbd3d68f6c55fe35f/work/bfbae78b58bf456cbd3d68f6c55fe35f_id_ecdsa (zuul-build-sshkey)
2025-12-16 00:20:51.483910 | controller -> localhost | ok: Runtime: 0:00:00.007572
2025-12-16 00:20:51.490403 | 
2025-12-16 00:20:51.490464 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-16 00:20:51.700571 | controller | ok
2025-12-16 00:20:51.715533 | 
2025-12-16 00:20:51.715730 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-16 00:20:51.763146 | controller | skipping: Conditional result was False
2025-12-16 00:20:51.789126 | 
2025-12-16 00:20:51.789277 | TASK [include_role : remove-zuul-sshkey]
2025-12-16 00:20:51.815992 | controller | skipping: Conditional result was False
2025-12-16 00:20:51.829794 | 
2025-12-16 00:20:51.829955 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-16 00:20:52.077995 | controller | ok: "logs"
2025-12-16 00:20:52.078466 | controller | ok: All items complete
2025-12-16 00:20:52.078532 | 
2025-12-16 00:20:52.275641 | controller | ok: "artifacts"
2025-12-16 00:20:52.474189 | controller | ok: "docs"
2025-12-16 00:20:52.495427 | 
2025-12-16 00:20:52.495625 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-16 00:20:52.751365 | controller | changed: "logs"
2025-12-16 00:20:52.952774 | controller | changed: "artifacts"
2025-12-16 00:20:53.142790 | controller | changed: "docs"
2025-12-16 00:20:53.203396 | 
2025-12-16 00:20:53.203575 | PLAY RECAP
2025-12-16 00:20:53.203705 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-12-16 00:20:53.203765 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-16 00:20:53.203801 | 
2025-12-16 00:20:53.326962 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-12-16 00:20:53.329004 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-16 00:20:53.971576 | 
2025-12-16 00:20:53.971710 | PLAY [all]
2025-12-16 00:20:54.001185 | 
2025-12-16 00:20:54.001328 | TASK [Install binary dependencies]
2025-12-16 00:20:54.065529 | controller | ok
2025-12-16 00:20:54.093413 | 
2025-12-16 00:20:54.093573 | TASK [bindep : Include find tasks]
2025-12-16 00:20:54.137290 | controller | ok
2025-12-16 00:20:54.148845 | controller | included: /var/lib/zuul/builds/bfbae78b58bf456cbd3d68f6c55fe35f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-12-16 00:20:54.157921 | 
2025-12-16 00:20:54.158016 | TASK [bindep : Look for bindep.txt]
2025-12-16 00:20:54.716732 | controller | ok
2025-12-16 00:20:54.730257 | 
2025-12-16 00:20:54.730418 | TASK [bindep : Define bindep_file fact]
2025-12-16 00:20:54.768903 | controller | skipping: Conditional result was False
2025-12-16 00:20:54.782898 | 
2025-12-16 00:20:54.783055 | TASK [bindep : Look for other-requirements.txt]
2025-12-16 00:20:54.998532 | controller | ok
2025-12-16 00:20:55.004320 | 
2025-12-16 00:20:55.004385 | TASK [bindep : Define bindep_file fact]
2025-12-16 00:20:55.027742 | controller | skipping: Conditional result was False
2025-12-16 00:20:55.034132 | 
2025-12-16 00:20:55.034200 | TASK [bindep : Look for bindep fallback file]
2025-12-16 00:20:55.068526 | controller | skipping: Conditional result was False
2025-12-16 00:20:55.087192 | 
2025-12-16 00:20:55.087370 | TASK [bindep : Define bindep_file fact]
2025-12-16 00:20:55.116021 | controller | skipping: Conditional result was False
2025-12-16 00:20:55.130872 | 
2025-12-16 00:20:55.131017 | TASK [bindep : Include bindep tasks]
2025-12-16 00:20:55.170929 | controller | skipping: Conditional result was False
2025-12-16 00:20:55.186093 | 
2025-12-16 00:20:55.186239 | TASK [bindep : Include install tasks]
2025-12-16 00:20:55.224238 | controller | skipping: Conditional result was False
2025-12-16 00:20:55.238352 | 
2025-12-16 00:20:55.238493 | LOOP [bindep : Include package tasks]
2025-12-16 00:20:55.335443 | 
2025-12-16 00:20:55.335788 | TASK [Run test-setup role]
2025-12-16 00:20:55.368984 | controller | ok
2025-12-16 00:20:55.400931 | 
2025-12-16 00:20:55.401033 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-12-16 00:20:55.633810 | controller | ok
2025-12-16 00:20:55.647260 | 
2025-12-16 00:20:55.647415 | TASK [test-setup : Run tools/test-setup.sh]
2025-12-16 00:20:56.200439 | controller | skipping: Conditional result was False
2025-12-16 00:20:56.255717 | 
2025-12-16 00:20:56.255812 | PLAY RECAP
2025-12-16 00:20:56.255863 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-16 00:20:56.255889 | 
2025-12-16 00:20:56.373435 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-16 00:20:56.375350 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-12-16 00:20:57.047997 | 
2025-12-16 00:20:57.048140 | PLAY [controller]
2025-12-16 00:20:57.068604 | 
2025-12-16 00:20:57.068703 | TASK [Create the /root directory]
2025-12-16 00:20:57.679277 | controller | ok
2025-12-16 00:20:57.693476 | 
2025-12-16 00:20:57.693703 | TASK [Install glibc-langpack-en]
2025-12-16 00:21:01.669162 | controller | ok: Nothing to do
2025-12-16 00:21:01.682828 | 
2025-12-16 00:21:01.682981 | TASK [Ensure controller directory exists]
2025-12-16 00:21:01.907292 | controller | changed
2025-12-16 00:21:01.921103 | 
2025-12-16 00:21:01.921279 | TASK [Install container runtime]
2025-12-16 00:21:02.002890 | controller | ok
2025-12-16 00:21:02.071616 | 
2025-12-16 00:21:02.071759 | LOOP [ensure-docker : Gather variables for each operating system]
2025-12-16 00:21:02.117429 | controller | ok: "/var/lib/zuul/builds/bfbae78b58bf456cbd3d68f6c55fe35f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2025-12-16 00:21:02.135332 | 
2025-12-16 00:21:02.135466 | TASK [ensure-docker : Sanity check for distro selection]
2025-12-16 00:21:02.159914 | controller | skipping: Conditional result was False
2025-12-16 00:21:02.166340 | 
2025-12-16 00:21:02.166421 | TASK [ensure-docker : Sanity check for distro install]
2025-12-16 00:21:02.190083 | controller | skipping: Conditional result was False
2025-12-16 00:21:02.196909 | 
2025-12-16 00:21:02.196991 | TASK [ensure-docker : Sanity check for upstream install]
2025-12-16 00:21:02.221676 | controller | skipping: Conditional result was False
2025-12-16 00:21:02.228743 | 
2025-12-16 00:21:02.228815 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2025-12-16 00:21:02.252217 | controller | skipping: Conditional result was False
2025-12-16 00:21:02.259824 | 
2025-12-16 00:21:02.259897 | TASK [ensure-docker : Include tasks for docker install]
2025-12-16 00:21:02.289152 | controller | ok
2025-12-16 00:21:02.298313 | controller | included: /var/lib/zuul/builds/bfbae78b58bf456cbd3d68f6c55fe35f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2025-12-16 00:21:02.306360 | 
2025-12-16 00:21:02.306427 | TASK [ensure-docker : Not Implemented]
2025-12-16 00:21:02.320166 | controller | skipping: Conditional result was False
2025-12-16 00:21:02.327182 | 
2025-12-16 00:21:02.327253 | TASK [Set up docker mirrors]
2025-12-16 00:21:02.348487 | controller | ok
2025-12-16 00:21:02.370592 | 
2025-12-16 00:21:02.370693 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2025-12-16 00:21:02.394727 | controller | skipping: Conditional result was False
2025-12-16 00:21:02.401128 | 
2025-12-16 00:21:02.401197 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2025-12-16 00:21:02.424844 | controller | skipping: Conditional result was False
2025-12-16 00:21:02.431853 | 
2025-12-16 00:21:02.431923 | TASK [use-docker-mirror : Set docker_mirror fact]
2025-12-16 00:21:02.455513 | controller | skipping: Conditional result was False
2025-12-16 00:21:02.462092 | 
2025-12-16 00:21:02.462160 | TASK [use-docker-mirror : Set up docker mirrors]
2025-12-16 00:21:02.485858 | controller | skipping: Conditional result was False
2025-12-16 00:21:02.504278 | 
2025-12-16 00:21:02.504388 | TASK [Add all repositories]
2025-12-16 00:21:02.526942 | controller | ok
2025-12-16 00:21:02.574172 | 
2025-12-16 00:21:02.574282 | LOOP [ensure-package-repositories : Install repositories]
2025-12-16 00:21:02.603890 | controller | ok: "/var/lib/zuul/builds/bfbae78b58bf456cbd3d68f6c55fe35f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2025-12-16 00:21:02.604390 | controller | ok: All items complete
2025-12-16 00:21:02.604459 | 
2025-12-16 00:21:02.662409 | controller | included: /var/lib/zuul/builds/bfbae78b58bf456cbd3d68f6c55fe35f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2025-12-16 00:21:02.669020 | 
2025-12-16 00:21:02.669083 | TASK [ensure-package-repositories : Create GPG key temp directory]
2025-12-16 00:21:02.996595 | controller | changed
2025-12-16 00:21:03.005203 | 
2025-12-16 00:21:03.005289 | LOOP [ensure-package-repositories : Upload GPG keys]
2025-12-16 00:26:51.774901 | controller | ERROR
2025-12-16 00:26:51.777827 | controller | {
2025-12-16 00:26:51.777919 | controller |   "msg": "Timeout (32s) waiting for privilege escalation prompt: "
2025-12-16 00:26:51.777944 | controller | }
failure
2025-12-16 00:26:51.780103 | 
2025-12-16 00:26:51.780154 | PLAY RECAP
2025-12-16 00:26:51.780199 | controller | ok: 7 changed: 2 unreachable: 0 failed: 1 skipped: 9 rescued: 0 ignored: 0
2025-12-16 00:26:51.780220 | 
2025-12-16 00:26:51.896326 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-12-16 00:26:51.897320 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-16 00:26:52.502197 | 
2025-12-16 00:26:52.502302 | PLAY [all]
2025-12-16 00:26:52.523404 | 
2025-12-16 00:26:52.523489 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-12-16 00:26:53.056337 | controller | changed: non-zero return code
2025-12-16 00:26:53.065952 | 
2025-12-16 00:26:53.066138 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-12-16 00:26:53.093466 | controller | skipping: Conditional result was False
2025-12-16 00:26:53.100208 | 
2025-12-16 00:26:53.100288 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-12-16 00:26:53.132422 | 
2025-12-16 00:26:53.132595 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-12-16 00:26:53.163129 | 
2025-12-16 00:26:53.163265 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-12-16 00:26:53.186966 | controller | skipping: Conditional result was False
2025-12-16 00:26:53.194897 | 
2025-12-16 00:26:53.195000 | LOOP [fetch-subunit-output : Generate subunit file]
2025-12-16 00:26:53.215705 | 
2025-12-16 00:26:53.215845 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-12-16 00:26:53.229558 | controller | skipping: Conditional result was False
2025-12-16 00:26:53.236120 | 
2025-12-16 00:26:53.236189 | TASK [fetch-subunit-output : Remove the temporary file]
2025-12-16 00:26:53.249869 | controller | skipping: Conditional result was False
2025-12-16 00:26:53.256291 | 
2025-12-16 00:26:53.256359 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-12-16 00:26:53.270349 | controller | skipping: Conditional result was False
2025-12-16 00:26:53.297266 | 
2025-12-16 00:26:53.297334 | PLAY RECAP
2025-12-16 00:26:53.297376 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-16 00:26:53.297396 | 
2025-12-16 00:26:53.397788 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-16 00:26:53.398680 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-12-16 00:26:53.969313 | 
2025-12-16 00:26:53.969450 | PLAY [all:!appliance*]
2025-12-16 00:26:54.007327 | 
2025-12-16 00:26:54.007453 | TASK [unregister the node]
2025-12-16 00:26:54.542990 | controller | skipping: Conditional result was False
2025-12-16 00:26:54.548967 | 
2025-12-16 00:26:54.549051 | TASK [include_role : fetch-output]
2025-12-16 00:26:54.599243 | controller | ok
2025-12-16 00:26:54.622930 | 
2025-12-16 00:26:54.623054 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-16 00:26:54.667633 | controller | skipping: Conditional result was False
2025-12-16 00:26:54.673805 | 
2025-12-16 00:26:54.673878 | TASK [fetch-output : Set log path for single node]
2025-12-16 00:26:54.703350 | controller | ok
2025-12-16 00:26:54.708968 | 
2025-12-16 00:26:54.709044 | LOOP [fetch-output : Ensure local output dirs]
2025-12-16 00:26:55.136320 | controller -> localhost | ok: "/var/lib/zuul/builds/bfbae78b58bf456cbd3d68f6c55fe35f/work/logs"
2025-12-16 00:26:55.390088 | controller -> localhost | changed: "/var/lib/zuul/builds/bfbae78b58bf456cbd3d68f6c55fe35f/work/artifacts"
2025-12-16 00:26:55.626749 | controller -> localhost | changed: "/var/lib/zuul/builds/bfbae78b58bf456cbd3d68f6c55fe35f/work/docs"
2025-12-16 00:26:55.637077 | 
2025-12-16 00:26:55.637194 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-16 00:26:56.331701 | controller | changed:
2025-12-16 00:26:56.331908 | controller | .d..t...... ./
2025-12-16 00:26:56.331937 | controller | cd+++++++++ controller/
2025-12-16 00:26:56.331968 | controller | changed: All items complete
2025-12-16 00:26:56.331989 | 
2025-12-16 00:26:56.901447 | controller | changed: .d..t...... ./
2025-12-16 00:26:57.395604 | controller | changed: .d..t...... ./
2025-12-16 00:26:57.438497 | 
2025-12-16 00:26:57.438733 | TASK [include_role : fetch-output-openshift]
2025-12-16 00:26:57.464863 | controller | skipping: Conditional result was False
2025-12-16 00:26:57.475228 | 
2025-12-16 00:26:57.475334 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-16 00:26:57.510215 | controller | skipping: Conditional result was False
2025-12-16 00:26:57.522130 | controller | skipping: Conditional result was False
2025-12-16 00:26:57.582591 | 
2025-12-16 00:26:57.582795 | PLAY [localhost]
2025-12-16 00:26:57.604334 | 
2025-12-16 00:26:57.604493 | TASK [Run Zuul manifest role]
2025-12-16 00:26:57.627222 | localhost | ok
2025-12-16 00:26:57.657951 | 
2025-12-16 00:26:57.658072 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-16 00:26:58.143547 | localhost | changed
2025-12-16 00:26:58.152338 | 
2025-12-16 00:26:58.152444 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-16 00:26:58.184509 | localhost | ok
2025-12-16 00:26:58.192622 | 
2025-12-16 00:26:58.192697 | TASK [Set zuul-log-path fact]
2025-12-16 00:26:58.213279 | localhost | ok
2025-12-16 00:26:58.229438 | 
2025-12-16 00:26:58.229507 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-16 00:26:58.259274 | localhost | ok
2025-12-16 00:26:58.267743 | 
2025-12-16 00:26:58.267812 | LOOP [Run upload-logs-swift role]
2025-12-16 00:26:58.304910 | localhost | Output suppressed because no_log was given
2025-12-16 00:26:58.349176 | 
2025-12-16 00:26:58.349297 | TASK [Set zuul-log-path fact]
2025-12-16 00:26:58.386342 | localhost | skipping: Conditional result was False
2025-12-16 00:26:58.391649 | 
2025-12-16 00:26:58.391730 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-12-16 00:26:58.836974 | localhost -> localhost | ok: Runtime: 0:00:00.009107
2025-12-16 00:26:58.914919 | 
2025-12-16 00:26:58.915075 | TASK [upload-logs-swift : Upload logs to swift]