2026-02-07 00:22:19.459635 | Job console starting... 2026-02-07 00:22:19.470274 | Updating repositories 2026-02-07 00:22:19.587581 | Preparing job workspace 2026-02-07 00:22:30.057532 | Running Ansible setup... 2026-02-07 00:22:37.940802 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-02-07 00:22:38.602511 | 2026-02-07 00:22:38.602666 | PLAY [localhost] 2026-02-07 00:22:38.611936 | 2026-02-07 00:22:38.612038 | TASK [Gathering Facts] 2026-02-07 00:22:39.592964 | localhost | ok 2026-02-07 00:22:39.627306 | 2026-02-07 00:22:39.627525 | TASK [Setup log path fact] 2026-02-07 00:22:39.653686 | localhost | ok 2026-02-07 00:22:39.678113 | 2026-02-07 00:22:39.678416 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-07 00:22:39.719908 | localhost | ok 2026-02-07 00:22:39.731772 | 2026-02-07 00:22:39.731895 | TASK [emit-job-header : Print job information] 2026-02-07 00:22:39.785406 | # Job Information 2026-02-07 00:22:39.785627 | Ansible Version: 2.15.12 2026-02-07 00:22:39.785683 | Job: ansible-test-sanity-docker-stable-2.11 2026-02-07 00:22:39.785753 | Pipeline: periodic 2026-02-07 00:22:39.785798 | Executor: ze03.softwarefactory-project.io 2026-02-07 00:22:39.785834 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-02-07 00:22:39.785879 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/486/ansible/4864ddab07704f92a38c3f3c4999abb0/ 2026-02-07 00:22:39.785918 | Event ID: debcfade88c04063a6272a678728f719 2026-02-07 00:22:39.793806 | 2026-02-07 00:22:39.793917 | LOOP [emit-job-header : Print node information] 2026-02-07 00:22:39.906862 | localhost | ok: 2026-02-07 00:22:39.907036 | localhost | # Node Information 2026-02-07 00:22:39.907065 | localhost | Inventory Hostname: controller 2026-02-07 00:22:39.907086 | localhost | Hostname: np0005611429 2026-02-07 00:22:39.907106 | localhost | Username: zuul 2026-02-07 00:22:39.907129 | localhost | Distro: Fedora 37 2026-02-07 00:22:39.907148 | localhost | Provider: ansible-vexxhost-ams1 2026-02-07 00:22:39.907167 | localhost | Region: ams1 2026-02-07 00:22:39.907185 | localhost | Label: ansible-fedora-37-1vcpu 2026-02-07 00:22:39.907203 | localhost | Product Name: OpenStack Nova 2026-02-07 00:22:39.907221 | localhost | Interface IP: 38.129.16.86 2026-02-07 00:22:39.920424 | 2026-02-07 00:22:39.920545 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-07 00:22:40.323073 | localhost -> localhost | changed 2026-02-07 00:22:40.329208 | 2026-02-07 00:22:40.329292 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-07 00:22:41.239794 | localhost -> localhost | changed 2026-02-07 00:22:41.273682 | 2026-02-07 00:22:41.273816 | PLAY [all:!appliance*] 2026-02-07 00:22:41.301273 | 2026-02-07 00:22:41.301423 | TASK [include_role : start-zuul-console] 2026-02-07 00:22:41.323089 | controller | ok 2026-02-07 00:22:41.339249 | 2026-02-07 00:22:41.339380 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-02-07 00:22:42.379118 | controller | ok 2026-02-07 00:22:42.392630 | 2026-02-07 00:22:42.392760 | TASK [use-our-mirror : Retrieve the IP address] 2026-02-07 00:23:03.824434 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-02-07 00:23:03.838453 | 2026-02-07 00:23:03.838607 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-02-07 00:23:04.213028 | controller | skipping: Conditional result was False 2026-02-07 00:23:04.227005 | 2026-02-07 00:23:04.227200 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-02-07 00:23:04.254510 | controller | skipping: Conditional result was False 2026-02-07 00:23:04.268069 | 2026-02-07 00:23:04.268213 | TASK [use-our-mirror : Create the podman configuration directory] 2026-02-07 00:23:04.295115 | controller | skipping: Conditional result was False 2026-02-07 00:23:04.310345 | 2026-02-07 00:23:04.310546 | TASK [use-our-mirror : Copy the podman configuration] 2026-02-07 00:23:04.337868 | controller | skipping: Conditional result was False 2026-02-07 00:23:04.357129 | 2026-02-07 00:23:04.357263 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-02-07 00:23:04.383451 | controller | skipping: Conditional result was False 2026-02-07 00:23:04.390565 | 2026-02-07 00:23:04.390654 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-02-07 00:23:04.416310 | controller | skipping: Conditional result was False 2026-02-07 00:23:04.428188 | 2026-02-07 00:23:04.428274 | TASK [Disable Fedora Modular] 2026-02-07 00:23:05.485121 | controller | changed 2026-02-07 00:23:05.494219 | 2026-02-07 00:23:05.494341 | TASK [Enable EPEL] 2026-02-07 00:23:05.508741 | controller | skipping: Conditional result was False 2026-02-07 00:23:05.518260 | 2026-02-07 00:23:05.518392 | TASK [Register the RHEL node] 2026-02-07 00:23:05.890373 | 2026-02-07 00:23:05.890566 | TASK [Show the subscription-manager status] 2026-02-07 00:23:06.256566 | controller | skipping: Conditional result was False 2026-02-07 00:23:06.266141 | 2026-02-07 00:23:06.266280 | TASK [Enable EPEL on RHEL] 2026-02-07 00:23:06.669783 | controller | skipping: Conditional result was False 2026-02-07 00:23:06.679092 | 2026-02-07 00:23:06.679234 | TASK [Install git and tox] 2026-02-07 00:24:59.222017 | controller | changed 2026-02-07 00:24:59.228518 | 2026-02-07 00:24:59.228603 | TASK [include_role : prepare-workspace] 2026-02-07 00:24:59.253479 | controller | ok 2026-02-07 00:24:59.313928 | 2026-02-07 00:24:59.314056 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-07 00:25:00.241811 | controller | ok 2026-02-07 00:25:00.261710 | 2026-02-07 00:25:00.261907 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-07 00:25:16.075178 | controller | Output suppressed because no_log was given 2026-02-07 00:25:16.086674 | 2026-02-07 00:25:16.086816 | TASK [include_role : prepare-workspace-openshift] 2026-02-07 00:25:16.103371 | controller | skipping: Conditional result was False 2026-02-07 00:25:16.125889 | 2026-02-07 00:25:16.125991 | PLAY [all:!appliance] 2026-02-07 00:25:16.141246 | 2026-02-07 00:25:16.141353 | TASK [Run add-build-sshkey role (RSA)] 2026-02-07 00:25:16.162270 | controller | ok 2026-02-07 00:25:16.179506 | 2026-02-07 00:25:16.179640 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-07 00:25:16.439994 | controller -> localhost | ok 2026-02-07 00:25:16.447538 | 2026-02-07 00:25:16.447652 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-07 00:25:16.490181 | controller | ok 2026-02-07 00:25:16.507954 | controller | included: /var/lib/zuul/builds/4864ddab07704f92a38c3f3c4999abb0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-07 00:25:16.515964 | 2026-02-07 00:25:16.516102 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-07 00:25:17.011025 | controller -> localhost | Generating public/private rsa key pair. 2026-02-07 00:25:17.011218 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/4864ddab07704f92a38c3f3c4999abb0/work/4864ddab07704f92a38c3f3c4999abb0_id_rsa. 2026-02-07 00:25:17.011248 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/4864ddab07704f92a38c3f3c4999abb0/work/4864ddab07704f92a38c3f3c4999abb0_id_rsa.pub. 2026-02-07 00:25:17.011270 | controller -> localhost | The key fingerprint is: 2026-02-07 00:25:17.011289 | controller -> localhost | SHA256:ZYy8W2huqMTZ5PY0A+T3BCQecpN9rlfqfJRX3s5Y828 zuul-build-sshkey 2026-02-07 00:25:17.011308 | controller -> localhost | The key's randomart image is: 2026-02-07 00:25:17.011327 | controller -> localhost | +---[RSA 2048]----+ 2026-02-07 00:25:17.011345 | controller -> localhost | | . =o. | 2026-02-07 00:25:17.011363 | controller -> localhost | | +.*.o. | 2026-02-07 00:25:17.011380 | controller -> localhost | | o +o+ | 2026-02-07 00:25:17.011397 | controller -> localhost | | o *. . .| 2026-02-07 00:25:17.011414 | controller -> localhost | | + S.oo . o.| 2026-02-07 00:25:17.011443 | controller -> localhost | | . = *.=o o ..+| 2026-02-07 00:25:17.011470 | controller -> localhost | | + = B+.. . =o| 2026-02-07 00:25:17.011493 | controller -> localhost | | . o + oo . . E| 2026-02-07 00:25:17.011514 | controller -> localhost | | . . . .o| 2026-02-07 00:25:17.011536 | controller -> localhost | +----[SHA256]-----+ 2026-02-07 00:25:17.011583 | controller -> localhost | ok: Runtime: 0:00:00.053946 2026-02-07 00:25:17.019564 | 2026-02-07 00:25:17.019652 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-07 00:25:17.053176 | controller | ok 2026-02-07 00:25:17.067302 | controller | included: /var/lib/zuul/builds/4864ddab07704f92a38c3f3c4999abb0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-07 00:25:17.079581 | 2026-02-07 00:25:17.079737 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-07 00:25:17.104350 | controller | skipping: Conditional result was False 2026-02-07 00:25:17.144898 | 2026-02-07 00:25:17.145026 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-07 00:25:18.188846 | controller | changed 2026-02-07 00:25:18.196006 | 2026-02-07 00:25:18.196128 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-07 00:25:18.830701 | controller | ok 2026-02-07 00:25:18.839457 | 2026-02-07 00:25:18.839808 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-07 00:25:21.829425 | controller | changed 2026-02-07 00:25:21.838454 | 2026-02-07 00:25:21.838615 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-07 00:25:24.800752 | controller | changed 2026-02-07 00:25:24.822764 | 2026-02-07 00:25:24.822956 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-07 00:25:24.840752 | controller | skipping: Conditional result was False 2026-02-07 00:25:24.850292 | 2026-02-07 00:25:24.850423 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-07 00:25:25.221877 | controller -> localhost | changed 2026-02-07 00:25:25.235344 | 2026-02-07 00:25:25.235451 | TASK [add-build-sshkey : Add back temp key] 2026-02-07 00:25:25.536815 | controller -> localhost | Identity added: /var/lib/zuul/builds/4864ddab07704f92a38c3f3c4999abb0/work/4864ddab07704f92a38c3f3c4999abb0_id_rsa (zuul-build-sshkey) 2026-02-07 00:25:25.537012 | controller -> localhost | ok: Runtime: 0:00:00.010188 2026-02-07 00:25:25.544146 | 2026-02-07 00:25:25.544232 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-07 00:25:26.439125 | controller | ok 2026-02-07 00:25:26.449536 | 2026-02-07 00:25:26.449824 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-07 00:25:26.497111 | controller | skipping: Conditional result was False 2026-02-07 00:25:26.519695 | 2026-02-07 00:25:26.519912 | TASK [Run add-build-sshkey role (ECDSA)] 2026-02-07 00:25:26.554886 | controller | ok 2026-02-07 00:25:26.582303 | 2026-02-07 00:25:26.582487 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-07 00:25:26.844872 | controller -> localhost | ok 2026-02-07 00:25:26.860591 | 2026-02-07 00:25:26.860829 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-07 00:25:26.903265 | controller | ok 2026-02-07 00:25:26.929063 | controller | included: /var/lib/zuul/builds/4864ddab07704f92a38c3f3c4999abb0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-07 00:25:26.939209 | 2026-02-07 00:25:26.939362 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-07 00:25:27.235991 | controller -> localhost | Generating public/private ecdsa key pair. 2026-02-07 00:25:27.236288 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/4864ddab07704f92a38c3f3c4999abb0/work/4864ddab07704f92a38c3f3c4999abb0_id_ecdsa. 2026-02-07 00:25:27.236332 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/4864ddab07704f92a38c3f3c4999abb0/work/4864ddab07704f92a38c3f3c4999abb0_id_ecdsa.pub. 2026-02-07 00:25:27.236377 | controller -> localhost | The key fingerprint is: 2026-02-07 00:25:27.236410 | controller -> localhost | SHA256:8lG+puu8gV32rH1cuDb+Cfo8quXA9JgXzbvz2PhYGuI zuul-build-sshkey 2026-02-07 00:25:27.236439 | controller -> localhost | The key's randomart image is: 2026-02-07 00:25:27.236464 | controller -> localhost | +---[ECDSA 521]---+ 2026-02-07 00:25:27.236489 | controller -> localhost | | | 2026-02-07 00:25:27.236513 | controller -> localhost | | | 2026-02-07 00:25:27.236537 | controller -> localhost | | . | 2026-02-07 00:25:27.236561 | controller -> localhost | | o o | 2026-02-07 00:25:27.236584 | controller -> localhost | | . S.+. o . | 2026-02-07 00:25:27.236607 | controller -> localhost | | =o+++. o .| 2026-02-07 00:25:27.236632 | controller -> localhost | | . +=o=o= + | 2026-02-07 00:25:27.236661 | controller -> localhost | | . +*o+o^ .| 2026-02-07 00:25:27.236688 | controller -> localhost | | .*+oE+%*B.| 2026-02-07 00:25:27.236714 | controller -> localhost | +----[SHA256]-----+ 2026-02-07 00:25:27.236813 | controller -> localhost | ok: Runtime: 0:00:00.016146 2026-02-07 00:25:27.245894 | 2026-02-07 00:25:27.246044 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-07 00:25:27.282890 | controller | ok 2026-02-07 00:25:27.298187 | controller | included: /var/lib/zuul/builds/4864ddab07704f92a38c3f3c4999abb0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-07 00:25:27.312888 | 2026-02-07 00:25:27.313029 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-07 00:25:27.329986 | controller | skipping: Conditional result was False 2026-02-07 00:25:27.338581 | 2026-02-07 00:25:27.338745 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-07 00:25:28.315272 | controller | changed 2026-02-07 00:25:28.322561 | 2026-02-07 00:25:28.322849 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-07 00:25:28.992941 | controller | ok 2026-02-07 00:25:28.999855 | 2026-02-07 00:25:28.999960 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-07 00:25:31.976457 | controller | changed 2026-02-07 00:25:31.983344 | 2026-02-07 00:25:31.983496 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-07 00:25:34.966876 | controller | changed 2026-02-07 00:25:34.973557 | 2026-02-07 00:25:34.973643 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-07 00:25:34.997910 | controller | skipping: Conditional result was False 2026-02-07 00:25:35.005023 | 2026-02-07 00:25:35.005115 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-07 00:25:35.274906 | controller -> localhost | changed 2026-02-07 00:25:35.291523 | 2026-02-07 00:25:35.291662 | TASK [add-build-sshkey : Add back temp key] 2026-02-07 00:25:35.614654 | controller -> localhost | Identity added: /var/lib/zuul/builds/4864ddab07704f92a38c3f3c4999abb0/work/4864ddab07704f92a38c3f3c4999abb0_id_ecdsa (zuul-build-sshkey) 2026-02-07 00:25:35.614896 | controller -> localhost | ok: Runtime: 0:00:00.007960 2026-02-07 00:25:35.655566 | 2026-02-07 00:25:35.655703 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-07 00:25:36.381348 | controller | ok 2026-02-07 00:25:36.395881 | 2026-02-07 00:25:36.396046 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-07 00:25:36.434573 | controller | skipping: Conditional result was False 2026-02-07 00:25:36.454716 | 2026-02-07 00:25:36.454884 | TASK [include_role : remove-zuul-sshkey] 2026-02-07 00:25:36.479716 | controller | skipping: Conditional result was False 2026-02-07 00:25:36.491047 | 2026-02-07 00:25:36.491168 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-07 00:25:37.154364 | controller | ok: "logs" 2026-02-07 00:25:37.154600 | controller | ok: All items complete 2026-02-07 00:25:37.154629 | 2026-02-07 00:25:37.758363 | controller | ok: "artifacts" 2026-02-07 00:25:38.364868 | controller | ok: "docs" 2026-02-07 00:25:38.374055 | 2026-02-07 00:25:38.374215 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-07 00:25:39.020400 | controller | changed: "logs" 2026-02-07 00:25:39.626250 | controller | changed: "artifacts" 2026-02-07 00:25:40.236762 | controller | changed: "docs" 2026-02-07 00:25:40.264509 | 2026-02-07 00:25:40.264650 | PLAY RECAP 2026-02-07 00:25:40.264693 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-02-07 00:25:40.264718 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-07 00:25:40.264759 | 2026-02-07 00:25:40.387155 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-02-07 00:25:40.388047 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-07 00:25:40.972181 | 2026-02-07 00:25:40.972303 | PLAY [all] 2026-02-07 00:25:40.994102 | 2026-02-07 00:25:40.994209 | TASK [Install binary dependencies] 2026-02-07 00:25:41.068334 | controller | ok 2026-02-07 00:25:41.100896 | 2026-02-07 00:25:41.101123 | TASK [bindep : Include find tasks] 2026-02-07 00:25:41.133000 | controller | ok 2026-02-07 00:25:41.141392 | controller | included: /var/lib/zuul/builds/4864ddab07704f92a38c3f3c4999abb0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-02-07 00:25:41.147716 | 2026-02-07 00:25:41.147802 | TASK [bindep : Look for bindep.txt] 2026-02-07 00:25:42.070014 | controller | ok 2026-02-07 00:25:42.075872 | 2026-02-07 00:25:42.075960 | TASK [bindep : Define bindep_file fact] 2026-02-07 00:25:42.090319 | controller | skipping: Conditional result was False 2026-02-07 00:25:42.096432 | 2026-02-07 00:25:42.096539 | TASK [bindep : Look for other-requirements.txt] 2026-02-07 00:25:42.711520 | controller | ok 2026-02-07 00:25:42.719747 | 2026-02-07 00:25:42.719883 | TASK [bindep : Define bindep_file fact] 2026-02-07 00:25:42.745089 | controller | skipping: Conditional result was False 2026-02-07 00:25:42.753903 | 2026-02-07 00:25:42.754035 | TASK [bindep : Look for bindep fallback file] 2026-02-07 00:25:42.789992 | controller | skipping: Conditional result was False 2026-02-07 00:25:42.798881 | 2026-02-07 00:25:42.799015 | TASK [bindep : Define bindep_file fact] 2026-02-07 00:25:42.827402 | controller | skipping: Conditional result was False 2026-02-07 00:25:42.835761 | 2026-02-07 00:25:42.835915 | TASK [bindep : Include bindep tasks] 2026-02-07 00:25:42.870506 | controller | skipping: Conditional result was False 2026-02-07 00:25:42.878270 | 2026-02-07 00:25:42.878433 | TASK [bindep : Include install tasks] 2026-02-07 00:25:42.905675 | controller | skipping: Conditional result was False 2026-02-07 00:25:42.914011 | 2026-02-07 00:25:42.914176 | LOOP [bindep : Include package tasks] 2026-02-07 00:25:42.978279 | 2026-02-07 00:25:42.978541 | TASK [Run test-setup role] 2026-02-07 00:25:43.002648 | controller | ok 2026-02-07 00:25:43.021589 | 2026-02-07 00:25:43.021757 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-02-07 00:25:43.718988 | controller | ok 2026-02-07 00:25:43.728565 | 2026-02-07 00:25:43.728776 | TASK [test-setup : Run tools/test-setup.sh] 2026-02-07 00:25:44.085498 | controller | skipping: Conditional result was False 2026-02-07 00:25:44.112896 | 2026-02-07 00:25:44.112995 | PLAY RECAP 2026-02-07 00:25:44.113036 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-07 00:25:44.113059 | 2026-02-07 00:25:44.212548 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-07 00:25:44.214225 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-02-07 00:25:44.834617 | 2026-02-07 00:25:44.834834 | PLAY [controller] 2026-02-07 00:25:44.863287 | 2026-02-07 00:25:44.863459 | TASK [Create the /root directory] 2026-02-07 00:25:45.889555 | controller | ok 2026-02-07 00:25:45.898506 | 2026-02-07 00:25:45.898656 | TASK [Install glibc-langpack-en] 2026-02-07 00:25:53.699834 | controller | ok: Nothing to do 2026-02-07 00:25:53.706309 | 2026-02-07 00:25:53.706390 | TASK [Ensure controller directory exists] 2026-02-07 00:25:54.497054 | controller | changed 2026-02-07 00:25:54.503906 | 2026-02-07 00:25:54.504062 | TASK [Install container runtime] 2026-02-07 00:25:54.565378 | controller | ok 2026-02-07 00:25:54.612343 | 2026-02-07 00:25:54.612471 | LOOP [ensure-docker : Gather variables for each operating system] 2026-02-07 00:25:54.652845 | controller | ok: "/var/lib/zuul/builds/4864ddab07704f92a38c3f3c4999abb0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2026-02-07 00:25:54.665461 | 2026-02-07 00:25:54.665565 | TASK [ensure-docker : Sanity check for distro selection] 2026-02-07 00:25:54.679631 | controller | skipping: Conditional result was False 2026-02-07 00:25:54.687440 | 2026-02-07 00:25:54.687558 | TASK [ensure-docker : Sanity check for distro install] 2026-02-07 00:25:54.711717 | controller | skipping: Conditional result was False 2026-02-07 00:25:54.720288 | 2026-02-07 00:25:54.720401 | TASK [ensure-docker : Sanity check for upstream install] 2026-02-07 00:25:54.746060 | controller | skipping: Conditional result was False 2026-02-07 00:25:54.752689 | 2026-02-07 00:25:54.752806 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2026-02-07 00:25:54.778167 | controller | skipping: Conditional result was False 2026-02-07 00:25:54.786905 | 2026-02-07 00:25:54.787144 | TASK [ensure-docker : Include tasks for docker install] 2026-02-07 00:25:54.812072 | controller | ok 2026-02-07 00:25:54.825838 | controller | included: /var/lib/zuul/builds/4864ddab07704f92a38c3f3c4999abb0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2026-02-07 00:25:54.834894 | 2026-02-07 00:25:54.834973 | TASK [ensure-docker : Not Implemented] 2026-02-07 00:25:54.849776 | controller | skipping: Conditional result was False 2026-02-07 00:25:54.858323 | 2026-02-07 00:25:54.858434 | TASK [Set up docker mirrors] 2026-02-07 00:25:54.879386 | controller | ok 2026-02-07 00:25:54.903612 | 2026-02-07 00:25:54.903765 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2026-02-07 00:25:54.928525 | controller | skipping: Conditional result was False 2026-02-07 00:25:54.935839 | 2026-02-07 00:25:54.935944 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2026-02-07 00:25:54.960670 | controller | skipping: Conditional result was False 2026-02-07 00:25:54.969109 | 2026-02-07 00:25:54.969261 | TASK [use-docker-mirror : Set docker_mirror fact] 2026-02-07 00:25:55.035770 | controller | skipping: Conditional result was False 2026-02-07 00:25:55.083463 | 2026-02-07 00:25:55.083641 | TASK [use-docker-mirror : Set up docker mirrors] 2026-02-07 00:25:55.108221 | controller | skipping: Conditional result was False 2026-02-07 00:25:55.123413 | 2026-02-07 00:25:55.123653 | TASK [Add all repositories] 2026-02-07 00:25:55.145483 | controller | ok 2026-02-07 00:25:55.176017 | 2026-02-07 00:25:55.176173 | LOOP [ensure-package-repositories : Install repositories] 2026-02-07 00:25:55.199565 | controller | ok: "/var/lib/zuul/builds/4864ddab07704f92a38c3f3c4999abb0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2026-02-07 00:25:55.199852 | controller | ok: All items complete 2026-02-07 00:25:55.199882 | 2026-02-07 00:25:55.211117 | controller | included: /var/lib/zuul/builds/4864ddab07704f92a38c3f3c4999abb0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2026-02-07 00:25:55.219103 | 2026-02-07 00:25:55.219205 | TASK [ensure-package-repositories : Create GPG key temp directory] 2026-02-07 00:25:56.159570 | controller | changed 2026-02-07 00:25:56.169968 | 2026-02-07 00:25:56.170266 | LOOP [ensure-package-repositories : Upload GPG keys] 2026-02-07 00:25:59.359710 | controller | changed: 2026-02-07 00:25:59.360038 | controller | { 2026-02-07 00:25:59.360105 | 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-02-07 00:25:59.360162 | controller | } 2026-02-07 00:25:59.379700 | 2026-02-07 00:25:59.379851 | LOOP [ensure-package-repositories : Import GPG keys] 2026-02-07 00:26:00.628757 | controller | changed: 2026-02-07 00:26:00.629125 | controller | { 2026-02-07 00:26:00.629193 | 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-02-07 00:26:00.629248 | controller | } 2026-02-07 00:26:00.629312 | controller | changed: All items complete 2026-02-07 00:26:00.629354 | 2026-02-07 00:26:00.640212 | 2026-02-07 00:26:00.640332 | TASK [ensure-package-repositories : Remove GPG temp directory] 2026-02-07 00:26:01.328220 | controller | changed 2026-02-07 00:26:01.337679 | 2026-02-07 00:26:01.337815 | LOOP [ensure-package-repositories : Add repositories] 2026-02-07 00:26:02.187602 | controller | changed: 2026-02-07 00:26:02.187993 | controller | { 2026-02-07 00:26:02.188038 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2026-02-07 00:26:02.188065 | controller | "description": "Docker CE Stable - $basearch", 2026-02-07 00:26:02.188091 | controller | "gpgcheck": true, 2026-02-07 00:26:02.188115 | controller | "name": "docker-ce-stable" 2026-02-07 00:26:02.188137 | controller | } 2026-02-07 00:26:02.205305 | 2026-02-07 00:26:02.205426 | TASK [ensure-docker : Install pre-reqs] 2026-02-07 00:26:06.866503 | controller | ok: Nothing to do 2026-02-07 00:26:06.875432 | 2026-02-07 00:26:06.875538 | TASK [ensure-docker : Remove packages] 2026-02-07 00:26:09.953527 | controller | ok: Nothing to do 2026-02-07 00:26:09.960420 | 2026-02-07 00:26:09.960567 | TASK [ensure-docker : Install upstream docker using package] 2026-02-07 00:27:31.426599 | controller | changed 2026-02-07 00:27:31.435035 | 2026-02-07 00:27:31.435166 | TASK [ensure-docker : Setup docker] 2026-02-07 00:27:31.457600 | controller | ok 2026-02-07 00:27:31.471207 | controller | included: /var/lib/zuul/builds/4864ddab07704f92a38c3f3c4999abb0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2026-02-07 00:27:31.486403 | 2026-02-07 00:27:31.486516 | TASK [ensure-docker : Ensure "docker" group exists] 2026-02-07 00:27:32.485798 | controller | ok 2026-02-07 00:27:32.498225 | 2026-02-07 00:27:32.498402 | TASK [ensure-docker : Add user to docker group] 2026-02-07 00:27:33.537464 | controller | changed 2026-02-07 00:27:33.552854 | 2026-02-07 00:27:33.553037 | TASK [Update docker daemon proxy configuration] 2026-02-07 00:27:33.590546 | controller | skipping: Conditional result was False 2026-02-07 00:27:33.597704 | 2026-02-07 00:27:33.597834 | TASK [Lower default MTU] 2026-02-07 00:27:33.622014 | controller | skipping: Conditional result was False 2026-02-07 00:27:33.634693 | 2026-02-07 00:27:33.634858 | TASK [ensure-docker : Get the status of the docket.socket unit] 2026-02-07 00:27:34.415889 | controller | inactive 2026-02-07 00:27:35.017439 | controller | ok: Runtime: 0:00:00.017641 2026-02-07 00:27:35.032828 | 2026-02-07 00:27:35.033049 | TASK [ensure-docker : Get the status of the docket unit] 2026-02-07 00:27:35.791009 | controller | inactive 2026-02-07 00:27:36.413123 | controller | ok: Runtime: 0:00:00.017142 2026-02-07 00:27:36.423081 | 2026-02-07 00:27:36.423179 | TASK [ensure-docker : Restart docker] 2026-02-07 00:27:36.472970 | Trigger a Docker restart 2026-02-07 00:27:36.486261 | 2026-02-07 00:27:36.486419 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2026-02-07 00:27:36.539834 | 2026-02-07 00:27:36.540017 | TASK [ensure-docker : Install docker-compose] 2026-02-07 00:27:36.568352 | controller | skipping: Conditional result was False 2026-02-07 00:27:36.583419 | 2026-02-07 00:27:36.583715 | TASK [ensure-docker : Flush handlers before role exit] 2026-02-07 00:27:36.602906 | 2026-02-07 00:27:36.603006 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2026-02-07 00:27:39.993294 | controller | changed 2026-02-07 00:27:40.004369 | 2026-02-07 00:27:40.004492 | TASK [ensure-docker : Assure docker service is running] 2026-02-07 00:27:43.924086 | controller | changed 2026-02-07 00:27:43.936165 | 2026-02-07 00:27:43.936296 | TASK [ensure-docker : Assure docker.socket service is running] 2026-02-07 00:27:45.418704 | controller | ok 2026-02-07 00:27:45.430657 | 2026-02-07 00:27:45.430780 | TASK [ensure-docker : Correct group ownership on docker sock] 2026-02-07 00:27:46.163436 | controller | ok 2026-02-07 00:27:46.172332 | 2026-02-07 00:27:46.172441 | TASK [ensure-docker : Validate ability to talk with docker] 2026-02-07 00:27:46.796586 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2026-02-07 00:27:47.046000 | controller | ok: Runtime: 0:00:00.040063 2026-02-07 00:27:47.060470 | 2026-02-07 00:27:47.060652 | TASK [ensure-docker : Get version details] 2026-02-07 00:27:47.702023 | controller | Client: Docker Engine - Community 2026-02-07 00:27:47.702096 | controller | Version: 24.0.7 2026-02-07 00:27:47.702188 | controller | API version: 1.43 2026-02-07 00:27:47.702198 | controller | Go version: go1.20.10 2026-02-07 00:27:47.702215 | controller | Git commit: afdd53b 2026-02-07 00:27:47.702223 | controller | Built: Thu Oct 26 09:09:39 2023 2026-02-07 00:27:47.702230 | controller | OS/Arch: linux/amd64 2026-02-07 00:27:47.702239 | controller | Context: default 2026-02-07 00:27:47.702248 | controller | 2026-02-07 00:27:47.702254 | controller | Server: Docker Engine - Community 2026-02-07 00:27:47.702260 | controller | Engine: 2026-02-07 00:27:47.702269 | controller | Version: 24.0.7 2026-02-07 00:27:47.702276 | controller | API version: 1.43 (minimum version 1.12) 2026-02-07 00:27:47.702282 | controller | Go version: go1.20.10 2026-02-07 00:27:47.702288 | controller | Git commit: 311b9ff 2026-02-07 00:27:47.702294 | controller | Built: Thu Oct 26 09:08:11 2023 2026-02-07 00:27:47.702300 | controller | OS/Arch: linux/amd64 2026-02-07 00:27:47.702307 | controller | Experimental: false 2026-02-07 00:27:47.702313 | controller | containerd: 2026-02-07 00:27:47.702319 | controller | Version: 1.6.25 2026-02-07 00:27:47.702325 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2026-02-07 00:27:47.702331 | controller | runc: 2026-02-07 00:27:47.702339 | controller | Version: 1.1.10 2026-02-07 00:27:47.705070 | controller | GitCommit: v1.1.10-0-g18a0cb0 2026-02-07 00:27:47.705182 | controller | docker-init: 2026-02-07 00:27:47.705191 | controller | Version: 0.19.0 2026-02-07 00:27:47.705197 | controller | GitCommit: de40ad0 2026-02-07 00:27:47.940167 | controller | ok: Runtime: 0:00:00.039792 2026-02-07 00:27:47.950468 | 2026-02-07 00:27:47.950648 | TASK [ensure-docker : Dump installed docker details] 2026-02-07 00:27:47.994908 | {'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-02-07 00:27:47.665692', 'end': '2026-02-07 00:27:47.705484', 'delta': '0:00:00.039792', 'msg': '', 'zuul_log_id': 'fa163ef9-e89a-559d-5b47-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-02-07 00:27:48.009363 | 2026-02-07 00:27:48.009616 | TASK [Use buildset registry] 2026-02-07 00:27:48.037063 | controller | skipping: Conditional result was False 2026-02-07 00:27:48.052770 | 2026-02-07 00:27:48.052908 | TASK [Ensure python3.8 is present] 2026-02-07 00:27:48.069031 | controller | skipping: Conditional result was False 2026-02-07 00:27:48.078528 | 2026-02-07 00:27:48.078630 | TASK [Run our-ensure-python role (Fedora only for now)] 2026-02-07 00:27:48.101371 | controller | ok 2026-02-07 00:27:48.130079 | 2026-02-07 00:27:48.130223 | TASK [our-ensure-python : Install the right Python version (rpm)] 2026-02-07 00:27:54.684998 | controller | ok: Nothing to do 2026-02-07 00:27:54.698899 | 2026-02-07 00:27:54.699070 | TASK [our-ensure-python : Also install python3-devel] 2026-02-07 00:28:10.515721 | controller | changed 2026-02-07 00:28:10.542496 | 2026-02-07 00:28:10.542644 | TASK [Run ensure-virtualenv role] 2026-02-07 00:28:10.570099 | controller | ok 2026-02-07 00:28:10.609577 | 2026-02-07 00:28:10.609834 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2026-02-07 00:28:11.356150 | controller | /usr/bin/virtualenv 2026-02-07 00:28:11.968204 | controller | ok: Runtime: 0:00:00.004512 2026-02-07 00:28:11.982626 | 2026-02-07 00:28:11.982819 | LOOP [ensure-virtualenv : Install virtualenv package] 2026-02-07 00:28:12.008646 | controller | skipping: Conditional result was False 2026-02-07 00:28:12.009050 | controller | changed: All items complete 2026-02-07 00:28:12.009079 | 2026-02-07 00:28:12.038718 | 2026-02-07 00:28:12.039000 | TASK [Find the full path of the Python interpreter] 2026-02-07 00:28:12.829438 | controller | /usr/bin/python3.9 2026-02-07 00:28:13.421462 | controller | ok 2026-02-07 00:28:13.443793 | 2026-02-07 00:28:13.444131 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2026-02-07 00:28:16.240122 | controller | created virtual environment CPython3.9.18.final.0-64 in 1304ms 2026-02-07 00:28:16.326300 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2026-02-07 00:28:16.326398 | 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-02-07 00:28:16.326414 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2026-02-07 00:28:16.326444 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2026-02-07 00:28:16.841931 | controller | changed 2026-02-07 00:28:16.849079 | 2026-02-07 00:28:16.849190 | TASK [Set selinux package] 2026-02-07 00:28:16.882684 | controller | ok 2026-02-07 00:28:16.888561 | 2026-02-07 00:28:16.888629 | TASK [Set selinux package (Fedora)] 2026-02-07 00:28:17.010891 | controller | ok 2026-02-07 00:28:17.019449 | 2026-02-07 00:28:17.019604 | TASK [Install selinux into virtualenv] 2026-02-07 00:28:22.596519 | controller | Collecting selinux-please-lie-to-me 2026-02-07 00:28:22.660783 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2026-02-07 00:28:23.725999 | controller | Collecting setuptools<50.0.0 2026-02-07 00:28:23.732143 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2026-02-07 00:28:23.788452 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 15.6 MB/s eta 0:00:00 2026-02-07 00:28:23.917476 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2026-02-07 00:28:23.917835 | controller | Attempting uninstall: setuptools 2026-02-07 00:28:23.918954 | controller | Found existing installation: setuptools 62.6.0 2026-02-07 00:28:24.051205 | controller | Uninstalling setuptools-62.6.0: 2026-02-07 00:28:24.066848 | controller | Successfully uninstalled setuptools-62.6.0 2026-02-07 00:28:24.766598 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2026-02-07 00:28:24.980248 | controller | 2026-02-07 00:28:25.242921 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0.1 2026-02-07 00:28:25.242976 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-02-07 00:28:25.391951 | controller | ok: Runtime: 0:00:07.133603 2026-02-07 00:28:25.410183 | 2026-02-07 00:28:25.410487 | TASK [Install pytest-forked into virtualenv] 2026-02-07 00:28:27.502068 | controller | Collecting pytest-forked 2026-02-07 00:28:27.571793 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2026-02-07 00:28:27.802437 | controller | Collecting pytest>=3.10 2026-02-07 00:28:27.808604 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2026-02-07 00:28:27.859828 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 8.1 MB/s eta 0:00:00 2026-02-07 00:28:27.918524 | controller | Collecting py 2026-02-07 00:28:27.928512 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2026-02-07 00:28:27.942504 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 9.8 MB/s eta 0:00:00 2026-02-07 00:28:28.028457 | controller | Collecting exceptiongroup>=1 2026-02-07 00:28:28.035493 | controller | Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB) 2026-02-07 00:28:28.138825 | controller | Collecting pygments>=2.7.2 2026-02-07 00:28:28.148339 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2026-02-07 00:28:28.182267 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 48.5 MB/s eta 0:00:00 2026-02-07 00:28:28.234148 | controller | Collecting iniconfig>=1 2026-02-07 00:28:28.242425 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2026-02-07 00:28:28.333501 | controller | Collecting packaging>=20 2026-02-07 00:28:28.343029 | controller | Downloading packaging-26.0-py3-none-any.whl (74 kB) 2026-02-07 00:28:28.355911 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 8.1 MB/s eta 0:00:00 2026-02-07 00:28:28.420983 | controller | Collecting pluggy<2,>=1.5 2026-02-07 00:28:28.428012 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-02-07 00:28:28.535917 | controller | Collecting tomli>=1 2026-02-07 00:28:28.542501 | controller | Downloading tomli-2.4.0-py3-none-any.whl (14 kB) 2026-02-07 00:28:28.642490 | controller | Collecting typing-extensions>=4.6.0 2026-02-07 00:28:28.650313 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2026-02-07 00:28:28.667188 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 3.7 MB/s eta 0:00:00 2026-02-07 00:28:28.813027 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2026-02-07 00:28:31.395019 | 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-02-07 00:28:31.400023 | controller | 2026-02-07 00:28:31.610571 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0.1 2026-02-07 00:28:31.610625 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-02-07 00:28:31.820140 | controller | ok: Runtime: 0:00:04.970506 2026-02-07 00:28:31.832575 | 2026-02-07 00:28:31.832794 | TASK [Update pip] 2026-02-07 00:28:34.206379 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2026-02-07 00:28:34.759995 | controller | Collecting pip 2026-02-07 00:28:34.889488 | controller | Downloading pip-26.0.1-py3-none-any.whl (1.8 MB) 2026-02-07 00:28:34.991085 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 19.4 MB/s eta 0:00:00 2026-02-07 00:28:35.110820 | controller | Installing collected packages: pip 2026-02-07 00:28:35.116050 | controller | Attempting uninstall: pip 2026-02-07 00:28:35.117031 | controller | Found existing installation: pip 22.2.2 2026-02-07 00:28:35.546405 | controller | Uninstalling pip-22.2.2: 2026-02-07 00:28:35.583710 | controller | Successfully uninstalled pip-22.2.2 2026-02-07 00:28:37.252118 | controller | Successfully installed pip-26.0.1 2026-02-07 00:28:37.705659 | controller | ok: Runtime: 0:00:04.576663 2026-02-07 00:28:37.720771 | 2026-02-07 00:28:37.720942 | TASK [Ensure pip temp directory exists in /var/tmp] 2026-02-07 00:28:38.767815 | controller | changed 2026-02-07 00:28:38.777376 | 2026-02-07 00:28:38.777521 | TASK [Install ansible into virtualenv] 2026-02-07 00:28:40.894633 | controller | Processing ./src/github.com/ansible/ansible 2026-02-07 00:28:40.915999 | controller | Installing build dependencies: started 2026-02-07 00:28:42.840037 | controller | Installing build dependencies: finished with status 'done' 2026-02-07 00:28:42.841167 | controller | Getting requirements to build wheel: started 2026-02-07 00:28:43.936656 | controller | Getting requirements to build wheel: finished with status 'done' 2026-02-07 00:28:43.939389 | controller | Preparing metadata (pyproject.toml): started 2026-02-07 00:28:44.716834 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2026-02-07 00:28:44.815551 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2026-02-07 00:28:44.872379 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2026-02-07 00:28:44.968683 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2026-02-07 00:28:44.974377 | 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-02-07 00:28:45.454989 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2026-02-07 00:28:45.460462 | controller | Downloading cryptography-46.0.4-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2026-02-07 00:28:45.479788 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (26.0) 2026-02-07 00:28:45.499261 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2026-02-07 00:28:45.505897 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2026-02-07 00:28:45.753559 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2026-02-07 00:28:45.759190 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2026-02-07 00:28:45.766359 | 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-02-07 00:28:45.788847 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2026-02-07 00:28:45.794531 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2026-02-07 00:28:45.921977 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2026-02-07 00:28:45.927772 | 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-02-07 00:28:45.945196 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2026-02-07 00:28:45.962330 | controller | Downloading cryptography-46.0.4-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2026-02-07 00:28:46.023409 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 88.3 MB/s 0:00:00 2026-02-07 00:28:46.029032 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2026-02-07 00:28:46.050347 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2026-02-07 00:28:46.061617 | 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-02-07 00:28:46.076773 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2026-02-07 00:28:46.091844 | 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-02-07 00:28:46.108984 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 38.6 MB/s 0:00:00 2026-02-07 00:28:46.126415 | controller | Building wheels for collected packages: ansible-core 2026-02-07 00:28:46.127969 | controller | Building wheel for ansible-core (pyproject.toml): started 2026-02-07 00:28:48.513904 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2026-02-07 00:28:48.517476 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961250 sha256=7bf478676357a257ae7afc11aa96201b35477530f755812e2e011f8506a3cec4 2026-02-07 00:28:48.517701 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-pgzq8i6w/wheels/0f/a0/93/ab85b739e1eb1e0467171316f13571c14efdf87e0d5d44a33e 2026-02-07 00:28:48.531703 | controller | Successfully built ansible-core 2026-02-07 00:28:48.579934 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2026-02-07 00:28:51.113242 | controller | 2026-02-07 00:28:51.117612 | controller | Successfully installed MarkupSafe-3.0.3 PyYAML-6.0.3 ansible-core-2.11.12.post0 cffi-2.0.0 cryptography-46.0.4 jinja2-3.1.6 pycparser-2.23 resolvelib-0.5.4 2026-02-07 00:28:51.657333 | controller | ok: Runtime: 0:00:11.656585 2026-02-07 00:28:51.664617 | 2026-02-07 00:28:51.664772 | TASK [Setup download-artifact-fork role] 2026-02-07 00:28:51.690027 | controller | ok 2026-02-07 00:28:51.718059 | 2026-02-07 00:28:51.718232 | TASK [download-artifact-fork : Parse inventory response] 2026-02-07 00:28:51.774691 | controller | ok 2026-02-07 00:28:51.783338 | 2026-02-07 00:28:51.783501 | TASK [download-artifact-fork : Fetch info from Zuul API] 2026-02-07 00:28:51.821782 | controller | skipping: Conditional result was False 2026-02-07 00:28:51.828994 | 2026-02-07 00:28:51.829105 | TASK [download-artifact-fork : Parse build response] 2026-02-07 00:28:51.855119 | controller | skipping: Conditional result was False 2026-02-07 00:28:51.865407 | 2026-02-07 00:28:51.865533 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2026-02-07 00:28:52.720856 | controller | changed 2026-02-07 00:28:52.731937 | 2026-02-07 00:28:52.732073 | LOOP [download-artifact-fork : Download archive by type] 2026-02-07 00:28:52.774371 | controller | skipping: Conditional result was False 2026-02-07 00:28:52.774715 | 2026-02-07 00:28:55.445133 | controller | changed: OK (20191 bytes) 2026-02-07 00:28:55.468547 | 2026-02-07 00:28:55.468691 | TASK [deploy-artifacts : define __collections] 2026-02-07 00:28:55.506365 | controller | ok 2026-02-07 00:28:55.520023 | 2026-02-07 00:28:55.520160 | LOOP [deploy-artifacts : Create list of collections] 2026-02-07 00:28:55.551493 | controller | skipping: Conditional result was False 2026-02-07 00:28:55.564451 | controller | ok: 2026-02-07 00:28:55.564581 | controller | { 2026-02-07 00:28:55.564616 | controller | "branch": "main", 2026-02-07 00:28:55.564643 | controller | "job": "build-ansible-collection", 2026-02-07 00:28:55.564668 | controller | "metadata": { 2026-02-07 00:28:55.564693 | controller | "type": "ansible_collection", 2026-02-07 00:28:55.564718 | controller | "version": "5.0.0" 2026-02-07 00:28:55.564769 | controller | }, 2026-02-07 00:28:55.564796 | controller | "name": "ansible.network", 2026-02-07 00:28:55.564821 | controller | "project": "ansible-collections/ansible.network", 2026-02-07 00:28:55.564845 | controller | "url": "https://adc8653ef2c5d2f71a46-e68139517039901174a82e1c38d5d3bd.ssl.cf1.rackcdn.com/ansible/3f8e053aa32c49ad8c391e3eae94de5d/artifacts/ansible-network-5.0.0.tar.gz" 2026-02-07 00:28:55.564880 | controller | } 2026-02-07 00:28:55.577975 | 2026-02-07 00:28:55.578128 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2026-02-07 00:28:57.164967 | controller | Starting galaxy collection install process 2026-02-07 00:28:57.165345 | controller | Process install dependency map 2026-02-07 00:29:00.682312 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-02-07 00:29:00.682637 | controller | unexpected error when getting available versions of collection 2026-02-07 00:29:00.682665 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2026-02-07 00:29:00.682673 | controller | ansible/netcommon/versions/' 2026-02-07 00:29:00.682684 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2026-02-07 00:29:00.682772 | controller | to see the full traceback, use -vvv 2026-02-07 00:29:00.947422 | controller | ERROR 2026-02-07 00:29:00.947665 | controller | { 2026-02-07 00:29:00.947754 | controller | "delta": "0:00:04.423913", 2026-02-07 00:29:00.947808 | controller | "end": "2026-02-07 00:29:00.792565", 2026-02-07 00:29:00.947847 | controller | "msg": "non-zero return code", 2026-02-07 00:29:00.947884 | controller | "rc": 250, 2026-02-07 00:29:00.947920 | controller | "start": "2026-02-07 00:28:56.368652" 2026-02-07 00:29:00.947956 | controller | } failure 2026-02-07 00:29:00.951021 | 2026-02-07 00:29:00.951142 | PLAY RECAP 2026-02-07 00:29:00.951255 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2026-02-07 00:29:00.951358 | 2026-02-07 00:29:01.081978 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-02-07 00:29:01.083074 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-07 00:29:01.708585 | 2026-02-07 00:29:01.708788 | PLAY [all] 2026-02-07 00:29:01.731695 | 2026-02-07 00:29:01.731841 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-02-07 00:29:04.749926 | controller | changed: non-zero return code 2026-02-07 00:29:04.764257 | 2026-02-07 00:29:04.764478 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-02-07 00:29:04.792830 | controller | skipping: Conditional result was False 2026-02-07 00:29:04.807354 | 2026-02-07 00:29:04.807514 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-02-07 00:29:04.843439 | 2026-02-07 00:29:04.843583 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-02-07 00:29:04.882450 | 2026-02-07 00:29:04.882855 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-02-07 00:29:04.900813 | controller | skipping: Conditional result was False 2026-02-07 00:29:04.915654 | 2026-02-07 00:29:04.915856 | LOOP [fetch-subunit-output : Generate subunit file] 2026-02-07 00:29:04.956302 | 2026-02-07 00:29:04.956584 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-02-07 00:29:04.973178 | controller | skipping: Conditional result was False 2026-02-07 00:29:04.990517 | 2026-02-07 00:29:04.990785 | TASK [fetch-subunit-output : Remove the temporary file] 2026-02-07 00:29:05.018100 | controller | skipping: Conditional result was False 2026-02-07 00:29:05.030146 | 2026-02-07 00:29:05.030337 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-02-07 00:29:05.057867 | controller | skipping: Conditional result was False 2026-02-07 00:29:05.100382 | 2026-02-07 00:29:05.100502 | PLAY RECAP 2026-02-07 00:29:05.100558 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-07 00:29:05.100588 | 2026-02-07 00:29:05.241162 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-07 00:29:05.242488 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-02-07 00:29:05.883217 | 2026-02-07 00:29:05.883334 | PLAY [all:!appliance*] 2026-02-07 00:29:05.906126 | 2026-02-07 00:29:05.906263 | TASK [unregister the node] 2026-02-07 00:29:06.258197 | controller | skipping: Conditional result was False 2026-02-07 00:29:06.271125 | 2026-02-07 00:29:06.271317 | TASK [include_role : fetch-output] 2026-02-07 00:29:06.311928 | controller | ok 2026-02-07 00:29:06.350897 | 2026-02-07 00:29:06.351044 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-07 00:29:06.417748 | controller | skipping: Conditional result was False 2026-02-07 00:29:06.426545 | 2026-02-07 00:29:06.426674 | TASK [fetch-output : Set log path for single node] 2026-02-07 00:29:06.458768 | controller | ok 2026-02-07 00:29:06.465229 | 2026-02-07 00:29:06.465319 | LOOP [fetch-output : Ensure local output dirs] 2026-02-07 00:29:06.929717 | controller -> localhost | ok: "/var/lib/zuul/builds/4864ddab07704f92a38c3f3c4999abb0/work/logs" 2026-02-07 00:29:07.203136 | controller -> localhost | changed: "/var/lib/zuul/builds/4864ddab07704f92a38c3f3c4999abb0/work/artifacts" 2026-02-07 00:29:07.448602 | controller -> localhost | changed: "/var/lib/zuul/builds/4864ddab07704f92a38c3f3c4999abb0/work/docs" 2026-02-07 00:29:07.458698 | 2026-02-07 00:29:07.458823 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-07 00:29:09.755481 | controller | changed: 2026-02-07 00:29:09.755751 | controller | .d..t...... ./ 2026-02-07 00:29:09.755787 | controller | cd+++++++++ controller/ 2026-02-07 00:29:09.755823 | controller | changed: All items complete 2026-02-07 00:29:09.755846 | 2026-02-07 00:29:11.832011 | controller | changed: .d..t...... ./ 2026-02-07 00:29:13.856231 | controller | changed: .d..t...... ./ 2026-02-07 00:29:13.869576 | 2026-02-07 00:29:13.869703 | TASK [include_role : fetch-output-openshift] 2026-02-07 00:29:13.885689 | controller | skipping: Conditional result was False 2026-02-07 00:29:13.895633 | 2026-02-07 00:29:13.895799 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-07 00:29:13.935676 | controller | skipping: Conditional result was False 2026-02-07 00:29:13.946885 | controller | skipping: Conditional result was False 2026-02-07 00:29:13.998171 | 2026-02-07 00:29:13.998290 | PLAY [localhost] 2026-02-07 00:29:14.011744 | 2026-02-07 00:29:14.011869 | TASK [Run Zuul manifest role] 2026-02-07 00:29:14.031050 | localhost | ok 2026-02-07 00:29:14.044542 | 2026-02-07 00:29:14.044664 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-07 00:29:14.470816 | localhost | changed 2026-02-07 00:29:14.475685 | 2026-02-07 00:29:14.475790 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-07 00:29:14.504907 | localhost | ok 2026-02-07 00:29:14.513377 | 2026-02-07 00:29:14.513457 | TASK [Set zuul-log-path fact] 2026-02-07 00:29:14.532618 | localhost | ok 2026-02-07 00:29:14.572481 | 2026-02-07 00:29:14.572623 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-07 00:29:14.601720 | localhost | ok 2026-02-07 00:29:14.609979 | 2026-02-07 00:29:14.610066 | LOOP [Run upload-logs-swift role] 2026-02-07 00:29:14.656400 | localhost | Output suppressed because no_log was given 2026-02-07 00:29:14.681392 | 2026-02-07 00:29:14.681513 | TASK [Set zuul-log-path fact] 2026-02-07 00:29:14.716862 | localhost | skipping: Conditional result was False 2026-02-07 00:29:14.723954 | 2026-02-07 00:29:14.724064 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-02-07 00:29:15.173533 | localhost -> localhost | ok: Runtime: 0:00:00.006439 2026-02-07 00:29:15.178966 | 2026-02-07 00:29:15.179080 | TASK [upload-logs-swift : Upload logs to swift]