2026-01-30 00:14:31.567401 | Job console starting...
2026-01-30 00:14:31.578265 | Updating repositories
2026-01-30 00:14:31.764923 | Preparing job workspace
2026-01-30 00:14:35.539222 | Running Ansible setup...
2026-01-30 00:14:43.674409 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-30 00:14:44.294882 | 
2026-01-30 00:14:44.295008 | PLAY [localhost]
2026-01-30 00:14:44.304041 | 
2026-01-30 00:14:44.304137 | TASK [Gathering Facts]
2026-01-30 00:14:45.359654 | localhost | ok
2026-01-30 00:14:45.373211 | 
2026-01-30 00:14:45.373297 | TASK [Setup log path fact]
2026-01-30 00:14:45.390902 | localhost | ok
2026-01-30 00:14:45.407778 | 
2026-01-30 00:14:45.407913 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-30 00:14:45.438838 | localhost | ok
2026-01-30 00:14:45.448477 | 
2026-01-30 00:14:45.448611 | TASK [emit-job-header : Print job information]
2026-01-30 00:14:45.477598 | # Job Information
2026-01-30 00:14:45.477772 | Ansible Version: 2.15.12
2026-01-30 00:14:45.477804 | Job: ansible-test-sanity-docker-stable-2.10
2026-01-30 00:14:45.477825 | Pipeline: periodic
2026-01-30 00:14:45.477843 | Executor: ze04.softwarefactory-project.io
2026-01-30 00:14:45.477861 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-01-30 00:14:45.477881 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/5e3/ansible/5e36017a749642bca526aaf17f11fcf9/
2026-01-30 00:14:45.477900 | Event ID: b7cd74837303434d8030351669e6bf4f
2026-01-30 00:14:45.481823 | 
2026-01-30 00:14:45.481889 | LOOP [emit-job-header : Print node information]
2026-01-30 00:14:45.577705 | localhost | ok:
2026-01-30 00:14:45.577899 | localhost | # Node Information
2026-01-30 00:14:45.577927 | localhost | Inventory Hostname: controller
2026-01-30 00:14:45.577947 | localhost | Hostname: np0005601752
2026-01-30 00:14:45.577966 | localhost | Username: zuul
2026-01-30 00:14:45.577988 | localhost | Distro: Fedora 37
2026-01-30 00:14:45.578006 | localhost | Provider: ansible-vexxhost-ams1
2026-01-30 00:14:45.578024 | localhost | Region: ams1
2026-01-30 00:14:45.578042 | localhost | Label: ansible-fedora-37-1vcpu
2026-01-30 00:14:45.578061 | localhost | Product Name: OpenStack Nova
2026-01-30 00:14:45.578078 | localhost | Interface IP: 38.129.16.217
2026-01-30 00:14:45.605202 | 
2026-01-30 00:14:45.605363 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-30 00:14:46.052150 | localhost -> localhost | changed
2026-01-30 00:14:46.057864 | 
2026-01-30 00:14:46.057971 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-30 00:14:47.054285 | localhost -> localhost | changed
2026-01-30 00:14:47.074433 | 
2026-01-30 00:14:47.074516 | PLAY [all:!appliance*]
2026-01-30 00:14:47.090603 | 
2026-01-30 00:14:47.090697 | TASK [include_role : start-zuul-console]
2026-01-30 00:14:47.112328 | controller | ok
2026-01-30 00:14:47.131673 | 
2026-01-30 00:14:47.131779 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-01-30 00:14:48.241077 | controller | ok
2026-01-30 00:14:48.256863 | 
2026-01-30 00:14:48.256981 | TASK [use-our-mirror : Retrieve the IP address]
2026-01-30 00:14:50.769621 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-01-30 00:14:50.779964 | 
2026-01-30 00:14:50.780094 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-01-30 00:14:51.145133 | controller | skipping: Conditional result was False
2026-01-30 00:14:51.156116 | 
2026-01-30 00:14:51.156273 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-01-30 00:14:51.192324 | controller | skipping: Conditional result was False
2026-01-30 00:14:51.204134 | 
2026-01-30 00:14:51.204289 | TASK [use-our-mirror : Create the podman configuration directory]
2026-01-30 00:14:51.232264 | controller | skipping: Conditional result was False
2026-01-30 00:14:51.249810 | 
2026-01-30 00:14:51.249970 | TASK [use-our-mirror : Copy the podman configuration]
2026-01-30 00:14:51.275415 | controller | skipping: Conditional result was False
2026-01-30 00:14:51.285170 | 
2026-01-30 00:14:51.285310 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-01-30 00:14:51.310831 | controller | skipping: Conditional result was False
2026-01-30 00:14:51.319956 | 
2026-01-30 00:14:51.320062 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-01-30 00:14:51.345329 | controller | skipping: Conditional result was False
2026-01-30 00:14:51.367144 | 
2026-01-30 00:14:51.367320 | TASK [Disable Fedora Modular]
2026-01-30 00:14:52.610380 | controller | changed
2026-01-30 00:14:52.618305 | 
2026-01-30 00:14:52.618410 | TASK [Enable EPEL]
2026-01-30 00:14:52.642419 | controller | skipping: Conditional result was False
2026-01-30 00:14:52.648756 | 
2026-01-30 00:14:52.648853 | TASK [Register the RHEL node]
2026-01-30 00:14:53.051300 | 
2026-01-30 00:14:53.051599 | TASK [Show the subscription-manager status]
2026-01-30 00:14:53.443322 | controller | skipping: Conditional result was False
2026-01-30 00:14:53.460321 | 
2026-01-30 00:14:53.460561 | TASK [Enable EPEL on RHEL]
2026-01-30 00:14:53.859962 | controller | skipping: Conditional result was False
2026-01-30 00:14:53.875138 | 
2026-01-30 00:14:53.875320 | TASK [Install git and tox]
2026-01-30 00:17:00.708775 | controller | changed
2026-01-30 00:17:00.722912 | 
2026-01-30 00:17:00.723068 | TASK [include_role : prepare-workspace]
2026-01-30 00:17:00.758560 | controller | ok
2026-01-30 00:17:00.785295 | 
2026-01-30 00:17:00.785402 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-01-30 00:17:01.620635 | controller | ok
2026-01-30 00:17:01.629130 | 
2026-01-30 00:17:01.629234 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-01-30 00:17:17.846919 | controller | Output suppressed because no_log was given
2026-01-30 00:17:17.874247 | 
2026-01-30 00:17:17.874388 | TASK [include_role : prepare-workspace-openshift]
2026-01-30 00:17:17.894144 | controller | skipping: Conditional result was False
2026-01-30 00:17:17.930818 | 
2026-01-30 00:17:17.930947 | PLAY [all:!appliance]
2026-01-30 00:17:17.957086 | 
2026-01-30 00:17:17.957221 | TASK [Run add-build-sshkey role (RSA)]
2026-01-30 00:17:17.978257 | controller | ok
2026-01-30 00:17:17.995265 | 
2026-01-30 00:17:17.995386 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-30 00:17:18.244278 | controller -> localhost | ok
2026-01-30 00:17:18.267120 | 
2026-01-30 00:17:18.267260 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-30 00:17:18.296398 | controller | ok
2026-01-30 00:17:18.313762 | controller | included: /var/lib/zuul/builds/5e36017a749642bca526aaf17f11fcf9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-30 00:17:18.320639 | 
2026-01-30 00:17:18.321402 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-30 00:17:18.863852 | controller -> localhost | Generating public/private rsa key pair.
2026-01-30 00:17:18.864075 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/5e36017a749642bca526aaf17f11fcf9/work/5e36017a749642bca526aaf17f11fcf9_id_rsa.
2026-01-30 00:17:18.864105 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/5e36017a749642bca526aaf17f11fcf9/work/5e36017a749642bca526aaf17f11fcf9_id_rsa.pub.
2026-01-30 00:17:18.864129 | controller -> localhost | The key fingerprint is:
2026-01-30 00:17:18.864151 | controller -> localhost | SHA256:TCt/mNN6WCyuF47ZXywaO7fAhvLjCsrFFOfQmIBNbjg zuul-build-sshkey
2026-01-30 00:17:18.864204 | controller -> localhost | The key's randomart image is:
2026-01-30 00:17:18.864229 | controller -> localhost | +---[RSA 2048]----+
2026-01-30 00:17:18.864249 | controller -> localhost | |.+.              |
2026-01-30 00:17:18.864270 | controller -> localhost | |.oo +            |
2026-01-30 00:17:18.864291 | controller -> localhost | |E o= o  .        |
2026-01-30 00:17:18.864310 | controller -> localhost | | o  =  o .       |
2026-01-30 00:17:18.864329 | controller -> localhost | |   . .. S.       |
2026-01-30 00:17:18.864357 | controller -> localhost | |  o    =o+o.     |
2026-01-30 00:17:18.864380 | controller -> localhost | |  .o. .*X=+ o    |
2026-01-30 00:17:18.864403 | controller -> localhost | |.... o+.BOoo     |
2026-01-30 00:17:18.864423 | controller -> localhost | |..  .o+++=o.     |
2026-01-30 00:17:18.864446 | controller -> localhost | +----[SHA256]-----+
2026-01-30 00:17:18.864500 | controller -> localhost | ok: Runtime: 0:00:00.104203
2026-01-30 00:17:18.871095 | 
2026-01-30 00:17:18.871159 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-30 00:17:18.890626 | controller | ok
2026-01-30 00:17:18.900826 | controller | included: /var/lib/zuul/builds/5e36017a749642bca526aaf17f11fcf9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-30 00:17:18.909914 | 
2026-01-30 00:17:18.909983 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-30 00:17:18.923616 | controller | skipping: Conditional result was False
2026-01-30 00:17:18.930959 | 
2026-01-30 00:17:18.931030 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-30 00:17:19.971505 | controller | changed
2026-01-30 00:17:19.980304 | 
2026-01-30 00:17:19.980438 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-30 00:17:20.641195 | controller | ok
2026-01-30 00:17:20.650963 | 
2026-01-30 00:17:20.651094 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-30 00:17:23.660868 | controller | changed
2026-01-30 00:17:23.666824 | 
2026-01-30 00:17:23.666904 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-30 00:17:26.616844 | controller | changed
2026-01-30 00:17:26.625853 | 
2026-01-30 00:17:26.625993 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-30 00:17:26.651712 | controller | skipping: Conditional result was False
2026-01-30 00:17:26.661580 | 
2026-01-30 00:17:26.661772 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-30 00:17:27.056420 | controller -> localhost | changed
2026-01-30 00:17:27.068517 | 
2026-01-30 00:17:27.068638 | TASK [add-build-sshkey : Add back temp key]
2026-01-30 00:17:27.411098 | controller -> localhost | Identity added: /var/lib/zuul/builds/5e36017a749642bca526aaf17f11fcf9/work/5e36017a749642bca526aaf17f11fcf9_id_rsa (zuul-build-sshkey)
2026-01-30 00:17:27.411400 | controller -> localhost | ok: Runtime: 0:00:00.011175
2026-01-30 00:17:27.424734 | 
2026-01-30 00:17:27.424879 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-30 00:17:28.349315 | controller | ok
2026-01-30 00:17:28.358275 | 
2026-01-30 00:17:28.358452 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-30 00:17:28.396950 | controller | skipping: Conditional result was False
2026-01-30 00:17:28.409707 | 
2026-01-30 00:17:28.409808 | TASK [Run add-build-sshkey role (ECDSA)]
2026-01-30 00:17:28.441748 | controller | ok
2026-01-30 00:17:28.458434 | 
2026-01-30 00:17:28.458516 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-30 00:17:28.738622 | controller -> localhost | ok
2026-01-30 00:17:28.746919 | 
2026-01-30 00:17:28.747025 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-30 00:17:28.781733 | controller | ok
2026-01-30 00:17:28.803615 | controller | included: /var/lib/zuul/builds/5e36017a749642bca526aaf17f11fcf9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-30 00:17:28.812737 | 
2026-01-30 00:17:28.812908 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-30 00:17:29.106812 | controller -> localhost | Generating public/private ecdsa key pair.
2026-01-30 00:17:29.107130 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/5e36017a749642bca526aaf17f11fcf9/work/5e36017a749642bca526aaf17f11fcf9_id_ecdsa.
2026-01-30 00:17:29.107182 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/5e36017a749642bca526aaf17f11fcf9/work/5e36017a749642bca526aaf17f11fcf9_id_ecdsa.pub.
2026-01-30 00:17:29.107232 | controller -> localhost | The key fingerprint is:
2026-01-30 00:17:29.107268 | controller -> localhost | SHA256:QaZq+O7V7suKIoxBCBIAlvwVTuYI1OZAsUBbLDRBhns zuul-build-sshkey
2026-01-30 00:17:29.107299 | controller -> localhost | The key's randomart image is:
2026-01-30 00:17:29.107333 | controller -> localhost | +---[ECDSA 521]---+
2026-01-30 00:17:29.107363 | controller -> localhost | |/#*. +. o        |
2026-01-30 00:17:29.107392 | controller -> localhost | |B=*+*. +         |
2026-01-30 00:17:29.107422 | controller -> localhost | |++*..o. .        |
2026-01-30 00:17:29.107451 | controller -> localhost | |o.E+ .   .       |
2026-01-30 00:17:29.107479 | controller -> localhost | |... o   S        |
2026-01-30 00:17:29.107509 | controller -> localhost | |.  o   .         |
2026-01-30 00:17:29.107539 | controller -> localhost | |o.  . . .        |
2026-01-30 00:17:29.107567 | controller -> localhost | |o... o o         |
2026-01-30 00:17:29.107596 | controller -> localhost | | . o+ .o=.       |
2026-01-30 00:17:29.107623 | controller -> localhost | +----[SHA256]-----+
2026-01-30 00:17:29.107720 | controller -> localhost | ok: Runtime: 0:00:00.010525
2026-01-30 00:17:29.117281 | 
2026-01-30 00:17:29.117430 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-30 00:17:29.151443 | controller | ok
2026-01-30 00:17:29.162542 | controller | included: /var/lib/zuul/builds/5e36017a749642bca526aaf17f11fcf9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-30 00:17:29.178233 | 
2026-01-30 00:17:29.178618 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-30 00:17:29.204648 | controller | skipping: Conditional result was False
2026-01-30 00:17:29.255913 | 
2026-01-30 00:17:29.256044 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-30 00:17:30.160417 | controller | changed
2026-01-30 00:17:30.173387 | 
2026-01-30 00:17:30.173567 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-30 00:17:30.838633 | controller | ok
2026-01-30 00:17:30.852957 | 
2026-01-30 00:17:30.853150 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-30 00:17:33.861382 | controller | changed
2026-01-30 00:17:33.873750 | 
2026-01-30 00:17:33.873893 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-30 00:17:36.840750 | controller | changed
2026-01-30 00:17:36.849944 | 
2026-01-30 00:17:36.850070 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-30 00:17:36.886999 | controller | skipping: Conditional result was False
2026-01-30 00:17:36.894424 | 
2026-01-30 00:17:36.894555 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-30 00:17:37.206634 | controller -> localhost | changed
2026-01-30 00:17:37.219842 | 
2026-01-30 00:17:37.220012 | TASK [add-build-sshkey : Add back temp key]
2026-01-30 00:17:37.469391 | controller -> localhost | Identity added: /var/lib/zuul/builds/5e36017a749642bca526aaf17f11fcf9/work/5e36017a749642bca526aaf17f11fcf9_id_ecdsa (zuul-build-sshkey)
2026-01-30 00:17:37.469639 | controller -> localhost | ok: Runtime: 0:00:00.010454
2026-01-30 00:17:37.478255 | 
2026-01-30 00:17:37.478369 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-30 00:17:38.185757 | controller | ok
2026-01-30 00:17:38.192976 | 
2026-01-30 00:17:38.193054 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-30 00:17:38.228900 | controller | skipping: Conditional result was False
2026-01-30 00:17:38.256072 | 
2026-01-30 00:17:38.256261 | TASK [include_role : remove-zuul-sshkey]
2026-01-30 00:17:38.282552 | controller | skipping: Conditional result was False
2026-01-30 00:17:38.295612 | 
2026-01-30 00:17:38.295821 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-30 00:17:38.950146 | controller | ok: "logs"
2026-01-30 00:17:38.950614 | controller | ok: All items complete
2026-01-30 00:17:38.950743 | 
2026-01-30 00:17:39.552532 | controller | ok: "artifacts"
2026-01-30 00:17:40.158567 | controller | ok: "docs"
2026-01-30 00:17:40.176319 | 
2026-01-30 00:17:40.176447 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-30 00:17:40.832155 | controller | changed: "logs"
2026-01-30 00:17:41.437937 | controller | changed: "artifacts"
2026-01-30 00:17:42.062232 | controller | changed: "docs"
2026-01-30 00:17:42.112969 | 
2026-01-30 00:17:42.113058 | PLAY RECAP
2026-01-30 00:17:42.113100 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-01-30 00:17:42.113127 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-30 00:17:42.113145 | 
2026-01-30 00:17:42.239865 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-30 00:17:42.240726 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-30 00:17:42.916882 | 
2026-01-30 00:17:42.917014 | PLAY [all]
2026-01-30 00:17:42.942721 | 
2026-01-30 00:17:42.942886 | TASK [Install binary dependencies]
2026-01-30 00:17:42.998964 | controller | ok
2026-01-30 00:17:43.020192 | 
2026-01-30 00:17:43.020333 | TASK [bindep : Include find tasks]
2026-01-30 00:17:43.050507 | controller | ok
2026-01-30 00:17:43.060597 | controller | included: /var/lib/zuul/builds/5e36017a749642bca526aaf17f11fcf9/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-01-30 00:17:43.067552 | 
2026-01-30 00:17:43.067631 | TASK [bindep : Look for bindep.txt]
2026-01-30 00:17:43.969062 | controller | ok
2026-01-30 00:17:43.976155 | 
2026-01-30 00:17:43.976274 | TASK [bindep : Define bindep_file fact]
2026-01-30 00:17:44.011651 | controller | skipping: Conditional result was False
2026-01-30 00:17:44.018997 | 
2026-01-30 00:17:44.019142 | TASK [bindep : Look for other-requirements.txt]
2026-01-30 00:17:44.625958 | controller | ok
2026-01-30 00:17:44.634970 | 
2026-01-30 00:17:44.635083 | TASK [bindep : Define bindep_file fact]
2026-01-30 00:17:44.670232 | controller | skipping: Conditional result was False
2026-01-30 00:17:44.680496 | 
2026-01-30 00:17:44.680638 | TASK [bindep : Look for bindep fallback file]
2026-01-30 00:17:44.713963 | controller | skipping: Conditional result was False
2026-01-30 00:17:44.722203 | 
2026-01-30 00:17:44.722325 | TASK [bindep : Define bindep_file fact]
2026-01-30 00:17:44.747961 | controller | skipping: Conditional result was False
2026-01-30 00:17:44.764705 | 
2026-01-30 00:17:44.764828 | TASK [bindep : Include bindep tasks]
2026-01-30 00:17:44.789831 | controller | skipping: Conditional result was False
2026-01-30 00:17:44.796898 | 
2026-01-30 00:17:44.796966 | TASK [bindep : Include install tasks]
2026-01-30 00:17:44.820325 | controller | skipping: Conditional result was False
2026-01-30 00:17:44.826973 | 
2026-01-30 00:17:44.827040 | LOOP [bindep : Include package tasks]
2026-01-30 00:17:44.886970 | 
2026-01-30 00:17:44.887144 | TASK [Run test-setup role]
2026-01-30 00:17:44.908004 | controller | ok
2026-01-30 00:17:44.927771 | 
2026-01-30 00:17:44.927889 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-01-30 00:17:45.615043 | controller | ok
2026-01-30 00:17:45.621787 | 
2026-01-30 00:17:45.621897 | TASK [test-setup : Run tools/test-setup.sh]
2026-01-30 00:17:45.984369 | controller | skipping: Conditional result was False
2026-01-30 00:17:46.040612 | 
2026-01-30 00:17:46.040845 | PLAY RECAP
2026-01-30 00:17:46.040913 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-01-30 00:17:46.040945 | 
2026-01-30 00:17:46.211163 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-30 00:17:46.212268 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-01-30 00:17:46.929515 | 
2026-01-30 00:17:46.929716 | PLAY [controller]
2026-01-30 00:17:46.951427 | 
2026-01-30 00:17:46.951585 | TASK [Create the /root directory]
2026-01-30 00:17:48.028500 | controller | ok
2026-01-30 00:17:48.038534 | 
2026-01-30 00:17:48.038735 | TASK [Install glibc-langpack-en]
2026-01-30 00:17:55.846999 | controller | ok: Nothing to do
2026-01-30 00:17:55.859617 | 
2026-01-30 00:17:55.859841 | TASK [Ensure controller directory exists]
2026-01-30 00:17:56.660718 | controller | changed
2026-01-30 00:17:56.675376 | 
2026-01-30 00:17:56.675570 | TASK [Install container runtime]
2026-01-30 00:17:56.735089 | controller | ok
2026-01-30 00:17:56.800580 | 
2026-01-30 00:17:56.800720 | LOOP [ensure-docker : Gather variables for each operating system]
2026-01-30 00:17:56.842081 | controller | ok: "/var/lib/zuul/builds/5e36017a749642bca526aaf17f11fcf9/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2026-01-30 00:17:56.854301 | 
2026-01-30 00:17:56.854430 | TASK [ensure-docker : Sanity check for distro selection]
2026-01-30 00:17:56.879127 | controller | skipping: Conditional result was False
2026-01-30 00:17:56.889114 | 
2026-01-30 00:17:56.889215 | TASK [ensure-docker : Sanity check for distro install]
2026-01-30 00:17:56.916415 | controller | skipping: Conditional result was False
2026-01-30 00:17:56.926366 | 
2026-01-30 00:17:56.926519 | TASK [ensure-docker : Sanity check for upstream install]
2026-01-30 00:17:56.953457 | controller | skipping: Conditional result was False
2026-01-30 00:17:56.964567 | 
2026-01-30 00:17:56.964777 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2026-01-30 00:17:56.991292 | controller | skipping: Conditional result was False
2026-01-30 00:17:57.000881 | 
2026-01-30 00:17:57.000999 | TASK [ensure-docker : Include tasks for docker install]
2026-01-30 00:17:57.037103 | controller | ok
2026-01-30 00:17:57.056403 | controller | included: /var/lib/zuul/builds/5e36017a749642bca526aaf17f11fcf9/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2026-01-30 00:17:57.065207 | 
2026-01-30 00:17:57.065360 | TASK [ensure-docker : Not Implemented]
2026-01-30 00:17:57.091548 | controller | skipping: Conditional result was False
2026-01-30 00:17:57.100107 | 
2026-01-30 00:17:57.100260 | TASK [Set up docker mirrors]
2026-01-30 00:17:57.123017 | controller | ok
2026-01-30 00:17:57.151783 | 
2026-01-30 00:17:57.152009 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2026-01-30 00:17:57.180133 | controller | skipping: Conditional result was False
2026-01-30 00:17:57.188344 | 
2026-01-30 00:17:57.188475 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2026-01-30 00:17:57.241831 | controller | skipping: Conditional result was False
2026-01-30 00:17:57.250184 | 
2026-01-30 00:17:57.250362 | TASK [use-docker-mirror : Set docker_mirror fact]
2026-01-30 00:17:57.327353 | controller | skipping: Conditional result was False
2026-01-30 00:17:57.391444 | 
2026-01-30 00:17:57.391702 | TASK [use-docker-mirror : Set up docker mirrors]
2026-01-30 00:17:57.430922 | controller | skipping: Conditional result was False
2026-01-30 00:17:57.448134 | 
2026-01-30 00:17:57.448300 | TASK [Add all repositories]
2026-01-30 00:17:57.480866 | controller | ok
2026-01-30 00:17:57.507729 | 
2026-01-30 00:17:57.507890 | LOOP [ensure-package-repositories : Install repositories]
2026-01-30 00:17:57.548179 | controller | ok: "/var/lib/zuul/builds/5e36017a749642bca526aaf17f11fcf9/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2026-01-30 00:17:57.550293 | controller | ok: All items complete
2026-01-30 00:17:57.551145 | 
2026-01-30 00:17:57.574639 | controller | included: /var/lib/zuul/builds/5e36017a749642bca526aaf17f11fcf9/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2026-01-30 00:17:57.584239 | 
2026-01-30 00:17:57.584428 | TASK [ensure-package-repositories : Create GPG key temp directory]
2026-01-30 00:17:58.490957 | controller | changed
2026-01-30 00:17:58.504962 | 
2026-01-30 00:17:58.505144 | LOOP [ensure-package-repositories : Upload GPG keys]
2026-01-30 00:18:01.623053 | controller | changed:
2026-01-30 00:18:01.623232 | controller | {
2026-01-30 00:18:01.623259 | 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-30 00:18:01.623279 | controller | }
2026-01-30 00:18:01.631092 | 
2026-01-30 00:18:01.631205 | LOOP [ensure-package-repositories : Import GPG keys]
2026-01-30 00:18:02.847261 | controller | changed:
2026-01-30 00:18:02.847454 | controller | {
2026-01-30 00:18:02.847489 | 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-30 00:18:02.847519 | controller | }
2026-01-30 00:18:02.847595 | controller | changed: All items complete
2026-01-30 00:18:02.847630 | 
2026-01-30 00:18:02.865614 | 
2026-01-30 00:18:02.865766 | TASK [ensure-package-repositories : Remove GPG temp directory]
2026-01-30 00:18:03.525485 | controller | changed
2026-01-30 00:18:03.539041 | 
2026-01-30 00:18:03.539197 | LOOP [ensure-package-repositories : Add repositories]
2026-01-30 00:18:04.369070 | controller | changed:
2026-01-30 00:18:04.369285 | controller | {
2026-01-30 00:18:04.369321 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2026-01-30 00:18:04.369349 | controller |   "description": "Docker CE Stable - $basearch",
2026-01-30 00:18:04.369374 | controller |   "gpgcheck": true,
2026-01-30 00:18:04.369399 | controller |   "name": "docker-ce-stable"
2026-01-30 00:18:04.369423 | controller | }
2026-01-30 00:18:04.391936 | 
2026-01-30 00:18:04.392101 | TASK [ensure-docker : Install pre-reqs]
2026-01-30 00:18:09.079745 | controller | ok: Nothing to do
2026-01-30 00:18:09.087888 | 
2026-01-30 00:18:09.087969 | TASK [ensure-docker : Remove packages]
2026-01-30 00:18:12.068354 | controller | ok: Nothing to do
2026-01-30 00:18:12.074496 | 
2026-01-30 00:18:12.074630 | TASK [ensure-docker : Install upstream docker using package]
2026-01-30 00:19:32.372591 | controller | changed
2026-01-30 00:19:32.378726 | 
2026-01-30 00:19:32.378799 | TASK [ensure-docker : Setup docker]
2026-01-30 00:19:32.438594 | controller | ok
2026-01-30 00:19:32.448862 | controller | included: /var/lib/zuul/builds/5e36017a749642bca526aaf17f11fcf9/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2026-01-30 00:19:32.460056 | 
2026-01-30 00:19:32.460137 | TASK [ensure-docker : Ensure "docker" group exists]
2026-01-30 00:19:33.440659 | controller | ok
2026-01-30 00:19:33.460023 | 
2026-01-30 00:19:33.460277 | TASK [ensure-docker : Add user to docker group]
2026-01-30 00:19:34.447225 | controller | changed
2026-01-30 00:19:34.462911 | 
2026-01-30 00:19:34.463054 | TASK [Update docker daemon proxy configuration]
2026-01-30 00:19:34.501218 | controller | skipping: Conditional result was False
2026-01-30 00:19:34.511205 | 
2026-01-30 00:19:34.511346 | TASK [Lower default MTU]
2026-01-30 00:19:34.536317 | controller | skipping: Conditional result was False
2026-01-30 00:19:34.545768 | 
2026-01-30 00:19:34.545866 | TASK [ensure-docker : Get the status of the docket.socket unit]
2026-01-30 00:19:35.295381 | controller | inactive
2026-01-30 00:19:35.911641 | controller | ok: Runtime: 0:00:00.016868
2026-01-30 00:19:35.926351 | 
2026-01-30 00:19:35.926502 | TASK [ensure-docker : Get the status of the docket unit]
2026-01-30 00:19:36.683977 | controller | inactive
2026-01-30 00:19:37.296908 | controller | ok: Runtime: 0:00:00.020422
2026-01-30 00:19:37.312458 | 
2026-01-30 00:19:37.312599 | TASK [ensure-docker : Restart docker]
2026-01-30 00:19:37.374808 | Trigger a Docker restart
2026-01-30 00:19:37.387378 | 
2026-01-30 00:19:37.387533 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2026-01-30 00:19:37.428370 | 
2026-01-30 00:19:37.428520 | TASK [ensure-docker : Install docker-compose]
2026-01-30 00:19:37.454947 | controller | skipping: Conditional result was False
2026-01-30 00:19:37.464137 | 
2026-01-30 00:19:37.464274 | TASK [ensure-docker : Flush handlers before role exit]
2026-01-30 00:19:37.479143 | 
2026-01-30 00:19:37.479270 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2026-01-30 00:19:40.951099 | controller | changed
2026-01-30 00:19:40.969929 | 
2026-01-30 00:19:40.970157 | TASK [ensure-docker : Assure docker service is running]
2026-01-30 00:19:44.606443 | controller | changed
2026-01-30 00:19:44.614559 | 
2026-01-30 00:19:44.614651 | TASK [ensure-docker : Assure docker.socket service is running]
2026-01-30 00:19:46.072348 | controller | ok
2026-01-30 00:19:46.091671 | 
2026-01-30 00:19:46.091892 | TASK [ensure-docker : Correct group ownership on docker sock]
2026-01-30 00:19:46.887593 | controller | ok
2026-01-30 00:19:46.900120 | 
2026-01-30 00:19:46.900306 | TASK [ensure-docker : Validate ability to talk with docker]
2026-01-30 00:19:47.529129 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2026-01-30 00:19:47.772453 | controller | ok: Runtime: 0:00:00.039422
2026-01-30 00:19:47.785935 | 
2026-01-30 00:19:47.786135 | TASK [ensure-docker : Get version details]
2026-01-30 00:19:48.454267 | controller | Client: Docker Engine - Community
2026-01-30 00:19:48.456144 | controller |  Version:           24.0.7
2026-01-30 00:19:48.456165 | controller |  API version:       1.43
2026-01-30 00:19:48.456172 | controller |  Go version:        go1.20.10
2026-01-30 00:19:48.456189 | controller |  Git commit:        afdd53b
2026-01-30 00:19:48.456196 | controller |  Built:             Thu Oct 26 09:09:39 2023
2026-01-30 00:19:48.456203 | controller |  OS/Arch:           linux/amd64
2026-01-30 00:19:48.456211 | controller |  Context:           default
2026-01-30 00:19:48.456220 | controller |
2026-01-30 00:19:48.456227 | controller | Server: Docker Engine - Community
2026-01-30 00:19:48.456233 | controller |  Engine:
2026-01-30 00:19:48.456239 | controller |   Version:          24.0.7
2026-01-30 00:19:48.456245 | controller |   API version:      1.43 (minimum version 1.12)
2026-01-30 00:19:48.456251 | controller |   Go version:       go1.20.10
2026-01-30 00:19:48.456257 | controller |   Git commit:       311b9ff
2026-01-30 00:19:48.456263 | controller |   Built:            Thu Oct 26 09:08:11 2023
2026-01-30 00:19:48.456269 | controller |   OS/Arch:          linux/amd64
2026-01-30 00:19:48.456275 | controller |   Experimental:     false
2026-01-30 00:19:48.456281 | controller |  containerd:
2026-01-30 00:19:48.456287 | controller |   Version:          1.6.25
2026-01-30 00:19:48.456293 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2026-01-30 00:19:48.456299 | controller |  runc:
2026-01-30 00:19:48.456304 | controller |   Version:          1.1.10
2026-01-30 00:19:48.456310 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2026-01-30 00:19:48.456318 | controller |  docker-init:
2026-01-30 00:19:48.456324 | controller |   Version:          0.19.0
2026-01-30 00:19:48.456330 | controller |   GitCommit:        de40ad0
2026-01-30 00:19:48.699006 | controller | ok: Runtime: 0:00:00.042627
2026-01-30 00:19:48.708058 | 
2026-01-30 00:19:48.708206 | TASK [ensure-docker : Dump installed docker details]
2026-01-30 00:19:48.745446 | {'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-30 00:19:48.413972', 'end': '2026-01-30 00:19:48.456599', 'delta': '0:00:00.042627', 'msg': '', 'zuul_log_id': 'fa163e3b-3c83-15df-d196-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-30 00:19:48.758163 | 
2026-01-30 00:19:48.758314 | TASK [Use buildset registry]
2026-01-30 00:19:48.775087 | controller | skipping: Conditional result was False
2026-01-30 00:19:48.800527 | 
2026-01-30 00:19:48.800769 | TASK [Ensure python3.8 is present]
2026-01-30 00:19:48.816029 | controller | skipping: Conditional result was False
2026-01-30 00:19:48.827263 | 
2026-01-30 00:19:48.827429 | TASK [Run our-ensure-python role (Fedora only for now)]
2026-01-30 00:19:48.853428 | controller | ok
2026-01-30 00:19:48.882700 | 
2026-01-30 00:19:48.882827 | TASK [our-ensure-python : Install the right Python version (rpm)]
2026-01-30 00:19:55.223772 | controller | ok: Nothing to do
2026-01-30 00:19:55.231759 | 
2026-01-30 00:19:55.231878 | TASK [our-ensure-python : Also install python3-devel]
2026-01-30 00:20:10.675196 | controller | changed
2026-01-30 00:20:10.702207 | 
2026-01-30 00:20:10.702399 | TASK [Run ensure-virtualenv role]
2026-01-30 00:20:10.728878 | controller | ok
2026-01-30 00:20:10.759668 | 
2026-01-30 00:20:10.759802 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2026-01-30 00:20:11.511048 | controller | /usr/bin/virtualenv
2026-01-30 00:20:12.122093 | controller | ok: Runtime: 0:00:00.006209
2026-01-30 00:20:12.135460 | 
2026-01-30 00:20:12.135616 | LOOP [ensure-virtualenv : Install virtualenv package]
2026-01-30 00:20:12.171987 | controller | skipping: Conditional result was False
2026-01-30 00:20:12.173132 | controller | changed: All items complete
2026-01-30 00:20:12.173218 | 
2026-01-30 00:20:12.207249 | 
2026-01-30 00:20:12.207438 | TASK [Find the full path of the Python interpreter]
2026-01-30 00:20:13.015322 | controller | /usr/bin/python3.8
2026-01-30 00:20:13.580306 | controller | ok
2026-01-30 00:20:13.593759 | 
2026-01-30 00:20:13.593955 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2026-01-30 00:20:16.204274 | controller | created virtual environment CPython3.8.18.final.0-64 in 1100ms
2026-01-30 00:20:16.316927 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2026-01-30 00:20:16.316968 | 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-30 00:20:16.317068 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2026-01-30 00:20:16.317092 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2026-01-30 00:20:16.517879 | controller | changed
2026-01-30 00:20:16.525626 | 
2026-01-30 00:20:16.525840 | TASK [Set selinux package]
2026-01-30 00:20:16.561972 | controller | ok
2026-01-30 00:20:16.574316 | 
2026-01-30 00:20:16.574502 | TASK [Set selinux package (Fedora)]
2026-01-30 00:20:16.619565 | controller | ok
2026-01-30 00:20:16.626565 | 
2026-01-30 00:20:16.626661 | TASK [Install selinux into virtualenv]
2026-01-30 00:20:22.587416 | controller | Collecting selinux-please-lie-to-me
2026-01-30 00:20:22.669770 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2026-01-30 00:20:23.675248 | controller | Collecting setuptools<50.0.0
2026-01-30 00:20:23.682437 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2026-01-30 00:20:23.734154 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 17.3 MB/s eta 0:00:00
2026-01-30 00:20:23.863653 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2026-01-30 00:20:23.864062 | controller |   Attempting uninstall: setuptools
2026-01-30 00:20:23.867376 | controller |     Found existing installation: setuptools 62.6.0
2026-01-30 00:20:23.994140 | controller |     Uninstalling setuptools-62.6.0:
2026-01-30 00:20:24.009441 | controller |       Successfully uninstalled setuptools-62.6.0
2026-01-30 00:20:24.674796 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2026-01-30 00:20:24.879581 | controller |
2026-01-30 00:20:25.165010 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-01-30 00:20:25.165065 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-01-30 00:20:25.513378 | controller | ok: Runtime: 0:00:07.646765
2026-01-30 00:20:25.525445 | 
2026-01-30 00:20:25.525624 | TASK [Install pytest-forked into virtualenv]
2026-01-30 00:20:27.333673 | controller | Collecting pytest-forked
2026-01-30 00:20:27.394436 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2026-01-30 00:20:27.607486 | controller | Collecting pytest>=3.10
2026-01-30 00:20:27.613055 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2026-01-30 00:20:27.661613 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 8.2 MB/s eta 0:00:00
2026-01-30 00:20:27.728565 | controller | Collecting py
2026-01-30 00:20:27.737095 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2026-01-30 00:20:27.751939 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 9.3 MB/s eta 0:00:00
2026-01-30 00:20:27.836439 | controller | Collecting pluggy<2,>=1.5
2026-01-30 00:20:27.843721 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2026-01-30 00:20:27.925743 | controller | Collecting packaging
2026-01-30 00:20:27.932722 | controller |   Downloading packaging-26.0-py3-none-any.whl (74 kB)
2026-01-30 00:20:27.945324 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 7.3 MB/s eta 0:00:00
2026-01-30 00:20:28.040662 | controller | Collecting tomli>=1
2026-01-30 00:20:28.046127 | controller |   Downloading tomli-2.4.0-py3-none-any.whl (14 kB)
2026-01-30 00:20:28.110283 | controller | Collecting exceptiongroup>=1.0.0rc8
2026-01-30 00:20:28.118641 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2026-01-30 00:20:28.166579 | controller | Collecting iniconfig
2026-01-30 00:20:28.173226 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2026-01-30 00:20:28.261655 | controller | Collecting typing-extensions>=4.6.0
2026-01-30 00:20:28.268805 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2026-01-30 00:20:28.279444 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 5.4 MB/s eta 0:00:00
2026-01-30 00:20:28.390082 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2026-01-30 00:20:29.309944 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.1.0 packaging-26.0 pluggy-1.5.0 py-1.11.0 pytest-8.3.5 pytest-forked-1.6.0 tomli-2.4.0 typing-extensions-4.13.2
2026-01-30 00:20:29.316962 | controller |
2026-01-30 00:20:29.562020 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-01-30 00:20:29.562077 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-01-30 00:20:29.907507 | controller | ok: Runtime: 0:00:02.973091
2026-01-30 00:20:29.915788 | 
2026-01-30 00:20:29.915912 | TASK [Update pip]
2026-01-30 00:20:32.287446 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2026-01-30 00:20:32.766766 | controller | Collecting pip
2026-01-30 00:20:32.905622 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2026-01-30 00:20:33.025082 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 16.7 MB/s eta 0:00:00
2026-01-30 00:20:33.164558 | controller | Installing collected packages: pip
2026-01-30 00:20:33.169318 | controller |   Attempting uninstall: pip
2026-01-30 00:20:33.172631 | controller |     Found existing installation: pip 22.2.2
2026-01-30 00:20:33.558655 | controller |     Uninstalling pip-22.2.2:
2026-01-30 00:20:33.628124 | controller |       Successfully uninstalled pip-22.2.2
2026-01-30 00:20:36.255900 | controller | Successfully installed pip-25.0.1
2026-01-30 00:20:36.789037 | controller | ok: Runtime: 0:00:05.607593
2026-01-30 00:20:36.795194 | 
2026-01-30 00:20:36.795265 | TASK [Ensure pip temp directory exists in /var/tmp]
2026-01-30 00:20:37.832720 | controller | changed
2026-01-30 00:20:37.842935 | 
2026-01-30 00:20:37.843031 | TASK [Install ansible into virtualenv]
2026-01-30 00:20:39.922979 | controller | Processing ./src/github.com/ansible/ansible
2026-01-30 00:20:39.923424 | controller |   Preparing metadata (setup.py): started
2026-01-30 00:20:40.767014 | controller |   Preparing metadata (setup.py): finished with status 'done'
2026-01-30 00:20:40.834986 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2026-01-30 00:20:40.852770 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2026-01-30 00:20:40.938965 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2026-01-30 00:20:40.946394 | 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-30 00:20:41.395971 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2026-01-30 00:20:41.397365 | controller |   Downloading cryptography-46.0.4-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2026-01-30 00:20:41.419401 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (26.0)
2026-01-30 00:20:41.643348 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2026-01-30 00:20:41.648909 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2026-01-30 00:20:41.656974 | 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-30 00:20:41.775430 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2026-01-30 00:20:41.782257 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2026-01-30 00:20:41.815429 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2026-01-30 00:20:41.820770 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2026-01-30 00:20:41.839089 | controller | Downloading cryptography-46.0.4-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2026-01-30 00:20:41.896373 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 90.4 MB/s eta 0:00:00
2026-01-30 00:20:41.902311 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2026-01-30 00:20:41.918381 | 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-30 00:20:41.939818 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 42.4 MB/s eta 0:00:00
2026-01-30 00:20:41.948197 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2026-01-30 00:20:41.969892 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2026-01-30 00:20:41.985423 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2026-01-30 00:20:42.013279 | controller | Building wheels for collected packages: ansible-base
2026-01-30 00:20:42.014404 | controller |   Building wheel for ansible-base (setup.py): started
2026-01-30 00:20:44.084758 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2026-01-30 00:20:44.098333 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=062c41be8dad067ff157c76b065112b92ca009e33dbd067067c0b92df9e0d36d
2026-01-30 00:20:44.098375 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-mhx4hlav/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2026-01-30 00:20:44.098392 | controller | Successfully built ansible-base
2026-01-30 00:20:44.154822 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2026-01-30 00:20:47.165943 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-base-2.10.17.post0 cffi-1.17.1 cryptography-46.0.4 jinja2-3.1.6 pycparser-2.23
2026-01-30 00:20:47.709097 | controller | ok: Runtime: 0:00:08.724403
2026-01-30 00:20:47.717666 | 
2026-01-30 00:20:47.717784 | TASK [Setup download-artifact-fork role]
2026-01-30 00:20:47.741729 | controller | ok
2026-01-30 00:20:47.768166 | 
2026-01-30 00:20:47.768303 | TASK [download-artifact-fork : Parse inventory response]
2026-01-30 00:20:47.809212 | controller | ok
2026-01-30 00:20:47.815136 | 
2026-01-30 00:20:47.815219 | TASK [download-artifact-fork : Fetch info from Zuul API]
2026-01-30 00:20:47.850188 | controller | skipping: Conditional result was False
2026-01-30 00:20:47.859883 | 
2026-01-30 00:20:47.860009 | TASK [download-artifact-fork : Parse build response]
2026-01-30 00:20:47.895974 | controller | skipping: Conditional result was False
2026-01-30 00:20:47.906754 | 
2026-01-30 00:20:47.907015 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2026-01-30 00:20:48.752851 | controller | changed
2026-01-30 00:20:48.759925 | 
2026-01-30 00:20:48.760002 | LOOP [download-artifact-fork : Download archive by type]
2026-01-30 00:20:48.799696 | controller | skipping: Conditional result was False
2026-01-30 00:20:48.799952 | 
2026-01-30 00:20:51.492718 | controller | changed: OK (20194 bytes)
2026-01-30 00:20:51.508843 | 
2026-01-30 00:20:51.508965 | TASK [deploy-artifacts : define __collections]
2026-01-30 00:20:51.539120 | controller | ok
2026-01-30 00:20:51.544972 | 
2026-01-30 00:20:51.545055 | LOOP [deploy-artifacts : Create list of collections]
2026-01-30 00:20:51.577270 | controller | skipping: Conditional result was False
2026-01-30 00:20:51.588914 | controller | ok:
2026-01-30 00:20:51.589004 | controller | {
2026-01-30 00:20:51.589026 | controller |   "branch": "main",
2026-01-30 00:20:51.589043 | controller |   "job": "build-ansible-collection",
2026-01-30 00:20:51.589060 | controller |   "metadata": {
2026-01-30 00:20:51.589075 | controller |     "type": "ansible_collection",
2026-01-30 00:20:51.589091 | controller |     "version": "5.0.0"
2026-01-30 00:20:51.589106 | controller |   },
2026-01-30 00:20:51.589121 | controller |   "name": "ansible.network",
2026-01-30 00:20:51.589136 | controller |   "project": "ansible-collections/ansible.network",
2026-01-30 00:20:51.589151 | controller |   "url": "https://f754d0deaa4e84ec4c02-9c87e31440add31f916fc2b8bf42d9f9.ssl.cf2.rackcdn.com/ansible/48451acdb9e248609864564fc8b34df1/artifacts/ansible-network-5.0.0.tar.gz"
2026-01-30 00:20:51.589173 | controller | }
2026-01-30 00:20:51.596541 | 
2026-01-30 00:20:51.596628 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2026-01-30 00:20:53.017442 | controller | Starting galaxy collection install process
2026-01-30 00:20:53.017951 | controller | Process install dependency map
2026-01-30 00:21:06.413336 | controller | Starting collection install process
2026-01-30 00:21:06.425174 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2026-01-30 00:21:06.425242 | controller | ansible.network (5.0.0) was installed successfully
2026-01-30 00:21:06.425341 | controller | Installing 'ansible.netcommon:8.2.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-01-30 00:21:06.425358 | 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-4090_3noyhn0/tmpda53xjpg
2026-01-30 00:21:07.976509 | controller | ansible.netcommon (8.2.1) was installed successfully
2026-01-30 00:21:07.978417 | controller | Installing 'ansible.utils:6.0.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-01-30 00:21:07.978442 | 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-4090_3noyhn0/tmpda53xjpg
2026-01-30 00:21:09.475040 | controller | ansible.utils (6.0.1) was installed successfully
2026-01-30 00:21:09.475176 | controller | Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2026-01-30 00:21:09.475368 | 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-4090_3noyhn0/tmpda53xjpg
2026-01-30 00:21:12.003026 | controller | arista.eos (12.0.0) was installed successfully
2026-01-30 00:21:12.003151 | controller | Installing 'cisco.ios:11.2.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2026-01-30 00:21:12.003414 | 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-4090_3noyhn0/tmpda53xjpg
2026-01-30 00:21:15.032168 | controller | cisco.ios (11.2.0) was installed successfully
2026-01-30 00:21:15.032334 | controller | Installing 'cisco.iosxr:12.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2026-01-30 00:21:15.032350 | 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-4090_3noyhn0/tmpda53xjpg
2026-01-30 00:21:17.583347 | controller | cisco.iosxr (12.1.1) was installed successfully
2026-01-30 00:21:17.583466 | controller | Installing 'cisco.nxos:11.1.3' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2026-01-30 00:21:17.584772 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.1.3.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4090_3noyhn0/tmpda53xjpg
2026-01-30 00:21:21.584188 | controller | cisco.nxos (11.1.3) was installed successfully
2026-01-30 00:21:21.584341 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2026-01-30 00:21:21.584729 | 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-4090_3noyhn0/tmpda53xjpg
2026-01-30 00:21:24.143996 | controller | junipernetworks.junos (11.0.0) was installed successfully
2026-01-30 00:21:24.468795 | controller | ok: Runtime: 0:00:31.886347
2026-01-30 00:21:24.493489 | 
2026-01-30 00:21:24.493661 | TASK [podman may need iptables kernel modules]
2026-01-30 00:21:25.868659 | controller | ok: Runtime: 0:00:00.006125
2026-01-30 00:21:25.917069 | 
2026-01-30 00:21:25.917209 | PLAY RECAP
2026-01-30 00:21:25.917296 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2026-01-30 00:21:25.917339 | 
2026-01-30 00:21:26.059620 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-01-30 00:21:26.060891 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-01-30 00:21:26.734568 | 
2026-01-30 00:21:26.734773 | PLAY [controller]
2026-01-30 00:21:26.759899 | 
2026-01-30 00:21:26.760040 | TASK [Copy the galaxy.yml on the controller]
2026-01-30 00:21:30.469768 | controller | changed
2026-01-30 00:21:30.484229 | 
2026-01-30 00:21:30.484444 | TASK [Load information from galaxy.yml]
2026-01-30 00:21:30.536789 | controller | ok
2026-01-30 00:21:30.550921 | 
2026-01-30 00:21:30.551094 | LOOP [Enable FIPS mode]
2026-01-30 00:21:30.595696 | 
2026-01-30 00:21:30.596206 | LOOP [ansible-test : Include branch specific variables.]
2026-01-30 00:21:30.659136 | controller | ok: "/var/lib/zuul/builds/5e36017a749642bca526aaf17f11fcf9/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2026-01-30 00:21:30.659476 | 
2026-01-30 00:21:30.676547 | 
2026-01-30 00:21:30.676798 | TASK [ansible-test : Setup --skip-tags for test_options]
2026-01-30 00:21:30.733758 | controller | ok
2026-01-30 00:21:30.742508 | 
2026-01-30 00:21:30.742642 | TASK [ansible-test : Enable --inventory for network-integration]
2026-01-30 00:21:30.769619 | controller | skipping: Conditional result was False
2026-01-30 00:21:30.782081 | 
2026-01-30 00:21:30.782213 | TASK [ansible-test : Enable --retry-on-error]
2026-01-30 00:21:30.808918 | controller | skipping: Conditional result was False
2026-01-30 00:21:30.818203 | 
2026-01-30 00:21:30.818308 | TASK [ansible-test : Enable --continue-on-error]
2026-01-30 00:21:30.867697 | controller | ok
2026-01-30 00:21:30.876829 | 
2026-01-30 00:21:30.877026 | TASK [ansible-test : Enable --requirements]
2026-01-30 00:21:30.904115 | controller | skipping: Conditional result was False
2026-01-30 00:21:30.920415 | 
2026-01-30 00:21:30.920660 | TASK [ansible-test : Adjust options for unit tests]
2026-01-30 00:21:30.951005 | controller | skipping: Conditional result was False
2026-01-30 00:21:30.962568 | 
2026-01-30 00:21:30.962735 | TASK [ansible-test : Install requirements with unit tests]
2026-01-30 00:21:31.010215 | controller | ok
2026-01-30 00:21:31.076055 | 
2026-01-30 00:21:31.076240 | TASK [ansible-test : Set --skip-test]
2026-01-30 00:21:31.132877 | controller | skipping: Conditional result was False
2026-01-30 00:21:31.143488 | 
2026-01-30 00:21:31.143675 | TASK [ansible-test : Reduce verbosity]
2026-01-30 00:21:31.180971 | controller | ok
2026-01-30 00:21:31.192123 | 
2026-01-30 00:21:31.192296 | TASK [ansible-test : Setup --python option]
2026-01-30 00:21:31.219084 | controller | skipping: Conditional result was False
2026-01-30 00:21:31.229583 | 
2026-01-30 00:21:31.229702 | TASK [ansible-test : Setup --docker option]
2026-01-30 00:21:31.264156 | controller | ok
2026-01-30 00:21:31.274128 | 
2026-01-30 00:21:31.274233 | TASK [ansible-test : check if MANIFEST.json exists]
2026-01-30 00:21:32.401953 | controller | ok
2026-01-30 00:21:32.409786 | 
2026-01-30 00:21:32.409957 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2026-01-30 00:21:32.425965 | controller | skipping: Conditional result was False
2026-01-30 00:21:32.434024 | 
2026-01-30 00:21:32.434121 | TASK [ansible-test : Load information from MANIFEST.json]
2026-01-30 00:21:32.460217 | controller | skipping: Conditional result was False
2026-01-30 00:21:32.468100 | 
2026-01-30 00:21:32.468180 | TASK [ansible-test : Setup location of project for integration tests]
2026-01-30 00:21:32.494129 | controller | skipping: Conditional result was False
2026-01-30 00:21:32.503958 | 
2026-01-30 00:21:32.504073 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2026-01-30 00:21:33.732988 | controller | ok
2026-01-30 00:21:33.747506 | 
2026-01-30 00:21:33.747756 | TASK [ansible-test : Load information from galaxy.yml]
2026-01-30 00:21:33.810383 | controller | ok
2026-01-30 00:21:33.825970 | 
2026-01-30 00:21:33.826141 | TASK [ansible-test : Setup location of project for integration tests]
2026-01-30 00:21:33.888127 | controller | ok
2026-01-30 00:21:33.900950 | 
2026-01-30 00:21:33.901121 | TASK [ansible-test : Setup minimum test requirements]
2026-01-30 00:21:33.951141 | controller | ok
2026-01-30 00:21:33.964092 | 
2026-01-30 00:21:33.964296 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2026-01-30 00:21:34.947800 | controller | ok
2026-01-30 00:21:34.963832 | 
2026-01-30 00:21:34.964027 | TASK [ansible-test : Install python requirements]
2026-01-30 00:21:36.447042 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-01-30 00:21:36.462564 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2026-01-30 00:21:36.504598 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-01-30 00:21:36.509790 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2026-01-30 00:21:36.521985 | 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-30 00:21:36.530260 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2026-01-30 00:21:36.545969 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2026-01-30 00:21:36.601129 | controller | Installing collected packages: pathspec, yamllint
2026-01-30 00:21:36.689572 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2026-01-30 00:21:37.338234 | controller | ok: Runtime: 0:00:01.005167
2026-01-30 00:21:37.352834 | 
2026-01-30 00:21:37.353014 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2026-01-30 00:21:38.186543 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2026-01-30 00:21:38.734842 | controller | ERROR
2026-01-30 00:21:38.735364 | controller | {
2026-01-30 00:21:38.735432 | controller |   "delta": "0:00:00.006536",
2026-01-30 00:21:38.735475 | controller |   "end": "2026-01-30 00:21:38.186782",
2026-01-30 00:21:38.735514 | controller |   "msg": "non-zero return code",
2026-01-30 00:21:38.735550 | controller |   "rc": 1,
2026-01-30 00:21:38.735585 | controller |   "start": "2026-01-30 00:21:38.180246"
2026-01-30 00:21:38.735620 | controller | }
2026-01-30 00:21:38.735670 | controller | ERROR: Ignoring Errors
2026-01-30 00:21:38.750955 | 
2026-01-30 00:21:38.751110 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2026-01-30 00:21:39.135442 | controller | skipping: Conditional result was False
2026-01-30 00:21:39.150086 | 
2026-01-30 00:21:39.150266 | TASK [ansible-test : Enable persistent connection logging]
2026-01-30 00:21:40.448414 | controller | changed: section and option added
2026-01-30 00:21:40.459342 | 
2026-01-30 00:21:40.459453 | TASK [ansible-test : Set persistent ssh_type value]
2026-01-30 00:21:41.682638 | controller | changed: option added
2026-01-30 00:21:41.692127 | 
2026-01-30 00:21:41.692259 | TASK [ansible-test : Set ansible log path]
2026-01-30 00:21:42.855098 | controller | changed: section and option added
2026-01-30 00:21:42.870944 | 
2026-01-30 00:21:42.871156 | TASK [ansible-test : Identify the changed targets]
2026-01-30 00:21:43.243257 | controller | skipping: Conditional result was False
2026-01-30 00:21:43.258413 | 
2026-01-30 00:21:43.258614 | TASK [ansible-test : set_fact]
2026-01-30 00:21:43.307872 | controller | skipping: Conditional result was False
2026-01-30 00:21:43.322368 | 
2026-01-30 00:21:43.322558 | TASK [ansible-test : Set the targets]
2026-01-30 00:21:43.364167 | controller | ok
2026-01-30 00:21:43.419149 | 
2026-01-30 00:21:43.419307 | TASK [ansible-test : set_fact]
2026-01-30 00:21:43.455602 | controller | skipping: Conditional result was False
2026-01-30 00:21:43.470933 | 
2026-01-30 00:21:43.471179 | TASK [ansible-test : List the targets]
2026-01-30 00:21:43.860492 | controller | skipping: Conditional result was False
2026-01-30 00:21:43.875850 | 
2026-01-30 00:21:43.876077 | TASK [ansible-test : set_fact]
2026-01-30 00:21:43.895815 | controller | skipping: Conditional result was False
2026-01-30 00:21:43.919282 | 
2026-01-30 00:21:43.919524 | TASK [ansible-test : set_fact]
2026-01-30 00:21:43.941147 | controller | skipping: Conditional result was False
2026-01-30 00:21:43.955353 | 
2026-01-30 00:21:43.955559 | LOOP [ansible-test : set_fact]
2026-01-30 00:21:44.014874 | 
2026-01-30 00:21:44.015113 | TASK [ansible-test : set_fact]
2026-01-30 00:21:44.031467 | controller | skipping: Conditional result was False
2026-01-30 00:21:44.042666 | 
2026-01-30 00:21:44.042862 | TASK [ansible-test : debug]
2026-01-30 00:21:44.071089 | controller | skipping: Conditional result was False
2026-01-30 00:21:44.083339 | 
2026-01-30 00:21:44.083539 | TASK [ansible-test : debug]
2026-01-30 00:21:44.121900 | About to run: ansible-test sanity --requirements --docker -v
2026-01-30 00:21:44.130571 | 
2026-01-30 00:21:44.130760 | TASK [ansible-test : Run the test suite]
2026-01-30 00:21:45.760608 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2026-01-30 00:21:45.862781 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2026-01-30 00:21:47.747767 | controller | 2.9.0: Pulling from ansible/default-test-container
2026-01-30 00:21:47.747814 | controller | 171857c49d0f: Pulling fs layer
2026-01-30 00:21:47.747837 | controller | 419640447d26: Pulling fs layer
2026-01-30 00:21:47.747845 | controller | 61e52f862619: Pulling fs layer
2026-01-30 00:21:47.747852 | controller | 851de0426e26: Pulling fs layer
2026-01-30 00:21:47.747861 | controller | 37425716ea5b: Pulling fs layer
2026-01-30 00:21:47.747870 | controller | cd9b0c439616: Pulling fs layer
2026-01-30 00:21:47.747876 | controller | d4612dbd8855: Pulling fs layer
2026-01-30 00:21:47.747882 | controller | 554699364730: Pulling fs layer
2026-01-30 00:21:47.747888 | controller | 1ff15d4bdb3e: Pulling fs layer
2026-01-30 00:21:47.747894 | controller | 5f2e4724adea: Pulling fs layer
2026-01-30 00:21:47.747900 | controller | a02d9a95ccb8: Pulling fs layer
2026-01-30 00:21:47.747906 | controller | 989f4ce370cb: Pulling fs layer
2026-01-30 00:21:47.747912 | controller | 0d7f52cdf9df: Pulling fs layer
2026-01-30 00:21:47.747926 | controller | c423742351d5: Pulling fs layer
2026-01-30 00:21:47.747932 | controller | 109236341d21: Pulling fs layer
2026-01-30 00:21:47.747940 | controller | 07a2f61c96b8: Pulling fs layer
2026-01-30 00:21:47.747946 | controller | 041f122f27ae: Pulling fs layer
2026-01-30 00:21:47.747952 | controller | ca6f5098b12e: Pulling fs layer
2026-01-30 00:21:47.747959 | controller | 1eb3e7b2afd9: Pulling fs layer
2026-01-30 00:21:47.747967 | controller | 18a0b750727a: Pulling fs layer
2026-01-30 00:21:47.747973 | controller | 17f2f596716c: Pulling fs layer
2026-01-30 00:21:47.747981 | controller | f35a9998a9bd: Pulling fs layer
2026-01-30 00:21:47.747988 | controller | fdfc10566405: Pulling fs layer
2026-01-30 00:21:47.747995 | controller | 1ff1c442c5fe: Pulling fs layer
2026-01-30 00:21:47.748010 | controller | a3443c4a17ed: Pulling fs layer
2026-01-30 00:21:47.748020 | controller | 0edcf9fb2b15: Pulling fs layer
2026-01-30 00:21:48.133903 | controller | 5af43b69e845: Pulling fs layer
2026-01-30 00:21:48.133961 | controller | 1651773effb2: Pulling fs layer
2026-01-30 00:21:48.133969 | controller | 79e23c5c0fe4: Pulling fs layer
2026-01-30 00:21:48.133982 | controller | e5058b1faaa6: Pulling fs layer
2026-01-30 00:21:48.133988 | controller | 846775490569: Pulling fs layer
2026-01-30 00:21:48.133994 | controller | 851de0426e26: Waiting
2026-01-30 00:21:48.134000 | controller | 37425716ea5b: Waiting
2026-01-30 00:21:48.134006 | controller | cd9b0c439616: Waiting
2026-01-30 00:21:48.134012 | controller | d4612dbd8855: Waiting
2026-01-30 00:21:48.134018 | controller | 554699364730: Waiting
2026-01-30 00:21:48.134024 | controller | 1ff15d4bdb3e: Waiting
2026-01-30 00:21:48.134033 | controller | 5f2e4724adea: Waiting
2026-01-30 00:21:48.134039 | controller | a02d9a95ccb8: Waiting
2026-01-30 00:21:48.134045 | controller | 989f4ce370cb: Waiting
2026-01-30 00:21:48.134051 | controller | 0d7f52cdf9df: Waiting
2026-01-30 00:21:48.134057 | controller | c423742351d5: Waiting
2026-01-30 00:21:48.134063 | controller | 109236341d21: Waiting
2026-01-30 00:21:48.134079 | controller | 07a2f61c96b8: Waiting
2026-01-30 00:21:48.134085 | controller | 041f122f27ae: Waiting
2026-01-30 00:21:48.134097 | controller | ca6f5098b12e: Waiting
2026-01-30 00:21:48.134103 | controller | 1eb3e7b2afd9: Waiting
2026-01-30 00:21:48.134109 | controller | 18a0b750727a: Waiting
2026-01-30 00:21:48.134115 | controller | 17f2f596716c: Waiting
2026-01-30 00:21:48.134124 | controller | f35a9998a9bd: Waiting
2026-01-30 00:21:48.134130 | controller | fdfc10566405: Waiting
2026-01-30 00:21:48.134136 | controller | 1ff1c442c5fe: Waiting
2026-01-30 00:21:48.134150 | controller | a3443c4a17ed: Waiting
2026-01-30 00:21:48.134156 | controller | 0edcf9fb2b15: Waiting
2026-01-30 00:21:48.134162 | controller | 5af43b69e845: Waiting
2026-01-30 00:21:48.134168 | controller | 1651773effb2: Waiting
2026-01-30 00:21:48.134174 | controller | 79e23c5c0fe4: Waiting
2026-01-30 00:21:48.134180 | controller | e5058b1faaa6: Waiting
2026-01-30 00:21:48.134186 | controller | 846775490569: Waiting
2026-01-30 00:21:48.134200 | controller | 61e52f862619: Verifying Checksum
2026-01-30 00:21:48.136053 | controller | 61e52f862619: Download complete
2026-01-30 00:21:48.136078 | controller | 419640447d26: Verifying Checksum
2026-01-30 00:21:48.415866 | controller | 419640447d26: Download complete
2026-01-30 00:21:48.415944 | controller | 171857c49d0f: Verifying Checksum
2026-01-30 00:21:48.989420 | controller | 171857c49d0f: Download complete
2026-01-30 00:21:48.989469 | controller | 851de0426e26: Verifying Checksum
2026-01-30 00:21:49.163105 | controller | 851de0426e26: Download complete
2026-01-30 00:21:49.164239 | controller | cd9b0c439616: Verifying Checksum
2026-01-30 00:21:49.852260 | controller | cd9b0c439616: Download complete
2026-01-30 00:21:49.852330 | controller | d4612dbd8855: Verifying Checksum
2026-01-30 00:21:50.677781 | controller | d4612dbd8855: Download complete
2026-01-30 00:21:50.677832 | controller | 1ff15d4bdb3e: Verifying Checksum
2026-01-30 00:21:51.495502 | controller | 1ff15d4bdb3e: Download complete
2026-01-30 00:21:51.495565 | controller | 5f2e4724adea: Verifying Checksum
2026-01-30 00:21:51.802759 | controller | 5f2e4724adea: Download complete
2026-01-30 00:21:51.802814 | controller | 171857c49d0f: Pull complete
2026-01-30 00:21:51.837144 | controller | 419640447d26: Pull complete
2026-01-30 00:21:51.894163 | controller | 61e52f862619: Pull complete
2026-01-30 00:21:51.917986 | controller | 851de0426e26: Pull complete
2026-01-30 00:21:52.797789 | controller | a02d9a95ccb8: Download complete
2026-01-30 00:21:53.619753 | controller | 989f4ce370cb: Verifying Checksum
2026-01-30 00:21:54.570113 | controller | 989f4ce370cb: Download complete
2026-01-30 00:21:54.570175 | controller | 0d7f52cdf9df: Verifying Checksum
2026-01-30 00:21:55.360365 | controller | 0d7f52cdf9df: Download complete
2026-01-30 00:21:55.360436 | controller | c423742351d5: Verifying Checksum
2026-01-30 00:22:02.859435 | controller | c423742351d5: Download complete
2026-01-30 00:22:02.859497 | controller | 554699364730: Verifying Checksum
2026-01-30 00:22:03.611392 | controller | 554699364730: Download complete
2026-01-30 00:22:03.611469 | controller | 07a2f61c96b8: Verifying Checksum
2026-01-30 00:22:04.346859 | controller | 07a2f61c96b8: Download complete
2026-01-30 00:22:04.346919 | controller | 041f122f27ae: Verifying Checksum
2026-01-30 00:22:06.023612 | controller | 041f122f27ae: Download complete
2026-01-30 00:22:06.023695 | controller | ca6f5098b12e: Verifying Checksum
2026-01-30 00:22:06.264293 | controller | ca6f5098b12e: Download complete
2026-01-30 00:22:06.264361 | controller | 109236341d21: Verifying Checksum
2026-01-30 00:22:07.350952 | controller | 109236341d21: Download complete
2026-01-30 00:22:07.351024 | controller | 1eb3e7b2afd9: Verifying Checksum
2026-01-30 00:22:07.351579 | controller | 1eb3e7b2afd9: Download complete
2026-01-30 00:22:07.864544 | controller | 18a0b750727a: Verifying Checksum
2026-01-30 00:22:08.109739 | controller | 18a0b750727a: Download complete
2026-01-30 00:22:08.109810 | controller | 17f2f596716c: Verifying Checksum
2026-01-30 00:22:08.664102 | controller | 17f2f596716c: Download complete
2026-01-30 00:22:08.664161 | controller | f35a9998a9bd: Verifying Checksum
2026-01-30 00:22:09.061359 | controller | f35a9998a9bd: Download complete
2026-01-30 00:22:09.061449 | controller | fdfc10566405: Verifying Checksum
2026-01-30 00:22:09.446632 | controller | fdfc10566405: Download complete
2026-01-30 00:22:09.446717 | controller | 1ff1c442c5fe: Verifying Checksum
2026-01-30 00:22:09.447257 | controller | 1ff1c442c5fe: Download complete
2026-01-30 00:22:11.651016 | controller | 0edcf9fb2b15: Verifying Checksum
2026-01-30 00:22:11.651479 | controller | 0edcf9fb2b15: Download complete
2026-01-30 00:22:12.710394 | controller | a3443c4a17ed: Verifying Checksum
2026-01-30 00:22:14.545107 | controller | a3443c4a17ed: Download complete
2026-01-30 00:22:14.545167 | controller | 5af43b69e845: Verifying Checksum
2026-01-30 00:22:15.491923 | controller | 5af43b69e845: Download complete
2026-01-30 00:22:15.492005 | controller | 1651773effb2: Verifying Checksum
2026-01-30 00:22:15.582758 | controller | 1651773effb2: Download complete
2026-01-30 00:22:15.582820 | controller | 37425716ea5b: Verifying Checksum
2026-01-30 00:22:19.461467 | controller | 37425716ea5b: Download complete
2026-01-30 00:22:19.461530 | controller | e5058b1faaa6: Verifying Checksum
2026-01-30 00:22:19.970810 | controller | e5058b1faaa6: Download complete
2026-01-30 00:22:19.970870 | controller | 79e23c5c0fe4: Verifying Checksum
2026-01-30 00:22:20.741590 | controller | 79e23c5c0fe4: Download complete
2026-01-30 00:22:20.741648 | controller | 846775490569: Verifying Checksum
2026-01-30 00:22:35.724518 | controller | 846775490569: Download complete
2026-01-30 00:22:35.724594 | controller | 37425716ea5b: Pull complete
2026-01-30 00:22:35.757983 | controller | cd9b0c439616: Pull complete
2026-01-30 00:22:35.791401 | controller | d4612dbd8855: Pull complete
2026-01-30 00:22:42.023499 | controller | 554699364730: Pull complete
2026-01-30 00:22:42.058982 | controller | 1ff15d4bdb3e: Pull complete
2026-01-30 00:22:42.101084 | controller | 5f2e4724adea: Pull complete
2026-01-30 00:22:42.146877 | controller | a02d9a95ccb8: Pull complete
2026-01-30 00:22:42.179348 | controller | 989f4ce370cb: Pull complete
2026-01-30 00:22:42.221656 | controller | 0d7f52cdf9df: Pull complete
2026-01-30 00:22:42.284971 | controller | c423742351d5: Pull complete
2026-01-30 00:22:45.618567 | controller | 109236341d21: Pull complete
2026-01-30 00:22:45.686880 | controller | 07a2f61c96b8: Pull complete
2026-01-30 00:22:45.736976 | controller | 041f122f27ae: Pull complete
2026-01-30 00:22:45.955332 | controller | ca6f5098b12e: Pull complete
2026-01-30 00:22:46.034105 | controller | 1eb3e7b2afd9: Pull complete
2026-01-30 00:22:46.092450 | controller | 18a0b750727a: Pull complete
2026-01-30 00:22:46.127771 | controller | 17f2f596716c: Pull complete
2026-01-30 00:22:46.156141 | controller | f35a9998a9bd: Pull complete
2026-01-30 00:22:46.217571 | controller | fdfc10566405: Pull complete
2026-01-30 00:22:46.270642 | controller | 1ff1c442c5fe: Pull complete
2026-01-30 00:22:48.743517 | controller | a3443c4a17ed: Pull complete
2026-01-30 00:22:51.030723 | controller | 0edcf9fb2b15: Pull complete
2026-01-30 00:22:54.302817 | controller | 5af43b69e845: Pull complete
2026-01-30 00:22:57.608884 | controller | 1651773effb2: Pull complete
2026-01-30 00:23:00.985946 | controller | 79e23c5c0fe4: Pull complete
2026-01-30 00:23:03.738410 | controller | e5058b1faaa6: Pull complete
2026-01-30 00:23:07.153009 | controller | 846775490569: Pull complete
2026-01-30 00:23:07.191864 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2026-01-30 00:23:07.199034 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2026-01-30 00:23:07.231185 | controller | quay.io/ansible/default-test-container:2.9.0
2026-01-30 00:23:07.235729 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2026-01-30 00:23:07.290770 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2026-01-30 00:23:08.749339 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2026-01-30 00:23:08.751610 | controller | 04a5f4cda3ee: Pulling fs layer
2026-01-30 00:23:08.751775 | controller | ff496a88c8ed: Pulling fs layer
2026-01-30 00:23:08.751803 | controller | 0ce83f459fe7: Pulling fs layer
2026-01-30 00:23:08.751809 | controller | 2e5170e1f099: Pulling fs layer
2026-01-30 00:23:08.751818 | controller | 7641eb41b08c: Pulling fs layer
2026-01-30 00:23:08.751824 | controller | ad15fa9da398: Pulling fs layer
2026-01-30 00:23:08.751830 | controller | 087d91352424: Pulling fs layer
2026-01-30 00:23:08.751836 | controller | 8b92efd6a100: Pulling fs layer
2026-01-30 00:23:08.751846 | controller | 2e5170e1f099: Waiting
2026-01-30 00:23:09.129942 | controller | 7641eb41b08c: Waiting
2026-01-30 00:23:09.130038 | controller | ad15fa9da398: Waiting
2026-01-30 00:23:09.130046 | controller | 087d91352424: Waiting
2026-01-30 00:23:09.130053 | controller | 8b92efd6a100: Waiting
2026-01-30 00:23:09.130069 | controller | ff496a88c8ed: Verifying Checksum
2026-01-30 00:23:09.139896 | controller | ff496a88c8ed: Download complete
2026-01-30 00:23:09.139969 | controller | 0ce83f459fe7: Verifying Checksum
2026-01-30 00:23:09.140051 | controller | 0ce83f459fe7: Download complete
2026-01-30 00:23:09.852858 | controller | 7641eb41b08c: Verifying Checksum
2026-01-30 00:23:09.852988 | controller | 7641eb41b08c: Download complete
2026-01-30 00:23:09.959187 | controller | 2e5170e1f099: Verifying Checksum
2026-01-30 00:23:09.959303 | controller | 2e5170e1f099: Download complete
2026-01-30 00:23:10.693407 | controller | ad15fa9da398: Verifying Checksum
2026-01-30 00:23:10.693788 | controller | ad15fa9da398: Download complete
2026-01-30 00:23:10.768724 | controller | 087d91352424: Verifying Checksum
2026-01-30 00:23:10.768864 | controller | 087d91352424: Download complete
2026-01-30 00:23:11.506067 | controller | 8b92efd6a100: Verifying Checksum
2026-01-30 00:23:11.506215 | controller | 8b92efd6a100: Download complete
2026-01-30 00:23:14.183607 | controller | 04a5f4cda3ee: Verifying Checksum
2026-01-30 00:23:14.183765 | controller | 04a5f4cda3ee: Download complete
2026-01-30 00:23:16.910875 | controller | 04a5f4cda3ee: Pull complete
2026-01-30 00:23:16.946271 | controller | ff496a88c8ed: Pull complete
2026-01-30 00:23:16.974912 | controller | 0ce83f459fe7: Pull complete
2026-01-30 00:23:18.943657 | controller | 2e5170e1f099: Pull complete
2026-01-30 00:23:18.970477 | controller | 7641eb41b08c: Pull complete
2026-01-30 00:23:18.998979 | controller | ad15fa9da398: Pull complete
2026-01-30 00:23:20.442868 | controller | 087d91352424: Pull complete
2026-01-30 00:23:20.475289 | controller | 8b92efd6a100: Pull complete
2026-01-30 00:23:20.500813 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2026-01-30 00:23:20.505984 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2026-01-30 00:23:20.516044 | controller | quay.io/ansible/pypi-test-container:1.0.0
2026-01-30 00:23:20.522799 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2026-01-30 00:23:21.138796 | controller | Run command: docker inspect 75a8781e717267129374a629813faca31956e266310258eafe9712bdc9ee945a
2026-01-30 00:23:21.205218 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2026-01-30 00:23:21.221676 | controller | Including collection: ansible.netcommon (287 files)
2026-01-30 00:23:21.221781 | controller | Including collection: ansible.network (23 files)
2026-01-30 00:23:21.237201 | controller | Including collection: ansible.utils (441 files)
2026-01-30 00:23:21.280830 | controller | Including collection: arista.eos (1022 files)
2026-01-30 00:23:21.336177 | controller | Including collection: cisco.ios (1174 files)
2026-01-30 00:23:21.387655 | controller | Including collection: cisco.iosxr (1044 files)
2026-01-30 00:23:21.465614 | controller | Including collection: cisco.nxos (1687 files)
2026-01-30 00:23:21.515488 | controller | Including collection: junipernetworks.junos (963 files)
2026-01-30 00:23:21.564972 | controller | Creating a payload archive containing 7367 files...
2026-01-30 00:23:23.715566 | controller | Created a 6008234 byte payload archive containing 7367 files in 2 seconds.
2026-01-30 00:23:23.720198 | controller | Assuming Docker is available on localhost.
2026-01-30 00:23:23.720817 | 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-30 00:23:24.210760 | controller | Run command: docker exec -i ad9aa5648264b51c470a5a6be34ac814b7be1ceab78386112d9ce429a390eaa4 dd of=/root/docker.sh bs=65536
2026-01-30 00:23:24.292773 | controller | Run command: docker exec ad9aa5648264b51c470a5a6be34ac814b7be1ceab78386112d9ce429a390eaa4 /bin/bash /root/docker.sh
2026-01-30 00:23:24.368465 | controller | Run command: docker exec -i ad9aa5648264b51c470a5a6be34ac814b7be1ceab78386112d9ce429a390eaa4 dd of=/root/test.tgz bs=65536
2026-01-30 00:23:24.468776 | controller | Run command: docker exec ad9aa5648264b51c470a5a6be34ac814b7be1ceab78386112d9ce429a390eaa4 tar oxzf /root/test.tgz -C /root
2026-01-30 00:23:26.166475 | controller | Run command: docker exec ad9aa5648264b51c470a5a6be34ac814b7be1ceab78386112d9ce429a390eaa4 /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-jarjqr_0.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2026-01-30 00:23:26.632127 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2026-01-30 00:23:26.632452 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2026-01-30 00:23:26.633029 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2026-01-30 00:23:26.635469 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2026-01-30 00:23:26.636532 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2026-01-30 00:23:26.646208 | controller | No tests applicable.
2026-01-30 00:23:26.646383 | controller | Running sanity test 'ansible-doc' with Python 3.6
2026-01-30 00:23:26.646636 | controller | No tests applicable.
2026-01-30 00:23:26.646799 | controller | Running sanity test 'changelog' with Python 3.6
2026-01-30 00:23:26.647488 | 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-30 00:23:27.629398 | 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-30 00:23:28.243541 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2026-01-30 00:23:43.266265 | controller |   WARNING: Retrying (Retry(total=4, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2026-01-30 00:23:58.784418 | controller |   WARNING: Retrying (Retry(total=3, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2026-01-30 00:24:14.802733 | controller |   WARNING: Retrying (Retry(total=2, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2026-01-30 00:24:31.821424 | controller |   WARNING: Retrying (Retry(total=1, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2026-01-30 00:24:50.840511 | controller |   WARNING: Retrying (Retry(total=0, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2026-01-30 00:25:05.858266 | 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-30 00:25:05.861704 | 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-30 00:25:05.970240 | 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-30 00:25:06.016858 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2026-01-30 00:25:06.016908 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2026-01-30 00:25:06.016916 | controller | Removing custom PyPI hosts entries: /etc/hosts
2026-01-30 00:25:06.016930 | controller | Run command: docker exec ad9aa5648264b51c470a5a6be34ac814b7be1ceab78386112d9ce429a390eaa4 tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2026-01-30 00:25:06.105088 | controller | Run command: docker exec -i ad9aa5648264b51c470a5a6be34ac814b7be1ceab78386112d9ce429a390eaa4 dd if=/root/output.tgz bs=65536
2026-01-30 00:25:06.188731 | controller | Run command: tar oxzf /tmp/ansible-result-z7t_q72w.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2026-01-30 00:25:06.202754 | controller | Run command: docker rm -f 75a8781e717267129374a629813faca31956e266310258eafe9712bdc9ee945a
2026-01-30 00:25:06.492228 | controller | Run command: docker rm -f ad9aa5648264b51c470a5a6be34ac814b7be1ceab78386112d9ce429a390eaa4
2026-01-30 00:25:07.211169 | controller | ERROR: Command "docker exec ad9aa5648264b51c470a5a6be34ac814b7be1ceab78386112d9ce429a390eaa4 /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-jarjqr_0.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2026-01-30 00:25:07.669003 | controller | ERROR
2026-01-30 00:25:07.669385 | controller | {
2026-01-30 00:25:07.669453 | controller |   "delta": "0:03:22.047968",
2026-01-30 00:25:07.669497 | controller |   "end": "2026-01-30 00:25:07.269442",
2026-01-30 00:25:07.669536 | controller |   "msg": "non-zero return code",
2026-01-30 00:25:07.669572 | controller |   "rc": 1,
2026-01-30 00:25:07.669609 | controller |   "start": "2026-01-30 00:21:45.221474"
2026-01-30 00:25:07.669665 | controller | }
failure
2026-01-30 00:25:07.677150 | 
2026-01-30 00:25:07.677310 | PLAY RECAP
2026-01-30 00:25:07.677396 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2026-01-30 00:25:07.677441 | 
2026-01-30 00:25:07.805489 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-01-30 00:25:07.807307 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-30 00:25:08.370597 | 
2026-01-30 00:25:08.370740 | PLAY [all]
2026-01-30 00:25:08.392252 | 
2026-01-30 00:25:08.392384 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-01-30 00:25:11.465372 | controller | changed: non-zero return code
2026-01-30 00:25:11.476318 | 
2026-01-30 00:25:11.476475 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-01-30 00:25:11.502955 | controller | skipping: Conditional result was False
2026-01-30 00:25:11.513960 | 
2026-01-30 00:25:11.514101 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-01-30 00:25:11.561369 | 
2026-01-30 00:25:11.561612 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-01-30 00:25:11.605043 | 
2026-01-30 00:25:11.605392 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-01-30 00:25:11.633251 | controller | skipping: Conditional result was False
2026-01-30 00:25:11.648180 | 
2026-01-30 00:25:11.648378 | LOOP [fetch-subunit-output : Generate subunit file]
2026-01-30 00:25:11.689291 | 
2026-01-30 00:25:11.689592 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-01-30 00:25:11.710467 | controller | skipping: Conditional result was False
2026-01-30 00:25:11.728298 | 
2026-01-30 00:25:11.728472 | TASK [fetch-subunit-output : Remove the temporary file]
2026-01-30 00:25:11.754717 | controller | skipping: Conditional result was False
2026-01-30 00:25:11.770959 | 
2026-01-30 00:25:11.771228 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-01-30 00:25:11.789346 | controller | skipping: Conditional result was False
2026-01-30 00:25:11.845424 | 
2026-01-30 00:25:11.845561 | PLAY RECAP
2026-01-30 00:25:11.845621 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-01-30 00:25:11.845651 | 
2026-01-30 00:25:11.960346 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-30 00:25:11.961518 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-01-30 00:25:12.629195 | 
2026-01-30 00:25:12.629311 | PLAY [all:!appliance*]
2026-01-30 00:25:12.650540 | 
2026-01-30 00:25:12.650637 | TASK [unregister the node]
2026-01-30 00:25:13.013103 | controller | skipping: Conditional result was False
2026-01-30 00:25:13.027281 | 
2026-01-30 00:25:13.027520 | TASK [include_role : fetch-output]
2026-01-30 00:25:13.081710 | controller | ok
2026-01-30 00:25:13.115458 | 
2026-01-30 00:25:13.115672 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-30 00:25:13.192838 | controller | skipping: Conditional result was False
2026-01-30 00:25:13.209267 | 
2026-01-30 00:25:13.209549 | TASK [fetch-output : Set log path for single node]
2026-01-30 00:25:13.259031 | controller | ok
2026-01-30 00:25:13.270963 | 
2026-01-30 00:25:13.271100 | LOOP [fetch-output : Ensure local output dirs]
2026-01-30 00:25:13.748436 | controller -> localhost | ok: "/var/lib/zuul/builds/5e36017a749642bca526aaf17f11fcf9/work/logs"
2026-01-30 00:25:14.012354 | controller -> localhost | changed: "/var/lib/zuul/builds/5e36017a749642bca526aaf17f11fcf9/work/artifacts"
2026-01-30 00:25:14.282052 | controller -> localhost | changed: "/var/lib/zuul/builds/5e36017a749642bca526aaf17f11fcf9/work/docs"
2026-01-30 00:25:14.297084 | 
2026-01-30 00:25:14.297192 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-30 00:25:16.610167 | controller | changed:
2026-01-30 00:25:16.610530 | controller | .d..t...... ./
2026-01-30 00:25:16.610614 | controller | cd+++++++++ controller/
2026-01-30 00:25:16.610765 | controller | changed: All items complete
2026-01-30 00:25:16.610837 | 
2026-01-30 00:25:18.744218 | controller | changed: .d..t...... ./
2026-01-30 00:25:20.831655 | controller | changed: .d..t...... ./
2026-01-30 00:25:20.870731 | 
2026-01-30 00:25:20.871127 | TASK [include_role : fetch-output-openshift]
2026-01-30 00:25:20.897972 | controller | skipping: Conditional result was False
2026-01-30 00:25:20.908934 | 
2026-01-30 00:25:20.909028 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-30 00:25:20.955866 | controller | skipping: Conditional result was False
2026-01-30 00:25:20.967595 | controller | skipping: Conditional result was False
2026-01-30 00:25:21.025519 | 
2026-01-30 00:25:21.025762 | PLAY [localhost]
2026-01-30 00:25:21.043764 | 
2026-01-30 00:25:21.043850 | TASK [Run Zuul manifest role]
2026-01-30 00:25:21.074185 | localhost | ok
2026-01-30 00:25:21.091897 | 
2026-01-30 00:25:21.091989 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-30 00:25:21.545663 | localhost | changed
2026-01-30 00:25:21.556034 | 
2026-01-30 00:25:21.556165 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-30 00:25:21.591831 | localhost | ok
2026-01-30 00:25:21.612476 | 
2026-01-30 00:25:21.612650 | TASK [Set zuul-log-path fact]
2026-01-30 00:25:21.666526 | localhost | ok
2026-01-30 00:25:21.680306 | 
2026-01-30 00:25:21.680380 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-30 00:25:21.711847 | localhost | ok
2026-01-30 00:25:21.723643 | 
2026-01-30 00:25:21.723749 | LOOP [Run upload-logs-swift role]
2026-01-30 00:25:21.758734 | localhost | Output suppressed because no_log was given
2026-01-30 00:25:21.793748 | 
2026-01-30 00:25:21.793879 | TASK [Set zuul-log-path fact]
2026-01-30 00:25:21.839016 | localhost | skipping: Conditional result was False
2026-01-30 00:25:21.850759 | 
2026-01-30 00:25:21.851050 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-01-30 00:25:22.277552 | localhost -> localhost | ok: Runtime: 0:00:00.006849
2026-01-30 00:25:22.288918 | 
2026-01-30 00:25:22.289055 | TASK [upload-logs-swift : Upload logs to swift]