2026-03-13 00:07:00.683962 | Job console starting...
2026-03-13 00:07:00.694613 | Updating repositories
2026-03-13 00:07:00.835158 | Preparing job workspace
2026-03-13 00:07:05.122343 | Running Ansible setup...
2026-03-13 00:07:12.919613 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-03-13 00:07:13.629118 | 
2026-03-13 00:07:13.629312 | PLAY [localhost]
2026-03-13 00:07:13.643355 | 
2026-03-13 00:07:13.643510 | TASK [Gathering Facts]
2026-03-13 00:07:14.666109 | localhost | ok
2026-03-13 00:07:14.681171 | 
2026-03-13 00:07:14.681297 | TASK [Setup log path fact]
2026-03-13 00:07:14.699845 | localhost | ok
2026-03-13 00:07:14.716517 | 
2026-03-13 00:07:14.716633 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-13 00:07:14.745110 | localhost | ok
2026-03-13 00:07:14.753231 | 
2026-03-13 00:07:14.753312 | TASK [emit-job-header : Print job information]
2026-03-13 00:07:14.803233 | # Job Information
2026-03-13 00:07:14.803409 | Ansible Version: 2.15.12
2026-03-13 00:07:14.803449 | Job: ansible-test-sanity-docker-stable-2.10
2026-03-13 00:07:14.803471 | Pipeline: periodic
2026-03-13 00:07:14.803490 | Executor: ze01.softwarefactory-project.io
2026-03-13 00:07:14.803509 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-03-13 00:07:14.803530 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/d84/ansible/d84b3746c1164878bbbbaac1f5e2c704/
2026-03-13 00:07:14.803551 | Event ID: 650d3710832e42c2aa0cb22d47460854
2026-03-13 00:07:14.807877 | 
2026-03-13 00:07:14.807963 | LOOP [emit-job-header : Print node information]
2026-03-13 00:07:14.918227 | localhost | ok:
2026-03-13 00:07:14.918469 | localhost | # Node Information
2026-03-13 00:07:14.918514 | localhost | Inventory Hostname: controller
2026-03-13 00:07:14.918547 | localhost | Hostname: np0005645842
2026-03-13 00:07:14.918577 | localhost | Username: zuul
2026-03-13 00:07:14.918621 | localhost | Distro: Fedora 37
2026-03-13 00:07:14.918656 | localhost | Provider: ansible-vexxhost-ams1
2026-03-13 00:07:14.918686 | localhost | Region: ams1
2026-03-13 00:07:14.918715 | localhost | Label: ansible-fedora-37-1vcpu
2026-03-13 00:07:14.918744 | localhost | Product Name: OpenStack Nova
2026-03-13 00:07:14.918773 | localhost | Interface IP: 38.129.16.220
2026-03-13 00:07:14.932284 | 
2026-03-13 00:07:14.932403 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-13 00:07:15.312669 | localhost -> localhost | changed
2026-03-13 00:07:15.318890 | 
2026-03-13 00:07:15.319148 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-13 00:07:16.346501 | localhost -> localhost | changed
2026-03-13 00:07:16.368658 | 
2026-03-13 00:07:16.368750 | PLAY [all:!appliance*]
2026-03-13 00:07:16.388684 | 
2026-03-13 00:07:16.388806 | TASK [include_role : start-zuul-console]
2026-03-13 00:07:16.408863 | controller | ok
2026-03-13 00:07:16.425761 | 
2026-03-13 00:07:16.425880 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-03-13 00:07:17.514332 | controller | ok
2026-03-13 00:07:17.533474 | 
2026-03-13 00:07:17.533602 | TASK [use-our-mirror : Retrieve the IP address]
2026-03-13 00:07:20.031661 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-03-13 00:07:20.046856 | 
2026-03-13 00:07:20.047009 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-03-13 00:07:20.433709 | controller | skipping: Conditional result was False
2026-03-13 00:07:20.443979 | 
2026-03-13 00:07:20.444139 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-03-13 00:07:20.470239 | controller | skipping: Conditional result was False
2026-03-13 00:07:20.486904 | 
2026-03-13 00:07:20.487281 | TASK [use-our-mirror : Create the podman configuration directory]
2026-03-13 00:07:20.535105 | controller | skipping: Conditional result was False
2026-03-13 00:07:20.543001 | 
2026-03-13 00:07:20.543188 | TASK [use-our-mirror : Copy the podman configuration]
2026-03-13 00:07:20.567531 | controller | skipping: Conditional result was False
2026-03-13 00:07:20.592497 | 
2026-03-13 00:07:20.593425 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-03-13 00:07:20.629195 | controller | skipping: Conditional result was False
2026-03-13 00:07:20.636360 | 
2026-03-13 00:07:20.636474 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-03-13 00:07:20.704315 | controller | skipping: Conditional result was False
2026-03-13 00:07:20.720352 | 
2026-03-13 00:07:20.720476 | TASK [Disable Fedora Modular]
2026-03-13 00:07:21.890839 | controller | changed
2026-03-13 00:07:21.898924 | 
2026-03-13 00:07:21.899098 | TASK [Enable EPEL]
2026-03-13 00:07:21.924977 | controller | skipping: Conditional result was False
2026-03-13 00:07:21.934580 | 
2026-03-13 00:07:21.934692 | TASK [Register the RHEL node]
2026-03-13 00:07:22.369563 | 
2026-03-13 00:07:22.369879 | TASK [Show the subscription-manager status]
2026-03-13 00:07:22.779856 | controller | skipping: Conditional result was False
2026-03-13 00:07:22.789597 | 
2026-03-13 00:07:22.789712 | TASK [Enable EPEL on RHEL]
2026-03-13 00:07:23.188590 | controller | skipping: Conditional result was False
2026-03-13 00:07:23.196168 | 
2026-03-13 00:07:23.196271 | TASK [Install git and tox]
2026-03-13 00:09:25.485927 | controller | changed
2026-03-13 00:09:25.502960 | 
2026-03-13 00:09:25.503176 | TASK [include_role : prepare-workspace]
2026-03-13 00:09:25.542250 | controller | ok
2026-03-13 00:09:25.581832 | 
2026-03-13 00:09:25.581976 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-13 00:09:26.461206 | controller | ok
2026-03-13 00:09:26.477252 | 
2026-03-13 00:09:26.477430 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-13 00:09:44.460654 | controller | Output suppressed because no_log was given
2026-03-13 00:09:44.477666 | 
2026-03-13 00:09:44.477785 | TASK [include_role : prepare-workspace-openshift]
2026-03-13 00:09:44.502402 | controller | skipping: Conditional result was False
2026-03-13 00:09:44.525294 | 
2026-03-13 00:09:44.525369 | PLAY [all:!appliance]
2026-03-13 00:09:44.568675 | 
2026-03-13 00:09:44.568791 | TASK [Run add-build-sshkey role (RSA)]
2026-03-13 00:09:44.599137 | controller | ok
2026-03-13 00:09:44.615663 | 
2026-03-13 00:09:44.615753 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-13 00:09:44.875384 | controller -> localhost | ok
2026-03-13 00:09:44.882731 | 
2026-03-13 00:09:44.882845 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-13 00:09:44.914326 | controller | ok
2026-03-13 00:09:44.933051 | controller | included: /var/lib/zuul/builds/d84b3746c1164878bbbbaac1f5e2c704/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-13 00:09:44.941384 | 
2026-03-13 00:09:44.941493 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-13 00:09:45.375610 | controller -> localhost | Generating public/private rsa key pair.
2026-03-13 00:09:45.375908 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/d84b3746c1164878bbbbaac1f5e2c704/work/d84b3746c1164878bbbbaac1f5e2c704_id_rsa.
2026-03-13 00:09:45.375955 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/d84b3746c1164878bbbbaac1f5e2c704/work/d84b3746c1164878bbbbaac1f5e2c704_id_rsa.pub.
2026-03-13 00:09:45.375993 | controller -> localhost | The key fingerprint is:
2026-03-13 00:09:45.376046 | controller -> localhost | SHA256:9Nz3ndLMFmSYKw2sCXcKU7j/QeUkA+395sLIStrA4JI zuul-build-sshkey
2026-03-13 00:09:45.376084 | controller -> localhost | The key's randomart image is:
2026-03-13 00:09:45.376113 | controller -> localhost | +---[RSA 2048]----+
2026-03-13 00:09:45.376144 | controller -> localhost | |        .oo      |
2026-03-13 00:09:45.376172 | controller -> localhost | |       .. .+ oo  |
2026-03-13 00:09:45.376198 | controller -> localhost | |       +o..+Bo o |
2026-03-13 00:09:45.376224 | controller -> localhost | |       o=o=+oo+  |
2026-03-13 00:09:45.376250 | controller -> localhost | |     .  S++..oo. |
2026-03-13 00:09:45.376290 | controller -> localhost | |    o o  . ...+++|
2026-03-13 00:09:45.376322 | controller -> localhost | |   E . o .o +.o*o|
2026-03-13 00:09:45.376353 | controller -> localhost | |    .   =  + oo. |
2026-03-13 00:09:45.376380 | controller -> localhost | |       . o.   .  |
2026-03-13 00:09:45.376410 | controller -> localhost | +----[SHA256]-----+
2026-03-13 00:09:45.376489 | controller -> localhost | ok: Runtime: 0:00:00.043833
2026-03-13 00:09:45.393141 | 
2026-03-13 00:09:45.393263 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-13 00:09:45.416533 | controller | ok
2026-03-13 00:09:45.435139 | controller | included: /var/lib/zuul/builds/d84b3746c1164878bbbbaac1f5e2c704/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-13 00:09:45.446077 | 
2026-03-13 00:09:45.446215 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-13 00:09:45.461872 | controller | skipping: Conditional result was False
2026-03-13 00:09:45.469903 | 
2026-03-13 00:09:45.470010 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-13 00:09:46.586311 | controller | changed
2026-03-13 00:09:46.601835 | 
2026-03-13 00:09:46.601996 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-13 00:09:47.258898 | controller | ok
2026-03-13 00:09:47.271638 | 
2026-03-13 00:09:47.271781 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-13 00:09:50.373669 | controller | changed
2026-03-13 00:09:50.386844 | 
2026-03-13 00:09:50.386982 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-13 00:09:53.479084 | controller | changed
2026-03-13 00:09:53.493463 | 
2026-03-13 00:09:53.493853 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-13 00:09:53.522881 | controller | skipping: Conditional result was False
2026-03-13 00:09:53.538346 | 
2026-03-13 00:09:53.538534 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-13 00:09:54.026125 | controller -> localhost | changed
2026-03-13 00:09:54.037996 | 
2026-03-13 00:09:54.038162 | TASK [add-build-sshkey : Add back temp key]
2026-03-13 00:09:54.379147 | controller -> localhost | Identity added: /var/lib/zuul/builds/d84b3746c1164878bbbbaac1f5e2c704/work/d84b3746c1164878bbbbaac1f5e2c704_id_rsa (zuul-build-sshkey)
2026-03-13 00:09:54.379517 | controller -> localhost | ok: Runtime: 0:00:00.011043
2026-03-13 00:09:54.389897 | 
2026-03-13 00:09:54.390042 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-13 00:09:55.378343 | controller | ok
2026-03-13 00:09:55.391408 | 
2026-03-13 00:09:55.391629 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-13 00:09:55.430358 | controller | skipping: Conditional result was False
2026-03-13 00:09:55.447751 | 
2026-03-13 00:09:55.447929 | TASK [Run add-build-sshkey role (ECDSA)]
2026-03-13 00:09:55.482337 | controller | ok
2026-03-13 00:09:55.506373 | 
2026-03-13 00:09:55.506507 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-13 00:09:55.776148 | controller -> localhost | ok
2026-03-13 00:09:55.785997 | 
2026-03-13 00:09:55.786151 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-13 00:09:55.821899 | controller | ok
2026-03-13 00:09:55.841352 | controller | included: /var/lib/zuul/builds/d84b3746c1164878bbbbaac1f5e2c704/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-13 00:09:55.852123 | 
2026-03-13 00:09:55.852262 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-13 00:09:56.195858 | controller -> localhost | Generating public/private ecdsa key pair.
2026-03-13 00:09:56.196087 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/d84b3746c1164878bbbbaac1f5e2c704/work/d84b3746c1164878bbbbaac1f5e2c704_id_ecdsa.
2026-03-13 00:09:56.196120 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/d84b3746c1164878bbbbaac1f5e2c704/work/d84b3746c1164878bbbbaac1f5e2c704_id_ecdsa.pub.
2026-03-13 00:09:56.196155 | controller -> localhost | The key fingerprint is:
2026-03-13 00:09:56.196177 | controller -> localhost | SHA256:fPFoeaJE3fLyoxj1psfFOu37a5Oa6InPWGAXpDZT0Rg zuul-build-sshkey
2026-03-13 00:09:56.196196 | controller -> localhost | The key's randomart image is:
2026-03-13 00:09:56.196214 | controller -> localhost | +---[ECDSA 521]---+
2026-03-13 00:09:56.196232 | controller -> localhost | |           E=    |
2026-03-13 00:09:56.196249 | controller -> localhost | |         .+o .   |
2026-03-13 00:09:56.196267 | controller -> localhost | |        .=+..    |
2026-03-13 00:09:56.196284 | controller -> localhost | |       o. oB.    |
2026-03-13 00:09:56.196300 | controller -> localhost | |        SoO.+.   |
2026-03-13 00:09:56.196318 | controller -> localhost | |       ..=o*  o  |
2026-03-13 00:09:56.196335 | controller -> localhost | |        o  o=+  .|
2026-03-13 00:09:56.196351 | controller -> localhost | |         o=+B.o+ |
2026-03-13 00:09:56.196368 | controller -> localhost | |        .o=B ==++|
2026-03-13 00:09:56.196385 | controller -> localhost | +----[SHA256]-----+
2026-03-13 00:09:56.196434 | controller -> localhost | ok: Runtime: 0:00:00.008525
2026-03-13 00:09:56.203262 | 
2026-03-13 00:09:56.203371 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-13 00:09:56.234370 | controller | ok
2026-03-13 00:09:56.243005 | controller | included: /var/lib/zuul/builds/d84b3746c1164878bbbbaac1f5e2c704/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-13 00:09:56.253719 | 
2026-03-13 00:09:56.253853 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-13 00:09:56.278906 | controller | skipping: Conditional result was False
2026-03-13 00:09:56.293595 | 
2026-03-13 00:09:56.293775 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-13 00:09:57.213150 | controller | changed
2026-03-13 00:09:57.219063 | 
2026-03-13 00:09:57.219148 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-13 00:09:57.887888 | controller | ok
2026-03-13 00:09:57.895010 | 
2026-03-13 00:09:57.895108 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-13 00:10:01.032470 | controller | changed
2026-03-13 00:10:01.041097 | 
2026-03-13 00:10:01.041323 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-13 00:10:04.139704 | controller | changed
2026-03-13 00:10:04.147556 | 
2026-03-13 00:10:04.147672 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-13 00:10:04.163796 | controller | skipping: Conditional result was False
2026-03-13 00:10:04.171924 | 
2026-03-13 00:10:04.172011 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-13 00:10:04.458977 | controller -> localhost | changed
2026-03-13 00:10:04.476574 | 
2026-03-13 00:10:04.476706 | TASK [add-build-sshkey : Add back temp key]
2026-03-13 00:10:04.767135 | controller -> localhost | Identity added: /var/lib/zuul/builds/d84b3746c1164878bbbbaac1f5e2c704/work/d84b3746c1164878bbbbaac1f5e2c704_id_ecdsa (zuul-build-sshkey)
2026-03-13 00:10:04.767629 | controller -> localhost | ok: Runtime: 0:00:00.013811
2026-03-13 00:10:04.787640 | 
2026-03-13 00:10:04.787831 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-13 00:10:05.528553 | controller | ok
2026-03-13 00:10:05.536935 | 
2026-03-13 00:10:05.537075 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-13 00:10:05.572706 | controller | skipping: Conditional result was False
2026-03-13 00:10:05.602185 | 
2026-03-13 00:10:05.602334 | TASK [include_role : remove-zuul-sshkey]
2026-03-13 00:10:05.639209 | controller | skipping: Conditional result was False
2026-03-13 00:10:05.651884 | 
2026-03-13 00:10:05.652076 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-13 00:10:06.336001 | controller | ok: "logs"
2026-03-13 00:10:06.336696 | controller | ok: All items complete
2026-03-13 00:10:06.336786 | 
2026-03-13 00:10:06.974426 | controller | ok: "artifacts"
2026-03-13 00:10:07.606620 | controller | ok: "docs"
2026-03-13 00:10:07.624839 | 
2026-03-13 00:10:07.625127 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-13 00:10:08.305785 | controller | changed: "logs"
2026-03-13 00:10:08.952302 | controller | changed: "artifacts"
2026-03-13 00:10:09.595201 | controller | changed: "docs"
2026-03-13 00:10:09.657616 | 
2026-03-13 00:10:09.657744 | PLAY RECAP
2026-03-13 00:10:09.657804 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-03-13 00:10:09.657840 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-13 00:10:09.657865 | 
2026-03-13 00:10:09.783873 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-03-13 00:10:09.785372 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-13 00:10:10.355685 | 
2026-03-13 00:10:10.355870 | PLAY [all]
2026-03-13 00:10:10.378967 | 
2026-03-13 00:10:10.379150 | TASK [Install binary dependencies]
2026-03-13 00:10:10.450351 | controller | ok
2026-03-13 00:10:10.492085 | 
2026-03-13 00:10:10.492209 | TASK [bindep : Include find tasks]
2026-03-13 00:10:10.521701 | controller | ok
2026-03-13 00:10:10.529829 | controller | included: /var/lib/zuul/builds/d84b3746c1164878bbbbaac1f5e2c704/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-03-13 00:10:10.536027 | 
2026-03-13 00:10:10.536096 | TASK [bindep : Look for bindep.txt]
2026-03-13 00:10:11.491879 | controller | ok
2026-03-13 00:10:11.499692 | 
2026-03-13 00:10:11.499819 | TASK [bindep : Define bindep_file fact]
2026-03-13 00:10:11.525625 | controller | skipping: Conditional result was False
2026-03-13 00:10:11.534462 | 
2026-03-13 00:10:11.534603 | TASK [bindep : Look for other-requirements.txt]
2026-03-13 00:10:12.194677 | controller | ok
2026-03-13 00:10:12.204033 | 
2026-03-13 00:10:12.204144 | TASK [bindep : Define bindep_file fact]
2026-03-13 00:10:12.239484 | controller | skipping: Conditional result was False
2026-03-13 00:10:12.250821 | 
2026-03-13 00:10:12.250976 | TASK [bindep : Look for bindep fallback file]
2026-03-13 00:10:12.288164 | controller | skipping: Conditional result was False
2026-03-13 00:10:12.296709 | 
2026-03-13 00:10:12.296845 | TASK [bindep : Define bindep_file fact]
2026-03-13 00:10:12.321476 | controller | skipping: Conditional result was False
2026-03-13 00:10:12.331304 | 
2026-03-13 00:10:12.331482 | TASK [bindep : Include bindep tasks]
2026-03-13 00:10:12.356462 | controller | skipping: Conditional result was False
2026-03-13 00:10:12.365221 | 
2026-03-13 00:10:12.365363 | TASK [bindep : Include install tasks]
2026-03-13 00:10:12.390392 | controller | skipping: Conditional result was False
2026-03-13 00:10:12.399236 | 
2026-03-13 00:10:12.399368 | LOOP [bindep : Include package tasks]
2026-03-13 00:10:12.481291 | 
2026-03-13 00:10:12.481573 | TASK [Run test-setup role]
2026-03-13 00:10:12.510166 | controller | ok
2026-03-13 00:10:12.542205 | 
2026-03-13 00:10:12.542349 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-03-13 00:10:13.239802 | controller | ok
2026-03-13 00:10:13.247958 | 
2026-03-13 00:10:13.248109 | TASK [test-setup : Run tools/test-setup.sh]
2026-03-13 00:10:13.627917 | controller | skipping: Conditional result was False
2026-03-13 00:10:13.662942 | 
2026-03-13 00:10:13.663077 | PLAY RECAP
2026-03-13 00:10:13.663140 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-13 00:10:13.663181 | 
2026-03-13 00:10:13.774483 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-13 00:10:13.776328 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-03-13 00:10:14.425633 | 
2026-03-13 00:10:14.425817 | PLAY [controller]
2026-03-13 00:10:14.446412 | 
2026-03-13 00:10:14.446548 | TASK [Create the /root directory]
2026-03-13 00:10:15.550490 | controller | ok
2026-03-13 00:10:15.565691 | 
2026-03-13 00:10:15.565937 | TASK [Install glibc-langpack-en]
2026-03-13 00:10:23.943098 | controller | ok: Nothing to do
2026-03-13 00:10:23.956720 | 
2026-03-13 00:10:23.956952 | TASK [Ensure controller directory exists]
2026-03-13 00:10:24.781418 | controller | changed
2026-03-13 00:10:24.787731 | 
2026-03-13 00:10:24.787807 | TASK [Install container runtime]
2026-03-13 00:10:24.827683 | controller | ok
2026-03-13 00:10:24.869950 | 
2026-03-13 00:10:24.870085 | LOOP [ensure-docker : Gather variables for each operating system]
2026-03-13 00:10:24.901218 | controller | ok: "/var/lib/zuul/builds/d84b3746c1164878bbbbaac1f5e2c704/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2026-03-13 00:10:24.910358 | 
2026-03-13 00:10:24.910446 | TASK [ensure-docker : Sanity check for distro selection]
2026-03-13 00:10:24.924167 | controller | skipping: Conditional result was False
2026-03-13 00:10:24.931263 | 
2026-03-13 00:10:24.931360 | TASK [ensure-docker : Sanity check for distro install]
2026-03-13 00:10:24.945254 | controller | skipping: Conditional result was False
2026-03-13 00:10:24.951804 | 
2026-03-13 00:10:24.951901 | TASK [ensure-docker : Sanity check for upstream install]
2026-03-13 00:10:24.975589 | controller | skipping: Conditional result was False
2026-03-13 00:10:24.981979 | 
2026-03-13 00:10:24.982086 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2026-03-13 00:10:25.005757 | controller | skipping: Conditional result was False
2026-03-13 00:10:25.012098 | 
2026-03-13 00:10:25.012184 | TASK [ensure-docker : Include tasks for docker install]
2026-03-13 00:10:25.031031 | controller | ok
2026-03-13 00:10:25.039773 | controller | included: /var/lib/zuul/builds/d84b3746c1164878bbbbaac1f5e2c704/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2026-03-13 00:10:25.053491 | 
2026-03-13 00:10:25.053625 | TASK [ensure-docker : Not Implemented]
2026-03-13 00:10:25.068792 | controller | skipping: Conditional result was False
2026-03-13 00:10:25.078614 | 
2026-03-13 00:10:25.078744 | TASK [Set up docker mirrors]
2026-03-13 00:10:25.101054 | controller | ok
2026-03-13 00:10:25.132418 | 
2026-03-13 00:10:25.132570 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2026-03-13 00:10:25.156808 | controller | skipping: Conditional result was False
2026-03-13 00:10:25.163538 | 
2026-03-13 00:10:25.163639 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2026-03-13 00:10:25.187689 | controller | skipping: Conditional result was False
2026-03-13 00:10:25.225636 | 
2026-03-13 00:10:25.225802 | TASK [use-docker-mirror : Set docker_mirror fact]
2026-03-13 00:10:25.260000 | controller | skipping: Conditional result was False
2026-03-13 00:10:25.266885 | 
2026-03-13 00:10:25.266980 | TASK [use-docker-mirror : Set up docker mirrors]
2026-03-13 00:10:25.301320 | controller | skipping: Conditional result was False
2026-03-13 00:10:25.313846 | 
2026-03-13 00:10:25.313960 | TASK [Add all repositories]
2026-03-13 00:10:25.333652 | controller | ok
2026-03-13 00:10:25.358999 | 
2026-03-13 00:10:25.359131 | LOOP [ensure-package-repositories : Install repositories]
2026-03-13 00:10:25.383332 | controller | ok: "/var/lib/zuul/builds/d84b3746c1164878bbbbaac1f5e2c704/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2026-03-13 00:10:25.383494 | controller | ok: All items complete
2026-03-13 00:10:25.383529 | 
2026-03-13 00:10:25.392583 | controller | included: /var/lib/zuul/builds/d84b3746c1164878bbbbaac1f5e2c704/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2026-03-13 00:10:25.399788 | 
2026-03-13 00:10:25.399871 | TASK [ensure-package-repositories : Create GPG key temp directory]
2026-03-13 00:10:26.263239 | controller | changed
2026-03-13 00:10:26.277426 | 
2026-03-13 00:10:26.277580 | LOOP [ensure-package-repositories : Upload GPG keys]
2026-03-13 00:10:29.563713 | controller | changed:
2026-03-13 00:10:29.563974 | controller | {
2026-03-13 00:10:29.564076 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2026-03-13 00:10:29.564155 | controller | }
2026-03-13 00:10:29.584986 | 
2026-03-13 00:10:29.585170 | LOOP [ensure-package-repositories : Import GPG keys]
2026-03-13 00:10:30.846607 | controller | changed:
2026-03-13 00:10:30.846796 | controller | {
2026-03-13 00:10:30.846825 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2026-03-13 00:10:30.846847 | controller | }
2026-03-13 00:10:30.846879 | controller | changed: All items complete
2026-03-13 00:10:30.846911 | 
2026-03-13 00:10:30.867903 | 
2026-03-13 00:10:30.868130 | TASK [ensure-package-repositories : Remove GPG temp directory]
2026-03-13 00:10:31.586870 | controller | changed
2026-03-13 00:10:31.601534 | 
2026-03-13 00:10:31.601678 | LOOP [ensure-package-repositories : Add repositories]
2026-03-13 00:10:32.501786 | controller | changed:
2026-03-13 00:10:32.502305 | controller | {
2026-03-13 00:10:32.502415 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2026-03-13 00:10:32.502481 | controller |   "description": "Docker CE Stable - $basearch",
2026-03-13 00:10:32.502521 | controller |   "gpgcheck": true,
2026-03-13 00:10:32.502557 | controller |   "name": "docker-ce-stable"
2026-03-13 00:10:32.502591 | controller | }
2026-03-13 00:10:32.540483 | 
2026-03-13 00:10:32.540707 | TASK [ensure-docker : Install pre-reqs]
2026-03-13 00:10:37.379868 | controller | ok: Nothing to do
2026-03-13 00:10:37.387888 | 
2026-03-13 00:10:37.387994 | TASK [ensure-docker : Remove packages]
2026-03-13 00:10:40.504816 | controller | ok: Nothing to do
2026-03-13 00:10:40.518779 | 
2026-03-13 00:10:40.518961 | TASK [ensure-docker : Install upstream docker using package]
2026-03-13 00:12:02.543867 | controller | changed
2026-03-13 00:12:02.557927 | 
2026-03-13 00:12:02.558093 | TASK [ensure-docker : Setup docker]
2026-03-13 00:12:02.587770 | controller | ok
2026-03-13 00:12:02.645717 | controller | included: /var/lib/zuul/builds/d84b3746c1164878bbbbaac1f5e2c704/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2026-03-13 00:12:02.657710 | 
2026-03-13 00:12:02.657788 | TASK [ensure-docker : Ensure "docker" group exists]
2026-03-13 00:12:03.632042 | controller | ok
2026-03-13 00:12:03.637726 | 
2026-03-13 00:12:03.637792 | TASK [ensure-docker : Add user to docker group]
2026-03-13 00:12:04.630862 | controller | changed
2026-03-13 00:12:04.644452 | 
2026-03-13 00:12:04.644634 | TASK [Update docker daemon proxy configuration]
2026-03-13 00:12:04.682353 | controller | skipping: Conditional result was False
2026-03-13 00:12:04.697140 | 
2026-03-13 00:12:04.697307 | TASK [Lower default MTU]
2026-03-13 00:12:04.725875 | controller | skipping: Conditional result was False
2026-03-13 00:12:04.755186 | 
2026-03-13 00:12:04.755347 | TASK [ensure-docker : Get the status of the docket.socket unit]
2026-03-13 00:12:05.496543 | controller | inactive
2026-03-13 00:12:05.649803 | controller | ok: Runtime: 0:00:00.017518
2026-03-13 00:12:05.658988 | 
2026-03-13 00:12:05.659135 | TASK [ensure-docker : Get the status of the docket unit]
2026-03-13 00:12:06.320255 | controller | inactive
2026-03-13 00:12:06.537730 | controller | ok: Runtime: 0:00:00.017407
2026-03-13 00:12:06.548451 | 
2026-03-13 00:12:06.548594 | TASK [ensure-docker : Restart docker]
2026-03-13 00:12:06.590844 | Trigger a Docker restart
2026-03-13 00:12:06.596863 | 
2026-03-13 00:12:06.596927 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2026-03-13 00:12:06.628302 | 
2026-03-13 00:12:06.628434 | TASK [ensure-docker : Install docker-compose]
2026-03-13 00:12:06.652750 | controller | skipping: Conditional result was False
2026-03-13 00:12:06.660007 | 
2026-03-13 00:12:06.660132 | TASK [ensure-docker : Flush handlers before role exit]
2026-03-13 00:12:06.672385 | 
2026-03-13 00:12:06.672478 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2026-03-13 00:12:10.011917 | controller | changed
2026-03-13 00:12:10.038478 | 
2026-03-13 00:12:10.038747 | TASK [ensure-docker : Assure docker service is running]
2026-03-13 00:12:13.618665 | controller | changed
2026-03-13 00:12:13.636553 | 
2026-03-13 00:12:13.636748 | TASK [ensure-docker : Assure docker.socket service is running]
2026-03-13 00:12:15.483769 | controller | ok
2026-03-13 00:12:15.491861 | 
2026-03-13 00:12:15.491936 | TASK [ensure-docker : Correct group ownership on docker sock]
2026-03-13 00:12:16.349749 | controller | ok
2026-03-13 00:12:16.362219 | 
2026-03-13 00:12:16.362361 | TASK [ensure-docker : Validate ability to talk with docker]
2026-03-13 00:12:16.978593 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2026-03-13 00:12:17.240488 | controller | ok: Runtime: 0:00:00.036086
2026-03-13 00:12:17.249686 | 
2026-03-13 00:12:17.249791 | TASK [ensure-docker : Get version details]
2026-03-13 00:12:18.042010 | controller | Client: Docker Engine - Community
2026-03-13 00:12:18.043673 | controller |  Version:           24.0.7
2026-03-13 00:12:18.043693 | controller |  API version:       1.43
2026-03-13 00:12:18.043702 | controller |  Go version:        go1.20.10
2026-03-13 00:12:18.043720 | controller |  Git commit:        afdd53b
2026-03-13 00:12:18.043771 | controller |  Built:             Thu Oct 26 09:09:39 2023
2026-03-13 00:12:18.043779 | controller |  OS/Arch:           linux/amd64
2026-03-13 00:12:18.043788 | controller |  Context:           default
2026-03-13 00:12:18.043797 | controller |
2026-03-13 00:12:18.043804 | controller | Server: Docker Engine - Community
2026-03-13 00:12:18.043810 | controller |  Engine:
2026-03-13 00:12:18.043816 | controller |   Version:          24.0.7
2026-03-13 00:12:18.043822 | controller |   API version:      1.43 (minimum version 1.12)
2026-03-13 00:12:18.045877 | controller |   Go version:       go1.20.10
2026-03-13 00:12:18.045893 | controller |   Git commit:       311b9ff
2026-03-13 00:12:18.045901 | controller |   Built:            Thu Oct 26 09:08:11 2023
2026-03-13 00:12:18.045909 | controller |   OS/Arch:          linux/amd64
2026-03-13 00:12:18.045915 | controller |   Experimental:     false
2026-03-13 00:12:18.045922 | controller |  containerd:
2026-03-13 00:12:18.045928 | controller |   Version:          1.6.25
2026-03-13 00:12:18.045934 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2026-03-13 00:12:18.045941 | controller |  runc:
2026-03-13 00:12:18.045947 | controller |   Version:          1.1.10
2026-03-13 00:12:18.045953 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2026-03-13 00:12:18.045962 | controller |  docker-init:
2026-03-13 00:12:18.045968 | controller |   Version:          0.19.0
2026-03-13 00:12:18.045975 | controller |   GitCommit:        de40ad0
2026-03-13 00:12:18.198435 | controller | ok: Runtime: 0:00:00.040124
2026-03-13 00:12:18.211556 | 
2026-03-13 00:12:18.211702 | TASK [ensure-docker : Dump installed docker details]
2026-03-13 00:12:18.251462 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version:           24.0.7\n API version:       1.43\n Go version:        go1.20.10\n Git commit:        afdd53b\n Built:             Thu Oct 26 09:09:39 2023\n OS/Arch:           linux/amd64\n Context:           default\n\nServer: Docker Engine - Community\n Engine:\n  Version:          24.0.7\n  API version:      1.43 (minimum version 1.12)\n  Go version:       go1.20.10\n  Git commit:       311b9ff\n  Built:            Thu Oct 26 09:08:11 2023\n  OS/Arch:          linux/amd64\n  Experimental:     false\n containerd:\n  Version:          1.6.25\n  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n  Version:          1.1.10\n  GitCommit:        v1.1.10-0-g18a0cb0\n docker-init:\n  Version:          0.19.0\n  GitCommit:        de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2026-03-13 00:12:18.006275', 'end': '2026-03-13 00:12:18.046399', 'delta': '0:00:00.040124', 'msg': '', 'zuul_log_id': 'fa163ec2-ffbe-220b-9e44-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version:           24.0.7', ' API version:       1.43', ' Go version:        go1.20.10', ' Git commit:        afdd53b', ' Built:             Thu Oct 26 09:09:39 2023', ' OS/Arch:           linux/amd64', ' Context:           default', '', 'Server: Docker Engine - Community', ' Engine:', '  Version:          24.0.7', '  API version:      1.43 (minimum version 1.12)', '  Go version:       go1.20.10', '  Git commit:       311b9ff', '  Built:            Thu Oct 26 09:08:11 2023', '  OS/Arch:          linux/amd64', '  Experimental:     false', ' containerd:', '  Version:          1.6.25', '  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', '  Version:          1.1.10', '  GitCommit:        v1.1.10-0-g18a0cb0', ' docker-init:', '  Version:          0.19.0', '  GitCommit:        de40ad0'], 'stderr_lines': [], 'failed': False}
2026-03-13 00:12:18.264184 | 
2026-03-13 00:12:18.264341 | TASK [Use buildset registry]
2026-03-13 00:12:18.281339 | controller | skipping: Conditional result was False
2026-03-13 00:12:18.306651 | 
2026-03-13 00:12:18.306790 | TASK [Ensure python3.8 is present]
2026-03-13 00:12:18.332695 | controller | skipping: Conditional result was False
2026-03-13 00:12:18.340727 | 
2026-03-13 00:12:18.340808 | TASK [Run our-ensure-python role (Fedora only for now)]
2026-03-13 00:12:18.362130 | controller | ok
2026-03-13 00:12:18.405149 | 
2026-03-13 00:12:18.405279 | TASK [our-ensure-python : Install the right Python version (rpm)]
2026-03-13 00:12:23.825067 | controller | ok: Nothing to do
2026-03-13 00:12:23.834255 | 
2026-03-13 00:12:23.834403 | TASK [our-ensure-python : Also install python3-devel]
2026-03-13 00:12:41.178998 | controller | changed
2026-03-13 00:12:41.208558 | 
2026-03-13 00:12:41.208748 | TASK [Run ensure-virtualenv role]
2026-03-13 00:12:41.232069 | controller | ok
2026-03-13 00:12:41.266191 | 
2026-03-13 00:12:41.266352 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2026-03-13 00:12:42.022462 | controller | /usr/bin/virtualenv
2026-03-13 00:12:42.643896 | controller | ok: Runtime: 0:00:00.004645
2026-03-13 00:12:42.651318 | 
2026-03-13 00:12:42.651490 | LOOP [ensure-virtualenv : Install virtualenv package]
2026-03-13 00:12:42.689144 | controller | skipping: Conditional result was False
2026-03-13 00:12:42.689403 | controller | changed: All items complete
2026-03-13 00:12:42.689432 | 
2026-03-13 00:12:42.745271 | 
2026-03-13 00:12:42.745427 | TASK [Find the full path of the Python interpreter]
2026-03-13 00:12:43.554789 | controller | /usr/bin/python3.8
2026-03-13 00:12:44.119715 | controller | ok
2026-03-13 00:12:44.129178 | 
2026-03-13 00:12:44.129318 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2026-03-13 00:12:46.218257 | controller | created virtual environment CPython3.8.18.final.0-64 in 995ms
2026-03-13 00:12:46.334025 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2026-03-13 00:12:46.334078 | controller |   seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv)
2026-03-13 00:12:46.334095 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2026-03-13 00:12:46.334115 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2026-03-13 00:12:46.518848 | controller | changed
2026-03-13 00:12:46.527526 | 
2026-03-13 00:12:46.527620 | TASK [Set selinux package]
2026-03-13 00:12:46.568697 | controller | ok
2026-03-13 00:12:46.582527 | 
2026-03-13 00:12:46.582686 | TASK [Set selinux package (Fedora)]
2026-03-13 00:12:46.617714 | controller | ok
2026-03-13 00:12:46.625602 | 
2026-03-13 00:12:46.625689 | TASK [Install selinux into virtualenv]
2026-03-13 00:12:52.401341 | controller | Collecting selinux-please-lie-to-me
2026-03-13 00:12:52.537851 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2026-03-13 00:12:53.800144 | controller | Collecting setuptools<50.0.0
2026-03-13 00:12:53.815646 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2026-03-13 00:12:53.945828 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 7.0 MB/s eta 0:00:00
2026-03-13 00:12:54.205674 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2026-03-13 00:12:54.206016 | controller |   Attempting uninstall: setuptools
2026-03-13 00:12:54.208838 | controller |     Found existing installation: setuptools 62.6.0
2026-03-13 00:12:54.336310 | controller |     Uninstalling setuptools-62.6.0:
2026-03-13 00:12:54.354723 | controller |       Successfully uninstalled setuptools-62.6.0
2026-03-13 00:12:55.052562 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2026-03-13 00:12:55.266773 | controller |
2026-03-13 00:12:55.528052 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-03-13 00:12:55.528104 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-03-13 00:12:56.019937 | controller | ok: Runtime: 0:00:07.780886
2026-03-13 00:12:56.032571 | 
2026-03-13 00:12:56.032759 | TASK [Install pytest-forked into virtualenv]
2026-03-13 00:12:57.597238 | controller | Collecting pytest-forked
2026-03-13 00:12:57.655602 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2026-03-13 00:12:57.727046 | controller | Collecting py
2026-03-13 00:12:57.735290 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2026-03-13 00:12:57.767648 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 3.1 MB/s eta 0:00:00
2026-03-13 00:12:57.985789 | controller | Collecting pytest>=3.10
2026-03-13 00:12:57.990386 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2026-03-13 00:12:58.010735 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 21.8 MB/s eta 0:00:00
2026-03-13 00:12:58.130293 | controller | Collecting tomli>=1
2026-03-13 00:12:58.137297 | controller |   Downloading tomli-2.4.0-py3-none-any.whl (14 kB)
2026-03-13 00:12:58.215172 | controller | Collecting packaging
2026-03-13 00:12:58.218046 | controller |   Downloading packaging-26.0-py3-none-any.whl (74 kB)
2026-03-13 00:12:58.231571 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 6.6 MB/s eta 0:00:00
2026-03-13 00:12:58.280932 | controller | Collecting pluggy<2,>=1.5
2026-03-13 00:12:58.287357 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2026-03-13 00:12:58.344464 | controller | Collecting exceptiongroup>=1.0.0rc8
2026-03-13 00:12:58.350811 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2026-03-13 00:12:58.401848 | controller | Collecting iniconfig
2026-03-13 00:12:58.406673 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2026-03-13 00:12:58.485641 | controller | Collecting typing-extensions>=4.6.0
2026-03-13 00:12:58.493743 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2026-03-13 00:12:58.505124 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 5.4 MB/s eta 0:00:00
2026-03-13 00:12:58.605977 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2026-03-13 00:12:59.529477 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.1.0 packaging-26.0 pluggy-1.5.0 py-1.11.0 pytest-8.3.5 pytest-forked-1.6.0 tomli-2.4.0 typing-extensions-4.13.2
2026-03-13 00:12:59.534561 | controller |
2026-03-13 00:12:59.765826 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-03-13 00:12:59.765890 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-03-13 00:12:59.943919 | controller | ok: Runtime: 0:00:02.910019
2026-03-13 00:12:59.952069 | 
2026-03-13 00:12:59.952175 | TASK [Update pip]
2026-03-13 00:13:02.348745 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2026-03-13 00:13:02.783736 | controller | Collecting pip
2026-03-13 00:13:02.904985 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2026-03-13 00:13:03.045662 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 14.3 MB/s eta 0:00:00
2026-03-13 00:13:03.265684 | controller | Installing collected packages: pip
2026-03-13 00:13:03.268704 | controller |   Attempting uninstall: pip
2026-03-13 00:13:03.269767 | controller |     Found existing installation: pip 22.2.2
2026-03-13 00:13:03.925958 | controller |     Uninstalling pip-22.2.2:
2026-03-13 00:13:04.000680 | controller |       Successfully uninstalled pip-22.2.2
2026-03-13 00:13:06.674118 | controller | Successfully installed pip-25.0.1
2026-03-13 00:13:07.348696 | controller | ok: Runtime: 0:00:05.876519
2026-03-13 00:13:07.356827 | 
2026-03-13 00:13:07.356949 | TASK [Ensure pip temp directory exists in /var/tmp]
2026-03-13 00:13:08.541668 | controller | changed
2026-03-13 00:13:08.598424 | 
2026-03-13 00:13:08.598599 | TASK [Install ansible into virtualenv]
2026-03-13 00:13:10.216472 | controller | Processing ./src/github.com/ansible/ansible
2026-03-13 00:13:10.217997 | controller |   Preparing metadata (setup.py): started
2026-03-13 00:13:11.023277 | controller |   Preparing metadata (setup.py): finished with status 'done'
2026-03-13 00:13:11.084658 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2026-03-13 00:13:11.101361 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2026-03-13 00:13:11.193204 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2026-03-13 00:13:11.199754 | controller |   Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.1 kB)
2026-03-13 00:13:11.658728 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2026-03-13 00:13:11.663664 | controller |   Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2026-03-13 00:13:11.685605 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (26.0)
2026-03-13 00:13:11.906844 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2026-03-13 00:13:11.913653 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2026-03-13 00:13:11.920826 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.8/site-packages (from cryptography->ansible-base==2.10.17.post0) (4.13.2)
2026-03-13 00:13:12.028349 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2026-03-13 00:13:12.033765 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2026-03-13 00:13:12.066487 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2026-03-13 00:13:12.071082 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2026-03-13 00:13:12.091733 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2026-03-13 00:13:12.144166 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 100.5 MB/s eta 0:00:00
2026-03-13 00:13:12.151348 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2026-03-13 00:13:12.161490 | controller | Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (806 kB)
2026-03-13 00:13:12.178315 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 50.4 MB/s eta 0:00:00
2026-03-13 00:13:12.186873 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2026-03-13 00:13:12.208332 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2026-03-13 00:13:12.223469 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2026-03-13 00:13:12.254379 | controller | Building wheels for collected packages: ansible-base
2026-03-13 00:13:12.255413 | controller |   Building wheel for ansible-base (setup.py): started
2026-03-13 00:13:14.442267 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2026-03-13 00:13:14.444316 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=ff9d9cce862d551ed89e95a1e78ed41e51fb65a6e0c68e98993ca07941e62fb5
2026-03-13 00:13:14.446442 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-kahu1894/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2026-03-13 00:13:14.460437 | controller | Successfully built ansible-base
2026-03-13 00:13:14.518011 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2026-03-13 00:13:17.625296 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-base-2.10.17.post0 cffi-1.17.1 cryptography-46.0.5 jinja2-3.1.6 pycparser-2.23
2026-03-13 00:13:17.971965 | controller | ok: Runtime: 0:00:08.368506
2026-03-13 00:13:17.986835 | 
2026-03-13 00:13:17.987060 | TASK [Setup download-artifact-fork role]
2026-03-13 00:13:18.021799 | controller | ok
2026-03-13 00:13:18.086814 | 
2026-03-13 00:13:18.086966 | TASK [download-artifact-fork : Parse inventory response]
2026-03-13 00:13:18.137874 | controller | ok
2026-03-13 00:13:18.144113 | 
2026-03-13 00:13:18.144190 | TASK [download-artifact-fork : Fetch info from Zuul API]
2026-03-13 00:13:18.179510 | controller | skipping: Conditional result was False
2026-03-13 00:13:18.190621 | 
2026-03-13 00:13:18.190763 | TASK [download-artifact-fork : Parse build response]
2026-03-13 00:13:18.228280 | controller | skipping: Conditional result was False
2026-03-13 00:13:18.238772 | 
2026-03-13 00:13:18.238882 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2026-03-13 00:13:19.137099 | controller | changed
2026-03-13 00:13:19.146680 | 
2026-03-13 00:13:19.146829 | LOOP [download-artifact-fork : Download archive by type]
2026-03-13 00:13:19.184148 | controller | skipping: Conditional result was False
2026-03-13 00:13:19.184652 | 
2026-03-13 00:13:21.466360 | controller | changed: OK (20207 bytes)
2026-03-13 00:13:21.500617 | 
2026-03-13 00:13:21.500802 | TASK [deploy-artifacts : define __collections]
2026-03-13 00:13:21.539620 | controller | ok
2026-03-13 00:13:21.552320 | 
2026-03-13 00:13:21.552492 | LOOP [deploy-artifacts : Create list of collections]
2026-03-13 00:13:21.591173 | controller | skipping: Conditional result was False
2026-03-13 00:13:21.609003 | controller | ok:
2026-03-13 00:13:21.609139 | controller | {
2026-03-13 00:13:21.609189 | controller |   "branch": "main",
2026-03-13 00:13:21.609214 | controller |   "job": "build-ansible-collection",
2026-03-13 00:13:21.609237 | controller |   "metadata": {
2026-03-13 00:13:21.609260 | controller |     "type": "ansible_collection",
2026-03-13 00:13:21.609281 | controller |     "version": "5.0.0"
2026-03-13 00:13:21.609302 | controller |   },
2026-03-13 00:13:21.609323 | controller |   "name": "ansible.network",
2026-03-13 00:13:21.609344 | controller |   "project": "ansible-collections/ansible.network",
2026-03-13 00:13:21.609366 | controller |   "url": "https://b5e9ab1d1a1a7a71c1ef-664e6a567f5aafe061e3244e9f0eb728.ssl.cf2.rackcdn.com/ansible/2aee315160914b64b131f801fb5be4d4/artifacts/ansible-network-5.0.0.tar.gz"
2026-03-13 00:13:21.609395 | controller | }
2026-03-13 00:13:21.628416 | 
2026-03-13 00:13:21.628628 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2026-03-13 00:13:23.027797 | controller | Starting galaxy collection install process
2026-03-13 00:13:23.031275 | controller | Process install dependency map
2026-03-13 00:13:39.955053 | controller | Starting collection install process
2026-03-13 00:13:39.955114 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2026-03-13 00:13:39.968584 | controller | ansible.network (5.0.0) was installed successfully
2026-03-13 00:13:39.968758 | controller | Installing 'ansible.netcommon:8.4.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-03-13 00:13:39.968778 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-netcommon-8.4.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4016uu0_h3kv/tmp2fy9tqau
2026-03-13 00:13:41.608853 | controller | ansible.netcommon (8.4.0) was installed successfully
2026-03-13 00:13:41.608904 | controller | Installing 'ansible.utils:6.0.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-03-13 00:13:41.610317 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-utils-6.0.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4016uu0_h3kv/tmp2fy9tqau
2026-03-13 00:13:43.460952 | controller | ansible.utils (6.0.1) was installed successfully
2026-03-13 00:13:43.461118 | controller | Installing 'arista.eos:12.0.1' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2026-03-13 00:13:43.461137 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/arista-eos-12.0.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4016uu0_h3kv/tmp2fy9tqau
2026-03-13 00:13:45.954857 | controller | arista.eos (12.0.1) was installed successfully
2026-03-13 00:13:45.954985 | controller | Installing 'cisco.ios:11.3.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2026-03-13 00:13:45.955292 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-ios-11.3.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4016uu0_h3kv/tmp2fy9tqau
2026-03-13 00:13:48.974232 | controller | cisco.ios (11.3.0) was installed successfully
2026-03-13 00:13:48.974357 | controller | Installing 'cisco.iosxr:12.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2026-03-13 00:13:48.974614 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-iosxr-12.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4016uu0_h3kv/tmp2fy9tqau
2026-03-13 00:13:51.929890 | controller | cisco.iosxr (12.1.1) was installed successfully
2026-03-13 00:13:51.930025 | controller | Installing 'cisco.nxos:11.1.3' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2026-03-13 00:13:51.930271 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.1.3.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4016uu0_h3kv/tmp2fy9tqau
2026-03-13 00:13:55.920415 | controller | cisco.nxos (11.1.3) was installed successfully
2026-03-13 00:13:55.920544 | controller | Installing 'junipernetworks.junos:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2026-03-13 00:13:55.920845 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/junipernetworks-junos-11.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4016uu0_h3kv/tmp2fy9tqau
2026-03-13 00:13:57.945249 | controller | junipernetworks.junos (11.1.1) was installed successfully
2026-03-13 00:13:57.945415 | controller | Installing 'juniper.device:2.0.1' to '/home/zuul/.ansible/collections/ansible_collections/juniper/device'
2026-03-13 00:13:57.945430 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/juniper-device-2.0.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4016uu0_h3kv/tmp2fy9tqau
2026-03-13 00:13:59.743004 | controller | juniper.device (2.0.1) was installed successfully
2026-03-13 00:14:00.044431 | controller | ok: Runtime: 0:00:37.492190
2026-03-13 00:14:00.068832 | 
2026-03-13 00:14:00.068984 | TASK [podman may need iptables kernel modules]
2026-03-13 00:14:01.482159 | controller | ok: Runtime: 0:00:00.007235
2026-03-13 00:14:01.533202 | 
2026-03-13 00:14:01.533403 | PLAY RECAP
2026-03-13 00:14:01.533496 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2026-03-13 00:14:01.533541 | 
2026-03-13 00:14:01.691748 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-03-13 00:14:01.692689 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-03-13 00:14:02.331496 | 
2026-03-13 00:14:02.331620 | PLAY [controller]
2026-03-13 00:14:02.355719 | 
2026-03-13 00:14:02.355839 | TASK [Copy the galaxy.yml on the controller]
2026-03-13 00:14:06.215459 | controller | changed
2026-03-13 00:14:06.225895 | 
2026-03-13 00:14:06.226097 | TASK [Load information from galaxy.yml]
2026-03-13 00:14:06.265724 | controller | ok
2026-03-13 00:14:06.275414 | 
2026-03-13 00:14:06.275532 | LOOP [Enable FIPS mode]
2026-03-13 00:14:06.301098 | 
2026-03-13 00:14:06.301274 | LOOP [ansible-test : Include branch specific variables.]
2026-03-13 00:14:06.343554 | controller | ok: "/var/lib/zuul/builds/d84b3746c1164878bbbbaac1f5e2c704/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2026-03-13 00:14:06.343869 | 
2026-03-13 00:14:06.368573 | 
2026-03-13 00:14:06.368751 | TASK [ansible-test : Setup --skip-tags for test_options]
2026-03-13 00:14:06.410465 | controller | ok
2026-03-13 00:14:06.416435 | 
2026-03-13 00:14:06.416510 | TASK [ansible-test : Enable --inventory for network-integration]
2026-03-13 00:14:06.441286 | controller | skipping: Conditional result was False
2026-03-13 00:14:06.452434 | 
2026-03-13 00:14:06.452550 | TASK [ansible-test : Enable --retry-on-error]
2026-03-13 00:14:06.467872 | controller | skipping: Conditional result was False
2026-03-13 00:14:06.478780 | 
2026-03-13 00:14:06.478889 | TASK [ansible-test : Enable --continue-on-error]
2026-03-13 00:14:06.513052 | controller | ok
2026-03-13 00:14:06.522089 | 
2026-03-13 00:14:06.522186 | TASK [ansible-test : Enable --requirements]
2026-03-13 00:14:06.537041 | controller | skipping: Conditional result was False
2026-03-13 00:14:06.546880 | 
2026-03-13 00:14:06.546985 | TASK [ansible-test : Adjust options for unit tests]
2026-03-13 00:14:06.563332 | controller | skipping: Conditional result was False
2026-03-13 00:14:06.574583 | 
2026-03-13 00:14:06.574699 | TASK [ansible-test : Install requirements with unit tests]
2026-03-13 00:14:06.641449 | controller | ok
2026-03-13 00:14:06.647383 | 
2026-03-13 00:14:06.647465 | TASK [ansible-test : Set --skip-test]
2026-03-13 00:14:06.691796 | controller | skipping: Conditional result was False
2026-03-13 00:14:06.698339 | 
2026-03-13 00:14:06.698409 | TASK [ansible-test : Reduce verbosity]
2026-03-13 00:14:06.728118 | controller | ok
2026-03-13 00:14:06.734714 | 
2026-03-13 00:14:06.734969 | TASK [ansible-test : Setup --python option]
2026-03-13 00:14:06.748809 | controller | skipping: Conditional result was False
2026-03-13 00:14:06.756119 | 
2026-03-13 00:14:06.756191 | TASK [ansible-test : Setup --docker option]
2026-03-13 00:14:06.785750 | controller | ok
2026-03-13 00:14:06.793624 | 
2026-03-13 00:14:06.793703 | TASK [ansible-test : check if MANIFEST.json exists]
2026-03-13 00:14:08.036887 | controller | ok
2026-03-13 00:14:08.043607 | 
2026-03-13 00:14:08.043693 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2026-03-13 00:14:08.058920 | controller | skipping: Conditional result was False
2026-03-13 00:14:08.067168 | 
2026-03-13 00:14:08.067277 | TASK [ansible-test : Load information from MANIFEST.json]
2026-03-13 00:14:08.081664 | controller | skipping: Conditional result was False
2026-03-13 00:14:08.094092 | 
2026-03-13 00:14:08.094400 | TASK [ansible-test : Setup location of project for integration tests]
2026-03-13 00:14:08.109650 | controller | skipping: Conditional result was False
2026-03-13 00:14:08.118518 | 
2026-03-13 00:14:08.118647 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2026-03-13 00:14:09.391617 | controller | ok
2026-03-13 00:14:09.405533 | 
2026-03-13 00:14:09.405704 | TASK [ansible-test : Load information from galaxy.yml]
2026-03-13 00:14:09.468561 | controller | ok
2026-03-13 00:14:09.482127 | 
2026-03-13 00:14:09.482281 | TASK [ansible-test : Setup location of project for integration tests]
2026-03-13 00:14:09.543628 | controller | ok
2026-03-13 00:14:09.556925 | 
2026-03-13 00:14:09.557135 | TASK [ansible-test : Setup minimum test requirements]
2026-03-13 00:14:09.594413 | controller | ok
2026-03-13 00:14:09.601818 | 
2026-03-13 00:14:09.601943 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2026-03-13 00:14:10.562649 | controller | ok
2026-03-13 00:14:10.578094 | 
2026-03-13 00:14:10.578253 | TASK [ansible-test : Install python requirements]
2026-03-13 00:14:11.962209 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-03-13 00:14:11.978271 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2026-03-13 00:14:12.011447 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-03-13 00:14:12.016062 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2026-03-13 00:14:12.027054 | controller | Requirement already satisfied: pyyaml in ./venv/lib64/python3.8/site-packages (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1)) (6.0.3)
2026-03-13 00:14:12.034447 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2026-03-13 00:14:12.050067 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2026-03-13 00:14:12.100659 | controller | Installing collected packages: pathspec, yamllint
2026-03-13 00:14:12.188040 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2026-03-13 00:14:12.467711 | controller | ok: Runtime: 0:00:00.968461
2026-03-13 00:14:12.478914 | 
2026-03-13 00:14:12.479036 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2026-03-13 00:14:13.321308 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2026-03-13 00:14:13.862498 | controller | ERROR
2026-03-13 00:14:13.862898 | controller | {
2026-03-13 00:14:13.862986 | controller |   "delta": "0:00:00.009178",
2026-03-13 00:14:13.863094 | controller |   "end": "2026-03-13 00:14:13.321527",
2026-03-13 00:14:13.863145 | controller |   "msg": "non-zero return code",
2026-03-13 00:14:13.863190 | controller |   "rc": 1,
2026-03-13 00:14:13.863238 | controller |   "start": "2026-03-13 00:14:13.312349"
2026-03-13 00:14:13.863288 | controller | }
2026-03-13 00:14:13.863346 | controller | ERROR: Ignoring Errors
2026-03-13 00:14:13.877075 | 
2026-03-13 00:14:13.877212 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2026-03-13 00:14:14.273383 | controller | skipping: Conditional result was False
2026-03-13 00:14:14.280834 | 
2026-03-13 00:14:14.280977 | TASK [ansible-test : Enable persistent connection logging]
2026-03-13 00:14:15.564134 | controller | changed: section and option added
2026-03-13 00:14:15.576084 | 
2026-03-13 00:14:15.576715 | TASK [ansible-test : Set persistent ssh_type value]
2026-03-13 00:14:16.952395 | controller | changed: option added
2026-03-13 00:14:16.963506 | 
2026-03-13 00:14:16.963646 | TASK [ansible-test : Set ansible log path]
2026-03-13 00:14:18.560103 | controller | changed: section and option added
2026-03-13 00:14:18.567799 | 
2026-03-13 00:14:18.567903 | TASK [ansible-test : Identify the changed targets]
2026-03-13 00:14:18.948326 | controller | skipping: Conditional result was False
2026-03-13 00:14:18.956566 | 
2026-03-13 00:14:18.956722 | TASK [ansible-test : set_fact]
2026-03-13 00:14:19.032790 | controller | skipping: Conditional result was False
2026-03-13 00:14:19.041899 | 
2026-03-13 00:14:19.042070 | TASK [ansible-test : Set the targets]
2026-03-13 00:14:19.120462 | controller | ok
2026-03-13 00:14:19.126675 | 
2026-03-13 00:14:19.126807 | TASK [ansible-test : set_fact]
2026-03-13 00:14:19.154810 | controller | skipping: Conditional result was False
2026-03-13 00:14:19.164577 | 
2026-03-13 00:14:19.164718 | TASK [ansible-test : List the targets]
2026-03-13 00:14:19.556065 | controller | skipping: Conditional result was False
2026-03-13 00:14:19.566581 | 
2026-03-13 00:14:19.566689 | TASK [ansible-test : set_fact]
2026-03-13 00:14:19.583032 | controller | skipping: Conditional result was False
2026-03-13 00:14:19.593150 | 
2026-03-13 00:14:19.593251 | TASK [ansible-test : set_fact]
2026-03-13 00:14:19.618248 | controller | skipping: Conditional result was False
2026-03-13 00:14:19.628477 | 
2026-03-13 00:14:19.628585 | LOOP [ansible-test : set_fact]
2026-03-13 00:14:19.694439 | 
2026-03-13 00:14:19.694652 | TASK [ansible-test : set_fact]
2026-03-13 00:14:19.719661 | controller | skipping: Conditional result was False
2026-03-13 00:14:19.729656 | 
2026-03-13 00:14:19.729781 | TASK [ansible-test : debug]
2026-03-13 00:14:19.755699 | controller | skipping: Conditional result was False
2026-03-13 00:14:19.768237 | 
2026-03-13 00:14:19.768345 | TASK [ansible-test : debug]
2026-03-13 00:14:19.802786 | About to run: ansible-test sanity --requirements --docker -v
2026-03-13 00:14:19.810766 | 
2026-03-13 00:14:19.810853 | TASK [ansible-test : Run the test suite]
2026-03-13 00:14:21.436610 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2026-03-13 00:14:21.532245 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2026-03-13 00:14:23.295914 | controller | 2.9.0: Pulling from ansible/default-test-container
2026-03-13 00:14:23.297629 | controller | 171857c49d0f: Pulling fs layer
2026-03-13 00:14:23.297657 | controller | 419640447d26: Pulling fs layer
2026-03-13 00:14:23.297665 | controller | 61e52f862619: Pulling fs layer
2026-03-13 00:14:23.297672 | controller | 851de0426e26: Pulling fs layer
2026-03-13 00:14:23.297682 | controller | 37425716ea5b: Pulling fs layer
2026-03-13 00:14:23.297690 | controller | cd9b0c439616: Pulling fs layer
2026-03-13 00:14:23.297697 | controller | d4612dbd8855: Pulling fs layer
2026-03-13 00:14:23.297703 | controller | 554699364730: Pulling fs layer
2026-03-13 00:14:23.297709 | controller | 1ff15d4bdb3e: Pulling fs layer
2026-03-13 00:14:23.297715 | controller | 5f2e4724adea: Pulling fs layer
2026-03-13 00:14:23.297721 | controller | a02d9a95ccb8: Pulling fs layer
2026-03-13 00:14:23.297727 | controller | 989f4ce370cb: Pulling fs layer
2026-03-13 00:14:23.297734 | controller | 0d7f52cdf9df: Pulling fs layer
2026-03-13 00:14:23.297740 | controller | c423742351d5: Pulling fs layer
2026-03-13 00:14:23.297746 | controller | 109236341d21: Pulling fs layer
2026-03-13 00:14:23.297752 | controller | 07a2f61c96b8: Pulling fs layer
2026-03-13 00:14:23.297758 | controller | 041f122f27ae: Pulling fs layer
2026-03-13 00:14:23.297764 | controller | ca6f5098b12e: Pulling fs layer
2026-03-13 00:14:23.297771 | controller | 1eb3e7b2afd9: Pulling fs layer
2026-03-13 00:14:23.297776 | controller | 18a0b750727a: Pulling fs layer
2026-03-13 00:14:23.297782 | controller | 17f2f596716c: Pulling fs layer
2026-03-13 00:14:23.297791 | controller | f35a9998a9bd: Pulling fs layer
2026-03-13 00:14:23.297797 | controller | fdfc10566405: Pulling fs layer
2026-03-13 00:14:23.297803 | controller | 1ff1c442c5fe: Pulling fs layer
2026-03-13 00:14:23.297809 | controller | a3443c4a17ed: Pulling fs layer
2026-03-13 00:14:23.297815 | controller | 0edcf9fb2b15: Pulling fs layer
2026-03-13 00:14:23.297821 | controller | 5af43b69e845: Pulling fs layer
2026-03-13 00:14:23.297827 | controller | 1651773effb2: Pulling fs layer
2026-03-13 00:14:23.297833 | controller | 79e23c5c0fe4: Pulling fs layer
2026-03-13 00:14:23.297842 | controller | e5058b1faaa6: Pulling fs layer
2026-03-13 00:14:23.297848 | controller | 846775490569: Pulling fs layer
2026-03-13 00:14:23.297854 | controller | 851de0426e26: Waiting
2026-03-13 00:14:23.297860 | controller | 37425716ea5b: Waiting
2026-03-13 00:14:23.297866 | controller | cd9b0c439616: Waiting
2026-03-13 00:14:23.297873 | controller | d4612dbd8855: Waiting
2026-03-13 00:14:23.297879 | controller | 554699364730: Waiting
2026-03-13 00:14:23.297885 | controller | 1ff15d4bdb3e: Waiting
2026-03-13 00:14:23.297894 | controller | 5f2e4724adea: Waiting
2026-03-13 00:14:23.297900 | controller | a02d9a95ccb8: Waiting
2026-03-13 00:14:23.297906 | controller | 989f4ce370cb: Waiting
2026-03-13 00:14:23.297912 | controller | 0d7f52cdf9df: Waiting
2026-03-13 00:14:23.297918 | controller | c423742351d5: Waiting
2026-03-13 00:14:23.297924 | controller | 109236341d21: Waiting
2026-03-13 00:14:23.297930 | controller | 07a2f61c96b8: Waiting
2026-03-13 00:14:23.297936 | controller | 041f122f27ae: Waiting
2026-03-13 00:14:23.297942 | controller | ca6f5098b12e: Waiting
2026-03-13 00:14:23.297948 | controller | 1eb3e7b2afd9: Waiting
2026-03-13 00:14:23.297954 | controller | 18a0b750727a: Waiting
2026-03-13 00:14:23.297960 | controller | 17f2f596716c: Waiting
2026-03-13 00:14:23.297968 | controller | f35a9998a9bd: Waiting
2026-03-13 00:14:23.297974 | controller | fdfc10566405: Waiting
2026-03-13 00:14:23.297980 | controller | 1ff1c442c5fe: Waiting
2026-03-13 00:14:23.297986 | controller | a3443c4a17ed: Waiting
2026-03-13 00:14:23.297992 | controller | 0edcf9fb2b15: Waiting
2026-03-13 00:14:23.297999 | controller | 5af43b69e845: Waiting
2026-03-13 00:14:23.298005 | controller | 1651773effb2: Waiting
2026-03-13 00:14:23.298016 | controller | 79e23c5c0fe4: Waiting
2026-03-13 00:14:24.080271 | controller | e5058b1faaa6: Waiting
2026-03-13 00:14:24.080326 | controller | 846775490569: Waiting
2026-03-13 00:14:24.080343 | controller | 419640447d26: Verifying Checksum
2026-03-13 00:14:24.421642 | controller | 419640447d26: Download complete
2026-03-13 00:14:24.421721 | controller | 61e52f862619: Verifying Checksum
2026-03-13 00:14:24.839063 | controller | 61e52f862619: Download complete
2026-03-13 00:14:24.839142 | controller | 851de0426e26: Verifying Checksum
2026-03-13 00:14:25.600497 | controller | 851de0426e26: Download complete
2026-03-13 00:14:25.600576 | controller | cd9b0c439616: Verifying Checksum
2026-03-13 00:14:25.600643 | controller | cd9b0c439616: Download complete
2026-03-13 00:14:26.433101 | controller | d4612dbd8855: Download complete
2026-03-13 00:14:26.986396 | controller | 171857c49d0f: Verifying Checksum
2026-03-13 00:14:27.840325 | controller | 171857c49d0f: Download complete
2026-03-13 00:14:27.840378 | controller | 1ff15d4bdb3e: Verifying Checksum
2026-03-13 00:14:28.673092 | controller | 1ff15d4bdb3e: Download complete
2026-03-13 00:14:28.673145 | controller | 5f2e4724adea: Verifying Checksum
2026-03-13 00:14:28.673382 | controller | 5f2e4724adea: Download complete
2026-03-13 00:14:30.064739 | controller | a02d9a95ccb8: Verifying Checksum
2026-03-13 00:14:30.500891 | controller | a02d9a95ccb8: Download complete
2026-03-13 00:14:30.500960 | controller | 171857c49d0f: Pull complete
2026-03-13 00:14:30.533725 | controller | 419640447d26: Pull complete
2026-03-13 00:14:30.561891 | controller | 61e52f862619: Pull complete
2026-03-13 00:14:30.601786 | controller | 851de0426e26: Pull complete
2026-03-13 00:14:30.831319 | controller | 989f4ce370cb: Verifying Checksum
2026-03-13 00:14:31.618601 | controller | 989f4ce370cb: Download complete
2026-03-13 00:14:31.618660 | controller | 0d7f52cdf9df: Verifying Checksum
2026-03-13 00:14:32.376110 | controller | 0d7f52cdf9df: Download complete
2026-03-13 00:14:32.376179 | controller | c423742351d5: Verifying Checksum
2026-03-13 00:14:38.117982 | controller | c423742351d5: Download complete
2026-03-13 00:14:38.118037 | controller | 554699364730: Verifying Checksum
2026-03-13 00:14:38.946131 | controller | 554699364730: Download complete
2026-03-13 00:14:38.946226 | controller | 07a2f61c96b8: Verifying Checksum
2026-03-13 00:14:39.707490 | controller | 07a2f61c96b8: Download complete
2026-03-13 00:14:39.707548 | controller | 041f122f27ae: Verifying Checksum
2026-03-13 00:14:40.507283 | controller | 041f122f27ae: Download complete
2026-03-13 00:14:40.507355 | controller | 109236341d21: Verifying Checksum
2026-03-13 00:14:40.507444 | controller | 109236341d21: Download complete
2026-03-13 00:14:41.370107 | controller | ca6f5098b12e: Verifying Checksum
2026-03-13 00:14:41.370334 | controller | ca6f5098b12e: Download complete
2026-03-13 00:14:41.862017 | controller | 1eb3e7b2afd9: Verifying Checksum
2026-03-13 00:14:41.862094 | controller | 1eb3e7b2afd9: Download complete
2026-03-13 00:14:42.573370 | controller | 17f2f596716c: Verifying Checksum
2026-03-13 00:14:42.573506 | controller | 17f2f596716c: Download complete
2026-03-13 00:14:42.844895 | controller | 18a0b750727a: Verifying Checksum
2026-03-13 00:14:42.844975 | controller | 18a0b750727a: Download complete
2026-03-13 00:14:43.289081 | controller | f35a9998a9bd: Verifying Checksum
2026-03-13 00:14:43.289160 | controller | f35a9998a9bd: Download complete
2026-03-13 00:14:43.636912 | controller | fdfc10566405: Verifying Checksum
2026-03-13 00:14:43.637058 | controller | fdfc10566405: Download complete
2026-03-13 00:14:44.027984 | controller | 1ff1c442c5fe: Verifying Checksum
2026-03-13 00:14:44.028133 | controller | 1ff1c442c5fe: Download complete
2026-03-13 00:14:50.565261 | controller | 0edcf9fb2b15: Verifying Checksum
2026-03-13 00:14:50.565379 | controller | 0edcf9fb2b15: Download complete
2026-03-13 00:14:51.564921 | controller | a3443c4a17ed: Verifying Checksum
2026-03-13 00:14:51.565122 | controller | a3443c4a17ed: Download complete
2026-03-13 00:14:55.355539 | controller | 37425716ea5b: Verifying Checksum
2026-03-13 00:14:55.357786 | controller | 37425716ea5b: Download complete
2026-03-13 00:14:57.377738 | controller | 1651773effb2: Verifying Checksum
2026-03-13 00:14:57.379254 | controller | 1651773effb2: Download complete
2026-03-13 00:14:58.719224 | controller | 5af43b69e845: Verifying Checksum
2026-03-13 00:14:58.719276 | controller | 5af43b69e845: Download complete
2026-03-13 00:14:59.207999 | controller | 79e23c5c0fe4: Verifying Checksum
2026-03-13 00:15:01.018020 | controller | 79e23c5c0fe4: Download complete
2026-03-13 00:15:01.018094 | controller | e5058b1faaa6: Verifying Checksum
2026-03-13 00:15:05.673445 | controller | e5058b1faaa6: Download complete
2026-03-13 00:15:05.673505 | controller | 846775490569: Verifying Checksum
2026-03-13 00:15:14.411822 | controller | 846775490569: Download complete
2026-03-13 00:15:14.411892 | controller | 37425716ea5b: Pull complete
2026-03-13 00:15:14.441594 | controller | cd9b0c439616: Pull complete
2026-03-13 00:15:14.470564 | controller | d4612dbd8855: Pull complete
2026-03-13 00:15:19.281025 | controller | 554699364730: Pull complete
2026-03-13 00:15:19.310494 | controller | 1ff15d4bdb3e: Pull complete
2026-03-13 00:15:19.345411 | controller | 5f2e4724adea: Pull complete
2026-03-13 00:15:19.403149 | controller | a02d9a95ccb8: Pull complete
2026-03-13 00:15:19.430853 | controller | 989f4ce370cb: Pull complete
2026-03-13 00:15:19.463848 | controller | 0d7f52cdf9df: Pull complete
2026-03-13 00:15:19.491917 | controller | c423742351d5: Pull complete
2026-03-13 00:15:21.932100 | controller | 109236341d21: Pull complete
2026-03-13 00:15:21.954697 | controller | 07a2f61c96b8: Pull complete
2026-03-13 00:15:21.979029 | controller | 041f122f27ae: Pull complete
2026-03-13 00:15:22.149725 | controller | ca6f5098b12e: Pull complete
2026-03-13 00:15:22.200824 | controller | 1eb3e7b2afd9: Pull complete
2026-03-13 00:15:22.266006 | controller | 18a0b750727a: Pull complete
2026-03-13 00:15:22.288576 | controller | 17f2f596716c: Pull complete
2026-03-13 00:15:22.312694 | controller | f35a9998a9bd: Pull complete
2026-03-13 00:15:22.339829 | controller | fdfc10566405: Pull complete
2026-03-13 00:15:22.363456 | controller | 1ff1c442c5fe: Pull complete
2026-03-13 00:15:24.038307 | controller | a3443c4a17ed: Pull complete
2026-03-13 00:15:26.043605 | controller | 0edcf9fb2b15: Pull complete
2026-03-13 00:15:29.082390 | controller | 5af43b69e845: Pull complete
2026-03-13 00:15:33.869288 | controller | 1651773effb2: Pull complete
2026-03-13 00:15:36.656979 | controller | 79e23c5c0fe4: Pull complete
2026-03-13 00:15:38.601552 | controller | e5058b1faaa6: Pull complete
2026-03-13 00:15:41.411744 | controller | 846775490569: Pull complete
2026-03-13 00:15:41.437824 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2026-03-13 00:15:41.441597 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2026-03-13 00:15:41.449018 | controller | quay.io/ansible/default-test-container:2.9.0
2026-03-13 00:15:41.454283 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2026-03-13 00:15:41.477291 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2026-03-13 00:15:42.991406 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2026-03-13 00:15:42.991593 | controller | 04a5f4cda3ee: Pulling fs layer
2026-03-13 00:15:42.991671 | controller | ff496a88c8ed: Pulling fs layer
2026-03-13 00:15:42.991733 | controller | 0ce83f459fe7: Pulling fs layer
2026-03-13 00:15:42.991791 | controller | 2e5170e1f099: Pulling fs layer
2026-03-13 00:15:42.991860 | controller | 7641eb41b08c: Pulling fs layer
2026-03-13 00:15:42.991930 | controller | ad15fa9da398: Pulling fs layer
2026-03-13 00:15:42.992008 | controller | 087d91352424: Pulling fs layer
2026-03-13 00:15:42.992082 | controller | 8b92efd6a100: Pulling fs layer
2026-03-13 00:15:42.992152 | controller | 2e5170e1f099: Waiting
2026-03-13 00:15:42.992250 | controller | 7641eb41b08c: Waiting
2026-03-13 00:15:42.992326 | controller | ad15fa9da398: Waiting
2026-03-13 00:15:42.992402 | controller | 087d91352424: Waiting
2026-03-13 00:15:42.992485 | controller | 8b92efd6a100: Waiting
2026-03-13 00:15:43.297294 | controller | 0ce83f459fe7: Verifying Checksum
2026-03-13 00:15:43.297372 | controller | 0ce83f459fe7: Download complete
2026-03-13 00:15:43.302375 | controller | ff496a88c8ed: Verifying Checksum
2026-03-13 00:15:43.302444 | controller | ff496a88c8ed: Download complete
2026-03-13 00:15:43.568873 | controller | 04a5f4cda3ee: Download complete
2026-03-13 00:15:43.615874 | controller | 7641eb41b08c: Verifying Checksum
2026-03-13 00:15:43.616028 | controller | 7641eb41b08c: Download complete
2026-03-13 00:15:43.885477 | controller | 2e5170e1f099: Verifying Checksum
2026-03-13 00:15:43.885589 | controller | 2e5170e1f099: Download complete
2026-03-13 00:15:43.919272 | controller | ad15fa9da398: Verifying Checksum
2026-03-13 00:15:43.919352 | controller | ad15fa9da398: Download complete
2026-03-13 00:15:44.056102 | controller | 087d91352424: Verifying Checksum
2026-03-13 00:15:44.056362 | controller | 087d91352424: Download complete
2026-03-13 00:15:44.195621 | controller | 8b92efd6a100: Verifying Checksum
2026-03-13 00:15:44.195800 | controller | 8b92efd6a100: Download complete
2026-03-13 00:15:45.626822 | controller | 04a5f4cda3ee: Pull complete
2026-03-13 00:15:45.651301 | controller | ff496a88c8ed: Pull complete
2026-03-13 00:15:45.673861 | controller | 0ce83f459fe7: Pull complete
2026-03-13 00:15:47.190329 | controller | 2e5170e1f099: Pull complete
2026-03-13 00:15:47.220329 | controller | 7641eb41b08c: Pull complete
2026-03-13 00:15:47.249942 | controller | ad15fa9da398: Pull complete
2026-03-13 00:15:48.195729 | controller | 087d91352424: Pull complete
2026-03-13 00:15:48.232100 | controller | 8b92efd6a100: Pull complete
2026-03-13 00:15:48.255598 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2026-03-13 00:15:48.261292 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2026-03-13 00:15:48.270608 | controller | quay.io/ansible/pypi-test-container:1.0.0
2026-03-13 00:15:48.272914 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2026-03-13 00:15:56.420535 | controller | Run command: docker inspect d325280df90754b7838e093fda79dc6aa9c9cc19fa83cc5c929dcac1075cd4d5
2026-03-13 00:15:56.499299 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2026-03-13 00:15:56.514557 | controller | Including collection: ansible.netcommon (278 files)
2026-03-13 00:15:56.526916 | controller | Including collection: ansible.network (23 files)
2026-03-13 00:15:56.526976 | controller | Including collection: ansible.utils (441 files)
2026-03-13 00:15:56.569376 | controller | Including collection: arista.eos (1031 files)
2026-03-13 00:15:56.621332 | controller | Including collection: cisco.ios (1215 files)
2026-03-13 00:15:56.670918 | controller | Including collection: cisco.iosxr (1044 files)
2026-03-13 00:15:56.757423 | controller | Including collection: cisco.nxos (1687 files)
2026-03-13 00:15:56.778111 | controller | Including collection: juniper.device (391 files)
2026-03-13 00:15:56.813531 | controller | Including collection: junipernetworks.junos (700 files)
2026-03-13 00:15:56.865874 | controller | Creating a payload archive containing 7536 files...
2026-03-13 00:15:59.091286 | controller | Created a 6222458 byte payload archive containing 7536 files in 2 seconds.
2026-03-13 00:15:59.095152 | controller | Assuming Docker is available on localhost.
2026-03-13 00:15:59.095411 | controller | Run command: docker run --detach --volume /sys/fs/cgroup:/sys/fs/cgroup:ro --privileged=false --security-opt seccomp=unconfined --volume /var/run/docker.sock:/var/run/docker.sock quay.io/ansible/default-test-container:2.9.0
2026-03-13 00:15:59.605767 | controller | Run command: docker exec -i 67b6460daf3f0a18d22db1d60d10379ce59fb5d565314fefc741667e45d79e7e dd of=/root/docker.sh bs=65536
2026-03-13 00:15:59.692663 | controller | Run command: docker exec 67b6460daf3f0a18d22db1d60d10379ce59fb5d565314fefc741667e45d79e7e /bin/bash /root/docker.sh
2026-03-13 00:15:59.766344 | controller | Run command: docker exec -i 67b6460daf3f0a18d22db1d60d10379ce59fb5d565314fefc741667e45d79e7e dd of=/root/test.tgz bs=65536
2026-03-13 00:15:59.872788 | controller | Run command: docker exec 67b6460daf3f0a18d22db1d60d10379ce59fb5d565314fefc741667e45d79e7e tar oxzf /root/test.tgz -C /root
2026-03-13 00:16:00.972615 | controller | Run command: docker exec 67b6460daf3f0a18d22db1d60d10379ce59fb5d565314fefc741667e45d79e7e /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-98uux_mv.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2026-03-13 00:16:01.403436 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2026-03-13 00:16:01.404415 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2026-03-13 00:16:01.406745 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2026-03-13 00:16:01.406781 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2026-03-13 00:16:01.407662 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2026-03-13 00:16:01.414706 | controller | No tests applicable.
2026-03-13 00:16:01.414893 | controller | Running sanity test 'ansible-doc' with Python 3.6
2026-03-13 00:16:01.414910 | controller | No tests applicable.
2026-03-13 00:16:01.415000 | controller | Running sanity test 'changelog' with Python 3.6
2026-03-13 00:16:01.415616 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/ansible-test.txt
2026-03-13 00:16:02.398551 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt
2026-03-13 00:16:03.026168 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2026-03-13 00:16:18.049946 | controller |   WARNING: Retrying (Retry(total=4, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2026-03-13 00:16:33.565766 | controller |   WARNING: Retrying (Retry(total=3, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2026-03-13 00:16:49.584552 | controller |   WARNING: Retrying (Retry(total=2, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2026-03-13 00:17:06.602638 | controller |   WARNING: Retrying (Retry(total=1, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2026-03-13 00:17:25.623159 | controller |   WARNING: Retrying (Retry(total=0, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2026-03-13 00:17:40.641532 | controller |   ERROR: Could not find a version that satisfies the requirement antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2)) (from versions: none)
2026-03-13 00:17:40.641897 | controller | ERROR: No matching distribution found for antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2026-03-13 00:17:40.756767 | controller | ERROR: Command "/usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt" returned exit status 1.
2026-03-13 00:17:40.811490 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2026-03-13 00:17:40.811540 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2026-03-13 00:17:40.811550 | controller | Removing custom PyPI hosts entries: /etc/hosts
2026-03-13 00:17:40.811565 | controller | Run command: docker exec 67b6460daf3f0a18d22db1d60d10379ce59fb5d565314fefc741667e45d79e7e tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2026-03-13 00:17:40.887812 | controller | Run command: docker exec -i 67b6460daf3f0a18d22db1d60d10379ce59fb5d565314fefc741667e45d79e7e dd if=/root/output.tgz bs=65536
2026-03-13 00:17:40.963054 | controller | Run command: tar oxzf /tmp/ansible-result-hy_43m0t.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2026-03-13 00:17:40.975658 | controller | Run command: docker rm -f d325280df90754b7838e093fda79dc6aa9c9cc19fa83cc5c929dcac1075cd4d5
2026-03-13 00:17:41.249330 | controller | Run command: docker rm -f 67b6460daf3f0a18d22db1d60d10379ce59fb5d565314fefc741667e45d79e7e
2026-03-13 00:17:41.960457 | controller | ERROR: Command "docker exec 67b6460daf3f0a18d22db1d60d10379ce59fb5d565314fefc741667e45d79e7e /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-98uux_mv.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2026-03-13 00:17:42.351121 | controller | ERROR
2026-03-13 00:17:42.351295 | controller | {
2026-03-13 00:17:42.351325 | controller |   "delta": "0:03:20.952252",
2026-03-13 00:17:42.351346 | controller |   "end": "2026-03-13 00:17:42.015581",
2026-03-13 00:17:42.351366 | controller |   "msg": "non-zero return code",
2026-03-13 00:17:42.351384 | controller |   "rc": 1,
2026-03-13 00:17:42.351405 | controller |   "start": "2026-03-13 00:14:21.063329"
2026-03-13 00:17:42.351423 | controller | }
failure
2026-03-13 00:17:42.354516 | 
2026-03-13 00:17:42.354580 | PLAY RECAP
2026-03-13 00:17:42.354621 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2026-03-13 00:17:42.354641 | 
2026-03-13 00:17:42.539866 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-03-13 00:17:42.541032 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-13 00:17:43.555829 | 
2026-03-13 00:17:43.555957 | PLAY [all]
2026-03-13 00:17:43.594537 | 
2026-03-13 00:17:43.594675 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-03-13 00:17:46.807293 | controller | changed: non-zero return code
2026-03-13 00:17:46.815682 | 
2026-03-13 00:17:46.815760 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-03-13 00:17:46.843471 | controller | skipping: Conditional result was False
2026-03-13 00:17:46.850146 | 
2026-03-13 00:17:46.850226 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-03-13 00:17:46.891717 | 
2026-03-13 00:17:46.891873 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-03-13 00:17:46.943205 | 
2026-03-13 00:17:46.943372 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-03-13 00:17:46.988170 | controller | skipping: Conditional result was False
2026-03-13 00:17:46.995751 | 
2026-03-13 00:17:46.995829 | LOOP [fetch-subunit-output : Generate subunit file]
2026-03-13 00:17:47.027722 | 
2026-03-13 00:17:47.027884 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-03-13 00:17:47.042122 | controller | skipping: Conditional result was False
2026-03-13 00:17:47.050094 | 
2026-03-13 00:17:47.050172 | TASK [fetch-subunit-output : Remove the temporary file]
2026-03-13 00:17:47.099448 | controller | skipping: Conditional result was False
2026-03-13 00:17:47.105897 | 
2026-03-13 00:17:47.105963 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-03-13 00:17:47.176460 | controller | skipping: Conditional result was False
2026-03-13 00:17:47.236700 | 
2026-03-13 00:17:47.236787 | PLAY RECAP
2026-03-13 00:17:47.236828 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-13 00:17:47.236848 | 
2026-03-13 00:17:47.360134 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-13 00:17:47.361051 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-03-13 00:17:48.351178 | 
2026-03-13 00:17:48.351312 | PLAY [all:!appliance*]
2026-03-13 00:17:48.390878 | 
2026-03-13 00:17:48.391055 | TASK [unregister the node]
2026-03-13 00:17:52.455310 | controller | skipping: Conditional result was False
2026-03-13 00:17:52.466870 | 
2026-03-13 00:17:52.467060 | TASK [include_role : fetch-output]
2026-03-13 00:17:52.501972 | controller | ok
2026-03-13 00:17:52.533017 | 
2026-03-13 00:17:52.533201 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-13 00:17:52.580296 | controller | skipping: Conditional result was False
2026-03-13 00:17:52.589232 | 
2026-03-13 00:17:52.589377 | TASK [fetch-output : Set log path for single node]
2026-03-13 00:17:52.628751 | controller | ok
2026-03-13 00:17:52.634090 | 
2026-03-13 00:17:52.634154 | LOOP [fetch-output : Ensure local output dirs]
2026-03-13 00:17:53.217506 | controller -> localhost | ok: "/var/lib/zuul/builds/d84b3746c1164878bbbbaac1f5e2c704/work/logs"
2026-03-13 00:17:53.467068 | controller -> localhost | changed: "/var/lib/zuul/builds/d84b3746c1164878bbbbaac1f5e2c704/work/artifacts"
2026-03-13 00:17:53.716269 | controller -> localhost | changed: "/var/lib/zuul/builds/d84b3746c1164878bbbbaac1f5e2c704/work/docs"
2026-03-13 00:17:53.730693 | 
2026-03-13 00:17:53.730820 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-13 00:17:56.551916 | controller | changed:
2026-03-13 00:17:56.555074 | controller | .d..t...... ./
2026-03-13 00:17:56.555140 | controller | cd+++++++++ controller/
2026-03-13 00:17:56.555448 | controller | changed: All items complete
2026-03-13 00:17:56.555485 | 
2026-03-13 00:17:58.732570 | controller | changed: .d..t...... ./
2026-03-13 00:18:01.165659 | controller | changed: .d..t...... ./
2026-03-13 00:18:01.188329 | 
2026-03-13 00:18:01.188457 | TASK [include_role : fetch-output-openshift]
2026-03-13 00:18:01.228136 | controller | skipping: Conditional result was False
2026-03-13 00:18:01.234928 | 
2026-03-13 00:18:01.235044 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-13 00:18:01.354316 | controller | skipping: Conditional result was False
2026-03-13 00:18:01.355332 | controller | skipping: Conditional result was False
2026-03-13 00:18:01.409384 | 
2026-03-13 00:18:01.409508 | PLAY [localhost]
2026-03-13 00:18:01.445853 | 
2026-03-13 00:18:01.446004 | TASK [Run Zuul manifest role]
2026-03-13 00:18:01.482138 | localhost | ok
2026-03-13 00:18:01.546887 | 
2026-03-13 00:18:01.548696 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-13 00:18:02.102279 | localhost | changed
2026-03-13 00:18:02.121208 | 
2026-03-13 00:18:02.121359 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-13 00:18:02.175576 | localhost | ok
2026-03-13 00:18:02.236950 | 
2026-03-13 00:18:02.237071 | TASK [Set zuul-log-path fact]
2026-03-13 00:18:02.255259 | localhost | ok
2026-03-13 00:18:02.275760 | 
2026-03-13 00:18:02.275865 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-13 00:18:02.315588 | localhost | ok
2026-03-13 00:18:02.324896 | 
2026-03-13 00:18:02.325016 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)]
2026-03-13 00:18:02.371609 | localhost | ok:
2026-03-13 00:18:02.371776 | localhost | {
2026-03-13 00:18:02.371836 | localhost |   "auth": {
2026-03-13 00:18:02.371861 | localhost |     "password": "IonWCwcuOFib&&<}wp^:[2G/",
2026-03-13 00:18:02.371881 | localhost |     "username": "9LF91O0RK7JU"
2026-03-13 00:18:02.371900 | localhost |   },
2026-03-13 00:18:02.371964 | localhost |   "profile": "rackspace",
2026-03-13 00:18:02.371989 | localhost |   "region_name": "IAD"
2026-03-13 00:18:02.372008 | localhost | }
2026-03-13 00:18:02.372061 | 
2026-03-13 00:18:02.385752 | 
2026-03-13 00:18:02.385868 | TASK [Run upload-logs-failover role (S3 then Swift)]
2026-03-13 00:18:02.407955 | localhost | Output suppressed because no_log was given
2026-03-13 00:18:02.435762 | 
2026-03-13 00:18:02.435916 | TASK [upload-logs-failover : Initialize control vars]
2026-03-13 00:18:02.467640 | localhost | ok
2026-03-13 00:18:02.472999 | 
2026-03-13 00:18:02.473111 | LOOP [upload-logs-failover : Perform log upload]
2026-03-13 00:18:02.522598 | localhost | Output suppressed because no_log was given
2026-03-13 00:18:02.530655 | localhost | included: /var/lib/zuul/builds/d84b3746c1164878bbbbaac1f5e2c704/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml
2026-03-13 00:18:02.539203 | 
2026-03-13 00:18:02.539326 | LOOP [upload-logs-failover : Unpack vars for swift upload]
2026-03-13 00:18:02.641132 | localhost | Output suppressed because no_log was given
2026-03-13 00:18:02.651630 | 
2026-03-13 00:18:02.651788 | TASK [Upload logs to swift]
2026-03-13 00:18:02.693280 | localhost | ok
2026-03-13 00:18:02.787529 | 
2026-03-13 00:18:02.787668 | TASK [Set zuul-log-path fact]
2026-03-13 00:18:02.884413 | localhost | skipping: Conditional result was False
2026-03-13 00:18:02.889864 | 
2026-03-13 00:18:02.889960 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-03-13 00:18:03.630342 | localhost -> localhost | ok: Runtime: 0:00:00.006365
2026-03-13 00:18:03.637698 | 
2026-03-13 00:18:03.637815 | TASK [upload-logs-swift : Upload logs to swift]