2025-10-13 00:13:08.807010 | Job console starting...
2025-10-13 00:13:08.820215 | Updating repositories
2025-10-13 00:13:08.940611 | Preparing job workspace
2025-10-13 00:13:12.946898 | Running Ansible setup...
2025-10-13 00:13:21.355074 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-10-13 00:13:22.020337 | 
2025-10-13 00:13:22.020459 | PLAY [localhost]
2025-10-13 00:13:22.028855 | 
2025-10-13 00:13:22.028924 | TASK [Gathering Facts]
2025-10-13 00:13:23.043603 | localhost | ok
2025-10-13 00:13:23.075235 | 
2025-10-13 00:13:23.075472 | TASK [Setup log path fact]
2025-10-13 00:13:23.102085 | localhost | ok
2025-10-13 00:13:23.129713 | 
2025-10-13 00:13:23.129843 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-13 00:13:23.170923 | localhost | ok
2025-10-13 00:13:23.182908 | 
2025-10-13 00:13:23.183067 | TASK [emit-job-header : Print job information]
2025-10-13 00:13:23.245183 | # Job Information
2025-10-13 00:13:23.245366 | Ansible Version: 2.15.12
2025-10-13 00:13:23.245408 | Job: ansible-test-sanity-docker-stable-2.10
2025-10-13 00:13:23.245442 | Pipeline: periodic
2025-10-13 00:13:23.245468 | Executor: ze01.softwarefactory-project.io
2025-10-13 00:13:23.245492 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-10-13 00:13:23.245536 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/f68/ansible/f68b409ea2404d5e8ba6df27d590b979/
2025-10-13 00:13:23.245562 | Event ID: 5c3858b4bf9e4fdab660facb3b50ba54
2025-10-13 00:13:23.250981 | 
2025-10-13 00:13:23.251093 | LOOP [emit-job-header : Print node information]
2025-10-13 00:13:23.379866 | localhost | ok:
2025-10-13 00:13:23.380164 | localhost | # Node Information
2025-10-13 00:13:23.380202 | localhost | Inventory Hostname: controller
2025-10-13 00:13:23.380231 | localhost | Hostname: np0005481800
2025-10-13 00:13:23.380259 | localhost | Username: zuul
2025-10-13 00:13:23.380295 | localhost | Distro: Fedora 37
2025-10-13 00:13:23.380322 | localhost | Provider: ansible-vexxhost-ams1
2025-10-13 00:13:23.380343 | localhost | Region: ams1
2025-10-13 00:13:23.380364 | localhost | Label: ansible-fedora-37-1vcpu
2025-10-13 00:13:23.380384 | localhost | Product Name: OpenStack Nova
2025-10-13 00:13:23.380405 | localhost | Interface IP: 38.129.16.129
2025-10-13 00:13:23.400226 | 
2025-10-13 00:13:23.400375 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-13 00:13:23.832638 | localhost -> localhost | changed
2025-10-13 00:13:23.838611 | 
2025-10-13 00:13:23.838682 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-13 00:13:24.734814 | localhost -> localhost | changed
2025-10-13 00:13:24.782813 | 
2025-10-13 00:13:24.782961 | PLAY [all:!appliance*]
2025-10-13 00:13:24.808281 | 
2025-10-13 00:13:24.808394 | TASK [include_role : start-zuul-console]
2025-10-13 00:13:24.831507 | controller | ok
2025-10-13 00:13:24.851064 | 
2025-10-13 00:13:24.851183 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-13 00:13:26.049070 | controller | ok
2025-10-13 00:13:26.060086 | 
2025-10-13 00:13:26.060194 | TASK [use-our-mirror : Retrieve the IP address]
2025-10-13 00:13:29.918191 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-10-13 00:13:29.934529 | 
2025-10-13 00:13:29.934669 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-10-13 00:13:30.310263 | controller | skipping: Conditional result was False
2025-10-13 00:13:30.326762 | 
2025-10-13 00:13:30.326906 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-10-13 00:13:30.354206 | controller | skipping: Conditional result was False
2025-10-13 00:13:30.370179 | 
2025-10-13 00:13:30.370343 | TASK [use-our-mirror : Create the podman configuration directory]
2025-10-13 00:13:30.398550 | controller | skipping: Conditional result was False
2025-10-13 00:13:30.413791 | 
2025-10-13 00:13:30.414101 | TASK [use-our-mirror : Copy the podman configuration]
2025-10-13 00:13:30.441918 | controller | skipping: Conditional result was False
2025-10-13 00:13:30.456566 | 
2025-10-13 00:13:30.456745 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-10-13 00:13:30.483882 | controller | skipping: Conditional result was False
2025-10-13 00:13:30.498916 | 
2025-10-13 00:13:30.499237 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-10-13 00:13:30.526011 | controller | skipping: Conditional result was False
2025-10-13 00:13:30.553826 | 
2025-10-13 00:13:30.553931 | TASK [Disable Fedora Modular]
2025-10-13 00:13:31.844779 | controller | changed
2025-10-13 00:13:31.858456 | 
2025-10-13 00:13:31.858610 | TASK [Enable EPEL]
2025-10-13 00:13:31.886146 | controller | skipping: Conditional result was False
2025-10-13 00:13:31.933184 | 
2025-10-13 00:13:31.933536 | TASK [Register the RHEL node]
2025-10-13 00:13:32.309057 | 
2025-10-13 00:13:32.309324 | TASK [Show the subscription-manager status]
2025-10-13 00:13:32.696820 | controller | skipping: Conditional result was False
2025-10-13 00:13:32.710962 | 
2025-10-13 00:13:32.711131 | TASK [Enable EPEL on RHEL]
2025-10-13 00:13:33.102820 | controller | skipping: Conditional result was False
2025-10-13 00:13:33.115743 | 
2025-10-13 00:13:33.115884 | TASK [Install git and tox]
2025-10-13 00:16:19.771884 | controller | changed
2025-10-13 00:16:19.783257 | 
2025-10-13 00:16:19.783354 | TASK [include_role : prepare-workspace]
2025-10-13 00:16:19.802931 | controller | ok
2025-10-13 00:16:19.824374 | 
2025-10-13 00:16:19.824473 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-13 00:16:20.698954 | controller | ok
2025-10-13 00:16:20.707941 | 
2025-10-13 00:16:20.708096 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-13 00:16:37.994610 | controller | Output suppressed because no_log was given
2025-10-13 00:16:38.008423 | 
2025-10-13 00:16:38.008507 | TASK [include_role : prepare-workspace-openshift]
2025-10-13 00:16:38.036437 | controller | skipping: Conditional result was False
2025-10-13 00:16:38.075465 | 
2025-10-13 00:16:38.075611 | PLAY [all:!appliance]
2025-10-13 00:16:38.100412 | 
2025-10-13 00:16:38.100527 | TASK [Run add-build-sshkey role (RSA)]
2025-10-13 00:16:38.121720 | controller | ok
2025-10-13 00:16:38.136331 | 
2025-10-13 00:16:38.136422 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-13 00:16:38.435329 | controller -> localhost | ok
2025-10-13 00:16:38.442090 | 
2025-10-13 00:16:38.442171 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-13 00:16:38.472804 | controller | ok
2025-10-13 00:16:38.487869 | controller | included: /var/lib/zuul/builds/f68b409ea2404d5e8ba6df27d590b979/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-13 00:16:38.494176 | 
2025-10-13 00:16:38.494252 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-13 00:16:39.177102 | controller -> localhost | Generating public/private rsa key pair.
2025-10-13 00:16:39.177562 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/f68b409ea2404d5e8ba6df27d590b979/work/f68b409ea2404d5e8ba6df27d590b979_id_rsa.
2025-10-13 00:16:39.177628 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/f68b409ea2404d5e8ba6df27d590b979/work/f68b409ea2404d5e8ba6df27d590b979_id_rsa.pub.
2025-10-13 00:16:39.177694 | controller -> localhost | The key fingerprint is:
2025-10-13 00:16:39.177737 | controller -> localhost | SHA256:p0yfwLLp6vBmZE3HxvIEVwnaFAnkxcOV+wWhs4irbr0 zuul-build-sshkey
2025-10-13 00:16:39.177779 | controller -> localhost | The key's randomart image is:
2025-10-13 00:16:39.177818 | controller -> localhost | +---[RSA 2048]----+
2025-10-13 00:16:39.177854 | controller -> localhost | |     .o+=*oo..   |
2025-10-13 00:16:39.177894 | controller -> localhost | |     ..=* o..    |
2025-10-13 00:16:39.177931 | controller -> localhost | |      o=..o. .   |
2025-10-13 00:16:39.177967 | controller -> localhost | |      oo*..o  .  |
2025-10-13 00:16:39.178003 | controller -> localhost | |     oo*S o. .   |
2025-10-13 00:16:39.178114 | controller -> localhost | |    o .B.= ..    |
2025-10-13 00:16:39.178159 | controller -> localhost | |  .o .+ o o      |
2025-10-13 00:16:39.178202 | controller -> localhost | |   o+o.          |
2025-10-13 00:16:39.178248 | controller -> localhost | |   **.E.         |
2025-10-13 00:16:39.178309 | controller -> localhost | +----[SHA256]-----+
2025-10-13 00:16:39.178426 | controller -> localhost | ok: Runtime: 0:00:00.154278
2025-10-13 00:16:39.195865 | 
2025-10-13 00:16:39.196092 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-13 00:16:39.231501 | controller | ok
2025-10-13 00:16:39.246787 | controller | included: /var/lib/zuul/builds/f68b409ea2404d5e8ba6df27d590b979/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-13 00:16:39.261362 | 
2025-10-13 00:16:39.261491 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-13 00:16:39.287087 | controller | skipping: Conditional result was False
2025-10-13 00:16:39.293894 | 
2025-10-13 00:16:39.293963 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-13 00:16:40.492067 | controller | changed
2025-10-13 00:16:40.499433 | 
2025-10-13 00:16:40.499706 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-13 00:16:41.204351 | controller | ok
2025-10-13 00:16:41.216964 | 
2025-10-13 00:16:41.217166 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-13 00:16:44.547429 | controller | changed
2025-10-13 00:16:44.559530 | 
2025-10-13 00:16:44.559759 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-13 00:16:47.801390 | controller | changed
2025-10-13 00:16:47.807447 | 
2025-10-13 00:16:47.807524 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-13 00:16:47.842569 | controller | skipping: Conditional result was False
2025-10-13 00:16:47.849380 | 
2025-10-13 00:16:47.849450 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-13 00:16:48.266073 | controller -> localhost | changed
2025-10-13 00:16:48.278161 | 
2025-10-13 00:16:48.278254 | TASK [add-build-sshkey : Add back temp key]
2025-10-13 00:16:48.549933 | controller -> localhost | Identity added: /var/lib/zuul/builds/f68b409ea2404d5e8ba6df27d590b979/work/f68b409ea2404d5e8ba6df27d590b979_id_rsa (zuul-build-sshkey)
2025-10-13 00:16:48.550175 | controller -> localhost | ok: Runtime: 0:00:00.007180
2025-10-13 00:16:48.556505 | 
2025-10-13 00:16:48.556594 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-13 00:16:49.554880 | controller | ok
2025-10-13 00:16:49.567411 | 
2025-10-13 00:16:49.567545 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-13 00:16:49.595539 | controller | skipping: Conditional result was False
2025-10-13 00:16:49.622420 | 
2025-10-13 00:16:49.622580 | TASK [Run add-build-sshkey role (ECDSA)]
2025-10-13 00:16:49.659680 | controller | ok
2025-10-13 00:16:49.683273 | 
2025-10-13 00:16:49.683430 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-13 00:16:50.005619 | controller -> localhost | ok
2025-10-13 00:16:50.013566 | 
2025-10-13 00:16:50.013653 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-13 00:16:50.046433 | controller | ok
2025-10-13 00:16:50.061728 | controller | included: /var/lib/zuul/builds/f68b409ea2404d5e8ba6df27d590b979/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-13 00:16:50.070712 | 
2025-10-13 00:16:50.070800 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-13 00:16:50.394950 | controller -> localhost | Generating public/private ecdsa key pair.
2025-10-13 00:16:50.395206 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/f68b409ea2404d5e8ba6df27d590b979/work/f68b409ea2404d5e8ba6df27d590b979_id_ecdsa.
2025-10-13 00:16:50.395236 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/f68b409ea2404d5e8ba6df27d590b979/work/f68b409ea2404d5e8ba6df27d590b979_id_ecdsa.pub.
2025-10-13 00:16:50.395264 | controller -> localhost | The key fingerprint is:
2025-10-13 00:16:50.395283 | controller -> localhost | SHA256:ulZoQ1PSanuzB1YRHHxdr8hKjA/Ga1o4FdKx9xry8k0 zuul-build-sshkey
2025-10-13 00:16:50.395302 | controller -> localhost | The key's randomart image is:
2025-10-13 00:16:50.395319 | controller -> localhost | +---[ECDSA 521]---+
2025-10-13 00:16:50.395336 | controller -> localhost | |       ..ooo . ..|
2025-10-13 00:16:50.395353 | controller -> localhost | |      ..oo+ . . .|
2025-10-13 00:16:50.395384 | controller -> localhost | |      .++ .o    .|
2025-10-13 00:16:50.395402 | controller -> localhost | |      =o =.o . . |
2025-10-13 00:16:50.395418 | controller -> localhost | |     o +S.+ + .  |
2025-10-13 00:16:50.395434 | controller -> localhost | |      ==*B +     |
2025-10-13 00:16:50.395450 | controller -> localhost | |     .+==+= E    |
2025-10-13 00:16:50.395466 | controller -> localhost | |      .*.o.o     |
2025-10-13 00:16:50.395482 | controller -> localhost | |     .o  .. .    |
2025-10-13 00:16:50.395506 | controller -> localhost | +----[SHA256]-----+
2025-10-13 00:16:50.395565 | controller -> localhost | ok: Runtime: 0:00:00.015805
2025-10-13 00:16:50.402558 | 
2025-10-13 00:16:50.402619 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-13 00:16:50.423886 | controller | ok
2025-10-13 00:16:50.466863 | controller | included: /var/lib/zuul/builds/f68b409ea2404d5e8ba6df27d590b979/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-13 00:16:50.476637 | 
2025-10-13 00:16:50.476715 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-13 00:16:50.501586 | controller | skipping: Conditional result was False
2025-10-13 00:16:50.508455 | 
2025-10-13 00:16:50.508519 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-13 00:16:51.539599 | controller | changed
2025-10-13 00:16:51.546185 | 
2025-10-13 00:16:51.546285 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-13 00:16:52.383378 | controller | ok
2025-10-13 00:16:52.398711 | 
2025-10-13 00:16:52.399031 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-13 00:16:55.950647 | controller | changed
2025-10-13 00:16:55.958677 | 
2025-10-13 00:16:55.958755 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-13 00:16:59.377447 | controller | changed
2025-10-13 00:16:59.386461 | 
2025-10-13 00:16:59.386549 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-13 00:16:59.411801 | controller | skipping: Conditional result was False
2025-10-13 00:16:59.423862 | 
2025-10-13 00:16:59.423968 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-13 00:16:59.726254 | controller -> localhost | changed
2025-10-13 00:16:59.747060 | 
2025-10-13 00:16:59.747221 | TASK [add-build-sshkey : Add back temp key]
2025-10-13 00:17:00.019518 | controller -> localhost | Identity added: /var/lib/zuul/builds/f68b409ea2404d5e8ba6df27d590b979/work/f68b409ea2404d5e8ba6df27d590b979_id_ecdsa (zuul-build-sshkey)
2025-10-13 00:17:00.019734 | controller -> localhost | ok: Runtime: 0:00:00.007294
2025-10-13 00:17:00.026954 | 
2025-10-13 00:17:00.027066 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-13 00:17:00.922147 | controller | ok
2025-10-13 00:17:00.934889 | 
2025-10-13 00:17:00.935108 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-13 00:17:00.973876 | controller | skipping: Conditional result was False
2025-10-13 00:17:00.986736 | 
2025-10-13 00:17:00.986842 | TASK [include_role : remove-zuul-sshkey]
2025-10-13 00:17:01.011623 | controller | skipping: Conditional result was False
2025-10-13 00:17:01.018682 | 
2025-10-13 00:17:01.018772 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-13 00:17:01.879622 | controller | ok: "logs"
2025-10-13 00:17:01.880079 | controller | ok: All items complete
2025-10-13 00:17:01.882442 | 
2025-10-13 00:17:02.619896 | controller | ok: "artifacts"
2025-10-13 00:17:03.301698 | controller | ok: "docs"
2025-10-13 00:17:03.321849 | 
2025-10-13 00:17:03.322348 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-13 00:17:04.118069 | controller | changed: "logs"
2025-10-13 00:17:04.929573 | controller | changed: "artifacts"
2025-10-13 00:17:05.725994 | controller | changed: "docs"
2025-10-13 00:17:05.783011 | 
2025-10-13 00:17:05.783166 | PLAY RECAP
2025-10-13 00:17:05.783229 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-10-13 00:17:05.783268 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-13 00:17:05.783294 | 
2025-10-13 00:17:05.962006 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-10-13 00:17:05.963783 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-10-13 00:17:06.603056 | 
2025-10-13 00:17:06.603174 | PLAY [all]
2025-10-13 00:17:06.628065 | 
2025-10-13 00:17:06.628260 | TASK [Install binary dependencies]
2025-10-13 00:17:06.709226 | controller | ok
2025-10-13 00:17:06.733279 | 
2025-10-13 00:17:06.733383 | TASK [bindep : Include find tasks]
2025-10-13 00:17:06.787843 | controller | ok
2025-10-13 00:17:06.799336 | controller | included: /var/lib/zuul/builds/f68b409ea2404d5e8ba6df27d590b979/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-10-13 00:17:06.808471 | 
2025-10-13 00:17:06.808568 | TASK [bindep : Look for bindep.txt]
2025-10-13 00:17:07.839699 | controller | ok
2025-10-13 00:17:07.854830 | 
2025-10-13 00:17:07.855122 | TASK [bindep : Define bindep_file fact]
2025-10-13 00:17:07.885667 | controller | skipping: Conditional result was False
2025-10-13 00:17:07.903082 | 
2025-10-13 00:17:07.903444 | TASK [bindep : Look for other-requirements.txt]
2025-10-13 00:17:08.590805 | controller | ok
2025-10-13 00:17:08.597519 | 
2025-10-13 00:17:08.597596 | TASK [bindep : Define bindep_file fact]
2025-10-13 00:17:08.632067 | controller | skipping: Conditional result was False
2025-10-13 00:17:08.649216 | 
2025-10-13 00:17:08.649445 | TASK [bindep : Look for bindep fallback file]
2025-10-13 00:17:08.688704 | controller | skipping: Conditional result was False
2025-10-13 00:17:08.705825 | 
2025-10-13 00:17:08.706011 | TASK [bindep : Define bindep_file fact]
2025-10-13 00:17:08.733635 | controller | skipping: Conditional result was False
2025-10-13 00:17:08.748445 | 
2025-10-13 00:17:08.748613 | TASK [bindep : Include bindep tasks]
2025-10-13 00:17:08.775616 | controller | skipping: Conditional result was False
2025-10-13 00:17:08.790253 | 
2025-10-13 00:17:08.790401 | TASK [bindep : Include install tasks]
2025-10-13 00:17:08.817484 | controller | skipping: Conditional result was False
2025-10-13 00:17:08.833123 | 
2025-10-13 00:17:08.833318 | LOOP [bindep : Include package tasks]
2025-10-13 00:17:08.918518 | 
2025-10-13 00:17:08.918941 | TASK [Run test-setup role]
2025-10-13 00:17:08.946658 | controller | ok
2025-10-13 00:17:08.990826 | 
2025-10-13 00:17:08.991059 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-10-13 00:17:09.742482 | controller | ok
2025-10-13 00:17:09.753978 | 
2025-10-13 00:17:09.754244 | TASK [test-setup : Run tools/test-setup.sh]
2025-10-13 00:17:10.124228 | controller | skipping: Conditional result was False
2025-10-13 00:17:10.183616 | 
2025-10-13 00:17:10.183806 | PLAY RECAP
2025-10-13 00:17:10.183873 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-10-13 00:17:10.183906 | 
2025-10-13 00:17:10.351080 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-10-13 00:17:10.353174 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-10-13 00:17:10.988638 | 
2025-10-13 00:17:10.988769 | PLAY [controller]
2025-10-13 00:17:11.009648 | 
2025-10-13 00:17:11.009741 | TASK [Create the /root directory]
2025-10-13 00:17:12.426400 | controller | ok
2025-10-13 00:17:12.434630 | 
2025-10-13 00:17:12.434720 | TASK [Install glibc-langpack-en]
2025-10-13 00:17:22.623766 | controller | ok: Nothing to do
2025-10-13 00:17:22.634501 | 
2025-10-13 00:17:22.634624 | TASK [Ensure controller directory exists]
2025-10-13 00:17:23.534834 | controller | changed
2025-10-13 00:17:23.552788 | 
2025-10-13 00:17:23.552884 | TASK [Install container runtime]
2025-10-13 00:17:23.614828 | controller | ok
2025-10-13 00:17:23.666706 | 
2025-10-13 00:17:23.666813 | LOOP [ensure-docker : Gather variables for each operating system]
2025-10-13 00:17:23.721075 | controller | ok: "/var/lib/zuul/builds/f68b409ea2404d5e8ba6df27d590b979/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2025-10-13 00:17:23.739792 | 
2025-10-13 00:17:23.740065 | TASK [ensure-docker : Sanity check for distro selection]
2025-10-13 00:17:23.768212 | controller | skipping: Conditional result was False
2025-10-13 00:17:23.783194 | 
2025-10-13 00:17:23.783390 | TASK [ensure-docker : Sanity check for distro install]
2025-10-13 00:17:23.821859 | controller | skipping: Conditional result was False
2025-10-13 00:17:23.828899 | 
2025-10-13 00:17:23.829010 | TASK [ensure-docker : Sanity check for upstream install]
2025-10-13 00:17:23.864326 | controller | skipping: Conditional result was False
2025-10-13 00:17:23.872486 | 
2025-10-13 00:17:23.872599 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2025-10-13 00:17:23.897340 | controller | skipping: Conditional result was False
2025-10-13 00:17:23.903612 | 
2025-10-13 00:17:23.903682 | TASK [ensure-docker : Include tasks for docker install]
2025-10-13 00:17:23.934832 | controller | ok
2025-10-13 00:17:23.946986 | controller | included: /var/lib/zuul/builds/f68b409ea2404d5e8ba6df27d590b979/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2025-10-13 00:17:23.955721 | 
2025-10-13 00:17:23.955803 | TASK [ensure-docker : Not Implemented]
2025-10-13 00:17:23.970992 | controller | skipping: Conditional result was False
2025-10-13 00:17:23.977946 | 
2025-10-13 00:17:23.978010 | TASK [Set up docker mirrors]
2025-10-13 00:17:23.998078 | controller | ok
2025-10-13 00:17:24.052550 | 
2025-10-13 00:17:24.053226 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2025-10-13 00:17:24.108654 | controller | skipping: Conditional result was False
2025-10-13 00:17:24.122983 | 
2025-10-13 00:17:24.123175 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2025-10-13 00:17:24.168511 | controller | skipping: Conditional result was False
2025-10-13 00:17:24.174743 | 
2025-10-13 00:17:24.174814 | TASK [use-docker-mirror : Set docker_mirror fact]
2025-10-13 00:17:24.198323 | controller | skipping: Conditional result was False
2025-10-13 00:17:24.204545 | 
2025-10-13 00:17:24.204618 | TASK [use-docker-mirror : Set up docker mirrors]
2025-10-13 00:17:24.229210 | controller | skipping: Conditional result was False
2025-10-13 00:17:24.241440 | 
2025-10-13 00:17:24.241510 | TASK [Add all repositories]
2025-10-13 00:17:24.259974 | controller | ok
2025-10-13 00:17:24.283059 | 
2025-10-13 00:17:24.283147 | LOOP [ensure-package-repositories : Install repositories]
2025-10-13 00:17:24.308073 | controller | ok: "/var/lib/zuul/builds/f68b409ea2404d5e8ba6df27d590b979/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2025-10-13 00:17:24.308304 | controller | ok: All items complete
2025-10-13 00:17:24.308331 | 
2025-10-13 00:17:24.326318 | controller | included: /var/lib/zuul/builds/f68b409ea2404d5e8ba6df27d590b979/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2025-10-13 00:17:24.332643 | 
2025-10-13 00:17:24.332717 | TASK [ensure-package-repositories : Create GPG key temp directory]
2025-10-13 00:17:25.378992 | controller | changed
2025-10-13 00:17:25.388234 | 
2025-10-13 00:17:25.388345 | LOOP [ensure-package-repositories : Upload GPG keys]
2025-10-13 00:17:29.076807 | controller | changed:
2025-10-13 00:17:29.077447 | controller | {
2025-10-13 00:17:29.077522 | 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-10-13 00:17:29.077843 | controller | }
2025-10-13 00:17:29.097533 | 
2025-10-13 00:17:29.097708 | LOOP [ensure-package-repositories : Import GPG keys]
2025-10-13 00:17:30.767005 | controller | changed:
2025-10-13 00:17:30.767230 | controller | {
2025-10-13 00:17:30.767258 | 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-10-13 00:17:30.767278 | controller | }
2025-10-13 00:17:30.767311 | controller | changed: All items complete
2025-10-13 00:17:30.767330 | 
2025-10-13 00:17:30.781984 | 
2025-10-13 00:17:30.782286 | TASK [ensure-package-repositories : Remove GPG temp directory]
2025-10-13 00:17:31.812010 | controller | changed
2025-10-13 00:17:31.821131 | 
2025-10-13 00:17:31.821321 | LOOP [ensure-package-repositories : Add repositories]
2025-10-13 00:17:32.978844 | controller | changed:
2025-10-13 00:17:32.979101 | controller | {
2025-10-13 00:17:32.979128 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2025-10-13 00:17:32.979147 | controller |   "description": "Docker CE Stable - $basearch",
2025-10-13 00:17:32.979164 | controller |   "gpgcheck": true,
2025-10-13 00:17:32.979180 | controller |   "name": "docker-ce-stable"
2025-10-13 00:17:32.979195 | controller | }
2025-10-13 00:17:33.014131 | 
2025-10-13 00:17:33.014387 | TASK [ensure-docker : Install pre-reqs]
2025-10-13 00:17:39.028558 | controller | ok: Nothing to do
2025-10-13 00:17:39.035955 | 
2025-10-13 00:17:39.036060 | TASK [ensure-docker : Remove packages]
2025-10-13 00:17:42.719089 | controller | ok: Nothing to do
2025-10-13 00:17:42.742106 | 
2025-10-13 00:17:42.742262 | TASK [ensure-docker : Install upstream docker using package]
2025-10-13 00:19:27.832007 | controller | changed
2025-10-13 00:19:27.839451 | 
2025-10-13 00:19:27.839578 | TASK [ensure-docker : Setup docker]
2025-10-13 00:19:27.870969 | controller | ok
2025-10-13 00:19:27.882821 | controller | included: /var/lib/zuul/builds/f68b409ea2404d5e8ba6df27d590b979/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2025-10-13 00:19:27.897894 | 
2025-10-13 00:19:27.898096 | TASK [ensure-docker : Ensure "docker" group exists]
2025-10-13 00:19:29.019990 | controller | ok
2025-10-13 00:19:29.029647 | 
2025-10-13 00:19:29.029855 | TASK [ensure-docker : Add user to docker group]
2025-10-13 00:19:30.204930 | controller | changed
2025-10-13 00:19:30.220360 | 
2025-10-13 00:19:30.220524 | TASK [Update docker daemon proxy configuration]
2025-10-13 00:19:30.259689 | controller | skipping: Conditional result was False
2025-10-13 00:19:30.275532 | 
2025-10-13 00:19:30.275674 | TASK [Lower default MTU]
2025-10-13 00:19:30.304834 | controller | skipping: Conditional result was False
2025-10-13 00:19:30.323729 | 
2025-10-13 00:19:30.323978 | TASK [ensure-docker : Get the status of the docket.socket unit]
2025-10-13 00:19:31.264791 | controller | inactive
2025-10-13 00:19:31.704078 | controller | ok: Runtime: 0:00:00.022561
2025-10-13 00:19:31.719741 | 
2025-10-13 00:19:31.719943 | TASK [ensure-docker : Get the status of the docket unit]
2025-10-13 00:19:32.653441 | controller | inactive
2025-10-13 00:19:33.106903 | controller | ok: Runtime: 0:00:00.027822
2025-10-13 00:19:33.121614 | 
2025-10-13 00:19:33.121760 | TASK [ensure-docker : Restart docker]
2025-10-13 00:19:33.174942 | Trigger a Docker restart
2025-10-13 00:19:33.188765 | 
2025-10-13 00:19:33.189556 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2025-10-13 00:19:33.231596 | 
2025-10-13 00:19:33.231723 | TASK [ensure-docker : Install docker-compose]
2025-10-13 00:19:33.247827 | controller | skipping: Conditional result was False
2025-10-13 00:19:33.257769 | 
2025-10-13 00:19:33.257883 | TASK [ensure-docker : Flush handlers before role exit]
2025-10-13 00:19:33.274324 | 
2025-10-13 00:19:33.274412 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2025-10-13 00:19:37.004470 | controller | changed
2025-10-13 00:19:37.022723 | 
2025-10-13 00:19:37.022869 | TASK [ensure-docker : Assure docker service is running]
2025-10-13 00:19:41.898904 | controller | changed
2025-10-13 00:19:41.909269 | 
2025-10-13 00:19:41.909347 | TASK [ensure-docker : Assure docker.socket service is running]
2025-10-13 00:19:43.449899 | controller | ok
2025-10-13 00:19:43.469381 | 
2025-10-13 00:19:43.469599 | TASK [ensure-docker : Correct group ownership on docker sock]
2025-10-13 00:19:44.318068 | controller | ok
2025-10-13 00:19:44.331566 | 
2025-10-13 00:19:44.331746 | TASK [ensure-docker : Validate ability to talk with docker]
2025-10-13 00:19:45.007445 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2025-10-13 00:19:45.212905 | controller | ok: Runtime: 0:00:00.038901
2025-10-13 00:19:45.223507 | 
2025-10-13 00:19:45.223624 | TASK [ensure-docker : Get version details]
2025-10-13 00:19:45.996717 | controller | Client: Docker Engine - Community
2025-10-13 00:19:45.998978 | controller |  Version:           24.0.7
2025-10-13 00:19:45.999007 | controller |  API version:       1.43
2025-10-13 00:19:45.999015 | controller |  Go version:        go1.20.10
2025-10-13 00:19:45.999033 | controller |  Git commit:        afdd53b
2025-10-13 00:19:45.999040 | controller |  Built:             Thu Oct 26 09:09:39 2023
2025-10-13 00:19:45.999048 | controller |  OS/Arch:           linux/amd64
2025-10-13 00:19:45.999056 | controller |  Context:           default
2025-10-13 00:19:45.999065 | controller |
2025-10-13 00:19:45.999072 | controller | Server: Docker Engine - Community
2025-10-13 00:19:45.999079 | controller |  Engine:
2025-10-13 00:19:45.999085 | controller |   Version:          24.0.7
2025-10-13 00:19:45.999091 | controller |   API version:      1.43 (minimum version 1.12)
2025-10-13 00:19:45.999097 | controller |   Go version:       go1.20.10
2025-10-13 00:19:45.999103 | controller |   Git commit:       311b9ff
2025-10-13 00:19:45.999109 | controller |   Built:            Thu Oct 26 09:08:11 2023
2025-10-13 00:19:45.999115 | controller |   OS/Arch:          linux/amd64
2025-10-13 00:19:45.999121 | controller |   Experimental:     false
2025-10-13 00:19:45.999127 | controller |  containerd:
2025-10-13 00:19:45.999133 | controller |   Version:          1.6.25
2025-10-13 00:19:45.999139 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2025-10-13 00:19:45.999145 | controller |  runc:
2025-10-13 00:19:45.999151 | controller |   Version:          1.1.10
2025-10-13 00:19:45.999157 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2025-10-13 00:19:45.999165 | controller |  docker-init:
2025-10-13 00:19:45.999171 | controller |   Version:          0.19.0
2025-10-13 00:19:45.999177 | controller |   GitCommit:        de40ad0
2025-10-13 00:19:46.593084 | controller | ok: Runtime: 0:00:00.067338
2025-10-13 00:19:46.607296 | 
2025-10-13 00:19:46.607436 | TASK [ensure-docker : Dump installed docker details]
2025-10-13 00:19:46.649170 | {'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-10-13 00:19:45.933471', 'end': '2025-10-13 00:19:46.000809', 'delta': '0:00:00.067338', 'msg': '', 'zuul_log_id': 'fa163ec2-ffbe-0a2f-ff17-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-10-13 00:19:46.663094 | 
2025-10-13 00:19:46.663392 | TASK [Use buildset registry]
2025-10-13 00:19:46.690880 | controller | skipping: Conditional result was False
2025-10-13 00:19:46.715886 | 
2025-10-13 00:19:46.716098 | TASK [Ensure python3.8 is present]
2025-10-13 00:19:46.744628 | controller | skipping: Conditional result was False
2025-10-13 00:19:46.768337 | 
2025-10-13 00:19:46.768456 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-10-13 00:19:46.803501 | controller | ok
2025-10-13 00:19:46.829847 | 
2025-10-13 00:19:46.829952 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-10-13 00:19:54.320896 | controller | ok: Nothing to do
2025-10-13 00:19:54.329252 | 
2025-10-13 00:19:54.329345 | TASK [our-ensure-python : Also install python3-devel]
2025-10-13 00:20:12.718374 | controller | changed
2025-10-13 00:20:12.783713 | 
2025-10-13 00:20:12.783887 | TASK [Run ensure-virtualenv role]
2025-10-13 00:20:12.804981 | controller | ok
2025-10-13 00:20:12.827006 | 
2025-10-13 00:20:12.827121 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-10-13 00:20:13.661056 | controller | /usr/bin/virtualenv
2025-10-13 00:20:14.192415 | controller | ok: Runtime: 0:00:00.007590
2025-10-13 00:20:14.207996 | 
2025-10-13 00:20:14.208197 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-10-13 00:20:14.249804 | controller | skipping: Conditional result was False
2025-10-13 00:20:14.250365 | controller | changed: All items complete
2025-10-13 00:20:14.250432 | 
2025-10-13 00:20:14.284400 | 
2025-10-13 00:20:14.284547 | TASK [Find the full path of the Python interpreter]
2025-10-13 00:20:15.205387 | controller | /usr/bin/python3.8
2025-10-13 00:20:15.661063 | controller | ok
2025-10-13 00:20:15.674300 | 
2025-10-13 00:20:15.674466 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-10-13 00:20:18.170432 | controller | created virtual environment CPython3.8.18.final.0-64 in 1278ms
2025-10-13 00:20:18.309616 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2025-10-13 00:20:18.309671 | 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-10-13 00:20:18.309686 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-10-13 00:20:18.309707 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-10-13 00:20:18.562138 | controller | changed
2025-10-13 00:20:18.575789 | 
2025-10-13 00:20:18.575941 | TASK [Set selinux package]
2025-10-13 00:20:18.618841 | controller | ok
2025-10-13 00:20:18.627901 | 
2025-10-13 00:20:18.627987 | TASK [Set selinux package (Fedora)]
2025-10-13 00:20:18.672444 | controller | ok
2025-10-13 00:20:18.680135 | 
2025-10-13 00:20:18.680223 | TASK [Install selinux into virtualenv]
2025-10-13 00:20:24.726124 | controller | Collecting selinux-please-lie-to-me
2025-10-13 00:20:24.829803 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-10-13 00:20:25.888100 | controller | Collecting setuptools<50.0.0
2025-10-13 00:20:25.893824 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-10-13 00:20:25.964170 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 12.8 MB/s eta 0:00:00
2025-10-13 00:20:26.106429 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-10-13 00:20:26.106848 | controller |   Attempting uninstall: setuptools
2025-10-13 00:20:26.108058 | controller |     Found existing installation: setuptools 62.6.0
2025-10-13 00:20:26.257352 | controller |     Uninstalling setuptools-62.6.0:
2025-10-13 00:20:26.279067 | controller |       Successfully uninstalled setuptools-62.6.0
2025-10-13 00:20:27.271790 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-10-13 00:20:27.573489 | controller |
2025-10-13 00:20:27.906368 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-10-13 00:20:27.906431 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-10-13 00:20:28.116370 | controller | ok: Runtime: 0:00:08.272849
2025-10-13 00:20:28.135776 | 
2025-10-13 00:20:28.135977 | TASK [Install pytest-forked into virtualenv]
2025-10-13 00:20:30.279611 | controller | Collecting pytest-forked
2025-10-13 00:20:30.359056 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2025-10-13 00:20:30.458087 | controller | Collecting py
2025-10-13 00:20:30.464500 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2025-10-13 00:20:30.513056 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 2.0 MB/s eta 0:00:00
2025-10-13 00:20:30.778053 | controller | Collecting pytest>=3.10
2025-10-13 00:20:30.788707 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2025-10-13 00:20:30.826526 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 10.2 MB/s eta 0:00:00
2025-10-13 00:20:30.966841 | controller | Collecting tomli>=1
2025-10-13 00:20:30.973775 | controller |   Downloading tomli-2.3.0-py3-none-any.whl (14 kB)
2025-10-13 00:20:31.041873 | controller | Collecting iniconfig
2025-10-13 00:20:31.051874 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2025-10-13 00:20:31.138180 | controller | Collecting exceptiongroup>=1.0.0rc8
2025-10-13 00:20:31.146959 | controller |   Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB)
2025-10-13 00:20:31.274345 | controller | Collecting packaging
2025-10-13 00:20:31.281709 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-10-13 00:20:31.309713 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 4.0 MB/s eta 0:00:00
2025-10-13 00:20:31.407107 | controller | Collecting pluggy<2,>=1.5
2025-10-13 00:20:31.416812 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2025-10-13 00:20:31.556401 | controller | Collecting typing-extensions>=4.6.0
2025-10-13 00:20:31.566005 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2025-10-13 00:20:31.586456 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 2.4 MB/s eta 0:00:00
2025-10-13 00:20:31.747370 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2025-10-13 00:20:33.041845 | controller | Successfully installed exceptiongroup-1.3.0 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-10-13 00:20:33.055614 | controller |
2025-10-13 00:20:33.345436 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-10-13 00:20:33.345489 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-10-13 00:20:33.546213 | controller | ok: Runtime: 0:00:04.242221
2025-10-13 00:20:33.559478 | 
2025-10-13 00:20:33.559636 | TASK [Update pip]
2025-10-13 00:20:35.849731 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2025-10-13 00:20:36.364778 | controller | Collecting pip
2025-10-13 00:20:36.512879 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2025-10-13 00:20:36.648469 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 15.7 MB/s eta 0:00:00
2025-10-13 00:20:36.893193 | controller | Installing collected packages: pip
2025-10-13 00:20:36.893564 | controller |   Attempting uninstall: pip
2025-10-13 00:20:36.894858 | controller |     Found existing installation: pip 22.2.2
2025-10-13 00:20:37.607554 | controller |     Uninstalling pip-22.2.2:
2025-10-13 00:20:37.707638 | controller |       Successfully uninstalled pip-22.2.2
2025-10-13 00:20:40.484169 | controller | Successfully installed pip-25.0.1
2025-10-13 00:20:40.998794 | controller | ok: Runtime: 0:00:06.192452
2025-10-13 00:20:41.011302 | 
2025-10-13 00:20:41.011458 | TASK [Ensure pip temp directory exists in /var/tmp]
2025-10-13 00:20:42.324265 | controller | changed
2025-10-13 00:20:42.336935 | 
2025-10-13 00:20:42.337115 | TASK [Install ansible into virtualenv]
2025-10-13 00:20:44.124648 | controller | Processing ./src/github.com/ansible/ansible
2025-10-13 00:20:44.129088 | controller |   Preparing metadata (setup.py): started
2025-10-13 00:20:45.232209 | controller |   Preparing metadata (setup.py): finished with status 'done'
2025-10-13 00:20:45.317817 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2025-10-13 00:20:45.339655 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2025-10-13 00:20:45.459752 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2025-10-13 00:20:45.469892 | 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-10-13 00:20:46.140688 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2025-10-13 00:20:46.149110 | controller |   Downloading cryptography-46.0.2-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2025-10-13 00:20:46.208052 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (25.0)
2025-10-13 00:20:46.499083 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2025-10-13 00:20:46.507421 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2025-10-13 00:20:46.522779 | 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-10-13 00:20:46.719030 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2025-10-13 00:20:46.726692 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2025-10-13 00:20:46.777027 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2025-10-13 00:20:46.785953 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2025-10-13 00:20:46.818337 | controller | Downloading cryptography-46.0.2-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2025-10-13 00:20:46.911048 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 55.6 MB/s eta 0:00:00
2025-10-13 00:20:46.921843 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2025-10-13 00:20:46.948418 | 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-10-13 00:20:46.981380 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 24.7 MB/s eta 0:00:00
2025-10-13 00:20:46.992967 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2025-10-13 00:20:47.029631 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2025-10-13 00:20:47.053523 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2025-10-13 00:20:47.101802 | controller | Building wheels for collected packages: ansible-base
2025-10-13 00:20:47.102846 | controller |   Building wheel for ansible-base (setup.py): started
2025-10-13 00:20:50.951355 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2025-10-13 00:20:50.952676 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=8921605e615bf93e583b638fb19d852276e190e254e5d96f0636df2e6925ebbc
2025-10-13 00:20:50.953026 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-ogrq0snx/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2025-10-13 00:20:50.978340 | controller | Successfully built ansible-base
2025-10-13 00:20:51.067202 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2025-10-13 00:20:54.677650 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-base-2.10.17.post0 cffi-1.17.1 cryptography-46.0.2 jinja2-3.1.6 pycparser-2.23
2025-10-13 00:20:55.261648 | controller | ok: Runtime: 0:00:11.751075
2025-10-13 00:20:55.268158 | 
2025-10-13 00:20:55.268224 | TASK [Setup download-artifact-fork role]
2025-10-13 00:20:55.298894 | controller | ok
2025-10-13 00:20:55.318219 | 
2025-10-13 00:20:55.318312 | TASK [download-artifact-fork : Parse inventory response]
2025-10-13 00:20:55.370452 | controller | ok
2025-10-13 00:20:55.376940 | 
2025-10-13 00:20:55.377048 | TASK [download-artifact-fork : Fetch info from Zuul API]
2025-10-13 00:20:55.411859 | controller | skipping: Conditional result was False
2025-10-13 00:20:55.424901 | 
2025-10-13 00:20:55.425087 | TASK [download-artifact-fork : Parse build response]
2025-10-13 00:20:55.452427 | controller | skipping: Conditional result was False
2025-10-13 00:20:55.472993 | 
2025-10-13 00:20:55.473213 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2025-10-13 00:20:56.410841 | controller | changed
2025-10-13 00:20:56.417107 | 
2025-10-13 00:20:56.417174 | LOOP [download-artifact-fork : Download archive by type]
2025-10-13 00:20:56.454923 | controller | skipping: Conditional result was False
2025-10-13 00:20:56.455458 | 
2025-10-13 00:20:59.384164 | controller | changed: OK (20188 bytes)
2025-10-13 00:20:59.429950 | 
2025-10-13 00:20:59.430318 | TASK [deploy-artifacts : define __collections]
2025-10-13 00:20:59.464909 | controller | ok
2025-10-13 00:20:59.479565 | 
2025-10-13 00:20:59.479752 | LOOP [deploy-artifacts : Create list of collections]
2025-10-13 00:20:59.507863 | controller | skipping: Conditional result was False
2025-10-13 00:20:59.518733 | controller | ok:
2025-10-13 00:20:59.519009 | controller | {
2025-10-13 00:20:59.519074 | controller |   "branch": "main",
2025-10-13 00:20:59.519099 | controller |   "job": "build-ansible-collection",
2025-10-13 00:20:59.519119 | controller |   "metadata": {
2025-10-13 00:20:59.519138 | controller |     "type": "ansible_collection",
2025-10-13 00:20:59.519157 | controller |     "version": "5.0.0"
2025-10-13 00:20:59.519175 | controller |   },
2025-10-13 00:20:59.519193 | controller |   "name": "ansible.network",
2025-10-13 00:20:59.519210 | controller |   "project": "ansible-collections/ansible.network",
2025-10-13 00:20:59.519229 | controller |   "url": "https://24b4f98c1bded3fc3ae5-14c221d65fba485f69651901e3be6f48.ssl.cf5.rackcdn.com/ansible/fcbc961e382849da896223a0cecc7d82/artifacts/ansible-network-5.0.0.tar.gz"
2025-10-13 00:20:59.519257 | controller | }
2025-10-13 00:20:59.540482 | 
2025-10-13 00:20:59.540662 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2025-10-13 00:21:01.266012 | controller | Starting galaxy collection install process
2025-10-13 00:21:01.266256 | controller | Process install dependency map
2025-10-13 00:21:17.579633 | controller | Starting collection install process
2025-10-13 00:21:17.584770 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2025-10-13 00:21:17.598791 | controller | ansible.network (5.0.0) was installed successfully
2025-10-13 00:21:17.600539 | controller | Installing 'ansible.netcommon:8.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2025-10-13 00:21:17.600777 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-netcommon-8.1.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4057p7ff69uo/tmphr8ozai2
2025-10-13 00:21:19.106331 | controller | ansible.netcommon (8.1.0) was installed successfully
2025-10-13 00:21:20.792316 | controller | Installing 'ansible.utils:6.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2025-10-13 00:21:20.792369 | 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-4057p7ff69uo/tmphr8ozai2
2025-10-13 00:21:20.792394 | controller | ansible.utils (6.0.0) was installed successfully
2025-10-13 00:21:23.321169 | controller | Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2025-10-13 00:21:23.321220 | 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-4057p7ff69uo/tmphr8ozai2
2025-10-13 00:21:23.321235 | controller | arista.eos (12.0.0) was installed successfully
2025-10-13 00:21:23.321354 | controller | Installing 'cisco.ios:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2025-10-13 00:21:23.323018 | 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-4057p7ff69uo/tmphr8ozai2
2025-10-13 00:21:26.349162 | controller | cisco.ios (11.1.1) was installed successfully
2025-10-13 00:21:26.349455 | controller | Installing 'cisco.iosxr:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2025-10-13 00:21:26.349472 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-iosxr-12.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4057p7ff69uo/tmphr8ozai2
2025-10-13 00:21:29.406748 | controller | cisco.iosxr (12.0.0) was installed successfully
2025-10-13 00:21:29.407129 | controller | Installing 'cisco.nxos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2025-10-13 00:21:29.407148 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4057p7ff69uo/tmphr8ozai2
2025-10-13 00:21:33.682812 | controller | cisco.nxos (11.0.0) was installed successfully
2025-10-13 00:21:33.683102 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2025-10-13 00:21:33.683127 | 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-4057p7ff69uo/tmphr8ozai2
2025-10-13 00:21:36.445905 | controller | junipernetworks.junos (11.0.0) was installed successfully
2025-10-13 00:21:36.956199 | controller | ok: Runtime: 0:00:36.235679
2025-10-13 00:21:36.980552 | 
2025-10-13 00:21:36.980724 | TASK [podman may need iptables kernel modules]
2025-10-13 00:21:38.356868 | controller | ok: Runtime: 0:00:00.007507
2025-10-13 00:21:38.431151 | 
2025-10-13 00:21:38.431308 | PLAY RECAP
2025-10-13 00:21:38.431367 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2025-10-13 00:21:38.431395 | 
2025-10-13 00:21:38.572505 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-10-13 00:21:38.574454 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-10-13 00:21:39.245990 | 
2025-10-13 00:21:39.246148 | PLAY [controller]
2025-10-13 00:21:39.270021 | 
2025-10-13 00:21:39.270191 | TASK [Copy the galaxy.yml on the controller]
2025-10-13 00:21:42.880512 | controller | changed
2025-10-13 00:21:42.893392 | 
2025-10-13 00:21:42.893595 | TASK [Load information from galaxy.yml]
2025-10-13 00:21:42.937932 | controller | ok
2025-10-13 00:21:42.944168 | 
2025-10-13 00:21:42.944234 | LOOP [Enable FIPS mode]
2025-10-13 00:21:42.979025 | 
2025-10-13 00:21:42.979372 | LOOP [ansible-test : Include branch specific variables.]
2025-10-13 00:21:43.042808 | controller | ok: "/var/lib/zuul/builds/f68b409ea2404d5e8ba6df27d590b979/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2025-10-13 00:21:43.043239 | 
2025-10-13 00:21:43.059894 | 
2025-10-13 00:21:43.060139 | TASK [ansible-test : Setup --skip-tags for test_options]
2025-10-13 00:21:43.135362 | controller | ok
2025-10-13 00:21:43.148069 | 
2025-10-13 00:21:43.148355 | TASK [ansible-test : Enable --inventory for network-integration]
2025-10-13 00:21:43.185685 | controller | skipping: Conditional result was False
2025-10-13 00:21:43.195694 | 
2025-10-13 00:21:43.195799 | TASK [ansible-test : Enable --retry-on-error]
2025-10-13 00:21:43.231779 | controller | skipping: Conditional result was False
2025-10-13 00:21:43.240478 | 
2025-10-13 00:21:43.240575 | TASK [ansible-test : Enable --continue-on-error]
2025-10-13 00:21:43.293321 | controller | ok
2025-10-13 00:21:43.300984 | 
2025-10-13 00:21:43.301092 | TASK [ansible-test : Enable --requirements]
2025-10-13 00:21:43.326259 | controller | skipping: Conditional result was False
2025-10-13 00:21:43.336345 | 
2025-10-13 00:21:43.336439 | TASK [ansible-test : Adjust options for unit tests]
2025-10-13 00:21:43.361763 | controller | skipping: Conditional result was False
2025-10-13 00:21:43.370524 | 
2025-10-13 00:21:43.370613 | TASK [ansible-test : Install requirements with unit tests]
2025-10-13 00:21:43.418022 | controller | ok
2025-10-13 00:21:43.471514 | 
2025-10-13 00:21:43.471683 | TASK [ansible-test : Set --skip-test]
2025-10-13 00:21:43.517689 | controller | skipping: Conditional result was False
2025-10-13 00:21:43.524653 | 
2025-10-13 00:21:43.524875 | TASK [ansible-test : Reduce verbosity]
2025-10-13 00:21:43.556822 | controller | ok
2025-10-13 00:21:43.565112 | 
2025-10-13 00:21:43.565205 | TASK [ansible-test : Setup --python option]
2025-10-13 00:21:43.580142 | controller | skipping: Conditional result was False
2025-10-13 00:21:43.590569 | 
2025-10-13 00:21:43.590676 | TASK [ansible-test : Setup --docker option]
2025-10-13 00:21:43.624441 | controller | ok
2025-10-13 00:21:43.633719 | 
2025-10-13 00:21:43.633812 | TASK [ansible-test : check if MANIFEST.json exists]
2025-10-13 00:21:44.620467 | controller | ok
2025-10-13 00:21:44.638500 | 
2025-10-13 00:21:44.638620 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2025-10-13 00:21:44.674892 | controller | skipping: Conditional result was False
2025-10-13 00:21:44.691260 | 
2025-10-13 00:21:44.691457 | TASK [ansible-test : Load information from MANIFEST.json]
2025-10-13 00:21:44.720203 | controller | skipping: Conditional result was False
2025-10-13 00:21:44.738741 | 
2025-10-13 00:21:44.738956 | TASK [ansible-test : Setup location of project for integration tests]
2025-10-13 00:21:44.777201 | controller | skipping: Conditional result was False
2025-10-13 00:21:44.791821 | 
2025-10-13 00:21:44.792024 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2025-10-13 00:21:46.163937 | controller | ok
2025-10-13 00:21:46.171862 | 
2025-10-13 00:21:46.171978 | TASK [ansible-test : Load information from galaxy.yml]
2025-10-13 00:21:46.217079 | controller | ok
2025-10-13 00:21:46.226507 | 
2025-10-13 00:21:46.226594 | TASK [ansible-test : Setup location of project for integration tests]
2025-10-13 00:21:46.269948 | controller | ok
2025-10-13 00:21:46.278229 | 
2025-10-13 00:21:46.278326 | TASK [ansible-test : Setup minimum test requirements]
2025-10-13 00:21:46.310847 | controller | ok
2025-10-13 00:21:46.321098 | 
2025-10-13 00:21:46.321233 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2025-10-13 00:21:47.388598 | controller | ok
2025-10-13 00:21:47.404144 | 
2025-10-13 00:21:47.404286 | TASK [ansible-test : Install python requirements]
2025-10-13 00:21:49.490321 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-10-13 00:21:49.522210 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2025-10-13 00:21:49.590192 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-10-13 00:21:49.596124 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2025-10-13 00:21:49.611733 | 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-10-13 00:21:49.623396 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2025-10-13 00:21:49.646394 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2025-10-13 00:21:49.713656 | controller | Installing collected packages: pathspec, yamllint
2025-10-13 00:21:49.838339 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2025-10-13 00:21:50.295218 | controller | ok: Runtime: 0:00:01.624549
2025-10-13 00:21:50.311059 | 
2025-10-13 00:21:50.311293 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2025-10-13 00:21:50.683434 | controller | skipping: Conditional result was False
2025-10-13 00:21:50.698802 | 
2025-10-13 00:21:50.698975 | TASK [ansible-test : Enable persistent connection logging]
2025-10-13 00:21:52.178900 | controller | changed: section and option added
2025-10-13 00:21:52.195959 | 
2025-10-13 00:21:52.196217 | TASK [ansible-test : Set persistent ssh_type value]
2025-10-13 00:21:53.377729 | controller | changed: option added
2025-10-13 00:21:53.391672 | 
2025-10-13 00:21:53.391824 | TASK [ansible-test : Set ansible log path]
2025-10-13 00:21:54.436492 | controller | changed: section and option added
2025-10-13 00:21:54.449785 | 
2025-10-13 00:21:54.449948 | TASK [ansible-test : Identify the changed targets]
2025-10-13 00:21:54.834825 | controller | skipping: Conditional result was False
2025-10-13 00:21:54.848898 | 
2025-10-13 00:21:54.849080 | TASK [ansible-test : set_fact]
2025-10-13 00:21:54.902248 | controller | skipping: Conditional result was False
2025-10-13 00:21:54.914415 | 
2025-10-13 00:21:54.914573 | TASK [ansible-test : Set the targets]
2025-10-13 00:21:54.951563 | controller | ok
2025-10-13 00:21:54.965488 | 
2025-10-13 00:21:54.965731 | TASK [ansible-test : set_fact]
2025-10-13 00:21:54.994812 | controller | skipping: Conditional result was False
2025-10-13 00:21:55.003773 | 
2025-10-13 00:21:55.003909 | TASK [ansible-test : List the targets]
2025-10-13 00:21:55.377181 | controller | skipping: Conditional result was False
2025-10-13 00:21:55.475926 | 
2025-10-13 00:21:55.476144 | TASK [ansible-test : set_fact]
2025-10-13 00:21:55.504187 | controller | skipping: Conditional result was False
2025-10-13 00:21:55.513786 | 
2025-10-13 00:21:55.513943 | TASK [ansible-test : set_fact]
2025-10-13 00:21:55.548355 | controller | skipping: Conditional result was False
2025-10-13 00:21:55.559128 | 
2025-10-13 00:21:55.559351 | LOOP [ansible-test : set_fact]
2025-10-13 00:21:55.644595 | 
2025-10-13 00:21:55.644903 | TASK [ansible-test : set_fact]
2025-10-13 00:21:55.675221 | controller | skipping: Conditional result was False
2025-10-13 00:21:55.688690 | 
2025-10-13 00:21:55.688908 | TASK [ansible-test : debug]
2025-10-13 00:21:55.719426 | controller | skipping: Conditional result was False
2025-10-13 00:21:55.730832 | 
2025-10-13 00:21:55.731002 | TASK [ansible-test : debug]
2025-10-13 00:21:55.773786 | About to run: ansible-test sanity --requirements --docker -v
2025-10-13 00:21:55.782990 | 
2025-10-13 00:21:55.783152 | TASK [ansible-test : Run the test suite]
2025-10-13 00:21:57.385924 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2025-10-13 00:21:57.434631 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2025-10-13 00:21:59.110929 | controller | 2.9.0: Pulling from ansible/default-test-container
2025-10-13 00:21:59.111123 | controller | 171857c49d0f: Pulling fs layer
2025-10-13 00:21:59.111163 | controller | 419640447d26: Pulling fs layer
2025-10-13 00:21:59.111171 | controller | 61e52f862619: Pulling fs layer
2025-10-13 00:21:59.111178 | controller | 851de0426e26: Pulling fs layer
2025-10-13 00:21:59.111188 | controller | 37425716ea5b: Pulling fs layer
2025-10-13 00:21:59.111198 | controller | cd9b0c439616: Pulling fs layer
2025-10-13 00:21:59.111204 | controller | d4612dbd8855: Pulling fs layer
2025-10-13 00:21:59.111210 | controller | 554699364730: Pulling fs layer
2025-10-13 00:21:59.111216 | controller | 1ff15d4bdb3e: Pulling fs layer
2025-10-13 00:21:59.111222 | controller | 5f2e4724adea: Pulling fs layer
2025-10-13 00:21:59.111228 | controller | a02d9a95ccb8: Pulling fs layer
2025-10-13 00:21:59.111234 | controller | 989f4ce370cb: Pulling fs layer
2025-10-13 00:21:59.111240 | controller | 0d7f52cdf9df: Pulling fs layer
2025-10-13 00:21:59.111245 | controller | c423742351d5: Pulling fs layer
2025-10-13 00:21:59.111252 | controller | 109236341d21: Pulling fs layer
2025-10-13 00:21:59.111258 | controller | 07a2f61c96b8: Pulling fs layer
2025-10-13 00:21:59.111263 | controller | 041f122f27ae: Pulling fs layer
2025-10-13 00:21:59.111269 | controller | ca6f5098b12e: Pulling fs layer
2025-10-13 00:21:59.111298 | controller | 1eb3e7b2afd9: Pulling fs layer
2025-10-13 00:21:59.111304 | controller | 18a0b750727a: Pulling fs layer
2025-10-13 00:21:59.111312 | controller | 17f2f596716c: Pulling fs layer
2025-10-13 00:21:59.111320 | controller | f35a9998a9bd: Pulling fs layer
2025-10-13 00:21:59.111327 | controller | fdfc10566405: Pulling fs layer
2025-10-13 00:21:59.111333 | controller | 1ff1c442c5fe: Pulling fs layer
2025-10-13 00:21:59.111339 | controller | a3443c4a17ed: Pulling fs layer
2025-10-13 00:21:59.111344 | controller | 0edcf9fb2b15: Pulling fs layer
2025-10-13 00:21:59.111350 | controller | 5af43b69e845: Pulling fs layer
2025-10-13 00:21:59.111356 | controller | 1651773effb2: Pulling fs layer
2025-10-13 00:21:59.111362 | controller | 79e23c5c0fe4: Pulling fs layer
2025-10-13 00:21:59.111372 | controller | e5058b1faaa6: Pulling fs layer
2025-10-13 00:21:59.111378 | controller | 846775490569: Pulling fs layer
2025-10-13 00:21:59.111384 | controller | 07a2f61c96b8: Waiting
2025-10-13 00:21:59.111390 | controller | 041f122f27ae: Waiting
2025-10-13 00:21:59.111396 | controller | ca6f5098b12e: Waiting
2025-10-13 00:21:59.111402 | controller | 1eb3e7b2afd9: Waiting
2025-10-13 00:21:59.111407 | controller | 18a0b750727a: Waiting
2025-10-13 00:21:59.111413 | controller | 17f2f596716c: Waiting
2025-10-13 00:21:59.111422 | controller | f35a9998a9bd: Waiting
2025-10-13 00:21:59.111428 | controller | fdfc10566405: Waiting
2025-10-13 00:21:59.111434 | controller | 1ff1c442c5fe: Waiting
2025-10-13 00:21:59.111439 | controller | a3443c4a17ed: Waiting
2025-10-13 00:21:59.111445 | controller | 0edcf9fb2b15: Waiting
2025-10-13 00:21:59.111451 | controller | 5af43b69e845: Waiting
2025-10-13 00:21:59.111457 | controller | 1651773effb2: Waiting
2025-10-13 00:21:59.111463 | controller | 79e23c5c0fe4: Waiting
2025-10-13 00:21:59.111469 | controller | e5058b1faaa6: Waiting
2025-10-13 00:21:59.111474 | controller | 846775490569: Waiting
2025-10-13 00:21:59.111480 | controller | 1ff15d4bdb3e: Waiting
2025-10-13 00:21:59.111486 | controller | 5f2e4724adea: Waiting
2025-10-13 00:21:59.111495 | controller | a02d9a95ccb8: Waiting
2025-10-13 00:21:59.111501 | controller | 989f4ce370cb: Waiting
2025-10-13 00:21:59.111513 | controller | 0d7f52cdf9df: Waiting
2025-10-13 00:21:59.513813 | controller | c423742351d5: Waiting
2025-10-13 00:21:59.513857 | controller | 109236341d21: Waiting
2025-10-13 00:21:59.513864 | controller | 37425716ea5b: Waiting
2025-10-13 00:21:59.513870 | controller | cd9b0c439616: Waiting
2025-10-13 00:21:59.513876 | controller | d4612dbd8855: Waiting
2025-10-13 00:21:59.513883 | controller | 554699364730: Waiting
2025-10-13 00:21:59.513890 | controller | 851de0426e26: Waiting
2025-10-13 00:21:59.513904 | controller | 419640447d26: Verifying Checksum
2025-10-13 00:21:59.522899 | controller | 419640447d26: Download complete
2025-10-13 00:21:59.523000 | controller | 61e52f862619: Verifying Checksum
2025-10-13 00:21:59.890494 | controller | 61e52f862619: Download complete
2025-10-13 00:21:59.890554 | controller | 171857c49d0f: Verifying Checksum
2025-10-13 00:22:00.715061 | controller | 171857c49d0f: Download complete
2025-10-13 00:22:00.715214 | controller | cd9b0c439616: Verifying Checksum
2025-10-13 00:22:00.804882 | controller | cd9b0c439616: Download complete
2025-10-13 00:22:00.804999 | controller | 851de0426e26: Verifying Checksum
2025-10-13 00:22:02.012915 | controller | 851de0426e26: Download complete
2025-10-13 00:22:02.012967 | controller | d4612dbd8855: Verifying Checksum
2025-10-13 00:22:02.013114 | controller | d4612dbd8855: Download complete
2025-10-13 00:22:03.250622 | controller | 1ff15d4bdb3e: Verifying Checksum
2025-10-13 00:22:04.410739 | controller | 1ff15d4bdb3e: Download complete
2025-10-13 00:22:04.410805 | controller | 5f2e4724adea: Verifying Checksum
2025-10-13 00:22:05.099536 | controller | 5f2e4724adea: Download complete
2025-10-13 00:22:05.099595 | controller | 171857c49d0f: Pull complete
2025-10-13 00:22:05.157855 | controller | 419640447d26: Pull complete
2025-10-13 00:22:05.195015 | controller | 61e52f862619: Pull complete
2025-10-13 00:22:05.289220 | controller | 851de0426e26: Pull complete
2025-10-13 00:22:06.162996 | controller | a02d9a95ccb8: Verifying Checksum
2025-10-13 00:22:06.163092 | controller | a02d9a95ccb8: Download complete
2025-10-13 00:22:07.147393 | controller | 989f4ce370cb: Verifying Checksum
2025-10-13 00:22:07.147524 | controller | 989f4ce370cb: Download complete
2025-10-13 00:22:08.235055 | controller | 0d7f52cdf9df: Verifying Checksum
2025-10-13 00:22:08.235251 | controller | 0d7f52cdf9df: Download complete
2025-10-13 00:22:09.130557 | controller | c423742351d5: Verifying Checksum
2025-10-13 00:22:09.130688 | controller | c423742351d5: Download complete
2025-10-13 00:22:15.846783 | controller | 554699364730: Verifying Checksum
2025-10-13 00:22:15.846914 | controller | 554699364730: Download complete
2025-10-13 00:22:16.879110 | controller | 07a2f61c96b8: Verifying Checksum
2025-10-13 00:22:16.879328 | controller | 07a2f61c96b8: Download complete
2025-10-13 00:22:18.007225 | controller | 041f122f27ae: Verifying Checksum
2025-10-13 00:22:18.007530 | controller | 041f122f27ae: Download complete
2025-10-13 00:22:20.223959 | controller | ca6f5098b12e: Download complete
2025-10-13 00:22:21.713551 | controller | 1eb3e7b2afd9: Verifying Checksum
2025-10-13 00:22:21.713686 | controller | 1eb3e7b2afd9: Download complete
2025-10-13 00:22:23.363724 | controller | 109236341d21: Verifying Checksum
2025-10-13 00:22:23.363916 | controller | 109236341d21: Download complete
2025-10-13 00:22:23.656772 | controller | 18a0b750727a: Verifying Checksum
2025-10-13 00:22:23.657015 | controller | 18a0b750727a: Download complete
2025-10-13 00:22:24.264562 | controller | 17f2f596716c: Verifying Checksum
2025-10-13 00:22:24.264651 | controller | 17f2f596716c: Download complete
2025-10-13 00:22:24.728801 | controller | f35a9998a9bd: Verifying Checksum
2025-10-13 00:22:24.728913 | controller | f35a9998a9bd: Download complete
2025-10-13 00:22:25.489738 | controller | fdfc10566405: Verifying Checksum
2025-10-13 00:22:25.489837 | controller | fdfc10566405: Download complete
2025-10-13 00:22:25.772141 | controller | 1ff1c442c5fe: Verifying Checksum
2025-10-13 00:22:25.772297 | controller | 1ff1c442c5fe: Download complete
2025-10-13 00:22:28.029454 | controller | a3443c4a17ed: Verifying Checksum
2025-10-13 00:22:29.055070 | controller | a3443c4a17ed: Download complete
2025-10-13 00:22:29.055159 | controller | 37425716ea5b: Verifying Checksum
2025-10-13 00:22:29.217992 | controller | 37425716ea5b: Download complete
2025-10-13 00:22:29.218076 | controller | 0edcf9fb2b15: Verifying Checksum
2025-10-13 00:22:29.218123 | controller | 0edcf9fb2b15: Download complete
2025-10-13 00:22:32.185015 | controller | 5af43b69e845: Download complete
2025-10-13 00:22:32.701240 | controller | 79e23c5c0fe4: Verifying Checksum
2025-10-13 00:22:32.701373 | controller | 79e23c5c0fe4: Download complete
2025-10-13 00:22:34.662955 | controller | 1651773effb2: Verifying Checksum
2025-10-13 00:22:34.664200 | controller | 1651773effb2: Download complete
2025-10-13 00:22:35.150241 | controller | e5058b1faaa6: Verifying Checksum
2025-10-13 00:22:39.244597 | controller | e5058b1faaa6: Download complete
2025-10-13 00:22:39.244693 | controller | 846775490569: Verifying Checksum
2025-10-13 00:22:54.854974 | controller | 846775490569: Download complete
2025-10-13 00:22:54.855045 | controller | 37425716ea5b: Pull complete
2025-10-13 00:22:54.896668 | controller | cd9b0c439616: Pull complete
2025-10-13 00:22:54.955425 | controller | d4612dbd8855: Pull complete
2025-10-13 00:23:01.446966 | controller | 554699364730: Pull complete
2025-10-13 00:23:01.515102 | controller | 1ff15d4bdb3e: Pull complete
2025-10-13 00:23:01.587807 | controller | 5f2e4724adea: Pull complete
2025-10-13 00:23:01.698891 | controller | a02d9a95ccb8: Pull complete
2025-10-13 00:23:01.771866 | controller | 989f4ce370cb: Pull complete
2025-10-13 00:23:01.832299 | controller | 0d7f52cdf9df: Pull complete
2025-10-13 00:23:01.875516 | controller | c423742351d5: Pull complete
2025-10-13 00:23:05.079732 | controller | 109236341d21: Pull complete
2025-10-13 00:23:05.111651 | controller | 07a2f61c96b8: Pull complete
2025-10-13 00:23:05.149159 | controller | 041f122f27ae: Pull complete
2025-10-13 00:23:05.398442 | controller | ca6f5098b12e: Pull complete
2025-10-13 00:23:05.483467 | controller | 1eb3e7b2afd9: Pull complete
2025-10-13 00:23:05.571002 | controller | 18a0b750727a: Pull complete
2025-10-13 00:23:05.618359 | controller | 17f2f596716c: Pull complete
2025-10-13 00:23:05.674785 | controller | f35a9998a9bd: Pull complete
2025-10-13 00:23:05.721200 | controller | fdfc10566405: Pull complete
2025-10-13 00:23:05.771652 | controller | 1ff1c442c5fe: Pull complete
2025-10-13 00:23:08.278967 | controller | a3443c4a17ed: Pull complete
2025-10-13 00:23:10.861528 | controller | 0edcf9fb2b15: Pull complete
2025-10-13 00:23:16.725379 | controller | 5af43b69e845: Pull complete
2025-10-13 00:23:20.522025 | controller | 1651773effb2: Pull complete
2025-10-13 00:23:24.097207 | controller | 79e23c5c0fe4: Pull complete
2025-10-13 00:23:26.933410 | controller | e5058b1faaa6: Pull complete
2025-10-13 00:23:30.651164 | controller | 846775490569: Pull complete
2025-10-13 00:23:30.688774 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2025-10-13 00:23:30.696184 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2025-10-13 00:23:30.722960 | controller | quay.io/ansible/default-test-container:2.9.0
2025-10-13 00:23:30.730475 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2025-10-13 00:23:30.801464 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2025-10-13 00:23:32.252704 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2025-10-13 00:23:32.252899 | controller | 04a5f4cda3ee: Pulling fs layer
2025-10-13 00:23:32.252912 | controller | ff496a88c8ed: Pulling fs layer
2025-10-13 00:23:32.252920 | controller | 0ce83f459fe7: Pulling fs layer
2025-10-13 00:23:32.252967 | controller | 2e5170e1f099: Pulling fs layer
2025-10-13 00:23:32.252976 | controller | 7641eb41b08c: Pulling fs layer
2025-10-13 00:23:32.252984 | controller | ad15fa9da398: Pulling fs layer
2025-10-13 00:23:32.253044 | controller | 087d91352424: Pulling fs layer
2025-10-13 00:23:32.253122 | controller | 8b92efd6a100: Pulling fs layer
2025-10-13 00:23:32.253134 | controller | 7641eb41b08c: Waiting
2025-10-13 00:23:32.253194 | controller | ad15fa9da398: Waiting
2025-10-13 00:23:32.253252 | controller | 087d91352424: Waiting
2025-10-13 00:23:32.253749 | controller | 2e5170e1f099: Waiting
2025-10-13 00:23:32.253766 | controller | 8b92efd6a100: Waiting
2025-10-13 00:23:32.983033 | controller | 0ce83f459fe7: Verifying Checksum
2025-10-13 00:23:33.038162 | controller | 0ce83f459fe7: Download complete
2025-10-13 00:23:33.038262 | controller | ff496a88c8ed: Verifying Checksum
2025-10-13 00:23:33.828688 | controller | ff496a88c8ed: Download complete
2025-10-13 00:23:33.828781 | controller | 7641eb41b08c: Verifying Checksum
2025-10-13 00:23:33.942616 | controller | 7641eb41b08c: Download complete
2025-10-13 00:23:33.942681 | controller | 2e5170e1f099: Verifying Checksum
2025-10-13 00:23:34.615925 | controller | 2e5170e1f099: Download complete
2025-10-13 00:23:34.616048 | controller | ad15fa9da398: Verifying Checksum
2025-10-13 00:23:35.079642 | controller | ad15fa9da398: Download complete
2025-10-13 00:23:35.079737 | controller | 04a5f4cda3ee: Verifying Checksum
2025-10-13 00:23:35.503407 | controller | 04a5f4cda3ee: Download complete
2025-10-13 00:23:35.503484 | controller | 8b92efd6a100: Verifying Checksum
2025-10-13 00:23:35.513961 | controller | 8b92efd6a100: Download complete
2025-10-13 00:23:35.514021 | controller | 087d91352424: Verifying Checksum
2025-10-13 00:23:35.514664 | controller | 087d91352424: Download complete
2025-10-13 00:23:41.224333 | controller | 04a5f4cda3ee: Pull complete
2025-10-13 00:23:41.364364 | controller | ff496a88c8ed: Pull complete
2025-10-13 00:23:41.531789 | controller | 0ce83f459fe7: Pull complete
2025-10-13 00:23:43.753630 | controller | 2e5170e1f099: Pull complete
2025-10-13 00:23:43.851473 | controller | 7641eb41b08c: Pull complete
2025-10-13 00:23:43.929236 | controller | ad15fa9da398: Pull complete
2025-10-13 00:23:45.223572 | controller | 087d91352424: Pull complete
2025-10-13 00:23:45.293302 | controller | 8b92efd6a100: Pull complete
2025-10-13 00:23:45.351747 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2025-10-13 00:23:45.373562 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2025-10-13 00:23:45.445675 | controller | quay.io/ansible/pypi-test-container:1.0.0
2025-10-13 00:23:49.528958 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2025-10-13 00:23:49.529022 | controller | Run command: docker inspect 744e3e75d211577475a69e08b0c73012a5b5bcc9501f99b9276d075355bfd716
2025-10-13 00:23:49.612777 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2025-10-13 00:23:49.631364 | controller | Including collection: ansible.netcommon (288 files)
2025-10-13 00:23:49.631447 | controller | Including collection: ansible.network (23 files)
2025-10-13 00:23:49.652202 | controller | Including collection: ansible.utils (442 files)
2025-10-13 00:23:49.706192 | controller | Including collection: arista.eos (1022 files)
2025-10-13 00:23:49.773647 | controller | Including collection: cisco.ios (1172 files)
2025-10-13 00:23:49.838962 | controller | Including collection: cisco.iosxr (1037 files)
2025-10-13 00:23:49.956803 | controller | Including collection: cisco.nxos (1680 files)
2025-10-13 00:23:50.030250 | controller | Including collection: junipernetworks.junos (963 files)
2025-10-13 00:23:50.096021 | controller | Creating a payload archive containing 7353 files...
2025-10-13 00:23:52.790533 | controller | Created a 5962076 byte payload archive containing 7353 files in 2 seconds.
2025-10-13 00:23:52.798667 | controller | Assuming Docker is available on localhost.
2025-10-13 00:23:52.799032 | 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-10-13 00:23:53.557224 | controller | Run command: docker exec -i c1d6163a977978825d39bd0d6c6ea570112e598ebe805211514bebcb877f766b dd of=/root/docker.sh bs=65536
2025-10-13 00:23:53.691410 | controller | Run command: docker exec c1d6163a977978825d39bd0d6c6ea570112e598ebe805211514bebcb877f766b /bin/bash /root/docker.sh
2025-10-13 00:23:53.821010 | controller | Run command: docker exec -i c1d6163a977978825d39bd0d6c6ea570112e598ebe805211514bebcb877f766b dd of=/root/test.tgz bs=65536
2025-10-13 00:23:53.963411 | controller | Run command: docker exec c1d6163a977978825d39bd0d6c6ea570112e598ebe805211514bebcb877f766b tar oxzf /root/test.tgz -C /root
2025-10-13 00:23:55.332657 | controller | Run command: docker exec c1d6163a977978825d39bd0d6c6ea570112e598ebe805211514bebcb877f766b /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-uo16dfps.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2025-10-13 00:23:55.885595 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2025-10-13 00:23:55.885823 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2025-10-13 00:23:55.886065 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2025-10-13 00:23:55.890074 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2025-10-13 00:23:55.897202 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2025-10-13 00:23:55.897259 | controller | No tests applicable.
2025-10-13 00:23:55.897408 | controller | Running sanity test 'ansible-doc' with Python 3.6
2025-10-13 00:23:55.897487 | controller | No tests applicable.
2025-10-13 00:23:55.898666 | controller | Running sanity test 'changelog' with Python 3.6
2025-10-13 00:23:55.898686 | 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-10-13 00:23:57.143229 | 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-10-13 00:23:57.926389 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2025-10-13 00:24:12.955552 | 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-10-13 00:24:28.482968 | 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-10-13 00:24:44.502467 | 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-10-13 00:25:01.525460 | 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-10-13 00:25:20.570237 | 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-10-13 00:25:35.617721 | 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-10-13 00:25:35.630352 | 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-10-13 00:25:35.963628 | 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-10-13 00:25:35.969260 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2025-10-13 00:25:36.204029 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2025-10-13 00:25:36.204101 | controller | Removing custom PyPI hosts entries: /etc/hosts
2025-10-13 00:25:36.204123 | controller | Run command: docker exec c1d6163a977978825d39bd0d6c6ea570112e598ebe805211514bebcb877f766b tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2025-10-13 00:25:36.420230 | controller | Run command: docker exec -i c1d6163a977978825d39bd0d6c6ea570112e598ebe805211514bebcb877f766b dd if=/root/output.tgz bs=65536
2025-10-13 00:25:36.569486 | controller | Run command: tar oxzf /tmp/ansible-result-035px30o.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2025-10-13 00:25:36.606598 | controller | Run command: docker rm -f 744e3e75d211577475a69e08b0c73012a5b5bcc9501f99b9276d075355bfd716
2025-10-13 00:25:37.058485 | controller | Run command: docker rm -f c1d6163a977978825d39bd0d6c6ea570112e598ebe805211514bebcb877f766b
2025-10-13 00:25:38.050863 | controller | ERROR: Command "docker exec c1d6163a977978825d39bd0d6c6ea570112e598ebe805211514bebcb877f766b /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-uo16dfps.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2025-10-13 00:25:38.505513 | controller | ERROR
2025-10-13 00:25:38.505867 | controller | {
2025-10-13 00:25:38.505928 | controller |   "delta": "0:03:41.105019",
2025-10-13 00:25:38.505970 | controller |   "end": "2025-10-13 00:25:38.165103",
2025-10-13 00:25:38.506008 | controller |   "msg": "non-zero return code",
2025-10-13 00:25:38.506085 | controller |   "rc": 1,
2025-10-13 00:25:38.506124 | controller |   "start": "2025-10-13 00:21:57.060084"
2025-10-13 00:25:38.506159 | controller | }
failure
2025-10-13 00:25:38.514295 | 
2025-10-13 00:25:38.514403 | PLAY RECAP
2025-10-13 00:25:38.514489 | controller | ok: 20 changed: 5 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 0
2025-10-13 00:25:38.514532 | 
2025-10-13 00:25:38.711631 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-10-13 00:25:38.713738 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-10-13 00:25:39.373988 | 
2025-10-13 00:25:39.374152 | PLAY [all]
2025-10-13 00:25:39.395876 | 
2025-10-13 00:25:39.395970 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-10-13 00:25:42.574448 | controller | changed: non-zero return code
2025-10-13 00:25:42.581094 | 
2025-10-13 00:25:42.581181 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-10-13 00:25:42.607869 | controller | skipping: Conditional result was False
2025-10-13 00:25:42.623525 | 
2025-10-13 00:25:42.623711 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-10-13 00:25:42.668650 | 
2025-10-13 00:25:42.668980 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-10-13 00:25:42.706289 | 
2025-10-13 00:25:42.706493 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-10-13 00:25:42.732854 | controller | skipping: Conditional result was False
2025-10-13 00:25:42.742486 | 
2025-10-13 00:25:42.742595 | LOOP [fetch-subunit-output : Generate subunit file]
2025-10-13 00:25:42.789770 | 
2025-10-13 00:25:42.790060 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-10-13 00:25:42.817123 | controller | skipping: Conditional result was False
2025-10-13 00:25:42.829610 | 
2025-10-13 00:25:42.829709 | TASK [fetch-subunit-output : Remove the temporary file]
2025-10-13 00:25:42.856272 | controller | skipping: Conditional result was False
2025-10-13 00:25:42.872412 | 
2025-10-13 00:25:42.872555 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-10-13 00:25:42.899395 | controller | skipping: Conditional result was False
2025-10-13 00:25:42.938412 | 
2025-10-13 00:25:42.938558 | PLAY RECAP
2025-10-13 00:25:42.938629 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-10-13 00:25:42.938665 | 
2025-10-13 00:25:43.091428 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-10-13 00:25:43.092490 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-10-13 00:25:43.753003 | 
2025-10-13 00:25:43.753257 | PLAY [all:!appliance*]
2025-10-13 00:25:43.775437 | 
2025-10-13 00:25:43.775547 | TASK [unregister the node]
2025-10-13 00:25:44.133258 | controller | skipping: Conditional result was False
2025-10-13 00:25:44.145923 | 
2025-10-13 00:25:44.146117 | TASK [include_role : fetch-output]
2025-10-13 00:25:44.196734 | controller | ok
2025-10-13 00:25:44.228451 | 
2025-10-13 00:25:44.228669 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-13 00:25:44.307369 | controller | skipping: Conditional result was False
2025-10-13 00:25:44.315642 | 
2025-10-13 00:25:44.315780 | TASK [fetch-output : Set log path for single node]
2025-10-13 00:25:44.358836 | controller | ok
2025-10-13 00:25:44.365274 | 
2025-10-13 00:25:44.365395 | LOOP [fetch-output : Ensure local output dirs]
2025-10-13 00:25:44.849666 | controller -> localhost | ok: "/var/lib/zuul/builds/f68b409ea2404d5e8ba6df27d590b979/work/logs"
2025-10-13 00:25:45.128384 | controller -> localhost | changed: "/var/lib/zuul/builds/f68b409ea2404d5e8ba6df27d590b979/work/artifacts"
2025-10-13 00:25:45.394411 | controller -> localhost | changed: "/var/lib/zuul/builds/f68b409ea2404d5e8ba6df27d590b979/work/docs"
2025-10-13 00:25:45.408780 | 
2025-10-13 00:25:45.408977 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-13 00:25:47.754665 | controller | changed:
2025-10-13 00:25:47.755309 | controller | .d..t...... ./
2025-10-13 00:25:47.755392 | controller | cd+++++++++ controller/
2025-10-13 00:25:47.755468 | controller | changed: All items complete
2025-10-13 00:25:47.755515 | 
2025-10-13 00:25:49.876884 | controller | changed: .d..t...... ./
2025-10-13 00:25:51.942613 | controller | changed: .d..t...... ./
2025-10-13 00:25:51.964180 | 
2025-10-13 00:25:51.964393 | TASK [include_role : fetch-output-openshift]
2025-10-13 00:25:51.991988 | controller | skipping: Conditional result was False
2025-10-13 00:25:52.005945 | 
2025-10-13 00:25:52.006174 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-13 00:25:52.067331 | controller | skipping: Conditional result was False
2025-10-13 00:25:52.080304 | controller | skipping: Conditional result was False
2025-10-13 00:25:52.137342 | 
2025-10-13 00:25:52.137533 | PLAY [localhost]
2025-10-13 00:25:52.163588 | 
2025-10-13 00:25:52.163784 | TASK [Run Zuul manifest role]
2025-10-13 00:25:52.185862 | localhost | ok
2025-10-13 00:25:52.201829 | 
2025-10-13 00:25:52.201953 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-13 00:25:52.682330 | localhost | changed
2025-10-13 00:25:52.691627 | 
2025-10-13 00:25:52.691763 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-13 00:25:52.721809 | localhost | ok
2025-10-13 00:25:52.731208 | 
2025-10-13 00:25:52.731315 | TASK [Set zuul-log-path fact]
2025-10-13 00:25:52.794794 | localhost | ok
2025-10-13 00:25:52.808367 | 
2025-10-13 00:25:52.808478 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-13 00:25:52.849289 | localhost | ok
2025-10-13 00:25:52.858600 | 
2025-10-13 00:25:52.858660 | LOOP [Run upload-logs-swift role]
2025-10-13 00:25:52.884164 | localhost | Output suppressed because no_log was given
2025-10-13 00:25:52.918833 | 
2025-10-13 00:25:52.918936 | TASK [Set zuul-log-path fact]
2025-10-13 00:25:52.954641 | localhost | skipping: Conditional result was False
2025-10-13 00:25:52.962286 | 
2025-10-13 00:25:52.962379 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-10-13 00:25:53.478877 | localhost -> localhost | ok: Runtime: 0:00:00.009874
2025-10-13 00:25:53.485177 | 
2025-10-13 00:25:53.485268 | TASK [upload-logs-swift : Upload logs to swift]