2026-03-09 00:26:40.690758 | Job console starting... 2026-03-09 00:26:40.704474 | Updating repositories 2026-03-09 00:26:40.894558 | Preparing job workspace 2026-03-09 00:26:44.910413 | Running Ansible setup... 2026-03-09 00:26:52.786075 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-09 00:26:53.410777 | 2026-03-09 00:26:53.410908 | PLAY [localhost] 2026-03-09 00:26:53.420195 | 2026-03-09 00:26:53.420293 | TASK [Gathering Facts] 2026-03-09 00:26:54.499523 | localhost | ok 2026-03-09 00:26:54.513172 | 2026-03-09 00:26:54.513267 | TASK [Setup log path fact] 2026-03-09 00:26:54.561806 | localhost | ok 2026-03-09 00:26:54.574075 | 2026-03-09 00:26:54.574146 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-09 00:26:54.611760 | localhost | ok 2026-03-09 00:26:54.619470 | 2026-03-09 00:26:54.619543 | TASK [emit-job-header : Print job information] 2026-03-09 00:26:54.647323 | # Job Information 2026-03-09 00:26:54.647472 | Ansible Version: 2.15.12 2026-03-09 00:26:54.647504 | Job: ansible-test-sanity-docker-stable-2.11 2026-03-09 00:26:54.647527 | Pipeline: periodic 2026-03-09 00:26:54.647546 | Executor: ze02.softwarefactory-project.io 2026-03-09 00:26:54.647565 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-03-09 00:26:54.647587 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/9bb/ansible/9bbbe718d2714fd49609ee62ba224d41/ 2026-03-09 00:26:54.647615 | Event ID: 6ed282d1e81147dcab9935779dfad26a 2026-03-09 00:26:54.651441 | 2026-03-09 00:26:54.651504 | LOOP [emit-job-header : Print node information] 2026-03-09 00:26:54.746800 | localhost | ok: 2026-03-09 00:26:54.747013 | localhost | # Node Information 2026-03-09 00:26:54.747052 | localhost | Inventory Hostname: controller 2026-03-09 00:26:54.747083 | localhost | Hostname: np0005641931 2026-03-09 00:26:54.747113 | localhost | Username: zuul 2026-03-09 00:26:54.747146 | localhost | Distro: Fedora 37 2026-03-09 00:26:54.747175 | localhost | Provider: ansible-vexxhost-ams1 2026-03-09 00:26:54.747203 | localhost | Region: ams1 2026-03-09 00:26:54.747230 | localhost | Label: ansible-fedora-37-1vcpu 2026-03-09 00:26:54.747256 | localhost | Product Name: OpenStack Nova 2026-03-09 00:26:54.747284 | localhost | Interface IP: 38.129.16.146 2026-03-09 00:26:54.759198 | 2026-03-09 00:26:54.759316 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-09 00:26:55.204233 | localhost -> localhost | changed 2026-03-09 00:26:55.217186 | 2026-03-09 00:26:55.217338 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-09 00:26:56.161128 | localhost -> localhost | changed 2026-03-09 00:26:56.206574 | 2026-03-09 00:26:56.206788 | PLAY [all:!appliance*] 2026-03-09 00:26:56.240018 | 2026-03-09 00:26:56.240175 | TASK [include_role : start-zuul-console] 2026-03-09 00:26:56.264741 | controller | ok 2026-03-09 00:26:56.286149 | 2026-03-09 00:26:56.286278 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-03-09 00:26:57.374245 | controller | ok 2026-03-09 00:26:57.401185 | 2026-03-09 00:26:57.401373 | TASK [use-our-mirror : Retrieve the IP address] 2026-03-09 00:27:19.382855 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-03-09 00:27:19.397615 | 2026-03-09 00:27:19.397796 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-03-09 00:27:19.788369 | controller | skipping: Conditional result was False 2026-03-09 00:27:19.803470 | 2026-03-09 00:27:19.803623 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-03-09 00:27:19.832345 | controller | skipping: Conditional result was False 2026-03-09 00:27:19.858128 | 2026-03-09 00:27:19.858839 | TASK [use-our-mirror : Create the podman configuration directory] 2026-03-09 00:27:19.895071 | controller | skipping: Conditional result was False 2026-03-09 00:27:19.912195 | 2026-03-09 00:27:19.912618 | TASK [use-our-mirror : Copy the podman configuration] 2026-03-09 00:27:19.938596 | controller | skipping: Conditional result was False 2026-03-09 00:27:19.948357 | 2026-03-09 00:27:19.948464 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-03-09 00:27:19.975508 | controller | skipping: Conditional result was False 2026-03-09 00:27:19.985144 | 2026-03-09 00:27:19.985258 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-03-09 00:27:20.011573 | controller | skipping: Conditional result was False 2026-03-09 00:27:20.030506 | 2026-03-09 00:27:20.030685 | TASK [Disable Fedora Modular] 2026-03-09 00:27:21.053090 | controller | changed 2026-03-09 00:27:21.066975 | 2026-03-09 00:27:21.067142 | TASK [Enable EPEL] 2026-03-09 00:27:21.097760 | controller | skipping: Conditional result was False 2026-03-09 00:27:21.116694 | 2026-03-09 00:27:21.117020 | TASK [Register the RHEL node] 2026-03-09 00:27:21.528933 | 2026-03-09 00:27:21.529264 | TASK [Show the subscription-manager status] 2026-03-09 00:27:21.945964 | controller | skipping: Conditional result was False 2026-03-09 00:27:21.961762 | 2026-03-09 00:27:21.961923 | TASK [Enable EPEL on RHEL] 2026-03-09 00:27:22.369543 | controller | skipping: Conditional result was False 2026-03-09 00:27:22.378542 | 2026-03-09 00:27:22.378631 | TASK [Install git and tox] 2026-03-09 00:29:02.421613 | controller | changed 2026-03-09 00:29:02.440257 | 2026-03-09 00:29:02.440464 | TASK [include_role : prepare-workspace] 2026-03-09 00:29:02.479335 | controller | ok 2026-03-09 00:29:02.547057 | 2026-03-09 00:29:02.547245 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-09 00:29:03.394072 | controller | ok 2026-03-09 00:29:03.402965 | 2026-03-09 00:29:03.403069 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-09 00:29:19.507760 | controller | Output suppressed because no_log was given 2026-03-09 00:29:19.521067 | 2026-03-09 00:29:19.521131 | TASK [include_role : prepare-workspace-openshift] 2026-03-09 00:29:19.546142 | controller | skipping: Conditional result was False 2026-03-09 00:29:19.568461 | 2026-03-09 00:29:19.568516 | PLAY [all:!appliance] 2026-03-09 00:29:19.583677 | 2026-03-09 00:29:19.583768 | TASK [Run add-build-sshkey role (RSA)] 2026-03-09 00:29:19.614098 | controller | ok 2026-03-09 00:29:19.629994 | 2026-03-09 00:29:19.630058 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-09 00:29:19.902308 | controller -> localhost | ok 2026-03-09 00:29:19.915980 | 2026-03-09 00:29:19.916107 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-09 00:29:19.944733 | controller | ok 2026-03-09 00:29:19.971323 | controller | included: /var/lib/zuul/builds/9bbbe718d2714fd49609ee62ba224d41/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-09 00:29:19.979362 | 2026-03-09 00:29:19.979459 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-09 00:29:20.439932 | controller -> localhost | Generating public/private rsa key pair. 2026-03-09 00:29:20.440186 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9bbbe718d2714fd49609ee62ba224d41/work/9bbbe718d2714fd49609ee62ba224d41_id_rsa. 2026-03-09 00:29:20.440230 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9bbbe718d2714fd49609ee62ba224d41/work/9bbbe718d2714fd49609ee62ba224d41_id_rsa.pub. 2026-03-09 00:29:20.440262 | controller -> localhost | The key fingerprint is: 2026-03-09 00:29:20.440291 | controller -> localhost | SHA256:Jsc4g8UU6B0g4bMftapyVHBUcwLV9R27VNYDVnG5liY zuul-build-sshkey 2026-03-09 00:29:20.440320 | controller -> localhost | The key's randomart image is: 2026-03-09 00:29:20.440350 | controller -> localhost | +---[RSA 2048]----+ 2026-03-09 00:29:20.440378 | controller -> localhost | | oo+*Bo... o+oB| 2026-03-09 00:29:20.440412 | controller -> localhost | | ...oo.+. ... Bo| 2026-03-09 00:29:20.440501 | controller -> localhost | | o+ .+. . + +| 2026-03-09 00:29:20.440535 | controller -> localhost | | oo+.+ E * | 2026-03-09 00:29:20.440562 | controller -> localhost | | ..o * S = | 2026-03-09 00:29:20.440602 | controller -> localhost | | .. o * | 2026-03-09 00:29:20.440636 | controller -> localhost | | . o | 2026-03-09 00:29:20.440690 | controller -> localhost | |. .. | 2026-03-09 00:29:20.440727 | controller -> localhost | | o. | 2026-03-09 00:29:20.440759 | controller -> localhost | +----[SHA256]-----+ 2026-03-09 00:29:20.440831 | controller -> localhost | ok: Runtime: 0:00:00.049091 2026-03-09 00:29:20.452179 | 2026-03-09 00:29:20.452269 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-09 00:29:20.485446 | controller | ok 2026-03-09 00:29:20.500343 | controller | included: /var/lib/zuul/builds/9bbbe718d2714fd49609ee62ba224d41/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-09 00:29:20.513586 | 2026-03-09 00:29:20.513726 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-09 00:29:20.539208 | controller | skipping: Conditional result was False 2026-03-09 00:29:20.548830 | 2026-03-09 00:29:20.548949 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-09 00:29:21.729809 | controller | changed 2026-03-09 00:29:21.740446 | 2026-03-09 00:29:21.740595 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-09 00:29:22.389101 | controller | ok 2026-03-09 00:29:22.396619 | 2026-03-09 00:29:22.396722 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-09 00:29:25.492017 | controller | changed 2026-03-09 00:29:25.505449 | 2026-03-09 00:29:25.505611 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-09 00:29:28.607848 | controller | changed 2026-03-09 00:29:28.622443 | 2026-03-09 00:29:28.622592 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-09 00:29:28.651981 | controller | skipping: Conditional result was False 2026-03-09 00:29:28.662240 | 2026-03-09 00:29:28.662336 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-09 00:29:29.069247 | controller -> localhost | changed 2026-03-09 00:29:29.095116 | 2026-03-09 00:29:29.095261 | TASK [add-build-sshkey : Add back temp key] 2026-03-09 00:29:29.449472 | controller -> localhost | Identity added: /var/lib/zuul/builds/9bbbe718d2714fd49609ee62ba224d41/work/9bbbe718d2714fd49609ee62ba224d41_id_rsa (zuul-build-sshkey) 2026-03-09 00:29:29.449978 | controller -> localhost | ok: Runtime: 0:00:00.013395 2026-03-09 00:29:29.460129 | 2026-03-09 00:29:29.460279 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-09 00:29:30.381765 | controller | ok 2026-03-09 00:29:30.388942 | 2026-03-09 00:29:30.389024 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-09 00:29:30.413926 | controller | skipping: Conditional result was False 2026-03-09 00:29:30.428930 | 2026-03-09 00:29:30.429021 | TASK [Run add-build-sshkey role (ECDSA)] 2026-03-09 00:29:30.451844 | controller | ok 2026-03-09 00:29:30.468765 | 2026-03-09 00:29:30.468851 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-09 00:29:30.679519 | controller -> localhost | ok 2026-03-09 00:29:30.688487 | 2026-03-09 00:29:30.688573 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-09 00:29:30.713054 | controller | ok 2026-03-09 00:29:30.728490 | controller | included: /var/lib/zuul/builds/9bbbe718d2714fd49609ee62ba224d41/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-09 00:29:30.736354 | 2026-03-09 00:29:30.736419 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-09 00:29:30.985754 | controller -> localhost | Generating public/private ecdsa key pair. 2026-03-09 00:29:30.986003 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9bbbe718d2714fd49609ee62ba224d41/work/9bbbe718d2714fd49609ee62ba224d41_id_ecdsa. 2026-03-09 00:29:30.986033 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9bbbe718d2714fd49609ee62ba224d41/work/9bbbe718d2714fd49609ee62ba224d41_id_ecdsa.pub. 2026-03-09 00:29:30.986064 | controller -> localhost | The key fingerprint is: 2026-03-09 00:29:30.986086 | controller -> localhost | SHA256:Y2SnUvqyqWCA0Kv1wXDIO+SCiVHYvkesLjm0bDuPyro zuul-build-sshkey 2026-03-09 00:29:30.986106 | controller -> localhost | The key's randomart image is: 2026-03-09 00:29:30.986126 | controller -> localhost | +---[ECDSA 521]---+ 2026-03-09 00:29:30.986145 | controller -> localhost | | o. | 2026-03-09 00:29:30.986164 | controller -> localhost | |.+.. | 2026-03-09 00:29:30.986184 | controller -> localhost | |o.*.. + . | 2026-03-09 00:29:30.986202 | controller -> localhost | |==.*o = o | 2026-03-09 00:29:30.986220 | controller -> localhost | |B *+o o S | 2026-03-09 00:29:30.986238 | controller -> localhost | | *oo.. + . | 2026-03-09 00:29:30.986256 | controller -> localhost | |+o+.. . . | 2026-03-09 00:29:30.986274 | controller -> localhost | |=*o. + | 2026-03-09 00:29:30.986291 | controller -> localhost | |EB+...o | 2026-03-09 00:29:30.986310 | controller -> localhost | +----[SHA256]-----+ 2026-03-09 00:29:30.986371 | controller -> localhost | ok: Runtime: 0:00:00.008483 2026-03-09 00:29:30.993273 | 2026-03-09 00:29:30.993336 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-09 00:29:31.024054 | controller | ok 2026-03-09 00:29:31.031509 | controller | included: /var/lib/zuul/builds/9bbbe718d2714fd49609ee62ba224d41/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-09 00:29:31.040174 | 2026-03-09 00:29:31.040238 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-09 00:29:31.054328 | controller | skipping: Conditional result was False 2026-03-09 00:29:31.061884 | 2026-03-09 00:29:31.061973 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-09 00:29:31.987476 | controller | changed 2026-03-09 00:29:32.002041 | 2026-03-09 00:29:32.002327 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-09 00:29:32.702148 | controller | ok 2026-03-09 00:29:32.720981 | 2026-03-09 00:29:32.721187 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-09 00:29:35.844409 | controller | changed 2026-03-09 00:29:35.858758 | 2026-03-09 00:29:35.858919 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-09 00:29:38.944403 | controller | changed 2026-03-09 00:29:38.956872 | 2026-03-09 00:29:38.956962 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-09 00:29:38.975546 | controller | skipping: Conditional result was False 2026-03-09 00:29:38.990914 | 2026-03-09 00:29:38.991085 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-09 00:29:39.263042 | controller -> localhost | changed 2026-03-09 00:29:39.278018 | 2026-03-09 00:29:39.278109 | TASK [add-build-sshkey : Add back temp key] 2026-03-09 00:29:39.639828 | controller -> localhost | Identity added: /var/lib/zuul/builds/9bbbe718d2714fd49609ee62ba224d41/work/9bbbe718d2714fd49609ee62ba224d41_id_ecdsa (zuul-build-sshkey) 2026-03-09 00:29:39.640316 | controller -> localhost | ok: Runtime: 0:00:00.012893 2026-03-09 00:29:39.680557 | 2026-03-09 00:29:39.680793 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-09 00:29:40.449752 | controller | ok 2026-03-09 00:29:40.458595 | 2026-03-09 00:29:40.458724 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-09 00:29:40.495456 | controller | skipping: Conditional result was False 2026-03-09 00:29:40.513533 | 2026-03-09 00:29:40.513631 | TASK [include_role : remove-zuul-sshkey] 2026-03-09 00:29:40.539448 | controller | skipping: Conditional result was False 2026-03-09 00:29:40.550096 | 2026-03-09 00:29:40.550206 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-09 00:29:41.220509 | controller | ok: "logs" 2026-03-09 00:29:41.220764 | controller | ok: All items complete 2026-03-09 00:29:41.220804 | 2026-03-09 00:29:41.828178 | controller | ok: "artifacts" 2026-03-09 00:29:42.457881 | controller | ok: "docs" 2026-03-09 00:29:42.466051 | 2026-03-09 00:29:42.466121 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-09 00:29:43.175064 | controller | changed: "logs" 2026-03-09 00:29:43.800744 | controller | changed: "artifacts" 2026-03-09 00:29:44.427448 | controller | changed: "docs" 2026-03-09 00:29:44.469868 | 2026-03-09 00:29:44.470053 | PLAY RECAP 2026-03-09 00:29:44.470114 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-03-09 00:29:44.470142 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-09 00:29:44.470160 | 2026-03-09 00:29:44.655714 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-09 00:29:44.656977 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-09 00:29:45.294108 | 2026-03-09 00:29:45.294239 | PLAY [all] 2026-03-09 00:29:45.316819 | 2026-03-09 00:29:45.316958 | TASK [Install binary dependencies] 2026-03-09 00:29:45.366866 | controller | ok 2026-03-09 00:29:45.387167 | 2026-03-09 00:29:45.387253 | TASK [bindep : Include find tasks] 2026-03-09 00:29:45.416884 | controller | ok 2026-03-09 00:29:45.424847 | controller | included: /var/lib/zuul/builds/9bbbe718d2714fd49609ee62ba224d41/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-03-09 00:29:45.432005 | 2026-03-09 00:29:45.432258 | TASK [bindep : Look for bindep.txt] 2026-03-09 00:29:46.398771 | controller | ok 2026-03-09 00:29:46.404728 | 2026-03-09 00:29:46.404795 | TASK [bindep : Define bindep_file fact] 2026-03-09 00:29:46.430656 | controller | skipping: Conditional result was False 2026-03-09 00:29:46.438118 | 2026-03-09 00:29:46.438189 | TASK [bindep : Look for other-requirements.txt] 2026-03-09 00:29:47.109017 | controller | ok 2026-03-09 00:29:47.115062 | 2026-03-09 00:29:47.115129 | TASK [bindep : Define bindep_file fact] 2026-03-09 00:29:47.139001 | controller | skipping: Conditional result was False 2026-03-09 00:29:47.145184 | 2026-03-09 00:29:47.145251 | TASK [bindep : Look for bindep fallback file] 2026-03-09 00:29:47.169190 | controller | skipping: Conditional result was False 2026-03-09 00:29:47.176141 | 2026-03-09 00:29:47.176208 | TASK [bindep : Define bindep_file fact] 2026-03-09 00:29:47.200036 | controller | skipping: Conditional result was False 2026-03-09 00:29:47.206356 | 2026-03-09 00:29:47.206423 | TASK [bindep : Include bindep tasks] 2026-03-09 00:29:47.229789 | controller | skipping: Conditional result was False 2026-03-09 00:29:47.236227 | 2026-03-09 00:29:47.236294 | TASK [bindep : Include install tasks] 2026-03-09 00:29:47.276420 | controller | skipping: Conditional result was False 2026-03-09 00:29:47.283575 | 2026-03-09 00:29:47.283644 | LOOP [bindep : Include package tasks] 2026-03-09 00:29:47.353465 | 2026-03-09 00:29:47.353653 | TASK [Run test-setup role] 2026-03-09 00:29:47.375762 | controller | ok 2026-03-09 00:29:47.393326 | 2026-03-09 00:29:47.393393 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-03-09 00:29:48.028195 | controller | ok 2026-03-09 00:29:48.035017 | 2026-03-09 00:29:48.035130 | TASK [test-setup : Run tools/test-setup.sh] 2026-03-09 00:29:48.407103 | controller | skipping: Conditional result was False 2026-03-09 00:29:48.459992 | 2026-03-09 00:29:48.460083 | PLAY RECAP 2026-03-09 00:29:48.460138 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-09 00:29:48.460166 | 2026-03-09 00:29:48.572798 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-09 00:29:48.573717 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-09 00:29:49.308480 | 2026-03-09 00:29:49.308613 | PLAY [controller] 2026-03-09 00:29:49.328890 | 2026-03-09 00:29:49.329000 | TASK [Create the /root directory] 2026-03-09 00:29:50.366381 | controller | ok 2026-03-09 00:29:50.374631 | 2026-03-09 00:29:50.374772 | TASK [Install glibc-langpack-en] 2026-03-09 00:29:58.171168 | controller | ok: Nothing to do 2026-03-09 00:29:58.183800 | 2026-03-09 00:29:58.183933 | TASK [Ensure controller directory exists] 2026-03-09 00:29:59.023646 | controller | changed 2026-03-09 00:29:59.039142 | 2026-03-09 00:29:59.039378 | TASK [Install container runtime] 2026-03-09 00:29:59.112146 | controller | ok 2026-03-09 00:29:59.174542 | 2026-03-09 00:29:59.174680 | LOOP [ensure-docker : Gather variables for each operating system] 2026-03-09 00:29:59.228250 | controller | ok: "/var/lib/zuul/builds/9bbbe718d2714fd49609ee62ba224d41/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2026-03-09 00:29:59.237483 | 2026-03-09 00:29:59.237554 | TASK [ensure-docker : Sanity check for distro selection] 2026-03-09 00:29:59.282839 | controller | skipping: Conditional result was False 2026-03-09 00:29:59.296607 | 2026-03-09 00:29:59.296795 | TASK [ensure-docker : Sanity check for distro install] 2026-03-09 00:29:59.345204 | controller | skipping: Conditional result was False 2026-03-09 00:29:59.360617 | 2026-03-09 00:29:59.360838 | TASK [ensure-docker : Sanity check for upstream install] 2026-03-09 00:29:59.388997 | controller | skipping: Conditional result was False 2026-03-09 00:29:59.402647 | 2026-03-09 00:29:59.402847 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2026-03-09 00:29:59.428798 | controller | skipping: Conditional result was False 2026-03-09 00:29:59.437283 | 2026-03-09 00:29:59.437374 | TASK [ensure-docker : Include tasks for docker install] 2026-03-09 00:29:59.460466 | controller | ok 2026-03-09 00:29:59.477977 | controller | included: /var/lib/zuul/builds/9bbbe718d2714fd49609ee62ba224d41/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2026-03-09 00:29:59.487930 | 2026-03-09 00:29:59.487996 | TASK [ensure-docker : Not Implemented] 2026-03-09 00:29:59.503223 | controller | skipping: Conditional result was False 2026-03-09 00:29:59.511819 | 2026-03-09 00:29:59.511893 | TASK [Set up docker mirrors] 2026-03-09 00:29:59.542132 | controller | ok 2026-03-09 00:29:59.561739 | 2026-03-09 00:29:59.561807 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2026-03-09 00:29:59.597576 | controller | skipping: Conditional result was False 2026-03-09 00:29:59.606211 | 2026-03-09 00:29:59.606300 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2026-03-09 00:29:59.642032 | controller | skipping: Conditional result was False 2026-03-09 00:29:59.649537 | 2026-03-09 00:29:59.649607 | TASK [use-docker-mirror : Set docker_mirror fact] 2026-03-09 00:29:59.724410 | controller | skipping: Conditional result was False 2026-03-09 00:29:59.764757 | 2026-03-09 00:29:59.764945 | TASK [use-docker-mirror : Set up docker mirrors] 2026-03-09 00:29:59.790753 | controller | skipping: Conditional result was False 2026-03-09 00:29:59.803766 | 2026-03-09 00:29:59.803840 | TASK [Add all repositories] 2026-03-09 00:29:59.823728 | controller | ok 2026-03-09 00:29:59.848076 | 2026-03-09 00:29:59.848142 | LOOP [ensure-package-repositories : Install repositories] 2026-03-09 00:29:59.873284 | controller | ok: "/var/lib/zuul/builds/9bbbe718d2714fd49609ee62ba224d41/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2026-03-09 00:29:59.873793 | controller | ok: All items complete 2026-03-09 00:29:59.873834 | 2026-03-09 00:29:59.892772 | controller | included: /var/lib/zuul/builds/9bbbe718d2714fd49609ee62ba224d41/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2026-03-09 00:29:59.899181 | 2026-03-09 00:29:59.899243 | TASK [ensure-package-repositories : Create GPG key temp directory] 2026-03-09 00:30:00.824386 | controller | changed 2026-03-09 00:30:00.831651 | 2026-03-09 00:30:00.831749 | LOOP [ensure-package-repositories : Upload GPG keys] 2026-03-09 00:30:04.053484 | controller | changed: 2026-03-09 00:30:04.053992 | controller | { 2026-03-09 00:30:04.054064 | 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-09 00:30:04.054143 | controller | } 2026-03-09 00:30:04.079347 | 2026-03-09 00:30:04.079575 | LOOP [ensure-package-repositories : Import GPG keys] 2026-03-09 00:30:05.369955 | controller | changed: 2026-03-09 00:30:05.370316 | controller | { 2026-03-09 00:30:05.370365 | 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-09 00:30:05.370402 | controller | } 2026-03-09 00:30:05.370458 | controller | changed: All items complete 2026-03-09 00:30:05.370489 | 2026-03-09 00:30:05.383903 | 2026-03-09 00:30:05.384020 | TASK [ensure-package-repositories : Remove GPG temp directory] 2026-03-09 00:30:06.085519 | controller | changed 2026-03-09 00:30:06.098362 | 2026-03-09 00:30:06.098509 | LOOP [ensure-package-repositories : Add repositories] 2026-03-09 00:30:06.989467 | controller | changed: 2026-03-09 00:30:06.989743 | controller | { 2026-03-09 00:30:06.989774 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2026-03-09 00:30:06.989794 | controller | "description": "Docker CE Stable - $basearch", 2026-03-09 00:30:06.989811 | controller | "gpgcheck": true, 2026-03-09 00:30:06.989827 | controller | "name": "docker-ce-stable" 2026-03-09 00:30:06.989843 | controller | } 2026-03-09 00:30:07.012637 | 2026-03-09 00:30:07.012824 | TASK [ensure-docker : Install pre-reqs] 2026-03-09 00:30:11.951834 | controller | ok: Nothing to do 2026-03-09 00:30:11.965103 | 2026-03-09 00:30:11.965243 | TASK [ensure-docker : Remove packages] 2026-03-09 00:30:15.029416 | controller | ok: Nothing to do 2026-03-09 00:30:15.035140 | 2026-03-09 00:30:15.035207 | TASK [ensure-docker : Install upstream docker using package] 2026-03-09 00:31:35.216322 | controller | changed 2026-03-09 00:31:35.229640 | 2026-03-09 00:31:35.229812 | TASK [ensure-docker : Setup docker] 2026-03-09 00:31:35.257165 | controller | ok 2026-03-09 00:31:35.276022 | controller | included: /var/lib/zuul/builds/9bbbe718d2714fd49609ee62ba224d41/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2026-03-09 00:31:35.297082 | 2026-03-09 00:31:35.297177 | TASK [ensure-docker : Ensure "docker" group exists] 2026-03-09 00:31:36.315355 | controller | ok 2026-03-09 00:31:36.320724 | 2026-03-09 00:31:36.320788 | TASK [ensure-docker : Add user to docker group] 2026-03-09 00:31:37.276874 | controller | changed 2026-03-09 00:31:37.290784 | 2026-03-09 00:31:37.290920 | TASK [Update docker daemon proxy configuration] 2026-03-09 00:31:37.328445 | controller | skipping: Conditional result was False 2026-03-09 00:31:37.337889 | 2026-03-09 00:31:37.337976 | TASK [Lower default MTU] 2026-03-09 00:31:37.363747 | controller | skipping: Conditional result was False 2026-03-09 00:31:37.376190 | 2026-03-09 00:31:37.376448 | TASK [ensure-docker : Get the status of the docket.socket unit] 2026-03-09 00:31:38.207190 | controller | inactive 2026-03-09 00:31:38.752137 | controller | ok: Runtime: 0:00:00.016761 2026-03-09 00:31:38.758561 | 2026-03-09 00:31:38.758627 | TASK [ensure-docker : Get the status of the docket unit] 2026-03-09 00:31:39.529813 | controller | inactive 2026-03-09 00:31:40.134189 | controller | ok: Runtime: 0:00:00.024579 2026-03-09 00:31:40.149143 | 2026-03-09 00:31:40.149307 | TASK [ensure-docker : Restart docker] 2026-03-09 00:31:40.211109 | Trigger a Docker restart 2026-03-09 00:31:40.226074 | 2026-03-09 00:31:40.226227 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2026-03-09 00:31:40.272978 | 2026-03-09 00:31:40.273124 | TASK [ensure-docker : Install docker-compose] 2026-03-09 00:31:40.299402 | controller | skipping: Conditional result was False 2026-03-09 00:31:40.309305 | 2026-03-09 00:31:40.309444 | TASK [ensure-docker : Flush handlers before role exit] 2026-03-09 00:31:40.323692 | 2026-03-09 00:31:40.323800 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2026-03-09 00:31:43.890908 | controller | changed 2026-03-09 00:31:43.907790 | 2026-03-09 00:31:43.907937 | TASK [ensure-docker : Assure docker service is running] 2026-03-09 00:31:47.695475 | controller | changed 2026-03-09 00:31:47.712330 | 2026-03-09 00:31:47.712472 | TASK [ensure-docker : Assure docker.socket service is running] 2026-03-09 00:31:49.255120 | controller | ok 2026-03-09 00:31:49.272534 | 2026-03-09 00:31:49.272738 | TASK [ensure-docker : Correct group ownership on docker sock] 2026-03-09 00:31:50.019313 | controller | ok 2026-03-09 00:31:50.032607 | 2026-03-09 00:31:50.032795 | TASK [ensure-docker : Validate ability to talk with docker] 2026-03-09 00:31:50.668135 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2026-03-09 00:31:50.917182 | controller | ok: Runtime: 0:00:00.032966 2026-03-09 00:31:50.930229 | 2026-03-09 00:31:50.930386 | TASK [ensure-docker : Get version details] 2026-03-09 00:31:51.613423 | controller | Client: Docker Engine - Community 2026-03-09 00:31:51.617173 | controller | Version: 24.0.7 2026-03-09 00:31:51.617207 | controller | API version: 1.43 2026-03-09 00:31:51.617215 | controller | Go version: go1.20.10 2026-03-09 00:31:51.617233 | controller | Git commit: afdd53b 2026-03-09 00:31:51.617240 | controller | Built: Thu Oct 26 09:09:39 2023 2026-03-09 00:31:51.617247 | controller | OS/Arch: linux/amd64 2026-03-09 00:31:51.617255 | controller | Context: default 2026-03-09 00:31:51.617264 | controller | 2026-03-09 00:31:51.617271 | controller | Server: Docker Engine - Community 2026-03-09 00:31:51.617276 | controller | Engine: 2026-03-09 00:31:51.617282 | controller | Version: 24.0.7 2026-03-09 00:31:51.617289 | controller | API version: 1.43 (minimum version 1.12) 2026-03-09 00:31:51.617295 | controller | Go version: go1.20.10 2026-03-09 00:31:51.617301 | controller | Git commit: 311b9ff 2026-03-09 00:31:51.617307 | controller | Built: Thu Oct 26 09:08:11 2023 2026-03-09 00:31:51.617313 | controller | OS/Arch: linux/amd64 2026-03-09 00:31:51.617319 | controller | Experimental: false 2026-03-09 00:31:51.617325 | controller | containerd: 2026-03-09 00:31:51.617331 | controller | Version: 1.6.25 2026-03-09 00:31:51.617337 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2026-03-09 00:31:51.617343 | controller | runc: 2026-03-09 00:31:51.617349 | controller | Version: 1.1.10 2026-03-09 00:31:51.617355 | controller | GitCommit: v1.1.10-0-g18a0cb0 2026-03-09 00:31:51.617364 | controller | docker-init: 2026-03-09 00:31:51.617370 | controller | Version: 0.19.0 2026-03-09 00:31:51.617376 | controller | GitCommit: de40ad0 2026-03-09 00:31:51.822318 | controller | ok: Runtime: 0:00:00.041927 2026-03-09 00:31:51.831326 | 2026-03-09 00:31:51.831424 | TASK [ensure-docker : Dump installed docker details] 2026-03-09 00:31:51.866844 | {'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-09 00:31:51.575847', 'end': '2026-03-09 00:31:51.617774', 'delta': '0:00:00.041927', 'msg': '', 'zuul_log_id': 'fa163efc-24cc-2ff0-c5bc-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-09 00:31:51.875398 | 2026-03-09 00:31:51.875497 | TASK [Use buildset registry] 2026-03-09 00:31:51.900937 | controller | skipping: Conditional result was False 2026-03-09 00:31:51.918568 | 2026-03-09 00:31:51.918685 | TASK [Ensure python3.8 is present] 2026-03-09 00:31:51.944054 | controller | skipping: Conditional result was False 2026-03-09 00:31:51.953121 | 2026-03-09 00:31:51.953209 | TASK [Run our-ensure-python role (Fedora only for now)] 2026-03-09 00:31:51.976192 | controller | ok 2026-03-09 00:31:52.003612 | 2026-03-09 00:31:52.003739 | TASK [our-ensure-python : Install the right Python version (rpm)] 2026-03-09 00:31:58.901442 | controller | ok: Nothing to do 2026-03-09 00:31:58.916250 | 2026-03-09 00:31:58.916397 | TASK [our-ensure-python : Also install python3-devel] 2026-03-09 00:32:14.749701 | controller | changed 2026-03-09 00:32:14.775941 | 2026-03-09 00:32:14.776137 | TASK [Run ensure-virtualenv role] 2026-03-09 00:32:14.802822 | controller | ok 2026-03-09 00:32:14.840713 | 2026-03-09 00:32:14.840838 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2026-03-09 00:32:15.606744 | controller | /usr/bin/virtualenv 2026-03-09 00:32:16.212007 | controller | ok: Runtime: 0:00:00.004952 2026-03-09 00:32:16.226656 | 2026-03-09 00:32:16.226857 | LOOP [ensure-virtualenv : Install virtualenv package] 2026-03-09 00:32:16.264261 | controller | skipping: Conditional result was False 2026-03-09 00:32:16.264870 | controller | changed: All items complete 2026-03-09 00:32:16.264935 | 2026-03-09 00:32:16.292349 | 2026-03-09 00:32:16.292566 | TASK [Find the full path of the Python interpreter] 2026-03-09 00:32:17.116565 | controller | /usr/bin/python3.9 2026-03-09 00:32:17.695529 | controller | ok 2026-03-09 00:32:17.707863 | 2026-03-09 00:32:17.708001 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2026-03-09 00:32:20.402046 | controller | created virtual environment CPython3.9.18.final.0-64 in 1246ms 2026-03-09 00:32:20.452812 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2026-03-09 00:32:20.452864 | 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-09 00:32:20.452877 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2026-03-09 00:32:20.452898 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2026-03-09 00:32:20.610360 | controller | changed 2026-03-09 00:32:20.623864 | 2026-03-09 00:32:20.624016 | TASK [Set selinux package] 2026-03-09 00:32:20.665915 | controller | ok 2026-03-09 00:32:20.677862 | 2026-03-09 00:32:20.678001 | TASK [Set selinux package (Fedora)] 2026-03-09 00:32:20.817479 | controller | ok 2026-03-09 00:32:20.822807 | 2026-03-09 00:32:20.822873 | TASK [Install selinux into virtualenv] 2026-03-09 00:32:27.809427 | controller | Collecting selinux-please-lie-to-me 2026-03-09 00:32:27.876485 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2026-03-09 00:32:28.505774 | controller | Collecting setuptools<50.0.0 2026-03-09 00:32:28.511787 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2026-03-09 00:32:28.569886 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 14.9 MB/s eta 0:00:00 2026-03-09 00:32:28.706251 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2026-03-09 00:32:28.706616 | controller | Attempting uninstall: setuptools 2026-03-09 00:32:28.707791 | controller | Found existing installation: setuptools 62.6.0 2026-03-09 00:32:28.837590 | controller | Uninstalling setuptools-62.6.0: 2026-03-09 00:32:28.855400 | controller | Successfully uninstalled setuptools-62.6.0 2026-03-09 00:32:29.516951 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2026-03-09 00:32:29.737353 | controller | 2026-03-09 00:32:30.027754 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0.1 2026-03-09 00:32:30.027806 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-03-09 00:32:30.211937 | controller | ok: Runtime: 0:00:08.137720 2026-03-09 00:32:30.224705 | 2026-03-09 00:32:30.224849 | TASK [Install pytest-forked into virtualenv] 2026-03-09 00:32:31.738789 | controller | Collecting pytest-forked 2026-03-09 00:32:31.796282 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2026-03-09 00:32:31.861949 | controller | Collecting py 2026-03-09 00:32:31.866445 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2026-03-09 00:32:31.895163 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 3.8 MB/s eta 0:00:00 2026-03-09 00:32:32.086960 | controller | Collecting pytest>=3.10 2026-03-09 00:32:32.092560 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2026-03-09 00:32:32.118329 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 17.8 MB/s eta 0:00:00 2026-03-09 00:32:32.192220 | controller | Collecting exceptiongroup>=1 2026-03-09 00:32:32.196690 | controller | Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB) 2026-03-09 00:32:32.289414 | controller | Collecting tomli>=1 2026-03-09 00:32:32.295369 | controller | Downloading tomli-2.4.0-py3-none-any.whl (14 kB) 2026-03-09 00:32:32.352182 | controller | Collecting pluggy<2,>=1.5 2026-03-09 00:32:32.359078 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-03-09 00:32:32.436317 | controller | Collecting packaging>=20 2026-03-09 00:32:32.442094 | controller | Downloading packaging-26.0-py3-none-any.whl (74 kB) 2026-03-09 00:32:32.451576 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 10.5 MB/s eta 0:00:00 2026-03-09 00:32:32.483137 | controller | Collecting iniconfig>=1 2026-03-09 00:32:32.488982 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2026-03-09 00:32:32.571422 | controller | Collecting pygments>=2.7.2 2026-03-09 00:32:32.577228 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2026-03-09 00:32:32.602794 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 55.2 MB/s eta 0:00:00 2026-03-09 00:32:32.723924 | controller | Collecting typing-extensions>=4.6.0 2026-03-09 00:32:32.730036 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2026-03-09 00:32:32.741366 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 4.4 MB/s eta 0:00:00 2026-03-09 00:32:32.858582 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2026-03-09 00:32:35.206479 | 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-09 00:32:35.214825 | controller | 2026-03-09 00:32:35.465835 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0.1 2026-03-09 00:32:35.465892 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-03-09 00:32:35.628471 | controller | ok: Runtime: 0:00:04.422988 2026-03-09 00:32:35.641650 | 2026-03-09 00:32:35.641845 | TASK [Update pip] 2026-03-09 00:32:38.040253 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2026-03-09 00:32:38.548346 | controller | Collecting pip 2026-03-09 00:32:38.675735 | controller | Downloading pip-26.0.1-py3-none-any.whl (1.8 MB) 2026-03-09 00:32:38.814771 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 14.3 MB/s eta 0:00:00 2026-03-09 00:32:39.069414 | controller | Installing collected packages: pip 2026-03-09 00:32:39.074599 | controller | Attempting uninstall: pip 2026-03-09 00:32:39.079451 | controller | Found existing installation: pip 22.2.2 2026-03-09 00:32:39.678616 | controller | Uninstalling pip-22.2.2: 2026-03-09 00:32:39.758712 | controller | Successfully uninstalled pip-22.2.2 2026-03-09 00:32:41.616738 | controller | Successfully installed pip-26.0.1 2026-03-09 00:32:42.035136 | controller | ok: Runtime: 0:00:04.995496 2026-03-09 00:32:42.047701 | 2026-03-09 00:32:42.047872 | TASK [Ensure pip temp directory exists in /var/tmp] 2026-03-09 00:32:43.118539 | controller | changed 2026-03-09 00:32:43.131294 | 2026-03-09 00:32:43.131429 | TASK [Install ansible into virtualenv] 2026-03-09 00:32:45.192969 | controller | Processing ./src/github.com/ansible/ansible 2026-03-09 00:32:45.212193 | controller | Installing build dependencies: started 2026-03-09 00:32:47.142605 | controller | Installing build dependencies: finished with status 'done' 2026-03-09 00:32:47.144152 | controller | Getting requirements to build wheel: started 2026-03-09 00:32:48.440109 | controller | Getting requirements to build wheel: finished with status 'done' 2026-03-09 00:32:48.441897 | controller | Preparing metadata (pyproject.toml): started 2026-03-09 00:32:49.299567 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2026-03-09 00:32:49.418854 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2026-03-09 00:32:49.477501 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2026-03-09 00:32:49.596115 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2026-03-09 00:32:49.602136 | 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-09 00:32:50.122669 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2026-03-09 00:32:50.127887 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2026-03-09 00:32:50.152729 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (26.0) 2026-03-09 00:32:50.178419 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2026-03-09 00:32:50.183658 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2026-03-09 00:32:50.466187 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2026-03-09 00:32:50.472490 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2026-03-09 00:32:50.480678 | 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-09 00:32:50.500620 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2026-03-09 00:32:50.505271 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2026-03-09 00:32:50.651150 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2026-03-09 00:32:50.660086 | 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-09 00:32:50.678978 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2026-03-09 00:32:50.697040 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2026-03-09 00:32:50.772231 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 74.8 MB/s 0:00:00 2026-03-09 00:32:50.780032 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2026-03-09 00:32:50.795512 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2026-03-09 00:32:50.811498 | 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-09 00:32:50.826566 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2026-03-09 00:32:50.840695 | 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-09 00:32:50.864047 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 31.0 MB/s 0:00:00 2026-03-09 00:32:50.884214 | controller | Building wheels for collected packages: ansible-core 2026-03-09 00:32:50.888287 | controller | Building wheel for ansible-core (pyproject.toml): started 2026-03-09 00:32:53.331911 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2026-03-09 00:32:53.335586 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961250 sha256=ec4941509bfefd9c3788a5746ef56a051725a8433445128be2c3f51d402e50f3 2026-03-09 00:32:53.335918 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-92tnb8ti/wheels/0f/a0/93/ab85b739e1eb1e0467171316f13571c14efdf87e0d5d44a33e 2026-03-09 00:32:53.350373 | controller | Successfully built ansible-core 2026-03-09 00:32:53.403012 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2026-03-09 00:32:56.132810 | controller | 2026-03-09 00:32:56.138026 | 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-09 00:32:57.043059 | controller | ok: Runtime: 0:00:12.669698 2026-03-09 00:32:57.049526 | 2026-03-09 00:32:57.049596 | TASK [Setup download-artifact-fork role] 2026-03-09 00:32:57.070811 | controller | ok 2026-03-09 00:32:57.088170 | 2026-03-09 00:32:57.088240 | TASK [download-artifact-fork : Parse inventory response] 2026-03-09 00:32:57.137909 | controller | ok 2026-03-09 00:32:57.143745 | 2026-03-09 00:32:57.143809 | TASK [download-artifact-fork : Fetch info from Zuul API] 2026-03-09 00:32:57.178169 | controller | skipping: Conditional result was False 2026-03-09 00:32:57.186423 | 2026-03-09 00:32:57.186492 | TASK [download-artifact-fork : Parse build response] 2026-03-09 00:32:57.210416 | controller | skipping: Conditional result was False 2026-03-09 00:32:57.217108 | 2026-03-09 00:32:57.217177 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2026-03-09 00:32:58.077711 | controller | changed 2026-03-09 00:32:58.083483 | 2026-03-09 00:32:58.083551 | LOOP [download-artifact-fork : Download archive by type] 2026-03-09 00:32:58.116071 | controller | skipping: Conditional result was False 2026-03-09 00:32:58.116268 | 2026-03-09 00:33:00.652068 | controller | changed: OK (20214 bytes) 2026-03-09 00:33:00.681266 | 2026-03-09 00:33:00.681492 | TASK [deploy-artifacts : define __collections] 2026-03-09 00:33:00.722348 | controller | ok 2026-03-09 00:33:00.776069 | 2026-03-09 00:33:00.776228 | LOOP [deploy-artifacts : Create list of collections] 2026-03-09 00:33:00.803735 | controller | skipping: Conditional result was False 2026-03-09 00:33:00.817101 | controller | ok: 2026-03-09 00:33:00.817183 | controller | { 2026-03-09 00:33:00.817215 | controller | "branch": "main", 2026-03-09 00:33:00.817242 | controller | "job": "build-ansible-collection", 2026-03-09 00:33:00.817265 | controller | "metadata": { 2026-03-09 00:33:00.817308 | controller | "type": "ansible_collection", 2026-03-09 00:33:00.817330 | controller | "version": "5.0.0" 2026-03-09 00:33:00.817350 | controller | }, 2026-03-09 00:33:00.817371 | controller | "name": "ansible.network", 2026-03-09 00:33:00.817392 | controller | "project": "ansible-collections/ansible.network", 2026-03-09 00:33:00.817413 | controller | "url": "https://22b1e0d4b8e70412cc4d-69e1c47a1811c463a459ff2268eea937.ssl.cf5.rackcdn.com/ansible/3d0fe746bbbd4310b179540d84ad3873/artifacts/ansible-network-5.0.0.tar.gz" 2026-03-09 00:33:00.817442 | controller | } 2026-03-09 00:33:00.828111 | 2026-03-09 00:33:00.828241 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2026-03-09 00:33:02.319384 | controller | Starting galaxy collection install process 2026-03-09 00:33:02.319773 | controller | Process install dependency map 2026-03-09 00:33:03.751786 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-03-09 00:33:03.752012 | controller | unexpected error when getting available versions of collection 2026-03-09 00:33:03.752040 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2026-03-09 00:33:03.752049 | controller | ansible/netcommon/versions/' 2026-03-09 00:33:03.752060 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2026-03-09 00:33:03.841453 | controller | to see the full traceback, use -vvv 2026-03-09 00:33:04.200254 | controller | ERROR 2026-03-09 00:33:04.200473 | controller | { 2026-03-09 00:33:04.200504 | controller | "delta": "0:00:02.244256", 2026-03-09 00:33:04.200524 | controller | "end": "2026-03-09 00:33:03.841932", 2026-03-09 00:33:04.200551 | controller | "msg": "non-zero return code", 2026-03-09 00:33:04.200568 | controller | "rc": 250, 2026-03-09 00:33:04.200584 | controller | "start": "2026-03-09 00:33:01.597676" 2026-03-09 00:33:04.200601 | controller | } failure 2026-03-09 00:33:04.202786 | 2026-03-09 00:33:04.202845 | PLAY RECAP 2026-03-09 00:33:04.202895 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2026-03-09 00:33:04.202925 | 2026-03-09 00:33:04.330016 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-09 00:33:04.330996 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-09 00:33:04.931255 | 2026-03-09 00:33:04.931373 | PLAY [all] 2026-03-09 00:33:04.953614 | 2026-03-09 00:33:04.953779 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-03-09 00:33:08.258258 | controller | changed: non-zero return code 2026-03-09 00:33:08.272340 | 2026-03-09 00:33:08.272511 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-03-09 00:33:08.301298 | controller | skipping: Conditional result was False 2026-03-09 00:33:08.317529 | 2026-03-09 00:33:08.317719 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-03-09 00:33:08.364526 | 2026-03-09 00:33:08.364841 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-03-09 00:33:08.408913 | 2026-03-09 00:33:08.409212 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-03-09 00:33:08.438079 | controller | skipping: Conditional result was False 2026-03-09 00:33:08.455219 | 2026-03-09 00:33:08.455424 | LOOP [fetch-subunit-output : Generate subunit file] 2026-03-09 00:33:08.500851 | 2026-03-09 00:33:08.501177 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-03-09 00:33:08.520806 | controller | skipping: Conditional result was False 2026-03-09 00:33:08.539187 | 2026-03-09 00:33:08.539346 | TASK [fetch-subunit-output : Remove the temporary file] 2026-03-09 00:33:08.567241 | controller | skipping: Conditional result was False 2026-03-09 00:33:08.577411 | 2026-03-09 00:33:08.577541 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-03-09 00:33:08.594079 | controller | skipping: Conditional result was False 2026-03-09 00:33:08.633879 | 2026-03-09 00:33:08.634057 | PLAY RECAP 2026-03-09 00:33:08.634129 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-09 00:33:08.634159 | 2026-03-09 00:33:08.757925 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-09 00:33:08.759539 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-03-09 00:33:09.384382 | 2026-03-09 00:33:09.384499 | PLAY [all:!appliance*] 2026-03-09 00:33:09.406832 | 2026-03-09 00:33:09.406935 | TASK [unregister the node] 2026-03-09 00:33:09.776827 | controller | skipping: Conditional result was False 2026-03-09 00:33:09.789528 | 2026-03-09 00:33:09.789726 | TASK [include_role : fetch-output] 2026-03-09 00:33:09.840979 | controller | ok 2026-03-09 00:33:09.883087 | 2026-03-09 00:33:09.883225 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-09 00:33:09.961246 | controller | skipping: Conditional result was False 2026-03-09 00:33:09.978031 | 2026-03-09 00:33:09.978233 | TASK [fetch-output : Set log path for single node] 2026-03-09 00:33:10.030889 | controller | ok 2026-03-09 00:33:10.045330 | 2026-03-09 00:33:10.045624 | LOOP [fetch-output : Ensure local output dirs] 2026-03-09 00:33:10.559369 | controller -> localhost | ok: "/var/lib/zuul/builds/9bbbe718d2714fd49609ee62ba224d41/work/logs" 2026-03-09 00:33:10.836873 | controller -> localhost | changed: "/var/lib/zuul/builds/9bbbe718d2714fd49609ee62ba224d41/work/artifacts" 2026-03-09 00:33:11.077834 | controller -> localhost | changed: "/var/lib/zuul/builds/9bbbe718d2714fd49609ee62ba224d41/work/docs" 2026-03-09 00:33:11.098347 | 2026-03-09 00:33:11.098598 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-09 00:33:13.481190 | controller | changed: 2026-03-09 00:33:13.481469 | controller | .d..t...... ./ 2026-03-09 00:33:13.481509 | controller | cd+++++++++ controller/ 2026-03-09 00:33:13.481557 | controller | changed: All items complete 2026-03-09 00:33:13.481585 | 2026-03-09 00:33:15.618821 | controller | changed: .d..t...... ./ 2026-03-09 00:33:17.790027 | controller | changed: .d..t...... ./ 2026-03-09 00:33:17.816351 | 2026-03-09 00:33:17.816520 | TASK [include_role : fetch-output-openshift] 2026-03-09 00:33:17.842430 | controller | skipping: Conditional result was False 2026-03-09 00:33:17.852565 | 2026-03-09 00:33:17.852712 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-09 00:33:17.897159 | controller | skipping: Conditional result was False 2026-03-09 00:33:17.907908 | controller | skipping: Conditional result was False 2026-03-09 00:33:17.960244 | 2026-03-09 00:33:17.960378 | PLAY [localhost] 2026-03-09 00:33:17.980770 | 2026-03-09 00:33:17.980879 | TASK [Run Zuul manifest role] 2026-03-09 00:33:18.001244 | localhost | ok 2026-03-09 00:33:18.014302 | 2026-03-09 00:33:18.014373 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-09 00:33:18.444743 | localhost | changed 2026-03-09 00:33:18.454935 | 2026-03-09 00:33:18.455068 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-09 00:33:18.501842 | localhost | ok 2026-03-09 00:33:18.554941 | 2026-03-09 00:33:18.555091 | TASK [Set zuul-log-path fact] 2026-03-09 00:33:18.576731 | localhost | ok 2026-03-09 00:33:18.594643 | 2026-03-09 00:33:18.594749 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-09 00:33:18.635694 | localhost | ok 2026-03-09 00:33:18.646446 | 2026-03-09 00:33:18.646527 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)] 2026-03-09 00:33:18.693098 | localhost | ok: 2026-03-09 00:33:18.693580 | localhost | { 2026-03-09 00:33:18.693650 | localhost | "auth": { 2026-03-09 00:33:18.693739 | localhost | "password": "IonWCwcuOFib&&<}wp^:[2G/", 2026-03-09 00:33:18.693781 | localhost | "username": "9LF91O0RK7JU" 2026-03-09 00:33:18.693823 | localhost | }, 2026-03-09 00:33:18.693859 | localhost | "profile": "rackspace", 2026-03-09 00:33:18.693893 | localhost | "project_name": "1251317", 2026-03-09 00:33:18.693926 | localhost | "region_name": "DFW" 2026-03-09 00:33:18.693959 | localhost | } 2026-03-09 00:33:18.694007 | 2026-03-09 00:33:18.713602 | 2026-03-09 00:33:18.713823 | TASK [Run upload-logs-failover role (S3 then Swift)] 2026-03-09 00:33:18.740042 | localhost | Output suppressed because no_log was given 2026-03-09 00:33:18.764771 | 2026-03-09 00:33:18.764862 | TASK [upload-logs-failover : Initialize control vars] 2026-03-09 00:33:18.796000 | localhost | ok 2026-03-09 00:33:18.801813 | 2026-03-09 00:33:18.801902 | LOOP [upload-logs-failover : Perform log upload] 2026-03-09 00:33:18.849846 | localhost | Output suppressed because no_log was given 2026-03-09 00:33:18.859981 | localhost | included: /var/lib/zuul/builds/9bbbe718d2714fd49609ee62ba224d41/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml 2026-03-09 00:33:18.869192 | 2026-03-09 00:33:18.869289 | LOOP [upload-logs-failover : Unpack vars for swift upload] 2026-03-09 00:33:18.978850 | localhost | Output suppressed because no_log was given 2026-03-09 00:33:18.987853 | 2026-03-09 00:33:18.987952 | TASK [Upload logs to swift] 2026-03-09 00:33:19.018562 | localhost | ok 2026-03-09 00:33:19.053396 | 2026-03-09 00:33:19.053484 | TASK [Set zuul-log-path fact] 2026-03-09 00:33:19.089558 | localhost | skipping: Conditional result was False 2026-03-09 00:33:19.097005 | 2026-03-09 00:33:19.097093 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-03-09 00:33:19.549546 | localhost -> localhost | ok: Runtime: 0:00:00.010375 2026-03-09 00:33:19.561159 | 2026-03-09 00:33:19.561297 | TASK [upload-logs-swift : Upload logs to swift]