2025-11-28 00:06:51.232990 | Job console starting...
2025-11-28 00:06:51.245749 | Updating repositories
2025-11-28 00:06:51.371430 | Preparing job workspace
2025-11-28 00:07:02.950791 | Running Ansible setup...
2025-11-28 00:07:08.132079 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-28 00:07:08.773095 | 
2025-11-28 00:07:08.773214 | PLAY [localhost]
2025-11-28 00:07:08.782055 | 
2025-11-28 00:07:08.782133 | TASK [Gathering Facts]
2025-11-28 00:07:09.825739 | localhost | ok
2025-11-28 00:07:09.861088 | 
2025-11-28 00:07:09.861303 | TASK [Setup log path fact]
2025-11-28 00:07:09.883094 | localhost | ok
2025-11-28 00:07:09.901161 | 
2025-11-28 00:07:09.901295 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-28 00:07:09.931884 | localhost | ok
2025-11-28 00:07:09.943934 | 
2025-11-28 00:07:09.944055 | TASK [emit-job-header : Print job information]
2025-11-28 00:07:09.984432 | # Job Information
2025-11-28 00:07:09.984651 | Ansible Version: 2.15.12
2025-11-28 00:07:09.984717 | Job: ansible-test-sanity-docker-stable-2.12
2025-11-28 00:07:09.984748 | Pipeline: periodic
2025-11-28 00:07:09.984775 | Executor: ze04.softwarefactory-project.io
2025-11-28 00:07:09.984800 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-11-28 00:07:09.984828 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/19d/ansible/19de7138cbc74a94a829b04d9f814586/
2025-11-28 00:07:09.984855 | Event ID: a5b225c8756b453caa3f2f71389276ab
2025-11-28 00:07:09.990976 | 
2025-11-28 00:07:09.991085 | LOOP [emit-job-header : Print node information]
2025-11-28 00:07:10.168422 | localhost | ok:
2025-11-28 00:07:10.168619 | localhost | # Node Information
2025-11-28 00:07:10.168654 | localhost | Inventory Hostname: controller
2025-11-28 00:07:10.168706 | localhost | Hostname: np0005538401
2025-11-28 00:07:10.168738 | localhost | Username: zuul
2025-11-28 00:07:10.168767 | localhost | Distro: Fedora 37
2025-11-28 00:07:10.168794 | localhost | Provider: ansible-vexxhost-ca-ymq-1
2025-11-28 00:07:10.168812 | localhost | Region: ca-ymq-1
2025-11-28 00:07:10.168828 | localhost | Label: ansible-fedora-37-1vcpu
2025-11-28 00:07:10.168844 | localhost | Product Name: OpenStack Nova
2025-11-28 00:07:10.168861 | localhost | Interface IP: 162.253.55.62
2025-11-28 00:07:10.183641 | 
2025-11-28 00:07:10.183813 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-28 00:07:10.561164 | localhost -> localhost | changed
2025-11-28 00:07:10.573377 | 
2025-11-28 00:07:10.573527 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-28 00:07:12.014740 | localhost -> localhost | changed
2025-11-28 00:07:12.060236 | 
2025-11-28 00:07:12.060330 | PLAY [all:!appliance*]
2025-11-28 00:07:12.080307 | 
2025-11-28 00:07:12.080414 | TASK [include_role : start-zuul-console]
2025-11-28 00:07:12.102074 | controller | ok
2025-11-28 00:07:12.115806 | 
2025-11-28 00:07:12.115886 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-28 00:07:12.531420 | controller | ok
2025-11-28 00:07:12.568815 | 
2025-11-28 00:07:12.568954 | TASK [use-our-mirror : Retrieve the IP address]
2025-11-28 00:07:13.573150 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-11-28 00:07:13.589288 | 
2025-11-28 00:07:13.589435 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-11-28 00:07:14.141595 | controller | skipping: Conditional result was False
2025-11-28 00:07:14.156479 | 
2025-11-28 00:07:14.157032 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-11-28 00:07:14.184666 | controller | skipping: Conditional result was False
2025-11-28 00:07:14.198761 | 
2025-11-28 00:07:14.198910 | TASK [use-our-mirror : Create the podman configuration directory]
2025-11-28 00:07:14.216420 | controller | skipping: Conditional result was False
2025-11-28 00:07:14.230465 | 
2025-11-28 00:07:14.230608 | TASK [use-our-mirror : Copy the podman configuration]
2025-11-28 00:07:14.248165 | controller | skipping: Conditional result was False
2025-11-28 00:07:14.261740 | 
2025-11-28 00:07:14.261879 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-11-28 00:07:14.279306 | controller | skipping: Conditional result was False
2025-11-28 00:07:14.289756 | 
2025-11-28 00:07:14.289861 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-11-28 00:07:14.315234 | controller | skipping: Conditional result was False
2025-11-28 00:07:14.331301 | 
2025-11-28 00:07:14.331408 | TASK [Disable Fedora Modular]
2025-11-28 00:07:14.573477 | controller | changed
2025-11-28 00:07:14.586420 | 
2025-11-28 00:07:14.586552 | TASK [Enable EPEL]
2025-11-28 00:07:14.613901 | controller | skipping: Conditional result was False
2025-11-28 00:07:14.630515 | 
2025-11-28 00:07:14.630671 | TASK [Register the RHEL node]
2025-11-28 00:07:15.191512 | 
2025-11-28 00:07:15.191886 | TASK [Show the subscription-manager status]
2025-11-28 00:07:15.766997 | controller | skipping: Conditional result was False
2025-11-28 00:07:15.786249 | 
2025-11-28 00:07:15.786428 | TASK [Enable EPEL on RHEL]
2025-11-28 00:07:16.340869 | controller | skipping: Conditional result was False
2025-11-28 00:07:16.353672 | 
2025-11-28 00:07:16.353842 | TASK [Install git and tox]
2025-11-28 00:08:44.261864 | controller | changed
2025-11-28 00:08:44.267974 | 
2025-11-28 00:08:44.268036 | TASK [include_role : prepare-workspace]
2025-11-28 00:08:44.297443 | controller | ok
2025-11-28 00:08:44.317955 | 
2025-11-28 00:08:44.318024 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-28 00:08:44.550754 | controller | ok
2025-11-28 00:08:44.556980 | 
2025-11-28 00:08:44.557177 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-28 00:14:15.958533 | controller | Output suppressed because no_log was given
2025-11-28 00:14:16.010885 | 
2025-11-28 00:14:16.011007 | TASK [include_role : prepare-workspace-openshift]
2025-11-28 00:14:16.035074 | controller | skipping: Conditional result was False
2025-11-28 00:14:16.060147 | 
2025-11-28 00:14:16.060248 | PLAY [all:!appliance]
2025-11-28 00:14:16.078540 | 
2025-11-28 00:14:16.078651 | TASK [Run add-build-sshkey role (RSA)]
2025-11-28 00:14:16.108658 | controller | ok
2025-11-28 00:14:16.149155 | 
2025-11-28 00:14:16.149284 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-28 00:14:16.390269 | controller -> localhost | ok
2025-11-28 00:14:16.406560 | 
2025-11-28 00:14:16.406778 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-28 00:14:16.438546 | controller | ok
2025-11-28 00:14:16.478629 | controller | included: /var/lib/zuul/builds/19de7138cbc74a94a829b04d9f814586/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-28 00:14:16.490127 | 
2025-11-28 00:14:16.490222 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-28 00:14:17.025405 | controller -> localhost | Generating public/private rsa key pair.
2025-11-28 00:14:17.025844 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/19de7138cbc74a94a829b04d9f814586/work/19de7138cbc74a94a829b04d9f814586_id_rsa.
2025-11-28 00:14:17.025909 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/19de7138cbc74a94a829b04d9f814586/work/19de7138cbc74a94a829b04d9f814586_id_rsa.pub.
2025-11-28 00:14:17.025954 | controller -> localhost | The key fingerprint is:
2025-11-28 00:14:17.025997 | controller -> localhost | SHA256:OdFh16H2U/Y1eKFkSfxjaYmkCOH6UCervYNprrE6vq0 zuul-build-sshkey
2025-11-28 00:14:17.026044 | controller -> localhost | The key's randomart image is:
2025-11-28 00:14:17.026083 | controller -> localhost | +---[RSA 2048]----+
2025-11-28 00:14:17.026152 | controller -> localhost | |       .. o +=oo |
2025-11-28 00:14:17.026191 | controller -> localhost | |      .. o oo=+ .|
2025-11-28 00:14:17.026223 | controller -> localhost | |       +o.o =ooo*|
2025-11-28 00:14:17.026255 | controller -> localhost | |      o ++ o o.X+|
2025-11-28 00:14:17.026285 | controller -> localhost | |     o .S     = o|
2025-11-28 00:14:17.026339 | controller -> localhost | |      =  .     . |
2025-11-28 00:14:17.026390 | controller -> localhost | |   . .oo         |
2025-11-28 00:14:17.026435 | controller -> localhost | | .. o+ ..        |
2025-11-28 00:14:17.026476 | controller -> localhost | |.E=++. ..        |
2025-11-28 00:14:17.026522 | controller -> localhost | +----[SHA256]-----+
2025-11-28 00:14:17.026630 | controller -> localhost | ok: Runtime: 0:00:00.134917
2025-11-28 00:14:17.041428 | 
2025-11-28 00:14:17.041570 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-28 00:14:17.065665 | controller | ok
2025-11-28 00:14:17.084773 | controller | included: /var/lib/zuul/builds/19de7138cbc74a94a829b04d9f814586/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-28 00:14:17.098581 | 
2025-11-28 00:14:17.098713 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-28 00:14:17.124268 | controller | skipping: Conditional result was False
2025-11-28 00:14:17.130818 | 
2025-11-28 00:14:17.130889 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-28 00:14:17.797651 | controller | changed
2025-11-28 00:14:17.812583 | 
2025-11-28 00:14:17.812710 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-28 00:14:18.057508 | controller | ok
2025-11-28 00:14:18.072413 | 
2025-11-28 00:14:18.072581 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-28 00:14:18.706127 | controller | changed
2025-11-28 00:14:18.721814 | 
2025-11-28 00:14:18.721994 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-28 00:14:19.376393 | controller | changed
2025-11-28 00:14:19.392819 | 
2025-11-28 00:14:19.393026 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-28 00:14:19.433517 | controller | skipping: Conditional result was False
2025-11-28 00:14:19.450836 | 
2025-11-28 00:14:19.451125 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-28 00:14:20.121796 | controller -> localhost | changed
2025-11-28 00:14:20.149013 | 
2025-11-28 00:14:20.149178 | TASK [add-build-sshkey : Add back temp key]
2025-11-28 00:14:20.454540 | controller -> localhost | Identity added: /var/lib/zuul/builds/19de7138cbc74a94a829b04d9f814586/work/19de7138cbc74a94a829b04d9f814586_id_rsa (zuul-build-sshkey)
2025-11-28 00:14:20.455005 | controller -> localhost | ok: Runtime: 0:00:00.012908
2025-11-28 00:14:20.469753 | 
2025-11-28 00:14:20.469898 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-28 00:14:20.860547 | controller | ok
2025-11-28 00:14:20.878319 | 
2025-11-28 00:14:20.878517 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-28 00:14:20.918942 | controller | skipping: Conditional result was False
2025-11-28 00:14:20.949657 | 
2025-11-28 00:14:20.949835 | TASK [Run add-build-sshkey role (ECDSA)]
2025-11-28 00:14:20.982260 | controller | ok
2025-11-28 00:14:21.008717 | 
2025-11-28 00:14:21.008867 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-28 00:14:21.300112 | controller -> localhost | ok
2025-11-28 00:14:21.307589 | 
2025-11-28 00:14:21.307699 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-28 00:14:21.338664 | controller | ok
2025-11-28 00:14:21.350431 | controller | included: /var/lib/zuul/builds/19de7138cbc74a94a829b04d9f814586/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-28 00:14:21.357331 | 
2025-11-28 00:14:21.357416 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-28 00:14:21.646984 | controller -> localhost | Generating public/private ecdsa key pair.
2025-11-28 00:14:21.647412 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/19de7138cbc74a94a829b04d9f814586/work/19de7138cbc74a94a829b04d9f814586_id_ecdsa.
2025-11-28 00:14:21.647487 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/19de7138cbc74a94a829b04d9f814586/work/19de7138cbc74a94a829b04d9f814586_id_ecdsa.pub.
2025-11-28 00:14:21.647556 | controller -> localhost | The key fingerprint is:
2025-11-28 00:14:21.647609 | controller -> localhost | SHA256:Y7uTNz1bpPti/YpFuhTVzJ/MO50xv8bF9xBuCcGz2ak zuul-build-sshkey
2025-11-28 00:14:21.647663 | controller -> localhost | The key's randomart image is:
2025-11-28 00:14:21.647759 | controller -> localhost | +---[ECDSA 521]---+
2025-11-28 00:14:21.647814 | controller -> localhost | |           .     |
2025-11-28 00:14:21.647950 | controller -> localhost | |            +  + |
2025-11-28 00:14:21.648003 | controller -> localhost | |             *..+|
2025-11-28 00:14:21.648051 | controller -> localhost | |            +.* o|
2025-11-28 00:14:21.648098 | controller -> localhost | |        S   .+oX.|
2025-11-28 00:14:21.648144 | controller -> localhost | |       . o  E*= @|
2025-11-28 00:14:21.648191 | controller -> localhost | |        .. .+o+=*|
2025-11-28 00:14:21.648237 | controller -> localhost | |        o.o.=*.o+|
2025-11-28 00:14:21.648283 | controller -> localhost | |        .o o==++.|
2025-11-28 00:14:21.648329 | controller -> localhost | +----[SHA256]-----+
2025-11-28 00:14:21.648440 | controller -> localhost | ok: Runtime: 0:00:00.013800
2025-11-28 00:14:21.664577 | 
2025-11-28 00:14:21.664894 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-28 00:14:21.705147 | controller | ok
2025-11-28 00:14:21.723774 | controller | included: /var/lib/zuul/builds/19de7138cbc74a94a829b04d9f814586/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-28 00:14:21.738962 | 
2025-11-28 00:14:21.739116 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-28 00:14:21.754941 | controller | skipping: Conditional result was False
2025-11-28 00:14:21.762077 | 
2025-11-28 00:14:21.762324 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-28 00:14:22.029104 | controller | changed
2025-11-28 00:14:22.038267 | 
2025-11-28 00:14:22.038366 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-28 00:14:22.265710 | controller | ok
2025-11-28 00:14:22.273949 | 
2025-11-28 00:14:22.274068 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-28 00:14:22.942282 | controller | changed
2025-11-28 00:14:22.951626 | 
2025-11-28 00:14:22.951791 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-28 00:14:23.563585 | controller | changed
2025-11-28 00:14:23.632428 | 
2025-11-28 00:14:23.632584 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-28 00:14:23.658630 | controller | skipping: Conditional result was False
2025-11-28 00:14:23.668628 | 
2025-11-28 00:14:23.668791 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-28 00:14:24.001819 | controller -> localhost | changed
2025-11-28 00:14:24.019213 | 
2025-11-28 00:14:24.019342 | TASK [add-build-sshkey : Add back temp key]
2025-11-28 00:14:24.299318 | controller -> localhost | Identity added: /var/lib/zuul/builds/19de7138cbc74a94a829b04d9f814586/work/19de7138cbc74a94a829b04d9f814586_id_ecdsa (zuul-build-sshkey)
2025-11-28 00:14:24.299595 | controller -> localhost | ok: Runtime: 0:00:00.012592
2025-11-28 00:14:24.311118 | 
2025-11-28 00:14:24.311306 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-28 00:14:24.531167 | controller | ok
2025-11-28 00:14:24.546772 | 
2025-11-28 00:14:24.546972 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-28 00:14:24.583148 | controller | skipping: Conditional result was False
2025-11-28 00:14:24.597236 | 
2025-11-28 00:14:24.597355 | TASK [include_role : remove-zuul-sshkey]
2025-11-28 00:14:24.623563 | controller | skipping: Conditional result was False
2025-11-28 00:14:24.630759 | 
2025-11-28 00:14:24.630835 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-28 00:14:24.868035 | controller | ok: "logs"
2025-11-28 00:14:24.868274 | controller | ok: All items complete
2025-11-28 00:14:24.868302 | 
2025-11-28 00:14:25.066906 | controller | ok: "artifacts"
2025-11-28 00:14:25.259081 | controller | ok: "docs"
2025-11-28 00:14:25.274825 | 
2025-11-28 00:14:25.274936 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-28 00:14:25.525254 | controller | changed: "logs"
2025-11-28 00:14:25.718734 | controller | changed: "artifacts"
2025-11-28 00:14:25.933957 | controller | changed: "docs"
2025-11-28 00:14:25.971648 | 
2025-11-28 00:14:25.971777 | PLAY RECAP
2025-11-28 00:14:25.971820 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-11-28 00:14:25.971845 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-28 00:14:25.971863 | 
2025-11-28 00:14:26.096473 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-28 00:14:26.097821 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-28 00:14:26.736342 | 
2025-11-28 00:14:26.736647 | PLAY [all]
2025-11-28 00:14:26.760889 | 
2025-11-28 00:14:26.761021 | TASK [Install binary dependencies]
2025-11-28 00:14:26.813643 | controller | ok
2025-11-28 00:14:26.833167 | 
2025-11-28 00:14:26.833288 | TASK [bindep : Include find tasks]
2025-11-28 00:14:26.862698 | controller | ok
2025-11-28 00:14:26.870751 | controller | included: /var/lib/zuul/builds/19de7138cbc74a94a829b04d9f814586/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-11-28 00:14:26.877015 | 
2025-11-28 00:14:26.877082 | TASK [bindep : Look for bindep.txt]
2025-11-28 00:14:27.447526 | controller | ok
2025-11-28 00:14:27.453858 | 
2025-11-28 00:14:27.453949 | TASK [bindep : Define bindep_file fact]
2025-11-28 00:14:27.479267 | controller | skipping: Conditional result was False
2025-11-28 00:14:27.487313 | 
2025-11-28 00:14:27.487462 | TASK [bindep : Look for other-requirements.txt]
2025-11-28 00:14:27.693047 | controller | ok
2025-11-28 00:14:27.709877 | 
2025-11-28 00:14:27.710002 | TASK [bindep : Define bindep_file fact]
2025-11-28 00:14:27.745575 | controller | skipping: Conditional result was False
2025-11-28 00:14:27.755991 | 
2025-11-28 00:14:27.756097 | TASK [bindep : Look for bindep fallback file]
2025-11-28 00:14:27.793276 | controller | skipping: Conditional result was False
2025-11-28 00:14:27.804444 | 
2025-11-28 00:14:27.804770 | TASK [bindep : Define bindep_file fact]
2025-11-28 00:14:27.829475 | controller | skipping: Conditional result was False
2025-11-28 00:14:27.836636 | 
2025-11-28 00:14:27.836737 | TASK [bindep : Include bindep tasks]
2025-11-28 00:14:27.871067 | controller | skipping: Conditional result was False
2025-11-28 00:14:27.880534 | 
2025-11-28 00:14:27.880631 | TASK [bindep : Include install tasks]
2025-11-28 00:14:27.906007 | controller | skipping: Conditional result was False
2025-11-28 00:14:27.916065 | 
2025-11-28 00:14:27.916403 | LOOP [bindep : Include package tasks]
2025-11-28 00:14:27.998242 | 
2025-11-28 00:14:27.998473 | TASK [Run test-setup role]
2025-11-28 00:14:28.023961 | controller | ok
2025-11-28 00:14:28.043282 | 
2025-11-28 00:14:28.043403 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-11-28 00:14:28.272276 | controller | ok
2025-11-28 00:14:28.285202 | 
2025-11-28 00:14:28.285359 | TASK [test-setup : Run tools/test-setup.sh]
2025-11-28 00:14:28.843100 | controller | skipping: Conditional result was False
2025-11-28 00:14:28.900320 | 
2025-11-28 00:14:28.900505 | PLAY RECAP
2025-11-28 00:14:28.900601 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-28 00:14:28.900638 | 
2025-11-28 00:14:29.012856 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-28 00:14:29.013877 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-28 00:14:29.685538 | 
2025-11-28 00:14:29.685714 | PLAY [controller]
2025-11-28 00:14:29.708918 | 
2025-11-28 00:14:29.709050 | TASK [Create the /root directory]
2025-11-28 00:14:30.287824 | controller | ok
2025-11-28 00:14:30.297738 | 
2025-11-28 00:14:30.297861 | TASK [Install glibc-langpack-en]
2025-11-28 00:14:34.341871 | controller | ok: Nothing to do
2025-11-28 00:14:34.359900 | 
2025-11-28 00:14:34.360030 | TASK [Ensure controller directory exists]
2025-11-28 00:14:34.573573 | controller | changed
2025-11-28 00:14:34.584228 | 
2025-11-28 00:14:34.584435 | TASK [Install container runtime]
2025-11-28 00:14:34.652382 | controller | ok
2025-11-28 00:14:34.687659 | 
2025-11-28 00:14:34.687780 | LOOP [ensure-podman : Find distribution installation]
2025-11-28 00:14:34.712413 | controller | ok: "/var/lib/zuul/builds/19de7138cbc74a94a829b04d9f814586/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml"
2025-11-28 00:14:34.729897 | controller | included: /var/lib/zuul/builds/19de7138cbc74a94a829b04d9f814586/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml
2025-11-28 00:14:34.736566 | 
2025-11-28 00:14:34.736634 | TASK [ensure-podman : Install podman (RedHat)]
2025-11-28 00:15:40.259254 | controller | changed
2025-11-28 00:15:40.273799 | 
2025-11-28 00:15:40.273935 | TASK [ensure-podman : Fetch podman version]
2025-11-28 00:15:40.843613 | controller | Client:       Podman Engine
2025-11-28 00:15:40.873001 | controller | Version:      4.6.2
2025-11-28 00:15:40.873028 | controller | API Version:  4.6.2
2025-11-28 00:15:40.873036 | controller | Go Version:   go1.19.12
2025-11-28 00:15:40.873053 | controller | Built:        Mon Aug 28 19:38:31 2023
2025-11-28 00:15:40.873061 | controller | OS/Arch:      linux/amd64
2025-11-28 00:15:41.327212 | controller | ok: Runtime: 0:00:00.199883
2025-11-28 00:15:41.333969 | 
2025-11-28 00:15:41.334038 | TASK [ensure-podman : Print podman version installed]
2025-11-28 00:15:41.373620 | Podman version: Client:       Podman Engine
2025-11-28 00:15:41.373784 | Version:      4.6.2
2025-11-28 00:15:41.373814 | API Version:  4.6.2
2025-11-28 00:15:41.373836 | Go Version:   go1.19.12
2025-11-28 00:15:41.373857 | Built:        Mon Aug 28 19:38:31 2023
2025-11-28 00:15:41.373879 | OS/Arch:      linux/amd64
2025-11-28 00:15:41.379760 | 
2025-11-28 00:15:41.379833 | TASK [ensure-podman : Validate podman engine]
2025-11-28 00:15:41.912094 | controller | skipping: Conditional result was False
2025-11-28 00:15:41.923413 | 
2025-11-28 00:15:41.923573 | TASK [ensure-podman : Set up docker compatability socket]
2025-11-28 00:15:41.941916 | controller | skipping: Conditional result was False
2025-11-28 00:15:41.962021 | 
2025-11-28 00:15:41.962167 | TASK [Ensure python3.8 is present]
2025-11-28 00:15:41.987456 | controller | skipping: Conditional result was False
2025-11-28 00:15:41.999046 | 
2025-11-28 00:15:41.999158 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-11-28 00:15:42.026061 | controller | ok
2025-11-28 00:15:42.054600 | 
2025-11-28 00:15:42.054717 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-11-28 00:15:43.484600 | controller | ok: Nothing to do
2025-11-28 00:15:43.491771 | 
2025-11-28 00:15:43.491869 | TASK [our-ensure-python : Also install python3-devel]
2025-11-28 00:15:51.989519 | controller | changed
2025-11-28 00:15:52.005507 | 
2025-11-28 00:15:52.005617 | TASK [Run ensure-virtualenv role]
2025-11-28 00:15:52.026595 | controller | ok
2025-11-28 00:15:52.047456 | 
2025-11-28 00:15:52.047559 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-11-28 00:15:52.296905 | controller | /usr/bin/virtualenv
2025-11-28 00:15:52.603605 | controller | ok: Runtime: 0:00:00.004473
2025-11-28 00:15:52.613596 | 
2025-11-28 00:15:52.613699 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-11-28 00:15:52.648899 | controller | skipping: Conditional result was False
2025-11-28 00:15:52.649363 | controller | ok: All items complete
2025-11-28 00:15:52.649424 | 
2025-11-28 00:15:52.678375 | 
2025-11-28 00:15:52.678563 | TASK [Find the full path of the Python interpreter]
2025-11-28 00:15:52.905195 | controller | /usr/bin/python3.10
2025-11-28 00:15:53.435575 | controller | ok
2025-11-28 00:15:53.451200 | 
2025-11-28 00:15:53.451381 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-11-28 00:15:54.604381 | controller | created virtual environment CPython3.10.13.final.0-64 in 654ms
2025-11-28 00:15:54.628732 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2025-11-28 00:15:54.629370 | 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-28 00:15:54.629517 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-11-28 00:15:54.629533 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-11-28 00:15:55.014916 | controller | changed
2025-11-28 00:15:55.022116 | 
2025-11-28 00:15:55.022201 | TASK [Set selinux package]
2025-11-28 00:15:55.048599 | controller | ok
2025-11-28 00:15:55.056553 | 
2025-11-28 00:15:55.056641 | TASK [Set selinux package (Fedora)]
2025-11-28 00:15:55.089041 | controller | ok
2025-11-28 00:15:55.096007 | 
2025-11-28 00:15:55.096088 | TASK [Install selinux into virtualenv]
2025-11-28 00:15:56.304591 | controller | Collecting selinux-please-lie-to-me
2025-11-28 00:15:56.353090 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-11-28 00:15:56.689140 | controller | Collecting setuptools<50.0.0
2025-11-28 00:15:56.695997 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-11-28 00:15:56.762443 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 12.5 MB/s eta 0:00:00
2025-11-28 00:15:56.838653 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-11-28 00:15:56.838885 | controller |   Attempting uninstall: setuptools
2025-11-28 00:15:56.839598 | controller |     Found existing installation: setuptools 62.6.0
2025-11-28 00:15:56.911642 | controller |     Uninstalling setuptools-62.6.0:
2025-11-28 00:15:56.920013 | controller |       Successfully uninstalled setuptools-62.6.0
2025-11-28 00:15:57.263676 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-11-28 00:15:57.392776 | controller |
2025-11-28 00:15:57.466838 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3
2025-11-28 00:15:57.466892 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-11-28 00:15:57.649427 | controller | ok: Runtime: 0:00:02.152806
2025-11-28 00:15:57.662892 | 
2025-11-28 00:15:57.663024 | TASK [Install pytest-forked into virtualenv]
2025-11-28 00:20:44.953386 | [Zuul] Log Stream did not terminate
2025-11-28 00:20:44.954276 | controller | ERROR
2025-11-28 00:20:44.954464 | controller | {
2025-11-28 00:20:44.954519 | controller |   "msg": "Data could not be sent to remote host \"162.253.55.62\". Make sure this host can be reached over ssh: ssh: connect to host 162.253.55.62 port 22: No route to host\r\n",
2025-11-28 00:20:44.954569 | controller |   "unreachable": true
2025-11-28 00:20:44.954606 | controller | }
unreachable
2025-11-28 00:20:44.956628 | 
2025-11-28 00:20:44.956756 | PLAY RECAP
2025-11-28 00:20:44.956839 | controller | ok: 15 changed: 7 unreachable: 1 failed: 0 skipped: 4 rescued: 0 ignored: 0
2025-11-28 00:20:44.956882 | 
2025-11-28 00:20:45.086190 | PRE-RUN END RESULT_UNREACHABLE: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-28 00:20:45.087271 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-28 00:20:45.757448 | 
2025-11-28 00:20:45.757566 | PLAY [all]
2025-11-28 00:20:45.780957 | 
2025-11-28 00:20:45.781107 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-11-28 00:21:01.814223 | controller | changed: non-zero return code
2025-11-28 00:21:01.827154 | 
2025-11-28 00:21:01.827321 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-11-28 00:21:01.855406 | controller | skipping: Conditional result was False
2025-11-28 00:21:01.869651 | 
2025-11-28 00:21:01.869914 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-11-28 00:21:01.913843 | 
2025-11-28 00:21:01.914159 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-11-28 00:21:01.945794 | 
2025-11-28 00:21:01.946108 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-11-28 00:21:01.963160 | controller | skipping: Conditional result was False
2025-11-28 00:21:01.980050 | 
2025-11-28 00:21:01.980242 | LOOP [fetch-subunit-output : Generate subunit file]
2025-11-28 00:21:02.015508 | 
2025-11-28 00:21:02.015705 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-11-28 00:21:02.031947 | controller | skipping: Conditional result was False
2025-11-28 00:21:02.041484 | 
2025-11-28 00:21:02.041583 | TASK [fetch-subunit-output : Remove the temporary file]
2025-11-28 00:21:02.057755 | controller | skipping: Conditional result was False
2025-11-28 00:21:02.067587 | 
2025-11-28 00:21:02.067702 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-11-28 00:21:02.083801 | controller | skipping: Conditional result was False
2025-11-28 00:21:02.120835 | 
2025-11-28 00:21:02.120943 | PLAY RECAP
2025-11-28 00:21:02.121000 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-28 00:21:02.121028 | 
2025-11-28 00:21:02.223538 | POST-RUN END RESULT_UNREACHABLE: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-28 00:21:02.224706 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-11-28 00:21:02.808341 | 
2025-11-28 00:21:02.808469 | PLAY [all:!appliance*]
2025-11-28 00:21:02.831125 | 
2025-11-28 00:21:02.831246 | TASK [unregister the node]
2025-11-28 00:21:03.363481 | controller | skipping: Conditional result was False
2025-11-28 00:21:03.376769 | 
2025-11-28 00:21:03.377075 | TASK [include_role : fetch-output]
2025-11-28 00:21:03.429229 | controller | ok
2025-11-28 00:21:03.470732 | 
2025-11-28 00:21:03.470888 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-28 00:21:03.547967 | controller | skipping: Conditional result was False
2025-11-28 00:21:03.557649 | 
2025-11-28 00:21:03.557811 | TASK [fetch-output : Set log path for single node]
2025-11-28 00:21:03.602370 | controller | ok
2025-11-28 00:21:03.610358 | 
2025-11-28 00:21:03.610457 | LOOP [fetch-output : Ensure local output dirs]
2025-11-28 00:21:04.024761 | controller -> localhost | ok: "/var/lib/zuul/builds/19de7138cbc74a94a829b04d9f814586/work/logs"
2025-11-28 00:21:04.283095 | controller -> localhost | changed: "/var/lib/zuul/builds/19de7138cbc74a94a829b04d9f814586/work/artifacts"
2025-11-28 00:21:04.545374 | controller -> localhost | changed: "/var/lib/zuul/builds/19de7138cbc74a94a829b04d9f814586/work/docs"
2025-11-28 00:21:04.569612 | 
2025-11-28 00:21:04.569794 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-28 00:21:05.319367 | controller | changed:
2025-11-28 00:21:05.320077 | controller | .d..t...... ./
2025-11-28 00:21:05.320173 | controller | cd+++++++++ controller/
2025-11-28 00:21:05.320255 | controller | changed: All items complete
2025-11-28 00:21:05.320316 | 
2025-11-28 00:21:05.865834 | controller | changed: .d..t...... ./
2025-11-28 00:21:06.394167 | controller | changed: .d..t...... ./
2025-11-28 00:21:06.420244 | 
2025-11-28 00:21:06.420401 | TASK [include_role : fetch-output-openshift]
2025-11-28 00:21:06.446779 | controller | skipping: Conditional result was False
2025-11-28 00:21:06.456180 | 
2025-11-28 00:21:06.456284 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-28 00:21:06.500244 | controller | skipping: Conditional result was False
2025-11-28 00:21:06.513450 | controller | skipping: Conditional result was False
2025-11-28 00:21:06.561921 | 
2025-11-28 00:21:06.562153 | PLAY [localhost]
2025-11-28 00:21:06.580359 | 
2025-11-28 00:21:06.580450 | TASK [Run Zuul manifest role]
2025-11-28 00:21:06.602241 | localhost | ok
2025-11-28 00:21:06.623845 | 
2025-11-28 00:21:06.623976 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-28 00:21:07.050119 | localhost | changed
2025-11-28 00:21:07.055054 | 
2025-11-28 00:21:07.055127 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-28 00:21:07.084022 | localhost | ok
2025-11-28 00:21:07.092433 | 
2025-11-28 00:21:07.092540 | TASK [Set zuul-log-path fact]
2025-11-28 00:21:07.111550 | localhost | ok
2025-11-28 00:21:07.127527 | 
2025-11-28 00:21:07.127663 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-28 00:21:07.157728 | localhost | ok
2025-11-28 00:21:07.166136 | 
2025-11-28 00:21:07.166205 | LOOP [Run upload-logs-swift role]
2025-11-28 00:21:07.200691 | localhost | Output suppressed because no_log was given
2025-11-28 00:21:07.228097 | 
2025-11-28 00:21:07.228207 | TASK [Set zuul-log-path fact]
2025-11-28 00:21:07.253279 | localhost | skipping: Conditional result was False
2025-11-28 00:21:07.261335 | 
2025-11-28 00:21:07.261434 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-11-28 00:21:07.718597 | localhost -> localhost | ok: Runtime: 0:00:00.006473
2025-11-28 00:21:07.759363 | 
2025-11-28 00:21:07.759505 | TASK [upload-logs-swift : Upload logs to swift]