2026-03-12 00:21:59.245534 | Job console starting...
2026-03-12 00:21:59.258009 | Updating repositories
2026-03-12 00:21:59.400476 | Preparing job workspace
2026-03-12 00:22:05.025705 | Running Ansible setup...
2026-03-12 00:22:12.823944 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-03-12 00:22:13.534418 | 
2026-03-12 00:22:13.534560 | PLAY [localhost]
2026-03-12 00:22:13.544007 | 
2026-03-12 00:22:13.544166 | TASK [Gathering Facts]
2026-03-12 00:22:14.562268 | localhost | ok
2026-03-12 00:22:14.601851 | 
2026-03-12 00:22:14.602135 | TASK [Setup log path fact]
2026-03-12 00:22:14.623746 | localhost | ok
2026-03-12 00:22:14.643259 | 
2026-03-12 00:22:14.643414 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-12 00:22:14.674121 | localhost | ok
2026-03-12 00:22:14.686210 | 
2026-03-12 00:22:14.686370 | TASK [emit-job-header : Print job information]
2026-03-12 00:22:14.728912 | # Job Information
2026-03-12 00:22:14.729208 | Ansible Version: 2.15.12
2026-03-12 00:22:14.729262 | Job: ansible-test-sanity-docker-stable-2.9
2026-03-12 00:22:14.729300 | Pipeline: periodic
2026-03-12 00:22:14.729335 | Executor: ze01.softwarefactory-project.io
2026-03-12 00:22:14.729530 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-03-12 00:22:14.729598 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/07f/ansible/07fd7d7e896145b99f9f5d68ec7d2187/
2026-03-12 00:22:14.729649 | Event ID: 1521915404ba4f519e0765e96d83261c
2026-03-12 00:22:14.735365 | 
2026-03-12 00:22:14.735457 | LOOP [emit-job-header : Print node information]
2026-03-12 00:22:14.895294 | localhost | ok:
2026-03-12 00:22:14.895460 | localhost | # Node Information
2026-03-12 00:22:14.895489 | localhost | Inventory Hostname: controller
2026-03-12 00:22:14.895510 | localhost | Hostname: np0005644956
2026-03-12 00:22:14.895530 | localhost | Username: zuul
2026-03-12 00:22:14.895554 | localhost | Distro: Fedora 37
2026-03-12 00:22:14.895572 | localhost | Provider: ansible-vexxhost-ams1
2026-03-12 00:22:14.895590 | localhost | Region: ams1
2026-03-12 00:22:14.895608 | localhost | Label: ansible-fedora-37-1vcpu
2026-03-12 00:22:14.895624 | localhost | Product Name: OpenStack Nova
2026-03-12 00:22:14.895641 | localhost | Interface IP: 38.129.16.182
2026-03-12 00:22:14.905997 | 
2026-03-12 00:22:14.906136 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-12 00:22:15.319687 | localhost -> localhost | changed
2026-03-12 00:22:15.325560 | 
2026-03-12 00:22:15.325710 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-12 00:22:16.346851 | localhost -> localhost | changed
2026-03-12 00:22:16.396313 | 
2026-03-12 00:22:16.396441 | PLAY [all:!appliance*]
2026-03-12 00:22:16.432374 | 
2026-03-12 00:22:16.432548 | TASK [include_role : start-zuul-console]
2026-03-12 00:22:16.455810 | controller | ok
2026-03-12 00:22:16.471754 | 
2026-03-12 00:22:16.471886 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-03-12 00:22:17.590679 | controller | ok
2026-03-12 00:22:17.617938 | 
2026-03-12 00:22:17.618126 | TASK [use-our-mirror : Retrieve the IP address]
2026-03-12 00:22:20.390824 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-03-12 00:22:20.400907 | 
2026-03-12 00:22:20.401033 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-03-12 00:22:20.774387 | controller | skipping: Conditional result was False
2026-03-12 00:22:20.789509 | 
2026-03-12 00:22:20.789693 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-03-12 00:22:20.808047 | controller | skipping: Conditional result was False
2026-03-12 00:22:20.819291 | 
2026-03-12 00:22:20.819440 | TASK [use-our-mirror : Create the podman configuration directory]
2026-03-12 00:22:20.845532 | controller | skipping: Conditional result was False
2026-03-12 00:22:20.852033 | 
2026-03-12 00:22:20.852150 | TASK [use-our-mirror : Copy the podman configuration]
2026-03-12 00:22:20.876071 | controller | skipping: Conditional result was False
2026-03-12 00:22:20.882908 | 
2026-03-12 00:22:20.883025 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-03-12 00:22:20.898469 | controller | skipping: Conditional result was False
2026-03-12 00:22:20.908382 | 
2026-03-12 00:22:20.908540 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-03-12 00:22:20.924616 | controller | skipping: Conditional result was False
2026-03-12 00:22:20.941186 | 
2026-03-12 00:22:20.941351 | TASK [Disable Fedora Modular]
2026-03-12 00:22:22.132902 | controller | changed
2026-03-12 00:22:22.156720 | 
2026-03-12 00:22:22.156882 | TASK [Enable EPEL]
2026-03-12 00:22:22.182648 | controller | skipping: Conditional result was False
2026-03-12 00:22:22.193384 | 
2026-03-12 00:22:22.193503 | TASK [Register the RHEL node]
2026-03-12 00:22:22.610827 | 
2026-03-12 00:22:22.611020 | TASK [Show the subscription-manager status]
2026-03-12 00:22:22.995986 | controller | skipping: Conditional result was False
2026-03-12 00:22:23.007201 | 
2026-03-12 00:22:23.007386 | TASK [Enable EPEL on RHEL]
2026-03-12 00:22:23.404419 | controller | skipping: Conditional result was False
2026-03-12 00:22:23.421574 | 
2026-03-12 00:22:23.421760 | TASK [Install git and tox]
2026-03-12 00:24:36.662211 | controller | changed
2026-03-12 00:24:36.676049 | 
2026-03-12 00:24:36.676162 | TASK [include_role : prepare-workspace]
2026-03-12 00:24:36.708481 | controller | ok
2026-03-12 00:24:36.741934 | 
2026-03-12 00:24:36.742104 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-12 00:24:37.620202 | controller | ok
2026-03-12 00:24:37.635216 | 
2026-03-12 00:24:37.635407 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-12 00:25:02.869383 | controller | Output suppressed because no_log was given
2026-03-12 00:25:02.879474 | 
2026-03-12 00:25:02.879535 | TASK [include_role : prepare-workspace-openshift]
2026-03-12 00:25:02.908963 | controller | skipping: Conditional result was False
2026-03-12 00:25:02.932951 | 
2026-03-12 00:25:02.933067 | PLAY [all:!appliance]
2026-03-12 00:25:02.949415 | 
2026-03-12 00:25:02.949498 | TASK [Run add-build-sshkey role (RSA)]
2026-03-12 00:25:02.980708 | controller | ok
2026-03-12 00:25:02.996694 | 
2026-03-12 00:25:02.996769 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-12 00:25:03.274481 | controller -> localhost | ok
2026-03-12 00:25:03.281275 | 
2026-03-12 00:25:03.281341 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-12 00:25:03.311961 | controller | ok
2026-03-12 00:25:03.328854 | controller | included: /var/lib/zuul/builds/07fd7d7e896145b99f9f5d68ec7d2187/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-12 00:25:03.335525 | 
2026-03-12 00:25:03.335595 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-12 00:25:03.903255 | controller -> localhost | Generating public/private rsa key pair.
2026-03-12 00:25:03.903558 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/07fd7d7e896145b99f9f5d68ec7d2187/work/07fd7d7e896145b99f9f5d68ec7d2187_id_rsa.
2026-03-12 00:25:03.903610 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/07fd7d7e896145b99f9f5d68ec7d2187/work/07fd7d7e896145b99f9f5d68ec7d2187_id_rsa.pub.
2026-03-12 00:25:03.903651 | controller -> localhost | The key fingerprint is:
2026-03-12 00:25:03.903691 | controller -> localhost | SHA256:PW/fZFqb0VWNQQAu6M8ThixFwYgPL3ViW5QB8i4wCP4 zuul-build-sshkey
2026-03-12 00:25:03.903731 | controller -> localhost | The key's randomart image is:
2026-03-12 00:25:03.903769 | controller -> localhost | +---[RSA 2048]----+
2026-03-12 00:25:03.903807 | controller -> localhost | |. ...==+  ...oo  |
2026-03-12 00:25:03.903846 | controller -> localhost | |+ oo=.=. .     o.|
2026-03-12 00:25:03.903882 | controller -> localhost | |+. *.=o . .   . o|
2026-03-12 00:25:03.903917 | controller -> localhost | | oo.++ . o      .|
2026-03-12 00:25:03.903952 | controller -> localhost | |  .Eo + S o     .|
2026-03-12 00:25:03.904003 | controller -> localhost | |   . . + . o    o|
2026-03-12 00:25:03.904109 | controller -> localhost | |        +   o  .=|
2026-03-12 00:25:03.904151 | controller -> localhost | |         . . . *+|
2026-03-12 00:25:03.904186 | controller -> localhost | |              oo.|
2026-03-12 00:25:03.904226 | controller -> localhost | +----[SHA256]-----+
2026-03-12 00:25:03.904307 | controller -> localhost | ok: Runtime: 0:00:00.084129
2026-03-12 00:25:03.916531 | 
2026-03-12 00:25:03.917084 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-12 00:25:03.944956 | controller | ok
2026-03-12 00:25:03.962617 | controller | included: /var/lib/zuul/builds/07fd7d7e896145b99f9f5d68ec7d2187/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-12 00:25:03.981600 | 
2026-03-12 00:25:03.981761 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-12 00:25:04.006695 | controller | skipping: Conditional result was False
2026-03-12 00:25:04.015555 | 
2026-03-12 00:25:04.015679 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-12 00:25:05.164628 | controller | changed
2026-03-12 00:25:05.177209 | 
2026-03-12 00:25:05.177490 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-12 00:25:05.852474 | controller | ok
2026-03-12 00:25:05.865519 | 
2026-03-12 00:25:05.865656 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-12 00:25:09.045588 | controller | changed
2026-03-12 00:25:09.053908 | 
2026-03-12 00:25:09.054161 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-12 00:25:12.239648 | controller | changed
2026-03-12 00:25:12.249579 | 
2026-03-12 00:25:12.249735 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-12 00:25:12.287415 | controller | skipping: Conditional result was False
2026-03-12 00:25:12.298242 | 
2026-03-12 00:25:12.298356 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-12 00:25:12.787447 | controller -> localhost | changed
2026-03-12 00:25:12.800714 | 
2026-03-12 00:25:12.800835 | TASK [add-build-sshkey : Add back temp key]
2026-03-12 00:25:13.127661 | controller -> localhost | Identity added: /var/lib/zuul/builds/07fd7d7e896145b99f9f5d68ec7d2187/work/07fd7d7e896145b99f9f5d68ec7d2187_id_rsa (zuul-build-sshkey)
2026-03-12 00:25:13.128196 | controller -> localhost | ok: Runtime: 0:00:00.013045
2026-03-12 00:25:13.148509 | 
2026-03-12 00:25:13.148610 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-12 00:25:14.130844 | controller | ok
2026-03-12 00:25:14.143814 | 
2026-03-12 00:25:14.144068 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-12 00:25:14.182076 | controller | skipping: Conditional result was False
2026-03-12 00:25:14.201650 | 
2026-03-12 00:25:14.201812 | TASK [Run add-build-sshkey role (ECDSA)]
2026-03-12 00:25:14.248272 | controller | ok
2026-03-12 00:25:14.273420 | 
2026-03-12 00:25:14.273588 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-12 00:25:14.607034 | controller -> localhost | ok
2026-03-12 00:25:14.614891 | 
2026-03-12 00:25:14.615038 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-12 00:25:14.666645 | controller | ok
2026-03-12 00:25:14.690982 | controller | included: /var/lib/zuul/builds/07fd7d7e896145b99f9f5d68ec7d2187/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-12 00:25:14.699057 | 
2026-03-12 00:25:14.699172 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-12 00:25:15.071609 | controller -> localhost | Generating public/private ecdsa key pair.
2026-03-12 00:25:15.072009 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/07fd7d7e896145b99f9f5d68ec7d2187/work/07fd7d7e896145b99f9f5d68ec7d2187_id_ecdsa.
2026-03-12 00:25:15.072228 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/07fd7d7e896145b99f9f5d68ec7d2187/work/07fd7d7e896145b99f9f5d68ec7d2187_id_ecdsa.pub.
2026-03-12 00:25:15.072312 | controller -> localhost | The key fingerprint is:
2026-03-12 00:25:15.072370 | controller -> localhost | SHA256:ndMCmSCbJZ7zmQvViptgkVNq6EvKzdUXsmXkKRpx7/Q zuul-build-sshkey
2026-03-12 00:25:15.072423 | controller -> localhost | The key's randomart image is:
2026-03-12 00:25:15.072476 | controller -> localhost | +---[ECDSA 521]---+
2026-03-12 00:25:15.072528 | controller -> localhost | |    +.o. .       |
2026-03-12 00:25:15.072579 | controller -> localhost | | . = Boo+o.      |
2026-03-12 00:25:15.072628 | controller -> localhost | |. * *..o=O       |
2026-03-12 00:25:15.072678 | controller -> localhost | |.. o =++Oooo     |
2026-03-12 00:25:15.072727 | controller -> localhost | | oo oo=oSo=E.    |
2026-03-12 00:25:15.072777 | controller -> localhost | |+.+..+ ..  o     |
2026-03-12 00:25:15.072826 | controller -> localhost | |.o oo .          |
2026-03-12 00:25:15.072875 | controller -> localhost | |                 |
2026-03-12 00:25:15.072924 | controller -> localhost | |                 |
2026-03-12 00:25:15.072974 | controller -> localhost | +----[SHA256]-----+
2026-03-12 00:25:15.073175 | controller -> localhost | ok: Runtime: 0:00:00.014809
2026-03-12 00:25:15.092621 | 
2026-03-12 00:25:15.092870 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-12 00:25:15.137359 | controller | ok
2026-03-12 00:25:15.149347 | controller | included: /var/lib/zuul/builds/07fd7d7e896145b99f9f5d68ec7d2187/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-12 00:25:15.163087 | 
2026-03-12 00:25:15.163251 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-12 00:25:15.200259 | controller | skipping: Conditional result was False
2026-03-12 00:25:15.209793 | 
2026-03-12 00:25:15.209890 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-12 00:25:16.185976 | controller | changed
2026-03-12 00:25:16.201168 | 
2026-03-12 00:25:16.201317 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-12 00:25:16.921416 | controller | ok
2026-03-12 00:25:16.937319 | 
2026-03-12 00:25:16.937626 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-12 00:25:20.177414 | controller | changed
2026-03-12 00:25:20.191697 | 
2026-03-12 00:25:20.191846 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-12 00:25:23.409991 | controller | changed
2026-03-12 00:25:23.416712 | 
2026-03-12 00:25:23.416799 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-12 00:25:23.453768 | controller | skipping: Conditional result was False
2026-03-12 00:25:23.468480 | 
2026-03-12 00:25:23.468708 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-12 00:25:23.751741 | controller -> localhost | changed
2026-03-12 00:25:23.765786 | 
2026-03-12 00:25:23.765959 | TASK [add-build-sshkey : Add back temp key]
2026-03-12 00:25:24.090517 | controller -> localhost | Identity added: /var/lib/zuul/builds/07fd7d7e896145b99f9f5d68ec7d2187/work/07fd7d7e896145b99f9f5d68ec7d2187_id_ecdsa (zuul-build-sshkey)
2026-03-12 00:25:24.090753 | controller -> localhost | ok: Runtime: 0:00:00.009372
2026-03-12 00:25:24.099271 | 
2026-03-12 00:25:24.099391 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-12 00:25:24.855974 | controller | ok
2026-03-12 00:25:24.861927 | 
2026-03-12 00:25:24.862217 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-12 00:25:24.887217 | controller | skipping: Conditional result was False
2026-03-12 00:25:24.906072 | 
2026-03-12 00:25:24.906197 | TASK [include_role : remove-zuul-sshkey]
2026-03-12 00:25:24.943140 | controller | skipping: Conditional result was False
2026-03-12 00:25:24.951481 | 
2026-03-12 00:25:24.951591 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-12 00:25:25.705380 | controller | ok: "logs"
2026-03-12 00:25:25.706259 | controller | ok: All items complete
2026-03-12 00:25:25.706361 | 
2026-03-12 00:25:26.409780 | controller | ok: "artifacts"
2026-03-12 00:25:27.051994 | controller | ok: "docs"
2026-03-12 00:25:27.075123 | 
2026-03-12 00:25:27.075487 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-12 00:25:27.895152 | controller | changed: "logs"
2026-03-12 00:25:28.477182 | controller | changed: "artifacts"
2026-03-12 00:25:29.176488 | controller | changed: "docs"
2026-03-12 00:25:29.236683 | 
2026-03-12 00:25:29.236900 | PLAY RECAP
2026-03-12 00:25:29.236970 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-03-12 00:25:29.237010 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-12 00:25:29.237103 | 
2026-03-12 00:25:29.413618 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-03-12 00:25:29.414730 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-12 00:25:30.007377 | 
2026-03-12 00:25:30.007504 | PLAY [all]
2026-03-12 00:25:30.030289 | 
2026-03-12 00:25:30.030424 | TASK [Install binary dependencies]
2026-03-12 00:25:30.111052 | controller | ok
2026-03-12 00:25:30.131548 | 
2026-03-12 00:25:30.131651 | TASK [bindep : Include find tasks]
2026-03-12 00:25:30.162117 | controller | ok
2026-03-12 00:25:30.171128 | controller | included: /var/lib/zuul/builds/07fd7d7e896145b99f9f5d68ec7d2187/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-03-12 00:25:30.178128 | 
2026-03-12 00:25:30.178199 | TASK [bindep : Look for bindep.txt]
2026-03-12 00:25:31.151129 | controller | ok
2026-03-12 00:25:31.159908 | 
2026-03-12 00:25:31.160052 | TASK [bindep : Define bindep_file fact]
2026-03-12 00:25:31.185553 | controller | skipping: Conditional result was False
2026-03-12 00:25:31.195831 | 
2026-03-12 00:25:31.195979 | TASK [bindep : Look for other-requirements.txt]
2026-03-12 00:25:31.848071 | controller | ok
2026-03-12 00:25:31.856886 | 
2026-03-12 00:25:31.857093 | TASK [bindep : Define bindep_file fact]
2026-03-12 00:25:31.893545 | controller | skipping: Conditional result was False
2026-03-12 00:25:31.901746 | 
2026-03-12 00:25:31.901900 | TASK [bindep : Look for bindep fallback file]
2026-03-12 00:25:31.937860 | controller | skipping: Conditional result was False
2026-03-12 00:25:31.946969 | 
2026-03-12 00:25:31.947162 | TASK [bindep : Define bindep_file fact]
2026-03-12 00:25:31.983346 | controller | skipping: Conditional result was False
2026-03-12 00:25:31.993152 | 
2026-03-12 00:25:31.993307 | TASK [bindep : Include bindep tasks]
2026-03-12 00:25:32.029575 | controller | skipping: Conditional result was False
2026-03-12 00:25:32.040216 | 
2026-03-12 00:25:32.040391 | TASK [bindep : Include install tasks]
2026-03-12 00:25:32.076558 | controller | skipping: Conditional result was False
2026-03-12 00:25:32.089454 | 
2026-03-12 00:25:32.089628 | LOOP [bindep : Include package tasks]
2026-03-12 00:25:32.175093 | 
2026-03-12 00:25:32.175330 | TASK [Run test-setup role]
2026-03-12 00:25:32.201416 | controller | ok
2026-03-12 00:25:32.232953 | 
2026-03-12 00:25:32.233155 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-03-12 00:25:32.945131 | controller | ok
2026-03-12 00:25:32.957142 | 
2026-03-12 00:25:32.957310 | TASK [test-setup : Run tools/test-setup.sh]
2026-03-12 00:25:33.345817 | controller | skipping: Conditional result was False
2026-03-12 00:25:33.404649 | 
2026-03-12 00:25:33.404786 | PLAY RECAP
2026-03-12 00:25:33.404856 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-12 00:25:33.404889 | 
2026-03-12 00:25:33.575997 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-12 00:25:33.576886 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-03-12 00:25:34.333398 | 
2026-03-12 00:25:34.333583 | PLAY [controller]
2026-03-12 00:25:34.360396 | 
2026-03-12 00:25:34.360598 | TASK [Create the /root directory]
2026-03-12 00:25:35.491813 | controller | ok
2026-03-12 00:25:35.507553 | 
2026-03-12 00:25:35.507885 | TASK [Install glibc-langpack-en]
2026-03-12 00:25:43.738718 | controller | ok: Nothing to do
2026-03-12 00:25:43.747083 | 
2026-03-12 00:25:43.747192 | TASK [Ensure controller directory exists]
2026-03-12 00:25:44.627525 | controller | changed
2026-03-12 00:25:44.636236 | 
2026-03-12 00:25:44.636393 | TASK [Install container runtime]
2026-03-12 00:25:44.706677 | controller | ok
2026-03-12 00:25:44.779245 | 
2026-03-12 00:25:44.779479 | LOOP [ensure-docker : Gather variables for each operating system]
2026-03-12 00:25:44.824342 | controller | ok: "/var/lib/zuul/builds/07fd7d7e896145b99f9f5d68ec7d2187/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2026-03-12 00:25:44.847183 | 
2026-03-12 00:25:44.847362 | TASK [ensure-docker : Sanity check for distro selection]
2026-03-12 00:25:44.864727 | controller | skipping: Conditional result was False
2026-03-12 00:25:44.881192 | 
2026-03-12 00:25:44.881371 | TASK [ensure-docker : Sanity check for distro install]
2026-03-12 00:25:44.897789 | controller | skipping: Conditional result was False
2026-03-12 00:25:44.908733 | 
2026-03-12 00:25:44.908891 | TASK [ensure-docker : Sanity check for upstream install]
2026-03-12 00:25:44.934857 | controller | skipping: Conditional result was False
2026-03-12 00:25:44.945125 | 
2026-03-12 00:25:44.945246 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2026-03-12 00:25:44.971231 | controller | skipping: Conditional result was False
2026-03-12 00:25:44.978120 | 
2026-03-12 00:25:44.978189 | TASK [ensure-docker : Include tasks for docker install]
2026-03-12 00:25:44.998968 | controller | ok
2026-03-12 00:25:45.008448 | controller | included: /var/lib/zuul/builds/07fd7d7e896145b99f9f5d68ec7d2187/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2026-03-12 00:25:45.017884 | 
2026-03-12 00:25:45.017969 | TASK [ensure-docker : Not Implemented]
2026-03-12 00:25:45.041964 | controller | skipping: Conditional result was False
2026-03-12 00:25:45.048302 | 
2026-03-12 00:25:45.048362 | TASK [Set up docker mirrors]
2026-03-12 00:25:45.067753 | controller | ok
2026-03-12 00:25:45.087244 | 
2026-03-12 00:25:45.087314 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2026-03-12 00:25:45.121464 | controller | skipping: Conditional result was False
2026-03-12 00:25:45.128198 | 
2026-03-12 00:25:45.128263 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2026-03-12 00:25:45.162277 | controller | skipping: Conditional result was False
2026-03-12 00:25:45.197270 | 
2026-03-12 00:25:45.197414 | TASK [use-docker-mirror : Set docker_mirror fact]
2026-03-12 00:25:45.231836 | controller | skipping: Conditional result was False
2026-03-12 00:25:45.243825 | 
2026-03-12 00:25:45.243957 | TASK [use-docker-mirror : Set up docker mirrors]
2026-03-12 00:25:45.268122 | controller | skipping: Conditional result was False
2026-03-12 00:25:45.279622 | 
2026-03-12 00:25:45.279688 | TASK [Add all repositories]
2026-03-12 00:25:45.299028 | controller | ok
2026-03-12 00:25:45.324543 | 
2026-03-12 00:25:45.324665 | LOOP [ensure-package-repositories : Install repositories]
2026-03-12 00:25:45.347306 | controller | ok: "/var/lib/zuul/builds/07fd7d7e896145b99f9f5d68ec7d2187/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2026-03-12 00:25:45.347523 | controller | ok: All items complete
2026-03-12 00:25:45.347551 | 
2026-03-12 00:25:45.357123 | controller | included: /var/lib/zuul/builds/07fd7d7e896145b99f9f5d68ec7d2187/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2026-03-12 00:25:45.363493 | 
2026-03-12 00:25:45.363554 | TASK [ensure-package-repositories : Create GPG key temp directory]
2026-03-12 00:25:46.278532 | controller | changed
2026-03-12 00:25:46.293941 | 
2026-03-12 00:25:46.294212 | LOOP [ensure-package-repositories : Upload GPG keys]
2026-03-12 00:25:49.668864 | controller | changed:
2026-03-12 00:25:49.669185 | controller | {
2026-03-12 00:25:49.669246 | 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-03-12 00:25:49.669295 | controller | }
2026-03-12 00:25:49.686396 | 
2026-03-12 00:25:49.686597 | LOOP [ensure-package-repositories : Import GPG keys]
2026-03-12 00:25:50.996431 | controller | changed:
2026-03-12 00:25:50.996770 | controller | {
2026-03-12 00:25:50.996832 | 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-03-12 00:25:50.996882 | controller | }
2026-03-12 00:25:50.996942 | controller | changed: All items complete
2026-03-12 00:25:50.996983 | 
2026-03-12 00:25:51.016183 | 
2026-03-12 00:25:51.016332 | TASK [ensure-package-repositories : Remove GPG temp directory]
2026-03-12 00:25:51.717327 | controller | changed
2026-03-12 00:25:51.730201 | 
2026-03-12 00:25:51.730345 | LOOP [ensure-package-repositories : Add repositories]
2026-03-12 00:25:52.619952 | controller | changed:
2026-03-12 00:25:52.620394 | controller | {
2026-03-12 00:25:52.620420 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2026-03-12 00:25:52.620440 | controller |   "description": "Docker CE Stable - $basearch",
2026-03-12 00:25:52.620457 | controller |   "gpgcheck": true,
2026-03-12 00:25:52.620474 | controller |   "name": "docker-ce-stable"
2026-03-12 00:25:52.620491 | controller | }
2026-03-12 00:25:52.653759 | 
2026-03-12 00:25:52.653969 | TASK [ensure-docker : Install pre-reqs]
2026-03-12 00:25:57.859387 | controller | ok: Nothing to do
2026-03-12 00:25:57.872242 | 
2026-03-12 00:25:57.872420 | TASK [ensure-docker : Remove packages]
2026-03-12 00:26:01.911241 | controller | ok: Nothing to do
2026-03-12 00:26:01.925174 | 
2026-03-12 00:26:01.925375 | TASK [ensure-docker : Install upstream docker using package]
2026-03-12 00:27:25.000857 | controller | changed
2026-03-12 00:27:25.016733 | 
2026-03-12 00:27:25.016897 | TASK [ensure-docker : Setup docker]
2026-03-12 00:27:25.043679 | controller | ok
2026-03-12 00:27:25.058907 | controller | included: /var/lib/zuul/builds/07fd7d7e896145b99f9f5d68ec7d2187/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2026-03-12 00:27:25.075171 | 
2026-03-12 00:27:25.075269 | TASK [ensure-docker : Ensure "docker" group exists]
2026-03-12 00:27:26.160742 | controller | ok
2026-03-12 00:27:26.166914 | 
2026-03-12 00:27:26.166994 | TASK [ensure-docker : Add user to docker group]
2026-03-12 00:27:27.309957 | controller | changed
2026-03-12 00:27:27.324994 | 
2026-03-12 00:27:27.325215 | TASK [Update docker daemon proxy configuration]
2026-03-12 00:27:27.363410 | controller | skipping: Conditional result was False
2026-03-12 00:27:27.372893 | 
2026-03-12 00:27:27.373095 | TASK [Lower default MTU]
2026-03-12 00:27:27.400694 | controller | skipping: Conditional result was False
2026-03-12 00:27:27.410662 | 
2026-03-12 00:27:27.410800 | TASK [ensure-docker : Get the status of the docket.socket unit]
2026-03-12 00:27:28.329857 | controller | inactive
2026-03-12 00:27:28.803277 | controller | ok: Runtime: 0:00:00.020805
2026-03-12 00:27:28.811056 | 
2026-03-12 00:27:28.811147 | TASK [ensure-docker : Get the status of the docket unit]
2026-03-12 00:27:29.626848 | controller | inactive
2026-03-12 00:27:30.194847 | controller | ok: Runtime: 0:00:00.024873
2026-03-12 00:27:30.207076 | 
2026-03-12 00:27:30.207344 | TASK [ensure-docker : Restart docker]
2026-03-12 00:27:30.270131 | Trigger a Docker restart
2026-03-12 00:27:30.276890 | 
2026-03-12 00:27:30.276959 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2026-03-12 00:27:30.317209 | 
2026-03-12 00:27:30.317317 | TASK [ensure-docker : Install docker-compose]
2026-03-12 00:27:30.342837 | controller | skipping: Conditional result was False
2026-03-12 00:27:30.350226 | 
2026-03-12 00:27:30.350296 | TASK [ensure-docker : Flush handlers before role exit]
2026-03-12 00:27:30.362328 | 
2026-03-12 00:27:30.362398 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2026-03-12 00:27:34.157542 | controller | changed
2026-03-12 00:27:34.176901 | 
2026-03-12 00:27:34.177113 | TASK [ensure-docker : Assure docker service is running]
2026-03-12 00:27:38.622329 | controller | changed
2026-03-12 00:27:38.641578 | 
2026-03-12 00:27:38.641797 | TASK [ensure-docker : Assure docker.socket service is running]
2026-03-12 00:27:40.029657 | controller | ok
2026-03-12 00:27:40.037732 | 
2026-03-12 00:27:40.037828 | TASK [ensure-docker : Correct group ownership on docker sock]
2026-03-12 00:27:40.826567 | controller | ok
2026-03-12 00:27:40.843813 | 
2026-03-12 00:27:40.843989 | TASK [ensure-docker : Validate ability to talk with docker]
2026-03-12 00:27:41.555886 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2026-03-12 00:27:41.731158 | controller | ok: Runtime: 0:00:00.062383
2026-03-12 00:27:41.737487 | 
2026-03-12 00:27:41.737562 | TASK [ensure-docker : Get version details]
2026-03-12 00:27:42.456001 | controller | Client: Docker Engine - Community
2026-03-12 00:27:42.456109 | controller |  Version:           24.0.7
2026-03-12 00:27:42.456224 | controller |  API version:       1.43
2026-03-12 00:27:42.456236 | controller |  Go version:        go1.20.10
2026-03-12 00:27:42.456256 | controller |  Git commit:        afdd53b
2026-03-12 00:27:42.456264 | controller |  Built:             Thu Oct 26 09:09:39 2023
2026-03-12 00:27:42.456271 | controller |  OS/Arch:           linux/amd64
2026-03-12 00:27:42.456280 | controller |  Context:           default
2026-03-12 00:27:42.456289 | controller |
2026-03-12 00:27:42.456296 | controller | Server: Docker Engine - Community
2026-03-12 00:27:42.456302 | controller |  Engine:
2026-03-12 00:27:42.456311 | controller |   Version:          24.0.7
2026-03-12 00:27:42.456332 | controller |   API version:      1.43 (minimum version 1.12)
2026-03-12 00:27:42.456340 | controller |   Go version:       go1.20.10
2026-03-12 00:27:42.456347 | controller |   Git commit:       311b9ff
2026-03-12 00:27:42.456353 | controller |   Built:            Thu Oct 26 09:08:11 2023
2026-03-12 00:27:42.456359 | controller |   OS/Arch:          linux/amd64
2026-03-12 00:27:42.456366 | controller |   Experimental:     false
2026-03-12 00:27:42.456372 | controller |  containerd:
2026-03-12 00:27:42.456380 | controller |   Version:          1.6.25
2026-03-12 00:27:42.456386 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2026-03-12 00:27:42.456392 | controller |  runc:
2026-03-12 00:27:42.456398 | controller |   Version:          1.1.10
2026-03-12 00:27:42.456404 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2026-03-12 00:27:42.456413 | controller |  docker-init:
2026-03-12 00:27:42.456421 | controller |   Version:          0.19.0
2026-03-12 00:27:42.458389 | controller |   GitCommit:        de40ad0
2026-03-12 00:27:42.651074 | controller | ok: Runtime: 0:00:00.061563
2026-03-12 00:27:42.666430 | 
2026-03-12 00:27:42.666584 | TASK [ensure-docker : Dump installed docker details]
2026-03-12 00:27:42.714859 | {'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-03-12 00:27:42.397340', 'end': '2026-03-12 00:27:42.458903', 'delta': '0:00:00.061563', 'msg': '', 'zuul_log_id': 'fa163ec2-ffbe-084e-60a9-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-03-12 00:27:42.724872 | 
2026-03-12 00:27:42.724976 | TASK [Use buildset registry]
2026-03-12 00:27:42.754402 | controller | skipping: Conditional result was False
2026-03-12 00:27:42.772219 | 
2026-03-12 00:27:42.772385 | TASK [Ensure python3.8 is present]
2026-03-12 00:27:42.799495 | controller | skipping: Conditional result was False
2026-03-12 00:27:42.808765 | 
2026-03-12 00:27:42.808885 | TASK [Run our-ensure-python role (Fedora only for now)]
2026-03-12 00:27:42.832898 | controller | ok
2026-03-12 00:27:42.861108 | 
2026-03-12 00:27:42.861278 | TASK [our-ensure-python : Install the right Python version (rpm)]
2026-03-12 00:27:49.853325 | controller | ok: Nothing to do
2026-03-12 00:27:49.869966 | 
2026-03-12 00:27:49.870223 | TASK [our-ensure-python : Also install python3-devel]
2026-03-12 00:28:06.254439 | controller | changed
2026-03-12 00:28:06.266884 | 
2026-03-12 00:28:06.267007 | TASK [Run ensure-virtualenv role]
2026-03-12 00:28:06.289181 | controller | ok
2026-03-12 00:28:06.314272 | 
2026-03-12 00:28:06.314421 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2026-03-12 00:28:07.103256 | controller | /usr/bin/virtualenv
2026-03-12 00:28:07.690812 | controller | ok: Runtime: 0:00:00.007685
2026-03-12 00:28:07.699128 | 
2026-03-12 00:28:07.699237 | LOOP [ensure-virtualenv : Install virtualenv package]
2026-03-12 00:28:07.722476 | controller | skipping: Conditional result was False
2026-03-12 00:28:07.722790 | controller | changed: All items complete
2026-03-12 00:28:07.722819 | 
2026-03-12 00:28:07.739570 | 
2026-03-12 00:28:07.739731 | TASK [Find the full path of the Python interpreter]
2026-03-12 00:28:08.497915 | controller | /usr/bin/python3.8
2026-03-12 00:28:09.112908 | controller | ok
2026-03-12 00:28:09.121996 | 
2026-03-12 00:28:09.122154 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2026-03-12 00:28:11.455757 | controller | created virtual environment CPython3.8.18.final.0-64 in 1152ms
2026-03-12 00:28:11.543808 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2026-03-12 00:28:11.543883 | 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-03-12 00:28:11.543899 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2026-03-12 00:28:11.543924 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2026-03-12 00:28:12.005458 | controller | changed
2026-03-12 00:28:12.085527 | 
2026-03-12 00:28:12.085763 | TASK [Set selinux package]
2026-03-12 00:28:12.118369 | controller | ok
2026-03-12 00:28:12.123955 | 
2026-03-12 00:28:12.124046 | TASK [Set selinux package (Fedora)]
2026-03-12 00:28:12.177183 | controller | ok
2026-03-12 00:28:12.183135 | 
2026-03-12 00:28:12.183228 | TASK [Install selinux into virtualenv]
2026-03-12 00:28:19.167773 | controller | Collecting selinux-please-lie-to-me
2026-03-12 00:28:19.327474 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2026-03-12 00:28:20.007866 | controller | Collecting setuptools<50.0.0
2026-03-12 00:28:20.016479 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2026-03-12 00:28:20.078198 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 14.6 MB/s eta 0:00:00
2026-03-12 00:28:20.229228 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2026-03-12 00:28:20.229603 | controller |   Attempting uninstall: setuptools
2026-03-12 00:28:20.233750 | controller |     Found existing installation: setuptools 62.6.0
2026-03-12 00:28:20.375807 | controller |     Uninstalling setuptools-62.6.0:
2026-03-12 00:28:20.392800 | controller |       Successfully uninstalled setuptools-62.6.0
2026-03-12 00:28:21.191135 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2026-03-12 00:28:21.404941 | controller |
2026-03-12 00:28:21.787821 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-03-12 00:28:21.787905 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-03-12 00:28:22.088865 | controller | ok: Runtime: 0:00:08.481311
2026-03-12 00:28:22.102816 | 
2026-03-12 00:28:22.103065 | TASK [Install pytest-forked into virtualenv]
2026-03-12 00:28:23.699342 | controller | Collecting pytest-forked
2026-03-12 00:28:23.762238 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2026-03-12 00:28:23.838448 | controller | Collecting py
2026-03-12 00:28:23.844215 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2026-03-12 00:28:23.878344 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 3.0 MB/s eta 0:00:00
2026-03-12 00:28:24.075695 | controller | Collecting pytest>=3.10
2026-03-12 00:28:24.081424 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2026-03-12 00:28:24.101212 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 22.9 MB/s eta 0:00:00
2026-03-12 00:28:24.173805 | controller | Collecting exceptiongroup>=1.0.0rc8
2026-03-12 00:28:24.179967 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2026-03-12 00:28:24.272965 | controller | Collecting tomli>=1
2026-03-12 00:28:24.279794 | controller |   Downloading tomli-2.4.0-py3-none-any.whl (14 kB)
2026-03-12 00:28:24.361057 | controller | Collecting packaging
2026-03-12 00:28:24.367874 | controller |   Downloading packaging-26.0-py3-none-any.whl (74 kB)
2026-03-12 00:28:24.380623 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 7.1 MB/s eta 0:00:00
2026-03-12 00:28:24.431288 | controller | Collecting pluggy<2,>=1.5
2026-03-12 00:28:24.437314 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2026-03-12 00:28:24.473419 | controller | Collecting iniconfig
2026-03-12 00:28:24.479247 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2026-03-12 00:28:24.558197 | controller | Collecting typing-extensions>=4.6.0
2026-03-12 00:28:24.567122 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2026-03-12 00:28:24.575641 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 8.0 MB/s eta 0:00:00
2026-03-12 00:28:24.680510 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2026-03-12 00:28:25.591031 | 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-03-12 00:28:25.596636 | controller |
2026-03-12 00:28:25.895266 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-03-12 00:28:25.895335 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-03-12 00:28:26.504078 | controller | ok: Runtime: 0:00:02.964470
2026-03-12 00:28:26.513561 | 
2026-03-12 00:28:26.513674 | TASK [Update pip]
2026-03-12 00:28:28.924639 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2026-03-12 00:28:29.396049 | controller | Collecting pip
2026-03-12 00:28:29.535429 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2026-03-12 00:28:29.701758 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 12.2 MB/s eta 0:00:00
2026-03-12 00:28:29.956946 | controller | Installing collected packages: pip
2026-03-12 00:28:29.957320 | controller |   Attempting uninstall: pip
2026-03-12 00:28:29.958420 | controller |     Found existing installation: pip 22.2.2
2026-03-12 00:28:30.487337 | controller |     Uninstalling pip-22.2.2:
2026-03-12 00:28:30.567369 | controller |       Successfully uninstalled pip-22.2.2
2026-03-12 00:28:33.304000 | controller | Successfully installed pip-25.0.1
2026-03-12 00:28:33.901559 | controller | ok: Runtime: 0:00:06.007988
2026-03-12 00:28:33.916897 | 
2026-03-12 00:28:33.917156 | TASK [Ensure pip temp directory exists in /var/tmp]
2026-03-12 00:28:35.514935 | controller | changed
2026-03-12 00:28:35.528897 | 
2026-03-12 00:28:35.529125 | TASK [Install ansible into virtualenv]
2026-03-12 00:28:37.059301 | controller | Processing ./src/github.com/ansible/ansible
2026-03-12 00:28:37.061002 | controller |   Preparing metadata (setup.py): started
2026-03-12 00:28:38.893804 | controller |   Preparing metadata (setup.py): finished with status 'done'
2026-03-12 00:28:38.968749 | controller | Collecting jinja2 (from ansible==2.9.27.post0)
2026-03-12 00:28:38.990056 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2026-03-12 00:28:39.078671 | controller | Collecting PyYAML (from ansible==2.9.27.post0)
2026-03-12 00:28:39.086526 | 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-03-12 00:28:39.599367 | controller | Collecting cryptography (from ansible==2.9.27.post0)
2026-03-12 00:28:39.604981 | controller |   Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2026-03-12 00:28:39.917405 | controller | Collecting cffi>=1.14 (from cryptography->ansible==2.9.27.post0)
2026-03-12 00:28:39.924376 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2026-03-12 00:28:39.944368 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.8/site-packages (from cryptography->ansible==2.9.27.post0) (4.13.2)
2026-03-12 00:28:40.078292 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible==2.9.27.post0)
2026-03-12 00:28:40.084000 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2026-03-12 00:28:40.117068 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible==2.9.27.post0)
2026-03-12 00:28:40.125597 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2026-03-12 00:28:40.151041 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2026-03-12 00:28:40.222173 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 75.5 MB/s eta 0:00:00
2026-03-12 00:28:40.228528 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2026-03-12 00:28:40.251771 | 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-03-12 00:28:40.283486 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 26.0 MB/s eta 0:00:00
2026-03-12 00:28:40.291145 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2026-03-12 00:28:40.317263 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2026-03-12 00:28:40.335525 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2026-03-12 00:28:40.374168 | controller | Building wheels for collected packages: ansible
2026-03-12 00:28:40.375977 | controller |   Building wheel for ansible (setup.py): started
2026-03-12 00:28:50.862103 | controller |   Building wheel for ansible (setup.py): finished with status 'done'
2026-03-12 00:28:50.881530 | controller |   Created wheel for ansible: filename=ansible-2.9.27.post0-py3-none-any.whl size=16207514 sha256=c20e421db5691ca8ab9a1a0602e71e2dd2c6cfb14205c6a1a66a9fcccd28e85a
2026-03-12 00:28:50.881799 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-emqo7rdc/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2026-03-12 00:28:50.969614 | controller | Successfully built ansible
2026-03-12 00:28:51.182227 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible
2026-03-12 00:29:06.980387 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-2.9.27.post0 cffi-1.17.1 cryptography-46.0.5 jinja2-3.1.6 pycparser-2.23
2026-03-12 00:29:07.458680 | controller | ok: Runtime: 0:00:30.989456
2026-03-12 00:29:07.470266 | 
2026-03-12 00:29:07.470395 | TASK [Setup download-artifact-fork role]
2026-03-12 00:29:07.493894 | controller | ok
2026-03-12 00:29:07.515308 | 
2026-03-12 00:29:07.515481 | TASK [download-artifact-fork : Parse inventory response]
2026-03-12 00:29:07.567508 | controller | ok
2026-03-12 00:29:07.577058 | 
2026-03-12 00:29:07.577208 | TASK [download-artifact-fork : Fetch info from Zuul API]
2026-03-12 00:29:07.614741 | controller | skipping: Conditional result was False
2026-03-12 00:29:07.624443 | 
2026-03-12 00:29:07.624593 | TASK [download-artifact-fork : Parse build response]
2026-03-12 00:29:07.663268 | controller | skipping: Conditional result was False
2026-03-12 00:29:07.678196 | 
2026-03-12 00:29:07.678419 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2026-03-12 00:29:08.614625 | controller | changed
2026-03-12 00:29:08.624395 | 
2026-03-12 00:29:08.624567 | LOOP [download-artifact-fork : Download archive by type]
2026-03-12 00:29:08.653006 | controller | skipping: Conditional result was False
2026-03-12 00:29:08.653466 | 
2026-03-12 00:29:11.412720 | controller | changed: OK (20196 bytes)
2026-03-12 00:29:11.511430 | 
2026-03-12 00:29:11.511616 | TASK [deploy-artifacts : define __collections]
2026-03-12 00:29:11.537368 | controller | ok
2026-03-12 00:29:11.546852 | 
2026-03-12 00:29:11.547111 | LOOP [deploy-artifacts : Create list of collections]
2026-03-12 00:29:11.584465 | controller | skipping: Conditional result was False
2026-03-12 00:29:11.607636 | controller | ok:
2026-03-12 00:29:11.607801 | controller | {
2026-03-12 00:29:11.607835 | controller |   "branch": "main",
2026-03-12 00:29:11.607861 | controller |   "job": "build-ansible-collection",
2026-03-12 00:29:11.607888 | controller |   "metadata": {
2026-03-12 00:29:11.607917 | controller |     "type": "ansible_collection",
2026-03-12 00:29:11.607970 | controller |     "version": "5.0.0"
2026-03-12 00:29:11.607996 | controller |   },
2026-03-12 00:29:11.608045 | controller |   "name": "ansible.network",
2026-03-12 00:29:11.608072 | controller |   "project": "ansible-collections/ansible.network",
2026-03-12 00:29:11.608096 | controller |   "url": "https://afe1c585d935f01ee6d5-204e96fac6dda80bfd8cb31ec724050b.ssl.cf2.rackcdn.com/ansible/e39929fb5a3f40269a7f30b1f9d2f7bb/artifacts/ansible-network-5.0.0.tar.gz"
2026-03-12 00:29:11.608128 | controller | }
2026-03-12 00:29:11.624685 | 
2026-03-12 00:29:11.624884 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2026-03-12 00:29:12.885121 | controller | Process install dependency map
2026-03-12 00:30:05.608559 | controller | |/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/Starting collection install process
2026-03-12 00:30:05.709456 | controller | |Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2026-03-12 00:30:05.709623 | controller | Installing 'ansible.netcommon:8.4.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-03-12 00:30:07.521428 | controller | /-\|/-\|/-\|/-\|/-Installing 'ansible.utils:6.0.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-03-12 00:30:09.833620 | controller | \|/-\|/-\|/-\|/-\|/-\|/Installing 'arista.eos:12.0.1' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2026-03-12 00:30:12.903632 | controller | -\|/-\|/-\|/-\|/-\|/-\|/-\|/-\Installing 'cisco.ios:11.3.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2026-03-12 00:30:15.725267 | controller | |/-\|/-\|/-\|/-\|/-\|/-\|/-\Installing 'cisco.iosxr:12.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2026-03-12 00:30:18.882254 | controller | |/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-Installing 'cisco.nxos:11.1.3' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2026-03-12 00:30:23.534225 | controller | \|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|Installing 'junipernetworks.junos:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2026-03-12 00:30:26.047370 | controller | /-\|/-\|/-\|/-\|/-\|/-\|/Installing 'juniper.device:2.0.1' to '/home/zuul/.ansible/collections/ansible_collections/juniper/device'
2026-03-12 00:30:28.559906 | controller | ok: Runtime: 0:01:15.948497
2026-03-12 00:30:28.585596 | 
2026-03-12 00:30:28.585785 | TASK [podman may need iptables kernel modules]
2026-03-12 00:30:29.972107 | controller | ok: Runtime: 0:00:00.013593
2026-03-12 00:30:30.006960 | 
2026-03-12 00:30:30.007129 | PLAY RECAP
2026-03-12 00:30:30.007190 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2026-03-12 00:30:30.007219 | 
2026-03-12 00:30:30.188042 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-03-12 00:30:30.189175 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-03-12 00:30:30.907201 | 
2026-03-12 00:30:30.907359 | PLAY [controller]
2026-03-12 00:30:30.931689 | 
2026-03-12 00:30:30.931887 | TASK [Copy the galaxy.yml on the controller]
2026-03-12 00:30:34.834065 | controller | changed
2026-03-12 00:30:34.841505 | 
2026-03-12 00:30:34.841835 | TASK [Load information from galaxy.yml]
2026-03-12 00:30:34.873898 | controller | ok
2026-03-12 00:30:34.880498 | 
2026-03-12 00:30:34.880615 | LOOP [Enable FIPS mode]
2026-03-12 00:30:34.914468 | 
2026-03-12 00:30:34.914668 | LOOP [ansible-test : Include branch specific variables.]
2026-03-12 00:30:34.954842 | controller | ok: "/var/lib/zuul/builds/07fd7d7e896145b99f9f5d68ec7d2187/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2026-03-12 00:30:34.955040 | 
2026-03-12 00:30:34.968008 | 
2026-03-12 00:30:34.968174 | TASK [ansible-test : Setup --skip-tags for test_options]
2026-03-12 00:30:35.017947 | controller | ok
2026-03-12 00:30:35.029003 | 
2026-03-12 00:30:35.029190 | TASK [ansible-test : Enable --inventory for network-integration]
2026-03-12 00:30:35.055882 | controller | skipping: Conditional result was False
2026-03-12 00:30:35.068402 | 
2026-03-12 00:30:35.068572 | TASK [ansible-test : Enable --retry-on-error]
2026-03-12 00:30:35.094548 | controller | skipping: Conditional result was False
2026-03-12 00:30:35.106384 | 
2026-03-12 00:30:35.106576 | TASK [ansible-test : Enable --continue-on-error]
2026-03-12 00:30:35.159637 | controller | ok
2026-03-12 00:30:35.165656 | 
2026-03-12 00:30:35.165734 | TASK [ansible-test : Enable --requirements]
2026-03-12 00:30:35.191794 | controller | skipping: Conditional result was False
2026-03-12 00:30:35.201169 | 
2026-03-12 00:30:35.201311 | TASK [ansible-test : Adjust options for unit tests]
2026-03-12 00:30:35.227933 | controller | skipping: Conditional result was False
2026-03-12 00:30:35.235049 | 
2026-03-12 00:30:35.235139 | TASK [ansible-test : Install requirements with unit tests]
2026-03-12 00:30:35.311669 | controller | ok
2026-03-12 00:30:35.317349 | 
2026-03-12 00:30:35.317426 | TASK [ansible-test : Set --skip-test]
2026-03-12 00:30:35.362711 | controller | skipping: Conditional result was False
2026-03-12 00:30:35.372900 | 
2026-03-12 00:30:35.373090 | TASK [ansible-test : Reduce verbosity]
2026-03-12 00:30:35.403429 | controller | ok
2026-03-12 00:30:35.409182 | 
2026-03-12 00:30:35.409267 | TASK [ansible-test : Setup --python option]
2026-03-12 00:30:35.424460 | controller | skipping: Conditional result was False
2026-03-12 00:30:35.431239 | 
2026-03-12 00:30:35.431337 | TASK [ansible-test : Setup --docker option]
2026-03-12 00:30:35.460398 | controller | ok
2026-03-12 00:30:35.467093 | 
2026-03-12 00:30:35.467177 | TASK [ansible-test : check if MANIFEST.json exists]
2026-03-12 00:30:36.467574 | controller | ok
2026-03-12 00:30:36.482236 | 
2026-03-12 00:30:36.482458 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2026-03-12 00:30:36.511997 | controller | skipping: Conditional result was False
2026-03-12 00:30:36.524717 | 
2026-03-12 00:30:36.524894 | TASK [ansible-test : Load information from MANIFEST.json]
2026-03-12 00:30:36.551593 | controller | skipping: Conditional result was False
2026-03-12 00:30:36.561128 | 
2026-03-12 00:30:36.561294 | TASK [ansible-test : Setup location of project for integration tests]
2026-03-12 00:30:36.587695 | controller | skipping: Conditional result was False
2026-03-12 00:30:36.600696 | 
2026-03-12 00:30:36.600839 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2026-03-12 00:30:37.876631 | controller | ok
2026-03-12 00:30:37.884156 | 
2026-03-12 00:30:37.884483 | TASK [ansible-test : Load information from galaxy.yml]
2026-03-12 00:30:37.940576 | controller | ok
2026-03-12 00:30:37.949421 | 
2026-03-12 00:30:37.949549 | TASK [ansible-test : Setup location of project for integration tests]
2026-03-12 00:30:38.010521 | controller | ok
2026-03-12 00:30:38.022918 | 
2026-03-12 00:30:38.023147 | TASK [ansible-test : Setup minimum test requirements]
2026-03-12 00:30:38.072380 | controller | ok
2026-03-12 00:30:38.084828 | 
2026-03-12 00:30:38.085063 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2026-03-12 00:30:39.141820 | controller | ok
2026-03-12 00:30:39.156440 | 
2026-03-12 00:30:39.156659 | TASK [ansible-test : Install python requirements]
2026-03-12 00:30:40.850556 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-03-12 00:30:40.869849 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2026-03-12 00:30:40.913463 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-03-12 00:30:40.922201 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2026-03-12 00:30:40.935074 | 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-03-12 00:30:40.945691 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2026-03-12 00:30:40.964179 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2026-03-12 00:30:41.036851 | controller | Installing collected packages: pathspec, yamllint
2026-03-12 00:30:41.141756 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2026-03-12 00:30:41.551665 | controller | ok: Runtime: 0:00:01.227702
2026-03-12 00:30:41.568070 | 
2026-03-12 00:30:41.568314 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2026-03-12 00:30:42.426518 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2026-03-12 00:30:42.960109 | controller | ERROR
2026-03-12 00:30:42.960464 | controller | {
2026-03-12 00:30:42.960529 | controller |   "delta": "0:00:00.009301",
2026-03-12 00:30:42.960573 | controller |   "end": "2026-03-12 00:30:42.426833",
2026-03-12 00:30:42.960612 | controller |   "msg": "non-zero return code",
2026-03-12 00:30:42.960671 | controller |   "rc": 1,
2026-03-12 00:30:42.960707 | controller |   "start": "2026-03-12 00:30:42.417532"
2026-03-12 00:30:42.960827 | controller | }
2026-03-12 00:30:42.960912 | controller | ERROR: Ignoring Errors
2026-03-12 00:30:42.975508 | 
2026-03-12 00:30:42.975665 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2026-03-12 00:30:43.369944 | controller | skipping: Conditional result was False
2026-03-12 00:30:43.386482 | 
2026-03-12 00:30:43.386699 | TASK [ansible-test : Enable persistent connection logging]
2026-03-12 00:30:44.708394 | controller | changed: section and option added
2026-03-12 00:30:44.722258 | 
2026-03-12 00:30:44.722413 | TASK [ansible-test : Set persistent ssh_type value]
2026-03-12 00:30:46.088943 | controller | changed: option added
2026-03-12 00:30:46.099696 | 
2026-03-12 00:30:46.099843 | TASK [ansible-test : Set ansible log path]
2026-03-12 00:30:47.143475 | controller | changed: section and option added
2026-03-12 00:30:47.156893 | 
2026-03-12 00:30:47.157226 | TASK [ansible-test : Identify the changed targets]
2026-03-12 00:30:47.547516 | controller | skipping: Conditional result was False
2026-03-12 00:30:47.564952 | 
2026-03-12 00:30:47.565121 | TASK [ansible-test : set_fact]
2026-03-12 00:30:47.610182 | controller | skipping: Conditional result was False
2026-03-12 00:30:47.624798 | 
2026-03-12 00:30:47.624963 | TASK [ansible-test : Set the targets]
2026-03-12 00:30:47.677222 | controller | ok
2026-03-12 00:30:47.779092 | 
2026-03-12 00:30:47.779241 | TASK [ansible-test : set_fact]
2026-03-12 00:30:47.793834 | controller | skipping: Conditional result was False
2026-03-12 00:30:47.801514 | 
2026-03-12 00:30:47.801653 | TASK [ansible-test : List the targets]
2026-03-12 00:30:48.170482 | controller | skipping: Conditional result was False
2026-03-12 00:30:48.182204 | 
2026-03-12 00:30:48.182403 | TASK [ansible-test : set_fact]
2026-03-12 00:30:48.208487 | controller | skipping: Conditional result was False
2026-03-12 00:30:48.222903 | 
2026-03-12 00:30:48.223141 | TASK [ansible-test : set_fact]
2026-03-12 00:30:48.250773 | controller | skipping: Conditional result was False
2026-03-12 00:30:48.265986 | 
2026-03-12 00:30:48.266320 | LOOP [ansible-test : set_fact]
2026-03-12 00:30:48.338110 | 
2026-03-12 00:30:48.338418 | TASK [ansible-test : set_fact]
2026-03-12 00:30:48.355981 | controller | skipping: Conditional result was False
2026-03-12 00:30:48.371913 | 
2026-03-12 00:30:48.372132 | TASK [ansible-test : debug]
2026-03-12 00:30:48.389194 | controller | skipping: Conditional result was False
2026-03-12 00:30:48.402935 | 
2026-03-12 00:30:48.403159 | TASK [ansible-test : debug]
2026-03-12 00:30:48.441565 | About to run: ansible-test sanity --requirements --docker -v
2026-03-12 00:30:48.449393 | 
2026-03-12 00:30:48.449499 | TASK [ansible-test : Run the test suite]
2026-03-12 00:30:50.643890 | controller | Run command: docker images quay.io/ansible/default-test-container:1.10.1 --format '{{json .}}'
2026-03-12 00:30:50.786792 | controller | Run command: docker pull quay.io/ansible/default-test-container:1.10.1
2026-03-12 00:30:52.740947 | controller | 1.10.1: Pulling from ansible/default-test-container
2026-03-12 00:30:52.741023 | controller | [DEPRECATION NOTICE] Docker Image Format v1, and Docker Image manifest version 2, schema 1 support will be removed in an upcoming release. Suggest the author of quay.io/ansible/default-test-container:1.10.1 to upgrade the image to the OCI Format, or Docker Image manifest v2, schema 2. More information at https://docs.docker.com/go/deprecated-image-specs/
2026-03-12 00:30:52.741063 | controller | 5667fdb72017: Pulling fs layer
2026-03-12 00:30:52.741073 | controller | d83811f270d5: Pulling fs layer
2026-03-12 00:30:52.741081 | controller | ee671aafb583: Pulling fs layer
2026-03-12 00:30:52.741092 | controller | 7fc152dfb3a6: Pulling fs layer
2026-03-12 00:30:52.741102 | controller | 3fb967a6fd03: Pulling fs layer
2026-03-12 00:30:52.741108 | controller | b3e63023ca5f: Pulling fs layer
2026-03-12 00:30:52.741114 | controller | 653cb3e65dbf: Pulling fs layer
2026-03-12 00:30:52.741125 | controller | f4712b586375: Pulling fs layer
2026-03-12 00:30:52.741153 | controller | d696ed7812f8: Pulling fs layer
2026-03-12 00:30:52.741163 | controller | 29bf28764f14: Pulling fs layer
2026-03-12 00:30:52.741169 | controller | 47d7edb3c2b7: Pulling fs layer
2026-03-12 00:30:52.741175 | controller | 07550ad4f6e4: Pulling fs layer
2026-03-12 00:30:52.741182 | controller | 7e79526e64c5: Pulling fs layer
2026-03-12 00:30:52.741188 | controller | 4fffb32882b7: Pulling fs layer
2026-03-12 00:30:52.741195 | controller | fc7a38b06f4e: Pulling fs layer
2026-03-12 00:30:52.741201 | controller | 6d9d912e060e: Pulling fs layer
2026-03-12 00:30:52.741207 | controller | 5dd542dd28d9: Pulling fs layer
2026-03-12 00:30:52.741224 | controller | cef8beb99552: Pulling fs layer
2026-03-12 00:30:52.741232 | controller | bd5557e101ad: Pulling fs layer
2026-03-12 00:30:52.741239 | controller | e057c20fabdd: Pulling fs layer
2026-03-12 00:30:52.741247 | controller | d16059cdae2e: Pulling fs layer
2026-03-12 00:30:52.741254 | controller | 431e42d906a3: Pulling fs layer
2026-03-12 00:30:52.741260 | controller | 7a0fd3b1d7c3: Pulling fs layer
2026-03-12 00:30:52.741268 | controller | aacdb6db0c8c: Pulling fs layer
2026-03-12 00:30:52.741274 | controller | 2e7f04ae8c8b: Pulling fs layer
2026-03-12 00:30:52.741281 | controller | 4cd343027a44: Pulling fs layer
2026-03-12 00:30:52.741289 | controller | 0b4379883f90: Pulling fs layer
2026-03-12 00:30:52.741295 | controller | 3d9d6d9dfc91: Pulling fs layer
2026-03-12 00:30:52.741305 | controller | 57d6314bd293: Pulling fs layer
2026-03-12 00:30:52.741314 | controller | 774036afeb7f: Pulling fs layer
2026-03-12 00:30:52.741320 | controller | 54b10f0e1645: Pulling fs layer
2026-03-12 00:30:52.741326 | controller | 78cf439d9fdb: Pulling fs layer
2026-03-12 00:30:52.741332 | controller | 36e5e20969c7: Pulling fs layer
2026-03-12 00:30:52.741339 | controller | 4b19ac3f17a8: Pulling fs layer
2026-03-12 00:30:52.741345 | controller | 4fffb32882b7: Waiting
2026-03-12 00:30:52.741351 | controller | fc7a38b06f4e: Waiting
2026-03-12 00:30:52.741360 | controller | 6d9d912e060e: Waiting
2026-03-12 00:30:52.741367 | controller | cef8beb99552: Waiting
2026-03-12 00:30:52.741373 | controller | bd5557e101ad: Waiting
2026-03-12 00:30:52.741379 | controller | e057c20fabdd: Waiting
2026-03-12 00:30:52.741385 | controller | d16059cdae2e: Waiting
2026-03-12 00:30:52.741398 | controller | 431e42d906a3: Waiting
2026-03-12 00:30:52.741405 | controller | 7a0fd3b1d7c3: Waiting
2026-03-12 00:30:52.741411 | controller | aacdb6db0c8c: Waiting
2026-03-12 00:30:52.741417 | controller | 2e7f04ae8c8b: Waiting
2026-03-12 00:30:52.741423 | controller | 4cd343027a44: Waiting
2026-03-12 00:30:52.741429 | controller | 0b4379883f90: Waiting
2026-03-12 00:30:52.741435 | controller | 3d9d6d9dfc91: Waiting
2026-03-12 00:30:52.741445 | controller | 57d6314bd293: Waiting
2026-03-12 00:30:52.741451 | controller | 774036afeb7f: Waiting
2026-03-12 00:30:52.741457 | controller | 54b10f0e1645: Waiting
2026-03-12 00:30:52.741463 | controller | 78cf439d9fdb: Waiting
2026-03-12 00:30:52.741469 | controller | 36e5e20969c7: Waiting
2026-03-12 00:30:52.741475 | controller | 4b19ac3f17a8: Waiting
2026-03-12 00:30:52.741482 | controller | 5dd542dd28d9: Waiting
2026-03-12 00:30:52.741488 | controller | 7fc152dfb3a6: Waiting
2026-03-12 00:30:52.741494 | controller | 653cb3e65dbf: Waiting
2026-03-12 00:30:52.741503 | controller | 3fb967a6fd03: Waiting
2026-03-12 00:30:53.542876 | controller | f4712b586375: Waiting
2026-03-12 00:30:53.542936 | controller | b3e63023ca5f: Waiting
2026-03-12 00:30:53.542947 | controller | d696ed7812f8: Waiting
2026-03-12 00:30:53.542971 | controller | 29bf28764f14: Waiting
2026-03-12 00:30:53.542978 | controller | 47d7edb3c2b7: Waiting
2026-03-12 00:30:53.542984 | controller | 07550ad4f6e4: Waiting
2026-03-12 00:30:53.542991 | controller | 7e79526e64c5: Waiting
2026-03-12 00:30:53.543012 | controller | ee671aafb583: Verifying Checksum
2026-03-12 00:30:53.799356 | controller | ee671aafb583: Download complete
2026-03-12 00:30:53.799465 | controller | d83811f270d5: Verifying Checksum
2026-03-12 00:30:54.305429 | controller | d83811f270d5: Download complete
2026-03-12 00:30:54.305532 | controller | 7fc152dfb3a6: Verifying Checksum
2026-03-12 00:30:55.005112 | controller | 7fc152dfb3a6: Download complete
2026-03-12 00:30:55.005212 | controller | b3e63023ca5f: Verifying Checksum
2026-03-12 00:30:55.789049 | controller | b3e63023ca5f: Download complete
2026-03-12 00:30:55.789161 | controller | 653cb3e65dbf: Verifying Checksum
2026-03-12 00:30:57.489014 | controller | 653cb3e65dbf: Download complete
2026-03-12 00:30:57.489152 | controller | 3fb967a6fd03: Verifying Checksum
2026-03-12 00:30:57.490807 | controller | 3fb967a6fd03: Download complete
2026-03-12 00:30:57.787127 | controller | 5667fdb72017: Verifying Checksum
2026-03-12 00:30:57.789969 | controller | 5667fdb72017: Download complete
2026-03-12 00:30:58.351313 | controller | d696ed7812f8: Verifying Checksum
2026-03-12 00:30:58.351552 | controller | d696ed7812f8: Download complete
2026-03-12 00:30:58.615794 | controller | 29bf28764f14: Verifying Checksum
2026-03-12 00:30:58.615905 | controller | 29bf28764f14: Download complete
2026-03-12 00:30:59.172409 | controller | 47d7edb3c2b7: Verifying Checksum
2026-03-12 00:30:59.172573 | controller | 47d7edb3c2b7: Download complete
2026-03-12 00:30:59.392278 | controller | 07550ad4f6e4: Verifying Checksum
2026-03-12 00:30:59.392453 | controller | 07550ad4f6e4: Download complete
2026-03-12 00:31:00.023232 | controller | 7e79526e64c5: Verifying Checksum
2026-03-12 00:31:00.023626 | controller | 7e79526e64c5: Download complete
2026-03-12 00:31:00.161850 | controller | 4fffb32882b7: Verifying Checksum
2026-03-12 00:31:00.162144 | controller | 4fffb32882b7: Download complete
2026-03-12 00:31:00.464616 | controller | 5667fdb72017: Pull complete
2026-03-12 00:31:00.510363 | controller | d83811f270d5: Pull complete
2026-03-12 00:31:00.545880 | controller | ee671aafb583: Pull complete
2026-03-12 00:31:00.582176 | controller | 7fc152dfb3a6: Pull complete
2026-03-12 00:31:00.848301 | controller | fc7a38b06f4e: Verifying Checksum
2026-03-12 00:31:00.865098 | controller | fc7a38b06f4e: Download complete
2026-03-12 00:31:00.865186 | controller | 6d9d912e060e: Verifying Checksum
2026-03-12 00:31:01.561618 | controller | 6d9d912e060e: Download complete
2026-03-12 00:31:01.561741 | controller | 3fb967a6fd03: Pull complete
2026-03-12 00:31:01.593060 | controller | b3e63023ca5f: Pull complete
2026-03-12 00:31:01.616326 | controller | 653cb3e65dbf: Pull complete
2026-03-12 00:31:01.659455 | controller | 5dd542dd28d9: Download complete
2026-03-12 00:31:02.407336 | controller | bd5557e101ad: Verifying Checksum
2026-03-12 00:31:02.407569 | controller | bd5557e101ad: Download complete
2026-03-12 00:31:03.265316 | controller | e057c20fabdd: Verifying Checksum
2026-03-12 00:31:03.265443 | controller | e057c20fabdd: Download complete
2026-03-12 00:31:03.966620 | controller | d16059cdae2e: Verifying Checksum
2026-03-12 00:31:03.966760 | controller | d16059cdae2e: Download complete
2026-03-12 00:31:05.014886 | controller | 431e42d906a3: Verifying Checksum
2026-03-12 00:31:05.014983 | controller | 431e42d906a3: Download complete
2026-03-12 00:31:05.880885 | controller | 7a0fd3b1d7c3: Verifying Checksum
2026-03-12 00:31:05.880951 | controller | 7a0fd3b1d7c3: Download complete
2026-03-12 00:31:07.367024 | controller | aacdb6db0c8c: Verifying Checksum
2026-03-12 00:31:07.367128 | controller | aacdb6db0c8c: Download complete
2026-03-12 00:31:08.129535 | controller | 2e7f04ae8c8b: Verifying Checksum
2026-03-12 00:31:08.129646 | controller | 2e7f04ae8c8b: Download complete
2026-03-12 00:31:08.883991 | controller | 4cd343027a44: Verifying Checksum
2026-03-12 00:31:08.884471 | controller | 4cd343027a44: Download complete
2026-03-12 00:31:09.703959 | controller | 0b4379883f90: Verifying Checksum
2026-03-12 00:31:09.704137 | controller | 0b4379883f90: Download complete
2026-03-12 00:31:10.475673 | controller | 3d9d6d9dfc91: Verifying Checksum
2026-03-12 00:31:24.768465 | controller | 3d9d6d9dfc91: Download complete
2026-03-12 00:31:24.768556 | controller | f4712b586375: Verifying Checksum
2026-03-12 00:31:24.768665 | controller | f4712b586375: Download complete
2026-03-12 00:31:26.906453 | controller | 57d6314bd293: Verifying Checksum
2026-03-12 00:31:30.941219 | controller | 57d6314bd293: Download complete
2026-03-12 00:31:30.941319 | controller | cef8beb99552: Verifying Checksum
2026-03-12 00:31:30.942485 | controller | cef8beb99552: Download complete
2026-03-12 00:31:39.490011 | controller | 54b10f0e1645: Verifying Checksum
2026-03-12 00:31:40.947564 | controller | 54b10f0e1645: Download complete
2026-03-12 00:31:40.947701 | controller | 78cf439d9fdb: Verifying Checksum
2026-03-12 00:31:41.540142 | controller | 78cf439d9fdb: Download complete
2026-03-12 00:31:41.540267 | controller | 774036afeb7f: Verifying Checksum
2026-03-12 00:31:43.213988 | controller | 774036afeb7f: Download complete
2026-03-12 00:31:43.214087 | controller | 4b19ac3f17a8: Verifying Checksum
2026-03-12 00:31:49.891296 | controller | 4b19ac3f17a8: Download complete
2026-03-12 00:31:49.891439 | controller | f4712b586375: Pull complete
2026-03-12 00:31:49.946032 | controller | d696ed7812f8: Pull complete
2026-03-12 00:31:50.010485 | controller | 29bf28764f14: Pull complete
2026-03-12 00:31:50.066418 | controller | 47d7edb3c2b7: Pull complete
2026-03-12 00:31:50.098767 | controller | 07550ad4f6e4: Pull complete
2026-03-12 00:31:50.141273 | controller | 7e79526e64c5: Pull complete
2026-03-12 00:31:50.173717 | controller | 4fffb32882b7: Pull complete
2026-03-12 00:31:50.291409 | controller | fc7a38b06f4e: Pull complete
2026-03-12 00:31:50.320408 | controller | 6d9d912e060e: Pull complete
2026-03-12 00:31:50.354737 | controller | 5dd542dd28d9: Pull complete
2026-03-12 00:31:52.431258 | controller | 36e5e20969c7: Verifying Checksum
2026-03-12 00:31:52.431367 | controller | 36e5e20969c7: Download complete
2026-03-12 00:32:11.861417 | controller | cef8beb99552: Pull complete
2026-03-12 00:32:11.891098 | controller | bd5557e101ad: Pull complete
2026-03-12 00:32:11.910492 | controller | e057c20fabdd: Pull complete
2026-03-12 00:32:11.933132 | controller | d16059cdae2e: Pull complete
2026-03-12 00:32:13.860367 | controller | 431e42d906a3: Pull complete
2026-03-12 00:32:13.939623 | controller | 7a0fd3b1d7c3: Pull complete
2026-03-12 00:32:13.996870 | controller | aacdb6db0c8c: Pull complete
2026-03-12 00:32:14.037111 | controller | 2e7f04ae8c8b: Pull complete
2026-03-12 00:32:14.078156 | controller | 4cd343027a44: Pull complete
2026-03-12 00:32:14.124263 | controller | 0b4379883f90: Pull complete
2026-03-12 00:32:14.174802 | controller | 3d9d6d9dfc91: Pull complete
2026-03-12 00:32:19.822397 | controller | 57d6314bd293: Pull complete
2026-03-12 00:32:42.677744 | controller | 774036afeb7f: Pull complete
2026-03-12 00:33:11.830942 | controller | 54b10f0e1645: Pull complete
2026-03-12 00:33:40.655999 | controller | 78cf439d9fdb: Pull complete
2026-03-12 00:34:09.061904 | controller | 36e5e20969c7: Pull complete
2026-03-12 00:34:37.057438 | controller | 4b19ac3f17a8: Pull complete
2026-03-12 00:34:37.077728 | controller | Digest: sha256:954c4edffb0905df64e23c8611b1c4a62db9af13062f51cad5b658e727238d41
2026-03-12 00:34:37.082184 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:1.10.1
2026-03-12 00:34:37.091763 | controller | quay.io/ansible/default-test-container:1.10.1
2026-03-12 00:34:37.100539 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2026-03-12 00:34:37.143710 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2026-03-12 00:34:38.526844 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2026-03-12 00:34:38.527042 | controller | 04a5f4cda3ee: Pulling fs layer
2026-03-12 00:34:38.527066 | controller | ff496a88c8ed: Pulling fs layer
2026-03-12 00:34:38.527129 | controller | 0ce83f459fe7: Pulling fs layer
2026-03-12 00:34:38.527179 | controller | 2e5170e1f099: Pulling fs layer
2026-03-12 00:34:38.527213 | controller | 7641eb41b08c: Pulling fs layer
2026-03-12 00:34:38.527451 | controller | ad15fa9da398: Pulling fs layer
2026-03-12 00:34:38.527584 | controller | 087d91352424: Pulling fs layer
2026-03-12 00:34:38.527647 | controller | 8b92efd6a100: Pulling fs layer
2026-03-12 00:34:38.527701 | controller | 7641eb41b08c: Waiting
2026-03-12 00:34:38.527735 | controller | ad15fa9da398: Waiting
2026-03-12 00:34:38.527776 | controller | 087d91352424: Waiting
2026-03-12 00:34:38.528091 | controller | 8b92efd6a100: Waiting
2026-03-12 00:34:38.528149 | controller | 2e5170e1f099: Waiting
2026-03-12 00:34:39.227646 | controller | 0ce83f459fe7: Verifying Checksum
2026-03-12 00:34:39.248253 | controller | 0ce83f459fe7: Download complete
2026-03-12 00:34:39.248324 | controller | ff496a88c8ed: Verifying Checksum
2026-03-12 00:34:40.033764 | controller | ff496a88c8ed: Download complete
2026-03-12 00:34:40.033840 | controller | 7641eb41b08c: Download complete
2026-03-12 00:34:40.850166 | controller | ad15fa9da398: Verifying Checksum
2026-03-12 00:34:41.926239 | controller | ad15fa9da398: Download complete
2026-03-12 00:34:41.926299 | controller | 087d91352424: Verifying Checksum
2026-03-12 00:34:42.648128 | controller | 087d91352424: Download complete
2026-03-12 00:34:42.648188 | controller | 8b92efd6a100: Verifying Checksum
2026-03-12 00:34:44.927214 | controller | 8b92efd6a100: Download complete
2026-03-12 00:34:44.927288 | controller | 04a5f4cda3ee: Verifying Checksum
2026-03-12 00:34:45.495787 | controller | 04a5f4cda3ee: Download complete
2026-03-12 00:34:45.495949 | controller | 2e5170e1f099: Verifying Checksum
2026-03-12 00:34:45.496155 | controller | 2e5170e1f099: Download complete
2026-03-12 00:34:46.918311 | controller | 04a5f4cda3ee: Pull complete
2026-03-12 00:34:46.942994 | controller | ff496a88c8ed: Pull complete
2026-03-12 00:34:46.970460 | controller | 0ce83f459fe7: Pull complete
2026-03-12 00:34:48.508757 | controller | 2e5170e1f099: Pull complete
2026-03-12 00:34:48.538194 | controller | 7641eb41b08c: Pull complete
2026-03-12 00:34:48.581791 | controller | ad15fa9da398: Pull complete
2026-03-12 00:34:51.005553 | controller | 087d91352424: Pull complete
2026-03-12 00:34:51.099003 | controller | 8b92efd6a100: Pull complete
2026-03-12 00:34:51.157516 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2026-03-12 00:34:51.199500 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2026-03-12 00:34:51.218368 | controller | quay.io/ansible/pypi-test-container:1.0.0
2026-03-12 00:34:51.222397 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2026-03-12 00:34:56.063156 | controller | Run command: docker inspect b864eedd88098e6caaffb0dc937dc476cb1448659292568d4349bd0271a08b30
2026-03-12 00:34:56.342795 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2026-03-12 00:34:56.359299 | controller | Including collection: ansible.netcommon (278 files)
2026-03-12 00:34:56.359378 | controller | Including collection: ansible.network (23 files)
2026-03-12 00:34:56.374298 | controller | Including collection: ansible.utils (441 files)
2026-03-12 00:34:56.421454 | controller | Including collection: arista.eos (1031 files)
2026-03-12 00:34:56.489596 | controller | Including collection: cisco.ios (1215 files)
2026-03-12 00:34:56.550617 | controller | Including collection: cisco.iosxr (1044 files)
2026-03-12 00:34:56.645989 | controller | Including collection: cisco.nxos (1687 files)
2026-03-12 00:34:56.666448 | controller | Including collection: juniper.device (391 files)
2026-03-12 00:34:56.707820 | controller | Including collection: junipernetworks.junos (700 files)
2026-03-12 00:34:56.755107 | controller | Creating a payload archive containing 8547 files...
2026-03-12 00:34:59.365560 | controller | Created a 7130351 byte payload archive containing 8547 files in 2 seconds.
2026-03-12 00:34:59.370173 | controller | Assuming Docker is available on localhost.
2026-03-12 00:34:59.370610 | controller | Run command: docker run --detach --volume /sys/fs/cgroup:/sys/fs/cgroup:ro --privileged=false --security-opt seccomp=unconfined --volume /var/run/docker.sock:/var/run/docker.sock quay.io/ansible/default-test-container:1.10.1
2026-03-12 00:34:59.983202 | controller | Run command: docker exec -i 3ab41d60cec5fe23e3a1aeaedbe19ffc7ba4f444869ed28be157f12440e50f47 dd of=/root/docker.sh bs=65536
2026-03-12 00:35:00.084188 | controller | Run command: docker exec 3ab41d60cec5fe23e3a1aeaedbe19ffc7ba4f444869ed28be157f12440e50f47 /bin/bash /root/docker.sh
2026-03-12 00:35:00.178025 | controller | Run command: docker exec -i 3ab41d60cec5fe23e3a1aeaedbe19ffc7ba4f444869ed28be157f12440e50f47 dd of=/root/ansible.tgz bs=65536
2026-03-12 00:35:00.287771 | controller | Run command: docker exec 3ab41d60cec5fe23e3a1aeaedbe19ffc7ba4f444869ed28be157f12440e50f47 mkdir /root/ansible
2026-03-12 00:35:00.353765 | controller | Run command: docker exec 3ab41d60cec5fe23e3a1aeaedbe19ffc7ba4f444869ed28be157f12440e50f47 tar oxzf /root/ansible.tgz -C /root/ansible
2026-03-12 00:35:01.652180 | controller | Run command: docker exec 3ab41d60cec5fe23e3a1aeaedbe19ffc7ba4f444869ed28be157f12440e50f47 /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata metadata-4or6d7bx.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2026-03-12 00:35:02.201687 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2026-03-12 00:35:02.202125 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2026-03-12 00:35:02.202743 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2026-03-12 00:35:02.204699 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2026-03-12 00:35:02.282167 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2026-03-12 00:35:02.285616 | controller | Read 0 sanity test ignore line(s) for Ansible 2.9 from: tests/sanity/ignore-2.9.txt
2026-03-12 00:35:02.293910 | controller | No tests applicable.
2026-03-12 00:35:02.294149 | controller | Running sanity test 'ansible-doc' with Python 3.6
2026-03-12 00:35:02.294394 | controller | No tests applicable.
2026-03-12 00:35:02.295440 | controller | Run command: /usr/bin/python2.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2026-03-12 00:35:02.381196 | controller | Running sanity test 'compile' with Python 2.6
2026-03-12 00:35:02.381701 | controller | No tests applicable.
2026-03-12 00:35:02.382115 | controller | Run command: /usr/bin/python2.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2026-03-12 00:35:02.588371 | controller | Running sanity test 'compile' with Python 2.7
2026-03-12 00:35:02.588690 | controller | No tests applicable.
2026-03-12 00:35:02.589134 | controller | Run command: /usr/bin/python3.5 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2026-03-12 00:35:02.722160 | controller | Running sanity test 'compile' with Python 3.5
2026-03-12 00:35:02.722483 | controller | No tests applicable.
2026-03-12 00:35:02.722642 | controller | Running sanity test 'compile' with Python 3.6
2026-03-12 00:35:02.722905 | controller | No tests applicable.
2026-03-12 00:35:02.723286 | controller | Run command: /usr/bin/python3.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2026-03-12 00:35:02.839273 | controller | Running sanity test 'compile' with Python 3.7
2026-03-12 00:35:02.839596 | controller | No tests applicable.
2026-03-12 00:35:02.840028 | controller | Run command: /usr/bin/python3.8 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2026-03-12 00:35:02.953843 | controller | Running sanity test 'compile' with Python 3.8
2026-03-12 00:35:02.954140 | controller | No tests applicable.
2026-03-12 00:35:02.954342 | controller | Running sanity test 'empty-init' with Python 3.6
2026-03-12 00:35:02.954573 | controller | No tests applicable.
2026-03-12 00:35:02.954759 | controller | Running sanity test 'future-import-boilerplate' with Python 3.6
2026-03-12 00:35:02.954979 | controller | No tests applicable.
2026-03-12 00:35:02.955141 | controller | Running sanity test 'ignores'
2026-03-12 00:35:02.956311 | controller | Run command: /usr/bin/python3.6 -m pip.__main__ install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt
2026-03-12 00:35:03.723463 | controller | Ignoring packaging: markers 'python_version < "3.6"' don't match your environment
2026-03-12 00:35:03.726856 | controller | Ignoring coverage: markers 'python_version > "3.7"' don't match your environment
2026-03-12 00:35:03.728431 | controller | Ignoring cryptography: markers 'python_version < "2.7"' don't match your environment
2026-03-12 00:35:03.731684 | controller | Ignoring deepdiff: markers 'python_version < "3"' don't match your environment
2026-03-12 00:35:03.733412 | controller | Ignoring jinja2: markers 'python_version < "2.7"' don't match your environment
2026-03-12 00:35:03.738085 | controller | Ignoring urllib3: markers 'python_version < "2.7"' don't match your environment
2026-03-12 00:35:03.741630 | controller | Ignoring sphinx: markers 'python_version < "2.7"' don't match your environment
2026-03-12 00:35:03.746398 | controller | Ignoring wheel: markers 'python_version < "2.7"' don't match your environment
2026-03-12 00:35:03.747913 | controller | Ignoring yamllint: markers 'python_version < "2.7"' don't match your environment
2026-03-12 00:35:03.752697 | controller | Ignoring paramiko: markers 'python_version < "2.7"' don't match your environment
2026-03-12 00:35:03.755337 | controller | Ignoring pytest: markers 'python_version < "2.7"' don't match your environment
2026-03-12 00:35:03.757674 | controller | Ignoring pytest: markers 'python_version == "2.7"' don't match your environment
2026-03-12 00:35:03.759192 | controller | Ignoring pytest-forked: markers 'python_version < "2.7"' don't match your environment
2026-03-12 00:35:03.765202 | controller | Ignoring requests: markers 'python_version < "2.7"' don't match your environment
2026-03-12 00:35:03.772595 | controller | Ignoring virtualenv: markers 'python_version < "2.7"' don't match your environment
2026-03-12 00:35:03.774347 | controller | Ignoring pathspec: markers 'python_version < "2.7"' don't match your environment
2026-03-12 00:35:03.775850 | controller | Ignoring pyopenssl: markers 'python_version < "2.7"' don't match your environment
2026-03-12 00:35:03.778782 | controller | Ignoring pyyaml: markers 'python_version < "2.7"' don't match your environment
2026-03-12 00:35:03.780185 | controller | Ignoring pycparser: markers 'python_version < "2.7"' don't match your environment
2026-03-12 00:35:03.784977 | controller | Ignoring xmltodict: markers 'python_version < "2.7"' don't match your environment
2026-03-12 00:35:03.786795 | controller | Ignoring lxml: markers 'python_version < "2.7"' don't match your environment
2026-03-12 00:35:03.788585 | controller | Ignoring pyvmomi: markers 'python_version < "2.7"' don't match your environment
2026-03-12 00:35:03.791187 | controller | Ignoring MarkupSafe: markers 'python_version < "3.6"' don't match your environment
2026-03-12 00:35:03.795878 | controller | Ignoring setuptools: markers 'python_version <= "2.7"' don't match your environment
2026-03-12 00:35:03.803085 | controller | Ignoring websocket-client: markers 'python_version < "3"' don't match your environment
2026-03-12 00:35:03.804544 | controller | Ignoring certifi: markers 'python_version < "3"' don't match your environment
2026-03-12 00:35:03.823257 | controller | Looking in indexes: http://172.17.0.2:3141/root/pypi/+simple/
2026-03-12 00:35:03.824385 | controller | Requirement already satisfied: cryptography<3.4 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 5)) (2.7)
2026-03-12 00:35:03.852694 | controller | Requirement already satisfied: rstcheck==3.3.1 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 12)) (3.3.1)
2026-03-12 00:35:03.856152 | controller | Requirement already satisfied: voluptuous>=0.11.0 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 30)) (0.11.7)
2026-03-12 00:35:03.859611 | controller | Requirement already satisfied: cffi!=1.14.4 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 49)) (1.13.0)
2026-03-12 00:35:03.862004 | controller | Requirement already satisfied: pylint==2.3.1 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 58)) (2.3.1)
2026-03-12 00:35:03.871413 | controller | Requirement already satisfied: jinja2 in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 2)) (2.10.3)
2026-03-12 00:35:03.875172 | controller | Requirement already satisfied: pycodestyle in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 3)) (2.5.0)
2026-03-12 00:35:03.879131 | controller | Requirement already satisfied: pyyaml in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 5)) (5.1.2)
2026-03-12 00:35:03.881048 | controller | Requirement already satisfied: virtualenv in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 7)) (16.7.5)
2026-03-12 00:35:03.899327 | controller | Requirement already satisfied: yamllint in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 9)) (1.18.0)
2026-03-12 00:35:03.902827 | controller | Requirement already satisfied: asn1crypto>=0.21.0 in /usr/local/lib/python3.6/dist-packages (from cryptography<3.4->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 5)) (1.1.0)
2026-03-12 00:35:03.905516 | controller | Requirement already satisfied: six>=1.4.1 in /usr/local/lib/python3.6/dist-packages (from cryptography<3.4->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 5)) (1.12.0)
2026-03-12 00:35:03.909755 | controller | Requirement already satisfied: docutils>=0.7 in /usr/local/lib/python3.6/dist-packages (from rstcheck==3.3.1->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 12)) (0.15.2)
2026-03-12 00:35:03.912892 | controller | Requirement already satisfied: pycparser in /usr/local/lib/python3.6/dist-packages (from cffi!=1.14.4->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 49)) (2.19)
2026-03-12 00:35:03.914708 | controller | Collecting isort==4.3.16 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 55))
2026-03-12 00:35:18.937219 | 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/isort/
2026-03-12 00:35:34.454440 | 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/isort/
2026-03-12 00:35:50.471991 | 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/isort/
2026-03-12 00:36:07.491050 | 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/isort/
2026-03-12 00:36:26.511827 | 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/isort/
2026-03-12 00:36:41.523828 | controller |   ERROR: Could not find a version that satisfies the requirement isort==4.3.16 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 55)) (from versions: none)
2026-03-12 00:36:41.646914 | controller | ERROR: No matching distribution found for isort==4.3.16 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 55))
2026-03-12 00:36:41.646993 | controller | ERROR: Command "/usr/bin/python3.6 -m pip.__main__ install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt" returned exit status 1.
2026-03-12 00:36:41.647185 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2026-03-12 00:36:41.647424 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2026-03-12 00:36:41.647544 | controller | Removing custom PyPI hosts entries: /etc/hosts
2026-03-12 00:36:41.695979 | controller | Run command: docker exec 3ab41d60cec5fe23e3a1aeaedbe19ffc7ba4f444869ed28be157f12440e50f47 tar czf /root/output.tgz --exclude .tmp -C /root/ansible/ansible_collections/ansible/network/tests output
2026-03-12 00:36:41.790638 | controller | Run command: docker exec -i 3ab41d60cec5fe23e3a1aeaedbe19ffc7ba4f444869ed28be157f12440e50f47 dd if=/root/output.tgz bs=65536
2026-03-12 00:36:41.898106 | controller | Run command: tar oxzf /tmp/ansible-result-pyn6og_t.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2026-03-12 00:36:41.914211 | controller | Run command: docker rm -f b864eedd88098e6caaffb0dc937dc476cb1448659292568d4349bd0271a08b30
2026-03-12 00:36:42.191962 | controller | Run command: docker rm -f 3ab41d60cec5fe23e3a1aeaedbe19ffc7ba4f444869ed28be157f12440e50f47
2026-03-12 00:36:43.013741 | controller | ERROR: Command "docker exec 3ab41d60cec5fe23e3a1aeaedbe19ffc7ba4f444869ed28be157f12440e50f47 /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata metadata-4or6d7bx.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2026-03-12 00:36:43.650370 | controller | ERROR
2026-03-12 00:36:43.650685 | controller | {
2026-03-12 00:36:43.650749 | controller |   "delta": "0:05:52.998943",
2026-03-12 00:36:43.650793 | controller |   "end": "2026-03-12 00:36:43.089526",
2026-03-12 00:36:43.650831 | controller |   "msg": "non-zero return code",
2026-03-12 00:36:43.650868 | controller |   "rc": 1,
2026-03-12 00:36:43.650904 | controller |   "start": "2026-03-12 00:30:50.090583"
2026-03-12 00:36:43.650940 | controller | }
failure
2026-03-12 00:36:43.660739 | 
2026-03-12 00:36:43.660920 | PLAY RECAP
2026-03-12 00:36:43.661006 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2026-03-12 00:36:43.661089 | 
2026-03-12 00:36:43.827402 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-03-12 00:36:43.829355 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-12 00:36:44.477090 | 
2026-03-12 00:36:44.477228 | PLAY [all]
2026-03-12 00:36:44.502763 | 
2026-03-12 00:36:44.502926 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-03-12 00:36:47.658864 | controller | changed: non-zero return code
2026-03-12 00:36:47.674604 | 
2026-03-12 00:36:47.674874 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-03-12 00:36:47.703840 | controller | skipping: Conditional result was False
2026-03-12 00:36:47.719115 | 
2026-03-12 00:36:47.719363 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-03-12 00:36:47.764323 | 
2026-03-12 00:36:47.764639 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-03-12 00:36:47.808244 | 
2026-03-12 00:36:47.808552 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-03-12 00:36:47.835605 | controller | skipping: Conditional result was False
2026-03-12 00:36:47.846854 | 
2026-03-12 00:36:47.847056 | LOOP [fetch-subunit-output : Generate subunit file]
2026-03-12 00:36:47.888295 | 
2026-03-12 00:36:47.888598 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-03-12 00:36:47.915834 | controller | skipping: Conditional result was False
2026-03-12 00:36:47.929739 | 
2026-03-12 00:36:47.930092 | TASK [fetch-subunit-output : Remove the temporary file]
2026-03-12 00:36:47.957295 | controller | skipping: Conditional result was False
2026-03-12 00:36:47.967758 | 
2026-03-12 00:36:47.967942 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-03-12 00:36:47.995602 | controller | skipping: Conditional result was False
2026-03-12 00:36:48.039958 | 
2026-03-12 00:36:48.040174 | PLAY RECAP
2026-03-12 00:36:48.040250 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-12 00:36:48.040289 | 
2026-03-12 00:36:48.257935 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-12 00:36:48.258953 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-03-12 00:36:48.945434 | 
2026-03-12 00:36:48.945620 | PLAY [all:!appliance*]
2026-03-12 00:36:48.968705 | 
2026-03-12 00:36:48.968892 | TASK [unregister the node]
2026-03-12 00:36:49.339948 | controller | skipping: Conditional result was False
2026-03-12 00:36:49.360454 | 
2026-03-12 00:36:49.360835 | TASK [include_role : fetch-output]
2026-03-12 00:36:49.406606 | controller | ok
2026-03-12 00:36:49.446794 | 
2026-03-12 00:36:49.446996 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-12 00:36:49.534833 | controller | skipping: Conditional result was False
2026-03-12 00:36:49.542383 | 
2026-03-12 00:36:49.542569 | TASK [fetch-output : Set log path for single node]
2026-03-12 00:36:49.587721 | controller | ok
2026-03-12 00:36:49.597785 | 
2026-03-12 00:36:49.598039 | LOOP [fetch-output : Ensure local output dirs]
2026-03-12 00:36:50.073572 | controller -> localhost | ok: "/var/lib/zuul/builds/07fd7d7e896145b99f9f5d68ec7d2187/work/logs"
2026-03-12 00:36:50.416382 | controller -> localhost | changed: "/var/lib/zuul/builds/07fd7d7e896145b99f9f5d68ec7d2187/work/artifacts"
2026-03-12 00:36:50.688071 | controller -> localhost | changed: "/var/lib/zuul/builds/07fd7d7e896145b99f9f5d68ec7d2187/work/docs"
2026-03-12 00:36:50.704605 | 
2026-03-12 00:36:50.704749 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-12 00:36:53.860377 | controller | changed:
2026-03-12 00:36:53.861139 | controller | .d..t...... ./
2026-03-12 00:36:53.861549 | controller | cd+++++++++ controller/
2026-03-12 00:36:53.861654 | controller | changed: All items complete
2026-03-12 00:36:53.861687 | 
2026-03-12 00:36:56.330437 | controller | changed: .d..t...... ./
2026-03-12 00:36:58.517299 | controller | changed: .d..t...... ./
2026-03-12 00:36:58.560433 | 
2026-03-12 00:36:58.560671 | TASK [include_role : fetch-output-openshift]
2026-03-12 00:36:58.589168 | controller | skipping: Conditional result was False
2026-03-12 00:36:58.606260 | 
2026-03-12 00:36:58.606454 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-12 00:36:58.647994 | controller | skipping: Conditional result was False
2026-03-12 00:36:58.661277 | controller | skipping: Conditional result was False
2026-03-12 00:36:58.709150 | 
2026-03-12 00:36:58.709311 | PLAY [localhost]
2026-03-12 00:36:58.728528 | 
2026-03-12 00:36:58.728687 | TASK [Run Zuul manifest role]
2026-03-12 00:36:58.751591 | localhost | ok
2026-03-12 00:36:58.772074 | 
2026-03-12 00:36:58.772300 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-12 00:36:59.242250 | localhost | changed
2026-03-12 00:36:59.286358 | 
2026-03-12 00:36:59.286501 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-12 00:36:59.317040 | localhost | ok
2026-03-12 00:36:59.326209 | 
2026-03-12 00:36:59.326318 | TASK [Set zuul-log-path fact]
2026-03-12 00:36:59.346884 | localhost | ok
2026-03-12 00:36:59.361902 | 
2026-03-12 00:36:59.362056 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-12 00:36:59.392359 | localhost | ok
2026-03-12 00:36:59.400643 | 
2026-03-12 00:36:59.400706 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)]
2026-03-12 00:36:59.438710 | localhost | ok:
2026-03-12 00:36:59.439251 | localhost | {
2026-03-12 00:36:59.439316 | localhost |   "auth": {
2026-03-12 00:36:59.439360 | localhost |     "password": "IonWCwcuOFib&&<}wp^:[2G/",
2026-03-12 00:36:59.439399 | localhost |     "username": "9LF91O0RK7JU"
2026-03-12 00:36:59.439437 | localhost |   },
2026-03-12 00:36:59.439473 | localhost |   "profile": "rackspace",
2026-03-12 00:36:59.439508 | localhost |   "region_name": "IAD"
2026-03-12 00:36:59.439543 | localhost | }
2026-03-12 00:36:59.439605 | 
2026-03-12 00:36:59.457104 | 
2026-03-12 00:36:59.457237 | TASK [Run upload-logs-failover role (S3 then Swift)]
2026-03-12 00:36:59.481931 | localhost | Output suppressed because no_log was given
2026-03-12 00:36:59.500493 | 
2026-03-12 00:36:59.500611 | TASK [upload-logs-failover : Initialize control vars]
2026-03-12 00:36:59.530727 | localhost | ok
2026-03-12 00:36:59.535841 | 
2026-03-12 00:36:59.535913 | LOOP [upload-logs-failover : Perform log upload]
2026-03-12 00:36:59.573293 | localhost | Output suppressed because no_log was given
2026-03-12 00:36:59.581801 | localhost | included: /var/lib/zuul/builds/07fd7d7e896145b99f9f5d68ec7d2187/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml
2026-03-12 00:36:59.590110 | 
2026-03-12 00:36:59.590218 | LOOP [upload-logs-failover : Unpack vars for swift upload]
2026-03-12 00:36:59.668278 | localhost | Output suppressed because no_log was given
2026-03-12 00:36:59.679345 | 
2026-03-12 00:36:59.679507 | TASK [Upload logs to swift]
2026-03-12 00:36:59.704245 | localhost | ok
2026-03-12 00:36:59.752508 | 
2026-03-12 00:36:59.752684 | TASK [Set zuul-log-path fact]
2026-03-12 00:36:59.778913 | localhost | skipping: Conditional result was False
2026-03-12 00:36:59.785563 | 
2026-03-12 00:36:59.785637 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-03-12 00:37:00.201302 | localhost -> localhost | ok: Runtime: 0:00:00.005393
2026-03-12 00:37:00.209193 | 
2026-03-12 00:37:00.209314 | TASK [upload-logs-swift : Upload logs to swift]