2025-12-03 00:27:10.779600 | Job console starting...
2025-12-03 00:27:10.788642 | Updating repositories
2025-12-03 00:27:10.931489 | Preparing job workspace
2025-12-03 00:27:14.788022 | Running Ansible setup...
2025-12-03 00:27:22.599653 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-12-03 00:27:23.149442 | 
2025-12-03 00:27:23.150074 | PLAY [localhost]
2025-12-03 00:27:23.163198 | 
2025-12-03 00:27:23.163273 | TASK [Gathering Facts]
2025-12-03 00:27:24.092418 | localhost | ok
2025-12-03 00:27:24.114995 | 
2025-12-03 00:27:24.115152 | TASK [Setup log path fact]
2025-12-03 00:27:24.133350 | localhost | ok
2025-12-03 00:27:24.147252 | 
2025-12-03 00:27:24.147381 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-03 00:27:24.176358 | localhost | ok
2025-12-03 00:27:24.188450 | 
2025-12-03 00:27:24.188584 | TASK [emit-job-header : Print job information]
2025-12-03 00:27:24.226347 | # Job Information
2025-12-03 00:27:24.226492 | Ansible Version: 2.15.12
2025-12-03 00:27:24.226522 | Job: ansible-test-sanity-docker-stable-2.10
2025-12-03 00:27:24.226543 | Pipeline: periodic
2025-12-03 00:27:24.226562 | Executor: ze04.softwarefactory-project.io
2025-12-03 00:27:24.226581 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-12-03 00:27:24.226602 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/478/ansible/4787182f95a34a9a999608aedac95ca0/
2025-12-03 00:27:24.226622 | Event ID: 08d03c5eb6c54d3f91ad96751eb57522
2025-12-03 00:27:24.230270 | 
2025-12-03 00:27:24.230333 | LOOP [emit-job-header : Print node information]
2025-12-03 00:27:24.375822 | localhost | ok:
2025-12-03 00:27:24.376122 | localhost | # Node Information
2025-12-03 00:27:24.376160 | localhost | Inventory Hostname: controller
2025-12-03 00:27:24.376181 | localhost | Hostname: np0005543043
2025-12-03 00:27:24.376201 | localhost | Username: zuul
2025-12-03 00:27:24.376226 | localhost | Distro: Fedora 37
2025-12-03 00:27:24.376245 | localhost | Provider: ansible-vexxhost-ams1
2025-12-03 00:27:24.376263 | localhost | Region: ams1
2025-12-03 00:27:24.376279 | localhost | Label: ansible-fedora-37-1vcpu
2025-12-03 00:27:24.376296 | localhost | Product Name: OpenStack Nova
2025-12-03 00:27:24.376313 | localhost | Interface IP: 38.129.16.115
2025-12-03 00:27:24.393990 | 
2025-12-03 00:27:24.394108 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-03 00:27:24.785290 | localhost -> localhost | changed
2025-12-03 00:27:24.797077 | 
2025-12-03 00:27:24.797222 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-03 00:27:25.690885 | localhost -> localhost | changed
2025-12-03 00:27:25.711280 | 
2025-12-03 00:27:25.711352 | PLAY [all:!appliance*]
2025-12-03 00:27:25.728190 | 
2025-12-03 00:27:25.728302 | TASK [include_role : start-zuul-console]
2025-12-03 00:27:25.751022 | controller | ok
2025-12-03 00:27:25.769562 | 
2025-12-03 00:27:25.769643 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-03 00:27:26.786593 | controller | ok
2025-12-03 00:27:26.798375 | 
2025-12-03 00:27:26.798458 | TASK [use-our-mirror : Retrieve the IP address]
2025-12-03 00:27:29.010033 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-12-03 00:27:29.025547 | 
2025-12-03 00:27:29.025748 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-12-03 00:27:29.408119 | controller | skipping: Conditional result was False
2025-12-03 00:27:29.422550 | 
2025-12-03 00:27:29.422765 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-12-03 00:27:29.452279 | controller | skipping: Conditional result was False
2025-12-03 00:27:29.470628 | 
2025-12-03 00:27:29.470836 | TASK [use-our-mirror : Create the podman configuration directory]
2025-12-03 00:27:29.508642 | controller | skipping: Conditional result was False
2025-12-03 00:27:29.522462 | 
2025-12-03 00:27:29.522610 | TASK [use-our-mirror : Copy the podman configuration]
2025-12-03 00:27:29.548947 | controller | skipping: Conditional result was False
2025-12-03 00:27:29.561861 | 
2025-12-03 00:27:29.562042 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-12-03 00:27:29.588311 | controller | skipping: Conditional result was False
2025-12-03 00:27:29.602859 | 
2025-12-03 00:27:29.603024 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-12-03 00:27:29.630253 | controller | skipping: Conditional result was False
2025-12-03 00:27:29.654523 | 
2025-12-03 00:27:29.654736 | TASK [Disable Fedora Modular]
2025-12-03 00:27:30.838779 | controller | changed
2025-12-03 00:27:30.850638 | 
2025-12-03 00:27:30.850810 | TASK [Enable EPEL]
2025-12-03 00:27:30.877240 | controller | skipping: Conditional result was False
2025-12-03 00:27:30.890060 | 
2025-12-03 00:27:30.890193 | TASK [Register the RHEL node]
2025-12-03 00:27:31.306062 | 
2025-12-03 00:27:31.306344 | TASK [Show the subscription-manager status]
2025-12-03 00:27:31.704811 | controller | skipping: Conditional result was False
2025-12-03 00:27:31.719727 | 
2025-12-03 00:27:31.719891 | TASK [Enable EPEL on RHEL]
2025-12-03 00:27:32.109735 | controller | skipping: Conditional result was False
2025-12-03 00:27:32.123169 | 
2025-12-03 00:27:32.123319 | TASK [Install git and tox]
2025-12-03 00:29:29.007202 | controller | changed
2025-12-03 00:29:29.026798 | 
2025-12-03 00:29:29.026957 | TASK [include_role : prepare-workspace]
2025-12-03 00:29:29.059544 | controller | ok
2025-12-03 00:29:29.081224 | 
2025-12-03 00:29:29.081336 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-03 00:29:29.893498 | controller | ok
2025-12-03 00:29:29.908711 | 
2025-12-03 00:29:29.908891 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-03 00:29:45.258749 | controller | Output suppressed because no_log was given
2025-12-03 00:29:45.278137 | 
2025-12-03 00:29:45.278275 | TASK [include_role : prepare-workspace-openshift]
2025-12-03 00:29:45.297583 | controller | skipping: Conditional result was False
2025-12-03 00:29:45.356754 | 
2025-12-03 00:29:45.356833 | PLAY [all:!appliance]
2025-12-03 00:29:45.372547 | 
2025-12-03 00:29:45.372620 | TASK [Run add-build-sshkey role (RSA)]
2025-12-03 00:29:45.401886 | controller | ok
2025-12-03 00:29:45.417130 | 
2025-12-03 00:29:45.417225 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-03 00:29:45.636947 | controller -> localhost | ok
2025-12-03 00:29:45.652737 | 
2025-12-03 00:29:45.652895 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-03 00:29:45.684018 | controller | ok
2025-12-03 00:29:45.709026 | controller | included: /var/lib/zuul/builds/4787182f95a34a9a999608aedac95ca0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-03 00:29:45.717744 | 
2025-12-03 00:29:45.717836 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-03 00:29:46.356254 | controller -> localhost | Generating public/private rsa key pair.
2025-12-03 00:29:46.356545 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/4787182f95a34a9a999608aedac95ca0/work/4787182f95a34a9a999608aedac95ca0_id_rsa.
2025-12-03 00:29:46.356589 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/4787182f95a34a9a999608aedac95ca0/work/4787182f95a34a9a999608aedac95ca0_id_rsa.pub.
2025-12-03 00:29:46.356621 | controller -> localhost | The key fingerprint is:
2025-12-03 00:29:46.356651 | controller -> localhost | SHA256:fSHKVsCIvmSTGijJtJofBQqRgj23kbjHQ8rr5n9hDx8 zuul-build-sshkey
2025-12-03 00:29:46.356705 | controller -> localhost | The key's randomart image is:
2025-12-03 00:29:46.356743 | controller -> localhost | +---[RSA 2048]----+
2025-12-03 00:29:46.356772 | controller -> localhost | |o+ . o o.        |
2025-12-03 00:29:46.356802 | controller -> localhost | |=.= * . ..       |
2025-12-03 00:29:46.356829 | controller -> localhost | |==.X +    o .    |
2025-12-03 00:29:46.356858 | controller -> localhost | |=o= %  . + . .   |
2025-12-03 00:29:46.356886 | controller -> localhost | |o. O +  S . .    |
2025-12-03 00:29:46.356927 | controller -> localhost | |o + . +.E  .     |
2025-12-03 00:29:46.356961 | controller -> localhost | | o . . = .       |
2025-12-03 00:29:46.356991 | controller -> localhost | |  +   . o        |
2025-12-03 00:29:46.357018 | controller -> localhost | | o....           |
2025-12-03 00:29:46.357049 | controller -> localhost | +----[SHA256]-----+
2025-12-03 00:29:46.357126 | controller -> localhost | ok: Runtime: 0:00:00.185974
2025-12-03 00:29:46.367999 | 
2025-12-03 00:29:46.368203 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-03 00:29:46.415859 | controller | ok
2025-12-03 00:29:46.433552 | controller | included: /var/lib/zuul/builds/4787182f95a34a9a999608aedac95ca0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-03 00:29:46.449886 | 
2025-12-03 00:29:46.449983 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-03 00:29:46.476463 | controller | skipping: Conditional result was False
2025-12-03 00:29:46.483702 | 
2025-12-03 00:29:46.483767 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-03 00:29:47.487409 | controller | changed
2025-12-03 00:29:47.500735 | 
2025-12-03 00:29:47.500890 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-03 00:29:48.184988 | controller | ok
2025-12-03 00:29:48.198085 | 
2025-12-03 00:29:48.198220 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-03 00:29:51.228475 | controller | changed
2025-12-03 00:29:51.237364 | 
2025-12-03 00:29:51.237503 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-03 00:29:54.282832 | controller | changed
2025-12-03 00:29:54.297285 | 
2025-12-03 00:29:54.297478 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-03 00:29:54.334042 | controller | skipping: Conditional result was False
2025-12-03 00:29:54.348329 | 
2025-12-03 00:29:54.348484 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-03 00:29:54.769965 | controller -> localhost | changed
2025-12-03 00:29:54.795209 | 
2025-12-03 00:29:54.795353 | TASK [add-build-sshkey : Add back temp key]
2025-12-03 00:29:55.101500 | controller -> localhost | Identity added: /var/lib/zuul/builds/4787182f95a34a9a999608aedac95ca0/work/4787182f95a34a9a999608aedac95ca0_id_rsa (zuul-build-sshkey)
2025-12-03 00:29:55.101874 | controller -> localhost | ok: Runtime: 0:00:00.009529
2025-12-03 00:29:55.118665 | 
2025-12-03 00:29:55.118876 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-03 00:29:56.031638 | controller | ok
2025-12-03 00:29:56.045209 | 
2025-12-03 00:29:56.045361 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-03 00:29:56.089279 | controller | skipping: Conditional result was False
2025-12-03 00:29:56.116873 | 
2025-12-03 00:29:56.117187 | TASK [Run add-build-sshkey role (ECDSA)]
2025-12-03 00:29:56.161110 | controller | ok
2025-12-03 00:29:56.189340 | 
2025-12-03 00:29:56.189496 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-03 00:29:56.522218 | controller -> localhost | ok
2025-12-03 00:29:56.532062 | 
2025-12-03 00:29:56.532188 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-03 00:29:56.579894 | controller | ok
2025-12-03 00:29:56.600971 | controller | included: /var/lib/zuul/builds/4787182f95a34a9a999608aedac95ca0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-03 00:29:56.611180 | 
2025-12-03 00:29:56.611308 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-03 00:29:57.013627 | controller -> localhost | Generating public/private ecdsa key pair.
2025-12-03 00:29:57.013823 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/4787182f95a34a9a999608aedac95ca0/work/4787182f95a34a9a999608aedac95ca0_id_ecdsa.
2025-12-03 00:29:57.013852 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/4787182f95a34a9a999608aedac95ca0/work/4787182f95a34a9a999608aedac95ca0_id_ecdsa.pub.
2025-12-03 00:29:57.013883 | controller -> localhost | The key fingerprint is:
2025-12-03 00:29:57.013904 | controller -> localhost | SHA256:PznV3ggo3Z1JjW4ouQVTQYgn2oLIZkU04KdjNh3RFx8 zuul-build-sshkey
2025-12-03 00:29:57.013936 | controller -> localhost | The key's randomart image is:
2025-12-03 00:29:57.013957 | controller -> localhost | +---[ECDSA 521]---+
2025-12-03 00:29:57.013978 | controller -> localhost | |  .+=.  .oEo+.   |
2025-12-03 00:29:57.013997 | controller -> localhost | | .  .o. +.oo   o |
2025-12-03 00:29:57.014016 | controller -> localhost | | ..oo. + o+   o .|
2025-12-03 00:29:57.014035 | controller -> localhost | |  =+..o .. * * o |
2025-12-03 00:29:57.014055 | controller -> localhost | | o* .  .S = * B  |
2025-12-03 00:29:57.014075 | controller -> localhost | | o o     o * + o |
2025-12-03 00:29:57.014093 | controller -> localhost | |          *   o .|
2025-12-03 00:29:57.014112 | controller -> localhost | |           o     |
2025-12-03 00:29:57.014131 | controller -> localhost | |                 |
2025-12-03 00:29:57.014151 | controller -> localhost | +----[SHA256]-----+
2025-12-03 00:29:57.014208 | controller -> localhost | ok: Runtime: 0:00:00.019870
2025-12-03 00:29:57.020625 | 
2025-12-03 00:29:57.020718 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-03 00:29:57.054433 | controller | ok
2025-12-03 00:29:57.061777 | controller | included: /var/lib/zuul/builds/4787182f95a34a9a999608aedac95ca0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-03 00:29:57.070555 | 
2025-12-03 00:29:57.070647 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-03 00:29:57.084933 | controller | skipping: Conditional result was False
2025-12-03 00:29:57.092641 | 
2025-12-03 00:29:57.092726 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-03 00:29:58.019761 | controller | changed
2025-12-03 00:29:58.034346 | 
2025-12-03 00:29:58.034433 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-03 00:29:58.704894 | controller | ok
2025-12-03 00:29:58.756449 | 
2025-12-03 00:29:58.756593 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-03 00:30:01.772499 | controller | changed
2025-12-03 00:30:01.786282 | 
2025-12-03 00:30:01.786615 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-03 00:30:04.805944 | controller | changed
2025-12-03 00:30:04.818390 | 
2025-12-03 00:30:04.818536 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-03 00:30:04.855229 | controller | skipping: Conditional result was False
2025-12-03 00:30:04.869901 | 
2025-12-03 00:30:04.870086 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-03 00:30:05.174913 | controller -> localhost | changed
2025-12-03 00:30:05.186812 | 
2025-12-03 00:30:05.186888 | TASK [add-build-sshkey : Add back temp key]
2025-12-03 00:30:05.517218 | controller -> localhost | Identity added: /var/lib/zuul/builds/4787182f95a34a9a999608aedac95ca0/work/4787182f95a34a9a999608aedac95ca0_id_ecdsa (zuul-build-sshkey)
2025-12-03 00:30:05.517467 | controller -> localhost | ok: Runtime: 0:00:00.010088
2025-12-03 00:30:05.528513 | 
2025-12-03 00:30:05.528611 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-03 00:30:06.256167 | controller | ok
2025-12-03 00:30:06.264393 | 
2025-12-03 00:30:06.264485 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-03 00:30:06.309966 | controller | skipping: Conditional result was False
2025-12-03 00:30:06.326111 | 
2025-12-03 00:30:06.326207 | TASK [include_role : remove-zuul-sshkey]
2025-12-03 00:30:06.350726 | controller | skipping: Conditional result was False
2025-12-03 00:30:06.361303 | 
2025-12-03 00:30:06.361408 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-03 00:30:07.013252 | controller | ok: "logs"
2025-12-03 00:30:07.013551 | controller | ok: All items complete
2025-12-03 00:30:07.013591 | 
2025-12-03 00:30:07.628437 | controller | ok: "artifacts"
2025-12-03 00:30:08.234069 | controller | ok: "docs"
2025-12-03 00:30:08.256366 | 
2025-12-03 00:30:08.256554 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-03 00:30:08.952362 | controller | changed: "logs"
2025-12-03 00:30:09.572945 | controller | changed: "artifacts"
2025-12-03 00:30:10.181569 | controller | changed: "docs"
2025-12-03 00:30:10.241091 | 
2025-12-03 00:30:10.241216 | PLAY RECAP
2025-12-03 00:30:10.241275 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-12-03 00:30:10.241313 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-03 00:30:10.241340 | 
2025-12-03 00:30:10.374582 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-12-03 00:30:10.375459 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-03 00:30:10.935713 | 
2025-12-03 00:30:10.935821 | PLAY [all]
2025-12-03 00:30:10.958627 | 
2025-12-03 00:30:10.958750 | TASK [Install binary dependencies]
2025-12-03 00:30:11.008552 | controller | ok
2025-12-03 00:30:11.027992 | 
2025-12-03 00:30:11.028081 | TASK [bindep : Include find tasks]
2025-12-03 00:30:11.056761 | controller | ok
2025-12-03 00:30:11.064270 | controller | included: /var/lib/zuul/builds/4787182f95a34a9a999608aedac95ca0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-12-03 00:30:11.070340 | 
2025-12-03 00:30:11.070401 | TASK [bindep : Look for bindep.txt]
2025-12-03 00:30:12.010505 | controller | ok
2025-12-03 00:30:12.033282 | 
2025-12-03 00:30:12.033495 | TASK [bindep : Define bindep_file fact]
2025-12-03 00:30:12.059020 | controller | skipping: Conditional result was False
2025-12-03 00:30:12.068928 | 
2025-12-03 00:30:12.069048 | TASK [bindep : Look for other-requirements.txt]
2025-12-03 00:30:12.718336 | controller | ok
2025-12-03 00:30:12.736284 | 
2025-12-03 00:30:12.736521 | TASK [bindep : Define bindep_file fact]
2025-12-03 00:30:12.776983 | controller | skipping: Conditional result was False
2025-12-03 00:30:12.790583 | 
2025-12-03 00:30:12.790792 | TASK [bindep : Look for bindep fallback file]
2025-12-03 00:30:12.828566 | controller | skipping: Conditional result was False
2025-12-03 00:30:12.842713 | 
2025-12-03 00:30:12.842876 | TASK [bindep : Define bindep_file fact]
2025-12-03 00:30:12.877243 | controller | skipping: Conditional result was False
2025-12-03 00:30:12.895286 | 
2025-12-03 00:30:12.895406 | TASK [bindep : Include bindep tasks]
2025-12-03 00:30:12.920387 | controller | skipping: Conditional result was False
2025-12-03 00:30:12.929375 | 
2025-12-03 00:30:12.929513 | TASK [bindep : Include install tasks]
2025-12-03 00:30:12.957443 | controller | skipping: Conditional result was False
2025-12-03 00:30:12.963632 | 
2025-12-03 00:30:12.963748 | LOOP [bindep : Include package tasks]
2025-12-03 00:30:13.020520 | 
2025-12-03 00:30:13.020702 | TASK [Run test-setup role]
2025-12-03 00:30:13.039979 | controller | ok
2025-12-03 00:30:13.056546 | 
2025-12-03 00:30:13.056652 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-12-03 00:30:13.775991 | controller | ok
2025-12-03 00:30:13.790829 | 
2025-12-03 00:30:13.790998 | TASK [test-setup : Run tools/test-setup.sh]
2025-12-03 00:30:14.148976 | controller | skipping: Conditional result was False
2025-12-03 00:30:14.175345 | 
2025-12-03 00:30:14.175420 | PLAY RECAP
2025-12-03 00:30:14.175468 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-03 00:30:14.175495 | 
2025-12-03 00:30:14.269091 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-03 00:30:14.271517 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-12-03 00:30:14.858349 | 
2025-12-03 00:30:14.858471 | PLAY [controller]
2025-12-03 00:30:14.878359 | 
2025-12-03 00:30:14.878467 | TASK [Create the /root directory]
2025-12-03 00:30:15.953012 | controller | ok
2025-12-03 00:30:15.965524 | 
2025-12-03 00:30:15.965710 | TASK [Install glibc-langpack-en]
2025-12-03 00:30:23.485799 | controller | ok: Nothing to do
2025-12-03 00:30:23.497874 | 
2025-12-03 00:30:23.498013 | TASK [Ensure controller directory exists]
2025-12-03 00:30:24.315809 | controller | changed
2025-12-03 00:30:24.328454 | 
2025-12-03 00:30:24.328595 | TASK [Install container runtime]
2025-12-03 00:30:24.377224 | controller | ok
2025-12-03 00:30:24.439369 | 
2025-12-03 00:30:24.439475 | LOOP [ensure-docker : Gather variables for each operating system]
2025-12-03 00:30:24.495861 | controller | ok: "/var/lib/zuul/builds/4787182f95a34a9a999608aedac95ca0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2025-12-03 00:30:24.518242 | 
2025-12-03 00:30:24.518399 | TASK [ensure-docker : Sanity check for distro selection]
2025-12-03 00:30:24.533336 | controller | skipping: Conditional result was False
2025-12-03 00:30:24.541625 | 
2025-12-03 00:30:24.541780 | TASK [ensure-docker : Sanity check for distro install]
2025-12-03 00:30:24.566767 | controller | skipping: Conditional result was False
2025-12-03 00:30:24.576209 | 
2025-12-03 00:30:24.576303 | TASK [ensure-docker : Sanity check for upstream install]
2025-12-03 00:30:24.600847 | controller | skipping: Conditional result was False
2025-12-03 00:30:24.613485 | 
2025-12-03 00:30:24.613781 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2025-12-03 00:30:24.639796 | controller | skipping: Conditional result was False
2025-12-03 00:30:24.647912 | 
2025-12-03 00:30:24.648004 | TASK [ensure-docker : Include tasks for docker install]
2025-12-03 00:30:24.679564 | controller | ok
2025-12-03 00:30:24.690806 | controller | included: /var/lib/zuul/builds/4787182f95a34a9a999608aedac95ca0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2025-12-03 00:30:24.702126 | 
2025-12-03 00:30:24.702241 | TASK [ensure-docker : Not Implemented]
2025-12-03 00:30:24.727027 | controller | skipping: Conditional result was False
2025-12-03 00:30:24.736420 | 
2025-12-03 00:30:24.736514 | TASK [Set up docker mirrors]
2025-12-03 00:30:24.758540 | controller | ok
2025-12-03 00:30:24.784418 | 
2025-12-03 00:30:24.784536 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2025-12-03 00:30:24.819440 | controller | skipping: Conditional result was False
2025-12-03 00:30:24.825814 | 
2025-12-03 00:30:24.825897 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2025-12-03 00:30:24.850035 | controller | skipping: Conditional result was False
2025-12-03 00:30:24.856466 | 
2025-12-03 00:30:24.856540 | TASK [use-docker-mirror : Set docker_mirror fact]
2025-12-03 00:30:24.900667 | controller | skipping: Conditional result was False
2025-12-03 00:30:24.932570 | 
2025-12-03 00:30:24.932728 | TASK [use-docker-mirror : Set up docker mirrors]
2025-12-03 00:30:24.966693 | controller | skipping: Conditional result was False
2025-12-03 00:30:24.977743 | 
2025-12-03 00:30:24.977824 | TASK [Add all repositories]
2025-12-03 00:30:24.997655 | controller | ok
2025-12-03 00:30:25.022163 | 
2025-12-03 00:30:25.022274 | LOOP [ensure-package-repositories : Install repositories]
2025-12-03 00:30:25.057379 | controller | ok: "/var/lib/zuul/builds/4787182f95a34a9a999608aedac95ca0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2025-12-03 00:30:25.057665 | controller | ok: All items complete
2025-12-03 00:30:25.057726 | 
2025-12-03 00:30:25.079766 | controller | included: /var/lib/zuul/builds/4787182f95a34a9a999608aedac95ca0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2025-12-03 00:30:25.087816 | 
2025-12-03 00:30:25.087900 | TASK [ensure-package-repositories : Create GPG key temp directory]
2025-12-03 00:30:26.024553 | controller | changed
2025-12-03 00:30:26.040405 | 
2025-12-03 00:30:26.040571 | LOOP [ensure-package-repositories : Upload GPG keys]
2025-12-03 00:30:29.166453 | controller | changed:
2025-12-03 00:30:29.166784 | controller | {
2025-12-03 00:30:29.166848 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2025-12-03 00:30:29.166894 | controller | }
2025-12-03 00:30:29.186528 | 
2025-12-03 00:30:29.186734 | LOOP [ensure-package-repositories : Import GPG keys]
2025-12-03 00:30:30.435059 | controller | changed:
2025-12-03 00:30:30.436064 | controller | {
2025-12-03 00:30:30.436117 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2025-12-03 00:30:30.436142 | controller | }
2025-12-03 00:30:30.436178 | controller | changed: All items complete
2025-12-03 00:30:30.436237 | 
2025-12-03 00:30:30.451808 | 
2025-12-03 00:30:30.451942 | TASK [ensure-package-repositories : Remove GPG temp directory]
2025-12-03 00:30:31.132528 | controller | changed
2025-12-03 00:30:31.144112 | 
2025-12-03 00:30:31.144247 | LOOP [ensure-package-repositories : Add repositories]
2025-12-03 00:30:31.968886 | controller | changed:
2025-12-03 00:30:31.969151 | controller | {
2025-12-03 00:30:31.969206 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2025-12-03 00:30:31.969247 | controller |   "description": "Docker CE Stable - $basearch",
2025-12-03 00:30:31.969285 | controller |   "gpgcheck": true,
2025-12-03 00:30:31.969321 | controller |   "name": "docker-ce-stable"
2025-12-03 00:30:31.969356 | controller | }
2025-12-03 00:30:32.001398 | 
2025-12-03 00:30:32.001554 | TASK [ensure-docker : Install pre-reqs]
2025-12-03 00:30:37.190959 | controller | ok: Nothing to do
2025-12-03 00:30:37.204842 | 
2025-12-03 00:30:37.204985 | TASK [ensure-docker : Remove packages]
2025-12-03 00:30:40.649877 | controller | ok: Nothing to do
2025-12-03 00:30:40.663009 | 
2025-12-03 00:30:40.663178 | TASK [ensure-docker : Install upstream docker using package]
2025-12-03 00:31:55.781792 | controller | changed
2025-12-03 00:31:55.794432 | 
2025-12-03 00:31:55.794575 | TASK [ensure-docker : Setup docker]
2025-12-03 00:31:55.863040 | controller | ok
2025-12-03 00:31:55.876185 | controller | included: /var/lib/zuul/builds/4787182f95a34a9a999608aedac95ca0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2025-12-03 00:31:55.889657 | 
2025-12-03 00:31:55.889752 | TASK [ensure-docker : Ensure "docker" group exists]
2025-12-03 00:31:56.823842 | controller | ok
2025-12-03 00:31:56.836518 | 
2025-12-03 00:31:56.836652 | TASK [ensure-docker : Add user to docker group]
2025-12-03 00:31:57.847416 | controller | changed
2025-12-03 00:31:57.862273 | 
2025-12-03 00:31:57.862410 | TASK [Update docker daemon proxy configuration]
2025-12-03 00:31:57.901573 | controller | skipping: Conditional result was False
2025-12-03 00:31:57.918844 | 
2025-12-03 00:31:57.919029 | TASK [Lower default MTU]
2025-12-03 00:31:57.947548 | controller | skipping: Conditional result was False
2025-12-03 00:31:57.962933 | 
2025-12-03 00:31:57.963077 | TASK [ensure-docker : Get the status of the docket.socket unit]
2025-12-03 00:31:58.727902 | controller | inactive
2025-12-03 00:31:59.336725 | controller | ok: Runtime: 0:00:00.017757
2025-12-03 00:31:59.352611 | 
2025-12-03 00:31:59.352791 | TASK [ensure-docker : Get the status of the docket unit]
2025-12-03 00:32:00.096018 | controller | inactive
2025-12-03 00:32:00.230250 | controller | ok: Runtime: 0:00:00.018437
2025-12-03 00:32:00.245511 | 
2025-12-03 00:32:00.245655 | TASK [ensure-docker : Restart docker]
2025-12-03 00:32:00.298968 | Trigger a Docker restart
2025-12-03 00:32:00.305040 | 
2025-12-03 00:32:00.305134 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2025-12-03 00:32:00.340776 | 
2025-12-03 00:32:00.340887 | TASK [ensure-docker : Install docker-compose]
2025-12-03 00:32:00.365450 | controller | skipping: Conditional result was False
2025-12-03 00:32:00.371924 | 
2025-12-03 00:32:00.372002 | TASK [ensure-docker : Flush handlers before role exit]
2025-12-03 00:32:00.382106 | 
2025-12-03 00:32:00.382170 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2025-12-03 00:32:04.115983 | controller | changed
2025-12-03 00:32:04.124774 | 
2025-12-03 00:32:04.124857 | TASK [ensure-docker : Assure docker service is running]
2025-12-03 00:32:07.851755 | controller | changed
2025-12-03 00:32:07.866786 | 
2025-12-03 00:32:07.866940 | TASK [ensure-docker : Assure docker.socket service is running]
2025-12-03 00:32:09.676004 | controller | ok
2025-12-03 00:32:09.693213 | 
2025-12-03 00:32:09.693523 | TASK [ensure-docker : Correct group ownership on docker sock]
2025-12-03 00:32:10.464144 | controller | ok
2025-12-03 00:32:10.477139 | 
2025-12-03 00:32:10.477333 | TASK [ensure-docker : Validate ability to talk with docker]
2025-12-03 00:32:11.096334 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2025-12-03 00:32:11.351740 | controller | ok: Runtime: 0:00:00.036727
2025-12-03 00:32:11.359283 | 
2025-12-03 00:32:11.359366 | TASK [ensure-docker : Get version details]
2025-12-03 00:32:12.099283 | controller | Client: Docker Engine - Community
2025-12-03 00:32:12.099390 | controller |  Version:           24.0.7
2025-12-03 00:32:12.099400 | controller |  API version:       1.43
2025-12-03 00:32:12.099406 | controller |  Go version:        go1.20.10
2025-12-03 00:32:12.099426 | controller |  Git commit:        afdd53b
2025-12-03 00:32:12.099434 | controller |  Built:             Thu Oct 26 09:09:39 2023
2025-12-03 00:32:12.099441 | controller |  OS/Arch:           linux/amd64
2025-12-03 00:32:12.099450 | controller |  Context:           default
2025-12-03 00:32:12.099459 | controller |
2025-12-03 00:32:12.099466 | controller | Server: Docker Engine - Community
2025-12-03 00:32:12.099476 | controller |  Engine:
2025-12-03 00:32:12.099484 | controller |   Version:          24.0.7
2025-12-03 00:32:12.099490 | controller |   API version:      1.43 (minimum version 1.12)
2025-12-03 00:32:12.099496 | controller |   Go version:       go1.20.10
2025-12-03 00:32:12.099512 | controller |   Git commit:       311b9ff
2025-12-03 00:32:12.099518 | controller |   Built:            Thu Oct 26 09:08:11 2023
2025-12-03 00:32:12.099524 | controller |   OS/Arch:          linux/amd64
2025-12-03 00:32:12.099530 | controller |   Experimental:     false
2025-12-03 00:32:12.099536 | controller |  containerd:
2025-12-03 00:32:12.099542 | controller |   Version:          1.6.25
2025-12-03 00:32:12.099548 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2025-12-03 00:32:12.099554 | controller |  runc:
2025-12-03 00:32:12.099560 | controller |   Version:          1.1.10
2025-12-03 00:32:12.099567 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2025-12-03 00:32:12.099575 | controller |  docker-init:
2025-12-03 00:32:12.099584 | controller |   Version:          0.19.0
2025-12-03 00:32:12.101452 | controller |   GitCommit:        de40ad0
2025-12-03 00:32:12.256607 | controller | ok: Runtime: 0:00:00.043359
2025-12-03 00:32:12.269834 | 
2025-12-03 00:32:12.270000 | TASK [ensure-docker : Dump installed docker details]
2025-12-03 00:32:12.310479 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version:           24.0.7\n API version:       1.43\n Go version:        go1.20.10\n Git commit:        afdd53b\n Built:             Thu Oct 26 09:09:39 2023\n OS/Arch:           linux/amd64\n Context:           default\n\nServer: Docker Engine - Community\n Engine:\n  Version:          24.0.7\n  API version:      1.43 (minimum version 1.12)\n  Go version:       go1.20.10\n  Git commit:       311b9ff\n  Built:            Thu Oct 26 09:08:11 2023\n  OS/Arch:          linux/amd64\n  Experimental:     false\n containerd:\n  Version:          1.6.25\n  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n  Version:          1.1.10\n  GitCommit:        v1.1.10-0-g18a0cb0\n docker-init:\n  Version:          0.19.0\n  GitCommit:        de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2025-12-03 00:32:12.058638', 'end': '2025-12-03 00:32:12.101997', 'delta': '0:00:00.043359', 'msg': '', 'zuul_log_id': 'fa163e3b-3c83-a459-81bd-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version:           24.0.7', ' API version:       1.43', ' Go version:        go1.20.10', ' Git commit:        afdd53b', ' Built:             Thu Oct 26 09:09:39 2023', ' OS/Arch:           linux/amd64', ' Context:           default', '', 'Server: Docker Engine - Community', ' Engine:', '  Version:          24.0.7', '  API version:      1.43 (minimum version 1.12)', '  Go version:       go1.20.10', '  Git commit:       311b9ff', '  Built:            Thu Oct 26 09:08:11 2023', '  OS/Arch:          linux/amd64', '  Experimental:     false', ' containerd:', '  Version:          1.6.25', '  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', '  Version:          1.1.10', '  GitCommit:        v1.1.10-0-g18a0cb0', ' docker-init:', '  Version:          0.19.0', '  GitCommit:        de40ad0'], 'stderr_lines': [], 'failed': False}
2025-12-03 00:32:12.323931 | 
2025-12-03 00:32:12.324082 | TASK [Use buildset registry]
2025-12-03 00:32:12.357949 | controller | skipping: Conditional result was False
2025-12-03 00:32:12.375456 | 
2025-12-03 00:32:12.375599 | TASK [Ensure python3.8 is present]
2025-12-03 00:32:12.403312 | controller | skipping: Conditional result was False
2025-12-03 00:32:12.416723 | 
2025-12-03 00:32:12.416905 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-12-03 00:32:12.442161 | controller | ok
2025-12-03 00:32:12.474373 | 
2025-12-03 00:32:12.474516 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-12-03 00:32:19.264328 | controller | ok: Nothing to do
2025-12-03 00:32:19.276961 | 
2025-12-03 00:32:19.277109 | TASK [our-ensure-python : Also install python3-devel]
2025-12-03 00:32:34.344717 | controller | changed
2025-12-03 00:32:34.356415 | 
2025-12-03 00:32:34.356518 | TASK [Run ensure-virtualenv role]
2025-12-03 00:32:34.378724 | controller | ok
2025-12-03 00:32:34.409022 | 
2025-12-03 00:32:34.409122 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-12-03 00:32:35.133970 | controller | /usr/bin/virtualenv
2025-12-03 00:32:35.263797 | controller | ok: Runtime: 0:00:00.004971
2025-12-03 00:32:35.270874 | 
2025-12-03 00:32:35.270948 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-12-03 00:32:35.293175 | controller | skipping: Conditional result was False
2025-12-03 00:32:35.293605 | controller | changed: All items complete
2025-12-03 00:32:35.293661 | 
2025-12-03 00:32:35.316041 | 
2025-12-03 00:32:35.316128 | TASK [Find the full path of the Python interpreter]
2025-12-03 00:32:35.892411 | controller | /usr/bin/python3.8
2025-12-03 00:32:36.171144 | controller | ok
2025-12-03 00:32:36.183912 | 
2025-12-03 00:32:36.184059 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-12-03 00:32:39.058576 | controller | created virtual environment CPython3.8.18.final.0-64 in 1235ms
2025-12-03 00:32:39.122381 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2025-12-03 00:32:39.122635 | controller |   seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv)
2025-12-03 00:32:39.122653 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-12-03 00:32:39.122682 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-12-03 00:32:39.604626 | controller | changed
2025-12-03 00:32:39.617891 | 
2025-12-03 00:32:39.618055 | TASK [Set selinux package]
2025-12-03 00:32:39.646638 | controller | ok
2025-12-03 00:32:39.659947 | 
2025-12-03 00:32:39.660091 | TASK [Set selinux package (Fedora)]
2025-12-03 00:32:39.708447 | controller | ok
2025-12-03 00:32:39.714071 | 
2025-12-03 00:32:39.714139 | TASK [Install selinux into virtualenv]
2025-12-03 00:32:45.867393 | controller | Collecting selinux-please-lie-to-me
2025-12-03 00:32:46.001895 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-12-03 00:32:46.650389 | controller | Collecting setuptools<50.0.0
2025-12-03 00:32:46.656275 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-12-03 00:32:46.752235 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 8.8 MB/s eta 0:00:00
2025-12-03 00:32:46.871064 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-12-03 00:32:46.871503 | controller |   Attempting uninstall: setuptools
2025-12-03 00:32:46.872748 | controller |     Found existing installation: setuptools 62.6.0
2025-12-03 00:32:46.986741 | controller |     Uninstalling setuptools-62.6.0:
2025-12-03 00:32:47.001785 | controller |       Successfully uninstalled setuptools-62.6.0
2025-12-03 00:32:47.713588 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-12-03 00:32:47.897266 | controller |
2025-12-03 00:32:48.263308 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-12-03 00:32:48.263391 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-12-03 00:32:48.581574 | controller | ok: Runtime: 0:00:07.744804
2025-12-03 00:32:48.593836 | 
2025-12-03 00:32:48.593968 | TASK [Install pytest-forked into virtualenv]
2025-12-03 00:32:50.349259 | controller | Collecting pytest-forked
2025-12-03 00:32:50.402962 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2025-12-03 00:32:50.468947 | controller | Collecting py
2025-12-03 00:32:50.474377 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2025-12-03 00:32:50.505951 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 3.4 MB/s eta 0:00:00
2025-12-03 00:32:50.683763 | controller | Collecting pytest>=3.10
2025-12-03 00:32:50.690550 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2025-12-03 00:32:50.708525 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 25.6 MB/s eta 0:00:00
2025-12-03 00:32:50.776562 | controller | Collecting exceptiongroup>=1.0.0rc8
2025-12-03 00:32:50.783308 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2025-12-03 00:32:50.834034 | controller | Collecting pluggy<2,>=1.5
2025-12-03 00:32:50.839561 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2025-12-03 00:32:50.874307 | controller | Collecting iniconfig
2025-12-03 00:32:50.879778 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2025-12-03 00:32:50.953208 | controller | Collecting tomli>=1
2025-12-03 00:32:50.958871 | controller |   Downloading tomli-2.3.0-py3-none-any.whl (14 kB)
2025-12-03 00:32:51.023235 | controller | Collecting packaging
2025-12-03 00:32:51.028546 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-12-03 00:32:51.040752 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 9.0 MB/s eta 0:00:00
2025-12-03 00:32:51.111712 | controller | Collecting typing-extensions>=4.6.0
2025-12-03 00:32:51.117668 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2025-12-03 00:32:51.127783 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 5.8 MB/s eta 0:00:00
2025-12-03 00:32:51.216837 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2025-12-03 00:32:51.990217 | 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
2025-12-03 00:32:51.996070 | controller |
2025-12-03 00:32:52.310113 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-12-03 00:32:52.310179 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-12-03 00:32:52.471337 | controller | ok: Runtime: 0:00:02.646246
2025-12-03 00:32:52.485290 | 
2025-12-03 00:32:52.485469 | TASK [Update pip]
2025-12-03 00:32:55.228130 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2025-12-03 00:32:55.738848 | controller | Collecting pip
2025-12-03 00:32:55.873053 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2025-12-03 00:32:56.032536 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 13.0 MB/s eta 0:00:00
2025-12-03 00:32:56.273886 | controller | Installing collected packages: pip
2025-12-03 00:32:56.274884 | controller |   Attempting uninstall: pip
2025-12-03 00:32:56.276657 | controller |     Found existing installation: pip 22.2.2
2025-12-03 00:32:56.997402 | controller |     Uninstalling pip-22.2.2:
2025-12-03 00:32:57.082256 | controller |       Successfully uninstalled pip-22.2.2
2025-12-03 00:32:59.738478 | controller | Successfully installed pip-25.0.1
2025-12-03 00:33:00.372614 | controller | ok: Runtime: 0:00:06.438500
2025-12-03 00:33:00.380466 | 
2025-12-03 00:33:00.380563 | TASK [Ensure pip temp directory exists in /var/tmp]
2025-12-03 00:33:01.654069 | controller | changed
2025-12-03 00:33:01.668627 | 
2025-12-03 00:33:01.668827 | TASK [Install ansible into virtualenv]
2025-12-03 00:33:03.140565 | controller | Processing ./src/github.com/ansible/ansible
2025-12-03 00:33:03.142245 | controller |   Preparing metadata (setup.py): started
2025-12-03 00:33:03.862401 | controller |   Preparing metadata (setup.py): finished with status 'done'
2025-12-03 00:33:03.929266 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2025-12-03 00:33:03.943371 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2025-12-03 00:33:04.026779 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2025-12-03 00:33:04.033333 | 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)
2025-12-03 00:33:04.454048 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2025-12-03 00:33:04.459551 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2025-12-03 00:33:04.480914 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (25.0)
2025-12-03 00:33:04.731701 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2025-12-03 00:33:04.737335 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2025-12-03 00:33:04.747191 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.8/site-packages (from cryptography->ansible-base==2.10.17.post0) (4.13.2)
2025-12-03 00:33:04.851999 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2025-12-03 00:33:04.858726 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2025-12-03 00:33:04.890472 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2025-12-03 00:33:04.896043 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2025-12-03 00:33:04.914517 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2025-12-03 00:33:04.988479 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 69.4 MB/s eta 0:00:00
2025-12-03 00:33:04.994022 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2025-12-03 00:33:05.005721 | controller | Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (806 kB)
2025-12-03 00:33:05.028607 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 42.0 MB/s eta 0:00:00
2025-12-03 00:33:05.033819 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2025-12-03 00:33:05.051546 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2025-12-03 00:33:05.062523 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2025-12-03 00:33:05.089624 | controller | Building wheels for collected packages: ansible-base
2025-12-03 00:33:05.090641 | controller |   Building wheel for ansible-base (setup.py): started
2025-12-03 00:33:07.037286 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2025-12-03 00:33:07.042423 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=d15bf044aec73fb497ae73541997f3e224c222f17f58a18bea46279fb0a3afaa
2025-12-03 00:33:07.042673 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-lnyiq5qj/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2025-12-03 00:33:07.056391 | controller | Successfully built ansible-base
2025-12-03 00:33:07.108290 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2025-12-03 00:33:09.879149 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-base-2.10.17.post0 cffi-1.17.1 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23
2025-12-03 00:33:10.552217 | controller | ok: Runtime: 0:00:07.490102
2025-12-03 00:33:10.566967 | 
2025-12-03 00:33:10.567129 | TASK [Setup download-artifact-fork role]
2025-12-03 00:33:10.598511 | controller | ok
2025-12-03 00:33:10.631337 | 
2025-12-03 00:33:10.631451 | TASK [download-artifact-fork : Parse inventory response]
2025-12-03 00:33:10.685069 | controller | ok
2025-12-03 00:33:10.692987 | 
2025-12-03 00:33:10.693070 | TASK [download-artifact-fork : Fetch info from Zuul API]
2025-12-03 00:33:10.728867 | controller | skipping: Conditional result was False
2025-12-03 00:33:10.742637 | 
2025-12-03 00:33:10.742891 | TASK [download-artifact-fork : Parse build response]
2025-12-03 00:33:10.781141 | controller | skipping: Conditional result was False
2025-12-03 00:33:10.796200 | 
2025-12-03 00:33:10.796351 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2025-12-03 00:33:11.594549 | controller | changed
2025-12-03 00:33:11.600523 | 
2025-12-03 00:33:11.600624 | LOOP [download-artifact-fork : Download archive by type]
2025-12-03 00:33:11.623199 | controller | skipping: Conditional result was False
2025-12-03 00:33:11.623485 | 
2025-12-03 00:33:13.452487 | controller | changed: OK (20188 bytes)
2025-12-03 00:33:13.469194 | 
2025-12-03 00:33:13.469335 | TASK [deploy-artifacts : define __collections]
2025-12-03 00:33:13.502126 | controller | ok
2025-12-03 00:33:13.510363 | 
2025-12-03 00:33:13.510489 | LOOP [deploy-artifacts : Create list of collections]
2025-12-03 00:33:13.547181 | controller | skipping: Conditional result was False
2025-12-03 00:33:13.565468 | controller | ok:
2025-12-03 00:33:13.565593 | controller | {
2025-12-03 00:33:13.565626 | controller |   "branch": "main",
2025-12-03 00:33:13.565653 | controller |   "job": "build-ansible-collection",
2025-12-03 00:33:13.565728 | controller |   "metadata": {
2025-12-03 00:33:13.565758 | controller |     "type": "ansible_collection",
2025-12-03 00:33:13.565782 | controller |     "version": "5.0.0"
2025-12-03 00:33:13.565807 | controller |   },
2025-12-03 00:33:13.565831 | controller |   "name": "ansible.network",
2025-12-03 00:33:13.565853 | controller |   "project": "ansible-collections/ansible.network",
2025-12-03 00:33:13.565876 | controller |   "url": "https://577bed6a164abcf20ded-3b84c870e0b5e05985daf965164e2101.ssl.cf1.rackcdn.com/ansible/bb2bdb988b774a2aa4476743e2b80bbb/artifacts/ansible-network-5.0.0.tar.gz"
2025-12-03 00:33:13.565910 | controller | }
2025-12-03 00:33:13.575231 | 
2025-12-03 00:33:13.575363 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2025-12-03 00:33:14.759923 | controller | Starting galaxy collection install process
2025-12-03 00:33:14.760478 | controller | Process install dependency map
2025-12-03 00:33:27.042682 | controller | Starting collection install process
2025-12-03 00:33:27.042902 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2025-12-03 00:33:27.055622 | controller | ansible.network (5.0.0) was installed successfully
2025-12-03 00:33:27.055770 | controller | Installing 'ansible.netcommon:8.2.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2025-12-03 00:33:27.055985 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-netcommon-8.2.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4081dy7wz8j1/tmpxv_tzurr
2025-12-03 00:33:28.629068 | controller | ansible.netcommon (8.2.0) was installed successfully
2025-12-03 00:33:28.629206 | controller | Installing 'ansible.utils:6.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2025-12-03 00:33:28.629221 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-utils-6.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4081dy7wz8j1/tmpxv_tzurr
2025-12-03 00:33:30.473091 | controller | ansible.utils (6.0.0) was installed successfully
2025-12-03 00:33:30.473200 | controller | Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2025-12-03 00:33:30.473213 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/arista-eos-12.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4081dy7wz8j1/tmpxv_tzurr
2025-12-03 00:33:32.691716 | controller | arista.eos (12.0.0) was installed successfully
2025-12-03 00:33:32.691896 | controller | Installing 'cisco.ios:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2025-12-03 00:33:32.692180 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-ios-11.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4081dy7wz8j1/tmpxv_tzurr
2025-12-03 00:33:35.441553 | controller | cisco.ios (11.1.1) was installed successfully
2025-12-03 00:33:35.441693 | controller | Installing 'cisco.iosxr:12.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2025-12-03 00:33:35.441994 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-iosxr-12.1.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4081dy7wz8j1/tmpxv_tzurr
2025-12-03 00:33:37.809168 | controller | cisco.iosxr (12.1.0) was installed successfully
2025-12-03 00:33:37.809305 | controller | Installing 'cisco.nxos:11.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2025-12-03 00:33:37.809554 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.1.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4081dy7wz8j1/tmpxv_tzurr
2025-12-03 00:33:41.803813 | controller | cisco.nxos (11.1.0) was installed successfully
2025-12-03 00:33:41.804106 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2025-12-03 00:33:41.804155 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/junipernetworks-junos-11.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4081dy7wz8j1/tmpxv_tzurr
2025-12-03 00:33:45.060874 | controller | junipernetworks.junos (11.0.0) was installed successfully
2025-12-03 00:33:45.458146 | controller | ok: Runtime: 0:00:31.016779
2025-12-03 00:33:45.469891 | 
2025-12-03 00:33:45.469977 | TASK [podman may need iptables kernel modules]
2025-12-03 00:33:46.823883 | controller | ok: Runtime: 0:00:00.006682
2025-12-03 00:33:46.872613 | 
2025-12-03 00:33:46.872837 | PLAY RECAP
2025-12-03 00:33:46.872930 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2025-12-03 00:33:46.872978 | 
2025-12-03 00:33:47.007343 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-12-03 00:33:47.009456 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-12-03 00:33:47.639425 | 
2025-12-03 00:33:47.639590 | PLAY [controller]
2025-12-03 00:33:47.660604 | 
2025-12-03 00:33:47.660717 | TASK [Copy the galaxy.yml on the controller]
2025-12-03 00:33:51.455875 | controller | changed
2025-12-03 00:33:51.468145 | 
2025-12-03 00:33:51.468298 | TASK [Load information from galaxy.yml]
2025-12-03 00:33:51.492490 | controller | ok
2025-12-03 00:33:51.500040 | 
2025-12-03 00:33:51.500225 | LOOP [Enable FIPS mode]
2025-12-03 00:33:51.520539 | 
2025-12-03 00:33:51.520652 | LOOP [ansible-test : Include branch specific variables.]
2025-12-03 00:33:51.548766 | controller | ok: "/var/lib/zuul/builds/4787182f95a34a9a999608aedac95ca0/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2025-12-03 00:33:51.548928 | 
2025-12-03 00:33:51.560831 | 
2025-12-03 00:33:51.560936 | TASK [ansible-test : Setup --skip-tags for test_options]
2025-12-03 00:33:51.590258 | controller | ok
2025-12-03 00:33:51.595498 | 
2025-12-03 00:33:51.595562 | TASK [ansible-test : Enable --inventory for network-integration]
2025-12-03 00:33:51.619369 | controller | skipping: Conditional result was False
2025-12-03 00:33:51.626256 | 
2025-12-03 00:33:51.626324 | TASK [ansible-test : Enable --retry-on-error]
2025-12-03 00:33:51.650474 | controller | skipping: Conditional result was False
2025-12-03 00:33:51.663418 | 
2025-12-03 00:33:51.663557 | TASK [ansible-test : Enable --continue-on-error]
2025-12-03 00:33:51.714290 | controller | ok
2025-12-03 00:33:51.726183 | 
2025-12-03 00:33:51.726315 | TASK [ansible-test : Enable --requirements]
2025-12-03 00:33:51.743739 | controller | skipping: Conditional result was False
2025-12-03 00:33:51.753240 | 
2025-12-03 00:33:51.753335 | TASK [ansible-test : Adjust options for unit tests]
2025-12-03 00:33:51.778260 | controller | skipping: Conditional result was False
2025-12-03 00:33:51.786572 | 
2025-12-03 00:33:51.786698 | TASK [ansible-test : Install requirements with unit tests]
2025-12-03 00:33:51.849726 | controller | ok
2025-12-03 00:33:51.857261 | 
2025-12-03 00:33:51.857344 | TASK [ansible-test : Set --skip-test]
2025-12-03 00:33:51.903308 | controller | skipping: Conditional result was False
2025-12-03 00:33:51.912459 | 
2025-12-03 00:33:51.912547 | TASK [ansible-test : Reduce verbosity]
2025-12-03 00:33:51.944449 | controller | ok
2025-12-03 00:33:51.951765 | 
2025-12-03 00:33:51.951848 | TASK [ansible-test : Setup --python option]
2025-12-03 00:33:51.976614 | controller | skipping: Conditional result was False
2025-12-03 00:33:51.984593 | 
2025-12-03 00:33:51.984697 | TASK [ansible-test : Setup --docker option]
2025-12-03 00:33:52.016729 | controller | ok
2025-12-03 00:33:52.025842 | 
2025-12-03 00:33:52.025924 | TASK [ansible-test : check if MANIFEST.json exists]
2025-12-03 00:33:53.345474 | controller | ok
2025-12-03 00:33:53.354025 | 
2025-12-03 00:33:53.354134 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2025-12-03 00:33:53.379991 | controller | skipping: Conditional result was False
2025-12-03 00:33:53.393396 | 
2025-12-03 00:33:53.393559 | TASK [ansible-test : Load information from MANIFEST.json]
2025-12-03 00:33:53.421287 | controller | skipping: Conditional result was False
2025-12-03 00:33:53.433252 | 
2025-12-03 00:33:53.433563 | TASK [ansible-test : Setup location of project for integration tests]
2025-12-03 00:33:53.459247 | controller | skipping: Conditional result was False
2025-12-03 00:33:53.469349 | 
2025-12-03 00:33:53.469516 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2025-12-03 00:33:54.978529 | controller | ok
2025-12-03 00:33:54.991334 | 
2025-12-03 00:33:54.991532 | TASK [ansible-test : Load information from galaxy.yml]
2025-12-03 00:33:55.042246 | controller | ok
2025-12-03 00:33:55.050801 | 
2025-12-03 00:33:55.050888 | TASK [ansible-test : Setup location of project for integration tests]
2025-12-03 00:33:55.100620 | controller | ok
2025-12-03 00:33:55.113717 | 
2025-12-03 00:33:55.113911 | TASK [ansible-test : Setup minimum test requirements]
2025-12-03 00:33:55.161122 | controller | ok
2025-12-03 00:33:55.169874 | 
2025-12-03 00:33:55.170027 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2025-12-03 00:33:56.046266 | controller | ok
2025-12-03 00:33:56.059346 | 
2025-12-03 00:33:56.059494 | TASK [ansible-test : Install python requirements]
2025-12-03 00:33:57.532592 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-12-03 00:33:57.551023 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2025-12-03 00:33:57.583353 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-12-03 00:33:57.588923 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2025-12-03 00:33:57.599848 | 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)
2025-12-03 00:33:57.608149 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2025-12-03 00:33:57.622881 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2025-12-03 00:33:57.672684 | controller | Installing collected packages: pathspec, yamllint
2025-12-03 00:33:57.767460 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2025-12-03 00:33:58.431583 | controller | ok: Runtime: 0:00:00.980925
2025-12-03 00:33:58.445105 | 
2025-12-03 00:33:58.445263 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2025-12-03 00:33:59.235855 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2025-12-03 00:33:59.821468 | controller | ERROR
2025-12-03 00:33:59.821715 | controller | {
2025-12-03 00:33:59.821752 | controller |   "delta": "0:00:00.007631",
2025-12-03 00:33:59.821775 | controller |   "end": "2025-12-03 00:33:59.236102",
2025-12-03 00:33:59.821794 | controller |   "msg": "non-zero return code",
2025-12-03 00:33:59.821820 | controller |   "rc": 1,
2025-12-03 00:33:59.821838 | controller |   "start": "2025-12-03 00:33:59.228471"
2025-12-03 00:33:59.821857 | controller | }
2025-12-03 00:33:59.821881 | controller | ERROR: Ignoring Errors
2025-12-03 00:33:59.828347 | 
2025-12-03 00:33:59.828411 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2025-12-03 00:34:00.178628 | controller | skipping: Conditional result was False
2025-12-03 00:34:00.192319 | 
2025-12-03 00:34:00.192466 | TASK [ansible-test : Enable persistent connection logging]
2025-12-03 00:34:01.465105 | controller | changed: section and option added
2025-12-03 00:34:01.475989 | 
2025-12-03 00:34:01.476112 | TASK [ansible-test : Set persistent ssh_type value]
2025-12-03 00:34:02.712122 | controller | changed: option added
2025-12-03 00:34:02.725877 | 
2025-12-03 00:34:02.726023 | TASK [ansible-test : Set ansible log path]
2025-12-03 00:34:04.023770 | controller | changed: section and option added
2025-12-03 00:34:04.036441 | 
2025-12-03 00:34:04.036603 | TASK [ansible-test : Identify the changed targets]
2025-12-03 00:34:04.421379 | controller | skipping: Conditional result was False
2025-12-03 00:34:04.435965 | 
2025-12-03 00:34:04.436162 | TASK [ansible-test : set_fact]
2025-12-03 00:34:04.524864 | controller | skipping: Conditional result was False
2025-12-03 00:34:04.539008 | 
2025-12-03 00:34:04.539156 | TASK [ansible-test : Set the targets]
2025-12-03 00:34:04.616938 | controller | ok
2025-12-03 00:34:04.624773 | 
2025-12-03 00:34:04.624859 | TASK [ansible-test : set_fact]
2025-12-03 00:34:04.649821 | controller | skipping: Conditional result was False
2025-12-03 00:34:04.658854 | 
2025-12-03 00:34:04.658976 | TASK [ansible-test : List the targets]
2025-12-03 00:34:05.028777 | controller | skipping: Conditional result was False
2025-12-03 00:34:05.045177 | 
2025-12-03 00:34:05.045406 | TASK [ansible-test : set_fact]
2025-12-03 00:34:05.063947 | controller | skipping: Conditional result was False
2025-12-03 00:34:05.079842 | 
2025-12-03 00:34:05.080002 | TASK [ansible-test : set_fact]
2025-12-03 00:34:05.107785 | controller | skipping: Conditional result was False
2025-12-03 00:34:05.122212 | 
2025-12-03 00:34:05.122357 | LOOP [ansible-test : set_fact]
2025-12-03 00:34:05.205812 | 
2025-12-03 00:34:05.206157 | TASK [ansible-test : set_fact]
2025-12-03 00:34:05.232355 | controller | skipping: Conditional result was False
2025-12-03 00:34:05.246071 | 
2025-12-03 00:34:05.246216 | TASK [ansible-test : debug]
2025-12-03 00:34:05.272248 | controller | skipping: Conditional result was False
2025-12-03 00:34:05.288641 | 
2025-12-03 00:34:05.288850 | TASK [ansible-test : debug]
2025-12-03 00:34:05.337025 | About to run: ansible-test sanity --requirements --docker -v
2025-12-03 00:34:05.349941 | 
2025-12-03 00:34:05.350114 | TASK [ansible-test : Run the test suite]
2025-12-03 00:34:07.174285 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2025-12-03 00:34:07.242923 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2025-12-03 00:34:08.668106 | controller | 2.9.0: Pulling from ansible/default-test-container
2025-12-03 00:34:08.668171 | controller | 171857c49d0f: Pulling fs layer
2025-12-03 00:34:08.668195 | controller | 419640447d26: Pulling fs layer
2025-12-03 00:34:08.668203 | controller | 61e52f862619: Pulling fs layer
2025-12-03 00:34:08.668210 | controller | 851de0426e26: Pulling fs layer
2025-12-03 00:34:08.668221 | controller | 37425716ea5b: Pulling fs layer
2025-12-03 00:34:08.668230 | controller | cd9b0c439616: Pulling fs layer
2025-12-03 00:34:08.668236 | controller | d4612dbd8855: Pulling fs layer
2025-12-03 00:34:08.668242 | controller | 554699364730: Pulling fs layer
2025-12-03 00:34:08.668248 | controller | 1ff15d4bdb3e: Pulling fs layer
2025-12-03 00:34:08.668255 | controller | 5f2e4724adea: Pulling fs layer
2025-12-03 00:34:08.668264 | controller | a02d9a95ccb8: Pulling fs layer
2025-12-03 00:34:08.668272 | controller | 989f4ce370cb: Pulling fs layer
2025-12-03 00:34:08.668280 | controller | 0d7f52cdf9df: Pulling fs layer
2025-12-03 00:34:08.668286 | controller | c423742351d5: Pulling fs layer
2025-12-03 00:34:08.668294 | controller | 109236341d21: Pulling fs layer
2025-12-03 00:34:08.668302 | controller | 07a2f61c96b8: Pulling fs layer
2025-12-03 00:34:08.668310 | controller | 041f122f27ae: Pulling fs layer
2025-12-03 00:34:08.668316 | controller | ca6f5098b12e: Pulling fs layer
2025-12-03 00:34:08.668322 | controller | 1eb3e7b2afd9: Pulling fs layer
2025-12-03 00:34:08.668328 | controller | 18a0b750727a: Pulling fs layer
2025-12-03 00:34:08.668335 | controller | 17f2f596716c: Pulling fs layer
2025-12-03 00:34:08.668343 | controller | f35a9998a9bd: Pulling fs layer
2025-12-03 00:34:08.668349 | controller | fdfc10566405: Pulling fs layer
2025-12-03 00:34:08.668355 | controller | 1ff1c442c5fe: Pulling fs layer
2025-12-03 00:34:08.668361 | controller | a3443c4a17ed: Pulling fs layer
2025-12-03 00:34:08.668367 | controller | 0edcf9fb2b15: Pulling fs layer
2025-12-03 00:34:08.668376 | controller | 5af43b69e845: Pulling fs layer
2025-12-03 00:34:08.668384 | controller | 1651773effb2: Pulling fs layer
2025-12-03 00:34:08.668391 | controller | 79e23c5c0fe4: Pulling fs layer
2025-12-03 00:34:08.668406 | controller | e5058b1faaa6: Pulling fs layer
2025-12-03 00:34:08.668413 | controller | 846775490569: Pulling fs layer
2025-12-03 00:34:08.668419 | controller | 0d7f52cdf9df: Waiting
2025-12-03 00:34:08.668425 | controller | c423742351d5: Waiting
2025-12-03 00:34:08.668432 | controller | 109236341d21: Waiting
2025-12-03 00:34:08.668438 | controller | 07a2f61c96b8: Waiting
2025-12-03 00:34:08.668444 | controller | 041f122f27ae: Waiting
2025-12-03 00:34:08.668450 | controller | ca6f5098b12e: Waiting
2025-12-03 00:34:08.668460 | controller | 1eb3e7b2afd9: Waiting
2025-12-03 00:34:08.668467 | controller | 18a0b750727a: Waiting
2025-12-03 00:34:08.668472 | controller | 17f2f596716c: Waiting
2025-12-03 00:34:08.668479 | controller | f35a9998a9bd: Waiting
2025-12-03 00:34:08.668484 | controller | fdfc10566405: Waiting
2025-12-03 00:34:08.668490 | controller | 1ff1c442c5fe: Waiting
2025-12-03 00:34:08.668496 | controller | a3443c4a17ed: Waiting
2025-12-03 00:34:08.668539 | controller | 0edcf9fb2b15: Waiting
2025-12-03 00:34:09.051877 | controller | 5af43b69e845: Waiting
2025-12-03 00:34:09.051937 | controller | 1651773effb2: Waiting
2025-12-03 00:34:09.051946 | controller | 79e23c5c0fe4: Waiting
2025-12-03 00:34:09.051953 | controller | e5058b1faaa6: Waiting
2025-12-03 00:34:09.051968 | controller | 846775490569: Waiting
2025-12-03 00:34:09.051975 | controller | d4612dbd8855: Waiting
2025-12-03 00:34:09.051980 | controller | 554699364730: Waiting
2025-12-03 00:34:09.051986 | controller | 1ff15d4bdb3e: Waiting
2025-12-03 00:34:09.051992 | controller | 5f2e4724adea: Waiting
2025-12-03 00:34:09.051998 | controller | a02d9a95ccb8: Waiting
2025-12-03 00:34:09.052004 | controller | 989f4ce370cb: Waiting
2025-12-03 00:34:09.052010 | controller | 37425716ea5b: Waiting
2025-12-03 00:34:09.052017 | controller | 851de0426e26: Waiting
2025-12-03 00:34:09.052024 | controller | cd9b0c439616: Waiting
2025-12-03 00:34:09.052040 | controller | 419640447d26: Verifying Checksum
2025-12-03 00:34:09.064618 | controller | 419640447d26: Download complete
2025-12-03 00:34:09.064674 | controller | 61e52f862619: Verifying Checksum
2025-12-03 00:34:09.378007 | controller | 61e52f862619: Download complete
2025-12-03 00:34:09.378082 | controller | 171857c49d0f: Verifying Checksum
2025-12-03 00:34:09.416972 | controller | 171857c49d0f: Download complete
2025-12-03 00:34:09.417034 | controller | 851de0426e26: Verifying Checksum
2025-12-03 00:34:09.851873 | controller | 851de0426e26: Download complete
2025-12-03 00:34:09.851948 | controller | cd9b0c439616: Verifying Checksum
2025-12-03 00:34:09.866827 | controller | cd9b0c439616: Download complete
2025-12-03 00:34:09.866886 | controller | d4612dbd8855: Verifying Checksum
2025-12-03 00:34:09.867205 | controller | d4612dbd8855: Download complete
2025-12-03 00:34:10.326005 | controller | 1ff15d4bdb3e: Verifying Checksum
2025-12-03 00:34:10.326238 | controller | 1ff15d4bdb3e: Download complete
2025-12-03 00:34:11.344300 | controller | 5f2e4724adea: Verifying Checksum
2025-12-03 00:34:11.344484 | controller | 5f2e4724adea: Download complete
2025-12-03 00:34:11.769231 | controller | a02d9a95ccb8: Verifying Checksum
2025-12-03 00:34:11.769408 | controller | a02d9a95ccb8: Download complete
2025-12-03 00:34:12.200899 | controller | 989f4ce370cb: Verifying Checksum
2025-12-03 00:34:12.201499 | controller | 989f4ce370cb: Download complete
2025-12-03 00:34:12.574058 | controller | 0d7f52cdf9df: Verifying Checksum
2025-12-03 00:34:12.574249 | controller | 0d7f52cdf9df: Download complete
2025-12-03 00:34:12.886585 | controller | 554699364730: Verifying Checksum
2025-12-03 00:34:12.982707 | controller | 554699364730: Download complete
2025-12-03 00:34:12.982771 | controller | c423742351d5: Verifying Checksum
2025-12-03 00:34:12.983506 | controller | c423742351d5: Download complete
2025-12-03 00:34:13.401618 | controller | 07a2f61c96b8: Verifying Checksum
2025-12-03 00:34:13.879170 | controller | 07a2f61c96b8: Download complete
2025-12-03 00:34:13.879252 | controller | 041f122f27ae: Verifying Checksum
2025-12-03 00:34:13.879527 | controller | 041f122f27ae: Download complete
2025-12-03 00:34:13.968273 | controller | 171857c49d0f: Pull complete
2025-12-03 00:34:14.007679 | controller | 419640447d26: Pull complete
2025-12-03 00:34:14.063095 | controller | 61e52f862619: Pull complete
2025-12-03 00:34:14.115956 | controller | 851de0426e26: Pull complete
2025-12-03 00:34:14.174780 | controller | 109236341d21: Verifying Checksum
2025-12-03 00:34:14.263928 | controller | 109236341d21: Download complete
2025-12-03 00:34:14.264009 | controller | ca6f5098b12e: Verifying Checksum
2025-12-03 00:34:14.552367 | controller | ca6f5098b12e: Download complete
2025-12-03 00:34:14.552434 | controller | 1eb3e7b2afd9: Verifying Checksum
2025-12-03 00:34:14.552552 | controller | 1eb3e7b2afd9: Download complete
2025-12-03 00:34:14.673822 | controller | 18a0b750727a: Verifying Checksum
2025-12-03 00:34:14.673950 | controller | 18a0b750727a: Download complete
2025-12-03 00:34:14.923104 | controller | 17f2f596716c: Verifying Checksum
2025-12-03 00:34:14.923312 | controller | 17f2f596716c: Download complete
2025-12-03 00:34:15.061924 | controller | f35a9998a9bd: Verifying Checksum
2025-12-03 00:34:15.062079 | controller | f35a9998a9bd: Download complete
2025-12-03 00:34:15.300102 | controller | fdfc10566405: Verifying Checksum
2025-12-03 00:34:15.300226 | controller | fdfc10566405: Download complete
2025-12-03 00:34:15.405336 | controller | 1ff1c442c5fe: Verifying Checksum
2025-12-03 00:34:15.828351 | controller | 1ff1c442c5fe: Download complete
2025-12-03 00:34:15.828466 | controller | a3443c4a17ed: Verifying Checksum
2025-12-03 00:34:15.939743 | controller | a3443c4a17ed: Download complete
2025-12-03 00:34:15.939869 | controller | 0edcf9fb2b15: Verifying Checksum
2025-12-03 00:34:15.940019 | controller | 0edcf9fb2b15: Download complete
2025-12-03 00:34:16.403031 | controller | 5af43b69e845: Verifying Checksum
2025-12-03 00:34:16.403196 | controller | 5af43b69e845: Download complete
2025-12-03 00:34:16.453367 | controller | 1651773effb2: Verifying Checksum
2025-12-03 00:34:16.453974 | controller | 1651773effb2: Download complete
2025-12-03 00:34:16.899426 | controller | 79e23c5c0fe4: Verifying Checksum
2025-12-03 00:34:16.899525 | controller | 79e23c5c0fe4: Download complete
2025-12-03 00:34:16.939819 | controller | e5058b1faaa6: Verifying Checksum
2025-12-03 00:34:16.940018 | controller | e5058b1faaa6: Download complete
2025-12-03 00:34:17.383720 | controller | 846775490569: Verifying Checksum
2025-12-03 00:34:17.383877 | controller | 846775490569: Download complete
2025-12-03 00:34:28.083045 | controller | 37425716ea5b: Verifying Checksum
2025-12-03 00:34:45.615724 | controller | 37425716ea5b: Download complete
2025-12-03 00:34:45.618014 | controller | 37425716ea5b: Pull complete
2025-12-03 00:34:45.641539 | controller | cd9b0c439616: Pull complete
2025-12-03 00:34:45.660020 | controller | d4612dbd8855: Pull complete
2025-12-03 00:34:50.024059 | controller | 554699364730: Pull complete
2025-12-03 00:34:50.057560 | controller | 1ff15d4bdb3e: Pull complete
2025-12-03 00:34:50.088953 | controller | 5f2e4724adea: Pull complete
2025-12-03 00:34:50.150856 | controller | a02d9a95ccb8: Pull complete
2025-12-03 00:34:50.175189 | controller | 989f4ce370cb: Pull complete
2025-12-03 00:34:50.201416 | controller | 0d7f52cdf9df: Pull complete
2025-12-03 00:34:50.225666 | controller | c423742351d5: Pull complete
2025-12-03 00:34:52.390453 | controller | 109236341d21: Pull complete
2025-12-03 00:34:52.412932 | controller | 07a2f61c96b8: Pull complete
2025-12-03 00:34:52.432372 | controller | 041f122f27ae: Pull complete
2025-12-03 00:34:52.604165 | controller | ca6f5098b12e: Pull complete
2025-12-03 00:34:52.727941 | controller | 1eb3e7b2afd9: Pull complete
2025-12-03 00:34:52.773307 | controller | 18a0b750727a: Pull complete
2025-12-03 00:34:52.794683 | controller | 17f2f596716c: Pull complete
2025-12-03 00:34:52.815414 | controller | f35a9998a9bd: Pull complete
2025-12-03 00:34:52.847583 | controller | fdfc10566405: Pull complete
2025-12-03 00:34:52.874142 | controller | 1ff1c442c5fe: Pull complete
2025-12-03 00:34:54.320210 | controller | a3443c4a17ed: Pull complete
2025-12-03 00:34:55.915906 | controller | 0edcf9fb2b15: Pull complete
2025-12-03 00:35:00.432390 | controller | 5af43b69e845: Pull complete
2025-12-03 00:35:02.787645 | controller | 1651773effb2: Pull complete
2025-12-03 00:35:05.484210 | controller | 79e23c5c0fe4: Pull complete
2025-12-03 00:35:07.498853 | controller | e5058b1faaa6: Pull complete
2025-12-03 00:35:10.225983 | controller | 846775490569: Pull complete
2025-12-03 00:35:10.256163 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2025-12-03 00:35:10.261227 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2025-12-03 00:35:10.268505 | controller | quay.io/ansible/default-test-container:2.9.0
2025-12-03 00:35:10.277907 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2025-12-03 00:35:10.303888 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2025-12-03 00:35:11.796695 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2025-12-03 00:35:11.797687 | controller | 04a5f4cda3ee: Pulling fs layer
2025-12-03 00:35:12.214013 | controller | ff496a88c8ed: Pulling fs layer
2025-12-03 00:35:12.214072 | controller | 0ce83f459fe7: Pulling fs layer
2025-12-03 00:35:12.214080 | controller | 2e5170e1f099: Pulling fs layer
2025-12-03 00:35:12.214086 | controller | 7641eb41b08c: Pulling fs layer
2025-12-03 00:35:12.214093 | controller | ad15fa9da398: Pulling fs layer
2025-12-03 00:35:12.214099 | controller | 087d91352424: Pulling fs layer
2025-12-03 00:35:12.214105 | controller | 8b92efd6a100: Pulling fs layer
2025-12-03 00:35:12.214111 | controller | 7641eb41b08c: Waiting
2025-12-03 00:35:12.214117 | controller | ad15fa9da398: Waiting
2025-12-03 00:35:12.214131 | controller | 087d91352424: Waiting
2025-12-03 00:35:12.214138 | controller | 8b92efd6a100: Waiting
2025-12-03 00:35:12.214144 | controller | 2e5170e1f099: Waiting
2025-12-03 00:35:12.214159 | controller | 0ce83f459fe7: Verifying Checksum
2025-12-03 00:35:12.214240 | controller | 0ce83f459fe7: Download complete
2025-12-03 00:35:12.216897 | controller | ff496a88c8ed: Verifying Checksum
2025-12-03 00:35:12.216990 | controller | ff496a88c8ed: Download complete
2025-12-03 00:35:12.451048 | controller | 04a5f4cda3ee: Verifying Checksum
2025-12-03 00:35:12.453511 | controller | 04a5f4cda3ee: Download complete
2025-12-03 00:35:12.621919 | controller | 7641eb41b08c: Verifying Checksum
2025-12-03 00:35:12.828862 | controller | 7641eb41b08c: Download complete
2025-12-03 00:35:12.828932 | controller | ad15fa9da398: Verifying Checksum
2025-12-03 00:35:12.829025 | controller | ad15fa9da398: Download complete
2025-12-03 00:35:12.878859 | controller | 2e5170e1f099: Verifying Checksum
2025-12-03 00:35:12.878964 | controller | 2e5170e1f099: Download complete
2025-12-03 00:35:13.080598 | controller | 087d91352424: Verifying Checksum
2025-12-03 00:35:13.182608 | controller | 8b92efd6a100: Verifying Checksum
2025-12-03 00:35:13.182713 | controller | 8b92efd6a100: Download complete
2025-12-03 00:35:14.357718 | controller | 04a5f4cda3ee: Pull complete
2025-12-03 00:35:14.387760 | controller | ff496a88c8ed: Pull complete
2025-12-03 00:35:14.415835 | controller | 0ce83f459fe7: Pull complete
2025-12-03 00:35:15.729059 | controller | 2e5170e1f099: Pull complete
2025-12-03 00:35:15.771301 | controller | 7641eb41b08c: Pull complete
2025-12-03 00:35:15.800300 | controller | ad15fa9da398: Pull complete
2025-12-03 00:35:16.873898 | controller | 087d91352424: Pull complete
2025-12-03 00:35:16.924643 | controller | 8b92efd6a100: Pull complete
2025-12-03 00:35:16.945623 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2025-12-03 00:35:16.949239 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2025-12-03 00:35:16.959444 | controller | quay.io/ansible/pypi-test-container:1.0.0
2025-12-03 00:35:16.963227 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2025-12-03 00:35:24.908925 | controller | Run command: docker inspect d125be1616a1c17e6b0651578bb3cb6eb9f894a6e671bed9715d66a62a18c204
2025-12-03 00:35:24.964887 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2025-12-03 00:35:24.976298 | controller | Including collection: ansible.netcommon (292 files)
2025-12-03 00:35:24.987517 | controller | Including collection: ansible.network (23 files)
2025-12-03 00:35:24.987569 | controller | Including collection: ansible.utils (442 files)
2025-12-03 00:35:25.026134 | controller | Including collection: arista.eos (1022 files)
2025-12-03 00:35:25.070392 | controller | Including collection: cisco.ios (1172 files)
2025-12-03 00:35:25.114971 | controller | Including collection: cisco.iosxr (1043 files)
2025-12-03 00:35:25.189213 | controller | Including collection: cisco.nxos (1684 files)
2025-12-03 00:35:25.228063 | controller | Including collection: junipernetworks.junos (963 files)
2025-12-03 00:35:25.265833 | controller | Creating a payload archive containing 7367 files...
2025-12-03 00:35:27.255433 | controller | Created a 5981851 byte payload archive containing 7367 files in 1 seconds.
2025-12-03 00:35:27.259207 | controller | Assuming Docker is available on localhost.
2025-12-03 00:35:27.259471 | controller | Run command: docker run --detach --volume /sys/fs/cgroup:/sys/fs/cgroup:ro --privileged=false --security-opt seccomp=unconfined --volume /var/run/docker.sock:/var/run/docker.sock quay.io/ansible/default-test-container:2.9.0
2025-12-03 00:35:27.714724 | controller | Run command: docker exec -i 1d345190fbf85fef461af34bd16e1d8879da5b38cec8e59ada5c8f7f7bb57892 dd of=/root/docker.sh bs=65536
2025-12-03 00:35:27.797747 | controller | Run command: docker exec 1d345190fbf85fef461af34bd16e1d8879da5b38cec8e59ada5c8f7f7bb57892 /bin/bash /root/docker.sh
2025-12-03 00:35:27.871999 | controller | Run command: docker exec -i 1d345190fbf85fef461af34bd16e1d8879da5b38cec8e59ada5c8f7f7bb57892 dd of=/root/test.tgz bs=65536
2025-12-03 00:35:27.964973 | controller | Run command: docker exec 1d345190fbf85fef461af34bd16e1d8879da5b38cec8e59ada5c8f7f7bb57892 tar oxzf /root/test.tgz -C /root
2025-12-03 00:35:28.861187 | controller | Run command: docker exec 1d345190fbf85fef461af34bd16e1d8879da5b38cec8e59ada5c8f7f7bb57892 /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata tests/output/.tmp/metadata-w0cto4sv.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2025-12-03 00:35:29.249454 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2025-12-03 00:35:29.249884 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2025-12-03 00:35:29.250328 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2025-12-03 00:35:29.252378 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2025-12-03 00:35:29.253252 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2025-12-03 00:35:29.259886 | controller | No tests applicable.
2025-12-03 00:35:29.259913 | controller | Running sanity test 'ansible-doc' with Python 3.6
2025-12-03 00:35:29.260199 | controller | No tests applicable.
2025-12-03 00:35:29.260266 | controller | Running sanity test 'changelog' with Python 3.6
2025-12-03 00:35:29.260786 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/ansible-test.txt
2025-12-03 00:35:30.162738 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt
2025-12-03 00:35:30.724618 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2025-12-03 00:35:45.738168 | controller |   WARNING: Retrying (Retry(total=4, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-12-03 00:36:01.257222 | controller |   WARNING: Retrying (Retry(total=3, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-12-03 00:36:17.267973 | controller |   WARNING: Retrying (Retry(total=2, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-12-03 00:36:34.284627 | controller |   WARNING: Retrying (Retry(total=1, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-12-03 00:36:53.303240 | controller |   WARNING: Retrying (Retry(total=0, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-12-03 00:37:08.315015 | controller |   ERROR: Could not find a version that satisfies the requirement antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2)) (from versions: none)
2025-12-03 00:37:08.315616 | controller | ERROR: No matching distribution found for antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2025-12-03 00:37:08.410083 | controller | ERROR: Command "/usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt" returned exit status 1.
2025-12-03 00:37:08.410231 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2025-12-03 00:37:08.444939 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2025-12-03 00:37:08.444996 | controller | Removing custom PyPI hosts entries: /etc/hosts
2025-12-03 00:37:08.445013 | controller | Run command: docker exec 1d345190fbf85fef461af34bd16e1d8879da5b38cec8e59ada5c8f7f7bb57892 tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2025-12-03 00:37:08.516697 | controller | Run command: docker exec -i 1d345190fbf85fef461af34bd16e1d8879da5b38cec8e59ada5c8f7f7bb57892 dd if=/root/output.tgz bs=65536
2025-12-03 00:37:08.580682 | controller | Run command: tar oxzf /tmp/ansible-result-_hdiqke4.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2025-12-03 00:37:08.595163 | controller | Run command: docker rm -f d125be1616a1c17e6b0651578bb3cb6eb9f894a6e671bed9715d66a62a18c204
2025-12-03 00:37:08.792389 | controller | Run command: docker rm -f 1d345190fbf85fef461af34bd16e1d8879da5b38cec8e59ada5c8f7f7bb57892
2025-12-03 00:37:09.432371 | controller | ERROR: Command "docker exec 1d345190fbf85fef461af34bd16e1d8879da5b38cec8e59ada5c8f7f7bb57892 /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata tests/output/.tmp/metadata-w0cto4sv.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2025-12-03 00:37:09.854551 | controller | ERROR
2025-12-03 00:37:09.854883 | controller | {
2025-12-03 00:37:09.854964 | controller |   "delta": "0:03:02.826381",
2025-12-03 00:37:09.855018 | controller |   "end": "2025-12-03 00:37:09.483772",
2025-12-03 00:37:09.855063 | controller |   "msg": "non-zero return code",
2025-12-03 00:37:09.855106 | controller |   "rc": 1,
2025-12-03 00:37:09.855148 | controller |   "start": "2025-12-03 00:34:06.657391"
2025-12-03 00:37:09.855205 | controller | }
failure
2025-12-03 00:37:09.863855 | 
2025-12-03 00:37:09.863969 | PLAY RECAP
2025-12-03 00:37:09.864057 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2025-12-03 00:37:09.864102 | 
2025-12-03 00:37:10.002921 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-12-03 00:37:10.003829 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-03 00:37:10.566696 | 
2025-12-03 00:37:10.566814 | PLAY [all]
2025-12-03 00:37:10.587840 | 
2025-12-03 00:37:10.587955 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-12-03 00:37:13.575773 | controller | changed: non-zero return code
2025-12-03 00:37:13.588557 | 
2025-12-03 00:37:13.588752 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-12-03 00:37:13.615927 | controller | skipping: Conditional result was False
2025-12-03 00:37:13.628417 | 
2025-12-03 00:37:13.628572 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-12-03 00:37:13.669724 | 
2025-12-03 00:37:13.670029 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-12-03 00:37:13.711871 | 
2025-12-03 00:37:13.712177 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-12-03 00:37:13.739174 | controller | skipping: Conditional result was False
2025-12-03 00:37:13.753592 | 
2025-12-03 00:37:13.753815 | LOOP [fetch-subunit-output : Generate subunit file]
2025-12-03 00:37:13.794834 | 
2025-12-03 00:37:13.795132 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-12-03 00:37:13.821948 | controller | skipping: Conditional result was False
2025-12-03 00:37:13.837899 | 
2025-12-03 00:37:13.838056 | TASK [fetch-subunit-output : Remove the temporary file]
2025-12-03 00:37:13.864744 | controller | skipping: Conditional result was False
2025-12-03 00:37:13.877331 | 
2025-12-03 00:37:13.877468 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-12-03 00:37:13.903670 | controller | skipping: Conditional result was False
2025-12-03 00:37:13.951132 | 
2025-12-03 00:37:13.951208 | PLAY RECAP
2025-12-03 00:37:13.951259 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-03 00:37:13.951286 | 
2025-12-03 00:37:14.043429 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-03 00:37:14.045434 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-12-03 00:37:14.690109 | 
2025-12-03 00:37:14.690228 | PLAY [all:!appliance*]
2025-12-03 00:37:14.712154 | 
2025-12-03 00:37:14.712277 | TASK [unregister the node]
2025-12-03 00:37:15.064945 | controller | skipping: Conditional result was False
2025-12-03 00:37:15.079573 | 
2025-12-03 00:37:15.079852 | TASK [include_role : fetch-output]
2025-12-03 00:37:15.130161 | controller | ok
2025-12-03 00:37:15.172157 | 
2025-12-03 00:37:15.172277 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-03 00:37:15.248195 | controller | skipping: Conditional result was False
2025-12-03 00:37:15.257079 | 
2025-12-03 00:37:15.257215 | TASK [fetch-output : Set log path for single node]
2025-12-03 00:37:15.299572 | controller | ok
2025-12-03 00:37:15.307541 | 
2025-12-03 00:37:15.307631 | LOOP [fetch-output : Ensure local output dirs]
2025-12-03 00:37:15.758551 | controller -> localhost | ok: "/var/lib/zuul/builds/4787182f95a34a9a999608aedac95ca0/work/logs"
2025-12-03 00:37:16.019899 | controller -> localhost | changed: "/var/lib/zuul/builds/4787182f95a34a9a999608aedac95ca0/work/artifacts"
2025-12-03 00:37:16.274368 | controller -> localhost | changed: "/var/lib/zuul/builds/4787182f95a34a9a999608aedac95ca0/work/docs"
2025-12-03 00:37:16.296040 | 
2025-12-03 00:37:16.296209 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-03 00:37:18.538900 | controller | changed:
2025-12-03 00:37:18.539487 | controller | .d..t...... ./
2025-12-03 00:37:18.539603 | controller | cd+++++++++ controller/
2025-12-03 00:37:18.539793 | controller | changed: All items complete
2025-12-03 00:37:18.539894 | 
2025-12-03 00:37:20.617461 | controller | changed: .d..t...... ./
2025-12-03 00:37:22.627468 | controller | changed: .d..t...... ./
2025-12-03 00:37:22.663023 | 
2025-12-03 00:37:22.663223 | TASK [include_role : fetch-output-openshift]
2025-12-03 00:37:22.679874 | controller | skipping: Conditional result was False
2025-12-03 00:37:22.689635 | 
2025-12-03 00:37:22.689826 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-03 00:37:22.716629 | controller | skipping: Conditional result was False
2025-12-03 00:37:22.724560 | controller | skipping: Conditional result was False
2025-12-03 00:37:22.787262 | 
2025-12-03 00:37:22.787368 | PLAY [localhost]
2025-12-03 00:37:22.800379 | 
2025-12-03 00:37:22.800471 | TASK [Run Zuul manifest role]
2025-12-03 00:37:22.818610 | localhost | ok
2025-12-03 00:37:22.832406 | 
2025-12-03 00:37:22.832482 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-03 00:37:23.256857 | localhost | changed
2025-12-03 00:37:23.267568 | 
2025-12-03 00:37:23.267745 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-03 00:37:23.314940 | localhost | ok
2025-12-03 00:37:23.333298 | 
2025-12-03 00:37:23.333441 | TASK [Set zuul-log-path fact]
2025-12-03 00:37:23.361101 | localhost | ok
2025-12-03 00:37:23.411079 | 
2025-12-03 00:37:23.411196 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-03 00:37:23.454353 | localhost | ok
2025-12-03 00:37:23.470561 | 
2025-12-03 00:37:23.470653 | LOOP [Run upload-logs-swift role]
2025-12-03 00:37:23.505464 | localhost | Output suppressed because no_log was given
2025-12-03 00:37:23.541822 | 
2025-12-03 00:37:23.541942 | TASK [Set zuul-log-path fact]
2025-12-03 00:37:23.577878 | localhost | skipping: Conditional result was False
2025-12-03 00:37:23.587570 | 
2025-12-03 00:37:23.587711 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-12-03 00:37:23.999878 | localhost -> localhost | ok: Runtime: 0:00:00.013230
2025-12-03 00:37:24.005715 | 
2025-12-03 00:37:24.005810 | TASK [upload-logs-swift : Upload logs to swift]