2025-11-28 00:26:26.299285 | Job console starting...
2025-11-28 00:26:26.312470 | Updating repositories
2025-11-28 00:26:26.444821 | Preparing job workspace
2025-11-28 00:26:30.213055 | Running Ansible setup...
2025-11-28 00:26:35.089192 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-28 00:26:35.719836 | 
2025-11-28 00:26:35.719957 | PLAY [localhost]
2025-11-28 00:26:35.728970 | 
2025-11-28 00:26:35.729077 | TASK [Gathering Facts]
2025-11-28 00:26:36.827521 | localhost | ok
2025-11-28 00:26:36.849265 | 
2025-11-28 00:26:36.849448 | TASK [Setup log path fact]
2025-11-28 00:26:36.877548 | localhost | ok
2025-11-28 00:26:36.892016 | 
2025-11-28 00:26:36.892255 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-28 00:26:36.934593 | localhost | ok
2025-11-28 00:26:36.951181 | 
2025-11-28 00:26:36.951332 | TASK [emit-job-header : Print job information]
2025-11-28 00:26:36.991465 | # Job Information
2025-11-28 00:26:36.991610 | Ansible Version: 2.15.12
2025-11-28 00:26:36.991641 | Job: ansible-test-sanity-docker-devel
2025-11-28 00:26:36.991663 | Pipeline: periodic
2025-11-28 00:26:36.991682 | Executor: ze03.softwarefactory-project.io
2025-11-28 00:26:36.991700 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-11-28 00:26:36.991722 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/a43/ansible/a43e371395c541b48dd2653db0fcee13/
2025-11-28 00:26:36.991765 | Event ID: a5b225c8756b453caa3f2f71389276ab
2025-11-28 00:26:36.995864 | 
2025-11-28 00:26:36.995927 | LOOP [emit-job-header : Print node information]
2025-11-28 00:26:37.098683 | localhost | ok:
2025-11-28 00:26:37.098939 | localhost | # Node Information
2025-11-28 00:26:37.098977 | localhost | Inventory Hostname: controller
2025-11-28 00:26:37.098999 | localhost | Hostname: np0005538416
2025-11-28 00:26:37.099018 | localhost | Username: zuul
2025-11-28 00:26:37.099038 | localhost | Distro: Fedora 37
2025-11-28 00:26:37.099056 | localhost | Provider: ansible-vexxhost-ca-ymq-1
2025-11-28 00:26:37.099074 | localhost | Region: ca-ymq-1
2025-11-28 00:26:37.099090 | localhost | Label: ansible-fedora-37-1vcpu
2025-11-28 00:26:37.099119 | localhost | Product Name: OpenStack Nova
2025-11-28 00:26:37.099139 | localhost | Interface IP: 162.253.55.94
2025-11-28 00:26:37.110158 | 
2025-11-28 00:26:37.110317 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-28 00:26:37.530918 | localhost -> localhost | changed
2025-11-28 00:26:37.558821 | 
2025-11-28 00:26:37.558948 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-28 00:26:38.416929 | localhost -> localhost | changed
2025-11-28 00:26:38.439956 | 
2025-11-28 00:26:38.440105 | PLAY [all:!appliance*]
2025-11-28 00:26:38.465549 | 
2025-11-28 00:26:38.465690 | TASK [include_role : start-zuul-console]
2025-11-28 00:26:38.488017 | controller | ok
2025-11-28 00:26:38.503708 | 
2025-11-28 00:26:38.503841 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-28 00:26:38.872055 | controller | ok
2025-11-28 00:26:38.900275 | 
2025-11-28 00:26:38.900519 | TASK [use-our-mirror : Retrieve the IP address]
2025-11-28 00:26:39.845429 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-11-28 00:26:39.853273 | 
2025-11-28 00:26:39.853377 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-11-28 00:26:40.385260 | controller | skipping: Conditional result was False
2025-11-28 00:26:40.392689 | 
2025-11-28 00:26:40.392800 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-11-28 00:26:40.416694 | controller | skipping: Conditional result was False
2025-11-28 00:26:40.426361 | 
2025-11-28 00:26:40.426464 | TASK [use-our-mirror : Create the podman configuration directory]
2025-11-28 00:26:40.451862 | controller | skipping: Conditional result was False
2025-11-28 00:26:40.460228 | 
2025-11-28 00:26:40.460340 | TASK [use-our-mirror : Copy the podman configuration]
2025-11-28 00:26:40.485572 | controller | skipping: Conditional result was False
2025-11-28 00:26:40.494404 | 
2025-11-28 00:26:40.494478 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-11-28 00:26:40.511970 | controller | skipping: Conditional result was False
2025-11-28 00:26:40.519402 | 
2025-11-28 00:26:40.519495 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-11-28 00:26:40.548320 | controller | skipping: Conditional result was False
2025-11-28 00:26:40.560477 | 
2025-11-28 00:26:40.560550 | TASK [Disable Fedora Modular]
2025-11-28 00:26:40.821620 | controller | changed
2025-11-28 00:26:40.833868 | 
2025-11-28 00:26:40.834019 | TASK [Enable EPEL]
2025-11-28 00:26:40.861101 | controller | skipping: Conditional result was False
2025-11-28 00:26:40.873620 | 
2025-11-28 00:26:40.873786 | TASK [Register the RHEL node]
2025-11-28 00:26:41.428417 | 
2025-11-28 00:26:41.428571 | TASK [Show the subscription-manager status]
2025-11-28 00:26:41.992366 | controller | skipping: Conditional result was False
2025-11-28 00:26:42.001912 | 
2025-11-28 00:26:42.002029 | TASK [Enable EPEL on RHEL]
2025-11-28 00:26:42.538761 | controller | skipping: Conditional result was False
2025-11-28 00:26:42.545821 | 
2025-11-28 00:26:42.545923 | TASK [Install git and tox]
2025-11-28 00:28:01.167720 | controller | changed
2025-11-28 00:28:01.176906 | 
2025-11-28 00:28:01.177005 | TASK [include_role : prepare-workspace]
2025-11-28 00:28:01.202288 | controller | ok
2025-11-28 00:28:01.233458 | 
2025-11-28 00:28:01.233605 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-28 00:28:01.468138 | controller | ok
2025-11-28 00:28:01.474590 | 
2025-11-28 00:28:01.474678 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-28 00:28:13.229393 | controller | Output suppressed because no_log was given
2025-11-28 00:28:13.239812 | 
2025-11-28 00:28:13.239880 | TASK [include_role : prepare-workspace-openshift]
2025-11-28 00:28:13.263770 | controller | skipping: Conditional result was False
2025-11-28 00:28:13.325341 | 
2025-11-28 00:28:13.325458 | PLAY [all:!appliance]
2025-11-28 00:28:13.348758 | 
2025-11-28 00:28:13.348976 | TASK [Run add-build-sshkey role (RSA)]
2025-11-28 00:28:13.383877 | controller | ok
2025-11-28 00:28:13.407106 | 
2025-11-28 00:28:13.407233 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-28 00:28:13.665950 | controller -> localhost | ok
2025-11-28 00:28:13.673013 | 
2025-11-28 00:28:13.673118 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-28 00:28:13.706889 | controller | ok
2025-11-28 00:28:13.729349 | controller | included: /var/lib/zuul/builds/a43e371395c541b48dd2653db0fcee13/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-28 00:28:13.740152 | 
2025-11-28 00:28:13.740277 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-28 00:28:14.231864 | controller -> localhost | Generating public/private rsa key pair.
2025-11-28 00:28:14.232140 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/a43e371395c541b48dd2653db0fcee13/work/a43e371395c541b48dd2653db0fcee13_id_rsa.
2025-11-28 00:28:14.232183 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/a43e371395c541b48dd2653db0fcee13/work/a43e371395c541b48dd2653db0fcee13_id_rsa.pub.
2025-11-28 00:28:14.232215 | controller -> localhost | The key fingerprint is:
2025-11-28 00:28:14.232243 | controller -> localhost | SHA256:V+GjnCio21H2FI1xURqKw5+yKxWt8vcvHTxZiZXC5w4 zuul-build-sshkey
2025-11-28 00:28:14.232273 | controller -> localhost | The key's randomart image is:
2025-11-28 00:28:14.232300 | controller -> localhost | +---[RSA 2048]----+
2025-11-28 00:28:14.232327 | controller -> localhost | |        . ++o  . |
2025-11-28 00:28:14.232354 | controller -> localhost | |     . . * +o.+  |
2025-11-28 00:28:14.232380 | controller -> localhost | |      +.+ o +* . |
2025-11-28 00:28:14.232439 | controller -> localhost | |     ..o.= +E.+  |
2025-11-28 00:28:14.232470 | controller -> localhost | |    . =oS =. =   |
2025-11-28 00:28:14.232509 | controller -> localhost | |   ..oo* .  = .  |
2025-11-28 00:28:14.232559 | controller -> localhost | |  . .+. .  . o   |
2025-11-28 00:28:14.232612 | controller -> localhost | |   o..... . .    |
2025-11-28 00:28:14.232660 | controller -> localhost | |  . .... ..o.    |
2025-11-28 00:28:14.232696 | controller -> localhost | +----[SHA256]-----+
2025-11-28 00:28:14.232815 | controller -> localhost | ok: Runtime: 0:00:00.109330
2025-11-28 00:28:14.243206 | 
2025-11-28 00:28:14.243325 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-28 00:28:14.267629 | controller | ok
2025-11-28 00:28:14.282156 | controller | included: /var/lib/zuul/builds/a43e371395c541b48dd2653db0fcee13/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-28 00:28:14.294738 | 
2025-11-28 00:28:14.294873 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-28 00:28:14.320072 | controller | skipping: Conditional result was False
2025-11-28 00:28:14.327372 | 
2025-11-28 00:28:14.327473 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-28 00:28:14.790914 | controller | changed
2025-11-28 00:28:14.806168 | 
2025-11-28 00:28:14.806372 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-28 00:28:15.025463 | controller | ok
2025-11-28 00:28:15.031246 | 
2025-11-28 00:28:15.031312 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-28 00:28:15.639058 | controller | changed
2025-11-28 00:28:15.653356 | 
2025-11-28 00:28:15.653519 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-28 00:28:16.304346 | controller | changed
2025-11-28 00:28:16.311133 | 
2025-11-28 00:28:16.311247 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-28 00:28:16.336389 | controller | skipping: Conditional result was False
2025-11-28 00:28:16.345010 | 
2025-11-28 00:28:16.345088 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-28 00:28:16.746384 | controller -> localhost | changed
2025-11-28 00:28:16.765054 | 
2025-11-28 00:28:16.765183 | TASK [add-build-sshkey : Add back temp key]
2025-11-28 00:28:17.047587 | controller -> localhost | Identity added: /var/lib/zuul/builds/a43e371395c541b48dd2653db0fcee13/work/a43e371395c541b48dd2653db0fcee13_id_rsa (zuul-build-sshkey)
2025-11-28 00:28:17.048179 | controller -> localhost | ok: Runtime: 0:00:00.015652
2025-11-28 00:28:17.064475 | 
2025-11-28 00:28:17.064684 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-28 00:28:17.436396 | controller | ok
2025-11-28 00:28:17.442762 | 
2025-11-28 00:28:17.442888 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-28 00:28:17.477636 | controller | skipping: Conditional result was False
2025-11-28 00:28:17.495976 | 
2025-11-28 00:28:17.496140 | TASK [Run add-build-sshkey role (ECDSA)]
2025-11-28 00:28:17.530141 | controller | ok
2025-11-28 00:28:17.556690 | 
2025-11-28 00:28:17.556869 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-28 00:28:17.829351 | controller -> localhost | ok
2025-11-28 00:28:17.843606 | 
2025-11-28 00:28:17.843812 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-28 00:28:17.885012 | controller | ok
2025-11-28 00:28:17.908242 | controller | included: /var/lib/zuul/builds/a43e371395c541b48dd2653db0fcee13/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-28 00:28:17.918384 | 
2025-11-28 00:28:17.918539 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-28 00:28:18.271264 | controller -> localhost | Generating public/private ecdsa key pair.
2025-11-28 00:28:18.271709 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/a43e371395c541b48dd2653db0fcee13/work/a43e371395c541b48dd2653db0fcee13_id_ecdsa.
2025-11-28 00:28:18.271944 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/a43e371395c541b48dd2653db0fcee13/work/a43e371395c541b48dd2653db0fcee13_id_ecdsa.pub.
2025-11-28 00:28:18.272012 | controller -> localhost | The key fingerprint is:
2025-11-28 00:28:18.272057 | controller -> localhost | SHA256:KRGHobFbVYKTad2vkLi7Y1TZnyfvhRJH7rc0MSQR4Xc zuul-build-sshkey
2025-11-28 00:28:18.272099 | controller -> localhost | The key's randomart image is:
2025-11-28 00:28:18.272139 | controller -> localhost | +---[ECDSA 521]---+
2025-11-28 00:28:18.272177 | controller -> localhost | |    . oB+o.  +o  |
2025-11-28 00:28:18.272214 | controller -> localhost | |     +B+... . .  |
2025-11-28 00:28:18.272251 | controller -> localhost | |    o.oo + . o.oE|
2025-11-28 00:28:18.272287 | controller -> localhost | |     o..=.. .o+ .|
2025-11-28 00:28:18.272323 | controller -> localhost | |    . .oS. o..oo |
2025-11-28 00:28:18.272358 | controller -> localhost | |      o.  . ++..o|
2025-11-28 00:28:18.272395 | controller -> localhost | |     . .    .+ooo|
2025-11-28 00:28:18.272431 | controller -> localhost | |      +      .ooo|
2025-11-28 00:28:18.272467 | controller -> localhost | |     ..o     ... |
2025-11-28 00:28:18.272502 | controller -> localhost | +----[SHA256]-----+
2025-11-28 00:28:18.272604 | controller -> localhost | ok: Runtime: 0:00:00.015485
2025-11-28 00:28:18.288336 | 
2025-11-28 00:28:18.288496 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-28 00:28:18.335178 | controller | ok
2025-11-28 00:28:18.352435 | controller | included: /var/lib/zuul/builds/a43e371395c541b48dd2653db0fcee13/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-28 00:28:18.367241 | 
2025-11-28 00:28:18.367389 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-28 00:28:18.383685 | controller | skipping: Conditional result was False
2025-11-28 00:28:18.394503 | 
2025-11-28 00:28:18.394665 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-28 00:28:18.678482 | controller | changed
2025-11-28 00:28:18.693422 | 
2025-11-28 00:28:18.693592 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-28 00:28:18.926345 | controller | ok
2025-11-28 00:28:18.939137 | 
2025-11-28 00:28:18.939297 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-28 00:28:19.563611 | controller | changed
2025-11-28 00:28:19.570080 | 
2025-11-28 00:28:19.570178 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-28 00:28:20.183195 | controller | changed
2025-11-28 00:28:20.189260 | 
2025-11-28 00:28:20.189350 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-28 00:28:20.214186 | controller | skipping: Conditional result was False
2025-11-28 00:28:20.220999 | 
2025-11-28 00:28:20.221098 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-28 00:28:20.467987 | controller -> localhost | changed
2025-11-28 00:28:20.479995 | 
2025-11-28 00:28:20.480116 | TASK [add-build-sshkey : Add back temp key]
2025-11-28 00:28:20.800778 | controller -> localhost | Identity added: /var/lib/zuul/builds/a43e371395c541b48dd2653db0fcee13/work/a43e371395c541b48dd2653db0fcee13_id_ecdsa (zuul-build-sshkey)
2025-11-28 00:28:20.801072 | controller -> localhost | ok: Runtime: 0:00:00.007220
2025-11-28 00:28:20.845768 | 
2025-11-28 00:28:20.845890 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-28 00:28:21.051839 | controller | ok
2025-11-28 00:28:21.059906 | 
2025-11-28 00:28:21.060016 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-28 00:28:21.084961 | controller | skipping: Conditional result was False
2025-11-28 00:28:21.100991 | 
2025-11-28 00:28:21.101105 | TASK [include_role : remove-zuul-sshkey]
2025-11-28 00:28:21.116146 | controller | skipping: Conditional result was False
2025-11-28 00:28:21.126061 | 
2025-11-28 00:28:21.126216 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-28 00:28:21.367380 | controller | ok: "logs"
2025-11-28 00:28:21.368061 | controller | ok: All items complete
2025-11-28 00:28:21.368132 | 
2025-11-28 00:28:21.561859 | controller | ok: "artifacts"
2025-11-28 00:28:21.760193 | controller | ok: "docs"
2025-11-28 00:28:21.785157 | 
2025-11-28 00:28:21.785345 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-28 00:28:22.043902 | controller | changed: "logs"
2025-11-28 00:28:22.246897 | controller | changed: "artifacts"
2025-11-28 00:28:22.434377 | controller | changed: "docs"
2025-11-28 00:28:22.490222 | 
2025-11-28 00:28:22.490353 | PLAY RECAP
2025-11-28 00:28:22.490414 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-11-28 00:28:22.490450 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-28 00:28:22.490474 | 
2025-11-28 00:28:22.610405 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-28 00:28:22.611696 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-28 00:28:23.266983 | 
2025-11-28 00:28:23.267097 | PLAY [all]
2025-11-28 00:28:23.290114 | 
2025-11-28 00:28:23.290227 | TASK [Install binary dependencies]
2025-11-28 00:28:23.367640 | controller | ok
2025-11-28 00:28:23.400281 | 
2025-11-28 00:28:23.400410 | TASK [bindep : Include find tasks]
2025-11-28 00:28:23.443323 | controller | ok
2025-11-28 00:28:23.456074 | controller | included: /var/lib/zuul/builds/a43e371395c541b48dd2653db0fcee13/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-11-28 00:28:23.465629 | 
2025-11-28 00:28:23.465758 | TASK [bindep : Look for bindep.txt]
2025-11-28 00:28:23.831982 | controller | ok
2025-11-28 00:28:23.845089 | 
2025-11-28 00:28:23.845248 | TASK [bindep : Define bindep_file fact]
2025-11-28 00:28:23.872309 | controller | skipping: Conditional result was False
2025-11-28 00:28:23.885970 | 
2025-11-28 00:28:23.886122 | TASK [bindep : Look for other-requirements.txt]
2025-11-28 00:28:24.107934 | controller | ok
2025-11-28 00:28:24.120527 | 
2025-11-28 00:28:24.120679 | TASK [bindep : Define bindep_file fact]
2025-11-28 00:28:24.157661 | controller | skipping: Conditional result was False
2025-11-28 00:28:24.171884 | 
2025-11-28 00:28:24.172211 | TASK [bindep : Look for bindep fallback file]
2025-11-28 00:28:24.210627 | controller | skipping: Conditional result was False
2025-11-28 00:28:24.226180 | 
2025-11-28 00:28:24.226335 | TASK [bindep : Define bindep_file fact]
2025-11-28 00:28:24.253053 | controller | skipping: Conditional result was False
2025-11-28 00:28:24.266365 | 
2025-11-28 00:28:24.266603 | TASK [bindep : Include bindep tasks]
2025-11-28 00:28:24.293267 | controller | skipping: Conditional result was False
2025-11-28 00:28:24.308282 | 
2025-11-28 00:28:24.308459 | TASK [bindep : Include install tasks]
2025-11-28 00:28:24.335159 | controller | skipping: Conditional result was False
2025-11-28 00:28:24.345676 | 
2025-11-28 00:28:24.345852 | LOOP [bindep : Include package tasks]
2025-11-28 00:28:24.419266 | 
2025-11-28 00:28:24.419489 | TASK [Run test-setup role]
2025-11-28 00:28:24.445351 | controller | ok
2025-11-28 00:28:24.470804 | 
2025-11-28 00:28:24.470952 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-11-28 00:28:24.683906 | controller | ok
2025-11-28 00:28:24.699650 | 
2025-11-28 00:28:24.699915 | TASK [test-setup : Run tools/test-setup.sh]
2025-11-28 00:28:25.261989 | controller | skipping: Conditional result was False
2025-11-28 00:28:25.289777 | 
2025-11-28 00:28:25.289902 | PLAY RECAP
2025-11-28 00:28:25.289951 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-28 00:28:25.289979 | 
2025-11-28 00:28:25.392972 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-28 00:28:25.393911 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-28 00:28:25.998823 | 
2025-11-28 00:28:25.998967 | PLAY [controller]
2025-11-28 00:28:26.021884 | 
2025-11-28 00:28:26.022021 | TASK [Create the /root directory]
2025-11-28 00:30:38.350656 | controller | ERROR
2025-11-28 00:30:38.350967 | controller | {
2025-11-28 00:30:38.351022 | controller |   "msg": "Timeout (32s) waiting for privilege escalation prompt: "
2025-11-28 00:30:38.351064 | controller | }
failure
2025-11-28 00:30:38.352225 | 
2025-11-28 00:30:38.352288 | PLAY RECAP
2025-11-28 00:30:38.352342 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2025-11-28 00:30:38.352375 | 
2025-11-28 00:30:38.487254 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-28 00:30:38.488281 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-28 00:30:39.098827 | 
2025-11-28 00:30:39.098981 | PLAY [all]
2025-11-28 00:30:39.122607 | 
2025-11-28 00:30:39.122745 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-11-28 00:33:25.910523 | controller | changed: non-zero return code
2025-11-28 00:33:25.925019 | 
2025-11-28 00:33:25.925248 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-11-28 00:33:25.953456 | controller | skipping: Conditional result was False
2025-11-28 00:33:25.965116 | 
2025-11-28 00:33:25.965273 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-11-28 00:33:26.002388 | 
2025-11-28 00:33:26.002572 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-11-28 00:33:26.036098 | 
2025-11-28 00:33:26.036292 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-11-28 00:33:26.061516 | controller | skipping: Conditional result was False
2025-11-28 00:33:26.070292 | 
2025-11-28 00:33:26.070389 | LOOP [fetch-subunit-output : Generate subunit file]
2025-11-28 00:33:26.103459 | 
2025-11-28 00:33:26.103640 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-11-28 00:33:26.128280 | controller | skipping: Conditional result was False
2025-11-28 00:33:26.136766 | 
2025-11-28 00:33:26.136857 | TASK [fetch-subunit-output : Remove the temporary file]
2025-11-28 00:33:26.161492 | controller | skipping: Conditional result was False
2025-11-28 00:33:26.171794 | 
2025-11-28 00:33:26.171887 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-11-28 00:33:26.187660 | controller | skipping: Conditional result was False
2025-11-28 00:33:26.235782 | 
2025-11-28 00:33:26.235894 | PLAY RECAP
2025-11-28 00:33:26.235953 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-28 00:33:26.235983 | 
2025-11-28 00:33:26.337830 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-28 00:33:26.338711 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-11-28 00:33:26.914081 | 
2025-11-28 00:33:26.914223 | PLAY [all:!appliance*]
2025-11-28 00:33:26.941891 | 
2025-11-28 00:33:26.942017 | TASK [unregister the node]
2025-11-28 00:33:27.472970 | controller | skipping: Conditional result was False
2025-11-28 00:33:27.486083 | 
2025-11-28 00:33:27.486281 | TASK [include_role : fetch-output]
2025-11-28 00:33:27.538081 | controller | ok
2025-11-28 00:33:27.586228 | 
2025-11-28 00:33:27.586376 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-28 00:33:27.674124 | controller | skipping: Conditional result was False
2025-11-28 00:33:27.689500 | 
2025-11-28 00:33:27.689708 | TASK [fetch-output : Set log path for single node]
2025-11-28 00:33:27.741379 | controller | ok
2025-11-28 00:33:27.754335 | 
2025-11-28 00:33:27.754531 | LOOP [fetch-output : Ensure local output dirs]
2025-11-28 00:33:28.169254 | controller -> localhost | ok: "/var/lib/zuul/builds/a43e371395c541b48dd2653db0fcee13/work/logs"
2025-11-28 00:33:28.394951 | controller -> localhost | changed: "/var/lib/zuul/builds/a43e371395c541b48dd2653db0fcee13/work/artifacts"
2025-11-28 00:33:28.647658 | controller -> localhost | changed: "/var/lib/zuul/builds/a43e371395c541b48dd2653db0fcee13/work/docs"
2025-11-28 00:33:28.674102 | 
2025-11-28 00:33:28.674300 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-28 00:33:29.361746 | controller | ok
2025-11-28 00:33:29.361952 | controller | changed: All items complete
2025-11-28 00:33:29.361982 | 
2025-11-28 00:33:29.872471 | controller | changed: .d..t...... ./
2025-11-28 00:33:30.422165 | controller | changed: .d..t...... ./
2025-11-28 00:33:30.451231 | 
2025-11-28 00:33:30.451415 | TASK [include_role : fetch-output-openshift]
2025-11-28 00:33:30.479548 | controller | skipping: Conditional result was False
2025-11-28 00:33:30.493958 | 
2025-11-28 00:33:30.494136 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-28 00:33:30.543601 | controller | skipping: Conditional result was False
2025-11-28 00:33:30.557279 | controller | skipping: Conditional result was False
2025-11-28 00:33:30.618477 | 
2025-11-28 00:33:30.618610 | PLAY [localhost]
2025-11-28 00:33:30.639461 | 
2025-11-28 00:33:30.639599 | TASK [Run Zuul manifest role]
2025-11-28 00:33:30.661286 | localhost | ok
2025-11-28 00:33:30.686025 | 
2025-11-28 00:33:30.686202 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-28 00:33:31.129814 | localhost | changed
2025-11-28 00:33:31.137180 | 
2025-11-28 00:33:31.137296 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-28 00:33:31.171392 | localhost | ok
2025-11-28 00:33:31.185126 | 
2025-11-28 00:33:31.185289 | TASK [Set zuul-log-path fact]
2025-11-28 00:33:31.206898 | localhost | ok
2025-11-28 00:33:31.232851 | 
2025-11-28 00:33:31.233165 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-28 00:33:31.274475 | localhost | ok
2025-11-28 00:33:31.283010 | 
2025-11-28 00:33:31.283082 | LOOP [Run upload-logs-swift role]
2025-11-28 00:33:31.318209 | localhost | Output suppressed because no_log was given
2025-11-28 00:33:31.348072 | 
2025-11-28 00:33:31.348183 | TASK [Set zuul-log-path fact]
2025-11-28 00:33:31.372348 | localhost | skipping: Conditional result was False
2025-11-28 00:33:31.377970 | 
2025-11-28 00:33:31.378039 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-11-28 00:33:31.849520 | localhost -> localhost | ok: Runtime: 0:00:00.010255
2025-11-28 00:33:31.898234 | 
2025-11-28 00:33:31.898399 | TASK [upload-logs-swift : Upload logs to swift]