2026-03-09 00:36:52.239505 | Job console starting...
2026-03-09 00:36:52.256717 | Updating repositories
2026-03-09 00:36:52.374089 | Preparing job workspace
2026-03-09 00:36:57.762734 | Running Ansible setup...
2026-03-09 00:37:05.911633 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-03-09 00:37:06.494746 | 
2026-03-09 00:37:06.494948 | PLAY [localhost]
2026-03-09 00:37:06.504346 | 
2026-03-09 00:37:06.504421 | TASK [Gathering Facts]
2026-03-09 00:37:07.580512 | localhost | ok
2026-03-09 00:37:07.599941 | 
2026-03-09 00:37:07.600059 | TASK [Setup log path fact]
2026-03-09 00:37:07.645346 | localhost | ok
2026-03-09 00:37:07.681616 | 
2026-03-09 00:37:07.681888 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-09 00:37:07.729258 | localhost | ok
2026-03-09 00:37:07.747283 | 
2026-03-09 00:37:07.747435 | TASK [emit-job-header : Print job information]
2026-03-09 00:37:07.801015 | # Job Information
2026-03-09 00:37:07.801232 | Ansible Version: 2.15.12
2026-03-09 00:37:07.801278 | Job: ansible-test-sanity-docker-stable-2.9
2026-03-09 00:37:07.801309 | Pipeline: periodic
2026-03-09 00:37:07.801335 | Executor: ze02.softwarefactory-project.io
2026-03-09 00:37:07.801361 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-03-09 00:37:07.801390 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/b56/ansible/b56e83ae95be46a78f062d6828a0fe8d/
2026-03-09 00:37:07.801417 | Event ID: 6ed282d1e81147dcab9935779dfad26a
2026-03-09 00:37:07.806948 | 
2026-03-09 00:37:07.807041 | LOOP [emit-job-header : Print node information]
2026-03-09 00:37:07.925592 | localhost | ok:
2026-03-09 00:37:07.925994 | localhost | # Node Information
2026-03-09 00:37:07.926032 | localhost | Inventory Hostname: controller
2026-03-09 00:37:07.926053 | localhost | Hostname: np0005641935
2026-03-09 00:37:07.926074 | localhost | Username: zuul
2026-03-09 00:37:07.926104 | localhost | Distro: Fedora 37
2026-03-09 00:37:07.926129 | localhost | Provider: ansible-vexxhost-ams1
2026-03-09 00:37:07.926152 | localhost | Region: ams1
2026-03-09 00:37:07.926173 | localhost | Label: ansible-fedora-37-1vcpu
2026-03-09 00:37:07.926197 | localhost | Product Name: OpenStack Nova
2026-03-09 00:37:07.926218 | localhost | Interface IP: 38.129.16.109
2026-03-09 00:37:07.953472 | 
2026-03-09 00:37:07.953830 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-09 00:37:08.350609 | localhost -> localhost | changed
2026-03-09 00:37:08.357527 | 
2026-03-09 00:37:08.357608 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-09 00:37:09.314920 | localhost -> localhost | changed
2026-03-09 00:37:09.363605 | 
2026-03-09 00:37:09.363784 | PLAY [all:!appliance*]
2026-03-09 00:37:09.388203 | 
2026-03-09 00:37:09.388319 | TASK [include_role : start-zuul-console]
2026-03-09 00:37:09.415973 | controller | ok
2026-03-09 00:37:09.436731 | 
2026-03-09 00:37:09.436832 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-03-09 00:37:10.533421 | controller | ok
2026-03-09 00:37:10.557320 | 
2026-03-09 00:37:10.557420 | TASK [use-our-mirror : Retrieve the IP address]
2026-03-09 00:37:13.416968 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-03-09 00:37:13.431782 | 
2026-03-09 00:37:13.431924 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-03-09 00:37:13.817454 | controller | skipping: Conditional result was False
2026-03-09 00:37:13.832028 | 
2026-03-09 00:37:13.832176 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-03-09 00:37:13.869996 | controller | skipping: Conditional result was False
2026-03-09 00:37:13.884420 | 
2026-03-09 00:37:13.884566 | TASK [use-our-mirror : Create the podman configuration directory]
2026-03-09 00:37:13.912340 | controller | skipping: Conditional result was False
2026-03-09 00:37:13.927578 | 
2026-03-09 00:37:13.927809 | TASK [use-our-mirror : Copy the podman configuration]
2026-03-09 00:37:13.956109 | controller | skipping: Conditional result was False
2026-03-09 00:37:13.972251 | 
2026-03-09 00:37:13.972415 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-03-09 00:37:13.999589 | controller | skipping: Conditional result was False
2026-03-09 00:37:14.015086 | 
2026-03-09 00:37:14.015290 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-03-09 00:37:14.042282 | controller | skipping: Conditional result was False
2026-03-09 00:37:14.070384 | 
2026-03-09 00:37:14.070536 | TASK [Disable Fedora Modular]
2026-03-09 00:37:15.676001 | controller | changed
2026-03-09 00:37:15.689046 | 
2026-03-09 00:37:15.689189 | TASK [Enable EPEL]
2026-03-09 00:37:15.727046 | controller | skipping: Conditional result was False
2026-03-09 00:37:15.740511 | 
2026-03-09 00:37:15.740659 | TASK [Register the RHEL node]
2026-03-09 00:37:16.165398 | 
2026-03-09 00:37:16.165714 | TASK [Show the subscription-manager status]
2026-03-09 00:37:16.573382 | controller | skipping: Conditional result was False
2026-03-09 00:37:16.589393 | 
2026-03-09 00:37:16.589580 | TASK [Enable EPEL on RHEL]
2026-03-09 00:37:17.006042 | controller | skipping: Conditional result was False
2026-03-09 00:37:17.019444 | 
2026-03-09 00:37:17.019587 | TASK [Install git and tox]
2026-03-09 00:39:33.366579 | controller | changed
2026-03-09 00:39:33.380968 | 
2026-03-09 00:39:33.381110 | TASK [include_role : prepare-workspace]
2026-03-09 00:39:33.423479 | controller | ok
2026-03-09 00:39:33.461080 | 
2026-03-09 00:39:33.461189 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-09 00:39:34.425156 | controller | ok
2026-03-09 00:39:34.439303 | 
2026-03-09 00:39:34.439504 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-09 00:39:55.361980 | controller | Output suppressed because no_log was given
2026-03-09 00:39:55.383408 | 
2026-03-09 00:39:55.383530 | TASK [include_role : prepare-workspace-openshift]
2026-03-09 00:39:55.430865 | controller | skipping: Conditional result was False
2026-03-09 00:39:55.479408 | 
2026-03-09 00:39:55.479515 | PLAY [all:!appliance]
2026-03-09 00:39:55.501036 | 
2026-03-09 00:39:55.501131 | TASK [Run add-build-sshkey role (RSA)]
2026-03-09 00:39:55.536821 | controller | ok
2026-03-09 00:39:55.558119 | 
2026-03-09 00:39:55.558210 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-09 00:39:55.904295 | controller -> localhost | ok
2026-03-09 00:39:55.919970 | 
2026-03-09 00:39:55.920123 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-09 00:39:55.957564 | controller | ok
2026-03-09 00:39:55.978810 | controller | included: /var/lib/zuul/builds/b56e83ae95be46a78f062d6828a0fe8d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-09 00:39:55.990184 | 
2026-03-09 00:39:55.990405 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-09 00:39:56.642217 | controller -> localhost | Generating public/private rsa key pair.
2026-03-09 00:39:56.642520 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/b56e83ae95be46a78f062d6828a0fe8d/work/b56e83ae95be46a78f062d6828a0fe8d_id_rsa.
2026-03-09 00:39:56.642609 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/b56e83ae95be46a78f062d6828a0fe8d/work/b56e83ae95be46a78f062d6828a0fe8d_id_rsa.pub.
2026-03-09 00:39:56.642657 | controller -> localhost | The key fingerprint is:
2026-03-09 00:39:56.642739 | controller -> localhost | SHA256:ePYUlakooNJefDDaX+CjpFQ0YQ8E59jbckbIQcL9a6Y zuul-build-sshkey
2026-03-09 00:39:56.642784 | controller -> localhost | The key's randomart image is:
2026-03-09 00:39:56.642825 | controller -> localhost | +---[RSA 2048]----+
2026-03-09 00:39:56.642866 | controller -> localhost | | .o*@.      .o   |
2026-03-09 00:39:56.642909 | controller -> localhost | |  .O**.    .o    |
2026-03-09 00:39:56.642950 | controller -> localhost | | ..*=*o. ...     |
2026-03-09 00:39:56.642991 | controller -> localhost | |. = =+*.o ..     |
2026-03-09 00:39:56.643031 | controller -> localhost | | + +o+==S .      |
2026-03-09 00:39:56.643088 | controller -> localhost | |  o .+=o o       |
2026-03-09 00:39:56.643139 | controller -> localhost | |     +    .      |
2026-03-09 00:39:56.643183 | controller -> localhost | |    E            |
2026-03-09 00:39:56.643225 | controller -> localhost | |                 |
2026-03-09 00:39:56.643271 | controller -> localhost | +----[SHA256]-----+
2026-03-09 00:39:56.643357 | controller -> localhost | ok: Runtime: 0:00:00.162990
2026-03-09 00:39:56.657746 | 
2026-03-09 00:39:56.657878 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-09 00:39:56.698022 | controller | ok
2026-03-09 00:39:56.719255 | controller | included: /var/lib/zuul/builds/b56e83ae95be46a78f062d6828a0fe8d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-09 00:39:56.731222 | 
2026-03-09 00:39:56.731308 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-09 00:39:56.757156 | controller | skipping: Conditional result was False
2026-03-09 00:39:56.769567 | 
2026-03-09 00:39:56.769773 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-09 00:39:57.929746 | controller | changed
2026-03-09 00:39:57.942483 | 
2026-03-09 00:39:57.942614 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-09 00:39:58.666690 | controller | ok
2026-03-09 00:39:58.681382 | 
2026-03-09 00:39:58.681490 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-09 00:40:01.890159 | controller | changed
2026-03-09 00:40:01.903692 | 
2026-03-09 00:40:01.903851 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-09 00:40:05.136767 | controller | changed
2026-03-09 00:40:05.145219 | 
2026-03-09 00:40:05.145307 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-09 00:40:05.171909 | controller | skipping: Conditional result was False
2026-03-09 00:40:05.181920 | 
2026-03-09 00:40:05.182035 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-09 00:40:05.572199 | controller -> localhost | changed
2026-03-09 00:40:05.589083 | 
2026-03-09 00:40:05.589237 | TASK [add-build-sshkey : Add back temp key]
2026-03-09 00:40:05.925625 | controller -> localhost | Identity added: /var/lib/zuul/builds/b56e83ae95be46a78f062d6828a0fe8d/work/b56e83ae95be46a78f062d6828a0fe8d_id_rsa (zuul-build-sshkey)
2026-03-09 00:40:05.926044 | controller -> localhost | ok: Runtime: 0:00:00.014886
2026-03-09 00:40:05.940222 | 
2026-03-09 00:40:05.940360 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-09 00:40:06.880861 | controller | ok
2026-03-09 00:40:06.893396 | 
2026-03-09 00:40:06.893540 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-09 00:40:06.932576 | controller | skipping: Conditional result was False
2026-03-09 00:40:06.960452 | 
2026-03-09 00:40:06.960606 | TASK [Run add-build-sshkey role (ECDSA)]
2026-03-09 00:40:07.004058 | controller | ok
2026-03-09 00:40:07.032622 | 
2026-03-09 00:40:07.032742 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-09 00:40:07.312226 | controller -> localhost | ok
2026-03-09 00:40:07.321821 | 
2026-03-09 00:40:07.321910 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-09 00:40:07.356080 | controller | ok
2026-03-09 00:40:07.380531 | controller | included: /var/lib/zuul/builds/b56e83ae95be46a78f062d6828a0fe8d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-09 00:40:07.389646 | 
2026-03-09 00:40:07.389770 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-09 00:40:07.722390 | controller -> localhost | Generating public/private ecdsa key pair.
2026-03-09 00:40:07.722623 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/b56e83ae95be46a78f062d6828a0fe8d/work/b56e83ae95be46a78f062d6828a0fe8d_id_ecdsa.
2026-03-09 00:40:07.722752 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/b56e83ae95be46a78f062d6828a0fe8d/work/b56e83ae95be46a78f062d6828a0fe8d_id_ecdsa.pub.
2026-03-09 00:40:07.722814 | controller -> localhost | The key fingerprint is:
2026-03-09 00:40:07.722846 | controller -> localhost | SHA256:7edrRczNXyf/NmQ2af6BuDpZtt7kZxi31qKndC/4JnY zuul-build-sshkey
2026-03-09 00:40:07.722877 | controller -> localhost | The key's randomart image is:
2026-03-09 00:40:07.722904 | controller -> localhost | +---[ECDSA 521]---+
2026-03-09 00:40:07.722929 | controller -> localhost | |                 |
2026-03-09 00:40:07.722954 | controller -> localhost | |                 |
2026-03-09 00:40:07.722979 | controller -> localhost | |             o o |
2026-03-09 00:40:07.723004 | controller -> localhost | |         .    = =|
2026-03-09 00:40:07.723030 | controller -> localhost | |        S .  . o=|
2026-03-09 00:40:07.723055 | controller -> localhost | |         . o..oOo|
2026-03-09 00:40:07.723079 | controller -> localhost | |          =.+=@.=|
2026-03-09 00:40:07.723104 | controller -> localhost | |         o =XoEO=|
2026-03-09 00:40:07.723128 | controller -> localhost | |         .+==%=+=|
2026-03-09 00:40:07.723152 | controller -> localhost | +----[SHA256]-----+
2026-03-09 00:40:07.723218 | controller -> localhost | ok: Runtime: 0:00:00.014004
2026-03-09 00:40:07.732786 | 
2026-03-09 00:40:07.732972 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-09 00:40:07.757199 | controller | ok
2026-03-09 00:40:07.768162 | controller | included: /var/lib/zuul/builds/b56e83ae95be46a78f062d6828a0fe8d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-09 00:40:07.781427 | 
2026-03-09 00:40:07.781507 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-09 00:40:07.797133 | controller | skipping: Conditional result was False
2026-03-09 00:40:07.807937 | 
2026-03-09 00:40:07.808029 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-09 00:40:08.767070 | controller | changed
2026-03-09 00:40:08.779977 | 
2026-03-09 00:40:08.780129 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-09 00:40:09.498948 | controller | ok
2026-03-09 00:40:09.513159 | 
2026-03-09 00:40:09.513312 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-09 00:40:12.800683 | controller | changed
2026-03-09 00:40:12.808034 | 
2026-03-09 00:40:12.808104 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-09 00:40:15.995813 | controller | changed
2026-03-09 00:40:16.009255 | 
2026-03-09 00:40:16.009394 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-09 00:40:16.038551 | controller | skipping: Conditional result was False
2026-03-09 00:40:16.052505 | 
2026-03-09 00:40:16.052758 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-09 00:40:16.378916 | controller -> localhost | changed
2026-03-09 00:40:16.401130 | 
2026-03-09 00:40:16.401244 | TASK [add-build-sshkey : Add back temp key]
2026-03-09 00:40:16.706908 | controller -> localhost | Identity added: /var/lib/zuul/builds/b56e83ae95be46a78f062d6828a0fe8d/work/b56e83ae95be46a78f062d6828a0fe8d_id_ecdsa (zuul-build-sshkey)
2026-03-09 00:40:16.707440 | controller -> localhost | ok: Runtime: 0:00:00.013460
2026-03-09 00:40:16.723240 | 
2026-03-09 00:40:16.723386 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-09 00:40:17.475245 | controller | ok
2026-03-09 00:40:17.484092 | 
2026-03-09 00:40:17.484182 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-09 00:40:17.531120 | controller | skipping: Conditional result was False
2026-03-09 00:40:17.558842 | 
2026-03-09 00:40:17.559010 | TASK [include_role : remove-zuul-sshkey]
2026-03-09 00:40:17.585710 | controller | skipping: Conditional result was False
2026-03-09 00:40:17.593600 | 
2026-03-09 00:40:17.593692 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-09 00:40:18.269414 | controller | ok: "logs"
2026-03-09 00:40:18.269723 | controller | ok: All items complete
2026-03-09 00:40:18.269757 | 
2026-03-09 00:40:18.910979 | controller | ok: "artifacts"
2026-03-09 00:40:19.545883 | controller | ok: "docs"
2026-03-09 00:40:19.563001 | 
2026-03-09 00:40:19.563297 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-09 00:40:20.241129 | controller | changed: "logs"
2026-03-09 00:40:20.871658 | controller | changed: "artifacts"
2026-03-09 00:40:21.532288 | controller | changed: "docs"
2026-03-09 00:40:21.579509 | 
2026-03-09 00:40:21.579627 | PLAY RECAP
2026-03-09 00:40:21.579689 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-03-09 00:40:21.579722 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-09 00:40:21.579742 | 
2026-03-09 00:40:21.715470 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-03-09 00:40:21.716774 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-09 00:40:22.289999 | 
2026-03-09 00:40:22.290180 | PLAY [all]
2026-03-09 00:40:22.313356 | 
2026-03-09 00:40:22.313444 | TASK [Install binary dependencies]
2026-03-09 00:40:22.386347 | controller | ok
2026-03-09 00:40:22.413062 | 
2026-03-09 00:40:22.413175 | TASK [bindep : Include find tasks]
2026-03-09 00:40:22.456049 | controller | ok
2026-03-09 00:40:22.466774 | controller | included: /var/lib/zuul/builds/b56e83ae95be46a78f062d6828a0fe8d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-03-09 00:40:22.475208 | 
2026-03-09 00:40:22.475294 | TASK [bindep : Look for bindep.txt]
2026-03-09 00:40:23.460887 | controller | ok
2026-03-09 00:40:23.473382 | 
2026-03-09 00:40:23.473527 | TASK [bindep : Define bindep_file fact]
2026-03-09 00:40:23.490739 | controller | skipping: Conditional result was False
2026-03-09 00:40:23.504934 | 
2026-03-09 00:40:23.505081 | TASK [bindep : Look for other-requirements.txt]
2026-03-09 00:40:24.145978 | controller | ok
2026-03-09 00:40:24.159321 | 
2026-03-09 00:40:24.159472 | TASK [bindep : Define bindep_file fact]
2026-03-09 00:40:24.197275 | controller | skipping: Conditional result was False
2026-03-09 00:40:24.210591 | 
2026-03-09 00:40:24.210796 | TASK [bindep : Look for bindep fallback file]
2026-03-09 00:40:24.249246 | controller | skipping: Conditional result was False
2026-03-09 00:40:24.264182 | 
2026-03-09 00:40:24.264353 | TASK [bindep : Define bindep_file fact]
2026-03-09 00:40:24.302379 | controller | skipping: Conditional result was False
2026-03-09 00:40:24.316110 | 
2026-03-09 00:40:24.316251 | TASK [bindep : Include bindep tasks]
2026-03-09 00:40:24.353755 | controller | skipping: Conditional result was False
2026-03-09 00:40:24.362301 | 
2026-03-09 00:40:24.362392 | TASK [bindep : Include install tasks]
2026-03-09 00:40:24.398923 | controller | skipping: Conditional result was False
2026-03-09 00:40:24.405554 | 
2026-03-09 00:40:24.405627 | LOOP [bindep : Include package tasks]
2026-03-09 00:40:24.467349 | 
2026-03-09 00:40:24.467558 | TASK [Run test-setup role]
2026-03-09 00:40:24.490130 | controller | ok
2026-03-09 00:40:24.514268 | 
2026-03-09 00:40:24.514387 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-03-09 00:40:25.261891 | controller | ok
2026-03-09 00:40:25.274901 | 
2026-03-09 00:40:25.275045 | TASK [test-setup : Run tools/test-setup.sh]
2026-03-09 00:40:25.684640 | controller | skipping: Conditional result was False
2026-03-09 00:40:25.710334 | 
2026-03-09 00:40:25.710438 | PLAY RECAP
2026-03-09 00:40:25.710478 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-09 00:40:25.710497 | 
2026-03-09 00:40:25.824110 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-09 00:40:25.825080 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-03-09 00:40:26.428401 | 
2026-03-09 00:40:26.428535 | PLAY [controller]
2026-03-09 00:40:26.448587 | 
2026-03-09 00:40:26.448683 | TASK [Create the /root directory]
2026-03-09 00:40:27.557931 | controller | ok
2026-03-09 00:40:27.572502 | 
2026-03-09 00:40:27.572706 | TASK [Install glibc-langpack-en]
2026-03-09 00:40:36.458445 | controller | ok: Nothing to do
2026-03-09 00:40:36.472648 | 
2026-03-09 00:40:36.472835 | TASK [Ensure controller directory exists]
2026-03-09 00:40:37.339565 | controller | changed
2026-03-09 00:40:37.350483 | 
2026-03-09 00:40:37.350592 | TASK [Install container runtime]
2026-03-09 00:40:37.396001 | controller | ok
2026-03-09 00:40:37.440144 | 
2026-03-09 00:40:37.440278 | LOOP [ensure-docker : Gather variables for each operating system]
2026-03-09 00:40:37.493229 | controller | ok: "/var/lib/zuul/builds/b56e83ae95be46a78f062d6828a0fe8d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2026-03-09 00:40:37.503145 | 
2026-03-09 00:40:37.503289 | TASK [ensure-docker : Sanity check for distro selection]
2026-03-09 00:40:37.528336 | controller | skipping: Conditional result was False
2026-03-09 00:40:37.534826 | 
2026-03-09 00:40:37.534959 | TASK [ensure-docker : Sanity check for distro install]
2026-03-09 00:40:37.569566 | controller | skipping: Conditional result was False
2026-03-09 00:40:37.575449 | 
2026-03-09 00:40:37.575515 | TASK [ensure-docker : Sanity check for upstream install]
2026-03-09 00:40:37.609892 | controller | skipping: Conditional result was False
2026-03-09 00:40:37.617970 | 
2026-03-09 00:40:37.618050 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2026-03-09 00:40:37.653502 | controller | skipping: Conditional result was False
2026-03-09 00:40:37.661455 | 
2026-03-09 00:40:37.661529 | TASK [ensure-docker : Include tasks for docker install]
2026-03-09 00:40:37.688882 | controller | ok
2026-03-09 00:40:37.704608 | controller | included: /var/lib/zuul/builds/b56e83ae95be46a78f062d6828a0fe8d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2026-03-09 00:40:37.717086 | 
2026-03-09 00:40:37.717254 | TASK [ensure-docker : Not Implemented]
2026-03-09 00:40:37.732916 | controller | skipping: Conditional result was False
2026-03-09 00:40:37.739977 | 
2026-03-09 00:40:37.740044 | TASK [Set up docker mirrors]
2026-03-09 00:40:37.758899 | controller | ok
2026-03-09 00:40:37.778068 | 
2026-03-09 00:40:37.778170 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2026-03-09 00:40:37.802424 | controller | skipping: Conditional result was False
2026-03-09 00:40:37.837951 | 
2026-03-09 00:40:37.838114 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2026-03-09 00:40:37.862822 | controller | skipping: Conditional result was False
2026-03-09 00:40:37.869551 | 
2026-03-09 00:40:37.869623 | TASK [use-docker-mirror : Set docker_mirror fact]
2026-03-09 00:40:37.904258 | controller | skipping: Conditional result was False
2026-03-09 00:40:37.910768 | 
2026-03-09 00:40:37.910858 | TASK [use-docker-mirror : Set up docker mirrors]
2026-03-09 00:40:37.945805 | controller | skipping: Conditional result was False
2026-03-09 00:40:37.957933 | 
2026-03-09 00:40:37.958049 | TASK [Add all repositories]
2026-03-09 00:40:37.978204 | controller | ok
2026-03-09 00:40:38.002498 | 
2026-03-09 00:40:38.002637 | LOOP [ensure-package-repositories : Install repositories]
2026-03-09 00:40:38.026442 | controller | ok: "/var/lib/zuul/builds/b56e83ae95be46a78f062d6828a0fe8d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2026-03-09 00:40:38.026608 | controller | ok: All items complete
2026-03-09 00:40:38.026636 | 
2026-03-09 00:40:38.035109 | controller | included: /var/lib/zuul/builds/b56e83ae95be46a78f062d6828a0fe8d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2026-03-09 00:40:38.041285 | 
2026-03-09 00:40:38.041343 | TASK [ensure-package-repositories : Create GPG key temp directory]
2026-03-09 00:40:38.932827 | controller | changed
2026-03-09 00:40:38.950884 | 
2026-03-09 00:40:38.951088 | LOOP [ensure-package-repositories : Upload GPG keys]
2026-03-09 00:40:42.344731 | controller | changed:
2026-03-09 00:40:42.345062 | controller | {
2026-03-09 00:40:42.345121 | 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-09 00:40:42.345167 | controller | }
2026-03-09 00:40:42.367142 | 
2026-03-09 00:40:42.367313 | LOOP [ensure-package-repositories : Import GPG keys]
2026-03-09 00:40:43.779955 | controller | changed:
2026-03-09 00:40:43.780150 | controller | {
2026-03-09 00:40:43.780187 | 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-09 00:40:43.780217 | controller | }
2026-03-09 00:40:43.780256 | controller | changed: All items complete
2026-03-09 00:40:43.780283 | 
2026-03-09 00:40:43.797694 | 
2026-03-09 00:40:43.797882 | TASK [ensure-package-repositories : Remove GPG temp directory]
2026-03-09 00:40:44.600402 | controller | changed
2026-03-09 00:40:44.609432 | 
2026-03-09 00:40:44.609553 | LOOP [ensure-package-repositories : Add repositories]
2026-03-09 00:40:45.487373 | controller | changed:
2026-03-09 00:40:45.487659 | controller | {
2026-03-09 00:40:45.487714 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2026-03-09 00:40:45.487737 | controller |   "description": "Docker CE Stable - $basearch",
2026-03-09 00:40:45.487760 | controller |   "gpgcheck": true,
2026-03-09 00:40:45.487936 | controller |   "name": "docker-ce-stable"
2026-03-09 00:40:45.487963 | controller | }
2026-03-09 00:40:45.524769 | 
2026-03-09 00:40:45.524983 | TASK [ensure-docker : Install pre-reqs]
2026-03-09 00:40:51.438922 | controller | ok: Nothing to do
2026-03-09 00:40:51.451542 | 
2026-03-09 00:40:51.451761 | TASK [ensure-docker : Remove packages]
2026-03-09 00:40:54.825732 | controller | ok: Nothing to do
2026-03-09 00:40:54.838414 | 
2026-03-09 00:40:54.838585 | TASK [ensure-docker : Install upstream docker using package]
2026-03-09 00:42:20.181215 | controller | changed
2026-03-09 00:42:20.195643 | 
2026-03-09 00:42:20.195888 | TASK [ensure-docker : Setup docker]
2026-03-09 00:42:20.225577 | controller | ok
2026-03-09 00:42:20.248088 | controller | included: /var/lib/zuul/builds/b56e83ae95be46a78f062d6828a0fe8d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2026-03-09 00:42:20.266687 | 
2026-03-09 00:42:20.266821 | TASK [ensure-docker : Ensure "docker" group exists]
2026-03-09 00:42:21.287433 | controller | ok
2026-03-09 00:42:21.294960 | 
2026-03-09 00:42:21.295039 | TASK [ensure-docker : Add user to docker group]
2026-03-09 00:42:22.300901 | controller | changed
2026-03-09 00:42:22.314492 | 
2026-03-09 00:42:22.314620 | TASK [Update docker daemon proxy configuration]
2026-03-09 00:42:22.351691 | controller | skipping: Conditional result was False
2026-03-09 00:42:22.365407 | 
2026-03-09 00:42:22.365535 | TASK [Lower default MTU]
2026-03-09 00:42:22.392740 | controller | skipping: Conditional result was False
2026-03-09 00:42:22.407433 | 
2026-03-09 00:42:22.407579 | TASK [ensure-docker : Get the status of the docket.socket unit]
2026-03-09 00:42:23.178097 | controller | inactive
2026-03-09 00:42:23.799539 | controller | ok: Runtime: 0:00:00.016043
2026-03-09 00:42:23.813525 | 
2026-03-09 00:42:23.813710 | TASK [ensure-docker : Get the status of the docket unit]
2026-03-09 00:42:24.638135 | controller | inactive
2026-03-09 00:42:25.212534 | controller | ok: Runtime: 0:00:00.022033
2026-03-09 00:42:25.225956 | 
2026-03-09 00:42:25.226095 | TASK [ensure-docker : Restart docker]
2026-03-09 00:42:25.285385 | Trigger a Docker restart
2026-03-09 00:42:25.297807 | 
2026-03-09 00:42:25.297946 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2026-03-09 00:42:25.343417 | 
2026-03-09 00:42:25.343508 | TASK [ensure-docker : Install docker-compose]
2026-03-09 00:42:25.368947 | controller | skipping: Conditional result was False
2026-03-09 00:42:25.377143 | 
2026-03-09 00:42:25.377227 | TASK [ensure-docker : Flush handlers before role exit]
2026-03-09 00:42:25.390688 | 
2026-03-09 00:42:25.390770 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2026-03-09 00:42:28.846860 | controller | changed
2026-03-09 00:42:28.863602 | 
2026-03-09 00:42:28.863784 | TASK [ensure-docker : Assure docker service is running]
2026-03-09 00:42:32.801460 | controller | changed
2026-03-09 00:42:32.818303 | 
2026-03-09 00:42:32.818440 | TASK [ensure-docker : Assure docker.socket service is running]
2026-03-09 00:42:34.370642 | controller | ok
2026-03-09 00:42:34.387163 | 
2026-03-09 00:42:34.387290 | TASK [ensure-docker : Correct group ownership on docker sock]
2026-03-09 00:42:35.150951 | controller | ok
2026-03-09 00:42:35.163443 | 
2026-03-09 00:42:35.163635 | TASK [ensure-docker : Validate ability to talk with docker]
2026-03-09 00:42:35.798226 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2026-03-09 00:42:36.052305 | controller | ok: Runtime: 0:00:00.034990
2026-03-09 00:42:36.064519 | 
2026-03-09 00:42:36.064652 | TASK [ensure-docker : Get version details]
2026-03-09 00:42:36.736636 | controller | Client: Docker Engine - Community
2026-03-09 00:42:36.736711 | controller |  Version:           24.0.7
2026-03-09 00:42:36.736750 | controller |  API version:       1.43
2026-03-09 00:42:36.736844 | controller |  Go version:        go1.20.10
2026-03-09 00:42:36.736865 | controller |  Git commit:        afdd53b
2026-03-09 00:42:36.736873 | controller |  Built:             Thu Oct 26 09:09:39 2023
2026-03-09 00:42:36.736881 | controller |  OS/Arch:           linux/amd64
2026-03-09 00:42:36.736901 | controller |  Context:           default
2026-03-09 00:42:36.736910 | controller |
2026-03-09 00:42:36.736917 | controller | Server: Docker Engine - Community
2026-03-09 00:42:36.736923 | controller |  Engine:
2026-03-09 00:42:36.736934 | controller |   Version:          24.0.7
2026-03-09 00:42:36.739988 | controller |   API version:      1.43 (minimum version 1.12)
2026-03-09 00:42:36.740008 | controller |   Go version:       go1.20.10
2026-03-09 00:42:36.740015 | controller |   Git commit:       311b9ff
2026-03-09 00:42:36.740022 | controller |   Built:            Thu Oct 26 09:08:11 2023
2026-03-09 00:42:36.740028 | controller |   OS/Arch:          linux/amd64
2026-03-09 00:42:36.740034 | controller |   Experimental:     false
2026-03-09 00:42:36.740041 | controller |  containerd:
2026-03-09 00:42:36.740047 | controller |   Version:          1.6.25
2026-03-09 00:42:36.740052 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2026-03-09 00:42:36.740058 | controller |  runc:
2026-03-09 00:42:36.740064 | controller |   Version:          1.1.10
2026-03-09 00:42:36.740070 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2026-03-09 00:42:36.740079 | controller |  docker-init:
2026-03-09 00:42:36.740085 | controller |   Version:          0.19.0
2026-03-09 00:42:36.740091 | controller |   GitCommit:        de40ad0
2026-03-09 00:42:36.959873 | controller | ok: Runtime: 0:00:00.054143
2026-03-09 00:42:36.972072 | 
2026-03-09 00:42:36.972204 | TASK [ensure-docker : Dump installed docker details]
2026-03-09 00:42:37.020785 | {'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-09 00:42:36.688221', 'end': '2026-03-09 00:42:36.742364', 'delta': '0:00:00.054143', 'msg': '', 'zuul_log_id': 'fa163efc-24cc-217e-9109-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-09 00:42:37.033140 | 
2026-03-09 00:42:37.033265 | TASK [Use buildset registry]
2026-03-09 00:42:37.070506 | controller | skipping: Conditional result was False
2026-03-09 00:42:37.097213 | 
2026-03-09 00:42:37.097365 | TASK [Ensure python3.8 is present]
2026-03-09 00:42:37.124554 | controller | skipping: Conditional result was False
2026-03-09 00:42:37.137810 | 
2026-03-09 00:42:37.137941 | TASK [Run our-ensure-python role (Fedora only for now)]
2026-03-09 00:42:37.168319 | controller | ok
2026-03-09 00:42:37.200451 | 
2026-03-09 00:42:37.200541 | TASK [our-ensure-python : Install the right Python version (rpm)]
2026-03-09 00:42:43.434990 | controller | ok: Nothing to do
2026-03-09 00:42:43.447164 | 
2026-03-09 00:42:43.447301 | TASK [our-ensure-python : Also install python3-devel]
2026-03-09 00:43:00.692507 | controller | changed
2026-03-09 00:43:00.703125 | 
2026-03-09 00:43:00.703185 | TASK [Run ensure-virtualenv role]
2026-03-09 00:43:00.722339 | controller | ok
2026-03-09 00:43:00.742218 | 
2026-03-09 00:43:00.742282 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2026-03-09 00:43:01.487668 | controller | /usr/bin/virtualenv
2026-03-09 00:43:02.110608 | controller | ok: Runtime: 0:00:00.005815
2026-03-09 00:43:02.121101 | 
2026-03-09 00:43:02.121301 | LOOP [ensure-virtualenv : Install virtualenv package]
2026-03-09 00:43:02.160245 | controller | skipping: Conditional result was False
2026-03-09 00:43:02.160991 | controller | changed: All items complete
2026-03-09 00:43:02.161049 | 
2026-03-09 00:43:02.196100 | 
2026-03-09 00:43:02.196357 | TASK [Find the full path of the Python interpreter]
2026-03-09 00:43:03.000272 | controller | /usr/bin/python3.8
2026-03-09 00:43:03.591070 | controller | ok
2026-03-09 00:43:03.604174 | 
2026-03-09 00:43:03.604334 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2026-03-09 00:43:06.153157 | controller | created virtual environment CPython3.8.18.final.0-64 in 1289ms
2026-03-09 00:43:06.264514 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2026-03-09 00:43:06.264575 | 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-09 00:43:06.264593 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2026-03-09 00:43:06.264613 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2026-03-09 00:43:06.504298 | controller | changed
2026-03-09 00:43:06.518429 | 
2026-03-09 00:43:06.518565 | TASK [Set selinux package]
2026-03-09 00:43:06.559948 | controller | ok
2026-03-09 00:43:06.571374 | 
2026-03-09 00:43:06.571595 | TASK [Set selinux package (Fedora)]
2026-03-09 00:43:06.632235 | controller | ok
2026-03-09 00:43:06.643479 | 
2026-03-09 00:43:06.643617 | TASK [Install selinux into virtualenv]
2026-03-09 00:43:12.961173 | controller | Collecting selinux-please-lie-to-me
2026-03-09 00:43:13.019210 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2026-03-09 00:43:13.918544 | controller | Collecting setuptools<50.0.0
2026-03-09 00:43:13.924507 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2026-03-09 00:43:13.966977 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 21.4 MB/s eta 0:00:00
2026-03-09 00:43:14.097988 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2026-03-09 00:43:14.098220 | controller |   Attempting uninstall: setuptools
2026-03-09 00:43:14.099385 | controller |     Found existing installation: setuptools 62.6.0
2026-03-09 00:43:14.227696 | controller |     Uninstalling setuptools-62.6.0:
2026-03-09 00:43:14.242550 | controller |       Successfully uninstalled setuptools-62.6.0
2026-03-09 00:43:14.900930 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2026-03-09 00:43:15.090853 | controller |
2026-03-09 00:43:15.354956 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-03-09 00:43:15.355007 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-03-09 00:43:15.562257 | controller | ok: Runtime: 0:00:07.466497
2026-03-09 00:43:15.575510 | 
2026-03-09 00:43:15.575642 | TASK [Install pytest-forked into virtualenv]
2026-03-09 00:43:17.373185 | controller | Collecting pytest-forked
2026-03-09 00:43:17.434185 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2026-03-09 00:43:17.659276 | controller | Collecting pytest>=3.10
2026-03-09 00:43:17.664374 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2026-03-09 00:43:17.711720 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 8.0 MB/s eta 0:00:00
2026-03-09 00:43:17.775468 | controller | Collecting py
2026-03-09 00:43:17.781583 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2026-03-09 00:43:17.797266 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 7.9 MB/s eta 0:00:00
2026-03-09 00:43:17.881046 | controller | Collecting exceptiongroup>=1.0.0rc8
2026-03-09 00:43:17.887499 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2026-03-09 00:43:17.923550 | controller | Collecting iniconfig
2026-03-09 00:43:17.930667 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2026-03-09 00:43:18.027997 | controller | Collecting packaging
2026-03-09 00:43:18.034723 | controller |   Downloading packaging-26.0-py3-none-any.whl (74 kB)
2026-03-09 00:43:18.043632 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 12.2 MB/s eta 0:00:00
2026-03-09 00:43:18.099479 | controller | Collecting pluggy<2,>=1.5
2026-03-09 00:43:18.104369 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2026-03-09 00:43:18.201780 | controller | Collecting tomli>=1
2026-03-09 00:43:18.206749 | controller |   Downloading tomli-2.4.0-py3-none-any.whl (14 kB)
2026-03-09 00:43:18.297695 | controller | Collecting typing-extensions>=4.6.0
2026-03-09 00:43:18.303867 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2026-03-09 00:43:18.313658 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 5.8 MB/s eta 0:00:00
2026-03-09 00:43:18.422967 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2026-03-09 00:43:19.327778 | 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-09 00:43:19.333803 | controller |
2026-03-09 00:43:19.547057 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-03-09 00:43:19.547111 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-03-09 00:43:19.976550 | controller | ok: Runtime: 0:00:02.948774
2026-03-09 00:43:19.990160 | 
2026-03-09 00:43:19.990297 | TASK [Update pip]
2026-03-09 00:43:22.167055 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2026-03-09 00:43:22.616706 | controller | Collecting pip
2026-03-09 00:43:22.755168 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2026-03-09 00:43:22.883982 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 15.6 MB/s eta 0:00:00
2026-03-09 00:43:23.110391 | controller | Installing collected packages: pip
2026-03-09 00:43:23.112167 | controller |   Attempting uninstall: pip
2026-03-09 00:43:23.113653 | controller |     Found existing installation: pip 22.2.2
2026-03-09 00:43:23.745911 | controller |     Uninstalling pip-22.2.2:
2026-03-09 00:43:23.812878 | controller |       Successfully uninstalled pip-22.2.2
2026-03-09 00:43:26.503770 | controller | Successfully installed pip-25.0.1
2026-03-09 00:43:26.903746 | controller | ok: Runtime: 0:00:05.592863
2026-03-09 00:43:26.909886 | 
2026-03-09 00:43:26.909955 | TASK [Ensure pip temp directory exists in /var/tmp]
2026-03-09 00:43:28.073395 | controller | changed
2026-03-09 00:43:28.081912 | 
2026-03-09 00:43:28.082002 | TASK [Install ansible into virtualenv]
2026-03-09 00:43:29.888333 | controller | Processing ./src/github.com/ansible/ansible
2026-03-09 00:43:29.889936 | controller |   Preparing metadata (setup.py): started
2026-03-09 00:43:31.813390 | controller |   Preparing metadata (setup.py): finished with status 'done'
2026-03-09 00:43:31.885984 | controller | Collecting jinja2 (from ansible==2.9.27.post0)
2026-03-09 00:43:31.908269 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2026-03-09 00:43:31.994434 | controller | Collecting PyYAML (from ansible==2.9.27.post0)
2026-03-09 00:43:32.001750 | 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-09 00:43:32.460020 | controller | Collecting cryptography (from ansible==2.9.27.post0)
2026-03-09 00:43:32.467438 | controller |   Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2026-03-09 00:43:32.725272 | controller | Collecting cffi>=1.14 (from cryptography->ansible==2.9.27.post0)
2026-03-09 00:43:32.731914 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2026-03-09 00:43:32.741166 | 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-09 00:43:32.860844 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible==2.9.27.post0)
2026-03-09 00:43:32.866697 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2026-03-09 00:43:32.897779 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible==2.9.27.post0)
2026-03-09 00:43:32.903628 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2026-03-09 00:43:32.924912 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2026-03-09 00:43:32.971178 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 109.9 MB/s eta 0:00:00
2026-03-09 00:43:32.977770 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2026-03-09 00:43:32.989086 | 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-09 00:43:33.005376 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 54.6 MB/s eta 0:00:00
2026-03-09 00:43:33.010539 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2026-03-09 00:43:33.031417 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2026-03-09 00:43:33.042961 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2026-03-09 00:43:33.070613 | controller | Building wheels for collected packages: ansible
2026-03-09 00:43:33.071526 | controller |   Building wheel for ansible (setup.py): started
2026-03-09 00:43:43.279008 | controller |   Building wheel for ansible (setup.py): finished with status 'done'
2026-03-09 00:43:43.298764 | controller |   Created wheel for ansible: filename=ansible-2.9.27.post0-py3-none-any.whl size=16207514 sha256=d45e179248218ed0f854d2c8dcd3dd40483d4d5718c5236ba4cc4d50b04cc44a
2026-03-09 00:43:43.298995 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-3purskqd/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2026-03-09 00:43:43.381700 | controller | Successfully built ansible
2026-03-09 00:43:43.562145 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible
2026-03-09 00:43:57.860993 | 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-09 00:43:58.480281 | controller | ok: Runtime: 0:00:29.089875
2026-03-09 00:43:58.494938 | 
2026-03-09 00:43:58.495078 | TASK [Setup download-artifact-fork role]
2026-03-09 00:43:58.525232 | controller | ok
2026-03-09 00:43:58.558858 | 
2026-03-09 00:43:58.558953 | TASK [download-artifact-fork : Parse inventory response]
2026-03-09 00:43:58.612372 | controller | ok
2026-03-09 00:43:58.621581 | 
2026-03-09 00:43:58.621761 | TASK [download-artifact-fork : Fetch info from Zuul API]
2026-03-09 00:43:58.698494 | controller | skipping: Conditional result was False
2026-03-09 00:43:58.745002 | 
2026-03-09 00:43:58.745158 | TASK [download-artifact-fork : Parse build response]
2026-03-09 00:43:58.770637 | controller | skipping: Conditional result was False
2026-03-09 00:43:58.779437 | 
2026-03-09 00:43:58.779529 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2026-03-09 00:43:59.632001 | controller | changed
2026-03-09 00:43:59.646250 | 
2026-03-09 00:43:59.646441 | LOOP [download-artifact-fork : Download archive by type]
2026-03-09 00:43:59.675655 | controller | skipping: Conditional result was False
2026-03-09 00:43:59.676147 | 
2026-03-09 00:44:01.646064 | controller | changed: OK (20214 bytes)
2026-03-09 00:44:01.677736 | 
2026-03-09 00:44:01.677949 | TASK [deploy-artifacts : define __collections]
2026-03-09 00:44:01.718981 | controller | ok
2026-03-09 00:44:01.730777 | 
2026-03-09 00:44:01.730913 | LOOP [deploy-artifacts : Create list of collections]
2026-03-09 00:44:01.767161 | controller | skipping: Conditional result was False
2026-03-09 00:44:01.783403 | controller | ok:
2026-03-09 00:44:01.783519 | controller | {
2026-03-09 00:44:01.783552 | controller |   "branch": "main",
2026-03-09 00:44:01.783576 | controller |   "job": "build-ansible-collection",
2026-03-09 00:44:01.783599 | controller |   "metadata": {
2026-03-09 00:44:01.783621 | controller |     "type": "ansible_collection",
2026-03-09 00:44:01.783643 | controller |     "version": "5.0.0"
2026-03-09 00:44:01.783688 | controller |   },
2026-03-09 00:44:01.783719 | controller |   "name": "ansible.network",
2026-03-09 00:44:01.783741 | controller |   "project": "ansible-collections/ansible.network",
2026-03-09 00:44:01.783763 | controller |   "url": "https://22b1e0d4b8e70412cc4d-69e1c47a1811c463a459ff2268eea937.ssl.cf5.rackcdn.com/ansible/3d0fe746bbbd4310b179540d84ad3873/artifacts/ansible-network-5.0.0.tar.gz"
2026-03-09 00:44:01.783793 | controller | }
2026-03-09 00:44:01.795477 | 
2026-03-09 00:44:01.795634 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2026-03-09 00:44:02.900056 | controller | Process install dependency map
2026-03-09 00:44:45.089318 | controller | |/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-Starting collection install process
2026-03-09 00:44:45.190072 | controller | |Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2026-03-09 00:44:45.190134 | controller | Installing 'ansible.netcommon:8.4.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-03-09 00:44:46.897802 | controller | /-\|/-\|/-\|/-\|/Installing 'ansible.utils:6.0.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-03-09 00:44:48.523435 | controller | -\|/-\|/-\|/-\|/Installing 'arista.eos:12.0.1' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2026-03-09 00:44:51.036126 | controller | -\|/-\|/-\|/-\|/-\|/-\|/-Installing 'cisco.ios:11.3.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2026-03-09 00:44:53.647638 | controller | \|/-\|/-\|/-\|/-\|/-\|/-\|Installing 'cisco.iosxr:12.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2026-03-09 00:44:56.368126 | controller | /-\|/-\|/-\|/-\|/-\|/-\|/-\Installing 'cisco.nxos:11.1.3' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2026-03-09 00:45:00.322218 | controller | |/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-Installing 'junipernetworks.junos:11.1.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2026-03-09 00:45:02.456504 | controller | \|/-\|/-\|/-\|/-\|/-\Installing 'juniper.device:2.0.1' to '/home/zuul/.ansible/collections/ansible_collections/juniper/device'
2026-03-09 00:45:04.694555 | controller | ok: Runtime: 0:01:02.070490
2026-03-09 00:45:04.705834 | 
2026-03-09 00:45:04.705939 | TASK [podman may need iptables kernel modules]
2026-03-09 00:45:06.076288 | controller | ok: Runtime: 0:00:00.006131
2026-03-09 00:45:06.123522 | 
2026-03-09 00:45:06.123656 | PLAY RECAP
2026-03-09 00:45:06.123780 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2026-03-09 00:45:06.123828 | 
2026-03-09 00:45:06.256989 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-03-09 00:45:06.259458 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-03-09 00:45:06.883030 | 
2026-03-09 00:45:06.883140 | PLAY [controller]
2026-03-09 00:45:06.905142 | 
2026-03-09 00:45:06.905224 | TASK [Copy the galaxy.yml on the controller]
2026-03-09 00:45:10.672899 | controller | changed
2026-03-09 00:45:10.686809 | 
2026-03-09 00:45:10.686978 | TASK [Load information from galaxy.yml]
2026-03-09 00:45:10.739547 | controller | ok
2026-03-09 00:45:10.753124 | 
2026-03-09 00:45:10.753300 | LOOP [Enable FIPS mode]
2026-03-09 00:45:10.784938 | 
2026-03-09 00:45:10.785119 | LOOP [ansible-test : Include branch specific variables.]
2026-03-09 00:45:10.837849 | controller | ok: "/var/lib/zuul/builds/b56e83ae95be46a78f062d6828a0fe8d/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2026-03-09 00:45:10.838108 | 
2026-03-09 00:45:10.863055 | 
2026-03-09 00:45:10.863358 | TASK [ansible-test : Setup --skip-tags for test_options]
2026-03-09 00:45:10.924203 | controller | ok
2026-03-09 00:45:10.937143 | 
2026-03-09 00:45:10.937289 | TASK [ansible-test : Enable --inventory for network-integration]
2026-03-09 00:45:10.964718 | controller | skipping: Conditional result was False
2026-03-09 00:45:10.984032 | 
2026-03-09 00:45:10.984490 | TASK [ansible-test : Enable --retry-on-error]
2026-03-09 00:45:11.015125 | controller | skipping: Conditional result was False
2026-03-09 00:45:11.029783 | 
2026-03-09 00:45:11.029937 | TASK [ansible-test : Enable --continue-on-error]
2026-03-09 00:45:11.082470 | controller | ok
2026-03-09 00:45:11.095397 | 
2026-03-09 00:45:11.095540 | TASK [ansible-test : Enable --requirements]
2026-03-09 00:45:11.124164 | controller | skipping: Conditional result was False
2026-03-09 00:45:11.138061 | 
2026-03-09 00:45:11.138205 | TASK [ansible-test : Adjust options for unit tests]
2026-03-09 00:45:11.166866 | controller | skipping: Conditional result was False
2026-03-09 00:45:11.181904 | 
2026-03-09 00:45:11.182064 | TASK [ansible-test : Install requirements with unit tests]
2026-03-09 00:45:11.297982 | controller | ok
2026-03-09 00:45:11.304280 | 
2026-03-09 00:45:11.304347 | TASK [ansible-test : Set --skip-test]
2026-03-09 00:45:11.371716 | controller | skipping: Conditional result was False
2026-03-09 00:45:11.389222 | 
2026-03-09 00:45:11.389412 | TASK [ansible-test : Reduce verbosity]
2026-03-09 00:45:11.443743 | controller | ok
2026-03-09 00:45:11.457879 | 
2026-03-09 00:45:11.458019 | TASK [ansible-test : Setup --python option]
2026-03-09 00:45:11.487833 | controller | skipping: Conditional result was False
2026-03-09 00:45:11.503654 | 
2026-03-09 00:45:11.503843 | TASK [ansible-test : Setup --docker option]
2026-03-09 00:45:11.553903 | controller | ok
2026-03-09 00:45:11.570478 | 
2026-03-09 00:45:11.570617 | TASK [ansible-test : check if MANIFEST.json exists]
2026-03-09 00:45:12.617564 | controller | ok
2026-03-09 00:45:12.630834 | 
2026-03-09 00:45:12.630975 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2026-03-09 00:45:12.658939 | controller | skipping: Conditional result was False
2026-03-09 00:45:12.676505 | 
2026-03-09 00:45:12.676903 | TASK [ansible-test : Load information from MANIFEST.json]
2026-03-09 00:45:12.705247 | controller | skipping: Conditional result was False
2026-03-09 00:45:12.722068 | 
2026-03-09 00:45:12.722252 | TASK [ansible-test : Setup location of project for integration tests]
2026-03-09 00:45:12.740874 | controller | skipping: Conditional result was False
2026-03-09 00:45:12.757358 | 
2026-03-09 00:45:12.757512 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2026-03-09 00:45:14.028711 | controller | ok
2026-03-09 00:45:14.041051 | 
2026-03-09 00:45:14.041193 | TASK [ansible-test : Load information from galaxy.yml]
2026-03-09 00:45:14.081977 | controller | ok
2026-03-09 00:45:14.095855 | 
2026-03-09 00:45:14.096000 | TASK [ansible-test : Setup location of project for integration tests]
2026-03-09 00:45:14.130469 | controller | ok
2026-03-09 00:45:14.137929 | 
2026-03-09 00:45:14.138023 | TASK [ansible-test : Setup minimum test requirements]
2026-03-09 00:45:14.181188 | controller | ok
2026-03-09 00:45:14.188695 | 
2026-03-09 00:45:14.188782 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2026-03-09 00:45:15.125496 | controller | ok
2026-03-09 00:45:15.139396 | 
2026-03-09 00:45:15.139536 | TASK [ansible-test : Install python requirements]
2026-03-09 00:45:16.665972 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-03-09 00:45:16.681621 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2026-03-09 00:45:16.715733 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-03-09 00:45:16.720792 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2026-03-09 00:45:16.730587 | 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-09 00:45:16.737292 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2026-03-09 00:45:16.750699 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2026-03-09 00:45:16.814364 | controller | Installing collected packages: pathspec, yamllint
2026-03-09 00:45:16.903197 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2026-03-09 00:45:17.529850 | controller | ok: Runtime: 0:00:00.979474
2026-03-09 00:45:17.543550 | 
2026-03-09 00:45:17.543736 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2026-03-09 00:45:18.380096 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2026-03-09 00:45:18.936164 | controller | ERROR
2026-03-09 00:45:18.936508 | controller | {
2026-03-09 00:45:18.936572 | controller |   "delta": "0:00:00.006797",
2026-03-09 00:45:18.936614 | controller |   "end": "2026-03-09 00:45:18.380365",
2026-03-09 00:45:18.936651 | controller |   "msg": "non-zero return code",
2026-03-09 00:45:18.936768 | controller |   "rc": 1,
2026-03-09 00:45:18.936817 | controller |   "start": "2026-03-09 00:45:18.373568"
2026-03-09 00:45:18.936870 | controller | }
2026-03-09 00:45:18.936947 | controller | ERROR: Ignoring Errors
2026-03-09 00:45:18.950853 | 
2026-03-09 00:45:18.951060 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2026-03-09 00:45:19.344973 | controller | skipping: Conditional result was False
2026-03-09 00:45:19.361598 | 
2026-03-09 00:45:19.361893 | TASK [ansible-test : Enable persistent connection logging]
2026-03-09 00:45:20.721972 | controller | changed: section and option added
2026-03-09 00:45:20.735849 | 
2026-03-09 00:45:20.736009 | TASK [ansible-test : Set persistent ssh_type value]
2026-03-09 00:45:21.684551 | controller | changed: option added
2026-03-09 00:45:21.697436 | 
2026-03-09 00:45:21.697570 | TASK [ansible-test : Set ansible log path]
2026-03-09 00:45:23.381492 | controller | changed: section and option added
2026-03-09 00:45:23.392576 | 
2026-03-09 00:45:23.392712 | TASK [ansible-test : Identify the changed targets]
2026-03-09 00:45:23.769807 | controller | skipping: Conditional result was False
2026-03-09 00:45:23.776751 | 
2026-03-09 00:45:23.776823 | TASK [ansible-test : set_fact]
2026-03-09 00:45:23.861731 | controller | skipping: Conditional result was False
2026-03-09 00:45:23.868236 | 
2026-03-09 00:45:23.868303 | TASK [ansible-test : Set the targets]
2026-03-09 00:45:23.948003 | controller | ok
2026-03-09 00:45:23.954065 | 
2026-03-09 00:45:23.954132 | TASK [ansible-test : set_fact]
2026-03-09 00:45:23.980518 | controller | skipping: Conditional result was False
2026-03-09 00:45:23.992431 | 
2026-03-09 00:45:23.992527 | TASK [ansible-test : List the targets]
2026-03-09 00:45:24.368379 | controller | skipping: Conditional result was False
2026-03-09 00:45:24.382896 | 
2026-03-09 00:45:24.383040 | TASK [ansible-test : set_fact]
2026-03-09 00:45:24.410482 | controller | skipping: Conditional result was False
2026-03-09 00:45:24.425354 | 
2026-03-09 00:45:24.425530 | TASK [ansible-test : set_fact]
2026-03-09 00:45:24.452638 | controller | skipping: Conditional result was False
2026-03-09 00:45:24.467224 | 
2026-03-09 00:45:24.467362 | LOOP [ansible-test : set_fact]
2026-03-09 00:45:24.551958 | 
2026-03-09 00:45:24.552283 | TASK [ansible-test : set_fact]
2026-03-09 00:45:24.581297 | controller | skipping: Conditional result was False
2026-03-09 00:45:24.597850 | 
2026-03-09 00:45:24.598020 | TASK [ansible-test : debug]
2026-03-09 00:45:24.626385 | controller | skipping: Conditional result was False
2026-03-09 00:45:24.645329 | 
2026-03-09 00:45:24.645505 | TASK [ansible-test : debug]
2026-03-09 00:45:24.698351 | About to run: ansible-test sanity --requirements --docker -v
2026-03-09 00:45:24.709522 | 
2026-03-09 00:45:24.709617 | TASK [ansible-test : Run the test suite]
2026-03-09 00:45:26.591009 | controller | Run command: docker images quay.io/ansible/default-test-container:1.10.1 --format '{{json .}}'
2026-03-09 00:45:26.629854 | controller | Run command: docker pull quay.io/ansible/default-test-container:1.10.1
2026-03-09 00:45:28.289337 | controller | 1.10.1: Pulling from ansible/default-test-container
2026-03-09 00:45:29.061130 | 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-09 00:45:29.061221 | controller | 5667fdb72017: Pulling fs layer
2026-03-09 00:45:29.061299 | controller | d83811f270d5: Pulling fs layer
2026-03-09 00:45:29.061311 | controller | ee671aafb583: Pulling fs layer
2026-03-09 00:45:29.061321 | controller | 7fc152dfb3a6: Pulling fs layer
2026-03-09 00:45:29.061357 | controller | 3fb967a6fd03: Pulling fs layer
2026-03-09 00:45:29.061365 | controller | b3e63023ca5f: Pulling fs layer
2026-03-09 00:45:29.061371 | controller | 653cb3e65dbf: Pulling fs layer
2026-03-09 00:45:29.061377 | controller | f4712b586375: Pulling fs layer
2026-03-09 00:45:29.061383 | controller | d696ed7812f8: Pulling fs layer
2026-03-09 00:45:29.061389 | controller | 29bf28764f14: Pulling fs layer
2026-03-09 00:45:29.061395 | controller | 47d7edb3c2b7: Pulling fs layer
2026-03-09 00:45:29.061401 | controller | 07550ad4f6e4: Pulling fs layer
2026-03-09 00:45:29.061408 | controller | 7e79526e64c5: Pulling fs layer
2026-03-09 00:45:29.061437 | controller | 4fffb32882b7: Pulling fs layer
2026-03-09 00:45:29.061443 | controller | fc7a38b06f4e: Pulling fs layer
2026-03-09 00:45:29.061450 | controller | 6d9d912e060e: Pulling fs layer
2026-03-09 00:45:29.061456 | controller | 5dd542dd28d9: Pulling fs layer
2026-03-09 00:45:29.061462 | controller | cef8beb99552: Pulling fs layer
2026-03-09 00:45:29.061469 | controller | bd5557e101ad: Pulling fs layer
2026-03-09 00:45:29.061475 | controller | e057c20fabdd: Pulling fs layer
2026-03-09 00:45:29.061484 | controller | d16059cdae2e: Pulling fs layer
2026-03-09 00:45:29.061490 | controller | 431e42d906a3: Pulling fs layer
2026-03-09 00:45:29.061521 | controller | 7a0fd3b1d7c3: Pulling fs layer
2026-03-09 00:45:29.061528 | controller | aacdb6db0c8c: Pulling fs layer
2026-03-09 00:45:29.061534 | controller | 2e7f04ae8c8b: Pulling fs layer
2026-03-09 00:45:29.061540 | controller | 4cd343027a44: Pulling fs layer
2026-03-09 00:45:29.061546 | controller | 0b4379883f90: Pulling fs layer
2026-03-09 00:45:29.061552 | controller | 3d9d6d9dfc91: Pulling fs layer
2026-03-09 00:45:29.061561 | controller | 57d6314bd293: Pulling fs layer
2026-03-09 00:45:29.061566 | controller | 774036afeb7f: Pulling fs layer
2026-03-09 00:45:29.061572 | controller | 54b10f0e1645: Pulling fs layer
2026-03-09 00:45:29.061599 | controller | 78cf439d9fdb: Pulling fs layer
2026-03-09 00:45:29.061607 | controller | 36e5e20969c7: Pulling fs layer
2026-03-09 00:45:29.061613 | controller | 4b19ac3f17a8: Pulling fs layer
2026-03-09 00:45:29.061619 | controller | cef8beb99552: Waiting
2026-03-09 00:45:29.061625 | controller | bd5557e101ad: Waiting
2026-03-09 00:45:29.061634 | controller | e057c20fabdd: Waiting
2026-03-09 00:45:29.061640 | controller | d16059cdae2e: Waiting
2026-03-09 00:45:29.061645 | controller | 431e42d906a3: Waiting
2026-03-09 00:45:29.061651 | controller | 7a0fd3b1d7c3: Waiting
2026-03-09 00:45:29.061657 | controller | aacdb6db0c8c: Waiting
2026-03-09 00:45:29.061684 | controller | 2e7f04ae8c8b: Waiting
2026-03-09 00:45:29.061691 | controller | 4cd343027a44: Waiting
2026-03-09 00:45:29.061697 | controller | 0b4379883f90: Waiting
2026-03-09 00:45:29.062296 | controller | 3d9d6d9dfc91: Waiting
2026-03-09 00:45:29.062305 | controller | 57d6314bd293: Waiting
2026-03-09 00:45:29.062311 | controller | 774036afeb7f: Waiting
2026-03-09 00:45:29.062317 | controller | 54b10f0e1645: Waiting
2026-03-09 00:45:29.062362 | controller | 78cf439d9fdb: Waiting
2026-03-09 00:45:29.062371 | controller | 36e5e20969c7: Waiting
2026-03-09 00:45:29.062377 | controller | 4b19ac3f17a8: Waiting
2026-03-09 00:45:29.062383 | controller | 7fc152dfb3a6: Waiting
2026-03-09 00:45:29.062389 | controller | 3fb967a6fd03: Waiting
2026-03-09 00:45:29.062395 | controller | b3e63023ca5f: Waiting
2026-03-09 00:45:29.062401 | controller | 653cb3e65dbf: Waiting
2026-03-09 00:45:29.062407 | controller | f4712b586375: Waiting
2026-03-09 00:45:29.062413 | controller | d696ed7812f8: Waiting
2026-03-09 00:45:29.062439 | controller | 29bf28764f14: Waiting
2026-03-09 00:45:29.062446 | controller | 47d7edb3c2b7: Waiting
2026-03-09 00:45:29.062452 | controller | 07550ad4f6e4: Waiting
2026-03-09 00:45:29.062460 | controller | 7e79526e64c5: Waiting
2026-03-09 00:45:29.062478 | controller | 4fffb32882b7: Waiting
2026-03-09 00:45:29.062485 | controller | fc7a38b06f4e: Waiting
2026-03-09 00:45:29.062491 | controller | 6d9d912e060e: Waiting
2026-03-09 00:45:29.062499 | controller | 5dd542dd28d9: Waiting
2026-03-09 00:45:29.062512 | controller | ee671aafb583: Verifying Checksum
2026-03-09 00:45:29.347934 | controller | ee671aafb583: Download complete
2026-03-09 00:45:29.348001 | controller | d83811f270d5: Verifying Checksum
2026-03-09 00:45:29.886313 | controller | d83811f270d5: Download complete
2026-03-09 00:45:29.886369 | controller | 7fc152dfb3a6: Verifying Checksum
2026-03-09 00:45:30.756976 | controller | 7fc152dfb3a6: Download complete
2026-03-09 00:45:30.757031 | controller | b3e63023ca5f: Verifying Checksum
2026-03-09 00:45:30.757092 | controller | b3e63023ca5f: Download complete
2026-03-09 00:45:31.546498 | controller | 653cb3e65dbf: Verifying Checksum
2026-03-09 00:45:35.439823 | controller | 653cb3e65dbf: Download complete
2026-03-09 00:45:35.439887 | controller | 5667fdb72017: Verifying Checksum
2026-03-09 00:45:36.252977 | controller | 5667fdb72017: Download complete
2026-03-09 00:45:36.253036 | controller | d696ed7812f8: Verifying Checksum
2026-03-09 00:45:36.905869 | controller | d696ed7812f8: Download complete
2026-03-09 00:45:36.905965 | controller | 3fb967a6fd03: Verifying Checksum
2026-03-09 00:45:36.907923 | controller | 3fb967a6fd03: Download complete
2026-03-09 00:45:37.083877 | controller | 29bf28764f14: Verifying Checksum
2026-03-09 00:45:37.968672 | controller | 29bf28764f14: Download complete
2026-03-09 00:45:37.968737 | controller | 07550ad4f6e4: Verifying Checksum
2026-03-09 00:45:38.047567 | controller | 07550ad4f6e4: Download complete
2026-03-09 00:45:38.047715 | controller | 47d7edb3c2b7: Verifying Checksum
2026-03-09 00:45:38.049575 | controller | 47d7edb3c2b7: Download complete
2026-03-09 00:45:38.049624 | controller | 5667fdb72017: Pull complete
2026-03-09 00:45:38.107939 | controller | d83811f270d5: Pull complete
2026-03-09 00:45:38.149903 | controller | ee671aafb583: Pull complete
2026-03-09 00:45:38.198939 | controller | 7fc152dfb3a6: Pull complete
2026-03-09 00:45:38.792118 | controller | 7e79526e64c5: Verifying Checksum
2026-03-09 00:45:38.887262 | controller | 7e79526e64c5: Download complete
2026-03-09 00:45:38.887319 | controller | 4fffb32882b7: Verifying Checksum
2026-03-09 00:45:39.366576 | controller | 4fffb32882b7: Download complete
2026-03-09 00:45:39.366642 | controller | 3fb967a6fd03: Pull complete
2026-03-09 00:45:39.397099 | controller | b3e63023ca5f: Pull complete
2026-03-09 00:45:39.444682 | controller | 653cb3e65dbf: Pull complete
2026-03-09 00:45:39.944470 | controller | 6d9d912e060e: Verifying Checksum
2026-03-09 00:45:40.178363 | controller | 6d9d912e060e: Download complete
2026-03-09 00:45:40.178426 | controller | fc7a38b06f4e: Verifying Checksum
2026-03-09 00:45:40.723086 | controller | fc7a38b06f4e: Download complete
2026-03-09 00:45:40.723146 | controller | 5dd542dd28d9: Verifying Checksum
2026-03-09 00:45:41.514646 | controller | 5dd542dd28d9: Download complete
2026-03-09 00:45:41.514706 | controller | bd5557e101ad: Verifying Checksum
2026-03-09 00:45:41.514751 | controller | bd5557e101ad: Download complete
2026-03-09 00:45:42.432361 | controller | e057c20fabdd: Verifying Checksum
2026-03-09 00:45:42.432414 | controller | e057c20fabdd: Download complete
2026-03-09 00:45:43.153873 | controller | d16059cdae2e: Verifying Checksum
2026-03-09 00:45:44.976849 | controller | d16059cdae2e: Download complete
2026-03-09 00:45:44.976930 | controller | 431e42d906a3: Verifying Checksum
2026-03-09 00:45:46.263575 | controller | 431e42d906a3: Download complete
2026-03-09 00:45:46.263680 | controller | 7a0fd3b1d7c3: Verifying Checksum
2026-03-09 00:45:46.263787 | controller | 7a0fd3b1d7c3: Download complete
2026-03-09 00:45:47.799903 | controller | aacdb6db0c8c: Verifying Checksum
2026-03-09 00:45:47.800085 | controller | aacdb6db0c8c: Download complete
2026-03-09 00:45:48.536487 | controller | 2e7f04ae8c8b: Download complete
2026-03-09 00:45:49.325033 | controller | 4cd343027a44: Download complete
2026-03-09 00:45:50.073152 | controller | 0b4379883f90: Verifying Checksum
2026-03-09 00:45:50.073281 | controller | 0b4379883f90: Download complete
2026-03-09 00:45:50.849380 | controller | 3d9d6d9dfc91: Verifying Checksum
2026-03-09 00:45:50.849475 | controller | 3d9d6d9dfc91: Download complete
2026-03-09 00:45:56.502627 | controller | f4712b586375: Verifying Checksum
2026-03-09 00:46:04.168405 | controller | f4712b586375: Download complete
2026-03-09 00:46:04.168535 | controller | 57d6314bd293: Verifying Checksum
2026-03-09 00:46:11.071054 | controller | 57d6314bd293: Download complete
2026-03-09 00:46:11.071140 | controller | cef8beb99552: Verifying Checksum
2026-03-09 00:46:14.578432 | controller | cef8beb99552: Download complete
2026-03-09 00:46:14.578490 | controller | 774036afeb7f: Verifying Checksum
2026-03-09 00:46:16.187892 | controller | 774036afeb7f: Download complete
2026-03-09 00:46:16.189363 | controller | 54b10f0e1645: Verifying Checksum
2026-03-09 00:46:16.189497 | controller | 54b10f0e1645: Download complete
2026-03-09 00:46:21.809564 | controller | f4712b586375: Pull complete
2026-03-09 00:46:21.899597 | controller | d696ed7812f8: Pull complete
2026-03-09 00:46:21.932765 | controller | 29bf28764f14: Pull complete
2026-03-09 00:46:22.130871 | controller | 47d7edb3c2b7: Pull complete
2026-03-09 00:46:22.184891 | controller | 07550ad4f6e4: Pull complete
2026-03-09 00:46:22.255960 | controller | 7e79526e64c5: Pull complete
2026-03-09 00:46:22.307188 | controller | 4fffb32882b7: Pull complete
2026-03-09 00:46:22.395923 | controller | fc7a38b06f4e: Pull complete
2026-03-09 00:46:22.421907 | controller | 6d9d912e060e: Pull complete
2026-03-09 00:46:22.457123 | controller | 5dd542dd28d9: Pull complete
2026-03-09 00:46:24.234535 | controller | 78cf439d9fdb: Verifying Checksum
2026-03-09 00:46:24.989167 | controller | 78cf439d9fdb: Download complete
2026-03-09 00:46:24.989254 | controller | 4b19ac3f17a8: Verifying Checksum
2026-03-09 00:46:25.059899 | controller | 4b19ac3f17a8: Download complete
2026-03-09 00:46:25.059965 | controller | 36e5e20969c7: Verifying Checksum
2026-03-09 00:46:48.700159 | controller | 36e5e20969c7: Download complete
2026-03-09 00:46:48.700291 | controller | cef8beb99552: Pull complete
2026-03-09 00:46:48.742868 | controller | bd5557e101ad: Pull complete
2026-03-09 00:46:48.771065 | controller | e057c20fabdd: Pull complete
2026-03-09 00:46:48.805024 | controller | d16059cdae2e: Pull complete
2026-03-09 00:46:50.911166 | controller | 431e42d906a3: Pull complete
2026-03-09 00:46:50.973894 | controller | 7a0fd3b1d7c3: Pull complete
2026-03-09 00:46:51.040416 | controller | aacdb6db0c8c: Pull complete
2026-03-09 00:46:51.080186 | controller | 2e7f04ae8c8b: Pull complete
2026-03-09 00:46:51.129167 | controller | 4cd343027a44: Pull complete
2026-03-09 00:46:51.183201 | controller | 0b4379883f90: Pull complete
2026-03-09 00:46:51.233553 | controller | 3d9d6d9dfc91: Pull complete
2026-03-09 00:46:56.682860 | controller | 57d6314bd293: Pull complete
2026-03-09 00:47:19.102434 | controller | 774036afeb7f: Pull complete
2026-03-09 00:47:47.605865 | controller | 54b10f0e1645: Pull complete
2026-03-09 00:48:15.579310 | controller | 78cf439d9fdb: Pull complete
2026-03-09 00:48:43.413681 | controller | 36e5e20969c7: Pull complete
2026-03-09 00:49:13.721415 | controller | 4b19ac3f17a8: Pull complete
2026-03-09 00:49:13.741206 | controller | Digest: sha256:954c4edffb0905df64e23c8611b1c4a62db9af13062f51cad5b658e727238d41
2026-03-09 00:49:13.749524 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:1.10.1
2026-03-09 00:49:13.758065 | controller | quay.io/ansible/default-test-container:1.10.1
2026-03-09 00:49:13.762096 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2026-03-09 00:49:13.789593 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2026-03-09 00:49:15.136267 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2026-03-09 00:49:15.136616 | controller | 04a5f4cda3ee: Pulling fs layer
2026-03-09 00:49:15.136632 | controller | ff496a88c8ed: Pulling fs layer
2026-03-09 00:49:15.136646 | controller | 0ce83f459fe7: Pulling fs layer
2026-03-09 00:49:15.136665 | controller | 2e5170e1f099: Pulling fs layer
2026-03-09 00:49:15.136672 | controller | 7641eb41b08c: Pulling fs layer
2026-03-09 00:49:15.136679 | controller | ad15fa9da398: Pulling fs layer
2026-03-09 00:49:15.136687 | controller | 087d91352424: Pulling fs layer
2026-03-09 00:49:15.136788 | controller | 8b92efd6a100: Pulling fs layer
2026-03-09 00:49:15.136800 | controller | 7641eb41b08c: Waiting
2026-03-09 00:49:15.136806 | controller | ad15fa9da398: Waiting
2026-03-09 00:49:15.136812 | controller | 087d91352424: Waiting
2026-03-09 00:49:15.136821 | controller | 8b92efd6a100: Waiting
2026-03-09 00:49:15.139617 | controller | 2e5170e1f099: Waiting
2026-03-09 00:49:15.830982 | controller | ff496a88c8ed: Verifying Checksum
2026-03-09 00:49:15.968842 | controller | ff496a88c8ed: Download complete
2026-03-09 00:49:15.968914 | controller | 0ce83f459fe7: Verifying Checksum
2026-03-09 00:49:16.684808 | controller | 0ce83f459fe7: Download complete
2026-03-09 00:49:16.684871 | controller | 7641eb41b08c: Verifying Checksum
2026-03-09 00:49:17.529529 | controller | 7641eb41b08c: Download complete
2026-03-09 00:49:17.529589 | controller | ad15fa9da398: Verifying Checksum
2026-03-09 00:49:18.839361 | controller | ad15fa9da398: Download complete
2026-03-09 00:49:18.839435 | controller | 2e5170e1f099: Verifying Checksum
2026-03-09 00:49:18.839686 | controller | 2e5170e1f099: Download complete
2026-03-09 00:49:19.076290 | controller | 087d91352424: Verifying Checksum
2026-03-09 00:49:19.076381 | controller | 087d91352424: Download complete
2026-03-09 00:49:19.505224 | controller | 8b92efd6a100: Verifying Checksum
2026-03-09 00:49:19.505377 | controller | 8b92efd6a100: Download complete
2026-03-09 00:49:19.685213 | controller | 04a5f4cda3ee: Verifying Checksum
2026-03-09 00:49:19.685343 | controller | 04a5f4cda3ee: Download complete
2026-03-09 00:49:21.685021 | controller | 04a5f4cda3ee: Pull complete
2026-03-09 00:49:21.736343 | controller | ff496a88c8ed: Pull complete
2026-03-09 00:49:21.793089 | controller | 0ce83f459fe7: Pull complete
2026-03-09 00:49:23.290285 | controller | 2e5170e1f099: Pull complete
2026-03-09 00:49:23.339408 | controller | 7641eb41b08c: Pull complete
2026-03-09 00:49:23.391438 | controller | ad15fa9da398: Pull complete
2026-03-09 00:49:24.403004 | controller | 087d91352424: Pull complete
2026-03-09 00:49:24.453642 | controller | 8b92efd6a100: Pull complete
2026-03-09 00:49:24.481876 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2026-03-09 00:49:24.489683 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2026-03-09 00:49:24.499535 | controller | quay.io/ansible/pypi-test-container:1.0.0
2026-03-09 00:49:24.501940 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2026-03-09 00:49:28.381391 | controller | Run command: docker inspect 1f4cecfc3d802aafebf23e3cdcf2051130b1da0ea440f6f5133c72196c75fa3f
2026-03-09 00:49:28.672548 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2026-03-09 00:49:28.693045 | controller | Including collection: ansible.netcommon (278 files)
2026-03-09 00:49:28.693132 | controller | Including collection: ansible.network (23 files)
2026-03-09 00:49:28.710749 | controller | Including collection: ansible.utils (441 files)
2026-03-09 00:49:28.774273 | controller | Including collection: arista.eos (1031 files)
2026-03-09 00:49:28.839126 | controller | Including collection: cisco.ios (1215 files)
2026-03-09 00:49:28.897248 | controller | Including collection: cisco.iosxr (1044 files)
2026-03-09 00:49:29.021433 | controller | Including collection: cisco.nxos (1687 files)
2026-03-09 00:49:29.040620 | controller | Including collection: juniper.device (391 files)
2026-03-09 00:49:29.073698 | controller | Including collection: junipernetworks.junos (700 files)
2026-03-09 00:49:29.123929 | controller | Creating a payload archive containing 8547 files...
2026-03-09 00:49:31.787138 | controller | Created a 7130558 byte payload archive containing 8547 files in 2 seconds.
2026-03-09 00:49:31.792912 | controller | Assuming Docker is available on localhost.
2026-03-09 00:49:31.793134 | 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-09 00:49:32.472525 | controller | Run command: docker exec -i 8e4e36189a2f02b45d7151e57b7b82de844746f0977fbe57e160510069441d94 dd of=/root/docker.sh bs=65536
2026-03-09 00:49:32.573675 | controller | Run command: docker exec 8e4e36189a2f02b45d7151e57b7b82de844746f0977fbe57e160510069441d94 /bin/bash /root/docker.sh
2026-03-09 00:49:32.673639 | controller | Run command: docker exec -i 8e4e36189a2f02b45d7151e57b7b82de844746f0977fbe57e160510069441d94 dd of=/root/ansible.tgz bs=65536
2026-03-09 00:49:32.795158 | controller | Run command: docker exec 8e4e36189a2f02b45d7151e57b7b82de844746f0977fbe57e160510069441d94 mkdir /root/ansible
2026-03-09 00:49:32.873412 | controller | Run command: docker exec 8e4e36189a2f02b45d7151e57b7b82de844746f0977fbe57e160510069441d94 tar oxzf /root/ansible.tgz -C /root/ansible
2026-03-09 00:49:34.128770 | controller | Run command: docker exec 8e4e36189a2f02b45d7151e57b7b82de844746f0977fbe57e160510069441d94 /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-lv6o_mhr.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2026-03-09 00:49:34.698553 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2026-03-09 00:49:34.698866 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2026-03-09 00:49:34.699339 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2026-03-09 00:49:34.701400 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2026-03-09 00:49:34.778727 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2026-03-09 00:49:34.782045 | controller | Read 0 sanity test ignore line(s) for Ansible 2.9 from: tests/sanity/ignore-2.9.txt
2026-03-09 00:49:34.790571 | controller | No tests applicable.
2026-03-09 00:49:34.790925 | controller | Running sanity test 'ansible-doc' with Python 3.6
2026-03-09 00:49:34.790944 | controller | No tests applicable.
2026-03-09 00:49:34.791274 | controller | Run command: /usr/bin/python2.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2026-03-09 00:49:34.899945 | controller | Running sanity test 'compile' with Python 2.6
2026-03-09 00:49:34.900114 | controller | No tests applicable.
2026-03-09 00:49:35.114357 | controller | Run command: /usr/bin/python2.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2026-03-09 00:49:35.114445 | controller | Running sanity test 'compile' with Python 2.7
2026-03-09 00:49:35.260506 | controller | No tests applicable.
2026-03-09 00:49:35.260569 | controller | Run command: /usr/bin/python3.5 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2026-03-09 00:49:35.260585 | controller | Running sanity test 'compile' with Python 3.5
2026-03-09 00:49:35.260847 | controller | No tests applicable.
2026-03-09 00:49:35.260929 | controller | Running sanity test 'compile' with Python 3.6
2026-03-09 00:49:35.261152 | controller | No tests applicable.
2026-03-09 00:49:35.261542 | controller | Run command: /usr/bin/python3.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2026-03-09 00:49:35.395571 | controller | Running sanity test 'compile' with Python 3.7
2026-03-09 00:49:35.395969 | controller | No tests applicable.
2026-03-09 00:49:35.395987 | controller | Run command: /usr/bin/python3.8 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2026-03-09 00:49:35.525737 | controller | Running sanity test 'compile' with Python 3.8
2026-03-09 00:49:35.526047 | controller | No tests applicable.
2026-03-09 00:49:35.526206 | controller | Running sanity test 'empty-init' with Python 3.6
2026-03-09 00:49:35.526453 | controller | No tests applicable.
2026-03-09 00:49:35.526587 | controller | Running sanity test 'future-import-boilerplate' with Python 3.6
2026-03-09 00:49:35.526793 | controller | No tests applicable.
2026-03-09 00:49:35.526874 | controller | Running sanity test 'ignores'
2026-03-09 00:49:35.528481 | 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-09 00:49:36.315892 | controller | Ignoring packaging: markers 'python_version < "3.6"' don't match your environment
2026-03-09 00:49:36.320706 | controller | Ignoring coverage: markers 'python_version > "3.7"' don't match your environment
2026-03-09 00:49:36.322148 | controller | Ignoring cryptography: markers 'python_version < "2.7"' don't match your environment
2026-03-09 00:49:36.324936 | controller | Ignoring deepdiff: markers 'python_version < "3"' don't match your environment
2026-03-09 00:49:36.328760 | controller | Ignoring jinja2: markers 'python_version < "2.7"' don't match your environment
2026-03-09 00:49:36.329874 | controller | Ignoring urllib3: markers 'python_version < "2.7"' don't match your environment
2026-03-09 00:49:36.333488 | controller | Ignoring sphinx: markers 'python_version < "2.7"' don't match your environment
2026-03-09 00:49:36.341173 | controller | Ignoring wheel: markers 'python_version < "2.7"' don't match your environment
2026-03-09 00:49:36.343059 | controller | Ignoring yamllint: markers 'python_version < "2.7"' don't match your environment
2026-03-09 00:49:36.348787 | controller | Ignoring paramiko: markers 'python_version < "2.7"' don't match your environment
2026-03-09 00:49:36.351479 | controller | Ignoring pytest: markers 'python_version < "2.7"' don't match your environment
2026-03-09 00:49:36.353162 | controller | Ignoring pytest: markers 'python_version == "2.7"' don't match your environment
2026-03-09 00:49:36.354631 | controller | Ignoring pytest-forked: markers 'python_version < "2.7"' don't match your environment
2026-03-09 00:49:36.358708 | controller | Ignoring requests: markers 'python_version < "2.7"' don't match your environment
2026-03-09 00:49:36.365800 | controller | Ignoring virtualenv: markers 'python_version < "2.7"' don't match your environment
2026-03-09 00:49:36.369945 | controller | Ignoring pathspec: markers 'python_version < "2.7"' don't match your environment
2026-03-09 00:49:36.371503 | controller | Ignoring pyopenssl: markers 'python_version < "2.7"' don't match your environment
2026-03-09 00:49:36.374039 | controller | Ignoring pyyaml: markers 'python_version < "2.7"' don't match your environment
2026-03-09 00:49:36.376548 | controller | Ignoring pycparser: markers 'python_version < "2.7"' don't match your environment
2026-03-09 00:49:36.382504 | controller | Ignoring xmltodict: markers 'python_version < "2.7"' don't match your environment
2026-03-09 00:49:36.384057 | controller | Ignoring lxml: markers 'python_version < "2.7"' don't match your environment
2026-03-09 00:49:36.385656 | controller | Ignoring pyvmomi: markers 'python_version < "2.7"' don't match your environment
2026-03-09 00:49:36.387988 | controller | Ignoring MarkupSafe: markers 'python_version < "3.6"' don't match your environment
2026-03-09 00:49:36.393287 | controller | Ignoring setuptools: markers 'python_version <= "2.7"' don't match your environment
2026-03-09 00:49:36.397383 | controller | Ignoring websocket-client: markers 'python_version < "3"' don't match your environment
2026-03-09 00:49:36.398823 | controller | Ignoring certifi: markers 'python_version < "3"' don't match your environment
2026-03-09 00:49:36.418401 | controller | Looking in indexes: http://172.17.0.2:3141/root/pypi/+simple/
2026-03-09 00:49:36.419708 | 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-09 00:49:36.447832 | 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-09 00:49:36.452856 | 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-09 00:49:36.456541 | 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-09 00:49:36.459317 | 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-09 00:49:36.468228 | 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-09 00:49:36.473656 | 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-09 00:49:36.477839 | 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-09 00:49:36.479959 | 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-09 00:49:36.499780 | 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-09 00:49:36.503564 | 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-09 00:49:36.506182 | 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-09 00:49:36.508197 | 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-09 00:49:36.510516 | 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-09 00:49:36.513177 | controller | Collecting isort==4.3.16 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 55))
2026-03-09 00:49:51.536978 | 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-09 00:50:07.054517 | 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-09 00:50:23.072191 | 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-09 00:50:40.090927 | 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-09 00:50:59.117304 | 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-09 00:51:14.129601 | 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-09 00:51:14.130387 | 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-09 00:51:14.251325 | 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-09 00:51:14.251493 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2026-03-09 00:51:14.251622 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2026-03-09 00:51:14.251722 | controller | Removing custom PyPI hosts entries: /etc/hosts
2026-03-09 00:51:14.293265 | controller | Run command: docker exec 8e4e36189a2f02b45d7151e57b7b82de844746f0977fbe57e160510069441d94 tar czf /root/output.tgz --exclude .tmp -C /root/ansible/ansible_collections/ansible/network/tests output
2026-03-09 00:51:14.377630 | controller | Run command: docker exec -i 8e4e36189a2f02b45d7151e57b7b82de844746f0977fbe57e160510069441d94 dd if=/root/output.tgz bs=65536
2026-03-09 00:51:14.469884 | controller | Run command: tar oxzf /tmp/ansible-result-vpuyv6ht.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2026-03-09 00:51:14.482622 | controller | Run command: docker rm -f 1f4cecfc3d802aafebf23e3cdcf2051130b1da0ea440f6f5133c72196c75fa3f
2026-03-09 00:51:14.746183 | controller | Run command: docker rm -f 8e4e36189a2f02b45d7151e57b7b82de844746f0977fbe57e160510069441d94
2026-03-09 00:51:15.502350 | controller | ERROR: Command "docker exec 8e4e36189a2f02b45d7151e57b7b82de844746f0977fbe57e160510069441d94 /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-lv6o_mhr.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2026-03-09 00:51:15.834042 | controller | ERROR
2026-03-09 00:51:15.834256 | controller | {
2026-03-09 00:51:15.834317 | controller |   "delta": "0:05:49.585610",
2026-03-09 00:51:15.834359 | controller |   "end": "2026-03-09 00:51:15.560217",
2026-03-09 00:51:15.834397 | controller |   "msg": "non-zero return code",
2026-03-09 00:51:15.834444 | controller |   "rc": 1,
2026-03-09 00:51:15.834481 | controller |   "start": "2026-03-09 00:45:25.974607"
2026-03-09 00:51:15.834516 | controller | }
failure
2026-03-09 00:51:15.841385 | 
2026-03-09 00:51:15.841498 | PLAY RECAP
2026-03-09 00:51:15.841586 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2026-03-09 00:51:15.841632 | 
2026-03-09 00:51:15.959359 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-03-09 00:51:15.960386 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-09 00:51:16.495474 | 
2026-03-09 00:51:16.495587 | PLAY [all]
2026-03-09 00:51:16.517310 | 
2026-03-09 00:51:16.517407 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-03-09 00:51:19.650322 | controller | changed: non-zero return code
2026-03-09 00:51:19.663175 | 
2026-03-09 00:51:19.663342 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-03-09 00:51:19.691085 | controller | skipping: Conditional result was False
2026-03-09 00:51:19.703867 | 
2026-03-09 00:51:19.704018 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-03-09 00:51:19.746597 | 
2026-03-09 00:51:19.747003 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-03-09 00:51:19.790527 | 
2026-03-09 00:51:19.790921 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-03-09 00:51:19.819523 | controller | skipping: Conditional result was False
2026-03-09 00:51:19.833518 | 
2026-03-09 00:51:19.833699 | LOOP [fetch-subunit-output : Generate subunit file]
2026-03-09 00:51:19.873966 | 
2026-03-09 00:51:19.874227 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-03-09 00:51:19.902264 | controller | skipping: Conditional result was False
2026-03-09 00:51:19.921211 | 
2026-03-09 00:51:19.921362 | TASK [fetch-subunit-output : Remove the temporary file]
2026-03-09 00:51:19.950031 | controller | skipping: Conditional result was False
2026-03-09 00:51:19.963313 | 
2026-03-09 00:51:19.963527 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-03-09 00:51:19.991899 | controller | skipping: Conditional result was False
2026-03-09 00:51:20.045639 | 
2026-03-09 00:51:20.045822 | PLAY RECAP
2026-03-09 00:51:20.045902 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-09 00:51:20.045940 | 
2026-03-09 00:51:20.154079 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-09 00:51:20.155010 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-03-09 00:51:20.806712 | 
2026-03-09 00:51:20.806833 | PLAY [all:!appliance*]
2026-03-09 00:51:20.828267 | 
2026-03-09 00:51:20.828348 | TASK [unregister the node]
2026-03-09 00:51:21.198777 | controller | skipping: Conditional result was False
2026-03-09 00:51:21.211979 | 
2026-03-09 00:51:21.212150 | TASK [include_role : fetch-output]
2026-03-09 00:51:21.261275 | controller | ok
2026-03-09 00:51:21.299862 | 
2026-03-09 00:51:21.299967 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-09 00:51:21.376262 | controller | skipping: Conditional result was False
2026-03-09 00:51:21.389865 | 
2026-03-09 00:51:21.390051 | TASK [fetch-output : Set log path for single node]
2026-03-09 00:51:21.449170 | controller | ok
2026-03-09 00:51:21.462112 | 
2026-03-09 00:51:21.462267 | LOOP [fetch-output : Ensure local output dirs]
2026-03-09 00:51:21.910450 | controller -> localhost | ok: "/var/lib/zuul/builds/b56e83ae95be46a78f062d6828a0fe8d/work/logs"
2026-03-09 00:51:22.158565 | controller -> localhost | changed: "/var/lib/zuul/builds/b56e83ae95be46a78f062d6828a0fe8d/work/artifacts"
2026-03-09 00:51:22.363459 | controller -> localhost | changed: "/var/lib/zuul/builds/b56e83ae95be46a78f062d6828a0fe8d/work/docs"
2026-03-09 00:51:22.373161 | 
2026-03-09 00:51:22.373281 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-09 00:51:24.746887 | controller | changed:
2026-03-09 00:51:24.747353 | controller | .d..t...... ./
2026-03-09 00:51:24.747424 | controller | cd+++++++++ controller/
2026-03-09 00:51:24.747499 | controller | changed: All items complete
2026-03-09 00:51:24.747546 | 
2026-03-09 00:51:26.906928 | controller | changed: .d..t...... ./
2026-03-09 00:51:29.103128 | controller | changed: .d..t...... ./
2026-03-09 00:51:29.139047 | 
2026-03-09 00:51:29.139198 | TASK [include_role : fetch-output-openshift]
2026-03-09 00:51:29.166420 | controller | skipping: Conditional result was False
2026-03-09 00:51:29.180910 | 
2026-03-09 00:51:29.181056 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-09 00:51:29.227296 | controller | skipping: Conditional result was False
2026-03-09 00:51:29.238712 | controller | skipping: Conditional result was False
2026-03-09 00:51:29.276777 | 
2026-03-09 00:51:29.276938 | PLAY [localhost]
2026-03-09 00:51:29.294269 | 
2026-03-09 00:51:29.294434 | TASK [Run Zuul manifest role]
2026-03-09 00:51:29.315016 | localhost | ok
2026-03-09 00:51:29.332247 | 
2026-03-09 00:51:29.332333 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-09 00:51:29.771837 | localhost | changed
2026-03-09 00:51:29.778073 | 
2026-03-09 00:51:29.778140 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-09 00:51:29.817272 | localhost | ok
2026-03-09 00:51:29.861276 | 
2026-03-09 00:51:29.861442 | TASK [Set zuul-log-path fact]
2026-03-09 00:51:29.882201 | localhost | ok
2026-03-09 00:51:29.897638 | 
2026-03-09 00:51:29.897726 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-09 00:51:29.935984 | localhost | ok
2026-03-09 00:51:29.945269 | 
2026-03-09 00:51:29.945418 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)]
2026-03-09 00:51:29.984825 | localhost | ok:
2026-03-09 00:51:29.984988 | localhost | {
2026-03-09 00:51:29.985013 | localhost |   "auth": {
2026-03-09 00:51:29.985033 | localhost |     "password": "IonWCwcuOFib&&<}wp^:[2G/",
2026-03-09 00:51:29.985051 | localhost |     "username": "9LF91O0RK7JU"
2026-03-09 00:51:29.985067 | localhost |   },
2026-03-09 00:51:29.985082 | localhost |   "profile": "rackspace",
2026-03-09 00:51:29.985097 | localhost |   "project_name": "1251317",
2026-03-09 00:51:29.985112 | localhost |   "region_name": "DFW"
2026-03-09 00:51:29.985127 | localhost | }
2026-03-09 00:51:29.985151 | 
2026-03-09 00:51:29.996753 | 
2026-03-09 00:51:29.996842 | TASK [Run upload-logs-failover role (S3 then Swift)]
2026-03-09 00:51:30.025271 | localhost | Output suppressed because no_log was given
2026-03-09 00:51:30.038894 | 
2026-03-09 00:51:30.038964 | TASK [upload-logs-failover : Initialize control vars]
2026-03-09 00:51:30.068571 | localhost | ok
2026-03-09 00:51:30.072991 | 
2026-03-09 00:51:30.073055 | LOOP [upload-logs-failover : Perform log upload]
2026-03-09 00:51:30.118571 | localhost | Output suppressed because no_log was given
2026-03-09 00:51:30.125803 | localhost | included: /var/lib/zuul/builds/b56e83ae95be46a78f062d6828a0fe8d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml
2026-03-09 00:51:30.132787 | 
2026-03-09 00:51:30.132865 | LOOP [upload-logs-failover : Unpack vars for swift upload]
2026-03-09 00:51:30.241354 | localhost | Output suppressed because no_log was given
2026-03-09 00:51:30.256315 | 
2026-03-09 00:51:30.256469 | TASK [Upload logs to swift]
2026-03-09 00:51:30.293232 | localhost | ok
2026-03-09 00:51:30.339846 | 
2026-03-09 00:51:30.339996 | TASK [Set zuul-log-path fact]
2026-03-09 00:51:30.375812 | localhost | skipping: Conditional result was False
2026-03-09 00:51:30.384024 | 
2026-03-09 00:51:30.384117 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-03-09 00:51:30.795306 | localhost -> localhost | ok: Runtime: 0:00:00.008640
2026-03-09 00:51:30.805469 | 
2026-03-09 00:51:30.805583 | TASK [upload-logs-swift : Upload logs to swift]