2025-09-22 00:08:03.854818 | Job console starting...
2025-09-22 00:08:03.864846 | Updating repositories
2025-09-22 00:08:03.978167 | Preparing job workspace
2025-09-22 00:08:10.510924 | Running Ansible setup...
2025-09-22 00:08:18.626441 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-09-22 00:08:19.264410 | 
2025-09-22 00:08:19.264587 | PLAY [localhost]
2025-09-22 00:08:19.274238 | 
2025-09-22 00:08:19.274342 | TASK [Gathering Facts]
2025-09-22 00:08:20.414589 | localhost | ok
2025-09-22 00:08:20.448582 | 
2025-09-22 00:08:20.448934 | TASK [Setup log path fact]
2025-09-22 00:08:20.472926 | localhost | ok
2025-09-22 00:08:20.496344 | 
2025-09-22 00:08:20.496498 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-22 00:08:20.530219 | localhost | ok
2025-09-22 00:08:20.542328 | 
2025-09-22 00:08:20.542544 | TASK [emit-job-header : Print job information]
2025-09-22 00:08:20.597336 | # Job Information
2025-09-22 00:08:20.597596 | Ansible Version: 2.15.12
2025-09-22 00:08:20.597645 | Job: ansible-test-sanity-docker-stable-2.9
2025-09-22 00:08:20.597710 | Pipeline: periodic
2025-09-22 00:08:20.597741 | Executor: ze02.softwarefactory-project.io
2025-09-22 00:08:20.597769 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-09-22 00:08:20.597807 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/72d/ansible/72d040de6c0d4d37b7ecbc94d9401fcb/
2025-09-22 00:08:20.597837 | Event ID: 80fa768b351f40cb92b41db9ba32389a
2025-09-22 00:08:20.605128 | 
2025-09-22 00:08:20.605238 | LOOP [emit-job-header : Print node information]
2025-09-22 00:08:20.733468 | localhost | ok:
2025-09-22 00:08:20.733844 | localhost | # Node Information
2025-09-22 00:08:20.733892 | localhost | Inventory Hostname: controller
2025-09-22 00:08:20.733922 | localhost | Hostname: np0005442647
2025-09-22 00:08:20.733948 | localhost | Username: zuul
2025-09-22 00:08:20.733979 | localhost | Distro: Fedora 37
2025-09-22 00:08:20.734004 | localhost | Provider: ansible-vexxhost-ams1
2025-09-22 00:08:20.734028 | localhost | Region: ams1
2025-09-22 00:08:20.734051 | localhost | Label: ansible-fedora-37-1vcpu
2025-09-22 00:08:20.734073 | localhost | Product Name: OpenStack Nova
2025-09-22 00:08:20.734095 | localhost | Interface IP: 38.129.16.48
2025-09-22 00:08:20.744721 | 
2025-09-22 00:08:20.744978 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-09-22 00:08:21.189653 | localhost -> localhost | changed
2025-09-22 00:08:21.199372 | 
2025-09-22 00:08:21.199489 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-09-22 00:08:22.136158 | localhost -> localhost | changed
2025-09-22 00:08:22.183430 | 
2025-09-22 00:08:22.183565 | PLAY [all:!appliance*]
2025-09-22 00:08:22.209053 | 
2025-09-22 00:08:22.209279 | TASK [include_role : start-zuul-console]
2025-09-22 00:08:22.247363 | controller | ok
2025-09-22 00:08:22.268205 | 
2025-09-22 00:08:22.268328 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-09-22 00:08:23.463657 | controller | ok
2025-09-22 00:08:23.486103 | 
2025-09-22 00:08:23.486226 | TASK [use-our-mirror : Retrieve the IP address]
2025-09-22 00:08:25.579032 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-09-22 00:08:25.594455 | 
2025-09-22 00:08:25.594763 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-09-22 00:08:25.976155 | controller | skipping: Conditional result was False
2025-09-22 00:08:25.984448 | 
2025-09-22 00:08:25.984545 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-09-22 00:08:26.009995 | controller | skipping: Conditional result was False
2025-09-22 00:08:26.017809 | 
2025-09-22 00:08:26.017891 | TASK [use-our-mirror : Create the podman configuration directory]
2025-09-22 00:08:26.042989 | controller | skipping: Conditional result was False
2025-09-22 00:08:26.050683 | 
2025-09-22 00:08:26.050805 | TASK [use-our-mirror : Copy the podman configuration]
2025-09-22 00:08:26.076688 | controller | skipping: Conditional result was False
2025-09-22 00:08:26.083944 | 
2025-09-22 00:08:26.084058 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-09-22 00:08:26.109349 | controller | skipping: Conditional result was False
2025-09-22 00:08:26.118976 | 
2025-09-22 00:08:26.119082 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-09-22 00:08:26.144052 | controller | skipping: Conditional result was False
2025-09-22 00:08:26.161645 | 
2025-09-22 00:08:26.161769 | TASK [Disable Fedora Modular]
2025-09-22 00:08:27.411215 | controller | changed
2025-09-22 00:08:27.426473 | 
2025-09-22 00:08:27.426643 | TASK [Enable EPEL]
2025-09-22 00:08:27.456856 | controller | skipping: Conditional result was False
2025-09-22 00:08:27.477278 | 
2025-09-22 00:08:27.477493 | TASK [Register the RHEL node]
2025-09-22 00:08:27.953630 | 
2025-09-22 00:08:27.954054 | TASK [Show the subscription-manager status]
2025-09-22 00:08:28.365820 | controller | skipping: Conditional result was False
2025-09-22 00:08:28.375445 | 
2025-09-22 00:08:28.375579 | TASK [Enable EPEL on RHEL]
2025-09-22 00:08:28.788989 | controller | skipping: Conditional result was False
2025-09-22 00:08:28.803443 | 
2025-09-22 00:08:28.803592 | TASK [Install git and tox]
2025-09-22 00:10:59.119876 | controller | changed
2025-09-22 00:10:59.140259 | 
2025-09-22 00:10:59.140631 | TASK [include_role : prepare-workspace]
2025-09-22 00:10:59.175597 | controller | ok
2025-09-22 00:10:59.207459 | 
2025-09-22 00:10:59.207649 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-09-22 00:11:00.135028 | controller | ok
2025-09-22 00:11:00.148047 | 
2025-09-22 00:11:00.148179 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-09-22 00:11:25.854599 | controller | Output suppressed because no_log was given
2025-09-22 00:11:25.879895 | 
2025-09-22 00:11:25.880071 | TASK [include_role : prepare-workspace-openshift]
2025-09-22 00:11:25.923031 | controller | skipping: Conditional result was False
2025-09-22 00:11:25.957760 | 
2025-09-22 00:11:25.957884 | PLAY [all:!appliance]
2025-09-22 00:11:25.984743 | 
2025-09-22 00:11:25.984901 | TASK [Run add-build-sshkey role (RSA)]
2025-09-22 00:11:26.016779 | controller | ok
2025-09-22 00:11:26.051657 | 
2025-09-22 00:11:26.051791 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-09-22 00:11:26.331052 | controller -> localhost | ok
2025-09-22 00:11:26.338075 | 
2025-09-22 00:11:26.338164 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-09-22 00:11:26.361078 | controller | ok
2025-09-22 00:11:26.377265 | controller | included: /var/lib/zuul/builds/72d040de6c0d4d37b7ecbc94d9401fcb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-09-22 00:11:26.384296 | 
2025-09-22 00:11:26.384369 | TASK [add-build-sshkey : Create Temp SSH key]
2025-09-22 00:11:26.963116 | controller -> localhost | Generating public/private rsa key pair.
2025-09-22 00:11:26.963442 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/72d040de6c0d4d37b7ecbc94d9401fcb/work/72d040de6c0d4d37b7ecbc94d9401fcb_id_rsa.
2025-09-22 00:11:26.963474 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/72d040de6c0d4d37b7ecbc94d9401fcb/work/72d040de6c0d4d37b7ecbc94d9401fcb_id_rsa.pub.
2025-09-22 00:11:26.963495 | controller -> localhost | The key fingerprint is:
2025-09-22 00:11:26.963515 | controller -> localhost | SHA256:gRpAOUicrmoSeT2G4DTP2Gzz1/xrK5DSH1CxkAkGu90 zuul-build-sshkey
2025-09-22 00:11:26.963535 | controller -> localhost | The key's randomart image is:
2025-09-22 00:11:26.963554 | controller -> localhost | +---[RSA 2048]----+
2025-09-22 00:11:26.963572 | controller -> localhost | |o++o.o..o..      |
2025-09-22 00:11:26.963590 | controller -> localhost | |.oo.o  +...      |
2025-09-22 00:11:26.963608 | controller -> localhost | |.  o. . o.       |
2025-09-22 00:11:26.963625 | controller -> localhost | |.+  oo.. .       |
2025-09-22 00:11:26.963643 | controller -> localhost | |+oB+...ES        |
2025-09-22 00:11:26.963697 | controller -> localhost | |+ooB+. + .       |
2025-09-22 00:11:26.963730 | controller -> localhost | |.o..o.. = .      |
2025-09-22 00:11:26.963752 | controller -> localhost | |o.   . . = .     |
2025-09-22 00:11:26.963774 | controller -> localhost | |o     .   ++o    |
2025-09-22 00:11:26.963803 | controller -> localhost | +----[SHA256]-----+
2025-09-22 00:11:26.963860 | controller -> localhost | ok: Runtime: 0:00:00.063501
2025-09-22 00:11:26.971784 | 
2025-09-22 00:11:26.972833 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-09-22 00:11:27.009227 | controller | ok
2025-09-22 00:11:27.030275 | controller | included: /var/lib/zuul/builds/72d040de6c0d4d37b7ecbc94d9401fcb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-09-22 00:11:27.046122 | 
2025-09-22 00:11:27.046268 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-09-22 00:11:27.072444 | controller | skipping: Conditional result was False
2025-09-22 00:11:27.080532 | 
2025-09-22 00:11:27.080740 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-09-22 00:11:28.251367 | controller | changed
2025-09-22 00:11:28.257890 | 
2025-09-22 00:11:28.257969 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-09-22 00:11:28.948716 | controller | ok
2025-09-22 00:11:28.961877 | 
2025-09-22 00:11:28.962028 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-09-22 00:11:32.099049 | controller | changed
2025-09-22 00:11:32.113097 | 
2025-09-22 00:11:32.113288 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-09-22 00:11:35.145338 | controller | changed
2025-09-22 00:11:35.160724 | 
2025-09-22 00:11:35.160926 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-09-22 00:11:35.200311 | controller | skipping: Conditional result was False
2025-09-22 00:11:35.217377 | 
2025-09-22 00:11:35.217553 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-09-22 00:11:35.694202 | controller -> localhost | changed
2025-09-22 00:11:35.706144 | 
2025-09-22 00:11:35.706227 | TASK [add-build-sshkey : Add back temp key]
2025-09-22 00:11:36.018207 | controller -> localhost | Identity added: /var/lib/zuul/builds/72d040de6c0d4d37b7ecbc94d9401fcb/work/72d040de6c0d4d37b7ecbc94d9401fcb_id_rsa (zuul-build-sshkey)
2025-09-22 00:11:36.018482 | controller -> localhost | ok: Runtime: 0:00:00.011665
2025-09-22 00:11:36.026005 | 
2025-09-22 00:11:36.026067 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-09-22 00:11:36.948203 | controller | ok
2025-09-22 00:11:36.963243 | 
2025-09-22 00:11:36.963402 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-09-22 00:11:37.012557 | controller | skipping: Conditional result was False
2025-09-22 00:11:37.039334 | 
2025-09-22 00:11:37.039516 | TASK [Run add-build-sshkey role (ECDSA)]
2025-09-22 00:11:37.083643 | controller | ok
2025-09-22 00:11:37.113067 | 
2025-09-22 00:11:37.113208 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-09-22 00:11:37.403784 | controller -> localhost | ok
2025-09-22 00:11:37.419317 | 
2025-09-22 00:11:37.419470 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-09-22 00:11:37.461565 | controller | ok
2025-09-22 00:11:37.480923 | controller | included: /var/lib/zuul/builds/72d040de6c0d4d37b7ecbc94d9401fcb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-09-22 00:11:37.488091 | 
2025-09-22 00:11:37.488168 | TASK [add-build-sshkey : Create Temp SSH key]
2025-09-22 00:11:37.810177 | controller -> localhost | Generating public/private ecdsa key pair.
2025-09-22 00:11:37.810531 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/72d040de6c0d4d37b7ecbc94d9401fcb/work/72d040de6c0d4d37b7ecbc94d9401fcb_id_ecdsa.
2025-09-22 00:11:37.810567 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/72d040de6c0d4d37b7ecbc94d9401fcb/work/72d040de6c0d4d37b7ecbc94d9401fcb_id_ecdsa.pub.
2025-09-22 00:11:37.810606 | controller -> localhost | The key fingerprint is:
2025-09-22 00:11:37.810632 | controller -> localhost | SHA256:UKwzYzAon6VW4ZRK8ikuoLiyKulCBU31CX8EGwEK+cQ zuul-build-sshkey
2025-09-22 00:11:37.810683 | controller -> localhost | The key's randomart image is:
2025-09-22 00:11:37.810714 | controller -> localhost | +---[ECDSA 521]---+
2025-09-22 00:11:37.810740 | controller -> localhost | | .=o+*.=+.       |
2025-09-22 00:11:37.810764 | controller -> localhost | |oo+EB +.*        |
2025-09-22 00:11:37.810791 | controller -> localhost | | *+O.o.* .       |
2025-09-22 00:11:37.810817 | controller -> localhost | |o Oo  *..        |
2025-09-22 00:11:37.810841 | controller -> localhost | |=o.  . +S        |
2025-09-22 00:11:37.810863 | controller -> localhost | |+o               |
2025-09-22 00:11:37.810883 | controller -> localhost | |oo               |
2025-09-22 00:11:37.810901 | controller -> localhost | |*                |
2025-09-22 00:11:37.810920 | controller -> localhost | |Oo               |
2025-09-22 00:11:37.810940 | controller -> localhost | +----[SHA256]-----+
2025-09-22 00:11:37.811002 | controller -> localhost | ok: Runtime: 0:00:00.013276
2025-09-22 00:11:37.821598 | 
2025-09-22 00:11:37.821807 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-09-22 00:11:37.857269 | controller | ok
2025-09-22 00:11:37.865904 | controller | included: /var/lib/zuul/builds/72d040de6c0d4d37b7ecbc94d9401fcb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-09-22 00:11:37.876510 | 
2025-09-22 00:11:37.876614 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-09-22 00:11:37.901910 | controller | skipping: Conditional result was False
2025-09-22 00:11:37.910001 | 
2025-09-22 00:11:37.910111 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-09-22 00:11:38.837811 | controller | changed
2025-09-22 00:11:38.851340 | 
2025-09-22 00:11:38.851494 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-09-22 00:11:39.549769 | controller | ok
2025-09-22 00:11:39.556757 | 
2025-09-22 00:11:39.556853 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-09-22 00:11:42.679318 | controller | changed
2025-09-22 00:11:42.694601 | 
2025-09-22 00:11:42.694812 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-09-22 00:11:45.963979 | controller | changed
2025-09-22 00:11:45.975018 | 
2025-09-22 00:11:45.975192 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-09-22 00:11:46.015869 | controller | skipping: Conditional result was False
2025-09-22 00:11:46.026041 | 
2025-09-22 00:11:46.026153 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-09-22 00:11:46.361307 | controller -> localhost | changed
2025-09-22 00:11:46.388232 | 
2025-09-22 00:11:46.388438 | TASK [add-build-sshkey : Add back temp key]
2025-09-22 00:11:46.734164 | controller -> localhost | Identity added: /var/lib/zuul/builds/72d040de6c0d4d37b7ecbc94d9401fcb/work/72d040de6c0d4d37b7ecbc94d9401fcb_id_ecdsa (zuul-build-sshkey)
2025-09-22 00:11:46.734435 | controller -> localhost | ok: Runtime: 0:00:00.014437
2025-09-22 00:11:46.742228 | 
2025-09-22 00:11:46.742298 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-09-22 00:11:47.504070 | controller | ok
2025-09-22 00:11:47.516910 | 
2025-09-22 00:11:47.517204 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-09-22 00:11:47.559091 | controller | skipping: Conditional result was False
2025-09-22 00:11:47.579562 | 
2025-09-22 00:11:47.579823 | TASK [include_role : remove-zuul-sshkey]
2025-09-22 00:11:47.607398 | controller | skipping: Conditional result was False
2025-09-22 00:11:47.616821 | 
2025-09-22 00:11:47.617020 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-09-22 00:11:48.333814 | controller | ok: "logs"
2025-09-22 00:11:48.334175 | controller | ok: All items complete
2025-09-22 00:11:48.334214 | 
2025-09-22 00:11:48.985960 | controller | ok: "artifacts"
2025-09-22 00:11:49.645467 | controller | ok: "docs"
2025-09-22 00:11:49.667347 | 
2025-09-22 00:11:49.667554 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-09-22 00:11:50.365233 | controller | changed: "logs"
2025-09-22 00:11:51.014557 | controller | changed: "artifacts"
2025-09-22 00:11:51.708451 | controller | changed: "docs"
2025-09-22 00:11:51.792310 | 
2025-09-22 00:11:51.792462 | PLAY RECAP
2025-09-22 00:11:51.792509 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-09-22 00:11:51.792534 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-09-22 00:11:51.792552 | 
2025-09-22 00:11:51.994976 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-09-22 00:11:51.996190 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-09-22 00:11:52.684592 | 
2025-09-22 00:11:52.684856 | PLAY [all]
2025-09-22 00:11:52.714185 | 
2025-09-22 00:11:52.714390 | TASK [Install binary dependencies]
2025-09-22 00:11:52.798825 | controller | ok
2025-09-22 00:11:52.829590 | 
2025-09-22 00:11:52.829829 | TASK [bindep : Include find tasks]
2025-09-22 00:11:52.885448 | controller | ok
2025-09-22 00:11:52.898857 | controller | included: /var/lib/zuul/builds/72d040de6c0d4d37b7ecbc94d9401fcb/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-09-22 00:11:52.908649 | 
2025-09-22 00:11:52.908770 | TASK [bindep : Look for bindep.txt]
2025-09-22 00:11:53.860702 | controller | ok
2025-09-22 00:11:53.874934 | 
2025-09-22 00:11:53.875088 | TASK [bindep : Define bindep_file fact]
2025-09-22 00:11:53.905405 | controller | skipping: Conditional result was False
2025-09-22 00:11:53.924934 | 
2025-09-22 00:11:53.925239 | TASK [bindep : Look for other-requirements.txt]
2025-09-22 00:11:54.563695 | controller | ok
2025-09-22 00:11:54.571514 | 
2025-09-22 00:11:54.571634 | TASK [bindep : Define bindep_file fact]
2025-09-22 00:11:54.596162 | controller | skipping: Conditional result was False
2025-09-22 00:11:54.606459 | 
2025-09-22 00:11:54.606563 | TASK [bindep : Look for bindep fallback file]
2025-09-22 00:11:54.637079 | controller | skipping: Conditional result was False
2025-09-22 00:11:54.647073 | 
2025-09-22 00:11:54.647177 | TASK [bindep : Define bindep_file fact]
2025-09-22 00:11:54.672055 | controller | skipping: Conditional result was False
2025-09-22 00:11:54.680913 | 
2025-09-22 00:11:54.681012 | TASK [bindep : Include bindep tasks]
2025-09-22 00:11:54.705767 | controller | skipping: Conditional result was False
2025-09-22 00:11:54.723581 | 
2025-09-22 00:11:54.723997 | TASK [bindep : Include install tasks]
2025-09-22 00:11:54.763748 | controller | skipping: Conditional result was False
2025-09-22 00:11:54.771724 | 
2025-09-22 00:11:54.771826 | LOOP [bindep : Include package tasks]
2025-09-22 00:11:54.843224 | 
2025-09-22 00:11:54.843436 | TASK [Run test-setup role]
2025-09-22 00:11:54.865907 | controller | ok
2025-09-22 00:11:54.885365 | 
2025-09-22 00:11:54.885449 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-09-22 00:11:55.640511 | controller | ok
2025-09-22 00:11:55.655786 | 
2025-09-22 00:11:55.656021 | TASK [test-setup : Run tools/test-setup.sh]
2025-09-22 00:11:56.028782 | controller | skipping: Conditional result was False
2025-09-22 00:11:56.085845 | 
2025-09-22 00:11:56.086004 | PLAY RECAP
2025-09-22 00:11:56.086063 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-09-22 00:11:56.086091 | 
2025-09-22 00:11:56.227251 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-09-22 00:11:56.229091 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-09-22 00:11:56.888372 | 
2025-09-22 00:11:56.888605 | PLAY [controller]
2025-09-22 00:11:56.910895 | 
2025-09-22 00:11:56.911077 | TASK [Create the /root directory]
2025-09-22 00:11:58.016444 | controller | ok
2025-09-22 00:11:58.026979 | 
2025-09-22 00:11:58.027115 | TASK [Install glibc-langpack-en]
2025-09-22 00:12:07.003328 | controller | ok: Nothing to do
2025-09-22 00:12:07.015041 | 
2025-09-22 00:12:07.015156 | TASK [Ensure controller directory exists]
2025-09-22 00:12:07.835086 | controller | changed
2025-09-22 00:12:07.840845 | 
2025-09-22 00:12:07.840909 | TASK [Install container runtime]
2025-09-22 00:12:07.881471 | controller | ok
2025-09-22 00:12:07.937851 | 
2025-09-22 00:12:07.938017 | LOOP [ensure-docker : Gather variables for each operating system]
2025-09-22 00:12:07.972345 | controller | ok: "/var/lib/zuul/builds/72d040de6c0d4d37b7ecbc94d9401fcb/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2025-09-22 00:12:07.981964 | 
2025-09-22 00:12:07.982118 | TASK [ensure-docker : Sanity check for distro selection]
2025-09-22 00:12:07.997521 | controller | skipping: Conditional result was False
2025-09-22 00:12:08.005732 | 
2025-09-22 00:12:08.005850 | TASK [ensure-docker : Sanity check for distro install]
2025-09-22 00:12:08.030656 | controller | skipping: Conditional result was False
2025-09-22 00:12:08.037596 | 
2025-09-22 00:12:08.037689 | TASK [ensure-docker : Sanity check for upstream install]
2025-09-22 00:12:08.062475 | controller | skipping: Conditional result was False
2025-09-22 00:12:08.069012 | 
2025-09-22 00:12:08.069145 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2025-09-22 00:12:08.094372 | controller | skipping: Conditional result was False
2025-09-22 00:12:08.104111 | 
2025-09-22 00:12:08.104261 | TASK [ensure-docker : Include tasks for docker install]
2025-09-22 00:12:08.128746 | controller | ok
2025-09-22 00:12:08.145362 | controller | included: /var/lib/zuul/builds/72d040de6c0d4d37b7ecbc94d9401fcb/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2025-09-22 00:12:08.157311 | 
2025-09-22 00:12:08.157410 | TASK [ensure-docker : Not Implemented]
2025-09-22 00:12:08.174176 | controller | skipping: Conditional result was False
2025-09-22 00:12:08.184072 | 
2025-09-22 00:12:08.184254 | TASK [Set up docker mirrors]
2025-09-22 00:12:08.207101 | controller | ok
2025-09-22 00:12:08.236468 | 
2025-09-22 00:12:08.236650 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2025-09-22 00:12:08.282525 | controller | skipping: Conditional result was False
2025-09-22 00:12:08.338103 | 
2025-09-22 00:12:08.338287 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2025-09-22 00:12:08.374597 | controller | skipping: Conditional result was False
2025-09-22 00:12:08.382093 | 
2025-09-22 00:12:08.382199 | TASK [use-docker-mirror : Set docker_mirror fact]
2025-09-22 00:12:08.428895 | controller | skipping: Conditional result was False
2025-09-22 00:12:08.443009 | 
2025-09-22 00:12:08.443160 | TASK [use-docker-mirror : Set up docker mirrors]
2025-09-22 00:12:08.481799 | controller | skipping: Conditional result was False
2025-09-22 00:12:08.506453 | 
2025-09-22 00:12:08.506592 | TASK [Add all repositories]
2025-09-22 00:12:08.541479 | controller | ok
2025-09-22 00:12:08.576867 | 
2025-09-22 00:12:08.576969 | LOOP [ensure-package-repositories : Install repositories]
2025-09-22 00:12:08.625007 | controller | ok: "/var/lib/zuul/builds/72d040de6c0d4d37b7ecbc94d9401fcb/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2025-09-22 00:12:08.627376 | controller | ok: All items complete
2025-09-22 00:12:08.627554 | 
2025-09-22 00:12:08.671038 | controller | included: /var/lib/zuul/builds/72d040de6c0d4d37b7ecbc94d9401fcb/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2025-09-22 00:12:08.685652 | 
2025-09-22 00:12:08.685832 | TASK [ensure-package-repositories : Create GPG key temp directory]
2025-09-22 00:12:09.672224 | controller | changed
2025-09-22 00:12:09.687457 | 
2025-09-22 00:12:09.687697 | LOOP [ensure-package-repositories : Upload GPG keys]
2025-09-22 00:12:12.941797 | controller | changed:
2025-09-22 00:12:12.942057 | controller | {
2025-09-22 00:12:12.942124 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2025-09-22 00:12:12.942158 | controller | }
2025-09-22 00:12:12.964861 | 
2025-09-22 00:12:12.965091 | LOOP [ensure-package-repositories : Import GPG keys]
2025-09-22 00:12:14.276828 | controller | changed:
2025-09-22 00:12:14.277053 | controller | {
2025-09-22 00:12:14.277080 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2025-09-22 00:12:14.277102 | controller | }
2025-09-22 00:12:14.277133 | controller | changed: All items complete
2025-09-22 00:12:14.277153 | 
2025-09-22 00:12:14.299295 | 
2025-09-22 00:12:14.299541 | TASK [ensure-package-repositories : Remove GPG temp directory]
2025-09-22 00:12:15.029589 | controller | changed
2025-09-22 00:12:15.043135 | 
2025-09-22 00:12:15.043282 | LOOP [ensure-package-repositories : Add repositories]
2025-09-22 00:12:15.935279 | controller | changed:
2025-09-22 00:12:15.935464 | controller | {
2025-09-22 00:12:15.935488 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2025-09-22 00:12:15.935507 | controller |   "description": "Docker CE Stable - $basearch",
2025-09-22 00:12:15.935523 | controller |   "gpgcheck": true,
2025-09-22 00:12:15.935540 | controller |   "name": "docker-ce-stable"
2025-09-22 00:12:15.935557 | controller | }
2025-09-22 00:12:15.976021 | 
2025-09-22 00:12:15.976336 | TASK [ensure-docker : Install pre-reqs]
2025-09-22 00:12:21.441541 | controller | ok: Nothing to do
2025-09-22 00:12:21.453945 | 
2025-09-22 00:12:21.454116 | TASK [ensure-docker : Remove packages]
2025-09-22 00:12:24.859232 | controller | ok: Nothing to do
2025-09-22 00:12:24.873016 | 
2025-09-22 00:12:24.873218 | TASK [ensure-docker : Install upstream docker using package]
2025-09-22 00:13:55.759625 | controller | changed
2025-09-22 00:13:55.771601 | 
2025-09-22 00:13:55.771820 | TASK [ensure-docker : Setup docker]
2025-09-22 00:13:55.792788 | controller | ok
2025-09-22 00:13:55.803888 | controller | included: /var/lib/zuul/builds/72d040de6c0d4d37b7ecbc94d9401fcb/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2025-09-22 00:13:55.816427 | 
2025-09-22 00:13:55.816551 | TASK [ensure-docker : Ensure "docker" group exists]
2025-09-22 00:13:56.851177 | controller | ok
2025-09-22 00:13:56.859701 | 
2025-09-22 00:13:56.859784 | TASK [ensure-docker : Add user to docker group]
2025-09-22 00:13:57.968085 | controller | changed
2025-09-22 00:13:57.974273 | 
2025-09-22 00:13:57.974338 | TASK [Update docker daemon proxy configuration]
2025-09-22 00:13:57.998216 | controller | skipping: Conditional result was False
2025-09-22 00:13:58.027881 | 
2025-09-22 00:13:58.028048 | TASK [Lower default MTU]
2025-09-22 00:13:58.041890 | controller | skipping: Conditional result was False
2025-09-22 00:13:58.049132 | 
2025-09-22 00:13:58.049196 | TASK [ensure-docker : Get the status of the docket.socket unit]
2025-09-22 00:13:58.824521 | controller | inactive
2025-09-22 00:13:59.406570 | controller | ok: Runtime: 0:00:00.017034
2025-09-22 00:13:59.420242 | 
2025-09-22 00:13:59.420418 | TASK [ensure-docker : Get the status of the docket unit]
2025-09-22 00:14:00.207856 | controller | inactive
2025-09-22 00:14:00.799045 | controller | ok: Runtime: 0:00:00.022042
2025-09-22 00:14:00.813715 | 
2025-09-22 00:14:00.813884 | TASK [ensure-docker : Restart docker]
2025-09-22 00:14:00.873947 | Trigger a Docker restart
2025-09-22 00:14:00.886423 | 
2025-09-22 00:14:00.886561 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2025-09-22 00:14:00.930075 | 
2025-09-22 00:14:00.930224 | TASK [ensure-docker : Install docker-compose]
2025-09-22 00:14:00.946561 | controller | skipping: Conditional result was False
2025-09-22 00:14:00.963963 | 
2025-09-22 00:14:00.964130 | TASK [ensure-docker : Flush handlers before role exit]
2025-09-22 00:14:01.015161 | 
2025-09-22 00:14:01.015307 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2025-09-22 00:14:04.475856 | controller | changed
2025-09-22 00:14:04.486247 | 
2025-09-22 00:14:04.486359 | TASK [ensure-docker : Assure docker service is running]
2025-09-22 00:14:08.578712 | controller | changed
2025-09-22 00:14:08.595183 | 
2025-09-22 00:14:08.595326 | TASK [ensure-docker : Assure docker.socket service is running]
2025-09-22 00:14:10.057436 | controller | ok
2025-09-22 00:14:10.075307 | 
2025-09-22 00:14:10.075503 | TASK [ensure-docker : Correct group ownership on docker sock]
2025-09-22 00:14:10.842658 | controller | ok
2025-09-22 00:14:10.856322 | 
2025-09-22 00:14:10.856473 | TASK [ensure-docker : Validate ability to talk with docker]
2025-09-22 00:14:11.598641 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2025-09-22 00:14:11.731422 | controller | ok: Runtime: 0:00:00.066977
2025-09-22 00:14:11.744164 | 
2025-09-22 00:14:11.744264 | TASK [ensure-docker : Get version details]
2025-09-22 00:14:12.487911 | controller | Client: Docker Engine - Community
2025-09-22 00:14:12.487986 | controller |  Version:           24.0.7
2025-09-22 00:14:12.487997 | controller |  API version:       1.43
2025-09-22 00:14:12.488005 | controller |  Go version:        go1.20.10
2025-09-22 00:14:12.488025 | controller |  Git commit:        afdd53b
2025-09-22 00:14:12.488037 | controller |  Built:             Thu Oct 26 09:09:39 2023
2025-09-22 00:14:12.488095 | controller |  OS/Arch:           linux/amd64
2025-09-22 00:14:12.488108 | controller |  Context:           default
2025-09-22 00:14:12.488130 | controller |
2025-09-22 00:14:12.488139 | controller | Server: Docker Engine - Community
2025-09-22 00:14:12.488163 | controller |  Engine:
2025-09-22 00:14:12.488169 | controller |   Version:          24.0.7
2025-09-22 00:14:12.488175 | controller |   API version:      1.43 (minimum version 1.12)
2025-09-22 00:14:12.488181 | controller |   Go version:       go1.20.10
2025-09-22 00:14:12.488187 | controller |   Git commit:       311b9ff
2025-09-22 00:14:12.488193 | controller |   Built:            Thu Oct 26 09:08:11 2023
2025-09-22 00:14:12.488199 | controller |   OS/Arch:          linux/amd64
2025-09-22 00:14:12.488205 | controller |   Experimental:     false
2025-09-22 00:14:12.488211 | controller |  containerd:
2025-09-22 00:14:12.488221 | controller |   Version:          1.6.25
2025-09-22 00:14:12.489914 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2025-09-22 00:14:12.490017 | controller |  runc:
2025-09-22 00:14:12.490028 | controller |   Version:          1.1.10
2025-09-22 00:14:12.490035 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2025-09-22 00:14:12.490045 | controller |  docker-init:
2025-09-22 00:14:12.490052 | controller |   Version:          0.19.0
2025-09-22 00:14:12.490058 | controller |   GitCommit:        de40ad0
2025-09-22 00:14:13.118005 | controller | ok: Runtime: 0:00:00.056469
2025-09-22 00:14:13.132085 | 
2025-09-22 00:14:13.132258 | TASK [ensure-docker : Dump installed docker details]
2025-09-22 00:14:13.174236 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version:           24.0.7\n API version:       1.43\n Go version:        go1.20.10\n Git commit:        afdd53b\n Built:             Thu Oct 26 09:09:39 2023\n OS/Arch:           linux/amd64\n Context:           default\n\nServer: Docker Engine - Community\n Engine:\n  Version:          24.0.7\n  API version:      1.43 (minimum version 1.12)\n  Go version:       go1.20.10\n  Git commit:       311b9ff\n  Built:            Thu Oct 26 09:08:11 2023\n  OS/Arch:          linux/amd64\n  Experimental:     false\n containerd:\n  Version:          1.6.25\n  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n  Version:          1.1.10\n  GitCommit:        v1.1.10-0-g18a0cb0\n docker-init:\n  Version:          0.19.0\n  GitCommit:        de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2025-09-22 00:14:12.437060', 'end': '2025-09-22 00:14:12.493529', 'delta': '0:00:00.056469', 'msg': '', 'zuul_log_id': 'fa163efc-24cc-47d4-3c9d-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version:           24.0.7', ' API version:       1.43', ' Go version:        go1.20.10', ' Git commit:        afdd53b', ' Built:             Thu Oct 26 09:09:39 2023', ' OS/Arch:           linux/amd64', ' Context:           default', '', 'Server: Docker Engine - Community', ' Engine:', '  Version:          24.0.7', '  API version:      1.43 (minimum version 1.12)', '  Go version:       go1.20.10', '  Git commit:       311b9ff', '  Built:            Thu Oct 26 09:08:11 2023', '  OS/Arch:          linux/amd64', '  Experimental:     false', ' containerd:', '  Version:          1.6.25', '  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', '  Version:          1.1.10', '  GitCommit:        v1.1.10-0-g18a0cb0', ' docker-init:', '  Version:          0.19.0', '  GitCommit:        de40ad0'], 'stderr_lines': [], 'failed': False}
2025-09-22 00:14:13.187928 | 
2025-09-22 00:14:13.188123 | TASK [Use buildset registry]
2025-09-22 00:14:13.215454 | controller | skipping: Conditional result was False
2025-09-22 00:14:13.233925 | 
2025-09-22 00:14:13.234112 | TASK [Ensure python3.8 is present]
2025-09-22 00:14:13.249831 | controller | skipping: Conditional result was False
2025-09-22 00:14:13.261201 | 
2025-09-22 00:14:13.261374 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-09-22 00:14:13.301818 | controller | ok
2025-09-22 00:14:13.331861 | 
2025-09-22 00:14:13.332002 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-09-22 00:14:19.467459 | controller | ok: Nothing to do
2025-09-22 00:14:19.473045 | 
2025-09-22 00:14:19.473113 | TASK [our-ensure-python : Also install python3-devel]
2025-09-22 00:14:36.644244 | controller | changed
2025-09-22 00:14:36.669480 | 
2025-09-22 00:14:36.669648 | TASK [Run ensure-virtualenv role]
2025-09-22 00:14:36.704855 | controller | ok
2025-09-22 00:14:36.743274 | 
2025-09-22 00:14:36.743432 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-09-22 00:14:37.523741 | controller | /usr/bin/virtualenv
2025-09-22 00:14:38.107518 | controller | ok: Runtime: 0:00:00.006141
2025-09-22 00:14:38.121236 | 
2025-09-22 00:14:38.121391 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-09-22 00:14:38.159247 | controller | skipping: Conditional result was False
2025-09-22 00:14:38.160005 | controller | changed: All items complete
2025-09-22 00:14:38.160095 | 
2025-09-22 00:14:38.195580 | 
2025-09-22 00:14:38.195817 | TASK [Find the full path of the Python interpreter]
2025-09-22 00:14:39.039321 | controller | /usr/bin/python3.8
2025-09-22 00:14:39.567453 | controller | ok
2025-09-22 00:14:39.581601 | 
2025-09-22 00:14:39.581849 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-09-22 00:14:42.348749 | controller | created virtual environment CPython3.8.18.final.0-64 in 1274ms
2025-09-22 00:14:42.457634 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2025-09-22 00:14:42.457683 | 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-09-22 00:14:42.457700 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-09-22 00:14:42.457721 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-09-22 00:14:42.972017 | controller | changed
2025-09-22 00:14:42.984562 | 
2025-09-22 00:14:42.984739 | TASK [Set selinux package]
2025-09-22 00:14:43.023765 | controller | ok
2025-09-22 00:14:43.035395 | 
2025-09-22 00:14:43.035525 | TASK [Set selinux package (Fedora)]
2025-09-22 00:14:43.084886 | controller | ok
2025-09-22 00:14:43.097125 | 
2025-09-22 00:14:43.097303 | TASK [Install selinux into virtualenv]
2025-09-22 00:14:49.183457 | controller | Collecting selinux-please-lie-to-me
2025-09-22 00:14:49.257283 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-09-22 00:14:49.979987 | controller | Collecting setuptools<50.0.0
2025-09-22 00:14:49.987319 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-09-22 00:14:50.047102 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 16.0 MB/s eta 0:00:00
2025-09-22 00:14:50.213390 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-09-22 00:14:50.214522 | controller |   Attempting uninstall: setuptools
2025-09-22 00:14:50.214554 | controller |     Found existing installation: setuptools 62.6.0
2025-09-22 00:14:50.358321 | controller |     Uninstalling setuptools-62.6.0:
2025-09-22 00:14:50.380819 | controller |       Successfully uninstalled setuptools-62.6.0
2025-09-22 00:14:51.296297 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-09-22 00:14:51.556995 | controller |
2025-09-22 00:14:51.902568 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-09-22 00:14:51.904724 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-09-22 00:14:52.498007 | controller | ok: Runtime: 0:00:07.838405
2025-09-22 00:14:52.510475 | 
2025-09-22 00:14:52.510634 | TASK [Install pytest-forked into virtualenv]
2025-09-22 00:14:54.309017 | controller | Collecting pytest-forked
2025-09-22 00:14:54.394607 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2025-09-22 00:14:54.485044 | controller | Collecting py
2025-09-22 00:14:54.490435 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2025-09-22 00:14:54.531308 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 2.7 MB/s eta 0:00:00
2025-09-22 00:14:54.789705 | controller | Collecting pytest>=3.10
2025-09-22 00:14:54.798168 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2025-09-22 00:14:54.821717 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 20.2 MB/s eta 0:00:00
2025-09-22 00:14:54.928049 | controller | Collecting tomli>=1
2025-09-22 00:14:54.945129 | controller |   Downloading tomli-2.2.1-py3-none-any.whl (14 kB)
2025-09-22 00:14:55.029098 | controller | Collecting pluggy<2,>=1.5
2025-09-22 00:14:55.033783 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2025-09-22 00:14:55.106375 | controller | Collecting exceptiongroup>=1.0.0rc8
2025-09-22 00:14:55.116510 | controller |   Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB)
2025-09-22 00:14:55.179930 | controller | Collecting iniconfig
2025-09-22 00:14:55.188506 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2025-09-22 00:14:55.292971 | controller | Collecting packaging
2025-09-22 00:14:55.298160 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-09-22 00:14:55.328816 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 2.5 MB/s eta 0:00:00
2025-09-22 00:14:55.440050 | controller | Collecting typing-extensions>=4.6.0
2025-09-22 00:14:55.447703 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2025-09-22 00:14:55.462273 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 3.2 MB/s eta 0:00:00
2025-09-22 00:14:55.602390 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2025-09-22 00:14:56.732004 | controller | Successfully installed exceptiongroup-1.3.0 iniconfig-2.1.0 packaging-25.0 pluggy-1.5.0 py-1.11.0 pytest-8.3.5 pytest-forked-1.6.0 tomli-2.2.1 typing-extensions-4.13.2
2025-09-22 00:14:56.741595 | controller |
2025-09-22 00:14:56.977173 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-09-22 00:14:56.977229 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-09-22 00:14:57.415123 | controller | ok: Runtime: 0:00:03.583490
2025-09-22 00:14:57.423262 | 
2025-09-22 00:14:57.423388 | TASK [Update pip]
2025-09-22 00:14:59.643443 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2025-09-22 00:15:00.051801 | controller | Collecting pip
2025-09-22 00:15:00.129487 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2025-09-22 00:15:00.217168 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 22.4 MB/s eta 0:00:00
2025-09-22 00:15:00.363733 | controller | Installing collected packages: pip
2025-09-22 00:15:00.364191 | controller |   Attempting uninstall: pip
2025-09-22 00:15:00.368202 | controller |     Found existing installation: pip 22.2.2
2025-09-22 00:15:00.956969 | controller |     Uninstalling pip-22.2.2:
2025-09-22 00:15:01.046006 | controller |       Successfully uninstalled pip-22.2.2
2025-09-22 00:15:03.919409 | controller | Successfully installed pip-25.0.1
2025-09-22 00:15:04.319283 | controller | ok: Runtime: 0:00:05.687633
2025-09-22 00:15:04.332618 | 
2025-09-22 00:15:04.332803 | TASK [Ensure pip temp directory exists in /var/tmp]
2025-09-22 00:15:05.559405 | controller | changed
2025-09-22 00:15:05.565373 | 
2025-09-22 00:15:05.565445 | TASK [Install ansible into virtualenv]
2025-09-22 00:15:07.523081 | controller | Processing ./src/github.com/ansible/ansible
2025-09-22 00:15:07.524804 | controller |   Preparing metadata (setup.py): started
2025-09-22 00:15:09.866442 | controller |   Preparing metadata (setup.py): finished with status 'done'
2025-09-22 00:15:09.945503 | controller | Collecting jinja2 (from ansible==2.9.27.post0)
2025-09-22 00:15:09.960865 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2025-09-22 00:15:10.073384 | controller | Collecting PyYAML (from ansible==2.9.27.post0)
2025-09-22 00:15:10.079485 | controller |   Downloading PyYAML-6.0.2-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (2.1 kB)
2025-09-22 00:15:10.604020 | controller | Collecting cryptography (from ansible==2.9.27.post0)
2025-09-22 00:15:10.611324 | controller |   Downloading cryptography-46.0.1-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2025-09-22 00:15:10.920456 | controller | Collecting cffi>=1.14 (from cryptography->ansible==2.9.27.post0)
2025-09-22 00:15:10.925435 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2025-09-22 00:15:10.932559 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.8/site-packages (from cryptography->ansible==2.9.27.post0) (4.13.2)
2025-09-22 00:15:11.067041 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible==2.9.27.post0)
2025-09-22 00:15:11.074116 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2025-09-22 00:15:11.109023 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible==2.9.27.post0)
2025-09-22 00:15:11.114910 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2025-09-22 00:15:11.140553 | controller | Downloading cryptography-46.0.1-cp38-abi3-manylinux_2_34_x86_64.whl (4.6 MB)
2025-09-22 00:15:11.223600 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.6/4.6 MB 62.3 MB/s eta 0:00:00
2025-09-22 00:15:11.231290 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2025-09-22 00:15:11.261047 | controller | Downloading PyYAML-6.0.2-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (746 kB)
2025-09-22 00:15:11.290299 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 746.5/746.5 kB 23.7 MB/s eta 0:00:00
2025-09-22 00:15:11.301099 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2025-09-22 00:15:11.324833 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2025-09-22 00:15:11.348514 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2025-09-22 00:15:11.400841 | controller | Building wheels for collected packages: ansible
2025-09-22 00:15:11.402134 | controller |   Building wheel for ansible (setup.py): started
2025-09-22 00:15:22.207150 | controller |   Building wheel for ansible (setup.py): finished with status 'done'
2025-09-22 00:15:22.232242 | controller |   Created wheel for ansible: filename=ansible-2.9.27.post0-py3-none-any.whl size=16207514 sha256=1c26dab7580be6a8c9f7aabdc380e8ad063630bc4083ff20769e580964b740b9
2025-09-22 00:15:22.232586 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-z4ox55k8/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2025-09-22 00:15:22.330767 | controller | Successfully built ansible
2025-09-22 00:15:22.473561 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible
2025-09-22 00:15:38.175690 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.2 ansible-2.9.27.post0 cffi-1.17.1 cryptography-46.0.1 jinja2-3.1.6 pycparser-2.23
2025-09-22 00:15:38.958601 | controller | ok: Runtime: 0:00:31.921109
2025-09-22 00:15:38.973177 | 
2025-09-22 00:15:38.973318 | TASK [Setup download-artifact-fork role]
2025-09-22 00:15:39.003670 | controller | ok
2025-09-22 00:15:39.035735 | 
2025-09-22 00:15:39.035849 | TASK [download-artifact-fork : Parse inventory response]
2025-09-22 00:15:39.088907 | controller | ok
2025-09-22 00:15:39.096905 | 
2025-09-22 00:15:39.096999 | TASK [download-artifact-fork : Fetch info from Zuul API]
2025-09-22 00:15:39.123780 | controller | skipping: Conditional result was False
2025-09-22 00:15:39.132320 | 
2025-09-22 00:15:39.132460 | TASK [download-artifact-fork : Parse build response]
2025-09-22 00:15:39.167539 | controller | skipping: Conditional result was False
2025-09-22 00:15:39.176449 | 
2025-09-22 00:15:39.176539 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2025-09-22 00:15:40.045341 | controller | changed
2025-09-22 00:15:40.052847 | 
2025-09-22 00:15:40.052938 | LOOP [download-artifact-fork : Download archive by type]
2025-09-22 00:15:40.080909 | controller | skipping: Conditional result was False
2025-09-22 00:15:40.081166 | 
2025-09-22 00:15:42.042980 | controller | changed: OK (20188 bytes)
2025-09-22 00:15:42.075415 | 
2025-09-22 00:15:42.075675 | TASK [deploy-artifacts : define __collections]
2025-09-22 00:15:42.117447 | controller | ok
2025-09-22 00:15:42.129443 | 
2025-09-22 00:15:42.129590 | LOOP [deploy-artifacts : Create list of collections]
2025-09-22 00:15:42.161895 | controller | skipping: Conditional result was False
2025-09-22 00:15:42.177491 | controller | ok:
2025-09-22 00:15:42.177668 | controller | {
2025-09-22 00:15:42.177753 | controller |   "branch": "main",
2025-09-22 00:15:42.177795 | controller |   "job": "build-ansible-collection",
2025-09-22 00:15:42.177832 | controller |   "metadata": {
2025-09-22 00:15:42.177867 | controller |     "type": "ansible_collection",
2025-09-22 00:15:42.177902 | controller |     "version": "5.0.0"
2025-09-22 00:15:42.177935 | controller |   },
2025-09-22 00:15:42.177969 | controller |   "name": "ansible.network",
2025-09-22 00:15:42.178003 | controller |   "project": "ansible-collections/ansible.network",
2025-09-22 00:15:42.178037 | controller |   "url": "https://965995be04fe6f9f4640-96b506c8e8f845708f44161accc71b77.ssl.cf1.rackcdn.com/ansible/c31091c6a9bb47a9a599ac51e1bd6e1d/artifacts/ansible-network-5.0.0.tar.gz"
2025-09-22 00:15:42.178085 | controller | }
2025-09-22 00:15:42.188416 | 
2025-09-22 00:15:42.188617 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2025-09-22 00:15:43.399273 | controller | Process install dependency map
2025-09-22 00:16:35.640560 | controller | |/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\Starting collection install process
2025-09-22 00:16:35.741603 | controller | |Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2025-09-22 00:16:35.741746 | controller | Installing 'ansible.netcommon:8.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2025-09-22 00:16:37.253600 | controller | /-\|/-\|/-\|/-\Installing 'ansible.utils:6.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2025-09-22 00:16:38.861718 | controller | |/-\|/-\|/-\|/-\Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2025-09-22 00:16:41.404144 | controller | |/-\|/-\|/-\|/-\|/-\|/-\|Installing 'cisco.ios:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2025-09-22 00:16:44.232649 | controller | /-\|/-\|/-\|/-\|/-\|/-\|/-\|Installing 'cisco.iosxr:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2025-09-22 00:16:46.761614 | controller | /-\|/-\|/-\|/-\|/-\|/-\|/Installing 'cisco.nxos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2025-09-22 00:16:50.487404 | controller | -\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2025-09-22 00:16:53.654770 | controller | ok: Runtime: 0:01:10.147462
2025-09-22 00:16:53.679380 | 
2025-09-22 00:16:53.679548 | TASK [podman may need iptables kernel modules]
2025-09-22 00:16:55.052284 | controller | ok: Runtime: 0:00:00.009758
2025-09-22 00:16:55.087204 | 
2025-09-22 00:16:55.087387 | PLAY RECAP
2025-09-22 00:16:55.087451 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2025-09-22 00:16:55.087480 | 
2025-09-22 00:16:55.223715 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-09-22 00:16:55.224875 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-09-22 00:16:55.902857 | 
2025-09-22 00:16:55.902991 | PLAY [controller]
2025-09-22 00:16:55.925612 | 
2025-09-22 00:16:55.925741 | TASK [Copy the galaxy.yml on the controller]
2025-09-22 00:16:59.836366 | controller | changed
2025-09-22 00:16:59.847403 | 
2025-09-22 00:16:59.847593 | TASK [Load information from galaxy.yml]
2025-09-22 00:16:59.894623 | controller | ok
2025-09-22 00:16:59.901980 | 
2025-09-22 00:16:59.902068 | LOOP [Enable FIPS mode]
2025-09-22 00:16:59.929101 | 
2025-09-22 00:16:59.929391 | LOOP [ansible-test : Include branch specific variables.]
2025-09-22 00:16:59.985072 | controller | ok: "/var/lib/zuul/builds/72d040de6c0d4d37b7ecbc94d9401fcb/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2025-09-22 00:16:59.985375 | 
2025-09-22 00:17:00.003517 | 
2025-09-22 00:17:00.003754 | TASK [ansible-test : Setup --skip-tags for test_options]
2025-09-22 00:17:00.060192 | controller | ok
2025-09-22 00:17:00.069342 | 
2025-09-22 00:17:00.069442 | TASK [ansible-test : Enable --inventory for network-integration]
2025-09-22 00:17:00.095313 | controller | skipping: Conditional result was False
2025-09-22 00:17:00.105338 | 
2025-09-22 00:17:00.105445 | TASK [ansible-test : Enable --retry-on-error]
2025-09-22 00:17:00.130754 | controller | skipping: Conditional result was False
2025-09-22 00:17:00.140363 | 
2025-09-22 00:17:00.140481 | TASK [ansible-test : Enable --continue-on-error]
2025-09-22 00:17:00.177283 | controller | ok
2025-09-22 00:17:00.185491 | 
2025-09-22 00:17:00.185709 | TASK [ansible-test : Enable --requirements]
2025-09-22 00:17:00.202344 | controller | skipping: Conditional result was False
2025-09-22 00:17:00.212117 | 
2025-09-22 00:17:00.212219 | TASK [ansible-test : Adjust options for unit tests]
2025-09-22 00:17:00.237515 | controller | skipping: Conditional result was False
2025-09-22 00:17:00.246274 | 
2025-09-22 00:17:00.246375 | TASK [ansible-test : Install requirements with unit tests]
2025-09-22 00:17:00.315399 | controller | ok
2025-09-22 00:17:00.322252 | 
2025-09-22 00:17:00.322342 | TASK [ansible-test : Set --skip-test]
2025-09-22 00:17:00.377386 | controller | skipping: Conditional result was False
2025-09-22 00:17:00.385005 | 
2025-09-22 00:17:00.385137 | TASK [ansible-test : Reduce verbosity]
2025-09-22 00:17:00.417156 | controller | ok
2025-09-22 00:17:00.423912 | 
2025-09-22 00:17:00.423996 | TASK [ansible-test : Setup --python option]
2025-09-22 00:17:00.448609 | controller | skipping: Conditional result was False
2025-09-22 00:17:00.456173 | 
2025-09-22 00:17:00.456314 | TASK [ansible-test : Setup --docker option]
2025-09-22 00:17:00.487539 | controller | ok
2025-09-22 00:17:00.495608 | 
2025-09-22 00:17:00.495722 | TASK [ansible-test : check if MANIFEST.json exists]
2025-09-22 00:17:01.783434 | controller | ok
2025-09-22 00:17:01.797841 | 
2025-09-22 00:17:01.798036 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2025-09-22 00:17:01.825898 | controller | skipping: Conditional result was False
2025-09-22 00:17:01.840921 | 
2025-09-22 00:17:01.841117 | TASK [ansible-test : Load information from MANIFEST.json]
2025-09-22 00:17:01.869263 | controller | skipping: Conditional result was False
2025-09-22 00:17:01.886149 | 
2025-09-22 00:17:01.886347 | TASK [ansible-test : Setup location of project for integration tests]
2025-09-22 00:17:01.913969 | controller | skipping: Conditional result was False
2025-09-22 00:17:01.931867 | 
2025-09-22 00:17:01.932077 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2025-09-22 00:17:03.283718 | controller | ok
2025-09-22 00:17:03.289845 | 
2025-09-22 00:17:03.289932 | TASK [ansible-test : Load information from galaxy.yml]
2025-09-22 00:17:03.320922 | controller | ok
2025-09-22 00:17:03.326945 | 
2025-09-22 00:17:03.327012 | TASK [ansible-test : Setup location of project for integration tests]
2025-09-22 00:17:03.357449 | controller | ok
2025-09-22 00:17:03.363114 | 
2025-09-22 00:17:03.363181 | TASK [ansible-test : Setup minimum test requirements]
2025-09-22 00:17:03.392797 | controller | ok
2025-09-22 00:17:03.398578 | 
2025-09-22 00:17:03.398649 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2025-09-22 00:17:04.309282 | controller | ok
2025-09-22 00:17:04.323103 | 
2025-09-22 00:17:04.323273 | TASK [ansible-test : Install python requirements]
2025-09-22 00:17:05.909114 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-09-22 00:17:05.928649 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2025-09-22 00:17:05.961823 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-09-22 00:17:05.970820 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2025-09-22 00:17:05.982603 | controller | Requirement already satisfied: pyyaml in ./venv/lib64/python3.8/site-packages (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1)) (6.0.2)
2025-09-22 00:17:05.989662 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2025-09-22 00:17:06.003834 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2025-09-22 00:17:06.074599 | controller | Installing collected packages: pathspec, yamllint
2025-09-22 00:17:06.181155 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2025-09-22 00:17:06.700244 | controller | ok: Runtime: 0:00:01.057166
2025-09-22 00:17:06.717635 | 
2025-09-22 00:17:06.717852 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2025-09-22 00:17:07.095828 | controller | skipping: Conditional result was False
2025-09-22 00:17:07.112156 | 
2025-09-22 00:17:07.113137 | TASK [ansible-test : Enable persistent connection logging]
2025-09-22 00:17:08.632993 | controller | changed: section and option added
2025-09-22 00:17:08.649409 | 
2025-09-22 00:17:08.649637 | TASK [ansible-test : Set persistent ssh_type value]
2025-09-22 00:17:09.757786 | controller | changed: option added
2025-09-22 00:17:09.772433 | 
2025-09-22 00:17:09.772596 | TASK [ansible-test : Set ansible log path]
2025-09-22 00:17:11.031231 | controller | changed: section and option added
2025-09-22 00:17:11.045284 | 
2025-09-22 00:17:11.045463 | TASK [ansible-test : Identify the changed targets]
2025-09-22 00:17:11.428282 | controller | skipping: Conditional result was False
2025-09-22 00:17:11.443545 | 
2025-09-22 00:17:11.443771 | TASK [ansible-test : set_fact]
2025-09-22 00:17:11.493156 | controller | skipping: Conditional result was False
2025-09-22 00:17:11.511549 | 
2025-09-22 00:17:11.511826 | TASK [ansible-test : Set the targets]
2025-09-22 00:17:11.561068 | controller | ok
2025-09-22 00:17:11.570252 | 
2025-09-22 00:17:11.570346 | TASK [ansible-test : set_fact]
2025-09-22 00:17:11.596942 | controller | skipping: Conditional result was False
2025-09-22 00:17:11.607274 | 
2025-09-22 00:17:11.607366 | TASK [ansible-test : List the targets]
2025-09-22 00:17:11.985338 | controller | skipping: Conditional result was False
2025-09-22 00:17:12.047152 | 
2025-09-22 00:17:12.047322 | TASK [ansible-test : set_fact]
2025-09-22 00:17:12.072956 | controller | skipping: Conditional result was False
2025-09-22 00:17:12.083776 | 
2025-09-22 00:17:12.083920 | TASK [ansible-test : set_fact]
2025-09-22 00:17:12.111137 | controller | skipping: Conditional result was False
2025-09-22 00:17:12.121403 | 
2025-09-22 00:17:12.121553 | LOOP [ansible-test : set_fact]
2025-09-22 00:17:12.205192 | 
2025-09-22 00:17:12.205527 | TASK [ansible-test : set_fact]
2025-09-22 00:17:12.233589 | controller | skipping: Conditional result was False
2025-09-22 00:17:12.261255 | 
2025-09-22 00:17:12.261466 | TASK [ansible-test : debug]
2025-09-22 00:17:12.288787 | controller | skipping: Conditional result was False
2025-09-22 00:17:12.304641 | 
2025-09-22 00:17:12.304791 | TASK [ansible-test : debug]
2025-09-22 00:17:12.359234 | About to run: ansible-test sanity --requirements --docker -v
2025-09-22 00:17:12.370830 | 
2025-09-22 00:17:12.370921 | TASK [ansible-test : Run the test suite]
2025-09-22 00:17:13.884999 | controller | Run command: docker images quay.io/ansible/default-test-container:1.10.1 --format '{{json .}}'
2025-09-22 00:17:13.959165 | controller | Run command: docker pull quay.io/ansible/default-test-container:1.10.1
2025-09-22 00:17:15.703918 | controller | 1.10.1: Pulling from ansible/default-test-container
2025-09-22 00:17:15.705891 | controller | [DEPRECATION NOTICE] Docker Image Format v1, and Docker Image manifest version 2, schema 1 support will be removed in an upcoming release. Suggest the author of quay.io/ansible/default-test-container:1.10.1 to upgrade the image to the OCI Format, or Docker Image manifest v2, schema 2. More information at https://docs.docker.com/go/deprecated-image-specs/
2025-09-22 00:17:15.705950 | controller | 5667fdb72017: Pulling fs layer
2025-09-22 00:17:15.705960 | controller | d83811f270d5: Pulling fs layer
2025-09-22 00:17:15.705967 | controller | ee671aafb583: Pulling fs layer
2025-09-22 00:17:15.705977 | controller | 7fc152dfb3a6: Pulling fs layer
2025-09-22 00:17:15.705986 | controller | 3fb967a6fd03: Pulling fs layer
2025-09-22 00:17:15.705993 | controller | b3e63023ca5f: Pulling fs layer
2025-09-22 00:17:15.705999 | controller | 653cb3e65dbf: Pulling fs layer
2025-09-22 00:17:15.706013 | controller | f4712b586375: Pulling fs layer
2025-09-22 00:17:16.701316 | controller | d696ed7812f8: Pulling fs layer
2025-09-22 00:17:16.701418 | controller | 29bf28764f14: Pulling fs layer
2025-09-22 00:17:16.701425 | controller | 47d7edb3c2b7: Pulling fs layer
2025-09-22 00:17:16.701433 | controller | 07550ad4f6e4: Pulling fs layer
2025-09-22 00:17:16.701440 | controller | 7e79526e64c5: Pulling fs layer
2025-09-22 00:17:16.701458 | controller | 4fffb32882b7: Pulling fs layer
2025-09-22 00:17:16.701465 | controller | fc7a38b06f4e: Pulling fs layer
2025-09-22 00:17:16.701471 | controller | 6d9d912e060e: Pulling fs layer
2025-09-22 00:17:16.701485 | controller | 5dd542dd28d9: Pulling fs layer
2025-09-22 00:17:16.701492 | controller | cef8beb99552: Pulling fs layer
2025-09-22 00:17:16.701499 | controller | bd5557e101ad: Pulling fs layer
2025-09-22 00:17:16.701505 | controller | e057c20fabdd: Pulling fs layer
2025-09-22 00:17:16.701513 | controller | d16059cdae2e: Pulling fs layer
2025-09-22 00:17:16.701519 | controller | 431e42d906a3: Pulling fs layer
2025-09-22 00:17:16.701525 | controller | 7a0fd3b1d7c3: Pulling fs layer
2025-09-22 00:17:16.701532 | controller | aacdb6db0c8c: Pulling fs layer
2025-09-22 00:17:16.701538 | controller | 2e7f04ae8c8b: Pulling fs layer
2025-09-22 00:17:16.701544 | controller | 4cd343027a44: Pulling fs layer
2025-09-22 00:17:16.701550 | controller | 0b4379883f90: Pulling fs layer
2025-09-22 00:17:16.701556 | controller | 3d9d6d9dfc91: Pulling fs layer
2025-09-22 00:17:16.701566 | controller | 57d6314bd293: Pulling fs layer
2025-09-22 00:17:16.701572 | controller | 774036afeb7f: Pulling fs layer
2025-09-22 00:17:16.701578 | controller | 54b10f0e1645: Pulling fs layer
2025-09-22 00:17:16.701584 | controller | 78cf439d9fdb: Pulling fs layer
2025-09-22 00:17:16.701591 | controller | 36e5e20969c7: Pulling fs layer
2025-09-22 00:17:16.701597 | controller | 4b19ac3f17a8: Pulling fs layer
2025-09-22 00:17:16.701603 | controller | 431e42d906a3: Waiting
2025-09-22 00:17:16.701609 | controller | 7a0fd3b1d7c3: Waiting
2025-09-22 00:17:16.701618 | controller | aacdb6db0c8c: Waiting
2025-09-22 00:17:16.701624 | controller | 2e7f04ae8c8b: Waiting
2025-09-22 00:17:16.701630 | controller | 4cd343027a44: Waiting
2025-09-22 00:17:16.701637 | controller | 0b4379883f90: Waiting
2025-09-22 00:17:16.701643 | controller | 3d9d6d9dfc91: Waiting
2025-09-22 00:17:16.701649 | controller | 57d6314bd293: Waiting
2025-09-22 00:17:16.701655 | controller | 7fc152dfb3a6: Waiting
2025-09-22 00:17:16.701661 | controller | 774036afeb7f: Waiting
2025-09-22 00:17:16.701667 | controller | 54b10f0e1645: Waiting
2025-09-22 00:17:16.701673 | controller | 78cf439d9fdb: Waiting
2025-09-22 00:17:16.701680 | controller | 36e5e20969c7: Waiting
2025-09-22 00:17:16.701686 | controller | 4b19ac3f17a8: Waiting
2025-09-22 00:17:16.701695 | controller | b3e63023ca5f: Waiting
2025-09-22 00:17:16.701701 | controller | 653cb3e65dbf: Waiting
2025-09-22 00:17:16.701707 | controller | f4712b586375: Waiting
2025-09-22 00:17:16.701714 | controller | d696ed7812f8: Waiting
2025-09-22 00:17:16.701720 | controller | 3fb967a6fd03: Waiting
2025-09-22 00:17:16.701726 | controller | 29bf28764f14: Waiting
2025-09-22 00:17:16.701732 | controller | 47d7edb3c2b7: Waiting
2025-09-22 00:17:16.701739 | controller | 4fffb32882b7: Waiting
2025-09-22 00:17:16.701745 | controller | fc7a38b06f4e: Waiting
2025-09-22 00:17:16.701751 | controller | 07550ad4f6e4: Waiting
2025-09-22 00:17:16.701757 | controller | 7e79526e64c5: Waiting
2025-09-22 00:17:16.701763 | controller | 6d9d912e060e: Waiting
2025-09-22 00:17:16.701771 | controller | 5dd542dd28d9: Waiting
2025-09-22 00:17:16.701991 | controller | cef8beb99552: Waiting
2025-09-22 00:17:16.701998 | controller | bd5557e101ad: Waiting
2025-09-22 00:17:16.702004 | controller | e057c20fabdd: Waiting
2025-09-22 00:17:16.702010 | controller | d16059cdae2e: Waiting
2025-09-22 00:17:16.702141 | controller | ee671aafb583: Verifying Checksum
2025-09-22 00:17:16.730489 | controller | ee671aafb583: Download complete
2025-09-22 00:17:16.730545 | controller | d83811f270d5: Verifying Checksum
2025-09-22 00:17:17.488664 | controller | d83811f270d5: Download complete
2025-09-22 00:17:17.489012 | controller | 7fc152dfb3a6: Verifying Checksum
2025-09-22 00:17:18.477133 | controller | 7fc152dfb3a6: Download complete
2025-09-22 00:17:18.478149 | controller | b3e63023ca5f: Verifying Checksum
2025-09-22 00:17:19.559550 | controller | b3e63023ca5f: Download complete
2025-09-22 00:17:19.560965 | controller | 653cb3e65dbf: Verifying Checksum
2025-09-22 00:17:19.561148 | controller | 653cb3e65dbf: Download complete
2025-09-22 00:17:26.731121 | controller | 3fb967a6fd03: Verifying Checksum
2025-09-22 00:17:27.564086 | controller | 3fb967a6fd03: Download complete
2025-09-22 00:17:27.564166 | controller | 5667fdb72017: Verifying Checksum
2025-09-22 00:17:27.921580 | controller | 5667fdb72017: Download complete
2025-09-22 00:17:27.921654 | controller | d696ed7812f8: Verifying Checksum
2025-09-22 00:17:28.588421 | controller | d696ed7812f8: Download complete
2025-09-22 00:17:28.588476 | controller | 29bf28764f14: Verifying Checksum
2025-09-22 00:17:29.588876 | controller | 29bf28764f14: Download complete
2025-09-22 00:17:29.588938 | controller | 47d7edb3c2b7: Verifying Checksum
2025-09-22 00:17:29.607075 | controller | 47d7edb3c2b7: Download complete
2025-09-22 00:17:29.607135 | controller | 07550ad4f6e4: Verifying Checksum
2025-09-22 00:17:30.549486 | controller | 07550ad4f6e4: Download complete
2025-09-22 00:17:30.549545 | controller | 7e79526e64c5: Verifying Checksum
2025-09-22 00:17:30.643656 | controller | 7e79526e64c5: Download complete
2025-09-22 00:17:30.643742 | controller | 4fffb32882b7: Verifying Checksum
2025-09-22 00:17:30.873782 | controller | 4fffb32882b7: Download complete
2025-09-22 00:17:30.873842 | controller | 5667fdb72017: Pull complete
2025-09-22 00:17:30.935867 | controller | d83811f270d5: Pull complete
2025-09-22 00:17:30.991006 | controller | ee671aafb583: Pull complete
2025-09-22 00:17:31.055380 | controller | 7fc152dfb3a6: Pull complete
2025-09-22 00:17:31.629556 | controller | 6d9d912e060e: Verifying Checksum
2025-09-22 00:17:32.220409 | controller | 6d9d912e060e: Download complete
2025-09-22 00:17:32.220540 | controller | 3fb967a6fd03: Pull complete
2025-09-22 00:17:32.280762 | controller | b3e63023ca5f: Pull complete
2025-09-22 00:17:32.336837 | controller | 653cb3e65dbf: Pull complete
2025-09-22 00:17:32.421472 | controller | fc7a38b06f4e: Verifying Checksum
2025-09-22 00:17:32.421521 | controller | fc7a38b06f4e: Download complete
2025-09-22 00:17:32.596497 | controller | 5dd542dd28d9: Verifying Checksum
2025-09-22 00:17:32.596800 | controller | 5dd542dd28d9: Download complete
2025-09-22 00:17:33.549672 | controller | bd5557e101ad: Verifying Checksum
2025-09-22 00:17:34.494555 | controller | bd5557e101ad: Download complete
2025-09-22 00:17:34.494621 | controller | e057c20fabdd: Verifying Checksum
2025-09-22 00:17:35.494609 | controller | e057c20fabdd: Download complete
2025-09-22 00:17:35.494698 | controller | d16059cdae2e: Verifying Checksum
2025-09-22 00:17:35.494736 | controller | d16059cdae2e: Download complete
2025-09-22 00:17:38.361441 | controller | 431e42d906a3: Verifying Checksum
2025-09-22 00:17:38.361592 | controller | 431e42d906a3: Download complete
2025-09-22 00:17:40.406114 | controller | 7a0fd3b1d7c3: Verifying Checksum
2025-09-22 00:17:40.406192 | controller | 7a0fd3b1d7c3: Download complete
2025-09-22 00:17:42.451614 | controller | aacdb6db0c8c: Verifying Checksum
2025-09-22 00:17:42.451739 | controller | aacdb6db0c8c: Download complete
2025-09-22 00:17:43.466655 | controller | 2e7f04ae8c8b: Verifying Checksum
2025-09-22 00:17:43.466821 | controller | 2e7f04ae8c8b: Download complete
2025-09-22 00:17:44.465088 | controller | 4cd343027a44: Verifying Checksum
2025-09-22 00:17:44.465224 | controller | 4cd343027a44: Download complete
2025-09-22 00:17:45.377544 | controller | 0b4379883f90: Verifying Checksum
2025-09-22 00:17:45.377752 | controller | 0b4379883f90: Download complete
2025-09-22 00:17:46.399383 | controller | 3d9d6d9dfc91: Verifying Checksum
2025-09-22 00:17:46.399480 | controller | 3d9d6d9dfc91: Download complete
2025-09-22 00:17:46.845708 | controller | f4712b586375: Verifying Checksum
2025-09-22 00:17:46.845892 | controller | f4712b586375: Download complete
2025-09-22 00:17:59.367762 | controller | 57d6314bd293: Verifying Checksum
2025-09-22 00:17:59.367897 | controller | 57d6314bd293: Download complete
2025-09-22 00:18:05.742843 | controller | 774036afeb7f: Verifying Checksum
2025-09-22 00:18:05.743000 | controller | 774036afeb7f: Download complete
2025-09-22 00:18:08.476106 | controller | 54b10f0e1645: Verifying Checksum
2025-09-22 00:18:08.476200 | controller | 54b10f0e1645: Download complete
2025-09-22 00:18:12.736632 | controller | cef8beb99552: Verifying Checksum
2025-09-22 00:18:12.736813 | controller | cef8beb99552: Download complete
2025-09-22 00:18:13.469530 | controller | f4712b586375: Pull complete
2025-09-22 00:18:13.496298 | controller | d696ed7812f8: Pull complete
2025-09-22 00:18:13.518594 | controller | 29bf28764f14: Pull complete
2025-09-22 00:18:13.570707 | controller | 47d7edb3c2b7: Pull complete
2025-09-22 00:18:13.604235 | controller | 07550ad4f6e4: Pull complete
2025-09-22 00:18:13.632055 | controller | 7e79526e64c5: Pull complete
2025-09-22 00:18:13.669871 | controller | 4fffb32882b7: Pull complete
2025-09-22 00:18:13.742864 | controller | fc7a38b06f4e: Pull complete
2025-09-22 00:18:13.780662 | controller | 6d9d912e060e: Pull complete
2025-09-22 00:18:13.809321 | controller | 5dd542dd28d9: Pull complete
2025-09-22 00:18:20.955435 | controller | 4b19ac3f17a8: Verifying Checksum
2025-09-22 00:18:20.955558 | controller | 4b19ac3f17a8: Download complete
2025-09-22 00:18:22.386832 | controller | 78cf439d9fdb: Verifying Checksum
2025-09-22 00:18:22.390324 | controller | 78cf439d9fdb: Download complete
2025-09-22 00:18:25.430315 | controller | 36e5e20969c7: Verifying Checksum
2025-09-22 00:18:25.433235 | controller | 36e5e20969c7: Download complete
2025-09-22 00:18:39.039964 | controller | cef8beb99552: Pull complete
2025-09-22 00:18:39.128927 | controller | bd5557e101ad: Pull complete
2025-09-22 00:18:39.205990 | controller | e057c20fabdd: Pull complete
2025-09-22 00:18:39.311603 | controller | d16059cdae2e: Pull complete
2025-09-22 00:18:41.581463 | controller | 431e42d906a3: Pull complete
2025-09-22 00:18:41.670655 | controller | 7a0fd3b1d7c3: Pull complete
2025-09-22 00:18:41.751725 | controller | aacdb6db0c8c: Pull complete
2025-09-22 00:18:41.801841 | controller | 2e7f04ae8c8b: Pull complete
2025-09-22 00:18:41.853530 | controller | 4cd343027a44: Pull complete
2025-09-22 00:18:41.917888 | controller | 0b4379883f90: Pull complete
2025-09-22 00:18:41.971986 | controller | 3d9d6d9dfc91: Pull complete
2025-09-22 00:18:47.834200 | controller | 57d6314bd293: Pull complete
2025-09-22 00:19:10.647406 | controller | 774036afeb7f: Pull complete
2025-09-22 00:19:37.783835 | controller | 54b10f0e1645: Pull complete
2025-09-22 00:20:06.428092 | controller | 78cf439d9fdb: Pull complete
2025-09-22 00:20:35.172125 | controller | 36e5e20969c7: Pull complete
2025-09-22 00:21:04.620985 | controller | 4b19ac3f17a8: Pull complete
2025-09-22 00:21:04.672482 | controller | Digest: sha256:954c4edffb0905df64e23c8611b1c4a62db9af13062f51cad5b658e727238d41
2025-09-22 00:21:04.680239 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:1.10.1
2025-09-22 00:21:04.694032 | controller | quay.io/ansible/default-test-container:1.10.1
2025-09-22 00:21:04.698432 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2025-09-22 00:21:04.728438 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2025-09-22 00:21:06.136887 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2025-09-22 00:21:06.137889 | controller | 04a5f4cda3ee: Pulling fs layer
2025-09-22 00:21:06.511582 | controller | ff496a88c8ed: Pulling fs layer
2025-09-22 00:21:06.511633 | controller | 0ce83f459fe7: Pulling fs layer
2025-09-22 00:21:06.511640 | controller | 2e5170e1f099: Pulling fs layer
2025-09-22 00:21:06.511646 | controller | 7641eb41b08c: Pulling fs layer
2025-09-22 00:21:06.511652 | controller | ad15fa9da398: Pulling fs layer
2025-09-22 00:21:06.511658 | controller | 087d91352424: Pulling fs layer
2025-09-22 00:21:06.511664 | controller | 8b92efd6a100: Pulling fs layer
2025-09-22 00:21:06.511670 | controller | 7641eb41b08c: Waiting
2025-09-22 00:21:06.511676 | controller | ad15fa9da398: Waiting
2025-09-22 00:21:06.511682 | controller | 087d91352424: Waiting
2025-09-22 00:21:06.511688 | controller | 8b92efd6a100: Waiting
2025-09-22 00:21:06.511694 | controller | 2e5170e1f099: Waiting
2025-09-22 00:21:06.511739 | controller | ff496a88c8ed: Verifying Checksum
2025-09-22 00:21:06.511776 | controller | ff496a88c8ed: Download complete
2025-09-22 00:21:06.623859 | controller | 0ce83f459fe7: Verifying Checksum
2025-09-22 00:21:06.623979 | controller | 0ce83f459fe7: Download complete
2025-09-22 00:21:06.784365 | controller | 04a5f4cda3ee: Verifying Checksum
2025-09-22 00:21:06.784456 | controller | 04a5f4cda3ee: Download complete
2025-09-22 00:21:07.012728 | controller | 7641eb41b08c: Verifying Checksum
2025-09-22 00:21:07.012932 | controller | 7641eb41b08c: Download complete
2025-09-22 00:21:07.159365 | controller | 2e5170e1f099: Verifying Checksum
2025-09-22 00:21:07.159466 | controller | 2e5170e1f099: Download complete
2025-09-22 00:21:07.170613 | controller | ad15fa9da398: Verifying Checksum
2025-09-22 00:21:07.170789 | controller | ad15fa9da398: Download complete
2025-09-22 00:21:07.552490 | controller | 087d91352424: Verifying Checksum
2025-09-22 00:21:07.552603 | controller | 087d91352424: Download complete
2025-09-22 00:21:07.564848 | controller | 8b92efd6a100: Verifying Checksum
2025-09-22 00:21:07.564985 | controller | 8b92efd6a100: Download complete
2025-09-22 00:21:09.164774 | controller | 04a5f4cda3ee: Pull complete
2025-09-22 00:21:09.198884 | controller | ff496a88c8ed: Pull complete
2025-09-22 00:21:09.237668 | controller | 0ce83f459fe7: Pull complete
2025-09-22 00:21:10.893992 | controller | 2e5170e1f099: Pull complete
2025-09-22 00:21:10.924401 | controller | 7641eb41b08c: Pull complete
2025-09-22 00:21:10.969024 | controller | ad15fa9da398: Pull complete
2025-09-22 00:21:13.607422 | controller | 087d91352424: Pull complete
2025-09-22 00:21:13.674065 | controller | 8b92efd6a100: Pull complete
2025-09-22 00:21:13.725843 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2025-09-22 00:21:13.740845 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2025-09-22 00:21:13.752900 | controller | quay.io/ansible/pypi-test-container:1.0.0
2025-09-22 00:21:13.756896 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2025-09-22 00:21:19.257332 | controller | Run command: docker inspect b0768ae1edeab667872b576f8cc65dc58c1d8b1ed4c29ccaa995a9efa4e52a4d
2025-09-22 00:21:19.557216 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2025-09-22 00:21:19.576513 | controller | Including collection: ansible.netcommon (288 files)
2025-09-22 00:21:19.576601 | controller | Including collection: ansible.network (23 files)
2025-09-22 00:21:19.593299 | controller | Including collection: ansible.utils (442 files)
2025-09-22 00:21:19.647805 | controller | Including collection: arista.eos (1022 files)
2025-09-22 00:21:19.714046 | controller | Including collection: cisco.ios (1167 files)
2025-09-22 00:21:19.759062 | controller | Including collection: cisco.iosxr (1037 files)
2025-09-22 00:21:19.836798 | controller | Including collection: cisco.nxos (1680 files)
2025-09-22 00:21:19.884748 | controller | Including collection: junipernetworks.junos (963 files)
2025-09-22 00:21:19.934172 | controller | Creating a payload archive containing 8359 files...
2025-09-22 00:21:22.454872 | controller | Created a 6852807 byte payload archive containing 8359 files in 2 seconds.
2025-09-22 00:21:22.462406 | controller | Assuming Docker is available on localhost.
2025-09-22 00:21:22.462759 | controller | Run command: docker run --detach --volume /sys/fs/cgroup:/sys/fs/cgroup:ro --privileged=false --security-opt seccomp=unconfined --volume /var/run/docker.sock:/var/run/docker.sock quay.io/ansible/default-test-container:1.10.1
2025-09-22 00:21:23.087650 | controller | Run command: docker exec -i daa899f93937edc246ac31c388191ff0d20425094c8a30d8b5cddcc091db6f30 dd of=/root/docker.sh bs=65536
2025-09-22 00:21:23.179842 | controller | Run command: docker exec daa899f93937edc246ac31c388191ff0d20425094c8a30d8b5cddcc091db6f30 /bin/bash /root/docker.sh
2025-09-22 00:21:23.306203 | controller | Run command: docker exec -i daa899f93937edc246ac31c388191ff0d20425094c8a30d8b5cddcc091db6f30 dd of=/root/ansible.tgz bs=65536
2025-09-22 00:21:23.421167 | controller | Run command: docker exec daa899f93937edc246ac31c388191ff0d20425094c8a30d8b5cddcc091db6f30 mkdir /root/ansible
2025-09-22 00:21:23.507407 | controller | Run command: docker exec daa899f93937edc246ac31c388191ff0d20425094c8a30d8b5cddcc091db6f30 tar oxzf /root/ansible.tgz -C /root/ansible
2025-09-22 00:21:24.807407 | controller | Run command: docker exec daa899f93937edc246ac31c388191ff0d20425094c8a30d8b5cddcc091db6f30 /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata metadata-dq0h4_f6.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2025-09-22 00:21:25.370402 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2025-09-22 00:21:25.370621 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2025-09-22 00:21:25.371057 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2025-09-22 00:21:25.372974 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-09-22 00:21:25.463277 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2025-09-22 00:21:25.468645 | controller | Read 0 sanity test ignore line(s) for Ansible 2.9 from: tests/sanity/ignore-2.9.txt
2025-09-22 00:21:25.476725 | controller | No tests applicable.
2025-09-22 00:21:25.476975 | controller | Running sanity test 'ansible-doc' with Python 3.6
2025-09-22 00:21:25.476991 | controller | No tests applicable.
2025-09-22 00:21:25.477289 | controller | Run command: /usr/bin/python2.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-09-22 00:21:25.557275 | controller | Running sanity test 'compile' with Python 2.6
2025-09-22 00:21:25.557649 | controller | No tests applicable.
2025-09-22 00:21:25.558013 | controller | Run command: /usr/bin/python2.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-09-22 00:21:25.762905 | controller | Running sanity test 'compile' with Python 2.7
2025-09-22 00:21:25.906212 | controller | No tests applicable.
2025-09-22 00:21:25.906268 | controller | Run command: /usr/bin/python3.5 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-09-22 00:21:25.906285 | controller | Running sanity test 'compile' with Python 3.5
2025-09-22 00:21:25.906798 | controller | No tests applicable.
2025-09-22 00:21:25.906842 | controller | Running sanity test 'compile' with Python 3.6
2025-09-22 00:21:25.907188 | controller | No tests applicable.
2025-09-22 00:21:25.907653 | controller | Run command: /usr/bin/python3.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-09-22 00:21:26.027689 | controller | Running sanity test 'compile' with Python 3.7
2025-09-22 00:21:26.028139 | controller | No tests applicable.
2025-09-22 00:21:26.030715 | controller | Run command: /usr/bin/python3.8 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-09-22 00:21:26.149127 | controller | Running sanity test 'compile' with Python 3.8
2025-09-22 00:21:26.149638 | controller | No tests applicable.
2025-09-22 00:21:26.149849 | controller | Running sanity test 'empty-init' with Python 3.6
2025-09-22 00:21:26.150133 | controller | No tests applicable.
2025-09-22 00:21:26.150317 | controller | Running sanity test 'future-import-boilerplate' with Python 3.6
2025-09-22 00:21:26.150608 | controller | No tests applicable.
2025-09-22 00:21:26.150636 | controller | Running sanity test 'ignores'
2025-09-22 00:21:26.151897 | controller | Run command: /usr/bin/python3.6 -m pip.__main__ install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt
2025-09-22 00:21:26.931892 | controller | Ignoring packaging: markers 'python_version < "3.6"' don't match your environment
2025-09-22 00:21:26.937770 | controller | Ignoring coverage: markers 'python_version > "3.7"' don't match your environment
2025-09-22 00:21:26.939472 | controller | Ignoring cryptography: markers 'python_version < "2.7"' don't match your environment
2025-09-22 00:21:26.942714 | controller | Ignoring deepdiff: markers 'python_version < "3"' don't match your environment
2025-09-22 00:21:26.945467 | controller | Ignoring jinja2: markers 'python_version < "2.7"' don't match your environment
2025-09-22 00:21:26.947097 | controller | Ignoring urllib3: markers 'python_version < "2.7"' don't match your environment
2025-09-22 00:21:26.950813 | controller | Ignoring sphinx: markers 'python_version < "2.7"' don't match your environment
2025-09-22 00:21:26.956900 | controller | Ignoring wheel: markers 'python_version < "2.7"' don't match your environment
2025-09-22 00:21:26.958467 | controller | Ignoring yamllint: markers 'python_version < "2.7"' don't match your environment
2025-09-22 00:21:26.965323 | controller | Ignoring paramiko: markers 'python_version < "2.7"' don't match your environment
2025-09-22 00:21:26.968248 | controller | Ignoring pytest: markers 'python_version < "2.7"' don't match your environment
2025-09-22 00:21:26.969914 | controller | Ignoring pytest: markers 'python_version == "2.7"' don't match your environment
2025-09-22 00:21:26.971411 | controller | Ignoring pytest-forked: markers 'python_version < "2.7"' don't match your environment
2025-09-22 00:21:26.975453 | controller | Ignoring requests: markers 'python_version < "2.7"' don't match your environment
2025-09-22 00:21:26.982285 | controller | Ignoring virtualenv: markers 'python_version < "2.7"' don't match your environment
2025-09-22 00:21:26.983987 | controller | Ignoring pathspec: markers 'python_version < "2.7"' don't match your environment
2025-09-22 00:21:26.985402 | controller | Ignoring pyopenssl: markers 'python_version < "2.7"' don't match your environment
2025-09-22 00:21:26.987922 | controller | Ignoring pyyaml: markers 'python_version < "2.7"' don't match your environment
2025-09-22 00:21:26.989361 | controller | Ignoring pycparser: markers 'python_version < "2.7"' don't match your environment
2025-09-22 00:21:26.992733 | controller | Ignoring xmltodict: markers 'python_version < "2.7"' don't match your environment
2025-09-22 00:21:26.994217 | controller | Ignoring lxml: markers 'python_version < "2.7"' don't match your environment
2025-09-22 00:21:26.996063 | controller | Ignoring pyvmomi: markers 'python_version < "2.7"' don't match your environment
2025-09-22 00:21:26.999262 | controller | Ignoring MarkupSafe: markers 'python_version < "3.6"' don't match your environment
2025-09-22 00:21:27.004559 | controller | Ignoring setuptools: markers 'python_version <= "2.7"' don't match your environment
2025-09-22 00:21:27.011266 | controller | Ignoring websocket-client: markers 'python_version < "3"' don't match your environment
2025-09-22 00:21:27.013245 | controller | Ignoring certifi: markers 'python_version < "3"' don't match your environment
2025-09-22 00:21:27.032313 | controller | Looking in indexes: http://172.17.0.2:3141/root/pypi/+simple/
2025-09-22 00:21:27.034719 | controller | Requirement already satisfied: cryptography<3.4 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 5)) (2.7)
2025-09-22 00:21:27.063714 | controller | Requirement already satisfied: rstcheck==3.3.1 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 12)) (3.3.1)
2025-09-22 00:21:27.067563 | controller | Requirement already satisfied: voluptuous>=0.11.0 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 30)) (0.11.7)
2025-09-22 00:21:27.071006 | controller | Requirement already satisfied: cffi!=1.14.4 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 49)) (1.13.0)
2025-09-22 00:21:27.073635 | controller | Requirement already satisfied: pylint==2.3.1 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 58)) (2.3.1)
2025-09-22 00:21:27.081693 | controller | Requirement already satisfied: jinja2 in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 2)) (2.10.3)
2025-09-22 00:21:27.087178 | controller | Requirement already satisfied: pycodestyle in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 3)) (2.5.0)
2025-09-22 00:21:27.091295 | controller | Requirement already satisfied: pyyaml in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 5)) (5.1.2)
2025-09-22 00:21:27.096278 | controller | Requirement already satisfied: virtualenv in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 7)) (16.7.5)
2025-09-22 00:21:27.114774 | controller | Requirement already satisfied: yamllint in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 9)) (1.18.0)
2025-09-22 00:21:27.118457 | controller | Requirement already satisfied: asn1crypto>=0.21.0 in /usr/local/lib/python3.6/dist-packages (from cryptography<3.4->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 5)) (1.1.0)
2025-09-22 00:21:27.124067 | controller | Requirement already satisfied: six>=1.4.1 in /usr/local/lib/python3.6/dist-packages (from cryptography<3.4->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 5)) (1.12.0)
2025-09-22 00:21:27.126161 | controller | Requirement already satisfied: docutils>=0.7 in /usr/local/lib/python3.6/dist-packages (from rstcheck==3.3.1->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 12)) (0.15.2)
2025-09-22 00:21:27.128433 | controller | Requirement already satisfied: pycparser in /usr/local/lib/python3.6/dist-packages (from cffi!=1.14.4->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 49)) (2.19)
2025-09-22 00:21:27.131020 | controller | Collecting astroid==2.3.3 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 54))
2025-09-22 00:21:42.157869 | controller |   WARNING: Retrying (Retry(total=4, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/astroid/
2025-09-22 00:21:57.676026 | controller |   WARNING: Retrying (Retry(total=3, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/astroid/
2025-09-22 00:22:13.694172 | controller |   WARNING: Retrying (Retry(total=2, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/astroid/
2025-09-22 00:22:30.711914 | controller |   WARNING: Retrying (Retry(total=1, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/astroid/
2025-09-22 00:22:49.730773 | controller |   WARNING: Retrying (Retry(total=0, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/astroid/
2025-09-22 00:23:04.751722 | controller |   ERROR: Could not find a version that satisfies the requirement astroid==2.3.3 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 54)) (from versions: none)
2025-09-22 00:23:04.872626 | controller | ERROR: No matching distribution found for astroid==2.3.3 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 54))
2025-09-22 00:23:04.872709 | controller | ERROR: Command "/usr/bin/python3.6 -m pip.__main__ install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt" returned exit status 1.
2025-09-22 00:23:04.872922 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2025-09-22 00:23:04.873174 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2025-09-22 00:23:04.873279 | controller | Removing custom PyPI hosts entries: /etc/hosts
2025-09-22 00:23:04.927813 | controller | Run command: docker exec daa899f93937edc246ac31c388191ff0d20425094c8a30d8b5cddcc091db6f30 tar czf /root/output.tgz --exclude .tmp -C /root/ansible/ansible_collections/ansible/network/tests output
2025-09-22 00:23:05.018590 | controller | Run command: docker exec -i daa899f93937edc246ac31c388191ff0d20425094c8a30d8b5cddcc091db6f30 dd if=/root/output.tgz bs=65536
2025-09-22 00:23:05.141071 | controller | Run command: tar oxzf /tmp/ansible-result-5vk2qjc3.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2025-09-22 00:23:05.156126 | controller | Run command: docker rm -f b0768ae1edeab667872b576f8cc65dc58c1d8b1ed4c29ccaa995a9efa4e52a4d
2025-09-22 00:23:05.458621 | controller | Run command: docker rm -f daa899f93937edc246ac31c388191ff0d20425094c8a30d8b5cddcc091db6f30
2025-09-22 00:23:06.240438 | controller | ERROR: Command "docker exec daa899f93937edc246ac31c388191ff0d20425094c8a30d8b5cddcc091db6f30 /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata metadata-dq0h4_f6.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2025-09-22 00:23:06.518145 | controller | ERROR
2025-09-22 00:23:06.518485 | controller | {
2025-09-22 00:23:06.518529 | controller |   "delta": "0:05:52.786555",
2025-09-22 00:23:06.518557 | controller |   "end": "2025-09-22 00:23:06.301616",
2025-09-22 00:23:06.518583 | controller |   "msg": "non-zero return code",
2025-09-22 00:23:06.518607 | controller |   "rc": 1,
2025-09-22 00:23:06.518631 | controller |   "start": "2025-09-22 00:17:13.515061"
2025-09-22 00:23:06.518656 | controller | }
failure
2025-09-22 00:23:06.527904 | 
2025-09-22 00:23:06.528055 | PLAY RECAP
2025-09-22 00:23:06.528178 | controller | ok: 20 changed: 5 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 0
2025-09-22 00:23:06.528229 | 
2025-09-22 00:23:06.666815 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-09-22 00:23:06.668916 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-09-22 00:23:07.303340 | 
2025-09-22 00:23:07.303499 | PLAY [all]
2025-09-22 00:23:07.326217 | 
2025-09-22 00:23:07.326368 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-09-22 00:23:10.360097 | controller | changed: non-zero return code
2025-09-22 00:23:10.373360 | 
2025-09-22 00:23:10.373633 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-09-22 00:23:10.403105 | controller | skipping: Conditional result was False
2025-09-22 00:23:10.416535 | 
2025-09-22 00:23:10.416797 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-09-22 00:23:10.469865 | 
2025-09-22 00:23:10.470186 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-09-22 00:23:10.512857 | 
2025-09-22 00:23:10.513155 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-09-22 00:23:10.530919 | controller | skipping: Conditional result was False
2025-09-22 00:23:10.544309 | 
2025-09-22 00:23:10.544483 | LOOP [fetch-subunit-output : Generate subunit file]
2025-09-22 00:23:10.585100 | 
2025-09-22 00:23:10.585399 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-09-22 00:23:10.612904 | controller | skipping: Conditional result was False
2025-09-22 00:23:10.628197 | 
2025-09-22 00:23:10.628371 | TASK [fetch-subunit-output : Remove the temporary file]
2025-09-22 00:23:10.656087 | controller | skipping: Conditional result was False
2025-09-22 00:23:10.670614 | 
2025-09-22 00:23:10.670900 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-09-22 00:23:10.689153 | controller | skipping: Conditional result was False
2025-09-22 00:23:10.738306 | 
2025-09-22 00:23:10.738482 | PLAY RECAP
2025-09-22 00:23:10.738545 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-09-22 00:23:10.738575 | 
2025-09-22 00:23:10.880565 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-09-22 00:23:10.881538 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-09-22 00:23:11.475223 | 
2025-09-22 00:23:11.475364 | PLAY [all:!appliance*]
2025-09-22 00:23:11.497586 | 
2025-09-22 00:23:11.497685 | TASK [unregister the node]
2025-09-22 00:23:11.853845 | controller | skipping: Conditional result was False
2025-09-22 00:23:11.866777 | 
2025-09-22 00:23:11.866940 | TASK [include_role : fetch-output]
2025-09-22 00:23:11.910880 | controller | ok
2025-09-22 00:23:11.960427 | 
2025-09-22 00:23:11.960578 | TASK [fetch-output : Set log path for multiple nodes]
2025-09-22 00:23:12.017075 | controller | skipping: Conditional result was False
2025-09-22 00:23:12.026506 | 
2025-09-22 00:23:12.026634 | TASK [fetch-output : Set log path for single node]
2025-09-22 00:23:12.069583 | controller | ok
2025-09-22 00:23:12.077764 | 
2025-09-22 00:23:12.077915 | LOOP [fetch-output : Ensure local output dirs]
2025-09-22 00:23:12.533377 | controller -> localhost | ok: "/var/lib/zuul/builds/72d040de6c0d4d37b7ecbc94d9401fcb/work/logs"
2025-09-22 00:23:12.840901 | controller -> localhost | changed: "/var/lib/zuul/builds/72d040de6c0d4d37b7ecbc94d9401fcb/work/artifacts"
2025-09-22 00:23:13.086277 | controller -> localhost | changed: "/var/lib/zuul/builds/72d040de6c0d4d37b7ecbc94d9401fcb/work/docs"
2025-09-22 00:23:13.105272 | 
2025-09-22 00:23:13.105557 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-09-22 00:23:15.448966 | controller | changed:
2025-09-22 00:23:15.449913 | controller | .d..t...... ./
2025-09-22 00:23:15.449998 | controller | cd+++++++++ controller/
2025-09-22 00:23:15.450074 | controller | changed: All items complete
2025-09-22 00:23:15.450120 | 
2025-09-22 00:23:17.508226 | controller | changed: .d..t...... ./
2025-09-22 00:23:19.607860 | controller | changed: .d..t...... ./
2025-09-22 00:23:19.633455 | 
2025-09-22 00:23:19.633610 | TASK [include_role : fetch-output-openshift]
2025-09-22 00:23:19.659326 | controller | skipping: Conditional result was False
2025-09-22 00:23:19.669540 | 
2025-09-22 00:23:19.669637 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-09-22 00:23:19.709934 | controller | skipping: Conditional result was False
2025-09-22 00:23:19.718024 | controller | skipping: Conditional result was False
2025-09-22 00:23:19.779045 | 
2025-09-22 00:23:19.779232 | PLAY [localhost]
2025-09-22 00:23:19.798979 | 
2025-09-22 00:23:19.799100 | TASK [Run Zuul manifest role]
2025-09-22 00:23:19.823064 | localhost | ok
2025-09-22 00:23:19.842439 | 
2025-09-22 00:23:19.842575 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-09-22 00:23:20.272861 | localhost | changed
2025-09-22 00:23:20.278382 | 
2025-09-22 00:23:20.278506 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-09-22 00:23:20.311561 | localhost | ok
2025-09-22 00:23:20.324509 | 
2025-09-22 00:23:20.324633 | TASK [Set zuul-log-path fact]
2025-09-22 00:23:20.387868 | localhost | ok
2025-09-22 00:23:20.402605 | 
2025-09-22 00:23:20.402716 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-09-22 00:23:20.434576 | localhost | ok
2025-09-22 00:23:20.446457 | 
2025-09-22 00:23:20.446570 | LOOP [Run upload-logs-swift role]
2025-09-22 00:23:20.484402 | localhost | Output suppressed because no_log was given
2025-09-22 00:23:20.512882 | 
2025-09-22 00:23:20.513036 | TASK [Set zuul-log-path fact]
2025-09-22 00:23:20.538736 | localhost | skipping: Conditional result was False
2025-09-22 00:23:20.545343 | 
2025-09-22 00:23:20.545457 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-09-22 00:23:20.970317 | localhost -> localhost | ok: Runtime: 0:00:00.008133
2025-09-22 00:23:20.978433 | 
2025-09-22 00:23:20.978538 | TASK [upload-logs-swift : Upload logs to swift]