2026-02-27 00:23:08.433248 | Job console starting...
2026-02-27 00:23:08.443057 | Updating repositories
2026-02-27 00:23:08.621096 | Preparing job workspace
2026-02-27 00:23:12.724334 | Running Ansible setup...
2026-02-27 00:23:20.640222 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-02-27 00:23:21.283187 | 
2026-02-27 00:23:21.283347 | PLAY [localhost]
2026-02-27 00:23:21.306165 | 
2026-02-27 00:23:21.306288 | TASK [Gathering Facts]
2026-02-27 00:23:22.340674 | localhost | ok
2026-02-27 00:23:22.381923 | 
2026-02-27 00:23:22.382171 | TASK [Setup log path fact]
2026-02-27 00:23:22.406975 | localhost | ok
2026-02-27 00:23:22.425780 | 
2026-02-27 00:23:22.425909 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-27 00:23:22.456875 | localhost | ok
2026-02-27 00:23:22.465615 | 
2026-02-27 00:23:22.465708 | TASK [emit-job-header : Print job information]
2026-02-27 00:23:22.506305 | # Job Information
2026-02-27 00:23:22.506486 | Ansible Version: 2.15.12
2026-02-27 00:23:22.506521 | Job: ansible-test-sanity-docker-stable-2.10
2026-02-27 00:23:22.506542 | Pipeline: periodic
2026-02-27 00:23:22.506560 | Executor: ze04.softwarefactory-project.io
2026-02-27 00:23:22.506578 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-02-27 00:23:22.506599 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/80a/ansible/80a0482d4d654ff4bf59fb36946b2e9d/
2026-02-27 00:23:22.506619 | Event ID: c66414a510e648d9aec75995eff050a1
2026-02-27 00:23:22.511327 | 
2026-02-27 00:23:22.511401 | LOOP [emit-job-header : Print node information]
2026-02-27 00:23:22.631472 | localhost | ok:
2026-02-27 00:23:22.631643 | localhost | # Node Information
2026-02-27 00:23:22.631671 | localhost | Inventory Hostname: controller
2026-02-27 00:23:22.631712 | localhost | Hostname: np0005632250
2026-02-27 00:23:22.631733 | localhost | Username: zuul
2026-02-27 00:23:22.631755 | localhost | Distro: Fedora 37
2026-02-27 00:23:22.631774 | localhost | Provider: ansible-vexxhost-ams1
2026-02-27 00:23:22.631792 | localhost | Region: ams1
2026-02-27 00:23:22.631809 | localhost | Label: ansible-fedora-37-1vcpu
2026-02-27 00:23:22.631826 | localhost | Product Name: OpenStack Nova
2026-02-27 00:23:22.631842 | localhost | Interface IP: 38.129.16.64
2026-02-27 00:23:22.650077 | 
2026-02-27 00:23:22.650221 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-27 00:23:23.052065 | localhost -> localhost | changed
2026-02-27 00:23:23.059273 | 
2026-02-27 00:23:23.059391 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-27 00:23:24.035492 | localhost -> localhost | changed
2026-02-27 00:23:24.060064 | 
2026-02-27 00:23:24.060219 | PLAY [all:!appliance*]
2026-02-27 00:23:24.078103 | 
2026-02-27 00:23:24.078232 | TASK [include_role : start-zuul-console]
2026-02-27 00:23:24.097447 | controller | ok
2026-02-27 00:23:24.111826 | 
2026-02-27 00:23:24.111931 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-02-27 00:23:25.110867 | controller | ok
2026-02-27 00:23:25.131151 | 
2026-02-27 00:23:25.131355 | TASK [use-our-mirror : Retrieve the IP address]
2026-02-27 00:23:27.229865 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-02-27 00:23:27.253067 | 
2026-02-27 00:23:27.253360 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-02-27 00:23:27.634808 | controller | skipping: Conditional result was False
2026-02-27 00:23:27.644859 | 
2026-02-27 00:23:27.644971 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-02-27 00:23:27.680929 | controller | skipping: Conditional result was False
2026-02-27 00:23:27.690564 | 
2026-02-27 00:23:27.690719 | TASK [use-our-mirror : Create the podman configuration directory]
2026-02-27 00:23:27.715104 | controller | skipping: Conditional result was False
2026-02-27 00:23:27.721674 | 
2026-02-27 00:23:27.721790 | TASK [use-our-mirror : Copy the podman configuration]
2026-02-27 00:23:27.745811 | controller | skipping: Conditional result was False
2026-02-27 00:23:27.753760 | 
2026-02-27 00:23:27.753889 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-02-27 00:23:27.777545 | controller | skipping: Conditional result was False
2026-02-27 00:23:27.785849 | 
2026-02-27 00:23:27.785986 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-02-27 00:23:27.810256 | controller | skipping: Conditional result was False
2026-02-27 00:23:27.821547 | 
2026-02-27 00:23:27.821639 | TASK [Disable Fedora Modular]
2026-02-27 00:23:28.954523 | controller | changed
2026-02-27 00:23:28.972170 | 
2026-02-27 00:23:28.972328 | TASK [Enable EPEL]
2026-02-27 00:23:28.990553 | controller | skipping: Conditional result was False
2026-02-27 00:23:29.005067 | 
2026-02-27 00:23:29.005168 | TASK [Register the RHEL node]
2026-02-27 00:23:29.446185 | 
2026-02-27 00:23:29.446331 | TASK [Show the subscription-manager status]
2026-02-27 00:23:29.809154 | controller | skipping: Conditional result was False
2026-02-27 00:23:29.820643 | 
2026-02-27 00:23:29.820826 | TASK [Enable EPEL on RHEL]
2026-02-27 00:23:30.211043 | controller | skipping: Conditional result was False
2026-02-27 00:23:30.229261 | 
2026-02-27 00:23:30.229463 | TASK [Install git and tox]
2026-02-27 00:25:41.883548 | controller | changed
2026-02-27 00:25:41.898473 | 
2026-02-27 00:25:41.898624 | TASK [include_role : prepare-workspace]
2026-02-27 00:25:41.936763 | controller | ok
2026-02-27 00:25:41.970222 | 
2026-02-27 00:25:41.970492 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-27 00:25:42.807720 | controller | ok
2026-02-27 00:25:42.815740 | 
2026-02-27 00:25:42.815849 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-27 00:25:59.125790 | controller | Output suppressed because no_log was given
2026-02-27 00:25:59.140946 | 
2026-02-27 00:25:59.141033 | TASK [include_role : prepare-workspace-openshift]
2026-02-27 00:25:59.165026 | controller | skipping: Conditional result was False
2026-02-27 00:25:59.187876 | 
2026-02-27 00:25:59.187958 | PLAY [all:!appliance]
2026-02-27 00:25:59.205331 | 
2026-02-27 00:25:59.205434 | TASK [Run add-build-sshkey role (RSA)]
2026-02-27 00:25:59.234928 | controller | ok
2026-02-27 00:25:59.249773 | 
2026-02-27 00:25:59.249853 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-27 00:25:59.520145 | controller -> localhost | ok
2026-02-27 00:25:59.535641 | 
2026-02-27 00:25:59.535829 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-27 00:25:59.566147 | controller | ok
2026-02-27 00:25:59.592049 | controller | included: /var/lib/zuul/builds/80a0482d4d654ff4bf59fb36946b2e9d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-27 00:25:59.600834 | 
2026-02-27 00:25:59.600919 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-27 00:26:00.106640 | controller -> localhost | Generating public/private rsa key pair.
2026-02-27 00:26:00.106903 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/80a0482d4d654ff4bf59fb36946b2e9d/work/80a0482d4d654ff4bf59fb36946b2e9d_id_rsa.
2026-02-27 00:26:00.106945 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/80a0482d4d654ff4bf59fb36946b2e9d/work/80a0482d4d654ff4bf59fb36946b2e9d_id_rsa.pub.
2026-02-27 00:26:00.106975 | controller -> localhost | The key fingerprint is:
2026-02-27 00:26:00.107002 | controller -> localhost | SHA256:LSWDoygYohApBH+1SoJLuE1dHKWARmDEtSmvulKih+0 zuul-build-sshkey
2026-02-27 00:26:00.107028 | controller -> localhost | The key's randomart image is:
2026-02-27 00:26:00.107053 | controller -> localhost | +---[RSA 2048]----+
2026-02-27 00:26:00.107078 | controller -> localhost | |OBoo..+o.        |
2026-02-27 00:26:00.107104 | controller -> localhost | |==o.o+.+         |
2026-02-27 00:26:00.107129 | controller -> localhost | |B++o+ = o .      |
2026-02-27 00:26:00.107154 | controller -> localhost | |**o= o . =       |
2026-02-27 00:26:00.107180 | controller -> localhost | |* o.o   S .      |
2026-02-27 00:26:00.107219 | controller -> localhost | |..o      .       |
2026-02-27 00:26:00.107252 | controller -> localhost | |.*               |
2026-02-27 00:26:00.107280 | controller -> localhost | |= o              |
2026-02-27 00:26:00.107307 | controller -> localhost | |++E              |
2026-02-27 00:26:00.107338 | controller -> localhost | +----[SHA256]-----+
2026-02-27 00:26:00.107408 | controller -> localhost | ok: Runtime: 0:00:00.056993
2026-02-27 00:26:00.118412 | 
2026-02-27 00:26:00.118505 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-27 00:26:00.156187 | controller | ok
2026-02-27 00:26:00.171807 | controller | included: /var/lib/zuul/builds/80a0482d4d654ff4bf59fb36946b2e9d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-27 00:26:00.184146 | 
2026-02-27 00:26:00.184257 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-27 00:26:00.209830 | controller | skipping: Conditional result was False
2026-02-27 00:26:00.218696 | 
2026-02-27 00:26:00.218793 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-27 00:26:01.295975 | controller | changed
2026-02-27 00:26:01.304296 | 
2026-02-27 00:26:01.304427 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-27 00:26:01.977284 | controller | ok
2026-02-27 00:26:01.992239 | 
2026-02-27 00:26:01.992387 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-27 00:26:05.035902 | controller | changed
2026-02-27 00:26:05.050949 | 
2026-02-27 00:26:05.051175 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-27 00:26:08.052466 | controller | changed
2026-02-27 00:26:08.067813 | 
2026-02-27 00:26:08.068006 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-27 00:26:08.084613 | controller | skipping: Conditional result was False
2026-02-27 00:26:08.095225 | 
2026-02-27 00:26:08.095363 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-27 00:26:08.548995 | controller -> localhost | changed
2026-02-27 00:26:08.566818 | 
2026-02-27 00:26:08.566946 | TASK [add-build-sshkey : Add back temp key]
2026-02-27 00:26:08.856424 | controller -> localhost | Identity added: /var/lib/zuul/builds/80a0482d4d654ff4bf59fb36946b2e9d/work/80a0482d4d654ff4bf59fb36946b2e9d_id_rsa (zuul-build-sshkey)
2026-02-27 00:26:08.856637 | controller -> localhost | ok: Runtime: 0:00:00.012906
2026-02-27 00:26:08.863498 | 
2026-02-27 00:26:08.863571 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-27 00:26:09.785861 | controller | ok
2026-02-27 00:26:09.794994 | 
2026-02-27 00:26:09.795115 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-27 00:26:09.820809 | controller | skipping: Conditional result was False
2026-02-27 00:26:09.837776 | 
2026-02-27 00:26:09.837874 | TASK [Run add-build-sshkey role (ECDSA)]
2026-02-27 00:26:09.871495 | controller | ok
2026-02-27 00:26:09.897185 | 
2026-02-27 00:26:09.897320 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-27 00:26:10.156427 | controller -> localhost | ok
2026-02-27 00:26:10.163185 | 
2026-02-27 00:26:10.163253 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-27 00:26:10.192444 | controller | ok
2026-02-27 00:26:10.203118 | controller | included: /var/lib/zuul/builds/80a0482d4d654ff4bf59fb36946b2e9d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-27 00:26:10.209336 | 
2026-02-27 00:26:10.209398 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-27 00:26:10.509418 | controller -> localhost | Generating public/private ecdsa key pair.
2026-02-27 00:26:10.509600 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/80a0482d4d654ff4bf59fb36946b2e9d/work/80a0482d4d654ff4bf59fb36946b2e9d_id_ecdsa.
2026-02-27 00:26:10.509630 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/80a0482d4d654ff4bf59fb36946b2e9d/work/80a0482d4d654ff4bf59fb36946b2e9d_id_ecdsa.pub.
2026-02-27 00:26:10.509658 | controller -> localhost | The key fingerprint is:
2026-02-27 00:26:10.509719 | controller -> localhost | SHA256:2vtdn7gkClWNCj0fPE0e0RvufwYNihhlRT9CSSy2dbU zuul-build-sshkey
2026-02-27 00:26:10.509751 | controller -> localhost | The key's randomart image is:
2026-02-27 00:26:10.509771 | controller -> localhost | +---[ECDSA 521]---+
2026-02-27 00:26:10.509789 | controller -> localhost | |           ===o..|
2026-02-27 00:26:10.509805 | controller -> localhost | |        . *.X.oo.|
2026-02-27 00:26:10.509823 | controller -> localhost | |       . * X.=+Eo|
2026-02-27 00:26:10.509841 | controller -> localhost | |        o * o..+ |
2026-02-27 00:26:10.509859 | controller -> localhost | |        S= o ..o |
2026-02-27 00:26:10.509875 | controller -> localhost | |       oo . . ...|
2026-02-27 00:26:10.509893 | controller -> localhost | |      ...  . ....|
2026-02-27 00:26:10.509910 | controller -> localhost | |        ....o...=|
2026-02-27 00:26:10.509928 | controller -> localhost | |        .o. .o.oo|
2026-02-27 00:26:10.509945 | controller -> localhost | +----[SHA256]-----+
2026-02-27 00:26:10.509995 | controller -> localhost | ok: Runtime: 0:00:00.014696
2026-02-27 00:26:10.516816 | 
2026-02-27 00:26:10.516882 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-27 00:26:10.546707 | controller | ok
2026-02-27 00:26:10.554011 | controller | included: /var/lib/zuul/builds/80a0482d4d654ff4bf59fb36946b2e9d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-27 00:26:10.563102 | 
2026-02-27 00:26:10.563165 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-27 00:26:10.587032 | controller | skipping: Conditional result was False
2026-02-27 00:26:10.593544 | 
2026-02-27 00:26:10.593620 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-27 00:26:11.503412 | controller | changed
2026-02-27 00:26:11.517998 | 
2026-02-27 00:26:11.518160 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-27 00:26:12.173759 | controller | ok
2026-02-27 00:26:12.179901 | 
2026-02-27 00:26:12.179971 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-27 00:26:15.214973 | controller | changed
2026-02-27 00:26:15.228166 | 
2026-02-27 00:26:15.228302 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-27 00:26:18.278778 | controller | changed
2026-02-27 00:26:18.293485 | 
2026-02-27 00:26:18.293631 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-27 00:26:18.321362 | controller | skipping: Conditional result was False
2026-02-27 00:26:18.337083 | 
2026-02-27 00:26:18.337251 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-27 00:26:18.625628 | controller -> localhost | changed
2026-02-27 00:26:18.638944 | 
2026-02-27 00:26:18.639028 | TASK [add-build-sshkey : Add back temp key]
2026-02-27 00:26:18.948656 | controller -> localhost | Identity added: /var/lib/zuul/builds/80a0482d4d654ff4bf59fb36946b2e9d/work/80a0482d4d654ff4bf59fb36946b2e9d_id_ecdsa (zuul-build-sshkey)
2026-02-27 00:26:18.948911 | controller -> localhost | ok: Runtime: 0:00:00.009808
2026-02-27 00:26:18.956761 | 
2026-02-27 00:26:18.956852 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-27 00:26:19.676914 | controller | ok
2026-02-27 00:26:19.689978 | 
2026-02-27 00:26:19.690144 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-27 00:26:19.729028 | controller | skipping: Conditional result was False
2026-02-27 00:26:19.755803 | 
2026-02-27 00:26:19.755949 | TASK [include_role : remove-zuul-sshkey]
2026-02-27 00:26:19.806011 | controller | skipping: Conditional result was False
2026-02-27 00:26:19.820430 | 
2026-02-27 00:26:19.820600 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-27 00:26:20.480898 | controller | ok: "logs"
2026-02-27 00:26:20.481277 | controller | ok: All items complete
2026-02-27 00:26:20.481320 | 
2026-02-27 00:26:21.106632 | controller | ok: "artifacts"
2026-02-27 00:26:21.734617 | controller | ok: "docs"
2026-02-27 00:26:21.750363 | 
2026-02-27 00:26:21.750561 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-27 00:26:22.432137 | controller | changed: "logs"
2026-02-27 00:26:23.072826 | controller | changed: "artifacts"
2026-02-27 00:26:23.707112 | controller | changed: "docs"
2026-02-27 00:26:23.742398 | 
2026-02-27 00:26:23.742503 | PLAY RECAP
2026-02-27 00:26:23.742546 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-02-27 00:26:23.742572 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-27 00:26:23.742589 | 
2026-02-27 00:26:23.867338 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-02-27 00:26:23.868255 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-02-27 00:26:24.515435 | 
2026-02-27 00:26:24.515568 | PLAY [all]
2026-02-27 00:26:24.538258 | 
2026-02-27 00:26:24.538404 | TASK [Install binary dependencies]
2026-02-27 00:26:24.605947 | controller | ok
2026-02-27 00:26:24.639156 | 
2026-02-27 00:26:24.639338 | TASK [bindep : Include find tasks]
2026-02-27 00:26:24.672340 | controller | ok
2026-02-27 00:26:24.684240 | controller | included: /var/lib/zuul/builds/80a0482d4d654ff4bf59fb36946b2e9d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-02-27 00:26:24.693596 | 
2026-02-27 00:26:24.693750 | TASK [bindep : Look for bindep.txt]
2026-02-27 00:26:25.620981 | controller | ok
2026-02-27 00:26:25.635452 | 
2026-02-27 00:26:25.635672 | TASK [bindep : Define bindep_file fact]
2026-02-27 00:26:25.663664 | controller | skipping: Conditional result was False
2026-02-27 00:26:25.677205 | 
2026-02-27 00:26:25.677359 | TASK [bindep : Look for other-requirements.txt]
2026-02-27 00:26:26.321355 | controller | ok
2026-02-27 00:26:26.337057 | 
2026-02-27 00:26:26.337221 | TASK [bindep : Define bindep_file fact]
2026-02-27 00:26:26.374228 | controller | skipping: Conditional result was False
2026-02-27 00:26:26.388771 | 
2026-02-27 00:26:26.388937 | TASK [bindep : Look for bindep fallback file]
2026-02-27 00:26:26.426447 | controller | skipping: Conditional result was False
2026-02-27 00:26:26.440531 | 
2026-02-27 00:26:26.440726 | TASK [bindep : Define bindep_file fact]
2026-02-27 00:26:26.478074 | controller | skipping: Conditional result was False
2026-02-27 00:26:26.499494 | 
2026-02-27 00:26:26.499750 | TASK [bindep : Include bindep tasks]
2026-02-27 00:26:26.538035 | controller | skipping: Conditional result was False
2026-02-27 00:26:26.554406 | 
2026-02-27 00:26:26.554624 | TASK [bindep : Include install tasks]
2026-02-27 00:26:26.592634 | controller | skipping: Conditional result was False
2026-02-27 00:26:26.603194 | 
2026-02-27 00:26:26.603367 | LOOP [bindep : Include package tasks]
2026-02-27 00:26:26.682345 | 
2026-02-27 00:26:26.682537 | TASK [Run test-setup role]
2026-02-27 00:26:26.701382 | controller | ok
2026-02-27 00:26:26.718595 | 
2026-02-27 00:26:26.718721 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-02-27 00:26:27.370265 | controller | ok
2026-02-27 00:26:27.384803 | 
2026-02-27 00:26:27.384952 | TASK [test-setup : Run tools/test-setup.sh]
2026-02-27 00:26:27.751621 | controller | skipping: Conditional result was False
2026-02-27 00:26:27.812358 | 
2026-02-27 00:26:27.812521 | PLAY RECAP
2026-02-27 00:26:27.812589 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-02-27 00:26:27.812619 | 
2026-02-27 00:26:27.970853 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-02-27 00:26:27.971726 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-02-27 00:26:28.709544 | 
2026-02-27 00:26:28.709693 | PLAY [controller]
2026-02-27 00:26:28.729969 | 
2026-02-27 00:26:28.730045 | TASK [Create the /root directory]
2026-02-27 00:26:29.850803 | controller | ok
2026-02-27 00:26:29.865530 | 
2026-02-27 00:26:29.865760 | TASK [Install glibc-langpack-en]
2026-02-27 00:26:38.569188 | controller | ok: Nothing to do
2026-02-27 00:26:38.582054 | 
2026-02-27 00:26:38.582211 | TASK [Ensure controller directory exists]
2026-02-27 00:26:39.451128 | controller | changed
2026-02-27 00:26:39.464674 | 
2026-02-27 00:26:39.464899 | TASK [Install container runtime]
2026-02-27 00:26:39.547383 | controller | ok
2026-02-27 00:26:39.615213 | 
2026-02-27 00:26:39.615341 | LOOP [ensure-docker : Gather variables for each operating system]
2026-02-27 00:26:39.662362 | controller | ok: "/var/lib/zuul/builds/80a0482d4d654ff4bf59fb36946b2e9d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2026-02-27 00:26:39.690956 | 
2026-02-27 00:26:39.691112 | TASK [ensure-docker : Sanity check for distro selection]
2026-02-27 00:26:39.716718 | controller | skipping: Conditional result was False
2026-02-27 00:26:39.726613 | 
2026-02-27 00:26:39.726750 | TASK [ensure-docker : Sanity check for distro install]
2026-02-27 00:26:39.751699 | controller | skipping: Conditional result was False
2026-02-27 00:26:39.761716 | 
2026-02-27 00:26:39.761869 | TASK [ensure-docker : Sanity check for upstream install]
2026-02-27 00:26:39.786936 | controller | skipping: Conditional result was False
2026-02-27 00:26:39.796173 | 
2026-02-27 00:26:39.796285 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2026-02-27 00:26:39.822031 | controller | skipping: Conditional result was False
2026-02-27 00:26:39.830442 | 
2026-02-27 00:26:39.830536 | TASK [ensure-docker : Include tasks for docker install]
2026-02-27 00:26:39.853673 | controller | ok
2026-02-27 00:26:39.868108 | controller | included: /var/lib/zuul/builds/80a0482d4d654ff4bf59fb36946b2e9d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2026-02-27 00:26:39.880279 | 
2026-02-27 00:26:39.880409 | TASK [ensure-docker : Not Implemented]
2026-02-27 00:26:39.905876 | controller | skipping: Conditional result was False
2026-02-27 00:26:39.912346 | 
2026-02-27 00:26:39.912419 | TASK [Set up docker mirrors]
2026-02-27 00:26:39.931877 | controller | ok
2026-02-27 00:26:39.952427 | 
2026-02-27 00:26:39.952548 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2026-02-27 00:26:39.997371 | controller | skipping: Conditional result was False
2026-02-27 00:26:40.031062 | 
2026-02-27 00:26:40.031230 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2026-02-27 00:26:40.075832 | controller | skipping: Conditional result was False
2026-02-27 00:26:40.084609 | 
2026-02-27 00:26:40.084731 | TASK [use-docker-mirror : Set docker_mirror fact]
2026-02-27 00:26:40.129663 | controller | skipping: Conditional result was False
2026-02-27 00:26:40.139337 | 
2026-02-27 00:26:40.139444 | TASK [use-docker-mirror : Set up docker mirrors]
2026-02-27 00:26:40.176067 | controller | skipping: Conditional result was False
2026-02-27 00:26:40.193904 | 
2026-02-27 00:26:40.194052 | TASK [Add all repositories]
2026-02-27 00:26:40.228803 | controller | ok
2026-02-27 00:26:40.267429 | 
2026-02-27 00:26:40.267555 | LOOP [ensure-package-repositories : Install repositories]
2026-02-27 00:26:40.296560 | controller | ok: "/var/lib/zuul/builds/80a0482d4d654ff4bf59fb36946b2e9d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2026-02-27 00:26:40.296907 | controller | ok: All items complete
2026-02-27 00:26:40.296971 | 
2026-02-27 00:26:40.311946 | controller | included: /var/lib/zuul/builds/80a0482d4d654ff4bf59fb36946b2e9d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2026-02-27 00:26:40.318559 | 
2026-02-27 00:26:40.318630 | TASK [ensure-package-repositories : Create GPG key temp directory]
2026-02-27 00:26:41.235008 | controller | changed
2026-02-27 00:26:41.249861 | 
2026-02-27 00:26:41.250015 | LOOP [ensure-package-repositories : Upload GPG keys]
2026-02-27 00:26:44.474971 | controller | changed:
2026-02-27 00:26:44.475155 | controller | {
2026-02-27 00:26:44.475187 | 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"
2026-02-27 00:26:44.475213 | controller | }
2026-02-27 00:26:44.498481 | 
2026-02-27 00:26:44.498638 | LOOP [ensure-package-repositories : Import GPG keys]
2026-02-27 00:26:45.714571 | controller | changed:
2026-02-27 00:26:45.714932 | controller | {
2026-02-27 00:26:45.714996 | 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"
2026-02-27 00:26:45.715049 | controller | }
2026-02-27 00:26:45.715115 | controller | changed: All items complete
2026-02-27 00:26:45.715159 | 
2026-02-27 00:26:45.730927 | 
2026-02-27 00:26:45.731145 | TASK [ensure-package-repositories : Remove GPG temp directory]
2026-02-27 00:26:46.461367 | controller | changed
2026-02-27 00:26:46.473366 | 
2026-02-27 00:26:46.473504 | LOOP [ensure-package-repositories : Add repositories]
2026-02-27 00:26:47.346549 | controller | changed:
2026-02-27 00:26:47.346780 | controller | {
2026-02-27 00:26:47.346819 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2026-02-27 00:26:47.346848 | controller |   "description": "Docker CE Stable - $basearch",
2026-02-27 00:26:47.346873 | controller |   "gpgcheck": true,
2026-02-27 00:26:47.346896 | controller |   "name": "docker-ce-stable"
2026-02-27 00:26:47.346926 | controller | }
2026-02-27 00:26:47.372599 | 
2026-02-27 00:26:47.372737 | TASK [ensure-docker : Install pre-reqs]
2026-02-27 00:26:52.341606 | controller | ok: Nothing to do
2026-02-27 00:26:52.355448 | 
2026-02-27 00:26:52.355591 | TASK [ensure-docker : Remove packages]
2026-02-27 00:26:55.520610 | controller | ok: Nothing to do
2026-02-27 00:26:55.529205 | 
2026-02-27 00:26:55.529312 | TASK [ensure-docker : Install upstream docker using package]
2026-02-27 00:28:22.892139 | controller | changed
2026-02-27 00:28:22.898149 | 
2026-02-27 00:28:22.898215 | TASK [ensure-docker : Setup docker]
2026-02-27 00:28:22.920551 | controller | ok
2026-02-27 00:28:22.932409 | controller | included: /var/lib/zuul/builds/80a0482d4d654ff4bf59fb36946b2e9d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2026-02-27 00:28:22.945094 | 
2026-02-27 00:28:22.945177 | TASK [ensure-docker : Ensure "docker" group exists]
2026-02-27 00:28:23.947080 | controller | ok
2026-02-27 00:28:23.962469 | 
2026-02-27 00:28:23.962729 | TASK [ensure-docker : Add user to docker group]
2026-02-27 00:28:25.011247 | controller | changed
2026-02-27 00:28:25.028150 | 
2026-02-27 00:28:25.028337 | TASK [Update docker daemon proxy configuration]
2026-02-27 00:28:25.065610 | controller | skipping: Conditional result was False
2026-02-27 00:28:25.077001 | 
2026-02-27 00:28:25.077158 | TASK [Lower default MTU]
2026-02-27 00:28:25.104282 | controller | skipping: Conditional result was False
2026-02-27 00:28:25.116639 | 
2026-02-27 00:28:25.116832 | TASK [ensure-docker : Get the status of the docket.socket unit]
2026-02-27 00:28:25.931853 | controller | inactive
2026-02-27 00:28:26.488340 | controller | ok: Runtime: 0:00:00.014917
2026-02-27 00:28:26.501817 | 
2026-02-27 00:28:26.502022 | TASK [ensure-docker : Get the status of the docket unit]
2026-02-27 00:28:27.292415 | controller | inactive
2026-02-27 00:28:27.890765 | controller | ok: Runtime: 0:00:00.021182
2026-02-27 00:28:27.900456 | 
2026-02-27 00:28:27.900558 | TASK [ensure-docker : Restart docker]
2026-02-27 00:28:27.955143 | Trigger a Docker restart
2026-02-27 00:28:27.962805 | 
2026-02-27 00:28:27.962885 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2026-02-27 00:28:28.001777 | 
2026-02-27 00:28:28.001889 | TASK [ensure-docker : Install docker-compose]
2026-02-27 00:28:28.028463 | controller | skipping: Conditional result was False
2026-02-27 00:28:28.037658 | 
2026-02-27 00:28:28.037786 | TASK [ensure-docker : Flush handlers before role exit]
2026-02-27 00:28:28.053468 | 
2026-02-27 00:28:28.053564 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2026-02-27 00:28:31.434641 | controller | changed
2026-02-27 00:28:31.453921 | 
2026-02-27 00:28:31.454112 | TASK [ensure-docker : Assure docker service is running]
2026-02-27 00:28:35.489869 | controller | changed
2026-02-27 00:28:35.524081 | 
2026-02-27 00:28:35.524304 | TASK [ensure-docker : Assure docker.socket service is running]
2026-02-27 00:28:37.082945 | controller | ok
2026-02-27 00:28:37.103404 | 
2026-02-27 00:28:37.103570 | TASK [ensure-docker : Correct group ownership on docker sock]
2026-02-27 00:28:37.860625 | controller | ok
2026-02-27 00:28:37.869652 | 
2026-02-27 00:28:37.869778 | TASK [ensure-docker : Validate ability to talk with docker]
2026-02-27 00:28:38.476846 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2026-02-27 00:28:38.742509 | controller | ok: Runtime: 0:00:00.035210
2026-02-27 00:28:38.752231 | 
2026-02-27 00:28:38.752355 | TASK [ensure-docker : Get version details]
2026-02-27 00:28:39.449680 | controller | Client: Docker Engine - Community
2026-02-27 00:28:39.451459 | controller |  Version:           24.0.7
2026-02-27 00:28:39.451526 | controller |  API version:       1.43
2026-02-27 00:28:39.451535 | controller |  Go version:        go1.20.10
2026-02-27 00:28:39.451555 | controller |  Git commit:        afdd53b
2026-02-27 00:28:39.451563 | controller |  Built:             Thu Oct 26 09:09:39 2023
2026-02-27 00:28:39.451570 | controller |  OS/Arch:           linux/amd64
2026-02-27 00:28:39.451579 | controller |  Context:           default
2026-02-27 00:28:39.451588 | controller |
2026-02-27 00:28:39.451595 | controller | Server: Docker Engine - Community
2026-02-27 00:28:39.451601 | controller |  Engine:
2026-02-27 00:28:39.451608 | controller |   Version:          24.0.7
2026-02-27 00:28:39.451614 | controller |   API version:      1.43 (minimum version 1.12)
2026-02-27 00:28:39.451620 | controller |   Go version:       go1.20.10
2026-02-27 00:28:39.451626 | controller |   Git commit:       311b9ff
2026-02-27 00:28:39.451633 | controller |   Built:            Thu Oct 26 09:08:11 2023
2026-02-27 00:28:39.451639 | controller |   OS/Arch:          linux/amd64
2026-02-27 00:28:39.451645 | controller |   Experimental:     false
2026-02-27 00:28:39.451652 | controller |  containerd:
2026-02-27 00:28:39.451658 | controller |   Version:          1.6.25
2026-02-27 00:28:39.451664 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2026-02-27 00:28:39.451670 | controller |  runc:
2026-02-27 00:28:39.451676 | controller |   Version:          1.1.10
2026-02-27 00:28:39.451682 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2026-02-27 00:28:39.451691 | controller |  docker-init:
2026-02-27 00:28:39.451697 | controller |   Version:          0.19.0
2026-02-27 00:28:39.451703 | controller |   GitCommit:        de40ad0
2026-02-27 00:28:39.642453 | controller | ok: Runtime: 0:00:00.049359
2026-02-27 00:28:39.657507 | 
2026-02-27 00:28:39.657660 | TASK [ensure-docker : Dump installed docker details]
2026-02-27 00:28:39.712957 | {'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': '2026-02-27 00:28:39.402793', 'end': '2026-02-27 00:28:39.452152', 'delta': '0:00:00.049359', 'msg': '', 'zuul_log_id': 'fa163e3b-3c83-c71b-0d2a-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}
2026-02-27 00:28:39.727603 | 
2026-02-27 00:28:39.727775 | TASK [Use buildset registry]
2026-02-27 00:28:39.755762 | controller | skipping: Conditional result was False
2026-02-27 00:28:39.774004 | 
2026-02-27 00:28:39.774104 | TASK [Ensure python3.8 is present]
2026-02-27 00:28:39.799399 | controller | skipping: Conditional result was False
2026-02-27 00:28:39.810631 | 
2026-02-27 00:28:39.810759 | TASK [Run our-ensure-python role (Fedora only for now)]
2026-02-27 00:28:39.836069 | controller | ok
2026-02-27 00:28:39.864510 | 
2026-02-27 00:28:39.864626 | TASK [our-ensure-python : Install the right Python version (rpm)]
2026-02-27 00:28:45.806982 | controller | ok: Nothing to do
2026-02-27 00:28:45.820132 | 
2026-02-27 00:28:45.820277 | TASK [our-ensure-python : Also install python3-devel]
2026-02-27 00:29:02.500844 | controller | changed
2026-02-27 00:29:02.520028 | 
2026-02-27 00:29:02.520183 | TASK [Run ensure-virtualenv role]
2026-02-27 00:29:02.546249 | controller | ok
2026-02-27 00:29:02.576725 | 
2026-02-27 00:29:02.576872 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2026-02-27 00:29:03.336133 | controller | /usr/bin/virtualenv
2026-02-27 00:29:03.938455 | controller | ok: Runtime: 0:00:00.004413
2026-02-27 00:29:03.953216 | 
2026-02-27 00:29:03.953378 | LOOP [ensure-virtualenv : Install virtualenv package]
2026-02-27 00:29:03.993142 | controller | skipping: Conditional result was False
2026-02-27 00:29:03.994034 | controller | changed: All items complete
2026-02-27 00:29:03.994148 | 
2026-02-27 00:29:04.025207 | 
2026-02-27 00:29:04.025454 | TASK [Find the full path of the Python interpreter]
2026-02-27 00:29:04.836259 | controller | /usr/bin/python3.8
2026-02-27 00:29:05.392565 | controller | ok
2026-02-27 00:29:05.406428 | 
2026-02-27 00:29:05.406595 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2026-02-27 00:29:07.914820 | controller | created virtual environment CPython3.8.18.final.0-64 in 1345ms
2026-02-27 00:29:08.002694 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2026-02-27 00:29:08.002753 | 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)
2026-02-27 00:29:08.002767 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2026-02-27 00:29:08.002790 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2026-02-27 00:29:08.297035 | controller | changed
2026-02-27 00:29:08.311049 | 
2026-02-27 00:29:08.311223 | TASK [Set selinux package]
2026-02-27 00:29:08.335777 | controller | ok
2026-02-27 00:29:08.349756 | 
2026-02-27 00:29:08.349874 | TASK [Set selinux package (Fedora)]
2026-02-27 00:29:08.380096 | controller | ok
2026-02-27 00:29:08.385390 | 
2026-02-27 00:29:08.385454 | TASK [Install selinux into virtualenv]
2026-02-27 00:29:14.264897 | controller | Collecting selinux-please-lie-to-me
2026-02-27 00:29:14.609403 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2026-02-27 00:29:15.760249 | controller | Collecting setuptools<50.0.0
2026-02-27 00:29:15.845642 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2026-02-27 00:29:16.209760 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 2.3 MB/s eta 0:00:00
2026-02-27 00:29:16.347288 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2026-02-27 00:29:16.347638 | controller |   Attempting uninstall: setuptools
2026-02-27 00:29:16.348727 | controller |     Found existing installation: setuptools 62.6.0
2026-02-27 00:29:16.476576 | controller |     Uninstalling setuptools-62.6.0:
2026-02-27 00:29:16.493696 | controller |       Successfully uninstalled setuptools-62.6.0
2026-02-27 00:29:17.250171 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2026-02-27 00:29:17.759210 | controller |
2026-02-27 00:29:18.063193 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-02-27 00:29:18.063246 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-02-27 00:29:18.257082 | controller | ok: Runtime: 0:00:08.533573
2026-02-27 00:29:18.265779 | 
2026-02-27 00:29:18.265863 | TASK [Install pytest-forked into virtualenv]
2026-02-27 00:29:20.167397 | controller | Collecting pytest-forked
2026-02-27 00:29:20.487191 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2026-02-27 00:29:20.658457 | controller | Collecting py
2026-02-27 00:29:20.747250 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2026-02-27 00:29:20.888116 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 741.3 kB/s eta 0:00:00
2026-02-27 00:29:21.271710 | controller | Collecting pytest>=3.10
2026-02-27 00:29:21.363056 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2026-02-27 00:29:21.496682 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 2.6 MB/s eta 0:00:00
2026-02-27 00:29:21.665920 | controller | Collecting pluggy<2,>=1.5
2026-02-27 00:29:21.755460 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2026-02-27 00:29:21.899128 | controller | Collecting iniconfig
2026-02-27 00:29:21.989250 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2026-02-27 00:29:22.155191 | controller | Collecting packaging
2026-02-27 00:29:22.245745 | controller |   Downloading packaging-26.0-py3-none-any.whl (74 kB)
2026-02-27 00:29:22.260571 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 5.7 MB/s eta 0:00:00
2026-02-27 00:29:22.443423 | controller | Collecting tomli>=1
2026-02-27 00:29:22.532291 | controller |   Downloading tomli-2.4.0-py3-none-any.whl (14 kB)
2026-02-27 00:29:22.670764 | controller | Collecting exceptiongroup>=1.0.0rc8
2026-02-27 00:29:22.760756 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2026-02-27 00:29:22.944096 | controller | Collecting typing-extensions>=4.6.0
2026-02-27 00:29:23.034782 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2026-02-27 00:29:23.047221 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 4.3 MB/s eta 0:00:00
2026-02-27 00:29:23.151249 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2026-02-27 00:29:24.093810 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.1.0 packaging-26.0 pluggy-1.5.0 py-1.11.0 pytest-8.3.5 pytest-forked-1.6.0 tomli-2.4.0 typing-extensions-4.13.2
2026-02-27 00:29:24.102576 | controller |
2026-02-27 00:29:24.340670 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-02-27 00:29:24.340718 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-02-27 00:29:24.631078 | controller | ok: Runtime: 0:00:05.266106
2026-02-27 00:29:24.644820 | 
2026-02-27 00:29:24.644985 | TASK [Update pip]
2026-02-27 00:29:27.258279 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2026-02-27 00:29:27.989959 | controller | Collecting pip
2026-02-27 00:29:28.345690 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2026-02-27 00:29:28.804687 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 4.1 MB/s eta 0:00:00
2026-02-27 00:29:28.909722 | controller | Installing collected packages: pip
2026-02-27 00:29:28.910249 | controller |   Attempting uninstall: pip
2026-02-27 00:29:28.911344 | controller |     Found existing installation: pip 22.2.2
2026-02-27 00:29:29.238802 | controller |     Uninstalling pip-22.2.2:
2026-02-27 00:29:29.267126 | controller |       Successfully uninstalled pip-22.2.2
2026-02-27 00:29:31.260327 | controller | Successfully installed pip-25.0.1
2026-02-27 00:29:32.075623 | controller | ok: Runtime: 0:00:05.561044
2026-02-27 00:29:32.089968 | 
2026-02-27 00:29:32.090139 | TASK [Ensure pip temp directory exists in /var/tmp]
2026-02-27 00:29:33.167880 | controller | changed
2026-02-27 00:29:33.173534 | 
2026-02-27 00:29:33.173601 | TASK [Install ansible into virtualenv]
2026-02-27 00:29:34.933499 | controller | Processing ./src/github.com/ansible/ansible
2026-02-27 00:29:34.936817 | controller |   Preparing metadata (setup.py): started
2026-02-27 00:29:35.791156 | controller |   Preparing metadata (setup.py): finished with status 'done'
2026-02-27 00:29:36.109837 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2026-02-27 00:29:36.380791 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2026-02-27 00:29:36.667329 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2026-02-27 00:29:36.757787 | controller |   Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.1 kB)
2026-02-27 00:29:37.472587 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2026-02-27 00:29:37.561807 | controller |   Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2026-02-27 00:29:37.596920 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (26.0)
2026-02-27 00:29:37.929289 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2026-02-27 00:29:38.021846 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2026-02-27 00:29:38.030773 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.8/site-packages (from cryptography->ansible-base==2.10.17.post0) (4.13.2)
2026-02-27 00:29:38.233950 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2026-02-27 00:29:38.323886 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2026-02-27 00:29:38.451340 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2026-02-27 00:29:38.540193 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2026-02-27 00:29:38.646088 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2026-02-27 00:29:39.250735 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 12.4 MB/s eta 0:00:00
2026-02-27 00:29:39.342081 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2026-02-27 00:29:39.445291 | controller | Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (806 kB)
2026-02-27 00:29:39.473717 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 26.1 MB/s eta 0:00:00
2026-02-27 00:29:39.564004 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2026-02-27 00:29:39.683708 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2026-02-27 00:29:39.786654 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2026-02-27 00:29:39.817059 | controller | Building wheels for collected packages: ansible-base
2026-02-27 00:29:39.819124 | controller |   Building wheel for ansible-base (setup.py): started
2026-02-27 00:29:42.165319 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2026-02-27 00:29:42.167539 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=c599e7a11d37becececeb44305c744be0b0990c918d1bfbf632bcdaacffc7351
2026-02-27 00:29:42.167812 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-w2g9bdes/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2026-02-27 00:29:42.182052 | controller | Successfully built ansible-base
2026-02-27 00:29:42.244290 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2026-02-27 00:29:45.065626 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-base-2.10.17.post0 cffi-1.17.1 cryptography-46.0.5 jinja2-3.1.6 pycparser-2.23
2026-02-27 00:29:45.548125 | controller | ok: Runtime: 0:00:11.266440
2026-02-27 00:29:45.562447 | 
2026-02-27 00:29:45.562596 | TASK [Setup download-artifact-fork role]
2026-02-27 00:29:45.595005 | controller | ok
2026-02-27 00:29:45.648403 | 
2026-02-27 00:29:45.648563 | TASK [download-artifact-fork : Parse inventory response]
2026-02-27 00:29:45.690163 | controller | ok
2026-02-27 00:29:45.696256 | 
2026-02-27 00:29:45.696325 | TASK [download-artifact-fork : Fetch info from Zuul API]
2026-02-27 00:29:45.722156 | controller | skipping: Conditional result was False
2026-02-27 00:29:45.730334 | 
2026-02-27 00:29:45.730410 | TASK [download-artifact-fork : Parse build response]
2026-02-27 00:29:45.755516 | controller | skipping: Conditional result was False
2026-02-27 00:29:45.768091 | 
2026-02-27 00:29:45.768198 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2026-02-27 00:29:46.698426 | controller | changed
2026-02-27 00:29:46.711325 | 
2026-02-27 00:29:46.711472 | LOOP [download-artifact-fork : Download archive by type]
2026-02-27 00:29:46.752269 | controller | skipping: Conditional result was False
2026-02-27 00:29:46.752893 | 
2026-02-27 00:29:49.551762 | controller | changed: OK (20207 bytes)
2026-02-27 00:29:49.577530 | 
2026-02-27 00:29:49.577728 | TASK [deploy-artifacts : define __collections]
2026-02-27 00:29:49.613444 | controller | ok
2026-02-27 00:29:49.621970 | 
2026-02-27 00:29:49.622075 | LOOP [deploy-artifacts : Create list of collections]
2026-02-27 00:29:49.648672 | controller | skipping: Conditional result was False
2026-02-27 00:29:49.661869 | controller | ok:
2026-02-27 00:29:49.661952 | controller | {
2026-02-27 00:29:49.661976 | controller |   "branch": "main",
2026-02-27 00:29:49.661995 | controller |   "job": "build-ansible-collection",
2026-02-27 00:29:49.662012 | controller |   "metadata": {
2026-02-27 00:29:49.662031 | controller |     "type": "ansible_collection",
2026-02-27 00:29:49.662048 | controller |     "version": "5.0.0"
2026-02-27 00:29:49.662064 | controller |   },
2026-02-27 00:29:49.662080 | controller |   "name": "ansible.network",
2026-02-27 00:29:49.662096 | controller |   "project": "ansible-collections/ansible.network",
2026-02-27 00:29:49.662112 | controller |   "url": "https://379e96246a6f0d8bbef5-52309d2d94fb7db8f721d2dba96edb59.ssl.cf5.rackcdn.com/ansible/35d413d323674ff19fa144cb7562d841/artifacts/ansible-network-5.0.0.tar.gz"
2026-02-27 00:29:49.662135 | controller | }
2026-02-27 00:29:49.680979 | 
2026-02-27 00:29:49.681432 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2026-02-27 00:29:51.259590 | controller | Starting galaxy collection install process
2026-02-27 00:29:51.260104 | controller | Process install dependency map
2026-02-27 00:30:03.872334 | controller | Starting collection install process
2026-02-27 00:30:03.872514 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2026-02-27 00:30:03.885388 | controller | ansible.network (5.0.0) was installed successfully
2026-02-27 00:30:03.885521 | controller | Installing 'ansible.netcommon:8.4.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-02-27 00:30:03.885706 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-netcommon-8.4.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4039_bvygb9y/tmpddv4293e
2026-02-27 00:30:05.443125 | controller | ansible.netcommon (8.4.0) was installed successfully
2026-02-27 00:30:05.443258 | controller | Installing 'ansible.utils:6.0.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-02-27 00:30:05.443475 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-utils-6.0.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4039_bvygb9y/tmpddv4293e
2026-02-27 00:30:07.639139 | controller | ansible.utils (6.0.1) was installed successfully
2026-02-27 00:30:07.639190 | controller | Installing 'arista.eos:12.0.1' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2026-02-27 00:30:07.639343 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/arista-eos-12.0.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4039_bvygb9y/tmpddv4293e
2026-02-27 00:30:12.233909 | controller | arista.eos (12.0.1) was installed successfully
2026-02-27 00:30:12.234108 | controller | Installing 'cisco.ios:11.3.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2026-02-27 00:30:12.234123 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-ios-11.3.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4039_bvygb9y/tmpddv4293e
2026-02-27 00:30:15.768865 | controller | cisco.ios (11.3.0) was installed successfully
2026-02-27 00:30:15.768943 | controller | Installing 'cisco.iosxr:12.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2026-02-27 00:30:15.768955 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-iosxr-12.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4039_bvygb9y/tmpddv4293e
2026-02-27 00:30:18.705583 | controller | cisco.iosxr (12.1.1) was installed successfully
2026-02-27 00:30:18.705719 | controller | Installing 'cisco.nxos:11.1.3' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2026-02-27 00:30:18.706119 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.1.3.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4039_bvygb9y/tmpddv4293e
2026-02-27 00:30:22.885827 | controller | cisco.nxos (11.1.3) was installed successfully
2026-02-27 00:30:22.886972 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2026-02-27 00:30:22.887018 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/junipernetworks-junos-11.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4039_bvygb9y/tmpddv4293e
2026-02-27 00:30:25.656335 | controller | junipernetworks.junos (11.0.0) was installed successfully
2026-02-27 00:30:26.129126 | controller | ok: Runtime: 0:00:35.230930
2026-02-27 00:30:26.146767 | 
2026-02-27 00:30:26.146918 | TASK [podman may need iptables kernel modules]
2026-02-27 00:30:27.514328 | controller | ok: Runtime: 0:00:00.007625
2026-02-27 00:30:27.564977 | 
2026-02-27 00:30:27.565148 | PLAY RECAP
2026-02-27 00:30:27.565209 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2026-02-27 00:30:27.565238 | 
2026-02-27 00:30:27.762017 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-02-27 00:30:27.763077 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-02-27 00:30:28.463883 | 
2026-02-27 00:30:28.464098 | PLAY [controller]
2026-02-27 00:30:28.488828 | 
2026-02-27 00:30:28.489007 | TASK [Copy the galaxy.yml on the controller]
2026-02-27 00:30:32.260387 | controller | changed
2026-02-27 00:30:32.269300 | 
2026-02-27 00:30:32.269430 | TASK [Load information from galaxy.yml]
2026-02-27 00:30:32.306354 | controller | ok
2026-02-27 00:30:32.316261 | 
2026-02-27 00:30:32.316439 | LOOP [Enable FIPS mode]
2026-02-27 00:30:32.344265 | 
2026-02-27 00:30:32.344523 | LOOP [ansible-test : Include branch specific variables.]
2026-02-27 00:30:32.404421 | controller | ok: "/var/lib/zuul/builds/80a0482d4d654ff4bf59fb36946b2e9d/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2026-02-27 00:30:32.404912 | 
2026-02-27 00:30:32.429368 | 
2026-02-27 00:30:32.429587 | TASK [ansible-test : Setup --skip-tags for test_options]
2026-02-27 00:30:32.484822 | controller | ok
2026-02-27 00:30:32.493950 | 
2026-02-27 00:30:32.494059 | TASK [ansible-test : Enable --inventory for network-integration]
2026-02-27 00:30:32.520812 | controller | skipping: Conditional result was False
2026-02-27 00:30:32.530112 | 
2026-02-27 00:30:32.530203 | TASK [ansible-test : Enable --retry-on-error]
2026-02-27 00:30:32.558787 | controller | skipping: Conditional result was False
2026-02-27 00:30:32.566700 | 
2026-02-27 00:30:32.566785 | TASK [ansible-test : Enable --continue-on-error]
2026-02-27 00:30:32.608210 | controller | ok
2026-02-27 00:30:32.614216 | 
2026-02-27 00:30:32.614282 | TASK [ansible-test : Enable --requirements]
2026-02-27 00:30:32.638887 | controller | skipping: Conditional result was False
2026-02-27 00:30:32.645496 | 
2026-02-27 00:30:32.645561 | TASK [ansible-test : Adjust options for unit tests]
2026-02-27 00:30:32.669655 | controller | skipping: Conditional result was False
2026-02-27 00:30:32.676535 | 
2026-02-27 00:30:32.676605 | TASK [ansible-test : Install requirements with unit tests]
2026-02-27 00:30:32.759847 | controller | ok
2026-02-27 00:30:32.767935 | 
2026-02-27 00:30:32.768056 | TASK [ansible-test : Set --skip-test]
2026-02-27 00:30:32.833790 | controller | skipping: Conditional result was False
2026-02-27 00:30:32.841313 | 
2026-02-27 00:30:32.841388 | TASK [ansible-test : Reduce verbosity]
2026-02-27 00:30:32.889436 | controller | ok
2026-02-27 00:30:32.901996 | 
2026-02-27 00:30:32.902136 | TASK [ansible-test : Setup --python option]
2026-02-27 00:30:32.928850 | controller | skipping: Conditional result was False
2026-02-27 00:30:32.943866 | 
2026-02-27 00:30:32.944017 | TASK [ansible-test : Setup --docker option]
2026-02-27 00:30:32.988389 | controller | ok
2026-02-27 00:30:33.004555 | 
2026-02-27 00:30:33.004648 | TASK [ansible-test : check if MANIFEST.json exists]
2026-02-27 00:30:34.087663 | controller | ok
2026-02-27 00:30:34.101215 | 
2026-02-27 00:30:34.101355 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2026-02-27 00:30:34.129072 | controller | skipping: Conditional result was False
2026-02-27 00:30:34.138164 | 
2026-02-27 00:30:34.138253 | TASK [ansible-test : Load information from MANIFEST.json]
2026-02-27 00:30:34.164058 | controller | skipping: Conditional result was False
2026-02-27 00:30:34.177206 | 
2026-02-27 00:30:34.177331 | TASK [ansible-test : Setup location of project for integration tests]
2026-02-27 00:30:34.204090 | controller | skipping: Conditional result was False
2026-02-27 00:30:34.215826 | 
2026-02-27 00:30:34.215928 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2026-02-27 00:30:35.546879 | controller | ok
2026-02-27 00:30:35.558655 | 
2026-02-27 00:30:35.559190 | TASK [ansible-test : Load information from galaxy.yml]
2026-02-27 00:30:35.605711 | controller | ok
2026-02-27 00:30:35.619134 | 
2026-02-27 00:30:35.619275 | TASK [ansible-test : Setup location of project for integration tests]
2026-02-27 00:30:35.655034 | controller | ok
2026-02-27 00:30:35.664725 | 
2026-02-27 00:30:35.664854 | TASK [ansible-test : Setup minimum test requirements]
2026-02-27 00:30:35.713501 | controller | ok
2026-02-27 00:30:35.721947 | 
2026-02-27 00:30:35.722046 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2026-02-27 00:30:36.696977 | controller | ok
2026-02-27 00:30:36.705598 | 
2026-02-27 00:30:36.705721 | TASK [ansible-test : Install python requirements]
2026-02-27 00:30:38.347485 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-02-27 00:30:38.612602 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2026-02-27 00:30:38.750143 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-02-27 00:30:38.836187 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2026-02-27 00:30:38.865897 | 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.3)
2026-02-27 00:30:38.957209 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2026-02-27 00:30:39.154257 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2026-02-27 00:30:39.221127 | controller | Installing collected packages: pathspec, yamllint
2026-02-27 00:30:39.328692 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2026-02-27 00:30:40.070773 | controller | ok: Runtime: 0:00:01.975401
2026-02-27 00:30:40.087373 | 
2026-02-27 00:30:40.087570 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2026-02-27 00:30:40.958790 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2026-02-27 00:30:41.467554 | controller | ERROR
2026-02-27 00:30:41.467995 | controller | {
2026-02-27 00:30:41.468062 | controller |   "delta": "0:00:00.008828",
2026-02-27 00:30:41.468105 | controller |   "end": "2026-02-27 00:30:40.959017",
2026-02-27 00:30:41.468143 | controller |   "msg": "non-zero return code",
2026-02-27 00:30:41.468178 | controller |   "rc": 1,
2026-02-27 00:30:41.468214 | controller |   "start": "2026-02-27 00:30:40.950189"
2026-02-27 00:30:41.468249 | controller | }
2026-02-27 00:30:41.468304 | controller | ERROR: Ignoring Errors
2026-02-27 00:30:41.485905 | 
2026-02-27 00:30:41.486187 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2026-02-27 00:30:41.869581 | controller | skipping: Conditional result was False
2026-02-27 00:30:41.884858 | 
2026-02-27 00:30:41.885015 | TASK [ansible-test : Enable persistent connection logging]
2026-02-27 00:30:43.134563 | controller | changed: section and option added
2026-02-27 00:30:43.141499 | 
2026-02-27 00:30:43.141622 | TASK [ansible-test : Set persistent ssh_type value]
2026-02-27 00:30:44.310458 | controller | changed: option added
2026-02-27 00:30:44.320406 | 
2026-02-27 00:30:44.320578 | TASK [ansible-test : Set ansible log path]
2026-02-27 00:30:45.410730 | controller | changed: section and option added
2026-02-27 00:30:45.418691 | 
2026-02-27 00:30:45.418791 | TASK [ansible-test : Identify the changed targets]
2026-02-27 00:30:45.788439 | controller | skipping: Conditional result was False
2026-02-27 00:30:45.803764 | 
2026-02-27 00:30:45.803959 | TASK [ansible-test : set_fact]
2026-02-27 00:30:45.883309 | controller | skipping: Conditional result was False
2026-02-27 00:30:45.898031 | 
2026-02-27 00:30:45.898198 | TASK [ansible-test : Set the targets]
2026-02-27 00:30:46.021878 | controller | ok
2026-02-27 00:30:46.030032 | 
2026-02-27 00:30:46.030122 | TASK [ansible-test : set_fact]
2026-02-27 00:30:46.046756 | controller | skipping: Conditional result was False
2026-02-27 00:30:46.056800 | 
2026-02-27 00:30:46.056941 | TASK [ansible-test : List the targets]
2026-02-27 00:30:46.432430 | controller | skipping: Conditional result was False
2026-02-27 00:30:46.440021 | 
2026-02-27 00:30:46.440155 | TASK [ansible-test : set_fact]
2026-02-27 00:30:46.465085 | controller | skipping: Conditional result was False
2026-02-27 00:30:46.474459 | 
2026-02-27 00:30:46.474564 | TASK [ansible-test : set_fact]
2026-02-27 00:30:46.499607 | controller | skipping: Conditional result was False
2026-02-27 00:30:46.509130 | 
2026-02-27 00:30:46.509235 | LOOP [ansible-test : set_fact]
2026-02-27 00:30:46.593999 | 
2026-02-27 00:30:46.594218 | TASK [ansible-test : set_fact]
2026-02-27 00:30:46.619339 | controller | skipping: Conditional result was False
2026-02-27 00:30:46.631837 | 
2026-02-27 00:30:46.631985 | TASK [ansible-test : debug]
2026-02-27 00:30:46.657292 | controller | skipping: Conditional result was False
2026-02-27 00:30:46.666790 | 
2026-02-27 00:30:46.666916 | TASK [ansible-test : debug]
2026-02-27 00:30:46.700338 | About to run: ansible-test sanity --requirements --docker -v
2026-02-27 00:30:46.708398 | 
2026-02-27 00:30:46.708487 | TASK [ansible-test : Run the test suite]
2026-02-27 00:30:48.812413 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2026-02-27 00:30:48.871245 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2026-02-27 00:30:50.384754 | controller | 2.9.0: Pulling from ansible/default-test-container
2026-02-27 00:30:50.384827 | controller | 171857c49d0f: Pulling fs layer
2026-02-27 00:30:50.384849 | controller | 419640447d26: Pulling fs layer
2026-02-27 00:30:50.384859 | controller | 61e52f862619: Pulling fs layer
2026-02-27 00:30:50.384869 | controller | 851de0426e26: Pulling fs layer
2026-02-27 00:30:50.384881 | controller | 37425716ea5b: Pulling fs layer
2026-02-27 00:30:50.384890 | controller | cd9b0c439616: Pulling fs layer
2026-02-27 00:30:50.384897 | controller | d4612dbd8855: Pulling fs layer
2026-02-27 00:30:50.384903 | controller | 554699364730: Pulling fs layer
2026-02-27 00:30:50.384909 | controller | 1ff15d4bdb3e: Pulling fs layer
2026-02-27 00:30:50.384915 | controller | 5f2e4724adea: Pulling fs layer
2026-02-27 00:30:50.384921 | controller | a02d9a95ccb8: Pulling fs layer
2026-02-27 00:30:50.384927 | controller | 989f4ce370cb: Pulling fs layer
2026-02-27 00:30:50.384933 | controller | 0d7f52cdf9df: Pulling fs layer
2026-02-27 00:30:50.384939 | controller | c423742351d5: Pulling fs layer
2026-02-27 00:30:50.384945 | controller | 109236341d21: Pulling fs layer
2026-02-27 00:30:50.384951 | controller | 07a2f61c96b8: Pulling fs layer
2026-02-27 00:30:50.384957 | controller | 041f122f27ae: Pulling fs layer
2026-02-27 00:30:50.384963 | controller | ca6f5098b12e: Pulling fs layer
2026-02-27 00:30:50.384968 | controller | 1eb3e7b2afd9: Pulling fs layer
2026-02-27 00:30:50.384984 | controller | 18a0b750727a: Pulling fs layer
2026-02-27 00:30:50.384993 | controller | 17f2f596716c: Pulling fs layer
2026-02-27 00:30:50.385001 | controller | f35a9998a9bd: Pulling fs layer
2026-02-27 00:30:50.385008 | controller | fdfc10566405: Pulling fs layer
2026-02-27 00:30:50.385014 | controller | 1ff1c442c5fe: Pulling fs layer
2026-02-27 00:30:50.385021 | controller | a3443c4a17ed: Pulling fs layer
2026-02-27 00:30:50.385027 | controller | 0edcf9fb2b15: Pulling fs layer
2026-02-27 00:30:50.385033 | controller | 5af43b69e845: Pulling fs layer
2026-02-27 00:30:50.385040 | controller | 1651773effb2: Pulling fs layer
2026-02-27 00:30:50.385046 | controller | 79e23c5c0fe4: Pulling fs layer
2026-02-27 00:30:50.385084 | controller | e5058b1faaa6: Pulling fs layer
2026-02-27 00:30:50.385095 | controller | 846775490569: Pulling fs layer
2026-02-27 00:30:50.385102 | controller | c423742351d5: Waiting
2026-02-27 00:30:50.385108 | controller | 109236341d21: Waiting
2026-02-27 00:30:50.385114 | controller | 07a2f61c96b8: Waiting
2026-02-27 00:30:50.385119 | controller | 041f122f27ae: Waiting
2026-02-27 00:30:50.385125 | controller | ca6f5098b12e: Waiting
2026-02-27 00:30:50.385132 | controller | 1eb3e7b2afd9: Waiting
2026-02-27 00:30:50.385141 | controller | 18a0b750727a: Waiting
2026-02-27 00:30:50.385147 | controller | 17f2f596716c: Waiting
2026-02-27 00:30:50.385153 | controller | f35a9998a9bd: Waiting
2026-02-27 00:30:50.385162 | controller | fdfc10566405: Waiting
2026-02-27 00:30:51.122328 | controller | 1ff1c442c5fe: Waiting
2026-02-27 00:30:51.122369 | controller | a3443c4a17ed: Waiting
2026-02-27 00:30:51.122376 | controller | 0edcf9fb2b15: Waiting
2026-02-27 00:30:51.122382 | controller | 5af43b69e845: Waiting
2026-02-27 00:30:51.122388 | controller | 851de0426e26: Waiting
2026-02-27 00:30:51.122394 | controller | 37425716ea5b: Waiting
2026-02-27 00:30:51.122400 | controller | 1651773effb2: Waiting
2026-02-27 00:30:51.122406 | controller | 79e23c5c0fe4: Waiting
2026-02-27 00:30:51.122417 | controller | cd9b0c439616: Waiting
2026-02-27 00:30:51.122423 | controller | e5058b1faaa6: Waiting
2026-02-27 00:30:51.122429 | controller | d4612dbd8855: Waiting
2026-02-27 00:30:51.122436 | controller | 846775490569: Waiting
2026-02-27 00:30:51.122441 | controller | 554699364730: Waiting
2026-02-27 00:30:51.122447 | controller | 1ff15d4bdb3e: Waiting
2026-02-27 00:30:51.122453 | controller | 989f4ce370cb: Waiting
2026-02-27 00:30:51.122459 | controller | 5f2e4724adea: Waiting
2026-02-27 00:30:51.122465 | controller | 0d7f52cdf9df: Waiting
2026-02-27 00:30:51.122472 | controller | a02d9a95ccb8: Waiting
2026-02-27 00:30:51.122486 | controller | 61e52f862619: Download complete
2026-02-27 00:30:51.124986 | controller | 419640447d26: Verifying Checksum
2026-02-27 00:30:51.891826 | controller | 419640447d26: Download complete
2026-02-27 00:30:51.891877 | controller | 851de0426e26: Verifying Checksum
2026-02-27 00:30:52.637102 | controller | 851de0426e26: Download complete
2026-02-27 00:30:52.637152 | controller | cd9b0c439616: Verifying Checksum
2026-02-27 00:30:53.491366 | controller | cd9b0c439616: Download complete
2026-02-27 00:30:53.491439 | controller | d4612dbd8855: Verifying Checksum
2026-02-27 00:30:56.513322 | controller | d4612dbd8855: Download complete
2026-02-27 00:30:56.513392 | controller | 171857c49d0f: Verifying Checksum
2026-02-27 00:30:57.353459 | controller | 171857c49d0f: Download complete
2026-02-27 00:30:57.353519 | controller | 1ff15d4bdb3e: Verifying Checksum
2026-02-27 00:30:58.131568 | controller | 1ff15d4bdb3e: Download complete
2026-02-27 00:30:58.131623 | controller | 5f2e4724adea: Verifying Checksum
2026-02-27 00:30:58.131762 | controller | 5f2e4724adea: Download complete
2026-02-27 00:30:59.350422 | controller | 171857c49d0f: Pull complete
2026-02-27 00:30:59.393515 | controller | 419640447d26: Pull complete
2026-02-27 00:30:59.441192 | controller | 61e52f862619: Pull complete
2026-02-27 00:30:59.514945 | controller | 851de0426e26: Pull complete
2026-02-27 00:30:59.536636 | controller | a02d9a95ccb8: Verifying Checksum
2026-02-27 00:31:00.263703 | controller | a02d9a95ccb8: Download complete
2026-02-27 00:31:00.263789 | controller | 989f4ce370cb: Verifying Checksum
2026-02-27 00:31:01.069875 | controller | 989f4ce370cb: Download complete
2026-02-27 00:31:01.069946 | controller | 0d7f52cdf9df: Verifying Checksum
2026-02-27 00:31:01.070091 | controller | 0d7f52cdf9df: Download complete
2026-02-27 00:31:01.833888 | controller | c423742351d5: Verifying Checksum
2026-02-27 00:31:01.833989 | controller | c423742351d5: Download complete
2026-02-27 00:31:05.182462 | controller | 554699364730: Verifying Checksum
2026-02-27 00:31:05.182568 | controller | 554699364730: Download complete
2026-02-27 00:31:05.923453 | controller | 07a2f61c96b8: Verifying Checksum
2026-02-27 00:31:05.923586 | controller | 07a2f61c96b8: Download complete
2026-02-27 00:31:06.717243 | controller | 041f122f27ae: Verifying Checksum
2026-02-27 00:31:06.717335 | controller | 041f122f27ae: Download complete
2026-02-27 00:31:08.316925 | controller | ca6f5098b12e: Verifying Checksum
2026-02-27 00:31:08.317057 | controller | ca6f5098b12e: Download complete
2026-02-27 00:31:09.607620 | controller | 1eb3e7b2afd9: Verifying Checksum
2026-02-27 00:31:09.607739 | controller | 1eb3e7b2afd9: Download complete
2026-02-27 00:31:11.127094 | controller | 18a0b750727a: Verifying Checksum
2026-02-27 00:31:11.127192 | controller | 18a0b750727a: Download complete
2026-02-27 00:31:11.285402 | controller | 109236341d21: Verifying Checksum
2026-02-27 00:31:11.285568 | controller | 109236341d21: Download complete
2026-02-27 00:31:11.848024 | controller | 17f2f596716c: Verifying Checksum
2026-02-27 00:31:11.848191 | controller | 17f2f596716c: Download complete
2026-02-27 00:31:12.006161 | controller | f35a9998a9bd: Verifying Checksum
2026-02-27 00:31:12.006293 | controller | f35a9998a9bd: Download complete
2026-02-27 00:31:12.653646 | controller | fdfc10566405: Verifying Checksum
2026-02-27 00:31:12.653736 | controller | fdfc10566405: Download complete
2026-02-27 00:31:12.805646 | controller | 1ff1c442c5fe: Verifying Checksum
2026-02-27 00:31:12.805805 | controller | 1ff1c442c5fe: Download complete
2026-02-27 00:31:16.114865 | controller | a3443c4a17ed: Verifying Checksum
2026-02-27 00:31:16.115029 | controller | a3443c4a17ed: Download complete
2026-02-27 00:31:19.880042 | controller | 5af43b69e845: Verifying Checksum
2026-02-27 00:31:21.269411 | controller | 5af43b69e845: Download complete
2026-02-27 00:31:21.269496 | controller | 0edcf9fb2b15: Verifying Checksum
2026-02-27 00:31:21.269534 | controller | 0edcf9fb2b15: Download complete
2026-02-27 00:31:24.051891 | controller | 79e23c5c0fe4: Verifying Checksum
2026-02-27 00:31:24.052001 | controller | 79e23c5c0fe4: Download complete
2026-02-27 00:31:25.115172 | controller | 37425716ea5b: Verifying Checksum
2026-02-27 00:31:25.115340 | controller | 37425716ea5b: Download complete
2026-02-27 00:31:26.656623 | controller | 1651773effb2: Verifying Checksum
2026-02-27 00:31:26.656976 | controller | 1651773effb2: Download complete
2026-02-27 00:31:27.526033 | controller | e5058b1faaa6: Verifying Checksum
2026-02-27 00:31:27.526218 | controller | e5058b1faaa6: Download complete
2026-02-27 00:31:32.876444 | controller | 846775490569: Verifying Checksum
2026-02-27 00:31:32.879489 | controller | 846775490569: Download complete
2026-02-27 00:31:45.082746 | controller | 37425716ea5b: Pull complete
2026-02-27 00:31:45.105272 | controller | cd9b0c439616: Pull complete
2026-02-27 00:31:45.127374 | controller | d4612dbd8855: Pull complete
2026-02-27 00:31:49.992779 | controller | 554699364730: Pull complete
2026-02-27 00:31:50.033937 | controller | 1ff15d4bdb3e: Pull complete
2026-02-27 00:31:50.073540 | controller | 5f2e4724adea: Pull complete
2026-02-27 00:31:50.128385 | controller | a02d9a95ccb8: Pull complete
2026-02-27 00:31:50.157268 | controller | 989f4ce370cb: Pull complete
2026-02-27 00:31:50.195229 | controller | 0d7f52cdf9df: Pull complete
2026-02-27 00:31:50.219584 | controller | c423742351d5: Pull complete
2026-02-27 00:31:52.640044 | controller | 109236341d21: Pull complete
2026-02-27 00:31:52.730379 | controller | 07a2f61c96b8: Pull complete
2026-02-27 00:31:52.823603 | controller | 041f122f27ae: Pull complete
2026-02-27 00:31:53.096793 | controller | ca6f5098b12e: Pull complete
2026-02-27 00:31:53.146987 | controller | 1eb3e7b2afd9: Pull complete
2026-02-27 00:31:53.306603 | controller | 18a0b750727a: Pull complete
2026-02-27 00:31:53.365093 | controller | 17f2f596716c: Pull complete
2026-02-27 00:31:53.396750 | controller | f35a9998a9bd: Pull complete
2026-02-27 00:31:53.441554 | controller | fdfc10566405: Pull complete
2026-02-27 00:31:53.469945 | controller | 1ff1c442c5fe: Pull complete
2026-02-27 00:31:55.319052 | controller | a3443c4a17ed: Pull complete
2026-02-27 00:31:57.213030 | controller | 0edcf9fb2b15: Pull complete
2026-02-27 00:32:01.017225 | controller | 5af43b69e845: Pull complete
2026-02-27 00:32:05.388996 | controller | 1651773effb2: Pull complete
2026-02-27 00:32:08.199109 | controller | 79e23c5c0fe4: Pull complete
2026-02-27 00:32:10.327244 | controller | e5058b1faaa6: Pull complete
2026-02-27 00:32:13.144521 | controller | 846775490569: Pull complete
2026-02-27 00:32:13.172752 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2026-02-27 00:32:13.179568 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2026-02-27 00:32:13.190504 | controller | quay.io/ansible/default-test-container:2.9.0
2026-02-27 00:32:13.193305 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2026-02-27 00:32:13.220850 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2026-02-27 00:32:14.615392 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2026-02-27 00:32:14.615485 | controller | 04a5f4cda3ee: Pulling fs layer
2026-02-27 00:32:14.615523 | controller | ff496a88c8ed: Pulling fs layer
2026-02-27 00:32:14.615558 | controller | 0ce83f459fe7: Pulling fs layer
2026-02-27 00:32:14.615592 | controller | 2e5170e1f099: Pulling fs layer
2026-02-27 00:32:14.615631 | controller | 7641eb41b08c: Pulling fs layer
2026-02-27 00:32:14.617491 | controller | ad15fa9da398: Pulling fs layer
2026-02-27 00:32:14.617519 | controller | 087d91352424: Pulling fs layer
2026-02-27 00:32:14.617578 | controller | 8b92efd6a100: Pulling fs layer
2026-02-27 00:32:14.617640 | controller | 2e5170e1f099: Waiting
2026-02-27 00:32:14.617674 | controller | 7641eb41b08c: Waiting
2026-02-27 00:32:14.617741 | controller | ad15fa9da398: Waiting
2026-02-27 00:32:14.617772 | controller | 087d91352424: Waiting
2026-02-27 00:32:14.617848 | controller | 8b92efd6a100: Waiting
2026-02-27 00:32:14.985621 | controller | ff496a88c8ed: Verifying Checksum
2026-02-27 00:32:15.025730 | controller | ff496a88c8ed: Download complete
2026-02-27 00:32:15.025801 | controller | 0ce83f459fe7: Download complete
2026-02-27 00:32:15.391061 | controller | 04a5f4cda3ee: Verifying Checksum
2026-02-27 00:32:15.445092 | controller | 04a5f4cda3ee: Download complete
2026-02-27 00:32:15.445162 | controller | 7641eb41b08c: Verifying Checksum
2026-02-27 00:32:15.569473 | controller | 7641eb41b08c: Download complete
2026-02-27 00:32:15.569545 | controller | 2e5170e1f099: Verifying Checksum
2026-02-27 00:32:15.569848 | controller | 2e5170e1f099: Download complete
2026-02-27 00:32:15.786601 | controller | ad15fa9da398: Verifying Checksum
2026-02-27 00:32:15.921783 | controller | ad15fa9da398: Download complete
2026-02-27 00:32:15.921857 | controller | 087d91352424: Verifying Checksum
2026-02-27 00:32:15.984654 | controller | 087d91352424: Download complete
2026-02-27 00:32:15.984726 | controller | 8b92efd6a100: Verifying Checksum
2026-02-27 00:32:17.915855 | controller | 8b92efd6a100: Download complete
2026-02-27 00:32:17.915940 | controller | 04a5f4cda3ee: Pull complete
2026-02-27 00:32:17.964282 | controller | ff496a88c8ed: Pull complete
2026-02-27 00:32:18.009834 | controller | 0ce83f459fe7: Pull complete
2026-02-27 00:32:19.990913 | controller | 2e5170e1f099: Pull complete
2026-02-27 00:32:20.034824 | controller | 7641eb41b08c: Pull complete
2026-02-27 00:32:20.080276 | controller | ad15fa9da398: Pull complete
2026-02-27 00:32:21.111271 | controller | 087d91352424: Pull complete
2026-02-27 00:32:21.140865 | controller | 8b92efd6a100: Pull complete
2026-02-27 00:32:21.164699 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2026-02-27 00:32:21.189562 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2026-02-27 00:32:21.210031 | controller | quay.io/ansible/pypi-test-container:1.0.0
2026-02-27 00:32:21.213732 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2026-02-27 00:32:31.054306 | controller | Run command: docker inspect 7dd774cb97a14393beaab47b44ee4370e8c683e3dacffa1bbfeb0f042e70937d
2026-02-27 00:32:31.120541 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2026-02-27 00:32:31.138177 | controller | Including collection: ansible.netcommon (278 files)
2026-02-27 00:32:31.151945 | controller | Including collection: ansible.network (23 files)
2026-02-27 00:32:31.152009 | controller | Including collection: ansible.utils (441 files)
2026-02-27 00:32:31.204228 | controller | Including collection: arista.eos (1031 files)
2026-02-27 00:32:31.257508 | controller | Including collection: cisco.ios (1215 files)
2026-02-27 00:32:31.308320 | controller | Including collection: cisco.iosxr (1044 files)
2026-02-27 00:32:31.395958 | controller | Including collection: cisco.nxos (1687 files)
2026-02-27 00:32:31.447252 | controller | Including collection: junipernetworks.junos (963 files)
2026-02-27 00:32:31.493481 | controller | Creating a payload archive containing 7408 files...
2026-02-27 00:32:33.757186 | controller | Created a 6041579 byte payload archive containing 7408 files in 2 seconds.
2026-02-27 00:32:33.761055 | controller | Assuming Docker is available on localhost.
2026-02-27 00:32:33.761345 | 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:2.9.0
2026-02-27 00:32:34.317377 | controller | Run command: docker exec -i 54f384f4e91297a478e81f4a61092e47020c6ca63ab25f3e8edb678d0ca0374f dd of=/root/docker.sh bs=65536
2026-02-27 00:32:34.404628 | controller | Run command: docker exec 54f384f4e91297a478e81f4a61092e47020c6ca63ab25f3e8edb678d0ca0374f /bin/bash /root/docker.sh
2026-02-27 00:32:34.483110 | controller | Run command: docker exec -i 54f384f4e91297a478e81f4a61092e47020c6ca63ab25f3e8edb678d0ca0374f dd of=/root/test.tgz bs=65536
2026-02-27 00:32:34.587705 | controller | Run command: docker exec 54f384f4e91297a478e81f4a61092e47020c6ca63ab25f3e8edb678d0ca0374f tar oxzf /root/test.tgz -C /root
2026-02-27 00:32:35.729434 | controller | Run command: docker exec 54f384f4e91297a478e81f4a61092e47020c6ca63ab25f3e8edb678d0ca0374f /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata tests/output/.tmp/metadata-tajpaogy.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2026-02-27 00:32:36.230358 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2026-02-27 00:32:36.230666 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2026-02-27 00:32:36.231116 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2026-02-27 00:32:36.233324 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2026-02-27 00:32:36.234279 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2026-02-27 00:32:36.244578 | controller | No tests applicable.
2026-02-27 00:32:36.244832 | controller | Running sanity test 'ansible-doc' with Python 3.6
2026-02-27 00:32:36.245083 | controller | No tests applicable.
2026-02-27 00:32:36.245270 | controller | Running sanity test 'changelog' with Python 3.6
2026-02-27 00:32:36.245805 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/ansible-test.txt
2026-02-27 00:32:37.308366 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt
2026-02-27 00:32:37.963318 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2026-02-27 00:32:52.984518 | 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/antsibull-changelog/
2026-02-27 00:33:08.501828 | 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/antsibull-changelog/
2026-02-27 00:33:24.520307 | 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/antsibull-changelog/
2026-02-27 00:33:41.539762 | 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/antsibull-changelog/
2026-02-27 00:34:00.560645 | 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/antsibull-changelog/
2026-02-27 00:34:15.578560 | controller |   ERROR: Could not find a version that satisfies the requirement antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2)) (from versions: none)
2026-02-27 00:34:15.697892 | controller | ERROR: No matching distribution found for antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2026-02-27 00:34:15.697955 | controller | ERROR: Command "/usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt" returned exit status 1.
2026-02-27 00:34:15.698172 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2026-02-27 00:34:15.743140 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2026-02-27 00:34:15.743191 | controller | Removing custom PyPI hosts entries: /etc/hosts
2026-02-27 00:34:15.743210 | controller | Run command: docker exec 54f384f4e91297a478e81f4a61092e47020c6ca63ab25f3e8edb678d0ca0374f tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2026-02-27 00:34:15.820523 | controller | Run command: docker exec -i 54f384f4e91297a478e81f4a61092e47020c6ca63ab25f3e8edb678d0ca0374f dd if=/root/output.tgz bs=65536
2026-02-27 00:34:15.906441 | controller | Run command: tar oxzf /tmp/ansible-result-mrfek4oz.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2026-02-27 00:34:15.922456 | controller | Run command: docker rm -f 7dd774cb97a14393beaab47b44ee4370e8c683e3dacffa1bbfeb0f042e70937d
2026-02-27 00:34:16.247010 | controller | Run command: docker rm -f 54f384f4e91297a478e81f4a61092e47020c6ca63ab25f3e8edb678d0ca0374f
2026-02-27 00:34:17.101243 | controller | ERROR: Command "docker exec 54f384f4e91297a478e81f4a61092e47020c6ca63ab25f3e8edb678d0ca0374f /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata tests/output/.tmp/metadata-tajpaogy.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2026-02-27 00:34:17.728985 | controller | ERROR
2026-02-27 00:34:17.729319 | controller | {
2026-02-27 00:34:17.729365 | controller |   "delta": "0:03:29.017826",
2026-02-27 00:34:17.729395 | controller |   "end": "2026-02-27 00:34:17.165198",
2026-02-27 00:34:17.729421 | controller |   "msg": "non-zero return code",
2026-02-27 00:34:17.729445 | controller |   "rc": 1,
2026-02-27 00:34:17.729468 | controller |   "start": "2026-02-27 00:30:48.147372"
2026-02-27 00:34:17.729493 | controller | }
failure
2026-02-27 00:34:17.736259 | 
2026-02-27 00:34:17.736369 | PLAY RECAP
2026-02-27 00:34:17.736434 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2026-02-27 00:34:17.736463 | 
2026-02-27 00:34:17.922988 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-02-27 00:34:17.924103 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-02-27 00:34:18.564450 | 
2026-02-27 00:34:18.564720 | PLAY [all]
2026-02-27 00:34:18.589150 | 
2026-02-27 00:34:18.589338 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-02-27 00:34:21.650539 | controller | changed: non-zero return code
2026-02-27 00:34:21.659383 | 
2026-02-27 00:34:21.659797 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-02-27 00:34:21.696182 | controller | skipping: Conditional result was False
2026-02-27 00:34:21.704964 | 
2026-02-27 00:34:21.705088 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-02-27 00:34:21.739792 | 
2026-02-27 00:34:21.740043 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-02-27 00:34:21.776918 | 
2026-02-27 00:34:21.777150 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-02-27 00:34:21.804577 | controller | skipping: Conditional result was False
2026-02-27 00:34:21.816246 | 
2026-02-27 00:34:21.816347 | LOOP [fetch-subunit-output : Generate subunit file]
2026-02-27 00:34:21.855343 | 
2026-02-27 00:34:21.855939 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-02-27 00:34:21.883870 | controller | skipping: Conditional result was False
2026-02-27 00:34:21.897655 | 
2026-02-27 00:34:21.897833 | TASK [fetch-subunit-output : Remove the temporary file]
2026-02-27 00:34:21.916485 | controller | skipping: Conditional result was False
2026-02-27 00:34:21.926279 | 
2026-02-27 00:34:21.926363 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-02-27 00:34:21.953398 | controller | skipping: Conditional result was False
2026-02-27 00:34:21.986129 | 
2026-02-27 00:34:21.986297 | PLAY RECAP
2026-02-27 00:34:21.986351 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-02-27 00:34:21.986372 | 
2026-02-27 00:34:22.181375 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-02-27 00:34:22.182437 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-02-27 00:34:22.828911 | 
2026-02-27 00:34:22.829104 | PLAY [all:!appliance*]
2026-02-27 00:34:22.852905 | 
2026-02-27 00:34:22.853148 | TASK [unregister the node]
2026-02-27 00:34:23.207337 | controller | skipping: Conditional result was False
2026-02-27 00:34:23.224795 | 
2026-02-27 00:34:23.225130 | TASK [include_role : fetch-output]
2026-02-27 00:34:23.295418 | controller | ok
2026-02-27 00:34:23.324347 | 
2026-02-27 00:34:23.324513 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-27 00:34:23.381010 | controller | skipping: Conditional result was False
2026-02-27 00:34:23.389096 | 
2026-02-27 00:34:23.389220 | TASK [fetch-output : Set log path for single node]
2026-02-27 00:34:23.434827 | controller | ok
2026-02-27 00:34:23.440919 | 
2026-02-27 00:34:23.440992 | LOOP [fetch-output : Ensure local output dirs]
2026-02-27 00:34:23.968165 | controller -> localhost | ok: "/var/lib/zuul/builds/80a0482d4d654ff4bf59fb36946b2e9d/work/logs"
2026-02-27 00:34:24.240925 | controller -> localhost | changed: "/var/lib/zuul/builds/80a0482d4d654ff4bf59fb36946b2e9d/work/artifacts"
2026-02-27 00:34:24.518164 | controller -> localhost | changed: "/var/lib/zuul/builds/80a0482d4d654ff4bf59fb36946b2e9d/work/docs"
2026-02-27 00:34:24.532933 | 
2026-02-27 00:34:24.533092 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-27 00:34:26.932286 | controller | changed:
2026-02-27 00:34:26.932730 | controller | .d..t...... ./
2026-02-27 00:34:26.932805 | controller | cd+++++++++ controller/
2026-02-27 00:34:26.932882 | controller | changed: All items complete
2026-02-27 00:34:26.932929 | 
2026-02-27 00:34:29.024044 | controller | changed: .d..t...... ./
2026-02-27 00:34:31.116801 | controller | changed: .d..t...... ./
2026-02-27 00:34:31.146068 | 
2026-02-27 00:34:31.146229 | TASK [include_role : fetch-output-openshift]
2026-02-27 00:34:31.164197 | controller | skipping: Conditional result was False
2026-02-27 00:34:31.175133 | 
2026-02-27 00:34:31.175291 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-27 00:34:31.207885 | controller | skipping: Conditional result was False
2026-02-27 00:34:31.232400 | controller | skipping: Conditional result was False
2026-02-27 00:34:31.270926 | 
2026-02-27 00:34:31.271064 | PLAY [localhost]
2026-02-27 00:34:31.284242 | 
2026-02-27 00:34:31.284317 | TASK [Run Zuul manifest role]
2026-02-27 00:34:31.313420 | localhost | ok
2026-02-27 00:34:31.326506 | 
2026-02-27 00:34:31.326598 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-27 00:34:31.745499 | localhost | changed
2026-02-27 00:34:31.750774 | 
2026-02-27 00:34:31.750852 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-27 00:34:31.781435 | localhost | ok
2026-02-27 00:34:31.790674 | 
2026-02-27 00:34:31.790753 | TASK [Set zuul-log-path fact]
2026-02-27 00:34:31.850214 | localhost | ok
2026-02-27 00:34:31.876975 | 
2026-02-27 00:34:31.877115 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-27 00:34:31.918653 | localhost | ok
2026-02-27 00:34:31.927704 | 
2026-02-27 00:34:31.927793 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)]
2026-02-27 00:34:31.961027 | localhost | ok:
2026-02-27 00:34:31.961368 | localhost | {
2026-02-27 00:34:31.961408 | localhost |   "auth": {
2026-02-27 00:34:31.961442 | localhost |     "password": "IonWCwcuOFib&&<}wp^:[2G/",
2026-02-27 00:34:31.961470 | localhost |     "username": "9LF91O0RK7JU"
2026-02-27 00:34:31.961496 | localhost |   },
2026-02-27 00:34:31.961522 | localhost |   "profile": "rackspace",
2026-02-27 00:34:31.961547 | localhost |   "region_name": "IAD"
2026-02-27 00:34:31.961573 | localhost | }
2026-02-27 00:34:31.961614 | 
2026-02-27 00:34:31.977083 | 
2026-02-27 00:34:31.977178 | TASK [Run upload-logs-failover role (S3 then Swift)]
2026-02-27 00:34:31.995674 | localhost | Output suppressed because no_log was given
2026-02-27 00:34:32.009311 | 
2026-02-27 00:34:32.009408 | TASK [upload-logs-failover : Initialize control vars]
2026-02-27 00:34:32.038113 | localhost | ok
2026-02-27 00:34:32.043097 | 
2026-02-27 00:34:32.043171 | LOOP [upload-logs-failover : Perform log upload]
2026-02-27 00:34:32.078177 | localhost | Output suppressed because no_log was given
2026-02-27 00:34:32.086420 | localhost | included: /var/lib/zuul/builds/80a0482d4d654ff4bf59fb36946b2e9d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml
2026-02-27 00:34:32.093785 | 
2026-02-27 00:34:32.093870 | LOOP [upload-logs-failover : Unpack vars for swift upload]
2026-02-27 00:34:32.169845 | localhost | Output suppressed because no_log was given
2026-02-27 00:34:32.175648 | 
2026-02-27 00:34:32.175788 | TASK [Upload logs to swift]
2026-02-27 00:34:32.198328 | localhost | ok
2026-02-27 00:34:32.231335 | 
2026-02-27 00:34:32.231488 | TASK [Set zuul-log-path fact]
2026-02-27 00:34:32.257050 | localhost | skipping: Conditional result was False
2026-02-27 00:34:32.263326 | 
2026-02-27 00:34:32.263471 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-02-27 00:34:32.701883 | localhost -> localhost | ok: Runtime: 0:00:00.005133
2026-02-27 00:34:32.715196 | 
2026-02-27 00:34:32.715390 | TASK [upload-logs-swift : Upload logs to swift]