2025-11-18 00:06:43.697760 | Job console starting...
2025-11-18 00:06:43.707545 | Updating repositories
2025-11-18 00:06:45.295403 | Preparing job workspace
2025-11-18 00:06:51.047239 | Running Ansible setup...
2025-11-18 00:06:56.173355 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-18 00:06:56.804059 | 
2025-11-18 00:06:56.804216 | PLAY [localhost]
2025-11-18 00:06:56.813378 | 
2025-11-18 00:06:56.813477 | TASK [Gathering Facts]
2025-11-18 00:06:57.811760 | localhost | ok
2025-11-18 00:06:57.836395 | 
2025-11-18 00:06:57.836575 | TASK [Setup log path fact]
2025-11-18 00:06:57.857396 | localhost | ok
2025-11-18 00:06:57.879567 | 
2025-11-18 00:06:57.879715 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-18 00:06:57.912804 | localhost | ok
2025-11-18 00:06:57.923727 | 
2025-11-18 00:06:57.923840 | TASK [emit-job-header : Print job information]
2025-11-18 00:06:57.985760 | # Job Information
2025-11-18 00:06:57.985960 | Ansible Version: 2.15.12
2025-11-18 00:06:57.986012 | Job: ansible-test-sanity-docker-stable-2.12
2025-11-18 00:06:57.986088 | Pipeline: periodic
2025-11-18 00:06:57.986121 | Executor: ze01.softwarefactory-project.io
2025-11-18 00:06:57.986150 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-11-18 00:06:57.986184 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/80c/ansible/80cc22e7448a4461bc76d523fab015d1/
2025-11-18 00:06:57.986213 | Event ID: ce1d8018739b49068127d11881773b47
2025-11-18 00:06:57.992071 | 
2025-11-18 00:06:57.992167 | LOOP [emit-job-header : Print node information]
2025-11-18 00:06:58.122496 | localhost | ok:
2025-11-18 00:06:58.122707 | localhost | # Node Information
2025-11-18 00:06:58.122735 | localhost | Inventory Hostname: controller
2025-11-18 00:06:58.122755 | localhost | Hostname: np0005525950
2025-11-18 00:06:58.122774 | localhost | Username: zuul
2025-11-18 00:06:58.122795 | localhost | Distro: Fedora 37
2025-11-18 00:06:58.122813 | localhost | Provider: ansible-vexxhost-ca-ymq-1
2025-11-18 00:06:58.122830 | localhost | Region: ca-ymq-1
2025-11-18 00:06:58.122847 | localhost | Label: ansible-fedora-37-1vcpu
2025-11-18 00:06:58.122863 | localhost | Product Name: OpenStack Nova
2025-11-18 00:06:58.122879 | localhost | Interface IP: 162.253.55.52
2025-11-18 00:06:58.149282 | 
2025-11-18 00:06:58.149490 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-18 00:06:58.563843 | localhost -> localhost | changed
2025-11-18 00:06:58.573200 | 
2025-11-18 00:06:58.573326 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-18 00:06:59.499386 | localhost -> localhost | changed
2025-11-18 00:06:59.521289 | 
2025-11-18 00:06:59.521387 | PLAY [all:!appliance*]
2025-11-18 00:06:59.540881 | 
2025-11-18 00:06:59.540996 | TASK [include_role : start-zuul-console]
2025-11-18 00:06:59.562764 | controller | ok
2025-11-18 00:06:59.578082 | 
2025-11-18 00:06:59.578203 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-18 00:06:59.962620 | controller | ok
2025-11-18 00:06:59.988068 | 
2025-11-18 00:06:59.988209 | TASK [use-our-mirror : Retrieve the IP address]
2025-11-18 00:07:00.942675 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-11-18 00:07:00.958345 | 
2025-11-18 00:07:00.958507 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-11-18 00:07:01.509449 | controller | skipping: Conditional result was False
2025-11-18 00:07:01.526560 | 
2025-11-18 00:07:01.526722 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-11-18 00:07:01.554629 | controller | skipping: Conditional result was False
2025-11-18 00:07:01.570650 | 
2025-11-18 00:07:01.570798 | TASK [use-our-mirror : Create the podman configuration directory]
2025-11-18 00:07:01.599581 | controller | skipping: Conditional result was False
2025-11-18 00:07:01.616493 | 
2025-11-18 00:07:01.616639 | TASK [use-our-mirror : Copy the podman configuration]
2025-11-18 00:07:01.645932 | controller | skipping: Conditional result was False
2025-11-18 00:07:01.662868 | 
2025-11-18 00:07:01.663121 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-11-18 00:07:01.701490 | controller | skipping: Conditional result was False
2025-11-18 00:07:01.711863 | 
2025-11-18 00:07:01.711964 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-11-18 00:07:01.748338 | controller | skipping: Conditional result was False
2025-11-18 00:07:01.764277 | 
2025-11-18 00:07:01.764358 | TASK [Disable Fedora Modular]
2025-11-18 00:07:02.009072 | controller | changed
2025-11-18 00:07:02.023123 | 
2025-11-18 00:07:02.023263 | TASK [Enable EPEL]
2025-11-18 00:07:02.050605 | controller | skipping: Conditional result was False
2025-11-18 00:07:02.059917 | 
2025-11-18 00:07:02.060021 | TASK [Register the RHEL node]
2025-11-18 00:07:02.617985 | 
2025-11-18 00:07:02.618304 | TASK [Show the subscription-manager status]
2025-11-18 00:07:03.197590 | controller | skipping: Conditional result was False
2025-11-18 00:07:03.211699 | 
2025-11-18 00:07:03.211872 | TASK [Enable EPEL on RHEL]
2025-11-18 00:07:03.773214 | controller | skipping: Conditional result was False
2025-11-18 00:07:03.802183 | 
2025-11-18 00:07:03.802378 | TASK [Install git and tox]
2025-11-18 00:08:32.897138 | controller | changed
2025-11-18 00:08:32.904485 | 
2025-11-18 00:08:32.904553 | TASK [include_role : prepare-workspace]
2025-11-18 00:08:32.936233 | controller | ok
2025-11-18 00:08:32.960304 | 
2025-11-18 00:08:32.960431 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-18 00:08:33.191653 | controller | ok
2025-11-18 00:08:33.199706 | 
2025-11-18 00:08:33.199831 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-18 00:14:06.184511 | controller | Output suppressed because no_log was given
2025-11-18 00:14:06.195895 | 
2025-11-18 00:14:06.195988 | TASK [include_role : prepare-workspace-openshift]
2025-11-18 00:14:06.220650 | controller | skipping: Conditional result was False
2025-11-18 00:14:06.244146 | 
2025-11-18 00:14:06.244242 | PLAY [all:!appliance]
2025-11-18 00:14:06.261570 | 
2025-11-18 00:14:06.261701 | TASK [Run add-build-sshkey role (RSA)]
2025-11-18 00:14:06.283971 | controller | ok
2025-11-18 00:14:06.300879 | 
2025-11-18 00:14:06.300992 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-18 00:14:06.569697 | controller -> localhost | ok
2025-11-18 00:14:06.577410 | 
2025-11-18 00:14:06.577500 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-18 00:14:06.628274 | controller | ok
2025-11-18 00:14:06.657325 | controller | included: /var/lib/zuul/builds/80cc22e7448a4461bc76d523fab015d1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-18 00:14:06.664344 | 
2025-11-18 00:14:06.664427 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-18 00:14:07.156048 | controller -> localhost | Generating public/private rsa key pair.
2025-11-18 00:14:07.156242 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/80cc22e7448a4461bc76d523fab015d1/work/80cc22e7448a4461bc76d523fab015d1_id_rsa.
2025-11-18 00:14:07.156272 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/80cc22e7448a4461bc76d523fab015d1/work/80cc22e7448a4461bc76d523fab015d1_id_rsa.pub.
2025-11-18 00:14:07.156294 | controller -> localhost | The key fingerprint is:
2025-11-18 00:14:07.156318 | controller -> localhost | SHA256:ufwN45s84Zhyir8f0C5Ygm55tIUz04eGAtJdT6QSLQ8 zuul-build-sshkey
2025-11-18 00:14:07.156364 | controller -> localhost | The key's randomart image is:
2025-11-18 00:14:07.156403 | controller -> localhost | +---[RSA 2048]----+
2025-11-18 00:14:07.156430 | controller -> localhost | |    ....o        |
2025-11-18 00:14:07.156451 | controller -> localhost | | . .Eo.+         |
2025-11-18 00:14:07.156471 | controller -> localhost | |o . o+. .        |
2025-11-18 00:14:07.156489 | controller -> localhost | |.. . =.o .       |
2025-11-18 00:14:07.156507 | controller -> localhost | |  o O B S        |
2025-11-18 00:14:07.156534 | controller -> localhost | | . + @ = ..      |
2025-11-18 00:14:07.156560 | controller -> localhost | |  + + . =+o.     |
2025-11-18 00:14:07.156584 | controller -> localhost | | . . ...+=o=     |
2025-11-18 00:14:07.156604 | controller -> localhost | |    ..+*. *o.    |
2025-11-18 00:14:07.156626 | controller -> localhost | +----[SHA256]-----+
2025-11-18 00:14:07.156673 | controller -> localhost | ok: Runtime: 0:00:00.098193
2025-11-18 00:14:07.163767 | 
2025-11-18 00:14:07.163862 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-18 00:14:07.193943 | controller | ok
2025-11-18 00:14:07.204383 | controller | included: /var/lib/zuul/builds/80cc22e7448a4461bc76d523fab015d1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-18 00:14:07.214903 | 
2025-11-18 00:14:07.215001 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-18 00:14:07.239348 | controller | skipping: Conditional result was False
2025-11-18 00:14:07.247898 | 
2025-11-18 00:14:07.248008 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-18 00:14:07.906926 | controller | changed
2025-11-18 00:14:07.922264 | 
2025-11-18 00:14:07.922449 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-18 00:14:08.147716 | controller | ok
2025-11-18 00:14:08.161683 | 
2025-11-18 00:14:08.161856 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-18 00:14:08.822183 | controller | changed
2025-11-18 00:14:08.835118 | 
2025-11-18 00:14:08.835316 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-18 00:14:09.480781 | controller | changed
2025-11-18 00:14:09.494660 | 
2025-11-18 00:14:09.494867 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-18 00:14:09.523339 | controller | skipping: Conditional result was False
2025-11-18 00:14:09.541254 | 
2025-11-18 00:14:09.541432 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-18 00:14:09.986965 | controller -> localhost | changed
2025-11-18 00:14:10.009821 | 
2025-11-18 00:14:10.009984 | TASK [add-build-sshkey : Add back temp key]
2025-11-18 00:14:10.303922 | controller -> localhost | Identity added: /var/lib/zuul/builds/80cc22e7448a4461bc76d523fab015d1/work/80cc22e7448a4461bc76d523fab015d1_id_rsa (zuul-build-sshkey)
2025-11-18 00:14:10.304252 | controller -> localhost | ok: Runtime: 0:00:00.008038
2025-11-18 00:14:10.314362 | 
2025-11-18 00:14:10.314482 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-18 00:14:10.672795 | controller | ok
2025-11-18 00:14:10.682262 | 
2025-11-18 00:14:10.682382 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-18 00:14:10.718195 | controller | skipping: Conditional result was False
2025-11-18 00:14:10.738292 | 
2025-11-18 00:14:10.738459 | TASK [Run add-build-sshkey role (ECDSA)]
2025-11-18 00:14:10.770032 | controller | ok
2025-11-18 00:14:10.786096 | 
2025-11-18 00:14:10.786178 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-18 00:14:11.034183 | controller -> localhost | ok
2025-11-18 00:14:11.045886 | 
2025-11-18 00:14:11.046038 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-18 00:14:11.081124 | controller | ok
2025-11-18 00:14:11.104394 | controller | included: /var/lib/zuul/builds/80cc22e7448a4461bc76d523fab015d1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-18 00:14:11.117767 | 
2025-11-18 00:14:11.117890 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-18 00:14:11.451661 | controller -> localhost | Generating public/private ecdsa key pair.
2025-11-18 00:14:11.451927 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/80cc22e7448a4461bc76d523fab015d1/work/80cc22e7448a4461bc76d523fab015d1_id_ecdsa.
2025-11-18 00:14:11.451960 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/80cc22e7448a4461bc76d523fab015d1/work/80cc22e7448a4461bc76d523fab015d1_id_ecdsa.pub.
2025-11-18 00:14:11.451998 | controller -> localhost | The key fingerprint is:
2025-11-18 00:14:11.452115 | controller -> localhost | SHA256:9OZZYe8VbORlD8rPByQQW8tnVovz+cIgdXlX1MFvVyc zuul-build-sshkey
2025-11-18 00:14:11.452143 | controller -> localhost | The key's randomart image is:
2025-11-18 00:14:11.452164 | controller -> localhost | +---[ECDSA 521]---+
2025-11-18 00:14:11.452184 | controller -> localhost | |          ooo ++O|
2025-11-18 00:14:11.452202 | controller -> localhost | |           = =E*O|
2025-11-18 00:14:11.452221 | controller -> localhost | |        . . OoBBB|
2025-11-18 00:14:11.452240 | controller -> localhost | |       . . o X+oB|
2025-11-18 00:14:11.452258 | controller -> localhost | |        S + o +++|
2025-11-18 00:14:11.452276 | controller -> localhost | |         o + + o.|
2025-11-18 00:14:11.452294 | controller -> localhost | |          o   + .|
2025-11-18 00:14:11.452311 | controller -> localhost | |               . |
2025-11-18 00:14:11.452329 | controller -> localhost | |                 |
2025-11-18 00:14:11.452347 | controller -> localhost | +----[SHA256]-----+
2025-11-18 00:14:11.452406 | controller -> localhost | ok: Runtime: 0:00:00.009811
2025-11-18 00:14:11.460967 | 
2025-11-18 00:14:11.471493 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-18 00:14:11.504299 | controller | ok
2025-11-18 00:14:11.514258 | controller | included: /var/lib/zuul/builds/80cc22e7448a4461bc76d523fab015d1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-18 00:14:11.525150 | 
2025-11-18 00:14:11.525370 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-18 00:14:11.551130 | controller | skipping: Conditional result was False
2025-11-18 00:14:11.558853 | 
2025-11-18 00:14:11.558981 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-18 00:14:11.832931 | controller | changed
2025-11-18 00:14:11.846497 | 
2025-11-18 00:14:11.846652 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-18 00:14:12.053959 | controller | ok
2025-11-18 00:14:12.065058 | 
2025-11-18 00:14:12.065165 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-18 00:14:12.721476 | controller | changed
2025-11-18 00:14:12.730459 | 
2025-11-18 00:14:12.730559 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-18 00:14:13.375956 | controller | changed
2025-11-18 00:14:13.415987 | 
2025-11-18 00:14:13.416134 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-18 00:14:13.442526 | controller | skipping: Conditional result was False
2025-11-18 00:14:13.449064 | 
2025-11-18 00:14:13.449136 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-18 00:14:13.678181 | controller -> localhost | changed
2025-11-18 00:14:13.704281 | 
2025-11-18 00:14:13.704454 | TASK [add-build-sshkey : Add back temp key]
2025-11-18 00:14:14.038607 | controller -> localhost | Identity added: /var/lib/zuul/builds/80cc22e7448a4461bc76d523fab015d1/work/80cc22e7448a4461bc76d523fab015d1_id_ecdsa (zuul-build-sshkey)
2025-11-18 00:14:14.038844 | controller -> localhost | ok: Runtime: 0:00:00.007204
2025-11-18 00:14:14.049544 | 
2025-11-18 00:14:14.049688 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-18 00:14:14.261186 | controller | ok
2025-11-18 00:14:14.280592 | 
2025-11-18 00:14:14.280829 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-18 00:14:14.319815 | controller | skipping: Conditional result was False
2025-11-18 00:14:14.343139 | 
2025-11-18 00:14:14.343309 | TASK [include_role : remove-zuul-sshkey]
2025-11-18 00:14:14.370243 | controller | skipping: Conditional result was False
2025-11-18 00:14:14.380704 | 
2025-11-18 00:14:14.380841 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-18 00:14:14.615182 | controller | ok: "logs"
2025-11-18 00:14:14.615604 | controller | ok: All items complete
2025-11-18 00:14:14.615667 | 
2025-11-18 00:14:14.817208 | controller | ok: "artifacts"
2025-11-18 00:14:15.018750 | controller | ok: "docs"
2025-11-18 00:14:15.032345 | 
2025-11-18 00:14:15.032498 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-18 00:14:15.274191 | controller | changed: "logs"
2025-11-18 00:14:15.470566 | controller | changed: "artifacts"
2025-11-18 00:14:15.667371 | controller | changed: "docs"
2025-11-18 00:14:15.710690 | 
2025-11-18 00:14:15.710786 | PLAY RECAP
2025-11-18 00:14:15.710826 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-11-18 00:14:15.710852 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-18 00:14:15.710871 | 
2025-11-18 00:14:15.839892 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-18 00:14:15.840852 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-18 00:14:16.433759 | 
2025-11-18 00:14:16.433929 | PLAY [all]
2025-11-18 00:14:16.462360 | 
2025-11-18 00:14:16.462514 | TASK [Install binary dependencies]
2025-11-18 00:14:16.503692 | controller | ok
2025-11-18 00:14:16.526933 | 
2025-11-18 00:14:16.527103 | TASK [bindep : Include find tasks]
2025-11-18 00:14:16.556958 | controller | ok
2025-11-18 00:14:16.566540 | controller | included: /var/lib/zuul/builds/80cc22e7448a4461bc76d523fab015d1/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-11-18 00:14:16.574484 | 
2025-11-18 00:14:16.574575 | TASK [bindep : Look for bindep.txt]
2025-11-18 00:14:17.142425 | controller | ok
2025-11-18 00:14:17.155155 | 
2025-11-18 00:14:17.155295 | TASK [bindep : Define bindep_file fact]
2025-11-18 00:14:17.182563 | controller | skipping: Conditional result was False
2025-11-18 00:14:17.197566 | 
2025-11-18 00:14:17.197729 | TASK [bindep : Look for other-requirements.txt]
2025-11-18 00:14:17.419314 | controller | ok
2025-11-18 00:14:17.428740 | 
2025-11-18 00:14:17.428842 | TASK [bindep : Define bindep_file fact]
2025-11-18 00:14:17.464763 | controller | skipping: Conditional result was False
2025-11-18 00:14:17.479941 | 
2025-11-18 00:14:17.480153 | TASK [bindep : Look for bindep fallback file]
2025-11-18 00:14:17.536507 | controller | skipping: Conditional result was False
2025-11-18 00:14:17.546338 | 
2025-11-18 00:14:17.546435 | TASK [bindep : Define bindep_file fact]
2025-11-18 00:14:17.581880 | controller | skipping: Conditional result was False
2025-11-18 00:14:17.590483 | 
2025-11-18 00:14:17.590559 | TASK [bindep : Include bindep tasks]
2025-11-18 00:14:17.625806 | controller | skipping: Conditional result was False
2025-11-18 00:14:17.634582 | 
2025-11-18 00:14:17.634682 | TASK [bindep : Include install tasks]
2025-11-18 00:14:17.670142 | controller | skipping: Conditional result was False
2025-11-18 00:14:17.679199 | 
2025-11-18 00:14:17.679293 | LOOP [bindep : Include package tasks]
2025-11-18 00:14:17.750261 | 
2025-11-18 00:14:17.750456 | TASK [Run test-setup role]
2025-11-18 00:14:17.774273 | controller | ok
2025-11-18 00:14:17.807424 | 
2025-11-18 00:14:17.807553 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-11-18 00:14:18.033756 | controller | ok
2025-11-18 00:14:18.047584 | 
2025-11-18 00:14:18.047751 | TASK [test-setup : Run tools/test-setup.sh]
2025-11-18 00:14:18.600335 | controller | skipping: Conditional result was False
2025-11-18 00:14:18.637276 | 
2025-11-18 00:14:18.637404 | PLAY RECAP
2025-11-18 00:14:18.637459 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-18 00:14:18.637489 | 
2025-11-18 00:14:18.767576 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-18 00:14:18.769336 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-18 00:14:19.409376 | 
2025-11-18 00:14:19.409535 | PLAY [controller]
2025-11-18 00:14:19.431261 | 
2025-11-18 00:14:19.431374 | TASK [Create the /root directory]
2025-11-18 00:14:20.007115 | controller | ok
2025-11-18 00:14:20.021320 | 
2025-11-18 00:14:20.021552 | TASK [Install glibc-langpack-en]
2025-11-18 00:14:23.939035 | controller | ok: Nothing to do
2025-11-18 00:14:23.945001 | 
2025-11-18 00:14:23.945107 | TASK [Ensure controller directory exists]
2025-11-18 00:14:24.168876 | controller | changed
2025-11-18 00:14:24.175147 | 
2025-11-18 00:14:24.175243 | TASK [Install container runtime]
2025-11-18 00:14:24.216439 | controller | ok
2025-11-18 00:14:24.255564 | 
2025-11-18 00:14:24.255710 | LOOP [ensure-podman : Find distribution installation]
2025-11-18 00:14:24.285715 | controller | ok: "/var/lib/zuul/builds/80cc22e7448a4461bc76d523fab015d1/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml"
2025-11-18 00:14:24.302819 | controller | included: /var/lib/zuul/builds/80cc22e7448a4461bc76d523fab015d1/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml
2025-11-18 00:14:24.313112 | 
2025-11-18 00:14:24.313281 | TASK [ensure-podman : Install podman (RedHat)]
2025-11-18 00:15:30.931960 | controller | changed
2025-11-18 00:15:30.941483 | 
2025-11-18 00:15:30.941583 | TASK [ensure-podman : Fetch podman version]
2025-11-18 00:15:31.538145 | controller | Client:       Podman Engine
2025-11-18 00:15:31.565273 | controller | Version:      4.6.2
2025-11-18 00:15:31.565468 | controller | API Version:  4.6.2
2025-11-18 00:15:31.565497 | controller | Go Version:   go1.19.12
2025-11-18 00:15:31.565543 | controller | Built:        Mon Aug 28 19:38:31 2023
2025-11-18 00:15:31.565556 | controller | OS/Arch:      linux/amd64
2025-11-18 00:15:31.985228 | controller | ok: Runtime: 0:00:00.202988
2025-11-18 00:15:32.000804 | 
2025-11-18 00:15:32.000974 | TASK [ensure-podman : Print podman version installed]
2025-11-18 00:15:32.038198 | Podman version: Client:       Podman Engine
2025-11-18 00:15:32.038454 | Version:      4.6.2
2025-11-18 00:15:32.038513 | API Version:  4.6.2
2025-11-18 00:15:32.038558 | Go Version:   go1.19.12
2025-11-18 00:15:32.038598 | Built:        Mon Aug 28 19:38:31 2023
2025-11-18 00:15:32.038640 | OS/Arch:      linux/amd64
2025-11-18 00:15:32.048346 | 
2025-11-18 00:15:32.048443 | TASK [ensure-podman : Validate podman engine]
2025-11-18 00:15:32.593273 | controller | skipping: Conditional result was False
2025-11-18 00:15:32.617146 | 
2025-11-18 00:15:32.617392 | TASK [ensure-podman : Set up docker compatability socket]
2025-11-18 00:15:32.648464 | controller | skipping: Conditional result was False
2025-11-18 00:15:32.678731 | 
2025-11-18 00:15:32.678893 | TASK [Ensure python3.8 is present]
2025-11-18 00:15:32.694815 | controller | skipping: Conditional result was False
2025-11-18 00:15:32.706074 | 
2025-11-18 00:15:32.706183 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-11-18 00:15:32.728436 | controller | ok
2025-11-18 00:15:32.747607 | 
2025-11-18 00:15:32.747763 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-11-18 00:15:34.081861 | controller | ok: Nothing to do
2025-11-18 00:15:34.096672 | 
2025-11-18 00:15:34.096842 | TASK [our-ensure-python : Also install python3-devel]
2025-11-18 00:15:43.912979 | controller | changed
2025-11-18 00:15:43.925010 | 
2025-11-18 00:15:43.925185 | TASK [Run ensure-virtualenv role]
2025-11-18 00:15:43.946251 | controller | ok
2025-11-18 00:15:43.969593 | 
2025-11-18 00:15:43.969744 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-11-18 00:15:44.212307 | controller | /usr/bin/virtualenv
2025-11-18 00:15:44.504869 | controller | ok: Runtime: 0:00:00.006731
2025-11-18 00:15:44.555431 | 
2025-11-18 00:15:44.555593 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-11-18 00:15:44.580648 | controller | skipping: Conditional result was False
2025-11-18 00:15:44.581073 | controller | ok: All items complete
2025-11-18 00:15:44.581125 | 
2025-11-18 00:15:44.600844 | 
2025-11-18 00:15:44.601305 | TASK [Find the full path of the Python interpreter]
2025-11-18 00:15:44.839867 | controller | /usr/bin/python3.10
2025-11-18 00:15:45.154477 | controller | ok
2025-11-18 00:15:45.167636 | 
2025-11-18 00:15:45.167828 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-11-18 00:15:46.369441 | controller | created virtual environment CPython3.10.13.final.0-64 in 649ms
2025-11-18 00:15:46.406047 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2025-11-18 00:15:46.406542 | controller |   seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv)
2025-11-18 00:15:46.406748 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-11-18 00:15:46.406986 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-11-18 00:15:46.724724 | controller | changed
2025-11-18 00:15:46.735334 | 
2025-11-18 00:15:46.735557 | TASK [Set selinux package]
2025-11-18 00:15:46.789574 | controller | ok
2025-11-18 00:15:46.798125 | 
2025-11-18 00:15:46.798229 | TASK [Set selinux package (Fedora)]
2025-11-18 00:15:46.843156 | controller | ok
2025-11-18 00:15:46.850790 | 
2025-11-18 00:15:46.850875 | TASK [Install selinux into virtualenv]
2025-11-18 00:15:48.046857 | controller | Collecting selinux-please-lie-to-me
2025-11-18 00:15:48.082961 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-11-18 00:15:48.420245 | controller | Collecting setuptools<50.0.0
2025-11-18 00:15:48.428109 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-11-18 00:15:48.463524 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 25.7 MB/s eta 0:00:00
2025-11-18 00:15:48.543747 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-11-18 00:15:48.543960 | controller |   Attempting uninstall: setuptools
2025-11-18 00:15:48.544645 | controller |     Found existing installation: setuptools 62.6.0
2025-11-18 00:15:48.617224 | controller |     Uninstalling setuptools-62.6.0:
2025-11-18 00:15:48.626018 | controller |       Successfully uninstalled setuptools-62.6.0
2025-11-18 00:15:48.963708 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-11-18 00:15:49.091821 | controller |
2025-11-18 00:15:49.178871 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3
2025-11-18 00:15:49.178921 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-11-18 00:15:49.392342 | controller | ok: Runtime: 0:00:02.111637
2025-11-18 00:15:49.401423 | 
2025-11-18 00:15:49.401558 | TASK [Install pytest-forked into virtualenv]
2025-11-18 00:15:50.006844 | controller | Collecting pytest-forked
2025-11-18 00:15:50.042911 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2025-11-18 00:15:50.178498 | controller | Collecting pytest>=3.10
2025-11-18 00:15:50.184962 | controller |   Downloading pytest-9.0.1-py3-none-any.whl (373 kB)
2025-11-18 00:15:50.208052 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 373.7/373.7 kB 19.0 MB/s eta 0:00:00
2025-11-18 00:15:50.241436 | controller | Collecting py
2025-11-18 00:15:50.245502 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2025-11-18 00:15:50.251964 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 21.5 MB/s eta 0:00:00
2025-11-18 00:15:50.302260 | controller | Collecting pluggy<2,>=1.5
2025-11-18 00:15:50.305818 | controller |   Downloading pluggy-1.6.0-py3-none-any.whl (20 kB)
2025-11-18 00:15:50.334474 | controller | Collecting iniconfig>=1.0.1
2025-11-18 00:15:50.339571 | controller |   Downloading iniconfig-2.3.0-py3-none-any.whl (7.5 kB)
2025-11-18 00:15:50.385820 | controller | Collecting tomli>=1
2025-11-18 00:15:50.390007 | controller |   Downloading tomli-2.3.0-py3-none-any.whl (14 kB)
2025-11-18 00:15:50.424004 | controller | Collecting exceptiongroup>=1
2025-11-18 00:15:50.429669 | controller |   Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB)
2025-11-18 00:15:50.478038 | controller | Collecting pygments>=2.7.2
2025-11-18 00:15:50.485632 | controller |   Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB)
2025-11-18 00:15:50.515783 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 46.7 MB/s eta 0:00:00
2025-11-18 00:15:50.562196 | controller | Collecting packaging>=22
2025-11-18 00:15:50.568661 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-11-18 00:15:50.576448 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 11.1 MB/s eta 0:00:00
2025-11-18 00:15:50.625794 | controller | Collecting typing-extensions>=4.6.0
2025-11-18 00:15:50.629089 | controller |   Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB)
2025-11-18 00:15:50.636101 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 7.4 MB/s eta 0:00:00
2025-11-18 00:15:50.706035 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2025-11-18 00:20:39.469033 | [Zuul] Log Stream did not terminate
2025-11-18 00:20:39.469285 | controller | ERROR
2025-11-18 00:20:39.469379 | controller | {
2025-11-18 00:20:39.469406 | controller |   "msg": "Data could not be sent to remote host \"162.253.55.52\". Make sure this host can be reached over ssh: ssh: connect to host 162.253.55.52 port 22: No route to host\r\n",
2025-11-18 00:20:39.469426 | controller |   "unreachable": true
2025-11-18 00:20:39.469444 | controller | }
unreachable
2025-11-18 00:20:39.473058 | 
2025-11-18 00:20:39.473213 | PLAY RECAP
2025-11-18 00:20:39.473302 | controller | ok: 15 changed: 7 unreachable: 1 failed: 0 skipped: 4 rescued: 0 ignored: 0
2025-11-18 00:20:39.473350 | 
2025-11-18 00:20:39.600204 | PRE-RUN END RESULT_UNREACHABLE: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-18 00:20:39.601698 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-18 00:20:40.215580 | 
2025-11-18 00:20:40.215704 | PLAY [all]
2025-11-18 00:20:40.238180 | 
2025-11-18 00:20:40.238317 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-11-18 00:20:52.225541 | controller | changed: non-zero return code
2025-11-18 00:20:52.234264 | 
2025-11-18 00:20:52.234426 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-11-18 00:20:52.259836 | controller | skipping: Conditional result was False
2025-11-18 00:20:52.268442 | 
2025-11-18 00:20:52.268579 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-11-18 00:20:52.304089 | 
2025-11-18 00:20:52.304361 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-11-18 00:20:52.339691 | 
2025-11-18 00:20:52.339898 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-11-18 00:20:52.367286 | controller | skipping: Conditional result was False
2025-11-18 00:20:52.377262 | 
2025-11-18 00:20:52.377381 | LOOP [fetch-subunit-output : Generate subunit file]
2025-11-18 00:20:52.409331 | 
2025-11-18 00:20:52.409484 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-11-18 00:20:52.433439 | controller | skipping: Conditional result was False
2025-11-18 00:20:52.442617 | 
2025-11-18 00:20:52.442729 | TASK [fetch-subunit-output : Remove the temporary file]
2025-11-18 00:20:52.468298 | controller | skipping: Conditional result was False
2025-11-18 00:20:52.478059 | 
2025-11-18 00:20:52.478161 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-11-18 00:20:52.503298 | controller | skipping: Conditional result was False
2025-11-18 00:20:52.541626 | 
2025-11-18 00:20:52.541731 | PLAY RECAP
2025-11-18 00:20:52.541772 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-18 00:20:52.541792 | 
2025-11-18 00:20:52.661492 | POST-RUN END RESULT_UNREACHABLE: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-18 00:20:52.662352 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-11-18 00:20:53.234720 | 
2025-11-18 00:20:53.234837 | PLAY [all:!appliance*]
2025-11-18 00:20:53.260149 | 
2025-11-18 00:20:53.260273 | TASK [unregister the node]
2025-11-18 00:20:53.790946 | controller | skipping: Conditional result was False
2025-11-18 00:20:53.803914 | 
2025-11-18 00:20:53.804144 | TASK [include_role : fetch-output]
2025-11-18 00:20:53.858757 | controller | ok
2025-11-18 00:20:53.879528 | 
2025-11-18 00:20:53.879629 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-18 00:20:53.934278 | controller | skipping: Conditional result was False
2025-11-18 00:20:53.943858 | 
2025-11-18 00:20:53.943992 | TASK [fetch-output : Set log path for single node]
2025-11-18 00:20:53.975534 | controller | ok
2025-11-18 00:20:53.984588 | 
2025-11-18 00:20:53.984675 | LOOP [fetch-output : Ensure local output dirs]
2025-11-18 00:20:54.372790 | controller -> localhost | ok: "/var/lib/zuul/builds/80cc22e7448a4461bc76d523fab015d1/work/logs"
2025-11-18 00:20:54.585575 | controller -> localhost | changed: "/var/lib/zuul/builds/80cc22e7448a4461bc76d523fab015d1/work/artifacts"
2025-11-18 00:20:54.821211 | controller -> localhost | changed: "/var/lib/zuul/builds/80cc22e7448a4461bc76d523fab015d1/work/docs"
2025-11-18 00:20:54.837554 | 
2025-11-18 00:20:54.837667 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-18 00:20:55.507158 | controller | changed:
2025-11-18 00:20:55.507340 | controller | .d..t...... ./
2025-11-18 00:20:55.507368 | controller | cd+++++++++ controller/
2025-11-18 00:20:55.507400 | controller | changed: All items complete
2025-11-18 00:20:55.507420 | 
2025-11-18 00:20:56.053453 | controller | changed: .d..t...... ./
2025-11-18 00:20:56.570481 | controller | changed: .d..t...... ./
2025-11-18 00:20:56.590427 | 
2025-11-18 00:20:56.590708 | TASK [include_role : fetch-output-openshift]
2025-11-18 00:20:56.616501 | controller | skipping: Conditional result was False
2025-11-18 00:20:56.626563 | 
2025-11-18 00:20:56.626693 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-18 00:20:56.660785 | controller | skipping: Conditional result was False
2025-11-18 00:20:56.674977 | controller | skipping: Conditional result was False
2025-11-18 00:20:56.725492 | 
2025-11-18 00:20:56.725621 | PLAY [localhost]
2025-11-18 00:20:56.744712 | 
2025-11-18 00:20:56.744815 | TASK [Run Zuul manifest role]
2025-11-18 00:20:56.765981 | localhost | ok
2025-11-18 00:20:56.788106 | 
2025-11-18 00:20:56.788236 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-18 00:20:57.191257 | localhost | changed
2025-11-18 00:20:57.201321 | 
2025-11-18 00:20:57.201478 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-18 00:20:57.246958 | localhost | ok
2025-11-18 00:20:57.264331 | 
2025-11-18 00:20:57.264470 | TASK [Set zuul-log-path fact]
2025-11-18 00:20:57.290440 | localhost | ok
2025-11-18 00:20:57.319649 | 
2025-11-18 00:20:57.319966 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-18 00:20:57.368125 | localhost | ok
2025-11-18 00:20:57.389214 | 
2025-11-18 00:20:57.389514 | LOOP [Run upload-logs-swift role]
2025-11-18 00:20:57.440888 | localhost | Output suppressed because no_log was given
2025-11-18 00:20:57.476403 | 
2025-11-18 00:20:57.476560 | TASK [Set zuul-log-path fact]
2025-11-18 00:20:57.523396 | localhost | skipping: Conditional result was False
2025-11-18 00:20:57.531141 | 
2025-11-18 00:20:57.531239 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-11-18 00:20:57.956046 | localhost -> localhost | ok: Runtime: 0:00:00.005571
2025-11-18 00:20:57.964476 | 
2025-11-18 00:20:57.964822 | TASK [upload-logs-swift : Upload logs to swift]