2026-01-21 00:06:22.905505 | Job console starting...
2026-01-21 00:06:22.919295 | Updating repositories
2026-01-21 00:06:23.248076 | Preparing job workspace
2026-01-21 00:06:34.145008 | Running Ansible setup...
2026-01-21 00:06:38.806033 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-21 00:06:39.514552 | 
2026-01-21 00:06:39.514684 | PLAY [localhost]
2026-01-21 00:06:39.523790 | 
2026-01-21 00:06:39.523896 | TASK [Gathering Facts]
2026-01-21 00:06:40.946295 | localhost | ok
2026-01-21 00:06:40.991243 | 
2026-01-21 00:06:40.991387 | TASK [Setup log path fact]
2026-01-21 00:06:41.031232 | localhost | ok
2026-01-21 00:06:41.048950 | 
2026-01-21 00:06:41.049081 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-21 00:06:41.100825 | localhost | ok
2026-01-21 00:06:41.114753 | 
2026-01-21 00:06:41.114894 | TASK [emit-job-header : Print job information]
2026-01-21 00:06:41.157921 | # Job Information
2026-01-21 00:06:41.158070 | Ansible Version: 2.15.12
2026-01-21 00:06:41.158101 | Job: ansible-test-sanity-docker-stable-2.10
2026-01-21 00:06:41.158123 | Pipeline: periodic
2026-01-21 00:06:41.158143 | Executor: ze03.softwarefactory-project.io
2026-01-21 00:06:41.158161 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-01-21 00:06:41.158182 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/513/ansible/51387024207e491dbaefbb8664a1c3fa/
2026-01-21 00:06:41.158202 | Event ID: 08cfdb9560914df89453f509f8798614
2026-01-21 00:06:41.161927 | 
2026-01-21 00:06:41.161989 | LOOP [emit-job-header : Print node information]
2026-01-21 00:06:41.265031 | localhost | ok:
2026-01-21 00:06:41.265326 | localhost | # Node Information
2026-01-21 00:06:41.265362 | localhost | Inventory Hostname: controller
2026-01-21 00:06:41.265388 | localhost | Hostname: np0005589721
2026-01-21 00:06:41.265409 | localhost | Username: zuul
2026-01-21 00:06:41.265436 | localhost | Distro: Fedora 37
2026-01-21 00:06:41.265457 | localhost | Provider: ansible-vexxhost-ca-ymq-1
2026-01-21 00:06:41.265475 | localhost | Region: ca-ymq-1
2026-01-21 00:06:41.265492 | localhost | Label: ansible-fedora-37-1vcpu
2026-01-21 00:06:41.265510 | localhost | Product Name: OpenStack Nova
2026-01-21 00:06:41.265527 | localhost | Interface IP: 162.253.55.195
2026-01-21 00:06:41.276304 | 
2026-01-21 00:06:41.276384 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-21 00:06:41.719643 | localhost -> localhost | changed
2026-01-21 00:06:41.726247 | 
2026-01-21 00:06:41.726349 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-21 00:06:43.048642 | localhost -> localhost | changed
2026-01-21 00:06:43.071355 | 
2026-01-21 00:06:43.071493 | PLAY [all:!appliance*]
2026-01-21 00:06:43.105269 | 
2026-01-21 00:06:43.105406 | TASK [include_role : start-zuul-console]
2026-01-21 00:06:43.125047 | controller | ok
2026-01-21 00:06:43.147553 | 
2026-01-21 00:06:43.147704 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-01-21 00:06:43.543671 | controller | ok
2026-01-21 00:06:43.555212 | 
2026-01-21 00:06:43.555280 | TASK [use-our-mirror : Retrieve the IP address]
2026-01-21 00:07:03.841033 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-01-21 00:07:03.850871 | 
2026-01-21 00:07:03.851088 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-01-21 00:07:04.386427 | controller | skipping: Conditional result was False
2026-01-21 00:07:04.393570 | 
2026-01-21 00:07:04.393664 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-01-21 00:07:04.417994 | controller | skipping: Conditional result was False
2026-01-21 00:07:04.425197 | 
2026-01-21 00:07:04.425328 | TASK [use-our-mirror : Create the podman configuration directory]
2026-01-21 00:07:04.440865 | controller | skipping: Conditional result was False
2026-01-21 00:07:04.447917 | 
2026-01-21 00:07:04.448012 | TASK [use-our-mirror : Copy the podman configuration]
2026-01-21 00:07:04.461368 | controller | skipping: Conditional result was False
2026-01-21 00:07:04.472359 | 
2026-01-21 00:07:04.472450 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-01-21 00:07:04.486685 | controller | skipping: Conditional result was False
2026-01-21 00:07:04.495039 | 
2026-01-21 00:07:04.495178 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-01-21 00:07:04.520546 | controller | skipping: Conditional result was False
2026-01-21 00:07:04.533339 | 
2026-01-21 00:07:04.533499 | TASK [Disable Fedora Modular]
2026-01-21 00:07:04.838792 | controller | changed
2026-01-21 00:07:04.847609 | 
2026-01-21 00:07:04.847704 | TASK [Enable EPEL]
2026-01-21 00:07:04.873985 | controller | skipping: Conditional result was False
2026-01-21 00:07:04.886029 | 
2026-01-21 00:07:04.886296 | TASK [Register the RHEL node]
2026-01-21 00:07:05.441692 | 
2026-01-21 00:07:05.442015 | TASK [Show the subscription-manager status]
2026-01-21 00:07:05.997971 | controller | skipping: Conditional result was False
2026-01-21 00:07:06.016955 | 
2026-01-21 00:07:06.017135 | TASK [Enable EPEL on RHEL]
2026-01-21 00:07:06.586072 | controller | skipping: Conditional result was False
2026-01-21 00:07:06.598754 | 
2026-01-21 00:07:06.598911 | TASK [Install git and tox]
2026-01-21 00:09:03.699867 | controller | changed
2026-01-21 00:09:03.708336 | 
2026-01-21 00:09:03.708452 | TASK [include_role : prepare-workspace]
2026-01-21 00:09:03.731179 | controller | ok
2026-01-21 00:09:03.760934 | 
2026-01-21 00:09:03.761056 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-01-21 00:09:03.985994 | controller | ok
2026-01-21 00:09:04.003030 | 
2026-01-21 00:09:04.003164 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-01-21 00:09:21.741775 | controller | Output suppressed because no_log was given
2026-01-21 00:09:21.759508 | 
2026-01-21 00:09:21.759771 | TASK [include_role : prepare-workspace-openshift]
2026-01-21 00:09:21.784552 | controller | skipping: Conditional result was False
2026-01-21 00:09:21.812395 | 
2026-01-21 00:09:21.812472 | PLAY [all:!appliance]
2026-01-21 00:09:21.829402 | 
2026-01-21 00:09:21.829509 | TASK [Run add-build-sshkey role (RSA)]
2026-01-21 00:09:21.859839 | controller | ok
2026-01-21 00:09:21.875505 | 
2026-01-21 00:09:21.875579 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-21 00:09:22.206966 | controller -> localhost | ok
2026-01-21 00:09:22.214084 | 
2026-01-21 00:09:22.214213 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-21 00:09:22.245636 | controller | ok
2026-01-21 00:09:22.263279 | controller | included: /var/lib/zuul/builds/51387024207e491dbaefbb8664a1c3fa/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-21 00:09:22.272286 | 
2026-01-21 00:09:22.272427 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-21 00:09:22.898634 | controller -> localhost | Generating public/private rsa key pair.
2026-01-21 00:09:22.898845 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/51387024207e491dbaefbb8664a1c3fa/work/51387024207e491dbaefbb8664a1c3fa_id_rsa.
2026-01-21 00:09:22.898876 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/51387024207e491dbaefbb8664a1c3fa/work/51387024207e491dbaefbb8664a1c3fa_id_rsa.pub.
2026-01-21 00:09:22.898898 | controller -> localhost | The key fingerprint is:
2026-01-21 00:09:22.898919 | controller -> localhost | SHA256:mTi99ojY5ds/Wy+tZmtClTi2kM1KseDzZkbo/U3kt2g zuul-build-sshkey
2026-01-21 00:09:22.898940 | controller -> localhost | The key's randomart image is:
2026-01-21 00:09:22.898958 | controller -> localhost | +---[RSA 2048]----+
2026-01-21 00:09:22.898977 | controller -> localhost | |                 |
2026-01-21 00:09:22.898996 | controller -> localhost | |        . .      |
2026-01-21 00:09:22.899015 | controller -> localhost | |       . o * . . |
2026-01-21 00:09:22.899033 | controller -> localhost | |       o+o* * +  |
2026-01-21 00:09:22.899050 | controller -> localhost | |      o.S* + *   |
2026-01-21 00:09:22.899078 | controller -> localhost | |       ...B o o .|
2026-01-21 00:09:22.899102 | controller -> localhost | |        ++ o ooo.|
2026-01-21 00:09:22.899121 | controller -> localhost | |     o = +  +E*o.|
2026-01-21 00:09:22.899140 | controller -> localhost | |    . o +.o.+Bo+.|
2026-01-21 00:09:22.899162 | controller -> localhost | +----[SHA256]-----+
2026-01-21 00:09:22.899209 | controller -> localhost | ok: Runtime: 0:00:00.175466
2026-01-21 00:09:22.905978 | 
2026-01-21 00:09:22.906044 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-21 00:09:22.927461 | controller | ok
2026-01-21 00:09:22.938075 | controller | included: /var/lib/zuul/builds/51387024207e491dbaefbb8664a1c3fa/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-21 00:09:22.946846 | 
2026-01-21 00:09:22.946914 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-21 00:09:22.982282 | controller | skipping: Conditional result was False
2026-01-21 00:09:22.989985 | 
2026-01-21 00:09:22.990052 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-21 00:09:23.431181 | controller | changed
2026-01-21 00:09:23.438946 | 
2026-01-21 00:09:23.439028 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-21 00:09:23.661768 | controller | ok
2026-01-21 00:09:23.677240 | 
2026-01-21 00:09:23.677424 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-21 00:09:24.350246 | controller | changed
2026-01-21 00:09:24.360017 | 
2026-01-21 00:09:24.360373 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-21 00:09:24.949287 | controller | changed
2026-01-21 00:09:24.955716 | 
2026-01-21 00:09:24.955820 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-21 00:09:24.980369 | controller | skipping: Conditional result was False
2026-01-21 00:09:24.988932 | 
2026-01-21 00:09:24.989068 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-21 00:09:25.437056 | controller -> localhost | changed
2026-01-21 00:09:25.455037 | 
2026-01-21 00:09:25.455194 | TASK [add-build-sshkey : Add back temp key]
2026-01-21 00:09:25.788808 | controller -> localhost | Identity added: /var/lib/zuul/builds/51387024207e491dbaefbb8664a1c3fa/work/51387024207e491dbaefbb8664a1c3fa_id_rsa (zuul-build-sshkey)
2026-01-21 00:09:25.789028 | controller -> localhost | ok: Runtime: 0:00:00.009108
2026-01-21 00:09:25.796358 | 
2026-01-21 00:09:25.796467 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-21 00:09:26.096455 | controller | ok
2026-01-21 00:09:26.104997 | 
2026-01-21 00:09:26.105121 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-21 00:09:26.129574 | controller | skipping: Conditional result was False
2026-01-21 00:09:26.145832 | 
2026-01-21 00:09:26.145959 | TASK [Run add-build-sshkey role (ECDSA)]
2026-01-21 00:09:26.170110 | controller | ok
2026-01-21 00:09:26.190163 | 
2026-01-21 00:09:26.190304 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-21 00:09:26.441510 | controller -> localhost | ok
2026-01-21 00:09:26.456744 | 
2026-01-21 00:09:26.456878 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-21 00:09:26.489835 | controller | ok
2026-01-21 00:09:26.505222 | controller | included: /var/lib/zuul/builds/51387024207e491dbaefbb8664a1c3fa/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-21 00:09:26.513613 | 
2026-01-21 00:09:26.513758 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-21 00:09:26.794616 | controller -> localhost | Generating public/private ecdsa key pair.
2026-01-21 00:09:26.794874 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/51387024207e491dbaefbb8664a1c3fa/work/51387024207e491dbaefbb8664a1c3fa_id_ecdsa.
2026-01-21 00:09:26.794913 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/51387024207e491dbaefbb8664a1c3fa/work/51387024207e491dbaefbb8664a1c3fa_id_ecdsa.pub.
2026-01-21 00:09:26.794945 | controller -> localhost | The key fingerprint is:
2026-01-21 00:09:26.794966 | controller -> localhost | SHA256:qESgyoz35iIjNb4Cb1aUUTxvk51oRC4ioxvWjAbOqRI zuul-build-sshkey
2026-01-21 00:09:26.794986 | controller -> localhost | The key's randomart image is:
2026-01-21 00:09:26.795007 | controller -> localhost | +---[ECDSA 521]---+
2026-01-21 00:09:26.795026 | controller -> localhost | |  .  o...        |
2026-01-21 00:09:26.795045 | controller -> localhost | | . .. o..        |
2026-01-21 00:09:26.795063 | controller -> localhost | |o o oo.+.+ .     |
2026-01-21 00:09:26.795083 | controller -> localhost | |O.=+o. oB o      |
2026-01-21 00:09:26.795101 | controller -> localhost | |EX.+. .oS.       |
2026-01-21 00:09:26.795119 | controller -> localhost | |==oo..           |
2026-01-21 00:09:26.795137 | controller -> localhost | |=+ o+            |
2026-01-21 00:09:26.795155 | controller -> localhost | |* *o             |
2026-01-21 00:09:26.795173 | controller -> localhost | |.*.o.            |
2026-01-21 00:09:26.795191 | controller -> localhost | +----[SHA256]-----+
2026-01-21 00:09:26.795246 | controller -> localhost | ok: Runtime: 0:00:00.007689
2026-01-21 00:09:26.802344 | 
2026-01-21 00:09:26.802437 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-21 00:09:26.830180 | controller | ok
2026-01-21 00:09:26.838746 | controller | included: /var/lib/zuul/builds/51387024207e491dbaefbb8664a1c3fa/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-21 00:09:26.849088 | 
2026-01-21 00:09:26.849200 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-21 00:09:26.864238 | controller | skipping: Conditional result was False
2026-01-21 00:09:26.872070 | 
2026-01-21 00:09:26.872165 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-21 00:09:27.168889 | controller | changed
2026-01-21 00:09:27.178812 | 
2026-01-21 00:09:27.178967 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-21 00:09:27.409860 | controller | ok
2026-01-21 00:09:27.420273 | 
2026-01-21 00:09:27.420423 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-21 00:09:28.017068 | controller | changed
2026-01-21 00:09:28.025912 | 
2026-01-21 00:09:28.026063 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-21 00:09:28.613907 | controller | changed
2026-01-21 00:09:28.620290 | 
2026-01-21 00:09:28.620392 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-21 00:09:28.645445 | controller | skipping: Conditional result was False
2026-01-21 00:09:28.653166 | 
2026-01-21 00:09:28.653258 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-21 00:09:28.945753 | controller -> localhost | changed
2026-01-21 00:09:28.961012 | 
2026-01-21 00:09:28.961156 | TASK [add-build-sshkey : Add back temp key]
2026-01-21 00:09:29.265906 | controller -> localhost | Identity added: /var/lib/zuul/builds/51387024207e491dbaefbb8664a1c3fa/work/51387024207e491dbaefbb8664a1c3fa_id_ecdsa (zuul-build-sshkey)
2026-01-21 00:09:29.266160 | controller -> localhost | ok: Runtime: 0:00:00.009560
2026-01-21 00:09:29.274387 | 
2026-01-21 00:09:29.274480 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-21 00:09:29.491370 | controller | ok
2026-01-21 00:09:29.498346 | 
2026-01-21 00:09:29.498465 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-21 00:09:29.523795 | controller | skipping: Conditional result was False
2026-01-21 00:09:29.544759 | 
2026-01-21 00:09:29.544896 | TASK [include_role : remove-zuul-sshkey]
2026-01-21 00:09:29.559202 | controller | skipping: Conditional result was False
2026-01-21 00:09:29.573961 | 
2026-01-21 00:09:29.574093 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-21 00:09:29.795461 | controller | ok: "logs"
2026-01-21 00:09:29.795782 | controller | ok: All items complete
2026-01-21 00:09:29.795818 | 
2026-01-21 00:09:29.981850 | controller | ok: "artifacts"
2026-01-21 00:09:30.167195 | controller | ok: "docs"
2026-01-21 00:09:30.185670 | 
2026-01-21 00:09:30.185835 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-21 00:09:30.413683 | controller | changed: "logs"
2026-01-21 00:09:30.603557 | controller | changed: "artifacts"
2026-01-21 00:09:30.796158 | controller | changed: "docs"
2026-01-21 00:09:30.862471 | 
2026-01-21 00:09:30.862575 | PLAY RECAP
2026-01-21 00:09:30.862630 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-01-21 00:09:30.862683 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-21 00:09:30.862708 | 
2026-01-21 00:09:31.015337 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-21 00:09:31.016966 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-21 00:09:31.679786 | 
2026-01-21 00:09:31.679985 | PLAY [all]
2026-01-21 00:09:31.708113 | 
2026-01-21 00:09:31.708274 | TASK [Install binary dependencies]
2026-01-21 00:09:31.761665 | controller | ok
2026-01-21 00:09:31.791522 | 
2026-01-21 00:09:31.791673 | TASK [bindep : Include find tasks]
2026-01-21 00:09:31.821112 | controller | ok
2026-01-21 00:09:31.828974 | controller | included: /var/lib/zuul/builds/51387024207e491dbaefbb8664a1c3fa/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-01-21 00:09:31.835471 | 
2026-01-21 00:09:31.835556 | TASK [bindep : Look for bindep.txt]
2026-01-21 00:09:32.274464 | controller | ok
2026-01-21 00:09:32.286422 | 
2026-01-21 00:09:32.286561 | TASK [bindep : Define bindep_file fact]
2026-01-21 00:09:32.324367 | controller | skipping: Conditional result was False
2026-01-21 00:09:32.330778 | 
2026-01-21 00:09:32.330892 | TASK [bindep : Look for other-requirements.txt]
2026-01-21 00:09:32.574751 | controller | ok
2026-01-21 00:09:32.580849 | 
2026-01-21 00:09:32.580948 | TASK [bindep : Define bindep_file fact]
2026-01-21 00:09:32.635582 | controller | skipping: Conditional result was False
2026-01-21 00:09:32.644813 | 
2026-01-21 00:09:32.644945 | TASK [bindep : Look for bindep fallback file]
2026-01-21 00:09:32.673967 | controller | skipping: Conditional result was False
2026-01-21 00:09:32.701192 | 
2026-01-21 00:09:32.701331 | TASK [bindep : Define bindep_file fact]
2026-01-21 00:09:32.748367 | controller | skipping: Conditional result was False
2026-01-21 00:09:32.755490 | 
2026-01-21 00:09:32.755601 | TASK [bindep : Include bindep tasks]
2026-01-21 00:09:32.793085 | controller | skipping: Conditional result was False
2026-01-21 00:09:32.801228 | 
2026-01-21 00:09:32.801354 | TASK [bindep : Include install tasks]
2026-01-21 00:09:32.849373 | controller | skipping: Conditional result was False
2026-01-21 00:09:32.856783 | 
2026-01-21 00:09:32.856916 | LOOP [bindep : Include package tasks]
2026-01-21 00:09:32.967095 | 
2026-01-21 00:09:32.967308 | TASK [Run test-setup role]
2026-01-21 00:09:32.991378 | controller | ok
2026-01-21 00:09:33.023187 | 
2026-01-21 00:09:33.023329 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-01-21 00:09:33.225399 | controller | ok
2026-01-21 00:09:33.239665 | 
2026-01-21 00:09:33.239851 | TASK [test-setup : Run tools/test-setup.sh]
2026-01-21 00:09:33.805494 | controller | skipping: Conditional result was False
2026-01-21 00:09:33.892995 | 
2026-01-21 00:09:33.893161 | PLAY RECAP
2026-01-21 00:09:33.893232 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-01-21 00:09:33.893262 | 
2026-01-21 00:09:34.065944 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-21 00:09:34.067273 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-01-21 00:09:34.983893 | 
2026-01-21 00:09:34.984087 | PLAY [controller]
2026-01-21 00:09:35.005487 | 
2026-01-21 00:09:35.005625 | TASK [Create the /root directory]
2026-01-21 00:09:35.586628 | controller | ok
2026-01-21 00:09:35.594221 | 
2026-01-21 00:09:35.594372 | TASK [Install glibc-langpack-en]
2026-01-21 00:09:39.562362 | controller | ok: Nothing to do
2026-01-21 00:09:39.574898 | 
2026-01-21 00:09:39.575016 | TASK [Ensure controller directory exists]
2026-01-21 00:09:39.779294 | controller | changed
2026-01-21 00:09:39.809032 | 
2026-01-21 00:09:39.809139 | TASK [Install container runtime]
2026-01-21 00:09:39.862406 | controller | ok
2026-01-21 00:09:39.919812 | 
2026-01-21 00:09:39.920316 | LOOP [ensure-docker : Gather variables for each operating system]
2026-01-21 00:09:39.962094 | controller | ok: "/var/lib/zuul/builds/51387024207e491dbaefbb8664a1c3fa/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2026-01-21 00:09:39.972854 | 
2026-01-21 00:09:39.972985 | TASK [ensure-docker : Sanity check for distro selection]
2026-01-21 00:09:39.997101 | controller | skipping: Conditional result was False
2026-01-21 00:09:40.004849 | 
2026-01-21 00:09:40.004962 | TASK [ensure-docker : Sanity check for distro install]
2026-01-21 00:09:40.029618 | controller | skipping: Conditional result was False
2026-01-21 00:09:40.037309 | 
2026-01-21 00:09:40.037421 | TASK [ensure-docker : Sanity check for upstream install]
2026-01-21 00:09:40.072037 | controller | skipping: Conditional result was False
2026-01-21 00:09:40.079756 | 
2026-01-21 00:09:40.079846 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2026-01-21 00:09:40.104263 | controller | skipping: Conditional result was False
2026-01-21 00:09:40.113146 | 
2026-01-21 00:09:40.113291 | TASK [ensure-docker : Include tasks for docker install]
2026-01-21 00:09:40.133858 | controller | ok
2026-01-21 00:09:40.155466 | controller | included: /var/lib/zuul/builds/51387024207e491dbaefbb8664a1c3fa/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2026-01-21 00:09:40.164448 | 
2026-01-21 00:09:40.164525 | TASK [ensure-docker : Not Implemented]
2026-01-21 00:09:40.178808 | controller | skipping: Conditional result was False
2026-01-21 00:09:40.188954 | 
2026-01-21 00:09:40.189072 | TASK [Set up docker mirrors]
2026-01-21 00:09:40.211165 | controller | ok
2026-01-21 00:09:40.234901 | 
2026-01-21 00:09:40.235019 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2026-01-21 00:09:40.260308 | controller | skipping: Conditional result was False
2026-01-21 00:09:40.273986 | 
2026-01-21 00:09:40.274143 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2026-01-21 00:09:40.326083 | controller | skipping: Conditional result was False
2026-01-21 00:09:40.334100 | 
2026-01-21 00:09:40.334207 | TASK [use-docker-mirror : Set docker_mirror fact]
2026-01-21 00:09:40.379050 | controller | skipping: Conditional result was False
2026-01-21 00:09:40.387235 | 
2026-01-21 00:09:40.387366 | TASK [use-docker-mirror : Set up docker mirrors]
2026-01-21 00:09:40.422858 | controller | skipping: Conditional result was False
2026-01-21 00:09:40.439914 | 
2026-01-21 00:09:40.440054 | TASK [Add all repositories]
2026-01-21 00:09:40.460303 | controller | ok
2026-01-21 00:09:40.489226 | 
2026-01-21 00:09:40.489372 | LOOP [ensure-package-repositories : Install repositories]
2026-01-21 00:09:40.518678 | controller | ok: "/var/lib/zuul/builds/51387024207e491dbaefbb8664a1c3fa/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2026-01-21 00:09:40.518940 | controller | ok: All items complete
2026-01-21 00:09:40.518979 | 
2026-01-21 00:09:40.596232 | controller | included: /var/lib/zuul/builds/51387024207e491dbaefbb8664a1c3fa/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2026-01-21 00:09:40.603791 | 
2026-01-21 00:09:40.603928 | TASK [ensure-package-repositories : Create GPG key temp directory]
2026-01-21 00:09:40.939772 | controller | changed
2026-01-21 00:09:40.947566 | 
2026-01-21 00:09:40.947689 | LOOP [ensure-package-repositories : Upload GPG keys]
2026-01-21 00:09:41.907899 | controller | changed:
2026-01-21 00:09:41.908081 | controller | {
2026-01-21 00:09:41.908107 | 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-21 00:09:41.908129 | controller | }
2026-01-21 00:09:41.926141 | 
2026-01-21 00:09:41.926263 | LOOP [ensure-package-repositories : Import GPG keys]
2026-01-21 00:09:42.580014 | controller | changed:
2026-01-21 00:09:42.580370 | controller | {
2026-01-21 00:09:42.580403 | 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-21 00:09:42.580426 | controller | }
2026-01-21 00:09:42.580457 | controller | changed: All items complete
2026-01-21 00:09:42.580476 | 
2026-01-21 00:09:42.600624 | 
2026-01-21 00:09:42.600891 | TASK [ensure-package-repositories : Remove GPG temp directory]
2026-01-21 00:09:42.843910 | controller | changed
2026-01-21 00:09:42.860729 | 
2026-01-21 00:09:42.860919 | LOOP [ensure-package-repositories : Add repositories]
2026-01-21 00:09:43.332311 | controller | changed:
2026-01-21 00:09:43.333293 | controller | {
2026-01-21 00:09:43.333325 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2026-01-21 00:09:43.333345 | controller |   "description": "Docker CE Stable - $basearch",
2026-01-21 00:09:43.333362 | controller |   "gpgcheck": true,
2026-01-21 00:09:43.333377 | controller |   "name": "docker-ce-stable"
2026-01-21 00:09:43.333393 | controller | }
2026-01-21 00:09:43.358254 | 
2026-01-21 00:09:43.358381 | TASK [ensure-docker : Install pre-reqs]
2026-01-21 00:09:46.181654 | controller | ok: Nothing to do
2026-01-21 00:09:46.191587 | 
2026-01-21 00:09:46.191714 | TASK [ensure-docker : Remove packages]
2026-01-21 00:09:47.499619 | controller | ok: Nothing to do
2026-01-21 00:09:47.509305 | 
2026-01-21 00:09:47.509478 | TASK [ensure-docker : Install upstream docker using package]
2026-01-21 00:10:34.926780 | controller | changed
2026-01-21 00:10:34.935638 | 
2026-01-21 00:10:34.935742 | TASK [ensure-docker : Setup docker]
2026-01-21 00:10:34.955767 | controller | ok
2026-01-21 00:10:35.042491 | controller | included: /var/lib/zuul/builds/51387024207e491dbaefbb8664a1c3fa/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2026-01-21 00:10:35.061071 | 
2026-01-21 00:10:35.061305 | TASK [ensure-docker : Ensure "docker" group exists]
2026-01-21 00:10:35.471164 | controller | ok
2026-01-21 00:10:35.478564 | 
2026-01-21 00:10:35.478643 | TASK [ensure-docker : Add user to docker group]
2026-01-21 00:10:35.975641 | controller | changed
2026-01-21 00:10:35.984821 | 
2026-01-21 00:10:35.984941 | TASK [Update docker daemon proxy configuration]
2026-01-21 00:10:36.022311 | controller | skipping: Conditional result was False
2026-01-21 00:10:36.035948 | 
2026-01-21 00:10:36.036077 | TASK [Lower default MTU]
2026-01-21 00:10:36.063632 | controller | skipping: Conditional result was False
2026-01-21 00:10:36.070281 | 
2026-01-21 00:10:36.070346 | TASK [ensure-docker : Get the status of the docket.socket unit]
2026-01-21 00:10:36.469766 | controller | inactive
2026-01-21 00:10:36.639166 | controller | ok: Runtime: 0:00:00.010082
2026-01-21 00:10:36.652611 | 
2026-01-21 00:10:36.652808 | TASK [ensure-docker : Get the status of the docket unit]
2026-01-21 00:10:36.916844 | controller | inactive
2026-01-21 00:10:37.210193 | controller | ok: Runtime: 0:00:00.009179
2026-01-21 00:10:37.226182 | 
2026-01-21 00:10:37.226410 | TASK [ensure-docker : Restart docker]
2026-01-21 00:10:37.290492 | Trigger a Docker restart
2026-01-21 00:10:37.305190 | 
2026-01-21 00:10:37.305369 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2026-01-21 00:10:37.342800 | 
2026-01-21 00:10:37.342944 | TASK [ensure-docker : Install docker-compose]
2026-01-21 00:10:37.369194 | controller | skipping: Conditional result was False
2026-01-21 00:10:37.378251 | 
2026-01-21 00:10:37.378355 | TASK [ensure-docker : Flush handlers before role exit]
2026-01-21 00:10:37.394083 | 
2026-01-21 00:10:37.394249 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2026-01-21 00:10:38.562505 | controller | changed
2026-01-21 00:10:38.579935 | 
2026-01-21 00:10:38.580087 | TASK [ensure-docker : Assure docker service is running]
2026-01-21 00:10:40.519640 | controller | changed
2026-01-21 00:10:40.537519 | 
2026-01-21 00:10:40.537675 | TASK [ensure-docker : Assure docker.socket service is running]
2026-01-21 00:10:41.493894 | controller | ok
2026-01-21 00:10:41.513411 | 
2026-01-21 00:10:41.513572 | TASK [ensure-docker : Correct group ownership on docker sock]
2026-01-21 00:10:42.026525 | controller | ok
2026-01-21 00:10:42.041934 | 
2026-01-21 00:10:42.042114 | TASK [ensure-docker : Validate ability to talk with docker]
2026-01-21 00:10:42.588684 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2026-01-21 00:10:42.636685 | controller | ok: Runtime: 0:00:00.044337
2026-01-21 00:10:42.644981 | 
2026-01-21 00:10:42.645066 | TASK [ensure-docker : Get version details]
2026-01-21 00:10:43.220858 | controller | Client: Docker Engine - Community
2026-01-21 00:10:43.220905 | controller |  Version:           24.0.7
2026-01-21 00:10:43.220933 | controller |  API version:       1.43
2026-01-21 00:10:43.220943 | controller |  Go version:        go1.20.10
2026-01-21 00:10:43.220957 | controller |  Git commit:        afdd53b
2026-01-21 00:10:43.220964 | controller |  Built:             Thu Oct 26 09:09:39 2023
2026-01-21 00:10:43.220971 | controller |  OS/Arch:           linux/amd64
2026-01-21 00:10:43.220979 | controller |  Context:           default
2026-01-21 00:10:43.220996 | controller |
2026-01-21 00:10:43.221003 | controller | Server: Docker Engine - Community
2026-01-21 00:10:43.221009 | controller |  Engine:
2026-01-21 00:10:43.221018 | controller |   Version:          24.0.7
2026-01-21 00:10:43.221105 | controller |   API version:      1.43 (minimum version 1.12)
2026-01-21 00:10:43.221116 | controller |   Go version:       go1.20.10
2026-01-21 00:10:43.221122 | controller |   Git commit:       311b9ff
2026-01-21 00:10:43.221138 | controller |   Built:            Thu Oct 26 09:08:11 2023
2026-01-21 00:10:43.221145 | controller |   OS/Arch:          linux/amd64
2026-01-21 00:10:43.221150 | controller |   Experimental:     false
2026-01-21 00:10:43.221156 | controller |  containerd:
2026-01-21 00:10:43.221166 | controller |   Version:          1.6.25
2026-01-21 00:10:43.222916 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2026-01-21 00:10:43.222953 | controller |  runc:
2026-01-21 00:10:43.222959 | controller |   Version:          1.1.10
2026-01-21 00:10:43.222965 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2026-01-21 00:10:43.222976 | controller |  docker-init:
2026-01-21 00:10:43.222982 | controller |   Version:          0.19.0
2026-01-21 00:10:43.222987 | controller |   GitCommit:        de40ad0
2026-01-21 00:10:43.690372 | controller | ok: Runtime: 0:00:00.077329
2026-01-21 00:10:43.711366 | 
2026-01-21 00:10:43.711644 | TASK [ensure-docker : Dump installed docker details]
2026-01-21 00:10:43.776539 | {'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-21 00:10:43.145886', 'end': '2026-01-21 00:10:43.223215', 'delta': '0:00:00.077329', 'msg': '', 'zuul_log_id': 'fa163ef9-e89a-cb80-4e1c-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-21 00:10:43.804295 | 
2026-01-21 00:10:43.804431 | TASK [Use buildset registry]
2026-01-21 00:10:43.829814 | controller | skipping: Conditional result was False
2026-01-21 00:10:43.847662 | 
2026-01-21 00:10:43.847856 | TASK [Ensure python3.8 is present]
2026-01-21 00:10:43.873817 | controller | skipping: Conditional result was False
2026-01-21 00:10:43.882965 | 
2026-01-21 00:10:43.883065 | TASK [Run our-ensure-python role (Fedora only for now)]
2026-01-21 00:10:43.916345 | controller | ok
2026-01-21 00:10:43.950862 | 
2026-01-21 00:10:43.951016 | TASK [our-ensure-python : Install the right Python version (rpm)]
2026-01-21 00:10:46.155331 | controller | ok: Nothing to do
2026-01-21 00:10:46.162697 | 
2026-01-21 00:10:46.162848 | TASK [our-ensure-python : Also install python3-devel]
2026-01-21 00:10:59.817584 | controller | changed
2026-01-21 00:10:59.840625 | 
2026-01-21 00:10:59.840783 | TASK [Run ensure-virtualenv role]
2026-01-21 00:10:59.871382 | controller | ok
2026-01-21 00:10:59.897258 | 
2026-01-21 00:10:59.897411 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2026-01-21 00:11:00.117395 | controller | /usr/bin/virtualenv
2026-01-21 00:11:00.431242 | controller | ok: Runtime: 0:00:00.005038
2026-01-21 00:11:00.440451 | 
2026-01-21 00:11:00.440610 | LOOP [ensure-virtualenv : Install virtualenv package]
2026-01-21 00:11:00.478023 | controller | skipping: Conditional result was False
2026-01-21 00:11:00.478776 | controller | changed: All items complete
2026-01-21 00:11:00.478867 | 
2026-01-21 00:11:00.501112 | 
2026-01-21 00:11:00.501242 | TASK [Find the full path of the Python interpreter]
2026-01-21 00:11:00.707323 | controller | /usr/bin/python3.8
2026-01-21 00:11:01.034920 | controller | ok
2026-01-21 00:11:01.050596 | 
2026-01-21 00:11:01.050846 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2026-01-21 00:11:02.614794 | controller | created virtual environment CPython3.8.18.final.0-64 in 764ms
2026-01-21 00:11:02.642028 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2026-01-21 00:11:02.642063 | 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-21 00:11:02.642073 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2026-01-21 00:11:02.642087 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2026-01-21 00:11:03.110119 | controller | changed
2026-01-21 00:11:03.116202 | 
2026-01-21 00:11:03.116303 | TASK [Set selinux package]
2026-01-21 00:11:03.136517 | controller | ok
2026-01-21 00:11:03.142331 | 
2026-01-21 00:11:03.142400 | TASK [Set selinux package (Fedora)]
2026-01-21 00:11:03.193624 | controller | ok
2026-01-21 00:11:03.200369 | 
2026-01-21 00:11:03.200458 | TASK [Install selinux into virtualenv]
2026-01-21 00:11:16.271673 | controller | Collecting selinux-please-lie-to-me
2026-01-21 00:11:28.585986 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2026-01-21 00:11:28.957249 | controller | Collecting setuptools<50.0.0
2026-01-21 00:11:28.963221 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2026-01-21 00:11:29.021795 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 15.1 MB/s eta 0:00:00
2026-01-21 00:11:29.099029 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2026-01-21 00:11:29.099134 | controller |   Attempting uninstall: setuptools
2026-01-21 00:11:29.099885 | controller |     Found existing installation: setuptools 62.6.0
2026-01-21 00:11:29.172295 | controller |     Uninstalling setuptools-62.6.0:
2026-01-21 00:11:29.181048 | controller |       Successfully uninstalled setuptools-62.6.0
2026-01-21 00:11:29.535257 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2026-01-21 00:11:40.969021 | controller |
2026-01-21 00:11:41.052595 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-01-21 00:11:41.052651 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-01-21 00:11:41.251665 | controller | ok: Runtime: 0:00:37.354335
2026-01-21 00:11:41.264761 | 
2026-01-21 00:11:41.264947 | TASK [Install pytest-forked into virtualenv]
2026-01-21 00:11:53.189212 | controller | Collecting pytest-forked
2026-01-21 00:12:05.515125 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2026-01-21 00:12:05.562399 | controller | Collecting py
2026-01-21 00:12:05.565840 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2026-01-21 00:12:05.590224 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 4.6 MB/s eta 0:00:00
2026-01-21 00:12:05.711710 | controller | Collecting pytest>=3.10
2026-01-21 00:12:05.717600 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2026-01-21 00:12:05.732404 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 29.2 MB/s eta 0:00:00
2026-01-21 00:12:05.790558 | controller | Collecting pluggy<2,>=1.5
2026-01-21 00:12:05.794737 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2026-01-21 00:12:05.844698 | controller | Collecting exceptiongroup>=1.0.0rc8
2026-01-21 00:12:05.848198 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2026-01-21 00:12:05.907093 | controller | Collecting packaging
2026-01-21 00:12:05.911405 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2026-01-21 00:12:05.917780 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 17.4 MB/s eta 0:00:00
2026-01-21 00:12:05.974873 | controller | Collecting tomli>=1
2026-01-21 00:12:05.978647 | controller |   Downloading tomli-2.4.0-py3-none-any.whl (14 kB)
2026-01-21 00:12:06.023563 | controller | Collecting iniconfig
2026-01-21 00:12:06.028076 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2026-01-21 00:12:06.112087 | controller | Collecting typing-extensions>=4.6.0
2026-01-21 00:12:06.116166 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2026-01-21 00:12:06.126375 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 5.4 MB/s eta 0:00:00
2026-01-21 00:12:06.189424 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2026-01-21 00:12:06.707533 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.1.0 packaging-25.0 pluggy-1.5.0 py-1.11.0 pytest-8.3.5 pytest-forked-1.6.0 tomli-2.4.0 typing-extensions-4.13.2
2026-01-21 00:12:06.713646 | controller |
2026-01-21 00:12:06.836002 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-01-21 00:12:06.836077 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-01-21 00:12:06.878618 | controller | ok: Runtime: 0:00:25.328415
2026-01-21 00:12:06.887128 | 
2026-01-21 00:12:06.887292 | TASK [Update pip]
2026-01-21 00:12:07.479776 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2026-01-21 00:12:17.901314 | controller | Collecting pip
2026-01-21 00:12:30.091909 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2026-01-21 00:12:30.154943 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 32.5 MB/s eta 0:00:00
2026-01-21 00:12:30.213470 | controller | Installing collected packages: pip
2026-01-21 00:12:30.213651 | controller |   Attempting uninstall: pip
2026-01-21 00:12:30.214302 | controller |     Found existing installation: pip 22.2.2
2026-01-21 00:12:30.390622 | controller |     Uninstalling pip-22.2.2:
2026-01-21 00:12:30.407782 | controller |       Successfully uninstalled pip-22.2.2
2026-01-21 00:12:31.244077 | controller | Successfully installed pip-25.0.1
2026-01-21 00:12:31.452131 | controller | ok: Runtime: 0:00:24.207717
2026-01-21 00:12:31.459021 | 
2026-01-21 00:12:31.459137 | TASK [Ensure pip temp directory exists in /var/tmp]
2026-01-21 00:12:31.707472 | controller | changed
2026-01-21 00:12:31.717638 | 
2026-01-21 00:12:31.717799 | TASK [Install ansible into virtualenv]
2026-01-21 00:12:32.284552 | controller | Processing ./src/github.com/ansible/ansible
2026-01-21 00:12:32.285420 | controller |   Preparing metadata (setup.py): started
2026-01-21 00:12:32.729774 | controller |   Preparing metadata (setup.py): finished with status 'done'
2026-01-21 00:12:45.186710 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2026-01-21 00:12:57.517207 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2026-01-21 00:12:57.604912 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2026-01-21 00:12:57.611807 | 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-21 00:12:57.901789 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2026-01-21 00:12:57.935643 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2026-01-21 00:12:57.956190 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (25.0)
2026-01-21 00:12:58.148603 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2026-01-21 00:12:58.156898 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2026-01-21 00:12:58.164347 | 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-21 00:12:58.240250 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2026-01-21 00:12:58.245916 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2026-01-21 00:12:58.271744 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2026-01-21 00:12:58.275905 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2026-01-21 00:12:58.290958 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2026-01-21 00:12:58.365712 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 67.1 MB/s eta 0:00:00
2026-01-21 00:12:58.371985 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2026-01-21 00:12:58.399308 | 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-21 00:12:58.415372 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 50.7 MB/s eta 0:00:00
2026-01-21 00:12:58.501649 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2026-01-21 00:12:58.523504 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2026-01-21 00:12:58.538444 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2026-01-21 00:12:58.561642 | controller | Building wheels for collected packages: ansible-base
2026-01-21 00:12:58.562286 | controller |   Building wheel for ansible-base (setup.py): started
2026-01-21 00:12:59.835606 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2026-01-21 00:12:59.838031 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=b9b57866d4de8f722d8133c4e8c979a3d92f0c83df2f15f3703df6d42e383c0c
2026-01-21 00:12:59.838236 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-68h5poke/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2026-01-21 00:12:59.847281 | controller | Successfully built ansible-base
2026-01-21 00:12:59.881009 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2026-01-21 00:13:01.266786 | 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-21 00:13:01.804969 | controller | ok: Runtime: 0:00:29.399141
2026-01-21 00:13:01.811881 | 
2026-01-21 00:13:01.812033 | TASK [Setup download-artifact-fork role]
2026-01-21 00:13:01.833539 | controller | ok
2026-01-21 00:13:01.860022 | 
2026-01-21 00:13:01.860216 | TASK [download-artifact-fork : Parse inventory response]
2026-01-21 00:13:01.906261 | controller | ok
2026-01-21 00:13:01.916173 | 
2026-01-21 00:13:01.916357 | TASK [download-artifact-fork : Fetch info from Zuul API]
2026-01-21 00:13:01.944692 | controller | skipping: Conditional result was False
2026-01-21 00:13:01.952909 | 
2026-01-21 00:13:01.953054 | TASK [download-artifact-fork : Parse build response]
2026-01-21 00:13:01.979965 | controller | skipping: Conditional result was False
2026-01-21 00:13:01.988454 | 
2026-01-21 00:13:01.988609 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2026-01-21 00:13:02.217899 | controller | changed
2026-01-21 00:13:02.228964 | 
2026-01-21 00:13:02.229119 | LOOP [download-artifact-fork : Download archive by type]
2026-01-21 00:13:02.257562 | controller | skipping: Conditional result was False
2026-01-21 00:13:02.258019 | 
2026-01-21 00:13:03.072226 | controller | changed: OK (20212 bytes)
2026-01-21 00:13:03.091063 | 
2026-01-21 00:13:03.091237 | TASK [deploy-artifacts : define __collections]
2026-01-21 00:13:03.115795 | controller | ok
2026-01-21 00:13:03.121754 | 
2026-01-21 00:13:03.121830 | LOOP [deploy-artifacts : Create list of collections]
2026-01-21 00:13:03.151985 | controller | skipping: Conditional result was False
2026-01-21 00:13:03.167152 | controller | ok:
2026-01-21 00:13:03.167296 | controller | {
2026-01-21 00:13:03.167338 | controller |   "branch": "main",
2026-01-21 00:13:03.167371 | controller |   "job": "build-ansible-collection",
2026-01-21 00:13:03.167403 | controller |   "metadata": {
2026-01-21 00:13:03.167432 | controller |     "type": "ansible_collection",
2026-01-21 00:13:03.167461 | controller |     "version": "5.0.0"
2026-01-21 00:13:03.167490 | controller |   },
2026-01-21 00:13:03.167518 | controller |   "name": "ansible.network",
2026-01-21 00:13:03.167548 | controller |   "project": "ansible-collections/ansible.network",
2026-01-21 00:13:03.167582 | controller |   "url": "https://e95bb8da617c5b968779-599d2318ead68e6acbd48cc4156cc9fe.ssl.cf5.rackcdn.com/ansible/afb623881655489d8357b6fdff541c37/artifacts/ansible-network-5.0.0.tar.gz"
2026-01-21 00:13:03.167626 | controller | }
2026-01-21 00:13:03.189917 | 
2026-01-21 00:13:03.190103 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2026-01-21 00:13:03.750618 | controller | Starting galaxy collection install process
2026-01-21 00:13:03.751207 | controller | Process install dependency map
2026-01-21 00:18:59.947151 | controller | Starting collection install process
2026-01-21 00:18:59.956327 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2026-01-21 00:18:59.956366 | controller | ansible.network (5.0.0) was installed successfully
2026-01-21 00:18:59.956441 | controller | Installing 'ansible.netcommon:8.2.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-01-21 00:18:59.956453 | 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-4044fao9f_zd/tmpddv9xbxj
2026-01-21 00:19:43.298875 | controller | ansible.netcommon (8.2.1) was installed successfully
2026-01-21 00:20:26.374056 | controller | Installing 'ansible.utils:6.0.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-01-21 00:20:26.374137 | 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-4044fao9f_zd/tmpddv9xbxj
2026-01-21 00:20:26.374181 | controller | ansible.utils (6.0.1) was installed successfully
2026-01-21 00:21:09.858201 | controller | Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2026-01-21 00:21:09.858291 | 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-4044fao9f_zd/tmpddv9xbxj
2026-01-21 00:21:09.858304 | controller | arista.eos (12.0.0) was installed successfully
2026-01-21 00:21:09.858417 | controller | Installing 'cisco.ios:11.2.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2026-01-21 00:21:09.858654 | 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-4044fao9f_zd/tmpddv9xbxj
2026-01-21 00:21:53.767699 | controller | cisco.ios (11.2.0) was installed successfully
2026-01-21 00:21:53.767805 | controller | Installing 'cisco.iosxr:12.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2026-01-21 00:21:53.767945 | 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-4044fao9f_zd/tmpddv9xbxj
2026-01-21 00:22:35.989741 | controller | cisco.iosxr (12.1.1) was installed successfully
2026-01-21 00:22:35.989840 | controller | Installing 'cisco.nxos:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2026-01-21 00:22:35.989977 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4044fao9f_zd/tmpddv9xbxj
2026-01-21 00:23:19.907686 | controller | cisco.nxos (11.1.1) was installed successfully
2026-01-21 00:23:19.907802 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2026-01-21 00:23:19.907964 | 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-4044fao9f_zd/tmpddv9xbxj
2026-01-21 00:23:59.088046 | controller | junipernetworks.junos (11.0.0) was installed successfully
2026-01-21 00:23:59.160229 | controller | ok: Runtime: 0:10:55.739272
2026-01-21 00:23:59.172437 | 
2026-01-21 00:23:59.172548 | TASK [podman may need iptables kernel modules]
2026-01-21 00:23:59.705700 | controller | ok: Runtime: 0:00:00.004974
2026-01-21 00:23:59.730825 | 
2026-01-21 00:23:59.730952 | PLAY RECAP
2026-01-21 00:23:59.730996 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2026-01-21 00:23:59.731018 | 
2026-01-21 00:23:59.881200 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-01-21 00:23:59.882180 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-01-21 00:24:00.646171 | 
2026-01-21 00:24:00.646303 | PLAY [controller]
2026-01-21 00:24:00.669871 | 
2026-01-21 00:24:00.670015 | TASK [Copy the galaxy.yml on the controller]
2026-01-21 00:24:01.572667 | controller | changed
2026-01-21 00:24:01.579664 | 
2026-01-21 00:24:01.579800 | TASK [Load information from galaxy.yml]
2026-01-21 00:24:01.613543 | controller | ok
2026-01-21 00:24:01.621848 | 
2026-01-21 00:24:01.621984 | LOOP [Enable FIPS mode]
2026-01-21 00:24:01.644311 | 
2026-01-21 00:24:01.644522 | LOOP [ansible-test : Include branch specific variables.]
2026-01-21 00:24:01.676934 | controller | ok: "/var/lib/zuul/builds/51387024207e491dbaefbb8664a1c3fa/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2026-01-21 00:24:01.677168 | 
2026-01-21 00:24:01.687102 | 
2026-01-21 00:24:01.687259 | TASK [ansible-test : Setup --skip-tags for test_options]
2026-01-21 00:24:01.732540 | controller | ok
2026-01-21 00:24:01.741749 | 
2026-01-21 00:24:01.741947 | TASK [ansible-test : Enable --inventory for network-integration]
2026-01-21 00:24:01.769172 | controller | skipping: Conditional result was False
2026-01-21 00:24:01.779672 | 
2026-01-21 00:24:01.779867 | TASK [ansible-test : Enable --retry-on-error]
2026-01-21 00:24:01.797147 | controller | skipping: Conditional result was False
2026-01-21 00:24:01.805776 | 
2026-01-21 00:24:01.805962 | TASK [ansible-test : Enable --continue-on-error]
2026-01-21 00:24:01.838845 | controller | ok
2026-01-21 00:24:01.844920 | 
2026-01-21 00:24:01.845012 | TASK [ansible-test : Enable --requirements]
2026-01-21 00:24:01.859994 | controller | skipping: Conditional result was False
2026-01-21 00:24:01.868042 | 
2026-01-21 00:24:01.868162 | TASK [ansible-test : Adjust options for unit tests]
2026-01-21 00:24:01.883251 | controller | skipping: Conditional result was False
2026-01-21 00:24:01.891243 | 
2026-01-21 00:24:01.891368 | TASK [ansible-test : Install requirements with unit tests]
2026-01-21 00:24:01.925764 | controller | ok
2026-01-21 00:24:01.934569 | 
2026-01-21 00:24:01.934715 | TASK [ansible-test : Set --skip-test]
2026-01-21 00:24:01.981219 | controller | skipping: Conditional result was False
2026-01-21 00:24:01.990454 | 
2026-01-21 00:24:01.990615 | TASK [ansible-test : Reduce verbosity]
2026-01-21 00:24:02.025426 | controller | ok
2026-01-21 00:24:02.068439 | 
2026-01-21 00:24:02.068583 | TASK [ansible-test : Setup --python option]
2026-01-21 00:24:02.094398 | controller | skipping: Conditional result was False
2026-01-21 00:24:02.103943 | 
2026-01-21 00:24:02.104080 | TASK [ansible-test : Setup --docker option]
2026-01-21 00:24:02.140008 | controller | ok
2026-01-21 00:24:02.148162 | 
2026-01-21 00:24:02.148292 | TASK [ansible-test : check if MANIFEST.json exists]
2026-01-21 00:24:02.692435 | controller | ok
2026-01-21 00:24:02.706368 | 
2026-01-21 00:24:02.706718 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2026-01-21 00:24:02.726912 | controller | skipping: Conditional result was False
2026-01-21 00:24:02.738851 | 
2026-01-21 00:24:02.739029 | TASK [ansible-test : Load information from MANIFEST.json]
2026-01-21 00:24:02.765547 | controller | skipping: Conditional result was False
2026-01-21 00:24:02.776130 | 
2026-01-21 00:24:02.776255 | TASK [ansible-test : Setup location of project for integration tests]
2026-01-21 00:24:02.801695 | controller | skipping: Conditional result was False
2026-01-21 00:24:02.811793 | 
2026-01-21 00:24:02.811961 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2026-01-21 00:24:03.434578 | controller | ok
2026-01-21 00:24:03.441074 | 
2026-01-21 00:24:03.441197 | TASK [ansible-test : Load information from galaxy.yml]
2026-01-21 00:24:03.475598 | controller | ok
2026-01-21 00:24:03.484884 | 
2026-01-21 00:24:03.485035 | TASK [ansible-test : Setup location of project for integration tests]
2026-01-21 00:24:03.519286 | controller | ok
2026-01-21 00:24:03.527453 | 
2026-01-21 00:24:03.527608 | TASK [ansible-test : Setup minimum test requirements]
2026-01-21 00:24:03.562888 | controller | ok
2026-01-21 00:24:03.572341 | 
2026-01-21 00:24:03.572543 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2026-01-21 00:24:04.242133 | controller | ok
2026-01-21 00:24:04.248716 | 
2026-01-21 00:24:04.248823 | TASK [ansible-test : Install python requirements]
2026-01-21 00:24:15.825637 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-01-21 00:24:28.076964 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2026-01-21 00:24:28.111047 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-01-21 00:24:28.116627 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2026-01-21 00:24:28.124715 | 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-21 00:24:28.140161 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2026-01-21 00:24:28.197035 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2026-01-21 00:24:28.239525 | controller | Installing collected packages: pathspec, yamllint
2026-01-21 00:24:28.300036 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2026-01-21 00:24:28.803275 | controller | ok: Runtime: 0:00:23.686134
2026-01-21 00:24:28.811425 | 
2026-01-21 00:24:28.811551 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2026-01-21 00:24:29.045767 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2026-01-21 00:24:29.345582 | controller | ERROR
2026-01-21 00:24:29.345881 | controller | {
2026-01-21 00:24:29.345928 | controller |   "delta": "0:00:00.004812",
2026-01-21 00:24:29.346275 | controller |   "end": "2026-01-21 00:24:29.045923",
2026-01-21 00:24:29.346315 | controller |   "msg": "non-zero return code",
2026-01-21 00:24:29.346341 | controller |   "rc": 1,
2026-01-21 00:24:29.346373 | controller |   "start": "2026-01-21 00:24:29.041111"
2026-01-21 00:24:29.346397 | controller | }
2026-01-21 00:24:29.346430 | controller | ERROR: Ignoring Errors
2026-01-21 00:24:29.355161 | 
2026-01-21 00:24:29.355273 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2026-01-21 00:24:29.899879 | controller | skipping: Conditional result was False
2026-01-21 00:24:29.906773 | 
2026-01-21 00:24:29.906888 | TASK [ansible-test : Enable persistent connection logging]
2026-01-21 00:24:30.574501 | controller | changed: section and option added
2026-01-21 00:24:30.584441 | 
2026-01-21 00:24:30.584606 | TASK [ansible-test : Set persistent ssh_type value]
2026-01-21 00:24:31.117420 | controller | changed: option added
2026-01-21 00:24:31.124519 | 
2026-01-21 00:24:31.124654 | TASK [ansible-test : Set ansible log path]
2026-01-21 00:24:31.620745 | controller | changed: section and option added
2026-01-21 00:24:31.631415 | 
2026-01-21 00:24:31.631578 | TASK [ansible-test : Identify the changed targets]
2026-01-21 00:24:32.183965 | controller | skipping: Conditional result was False
2026-01-21 00:24:32.257464 | 
2026-01-21 00:24:32.257642 | TASK [ansible-test : set_fact]
2026-01-21 00:24:32.304098 | controller | skipping: Conditional result was False
2026-01-21 00:24:32.311765 | 
2026-01-21 00:24:32.311862 | TASK [ansible-test : Set the targets]
2026-01-21 00:24:32.346510 | controller | ok
2026-01-21 00:24:32.353030 | 
2026-01-21 00:24:32.353111 | TASK [ansible-test : set_fact]
2026-01-21 00:24:32.379409 | controller | skipping: Conditional result was False
2026-01-21 00:24:32.390946 | 
2026-01-21 00:24:32.391182 | TASK [ansible-test : List the targets]
2026-01-21 00:24:32.441555 | controller | skipping: Conditional result was False
2026-01-21 00:24:32.449484 | 
2026-01-21 00:24:32.449621 | TASK [ansible-test : set_fact]
2026-01-21 00:24:32.464206 | controller | skipping: Conditional result was False
2026-01-21 00:24:32.472249 | 
2026-01-21 00:24:32.472390 | TASK [ansible-test : set_fact]
2026-01-21 00:24:32.498038 | controller | skipping: Conditional result was False
2026-01-21 00:24:32.505369 | 
2026-01-21 00:24:32.505490 | LOOP [ansible-test : set_fact]
2026-01-21 00:24:32.559937 | 
2026-01-21 00:24:32.560161 | TASK [ansible-test : set_fact]
2026-01-21 00:24:32.585934 | controller | skipping: Conditional result was False
2026-01-21 00:24:32.596771 | 
2026-01-21 00:24:32.596922 | TASK [ansible-test : debug]
2026-01-21 00:24:32.622114 | controller | skipping: Conditional result was False
2026-01-21 00:24:32.629632 | 
2026-01-21 00:24:32.629837 | TASK [ansible-test : debug]
2026-01-21 00:24:32.661100 | About to run: ansible-test sanity --requirements --docker -v
2026-01-21 00:24:32.666977 | 
2026-01-21 00:24:32.667069 | TASK [ansible-test : Run the test suite]
2026-01-21 00:24:33.360552 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2026-01-21 00:24:33.382411 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2026-01-21 00:24:35.085182 | controller | 2.9.0: Pulling from ansible/default-test-container
2026-01-21 00:24:35.085280 | controller | 171857c49d0f: Pulling fs layer
2026-01-21 00:24:35.085299 | controller | 419640447d26: Pulling fs layer
2026-01-21 00:24:35.085305 | controller | 61e52f862619: Pulling fs layer
2026-01-21 00:24:35.085310 | controller | 851de0426e26: Pulling fs layer
2026-01-21 00:24:35.085316 | controller | 37425716ea5b: Pulling fs layer
2026-01-21 00:24:35.085323 | controller | cd9b0c439616: Pulling fs layer
2026-01-21 00:24:35.085330 | controller | d4612dbd8855: Pulling fs layer
2026-01-21 00:24:35.085355 | controller | 554699364730: Pulling fs layer
2026-01-21 00:24:35.085361 | controller | 1ff15d4bdb3e: Pulling fs layer
2026-01-21 00:24:35.085367 | controller | 5f2e4724adea: Pulling fs layer
2026-01-21 00:24:35.085434 | controller | a02d9a95ccb8: Pulling fs layer
2026-01-21 00:24:35.085502 | controller | 989f4ce370cb: Pulling fs layer
2026-01-21 00:24:35.085511 | controller | 0d7f52cdf9df: Pulling fs layer
2026-01-21 00:24:35.085515 | controller | c423742351d5: Pulling fs layer
2026-01-21 00:24:35.085520 | controller | 109236341d21: Pulling fs layer
2026-01-21 00:24:35.085524 | controller | 07a2f61c96b8: Pulling fs layer
2026-01-21 00:24:35.085530 | controller | 041f122f27ae: Pulling fs layer
2026-01-21 00:24:35.085579 | controller | ca6f5098b12e: Pulling fs layer
2026-01-21 00:24:35.085586 | controller | 1eb3e7b2afd9: Pulling fs layer
2026-01-21 00:24:35.085590 | controller | 18a0b750727a: Pulling fs layer
2026-01-21 00:24:35.085594 | controller | 17f2f596716c: Pulling fs layer
2026-01-21 00:24:35.085601 | controller | f35a9998a9bd: Pulling fs layer
2026-01-21 00:24:35.085607 | controller | fdfc10566405: Pulling fs layer
2026-01-21 00:24:35.085656 | controller | 1ff1c442c5fe: Pulling fs layer
2026-01-21 00:24:35.085663 | controller | a3443c4a17ed: Pulling fs layer
2026-01-21 00:24:35.085668 | controller | 0edcf9fb2b15: Pulling fs layer
2026-01-21 00:24:35.085672 | controller | 5af43b69e845: Pulling fs layer
2026-01-21 00:24:35.085678 | controller | 1651773effb2: Pulling fs layer
2026-01-21 00:24:35.085735 | controller | 79e23c5c0fe4: Pulling fs layer
2026-01-21 00:24:35.085745 | controller | e5058b1faaa6: Pulling fs layer
2026-01-21 00:24:35.085749 | controller | 846775490569: Pulling fs layer
2026-01-21 00:24:35.085755 | controller | 041f122f27ae: Waiting
2026-01-21 00:24:35.085813 | controller | ca6f5098b12e: Waiting
2026-01-21 00:24:35.085820 | controller | 1eb3e7b2afd9: Waiting
2026-01-21 00:24:35.085824 | controller | 18a0b750727a: Waiting
2026-01-21 00:24:35.085828 | controller | 17f2f596716c: Waiting
2026-01-21 00:24:35.085832 | controller | f35a9998a9bd: Waiting
2026-01-21 00:24:35.085841 | controller | fdfc10566405: Waiting
2026-01-21 00:24:35.085890 | controller | 1ff1c442c5fe: Waiting
2026-01-21 00:24:35.085897 | controller | a3443c4a17ed: Waiting
2026-01-21 00:24:35.085902 | controller | 0edcf9fb2b15: Waiting
2026-01-21 00:24:35.085906 | controller | 5af43b69e845: Waiting
2026-01-21 00:24:35.085910 | controller | 1651773effb2: Waiting
2026-01-21 00:24:35.085914 | controller | 79e23c5c0fe4: Waiting
2026-01-21 00:24:35.085918 | controller | e5058b1faaa6: Waiting
2026-01-21 00:24:35.085925 | controller | 846775490569: Waiting
2026-01-21 00:24:35.085980 | controller | 1ff15d4bdb3e: Waiting
2026-01-21 00:24:35.085988 | controller | 5f2e4724adea: Waiting
2026-01-21 00:24:35.085994 | controller | a02d9a95ccb8: Waiting
2026-01-21 00:24:35.086056 | controller | 989f4ce370cb: Waiting
2026-01-21 00:24:35.086063 | controller | 0d7f52cdf9df: Waiting
2026-01-21 00:24:35.086069 | controller | c423742351d5: Waiting
2026-01-21 00:24:35.086094 | controller | 109236341d21: Waiting
2026-01-21 00:24:35.086100 | controller | 07a2f61c96b8: Waiting
2026-01-21 00:24:35.086104 | controller | 851de0426e26: Waiting
2026-01-21 00:24:35.086109 | controller | 37425716ea5b: Waiting
2026-01-21 00:24:35.086131 | controller | cd9b0c439616: Waiting
2026-01-21 00:24:35.086138 | controller | d4612dbd8855: Waiting
2026-01-21 00:24:36.005117 | controller | 554699364730: Waiting
2026-01-21 00:24:36.005165 | controller | 419640447d26: Verifying Checksum
2026-01-21 00:24:36.117574 | controller | 419640447d26: Download complete
2026-01-21 00:24:36.117598 | controller | 61e52f862619: Verifying Checksum
2026-01-21 00:24:36.455300 | controller | 61e52f862619: Download complete
2026-01-21 00:24:36.455350 | controller | 171857c49d0f: Verifying Checksum
2026-01-21 00:24:37.005386 | controller | 171857c49d0f: Download complete
2026-01-21 00:24:37.005433 | controller | 851de0426e26: Verifying Checksum
2026-01-21 00:24:37.652389 | controller | 851de0426e26: Download complete
2026-01-21 00:24:37.652445 | controller | cd9b0c439616: Verifying Checksum
2026-01-21 00:24:38.020625 | controller | cd9b0c439616: Download complete
2026-01-21 00:24:38.020674 | controller | d4612dbd8855: Verifying Checksum
2026-01-21 00:24:38.292760 | controller | d4612dbd8855: Download complete
2026-01-21 00:24:38.292823 | controller | 171857c49d0f: Pull complete
2026-01-21 00:24:38.329792 | controller | 419640447d26: Pull complete
2026-01-21 00:24:38.349231 | controller | 61e52f862619: Pull complete
2026-01-21 00:24:38.367992 | controller | 851de0426e26: Pull complete
2026-01-21 00:24:39.033069 | controller | 1ff15d4bdb3e: Verifying Checksum
2026-01-21 00:24:39.977412 | controller | 1ff15d4bdb3e: Download complete
2026-01-21 00:24:39.977465 | controller | 554699364730: Verifying Checksum
2026-01-21 00:24:40.355062 | controller | 554699364730: Download complete
2026-01-21 00:24:40.355096 | controller | 5f2e4724adea: Verifying Checksum
2026-01-21 00:24:40.355127 | controller | 5f2e4724adea: Download complete
2026-01-21 00:24:41.047110 | controller | a02d9a95ccb8: Verifying Checksum
2026-01-21 00:24:41.260555 | controller | a02d9a95ccb8: Download complete
2026-01-21 00:24:41.260600 | controller | 989f4ce370cb: Verifying Checksum
2026-01-21 00:24:41.982096 | controller | 989f4ce370cb: Download complete
2026-01-21 00:24:41.982144 | controller | 0d7f52cdf9df: Download complete
2026-01-21 00:24:42.150478 | controller | c423742351d5: Verifying Checksum
2026-01-21 00:24:43.072243 | controller | c423742351d5: Download complete
2026-01-21 00:24:43.072301 | controller | 07a2f61c96b8: Verifying Checksum
2026-01-21 00:24:44.093152 | controller | 07a2f61c96b8: Download complete
2026-01-21 00:24:44.093204 | controller | 041f122f27ae: Verifying Checksum
2026-01-21 00:24:44.773920 | controller | 041f122f27ae: Download complete
2026-01-21 00:24:44.773977 | controller | 109236341d21: Verifying Checksum
2026-01-21 00:24:44.774014 | controller | 109236341d21: Download complete
2026-01-21 00:24:45.392622 | controller | ca6f5098b12e: Verifying Checksum
2026-01-21 00:24:45.392658 | controller | ca6f5098b12e: Download complete
2026-01-21 00:24:45.730714 | controller | 1eb3e7b2afd9: Verifying Checksum
2026-01-21 00:24:45.730760 | controller | 1eb3e7b2afd9: Download complete
2026-01-21 00:24:46.415254 | controller | 18a0b750727a: Download complete
2026-01-21 00:24:46.889700 | controller | 17f2f596716c: Verifying Checksum
2026-01-21 00:24:46.889871 | controller | 17f2f596716c: Download complete
2026-01-21 00:24:47.252293 | controller | f35a9998a9bd: Verifying Checksum
2026-01-21 00:24:47.852090 | controller | f35a9998a9bd: Download complete
2026-01-21 00:24:47.852152 | controller | fdfc10566405: Verifying Checksum
2026-01-21 00:24:48.386778 | controller | fdfc10566405: Download complete
2026-01-21 00:24:48.386852 | controller | 1ff1c442c5fe: Verifying Checksum
2026-01-21 00:24:49.356864 | controller | 1ff1c442c5fe: Download complete
2026-01-21 00:24:49.357503 | controller | a3443c4a17ed: Verifying Checksum
2026-01-21 00:24:49.932990 | controller | a3443c4a17ed: Download complete
2026-01-21 00:24:49.933057 | controller | 0edcf9fb2b15: Verifying Checksum
2026-01-21 00:24:50.759219 | controller | 0edcf9fb2b15: Download complete
2026-01-21 00:24:50.759289 | controller | 5af43b69e845: Download complete
2026-01-21 00:24:51.875592 | controller | 1651773effb2: Verifying Checksum
2026-01-21 00:24:51.875754 | controller | 1651773effb2: Download complete
2026-01-21 00:24:52.515150 | controller | 79e23c5c0fe4: Verifying Checksum
2026-01-21 00:24:52.515219 | controller | 79e23c5c0fe4: Download complete
2026-01-21 00:24:53.106378 | controller | 37425716ea5b: Verifying Checksum
2026-01-21 00:24:53.106449 | controller | 37425716ea5b: Download complete
2026-01-21 00:24:53.297013 | controller | e5058b1faaa6: Verifying Checksum
2026-01-21 00:24:53.297141 | controller | e5058b1faaa6: Download complete
2026-01-21 00:24:54.851840 | controller | 846775490569: Verifying Checksum
2026-01-21 00:24:54.851924 | controller | 846775490569: Download complete
2026-01-21 00:25:04.920108 | controller | 37425716ea5b: Pull complete
2026-01-21 00:25:04.941316 | controller | cd9b0c439616: Pull complete
2026-01-21 00:25:04.959518 | controller | d4612dbd8855: Pull complete
2026-01-21 00:25:07.576965 | controller | 554699364730: Pull complete
2026-01-21 00:25:07.599284 | controller | 1ff15d4bdb3e: Pull complete
2026-01-21 00:25:07.628090 | controller | 5f2e4724adea: Pull complete
2026-01-21 00:25:07.663100 | controller | a02d9a95ccb8: Pull complete
2026-01-21 00:25:07.683695 | controller | 989f4ce370cb: Pull complete
2026-01-21 00:25:07.703447 | controller | 0d7f52cdf9df: Pull complete
2026-01-21 00:25:07.723526 | controller | c423742351d5: Pull complete
2026-01-21 00:25:08.873672 | controller | 109236341d21: Pull complete
2026-01-21 00:25:08.901242 | controller | 07a2f61c96b8: Pull complete
2026-01-21 00:25:08.921414 | controller | 041f122f27ae: Pull complete
2026-01-21 00:25:09.025433 | controller | ca6f5098b12e: Pull complete
2026-01-21 00:25:09.070003 | controller | 1eb3e7b2afd9: Pull complete
2026-01-21 00:25:09.108062 | controller | 18a0b750727a: Pull complete
2026-01-21 00:25:09.130537 | controller | 17f2f596716c: Pull complete
2026-01-21 00:25:09.157966 | controller | f35a9998a9bd: Pull complete
2026-01-21 00:25:09.179158 | controller | fdfc10566405: Pull complete
2026-01-21 00:25:09.202548 | controller | 1ff1c442c5fe: Pull complete
2026-01-21 00:25:10.149674 | controller | a3443c4a17ed: Pull complete
2026-01-21 00:25:11.314263 | controller | 0edcf9fb2b15: Pull complete
2026-01-21 00:25:13.321096 | controller | 5af43b69e845: Pull complete
2026-01-21 00:25:15.693315 | controller | 1651773effb2: Pull complete
2026-01-21 00:25:17.157236 | controller | 79e23c5c0fe4: Pull complete
2026-01-21 00:25:18.244704 | controller | e5058b1faaa6: Pull complete
2026-01-21 00:25:19.758855 | controller | 846775490569: Pull complete
2026-01-21 00:25:19.792067 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2026-01-21 00:25:19.801279 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2026-01-21 00:25:19.815172 | controller | quay.io/ansible/default-test-container:2.9.0
2026-01-21 00:25:19.819580 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2026-01-21 00:25:19.839872 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2026-01-21 00:25:21.564824 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2026-01-21 00:25:21.566060 | controller | 04a5f4cda3ee: Pulling fs layer
2026-01-21 00:25:21.566593 | controller | ff496a88c8ed: Pulling fs layer
2026-01-21 00:25:21.566633 | controller | 0ce83f459fe7: Pulling fs layer
2026-01-21 00:25:21.566648 | controller | 2e5170e1f099: Pulling fs layer
2026-01-21 00:25:21.566745 | controller | 7641eb41b08c: Pulling fs layer
2026-01-21 00:25:21.567250 | controller | 2e5170e1f099: Waiting
2026-01-21 00:25:21.567877 | controller | ad15fa9da398: Pulling fs layer
2026-01-21 00:25:21.567919 | controller | 087d91352424: Pulling fs layer
2026-01-21 00:25:21.567941 | controller | 8b92efd6a100: Pulling fs layer
2026-01-21 00:25:21.568088 | controller | 7641eb41b08c: Waiting
2026-01-21 00:25:21.568113 | controller | ad15fa9da398: Waiting
2026-01-21 00:25:21.568191 | controller | 087d91352424: Waiting
2026-01-21 00:25:21.568345 | controller | 8b92efd6a100: Waiting
2026-01-21 00:25:22.392264 | controller | ff496a88c8ed: Verifying Checksum
2026-01-21 00:25:22.392329 | controller | ff496a88c8ed: Download complete
2026-01-21 00:25:22.653374 | controller | 0ce83f459fe7: Verifying Checksum
2026-01-21 00:25:22.931460 | controller | 0ce83f459fe7: Download complete
2026-01-21 00:25:22.931523 | controller | 04a5f4cda3ee: Verifying Checksum
2026-01-21 00:25:22.932663 | controller | 04a5f4cda3ee: Download complete
2026-01-21 00:25:23.615631 | controller | 7641eb41b08c: Verifying Checksum
2026-01-21 00:25:23.754209 | controller | 7641eb41b08c: Download complete
2026-01-21 00:25:23.754245 | controller | ad15fa9da398: Verifying Checksum
2026-01-21 00:25:24.053679 | controller | ad15fa9da398: Download complete
2026-01-21 00:25:24.053738 | controller | 2e5170e1f099: Verifying Checksum
2026-01-21 00:25:24.099352 | controller | 2e5170e1f099: Download complete
2026-01-21 00:25:24.099395 | controller | 04a5f4cda3ee: Pull complete
2026-01-21 00:25:24.282690 | controller | ff496a88c8ed: Pull complete
2026-01-21 00:25:24.313809 | controller | 0ce83f459fe7: Pull complete
2026-01-21 00:25:24.547839 | controller | 087d91352424: Verifying Checksum
2026-01-21 00:25:24.701073 | controller | 087d91352424: Download complete
2026-01-21 00:25:24.701135 | controller | 8b92efd6a100: Verifying Checksum
2026-01-21 00:25:25.156249 | controller | 8b92efd6a100: Download complete
2026-01-21 00:25:25.156325 | controller | 2e5170e1f099: Pull complete
2026-01-21 00:25:25.179194 | controller | 7641eb41b08c: Pull complete
2026-01-21 00:25:25.199433 | controller | ad15fa9da398: Pull complete
2026-01-21 00:25:25.774840 | controller | 087d91352424: Pull complete
2026-01-21 00:25:25.802096 | controller | 8b92efd6a100: Pull complete
2026-01-21 00:25:25.817742 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2026-01-21 00:25:25.821335 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2026-01-21 00:25:25.826681 | controller | quay.io/ansible/pypi-test-container:1.0.0
2026-01-21 00:25:25.829709 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2026-01-21 00:25:33.790600 | controller | Run command: docker inspect b91af483c03d33003576c3c9135d0329fbab9f631f7f6462b53289fb484aa641
2026-01-21 00:25:33.828026 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2026-01-21 00:25:33.836576 | controller | Including collection: ansible.netcommon (287 files)
2026-01-21 00:25:33.836614 | controller | Including collection: ansible.network (23 files)
2026-01-21 00:25:33.844231 | controller | Including collection: ansible.utils (441 files)
2026-01-21 00:25:33.869463 | controller | Including collection: arista.eos (1022 files)
2026-01-21 00:25:33.897969 | controller | Including collection: cisco.ios (1174 files)
2026-01-21 00:25:33.925028 | controller | Including collection: cisco.iosxr (1044 files)
2026-01-21 00:25:33.972504 | controller | Including collection: cisco.nxos (1688 files)
2026-01-21 00:25:33.998939 | controller | Including collection: junipernetworks.junos (963 files)
2026-01-21 00:25:34.021904 | controller | Creating a payload archive containing 7368 files...
2026-01-21 00:25:35.517987 | controller | Created a 6006647 byte payload archive containing 7368 files in 1 seconds.
2026-01-21 00:25:35.519236 | controller | Assuming Docker is available on localhost.
2026-01-21 00:25:35.519622 | 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-21 00:25:35.952514 | controller | Run command: docker exec -i 3e9527d1706107028df420a1d47e5bc3cb3fa238f5a146d9936ab5d2b6ac409d dd of=/root/docker.sh bs=65536
2026-01-21 00:25:36.004511 | controller | Run command: docker exec 3e9527d1706107028df420a1d47e5bc3cb3fa238f5a146d9936ab5d2b6ac409d /bin/bash /root/docker.sh
2026-01-21 00:25:36.061928 | controller | Run command: docker exec -i 3e9527d1706107028df420a1d47e5bc3cb3fa238f5a146d9936ab5d2b6ac409d dd of=/root/test.tgz bs=65536
2026-01-21 00:25:36.128702 | controller | Run command: docker exec 3e9527d1706107028df420a1d47e5bc3cb3fa238f5a146d9936ab5d2b6ac409d tar oxzf /root/test.tgz -C /root
2026-01-21 00:25:36.765547 | controller | Run command: docker exec 3e9527d1706107028df420a1d47e5bc3cb3fa238f5a146d9936ab5d2b6ac409d /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-93_xkda9.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2026-01-21 00:25:37.013362 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2026-01-21 00:25:37.013425 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2026-01-21 00:25:37.013711 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2026-01-21 00:25:37.015008 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2026-01-21 00:25:37.015466 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2026-01-21 00:25:37.019939 | controller | No tests applicable.
2026-01-21 00:25:37.020080 | controller | Running sanity test 'ansible-doc' with Python 3.6
2026-01-21 00:25:37.020234 | controller | No tests applicable.
2026-01-21 00:25:37.020532 | controller | Running sanity test 'changelog' with Python 3.6
2026-01-21 00:25:37.020548 | 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-21 00:25:37.591044 | 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-21 00:25:37.963324 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2026-01-21 00:25:37.993553 | 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-21 00:25:37.994727 | 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-21 00:25:38.028827 | 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-21 00:25:38.051572 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2026-01-21 00:25:38.051613 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2026-01-21 00:25:38.051618 | controller | Removing custom PyPI hosts entries: /etc/hosts
2026-01-21 00:25:38.051630 | controller | Run command: docker exec 3e9527d1706107028df420a1d47e5bc3cb3fa238f5a146d9936ab5d2b6ac409d tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2026-01-21 00:25:38.107334 | controller | Run command: docker exec -i 3e9527d1706107028df420a1d47e5bc3cb3fa238f5a146d9936ab5d2b6ac409d dd if=/root/output.tgz bs=65536
2026-01-21 00:25:38.168382 | controller | Run command: tar oxzf /tmp/ansible-result-ho_eacuq.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2026-01-21 00:25:38.178547 | controller | Run command: docker rm -f b91af483c03d33003576c3c9135d0329fbab9f631f7f6462b53289fb484aa641
2026-01-21 00:25:38.970831 | controller | Run command: docker rm -f 3e9527d1706107028df420a1d47e5bc3cb3fa238f5a146d9936ab5d2b6ac409d
2026-01-21 00:25:39.375796 | controller | ERROR: Command "docker exec 3e9527d1706107028df420a1d47e5bc3cb3fa238f5a146d9936ab5d2b6ac409d /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-93_xkda9.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2026-01-21 00:25:39.747375 | controller | ERROR
2026-01-21 00:25:39.747673 | controller | {
2026-01-21 00:25:39.747719 | controller |   "delta": "0:01:06.262525",
2026-01-21 00:25:39.747788 | controller |   "end": "2026-01-21 00:25:39.407561",
2026-01-21 00:25:39.747817 | controller |   "msg": "non-zero return code",
2026-01-21 00:25:39.747843 | controller |   "rc": 1,
2026-01-21 00:25:39.747868 | controller |   "start": "2026-01-21 00:24:33.145036"
2026-01-21 00:25:39.747895 | controller | }
failure
2026-01-21 00:25:39.752885 | 
2026-01-21 00:25:39.753033 | PLAY RECAP
2026-01-21 00:25:39.753088 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2026-01-21 00:25:39.753124 | 
2026-01-21 00:25:39.877790 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-01-21 00:25:39.879286 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-21 00:25:40.523111 | 
2026-01-21 00:25:40.523316 | PLAY [all]
2026-01-21 00:25:40.548182 | 
2026-01-21 00:25:40.548348 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-01-21 00:25:40.998177 | controller | changed: non-zero return code
2026-01-21 00:25:41.015083 | 
2026-01-21 00:25:41.015404 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-01-21 00:25:41.032348 | controller | skipping: Conditional result was False
2026-01-21 00:25:41.042600 | 
2026-01-21 00:25:41.042779 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-01-21 00:25:41.064682 | 
2026-01-21 00:25:41.064877 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-01-21 00:25:41.089588 | 
2026-01-21 00:25:41.089840 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-01-21 00:25:41.105914 | controller | skipping: Conditional result was False
2026-01-21 00:25:41.113983 | 
2026-01-21 00:25:41.114077 | LOOP [fetch-subunit-output : Generate subunit file]
2026-01-21 00:25:41.145698 | 
2026-01-21 00:25:41.145911 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-01-21 00:25:41.170660 | controller | skipping: Conditional result was False
2026-01-21 00:25:41.178785 | 
2026-01-21 00:25:41.178936 | TASK [fetch-subunit-output : Remove the temporary file]
2026-01-21 00:25:41.205228 | controller | skipping: Conditional result was False
2026-01-21 00:25:41.212310 | 
2026-01-21 00:25:41.212413 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-01-21 00:25:41.237720 | controller | skipping: Conditional result was False
2026-01-21 00:25:41.269741 | 
2026-01-21 00:25:41.269867 | PLAY RECAP
2026-01-21 00:25:41.269912 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-01-21 00:25:41.269934 | 
2026-01-21 00:25:41.396691 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-21 00:25:41.398097 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-01-21 00:25:42.082756 | 
2026-01-21 00:25:42.082974 | PLAY [all:!appliance*]
2026-01-21 00:25:42.110519 | 
2026-01-21 00:25:42.110686 | TASK [unregister the node]
2026-01-21 00:25:42.650265 | controller | skipping: Conditional result was False
2026-01-21 00:25:42.660128 | 
2026-01-21 00:25:42.660327 | TASK [include_role : fetch-output]
2026-01-21 00:25:42.702985 | controller | ok
2026-01-21 00:25:42.727203 | 
2026-01-21 00:25:42.727358 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-21 00:25:42.772758 | controller | skipping: Conditional result was False
2026-01-21 00:25:42.782563 | 
2026-01-21 00:25:42.782754 | TASK [fetch-output : Set log path for single node]
2026-01-21 00:25:42.825163 | controller | ok
2026-01-21 00:25:42.831605 | 
2026-01-21 00:25:42.831929 | LOOP [fetch-output : Ensure local output dirs]
2026-01-21 00:25:43.260784 | controller -> localhost | ok: "/var/lib/zuul/builds/51387024207e491dbaefbb8664a1c3fa/work/logs"
2026-01-21 00:25:43.492966 | controller -> localhost | changed: "/var/lib/zuul/builds/51387024207e491dbaefbb8664a1c3fa/work/artifacts"
2026-01-21 00:25:43.715004 | controller -> localhost | changed: "/var/lib/zuul/builds/51387024207e491dbaefbb8664a1c3fa/work/docs"
2026-01-21 00:25:43.728620 | 
2026-01-21 00:25:43.728812 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-21 00:25:44.482756 | controller | changed:
2026-01-21 00:25:44.483145 | controller | .d..t...... ./
2026-01-21 00:25:44.483181 | controller | cd+++++++++ controller/
2026-01-21 00:25:44.483217 | controller | changed: All items complete
2026-01-21 00:25:44.483239 | 
2026-01-21 00:25:45.029767 | controller | changed: .d..t...... ./
2026-01-21 00:25:45.564856 | controller | changed: .d..t...... ./
2026-01-21 00:25:45.589906 | 
2026-01-21 00:25:45.590043 | TASK [include_role : fetch-output-openshift]
2026-01-21 00:25:45.604269 | controller | skipping: Conditional result was False
2026-01-21 00:25:45.611866 | 
2026-01-21 00:25:45.611986 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-21 00:25:45.648717 | controller | skipping: Conditional result was False
2026-01-21 00:25:45.660712 | controller | skipping: Conditional result was False
2026-01-21 00:25:45.700641 | 
2026-01-21 00:25:45.700783 | PLAY [localhost]
2026-01-21 00:25:45.714038 | 
2026-01-21 00:25:45.714221 | TASK [Run Zuul manifest role]
2026-01-21 00:25:45.736204 | localhost | ok
2026-01-21 00:25:45.755377 | 
2026-01-21 00:25:45.755503 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-21 00:25:46.132464 | localhost | changed
2026-01-21 00:25:46.138794 | 
2026-01-21 00:25:46.138937 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-21 00:25:46.172792 | localhost | ok
2026-01-21 00:25:46.185501 | 
2026-01-21 00:25:46.185657 | TASK [Set zuul-log-path fact]
2026-01-21 00:25:46.207492 | localhost | ok
2026-01-21 00:25:46.225916 | 
2026-01-21 00:25:46.226063 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-21 00:25:46.258841 | localhost | ok
2026-01-21 00:25:46.270913 | 
2026-01-21 00:25:46.271091 | LOOP [Run upload-logs-swift role]
2026-01-21 00:25:46.309770 | localhost | Output suppressed because no_log was given
2026-01-21 00:25:46.346545 | 
2026-01-21 00:25:46.346693 | TASK [Set zuul-log-path fact]
2026-01-21 00:25:46.372770 | localhost | skipping: Conditional result was False
2026-01-21 00:25:46.379928 | 
2026-01-21 00:25:46.380066 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-01-21 00:25:46.879261 | localhost -> localhost | ok: Runtime: 0:00:00.005988
2026-01-21 00:25:46.884917 | 
2026-01-21 00:25:46.885043 | TASK [upload-logs-swift : Upload logs to swift]