2026-01-02 00:07:48.370552 | Job console starting...
2026-01-02 00:07:48.378013 | Updating repositories
2026-01-02 00:07:48.505173 | Preparing job workspace
2026-01-02 00:07:53.558529 | Running Ansible setup...
2026-01-02 00:08:01.444842 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-02 00:08:02.134038 | 
2026-01-02 00:08:02.134158 | PLAY [localhost]
2026-01-02 00:08:02.142703 | 
2026-01-02 00:08:02.142785 | TASK [Gathering Facts]
2026-01-02 00:08:03.163637 | localhost | ok
2026-01-02 00:08:03.201741 | 
2026-01-02 00:08:03.201933 | TASK [Setup log path fact]
2026-01-02 00:08:03.227080 | localhost | ok
2026-01-02 00:08:03.251236 | 
2026-01-02 00:08:03.251359 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-02 00:08:03.297032 | localhost | ok
2026-01-02 00:08:03.315118 | 
2026-01-02 00:08:03.315284 | TASK [emit-job-header : Print job information]
2026-01-02 00:08:03.368286 | # Job Information
2026-01-02 00:08:03.368467 | Ansible Version: 2.15.12
2026-01-02 00:08:03.368511 | Job: ansible-test-sanity-docker-stable-2.9
2026-01-02 00:08:03.368541 | Pipeline: periodic
2026-01-02 00:08:03.368568 | Executor: ze04.softwarefactory-project.io
2026-01-02 00:08:03.368594 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-01-02 00:08:03.368623 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/c7b/ansible/c7b2158372ef42a1a475ba3a717451c6/
2026-01-02 00:08:03.368650 | Event ID: c804f0f0b15c4818b5153ddc52d4da2c
2026-01-02 00:08:03.373808 | 
2026-01-02 00:08:03.373894 | LOOP [emit-job-header : Print node information]
2026-01-02 00:08:03.491160 | localhost | ok:
2026-01-02 00:08:03.491499 | localhost | # Node Information
2026-01-02 00:08:03.491586 | localhost | Inventory Hostname: controller
2026-01-02 00:08:03.491650 | localhost | Hostname: np0005573263
2026-01-02 00:08:03.491772 | localhost | Username: zuul
2026-01-02 00:08:03.491865 | localhost | Distro: Fedora 37
2026-01-02 00:08:03.491955 | localhost | Provider: ansible-vexxhost-ams1
2026-01-02 00:08:03.492021 | localhost | Region: ams1
2026-01-02 00:08:03.492076 | localhost | Label: ansible-fedora-37-1vcpu
2026-01-02 00:08:03.492131 | localhost | Product Name: OpenStack Nova
2026-01-02 00:08:03.492195 | localhost | Interface IP: 38.129.16.154
2026-01-02 00:08:03.501835 | 
2026-01-02 00:08:03.502005 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-02 00:08:03.895297 | localhost -> localhost | changed
2026-01-02 00:08:03.907917 | 
2026-01-02 00:08:03.908063 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-02 00:08:04.804830 | localhost -> localhost | changed
2026-01-02 00:08:04.827917 | 
2026-01-02 00:08:04.827998 | PLAY [all:!appliance*]
2026-01-02 00:08:04.848636 | 
2026-01-02 00:08:04.848782 | TASK [include_role : start-zuul-console]
2026-01-02 00:08:04.869529 | controller | ok
2026-01-02 00:08:04.884815 | 
2026-01-02 00:08:04.884912 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-01-02 00:08:06.001920 | controller | ok
2026-01-02 00:08:06.027651 | 
2026-01-02 00:08:06.027917 | TASK [use-our-mirror : Retrieve the IP address]
2026-01-02 00:08:08.026290 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-01-02 00:08:08.041657 | 
2026-01-02 00:08:08.041821 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-01-02 00:08:08.418327 | controller | skipping: Conditional result was False
2026-01-02 00:08:08.432873 | 
2026-01-02 00:08:08.433028 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-01-02 00:08:08.471235 | controller | skipping: Conditional result was False
2026-01-02 00:08:08.489632 | 
2026-01-02 00:08:08.489934 | TASK [use-our-mirror : Create the podman configuration directory]
2026-01-02 00:08:08.521751 | controller | skipping: Conditional result was False
2026-01-02 00:08:08.545860 | 
2026-01-02 00:08:08.546095 | TASK [use-our-mirror : Copy the podman configuration]
2026-01-02 00:08:08.575123 | controller | skipping: Conditional result was False
2026-01-02 00:08:08.586002 | 
2026-01-02 00:08:08.586105 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-01-02 00:08:08.611631 | controller | skipping: Conditional result was False
2026-01-02 00:08:08.622227 | 
2026-01-02 00:08:08.622383 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-01-02 00:08:08.647938 | controller | skipping: Conditional result was False
2026-01-02 00:08:08.664387 | 
2026-01-02 00:08:08.664477 | TASK [Disable Fedora Modular]
2026-01-02 00:08:09.963936 | controller | changed
2026-01-02 00:08:09.978720 | 
2026-01-02 00:08:09.978861 | TASK [Enable EPEL]
2026-01-02 00:08:10.009005 | controller | skipping: Conditional result was False
2026-01-02 00:08:10.026045 | 
2026-01-02 00:08:10.026225 | TASK [Register the RHEL node]
2026-01-02 00:08:10.445918 | 
2026-01-02 00:08:10.446193 | TASK [Show the subscription-manager status]
2026-01-02 00:08:10.827355 | controller | skipping: Conditional result was False
2026-01-02 00:08:10.841565 | 
2026-01-02 00:08:10.841731 | TASK [Enable EPEL on RHEL]
2026-01-02 00:08:11.258980 | controller | skipping: Conditional result was False
2026-01-02 00:08:11.272809 | 
2026-01-02 00:08:11.273001 | TASK [Install git and tox]
2026-01-02 00:10:29.698089 | controller | changed
2026-01-02 00:10:29.706590 | 
2026-01-02 00:10:29.706672 | TASK [include_role : prepare-workspace]
2026-01-02 00:10:29.741143 | controller | ok
2026-01-02 00:10:29.771774 | 
2026-01-02 00:10:29.771882 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-01-02 00:10:30.638488 | controller | ok
2026-01-02 00:10:30.646831 | 
2026-01-02 00:10:30.646907 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-01-02 00:10:51.593226 | controller | Output suppressed because no_log was given
2026-01-02 00:10:51.608268 | 
2026-01-02 00:10:51.608348 | TASK [include_role : prepare-workspace-openshift]
2026-01-02 00:10:51.628568 | controller | skipping: Conditional result was False
2026-01-02 00:10:51.660384 | 
2026-01-02 00:10:51.660461 | PLAY [all:!appliance]
2026-01-02 00:10:51.683893 | 
2026-01-02 00:10:51.683995 | TASK [Run add-build-sshkey role (RSA)]
2026-01-02 00:10:51.724858 | controller | ok
2026-01-02 00:10:51.739787 | 
2026-01-02 00:10:51.739866 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-02 00:10:51.997381 | controller -> localhost | ok
2026-01-02 00:10:52.005062 | 
2026-01-02 00:10:52.005166 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-02 00:10:52.038999 | controller | ok
2026-01-02 00:10:52.060483 | controller | included: /var/lib/zuul/builds/c7b2158372ef42a1a475ba3a717451c6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-02 00:10:52.069207 | 
2026-01-02 00:10:52.069295 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-02 00:10:52.672819 | controller -> localhost | Generating public/private rsa key pair.
2026-01-02 00:10:52.673163 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/c7b2158372ef42a1a475ba3a717451c6/work/c7b2158372ef42a1a475ba3a717451c6_id_rsa.
2026-01-02 00:10:52.673197 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/c7b2158372ef42a1a475ba3a717451c6/work/c7b2158372ef42a1a475ba3a717451c6_id_rsa.pub.
2026-01-02 00:10:52.673219 | controller -> localhost | The key fingerprint is:
2026-01-02 00:10:52.673238 | controller -> localhost | SHA256:VaW5SFcdPFQC+IOVz5+mW6eDyxzbkOnJ7F1gGg3+lBg zuul-build-sshkey
2026-01-02 00:10:52.673258 | controller -> localhost | The key's randomart image is:
2026-01-02 00:10:52.673277 | controller -> localhost | +---[RSA 2048]----+
2026-01-02 00:10:52.673295 | controller -> localhost | |           .o+*++|
2026-01-02 00:10:52.673314 | controller -> localhost | |          ..o+ +.|
2026-01-02 00:10:52.673332 | controller -> localhost | |          o+Eo  .|
2026-01-02 00:10:52.673350 | controller -> localhost | |         o.+o*o. |
2026-01-02 00:10:52.673368 | controller -> localhost | |        S . =.*..|
2026-01-02 00:10:52.673395 | controller -> localhost | |             O +.|
2026-01-02 00:10:52.673417 | controller -> localhost | |            *.+.o|
2026-01-02 00:10:52.673436 | controller -> localhost | |           *.Ooo.|
2026-01-02 00:10:52.673455 | controller -> localhost | |           .@o=. |
2026-01-02 00:10:52.673475 | controller -> localhost | +----[SHA256]-----+
2026-01-02 00:10:52.673518 | controller -> localhost | ok: Runtime: 0:00:00.154275
2026-01-02 00:10:52.680060 | 
2026-01-02 00:10:52.680147 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-02 00:10:52.714108 | controller | ok
2026-01-02 00:10:52.724416 | controller | included: /var/lib/zuul/builds/c7b2158372ef42a1a475ba3a717451c6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-02 00:10:52.735793 | 
2026-01-02 00:10:52.735952 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-02 00:10:52.760349 | controller | skipping: Conditional result was False
2026-01-02 00:10:52.766978 | 
2026-01-02 00:10:52.767060 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-02 00:10:53.823692 | controller | changed
2026-01-02 00:10:53.829866 | 
2026-01-02 00:10:53.829931 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-02 00:10:54.483594 | controller | ok
2026-01-02 00:10:54.492059 | 
2026-01-02 00:10:54.492210 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-02 00:10:57.509277 | controller | changed
2026-01-02 00:10:57.521117 | 
2026-01-02 00:10:57.521217 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-02 00:11:00.580381 | controller | changed
2026-01-02 00:11:00.594027 | 
2026-01-02 00:11:00.594178 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-02 00:11:00.633929 | controller | skipping: Conditional result was False
2026-01-02 00:11:00.649514 | 
2026-01-02 00:11:00.649665 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-02 00:11:01.074984 | controller -> localhost | changed
2026-01-02 00:11:01.087951 | 
2026-01-02 00:11:01.088033 | TASK [add-build-sshkey : Add back temp key]
2026-01-02 00:11:01.453715 | controller -> localhost | Identity added: /var/lib/zuul/builds/c7b2158372ef42a1a475ba3a717451c6/work/c7b2158372ef42a1a475ba3a717451c6_id_rsa (zuul-build-sshkey)
2026-01-02 00:11:01.454019 | controller -> localhost | ok: Runtime: 0:00:00.009214
2026-01-02 00:11:01.464281 | 
2026-01-02 00:11:01.464434 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-02 00:11:02.400977 | controller | ok
2026-01-02 00:11:02.407171 | 
2026-01-02 00:11:02.407246 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-02 00:11:02.432056 | controller | skipping: Conditional result was False
2026-01-02 00:11:02.461487 | 
2026-01-02 00:11:02.461673 | TASK [Run add-build-sshkey role (ECDSA)]
2026-01-02 00:11:02.495777 | controller | ok
2026-01-02 00:11:02.518948 | 
2026-01-02 00:11:02.519040 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-02 00:11:02.819705 | controller -> localhost | ok
2026-01-02 00:11:02.834464 | 
2026-01-02 00:11:02.834606 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-02 00:11:02.876629 | controller | ok
2026-01-02 00:11:02.901429 | controller | included: /var/lib/zuul/builds/c7b2158372ef42a1a475ba3a717451c6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-02 00:11:02.910731 | 
2026-01-02 00:11:02.910825 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-02 00:11:03.221170 | controller -> localhost | Generating public/private ecdsa key pair.
2026-01-02 00:11:03.221433 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/c7b2158372ef42a1a475ba3a717451c6/work/c7b2158372ef42a1a475ba3a717451c6_id_ecdsa.
2026-01-02 00:11:03.221464 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/c7b2158372ef42a1a475ba3a717451c6/work/c7b2158372ef42a1a475ba3a717451c6_id_ecdsa.pub.
2026-01-02 00:11:03.221500 | controller -> localhost | The key fingerprint is:
2026-01-02 00:11:03.221522 | controller -> localhost | SHA256:jneGGCLrbKpNxeJWxAzNLs3cw0Ddk6G22JxQdkM7nUA zuul-build-sshkey
2026-01-02 00:11:03.221544 | controller -> localhost | The key's randomart image is:
2026-01-02 00:11:03.221565 | controller -> localhost | +---[ECDSA 521]---+
2026-01-02 00:11:03.221584 | controller -> localhost | |  .+..o+Eo       |
2026-01-02 00:11:03.221603 | controller -> localhost | |   ++o.o+= .     |
2026-01-02 00:11:03.221621 | controller -> localhost | |   ==+o o.o      |
2026-01-02 00:11:03.221640 | controller -> localhost | |  .o=*+o .       |
2026-01-02 00:11:03.221659 | controller -> localhost | |  o.* *.S        |
2026-01-02 00:11:03.221699 | controller -> localhost | | . * . = .       |
2026-01-02 00:11:03.221723 | controller -> localhost | |  =   o + o      |
2026-01-02 00:11:03.221742 | controller -> localhost | | *.    . o       |
2026-01-02 00:11:03.221766 | controller -> localhost | |+o+              |
2026-01-02 00:11:03.221787 | controller -> localhost | +----[SHA256]-----+
2026-01-02 00:11:03.221843 | controller -> localhost | ok: Runtime: 0:00:00.015092
2026-01-02 00:11:03.229375 | 
2026-01-02 00:11:03.229440 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-02 00:11:03.267147 | controller | ok
2026-01-02 00:11:03.282180 | controller | included: /var/lib/zuul/builds/c7b2158372ef42a1a475ba3a717451c6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-02 00:11:03.296122 | 
2026-01-02 00:11:03.296223 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-02 00:11:03.324520 | controller | skipping: Conditional result was False
2026-01-02 00:11:03.340482 | 
2026-01-02 00:11:03.340629 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-02 00:11:04.249083 | controller | changed
2026-01-02 00:11:04.266558 | 
2026-01-02 00:11:04.266869 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-02 00:11:04.893032 | controller | ok
2026-01-02 00:11:04.906934 | 
2026-01-02 00:11:04.907088 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-02 00:11:07.937698 | controller | changed
2026-01-02 00:11:07.950433 | 
2026-01-02 00:11:07.950576 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-02 00:11:11.099752 | controller | changed
2026-01-02 00:11:11.106762 | 
2026-01-02 00:11:11.106830 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-02 00:11:11.142236 | controller | skipping: Conditional result was False
2026-01-02 00:11:11.151588 | 
2026-01-02 00:11:11.151742 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-02 00:11:11.390555 | controller -> localhost | changed
2026-01-02 00:11:11.403075 | 
2026-01-02 00:11:11.403171 | TASK [add-build-sshkey : Add back temp key]
2026-01-02 00:11:11.653284 | controller -> localhost | Identity added: /var/lib/zuul/builds/c7b2158372ef42a1a475ba3a717451c6/work/c7b2158372ef42a1a475ba3a717451c6_id_ecdsa (zuul-build-sshkey)
2026-01-02 00:11:11.653491 | controller -> localhost | ok: Runtime: 0:00:00.007490
2026-01-02 00:11:11.660411 | 
2026-01-02 00:11:11.660479 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-02 00:11:12.364185 | controller | ok
2026-01-02 00:11:12.371632 | 
2026-01-02 00:11:12.371756 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-02 00:11:12.407121 | controller | skipping: Conditional result was False
2026-01-02 00:11:12.433006 | 
2026-01-02 00:11:12.433164 | TASK [include_role : remove-zuul-sshkey]
2026-01-02 00:11:12.458514 | controller | skipping: Conditional result was False
2026-01-02 00:11:12.465437 | 
2026-01-02 00:11:12.465507 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-02 00:11:13.124204 | controller | ok: "logs"
2026-01-02 00:11:13.124456 | controller | ok: All items complete
2026-01-02 00:11:13.124492 | 
2026-01-02 00:11:13.760049 | controller | ok: "artifacts"
2026-01-02 00:11:14.372289 | controller | ok: "docs"
2026-01-02 00:11:14.384491 | 
2026-01-02 00:11:14.384755 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-02 00:11:15.026544 | controller | changed: "logs"
2026-01-02 00:11:15.718899 | controller | changed: "artifacts"
2026-01-02 00:11:16.408664 | controller | changed: "docs"
2026-01-02 00:11:16.466194 | 
2026-01-02 00:11:16.466297 | PLAY RECAP
2026-01-02 00:11:16.466355 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-01-02 00:11:16.466390 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-02 00:11:16.466414 | 
2026-01-02 00:11:16.613115 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-02 00:11:16.615202 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-02 00:11:17.220990 | 
2026-01-02 00:11:17.221179 | PLAY [all]
2026-01-02 00:11:17.244583 | 
2026-01-02 00:11:17.244796 | TASK [Install binary dependencies]
2026-01-02 00:11:17.296089 | controller | ok
2026-01-02 00:11:17.315797 | 
2026-01-02 00:11:17.315892 | TASK [bindep : Include find tasks]
2026-01-02 00:11:17.356140 | controller | ok
2026-01-02 00:11:17.364159 | controller | included: /var/lib/zuul/builds/c7b2158372ef42a1a475ba3a717451c6/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-01-02 00:11:17.371352 | 
2026-01-02 00:11:17.371456 | TASK [bindep : Look for bindep.txt]
2026-01-02 00:11:18.404638 | controller | ok
2026-01-02 00:11:18.415433 | 
2026-01-02 00:11:18.415557 | TASK [bindep : Define bindep_file fact]
2026-01-02 00:11:18.451673 | controller | skipping: Conditional result was False
2026-01-02 00:11:18.462326 | 
2026-01-02 00:11:18.462425 | TASK [bindep : Look for other-requirements.txt]
2026-01-02 00:11:19.193874 | controller | ok
2026-01-02 00:11:19.210332 | 
2026-01-02 00:11:19.210533 | TASK [bindep : Define bindep_file fact]
2026-01-02 00:11:19.248523 | controller | skipping: Conditional result was False
2026-01-02 00:11:19.264598 | 
2026-01-02 00:11:19.264817 | TASK [bindep : Look for bindep fallback file]
2026-01-02 00:11:19.303586 | controller | skipping: Conditional result was False
2026-01-02 00:11:19.319618 | 
2026-01-02 00:11:19.319836 | TASK [bindep : Define bindep_file fact]
2026-01-02 00:11:19.348298 | controller | skipping: Conditional result was False
2026-01-02 00:11:19.363178 | 
2026-01-02 00:11:19.363477 | TASK [bindep : Include bindep tasks]
2026-01-02 00:11:19.390185 | controller | skipping: Conditional result was False
2026-01-02 00:11:19.404943 | 
2026-01-02 00:11:19.405109 | TASK [bindep : Include install tasks]
2026-01-02 00:11:19.431971 | controller | skipping: Conditional result was False
2026-01-02 00:11:19.449229 | 
2026-01-02 00:11:19.449395 | LOOP [bindep : Include package tasks]
2026-01-02 00:11:19.539542 | 
2026-01-02 00:11:19.539889 | TASK [Run test-setup role]
2026-01-02 00:11:19.572601 | controller | ok
2026-01-02 00:11:19.603234 | 
2026-01-02 00:11:19.603362 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-01-02 00:11:20.336389 | controller | ok
2026-01-02 00:11:20.350924 | 
2026-01-02 00:11:20.351063 | TASK [test-setup : Run tools/test-setup.sh]
2026-01-02 00:11:20.724104 | controller | skipping: Conditional result was False
2026-01-02 00:11:20.781351 | 
2026-01-02 00:11:20.781493 | PLAY RECAP
2026-01-02 00:11:20.781559 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-01-02 00:11:20.781595 | 
2026-01-02 00:11:20.894729 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-02 00:11:20.895604 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-01-02 00:11:21.535358 | 
2026-01-02 00:11:21.535495 | PLAY [controller]
2026-01-02 00:11:21.556740 | 
2026-01-02 00:11:21.556857 | TASK [Create the /root directory]
2026-01-02 00:11:22.688639 | controller | ok
2026-01-02 00:11:22.702573 | 
2026-01-02 00:11:22.702783 | TASK [Install glibc-langpack-en]
2026-01-02 00:11:31.072771 | controller | ok: Nothing to do
2026-01-02 00:11:31.082235 | 
2026-01-02 00:11:31.082338 | TASK [Ensure controller directory exists]
2026-01-02 00:11:31.919472 | controller | changed
2026-01-02 00:11:31.926241 | 
2026-01-02 00:11:31.926311 | TASK [Install container runtime]
2026-01-02 00:11:31.970817 | controller | ok
2026-01-02 00:11:32.026712 | 
2026-01-02 00:11:32.026833 | LOOP [ensure-docker : Gather variables for each operating system]
2026-01-02 00:11:32.088146 | controller | ok: "/var/lib/zuul/builds/c7b2158372ef42a1a475ba3a717451c6/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2026-01-02 00:11:32.104987 | 
2026-01-02 00:11:32.105140 | TASK [ensure-docker : Sanity check for distro selection]
2026-01-02 00:11:32.130537 | controller | skipping: Conditional result was False
2026-01-02 00:11:32.140882 | 
2026-01-02 00:11:32.141009 | TASK [ensure-docker : Sanity check for distro install]
2026-01-02 00:11:32.166203 | controller | skipping: Conditional result was False
2026-01-02 00:11:32.174399 | 
2026-01-02 00:11:32.174527 | TASK [ensure-docker : Sanity check for upstream install]
2026-01-02 00:11:32.199204 | controller | skipping: Conditional result was False
2026-01-02 00:11:32.206436 | 
2026-01-02 00:11:32.206576 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2026-01-02 00:11:32.231126 | controller | skipping: Conditional result was False
2026-01-02 00:11:32.240110 | 
2026-01-02 00:11:32.240271 | TASK [ensure-docker : Include tasks for docker install]
2026-01-02 00:11:32.263301 | controller | ok
2026-01-02 00:11:32.276800 | controller | included: /var/lib/zuul/builds/c7b2158372ef42a1a475ba3a717451c6/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2026-01-02 00:11:32.290773 | 
2026-01-02 00:11:32.290923 | TASK [ensure-docker : Not Implemented]
2026-01-02 00:11:32.305565 | controller | skipping: Conditional result was False
2026-01-02 00:11:32.315345 | 
2026-01-02 00:11:32.315479 | TASK [Set up docker mirrors]
2026-01-02 00:11:32.337898 | controller | ok
2026-01-02 00:11:32.362066 | 
2026-01-02 00:11:32.362184 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2026-01-02 00:11:32.387598 | controller | skipping: Conditional result was False
2026-01-02 00:11:32.397489 | 
2026-01-02 00:11:32.397597 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2026-01-02 00:11:32.453691 | controller | skipping: Conditional result was False
2026-01-02 00:11:32.483692 | 
2026-01-02 00:11:32.483826 | TASK [use-docker-mirror : Set docker_mirror fact]
2026-01-02 00:11:32.507760 | controller | skipping: Conditional result was False
2026-01-02 00:11:32.513896 | 
2026-01-02 00:11:32.513960 | TASK [use-docker-mirror : Set up docker mirrors]
2026-01-02 00:11:32.537847 | controller | skipping: Conditional result was False
2026-01-02 00:11:32.549638 | 
2026-01-02 00:11:32.549728 | TASK [Add all repositories]
2026-01-02 00:11:32.568173 | controller | ok
2026-01-02 00:11:32.592514 | 
2026-01-02 00:11:32.592606 | LOOP [ensure-package-repositories : Install repositories]
2026-01-02 00:11:32.626581 | controller | ok: "/var/lib/zuul/builds/c7b2158372ef42a1a475ba3a717451c6/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2026-01-02 00:11:32.626759 | controller | ok: All items complete
2026-01-02 00:11:32.626793 | 
2026-01-02 00:11:32.636191 | controller | included: /var/lib/zuul/builds/c7b2158372ef42a1a475ba3a717451c6/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2026-01-02 00:11:32.642449 | 
2026-01-02 00:11:32.642519 | TASK [ensure-package-repositories : Create GPG key temp directory]
2026-01-02 00:11:33.475630 | controller | changed
2026-01-02 00:11:33.488883 | 
2026-01-02 00:11:33.489262 | LOOP [ensure-package-repositories : Upload GPG keys]
2026-01-02 00:11:36.617569 | controller | changed:
2026-01-02 00:11:36.618084 | controller | {
2026-01-02 00:11:36.618155 | 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-01-02 00:11:36.618199 | controller | }
2026-01-02 00:11:36.628598 | 
2026-01-02 00:11:36.628771 | LOOP [ensure-package-repositories : Import GPG keys]
2026-01-02 00:11:37.849639 | controller | changed:
2026-01-02 00:11:37.850150 | controller | {
2026-01-02 00:11:37.850265 | 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-01-02 00:11:37.850324 | controller | }
2026-01-02 00:11:37.850407 | controller | changed: All items complete
2026-01-02 00:11:37.850477 | 
2026-01-02 00:11:37.867198 | 
2026-01-02 00:11:37.867327 | TASK [ensure-package-repositories : Remove GPG temp directory]
2026-01-02 00:11:38.549852 | controller | changed
2026-01-02 00:11:38.565082 | 
2026-01-02 00:11:38.565224 | LOOP [ensure-package-repositories : Add repositories]
2026-01-02 00:11:39.428405 | controller | changed:
2026-01-02 00:11:39.428667 | controller | {
2026-01-02 00:11:39.428772 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2026-01-02 00:11:39.428822 | controller |   "description": "Docker CE Stable - $basearch",
2026-01-02 00:11:39.428860 | controller |   "gpgcheck": true,
2026-01-02 00:11:39.428894 | controller |   "name": "docker-ce-stable"
2026-01-02 00:11:39.428933 | controller | }
2026-01-02 00:11:39.441986 | 
2026-01-02 00:11:39.442079 | TASK [ensure-docker : Install pre-reqs]
2026-01-02 00:11:44.466648 | controller | ok: Nothing to do
2026-01-02 00:11:44.479304 | 
2026-01-02 00:11:44.479440 | TASK [ensure-docker : Remove packages]
2026-01-02 00:11:47.952262 | controller | ok: Nothing to do
2026-01-02 00:11:47.958005 | 
2026-01-02 00:11:47.958069 | TASK [ensure-docker : Install upstream docker using package]
2026-01-02 00:13:13.847198 | controller | changed
2026-01-02 00:13:13.864850 | 
2026-01-02 00:13:13.865079 | TASK [ensure-docker : Setup docker]
2026-01-02 00:13:13.952629 | controller | ok
2026-01-02 00:13:13.967054 | controller | included: /var/lib/zuul/builds/c7b2158372ef42a1a475ba3a717451c6/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2026-01-02 00:13:13.984598 | 
2026-01-02 00:13:13.984716 | TASK [ensure-docker : Ensure "docker" group exists]
2026-01-02 00:13:15.028508 | controller | ok
2026-01-02 00:13:15.034443 | 
2026-01-02 00:13:15.034523 | TASK [ensure-docker : Add user to docker group]
2026-01-02 00:13:16.055372 | controller | changed
2026-01-02 00:13:16.061003 | 
2026-01-02 00:13:16.061062 | TASK [Update docker daemon proxy configuration]
2026-01-02 00:13:16.085285 | controller | skipping: Conditional result was False
2026-01-02 00:13:16.092143 | 
2026-01-02 00:13:16.092206 | TASK [Lower default MTU]
2026-01-02 00:13:16.116345 | controller | skipping: Conditional result was False
2026-01-02 00:13:16.125140 | 
2026-01-02 00:13:16.125220 | TASK [ensure-docker : Get the status of the docket.socket unit]
2026-01-02 00:13:16.928774 | controller | inactive
2026-01-02 00:13:17.487646 | controller | ok: Runtime: 0:00:00.024726
2026-01-02 00:13:17.494292 | 
2026-01-02 00:13:17.494363 | TASK [ensure-docker : Get the status of the docket unit]
2026-01-02 00:13:18.244269 | controller | inactive
2026-01-02 00:13:18.851764 | controller | ok: Runtime: 0:00:00.024667
2026-01-02 00:13:18.861374 | 
2026-01-02 00:13:18.861503 | TASK [ensure-docker : Restart docker]
2026-01-02 00:13:18.916240 | Trigger a Docker restart
2026-01-02 00:13:18.924071 | 
2026-01-02 00:13:18.924174 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2026-01-02 00:13:18.958758 | 
2026-01-02 00:13:18.958896 | TASK [ensure-docker : Install docker-compose]
2026-01-02 00:13:18.973981 | controller | skipping: Conditional result was False
2026-01-02 00:13:18.988587 | 
2026-01-02 00:13:18.988734 | TASK [ensure-docker : Flush handlers before role exit]
2026-01-02 00:13:19.002505 | 
2026-01-02 00:13:19.002611 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2026-01-02 00:13:22.598029 | controller | changed
2026-01-02 00:13:22.612988 | 
2026-01-02 00:13:22.613081 | TASK [ensure-docker : Assure docker service is running]
2026-01-02 00:13:26.396951 | controller | changed
2026-01-02 00:13:26.406337 | 
2026-01-02 00:13:26.406426 | TASK [ensure-docker : Assure docker.socket service is running]
2026-01-02 00:13:28.038163 | controller | ok
2026-01-02 00:13:28.051182 | 
2026-01-02 00:13:28.051742 | TASK [ensure-docker : Correct group ownership on docker sock]
2026-01-02 00:13:28.922795 | controller | ok
2026-01-02 00:13:28.932310 | 
2026-01-02 00:13:28.932411 | TASK [ensure-docker : Validate ability to talk with docker]
2026-01-02 00:13:29.587255 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2026-01-02 00:13:29.798626 | controller | ok: Runtime: 0:00:00.045771
2026-01-02 00:13:29.805654 | 
2026-01-02 00:13:29.805743 | TASK [ensure-docker : Get version details]
2026-01-02 00:13:30.556992 | controller | Client: Docker Engine - Community
2026-01-02 00:13:30.557082 | controller |  Version:           24.0.7
2026-01-02 00:13:30.557093 | controller |  API version:       1.43
2026-01-02 00:13:30.557101 | controller |  Go version:        go1.20.10
2026-01-02 00:13:30.557119 | controller |  Git commit:        afdd53b
2026-01-02 00:13:30.557127 | controller |  Built:             Thu Oct 26 09:09:39 2023
2026-01-02 00:13:30.557134 | controller |  OS/Arch:           linux/amd64
2026-01-02 00:13:30.557143 | controller |  Context:           default
2026-01-02 00:13:30.557152 | controller |
2026-01-02 00:13:30.557158 | controller | Server: Docker Engine - Community
2026-01-02 00:13:30.557165 | controller |  Engine:
2026-01-02 00:13:30.557234 | controller |   Version:          24.0.7
2026-01-02 00:13:30.557243 | controller |   API version:      1.43 (minimum version 1.12)
2026-01-02 00:13:30.557249 | controller |   Go version:       go1.20.10
2026-01-02 00:13:30.557255 | controller |   Git commit:       311b9ff
2026-01-02 00:13:30.557261 | controller |   Built:            Thu Oct 26 09:08:11 2023
2026-01-02 00:13:30.557267 | controller |   OS/Arch:          linux/amd64
2026-01-02 00:13:30.557273 | controller |   Experimental:     false
2026-01-02 00:13:30.557279 | controller |  containerd:
2026-01-02 00:13:30.557288 | controller |   Version:          1.6.25
2026-01-02 00:13:30.559242 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2026-01-02 00:13:30.559257 | controller |  runc:
2026-01-02 00:13:30.559264 | controller |   Version:          1.1.10
2026-01-02 00:13:30.559271 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2026-01-02 00:13:30.559281 | controller |  docker-init:
2026-01-02 00:13:30.559287 | controller |   Version:          0.19.0
2026-01-02 00:13:30.559293 | controller |   GitCommit:        de40ad0
2026-01-02 00:13:30.733975 | controller | ok: Runtime: 0:00:00.053556
2026-01-02 00:13:30.740651 | 
2026-01-02 00:13:30.740746 | TASK [ensure-docker : Dump installed docker details]
2026-01-02 00:13:30.769873 | {'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-01-02 00:13:30.506126', 'end': '2026-01-02 00:13:30.559682', 'delta': '0:00:00.053556', 'msg': '', 'zuul_log_id': 'fa163e3b-3c83-0aac-6f5f-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-01-02 00:13:30.775431 | 
2026-01-02 00:13:30.775491 | TASK [Use buildset registry]
2026-01-02 00:13:30.800388 | controller | skipping: Conditional result was False
2026-01-02 00:13:30.819149 | 
2026-01-02 00:13:30.819264 | TASK [Ensure python3.8 is present]
2026-01-02 00:13:30.833856 | controller | skipping: Conditional result was False
2026-01-02 00:13:30.840835 | 
2026-01-02 00:13:30.840924 | TASK [Run our-ensure-python role (Fedora only for now)]
2026-01-02 00:13:30.866461 | controller | ok
2026-01-02 00:13:30.885005 | 
2026-01-02 00:13:30.885123 | TASK [our-ensure-python : Install the right Python version (rpm)]
2026-01-02 00:13:37.119515 | controller | ok: Nothing to do
2026-01-02 00:13:37.127616 | 
2026-01-02 00:13:37.127721 | TASK [our-ensure-python : Also install python3-devel]
2026-01-02 00:13:54.313839 | controller | changed
2026-01-02 00:13:54.324590 | 
2026-01-02 00:13:54.324656 | TASK [Run ensure-virtualenv role]
2026-01-02 00:13:54.351960 | controller | ok
2026-01-02 00:13:54.384630 | 
2026-01-02 00:13:54.384797 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2026-01-02 00:13:55.116356 | controller | /usr/bin/virtualenv
2026-01-02 00:13:55.241336 | controller | ok: Runtime: 0:00:00.004771
2026-01-02 00:13:55.248785 | 
2026-01-02 00:13:55.248871 | LOOP [ensure-virtualenv : Install virtualenv package]
2026-01-02 00:13:55.277581 | controller | skipping: Conditional result was False
2026-01-02 00:13:55.277848 | controller | changed: All items complete
2026-01-02 00:13:55.277876 | 
2026-01-02 00:13:55.294222 | 
2026-01-02 00:13:55.294301 | TASK [Find the full path of the Python interpreter]
2026-01-02 00:13:55.904166 | controller | /usr/bin/python3.8
2026-01-02 00:13:56.148418 | controller | ok
2026-01-02 00:13:56.156127 | 
2026-01-02 00:13:56.156212 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2026-01-02 00:13:58.409091 | controller | created virtual environment CPython3.8.18.final.0-64 in 1006ms
2026-01-02 00:13:58.545974 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2026-01-02 00:13:58.546033 | 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-01-02 00:13:58.546052 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2026-01-02 00:13:58.546073 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2026-01-02 00:13:59.016878 | controller | changed
2026-01-02 00:13:59.030837 | 
2026-01-02 00:13:59.030977 | TASK [Set selinux package]
2026-01-02 00:13:59.064298 | controller | ok
2026-01-02 00:13:59.069560 | 
2026-01-02 00:13:59.069628 | TASK [Set selinux package (Fedora)]
2026-01-02 00:13:59.129152 | controller | ok
2026-01-02 00:13:59.142588 | 
2026-01-02 00:13:59.142744 | TASK [Install selinux into virtualenv]
2026-01-02 00:14:04.721743 | controller | Collecting selinux-please-lie-to-me
2026-01-02 00:14:04.846278 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2026-01-02 00:14:06.113369 | controller | Collecting setuptools<50.0.0
2026-01-02 00:14:06.126482 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2026-01-02 00:14:06.238382 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 8.0 MB/s eta 0:00:00
2026-01-02 00:14:06.373477 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2026-01-02 00:14:06.374868 | controller |   Attempting uninstall: setuptools
2026-01-02 00:14:06.376047 | controller |     Found existing installation: setuptools 62.6.0
2026-01-02 00:14:06.506303 | controller |     Uninstalling setuptools-62.6.0:
2026-01-02 00:14:06.526266 | controller |       Successfully uninstalled setuptools-62.6.0
2026-01-02 00:14:07.237521 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2026-01-02 00:14:07.459614 | controller |
2026-01-02 00:14:07.719048 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-01-02 00:14:07.719110 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-01-02 00:14:08.048612 | controller | ok: Runtime: 0:00:07.283140
2026-01-02 00:14:08.054121 | 
2026-01-02 00:14:08.054181 | TASK [Install pytest-forked into virtualenv]
2026-01-02 00:14:09.858280 | controller | Collecting pytest-forked
2026-01-02 00:14:09.923517 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2026-01-02 00:14:10.027002 | controller | Collecting py
2026-01-02 00:14:10.032997 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2026-01-02 00:14:10.079412 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 2.2 MB/s eta 0:00:00
2026-01-02 00:14:10.304948 | controller | Collecting pytest>=3.10
2026-01-02 00:14:10.312499 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2026-01-02 00:14:10.359129 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 9.1 MB/s eta 0:00:00
2026-01-02 00:14:10.452994 | controller | Collecting pluggy<2,>=1.5
2026-01-02 00:14:10.460254 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2026-01-02 00:14:10.559992 | controller | Collecting packaging
2026-01-02 00:14:10.566758 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2026-01-02 00:14:10.582101 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 4.8 MB/s eta 0:00:00
2026-01-02 00:14:10.669409 | controller | Collecting tomli>=1
2026-01-02 00:14:10.677583 | controller |   Downloading tomli-2.3.0-py3-none-any.whl (14 kB)
2026-01-02 00:14:10.723070 | controller | Collecting iniconfig
2026-01-02 00:14:10.728133 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2026-01-02 00:14:10.788111 | controller | Collecting exceptiongroup>=1.0.0rc8
2026-01-02 00:14:10.794062 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2026-01-02 00:14:10.894491 | controller | Collecting typing-extensions>=4.6.0
2026-01-02 00:14:10.901789 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2026-01-02 00:14:10.916072 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 3.4 MB/s eta 0:00:00
2026-01-02 00:14:11.026991 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2026-01-02 00:14:11.949155 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.1.0 packaging-25.0 pluggy-1.5.0 py-1.11.0 pytest-8.3.5 pytest-forked-1.6.0 tomli-2.3.0 typing-extensions-4.13.2
2026-01-02 00:14:11.956725 | controller |
2026-01-02 00:14:12.225431 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-01-02 00:14:12.225462 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-01-02 00:14:12.412471 | controller | ok: Runtime: 0:00:03.144815
2026-01-02 00:14:12.423871 | 
2026-01-02 00:14:12.424028 | TASK [Update pip]
2026-01-02 00:14:15.030764 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2026-01-02 00:14:15.512021 | controller | Collecting pip
2026-01-02 00:14:15.642260 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2026-01-02 00:14:15.786449 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 14.1 MB/s eta 0:00:00
2026-01-02 00:14:15.948010 | controller | Installing collected packages: pip
2026-01-02 00:14:15.948382 | controller |   Attempting uninstall: pip
2026-01-02 00:14:15.949426 | controller |     Found existing installation: pip 22.2.2
2026-01-02 00:14:16.476246 | controller |     Uninstalling pip-22.2.2:
2026-01-02 00:14:16.523293 | controller |       Successfully uninstalled pip-22.2.2
2026-01-02 00:14:18.827354 | controller | Successfully installed pip-25.0.1
2026-01-02 00:14:19.316354 | controller | ok: Runtime: 0:00:05.406276
2026-01-02 00:14:19.331237 | 
2026-01-02 00:14:19.331400 | TASK [Ensure pip temp directory exists in /var/tmp]
2026-01-02 00:14:20.664742 | controller | changed
2026-01-02 00:14:20.677649 | 
2026-01-02 00:14:20.677840 | TASK [Install ansible into virtualenv]
2026-01-02 00:14:22.773985 | controller | Processing ./src/github.com/ansible/ansible
2026-01-02 00:14:22.775312 | controller |   Preparing metadata (setup.py): started
2026-01-02 00:14:24.711892 | controller |   Preparing metadata (setup.py): finished with status 'done'
2026-01-02 00:14:24.781835 | controller | Collecting jinja2 (from ansible==2.9.27.post0)
2026-01-02 00:14:24.802022 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2026-01-02 00:14:24.886438 | controller | Collecting PyYAML (from ansible==2.9.27.post0)
2026-01-02 00:14:24.894728 | 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-01-02 00:14:25.330241 | controller | Collecting cryptography (from ansible==2.9.27.post0)
2026-01-02 00:14:25.339422 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2026-01-02 00:14:25.601767 | controller | Collecting cffi>=1.14 (from cryptography->ansible==2.9.27.post0)
2026-01-02 00:14:25.606783 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2026-01-02 00:14:25.616469 | 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-01-02 00:14:25.727079 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible==2.9.27.post0)
2026-01-02 00:14:25.732841 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2026-01-02 00:14:25.756865 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible==2.9.27.post0)
2026-01-02 00:14:25.761665 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2026-01-02 00:14:25.779381 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2026-01-02 00:14:25.838519 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 84.1 MB/s eta 0:00:00
2026-01-02 00:14:25.843019 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2026-01-02 00:14:25.860245 | 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-01-02 00:14:25.882473 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 33.1 MB/s eta 0:00:00
2026-01-02 00:14:25.889699 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2026-01-02 00:14:25.908976 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2026-01-02 00:14:25.918856 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2026-01-02 00:14:25.951774 | controller | Building wheels for collected packages: ansible
2026-01-02 00:14:25.952717 | controller |   Building wheel for ansible (setup.py): started
2026-01-02 00:14:37.070216 | controller |   Building wheel for ansible (setup.py): finished with status 'done'
2026-01-02 00:14:37.089023 | controller |   Created wheel for ansible: filename=ansible-2.9.27.post0-py3-none-any.whl size=16207514 sha256=824392774bc9cdd414a91da7a641e47d2c5bbbc438f02f4e8209d48971247ac4
2026-01-02 00:14:37.089344 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-khdm2_8l/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2026-01-02 00:14:37.168521 | controller | Successfully built ansible
2026-01-02 00:14:37.351924 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible
2026-01-02 00:14:52.362463 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-2.9.27.post0 cffi-1.17.1 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23
2026-01-02 00:14:53.088315 | controller | ok: Runtime: 0:00:30.945927
2026-01-02 00:14:53.098240 | 
2026-01-02 00:14:53.098323 | TASK [Setup download-artifact-fork role]
2026-01-02 00:14:53.122178 | controller | ok
2026-01-02 00:14:53.150490 | 
2026-01-02 00:14:53.150645 | TASK [download-artifact-fork : Parse inventory response]
2026-01-02 00:14:53.185574 | controller | ok
2026-01-02 00:14:53.194892 | 
2026-01-02 00:14:53.194984 | TASK [download-artifact-fork : Fetch info from Zuul API]
2026-01-02 00:14:53.220860 | controller | skipping: Conditional result was False
2026-01-02 00:14:53.231193 | 
2026-01-02 00:14:53.231315 | TASK [download-artifact-fork : Parse build response]
2026-01-02 00:14:53.257201 | controller | skipping: Conditional result was False
2026-01-02 00:14:53.267431 | 
2026-01-02 00:14:53.267551 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2026-01-02 00:14:54.370646 | controller | changed
2026-01-02 00:14:54.385254 | 
2026-01-02 00:14:54.385405 | LOOP [download-artifact-fork : Download archive by type]
2026-01-02 00:14:54.424418 | controller | skipping: Conditional result was False
2026-01-02 00:14:54.424918 | 
2026-01-02 00:14:56.627956 | controller | changed: OK (20217 bytes)
2026-01-02 00:14:56.695002 | 
2026-01-02 00:14:56.695127 | TASK [deploy-artifacts : define __collections]
2026-01-02 00:14:56.715972 | controller | ok
2026-01-02 00:14:56.722226 | 
2026-01-02 00:14:56.722334 | LOOP [deploy-artifacts : Create list of collections]
2026-01-02 00:14:56.755095 | controller | skipping: Conditional result was False
2026-01-02 00:14:56.768426 | controller | ok:
2026-01-02 00:14:56.768760 | controller | {
2026-01-02 00:14:56.768819 | controller |   "branch": "main",
2026-01-02 00:14:56.768850 | controller |   "job": "build-ansible-collection",
2026-01-02 00:14:56.768875 | controller |   "metadata": {
2026-01-02 00:14:56.768899 | controller |     "type": "ansible_collection",
2026-01-02 00:14:56.768923 | controller |     "version": "5.0.0"
2026-01-02 00:14:56.768945 | controller |   },
2026-01-02 00:14:56.768968 | controller |   "name": "ansible.network",
2026-01-02 00:14:56.768990 | controller |   "project": "ansible-collections/ansible.network",
2026-01-02 00:14:56.769013 | controller |   "url": "https://116e64810a21f6876125-393f8a85a6fed6dbee9a10c72db245b3.ssl.cf2.rackcdn.com/ansible/e42ffa7181af4fc4be2cd75dc6e7ccb8/artifacts/ansible-network-5.0.0.tar.gz"
2026-01-02 00:14:56.769043 | controller | }
2026-01-02 00:14:56.786165 | 
2026-01-02 00:14:56.786315 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2026-01-02 00:14:58.047497 | controller | Process install dependency map
2026-01-02 00:15:56.865072 | controller | |/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\Starting collection install process
2026-01-02 00:15:56.966726 | controller | |Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2026-01-02 00:15:56.966864 | controller | Installing 'ansible.netcommon:8.2.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-01-02 00:15:58.786055 | controller | /-\|/-\|/-\|/-\|/-Installing 'ansible.utils:6.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-01-02 00:16:00.632853 | controller | \|/-\|/-\|/-\|/-\|Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2026-01-02 00:16:02.965014 | controller | /-\|/-\|/-\|/-\|/-\|/-\Installing 'cisco.ios:11.2.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2026-01-02 00:16:05.998622 | controller | |/-\|/-\|/-\|/-\|/-\|/-\|/-\|/Installing 'cisco.iosxr:12.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2026-01-02 00:16:08.836672 | controller | -\|/-\|/-\|/-\|/-\|/-\|/-\|/Installing 'cisco.nxos:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2026-01-02 00:16:13.213531 | controller | -\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2026-01-02 00:16:16.204753 | controller | ok: Runtime: 0:01:18.323017
2026-01-02 00:16:16.229474 | 
2026-01-02 00:16:16.229612 | TASK [podman may need iptables kernel modules]
2026-01-02 00:16:17.609553 | controller | ok: Runtime: 0:00:00.012054
2026-01-02 00:16:17.655336 | 
2026-01-02 00:16:17.655546 | PLAY RECAP
2026-01-02 00:16:17.655606 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2026-01-02 00:16:17.655633 | 
2026-01-02 00:16:17.879455 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-01-02 00:16:17.881535 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-01-02 00:16:18.544355 | 
2026-01-02 00:16:18.544483 | PLAY [controller]
2026-01-02 00:16:18.567412 | 
2026-01-02 00:16:18.567536 | TASK [Copy the galaxy.yml on the controller]
2026-01-02 00:16:22.400567 | controller | changed
2026-01-02 00:16:22.406360 | 
2026-01-02 00:16:22.406432 | TASK [Load information from galaxy.yml]
2026-01-02 00:16:22.437746 | controller | ok
2026-01-02 00:16:22.443727 | 
2026-01-02 00:16:22.443802 | LOOP [Enable FIPS mode]
2026-01-02 00:16:22.464788 | 
2026-01-02 00:16:22.464918 | LOOP [ansible-test : Include branch specific variables.]
2026-01-02 00:16:22.515882 | controller | ok: "/var/lib/zuul/builds/c7b2158372ef42a1a475ba3a717451c6/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2026-01-02 00:16:22.516287 | 
2026-01-02 00:16:22.529253 | 
2026-01-02 00:16:22.529376 | TASK [ansible-test : Setup --skip-tags for test_options]
2026-01-02 00:16:22.568517 | controller | ok
2026-01-02 00:16:22.574119 | 
2026-01-02 00:16:22.574184 | TASK [ansible-test : Enable --inventory for network-integration]
2026-01-02 00:16:22.597569 | controller | skipping: Conditional result was False
2026-01-02 00:16:22.604894 | 
2026-01-02 00:16:22.604961 | TASK [ansible-test : Enable --retry-on-error]
2026-01-02 00:16:22.633014 | controller | skipping: Conditional result was False
2026-01-02 00:16:22.639499 | 
2026-01-02 00:16:22.639570 | TASK [ansible-test : Enable --continue-on-error]
2026-01-02 00:16:22.668648 | controller | ok
2026-01-02 00:16:22.674101 | 
2026-01-02 00:16:22.674162 | TASK [ansible-test : Enable --requirements]
2026-01-02 00:16:22.697285 | controller | skipping: Conditional result was False
2026-01-02 00:16:22.703791 | 
2026-01-02 00:16:22.703864 | TASK [ansible-test : Adjust options for unit tests]
2026-01-02 00:16:22.727477 | controller | skipping: Conditional result was False
2026-01-02 00:16:22.736928 | 
2026-01-02 00:16:22.737027 | TASK [ansible-test : Install requirements with unit tests]
2026-01-02 00:16:22.800923 | controller | ok
2026-01-02 00:16:22.806760 | 
2026-01-02 00:16:22.806832 | TASK [ansible-test : Set --skip-test]
2026-01-02 00:16:22.861018 | controller | skipping: Conditional result was False
2026-01-02 00:16:22.867283 | 
2026-01-02 00:16:22.867345 | TASK [ansible-test : Reduce verbosity]
2026-01-02 00:16:22.906246 | controller | ok
2026-01-02 00:16:22.912193 | 
2026-01-02 00:16:22.912255 | TASK [ansible-test : Setup --python option]
2026-01-02 00:16:22.935651 | controller | skipping: Conditional result was False
2026-01-02 00:16:22.943300 | 
2026-01-02 00:16:22.943379 | TASK [ansible-test : Setup --docker option]
2026-01-02 00:16:22.993832 | controller | ok
2026-01-02 00:16:23.000884 | 
2026-01-02 00:16:23.000958 | TASK [ansible-test : check if MANIFEST.json exists]
2026-01-02 00:16:24.305520 | controller | ok
2026-01-02 00:16:24.311451 | 
2026-01-02 00:16:24.311510 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2026-01-02 00:16:24.336354 | controller | skipping: Conditional result was False
2026-01-02 00:16:24.343621 | 
2026-01-02 00:16:24.343702 | TASK [ansible-test : Load information from MANIFEST.json]
2026-01-02 00:16:24.367919 | controller | skipping: Conditional result was False
2026-01-02 00:16:24.376549 | 
2026-01-02 00:16:24.376622 | TASK [ansible-test : Setup location of project for integration tests]
2026-01-02 00:16:24.402500 | controller | skipping: Conditional result was False
2026-01-02 00:16:24.410346 | 
2026-01-02 00:16:24.410411 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2026-01-02 00:16:25.993484 | controller | ok
2026-01-02 00:16:26.005749 | 
2026-01-02 00:16:26.005900 | TASK [ansible-test : Load information from galaxy.yml]
2026-01-02 00:16:26.062175 | controller | ok
2026-01-02 00:16:26.072300 | 
2026-01-02 00:16:26.072389 | TASK [ansible-test : Setup location of project for integration tests]
2026-01-02 00:16:26.124235 | controller | ok
2026-01-02 00:16:26.129826 | 
2026-01-02 00:16:26.129888 | TASK [ansible-test : Setup minimum test requirements]
2026-01-02 00:16:26.161663 | controller | ok
2026-01-02 00:16:26.167830 | 
2026-01-02 00:16:26.167904 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2026-01-02 00:16:27.250940 | controller | ok
2026-01-02 00:16:27.264945 | 
2026-01-02 00:16:27.265154 | TASK [ansible-test : Install python requirements]
2026-01-02 00:16:28.849957 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-01-02 00:16:28.873286 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2026-01-02 00:16:28.905094 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-01-02 00:16:28.910749 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2026-01-02 00:16:28.922085 | 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-01-02 00:16:28.930384 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2026-01-02 00:16:28.945880 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2026-01-02 00:16:29.015629 | controller | Installing collected packages: pathspec, yamllint
2026-01-02 00:16:29.118367 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2026-01-02 00:16:29.638910 | controller | ok: Runtime: 0:00:01.106878
2026-01-02 00:16:29.654728 | 
2026-01-02 00:16:29.654886 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2026-01-02 00:16:30.471396 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2026-01-02 00:16:31.032547 | controller | ERROR
2026-01-02 00:16:31.032967 | controller | {
2026-01-02 00:16:31.033032 | controller |   "delta": "0:00:00.011012",
2026-01-02 00:16:31.033073 | controller |   "end": "2026-01-02 00:16:30.471680",
2026-01-02 00:16:31.033109 | controller |   "msg": "non-zero return code",
2026-01-02 00:16:31.033143 | controller |   "rc": 1,
2026-01-02 00:16:31.033177 | controller |   "start": "2026-01-02 00:16:30.460668"
2026-01-02 00:16:31.033210 | controller | }
2026-01-02 00:16:31.033256 | controller | ERROR: Ignoring Errors
2026-01-02 00:16:31.046788 | 
2026-01-02 00:16:31.046982 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2026-01-02 00:16:31.424641 | controller | skipping: Conditional result was False
2026-01-02 00:16:31.438645 | 
2026-01-02 00:16:31.438818 | TASK [ansible-test : Enable persistent connection logging]
2026-01-02 00:16:32.931305 | controller | changed: section and option added
2026-01-02 00:16:32.946565 | 
2026-01-02 00:16:32.946772 | TASK [ansible-test : Set persistent ssh_type value]
2026-01-02 00:16:33.978005 | controller | changed: option added
2026-01-02 00:16:33.991598 | 
2026-01-02 00:16:33.991792 | TASK [ansible-test : Set ansible log path]
2026-01-02 00:16:35.145541 | controller | changed: section and option added
2026-01-02 00:16:35.160821 | 
2026-01-02 00:16:35.160974 | TASK [ansible-test : Identify the changed targets]
2026-01-02 00:16:35.542030 | controller | skipping: Conditional result was False
2026-01-02 00:16:35.552003 | 
2026-01-02 00:16:35.552111 | TASK [ansible-test : set_fact]
2026-01-02 00:16:35.640038 | controller | skipping: Conditional result was False
2026-01-02 00:16:35.687806 | 
2026-01-02 00:16:35.687952 | TASK [ansible-test : Set the targets]
2026-01-02 00:16:35.720803 | controller | ok
2026-01-02 00:16:35.727969 | 
2026-01-02 00:16:35.728052 | TASK [ansible-test : set_fact]
2026-01-02 00:16:35.753623 | controller | skipping: Conditional result was False
2026-01-02 00:16:35.760019 | 
2026-01-02 00:16:35.760089 | TASK [ansible-test : List the targets]
2026-01-02 00:16:36.129630 | controller | skipping: Conditional result was False
2026-01-02 00:16:36.136670 | 
2026-01-02 00:16:36.136776 | TASK [ansible-test : set_fact]
2026-01-02 00:16:36.161294 | controller | skipping: Conditional result was False
2026-01-02 00:16:36.167554 | 
2026-01-02 00:16:36.167720 | TASK [ansible-test : set_fact]
2026-01-02 00:16:36.192266 | controller | skipping: Conditional result was False
2026-01-02 00:16:36.207311 | 
2026-01-02 00:16:36.207464 | LOOP [ansible-test : set_fact]
2026-01-02 00:16:36.284447 | 
2026-01-02 00:16:36.284650 | TASK [ansible-test : set_fact]
2026-01-02 00:16:36.309812 | controller | skipping: Conditional result was False
2026-01-02 00:16:36.321469 | 
2026-01-02 00:16:36.321608 | TASK [ansible-test : debug]
2026-01-02 00:16:36.348187 | controller | skipping: Conditional result was False
2026-01-02 00:16:36.357037 | 
2026-01-02 00:16:36.357130 | TASK [ansible-test : debug]
2026-01-02 00:16:36.394023 | About to run: ansible-test sanity --requirements --docker -v
2026-01-02 00:16:36.402802 | 
2026-01-02 00:16:36.402889 | TASK [ansible-test : Run the test suite]
2026-01-02 00:16:38.315841 | controller | Run command: docker images quay.io/ansible/default-test-container:1.10.1 --format '{{json .}}'
2026-01-02 00:16:38.442308 | controller | Run command: docker pull quay.io/ansible/default-test-container:1.10.1
2026-01-02 00:16:40.159669 | controller | 1.10.1: Pulling from ansible/default-test-container
2026-01-02 00:16:40.159828 | 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-01-02 00:16:40.159864 | controller | 5667fdb72017: Pulling fs layer
2026-01-02 00:16:40.159874 | controller | d83811f270d5: Pulling fs layer
2026-01-02 00:16:40.159881 | controller | ee671aafb583: Pulling fs layer
2026-01-02 00:16:40.159892 | controller | 7fc152dfb3a6: Pulling fs layer
2026-01-02 00:16:40.159901 | controller | 3fb967a6fd03: Pulling fs layer
2026-01-02 00:16:40.159907 | controller | b3e63023ca5f: Pulling fs layer
2026-01-02 00:16:40.159913 | controller | 653cb3e65dbf: Pulling fs layer
2026-01-02 00:16:40.159919 | controller | f4712b586375: Pulling fs layer
2026-01-02 00:16:40.159926 | controller | d696ed7812f8: Pulling fs layer
2026-01-02 00:16:40.159933 | controller | 29bf28764f14: Pulling fs layer
2026-01-02 00:16:40.159941 | controller | 47d7edb3c2b7: Pulling fs layer
2026-01-02 00:16:40.159947 | controller | 07550ad4f6e4: Pulling fs layer
2026-01-02 00:16:40.159954 | controller | 7e79526e64c5: Pulling fs layer
2026-01-02 00:16:40.159960 | controller | 4fffb32882b7: Pulling fs layer
2026-01-02 00:16:40.159966 | controller | fc7a38b06f4e: Pulling fs layer
2026-01-02 00:16:40.159973 | controller | 6d9d912e060e: Pulling fs layer
2026-01-02 00:16:40.159979 | controller | 5dd542dd28d9: Pulling fs layer
2026-01-02 00:16:40.159985 | controller | cef8beb99552: Pulling fs layer
2026-01-02 00:16:40.159991 | controller | bd5557e101ad: Pulling fs layer
2026-01-02 00:16:40.159996 | controller | e057c20fabdd: Pulling fs layer
2026-01-02 00:16:40.160005 | controller | d16059cdae2e: Pulling fs layer
2026-01-02 00:16:40.160012 | controller | 431e42d906a3: Pulling fs layer
2026-01-02 00:16:40.160018 | controller | 7a0fd3b1d7c3: Pulling fs layer
2026-01-02 00:16:40.160024 | controller | aacdb6db0c8c: Pulling fs layer
2026-01-02 00:16:40.160030 | controller | 2e7f04ae8c8b: Pulling fs layer
2026-01-02 00:16:40.160036 | controller | 4cd343027a44: Pulling fs layer
2026-01-02 00:16:40.160043 | controller | 0b4379883f90: Pulling fs layer
2026-01-02 00:16:40.160049 | controller | 3d9d6d9dfc91: Pulling fs layer
2026-01-02 00:16:40.160059 | controller | 57d6314bd293: Pulling fs layer
2026-01-02 00:16:40.160065 | controller | 774036afeb7f: Pulling fs layer
2026-01-02 00:16:40.160072 | controller | 54b10f0e1645: Pulling fs layer
2026-01-02 00:16:40.160078 | controller | 78cf439d9fdb: Pulling fs layer
2026-01-02 00:16:40.160084 | controller | 36e5e20969c7: Pulling fs layer
2026-01-02 00:16:40.160090 | controller | 4b19ac3f17a8: Pulling fs layer
2026-01-02 00:16:40.160100 | controller | 7fc152dfb3a6: Waiting
2026-01-02 00:16:40.160205 | controller | 3fb967a6fd03: Waiting
2026-01-02 00:16:40.160222 | controller | b3e63023ca5f: Waiting
2026-01-02 00:16:40.160233 | controller | 653cb3e65dbf: Waiting
2026-01-02 00:16:40.160380 | controller | f4712b586375: Waiting
2026-01-02 00:16:40.160393 | controller | d696ed7812f8: Waiting
2026-01-02 00:16:40.160399 | controller | 29bf28764f14: Waiting
2026-01-02 00:16:40.160406 | controller | 47d7edb3c2b7: Waiting
2026-01-02 00:16:40.160436 | controller | 07550ad4f6e4: Waiting
2026-01-02 00:16:40.160509 | controller | 7e79526e64c5: Waiting
2026-01-02 00:16:40.160528 | controller | 4fffb32882b7: Waiting
2026-01-02 00:16:40.160535 | controller | fc7a38b06f4e: Waiting
2026-01-02 00:16:40.160542 | controller | 6d9d912e060e: Waiting
2026-01-02 00:16:40.160548 | controller | 5dd542dd28d9: Waiting
2026-01-02 00:16:40.160559 | controller | cef8beb99552: Waiting
2026-01-02 00:16:40.160565 | controller | bd5557e101ad: Waiting
2026-01-02 00:16:40.160572 | controller | e057c20fabdd: Waiting
2026-01-02 00:16:40.160578 | controller | d16059cdae2e: Waiting
2026-01-02 00:16:40.160583 | controller | 431e42d906a3: Waiting
2026-01-02 00:16:40.160589 | controller | 7a0fd3b1d7c3: Waiting
2026-01-02 00:16:40.160596 | controller | aacdb6db0c8c: Waiting
2026-01-02 00:16:40.160603 | controller | 2e7f04ae8c8b: Waiting
2026-01-02 00:16:40.160609 | controller | 4cd343027a44: Waiting
2026-01-02 00:16:40.160618 | controller | 0b4379883f90: Waiting
2026-01-02 00:16:40.160687 | controller | 3d9d6d9dfc91: Waiting
2026-01-02 00:16:40.160699 | controller | 57d6314bd293: Waiting
2026-01-02 00:16:40.160709 | controller | 774036afeb7f: Waiting
2026-01-02 00:16:40.160729 | controller | 54b10f0e1645: Waiting
2026-01-02 00:16:41.191809 | controller | 78cf439d9fdb: Waiting
2026-01-02 00:16:41.191869 | controller | 36e5e20969c7: Waiting
2026-01-02 00:16:41.191882 | controller | 4b19ac3f17a8: Waiting
2026-01-02 00:16:41.191899 | controller | ee671aafb583: Verifying Checksum
2026-01-02 00:16:41.557746 | controller | ee671aafb583: Download complete
2026-01-02 00:16:41.557815 | controller | d83811f270d5: Verifying Checksum
2026-01-02 00:16:42.232052 | controller | d83811f270d5: Download complete
2026-01-02 00:16:42.232199 | controller | 7fc152dfb3a6: Download complete
2026-01-02 00:16:43.018271 | controller | b3e63023ca5f: Verifying Checksum
2026-01-02 00:16:43.809283 | controller | b3e63023ca5f: Download complete
2026-01-02 00:16:43.809355 | controller | 653cb3e65dbf: Verifying Checksum
2026-01-02 00:16:46.146522 | controller | 653cb3e65dbf: Download complete
2026-01-02 00:16:46.147655 | controller | 5667fdb72017: Verifying Checksum
2026-01-02 00:16:46.728570 | controller | 5667fdb72017: Download complete
2026-01-02 00:16:46.728654 | controller | 3fb967a6fd03: Verifying Checksum
2026-01-02 00:16:47.278192 | controller | 3fb967a6fd03: Download complete
2026-01-02 00:16:47.278280 | controller | d696ed7812f8: Verifying Checksum
2026-01-02 00:16:47.545162 | controller | d696ed7812f8: Download complete
2026-01-02 00:16:47.545256 | controller | 29bf28764f14: Verifying Checksum
2026-01-02 00:16:47.546195 | controller | 29bf28764f14: Download complete
2026-01-02 00:16:48.368298 | controller | 07550ad4f6e4: Verifying Checksum
2026-01-02 00:16:48.368362 | controller | 07550ad4f6e4: Download complete
2026-01-02 00:16:48.529801 | controller | 47d7edb3c2b7: Verifying Checksum
2026-01-02 00:16:48.529862 | controller | 47d7edb3c2b7: Download complete
2026-01-02 00:16:49.007134 | controller | 5667fdb72017: Pull complete
2026-01-02 00:16:49.057064 | controller | d83811f270d5: Pull complete
2026-01-02 00:16:49.107714 | controller | ee671aafb583: Pull complete
2026-01-02 00:16:49.158458 | controller | 7e79526e64c5: Download complete
2026-01-02 00:16:49.165468 | controller | 7fc152dfb3a6: Pull complete
2026-01-02 00:16:49.310135 | controller | 4fffb32882b7: Verifying Checksum
2026-01-02 00:16:49.310253 | controller | 4fffb32882b7: Download complete
2026-01-02 00:16:50.111414 | controller | 6d9d912e060e: Verifying Checksum
2026-01-02 00:16:50.111545 | controller | 6d9d912e060e: Download complete
2026-01-02 00:16:50.481322 | controller | 3fb967a6fd03: Pull complete
2026-01-02 00:16:50.523840 | controller | b3e63023ca5f: Pull complete
2026-01-02 00:16:50.540019 | controller | fc7a38b06f4e: Verifying Checksum
2026-01-02 00:16:50.540150 | controller | fc7a38b06f4e: Download complete
2026-01-02 00:16:50.560429 | controller | 653cb3e65dbf: Pull complete
2026-01-02 00:16:50.930720 | controller | 5dd542dd28d9: Verifying Checksum
2026-01-02 00:16:50.930876 | controller | 5dd542dd28d9: Download complete
2026-01-02 00:16:51.718316 | controller | bd5557e101ad: Verifying Checksum
2026-01-02 00:16:51.718494 | controller | bd5557e101ad: Download complete
2026-01-02 00:16:52.505461 | controller | e057c20fabdd: Verifying Checksum
2026-01-02 00:16:52.505660 | controller | e057c20fabdd: Download complete
2026-01-02 00:16:53.265490 | controller | d16059cdae2e: Verifying Checksum
2026-01-02 00:16:53.265621 | controller | d16059cdae2e: Download complete
2026-01-02 00:16:55.127552 | controller | 431e42d906a3: Verifying Checksum
2026-01-02 00:16:55.127793 | controller | 431e42d906a3: Download complete
2026-01-02 00:16:56.455835 | controller | 7a0fd3b1d7c3: Verifying Checksum
2026-01-02 00:16:56.456017 | controller | 7a0fd3b1d7c3: Download complete
2026-01-02 00:16:57.729079 | controller | aacdb6db0c8c: Verifying Checksum
2026-01-02 00:16:57.729407 | controller | aacdb6db0c8c: Download complete
2026-01-02 00:16:58.564500 | controller | 2e7f04ae8c8b: Verifying Checksum
2026-01-02 00:16:58.564712 | controller | 2e7f04ae8c8b: Download complete
2026-01-02 00:16:59.448656 | controller | 4cd343027a44: Verifying Checksum
2026-01-02 00:17:00.243404 | controller | 4cd343027a44: Download complete
2026-01-02 00:17:00.243496 | controller | 0b4379883f90: Verifying Checksum
2026-01-02 00:17:00.965026 | controller | 0b4379883f90: Download complete
2026-01-02 00:17:00.965112 | controller | 3d9d6d9dfc91: Verifying Checksum
2026-01-02 00:17:10.176042 | controller | 3d9d6d9dfc91: Download complete
2026-01-02 00:17:10.176186 | controller | f4712b586375: Verifying Checksum
2026-01-02 00:17:10.176392 | controller | f4712b586375: Download complete
2026-01-02 00:17:14.941065 | controller | 57d6314bd293: Verifying Checksum
2026-01-02 00:17:24.504928 | controller | 57d6314bd293: Download complete
2026-01-02 00:17:24.505033 | controller | cef8beb99552: Verifying Checksum
2026-01-02 00:17:25.342662 | controller | cef8beb99552: Download complete
2026-01-02 00:17:25.342751 | controller | 54b10f0e1645: Verifying Checksum
2026-01-02 00:17:25.342909 | controller | 54b10f0e1645: Download complete
2026-01-02 00:17:26.396416 | controller | 774036afeb7f: Verifying Checksum
2026-01-02 00:17:34.853006 | controller | 774036afeb7f: Download complete
2026-01-02 00:17:34.853124 | controller | 78cf439d9fdb: Verifying Checksum
2026-01-02 00:17:34.853332 | controller | 78cf439d9fdb: Download complete
2026-01-02 00:17:36.390552 | controller | f4712b586375: Pull complete
2026-01-02 00:17:36.427689 | controller | d696ed7812f8: Pull complete
2026-01-02 00:17:36.449612 | controller | 29bf28764f14: Pull complete
2026-01-02 00:17:36.494391 | controller | 47d7edb3c2b7: Pull complete
2026-01-02 00:17:36.530526 | controller | 07550ad4f6e4: Pull complete
2026-01-02 00:17:36.562679 | controller | 7e79526e64c5: Pull complete
2026-01-02 00:17:36.598134 | controller | 4fffb32882b7: Pull complete
2026-01-02 00:17:36.674330 | controller | fc7a38b06f4e: Pull complete
2026-01-02 00:17:36.737298 | controller | 36e5e20969c7: Verifying Checksum
2026-01-02 00:17:36.737474 | controller | 36e5e20969c7: Download complete
2026-01-02 00:17:36.737555 | controller | 6d9d912e060e: Pull complete
2026-01-02 00:17:36.801863 | controller | 4b19ac3f17a8: Verifying Checksum
2026-01-02 00:17:36.802042 | controller | 4b19ac3f17a8: Download complete
2026-01-02 00:17:36.811230 | controller | 5dd542dd28d9: Pull complete
2026-01-02 00:17:58.440724 | controller | cef8beb99552: Pull complete
2026-01-02 00:17:58.487728 | controller | bd5557e101ad: Pull complete
2026-01-02 00:17:58.531413 | controller | e057c20fabdd: Pull complete
2026-01-02 00:17:58.552464 | controller | d16059cdae2e: Pull complete
2026-01-02 00:18:00.970039 | controller | 431e42d906a3: Pull complete
2026-01-02 00:18:01.020152 | controller | 7a0fd3b1d7c3: Pull complete
2026-01-02 00:18:01.076009 | controller | aacdb6db0c8c: Pull complete
2026-01-02 00:18:01.120222 | controller | 2e7f04ae8c8b: Pull complete
2026-01-02 00:18:01.160094 | controller | 4cd343027a44: Pull complete
2026-01-02 00:18:01.212360 | controller | 0b4379883f90: Pull complete
2026-01-02 00:18:01.252232 | controller | 3d9d6d9dfc91: Pull complete
2026-01-02 00:18:06.838602 | controller | 57d6314bd293: Pull complete
2026-01-02 00:18:28.435926 | controller | 774036afeb7f: Pull complete
2026-01-02 00:18:55.246459 | controller | 54b10f0e1645: Pull complete
2026-01-02 00:19:21.413966 | controller | 78cf439d9fdb: Pull complete
2026-01-02 00:19:47.975443 | controller | 36e5e20969c7: Pull complete
2026-01-02 00:20:15.242266 | controller | 4b19ac3f17a8: Pull complete
2026-01-02 00:20:15.278241 | controller | Digest: sha256:954c4edffb0905df64e23c8611b1c4a62db9af13062f51cad5b658e727238d41
2026-01-02 00:20:15.282788 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:1.10.1
2026-01-02 00:20:15.294654 | controller | quay.io/ansible/default-test-container:1.10.1
2026-01-02 00:20:15.302797 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2026-01-02 00:20:15.343939 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2026-01-02 00:20:16.910565 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2026-01-02 00:20:16.915158 | controller | 04a5f4cda3ee: Pulling fs layer
2026-01-02 00:20:16.915257 | controller | ff496a88c8ed: Pulling fs layer
2026-01-02 00:20:16.915293 | controller | 0ce83f459fe7: Pulling fs layer
2026-01-02 00:20:16.915325 | controller | 2e5170e1f099: Pulling fs layer
2026-01-02 00:20:16.915358 | controller | 7641eb41b08c: Pulling fs layer
2026-01-02 00:20:16.915390 | controller | 2e5170e1f099: Waiting
2026-01-02 00:20:16.915467 | controller | 7641eb41b08c: Waiting
2026-01-02 00:20:16.915501 | controller | ad15fa9da398: Pulling fs layer
2026-01-02 00:20:16.915534 | controller | 087d91352424: Pulling fs layer
2026-01-02 00:20:16.915567 | controller | 8b92efd6a100: Pulling fs layer
2026-01-02 00:20:16.915599 | controller | ad15fa9da398: Waiting
2026-01-02 00:20:16.915631 | controller | 087d91352424: Waiting
2026-01-02 00:20:16.915662 | controller | 8b92efd6a100: Waiting
2026-01-02 00:20:17.284347 | controller | ff496a88c8ed: Verifying Checksum
2026-01-02 00:20:17.284455 | controller | ff496a88c8ed: Download complete
2026-01-02 00:20:17.291674 | controller | 0ce83f459fe7: Verifying Checksum
2026-01-02 00:20:17.291823 | controller | 0ce83f459fe7: Download complete
2026-01-02 00:20:17.693069 | controller | 7641eb41b08c: Verifying Checksum
2026-01-02 00:20:17.693193 | controller | 7641eb41b08c: Download complete
2026-01-02 00:20:17.710139 | controller | 04a5f4cda3ee: Verifying Checksum
2026-01-02 00:20:17.713288 | controller | 04a5f4cda3ee: Download complete
2026-01-02 00:20:18.026876 | controller | 2e5170e1f099: Verifying Checksum
2026-01-02 00:20:18.028221 | controller | 2e5170e1f099: Download complete
2026-01-02 00:20:18.056382 | controller | ad15fa9da398: Verifying Checksum
2026-01-02 00:20:18.199301 | controller | ad15fa9da398: Download complete
2026-01-02 00:20:18.199398 | controller | 087d91352424: Verifying Checksum
2026-01-02 00:20:18.440412 | controller | 087d91352424: Download complete
2026-01-02 00:20:18.440507 | controller | 8b92efd6a100: Verifying Checksum
2026-01-02 00:20:20.252270 | controller | 8b92efd6a100: Download complete
2026-01-02 00:20:20.252364 | controller | 04a5f4cda3ee: Pull complete
2026-01-02 00:20:20.291557 | controller | ff496a88c8ed: Pull complete
2026-01-02 00:20:20.324771 | controller | 0ce83f459fe7: Pull complete
2026-01-02 00:20:21.973782 | controller | 2e5170e1f099: Pull complete
2026-01-02 00:20:22.020879 | controller | 7641eb41b08c: Pull complete
2026-01-02 00:20:22.051527 | controller | ad15fa9da398: Pull complete
2026-01-02 00:20:23.478386 | controller | 087d91352424: Pull complete
2026-01-02 00:20:23.599065 | controller | 8b92efd6a100: Pull complete
2026-01-02 00:20:23.665677 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2026-01-02 00:20:23.669363 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2026-01-02 00:20:23.678252 | controller | quay.io/ansible/pypi-test-container:1.0.0
2026-01-02 00:20:23.682229 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2026-01-02 00:20:32.523933 | controller | Run command: docker inspect 0674e73164fce1df07179e647531b43e21b080ca438f2c8e6661f8029a5edc1e
2026-01-02 00:20:32.757691 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2026-01-02 00:20:32.778855 | controller | Including collection: ansible.netcommon (292 files)
2026-01-02 00:20:32.778944 | controller | Including collection: ansible.network (23 files)
2026-01-02 00:20:32.793004 | controller | Including collection: ansible.utils (442 files)
2026-01-02 00:20:32.841788 | controller | Including collection: arista.eos (1022 files)
2026-01-02 00:20:32.895353 | controller | Including collection: cisco.ios (1174 files)
2026-01-02 00:20:32.945560 | controller | Including collection: cisco.iosxr (1044 files)
2026-01-02 00:20:33.027095 | controller | Including collection: cisco.nxos (1682 files)
2026-01-02 00:20:33.078299 | controller | Including collection: junipernetworks.junos (963 files)
2026-01-02 00:20:33.123837 | controller | Creating a payload archive containing 8379 files...
2026-01-02 00:20:35.544237 | controller | Created a 6899680 byte payload archive containing 8379 files in 2 seconds.
2026-01-02 00:20:35.548672 | controller | Assuming Docker is available on localhost.
2026-01-02 00:20:35.549159 | 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-01-02 00:20:36.117831 | controller | Run command: docker exec -i 01ea87ab333ec29f6fe254f7fdd53f999537e92b023303ca9c4eb9d6fbf2db1c dd of=/root/docker.sh bs=65536
2026-01-02 00:20:36.218910 | controller | Run command: docker exec 01ea87ab333ec29f6fe254f7fdd53f999537e92b023303ca9c4eb9d6fbf2db1c /bin/bash /root/docker.sh
2026-01-02 00:20:36.300463 | controller | Run command: docker exec -i 01ea87ab333ec29f6fe254f7fdd53f999537e92b023303ca9c4eb9d6fbf2db1c dd of=/root/ansible.tgz bs=65536
2026-01-02 00:20:36.401004 | controller | Run command: docker exec 01ea87ab333ec29f6fe254f7fdd53f999537e92b023303ca9c4eb9d6fbf2db1c mkdir /root/ansible
2026-01-02 00:20:36.462885 | controller | Run command: docker exec 01ea87ab333ec29f6fe254f7fdd53f999537e92b023303ca9c4eb9d6fbf2db1c tar oxzf /root/ansible.tgz -C /root/ansible
2026-01-02 00:20:37.663586 | controller | Run command: docker exec 01ea87ab333ec29f6fe254f7fdd53f999537e92b023303ca9c4eb9d6fbf2db1c /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-azwlermp.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2026-01-02 00:20:38.175538 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2026-01-02 00:20:38.175975 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2026-01-02 00:20:38.176508 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2026-01-02 00:20:38.178548 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2026-01-02 00:20:38.257268 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2026-01-02 00:20:38.263045 | controller | Read 0 sanity test ignore line(s) for Ansible 2.9 from: tests/sanity/ignore-2.9.txt
2026-01-02 00:20:38.270304 | controller | No tests applicable.
2026-01-02 00:20:38.270593 | controller | Running sanity test 'ansible-doc' with Python 3.6
2026-01-02 00:20:38.271957 | controller | No tests applicable.
2026-01-02 00:20:38.272408 | controller | Run command: /usr/bin/python2.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2026-01-02 00:20:38.370104 | controller | Running sanity test 'compile' with Python 2.6
2026-01-02 00:20:38.370674 | controller | No tests applicable.
2026-01-02 00:20:38.371500 | controller | Run command: /usr/bin/python2.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2026-01-02 00:20:38.582499 | controller | Running sanity test 'compile' with Python 2.7
2026-01-02 00:20:38.582781 | controller | No tests applicable.
2026-01-02 00:20:38.583230 | controller | Run command: /usr/bin/python3.5 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2026-01-02 00:20:38.712802 | controller | Running sanity test 'compile' with Python 3.5
2026-01-02 00:20:38.713193 | controller | No tests applicable.
2026-01-02 00:20:38.713397 | controller | Running sanity test 'compile' with Python 3.6
2026-01-02 00:20:38.713412 | controller | No tests applicable.
2026-01-02 00:20:38.713808 | controller | Run command: /usr/bin/python3.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2026-01-02 00:20:38.826382 | controller | Running sanity test 'compile' with Python 3.7
2026-01-02 00:20:38.826649 | controller | No tests applicable.
2026-01-02 00:20:38.827043 | controller | Run command: /usr/bin/python3.8 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2026-01-02 00:20:38.934773 | controller | Running sanity test 'compile' with Python 3.8
2026-01-02 00:20:38.935054 | controller | No tests applicable.
2026-01-02 00:20:38.935184 | controller | Running sanity test 'empty-init' with Python 3.6
2026-01-02 00:20:38.935422 | controller | No tests applicable.
2026-01-02 00:20:38.935527 | controller | Running sanity test 'future-import-boilerplate' with Python 3.6
2026-01-02 00:20:38.935736 | controller | No tests applicable.
2026-01-02 00:20:38.936970 | controller | Running sanity test 'ignores'
2026-01-02 00:20:38.937002 | 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-01-02 00:20:39.761740 | controller | Ignoring packaging: markers 'python_version < "3.6"' don't match your environment
2026-01-02 00:20:39.766758 | controller | Ignoring coverage: markers 'python_version > "3.7"' don't match your environment
2026-01-02 00:20:39.768294 | controller | Ignoring cryptography: markers 'python_version < "2.7"' don't match your environment
2026-01-02 00:20:39.771332 | controller | Ignoring deepdiff: markers 'python_version < "3"' don't match your environment
2026-01-02 00:20:39.773088 | controller | Ignoring jinja2: markers 'python_version < "2.7"' don't match your environment
2026-01-02 00:20:39.774642 | controller | Ignoring urllib3: markers 'python_version < "2.7"' don't match your environment
2026-01-02 00:20:39.777875 | controller | Ignoring sphinx: markers 'python_version < "2.7"' don't match your environment
2026-01-02 00:20:39.785279 | controller | Ignoring wheel: markers 'python_version < "2.7"' don't match your environment
2026-01-02 00:20:39.788453 | controller | Ignoring yamllint: markers 'python_version < "2.7"' don't match your environment
2026-01-02 00:20:39.793909 | controller | Ignoring paramiko: markers 'python_version < "2.7"' don't match your environment
2026-01-02 00:20:39.796905 | controller | Ignoring pytest: markers 'python_version < "2.7"' don't match your environment
2026-01-02 00:20:39.798729 | controller | Ignoring pytest: markers 'python_version == "2.7"' don't match your environment
2026-01-02 00:20:39.800198 | controller | Ignoring pytest-forked: markers 'python_version < "2.7"' don't match your environment
2026-01-02 00:20:39.805192 | controller | Ignoring requests: markers 'python_version < "2.7"' don't match your environment
2026-01-02 00:20:39.812449 | controller | Ignoring virtualenv: markers 'python_version < "2.7"' don't match your environment
2026-01-02 00:20:39.815245 | controller | Ignoring pathspec: markers 'python_version < "2.7"' don't match your environment
2026-01-02 00:20:39.817108 | controller | Ignoring pyopenssl: markers 'python_version < "2.7"' don't match your environment
2026-01-02 00:20:39.820018 | controller | Ignoring pyyaml: markers 'python_version < "2.7"' don't match your environment
2026-01-02 00:20:39.821663 | controller | Ignoring pycparser: markers 'python_version < "2.7"' don't match your environment
2026-01-02 00:20:39.827231 | controller | Ignoring xmltodict: markers 'python_version < "2.7"' don't match your environment
2026-01-02 00:20:39.828581 | controller | Ignoring lxml: markers 'python_version < "2.7"' don't match your environment
2026-01-02 00:20:39.830135 | controller | Ignoring pyvmomi: markers 'python_version < "2.7"' don't match your environment
2026-01-02 00:20:39.832367 | controller | Ignoring MarkupSafe: markers 'python_version < "3.6"' don't match your environment
2026-01-02 00:20:39.836770 | controller | Ignoring setuptools: markers 'python_version <= "2.7"' don't match your environment
2026-01-02 00:20:39.842405 | controller | Ignoring websocket-client: markers 'python_version < "3"' don't match your environment
2026-01-02 00:20:39.843825 | controller | Ignoring certifi: markers 'python_version < "3"' don't match your environment
2026-01-02 00:20:39.862096 | controller | Looking in indexes: http://172.17.0.2:3141/root/pypi/+simple/
2026-01-02 00:20:39.863073 | 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-01-02 00:20:39.891242 | 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-01-02 00:20:39.894750 | 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-01-02 00:20:39.897937 | 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-01-02 00:20:39.900281 | 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-01-02 00:20:39.906776 | 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-01-02 00:20:39.914036 | 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-01-02 00:20:39.918297 | 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-01-02 00:20:39.937013 | 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-01-02 00:20:39.937082 | 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-01-02 00:20:39.940837 | 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-01-02 00:20:39.942906 | 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-01-02 00:20:39.947855 | 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-01-02 00:20:39.950097 | 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-01-02 00:20:39.952877 | controller | Collecting astroid==2.3.3 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 54))
2026-01-02 00:20:54.977112 | controller |   WARNING: Retrying (Retry(total=4, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/astroid/
2026-01-02 00:21:10.493545 | controller |   WARNING: Retrying (Retry(total=3, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/astroid/
2026-01-02 00:21:26.510259 | controller |   WARNING: Retrying (Retry(total=2, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/astroid/
2026-01-02 00:21:43.528714 | controller |   WARNING: Retrying (Retry(total=1, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/astroid/
2026-01-02 00:22:02.548991 | controller |   WARNING: Retrying (Retry(total=0, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/astroid/
2026-01-02 00:22:17.559563 | controller |   ERROR: Could not find a version that satisfies the requirement astroid==2.3.3 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 54)) (from versions: none)
2026-01-02 00:22:17.561608 | controller | ERROR: No matching distribution found for astroid==2.3.3 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 54))
2026-01-02 00:22:17.694987 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2026-01-02 00:22:17.695138 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2026-01-02 00:22:17.695219 | controller | Removing custom PyPI hosts entries: /etc/hosts
2026-01-02 00:22:17.695234 | 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-01-02 00:22:17.748358 | controller | Run command: docker exec 01ea87ab333ec29f6fe254f7fdd53f999537e92b023303ca9c4eb9d6fbf2db1c tar czf /root/output.tgz --exclude .tmp -C /root/ansible/ansible_collections/ansible/network/tests output
2026-01-02 00:22:17.847355 | controller | Run command: docker exec -i 01ea87ab333ec29f6fe254f7fdd53f999537e92b023303ca9c4eb9d6fbf2db1c dd if=/root/output.tgz bs=65536
2026-01-02 00:22:17.922499 | controller | Run command: tar oxzf /tmp/ansible-result-3_pukxv9.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2026-01-02 00:22:17.935408 | controller | Run command: docker rm -f 0674e73164fce1df07179e647531b43e21b080ca438f2c8e6661f8029a5edc1e
2026-01-02 00:22:18.228301 | controller | Run command: docker rm -f 01ea87ab333ec29f6fe254f7fdd53f999537e92b023303ca9c4eb9d6fbf2db1c
2026-01-02 00:22:19.015990 | controller | ERROR: Command "docker exec 01ea87ab333ec29f6fe254f7fdd53f999537e92b023303ca9c4eb9d6fbf2db1c /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-azwlermp.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2026-01-02 00:22:19.494332 | controller | ERROR
2026-01-02 00:22:19.494559 | controller | {
2026-01-02 00:22:19.494619 | controller |   "delta": "0:05:41.572239",
2026-01-02 00:22:19.494661 | controller |   "end": "2026-01-02 00:22:19.084148",
2026-01-02 00:22:19.494770 | controller |   "msg": "non-zero return code",
2026-01-02 00:22:19.494813 | controller |   "rc": 1,
2026-01-02 00:22:19.494849 | controller |   "start": "2026-01-02 00:16:37.511909"
2026-01-02 00:22:19.494883 | controller | }
failure
2026-01-02 00:22:19.505295 | 
2026-01-02 00:22:19.505397 | PLAY RECAP
2026-01-02 00:22:19.505484 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2026-01-02 00:22:19.505528 | 
2026-01-02 00:22:19.625304 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-01-02 00:22:19.627239 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-02 00:22:20.189623 | 
2026-01-02 00:22:20.189746 | PLAY [all]
2026-01-02 00:22:20.211077 | 
2026-01-02 00:22:20.211173 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-01-02 00:22:23.222085 | controller | changed: non-zero return code
2026-01-02 00:22:23.238130 | 
2026-01-02 00:22:23.238331 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-01-02 00:22:23.257876 | controller | skipping: Conditional result was False
2026-01-02 00:22:23.268518 | 
2026-01-02 00:22:23.268645 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-01-02 00:22:23.314211 | 
2026-01-02 00:22:23.314569 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-01-02 00:22:23.343894 | 
2026-01-02 00:22:23.344108 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-01-02 00:22:23.371752 | controller | skipping: Conditional result was False
2026-01-02 00:22:23.380293 | 
2026-01-02 00:22:23.380385 | LOOP [fetch-subunit-output : Generate subunit file]
2026-01-02 00:22:23.414313 | 
2026-01-02 00:22:23.414480 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-01-02 00:22:23.440036 | controller | skipping: Conditional result was False
2026-01-02 00:22:23.457597 | 
2026-01-02 00:22:23.457782 | TASK [fetch-subunit-output : Remove the temporary file]
2026-01-02 00:22:23.484361 | controller | skipping: Conditional result was False
2026-01-02 00:22:23.499522 | 
2026-01-02 00:22:23.499773 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-01-02 00:22:23.529058 | controller | skipping: Conditional result was False
2026-01-02 00:22:23.580268 | 
2026-01-02 00:22:23.580367 | PLAY RECAP
2026-01-02 00:22:23.580432 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-01-02 00:22:23.580470 | 
2026-01-02 00:22:23.716525 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-02 00:22:23.718345 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-01-02 00:22:24.326587 | 
2026-01-02 00:22:24.326729 | PLAY [all:!appliance*]
2026-01-02 00:22:24.347918 | 
2026-01-02 00:22:24.348036 | TASK [unregister the node]
2026-01-02 00:22:24.702626 | controller | skipping: Conditional result was False
2026-01-02 00:22:24.716733 | 
2026-01-02 00:22:24.716914 | TASK [include_role : fetch-output]
2026-01-02 00:22:24.760809 | controller | ok
2026-01-02 00:22:24.780912 | 
2026-01-02 00:22:24.781001 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-02 00:22:24.856052 | controller | skipping: Conditional result was False
2026-01-02 00:22:24.865468 | 
2026-01-02 00:22:24.865600 | TASK [fetch-output : Set log path for single node]
2026-01-02 00:22:24.909965 | controller | ok
2026-01-02 00:22:24.918086 | 
2026-01-02 00:22:24.918303 | LOOP [fetch-output : Ensure local output dirs]
2026-01-02 00:22:25.375971 | controller -> localhost | ok: "/var/lib/zuul/builds/c7b2158372ef42a1a475ba3a717451c6/work/logs"
2026-01-02 00:22:25.635514 | controller -> localhost | changed: "/var/lib/zuul/builds/c7b2158372ef42a1a475ba3a717451c6/work/artifacts"
2026-01-02 00:22:25.891252 | controller -> localhost | changed: "/var/lib/zuul/builds/c7b2158372ef42a1a475ba3a717451c6/work/docs"
2026-01-02 00:22:25.905935 | 
2026-01-02 00:22:25.906068 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-02 00:22:28.178978 | controller | changed:
2026-01-02 00:22:28.179328 | controller | .d..t...... ./
2026-01-02 00:22:28.179392 | controller | cd+++++++++ controller/
2026-01-02 00:22:28.179461 | controller | changed: All items complete
2026-01-02 00:22:28.179508 | 
2026-01-02 00:22:30.259499 | controller | changed: .d..t...... ./
2026-01-02 00:22:32.373211 | controller | changed: .d..t...... ./
2026-01-02 00:22:32.406407 | 
2026-01-02 00:22:32.406584 | TASK [include_role : fetch-output-openshift]
2026-01-02 00:22:32.434199 | controller | skipping: Conditional result was False
2026-01-02 00:22:32.448846 | 
2026-01-02 00:22:32.448989 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-02 00:22:32.495399 | controller | skipping: Conditional result was False
2026-01-02 00:22:32.505653 | controller | skipping: Conditional result was False
2026-01-02 00:22:32.559857 | 
2026-01-02 00:22:32.559958 | PLAY [localhost]
2026-01-02 00:22:32.576840 | 
2026-01-02 00:22:32.576922 | TASK [Run Zuul manifest role]
2026-01-02 00:22:32.598184 | localhost | ok
2026-01-02 00:22:32.615894 | 
2026-01-02 00:22:32.615989 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-02 00:22:33.057932 | localhost | changed
2026-01-02 00:22:33.068137 | 
2026-01-02 00:22:33.068314 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-02 00:22:33.114357 | localhost | ok
2026-01-02 00:22:33.132938 | 
2026-01-02 00:22:33.133063 | TASK [Set zuul-log-path fact]
2026-01-02 00:22:33.194288 | localhost | ok
2026-01-02 00:22:33.216835 | 
2026-01-02 00:22:33.216972 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-02 00:22:33.256002 | localhost | ok
2026-01-02 00:22:33.265084 | 
2026-01-02 00:22:33.265145 | LOOP [Run upload-logs-swift role]
2026-01-02 00:22:33.309421 | localhost | Output suppressed because no_log was given
2026-01-02 00:22:33.344065 | 
2026-01-02 00:22:33.344175 | TASK [Set zuul-log-path fact]
2026-01-02 00:22:33.389999 | localhost | skipping: Conditional result was False
2026-01-02 00:22:33.400572 | 
2026-01-02 00:22:33.400764 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-01-02 00:22:33.804311 | localhost -> localhost | ok: Runtime: 0:00:00.008704
2026-01-02 00:22:33.815297 | 
2026-01-02 00:22:33.815436 | TASK [upload-logs-swift : Upload logs to swift]