2026-01-18 00:06:56.060149 | Job console starting...
2026-01-18 00:06:56.072783 | Updating repositories
2026-01-18 00:06:56.226394 | Preparing job workspace
2026-01-18 00:07:08.294165 | Running Ansible setup...
2026-01-18 00:07:13.226998 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-18 00:07:14.045569 | 
2026-01-18 00:07:14.045774 | PLAY [localhost]
2026-01-18 00:07:14.063461 | 
2026-01-18 00:07:14.063613 | TASK [Gathering Facts]
2026-01-18 00:07:15.100502 | localhost | ok
2026-01-18 00:07:15.129316 | 
2026-01-18 00:07:15.129460 | TASK [Setup log path fact]
2026-01-18 00:07:15.158771 | localhost | ok
2026-01-18 00:07:15.174242 | 
2026-01-18 00:07:15.174346 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-18 00:07:15.212847 | localhost | ok
2026-01-18 00:07:15.222044 | 
2026-01-18 00:07:15.222153 | TASK [emit-job-header : Print job information]
2026-01-18 00:07:15.252174 | # Job Information
2026-01-18 00:07:15.252336 | Ansible Version: 2.15.12
2026-01-18 00:07:15.252368 | Job: ansible-test-sanity-docker-stable-2.10
2026-01-18 00:07:15.252388 | Pipeline: periodic
2026-01-18 00:07:15.252407 | Executor: ze01.softwarefactory-project.io
2026-01-18 00:07:15.252425 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-01-18 00:07:15.252445 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/6e2/ansible/6e290fac541542dc9ed0628f8cdac91e/
2026-01-18 00:07:15.252464 | Event ID: 9ecfb4d48ef5425f8ac323d52d7bd141
2026-01-18 00:07:15.258255 | 
2026-01-18 00:07:15.258354 | LOOP [emit-job-header : Print node information]
2026-01-18 00:07:15.361080 | localhost | ok:
2026-01-18 00:07:15.361303 | localhost | # Node Information
2026-01-18 00:07:15.361332 | localhost | Inventory Hostname: controller
2026-01-18 00:07:15.361352 | localhost | Hostname: np0005586968
2026-01-18 00:07:15.361371 | localhost | Username: zuul
2026-01-18 00:07:15.361396 | localhost | Distro: Fedora 37
2026-01-18 00:07:15.361415 | localhost | Provider: ansible-vexxhost-ca-ymq-1
2026-01-18 00:07:15.361432 | localhost | Region: ca-ymq-1
2026-01-18 00:07:15.361449 | localhost | Label: ansible-fedora-37-1vcpu
2026-01-18 00:07:15.361465 | localhost | Product Name: OpenStack Nova
2026-01-18 00:07:15.361481 | localhost | Interface IP: 162.253.55.43
2026-01-18 00:07:15.382585 | 
2026-01-18 00:07:15.382750 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-18 00:07:15.812727 | localhost -> localhost | changed
2026-01-18 00:07:15.820986 | 
2026-01-18 00:07:15.821115 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-18 00:07:16.850761 | localhost -> localhost | changed
2026-01-18 00:07:16.895089 | 
2026-01-18 00:07:16.895207 | PLAY [all:!appliance*]
2026-01-18 00:07:16.919279 | 
2026-01-18 00:07:16.919402 | TASK [include_role : start-zuul-console]
2026-01-18 00:07:16.942913 | controller | ok
2026-01-18 00:07:16.963205 | 
2026-01-18 00:07:16.963302 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-01-18 00:07:17.332092 | controller | ok
2026-01-18 00:07:17.344042 | 
2026-01-18 00:07:17.344126 | TASK [use-our-mirror : Retrieve the IP address]
2026-01-18 00:07:39.618144 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-01-18 00:07:39.631521 | 
2026-01-18 00:07:39.631696 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-01-18 00:07:40.180228 | controller | skipping: Conditional result was False
2026-01-18 00:07:40.194737 | 
2026-01-18 00:07:40.194956 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-01-18 00:07:40.224307 | controller | skipping: Conditional result was False
2026-01-18 00:07:40.241763 | 
2026-01-18 00:07:40.241978 | TASK [use-our-mirror : Create the podman configuration directory]
2026-01-18 00:07:40.269241 | controller | skipping: Conditional result was False
2026-01-18 00:07:40.284874 | 
2026-01-18 00:07:40.285093 | TASK [use-our-mirror : Copy the podman configuration]
2026-01-18 00:07:40.323255 | controller | skipping: Conditional result was False
2026-01-18 00:07:40.349382 | 
2026-01-18 00:07:40.349637 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-01-18 00:07:40.379538 | controller | skipping: Conditional result was False
2026-01-18 00:07:40.395254 | 
2026-01-18 00:07:40.395630 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-01-18 00:07:40.423889 | controller | skipping: Conditional result was False
2026-01-18 00:07:40.449898 | 
2026-01-18 00:07:40.450142 | TASK [Disable Fedora Modular]
2026-01-18 00:07:40.788071 | controller | changed
2026-01-18 00:07:40.801331 | 
2026-01-18 00:07:40.801479 | TASK [Enable EPEL]
2026-01-18 00:07:40.830059 | controller | skipping: Conditional result was False
2026-01-18 00:07:40.839988 | 
2026-01-18 00:07:40.840208 | TASK [Register the RHEL node]
2026-01-18 00:07:41.399287 | 
2026-01-18 00:07:41.399500 | TASK [Show the subscription-manager status]
2026-01-18 00:07:41.943699 | controller | skipping: Conditional result was False
2026-01-18 00:07:41.950791 | 
2026-01-18 00:07:41.950888 | TASK [Enable EPEL on RHEL]
2026-01-18 00:07:42.010302 | controller | skipping: Conditional result was False
2026-01-18 00:07:42.019226 | 
2026-01-18 00:07:42.019364 | TASK [Install git and tox]
2026-01-18 00:08:38.301051 | controller | changed
2026-01-18 00:08:38.310699 | 
2026-01-18 00:08:38.310808 | TASK [include_role : prepare-workspace]
2026-01-18 00:08:38.334238 | controller | ok
2026-01-18 00:08:38.368203 | 
2026-01-18 00:08:38.368350 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-01-18 00:08:38.623105 | controller | ok
2026-01-18 00:08:38.638568 | 
2026-01-18 00:08:38.638713 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-01-18 00:08:54.304820 | controller | Output suppressed because no_log was given
2026-01-18 00:08:54.325408 | 
2026-01-18 00:08:54.325552 | TASK [include_role : prepare-workspace-openshift]
2026-01-18 00:08:54.343897 | controller | skipping: Conditional result was False
2026-01-18 00:08:54.375482 | 
2026-01-18 00:08:54.375578 | PLAY [all:!appliance]
2026-01-18 00:08:54.398832 | 
2026-01-18 00:08:54.398991 | TASK [Run add-build-sshkey role (RSA)]
2026-01-18 00:08:54.491949 | controller | ok
2026-01-18 00:08:54.516220 | 
2026-01-18 00:08:54.516464 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-18 00:08:54.844938 | controller -> localhost | ok
2026-01-18 00:08:54.855540 | 
2026-01-18 00:08:54.855649 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-18 00:08:54.889884 | controller | ok
2026-01-18 00:08:54.910561 | controller | included: /var/lib/zuul/builds/6e290fac541542dc9ed0628f8cdac91e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-18 00:08:54.918804 | 
2026-01-18 00:08:54.918876 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-18 00:08:55.547871 | controller -> localhost | Generating public/private rsa key pair.
2026-01-18 00:08:55.548111 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/6e290fac541542dc9ed0628f8cdac91e/work/6e290fac541542dc9ed0628f8cdac91e_id_rsa.
2026-01-18 00:08:55.548151 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/6e290fac541542dc9ed0628f8cdac91e/work/6e290fac541542dc9ed0628f8cdac91e_id_rsa.pub.
2026-01-18 00:08:55.548178 | controller -> localhost | The key fingerprint is:
2026-01-18 00:08:55.548203 | controller -> localhost | SHA256:/+ArGnlT/UOOetdKMLpIzLErLeJcq562WS+YLuSCGuA zuul-build-sshkey
2026-01-18 00:08:55.548226 | controller -> localhost | The key's randomart image is:
2026-01-18 00:08:55.548249 | controller -> localhost | +---[RSA 2048]----+
2026-01-18 00:08:55.548272 | controller -> localhost | |                 |
2026-01-18 00:08:55.548296 | controller -> localhost | |                 |
2026-01-18 00:08:55.548316 | controller -> localhost | |                 |
2026-01-18 00:08:55.548335 | controller -> localhost | |           .     |
2026-01-18 00:08:55.548361 | controller -> localhost | |.       S . + .  |
2026-01-18 00:08:55.548394 | controller -> localhost | |o .    + = . B   |
2026-01-18 00:08:55.548419 | controller -> localhost | |oE   o=.B + . =. |
2026-01-18 00:08:55.548443 | controller -> localhost | |o.o.==+*o= =.....|
2026-01-18 00:08:55.548471 | controller -> localhost | |o. *X+o=+.=o.... |
2026-01-18 00:08:55.548505 | controller -> localhost | +----[SHA256]-----+
2026-01-18 00:08:55.548567 | controller -> localhost | ok: Runtime: 0:00:00.130209
2026-01-18 00:08:55.560694 | 
2026-01-18 00:08:55.560863 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-18 00:08:55.606767 | controller | ok
2026-01-18 00:08:55.622887 | controller | included: /var/lib/zuul/builds/6e290fac541542dc9ed0628f8cdac91e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-18 00:08:55.638659 | 
2026-01-18 00:08:55.638776 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-18 00:08:55.664442 | controller | skipping: Conditional result was False
2026-01-18 00:08:55.673888 | 
2026-01-18 00:08:55.673980 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-18 00:08:56.232137 | controller | changed
2026-01-18 00:08:56.243570 | 
2026-01-18 00:08:56.243722 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-18 00:08:56.516359 | controller | ok
2026-01-18 00:08:56.529995 | 
2026-01-18 00:08:56.530221 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-18 00:08:57.266868 | controller | changed
2026-01-18 00:08:57.281953 | 
2026-01-18 00:08:57.282089 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-18 00:08:57.999934 | controller | changed
2026-01-18 00:08:58.014618 | 
2026-01-18 00:08:58.014762 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-18 00:08:58.045626 | controller | skipping: Conditional result was False
2026-01-18 00:08:58.052641 | 
2026-01-18 00:08:58.052746 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-18 00:08:58.589059 | controller -> localhost | changed
2026-01-18 00:08:58.604818 | 
2026-01-18 00:08:58.604947 | TASK [add-build-sshkey : Add back temp key]
2026-01-18 00:08:58.920164 | controller -> localhost | Identity added: /var/lib/zuul/builds/6e290fac541542dc9ed0628f8cdac91e/work/6e290fac541542dc9ed0628f8cdac91e_id_rsa (zuul-build-sshkey)
2026-01-18 00:08:58.920405 | controller -> localhost | ok: Runtime: 0:00:00.008463
2026-01-18 00:08:58.931198 | 
2026-01-18 00:08:58.931332 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-18 00:08:59.317739 | controller | ok
2026-01-18 00:08:59.329865 | 
2026-01-18 00:08:59.330003 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-18 00:08:59.367081 | controller | skipping: Conditional result was False
2026-01-18 00:08:59.382688 | 
2026-01-18 00:08:59.382785 | TASK [Run add-build-sshkey role (ECDSA)]
2026-01-18 00:08:59.405339 | controller | ok
2026-01-18 00:08:59.429870 | 
2026-01-18 00:08:59.430042 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-18 00:08:59.699348 | controller -> localhost | ok
2026-01-18 00:08:59.706161 | 
2026-01-18 00:08:59.706232 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-18 00:08:59.735505 | controller | ok
2026-01-18 00:08:59.746693 | controller | included: /var/lib/zuul/builds/6e290fac541542dc9ed0628f8cdac91e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-18 00:08:59.753315 | 
2026-01-18 00:08:59.753445 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-18 00:09:00.109071 | controller -> localhost | Generating public/private ecdsa key pair.
2026-01-18 00:09:00.109296 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/6e290fac541542dc9ed0628f8cdac91e/work/6e290fac541542dc9ed0628f8cdac91e_id_ecdsa.
2026-01-18 00:09:00.109324 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/6e290fac541542dc9ed0628f8cdac91e/work/6e290fac541542dc9ed0628f8cdac91e_id_ecdsa.pub.
2026-01-18 00:09:00.109351 | controller -> localhost | The key fingerprint is:
2026-01-18 00:09:00.109370 | controller -> localhost | SHA256:6l9UC6vFO18+uRHuEvNP1ub7Mc+fEmZoiHggofcE89Y zuul-build-sshkey
2026-01-18 00:09:00.109390 | controller -> localhost | The key's randomart image is:
2026-01-18 00:09:00.109408 | controller -> localhost | +---[ECDSA 521]---+
2026-01-18 00:09:00.109425 | controller -> localhost | |                 |
2026-01-18 00:09:00.109442 | controller -> localhost | |   +             |
2026-01-18 00:09:00.109459 | controller -> localhost | |  . = .   . .    |
2026-01-18 00:09:00.109475 | controller -> localhost | | . o = E . + .   |
2026-01-18 00:09:00.109491 | controller -> localhost | |  . = o S * o .  |
2026-01-18 00:09:00.109507 | controller -> localhost | |     o + = +o= ..|
2026-01-18 00:09:00.109523 | controller -> localhost | |      o . = o+=+=|
2026-01-18 00:09:00.109539 | controller -> localhost | |     .   . o.=+B=|
2026-01-18 00:09:00.109555 | controller -> localhost | |      ...   ..=*@|
2026-01-18 00:09:00.109571 | controller -> localhost | +----[SHA256]-----+
2026-01-18 00:09:00.109996 | controller -> localhost | ok: Runtime: 0:00:00.011897
2026-01-18 00:09:00.116906 | 
2026-01-18 00:09:00.116970 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-18 00:09:00.151933 | controller | ok
2026-01-18 00:09:00.163695 | controller | included: /var/lib/zuul/builds/6e290fac541542dc9ed0628f8cdac91e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-18 00:09:00.180182 | 
2026-01-18 00:09:00.180347 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-18 00:09:00.218618 | controller | skipping: Conditional result was False
2026-01-18 00:09:00.228583 | 
2026-01-18 00:09:00.228716 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-18 00:09:00.544700 | controller | changed
2026-01-18 00:09:00.551315 | 
2026-01-18 00:09:00.551391 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-18 00:09:00.765804 | controller | ok
2026-01-18 00:09:00.772052 | 
2026-01-18 00:09:00.772129 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-18 00:09:01.432412 | controller | changed
2026-01-18 00:09:01.441463 | 
2026-01-18 00:09:01.441607 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-18 00:09:02.090290 | controller | changed
2026-01-18 00:09:02.097693 | 
2026-01-18 00:09:02.097800 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-18 00:09:02.133247 | controller | skipping: Conditional result was False
2026-01-18 00:09:02.140242 | 
2026-01-18 00:09:02.140322 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-18 00:09:02.497541 | controller -> localhost | changed
2026-01-18 00:09:02.524008 | 
2026-01-18 00:09:02.524249 | TASK [add-build-sshkey : Add back temp key]
2026-01-18 00:09:02.836224 | controller -> localhost | Identity added: /var/lib/zuul/builds/6e290fac541542dc9ed0628f8cdac91e/work/6e290fac541542dc9ed0628f8cdac91e_id_ecdsa (zuul-build-sshkey)
2026-01-18 00:09:02.836520 | controller -> localhost | ok: Runtime: 0:00:00.010159
2026-01-18 00:09:02.843367 | 
2026-01-18 00:09:02.843431 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-18 00:09:03.035728 | controller | ok
2026-01-18 00:09:03.042705 | 
2026-01-18 00:09:03.042831 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-18 00:09:03.067156 | controller | skipping: Conditional result was False
2026-01-18 00:09:03.133585 | 
2026-01-18 00:09:03.133683 | TASK [include_role : remove-zuul-sshkey]
2026-01-18 00:09:03.147796 | controller | skipping: Conditional result was False
2026-01-18 00:09:03.155688 | 
2026-01-18 00:09:03.155784 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-18 00:09:03.379926 | controller | ok: "logs"
2026-01-18 00:09:03.380605 | controller | ok: All items complete
2026-01-18 00:09:03.380692 | 
2026-01-18 00:09:03.570970 | controller | ok: "artifacts"
2026-01-18 00:09:03.764549 | controller | ok: "docs"
2026-01-18 00:09:03.784815 | 
2026-01-18 00:09:03.784982 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-18 00:09:04.023919 | controller | changed: "logs"
2026-01-18 00:09:04.217060 | controller | changed: "artifacts"
2026-01-18 00:09:04.404488 | controller | changed: "docs"
2026-01-18 00:09:04.448701 | 
2026-01-18 00:09:04.448828 | PLAY RECAP
2026-01-18 00:09:04.448888 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-01-18 00:09:04.448924 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-18 00:09:04.448950 | 
2026-01-18 00:09:04.572228 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-18 00:09:04.573381 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-18 00:09:05.196147 | 
2026-01-18 00:09:05.196298 | PLAY [all]
2026-01-18 00:09:05.222075 | 
2026-01-18 00:09:05.222227 | TASK [Install binary dependencies]
2026-01-18 00:09:05.279355 | controller | ok
2026-01-18 00:09:05.306610 | 
2026-01-18 00:09:05.306799 | TASK [bindep : Include find tasks]
2026-01-18 00:09:05.339719 | controller | ok
2026-01-18 00:09:05.354698 | controller | included: /var/lib/zuul/builds/6e290fac541542dc9ed0628f8cdac91e/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-01-18 00:09:05.362069 | 
2026-01-18 00:09:05.362163 | TASK [bindep : Look for bindep.txt]
2026-01-18 00:09:05.730874 | controller | ok
2026-01-18 00:09:05.738383 | 
2026-01-18 00:09:05.738496 | TASK [bindep : Define bindep_file fact]
2026-01-18 00:09:05.764345 | controller | skipping: Conditional result was False
2026-01-18 00:09:05.771806 | 
2026-01-18 00:09:05.772035 | TASK [bindep : Look for other-requirements.txt]
2026-01-18 00:09:05.970118 | controller | ok
2026-01-18 00:09:05.981734 | 
2026-01-18 00:09:05.981881 | TASK [bindep : Define bindep_file fact]
2026-01-18 00:09:06.006165 | controller | skipping: Conditional result was False
2026-01-18 00:09:06.012510 | 
2026-01-18 00:09:06.012584 | TASK [bindep : Look for bindep fallback file]
2026-01-18 00:09:06.037290 | controller | skipping: Conditional result was False
2026-01-18 00:09:06.045108 | 
2026-01-18 00:09:06.045207 | TASK [bindep : Define bindep_file fact]
2026-01-18 00:09:06.071249 | controller | skipping: Conditional result was False
2026-01-18 00:09:06.079124 | 
2026-01-18 00:09:06.079247 | TASK [bindep : Include bindep tasks]
2026-01-18 00:09:06.103191 | controller | skipping: Conditional result was False
2026-01-18 00:09:06.109892 | 
2026-01-18 00:09:06.110045 | TASK [bindep : Include install tasks]
2026-01-18 00:09:06.134253 | controller | skipping: Conditional result was False
2026-01-18 00:09:06.141099 | 
2026-01-18 00:09:06.141259 | LOOP [bindep : Include package tasks]
2026-01-18 00:09:06.202772 | 
2026-01-18 00:09:06.202990 | TASK [Run test-setup role]
2026-01-18 00:09:06.227550 | controller | ok
2026-01-18 00:09:06.244337 | 
2026-01-18 00:09:06.244411 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-01-18 00:09:06.448176 | controller | ok
2026-01-18 00:09:06.454132 | 
2026-01-18 00:09:06.454196 | TASK [test-setup : Run tools/test-setup.sh]
2026-01-18 00:09:06.986476 | controller | skipping: Conditional result was False
2026-01-18 00:09:07.040111 | 
2026-01-18 00:09:07.040265 | PLAY RECAP
2026-01-18 00:09:07.040343 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-01-18 00:09:07.040384 | 
2026-01-18 00:09:07.211594 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-18 00:09:07.212900 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-01-18 00:09:07.894928 | 
2026-01-18 00:09:07.895085 | PLAY [controller]
2026-01-18 00:09:07.916771 | 
2026-01-18 00:09:07.917062 | TASK [Create the /root directory]
2026-01-18 00:09:08.286148 | controller | ok
2026-01-18 00:09:08.292765 | 
2026-01-18 00:09:08.292859 | TASK [Install glibc-langpack-en]
2026-01-18 00:09:12.374697 | controller | ok: Nothing to do
2026-01-18 00:09:12.381210 | 
2026-01-18 00:09:12.381297 | TASK [Ensure controller directory exists]
2026-01-18 00:09:12.603800 | controller | changed
2026-01-18 00:09:12.619009 | 
2026-01-18 00:09:12.619245 | TASK [Install container runtime]
2026-01-18 00:09:12.680794 | controller | ok
2026-01-18 00:09:12.742604 | 
2026-01-18 00:09:12.742719 | LOOP [ensure-docker : Gather variables for each operating system]
2026-01-18 00:09:12.799085 | controller | ok: "/var/lib/zuul/builds/6e290fac541542dc9ed0628f8cdac91e/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2026-01-18 00:09:12.820213 | 
2026-01-18 00:09:12.820355 | TASK [ensure-docker : Sanity check for distro selection]
2026-01-18 00:09:12.845676 | controller | skipping: Conditional result was False
2026-01-18 00:09:12.856298 | 
2026-01-18 00:09:12.856482 | TASK [ensure-docker : Sanity check for distro install]
2026-01-18 00:09:12.883302 | controller | skipping: Conditional result was False
2026-01-18 00:09:12.893229 | 
2026-01-18 00:09:12.893376 | TASK [ensure-docker : Sanity check for upstream install]
2026-01-18 00:09:12.918982 | controller | skipping: Conditional result was False
2026-01-18 00:09:12.931618 | 
2026-01-18 00:09:12.931756 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2026-01-18 00:09:12.957190 | controller | skipping: Conditional result was False
2026-01-18 00:09:12.967980 | 
2026-01-18 00:09:12.968122 | TASK [ensure-docker : Include tasks for docker install]
2026-01-18 00:09:12.992692 | controller | ok
2026-01-18 00:09:13.007110 | controller | included: /var/lib/zuul/builds/6e290fac541542dc9ed0628f8cdac91e/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2026-01-18 00:09:13.020727 | 
2026-01-18 00:09:13.020859 | TASK [ensure-docker : Not Implemented]
2026-01-18 00:09:13.036086 | controller | skipping: Conditional result was False
2026-01-18 00:09:13.042947 | 
2026-01-18 00:09:13.043028 | TASK [Set up docker mirrors]
2026-01-18 00:09:13.062905 | controller | ok
2026-01-18 00:09:13.085361 | 
2026-01-18 00:09:13.085478 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2026-01-18 00:09:13.109780 | controller | skipping: Conditional result was False
2026-01-18 00:09:13.116950 | 
2026-01-18 00:09:13.117102 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2026-01-18 00:09:13.141447 | controller | skipping: Conditional result was False
2026-01-18 00:09:13.149332 | 
2026-01-18 00:09:13.149477 | TASK [use-docker-mirror : Set docker_mirror fact]
2026-01-18 00:09:13.174193 | controller | skipping: Conditional result was False
2026-01-18 00:09:13.181217 | 
2026-01-18 00:09:13.181304 | TASK [use-docker-mirror : Set up docker mirrors]
2026-01-18 00:09:13.206133 | controller | skipping: Conditional result was False
2026-01-18 00:09:13.220785 | 
2026-01-18 00:09:13.220905 | TASK [Add all repositories]
2026-01-18 00:09:13.240775 | controller | ok
2026-01-18 00:09:13.266612 | 
2026-01-18 00:09:13.266726 | LOOP [ensure-package-repositories : Install repositories]
2026-01-18 00:09:13.292734 | controller | ok: "/var/lib/zuul/builds/6e290fac541542dc9ed0628f8cdac91e/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2026-01-18 00:09:13.292893 | controller | ok: All items complete
2026-01-18 00:09:13.292919 | 
2026-01-18 00:09:13.336783 | controller | included: /var/lib/zuul/builds/6e290fac541542dc9ed0628f8cdac91e/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2026-01-18 00:09:13.343993 | 
2026-01-18 00:09:13.344094 | TASK [ensure-package-repositories : Create GPG key temp directory]
2026-01-18 00:09:13.670423 | controller | changed
2026-01-18 00:09:13.683741 | 
2026-01-18 00:09:13.684053 | LOOP [ensure-package-repositories : Upload GPG keys]
2026-01-18 00:09:14.660261 | controller | changed:
2026-01-18 00:09:14.660480 | controller | {
2026-01-18 00:09:14.660517 | 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-18 00:09:14.660542 | controller | }
2026-01-18 00:09:14.675482 | 
2026-01-18 00:09:14.675596 | LOOP [ensure-package-repositories : Import GPG keys]
2026-01-18 00:09:15.371111 | controller | changed:
2026-01-18 00:09:15.371320 | controller | {
2026-01-18 00:09:15.371353 | 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-18 00:09:15.371376 | controller | }
2026-01-18 00:09:15.371413 | controller | changed: All items complete
2026-01-18 00:09:15.371461 | 
2026-01-18 00:09:15.392922 | 
2026-01-18 00:09:15.393169 | TASK [ensure-package-repositories : Remove GPG temp directory]
2026-01-18 00:09:15.617277 | controller | changed
2026-01-18 00:09:15.631138 | 
2026-01-18 00:09:15.631251 | LOOP [ensure-package-repositories : Add repositories]
2026-01-18 00:09:15.988838 | controller | changed:
2026-01-18 00:09:15.989008 | controller | {
2026-01-18 00:09:15.989056 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2026-01-18 00:09:15.989077 | controller |   "description": "Docker CE Stable - $basearch",
2026-01-18 00:09:15.989095 | controller |   "gpgcheck": true,
2026-01-18 00:09:15.989111 | controller |   "name": "docker-ce-stable"
2026-01-18 00:09:15.989127 | controller | }
2026-01-18 00:09:16.018579 | 
2026-01-18 00:09:16.018791 | TASK [ensure-docker : Install pre-reqs]
2026-01-18 00:09:19.039128 | controller | ok: Nothing to do
2026-01-18 00:09:19.054292 | 
2026-01-18 00:09:19.054538 | TASK [ensure-docker : Remove packages]
2026-01-18 00:09:20.407486 | controller | ok: Nothing to do
2026-01-18 00:09:20.421659 | 
2026-01-18 00:09:20.421882 | TASK [ensure-docker : Install upstream docker using package]
2026-01-18 00:10:08.487652 | controller | changed
2026-01-18 00:10:08.497276 | 
2026-01-18 00:10:08.497424 | TASK [ensure-docker : Setup docker]
2026-01-18 00:10:08.524490 | controller | ok
2026-01-18 00:10:08.539460 | controller | included: /var/lib/zuul/builds/6e290fac541542dc9ed0628f8cdac91e/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2026-01-18 00:10:08.554143 | 
2026-01-18 00:10:08.554309 | TASK [ensure-docker : Ensure "docker" group exists]
2026-01-18 00:10:08.949961 | controller | ok
2026-01-18 00:10:08.958543 | 
2026-01-18 00:10:08.958709 | TASK [ensure-docker : Add user to docker group]
2026-01-18 00:10:09.490933 | controller | changed
2026-01-18 00:10:09.500913 | 
2026-01-18 00:10:09.501102 | TASK [Update docker daemon proxy configuration]
2026-01-18 00:10:09.537621 | controller | skipping: Conditional result was False
2026-01-18 00:10:09.548304 | 
2026-01-18 00:10:09.548465 | TASK [Lower default MTU]
2026-01-18 00:10:09.573919 | controller | skipping: Conditional result was False
2026-01-18 00:10:09.585117 | 
2026-01-18 00:10:09.585274 | TASK [ensure-docker : Get the status of the docket.socket unit]
2026-01-18 00:10:09.988162 | controller | inactive
2026-01-18 00:10:10.124151 | controller | ok: Runtime: 0:00:00.015031
2026-01-18 00:10:10.134220 | 
2026-01-18 00:10:10.134348 | TASK [ensure-docker : Get the status of the docket unit]
2026-01-18 00:10:10.370655 | controller | inactive
2026-01-18 00:10:10.681651 | controller | ok: Runtime: 0:00:00.012022
2026-01-18 00:10:10.689315 | 
2026-01-18 00:10:10.689406 | TASK [ensure-docker : Restart docker]
2026-01-18 00:10:10.731139 | Trigger a Docker restart
2026-01-18 00:10:10.737398 | 
2026-01-18 00:10:10.737504 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2026-01-18 00:10:10.770118 | 
2026-01-18 00:10:10.770253 | TASK [ensure-docker : Install docker-compose]
2026-01-18 00:10:10.785833 | controller | skipping: Conditional result was False
2026-01-18 00:10:10.795908 | 
2026-01-18 00:10:10.796130 | TASK [ensure-docker : Flush handlers before role exit]
2026-01-18 00:10:10.808828 | 
2026-01-18 00:10:10.808968 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2026-01-18 00:10:12.058498 | controller | changed
2026-01-18 00:10:12.068152 | 
2026-01-18 00:10:12.068268 | TASK [ensure-docker : Assure docker service is running]
2026-01-18 00:10:14.071214 | controller | changed
2026-01-18 00:10:14.084136 | 
2026-01-18 00:10:14.084302 | TASK [ensure-docker : Assure docker.socket service is running]
2026-01-18 00:10:15.036604 | controller | ok
2026-01-18 00:10:15.053920 | 
2026-01-18 00:10:15.054099 | TASK [ensure-docker : Correct group ownership on docker sock]
2026-01-18 00:10:15.646224 | controller | ok
2026-01-18 00:10:15.673404 | 
2026-01-18 00:10:15.673571 | TASK [ensure-docker : Validate ability to talk with docker]
2026-01-18 00:10:16.272553 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2026-01-18 00:10:16.723583 | controller | ok: Runtime: 0:00:00.046252
2026-01-18 00:10:16.731110 | 
2026-01-18 00:10:16.731218 | TASK [ensure-docker : Get version details]
2026-01-18 00:10:17.324597 | controller | Client: Docker Engine - Community
2026-01-18 00:10:17.325709 | controller |  Version:           24.0.7
2026-01-18 00:10:17.325720 | controller |  API version:       1.43
2026-01-18 00:10:17.325726 | controller |  Go version:        go1.20.10
2026-01-18 00:10:17.325737 | controller |  Git commit:        afdd53b
2026-01-18 00:10:17.325742 | controller |  Built:             Thu Oct 26 09:09:39 2023
2026-01-18 00:10:17.325747 | controller |  OS/Arch:           linux/amd64
2026-01-18 00:10:17.325753 | controller |  Context:           default
2026-01-18 00:10:17.325759 | controller |
2026-01-18 00:10:17.325764 | controller | Server: Docker Engine - Community
2026-01-18 00:10:17.325768 | controller |  Engine:
2026-01-18 00:10:17.325772 | controller |   Version:          24.0.7
2026-01-18 00:10:17.325776 | controller |   API version:      1.43 (minimum version 1.12)
2026-01-18 00:10:17.325781 | controller |   Go version:       go1.20.10
2026-01-18 00:10:17.325785 | controller |   Git commit:       311b9ff
2026-01-18 00:10:17.325789 | controller |   Built:            Thu Oct 26 09:08:11 2023
2026-01-18 00:10:17.325793 | controller |   OS/Arch:          linux/amd64
2026-01-18 00:10:17.325798 | controller |   Experimental:     false
2026-01-18 00:10:17.325802 | controller |  containerd:
2026-01-18 00:10:17.325806 | controller |   Version:          1.6.25
2026-01-18 00:10:17.325810 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2026-01-18 00:10:17.325814 | controller |  runc:
2026-01-18 00:10:17.325818 | controller |   Version:          1.1.10
2026-01-18 00:10:17.325823 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2026-01-18 00:10:17.325828 | controller |  docker-init:
2026-01-18 00:10:17.325833 | controller |   Version:          0.19.0
2026-01-18 00:10:17.325837 | controller |   GitCommit:        de40ad0
2026-01-18 00:10:17.772508 | controller | ok: Runtime: 0:00:00.066254
2026-01-18 00:10:17.784282 | 
2026-01-18 00:10:17.784412 | TASK [ensure-docker : Dump installed docker details]
2026-01-18 00:10:17.814399 | {'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-18 00:10:17.259788', 'end': '2026-01-18 00:10:17.326042', 'delta': '0:00:00.066254', 'msg': '', 'zuul_log_id': 'fa163ec2-ffbe-09cf-968b-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-18 00:10:17.821282 | 
2026-01-18 00:10:17.821374 | TASK [Use buildset registry]
2026-01-18 00:10:17.835587 | controller | skipping: Conditional result was False
2026-01-18 00:10:17.847742 | 
2026-01-18 00:10:17.847859 | TASK [Ensure python3.8 is present]
2026-01-18 00:10:17.862140 | controller | skipping: Conditional result was False
2026-01-18 00:10:17.870159 | 
2026-01-18 00:10:17.870287 | TASK [Run our-ensure-python role (Fedora only for now)]
2026-01-18 00:10:17.890816 | controller | ok
2026-01-18 00:10:17.911490 | 
2026-01-18 00:10:17.911624 | TASK [our-ensure-python : Install the right Python version (rpm)]
2026-01-18 00:10:20.037562 | controller | ok: Nothing to do
2026-01-18 00:10:20.051724 | 
2026-01-18 00:10:20.051904 | TASK [our-ensure-python : Also install python3-devel]
2026-01-18 00:10:33.095040 | controller | changed
2026-01-18 00:10:33.114917 | 
2026-01-18 00:10:33.115060 | TASK [Run ensure-virtualenv role]
2026-01-18 00:10:33.175490 | controller | ok
2026-01-18 00:10:33.226805 | 
2026-01-18 00:10:33.226935 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2026-01-18 00:10:33.474230 | controller | /usr/bin/virtualenv
2026-01-18 00:10:33.799937 | controller | ok: Runtime: 0:00:00.003278
2026-01-18 00:10:33.806580 | 
2026-01-18 00:10:33.806662 | LOOP [ensure-virtualenv : Install virtualenv package]
2026-01-18 00:10:33.831512 | controller | skipping: Conditional result was False
2026-01-18 00:10:33.831947 | controller | changed: All items complete
2026-01-18 00:10:33.831987 | 
2026-01-18 00:10:33.861418 | 
2026-01-18 00:10:33.861545 | TASK [Find the full path of the Python interpreter]
2026-01-18 00:10:34.084644 | controller | /usr/bin/python3.8
2026-01-18 00:10:34.405451 | controller | ok
2026-01-18 00:10:34.413391 | 
2026-01-18 00:10:34.413513 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2026-01-18 00:10:36.142029 | controller | created virtual environment CPython3.8.18.final.0-64 in 821ms
2026-01-18 00:10:36.158367 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2026-01-18 00:10:36.158408 | 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-18 00:10:36.158417 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2026-01-18 00:10:36.158431 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2026-01-18 00:10:36.499218 | controller | changed
2026-01-18 00:10:36.508821 | 
2026-01-18 00:10:36.508943 | TASK [Set selinux package]
2026-01-18 00:10:36.546872 | controller | ok
2026-01-18 00:10:36.564661 | 
2026-01-18 00:10:36.564856 | TASK [Set selinux package (Fedora)]
2026-01-18 00:10:36.602669 | controller | ok
2026-01-18 00:10:36.610344 | 
2026-01-18 00:10:36.610425 | TASK [Install selinux into virtualenv]
2026-01-18 00:10:49.651159 | controller | Collecting selinux-please-lie-to-me
2026-01-18 00:11:01.966614 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2026-01-18 00:11:02.333944 | controller | Collecting setuptools<50.0.0
2026-01-18 00:11:02.340171 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2026-01-18 00:11:02.402985 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 14.3 MB/s eta 0:00:00
2026-01-18 00:11:02.481054 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2026-01-18 00:11:02.481314 | controller |   Attempting uninstall: setuptools
2026-01-18 00:11:02.482050 | controller |     Found existing installation: setuptools 62.6.0
2026-01-18 00:11:02.555064 | controller |     Uninstalling setuptools-62.6.0:
2026-01-18 00:11:02.563919 | controller |       Successfully uninstalled setuptools-62.6.0
2026-01-18 00:11:02.893167 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2026-01-18 00:11:14.340378 | controller |
2026-01-18 00:11:14.419390 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-01-18 00:11:14.419432 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-01-18 00:11:14.693508 | controller | ok: Runtime: 0:00:37.383545
2026-01-18 00:11:14.699644 | 
2026-01-18 00:11:14.699713 | TASK [Install pytest-forked into virtualenv]
2026-01-18 00:11:26.579804 | controller | Collecting pytest-forked
2026-01-18 00:11:36.781669 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2026-01-18 00:11:36.924596 | controller | Collecting pytest>=3.10
2026-01-18 00:11:36.935249 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2026-01-18 00:11:36.972941 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 10.7 MB/s eta 0:00:00
2026-01-18 00:11:37.012956 | controller | Collecting py
2026-01-18 00:11:37.019008 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2026-01-18 00:11:37.027066 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 17.3 MB/s eta 0:00:00
2026-01-18 00:11:37.104774 | controller | Collecting tomli>=1
2026-01-18 00:11:37.109297 | controller |   Downloading tomli-2.4.0-py3-none-any.whl (14 kB)
2026-01-18 00:11:37.147461 | controller | Collecting exceptiongroup>=1.0.0rc8
2026-01-18 00:11:37.151379 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2026-01-18 00:11:37.180302 | controller | Collecting iniconfig
2026-01-18 00:11:37.186233 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2026-01-18 00:11:37.235293 | controller | Collecting pluggy<2,>=1.5
2026-01-18 00:11:37.240675 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2026-01-18 00:11:37.292040 | controller | Collecting packaging
2026-01-18 00:11:37.295814 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2026-01-18 00:11:37.302998 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 11.9 MB/s eta 0:00:00
2026-01-18 00:11:37.348014 | controller | Collecting typing-extensions>=4.6.0
2026-01-18 00:11:37.352057 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2026-01-18 00:11:37.359161 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 7.4 MB/s eta 0:00:00
2026-01-18 00:11:37.416741 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2026-01-18 00:11:37.852525 | 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-18 00:11:37.857895 | controller |
2026-01-18 00:11:37.929871 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-01-18 00:11:37.929910 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-01-18 00:11:38.293943 | controller | ok: Runtime: 0:00:22.982373
2026-01-18 00:11:38.304518 | 
2026-01-18 00:11:38.304686 | TASK [Update pip]
2026-01-18 00:11:38.837562 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2026-01-18 00:11:49.162014 | controller | Collecting pip
2026-01-18 00:12:01.369020 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2026-01-18 00:12:01.449129 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 24.7 MB/s eta 0:00:00
2026-01-18 00:12:01.507332 | controller | Installing collected packages: pip
2026-01-18 00:12:01.507426 | controller |   Attempting uninstall: pip
2026-01-18 00:12:01.508037 | controller |     Found existing installation: pip 22.2.2
2026-01-18 00:12:01.682724 | controller |     Uninstalling pip-22.2.2:
2026-01-18 00:12:01.700123 | controller |       Successfully uninstalled pip-22.2.2
2026-01-18 00:12:02.500125 | controller | Successfully installed pip-25.0.1
2026-01-18 00:12:02.883868 | controller | ok: Runtime: 0:00:24.028398
2026-01-18 00:12:02.892818 | 
2026-01-18 00:12:02.892951 | TASK [Ensure pip temp directory exists in /var/tmp]
2026-01-18 00:12:03.110738 | controller | changed
2026-01-18 00:12:03.118356 | 
2026-01-18 00:12:03.118503 | TASK [Install ansible into virtualenv]
2026-01-18 00:12:03.643198 | controller | Processing ./src/github.com/ansible/ansible
2026-01-18 00:12:03.643953 | controller |   Preparing metadata (setup.py): started
2026-01-18 00:12:04.064686 | controller |   Preparing metadata (setup.py): finished with status 'done'
2026-01-18 00:12:13.656761 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2026-01-18 00:12:25.907772 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2026-01-18 00:12:25.972624 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2026-01-18 00:12:25.977852 | 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-18 00:12:26.234505 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2026-01-18 00:12:26.239595 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2026-01-18 00:12:26.257643 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (25.0)
2026-01-18 00:12:26.388308 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2026-01-18 00:12:26.393855 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2026-01-18 00:12:26.400183 | 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-18 00:12:26.469962 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2026-01-18 00:12:26.475727 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2026-01-18 00:12:26.496642 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2026-01-18 00:12:26.502470 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2026-01-18 00:12:26.530494 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2026-01-18 00:12:26.659384 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 35.8 MB/s eta 0:00:00
2026-01-18 00:12:26.664992 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2026-01-18 00:12:26.680352 | 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-18 00:12:26.714997 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 17.3 MB/s eta 0:00:00
2026-01-18 00:12:26.719924 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2026-01-18 00:12:26.754159 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2026-01-18 00:12:26.763657 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2026-01-18 00:12:26.787062 | controller | Building wheels for collected packages: ansible-base
2026-01-18 00:12:26.787706 | controller |   Building wheel for ansible-base (setup.py): started
2026-01-18 00:12:28.024210 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2026-01-18 00:12:28.025835 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=11a07ca37bc6cbcec03891f498a5277caad828b10b85436ae97034508381063b
2026-01-18 00:12:28.026036 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-mzu70xxn/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2026-01-18 00:12:28.034894 | controller | Successfully built ansible-base
2026-01-18 00:12:28.068747 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2026-01-18 00:12:29.393647 | 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-18 00:12:29.674077 | controller | ok: Runtime: 0:00:26.130220
2026-01-18 00:12:29.683070 | 
2026-01-18 00:12:29.683149 | TASK [Setup download-artifact-fork role]
2026-01-18 00:12:29.704353 | controller | ok
2026-01-18 00:12:29.726814 | 
2026-01-18 00:12:29.726946 | TASK [download-artifact-fork : Parse inventory response]
2026-01-18 00:12:29.758628 | controller | ok
2026-01-18 00:12:29.764945 | 
2026-01-18 00:12:29.765009 | TASK [download-artifact-fork : Fetch info from Zuul API]
2026-01-18 00:12:29.789221 | controller | skipping: Conditional result was False
2026-01-18 00:12:29.796379 | 
2026-01-18 00:12:29.796448 | TASK [download-artifact-fork : Parse build response]
2026-01-18 00:12:29.821895 | controller | skipping: Conditional result was False
2026-01-18 00:12:29.831642 | 
2026-01-18 00:12:29.831747 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2026-01-18 00:12:30.045831 | controller | changed
2026-01-18 00:12:30.056977 | 
2026-01-18 00:12:30.057152 | LOOP [download-artifact-fork : Download archive by type]
2026-01-18 00:12:30.083164 | controller | skipping: Conditional result was False
2026-01-18 00:12:30.083514 | 
2026-01-18 00:12:31.071198 | controller | changed: OK (20194 bytes)
2026-01-18 00:12:31.094942 | 
2026-01-18 00:12:31.095112 | TASK [deploy-artifacts : define __collections]
2026-01-18 00:12:31.129325 | controller | ok
2026-01-18 00:12:31.138388 | 
2026-01-18 00:12:31.138535 | LOOP [deploy-artifacts : Create list of collections]
2026-01-18 00:12:31.161525 | controller | skipping: Conditional result was False
2026-01-18 00:12:31.168758 | controller | ok:
2026-01-18 00:12:31.168857 | controller | {
2026-01-18 00:12:31.168881 | controller |   "branch": "main",
2026-01-18 00:12:31.168899 | controller |   "job": "build-ansible-collection",
2026-01-18 00:12:31.168917 | controller |   "metadata": {
2026-01-18 00:12:31.168933 | controller |     "type": "ansible_collection",
2026-01-18 00:12:31.168950 | controller |     "version": "5.0.0"
2026-01-18 00:12:31.168966 | controller |   },
2026-01-18 00:12:31.168981 | controller |   "name": "ansible.network",
2026-01-18 00:12:31.168996 | controller |   "project": "ansible-collections/ansible.network",
2026-01-18 00:12:31.169034 | controller |   "url": "https://25a504daa72e356d7eac-445e78ce705e41bfad21670532b58dab.ssl.cf5.rackcdn.com/ansible/e72d784a469c4e21a6c812d825cf7266/artifacts/ansible-network-5.0.0.tar.gz"
2026-01-18 00:12:31.169059 | controller | }
2026-01-18 00:12:31.177052 | 
2026-01-18 00:12:31.177179 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2026-01-18 00:12:31.725888 | controller | Starting galaxy collection install process
2026-01-18 00:12:31.726209 | controller | Process install dependency map
2026-01-18 00:18:17.147183 | controller | Starting collection install process
2026-01-18 00:18:17.147435 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2026-01-18 00:18:17.161775 | controller | ansible.network (5.0.0) was installed successfully
2026-01-18 00:18:17.161845 | controller | Installing 'ansible.netcommon:8.2.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-01-18 00:18:17.161997 | 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-4273ub_wta5p/tmphs5hbtq7
2026-01-18 00:18:54.857838 | controller | ansible.netcommon (8.2.1) was installed successfully
2026-01-18 00:18:54.857941 | controller | Installing 'ansible.utils:6.0.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-01-18 00:18:54.858091 | 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-4273ub_wta5p/tmphs5hbtq7
2026-01-18 00:19:37.145197 | controller | ansible.utils (6.0.1) was installed successfully
2026-01-18 00:19:37.145893 | controller | Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2026-01-18 00:19:37.145906 | 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-4273ub_wta5p/tmphs5hbtq7
2026-01-18 00:20:20.535310 | controller | arista.eos (12.0.0) was installed successfully
2026-01-18 00:20:20.535419 | controller | Installing 'cisco.ios:11.2.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2026-01-18 00:20:20.535552 | 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-4273ub_wta5p/tmphs5hbtq7
2026-01-18 00:21:01.693643 | controller | cisco.ios (11.2.0) was installed successfully
2026-01-18 00:21:01.693736 | controller | Installing 'cisco.iosxr:12.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2026-01-18 00:21:01.693874 | 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-4273ub_wta5p/tmphs5hbtq7
2026-01-18 00:21:45.176690 | controller | cisco.iosxr (12.1.1) was installed successfully
2026-01-18 00:21:45.176795 | controller | Installing 'cisco.nxos:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2026-01-18 00:21:45.176941 | 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-4273ub_wta5p/tmphs5hbtq7
2026-01-18 00:22:26.539692 | controller | cisco.nxos (11.1.1) was installed successfully
2026-01-18 00:22:26.539792 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2026-01-18 00:22:26.539933 | 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-4273ub_wta5p/tmphs5hbtq7
2026-01-18 00:23:10.374611 | controller | junipernetworks.junos (11.0.0) was installed successfully
2026-01-18 00:23:10.682616 | controller | ok: Runtime: 0:10:39.028009
2026-01-18 00:23:10.700659 | 
2026-01-18 00:23:10.700779 | TASK [podman may need iptables kernel modules]
2026-01-18 00:23:11.244221 | controller | ok: Runtime: 0:00:00.003582
2026-01-18 00:23:11.277321 | 
2026-01-18 00:23:11.277493 | PLAY RECAP
2026-01-18 00:23:11.277552 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2026-01-18 00:23:11.277580 | 
2026-01-18 00:23:11.422773 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-01-18 00:23:11.423677 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-01-18 00:23:12.195585 | 
2026-01-18 00:23:12.195722 | PLAY [controller]
2026-01-18 00:23:12.225386 | 
2026-01-18 00:23:12.225658 | TASK [Copy the galaxy.yml on the controller]
2026-01-18 00:23:13.294261 | controller | changed
2026-01-18 00:23:13.301749 | 
2026-01-18 00:23:13.301880 | TASK [Load information from galaxy.yml]
2026-01-18 00:23:13.333181 | controller | ok
2026-01-18 00:23:13.340029 | 
2026-01-18 00:23:13.340150 | LOOP [Enable FIPS mode]
2026-01-18 00:23:13.361331 | 
2026-01-18 00:23:13.361490 | LOOP [ansible-test : Include branch specific variables.]
2026-01-18 00:23:13.394605 | controller | ok: "/var/lib/zuul/builds/6e290fac541542dc9ed0628f8cdac91e/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2026-01-18 00:23:13.394888 | 
2026-01-18 00:23:13.403437 | 
2026-01-18 00:23:13.403577 | TASK [ansible-test : Setup --skip-tags for test_options]
2026-01-18 00:23:13.451158 | controller | ok
2026-01-18 00:23:13.457475 | 
2026-01-18 00:23:13.457594 | TASK [ansible-test : Enable --inventory for network-integration]
2026-01-18 00:23:13.481988 | controller | skipping: Conditional result was False
2026-01-18 00:23:13.489509 | 
2026-01-18 00:23:13.489626 | TASK [ansible-test : Enable --retry-on-error]
2026-01-18 00:23:13.512437 | controller | skipping: Conditional result was False
2026-01-18 00:23:13.520222 | 
2026-01-18 00:23:13.520382 | TASK [ansible-test : Enable --continue-on-error]
2026-01-18 00:23:13.562145 | controller | ok
2026-01-18 00:23:13.567782 | 
2026-01-18 00:23:13.567843 | TASK [ansible-test : Enable --requirements]
2026-01-18 00:23:13.581144 | controller | skipping: Conditional result was False
2026-01-18 00:23:13.588321 | 
2026-01-18 00:23:13.588393 | TASK [ansible-test : Adjust options for unit tests]
2026-01-18 00:23:13.601636 | controller | skipping: Conditional result was False
2026-01-18 00:23:13.607852 | 
2026-01-18 00:23:13.607926 | TASK [ansible-test : Install requirements with unit tests]
2026-01-18 00:23:13.639239 | controller | ok
2026-01-18 00:23:13.647606 | 
2026-01-18 00:23:13.647704 | TASK [ansible-test : Set --skip-test]
2026-01-18 00:23:13.703770 | controller | skipping: Conditional result was False
2026-01-18 00:23:13.711109 | 
2026-01-18 00:23:13.711219 | TASK [ansible-test : Reduce verbosity]
2026-01-18 00:23:13.741133 | controller | ok
2026-01-18 00:23:13.777934 | 
2026-01-18 00:23:13.778085 | TASK [ansible-test : Setup --python option]
2026-01-18 00:23:13.802367 | controller | skipping: Conditional result was False
2026-01-18 00:23:13.808849 | 
2026-01-18 00:23:13.808934 | TASK [ansible-test : Setup --docker option]
2026-01-18 00:23:13.841970 | controller | ok
2026-01-18 00:23:13.850503 | 
2026-01-18 00:23:13.850642 | TASK [ansible-test : check if MANIFEST.json exists]
2026-01-18 00:23:14.396301 | controller | ok
2026-01-18 00:23:14.407694 | 
2026-01-18 00:23:14.407834 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2026-01-18 00:23:14.432385 | controller | skipping: Conditional result was False
2026-01-18 00:23:14.453720 | 
2026-01-18 00:23:14.453869 | TASK [ansible-test : Load information from MANIFEST.json]
2026-01-18 00:23:14.479324 | controller | skipping: Conditional result was False
2026-01-18 00:23:14.490789 | 
2026-01-18 00:23:14.490980 | TASK [ansible-test : Setup location of project for integration tests]
2026-01-18 00:23:14.519269 | controller | skipping: Conditional result was False
2026-01-18 00:23:14.529561 | 
2026-01-18 00:23:14.529680 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2026-01-18 00:23:15.158006 | controller | ok
2026-01-18 00:23:15.167102 | 
2026-01-18 00:23:15.167246 | TASK [ansible-test : Load information from galaxy.yml]
2026-01-18 00:23:15.212723 | controller | ok
2026-01-18 00:23:15.222168 | 
2026-01-18 00:23:15.222316 | TASK [ansible-test : Setup location of project for integration tests]
2026-01-18 00:23:15.268836 | controller | ok
2026-01-18 00:23:15.277647 | 
2026-01-18 00:23:15.277791 | TASK [ansible-test : Setup minimum test requirements]
2026-01-18 00:23:15.321764 | controller | ok
2026-01-18 00:23:15.329909 | 
2026-01-18 00:23:15.330080 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2026-01-18 00:23:16.011706 | controller | ok
2026-01-18 00:23:16.031280 | 
2026-01-18 00:23:16.031469 | TASK [ansible-test : Install python requirements]
2026-01-18 00:23:27.951494 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-01-18 00:23:40.274970 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2026-01-18 00:23:40.308882 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-01-18 00:23:40.321710 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2026-01-18 00:23:40.331376 | 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-18 00:23:40.338554 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2026-01-18 00:23:40.349584 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2026-01-18 00:23:40.384834 | controller | Installing collected packages: pathspec, yamllint
2026-01-18 00:23:40.436658 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2026-01-18 00:23:40.596995 | controller | ok: Runtime: 0:00:23.730284
2026-01-18 00:23:40.611555 | 
2026-01-18 00:23:40.611734 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2026-01-18 00:23:40.891301 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2026-01-18 00:23:41.164810 | controller | ERROR
2026-01-18 00:23:41.165502 | controller | {
2026-01-18 00:23:41.165631 | controller |   "delta": "0:00:00.004626",
2026-01-18 00:23:41.165713 | controller |   "end": "2026-01-18 00:23:40.891440",
2026-01-18 00:23:41.165789 | controller |   "msg": "non-zero return code",
2026-01-18 00:23:41.165865 | controller |   "rc": 1,
2026-01-18 00:23:41.165942 | controller |   "start": "2026-01-18 00:23:40.886814"
2026-01-18 00:23:41.166066 | controller | }
2026-01-18 00:23:41.166183 | controller | ERROR: Ignoring Errors
2026-01-18 00:23:41.187973 | 
2026-01-18 00:23:41.188236 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2026-01-18 00:23:41.745624 | controller | skipping: Conditional result was False
2026-01-18 00:23:41.755754 | 
2026-01-18 00:23:41.755881 | TASK [ansible-test : Enable persistent connection logging]
2026-01-18 00:23:42.213873 | controller | changed: section and option added
2026-01-18 00:23:42.228865 | 
2026-01-18 00:23:42.229169 | TASK [ansible-test : Set persistent ssh_type value]
2026-01-18 00:23:42.531715 | controller | changed: option added
2026-01-18 00:23:42.544092 | 
2026-01-18 00:23:42.544244 | TASK [ansible-test : Set ansible log path]
2026-01-18 00:23:43.056600 | controller | changed: section and option added
2026-01-18 00:23:43.073173 | 
2026-01-18 00:23:43.073389 | TASK [ansible-test : Identify the changed targets]
2026-01-18 00:23:43.624073 | controller | skipping: Conditional result was False
2026-01-18 00:23:43.681970 | 
2026-01-18 00:23:43.682159 | TASK [ansible-test : set_fact]
2026-01-18 00:23:43.739203 | controller | skipping: Conditional result was False
2026-01-18 00:23:43.748855 | 
2026-01-18 00:23:43.749184 | TASK [ansible-test : Set the targets]
2026-01-18 00:23:43.782551 | controller | ok
2026-01-18 00:23:43.790849 | 
2026-01-18 00:23:43.790951 | TASK [ansible-test : set_fact]
2026-01-18 00:23:43.807407 | controller | skipping: Conditional result was False
2026-01-18 00:23:43.817449 | 
2026-01-18 00:23:43.817605 | TASK [ansible-test : List the targets]
2026-01-18 00:23:44.366172 | controller | skipping: Conditional result was False
2026-01-18 00:23:44.381540 | 
2026-01-18 00:23:44.381762 | TASK [ansible-test : set_fact]
2026-01-18 00:23:44.409355 | controller | skipping: Conditional result was False
2026-01-18 00:23:44.424175 | 
2026-01-18 00:23:44.424360 | TASK [ansible-test : set_fact]
2026-01-18 00:23:44.451539 | controller | skipping: Conditional result was False
2026-01-18 00:23:44.459835 | 
2026-01-18 00:23:44.459975 | LOOP [ansible-test : set_fact]
2026-01-18 00:23:44.534133 | 
2026-01-18 00:23:44.534305 | TASK [ansible-test : set_fact]
2026-01-18 00:23:44.558660 | controller | skipping: Conditional result was False
2026-01-18 00:23:44.567650 | 
2026-01-18 00:23:44.567789 | TASK [ansible-test : debug]
2026-01-18 00:23:44.582466 | controller | skipping: Conditional result was False
2026-01-18 00:23:44.589576 | 
2026-01-18 00:23:44.589708 | TASK [ansible-test : debug]
2026-01-18 00:23:44.623264 | About to run: ansible-test sanity --requirements --docker -v
2026-01-18 00:23:44.634153 | 
2026-01-18 00:23:44.634308 | TASK [ansible-test : Run the test suite]
2026-01-18 00:23:45.330405 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2026-01-18 00:23:45.352465 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2026-01-18 00:23:47.162006 | controller | 2.9.0: Pulling from ansible/default-test-container
2026-01-18 00:23:47.162305 | controller | 171857c49d0f: Pulling fs layer
2026-01-18 00:23:47.163053 | controller | 419640447d26: Pulling fs layer
2026-01-18 00:23:47.163067 | controller | 61e52f862619: Pulling fs layer
2026-01-18 00:23:47.163072 | controller | 851de0426e26: Pulling fs layer
2026-01-18 00:23:47.163082 | controller | 37425716ea5b: Pulling fs layer
2026-01-18 00:23:47.163089 | controller | cd9b0c439616: Pulling fs layer
2026-01-18 00:23:47.163094 | controller | d4612dbd8855: Pulling fs layer
2026-01-18 00:23:47.163098 | controller | 554699364730: Pulling fs layer
2026-01-18 00:23:47.163102 | controller | 1ff15d4bdb3e: Pulling fs layer
2026-01-18 00:23:47.163121 | controller | 5f2e4724adea: Pulling fs layer
2026-01-18 00:23:47.163125 | controller | a02d9a95ccb8: Pulling fs layer
2026-01-18 00:23:47.163130 | controller | 989f4ce370cb: Pulling fs layer
2026-01-18 00:23:47.163134 | controller | 0d7f52cdf9df: Pulling fs layer
2026-01-18 00:23:47.163138 | controller | c423742351d5: Pulling fs layer
2026-01-18 00:23:47.163142 | controller | 109236341d21: Pulling fs layer
2026-01-18 00:23:47.163199 | controller | 07a2f61c96b8: Pulling fs layer
2026-01-18 00:23:47.163205 | controller | 041f122f27ae: Pulling fs layer
2026-01-18 00:23:47.163209 | controller | ca6f5098b12e: Pulling fs layer
2026-01-18 00:23:47.163213 | controller | 1eb3e7b2afd9: Pulling fs layer
2026-01-18 00:23:47.163217 | controller | 18a0b750727a: Pulling fs layer
2026-01-18 00:23:47.163221 | controller | 17f2f596716c: Pulling fs layer
2026-01-18 00:23:47.163227 | controller | f35a9998a9bd: Pulling fs layer
2026-01-18 00:23:47.163231 | controller | fdfc10566405: Pulling fs layer
2026-01-18 00:23:47.163235 | controller | 1ff1c442c5fe: Pulling fs layer
2026-01-18 00:23:47.163239 | controller | a3443c4a17ed: Pulling fs layer
2026-01-18 00:23:47.163243 | controller | 0edcf9fb2b15: Pulling fs layer
2026-01-18 00:23:47.163247 | controller | 5af43b69e845: Pulling fs layer
2026-01-18 00:23:47.163251 | controller | 1651773effb2: Pulling fs layer
2026-01-18 00:23:47.163264 | controller | 79e23c5c0fe4: Pulling fs layer
2026-01-18 00:23:47.163271 | controller | e5058b1faaa6: Pulling fs layer
2026-01-18 00:23:47.163278 | controller | 846775490569: Pulling fs layer
2026-01-18 00:23:47.163331 | controller | 07a2f61c96b8: Waiting
2026-01-18 00:23:47.163612 | controller | 041f122f27ae: Waiting
2026-01-18 00:23:47.163622 | controller | ca6f5098b12e: Waiting
2026-01-18 00:23:47.163627 | controller | 1eb3e7b2afd9: Waiting
2026-01-18 00:23:47.163631 | controller | 18a0b750727a: Waiting
2026-01-18 00:23:47.163635 | controller | 17f2f596716c: Waiting
2026-01-18 00:23:47.163642 | controller | f35a9998a9bd: Waiting
2026-01-18 00:23:47.163648 | controller | fdfc10566405: Waiting
2026-01-18 00:23:48.072048 | controller | 1ff1c442c5fe: Waiting
2026-01-18 00:23:48.072532 | controller | a3443c4a17ed: Waiting
2026-01-18 00:23:48.072543 | controller | 0edcf9fb2b15: Waiting
2026-01-18 00:23:48.072548 | controller | 5af43b69e845: Waiting
2026-01-18 00:23:48.072552 | controller | 1651773effb2: Waiting
2026-01-18 00:23:48.072556 | controller | 79e23c5c0fe4: Waiting
2026-01-18 00:23:48.072560 | controller | e5058b1faaa6: Waiting
2026-01-18 00:23:48.072565 | controller | 846775490569: Waiting
2026-01-18 00:23:48.072569 | controller | 851de0426e26: Waiting
2026-01-18 00:23:48.072573 | controller | 37425716ea5b: Waiting
2026-01-18 00:23:48.072581 | controller | cd9b0c439616: Waiting
2026-01-18 00:23:48.072596 | controller | d4612dbd8855: Waiting
2026-01-18 00:23:48.072600 | controller | 554699364730: Waiting
2026-01-18 00:23:48.072604 | controller | 5f2e4724adea: Waiting
2026-01-18 00:23:48.072609 | controller | a02d9a95ccb8: Waiting
2026-01-18 00:23:48.072681 | controller | 989f4ce370cb: Waiting
2026-01-18 00:23:48.072688 | controller | 0d7f52cdf9df: Waiting
2026-01-18 00:23:48.072693 | controller | c423742351d5: Waiting
2026-01-18 00:23:48.072697 | controller | 109236341d21: Waiting
2026-01-18 00:23:48.072702 | controller | 1ff15d4bdb3e: Waiting
2026-01-18 00:23:48.072711 | controller | 419640447d26: Verifying Checksum
2026-01-18 00:23:48.410593 | controller | 419640447d26: Download complete
2026-01-18 00:23:48.410648 | controller | 61e52f862619: Verifying Checksum
2026-01-18 00:23:48.877328 | controller | 61e52f862619: Download complete
2026-01-18 00:23:48.877366 | controller | 171857c49d0f: Verifying Checksum
2026-01-18 00:23:49.102969 | controller | 171857c49d0f: Download complete
2026-01-18 00:23:49.103498 | controller | 851de0426e26: Verifying Checksum
2026-01-18 00:23:49.722002 | controller | 851de0426e26: Download complete
2026-01-18 00:23:49.722047 | controller | cd9b0c439616: Verifying Checksum
2026-01-18 00:23:50.056725 | controller | cd9b0c439616: Download complete
2026-01-18 00:23:50.056765 | controller | d4612dbd8855: Verifying Checksum
2026-01-18 00:23:50.809506 | controller | d4612dbd8855: Download complete
2026-01-18 00:23:50.809558 | controller | 171857c49d0f: Pull complete
2026-01-18 00:23:50.851837 | controller | 419640447d26: Pull complete
2026-01-18 00:23:50.879920 | controller | 61e52f862619: Pull complete
2026-01-18 00:23:50.906075 | controller | 851de0426e26: Pull complete
2026-01-18 00:23:50.988060 | controller | 1ff15d4bdb3e: Verifying Checksum
2026-01-18 00:23:51.935183 | controller | 1ff15d4bdb3e: Download complete
2026-01-18 00:23:51.935228 | controller | 554699364730: Verifying Checksum
2026-01-18 00:23:52.202284 | controller | 554699364730: Download complete
2026-01-18 00:23:52.202306 | controller | 5f2e4724adea: Verifying Checksum
2026-01-18 00:23:53.129708 | controller | 5f2e4724adea: Download complete
2026-01-18 00:23:53.129744 | controller | 989f4ce370cb: Verifying Checksum
2026-01-18 00:23:53.383779 | controller | 989f4ce370cb: Download complete
2026-01-18 00:23:53.383803 | controller | a02d9a95ccb8: Download complete
2026-01-18 00:23:54.080585 | controller | 0d7f52cdf9df: Verifying Checksum
2026-01-18 00:23:54.518815 | controller | 0d7f52cdf9df: Download complete
2026-01-18 00:23:54.518879 | controller | c423742351d5: Verifying Checksum
2026-01-18 00:23:55.414938 | controller | c423742351d5: Download complete
2026-01-18 00:23:55.414985 | controller | 07a2f61c96b8: Verifying Checksum
2026-01-18 00:23:55.415052 | controller | 07a2f61c96b8: Download complete
2026-01-18 00:23:57.301393 | controller | 109236341d21: Verifying Checksum
2026-01-18 00:23:57.408852 | controller | 109236341d21: Download complete
2026-01-18 00:23:57.408916 | controller | 041f122f27ae: Verifying Checksum
2026-01-18 00:23:57.408955 | controller | 041f122f27ae: Download complete
2026-01-18 00:23:58.458318 | controller | 1eb3e7b2afd9: Verifying Checksum
2026-01-18 00:23:58.720961 | controller | 1eb3e7b2afd9: Download complete
2026-01-18 00:23:58.721032 | controller | ca6f5098b12e: Verifying Checksum
2026-01-18 00:23:59.636023 | controller | ca6f5098b12e: Download complete
2026-01-18 00:23:59.636137 | controller | 17f2f596716c: Verifying Checksum
2026-01-18 00:23:59.671159 | controller | 17f2f596716c: Download complete
2026-01-18 00:23:59.671203 | controller | 18a0b750727a: Verifying Checksum
2026-01-18 00:24:00.550044 | controller | 18a0b750727a: Download complete
2026-01-18 00:24:00.550147 | controller | f35a9998a9bd: Verifying Checksum
2026-01-18 00:24:00.550360 | controller | f35a9998a9bd: Download complete
2026-01-18 00:24:00.600453 | controller | fdfc10566405: Verifying Checksum
2026-01-18 00:24:01.772215 | controller | fdfc10566405: Download complete
2026-01-18 00:24:01.772283 | controller | 1ff1c442c5fe: Verifying Checksum
2026-01-18 00:24:02.146430 | controller | 1ff1c442c5fe: Download complete
2026-01-18 00:24:02.146495 | controller | a3443c4a17ed: Verifying Checksum
2026-01-18 00:24:03.099368 | controller | a3443c4a17ed: Download complete
2026-01-18 00:24:03.099443 | controller | 0edcf9fb2b15: Verifying Checksum
2026-01-18 00:24:03.099485 | controller | 0edcf9fb2b15: Download complete
2026-01-18 00:24:03.831278 | controller | 5af43b69e845: Verifying Checksum
2026-01-18 00:24:03.831373 | controller | 5af43b69e845: Download complete
2026-01-18 00:24:04.917195 | controller | 1651773effb2: Verifying Checksum
2026-01-18 00:24:06.229180 | controller | 1651773effb2: Download complete
2026-01-18 00:24:06.229235 | controller | 79e23c5c0fe4: Verifying Checksum
2026-01-18 00:24:07.747559 | controller | 79e23c5c0fe4: Download complete
2026-01-18 00:24:07.747612 | controller | e5058b1faaa6: Verifying Checksum
2026-01-18 00:24:08.474213 | controller | e5058b1faaa6: Download complete
2026-01-18 00:24:08.474284 | controller | 37425716ea5b: Verifying Checksum
2026-01-18 00:24:08.561254 | controller | 37425716ea5b: Download complete
2026-01-18 00:24:08.561319 | controller | 846775490569: Verifying Checksum
2026-01-18 00:24:21.464959 | controller | 846775490569: Download complete
2026-01-18 00:24:21.465221 | controller | 37425716ea5b: Pull complete
2026-01-18 00:24:21.481857 | controller | cd9b0c439616: Pull complete
2026-01-18 00:24:21.498499 | controller | d4612dbd8855: Pull complete
2026-01-18 00:24:24.098125 | controller | 554699364730: Pull complete
2026-01-18 00:24:24.119991 | controller | 1ff15d4bdb3e: Pull complete
2026-01-18 00:24:24.141838 | controller | 5f2e4724adea: Pull complete
2026-01-18 00:24:24.177096 | controller | a02d9a95ccb8: Pull complete
2026-01-18 00:24:24.195900 | controller | 989f4ce370cb: Pull complete
2026-01-18 00:24:24.214193 | controller | 0d7f52cdf9df: Pull complete
2026-01-18 00:24:24.235389 | controller | c423742351d5: Pull complete
2026-01-18 00:24:25.434245 | controller | 109236341d21: Pull complete
2026-01-18 00:24:25.454773 | controller | 07a2f61c96b8: Pull complete
2026-01-18 00:24:25.473389 | controller | 041f122f27ae: Pull complete
2026-01-18 00:24:25.583568 | controller | ca6f5098b12e: Pull complete
2026-01-18 00:24:25.624475 | controller | 1eb3e7b2afd9: Pull complete
2026-01-18 00:24:25.660412 | controller | 18a0b750727a: Pull complete
2026-01-18 00:24:25.680600 | controller | 17f2f596716c: Pull complete
2026-01-18 00:24:25.701004 | controller | f35a9998a9bd: Pull complete
2026-01-18 00:24:25.736539 | controller | fdfc10566405: Pull complete
2026-01-18 00:24:25.761158 | controller | 1ff1c442c5fe: Pull complete
2026-01-18 00:24:26.712578 | controller | a3443c4a17ed: Pull complete
2026-01-18 00:24:28.076149 | controller | 0edcf9fb2b15: Pull complete
2026-01-18 00:24:29.427649 | controller | 5af43b69e845: Pull complete
2026-01-18 00:24:32.074115 | controller | 1651773effb2: Pull complete
2026-01-18 00:24:33.808784 | controller | 79e23c5c0fe4: Pull complete
2026-01-18 00:24:34.890855 | controller | e5058b1faaa6: Pull complete
2026-01-18 00:24:36.502895 | controller | 846775490569: Pull complete
2026-01-18 00:24:36.525909 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2026-01-18 00:24:36.537285 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2026-01-18 00:24:36.550303 | controller | quay.io/ansible/default-test-container:2.9.0
2026-01-18 00:24:36.553186 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2026-01-18 00:24:36.575662 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2026-01-18 00:24:38.367293 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2026-01-18 00:24:38.367344 | controller | 04a5f4cda3ee: Pulling fs layer
2026-01-18 00:24:38.367356 | controller | ff496a88c8ed: Pulling fs layer
2026-01-18 00:24:38.367365 | controller | 0ce83f459fe7: Pulling fs layer
2026-01-18 00:24:38.367375 | controller | 2e5170e1f099: Pulling fs layer
2026-01-18 00:24:38.367387 | controller | 7641eb41b08c: Pulling fs layer
2026-01-18 00:24:38.367506 | controller | ad15fa9da398: Pulling fs layer
2026-01-18 00:24:38.367523 | controller | 087d91352424: Pulling fs layer
2026-01-18 00:24:38.367532 | controller | 8b92efd6a100: Pulling fs layer
2026-01-18 00:24:38.367546 | controller | 2e5170e1f099: Waiting
2026-01-18 00:24:38.367558 | controller | 7641eb41b08c: Waiting
2026-01-18 00:24:38.367707 | controller | ad15fa9da398: Waiting
2026-01-18 00:24:38.367730 | controller | 087d91352424: Waiting
2026-01-18 00:24:38.367832 | controller | 8b92efd6a100: Waiting
2026-01-18 00:24:39.362994 | controller | ff496a88c8ed: Verifying Checksum
2026-01-18 00:24:39.585367 | controller | ff496a88c8ed: Download complete
2026-01-18 00:24:39.585476 | controller | 0ce83f459fe7: Verifying Checksum
2026-01-18 00:24:39.796508 | controller | 0ce83f459fe7: Download complete
2026-01-18 00:24:39.796564 | controller | 04a5f4cda3ee: Verifying Checksum
2026-01-18 00:24:40.550160 | controller | 04a5f4cda3ee: Download complete
2026-01-18 00:24:40.550217 | controller | 7641eb41b08c: Verifying Checksum
2026-01-18 00:24:40.746063 | controller | 7641eb41b08c: Download complete
2026-01-18 00:24:40.746122 | controller | ad15fa9da398: Verifying Checksum
2026-01-18 00:24:40.984214 | controller | ad15fa9da398: Download complete
2026-01-18 00:24:40.984280 | controller | 04a5f4cda3ee: Pull complete
2026-01-18 00:24:40.998833 | controller | 2e5170e1f099: Verifying Checksum
2026-01-18 00:24:40.998877 | controller | 2e5170e1f099: Download complete
2026-01-18 00:24:41.028162 | controller | ff496a88c8ed: Pull complete
2026-01-18 00:24:41.061786 | controller | 0ce83f459fe7: Pull complete
2026-01-18 00:24:41.576577 | controller | 087d91352424: Verifying Checksum
2026-01-18 00:24:41.628509 | controller | 087d91352424: Download complete
2026-01-18 00:24:41.628531 | controller | 8b92efd6a100: Verifying Checksum
2026-01-18 00:24:41.909922 | controller | 8b92efd6a100: Download complete
2026-01-18 00:24:41.909988 | controller | 2e5170e1f099: Pull complete
2026-01-18 00:24:41.936752 | controller | 7641eb41b08c: Pull complete
2026-01-18 00:24:41.962020 | controller | ad15fa9da398: Pull complete
2026-01-18 00:24:42.542235 | controller | 087d91352424: Pull complete
2026-01-18 00:24:42.563058 | controller | 8b92efd6a100: Pull complete
2026-01-18 00:24:42.582761 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2026-01-18 00:24:42.586260 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2026-01-18 00:24:42.592120 | controller | quay.io/ansible/pypi-test-container:1.0.0
2026-01-18 00:24:42.594908 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2026-01-18 00:24:51.569378 | controller | Run command: docker inspect 204188d92dcc2e3d2eb6b97d141f8b0f97a515559349fb76f9c790b12cf433ca
2026-01-18 00:24:51.603014 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2026-01-18 00:24:51.611113 | controller | Including collection: ansible.netcommon (287 files)
2026-01-18 00:24:51.611161 | controller | Including collection: ansible.network (23 files)
2026-01-18 00:24:51.618479 | controller | Including collection: ansible.utils (441 files)
2026-01-18 00:24:51.642322 | controller | Including collection: arista.eos (1022 files)
2026-01-18 00:24:51.670122 | controller | Including collection: cisco.ios (1174 files)
2026-01-18 00:24:51.697067 | controller | Including collection: cisco.iosxr (1044 files)
2026-01-18 00:24:51.740593 | controller | Including collection: cisco.nxos (1682 files)
2026-01-18 00:24:51.765518 | controller | Including collection: junipernetworks.junos (963 files)
2026-01-18 00:24:51.788545 | controller | Creating a payload archive containing 7362 files...
2026-01-18 00:24:53.286687 | controller | Created a 6001949 byte payload archive containing 7362 files in 1 seconds.
2026-01-18 00:24:53.287913 | controller | Assuming Docker is available on localhost.
2026-01-18 00:24:53.288261 | 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-18 00:24:53.832846 | controller | Run command: docker exec -i b0db88b3bc47fae56ea0b23fd565099f809c22fb03f212fcb312b209e5d908bd dd of=/root/docker.sh bs=65536
2026-01-18 00:24:53.892273 | controller | Run command: docker exec b0db88b3bc47fae56ea0b23fd565099f809c22fb03f212fcb312b209e5d908bd /bin/bash /root/docker.sh
2026-01-18 00:24:53.947862 | controller | Run command: docker exec -i b0db88b3bc47fae56ea0b23fd565099f809c22fb03f212fcb312b209e5d908bd dd of=/root/test.tgz bs=65536
2026-01-18 00:24:54.022813 | controller | Run command: docker exec b0db88b3bc47fae56ea0b23fd565099f809c22fb03f212fcb312b209e5d908bd tar oxzf /root/test.tgz -C /root
2026-01-18 00:24:54.661699 | controller | Run command: docker exec b0db88b3bc47fae56ea0b23fd565099f809c22fb03f212fcb312b209e5d908bd /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-q4p95p73.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2026-01-18 00:24:54.909570 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2026-01-18 00:24:54.909633 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2026-01-18 00:24:54.909843 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2026-01-18 00:24:54.911203 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2026-01-18 00:24:54.911703 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2026-01-18 00:24:54.916245 | controller | No tests applicable.
2026-01-18 00:24:54.916520 | controller | Running sanity test 'ansible-doc' with Python 3.6
2026-01-18 00:24:54.916545 | controller | No tests applicable.
2026-01-18 00:24:54.916829 | controller | Running sanity test 'changelog' with Python 3.6
2026-01-18 00:24:54.916854 | 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-18 00:24:55.491618 | 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-18 00:24:55.875088 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2026-01-18 00:24:55.906159 | 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-18 00:24:55.907525 | 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-18 00:24:55.943493 | 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-18 00:24:55.944586 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2026-01-18 00:24:55.968825 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2026-01-18 00:24:55.968874 | controller | Removing custom PyPI hosts entries: /etc/hosts
2026-01-18 00:24:55.968887 | controller | Run command: docker exec b0db88b3bc47fae56ea0b23fd565099f809c22fb03f212fcb312b209e5d908bd tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2026-01-18 00:24:56.020142 | controller | Run command: docker exec -i b0db88b3bc47fae56ea0b23fd565099f809c22fb03f212fcb312b209e5d908bd dd if=/root/output.tgz bs=65536
2026-01-18 00:24:56.075859 | controller | Run command: tar oxzf /tmp/ansible-result-sqkwukm_.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2026-01-18 00:24:56.092538 | controller | Run command: docker rm -f 204188d92dcc2e3d2eb6b97d141f8b0f97a515559349fb76f9c790b12cf433ca
2026-01-18 00:24:58.377858 | controller | Run command: docker rm -f b0db88b3bc47fae56ea0b23fd565099f809c22fb03f212fcb312b209e5d908bd
2026-01-18 00:24:58.817471 | controller | ERROR: Command "docker exec b0db88b3bc47fae56ea0b23fd565099f809c22fb03f212fcb312b209e5d908bd /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-q4p95p73.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2026-01-18 00:24:59.240571 | controller | ERROR
2026-01-18 00:24:59.240869 | controller | {
2026-01-18 00:24:59.240931 | controller |   "delta": "0:01:13.726377",
2026-01-18 00:24:59.240973 | controller |   "end": "2026-01-18 00:24:58.838268",
2026-01-18 00:24:59.241042 | controller |   "msg": "non-zero return code",
2026-01-18 00:24:59.241088 | controller |   "rc": 1,
2026-01-18 00:24:59.241124 | controller |   "start": "2026-01-18 00:23:45.111891"
2026-01-18 00:24:59.241160 | controller | }
failure
2026-01-18 00:24:59.248378 | 
2026-01-18 00:24:59.248545 | PLAY RECAP
2026-01-18 00:24:59.248647 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2026-01-18 00:24:59.248718 | 
2026-01-18 00:24:59.387392 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-01-18 00:24:59.388365 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-18 00:24:59.981322 | 
2026-01-18 00:24:59.981453 | PLAY [all]
2026-01-18 00:25:00.005205 | 
2026-01-18 00:25:00.005344 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-01-18 00:25:00.436272 | controller | changed: non-zero return code
2026-01-18 00:25:00.443387 | 
2026-01-18 00:25:00.443494 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-01-18 00:25:00.468283 | controller | skipping: Conditional result was False
2026-01-18 00:25:00.477344 | 
2026-01-18 00:25:00.477449 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-01-18 00:25:00.514516 | 
2026-01-18 00:25:00.514745 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-01-18 00:25:00.537937 | 
2026-01-18 00:25:00.538127 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-01-18 00:25:00.552215 | controller | skipping: Conditional result was False
2026-01-18 00:25:00.560291 | 
2026-01-18 00:25:00.560416 | LOOP [fetch-subunit-output : Generate subunit file]
2026-01-18 00:25:00.580833 | 
2026-01-18 00:25:00.581078 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-01-18 00:25:00.594820 | controller | skipping: Conditional result was False
2026-01-18 00:25:00.601718 | 
2026-01-18 00:25:00.601812 | TASK [fetch-subunit-output : Remove the temporary file]
2026-01-18 00:25:00.615312 | controller | skipping: Conditional result was False
2026-01-18 00:25:00.621886 | 
2026-01-18 00:25:00.621972 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-01-18 00:25:00.635520 | controller | skipping: Conditional result was False
2026-01-18 00:25:00.662185 | 
2026-01-18 00:25:00.662284 | PLAY RECAP
2026-01-18 00:25:00.662324 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-01-18 00:25:00.662345 | 
2026-01-18 00:25:00.753876 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-18 00:25:00.754738 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-01-18 00:25:01.306372 | 
2026-01-18 00:25:01.306501 | PLAY [all:!appliance*]
2026-01-18 00:25:01.328774 | 
2026-01-18 00:25:01.328909 | TASK [unregister the node]
2026-01-18 00:25:01.864068 | controller | skipping: Conditional result was False
2026-01-18 00:25:01.877392 | 
2026-01-18 00:25:01.877641 | TASK [include_role : fetch-output]
2026-01-18 00:25:01.932618 | controller | ok
2026-01-18 00:25:01.959328 | 
2026-01-18 00:25:01.959470 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-18 00:25:02.035757 | controller | skipping: Conditional result was False
2026-01-18 00:25:02.044167 | 
2026-01-18 00:25:02.044314 | TASK [fetch-output : Set log path for single node]
2026-01-18 00:25:02.087450 | controller | ok
2026-01-18 00:25:02.094725 | 
2026-01-18 00:25:02.094839 | LOOP [fetch-output : Ensure local output dirs]
2026-01-18 00:25:02.563455 | controller -> localhost | ok: "/var/lib/zuul/builds/6e290fac541542dc9ed0628f8cdac91e/work/logs"
2026-01-18 00:25:02.818978 | controller -> localhost | changed: "/var/lib/zuul/builds/6e290fac541542dc9ed0628f8cdac91e/work/artifacts"
2026-01-18 00:25:03.050976 | controller -> localhost | changed: "/var/lib/zuul/builds/6e290fac541542dc9ed0628f8cdac91e/work/docs"
2026-01-18 00:25:03.060110 | 
2026-01-18 00:25:03.060194 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-18 00:25:03.861364 | controller | changed:
2026-01-18 00:25:03.861624 | controller | .d..t...... ./
2026-01-18 00:25:03.861664 | controller | cd+++++++++ controller/
2026-01-18 00:25:03.861714 | controller | changed: All items complete
2026-01-18 00:25:03.861744 | 
2026-01-18 00:25:04.443112 | controller | changed: .d..t...... ./
2026-01-18 00:25:04.979489 | controller | changed: .d..t...... ./
2026-01-18 00:25:05.004780 | 
2026-01-18 00:25:05.005173 | TASK [include_role : fetch-output-openshift]
2026-01-18 00:25:05.031382 | controller | skipping: Conditional result was False
2026-01-18 00:25:05.041952 | 
2026-01-18 00:25:05.042152 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-18 00:25:05.087561 | controller | skipping: Conditional result was False
2026-01-18 00:25:05.100558 | controller | skipping: Conditional result was False
2026-01-18 00:25:05.155003 | 
2026-01-18 00:25:05.155152 | PLAY [localhost]
2026-01-18 00:25:05.171102 | 
2026-01-18 00:25:05.171253 | TASK [Run Zuul manifest role]
2026-01-18 00:25:05.192042 | localhost | ok
2026-01-18 00:25:05.207631 | 
2026-01-18 00:25:05.207759 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-18 00:25:05.634653 | localhost | changed
2026-01-18 00:25:05.639691 | 
2026-01-18 00:25:05.639762 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-18 00:25:05.667486 | localhost | ok
2026-01-18 00:25:05.675739 | 
2026-01-18 00:25:05.675820 | TASK [Set zuul-log-path fact]
2026-01-18 00:25:05.694294 | localhost | ok
2026-01-18 00:25:05.712922 | 
2026-01-18 00:25:05.713052 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-18 00:25:05.741995 | localhost | ok
2026-01-18 00:25:05.750153 | 
2026-01-18 00:25:05.750861 | LOOP [Run upload-logs-swift role]
2026-01-18 00:25:05.785747 | localhost | Output suppressed because no_log was given
2026-01-18 00:25:05.844987 | 
2026-01-18 00:25:05.845210 | TASK [Set zuul-log-path fact]
2026-01-18 00:25:05.870717 | localhost | skipping: Conditional result was False
2026-01-18 00:25:05.880410 | 
2026-01-18 00:25:05.880542 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-01-18 00:25:06.253120 | localhost -> localhost | ok: Runtime: 0:00:00.005139
2026-01-18 00:25:06.264105 | 
2026-01-18 00:25:06.264300 | TASK [upload-logs-swift : Upload logs to swift]