2026-01-28 00:06:47.136239 | Job console starting...
2026-01-28 00:06:47.149961 | Updating repositories
2026-01-28 00:06:47.246775 | Preparing job workspace
2026-01-28 00:06:51.007646 | Running Ansible setup...
2026-01-28 00:06:58.494178 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-28 00:06:59.072378 | 
2026-01-28 00:06:59.072496 | PLAY [localhost]
2026-01-28 00:06:59.092392 | 
2026-01-28 00:06:59.092468 | TASK [Gathering Facts]
2026-01-28 00:07:00.052231 | localhost | ok
2026-01-28 00:07:00.068507 | 
2026-01-28 00:07:00.068643 | TASK [Setup log path fact]
2026-01-28 00:07:00.086360 | localhost | ok
2026-01-28 00:07:00.099243 | 
2026-01-28 00:07:00.099334 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-28 00:07:00.126884 | localhost | ok
2026-01-28 00:07:00.134702 | 
2026-01-28 00:07:00.134772 | TASK [emit-job-header : Print job information]
2026-01-28 00:07:00.162164 | # Job Information
2026-01-28 00:07:00.162301 | Ansible Version: 2.15.12
2026-01-28 00:07:00.162332 | Job: ansible-test-sanity-docker-stable-2.10
2026-01-28 00:07:00.162352 | Pipeline: periodic
2026-01-28 00:07:00.162371 | Executor: ze02.softwarefactory-project.io
2026-01-28 00:07:00.162389 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-01-28 00:07:00.162409 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/cab/ansible/cab1997e7b054be8a352eba162d34430/
2026-01-28 00:07:00.162428 | Event ID: 8587ee331e794653bf2b53926b884eec
2026-01-28 00:07:00.166058 | 
2026-01-28 00:07:00.166120 | LOOP [emit-job-header : Print node information]
2026-01-28 00:07:00.263026 | localhost | ok:
2026-01-28 00:07:00.263214 | localhost | # Node Information
2026-01-28 00:07:00.263308 | localhost | Inventory Hostname: controller
2026-01-28 00:07:00.263340 | localhost | Hostname: np0005598301
2026-01-28 00:07:00.263361 | localhost | Username: zuul
2026-01-28 00:07:00.263386 | localhost | Distro: Fedora 37
2026-01-28 00:07:00.263405 | localhost | Provider: ansible-vexxhost-ams1
2026-01-28 00:07:00.263422 | localhost | Region: ams1
2026-01-28 00:07:00.263439 | localhost | Label: ansible-fedora-37-1vcpu
2026-01-28 00:07:00.263455 | localhost | Product Name: OpenStack Nova
2026-01-28 00:07:00.263471 | localhost | Interface IP: 38.129.16.198
2026-01-28 00:07:00.278808 | 
2026-01-28 00:07:00.278983 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-28 00:07:00.636936 | localhost -> localhost | changed
2026-01-28 00:07:00.642418 | 
2026-01-28 00:07:00.642484 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-28 00:07:01.466891 | localhost -> localhost | changed
2026-01-28 00:07:01.491338 | 
2026-01-28 00:07:01.491420 | PLAY [all:!appliance*]
2026-01-28 00:07:01.508026 | 
2026-01-28 00:07:01.508121 | TASK [include_role : start-zuul-console]
2026-01-28 00:07:01.527409 | controller | ok
2026-01-28 00:07:01.541825 | 
2026-01-28 00:07:01.541920 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-01-28 00:07:02.568958 | controller | ok
2026-01-28 00:07:02.591709 | 
2026-01-28 00:07:02.591823 | TASK [use-our-mirror : Retrieve the IP address]
2026-01-28 00:07:04.920244 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-01-28 00:07:04.928060 | 
2026-01-28 00:07:04.928180 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-01-28 00:07:05.325386 | controller | skipping: Conditional result was False
2026-01-28 00:07:05.331866 | 
2026-01-28 00:07:05.331955 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-01-28 00:07:05.348305 | controller | skipping: Conditional result was False
2026-01-28 00:07:05.354553 | 
2026-01-28 00:07:05.354624 | TASK [use-our-mirror : Create the podman configuration directory]
2026-01-28 00:07:05.378453 | controller | skipping: Conditional result was False
2026-01-28 00:07:05.385427 | 
2026-01-28 00:07:05.385518 | TASK [use-our-mirror : Copy the podman configuration]
2026-01-28 00:07:05.401504 | controller | skipping: Conditional result was False
2026-01-28 00:07:05.416646 | 
2026-01-28 00:07:05.419811 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-01-28 00:07:05.465206 | controller | skipping: Conditional result was False
2026-01-28 00:07:05.471921 | 
2026-01-28 00:07:05.472021 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-01-28 00:07:05.496632 | controller | skipping: Conditional result was False
2026-01-28 00:07:05.512638 | 
2026-01-28 00:07:05.512793 | TASK [Disable Fedora Modular]
2026-01-28 00:07:06.601711 | controller | changed
2026-01-28 00:07:06.607530 | 
2026-01-28 00:07:06.607591 | TASK [Enable EPEL]
2026-01-28 00:07:06.627652 | controller | skipping: Conditional result was False
2026-01-28 00:07:06.633739 | 
2026-01-28 00:07:06.633805 | TASK [Register the RHEL node]
2026-01-28 00:07:07.036611 | 
2026-01-28 00:07:07.036824 | TASK [Show the subscription-manager status]
2026-01-28 00:07:07.433768 | controller | skipping: Conditional result was False
2026-01-28 00:07:07.440711 | 
2026-01-28 00:07:07.440782 | TASK [Enable EPEL on RHEL]
2026-01-28 00:07:07.833499 | controller | skipping: Conditional result was False
2026-01-28 00:07:07.842488 | 
2026-01-28 00:07:07.842589 | TASK [Install git and tox]
2026-01-28 00:09:17.686676 | controller | changed
2026-01-28 00:09:17.693466 | 
2026-01-28 00:09:17.693585 | TASK [include_role : prepare-workspace]
2026-01-28 00:09:17.725533 | controller | ok
2026-01-28 00:09:17.755503 | 
2026-01-28 00:09:17.755634 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-01-28 00:09:18.616299 | controller | ok
2026-01-28 00:09:18.631150 | 
2026-01-28 00:09:18.631331 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-01-28 00:09:35.038722 | controller | Output suppressed because no_log was given
2026-01-28 00:09:35.085364 | 
2026-01-28 00:09:35.085487 | TASK [include_role : prepare-workspace-openshift]
2026-01-28 00:09:35.111738 | controller | skipping: Conditional result was False
2026-01-28 00:09:35.133932 | 
2026-01-28 00:09:35.134042 | PLAY [all:!appliance]
2026-01-28 00:09:35.150888 | 
2026-01-28 00:09:35.151029 | TASK [Run add-build-sshkey role (RSA)]
2026-01-28 00:09:35.183239 | controller | ok
2026-01-28 00:09:35.198652 | 
2026-01-28 00:09:35.198744 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-28 00:09:35.449486 | controller -> localhost | ok
2026-01-28 00:09:35.461280 | 
2026-01-28 00:09:35.461408 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-28 00:09:35.486046 | controller | ok
2026-01-28 00:09:35.507981 | controller | included: /var/lib/zuul/builds/cab1997e7b054be8a352eba162d34430/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-28 00:09:35.516999 | 
2026-01-28 00:09:35.517106 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-28 00:09:35.940238 | controller -> localhost | Generating public/private rsa key pair.
2026-01-28 00:09:35.940543 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/cab1997e7b054be8a352eba162d34430/work/cab1997e7b054be8a352eba162d34430_id_rsa.
2026-01-28 00:09:35.940606 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/cab1997e7b054be8a352eba162d34430/work/cab1997e7b054be8a352eba162d34430_id_rsa.pub.
2026-01-28 00:09:35.940653 | controller -> localhost | The key fingerprint is:
2026-01-28 00:09:35.940739 | controller -> localhost | SHA256:RX9yaoH4veAj3upVXBNKm7QLfISaUQGfSVrDQZ8hTvw zuul-build-sshkey
2026-01-28 00:09:35.940786 | controller -> localhost | The key's randomart image is:
2026-01-28 00:09:35.940828 | controller -> localhost | +---[RSA 2048]----+
2026-01-28 00:09:35.940894 | controller -> localhost | |        .*%+= .  |
2026-01-28 00:09:35.940938 | controller -> localhost | |        .@+% B . |
2026-01-28 00:09:35.940978 | controller -> localhost | |        o+X.% =  |
2026-01-28 00:09:35.941018 | controller -> localhost | |        oo =EO . |
2026-01-28 00:09:35.941055 | controller -> localhost | |        S o B    |
2026-01-28 00:09:35.941113 | controller -> localhost | |         . + .   |
2026-01-28 00:09:35.941158 | controller -> localhost | |        . + .    |
2026-01-28 00:09:35.941201 | controller -> localhost | |       . + .     |
2026-01-28 00:09:35.941240 | controller -> localhost | |       .+..      |
2026-01-28 00:09:35.941283 | controller -> localhost | +----[SHA256]-----+
2026-01-28 00:09:35.941376 | controller -> localhost | ok: Runtime: 0:00:00.063867
2026-01-28 00:09:35.957361 | 
2026-01-28 00:09:35.957576 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-28 00:09:36.008134 | controller | ok
2026-01-28 00:09:36.029220 | controller | included: /var/lib/zuul/builds/cab1997e7b054be8a352eba162d34430/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-28 00:09:36.043866 | 
2026-01-28 00:09:36.044045 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-28 00:09:36.079991 | controller | skipping: Conditional result was False
2026-01-28 00:09:36.089320 | 
2026-01-28 00:09:36.089475 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-28 00:09:37.179621 | controller | changed
2026-01-28 00:09:37.192072 | 
2026-01-28 00:09:37.192209 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-28 00:09:37.852071 | controller | ok
2026-01-28 00:09:37.859947 | 
2026-01-28 00:09:37.860028 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-28 00:09:41.088280 | controller | changed
2026-01-28 00:09:41.103918 | 
2026-01-28 00:09:41.104125 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-28 00:09:44.144280 | controller | changed
2026-01-28 00:09:44.153342 | 
2026-01-28 00:09:44.153441 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-28 00:09:44.179478 | controller | skipping: Conditional result was False
2026-01-28 00:09:44.190026 | 
2026-01-28 00:09:44.190140 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-28 00:09:44.622821 | controller -> localhost | changed
2026-01-28 00:09:44.634307 | 
2026-01-28 00:09:44.634377 | TASK [add-build-sshkey : Add back temp key]
2026-01-28 00:09:44.975818 | controller -> localhost | Identity added: /var/lib/zuul/builds/cab1997e7b054be8a352eba162d34430/work/cab1997e7b054be8a352eba162d34430_id_rsa (zuul-build-sshkey)
2026-01-28 00:09:44.976265 | controller -> localhost | ok: Runtime: 0:00:00.014826
2026-01-28 00:09:44.991736 | 
2026-01-28 00:09:44.991871 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-28 00:09:45.929853 | controller | ok
2026-01-28 00:09:45.939772 | 
2026-01-28 00:09:45.939912 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-28 00:09:45.966441 | controller | skipping: Conditional result was False
2026-01-28 00:09:45.987150 | 
2026-01-28 00:09:45.987304 | TASK [Run add-build-sshkey role (ECDSA)]
2026-01-28 00:09:46.015074 | controller | ok
2026-01-28 00:09:46.046716 | 
2026-01-28 00:09:46.046859 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-28 00:09:46.318597 | controller -> localhost | ok
2026-01-28 00:09:46.325872 | 
2026-01-28 00:09:46.325966 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-28 00:09:46.356184 | controller | ok
2026-01-28 00:09:46.367784 | controller | included: /var/lib/zuul/builds/cab1997e7b054be8a352eba162d34430/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-28 00:09:46.375355 | 
2026-01-28 00:09:46.375444 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-28 00:09:46.696772 | controller -> localhost | Generating public/private ecdsa key pair.
2026-01-28 00:09:46.697174 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/cab1997e7b054be8a352eba162d34430/work/cab1997e7b054be8a352eba162d34430_id_ecdsa.
2026-01-28 00:09:46.697242 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/cab1997e7b054be8a352eba162d34430/work/cab1997e7b054be8a352eba162d34430_id_ecdsa.pub.
2026-01-28 00:09:46.697303 | controller -> localhost | The key fingerprint is:
2026-01-28 00:09:46.697348 | controller -> localhost | SHA256:2SnGXelNGYGxvlQRWEbT40Avuj1MU8teilqeFURP5Zc zuul-build-sshkey
2026-01-28 00:09:46.697394 | controller -> localhost | The key's randomart image is:
2026-01-28 00:09:46.697435 | controller -> localhost | +---[ECDSA 521]---+
2026-01-28 00:09:46.697475 | controller -> localhost | |            oB@++|
2026-01-28 00:09:46.697513 | controller -> localhost | |            o*.Xo|
2026-01-28 00:09:46.697551 | controller -> localhost | |            +.BE=|
2026-01-28 00:09:46.697589 | controller -> localhost | |       . + =.=+.o|
2026-01-28 00:09:46.697627 | controller -> localhost | |        S +.+ooo.|
2026-01-28 00:09:46.697696 | controller -> localhost | |       . . .=ooo.|
2026-01-28 00:09:46.697744 | controller -> localhost | |           .++o. |
2026-01-28 00:09:46.697784 | controller -> localhost | |           + o.  |
2026-01-28 00:09:46.697822 | controller -> localhost | |          . o    |
2026-01-28 00:09:46.697860 | controller -> localhost | +----[SHA256]-----+
2026-01-28 00:09:46.697963 | controller -> localhost | ok: Runtime: 0:00:00.008148
2026-01-28 00:09:46.715557 | 
2026-01-28 00:09:46.716227 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-28 00:09:46.747728 | controller | ok
2026-01-28 00:09:46.765104 | controller | included: /var/lib/zuul/builds/cab1997e7b054be8a352eba162d34430/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-28 00:09:46.780008 | 
2026-01-28 00:09:46.780135 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-28 00:09:46.795781 | controller | skipping: Conditional result was False
2026-01-28 00:09:46.807902 | 
2026-01-28 00:09:46.808035 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-28 00:09:47.696365 | controller | changed
2026-01-28 00:09:47.709657 | 
2026-01-28 00:09:47.709980 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-28 00:09:48.356623 | controller | ok
2026-01-28 00:09:48.370758 | 
2026-01-28 00:09:48.370902 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-28 00:09:51.345263 | controller | changed
2026-01-28 00:09:51.358262 | 
2026-01-28 00:09:51.358403 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-28 00:09:54.342557 | controller | changed
2026-01-28 00:09:54.356883 | 
2026-01-28 00:09:54.357015 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-28 00:09:54.385914 | controller | skipping: Conditional result was False
2026-01-28 00:09:54.402824 | 
2026-01-28 00:09:54.402985 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-28 00:09:54.661138 | controller -> localhost | changed
2026-01-28 00:09:54.674361 | 
2026-01-28 00:09:54.674443 | TASK [add-build-sshkey : Add back temp key]
2026-01-28 00:09:54.984951 | controller -> localhost | Identity added: /var/lib/zuul/builds/cab1997e7b054be8a352eba162d34430/work/cab1997e7b054be8a352eba162d34430_id_ecdsa (zuul-build-sshkey)
2026-01-28 00:09:54.985202 | controller -> localhost | ok: Runtime: 0:00:00.012610
2026-01-28 00:09:54.992137 | 
2026-01-28 00:09:54.992197 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-28 00:09:55.679698 | controller | ok
2026-01-28 00:09:55.685221 | 
2026-01-28 00:09:55.685289 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-28 00:09:55.719496 | controller | skipping: Conditional result was False
2026-01-28 00:09:55.731592 | 
2026-01-28 00:09:55.731687 | TASK [include_role : remove-zuul-sshkey]
2026-01-28 00:09:55.756951 | controller | skipping: Conditional result was False
2026-01-28 00:09:55.766656 | 
2026-01-28 00:09:55.766797 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-28 00:09:56.445274 | controller | ok: "logs"
2026-01-28 00:09:56.445869 | controller | ok: All items complete
2026-01-28 00:09:56.445967 | 
2026-01-28 00:09:57.051759 | controller | ok: "artifacts"
2026-01-28 00:09:57.661748 | controller | ok: "docs"
2026-01-28 00:09:57.691150 | 
2026-01-28 00:09:57.691456 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-28 00:09:58.381780 | controller | changed: "logs"
2026-01-28 00:09:59.010306 | controller | changed: "artifacts"
2026-01-28 00:09:59.621488 | controller | changed: "docs"
2026-01-28 00:09:59.682636 | 
2026-01-28 00:09:59.682770 | PLAY RECAP
2026-01-28 00:09:59.682826 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-01-28 00:09:59.682861 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-28 00:09:59.682887 | 
2026-01-28 00:09:59.801634 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-28 00:09:59.802502 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-28 00:10:00.385109 | 
2026-01-28 00:10:00.385215 | PLAY [all]
2026-01-28 00:10:00.407786 | 
2026-01-28 00:10:00.407870 | TASK [Install binary dependencies]
2026-01-28 00:10:00.484321 | controller | ok
2026-01-28 00:10:00.514116 | 
2026-01-28 00:10:00.514227 | TASK [bindep : Include find tasks]
2026-01-28 00:10:00.557834 | controller | ok
2026-01-28 00:10:00.568454 | controller | included: /var/lib/zuul/builds/cab1997e7b054be8a352eba162d34430/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-01-28 00:10:00.577143 | 
2026-01-28 00:10:00.577230 | TASK [bindep : Look for bindep.txt]
2026-01-28 00:10:01.568237 | controller | ok
2026-01-28 00:10:01.583493 | 
2026-01-28 00:10:01.583689 | TASK [bindep : Define bindep_file fact]
2026-01-28 00:10:01.601485 | controller | skipping: Conditional result was False
2026-01-28 00:10:01.616080 | 
2026-01-28 00:10:01.616251 | TASK [bindep : Look for other-requirements.txt]
2026-01-28 00:10:02.242723 | controller | ok
2026-01-28 00:10:02.249184 | 
2026-01-28 00:10:02.249256 | TASK [bindep : Define bindep_file fact]
2026-01-28 00:10:02.284474 | controller | skipping: Conditional result was False
2026-01-28 00:10:02.291423 | 
2026-01-28 00:10:02.291498 | TASK [bindep : Look for bindep fallback file]
2026-01-28 00:10:02.316761 | controller | skipping: Conditional result was False
2026-01-28 00:10:02.325614 | 
2026-01-28 00:10:02.325739 | TASK [bindep : Define bindep_file fact]
2026-01-28 00:10:02.351308 | controller | skipping: Conditional result was False
2026-01-28 00:10:02.362044 | 
2026-01-28 00:10:02.362159 | TASK [bindep : Include bindep tasks]
2026-01-28 00:10:02.387321 | controller | skipping: Conditional result was False
2026-01-28 00:10:02.393877 | 
2026-01-28 00:10:02.393954 | TASK [bindep : Include install tasks]
2026-01-28 00:10:02.418037 | controller | skipping: Conditional result was False
2026-01-28 00:10:02.424100 | 
2026-01-28 00:10:02.424168 | LOOP [bindep : Include package tasks]
2026-01-28 00:10:02.482642 | 
2026-01-28 00:10:02.482816 | TASK [Run test-setup role]
2026-01-28 00:10:02.502563 | controller | ok
2026-01-28 00:10:02.549227 | 
2026-01-28 00:10:02.549325 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-01-28 00:10:03.236388 | controller | ok
2026-01-28 00:10:03.245190 | 
2026-01-28 00:10:03.245282 | TASK [test-setup : Run tools/test-setup.sh]
2026-01-28 00:10:03.609427 | controller | skipping: Conditional result was False
2026-01-28 00:10:03.635208 | 
2026-01-28 00:10:03.635278 | PLAY RECAP
2026-01-28 00:10:03.635324 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-01-28 00:10:03.635349 | 
2026-01-28 00:10:03.729222 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-28 00:10:03.730979 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-01-28 00:10:04.385979 | 
2026-01-28 00:10:04.386130 | PLAY [controller]
2026-01-28 00:10:04.412475 | 
2026-01-28 00:10:04.412578 | TASK [Create the /root directory]
2026-01-28 00:10:05.446774 | controller | ok
2026-01-28 00:10:05.463615 | 
2026-01-28 00:10:05.463803 | TASK [Install glibc-langpack-en]
2026-01-28 00:10:13.546910 | controller | ok: Nothing to do
2026-01-28 00:10:13.554566 | 
2026-01-28 00:10:13.554648 | TASK [Ensure controller directory exists]
2026-01-28 00:10:14.361333 | controller | changed
2026-01-28 00:10:14.367429 | 
2026-01-28 00:10:14.367500 | TASK [Install container runtime]
2026-01-28 00:10:14.453296 | controller | ok
2026-01-28 00:10:14.503322 | 
2026-01-28 00:10:14.503458 | LOOP [ensure-docker : Gather variables for each operating system]
2026-01-28 00:10:14.544288 | controller | ok: "/var/lib/zuul/builds/cab1997e7b054be8a352eba162d34430/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2026-01-28 00:10:14.554555 | 
2026-01-28 00:10:14.554907 | TASK [ensure-docker : Sanity check for distro selection]
2026-01-28 00:10:14.573168 | controller | skipping: Conditional result was False
2026-01-28 00:10:14.580611 | 
2026-01-28 00:10:14.580767 | TASK [ensure-docker : Sanity check for distro install]
2026-01-28 00:10:14.608876 | controller | skipping: Conditional result was False
2026-01-28 00:10:14.616017 | 
2026-01-28 00:10:14.616123 | TASK [ensure-docker : Sanity check for upstream install]
2026-01-28 00:10:14.641455 | controller | skipping: Conditional result was False
2026-01-28 00:10:14.651103 | 
2026-01-28 00:10:14.651225 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2026-01-28 00:10:14.675170 | controller | skipping: Conditional result was False
2026-01-28 00:10:14.684390 | 
2026-01-28 00:10:14.684486 | TASK [ensure-docker : Include tasks for docker install]
2026-01-28 00:10:14.708634 | controller | ok
2026-01-28 00:10:14.720490 | controller | included: /var/lib/zuul/builds/cab1997e7b054be8a352eba162d34430/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2026-01-28 00:10:14.732108 | 
2026-01-28 00:10:14.732211 | TASK [ensure-docker : Not Implemented]
2026-01-28 00:10:14.757527 | controller | skipping: Conditional result was False
2026-01-28 00:10:14.767277 | 
2026-01-28 00:10:14.767360 | TASK [Set up docker mirrors]
2026-01-28 00:10:14.790409 | controller | ok
2026-01-28 00:10:14.825596 | 
2026-01-28 00:10:14.825732 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2026-01-28 00:10:14.850422 | controller | skipping: Conditional result was False
2026-01-28 00:10:14.857332 | 
2026-01-28 00:10:14.857410 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2026-01-28 00:10:14.881430 | controller | skipping: Conditional result was False
2026-01-28 00:10:14.889131 | 
2026-01-28 00:10:14.889200 | TASK [use-docker-mirror : Set docker_mirror fact]
2026-01-28 00:10:14.954339 | controller | skipping: Conditional result was False
2026-01-28 00:10:15.004627 | 
2026-01-28 00:10:15.004789 | TASK [use-docker-mirror : Set up docker mirrors]
2026-01-28 00:10:15.030348 | controller | skipping: Conditional result was False
2026-01-28 00:10:15.055369 | 
2026-01-28 00:10:15.055504 | TASK [Add all repositories]
2026-01-28 00:10:15.103137 | controller | ok
2026-01-28 00:10:15.169075 | 
2026-01-28 00:10:15.169233 | LOOP [ensure-package-repositories : Install repositories]
2026-01-28 00:10:15.194678 | controller | ok: "/var/lib/zuul/builds/cab1997e7b054be8a352eba162d34430/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2026-01-28 00:10:15.194915 | controller | ok: All items complete
2026-01-28 00:10:15.194942 | 
2026-01-28 00:10:15.215452 | controller | included: /var/lib/zuul/builds/cab1997e7b054be8a352eba162d34430/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2026-01-28 00:10:15.221948 | 
2026-01-28 00:10:15.222011 | TASK [ensure-package-repositories : Create GPG key temp directory]
2026-01-28 00:10:16.195936 | controller | changed
2026-01-28 00:10:16.207082 | 
2026-01-28 00:10:16.207199 | LOOP [ensure-package-repositories : Upload GPG keys]
2026-01-28 00:10:19.359472 | controller | changed:
2026-01-28 00:10:19.359701 | controller | {
2026-01-28 00:10:19.359739 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2026-01-28 00:10:19.359761 | controller | }
2026-01-28 00:10:19.377287 | 
2026-01-28 00:10:19.377421 | LOOP [ensure-package-repositories : Import GPG keys]
2026-01-28 00:10:20.644731 | controller | changed:
2026-01-28 00:10:20.645084 | controller | {
2026-01-28 00:10:20.645146 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2026-01-28 00:10:20.645196 | controller | }
2026-01-28 00:10:20.645261 | controller | changed: All items complete
2026-01-28 00:10:20.645306 | 
2026-01-28 00:10:20.665070 | 
2026-01-28 00:10:20.665276 | TASK [ensure-package-repositories : Remove GPG temp directory]
2026-01-28 00:10:21.419975 | controller | changed
2026-01-28 00:10:21.433957 | 
2026-01-28 00:10:21.434114 | LOOP [ensure-package-repositories : Add repositories]
2026-01-28 00:10:22.249289 | controller | changed:
2026-01-28 00:10:22.249633 | controller | {
2026-01-28 00:10:22.249731 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2026-01-28 00:10:22.249792 | controller |   "description": "Docker CE Stable - $basearch",
2026-01-28 00:10:22.249853 | controller |   "gpgcheck": true,
2026-01-28 00:10:22.249898 | controller |   "name": "docker-ce-stable"
2026-01-28 00:10:22.249944 | controller | }
2026-01-28 00:10:22.290928 | 
2026-01-28 00:10:22.291178 | TASK [ensure-docker : Install pre-reqs]
2026-01-28 00:10:27.018259 | controller | ok: Nothing to do
2026-01-28 00:10:27.026417 | 
2026-01-28 00:10:27.026514 | TASK [ensure-docker : Remove packages]
2026-01-28 00:10:30.117579 | controller | ok: Nothing to do
2026-01-28 00:10:30.131792 | 
2026-01-28 00:10:30.131953 | TASK [ensure-docker : Install upstream docker using package]
2026-01-28 00:11:54.091848 | controller | changed
2026-01-28 00:11:54.133979 | 
2026-01-28 00:11:54.134118 | TASK [ensure-docker : Setup docker]
2026-01-28 00:11:54.155845 | controller | ok
2026-01-28 00:11:54.166256 | controller | included: /var/lib/zuul/builds/cab1997e7b054be8a352eba162d34430/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2026-01-28 00:11:54.177562 | 
2026-01-28 00:11:54.177630 | TASK [ensure-docker : Ensure "docker" group exists]
2026-01-28 00:11:55.193344 | controller | ok
2026-01-28 00:11:55.206718 | 
2026-01-28 00:11:55.206861 | TASK [ensure-docker : Add user to docker group]
2026-01-28 00:11:56.386559 | controller | changed
2026-01-28 00:11:56.394931 | 
2026-01-28 00:11:56.395159 | TASK [Update docker daemon proxy configuration]
2026-01-28 00:11:56.431418 | controller | skipping: Conditional result was False
2026-01-28 00:11:56.439078 | 
2026-01-28 00:11:56.439177 | TASK [Lower default MTU]
2026-01-28 00:11:56.464322 | controller | skipping: Conditional result was False
2026-01-28 00:11:56.472258 | 
2026-01-28 00:11:56.472351 | TASK [ensure-docker : Get the status of the docket.socket unit]
2026-01-28 00:11:57.221526 | controller | inactive
2026-01-28 00:11:57.832333 | controller | ok: Runtime: 0:00:00.020232
2026-01-28 00:11:57.841411 | 
2026-01-28 00:11:57.841514 | TASK [ensure-docker : Get the status of the docket unit]
2026-01-28 00:11:58.595387 | controller | inactive
2026-01-28 00:11:59.212231 | controller | ok: Runtime: 0:00:00.020008
2026-01-28 00:11:59.227777 | 
2026-01-28 00:11:59.227921 | TASK [ensure-docker : Restart docker]
2026-01-28 00:11:59.290100 | Trigger a Docker restart
2026-01-28 00:11:59.303377 | 
2026-01-28 00:11:59.303519 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2026-01-28 00:11:59.351216 | 
2026-01-28 00:11:59.351338 | TASK [ensure-docker : Install docker-compose]
2026-01-28 00:11:59.376853 | controller | skipping: Conditional result was False
2026-01-28 00:11:59.386146 | 
2026-01-28 00:11:59.386241 | TASK [ensure-docker : Flush handlers before role exit]
2026-01-28 00:11:59.401004 | 
2026-01-28 00:11:59.401091 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2026-01-28 00:12:02.828731 | controller | changed
2026-01-28 00:12:02.847254 | 
2026-01-28 00:12:02.847406 | TASK [ensure-docker : Assure docker service is running]
2026-01-28 00:12:06.912286 | controller | changed
2026-01-28 00:12:06.929751 | 
2026-01-28 00:12:06.929883 | TASK [ensure-docker : Assure docker.socket service is running]
2026-01-28 00:12:08.432888 | controller | ok
2026-01-28 00:12:08.452530 | 
2026-01-28 00:12:08.452713 | TASK [ensure-docker : Correct group ownership on docker sock]
2026-01-28 00:12:09.200236 | controller | ok
2026-01-28 00:12:09.214446 | 
2026-01-28 00:12:09.214592 | TASK [ensure-docker : Validate ability to talk with docker]
2026-01-28 00:12:09.858142 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2026-01-28 00:12:10.101651 | controller | ok: Runtime: 0:00:00.050196
2026-01-28 00:12:10.109066 | 
2026-01-28 00:12:10.109160 | TASK [ensure-docker : Get version details]
2026-01-28 00:12:10.801804 | controller | Client: Docker Engine - Community
2026-01-28 00:12:10.801909 | controller |  Version:           24.0.7
2026-01-28 00:12:10.801919 | controller |  API version:       1.43
2026-01-28 00:12:10.801927 | controller |  Go version:        go1.20.10
2026-01-28 00:12:10.801947 | controller |  Git commit:        afdd53b
2026-01-28 00:12:10.801955 | controller |  Built:             Thu Oct 26 09:09:39 2023
2026-01-28 00:12:10.801963 | controller |  OS/Arch:           linux/amd64
2026-01-28 00:12:10.801972 | controller |  Context:           default
2026-01-28 00:12:10.801981 | controller |
2026-01-28 00:12:10.801988 | controller | Server: Docker Engine - Community
2026-01-28 00:12:10.801994 | controller |  Engine:
2026-01-28 00:12:10.802000 | controller |   Version:          24.0.7
2026-01-28 00:12:10.802007 | controller |   API version:      1.43 (minimum version 1.12)
2026-01-28 00:12:10.802013 | controller |   Go version:       go1.20.10
2026-01-28 00:12:10.802019 | controller |   Git commit:       311b9ff
2026-01-28 00:12:10.802025 | controller |   Built:            Thu Oct 26 09:08:11 2023
2026-01-28 00:12:10.802032 | controller |   OS/Arch:          linux/amd64
2026-01-28 00:12:10.802038 | controller |   Experimental:     false
2026-01-28 00:12:10.802045 | controller |  containerd:
2026-01-28 00:12:10.802051 | controller |   Version:          1.6.25
2026-01-28 00:12:10.802057 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2026-01-28 00:12:10.802063 | controller |  runc:
2026-01-28 00:12:10.802069 | controller |   Version:          1.1.10
2026-01-28 00:12:10.802075 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2026-01-28 00:12:10.802084 | controller |  docker-init:
2026-01-28 00:12:10.802094 | controller |   Version:          0.19.0
2026-01-28 00:12:10.803903 | controller |   GitCommit:        de40ad0
2026-01-28 00:12:10.968814 | controller | ok: Runtime: 0:00:00.054206
2026-01-28 00:12:10.979386 | 
2026-01-28 00:12:10.979498 | TASK [ensure-docker : Dump installed docker details]
2026-01-28 00:12:11.028591 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version:           24.0.7\n API version:       1.43\n Go version:        go1.20.10\n Git commit:        afdd53b\n Built:             Thu Oct 26 09:09:39 2023\n OS/Arch:           linux/amd64\n Context:           default\n\nServer: Docker Engine - Community\n Engine:\n  Version:          24.0.7\n  API version:      1.43 (minimum version 1.12)\n  Go version:       go1.20.10\n  Git commit:       311b9ff\n  Built:            Thu Oct 26 09:08:11 2023\n  OS/Arch:          linux/amd64\n  Experimental:     false\n containerd:\n  Version:          1.6.25\n  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n  Version:          1.1.10\n  GitCommit:        v1.1.10-0-g18a0cb0\n docker-init:\n  Version:          0.19.0\n  GitCommit:        de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2026-01-28 00:12:10.750587', 'end': '2026-01-28 00:12:10.804793', 'delta': '0:00:00.054206', 'msg': '', 'zuul_log_id': 'fa163efc-24cc-98bc-3f28-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version:           24.0.7', ' API version:       1.43', ' Go version:        go1.20.10', ' Git commit:        afdd53b', ' Built:             Thu Oct 26 09:09:39 2023', ' OS/Arch:           linux/amd64', ' Context:           default', '', 'Server: Docker Engine - Community', ' Engine:', '  Version:          24.0.7', '  API version:      1.43 (minimum version 1.12)', '  Go version:       go1.20.10', '  Git commit:       311b9ff', '  Built:            Thu Oct 26 09:08:11 2023', '  OS/Arch:          linux/amd64', '  Experimental:     false', ' containerd:', '  Version:          1.6.25', '  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', '  Version:          1.1.10', '  GitCommit:        v1.1.10-0-g18a0cb0', ' docker-init:', '  Version:          0.19.0', '  GitCommit:        de40ad0'], 'stderr_lines': [], 'failed': False}
2026-01-28 00:12:11.034434 | 
2026-01-28 00:12:11.034505 | TASK [Use buildset registry]
2026-01-28 00:12:11.048576 | controller | skipping: Conditional result was False
2026-01-28 00:12:11.061178 | 
2026-01-28 00:12:11.061279 | TASK [Ensure python3.8 is present]
2026-01-28 00:12:11.076321 | controller | skipping: Conditional result was False
2026-01-28 00:12:11.083408 | 
2026-01-28 00:12:11.083528 | TASK [Run our-ensure-python role (Fedora only for now)]
2026-01-28 00:12:11.104082 | controller | ok
2026-01-28 00:12:11.125159 | 
2026-01-28 00:12:11.125275 | TASK [our-ensure-python : Install the right Python version (rpm)]
2026-01-28 00:12:18.281484 | controller | ok: Nothing to do
2026-01-28 00:12:18.287461 | 
2026-01-28 00:12:18.287535 | TASK [our-ensure-python : Also install python3-devel]
2026-01-28 00:12:34.613084 | controller | changed
2026-01-28 00:12:34.641887 | 
2026-01-28 00:12:34.642026 | TASK [Run ensure-virtualenv role]
2026-01-28 00:12:34.670603 | controller | ok
2026-01-28 00:12:34.707791 | 
2026-01-28 00:12:34.707946 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2026-01-28 00:12:35.488748 | controller | /usr/bin/virtualenv
2026-01-28 00:12:36.077202 | controller | ok: Runtime: 0:00:00.006256
2026-01-28 00:12:36.093137 | 
2026-01-28 00:12:36.093371 | LOOP [ensure-virtualenv : Install virtualenv package]
2026-01-28 00:12:36.132522 | controller | skipping: Conditional result was False
2026-01-28 00:12:36.133059 | controller | changed: All items complete
2026-01-28 00:12:36.133127 | 
2026-01-28 00:12:36.165326 | 
2026-01-28 00:12:36.165549 | TASK [Find the full path of the Python interpreter]
2026-01-28 00:12:36.948323 | controller | /usr/bin/python3.8
2026-01-28 00:12:37.546793 | controller | ok
2026-01-28 00:12:37.561297 | 
2026-01-28 00:12:37.561475 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2026-01-28 00:12:40.195902 | controller | created virtual environment CPython3.8.18.final.0-64 in 1259ms
2026-01-28 00:12:40.321781 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2026-01-28 00:12:40.321846 | controller |   seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv)
2026-01-28 00:12:40.321864 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2026-01-28 00:12:40.321888 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2026-01-28 00:12:40.947043 | controller | changed
2026-01-28 00:12:40.959845 | 
2026-01-28 00:12:40.960008 | TASK [Set selinux package]
2026-01-28 00:12:41.002362 | controller | ok
2026-01-28 00:12:41.018896 | 
2026-01-28 00:12:41.019040 | TASK [Set selinux package (Fedora)]
2026-01-28 00:12:41.074771 | controller | ok
2026-01-28 00:12:41.083200 | 
2026-01-28 00:12:41.083308 | TASK [Install selinux into virtualenv]
2026-01-28 00:12:47.909976 | controller | Collecting selinux-please-lie-to-me
2026-01-28 00:12:47.973444 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2026-01-28 00:12:48.615331 | controller | Collecting setuptools<50.0.0
2026-01-28 00:12:48.622435 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2026-01-28 00:12:48.671556 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 19.7 MB/s eta 0:00:00
2026-01-28 00:12:48.807287 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2026-01-28 00:12:48.807542 | controller |   Attempting uninstall: setuptools
2026-01-28 00:12:48.808854 | controller |     Found existing installation: setuptools 62.6.0
2026-01-28 00:12:48.950572 | controller |     Uninstalling setuptools-62.6.0:
2026-01-28 00:12:48.970958 | controller |       Successfully uninstalled setuptools-62.6.0
2026-01-28 00:12:49.849372 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2026-01-28 00:12:50.086445 | controller |
2026-01-28 00:12:50.559114 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-01-28 00:12:50.559184 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-01-28 00:12:50.967648 | controller | ok: Runtime: 0:00:08.370008
2026-01-28 00:12:50.981996 | 
2026-01-28 00:12:50.982167 | TASK [Install pytest-forked into virtualenv]
2026-01-28 00:12:52.613399 | controller | Collecting pytest-forked
2026-01-28 00:12:52.675973 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2026-01-28 00:12:52.752225 | controller | Collecting py
2026-01-28 00:12:52.760892 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2026-01-28 00:12:52.804142 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 2.7 MB/s eta 0:00:00
2026-01-28 00:12:53.017279 | controller | Collecting pytest>=3.10
2026-01-28 00:12:53.024898 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2026-01-28 00:12:53.055060 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 12.9 MB/s eta 0:00:00
2026-01-28 00:12:53.116158 | controller | Collecting iniconfig
2026-01-28 00:12:53.121468 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2026-01-28 00:12:53.193510 | controller | Collecting exceptiongroup>=1.0.0rc8
2026-01-28 00:12:53.203012 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2026-01-28 00:12:53.298941 | controller | Collecting tomli>=1
2026-01-28 00:12:53.306481 | controller |   Downloading tomli-2.4.0-py3-none-any.whl (14 kB)
2026-01-28 00:12:53.382727 | controller | Collecting packaging
2026-01-28 00:12:53.391179 | controller |   Downloading packaging-26.0-py3-none-any.whl (74 kB)
2026-01-28 00:12:53.403535 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 7.7 MB/s eta 0:00:00
2026-01-28 00:12:53.453849 | controller | Collecting pluggy<2,>=1.5
2026-01-28 00:12:53.459653 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2026-01-28 00:12:53.554585 | controller | Collecting typing-extensions>=4.6.0
2026-01-28 00:12:53.563430 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2026-01-28 00:12:53.576922 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 5.1 MB/s eta 0:00:00
2026-01-28 00:12:53.685486 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2026-01-28 00:12:54.781247 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.1.0 packaging-26.0 pluggy-1.5.0 py-1.11.0 pytest-8.3.5 pytest-forked-1.6.0 tomli-2.4.0 typing-extensions-4.13.2
2026-01-28 00:12:54.788275 | controller |
2026-01-28 00:12:55.188408 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-01-28 00:12:55.188499 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-01-28 00:12:55.357486 | controller | ok: Runtime: 0:00:03.380977
2026-01-28 00:12:55.365303 | 
2026-01-28 00:12:55.365386 | TASK [Update pip]
2026-01-28 00:12:57.906506 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2026-01-28 00:12:58.442877 | controller | Collecting pip
2026-01-28 00:12:58.586093 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2026-01-28 00:12:58.725909 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 14.5 MB/s eta 0:00:00
2026-01-28 00:12:58.920201 | controller | Installing collected packages: pip
2026-01-28 00:12:58.920409 | controller |   Attempting uninstall: pip
2026-01-28 00:12:58.921468 | controller |     Found existing installation: pip 22.2.2
2026-01-28 00:12:59.482981 | controller |     Uninstalling pip-22.2.2:
2026-01-28 00:12:59.560397 | controller |       Successfully uninstalled pip-22.2.2
2026-01-28 00:13:02.452469 | controller | Successfully installed pip-25.0.1
2026-01-28 00:13:03.259378 | controller | ok: Runtime: 0:00:06.243001
2026-01-28 00:13:03.273224 | 
2026-01-28 00:13:03.273499 | TASK [Ensure pip temp directory exists in /var/tmp]
2026-01-28 00:13:04.509094 | controller | changed
2026-01-28 00:13:04.517741 | 
2026-01-28 00:13:04.517871 | TASK [Install ansible into virtualenv]
2026-01-28 00:13:06.170211 | controller | Processing ./src/github.com/ansible/ansible
2026-01-28 00:13:06.172771 | controller |   Preparing metadata (setup.py): started
2026-01-28 00:13:07.072428 | controller |   Preparing metadata (setup.py): finished with status 'done'
2026-01-28 00:13:07.137007 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2026-01-28 00:13:07.154581 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2026-01-28 00:13:07.247080 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2026-01-28 00:13:07.253893 | controller |   Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.1 kB)
2026-01-28 00:13:07.751773 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2026-01-28 00:13:07.759201 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2026-01-28 00:13:07.782715 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (26.0)
2026-01-28 00:13:08.018031 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2026-01-28 00:13:08.024244 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2026-01-28 00:13:08.038760 | 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)
2026-01-28 00:13:08.165066 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2026-01-28 00:13:08.172322 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2026-01-28 00:13:08.213381 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2026-01-28 00:13:08.220538 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2026-01-28 00:13:08.246584 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2026-01-28 00:13:08.306804 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 88.7 MB/s eta 0:00:00
2026-01-28 00:13:08.312899 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2026-01-28 00:13:08.330763 | controller | Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (806 kB)
2026-01-28 00:13:08.356957 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 26.7 MB/s eta 0:00:00
2026-01-28 00:13:08.364364 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2026-01-28 00:13:08.386469 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2026-01-28 00:13:08.399327 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2026-01-28 00:13:08.433226 | controller | Building wheels for collected packages: ansible-base
2026-01-28 00:13:08.434390 | controller |   Building wheel for ansible-base (setup.py): started
2026-01-28 00:13:10.881090 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2026-01-28 00:13:10.886408 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=07cafa1adccd63ca8ed94358f52d0b65da8803151d07313644f0a08712623aa5
2026-01-28 00:13:10.886759 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-an6ttrvw/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2026-01-28 00:13:10.901836 | controller | Successfully built ansible-base
2026-01-28 00:13:10.965106 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2026-01-28 00:13:14.400583 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-base-2.10.17.post0 cffi-1.17.1 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23
2026-01-28 00:13:14.902063 | controller | ok: Runtime: 0:00:09.204579
2026-01-28 00:13:14.915651 | 
2026-01-28 00:13:14.915838 | TASK [Setup download-artifact-fork role]
2026-01-28 00:13:14.945197 | controller | ok
2026-01-28 00:13:15.005721 | 
2026-01-28 00:13:15.006007 | TASK [download-artifact-fork : Parse inventory response]
2026-01-28 00:13:15.056931 | controller | ok
2026-01-28 00:13:15.070624 | 
2026-01-28 00:13:15.070808 | TASK [download-artifact-fork : Fetch info from Zuul API]
2026-01-28 00:13:15.098090 | controller | skipping: Conditional result was False
2026-01-28 00:13:15.107575 | 
2026-01-28 00:13:15.107743 | TASK [download-artifact-fork : Parse build response]
2026-01-28 00:13:15.133062 | controller | skipping: Conditional result was False
2026-01-28 00:13:15.145136 | 
2026-01-28 00:13:15.145292 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2026-01-28 00:13:16.021745 | controller | changed
2026-01-28 00:13:16.077705 | 
2026-01-28 00:13:16.078199 | LOOP [download-artifact-fork : Download archive by type]
2026-01-28 00:13:16.105346 | controller | skipping: Conditional result was False
2026-01-28 00:13:16.105626 | 
2026-01-28 00:13:18.813014 | controller | changed: OK (20208 bytes)
2026-01-28 00:13:18.839346 | 
2026-01-28 00:13:18.839515 | TASK [deploy-artifacts : define __collections]
2026-01-28 00:13:18.865747 | controller | ok
2026-01-28 00:13:18.875347 | 
2026-01-28 00:13:18.875502 | LOOP [deploy-artifacts : Create list of collections]
2026-01-28 00:13:18.902702 | controller | skipping: Conditional result was False
2026-01-28 00:13:18.914858 | controller | ok:
2026-01-28 00:13:18.914982 | controller | {
2026-01-28 00:13:18.915020 | controller |   "branch": "main",
2026-01-28 00:13:18.915049 | controller |   "job": "build-ansible-collection",
2026-01-28 00:13:18.915076 | controller |   "metadata": {
2026-01-28 00:13:18.915104 | controller |     "type": "ansible_collection",
2026-01-28 00:13:18.915130 | controller |     "version": "5.0.0"
2026-01-28 00:13:18.915156 | controller |   },
2026-01-28 00:13:18.915183 | controller |   "name": "ansible.network",
2026-01-28 00:13:18.915213 | controller |   "project": "ansible-collections/ansible.network",
2026-01-28 00:13:18.915243 | controller |   "url": "https://b9c19f48603fec6ea4cc-534754a89e5f8ba58ca1ff931aba0d6b.ssl.cf1.rackcdn.com/ansible/50900bfc5864430cb00abaf9b8ab76ff/artifacts/ansible-network-5.0.0.tar.gz"
2026-01-28 00:13:18.915283 | controller | }
2026-01-28 00:13:18.932555 | 
2026-01-28 00:13:18.932738 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2026-01-28 00:13:20.544819 | controller | Starting galaxy collection install process
2026-01-28 00:13:20.546520 | controller | Process install dependency map
2026-01-28 00:13:37.562402 | controller | Starting collection install process
2026-01-28 00:13:37.562474 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2026-01-28 00:13:37.579948 | controller | ansible.network (5.0.0) was installed successfully
2026-01-28 00:13:37.580099 | controller | Installing 'ansible.netcommon:8.2.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-01-28 00:13:37.580115 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-netcommon-8.2.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4064jqjzzf04/tmp9_0fcxgy
2026-01-28 00:13:39.481663 | controller | ansible.netcommon (8.2.1) was installed successfully
2026-01-28 00:13:39.481910 | controller | Installing 'ansible.utils:6.0.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-01-28 00:13:39.481928 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-utils-6.0.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4064jqjzzf04/tmp9_0fcxgy
2026-01-28 00:13:41.229436 | controller | ansible.utils (6.0.1) was installed successfully
2026-01-28 00:13:41.229684 | controller | Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2026-01-28 00:13:41.229702 | 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-4064jqjzzf04/tmp9_0fcxgy
2026-01-28 00:13:43.805836 | controller | arista.eos (12.0.0) was installed successfully
2026-01-28 00:13:46.830902 | controller | Installing 'cisco.ios:11.2.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2026-01-28 00:13:46.831985 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-ios-11.2.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4064jqjzzf04/tmp9_0fcxgy
2026-01-28 00:13:46.832013 | controller | cisco.ios (11.2.0) was installed successfully
2026-01-28 00:13:46.832363 | controller | Installing 'cisco.iosxr:12.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2026-01-28 00:13:46.832960 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-iosxr-12.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4064jqjzzf04/tmp9_0fcxgy
2026-01-28 00:13:49.499101 | controller | cisco.iosxr (12.1.1) was installed successfully
2026-01-28 00:13:49.499315 | controller | Installing 'cisco.nxos:11.1.2' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2026-01-28 00:13:49.499331 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.1.2.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4064jqjzzf04/tmp9_0fcxgy
2026-01-28 00:13:53.871672 | controller | cisco.nxos (11.1.2) was installed successfully
2026-01-28 00:13:53.871817 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2026-01-28 00:13:53.872161 | 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-4064jqjzzf04/tmp9_0fcxgy
2026-01-28 00:13:56.407158 | controller | junipernetworks.junos (11.0.0) was installed successfully
2026-01-28 00:13:56.837613 | controller | ok: Runtime: 0:00:36.800304
2026-01-28 00:13:56.854844 | 
2026-01-28 00:13:56.854950 | TASK [podman may need iptables kernel modules]
2026-01-28 00:13:58.221151 | controller | ok: Runtime: 0:00:00.009528
2026-01-28 00:13:58.267536 | 
2026-01-28 00:13:58.267642 | PLAY RECAP
2026-01-28 00:13:58.267697 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2026-01-28 00:13:58.267720 | 
2026-01-28 00:13:58.395474 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-01-28 00:13:58.396469 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-01-28 00:14:02.324499 | 
2026-01-28 00:14:02.324639 | PLAY [controller]
2026-01-28 00:14:02.354817 | 
2026-01-28 00:14:02.354957 | TASK [Copy the galaxy.yml on the controller]
2026-01-28 00:14:06.462515 | controller | changed
2026-01-28 00:14:06.471481 | 
2026-01-28 00:14:06.471617 | TASK [Load information from galaxy.yml]
2026-01-28 00:14:06.504165 | controller | ok
2026-01-28 00:14:06.513865 | 
2026-01-28 00:14:06.514114 | LOOP [Enable FIPS mode]
2026-01-28 00:14:06.547651 | 
2026-01-28 00:14:06.547819 | LOOP [ansible-test : Include branch specific variables.]
2026-01-28 00:14:06.606032 | controller | ok: "/var/lib/zuul/builds/cab1997e7b054be8a352eba162d34430/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2026-01-28 00:14:06.606861 | 
2026-01-28 00:14:06.624275 | 
2026-01-28 00:14:06.624352 | TASK [ansible-test : Setup --skip-tags for test_options]
2026-01-28 00:14:06.672693 | controller | ok
2026-01-28 00:14:06.679535 | 
2026-01-28 00:14:06.679600 | TASK [ansible-test : Enable --inventory for network-integration]
2026-01-28 00:14:06.703759 | controller | skipping: Conditional result was False
2026-01-28 00:14:06.710692 | 
2026-01-28 00:14:06.710759 | TASK [ansible-test : Enable --retry-on-error]
2026-01-28 00:14:06.734994 | controller | skipping: Conditional result was False
2026-01-28 00:14:06.743229 | 
2026-01-28 00:14:06.743397 | TASK [ansible-test : Enable --continue-on-error]
2026-01-28 00:14:06.787570 | controller | ok
2026-01-28 00:14:06.793452 | 
2026-01-28 00:14:06.793513 | TASK [ansible-test : Enable --requirements]
2026-01-28 00:14:06.807652 | controller | skipping: Conditional result was False
2026-01-28 00:14:06.814086 | 
2026-01-28 00:14:06.814157 | TASK [ansible-test : Adjust options for unit tests]
2026-01-28 00:14:06.829563 | controller | skipping: Conditional result was False
2026-01-28 00:14:06.838636 | 
2026-01-28 00:14:06.838736 | TASK [ansible-test : Install requirements with unit tests]
2026-01-28 00:14:06.910047 | controller | ok
2026-01-28 00:14:06.915754 | 
2026-01-28 00:14:06.915813 | TASK [ansible-test : Set --skip-test]
2026-01-28 00:14:06.970325 | controller | skipping: Conditional result was False
2026-01-28 00:14:06.977432 | 
2026-01-28 00:14:06.977498 | TASK [ansible-test : Reduce verbosity]
2026-01-28 00:14:07.017943 | controller | ok
2026-01-28 00:14:07.023559 | 
2026-01-28 00:14:07.023621 | TASK [ansible-test : Setup --python option]
2026-01-28 00:14:07.047531 | controller | skipping: Conditional result was False
2026-01-28 00:14:07.053864 | 
2026-01-28 00:14:07.053926 | TASK [ansible-test : Setup --docker option]
2026-01-28 00:14:07.098117 | controller | ok
2026-01-28 00:14:07.103864 | 
2026-01-28 00:14:07.103929 | TASK [ansible-test : check if MANIFEST.json exists]
2026-01-28 00:14:07.978182 | controller | ok
2026-01-28 00:14:07.993201 | 
2026-01-28 00:14:07.993362 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2026-01-28 00:14:08.020036 | controller | skipping: Conditional result was False
2026-01-28 00:14:08.031314 | 
2026-01-28 00:14:08.031425 | TASK [ansible-test : Load information from MANIFEST.json]
2026-01-28 00:14:08.059132 | controller | skipping: Conditional result was False
2026-01-28 00:14:08.072369 | 
2026-01-28 00:14:08.072485 | TASK [ansible-test : Setup location of project for integration tests]
2026-01-28 00:14:08.100280 | controller | skipping: Conditional result was False
2026-01-28 00:14:08.111102 | 
2026-01-28 00:14:08.111228 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2026-01-28 00:14:09.024718 | controller | ok
2026-01-28 00:14:09.044285 | 
2026-01-28 00:14:09.044388 | TASK [ansible-test : Load information from galaxy.yml]
2026-01-28 00:14:09.104362 | controller | ok
2026-01-28 00:14:09.114406 | 
2026-01-28 00:14:09.114539 | TASK [ansible-test : Setup location of project for integration tests]
2026-01-28 00:14:09.161716 | controller | ok
2026-01-28 00:14:09.170634 | 
2026-01-28 00:14:09.170737 | TASK [ansible-test : Setup minimum test requirements]
2026-01-28 00:14:09.215697 | controller | ok
2026-01-28 00:14:09.221373 | 
2026-01-28 00:14:09.221434 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2026-01-28 00:14:10.037437 | controller | ok
2026-01-28 00:14:10.055014 | 
2026-01-28 00:14:10.055118 | TASK [ansible-test : Install python requirements]
2026-01-28 00:14:11.824202 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-01-28 00:14:11.843763 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2026-01-28 00:14:11.889900 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-01-28 00:14:11.896482 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2026-01-28 00:14:11.910610 | controller | Requirement already satisfied: pyyaml in ./venv/lib64/python3.8/site-packages (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1)) (6.0.3)
2026-01-28 00:14:11.919135 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2026-01-28 00:14:11.938047 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2026-01-28 00:14:11.998238 | controller | Installing collected packages: pathspec, yamllint
2026-01-28 00:14:12.092907 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2026-01-28 00:14:12.929054 | controller | ok: Runtime: 0:00:01.235940
2026-01-28 00:14:12.937162 | 
2026-01-28 00:14:12.937257 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2026-01-28 00:14:13.766919 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2026-01-28 00:14:14.310277 | controller | ERROR
2026-01-28 00:14:14.310553 | controller | {
2026-01-28 00:14:14.310621 | controller |   "delta": "0:00:00.017562",
2026-01-28 00:14:14.310656 | controller |   "end": "2026-01-28 00:14:13.767347",
2026-01-28 00:14:14.310722 | controller |   "msg": "non-zero return code",
2026-01-28 00:14:14.310748 | controller |   "rc": 1,
2026-01-28 00:14:14.310772 | controller |   "start": "2026-01-28 00:14:13.749785"
2026-01-28 00:14:14.310795 | controller | }
2026-01-28 00:14:14.310828 | controller | ERROR: Ignoring Errors
2026-01-28 00:14:14.320491 | 
2026-01-28 00:14:14.320597 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2026-01-28 00:14:14.688187 | controller | skipping: Conditional result was False
2026-01-28 00:14:14.695181 | 
2026-01-28 00:14:14.695264 | TASK [ansible-test : Enable persistent connection logging]
2026-01-28 00:14:16.141025 | controller | changed: section and option added
2026-01-28 00:14:16.148614 | 
2026-01-28 00:14:16.148805 | TASK [ansible-test : Set persistent ssh_type value]
2026-01-28 00:14:17.339419 | controller | changed: option added
2026-01-28 00:14:17.346083 | 
2026-01-28 00:14:17.346147 | TASK [ansible-test : Set ansible log path]
2026-01-28 00:14:18.407636 | controller | changed: section and option added
2026-01-28 00:14:18.420955 | 
2026-01-28 00:14:18.421086 | TASK [ansible-test : Identify the changed targets]
2026-01-28 00:14:18.835874 | controller | skipping: Conditional result was False
2026-01-28 00:14:18.843417 | 
2026-01-28 00:14:18.843693 | TASK [ansible-test : set_fact]
2026-01-28 00:14:18.909614 | controller | skipping: Conditional result was False
2026-01-28 00:14:18.926926 | 
2026-01-28 00:14:18.927063 | TASK [ansible-test : Set the targets]
2026-01-28 00:14:18.980808 | controller | ok
2026-01-28 00:14:19.073163 | 
2026-01-28 00:14:19.073278 | TASK [ansible-test : set_fact]
2026-01-28 00:14:19.097475 | controller | skipping: Conditional result was False
2026-01-28 00:14:19.105141 | 
2026-01-28 00:14:19.105336 | TASK [ansible-test : List the targets]
2026-01-28 00:14:19.508438 | controller | skipping: Conditional result was False
2026-01-28 00:14:19.516575 | 
2026-01-28 00:14:19.516723 | TASK [ansible-test : set_fact]
2026-01-28 00:14:19.571655 | controller | skipping: Conditional result was False
2026-01-28 00:14:19.578606 | 
2026-01-28 00:14:19.578744 | TASK [ansible-test : set_fact]
2026-01-28 00:14:19.624391 | controller | skipping: Conditional result was False
2026-01-28 00:14:19.631545 | 
2026-01-28 00:14:19.631623 | LOOP [ansible-test : set_fact]
2026-01-28 00:14:19.724047 | 
2026-01-28 00:14:19.724224 | TASK [ansible-test : set_fact]
2026-01-28 00:14:19.759026 | controller | skipping: Conditional result was False
2026-01-28 00:14:19.766407 | 
2026-01-28 00:14:19.766497 | TASK [ansible-test : debug]
2026-01-28 00:14:19.796215 | controller | skipping: Conditional result was False
2026-01-28 00:14:19.802616 | 
2026-01-28 00:14:19.802753 | TASK [ansible-test : debug]
2026-01-28 00:14:19.844022 | About to run: ansible-test sanity --requirements --docker -v
2026-01-28 00:14:19.849356 | 
2026-01-28 00:14:19.849423 | TASK [ansible-test : Run the test suite]
2026-01-28 00:14:21.762014 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2026-01-28 00:14:21.811992 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2026-01-28 00:14:23.360233 | controller | 2.9.0: Pulling from ansible/default-test-container
2026-01-28 00:14:23.360331 | controller | 171857c49d0f: Pulling fs layer
2026-01-28 00:14:23.360362 | controller | 419640447d26: Pulling fs layer
2026-01-28 00:14:23.360370 | controller | 61e52f862619: Pulling fs layer
2026-01-28 00:14:23.360377 | controller | 851de0426e26: Pulling fs layer
2026-01-28 00:14:23.360387 | controller | 37425716ea5b: Pulling fs layer
2026-01-28 00:14:23.360397 | controller | cd9b0c439616: Pulling fs layer
2026-01-28 00:14:23.360403 | controller | d4612dbd8855: Pulling fs layer
2026-01-28 00:14:23.360409 | controller | 554699364730: Pulling fs layer
2026-01-28 00:14:23.360416 | controller | 1ff15d4bdb3e: Pulling fs layer
2026-01-28 00:14:23.360422 | controller | 5f2e4724adea: Pulling fs layer
2026-01-28 00:14:23.360428 | controller | a02d9a95ccb8: Pulling fs layer
2026-01-28 00:14:23.360434 | controller | 989f4ce370cb: Pulling fs layer
2026-01-28 00:14:23.360440 | controller | 0d7f52cdf9df: Pulling fs layer
2026-01-28 00:14:23.360446 | controller | c423742351d5: Pulling fs layer
2026-01-28 00:14:23.360453 | controller | 109236341d21: Pulling fs layer
2026-01-28 00:14:23.360459 | controller | 07a2f61c96b8: Pulling fs layer
2026-01-28 00:14:23.360465 | controller | 041f122f27ae: Pulling fs layer
2026-01-28 00:14:23.360471 | controller | ca6f5098b12e: Pulling fs layer
2026-01-28 00:14:23.360477 | controller | 1eb3e7b2afd9: Pulling fs layer
2026-01-28 00:14:23.360490 | controller | 18a0b750727a: Pulling fs layer
2026-01-28 00:14:23.360496 | controller | 17f2f596716c: Pulling fs layer
2026-01-28 00:14:23.360506 | controller | f35a9998a9bd: Pulling fs layer
2026-01-28 00:14:23.360512 | controller | fdfc10566405: Pulling fs layer
2026-01-28 00:14:23.360518 | controller | 1ff1c442c5fe: Pulling fs layer
2026-01-28 00:14:23.360524 | controller | a3443c4a17ed: Pulling fs layer
2026-01-28 00:14:23.360530 | controller | 0edcf9fb2b15: Pulling fs layer
2026-01-28 00:14:23.360536 | controller | 5af43b69e845: Pulling fs layer
2026-01-28 00:14:23.360542 | controller | 1651773effb2: Pulling fs layer
2026-01-28 00:14:23.360548 | controller | 79e23c5c0fe4: Pulling fs layer
2026-01-28 00:14:23.360558 | controller | e5058b1faaa6: Pulling fs layer
2026-01-28 00:14:23.360565 | controller | 846775490569: Pulling fs layer
2026-01-28 00:14:23.360575 | controller | 041f122f27ae: Waiting
2026-01-28 00:14:23.741570 | controller | ca6f5098b12e: Waiting
2026-01-28 00:14:23.741674 | controller | 1eb3e7b2afd9: Waiting
2026-01-28 00:14:23.741682 | controller | 18a0b750727a: Waiting
2026-01-28 00:14:23.741688 | controller | 17f2f596716c: Waiting
2026-01-28 00:14:23.741695 | controller | f35a9998a9bd: Waiting
2026-01-28 00:14:23.741710 | controller | fdfc10566405: Waiting
2026-01-28 00:14:23.741716 | controller | 1ff1c442c5fe: Waiting
2026-01-28 00:14:23.741722 | controller | a3443c4a17ed: Waiting
2026-01-28 00:14:23.741728 | controller | 0edcf9fb2b15: Waiting
2026-01-28 00:14:23.741734 | controller | 5af43b69e845: Waiting
2026-01-28 00:14:23.741740 | controller | 1651773effb2: Waiting
2026-01-28 00:14:23.741746 | controller | 79e23c5c0fe4: Waiting
2026-01-28 00:14:23.741752 | controller | e5058b1faaa6: Waiting
2026-01-28 00:14:23.741758 | controller | 846775490569: Waiting
2026-01-28 00:14:23.741764 | controller | 1ff15d4bdb3e: Waiting
2026-01-28 00:14:23.741780 | controller | 5f2e4724adea: Waiting
2026-01-28 00:14:23.741787 | controller | a02d9a95ccb8: Waiting
2026-01-28 00:14:23.741796 | controller | 989f4ce370cb: Waiting
2026-01-28 00:14:23.741802 | controller | 0d7f52cdf9df: Waiting
2026-01-28 00:14:23.741808 | controller | c423742351d5: Waiting
2026-01-28 00:14:23.741814 | controller | 109236341d21: Waiting
2026-01-28 00:14:23.741820 | controller | 07a2f61c96b8: Waiting
2026-01-28 00:14:23.741825 | controller | cd9b0c439616: Waiting
2026-01-28 00:14:23.741832 | controller | d4612dbd8855: Waiting
2026-01-28 00:14:23.741838 | controller | 554699364730: Waiting
2026-01-28 00:14:23.741844 | controller | 37425716ea5b: Waiting
2026-01-28 00:14:23.741852 | controller | 851de0426e26: Waiting
2026-01-28 00:14:23.741875 | controller | 419640447d26: Verifying Checksum
2026-01-28 00:14:23.759541 | controller | 419640447d26: Download complete
2026-01-28 00:14:23.759608 | controller | 61e52f862619: Verifying Checksum
2026-01-28 00:14:24.110748 | controller | 61e52f862619: Download complete
2026-01-28 00:14:24.110843 | controller | 171857c49d0f: Verifying Checksum
2026-01-28 00:14:24.633796 | controller | 171857c49d0f: Download complete
2026-01-28 00:14:24.633865 | controller | 851de0426e26: Verifying Checksum
2026-01-28 00:14:24.950084 | controller | 851de0426e26: Download complete
2026-01-28 00:14:24.950164 | controller | cd9b0c439616: Verifying Checksum
2026-01-28 00:14:25.550916 | controller | cd9b0c439616: Download complete
2026-01-28 00:14:25.551012 | controller | d4612dbd8855: Verifying Checksum
2026-01-28 00:14:26.409509 | controller | d4612dbd8855: Download complete
2026-01-28 00:14:26.409584 | controller | 1ff15d4bdb3e: Verifying Checksum
2026-01-28 00:14:27.286392 | controller | 1ff15d4bdb3e: Download complete
2026-01-28 00:14:27.286518 | controller | 5f2e4724adea: Verifying Checksum
2026-01-28 00:14:28.756894 | controller | 5f2e4724adea: Download complete
2026-01-28 00:14:28.756966 | controller | a02d9a95ccb8: Verifying Checksum
2026-01-28 00:14:29.042448 | controller | a02d9a95ccb8: Download complete
2026-01-28 00:14:29.042534 | controller | 171857c49d0f: Pull complete
2026-01-28 00:14:29.077208 | controller | 419640447d26: Pull complete
2026-01-28 00:14:29.125310 | controller | 61e52f862619: Pull complete
2026-01-28 00:14:29.177735 | controller | 851de0426e26: Pull complete
2026-01-28 00:14:29.568251 | controller | 989f4ce370cb: Verifying Checksum
2026-01-28 00:14:29.568404 | controller | 989f4ce370cb: Download complete
2026-01-28 00:14:30.421408 | controller | 0d7f52cdf9df: Verifying Checksum
2026-01-28 00:14:31.202463 | controller | 0d7f52cdf9df: Download complete
2026-01-28 00:14:31.202551 | controller | c423742351d5: Verifying Checksum
2026-01-28 00:14:33.929395 | controller | c423742351d5: Download complete
2026-01-28 00:14:33.929501 | controller | 554699364730: Verifying Checksum
2026-01-28 00:14:34.730185 | controller | 554699364730: Download complete
2026-01-28 00:14:34.730317 | controller | 07a2f61c96b8: Verifying Checksum
2026-01-28 00:14:34.730385 | controller | 07a2f61c96b8: Download complete
2026-01-28 00:14:35.488047 | controller | 041f122f27ae: Verifying Checksum
2026-01-28 00:14:35.488203 | controller | 041f122f27ae: Download complete
2026-01-28 00:14:36.372406 | controller | ca6f5098b12e: Verifying Checksum
2026-01-28 00:14:36.372533 | controller | ca6f5098b12e: Download complete
2026-01-28 00:14:37.665219 | controller | 1eb3e7b2afd9: Verifying Checksum
2026-01-28 00:14:37.676144 | controller | 1eb3e7b2afd9: Download complete
2026-01-28 00:14:37.676201 | controller | 37425716ea5b: Verifying Checksum
2026-01-28 00:14:38.362156 | controller | 37425716ea5b: Download complete
2026-01-28 00:14:38.362227 | controller | 17f2f596716c: Download complete
2026-01-28 00:14:38.431066 | controller | 18a0b750727a: Verifying Checksum
2026-01-28 00:14:38.746966 | controller | 18a0b750727a: Download complete
2026-01-28 00:14:38.747078 | controller | f35a9998a9bd: Verifying Checksum
2026-01-28 00:14:38.747208 | controller | f35a9998a9bd: Download complete
2026-01-28 00:14:38.853816 | controller | fdfc10566405: Verifying Checksum
2026-01-28 00:14:38.853948 | controller | fdfc10566405: Download complete
2026-01-28 00:14:39.134721 | controller | 1ff1c442c5fe: Verifying Checksum
2026-01-28 00:14:39.135316 | controller | 1ff1c442c5fe: Download complete
2026-01-28 00:14:39.754495 | controller | 109236341d21: Verifying Checksum
2026-01-28 00:14:39.754609 | controller | 109236341d21: Download complete
2026-01-28 00:14:39.863547 | controller | 0edcf9fb2b15: Verifying Checksum
2026-01-28 00:14:39.863877 | controller | 0edcf9fb2b15: Download complete
2026-01-28 00:14:40.656741 | controller | 5af43b69e845: Verifying Checksum
2026-01-28 00:14:40.693866 | controller | 5af43b69e845: Download complete
2026-01-28 00:14:40.693986 | controller | 1651773effb2: Verifying Checksum
2026-01-28 00:14:40.694021 | controller | 1651773effb2: Download complete
2026-01-28 00:14:41.020294 | controller | a3443c4a17ed: Verifying Checksum
2026-01-28 00:14:41.020517 | controller | a3443c4a17ed: Download complete
2026-01-28 00:14:41.468860 | controller | e5058b1faaa6: Verifying Checksum
2026-01-28 00:14:41.469007 | controller | e5058b1faaa6: Download complete
2026-01-28 00:14:41.478873 | controller | 79e23c5c0fe4: Verifying Checksum
2026-01-28 00:14:41.478956 | controller | 79e23c5c0fe4: Download complete
2026-01-28 00:14:44.515968 | controller | 846775490569: Verifying Checksum
2026-01-28 00:14:57.687368 | controller | 846775490569: Download complete
2026-01-28 00:14:57.687511 | controller | 37425716ea5b: Pull complete
2026-01-28 00:14:57.724876 | controller | cd9b0c439616: Pull complete
2026-01-28 00:14:57.753262 | controller | d4612dbd8855: Pull complete
2026-01-28 00:15:02.722469 | controller | 554699364730: Pull complete
2026-01-28 00:15:02.753604 | controller | 1ff15d4bdb3e: Pull complete
2026-01-28 00:15:02.786805 | controller | 5f2e4724adea: Pull complete
2026-01-28 00:15:02.837736 | controller | a02d9a95ccb8: Pull complete
2026-01-28 00:15:02.865041 | controller | 989f4ce370cb: Pull complete
2026-01-28 00:15:02.891488 | controller | 0d7f52cdf9df: Pull complete
2026-01-28 00:15:02.912367 | controller | c423742351d5: Pull complete
2026-01-28 00:15:05.529583 | controller | 109236341d21: Pull complete
2026-01-28 00:15:05.557357 | controller | 07a2f61c96b8: Pull complete
2026-01-28 00:15:05.579851 | controller | 041f122f27ae: Pull complete
2026-01-28 00:15:05.766589 | controller | ca6f5098b12e: Pull complete
2026-01-28 00:15:05.824765 | controller | 1eb3e7b2afd9: Pull complete
2026-01-28 00:15:05.883524 | controller | 18a0b750727a: Pull complete
2026-01-28 00:15:05.910134 | controller | 17f2f596716c: Pull complete
2026-01-28 00:15:05.939791 | controller | f35a9998a9bd: Pull complete
2026-01-28 00:15:05.982749 | controller | fdfc10566405: Pull complete
2026-01-28 00:15:06.009130 | controller | 1ff1c442c5fe: Pull complete
2026-01-28 00:15:07.808525 | controller | a3443c4a17ed: Pull complete
2026-01-28 00:15:09.919830 | controller | 0edcf9fb2b15: Pull complete
2026-01-28 00:15:12.732816 | controller | 5af43b69e845: Pull complete
2026-01-28 00:15:17.484153 | controller | 1651773effb2: Pull complete
2026-01-28 00:15:20.115065 | controller | 79e23c5c0fe4: Pull complete
2026-01-28 00:15:21.893552 | controller | e5058b1faaa6: Pull complete
2026-01-28 00:15:24.500329 | controller | 846775490569: Pull complete
2026-01-28 00:15:24.533902 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2026-01-28 00:15:24.539050 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2026-01-28 00:15:24.551011 | controller | quay.io/ansible/default-test-container:2.9.0
2026-01-28 00:15:24.556414 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2026-01-28 00:15:24.597751 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2026-01-28 00:15:25.934269 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2026-01-28 00:15:25.935410 | controller | 04a5f4cda3ee: Pulling fs layer
2026-01-28 00:15:25.935477 | controller | ff496a88c8ed: Pulling fs layer
2026-01-28 00:15:25.935549 | controller | 0ce83f459fe7: Pulling fs layer
2026-01-28 00:15:25.935602 | controller | 2e5170e1f099: Pulling fs layer
2026-01-28 00:15:25.935693 | controller | 7641eb41b08c: Pulling fs layer
2026-01-28 00:15:25.935748 | controller | ad15fa9da398: Pulling fs layer
2026-01-28 00:15:25.935826 | controller | 087d91352424: Pulling fs layer
2026-01-28 00:15:25.936501 | controller | 2e5170e1f099: Waiting
2026-01-28 00:15:25.936626 | controller | 7641eb41b08c: Waiting
2026-01-28 00:15:25.936751 | controller | ad15fa9da398: Waiting
2026-01-28 00:15:25.937147 | controller | 8b92efd6a100: Pulling fs layer
2026-01-28 00:15:25.937446 | controller | 087d91352424: Waiting
2026-01-28 00:15:25.937569 | controller | 8b92efd6a100: Waiting
2026-01-28 00:15:26.315743 | controller | 0ce83f459fe7: Verifying Checksum
2026-01-28 00:15:26.320586 | controller | 0ce83f459fe7: Download complete
2026-01-28 00:15:26.320697 | controller | ff496a88c8ed: Verifying Checksum
2026-01-28 00:15:26.586999 | controller | ff496a88c8ed: Download complete
2026-01-28 00:15:26.587099 | controller | 04a5f4cda3ee: Verifying Checksum
2026-01-28 00:15:26.720027 | controller | 04a5f4cda3ee: Download complete
2026-01-28 00:15:26.720127 | controller | 7641eb41b08c: Verifying Checksum
2026-01-28 00:15:26.971949 | controller | 7641eb41b08c: Download complete
2026-01-28 00:15:26.972052 | controller | ad15fa9da398: Verifying Checksum
2026-01-28 00:15:27.056307 | controller | ad15fa9da398: Download complete
2026-01-28 00:15:27.056407 | controller | 2e5170e1f099: Verifying Checksum
2026-01-28 00:15:27.221863 | controller | 2e5170e1f099: Download complete
2026-01-28 00:15:27.221964 | controller | 087d91352424: Verifying Checksum
2026-01-28 00:15:27.222218 | controller | 087d91352424: Download complete
2026-01-28 00:15:27.392788 | controller | 8b92efd6a100: Verifying Checksum
2026-01-28 00:15:29.013677 | controller | 8b92efd6a100: Download complete
2026-01-28 00:15:29.013739 | controller | 04a5f4cda3ee: Pull complete
2026-01-28 00:15:29.080466 | controller | ff496a88c8ed: Pull complete
2026-01-28 00:15:29.136225 | controller | 0ce83f459fe7: Pull complete
2026-01-28 00:15:30.835389 | controller | 2e5170e1f099: Pull complete
2026-01-28 00:15:30.860061 | controller | 7641eb41b08c: Pull complete
2026-01-28 00:15:30.890469 | controller | ad15fa9da398: Pull complete
2026-01-28 00:15:31.896580 | controller | 087d91352424: Pull complete
2026-01-28 00:15:31.945018 | controller | 8b92efd6a100: Pull complete
2026-01-28 00:15:31.987287 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2026-01-28 00:15:31.997045 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2026-01-28 00:15:32.009938 | controller | quay.io/ansible/pypi-test-container:1.0.0
2026-01-28 00:15:32.013836 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2026-01-28 00:15:40.201884 | controller | Run command: docker inspect 072493d5e5c6b94f83282d8d1ef6cc23e45e4d48220c747fd7d8a206d61f0a4e
2026-01-28 00:15:40.264589 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2026-01-28 00:15:40.283820 | controller | Including collection: ansible.netcommon (287 files)
2026-01-28 00:15:40.283914 | controller | Including collection: ansible.network (23 files)
2026-01-28 00:15:40.295499 | controller | Including collection: ansible.utils (441 files)
2026-01-28 00:15:40.335766 | controller | Including collection: arista.eos (1022 files)
2026-01-28 00:15:40.382896 | controller | Including collection: cisco.ios (1174 files)
2026-01-28 00:15:40.453167 | controller | Including collection: cisco.iosxr (1044 files)
2026-01-28 00:15:40.540536 | controller | Including collection: cisco.nxos (1687 files)
2026-01-28 00:15:40.591216 | controller | Including collection: junipernetworks.junos (963 files)
2026-01-28 00:15:40.643542 | controller | Creating a payload archive containing 7367 files...
2026-01-28 00:15:42.902553 | controller | Created a 6007598 byte payload archive containing 7367 files in 2 seconds.
2026-01-28 00:15:42.909207 | controller | Assuming Docker is available on localhost.
2026-01-28 00:15:42.909805 | 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
2026-01-28 00:15:43.407401 | controller | Run command: docker exec -i a1d59d4aa266eb61c00f9ed703d1562a5d2aaccca9af0c1002b2644f49ad3874 dd of=/root/docker.sh bs=65536
2026-01-28 00:15:43.490272 | controller | Run command: docker exec a1d59d4aa266eb61c00f9ed703d1562a5d2aaccca9af0c1002b2644f49ad3874 /bin/bash /root/docker.sh
2026-01-28 00:15:43.558255 | controller | Run command: docker exec -i a1d59d4aa266eb61c00f9ed703d1562a5d2aaccca9af0c1002b2644f49ad3874 dd of=/root/test.tgz bs=65536
2026-01-28 00:15:43.660955 | controller | Run command: docker exec a1d59d4aa266eb61c00f9ed703d1562a5d2aaccca9af0c1002b2644f49ad3874 tar oxzf /root/test.tgz -C /root
2026-01-28 00:15:44.657752 | controller | Run command: docker exec a1d59d4aa266eb61c00f9ed703d1562a5d2aaccca9af0c1002b2644f49ad3874 /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-8i8id4h0.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2026-01-28 00:15:45.076406 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2026-01-28 00:15:45.076609 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2026-01-28 00:15:45.076788 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2026-01-28 00:15:45.078680 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2026-01-28 00:15:45.080459 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2026-01-28 00:15:45.085924 | controller | No tests applicable.
2026-01-28 00:15:45.086031 | controller | Running sanity test 'ansible-doc' with Python 3.6
2026-01-28 00:15:45.086132 | controller | No tests applicable.
2026-01-28 00:15:45.086613 | controller | Running sanity test 'changelog' with Python 3.6
2026-01-28 00:15:45.086646 | 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
2026-01-28 00:15:46.075697 | 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
2026-01-28 00:15:46.675948 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2026-01-28 00:16:01.707268 | 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/
2026-01-28 00:16:17.226288 | 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/
2026-01-28 00:16:33.244967 | 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/
2026-01-28 00:16:50.264774 | 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/
2026-01-28 00:17:09.286166 | 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/
2026-01-28 00:17:24.303807 | 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)
2026-01-28 00:17:24.307521 | 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))
2026-01-28 00:17:24.505695 | 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.
2026-01-28 00:17:24.505972 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2026-01-28 00:17:24.506305 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2026-01-28 00:17:24.506487 | controller | Removing custom PyPI hosts entries: /etc/hosts
2026-01-28 00:17:24.578789 | controller | Run command: docker exec a1d59d4aa266eb61c00f9ed703d1562a5d2aaccca9af0c1002b2644f49ad3874 tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2026-01-28 00:17:24.666811 | controller | Run command: docker exec -i a1d59d4aa266eb61c00f9ed703d1562a5d2aaccca9af0c1002b2644f49ad3874 dd if=/root/output.tgz bs=65536
2026-01-28 00:17:24.754754 | controller | Run command: tar oxzf /tmp/ansible-result-rdepi2ak.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2026-01-28 00:17:24.773685 | controller | Run command: docker rm -f 072493d5e5c6b94f83282d8d1ef6cc23e45e4d48220c747fd7d8a206d61f0a4e
2026-01-28 00:17:25.074072 | controller | Run command: docker rm -f a1d59d4aa266eb61c00f9ed703d1562a5d2aaccca9af0c1002b2644f49ad3874
2026-01-28 00:17:25.773799 | controller | ERROR: Command "docker exec a1d59d4aa266eb61c00f9ed703d1562a5d2aaccca9af0c1002b2644f49ad3874 /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-8i8id4h0.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2026-01-28 00:17:26.407391 | controller | ERROR
2026-01-28 00:17:26.407726 | controller | {
2026-01-28 00:17:26.407800 | controller |   "delta": "0:03:04.637731",
2026-01-28 00:17:26.407844 | controller |   "end": "2026-01-28 00:17:25.839058",
2026-01-28 00:17:26.407918 | controller |   "msg": "non-zero return code",
2026-01-28 00:17:26.407956 | controller |   "rc": 1,
2026-01-28 00:17:26.407992 | controller |   "start": "2026-01-28 00:14:21.201327"
2026-01-28 00:17:26.408050 | controller | }
failure
2026-01-28 00:17:26.416968 | 
2026-01-28 00:17:26.417073 | PLAY RECAP
2026-01-28 00:17:26.417160 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2026-01-28 00:17:26.417206 | 
2026-01-28 00:17:26.535018 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-01-28 00:17:26.536049 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-28 00:17:27.268411 | 
2026-01-28 00:17:27.268541 | PLAY [all]
2026-01-28 00:17:27.294101 | 
2026-01-28 00:17:27.294229 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-01-28 00:17:30.371539 | controller | changed: non-zero return code
2026-01-28 00:17:30.389307 | 
2026-01-28 00:17:30.389513 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-01-28 00:17:30.418764 | controller | skipping: Conditional result was False
2026-01-28 00:17:30.437820 | 
2026-01-28 00:17:30.438039 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-01-28 00:17:30.485201 | 
2026-01-28 00:17:30.485529 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-01-28 00:17:30.536159 | 
2026-01-28 00:17:30.536368 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-01-28 00:17:30.562013 | controller | skipping: Conditional result was False
2026-01-28 00:17:30.570771 | 
2026-01-28 00:17:30.570875 | LOOP [fetch-subunit-output : Generate subunit file]
2026-01-28 00:17:30.605251 | 
2026-01-28 00:17:30.605445 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-01-28 00:17:30.630488 | controller | skipping: Conditional result was False
2026-01-28 00:17:30.640774 | 
2026-01-28 00:17:30.640887 | TASK [fetch-subunit-output : Remove the temporary file]
2026-01-28 00:17:30.677041 | controller | skipping: Conditional result was False
2026-01-28 00:17:30.685400 | 
2026-01-28 00:17:30.685500 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-01-28 00:17:30.709710 | controller | skipping: Conditional result was False
2026-01-28 00:17:30.743367 | 
2026-01-28 00:17:30.743469 | PLAY RECAP
2026-01-28 00:17:30.743510 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-01-28 00:17:30.743531 | 
2026-01-28 00:17:30.863658 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-28 00:17:30.864555 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-01-28 00:17:31.512280 | 
2026-01-28 00:17:31.512391 | PLAY [all:!appliance*]
2026-01-28 00:17:31.534448 | 
2026-01-28 00:17:31.534565 | TASK [unregister the node]
2026-01-28 00:17:31.891009 | controller | skipping: Conditional result was False
2026-01-28 00:17:31.907200 | 
2026-01-28 00:17:31.907418 | TASK [include_role : fetch-output]
2026-01-28 00:17:31.963698 | controller | ok
2026-01-28 00:17:31.999113 | 
2026-01-28 00:17:31.999283 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-28 00:17:32.075658 | controller | skipping: Conditional result was False
2026-01-28 00:17:32.094262 | 
2026-01-28 00:17:32.094448 | TASK [fetch-output : Set log path for single node]
2026-01-28 00:17:32.139965 | controller | ok
2026-01-28 00:17:32.147557 | 
2026-01-28 00:17:32.147652 | LOOP [fetch-output : Ensure local output dirs]
2026-01-28 00:17:32.574853 | controller -> localhost | ok: "/var/lib/zuul/builds/cab1997e7b054be8a352eba162d34430/work/logs"
2026-01-28 00:17:32.828180 | controller -> localhost | changed: "/var/lib/zuul/builds/cab1997e7b054be8a352eba162d34430/work/artifacts"
2026-01-28 00:17:33.063307 | controller -> localhost | changed: "/var/lib/zuul/builds/cab1997e7b054be8a352eba162d34430/work/docs"
2026-01-28 00:17:33.079633 | 
2026-01-28 00:17:33.079736 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-28 00:17:35.364215 | controller | changed:
2026-01-28 00:17:35.364456 | controller | .d..t...... ./
2026-01-28 00:17:35.364497 | controller | cd+++++++++ controller/
2026-01-28 00:17:35.364546 | controller | changed: All items complete
2026-01-28 00:17:35.364576 | 
2026-01-28 00:17:37.410369 | controller | changed: .d..t...... ./
2026-01-28 00:17:39.444162 | controller | changed: .d..t...... ./
2026-01-28 00:17:39.490842 | 
2026-01-28 00:17:39.492081 | TASK [include_role : fetch-output-openshift]
2026-01-28 00:17:39.517783 | controller | skipping: Conditional result was False
2026-01-28 00:17:39.536271 | 
2026-01-28 00:17:39.536410 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-28 00:17:39.583909 | controller | skipping: Conditional result was False
2026-01-28 00:17:39.595753 | controller | skipping: Conditional result was False
2026-01-28 00:17:39.669688 | 
2026-01-28 00:17:39.669912 | PLAY [localhost]
2026-01-28 00:17:39.692612 | 
2026-01-28 00:17:39.692738 | TASK [Run Zuul manifest role]
2026-01-28 00:17:39.723565 | localhost | ok
2026-01-28 00:17:39.739335 | 
2026-01-28 00:17:39.739430 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-28 00:17:40.149252 | localhost | changed
2026-01-28 00:17:40.154310 | 
2026-01-28 00:17:40.154382 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-28 00:17:40.194589 | localhost | ok
2026-01-28 00:17:40.203863 | 
2026-01-28 00:17:40.203999 | TASK [Set zuul-log-path fact]
2026-01-28 00:17:40.226975 | localhost | ok
2026-01-28 00:17:40.291361 | 
2026-01-28 00:17:40.291533 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-28 00:17:40.321753 | localhost | ok
2026-01-28 00:17:40.329942 | 
2026-01-28 00:17:40.330011 | LOOP [Run upload-logs-swift role]
2026-01-28 00:17:40.365608 | localhost | Output suppressed because no_log was given
2026-01-28 00:17:40.394557 | 
2026-01-28 00:17:40.394713 | TASK [Set zuul-log-path fact]
2026-01-28 00:17:40.440985 | localhost | skipping: Conditional result was False
2026-01-28 00:17:40.448520 | 
2026-01-28 00:17:40.448625 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-01-28 00:17:40.904230 | localhost -> localhost | ok: Runtime: 0:00:00.011542
2026-01-28 00:17:40.910257 | 
2026-01-28 00:17:40.910356 | TASK [upload-logs-swift : Upload logs to swift]