2026-03-10 00:14:15.932400 | Job console starting... 2026-03-10 00:14:15.943470 | Updating repositories 2026-03-10 00:14:16.055121 | Preparing job workspace 2026-03-10 00:14:20.075051 | Running Ansible setup... 2026-03-10 00:14:24.629764 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-10 00:14:25.248489 | 2026-03-10 00:14:25.248626 | PLAY [localhost] 2026-03-10 00:14:25.258059 | 2026-03-10 00:14:25.258179 | TASK [Gathering Facts] 2026-03-10 00:14:26.272421 | localhost | ok 2026-03-10 00:14:26.335303 | 2026-03-10 00:14:26.335475 | TASK [Setup log path fact] 2026-03-10 00:14:26.353637 | localhost | ok 2026-03-10 00:14:26.384256 | 2026-03-10 00:14:26.384407 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-10 00:14:26.413679 | localhost | ok 2026-03-10 00:14:26.422019 | 2026-03-10 00:14:26.422093 | TASK [emit-job-header : Print job information] 2026-03-10 00:14:26.450518 | # Job Information 2026-03-10 00:14:26.450674 | Ansible Version: 2.15.12 2026-03-10 00:14:26.450704 | Job: ansible-test-sanity-docker-stable-2.11 2026-03-10 00:14:26.450741 | Pipeline: periodic 2026-03-10 00:14:26.450764 | Executor: ze03.softwarefactory-project.io 2026-03-10 00:14:26.450782 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-03-10 00:14:26.450802 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/d25/ansible/d25365426be749e2996dd544316a083d/ 2026-03-10 00:14:26.450833 | Event ID: e6ee9e03205a430c87790b3a203d0d50 2026-03-10 00:14:26.454492 | 2026-03-10 00:14:26.454554 | LOOP [emit-job-header : Print node information] 2026-03-10 00:14:26.551882 | localhost | ok: 2026-03-10 00:14:26.552181 | localhost | # Node Information 2026-03-10 00:14:26.552217 | localhost | Inventory Hostname: controller 2026-03-10 00:14:26.552237 | localhost | Hostname: np0005642992 2026-03-10 00:14:26.552256 | localhost | Username: zuul 2026-03-10 00:14:26.552277 | localhost | Distro: Fedora 37 2026-03-10 00:14:26.552295 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-03-10 00:14:26.552312 | localhost | Region: ca-ymq-1 2026-03-10 00:14:26.552328 | localhost | Label: ansible-fedora-37-1vcpu 2026-03-10 00:14:26.552344 | localhost | Product Name: OpenStack Nova 2026-03-10 00:14:26.552360 | localhost | Interface IP: 162.253.55.62 2026-03-10 00:14:26.581299 | 2026-03-10 00:14:26.581469 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-10 00:14:27.037331 | localhost -> localhost | changed 2026-03-10 00:14:27.045099 | 2026-03-10 00:14:27.045225 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-10 00:14:27.953004 | localhost -> localhost | changed 2026-03-10 00:14:27.988229 | 2026-03-10 00:14:27.988361 | PLAY [all:!appliance*] 2026-03-10 00:14:28.018534 | 2026-03-10 00:14:28.018691 | TASK [include_role : start-zuul-console] 2026-03-10 00:14:28.055010 | controller | ok 2026-03-10 00:14:28.079351 | 2026-03-10 00:14:28.079476 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-03-10 00:14:28.484256 | controller | ok 2026-03-10 00:14:28.510055 | 2026-03-10 00:14:28.510261 | TASK [use-our-mirror : Retrieve the IP address] 2026-03-10 00:14:29.482052 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-03-10 00:14:29.497021 | 2026-03-10 00:14:29.497191 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-03-10 00:14:30.044827 | controller | skipping: Conditional result was False 2026-03-10 00:14:30.052375 | 2026-03-10 00:14:30.052485 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-03-10 00:14:30.076464 | controller | skipping: Conditional result was False 2026-03-10 00:14:30.083412 | 2026-03-10 00:14:30.083524 | TASK [use-our-mirror : Create the podman configuration directory] 2026-03-10 00:14:30.107341 | controller | skipping: Conditional result was False 2026-03-10 00:14:30.114112 | 2026-03-10 00:14:30.114203 | TASK [use-our-mirror : Copy the podman configuration] 2026-03-10 00:14:30.137706 | controller | skipping: Conditional result was False 2026-03-10 00:14:30.144161 | 2026-03-10 00:14:30.144252 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-03-10 00:14:30.167954 | controller | skipping: Conditional result was False 2026-03-10 00:14:30.184292 | 2026-03-10 00:14:30.184568 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-03-10 00:14:30.211317 | controller | skipping: Conditional result was False 2026-03-10 00:14:30.230491 | 2026-03-10 00:14:30.230649 | TASK [Disable Fedora Modular] 2026-03-10 00:14:30.480768 | controller | changed 2026-03-10 00:14:30.489168 | 2026-03-10 00:14:30.489287 | TASK [Enable EPEL] 2026-03-10 00:14:30.504938 | controller | skipping: Conditional result was False 2026-03-10 00:14:30.519381 | 2026-03-10 00:14:30.519495 | TASK [Register the RHEL node] 2026-03-10 00:14:31.081443 | 2026-03-10 00:14:31.081720 | TASK [Show the subscription-manager status] 2026-03-10 00:14:31.667185 | controller | skipping: Conditional result was False 2026-03-10 00:14:31.674545 | 2026-03-10 00:14:31.674644 | TASK [Enable EPEL on RHEL] 2026-03-10 00:14:32.213878 | controller | skipping: Conditional result was False 2026-03-10 00:14:32.228248 | 2026-03-10 00:14:32.228399 | TASK [Install git and tox] 2026-03-10 00:15:52.612876 | controller | changed 2026-03-10 00:15:52.621389 | 2026-03-10 00:15:52.621479 | TASK [include_role : prepare-workspace] 2026-03-10 00:15:52.642030 | controller | ok 2026-03-10 00:15:52.663109 | 2026-03-10 00:15:52.663214 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-10 00:15:52.895929 | controller | ok 2026-03-10 00:15:52.912099 | 2026-03-10 00:15:52.912289 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-10 00:16:05.672630 | controller | Output suppressed because no_log was given 2026-03-10 00:16:05.760900 | 2026-03-10 00:16:05.761116 | TASK [include_role : prepare-workspace-openshift] 2026-03-10 00:16:05.782574 | controller | skipping: Conditional result was False 2026-03-10 00:16:05.809500 | 2026-03-10 00:16:05.809618 | PLAY [all:!appliance] 2026-03-10 00:16:05.833669 | 2026-03-10 00:16:05.833898 | TASK [Run add-build-sshkey role (RSA)] 2026-03-10 00:16:05.864553 | controller | ok 2026-03-10 00:16:05.880578 | 2026-03-10 00:16:05.880691 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-10 00:16:06.182658 | controller -> localhost | ok 2026-03-10 00:16:06.192193 | 2026-03-10 00:16:06.192312 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-10 00:16:06.215626 | controller | ok 2026-03-10 00:16:06.232431 | controller | included: /var/lib/zuul/builds/d25365426be749e2996dd544316a083d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-10 00:16:06.239526 | 2026-03-10 00:16:06.239607 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-10 00:16:06.716268 | controller -> localhost | Generating public/private rsa key pair. 2026-03-10 00:16:06.716608 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/d25365426be749e2996dd544316a083d/work/d25365426be749e2996dd544316a083d_id_rsa. 2026-03-10 00:16:06.716670 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/d25365426be749e2996dd544316a083d/work/d25365426be749e2996dd544316a083d_id_rsa.pub. 2026-03-10 00:16:06.716714 | controller -> localhost | The key fingerprint is: 2026-03-10 00:16:06.716790 | controller -> localhost | SHA256:bn3Yukso627S18H6z3PB0kWOsuI5jkb54XCYUnG4IqQ zuul-build-sshkey 2026-03-10 00:16:06.716832 | controller -> localhost | The key's randomart image is: 2026-03-10 00:16:06.716871 | controller -> localhost | +---[RSA 2048]----+ 2026-03-10 00:16:06.716909 | controller -> localhost | | . | 2026-03-10 00:16:06.716949 | controller -> localhost | | . o . .| 2026-03-10 00:16:06.716987 | controller -> localhost | | o + + | 2026-03-10 00:16:06.717025 | controller -> localhost | | E . . o . . o| 2026-03-10 00:16:06.717063 | controller -> localhost | | . oS= = . | 2026-03-10 00:16:06.717116 | controller -> localhost | | ..*o*oo + | 2026-03-10 00:16:06.717164 | controller -> localhost | | ..o+O+=o. . | 2026-03-10 00:16:06.717206 | controller -> localhost | | . o++oB+. . | 2026-03-10 00:16:06.717246 | controller -> localhost | | =+o.o*=oo | 2026-03-10 00:16:06.717291 | controller -> localhost | +----[SHA256]-----+ 2026-03-10 00:16:06.717382 | controller -> localhost | ok: Runtime: 0:00:00.064269 2026-03-10 00:16:06.734530 | 2026-03-10 00:16:06.734720 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-10 00:16:06.768298 | controller | ok 2026-03-10 00:16:06.783152 | controller | included: /var/lib/zuul/builds/d25365426be749e2996dd544316a083d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-10 00:16:06.797334 | 2026-03-10 00:16:06.797462 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-10 00:16:06.812800 | controller | skipping: Conditional result was False 2026-03-10 00:16:06.823180 | 2026-03-10 00:16:06.823314 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-10 00:16:07.266536 | controller | changed 2026-03-10 00:16:07.279577 | 2026-03-10 00:16:07.279720 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-10 00:16:07.528158 | controller | ok 2026-03-10 00:16:07.541140 | 2026-03-10 00:16:07.541282 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-10 00:16:08.209964 | controller | changed 2026-03-10 00:16:08.221587 | 2026-03-10 00:16:08.221710 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-10 00:16:08.880953 | controller | changed 2026-03-10 00:16:08.894935 | 2026-03-10 00:16:08.895090 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-10 00:16:08.923055 | controller | skipping: Conditional result was False 2026-03-10 00:16:08.949005 | 2026-03-10 00:16:08.949538 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-10 00:16:09.445146 | controller -> localhost | changed 2026-03-10 00:16:09.459865 | 2026-03-10 00:16:09.459986 | TASK [add-build-sshkey : Add back temp key] 2026-03-10 00:16:09.739905 | controller -> localhost | Identity added: /var/lib/zuul/builds/d25365426be749e2996dd544316a083d/work/d25365426be749e2996dd544316a083d_id_rsa (zuul-build-sshkey) 2026-03-10 00:16:09.740100 | controller -> localhost | ok: Runtime: 0:00:00.008024 2026-03-10 00:16:09.747797 | 2026-03-10 00:16:09.747863 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-10 00:16:10.108794 | controller | ok 2026-03-10 00:16:10.131501 | 2026-03-10 00:16:10.131656 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-10 00:16:10.178814 | controller | skipping: Conditional result was False 2026-03-10 00:16:10.196772 | 2026-03-10 00:16:10.196878 | TASK [Run add-build-sshkey role (ECDSA)] 2026-03-10 00:16:10.227515 | controller | ok 2026-03-10 00:16:10.243799 | 2026-03-10 00:16:10.243869 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-10 00:16:10.483215 | controller -> localhost | ok 2026-03-10 00:16:10.491210 | 2026-03-10 00:16:10.491312 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-10 00:16:10.526912 | controller | ok 2026-03-10 00:16:10.545585 | controller | included: /var/lib/zuul/builds/d25365426be749e2996dd544316a083d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-10 00:16:10.555115 | 2026-03-10 00:16:10.555217 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-10 00:16:10.825268 | controller -> localhost | Generating public/private ecdsa key pair. 2026-03-10 00:16:10.825445 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/d25365426be749e2996dd544316a083d/work/d25365426be749e2996dd544316a083d_id_ecdsa. 2026-03-10 00:16:10.825475 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/d25365426be749e2996dd544316a083d/work/d25365426be749e2996dd544316a083d_id_ecdsa.pub. 2026-03-10 00:16:10.825504 | controller -> localhost | The key fingerprint is: 2026-03-10 00:16:10.825524 | controller -> localhost | SHA256:5vJFkjgNdlRRXJ7/rckfqA3Jr9jcmsXyIa36JXjJXcY zuul-build-sshkey 2026-03-10 00:16:10.825544 | controller -> localhost | The key's randomart image is: 2026-03-10 00:16:10.825581 | controller -> localhost | +---[ECDSA 521]---+ 2026-03-10 00:16:10.825600 | controller -> localhost | | ..o+... | 2026-03-10 00:16:10.825619 | controller -> localhost | | . .. . | 2026-03-10 00:16:10.825637 | controller -> localhost | | o . o | 2026-03-10 00:16:10.825654 | controller -> localhost | | . = . .. | 2026-03-10 00:16:10.825671 | controller -> localhost | | o S . E.| 2026-03-10 00:16:10.825688 | controller -> localhost | | + o+ * + o| 2026-03-10 00:16:10.825705 | controller -> localhost | | . ...@ O .o| 2026-03-10 00:16:10.825742 | controller -> localhost | | o .= /..o.| 2026-03-10 00:16:10.825776 | controller -> localhost | | .ooXo=+..| 2026-03-10 00:16:10.825796 | controller -> localhost | +----[SHA256]-----+ 2026-03-10 00:16:10.825839 | controller -> localhost | ok: Runtime: 0:00:00.012676 2026-03-10 00:16:10.833419 | 2026-03-10 00:16:10.833535 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-10 00:16:10.865282 | controller | ok 2026-03-10 00:16:10.873481 | controller | included: /var/lib/zuul/builds/d25365426be749e2996dd544316a083d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-10 00:16:10.884471 | 2026-03-10 00:16:10.884616 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-10 00:16:10.920076 | controller | skipping: Conditional result was False 2026-03-10 00:16:10.933077 | 2026-03-10 00:16:10.933230 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-10 00:16:11.206271 | controller | changed 2026-03-10 00:16:11.220844 | 2026-03-10 00:16:11.221036 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-10 00:16:11.454895 | controller | ok 2026-03-10 00:16:11.467807 | 2026-03-10 00:16:11.467963 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-10 00:16:12.084447 | controller | changed 2026-03-10 00:16:12.092943 | 2026-03-10 00:16:12.093105 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-10 00:16:12.679801 | controller | changed 2026-03-10 00:16:12.689011 | 2026-03-10 00:16:12.689103 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-10 00:16:12.725076 | controller | skipping: Conditional result was False 2026-03-10 00:16:12.767366 | 2026-03-10 00:16:12.767493 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-10 00:16:13.046416 | controller -> localhost | changed 2026-03-10 00:16:13.060283 | 2026-03-10 00:16:13.060458 | TASK [add-build-sshkey : Add back temp key] 2026-03-10 00:16:13.362455 | controller -> localhost | Identity added: /var/lib/zuul/builds/d25365426be749e2996dd544316a083d/work/d25365426be749e2996dd544316a083d_id_ecdsa (zuul-build-sshkey) 2026-03-10 00:16:13.362773 | controller -> localhost | ok: Runtime: 0:00:00.012222 2026-03-10 00:16:13.370584 | 2026-03-10 00:16:13.370685 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-10 00:16:13.581077 | controller | ok 2026-03-10 00:16:13.597829 | 2026-03-10 00:16:13.598088 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-10 00:16:13.637220 | controller | skipping: Conditional result was False 2026-03-10 00:16:13.652540 | 2026-03-10 00:16:13.652689 | TASK [include_role : remove-zuul-sshkey] 2026-03-10 00:16:13.679002 | controller | skipping: Conditional result was False 2026-03-10 00:16:13.690019 | 2026-03-10 00:16:13.690119 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-10 00:16:13.910030 | controller | ok: "logs" 2026-03-10 00:16:13.910272 | controller | ok: All items complete 2026-03-10 00:16:13.910299 | 2026-03-10 00:16:14.087975 | controller | ok: "artifacts" 2026-03-10 00:16:14.267543 | controller | ok: "docs" 2026-03-10 00:16:14.282833 | 2026-03-10 00:16:14.282977 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-10 00:16:14.505722 | controller | changed: "logs" 2026-03-10 00:16:14.692529 | controller | changed: "artifacts" 2026-03-10 00:16:14.896376 | controller | changed: "docs" 2026-03-10 00:16:14.948653 | 2026-03-10 00:16:14.948791 | PLAY RECAP 2026-03-10 00:16:14.948851 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-03-10 00:16:14.948888 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-10 00:16:14.948915 | 2026-03-10 00:16:15.113883 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-10 00:16:15.114962 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-10 00:16:15.785515 | 2026-03-10 00:16:15.785654 | PLAY [all] 2026-03-10 00:16:15.813074 | 2026-03-10 00:16:15.813203 | TASK [Install binary dependencies] 2026-03-10 00:16:15.875899 | controller | ok 2026-03-10 00:16:15.901777 | 2026-03-10 00:16:15.901953 | TASK [bindep : Include find tasks] 2026-03-10 00:16:15.953096 | controller | ok 2026-03-10 00:16:15.967634 | controller | included: /var/lib/zuul/builds/d25365426be749e2996dd544316a083d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-03-10 00:16:15.974955 | 2026-03-10 00:16:15.975122 | TASK [bindep : Look for bindep.txt] 2026-03-10 00:16:16.334522 | controller | ok 2026-03-10 00:16:16.341767 | 2026-03-10 00:16:16.341854 | TASK [bindep : Define bindep_file fact] 2026-03-10 00:16:16.367161 | controller | skipping: Conditional result was False 2026-03-10 00:16:16.374163 | 2026-03-10 00:16:16.374275 | TASK [bindep : Look for other-requirements.txt] 2026-03-10 00:16:16.580085 | controller | ok 2026-03-10 00:16:16.591780 | 2026-03-10 00:16:16.592280 | TASK [bindep : Define bindep_file fact] 2026-03-10 00:16:16.631296 | controller | skipping: Conditional result was False 2026-03-10 00:16:16.644210 | 2026-03-10 00:16:16.644509 | TASK [bindep : Look for bindep fallback file] 2026-03-10 00:16:16.682803 | controller | skipping: Conditional result was False 2026-03-10 00:16:16.699993 | 2026-03-10 00:16:16.700142 | TASK [bindep : Define bindep_file fact] 2026-03-10 00:16:16.767100 | controller | skipping: Conditional result was False 2026-03-10 00:16:16.773338 | 2026-03-10 00:16:16.773431 | TASK [bindep : Include bindep tasks] 2026-03-10 00:16:16.802703 | controller | skipping: Conditional result was False 2026-03-10 00:16:16.808996 | 2026-03-10 00:16:16.809089 | TASK [bindep : Include install tasks] 2026-03-10 00:16:16.832649 | controller | skipping: Conditional result was False 2026-03-10 00:16:16.841897 | 2026-03-10 00:16:16.842042 | LOOP [bindep : Include package tasks] 2026-03-10 00:16:16.904619 | 2026-03-10 00:16:16.905018 | TASK [Run test-setup role] 2026-03-10 00:16:16.930145 | controller | ok 2026-03-10 00:16:16.958079 | 2026-03-10 00:16:16.958246 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-03-10 00:21:17.011689 | controller | ok 2026-03-10 00:21:17.021265 | 2026-03-10 00:21:17.021389 | TASK [test-setup : Run tools/test-setup.sh] 2026-03-10 00:21:17.565946 | controller | skipping: Conditional result was False 2026-03-10 00:21:17.603520 | 2026-03-10 00:21:17.603652 | PLAY RECAP 2026-03-10 00:21:17.603708 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-10 00:21:17.603745 | 2026-03-10 00:21:17.730617 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-10 00:21:17.732608 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-10 00:21:18.436970 | 2026-03-10 00:21:18.437172 | PLAY [controller] 2026-03-10 00:21:18.463043 | 2026-03-10 00:21:18.463211 | TASK [Create the /root directory] 2026-03-10 00:21:19.008128 | controller | ok 2026-03-10 00:21:19.017020 | 2026-03-10 00:21:19.017152 | TASK [Install glibc-langpack-en] 2026-03-10 00:21:22.835793 | controller | ok: Nothing to do 2026-03-10 00:21:22.843349 | 2026-03-10 00:21:22.843465 | TASK [Ensure controller directory exists] 2026-03-10 00:21:23.052991 | controller | changed 2026-03-10 00:21:23.059291 | 2026-03-10 00:21:23.059372 | TASK [Install container runtime] 2026-03-10 00:21:23.126560 | controller | ok 2026-03-10 00:21:23.192646 | 2026-03-10 00:21:23.192797 | LOOP [ensure-docker : Gather variables for each operating system] 2026-03-10 00:21:23.228025 | controller | ok: "/var/lib/zuul/builds/d25365426be749e2996dd544316a083d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2026-03-10 00:21:23.246799 | 2026-03-10 00:21:23.247100 | TASK [ensure-docker : Sanity check for distro selection] 2026-03-10 00:21:23.262863 | controller | skipping: Conditional result was False 2026-03-10 00:21:23.271450 | 2026-03-10 00:21:23.271607 | TASK [ensure-docker : Sanity check for distro install] 2026-03-10 00:21:23.289783 | controller | skipping: Conditional result was False 2026-03-10 00:21:23.307506 | 2026-03-10 00:21:23.307784 | TASK [ensure-docker : Sanity check for upstream install] 2026-03-10 00:21:23.334975 | controller | skipping: Conditional result was False 2026-03-10 00:21:23.345006 | 2026-03-10 00:21:23.345141 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2026-03-10 00:21:23.370519 | controller | skipping: Conditional result was False 2026-03-10 00:21:23.382716 | 2026-03-10 00:21:23.382908 | TASK [ensure-docker : Include tasks for docker install] 2026-03-10 00:21:23.408076 | controller | ok 2026-03-10 00:21:23.421668 | controller | included: /var/lib/zuul/builds/d25365426be749e2996dd544316a083d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2026-03-10 00:21:23.434527 | 2026-03-10 00:21:23.434661 | TASK [ensure-docker : Not Implemented] 2026-03-10 00:21:23.449866 | controller | skipping: Conditional result was False 2026-03-10 00:21:23.460597 | 2026-03-10 00:21:23.460800 | TASK [Set up docker mirrors] 2026-03-10 00:21:23.484689 | controller | ok 2026-03-10 00:21:23.512204 | 2026-03-10 00:21:23.512346 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2026-03-10 00:21:23.558414 | controller | skipping: Conditional result was False 2026-03-10 00:21:23.564929 | 2026-03-10 00:21:23.565002 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2026-03-10 00:21:23.599060 | controller | skipping: Conditional result was False 2026-03-10 00:21:23.605284 | 2026-03-10 00:21:23.605350 | TASK [use-docker-mirror : Set docker_mirror fact] 2026-03-10 00:21:23.641431 | controller | skipping: Conditional result was False 2026-03-10 00:21:23.649759 | 2026-03-10 00:21:23.649846 | TASK [use-docker-mirror : Set up docker mirrors] 2026-03-10 00:21:23.674337 | controller | skipping: Conditional result was False 2026-03-10 00:21:23.690456 | 2026-03-10 00:21:23.690550 | TASK [Add all repositories] 2026-03-10 00:21:23.710145 | controller | ok 2026-03-10 00:21:23.734991 | 2026-03-10 00:21:23.735072 | LOOP [ensure-package-repositories : Install repositories] 2026-03-10 00:21:23.757882 | controller | ok: "/var/lib/zuul/builds/d25365426be749e2996dd544316a083d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2026-03-10 00:21:23.758067 | controller | ok: All items complete 2026-03-10 00:21:23.758099 | 2026-03-10 00:21:23.796987 | controller | included: /var/lib/zuul/builds/d25365426be749e2996dd544316a083d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2026-03-10 00:21:23.803277 | 2026-03-10 00:21:23.803345 | TASK [ensure-package-repositories : Create GPG key temp directory] 2026-03-10 00:21:24.127194 | controller | changed 2026-03-10 00:21:24.140546 | 2026-03-10 00:21:24.140685 | LOOP [ensure-package-repositories : Upload GPG keys] 2026-03-10 00:21:25.011926 | controller | changed: 2026-03-10 00:21:25.012317 | controller | { 2026-03-10 00:21:25.012384 | 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-10 00:21:25.012435 | controller | } 2026-03-10 00:21:25.036002 | 2026-03-10 00:21:25.036116 | LOOP [ensure-package-repositories : Import GPG keys] 2026-03-10 00:21:25.621111 | controller | changed: 2026-03-10 00:21:25.621471 | controller | { 2026-03-10 00:21:25.621502 | 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-10 00:21:25.621526 | controller | } 2026-03-10 00:21:25.621555 | controller | changed: All items complete 2026-03-10 00:21:25.621575 | 2026-03-10 00:21:25.637428 | 2026-03-10 00:21:25.637521 | TASK [ensure-package-repositories : Remove GPG temp directory] 2026-03-10 00:21:25.869696 | controller | changed 2026-03-10 00:21:25.880193 | 2026-03-10 00:21:25.880334 | LOOP [ensure-package-repositories : Add repositories] 2026-03-10 00:21:26.227207 | controller | changed: 2026-03-10 00:21:26.227419 | controller | { 2026-03-10 00:21:26.227461 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2026-03-10 00:21:26.227495 | controller | "description": "Docker CE Stable - $basearch", 2026-03-10 00:21:26.227526 | controller | "gpgcheck": true, 2026-03-10 00:21:26.227554 | controller | "name": "docker-ce-stable" 2026-03-10 00:21:26.227583 | controller | } 2026-03-10 00:21:26.250583 | 2026-03-10 00:21:26.250764 | TASK [ensure-docker : Install pre-reqs] 2026-03-10 00:21:28.633870 | controller | ok: Nothing to do 2026-03-10 00:21:28.643552 | 2026-03-10 00:21:28.643652 | TASK [ensure-docker : Remove packages] 2026-03-10 00:21:29.951020 | controller | ok: Nothing to do 2026-03-10 00:21:29.958372 | 2026-03-10 00:21:29.958456 | TASK [ensure-docker : Install upstream docker using package] 2026-03-10 00:22:15.001979 | controller | changed 2026-03-10 00:22:15.010999 | 2026-03-10 00:22:15.011099 | TASK [ensure-docker : Setup docker] 2026-03-10 00:22:15.044184 | controller | ok 2026-03-10 00:22:15.059599 | controller | included: /var/lib/zuul/builds/d25365426be749e2996dd544316a083d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2026-03-10 00:22:15.079039 | 2026-03-10 00:22:15.079178 | TASK [ensure-docker : Ensure "docker" group exists] 2026-03-10 00:22:15.469665 | controller | ok 2026-03-10 00:22:15.477212 | 2026-03-10 00:22:15.477300 | TASK [ensure-docker : Add user to docker group] 2026-03-10 00:22:15.982424 | controller | changed 2026-03-10 00:22:15.997560 | 2026-03-10 00:22:15.997811 | TASK [Update docker daemon proxy configuration] 2026-03-10 00:22:16.036012 | controller | skipping: Conditional result was False 2026-03-10 00:22:16.046020 | 2026-03-10 00:22:16.046173 | TASK [Lower default MTU] 2026-03-10 00:22:16.071991 | controller | skipping: Conditional result was False 2026-03-10 00:22:16.084021 | 2026-03-10 00:22:16.084186 | TASK [ensure-docker : Get the status of the docket.socket unit] 2026-03-10 00:22:16.456406 | controller | inactive 2026-03-10 00:22:16.661030 | controller | ok: Runtime: 0:00:00.013434 2026-03-10 00:22:16.675021 | 2026-03-10 00:22:16.675215 | TASK [ensure-docker : Get the status of the docket unit] 2026-03-10 00:22:16.933995 | controller | inactive 2026-03-10 00:22:17.232639 | controller | ok: Runtime: 0:00:00.010394 2026-03-10 00:22:17.253025 | 2026-03-10 00:22:17.253257 | TASK [ensure-docker : Restart docker] 2026-03-10 00:22:17.301444 | Trigger a Docker restart 2026-03-10 00:22:17.309860 | 2026-03-10 00:22:17.309974 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2026-03-10 00:22:17.344302 | 2026-03-10 00:22:17.344475 | TASK [ensure-docker : Install docker-compose] 2026-03-10 00:22:17.361047 | controller | skipping: Conditional result was False 2026-03-10 00:22:17.370804 | 2026-03-10 00:22:17.370937 | TASK [ensure-docker : Flush handlers before role exit] 2026-03-10 00:22:17.386265 | 2026-03-10 00:22:17.386368 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2026-03-10 00:22:18.613524 | controller | changed 2026-03-10 00:22:18.624404 | 2026-03-10 00:22:18.624501 | TASK [ensure-docker : Assure docker service is running] 2026-03-10 00:22:20.702982 | controller | changed 2026-03-10 00:22:20.711918 | 2026-03-10 00:22:20.712154 | TASK [ensure-docker : Assure docker.socket service is running] 2026-03-10 00:22:21.527229 | controller | ok 2026-03-10 00:22:21.537602 | 2026-03-10 00:22:21.537756 | TASK [ensure-docker : Correct group ownership on docker sock] 2026-03-10 00:22:22.120960 | controller | ok 2026-03-10 00:22:22.127181 | 2026-03-10 00:22:22.127260 | TASK [ensure-docker : Validate ability to talk with docker] 2026-03-10 00:22:22.709255 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2026-03-10 00:22:23.172461 | controller | ok: Runtime: 0:00:00.043664 2026-03-10 00:22:23.188916 | 2026-03-10 00:22:23.189134 | TASK [ensure-docker : Get version details] 2026-03-10 00:22:23.683057 | controller | Client: Docker Engine - Community 2026-03-10 00:22:23.683104 | controller | Version: 24.0.7 2026-03-10 00:22:23.683152 | controller | API version: 1.43 2026-03-10 00:22:23.683159 | controller | Go version: go1.20.10 2026-03-10 00:22:23.683170 | controller | Git commit: afdd53b 2026-03-10 00:22:23.683176 | controller | Built: Thu Oct 26 09:09:39 2023 2026-03-10 00:22:23.683181 | controller | OS/Arch: linux/amd64 2026-03-10 00:22:23.683193 | controller | Context: default 2026-03-10 00:22:23.683200 | controller | 2026-03-10 00:22:23.683205 | controller | Server: Docker Engine - Community 2026-03-10 00:22:23.683209 | controller | Engine: 2026-03-10 00:22:23.683216 | controller | Version: 24.0.7 2026-03-10 00:22:23.683235 | controller | API version: 1.43 (minimum version 1.12) 2026-03-10 00:22:23.683248 | controller | Go version: go1.20.10 2026-03-10 00:22:23.683253 | controller | Git commit: 311b9ff 2026-03-10 00:22:23.683257 | controller | Built: Thu Oct 26 09:08:11 2023 2026-03-10 00:22:23.683261 | controller | OS/Arch: linux/amd64 2026-03-10 00:22:23.683266 | controller | Experimental: false 2026-03-10 00:22:23.683270 | controller | containerd: 2026-03-10 00:22:23.683274 | controller | Version: 1.6.25 2026-03-10 00:22:23.683283 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2026-03-10 00:22:23.683287 | controller | runc: 2026-03-10 00:22:23.683297 | controller | Version: 1.1.10 2026-03-10 00:22:23.683302 | controller | GitCommit: v1.1.10-0-g18a0cb0 2026-03-10 00:22:23.683308 | controller | docker-init: 2026-03-10 00:22:23.683322 | controller | Version: 0.19.0 2026-03-10 00:22:23.684658 | controller | GitCommit: de40ad0 2026-03-10 00:22:23.751080 | controller | ok: Runtime: 0:00:00.078515 2026-03-10 00:22:23.766712 | 2026-03-10 00:22:23.766912 | TASK [ensure-docker : Dump installed docker details] 2026-03-10 00:22:23.809353 | {'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-10 00:22:23.606308', 'end': '2026-03-10 00:22:23.684823', 'delta': '0:00:00.078515', 'msg': '', 'zuul_log_id': 'fa163ef9-e89a-d7dc-c39f-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-10 00:22:23.826278 | 2026-03-10 00:22:23.826431 | TASK [Use buildset registry] 2026-03-10 00:22:23.852700 | controller | skipping: Conditional result was False 2026-03-10 00:22:23.869404 | 2026-03-10 00:22:23.869517 | TASK [Ensure python3.8 is present] 2026-03-10 00:22:23.896114 | controller | skipping: Conditional result was False 2026-03-10 00:22:23.913819 | 2026-03-10 00:22:23.913930 | TASK [Run our-ensure-python role (Fedora only for now)] 2026-03-10 00:22:23.940809 | controller | ok 2026-03-10 00:22:23.969230 | 2026-03-10 00:22:23.969375 | TASK [our-ensure-python : Install the right Python version (rpm)] 2026-03-10 00:22:26.046238 | controller | ok: Nothing to do 2026-03-10 00:22:26.063802 | 2026-03-10 00:22:26.064022 | TASK [our-ensure-python : Also install python3-devel] 2026-03-10 00:22:37.126022 | controller | changed 2026-03-10 00:22:37.151211 | 2026-03-10 00:22:37.151416 | TASK [Run ensure-virtualenv role] 2026-03-10 00:22:37.174793 | controller | ok 2026-03-10 00:22:37.196904 | 2026-03-10 00:22:37.197030 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2026-03-10 00:22:37.404191 | controller | /usr/bin/virtualenv 2026-03-10 00:22:37.728289 | controller | ok: Runtime: 0:00:00.003436 2026-03-10 00:22:37.742416 | 2026-03-10 00:22:37.742610 | LOOP [ensure-virtualenv : Install virtualenv package] 2026-03-10 00:22:37.764846 | controller | skipping: Conditional result was False 2026-03-10 00:22:37.765323 | controller | changed: All items complete 2026-03-10 00:22:37.765383 | 2026-03-10 00:22:37.785927 | 2026-03-10 00:22:37.786093 | TASK [Find the full path of the Python interpreter] 2026-03-10 00:22:38.015915 | controller | /usr/bin/python3.9 2026-03-10 00:22:38.340208 | controller | ok 2026-03-10 00:22:38.354418 | 2026-03-10 00:22:38.354631 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2026-03-10 00:22:40.004812 | controller | created virtual environment CPython3.9.18.final.0-64 in 823ms 2026-03-10 00:22:40.021873 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2026-03-10 00:22:40.021913 | 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-10 00:22:40.021924 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2026-03-10 00:22:40.021938 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2026-03-10 00:22:40.423475 | controller | changed 2026-03-10 00:22:40.437794 | 2026-03-10 00:22:40.437941 | TASK [Set selinux package] 2026-03-10 00:22:40.478099 | controller | ok 2026-03-10 00:22:40.489915 | 2026-03-10 00:22:40.490045 | TASK [Set selinux package (Fedora)] 2026-03-10 00:22:40.550146 | controller | ok 2026-03-10 00:22:40.562022 | 2026-03-10 00:22:40.562156 | TASK [Install selinux into virtualenv] 2026-03-10 00:22:43.552526 | controller | Collecting selinux-please-lie-to-me 2026-03-10 00:22:43.638728 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2026-03-10 00:22:44.197659 | controller | Collecting setuptools<50.0.0 2026-03-10 00:22:44.202410 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2026-03-10 00:22:44.266242 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 14.0 MB/s eta 0:00:00 2026-03-10 00:22:44.519263 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2026-03-10 00:22:44.521604 | controller | Attempting uninstall: setuptools 2026-03-10 00:22:44.526453 | controller | Found existing installation: setuptools 62.6.0 2026-03-10 00:22:44.747249 | controller | Uninstalling setuptools-62.6.0: 2026-03-10 00:22:44.777940 | controller | Successfully uninstalled setuptools-62.6.0 2026-03-10 00:22:45.729831 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2026-03-10 00:22:46.053660 | controller | 2026-03-10 00:22:46.296355 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0.1 2026-03-10 00:22:46.296393 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-03-10 00:22:46.612516 | controller | ok: Runtime: 0:00:05.445922 2026-03-10 00:22:46.619009 | 2026-03-10 00:22:46.619137 | TASK [Install pytest-forked into virtualenv] 2026-03-10 00:22:47.634168 | controller | Collecting pytest-forked 2026-03-10 00:22:47.703893 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2026-03-10 00:22:48.035747 | controller | Collecting pytest>=3.10 2026-03-10 00:22:48.052288 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2026-03-10 00:22:48.089212 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 14.6 MB/s eta 0:00:00 2026-03-10 00:22:48.160083 | controller | Collecting py 2026-03-10 00:22:48.167709 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2026-03-10 00:22:48.177732 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 11.9 MB/s eta 0:00:00 2026-03-10 00:22:48.256546 | controller | Collecting iniconfig>=1 2026-03-10 00:22:48.266511 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2026-03-10 00:22:48.337618 | controller | Collecting pluggy<2,>=1.5 2026-03-10 00:22:48.343408 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-03-10 00:22:48.411072 | controller | Collecting exceptiongroup>=1 2026-03-10 00:22:48.416480 | controller | Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB) 2026-03-10 00:22:48.544015 | controller | Collecting pygments>=2.7.2 2026-03-10 00:22:48.549755 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2026-03-10 00:22:48.583745 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 39.0 MB/s eta 0:00:00 2026-03-10 00:22:48.723129 | controller | Collecting tomli>=1 2026-03-10 00:22:48.730158 | controller | Downloading tomli-2.4.0-py3-none-any.whl (14 kB) 2026-03-10 00:22:48.848024 | controller | Collecting packaging>=20 2026-03-10 00:22:48.853376 | controller | Downloading packaging-26.0-py3-none-any.whl (74 kB) 2026-03-10 00:22:48.864383 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 9.1 MB/s eta 0:00:00 2026-03-10 00:22:48.981968 | controller | Collecting typing-extensions>=4.6.0 2026-03-10 00:22:48.988100 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2026-03-10 00:22:48.996625 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 7.0 MB/s eta 0:00:00 2026-03-10 00:22:49.203949 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2026-03-10 00:22:51.105622 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.1.0 packaging-26.0 pluggy-1.6.0 py-1.11.0 pygments-2.19.2 pytest-8.4.2 pytest-forked-1.6.0 tomli-2.4.0 typing-extensions-4.15.0 2026-03-10 00:22:51.109898 | controller | 2026-03-10 00:22:51.183173 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0.1 2026-03-10 00:22:51.183187 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-03-10 00:22:51.653892 | controller | ok: Runtime: 0:00:04.143696 2026-03-10 00:22:51.663534 | 2026-03-10 00:22:51.663676 | TASK [Update pip] 2026-03-10 00:22:52.962205 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2026-03-10 00:22:53.337567 | controller | Collecting pip 2026-03-10 00:22:53.425267 | controller | Downloading pip-26.0.1-py3-none-any.whl (1.8 MB) 2026-03-10 00:22:53.538656 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 17.4 MB/s eta 0:00:00 2026-03-10 00:22:53.704079 | controller | Installing collected packages: pip 2026-03-10 00:22:53.707805 | controller | Attempting uninstall: pip 2026-03-10 00:22:53.708555 | controller | Found existing installation: pip 22.2.2 2026-03-10 00:22:54.240712 | controller | Uninstalling pip-22.2.2: 2026-03-10 00:22:54.287601 | controller | Successfully uninstalled pip-22.2.2 2026-03-10 00:22:56.493826 | controller | Successfully installed pip-26.0.1 2026-03-10 00:22:56.744671 | controller | ok: Runtime: 0:00:04.573634 2026-03-10 00:22:56.752740 | 2026-03-10 00:22:56.752849 | TASK [Ensure pip temp directory exists in /var/tmp] 2026-03-10 00:22:57.343943 | controller | changed 2026-03-10 00:22:57.358362 | 2026-03-10 00:22:57.358530 | TASK [Install ansible into virtualenv] 2026-03-10 00:22:58.797688 | controller | Processing ./src/github.com/ansible/ansible 2026-03-10 00:22:58.805908 | controller | Installing build dependencies: started 2026-03-10 00:23:00.937396 | controller | Installing build dependencies: finished with status 'done' 2026-03-10 00:23:00.938277 | controller | Getting requirements to build wheel: started 2026-03-10 00:23:02.022447 | controller | Getting requirements to build wheel: finished with status 'done' 2026-03-10 00:23:02.023642 | controller | Preparing metadata (pyproject.toml): started 2026-03-10 00:23:02.504466 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2026-03-10 00:23:38.744351 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2026-03-10 00:23:50.995962 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2026-03-10 00:23:51.081619 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2026-03-10 00:23:51.085229 | controller | Downloading pyyaml-6.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.4 kB) 2026-03-10 00:23:51.372974 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2026-03-10 00:23:51.377807 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2026-03-10 00:23:51.422989 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (26.0) 2026-03-10 00:23:51.449547 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2026-03-10 00:23:51.455001 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2026-03-10 00:23:51.619345 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2026-03-10 00:23:51.623650 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2026-03-10 00:23:51.635366 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.9/site-packages (from cryptography->ansible-core==2.11.12.post0) (4.15.0) 2026-03-10 00:23:51.656072 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2026-03-10 00:23:51.660173 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2026-03-10 00:23:51.751594 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2026-03-10 00:23:51.755839 | controller | Downloading markupsafe-3.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.7 kB) 2026-03-10 00:23:51.776816 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2026-03-10 00:23:51.792997 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2026-03-10 00:23:51.851060 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 92.0 MB/s 0:00:00 2026-03-10 00:23:51.856769 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2026-03-10 00:23:51.871902 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2026-03-10 00:23:51.888367 | controller | Downloading markupsafe-3.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (20 kB) 2026-03-10 00:23:51.904546 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2026-03-10 00:23:51.922358 | controller | Downloading pyyaml-6.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (750 kB) 2026-03-10 00:23:51.938227 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 42.0 MB/s 0:00:00 2026-03-10 00:23:51.951529 | controller | Building wheels for collected packages: ansible-core 2026-03-10 00:23:51.952232 | controller | Building wheel for ansible-core (pyproject.toml): started 2026-03-10 00:23:53.386430 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2026-03-10 00:23:53.388990 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961250 sha256=d60708239cb259657b7a18ea588ca3b97dd07989b930020f736ac4f37d451d76 2026-03-10 00:23:53.389152 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-jzwem6he/wheels/0f/a0/93/ab85b739e1eb1e0467171316f13571c14efdf87e0d5d44a33e 2026-03-10 00:23:53.397692 | controller | Successfully built ansible-core 2026-03-10 00:23:53.428771 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2026-03-10 00:23:54.875155 | controller | 2026-03-10 00:23:54.878278 | controller | Successfully installed MarkupSafe-3.0.3 PyYAML-6.0.3 ansible-core-2.11.12.post0 cffi-2.0.0 cryptography-46.0.5 jinja2-3.1.6 pycparser-2.23 resolvelib-0.5.4 2026-03-10 00:23:55.455210 | controller | ok: Runtime: 0:00:57.079181 2026-03-10 00:23:55.469436 | 2026-03-10 00:23:55.469571 | TASK [Setup download-artifact-fork role] 2026-03-10 00:23:55.499694 | controller | ok 2026-03-10 00:23:55.533786 | 2026-03-10 00:23:55.533900 | TASK [download-artifact-fork : Parse inventory response] 2026-03-10 00:23:55.586821 | controller | ok 2026-03-10 00:23:55.595300 | 2026-03-10 00:23:55.595386 | TASK [download-artifact-fork : Fetch info from Zuul API] 2026-03-10 00:23:55.620779 | controller | skipping: Conditional result was False 2026-03-10 00:23:55.637511 | 2026-03-10 00:23:55.637769 | TASK [download-artifact-fork : Parse build response] 2026-03-10 00:23:55.677554 | controller | skipping: Conditional result was False 2026-03-10 00:23:55.693861 | 2026-03-10 00:23:55.694031 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2026-03-10 00:23:55.931438 | controller | changed 2026-03-10 00:23:55.944939 | 2026-03-10 00:23:55.945433 | LOOP [download-artifact-fork : Download archive by type] 2026-03-10 00:23:55.983324 | controller | skipping: Conditional result was False 2026-03-10 00:23:55.983931 | 2026-03-10 00:23:57.314003 | controller | changed: OK (20206 bytes) 2026-03-10 00:23:57.348000 | 2026-03-10 00:23:57.348158 | TASK [deploy-artifacts : define __collections] 2026-03-10 00:23:57.373199 | controller | ok 2026-03-10 00:23:57.380775 | 2026-03-10 00:23:57.380859 | LOOP [deploy-artifacts : Create list of collections] 2026-03-10 00:23:57.412974 | controller | skipping: Conditional result was False 2026-03-10 00:23:57.423809 | controller | ok: 2026-03-10 00:23:57.423949 | controller | { 2026-03-10 00:23:57.423982 | controller | "branch": "main", 2026-03-10 00:23:57.424007 | controller | "job": "build-ansible-collection", 2026-03-10 00:23:57.424030 | controller | "metadata": { 2026-03-10 00:23:57.424052 | controller | "type": "ansible_collection", 2026-03-10 00:23:57.424073 | controller | "version": "5.0.0" 2026-03-10 00:23:57.424093 | controller | }, 2026-03-10 00:23:57.424114 | controller | "name": "ansible.network", 2026-03-10 00:23:57.424135 | controller | "project": "ansible-collections/ansible.network", 2026-03-10 00:23:57.424156 | controller | "url": "https://0a64323374d2e07c7a8c-e0f44979a43c218ab483ad80f6c0c8d7.ssl.cf5.rackcdn.com/ansible/c434d4abf31a4274b9311d575049e04b/artifacts/ansible-network-5.0.0.tar.gz" 2026-03-10 00:23:57.424186 | controller | } 2026-03-10 00:23:57.433795 | 2026-03-10 00:23:57.433896 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2026-03-10 00:23:58.047141 | controller | Starting galaxy collection install process 2026-03-10 00:23:58.047373 | controller | Process install dependency map 2026-03-10 00:24:53.737647 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-03-10 00:24:53.782738 | controller | unexpected error when getting available versions of collection 2026-03-10 00:24:53.782821 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2026-03-10 00:24:53.782835 | controller | ansible/netcommon/versions/' 2026-03-10 00:24:53.782847 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2026-03-10 00:24:53.782861 | controller | to see the full traceback, use -vvv 2026-03-10 00:24:54.017823 | controller | ERROR 2026-03-10 00:24:54.018161 | controller | { 2026-03-10 00:24:54.018231 | controller | "delta": "0:00:56.125523", 2026-03-10 00:24:54.018275 | controller | "end": "2026-03-10 00:24:53.783388", 2026-03-10 00:24:54.018315 | controller | "msg": "non-zero return code", 2026-03-10 00:24:54.018386 | controller | "rc": 250, 2026-03-10 00:24:54.018424 | controller | "start": "2026-03-10 00:23:57.657865" 2026-03-10 00:24:54.018458 | controller | } failure 2026-03-10 00:24:54.021981 | 2026-03-10 00:24:54.022082 | PLAY RECAP 2026-03-10 00:24:54.022168 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2026-03-10 00:24:54.022216 | 2026-03-10 00:24:54.166741 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-10 00:24:54.167756 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-10 00:24:54.754936 | 2026-03-10 00:24:54.755068 | PLAY [all] 2026-03-10 00:24:54.776802 | 2026-03-10 00:24:54.776931 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-03-10 00:24:55.205437 | controller | changed: non-zero return code 2026-03-10 00:24:55.218559 | 2026-03-10 00:24:55.218718 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-03-10 00:24:55.235632 | controller | skipping: Conditional result was False 2026-03-10 00:24:55.250078 | 2026-03-10 00:24:55.250256 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-03-10 00:24:55.283513 | 2026-03-10 00:24:55.283963 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-03-10 00:24:55.325463 | 2026-03-10 00:24:55.325784 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-03-10 00:24:55.342378 | controller | skipping: Conditional result was False 2026-03-10 00:24:55.359803 | 2026-03-10 00:24:55.359973 | LOOP [fetch-subunit-output : Generate subunit file] 2026-03-10 00:24:55.399793 | 2026-03-10 00:24:55.400104 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-03-10 00:24:55.426396 | controller | skipping: Conditional result was False 2026-03-10 00:24:55.439993 | 2026-03-10 00:24:55.440148 | TASK [fetch-subunit-output : Remove the temporary file] 2026-03-10 00:24:55.457876 | controller | skipping: Conditional result was False 2026-03-10 00:24:55.472208 | 2026-03-10 00:24:55.472406 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-03-10 00:24:55.486535 | controller | skipping: Conditional result was False 2026-03-10 00:24:55.517203 | 2026-03-10 00:24:55.517296 | PLAY RECAP 2026-03-10 00:24:55.517345 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-10 00:24:55.517374 | 2026-03-10 00:24:55.618795 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-10 00:24:55.619888 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-03-10 00:24:56.200178 | 2026-03-10 00:24:56.200305 | PLAY [all:!appliance*] 2026-03-10 00:24:56.223564 | 2026-03-10 00:24:56.223693 | TASK [unregister the node] 2026-03-10 00:24:56.756336 | controller | skipping: Conditional result was False 2026-03-10 00:24:56.764799 | 2026-03-10 00:24:56.764917 | TASK [include_role : fetch-output] 2026-03-10 00:24:56.817098 | controller | ok 2026-03-10 00:24:56.856417 | 2026-03-10 00:24:56.856596 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-10 00:24:56.912445 | controller | skipping: Conditional result was False 2026-03-10 00:24:56.921991 | 2026-03-10 00:24:56.922118 | TASK [fetch-output : Set log path for single node] 2026-03-10 00:24:56.969499 | controller | ok 2026-03-10 00:24:56.982571 | 2026-03-10 00:24:56.982832 | LOOP [fetch-output : Ensure local output dirs] 2026-03-10 00:24:57.414392 | controller -> localhost | ok: "/var/lib/zuul/builds/d25365426be749e2996dd544316a083d/work/logs" 2026-03-10 00:24:57.683943 | controller -> localhost | changed: "/var/lib/zuul/builds/d25365426be749e2996dd544316a083d/work/artifacts" 2026-03-10 00:24:57.916622 | controller -> localhost | changed: "/var/lib/zuul/builds/d25365426be749e2996dd544316a083d/work/docs" 2026-03-10 00:24:57.931254 | 2026-03-10 00:24:57.931406 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-10 00:24:58.733212 | controller | changed: 2026-03-10 00:24:58.733645 | controller | .d..t...... ./ 2026-03-10 00:24:58.733707 | controller | cd+++++++++ controller/ 2026-03-10 00:24:58.733814 | controller | changed: All items complete 2026-03-10 00:24:58.733859 | 2026-03-10 00:24:59.296840 | controller | changed: .d..t...... ./ 2026-03-10 00:24:59.870112 | controller | changed: .d..t...... ./ 2026-03-10 00:24:59.893959 | 2026-03-10 00:24:59.894052 | TASK [include_role : fetch-output-openshift] 2026-03-10 00:24:59.909012 | controller | skipping: Conditional result was False 2026-03-10 00:24:59.917347 | 2026-03-10 00:24:59.917436 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-10 00:24:59.943982 | controller | skipping: Conditional result was False 2026-03-10 00:24:59.952265 | controller | skipping: Conditional result was False 2026-03-10 00:24:59.999697 | 2026-03-10 00:24:59.999869 | PLAY [localhost] 2026-03-10 00:25:00.016180 | 2026-03-10 00:25:00.016261 | TASK [Run Zuul manifest role] 2026-03-10 00:25:00.037323 | localhost | ok 2026-03-10 00:25:00.053589 | 2026-03-10 00:25:00.053658 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-10 00:25:00.444497 | localhost | changed 2026-03-10 00:25:00.454964 | 2026-03-10 00:25:00.455123 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-10 00:25:00.503434 | localhost | ok 2026-03-10 00:25:00.523597 | 2026-03-10 00:25:00.523797 | TASK [Set zuul-log-path fact] 2026-03-10 00:25:00.547203 | localhost | ok 2026-03-10 00:25:00.567921 | 2026-03-10 00:25:00.568068 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-10 00:25:00.600923 | localhost | ok 2026-03-10 00:25:00.613854 | 2026-03-10 00:25:00.613986 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)] 2026-03-10 00:25:00.645958 | localhost | ok: 2026-03-10 00:25:00.646206 | localhost | { 2026-03-10 00:25:00.646242 | localhost | "auth": { 2026-03-10 00:25:00.646275 | localhost | "password": "IonWCwcuOFib&&<}wp^:[2G/", 2026-03-10 00:25:00.646301 | localhost | "username": "9LF91O0RK7JU" 2026-03-10 00:25:00.646325 | localhost | }, 2026-03-10 00:25:00.646350 | localhost | "profile": "rackspace", 2026-03-10 00:25:00.646374 | localhost | "region_name": "ORD" 2026-03-10 00:25:00.646397 | localhost | } 2026-03-10 00:25:00.646428 | 2026-03-10 00:25:00.658402 | 2026-03-10 00:25:00.658552 | TASK [Run upload-logs-failover role (S3 then Swift)] 2026-03-10 00:25:00.701506 | localhost | Output suppressed because no_log was given 2026-03-10 00:25:00.720612 | 2026-03-10 00:25:00.720759 | TASK [upload-logs-failover : Initialize control vars] 2026-03-10 00:25:00.752441 | localhost | ok 2026-03-10 00:25:00.787581 | 2026-03-10 00:25:00.787746 | LOOP [upload-logs-failover : Perform log upload] 2026-03-10 00:25:00.822293 | localhost | Output suppressed because no_log was given 2026-03-10 00:25:00.831959 | localhost | included: /var/lib/zuul/builds/d25365426be749e2996dd544316a083d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml 2026-03-10 00:25:00.841626 | 2026-03-10 00:25:00.841720 | LOOP [upload-logs-failover : Unpack vars for swift upload] 2026-03-10 00:25:00.972465 | localhost | Output suppressed because no_log was given 2026-03-10 00:25:00.980243 | 2026-03-10 00:25:00.980340 | TASK [Upload logs to swift] 2026-03-10 00:25:01.023379 | localhost | ok 2026-03-10 00:25:01.064792 | 2026-03-10 00:25:01.064904 | TASK [Set zuul-log-path fact] 2026-03-10 00:25:01.089459 | localhost | skipping: Conditional result was False 2026-03-10 00:25:01.095432 | 2026-03-10 00:25:01.095507 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-03-10 00:25:01.508044 | localhost -> localhost | ok: Runtime: 0:00:00.004515 2026-03-10 00:25:01.516338 | 2026-03-10 00:25:01.516435 | TASK [upload-logs-swift : Upload logs to swift]