2025-12-15 00:06:45.494471 | Job console starting...
2025-12-15 00:06:45.505955 | Updating repositories
2025-12-15 00:06:45.666973 | Preparing job workspace
2025-12-15 00:06:49.769686 | Running Ansible setup...
2025-12-15 00:06:54.303798 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-12-15 00:06:54.898599 | 
2025-12-15 00:06:54.898752 | PLAY [localhost]
2025-12-15 00:06:54.907412 | 
2025-12-15 00:06:54.907490 | TASK [Gathering Facts]
2025-12-15 00:06:55.913628 | localhost | ok
2025-12-15 00:06:55.946042 | 
2025-12-15 00:06:55.946234 | TASK [Setup log path fact]
2025-12-15 00:06:55.969225 | localhost | ok
2025-12-15 00:06:55.988024 | 
2025-12-15 00:06:55.988141 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-15 00:06:56.039429 | localhost | ok
2025-12-15 00:06:56.065930 | 
2025-12-15 00:06:56.066127 | TASK [emit-job-header : Print job information]
2025-12-15 00:06:56.111715 | # Job Information
2025-12-15 00:06:56.112076 | Ansible Version: 2.15.12
2025-12-15 00:06:56.112121 | Job: ansible-test-sanity-docker-stable-2.10
2025-12-15 00:06:56.112151 | Pipeline: periodic
2025-12-15 00:06:56.112177 | Executor: ze03.softwarefactory-project.io
2025-12-15 00:06:56.112202 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-12-15 00:06:56.112231 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/aff/ansible/affd1c5246f743a7a6a307b8c61c86ad/
2025-12-15 00:06:56.112258 | Event ID: 4fcac70710aa4c2dbcb2a11ca9888703
2025-12-15 00:06:56.117845 | 
2025-12-15 00:06:56.117932 | LOOP [emit-job-header : Print node information]
2025-12-15 00:06:56.223719 | localhost | ok:
2025-12-15 00:06:56.223937 | localhost | # Node Information
2025-12-15 00:06:56.223963 | localhost | Inventory Hostname: controller
2025-12-15 00:06:56.223983 | localhost | Hostname: np0005559294
2025-12-15 00:06:56.224001 | localhost | Username: zuul
2025-12-15 00:06:56.224024 | localhost | Distro: Fedora 37
2025-12-15 00:06:56.224042 | localhost | Provider: ansible-vexxhost-ca-ymq-1
2025-12-15 00:06:56.224058 | localhost | Region: ca-ymq-1
2025-12-15 00:06:56.224075 | localhost | Label: ansible-fedora-37-1vcpu
2025-12-15 00:06:56.224091 | localhost | Product Name: OpenStack Nova
2025-12-15 00:06:56.224108 | localhost | Interface IP: 162.253.55.221
2025-12-15 00:06:56.247887 | 
2025-12-15 00:06:56.248095 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-15 00:06:56.724963 | localhost -> localhost | changed
2025-12-15 00:06:56.733679 | 
2025-12-15 00:06:56.733827 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-15 00:06:57.645992 | localhost -> localhost | changed
2025-12-15 00:06:57.675873 | 
2025-12-15 00:06:57.676081 | PLAY [all:!appliance*]
2025-12-15 00:06:57.700406 | 
2025-12-15 00:06:57.700521 | TASK [include_role : start-zuul-console]
2025-12-15 00:06:57.719703 | controller | ok
2025-12-15 00:06:57.733755 | 
2025-12-15 00:06:57.733825 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-15 00:06:58.097114 | controller | ok
2025-12-15 00:06:58.107577 | 
2025-12-15 00:06:58.107637 | TASK [use-our-mirror : Retrieve the IP address]
2025-12-15 00:06:59.761416 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-12-15 00:06:59.771950 | 
2025-12-15 00:06:59.772047 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-12-15 00:07:00.308176 | controller | skipping: Conditional result was False
2025-12-15 00:07:00.315160 | 
2025-12-15 00:07:00.315261 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-12-15 00:07:00.349593 | controller | skipping: Conditional result was False
2025-12-15 00:07:00.356947 | 
2025-12-15 00:07:00.357042 | TASK [use-our-mirror : Create the podman configuration directory]
2025-12-15 00:07:00.391284 | controller | skipping: Conditional result was False
2025-12-15 00:07:00.398343 | 
2025-12-15 00:07:00.398422 | TASK [use-our-mirror : Copy the podman configuration]
2025-12-15 00:07:00.412603 | controller | skipping: Conditional result was False
2025-12-15 00:07:00.419959 | 
2025-12-15 00:07:00.420058 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-12-15 00:07:00.458250 | controller | skipping: Conditional result was False
2025-12-15 00:07:00.465348 | 
2025-12-15 00:07:00.465444 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-12-15 00:07:00.492330 | controller | skipping: Conditional result was False
2025-12-15 00:07:00.506519 | 
2025-12-15 00:07:00.506620 | TASK [Disable Fedora Modular]
2025-12-15 00:07:00.765770 | controller | changed
2025-12-15 00:07:00.774385 | 
2025-12-15 00:07:00.774486 | TASK [Enable EPEL]
2025-12-15 00:07:00.800007 | controller | skipping: Conditional result was False
2025-12-15 00:07:00.809713 | 
2025-12-15 00:07:00.809870 | TASK [Register the RHEL node]
2025-12-15 00:07:01.376258 | 
2025-12-15 00:07:01.376413 | TASK [Show the subscription-manager status]
2025-12-15 00:07:01.983747 | controller | skipping: Conditional result was False
2025-12-15 00:07:01.990556 | 
2025-12-15 00:07:01.990650 | TASK [Enable EPEL on RHEL]
2025-12-15 00:07:02.557208 | controller | skipping: Conditional result was False
2025-12-15 00:07:02.570811 | 
2025-12-15 00:07:02.570997 | TASK [Install git and tox]
2025-12-15 00:09:32.375286 | controller | changed
2025-12-15 00:09:32.391240 | 
2025-12-15 00:09:32.391422 | TASK [include_role : prepare-workspace]
2025-12-15 00:09:32.433846 | controller | ok
2025-12-15 00:09:32.461522 | 
2025-12-15 00:09:32.461671 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-15 00:09:32.711092 | controller | ok
2025-12-15 00:09:32.725715 | 
2025-12-15 00:09:32.725902 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-15 00:09:45.356012 | controller | Output suppressed because no_log was given
2025-12-15 00:09:45.370407 | 
2025-12-15 00:09:45.370505 | TASK [include_role : prepare-workspace-openshift]
2025-12-15 00:09:45.388830 | controller | skipping: Conditional result was False
2025-12-15 00:09:45.449146 | 
2025-12-15 00:09:45.449244 | PLAY [all:!appliance]
2025-12-15 00:09:45.465990 | 
2025-12-15 00:09:45.466067 | TASK [Run add-build-sshkey role (RSA)]
2025-12-15 00:09:45.505460 | controller | ok
2025-12-15 00:09:45.521668 | 
2025-12-15 00:09:45.521767 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-15 00:09:45.810865 | controller -> localhost | ok
2025-12-15 00:09:45.827055 | 
2025-12-15 00:09:45.827280 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-15 00:09:45.871385 | controller | ok
2025-12-15 00:09:45.895428 | controller | included: /var/lib/zuul/builds/affd1c5246f743a7a6a307b8c61c86ad/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-15 00:09:45.906808 | 
2025-12-15 00:09:45.906966 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-15 00:09:46.320576 | controller -> localhost | Generating public/private rsa key pair.
2025-12-15 00:09:46.320835 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/affd1c5246f743a7a6a307b8c61c86ad/work/affd1c5246f743a7a6a307b8c61c86ad_id_rsa.
2025-12-15 00:09:46.320864 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/affd1c5246f743a7a6a307b8c61c86ad/work/affd1c5246f743a7a6a307b8c61c86ad_id_rsa.pub.
2025-12-15 00:09:46.320884 | controller -> localhost | The key fingerprint is:
2025-12-15 00:09:46.320903 | controller -> localhost | SHA256:Wkzdt2BbtlP6PoK/cIXQqN0YcAt/Y0OVbCeL863hYgc zuul-build-sshkey
2025-12-15 00:09:46.320921 | controller -> localhost | The key's randomart image is:
2025-12-15 00:09:46.320939 | controller -> localhost | +---[RSA 2048]----+
2025-12-15 00:09:46.320956 | controller -> localhost | |         o . .o..|
2025-12-15 00:09:46.320974 | controller -> localhost | |         .=.= .+.|
2025-12-15 00:09:46.320991 | controller -> localhost | |        . .*+B=oo|
2025-12-15 00:09:46.321008 | controller -> localhost | |       o  o.X*== |
2025-12-15 00:09:46.321024 | controller -> localhost | |        S. o.==o |
2025-12-15 00:09:46.321054 | controller -> localhost | |       o    E +o.|
2025-12-15 00:09:46.321080 | controller -> localhost | |      .    ..+ o.|
2025-12-15 00:09:46.321099 | controller -> localhost | |           .=.+o |
2025-12-15 00:09:46.321117 | controller -> localhost | |           ..=o o|
2025-12-15 00:09:46.321137 | controller -> localhost | +----[SHA256]-----+
2025-12-15 00:09:46.321185 | controller -> localhost | ok: Runtime: 0:00:00.053751
2025-12-15 00:09:46.329346 | 
2025-12-15 00:09:46.329430 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-15 00:09:46.360151 | controller | ok
2025-12-15 00:09:46.370802 | controller | included: /var/lib/zuul/builds/affd1c5246f743a7a6a307b8c61c86ad/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-15 00:09:46.380796 | 
2025-12-15 00:09:46.380913 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-15 00:09:46.405490 | controller | skipping: Conditional result was False
2025-12-15 00:09:46.413032 | 
2025-12-15 00:09:46.413129 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-15 00:09:46.825771 | controller | changed
2025-12-15 00:09:46.834810 | 
2025-12-15 00:09:46.834928 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-15 00:09:47.057528 | controller | ok
2025-12-15 00:09:47.072039 | 
2025-12-15 00:09:47.072194 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-15 00:09:47.723119 | controller | changed
2025-12-15 00:09:47.732396 | 
2025-12-15 00:09:47.732490 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-15 00:09:48.442843 | controller | changed
2025-12-15 00:09:48.456680 | 
2025-12-15 00:09:48.456985 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-15 00:09:48.484119 | controller | skipping: Conditional result was False
2025-12-15 00:09:48.493519 | 
2025-12-15 00:09:48.493608 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-15 00:09:48.930299 | controller -> localhost | changed
2025-12-15 00:09:48.956970 | 
2025-12-15 00:09:48.957247 | TASK [add-build-sshkey : Add back temp key]
2025-12-15 00:09:49.299422 | controller -> localhost | Identity added: /var/lib/zuul/builds/affd1c5246f743a7a6a307b8c61c86ad/work/affd1c5246f743a7a6a307b8c61c86ad_id_rsa (zuul-build-sshkey)
2025-12-15 00:09:49.299616 | controller -> localhost | ok: Runtime: 0:00:00.006704
2025-12-15 00:09:49.305885 | 
2025-12-15 00:09:49.305947 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-15 00:09:49.650599 | controller | ok
2025-12-15 00:09:49.664180 | 
2025-12-15 00:09:49.664328 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-15 00:09:49.705104 | controller | skipping: Conditional result was False
2025-12-15 00:09:49.721212 | 
2025-12-15 00:09:49.721333 | TASK [Run add-build-sshkey role (ECDSA)]
2025-12-15 00:09:49.758104 | controller | ok
2025-12-15 00:09:49.784864 | 
2025-12-15 00:09:49.784977 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-15 00:09:50.023857 | controller -> localhost | ok
2025-12-15 00:09:50.032430 | 
2025-12-15 00:09:50.032536 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-15 00:09:50.069876 | controller | ok
2025-12-15 00:09:50.088618 | controller | included: /var/lib/zuul/builds/affd1c5246f743a7a6a307b8c61c86ad/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-15 00:09:50.097768 | 
2025-12-15 00:09:50.097859 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-15 00:09:50.426491 | controller -> localhost | Generating public/private ecdsa key pair.
2025-12-15 00:09:50.426812 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/affd1c5246f743a7a6a307b8c61c86ad/work/affd1c5246f743a7a6a307b8c61c86ad_id_ecdsa.
2025-12-15 00:09:50.426858 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/affd1c5246f743a7a6a307b8c61c86ad/work/affd1c5246f743a7a6a307b8c61c86ad_id_ecdsa.pub.
2025-12-15 00:09:50.426897 | controller -> localhost | The key fingerprint is:
2025-12-15 00:09:50.426924 | controller -> localhost | SHA256:2c0cKtCJQ1bnBWM/kv3Nw/S9+86Mp2WeYMCzwklTzJc zuul-build-sshkey
2025-12-15 00:09:50.426950 | controller -> localhost | The key's randomart image is:
2025-12-15 00:09:50.426976 | controller -> localhost | +---[ECDSA 521]---+
2025-12-15 00:09:50.427000 | controller -> localhost | |      o.. =..    |
2025-12-15 00:09:50.427024 | controller -> localhost | |     o o =o*  .  |
2025-12-15 00:09:50.427047 | controller -> localhost | |      + o ++=E . |
2025-12-15 00:09:50.427070 | controller -> localhost | |       o oo*.+ooo|
2025-12-15 00:09:50.427103 | controller -> localhost | |        Soo++ .o=|
2025-12-15 00:09:50.427141 | controller -> localhost | |        o.o +   o|
2025-12-15 00:09:50.427186 | controller -> localhost | |         + . o .o|
2025-12-15 00:09:50.427228 | controller -> localhost | |          . . .B+|
2025-12-15 00:09:50.427257 | controller -> localhost | |              o*B|
2025-12-15 00:09:50.427284 | controller -> localhost | +----[SHA256]-----+
2025-12-15 00:09:50.427384 | controller -> localhost | ok: Runtime: 0:00:00.015446
2025-12-15 00:09:50.437604 | 
2025-12-15 00:09:50.437761 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-15 00:09:50.469876 | controller | ok
2025-12-15 00:09:50.478026 | controller | included: /var/lib/zuul/builds/affd1c5246f743a7a6a307b8c61c86ad/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-15 00:09:50.489869 | 
2025-12-15 00:09:50.489955 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-15 00:09:50.514894 | controller | skipping: Conditional result was False
2025-12-15 00:09:50.522225 | 
2025-12-15 00:09:50.522303 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-15 00:09:50.788443 | controller | changed
2025-12-15 00:09:50.794762 | 
2025-12-15 00:09:50.794837 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-15 00:09:51.031694 | controller | ok
2025-12-15 00:09:51.044863 | 
2025-12-15 00:09:51.045015 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-15 00:09:51.650222 | controller | changed
2025-12-15 00:09:51.657989 | 
2025-12-15 00:09:51.658074 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-15 00:09:52.330326 | controller | changed
2025-12-15 00:09:52.343670 | 
2025-12-15 00:09:52.343833 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-15 00:09:52.381375 | controller | skipping: Conditional result was False
2025-12-15 00:09:52.395611 | 
2025-12-15 00:09:52.395805 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-15 00:09:52.678456 | controller -> localhost | changed
2025-12-15 00:09:52.690453 | 
2025-12-15 00:09:52.690534 | TASK [add-build-sshkey : Add back temp key]
2025-12-15 00:09:53.061365 | controller -> localhost | Identity added: /var/lib/zuul/builds/affd1c5246f743a7a6a307b8c61c86ad/work/affd1c5246f743a7a6a307b8c61c86ad_id_ecdsa (zuul-build-sshkey)
2025-12-15 00:09:53.061624 | controller -> localhost | ok: Runtime: 0:00:00.012993
2025-12-15 00:09:53.102711 | 
2025-12-15 00:09:53.102856 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-15 00:09:53.334344 | controller | ok
2025-12-15 00:09:53.348680 | 
2025-12-15 00:09:53.348909 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-15 00:09:53.376673 | controller | skipping: Conditional result was False
2025-12-15 00:09:53.406982 | 
2025-12-15 00:09:53.407218 | TASK [include_role : remove-zuul-sshkey]
2025-12-15 00:09:53.446767 | controller | skipping: Conditional result was False
2025-12-15 00:09:53.461463 | 
2025-12-15 00:09:53.461630 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-15 00:09:53.708744 | controller | ok: "logs"
2025-12-15 00:09:53.709064 | controller | ok: All items complete
2025-12-15 00:09:53.709092 | 
2025-12-15 00:09:53.905859 | controller | ok: "artifacts"
2025-12-15 00:09:54.082704 | controller | ok: "docs"
2025-12-15 00:09:54.106465 | 
2025-12-15 00:09:54.106761 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-15 00:09:54.338500 | controller | changed: "logs"
2025-12-15 00:09:54.533457 | controller | changed: "artifacts"
2025-12-15 00:09:54.741341 | controller | changed: "docs"
2025-12-15 00:09:54.780903 | 
2025-12-15 00:09:54.781080 | PLAY RECAP
2025-12-15 00:09:54.781145 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-12-15 00:09:54.781173 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-15 00:09:54.781191 | 
2025-12-15 00:09:54.953177 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-12-15 00:09:54.955025 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-15 00:09:55.587703 | 
2025-12-15 00:09:55.587921 | PLAY [all]
2025-12-15 00:09:55.611274 | 
2025-12-15 00:09:55.611405 | TASK [Install binary dependencies]
2025-12-15 00:09:55.682642 | controller | ok
2025-12-15 00:09:55.704763 | 
2025-12-15 00:09:55.704893 | TASK [bindep : Include find tasks]
2025-12-15 00:09:55.743570 | controller | ok
2025-12-15 00:09:55.765993 | controller | included: /var/lib/zuul/builds/affd1c5246f743a7a6a307b8c61c86ad/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-12-15 00:09:55.782202 | 
2025-12-15 00:09:55.782363 | TASK [bindep : Look for bindep.txt]
2025-12-15 00:09:56.131390 | controller | ok
2025-12-15 00:09:56.143896 | 
2025-12-15 00:09:56.144042 | TASK [bindep : Define bindep_file fact]
2025-12-15 00:09:56.172598 | controller | skipping: Conditional result was False
2025-12-15 00:09:56.186393 | 
2025-12-15 00:09:56.186538 | TASK [bindep : Look for other-requirements.txt]
2025-12-15 00:09:56.418603 | controller | ok
2025-12-15 00:09:56.430673 | 
2025-12-15 00:09:56.430878 | TASK [bindep : Define bindep_file fact]
2025-12-15 00:09:56.469310 | controller | skipping: Conditional result was False
2025-12-15 00:09:56.484020 | 
2025-12-15 00:09:56.484164 | TASK [bindep : Look for bindep fallback file]
2025-12-15 00:09:56.522714 | controller | skipping: Conditional result was False
2025-12-15 00:09:56.538861 | 
2025-12-15 00:09:56.539012 | TASK [bindep : Define bindep_file fact]
2025-12-15 00:09:56.575882 | controller | skipping: Conditional result was False
2025-12-15 00:09:56.589088 | 
2025-12-15 00:09:56.589223 | TASK [bindep : Include bindep tasks]
2025-12-15 00:09:56.625451 | controller | skipping: Conditional result was False
2025-12-15 00:09:56.639455 | 
2025-12-15 00:09:56.639590 | TASK [bindep : Include install tasks]
2025-12-15 00:09:56.677091 | controller | skipping: Conditional result was False
2025-12-15 00:09:56.690894 | 
2025-12-15 00:09:56.691067 | LOOP [bindep : Include package tasks]
2025-12-15 00:09:56.773826 | 
2025-12-15 00:09:56.774088 | TASK [Run test-setup role]
2025-12-15 00:09:56.805792 | controller | ok
2025-12-15 00:09:56.834562 | 
2025-12-15 00:09:56.834651 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-12-15 00:09:57.064703 | controller | ok
2025-12-15 00:09:57.078674 | 
2025-12-15 00:09:57.078844 | TASK [test-setup : Run tools/test-setup.sh]
2025-12-15 00:09:57.629182 | controller | skipping: Conditional result was False
2025-12-15 00:09:57.684998 | 
2025-12-15 00:09:57.685113 | PLAY RECAP
2025-12-15 00:09:57.685167 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-15 00:09:57.685196 | 
2025-12-15 00:09:57.791465 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-15 00:09:57.792518 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-12-15 00:09:58.421126 | 
2025-12-15 00:09:58.421245 | PLAY [controller]
2025-12-15 00:09:58.441265 | 
2025-12-15 00:09:58.441347 | TASK [Create the /root directory]
2025-12-15 00:09:58.834424 | controller | ok
2025-12-15 00:09:58.847027 | 
2025-12-15 00:09:58.847175 | TASK [Install glibc-langpack-en]
2025-12-15 00:10:03.009642 | controller | ok: Nothing to do
2025-12-15 00:10:03.015071 | 
2025-12-15 00:10:03.015137 | TASK [Ensure controller directory exists]
2025-12-15 00:10:03.266072 | controller | changed
2025-12-15 00:10:03.279237 | 
2025-12-15 00:10:03.279381 | TASK [Install container runtime]
2025-12-15 00:10:03.358359 | controller | ok
2025-12-15 00:10:03.402963 | 
2025-12-15 00:10:03.403103 | LOOP [ensure-docker : Gather variables for each operating system]
2025-12-15 00:10:03.457682 | controller | ok: "/var/lib/zuul/builds/affd1c5246f743a7a6a307b8c61c86ad/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2025-12-15 00:10:03.472164 | 
2025-12-15 00:10:03.472312 | TASK [ensure-docker : Sanity check for distro selection]
2025-12-15 00:10:03.489945 | controller | skipping: Conditional result was False
2025-12-15 00:10:03.503642 | 
2025-12-15 00:10:03.503846 | TASK [ensure-docker : Sanity check for distro install]
2025-12-15 00:10:03.521102 | controller | skipping: Conditional result was False
2025-12-15 00:10:03.534388 | 
2025-12-15 00:10:03.534578 | TASK [ensure-docker : Sanity check for upstream install]
2025-12-15 00:10:03.561022 | controller | skipping: Conditional result was False
2025-12-15 00:10:03.574372 | 
2025-12-15 00:10:03.574530 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2025-12-15 00:10:03.601505 | controller | skipping: Conditional result was False
2025-12-15 00:10:03.617560 | 
2025-12-15 00:10:03.617710 | TASK [ensure-docker : Include tasks for docker install]
2025-12-15 00:10:03.642265 | controller | ok
2025-12-15 00:10:03.654337 | controller | included: /var/lib/zuul/builds/affd1c5246f743a7a6a307b8c61c86ad/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2025-12-15 00:10:03.665823 | 
2025-12-15 00:10:03.665942 | TASK [ensure-docker : Not Implemented]
2025-12-15 00:10:03.691064 | controller | skipping: Conditional result was False
2025-12-15 00:10:03.700118 | 
2025-12-15 00:10:03.700224 | TASK [Set up docker mirrors]
2025-12-15 00:10:03.723259 | controller | ok
2025-12-15 00:10:03.751577 | 
2025-12-15 00:10:03.751705 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2025-12-15 00:10:03.786992 | controller | skipping: Conditional result was False
2025-12-15 00:10:03.797152 | 
2025-12-15 00:10:03.797257 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2025-12-15 00:10:03.832779 | controller | skipping: Conditional result was False
2025-12-15 00:10:03.839101 | 
2025-12-15 00:10:03.839170 | TASK [use-docker-mirror : Set docker_mirror fact]
2025-12-15 00:10:03.873423 | controller | skipping: Conditional result was False
2025-12-15 00:10:03.879894 | 
2025-12-15 00:10:03.879964 | TASK [use-docker-mirror : Set up docker mirrors]
2025-12-15 00:10:03.904103 | controller | skipping: Conditional result was False
2025-12-15 00:10:03.917501 | 
2025-12-15 00:10:03.917583 | TASK [Add all repositories]
2025-12-15 00:10:03.937303 | controller | ok
2025-12-15 00:10:03.960447 | 
2025-12-15 00:10:03.960547 | LOOP [ensure-package-repositories : Install repositories]
2025-12-15 00:10:03.988551 | controller | ok: "/var/lib/zuul/builds/affd1c5246f743a7a6a307b8c61c86ad/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2025-12-15 00:10:03.988831 | controller | ok: All items complete
2025-12-15 00:10:03.988862 | 
2025-12-15 00:10:04.039592 | controller | included: /var/lib/zuul/builds/affd1c5246f743a7a6a307b8c61c86ad/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2025-12-15 00:10:04.045700 | 
2025-12-15 00:10:04.045775 | TASK [ensure-package-repositories : Create GPG key temp directory]
2025-12-15 00:10:04.364574 | controller | changed
2025-12-15 00:10:04.373641 | 
2025-12-15 00:10:04.373780 | LOOP [ensure-package-repositories : Upload GPG keys]
2025-12-15 00:10:05.322310 | controller | changed:
2025-12-15 00:10:05.322588 | controller | {
2025-12-15 00:10:05.322646 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2025-12-15 00:10:05.322696 | controller | }
2025-12-15 00:10:05.335343 | 
2025-12-15 00:10:05.335459 | LOOP [ensure-package-repositories : Import GPG keys]
2025-12-15 00:10:05.994066 | controller | changed:
2025-12-15 00:10:05.994369 | controller | {
2025-12-15 00:10:05.994429 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2025-12-15 00:10:05.994481 | controller | }
2025-12-15 00:10:05.994543 | controller | changed: All items complete
2025-12-15 00:10:05.994587 | 
2025-12-15 00:10:06.014812 | 
2025-12-15 00:10:06.015029 | TASK [ensure-package-repositories : Remove GPG temp directory]
2025-12-15 00:10:06.270187 | controller | changed
2025-12-15 00:10:06.285586 | 
2025-12-15 00:10:06.285787 | LOOP [ensure-package-repositories : Add repositories]
2025-12-15 00:10:06.655009 | controller | changed:
2025-12-15 00:10:06.655337 | controller | {
2025-12-15 00:10:06.655395 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2025-12-15 00:10:06.655439 | controller |   "description": "Docker CE Stable - $basearch",
2025-12-15 00:10:06.655477 | controller |   "gpgcheck": true,
2025-12-15 00:10:06.655513 | controller |   "name": "docker-ce-stable"
2025-12-15 00:10:06.655549 | controller | }
2025-12-15 00:10:06.686703 | 
2025-12-15 00:10:06.686916 | TASK [ensure-docker : Install pre-reqs]
2025-12-15 00:10:10.217447 | controller | ok: Nothing to do
2025-12-15 00:10:10.232092 | 
2025-12-15 00:10:10.232256 | TASK [ensure-docker : Remove packages]
2025-12-15 00:10:11.601017 | controller | ok: Nothing to do
2025-12-15 00:10:11.614969 | 
2025-12-15 00:10:11.615126 | TASK [ensure-docker : Install upstream docker using package]
2025-12-15 00:11:01.560144 | controller | changed
2025-12-15 00:11:01.575829 | 
2025-12-15 00:11:01.575990 | TASK [ensure-docker : Setup docker]
2025-12-15 00:11:01.607832 | controller | ok
2025-12-15 00:11:01.631598 | controller | included: /var/lib/zuul/builds/affd1c5246f743a7a6a307b8c61c86ad/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2025-12-15 00:11:01.648065 | 
2025-12-15 00:11:01.648171 | TASK [ensure-docker : Ensure "docker" group exists]
2025-12-15 00:11:02.063415 | controller | ok
2025-12-15 00:11:02.078516 | 
2025-12-15 00:11:02.078697 | TASK [ensure-docker : Add user to docker group]
2025-12-15 00:11:02.628614 | controller | changed
2025-12-15 00:11:02.641492 | 
2025-12-15 00:11:02.641643 | TASK [Update docker daemon proxy configuration]
2025-12-15 00:11:02.679193 | controller | skipping: Conditional result was False
2025-12-15 00:11:02.688462 | 
2025-12-15 00:11:02.688558 | TASK [Lower default MTU]
2025-12-15 00:11:02.714389 | controller | skipping: Conditional result was False
2025-12-15 00:11:02.723926 | 
2025-12-15 00:11:02.724034 | TASK [ensure-docker : Get the status of the docket.socket unit]
2025-12-15 00:11:03.132032 | controller | inactive
2025-12-15 00:11:03.302143 | controller | ok: Runtime: 0:00:00.013322
2025-12-15 00:11:03.311962 | 
2025-12-15 00:11:03.312078 | TASK [ensure-docker : Get the status of the docket unit]
2025-12-15 00:11:03.574510 | controller | inactive
2025-12-15 00:11:03.857075 | controller | ok: Runtime: 0:00:00.008179
2025-12-15 00:11:03.870756 | 
2025-12-15 00:11:03.870929 | TASK [ensure-docker : Restart docker]
2025-12-15 00:11:03.921935 | Trigger a Docker restart
2025-12-15 00:11:03.935916 | 
2025-12-15 00:11:03.936121 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2025-12-15 00:11:03.978506 | 
2025-12-15 00:11:03.978660 | TASK [ensure-docker : Install docker-compose]
2025-12-15 00:11:03.993624 | controller | skipping: Conditional result was False
2025-12-15 00:11:04.003308 | 
2025-12-15 00:11:04.003433 | TASK [ensure-docker : Flush handlers before role exit]
2025-12-15 00:11:04.020801 | 
2025-12-15 00:11:04.020936 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2025-12-15 00:11:05.255254 | controller | changed
2025-12-15 00:11:05.263544 | 
2025-12-15 00:11:05.263650 | TASK [ensure-docker : Assure docker service is running]
2025-12-15 00:11:07.605151 | controller | changed
2025-12-15 00:11:07.621830 | 
2025-12-15 00:11:07.621962 | TASK [ensure-docker : Assure docker.socket service is running]
2025-12-15 00:11:08.643033 | controller | ok
2025-12-15 00:11:08.653633 | 
2025-12-15 00:11:08.653722 | TASK [ensure-docker : Correct group ownership on docker sock]
2025-12-15 00:11:09.274924 | controller | ok
2025-12-15 00:11:09.283250 | 
2025-12-15 00:11:09.283338 | TASK [ensure-docker : Validate ability to talk with docker]
2025-12-15 00:11:09.868230 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2025-12-15 00:11:10.322281 | controller | ok: Runtime: 0:00:00.048082
2025-12-15 00:11:10.331164 | 
2025-12-15 00:11:10.331259 | TASK [ensure-docker : Get version details]
2025-12-15 00:11:10.994831 | controller | Client: Docker Engine - Community
2025-12-15 00:11:10.996231 | controller |  Version:           24.0.7
2025-12-15 00:11:10.996286 | controller |  API version:       1.43
2025-12-15 00:11:10.996292 | controller |  Go version:        go1.20.10
2025-12-15 00:11:10.996313 | controller |  Git commit:        afdd53b
2025-12-15 00:11:10.996320 | controller |  Built:             Thu Oct 26 09:09:39 2023
2025-12-15 00:11:10.996325 | controller |  OS/Arch:           linux/amd64
2025-12-15 00:11:10.996337 | controller |  Context:           default
2025-12-15 00:11:10.996344 | controller |
2025-12-15 00:11:10.996350 | controller | Server: Docker Engine - Community
2025-12-15 00:11:10.996354 | controller |  Engine:
2025-12-15 00:11:10.996359 | controller |   Version:          24.0.7
2025-12-15 00:11:10.996363 | controller |   API version:      1.43 (minimum version 1.12)
2025-12-15 00:11:10.996368 | controller |   Go version:       go1.20.10
2025-12-15 00:11:10.996372 | controller |   Git commit:       311b9ff
2025-12-15 00:11:10.996377 | controller |   Built:            Thu Oct 26 09:08:11 2023
2025-12-15 00:11:10.996381 | controller |   OS/Arch:          linux/amd64
2025-12-15 00:11:10.996386 | controller |   Experimental:     false
2025-12-15 00:11:10.996390 | controller |  containerd:
2025-12-15 00:11:10.996394 | controller |   Version:          1.6.25
2025-12-15 00:11:10.996399 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2025-12-15 00:11:10.996403 | controller |  runc:
2025-12-15 00:11:10.996407 | controller |   Version:          1.1.10
2025-12-15 00:11:10.996412 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2025-12-15 00:11:10.996418 | controller |  docker-init:
2025-12-15 00:11:10.996422 | controller |   Version:          0.19.0
2025-12-15 00:11:10.996426 | controller |   GitCommit:        de40ad0
2025-12-15 00:11:11.382169 | controller | ok: Runtime: 0:00:00.065940
2025-12-15 00:11:11.391904 | 
2025-12-15 00:11:11.392032 | TASK [ensure-docker : Dump installed docker details]
2025-12-15 00:11:11.437283 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version:           24.0.7\n API version:       1.43\n Go version:        go1.20.10\n Git commit:        afdd53b\n Built:             Thu Oct 26 09:09:39 2023\n OS/Arch:           linux/amd64\n Context:           default\n\nServer: Docker Engine - Community\n Engine:\n  Version:          24.0.7\n  API version:      1.43 (minimum version 1.12)\n  Go version:       go1.20.10\n  Git commit:       311b9ff\n  Built:            Thu Oct 26 09:08:11 2023\n  OS/Arch:          linux/amd64\n  Experimental:     false\n containerd:\n  Version:          1.6.25\n  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n  Version:          1.1.10\n  GitCommit:        v1.1.10-0-g18a0cb0\n docker-init:\n  Version:          0.19.0\n  GitCommit:        de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2025-12-15 00:11:10.930798', 'end': '2025-12-15 00:11:10.996738', 'delta': '0:00:00.065940', 'msg': '', 'zuul_log_id': 'fa163ef9-e89a-65de-2098-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version:           24.0.7', ' API version:       1.43', ' Go version:        go1.20.10', ' Git commit:        afdd53b', ' Built:             Thu Oct 26 09:09:39 2023', ' OS/Arch:           linux/amd64', ' Context:           default', '', 'Server: Docker Engine - Community', ' Engine:', '  Version:          24.0.7', '  API version:      1.43 (minimum version 1.12)', '  Go version:       go1.20.10', '  Git commit:       311b9ff', '  Built:            Thu Oct 26 09:08:11 2023', '  OS/Arch:          linux/amd64', '  Experimental:     false', ' containerd:', '  Version:          1.6.25', '  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', '  Version:          1.1.10', '  GitCommit:        v1.1.10-0-g18a0cb0', ' docker-init:', '  Version:          0.19.0', '  GitCommit:        de40ad0'], 'stderr_lines': [], 'failed': False}
2025-12-15 00:11:11.445695 | 
2025-12-15 00:11:11.445804 | TASK [Use buildset registry]
2025-12-15 00:11:11.472598 | controller | skipping: Conditional result was False
2025-12-15 00:11:11.490757 | 
2025-12-15 00:11:11.490889 | TASK [Ensure python3.8 is present]
2025-12-15 00:11:11.517694 | controller | skipping: Conditional result was False
2025-12-15 00:11:11.527499 | 
2025-12-15 00:11:11.527583 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-12-15 00:11:11.553716 | controller | ok
2025-12-15 00:11:11.572929 | 
2025-12-15 00:11:11.573010 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-12-15 00:11:14.077961 | controller | ok: Nothing to do
2025-12-15 00:11:14.083834 | 
2025-12-15 00:11:14.083918 | TASK [our-ensure-python : Also install python3-devel]
2025-12-15 00:11:26.559635 | controller | changed
2025-12-15 00:11:26.570451 | 
2025-12-15 00:11:26.570530 | TASK [Run ensure-virtualenv role]
2025-12-15 00:11:26.590165 | controller | ok
2025-12-15 00:11:26.613221 | 
2025-12-15 00:11:26.613359 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-12-15 00:11:26.821026 | controller | /usr/bin/virtualenv
2025-12-15 00:11:27.149087 | controller | ok: Runtime: 0:00:00.005790
2025-12-15 00:11:27.156071 | 
2025-12-15 00:11:27.156145 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-12-15 00:11:27.192407 | controller | skipping: Conditional result was False
2025-12-15 00:11:27.193087 | controller | changed: All items complete
2025-12-15 00:11:27.193163 | 
2025-12-15 00:11:27.214877 | 
2025-12-15 00:11:27.215006 | TASK [Find the full path of the Python interpreter]
2025-12-15 00:11:27.448635 | controller | /usr/bin/python3.8
2025-12-15 00:11:27.748814 | controller | ok
2025-12-15 00:11:27.755252 | 
2025-12-15 00:11:27.755343 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-12-15 00:11:29.423388 | controller | created virtual environment CPython3.8.18.final.0-64 in 817ms
2025-12-15 00:11:29.474317 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2025-12-15 00:11:29.474366 | controller |   seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv)
2025-12-15 00:11:29.474380 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-12-15 00:11:29.474397 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-12-15 00:11:29.792506 | controller | changed
2025-12-15 00:11:29.806602 | 
2025-12-15 00:11:29.806781 | TASK [Set selinux package]
2025-12-15 00:11:29.846781 | controller | ok
2025-12-15 00:11:29.858526 | 
2025-12-15 00:11:29.858654 | TASK [Set selinux package (Fedora)]
2025-12-15 00:11:29.905622 | controller | ok
2025-12-15 00:11:29.912924 | 
2025-12-15 00:11:29.913009 | TASK [Install selinux into virtualenv]
2025-12-15 00:11:57.561637 | controller | Collecting selinux-please-lie-to-me
2025-12-15 00:12:09.794811 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-12-15 00:12:10.172786 | controller | Collecting setuptools<50.0.0
2025-12-15 00:12:10.183225 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-12-15 00:12:10.477805 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 2.7 MB/s eta 0:00:00
2025-12-15 00:12:10.560142 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-12-15 00:12:10.560255 | controller |   Attempting uninstall: setuptools
2025-12-15 00:12:10.561088 | controller |     Found existing installation: setuptools 62.6.0
2025-12-15 00:12:10.638727 | controller |     Uninstalling setuptools-62.6.0:
2025-12-15 00:12:10.648463 | controller |       Successfully uninstalled setuptools-62.6.0
2025-12-15 00:12:11.003912 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-12-15 00:12:26.100591 | controller |
2025-12-15 00:12:26.210656 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-12-15 00:12:26.210876 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-12-15 00:12:26.510716 | controller | ok: Runtime: 0:00:55.747572
2025-12-15 00:12:26.523098 | 
2025-12-15 00:12:26.523229 | TASK [Install pytest-forked into virtualenv]
2025-12-15 00:12:38.340394 | controller | Collecting pytest-forked
2025-12-15 00:12:50.586839 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2025-12-15 00:12:50.749391 | controller | Collecting pytest>=3.10
2025-12-15 00:12:50.755085 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2025-12-15 00:12:50.785166 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 12.8 MB/s eta 0:00:00
2025-12-15 00:12:50.950365 | controller | Collecting py
2025-12-15 00:12:50.956696 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2025-12-15 00:12:50.964832 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 17.3 MB/s eta 0:00:00
2025-12-15 00:12:51.077010 | controller | Collecting packaging
2025-12-15 00:12:51.081768 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-12-15 00:12:51.090135 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 10.7 MB/s eta 0:00:00
2025-12-15 00:12:51.125959 | controller | Collecting iniconfig
2025-12-15 00:12:51.140527 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2025-12-15 00:12:51.183906 | controller | Collecting pluggy<2,>=1.5
2025-12-15 00:12:51.190250 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2025-12-15 00:12:51.276736 | controller | Collecting tomli>=1
2025-12-15 00:12:51.343726 | controller |   Downloading tomli-2.3.0-py3-none-any.whl (14 kB)
2025-12-15 00:12:51.390168 | controller | Collecting exceptiongroup>=1.0.0rc8
2025-12-15 00:12:51.415380 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2025-12-15 00:12:51.494053 | controller | Collecting typing-extensions>=4.6.0
2025-12-15 00:12:51.501349 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2025-12-15 00:12:51.509901 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 6.2 MB/s eta 0:00:00
2025-12-15 00:12:51.573486 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2025-12-15 00:12:52.021670 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.1.0 packaging-25.0 pluggy-1.5.0 py-1.11.0 pytest-8.3.5 pytest-forked-1.6.0 tomli-2.3.0 typing-extensions-4.13.2
2025-12-15 00:12:52.026341 | controller |
2025-12-15 00:12:52.100399 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-12-15 00:12:52.100447 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-12-15 00:12:52.594513 | controller | ok: Runtime: 0:00:25.337360
2025-12-15 00:12:52.607776 | 
2025-12-15 00:12:52.608051 | TASK [Update pip]
2025-12-15 00:12:53.159054 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2025-12-15 00:13:02.953452 | controller | Collecting pip
2025-12-15 00:13:15.276874 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2025-12-15 00:13:15.337566 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 33.3 MB/s eta 0:00:00
2025-12-15 00:13:15.393531 | controller | Installing collected packages: pip
2025-12-15 00:13:15.393758 | controller |   Attempting uninstall: pip
2025-12-15 00:13:15.394387 | controller |     Found existing installation: pip 22.2.2
2025-12-15 00:13:15.564463 | controller |     Uninstalling pip-22.2.2:
2025-12-15 00:13:15.579401 | controller |       Successfully uninstalled pip-22.2.2
2025-12-15 00:13:16.386907 | controller | Successfully installed pip-25.0.1
2025-12-15 00:13:16.676009 | controller | ok: Runtime: 0:00:23.610651
2025-12-15 00:13:16.686616 | 
2025-12-15 00:13:16.686774 | TASK [Ensure pip temp directory exists in /var/tmp]
2025-12-15 00:13:16.921566 | controller | changed
2025-12-15 00:13:16.937152 | 
2025-12-15 00:13:16.937306 | TASK [Install ansible into virtualenv]
2025-12-15 00:13:17.517043 | controller | Processing ./src/github.com/ansible/ansible
2025-12-15 00:13:17.517861 | controller |   Preparing metadata (setup.py): started
2025-12-15 00:13:17.958561 | controller |   Preparing metadata (setup.py): finished with status 'done'
2025-12-15 00:13:25.510617 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2025-12-15 00:13:37.842666 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2025-12-15 00:13:37.907986 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2025-12-15 00:13:37.921767 | controller |   Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.1 kB)
2025-12-15 00:13:38.190802 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2025-12-15 00:13:38.202040 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2025-12-15 00:13:38.222406 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (25.0)
2025-12-15 00:13:38.359275 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2025-12-15 00:13:38.363678 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2025-12-15 00:13:38.372740 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.8/site-packages (from cryptography->ansible-base==2.10.17.post0) (4.13.2)
2025-12-15 00:13:38.451043 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2025-12-15 00:13:38.466257 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2025-12-15 00:13:38.498584 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2025-12-15 00:13:38.502987 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2025-12-15 00:13:38.518474 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2025-12-15 00:13:38.588011 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 71.2 MB/s eta 0:00:00
2025-12-15 00:13:38.594647 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2025-12-15 00:13:38.608169 | controller | Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (806 kB)
2025-12-15 00:13:38.628275 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 42.3 MB/s eta 0:00:00
2025-12-15 00:13:38.634261 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2025-12-15 00:13:38.652155 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2025-12-15 00:13:38.661770 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2025-12-15 00:13:38.683879 | controller | Building wheels for collected packages: ansible-base
2025-12-15 00:13:38.684747 | controller |   Building wheel for ansible-base (setup.py): started
2025-12-15 00:13:39.933117 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2025-12-15 00:13:39.935248 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=6d8c13c67dff86f9a4ea2c6a66c83d3da2bd7ecb0ca4386310faa89930e4eab6
2025-12-15 00:13:39.935458 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-bt7e7jja/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2025-12-15 00:13:39.944702 | controller | Successfully built ansible-base
2025-12-15 00:13:39.978754 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2025-12-15 00:13:41.359498 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-base-2.10.17.post0 cffi-1.17.1 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23
2025-12-15 00:13:41.503627 | controller | ok: Runtime: 0:00:24.245071
2025-12-15 00:13:41.517532 | 
2025-12-15 00:13:41.517688 | TASK [Setup download-artifact-fork role]
2025-12-15 00:13:41.542847 | controller | ok
2025-12-15 00:13:41.569082 | 
2025-12-15 00:13:41.569196 | TASK [download-artifact-fork : Parse inventory response]
2025-12-15 00:13:41.623542 | controller | ok
2025-12-15 00:13:41.633542 | 
2025-12-15 00:13:41.633635 | TASK [download-artifact-fork : Fetch info from Zuul API]
2025-12-15 00:13:41.678638 | controller | skipping: Conditional result was False
2025-12-15 00:13:41.690412 | 
2025-12-15 00:13:41.690529 | TASK [download-artifact-fork : Parse build response]
2025-12-15 00:13:41.746660 | controller | skipping: Conditional result was False
2025-12-15 00:13:41.755468 | 
2025-12-15 00:13:41.755543 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2025-12-15 00:13:41.982247 | controller | changed
2025-12-15 00:13:41.990584 | 
2025-12-15 00:13:41.990847 | LOOP [download-artifact-fork : Download archive by type]
2025-12-15 00:13:42.028522 | controller | skipping: Conditional result was False
2025-12-15 00:13:42.029279 | 
2025-12-15 00:13:44.489554 | controller | changed: OK (20190 bytes)
2025-12-15 00:13:44.523070 | 
2025-12-15 00:13:44.523339 | TASK [deploy-artifacts : define __collections]
2025-12-15 00:13:44.570682 | controller | ok
2025-12-15 00:13:44.583417 | 
2025-12-15 00:13:44.583597 | LOOP [deploy-artifacts : Create list of collections]
2025-12-15 00:13:44.634428 | controller | skipping: Conditional result was False
2025-12-15 00:13:44.665870 | controller | ok:
2025-12-15 00:13:44.666115 | controller | {
2025-12-15 00:13:44.666166 | controller |   "branch": "main",
2025-12-15 00:13:44.666204 | controller |   "job": "build-ansible-collection",
2025-12-15 00:13:44.666240 | controller |   "metadata": {
2025-12-15 00:13:44.666276 | controller |     "type": "ansible_collection",
2025-12-15 00:13:44.666310 | controller |     "version": "5.0.0"
2025-12-15 00:13:44.666344 | controller |   },
2025-12-15 00:13:44.666377 | controller |   "name": "ansible.network",
2025-12-15 00:13:44.666410 | controller |   "project": "ansible-collections/ansible.network",
2025-12-15 00:13:44.666444 | controller |   "url": "https://24c5dc7a47d0cb0ca418-19290944e0223d62b285baba8e145534.ssl.cf2.rackcdn.com/ansible/f77125c4af344faca8eb1048bff2dad3/artifacts/ansible-network-5.0.0.tar.gz"
2025-12-15 00:13:44.666491 | controller | }
2025-12-15 00:13:44.677993 | 
2025-12-15 00:13:44.678450 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2025-12-15 00:13:45.254585 | controller | Starting galaxy collection install process
2025-12-15 00:13:45.254772 | controller | Process install dependency map
2025-12-15 00:20:52.137475 | controller | Starting collection install process
2025-12-15 00:20:52.137750 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2025-12-15 00:20:52.150371 | controller | ansible.network (5.0.0) was installed successfully
2025-12-15 00:20:52.150444 | controller | Installing 'ansible.netcommon:8.2.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2025-12-15 00:20:52.150604 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-netcommon-8.2.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4315ramdfpu4/tmpgxk1s252
2025-12-15 00:21:32.996796 | controller | ansible.netcommon (8.2.0) was installed successfully
2025-12-15 00:21:32.996893 | controller | Installing 'ansible.utils:6.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2025-12-15 00:21:32.997028 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-utils-6.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4315ramdfpu4/tmpgxk1s252
2025-12-15 00:22:15.041477 | controller | ansible.utils (6.0.0) was installed successfully
2025-12-15 00:22:15.041568 | controller | Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2025-12-15 00:22:15.041703 | 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-4315ramdfpu4/tmpgxk1s252
2025-12-15 00:22:58.451999 | controller | arista.eos (12.0.0) was installed successfully
2025-12-15 00:22:58.452097 | controller | Installing 'cisco.ios:11.2.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2025-12-15 00:22:58.452237 | 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-4315ramdfpu4/tmpgxk1s252
2025-12-15 00:23:55.866199 | controller | cisco.ios (11.2.0) was installed successfully
2025-12-15 00:23:55.866313 | controller | Installing 'cisco.iosxr:12.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2025-12-15 00:23:55.866467 | 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-4315ramdfpu4/tmpgxk1s252
2025-12-15 00:24:38.815498 | controller | cisco.iosxr (12.1.1) was installed successfully
2025-12-15 00:24:38.815630 | controller | Installing 'cisco.nxos:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2025-12-15 00:24:38.815895 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4315ramdfpu4/tmpgxk1s252
2025-12-15 00:25:57.937541 | controller | cisco.nxos (11.1.1) was installed successfully
2025-12-15 00:25:57.937662 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2025-12-15 00:25:57.937811 | 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-4315ramdfpu4/tmpgxk1s252
2025-12-15 00:26:37.779333 | controller | junipernetworks.junos (11.0.0) was installed successfully
2025-12-15 00:26:38.169231 | controller | ok: Runtime: 0:12:52.908689
2025-12-15 00:26:38.190981 | 
2025-12-15 00:26:38.191172 | TASK [podman may need iptables kernel modules]
2025-12-15 00:26:38.739316 | controller | ok: Runtime: 0:00:00.004806
2025-12-15 00:26:38.787892 | 
2025-12-15 00:26:38.788011 | PLAY RECAP
2025-12-15 00:26:38.788075 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2025-12-15 00:26:38.788105 | 
2025-12-15 00:26:38.925334 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-12-15 00:26:38.926532 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-12-15 00:26:39.578414 | 
2025-12-15 00:26:39.578572 | PLAY [controller]
2025-12-15 00:26:39.600708 | 
2025-12-15 00:26:39.600847 | TASK [Copy the galaxy.yml on the controller]
2025-12-15 00:26:40.720618 | controller | changed
2025-12-15 00:26:40.727591 | 
2025-12-15 00:26:40.727712 | TASK [Load information from galaxy.yml]
2025-12-15 00:26:40.758172 | controller | ok
2025-12-15 00:26:40.768837 | 
2025-12-15 00:26:40.768930 | LOOP [Enable FIPS mode]
2025-12-15 00:26:40.789985 | 
2025-12-15 00:26:40.790110 | LOOP [ansible-test : Include branch specific variables.]
2025-12-15 00:26:40.826097 | controller | ok: "/var/lib/zuul/builds/affd1c5246f743a7a6a307b8c61c86ad/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2025-12-15 00:26:40.826484 | 
2025-12-15 00:26:40.840810 | 
2025-12-15 00:26:40.840912 | TASK [ansible-test : Setup --skip-tags for test_options]
2025-12-15 00:26:40.881627 | controller | ok
2025-12-15 00:26:40.887084 | 
2025-12-15 00:26:40.887147 | TASK [ansible-test : Enable --inventory for network-integration]
2025-12-15 00:26:40.911254 | controller | skipping: Conditional result was False
2025-12-15 00:26:40.918395 | 
2025-12-15 00:26:40.918462 | TASK [ansible-test : Enable --retry-on-error]
2025-12-15 00:26:40.942699 | controller | skipping: Conditional result was False
2025-12-15 00:26:40.949097 | 
2025-12-15 00:26:40.949163 | TASK [ansible-test : Enable --continue-on-error]
2025-12-15 00:26:40.978805 | controller | ok
2025-12-15 00:26:40.984163 | 
2025-12-15 00:26:40.984220 | TASK [ansible-test : Enable --requirements]
2025-12-15 00:26:41.008217 | controller | skipping: Conditional result was False
2025-12-15 00:26:41.015866 | 
2025-12-15 00:26:41.015929 | TASK [ansible-test : Adjust options for unit tests]
2025-12-15 00:26:41.040155 | controller | skipping: Conditional result was False
2025-12-15 00:26:41.049235 | 
2025-12-15 00:26:41.049343 | TASK [ansible-test : Install requirements with unit tests]
2025-12-15 00:26:41.082562 | controller | ok
2025-12-15 00:26:41.090129 | 
2025-12-15 00:26:41.090214 | TASK [ansible-test : Set --skip-test]
2025-12-15 00:26:41.145459 | controller | skipping: Conditional result was False
2025-12-15 00:26:41.154441 | 
2025-12-15 00:26:41.154525 | TASK [ansible-test : Reduce verbosity]
2025-12-15 00:26:41.186833 | controller | ok
2025-12-15 00:26:41.194888 | 
2025-12-15 00:26:41.194971 | TASK [ansible-test : Setup --python option]
2025-12-15 00:26:41.210400 | controller | skipping: Conditional result was False
2025-12-15 00:26:41.252943 | 
2025-12-15 00:26:41.253076 | TASK [ansible-test : Setup --docker option]
2025-12-15 00:26:41.293225 | controller | ok
2025-12-15 00:26:41.298568 | 
2025-12-15 00:26:41.298628 | TASK [ansible-test : check if MANIFEST.json exists]
2025-12-15 00:26:41.825300 | controller | ok
2025-12-15 00:26:41.841151 | 
2025-12-15 00:26:41.841301 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2025-12-15 00:26:41.859167 | controller | skipping: Conditional result was False
2025-12-15 00:26:41.874846 | 
2025-12-15 00:26:41.874986 | TASK [ansible-test : Load information from MANIFEST.json]
2025-12-15 00:26:41.902277 | controller | skipping: Conditional result was False
2025-12-15 00:26:41.919048 | 
2025-12-15 00:26:41.919228 | TASK [ansible-test : Setup location of project for integration tests]
2025-12-15 00:26:41.946820 | controller | skipping: Conditional result was False
2025-12-15 00:26:41.961220 | 
2025-12-15 00:26:41.961364 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2025-12-15 00:26:42.500527 | controller | ok
2025-12-15 00:26:42.516388 | 
2025-12-15 00:26:42.516626 | TASK [ansible-test : Load information from galaxy.yml]
2025-12-15 00:26:42.560273 | controller | ok
2025-12-15 00:26:42.571225 | 
2025-12-15 00:26:42.571374 | TASK [ansible-test : Setup location of project for integration tests]
2025-12-15 00:26:42.613032 | controller | ok
2025-12-15 00:26:42.625815 | 
2025-12-15 00:26:42.625958 | TASK [ansible-test : Setup minimum test requirements]
2025-12-15 00:26:42.676037 | controller | ok
2025-12-15 00:26:42.688213 | 
2025-12-15 00:26:42.688303 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2025-12-15 00:26:43.117670 | controller | ok
2025-12-15 00:26:43.126125 | 
2025-12-15 00:26:43.126233 | TASK [ansible-test : Install python requirements]
2025-12-15 00:27:09.274099 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-12-15 00:27:21.515338 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2025-12-15 00:27:21.544030 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-12-15 00:27:21.550281 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2025-12-15 00:27:21.559594 | controller | Requirement already satisfied: pyyaml in ./venv/lib64/python3.8/site-packages (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1)) (6.0.3)
2025-12-15 00:27:21.568394 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2025-12-15 00:27:21.583911 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2025-12-15 00:27:21.625674 | controller | Installing collected packages: pathspec, yamllint
2025-12-15 00:27:21.690358 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2025-12-15 00:27:22.180286 | controller | ok: Runtime: 0:00:37.879253
2025-12-15 00:27:22.193391 | 
2025-12-15 00:27:22.193534 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2025-12-15 00:27:22.468883 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2025-12-15 00:27:22.741770 | controller | ERROR
2025-12-15 00:27:22.741991 | controller | {
2025-12-15 00:27:22.742030 | controller |   "delta": "0:00:00.003784",
2025-12-15 00:27:22.742057 | controller |   "end": "2025-12-15 00:27:22.469014",
2025-12-15 00:27:22.742081 | controller |   "msg": "non-zero return code",
2025-12-15 00:27:22.742103 | controller |   "rc": 1,
2025-12-15 00:27:22.742126 | controller |   "start": "2025-12-15 00:27:22.465230"
2025-12-15 00:27:22.742148 | controller | }
2025-12-15 00:27:22.742178 | controller | ERROR: Ignoring Errors
2025-12-15 00:27:22.748882 | 
2025-12-15 00:27:22.748944 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2025-12-15 00:27:23.319879 | controller | skipping: Conditional result was False
2025-12-15 00:27:23.326989 | 
2025-12-15 00:27:23.327066 | TASK [ansible-test : Enable persistent connection logging]
2025-12-15 00:27:24.009066 | controller | changed: section and option added
2025-12-15 00:27:24.015398 | 
2025-12-15 00:27:24.015470 | TASK [ansible-test : Set persistent ssh_type value]
2025-12-15 00:27:24.499692 | controller | changed: option added
2025-12-15 00:27:24.514824 | 
2025-12-15 00:27:24.514971 | TASK [ansible-test : Set ansible log path]
2025-12-15 00:27:25.087834 | controller | changed: section and option added
2025-12-15 00:27:25.094075 | 
2025-12-15 00:27:25.094142 | TASK [ansible-test : Identify the changed targets]
2025-12-15 00:27:25.653812 | controller | skipping: Conditional result was False
2025-12-15 00:27:25.669179 | 
2025-12-15 00:27:25.669335 | TASK [ansible-test : set_fact]
2025-12-15 00:27:25.737451 | controller | skipping: Conditional result was False
2025-12-15 00:27:25.744224 | 
2025-12-15 00:27:25.744298 | TASK [ansible-test : Set the targets]
2025-12-15 00:27:25.774525 | controller | ok
2025-12-15 00:27:25.780378 | 
2025-12-15 00:27:25.780446 | TASK [ansible-test : set_fact]
2025-12-15 00:27:25.805039 | controller | skipping: Conditional result was False
2025-12-15 00:27:25.815944 | 
2025-12-15 00:27:25.816082 | TASK [ansible-test : List the targets]
2025-12-15 00:27:26.359788 | controller | skipping: Conditional result was False
2025-12-15 00:27:26.376055 | 
2025-12-15 00:27:26.376228 | TASK [ansible-test : set_fact]
2025-12-15 00:27:26.404116 | controller | skipping: Conditional result was False
2025-12-15 00:27:26.419530 | 
2025-12-15 00:27:26.419685 | TASK [ansible-test : set_fact]
2025-12-15 00:27:26.448093 | controller | skipping: Conditional result was False
2025-12-15 00:27:26.465098 | 
2025-12-15 00:27:26.465261 | LOOP [ansible-test : set_fact]
2025-12-15 00:27:26.550326 | 
2025-12-15 00:27:26.550643 | TASK [ansible-test : set_fact]
2025-12-15 00:27:26.578509 | controller | skipping: Conditional result was False
2025-12-15 00:27:26.593814 | 
2025-12-15 00:27:26.593965 | TASK [ansible-test : debug]
2025-12-15 00:27:26.622718 | controller | skipping: Conditional result was False
2025-12-15 00:27:26.640614 | 
2025-12-15 00:27:26.640858 | TASK [ansible-test : debug]
2025-12-15 00:27:26.697986 | About to run: ansible-test sanity --requirements --docker -v
2025-12-15 00:27:26.712588 | 
2025-12-15 00:27:26.712802 | TASK [ansible-test : Run the test suite]
2025-12-15 00:27:27.415666 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2025-12-15 00:27:27.435850 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2025-12-15 00:27:29.207919 | controller | 2.9.0: Pulling from ansible/default-test-container
2025-12-15 00:27:30.046797 | controller | 171857c49d0f: Pulling fs layer
2025-12-15 00:27:30.046887 | controller | 419640447d26: Pulling fs layer
2025-12-15 00:27:30.046897 | controller | 61e52f862619: Pulling fs layer
2025-12-15 00:27:30.046903 | controller | 851de0426e26: Pulling fs layer
2025-12-15 00:27:30.046913 | controller | 37425716ea5b: Pulling fs layer
2025-12-15 00:27:30.046921 | controller | cd9b0c439616: Pulling fs layer
2025-12-15 00:27:30.046928 | controller | d4612dbd8855: Pulling fs layer
2025-12-15 00:27:30.046934 | controller | 554699364730: Pulling fs layer
2025-12-15 00:27:30.046940 | controller | 1ff15d4bdb3e: Pulling fs layer
2025-12-15 00:27:30.046946 | controller | 5f2e4724adea: Pulling fs layer
2025-12-15 00:27:30.046952 | controller | a02d9a95ccb8: Pulling fs layer
2025-12-15 00:27:30.046958 | controller | 989f4ce370cb: Pulling fs layer
2025-12-15 00:27:30.046964 | controller | 0d7f52cdf9df: Pulling fs layer
2025-12-15 00:27:30.046970 | controller | c423742351d5: Pulling fs layer
2025-12-15 00:27:30.046976 | controller | 109236341d21: Pulling fs layer
2025-12-15 00:27:30.046982 | controller | 07a2f61c96b8: Pulling fs layer
2025-12-15 00:27:30.046990 | controller | 041f122f27ae: Pulling fs layer
2025-12-15 00:27:30.046995 | controller | ca6f5098b12e: Pulling fs layer
2025-12-15 00:27:30.047001 | controller | 1eb3e7b2afd9: Pulling fs layer
2025-12-15 00:27:30.047007 | controller | 18a0b750727a: Pulling fs layer
2025-12-15 00:27:30.047013 | controller | 17f2f596716c: Pulling fs layer
2025-12-15 00:27:30.047020 | controller | f35a9998a9bd: Pulling fs layer
2025-12-15 00:27:30.047026 | controller | fdfc10566405: Pulling fs layer
2025-12-15 00:27:30.047031 | controller | 1ff1c442c5fe: Pulling fs layer
2025-12-15 00:27:30.047037 | controller | a3443c4a17ed: Pulling fs layer
2025-12-15 00:27:30.047043 | controller | 0edcf9fb2b15: Pulling fs layer
2025-12-15 00:27:30.047049 | controller | 5af43b69e845: Pulling fs layer
2025-12-15 00:27:30.047054 | controller | 1651773effb2: Pulling fs layer
2025-12-15 00:27:30.047060 | controller | 79e23c5c0fe4: Pulling fs layer
2025-12-15 00:27:30.047495 | controller | e5058b1faaa6: Pulling fs layer
2025-12-15 00:27:30.047514 | controller | 846775490569: Pulling fs layer
2025-12-15 00:27:30.047520 | controller | 109236341d21: Waiting
2025-12-15 00:27:30.047526 | controller | 07a2f61c96b8: Waiting
2025-12-15 00:27:30.047532 | controller | 851de0426e26: Waiting
2025-12-15 00:27:30.047538 | controller | 37425716ea5b: Waiting
2025-12-15 00:27:30.047544 | controller | cd9b0c439616: Waiting
2025-12-15 00:27:30.047549 | controller | d4612dbd8855: Waiting
2025-12-15 00:27:30.047558 | controller | 554699364730: Waiting
2025-12-15 00:27:30.047564 | controller | 1ff15d4bdb3e: Waiting
2025-12-15 00:27:30.047569 | controller | 5f2e4724adea: Waiting
2025-12-15 00:27:30.047575 | controller | a02d9a95ccb8: Waiting
2025-12-15 00:27:30.047581 | controller | 989f4ce370cb: Waiting
2025-12-15 00:27:30.047587 | controller | 0d7f52cdf9df: Waiting
2025-12-15 00:27:30.047592 | controller | 041f122f27ae: Waiting
2025-12-15 00:27:30.047598 | controller | c423742351d5: Waiting
2025-12-15 00:27:30.047604 | controller | ca6f5098b12e: Waiting
2025-12-15 00:27:30.047610 | controller | 1eb3e7b2afd9: Waiting
2025-12-15 00:27:30.047615 | controller | 18a0b750727a: Waiting
2025-12-15 00:27:30.047621 | controller | a3443c4a17ed: Waiting
2025-12-15 00:27:30.047637 | controller | 17f2f596716c: Waiting
2025-12-15 00:27:30.047644 | controller | f35a9998a9bd: Waiting
2025-12-15 00:27:30.047649 | controller | 0edcf9fb2b15: Waiting
2025-12-15 00:27:30.047655 | controller | fdfc10566405: Waiting
2025-12-15 00:27:30.047661 | controller | 1ff1c442c5fe: Waiting
2025-12-15 00:27:30.047667 | controller | 5af43b69e845: Waiting
2025-12-15 00:27:30.047673 | controller | e5058b1faaa6: Waiting
2025-12-15 00:27:30.047678 | controller | 1651773effb2: Waiting
2025-12-15 00:27:30.047684 | controller | 79e23c5c0fe4: Waiting
2025-12-15 00:27:30.047690 | controller | 846775490569: Waiting
2025-12-15 00:27:30.047703 | controller | 61e52f862619: Verifying Checksum
2025-12-15 00:27:30.197742 | controller | 61e52f862619: Download complete
2025-12-15 00:27:30.197779 | controller | 419640447d26: Download complete
2025-12-15 00:27:31.228344 | controller | 851de0426e26: Download complete
2025-12-15 00:27:31.450392 | controller | 171857c49d0f: Download complete
2025-12-15 00:27:32.095828 | controller | cd9b0c439616: Verifying Checksum
2025-12-15 00:27:32.399382 | controller | cd9b0c439616: Download complete
2025-12-15 00:27:32.399420 | controller | d4612dbd8855: Verifying Checksum
2025-12-15 00:27:32.399577 | controller | d4612dbd8855: Download complete
2025-12-15 00:27:33.481433 | controller | 1ff15d4bdb3e: Verifying Checksum
2025-12-15 00:27:34.363054 | controller | 1ff15d4bdb3e: Download complete
2025-12-15 00:27:34.363101 | controller | 171857c49d0f: Pull complete
2025-12-15 00:27:34.397003 | controller | 419640447d26: Pull complete
2025-12-15 00:27:34.402741 | controller | 5f2e4724adea: Verifying Checksum
2025-12-15 00:27:34.403006 | controller | 5f2e4724adea: Download complete
2025-12-15 00:27:34.424492 | controller | 61e52f862619: Pull complete
2025-12-15 00:27:34.477049 | controller | 851de0426e26: Pull complete
2025-12-15 00:27:34.942562 | controller | 554699364730: Verifying Checksum
2025-12-15 00:27:35.548547 | controller | 554699364730: Download complete
2025-12-15 00:27:35.548594 | controller | a02d9a95ccb8: Verifying Checksum
2025-12-15 00:27:35.876109 | controller | a02d9a95ccb8: Download complete
2025-12-15 00:27:35.876153 | controller | 989f4ce370cb: Verifying Checksum
2025-12-15 00:27:36.446418 | controller | 989f4ce370cb: Download complete
2025-12-15 00:27:36.446462 | controller | 0d7f52cdf9df: Verifying Checksum
2025-12-15 00:27:36.748915 | controller | 0d7f52cdf9df: Download complete
2025-12-15 00:27:36.748988 | controller | c423742351d5: Verifying Checksum
2025-12-15 00:27:37.745233 | controller | c423742351d5: Download complete
2025-12-15 00:27:37.745276 | controller | 07a2f61c96b8: Verifying Checksum
2025-12-15 00:27:38.659140 | controller | 07a2f61c96b8: Download complete
2025-12-15 00:27:38.659204 | controller | 041f122f27ae: Verifying Checksum
2025-12-15 00:27:38.971048 | controller | 041f122f27ae: Download complete
2025-12-15 00:27:38.971105 | controller | 109236341d21: Verifying Checksum
2025-12-15 00:27:39.739533 | controller | 109236341d21: Download complete
2025-12-15 00:27:39.739640 | controller | ca6f5098b12e: Verifying Checksum
2025-12-15 00:27:39.739700 | controller | ca6f5098b12e: Download complete
2025-12-15 00:27:39.910346 | controller | 1eb3e7b2afd9: Verifying Checksum
2025-12-15 00:27:39.910464 | controller | 1eb3e7b2afd9: Download complete
2025-12-15 00:27:40.768892 | controller | 17f2f596716c: Verifying Checksum
2025-12-15 00:27:40.769442 | controller | 17f2f596716c: Download complete
2025-12-15 00:27:40.864152 | controller | 18a0b750727a: Verifying Checksum
2025-12-15 00:27:40.864221 | controller | 18a0b750727a: Download complete
2025-12-15 00:27:41.695363 | controller | f35a9998a9bd: Verifying Checksum
2025-12-15 00:27:41.695608 | controller | f35a9998a9bd: Download complete
2025-12-15 00:27:41.821531 | controller | fdfc10566405: Verifying Checksum
2025-12-15 00:27:42.663069 | controller | fdfc10566405: Download complete
2025-12-15 00:27:42.663145 | controller | 1ff1c442c5fe: Verifying Checksum
2025-12-15 00:27:42.663253 | controller | 1ff1c442c5fe: Download complete
2025-12-15 00:27:43.285384 | controller | a3443c4a17ed: Verifying Checksum
2025-12-15 00:27:44.646285 | controller | a3443c4a17ed: Download complete
2025-12-15 00:27:44.646376 | controller | 0edcf9fb2b15: Verifying Checksum
2025-12-15 00:27:45.207920 | controller | 0edcf9fb2b15: Download complete
2025-12-15 00:27:45.207972 | controller | 5af43b69e845: Verifying Checksum
2025-12-15 00:27:45.252277 | controller | 5af43b69e845: Download complete
2025-12-15 00:27:45.252351 | controller | 37425716ea5b: Verifying Checksum
2025-12-15 00:27:45.252449 | controller | 37425716ea5b: Download complete
2025-12-15 00:27:46.839435 | controller | e5058b1faaa6: Verifying Checksum
2025-12-15 00:27:46.839523 | controller | e5058b1faaa6: Download complete
2025-12-15 00:27:47.026154 | controller | 79e23c5c0fe4: Verifying Checksum
2025-12-15 00:27:47.026216 | controller | 79e23c5c0fe4: Download complete
2025-12-15 00:27:47.138902 | controller | 1651773effb2: Verifying Checksum
2025-12-15 00:27:47.139023 | controller | 1651773effb2: Download complete
2025-12-15 00:27:49.006763 | controller | 846775490569: Verifying Checksum
2025-12-15 00:27:49.006852 | controller | 846775490569: Download complete
2025-12-15 00:27:58.365346 | controller | 37425716ea5b: Pull complete
2025-12-15 00:27:58.386556 | controller | cd9b0c439616: Pull complete
2025-12-15 00:27:58.407062 | controller | d4612dbd8855: Pull complete
2025-12-15 00:28:01.523573 | controller | 554699364730: Pull complete
2025-12-15 00:28:01.553648 | controller | 1ff15d4bdb3e: Pull complete
2025-12-15 00:28:01.575120 | controller | 5f2e4724adea: Pull complete
2025-12-15 00:28:01.615096 | controller | a02d9a95ccb8: Pull complete
2025-12-15 00:28:01.638392 | controller | 989f4ce370cb: Pull complete
2025-12-15 00:28:01.657579 | controller | 0d7f52cdf9df: Pull complete
2025-12-15 00:28:01.682062 | controller | c423742351d5: Pull complete
2025-12-15 00:28:02.973024 | controller | 109236341d21: Pull complete
2025-12-15 00:28:03.003609 | controller | 07a2f61c96b8: Pull complete
2025-12-15 00:28:03.023565 | controller | 041f122f27ae: Pull complete
2025-12-15 00:28:03.132806 | controller | ca6f5098b12e: Pull complete
2025-12-15 00:28:03.178015 | controller | 1eb3e7b2afd9: Pull complete
2025-12-15 00:28:03.225498 | controller | 18a0b750727a: Pull complete
2025-12-15 00:28:03.256227 | controller | 17f2f596716c: Pull complete
2025-12-15 00:28:03.278179 | controller | f35a9998a9bd: Pull complete
2025-12-15 00:28:03.306371 | controller | fdfc10566405: Pull complete
2025-12-15 00:28:03.329816 | controller | 1ff1c442c5fe: Pull complete
2025-12-15 00:28:04.334757 | controller | a3443c4a17ed: Pull complete
2025-12-15 00:28:05.409189 | controller | 0edcf9fb2b15: Pull complete
2025-12-15 00:28:06.920755 | controller | 5af43b69e845: Pull complete
2025-12-15 00:28:08.486513 | controller | 1651773effb2: Pull complete
2025-12-15 00:28:10.103122 | controller | 79e23c5c0fe4: Pull complete
2025-12-15 00:28:11.216606 | controller | e5058b1faaa6: Pull complete
2025-12-15 00:28:12.797783 | controller | 846775490569: Pull complete
2025-12-15 00:28:12.822603 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2025-12-15 00:28:12.826718 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2025-12-15 00:28:12.833134 | controller | quay.io/ansible/default-test-container:2.9.0
2025-12-15 00:28:12.836749 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2025-12-15 00:28:12.889368 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2025-12-15 00:28:14.647590 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2025-12-15 00:28:14.647720 | controller | 04a5f4cda3ee: Pulling fs layer
2025-12-15 00:28:14.647774 | controller | ff496a88c8ed: Pulling fs layer
2025-12-15 00:28:14.647822 | controller | 0ce83f459fe7: Pulling fs layer
2025-12-15 00:28:14.647867 | controller | 2e5170e1f099: Pulling fs layer
2025-12-15 00:28:14.647911 | controller | 7641eb41b08c: Pulling fs layer
2025-12-15 00:28:14.647958 | controller | ad15fa9da398: Pulling fs layer
2025-12-15 00:28:14.648017 | controller | 087d91352424: Pulling fs layer
2025-12-15 00:28:14.648054 | controller | 8b92efd6a100: Pulling fs layer
2025-12-15 00:28:14.648128 | controller | 2e5170e1f099: Waiting
2025-12-15 00:28:14.648184 | controller | 7641eb41b08c: Waiting
2025-12-15 00:28:14.648229 | controller | ad15fa9da398: Waiting
2025-12-15 00:28:14.648273 | controller | 087d91352424: Waiting
2025-12-15 00:28:14.648332 | controller | 8b92efd6a100: Waiting
2025-12-15 00:28:15.893163 | controller | 0ce83f459fe7: Verifying Checksum
2025-12-15 00:28:15.997491 | controller | 0ce83f459fe7: Download complete
2025-12-15 00:28:15.997536 | controller | ff496a88c8ed: Verifying Checksum
2025-12-15 00:28:16.404181 | controller | ff496a88c8ed: Download complete
2025-12-15 00:28:16.404225 | controller | 04a5f4cda3ee: Verifying Checksum
2025-12-15 00:28:17.254189 | controller | 04a5f4cda3ee: Download complete
2025-12-15 00:28:17.254233 | controller | 7641eb41b08c: Verifying Checksum
2025-12-15 00:28:17.658383 | controller | 7641eb41b08c: Download complete
2025-12-15 00:28:17.658445 | controller | 04a5f4cda3ee: Pull complete
2025-12-15 00:28:17.726031 | controller | ff496a88c8ed: Pull complete
2025-12-15 00:28:17.727644 | controller | ad15fa9da398: Verifying Checksum
2025-12-15 00:28:17.784567 | controller | ad15fa9da398: Download complete
2025-12-15 00:28:17.784630 | controller | 0ce83f459fe7: Pull complete
2025-12-15 00:28:18.057255 | controller | 2e5170e1f099: Verifying Checksum
2025-12-15 00:28:18.810164 | controller | 2e5170e1f099: Download complete
2025-12-15 00:28:18.810212 | controller | 087d91352424: Verifying Checksum
2025-12-15 00:28:18.916373 | controller | 087d91352424: Download complete
2025-12-15 00:28:18.916430 | controller | 8b92efd6a100: Verifying Checksum
2025-12-15 00:28:18.916487 | controller | 8b92efd6a100: Download complete
2025-12-15 00:28:18.999699 | controller | 2e5170e1f099: Pull complete
2025-12-15 00:28:19.048749 | controller | 7641eb41b08c: Pull complete
2025-12-15 00:28:19.094960 | controller | ad15fa9da398: Pull complete
2025-12-15 00:28:19.734114 | controller | 087d91352424: Pull complete
2025-12-15 00:28:19.795620 | controller | 8b92efd6a100: Pull complete
2025-12-15 00:28:19.828940 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2025-12-15 00:28:19.837448 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2025-12-15 00:28:19.848460 | controller | quay.io/ansible/pypi-test-container:1.0.0
2025-12-15 00:28:19.856363 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2025-12-15 00:28:23.981549 | controller | Run command: docker inspect 85a041db59fb2e6425d648bc129a428016198d259e536d69a5e3648a4c5c1253
2025-12-15 00:28:24.021919 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2025-12-15 00:28:24.030410 | controller | Including collection: ansible.netcommon (292 files)
2025-12-15 00:28:24.030449 | controller | Including collection: ansible.network (23 files)
2025-12-15 00:28:24.037625 | controller | Including collection: ansible.utils (442 files)
2025-12-15 00:28:24.062758 | controller | Including collection: arista.eos (1022 files)
2025-12-15 00:28:24.093186 | controller | Including collection: cisco.ios (1174 files)
2025-12-15 00:28:24.122809 | controller | Including collection: cisco.iosxr (1044 files)
2025-12-15 00:28:24.173069 | controller | Including collection: cisco.nxos (1682 files)
2025-12-15 00:28:24.199683 | controller | Including collection: junipernetworks.junos (963 files)
2025-12-15 00:28:24.223123 | controller | Creating a payload archive containing 7368 files...
2025-12-15 00:28:25.791181 | controller | Created a 5992191 byte payload archive containing 7368 files in 1 seconds.
2025-12-15 00:28:25.792527 | controller | Assuming Docker is available on localhost.
2025-12-15 00:28:25.792871 | controller | Run command: docker run --detach --volume /sys/fs/cgroup:/sys/fs/cgroup:ro --privileged=false --security-opt seccomp=unconfined --volume /var/run/docker.sock:/var/run/docker.sock quay.io/ansible/default-test-container:2.9.0
2025-12-15 00:28:26.198574 | controller | Run command: docker exec -i 9ea27554dc354044c287f188cf3ba77e84375952482ec8a4549f598633112265 dd of=/root/docker.sh bs=65536
2025-12-15 00:28:26.260418 | controller | Run command: docker exec 9ea27554dc354044c287f188cf3ba77e84375952482ec8a4549f598633112265 /bin/bash /root/docker.sh
2025-12-15 00:28:26.304425 | controller | Run command: docker exec -i 9ea27554dc354044c287f188cf3ba77e84375952482ec8a4549f598633112265 dd of=/root/test.tgz bs=65536
2025-12-15 00:28:26.366520 | controller | Run command: docker exec 9ea27554dc354044c287f188cf3ba77e84375952482ec8a4549f598633112265 tar oxzf /root/test.tgz -C /root
2025-12-15 00:28:27.009083 | controller | Run command: docker exec 9ea27554dc354044c287f188cf3ba77e84375952482ec8a4549f598633112265 /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-wy3bdxy6.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2025-12-15 00:28:27.265457 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2025-12-15 00:28:27.265791 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2025-12-15 00:28:27.265817 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2025-12-15 00:28:27.267182 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2025-12-15 00:28:27.267722 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2025-12-15 00:28:27.272239 | controller | No tests applicable.
2025-12-15 00:28:27.272468 | controller | Running sanity test 'ansible-doc' with Python 3.6
2025-12-15 00:28:27.272510 | controller | No tests applicable.
2025-12-15 00:28:27.272868 | controller | Running sanity test 'changelog' with Python 3.6
2025-12-15 00:28:27.272912 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/ansible-test.txt
2025-12-15 00:28:27.843283 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt
2025-12-15 00:28:28.216766 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2025-12-15 00:28:28.252374 | controller |   ERROR: Could not find a version that satisfies the requirement antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2)) (from versions: none)
2025-12-15 00:28:28.253614 | controller | ERROR: No matching distribution found for antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2025-12-15 00:28:28.294123 | controller | ERROR: Command "/usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt" returned exit status 1.
2025-12-15 00:28:28.294443 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2025-12-15 00:28:28.294642 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2025-12-15 00:28:28.312099 | controller | Removing custom PyPI hosts entries: /etc/hosts
2025-12-15 00:28:28.312138 | controller | Run command: docker exec 9ea27554dc354044c287f188cf3ba77e84375952482ec8a4549f598633112265 tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2025-12-15 00:28:28.381889 | controller | Run command: docker exec -i 9ea27554dc354044c287f188cf3ba77e84375952482ec8a4549f598633112265 dd if=/root/output.tgz bs=65536
2025-12-15 00:28:28.434743 | controller | Run command: tar oxzf /tmp/ansible-result-nleiz265.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2025-12-15 00:28:28.444014 | controller | Run command: docker rm -f 85a041db59fb2e6425d648bc129a428016198d259e536d69a5e3648a4c5c1253
2025-12-15 00:28:29.082491 | controller | Run command: docker rm -f 9ea27554dc354044c287f188cf3ba77e84375952482ec8a4549f598633112265
2025-12-15 00:28:29.519907 | controller | ERROR: Command "docker exec 9ea27554dc354044c287f188cf3ba77e84375952482ec8a4549f598633112265 /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-wy3bdxy6.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2025-12-15 00:28:29.788010 | controller | ERROR
2025-12-15 00:28:29.788312 | controller | {
2025-12-15 00:28:29.788365 | controller |   "delta": "0:01:02.333617",
2025-12-15 00:28:29.788401 | controller |   "end": "2025-12-15 00:28:29.554314",
2025-12-15 00:28:29.788428 | controller |   "msg": "non-zero return code",
2025-12-15 00:28:29.788452 | controller |   "rc": 1,
2025-12-15 00:28:29.788474 | controller |   "start": "2025-12-15 00:27:27.220697"
2025-12-15 00:28:29.788496 | controller | }
failure
2025-12-15 00:28:29.796687 | 
2025-12-15 00:28:29.796876 | PLAY RECAP
2025-12-15 00:28:29.796950 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2025-12-15 00:28:29.796985 | 
2025-12-15 00:28:29.932563 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-12-15 00:28:29.933885 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-15 00:28:30.547176 | 
2025-12-15 00:28:30.547300 | PLAY [all]
2025-12-15 00:28:30.569453 | 
2025-12-15 00:28:30.569548 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-12-15 00:28:30.965811 | controller | changed: non-zero return code
2025-12-15 00:28:30.971585 | 
2025-12-15 00:28:30.971657 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-12-15 00:28:30.996067 | controller | skipping: Conditional result was False
2025-12-15 00:28:31.007934 | 
2025-12-15 00:28:31.008158 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-12-15 00:28:31.043675 | 
2025-12-15 00:28:31.043851 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-12-15 00:28:31.078908 | 
2025-12-15 00:28:31.079134 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-12-15 00:28:31.105116 | controller | skipping: Conditional result was False
2025-12-15 00:28:31.115968 | 
2025-12-15 00:28:31.116094 | LOOP [fetch-subunit-output : Generate subunit file]
2025-12-15 00:28:31.141173 | 
2025-12-15 00:28:31.141369 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-12-15 00:28:31.156276 | controller | skipping: Conditional result was False
2025-12-15 00:28:31.165914 | 
2025-12-15 00:28:31.166070 | TASK [fetch-subunit-output : Remove the temporary file]
2025-12-15 00:28:31.181584 | controller | skipping: Conditional result was False
2025-12-15 00:28:31.191001 | 
2025-12-15 00:28:31.191141 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-12-15 00:28:31.206850 | controller | skipping: Conditional result was False
2025-12-15 00:28:31.243557 | 
2025-12-15 00:28:31.243639 | PLAY RECAP
2025-12-15 00:28:31.243690 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-15 00:28:31.243714 | 
2025-12-15 00:28:31.355830 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-15 00:28:31.357548 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-12-15 00:28:31.934674 | 
2025-12-15 00:28:31.934809 | PLAY [all:!appliance*]
2025-12-15 00:28:31.957063 | 
2025-12-15 00:28:31.957172 | TASK [unregister the node]
2025-12-15 00:28:32.489226 | controller | skipping: Conditional result was False
2025-12-15 00:28:32.501581 | 
2025-12-15 00:28:32.501796 | TASK [include_role : fetch-output]
2025-12-15 00:28:32.551227 | controller | ok
2025-12-15 00:28:32.598570 | 
2025-12-15 00:28:32.598711 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-15 00:28:32.665089 | controller | skipping: Conditional result was False
2025-12-15 00:28:32.681282 | 
2025-12-15 00:28:32.681469 | TASK [fetch-output : Set log path for single node]
2025-12-15 00:28:32.731682 | controller | ok
2025-12-15 00:28:32.744457 | 
2025-12-15 00:28:32.744701 | LOOP [fetch-output : Ensure local output dirs]
2025-12-15 00:28:33.203507 | controller -> localhost | ok: "/var/lib/zuul/builds/affd1c5246f743a7a6a307b8c61c86ad/work/logs"
2025-12-15 00:28:33.460052 | controller -> localhost | changed: "/var/lib/zuul/builds/affd1c5246f743a7a6a307b8c61c86ad/work/artifacts"
2025-12-15 00:28:33.718376 | controller -> localhost | changed: "/var/lib/zuul/builds/affd1c5246f743a7a6a307b8c61c86ad/work/docs"
2025-12-15 00:28:33.731527 | 
2025-12-15 00:28:33.731661 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-15 00:28:34.444809 | controller | changed:
2025-12-15 00:28:34.445155 | controller | .d..t...... ./
2025-12-15 00:28:34.445213 | controller | cd+++++++++ controller/
2025-12-15 00:28:34.445290 | controller | changed: All items complete
2025-12-15 00:28:34.445337 | 
2025-12-15 00:28:34.987416 | controller | changed: .d..t...... ./
2025-12-15 00:28:35.619155 | controller | changed: .d..t...... ./
2025-12-15 00:28:35.633539 | 
2025-12-15 00:28:35.633666 | TASK [include_role : fetch-output-openshift]
2025-12-15 00:28:35.649444 | controller | skipping: Conditional result was False
2025-12-15 00:28:35.656149 | 
2025-12-15 00:28:35.656216 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-15 00:28:35.703851 | controller | skipping: Conditional result was False
2025-12-15 00:28:35.715795 | controller | skipping: Conditional result was False
2025-12-15 00:28:35.748521 | 
2025-12-15 00:28:35.748657 | PLAY [localhost]
2025-12-15 00:28:35.761124 | 
2025-12-15 00:28:35.761184 | TASK [Run Zuul manifest role]
2025-12-15 00:28:35.779570 | localhost | ok
2025-12-15 00:28:35.794224 | 
2025-12-15 00:28:35.794285 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-15 00:28:36.164998 | localhost | changed
2025-12-15 00:28:36.176451 | 
2025-12-15 00:28:36.176602 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-15 00:28:36.225634 | localhost | ok
2025-12-15 00:28:36.244892 | 
2025-12-15 00:28:36.245021 | TASK [Set zuul-log-path fact]
2025-12-15 00:28:36.269937 | localhost | ok
2025-12-15 00:28:36.299576 | 
2025-12-15 00:28:36.299789 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-15 00:28:36.345657 | localhost | ok
2025-12-15 00:28:36.363465 | 
2025-12-15 00:28:36.363595 | LOOP [Run upload-logs-swift role]
2025-12-15 00:28:36.414546 | localhost | Output suppressed because no_log was given
2025-12-15 00:28:36.464580 | 
2025-12-15 00:28:36.464668 | TASK [Set zuul-log-path fact]
2025-12-15 00:28:36.489849 | localhost | skipping: Conditional result was False
2025-12-15 00:28:36.496477 | 
2025-12-15 00:28:36.496562 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-12-15 00:28:36.950795 | localhost -> localhost | ok: Runtime: 0:00:00.010585
2025-12-15 00:28:36.984872 | 
2025-12-15 00:28:36.985018 | TASK [upload-logs-swift : Upload logs to swift]