2025-12-11 00:26:59.045722 | Job console starting...
2025-12-11 00:26:59.057438 | Updating repositories
2025-12-11 00:26:59.176755 | Preparing job workspace
2025-12-11 00:27:03.015386 | Running Ansible setup...
2025-12-11 00:27:11.042466 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-12-11 00:27:11.675791 | 
2025-12-11 00:27:11.675915 | PLAY [localhost]
2025-12-11 00:27:11.684072 | 
2025-12-11 00:27:11.684139 | TASK [Gathering Facts]
2025-12-11 00:27:12.710312 | localhost | ok
2025-12-11 00:27:12.742119 | 
2025-12-11 00:27:12.742337 | TASK [Setup log path fact]
2025-12-11 00:27:12.763519 | localhost | ok
2025-12-11 00:27:12.779992 | 
2025-12-11 00:27:12.780083 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-11 00:27:12.809612 | localhost | ok
2025-12-11 00:27:12.820076 | 
2025-12-11 00:27:12.820170 | TASK [emit-job-header : Print job information]
2025-12-11 00:27:12.860018 | # Job Information
2025-12-11 00:27:12.860167 | Ansible Version: 2.15.12
2025-12-11 00:27:12.860210 | Job: ansible-test-sanity-docker-stable-2.10
2025-12-11 00:27:12.860241 | Pipeline: periodic
2025-12-11 00:27:12.860268 | Executor: ze02.softwarefactory-project.io
2025-12-11 00:27:12.860294 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-12-11 00:27:12.860324 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/c07/ansible/c070bc2f1d8244da8e0e4747fd381e6c/
2025-12-11 00:27:12.860351 | Event ID: ada59fb35c7242cc951334ebfc65a188
2025-12-11 00:27:12.865622 | 
2025-12-11 00:27:12.865748 | LOOP [emit-job-header : Print node information]
2025-12-11 00:27:12.974239 | localhost | ok:
2025-12-11 00:27:12.974511 | localhost | # Node Information
2025-12-11 00:27:12.974540 | localhost | Inventory Hostname: controller
2025-12-11 00:27:12.974559 | localhost | Hostname: np0005554757
2025-12-11 00:27:12.974578 | localhost | Username: zuul
2025-12-11 00:27:12.974602 | localhost | Distro: Fedora 37
2025-12-11 00:27:12.974621 | localhost | Provider: ansible-vexxhost-ams1
2025-12-11 00:27:12.974637 | localhost | Region: ams1
2025-12-11 00:27:12.974654 | localhost | Label: ansible-fedora-37-1vcpu
2025-12-11 00:27:12.974689 | localhost | Product Name: OpenStack Nova
2025-12-11 00:27:12.974708 | localhost | Interface IP: 38.129.16.221
2025-12-11 00:27:12.999811 | 
2025-12-11 00:27:13.000014 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-11 00:27:13.508500 | localhost -> localhost | changed
2025-12-11 00:27:13.521133 | 
2025-12-11 00:27:13.521285 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-11 00:27:14.497378 | localhost -> localhost | changed
2025-12-11 00:27:14.517614 | 
2025-12-11 00:27:14.517709 | PLAY [all:!appliance*]
2025-12-11 00:27:14.533565 | 
2025-12-11 00:27:14.533629 | TASK [include_role : start-zuul-console]
2025-12-11 00:27:14.554169 | controller | ok
2025-12-11 00:27:14.567855 | 
2025-12-11 00:27:14.567917 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-11 00:27:15.639460 | controller | ok
2025-12-11 00:27:15.663923 | 
2025-12-11 00:27:15.664078 | TASK [use-our-mirror : Retrieve the IP address]
2025-12-11 00:27:18.038953 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-12-11 00:27:18.053462 | 
2025-12-11 00:27:18.053598 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-12-11 00:27:18.429416 | controller | skipping: Conditional result was False
2025-12-11 00:27:18.436220 | 
2025-12-11 00:27:18.436297 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-12-11 00:27:18.470441 | controller | skipping: Conditional result was False
2025-12-11 00:27:18.477573 | 
2025-12-11 00:27:18.477655 | TASK [use-our-mirror : Create the podman configuration directory]
2025-12-11 00:27:18.513143 | controller | skipping: Conditional result was False
2025-12-11 00:27:18.521439 | 
2025-12-11 00:27:18.521520 | TASK [use-our-mirror : Copy the podman configuration]
2025-12-11 00:27:18.556158 | controller | skipping: Conditional result was False
2025-12-11 00:27:18.564095 | 
2025-12-11 00:27:18.564175 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-12-11 00:27:18.599142 | controller | skipping: Conditional result was False
2025-12-11 00:27:18.605348 | 
2025-12-11 00:27:18.605414 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-12-11 00:27:18.639788 | controller | skipping: Conditional result was False
2025-12-11 00:27:18.666831 | 
2025-12-11 00:27:18.666989 | TASK [Disable Fedora Modular]
2025-12-11 00:27:19.855860 | controller | changed
2025-12-11 00:27:19.868237 | 
2025-12-11 00:27:19.868381 | TASK [Enable EPEL]
2025-12-11 00:27:19.894143 | controller | skipping: Conditional result was False
2025-12-11 00:27:19.905652 | 
2025-12-11 00:27:19.905819 | TASK [Register the RHEL node]
2025-12-11 00:27:20.308243 | 
2025-12-11 00:27:20.308502 | TASK [Show the subscription-manager status]
2025-12-11 00:27:20.686057 | controller | skipping: Conditional result was False
2025-12-11 00:27:20.695651 | 
2025-12-11 00:27:20.695847 | TASK [Enable EPEL on RHEL]
2025-12-11 00:27:21.076494 | controller | skipping: Conditional result was False
2025-12-11 00:27:21.090582 | 
2025-12-11 00:27:21.090784 | TASK [Install git and tox]
2025-12-11 00:29:29.086429 | controller | changed
2025-12-11 00:29:29.096109 | 
2025-12-11 00:29:29.096223 | TASK [include_role : prepare-workspace]
2025-12-11 00:29:29.121187 | controller | ok
2025-12-11 00:29:29.151089 | 
2025-12-11 00:29:29.151201 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-11 00:29:30.106101 | controller | ok
2025-12-11 00:29:30.122427 | 
2025-12-11 00:29:30.122577 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-11 00:29:45.719870 | controller | Output suppressed because no_log was given
2025-12-11 00:29:45.730146 | 
2025-12-11 00:29:45.730207 | TASK [include_role : prepare-workspace-openshift]
2025-12-11 00:29:45.754293 | controller | skipping: Conditional result was False
2025-12-11 00:29:45.777762 | 
2025-12-11 00:29:45.777821 | PLAY [all:!appliance]
2025-12-11 00:29:45.793506 | 
2025-12-11 00:29:45.793569 | TASK [Run add-build-sshkey role (RSA)]
2025-12-11 00:29:45.814112 | controller | ok
2025-12-11 00:29:45.833708 | 
2025-12-11 00:29:45.833824 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-11 00:29:46.051280 | controller -> localhost | ok
2025-12-11 00:29:46.057945 | 
2025-12-11 00:29:46.058014 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-11 00:29:46.097043 | controller | ok
2025-12-11 00:29:46.110874 | controller | included: /var/lib/zuul/builds/c070bc2f1d8244da8e0e4747fd381e6c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-11 00:29:46.116758 | 
2025-12-11 00:29:46.116818 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-11 00:29:46.629939 | controller -> localhost | Generating public/private rsa key pair.
2025-12-11 00:29:46.630212 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/c070bc2f1d8244da8e0e4747fd381e6c/work/c070bc2f1d8244da8e0e4747fd381e6c_id_rsa.
2025-12-11 00:29:46.630253 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/c070bc2f1d8244da8e0e4747fd381e6c/work/c070bc2f1d8244da8e0e4747fd381e6c_id_rsa.pub.
2025-12-11 00:29:46.630284 | controller -> localhost | The key fingerprint is:
2025-12-11 00:29:46.630312 | controller -> localhost | SHA256:YODDGofken3yljpmCBjfLs6FstQvHmk5v7iXZ6jC4VY zuul-build-sshkey
2025-12-11 00:29:46.630339 | controller -> localhost | The key's randomart image is:
2025-12-11 00:29:46.630365 | controller -> localhost | +---[RSA 2048]----+
2025-12-11 00:29:46.630391 | controller -> localhost | |  . .            |
2025-12-11 00:29:46.630417 | controller -> localhost | | o + .           |
2025-12-11 00:29:46.630443 | controller -> localhost | |  + = o          |
2025-12-11 00:29:46.630469 | controller -> localhost | |.. = o .         |
2025-12-11 00:29:46.630494 | controller -> localhost | |ooo.o . S        |
2025-12-11 00:29:46.630532 | controller -> localhost | |oooE++ .         |
2025-12-11 00:29:46.630569 | controller -> localhost | |+o+X. *          |
2025-12-11 00:29:46.630598 | controller -> localhost | |.O+o@* o         |
2025-12-11 00:29:46.630624 | controller -> localhost | |o.=O*=+          |
2025-12-11 00:29:46.630653 | controller -> localhost | +----[SHA256]-----+
2025-12-11 00:29:46.630739 | controller -> localhost | ok: Runtime: 0:00:00.098298
2025-12-11 00:29:46.642633 | 
2025-12-11 00:29:46.642812 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-11 00:29:46.677087 | controller | ok
2025-12-11 00:29:46.696772 | controller | included: /var/lib/zuul/builds/c070bc2f1d8244da8e0e4747fd381e6c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-11 00:29:46.725184 | 
2025-12-11 00:29:46.725412 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-11 00:29:46.752990 | controller | skipping: Conditional result was False
2025-12-11 00:29:46.759646 | 
2025-12-11 00:29:46.759762 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-11 00:29:48.074290 | controller | changed
2025-12-11 00:29:48.088265 | 
2025-12-11 00:29:48.088423 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-11 00:29:48.751268 | controller | ok
2025-12-11 00:29:48.765056 | 
2025-12-11 00:29:48.765193 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-11 00:29:51.865024 | controller | changed
2025-12-11 00:29:51.882345 | 
2025-12-11 00:29:51.882534 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-11 00:29:54.988519 | controller | changed
2025-12-11 00:29:55.002919 | 
2025-12-11 00:29:55.003106 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-11 00:29:55.030533 | controller | skipping: Conditional result was False
2025-12-11 00:29:55.045259 | 
2025-12-11 00:29:55.045410 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-11 00:29:55.483190 | controller -> localhost | changed
2025-12-11 00:29:55.505239 | 
2025-12-11 00:29:55.505406 | TASK [add-build-sshkey : Add back temp key]
2025-12-11 00:29:55.800863 | controller -> localhost | Identity added: /var/lib/zuul/builds/c070bc2f1d8244da8e0e4747fd381e6c/work/c070bc2f1d8244da8e0e4747fd381e6c_id_rsa (zuul-build-sshkey)
2025-12-11 00:29:55.801259 | controller -> localhost | ok: Runtime: 0:00:00.012654
2025-12-11 00:29:55.817541 | 
2025-12-11 00:29:55.817708 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-11 00:29:56.791869 | controller | ok
2025-12-11 00:29:56.808044 | 
2025-12-11 00:29:56.808137 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-11 00:29:56.847813 | controller | skipping: Conditional result was False
2025-12-11 00:29:56.882178 | 
2025-12-11 00:29:56.882326 | TASK [Run add-build-sshkey role (ECDSA)]
2025-12-11 00:29:56.920248 | controller | ok
2025-12-11 00:29:56.946451 | 
2025-12-11 00:29:56.946553 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-11 00:29:57.194591 | controller -> localhost | ok
2025-12-11 00:29:57.201563 | 
2025-12-11 00:29:57.201629 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-11 00:29:57.226651 | controller | ok
2025-12-11 00:29:57.239226 | controller | included: /var/lib/zuul/builds/c070bc2f1d8244da8e0e4747fd381e6c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-11 00:29:57.245513 | 
2025-12-11 00:29:57.245576 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-11 00:29:57.515619 | controller -> localhost | Generating public/private ecdsa key pair.
2025-12-11 00:29:57.515810 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/c070bc2f1d8244da8e0e4747fd381e6c/work/c070bc2f1d8244da8e0e4747fd381e6c_id_ecdsa.
2025-12-11 00:29:57.515839 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/c070bc2f1d8244da8e0e4747fd381e6c/work/c070bc2f1d8244da8e0e4747fd381e6c_id_ecdsa.pub.
2025-12-11 00:29:57.516199 | controller -> localhost | The key fingerprint is:
2025-12-11 00:29:57.516220 | controller -> localhost | SHA256:yyXk2LD7lqjdnZyGr5MCTmxEw6KDsJRqGfu/VGaVRS0 zuul-build-sshkey
2025-12-11 00:29:57.516239 | controller -> localhost | The key's randomart image is:
2025-12-11 00:29:57.516257 | controller -> localhost | +---[ECDSA 521]---+
2025-12-11 00:29:57.516275 | controller -> localhost | |  ..      .o.    |
2025-12-11 00:29:57.516292 | controller -> localhost | |.+. +     oE .   |
2025-12-11 00:29:57.516308 | controller -> localhost | |=o+o .. .o  .    |
2025-12-11 00:29:57.516325 | controller -> localhost | |*+  .  B.        |
2025-12-11 00:29:57.516341 | controller -> localhost | |...o  o+S .      |
2025-12-11 00:29:57.516358 | controller -> localhost | |   .= +o +       |
2025-12-11 00:29:57.516375 | controller -> localhost | |   +.o..o+       |
2025-12-11 00:29:57.516391 | controller -> localhost | |    oooo*o.o     |
2025-12-11 00:29:57.516408 | controller -> localhost | |    .ooo+=*      |
2025-12-11 00:29:57.516425 | controller -> localhost | +----[SHA256]-----+
2025-12-11 00:29:57.516467 | controller -> localhost | ok: Runtime: 0:00:00.016986
2025-12-11 00:29:57.523376 | 
2025-12-11 00:29:57.523443 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-11 00:29:57.544278 | controller | ok
2025-12-11 00:29:57.551758 | controller | included: /var/lib/zuul/builds/c070bc2f1d8244da8e0e4747fd381e6c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-11 00:29:57.561014 | 
2025-12-11 00:29:57.561077 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-11 00:29:57.575091 | controller | skipping: Conditional result was False
2025-12-11 00:29:57.612699 | 
2025-12-11 00:29:57.612823 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-11 00:29:58.593245 | controller | changed
2025-12-11 00:29:58.603121 | 
2025-12-11 00:29:58.603219 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-11 00:29:59.355487 | controller | ok
2025-12-11 00:29:59.371314 | 
2025-12-11 00:29:59.371437 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-11 00:30:02.579886 | controller | changed
2025-12-11 00:30:02.587856 | 
2025-12-11 00:30:02.587940 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-11 00:30:05.734305 | controller | changed
2025-12-11 00:30:05.749720 | 
2025-12-11 00:30:05.749905 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-11 00:30:05.787670 | controller | skipping: Conditional result was False
2025-12-11 00:30:05.797899 | 
2025-12-11 00:30:05.798038 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-11 00:30:06.050965 | controller -> localhost | changed
2025-12-11 00:30:06.079169 | 
2025-12-11 00:30:06.079353 | TASK [add-build-sshkey : Add back temp key]
2025-12-11 00:30:06.394106 | controller -> localhost | Identity added: /var/lib/zuul/builds/c070bc2f1d8244da8e0e4747fd381e6c/work/c070bc2f1d8244da8e0e4747fd381e6c_id_ecdsa (zuul-build-sshkey)
2025-12-11 00:30:06.394305 | controller -> localhost | ok: Runtime: 0:00:00.015461
2025-12-11 00:30:06.400679 | 
2025-12-11 00:30:06.400755 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-11 00:30:07.150123 | controller | ok
2025-12-11 00:30:07.163966 | 
2025-12-11 00:30:07.164174 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-11 00:30:07.192565 | controller | skipping: Conditional result was False
2025-12-11 00:30:07.219470 | 
2025-12-11 00:30:07.219603 | TASK [include_role : remove-zuul-sshkey]
2025-12-11 00:30:07.236309 | controller | skipping: Conditional result was False
2025-12-11 00:30:07.245565 | 
2025-12-11 00:30:07.245653 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-11 00:30:07.917062 | controller | ok: "logs"
2025-12-11 00:30:07.917746 | controller | ok: All items complete
2025-12-11 00:30:07.917833 | 
2025-12-11 00:30:08.572502 | controller | ok: "artifacts"
2025-12-11 00:30:09.281992 | controller | ok: "docs"
2025-12-11 00:30:09.305996 | 
2025-12-11 00:30:09.306151 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-11 00:30:10.039130 | controller | changed: "logs"
2025-12-11 00:30:10.732938 | controller | changed: "artifacts"
2025-12-11 00:30:11.395906 | controller | changed: "docs"
2025-12-11 00:30:11.431803 | 
2025-12-11 00:30:11.431925 | PLAY RECAP
2025-12-11 00:30:11.431975 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-12-11 00:30:11.432009 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-11 00:30:11.432034 | 
2025-12-11 00:30:11.549553 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-12-11 00:30:11.551302 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-11 00:30:12.154243 | 
2025-12-11 00:30:12.154396 | PLAY [all]
2025-12-11 00:30:12.176568 | 
2025-12-11 00:30:12.176705 | TASK [Install binary dependencies]
2025-12-11 00:30:12.249998 | controller | ok
2025-12-11 00:30:12.278516 | 
2025-12-11 00:30:12.278703 | TASK [bindep : Include find tasks]
2025-12-11 00:30:12.311165 | controller | ok
2025-12-11 00:30:12.324298 | controller | included: /var/lib/zuul/builds/c070bc2f1d8244da8e0e4747fd381e6c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-12-11 00:30:12.333611 | 
2025-12-11 00:30:12.333774 | TASK [bindep : Look for bindep.txt]
2025-12-11 00:30:13.336307 | controller | ok
2025-12-11 00:30:13.345883 | 
2025-12-11 00:30:13.346027 | TASK [bindep : Define bindep_file fact]
2025-12-11 00:30:13.373430 | controller | skipping: Conditional result was False
2025-12-11 00:30:13.381082 | 
2025-12-11 00:30:13.381181 | TASK [bindep : Look for other-requirements.txt]
2025-12-11 00:30:14.051835 | controller | ok
2025-12-11 00:30:14.068529 | 
2025-12-11 00:30:14.068768 | TASK [bindep : Define bindep_file fact]
2025-12-11 00:30:14.108647 | controller | skipping: Conditional result was False
2025-12-11 00:30:14.131071 | 
2025-12-11 00:30:14.131226 | TASK [bindep : Look for bindep fallback file]
2025-12-11 00:30:14.157511 | controller | skipping: Conditional result was False
2025-12-11 00:30:14.166025 | 
2025-12-11 00:30:14.166135 | TASK [bindep : Define bindep_file fact]
2025-12-11 00:30:14.192538 | controller | skipping: Conditional result was False
2025-12-11 00:30:14.201134 | 
2025-12-11 00:30:14.201229 | TASK [bindep : Include bindep tasks]
2025-12-11 00:30:14.226812 | controller | skipping: Conditional result was False
2025-12-11 00:30:14.235463 | 
2025-12-11 00:30:14.235560 | TASK [bindep : Include install tasks]
2025-12-11 00:30:14.261824 | controller | skipping: Conditional result was False
2025-12-11 00:30:14.273047 | 
2025-12-11 00:30:14.273202 | LOOP [bindep : Include package tasks]
2025-12-11 00:30:14.345773 | 
2025-12-11 00:30:14.345918 | TASK [Run test-setup role]
2025-12-11 00:30:14.366993 | controller | ok
2025-12-11 00:30:14.407904 | 
2025-12-11 00:30:14.408033 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-12-11 00:30:15.149324 | controller | ok
2025-12-11 00:30:15.163123 | 
2025-12-11 00:30:15.163265 | TASK [test-setup : Run tools/test-setup.sh]
2025-12-11 00:30:15.555709 | controller | skipping: Conditional result was False
2025-12-11 00:30:15.612735 | 
2025-12-11 00:30:15.612848 | PLAY RECAP
2025-12-11 00:30:15.612905 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-11 00:30:15.612935 | 
2025-12-11 00:30:15.717503 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-11 00:30:15.718380 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-12-11 00:30:16.280941 | 
2025-12-11 00:30:16.281063 | PLAY [controller]
2025-12-11 00:30:16.301539 | 
2025-12-11 00:30:16.301654 | TASK [Create the /root directory]
2025-12-11 00:30:17.431085 | controller | ok
2025-12-11 00:30:17.437516 | 
2025-12-11 00:30:17.437600 | TASK [Install glibc-langpack-en]
2025-12-11 00:30:26.448959 | controller | ok: Nothing to do
2025-12-11 00:30:26.461163 | 
2025-12-11 00:30:26.461277 | TASK [Ensure controller directory exists]
2025-12-11 00:30:27.302767 | controller | changed
2025-12-11 00:30:27.315816 | 
2025-12-11 00:30:27.315950 | TASK [Install container runtime]
2025-12-11 00:30:27.396355 | controller | ok
2025-12-11 00:30:27.457473 | 
2025-12-11 00:30:27.457559 | LOOP [ensure-docker : Gather variables for each operating system]
2025-12-11 00:30:27.499451 | controller | ok: "/var/lib/zuul/builds/c070bc2f1d8244da8e0e4747fd381e6c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2025-12-11 00:30:27.509874 | 
2025-12-11 00:30:27.510065 | TASK [ensure-docker : Sanity check for distro selection]
2025-12-11 00:30:27.534209 | controller | skipping: Conditional result was False
2025-12-11 00:30:27.540101 | 
2025-12-11 00:30:27.540171 | TASK [ensure-docker : Sanity check for distro install]
2025-12-11 00:30:27.564962 | controller | skipping: Conditional result was False
2025-12-11 00:30:27.571200 | 
2025-12-11 00:30:27.571290 | TASK [ensure-docker : Sanity check for upstream install]
2025-12-11 00:30:27.595894 | controller | skipping: Conditional result was False
2025-12-11 00:30:27.601953 | 
2025-12-11 00:30:27.602017 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2025-12-11 00:30:27.625981 | controller | skipping: Conditional result was False
2025-12-11 00:30:27.631731 | 
2025-12-11 00:30:27.631793 | TASK [ensure-docker : Include tasks for docker install]
2025-12-11 00:30:27.650944 | controller | ok
2025-12-11 00:30:27.660887 | controller | included: /var/lib/zuul/builds/c070bc2f1d8244da8e0e4747fd381e6c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2025-12-11 00:30:27.668746 | 
2025-12-11 00:30:27.668809 | TASK [ensure-docker : Not Implemented]
2025-12-11 00:30:27.692660 | controller | skipping: Conditional result was False
2025-12-11 00:30:27.700809 | 
2025-12-11 00:30:27.700890 | TASK [Set up docker mirrors]
2025-12-11 00:30:27.732830 | controller | ok
2025-12-11 00:30:27.777774 | 
2025-12-11 00:30:27.777968 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2025-12-11 00:30:27.846701 | controller | skipping: Conditional result was False
2025-12-11 00:30:27.906187 | 
2025-12-11 00:30:27.906353 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2025-12-11 00:30:27.943256 | controller | skipping: Conditional result was False
2025-12-11 00:30:27.950606 | 
2025-12-11 00:30:27.950760 | TASK [use-docker-mirror : Set docker_mirror fact]
2025-12-11 00:30:27.985435 | controller | skipping: Conditional result was False
2025-12-11 00:30:27.992822 | 
2025-12-11 00:30:27.992908 | TASK [use-docker-mirror : Set up docker mirrors]
2025-12-11 00:30:28.017089 | controller | skipping: Conditional result was False
2025-12-11 00:30:28.031572 | 
2025-12-11 00:30:28.031699 | TASK [Add all repositories]
2025-12-11 00:30:28.053081 | controller | ok
2025-12-11 00:30:28.080353 | 
2025-12-11 00:30:28.080485 | LOOP [ensure-package-repositories : Install repositories]
2025-12-11 00:30:28.117940 | controller | ok: "/var/lib/zuul/builds/c070bc2f1d8244da8e0e4747fd381e6c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2025-12-11 00:30:28.118705 | controller | ok: All items complete
2025-12-11 00:30:28.118796 | 
2025-12-11 00:30:28.138551 | controller | included: /var/lib/zuul/builds/c070bc2f1d8244da8e0e4747fd381e6c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2025-12-11 00:30:28.147723 | 
2025-12-11 00:30:28.147861 | TASK [ensure-package-repositories : Create GPG key temp directory]
2025-12-11 00:30:29.029333 | controller | changed
2025-12-11 00:30:29.037621 | 
2025-12-11 00:30:29.037736 | LOOP [ensure-package-repositories : Upload GPG keys]
2025-12-11 00:30:32.365715 | controller | changed:
2025-12-11 00:30:32.365981 | controller | {
2025-12-11 00:30:32.366010 | 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-11 00:30:32.366031 | controller | }
2025-12-11 00:30:32.377613 | 
2025-12-11 00:30:32.377790 | LOOP [ensure-package-repositories : Import GPG keys]
2025-12-11 00:30:33.893409 | controller | changed:
2025-12-11 00:30:33.893578 | controller | {
2025-12-11 00:30:33.893605 | 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-11 00:30:33.893626 | controller | }
2025-12-11 00:30:33.893657 | controller | changed: All items complete
2025-12-11 00:30:33.893715 | 
2025-12-11 00:30:33.908795 | 
2025-12-11 00:30:33.908919 | TASK [ensure-package-repositories : Remove GPG temp directory]
2025-12-11 00:30:34.660622 | controller | changed
2025-12-11 00:30:34.669792 | 
2025-12-11 00:30:34.669866 | LOOP [ensure-package-repositories : Add repositories]
2025-12-11 00:30:35.594164 | controller | changed:
2025-12-11 00:30:35.594739 | controller | {
2025-12-11 00:30:35.594809 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2025-12-11 00:30:35.594853 | controller |   "description": "Docker CE Stable - $basearch",
2025-12-11 00:30:35.594889 | controller |   "gpgcheck": true,
2025-12-11 00:30:35.594924 | controller |   "name": "docker-ce-stable"
2025-12-11 00:30:35.594959 | controller | }
2025-12-11 00:30:35.620453 | 
2025-12-11 00:30:35.620645 | TASK [ensure-docker : Install pre-reqs]
2025-12-11 00:30:41.512981 | controller | ok: Nothing to do
2025-12-11 00:30:41.525607 | 
2025-12-11 00:30:41.525789 | TASK [ensure-docker : Remove packages]
2025-12-11 00:30:45.303095 | controller | ok: Nothing to do
2025-12-11 00:30:45.316049 | 
2025-12-11 00:30:45.316227 | TASK [ensure-docker : Install upstream docker using package]
2025-12-11 00:32:13.797313 | controller | changed
2025-12-11 00:32:13.812451 | 
2025-12-11 00:32:13.812589 | TASK [ensure-docker : Setup docker]
2025-12-11 00:32:13.840899 | controller | ok
2025-12-11 00:32:13.857395 | controller | included: /var/lib/zuul/builds/c070bc2f1d8244da8e0e4747fd381e6c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2025-12-11 00:32:13.875331 | 
2025-12-11 00:32:13.875431 | TASK [ensure-docker : Ensure "docker" group exists]
2025-12-11 00:32:14.972728 | controller | ok
2025-12-11 00:32:14.985402 | 
2025-12-11 00:32:14.985551 | TASK [ensure-docker : Add user to docker group]
2025-12-11 00:32:16.108165 | controller | changed
2025-12-11 00:32:16.117553 | 
2025-12-11 00:32:16.117639 | TASK [Update docker daemon proxy configuration]
2025-12-11 00:32:16.154309 | controller | skipping: Conditional result was False
2025-12-11 00:32:16.168543 | 
2025-12-11 00:32:16.168726 | TASK [Lower default MTU]
2025-12-11 00:32:16.186952 | controller | skipping: Conditional result was False
2025-12-11 00:32:16.201517 | 
2025-12-11 00:32:16.201649 | TASK [ensure-docker : Get the status of the docket.socket unit]
2025-12-11 00:32:17.098821 | controller | inactive
2025-12-11 00:32:17.576396 | controller | ok: Runtime: 0:00:00.022352
2025-12-11 00:32:17.591612 | 
2025-12-11 00:32:17.591792 | TASK [ensure-docker : Get the status of the docket unit]
2025-12-11 00:32:18.434132 | controller | inactive
2025-12-11 00:32:18.970579 | controller | ok: Runtime: 0:00:00.025282
2025-12-11 00:32:18.985379 | 
2025-12-11 00:32:18.985537 | TASK [ensure-docker : Restart docker]
2025-12-11 00:32:19.049460 | Trigger a Docker restart
2025-12-11 00:32:19.062225 | 
2025-12-11 00:32:19.062361 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2025-12-11 00:32:19.097358 | 
2025-12-11 00:32:19.097457 | TASK [ensure-docker : Install docker-compose]
2025-12-11 00:32:19.113658 | controller | skipping: Conditional result was False
2025-12-11 00:32:19.121726 | 
2025-12-11 00:32:19.121796 | TASK [ensure-docker : Flush handlers before role exit]
2025-12-11 00:32:19.132847 | 
2025-12-11 00:32:19.132910 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2025-12-11 00:32:22.594714 | controller | changed
2025-12-11 00:32:22.613338 | 
2025-12-11 00:32:22.613545 | TASK [ensure-docker : Assure docker service is running]
2025-12-11 00:32:26.625227 | controller | changed
2025-12-11 00:32:26.633077 | 
2025-12-11 00:32:26.633148 | TASK [ensure-docker : Assure docker.socket service is running]
2025-12-11 00:32:28.102913 | controller | ok
2025-12-11 00:32:28.110872 | 
2025-12-11 00:32:28.110938 | TASK [ensure-docker : Correct group ownership on docker sock]
2025-12-11 00:32:28.859899 | controller | ok
2025-12-11 00:32:28.873360 | 
2025-12-11 00:32:28.873505 | TASK [ensure-docker : Validate ability to talk with docker]
2025-12-11 00:32:29.515346 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2025-12-11 00:32:29.746569 | controller | ok: Runtime: 0:00:00.034785
2025-12-11 00:32:29.767830 | 
2025-12-11 00:32:29.767977 | TASK [ensure-docker : Get version details]
2025-12-11 00:32:30.464498 | controller | Client: Docker Engine - Community
2025-12-11 00:32:30.464736 | controller |  Version:           24.0.7
2025-12-11 00:32:30.464807 | controller |  API version:       1.43
2025-12-11 00:32:30.464871 | controller |  Go version:        go1.20.10
2025-12-11 00:32:30.464937 | controller |  Git commit:        afdd53b
2025-12-11 00:32:30.465017 | controller |  Built:             Thu Oct 26 09:09:39 2023
2025-12-11 00:32:30.465089 | controller |  OS/Arch:           linux/amd64
2025-12-11 00:32:30.465156 | controller |  Context:           default
2025-12-11 00:32:30.465177 | controller |
2025-12-11 00:32:30.465241 | controller | Server: Docker Engine - Community
2025-12-11 00:32:30.465299 | controller |  Engine:
2025-12-11 00:32:30.465487 | controller |   Version:          24.0.7
2025-12-11 00:32:30.465541 | controller |   API version:      1.43 (minimum version 1.12)
2025-12-11 00:32:30.465600 | controller |   Go version:       go1.20.10
2025-12-11 00:32:30.465655 | controller |   Git commit:       311b9ff
2025-12-11 00:32:30.465703 | controller |   Built:            Thu Oct 26 09:08:11 2023
2025-12-11 00:32:30.465765 | controller |   OS/Arch:          linux/amd64
2025-12-11 00:32:30.465820 | controller |   Experimental:     false
2025-12-11 00:32:30.465845 | controller |  containerd:
2025-12-11 00:32:30.466004 | controller |   Version:          1.6.25
2025-12-11 00:32:30.466050 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2025-12-11 00:32:30.466093 | controller |  runc:
2025-12-11 00:32:30.466189 | controller |   Version:          1.1.10
2025-12-11 00:32:30.466245 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2025-12-11 00:32:30.466274 | controller |  docker-init:
2025-12-11 00:32:30.466369 | controller |   Version:          0.19.0
2025-12-11 00:32:30.466427 | controller |   GitCommit:        de40ad0
2025-12-11 00:32:30.685110 | controller | ok: Runtime: 0:00:00.045040
2025-12-11 00:32:30.698628 | 
2025-12-11 00:32:30.698793 | TASK [ensure-docker : Dump installed docker details]
2025-12-11 00:32:30.737361 | {'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-11 00:32:30.425552', 'end': '2025-12-11 00:32:30.470592', 'delta': '0:00:00.045040', 'msg': '', 'zuul_log_id': 'fa163efc-24cc-a33b-7f85-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-11 00:32:30.749981 | 
2025-12-11 00:32:30.750114 | TASK [Use buildset registry]
2025-12-11 00:32:30.776887 | controller | skipping: Conditional result was False
2025-12-11 00:32:30.803718 | 
2025-12-11 00:32:30.803884 | TASK [Ensure python3.8 is present]
2025-12-11 00:32:30.830287 | controller | skipping: Conditional result was False
2025-12-11 00:32:30.838372 | 
2025-12-11 00:32:30.838463 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-12-11 00:32:30.860548 | controller | ok
2025-12-11 00:32:30.886045 | 
2025-12-11 00:32:30.886159 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-12-11 00:32:37.666212 | controller | ok: Nothing to do
2025-12-11 00:32:37.679584 | 
2025-12-11 00:32:37.679796 | TASK [our-ensure-python : Also install python3-devel]
2025-12-11 00:32:53.992373 | controller | changed
2025-12-11 00:32:54.015814 | 
2025-12-11 00:32:54.015951 | TASK [Run ensure-virtualenv role]
2025-12-11 00:32:54.045310 | controller | ok
2025-12-11 00:32:54.081132 | 
2025-12-11 00:32:54.081233 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-12-11 00:32:54.831906 | controller | /usr/bin/virtualenv
2025-12-11 00:32:55.440641 | controller | ok: Runtime: 0:00:00.005731
2025-12-11 00:32:55.457721 | 
2025-12-11 00:32:55.457928 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-12-11 00:32:55.484447 | controller | skipping: Conditional result was False
2025-12-11 00:32:55.484964 | controller | changed: All items complete
2025-12-11 00:32:55.485029 | 
2025-12-11 00:32:55.510746 | 
2025-12-11 00:32:55.510914 | TASK [Find the full path of the Python interpreter]
2025-12-11 00:32:56.386050 | controller | /usr/bin/python3.8
2025-12-11 00:32:56.888331 | controller | ok
2025-12-11 00:32:56.901617 | 
2025-12-11 00:32:56.901806 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-12-11 00:32:59.252680 | controller | created virtual environment CPython3.8.18.final.0-64 in 1144ms
2025-12-11 00:32:59.303685 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2025-12-11 00:32:59.303748 | 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-11 00:32:59.303763 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-12-11 00:32:59.303784 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-12-11 00:32:59.782527 | controller | changed
2025-12-11 00:32:59.788313 | 
2025-12-11 00:32:59.788372 | TASK [Set selinux package]
2025-12-11 00:32:59.817433 | controller | ok
2025-12-11 00:32:59.822777 | 
2025-12-11 00:32:59.822834 | TASK [Set selinux package (Fedora)]
2025-12-11 00:32:59.874020 | controller | ok
2025-12-11 00:32:59.880880 | 
2025-12-11 00:32:59.880960 | TASK [Install selinux into virtualenv]
2025-12-11 00:33:05.543160 | controller | Collecting selinux-please-lie-to-me
2025-12-11 00:33:05.684051 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-12-11 00:33:06.930298 | controller | Collecting setuptools<50.0.0
2025-12-11 00:33:06.937301 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-12-11 00:33:07.028711 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 9.4 MB/s eta 0:00:00
2025-12-11 00:33:07.155150 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-12-11 00:33:07.155370 | controller |   Attempting uninstall: setuptools
2025-12-11 00:33:07.156441 | controller |     Found existing installation: setuptools 62.6.0
2025-12-11 00:33:07.291832 | controller |     Uninstalling setuptools-62.6.0:
2025-12-11 00:33:07.312937 | controller |       Successfully uninstalled setuptools-62.6.0
2025-12-11 00:33:07.985540 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-12-11 00:33:08.198147 | controller |
2025-12-11 00:33:08.664152 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-12-11 00:33:08.664239 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-12-11 00:33:09.258652 | controller | ok: Runtime: 0:00:07.886898
2025-12-11 00:33:09.267067 | 
2025-12-11 00:33:09.267152 | TASK [Install pytest-forked into virtualenv]
2025-12-11 00:33:10.821298 | controller | Collecting pytest-forked
2025-12-11 00:33:10.882265 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2025-12-11 00:33:10.954141 | controller | Collecting py
2025-12-11 00:33:10.959299 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2025-12-11 00:33:10.990891 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 3.3 MB/s eta 0:00:00
2025-12-11 00:33:11.186039 | controller | Collecting pytest>=3.10
2025-12-11 00:33:11.193295 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2025-12-11 00:33:11.217041 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 19.5 MB/s eta 0:00:00
2025-12-11 00:33:11.287131 | controller | Collecting iniconfig
2025-12-11 00:33:11.293470 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2025-12-11 00:33:11.349556 | controller | Collecting pluggy<2,>=1.5
2025-12-11 00:33:11.355238 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2025-12-11 00:33:11.411725 | controller | Collecting exceptiongroup>=1.0.0rc8
2025-12-11 00:33:11.419200 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2025-12-11 00:33:11.493632 | controller | Collecting packaging
2025-12-11 00:33:11.498668 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-12-11 00:33:11.528803 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 2.1 MB/s eta 0:00:00
2025-12-11 00:33:11.599248 | controller | Collecting tomli>=1
2025-12-11 00:33:11.606749 | controller |   Downloading tomli-2.3.0-py3-none-any.whl (14 kB)
2025-12-11 00:33:11.695110 | controller | Collecting typing-extensions>=4.6.0
2025-12-11 00:33:11.700601 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2025-12-11 00:33:11.714135 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 3.6 MB/s eta 0:00:00
2025-12-11 00:33:11.821152 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2025-12-11 00:33:12.640782 | 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-11 00:33:12.647820 | controller |
2025-12-11 00:33:12.869808 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-12-11 00:33:12.869866 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-12-11 00:33:13.134812 | controller | ok: Runtime: 0:00:02.805432
2025-12-11 00:33:13.148844 | 
2025-12-11 00:33:13.149057 | TASK [Update pip]
2025-12-11 00:33:15.303195 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2025-12-11 00:33:15.737207 | controller | Collecting pip
2025-12-11 00:33:15.864153 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2025-12-11 00:33:16.011330 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 14.2 MB/s eta 0:00:00
2025-12-11 00:33:16.229929 | controller | Installing collected packages: pip
2025-12-11 00:33:16.234818 | controller |   Attempting uninstall: pip
2025-12-11 00:33:16.236320 | controller |     Found existing installation: pip 22.2.2
2025-12-11 00:33:16.681893 | controller |     Uninstalling pip-22.2.2:
2025-12-11 00:33:16.726315 | controller |       Successfully uninstalled pip-22.2.2
2025-12-11 00:33:19.574837 | controller | Successfully installed pip-25.0.1
2025-12-11 00:33:20.048366 | controller | ok: Runtime: 0:00:05.596435
2025-12-11 00:33:20.062045 | 
2025-12-11 00:33:20.062201 | TASK [Ensure pip temp directory exists in /var/tmp]
2025-12-11 00:33:21.284369 | controller | changed
2025-12-11 00:33:21.296992 | 
2025-12-11 00:33:21.297135 | TASK [Install ansible into virtualenv]
2025-12-11 00:33:23.196634 | controller | Processing ./src/github.com/ansible/ansible
2025-12-11 00:33:23.198254 | controller |   Preparing metadata (setup.py): started
2025-12-11 00:33:24.025039 | controller |   Preparing metadata (setup.py): finished with status 'done'
2025-12-11 00:33:24.085357 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2025-12-11 00:33:24.099813 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2025-12-11 00:33:24.188473 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2025-12-11 00:33:24.194104 | 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-11 00:33:24.613434 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2025-12-11 00:33:24.619374 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2025-12-11 00:33:24.649050 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (25.0)
2025-12-11 00:33:24.878102 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2025-12-11 00:33:24.883817 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2025-12-11 00:33:24.891531 | 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-11 00:33:24.996902 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2025-12-11 00:33:25.002430 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2025-12-11 00:33:25.034390 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2025-12-11 00:33:25.039230 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2025-12-11 00:33:25.055497 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2025-12-11 00:33:25.109398 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 99.4 MB/s eta 0:00:00
2025-12-11 00:33:25.117427 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2025-12-11 00:33:25.130761 | 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-11 00:33:25.150257 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 38.5 MB/s eta 0:00:00
2025-12-11 00:33:25.156013 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2025-12-11 00:33:25.173202 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2025-12-11 00:33:25.182914 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2025-12-11 00:33:25.209119 | controller | Building wheels for collected packages: ansible-base
2025-12-11 00:33:25.210089 | controller |   Building wheel for ansible-base (setup.py): started
2025-12-11 00:33:27.500033 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2025-12-11 00:33:27.511049 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=ac099b02e1fe64102ab8fae9a1fbcdf2b4aa6f7638e93c80cd0bcb8c63adc9eb
2025-12-11 00:33:27.511079 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-yercx0n8/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2025-12-11 00:33:27.511095 | controller | Successfully built ansible-base
2025-12-11 00:33:27.563927 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2025-12-11 00:33:30.469324 | 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-11 00:33:31.180041 | controller | ok: Runtime: 0:00:08.466100
2025-12-11 00:33:31.186503 | 
2025-12-11 00:33:31.186568 | TASK [Setup download-artifact-fork role]
2025-12-11 00:33:31.216279 | controller | ok
2025-12-11 00:33:31.235286 | 
2025-12-11 00:33:31.235364 | TASK [download-artifact-fork : Parse inventory response]
2025-12-11 00:33:31.287219 | controller | ok
2025-12-11 00:33:31.293785 | 
2025-12-11 00:33:31.293851 | TASK [download-artifact-fork : Fetch info from Zuul API]
2025-12-11 00:33:31.328478 | controller | skipping: Conditional result was False
2025-12-11 00:33:31.335657 | 
2025-12-11 00:33:31.335748 | TASK [download-artifact-fork : Parse build response]
2025-12-11 00:33:31.370714 | controller | skipping: Conditional result was False
2025-12-11 00:33:31.378098 | 
2025-12-11 00:33:31.378171 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2025-12-11 00:33:32.195039 | controller | changed
2025-12-11 00:33:32.210778 | 
2025-12-11 00:33:32.210939 | LOOP [download-artifact-fork : Download archive by type]
2025-12-11 00:33:32.251031 | controller | skipping: Conditional result was False
2025-12-11 00:33:32.251895 | 
2025-12-11 00:33:41.717610 | controller | changed: OK (20205 bytes)
2025-12-11 00:33:41.730597 | 
2025-12-11 00:33:41.730665 | TASK [deploy-artifacts : define __collections]
2025-12-11 00:33:41.760123 | controller | ok
2025-12-11 00:33:41.765247 | 
2025-12-11 00:33:41.765308 | LOOP [deploy-artifacts : Create list of collections]
2025-12-11 00:33:41.799716 | controller | skipping: Conditional result was False
2025-12-11 00:33:41.813704 | controller | ok:
2025-12-11 00:33:41.813760 | controller | {
2025-12-11 00:33:41.813781 | controller |   "branch": "main",
2025-12-11 00:33:41.813799 | controller |   "job": "build-ansible-collection",
2025-12-11 00:33:41.813815 | controller |   "metadata": {
2025-12-11 00:33:41.813830 | controller |     "type": "ansible_collection",
2025-12-11 00:33:41.813846 | controller |     "version": "5.0.0"
2025-12-11 00:33:41.813861 | controller |   },
2025-12-11 00:33:41.813876 | controller |   "name": "ansible.network",
2025-12-11 00:33:41.813891 | controller |   "project": "ansible-collections/ansible.network",
2025-12-11 00:33:41.813906 | controller |   "url": "https://51a3e48a3cc523ca214a-2104431b8a4d8392574411b303d2f0d2.ssl.cf5.rackcdn.com/ansible/cc5483cbb965423cb2c3c752525ee859/artifacts/ansible-network-5.0.0.tar.gz"
2025-12-11 00:33:41.813928 | controller | }
2025-12-11 00:33:41.819888 | 
2025-12-11 00:33:41.819952 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2025-12-11 00:33:43.313247 | controller | Starting galaxy collection install process
2025-12-11 00:33:43.313401 | controller | Process install dependency map
2025-12-11 00:33:58.343992 | controller | Starting collection install process
2025-12-11 00:33:58.344117 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2025-12-11 00:33:58.356810 | controller | ansible.network (5.0.0) was installed successfully
2025-12-11 00:33:58.356965 | controller | Installing 'ansible.netcommon:8.2.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2025-12-11 00:33:58.356986 | 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-4068krv2g604/tmp8z1sn09h
2025-12-11 00:33:59.963115 | controller | ansible.netcommon (8.2.0) was installed successfully
2025-12-11 00:33:59.963273 | controller | Installing 'ansible.utils:6.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2025-12-11 00:33:59.963289 | 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-4068krv2g604/tmp8z1sn09h
2025-12-11 00:34:01.600352 | controller | ansible.utils (6.0.0) was installed successfully
2025-12-11 00:34:01.600525 | controller | Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2025-12-11 00:34:01.600542 | 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-4068krv2g604/tmp8z1sn09h
2025-12-11 00:34:04.488058 | controller | arista.eos (12.0.0) was installed successfully
2025-12-11 00:34:04.488423 | controller | Installing 'cisco.ios:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2025-12-11 00:34:04.488452 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-ios-11.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4068krv2g604/tmp8z1sn09h
2025-12-11 00:34:07.628510 | controller | cisco.ios (11.1.1) was installed successfully
2025-12-11 00:34:07.628804 | controller | Installing 'cisco.iosxr:12.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2025-12-11 00:34:07.628821 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-iosxr-12.1.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4068krv2g604/tmp8z1sn09h
2025-12-11 00:34:10.378083 | controller | cisco.iosxr (12.1.0) was installed successfully
2025-12-11 00:34:10.378285 | controller | Installing 'cisco.nxos:11.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2025-12-11 00:34:10.378724 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.1.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4068krv2g604/tmp8z1sn09h
2025-12-11 00:34:14.587520 | controller | cisco.nxos (11.1.0) was installed successfully
2025-12-11 00:34:14.587713 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2025-12-11 00:34:14.588179 | 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-4068krv2g604/tmp8z1sn09h
2025-12-11 00:34:17.168654 | controller | junipernetworks.junos (11.0.0) was installed successfully
2025-12-11 00:34:17.770033 | controller | ok: Runtime: 0:00:34.670336
2025-12-11 00:34:17.798411 | 
2025-12-11 00:34:17.798588 | TASK [podman may need iptables kernel modules]
2025-12-11 00:34:19.172989 | controller | ok: Runtime: 0:00:00.010094
2025-12-11 00:34:19.196397 | 
2025-12-11 00:34:19.196493 | PLAY RECAP
2025-12-11 00:34:19.196534 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2025-12-11 00:34:19.196557 | 
2025-12-11 00:34:19.319522 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-12-11 00:34:19.320433 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-12-11 00:34:20.017431 | 
2025-12-11 00:34:20.017567 | PLAY [controller]
2025-12-11 00:34:20.040960 | 
2025-12-11 00:34:20.041104 | TASK [Copy the galaxy.yml on the controller]
2025-12-11 00:34:23.631083 | controller | changed
2025-12-11 00:34:23.640921 | 
2025-12-11 00:34:23.641036 | TASK [Load information from galaxy.yml]
2025-12-11 00:34:23.694216 | controller | ok
2025-12-11 00:34:23.704219 | 
2025-12-11 00:34:23.704327 | LOOP [Enable FIPS mode]
2025-12-11 00:34:23.741279 | 
2025-12-11 00:34:23.741522 | LOOP [ansible-test : Include branch specific variables.]
2025-12-11 00:34:23.796366 | controller | ok: "/var/lib/zuul/builds/c070bc2f1d8244da8e0e4747fd381e6c/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2025-12-11 00:34:23.796749 | 
2025-12-11 00:34:23.811080 | 
2025-12-11 00:34:23.811244 | TASK [ansible-test : Setup --skip-tags for test_options]
2025-12-11 00:34:23.861314 | controller | ok
2025-12-11 00:34:23.875807 | 
2025-12-11 00:34:23.875950 | TASK [ansible-test : Enable --inventory for network-integration]
2025-12-11 00:34:23.902101 | controller | skipping: Conditional result was False
2025-12-11 00:34:23.914471 | 
2025-12-11 00:34:23.914608 | TASK [ansible-test : Enable --retry-on-error]
2025-12-11 00:34:23.941164 | controller | skipping: Conditional result was False
2025-12-11 00:34:23.950731 | 
2025-12-11 00:34:23.950847 | TASK [ansible-test : Enable --continue-on-error]
2025-12-11 00:34:23.993310 | controller | ok
2025-12-11 00:34:24.001788 | 
2025-12-11 00:34:24.001936 | TASK [ansible-test : Enable --requirements]
2025-12-11 00:34:24.027636 | controller | skipping: Conditional result was False
2025-12-11 00:34:24.035058 | 
2025-12-11 00:34:24.035133 | TASK [ansible-test : Adjust options for unit tests]
2025-12-11 00:34:24.059320 | controller | skipping: Conditional result was False
2025-12-11 00:34:24.068942 | 
2025-12-11 00:34:24.069111 | TASK [ansible-test : Install requirements with unit tests]
2025-12-11 00:34:24.114756 | controller | ok
2025-12-11 00:34:24.157688 | 
2025-12-11 00:34:24.157847 | TASK [ansible-test : Set --skip-test]
2025-12-11 00:34:24.224016 | controller | skipping: Conditional result was False
2025-12-11 00:34:24.232989 | 
2025-12-11 00:34:24.233088 | TASK [ansible-test : Reduce verbosity]
2025-12-11 00:34:24.277392 | controller | ok
2025-12-11 00:34:24.285442 | 
2025-12-11 00:34:24.285532 | TASK [ansible-test : Setup --python option]
2025-12-11 00:34:24.311907 | controller | skipping: Conditional result was False
2025-12-11 00:34:24.318985 | 
2025-12-11 00:34:24.319067 | TASK [ansible-test : Setup --docker option]
2025-12-11 00:34:24.353312 | controller | ok
2025-12-11 00:34:24.363474 | 
2025-12-11 00:34:24.363592 | TASK [ansible-test : check if MANIFEST.json exists]
2025-12-11 00:34:25.485236 | controller | ok
2025-12-11 00:34:25.500244 | 
2025-12-11 00:34:25.500399 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2025-12-11 00:34:25.527479 | controller | skipping: Conditional result was False
2025-12-11 00:34:25.543648 | 
2025-12-11 00:34:25.543914 | TASK [ansible-test : Load information from MANIFEST.json]
2025-12-11 00:34:25.563573 | controller | skipping: Conditional result was False
2025-12-11 00:34:25.585082 | 
2025-12-11 00:34:25.585303 | TASK [ansible-test : Setup location of project for integration tests]
2025-12-11 00:34:25.613347 | controller | skipping: Conditional result was False
2025-12-11 00:34:25.629244 | 
2025-12-11 00:34:25.629398 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2025-12-11 00:34:26.817626 | controller | ok
2025-12-11 00:34:26.825356 | 
2025-12-11 00:34:26.825498 | TASK [ansible-test : Load information from galaxy.yml]
2025-12-11 00:34:26.888681 | controller | ok
2025-12-11 00:34:26.895867 | 
2025-12-11 00:34:26.896018 | TASK [ansible-test : Setup location of project for integration tests]
2025-12-11 00:34:26.929408 | controller | ok
2025-12-11 00:34:26.935444 | 
2025-12-11 00:34:26.935518 | TASK [ansible-test : Setup minimum test requirements]
2025-12-11 00:34:26.966985 | controller | ok
2025-12-11 00:34:26.973423 | 
2025-12-11 00:34:26.973491 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2025-12-11 00:34:28.021537 | controller | ok
2025-12-11 00:34:28.035952 | 
2025-12-11 00:34:28.036105 | TASK [ansible-test : Install python requirements]
2025-12-11 00:34:29.529151 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-12-11 00:34:29.548269 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2025-12-11 00:34:29.579854 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-12-11 00:34:29.585760 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2025-12-11 00:34:29.595599 | 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-11 00:34:29.604078 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2025-12-11 00:34:29.617335 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2025-12-11 00:34:29.668125 | controller | Installing collected packages: pathspec, yamllint
2025-12-11 00:34:29.770314 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2025-12-11 00:34:30.404749 | controller | ok: Runtime: 0:00:01.001441
2025-12-11 00:34:30.421792 | 
2025-12-11 00:34:30.421968 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2025-12-11 00:34:31.319628 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2025-12-11 00:34:31.804794 | controller | ERROR
2025-12-11 00:34:31.805012 | controller | {
2025-12-11 00:34:31.805041 | controller |   "delta": "0:00:00.011715",
2025-12-11 00:34:31.805061 | controller |   "end": "2025-12-11 00:34:31.319932",
2025-12-11 00:34:31.805078 | controller |   "msg": "non-zero return code",
2025-12-11 00:34:31.805094 | controller |   "rc": 1,
2025-12-11 00:34:31.805110 | controller |   "start": "2025-12-11 00:34:31.308217"
2025-12-11 00:34:31.805127 | controller | }
2025-12-11 00:34:31.805149 | controller | ERROR: Ignoring Errors
2025-12-11 00:34:31.812489 | 
2025-12-11 00:34:31.812739 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2025-12-11 00:34:32.167895 | controller | skipping: Conditional result was False
2025-12-11 00:34:32.182049 | 
2025-12-11 00:34:32.182191 | TASK [ansible-test : Enable persistent connection logging]
2025-12-11 00:34:33.534237 | controller | changed: section and option added
2025-12-11 00:34:33.548932 | 
2025-12-11 00:34:33.549087 | TASK [ansible-test : Set persistent ssh_type value]
2025-12-11 00:34:34.722342 | controller | changed: option added
2025-12-11 00:34:34.736509 | 
2025-12-11 00:34:34.736701 | TASK [ansible-test : Set ansible log path]
2025-12-11 00:34:35.840330 | controller | changed: section and option added
2025-12-11 00:34:35.853301 | 
2025-12-11 00:34:35.853438 | TASK [ansible-test : Identify the changed targets]
2025-12-11 00:34:36.231634 | controller | skipping: Conditional result was False
2025-12-11 00:34:36.245783 | 
2025-12-11 00:34:36.246141 | TASK [ansible-test : set_fact]
2025-12-11 00:34:36.294034 | controller | skipping: Conditional result was False
2025-12-11 00:34:36.307794 | 
2025-12-11 00:34:36.307936 | TASK [ansible-test : Set the targets]
2025-12-11 00:34:36.340282 | controller | ok
2025-12-11 00:34:36.345814 | 
2025-12-11 00:34:36.345879 | TASK [ansible-test : set_fact]
2025-12-11 00:34:36.370263 | controller | skipping: Conditional result was False
2025-12-11 00:34:36.407147 | 
2025-12-11 00:34:36.407300 | TASK [ansible-test : List the targets]
2025-12-11 00:34:36.766393 | controller | skipping: Conditional result was False
2025-12-11 00:34:36.783879 | 
2025-12-11 00:34:36.784049 | TASK [ansible-test : set_fact]
2025-12-11 00:34:36.802286 | controller | skipping: Conditional result was False
2025-12-11 00:34:36.818246 | 
2025-12-11 00:34:36.818392 | TASK [ansible-test : set_fact]
2025-12-11 00:34:36.845383 | controller | skipping: Conditional result was False
2025-12-11 00:34:36.857295 | 
2025-12-11 00:34:36.857379 | LOOP [ansible-test : set_fact]
2025-12-11 00:34:36.908742 | 
2025-12-11 00:34:36.908892 | TASK [ansible-test : set_fact]
2025-12-11 00:34:36.923109 | controller | skipping: Conditional result was False
2025-12-11 00:34:36.930410 | 
2025-12-11 00:34:36.930475 | TASK [ansible-test : debug]
2025-12-11 00:34:36.944566 | controller | skipping: Conditional result was False
2025-12-11 00:34:36.951141 | 
2025-12-11 00:34:36.951209 | TASK [ansible-test : debug]
2025-12-11 00:34:36.980241 | About to run: ansible-test sanity --requirements --docker -v
2025-12-11 00:34:36.987284 | 
2025-12-11 00:34:36.987345 | TASK [ansible-test : Run the test suite]
2025-12-11 00:34:38.421415 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2025-12-11 00:34:38.473021 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2025-12-11 00:34:40.072162 | controller | 2.9.0: Pulling from ansible/default-test-container
2025-12-11 00:34:40.072209 | controller | 171857c49d0f: Pulling fs layer
2025-12-11 00:34:40.072232 | controller | 419640447d26: Pulling fs layer
2025-12-11 00:34:40.072240 | controller | 61e52f862619: Pulling fs layer
2025-12-11 00:34:40.072247 | controller | 851de0426e26: Pulling fs layer
2025-12-11 00:34:40.072257 | controller | 37425716ea5b: Pulling fs layer
2025-12-11 00:34:40.072265 | controller | cd9b0c439616: Pulling fs layer
2025-12-11 00:34:40.072272 | controller | d4612dbd8855: Pulling fs layer
2025-12-11 00:34:40.072281 | controller | 554699364730: Pulling fs layer
2025-12-11 00:34:40.072288 | controller | 1ff15d4bdb3e: Pulling fs layer
2025-12-11 00:34:40.072294 | controller | 5f2e4724adea: Pulling fs layer
2025-12-11 00:34:40.072300 | controller | a02d9a95ccb8: Pulling fs layer
2025-12-11 00:34:40.072306 | controller | 989f4ce370cb: Pulling fs layer
2025-12-11 00:34:40.072312 | controller | 0d7f52cdf9df: Pulling fs layer
2025-12-11 00:34:40.072318 | controller | c423742351d5: Pulling fs layer
2025-12-11 00:34:40.072327 | controller | 109236341d21: Pulling fs layer
2025-12-11 00:34:40.072335 | controller | 07a2f61c96b8: Pulling fs layer
2025-12-11 00:34:40.072341 | controller | 041f122f27ae: Pulling fs layer
2025-12-11 00:34:40.072347 | controller | ca6f5098b12e: Pulling fs layer
2025-12-11 00:34:40.072355 | controller | 1eb3e7b2afd9: Pulling fs layer
2025-12-11 00:34:40.072362 | controller | 18a0b750727a: Pulling fs layer
2025-12-11 00:34:40.072368 | controller | 17f2f596716c: Pulling fs layer
2025-12-11 00:34:40.072375 | controller | f35a9998a9bd: Pulling fs layer
2025-12-11 00:34:40.072381 | controller | fdfc10566405: Pulling fs layer
2025-12-11 00:34:40.072388 | controller | 1ff1c442c5fe: Pulling fs layer
2025-12-11 00:34:40.072394 | controller | a3443c4a17ed: Pulling fs layer
2025-12-11 00:34:40.072400 | controller | 0edcf9fb2b15: Pulling fs layer
2025-12-11 00:34:40.072443 | controller | 5af43b69e845: Pulling fs layer
2025-12-11 00:34:40.072451 | controller | 1651773effb2: Pulling fs layer
2025-12-11 00:34:40.072457 | controller | 79e23c5c0fe4: Pulling fs layer
2025-12-11 00:34:40.072469 | controller | e5058b1faaa6: Pulling fs layer
2025-12-11 00:34:40.072479 | controller | 846775490569: Pulling fs layer
2025-12-11 00:34:40.456521 | controller | 851de0426e26: Waiting
2025-12-11 00:34:40.456559 | controller | 37425716ea5b: Waiting
2025-12-11 00:34:40.456567 | controller | cd9b0c439616: Waiting
2025-12-11 00:34:40.456573 | controller | d4612dbd8855: Waiting
2025-12-11 00:34:40.456578 | controller | 554699364730: Waiting
2025-12-11 00:34:40.456584 | controller | 1ff15d4bdb3e: Waiting
2025-12-11 00:34:40.456595 | controller | 5f2e4724adea: Waiting
2025-12-11 00:34:40.456601 | controller | a02d9a95ccb8: Waiting
2025-12-11 00:34:40.456607 | controller | 989f4ce370cb: Waiting
2025-12-11 00:34:40.456613 | controller | 0d7f52cdf9df: Waiting
2025-12-11 00:34:40.456618 | controller | c423742351d5: Waiting
2025-12-11 00:34:40.456624 | controller | 109236341d21: Waiting
2025-12-11 00:34:40.456630 | controller | 07a2f61c96b8: Waiting
2025-12-11 00:34:40.456636 | controller | 041f122f27ae: Waiting
2025-12-11 00:34:40.456641 | controller | ca6f5098b12e: Waiting
2025-12-11 00:34:40.456647 | controller | 1eb3e7b2afd9: Waiting
2025-12-11 00:34:40.456653 | controller | 18a0b750727a: Waiting
2025-12-11 00:34:40.456659 | controller | 17f2f596716c: Waiting
2025-12-11 00:34:40.456667 | controller | f35a9998a9bd: Waiting
2025-12-11 00:34:40.456673 | controller | fdfc10566405: Waiting
2025-12-11 00:34:40.456679 | controller | 1ff1c442c5fe: Waiting
2025-12-11 00:34:40.456685 | controller | a3443c4a17ed: Waiting
2025-12-11 00:34:40.456690 | controller | 0edcf9fb2b15: Waiting
2025-12-11 00:34:40.456696 | controller | 5af43b69e845: Waiting
2025-12-11 00:34:40.456702 | controller | 1651773effb2: Waiting
2025-12-11 00:34:40.456708 | controller | 79e23c5c0fe4: Waiting
2025-12-11 00:34:40.456714 | controller | e5058b1faaa6: Waiting
2025-12-11 00:34:40.456721 | controller | 846775490569: Waiting
2025-12-11 00:34:40.456733 | controller | 419640447d26: Verifying Checksum
2025-12-11 00:34:40.475895 | controller | 419640447d26: Download complete
2025-12-11 00:34:40.475931 | controller | 61e52f862619: Verifying Checksum
2025-12-11 00:34:40.791662 | controller | 61e52f862619: Download complete
2025-12-11 00:34:40.791733 | controller | 171857c49d0f: Verifying Checksum
2025-12-11 00:34:41.359225 | controller | 171857c49d0f: Download complete
2025-12-11 00:34:41.359276 | controller | 851de0426e26: Verifying Checksum
2025-12-11 00:34:41.678214 | controller | 851de0426e26: Download complete
2025-12-11 00:34:41.678267 | controller | cd9b0c439616: Verifying Checksum
2025-12-11 00:34:41.678489 | controller | cd9b0c439616: Download complete
2025-12-11 00:34:42.151672 | controller | d4612dbd8855: Verifying Checksum
2025-12-11 00:34:43.106561 | controller | d4612dbd8855: Download complete
2025-12-11 00:34:43.106615 | controller | 1ff15d4bdb3e: Verifying Checksum
2025-12-11 00:34:43.106750 | controller | 1ff15d4bdb3e: Download complete
2025-12-11 00:34:44.012614 | controller | 5f2e4724adea: Verifying Checksum
2025-12-11 00:34:44.415537 | controller | 5f2e4724adea: Download complete
2025-12-11 00:34:44.415595 | controller | 171857c49d0f: Pull complete
2025-12-11 00:34:44.444422 | controller | 419640447d26: Pull complete
2025-12-11 00:34:44.476148 | controller | 61e52f862619: Pull complete
2025-12-11 00:34:44.526637 | controller | 851de0426e26: Pull complete
2025-12-11 00:34:45.540633 | controller | a02d9a95ccb8: Download complete
2025-12-11 00:34:46.324214 | controller | 989f4ce370cb: Download complete
2025-12-11 00:34:47.262746 | controller | 0d7f52cdf9df: Verifying Checksum
2025-12-11 00:34:47.995727 | controller | 0d7f52cdf9df: Download complete
2025-12-11 00:34:47.998545 | controller | c423742351d5: Verifying Checksum
2025-12-11 00:34:54.846923 | controller | c423742351d5: Download complete
2025-12-11 00:34:54.847011 | controller | 109236341d21: Verifying Checksum
2025-12-11 00:34:55.247046 | controller | 109236341d21: Download complete
2025-12-11 00:34:55.247154 | controller | 554699364730: Verifying Checksum
2025-12-11 00:34:55.680759 | controller | 554699364730: Download complete
2025-12-11 00:34:55.680868 | controller | 07a2f61c96b8: Download complete
2025-12-11 00:34:56.034708 | controller | 041f122f27ae: Verifying Checksum
2025-12-11 00:34:56.034888 | controller | 041f122f27ae: Download complete
2025-12-11 00:34:56.543830 | controller | ca6f5098b12e: Verifying Checksum
2025-12-11 00:34:56.543912 | controller | ca6f5098b12e: Download complete
2025-12-11 00:34:56.846622 | controller | 1eb3e7b2afd9: Verifying Checksum
2025-12-11 00:34:57.599179 | controller | 1eb3e7b2afd9: Download complete
2025-12-11 00:34:57.599280 | controller | 18a0b750727a: Verifying Checksum
2025-12-11 00:34:57.737175 | controller | 18a0b750727a: Download complete
2025-12-11 00:34:57.737308 | controller | 17f2f596716c: Verifying Checksum
2025-12-11 00:34:58.541110 | controller | 17f2f596716c: Download complete
2025-12-11 00:34:58.541208 | controller | f35a9998a9bd: Verifying Checksum
2025-12-11 00:34:58.574195 | controller | f35a9998a9bd: Download complete
2025-12-11 00:34:58.574286 | controller | fdfc10566405: Verifying Checksum
2025-12-11 00:34:59.232876 | controller | fdfc10566405: Download complete
2025-12-11 00:34:59.233039 | controller | 1ff1c442c5fe: Verifying Checksum
2025-12-11 00:35:02.424615 | controller | 1ff1c442c5fe: Download complete
2025-12-11 00:35:02.424719 | controller | a3443c4a17ed: Verifying Checksum
2025-12-11 00:35:04.515340 | controller | a3443c4a17ed: Download complete
2025-12-11 00:35:04.515445 | controller | 5af43b69e845: Verifying Checksum
2025-12-11 00:35:04.920438 | controller | 5af43b69e845: Download complete
2025-12-11 00:35:04.920534 | controller | 0edcf9fb2b15: Verifying Checksum
2025-12-11 00:35:05.778980 | controller | 0edcf9fb2b15: Download complete
2025-12-11 00:35:05.779091 | controller | 79e23c5c0fe4: Verifying Checksum
2025-12-11 00:35:07.270855 | controller | 79e23c5c0fe4: Download complete
2025-12-11 00:35:07.270998 | controller | 1651773effb2: Verifying Checksum
2025-12-11 00:35:08.582416 | controller | 1651773effb2: Download complete
2025-12-11 00:35:08.582526 | controller | e5058b1faaa6: Verifying Checksum
2025-12-11 00:35:11.549800 | controller | e5058b1faaa6: Download complete
2025-12-11 00:35:11.549905 | controller | 37425716ea5b: Verifying Checksum
2025-12-11 00:35:14.714058 | controller | 37425716ea5b: Download complete
2025-12-11 00:35:14.714175 | controller | 846775490569: Verifying Checksum
2025-12-11 00:35:14.714220 | controller | 846775490569: Download complete
2025-12-11 00:35:31.947073 | controller | 37425716ea5b: Pull complete
2025-12-11 00:35:31.984337 | controller | cd9b0c439616: Pull complete
2025-12-11 00:35:32.016451 | controller | d4612dbd8855: Pull complete
2025-12-11 00:35:38.256560 | controller | 554699364730: Pull complete
2025-12-11 00:35:38.291460 | controller | 1ff15d4bdb3e: Pull complete
2025-12-11 00:35:38.328935 | controller | 5f2e4724adea: Pull complete
2025-12-11 00:35:38.384343 | controller | a02d9a95ccb8: Pull complete
2025-12-11 00:35:38.425672 | controller | 989f4ce370cb: Pull complete
2025-12-11 00:35:38.454164 | controller | 0d7f52cdf9df: Pull complete
2025-12-11 00:35:38.489316 | controller | c423742351d5: Pull complete
2025-12-11 00:35:41.555742 | controller | 109236341d21: Pull complete
2025-12-11 00:35:41.582625 | controller | 07a2f61c96b8: Pull complete
2025-12-11 00:35:41.602111 | controller | 041f122f27ae: Pull complete
2025-12-11 00:35:41.757999 | controller | ca6f5098b12e: Pull complete
2025-12-11 00:35:41.825292 | controller | 1eb3e7b2afd9: Pull complete
2025-12-11 00:35:41.878395 | controller | 18a0b750727a: Pull complete
2025-12-11 00:35:41.903117 | controller | 17f2f596716c: Pull complete
2025-12-11 00:35:42.042747 | controller | f35a9998a9bd: Pull complete
2025-12-11 00:35:42.083336 | controller | fdfc10566405: Pull complete
2025-12-11 00:35:42.124938 | controller | 1ff1c442c5fe: Pull complete
2025-12-11 00:35:44.424282 | controller | a3443c4a17ed: Pull complete
2025-12-11 00:35:46.747398 | controller | 0edcf9fb2b15: Pull complete
2025-12-11 00:35:49.977956 | controller | 5af43b69e845: Pull complete
2025-12-11 00:35:53.336575 | controller | 1651773effb2: Pull complete
2025-12-11 00:35:56.702627 | controller | 79e23c5c0fe4: Pull complete
2025-12-11 00:35:59.043136 | controller | e5058b1faaa6: Pull complete
2025-12-11 00:36:02.475432 | controller | 846775490569: Pull complete
2025-12-11 00:36:02.505526 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2025-12-11 00:36:02.509624 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2025-12-11 00:36:02.519821 | controller | quay.io/ansible/default-test-container:2.9.0
2025-12-11 00:36:02.524588 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2025-12-11 00:36:02.558055 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2025-12-11 00:36:04.132439 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2025-12-11 00:36:04.135857 | controller | 04a5f4cda3ee: Pulling fs layer
2025-12-11 00:36:04.137430 | controller | ff496a88c8ed: Pulling fs layer
2025-12-11 00:36:04.137453 | controller | 0ce83f459fe7: Pulling fs layer
2025-12-11 00:36:04.137460 | controller | 2e5170e1f099: Pulling fs layer
2025-12-11 00:36:04.137466 | controller | 7641eb41b08c: Pulling fs layer
2025-12-11 00:36:04.137472 | controller | ad15fa9da398: Pulling fs layer
2025-12-11 00:36:04.137478 | controller | 087d91352424: Pulling fs layer
2025-12-11 00:36:04.137484 | controller | 8b92efd6a100: Pulling fs layer
2025-12-11 00:36:04.137497 | controller | 7641eb41b08c: Waiting
2025-12-11 00:36:04.137576 | controller | ad15fa9da398: Waiting
2025-12-11 00:36:04.137626 | controller | 087d91352424: Waiting
2025-12-11 00:36:04.137668 | controller | 8b92efd6a100: Waiting
2025-12-11 00:36:04.137785 | controller | 2e5170e1f099: Waiting
2025-12-11 00:36:04.922428 | controller | 0ce83f459fe7: Verifying Checksum
2025-12-11 00:36:04.979718 | controller | 0ce83f459fe7: Download complete
2025-12-11 00:36:04.979812 | controller | ff496a88c8ed: Verifying Checksum
2025-12-11 00:36:06.042401 | controller | ff496a88c8ed: Download complete
2025-12-11 00:36:06.042497 | controller | 04a5f4cda3ee: Download complete
2025-12-11 00:36:06.125870 | controller | 7641eb41b08c: Verifying Checksum
2025-12-11 00:36:06.635738 | controller | 7641eb41b08c: Download complete
2025-12-11 00:36:06.635834 | controller | 2e5170e1f099: Verifying Checksum
2025-12-11 00:36:07.215248 | controller | 2e5170e1f099: Download complete
2025-12-11 00:36:07.215342 | controller | ad15fa9da398: Verifying Checksum
2025-12-11 00:36:07.699378 | controller | ad15fa9da398: Download complete
2025-12-11 00:36:07.699447 | controller | 8b92efd6a100: Verifying Checksum
2025-12-11 00:36:08.430395 | controller | 8b92efd6a100: Download complete
2025-12-11 00:36:08.430461 | controller | 087d91352424: Verifying Checksum
2025-12-11 00:36:09.292517 | controller | 087d91352424: Download complete
2025-12-11 00:36:09.292595 | controller | 04a5f4cda3ee: Pull complete
2025-12-11 00:36:09.321341 | controller | ff496a88c8ed: Pull complete
2025-12-11 00:36:09.340571 | controller | 0ce83f459fe7: Pull complete
2025-12-11 00:36:11.307095 | controller | 2e5170e1f099: Pull complete
2025-12-11 00:36:11.329392 | controller | 7641eb41b08c: Pull complete
2025-12-11 00:36:11.353613 | controller | ad15fa9da398: Pull complete
2025-12-11 00:36:12.616031 | controller | 087d91352424: Pull complete
2025-12-11 00:36:12.647614 | controller | 8b92efd6a100: Pull complete
2025-12-11 00:36:12.668029 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2025-12-11 00:36:12.672639 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2025-12-11 00:36:12.682461 | controller | quay.io/ansible/pypi-test-container:1.0.0
2025-12-11 00:36:12.686015 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2025-12-11 00:36:13.232306 | controller | Run command: docker inspect 210ced27f50d4f7f8835d20d5191942bea33be6a6878c7940c6332a1a38e2ada
2025-12-11 00:36:13.294806 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2025-12-11 00:36:13.308250 | controller | Including collection: ansible.netcommon (292 files)
2025-12-11 00:36:13.308331 | controller | Including collection: ansible.network (23 files)
2025-12-11 00:36:13.322222 | controller | Including collection: ansible.utils (442 files)
2025-12-11 00:36:13.363035 | controller | Including collection: arista.eos (1022 files)
2025-12-11 00:36:13.428634 | controller | Including collection: cisco.ios (1172 files)
2025-12-11 00:36:13.480073 | controller | Including collection: cisco.iosxr (1043 files)
2025-12-11 00:36:13.561700 | controller | Including collection: cisco.nxos (1684 files)
2025-12-11 00:36:13.609413 | controller | Including collection: junipernetworks.junos (963 files)
2025-12-11 00:36:13.653616 | controller | Creating a payload archive containing 7367 files...
2025-12-11 00:36:15.848275 | controller | Created a 5982357 byte payload archive containing 7367 files in 2 seconds.
2025-12-11 00:36:15.852522 | controller | Assuming Docker is available on localhost.
2025-12-11 00:36:15.853101 | 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-11 00:36:16.368026 | controller | Run command: docker exec -i 9faf446d37a83ed9710ed077d2d8b5f18784e0038e02c04b52f25960bc7fe209 dd of=/root/docker.sh bs=65536
2025-12-11 00:36:16.456580 | controller | Run command: docker exec 9faf446d37a83ed9710ed077d2d8b5f18784e0038e02c04b52f25960bc7fe209 /bin/bash /root/docker.sh
2025-12-11 00:36:16.556747 | controller | Run command: docker exec -i 9faf446d37a83ed9710ed077d2d8b5f18784e0038e02c04b52f25960bc7fe209 dd of=/root/test.tgz bs=65536
2025-12-11 00:36:16.680833 | controller | Run command: docker exec 9faf446d37a83ed9710ed077d2d8b5f18784e0038e02c04b52f25960bc7fe209 tar oxzf /root/test.tgz -C /root
2025-12-11 00:36:18.410269 | controller | Run command: docker exec 9faf446d37a83ed9710ed077d2d8b5f18784e0038e02c04b52f25960bc7fe209 /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-wkcv0xya.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2025-12-11 00:36:18.862501 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2025-12-11 00:36:18.862691 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2025-12-11 00:36:18.862707 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2025-12-11 00:36:18.866089 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2025-12-11 00:36:18.867176 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2025-12-11 00:36:18.874147 | controller | No tests applicable.
2025-12-11 00:36:18.874249 | controller | Running sanity test 'ansible-doc' with Python 3.6
2025-12-11 00:36:18.874470 | controller | No tests applicable.
2025-12-11 00:36:18.874640 | controller | Running sanity test 'changelog' with Python 3.6
2025-12-11 00:36:18.879097 | 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-11 00:36:19.860980 | 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-11 00:36:20.468105 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2025-12-11 00:36:35.496177 | controller |   WARNING: Retrying (Retry(total=4, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-12-11 00:36:51.023492 | controller |   WARNING: Retrying (Retry(total=3, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-12-11 00:37:07.047633 | controller |   WARNING: Retrying (Retry(total=2, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-12-11 00:37:24.075249 | controller |   WARNING: Retrying (Retry(total=1, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-12-11 00:37:43.097554 | controller |   WARNING: Retrying (Retry(total=0, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-12-11 00:37:58.121433 | 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-11 00:37:58.121840 | 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-11 00:37:58.245570 | 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-11 00:37:58.245633 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2025-12-11 00:37:58.292062 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2025-12-11 00:37:58.292118 | controller | Removing custom PyPI hosts entries: /etc/hosts
2025-12-11 00:37:58.292136 | controller | Run command: docker exec 9faf446d37a83ed9710ed077d2d8b5f18784e0038e02c04b52f25960bc7fe209 tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2025-12-11 00:37:58.377229 | controller | Run command: docker exec -i 9faf446d37a83ed9710ed077d2d8b5f18784e0038e02c04b52f25960bc7fe209 dd if=/root/output.tgz bs=65536
2025-12-11 00:37:58.450656 | controller | Run command: tar oxzf /tmp/ansible-result-o1oo38xl.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2025-12-11 00:37:58.469154 | controller | Run command: docker rm -f 210ced27f50d4f7f8835d20d5191942bea33be6a6878c7940c6332a1a38e2ada
2025-12-11 00:37:58.747130 | controller | Run command: docker rm -f 9faf446d37a83ed9710ed077d2d8b5f18784e0038e02c04b52f25960bc7fe209
2025-12-11 00:37:59.495195 | controller | ERROR: Command "docker exec 9faf446d37a83ed9710ed077d2d8b5f18784e0038e02c04b52f25960bc7fe209 /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-wkcv0xya.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2025-12-11 00:38:00.116059 | controller | ERROR
2025-12-11 00:38:00.116334 | controller | {
2025-12-11 00:38:00.116400 | controller |   "delta": "0:03:21.378957",
2025-12-11 00:38:00.116496 | controller |   "end": "2025-12-11 00:37:59.555668",
2025-12-11 00:38:00.116561 | controller |   "msg": "non-zero return code",
2025-12-11 00:38:00.116602 | controller |   "rc": 1,
2025-12-11 00:38:00.116640 | controller |   "start": "2025-12-11 00:34:38.176711"
2025-12-11 00:38:00.116768 | controller | }
failure
2025-12-11 00:38:00.123850 | 
2025-12-11 00:38:00.123959 | PLAY RECAP
2025-12-11 00:38:00.124200 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2025-12-11 00:38:00.124274 | 
2025-12-11 00:38:00.255953 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-12-11 00:38:00.257227 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-11 00:38:00.849731 | 
2025-12-11 00:38:00.849858 | PLAY [all]
2025-12-11 00:38:00.873688 | 
2025-12-11 00:38:00.873823 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-12-11 00:38:03.915658 | controller | changed: non-zero return code
2025-12-11 00:38:03.931100 | 
2025-12-11 00:38:03.931310 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-12-11 00:38:03.948545 | controller | skipping: Conditional result was False
2025-12-11 00:38:03.955590 | 
2025-12-11 00:38:03.955715 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-12-11 00:38:03.986633 | 
2025-12-11 00:38:03.986807 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-12-11 00:38:04.017357 | 
2025-12-11 00:38:04.017511 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-12-11 00:38:04.041306 | controller | skipping: Conditional result was False
2025-12-11 00:38:04.050248 | 
2025-12-11 00:38:04.050344 | LOOP [fetch-subunit-output : Generate subunit file]
2025-12-11 00:38:04.084576 | 
2025-12-11 00:38:04.084796 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-12-11 00:38:04.109434 | controller | skipping: Conditional result was False
2025-12-11 00:38:04.119831 | 
2025-12-11 00:38:04.119950 | TASK [fetch-subunit-output : Remove the temporary file]
2025-12-11 00:38:04.145111 | controller | skipping: Conditional result was False
2025-12-11 00:38:04.153660 | 
2025-12-11 00:38:04.153774 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-12-11 00:38:04.180903 | controller | skipping: Conditional result was False
2025-12-11 00:38:04.221969 | 
2025-12-11 00:38:04.222051 | PLAY RECAP
2025-12-11 00:38:04.222104 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-11 00:38:04.222131 | 
2025-12-11 00:38:04.322659 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-11 00:38:04.323634 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-12-11 00:38:04.896157 | 
2025-12-11 00:38:04.896273 | PLAY [all:!appliance*]
2025-12-11 00:38:04.917398 | 
2025-12-11 00:38:04.917484 | TASK [unregister the node]
2025-12-11 00:38:05.281271 | controller | skipping: Conditional result was False
2025-12-11 00:38:05.294819 | 
2025-12-11 00:38:05.295034 | TASK [include_role : fetch-output]
2025-12-11 00:38:05.345763 | controller | ok
2025-12-11 00:38:05.383418 | 
2025-12-11 00:38:05.383620 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-11 00:38:05.449471 | controller | skipping: Conditional result was False
2025-12-11 00:38:05.458701 | 
2025-12-11 00:38:05.458809 | TASK [fetch-output : Set log path for single node]
2025-12-11 00:38:05.501933 | controller | ok
2025-12-11 00:38:05.510043 | 
2025-12-11 00:38:05.510136 | LOOP [fetch-output : Ensure local output dirs]
2025-12-11 00:38:05.927920 | controller -> localhost | ok: "/var/lib/zuul/builds/c070bc2f1d8244da8e0e4747fd381e6c/work/logs"
2025-12-11 00:38:06.209492 | controller -> localhost | changed: "/var/lib/zuul/builds/c070bc2f1d8244da8e0e4747fd381e6c/work/artifacts"
2025-12-11 00:38:06.459391 | controller -> localhost | changed: "/var/lib/zuul/builds/c070bc2f1d8244da8e0e4747fd381e6c/work/docs"
2025-12-11 00:38:06.483136 | 
2025-12-11 00:38:06.483324 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-11 00:38:08.752000 | controller | changed:
2025-12-11 00:38:08.752411 | controller | .d..t...... ./
2025-12-11 00:38:08.752459 | controller | cd+++++++++ controller/
2025-12-11 00:38:08.752505 | controller | changed: All items complete
2025-12-11 00:38:08.752543 | 
2025-12-11 00:38:10.841596 | controller | changed: .d..t...... ./
2025-12-11 00:38:12.917761 | controller | changed: .d..t...... ./
2025-12-11 00:38:12.956358 | 
2025-12-11 00:38:12.956580 | TASK [include_role : fetch-output-openshift]
2025-12-11 00:38:12.983142 | controller | skipping: Conditional result was False
2025-12-11 00:38:12.993712 | 
2025-12-11 00:38:12.993810 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-11 00:38:13.042120 | controller | skipping: Conditional result was False
2025-12-11 00:38:13.055740 | controller | skipping: Conditional result was False
2025-12-11 00:38:13.100339 | 
2025-12-11 00:38:13.100475 | PLAY [localhost]
2025-12-11 00:38:13.119469 | 
2025-12-11 00:38:13.119599 | TASK [Run Zuul manifest role]
2025-12-11 00:38:13.140184 | localhost | ok
2025-12-11 00:38:13.159520 | 
2025-12-11 00:38:13.159729 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-11 00:38:13.535549 | localhost | changed
2025-12-11 00:38:13.540390 | 
2025-12-11 00:38:13.540466 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-11 00:38:13.577867 | localhost | ok
2025-12-11 00:38:13.586880 | 
2025-12-11 00:38:13.586944 | TASK [Set zuul-log-path fact]
2025-12-11 00:38:13.631136 | localhost | ok
2025-12-11 00:38:13.644644 | 
2025-12-11 00:38:13.644733 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-11 00:38:13.673031 | localhost | ok
2025-12-11 00:38:13.681842 | 
2025-12-11 00:38:13.681924 | LOOP [Run upload-logs-swift role]
2025-12-11 00:38:13.729305 | localhost | Output suppressed because no_log was given
2025-12-11 00:38:13.765608 | 
2025-12-11 00:38:13.765766 | TASK [Set zuul-log-path fact]
2025-12-11 00:38:13.800956 | localhost | skipping: Conditional result was False
2025-12-11 00:38:13.808630 | 
2025-12-11 00:38:13.808754 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-12-11 00:38:14.220521 | localhost -> localhost | ok: Runtime: 0:00:00.009029
2025-12-11 00:38:14.230073 | 
2025-12-11 00:38:14.230176 | TASK [upload-logs-swift : Upload logs to swift]