2025-12-14 00:35:20.989544 | Job console starting... 2025-12-14 00:35:21.006556 | Updating repositories 2025-12-14 00:35:21.150353 | Preparing job workspace 2025-12-14 00:35:24.853545 | Running Ansible setup... 2025-12-14 00:35:29.812546 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-12-14 00:35:30.442687 | 2025-12-14 00:35:30.442870 | PLAY [localhost] 2025-12-14 00:35:30.452319 | 2025-12-14 00:35:30.452402 | TASK [Gathering Facts] 2025-12-14 00:35:31.504321 | localhost | ok 2025-12-14 00:35:31.545522 | 2025-12-14 00:35:31.545777 | TASK [Setup log path fact] 2025-12-14 00:35:31.567015 | localhost | ok 2025-12-14 00:35:31.579708 | 2025-12-14 00:35:31.579778 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-14 00:35:31.608034 | localhost | ok 2025-12-14 00:35:31.617282 | 2025-12-14 00:35:31.617373 | TASK [emit-job-header : Print job information] 2025-12-14 00:35:31.657432 | # Job Information 2025-12-14 00:35:31.657769 | Ansible Version: 2.15.12 2025-12-14 00:35:31.657815 | Job: ansible-test-sanity-docker-stable-2.11 2025-12-14 00:35:31.657842 | Pipeline: periodic 2025-12-14 00:35:31.657863 | Executor: ze04.softwarefactory-project.io 2025-12-14 00:35:31.657885 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-12-14 00:35:31.657909 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/862/ansible/86293a0011344053ab9d2f10a5a67b01/ 2025-12-14 00:35:31.657931 | Event ID: 80329a5e66c843d0a83b17377e8761c8 2025-12-14 00:35:31.666350 | 2025-12-14 00:35:31.666476 | LOOP [emit-job-header : Print node information] 2025-12-14 00:35:31.776152 | localhost | ok: 2025-12-14 00:35:31.776460 | localhost | # Node Information 2025-12-14 00:35:31.776491 | localhost | Inventory Hostname: controller 2025-12-14 00:35:31.776511 | localhost | Hostname: np0005558984 2025-12-14 00:35:31.776530 | localhost | Username: zuul 2025-12-14 00:35:31.776554 | localhost | Distro: Fedora 37 2025-12-14 00:35:31.776573 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2025-12-14 00:35:31.776590 | localhost | Region: ca-ymq-1 2025-12-14 00:35:31.776606 | localhost | Label: ansible-fedora-37-1vcpu 2025-12-14 00:35:31.776622 | localhost | Product Name: OpenStack Nova 2025-12-14 00:35:31.776639 | localhost | Interface IP: 162.253.55.231 2025-12-14 00:35:31.793775 | 2025-12-14 00:35:31.793858 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-14 00:35:32.232321 | localhost -> localhost | changed 2025-12-14 00:35:32.238819 | 2025-12-14 00:35:32.238894 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-14 00:35:33.132052 | localhost -> localhost | changed 2025-12-14 00:35:33.166332 | 2025-12-14 00:35:33.166565 | PLAY [all:!appliance*] 2025-12-14 00:35:33.187729 | 2025-12-14 00:35:33.187817 | TASK [include_role : start-zuul-console] 2025-12-14 00:35:33.218099 | controller | ok 2025-12-14 00:35:33.232576 | 2025-12-14 00:35:33.232648 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-14 00:35:33.644638 | controller | ok 2025-12-14 00:35:33.658473 | 2025-12-14 00:35:33.658597 | TASK [use-our-mirror : Retrieve the IP address] 2025-12-14 00:35:35.473825 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-12-14 00:35:35.480544 | 2025-12-14 00:35:35.480629 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-12-14 00:35:36.017834 | controller | skipping: Conditional result was False 2025-12-14 00:35:36.033339 | 2025-12-14 00:35:36.033765 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-12-14 00:35:36.072961 | controller | skipping: Conditional result was False 2025-12-14 00:35:36.086730 | 2025-12-14 00:35:36.086878 | TASK [use-our-mirror : Create the podman configuration directory] 2025-12-14 00:35:36.114935 | controller | skipping: Conditional result was False 2025-12-14 00:35:36.130330 | 2025-12-14 00:35:36.130576 | TASK [use-our-mirror : Copy the podman configuration] 2025-12-14 00:35:36.168527 | controller | skipping: Conditional result was False 2025-12-14 00:35:36.186266 | 2025-12-14 00:35:36.186434 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-12-14 00:35:36.217122 | controller | skipping: Conditional result was False 2025-12-14 00:35:36.234918 | 2025-12-14 00:35:36.235098 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-12-14 00:35:36.275717 | controller | skipping: Conditional result was False 2025-12-14 00:35:36.303971 | 2025-12-14 00:35:36.304164 | TASK [Disable Fedora Modular] 2025-12-14 00:35:36.582606 | controller | changed 2025-12-14 00:35:36.595255 | 2025-12-14 00:35:36.595397 | TASK [Enable EPEL] 2025-12-14 00:35:36.625924 | controller | skipping: Conditional result was False 2025-12-14 00:35:36.644138 | 2025-12-14 00:35:36.644287 | TASK [Register the RHEL node] 2025-12-14 00:35:37.209440 | 2025-12-14 00:35:37.209826 | TASK [Show the subscription-manager status] 2025-12-14 00:35:37.808021 | controller | skipping: Conditional result was False 2025-12-14 00:35:37.818139 | 2025-12-14 00:35:37.818236 | TASK [Enable EPEL on RHEL] 2025-12-14 00:35:38.198653 | controller | skipping: Conditional result was False 2025-12-14 00:35:38.214730 | 2025-12-14 00:35:38.214901 | TASK [Install git and tox] 2025-12-14 00:36:54.434398 | controller | changed 2025-12-14 00:36:54.443458 | 2025-12-14 00:36:54.443551 | TASK [include_role : prepare-workspace] 2025-12-14 00:36:54.494032 | controller | ok 2025-12-14 00:36:54.544024 | 2025-12-14 00:36:54.544281 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-12-14 00:36:54.811182 | controller | ok 2025-12-14 00:36:54.822400 | 2025-12-14 00:36:54.822563 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-12-14 00:37:07.115908 | controller | Output suppressed because no_log was given 2025-12-14 00:37:07.126139 | 2025-12-14 00:37:07.126201 | TASK [include_role : prepare-workspace-openshift] 2025-12-14 00:37:07.170126 | controller | skipping: Conditional result was False 2025-12-14 00:37:07.198627 | 2025-12-14 00:37:07.198775 | PLAY [all:!appliance] 2025-12-14 00:37:07.265852 | 2025-12-14 00:37:07.266017 | TASK [Run add-build-sshkey role (RSA)] 2025-12-14 00:37:07.306413 | controller | ok 2025-12-14 00:37:07.338731 | 2025-12-14 00:37:07.338852 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-14 00:37:07.603080 | controller -> localhost | ok 2025-12-14 00:37:07.622426 | 2025-12-14 00:37:07.622649 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-14 00:37:07.665875 | controller | ok 2025-12-14 00:37:07.693403 | controller | included: /var/lib/zuul/builds/86293a0011344053ab9d2f10a5a67b01/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-14 00:37:07.703336 | 2025-12-14 00:37:07.703454 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-14 00:37:08.257915 | controller -> localhost | Generating public/private rsa key pair. 2025-12-14 00:37:08.258362 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/86293a0011344053ab9d2f10a5a67b01/work/86293a0011344053ab9d2f10a5a67b01_id_rsa. 2025-12-14 00:37:08.258414 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/86293a0011344053ab9d2f10a5a67b01/work/86293a0011344053ab9d2f10a5a67b01_id_rsa.pub. 2025-12-14 00:37:08.258447 | controller -> localhost | The key fingerprint is: 2025-12-14 00:37:08.258477 | controller -> localhost | SHA256:FPkAftqQfKhuQfEqB5J3n9hnrDxCh6sDxnKM/TyQ0KQ zuul-build-sshkey 2025-12-14 00:37:08.258507 | controller -> localhost | The key's randomart image is: 2025-12-14 00:37:08.258538 | controller -> localhost | +---[RSA 2048]----+ 2025-12-14 00:37:08.258565 | controller -> localhost | | . .... | 2025-12-14 00:37:08.258594 | controller -> localhost | | .. = oo. | 2025-12-14 00:37:08.258631 | controller -> localhost | |o+o o B +o | 2025-12-14 00:37:08.258670 | controller -> localhost | |Eo.+ B X . | 2025-12-14 00:37:08.258738 | controller -> localhost | |.=..O * S | 2025-12-14 00:37:08.258784 | controller -> localhost | |+o*= = + | 2025-12-14 00:37:08.258819 | controller -> localhost | |oo += + | 2025-12-14 00:37:08.258848 | controller -> localhost | | .o+. . | 2025-12-14 00:37:08.258878 | controller -> localhost | | .. . | 2025-12-14 00:37:08.258909 | controller -> localhost | +----[SHA256]-----+ 2025-12-14 00:37:08.259003 | controller -> localhost | ok: Runtime: 0:00:00.120314 2025-12-14 00:37:08.273045 | 2025-12-14 00:37:08.273194 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-14 00:37:08.317155 | controller | ok 2025-12-14 00:37:08.333578 | controller | included: /var/lib/zuul/builds/86293a0011344053ab9d2f10a5a67b01/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-14 00:37:08.348322 | 2025-12-14 00:37:08.348451 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-14 00:37:08.375639 | controller | skipping: Conditional result was False 2025-12-14 00:37:08.383828 | 2025-12-14 00:37:08.383899 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-14 00:37:08.858481 | controller | changed 2025-12-14 00:37:08.865165 | 2025-12-14 00:37:08.865230 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-14 00:37:09.109517 | controller | ok 2025-12-14 00:37:09.123267 | 2025-12-14 00:37:09.123401 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-14 00:37:09.758753 | controller | changed 2025-12-14 00:37:09.774973 | 2025-12-14 00:37:09.775151 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-14 00:37:10.413530 | controller | changed 2025-12-14 00:37:10.427519 | 2025-12-14 00:37:10.427720 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-14 00:37:10.446717 | controller | skipping: Conditional result was False 2025-12-14 00:37:10.463061 | 2025-12-14 00:37:10.463212 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-14 00:37:10.897925 | controller -> localhost | changed 2025-12-14 00:37:10.911393 | 2025-12-14 00:37:10.911477 | TASK [add-build-sshkey : Add back temp key] 2025-12-14 00:37:11.261787 | controller -> localhost | Identity added: /var/lib/zuul/builds/86293a0011344053ab9d2f10a5a67b01/work/86293a0011344053ab9d2f10a5a67b01_id_rsa (zuul-build-sshkey) 2025-12-14 00:37:11.262091 | controller -> localhost | ok: Runtime: 0:00:00.008861 2025-12-14 00:37:11.269425 | 2025-12-14 00:37:11.269514 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-14 00:37:11.655023 | controller | ok 2025-12-14 00:37:11.669195 | 2025-12-14 00:37:11.669402 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-14 00:37:11.709794 | controller | skipping: Conditional result was False 2025-12-14 00:37:11.741487 | 2025-12-14 00:37:11.741668 | TASK [Run add-build-sshkey role (ECDSA)] 2025-12-14 00:37:11.776633 | controller | ok 2025-12-14 00:37:11.801167 | 2025-12-14 00:37:11.801280 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-14 00:37:12.103220 | controller -> localhost | ok 2025-12-14 00:37:12.119106 | 2025-12-14 00:37:12.119284 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-14 00:37:12.162402 | controller | ok 2025-12-14 00:37:12.187043 | controller | included: /var/lib/zuul/builds/86293a0011344053ab9d2f10a5a67b01/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-14 00:37:12.196007 | 2025-12-14 00:37:12.196100 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-14 00:37:12.560633 | controller -> localhost | Generating public/private ecdsa key pair. 2025-12-14 00:37:12.561106 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/86293a0011344053ab9d2f10a5a67b01/work/86293a0011344053ab9d2f10a5a67b01_id_ecdsa. 2025-12-14 00:37:12.561210 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/86293a0011344053ab9d2f10a5a67b01/work/86293a0011344053ab9d2f10a5a67b01_id_ecdsa.pub. 2025-12-14 00:37:12.561278 | controller -> localhost | The key fingerprint is: 2025-12-14 00:37:12.561326 | controller -> localhost | SHA256:y9s+SMemRg1PCxxmylKHfJ8l266+LeF2Bpxn+hgOmpk zuul-build-sshkey 2025-12-14 00:37:12.561370 | controller -> localhost | The key's randomart image is: 2025-12-14 00:37:12.561414 | controller -> localhost | +---[ECDSA 521]---+ 2025-12-14 00:37:12.561456 | controller -> localhost | | . . | 2025-12-14 00:37:12.561498 | controller -> localhost | | + * . . | 2025-12-14 00:37:12.561540 | controller -> localhost | | o B o * | 2025-12-14 00:37:12.561581 | controller -> localhost | | . o + = . | 2025-12-14 00:37:12.561621 | controller -> localhost | | . SO + | 2025-12-14 00:37:12.561662 | controller -> localhost | | .o.% + | 2025-12-14 00:37:12.561753 | controller -> localhost | | o+*.B | 2025-12-14 00:37:12.561797 | controller -> localhost | | =+=B+o | 2025-12-14 00:37:12.561838 | controller -> localhost | | E..+BBo | 2025-12-14 00:37:12.561879 | controller -> localhost | +----[SHA256]-----+ 2025-12-14 00:37:12.561992 | controller -> localhost | ok: Runtime: 0:00:00.008311 2025-12-14 00:37:12.576779 | 2025-12-14 00:37:12.576925 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-14 00:37:12.608098 | controller | ok 2025-12-14 00:37:12.626478 | controller | included: /var/lib/zuul/builds/86293a0011344053ab9d2f10a5a67b01/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-14 00:37:12.640556 | 2025-12-14 00:37:12.640650 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-14 00:37:12.666368 | controller | skipping: Conditional result was False 2025-12-14 00:37:12.675130 | 2025-12-14 00:37:12.675220 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-14 00:37:12.960284 | controller | changed 2025-12-14 00:37:12.974921 | 2025-12-14 00:37:12.975133 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-14 00:37:13.202759 | controller | ok 2025-12-14 00:37:13.222110 | 2025-12-14 00:37:13.222390 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-14 00:37:13.876491 | controller | changed 2025-12-14 00:37:13.891215 | 2025-12-14 00:37:13.891359 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-14 00:37:14.567006 | controller | changed 2025-12-14 00:37:14.581532 | 2025-12-14 00:37:14.581720 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-14 00:37:14.620571 | controller | skipping: Conditional result was False 2025-12-14 00:37:14.635598 | 2025-12-14 00:37:14.635766 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-14 00:37:14.969480 | controller -> localhost | changed 2025-12-14 00:37:14.995099 | 2025-12-14 00:37:14.995246 | TASK [add-build-sshkey : Add back temp key] 2025-12-14 00:37:15.349966 | controller -> localhost | Identity added: /var/lib/zuul/builds/86293a0011344053ab9d2f10a5a67b01/work/86293a0011344053ab9d2f10a5a67b01_id_ecdsa (zuul-build-sshkey) 2025-12-14 00:37:15.350540 | controller -> localhost | ok: Runtime: 0:00:00.017050 2025-12-14 00:37:15.369474 | 2025-12-14 00:37:15.369648 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-14 00:37:15.655519 | controller | ok 2025-12-14 00:37:15.664160 | 2025-12-14 00:37:15.664308 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-14 00:37:15.699451 | controller | skipping: Conditional result was False 2025-12-14 00:37:15.712992 | 2025-12-14 00:37:15.713136 | TASK [include_role : remove-zuul-sshkey] 2025-12-14 00:37:15.738325 | controller | skipping: Conditional result was False 2025-12-14 00:37:15.745741 | 2025-12-14 00:37:15.745832 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-12-14 00:37:16.006282 | controller | ok: "logs" 2025-12-14 00:37:16.006725 | controller | ok: All items complete 2025-12-14 00:37:16.006790 | 2025-12-14 00:37:16.212644 | controller | ok: "artifacts" 2025-12-14 00:37:16.416582 | controller | ok: "docs" 2025-12-14 00:37:16.433145 | 2025-12-14 00:37:16.433374 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-12-14 00:37:16.692800 | controller | changed: "logs" 2025-12-14 00:37:16.892124 | controller | changed: "artifacts" 2025-12-14 00:37:17.092004 | controller | changed: "docs" 2025-12-14 00:37:17.129619 | 2025-12-14 00:37:17.129791 | PLAY RECAP 2025-12-14 00:37:17.129843 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-12-14 00:37:17.129870 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-14 00:37:17.129892 | 2025-12-14 00:37:17.279673 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-12-14 00:37:17.282591 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-12-14 00:37:17.895133 | 2025-12-14 00:37:17.895295 | PLAY [all] 2025-12-14 00:37:17.922664 | 2025-12-14 00:37:17.922845 | TASK [Install binary dependencies] 2025-12-14 00:37:17.978219 | controller | ok 2025-12-14 00:37:18.003065 | 2025-12-14 00:37:18.003175 | TASK [bindep : Include find tasks] 2025-12-14 00:37:18.037285 | controller | ok 2025-12-14 00:37:18.049322 | controller | included: /var/lib/zuul/builds/86293a0011344053ab9d2f10a5a67b01/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-12-14 00:37:18.060109 | 2025-12-14 00:37:18.060231 | TASK [bindep : Look for bindep.txt] 2025-12-14 00:37:18.447103 | controller | ok 2025-12-14 00:37:18.461086 | 2025-12-14 00:37:18.461236 | TASK [bindep : Define bindep_file fact] 2025-12-14 00:37:18.489868 | controller | skipping: Conditional result was False 2025-12-14 00:37:18.504565 | 2025-12-14 00:37:18.504751 | TASK [bindep : Look for other-requirements.txt] 2025-12-14 00:37:18.729838 | controller | ok 2025-12-14 00:37:18.743015 | 2025-12-14 00:37:18.743206 | TASK [bindep : Define bindep_file fact] 2025-12-14 00:37:18.782263 | controller | skipping: Conditional result was False 2025-12-14 00:37:18.797800 | 2025-12-14 00:37:18.797979 | TASK [bindep : Look for bindep fallback file] 2025-12-14 00:37:18.847466 | controller | skipping: Conditional result was False 2025-12-14 00:37:18.867591 | 2025-12-14 00:37:18.867763 | TASK [bindep : Define bindep_file fact] 2025-12-14 00:37:18.896225 | controller | skipping: Conditional result was False 2025-12-14 00:37:18.910091 | 2025-12-14 00:37:18.910210 | TASK [bindep : Include bindep tasks] 2025-12-14 00:37:18.948060 | controller | skipping: Conditional result was False 2025-12-14 00:37:18.960263 | 2025-12-14 00:37:18.960526 | TASK [bindep : Include install tasks] 2025-12-14 00:37:18.998094 | controller | skipping: Conditional result was False 2025-12-14 00:37:19.007056 | 2025-12-14 00:37:19.007363 | LOOP [bindep : Include package tasks] 2025-12-14 00:37:19.081579 | 2025-12-14 00:37:19.081875 | TASK [Run test-setup role] 2025-12-14 00:37:19.108517 | controller | ok 2025-12-14 00:37:19.127886 | 2025-12-14 00:37:19.128063 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-12-14 00:37:19.346492 | controller | ok 2025-12-14 00:37:19.355830 | 2025-12-14 00:37:19.355941 | TASK [test-setup : Run tools/test-setup.sh] 2025-12-14 00:37:19.903601 | controller | skipping: Conditional result was False 2025-12-14 00:37:19.962605 | 2025-12-14 00:37:19.962802 | PLAY RECAP 2025-12-14 00:37:19.962867 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-12-14 00:37:19.962896 | 2025-12-14 00:37:20.109958 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-12-14 00:37:20.110885 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-12-14 00:37:20.747328 | 2025-12-14 00:37:20.747493 | PLAY [controller] 2025-12-14 00:37:20.768013 | 2025-12-14 00:37:20.768099 | TASK [Create the /root directory] 2025-12-14 00:37:21.220356 | controller | ok 2025-12-14 00:37:21.228166 | 2025-12-14 00:37:21.228296 | TASK [Install glibc-langpack-en] 2025-12-14 00:37:25.134989 | controller | ok: Nothing to do 2025-12-14 00:37:25.147396 | 2025-12-14 00:37:25.147533 | TASK [Ensure controller directory exists] 2025-12-14 00:37:25.373612 | controller | changed 2025-12-14 00:37:25.388330 | 2025-12-14 00:37:25.388509 | TASK [Install container runtime] 2025-12-14 00:37:25.471667 | controller | ok 2025-12-14 00:37:25.539311 | 2025-12-14 00:37:25.539469 | LOOP [ensure-docker : Gather variables for each operating system] 2025-12-14 00:37:25.603965 | controller | ok: "/var/lib/zuul/builds/86293a0011344053ab9d2f10a5a67b01/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2025-12-14 00:37:25.621490 | 2025-12-14 00:37:25.621763 | TASK [ensure-docker : Sanity check for distro selection] 2025-12-14 00:37:25.649659 | controller | skipping: Conditional result was False 2025-12-14 00:37:25.667312 | 2025-12-14 00:37:25.667525 | TASK [ensure-docker : Sanity check for distro install] 2025-12-14 00:37:25.704377 | controller | skipping: Conditional result was False 2025-12-14 00:37:25.712634 | 2025-12-14 00:37:25.712747 | TASK [ensure-docker : Sanity check for upstream install] 2025-12-14 00:37:25.747745 | controller | skipping: Conditional result was False 2025-12-14 00:37:25.761721 | 2025-12-14 00:37:25.761869 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2025-12-14 00:37:25.799976 | controller | skipping: Conditional result was False 2025-12-14 00:37:25.816893 | 2025-12-14 00:37:25.817101 | TASK [ensure-docker : Include tasks for docker install] 2025-12-14 00:37:25.858180 | controller | ok 2025-12-14 00:37:25.877875 | controller | included: /var/lib/zuul/builds/86293a0011344053ab9d2f10a5a67b01/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2025-12-14 00:37:25.891600 | 2025-12-14 00:37:25.891712 | TASK [ensure-docker : Not Implemented] 2025-12-14 00:37:25.918075 | controller | skipping: Conditional result was False 2025-12-14 00:37:25.933421 | 2025-12-14 00:37:25.933563 | TASK [Set up docker mirrors] 2025-12-14 00:37:25.973870 | controller | ok 2025-12-14 00:37:26.011830 | 2025-12-14 00:37:26.011953 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2025-12-14 00:37:26.058254 | controller | skipping: Conditional result was False 2025-12-14 00:37:26.074274 | 2025-12-14 00:37:26.074441 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2025-12-14 00:37:26.115190 | controller | skipping: Conditional result was False 2025-12-14 00:37:26.131827 | 2025-12-14 00:37:26.132016 | TASK [use-docker-mirror : Set docker_mirror fact] 2025-12-14 00:37:26.171564 | controller | skipping: Conditional result was False 2025-12-14 00:37:26.188322 | 2025-12-14 00:37:26.188547 | TASK [use-docker-mirror : Set up docker mirrors] 2025-12-14 00:37:26.217826 | controller | skipping: Conditional result was False 2025-12-14 00:37:26.250784 | 2025-12-14 00:37:26.250986 | TASK [Add all repositories] 2025-12-14 00:37:26.282596 | controller | ok 2025-12-14 00:37:26.321971 | 2025-12-14 00:37:26.322175 | LOOP [ensure-package-repositories : Install repositories] 2025-12-14 00:37:26.366743 | controller | ok: "/var/lib/zuul/builds/86293a0011344053ab9d2f10a5a67b01/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2025-12-14 00:37:26.367185 | controller | ok: All items complete 2025-12-14 00:37:26.367280 | 2025-12-14 00:37:26.438464 | controller | included: /var/lib/zuul/builds/86293a0011344053ab9d2f10a5a67b01/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2025-12-14 00:37:26.446018 | 2025-12-14 00:37:26.446104 | TASK [ensure-package-repositories : Create GPG key temp directory] 2025-12-14 00:37:26.802831 | controller | changed 2025-12-14 00:37:26.817462 | 2025-12-14 00:37:26.817620 | LOOP [ensure-package-repositories : Upload GPG keys] 2025-12-14 00:37:27.760098 | controller | changed: 2025-12-14 00:37:27.760468 | controller | { 2025-12-14 00:37:27.760535 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2025-12-14 00:37:27.760588 | controller | } 2025-12-14 00:37:27.782807 | 2025-12-14 00:37:27.783070 | LOOP [ensure-package-repositories : Import GPG keys] 2025-12-14 00:37:28.441744 | controller | changed: 2025-12-14 00:37:28.442027 | controller | { 2025-12-14 00:37:28.442056 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2025-12-14 00:37:28.442080 | controller | } 2025-12-14 00:37:28.442110 | controller | changed: All items complete 2025-12-14 00:37:28.442129 | 2025-12-14 00:37:28.462875 | 2025-12-14 00:37:28.463237 | TASK [ensure-package-repositories : Remove GPG temp directory] 2025-12-14 00:37:28.682184 | controller | changed 2025-12-14 00:37:28.689279 | 2025-12-14 00:37:28.689353 | LOOP [ensure-package-repositories : Add repositories] 2025-12-14 00:37:29.006994 | controller | changed: 2025-12-14 00:37:29.007308 | controller | { 2025-12-14 00:37:29.007336 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2025-12-14 00:37:29.007356 | controller | "description": "Docker CE Stable - $basearch", 2025-12-14 00:37:29.007373 | controller | "gpgcheck": true, 2025-12-14 00:37:29.007389 | controller | "name": "docker-ce-stable" 2025-12-14 00:37:29.007405 | controller | } 2025-12-14 00:37:29.024477 | 2025-12-14 00:37:29.024648 | TASK [ensure-docker : Install pre-reqs] 2025-12-14 00:37:31.767663 | controller | ok: Nothing to do 2025-12-14 00:37:31.791828 | 2025-12-14 00:37:31.792124 | TASK [ensure-docker : Remove packages] 2025-12-14 00:37:33.103140 | controller | ok: Nothing to do 2025-12-14 00:37:33.117179 | 2025-12-14 00:37:33.117337 | TASK [ensure-docker : Install upstream docker using package] 2025-12-14 00:38:19.354160 | controller | changed 2025-12-14 00:38:19.367629 | 2025-12-14 00:38:19.367818 | TASK [ensure-docker : Setup docker] 2025-12-14 00:38:19.402224 | controller | ok 2025-12-14 00:38:19.423114 | controller | included: /var/lib/zuul/builds/86293a0011344053ab9d2f10a5a67b01/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2025-12-14 00:38:19.441709 | 2025-12-14 00:38:19.441841 | TASK [ensure-docker : Ensure "docker" group exists] 2025-12-14 00:38:19.855216 | controller | ok 2025-12-14 00:38:19.863365 | 2025-12-14 00:38:19.863461 | TASK [ensure-docker : Add user to docker group] 2025-12-14 00:38:20.437087 | controller | changed 2025-12-14 00:38:20.451781 | 2025-12-14 00:38:20.451942 | TASK [Update docker daemon proxy configuration] 2025-12-14 00:38:20.502199 | controller | skipping: Conditional result was False 2025-12-14 00:38:20.517296 | 2025-12-14 00:38:20.517458 | TASK [Lower default MTU] 2025-12-14 00:38:20.547036 | controller | skipping: Conditional result was False 2025-12-14 00:38:20.564278 | 2025-12-14 00:38:20.564473 | TASK [ensure-docker : Get the status of the docket.socket unit] 2025-12-14 00:38:21.001385 | controller | inactive 2025-12-14 00:38:21.163883 | controller | ok: Runtime: 0:00:00.015303 2025-12-14 00:38:21.177362 | 2025-12-14 00:38:21.177500 | TASK [ensure-docker : Get the status of the docket unit] 2025-12-14 00:38:21.439424 | controller | inactive 2025-12-14 00:38:21.734040 | controller | ok: Runtime: 0:00:00.010127 2025-12-14 00:38:21.747515 | 2025-12-14 00:38:21.747706 | TASK [ensure-docker : Restart docker] 2025-12-14 00:38:21.802145 | Trigger a Docker restart 2025-12-14 00:38:21.814160 | 2025-12-14 00:38:21.814294 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2025-12-14 00:38:21.873408 | 2025-12-14 00:38:21.873634 | TASK [ensure-docker : Install docker-compose] 2025-12-14 00:38:21.903742 | controller | skipping: Conditional result was False 2025-12-14 00:38:21.916231 | 2025-12-14 00:38:21.916352 | TASK [ensure-docker : Flush handlers before role exit] 2025-12-14 00:38:21.934078 | 2025-12-14 00:38:21.934181 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2025-12-14 00:38:23.290726 | controller | changed 2025-12-14 00:38:23.307534 | 2025-12-14 00:38:23.307737 | TASK [ensure-docker : Assure docker service is running] 2025-12-14 00:38:25.320200 | controller | changed 2025-12-14 00:38:25.334227 | 2025-12-14 00:38:25.334367 | TASK [ensure-docker : Assure docker.socket service is running] 2025-12-14 00:38:25.977816 | controller | ok 2025-12-14 00:38:25.998206 | 2025-12-14 00:38:25.998372 | TASK [ensure-docker : Correct group ownership on docker sock] 2025-12-14 00:38:26.370865 | controller | ok 2025-12-14 00:38:26.379945 | 2025-12-14 00:38:26.380052 | TASK [ensure-docker : Validate ability to talk with docker] 2025-12-14 00:38:26.984444 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2025-12-14 00:38:27.426475 | controller | ok: Runtime: 0:00:00.051627 2025-12-14 00:38:27.433960 | 2025-12-14 00:38:27.434027 | TASK [ensure-docker : Get version details] 2025-12-14 00:38:28.003800 | controller | Client: Docker Engine - Community 2025-12-14 00:38:28.005183 | controller | Version: 24.0.7 2025-12-14 00:38:28.005225 | controller | API version: 1.43 2025-12-14 00:38:28.005231 | controller | Go version: go1.20.10 2025-12-14 00:38:28.005245 | controller | Git commit: afdd53b 2025-12-14 00:38:28.005250 | controller | Built: Thu Oct 26 09:09:39 2023 2025-12-14 00:38:28.005255 | controller | OS/Arch: linux/amd64 2025-12-14 00:38:28.005261 | controller | Context: default 2025-12-14 00:38:28.005269 | controller | 2025-12-14 00:38:28.005273 | controller | Server: Docker Engine - Community 2025-12-14 00:38:28.005278 | controller | Engine: 2025-12-14 00:38:28.005282 | controller | Version: 24.0.7 2025-12-14 00:38:28.005287 | controller | API version: 1.43 (minimum version 1.12) 2025-12-14 00:38:28.005291 | controller | Go version: go1.20.10 2025-12-14 00:38:28.005296 | controller | Git commit: 311b9ff 2025-12-14 00:38:28.005300 | controller | Built: Thu Oct 26 09:08:11 2023 2025-12-14 00:38:28.005304 | controller | OS/Arch: linux/amd64 2025-12-14 00:38:28.005309 | controller | Experimental: false 2025-12-14 00:38:28.005313 | controller | containerd: 2025-12-14 00:38:28.005318 | controller | Version: 1.6.25 2025-12-14 00:38:28.005322 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2025-12-14 00:38:28.005326 | controller | runc: 2025-12-14 00:38:28.005330 | controller | Version: 1.1.10 2025-12-14 00:38:28.005335 | controller | GitCommit: v1.1.10-0-g18a0cb0 2025-12-14 00:38:28.005341 | controller | docker-init: 2025-12-14 00:38:28.005345 | controller | Version: 0.19.0 2025-12-14 00:38:28.005349 | controller | GitCommit: de40ad0 2025-12-14 00:38:28.470745 | controller | ok: Runtime: 0:00:00.066091 2025-12-14 00:38:28.485606 | 2025-12-14 00:38:28.485808 | TASK [ensure-docker : Dump installed docker details] 2025-12-14 00:38:28.517131 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version: 24.0.7\n API version: 1.43\n Go version: go1.20.10\n Git commit: afdd53b\n Built: Thu Oct 26 09:09:39 2023\n OS/Arch: linux/amd64\n Context: default\n\nServer: Docker Engine - Community\n Engine:\n Version: 24.0.7\n API version: 1.43 (minimum version 1.12)\n Go version: go1.20.10\n Git commit: 311b9ff\n Built: Thu Oct 26 09:08:11 2023\n OS/Arch: linux/amd64\n Experimental: false\n containerd:\n Version: 1.6.25\n GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n Version: 1.1.10\n GitCommit: v1.1.10-0-g18a0cb0\n docker-init:\n Version: 0.19.0\n GitCommit: de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2025-12-14 00:38:27.939516', 'end': '2025-12-14 00:38:28.005607', 'delta': '0:00:00.066091', 'msg': '', 'zuul_log_id': 'fa163e3b-3c83-9491-a0ed-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version: 24.0.7', ' API version: 1.43', ' Go version: go1.20.10', ' Git commit: afdd53b', ' Built: Thu Oct 26 09:09:39 2023', ' OS/Arch: linux/amd64', ' Context: default', '', 'Server: Docker Engine - Community', ' Engine:', ' Version: 24.0.7', ' API version: 1.43 (minimum version 1.12)', ' Go version: go1.20.10', ' Git commit: 311b9ff', ' Built: Thu Oct 26 09:08:11 2023', ' OS/Arch: linux/amd64', ' Experimental: false', ' containerd:', ' Version: 1.6.25', ' GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', ' Version: 1.1.10', ' GitCommit: v1.1.10-0-g18a0cb0', ' docker-init:', ' Version: 0.19.0', ' GitCommit: de40ad0'], 'stderr_lines': [], 'failed': False} 2025-12-14 00:38:28.532175 | 2025-12-14 00:38:28.532309 | TASK [Use buildset registry] 2025-12-14 00:38:28.559532 | controller | skipping: Conditional result was False 2025-12-14 00:38:28.585169 | 2025-12-14 00:38:28.585309 | TASK [Ensure python3.8 is present] 2025-12-14 00:38:28.610806 | controller | skipping: Conditional result was False 2025-12-14 00:38:28.619751 | 2025-12-14 00:38:28.619851 | TASK [Run our-ensure-python role (Fedora only for now)] 2025-12-14 00:38:28.643433 | controller | ok 2025-12-14 00:38:28.674082 | 2025-12-14 00:38:28.674215 | TASK [our-ensure-python : Install the right Python version (rpm)] 2025-12-14 00:38:30.827870 | controller | ok: Nothing to do 2025-12-14 00:38:30.841312 | 2025-12-14 00:38:30.841457 | TASK [our-ensure-python : Also install python3-devel] 2025-12-14 00:38:45.655828 | controller | changed 2025-12-14 00:38:45.679492 | 2025-12-14 00:38:45.679636 | TASK [Run ensure-virtualenv role] 2025-12-14 00:38:45.703866 | controller | ok 2025-12-14 00:38:45.736056 | 2025-12-14 00:38:45.736134 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2025-12-14 00:38:45.942627 | controller | /usr/bin/virtualenv 2025-12-14 00:38:46.269941 | controller | ok: Runtime: 0:00:00.004428 2025-12-14 00:38:46.276278 | 2025-12-14 00:38:46.276360 | LOOP [ensure-virtualenv : Install virtualenv package] 2025-12-14 00:38:46.317709 | controller | skipping: Conditional result was False 2025-12-14 00:38:46.318033 | controller | changed: All items complete 2025-12-14 00:38:46.318060 | 2025-12-14 00:38:46.334119 | 2025-12-14 00:38:46.334257 | TASK [Find the full path of the Python interpreter] 2025-12-14 00:38:46.544368 | controller | /usr/bin/python3.9 2025-12-14 00:38:46.868296 | controller | ok 2025-12-14 00:38:46.874554 | 2025-12-14 00:38:46.874626 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2025-12-14 00:38:48.464114 | controller | created virtual environment CPython3.9.18.final.0-64 in 830ms 2025-12-14 00:38:48.509709 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2025-12-14 00:38:48.509738 | controller | seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv) 2025-12-14 00:38:48.509749 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2025-12-14 00:38:48.509762 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2025-12-14 00:38:48.914825 | controller | changed 2025-12-14 00:38:48.922597 | 2025-12-14 00:38:48.922673 | TASK [Set selinux package] 2025-12-14 00:38:48.961403 | controller | ok 2025-12-14 00:38:48.973591 | 2025-12-14 00:38:48.973764 | TASK [Set selinux package (Fedora)] 2025-12-14 00:38:49.020252 | controller | ok 2025-12-14 00:38:49.027440 | 2025-12-14 00:38:49.027533 | TASK [Install selinux into virtualenv] 2025-12-14 00:39:16.143910 | controller | Collecting selinux-please-lie-to-me 2025-12-14 00:39:52.344616 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2025-12-14 00:39:52.707607 | controller | Collecting setuptools<50.0.0 2025-12-14 00:39:52.721376 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2025-12-14 00:39:52.756518 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 26.2 MB/s eta 0:00:00 2025-12-14 00:39:52.835378 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2025-12-14 00:39:52.835582 | controller | Attempting uninstall: setuptools 2025-12-14 00:39:52.836251 | controller | Found existing installation: setuptools 62.6.0 2025-12-14 00:39:52.907811 | controller | Uninstalling setuptools-62.6.0: 2025-12-14 00:39:52.916472 | controller | Successfully uninstalled setuptools-62.6.0 2025-12-14 00:39:53.265039 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2025-12-14 00:40:08.582407 | controller | 2025-12-14 00:40:08.659771 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2025-12-14 00:40:08.659948 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2025-12-14 00:40:09.134146 | controller | ok: Runtime: 0:01:19.078598 2025-12-14 00:40:09.146867 | 2025-12-14 00:40:09.146998 | TASK [Install pytest-forked into virtualenv] 2025-12-14 00:40:29.182613 | controller | Collecting pytest-forked 2025-12-14 00:40:41.431252 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2025-12-14 00:40:41.476038 | controller | Collecting py 2025-12-14 00:40:41.480939 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2025-12-14 00:40:41.501250 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 5.4 MB/s eta 0:00:00 2025-12-14 00:40:41.623852 | controller | Collecting pytest>=3.10 2025-12-14 00:40:41.635391 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2025-12-14 00:40:41.649888 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 34.3 MB/s eta 0:00:00 2025-12-14 00:40:41.710642 | controller | Collecting pygments>=2.7.2 2025-12-14 00:40:41.716565 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2025-12-14 00:40:41.736414 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 70.0 MB/s eta 0:00:00 2025-12-14 00:40:41.774183 | controller | Collecting exceptiongroup>=1 2025-12-14 00:40:41.778202 | controller | Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB) 2025-12-14 00:40:41.804600 | controller | Collecting iniconfig>=1 2025-12-14 00:40:41.810356 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2025-12-14 00:40:41.856070 | controller | Collecting packaging>=20 2025-12-14 00:40:41.859298 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-12-14 00:40:41.866987 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 11.7 MB/s eta 0:00:00 2025-12-14 00:40:41.911658 | controller | Collecting tomli>=1 2025-12-14 00:40:41.915221 | controller | Downloading tomli-2.3.0-py3-none-any.whl (14 kB) 2025-12-14 00:40:41.950834 | controller | Collecting pluggy<2,>=1.5 2025-12-14 00:40:41.954178 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-12-14 00:40:42.011259 | controller | Collecting typing-extensions>=4.6.0 2025-12-14 00:40:42.015104 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2025-12-14 00:40:42.022814 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 7.3 MB/s eta 0:00:00 2025-12-14 00:40:42.089946 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2025-12-14 00:40:43.148169 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.1.0 packaging-25.0 pluggy-1.6.0 py-1.11.0 pygments-2.19.2 pytest-8.4.2 pytest-forked-1.6.0 tomli-2.3.0 typing-extensions-4.15.0 2025-12-14 00:40:43.152610 | controller | 2025-12-14 00:40:43.222270 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2025-12-14 00:40:43.222314 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2025-12-14 00:40:43.718642 | controller | ok: Runtime: 0:00:33.852316 2025-12-14 00:40:43.730987 | 2025-12-14 00:40:43.731120 | TASK [Update pip] 2025-12-14 00:40:44.277762 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2025-12-14 00:40:53.833320 | controller | Collecting pip 2025-12-14 00:41:06.005890 | controller | Downloading pip-25.3-py3-none-any.whl (1.8 MB) 2025-12-14 00:41:06.062769 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 35.3 MB/s eta 0:00:00 2025-12-14 00:41:06.123057 | controller | Installing collected packages: pip 2025-12-14 00:41:06.123285 | controller | Attempting uninstall: pip 2025-12-14 00:41:06.123920 | controller | Found existing installation: pip 22.2.2 2025-12-14 00:41:06.288500 | controller | Uninstalling pip-22.2.2: 2025-12-14 00:41:06.305598 | controller | Successfully uninstalled pip-22.2.2 2025-12-14 00:41:07.075635 | controller | Successfully installed pip-25.3 2025-12-14 00:41:07.301067 | controller | ok: Runtime: 0:00:23.168534 2025-12-14 00:41:07.314491 | 2025-12-14 00:41:07.314841 | TASK [Ensure pip temp directory exists in /var/tmp] 2025-12-14 00:41:07.550783 | controller | changed 2025-12-14 00:41:07.562552 | 2025-12-14 00:41:07.562740 | TASK [Install ansible into virtualenv] 2025-12-14 00:41:08.062376 | controller | Processing ./src/github.com/ansible/ansible 2025-12-14 00:41:08.066279 | controller | Installing build dependencies: started 2025-12-14 00:41:29.113898 | controller | Installing build dependencies: finished with status 'done' 2025-12-14 00:41:29.114857 | controller | Getting requirements to build wheel: started 2025-12-14 00:41:29.728567 | controller | Getting requirements to build wheel: finished with status 'done' 2025-12-14 00:41:29.729953 | controller | Preparing metadata (pyproject.toml): started 2025-12-14 00:41:30.185041 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2025-12-14 00:41:41.112137 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2025-12-14 00:41:53.367154 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2025-12-14 00:41:53.443638 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2025-12-14 00:41:53.451953 | 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) 2025-12-14 00:41:53.749889 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2025-12-14 00:41:53.754247 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2025-12-14 00:41:53.777052 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (25.0) 2025-12-14 00:41:53.796779 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2025-12-14 00:41:53.802314 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2025-12-14 00:41:53.985042 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2025-12-14 00:41:53.990057 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2025-12-14 00:41:53.996578 | 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) 2025-12-14 00:41:54.012855 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2025-12-14 00:41:54.019329 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2025-12-14 00:41:54.111241 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2025-12-14 00:41:54.116173 | 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) 2025-12-14 00:41:54.129218 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2025-12-14 00:41:54.140072 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2025-12-14 00:41:54.314463 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 25.8 MB/s 0:00:00 2025-12-14 00:41:54.321281 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2025-12-14 00:41:54.331991 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2025-12-14 00:41:54.342821 | controller | Downloading markupsafe-3.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (20 kB) 2025-12-14 00:41:54.351450 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2025-12-14 00:41:54.363173 | controller | Downloading pyyaml-6.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (750 kB) 2025-12-14 00:41:54.380403 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 33.6 MB/s 0:00:00 2025-12-14 00:41:54.391374 | controller | Building wheels for collected packages: ansible-core 2025-12-14 00:41:54.392218 | controller | Building wheel for ansible-core (pyproject.toml): started 2025-12-14 00:41:55.781158 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2025-12-14 00:41:55.786218 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961250 sha256=6a995b25438e601533a8d16a2250d04f0dcd0444cb20f9a71e53d3268f06a6f9 2025-12-14 00:41:55.786522 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-jo7ujoa2/wheels/0f/a0/93/ab85b739e1eb1e0467171316f13571c14efdf87e0d5d44a33e 2025-12-14 00:41:55.797088 | controller | Successfully built ansible-core 2025-12-14 00:41:55.834635 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2025-12-14 00:41:57.284655 | controller | 2025-12-14 00:41:57.288288 | controller | Successfully installed MarkupSafe-3.0.3 PyYAML-6.0.3 ansible-core-2.11.12.post0 cffi-2.0.0 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23 resolvelib-0.5.4 2025-12-14 00:41:57.641844 | controller | ok: Runtime: 0:00:49.603300 2025-12-14 00:41:57.654410 | 2025-12-14 00:41:57.654536 | TASK [Setup download-artifact-fork role] 2025-12-14 00:41:57.682527 | controller | ok 2025-12-14 00:41:57.718346 | 2025-12-14 00:41:57.718440 | TASK [download-artifact-fork : Parse inventory response] 2025-12-14 00:41:57.775322 | controller | ok 2025-12-14 00:41:57.788027 | 2025-12-14 00:41:57.788157 | TASK [download-artifact-fork : Fetch info from Zuul API] 2025-12-14 00:41:57.824871 | controller | skipping: Conditional result was False 2025-12-14 00:41:57.838078 | 2025-12-14 00:41:57.838221 | TASK [download-artifact-fork : Parse build response] 2025-12-14 00:41:57.874832 | controller | skipping: Conditional result was False 2025-12-14 00:41:57.888065 | 2025-12-14 00:41:57.888205 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2025-12-14 00:41:58.118186 | controller | changed 2025-12-14 00:41:58.129574 | 2025-12-14 00:41:58.129750 | LOOP [download-artifact-fork : Download archive by type] 2025-12-14 00:41:58.168843 | controller | skipping: Conditional result was False 2025-12-14 00:41:58.169375 | 2025-12-14 00:41:58.932987 | controller | changed: OK (20190 bytes) 2025-12-14 00:41:58.951329 | 2025-12-14 00:41:58.951592 | TASK [deploy-artifacts : define __collections] 2025-12-14 00:41:58.985430 | controller | ok 2025-12-14 00:41:58.991842 | 2025-12-14 00:41:58.991995 | LOOP [deploy-artifacts : Create list of collections] 2025-12-14 00:41:59.030269 | controller | skipping: Conditional result was False 2025-12-14 00:41:59.049928 | controller | ok: 2025-12-14 00:41:59.050096 | controller | { 2025-12-14 00:41:59.050146 | controller | "branch": "main", 2025-12-14 00:41:59.050183 | controller | "job": "build-ansible-collection", 2025-12-14 00:41:59.050219 | controller | "metadata": { 2025-12-14 00:41:59.050253 | controller | "type": "ansible_collection", 2025-12-14 00:41:59.050286 | controller | "version": "5.0.0" 2025-12-14 00:41:59.050320 | controller | }, 2025-12-14 00:41:59.050353 | controller | "name": "ansible.network", 2025-12-14 00:41:59.050386 | controller | "project": "ansible-collections/ansible.network", 2025-12-14 00:41:59.050419 | controller | "url": "https://435a45bd88519b47a81e-04126b0707336f2de249476781b66574.ssl.cf5.rackcdn.com/ansible/15c438445f8e449090e978bce81e914f/artifacts/ansible-network-5.0.0.tar.gz" 2025-12-14 00:41:59.050466 | controller | } 2025-12-14 00:41:59.066065 | 2025-12-14 00:41:59.066276 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2025-12-14 00:41:59.736431 | controller | Starting galaxy collection install process 2025-12-14 00:41:59.736685 | controller | Process install dependency map 2025-12-14 00:43:26.159830 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2025-12-14 00:43:26.159997 | controller | unexpected error when getting available versions of collection 2025-12-14 00:43:26.160033 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2025-12-14 00:43:26.160045 | controller | ansible/netcommon/versions/' 2025-12-14 00:43:26.160061 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2025-12-14 00:43:26.197513 | controller | to see the full traceback, use -vvv 2025-12-14 00:43:26.231566 | controller | ERROR 2025-12-14 00:43:26.231771 | controller | { 2025-12-14 00:43:26.231803 | controller | "delta": "0:01:26.856745", 2025-12-14 00:43:26.231824 | controller | "end": "2025-12-14 00:43:26.198118", 2025-12-14 00:43:26.231842 | controller | "msg": "non-zero return code", 2025-12-14 00:43:26.231858 | controller | "rc": 250, 2025-12-14 00:43:26.231874 | controller | "start": "2025-12-14 00:41:59.341373" 2025-12-14 00:43:26.231890 | controller | } failure 2025-12-14 00:43:26.233809 | 2025-12-14 00:43:26.233855 | PLAY RECAP 2025-12-14 00:43:26.233898 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2025-12-14 00:43:26.233919 | 2025-12-14 00:43:26.364460 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-12-14 00:43:26.365486 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-14 00:43:26.930827 | 2025-12-14 00:43:26.930954 | PLAY [all] 2025-12-14 00:43:26.952189 | 2025-12-14 00:43:26.952274 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-12-14 00:43:27.400611 | controller | changed: non-zero return code 2025-12-14 00:43:27.412925 | 2025-12-14 00:43:27.413082 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-12-14 00:43:27.441794 | controller | skipping: Conditional result was False 2025-12-14 00:43:27.454824 | 2025-12-14 00:43:27.454993 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-12-14 00:43:27.496171 | 2025-12-14 00:43:27.496456 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-12-14 00:43:27.536390 | 2025-12-14 00:43:27.536661 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-12-14 00:43:27.563546 | controller | skipping: Conditional result was False 2025-12-14 00:43:27.578624 | 2025-12-14 00:43:27.578833 | LOOP [fetch-subunit-output : Generate subunit file] 2025-12-14 00:43:27.619384 | 2025-12-14 00:43:27.619666 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-12-14 00:43:27.646351 | controller | skipping: Conditional result was False 2025-12-14 00:43:27.659037 | 2025-12-14 00:43:27.659174 | TASK [fetch-subunit-output : Remove the temporary file] 2025-12-14 00:43:27.685804 | controller | skipping: Conditional result was False 2025-12-14 00:43:27.699151 | 2025-12-14 00:43:27.699314 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-12-14 00:43:27.726405 | controller | skipping: Conditional result was False 2025-12-14 00:43:27.773872 | 2025-12-14 00:43:27.773964 | PLAY RECAP 2025-12-14 00:43:27.774005 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-12-14 00:43:27.774025 | 2025-12-14 00:43:27.879770 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-14 00:43:27.880694 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-12-14 00:43:28.437083 | 2025-12-14 00:43:28.437206 | PLAY [all:!appliance*] 2025-12-14 00:43:28.459237 | 2025-12-14 00:43:28.459351 | TASK [unregister the node] 2025-12-14 00:43:28.990531 | controller | skipping: Conditional result was False 2025-12-14 00:43:29.004660 | 2025-12-14 00:43:29.004909 | TASK [include_role : fetch-output] 2025-12-14 00:43:29.066387 | controller | ok 2025-12-14 00:43:29.107531 | 2025-12-14 00:43:29.107980 | TASK [fetch-output : Set log path for multiple nodes] 2025-12-14 00:43:29.184540 | controller | skipping: Conditional result was False 2025-12-14 00:43:29.193590 | 2025-12-14 00:43:29.193739 | TASK [fetch-output : Set log path for single node] 2025-12-14 00:43:29.248035 | controller | ok 2025-12-14 00:43:29.255960 | 2025-12-14 00:43:29.256053 | LOOP [fetch-output : Ensure local output dirs] 2025-12-14 00:43:29.746523 | controller -> localhost | ok: "/var/lib/zuul/builds/86293a0011344053ab9d2f10a5a67b01/work/logs" 2025-12-14 00:43:30.010764 | controller -> localhost | changed: "/var/lib/zuul/builds/86293a0011344053ab9d2f10a5a67b01/work/artifacts" 2025-12-14 00:43:30.272099 | controller -> localhost | changed: "/var/lib/zuul/builds/86293a0011344053ab9d2f10a5a67b01/work/docs" 2025-12-14 00:43:30.295785 | 2025-12-14 00:43:30.296082 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-12-14 00:43:31.044084 | controller | changed: 2025-12-14 00:43:31.044775 | controller | .d..t...... ./ 2025-12-14 00:43:31.044872 | controller | cd+++++++++ controller/ 2025-12-14 00:43:31.044981 | controller | changed: All items complete 2025-12-14 00:43:31.045035 | 2025-12-14 00:43:31.652897 | controller | changed: .d..t...... ./ 2025-12-14 00:43:32.272801 | controller | changed: .d..t...... ./ 2025-12-14 00:43:32.306127 | 2025-12-14 00:43:32.306341 | TASK [include_role : fetch-output-openshift] 2025-12-14 00:43:32.334520 | controller | skipping: Conditional result was False 2025-12-14 00:43:32.351620 | 2025-12-14 00:43:32.351896 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-12-14 00:43:32.405308 | controller | skipping: Conditional result was False 2025-12-14 00:43:32.416756 | controller | skipping: Conditional result was False 2025-12-14 00:43:32.461489 | 2025-12-14 00:43:32.461732 | PLAY [localhost] 2025-12-14 00:43:32.481966 | 2025-12-14 00:43:32.482079 | TASK [Run Zuul manifest role] 2025-12-14 00:43:32.514963 | localhost | ok 2025-12-14 00:43:32.531560 | 2025-12-14 00:43:32.531725 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-12-14 00:43:32.995813 | localhost | changed 2025-12-14 00:43:33.008596 | 2025-12-14 00:43:33.008782 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-12-14 00:43:33.048021 | localhost | ok 2025-12-14 00:43:33.066782 | 2025-12-14 00:43:33.066911 | TASK [Set zuul-log-path fact] 2025-12-14 00:43:33.092838 | localhost | ok 2025-12-14 00:43:33.115610 | 2025-12-14 00:43:33.115783 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-14 00:43:33.158973 | localhost | ok 2025-12-14 00:43:33.171150 | 2025-12-14 00:43:33.171267 | LOOP [Run upload-logs-swift role] 2025-12-14 00:43:33.228479 | localhost | Output suppressed because no_log was given 2025-12-14 00:43:33.268280 | 2025-12-14 00:43:33.268462 | TASK [Set zuul-log-path fact] 2025-12-14 00:43:33.305264 | localhost | skipping: Conditional result was False 2025-12-14 00:43:33.312994 | 2025-12-14 00:43:33.313083 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-12-14 00:43:33.796919 | localhost -> localhost | ok: Runtime: 0:00:00.010898 2025-12-14 00:43:33.804673 | 2025-12-14 00:43:33.804812 | TASK [upload-logs-swift : Upload logs to swift]